This thread has been locked.

If you have a related question, please click the "Ask a related question" button in the top right corner. The newly created question will be automatically linked to this question.

AM5708: mmcblk1p3 cannot be mounted

Part Number: AM5708

Hi,

My customer is considering using IS21ES04G-JCLI(ISSI) instead of MTFC4GACAJCN-1M_WT(Micron) as an eMMC boot device.
When they actually mounted ISSI's eMMC on their board and started Linux, transfer errors occurred when mounting the eMMC.
It is trying to mount three partitions, mmcblk1p1, mmcblk1p2, mmcblk1p3, but mmcblk1p3 could not be mounted.

I attach the startup log at that time.

U-Boot SPL 2017.01-00360-g705371f-dirty (Nov 27 2019 - 22:54:35)
DRA722-GP ES2.0
Trying to boot from MMC2


U-Boot 2017.01-00360-g705371f-dirty (Nov 27 2019 - 22:54:35 +0900)

CPU  : DRA722-GP ES2.0
Model: TI AM5718 IDK
Board: D348 REV 1.00
DRAM:  1 GiB
MMC:   OMAP SD/MMC: 0, OMAP SD/MMC: 1
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:   MAC Address 18:62:E4:CE:AC:16
Could not get PHY for cpsw: addr 3
cpsw
update button release
normal partition ok
user data partition ok
Hit any key to stop autoboot:  0
3560304 bytes read in 52 ms (65.3 MiB/s)
90844 bytes read in 6 ms (14.4 MiB/s)
111256349 bytes read in 1446 ms (73.4 MiB/s)
## Flattened Device Tree blob at 88000000
   Booting using the fdt blob at 0x88000000
   Loading Ramdisk to 8e5e5000, end 94fff31d ... OK
   Loading Device Tree to 8e5cb000, end 8e5e42db ... OK

Starting kernel ...

