Skip to content

Instantly share code, notes, and snippets.

@listout
Created January 13, 2023 19:08
Show Gist options
  • Star 0 You must be signed in to star a gist
  • Fork 0 You must be signed in to fork a gist
  • Save listout/73d0ce52433b1953b208665b810b0080 to your computer and use it in GitHub Desktop.
Save listout/73d0ce52433b1953b208665b810b0080 to your computer and use it in GitHub Desktop.
verbose output
rst:0x1 (POWERON_RESET),boot:0x13 (SPI_FAST_FLASH_BOOT)
configsip: 271414342, 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:0x3fff0030,len:7000
load:0x40078000,len:15452
ho 0 tail 12 room 4
load:0x40080400,len:3840
0x40080400: _init at ??:?
entry 0x4008064c
I (30) boot: ESP-IDF v5.0-dirty 2nd stage bootloader
I (30) boot: compile time 00:29:14
I (30) boot: chip revision: v3.0
I (33) boot_comm: chip revision: 3, min. bootloader chip revision: 0
I (40) boot.esp32: SPI Speed : 40MHz
I (45) boot.esp32: SPI Mode : DIO
I (49) boot.esp32: SPI Flash Size : 2MB
I (54) boot: Enabling RNG early entropy source...
I (59) boot: Partition Table:
I (63) boot: ## Label Usage Type ST Offset Length
I (70) boot: 0 nvs WiFi data 01 02 00009000 00006000
I (78) boot: 1 phy_init RF data 01 01 0000f000 00001000
I (85) boot: 2 factory factory app 00 00 00010000 00100000
I (93) boot: End of partition table
I (97) boot_comm: chip revision: 3, min. application chip revision: 0
I (104) esp_image: segment 0: paddr=00010020 vaddr=3f400020 size=0e5e4h ( 58852) map
I (134) esp_image: segment 1: paddr=0001e60c vaddr=3ffb0000 size=01a0ch ( 6668) load
I (137) esp_image: segment 2: paddr=00020020 vaddr=400d0020 size=261c4h (156100) map
I (196) esp_image: segment 3: paddr=000461ec vaddr=3ffb1a0c size=00f10h ( 3856) load
I (198) esp_image: segment 4: paddr=00047104 vaddr=40080000 size=0d890h ( 55440) load
I (225) esp_image: segment 5: paddr=0005499c vaddr=50000000 size=00010h ( 16) load
I (232) boot: Loaded app from partition at offset 0x10000
I (232) boot: Disabling RNG early entropy source...
I (245) cpu_start: Pro cpu up.
I (245) cpu_start: Starting app cpu, entry point is 0x400811dc
0x400811dc: call_start_cpu1 at /home/listout/esp/esp-idf-v5.0/components/esp_system/port/cpu_start.c:142
I (0) cpu_start: App cpu up.
D (253) clk: RTC_SLOW_CLK calibration value: 3328768
I (264) cpu_start: Pro cpu start user code
I (264) cpu_start: cpu freq: 160000000 Hz
I (264) cpu_start: Application information:
I (269) cpu_start: Project name: sd_card
I (274) cpu_start: App version: 1
I (278) cpu_start: Compile time: Jan 14 2023 00:29:10
I (284) cpu_start: ELF file SHA256: ea6b03028d0dcfdd...
I (290) cpu_start: ESP-IDF: v5.0-dirty
V (296) memory_layout: reserved range is 0x3f40e5bc - 0x3f40e5f4
D (302) memory_layout: Checking 7 reserved memory ranges:
D (307) memory_layout: Reserved memory range 0x3ffae000 - 0x3ffae6e0
D (313) memory_layout: Reserved memory range 0x3ffb0000 - 0x3ffb32a8
D (320) memory_layout: Reserved memory range 0x3ffe0000 - 0x3ffe0440
D (326) memory_layout: Reserved memory range 0x3ffe3f20 - 0x3ffe4350
D (333) memory_layout: Reserved memory range 0x40070000 - 0x40078000
D (339) memory_layout: Reserved memory range 0x40078000 - 0x40080000
0x40080000: _WindowOverflow4 at /home/listout/esp/esp-idf-v5.0/components/freertos/FreeRTOS-Kernel/portable/xtensa/xtensa_vectors.S:1742
D (346) memory_layout: Reserved memory range 0x40080000 - 0x4008d890
0x40080000: _WindowOverflow4 at /home/listout/esp/esp-idf-v5.0/components/freertos/FreeRTOS-Kernel/portable/xtensa/xtensa_vectors.S:1742
D (352) memory_layout: Building list of available memory regions:
V (358) memory_layout: Examining memory region 0x3ffae000 - 0x3ffb0000
V (365) memory_layout: Start of region 0x3ffae000 - 0x3ffb0000 overlaps reserved 0x3ffae000 - 0x3ffae6e0
D (374) memory_layout: Available memory region 0x3ffae6e0 - 0x3ffb0000
V (381) memory_layout: Examining memory region 0x3ffb0000 - 0x3ffb8000
V (388) memory_layout: Start of region 0x3ffb0000 - 0x3ffb8000 overlaps reserved 0x3ffb0000 - 0x3ffb32a8
D (397) memory_layout: Available memory region 0x3ffb32a8 - 0x3ffb8000
V (404) memory_layout: Examining memory region 0x3ffb8000 - 0x3ffc0000
D (410) memory_layout: Available memory region 0x3ffb8000 - 0x3ffc0000
V (417) memory_layout: Examining memory region 0x3ffc0000 - 0x3ffc2000
D (423) memory_layout: Available memory region 0x3ffc0000 - 0x3ffc2000
V (430) memory_layout: Examining memory region 0x3ffc2000 - 0x3ffc4000
D (437) memory_layout: Available memory region 0x3ffc2000 - 0x3ffc4000
V (443) memory_layout: Examining memory region 0x3ffc4000 - 0x3ffc6000
D (450) memory_layout: Available memory region 0x3ffc4000 - 0x3ffc6000
V (456) memory_layout: Examining memory region 0x3ffc6000 - 0x3ffc8000
D (463) memory_layout: Available memory region 0x3ffc6000 - 0x3ffc8000
V (470) memory_layout: Examining memory region 0x3ffc8000 - 0x3ffca000
D (476) memory_layout: Available memory region 0x3ffc8000 - 0x3ffca000
V (483) memory_layout: Examining memory region 0x3ffca000 - 0x3ffcc000
D (489) memory_layout: Available memory region 0x3ffca000 - 0x3ffcc000
V (496) memory_layout: Examining memory region 0x3ffcc000 - 0x3ffce000
D (503) memory_layout: Available memory region 0x3ffcc000 - 0x3ffce000
V (509) memory_layout: Examining memory region 0x3ffce000 - 0x3ffd0000
D (516) memory_layout: Available memory region 0x3ffce000 - 0x3ffd0000
V (522) memory_layout: Examining memory region 0x3ffd0000 - 0x3ffd2000
D (529) memory_layout: Available memory region 0x3ffd0000 - 0x3ffd2000
V (536) memory_layout: Examining memory region 0x3ffd2000 - 0x3ffd4000
D (542) memory_layout: Available memory region 0x3ffd2000 - 0x3ffd4000
V (549) memory_layout: Examining memory region 0x3ffd4000 - 0x3ffd6000
D (555) memory_layout: Available memory region 0x3ffd4000 - 0x3ffd6000
V (562) memory_layout: Examining memory region 0x3ffd6000 - 0x3ffd8000
D (569) memory_layout: Available memory region 0x3ffd6000 - 0x3ffd8000
V (575) memory_layout: Examining memory region 0x3ffd8000 - 0x3ffda000
D (582) memory_layout: Available memory region 0x3ffd8000 - 0x3ffda000
V (588) memory_layout: Examining memory region 0x3ffda000 - 0x3ffdc000
D (595) memory_layout: Available memory region 0x3ffda000 - 0x3ffdc000
V (602) memory_layout: Examining memory region 0x3ffdc000 - 0x3ffde000
D (608) memory_layout: Available memory region 0x3ffdc000 - 0x3ffde000
V (615) memory_layout: Examining memory region 0x3ffde000 - 0x3ffe0000
D (621) memory_layout: Available memory region 0x3ffde000 - 0x3ffe0000
V (628) memory_layout: Examining memory region 0x3ffe0000 - 0x3ffe4000
V (635) memory_layout: Start of region 0x3ffe0000 - 0x3ffe4000 overlaps reserved 0x3ffe0000 - 0x3ffe0440
V (644) memory_layout: End of region 0x3ffe0440 - 0x3ffe4000 overlaps reserved 0x3ffe3f20 - 0x3ffe4350
D (653) memory_layout: Available memory region 0x3ffe0440 - 0x3ffe3f20
V (660) memory_layout: Examining memory region 0x3ffe4000 - 0x3ffe8000
V (667) memory_layout: Start of region 0x3ffe4000 - 0x3ffe8000 overlaps reserved 0x3ffe3f20 - 0x3ffe4350
D (676) memory_layout: Available memory region 0x3ffe4350 - 0x3ffe8000
V (683) memory_layout: Examining memory region 0x3ffe8000 - 0x3fff0000
D (689) memory_layout: Available memory region 0x3ffe8000 - 0x3fff0000
V (696) memory_layout: Examining memory region 0x3fff0000 - 0x3fff8000
D (703) memory_layout: Available memory region 0x3fff0000 - 0x3fff8000
V (709) memory_layout: Examining memory region 0x3fff8000 - 0x3fffc000
D (716) memory_layout: Available memory region 0x3fff8000 - 0x3fffc000
V (722) memory_layout: Examining memory region 0x3fffc000 - 0x40000000
D (729) memory_layout: Available memory region 0x3fffc000 - 0x40000000
V (736) memory_layout: Examining memory region 0x40070000 - 0x40078000
V (742) memory_layout: Region 0x40070000 - 0x40078000 inside of reserved 0x40070000 - 0x40078000
V (751) memory_layout: Examining memory region 0x40078000 - 0x40080000
0x40080000: _WindowOverflow4 at /home/listout/esp/esp-idf-v5.0/components/freertos/FreeRTOS-Kernel/portable/xtensa/xtensa_vectors.S:1742
V (758) memory_layout: Region 0x40078000 - 0x40080000 inside of reserved 0x40078000 - 0x40080000
0x40080000: _WindowOverflow4 at /home/listout/esp/esp-idf-v5.0/components/freertos/FreeRTOS-Kernel/portable/xtensa/xtensa_vectors.S:1742
0x40080000: _WindowOverflow4 at /home/listout/esp/esp-idf-v5.0/components/freertos/FreeRTOS-Kernel/portable/xtensa/xtensa_vectors.S:1742
V (766) memory_layout: Examining memory region 0x40080000 - 0x40082000
0x40080000: _WindowOverflow4 at /home/listout/esp/esp-idf-v5.0/components/freertos/FreeRTOS-Kernel/portable/xtensa/xtensa_vectors.S:1742
0x40082000: heap_caps_realloc_base at /home/listout/esp/esp-idf-v5.0/components/heap/heap_caps.c:364
V (773) memory_layout: Region 0x40080000 - 0x40082000 inside of reserved 0x40080000 - 0x4008d890
0x40080000: _WindowOverflow4 at /home/listout/esp/esp-idf-v5.0/components/freertos/FreeRTOS-Kernel/portable/xtensa/xtensa_vectors.S:1742
0x40082000: heap_caps_realloc_base at /home/listout/esp/esp-idf-v5.0/components/heap/heap_caps.c:364
0x40080000: _WindowOverflow4 at /home/listout/esp/esp-idf-v5.0/components/freertos/FreeRTOS-Kernel/portable/xtensa/xtensa_vectors.S:1742
V (782) memory_layout: Examining memory region 0x40082000 - 0x40084000
0x40082000: heap_caps_realloc_base at /home/listout/esp/esp-idf-v5.0/components/heap/heap_caps.c:364
0x40084000: bootloader_flash_execute_command_common at /home/listout/esp/esp-idf-v5.0/components/bootloader_support/bootloader_flash/src/bootloader_flash.c:510
V (789) memory_layout: Region 0x40082000 - 0x40084000 inside of reserved 0x40080000 - 0x4008d890
0x40082000: heap_caps_realloc_base at /home/listout/esp/esp-idf-v5.0/components/heap/heap_caps.c:364
0x40084000: bootloader_flash_execute_command_common at /home/listout/esp/esp-idf-v5.0/components/bootloader_support/bootloader_flash/src/bootloader_flash.c:510
0x40080000: _WindowOverflow4 at /home/listout/esp/esp-idf-v5.0/components/freertos/FreeRTOS-Kernel/portable/xtensa/xtensa_vectors.S:1742
V (797) memory_layout: Examining memory region 0x40084000 - 0x40086000
0x40084000: bootloader_flash_execute_command_common at /home/listout/esp/esp-idf-v5.0/components/bootloader_support/bootloader_flash/src/bootloader_flash.c:510
0x40086000: clk_ll_ref_tick_set_divider at /home/listout/esp/esp-idf-v5.0/components/hal/esp32/include/hal/clk_tree_ll.h:643
(inlined by) rtc_clk_cpu_freq_to_8m at /home/listout/esp/esp-idf-v5.0/components/esp_hw_support/port/esp32/rtc_clk.c:376
V (804) memory_layout: Region 0x40084000 - 0x40086000 inside of reserved 0x40080000 - 0x4008d890
0x40084000: bootloader_flash_execute_command_common at /home/listout/esp/esp-idf-v5.0/components/bootloader_support/bootloader_flash/src/bootloader_flash.c:510
0x40086000: clk_ll_ref_tick_set_divider at /home/listout/esp/esp-idf-v5.0/components/hal/esp32/include/hal/clk_tree_ll.h:643
(inlined by) rtc_clk_cpu_freq_to_8m at /home/listout/esp/esp-idf-v5.0/components/esp_hw_support/port/esp32/rtc_clk.c:376
0x40080000: _WindowOverflow4 at /home/listout/esp/esp-idf-v5.0/components/freertos/FreeRTOS-Kernel/portable/xtensa/xtensa_vectors.S:1742
V (813) memory_layout: Examining memory region 0x40086000 - 0x40088000
0x40086000: clk_ll_ref_tick_set_divider at /home/listout/esp/esp-idf-v5.0/components/hal/esp32/include/hal/clk_tree_ll.h:643
(inlined by) rtc_clk_cpu_freq_to_8m at /home/listout/esp/esp-idf-v5.0/components/esp_hw_support/port/esp32/rtc_clk.c:376
0x40088000: xTaskCreateStaticPinnedToCore at /home/listout/esp/esp-idf-v5.0/components/freertos/FreeRTOS-Kernel/tasks.c:684
V (819) memory_layout: Region 0x40086000 - 0x40088000 inside of reserved 0x40080000 - 0x4008d890
0x40086000: clk_ll_ref_tick_set_divider at /home/listout/esp/esp-idf-v5.0/components/hal/esp32/include/hal/clk_tree_ll.h:643
(inlined by) rtc_clk_cpu_freq_to_8m at /home/listout/esp/esp-idf-v5.0/components/esp_hw_support/port/esp32/rtc_clk.c:376
0x40088000: xTaskCreateStaticPinnedToCore at /home/listout/esp/esp-idf-v5.0/components/freertos/FreeRTOS-Kernel/tasks.c:684
0x40080000: _WindowOverflow4 at /home/listout/esp/esp-idf-v5.0/components/freertos/FreeRTOS-Kernel/portable/xtensa/xtensa_vectors.S:1742
V (828) memory_layout: Examining memory region 0x40088000 - 0x4008a000
0x40088000: xTaskCreateStaticPinnedToCore at /home/listout/esp/esp-idf-v5.0/components/freertos/FreeRTOS-Kernel/tasks.c:684
0x4008a000: spi_flash_hal_program_page at /home/listout/esp/esp-idf-v5.0/components/hal/spi_flash_hal_iram.c:78
V (835) memory_layout: Region 0x40088000 - 0x4008a000 inside of reserved 0x40080000 - 0x4008d890
0x40088000: xTaskCreateStaticPinnedToCore at /home/listout/esp/esp-idf-v5.0/components/freertos/FreeRTOS-Kernel/tasks.c:684
0x4008a000: spi_flash_hal_program_page at /home/listout/esp/esp-idf-v5.0/components/hal/spi_flash_hal_iram.c:78
0x40080000: _WindowOverflow4 at /home/listout/esp/esp-idf-v5.0/components/freertos/FreeRTOS-Kernel/portable/xtensa/xtensa_vectors.S:1742
V (844) memory_layout: Examining memory region 0x4008a000 - 0x4008c000
0x4008a000: spi_flash_hal_program_page at /home/listout/esp/esp-idf-v5.0/components/hal/spi_flash_hal_iram.c:78
0x4008c000: block_next at /home/listout/esp/esp-idf-v5.0/components/heap/tlsf/tlsf_block_functions.h:94
(inlined by) block_link_next at /home/listout/esp/esp-idf-v5.0/components/heap/tlsf/tlsf_block_functions.h:101
(inlined by) block_absorb at /home/listout/esp/esp-idf-v5.0/components/heap/tlsf/tlsf.c:458
(inlined by) block_merge_next at /home/listout/esp/esp-idf-v5.0/components/heap/tlsf/tlsf.c:493
(inlined by) block_trim_used at /home/listout/esp/esp-idf-v5.0/components/heap/tlsf/tlsf.c:522
(inlined by) tlsf_realloc at /home/listout/esp/esp-idf-v5.0/components/heap/tlsf/tlsf.c:1120
V (850) memory_layout: Region 0x4008a000 - 0x4008c000 inside of reserved 0x40080000 - 0x4008d890
0x4008a000: spi_flash_hal_program_page at /home/listout/esp/esp-idf-v5.0/components/hal/spi_flash_hal_iram.c:78
0x4008c000: block_next at /home/listout/esp/esp-idf-v5.0/components/heap/tlsf/tlsf_block_functions.h:94
(inlined by) block_link_next at /home/listout/esp/esp-idf-v5.0/components/heap/tlsf/tlsf_block_functions.h:101
(inlined by) block_absorb at /home/listout/esp/esp-idf-v5.0/components/heap/tlsf/tlsf.c:458
(inlined by) block_merge_next at /home/listout/esp/esp-idf-v5.0/components/heap/tlsf/tlsf.c:493
(inlined by) block_trim_used at /home/listout/esp/esp-idf-v5.0/components/heap/tlsf/tlsf.c:522
(inlined by) tlsf_realloc at /home/listout/esp/esp-idf-v5.0/components/heap/tlsf/tlsf.c:1120
0x40080000: _WindowOverflow4 at /home/listout/esp/esp-idf-v5.0/components/freertos/FreeRTOS-Kernel/portable/xtensa/xtensa_vectors.S:1742
V (859) memory_layout: Examining memory region 0x4008c000 - 0x4008e000
0x4008c000: block_next at /home/listout/esp/esp-idf-v5.0/components/heap/tlsf/tlsf_block_functions.h:94
(inlined by) block_link_next at /home/listout/esp/esp-idf-v5.0/components/heap/tlsf/tlsf_block_functions.h:101
(inlined by) block_absorb at /home/listout/esp/esp-idf-v5.0/components/heap/tlsf/tlsf.c:458
(inlined by) block_merge_next at /home/listout/esp/esp-idf-v5.0/components/heap/tlsf/tlsf.c:493
(inlined by) block_trim_used at /home/listout/esp/esp-idf-v5.0/components/heap/tlsf/tlsf.c:522
(inlined by) tlsf_realloc at /home/listout/esp/esp-idf-v5.0/components/heap/tlsf/tlsf.c:1120
V (866) memory_layout: Start of region 0x4008c000 - 0x4008e000 overlaps reserved 0x40080000 - 0x4008d890
0x4008c000: block_next at /home/listout/esp/esp-idf-v5.0/components/heap/tlsf/tlsf_block_functions.h:94
(inlined by) block_link_next at /home/listout/esp/esp-idf-v5.0/components/heap/tlsf/tlsf_block_functions.h:101
(inlined by) block_absorb at /home/listout/esp/esp-idf-v5.0/components/heap/tlsf/tlsf.c:458
(inlined by) block_merge_next at /home/listout/esp/esp-idf-v5.0/components/heap/tlsf/tlsf.c:493
(inlined by) block_trim_used at /home/listout/esp/esp-idf-v5.0/components/heap/tlsf/tlsf.c:522
(inlined by) tlsf_realloc at /home/listout/esp/esp-idf-v5.0/components/heap/tlsf/tlsf.c:1120
0x40080000: _WindowOverflow4 at /home/listout/esp/esp-idf-v5.0/components/freertos/FreeRTOS-Kernel/portable/xtensa/xtensa_vectors.S:1742
D (875) memory_layout: Available memory region 0x4008d890 - 0x4008e000
V (882) memory_layout: Examining memory region 0x4008e000 - 0x40090000
D (889) memory_layout: Available memory region 0x4008e000 - 0x40090000
V (895) memory_layout: Examining memory region 0x40090000 - 0x40092000
D (902) memory_layout: Available memory region 0x40090000 - 0x40092000
V (908) memory_layout: Examining memory region 0x40092000 - 0x40094000
D (915) memory_layout: Available memory region 0x40092000 - 0x40094000
V (922) memory_layout: Examining memory region 0x40094000 - 0x40096000
D (928) memory_layout: Available memory region 0x40094000 - 0x40096000
V (935) memory_layout: Examining memory region 0x40096000 - 0x40098000
D (941) memory_layout: Available memory region 0x40096000 - 0x40098000
V (948) memory_layout: Examining memory region 0x40098000 - 0x4009a000
D (955) memory_layout: Available memory region 0x40098000 - 0x4009a000
V (961) memory_layout: Examining memory region 0x4009a000 - 0x4009c000
D (968) memory_layout: Available memory region 0x4009a000 - 0x4009c000
V (974) memory_layout: Examining memory region 0x4009c000 - 0x4009e000
D (981) memory_layout: Available memory region 0x4009c000 - 0x4009e000
V (987) memory_layout: Examining memory region 0x4009e000 - 0x400a0000
D (994) memory_layout: Available memory region 0x4009e000 - 0x400a0000
I (1001) heap_init: Initializing. RAM available for dynamic allocation:
D (1008) heap_init: New heap initialised at 0x3ffae6e0
I (1013) heap_init: At 3FFAE6E0 len 00001920 (6 KiB): DRAM
D (1019) heap_init: New heap initialised at 0x3ffb32a8
I (1025) heap_init: At 3FFB32A8 len 0002CD58 (179 KiB): DRAM
I (1031) heap_init: At 3FFE0440 len 00003AE0 (14 KiB): D/IRAM
I (1037) heap_init: At 3FFE4350 len 0001BCB0 (111 KiB): D/IRAM
D (1044) heap_init: New heap initialised at 0x4008d890
I (1049) heap_init: At 4008D890 len 00012770 (73 KiB): IRAM
V (1056) intr_alloc: esp_intr_alloc_intrstatus (cpu 0): checking args
V (1062) intr_alloc: esp_intr_alloc_intrstatus (cpu 0): Args okay. Resulting flags 0x40E
D (1070) intr_alloc: Connected src 46 to int 2 (cpu 0)
V (1076) memspi: raw_chip_id: 174068
V (1079) memspi: chip_id: 684017
V (1082) memspi: raw_chip_id: 174068
V (1086) memspi: chip_id: 684017
D (1090) spi_flash: trying chip: issi
D (1094) spi_flash: trying chip: gd
D (1097) spi_flash: trying chip: mxic
D (1101) spi_flash: trying chip: winbond
D (1105) spi_flash: trying chip: generic
I (1109) spi_flash: detected chip: generic
I (1114) spi_flash: flash io: dio
W (1118) spi_flash: Detected size(8192k) larger than the size in the binary image header(2048k). Using the size in the binary image header.
D (1131) cpu_start: calling init function: 0x400e26a0
0x400e26a0: esp_ipc_init at /home/listout/esp/esp-idf-v5.0/components/esp_system/esp_ipc.c:115
D (1136) cpu_start: calling init function: 0x400d2300
0x400d2300: esp_reset_reason_init at /home/listout/esp/esp-idf-v5.0/components/esp_system/port/soc/esp32/reset_reason.c:68
D (1141) cpu_start: calling init function: 0x400d1764
0x400d1764: esp_init_app_elf_sha256 at /home/listout/esp/esp-idf-v5.0/components/esp_app_format/esp_app_desc.c:69
D (1146) cpu_start: calling init function: 0x400d4f2c on core: 0
0x400d4f2c: __esp_system_init_fn_esp_timer_startup_init at /home/listout/esp/esp-idf-v5.0/components/esp_timer/src/esp_timer.c:464
V (1152) intr_alloc: esp_intr_alloc_intrstatus (cpu 0): checking args
V (1159) intr_alloc: esp_intr_alloc_intrstatus (cpu 0): Args okay. Resulting flags 0xC02
D (1167) intr_alloc: Connected src 17 to int 3 (cpu 0)
D (1172) cpu_start: calling init function: 0x400f5508 on core: 0
0x400f5508: __esp_system_init_fn_init_components0 at /home/listout/esp/esp-idf-v5.0/components/esp_system/startup.c:477
V (1178) intr_alloc: esp_intr_alloc_intrstatus (cpu 0): checking args
V (1185) intr_alloc: esp_intr_alloc_intrstatus (cpu 0): Args okay. Resulting flags 0x40E
D (1193) intr_alloc: Connected src 24 to int 9 (cpu 0)
I (1198) 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 (1248) heap_init: New heap initialised at 0x3ffe0440
D (1248) heap_init: New heap initialised at 0x3ffe4350
V (1258) intr_alloc: esp_intr_alloc_intrstatus (cpu 0): checking args
V (1268) intr_alloc: esp_intr_alloc_intrstatus (cpu 0): Args okay. Resulting flags 0xE
D (1268) intr_alloc: Connected src 16 to int 12 (cpu 0)
I (1278) example: Initializing SD card
I (1278) example: Using SPI peripheral
D (1288) spi: SPI2 use iomux pins.
I (1288) example: Mounting filesystem
D (1298) sdspi_host: sdspi_host_init_device: SPI2 cs=20 cd=-1 wp=-1
V (1298) intr_alloc: esp_intr_alloc_intrstatus (cpu 0): checking args
V (1308) intr_alloc: esp_intr_alloc_intrstatus (cpu 0): Args okay. Resulting flags 0x80E
D (1318) intr_alloc: Connected src 30 to int 13 (cpu 0)
V (1318) bus_lock: device registered on bus 1 slot 5.
D (1328) spi_hal: eff: 400, limit: 80000k(/0), 0 dummy, -1 delay
D (1328) spi_master: SPI2: New device added to CS5, effective clock: 400kHz
I (1338) gpio: GPIO[20]| InputEn: 0| OutputEn: 1| OpenDrain: 0| Pullup: 0| Pulldown: 0| Intr:0
V (1348) sdmmc_cmd: sending cmd slot=1 op=52 arg=80000c08 flags=1c00 data=0x0 blklen=0 datalen=0 timeout=3000
V (1358) sdspi_host: sdspi_host_start_command: slot=1, CMD52, arg=0x80000c08 flags=0x80, data=0x0, data_size=0 crc=0x4f
V bus_lock: dev 5 acquired.
D (1368) spi_master: device5 locked the bus
V bus_lock: SPI dev changed from -1 to 5
V (1378) spi_master: polling trans
V (1378) spi_master: polling trans done
V (1388) spi_master: polling trans
V (1388) spi_master: polling trans done
V (1398) spi_master: polling trans
V (1398) spi_master: polling trans done
V (1398) spi_master: polling trans
V (1408) spi_master: polling trans done
D (1408) spi_master: device5 release bus
V (1408) bus_lock: dev 5 released.
D (1418) sdspi_host: sdspi_host_start_command: cmd=52 error=0x107
D (1418) sdmmc_cmd: cmd=52, sdmmc_req_run returned 0x107
V (1428) sdmmc_io: sdmmc_io_rw_direct: sdmmc_send_cmd returned 0x107
V (1438) sdmmc_cmd: sending cmd slot=1 op=0 arg=0 flags=20 data=0x0 blklen=0 datalen=0 timeout=3000
V (1448) sdspi_host: sdspi_host_start_command: slot=1, CMD0, arg=0x00000000 flags=0x200, data=0x0, data_size=0 crc=0x4a
V bus_lock: dev 5 acquired.
D (1458) spi_master: device5 locked the bus
V (1458) spi_master: polling trans
V (1468) spi_master: polling trans done
V (1468) spi_master: polling trans
V (1468) spi_master: polling trans done
V (1478) spi_master: polling trans
V (1478) spi_master: polling trans done
V (1488) spi_master: polling trans
V (1488) spi_master: polling trans done
V (1488) sdspi_host: start_command_default: ignoring response byte
V (1498) spi_master: polling trans
V (1498) spi_master: polling trans done
D (1508) spi_master: device5 release bus
V (1508) bus_lock: dev 5 released.
V (1508) sdspi_transaction: r1 = 0x00 hw_cmd.r[0]=0xffffffff
V (1518) sdmmc_cmd: cmd response 00000000 00000000 00000000 00000000 err=0x0 state=0
V (1548) sdmmc_cmd: sending cmd slot=1 op=0 arg=0 flags=1c20 data=0x0 blklen=0 datalen=0 timeout=3000
V (1548) sdspi_host: sdspi_host_start_command: slot=1, CMD0, arg=0x00000000 flags=0x4, data=0x0, data_size=0 crc=0x4a
V bus_lock: dev 5 acquired.
D (1558) spi_master: device5 locked the bus
V (1558) spi_master: polling trans
V (1558) spi_master: polling trans done
V (1568) spi_master: polling trans
V (1568) spi_master: polling trans done
V (1578) spi_master: polling trans
V (1578) spi_master: polling trans done
V (1578) spi_master: polling trans
V (1588) spi_master: polling trans done
V (1588) spi_master: polling trans
V (1588) spi_master: polling trans done
D (1598) spi_master: device5 release bus
V (1598) bus_lock: dev 5 released.
V (1608) sdspi_transaction: r1 = 0x01 hw_cmd.r[0]=0xffffffff
V (1608) sdmmc_cmd: cmd response 00000001 00000000 00000000 00000000 err=0x0 state=0
V (1638) sdmmc_cmd: sending cmd slot=1 op=8 arg=1aa flags=1c30 data=0x0 blklen=0 datalen=0 timeout=3000
V (1638) sdspi_host: sdspi_host_start_command: slot=1, CMD8, arg=0x000001aa flags=0x100, data=0x0, data_size=0 crc=0x43
V bus_lock: dev 5 acquired.
D (1648) spi_master: device5 locked the bus
V (1648) spi_master: polling trans
V (1648) spi_master: polling trans done
V (1658) spi_master: polling trans
V (1658) spi_master: polling trans done
V (1668) spi_master: polling trans
V (1668) spi_master: polling trans done
V (1668) spi_master: polling trans
V (1678) spi_master: polling trans done
D (1678) spi_master: device5 release bus
V (1678) bus_lock: dev 5 released.
V (1688) sdspi_transaction: r1 = 0x01 hw_cmd.r[0]=0xaa010000
V (1688) sdmmc_cmd: cmd response 000001aa 00000000 00000000 00000000 err=0x0 state=0
D (1698) sdmmc_sd: SDHC/SDXC card
V (1708) sdmmc_cmd: sending cmd slot=1 op=5 arg=0 flags=1030 data=0x0 blklen=0 datalen=0 timeout=3000
V (1708) sdspi_host: sdspi_host_start_command: slot=1, CMD5, arg=0x00000000 flags=0x40, data=0x0, data_size=0 crc=0x2d
V bus_lock: dev 5 acquired.
D (1728) spi_master: device5 locked the bus
V (1728) spi_master: polling trans
V (1738) spi_master: polling trans done
V (1738) spi_master: polling trans
V (1738) spi_master: polling trans done
V (1748) spi_master: polling trans
V (1748) spi_master: polling trans done
V (1748) spi_master: polling trans
V (1758) spi_master: polling trans done
D (1758) spi_master: device5 release bus
V (1768) bus_lock: dev 5 released.
V (1768) sdspi_transaction: r1 = 0x05 hw_cmd.r[0]=0xffffffff
I (1768) sdspi_transaction: cmd=5, R1 response: command not supported
D (1778) sdmmc_cmd: cmd=5, sdmmc_req_run returned 0x106
D (1788) sdmmc_io: sdmmc_init_io: io_send_op_cond (1) returned 0x106; not IO card
V (1788) sdmmc_cmd: sending cmd slot=1 op=59 arg=1 flags=1c00 data=0x0 blklen=0 datalen=0 timeout=3000
V (1798) sdspi_host: sdspi_host_start_command: slot=1, CMD59, arg=0x00000001 flags=0x4, data=0x0, data_size=0 crc=0x41
V bus_lock: dev 5 acquired.
D (1818) spi_master: device5 locked the bus
V (1818) spi_master: polling trans
V (1818) spi_master: polling trans done
V (1828) spi_master: polling trans
V (1828) spi_master: polling trans done
V (1838) spi_master: polling trans
V (1838) spi_master: polling trans done
V (1838) spi_master: polling trans
V (1848) spi_master: polling trans done
D (1848) spi_master: device5 release bus
V (1848) bus_lock: dev 5 released.
D (1858) sdspi_host: data CRC set=1
V (1858) sdspi_transaction: r1 = 0x01 hw_cmd.r[0]=0xffffffff
V (1868) sdmmc_cmd: cmd response 00000001 00000000 00000000 00000000 err=0x0 state=0
V (1878) sdmmc_cmd: sending cmd slot=1 op=55 arg=0 flags=1c00 data=0x0 blklen=0 datalen=0 timeout=3000
V (1878) sdspi_host: sdspi_host_start_command: slot=1, CMD55, arg=0x00000000 flags=0x4, data=0x0, data_size=0 crc=0x32
V bus_lock: dev 5 acquired.
D (1898) spi_master: device5 locked the bus
V (1898) spi_master: polling trans
V (1908) spi_master: polling trans done
V (1908) spi_master: polling trans
V (1908) spi_master: polling trans done
V (1918) spi_master: polling trans
V (1918) spi_master: polling trans done
V (1918) spi_master: polling trans
V (1928) spi_master: polling trans done
D (1928) spi_master: device5 release bus
V (1938) bus_lock: dev 5 released.
V (1938) sdspi_transaction: r1 = 0x01 hw_cmd.r[0]=0xffffffff
V (1948) sdmmc_cmd: cmd response 00000001 00000000 00000000 00000000 err=0x0 state=0
V (1948) sdmmc_cmd: sending cmd slot=1 op=41 arg=40ff8000 flags=1030 data=0x0 blklen=0 datalen=0 timeout=3000
V (1958) sdspi_host: sdspi_host_start_command: slot=1, CMD41, arg=0x40ff8000 flags=0x4, data=0x0, data_size=0 crc=0x0b
V bus_lock: dev 5 acquired.
D (1978) spi_master: device5 locked the bus
V (1978) spi_master: polling trans
V (1978) spi_master: polling trans done
V (1988) spi_master: polling trans
V (1988) spi_master: polling trans done
V (1998) spi_master: polling trans
V (1998) spi_master: polling trans done
V (1998) spi_master: polling trans
V (2008) spi_master: polling trans done
D (2008) spi_master: device5 release bus
V (2008) bus_lock: dev 5 released.
V (2018) sdspi_transaction: r1 = 0x01 hw_cmd.r[0]=0xffffffff
V (2018) sdmmc_cmd: cmd response 00000001 00000000 00000000 00000000 err=0x0 state=0
V (2038) sdmmc_cmd: sending cmd slot=1 op=55 arg=0 flags=1c00 data=0x0 blklen=0 datalen=0 timeout=3000
V (2038) sdspi_host: sdspi_host_start_command: slot=1, CMD55, arg=0x00000000 flags=0x4, data=0x0, data_size=0 crc=0x32
V bus_lock: dev 5 acquired.
D (2048) spi_master: device5 locked the bus
V (2058) spi_master: polling trans
V (2058) spi_master: polling trans done
V (2068) spi_master: polling trans
V (2068) spi_master: polling trans done
V (2068) spi_master: polling trans
V (2078) spi_master: polling trans done
V (2078) spi_master: polling trans
V (2078) spi_master: polling trans done
D (2088) spi_master: device5 release bus
V (2088) bus_lock: dev 5 released.
V (2088) sdspi_transaction: r1 = 0x01 hw_cmd.r[0]=0xffffffff
V (2098) sdmmc_cmd: cmd response 00000001 00000000 00000000 00000000 err=0x0 state=0
V (2108) sdmmc_cmd: sending cmd slot=1 op=41 arg=40ff8000 flags=1030 data=0x0 blklen=0 datalen=0 timeout=3000
V (2118) sdspi_host: sdspi_host_start_command: slot=1, CMD41, arg=0x40ff8000 flags=0x4, data=0x0, data_size=0 crc=0x0b
V bus_lock: dev 5 acquired.
D (2128) spi_master: device5 locked the bus
V (2138) spi_master: polling trans
V (2138) spi_master: polling trans done
V (2138) spi_master: polling trans
V (2148) spi_master: polling trans done
V (2148) spi_master: polling trans
V (2148) spi_master: polling trans done
V (2158) spi_master: polling trans
V (2158) spi_master: polling trans done
D (2168) spi_master: device5 release bus
V (2168) bus_lock: dev 5 released.
V (2168) sdspi_transaction: r1 = 0x01 hw_cmd.r[0]=0xffffffff
V (2178) sdmmc_cmd: cmd response 00000001 00000000 00000000 00000000 err=0x0 state=0
V (2198) sdmmc_cmd: sending cmd slot=1 op=55 arg=0 flags=1c00 data=0x0 blklen=0 datalen=0 timeout=3000
V (2198) sdspi_host: sdspi_host_start_command: slot=1, CMD55, arg=0x00000000 flags=0x4, data=0x0, data_size=0 crc=0x32
V bus_lock: dev 5 acquired.
D (2208) spi_master: device5 locked the bus
V (2208) spi_master: polling trans
V (2218) spi_master: polling trans done
V (2218) spi_master: polling trans
V (2218) spi_master: polling trans done
V (2228) spi_master: polling trans
V (2228) spi_master: polling trans done
V (2238) spi_master: polling trans
V (2238) spi_master: polling trans done
D (2238) spi_master: device5 release bus
V (2248) bus_lock: dev 5 released.
V (2248) sdspi_transaction: r1 = 0x01 hw_cmd.r[0]=0xffffffff
V (2258) sdmmc_cmd: cmd response 00000001 00000000 00000000 00000000 err=0x0 state=0
V (2258) sdmmc_cmd: sending cmd slot=1 op=41 arg=40ff8000 flags=1030 data=0x0 blklen=0 datalen=0 timeout=3000
V (2268) sdspi_host: sdspi_host_start_command: slot=1, CMD41, arg=0x40ff8000 flags=0x4, data=0x0, data_size=0 crc=0x0b
V bus_lock: dev 5 acquired.
D (2288) spi_master: device5 locked the bus
V (2288) spi_master: polling trans
V (2288) spi_master: polling trans done
V (2298) spi_master: polling trans
V (2298) spi_master: polling trans done
V (2308) spi_master: polling trans
V (2308) spi_master: polling trans done
V (2308) spi_master: polling trans
V (2318) spi_master: polling trans done
D (2318) spi_master: device5 release bus
V (2318) bus_lock: dev 5 released.
V (2328) sdspi_transaction: r1 = 0x01 hw_cmd.r[0]=0xffffffff
V (2328) sdmmc_cmd: cmd response 00000001 00000000 00000000 00000000 err=0x0 state=0
V (2348) sdmmc_cmd: sending cmd slot=1 op=55 arg=0 flags=1c00 data=0x0 blklen=0 datalen=0 timeout=3000
V (2348) sdspi_host: sdspi_host_start_command: slot=1, CMD55, arg=0x00000000 flags=0x4, data=0x0, data_size=0 crc=0x32
V bus_lock: dev 5 acquired.
D (2358) spi_master: device5 locked the bus
V (2368) spi_master: polling trans
V (2368) spi_master: polling trans done
V (2378) spi_master: polling trans
V (2378) spi_master: polling trans done
V (2378) spi_master: polling trans
V (2388) spi_master: polling trans done
V (2388) spi_master: polling trans
V (2388) spi_master: polling trans done
D (2398) spi_master: device5 release bus
V (2398) bus_lock: dev 5 released.
V (2408) sdspi_transaction: r1 = 0x01 hw_cmd.r[0]=0xffffffff
V (2408) sdmmc_cmd: cmd response 00000001 00000000 00000000 00000000 err=0x0 state=0
V (2418) sdmmc_cmd: sending cmd slot=1 op=41 arg=40ff8000 flags=1030 data=0x0 blklen=0 datalen=0 timeout=3000
V (2428) sdspi_host: sdspi_host_start_command: slot=1, CMD41, arg=0x40ff8000 flags=0x4, data=0x0, data_size=0 crc=0x0b
V bus_lock: dev 5 acquired.
D (2438) spi_master: device5 locked the bus
V (2448) spi_master: polling trans
V (2448) spi_master: polling trans done
V (2448) spi_master: polling trans
V (2458) spi_master: polling trans done
V (2458) spi_master: polling trans
V (2468) spi_master: polling trans done
V (2468) spi_master: polling trans
V (2468) spi_master: polling trans done
D (2478) spi_master: device5 release bus
V (2478) bus_lock: dev 5 released.
V (2478) sdspi_transaction: r1 = 0x01 hw_cmd.r[0]=0xffffffff
V (2488) sdmmc_cmd: cmd response 00000001 00000000 00000000 00000000 err=0x0 state=0
V (2508) sdmmc_cmd: sending cmd slot=1 op=55 arg=0 flags=1c00 data=0x0 blklen=0 datalen=0 timeout=3000
V (2508) sdspi_host: sdspi_host_start_command: slot=1, CMD55, arg=0x00000000 flags=0x4, data=0x0, data_size=0 crc=0x32
V bus_lock: dev 5 acquired.
D (2518) spi_master: device5 locked the bus
V (2518) spi_master: polling trans
V (2528) spi_master: polling trans done
V (2528) spi_master: polling trans
V (2538) spi_master: polling trans done
V (2538) spi_master: polling trans
V (2538) spi_master: polling trans done
V (2548) spi_master: polling trans
V (2548) spi_master: polling trans done
D (2548) spi_master: device5 release bus
V (2558) bus_lock: dev 5 released.
V (2558) sdspi_transaction: r1 = 0x01 hw_cmd.r[0]=0xffffffff
V (2568) sdmmc_cmd: cmd response 00000001 00000000 00000000 00000000 err=0x0 state=0
V (2568) sdmmc_cmd: sending cmd slot=1 op=41 arg=40ff8000 flags=1030 data=0x0 blklen=0 datalen=0 timeout=3000
V (2578) sdspi_host: sdspi_host_start_command: slot=1, CMD41, arg=0x40ff8000 flags=0x4, data=0x0, data_size=0 crc=0x0b
V bus_lock: dev 5 acquired.
D (2598) spi_master: device5 locked the bus
V (2598) spi_master: polling trans
V (2608) spi_master: polling trans done
V (2608) spi_master: polling trans
V (2608) spi_master: polling trans done
V (2618) spi_master: polling trans
V (2618) spi_master: polling trans done
V (2618) spi_master: polling trans
V (2628) spi_master: polling trans done
D (2628) spi_master: device5 release bus
V (2638) bus_lock: dev 5 released.
V (2638) sdspi_transaction: r1 = 0x01 hw_cmd.r[0]=0xffffffff
V (2648) sdmmc_cmd: cmd response 00000001 00000000 00000000 00000000 err=0x0 state=0
V (2658) sdmmc_cmd: sending cmd slot=1 op=55 arg=0 flags=1c00 data=0x0 blklen=0 datalen=0 timeout=3000
V (2658) sdspi_host: sdspi_host_start_command: slot=1, CMD55, arg=0x00000000 flags=0x4, data=0x0, data_size=0 crc=0x32
V bus_lock: dev 5 acquired.
D (2678) spi_master: device5 locked the bus
V (2678) spi_master: polling trans
V (2678) spi_master: polling trans done
V (2688) spi_master: polling trans
V (2688) spi_master: polling trans done
V (2688) spi_master: polling trans
V (2698) spi_master: polling trans done
V (2698) spi_master: polling trans
V (2708) spi_master: polling trans done
D (2708) spi_master: device5 release bus
V (2708) bus_lock: dev 5 released.
V (2718) sdspi_transaction: r1 = 0x01 hw_cmd.r[0]=0xffffffff
V (2718) sdmmc_cmd: cmd response 00000001 00000000 00000000 00000000 err=0x0 state=0
V (2728) sdmmc_cmd: sending cmd slot=1 op=41 arg=40ff8000 flags=1030 data=0x0 blklen=0 datalen=0 timeout=3000
V (2738) sdspi_host: sdspi_host_start_command: slot=1, CMD41, arg=0x40ff8000 flags=0x4, data=0x0, data_size=0 crc=0x0b
V bus_lock: dev 5 acquired.
D (2748) spi_master: device5 locked the bus
V (2758) spi_master: polling trans
V (2758) spi_master: polling trans done
V (2768) spi_master: polling trans
V (2768) spi_master: polling trans done
V (2768) spi_master: polling trans
V (2778) spi_master: polling trans done
V (2778) spi_master: polling trans
V (2778) spi_master: polling trans done
D (2788) spi_master: device5 release bus
V (2788) bus_lock: dev 5 released.
V (2788) sdspi_transaction: r1 = 0x01 hw_cmd.r[0]=0xffffffff
V (2798) sdmmc_cmd: cmd response 00000001 00000000 00000000 00000000 err=0x0 state=0
V (2818) sdmmc_cmd: sending cmd slot=1 op=55 arg=0 flags=1c00 data=0x0 blklen=0 datalen=0 timeout=3000
V (2818) sdspi_host: sdspi_host_start_command: slot=1, CMD55, arg=0x00000000 flags=0x4, data=0x0, data_size=0 crc=0x32
V bus_lock: dev 5 acquired.
D (2828) spi_master: device5 locked the bus
V (2838) spi_master: polling trans
V (2838) spi_master: polling trans done
V (2838) spi_master: polling trans
V (2848) spi_master: polling trans done
V (2848) spi_master: polling trans
V (2848) spi_master: polling trans done
V (2858) spi_master: polling trans
V (2858) spi_master: polling trans done
D (2858) spi_master: device5 release bus
V (2868) bus_lock: dev 5 released.
V (2868) sdspi_transaction: r1 = 0x01 hw_cmd.r[0]=0xffffffff
V (2878) sdmmc_cmd: cmd response 00000001 00000000 00000000 00000000 err=0x0 state=0
V (2888) sdmmc_cmd: sending cmd slot=1 op=41 arg=40ff8000 flags=1030 data=0x0 blklen=0 datalen=0 timeout=3000
V (2898) sdspi_host: sdspi_host_start_command: slot=1, CMD41, arg=0x40ff8000 flags=0x4, data=0x0, data_size=0 crc=0x0b
V bus_lock: dev 5 acquired.
D (2908) spi_master: device5 locked the bus
V (2908) spi_master: polling trans
V (2918) spi_master: polling trans done
V (2918) spi_master: polling trans
V (2918) spi_master: polling trans done
V (2928) spi_master: polling trans
V (2928) spi_master: polling trans done
V (2938) spi_master: polling trans
V (2938) spi_master: polling trans done
D (2938) spi_master: device5 release bus
V (2948) bus_lock: dev 5 released.
V (2948) sdspi_transaction: r1 = 0x01 hw_cmd.r[0]=0xffffffff
V (2958) sdmmc_cmd: cmd response 00000001 00000000 00000000 00000000 err=0x0 state=0
V (2968) sdmmc_cmd: sending cmd slot=1 op=55 arg=0 flags=1c00 data=0x0 blklen=0 datalen=0 timeout=3000
V (2968) sdspi_host: sdspi_host_start_command: slot=1, CMD55, arg=0x00000000 flags=0x4, data=0x0, data_size=0 crc=0x32
V bus_lock: dev 5 acquired.
D (2988) spi_master: device5 locked the bus
V (2988) spi_master: polling trans
V (2988) spi_master: polling trans done
V (2998) spi_master: polling trans
V (2998) spi_master: polling trans done
V (3008) spi_master: polling trans
V (3008) spi_master: polling trans done
V (3008) spi_master: polling trans
V (3018) spi_master: polling trans done
D (3018) spi_master: device5 release bus
V (3018) bus_lock: dev 5 released.
V (3028) sdspi_transaction: r1 = 0x01 hw_cmd.r[0]=0xffffffff
V (3028) sdmmc_cmd: cmd response 00000001 00000000 00000000 00000000 err=0x0 state=0
V (3038) sdmmc_cmd: sending cmd slot=1 op=41 arg=40ff8000 flags=1030 data=0x0 blklen=0 datalen=0 timeout=3000
V (3048) sdspi_host: sdspi_host_start_command: slot=1, CMD41, arg=0x40ff8000 flags=0x4, data=0x0, data_size=0 crc=0x0b
V bus_lock: dev 5 acquired.
D (3058) spi_master: device5 locked the bus
V (3068) spi_master: polling trans
V (3068) spi_master: polling trans done
V (3078) spi_master: polling trans
V (3078) spi_master: polling trans done
V (3078) spi_master: polling trans
V (3088) spi_master: polling trans done
V (3088) spi_master: polling trans
V (3088) spi_master: polling trans done
D (3098) spi_master: device5 release bus
V (3098) bus_lock: dev 5 released.
V (3108) sdspi_transaction: r1 = 0x01 hw_cmd.r[0]=0xffffffff
V (3108) sdmmc_cmd: cmd response 00000001 00000000 00000000 00000000 err=0x0 state=0
V (3128) sdmmc_cmd: sending cmd slot=1 op=55 arg=0 flags=1c00 data=0x0 blklen=0 datalen=0 timeout=3000
V (3128) sdspi_host: sdspi_host_start_command: slot=1, CMD55, arg=0x00000000 flags=0x4, data=0x0, data_size=0 crc=0x32
V bus_lock: dev 5 acquired.
D (3138) spi_master: device5 locked the bus
V (3148) spi_master: polling trans
V (3148) spi_master: polling trans done
V (3148) spi_master: polling trans
V (3158) spi_master: polling trans done
V (3158) spi_master: polling trans
V (3158) spi_master: polling trans done
V (3168) spi_master: polling trans
V (3168) spi_master: polling trans done
D (3178) spi_master: device5 release bus
V (3178) bus_lock: dev 5 released.
V (3178) sdspi_transaction: r1 = 0x01 hw_cmd.r[0]=0xffffffff
V (3188) sdmmc_cmd: cmd response 00000001 00000000 00000000 00000000 err=0x0 state=0
V (3198) sdmmc_cmd: sending cmd slot=1 op=41 arg=40ff8000 flags=1030 data=0x0 blklen=0 datalen=0 timeout=3000
V (3208) sdspi_host: sdspi_host_start_command: slot=1, CMD41, arg=0x40ff8000 flags=0x4, data=0x0, data_size=0 crc=0x0b
V bus_lock: dev 5 acquired.
D (3218) spi_master: device5 locked the bus
V (3218) spi_master: polling trans
V (3228) spi_master: polling trans done
V (3228) spi_master: polling trans
V (3238) spi_master: polling trans done
V (3238) spi_master: polling trans
V (3238) spi_master: polling trans done
V (3248) spi_master: polling trans
V (3248) spi_master: polling trans done
D (3248) spi_master: device5 release bus
V (3258) bus_lock: dev 5 released.
V (3258) sdspi_transaction: r1 = 0x01 hw_cmd.r[0]=0xffffffff
V (3268) sdmmc_cmd: cmd response 00000001 00000000 00000000 00000000 err=0x0 state=0
V (3278) sdmmc_cmd: sending cmd slot=1 op=55 arg=0 flags=1c00 data=0x0 blklen=0 datalen=0 timeout=3000
V (3278) sdspi_host: sdspi_host_start_command: slot=1, CMD55, arg=0x00000000 flags=0x4, data=0x0, data_size=0 crc=0x32
V bus_lock: dev 5 acquired.
D (3298) spi_master: device5 locked the bus
V (3298) spi_master: polling trans
V (3308) spi_master: polling trans done
V (3308) spi_master: polling trans
V (3308) spi_master: polling trans done
V (3318) spi_master: polling trans
V (3318) spi_master: polling trans done
V (3318) spi_master: polling trans
V (3328) spi_master: polling trans done
D (3328) spi_master: device5 release bus
V (3338) bus_lock: dev 5 released.
V (3338) sdspi_transaction: r1 = 0x01 hw_cmd.r[0]=0xffffffff
V (3338) sdmmc_cmd: cmd response 00000001 00000000 00000000 00000000 err=0x0 state=0
V (3348) sdmmc_cmd: sending cmd slot=1 op=41 arg=40ff8000 flags=1030 data=0x0 blklen=0 datalen=0 timeout=3000
V (3358) sdspi_host: sdspi_host_start_command: slot=1, CMD41, arg=0x40ff8000 flags=0x4, data=0x0, data_size=0 crc=0x0b
V bus_lock: dev 5 acquired.
D (3378) spi_master: device5 locked the bus
V (3378) spi_master: polling trans
V (3378) spi_master: polling trans done
V (3388) spi_master: polling trans
V (3388) spi_master: polling trans done
V (3388) spi_master: polling trans
V (3398) spi_master: polling trans done
V (3398) spi_master: polling trans
V (3408) spi_master: polling trans done
D (3408) spi_master: device5 release bus
V (3408) bus_lock: dev 5 released.
V (3418) sdspi_transaction: r1 = 0x01 hw_cmd.r[0]=0xffffffff
V (3418) sdmmc_cmd: cmd response 00000001 00000000 00000000 00000000 err=0x0 state=0
V (3438) sdmmc_cmd: sending cmd slot=1 op=55 arg=0 flags=1c00 data=0x0 blklen=0 datalen=0 timeout=3000
V (3438) sdspi_host: sdspi_host_start_command: slot=1, CMD55, arg=0x00000000 flags=0x4, data=0x0, data_size=0 crc=0x32
V bus_lock: dev 5 acquired.
D (3448) spi_master: device5 locked the bus
V (3458) spi_master: polling trans
V (3458) spi_master: polling trans done
V (3458) spi_master: polling trans
V (3468) spi_master: polling trans done
V (3468) spi_master: polling trans
V (3478) spi_master: polling trans done
V (3478) spi_master: polling trans
V (3478) spi_master: polling trans done
D (3488) spi_master: device5 release bus
V (3488) bus_lock: dev 5 released.
V (3488) sdspi_transaction: r1 = 0x01 hw_cmd.r[0]=0xffffffff
V (3498) sdmmc_cmd: cmd response 00000001 00000000 00000000 00000000 err=0x0 state=0
V (3508) sdmmc_cmd: sending cmd slot=1 op=41 arg=40ff8000 flags=1030 data=0x0 blklen=0 datalen=0 timeout=3000
V (3518) sdspi_host: sdspi_host_start_command: slot=1, CMD41, arg=0x40ff8000 flags=0x4, data=0x0, data_size=0 crc=0x0b
V bus_lock: dev 5 acquired.
D (3528) spi_master: device5 locked the bus
V (3538) spi_master: polling trans
V (3538) spi_master: polling trans done
V (3538) spi_master: polling trans
V (3548) spi_master: polling trans done
V (3548) spi_master: polling trans
V (3548) spi_master: polling trans done
V (3558) spi_master: polling trans
V (3558) spi_master: polling trans done
D (3558) spi_master: device5 release bus
V (3568) bus_lock: dev 5 released.
V (3568) sdspi_transaction: r1 = 0x01 hw_cmd.r[0]=0xffffffff
V (3578) sdmmc_cmd: cmd response 00000001 00000000 00000000 00000000 err=0x0 state=0
V (3598) sdmmc_cmd: sending cmd slot=1 op=55 arg=0 flags=1c00 data=0x0 blklen=0 datalen=0 timeout=3000
V (3598) sdspi_host: sdspi_host_start_command: slot=1, CMD55, arg=0x00000000 flags=0x4, data=0x0, data_size=0 crc=0x32
V bus_lock: dev 5 acquired.
D (3608) spi_master: device5 locked the bus
V (3608) spi_master: polling trans
V (3618) spi_master: polling trans done
V (3618) spi_master: polling trans
V (3618) spi_master: polling trans done
V (3628) spi_master: polling trans
V (3628) spi_master: polling trans done
V (3628) spi_master: polling trans
V (3638) spi_master: polling trans done
D (3638) spi_master: device5 release bus
V (3648) bus_lock: dev 5 released.
V (3648) sdspi_transaction: r1 = 0x01 hw_cmd.r[0]=0xffffffff
V (3658) sdmmc_cmd: cmd response 00000001 00000000 00000000 00000000 err=0x0 state=0
V (3658) sdmmc_cmd: sending cmd slot=1 op=41 arg=40ff8000 flags=1030 data=0x0 blklen=0 datalen=0 timeout=3000
V (3668) sdspi_host: sdspi_host_start_command: slot=1, CMD41, arg=0x40ff8000 flags=0x4, data=0x0, data_size=0 crc=0x0b
V bus_lock: dev 5 acquired.
D (3688) spi_master: device5 locked the bus
V (3688) spi_master: polling trans
V (3688) spi_master: polling trans done
V (3698) spi_master: polling trans
V (3698) spi_master: polling trans done
V (3708) spi_master: polling trans
V (3708) spi_master: polling trans done
V (3708) spi_master: polling trans
V (3718) spi_master: polling trans done
D (3718) spi_master: device5 release bus
V (3718) bus_lock: dev 5 released.
V (3728) sdspi_transaction: r1 = 0x01 hw_cmd.r[0]=0xffffffff
V (3728) sdmmc_cmd: cmd response 00000001 00000000 00000000 00000000 err=0x0 state=0
V (3748) sdmmc_cmd: sending cmd slot=1 op=55 arg=0 flags=1c00 data=0x0 blklen=0 datalen=0 timeout=3000
V (3748) sdspi_host: sdspi_host_start_command: slot=1, CMD55, arg=0x00000000 flags=0x4, data=0x0, data_size=0 crc=0x32
V bus_lock: dev 5 acquired.
D (3758) spi_master: device5 locked the bus
V (3768) spi_master: polling trans
V (3768) spi_master: polling trans done
V (3778) spi_master: polling trans
V (3778) spi_master: polling trans done
V (3778) spi_master: polling trans
V (3788) spi_master: polling trans done
V (3788) spi_master: polling trans
V (3788) spi_master: polling trans done
D (3798) spi_master: device5 release bus
V (3798) bus_lock: dev 5 released.
V (3798) sdspi_transaction: r1 = 0x01 hw_cmd.r[0]=0xffffffff
V (3808) sdmmc_cmd: cmd response 00000001 00000000 00000000 00000000 err=0x0 state=0
V (3818) sdmmc_cmd: sending cmd slot=1 op=41 arg=40ff8000 flags=1030 data=0x0 blklen=0 datalen=0 timeout=3000
V (3828) sdspi_host: sdspi_host_start_command: slot=1, CMD41, arg=0x40ff8000 flags=0x4, data=0x0, data_size=0 crc=0x0b
V bus_lock: dev 5 acquired.
D (3838) spi_master: device5 locked the bus
V (3848) spi_master: polling trans
V (3848) spi_master: polling trans done
V (3848) spi_master: polling trans
V (3858) spi_master: polling trans done
V (3858) spi_master: polling trans
V (3858) spi_master: polling trans done
V (3868) spi_master: polling trans
V (3868) spi_master: polling trans done
D (3878) spi_master: device5 release bus
V (3878) bus_lock: dev 5 released.
V (3878) sdspi_transaction: r1 = 0x01 hw_cmd.r[0]=0xffffffff
V (3888) sdmmc_cmd: cmd response 00000001 00000000 00000000 00000000 err=0x0 state=0
V (3908) sdmmc_cmd: sending cmd slot=1 op=55 arg=0 flags=1c00 data=0x0 blklen=0 datalen=0 timeout=3000
V (3908) sdspi_host: sdspi_host_start_command: slot=1, CMD55, arg=0x00000000 flags=0x4, data=0x0, data_size=0 crc=0x32
V bus_lock: dev 5 acquired.
D (3918) spi_master: device5 locked the bus
V (3918) spi_master: polling trans
V (3928) spi_master: polling trans done
V (3928) spi_master: polling trans
V (3928) spi_master: polling trans done
V (3938) spi_master: polling trans
V (3938) spi_master: polling trans done
V (3948) spi_master: polling trans
V (3948) spi_master: polling trans done
D (3948) spi_master: device5 release bus
V (3958) bus_lock: dev 5 released.
V (3958) sdspi_transaction: r1 = 0x01 hw_cmd.r[0]=0xffffffff
V (3968) sdmmc_cmd: cmd response 00000001 00000000 00000000 00000000 err=0x0 state=0
V (3968) sdmmc_cmd: sending cmd slot=1 op=41 arg=40ff8000 flags=1030 data=0x0 blklen=0 datalen=0 timeout=3000
V (3978) sdspi_host: sdspi_host_start_command: slot=1, CMD41, arg=0x40ff8000 flags=0x4, data=0x0, data_size=0 crc=0x0b
V bus_lock: dev 5 acquired.
D (3998) spi_master: device5 locked the bus
V (3998) spi_master: polling trans
V (4008) spi_master: polling trans done
V (4008) spi_master: polling trans
V (4008) spi_master: polling trans done
V (4018) spi_master: polling trans
V (4018) spi_master: polling trans done
V (4018) spi_master: polling trans
V (4028) spi_master: polling trans done
D (4028) spi_master: device5 release bus
V (4038) bus_lock: dev 5 released.
V (4038) sdspi_transaction: r1 = 0x01 hw_cmd.r[0]=0xffffffff
V (4038) sdmmc_cmd: cmd response 00000001 00000000 00000000 00000000 err=0x0 state=0
V (4058) sdmmc_cmd: sending cmd slot=1 op=55 arg=0 flags=1c00 data=0x0 blklen=0 datalen=0 timeout=3000
V (4058) sdspi_host: sdspi_host_start_command: slot=1, CMD55, arg=0x00000000 flags=0x4, data=0x0, data_size=0 crc=0x32
V bus_lock: dev 5 acquired.
D (4068) spi_master: device5 locked the bus
V (4078) spi_master: polling trans
V (4078) spi_master: polling trans done
V (4088) spi_master: polling trans
V (4088) spi_master: polling trans done
V (4088) spi_master: polling trans
V (4098) spi_master: polling trans done
V (4098) spi_master: polling trans
V (4098) spi_master: polling trans done
D (4108) spi_master: device5 release bus
V (4108) bus_lock: dev 5 released.
V (4118) sdspi_transaction: r1 = 0x01 hw_cmd.r[0]=0xffffffff
V (4118) sdmmc_cmd: cmd response 00000001 00000000 00000000 00000000 err=0x0 state=0
V (4128) sdmmc_cmd: sending cmd slot=1 op=41 arg=40ff8000 flags=1030 data=0x0 blklen=0 datalen=0 timeout=3000
V (4138) sdspi_host: sdspi_host_start_command: slot=1, CMD41, arg=0x40ff8000 flags=0x4, data=0x0, data_size=0 crc=0x0b
V bus_lock: dev 5 acquired.
D (4148) spi_master: device5 locked the bus
V (4158) spi_master: polling trans
V (4158) spi_master: polling trans done
V (4158) spi_master: polling trans
V (4168) spi_master: polling trans done
V (4168) spi_master: polling trans
V (4178) spi_master: polling trans done
V (4178) spi_master: polling trans
V (4178) spi_master: polling trans done
D (4188) spi_master: device5 release bus
V (4188) bus_lock: dev 5 released.
V (4188) sdspi_transaction: r1 = 0x01 hw_cmd.r[0]=0xffffffff
V (4198) sdmmc_cmd: cmd response 00000001 00000000 00000000 00000000 err=0x0 state=0
V (4218) sdmmc_cmd: sending cmd slot=1 op=55 arg=0 flags=1c00 data=0x0 blklen=0 datalen=0 timeout=3000
V (4218) sdspi_host: sdspi_host_start_command: slot=1, CMD55, arg=0x00000000 flags=0x4, data=0x0, data_size=0 crc=0x32
V bus_lock: dev 5 acquired.
D (4228) spi_master: device5 locked the bus
V (4228) spi_master: polling trans
V (4238) spi_master: polling trans done
V (4238) spi_master: polling trans
V (4248) spi_master: polling trans done
V (4248) spi_master: polling trans
V (4248) spi_master: polling trans done
V (4258) spi_master: polling trans
V (4258) spi_master: polling trans done
D (4258) spi_master: device5 release bus
V (4268) bus_lock: dev 5 released.
V (4268) sdspi_transaction: r1 = 0x01 hw_cmd.r[0]=0xffffffff
V (4278) sdmmc_cmd: cmd response 00000001 00000000 00000000 00000000 err=0x0 state=0
V (4278) sdmmc_cmd: sending cmd slot=1 op=41 arg=40ff8000 flags=1030 data=0x0 blklen=0 datalen=0 timeout=3000
V (4288) sdspi_host: sdspi_host_start_command: slot=1, CMD41, arg=0x40ff8000 flags=0x4, data=0x0, data_size=0 crc=0x0b
V bus_lock: dev 5 acquired.
D (4308) spi_master: device5 locked the bus
V (4308) spi_master: polling trans
V (4318) spi_master: polling trans done
V (4318) spi_master: polling trans
V (4318) spi_master: polling trans done
V (4328) spi_master: polling trans
V (4328) spi_master: polling trans done
V (4328) spi_master: polling trans
V (4338) spi_master: polling trans done
D (4338) spi_master: device5 release bus
V (4348) bus_lock: dev 5 released.
V (4348) sdspi_transaction: r1 = 0x01 hw_cmd.r[0]=0xffffffff
V (4358) sdmmc_cmd: cmd response 00000001 00000000 00000000 00000000 err=0x0 state=0
V (4368) sdmmc_cmd: sending cmd slot=1 op=55 arg=0 flags=1c00 data=0x0 blklen=0 datalen=0 timeout=3000
V (4368) sdspi_host: sdspi_host_start_command: slot=1, CMD55, arg=0x00000000 flags=0x4, data=0x0, data_size=0 crc=0x32
V bus_lock: dev 5 acquired.
D (4388) spi_master: device5 locked the bus
V (4388) spi_master: polling trans
V (4388) spi_master: polling trans done
V (4398) spi_master: polling trans
V (4398) spi_master: polling trans done
V (4398) spi_master: polling trans
V (4408) spi_master: polling trans done
V (4408) spi_master: polling trans
V (4418) spi_master: polling trans done
D (4418) spi_master: device5 release bus
V (4418) bus_lock: dev 5 released.
V (4428) sdspi_transaction: r1 = 0x01 hw_cmd.r[0]=0xffffffff
V (4428) sdmmc_cmd: cmd response 00000001 00000000 00000000 00000000 err=0x0 state=0
V (4438) sdmmc_cmd: sending cmd slot=1 op=41 arg=40ff8000 flags=1030 data=0x0 blklen=0 datalen=0 timeout=3000
V (4448) sdspi_host: sdspi_host_start_command: slot=1, CMD41, arg=0x40ff8000 flags=0x4, data=0x0, data_size=0 crc=0x0b
V bus_lock: dev 5 acquired.
D (4458) spi_master: device5 locked the bus
V (4468) spi_master: polling trans
V (4468) spi_master: polling trans done
V (4478) spi_master: polling trans
V (4478) spi_master: polling trans done
V (4478) spi_master: polling trans
V (4488) spi_master: polling trans done
V (4488) spi_master: polling trans
V (4488) spi_master: polling trans done
D (4498) spi_master: device5 release bus
V (4498) bus_lock: dev 5 released.
V (4498) sdspi_transaction: r1 = 0x01 hw_cmd.r[0]=0xffffffff
V (4508) sdmmc_cmd: cmd response 00000001 00000000 00000000 00000000 err=0x0 state=0
V (4528) sdmmc_cmd: sending cmd slot=1 op=55 arg=0 flags=1c00 data=0x0 blklen=0 datalen=0 timeout=3000
V (4528) sdspi_host: sdspi_host_start_command: slot=1, CMD55, arg=0x00000000 flags=0x4, data=0x0, data_size=0 crc=0x32
V bus_lock: dev 5 acquired.
D (4538) spi_master: device5 locked the bus
V (4548) spi_master: polling trans
V (4548) spi_master: polling trans done
V (4548) spi_master: polling trans
V (4558) spi_master: polling trans done
V (4558) spi_master: polling trans
V (4558) spi_master: polling trans done
V (4568) spi_master: polling trans
V (4568) spi_master: polling trans done
D (4568) spi_master: device5 release bus
V (4578) bus_lock: dev 5 released.
V (4578) sdspi_transaction: r1 = 0x01 hw_cmd.r[0]=0xffffffff
V (4588) sdmmc_cmd: cmd response 00000001 00000000 00000000 00000000 err=0x0 state=0
V (4598) sdmmc_cmd: sending cmd slot=1 op=41 arg=40ff8000 flags=1030 data=0x0 blklen=0 datalen=0 timeout=3000
V (4608) sdspi_host: sdspi_host_start_command: slot=1, CMD41, arg=0x40ff8000 flags=0x4, data=0x0, data_size=0 crc=0x0b
V bus_lock: dev 5 acquired.
D (4618) spi_master: device5 locked the bus
V (4618) spi_master: polling trans
V (4628) spi_master: polling trans done
V (4628) spi_master: polling trans
V (4628) spi_master: polling trans done
V (4638) spi_master: polling trans
V (4638) spi_master: polling trans done
V (4648) spi_master: polling trans
V (4648) spi_master: polling trans done
D (4648) spi_master: device5 release bus
V (4658) bus_lock: dev 5 released.
V (4658) sdspi_transaction: r1 = 0x01 hw_cmd.r[0]=0xffffffff
V (4668) sdmmc_cmd: cmd response 00000001 00000000 00000000 00000000 err=0x0 state=0
V (4678) sdmmc_cmd: sending cmd slot=1 op=55 arg=0 flags=1c00 data=0x0 blklen=0 datalen=0 timeout=3000
V (4678) sdspi_host: sdspi_host_start_command: slot=1, CMD55, arg=0x00000000 flags=0x4, data=0x0, data_size=0 crc=0x32
V bus_lock: dev 5 acquired.
D (4698) spi_master: device5 locked the bus
V (4698) spi_master: polling trans
V (4698) spi_master: polling trans done
V (4708) spi_master: polling trans
V (4708) spi_master: polling trans done
V (4718) spi_master: polling trans
V (4718) spi_master: polling trans done
V (4718) spi_master: polling trans
V (4728) spi_master: polling trans done
D (4728) spi_master: device5 release bus
V (4728) bus_lock: dev 5 released.
V (4738) sdspi_transaction: r1 = 0x01 hw_cmd.r[0]=0xffffffff
V (4738) sdmmc_cmd: cmd response 00000001 00000000 00000000 00000000 err=0x0 state=0
V (4748) sdmmc_cmd: sending cmd slot=1 op=41 arg=40ff8000 flags=1030 data=0x0 blklen=0 datalen=0 timeout=3000
V (4758) sdspi_host: sdspi_host_start_command: slot=1, CMD41, arg=0x40ff8000 flags=0x4, data=0x0, data_size=0 crc=0x0b
V bus_lock: dev 5 acquired.
D (4768) spi_master: device5 locked the bus
V (4778) spi_master: polling trans
V (4778) spi_master: polling trans done
V (4788) spi_master: polling trans
V (4788) spi_master: polling trans done
V (4788) spi_master: polling trans
V (4798) spi_master: polling trans done
V (4798) spi_master: polling trans
V (4798) spi_master: polling trans done
D (4808) spi_master: device5 release bus
V (4808) bus_lock: dev 5 released.
V (4818) sdspi_transaction: r1 = 0x01 hw_cmd.r[0]=0xffffffff
V (4818) sdmmc_cmd: cmd response 00000001 00000000 00000000 00000000 err=0x0 state=0
V (4838) sdmmc_cmd: sending cmd slot=1 op=55 arg=0 flags=1c00 data=0x0 blklen=0 datalen=0 timeout=3000
V (4838) sdspi_host: sdspi_host_start_command: slot=1, CMD55, arg=0x00000000 flags=0x4, data=0x0, data_size=0 crc=0x32
V bus_lock: dev 5 acquired.
D (4848) spi_master: device5 locked the bus
V (4858) spi_master: polling trans
V (4858) spi_master: polling trans done
V (4858) spi_master: polling trans
V (4868) spi_master: polling trans done
V (4868) spi_master: polling trans
V (4868) spi_master: polling trans done
V (4878) spi_master: polling trans
V (4878) spi_master: polling trans done
D (4888) spi_master: device5 release bus
V (4888) bus_lock: dev 5 released.
V (4888) sdspi_transaction: r1 = 0x01 hw_cmd.r[0]=0xffffffff
V (4898) sdmmc_cmd: cmd response 00000001 00000000 00000000 00000000 err=0x0 state=0
V (4908) sdmmc_cmd: sending cmd slot=1 op=41 arg=40ff8000 flags=1030 data=0x0 blklen=0 datalen=0 timeout=3000
V (4918) sdspi_host: sdspi_host_start_command: slot=1, CMD41, arg=0x40ff8000 flags=0x4, data=0x0, data_size=0 crc=0x0b
V bus_lock: dev 5 acquired.
D (4928) spi_master: device5 locked the bus
V (4928) spi_master: polling trans
V (4938) spi_master: polling trans done
V (4938) spi_master: polling trans
V (4948) spi_master: polling trans done
V (4948) spi_master: polling trans
V (4948) spi_master: polling trans done
V (4958) spi_master: polling trans
V (4958) spi_master: polling trans done
D (4958) spi_master: device5 release bus
V (4968) bus_lock: dev 5 released.
V (4968) sdspi_transaction: r1 = 0x01 hw_cmd.r[0]=0xffffffff
V (4978) sdmmc_cmd: cmd response 00000001 00000000 00000000 00000000 err=0x0 state=0
V (4988) sdmmc_cmd: sending cmd slot=1 op=55 arg=0 flags=1c00 data=0x0 blklen=0 datalen=0 timeout=3000
V (4988) sdspi_host: sdspi_host_start_command: slot=1, CMD55, arg=0x00000000 flags=0x4, data=0x0, data_size=0 crc=0x32
V bus_lock: dev 5 acquired.
D (5008) spi_master: device5 locked the bus
V (5008) spi_master: polling trans
V (5018) spi_master: polling trans done
V (5018) spi_master: polling trans
V (5018) spi_master: polling trans done
V (5028) spi_master: polling trans
V (5028) spi_master: polling trans done
V (5028) spi_master: polling trans
V (5038) spi_master: polling trans done
D (5038) spi_master: device5 release bus
V (5048) bus_lock: dev 5 released.
V (5048) sdspi_transaction: r1 = 0x01 hw_cmd.r[0]=0xffffffff
V (5048) sdmmc_cmd: cmd response 00000001 00000000 00000000 00000000 err=0x0 state=0
V (5058) sdmmc_cmd: sending cmd slot=1 op=41 arg=40ff8000 flags=1030 data=0x0 blklen=0 datalen=0 timeout=3000
V (5068) sdspi_host: sdspi_host_start_command: slot=1, CMD41, arg=0x40ff8000 flags=0x4, data=0x0, data_size=0 crc=0x0b
V bus_lock: dev 5 acquired.
D (5088) spi_master: device5 locked the bus
V (5088) spi_master: polling trans
V (5088) spi_master: polling trans done
V (5098) spi_master: polling trans
V (5098) spi_master: polling trans done
V (5098) spi_master: polling trans
V (5108) spi_master: polling trans done
V (5108) spi_master: polling trans
V (5118) spi_master: polling trans done
D (5118) spi_master: device5 release bus
V (5118) bus_lock: dev 5 released.
V (5128) sdspi_transaction: r1 = 0x01 hw_cmd.r[0]=0xffffffff
V (5128) sdmmc_cmd: cmd response 00000001 00000000 00000000 00000000 err=0x0 state=0
V (5148) sdmmc_cmd: sending cmd slot=1 op=55 arg=0 flags=1c00 data=0x0 blklen=0 datalen=0 timeout=3000
V (5148) sdspi_host: sdspi_host_start_command: slot=1, CMD55, arg=0x00000000 flags=0x4, data=0x0, data_size=0 crc=0x32
V bus_lock: dev 5 acquired.
D (5158) spi_master: device5 locked the bus
V (5168) spi_master: polling trans
V (5168) spi_master: polling trans done
V (5168) spi_master: polling trans
V (5178) spi_master: polling trans done
V (5178) spi_master: polling trans
V (5188) spi_master: polling trans done
V (5188) spi_master: polling trans
V (5188) spi_master: polling trans done
D (5198) spi_master: device5 release bus
V (5198) bus_lock: dev 5 released.
V (5198) sdspi_transaction: r1 = 0x01 hw_cmd.r[0]=0xffffffff
V (5208) sdmmc_cmd: cmd response 00000001 00000000 00000000 00000000 err=0x0 state=0
V (5218) sdmmc_cmd: sending cmd slot=1 op=41 arg=40ff8000 flags=1030 data=0x0 blklen=0 datalen=0 timeout=3000
V (5228) sdspi_host: sdspi_host_start_command: slot=1, CMD41, arg=0x40ff8000 flags=0x4, data=0x0, data_size=0 crc=0x0b
V bus_lock: dev 5 acquired.
D (5238) spi_master: device5 locked the bus
V (5248) spi_master: polling trans
V (5248) spi_master: polling trans done
V (5248) spi_master: polling trans
V (5258) spi_master: polling trans done
V (5258) spi_master: polling trans
V (5258) spi_master: polling trans done
V (5268) spi_master: polling trans
V (5268) spi_master: polling trans done
D (5268) spi_master: device5 release bus
V (5278) bus_lock: dev 5 released.
V (5278) sdspi_transaction: r1 = 0x01 hw_cmd.r[0]=0xffffffff
V (5288) sdmmc_cmd: cmd response 00000001 00000000 00000000 00000000 err=0x0 state=0
V (5308) sdmmc_cmd: sending cmd slot=1 op=55 arg=0 flags=1c00 data=0x0 blklen=0 datalen=0 timeout=3000
V (5308) sdspi_host: sdspi_host_start_command: slot=1, CMD55, arg=0x00000000 flags=0x4, data=0x0, data_size=0 crc=0x32
V bus_lock: dev 5 acquired.
D (5318) spi_master: device5 locked the bus
V (5318) spi_master: polling trans
V (5328) spi_master: polling trans done
V (5328) spi_master: polling trans
V (5328) spi_master: polling trans done
V (5338) spi_master: polling trans
V (5338) spi_master: polling trans done
V (5338) spi_master: polling trans
V (5348) spi_master: polling trans done
D (5348) spi_master: device5 release bus
V (5358) bus_lock: dev 5 released.
V (5358) sdspi_transaction: r1 = 0x01 hw_cmd.r[0]=0xffffffff
V (5368) sdmmc_cmd: cmd response 00000001 00000000 00000000 00000000 err=0x0 state=0
V (5368) sdmmc_cmd: sending cmd slot=1 op=41 arg=40ff8000 flags=1030 data=0x0 blklen=0 datalen=0 timeout=3000
V (5378) sdspi_host: sdspi_host_start_command: slot=1, CMD41, arg=0x40ff8000 flags=0x4, data=0x0, data_size=0 crc=0x0b
V bus_lock: dev 5 acquired.
D (5398) spi_master: device5 locked the bus
V (5398) spi_master: polling trans
V (5398) spi_master: polling trans done
V (5408) spi_master: polling trans
V (5408) spi_master: polling trans done
V (5418) spi_master: polling trans
V (5418) spi_master: polling trans done
V (5418) spi_master: polling trans
V (5428) spi_master: polling trans done
D (5428) spi_master: device5 release bus
V (5428) bus_lock: dev 5 released.
V (5438) sdspi_transaction: r1 = 0x01 hw_cmd.r[0]=0xffffffff
V (5438) sdmmc_cmd: cmd response 00000001 00000000 00000000 00000000 err=0x0 state=0
V (5458) sdmmc_cmd: sending cmd slot=1 op=55 arg=0 flags=1c00 data=0x0 blklen=0 datalen=0 timeout=3000
V (5458) sdspi_host: sdspi_host_start_command: slot=1, CMD55, arg=0x00000000 flags=0x4, data=0x0, data_size=0 crc=0x32
V bus_lock: dev 5 acquired.
D (5468) spi_master: device5 locked the bus
V (5478) spi_master: polling trans
V (5478) spi_master: polling trans done
V (5488) spi_master: polling trans
V (5488) spi_master: polling trans done
V (5488) spi_master: polling trans
V (5498) spi_master: polling trans done
V (5498) spi_master: polling trans
V (5498) spi_master: polling trans done
D (5508) spi_master: device5 release bus
V (5508) bus_lock: dev 5 released.
V (5518) sdspi_transaction: r1 = 0x01 hw_cmd.r[0]=0xffffffff
V (5518) sdmmc_cmd: cmd response 00000001 00000000 00000000 00000000 err=0x0 state=0
V (5528) sdmmc_cmd: sending cmd slot=1 op=41 arg=40ff8000 flags=1030 data=0x0 blklen=0 datalen=0 timeout=3000
V (5538) sdspi_host: sdspi_host_start_command: slot=1, CMD41, arg=0x40ff8000 flags=0x4, data=0x0, data_size=0 crc=0x0b
V bus_lock: dev 5 acquired.
D (5548) spi_master: device5 locked the bus
V (5558) spi_master: polling trans
V (5558) spi_master: polling trans done
V (5558) spi_master: polling trans
V (5568) spi_master: polling trans done
V (5568) spi_master: polling trans
V (5568) spi_master: polling trans done
V (5578) spi_master: polling trans
V (5578) spi_master: polling trans done
D (5588) spi_master: device5 release bus
V (5588) bus_lock: dev 5 released.
V (5588) sdspi_transaction: r1 = 0x01 hw_cmd.r[0]=0xffffffff
V (5598) sdmmc_cmd: cmd response 00000001 00000000 00000000 00000000 err=0x0 state=0
V (5618) sdmmc_cmd: sending cmd slot=1 op=55 arg=0 flags=1c00 data=0x0 blklen=0 datalen=0 timeout=3000
V (5618) sdspi_host: sdspi_host_start_command: slot=1, CMD55, arg=0x00000000 flags=0x4, data=0x0, data_size=0 crc=0x32
V bus_lock: dev 5 acquired.
D (5628) spi_master: device5 locked the bus
V (5628) spi_master: polling trans
V (5638) spi_master: polling trans done
V (5638) spi_master: polling trans
V (5638) spi_master: polling trans done
V (5648) spi_master: polling trans
V (5648) spi_master: polling trans done
V (5658) spi_master: polling trans
V (5658) spi_master: polling trans done
D (5658) spi_master: device5 release bus
V (5668) bus_lock: dev 5 released.
V (5668) sdspi_transaction: r1 = 0x01 hw_cmd.r[0]=0xffffffff
V (5678) sdmmc_cmd: cmd response 00000001 00000000 00000000 00000000 err=0x0 state=0
V (5678) sdmmc_cmd: sending cmd slot=1 op=41 arg=40ff8000 flags=1030 data=0x0 blklen=0 datalen=0 timeout=3000
V (5688) sdspi_host: sdspi_host_start_command: slot=1, CMD41, arg=0x40ff8000 flags=0x4, data=0x0, data_size=0 crc=0x0b
V bus_lock: dev 5 acquired.
D (5708) spi_master: device5 locked the bus
V (5708) spi_master: polling trans
V (5718) spi_master: polling trans done
V (5718) spi_master: polling trans
V (5718) spi_master: polling trans done
V (5728) spi_master: polling trans
V (5728) spi_master: polling trans done
V (5728) spi_master: polling trans
V (5738) spi_master: polling trans done
D (5738) spi_master: device5 release bus
V (5748) bus_lock: dev 5 released.
V (5748) sdspi_transaction: r1 = 0x01 hw_cmd.r[0]=0xffffffff
V (5748) sdmmc_cmd: cmd response 00000001 00000000 00000000 00000000 err=0x0 state=0
V (5768) sdmmc_cmd: sending cmd slot=1 op=55 arg=0 flags=1c00 data=0x0 blklen=0 datalen=0 timeout=3000
V (5768) sdspi_host: sdspi_host_start_command: slot=1, CMD55, arg=0x00000000 flags=0x4, data=0x0, data_size=0 crc=0x32
V bus_lock: dev 5 acquired.
D (5778) spi_master: device5 locked the bus
V (5788) spi_master: polling trans
V (5788) spi_master: polling trans done
V (5798) spi_master: polling trans
V (5798) spi_master: polling trans done
V (5798) spi_master: polling trans
V (5808) spi_master: polling trans done
V (5808) spi_master: polling trans
V (5808) spi_master: polling trans done
D (5818) spi_master: device5 release bus
V (5818) bus_lock: dev 5 released.
V (5828) sdspi_transaction: r1 = 0x01 hw_cmd.r[0]=0xffffffff
V (5828) sdmmc_cmd: cmd response 00000001 00000000 00000000 00000000 err=0x0 state=0
V (5838) sdmmc_cmd: sending cmd slot=1 op=41 arg=40ff8000 flags=1030 data=0x0 blklen=0 datalen=0 timeout=3000
V (5848) sdspi_host: sdspi_host_start_command: slot=1, CMD41, arg=0x40ff8000 flags=0x4, data=0x0, data_size=0 crc=0x0b
V bus_lock: dev 5 acquired.
D (5858) spi_master: device5 locked the bus
V (5868) spi_master: polling trans
V (5868) spi_master: polling trans done
V (5868) spi_master: polling trans
V (5878) spi_master: polling trans done
V (5878) spi_master: polling trans
V (5888) spi_master: polling trans done
V (5888) spi_master: polling trans
V (5888) spi_master: polling trans done
D (5898) spi_master: device5 release bus
V (5898) bus_lock: dev 5 released.
V (5898) sdspi_transaction: r1 = 0x01 hw_cmd.r[0]=0xffffffff
V (5908) sdmmc_cmd: cmd response 00000001 00000000 00000000 00000000 err=0x0 state=0
V (5928) sdmmc_cmd: sending cmd slot=1 op=55 arg=0 flags=1c00 data=0x0 blklen=0 datalen=0 timeout=3000
V (5928) sdspi_host: sdspi_host_start_command: slot=1, CMD55, arg=0x00000000 flags=0x4, data=0x0, data_size=0 crc=0x32
V bus_lock: dev 5 acquired.
D (5938) spi_master: device5 locked the bus
V (5938) spi_master: polling trans
V (5948) spi_master: polling trans done
V (5948) spi_master: polling trans
V (5958) spi_master: polling trans done
V (5958) spi_master: polling trans
V (5958) spi_master: polling trans done
V (5968) spi_master: polling trans
V (5968) spi_master: polling trans done
D (5968) spi_master: device5 release bus
V (5978) bus_lock: dev 5 released.
V (5978) sdspi_transaction: r1 = 0x01 hw_cmd.r[0]=0xffffffff
V (5988) sdmmc_cmd: cmd response 00000001 00000000 00000000 00000000 err=0x0 state=0
V (5998) sdmmc_cmd: sending cmd slot=1 op=41 arg=40ff8000 flags=1030 data=0x0 blklen=0 datalen=0 timeout=3000
V (6008) sdspi_host: sdspi_host_start_command: slot=1, CMD41, arg=0x40ff8000 flags=0x4, data=0x0, data_size=0 crc=0x0b
V bus_lock: dev 5 acquired.
D (6018) spi_master: device5 locked the bus
V (6018) spi_master: polling trans
V (6028) spi_master: polling trans done
V (6028) spi_master: polling trans
V (6028) spi_master: polling trans done
V (6038) spi_master: polling trans
V (6038) spi_master: polling trans done
V (6038) spi_master: polling trans
V (6048) spi_master: polling trans done
D (6048) spi_master: device5 release bus
V (6058) bus_lock: dev 5 released.
V (6058) sdspi_transaction: r1 = 0x01 hw_cmd.r[0]=0xffffffff
V (6068) sdmmc_cmd: cmd response 00000001 00000000 00000000 00000000 err=0x0 state=0
V (6078) sdmmc_cmd: sending cmd slot=1 op=55 arg=0 flags=1c00 data=0x0 blklen=0 datalen=0 timeout=3000
V (6078) sdspi_host: sdspi_host_start_command: slot=1, CMD55, arg=0x00000000 flags=0x4, data=0x0, data_size=0 crc=0x32
V bus_lock: dev 5 acquired.
D (6098) spi_master: device5 locked the bus
V (6098) spi_master: polling trans
V (6098) spi_master: polling trans done
V (6108) spi_master: polling trans
V (6108) spi_master: polling trans done
V (6108) spi_master: polling trans
V (6118) spi_master: polling trans done
V (6118) spi_master: polling trans
V (6128) spi_master: polling trans done
D (6128) spi_master: device5 release bus
V (6128) bus_lock: dev 5 released.
V (6138) sdspi_transaction: r1 = 0x01 hw_cmd.r[0]=0xffffffff
V (6138) sdmmc_cmd: cmd response 00000001 00000000 00000000 00000000 err=0x0 state=0
V (6148) sdmmc_cmd: sending cmd slot=1 op=41 arg=40ff8000 flags=1030 data=0x0 blklen=0 datalen=0 timeout=3000
V (6158) sdspi_host: sdspi_host_start_command: slot=1, CMD41, arg=0x40ff8000 flags=0x4, data=0x0, data_size=0 crc=0x0b
V bus_lock: dev 5 acquired.
D (6168) spi_master: device5 locked the bus
V (6178) spi_master: polling trans
V (6178) spi_master: polling trans done
V (6188) spi_master: polling trans
V (6188) spi_master: polling trans done
V (6188) spi_master: polling trans
V (6198) spi_master: polling trans done
V (6198) spi_master: polling trans
V (6198) spi_master: polling trans done
D (6208) spi_master: device5 release bus
V (6208) bus_lock: dev 5 released.
V (6218) sdspi_transaction: r1 = 0x01 hw_cmd.r[0]=0xffffffff
V (6218) sdmmc_cmd: cmd response 00000001 00000000 00000000 00000000 err=0x0 state=0
V (6238) sdmmc_cmd: sending cmd slot=1 op=55 arg=0 flags=1c00 data=0x0 blklen=0 datalen=0 timeout=3000
V (6238) sdspi_host: sdspi_host_start_command: slot=1, CMD55, arg=0x00000000 flags=0x4, data=0x0, data_size=0 crc=0x32
V bus_lock: dev 5 acquired.
D (6248) spi_master: device5 locked the bus
V (6258) spi_master: polling trans
V (6258) spi_master: polling trans done
V (6258) spi_master: polling trans
V (6268) spi_master: polling trans done
V (6268) spi_master: polling trans
V (6268) spi_master: polling trans done
V (6278) spi_master: polling trans
V (6278) spi_master: polling trans done
D (6288) spi_master: device5 release bus
V (6288) bus_lock: dev 5 released.
V (6288) sdspi_transaction: r1 = 0x01 hw_cmd.r[0]=0xffffffff
V (6298) sdmmc_cmd: cmd response 00000001 00000000 00000000 00000000 err=0x0 state=0
V (6308) sdmmc_cmd: sending cmd slot=1 op=41 arg=40ff8000 flags=1030 data=0x0 blklen=0 datalen=0 timeout=3000
V (6318) sdspi_host: sdspi_host_start_command: slot=1, CMD41, arg=0x40ff8000 flags=0x4, data=0x0, data_size=0 crc=0x0b
V bus_lock: dev 5 acquired.
D (6328) spi_master: device5 locked the bus
V (6328) spi_master: polling trans
V (6338) spi_master: polling trans done
V (6338) spi_master: polling trans
V (6338) spi_master: polling trans done
V (6348) spi_master: polling trans
V (6348) spi_master: polling trans done
V (6358) spi_master: polling trans
V (6358) spi_master: polling trans done
D (6358) spi_master: device5 release bus
V (6368) bus_lock: dev 5 released.
V (6368) sdspi_transaction: r1 = 0x01 hw_cmd.r[0]=0xffffffff
V (6378) sdmmc_cmd: cmd response 00000001 00000000 00000000 00000000 err=0x0 state=0
V (6388) sdmmc_cmd: sending cmd slot=1 op=55 arg=0 flags=1c00 data=0x0 blklen=0 datalen=0 timeout=3000
V (6388) sdspi_host: sdspi_host_start_command: slot=1, CMD55, arg=0x00000000 flags=0x4, data=0x0, data_size=0 crc=0x32
V bus_lock: dev 5 acquired.
D (6408) spi_master: device5 locked the bus
V (6408) spi_master: polling trans
V (6408) spi_master: polling trans done
V (6418) spi_master: polling trans
V (6418) spi_master: polling trans done
V (6428) spi_master: polling trans
V (6428) spi_master: polling trans done
V (6428) spi_master: polling trans
V (6438) spi_master: polling trans done
D (6438) spi_master: device5 release bus
V (6438) bus_lock: dev 5 released.
V (6448) sdspi_transaction: r1 = 0x01 hw_cmd.r[0]=0xffffffff
V (6448) sdmmc_cmd: cmd response 00000001 00000000 00000000 00000000 err=0x0 state=0
V (6458) sdmmc_cmd: sending cmd slot=1 op=41 arg=40ff8000 flags=1030 data=0x0 blklen=0 datalen=0 timeout=3000
V (6468) sdspi_host: sdspi_host_start_command: slot=1, CMD41, arg=0x40ff8000 flags=0x4, data=0x0, data_size=0 crc=0x0b
V bus_lock: dev 5 acquired.
D (6478) spi_master: device5 locked the bus
V (6488) spi_master: polling trans
V (6488) spi_master: polling trans done
V (6498) spi_master: polling trans
V (6498) spi_master: polling trans done
V (6498) spi_master: polling trans
V (6508) spi_master: polling trans done
V (6508) spi_master: polling trans
V (6508) spi_master: polling trans done
D (6518) spi_master: device5 release bus
V (6518) bus_lock: dev 5 released.
V (6528) sdspi_transaction: r1 = 0x01 hw_cmd.r[0]=0xffffffff
V (6528) sdmmc_cmd: cmd response 00000001 00000000 00000000 00000000 err=0x0 state=0
V (6548) sdmmc_cmd: sending cmd slot=1 op=55 arg=0 flags=1c00 data=0x0 blklen=0 datalen=0 timeout=3000
V (6548) sdspi_host: sdspi_host_start_command: slot=1, CMD55, arg=0x00000000 flags=0x4, data=0x0, data_size=0 crc=0x32
V bus_lock: dev 5 acquired.
D (6558) spi_master: device5 locked the bus
V (6568) spi_master: polling trans
V (6568) spi_master: polling trans done
V (6568) spi_master: polling trans
V (6578) spi_master: polling trans done
V (6578) spi_master: polling trans
V (6578) spi_master: polling trans done
V (6588) spi_master: polling trans
V (6588) spi_master: polling trans done
D (6598) spi_master: device5 release bus
V (6598) bus_lock: dev 5 released.
V (6598) sdspi_transaction: r1 = 0x01 hw_cmd.r[0]=0xffffffff
V (6608) sdmmc_cmd: cmd response 00000001 00000000 00000000 00000000 err=0x0 state=0
V (6618) sdmmc_cmd: sending cmd slot=1 op=41 arg=40ff8000 flags=1030 data=0x0 blklen=0 datalen=0 timeout=3000
V (6628) sdspi_host: sdspi_host_start_command: slot=1, CMD41, arg=0x40ff8000 flags=0x4, data=0x0, data_size=0 crc=0x0b
V bus_lock: dev 5 acquired.
D (6638) spi_master: device5 locked the bus
V (6638) spi_master: polling trans
V (6648) spi_master: polling trans done
V (6648) spi_master: polling trans
V (6658) spi_master: polling trans done
V (6658) spi_master: polling trans
V (6658) spi_master: polling trans done
V (6668) spi_master: polling trans
V (6668) spi_master: polling trans done
D (6668) spi_master: device5 release bus
V (6678) bus_lock: dev 5 released.
V (6678) sdspi_transaction: r1 = 0x01 hw_cmd.r[0]=0xffffffff
V (6688) sdmmc_cmd: cmd response 00000001 00000000 00000000 00000000 err=0x0 state=0
V (6698) sdmmc_cmd: sending cmd slot=1 op=55 arg=0 flags=1c00 data=0x0 blklen=0 datalen=0 timeout=3000
V (6698) sdspi_host: sdspi_host_start_command: slot=1, CMD55, arg=0x00000000 flags=0x4, data=0x0, data_size=0 crc=0x32
V bus_lock: dev 5 acquired.
D (6718) spi_master: device5 locked the bus
V (6718) spi_master: polling trans
V (6728) spi_master: polling trans done
V (6728) spi_master: polling trans
V (6728) spi_master: polling trans done
V (6738) spi_master: polling trans
V (6738) spi_master: polling trans done
V (6738) spi_master: polling trans
V (6748) spi_master: polling trans done
D (6748) spi_master: device5 release bus
V (6758) bus_lock: dev 5 released.
V (6758) sdspi_transaction: r1 = 0x01 hw_cmd.r[0]=0xffffffff
V (6758) sdmmc_cmd: cmd response 00000001 00000000 00000000 00000000 err=0x0 state=0
V (6768) sdmmc_cmd: sending cmd slot=1 op=41 arg=40ff8000 flags=1030 data=0x0 blklen=0 datalen=0 timeout=3000
V (6778) sdspi_host: sdspi_host_start_command: slot=1, CMD41, arg=0x40ff8000 flags=0x4, data=0x0, data_size=0 crc=0x0b
V bus_lock: dev 5 acquired.
D (6798) spi_master: device5 locked the bus
V (6798) spi_master: polling trans
V (6798) spi_master: polling trans done
V (6808) spi_master: polling trans
V (6808) spi_master: polling trans done
V (6808) spi_master: polling trans
V (6818) spi_master: polling trans done
V (6818) spi_master: polling trans
V (6828) spi_master: polling trans done
D (6828) spi_master: device5 release bus
V (6828) bus_lock: dev 5 released.
V (6838) sdspi_transaction: r1 = 0x01 hw_cmd.r[0]=0xffffffff
V (6838) sdmmc_cmd: cmd response 00000001 00000000 00000000 00000000 err=0x0 state=0
V (6858) sdmmc_cmd: sending cmd slot=1 op=55 arg=0 flags=1c00 data=0x0 blklen=0 datalen=0 timeout=3000
V (6858) sdspi_host: sdspi_host_start_command: slot=1, CMD55, arg=0x00000000 flags=0x4, data=0x0, data_size=0 crc=0x32
V bus_lock: dev 5 acquired.
D (6868) spi_master: device5 locked the bus
V (6878) spi_master: polling trans
V (6878) spi_master: polling trans done
V (6878) spi_master: polling trans
V (6888) spi_master: polling trans done
V (6888) spi_master: polling trans
V (6898) spi_master: polling trans done
V (6898) spi_master: polling trans
V (6898) spi_master: polling trans done
D (6908) spi_master: device5 release bus
V (6908) bus_lock: dev 5 released.
V (6908) sdspi_transaction: r1 = 0x01 hw_cmd.r[0]=0xffffffff
V (6918) sdmmc_cmd: cmd response 00000001 00000000 00000000 00000000 err=0x0 state=0
V (6928) sdmmc_cmd: sending cmd slot=1 op=41 arg=40ff8000 flags=1030 data=0x0 blklen=0 datalen=0 timeout=3000
V (6938) sdspi_host: sdspi_host_start_command: slot=1, CMD41, arg=0x40ff8000 flags=0x4, data=0x0, data_size=0 crc=0x0b
V bus_lock: dev 5 acquired.
D (6948) spi_master: device5 locked the bus
V (6958) spi_master: polling trans
V (6958) spi_master: polling trans done
V (6958) spi_master: polling trans
V (6968) spi_master: polling trans done
V (6968) spi_master: polling trans
V (6968) spi_master: polling trans done
V (6978) spi_master: polling trans
V (6978) spi_master: polling trans done
D (6978) spi_master: device5 release bus
V (6988) bus_lock: dev 5 released.
V (6988) sdspi_transaction: r1 = 0x01 hw_cmd.r[0]=0xffffffff
V (6998) sdmmc_cmd: cmd response 00000001 00000000 00000000 00000000 err=0x0 state=0
V (7018) sdmmc_cmd: sending cmd slot=1 op=55 arg=0 flags=1c00 data=0x0 blklen=0 datalen=0 timeout=3000
V (7018) sdspi_host: sdspi_host_start_command: slot=1, CMD55, arg=0x00000000 flags=0x4, data=0x0, data_size=0 crc=0x32
V bus_lock: dev 5 acquired.
D (7028) spi_master: device5 locked the bus
V (7028) spi_master: polling trans
V (7038) spi_master: polling trans done
V (7038) spi_master: polling trans
V (7038) spi_master: polling trans done
V (7048) spi_master: polling trans
V (7048) spi_master: polling trans done
V (7048) spi_master: polling trans
V (7058) spi_master: polling trans done
D (7058) spi_master: device5 release bus
V (7068) bus_lock: dev 5 released.
V (7068) sdspi_transaction: r1 = 0x01 hw_cmd.r[0]=0xffffffff
V (7078) sdmmc_cmd: cmd response 00000001 00000000 00000000 00000000 err=0x0 state=0
V (7078) sdmmc_cmd: sending cmd slot=1 op=41 arg=40ff8000 flags=1030 data=0x0 blklen=0 datalen=0 timeout=3000
V (7088) sdspi_host: sdspi_host_start_command: slot=1, CMD41, arg=0x40ff8000 flags=0x4, data=0x0, data_size=0 crc=0x0b
V bus_lock: dev 5 acquired.
D (7108) spi_master: device5 locked the bus
V (7108) spi_master: polling trans
V (7108) spi_master: polling trans done
V (7118) spi_master: polling trans
V (7118) spi_master: polling trans done
V (7128) spi_master: polling trans
V (7128) spi_master: polling trans done
V (7128) spi_master: polling trans
V (7138) spi_master: polling trans done
D (7138) spi_master: device5 release bus
V (7138) bus_lock: dev 5 released.
V (7148) sdspi_transaction: r1 = 0x01 hw_cmd.r[0]=0xffffffff
V (7148) sdmmc_cmd: cmd response 00000001 00000000 00000000 00000000 err=0x0 state=0
V (7168) sdmmc_cmd: sending cmd slot=1 op=55 arg=0 flags=1c00 data=0x0 blklen=0 datalen=0 timeout=3000
V (7168) sdspi_host: sdspi_host_start_command: slot=1, CMD55, arg=0x00000000 flags=0x4, data=0x0, data_size=0 crc=0x32
V bus_lock: dev 5 acquired.
D (7178) spi_master: device5 locked the bus
V (7188) spi_master: polling trans
V (7188) spi_master: polling trans done
V (7198) spi_master: polling trans
V (7198) spi_master: polling trans done
V (7198) spi_master: polling trans
V (7208) spi_master: polling trans done
V (7208) spi_master: polling trans
V (7208) spi_master: polling trans done
D (7218) spi_master: device5 release bus
V (7218) bus_lock: dev 5 released.
V (7228) sdspi_transaction: r1 = 0x01 hw_cmd.r[0]=0xffffffff
V (7228) sdmmc_cmd: cmd response 00000001 00000000 00000000 00000000 err=0x0 state=0
V (7238) sdmmc_cmd: sending cmd slot=1 op=41 arg=40ff8000 flags=1030 data=0x0 blklen=0 datalen=0 timeout=3000
V (7248) sdspi_host: sdspi_host_start_command: slot=1, CMD41, arg=0x40ff8000 flags=0x4, data=0x0, data_size=0 crc=0x0b
V bus_lock: dev 5 acquired.
D (7258) spi_master: device5 locked the bus
V (7268) spi_master: polling trans
V (7268) spi_master: polling trans done
V (7268) spi_master: polling trans
V (7278) spi_master: polling trans done
V (7278) spi_master: polling trans
V (7278) spi_master: polling trans done
V (7288) spi_master: polling trans
V (7288) spi_master: polling trans done
D (7298) spi_master: device5 release bus
V (7298) bus_lock: dev 5 released.
V (7298) sdspi_transaction: r1 = 0x01 hw_cmd.r[0]=0xffffffff
V (7308) sdmmc_cmd: cmd response 00000001 00000000 00000000 00000000 err=0x0 state=0
V (7328) sdmmc_cmd: sending cmd slot=1 op=55 arg=0 flags=1c00 data=0x0 blklen=0 datalen=0 timeout=3000
V (7328) sdspi_host: sdspi_host_start_command: slot=1, CMD55, arg=0x00000000 flags=0x4, data=0x0, data_size=0 crc=0x32
V bus_lock: dev 5 acquired.
D (7338) spi_master: device5 locked the bus
V (7338) spi_master: polling trans
V (7348) spi_master: polling trans done
V (7348) spi_master: polling trans
V (7348) spi_master: polling trans done
V (7358) spi_master: polling trans
V (7358) spi_master: polling trans done
V (7368) spi_master: polling trans
V (7368) spi_master: polling trans done
D (7368) spi_master: device5 release bus
V (7378) bus_lock: dev 5 released.
V (7378) sdspi_transaction: r1 = 0x01 hw_cmd.r[0]=0xffffffff
V (7388) sdmmc_cmd: cmd response 00000001 00000000 00000000 00000000 err=0x0 state=0
V (7388) sdmmc_cmd: sending cmd slot=1 op=41 arg=40ff8000 flags=1030 data=0x0 blklen=0 datalen=0 timeout=3000
V (7398) sdspi_host: sdspi_host_start_command: slot=1, CMD41, arg=0x40ff8000 flags=0x4, data=0x0, data_size=0 crc=0x0b
V bus_lock: dev 5 acquired.
D (7418) spi_master: device5 locked the bus
V (7418) spi_master: polling trans
V (7428) spi_master: polling trans done
V (7428) spi_master: polling trans
V (7428) spi_master: polling trans done
V (7438) spi_master: polling trans
V (7438) spi_master: polling trans done
V (7438) spi_master: polling trans
V (7448) spi_master: polling trans done
D (7448) spi_master: device5 release bus
V (7458) bus_lock: dev 5 released.
V (7458) sdspi_transaction: r1 = 0x01 hw_cmd.r[0]=0xffffffff
V (7458) sdmmc_cmd: cmd response 00000001 00000000 00000000 00000000 err=0x0 state=0
V (7478) sdmmc_cmd: sending cmd slot=1 op=55 arg=0 flags=1c00 data=0x0 blklen=0 datalen=0 timeout=3000
V (7478) sdspi_host: sdspi_host_start_command: slot=1, CMD55, arg=0x00000000 flags=0x4, data=0x0, data_size=0 crc=0x32
V bus_lock: dev 5 acquired.
D (7488) spi_master: device5 locked the bus
V (7498) spi_master: polling trans
V (7498) spi_master: polling trans done
V (7508) spi_master: polling trans
V (7508) spi_master: polling trans done
V (7508) spi_master: polling trans
V (7518) spi_master: polling trans done
V (7518) spi_master: polling trans
V (7518) spi_master: polling trans done
D (7528) spi_master: device5 release bus
V (7528) bus_lock: dev 5 released.
V (7538) sdspi_transaction: r1 = 0x01 hw_cmd.r[0]=0xffffffff
V (7538) sdmmc_cmd: cmd response 00000001 00000000 00000000 00000000 err=0x0 state=0
V (7548) sdmmc_cmd: sending cmd slot=1 op=41 arg=40ff8000 flags=1030 data=0x0 blklen=0 datalen=0 timeout=3000
V (7558) sdspi_host: sdspi_host_start_command: slot=1, CMD41, arg=0x40ff8000 flags=0x4, data=0x0, data_size=0 crc=0x0b
V bus_lock: dev 5 acquired.
D (7568) spi_master: device5 locked the bus
V (7578) spi_master: polling trans
V (7578) spi_master: polling trans done
V (7578) spi_master: polling trans
V (7588) spi_master: polling trans done
V (7588) spi_master: polling trans
V (7598) spi_master: polling trans done
V (7598) spi_master: polling trans
V (7598) spi_master: polling trans done
D (7608) spi_master: device5 release bus
V (7608) bus_lock: dev 5 released.
V (7608) sdspi_transaction: r1 = 0x01 hw_cmd.r[0]=0xffffffff
V (7618) sdmmc_cmd: cmd response 00000001 00000000 00000000 00000000 err=0x0 state=0
V (7638) sdmmc_cmd: sending cmd slot=1 op=55 arg=0 flags=1c00 data=0x0 blklen=0 datalen=0 timeout=3000
V (7638) sdspi_host: sdspi_host_start_command: slot=1, CMD55, arg=0x00000000 flags=0x4, data=0x0, data_size=0 crc=0x32
V bus_lock: dev 5 acquired.
D (7648) spi_master: device5 locked the bus
V (7648) spi_master: polling trans
V (7658) spi_master: polling trans done
V (7658) spi_master: polling trans
V (7668) spi_master: polling trans done
V (7668) spi_master: polling trans
V (7668) spi_master: polling trans done
V (7678) spi_master: polling trans
V (7678) spi_master: polling trans done
D (7678) spi_master: device5 release bus
V (7688) bus_lock: dev 5 released.
V (7688) sdspi_transaction: r1 = 0x01 hw_cmd.r[0]=0xffffffff
V (7698) sdmmc_cmd: cmd response 00000001 00000000 00000000 00000000 err=0x0 state=0
V (7708) sdmmc_cmd: sending cmd slot=1 op=41 arg=40ff8000 flags=1030 data=0x0 blklen=0 datalen=0 timeout=3000
V (7718) sdspi_host: sdspi_host_start_command: slot=1, CMD41, arg=0x40ff8000 flags=0x4, data=0x0, data_size=0 crc=0x0b
V bus_lock: dev 5 acquired.
D (7728) spi_master: device5 locked the bus
V (7728) spi_master: polling trans
V (7738) spi_master: polling trans done
V (7738) spi_master: polling trans
V (7738) spi_master: polling trans done
V (7748) spi_master: polling trans
V (7748) spi_master: polling trans done
V (7748) spi_master: polling trans
V (7758) spi_master: polling trans done
D (7758) spi_master: device5 release bus
V (7768) bus_lock: dev 5 released.
V (7768) sdspi_transaction: r1 = 0x01 hw_cmd.r[0]=0xffffffff
V (7778) sdmmc_cmd: cmd response 00000001 00000000 00000000 00000000 err=0x0 state=0
V (7788) sdmmc_cmd: sending cmd slot=1 op=55 arg=0 flags=1c00 data=0x0 blklen=0 datalen=0 timeout=3000
V (7788) sdspi_host: sdspi_host_start_command: slot=1, CMD55, arg=0x00000000 flags=0x4, data=0x0, data_size=0 crc=0x32
V bus_lock: dev 5 acquired.
D (7808) spi_master: device5 locked the bus
V (7808) spi_master: polling trans
V (7808) spi_master: polling trans done
V (7818) spi_master: polling trans
V (7818) spi_master: polling trans done
V (7818) spi_master: polling trans
V (7828) spi_master: polling trans done
V (7828) spi_master: polling trans
V (7838) spi_master: polling trans done
D (7838) spi_master: device5 release bus
V (7838) bus_lock: dev 5 released.
V (7848) sdspi_transaction: r1 = 0x01 hw_cmd.r[0]=0xffffffff
V (7848) sdmmc_cmd: cmd response 00000001 00000000 00000000 00000000 err=0x0 state=0
V (7858) sdmmc_cmd: sending cmd slot=1 op=41 arg=40ff8000 flags=1030 data=0x0 blklen=0 datalen=0 timeout=3000
V (7868) sdspi_host: sdspi_host_start_command: slot=1, CMD41, arg=0x40ff8000 flags=0x4, data=0x0, data_size=0 crc=0x0b
V bus_lock: dev 5 acquired.
D (7878) spi_master: device5 locked the bus
V (7888) spi_master: polling trans
V (7888) spi_master: polling trans done
V (7898) spi_master: polling trans
V (7898) spi_master: polling trans done
V (7898) spi_master: polling trans
V (7908) spi_master: polling trans done
V (7908) spi_master: polling trans
V (7908) spi_master: polling trans done
D (7918) spi_master: device5 release bus
V (7918) bus_lock: dev 5 released.
V (7928) sdspi_transaction: r1 = 0x01 hw_cmd.r[0]=0xffffffff
V (7928) sdmmc_cmd: cmd response 00000001 00000000 00000000 00000000 err=0x0 state=0
V (7948) sdmmc_cmd: sending cmd slot=1 op=55 arg=0 flags=1c00 data=0x0 blklen=0 datalen=0 timeout=3000
V (7948) sdspi_host: sdspi_host_start_command: slot=1, CMD55, arg=0x00000000 flags=0x4, data=0x0, data_size=0 crc=0x32
V bus_lock: dev 5 acquired.
D (7958) spi_master: device5 locked the bus
V (7968) spi_master: polling trans
V (7968) spi_master: polling trans done
V (7968) spi_master: polling trans
V (7978) spi_master: polling trans done
V (7978) spi_master: polling trans
V (7978) spi_master: polling trans done
V (7988) spi_master: polling trans
V (7988) spi_master: polling trans done
D (7998) spi_master: device5 release bus
V (7998) bus_lock: dev 5 released.
V (7998) sdspi_transaction: r1 = 0x01 hw_cmd.r[0]=0xffffffff
V (8008) sdmmc_cmd: cmd response 00000001 00000000 00000000 00000000 err=0x0 state=0
V (8018) sdmmc_cmd: sending cmd slot=1 op=41 arg=40ff8000 flags=1030 data=0x0 blklen=0 datalen=0 timeout=3000
V (8028) sdspi_host: sdspi_host_start_command: slot=1, CMD41, arg=0x40ff8000 flags=0x4, data=0x0, data_size=0 crc=0x0b
V bus_lock: dev 5 acquired.
D (8038) spi_master: device5 locked the bus
V (8038) spi_master: polling trans
V (8048) spi_master: polling trans done
V (8048) spi_master: polling trans
V (8048) spi_master: polling trans done
V (8058) spi_master: polling trans
V (8058) spi_master: polling trans done
V (8068) spi_master: polling trans
V (8068) spi_master: polling trans done
D (8068) spi_master: device5 release bus
V (8078) bus_lock: dev 5 released.
V (8078) sdspi_transaction: r1 = 0x01 hw_cmd.r[0]=0xffffffff
V (8088) sdmmc_cmd: cmd response 00000001 00000000 00000000 00000000 err=0x0 state=0
V (8098) sdmmc_cmd: sending cmd slot=1 op=55 arg=0 flags=1c00 data=0x0 blklen=0 datalen=0 timeout=3000
V (8098) sdspi_host: sdspi_host_start_command: slot=1, CMD55, arg=0x00000000 flags=0x4, data=0x0, data_size=0 crc=0x32
V bus_lock: dev 5 acquired.
D (8118) spi_master: device5 locked the bus
V (8118) spi_master: polling trans
V (8118) spi_master: polling trans done
V (8128) spi_master: polling trans
V (8128) spi_master: polling trans done
V (8138) spi_master: polling trans
V (8138) spi_master: polling trans done
V (8138) spi_master: polling trans
V (8148) spi_master: polling trans done
D (8148) spi_master: device5 release bus
V (8148) bus_lock: dev 5 released.
V (8158) sdspi_transaction: r1 = 0x01 hw_cmd.r[0]=0xffffffff
V (8158) sdmmc_cmd: cmd response 00000001 00000000 00000000 00000000 err=0x0 state=0
V (8168) sdmmc_cmd: sending cmd slot=1 op=41 arg=40ff8000 flags=1030 data=0x0 blklen=0 datalen=0 timeout=3000
V (8178) sdspi_host: sdspi_host_start_command: slot=1, CMD41, arg=0x40ff8000 flags=0x4, data=0x0, data_size=0 crc=0x0b
V bus_lock: dev 5 acquired.
D (8188) spi_master: device5 locked the bus
V (8198) spi_master: polling trans
V (8198) spi_master: polling trans done
V (8208) spi_master: polling trans
V (8208) spi_master: polling trans done
V (8208) spi_master: polling trans
V (8218) spi_master: polling trans done
V (8218) spi_master: polling trans
V (8218) spi_master: polling trans done
D (8228) spi_master: device5 release bus
V (8228) bus_lock: dev 5 released.
V (8238) sdspi_transaction: r1 = 0x01 hw_cmd.r[0]=0xffffffff
V (8238) sdmmc_cmd: cmd response 00000001 00000000 00000000 00000000 err=0x0 state=0
V (8258) sdmmc_cmd: sending cmd slot=1 op=55 arg=0 flags=1c00 data=0x0 blklen=0 datalen=0 timeout=3000
V (8258) sdspi_host: sdspi_host_start_command: slot=1, CMD55, arg=0x00000000 flags=0x4, data=0x0, data_size=0 crc=0x32
V bus_lock: dev 5 acquired.
D (8268) spi_master: device5 locked the bus
V (8278) spi_master: polling trans
V (8278) spi_master: polling trans done
V (8278) spi_master: polling trans
V (8288) spi_master: polling trans done
V (8288) spi_master: polling trans
V (8298) spi_master: polling trans done
V (8298) spi_master: polling trans
V (8298) spi_master: polling trans done
D (8308) spi_master: device5 release bus
V (8308) bus_lock: dev 5 released.
V (8308) sdspi_transaction: r1 = 0x01 hw_cmd.r[0]=0xffffffff
V (8318) sdmmc_cmd: cmd response 00000001 00000000 00000000 00000000 err=0x0 state=0
V (8328) sdmmc_cmd: sending cmd slot=1 op=41 arg=40ff8000 flags=1030 data=0x0 blklen=0 datalen=0 timeout=3000
V (8338) sdspi_host: sdspi_host_start_command: slot=1, CMD41, arg=0x40ff8000 flags=0x4, data=0x0, data_size=0 crc=0x0b
V bus_lock: dev 5 acquired.
D (8348) spi_master: device5 locked the bus
V (8348) spi_master: polling trans
V (8358) spi_master: polling trans done
V (8358) spi_master: polling trans
V (8368) spi_master: polling trans done
V (8368) spi_master: polling trans
V (8368) spi_master: polling trans done
V (8378) spi_master: polling trans
V (8378) spi_master: polling trans done
D (8378) spi_master: device5 release bus
V (8388) bus_lock: dev 5 released.
V (8388) sdspi_transaction: r1 = 0x01 hw_cmd.r[0]=0xffffffff
V (8398) sdmmc_cmd: cmd response 00000001 00000000 00000000 00000000 err=0x0 state=0
V (8418) sdmmc_cmd: sending cmd slot=1 op=55 arg=0 flags=1c00 data=0x0 blklen=0 datalen=0 timeout=3000
V (8418) sdspi_host: sdspi_host_start_command: slot=1, CMD55, arg=0x00000000 flags=0x4, data=0x0, data_size=0 crc=0x32
V bus_lock: dev 5 acquired.
D (8428) spi_master: device5 locked the bus
V (8428) spi_master: polling trans
V (8438) spi_master: polling trans done
V (8438) spi_master: polling trans
V (8438) spi_master: polling trans done
V (8448) spi_master: polling trans
V (8448) spi_master: polling trans done
V (8448) spi_master: polling trans
V (8458) spi_master: polling trans done
D (8458) spi_master: device5 release bus
V (8468) bus_lock: dev 5 released.
V (8468) sdspi_transaction: r1 = 0x01 hw_cmd.r[0]=0xffffffff
V (8468) sdmmc_cmd: cmd response 00000001 00000000 00000000 00000000 err=0x0 state=0
V (8478) sdmmc_cmd: sending cmd slot=1 op=41 arg=40ff8000 flags=1030 data=0x0 blklen=0 datalen=0 timeout=3000
V (8488) sdspi_host: sdspi_host_start_command: slot=1, CMD41, arg=0x40ff8000 flags=0x4, data=0x0, data_size=0 crc=0x0b
V bus_lock: dev 5 acquired.
D (8508) spi_master: device5 locked the bus
V (8508) spi_master: polling trans
V (8508) spi_master: polling trans done
V (8518) spi_master: polling trans
V (8518) spi_master: polling trans done
V (8518) spi_master: polling trans
V (8528) spi_master: polling trans done
V (8528) spi_master: polling trans
V (8538) spi_master: polling trans done
D (8538) spi_master: device5 release bus
V (8538) bus_lock: dev 5 released.
V (8548) sdspi_transaction: r1 = 0x01 hw_cmd.r[0]=0xffffffff
V (8548) sdmmc_cmd: cmd response 00000001 00000000 00000000 00000000 err=0x0 state=0
V (8568) sdmmc_cmd: sending cmd slot=1 op=55 arg=0 flags=1c00 data=0x0 blklen=0 datalen=0 timeout=3000
V (8568) sdspi_host: sdspi_host_start_command: slot=1, CMD55, arg=0x00000000 flags=0x4, data=0x0, data_size=0 crc=0x32
V bus_lock: dev 5 acquired.
D (8578) spi_master: device5 locked the bus
V (8588) spi_master: polling trans
V (8588) spi_master: polling trans done
V (8588) spi_master: polling trans
V (8598) spi_master: polling trans done
V (8598) spi_master: polling trans
V (8608) spi_master: polling trans done
V (8608) spi_master: polling trans
V (8608) spi_master: polling trans done
D (8618) spi_master: device5 release bus
V (8618) bus_lock: dev 5 released.
V (8618) sdspi_transaction: r1 = 0x01 hw_cmd.r[0]=0xffffffff
V (8628) sdmmc_cmd: cmd response 00000001 00000000 00000000 00000000 err=0x0 state=0
V (8638) sdmmc_cmd: sending cmd slot=1 op=41 arg=40ff8000 flags=1030 data=0x0 blklen=0 datalen=0 timeout=3000
V (8648) sdspi_host: sdspi_host_start_command: slot=1, CMD41, arg=0x40ff8000 flags=0x4, data=0x0, data_size=0 crc=0x0b
V bus_lock: dev 5 acquired.
D (8658) spi_master: device5 locked the bus
V (8668) spi_master: polling trans
V (8668) spi_master: polling trans done
V (8668) spi_master: polling trans
V (8678) spi_master: polling trans done
V (8678) spi_master: polling trans
V (8678) spi_master: polling trans done
V (8688) spi_master: polling trans
V (8688) spi_master: polling trans done
D (8698) spi_master: device5 release bus
V (8698) bus_lock: dev 5 released.
V (8698) sdspi_transaction: r1 = 0x01 hw_cmd.r[0]=0xffffffff
V (8708) sdmmc_cmd: cmd response 00000001 00000000 00000000 00000000 err=0x0 state=0
V (8728) sdmmc_cmd: sending cmd slot=1 op=55 arg=0 flags=1c00 data=0x0 blklen=0 datalen=0 timeout=3000
V (8728) sdspi_host: sdspi_host_start_command: slot=1, CMD55, arg=0x00000000 flags=0x4, data=0x0, data_size=0 crc=0x32
V bus_lock: dev 5 acquired.
D (8738) spi_master: device5 locked the bus
V (8738) spi_master: polling trans
V (8748) spi_master: polling trans done
V (8748) spi_master: polling trans
V (8748) spi_master: polling trans done
V (8758) spi_master: polling trans
V (8758) spi_master: polling trans done
V (8768) spi_master: polling trans
V (8768) spi_master: polling trans done
D (8768) spi_master: device5 release bus
V (8778) bus_lock: dev 5 released.
V (8778) sdspi_transaction: r1 = 0x01 hw_cmd.r[0]=0xffffffff
V (8788) sdmmc_cmd: cmd response 00000001 00000000 00000000 00000000 err=0x0 state=0
V (8788) sdmmc_cmd: sending cmd slot=1 op=41 arg=40ff8000 flags=1030 data=0x0 blklen=0 datalen=0 timeout=3000
V (8798) sdspi_host: sdspi_host_start_command: slot=1, CMD41, arg=0x40ff8000 flags=0x4, data=0x0, data_size=0 crc=0x0b
V bus_lock: dev 5 acquired.
D (8818) spi_master: device5 locked the bus
V (8818) spi_master: polling trans
V (8818) spi_master: polling trans done
V (8828) spi_master: polling trans
V (8828) spi_master: polling trans done
V (8838) spi_master: polling trans
V (8838) spi_master: polling trans done
V (8838) spi_master: polling trans
V (8848) spi_master: polling trans done
D (8848) spi_master: device5 release bus
V (8848) bus_lock: dev 5 released.
V (8858) sdspi_transaction: r1 = 0x01 hw_cmd.r[0]=0xffffffff
V (8858) sdmmc_cmd: cmd response 00000001 00000000 00000000 00000000 err=0x0 state=0
V (8878) sdmmc_cmd: sending cmd slot=1 op=55 arg=0 flags=1c00 data=0x0 blklen=0 datalen=0 timeout=3000
V (8878) sdspi_host: sdspi_host_start_command: slot=1, CMD55, arg=0x00000000 flags=0x4, data=0x0, data_size=0 crc=0x32
V bus_lock: dev 5 acquired.
D (8888) spi_master: device5 locked the bus
V (8898) spi_master: polling trans
V (8898) spi_master: polling trans done
V (8908) spi_master: polling trans
V (8908) spi_master: polling trans done
V (8908) spi_master: polling trans
V (8918) spi_master: polling trans done
V (8918) spi_master: polling trans
V (8918) spi_master: polling trans done
D (8928) spi_master: device5 release bus
V (8928) bus_lock: dev 5 released.
V (8938) sdspi_transaction: r1 = 0x01 hw_cmd.r[0]=0xffffffff
V (8938) sdmmc_cmd: cmd response 00000001 00000000 00000000 00000000 err=0x0 state=0
V (8948) sdmmc_cmd: sending cmd slot=1 op=41 arg=40ff8000 flags=1030 data=0x0 blklen=0 datalen=0 timeout=3000
V (8958) sdspi_host: sdspi_host_start_command: slot=1, CMD41, arg=0x40ff8000 flags=0x4, data=0x0, data_size=0 crc=0x0b
V bus_lock: dev 5 acquired.
D (8968) spi_master: device5 locked the bus
V (8978) spi_master: polling trans
V (8978) spi_master: polling trans done
V (8978) spi_master: polling trans
V (8988) spi_master: polling trans done
V (8988) spi_master: polling trans
V (8998) spi_master: polling trans done
V (8998) spi_master: polling trans
V (8998) spi_master: polling trans done
D (9008) spi_master: device5 release bus
V (9008) bus_lock: dev 5 released.
V (9008) sdspi_transaction: r1 = 0x01 hw_cmd.r[0]=0xffffffff
V (9018) sdmmc_cmd: cmd response 00000001 00000000 00000000 00000000 err=0x0 state=0
V (9038) sdmmc_cmd: sending cmd slot=1 op=55 arg=0 flags=1c00 data=0x0 blklen=0 datalen=0 timeout=3000
V (9038) sdspi_host: sdspi_host_start_command: slot=1, CMD55, arg=0x00000000 flags=0x4, data=0x0, data_size=0 crc=0x32
V bus_lock: dev 5 acquired.
D (9048) spi_master: device5 locked the bus
V (9048) spi_master: polling trans
V (9058) spi_master: polling trans done
V (9058) spi_master: polling trans
V (9058) spi_master: polling trans done
V (9068) spi_master: polling trans
V (9068) spi_master: polling trans done
V (9078) spi_master: polling trans
V (9078) spi_master: polling trans done
D (9078) spi_master: device5 release bus
V (9088) bus_lock: dev 5 released.
V (9088) sdspi_transaction: r1 = 0x01 hw_cmd.r[0]=0xffffffff
V (9098) sdmmc_cmd: cmd response 00000001 00000000 00000000 00000000 err=0x0 state=0
V (9098) sdmmc_cmd: sending cmd slot=1 op=41 arg=40ff8000 flags=1030 data=0x0 blklen=0 datalen=0 timeout=3000
V (9108) sdspi_host: sdspi_host_start_command: slot=1, CMD41, arg=0x40ff8000 flags=0x4, data=0x0, data_size=0 crc=0x0b
V bus_lock: dev 5 acquired.
D (9128) spi_master: device5 locked the bus
V (9128) spi_master: polling trans
V (9138) spi_master: polling trans done
V (9138) spi_master: polling trans
V (9138) spi_master: polling trans done
V (9148) spi_master: polling trans
V (9148) spi_master: polling trans done
V (9148) spi_master: polling trans
V (9158) spi_master: polling trans done
D (9158) spi_master: device5 release bus
V (9168) bus_lock: dev 5 released.
V (9168) sdspi_transaction: r1 = 0x01 hw_cmd.r[0]=0xffffffff
V (9168) sdmmc_cmd: cmd response 00000001 00000000 00000000 00000000 err=0x0 state=0
V (9188) sdmmc_cmd: sending cmd slot=1 op=55 arg=0 flags=1c00 data=0x0 blklen=0 datalen=0 timeout=3000
V (9188) sdspi_host: sdspi_host_start_command: slot=1, CMD55, arg=0x00000000 flags=0x4, data=0x0, data_size=0 crc=0x32
V bus_lock: dev 5 acquired.
D (9208) spi_master: device5 locked the bus
V (9208) spi_master: polling trans
V (9208) spi_master: polling trans done
V (9218) spi_master: polling trans
V (9218) spi_master: polling trans done
V (9218) spi_master: polling trans
V (9228) spi_master: polling trans done
V (9228) spi_master: polling trans
V (9238) spi_master: polling trans done
D (9238) spi_master: device5 release bus
V (9238) bus_lock: dev 5 released.
V (9248) sdspi_transaction: r1 = 0x01 hw_cmd.r[0]=0xffffffff
V (9248) sdmmc_cmd: cmd response 00000001 00000000 00000000 00000000 err=0x0 state=0
V (9258) sdmmc_cmd: sending cmd slot=1 op=41 arg=40ff8000 flags=1030 data=0x0 blklen=0 datalen=0 timeout=3000
V (9268) sdspi_host: sdspi_host_start_command: slot=1, CMD41, arg=0x40ff8000 flags=0x4, data=0x0, data_size=0 crc=0x0b
V bus_lock: dev 5 acquired.
D (9278) spi_master: device5 locked the bus
V (9288) spi_master: polling trans
V (9288) spi_master: polling trans done
V (9288) spi_master: polling trans
V (9298) spi_master: polling trans done
V (9298) spi_master: polling trans
V (9308) spi_master: polling trans done
V (9308) spi_master: polling trans
V (9308) spi_master: polling trans done
D (9318) spi_master: device5 release bus
V (9318) bus_lock: dev 5 released.
V (9318) sdspi_transaction: r1 = 0x01 hw_cmd.r[0]=0xffffffff
V (9328) sdmmc_cmd: cmd response 00000001 00000000 00000000 00000000 err=0x0 state=0
V (9348) sdmmc_cmd: sending cmd slot=1 op=55 arg=0 flags=1c00 data=0x0 blklen=0 datalen=0 timeout=3000
V (9348) sdspi_host: sdspi_host_start_command: slot=1, CMD55, arg=0x00000000 flags=0x4, data=0x0, data_size=0 crc=0x32
V bus_lock: dev 5 acquired.
D (9358) spi_master: device5 locked the bus
V (9358) spi_master: polling trans
V (9368) spi_master: polling trans done
V (9368) spi_master: polling trans
V (9378) spi_master: polling trans done
V (9378) spi_master: polling trans
V (9378) spi_master: polling trans done
V (9388) spi_master: polling trans
V (9388) spi_master: polling trans done
D (9388) spi_master: device5 release bus
V (9398) bus_lock: dev 5 released.
V (9398) sdspi_transaction: r1 = 0x01 hw_cmd.r[0]=0xffffffff
V (9408) sdmmc_cmd: cmd response 00000001 00000000 00000000 00000000 err=0x0 state=0
V (9418) sdmmc_cmd: sending cmd slot=1 op=41 arg=40ff8000 flags=1030 data=0x0 blklen=0 datalen=0 timeout=3000
V (9428) sdspi_host: sdspi_host_start_command: slot=1, CMD41, arg=0x40ff8000 flags=0x4, data=0x0, data_size=0 crc=0x0b
V bus_lock: dev 5 acquired.
D (9438) spi_master: device5 locked the bus
V (9438) spi_master: polling trans
V (9448) spi_master: polling trans done
V (9448) spi_master: polling trans
V (9448) spi_master: polling trans done
V (9458) spi_master: polling trans
V (9458) spi_master: polling trans done
V (9468) spi_master: polling trans
V (9468) spi_master: polling trans done
D (9468) spi_master: device5 release bus
V (9478) bus_lock: dev 5 released.
V (9478) sdspi_transaction: r1 = 0x01 hw_cmd.r[0]=0xffffffff
V (9488) sdmmc_cmd: cmd response 00000001 00000000 00000000 00000000 err=0x0 state=0
V (9498) sdmmc_cmd: sending cmd slot=1 op=55 arg=0 flags=1c00 data=0x0 blklen=0 datalen=0 timeout=3000
V (9498) sdspi_host: sdspi_host_start_command: slot=1, CMD55, arg=0x00000000 flags=0x4, data=0x0, data_size=0 crc=0x32
V bus_lock: dev 5 acquired.
D (9518) spi_master: device5 locked the bus
V (9518) spi_master: polling trans
V (9518) spi_master: polling trans done
V (9528) spi_master: polling trans
V (9528) spi_master: polling trans done
V (9538) spi_master: polling trans
V (9538) spi_master: polling trans done
V (9538) spi_master: polling trans
V (9548) spi_master: polling trans done
D (9548) spi_master: device5 release bus
V (9548) bus_lock: dev 5 released.
V (9558) sdspi_transaction: r1 = 0x01 hw_cmd.r[0]=0xffffffff
V (9558) sdmmc_cmd: cmd response 00000001 00000000 00000000 00000000 err=0x0 state=0
V (9568) sdmmc_cmd: sending cmd slot=1 op=41 arg=40ff8000 flags=1030 data=0x0 blklen=0 datalen=0 timeout=3000
V (9578) sdspi_host: sdspi_host_start_command: slot=1, CMD41, arg=0x40ff8000 flags=0x4, data=0x0, data_size=0 crc=0x0b
V bus_lock: dev 5 acquired.
D (9588) spi_master: device5 locked the bus
V (9598) spi_master: polling trans
V (9598) spi_master: polling trans done
V (9608) spi_master: polling trans
V (9608) spi_master: polling trans done
V (9608) spi_master: polling trans
V (9618) spi_master: polling trans done
V (9618) spi_master: polling trans
V (9618) spi_master: polling trans done
D (9628) spi_master: device5 release bus
V (9628) bus_lock: dev 5 released.
V (9638) sdspi_transaction: r1 = 0x01 hw_cmd.r[0]=0xffffffff
V (9638) sdmmc_cmd: cmd response 00000001 00000000 00000000 00000000 err=0x0 state=0
V (9658) sdmmc_cmd: sending cmd slot=1 op=55 arg=0 flags=1c00 data=0x0 blklen=0 datalen=0 timeout=3000
V (9658) sdspi_host: sdspi_host_start_command: slot=1, CMD55, arg=0x00000000 flags=0x4, data=0x0, data_size=0 crc=0x32
V bus_lock: dev 5 acquired.
D (9668) spi_master: device5 locked the bus
V (9678) spi_master: polling trans
V (9678) spi_master: polling trans done
V (9678) spi_master: polling trans
V (9688) spi_master: polling trans done
V (9688) spi_master: polling trans
V (9688) spi_master: polling trans done
V (9698) spi_master: polling trans
V (9698) spi_master: polling trans done
D (9708) spi_master: device5 release bus
V (9708) bus_lock: dev 5 released.
V (9708) sdspi_transaction: r1 = 0x01 hw_cmd.r[0]=0xffffffff
V (9718) sdmmc_cmd: cmd response 00000001 00000000 00000000 00000000 err=0x0 state=0
V (9728) sdmmc_cmd: sending cmd slot=1 op=41 arg=40ff8000 flags=1030 data=0x0 blklen=0 datalen=0 timeout=3000
V (9738) sdspi_host: sdspi_host_start_command: slot=1, CMD41, arg=0x40ff8000 flags=0x4, data=0x0, data_size=0 crc=0x0b
V bus_lock: dev 5 acquired.
D (9748) spi_master: device5 locked the bus
V (9748) spi_master: polling trans
V (9758) spi_master: polling trans done
V (9758) spi_master: polling trans
V (9758) spi_master: polling trans done
V (9768) spi_master: polling trans
V (9768) spi_master: polling trans done
V (9778) spi_master: polling trans
V (9778) spi_master: polling trans done
D (9778) spi_master: device5 release bus
V (9788) bus_lock: dev 5 released.
V (9788) sdspi_transaction: r1 = 0x01 hw_cmd.r[0]=0xffffffff
V (9798) sdmmc_cmd: cmd response 00000001 00000000 00000000 00000000 err=0x0 state=0
V (9808) sdmmc_cmd: sending cmd slot=1 op=55 arg=0 flags=1c00 data=0x0 blklen=0 datalen=0 timeout=3000
V (9808) sdspi_host: sdspi_host_start_command: slot=1, CMD55, arg=0x00000000 flags=0x4, data=0x0, data_size=0 crc=0x32
V bus_lock: dev 5 acquired.
D (9828) spi_master: device5 locked the bus
V (9828) spi_master: polling trans
V (9828) spi_master: polling trans done
V (9838) spi_master: polling trans
V (9838) spi_master: polling trans done
V (9848) spi_master: polling trans
V (9848) spi_master: polling trans done
V (9848) spi_master: polling trans
V (9858) spi_master: polling trans done
D (9858) spi_master: device5 release bus
V (9858) bus_lock: dev 5 released.
V (9868) sdspi_transaction: r1 = 0x01 hw_cmd.r[0]=0xffffffff
V (9868) sdmmc_cmd: cmd response 00000001 00000000 00000000 00000000 err=0x0 state=0
V (9878) sdmmc_cmd: sending cmd slot=1 op=41 arg=40ff8000 flags=1030 data=0x0 blklen=0 datalen=0 timeout=3000
V (9888) sdspi_host: sdspi_host_start_command: slot=1, CMD41, arg=0x40ff8000 flags=0x4, data=0x0, data_size=0 crc=0x0b
V bus_lock: dev 5 acquired.
D (9898) spi_master: device5 locked the bus
V (9908) spi_master: polling trans
V (9908) spi_master: polling trans done
V (9918) spi_master: polling trans
V (9918) spi_master: polling trans done
V (9918) spi_master: polling trans
V (9928) spi_master: polling trans done
V (9928) spi_master: polling trans
V (9928) spi_master: polling trans done
D (9938) spi_master: device5 release bus
V (9938) bus_lock: dev 5 released.
V (9948) sdspi_transaction: r1 = 0x01 hw_cmd.r[0]=0xffffffff
V (9948) sdmmc_cmd: cmd response 00000001 00000000 00000000 00000000 err=0x0 state=0
V (9968) sdmmc_cmd: sending cmd slot=1 op=55 arg=0 flags=1c00 data=0x0 blklen=0 datalen=0 timeout=3000
V (9968) sdspi_host: sdspi_host_start_command: slot=1, CMD55, arg=0x00000000 flags=0x4, data=0x0, data_size=0 crc=0x32
V bus_lock: dev 5 acquired.
D (9978) spi_master: device5 locked the bus
V (9988) spi_master: polling trans
V (9988) spi_master: polling trans done
V (9988) spi_master: polling trans
V (9998) spi_master: polling trans done
V (9998) spi_master: polling trans
V (10008) spi_master: polling trans done
V (10008) spi_master: polling trans
V (10008) spi_master: polling trans done
D (10018) spi_master: device5 release bus
V (10018) bus_lock: dev 5 released.
V (10018) sdspi_transaction: r1 = 0x01 hw_cmd.r[0]=0xffffffff
V (10028) sdmmc_cmd: cmd response 00000001 00000000 00000000 00000000 err=0x0 state=0
V (10038) sdmmc_cmd: sending cmd slot=1 op=41 arg=40ff8000 flags=1030 data=0x0 blklen=0 datalen=0 timeout=3000
V (10048) sdspi_host: sdspi_host_start_command: slot=1, CMD41, arg=0x40ff8000 flags=0x4, data=0x0, data_size=0 crc=0x0b
V bus_lock: dev 5 acquired.
D (10058) spi_master: device5 locked the bus
V (10068) spi_master: polling trans
V (10068) spi_master: polling trans done
V (10068) spi_master: polling trans
V (10078) spi_master: polling trans done
V (10078) spi_master: polling trans
V (10078) spi_master: polling trans done
V (10088) spi_master: polling trans
V (10088) spi_master: polling trans done
D (10098) spi_master: device5 release bus
V (10098) bus_lock: dev 5 released.
V (10098) sdspi_transaction: r1 = 0x01 hw_cmd.r[0]=0xffffffff
V (10108) sdmmc_cmd: cmd response 00000001 00000000 00000000 00000000 err=0x0 state=0
V (10128) sdmmc_cmd: sending cmd slot=1 op=55 arg=0 flags=1c00 data=0x0 blklen=0 datalen=0 timeout=3000
V (10128) sdspi_host: sdspi_host_start_command: slot=1, CMD55, arg=0x00000000 flags=0x4, data=0x0, data_size=0 crc=0x32
V bus_lock: dev 5 acquired.
D (10138) spi_master: device5 locked the bus
V (10138) spi_master: polling trans
V (10148) spi_master: polling trans done
V (10148) spi_master: polling trans
V (10158) spi_master: polling trans done
V (10158) spi_master: polling trans
V (10158) spi_master: polling trans done
V (10168) spi_master: polling trans
V (10168) spi_master: polling trans done
D (10168) spi_master: device5 release bus
V (10178) bus_lock: dev 5 released.
V (10178) sdspi_transaction: r1 = 0x01 hw_cmd.r[0]=0xffffffff
V (10188) sdmmc_cmd: cmd response 00000001 00000000 00000000 00000000 err=0x0 state=0
V (10198) sdmmc_cmd: sending cmd slot=1 op=41 arg=40ff8000 flags=1030 data=0x0 blklen=0 datalen=0 timeout=3000
V (10208) sdspi_host: sdspi_host_start_command: slot=1, CMD41, arg=0x40ff8000 flags=0x4, data=0x0, data_size=0 crc=0x0b
V bus_lock: dev 5 acquired.
D (10218) spi_master: device5 locked the bus
V (10218) spi_master: polling trans
V (10228) spi_master: polling trans done
V (10228) spi_master: polling trans
V (10228) spi_master: polling trans done
V (10238) spi_master: polling trans
V (10238) spi_master: polling trans done
V (10248) spi_master: polling trans
V (10248) spi_master: polling trans done
D (10248) spi_master: device5 release bus
V (10258) bus_lock: dev 5 released.
V (10258) sdspi_transaction: r1 = 0x01 hw_cmd.r[0]=0xffffffff
V (10268) sdmmc_cmd: cmd response 00000001 00000000 00000000 00000000 err=0x0 state=0
V (10288) sdmmc_cmd: sending cmd slot=1 op=55 arg=0 flags=1c00 data=0x0 blklen=0 datalen=0 timeout=3000
V (10288) sdspi_host: sdspi_host_start_command: slot=1, CMD55, arg=0x00000000 flags=0x4, data=0x0, data_size=0 crc=0x32
V bus_lock: dev 5 acquired.
D (10298) spi_master: device5 locked the bus
V (10298) spi_master: polling trans
V (10308) spi_master: polling trans done
V (10308) spi_master: polling trans
V (10308) spi_master: polling trans done
V (10318) spi_master: polling trans
V (10318) spi_master: polling trans done
V (10328) spi_master: polling trans
V (10328) spi_master: polling trans done
D (10328) spi_master: device5 release bus
V (10338) bus_lock: dev 5 released.
V (10338) sdspi_transaction: r1 = 0x01 hw_cmd.r[0]=0xffffffff
V (10348) sdmmc_cmd: cmd response 00000001 00000000 00000000 00000000 err=0x0 state=0
V (10348) sdmmc_cmd: sending cmd slot=1 op=41 arg=40ff8000 flags=1030 data=0x0 blklen=0 datalen=0 timeout=3000
V (10358) sdspi_host: sdspi_host_start_command: slot=1, CMD41, arg=0x40ff8000 flags=0x4, data=0x0, data_size=0 crc=0x0b
V bus_lock: dev 5 acquired.
D (10378) spi_master: device5 locked the bus
V (10378) spi_master: polling trans
V (10388) spi_master: polling trans done
V (10388) spi_master: polling trans
V (10388) spi_master: polling trans done
V (10398) spi_master: polling trans
V (10398) spi_master: polling trans done
V (10398) spi_master: polling trans
V (10408) spi_master: polling trans done
D (10408) spi_master: device5 release bus
V (10418) bus_lock: dev 5 released.
V (10418) sdspi_transaction: r1 = 0x01 hw_cmd.r[0]=0xffffffff
V (10428) sdmmc_cmd: cmd response 00000001 00000000 00000000 00000000 err=0x0 state=0
V (10438) sdmmc_cmd: sending cmd slot=1 op=55 arg=0 flags=1c00 data=0x0 blklen=0 datalen=0 timeout=3000
V (10438) sdspi_host: sdspi_host_start_command: slot=1, CMD55, arg=0x00000000 flags=0x4, data=0x0, data_size=0 crc=0x32
V bus_lock: dev 5 acquired.
D (10458) spi_master: device5 locked the bus
V (10458) spi_master: polling trans
V (10458) spi_master: polling trans done
V (10468) spi_master: polling trans
V (10468) spi_master: polling trans done
V (10478) spi_master: polling trans
V (10478) spi_master: polling trans done
V (10478) spi_master: polling trans
V (10488) spi_master: polling trans done
D (10488) spi_master: device5 release bus
V (10488) bus_lock: dev 5 released.
V (10498) sdspi_transaction: r1 = 0x01 hw_cmd.r[0]=0xffffffff
V (10498) sdmmc_cmd: cmd response 00000001 00000000 00000000 00000000 err=0x0 state=0
V (10508) sdmmc_cmd: sending cmd slot=1 op=41 arg=40ff8000 flags=1030 data=0x0 blklen=0 datalen=0 timeout=3000
V (10518) sdspi_host: sdspi_host_start_command: slot=1, CMD41, arg=0x40ff8000 flags=0x4, data=0x0, data_size=0 crc=0x0b
V bus_lock: dev 5 acquired.
D (10538) spi_master: device5 locked the bus
V (10538) spi_master: polling trans
V (10538) spi_master: polling trans done
V (10548) spi_master: polling trans
V (10548) spi_master: polling trans done
V (10548) spi_master: polling trans
V (10558) spi_master: polling trans done
V (10558) spi_master: polling trans
V (10568) spi_master: polling trans done
D (10568) spi_master: device5 release bus
V (10568) bus_lock: dev 5 released.
V (10578) sdspi_transaction: r1 = 0x01 hw_cmd.r[0]=0xffffffff
V (10578) sdmmc_cmd: cmd response 00000001 00000000 00000000 00000000 err=0x0 state=0
V (10598) sdmmc_cmd: sending cmd slot=1 op=55 arg=0 flags=1c00 data=0x0 blklen=0 datalen=0 timeout=3000
V (10598) sdspi_host: sdspi_host_start_command: slot=1, CMD55, arg=0x00000000 flags=0x4, data=0x0, data_size=0 crc=0x32
V bus_lock: dev 5 acquired.
D (10608) spi_master: device5 locked the bus
V (10618) spi_master: polling trans
V (10618) spi_master: polling trans done
V (10628) spi_master: polling trans
V (10628) spi_master: polling trans done
V (10628) spi_master: polling trans
V (10638) spi_master: polling trans done
V (10638) spi_master: polling trans
V (10638) spi_master: polling trans done
D (10648) spi_master: device5 release bus
V (10648) bus_lock: dev 5 released.
V (10658) sdspi_transaction: r1 = 0x01 hw_cmd.r[0]=0xffffffff
V (10658) sdmmc_cmd: cmd response 00000001 00000000 00000000 00000000 err=0x0 state=0
V (10668) sdmmc_cmd: sending cmd slot=1 op=41 arg=40ff8000 flags=1030 data=0x0 blklen=0 datalen=0 timeout=3000
V (10678) sdspi_host: sdspi_host_start_command: slot=1, CMD41, arg=0x40ff8000 flags=0x4, data=0x0, data_size=0 crc=0x0b
V bus_lock: dev 5 acquired.
D (10688) spi_master: device5 locked the bus
V (10698) spi_master: polling trans
V (10698) spi_master: polling trans done
V (10708) spi_master: polling trans
V (10708) spi_master: polling trans done
V (10708) spi_master: polling trans
V (10718) spi_master: polling trans done
V (10718) spi_master: polling trans
V (10718) spi_master: polling trans done
D (10728) spi_master: device5 release bus
V (10728) bus_lock: dev 5 released.
V (10738) sdspi_transaction: r1 = 0x01 hw_cmd.r[0]=0xffffffff
V (10738) sdmmc_cmd: cmd response 00000001 00000000 00000000 00000000 err=0x0 state=0
V (10758) sdmmc_cmd: sending cmd slot=1 op=55 arg=0 flags=1c00 data=0x0 blklen=0 datalen=0 timeout=3000
V (10758) sdspi_host: sdspi_host_start_command: slot=1, CMD55, arg=0x00000000 flags=0x4, data=0x0, data_size=0 crc=0x32
V bus_lock: dev 5 acquired.
D (10768) spi_master: device5 locked the bus
V (10778) spi_master: polling trans
V (10778) spi_master: polling trans done
V (10778) spi_master: polling trans
V (10788) spi_master: polling trans done
V (10788) spi_master: polling trans
V (10798) spi_master: polling trans done
V (10798) spi_master: polling trans
V (10798) spi_master: polling trans done
D (10808) spi_master: device5 release bus
V (10808) bus_lock: dev 5 released.
V (10808) sdspi_transaction: r1 = 0x01 hw_cmd.r[0]=0xffffffff
V (10818) sdmmc_cmd: cmd response 00000001 00000000 00000000 00000000 err=0x0 state=0
V (10828) sdmmc_cmd: sending cmd slot=1 op=41 arg=40ff8000 flags=1030 data=0x0 blklen=0 datalen=0 timeout=3000
V (10838) sdspi_host: sdspi_host_start_command: slot=1, CMD41, arg=0x40ff8000 flags=0x4, data=0x0, data_size=0 crc=0x0b
V bus_lock: dev 5 acquired.
D (10848) spi_master: device5 locked the bus
V (10858) spi_master: polling trans
V (10858) spi_master: polling trans done
V (10858) spi_master: polling trans
V (10868) spi_master: polling trans done
V (10868) spi_master: polling trans
V (10868) spi_master: polling trans done
V (10878) spi_master: polling trans
V (10878) spi_master: polling trans done
D (10888) spi_master: device5 release bus
V (10888) bus_lock: dev 5 released.
V (10888) sdspi_transaction: r1 = 0x01 hw_cmd.r[0]=0xffffffff
V (10898) sdmmc_cmd: cmd response 00000001 00000000 00000000 00000000 err=0x0 state=0
V (10918) sdmmc_cmd: sending cmd slot=1 op=55 arg=0 flags=1c00 data=0x0 blklen=0 datalen=0 timeout=3000
V (10918) sdspi_host: sdspi_host_start_command: slot=1, CMD55, arg=0x00000000 flags=0x4, data=0x0, data_size=0 crc=0x32
V bus_lock: dev 5 acquired.
D (10928) spi_master: device5 locked the bus
V (10928) spi_master: polling trans
V (10938) spi_master: polling trans done
V (10938) spi_master: polling trans
V (10948) spi_master: polling trans done
V (10948) spi_master: polling trans
V (10948) spi_master: polling trans done
V (10958) spi_master: polling trans
V (10958) spi_master: polling trans done
D (10958) spi_master: device5 release bus
V (10968) bus_lock: dev 5 released.
V (10968) sdspi_transaction: r1 = 0x01 hw_cmd.r[0]=0xffffffff
V (10978) sdmmc_cmd: cmd response 00000001 00000000 00000000 00000000 err=0x0 state=0
V (10988) sdmmc_cmd: sending cmd slot=1 op=41 arg=40ff8000 flags=1030 data=0x0 blklen=0 datalen=0 timeout=3000
V (10998) sdspi_host: sdspi_host_start_command: slot=1, CMD41, arg=0x40ff8000 flags=0x4, data=0x0, data_size=0 crc=0x0b
V bus_lock: dev 5 acquired.
D (11008) spi_master: device5 locked the bus
V (11008) spi_master: polling trans
V (11018) spi_master: polling trans done
V (11018) spi_master: polling trans
V (11028) spi_master: polling trans done
V (11028) spi_master: polling trans
V (11028) spi_master: polling trans done
V (11038) spi_master: polling trans
V (11038) spi_master: polling trans done
D (11038) spi_master: device5 release bus
V (11048) bus_lock: dev 5 released.
V (11048) sdspi_transaction: r1 = 0x01 hw_cmd.r[0]=0xffffffff
V (11058) sdmmc_cmd: cmd response 00000001 00000000 00000000 00000000 err=0x0 state=0
V (11078) sdmmc_cmd: sending cmd slot=1 op=55 arg=0 flags=1c00 data=0x0 blklen=0 datalen=0 timeout=3000
V (11078) sdspi_host: sdspi_host_start_command: slot=1, CMD55, arg=0x00000000 flags=0x4, data=0x0, data_size=0 crc=0x32
V bus_lock: dev 5 acquired.
D (11088) spi_master: device5 locked the bus
V (11088) spi_master: polling trans
V (11098) spi_master: polling trans done
V (11098) spi_master: polling trans
V (11098) spi_master: polling trans done
V (11108) spi_master: polling trans
V (11108) spi_master: polling trans done
V (11118) spi_master: polling trans
V (11118) spi_master: polling trans done
D (11118) spi_master: device5 release bus
V (11128) bus_lock: dev 5 released.
V (11128) sdspi_transaction: r1 = 0x01 hw_cmd.r[0]=0xffffffff
V (11138) sdmmc_cmd: cmd response 00000001 00000000 00000000 00000000 err=0x0 state=0
V (11138) sdmmc_cmd: sending cmd slot=1 op=41 arg=40ff8000 flags=1030 data=0x0 blklen=0 datalen=0 timeout=3000
V (11148) sdspi_host: sdspi_host_start_command: slot=1, CMD41, arg=0x40ff8000 flags=0x4, data=0x0, data_size=0 crc=0x0b
V bus_lock: dev 5 acquired.
D (11168) spi_master: device5 locked the bus
V (11168) spi_master: polling trans
V (11178) spi_master: polling trans done
V (11178) spi_master: polling trans
V (11178) spi_master: polling trans done
V (11188) spi_master: polling trans
V (11188) spi_master: polling trans done
V (11188) spi_master: polling trans
V (11198) spi_master: polling trans done
D (11198) spi_master: device5 release bus
V (11208) bus_lock: dev 5 released.
V (11208) sdspi_transaction: r1 = 0x01 hw_cmd.r[0]=0xffffffff
V (11218) sdmmc_cmd: cmd response 00000001 00000000 00000000 00000000 err=0x0 state=0
V (11228) sdmmc_cmd: sending cmd slot=1 op=55 arg=0 flags=1c00 data=0x0 blklen=0 datalen=0 timeout=3000
V (11228) sdspi_host: sdspi_host_start_command: slot=1, CMD55, arg=0x00000000 flags=0x4, data=0x0, data_size=0 crc=0x32
V bus_lock: dev 5 acquired.
D (11248) spi_master: device5 locked the bus
V (11248) spi_master: polling trans
V (11248) spi_master: polling trans done
V (11258) spi_master: polling trans
V (11258) spi_master: polling trans done
V (11268) spi_master: polling trans
V (11268) spi_master: polling trans done
V (11268) spi_master: polling trans
V (11278) spi_master: polling trans done
D (11278) spi_master: device5 release bus
V (11288) bus_lock: dev 5 released.
V (11288) sdspi_transaction: r1 = 0x01 hw_cmd.r[0]=0xffffffff
V (11288) sdmmc_cmd: cmd response 00000001 00000000 00000000 00000000 err=0x0 state=0
V (11298) sdmmc_cmd: sending cmd slot=1 op=41 arg=40ff8000 flags=1030 data=0x0 blklen=0 datalen=0 timeout=3000
V (11308) sdspi_host: sdspi_host_start_command: slot=1, CMD41, arg=0x40ff8000 flags=0x4, data=0x0, data_size=0 crc=0x0b
V bus_lock: dev 5 acquired.
D (11328) spi_master: device5 locked the bus
V (11328) spi_master: polling trans
V (11328) spi_master: polling trans done
V (11338) spi_master: polling trans
V (11338) spi_master: polling trans done
V (11348) spi_master: polling trans
V (11348) spi_master: polling trans done
V (11348) spi_master: polling trans
V (11358) spi_master: polling trans done
D (11358) spi_master: device5 release bus
V (11358) bus_lock: dev 5 released.
V (11368) sdspi_transaction: r1 = 0x01 hw_cmd.r[0]=0xffffffff
V (11368) sdmmc_cmd: cmd response 00000001 00000000 00000000 00000000 err=0x0 state=0
V (11388) sdmmc_cmd: sending cmd slot=1 op=55 arg=0 flags=1c00 data=0x0 blklen=0 datalen=0 timeout=3000
V (11388) sdspi_host: sdspi_host_start_command: slot=1, CMD55, arg=0x00000000 flags=0x4, data=0x0, data_size=0 crc=0x32
V bus_lock: dev 5 acquired.
D (11398) spi_master: device5 locked the bus
V (11408) spi_master: polling trans
V (11408) spi_master: polling trans done
V (11418) spi_master: polling trans
V (11418) spi_master: polling trans done
V (11418) spi_master: polling trans
V (11428) spi_master: polling trans done
V (11428) spi_master: polling trans
V (11438) spi_master: polling trans done
D (11438) spi_master: device5 release bus
V (11438) bus_lock: dev 5 released.
V (11448) sdspi_transaction: r1 = 0x01 hw_cmd.r[0]=0xffffffff
V (11448) sdmmc_cmd: cmd response 00000001 00000000 00000000 00000000 err=0x0 state=0
V (11458) sdmmc_cmd: sending cmd slot=1 op=41 arg=40ff8000 flags=1030 data=0x0 blklen=0 datalen=0 timeout=3000
V (11468) sdspi_host: sdspi_host_start_command: slot=1, CMD41, arg=0x40ff8000 flags=0x4, data=0x0, data_size=0 crc=0x0b
V bus_lock: dev 5 acquired.
D (11478) spi_master: device5 locked the bus
V (11488) spi_master: polling trans
V (11488) spi_master: polling trans done
V (11498) spi_master: polling trans
V (11498) spi_master: polling trans done
V (11498) spi_master: polling trans
V (11508) spi_master: polling trans done
V (11508) spi_master: polling trans
V (11508) spi_master: polling trans done
D (11518) spi_master: device5 release bus
V (11518) bus_lock: dev 5 released.
V (11528) sdspi_transaction: r1 = 0x01 hw_cmd.r[0]=0xffffffff
V (11528) sdmmc_cmd: cmd response 00000001 00000000 00000000 00000000 err=0x0 state=0
V (11548) sdmmc_cmd: sending cmd slot=1 op=55 arg=0 flags=1c00 data=0x0 blklen=0 datalen=0 timeout=3000
V (11548) sdspi_host: sdspi_host_start_command: slot=1, CMD55, arg=0x00000000 flags=0x4, data=0x0, data_size=0 crc=0x32
V bus_lock: dev 5 acquired.
D (11558) spi_master: device5 locked the bus
V (11568) spi_master: polling trans
V (11568) spi_master: polling trans done
V (11568) spi_master: polling trans
V (11578) spi_master: polling trans done
V (11578) spi_master: polling trans
V (11588) spi_master: polling trans done
V (11588) spi_master: polling trans
V (11588) spi_master: polling trans done
D (11598) spi_master: device5 release bus
V (11598) bus_lock: dev 5 released.
V (11598) sdspi_transaction: r1 = 0x01 hw_cmd.r[0]=0xffffffff
V (11608) sdmmc_cmd: cmd response 00000001 00000000 00000000 00000000 err=0x0 state=0
V (11618) sdmmc_cmd: sending cmd slot=1 op=41 arg=40ff8000 flags=1030 data=0x0 blklen=0 datalen=0 timeout=3000
V (11628) sdspi_host: sdspi_host_start_command: slot=1, CMD41, arg=0x40ff8000 flags=0x4, data=0x0, data_size=0 crc=0x0b
V bus_lock: dev 5 acquired.
D (11638) spi_master: device5 locked the bus
V (11648) spi_master: polling trans
V (11648) spi_master: polling trans done
V (11648) spi_master: polling trans
V (11658) spi_master: polling trans done
V (11658) spi_master: polling trans
V (11668) spi_master: polling trans done
V (11668) spi_master: polling trans
V (11668) spi_master: polling trans done
D (11678) spi_master: device5 release bus
V (11678) bus_lock: dev 5 released.
V (11678) sdspi_transaction: r1 = 0x01 hw_cmd.r[0]=0xffffffff
V (11688) sdmmc_cmd: cmd response 00000001 00000000 00000000 00000000 err=0x0 state=0
V (11708) sdmmc_cmd: sending cmd slot=1 op=55 arg=0 flags=1c00 data=0x0 blklen=0 datalen=0 timeout=3000
V (11708) sdspi_host: sdspi_host_start_command: slot=1, CMD55, arg=0x00000000 flags=0x4, data=0x0, data_size=0 crc=0x32
V bus_lock: dev 5 acquired.
D (11718) spi_master: device5 locked the bus
V (11728) spi_master: polling trans
V (11728) spi_master: polling trans done
V (11728) spi_master: polling trans
V (11738) spi_master: polling trans done
V (11738) spi_master: polling trans
V (11738) spi_master: polling trans done
V (11748) spi_master: polling trans
V (11748) spi_master: polling trans done
D (11758) spi_master: device5 release bus
V (11758) bus_lock: dev 5 released.
V (11758) sdspi_transaction: r1 = 0x01 hw_cmd.r[0]=0xffffffff
V (11768) sdmmc_cmd: cmd response 00000001 00000000 00000000 00000000 err=0x0 state=0
V (11778) sdmmc_cmd: sending cmd slot=1 op=41 arg=40ff8000 flags=1030 data=0x0 blklen=0 datalen=0 timeout=3000
V (11788) sdspi_host: sdspi_host_start_command: slot=1, CMD41, arg=0x40ff8000 flags=0x4, data=0x0, data_size=0 crc=0x0b
V bus_lock: dev 5 acquired.
D (11798) spi_master: device5 locked the bus
V (11798) spi_master: polling trans
V (11808) spi_master: polling trans done
V (11808) spi_master: polling trans
V (11818) spi_master: polling trans done
V (11818) spi_master: polling trans
V (11818) spi_master: polling trans done
V (11828) spi_master: polling trans
V (11828) spi_master: polling trans done
D (11828) spi_master: device5 release bus
V (11838) bus_lock: dev 5 released.
V (11838) sdspi_transaction: r1 = 0x01 hw_cmd.r[0]=0xffffffff
V (11848) sdmmc_cmd: cmd response 00000001 00000000 00000000 00000000 err=0x0 state=0
V (11868) sdmmc_cmd: sending cmd slot=1 op=55 arg=0 flags=1c00 data=0x0 blklen=0 datalen=0 timeout=3000
V (11868) sdspi_host: sdspi_host_start_command: slot=1, CMD55, arg=0x00000000 flags=0x4, data=0x0, data_size=0 crc=0x32
V bus_lock: dev 5 acquired.
D (11878) spi_master: device5 locked the bus
V (11878) spi_master: polling trans
V (11888) spi_master: polling trans done
V (11888) spi_master: polling trans
V (11888) spi_master: polling trans done
V (11898) spi_master: polling trans
V (11898) spi_master: polling trans done
V (11908) spi_master: polling trans
V (11908) spi_master: polling trans done
D (11908) spi_master: device5 release bus
V (11918) bus_lock: dev 5 released.
V (11918) sdspi_transaction: r1 = 0x01 hw_cmd.r[0]=0xffffffff
V (11928) sdmmc_cmd: cmd response 00000001 00000000 00000000 00000000 err=0x0 state=0
V (11928) sdmmc_cmd: sending cmd slot=1 op=41 arg=40ff8000 flags=1030 data=0x0 blklen=0 datalen=0 timeout=3000
V (11938) sdspi_host: sdspi_host_start_command: slot=1, CMD41, arg=0x40ff8000 flags=0x4, data=0x0, data_size=0 crc=0x0b
V bus_lock: dev 5 acquired.
D (11958) spi_master: device5 locked the bus
V (11958) spi_master: polling trans
V (11968) spi_master: polling trans done
V (11968) spi_master: polling trans
V (11968) spi_master: polling trans done
V (11978) spi_master: polling trans
V (11978) spi_master: polling trans done
V (11988) spi_master: polling trans
V (11988) spi_master: polling trans done
D (11988) spi_master: device5 release bus
V (11998) bus_lock: dev 5 released.
V (11998) sdspi_transaction: r1 = 0x01 hw_cmd.r[0]=0xffffffff
V (12008) sdmmc_cmd: cmd response 00000001 00000000 00000000 00000000 err=0x0 state=0
V (12018) sdmmc_cmd: sending cmd slot=1 op=55 arg=0 flags=1c00 data=0x0 blklen=0 datalen=0 timeout=3000
V (12018) sdspi_host: sdspi_host_start_command: slot=1, CMD55, arg=0x00000000 flags=0x4, data=0x0, data_size=0 crc=0x32
V bus_lock: dev 5 acquired.
D (12038) spi_master: device5 locked the bus
V (12038) spi_master: polling trans
V (12038) spi_master: polling trans done
V (12048) spi_master: polling trans
V (12048) spi_master: polling trans done
V (12058) spi_master: polling trans
V (12058) spi_master: polling trans done
V (12058) spi_master: polling trans
V (12068) spi_master: polling trans done
D (12068) spi_master: device5 release bus
V (12078) bus_lock: dev 5 released.
V (12078) sdspi_transaction: r1 = 0x01 hw_cmd.r[0]=0xffffffff
V (12078) sdmmc_cmd: cmd response 00000001 00000000 00000000 00000000 err=0x0 state=0
V (12088) sdmmc_cmd: sending cmd slot=1 op=41 arg=40ff8000 flags=1030 data=0x0 blklen=0 datalen=0 timeout=3000
V (12098) sdspi_host: sdspi_host_start_command: slot=1, CMD41, arg=0x40ff8000 flags=0x4, data=0x0, data_size=0 crc=0x0b
V bus_lock: dev 5 acquired.
D (12118) spi_master: device5 locked the bus
V (12118) spi_master: polling trans
V (12118) spi_master: polling trans done
V (12128) spi_master: polling trans
V (12128) spi_master: polling trans done
V (12138) spi_master: polling trans
V (12138) spi_master: polling trans done
V (12138) spi_master: polling trans
V (12148) spi_master: polling trans done
D (12148) spi_master: device5 release bus
V (12148) bus_lock: dev 5 released.
V (12158) sdspi_transaction: r1 = 0x01 hw_cmd.r[0]=0xffffffff
V (12158) sdmmc_cmd: cmd response 00000001 00000000 00000000 00000000 err=0x0 state=0
V (12178) sdmmc_cmd: sending cmd slot=1 op=55 arg=0 flags=1c00 data=0x0 blklen=0 datalen=0 timeout=3000
V (12178) sdspi_host: sdspi_host_start_command: slot=1, CMD55, arg=0x00000000 flags=0x4, data=0x0, data_size=0 crc=0x32
V bus_lock: dev 5 acquired.
D (12188) spi_master: device5 locked the bus
V (12198) spi_master: polling trans
V (12198) spi_master: polling trans done
V (12208) spi_master: polling trans
V (12208) spi_master: polling trans done
V (12208) spi_master: polling trans
V (12218) spi_master: polling trans done
V (12218) spi_master: polling trans
V (12228) spi_master: polling trans done
D (12228) spi_master: device5 release bus
V (12228) bus_lock: dev 5 released.
V (12238) sdspi_transaction: r1 = 0x01 hw_cmd.r[0]=0xffffffff
V (12238) sdmmc_cmd: cmd response 00000001 00000000 00000000 00000000 err=0x0 state=0
V (12248) sdmmc_cmd: sending cmd slot=1 op=41 arg=40ff8000 flags=1030 data=0x0 blklen=0 datalen=0 timeout=3000
V (12258) sdspi_host: sdspi_host_start_command: slot=1, CMD41, arg=0x40ff8000 flags=0x4, data=0x0, data_size=0 crc=0x0b
V bus_lock: dev 5 acquired.
D (12268) spi_master: device5 locked the bus
V (12278) spi_master: polling trans
V (12278) spi_master: polling trans done
V (12288) spi_master: polling trans
V (12288) spi_master: polling trans done
V (12288) spi_master: polling trans
V (12298) spi_master: polling trans done
V (12298) spi_master: polling trans
V (12298) spi_master: polling trans done
D (12308) spi_master: device5 release bus
V (12308) bus_lock: dev 5 released.
V (12318) sdspi_transaction: r1 = 0x01 hw_cmd.r[0]=0xffffffff
V (12318) sdmmc_cmd: cmd response 00000001 00000000 00000000 00000000 err=0x0 state=0
V (12338) sdmmc_cmd: sending cmd slot=1 op=55 arg=0 flags=1c00 data=0x0 blklen=0 datalen=0 timeout=3000
V (12338) sdspi_host: sdspi_host_start_command: slot=1, CMD55, arg=0x00000000 flags=0x4, data=0x0, data_size=0 crc=0x32
V bus_lock: dev 5 acquired.
D (12348) spi_master: device5 locked the bus
V (12358) spi_master: polling trans
V (12358) spi_master: polling trans done
V (12358) spi_master: polling trans
V (12368) spi_master: polling trans done
V (12368) spi_master: polling trans
V (12378) spi_master: polling trans done
V (12378) spi_master: polling trans
V (12378) spi_master: polling trans done
D (12388) spi_master: device5 release bus
V (12388) bus_lock: dev 5 released.
V (12398) sdspi_transaction: r1 = 0x01 hw_cmd.r[0]=0xffffffff
V (12398) sdmmc_cmd: cmd response 00000001 00000000 00000000 00000000 err=0x0 state=0
V (12408) sdmmc_cmd: sending cmd slot=1 op=41 arg=40ff8000 flags=1030 data=0x0 blklen=0 datalen=0 timeout=3000
V (12418) sdspi_host: sdspi_host_start_command: slot=1, CMD41, arg=0x40ff8000 flags=0x4, data=0x0, data_size=0 crc=0x0b
V bus_lock: dev 5 acquired.
D (12428) spi_master: device5 locked the bus
V (12438) spi_master: polling trans
V (12438) spi_master: polling trans done
V (12438) spi_master: polling trans
V (12448) spi_master: polling trans done
V (12448) spi_master: polling trans
V (12458) spi_master: polling trans done
V (12458) spi_master: polling trans
V (12458) spi_master: polling trans done
D (12468) spi_master: device5 release bus
V (12468) bus_lock: dev 5 released.
V (12468) sdspi_transaction: r1 = 0x01 hw_cmd.r[0]=0xffffffff
V (12478) sdmmc_cmd: cmd response 00000001 00000000 00000000 00000000 err=0x0 state=0
V (12498) sdmmc_cmd: sending cmd slot=1 op=55 arg=0 flags=1c00 data=0x0 blklen=0 datalen=0 timeout=3000
V (12498) sdspi_host: sdspi_host_start_command: slot=1, CMD55, arg=0x00000000 flags=0x4, data=0x0, data_size=0 crc=0x32
V bus_lock: dev 5 acquired.
D (12508) spi_master: device5 locked the bus
V (12518) spi_master: polling trans
V (12518) spi_master: polling trans done
V (12518) spi_master: polling trans
V (12528) spi_master: polling trans done
V (12528) spi_master: polling trans
V (12528) spi_master: polling trans done
V (12538) spi_master: polling trans
V (12538) spi_master: polling trans done
D (12548) spi_master: device5 release bus
V (12548) bus_lock: dev 5 released.
V (12548) sdspi_transaction: r1 = 0x01 hw_cmd.r[0]=0xffffffff
V (12558) sdmmc_cmd: cmd response 00000001 00000000 00000000 00000000 err=0x0 state=0
V (12568) sdmmc_cmd: sending cmd slot=1 op=41 arg=40ff8000 flags=1030 data=0x0 blklen=0 datalen=0 timeout=3000
V (12578) sdspi_host: sdspi_host_start_command: slot=1, CMD41, arg=0x40ff8000 flags=0x4, data=0x0, data_size=0 crc=0x0b
V bus_lock: dev 5 acquired.
D (12588) spi_master: device5 locked the bus
V (12588) spi_master: polling trans
V (12598) spi_master: polling trans done
V (12598) spi_master: polling trans
V (12608) spi_master: polling trans done
V (12608) spi_master: polling trans
V (12608) spi_master: polling trans done
V (12618) spi_master: polling trans
V (12618) spi_master: polling trans done
D (12618) spi_master: device5 release bus
V (12628) bus_lock: dev 5 released.
V (12628) sdspi_transaction: r1 = 0x01 hw_cmd.r[0]=0xffffffff
V (12638) sdmmc_cmd: cmd response 00000001 00000000 00000000 00000000 err=0x0 state=0
V (12658) sdmmc_cmd: sending cmd slot=1 op=55 arg=0 flags=1c00 data=0x0 blklen=0 datalen=0 timeout=3000
V (12658) sdspi_host: sdspi_host_start_command: slot=1, CMD55, arg=0x00000000 flags=0x4, data=0x0, data_size=0 crc=0x32
V bus_lock: dev 5 acquired.
D (12668) spi_master: device5 locked the bus
V (12668) spi_master: polling trans
V (12678) spi_master: polling trans done
V (12678) spi_master: polling trans
V (12678) spi_master: polling trans done
V (12688) spi_master: polling trans
V (12688) spi_master: polling trans done
V (12698) spi_master: polling trans
V (12698) spi_master: polling trans done
D (12698) spi_master: device5 release bus
V (12708) bus_lock: dev 5 released.
V (12708) sdspi_transaction: r1 = 0x01 hw_cmd.r[0]=0xffffffff
V (12718) sdmmc_cmd: cmd response 00000001 00000000 00000000 00000000 err=0x0 state=0
V (12728) sdmmc_cmd: sending cmd slot=1 op=41 arg=40ff8000 flags=1030 data=0x0 blklen=0 datalen=0 timeout=3000
V (12738) sdspi_host: sdspi_host_start_command: slot=1, CMD41, arg=0x40ff8000 flags=0x4, data=0x0, data_size=0 crc=0x0b
V bus_lock: dev 5 acquired.
D (12748) spi_master: device5 locked the bus
V (12748) spi_master: polling trans
V (12758) spi_master: polling trans done
V (12758) spi_master: polling trans
V (12758) spi_master: polling trans done
V (12768) spi_master: polling trans
V (12768) spi_master: polling trans done
V (12778) spi_master: polling trans
V (12778) spi_master: polling trans done
D (12778) spi_master: device5 release bus
V (12788) bus_lock: dev 5 released.
V (12788) sdspi_transaction: r1 = 0x01 hw_cmd.r[0]=0xffffffff
V (12798) sdmmc_cmd: cmd response 00000001 00000000 00000000 00000000 err=0x0 state=0
V (12808) sdmmc_cmd: sending cmd slot=1 op=55 arg=0 flags=1c00 data=0x0 blklen=0 datalen=0 timeout=3000
V (12808) sdspi_host: sdspi_host_start_command: slot=1, CMD55, arg=0x00000000 flags=0x4, data=0x0, data_size=0 crc=0x32
V bus_lock: dev 5 acquired.
D (12828) spi_master: device5 locked the bus
V (12828) spi_master: polling trans
V (12838) spi_master: polling trans done
V (12838) spi_master: polling trans
V (12838) spi_master: polling trans done
V (12848) spi_master: polling trans
V (12848) spi_master: polling trans done
V (12848) spi_master: polling trans
V (12858) spi_master: polling trans done
D (12858) spi_master: device5 release bus
V (12868) bus_lock: dev 5 released.
V (12868) sdspi_transaction: r1 = 0x01 hw_cmd.r[0]=0xffffffff
V (12878) sdmmc_cmd: cmd response 00000001 00000000 00000000 00000000 err=0x0 state=0
V (12878) sdmmc_cmd: sending cmd slot=1 op=41 arg=40ff8000 flags=1030 data=0x0 blklen=0 datalen=0 timeout=3000
V (12888) sdspi_host: sdspi_host_start_command: slot=1, CMD41, arg=0x40ff8000 flags=0x4, data=0x0, data_size=0 crc=0x0b
V bus_lock: dev 5 acquired.
D (12908) spi_master: device5 locked the bus
V (12908) spi_master: polling trans
V (12908) spi_master: polling trans done
V (12918) spi_master: polling trans
V (12918) spi_master: polling trans done
V (12928) spi_master: polling trans
V (12928) spi_master: polling trans done
V (12928) spi_master: polling trans
V (12938) spi_master: polling trans done
D (12938) spi_master: device5 release bus
V (12948) bus_lock: dev 5 released.
V (12948) sdspi_transaction: r1 = 0x01 hw_cmd.r[0]=0xffffffff
V (12948) sdmmc_cmd: cmd response 00000001 00000000 00000000 00000000 err=0x0 state=0
V (12968) sdmmc_cmd: sending cmd slot=1 op=55 arg=0 flags=1c00 data=0x0 blklen=0 datalen=0 timeout=3000
V (12968) sdspi_host: sdspi_host_start_command: slot=1, CMD55, arg=0x00000000 flags=0x4, data=0x0, data_size=0 crc=0x32
V bus_lock: dev 5 acquired.
D (12988) spi_master: device5 locked the bus
V (12988) spi_master: polling trans
V (12988) spi_master: polling trans done
V (12998) spi_master: polling trans
V (12998) spi_master: polling trans done
V (12998) spi_master: polling trans
V (13008) spi_master: polling trans done
V (13008) spi_master: polling trans
V (13018) spi_master: polling trans done
D (13018) spi_master: device5 release bus
V (13018) bus_lock: dev 5 released.
V (13028) sdspi_transaction: r1 = 0x01 hw_cmd.r[0]=0xffffffff
V (13028) sdmmc_cmd: cmd response 00000001 00000000 00000000 00000000 err=0x0 state=0
V (13038) sdmmc_cmd: sending cmd slot=1 op=41 arg=40ff8000 flags=1030 data=0x0 blklen=0 datalen=0 timeout=3000
V (13048) sdspi_host: sdspi_host_start_command: slot=1, CMD41, arg=0x40ff8000 flags=0x4, data=0x0, data_size=0 crc=0x0b
V bus_lock: dev 5 acquired.
D (13058) spi_master: device5 locked the bus
V (13068) spi_master: polling trans
V (13068) spi_master: polling trans done
V (13078) spi_master: polling trans
V (13078) spi_master: polling trans done
V (13078) spi_master: polling trans
V (13088) spi_master: polling trans done
V (13088) spi_master: polling trans
V (13098) spi_master: polling trans done
D (13098) spi_master: device5 release bus
V (13098) bus_lock: dev 5 released.
V (13108) sdspi_transaction: r1 = 0x01 hw_cmd.r[0]=0xffffffff
V (13108) sdmmc_cmd: cmd response 00000001 00000000 00000000 00000000 err=0x0 state=0
V (13128) sdmmc_cmd: sending cmd slot=1 op=55 arg=0 flags=1c00 data=0x0 blklen=0 datalen=0 timeout=3000
V (13128) sdspi_host: sdspi_host_start_command: slot=1, CMD55, arg=0x00000000 flags=0x4, data=0x0, data_size=0 crc=0x32
V bus_lock: dev 5 acquired.
D (13138) spi_master: device5 locked the bus
V (13148) spi_master: polling trans
V (13148) spi_master: polling trans done
V (13158) spi_master: polling trans
V (13158) spi_master: polling trans done
V (13158) spi_master: polling trans
V (13168) spi_master: polling trans done
V (13168) spi_master: polling trans
V (13168) spi_master: polling trans done
D (13178) spi_master: device5 release bus
V (13178) bus_lock: dev 5 released.
V (13188) sdspi_transaction: r1 = 0x01 hw_cmd.r[0]=0xffffffff
V (13188) sdmmc_cmd: cmd response 00000001 00000000 00000000 00000000 err=0x0 state=0
V (13198) sdmmc_cmd: sending cmd slot=1 op=41 arg=40ff8000 flags=1030 data=0x0 blklen=0 datalen=0 timeout=3000
V (13208) sdspi_host: sdspi_host_start_command: slot=1, CMD41, arg=0x40ff8000 flags=0x4, data=0x0, data_size=0 crc=0x0b
V bus_lock: dev 5 acquired.
D (13218) spi_master: device5 locked the bus
V (13228) spi_master: polling trans
V (13228) spi_master: polling trans done
V (13228) spi_master: polling trans
V (13238) spi_master: polling trans done
V (13238) spi_master: polling trans
V (13248) spi_master: polling trans done
V (13248) spi_master: polling trans
V (13248) spi_master: polling trans done
D (13258) spi_master: device5 release bus
V (13258) bus_lock: dev 5 released.
V (13258) sdspi_transaction: r1 = 0x01 hw_cmd.r[0]=0xffffffff
V (13268) sdmmc_cmd: cmd response 00000001 00000000 00000000 00000000 err=0x0 state=0
V (13288) sdmmc_cmd: sending cmd slot=1 op=55 arg=0 flags=1c00 data=0x0 blklen=0 datalen=0 timeout=3000
V (13288) sdspi_host: sdspi_host_start_command: slot=1, CMD55, arg=0x00000000 flags=0x4, data=0x0, data_size=0 crc=0x32
V bus_lock: dev 5 acquired.
D (13298) spi_master: device5 locked the bus
V (13308) spi_master: polling trans
V (13308) spi_master: polling trans done
V (13308) spi_master: polling trans
V (13318) spi_master: polling trans done
V (13318) spi_master: polling trans
V (13318) spi_master: polling trans done
V (13328) spi_master: polling trans
V (13328) spi_master: polling trans done
D (13338) spi_master: device5 release bus
V (13338) bus_lock: dev 5 released.
V (13338) sdspi_transaction: r1 = 0x01 hw_cmd.r[0]=0xffffffff
V (13348) sdmmc_cmd: cmd response 00000001 00000000 00000000 00000000 err=0x0 state=0
V (13358) sdmmc_cmd: sending cmd slot=1 op=41 arg=40ff8000 flags=1030 data=0x0 blklen=0 datalen=0 timeout=3000
V (13368) sdspi_host: sdspi_host_start_command: slot=1, CMD41, arg=0x40ff8000 flags=0x4, data=0x0, data_size=0 crc=0x0b
V bus_lock: dev 5 acquired.
D (13378) spi_master: device5 locked the bus
V (13388) spi_master: polling trans
V (13388) spi_master: polling trans done
V (13388) spi_master: polling trans
V (13398) spi_master: polling trans done
V (13398) spi_master: polling trans
V (13398) spi_master: polling trans done
V (13408) spi_master: polling trans
V (13408) spi_master: polling trans done
D (13418) spi_master: device5 release bus
V (13418) bus_lock: dev 5 released.
V (13418) sdspi_transaction: r1 = 0x01 hw_cmd.r[0]=0xffffffff
V (13428) sdmmc_cmd: cmd response 00000001 00000000 00000000 00000000 err=0x0 state=0
V (13448) sdmmc_cmd: sending cmd slot=1 op=55 arg=0 flags=1c00 data=0x0 blklen=0 datalen=0 timeout=3000
V (13448) sdspi_host: sdspi_host_start_command: slot=1, CMD55, arg=0x00000000 flags=0x4, data=0x0, data_size=0 crc=0x32
V bus_lock: dev 5 acquired.
D (13458) spi_master: device5 locked the bus
V (13458) spi_master: polling trans
V (13468) spi_master: polling trans done
V (13468) spi_master: polling trans
V (13468) spi_master: polling trans done
V (13478) spi_master: polling trans
V (13478) spi_master: polling trans done
V (13488) spi_master: polling trans
V (13488) spi_master: polling trans done
D (13488) spi_master: device5 release bus
V (13498) bus_lock: dev 5 released.
V (13498) sdspi_transaction: r1 = 0x01 hw_cmd.r[0]=0xffffffff
V (13508) sdmmc_cmd: cmd response 00000001 00000000 00000000 00000000 err=0x0 state=0
V (13518) sdmmc_cmd: sending cmd slot=1 op=41 arg=40ff8000 flags=1030 data=0x0 blklen=0 datalen=0 timeout=3000
V (13528) sdspi_host: sdspi_host_start_command: slot=1, CMD41, arg=0x40ff8000 flags=0x4, data=0x0, data_size=0 crc=0x0b
V bus_lock: dev 5 acquired.
D (13538) spi_master: device5 locked the bus
V (13538) spi_master: polling trans
V (13548) spi_master: polling trans done
V (13548) spi_master: polling trans
V (13548) spi_master: polling trans done
V (13558) spi_master: polling trans
V (13558) spi_master: polling trans done
V (13568) spi_master: polling trans
V (13568) spi_master: polling trans done
D (13568) spi_master: device5 release bus
V (13578) bus_lock: dev 5 released.
V (13578) sdspi_transaction: r1 = 0x01 hw_cmd.r[0]=0xffffffff
V (13588) sdmmc_cmd: cmd response 00000001 00000000 00000000 00000000 err=0x0 state=0
V (13598) sdmmc_cmd: sending cmd slot=1 op=55 arg=0 flags=1c00 data=0x0 blklen=0 datalen=0 timeout=3000
V (13598) sdspi_host: sdspi_host_start_command: slot=1, CMD55, arg=0x00000000 flags=0x4, data=0x0, data_size=0 crc=0x32
V bus_lock: dev 5 acquired.
D (13618) spi_master: device5 locked the bus
V (13618) spi_master: polling trans
V (13628) spi_master: polling trans done
V (13628) spi_master: polling trans
V (13628) spi_master: polling trans done
V (13638) spi_master: polling trans
V (13638) spi_master: polling trans done
V (13638) spi_master: polling trans
V (13648) spi_master: polling trans done
D (13648) spi_master: device5 release bus
V (13658) bus_lock: dev 5 released.
V (13658) sdspi_transaction: r1 = 0x01 hw_cmd.r[0]=0xffffffff
V (13668) sdmmc_cmd: cmd response 00000001 00000000 00000000 00000000 err=0x0 state=0
V (13668) sdmmc_cmd: sending cmd slot=1 op=41 arg=40ff8000 flags=1030 data=0x0 blklen=0 datalen=0 timeout=3000
V (13678) sdspi_host: sdspi_host_start_command: slot=1, CMD41, arg=0x40ff8000 flags=0x4, data=0x0, data_size=0 crc=0x0b
V bus_lock: dev 5 acquired.
D (13698) spi_master: device5 locked the bus
V (13698) spi_master: polling trans
V (13698) spi_master: polling trans done
V (13708) spi_master: polling trans
V (13708) spi_master: polling trans done
V (13718) spi_master: polling trans
V (13718) spi_master: polling trans done
V (13718) spi_master: polling trans
V (13728) spi_master: polling trans done
D (13728) spi_master: device5 release bus
V (13738) bus_lock: dev 5 released.
V (13738) sdspi_transaction: r1 = 0x01 hw_cmd.r[0]=0xffffffff
V (13738) sdmmc_cmd: cmd response 00000001 00000000 00000000 00000000 err=0x0 state=0
V (13758) sdmmc_cmd: sending cmd slot=1 op=55 arg=0 flags=1c00 data=0x0 blklen=0 datalen=0 timeout=3000
V (13758) sdspi_host: sdspi_host_start_command: slot=1, CMD55, arg=0x00000000 flags=0x4, data=0x0, data_size=0 crc=0x32
V bus_lock: dev 5 acquired.
D (13778) spi_master: device5 locked the bus
V (13778) spi_master: polling trans
V (13778) spi_master: polling trans done
V (13788) spi_master: polling trans
V (13788) spi_master: polling trans done
V (13798) spi_master: polling trans
V (13798) spi_master: polling trans done
V (13798) spi_master: polling trans
V (13808) spi_master: polling trans done
D (13808) spi_master: device5 release bus
V (13808) bus_lock: dev 5 released.
V (13818) sdspi_transaction: r1 = 0x01 hw_cmd.r[0]=0xffffffff
V (13818) sdmmc_cmd: cmd response 00000001 00000000 00000000 00000000 err=0x0 state=0
V (13828) sdmmc_cmd: sending cmd slot=1 op=41 arg=40ff8000 flags=1030 data=0x0 blklen=0 datalen=0 timeout=3000
V (13838) sdspi_host: sdspi_host_start_command: slot=1, CMD41, arg=0x40ff8000 flags=0x4, data=0x0, data_size=0 crc=0x0b
V bus_lock: dev 5 acquired.
D (13848) spi_master: device5 locked the bus
V (13858) spi_master: polling trans
V (13858) spi_master: polling trans done
V (13868) spi_master: polling trans
V (13868) spi_master: polling trans done
V (13868) spi_master: polling trans
V (13878) spi_master: polling trans done
V (13878) spi_master: polling trans
V (13888) spi_master: polling trans done
D (13888) spi_master: device5 release bus
V (13888) bus_lock: dev 5 released.
V (13898) sdspi_transaction: r1 = 0x01 hw_cmd.r[0]=0xffffffff
V (13898) sdmmc_cmd: cmd response 00000001 00000000 00000000 00000000 err=0x0 state=0
V (13918) sdmmc_cmd: sending cmd slot=1 op=55 arg=0 flags=1c00 data=0x0 blklen=0 datalen=0 timeout=3000
V (13918) sdspi_host: sdspi_host_start_command: slot=1, CMD55, arg=0x00000000 flags=0x4, data=0x0, data_size=0 crc=0x32
V bus_lock: dev 5 acquired.
D (13928) spi_master: device5 locked the bus
V (13938) spi_master: polling trans
V (13938) spi_master: polling trans done
V (13948) spi_master: polling trans
V (13948) spi_master: polling trans done
V (13948) spi_master: polling trans
V (13958) spi_master: polling trans done
V (13958) spi_master: polling trans
V (13958) spi_master: polling trans done
D (13968) spi_master: device5 release bus
V (13968) bus_lock: dev 5 released.
V (13978) sdspi_transaction: r1 = 0x01 hw_cmd.r[0]=0xffffffff
V (13978) sdmmc_cmd: cmd response 00000001 00000000 00000000 00000000 err=0x0 state=0
V (13988) sdmmc_cmd: sending cmd slot=1 op=41 arg=40ff8000 flags=1030 data=0x0 blklen=0 datalen=0 timeout=3000
V (13998) sdspi_host: sdspi_host_start_command: slot=1, CMD41, arg=0x40ff8000 flags=0x4, data=0x0, data_size=0 crc=0x0b
V bus_lock: dev 5 acquired.
D (14008) spi_master: device5 locked the bus
V (14018) spi_master: polling trans
V (14018) spi_master: polling trans done
V (14018) spi_master: polling trans
V (14028) spi_master: polling trans done
V (14028) spi_master: polling trans
V (14038) spi_master: polling trans done
V (14038) spi_master: polling trans
V (14038) spi_master: polling trans done
D (14048) spi_master: device5 release bus
V (14048) bus_lock: dev 5 released.
V (14058) sdspi_transaction: r1 = 0x01 hw_cmd.r[0]=0xffffffff
V (14058) sdmmc_cmd: cmd response 00000001 00000000 00000000 00000000 err=0x0 state=0
V (14078) sdmmc_cmd: sending cmd slot=1 op=55 arg=0 flags=1c00 data=0x0 blklen=0 datalen=0 timeout=3000
V (14078) sdspi_host: sdspi_host_start_command: slot=1, CMD55, arg=0x00000000 flags=0x4, data=0x0, data_size=0 crc=0x32
V bus_lock: dev 5 acquired.
D (14088) spi_master: device5 locked the bus
V (14098) spi_master: polling trans
V (14098) spi_master: polling trans done
V (14098) spi_master: polling trans
V (14108) spi_master: polling trans done
V (14108) spi_master: polling trans
V (14118) spi_master: polling trans done
V (14118) spi_master: polling trans
V (14118) spi_master: polling trans done
D (14128) spi_master: device5 release bus
V (14128) bus_lock: dev 5 released.
V (14128) sdspi_transaction: r1 = 0x01 hw_cmd.r[0]=0xffffffff
V (14138) sdmmc_cmd: cmd response 00000001 00000000 00000000 00000000 err=0x0 state=0
V (14148) sdmmc_cmd: sending cmd slot=1 op=41 arg=40ff8000 flags=1030 data=0x0 blklen=0 datalen=0 timeout=3000
V (14158) sdspi_host: sdspi_host_start_command: slot=1, CMD41, arg=0x40ff8000 flags=0x4, data=0x0, data_size=0 crc=0x0b
V bus_lock: dev 5 acquired.
D (14168) spi_master: device5 locked the bus
V (14178) spi_master: polling trans
V (14178) spi_master: polling trans done
V (14178) spi_master: polling trans
V (14188) spi_master: polling trans done
V (14188) spi_master: polling trans
V (14188) spi_master: polling trans done
V (14198) spi_master: polling trans
V (14198) spi_master: polling trans done
D (14208) spi_master: device5 release bus
V (14208) bus_lock: dev 5 released.
V (14208) sdspi_transaction: r1 = 0x01 hw_cmd.r[0]=0xffffffff
V (14218) sdmmc_cmd: cmd response 00000001 00000000 00000000 00000000 err=0x0 state=0
V (14238) sdmmc_cmd: sending cmd slot=1 op=55 arg=0 flags=1c00 data=0x0 blklen=0 datalen=0 timeout=3000
V (14238) sdspi_host: sdspi_host_start_command: slot=1, CMD55, arg=0x00000000 flags=0x4, data=0x0, data_size=0 crc=0x32
V bus_lock: dev 5 acquired.
D (14248) spi_master: device5 locked the bus
V (14248) spi_master: polling trans
V (14258) spi_master: polling trans done
V (14258) spi_master: polling trans
V (14268) spi_master: polling trans done
V (14268) spi_master: polling trans
V (14268) spi_master: polling trans done
V (14278) spi_master: polling trans
V (14278) spi_master: polling trans done
D (14278) spi_master: device5 release bus
V (14288) bus_lock: dev 5 released.
V (14288) sdspi_transaction: r1 = 0x01 hw_cmd.r[0]=0xffffffff
V (14298) sdmmc_cmd: cmd response 00000001 00000000 00000000 00000000 err=0x0 state=0
V (14308) sdmmc_cmd: sending cmd slot=1 op=41 arg=40ff8000 flags=1030 data=0x0 blklen=0 datalen=0 timeout=3000
V (14318) sdspi_host: sdspi_host_start_command: slot=1, CMD41, arg=0x40ff8000 flags=0x4, data=0x0, data_size=0 crc=0x0b
V bus_lock: dev 5 acquired.
D (14328) spi_master: device5 locked the bus
V (14328) spi_master: polling trans
V (14338) spi_master: polling trans done
V (14338) spi_master: polling trans
V (14338) spi_master: polling trans done
V (14348) spi_master: polling trans
V (14348) spi_master: polling trans done
V (14358) spi_master: polling trans
V (14358) spi_master: polling trans done
D (14358) spi_master: device5 release bus
V (14368) bus_lock: dev 5 released.
V (14368) sdspi_transaction: r1 = 0x01 hw_cmd.r[0]=0xffffffff
V (14378) sdmmc_cmd: cmd response 00000001 00000000 00000000 00000000 err=0x0 state=0
V (14398) sdmmc_cmd: sending cmd slot=1 op=55 arg=0 flags=1c00 data=0x0 blklen=0 datalen=0 timeout=3000
V (14398) sdspi_host: sdspi_host_start_command: slot=1, CMD55, arg=0x00000000 flags=0x4, data=0x0, data_size=0 crc=0x32
V bus_lock: dev 5 acquired.
D (14408) spi_master: device5 locked the bus
V (14408) spi_master: polling trans
V (14418) spi_master: polling trans done
V (14418) spi_master: polling trans
V (14418) spi_master: polling trans done
V (14428) spi_master: polling trans
V (14428) spi_master: polling trans done
V (14428) spi_master: polling trans
V (14438) spi_master: polling trans done
D (14438) spi_master: device5 release bus
V (14448) bus_lock: dev 5 released.
V (14448) sdspi_transaction: r1 = 0x01 hw_cmd.r[0]=0xffffffff
V (14458) sdmmc_cmd: cmd response 00000001 00000000 00000000 00000000 err=0x0 state=0
V (14458) sdmmc_cmd: sending cmd slot=1 op=41 arg=40ff8000 flags=1030 data=0x0 blklen=0 datalen=0 timeout=3000
V (14468) sdspi_host: sdspi_host_start_command: slot=1, CMD41, arg=0x40ff8000 flags=0x4, data=0x0, data_size=0 crc=0x0b
V bus_lock: dev 5 acquired.
D (14488) spi_master: device5 locked the bus
V (14488) spi_master: polling trans
V (14498) spi_master: polling trans done
V (14498) spi_master: polling trans
V (14498) spi_master: polling trans done
V (14508) spi_master: polling trans
V (14508) spi_master: polling trans done
V (14508) spi_master: polling trans
V (14518) spi_master: polling trans done
D (14518) spi_master: device5 release bus
V (14528) bus_lock: dev 5 released.
V (14528) sdspi_transaction: r1 = 0x01 hw_cmd.r[0]=0xffffffff
V (14538) sdmmc_cmd: cmd response 00000001 00000000 00000000 00000000 err=0x0 state=0
V (14548) sdmmc_cmd: sending cmd slot=1 op=55 arg=0 flags=1c00 data=0x0 blklen=0 datalen=0 timeout=3000
V (14548) sdspi_host: sdspi_host_start_command: slot=1, CMD55, arg=0x00000000 flags=0x4, data=0x0, data_size=0 crc=0x32
V bus_lock: dev 5 acquired.
D (14568) spi_master: device5 locked the bus
V (14568) spi_master: polling trans
V (14568) spi_master: polling trans done
V (14578) spi_master: polling trans
V (14578) spi_master: polling trans done
V (14588) spi_master: polling trans
V (14588) spi_master: polling trans done
V (14588) spi_master: polling trans
V (14598) spi_master: polling trans done
D (14598) spi_master: device5 release bus
V (14598) bus_lock: dev 5 released.
V (14608) sdspi_transaction: r1 = 0x01 hw_cmd.r[0]=0xffffffff
V (14608) sdmmc_cmd: cmd response 00000001 00000000 00000000 00000000 err=0x0 state=0
V (14618) sdmmc_cmd: sending cmd slot=1 op=41 arg=40ff8000 flags=1030 data=0x0 blklen=0 datalen=0 timeout=3000
V (14628) sdspi_host: sdspi_host_start_command: slot=1, CMD41, arg=0x40ff8000 flags=0x4, data=0x0, data_size=0 crc=0x0b
V bus_lock: dev 5 acquired.
D (14648) spi_master: device5 locked the bus
V (14648) spi_master: polling trans
V (14648) spi_master: polling trans done
V (14658) spi_master: polling trans
V (14658) spi_master: polling trans done
V (14658) spi_master: polling trans
V (14668) spi_master: polling trans done
V (14668) spi_master: polling trans
V (14678) spi_master: polling trans done
D (14678) spi_master: device5 release bus
V (14678) bus_lock: dev 5 released.
V (14688) sdspi_transaction: r1 = 0x01 hw_cmd.r[0]=0xffffffff
V (14688) sdmmc_cmd: cmd response 00000001 00000000 00000000 00000000 err=0x0 state=0
V (14708) sdmmc_cmd: sending cmd slot=1 op=55 arg=0 flags=1c00 data=0x0 blklen=0 datalen=0 timeout=3000
V (14708) sdspi_host: sdspi_host_start_command: slot=1, CMD55, arg=0x00000000 flags=0x4, data=0x0, data_size=0 crc=0x32
V bus_lock: dev 5 acquired.
D (14718) spi_master: device5 locked the bus
V (14728) spi_master: polling trans
V (14728) spi_master: polling trans done
V (14738) spi_master: polling trans
V (14738) spi_master: polling trans done
V (14738) spi_master: polling trans
V (14748) spi_master: polling trans done
V (14748) spi_master: polling trans
V (14748) spi_master: polling trans done
D (14758) spi_master: device5 release bus
V (14758) bus_lock: dev 5 released.
V (14768) sdspi_transaction: r1 = 0x01 hw_cmd.r[0]=0xffffffff
V (14768) sdmmc_cmd: cmd response 00000001 00000000 00000000 00000000 err=0x0 state=0
V (14778) sdmmc_cmd: sending cmd slot=1 op=41 arg=40ff8000 flags=1030 data=0x0 blklen=0 datalen=0 timeout=3000
V (14788) sdspi_host: sdspi_host_start_command: slot=1, CMD41, arg=0x40ff8000 flags=0x4, data=0x0, data_size=0 crc=0x0b
V bus_lock: dev 5 acquired.
D (14798) spi_master: device5 locked the bus
V (14808) spi_master: polling trans
V (14808) spi_master: polling trans done
V (14818) spi_master: polling trans
V (14818) spi_master: polling trans done
V (14818) spi_master: polling trans
V (14828) spi_master: polling trans done
V (14828) spi_master: polling trans
V (14828) spi_master: polling trans done
D (14838) spi_master: device5 release bus
V (14838) bus_lock: dev 5 released.
V (14848) sdspi_transaction: r1 = 0x01 hw_cmd.r[0]=0xffffffff
V (14848) sdmmc_cmd: cmd response 00000001 00000000 00000000 00000000 err=0x0 state=0
V (14868) sdmmc_cmd: sending cmd slot=1 op=55 arg=0 flags=1c00 data=0x0 blklen=0 datalen=0 timeout=3000
V (14868) sdspi_host: sdspi_host_start_command: slot=1, CMD55, arg=0x00000000 flags=0x4, data=0x0, data_size=0 crc=0x32
V bus_lock: dev 5 acquired.
D (14878) spi_master: device5 locked the bus
V (14888) spi_master: polling trans
V (14888) spi_master: polling trans done
V (14888) spi_master: polling trans
V (14898) spi_master: polling trans done
V (14898) spi_master: polling trans
V (14908) spi_master: polling trans done
V (14908) spi_master: polling trans
V (14908) spi_master: polling trans done
D (14918) spi_master: device5 release bus
V (14918) bus_lock: dev 5 released.
V (14918) sdspi_transaction: r1 = 0x01 hw_cmd.r[0]=0xffffffff
V (14928) sdmmc_cmd: cmd response 00000001 00000000 00000000 00000000 err=0x0 state=0
V (14938) sdmmc_cmd: sending cmd slot=1 op=41 arg=40ff8000 flags=1030 data=0x0 blklen=0 datalen=0 timeout=3000
V (14948) sdspi_host: sdspi_host_start_command: slot=1, CMD41, arg=0x40ff8000 flags=0x4, data=0x0, data_size=0 crc=0x0b
V bus_lock: dev 5 acquired.
D (14958) spi_master: device5 locked the bus
V (14968) spi_master: polling trans
V (14968) spi_master: polling trans done
V (14968) spi_master: polling trans
V (14978) spi_master: polling trans done
V (14978) spi_master: polling trans
V (14978) spi_master: polling trans done
V (14988) spi_master: polling trans
V (14988) spi_master: polling trans done
D (14998) spi_master: device5 release bus
V (14998) bus_lock: dev 5 released.
V (14998) sdspi_transaction: r1 = 0x01 hw_cmd.r[0]=0xffffffff
V (15008) sdmmc_cmd: cmd response 00000001 00000000 00000000 00000000 err=0x0 state=0
V (15028) sdmmc_cmd: sending cmd slot=1 op=55 arg=0 flags=1c00 data=0x0 blklen=0 datalen=0 timeout=3000
V (15028) sdspi_host: sdspi_host_start_command: slot=1, CMD55, arg=0x00000000 flags=0x4, data=0x0, data_size=0 crc=0x32
V bus_lock: dev 5 acquired.
D (15038) spi_master: device5 locked the bus
V (15038) spi_master: polling trans
V (15048) spi_master: polling trans done
V (15048) spi_master: polling trans
V (15058) spi_master: polling trans done
V (15058) spi_master: polling trans
V (15058) spi_master: polling trans done
V (15068) spi_master: polling trans
V (15068) spi_master: polling trans done
D (15068) spi_master: device5 release bus
V (15078) bus_lock: dev 5 released.
V (15078) sdspi_transaction: r1 = 0x01 hw_cmd.r[0]=0xffffffff
V (15088) sdmmc_cmd: cmd response 00000001 00000000 00000000 00000000 err=0x0 state=0
V (15098) sdmmc_cmd: sending cmd slot=1 op=41 arg=40ff8000 flags=1030 data=0x0 blklen=0 datalen=0 timeout=3000
V (15108) sdspi_host: sdspi_host_start_command: slot=1, CMD41, arg=0x40ff8000 flags=0x4, data=0x0, data_size=0 crc=0x0b
V bus_lock: dev 5 acquired.
D (15118) spi_master: device5 locked the bus
V (15118) spi_master: polling trans
V (15128) spi_master: polling trans done
V (15128) spi_master: polling trans
V (15138) spi_master: polling trans done
V (15138) spi_master: polling trans
V (15138) spi_master: polling trans done
V (15148) spi_master: polling trans
V (15148) spi_master: polling trans done
D (15148) spi_master: device5 release bus
V (15158) bus_lock: dev 5 released.
V (15158) sdspi_transaction: r1 = 0x01 hw_cmd.r[0]=0xffffffff
V (15168) sdmmc_cmd: cmd response 00000001 00000000 00000000 00000000 err=0x0 state=0
V (15188) sdmmc_cmd: sending cmd slot=1 op=55 arg=0 flags=1c00 data=0x0 blklen=0 datalen=0 timeout=3000
V (15188) sdspi_host: sdspi_host_start_command: slot=1, CMD55, arg=0x00000000 flags=0x4, data=0x0, data_size=0 crc=0x32
V bus_lock: dev 5 acquired.
D (15198) spi_master: device5 locked the bus
V (15198) spi_master: polling trans
V (15208) spi_master: polling trans done
V (15208) spi_master: polling trans
V (15208) spi_master: polling trans done
V (15218) spi_master: polling trans
V (15218) spi_master: polling trans done
V (15228) spi_master: polling trans
V (15228) spi_master: polling trans done
D (15228) spi_master: device5 release bus
V (15238) bus_lock: dev 5 released.
V (15238) sdspi_transaction: r1 = 0x01 hw_cmd.r[0]=0xffffffff
V (15248) sdmmc_cmd: cmd response 00000001 00000000 00000000 00000000 err=0x0 state=0
V (15248) sdmmc_cmd: sending cmd slot=1 op=41 arg=40ff8000 flags=1030 data=0x0 blklen=0 datalen=0 timeout=3000
V (15258) sdspi_host: sdspi_host_start_command: slot=1, CMD41, arg=0x40ff8000 flags=0x4, data=0x0, data_size=0 crc=0x0b
V bus_lock: dev 5 acquired.
D (15278) spi_master: device5 locked the bus
V (15278) spi_master: polling trans
V (15288) spi_master: polling trans done
V (15288) spi_master: polling trans
V (15288) spi_master: polling trans done
V (15298) spi_master: polling trans
V (15298) spi_master: polling trans done
V (15298) spi_master: polling trans
V (15308) spi_master: polling trans done
D (15308) spi_master: device5 release bus
V (15318) bus_lock: dev 5 released.
V (15318) sdspi_transaction: r1 = 0x01 hw_cmd.r[0]=0xffffffff
V (15328) sdmmc_cmd: cmd response 00000001 00000000 00000000 00000000 err=0x0 state=0
V (15338) sdmmc_cmd: sending cmd slot=1 op=55 arg=0 flags=1c00 data=0x0 blklen=0 datalen=0 timeout=3000
V (15338) sdspi_host: sdspi_host_start_command: slot=1, CMD55, arg=0x00000000 flags=0x4, data=0x0, data_size=0 crc=0x32
V bus_lock: dev 5 acquired.
D (15358) spi_master: device5 locked the bus
V (15358) spi_master: polling trans
V (15358) spi_master: polling trans done
V (15368) spi_master: polling trans
V (15368) spi_master: polling trans done
V (15378) spi_master: polling trans
V (15378) spi_master: polling trans done
V (15378) spi_master: polling trans
V (15388) spi_master: polling trans done
D (15388) spi_master: device5 release bus
V (15388) bus_lock: dev 5 released.
V (15398) sdspi_transaction: r1 = 0x01 hw_cmd.r[0]=0xffffffff
V (15398) sdmmc_cmd: cmd response 00000001 00000000 00000000 00000000 err=0x0 state=0
V (15408) sdmmc_cmd: sending cmd slot=1 op=41 arg=40ff8000 flags=1030 data=0x0 blklen=0 datalen=0 timeout=3000
V (15418) sdspi_host: sdspi_host_start_command: slot=1, CMD41, arg=0x40ff8000 flags=0x4, data=0x0, data_size=0 crc=0x0b
V bus_lock: dev 5 acquired.
D (15438) spi_master: device5 locked the bus
V (15438) spi_master: polling trans
V (15438) spi_master: polling trans done
V (15448) spi_master: polling trans
V (15448) spi_master: polling trans done
V (15458) spi_master: polling trans
V (15458) spi_master: polling trans done
V (15458) spi_master: polling trans
V (15468) spi_master: polling trans done
D (15468) spi_master: device5 release bus
V (15468) bus_lock: dev 5 released.
V (15478) sdspi_transaction: r1 = 0x01 hw_cmd.r[0]=0xffffffff
V (15478) sdmmc_cmd: cmd response 00000001 00000000 00000000 00000000 err=0x0 state=0
V (15498) sdmmc_cmd: sending cmd slot=1 op=55 arg=0 flags=1c00 data=0x0 blklen=0 datalen=0 timeout=3000
V (15498) sdspi_host: sdspi_host_start_command: slot=1, CMD55, arg=0x00000000 flags=0x4, data=0x0, data_size=0 crc=0x32
V bus_lock: dev 5 acquired.
D (15508) spi_master: device5 locked the bus
V (15518) spi_master: polling trans
V (15518) spi_master: polling trans done
V (15528) spi_master: polling trans
V (15528) spi_master: polling trans done
V (15528) spi_master: polling trans
V (15538) spi_master: polling trans done
V (15538) spi_master: polling trans
V (15548) spi_master: polling trans done
D (15548) spi_master: device5 release bus
V (15548) bus_lock: dev 5 released.
V (15558) sdspi_transaction: r1 = 0x01 hw_cmd.r[0]=0xffffffff
V (15558) sdmmc_cmd: cmd response 00000001 00000000 00000000 00000000 err=0x0 state=0
V (15568) sdmmc_cmd: sending cmd slot=1 op=41 arg=40ff8000 flags=1030 data=0x0 blklen=0 datalen=0 timeout=3000
V (15578) sdspi_host: sdspi_host_start_command: slot=1, CMD41, arg=0x40ff8000 flags=0x4, data=0x0, data_size=0 crc=0x0b
V bus_lock: dev 5 acquired.
D (15588) spi_master: device5 locked the bus
V (15598) spi_master: polling trans
V (15598) spi_master: polling trans done
V (15608) spi_master: polling trans
V (15608) spi_master: polling trans done
V (15608) spi_master: polling trans
V (15618) spi_master: polling trans done
V (15618) spi_master: polling trans
V (15618) spi_master: polling trans done
D (15628) spi_master: device5 release bus
V (15628) bus_lock: dev 5 released.
V (15638) sdspi_transaction: r1 = 0x01 hw_cmd.r[0]=0xffffffff
V (15638) sdmmc_cmd: cmd response 00000001 00000000 00000000 00000000 err=0x0 state=0
V (15658) sdmmc_cmd: sending cmd slot=1 op=55 arg=0 flags=1c00 data=0x0 blklen=0 datalen=0 timeout=3000
V (15658) sdspi_host: sdspi_host_start_command: slot=1, CMD55, arg=0x00000000 flags=0x4, data=0x0, data_size=0 crc=0x32
V bus_lock: dev 5 acquired.
D (15668) spi_master: device5 locked the bus
V (15678) spi_master: polling trans
V (15678) spi_master: polling trans done
V (15678) spi_master: polling trans
V (15688) spi_master: polling trans done
V (15688) spi_master: polling trans
V (15698) spi_master: polling trans done
V (15698) spi_master: polling trans
V (15698) spi_master: polling trans done
D (15708) spi_master: device5 release bus
V (15708) bus_lock: dev 5 released.
V (15708) sdspi_transaction: r1 = 0x01 hw_cmd.r[0]=0xffffffff
V (15718) sdmmc_cmd: cmd response 00000001 00000000 00000000 00000000 err=0x0 state=0
V (15728) sdmmc_cmd: sending cmd slot=1 op=41 arg=40ff8000 flags=1030 data=0x0 blklen=0 datalen=0 timeout=3000
V (15738) sdspi_host: sdspi_host_start_command: slot=1, CMD41, arg=0x40ff8000 flags=0x4, data=0x0, data_size=0 crc=0x0b
V bus_lock: dev 5 acquired.
D (15748) spi_master: device5 locked the bus
V (15758) spi_master: polling trans
V (15758) spi_master: polling trans done
V (15758) spi_master: polling trans
V (15768) spi_master: polling trans done
V (15768) spi_master: polling trans
V (15778) spi_master: polling trans done
V (15778) spi_master: polling trans
V (15778) spi_master: polling trans done
D (15788) spi_master: device5 release bus
V (15788) bus_lock: dev 5 released.
V (15788) sdspi_transaction: r1 = 0x01 hw_cmd.r[0]=0xffffffff
V (15798) sdmmc_cmd: cmd response 00000001 00000000 00000000 00000000 err=0x0 state=0
V (15818) sdmmc_cmd: sending cmd slot=1 op=55 arg=0 flags=1c00 data=0x0 blklen=0 datalen=0 timeout=3000
V (15818) sdspi_host: sdspi_host_start_command: slot=1, CMD55, arg=0x00000000 flags=0x4, data=0x0, data_size=0 crc=0x32
V bus_lock: dev 5 acquired.
D (15828) spi_master: device5 locked the bus
V (15828) spi_master: polling trans
V (15838) spi_master: polling trans done
V (15838) spi_master: polling trans
V (15848) spi_master: polling trans done
V (15848) spi_master: polling trans
V (15848) spi_master: polling trans done
V (15858) spi_master: polling trans
V (15858) spi_master: polling trans done
D (15868) spi_master: device5 release bus
V (15868) bus_lock: dev 5 released.
V (15868) sdspi_transaction: r1 = 0x01 hw_cmd.r[0]=0xffffffff
V (15878) sdmmc_cmd: cmd response 00000001 00000000 00000000 00000000 err=0x0 state=0
V (15888) sdmmc_cmd: sending cmd slot=1 op=41 arg=40ff8000 flags=1030 data=0x0 blklen=0 datalen=0 timeout=3000
V (15898) sdspi_host: sdspi_host_start_command: slot=1, CMD41, arg=0x40ff8000 flags=0x4, data=0x0, data_size=0 crc=0x0b
V bus_lock: dev 5 acquired.
D (15908) spi_master: device5 locked the bus
V (15908) spi_master: polling trans
V (15918) spi_master: polling trans done
V (15918) spi_master: polling trans
V (15928) spi_master: polling trans done
V (15928) spi_master: polling trans
V (15928) spi_master: polling trans done
V (15938) spi_master: polling trans
V (15938) spi_master: polling trans done
D (15938) spi_master: device5 release bus
V (15948) bus_lock: dev 5 released.
V (15948) sdspi_transaction: r1 = 0x01 hw_cmd.r[0]=0xffffffff
V (15958) sdmmc_cmd: cmd response 00000001 00000000 00000000 00000000 err=0x0 state=0
V (15978) sdmmc_cmd: sending cmd slot=1 op=55 arg=0 flags=1c00 data=0x0 blklen=0 datalen=0 timeout=3000
V (15978) sdspi_host: sdspi_host_start_command: slot=1, CMD55, arg=0x00000000 flags=0x4, data=0x0, data_size=0 crc=0x32
V bus_lock: dev 5 acquired.
D (15988) spi_master: device5 locked the bus
V (15988) spi_master: polling trans
V (15998) spi_master: polling trans done
V (15998) spi_master: polling trans
V (15998) spi_master: polling trans done
V (16008) spi_master: polling trans
V (16008) spi_master: polling trans done
V (16018) spi_master: polling trans
V (16018) spi_master: polling trans done
D (16018) spi_master: device5 release bus
V (16028) bus_lock: dev 5 released.
V (16028) sdspi_transaction: r1 = 0x01 hw_cmd.r[0]=0xffffffff
V (16038) sdmmc_cmd: cmd response 00000001 00000000 00000000 00000000 err=0x0 state=0
V (16038) sdmmc_cmd: sending cmd slot=1 op=41 arg=40ff8000 flags=1030 data=0x0 blklen=0 datalen=0 timeout=3000
V (16048) sdspi_host: sdspi_host_start_command: slot=1, CMD41, arg=0x40ff8000 flags=0x4, data=0x0, data_size=0 crc=0x0b
V bus_lock: dev 5 acquired.
D (16068) spi_master: device5 locked the bus
V (16068) spi_master: polling trans
V (16078) spi_master: polling trans done
V (16078) spi_master: polling trans
V (16078) spi_master: polling trans done
V (16088) spi_master: polling trans
V (16088) spi_master: polling trans done
V (16088) spi_master: polling trans
V (16098) spi_master: polling trans done
D (16098) spi_master: device5 release bus
V (16108) bus_lock: dev 5 released.
V (16108) sdspi_transaction: r1 = 0x01 hw_cmd.r[0]=0xffffffff
V (16118) sdmmc_cmd: cmd response 00000001 00000000 00000000 00000000 err=0x0 state=0
V (16128) sdmmc_cmd: sending cmd slot=1 op=55 arg=0 flags=1c00 data=0x0 blklen=0 datalen=0 timeout=3000
V (16128) sdspi_host: sdspi_host_start_command: slot=1, CMD55, arg=0x00000000 flags=0x4, data=0x0, data_size=0 crc=0x32
V bus_lock: dev 5 acquired.
D (16148) spi_master: device5 locked the bus
V (16148) spi_master: polling trans
V (16148) spi_master: polling trans done
V (16158) spi_master: polling trans
V (16158) spi_master: polling trans done
V (16168) spi_master: polling trans
V (16168) spi_master: polling trans done
V (16168) spi_master: polling trans
V (16178) spi_master: polling trans done
D (16178) spi_master: device5 release bus
V (16188) bus_lock: dev 5 released.
V (16188) sdspi_transaction: r1 = 0x01 hw_cmd.r[0]=0xffffffff
V (16188) sdmmc_cmd: cmd response 00000001 00000000 00000000 00000000 err=0x0 state=0
V (16198) sdmmc_cmd: sending cmd slot=1 op=41 arg=40ff8000 flags=1030 data=0x0 blklen=0 datalen=0 timeout=3000
V (16208) sdspi_host: sdspi_host_start_command: slot=1, CMD41, arg=0x40ff8000 flags=0x4, data=0x0, data_size=0 crc=0x0b
V bus_lock: dev 5 acquired.
D (16228) spi_master: device5 locked the bus
V (16228) spi_master: polling trans
V (16228) spi_master: polling trans done
V (16238) spi_master: polling trans
V (16238) spi_master: polling trans done
V (16248) spi_master: polling trans
V (16248) spi_master: polling trans done
V (16248) spi_master: polling trans
V (16258) spi_master: polling trans done
D (16258) spi_master: device5 release bus
V (16258) bus_lock: dev 5 released.
V (16268) sdspi_transaction: r1 = 0x01 hw_cmd.r[0]=0xffffffff
V (16268) sdmmc_cmd: cmd response 00000001 00000000 00000000 00000000 err=0x0 state=0
V (16288) sdmmc_cmd: sending cmd slot=1 op=55 arg=0 flags=1c00 data=0x0 blklen=0 datalen=0 timeout=3000
V (16288) sdspi_host: sdspi_host_start_command: slot=1, CMD55, arg=0x00000000 flags=0x4, data=0x0, data_size=0 crc=0x32
V bus_lock: dev 5 acquired.
D (16298) spi_master: device5 locked the bus
V (16308) spi_master: polling trans
V (16308) spi_master: polling trans done
V (16318) spi_master: polling trans
V (16318) spi_master: polling trans done
V (16318) spi_master: polling trans
V (16328) spi_master: polling trans done
V (16328) spi_master: polling trans
V (16338) spi_master: polling trans done
D (16338) spi_master: device5 release bus
V (16338) bus_lock: dev 5 released.
V (16348) sdspi_transaction: r1 = 0x01 hw_cmd.r[0]=0xffffffff
V (16348) sdmmc_cmd: cmd response 00000001 00000000 00000000 00000000 err=0x0 state=0
V (16358) sdmmc_cmd: sending cmd slot=1 op=41 arg=40ff8000 flags=1030 data=0x0 blklen=0 datalen=0 timeout=3000
V (16368) sdspi_host: sdspi_host_start_command: slot=1, CMD41, arg=0x40ff8000 flags=0x4, data=0x0, data_size=0 crc=0x0b
V bus_lock: dev 5 acquired.
D (16378) spi_master: device5 locked the bus
V (16388) spi_master: polling trans
V (16388) spi_master: polling trans done
V (16398) spi_master: polling trans
V (16398) spi_master: polling trans done
V (16398) spi_master: polling trans
V (16408) spi_master: polling trans done
V (16408) spi_master: polling trans
V (16408) spi_master: polling trans done
D (16418) spi_master: device5 release bus
V (16418) bus_lock: dev 5 released.
V (16428) sdspi_transaction: r1 = 0x01 hw_cmd.r[0]=0xffffffff
V (16428) sdmmc_cmd: cmd response 00000001 00000000 00000000 00000000 err=0x0 state=0
V (16448) sdmmc_cmd: sending cmd slot=1 op=55 arg=0 flags=1c00 data=0x0 blklen=0 datalen=0 timeout=3000
V (16448) sdspi_host: sdspi_host_start_command: slot=1, CMD55, arg=0x00000000 flags=0x4, data=0x0, data_size=0 crc=0x32
V bus_lock: dev 5 acquired.
D (16458) spi_master: device5 locked the bus
V (16468) spi_master: polling trans
V (16468) spi_master: polling trans done
V (16468) spi_master: polling trans
V (16478) spi_master: polling trans done
V (16478) spi_master: polling trans
V (16488) spi_master: polling trans done
V (16488) spi_master: polling trans
V (16488) spi_master: polling trans done
D (16498) spi_master: device5 release bus
V (16498) bus_lock: dev 5 released.
V (16508) sdspi_transaction: r1 = 0x01 hw_cmd.r[0]=0xffffffff
V (16508) sdmmc_cmd: cmd response 00000001 00000000 00000000 00000000 err=0x0 state=0
V (16518) sdmmc_cmd: sending cmd slot=1 op=41 arg=40ff8000 flags=1030 data=0x0 blklen=0 datalen=0 timeout=3000
V (16528) sdspi_host: sdspi_host_start_command: slot=1, CMD41, arg=0x40ff8000 flags=0x4, data=0x0, data_size=0 crc=0x0b
V bus_lock: dev 5 acquired.
D (16538) spi_master: device5 locked the bus
V (16548) spi_master: polling trans
V (16548) spi_master: polling trans done
V (16548) spi_master: polling trans
V (16558) spi_master: polling trans done
V (16558) spi_master: polling trans
V (16568) spi_master: polling trans done
V (16568) spi_master: polling trans
V (16568) spi_master: polling trans done
D (16578) spi_master: device5 release bus
V (16578) bus_lock: dev 5 released.
V (16578) sdspi_transaction: r1 = 0x01 hw_cmd.r[0]=0xffffffff
V (16588) sdmmc_cmd: cmd response 00000001 00000000 00000000 00000000 err=0x0 state=0
V (16608) sdmmc_cmd: sending cmd slot=1 op=55 arg=0 flags=1c00 data=0x0 blklen=0 datalen=0 timeout=3000
V (16608) sdspi_host: sdspi_host_start_command: slot=1, CMD55, arg=0x00000000 flags=0x4, data=0x0, data_size=0 crc=0x32
V bus_lock: dev 5 acquired.
D (16618) spi_master: device5 locked the bus
V (16628) spi_master: polling trans
V (16628) spi_master: polling trans done
V (16628) spi_master: polling trans
V (16638) spi_master: polling trans done
V (16638) spi_master: polling trans
V (16638) spi_master: polling trans done
V (16648) spi_master: polling trans
V (16648) spi_master: polling trans done
D (16658) spi_master: device5 release bus
V (16658) bus_lock: dev 5 released.
V (16658) sdspi_transaction: r1 = 0x01 hw_cmd.r[0]=0xffffffff
V (16668) sdmmc_cmd: cmd response 00000001 00000000 00000000 00000000 err=0x0 state=0
V (16678) sdmmc_cmd: sending cmd slot=1 op=41 arg=40ff8000 flags=1030 data=0x0 blklen=0 datalen=0 timeout=3000
V (16688) sdspi_host: sdspi_host_start_command: slot=1, CMD41, arg=0x40ff8000 flags=0x4, data=0x0, data_size=0 crc=0x0b
V bus_lock: dev 5 acquired.
D (16698) spi_master: device5 locked the bus
V (16698) spi_master: polling trans
V (16708) spi_master: polling trans done
V (16708) spi_master: polling trans
V (16718) spi_master: polling trans done
V (16718) spi_master: polling trans
V (16718) spi_master: polling trans done
V (16728) spi_master: polling trans
V (16728) spi_master: polling trans done
D (16728) spi_master: device5 release bus
V (16738) bus_lock: dev 5 released.
V (16738) sdspi_transaction: r1 = 0x01 hw_cmd.r[0]=0xffffffff
V (16748) sdmmc_cmd: cmd response 00000001 00000000 00000000 00000000 err=0x0 state=0
V (16768) sdmmc_cmd: sending cmd slot=1 op=55 arg=0 flags=1c00 data=0x0 blklen=0 datalen=0 timeout=3000
V (16768) sdspi_host: sdspi_host_start_command: slot=1, CMD55, arg=0x00000000 flags=0x4, data=0x0, data_size=0 crc=0x32
V bus_lock: dev 5 acquired.
D (16778) spi_master: device5 locked the bus
V (16778) spi_master: polling trans
V (16788) spi_master: polling trans done
V (16788) spi_master: polling trans
V (16788) spi_master: polling trans done
V (16798) spi_master: polling trans
V (16798) spi_master: polling trans done
V (16808) spi_master: polling trans
V (16808) spi_master: polling trans done
D (16808) spi_master: device5 release bus
V (16818) bus_lock: dev 5 released.
V (16818) sdspi_transaction: r1 = 0x01 hw_cmd.r[0]=0xffffffff
V (16828) sdmmc_cmd: cmd response 00000001 00000000 00000000 00000000 err=0x0 state=0
V (16828) sdmmc_cmd: sending cmd slot=1 op=41 arg=40ff8000 flags=1030 data=0x0 blklen=0 datalen=0 timeout=3000
V (16838) sdspi_host: sdspi_host_start_command: slot=1, CMD41, arg=0x40ff8000 flags=0x4, data=0x0, data_size=0 crc=0x0b
V bus_lock: dev 5 acquired.
D (16858) spi_master: device5 locked the bus
V (16858) spi_master: polling trans
V (16868) spi_master: polling trans done
V (16868) spi_master: polling trans
V (16868) spi_master: polling trans done
V (16878) spi_master: polling trans
V (16878) spi_master: polling trans done
V (16888) spi_master: polling trans
V (16888) spi_master: polling trans done
D (16888) spi_master: device5 release bus
V (16898) bus_lock: dev 5 released.
V (16898) sdspi_transaction: r1 = 0x01 hw_cmd.r[0]=0xffffffff
V (16908) sdmmc_cmd: cmd response 00000001 00000000 00000000 00000000 err=0x0 state=0
V (16918) sdmmc_cmd: sending cmd slot=1 op=55 arg=0 flags=1c00 data=0x0 blklen=0 datalen=0 timeout=3000
V (16918) sdspi_host: sdspi_host_start_command: slot=1, CMD55, arg=0x00000000 flags=0x4, data=0x0, data_size=0 crc=0x32
V bus_lock: dev 5 acquired.
D (16938) spi_master: device5 locked the bus
V (16938) spi_master: polling trans
V (16948) spi_master: polling trans done
V (16948) spi_master: polling trans
V (16948) spi_master: polling trans done
V (16958) spi_master: polling trans
V (16958) spi_master: polling trans done
V (16958) spi_master: polling trans
V (16968) spi_master: polling trans done
D (16968) spi_master: device5 release bus
V (16978) bus_lock: dev 5 released.
V (16978) sdspi_transaction: r1 = 0x01 hw_cmd.r[0]=0xffffffff
V (16988) sdmmc_cmd: cmd response 00000001 00000000 00000000 00000000 err=0x0 state=0
V (16988) sdmmc_cmd: sending cmd slot=1 op=41 arg=40ff8000 flags=1030 data=0x0 blklen=0 datalen=0 timeout=3000
V (16998) sdspi_host: sdspi_host_start_command: slot=1, CMD41, arg=0x40ff8000 flags=0x4, data=0x0, data_size=0 crc=0x0b
V bus_lock: dev 5 acquired.
D (17018) spi_master: device5 locked the bus
V (17018) spi_master: polling trans
V (17018) spi_master: polling trans done
V (17028) spi_master: polling trans
V (17028) spi_master: polling trans done
V (17038) spi_master: polling trans
V (17038) spi_master: polling trans done
V (17038) spi_master: polling trans
V (17048) spi_master: polling trans done
D (17048) spi_master: device5 release bus
V (17058) bus_lock: dev 5 released.
V (17058) sdspi_transaction: r1 = 0x01 hw_cmd.r[0]=0xffffffff
V (17058) sdmmc_cmd: cmd response 00000001 00000000 00000000 00000000 err=0x0 state=0
V (17078) sdmmc_cmd: sending cmd slot=1 op=55 arg=0 flags=1c00 data=0x0 blklen=0 datalen=0 timeout=3000
V (17078) sdspi_host: sdspi_host_start_command: slot=1, CMD55, arg=0x00000000 flags=0x4, data=0x0, data_size=0 crc=0x32
V bus_lock: dev 5 acquired.
D (17098) spi_master: device5 locked the bus
V (17098) spi_master: polling trans
V (17098) spi_master: polling trans done
V (17108) spi_master: polling trans
V (17108) spi_master: polling trans done
V (17108) spi_master: polling trans
V (17118) spi_master: polling trans done
V (17118) spi_master: polling trans
V (17128) spi_master: polling trans done
D (17128) spi_master: device5 release bus
V (17128) bus_lock: dev 5 released.
V (17138) sdspi_transaction: r1 = 0x01 hw_cmd.r[0]=0xffffffff
V (17138) sdmmc_cmd: cmd response 00000001 00000000 00000000 00000000 err=0x0 state=0
V (17148) sdmmc_cmd: sending cmd slot=1 op=41 arg=40ff8000 flags=1030 data=0x0 blklen=0 datalen=0 timeout=3000
V (17158) sdspi_host: sdspi_host_start_command: slot=1, CMD41, arg=0x40ff8000 flags=0x4, data=0x0, data_size=0 crc=0x0b
V bus_lock: dev 5 acquired.
D (17168) spi_master: device5 locked the bus
V (17178) spi_master: polling trans
V (17178) spi_master: polling trans done
V (17188) spi_master: polling trans
V (17188) spi_master: polling trans done
V (17188) spi_master: polling trans
V (17198) spi_master: polling trans done
V (17198) spi_master: polling trans
V (17208) spi_master: polling trans done
D (17208) spi_master: device5 release bus
V (17208) bus_lock: dev 5 released.
V (17218) sdspi_transaction: r1 = 0x01 hw_cmd.r[0]=0xffffffff
V (17218) sdmmc_cmd: cmd response 00000001 00000000 00000000 00000000 err=0x0 state=0
V (17238) sdmmc_cmd: sending cmd slot=1 op=55 arg=0 flags=1c00 data=0x0 blklen=0 datalen=0 timeout=3000
V (17238) sdspi_host: sdspi_host_start_command: slot=1, CMD55, arg=0x00000000 flags=0x4, data=0x0, data_size=0 crc=0x32
V bus_lock: dev 5 acquired.
D (17248) spi_master: device5 locked the bus
V (17258) spi_master: polling trans
V (17258) spi_master: polling trans done
V (17268) spi_master: polling trans
V (17268) spi_master: polling trans done
V (17268) spi_master: polling trans
V (17278) spi_master: polling trans done
V (17278) spi_master: polling trans
V (17278) spi_master: polling trans done
D (17288) spi_master: device5 release bus
V (17288) bus_lock: dev 5 released.
V (17298) sdspi_transaction: r1 = 0x01 hw_cmd.r[0]=0xffffffff
V (17298) sdmmc_cmd: cmd response 00000001 00000000 00000000 00000000 err=0x0 state=0
V (17308) sdmmc_cmd: sending cmd slot=1 op=41 arg=40ff8000 flags=1030 data=0x0 blklen=0 datalen=0 timeout=3000
V (17318) sdspi_host: sdspi_host_start_command: slot=1, CMD41, arg=0x40ff8000 flags=0x4, data=0x0, data_size=0 crc=0x0b
V bus_lock: dev 5 acquired.
D (17328) spi_master: device5 locked the bus
V (17338) spi_master: polling trans
V (17338) spi_master: polling trans done
V (17338) spi_master: polling trans
V (17348) spi_master: polling trans done
V (17348) spi_master: polling trans
V (17358) spi_master: polling trans done
V (17358) spi_master: polling trans
V (17358) spi_master: polling trans done
D (17368) spi_master: device5 release bus
V (17368) bus_lock: dev 5 released.
V (17368) sdspi_transaction: r1 = 0x01 hw_cmd.r[0]=0xffffffff
V (17378) sdmmc_cmd: cmd response 00000001 00000000 00000000 00000000 err=0x0 state=0
V (17398) sdmmc_cmd: sending cmd slot=1 op=55 arg=0 flags=1c00 data=0x0 blklen=0 datalen=0 timeout=3000
V (17398) sdspi_host: sdspi_host_start_command: slot=1, CMD55, arg=0x00000000 flags=0x4, data=0x0, data_size=0 crc=0x32
V bus_lock: dev 5 acquired.
D (17408) spi_master: device5 locked the bus
V (17418) spi_master: polling trans
V (17418) spi_master: polling trans done
V (17418) spi_master: polling trans
V (17428) spi_master: polling trans done
V (17428) spi_master: polling trans
V (17428) spi_master: polling trans done
V (17438) spi_master: polling trans
V (17438) spi_master: polling trans done
D (17448) spi_master: device5 release bus
V (17448) bus_lock: dev 5 released.
V (17448) sdspi_transaction: r1 = 0x01 hw_cmd.r[0]=0xffffffff
V (17458) sdmmc_cmd: cmd response 00000001 00000000 00000000 00000000 err=0x0 state=0
V (17468) sdmmc_cmd: sending cmd slot=1 op=41 arg=40ff8000 flags=1030 data=0x0 blklen=0 datalen=0 timeout=3000
V (17478) sdspi_host: sdspi_host_start_command: slot=1, CMD41, arg=0x40ff8000 flags=0x4, data=0x0, data_size=0 crc=0x0b
V bus_lock: dev 5 acquired.
D (17488) spi_master: device5 locked the bus
V (17488) spi_master: polling trans
V (17498) spi_master: polling trans done
V (17498) spi_master: polling trans
V (17508) spi_master: polling trans done
V (17508) spi_master: polling trans
V (17508) spi_master: polling trans done
V (17518) spi_master: polling trans
V (17518) spi_master: polling trans done
D (17528) spi_master: device5 release bus
V (17528) bus_lock: dev 5 released.
V (17528) sdspi_transaction: r1 = 0x01 hw_cmd.r[0]=0xffffffff
V (17538) sdmmc_cmd: cmd response 00000001 00000000 00000000 00000000 err=0x0 state=0
E (17558) sdmmc_common: sdmmc_init_ocr: send_op_cond (1) returned 0x107
D (17558) sdmmc_init: sdmmc_card_init: sdmmc_init_ocr returned 0x107
E (17558) vfs_fat_sdmmc: sdmmc_card_init failed (0x107).
I (17568) gpio: GPIO[20]| InputEn: 1| OutputEn: 0| OpenDrain: 0| Pullup: 0| Pulldown: 0| Intr:0
E (17578) example: Failed to initialize the card (ESP_ERR_TIMEOUT). Make sure SD card lines have pull-up resistors in place.
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment