Linux/AM5728: SD card transfer fails

Please post complete details of this use case, along with relevant log files.

Hi Biser,

Thanks for your reply,

IN my use case i am using vendor commands and some sequence of data write/read in to SD card's SRAM.
where i has to use cmd 25(multi block write) and to stop it, used cmd12.


Please find the attached bus doctor log and the kernel log.

sync
root@am57xx-evm:~#
U-Boot SPL 2016.05-00328-g68790ff-dirty (Feb 23 2018 - 15:51:16)
DRA752-GP ES2.0
Trying to boot from MMC2_2
reading args
spl_load_image_fat_os: error reading image args, err - -1
reading u-boot.img
reading u-boot.img
reading u-boot.img
reading u-boot.img


U-Boot 2016.05-00328-g68790ff-dirty (Feb 23 2018 - 15:51:16 +0530)

CPU : DRA752-GP ES2.0
Model: TI AM5728 BeagleBoard-X15
Board: AM572x EVM REV A.3A
DRAM: 2 GiB
MMC: no pinctrl for sdr104
no pinctrl for ddr50
no pinctrl for sdr50
no pinctrl for sdr25
no pinctrl for sdr12
OMAP SD/MMC: 0, OMAP SD/MMC: 1
MMC: no card present
** Bad device mmc 0 **
Using default environment

SCSI: SATA link 0 timeout.
AHCI 0001.0300 32 slots 1 ports 3 Gbps 0x1 impl SATA mode
flags: 64bit ncq stag pm led clo only pmp pio slum part ccc apst
scanning bus for devices...
Found 0 device(s).
Net:
Warning: ethernet@48484000 using MAC address from ROM
eth0: ethernet@48484000
Hit any key to stop autoboot: 0
MMC: no card present
MMC: no card present
MMC: no card present
MMC: no card present
switch to partitions #0, OK
mmc1(part 0) is current device
SD/MMC found on device 1
3607192 bytes read in 58 ms (59.3 MiB/s)
103020 bytes read in 18 ms (5.5 MiB/s)
Kernel image @ 0x82000000 [ 0x000000 - 0x370a98 ]
## Flattened Device Tree blob at 88000000
Booting using the fdt blob at 0x88000000
Loading Device Tree to 8ffe3000, end 8ffff26b ... OK

Starting kernel ...