[    0.000000] Booting Linux on physical CPU 0x0
[    0.000000] Linux version 4.9.41-ge3a80a1c5c (zoom@zoom-VirtualBox) (gcc version 6.2.1 20161016 (Linaro GCC 6.2-2016.11) ) #1 PREEMPT Mon Dec 9 14:53:52 JST 2019
[    0.000000] CPU: ARMv7 Processor [412fc0f2] revision 2 (ARMv7), cr=30c53c7d
[    0.000000] CPU: div instructions available: patching division code
[    0.000000] CPU: PIPT / VIPT nonaliasing data cache, PIPT instruction cache
[    0.000000] OF: fdt:Machine model: TI AM5718 IDK
[    0.000000] efi: Getting EFI parameters from FDT:
[    0.000000] efi: UEFI not found.
[    0.000000] Reserved memory: created CMA memory pool at 0x0000000099000000, size 128 MiB
[    0.000000] OF: reserved mem: initialized node dsp1_cma@99000000, compatible id shared-dma-pool
[    0.000000] cma: Reserved 24 MiB at 0x00000000be400000
[    0.000000] Memory policy: Data cache writeback
[    0.000000] OMAP4: Map 0x00000000bfd00000 to fe600000 for dram barrier
[    0.000000] CPU: All CPU(s) started in HYP mode.
[    0.000000] CPU: Virtualization extensions available.
[    0.000000] DRA722 ES2.0
[    0.000000] Built 1 zonelists in Zone order, mobility grouping on.  Total pages: 210496
[    0.000000] Kernel command line: console=ttyS2,115200n8 root=/dev/ram0 rw rootfstype=ext4 rootwait vt.global_cursor_default=0 consoleblank=0
[    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: 560652K/848896K available (8192K kernel code, 294K rwdata, 2344K rodata, 2048K init, 282K bss, 132596K reserved, 155648K cma-reserved, 234496K 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 - 0xc0a00000   (10208 kB)
[    0.000000]       .init : 0xc0e00000 - 0xc1000000   (2048 kB)
[    0.000000]       .data : 0xc1000000 - 0xc1049b08   ( 295 kB)
[    0.000000]        .bss : 0xc104b000 - 0xc1091b34   ( 283 kB)
[    0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, 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] OMAP clockevent source: timer1 at 32786 Hz
[    0.000000] arm_arch_timer: 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.000013] Switching to timer-based delay loop, resolution 162ns
[    0.000300] clocksource: 32k_counter: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 58327039986419 ns
[    0.000305] OMAP clocksource: 32k_counter at 32768 Hz
[    0.000628] Console: colour dummy device 80x30
[    0.000648] Calibrating delay loop (skipped), value calculated using timer frequency.. 12.29 BogoMIPS (lpj=61475)
[    0.000658] pid_max: default: 32768 minimum: 301
[    0.000730] Mount-cache hash table entries: 2048 (order: 1, 8192 bytes)
[    0.000737] Mountpoint-cache hash table entries: 2048 (order: 1, 8192 bytes)
[    0.001198] CPU: Testing write buffer coherency: ok
[    0.001344] Setting up static identity map for 0x80200000 - 0x80200060
[    0.001828] EFI services will not be available.
[    0.002053] devtmpfs: initialized
[    0.021959] VFP support v0.3: implementor 41 architecture 4 part 30 variant f rev 0
[    0.022154] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns
[    0.022166] futex hash table entries: 256 (order: -1, 3072 bytes)
[    0.025029] pinctrl core: initialized pinctrl subsystem
[    0.025688] NET: Registered protocol family 16
[    0.026315] DMA: preallocated 256 KiB pool for atomic coherent allocations
[    0.026835] omap_hwmod: l3_main_2 using broken dt data from ocp
[    0.199989] cpuidle: using governor ladder
[    0.230004] cpuidle: using governor menu
[    0.237312] OMAP GPIO hardware version 0.1
[    0.248247] irq: no irq domain found for /ocp/l4@4a000000/scm@2000/pinmux@1400 !
[    0.262678] No ATAGs?
[    0.262693] hw-breakpoint: found 5 (+1 reserved) breakpoint and 4 watchpoint registers.
[    0.262700] hw-breakpoint: maximum watchpoint size is 8 bytes.
[    0.263014] omap4_sram_init:Unable to allocate sram needed to handle errata I688
[    0.263021] omap4_sram_init:Unable to get sram pool needed to handle errata I688
[    0.263364] OMAP DMA hardware revision 0.0
[    0.298510] omap-dma-engine 4a056000.dma-controller: OMAP DMA engine driver (LinkedList1/2/3 supported)
[    0.299381] edma 43300000.edma: memcpy is disabled
[    0.303120] edma 43300000.edma: TI EDMA DMA engine driver
[    0.303737] evm_3v3_sd: supplied by V3_3D
[    0.305530] omap-iommu 40d01000.mmu: 40d01000.mmu registered
[    0.305657] omap-iommu 40d02000.mmu: 40d02000.mmu registered
[    0.307539] omap_i2c 48070000.i2c: bus 0 rev0.12 at 400 kHz
[    0.307647] media: Linux media interface: v0.10
[    0.307684] Linux video capture interface: v2.00
[    0.307712] pps_core: LinuxPPS API ver. 1 registered
[    0.307718] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>
[    0.307733] PTP clock support registered
[    0.308145] omap-mailbox 4883c000.mailbox: omap mailbox rev 0x400
[    0.308365] omap-mailbox 4883e000.mailbox: omap mailbox rev 0x400
[    0.308510] omap-mailbox 48840000.mailbox: omap mailbox rev 0x400
[    0.308731] Advanced Linux Sound Architecture Driver Initialized.
[    0.309367] clocksource: Switched to clocksource arch_sys_counter
[    0.316335] NET: Registered protocol family 2
[    0.316751] TCP established hash table entries: 8192 (order: 3, 32768 bytes)
[    0.316810] TCP bind hash table entries: 8192 (order: 3, 32768 bytes)
[    0.316907] TCP: Hash tables configured (established 8192 bind 8192)
[    0.316953] UDP hash table entries: 512 (order: 1, 8192 bytes)
[    0.316975] UDP-Lite hash table entries: 512 (order: 1, 8192 bytes)
[    0.317062] NET: Registered protocol family 1
[    0.317266] RPC: Registered named UNIX socket transport module.
[    0.317272] RPC: Registered udp transport module.
[    0.317276] RPC: Registered tcp transport module.
[    0.317280] RPC: Registered tcp NFSv4.1 backchannel transport module.
[    0.317567] Trying to unpack rootfs image as initramfs...
[    6.323821] Freeing initrd memory: 108652K (ce5e5000 - d5000000)
[    6.324357] hw perfevents: enabled with armv7_cortex_a15 PMU driver, 7 counters available
[    6.326314] workingset: timestamp_bits=14 max_order=18 bucket_order=4
[    6.331929] squashfs: version 4.0 (2009/01/31) Phillip Lougher
[    6.332466] NFS: Registering the id_resolver key type
[    6.332485] Key type id_resolver registered
[    6.332491] Key type id_legacy registered
[    6.332524] ntfs: driver 2.1.32 [Flags: R/O].
[    6.333500] bounce: pool size: 64 pages
[    6.333616] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247)
[    6.333623] io scheduler noop registered
[    6.333628] io scheduler deadline registered
[    6.333722] io scheduler cfq registered (default)
[    6.336539] pinctrl-single 4a003400.pinmux: 282 pins at pa fc003400 size 1128
[    6.336593] pinctrl-single 4a002e8c.pinmux: please update dts to use #pinctrl-cells = <1>
[    6.336646] pinctrl-single 4a002e8c.pinmux: initialized with no interrupts
[    6.336654] pinctrl-single 4a002e8c.pinmux: 1 pins at pa fc002e8c size 4
[    6.338898] backlight supply power not found, using dummy regulator
[    6.380008] Serial: 8250/16550 driver, 10 ports, IRQ sharing disabled
[    6.382341] console [ttyS2] disabled
[    6.382379] 48020000.serial: ttyS2 at MMIO 0x48020000 (irq = 299, base_baud = 3000000) is a 8250
[    7.113156] console [ttyS2] enabled
[    7.118055] omap_rng 48090000.rng: OMAP Random Number Generator ver. 20
[    7.124782] [drm] Initialized
[    7.128364] 58000000.dss supply vdda_video not found, using dummy regulator
[    7.135487] OMAP DSS rev 6.1
[    7.138788] omapdss_dss 58000000.dss: bound 58001000.dispc (ops dispc_component_ops)
[    7.147682] display supply vcc not found, using dummy regulator
[    7.153776] Error: Driver 'tfp410' is already registered, aborting...
[    7.168845] brd: module loaded
[    7.175604] loop: module loaded
[    7.200851] LDO0: supplied by V3_3D
[    7.204954] LDO1: supplied by VMAIN
[    7.210694] LDO0: supplied by V3_3D
[    7.214927] LDO1: supplied by V3_3D
[    7.220852] libphy: Fixed MDIO Bus: probed
[    7.279399] davinci_mdio 48485000.mdio: davinci mdio revision 1.6
[    7.285522] libphy: 48485000.mdio: probed
[    7.294320] davinci_mdio 48485000.mdio: phy[3]: device 48485000.mdio:03, driver unknown
[    7.302852] cpsw 48484000.ethernet: Detected MACID = 18:62:e4:ce:ac:16
[    7.309534] cpsw 48484000.ethernet: cpts: overflow check period 500 (jiffies)
[    7.317684] i2c /dev entries driver
[    7.324234] cpu cpu0: "syscon" is missing, cannot use OPPv2 table.
[    7.330717] sdhci: Secure Digital Host Controller Interface driver
[    7.336920] sdhci: Copyright(c) Pierre Ossman
[    7.341939] omap_hsmmc 4809c000.mmc: Got CD GPIO
[    7.409869] omap_hsmmc 480b4000.mmc: no pinctrl state for sdr25 mode
[    7.416252] omap_hsmmc 480b4000.mmc: no pinctrl state for sdr12 mode
[    7.429377] omap_hsmmc 480b4000.mmc: no pinctrl state for hs200_1_8v mode
[    7.489504] sdhci-pltfm: SDHCI platform and OF driver helper
[    7.495687] ledtrig-cpu: registered to indicate activity on CPUs
[    7.520697] NET: Registered protocol family 10
[    7.525803] sit: IPv6, IPv4 and MPLS over IPv4 tunneling driver
[    7.539821] NET: Registered protocol family 17
[    7.544400] Key type dns_resolver registered
[    7.548811] omap_voltage_late_init: Voltage driver support not added
[    7.569453] Power Management for TI OMAP4+ devices.
[    7.585763] dmm 4e000000.dmm: workaround for errata i878 in use
[    7.610887] dmm 4e000000.dmm: initialized all PAT entries
[    7.639911] [drm] Supports vblank timestamp caching Rev 2 (21.10.2013).
[    7.646552] [drm] No driver support for vblank timestamp query.
[    7.672334] [drm] Enabling DMM ywrap scrolling
[    7.760979] Console: switching to colour frame buffer device 100x30
[    7.779158] omapdrm omapdrm.0: fb0: omapdrm frame buffer device
[    7.830111] backlight brightness=5 max_brightness=8
[    8.532958] lp8733-ldo0: disabling
[    8.536691] ALSA device list:
[    8.539760]   No soundcards found.
[    8.549012] Freeing unused kernel memory: 2048K (c0e00000 - c1000000)
System time before build time, advancing clock.[    8.564511] random: systemd: uninitialized urandom read (16 bytes read)

