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.

CCS/TDA2EX17EVM: SD card probability is not recognized, and remove SD card Probability node still exists

Part Number: TDA2EX17EVM


Tool/software: Code Composer Studio

we have check the irq,it‘s ok。 Some times it will failed in some cmd, like get_cid or sd card in busy state. but replug the sd card, it will be ok。

And sometimes we eject sd card while recording video, it will block at cmd25 WRITE_MULTIPLE_BLOCK 。 In this time the node /dev/mmcblk1p1 still existence

so we use vsdk2.8 kernel omap_hsmmc.c instead it, cmd25 error has been solved , but SD card probability is not recognized, it failed in initial stage。

  • Hi,

    Is the issue "SD card not recognized some times"?

    Could you share the kernel log of the issue and MMC part of kernel dts?

    Regards,
    Vishal

  • As we test, cmd err is randomness,sometime in  get cid, sometime in mmc_app_send_scr  sometimes  in mmc_send_app_op_cond(host, 0, &ocr); sometime sd log say ( [ 697.503728] mmc1: card never left busy state )

  • mmc1: mmc@4809c000 {
    compatible = "ti,dra7-hsmmc", "ti,omap4-hsmmc";
    reg = <0x4809c000 0x400>;
    interrupts = <GIC_SPI 78 IRQ_TYPE_LEVEL_HIGH>;
    ti,hwmods = "mmc1";
    ti,dual-volt;
    ti,needs-special-reset;
    dmas = <&sdma_xbar 61>, <&sdma_xbar 62>;
    dma-names = "tx", "rx";
    status = "okay";

    pinctrl-names = "default", "hs";
    pinctrl-0 = <&mmc1_pins_default>;
    pinctrl-1 = <&mmc1_pins_hs>;

    max-frequency = <192000000>;

    pbias-supply = <&pbias_mmc_reg>;
    vmmc-supply = <&ofilm_evm_3v3_sd>;
    vmmc_aux-supply = <&vpo_sd_1v8_3v3>;
    bus-width = <4>;
    cd-gpios = <&gpio6 27 GPIO_ACTIVE_LOW>;
    sd-uhs-sdr104;
    sd-uhs-sdr50;
    sd-uhs-ddr50;
    sd-uhs-sdr25;
    sd-uhs-sdr12;
    };

  • Hi,

    I am still not clear on what issue you are facing. Is the issue related to SD card insertion/removal not detected properly?

    Is the cd-gpio correct for your board? (gpio6 pin 27)

    Regards,
    Vishal

  • the problem is that:   as sd card inserted, the detect gpio-irq can work well,   but in initialization phase, some  cmd failed, it is randomness.

  • Ok, thanks for the calrification.

    Are there certain types of SD card that show this behavior?
    Could you share a full kernel log of the issue?

  • any card, it independs of  size(8g 16g 32g 64g) and brands(kingston sandisk ) 

    kernel  log: 

    [    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.84+ (fan@fan-kylin) (gcc version 5.3.1 20160113 (Linaro GCC 5.3-2016.02) ) #64 SMP PREEMPT Fri May 29 12:14:09 CST 2020
    [    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 DRA718 EVM
    [    0.000000] Reserved memory: created DMA memory pool at 0x0000000040300000, size 3 MiB
    [    0.000000] Reserved memory: initialized node cmem@40300000, compatible id shared-dma-pool
    [    0.000000] Reserved memory: regions without no-map are not yet supported
    [    0.000000] Reserved memory: created CMA memory pool at 0x0000000099000000, size 32 MiB
    [    0.000000] Reserved memory: initialized node ipu2_cma@95800000, compatible id shared-dma-pool
    [    0.000000] Reserved memory: created CMA memory pool at 0x000000009b000000, size 16 MiB
    [    0.000000] Reserved memory: initialized node dsp1_cma@99000000, compatible id shared-dma-pool
    [    0.000000] Reserved memory: created CMA memory pool at 0x000000009c000000, size 8 MiB
    [    0.000000] Reserved memory: initialized node ipu1_cma@9d000000, compatible id shared-dma-pool
    [    0.000000] Reserved memory: created DMA memory pool at 0x000000009d200000, size 32 MiB
    [    0.000000] Reserved memory: initialized node cmem@9d200000, compatible id shared-dma-pool
    [    0.000000] Reserved memory: regions without no-map are not yet supported
    [    0.000000] cma: Reserved 8 MiB at 0x000000009c800000
    [    0.000000] Forcing write-allocate cache policy for SMP
    [    0.000000] Memory policy: Data cache writealloc
    [    0.000000] OMAP4: Map 0x000000009fd00000 to fe600000 for dram barrier
    [    0.000000] On node 0 totalpages: 122112
    [    0.000000] free_area_init_node: node 0, pgdat c076b440, node_mem_map df651000
    [    0.000000]   DMA zone: 1152 pages used for memmap
    [    0.000000]   DMA zone: 0 pages reserved
    [    0.000000]   DMA zone: 122112 pages, LIFO batch:31
    [    0.000000] DRA722 ES2.1
    [    0.000000] PERCPU: Embedded 11 pages/cpu @df5e6000 s14784 r8192 d22080 u45056
    [    0.000000] pcpu-alloc: s14784 r8192 d22080 u45056 alloc=11*4096
    [    0.000000] pcpu-alloc: [0] 0
    [    0.000000] Built 1 zonelists in Zone order, mobility grouping on.  Total pages: 120960
    [    0.000000] Kernel command line: console=ttyS0,115200n8 root=/dev/mmcblk0p2 rootwait mem=512M quiet fixrtc omapdrm.num_crtc=2 rootfstype=ext4 snd.slots_reserved=1,1 loglevel=0 clk_ignore_unused
    [    0.000000] PID hash table entries: 2048 (order: 1, 8192 bytes)
    [    0.000000] Dentry cache hash table entries: 65536 (order: 6, 262144 bytes)
    [    0.000000] Inode-cache hash table entries: 32768 (order: 5, 131072 bytes)
    [    0.000000] Memory: 194560K/488448K available (5095K kernel code, 254K rwdata, 1920K rodata, 304K init, 255K bss, 228352K reserved, 65536K cma-reserved, 0K highmem)
    [    0.000000] Virtual kernel memory layout:
    [    0.000000]     vector  : 0xffff0000 - 0xffff1000   (   4 kB)
    [    0.000000]     fixmap  : 0xffc00000 - 0xfff00000   (3072 kB)
    [    0.000000]     vmalloc : 0xe0800000 - 0xff800000   ( 496 MB)
    [    0.000000]     lowmem  : 0xc0000000 - 0xe0000000   ( 512 MB)
    [    0.000000]     pkmap   : 0xbfe00000 - 0xc0000000   (   2 MB)
    [    0.000000]     modules : 0xbf000000 - 0xbfe00000   (  14 MB)
    [    0.000000]       .text : 0xc0008000 - 0xc06e1fe4   (7016 kB)
    [    0.000000]       .init : 0xc06e2000 - 0xc072e000   ( 304 kB)
    [    0.000000]       .data : 0xc072e000 - 0xc076dbc0   ( 255 kB)
    [    0.000000]        .bss : 0xc076f000 - 0xc07aee74   ( 256 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]  RCU restricting CPUs from NR_CPUS=2 to nr_cpu_ids=1.
    [    0.000000] RCU: Adjusting geometry for rcu_fanout_leaf=32, nr_cpu_ids=1
    [    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 31475 Hz
    [    0.000000] Architected cp15 timer(s) running at 5.90MHz (phys).
    [    0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x15c70fb29, max_idle_ns: 440795202138 ns
    [    0.000004] sched_clock: 56 bits at 5MHz, resolution 169ns, wraps every 4398046511093ns
    [    0.000015] Switching to timer-based delay loop, resolution 169ns
    [    0.000338] clocksource: 32k_counter: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 58327039986419 ns
    [    0.000346] OMAP clocksource: 32k_counter at 32768 Hz
    [    0.000774] Console: colour dummy device 80x30
    [    0.000794] Calibrating delay loop (skipped), value calculated using timer frequency.. 11.80 BogoMIPS (lpj=59016)
    [    0.000807] pid_max: default: 32768 minimum: 301
    [    0.000908] Mount-cache hash table entries: 1024 (order: 0, 4096 bytes)
    [    0.000917] Mountpoint-cache hash table entries: 1024 (order: 0, 4096 bytes)
    [    0.001427] Initializing cgroup subsys io
    [    0.001444] Initializing cgroup subsys memory
    [    0.001467] Initializing cgroup subsys devices
    [    0.001479] Initializing cgroup subsys freezer
    [    0.001490] Initializing cgroup subsys perf_event
    [    0.001501] Initializing cgroup subsys pids
    [    0.001528] CPU: Testing write buffer coherency: ok
    [    0.001726] /cpus/cpu@0 missing clock-frequency property
    [    0.001739] CPU0: thread -1, cpu 0, socket 0, mpidr 80000000
    [    0.001774] Setting up static identity map for 0x800082c0 - 0x80008320
    [    0.050017] Brought up 1 CPUs
    [    0.050027] SMP: Total of 1 processors activated (11.80 BogoMIPS).
    [    0.050034] CPU: All CPU(s) started in HYP mode.
    [    0.050039] CPU: Virtualization extensions available.
    [    0.050324] devtmpfs: initialized
    [    0.080049] VFP support v0.3: implementor 41 architecture 4 part 30 variant f rev 0
    [    0.081035] omap_hwmod: l3_main_2 using broken dt data from ocp
    [    0.260545] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns
    [    0.260568] futex hash table entries: 256 (order: 2, 16384 bytes)
    [    0.271525] pinctrl core: initialized pinctrl subsystem
    [    0.272356] NET: Registered protocol family 16
    [    0.273323] DMA: preallocated 256 KiB pool for atomic coherent allocations
    [    0.300179] cpuidle: using governor ladder
    [    0.330202] cpuidle: using governor menu
    [    0.338986] OMAP GPIO hardware version 0.1
    [    0.345514] irq: no irq domain found for /ocp/l4@4a000000/scm@2000/pinmux@1400 !
    [    0.366448] hw-breakpoint: found 5 (+1 reserved) breakpoint and 4 watchpoint registers.
    [    0.366459] hw-breakpoint: maximum watchpoint size is 8 bytes.
    [    0.366875] omap4_sram_init:Unable to allocate sram needed to handle errata I688
    [    0.366885] omap4_sram_init:Unable to get sram pool needed to handle errata I688
    [    0.367475] OMAP DMA hardware revision 0.0
    [    0.451095] omap-dma-engine 4a056000.dma-controller: OMAP DMA engine driver (LinkedList1/2/3 supported)
    [    0.452240] edma 43300000.edma: memcpy is disabled
    [    0.457050] edma 43300000.edma: TI EDMA DMA engine driver
    [    0.461757] omap-iommu 40d01000.mmu: 40d01000.mmu registered
    [    0.461944] omap-iommu 40d02000.mmu: 40d02000.mmu registered
    [    0.462108] omap-iommu 58882000.mmu: 58882000.mmu registered
    [    0.462274] omap-iommu 55082000.mmu: 55082000.mmu registered
    [    0.481129] pcf857x: probe of 0-0020 failed with error -121
    [    0.481398] pcf857x: probe of 0-0021 failed with error -121
    [    0.481776] omap_i2c 48070000.i2c: bus 0 rev0.12 at 400 kHz
    [    0.482225] omap_i2c 48072000.i2c: bus 1 rev0.12 at 400 kHz
    [    0.482632] omap_i2c 48060000.i2c: bus 2 rev0.12 at 400 kHz
    [    0.501222] pcf857x: probe of 4-0026 failed with error -121
    [    0.501620] pca953x 4-0020: failed reading register
    [    0.501638] pca953x: probe of 4-0020 failed with error -121
    [    0.501794] omap_i2c 4807c000.i2c: bus 4 rev0.12 at 100 kHz
    [    0.501957] media: Linux media interface: v0.10
    [    0.502010] Linux video capture interface: v2.00
    [    0.502051] pps_core: LinuxPPS API ver. 1 registered
    [    0.502058] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>
    [    0.502082] PTP clock support registered
    [    0.502669] omap-mailbox 48840000.mailbox: omap mailbox rev 0x400
    [    0.502873] omap-mailbox 48842000.mailbox: omap mailbox rev 0x400
    [    0.503795] clocksource: Switched to clocksource arch_sys_counter
    [    0.513545] NET: Registered protocol family 2
    [    0.514056] TCP established hash table entries: 4096 (order: 2, 16384 bytes)
    [    0.514094] TCP bind hash table entries: 4096 (order: 3, 32768 bytes)
    [    0.514160] TCP: Hash tables configured (established 4096 bind 4096)
    [    0.514199] UDP hash table entries: 256 (order: 1, 8192 bytes)
    [    0.514219] UDP-Lite hash table entries: 256 (order: 1, 8192 bytes)
    [    0.514349] NET: Registered protocol family 1
    [    0.514389] PCI: CLS 0 bytes, default 64
    [    0.515335] hw perfevents: enabled with armv7_cortex_a15 PMU driver, 7 counters available
    [    0.535192] ntfs: driver 2.1.32 [Flags: R/O].
    [    0.536798] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 248)
    [    0.536817] io scheduler noop registered
    [    0.536831] io scheduler deadline registered
    [    0.536865] io scheduler cfq registered (default)
    [    0.540933] pinctrl-single 4a003400.pinmux: 282 pins at pa fc003400 size 1128
    [    0.541063] pinctrl-single 4a002e8c.pinmux: 1 pins at pa fc002e8c size 4
    [    0.544180] PCI host bridge /ocp/axi@0/pcie_rc@51000000 ranges:
    [    0.544193]   No bus range found for /ocp/axi@0/pcie_rc@51000000, using [bus 00-ff]
    [    0.544228]    IO 0x20003000..0x20012fff -> 0x00000000
    [    0.544249]   MEM 0x20013000..0x2fffffff -> 0x20013000
    [    0.573636] dra7-pcie 51000000.pcie_rc: link is not up
    [    0.573861] dra7-pcie 51000000.pcie_rc: PCI host bridge to bus 0000:00
    [    0.573875] pci_bus 0000:00: root bus resource [bus 00-ff]
    [    0.573887] pci_bus 0000:00: root bus resource [io  0x0000-0xffff]
    [    0.573897] pci_bus 0000:00: root bus resource [mem 0x20013000-0x2fffffff]
    [    0.573936] pci 0000:00:00.0: [104c:8888] type 01 class 0x060400
    [    0.573980] pci 0000:00:00.0: reg 0x10: [mem 0x00000000-0x000fffff]
    [    0.574004] pci 0000:00:00.0: reg 0x14: [mem 0x00000000-0x0000ffff]
    [    0.574072] pci 0000:00:00.0: supports D1
    [    0.574082] pci 0000:00:00.0: PME# supported from D0 D1 D3hot
    [    0.574316] PCI: bus0: Fast back to back transfers disabled
    [    0.574441] PCI: bus1: Fast back to back transfers enabled
    [    0.574531] pci 0000:00:00.0: BAR 0: assigned [mem 0x20100000-0x201fffff]
    [    0.574546] pci 0000:00:00.0: BAR 1: assigned [mem 0x20020000-0x2002ffff]
    [    0.574559] pci 0000:00:00.0: PCI bridge to [bus 01]
    [    0.574784] pcieport 0000:00:00.0: Signaling PME through PCIe PME interrupt
    [    0.574797] pcie_pme 0000:00:00.0:pcie01: service driver pcie_pme loaded
    [    0.574921] aer 0000:00:00.0:pcie02: service driver aer loaded
    [    0.629577] Serial: 8250/16550 driver, 10 ports, IRQ sharing disabled
    [    0.632862] console [ttyS0] disabled
    [    0.632916] 4806a000.serial: ttyS0 at MMIO 0x4806a000 (irq = 301, base_baud = 3000000) is a 8250
    [    0.633287] console [ttyS0] enabled
    [    0.634227] 48020000.serial: ttyS2 at MMIO 0x48020000 (irq = 302, base_baud = 3000000) is a 8250
    [    0.635346] [drm] Initialized drm 1.1.0 20060810
    [    0.636216] [drm] Supports vblank timestamp caching Rev 2 (21.10.2013).
    [    0.636226] [drm] No driver support for vblank timestamp query.
    [    0.636413] [drm] Initialized vdrm 1.0.0 20110917 on minor 0
    [    0.723498] loop: module loaded
    [    0.724003] [camera_vsync_probe:66] [camera_vsync_probe:80] [camera_vsync_hrtimer_start:46]
    [    0.724297] lp873x 0-0060: Failed to read OTP ID
    [    0.724372] lp873x: probe of 0-0060 failed with error -121
    [    0.724513] lp873x 0-0061: Failed to read OTP ID
    [    0.724575] lp873x: probe of 0-0061 failed with error -121
    [    0.724800] vmemexp device MAJOR num = 246
    [    0.724825] vmemexp class registered
    [    0.724951] /dev/vmemexp device registered
    [    0.724958] ioctl DBUFIOC_EXPORT_VIRTMEM = -1072899120
    [    0.727011] libphy: Fixed MDIO Bus: probed
    [    0.728993] i2c /dev entries driver
    [    0.734557] vsys_3v3: supplied by evm_12v0
    [    0.734611] evm_3v3: supplied by vsys_3v3
    [    0.774543] evm_3v3_sd: supplied by evm_3v3
    [    0.815029] NET: Registered protocol family 17
    [    0.815163] omap_voltage_late_init: Voltage driver support not added
    [    0.815682] Adding alias for supply vdd,cpu0 -> vdd,4a003b20.oppdm
    [    0.815692] Adding alias for supply vbb,cpu0 -> vbb,4a003b20.oppdm
    [    0.815714] cpu0 supply vdd not found, using dummy regulator
    [    0.815959] Adding alias for supply vdd,cpu0 -> vdd,4a003b20.oppdm
    [    0.815968] Adding alias for supply vbb,cpu0 -> vbb,4a003b20.oppdm
    [    0.815986] cpu0 supply vdd not found, using dummy regulator
    [    0.816801] Power Management for TI OMAP4+ devices.
    [    0.817030] Registering SWP/SWPB emulation handler
    [    0.818082] dmm 4e000000.dmm: workaround for errata i878 in use
    [    0.819607] dmm 4e000000.dmm: initialized all PAT entries
    [    0.820584] [drm] Initialized omapdrm 1.0.0 20110917 on minor 1
    [    0.853727] aic_dvdd: disabling
    [    0.853743] vmmcwl_fixed: disabling
    [    0.853766] clk: Not disabling unused clocks
    [    0.854762] Waiting for root device /dev/mmcblk0p2...
    [    0.943806] omap_hsmmc 480b4000.mmc: timeout : i/o low 0x1f00000
    [    0.963941] omap_hsmmc 480b4000.mmc: timeout : i/o low 0x1f00000
    [    1.007325] mmc0: MAN_BKOPS_EN bit is not set
    [    1.011962] omap_hsmmc 480b4000.mmc: no pinctrl state for hs200 mode
    [    1.012281] omap_hsmmc 480b4000.mmc: no pinctrl state for hs200 mode
    [    1.042389] mmc0: new HS200 MMC card at address 0001
    [    1.052880] mmcblk0: mmc0:0001 IS004G 3.64 GiB
    [    1.063126] mmcblk0boot0: mmc0:0001 IS004G partition 1 2.00 MiB
    [    1.073365] mmcblk0boot1: mmc0:0001 IS004G partition 2 2.00 MiB
    [    1.075093]  mmcblk0: p1 p2 p3 p4 < p5 p6 >
    [    1.185186] EXT4-fs (mmcblk0p2): INFO: recovery required on readonly filesystem
    [    1.185197] EXT4-fs (mmcblk0p2): write access will be enabled during recovery
    [    1.243897] omap_hsmmc 4809c000.mmc: timeout : i/o low 0x1f40000
    [    1.263795] omap_hsmmc 4809c000.mmc: timeout : i/o low 0x1f40000
    [    1.351212] mmc1: host does not support reading read-only switch, assuming write-enable
    [    1.353136] mmc1: Problem switching card into high-speed mode!
    [    1.353205] mmc1: new SDHC card at address 0001
    [    1.363684] mmcblk1: mmc1:0001 SD16G 14.6 GiB
    [    1.368371]  mmcblk1: p1 p2 p3 p4 < p5 p6 >
    [    1.474343] EXT4-fs (mmcblk0p2): recovery complete
    [    1.475868] EXT4-fs (mmcblk0p2): mounted filesystem with ordered data mode. Opts: (null)
    [    1.475909] VFS: Mounted root (ext4 filesystem) readonly on device 179:2.
    [    1.477211] devtmpfs: mounted
    [    1.477413] Freeing unused kernel memory: 304K
    [    1.477421] This architecture does not have kernel memory protection.
    [    1.540054] systemd[1]: System time before build time, advancing clock.
    [    1.553260] random: systemd: uninitialized urandom read (16 bytes read, 13 bits of entropy available)
    [    1.556179] random: systemd: uninitialized urandom read (16 bytes read, 13 bits of entropy available)
    [    1.558531] 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)
    [    1.558957] systemd[1]: Detected architecture arm.
    [    1.559661] systemd[1]: Set hostname to <dra7xx-evm>.
    [    1.597931] random: systemd-gpt-aut: uninitialized urandom read (16 bytes read, 15 bits of entropy available)
    [    1.598108] random: systemd-gpt-aut: uninitialized urandom read (16 bytes read, 15 bits of entropy available)
    [    1.602496] random: systemd-sysv-ge: uninitialized urandom read (16 bytes read, 15 bits of entropy available)
    [    1.611628] random: systemd-sysv-ge: uninitialized urandom read (16 bytes read, 16 bits of entropy available)
    [    1.652172] random: systemd: uninitialized urandom read (16 bytes read, 21 bits of entropy available)
    [    1.652776] random: systemd: uninitialized urandom read (16 bytes read, 21 bits of entropy available)
    [    1.653086] random: systemd: uninitialized urandom read (16 bytes read, 21 bits of entropy available)
    [    1.653647] random: systemd: uninitialized urandom read (16 bytes read, 21 bits of entropy available)
    [    1.780028] systemd[1]: sysinit.target: Found ordering cycle on sysinit.target/start
    [    1.780049] systemd[1]: sysinit.target: Found dependency on alignment.service/start
    [    1.780066] systemd[1]: sysinit.target: Found dependency on basic.target/start
    [    1.780080] systemd[1]: sysinit.target: Found dependency on sockets.target/start
    [    1.780092] systemd[1]: sysinit.target: Found dependency on dropbear.socket/start
    [    1.780105] systemd[1]: sysinit.target: Found dependency on sysinit.target/start
    [    1.780117] systemd[1]: sysinit.target: Breaking ordering cycle by deleting job alignment.service/start
    [    1.780131] systemd[1]: alignment.service: Job alignment.service/start deleted to break ordering cycle starting with sysinit.target/start
    [    1.782334] systemd[1]: Listening on Syslog Socket.
    [    1.782398] systemd[1]: Reached target Swap.
    [    1.784667] systemd[1]: Created slice User and Session Slice.
    [    1.784860] systemd[1]: Listening on udev Control Socket.
    [    1.785096] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.
    [    1.785821] systemd[1]: Created slice System Slice.
    [    1.786591] systemd[1]: Created slice system-getty.slice.
    [    1.786656] systemd[1]: Reached target Slices.
    [    1.786829] systemd[1]: Listening on /dev/initctl Compatibility Named Pipe.
    [    1.787035] systemd[1]: Started Forward Password Requests to Wall Directory Watch.
    [    1.787092] systemd[1]: Reached target Paths.
    [    1.787208] systemd[1]: Listening on udev Kernel Socket.
    [    1.787380] systemd[1]: Listening on Journal Socket.
    [    1.789555] systemd[1]: Starting File System Check on Root Device...
    [    1.792204] systemd[1]: Mounting Debug File System...
    [    1.796162] systemd[1]: Mounting Temporary Directory...
    [    1.798551] systemd[1]: Starting Setup Virtual Console...
    [    1.801087] systemd[1]: Starting Create list of required static device nodes for the current kernel...
    [    1.803425] systemd[1]: Starting AVM starting...
    [    1.803654] systemd[1]: Listening on Journal Socket (/dev/log).
    [    1.888708] systemd[1]: Starting USB net init...
    [    1.895935] systemd[1]: Reached target Remote File Systems.
    [    1.908274] systemd[1]: Starting Apply Kernel Variables...
    [    1.909160] systemd[1]: Created slice system-serial\x2dgetty.slice.
    [    1.911627] systemd[1]: Mounting POSIX Message Queue File System...
    [    1.911974] systemd[1]: Listening on Network Service Netlink Socket.
    [    1.999954] systemd[1]: Starting Journal Service...
    [    2.033331] systemd[1]: Mounted Debug File System.
    [    2.042712] systemd[1]: Mounted POSIX Message Queue File System.
    [    2.042856] systemd[1]: Mounted Temporary Directory.
    [    2.046566] systemd[1]: Started File System Check on Root Device.
    [    2.048844] systemd[1]: Started Setup Virtual Console.
    [    2.050181] systemd[1]: Started Create list of required static device nodes for the current kernel.
    [    2.051522] systemd[1]: Started Apply Kernel Variables.
    [    2.077842] NET: Registered protocol family 41
    [    2.107869] omap-rproc 58820000.ipu: assigned reserved memory node ipu1_cma@9d000000
    [    2.107929]  remoteproc0: 58820000.ipu is available
    [    2.107939]  remoteproc0: Note: remoteproc is still under development and considered experimental.
    [    2.107947]  remoteproc0: THE BINARY FORMAT IS NOT YET FINALIZED, and backward compatibility isn't yet guaranteed.
    [    2.109350]  remoteproc0: Direct firmware load for dra7-ipu1-fw.xem4 failed with error -2
    [    2.109365]  remoteproc0: failed to load dra7-ipu1-fw.xem4
    [    2.109513] omap-rproc 55020000.ipu: assigned reserved memory node ipu2_cma@95800000
    [    2.109565]  remoteproc1: 55020000.ipu is available
    [    2.109573]  remoteproc1: Note: remoteproc is still under development and considered experimental.
    [    2.109581]  remoteproc1: THE BINARY FORMAT IS NOT YET FINALIZED, and backward compatibility isn't yet guaranteed.
    [    2.187379] systemd[1]: Started Journal Service.
    [    2.267608] [drm] Initialized pvr 1.14.3699939 20110701 on minor 2
    [    2.270085]  remoteproc1: powering up 55020000.ipu
    [    2.270103]  remoteproc1: Booting fw image dra7-ipu2-fw.xem4, size 7922128
    [    2.270183] omap-iommu 55082000.mmu: 55082000.mmu: version 2.1
    [    2.311249] PVR_K: UM DDK-(3699939) and KM DDK-(3699939) match. [ OK ]
    [    2.350362]  remoteproc1: remote processor 55020000.ipu is now up
    [    2.350763] virtio_rpmsg_bus virtio0: rpmsg host is online
    [    2.361611]  remoteproc1: registered virtio0 (type 7)
    [    2.406193] virtio_rpmsg_bus virtio0: creating channel rpmsg-proto addr 0x50
    [    2.462340] EXT4-fs (mmcblk0p5): recovery complete
    [    2.462658] EXT4-fs (mmcblk0p5): mounted filesystem with ordered data mode. Opts: (null)
    [    2.504825] EXT4-fs (mmcblk0p6): recovery complete
    [    2.505399] EXT4-fs (mmcblk0p6): mounted filesystem with ordered data mode. Opts: (null)
    [    2.559119] tfat: module license 'Commercial. For support email exfat-support@tuxera.com' taints kernel.
    [    2.559133] Disabling lock debugging due to kernel taint
    [    2.561779] Tuxera FAT 12/16/32 driver version 3017.7.24.36 [Flags: R/W MODULE].
    [    2.561793] Built against headers 4.4.84+ #1 SMP PREEMPT Tue Apr 28 11:09:02 CST 2020 arm
    [    2.561801] Running on kernel 4.4.84+ #64 SMP PREEMPT Fri May 29 12:14:09 CST 2020 armv7l
    [    2.739427] EXT4-fs (mmcblk0p2): re-mounted. Opts: (null)
    [    2.831940] DMA: Module install successful, device major num = 243
    [    2.831952] DRV: Module install successful
    [    2.855586] CMEMK module: reference Linux version 4.4.84
    [    2.857120] cmemk initialized
    [    3.433947] systemd-journald[107]: Received request to flush runtime journal from PID 1
    [    4.055079] omap_l3_noc 44000000.ocp: L3 application error: target 3 mod:2 (unclearable)
    [    4.055102] omap_l3_noc 44000000.ocp: L3 debug error: target 3 mod:2 (unclearable)
    [    5.243606] omap_rng 48090000.rng: OMAP Random Number Generator ver. 20
    [    5.267937] omap-des 480a5000.des: OMAP DES hw accel rev: 2.2
    [    6.645368] omap-sham 4b101000.sham: hw accel on OMAP rev 4.3
    [    6.767424] omap-aes 4b500000.aes: OMAP AES hw accel rev: 3.3
    [    6.814133] omap-aes 4b700000.aes: OMAP AES hw accel rev: 3.3
    [   10.572017] tfat: fail_safe is enabled
    [   10.573507] tfat: cluster_heap_lbo 0x402000
    [   10.573518] tfat: 12 blkbits for normal inodes
    [   10.573526] tfat: fat start lbo 0x4000
    [   10.573533] tfat: 12 blkbits for main FAT32 inode
    [   10.573539] tfat: cluster size 4096
    [   10.765353] tfat info: FAT32 volume name 'boot', version 0.0.
    [   10.805124] tfat info: Exiting online fsck with status 5: aborted
    [   11.211868] tfat: fail_safe is enabled
    [   11.212453] tfat: cluster_heap_lbo 0x402000
    [   11.212462] tfat: 12 blkbits for normal inodes
    [   11.212470] tfat: fat start lbo 0x4000
    [   11.212477] tfat: 12 blkbits for main FAT32 inode
    [   11.212483] tfat: cluster size 4096
    [   11.401898] tfat info: FAT32 volume name 'boot', version 0.0.
    [   11.456605] tfat info: Exiting online fsck with status 4: completed successfully
    [   15.230763] random: nonblocking pool is initialized
    

  • The log you shared is working log or failing log?

  • I see some new failures in the log, I need to find the meaning of these. 

    [    0.943806] omap_hsmmc 480b4000.mmc: timeout : i/o low 0x1f00000
    [    0.963941] omap_hsmmc 480b4000.mmc: timeout : i/o low 0x1f00000
    .
    .
    .
    [    1.243897] omap_hsmmc 4809c000.mmc: timeout : i/o low 0x1f40000
    [    1.263795] omap_hsmmc 4809c000.mmc: timeout : i/o low 0x1f40000
  • Are there any additional patches you have added on omap_hsmmc driver on top of TI SDK?
    I don't see this log in our source code.