MMWCAS-DSP-EVM: The board fails to boot up

Hi,

Could you please check visually that the DSP board looks OK?

Could you please send us the entire log?

Thank you

cesar

https://e2e.ti.com/support/sensors-group/sensors/f/sensors-forum/881793/tidep-01012-device-2-spi-connection-failed

Hi Cesar,

Visually the boards look fine; they are always handled with great care. Here's the full log:

U-Boot SPL 2016.05-00018-g6bc25a5 (Aug 05 2019 - 14:43:03)
DRA752-GP ES2.0
no pinctrl for hs200_1_8v
no pinctrl for ddr_1_8v
Card did not respond to voltage select!
*** Warning - MMC init failed, using default environment

Trying to boot from MMC1
reading dra7-ipu2-fw.lzop
spl_load_file_fat: error reading file dra7-ipu2-fw.lzop, err - -1
spl: error reading image dra7-ipu2-fw.lzop, err - -1
Error loading remotecore IPU2!,Continuing with boot ...
reading dra7-dsp1-fw.lzop
spl_load_file_fat: error reading file dra7-dsp1-fw.lzop, err - -1
spl: error reading image dra7-dsp1-fw.lzop, err - -1
Error loading remotecore DSP1!,Continuing with boot ...
reading dra7-dsp2-fw.lzop
spl_load_file_fat: error reading file dra7-dsp2-fw.lzop, err - -1
spl: error reading image dra7-dsp2-fw.lzop, err - -1
Error loading remotecore DSP2!,Continuing with boot ...
reading dra7-ipu1-fw.lzop
spl_load_file_fat: error reading file dra7-ipu1-fw.lzop, err - -1
spl: error reading image dra7-ipu1-fw.lzop, err - -1
Error loading remotecore IPU1!,Continuing with boot ...
spl: falcon_args_file not set in environment, falling back to default
reading single-stage-boot.dtb
spl_load_image_fat_os: error reading image single-stage-boot.dtb, err - -1
reading u-boot.img
reading u-boot.img
reading u-boot.img
reading u-boot.img


U-Boot 2016.05-00018-g6bc25a5 (Aug 05 2019 - 14:43:03 +0530)

CPU  : DRA752-GP ES2.0
Model: TI DRA742
Board: DRA74x Cascade EVM REV H.4
DRAM:  4 GiB
MMC:   i2c_write: error waiting for data ACK (status=0x116)
pcf8575_i2c_write_le16 i2c write failed to addr 21
OMAP SD/MMC: 0, OMAP SD/MMC: 1
Card did not respond to voltage select!
*** Warning - MMC init failed, using default environment

Card did not respond to voltage select!
ERROR: invalid mmc device

at arch/arm/cpu/armv7/omap-common/utils.c:193/mmc_get_part_size()
Warning: fastboot.userdata_size: unable to calc
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:  2  1  0 
## Error: "board_findfdt" not defined
i2c_write: error waiting for data ACK (status=0x116)
pcf8575_i2c_write_le16 i2c write failed to addr 21
i2c_write: error waiting for data ACK (status=0x116)
pcf8575_i2c_write_le16 i2c write failed to addr 21
switch to partitions #0, OK
mmc0 is current device
i2c_write: error waiting for data ACK (status=0x116)
pcf8575_i2c_write_le16 i2c write failed to addr 21
i2c_write: error waiting for data ACK (status=0x116)
pcf8575_i2c_write_le16 i2c write failed to addr 21
SD/MMC found on device 0
reading boot.scr
** Unable to read file boot.scr **
reading uEnv.txt
185 bytes read in 2 ms (89.8 KiB/s)
Loaded env from uEnv.txt
Importing environment from mmc0 ...
i2c_write: error waiting for data ACK (status=0x116)
pcf8575_i2c_write_le16 i2c write failed to addr 21
i2c_write: error waiting for data ACK (status=0x116)
pcf8575_i2c_write_le16 i2c write failed to addr 21
switch to partitions #0, OK
mmc0 is current device
i2c_write: error waiting for data ACK (status=0x116)
pcf8575_i2c_write_le16 i2c write failed to addr 21
i2c_write: error waiting for data ACK (status=0x116)
pcf8575_i2c_write_le16 i2c write failed to addr 21
SD/MMC found on device 0
3577032 bytes read in 170 ms (20.1 MiB/s)
107792 bytes read in 24 ms (4.3 MiB/s)
Booting from mmc0 ...
Kernel image @ 0x82000000 [ 0x000000 - 0x3694c8 ]
## Flattened Device Tree blob at 88000000
   Booting using the fdt blob at 0x88000000
   Loading Device Tree to 8ffe2000, end 8ffff50f ... 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.84-00041-g84382ab (x0284921@bangvideoapps02) (gcc version 5.3.1 20160113 (Linaro GCC 5.3-2016.02) ) #1 SMP PREEMPT Mon Aug 5 14:52:40 IST 2019