[    8.573071] random: systemd: uninitialized urandom read (16 bytes read)
[    8.581519] systemd[1]: systemd 230 running in system mode. (+PAM -AUDIT -SELINUX +IMA -APPARMOR +SMACK +SYSVINIT +UTMP -LIBCRYPTSETUP -GCRYPT -GNUTLS +ACL +XZ -LZ4 -SECCOMP +BLKID -ELFUTILS +KMOD -IDN)
[    8.599975] systemd[1]: Detected architecture arm.

[    8.604955] systemd[1]: Set hostname to <am57xx-evm>.
Welcome to Arago 2017.06!

[    8.610256] random: systemd: uninitialized urandom read (16 bytes read)
[    8.620107] systemd[1]: Initializing machine ID from random generator.
[    8.629138] mmc1: new DDR MMC card at address 0001
[    8.646678] mmcblk1: mmc1:0001 IS004G 3.64 GiB
[    8.671944] random: systemd-sysv-ge: uninitialized urandom read (16 bytes read)
[    8.673074] random: systemd-sysv-ge: uninitialized urandom read (16 bytes read)
[    8.674689] random: systemd-sysv-ge: uninitialized urandom read (16 bytes read)
[    8.680476] mmcblk1boot0: mmc1:0001 IS004G partition 1 2.00 MiB
[    8.695565] mmcblk1boot1: mmc1:0001 IS004G partition 2 2.00 MiB
[    8.717399] random: systemd: uninitialized urandom read (16 bytes read)
[    8.724628] random: systemd: uninitialized urandom read (16 bytes read)
[    8.731421]  mmcblk1: p1 p2 p3
[    8.736159] random: systemd: uninitialized urandom read (16 bytes read)
[    8.743482] random: systemd: uninitialized urandom read (16 bytes read)
[    8.773226] systemd[1]: Configuration file /lib/systemd/system/startup-application.service is marked executable. Please remove executable permission bits. Proceeding anyway.
[    8.833110] systemd[1]: sysinit.target: Found ordering cycle on sysinit.target/start
[    8.841016] systemd[1]: sysinit.target: Found dependency on alignment.service/start
[    8.848725] systemd[1]: sysinit.target: Found dependency on basic.target/start
[    8.856028] systemd[1]: sysinit.target: Found dependency on sockets.target/start
[    8.863471] systemd[1]: sysinit.target: Found dependency on dbus.socket/start
[ SKIP ] Ordering cycle found, skipping alignment.service
[  OK  ] Started Forward Password Requests to Wall Directory Watch.
[  OK  ] Listening on Network Service Netlink Socket.
[  OK  ] Listening on Syslog Socket.
[  OK  ] Listening on Process Core Dump Socket.
[  OK  ] Listening on udev Kernel Socket.
[  OK  ] Started Dispatch Password Requests to Console Di[    8.907337] cryptodev: loading out-of-tree module taints kernel.
rectory Watch.
[  OK  ] Reached target Paths.
[  OK  ] Created slice User and Session Slice.
[  OK  ] Reached target Swap.
[  OK  ] Listening on /dev/initctl Compatibility Named Pipe.
[  OK  ] Listening on udev Control Socket.
[  OK  ] Listening on[    8.940399] cryptodev: driver 1.8 loaded.
 Journal Socket (/dev/log).