[ 0.000000] Booting Linux on physical CPU 0x0
[ 0.000000] Initializing cgroup subsys cpuset
[ 0.000000] Initializing cgroup subsys cpu
[ 0.000000] Initializing cgroup subsys cpuacct
[ 0.000000] Linux version 4.4.41-564679-gcc2fc2b-dirty (gytechs@gytechs-All-Series) (gcc version 5.3.1 20160113 (Linaro GCC 5.3-2016.02) ) #44 SMP PREEMPT Mon Feb 26 11:28:38 IST 2018
[ 0.000000] CPU: ARMv7 Processor [412fc0f2] revision 2 (ARMv7), cr=30c5387d
[ 0.000000] CPU: PIPT / VIPT nonaliasing data cache, PIPT instruction cache
[ 0.000000] Machine model: TI AM572x EVM Rev A3
[ 0.000000] Reserved memory: created CMA memory pool at 0x0000000095800000, size 56 MiB
[ 0.000000] Reserved memory: initialized node ipu2_cma@95800000, compatible id shared-dma-pool
[ 0.000000] Reserved memory: created CMA memory pool at 0x0000000099000000, size 64 MiB
[ 0.000000] Reserved memory: initialized node dsp1_cma@99000000, compatible id shared-dma-pool
[ 0.000000] Reserved memory: created CMA memory pool at 0x000000009d000000, size 32 MiB
[ 0.000000] Reserved memory: initialized node ipu1_cma@9d000000, compatible id shared-dma-pool
[ 0.000000] Reserved memory: created CMA memory pool at 0x000000009f000000, size 8 MiB
[ 0.000000] Reserved memory: initialized node dsp2_cma@9f000000, compatible id shared-dma-pool
[ 0.000000] cma: Reserved 24 MiB at 0x00000000fe400000
[ 0.000000] Forcing write-allocate cache policy for SMP
[ 0.000000] Memory policy: Data cache writealloc
[ 0.000000] OMAP4: Map 0x00000000ffd00000 to fe600000 for dram barrier
[ 0.000000] DRA752 ES2.0
[ 0.000000] PERCPU: Embedded 11 pages/cpu @eed2f000 s14848 r8192 d22016 u45056
[ 0.000000] Built 1 zonelists in Zone order, mobility grouping on. Total pages: 464448
[ 0.000000] Kernel command line: console=ttyO2,115200n8 root=PARTUUID=2e5d9ce8-02 rw rootfstype=ext4 rootwait
[ 0.000000] PID hash table entries: 4096 (order: 2, 16384 bytes)
[ 0.000000] Dentry cache hash table entries: 131072 (order: 7, 524288 bytes)
[ 0.000000] Inode-cache hash table entries: 65536 (order: 6, 262144 bytes)
[ 0.000000] Memory: 1648124K/1864704K available (6603K kernel code, 361K rwdata, 2412K rodata, 332K init, 289K bss, 28164K reserved, 188416K cma-reserved, 1250304K highmem)
[ 0.000000] Virtual kernel memory layout:
[ 0.000000] vector : 0xffff0000 - 0xffff1000 ( 4 kB)
[ 0.000000] fixmap : 0xffc00000 - 0xfff00000 (3072 kB)
[ 0.000000] vmalloc : 0xf0800000 - 0xff800000 ( 240 MB)
[ 0.000000] lowmem : 0xc0000000 - 0xf0000000 ( 768 MB)
[ 0.000000] pkmap : 0xbfe00000 - 0xc0000000 ( 2 MB)
[ 0.000000] modules : 0xbf000000 - 0xbfe00000 ( 14 MB)
[ 0.000000] .text : 0xc0008000 - 0xc08d6094 (9017 kB)
[ 0.000000] .init : 0xc08d7000 - 0xc092a000 ( 332 kB)
[ 0.000000] .data : 0xc092a000 - 0xc09846f0 ( 362 kB)
[ 0.000000] .bss : 0xc0986000 - 0xc09ce700 ( 290 kB)
[ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=2, Nodes=1
[ 0.000000] Preemptible hierarchical RCU implementation.
[ 0.000000] Build-time adjustment of leaf fanout to 32.
[ 0.000000] NR_IRQS:16 nr_irqs:16 16
[ 0.000000] ti_dt_clocks_register: failed to lookup clock node gmac_gmii_ref_clk_div
[ 0.000000] OMAP clockevent source: timer1 at 32786 Hz
[ 0.000000] Architected cp15 timer(s) running at 6.14MHz (phys).
[ 0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x16af5adb9, max_idle_ns: 440795202250 ns
[ 0.000004] sched_clock: 56 bits at 6MHz, resolution 162ns, wraps every 4398046511023ns
[ 0.000016] Switching to timer-based delay loop, resolution 162ns
[ 0.000326] clocksource: 32k_counter: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 58327039986419 ns
[ 0.000334] OMAP clocksource: 32k_counter at 32768 Hz
[ 0.000755] Console: colour dummy device 80x30
[ 0.000770] WARNING: Your 'console=ttyO2' has been replaced by 'ttyS2'
[ 0.000777] This ensures that you still see kernel messages. Please
[ 0.000783] update your kernel commandline.
[ 0.000796] Calibrating delay loop (skipped), value calculated using timer frequency.. 12.29 BogoMIPS (lpj=61475)
[ 0.000809] pid_max: default: 32768 minimum: 301
[ 0.000902] Mount-cache hash table entries: 2048 (order: 1, 8192 bytes)
[ 0.000912] Mountpoint-cache hash table entries: 2048 (order: 1, 8192 bytes)
[ 0.001449] Initializing cgroup subsys io
[ 0.001466] Initializing cgroup subsys memory
[ 0.001489] Initializing cgroup subsys devices
[ 0.001501] Initializing cgroup subsys freezer
[ 0.001511] Initializing cgroup subsys perf_event
[ 0.001523] Initializing cgroup subsys pids
[ 0.001549] CPU: Testing write buffer coherency: ok
[ 0.001748] /cpus/cpu@0 missing clock-frequency property
[ 0.001764] /cpus/cpu@1 missing clock-frequency property
[ 0.001775] CPU0: thread -1, cpu 0, socket 0, mpidr 80000000
[ 0.001809] Setting up static identity map for 0x800082c0 - 0x80008320
[ 0.080081] CPU1: thread -1, cpu 1, socket 0, mpidr 80000001
[ 0.080140] Brought up 2 CPUs
[ 0.080154] SMP: Total of 2 processors activated (24.59 BogoMIPS).
[ 0.080160] CPU: All CPU(s) started in HYP mode.
[ 0.080166] CPU: Virtualization extensions available.
[ 0.080534] devtmpfs: initialized
[ 0.108002] VFP support v0.3: implementor 41 architecture 4 part 30 variant f rev 0
[ 0.108933] omap_hwmod: l3_main_2 using broken dt data from ocp
[ 0.309777] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns
[ 0.313748] pinctrl core: initialized pinctrl subsystem
[ 0.314587] NET: Registered protocol family 16
[ 0.315488] DMA: preallocated 256 KiB pool for atomic coherent allocations
[ 0.340238] cpuidle: using governor ladder
[ 0.370264] cpuidle: using governor menu
[ 0.378857] OMAP GPIO hardware version 0.1
[ 0.385334] irq: no irq domain found for /ocp/l4@4a000000/scm@2000/pinmux@1400 !
[ 0.410311] hw-breakpoint: found 5 (+1 reserved) breakpoint and 4 watchpoint registers.
[ 0.410322] hw-breakpoint: maximum watchpoint size is 8 bytes.
[ 0.410781] omap4_sram_init:Unable to allocate sram needed to handle errata I688
[ 0.410791] omap4_sram_init:Unable to get sram pool needed to handle errata I688
[ 0.411366] OMAP DMA hardware revision 0.0
[ 0.451272] omap-dma-engine 4a056000.dma-controller: OMAP DMA engine driver (LinkedList1/2/3 supported)
[ 0.452425] edma 43300000.edma: memcpy is disabled
[ 0.457168] edma 43300000.edma: TI EDMA DMA engine driver
[ 0.461325] omap-iommu 40d01000.mmu: 40d01000.mmu registered
[ 0.461509] omap-iommu 40d02000.mmu: 40d02000.mmu registered
[ 0.461671] omap-iommu 58882000.mmu: 58882000.mmu registered
[ 0.461824] omap-iommu 55082000.mmu: 55082000.mmu registered
[ 0.462089] omap-iommu 41501000.mmu: 41501000.mmu registered
[ 0.462284] omap-iommu 41502000.mmu: 41502000.mmu registered
[ 0.465402] palmas 0-0058: Irq flag is 0x00000008
[ 0.481953] palmas 0-0058: Muxing GPIO 2f, PWM 0, LED 0
[ 0.564014] omap_i2c 48070000.i2c: bus 0 rev0.12 at 400 kHz
[ 0.564506] omap_i2c 48060000.i2c: bus 2 rev0.12 at 400 kHz
[ 0.565067] omap_i2c 4807c000.i2c: bus 4 rev0.12 at 400 kHz
[ 0.565277] media: Linux media interface: v0.10
[ 0.565331] Linux video capture interface: v2.00
[ 0.565371] pps_core: LinuxPPS API ver. 1 registered
[ 0.565379] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>
[ 0.565403] PTP clock support registered
[ 0.565450] EDAC MC: Ver: 3.0.0
[ 0.566186] omap-mailbox 4883c000.mailbox: omap mailbox rev 0x400
[ 0.566478] omap-mailbox 4883e000.mailbox: omap mailbox rev 0x400
[ 0.566763] omap-mailbox 48840000.mailbox: omap mailbox rev 0x400
[ 0.567055] omap-mailbox 48842000.mailbox: omap mailbox rev 0x400
[ 0.567394] Advanced Linux Sound Architecture Driver Initialized.
[ 0.568204] clocksource: Switched to clocksource arch_sys_counter
[ 0.579235] NET: Registered protocol family 2
[ 0.579730] TCP established hash table entries: 8192 (order: 3, 32768 bytes)
[ 0.579794] TCP bind hash table entries: 8192 (order: 4, 65536 bytes)
[ 0.579919] TCP: Hash tables configured (established 8192 bind 8192)
[ 0.579969] UDP hash table entries: 512 (order: 2, 16384 bytes)
[ 0.580000] UDP-Lite hash table entries: 512 (order: 2, 16384 bytes)
[ 0.580209] NET: Registered protocol family 1
[ 0.580452] RPC: Registered named UNIX socket transport module.
[ 0.580462] RPC: Registered udp transport module.
[ 0.580469] RPC: Registered tcp transport module.
[ 0.580475] RPC: Registered tcp NFSv4.1 backchannel transport module.
[ 0.581446] hw perfevents: enabled with armv7_cortex_a15 PMU driver, 7 counters available
[ 0.583689] futex hash table entries: 512 (order: 3, 32768 bytes)
[ 0.591298] squashfs: version 4.0 (2009/01/31) Phillip Lougher
[ 0.591921] NFS: Registering the id_resolver key type
[ 0.591955] Key type id_resolver registered
[ 0.591962] Key type id_legacy registered
[ 0.592016] ntfs: driver 2.1.32 [Flags: R/O].
[ 0.593932] bounce: pool size: 64 pages
[ 0.594090] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247)
[ 0.594105] io scheduler noop registered
[ 0.594118] io scheduler deadline registered
[ 0.594150] io scheduler cfq registered (default)
[ 0.599140] pinctrl-single 4a003400.pinmux: 282 pins at pa fc003400 size 1128
[ 0.602851] PCI host bridge /ocp/axi@0/pcie_rc@51000000 ranges:
[ 0.602862] No bus range found for /ocp/axi@0/pcie_rc@51000000, using [bus 00-ff]
[ 0.602894] IO 0x20003000..0x20012fff -> 0x00000000
[ 0.602914] MEM 0x20013000..0x2fffffff -> 0x20013000
[ 0.632792] dra7-pcie 51000000.pcie_rc: link is not up
[ 0.632960] dra7-pcie 51000000.pcie_rc: PCI host bridge to bus 0000:00
[ 0.632977] pci_bus 0000:00: root bus resource [bus 00-ff]
[ 0.632987] pci_bus 0000:00: root bus resource [io 0x0000-0xffff]
[ 0.632998] pci_bus 0000:00: root bus resource [mem 0x20013000-0x2fffffff]
[ 0.633392] PCI: bus0: Fast back to back transfers disabled
[ 0.633512] PCI: bus1: Fast back to back transfers enabled
[ 0.633597] pci 0000:00:00.0: BAR 0: assigned [mem 0x20100000-0x201fffff]
[ 0.633611] pci 0000:00:00.0: BAR 1: assigned [mem 0x20020000-0x2002ffff]
[ 0.633624] pci 0000:00:00.0: PCI bridge to [bus 01]
[ 0.633839] pcieport 0000:00:00.0: Signaling PME through PCIe PME interrupt
[ 0.635173] backlight supply power not found, using dummy regulator
[ 0.694777] Serial: 8250/16550 driver, 10 ports, IRQ sharing disabled
[ 0.698192] 48020000.serial: ttyS2 at MMIO 0x48020000 (irq = 301, base_baud = 3000000) is a 8250
[ 1.660779] console [ttyS2] enabled
[ 1.665110] 48422000.serial: ttyS7 at MMIO 0x48422000 (irq = 302, base_baud = 3000000) is a 8250
[ 1.675202] [drm] Initialized drm 1.1.0 20060810
[ 1.681873] OMAP DSS rev 6.1
[ 1.685647] omapdss_dss 58000000.dss: bound 58001000.dispc (ops dispc_component_ops)
[ 1.694138] omapdss_dss 58000000.dss: bound 58040000.encoder (ops hdmi5_component_ops)
[ 1.710430] loop: module loaded
[ 1.716288] libphy: Fixed MDIO Bus: probed
[ 1.768239] davinci_mdio 48485000.mdio: davinci mdio revision 1.6
[ 1.774361] davinci_mdio 48485000.mdio: detected phy mask fffffff9
[ 1.784715] libphy: 48485000.mdio: probed
[ 1.788790] davinci_mdio 48485000.mdio: phy[1]: device 48485000.mdio:01, driver Micrel KSZ9031 Gigabit PHY
[ 1.798525] davinci_mdio 48485000.mdio: phy[2]: device 48485000.mdio:02, driver Micrel KSZ9031 Gigabit PHY
[ 1.808906] cpsw 48484000.ethernet: Detected MACID = fc:0f:4b:8b:06:4a
[ 1.815552] cpsw 48484000.ethernet: cpts: overflow check period 800
[ 1.822544] cpsw 48484000.ethernet: cpsw: Detected MACID = fc:0f:4b:8b:06:4b
[ 1.831073] mousedev: PS/2 mouse device common for all mice
[ 1.837107] i2c /dev entries driver
[ 1.843455] gpio-fan gpio_fan: GPIO fan initialized
[ 1.849375] tmp102 0-0048: initialized
[ 1.856205] omap_hsmmc 4809c000.mmc: Got CD GPIO
[ 1.861259] vdd_3v3: supplied by regen1
[ 1.908699] omap_hsmmc 480b4000.mmc: no pinctrl state for sdr25 mode
[ 1.915083] omap_hsmmc 480b4000.mmc: no pinctrl state for sdr12 mode
[ 2.019667] ledtrig-cpu: registered to indicate activity on CPUs
[ 2.030705] NET: Registered protocol family 10
[ 2.046019] sit: IPv6 over IPv4 tunneling driver
[ 2.051335] NET: Registered protocol family 17
[ 2.056026] Key type dns_resolver registered
[ 2.060481] omap_voltage_late_init: Voltage driver support not added
[ 2.067368] Adding alias for supply vdd,cpu0 -> vdd,4a003b20.oppdm
[ 2.073594] Adding alias for supply vbb,cpu0 -> vbb,4a003b20.oppdm
[ 2.080297] Adding alias for supply vdd,cpu0 -> vdd,4a003b20.oppdm
[ 2.086505] Adding alias for supply vbb,cpu0 -> vbb,4a003b20.oppdm
[ 2.094624] Power Management for TI OMAP4+ devices.
[ 2.099723] Registering SWP/SWPB emulation handler
[ 2.106546] dmm 4e000000.dmm: initialized all PAT entries
[ 2.131507] [drm] Supports vblank timestamp caching Rev 2 (21.10.2013).
[ 2.138150] [drm] No driver support for vblank timestamp query.
[ 2.145959] [drm] Enabling DMM ywrap scrolling
[ 2.190244] Console: switching to colour frame buffer device 100x30
[ 2.192800] mmc1: MAN_BKOPS_EN bit is not set
[ 2.201489] mmc1: new DDR MMC card at address 0001
[ 2.207556] omapdrm omapdrm.0: fb0: omapdrm frame buffer device
[ 2.211900] mmcblk0: mmc1:0001 S10004 3.56 GiB
[ 2.222074] mmcblk0boot0: mmc1:0001 S10004 partition 1 4.00 MiB
[ 2.228148] mmcblk0boot1: mmc1:0001 S10004 partition 2 4.00 MiB
[ 2.234731] mmcblk0: p1 p2
[ 2.248692] [drm] Initialized omapdrm 1.0.0 20110917 on minor 0
[ 2.255808] input: gpio_keys as /devices/platform/gpio_keys/input/input0
[ 2.262746] hctosys: unable to open rtc device (rtc0)
[ 2.276060] aic_dvdd_fixed: disabling
[ 2.279768] vmmcwl_fixed: disabling
[ 2.283447] ldousb: disabling
[ 2.286829] ALSA device list:
[ 2.289818] No soundcards found.
[ 2.630956] EXT4-fs (mmcblk0p2): warning: mounting fs with errors, running e2fsck is recommended
[ 2.641026] EXT4-fs (mmcblk0p2): recovery complete
[ 2.648165] EXT4-fs (mmcblk0p2): mounted filesystem with ordered data mode. Opts: (null)
[ 2.656336] VFS: Mounted root (ext4 filesystem) on device 179:2.
[ 2.662827] devtmpfs: mounted
[ 2.665977] Freeing unused kernel memory: 332K (c08d7000 - c092a000)
[ 2.672371] This architecture does not have kernel memory protection.
[ 2.751646] systemd[1]: System time before build time, advancing clock.
[ 2.770865] random: systemd: uninitialized urandom read (16 bytes read, 23 bits of entropy available)
[ 2.782474] random: systemd: uninitialized urandom read (16 bytes read, 23 bits of entropy available)
[ 2.793811] systemd[1]: systemd 229 running in system mode. (+PAM -AUDIT -SELINUX +IMA -APPARMOR +SMACK +SYSVINIT +UTMP -LIBCRYPTSETUP -GCRYPT -GNUTLS +ACL +XZ -LZ4 -SECCOMP +BLKID -ELFUTILS +KMOD -IDN)
[ 2.812314] systemd[1]: Detected architecture arm.

Welcome to Arago 2016.12!

[ 2.838810] systemd[1]: Set hostname to <am57xx-evm>.
[ 2.889212] random: systemd-sysv-ge: uninitialized urandom read (16 bytes read, 24 bits of entropy available)
[ 2.910878] random: systemd-sysv-ge: uninitialized urandom read (16 bytes read, 25 bits of entropy available)
[ 2.921461] random: systemd-gpt-aut: uninitialized urandom read (16 bytes read, 25 bits of entropy available)
[ 2.931737] random: systemd-gpt-aut: uninitialized urandom read (16 bytes read, 25 bits of entropy available)
[ 2.946918] random: systemd-sysv-ge: uninitialized urandom read (16 bytes read, 26 bits of entropy available)
[ 2.995618] random: systemd: uninitialized urandom read (16 bytes read, 29 bits of entropy available)
[ 3.005136] random: systemd: uninitialized urandom read (16 bytes read, 29 bits of entropy available)
[ 3.015033] random: systemd: uninitialized urandom read (16 bytes read, 29 bits of entropy available)
[ 3.226402] systemd[1]: basic.target: Found ordering cycle on basic.target/start
[ 3.233899] systemd[1]: basic.target: Found dependency on sysinit.target/start
[ 3.241248] systemd[1]: basic.target: Found dependency on alignment.service/start
[ 3.248808] systemd[1]: basic.target: Found dependency on basic.target/start
[ 3.255892] systemd[1]: basic.target: Breaking ordering cycle by deleting job alignment.service/start
[ 3.265174] systemd[1]: alignment.service: Job alignment.service/start deleted to break ordering cycle starting with basic.target/start
[ SKIP ] Ordering cycle found, skipping alignment.service
[ 3.302005] systemd[1]: Created slice System Slice.
[ OK ] Created slice System Slice.
[ 3.328541] systemd[1]: Listening on Journal Socket (/dev/log).
[ OK ] Listening on Journal Socket (/dev/log).
[ 3.364262] systemd[1]: Reached target Remote File Systems.
[ OK ] Reached target Remote File Systems.
[ 3.388484] systemd[1]: Listening on /dev/initctl Compatibility Named Pipe.
[ OK ] Listening on /dev/initctl Compatibility Named Pipe.
[ 3.418587] systemd[1]: Listening on Network Service Netlink Socket.
[ OK ] Listening on Network Service Netlink Socket.
[ 3.449022] systemd[1]: Created slice system-getty.slice.
[ OK ] Created slice system-getty.slice.
[ 3.478400] systemd[1]: Reached target Swap.
[ OK ] Reached target Swap.
[ 3.498510] systemd[1]: Started Forward Password Requests to Wall Directory Watch.
[ OK ] Started Forward Password Requests to Wall Directory Watch.
[ 3.528952] systemd[1]: Created slice system-serial\x2dgetty.slice.
[ OK ] Created slice system-serial\x2dgetty.slice.
[ 3.558532] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.
[ OK ] Started Dispatch Password Requests to Console Directory Watch.
[ 3.588406] systemd[1]: Reached target Paths.
[ OK ] Reached target Paths.
[ 3.608437] systemd[1]: Listening on udev Kernel Socket.
[ OK ] Listening on udev Kernel Socket.
[ 3.638478] systemd[1]: Listening on udev Control Socket.
[ OK ] Listening on udev Control Socket.
[ 3.668495] systemd[1]: Listening on Journal Socket.
[ OK ] Listening on Journal Socket.
[ 3.728586] systemd[1]: Starting Create list of required static device nodes for the current kernel...
Starting Create list of required st... nodes for the current kernel...
[ 3.760675] systemd[1]: Starting Setup Virtual Console...
Starting Setup Virtual Console...
[ 3.790509] systemd[1]: Mounting POSIX Message Queue File System...
Mounting POSIX Message Queue File System...
[ 3.820482] systemd[1]: Starting Remount Root and Kernel File Systems...
Starting Remount Root and Kernel File Systems...
[ 3.834677] EXT4-fs (mmcblk0p2): re-mounted. Opts: (null)
[ 3.888618] systemd[1]: Starting Load Kernel Modules...
Starting Load Kernel Modules...
[ 3.920827] systemd[1]: Mounting Temporary Directory...
Mounting Temporary Directory...
[ 3.950567] systemd[1]: Mounting Debug File System...
Mounting Debug File System...
[ 3.968512] systemd[1]: Listening on Syslog Socket.
[ OK ] Listening on Syslog Socket.
[ 3.990601] systemd[1]: Starting Journal Service...
Starting Journal Service...
[ 4.019151] systemd[1]: Created slice User and Session Slice.
[ OK ] Created slice User and Session Slice.
[ 4.038561] systemd[1]: Reached target Slices.
[ OK ] Reached target Slices.
[ 4.103381] systemd[1]: Mounted Debug File System.
[ OK ] Mounted Debug File System.
[ 4.128507] systemd[1]: Mounted POSIX Message Queue File System.
[ OK ] Mounted POSIX Message Queue File System.
[ 4.158475] systemd[1]: Mounted Temporary Directory.
[ OK ] Mounted Temporary Directory.
[ 4.178617] systemd[1]: Started Journal Service.
[ OK ] Started Journal Service.
[ OK ] Started Create list of required sta...ce nodes for the current kernel.
[ OK ] Started Setup Virtual Console.
[ OK ] Started Remount Root and Kernel File Systems.
[FAILED] Failed to start Load Kernel Modules.
See 'systemctl status systemd-modules-load.service' for details.
Starting Apply Kernel Variables...
Starting udev Coldplug all Devices...
Starting Create Static Device Nodes in /dev...
Starting Flush Journal to Persistent Storage...
[ OK ] Started Apply Kernel Variables.
[ OK ] Started Create Static Device Nodes in /dev.
[ 4.814634] systemd-journald[144]: Received request to flush runtime journal from PID 1
[ OK ] Started Flush Journal to Persistent Storage.
Starting udev Kernel Device Manager...
[ OK ] Reached target Local File Systems (Pre).
Mounting /var/volatile...
Mounting /media/ram...
[ OK ] Mounted /var/volatile.
[ OK ] Mounted /media/ram.
[ OK ] Started udev Kernel Device Manager.
[ OK ] Started udev Coldplug all Devices.
[ 5.140544] omap-rproc 58820000.ipu: assigned reserved memory node ipu1_cma@9d000000
[ 5.167734] remoteproc0: 58820000.ipu is available
[ 5.188254] remoteproc0: Note: remoteproc is still under development and considered experimental.
[ 5.197261] remoteproc0: THE BINARY FORMAT IS NOT YET FINALIZED, and backward compatibility isn't yet guaranteed.
[ 5.253789] omap-rproc 55020000.ipu: assigned reserved memory node ipu2_cma@95800000
[ 5.283978] remoteproc1: 55020000.ipu is available
[ 5.301451] remoteproc1: Note: remoteproc is still under development and considered experimental.
[ OK ] Found device /dev/ttyS2.
[ 5.328398] remoteproc1: THE BINARY FORMAT IS NOT YET FINALIZED, and backward compatibility isn't yet guaranteed.
[ 5.359064] omap-rproc 40800000.dsp: assigned reserved memory node dsp1_cma@99000000
[ 5.390964] remoteproc2: 40800000.dsp is available
[ 5.411708] remoteproc2: Note: remoteproc is still under development and considered experimental.
[ 5.421104] remoteproc0: registered virtio0 (type 7)
[ 5.423728] aic_dvdd_fixed: supplied by vdd_3v3
[ 5.450664] rtc-ds1307 2-006f: rtc core: registered mcp7941x as rtc0
[ 5.462447] remoteproc2: THE BINARY FORMAT IS NOT YET FINALIZED, and backward compatibility isn't yet guaranteed.
[ 5.473333] remoteproc1: registered virtio1 (type 7)
[ 5.478811] rtc-ds1307 2-006f: 64 bytes nvram
[ 5.485615] palmas-rtc 48070000.i2c:tps659038@58:tps659038_rtc: rtc core: registered 48070000.i2c:tps659 as rtc1
[ 5.490724] omap-rproc 41000000.dsp: assigned reserved memory node dsp2_cma@9f000000
[ 5.490762] remoteproc3: 41000000.dsp is available
[ 5.490765] remoteproc3: Note: remoteproc is still under development and considered experimental.
[ 5.490768] remoteproc3: THE BINARY FORMAT IS NOT YET FINALIZED, and backward compatibility isn't yet guaranteed.
[ 5.530212] omap_rng 48090000.rng: OMAP Random Number Generator ver. 20
[ OK ] Reached target Local File Systems.
[ 5.564370] omap-des 480a5000.des: OMAP DES hw accel rev: 2.2
[ 5.576018] mt9t11x 4-003c: input-clock-freq: 32000000
[ 5.579632] alg: skcipher: setkey failed on test 5 for ecb-des-omap: flags=100
[ 5.583588] alg: skcipher-ddst: setkey failed on test 5 for ecb-des-omap: flags=100
[ 5.583856] alg: skcipher-ddst: setkey failed on test 5 for ecb-des-omap: flags=100
[ 5.607048] mt9t11x 4-003c: pixel-clock-freq: 96000000
Starting Create Volatile Files and Directories...
Starting Load/Save Random Seed...
[ 5.678488] mt9t11x 4-003c: Product ID error ffffff87
[ 5.698805] omap_rtc 48838000.rtc: rtc core: registered 48838000.rtc as rtc2
[ OK ] Started Create Volatile Files and Directories.
[ OK ] Started Load/Save Random Seed.
[ 5.808957] input: pixcir_tangoc as /devices/platform/44000000.ocp/4807c000.i2c/i2c-4/4-005c/input/input1
[ 5.853380] vpe 489d0000.vpe: loading firmware vpdma-1b8.bin
Starting Network Time Synchronization...
[ 5.899124] vpe 489d0000.vpe: Device registered as /dev/video0
Starting Update UTMP about System Boot/Shutdown...
[ 5.945535] vip 48990000.vip: loading firmware vpdma-1b8.bin
[ 5.969696] vip 48990000.vip: VPDMA firmware loaded
[ 6.021532] ti-pruss 4b200000.pruss: creating PRU cores and other child platform devices
[ 6.041395] irq: no irq domain found for /ocp/pruss@4b200000/intc@4b220000 !
[ 6.072877] SCSI subsystem initialized
[ 6.086585] irq: no irq domain found for /ocp/pruss@4b200000/intc@4b220000 !
[ OK ] Started Network Time Synchronization.
[ 6.120057] omap_wdt: OMAP Watchdog Timer Rev 0x01: initial timeout 60 sec
[ 6.130098] omap-sham 4b101000.sham: hw accel on OMAP rev 4.3
[ OK ] Started Update UTMP about System Boot/Shutdown.
[ 6.189235] omap-hdmi-audio omap-hdmi-audio.0.auto: snd-soc-dummy-dai <-> 58040000.encoder mapping ok
[ OK ] Created slice system-systemd\x2dbacklight.slice.
Starting Load/Save Screen Backlight...htness of backlight:backlight...
[ OK ] Reached target System Time Synchronized.
Starting Synchronize System and HW clocks...
[ OK ] Started Synchronize System and HW clocks.
[ 6.401372] remoteproc3: registered virtio2 (type 7)
[ 6.409084] remoteproc2: registered virtio3 (type 7)
[ OK ] Started Load/Save Screen Backlight Brightness of backlight:backlight.
[ 6.513916] asoc-simple-card sound@0: tlv320aic3x-hifi <-> 48468000.mcasp mapping ok
[ 6.599734] FAT-fs (mmcblk0p1): Volume was not properly unmounted. Some data may be corrupt. Please run fsck.
[ OK ] Reached target System Initialization.
[ 6.685255] omap-aes 4b500000.aes: OMAP AES hw accel rev: 3.3
[ OK ] Listening on RPCbind Server Activation Socket.
[ OK ] Started Daily Cleanup of Temporary Directories.
[ OK ] Reached target Timers.
[ 6.777100] omap-aes 4b700000.aes: OMAP AES hw accel rev: 3.3
[ 6.786788] ti-pruss 4b280000.pruss: creating PRU cores and other child platform devices
[ OK ] Listening on Avahi mDNS/DNS-SD Stack Activation Socket.
[ 6.804024] irq: no irq domain found for /ocp/pruss@4b280000/intc@4b2a0000 !
[ OK ] Listening on D-Bus System Message Bus Socket.
[ 6.832236] irq: no irq domain found for /ocp/pruss@4b280000/intc@4b2a0000 !
[ 6846172] ahci 4a140000.sata: SSS flag set, parallel bus scan disabled
[ OK ] Listening on dropbear.socket.
[ 6.855346] ahci 4a140000.sata: AHCI 0001.0300 32 slots 1 ports 3 Gbps 0x1 impl platform mode
[ 6.872320] remoteproc4: 4b234000.pru0 is available
[ 6.878721] remoteproc4: Note: remoteproc is still under development and considered experimental.
[ OK ] Reached target Sockets.
[ 6.889118] remoteproc4: THE BINARY FORMAT IS NOT YET FINALIZED, and backward compatibility isn't yet guaranteed.
[ 6.905429] ahci 4a140000.sata: flags: 64bit ncq sntf stag pm led clo only pmp pio slum part ccc apst
[ OK ] Reached target Basic System.
[ 6.918995] remoteproc4: registered virtio4 (type 7)
[ 6.925555] pru-rproc 4b234000.pru0: PRU rproc node /ocp/pruss@4b200000/pru0@4b234000 probed successfully
[ 6.935884] scsi host0: ahci
[ 6.941211] ata1: SATA max UDMA/133 mmio [mem 0x4a140000-0x4a1410ff] port 0x100 irq 346
[ 6.950936] remoteproc5: 4b238000.pru1 is available
[ 6.955923] remoteproc5: Note: remoteproc is still under development and considered experimental.
[ 6.967159] remoteproc5: THE BINARY FORMAT IS NOT YET FINALIZED, and backward compatibility isn't yet guaranteed.
[ 6.981345] remoteproc5: registered virtio5 (type 7)
[ 6.986953] pru-rproc 4b238000.pru1: PRU rproc node /ocp/pruss@4b200000/pru1@4b238000 probed successfully
Starting Avahi mDNS/DNS-SD Stack...[ 6.998657] remoteproc6: 4b2b4000.pru0 is available

[ 7.006571] remoteproc6: Note: remoteproc is still under development and considered experimental.
[ 7.016034] remoteproc6: THE BINARY FORMAT IS NOT YET FINALIZED, and backward compatibility isn't yet guaranteed.
Starting Login Service...
[ 7.033043] remoteproc6: registered virtio6 (type 7)
[ 7.038634] pru-rproc 4b2b4000.pru0: PRU rproc node /ocp/pruss@4b280000/pru0@4b2b4000 probed successfully
[ 7.054410] remoteproc7: 4b2b8000.pru1 is available
[ OK ] Started D-Bus System Message Bus.
[ 7.062533] remoteproc7: Note: remoteproc is still under development and considered experimental.
[ 7.077925] remoteproc7: THE BINARY FORMAT IS NOT YET FINALIZED, and backward compatibility isn't yet guaranteed.
[ 7.107056] remoteproc7: registered virtio7 (type 7)
[ 7.112265] pru-rproc 4b2b8000.pru1: PRU rproc node /ocp/pruss@4b280000/pru1@4b2b8000 probed successfully
[ OK ] Started Avahi mDNS/DNS-SD Stack.
[ 7.252896] remoteproc0: powering up 58820000.ipu
Starting Network Service...
[ 7.308269] ata1: SATA link down (SStatus 0 SControl 300)
[ 7.326370] remoteproc0: Booting fw image dra7-ipu1-fw.xem4, size 4890352
Starting Print notice about GPLv3 packages...[ 7.345412] net eth1: initializing cpsw version 1.15 (0)

[ 7.360749] omap-iommu 58882000.mmu: 58882000.mmu: version 2.1
[ 7.378021] net eth0: initialized cpsw ale version 1.4
[ 7.384512] net eth0: ALE Table size 1024
Starting uim-sysfs.service...
[ OK ] Started System Logging Service.
[ OK ] Started strongSwan IPsec IKEv1/IKEv2 daemon using ipsec.conf.
[ 7.404807] remoteproc0: remote processor 58820000.ipu is now up
Starting TI MultiCore Tools Daemon...
Starting Permit User Sessions...
Starting Telephony service...
Starting Save/Restore Sound Card State...
[ OK ] Started Kernel Logging Service.
[ 7.484465] virtio_rpmsg_bus virtio0: rpmsg host is online
[ 7.485804] virtio_rpmsg_bus virtio0: creating channel rpmsg-client-sample addr 0x32
[ 7.485954] virtio_rpmsg_bus virtio0: creating channel rpmsg-client-sample addr 0x33
[ 7.486402] virtio_rpmsg_bus virtio0: creating channel rpmsg-omx addr 0x3c
[ 7.486517] virtio_rpmsg_bus virtio0: creating channel rpmsg-rpc addr 0x65
[ OK ] Started Network Service.
[ 7.544622] net eth1: phy found : id is : 0x221622
[FAILED] Failed to start TI MultiCore Tools Daemon.
See 'systemctl status ti-mct-daemon.service' for details.
[ OK ] Started Permit User Sessions.
[ OK ] Started Save/Restore Sound Card State.
[ 7.599569] IPv6: ADDRCONF(NETDEV_UP): eth1: link is not ready
[ 7.623391] Bluetooth: Core ver 2.21
[ 7.629185] NET: Registered protocol family 31
[ 7.633672] Bluetooth: HCI device and connection manager initialized
[ 7.666653] NET: Registered protocol family 15
[ 7.668052] net eth0: initializing cpsw version 1.15 (0)
[ 7.681223] remoteproc1: powering up 55020000.ipu
[ 7.686205] Bluetooth: HCI socket layer initialized
[ 7.698750] Bluetooth: L2CAP socket layer initialized
[ 7.712511] remoteproc1: Booting fw image dra7-ipu2-fw.xem4, size 3743116
[ 7.722225] Bluetooth: SCO socket layer initialized
[ 7.735581] omap-iommu 55082000.mmu: 55082000.mmu: version 2.1
[ 7.810109] net eth0: phy found : id is : 0x221622
[ 7.849683] IPv6: ADDRCONF(NETDEV_UP): eth0: link is not ready
[ 7.926928] remoteproc1: remote processor 55020000.ipu is now up
[ 7.935172] random: nonblocking pool is initialized
[ 7.945297] Initializing XFRM netlink socket
[ 7.954678] virtio_rpmsg_bus virtio1: rpmsg host is online
[ 7.957368] virtio_rpmsg_bus virtio1: creating channel rpmsg-rpc addr 0x65
[ 7.958267] virtio_rpmsg_bus virtio1: creating channel rpmsg-rpc addr 0x66
[ 8.028482] remoteproc3: powering up 41000000.dsp
[ 8.135865] remoteproc3: Booting fw image dra7-dsp2-fw.xe66, size 21914156
[ 8.172677] omap_hwmod: mmu0_dsp2: _wait_target_disable failed
[ 8.178573] omap-iommu 41501000.mmu: 41501000.mmu: version 3.0
[ 8.184505] omap-iommu 41502000.mmu: 41502000.mmu: version 3.0
[ 8.297569] remoteproc3: remote processor 41000000.dsp is now up
[ 8.368963] virtio_rpmsg_bus virtio2: rpmsg host is online
[ 8.369042] virtio_rpmsg_bus virtio2: creating channel rpmsg-proto addr 0x3d
[ 8.432202] remoteproc2: powering up 40800000.dsp
[ 8.554057] usbcore: registered new interface driver usbfs
[ 8.567352] usbcore: registered new interface driver hub
[ 8.568336] remoteproc2: Booting fw image dra7-dsp1-fw.xe66, size 21914156
[ 8.574933] omap_hwmod: mmu0_dsp1: _wait_target_disable failed
[ 8.574970] omap-iommu 40d01000.mmu: 40d01000.mmu: version 3.0
[ 8.575012] omap-iommu 40d02000.mmu: 40d02000.mmu: version 3.0
[ 8.648652] usbcore: registered new device driver usb
[ 8.669606] remoteproc2: remote processor 40800000.dsp is now up
[ 8.678483] xhci-hcd xhci-hcd.1.auto: xHCI Host Controller
[ 8.684025] xhci-hcd xhci-hcd.1.auto: new USB bus registered, assigned bus number 1
[ 8.705576] virtio_rpmsg_bus virtio3: rpmsg host is online
[ 8.705657] virtio_rpmsg_bus virtio3: creating channel rpmsg-proto addr 0x3d
[ 8.749895] xhci-hcd xhci-hcd.1.auto: hcc params 0x0220f04c hci version 0x100 quirks 0x00210010
[ 8.764054] remoteproc4: powering up 4b234000.pru0
[ 8.769666] xhci-hcd xhci-hcd.1.auto: irq 473, io mem 0x48890000
[ 8.788922] remoteproc4: Booting fw image am57xx-pru1_0-fw, size 75952
[ 8.800384] hub 1-0:1.0: USB hub found
[ 8.803590] ti-pruss 4b200000.pruss: configured system_events = 0x0000000000030000 intr_channels = 0x00000005 host_intr = 0x00000005
[ 8.808832] remoteproc4: remote processor 4b234000.pru0 is now up
[ 8.816191] virtio_rpmsg_bus virtio4: rpmsg host is online
[ 8.822414] virtio_rpmsg_bus virtio4: creating channel rpmsg-pru addr 0x1e
[ 8.822645] remoteproc5: powering up 4b238000.pru1
[ 8.822779] remoteproc5: Booting fw image am57xx-pru1_1-fw, size 75952
[ 8.822906] ti-pruss 4b200000.pruss: configured system_events = 0x00000000000c0000 intr_channels = 0x0000000a host_intr = 0x0000000a
[ 8.823020] remoteproc5: remote processor 4b238000.pru1 is now up
[ 8.823109] virtio_rpmsg_bus virtio5: rpmsg host is online
[ 8.823201] remoteproc6: powering up 4b2b4000.pru0
[ 8.823318] remoteproc6: Booting fw image am57xx-pru2_0-fw, size 75952
[ 8.823427] ti-pruss 4b280000.pruss: configured system_events = 0x0000000000030000 intr_channels = 0x00000005 host_intr = 0x00000005
[ 8.827933] virtio_rpmsg_bus virtio5: creating channel rpmsg-pru addr 0x1f
[ 8.828114] remoteproc6: remote processor 4b2b4000.pru0 is now up
[ 8.828223] virtio_rpmsg_bus virtio6: rpmsg host is online
[ 8.828304] remoteproc7: powering up 4b2b8000.pru1
[ 8.828422] remoteproc7: Booting fw image am57xx-pru2_1-fw, size 75952
[ 8.828537] ti-pruss 4b280000.pruss: configured system_events = 0x00000000000c0000 intr_channels = 0x0000000a host_intr = 0x0000000a
[ 8.834868] virtio_rpmsg_bus virtio6: creating channel rpmsg-pru addr 0x20
[ 8.835041] remoteproc7: remote processor 4b2b8000.pru1 is now up
[ 8.835137] virtio_rpmsg_bus virtio7: rpmsg host is online
[ 8.839808] virtio_rpmsg_bus virtio7: creating channel rpmsg-pru addr 0x21
[ 8.882552] rpmsg_pru rpmsg8: new rpmsg_pru device: /dev/rpmsg_pru30
[ 8.882717] rpmsg_pru rpmsg9: new rpmsg_pru device: /dev/rpmsg_pru31
[ 8.882870] rpmsg_pru rpmsg10: new rpmsg_pru device: /dev/rpmsg_pru32
[ 8.883016] rpmsg_pru rpmsg11: new rpmsg_pru device: /dev/rpmsg_pru33
[ 8.901446] rpmsg_rpc rpmsg3: probing service rpc_example_2 with src 1024 dst 101
[ 8.901924] rpmsg_rpc rpmsg4: probing service dce-callback with src 1024 dst 101
[ 8.902320] rpmsg_rpc rpmsg5: probing service rpmsg-dce with src 1025 dst 102
[ 8.913011] NET: Registered protocol family 41
[ 9.024338] rpmsg_rpc rpmsg4: published functions = 4
[ 9.029461] rpmsg_rpc rpmsg5: published functions = 9
[ 9.034572] rpmsg_rpc rpmsg3: published functions = 8
[ 9.056103] hub 1-0:1.0: 1 port detected
[ 9.062848] xhci-hcd xhci-hcd.1.auto: xHCI Host Controller
[ 9.071494] xhci-hcd xhci-hcd.1.auto: new USB bus registered, assigned bus number 2
[ 9.084576] usb usb2: We don't know the algorithms for LPM for this host, disabling LPM.
[ 9.099517] hub 2-0:1.0: USB hub found
[ 9.103314] hub 2-0:1.0: 1 port detected
***************************************************************
***************************************************************
NOTICE: This file system contains the following GPLv3 packages:
autoconf
binutils-dev
binutils
bison-dev
bison
cpp-symlinks
cpp
dosfstools
g++-symlinks
g++
gawk-dev
gawk
gcc-symlinks
gcc
gdb
gdbc6x
gdbserver
gstreamer1.0-libav
hidapi
libcairo-perf-utils
libgmp10
libidn11
libmpc3
libmpfr4
m4-dev
m4
make
nettle
parted
swig-dev
swig

If you do not wish to distribute GPLv3 components please remove
the above packages prior to distribution. This can be done using
the opkg remove command. i.e.:
opkg remove <package>
Where <package> is the name printed in the list above

NOTE: If the package is a dependency of another package you
will be notified of the dependent packages. You should
use the --force-removal-of-dependent-packages option to
also remove the dependent packages as well
***************************************************************
***************************************************************
9.378240] usb 1-1: new high-speed USB device number 2 using xhci-hcd
[ 9.428325] usb 2-1: new SuperSpeed USB device number 2 using xhci-hcd
[[0;32m OK ]Started Print notice about GPLv3 packages.[ 9.452732] hub 2-1:1.0: USB hub found

[ 9.458383] hub 2-1:1.0: 4 ports detected
[[0;32m OK ]Started Telephony service.
[[0;32m OK ]Started Login Service.
[[0;32m OK ]Reached target Sound Card.
[ 9.628996] hub 1-1:1.0: USB hub found
[ 9.632817] hub 1-1:1.0: 4 ports detected
[[0;32m OK ]Listening on Load/Save RF Kill Switch Status dev/rfkill Watch.
[ OK ] Started Getty on tty1.
[ OK ] Started Serial Getty on ttyS2.
[ OK ] Reached target Network.
Starting Network Name Resolution...
Starting Lightning Fast Webserver With Light System Requirements...
[ OK ] Started Network Name Resolution.
[ OK ] Started uim-sysfs.service.
[ OK ] Started Lightning Fast Webserver With Light System Requirements.
Starting rc.pvr.service...
[ OK ] Started rc.pvr.service.
Starting weston.service...
[ OK ] Started weston.service.
Starting tiipclad-daemon.service...
Starting telnetd.service...
[ OK ] Started tiipclad-daemon.service.
[ OK ] Started telnetd.service.
Starting thttpd.service...
[ OK ] Started thttpd.service.
Starting rng-tools.service...
[ OK ] Started rng-tools.service.
Starting gdbserverproxy.service...
[ OK ] Started gdbserverproxy.service.
Starting matrix-gui-2.0.service...
[ OK ] Started matrix-gui-2.0.service.
Starting thermal-zone-init.service...
[ OK ] Started thermal-zone-init.service.

_____ _____ _ _
| _ |___ ___ ___ ___ | _ |___ ___ |_|___ ___| |_
| | _| .'| . | . | | __| _| . | | | -_| _| _|
|__|__|_| |__,|_ |___| |__| |_| |___|_| |___|___|_|
|___| |___|

Arago Project http://arago-project.org am57xx-evm ttyS2

Arago 2016.12 am57xx-evm ttyS2

am57xx-evm login: root
root@am57xx-evm:~# [ 19.045678] omap_hwmod: mmu1_dsp1: _wait_target_disable failed
[ 19.059644] omap_hwmod: mmu1_dsp2: _wait_target_disable failed
[ 19.073421] omap_hwmod: mmu0_dsp1: _wait_target_disable failed
[ 19.087145] omap_hwmod: mmu0_dsp2: _wait_target_disable failed
[ 20.546837] cpsw 48484000.ethernet eth1: Link is Up - 100Mbps/Full - flow control rx/tx
[ 20.554925] IPv6: ADDRCONF(NETDEV_CHANGE): eth1: link becomes ready

root@am57xx-evm:~# [ 30.935269] mmc0: host does not support reading read-only switch, assuming write-enable
[ 30.945352] mmc0: new high speed SDHC card at address 59b4
[ 30.965449] mmcblk1: mmc0:59b4 AF SD 29.7 GiB
[ 30.970979] mmcblk1: p1
[ 31.348037] FAT-fs (mmcblk1p1): Volume was not properly unmounted. Some data may be corrupt. Please run fsck.

root@am57xx-evm:~# ./mmc_cmd_test_deleteme /dev/mmcblk1 Nov16uSD32gb.BIN

IN send_cmd: Ret:0 cmd[60]:Response:00000800
resp[0]:800 resp[1]:0 resp[2]:0 resp[3]:0

IN send_cmd: Ret:0 cmd[24]:Response:00000900
resp[0]:900 resp[1]:0 resp[2]:0 resp[3]:0

IN send_cmd: Ret:0 cmd[17]:Response:00000900
resp[0]:900 resp[1]:0 resp[2]:0 resp[3]:0

IN send_cmd: Ret:0 cmd[61]:Response:00000000
resp[0]:0 resp[1]:0 resp[2]:0 resp[3]:0

IN send_cmd: Ret:0 cmd[0]:Response:00000000
resp[0]:0 resp[1]:0 resp[2]:0 resp[3]:0

IN send_cmd: Ret:0 cmd[8]:Response:000001aa
resp[0]:1aa resp[1]:0 resp[2]:0 resp[3]:0

==========================[0]
IN send_cmd: Ret:0 cmd[55]:Response:00000120
IN send_cmd: Ret:0 cmd[41]:Response:00ff8000
Card has not finished the power up routine
==========================
==========================[1]
IN send_cmd: Ret:0 cmd[55]:Response:00000120
IN send_cmd: Ret:0 cmd[41]:Response:c0ff8000
Card power up has finished
IN send_cmd: Ret:0 cmd[2]:Response:09415041
resp[0]:9415041 resp[1]:46205344 resp[2]:2003338 resp[3]:53002251

CID:941504146205344200333853002251
IN send_cmd: Ret:0 cmd[3]:Response:59b40520
resp[0]:59b40520 resp[1]:0 resp[2]:0 resp[3]:0

RCA:59b4
rca:59b40000
IN send_cmd: Ret:0 cmd[9]:Response:400e0032
resp[0]:400e0032 resp[1]:5b590000 resp[2]:ed9d7f80 resp[3]:a40007b

CSD:400e00325b590000ed9d7f80a40007b
IN send_cmd: Ret:0 cmd[7]:Response:00000700
resp[0]:700 resp[1]:0 resp[2]:0 resp[3]:0

IN send_cmd: Ret:0 cmd[16]:Response:00000900
resp[0]:900 resp[1]:0 resp[2]:0 resp[3]:0

IN send_cmd: Ret:0 cmd[55]:Response:00000920
resp[0]:920 resp[1]:0 resp[2]:0 resp[3]:0

IN send_cmd: Ret:0 cmd[6]:Response:00000920
resp[0]:920 resp[1]:0 resp[2]:0 resp[3]:0

IN send_cmd: Ret:0 cmd[13]:Response:00000900
resp[0]:900 resp[1]:0 resp[2]:0 resp[3]:0

IN send_cmd: Ret:0 cmd[60]:Response:00000800
resp[0]:800 resp[1]:0 resp[2]:0 resp[3]:0

IN send_cmd: Ret:0 cmd[24]:Response:00000900
resp[0]:900 resp[1]:0 resp[2]:0 resp[3]:0

IN send_cmd: Ret:0 cmd[25]:Response:00000900
resp[0]:900 resp[1]:0 resp[2]:0 resp[3]:0

cmd 12 started..
*******************************************
After CMD 12 there is no respose.

and once i remove the sd card from the board, the following is the log.

IN send_cmd: Ret:0 cmd[12]:Response:00000c00
ret_val=0
resp[0]:c00 resp[1]:0 resp[2]:0 resp[3]:0

[ 89.486307] omap_hsmmc 4809c000.mmc: __mmc_blk_ioctl_cmd: cmd error -110
E:
IN send_cmd: Ret:-1 cmd[13]:Response:00000000
A[1]....resp[0]:0 resp[1]:0 resp[2]:0 resp[3]:0

[ 89.654209] mmc0: card 59b4 removed

*************************BUS DOCTOR LOG*****
0 000s:000ms:000us 005 s CMD60(reserved for Manufacture) ARG:0055AA00 CRC:0D - SD :47.7MHz Nrc:2
1 000s:000ms:001us 001 us Read 00000000 00000000 00000000 00000000 .... .... .... .... WaitTime:0us SD :1bit Nac:1
2 000s:000ms:001us 000 us --- RSP:3C00000800A3 [47:0] - SD :- Ncr:5
3 000s:000ms:086us 085 us Read 00000000 00000000 00000000 00000000 .... .... .... .... WaitTime:0us SD :1bit Nac:0
4 000s:000ms:172us 085 us Write 00000000 00000000 00000000 00000000 .... .... .... .... CRC16 ERROR(HOST) CRC STATUS 000 CRC Error SD :1bit Nwr:0
5 000s:025ms:260us 025 ms CMD24(WRITE_SINGLE_BLOCK) ARG:000055AA CRC:6D - SD :47.7MHz Nrc:8476
6 000s:025ms:261us 001 us R1 RSP:18000009005D [47:0] - SD :- Ncr:5
7 000s:025ms:340us 078 us BUSY START - - SD :- -
8 000s:025ms:369us 029 us BUSY END - BUSY 29 us SC:1 fromCMD:0us, Avg:29us, Max:29us, Min:29us SD :- -
9 000s:123ms:900us 098 ms CMD17(READ_SINGLE_BLOCK) ARG:000055AA CRC:70 - SD :47.7MHz Nrc:5153
10 000s:123ms:901us 001 us R1 RSP:110000090067 [47:0] - SD :- Ncr:5
11 000s:124ms:377us 475 us Read 4002000B 82312565 17010000 00000000 .... .1.e .... .... WaitTime:474us SD :4bit Nac:22845
12 000s:495ms:930us 371 ms CMD61(reserved for Manufacture) ARG:00000001 CRC:7C - SD :47.7MHz Nrc:23846
13 000s:623ms:604us 127 ms CMD00(GO_IDLE_STATE) ARG:00000000 CRC:4A - SD :47.7MHz Ncc:9
14 000s:648ms:046us 024 ms CMD08(SEND_IF_COND) ARG:000001AA CRC:43 - SD :48.5MHz Ncc:9
15 000s:648ms:047us 001 us R7 RSP:08000001AA13 [47:0] - SD :- Ncr:6
16 000s:675ms:379us 027 ms CMD55(APP_CMD) ARG:00000000 CRC:32 - SD :48.5MHz Nrc:13
17 000s:675ms:380us 001 us R1 RSP:370000012083 [47:0] - SD :- Ncr:6
18 000s:696ms:237us 020 ms ACMD41(SD_SEND_OP_COND) ARG:40FF8000 CRC:0B - SD :47.7MHz Nrc:13
19 000s:696ms:238us 001 us R3 RSP:3F00FF8000FF [47:0] - SD :- Nid:6
20 010s:716ms:013us 010 s CMD55(APP_CMD) ARG:00000000 CRC:32 - SD :48.5MHz Nrc:13
21 010s:716ms:014us 001 us R1 RSP:370000012083 [47:0] - SD :- Ncr:6
22 010s:736ms:932us 020 ms ACMD41(SD_SEND_OP_COND) ARG:40FF8000 CRC:0B - SD :47.7MHz Nrc:13
23 010s:736ms:933us 001 us R3 RSP:3FC0FF8000FF [47:0] - SD :- Nid:6
24 010s:760ms:145us 023 ms CMD02(ALL_SEND_CID) ARG:00000000 CRC:26 - SD :47.7MHz Nrc:13
25 010s:760ms:146us 001 us R2 RSP:3F09415041462053440200333853002251 [135:0] - SD :- Nid:6
26 010s:789ms:986us 029 ms CMD03(SEND_RELATIVE_ADDR) ARG:00000000 CRC:10 - SD :48.5MHz Nrc:13
27 010s:789ms:987us 001 us R6 RSP:0359B4052067 [47:0] - SD :- Ncr:6
28 010s:817ms:159us 027 ms CMD09(SEND_CSD) ARG:59B40000 CRC:2B - SD :47.7MHz Nrc:13
29 010s:817ms:161us 001 us R2 RSP:3F400E00325B590000ED9D7F800A40007B [135:0] - SD :- Ncr:6
30 010s:847ms:213us 030 ms CMD07(SELECT/DESELECT_CARD) ARG:59B40000 CRC:3D - SD :47.7MHz Nrc:13
31 010s:847ms:214us 001 us R1b RSP:070000070075 [47:0] - SD :- Ncr:6
32 010s:871ms:808us 024 ms CMD16(SET_BLOCK_LEN) ARG:00000200 CRC:0A - SD :47.7MHz Nrc:13
33 010s:871ms:809us 001 us R1 RSP:10000009000B [47:0] - SD :- Ncr:6
34 010s:896ms:543us 024 ms CMD55(APP_CMD) ARG:59B40000 CRC:4E - SD :48.5MHz Nrc:13
35 010s:896ms:544us 001 us R1 RSP:370000092033 [47:0] - SD :- Ncr:6
36 010s:921ms:376us 024 ms ACMD06(SET_BUS_WIDTH) ARG:00000002 CRC:65 - SD :47.7MHz Nrc:13
37 010s:921ms:377us 001 us R1 RSP:0600000920B9 [47:0] - SD :- Ncr:6
38 010s:950ms:783us 029 ms CMD60(reserved for Manufacture) ARG:0055AA00 CRC:0D - SD :47.7MHz Nrc:13
39 010s:950ms:784us 001 us Write 00000000 00000000 00000000 00000000 .... .... .... .... CRC16 ERROR(HOST) CRC STATUS 000 CRC Error SD :1bit Nwr:2
40 010s:950ms:784us 000 us --- RSP:3C00000800A3 [47:0] - SD :- Ncr:6
41 010s:975ms:658us 024 ms CMD24(WRITE_SINGLE_BLOCK) ARG:000055AA CRC:6D - SD :47.7MHz Nrc:2948
42 010s:975ms:659us 001 us R1 RSP:18000009005D [47:0] - SD :- Ncr:6
43 010s:975ms:682us 022 us BUSY START - - SD :- -
44 010s:975ms:683us 001 us BUSY END - BUSY 1 us SC:1 fromCMD:0us, Avg:1us, Max:1us, Min:1us SD :- -
45 010s:975ms:683us 000 us Write 3E394692 244FFFEE FEFEEEEE EEEEEEEE .9F. .O.. .... .... CRC16 ERROR(HOST) CRC STATUS 000 CRC Error SD :4bit Nwr:0
46 010s:975ms:705us 021 us BUSY START - - SD :- -
47 010s:975ms:767us 062 us BUSY END - BUSY 62 us SC:1 fromCMD:0us, Avg:62us, Max:62us, Min:62us SD :- -
48 011s:000ms:425us 024 ms CMD24(WRITE_SINGLE_BLOCK) ARG:000055AA CRC:6D - SD :47.7MHz Nrc:5155
49 011s:000ms:426us 001 us R1 RSP:18000009005D [47:0] - SD :- Ncr:6
50 011s:000ms:428us 002 us Write 00010203 04050809 00001011 14151819 .... .... .... .... CRC16 ERROR(HOST) SD :4bit Nwr:68
51 011s:000ms:450us 021 us BUSY START - - SD :- -
52 011s:000ms:711us 260 us BUSY END - BUSY 260 us SC:1 fromCMD:0us, Avg:260us, Max:260us, Min:260us SD :- -
53 011s:029ms:595us 028 ms CMD60(reserved for Manufacture) ARG:0055AA00 CRC:0D - SD :47.7MHz Nrc:13627
54 011s:029ms:596us 001 us Write 00000000 00000000 00000000 00000000 .... .... .... .... CRC16 ERROR(HOST) CRC STATUS 000 CRC Error SD :1bit Nwr:2
55 011s:029ms:596us 000 us --- RSP:3C00000800A3 [47:0] - SD :- Ncr:6
56 011s:054ms:304us 024 ms CMD24(WRITE_SINGLE_BLOCK) ARG:000055AA CRC:6D - SD :47.7MHz Nrc:1388
57 011s:054ms:305us 001 us R1 RSP:18000009005D [47:0] - SD :- Ncr:6
58 011s:054ms:360us 054 us BUSY START - - SD :- -
59 011s:054ms:413us 053 us BUSY END - BUSY 53 us SC:1 fromCMD:0us, Avg:53us, Max:53us, Min:53us SD :- -
60 011s:079ms:100us 024 ms CMD24(WRITE_SINGLE_BLOCK) ARG:000055AA CRC:6D - SD :47.7MHz Nrc:5153
61 011s:079ms:101us 001 us R1 RSP:18000009005D [47:0] - SD :- Ncr:6
62 011s:079ms:104us 002 us Write 00D5CB03 45546306 B7684C88 54DA0523 .... ETc. .hL. T... CRC16 ERROR(HOST) SD :4bit Nwr:67
63 011s:079ms:125us 021 us BUSY START - - SD :- -
64 011s:079ms:386us 260 us BUSY END - BUSY 260 us SC:1 fromCMD:0us, Avg:260us, Max:260us, Min:260us SD :- -
65 011s:109ms:100us 029 ms CMD13(SEND_STATUS) ARG:59B40000 CRC:7A - SD :47.7MHz Nrc:13625
66 011s:109ms:101us 001 us R1 RSP:0D000009003F [47:0] - SD :- Ncr:6
67 011s:133ms:782us 024 ms CMD60(reserved for Manufacture) ARG:0055AA00 CRC:0D - SD :47.7MHz Nrc:13
68 011s:133ms:783us 001 us Write 00000000 00000000 00000000 00000000 .... .... .... .... CRC16 ERROR(HOST) CRC STATUS 000 CRC Error SD :1bit Nwr:2
69 011s:133ms:783us 000 us --- RSP:3C00000800A3 [47:0] - SD :- Ncr:6
70 011s:158ms:470us 024 ms CMD24(WRITE_SINGLE_BLOCK) ARG:000055AA CRC:6D - SD :48.5MHz Nrc:1350
71 011s:158ms:471us 001 us R1 RSP:18000009005D [47:0] - SD :- Ncr:6
72 011s:158ms:526us 055 us BUSY START - - SD :- -
73 011s:158ms:579us 052 us BUSY END - BUSY 52 us SC:1 fromCMD:0us, Avg:52us, Max:52us, Min:52us SD :- -
74 011s:229ms:591us 071 ms CMD25(WRITE_MULTIPLE_BLOCK) ARG:000055AA CRC:5B SC:32 SD :48.5MHz Nrc:5149
75 011s:229ms:592us 001 us R1 RSP:190000090031 [47:0] - SD :- Ncr:6
76 011s:229ms:595us 002 us Write 02712490 00432090 07522090 00454090 .q.. .C.. .R.. .E.. CRC16 ERROR(HOST) SD :4bit Nwr:66
77 011s:229ms:617us 021 us BUSY START - - SD :- -
78 011s:229ms:628us 011 us BUSY END - BUSY 11 us SD :- -
79 011s:229ms:628us 000 us Write 45600990 00012030 02008000 00091000 E... ...0 .... .... CRC16 ERROR(HOST) SD :4bit Nwr:2
80 011s:229ms:650us 021 us BUSY START - - SD :- -
81 011s:229ms:654us 004 us BUSY END - BUSY 4 us SD :- -
82 011s:229ms:655us 000 us Write 0F120026 90092EEF F0000911 000F2004 .... .... .... .... CRC16 ERROR(HOST) SD :4bit Nwr:2
83 011s:229ms:676us 021 us BUSY START - - SD :- -
84 011s:229ms:681us 004 us BUSY END - BUSY 4 us SD :- -
85 011s:229ms:681us 000 us Write 033F1237 49850045 006000AE 00AA0012 ...7 I..E .... .... CRC16 ERROR(HOST) SD :4bit Nwr:2
86 011s:229ms:703us 021 us BUSY START - - SD :- -
87 011s:229ms:708us 004 us BUSY END - BUSY 4 us SD :- -
88 011s:229ms:708us 000 us Write 127524CE C3130E13 18891225 5CC08300 .u.. .... .... .... CRC16 ERROR(HOST) SD :4bit Nwr:2
89 011s:229ms:730us 021 us BUSY START - - SD :- -
90 011s:229ms:735us 004 us BUSY END - BUSY 4 us SD :- -
91 011s:229ms:735us 000 us Write 1318899F 7C000D05 1234C612 37415403 .... .... .4.. 7AT. CRC16 ERROR(HOST) SD :4bit Nwr:2
92 011s:229ms:757us 021 us BUSY START - - SD :- -
93 011s:229ms:761us 004 us BUSY END - BUSY 4 us SD :- -
94 011s:229ms:761us 000 us Write 00149004 8220244F FE900481 00344F90 .... ...O .... .4O. CRC16 ERROR(HOST) SD :4bit Nwr:2
95 011s:229ms:783us 021 us BUSY START - - SD :- -
96 011s:229ms:788us 004 us BUSY END - BUSY 4 us SD :- -
97 011s:229ms:788us 000 us Write 05822434 08123710 B4600A90 088C7449 ...4 ..7. .... ..tI CRC16 ERROR(HOST) SD :4bit Nwr:2
98 011s:229ms:810us 021 us BUSY START - - SD :- -
99 011s:229ms:815us 004 us BUSY END - BUSY 4 us SD :- -
100 011s:229ms:815us 000 us Write 088986FE EFA80008 80020313 188CF552 .... .... .... ...R CRC16 ERROR(HOST) SD :4bit Nwr:2
101 011s:229ms:837us 021 us BUSY START - - SD :- -
102 011s:229ms:842us 004 us BUSY END - BUSY 4 us SD :- -
103 011s:229ms:842us 000 us Write 04C00208 04400202 0D126C71 12144590 .... .... ..lq ..E. CRC16 ERROR(HOST) SD :4bit Nwr:2
104 011s:229ms:864us 021 us BUSY START - - SD :- -
105 011s:229ms:868us 004 us BUSY END - BUSY 4 us SD :- -
106 011s:229ms:868us 000 us Write 05144515 12208A12 37957B31 1D02244F ..E. .... 7..1 ...O CRC16 ERROR(HOST) SD :4bit Nwr:2
107 011s:229ms:890us 021 us BUSY START - - SD :- -
108 011s:229ms:895us 004 us BUSY END - BUSY 4 us SD :- -
109 011s:229ms:895us 000 us Write 04C332F5 83222555 85533004 4FE5547A ..2. ...U .S0. O.Tz CRC16 ERROR(HOST) SD :4bit Nwr:2
110 011s:229ms:917us 021 us BUSY START - - SD :- -
111 011s:229ms:922us 004 us BUSY END - BUSY 4 us SD :- -
112 011s:229ms:922us 000 us Write 400836FD F698DCAD 44144244 66D00BC4 ..6. .... D.BD f... CRC16 ERROR(HOST) SD :4bit Nwr:2
113 011s:229ms:944us 021 us BUSY START - - SD :- -
114 011s:229ms:948us 004 us BUSY END - BUSY 4 us SD :- -
115 011s:229ms:948us 000 us Write 4312AB00 D5378300 0800747D EC480848 C... .7.. ..t. .H.H CRC16 ERROR(HOST) SD :4bit Nwr:2
116 011s:229ms:970us 021 us BUSY START - - SD :- -
117 011s:229ms:975us 004 us BUSY END - BUSY 4 us SD :- -
118 011s:229ms:975us 000 us Write 08010254 4EF2244F FE1200BB 1227A364 ...T N..O .... ...d CRC16 ERROR(HOST) SD :4bit Nwr:2
119 011s:229ms:997us 021 us BUSY START - - SD :- -
120 011s:230ms:002us 004 us BUSY END - BUSY 4 us SD :- -
121 011s:230ms:002us 000 us Write 091F0180 023F8012 00F8AF78 12091884 .... .... ...x .... CRC16 ERROR(HOST) SD :4bit Nwr:2
122 011s:230ms:024us 021 us BUSY START - - SD :- -
123 011s:230ms:028us 004 us BUSY END - BUSY 4 us SD :- -
124 011s:230ms:029us 000 us Write 00000000 00000000 00000000 00000000 .... .... .... .... - SD :4bit Nwr:2
125 011s:230ms:051us 021 us BUSY START - - SD :- -
126 011s:230ms:055us 004 us BUSY END - BUSY 4 us SD :- -
127 011s:230ms:055us 000 us Write 00000000 00000000 00000000 00000000 .... .... .... .... - SD :4bit Nwr:2
128 011s:230ms:077us 021 us BUSY START - - SD :- -
129 011s:230ms:082us 004 us BUSY END - BUSY 4 us SD :- -
130 011s:230ms:082us 000 us Write 00000000 00000000 00000000 00000000 .... .... .... .... - SD :4bit Nwr:2
131 011s:230ms:104us 021 us BUSY START - - SD :- -
132 011s:230ms:109us 004 us BUSY END - BUSY 4 us SD :- -
133 011s:230ms:109us 000 us Write 00000000 00000000 00000000 00000000 .... .... .... .... - SD :4bit Nwr:2
134 011s:230ms:131us 021 us BUSY START - - SD :- -
135 011s:230ms:135us 004 us BUSY END - BUSY 4 us SD :- -
136 011s:230ms:136us 000 us Write 00000000 00000000 00000000 00000000 .... .... .... .... - SD :4bit Nwr:2
137 011s:230ms:157us 021 us BUSY START - - SD :- -
138 011s:230ms:162us 004 us BUSY END - BUSY 4 us SD :- -
139 011s:230ms:162us 000 us Write 00000000 00000000 00000000 00000000 .... .... .... .... - SD :4bit Nwr:2
140 011s:230ms:184us 021 us BUSY START - - SD :- -
141 011s:230ms:189us 004 us BUSY END - BUSY 4 us SD :- -
142 011s:230ms:189us 000 us Write 00000000 00000000 00000000 00000000 .... .... .... .... - SD :4bit Nwr:2
143 011s:230ms:211us 021 us BUSY START - - SD :- -
144 011s:230ms:215us 004 us BUSY END - BUSY 4 us SD :- -
145 011s:230ms:216us 000 us Write 00000000 00000000 00000000 00000000 .... .... .... .... - SD :4bit Nwr:2
146 011s:230ms:238us 021 us BUSY START - - SD :- -
147 011s:230ms:242us 004 us BUSY END - BUSY 4 us SD :- -
148 011s:230ms:242us 000 us Write 00000000 00000000 00000000 00000000 .... .... .... .... - SD :4bit Nwr:2
149 011s:230ms:264us 021 us BUSY START - - SD :- -
150 011s:230ms:269us 004 us BUSY END - BUSY 4 us SD :- -
151 011s:230ms:269us 000 us Write 00000000 00000000 00000000 00000000 .... .... .... .... - SD :4bit Nwr:2
152 011s:230ms:291us 021 us BUSY START - - SD :- -
153 011s:230ms:296us 004 us BUSY END - BUSY 4 us SD :- -
154 011s:230ms:296us 000 us Write 00000000 00000000 00000000 00000000 .... .... .... .... - SD :4bit Nwr:2
155 011s:230ms:318us 021 us BUSY START - - SD :- -
156 011s:230ms:322us 004 us BUSY END - BUSY 4 us SD :- -
157 011s:230ms:323us 000 us Write 00000000 00000000 00000000 00000000 .... .... .... .... - SD :4bit Nwr:2
158 011s:230ms:344us 021 us BUSY START - - SD :- -
159 011s:230ms:349us 004 us BUSY END - BUSY 4 us SD :- -
160 011s:230ms:349us 000 us Write 00000000 00000000 00000000 00000000 .... .... .... .... - SD :4bit Nwr:2
161 011s:230ms:371us 021 us BUSY START - - SD :- -
162 011s:230ms:376us 004 us BUSY END - BUSY 4 us SD :- -
163 011s:230ms:376us 000 us Write 00000000 00000000 00000000 00000000 .... .... .... .... - SD :4bit Nwr:2
164 011s:230ms:398us 021 us BUSY START - - SD :- -
165 011s:230ms:403us 004 us BUSY END - BUSY 4 us SD :- -
166 011s:230ms:403us 000 us Write 00000000 00000000 00000000 00000000 .... .... .... .... - SD :4bit Nwr:2
167 011s:230ms:425us 021 us BUSY START - - SD :- -
168 011s:230ms:429us 004 us BUSY END - BUSY 4 us SD :- -
169 011s:230ms:429us 000 us Write 00000000 00000000 00000000 00000000 .... .... .... .... CRC16 ERROR(HOST) SD :4bit Nwr:2
170 011s:230ms:451us 021 us BUSY START - - SD :- -
171 011s:230ms:456us 004 us BUSY END - BUSY 4 us SD :- -
172 011s:383ms:776us 153 ms CMD12(STOP_TRANSMISSION) ARG:00000000 CRC:30 - SD :48.5MHz Nrc:41424
173 011s:383ms:777us 001 us BUSY START - - SD :- -
174 011s:383ms:777us 000 us R1b RSP:0C00000C001D [47:0] - SD :- Ncr:6


Thanks ,

Anil

Hi TI,

After CMD12, Transfer Complete bit is not setting in omap_hsmmc_do_irq()
if ((end_trans || (status & TC_EN)) && host->mrq)
    omap_hsmmc_xfer_done(host, data);

But, In the Bus Doctor it is showing for CMD12 the R1B response(RSP:0C00000C001D [47:0]).

Once i remove card then Transfer Complete bit is setting and the response is coming.
IN send_cmd:     Ret:0 cmd[12]:Response:00000c00

Can any one explain the behavior.

Thanks,

Anil