[    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 DRA742 Cascade Radar Board
[    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 80 MiB
[    0.000000] Reserved memory: initialized node ipu2_cma@95800000, compatible id shared-dma-pool
[    0.000000] Reserved memory: created CMA memory pool at 0x000000009e000000, size 32 MiB
[    0.000000] Reserved memory: initialized node ipu1_cma@9d000000, 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 0x00000000a1000000, size 32 MiB
[    0.000000] Reserved memory: initialized node dsp1_cma@99000000, compatible id shared-dma-pool
[    0.000000] Reserved memory: created CMA memory pool at 0x00000000a3000000, size 32 MiB
[    0.000000] Reserved memory: initialized node dsp2_cma@9f000000, compatible id shared-dma-pool
[    0.000000] Reserved memory: regions without no-map are not yet supported
[    0.000000] Reserved memory: created DMA memory pool at 0x00000000a9000000, size 64 MiB
[    0.000000] Reserved memory: initialized node cmem@A9000000, compatible id shared-dma-pool
[    0.000000] Reserved memory: regions without no-map are not yet supported
[    0.000000] cma: Reserved 64 MiB at 0x00000000bc000000
[    0.000000] Forcing write-allocate cache policy for SMP
[    0.000000] Memory policy: Data cache writealloc
[    0.000000] OMAP4: Map 0x000000027fd00000 to fe600000 for dram barrier
[    0.000000] DRA752 ES2.0
[    0.000000] PERCPU: Embedded 11 pages/cpu @f2f1e000 s14912 r8192 d21952 u45056
[    0.000000] Built 1 zonelists in Zone order, mobility grouping on.  Total pages: 1025330
[    0.000000] Kernel command line: console=ttyO0,115200n8 vram=16M root=PARTUUID=3cc717e1-02 rw rootwait ip=none cma=64M vmalloc=128M
[    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: 2439424K/4109312K available (6671K kernel code, 320K rwdata, 2396K rodata, 336K init, 290K bss, 1424128K reserved, 245760K cma-reserved, 2167808K highmem)
[    0.000000] Virtual kernel memory layout:
[    0.000000]     vector  : 0xffff0000 - 0xffff1000   (   4 kB)
[    0.000000]     fixmap  : 0xffc00000 - 0xfff00000   (3072 kB)
[    0.000000]     vmalloc : 0xf8000000 - 0xff800000   ( 120 MB)
[    0.000000]     lowmem  : 0xc0000000 - 0xf7800000   ( 888 MB)
[    0.000000]     pkmap   : 0xbfe00000 - 0xc0000000   (   2 MB)
[    0.000000]     modules : 0xbf000000 - 0xbfe00000   (  14 MB)
[    0.000000]       .text : 0xc0008000 - 0xc08e302c   (9069 kB)
[    0.000000]       .init : 0xc08e4000 - 0xc0938000   ( 336 kB)
[    0.000000]       .data : 0xc0938000 - 0xc0988220   ( 321 kB)
[    0.000000]        .bss : 0xc098a000 - 0xc09d2880   ( 291 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.000005] sched_clock: 56 bits at 6MHz, resolution 162ns, wraps every 4398046511023ns
[    0.000015] Switching to timer-based delay loop, resolution 162ns
[    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.000798] Console: colour dummy device 80x30
[    0.000814] WARNING: Your 'console=ttyO0' has been replaced by 'ttyS0'
[    0.000820] This ensures that you still see kernel messages. Please
[    0.000826] update your kernel commandline.
[    0.000839] Calibrating delay loop (skipped), value calculated using timer frequency.. 12.29 BogoMIPS (lpj=61475)
[    0.000852] pid_max: default: 32768 minimum: 301
[    0.000948] Mount-cache hash table entries: 2048 (order: 1, 8192 bytes)
[    0.000958] Mountpoint-cache hash table entries: 2048 (order: 1, 8192 bytes)
[    0.001504] Initializing cgroup subsys io
[    0.001521] Initializing cgroup subsys memory
[    0.001546] Initializing cgroup subsys devices
[    0.001558] Initializing cgroup subsys freezer
[    0.001570] Initializing cgroup subsys perf_event
[    0.001581] Initializing cgroup subsys pids
[    0.001608] CPU: Testing write buffer coherency: ok
[    0.001807] /cpus/cpu@0 missing clock-frequency property
[    0.001823] /cpus/cpu@1 missing clock-frequency property
[    0.001833] CPU0: thread -1, cpu 0, socket 0, mpidr 80000000
[    0.001868] Setting up static identity map for 0x80008380 - 0x800083e0
[    0.080053] CPU1: thread -1, cpu 1, socket 0, mpidr 80000001
[    0.080122] Brought up 2 CPUs
[    0.080135] SMP: Total of 2 processors activated (24.59 BogoMIPS).
[    0.080141] CPU: All CPU(s) started in HYP mode.
[    0.080147] CPU: Virtualization extensions available.
[    0.080524] devtmpfs: initialized
[    0.109681] VFP support v0.3: implementor 41 architecture 4 part 30 variant f rev 0
[    0.110673] omap_hwmod: l3_main_2 using broken dt data from ocp
[    0.314013] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns
[    0.314035] futex hash table entries: 512 (order: 3, 32768 bytes)
[    0.319159] pinctrl core: initialized pinctrl subsystem
[    0.320026] NET: Registered protocol family 16
[    0.320991] DMA: preallocated 256 KiB pool for atomic coherent allocations
[    0.350229] cpuidle: using governor ladder
[    0.380254] cpuidle: using governor menu
[    0.388796] OMAP GPIO hardware version 0.1
[    0.392747] irq: no irq domain found for /ocp/l4@4a000000/scm@2000/pinmux@1400 !
[    0.408215] omap-gpmc 50000000.gpmc: GPMC revision 6.0
[    0.408228] gpmc_mem_init: disabling cs 0 mapped at 0x0-0x1000000
[    0.417345] hw-breakpoint: found 5 (+1 reserved) breakpoint and 4 watchpoint registers.
[    0.417356] hw-breakpoint: maximum watchpoint size is 8 bytes.
[    0.417826] omap4_sram_init:Unable to allocate sram needed to handle errata I688
[    0.417836] omap4_sram_init:Unable to get sram pool needed to handle errata I688
[    0.418407] OMAP DMA hardware revision 0.0
[    0.461517] omap-dma-engine 4a056000.dma-controller: OMAP DMA engine driver (LinkedList1/2/3 supported)
[    0.462650] edma 43300000.edma: memcpy is disabled
[    0.467384] edma 43300000.edma: TI EDMA DMA engine driver
[    0.471441] omap-iommu 40d01000.mmu: 40d01000.mmu registered
[    0.471624] omap-iommu 40d02000.mmu: 40d02000.mmu registered
[    0.471783] omap-iommu 58882000.mmu: 58882000.mmu registered
[    0.471941] omap-iommu 55082000.mmu: 55082000.mmu registered
[    0.472217] omap-iommu 41501000.mmu: 41501000.mmu registered
[    0.472420] omap-iommu 41502000.mmu: 41502000.mmu registered
[    0.475444] palmas 0-0058: IRQ missing: skipping irq request
[    0.490890] palmas 0-0058: Muxing GPIO 2e, PWM 0, LED 0
[    0.552764] pcf857x: probe of 0-0020 failed with error -121
[    0.553015] pcf857x: probe of 0-0021 failed with error -121
[    0.553875] omap_i2c 48070000.i2c: bus 0 rev0.12 at 400 kHz
[    0.554280] omap_i2c 48060000.i2c: bus 2 rev0.12 at 400 kHz
[    0.554477] media: Linux media interface: v0.10
[    0.554529] Linux video capture interface: v2.00
[    0.554571] pps_core: LinuxPPS API ver. 1 registered
[    0.554578] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>
[    0.554606] PTP clock support registered
[    0.554656] EDAC MC: Ver: 3.0.0
[    0.555412] omap-mailbox 48840000.mailbox: omap mailbox rev 0x400
[    0.555703] omap-mailbox 48842000.mailbox: omap mailbox rev 0x400
[    0.556031] Advanced Linux Sound Architecture Driver Initialized.
[    0.556888] clocksource: Switched to clocksource arch_sys_counter
[    0.567482] NET: Registered protocol family 2
[    0.567984] TCP established hash table entries: 8192 (order: 3, 32768 bytes)
[    0.568048] TCP bind hash table entries: 8192 (order: 4, 65536 bytes)
[    0.568173] TCP: Hash tables configured (established 8192 bind 8192)
[    0.568219] UDP hash table entries: 512 (order: 2, 16384 bytes)
[    0.568251] UDP-Lite hash table entries: 512 (order: 2, 16384 bytes)
[    0.568391] NET: Registered protocol family 1
[    0.568696] RPC: Registered named UNIX socket transport module.
[    0.568705] RPC: Registered udp transport module.
[    0.568712] RPC: Registered tcp transport module.
[    0.568718] RPC: Registered tcp NFSv4.1 backchannel transport module.
[    0.569694] hw perfevents: enabled with armv7_cortex_a15 PMU driver, 7 counters available
[    0.579722] squashfs: version 4.0 (2009/01/31) Phillip Lougher
[    0.580368] NFS: Registering the id_resolver key type
[    0.580398] Key type id_resolver registered
[    0.580405] Key type id_legacy registered
[    0.580463] ntfs: driver 2.1.32 [Flags: R/O].
[    0.581734] bounce: pool size: 64 pages
[    0.581892] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247)
[    0.581907] io scheduler noop registered
[    0.581919] io scheduler deadline registered
[    0.581953] io scheduler cfq registered (default)
[    0.587464] pinctrl-single 4a003400.pinmux: 282 pins at pa fc003400 size 1128
[    0.590963] PCI host bridge /ocp/axi@0/pcie_rc@51000000 ranges:
[    0.590976]   No bus range found for /ocp/axi@0/pcie_rc@51000000, using [bus 00-ff]
[    0.591009]    IO 0x20003000..0x20012fff -> 0x00000000
[    0.591031]   MEM 0x20013000..0x2fffffff -> 0x20013000
[    0.591238] dra7-pcie 51000000.pcie_rc: CFG 'Bus Master Enable' change
[    0.591247] dra7-pcie 51000000.pcie_rc: CFG 'Memory Space Enable' change
[    0.607374] dra7-pcie 51000000.pcie_rc: PCI host bridge to bus 0000:00
[    0.607387] pci_bus 0000:00: root bus resource [bus 00-ff]
[    0.607397] pci_bus 0000:00: root bus resource [io  0x0000-0xffff]
[    0.607407] pci_bus 0000:00: root bus resource [mem 0x20013000-0x2fffffff]
[    0.607470] dra7-pcie 51000000.pcie_rc: CFG 'Memory Space Enable' change
[    0.607489] dra7-pcie 51000000.pcie_rc: CFG 'Memory Space Enable' change
[    0.607514] dra7-pcie 51000000.pcie_rc: CFG 'Memory Space Enable' change
[    0.607528] dra7-pcie 51000000.pcie_rc: CFG 'Memory Space Enable' change
[    0.607551] dra7-pcie 51000000.pcie_rc: CFG 'Memory Space Enable' change
[    0.607565] dra7-pcie 51000000.pcie_rc: CFG 'Memory Space Enable' change
[    0.607867] PCI: bus0: Fast back to back transfers disabled
[    0.626979] PCI: bus1: Fast back to back transfers disabled
[    0.627106] pci 0000:00:00.0: BAR 0: assigned [mem 0x20100000-0x201fffff]
[    0.627121] pci 0000:00:00.0: BAR 8: assigned [mem 0x20200000-0x202fffff]
[    0.627132] pci 0000:00:00.0: BAR 1: assigned [mem 0x20020000-0x2002ffff]
[    0.627147] pci 0000:01:00.0: BAR 0: assigned [mem 0x20200000-0x20203fff 64bit]
[    0.627193] pci 0000:00:00.0: PCI bridge to [bus 01]
[    0.627206] pci 0000:00:00.0:   bridge window [mem 0x20200000-0x202fffff]
[    0.627416] pcieport 0000:00:00.0: Signaling PME through PCIe PME interrupt
[    0.627426] pci 0000:01:00.0: Signaling PME through PCIe PME interrupt
[    0.688736] Serial: 8250/16550 driver, 10 ports, IRQ sharing disabled
[    0.692112] 4806a000.serial: ttyS0 at MMIO 0x4806a000 (irq = 202, base_baud = 3000000) is a 8250
[    1.792038] console [ttyS0] enabled
[    1.796426] 4806c000.serial: ttyS1 at MMIO 0x4806c000 (irq = 203, base_baud = 3000000) is a 8250
[    1.806126] 48020000.serial: ttyS2 at MMIO 0x48020000 (irq = 204, base_baud = 3000000) is a 8250
[    1.816156] [drm] Initialized drm 1.1.0 20060810
[    1.821712] [drm] Supports vblank timestamp caching Rev 2 (21.10.2013).
[    1.828403] [drm] No driver support for vblank timestamp query.
[    1.834532] [drm] Initialized vdrm 1.0.0 20110917 on minor 0
[    1.841689] OMAP DSS rev 6.1
[    1.845435] omapdss_dss 58000000.dss: bound 58001000.dispc (ops dispc_component_ops)
[    1.861075] loop: module loaded
[    1.864611] vmemexp device MAJOR num = 245
[    1.868763] vmemexp class registered
[    1.872477] /dev/vmemexp device registered
[    1.876591] ioctl DBUFIOC_EXPORT_VIRTMEM = -1072899120
[    1.882141] nvme 0000:01:00.0: enabling device (0140 -> 0142)
[    1.888563] nand: No NAND device found
[    1.892335] omap2-nand 8000000.nand: scan failed, may be bus-width mismatch
[    1.901415] m25p80 spi32766.0: found n25q00, expected s25fl256s1
[    1.907538] m25p80 spi32766.0: n25q00 (131072 Kbytes)
[    1.912670] 7 ofpart partitions found on MTD device spi32766.0
[    1.918549] Creating 7 MTD partitions on "spi32766.0":
[    1.923714] 0x000000000000-0x000000040000 : "QSPI.SPL"
[    1.939868] 0x000000040000-0x000000140000 : "QSPI.u-boot"
[    1.946155] 0x000000140000-0x0000001c0000 : "QSPI.u-boot-spl-os"
[    1.963124] 0x0000001c0000-0x0000001d0000 : "QSPI.u-boot-env"
[    1.979814] 0x0000001d0000-0x0000001e0000 : "QSPI.u-boot-env.backup1"
[    1.997247] 0x0000001e0000-0x0000009e0000 : "QSPI.kernel"
[    2.010831]  nvme0n1: p1
[    2.013671] 0x0000009e0000-0x000002000000 : "QSPI.file-system"
[    2.031560] libphy: Fixed MDIO Bus: probed
[    2.086919] davinci_mdio 48485000.mdio: davinci mdio revision 1.6
[    2.093044] libphy: 48485000.mdio: probed
[    2.099848] davinci_mdio 48485000.mdio: phy[2]: device 48485000.mdio:02, driver TI DP83867
[    2.108797] cpsw 48484000.ethernet: Detected MACID = b8:80:4f:0d:83:7a
[    2.115441] cpsw 48484000.ethernet: cpts: overflow check period 800
[    2.122421] cpsw 48484000.ethernet: cpsw: Detected MACID = b8:80:4f:0d:83:7b
[    2.131054] mousedev: PS/2 mouse device common for all mice
[    2.137257] i2c /dev entries driver
[    2.145976] omap_hsmmc 4809c000.mmc: Got CD GPIO
[    2.187554] omap_hsmmc 480b4000.mmc: no pinctrl state for sdr25 mode
[    2.193939] omap_hsmmc 480b4000.mmc: no pinctrl state for sdr12 mode
[    2.200879] evm_3v3_sw: supplied by sysen1
[    2.237762] ledtrig-cpu: registered to indicate activity on CPUs
[    2.246184] aic_dvdd: supplied by evm_3v3_sw
[    2.251470] davinci-mcasp 4847c000.mcasp: invalid tdm slots: 0
[    2.258981] NET: Registered protocol family 10
[    2.274276] sit: IPv6 over IPv4 tunneling driver
[    2.279492] NET: Registered protocol family 17
[    2.284181] Key type dns_resolver registered
[    2.288628] omap_voltage_late_init: Voltage driver support not added
[    2.295534] Adding alias for supply vdd,cpu0 -> vdd,4a003b20.oppdm
[    2.301760] Adding alias for supply vbb,cpu0 -> vbb,4a003b20.oppdm
[    2.308454] Adding alias for supply vdd,cpu0 -> vdd,4a003b20.oppdm
[    2.314661] Adding alias for supply vbb,cpu0 -> vbb,4a003b20.oppdm
[    2.321993] Power Management for TI OMAP4+ devices.
[    2.327129] Registering SWP/SWPB emulation handler
[    2.333247] dmm 4e000000.dmm: workaround for errata i878 in use
[    2.340830] dmm 4e000000.dmm: initialized all PAT entries
[    2.347257] [drm] Initialized omapdrm 1.0.0 20110917 on minor 1
[    2.355077] hctosys: unable to open rtc device (rtc0)
[    2.372200] evm_1v8: disabling
[    2.375277] aic_dvdd: disabling
[    2.378651] ldousb: disabling
[    2.382119] ALSA device list:
[    2.385100]   No soundcards found.
[    2.389417] Waiting for root device PARTUUID=3cc717e1-02...
[    2.417805] mmc0: host does not support reading read-only switch, assuming write-enable
[    2.431356] mmc0: new high speed SDHC card at address aaaa
[    2.437354] mmcblk0: mmc0:aaaa SL16G 14.8 GiB 
[    2.446430]  mmcblk0: p1 p2
[    2.500375] EXT4-fs (mmcblk0p2): couldn't mount as ext3 due to feature incompatibilities
[    2.511957] EXT4-fs (mmcblk0p2): couldn't mount as ext2 due to feature incompatibilities
[    2.636597] EXT4-fs (mmcblk0p2): recovery complete
[    2.646504] EXT4-fs (mmcblk0p2): mounted filesystem with ordered data mode. Opts: (null)
[    2.654679] VFS: Mounted root (ext4 filesystem) on device 179:2.
[    2.667014] devtmpfs: mounted
[    2.670203] Freeing unused kernel memory: 336K
[    2.674742] This architecture does not have kernel memory protection.
[    2.873820] systemd[1]: System time before build time, advancing clock.
[    2.918909] random: systemd: uninitialized urandom read (16 bytes read, 16 bits of entropy available)
[    2.934339] random: systemd: uninitialized urandom read (16 bytes read, 16 bits of entropy available)
[    2.946057] 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.964590] systemd[1]: Detected architecture arm.

Welcome to Arago 2016.12!

[    2.990702] systemd[1]: Set hostname to <dra7xx-evm>.
[    2.998033] random: systemd: uninitialized urandom read (16 bytes read, 17 bits of entropy available)
[    3.007363] systemd[1]: Initializing machine ID from random generator.
[    3.088184] random: systemd-gpt-aut: uninitialized urandom read (16 bytes read, 18 bits of entropy available)
[    3.088693] random: systemd-sysv-ge: uninitialized urandom read (16 bytes read, 18 bits of entropy available)
[    3.108388] random: systemd-gpt-aut: uninitialized urandom read (16 bytes read, 18 bits of entropy available)
[    3.122219] random: systemd-sysv-ge: uninitialized urandom read (16 bytes read, 18 bits of entropy available)
[    3.139559] random: systemd-sysv-ge: uninitialized urandom read (16 bytes read, 19 bits of entropy available)
[    3.227747] random: systemd: uninitialized urandom read (16 bytes read, 21 bits of entropy available)
[    3.237281] random: systemd: uninitialized urandom read (16 bytes read, 21 bits of entropy available)
[    3.522594] systemd[1]: Configuration file /etc/systemd/system/multi-user.target.wants/vsdk.service is marked executable. Please remove executable permission bits. Proceeding anyway.
[    3.542318] systemd[1]: [/etc/systemd/system/multi-user.target.wants/vsdk.service:3] Failed to add dependency on rc.pvr, ignoring: Invalid argument
[    3.645285] systemd[1]: sysinit.target: Found ordering cycle on sysinit.target/start
[    3.653175] systemd[1]: sysinit.target: Found dependency on alignment.service/start
[    3.660916] systemd[1]: sysinit.target: Found dependency on basic.target/start
[    3.668248] systemd[1]: sysinit.target: Found dependency on sockets.target/start
[    3.675681] systemd[1]: sysinit.target: Found dependency on dbus.socket/start
[    3.682872] systemd[1]: sysinit.target: Found dependency on sysinit.target/start
[    3.690324] systemd[1]: sysinit.target: Breaking ordering cycle by deleting job alignment.service/start
[    3.699778] systemd[1]: alignment.service: Job alignment.service/start deleted to break ordering cycle starting with sysinit.target/start
[ SKIP ] Ordering cycle found, skipping alignment.service
[    3.739336] systemd[1]: Reached target Remote File Systems.
[  OK  ] Reached target Remote File Systems.
[    3.767229] systemd[1]: Listening on /dev/initctl Compatibility Named Pipe.
[  OK  ] Listening on /dev/initctl Compatibility Named Pipe.
[    3.797346] systemd[1]: Listening on Network Service Netlink Socket.
[  OK  ] Listening on Network Service Netlink Socket.
[    3.827145] systemd[1]: Listening on udev Kernel Socket.
[  OK  ] Listening on udev Kernel Socket.
[    3.857317] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.
[  OK  ] Started Dispatch Password Requests to Console Directory Watch.
[    3.887194] systemd[1]: Listening on udev Control Socket.
[  OK  ] Listening on udev Control Socket.
[    3.917240] systemd[1]: Listening on Journal Socket.
[  OK  ] Listening on Journal Socket.
[    3.938557] systemd[1]: Created slice System Slice.
[  OK  ] Created slice System Slice.
[    3.967816] systemd[1]: Created slice system-serial\x2dgetty.slice.
[  OK  ] Created slice system-serial\x2dgetty.slice.
[    4.027160] systemd[1]: Mounting Debug File System...
         Mounting Debug File System...
[    4.049819] 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...
[    4.077950] systemd[1]: Created slice system-getty.slice.
[  OK  ] Created slice system-getty.slice.
[    4.113682] systemd[1]: Started Forward Password Requests to Wall Directory Watch.
[  OK  ] Started Forward Password Requests to Wall Directory Watch.
[    4.147197] systemd[1]: Listening on Journal Socket (/dev/log).
[  OK  ] Listening on Journal Socket (/dev/log).
[    4.207337] systemd[1]: Starting Setup Virtual Console...
         Starting Setup Virtual Console...
[    4.239523] systemd[1]: Starting Remount Root and Kernel File Systems...
         Starting Remount Root and Kernel File Systems...
[    4.259742] EXT4-fs (mmcblk0p2): re-mounted. Opts: (null)
[    4.268062] systemd[1]: Created slice User and Session Slice.
[  OK  ] Created slice User and Session Slice.
[    4.327319] systemd[1]: Starting Load Kernel Modules...
         Starting Load Kernel Modules...
[    4.350140] systemd[1]: Mounting Temporary Directory...
         Mounting Temporary Directory...
[    4.377218] systemd[1]: Reached target Slices.
[  OK  ] Reached target Slices.
[    4.397445] systemd[1]: Listening on Syslog Socket.
[  OK  ] Listening on Syslog Socket.
[    4.457299] systemd[1]: Starting Journal Service...
         Starting Journal Service...
[    4.477137] systemd[1]: Reached target Paths.
[  OK  ] Reached target Paths.
[    4.497211] systemd[1]: Reached target Swap.
[  OK  ] Reached target Swap.
[    4.547129] systemd[1]: Mounting POSIX Message Queue File System...
         Mounting POSIX Message Queue File System...
[    4.584665] systemd[1]: Mounted Debug File System.
[  OK  ] Mounted Debug File System.
[    4.607201] systemd[1]: Mounted POSIX Message Queue File System.
[  OK  ] Mounted POSIX Message Queue File System.
[    4.638071] systemd[1]: Mounted Temporary Directory.
[  OK  ] Mounted Temporary Directory.
[    4.657363] 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.
[    5.415823] systemd-journald[143]: 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.
         Starting Load/Save Random Seed...
[  OK  ] Reached target Local File Systems.
         Starting Create Volatile Files and Directories...
[  OK  ] Started Load/Save Random Seed.
[  OK  ] Started Create Volatile Files and Directories.
[    6.052081] omap-rproc 55020000.ipu: assigned reserved memory node ipu2_cma@95800000
[    6.078995]  remoteproc0: 55020000.ipu is available
[    6.083905]  remoteproc0: Note: remoteproc is still under development and considered experimental.
[    6.132270]  remoteproc0: THE BINARY FORMAT IS NOT YET FINALIZED, and backward compatibility isn't yet guaranteed.
[    6.170182] omap-rproc 40800000.dsp: assigned reserved memory node dsp1_cma@99000000
[    6.187688]  remoteproc1: 40800000.dsp is available
         Starting Network Time Synchronization...
[    6.213108]  remoteproc1: Note: remoteproc is still under development and considered experimental.
[    6.226303]  remoteproc1: THE BINARY FORMAT IS NOT YET FINALIZED, and backward compatibility isn't yet guaranteed.
[    6.254354]  remoteproc1: Direct firmware load for dra7-dsp1-fw.xe66 failed with error -2
[    6.265131] omap-rproc 41000000.dsp: assigned reserved memory node dsp2_cma@9f000000
[    6.277590]  remoteproc1: failed to load dra7-dsp1-fw.xe66
         Starting Update UTMP about System Boot/Shutdown...[    6.298658]  remoteproc2: 41000000.dsp is available

[    6.329956]  remoteproc2: Note: remoteproc is still under development and considered experimental.
[  OK  ] Started udev Coldplug all Devices.[    6.342486]  remoteproc2: THE BINARY FORMAT IS NOT YET FINALIZED, and backward compatibility isn't yet guaranteed.

[    6.401432]  remoteproc2: Direct firmware load for dra7-dsp2-fw.xe66 failed with error -2
[    6.427281]  remoteproc2: failed to load dra7-dsp2-fw.xe66
[  OK  ] Started Network Time Synchronization.
[    6.509264] omap-des 480a5000.des: OMAP DES hw accel rev: 2.2
[  OK  ] Found device /dev/ttyS0.
[    6.610331]  remoteproc0: registered virtio0 (type 7)
[    6.646382] omap_rng 48090000.rng: OMAP Random Number Generator ver. 20
[    6.679741] omap_rtc 48838000.rtc: rtc core: registered 48838000.rtc as rtc0
[  OK  ] Started Update UTMP about System Boot/Shutdown.
[  OK  ] Reached target System Time Synchronized.
         Starting Synchronize System and HW clocks...
[    6.961603] omap-sham 4b101000.sham: hw accel on OMAP rev 4.3
[    6.982397] SCSI subsystem initialized
[  OK  ] Started Synchronize System and HW clocks.
[    7.023898] omap-aes 4b500000.aes: OMAP AES hw accel rev: 3.3
[    7.049827] CAN device driver interface
[    7.069763] omap-aes 4b700000.aes: OMAP AES hw accel rev: 3.3
[  OK  ] Reached target System Initialization.
[    7.204891] c_can_platform 4ae3c000.can: c_can_platform device registered (regs=fce3c000, irq=254)
[  OK  ] Listening on dropbear.socket.
[  OK  ] Listening on D-Bus System Message Bus Socket.
[  OK  ] Reached target Sockets.
[  OK  ] Reached target Basic System.
[    7.350431] ahci 4a140000.sata: SSS flag set, parallel bus scan disabled
[    7.372250] ahci 4a140000.sata: Enable 32-bit DMA instead of 64-bit.
         Starting Permit User Sessions...
[  OK  ] Started System Logging Service.
[    7.428351] ahci 4a140000.sata: AHCI 0001.0300 32 slots 1 ports 3 Gbps 0x1 impl platform mode
[  OK  ] Started D-Bus System Message Bus.
[    7.507307] ahci 4a140000.sata: flags: 64bit ncq sntf stag pm led clo only pmp pio slum part ccc apst 
[    7.577476] scsi host0: ahci
[    7.580815] ata1: SATA max UDMA/133 mmio [mem 0x4a140000-0x4a1410ff] port 0x100 irq 240
[    7.632672] [drm] Initialized pvr 1.14.3699939 20110701 on minor 2
         Starting Login Service...
[  OK  ] Started Daily Cleanup of Temporary Directories.
[  OK  ] Reached target Timers.
[  OK  ] Started Kernel Logging Service.
[  OK  ] Started Launching Vision SDK App.
         Starting rc.pvr.service...
         Starting Network Service...
[  OK  ] Started Permit User Sessions.
[    7.958339] ata1: SATA link down (SStatus 0 SControl 300)
[    8.000394] PVR_K: UM DDK-(3699939) and KM DDK-(3699939) match. [ OK ]
[    8.063708] FAT-fs (mmcblk0p1): Volume was not properly unmounted. Some data may be corrupt. Please run fsck.
[    8.171277] net eth0: initializing cpsw version 1.15 (0)
[    8.204231] net eth0: initialized cpsw ale version 1.4
[    8.227111] EXT4-fs (nvme0n1p1): recovery complete
[    8.234478] net eth0: ALE Table size 1024
[    8.253412] EXT4-fs (nvme0n1p1): mounted filesystem with ordered data mode. Opts: (null)
[    8.275488] net eth0: phy found : id is : 0x2000a231
[  OK  ] Started rc.pvr.service.
[    8.307048] IPv6: ADDRCONF(NETDEV_UP): eth0: link is not ready
[  OK  ] Started Network Service.
[    8.714260]  remoteproc0: powering up 55020000.ipu
[    8.736496]  remoteproc0: Booting fw image dra7-ipu2-fw.xem4, size 4396172
[    8.749797] omap-iommu 55082000.mmu: 55082000.mmu: version 2.1
[    8.787683]  remoteproc0: remote processor 55020000.ipu is now up
[    8.798583] virtio_rpmsg_bus virtio0: rpmsg host is online
[    8.838161] virtio_rpmsg_bus virtio0: creating channel rpmsg-proto addr 0x50
[    8.857863] Unable to handle kernel paging request at virtual address 325ea025
[    8.865127] pgd = c0003000
[    8.867844] [325ea025] *pgd=80000080004003, *pmd=00000000
[    8.873283] Internal error: Oops: 206 [#1] PREEMPT SMP ARM
[    8.878790] Modules linked in: virtio_rpmsg_bus bc_example(O) extcon_usb_gpio pvrsrvkm(O) ahci_platform libahci_platform libahci c_can_platform libata c_can can_dev omap_aes_driver scsi_mod omap_sham dwc3_omap rtc_omap omap_rng extcon rng_core omap_des omap_remoteproc remoteproc virtio virtio_ring sch_fq_codel
[    8.906579] CPU: 1 PID: 72 Comm: mmcqd/0 Tainted: G           O    4.4.84-00041-g84382ab #1
[    8.914964] Hardware name: Generic DRA74X (Flattened Device Tree)
[    8.921082] task: f5abda00 ti: f5418000 task.ti: f5418000
[    8.926508] PC is at part_round_stats_single+0x44/0x74
[    8.931667] LR is at 0x1
[    8.934211] pc : [<c028d430>]    lr : [<00000001>]    psr: 00030193
[    8.934211] sp : f5419dc4  ip : 00000001  fp : f5419dd4
[    8.945738] r10: f63d4800  r9 : 00000000  r8 : 60030113
[    8.950983] r7 : 00000000  r6 : ffff8e46  r5 : 00000001  r4 : 325ea000
[    8.957537] r3 : 00000001  r2 : ffff8e46  r1 : f5a06c40  r0 : 325ea001
[    8.964092] Flags: nzcv  IRQs off  FIQs on  Mode SVC_32  ISA ARM  Segment kernel
[    8.971518] Control: 30c5387d  Table: b5a6a8c0  DAC: 55555555
[    8.977286] Process mmcqd/0 (pid: 72, stack limit = 0xf5418210)
[    8.983230] Stack: (0xf5419dc4 to 0xf541a000)
[    8.987605] 9dc0:          f5b07400 f5419df4 f5419dd8 c028d6d8 c028d3f8 f5b07400 00000000
[    8.995818] 9de0: 000002b4 00000000 f5419e14 f5419df8 c0290890 c028d69c f5400d98 00000000
[    9.004031] 9e00: f59cf200 00000000 f5419e2c f5419e18 c0290a64 c0290764 f5400d98 00000000
[    9.012244] 9e20: f5419e54 f5419e30 c0290b18 c02909e4 f5a06940 f5400d98 f5a06808 f5a06830
[    9.020456] 9e40: 00000000 00000000 f5419e64 f5419e58 c0290b40 c0290ae8 f5419ecc f5419e68
[    9.028669] 9e60: c04f5604 c0290b38 f5419e94 ffffe000 f63d486c 00000000 f5abda00 c0888cd0
[    9.036881] 9e80: 00000000 00000000 00000000 f5a06800 00000000 00000000 f5a06800 00000000
[    9.045093] 9ea0: ffffe000 f5a06808 f63d4800 f5b726dc f5b72400 f5a06800 ffffe000 00000000
[    9.053306] 9ec0: f5419f24 f5419ed0 c04f5b18 c04f4e18 f59cf200 f5a06810 ffffe000 00000000
[    9.061518] 9ee0: f5419f0c f5419ef0 c0291564 c02ad038 f5b72400 00000000 f59cf200 f5a06808
[    9.069732] 9f00: 00000000 f59cf200 f5418000 f5a06810 ffffe000 f5400d98 f5419f54 f5419f28
[    9.077943] 9f20: c04f74b8 c04f58c4 f5abda00 00000000 f5a6a4c0 f5418000 f5a06808 c04f73b4
[    9.086156] 9f40: 00000000 00000000 f5419fac f5419f58 c0050508 c04f73c0 00000000 00000000
[    9.094368] 9f60: 00000000 f5a06808 00000000 00000000 f5419f70 f5419f70 00000000 00000000
[    9.102580] 9f80: f5419f80 f5419f80 f5419fac f5a6a4c0 c0050408 00000000 00000000 00000000
[    9.110793] 9fa0: 00000000 f5419fb0 c000fb88 c0050414 00000000 00000000 00000000 00000000
[    9.119005] 9fc0: 00000000 00000000 00000000 00000000 00000000 00000000 00000000 00000000
[    9.127216] 9fe0: 00000000 00000000 00000000 00000000 00000013 00000000 ffffffff 00000001
[    9.135425] Backtrace: 
[    9.137893] [<c028d3ec>] (part_round_stats_single) from [<c028d6d8>] (part_round_stats+0x48/0x5c)
[    9.146801]  r4:f5b07400
[    9.149355] [<c028d690>] (part_round_stats) from [<c0290890>] (blk_account_io_done+0x138/0x280)
[    9.158088]  r7:00000000 r6:000002b4 r5:00000000 r4:f5b07400
[    9.163809] [<c0290758>] (blk_account_io_done) from [<c0290a64>] (blk_finish_request+0x8c/0x104)
[    9.172629]  r7:00000000 r6:f59cf200 r5:00000000 r4:f5400d98
[    9.178349] [<c02909d8>] (blk_finish_request) from [<c0290b18>] (blk_end_bidi_request+0x3c/0x50)
[    9.187168]  r5:00000000 r4:f5400d98
[    9.190777] [<c0290adc>] (blk_end_bidi_request) from [<c0290b40>] (blk_end_request+0x14/0x18)
[    9.199335]  r9:00000000 r8:00000000 r7:f5a06830 r6:f5a06808 r5:f5400d98 r4:f5a06940
[    9.207160] [<c0290b2c>] (blk_end_request) from [<c04f5604>] (mmc_blk_issue_rw_rq+0x7f8/0xaac)
[    9.215813] [<c04f4e0c>] (mmc_blk_issue_rw_rq) from [<c04f5b18>] (mmc_blk_issue_rq+0x260/0x4f4)
[    9.224546]  r10:00000000 r9:ffffe000 r8:f5a06800 r7:f5b72400 r6:f5b726dc r5:f63d4800
[    9.232449]  r4:f5a06808
[    9.235004] [<c04f58b8>] (mmc_blk_issue_rq) from [<c04f74b8>] (mmc_queue_thread+0x104/0x178)
[    9.243476]  r10:f5400d98 r9:ffffe000 r8:f5a06810 r7:f5418000 r6:f59cf200 r5:00000000
[    9.251378]  r4:f5a06808
[    9.253935] [<c04f73b4>] (mmc_queue_thread) from [<c0050508>] (kthread+0x100/0x118)
[    9.261620]  r10:00000000 r9:00000000 r8:c04f73b4 r7:f5a06808 r6:f5418000 r5:f5a6a4c0
[    9.269522]  r4:00000000 r3:f5abda00
[    9.273133] [<c0050408>] (kthread) from [<c000fb88>] (ret_from_fork+0x14/0x2c)
[    9.280382]  r8:00000000 r7:00000000 r6:00000000 r5:c0050408 r4:f5a6a4c0
[    9.287152] Code: e0423003 e7944100 e1a0000c e0800004 (e590c024) 
[    9.293274] ---[ end trace f6e7e70ccaf9a379 ]---
[    9.297910] note: mmcqd/0[72] exited with preempt_count 2
[   29.876881] INFO: rcu_preempt detected stalls on CPUs/tasks:
[   29.882583] 	0-...: (1 GPs behind) idle=12b/140000000000000/0 softirq=3300/3336 fqs=27 
[   29.890621] 	(detected by 1, t=2102 jiffies, g=186, c=185, q=14375)
[   29.896923] Task dump for CPU 0:
[   29.900163] systemd-udevd   R running      0   706    191 0x00000002
[   29.906560] Backtrace: 
[   29.909027] [<f7722d5c>] (0xf7722d5c) from [<00000001>] (0x1)
[   29.914795] Backtrace aborted due to bad frame pointer <00000001>
[   29.920916] rcu_preempt kthread starved for 2032 jiffies! g186 c185 f0x0 s3 ->state=0x0

Thank you for your reply. 

Tsotne 

Please give me some time to review this

thank you

cesar

Hi,

I have discussed this with the team.

The logs that you have provided seems to indicate that the information on the SD card is corrupted.

Can you please try another SD Card?

Thank you

Cesar

Hello,

I have tried 5 different SD cards, including the two provided by TI and none of them seemed to work. Is there anything else I can try or should we return the boards? 

Hello User,

Thankyou for your trials on this. 

Due to summer holidays, there might be a delay in our response. 

We hope that's okay with you. Thankyou for your patience. 

Regards,

Ishita