[  OK  ] Created slice System Slice.
[  OK  ] Reached target Slices.
[  OK  ] Created slice system-serial\x2dgetty.slice.
[  OK  ] Reached target Remote File Systems.
[  OK  ] Listening on Journal Socket.
         Mounting Debug File System...
         Mounting Temporary Directory...
         Starting Load Kernel Modules...
         Starting Create list of required st... nodes for the current kernel...
         Starting Journal Service...
         Starting Setup Virtual Console...
         Starting Remount Root and Kernel File Systems...
         Mounting POSIX Message Queue File System...
[  OK  ] Mounted POSIX Message Queue File System.
[  OK  ] Mounted Debug File System.
[  OK  ] Mounted Temporary Directory.
[  OK  ] Started Create list of required sta...ce nodes for the current kernel.
[  OK  ] Started Load Kernel Modules.
[  OK  ] Started Setup Virtual Console.
[  OK  ] Started Remount Root and Kernel File Systems.
         Starting Rebuild Hardware Database...
         Starting Apply Kernel Variables...
         Mounting Configuration File System...
         Starting Create Static Device Nodes in /dev...
[  OK  ] Mounted Configuration File System.
[  OK  ] Started Apply Kernel Variables.
[  OK  ] Started Create Static Device Nodes in /dev.
         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.
         Starting Load/Save Random Seed...
[  OK  ] Reached target Local File Systems.
         Starting Rebuild Journal Catalog...
         Starting Rebuild Dynamic Linker Cache...
[  OK  ] Started Journal Service.
[  OK  ] Started udev Kernel Device Manager.
[  OK  ] Started Load/Save Random Seed.
         Starting Flush Journal to Persistent Storage...
[  OK  ] Started Rebuild Journal Catalog.
[    9.304346] systemd-journald[91]: Received request to flush runtime journal from PID 1
[  OK  ] Started Rebuild Dynamic Linker Cache.
[  OK  ] Started Flush Journal to Persistent Storage.
         Starting Create Volatile Files and Directories...
[  OK  ] Started Create Volatile Files and Directories.
         Starting Network Time Synchronization...
         Starting Opkg first boot configure...
         Starting Update UTMP about System Boot/Shutdown...
[  OK  ] Started Update UTMP about System Boot/Shutdown.
[  OK  ] Started Network Time Synchronization.
[  OK  ] Reached target System Time Synchronized.
[  OK  ] Started Opkg first boot configure.
[  OK  ] Started Rebuild Hardware Database.
         Starting udev Coldplug all Devices...
         Starting Update is Completed...
[  OK  ] Started Update is Completed.
[   11.074322] random: fast init done
[  OK  ] Found device /dev/ttyS2.
[  OK  ] Started udev Coldplug all Devices.
[  OK  ] Reached target System Initialization.
[  OK  ] Started Daily Cleanup of Temporary Directories.
[  OK  ] Reached target Timers.
[  OK  ] Listening on D-Bus System Message Bus Socket.
[  OK  ] Listening on RPCbind Server Activation Socket.
[  OK  ] Reached target Sockets.
[  OK  ] Reached target Basic System.
[  OK  ] Started Kernel Logging Service.
         Starting Print notice about GPLv3 packages...
         Starting rc.pvr.service...
[  OK  ] Started System Logging Service.
         Starting Login Service...
[  OK  ] Started D-Bus System Message Bus.
[   11.845085] omap-des 480a5000.des: OMAP DES hw accel rev: 2.2
[   11.866070] omap-des 480a5000.des: will run requests pump with realtime priority
         Starting Network Service...
[  OK  ] Started Login Service.
[  OK  ] Started Network Service.
[  OK  ] Reached target Network.
         Starting Permit User Sessions...
[   12.316823] net eth0: initializing cpsw version 1.15 (0)
[  OK  ] Started Permit User Sessions.
[  OK  ] Started Serial Getty on ttyS2.
[  OK[   12.407022] net eth0: initialized cpsw ale version 1.4
  ] Reached target Login Prompts.
[   12.414899] vpe 489d0000.vpe: loading firmware vpdma-1b8.bin
[   12.439578] vpe 489d0000.vpe: Device registered as /dev/video0
[   12.589471] net eth0: ALE Table size 1024
[   12.697119] Generic PHY 48485000.mdio:03: attached PHY driver [Generic PHY] (mii_bus:phy_addr=48485000.mdio:03, irq=-1)
***************************************************************
***************************************************************
NOTICE: This file system contains the following GPLv3 packages:
        binutils
        cifs-utils
        dosfstools
        libreadline6
        m4

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
***************************************************************
***************************************************************
[  OK  ] Started Print notice about GPLv3 packages.
[   12.868740] IPv6: ADDRCONF(NETDEV_UP): eth0: link is not ready
[   13.094935] SCSI subsystem initialized
[   13.269517] ahci 4a140000.sata: SSS flag set, parallel bus scan disabled
[   13.307784] EXT4-fs (mmcblk1p1): mounting ext3 file system using the ext4 subsystem
[   13.334646] ahci 4a140000.sata: AHCI 0001.0300 32 slots 1 ports 3 Gbps 0x1 impl platform mode
[   13.334654] ahci 4a140000.sata: flags: 64bit ncq sntf stag pm led clo only pmp pio slum part ccc apst
[   13.349976] scsi host0: ahci
[   13.350257] ata1: SATA max UDMA/133 mmio [mem 0x4a140000-0x4a1410ff] port 0x100 irq 334
[   13.351072] omap_wdt: OMAP Watchdog Timer Rev 0x01: initial timeout 60 sec
[   13.352030] omap-sham 4b101000.sham: hw accel on OMAP rev 4.3
[   13.363541] EXT4-fs (mmcblk1p3): mounting ext3 file system using the ext4 subsystem
[   13.383020] omap_hsmmc 480b4000.mmc: ADMA err: ST_TFR, desc at 0xbe448008 follows the erroneous one
[   13.390792] EXT4-fs (mmcblk1p2): mounting ext3 file system using the ext4 subsystem
[   13.435146] omap-aes 4b500000.aes: OMAP AES hw accel rev: 3.3
[   13.454697] omap-aes 4b500000.aes: will run requests pump with realtime priority
[   13.480395] omap-aes 4b700000.aes: OMAP AES hw accel rev: 3.3
[   13.481717] omap-aes 4b700000.aes: will run requests pump with realtime priority
[   13.637667] mmcblk1: error -84 transferring data, sector 1048610, nr 8, cmd response 0x900, card status 0xc00
[   13.682688] ata1: SATA link down (SStatus 0 SControl 300)
[   13.741101] omap_hsmmc 480b4000.mmc: ADMA err: ST_TFR, desc at 0xbe448008 follows the erroneous one
[   13.741354] mmcblk1: error -84 transferring data, sector 1048610, nr 8, cmd response 0x900, card status 0xc00
[   13.741365] blk_update_request: I/O error, dev mmcblk1, sector 1048610
[   13.741370] Buffer I/O error on dev mmcblk1p3, logical block 0, lost async page write
[   13.741426] JBD2: recovery failed
[   13.741432] EXT4-fs (mmcblk1p3): error loading journal
[   13.741739] VFS: Dirty inode writeback failed for block device mmcblk1p3 (err=-5).
[   13.793653] mmcblk1: error -84 transferring data, sector 524324, nr 2, cmd response 0x900, card status 0x0
[   13.793662] blk_update_request: I/O error, dev mmcblk1, sector 524324
[   13.793668] Buffer I/O error on dev mmcblk1p2, logical block 1, lost sync page write
[   13.794036] EXT4-fs (mmcblk1p2): recovery complete
[   13.794045] EXT4-fs (mmcblk1p2): mounted filesystem with ordered data mode. Opts: (null)
[   14.518636] EXT4-fs (mmcblk1p3): mounting ext3 file system using the ext4 subsystem
[   14.532099] omap_hsmmc 480b4000.mmc: ADMA err: ST_TFR, desc at 0xbe448008 follows the erroneous one
[   14.532251] mmcblk1: error -84 transferring data, sector 1048610, nr 8, cmd response 0x900, card status 0xc00
[   14.532258] blk_update_request: I/O error, dev mmcblk1, sector 1048610
[   14.532263] Buffer I/O error on dev mmcblk1p3, logical block 0, lost async page write
[   14.535736] JBD2: recovery failed
[   14.535743] EXT4-fs (mmcblk1p3): error loading journal
[   14.536056] VFS: Dirty inode writeback failed for block device mmcblk1p3 (err=-5).
[  OK  ] Created slice system-systemd\x2dbacklight.slice.
         Starting Load/Save Screen Backlight...htness of backlight:backlight...
[   15.391735] mmcblk1: error -84 transferring data, sector 36, nr 2, cmd response 0x900, card status 0xc00
[  OK  ] Started rc.pvr.service.
[  OK  ] Started Load/Save Screen Backlight Brightnes[   15.451016] blk_update_request: I/O error, dev mmcblk1, sector 36
s of backlight:backlight.
[   15.526548] Buffer I/O error on dev mmcblk1p1, logical block 1, lost sync page write
[   15.580387] EXT4-fs (mmcblk1p1): recovery complete
[   15.580396] EXT4-fs (mmcblk1p1): mounted filesystem with ordered data mode. Opts: (null)
[   15.713327] usbcore: registered new interface driver usbfs
[   15.732990] usbcore: registered new interface driver hub
[   15.749350] usbcore: registered new device driver usb
[   15.778076] xhci-hcd xhci-hcd.4.auto: xHCI Host Controller
[   15.798800] xhci-hcd xhci-hcd.4.auto: new USB bus registered, assigned bus number 1
[   15.829826] xhci-hcd xhci-hcd.4.auto: hcc params 0x0220f04c hci version 0x100 quirks 0x02010010
[   15.838597] xhci-hcd xhci-hcd.4.auto: irq 415, io mem 0x48890000
[   15.865648] hub 1-0:1.0: USB hub found
[   15.875536] hub 1-0:1.0: 1 port detected
[   15.885301] xhci-hcd xhci-hcd.4.auto: xHCI Host Controller
[   15.899300] xhci-hcd xhci-hcd.4.auto: new USB bus registered, assigned bus number 2
[   15.917373] usb usb2: We don't know the algorithms for LPM for this host, disabling LPM.
[   15.942669] hub 2-0:1.0: USB hub found
[   15.952423] hub 2-0:1.0: 1 port detected
[  OK  ] Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch.
         Starting telnetd.service...
         Starting tiipclad-daemon.service...
[  OK  ] Started telnetd.service.
[  OK  ] Started tiipclad-daemon.service.
         Starting thttpd.service...
[  OK  ] Started thttpd.service.
         Starting rng-tools.service...
[  OK  ] Started rng-tools.service.
         Starting thermal-zone-init.service...
[   16.428129] random: crng init done
[  OK  ] Started thermal-zone-init.service.
[  OK  ] Reached target Multi-User System.
         Starting Startup Application...
         Starting Update UTMP about System Runlevel Changes...
         Starting DSP module load...
[   16.494237] omap-rproc 40800000.dsp: assigned reserved memory node dsp1_cma@99000000
[  OK  ] Started Update UTMP about System Runlevel Changes.
[   16.519072] remoteproc remoteproc0: 40800000.dsp is available
[   16.530640] remoteproc remoteproc0: Direct firmware load for dra7-dsp1-fw.xe66 failed with error -2
         Starting X11...
[   16.550442] remoteproc remoteproc0: powering up 40800000.dsp
[   16.556191] remoteproc remoteproc0: Direct firmware load for dra7-dsp1-fw.xe66 failed with error -2
Starting Xorg
         Starting Application...
[   16.589535] remoteproc remoteproc0: request_firmware failed: -2

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

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

Arago 2017.06 am57xx-evm ttyS2

am57xx-evm login:          Starting TouchScreen Driver load...
[   18.591957] Gt9xx driver installing..
[   18.595822] goodix-ts 0-0014: GTP Driver Version: V2.8.0.2<2017/12/14>
matchbox: failed to load keyboard config[   18.612677] goodix-ts 0-0014: GTP I2C Address: 0x14

[   18.618220] goodix-ts 0-0014: touch input parameters is [id x y w p]<5 800 480 512 512>
[   18.635179] goodix-ts 0-0014: int-sync enabled
[   18.640860] goodix-ts 0-0014: driver-send-cfg enabled
[   18.646189] goodix-ts 0-0014: No pinctrl found
[   18.653885] goodix-ts 0-0014: Success request irq-gpio
[   18.659061] goodix-ts 0-0014: Success request rst-gpio
[   18.666075] goodix-ts 0-0014: Guitar reset
[   18.780170] goodix-ts 0-0014: I2c read failed,dev:14,reg:8047,size:1
[   18.786552] goodix-ts 0-0014: GTP i2c test failed time 1
[   18.815483] goodix-ts 0-0014: I2c read failed,dev:14,reg:8047,size:1
[   18.821907] goodix-ts 0-0014: GTP i2c test failed time 2
[   18.849010] goodix-ts 0-0014: I2c read failed,dev:14,reg:8047,size:1
[   18.855417] goodix-ts 0-0014: GTP i2c test failed time 3
[   18.873662] goodix-ts 0-0014: Failed communicate with IC use I2C
[   18.879716] goodix-ts 0-0014: Regulator vcc_i2c disabled
[   18.885056] goodix-ts 0-0014: Regulator vdd_ana disabled
[   18.893162] goodix-ts: probe of 0-0014 failed with error -11
         Starting TouchScreen Driver load Complete.
cp: can't stat '/run/media/mmcblk1p3/DSP_Updater': No such file or directory
cp: can't stat '/run/media/mmcblk1p3/DSP_Updater': No such file or directory
cp: can't stat '/run/media/mmcblk1p3/DSP_Updater': No such file or directory
cp: can't stat '/run/media/mmcblk1p3/DSP_Updater': No such file or directory
cp: can't stat '/run/media/mmcblk1p3/DSP_Updater': No such file or directory

Did you know why some eMMC partition cannot be mounted and the parts number of the ISSI which operation is confirmed?

Best Regards,
H.U

  • Hi,

    Could you enable CONFIG_MMC_DEBUG in Kernel config and share the logs?
    (You may have to append loglevel=9 to Kernel bootargs)

    Regards,
    Vishal

  • Also please share the dts setting of MMC.

  • Hi Vishal,

    Thank you for your reply.

    I will attach their dts file in haste.
    am570x.zip

    Getting the debug log is going to take a while, could you get any hints of this problem from the attached file?

    Best Regards,
    H.U

  • Hi,

    We would need the logs to see what's going on.
    I don't see DDR mode iodelay values in dts. Why is it missing?

    Could you also try going to lower speed like SDR25?

    Regards,
    Vishal

  • Hi Vishal,


    The io-delay is set as follows in attached file, is that what you want?

    ==========================================================================================


    &dra7_iodelay_core {
    // for linux kernel 4.4 / processor sdk 3.x
      mmc1_iodelay_ddr50_rev20_conf: mmc1_iodelay_ddr50_rev20_conf {
      pinctrl-pin-array = <
      0x618 A_DELAY_PS(588) G_DELAY_PS(0) /* CFG_MMC1_CLK_IN */
      0x620 A_DELAY_PS(1230) G_DELAY_PS(0) /* CFG_MMC1_CLK_OUT */
      0x624 A_DELAY_PS(1000) G_DELAY_PS(0) /* CFG_MMC1_CMD_IN */
      0x628 A_DELAY_PS(0) G_DELAY_PS(0) /* CFG_MMC1_CMD_OEN */
      0x62C A_DELAY_PS(0) G_DELAY_PS(0) /* CFG_MMC1_CMD_OUT */
      0x630 A_DELAY_PS(1375) G_DELAY_PS(0) /* CFG_MMC1_DAT0_IN */
      0x634 A_DELAY_PS(0) G_DELAY_PS(0) /* CFG_MMC1_DAT0_OEN */
      0x638 A_DELAY_PS(56) G_DELAY_PS(0) /* CFG_MMC1_DAT0_OUT */
      0x63C A_DELAY_PS(1000) G_DELAY_PS(0) /* CFG_MMC1_DAT1_IN */
      0x640 A_DELAY_PS(0) G_DELAY_PS(0) /* CFG_MMC1_DAT1_OEN */   
      0x644 A_DELAY_PS(76) G_DELAY_PS(0) /* CFG_MMC1_DAT1_OUT */
      0x648 A_DELAY_PS(1000) G_DELAY_PS(0) /* CFG_MMC1_DAT2_IN */
      0x64C A_DELAY_PS(0) G_DELAY_PS(0) /* CFG_MMC1_DAT2_OEN */
      0x650 A_DELAY_PS(91) G_DELAY_PS(0) /* CFG_MMC1_DAT2_OUT */
      0x654 A_DELAY_PS(1000) G_DELAY_PS(0) /* CFG_MMC1_DAT3_IN */
      0x658 A_DELAY_PS(0) G_DELAY_PS(0) /* CFG_MMC1_DAT3_OEN */
      0x65C A_DELAY_PS(99) G_DELAY_PS(0) /* CFG_MMC1_DAT3_OUT */
      >;
    };

    mmc1_iodelay_sdr104_rev20_conf: mmc1_iodelay_sdr104_rev20_conf {
      pinctrl-pin-array = <
      0x620 A_DELAY_PS(520) G_DELAY_PS(320) /* CFG_MMC1_CLK_OUT */
      0x628 A_DELAY_PS(51) G_DELAY_PS(0) /* CFG_MMC1_CMD_OEN */
      0x62C A_DELAY_PS(0) G_DELAY_PS(0) /* CFG_MMC1_CMD_OUT */
      0x634 A_DELAY_PS(0) G_DELAY_PS(0) /* CFG_MMC1_DAT0_OEN */
      0x638 A_DELAY_PS(40) G_DELAY_PS(0) /* CFG_MMC1_DAT0_OUT */
      0x640 A_DELAY_PS(363) G_DELAY_PS(0) /* CFG_MMC1_DAT1_OEN */
      0x644 A_DELAY_PS(83) G_DELAY_PS(0) /* CFG_MMC1_DAT1_OUT */
      0x64C A_DELAY_PS(199) G_DELAY_PS(0) /* CFG_MMC1_DAT2_OEN */
      0x650 A_DELAY_PS(98) G_DELAY_PS(0) /* CFG_MMC1_DAT2_OUT */
      0x658 A_DELAY_PS(273) G_DELAY_PS(0) /* CFG_MMC1_DAT3_OEN */
      0x65C A_DELAY_PS(106) G_DELAY_PS(0) /* CFG_MMC1_DAT3_OUT */
      >;
    };

    mmc2_iodelay_ddr_rev20_conf: mmc2_iodelay_ddr_rev20_conf {
      pinctrl-pin-array = <
      0x1C8 A_DELAY_PS(894) G_DELAY_PS(0) /* CFG_GPMC_A23_IN */
      0x1D0 A_DELAY_PS(266) G_DELAY_PS(0) /* CFG_GPMC_A23_OUT */
      0x360 A_DELAY_PS(0) G_DELAY_PS(0) /* CFG_GPMC_CS1_IN */
      0x364 A_DELAY_PS(0) G_DELAY_PS(0) /* CFG_GPMC_CS1_OEN */
      0x368 A_DELAY_PS(0) G_DELAY_PS(0) /* CFG_GPMC_CS1_OUT */
      0x1D4 A_DELAY_PS(30) G_DELAY_PS(0) /* CFG_GPMC_A24_IN */
      0x1D8 A_DELAY_PS(0) G_DELAY_PS(0) /* CFG_GPMC_A24_OEN */
      0x1DC A_DELAY_PS(0) G_DELAY_PS(0) /* CFG_GPMC_A24_OUT */
      0x1E0 A_DELAY_PS(0) G_DELAY_PS(0) /* CFG_GPMC_A25_IN */
      0x1E4 A_DELAY_PS(0) G_DELAY_PS(0) /* CFG_GPMC_A25_OEN */
      0x1E8 A_DELAY_PS(0) G_DELAY_PS(0) /* CFG_GPMC_A25_OUT */
      0x1EC A_DELAY_PS(23) G_DELAY_PS(0) /* CFG_GPMC_A26_IN */
      0x1F0 A_DELAY_PS(0) G_DELAY_PS(0) /* CFG_GPMC_A26_OEN */
      0x1F4 A_DELAY_PS(43) G_DELAY_PS(0) /* CFG_GPMC_A26_OUT */
      0x1F8 A_DELAY_PS(0) G_DELAY_PS(0) /* CFG_GPMC_A27_IN */
      0x1FC A_DELAY_PS(0) G_DELAY_PS(0) /* CFG_GPMC_A27_OEN */
      0x200 A_DELAY_PS(0) G_DELAY_PS(0) /* CFG_GPMC_A27_OUT */
      0x18C A_DELAY_PS(0) G_DELAY_PS(0) /* CFG_GPMC_A19_IN */
      0x190 A_DELAY_PS(0) G_DELAY_PS(0) /* CFG_GPMC_A19_OEN */
      0x194 A_DELAY_PS(152) G_DELAY_PS(0) /* CFG_GPMC_A19_OUT */
      0x1A4 A_DELAY_PS(119) G_DELAY_PS(0) /* CFG_GPMC_A20_IN */
      0x1A8 A_DELAY_PS(0) G_DELAY_PS(0) /* CFG_GPMC_A20_OEN */
      0x1AC A_DELAY_PS(206) G_DELAY_PS(0) /* CFG_GPMC_A20_OUT */
      0x1B0 A_DELAY_PS(0) G_DELAY_PS(0) /* CFG_GPMC_A21_IN */
      0x1B4 A_DELAY_PS(0) G_DELAY_PS(0) /* CFG_GPMC_A21_OEN */
      0x1B8 A_DELAY_PS(78) G_DELAY_PS(0) /* CFG_GPMC_A21_OUT */
      0x1BC A_DELAY_PS(18) G_DELAY_PS(0) /* CFG_GPMC_A22_IN */
      0x1C0 A_DELAY_PS(0) G_DELAY_PS(0) /* CFG_GPMC_A22_OEN */
      0x1C4 A_DELAY_PS(2) G_DELAY_PS(0) /* CFG_GPMC_A22_OUT */
      >;
    };

    mmc2_iodelay_hs200_rev20_conf: mmc2_iodelay_hs200_rev20_conf {
      pinctrl-pin-array = <
      0x1D0 A_DELAY_PS(730) G_DELAY_PS(360) /* CFG_GPMC_A23_OUT */
      0x364 A_DELAY_PS(360) G_DELAY_PS(0) /* CFG_GPMC_CS1_OEN */
      0x368 A_DELAY_PS(0) G_DELAY_PS(120) /* CFG_GPMC_CS1_OUT */
      0x1D8 A_DELAY_PS(176) G_DELAY_PS(0) /* CFG_GPMC_A24_OEN */
      0x1DC A_DELAY_PS(0) G_DELAY_PS(0) /* CFG_GPMC_A24_OUT */
      0x1E4 A_DELAY_PS(0) G_DELAY_PS(0) /* CFG_GPMC_A25_OEN */
      0x1E8 A_DELAY_PS(0) G_DELAY_PS(0) /* CFG_GPMC_A25_OUT */
      0x1F0 A_DELAY_PS(101) G_DELAY_PS(0) /* CFG_GPMC_A26_OEN */
      0x1F4 A_DELAY_PS(70) G_DELAY_PS(0) /* CFG_GPMC_A26_OUT */
      0x1FC A_DELAY_PS(0) G_DELAY_PS(0) /* CFG_GPMC_A27_OEN */
      0x200 A_DELAY_PS(0) G_DELAY_PS(0) /* CFG_GPMC_A27_OUT */
      0x190 A_DELAY_PS(0) G_DELAY_PS(0) /* CFG_GPMC_A19_OEN */
      0x194 A_DELAY_PS(285) G_DELAY_PS(0) /* CFG_GPMC_A19_OUT */$
      0x1A8 A_DELAY_PS(231) G_DELAY_PS(0) /* CFG_GPMC_A20_OEN */
      0x1AC A_DELAY_PS(189) G_DELAY_PS(0) /* CFG_GPMC_A20_OUT */
      0x1B4 A_DELAY_PS(39) G_DELAY_PS(0) /* CFG_GPMC_A21_OEN */
      0x1B8 A_DELAY_PS(0) G_DELAY_PS(120) /* CFG_GPMC_A21_OUT */
      0x1C0 A_DELAY_PS(91) G_DELAY_PS(0) /* CFG_GPMC_A22_OEN */
      0x1C4 A_DELAY_PS(0) G_DELAY_PS(70) /* CFG_GPMC_A22_OUT */
      >;
      };

    };

    ==========================================================================================

    The operation of the log and SDR25 is under confirmation with the customer. I will get back to you.


    Best Regards,
    H.U

  • Hi H.U,

    Yes, these iodelay values should be referenced in the mmc node. I didn't see that in your dts.
    Snippet below from dts you shared - am570x.dts

    &mmc2 {
    	status = "okay";
    
    	pinctrl-names = "default";
    	pinctrl-0 = <&mmc2_pins_std>;
    
    	vmmc-supply = <&evm_3v3_sd>;
    	bus-width = <8>;
    	ti,non-removable;
    	cap-mmc-dual-data-rate;
    	no-1-8-v;
    };

    Regards,
    Vishal

  • Hello Vishal,


    Please check line 4137 in the attached dts file.

    ====
    &mmc2 {
        pinctrl-names = "default", "hs", "ddr_1_8v", "hs200";
        pinctrl-0 = <&mmc2_pins_std>;
        pinctrl-1 = <&mmc2_pins_hs>;
        pinctrl-2 = <&mmc2_pins_ddr &mmc2_iodelay_ddr_rev20_conf>;
        pinctrl-3 = <&mmc2_pins_hs200 &mmc2_iodelay_hs200_rev20_conf>;
    };
    ====


    I also share updates from the customer.
    They mounted as expected, when modified to work in low speed mode as described below.

    ====
    &mmc2 {
        pinctrl-names = "default", "hs";
        pinctrl-0 = <&mmc2_pins_std>;
        pinctrl-1 = <&mmc2_pins_hs>;
        pinctrl-2 = <&mmc2_pins_ddr &mmc2_iodelay_ddr_rev20_conf>;
        pinctrl-3 = <&mmc2_pins_hs200 &mmc2_iodelay_hs200_rev20_conf>;
    };
    ====

    From this result we suspect that there is a problem with the IODELAY settings. They are currently using the value specified in the PINMUX Tool.
    Do we need to change this value depending on the eMMC device used?
    If it needs to be changed, how should this value be determined?


    Best Regards,
    H.U

  • Hi H.U,

    IODELAY values depends on speed mode. They are not dependent on the part.
    The dts you attached is very confusing. Why is mmc2 node defined in so many places?

    With the change what mode does the eMMC enumerate. You can see this in boot log.
    With this change you are removing the pinctrl values for ddr50 mode and hs200 mode. But these modes are not disabled.


    Regards,
    Vishal


  • Hi Vishal,

    It does not make sense that the mmc2 node is described in multiple parts. I think we are unfamiliar with Linux development.
    Also, I am terribly sorry about the amateurish question. I am terribly sorry about the amateurish question.

    Best Regards,
    H.U

  • Hi H.U,

    In the dts file you attached, mmc2 node is extended in multiple places, 
    Line # 3563
    Line # 4137

    Could you share the new log after the modifications that made it work?

    Regards,
    Vishal

  • Hi H.U,

    Any more questions on this topic or we can close this?

    Regards,
    Vishal

  • Hi Vishal,

    Since the 3.3V IO voltage was used, there may have been a deviation from the eMMC register settings.
    After reviewing the register settings, it worked.
    Thank you very much for your help.

    Best Regards,
    H.U