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.

SATA mount fail

Our processor is DRA74X with PMIC-659038, and use 3.02.00.03 SDK version.

Most of time, SATA device(Apacer APM2T42SM23008GTN-2ATMW) will mounted by system automatically after boot up, and the result of "lsblk" as below(SATA is mounted at /run/media/sda1)

But sometimes mount fail, so i can't see any result at "MOUNTPOINT", and if i try to mount it manually, will got some error message as below

root@dra7xx-evm:~# [   43.126541] ata1.00: exception Emask 0x0 SAct 0x3c SErr 0x0 action 0x6 frozen
[   43.133721] ata1.00: failed command: READ FPDMA QUEUED
[   43.139078] ata1.00: cmd 60/08:10:10:00:00/00:00:00:00:00/40 tag 2 ncq 4096 in
[   43.139078]          res 40/00:00:00:00:00/00:00:00:00:00/00 Emask 0x4 (timeout)
[   43.153939] ata1.00: status: { DRDY }
[   43.157741] ata1.00: failed command: READ FPDMA QUEUED
[   43.162948] ata1.00: cmd 60/18:18:20:00:00/00:00:00:00:00/40 tag 3 ncq 12288 in
[   43.162948]          res 40/00:00:00:00:00/00:00:00:00:00/00 Emask 0x4 (timeout)
[   43.177815] ata1.00: status: { DRDY }
[   43.181565] ata1.00: failed command: READ FPDMA QUEUED
[   43.186851] ata1.00: cmd 60/38:20:40:00:00/00:00:00:00:00/40 tag 4 ncq 28672 in
[   43.186851]          res 40/00:00:00:00:00/00:00:00:00:00/00 Emask 0x4 (timeout)
[   43.201813] ata1.00: status: { DRDY }
[   43.205600] ata1.00: failed command: READ FPDMA QUEUED
[   43.210867] ata1.00: cmd 60/90:28:80:00:00/00:00:00:00:00/40 tag 5 ncq 73728 in
[   43.210867]          res 40/00:00:00:00:00/00:00:00:00:00/00 Emask 0x4 (timeout)
[   43.225772] ata1.00: status: { DRDY }
[   43.229578] ata1: hard resetting link

What's the posible reason for this error? And any suggestion to fix this?

Regards,

Shawn

  • Hi
    We have not been testing with SATA, though the driver is included with the SDK release
    Will try and provide guidance here, based on the failure log messages

    from the logs, looks like the SATA disk detection did not go through as expected.
    Would help if you can attach the complete boot log and also provide additional details on when it works and when it does not
  • Hi Sriram,

    Here is the complete boot log(with SATA mount ok)=> 

    U-Boot SPL 2016.05-g7716941-dirty (Oct 03 2018 - 10:41:43)
    DRA752-GP ES2.0
    no pinctrl for hs200_1_8v
    no pinctrl for ddr_1_8v
    @SD max clock = 10000000@
    @eMMC max clock = 10000000@
    *** Warning - bad CRC, using default environment
    
    Trying to boot from MMC1
    *** Warning - bad CRC, using default environment
    
    reading u-boot.img
    reading u-boot.img
    reading u-boot.img
    reading u-boot.img
    
    
    U-Boot 2016.05-g7716941-dirty (Oct 03 2018 - 10:41:43 +0800)
    
    CPU  : DRA752-GP ES2.0
    Model: TI DRA742
    Board: DRA74x EVM REV 
    DRAM:  2 GiB
    NAND:  0 MiB
    MMC:   OMAP SD/MMC: 0, OMAP SD/MMC: 1
    @eMMC max clock = 10000000@
    *** Warning - bad CRC, using default environment
    
    current ID is 0
    Warning: fastboot.board_rev: unknown board revision
    GUID Partition Table Header signature is wrong: 0x0 != 0x5452415020494645
    part_get_info_efi: *** ERROR: Invalid GPT ***
    GUID Partition Table Header signature is wrong: 0x0 != 0x5452415020494645
    part_get_info_efi: *** ERROR: Invalid Backup GPT ***
    ERROR: cannot find partition: 'userdata'
    
    at arch/arm/cpu/armv7/omap-common/utils.c:199/mmc_get_part_size()
    Warning: fastboot.userdata_size: unable to calc
    SCSI:  Target spinup took 0 ms.
    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...
      Device 0: (0:0) Vendor: ATA Prod.: 8GB SATA Flash D Rev: SFMA
                Type: Hard Disk
                Capacity: 7641.2 MB = 7.4 GB (15649200 x 512)
    Found 1 device(s).
    Net:   
    Warning: ethernet@48484000 using MAC address from ROM
    eth0: ethernet@48484000
    Hit any key to stop autoboot:  0 
    load MAN2020_original.dtb
    load MAN2020_2g.dtb
    switch to partitions #0, OK
    mmc0 is current device
    @SD max clock = 10000000@
    SD/MMC found on device 0
    reading boot.scr
    ** Unable to read file boot.scr **
    reading uEnv.txt
    222 bytes read in 3 ms (72.3 KiB/s)
    Loaded env from uEnv.txt
    Importing environment from mmc0 ...
    @SD max clock = 10000000@
    switch to partitions #0, OK
    mmc0 is current device
    @SD max clock = 10000000@
    SD/MMC found on device 0
    3639032 bytes read in 781 ms (4.4 MiB/s)
    111801 bytes read in 85 ms (1.3 MiB/s)
    Booting from mmc0 ...
    Kernel image @ 0x82000000 [ 0x000000 - 0x3786f8 ]
    ## Flattened Device Tree blob at 88000000
       Booting using the fdt blob at 0x88000000
       Loading Device Tree to 8ffe1000, end 8ffff4b8 ... 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.45-g55ec0e9-dirty (hsuyz@hsuyz-VirtualBox) (gcc version 5.3.1 20160113 (Linaro GCC 5.3-2016.02) ) #12 SMP PREEMPT Wed Oct 3 10:42:18 CST 2018
    [    0.000000] CPU: ARMv7 Processor [412fc0f2] revision 2 (ARMv7), cr=30c5387d
    [    0.000000] CPU: PIPT / VIPT nonaliasing data cache, PIPT instruction cache
    [    0.000000] Machine model: TI DRA742
    [    0.000000] cma: Reserved 64 MiB at 0x00000000fac00000
    [    0.000000] Forcing write-allocate cache policy for SMP
    [    0.000000] Memory policy: Data cache writealloc
    [    0.000000] DRA752 ES2.0
    [    0.000000] PERCPU: Embedded 11 pages/cpu @eed9f000 s14848 r8192 d22016 u45056
    [    0.000000] Built 1 zonelists in Zone order, mobility grouping on.  Total pages: 517952
    [    0.000000] Kernel command line: console=ttyO3,115200n8 elevator=noop root=/dev/mmcblk0p2 rw rootwait earlyprintk fixrtc omapdrm.num_crtc=2 consoleblank=0 cma=64M rootfstype=ext4 snd.slots_reserved=1,1
    [    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: 1983324K/2078720K available (6643K kernel code, 364K rwdata, 2472K rodata, 336K init, 302K bss, 29860K reserved, 65536K cma-reserved, 1226752K 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 - 0xc08ef09c   (9117 kB)
    [    0.000000]       .init : 0xc08f0000 - 0xc0944000   ( 336 kB)
    [    0.000000]       .data : 0xc0944000 - 0xc099f310   ( 365 kB)
    [    0.000000]        .bss : 0xc09a1000 - 0xc09eca78   ( 303 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 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.000005] sched_clock: 56 bits at 5MHz, resolution 169ns, wraps every 4398046511093ns
    [    0.000016] Switching to timer-based delay loop, resolution 169ns
    [    0.000334] clocksource: 32k_counter: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 58327039986419 ns
    [    0.000341] OMAP clocksource: 32k_counter at 32768 Hz
    [    0.000818] Console: colour dummy device 80x30
    [    0.000834] WARNING: Your 'console=ttyO3' has been replaced by 'ttyS3'
    [    0.000841] This ensures that you still see kernel messages. Please
    [    0.000847] update your kernel commandline.
    [    0.000862] Calibrating delay loop (skipped), value calculated using timer frequency.. 11.80 BogoMIPS (lpj=59016)
    [    0.000877] pid_max: default: 32768 minimum: 301
    [    0.000976] Mount-cache hash table entries: 2048 (order: 1, 8192 bytes)
    [    0.000986] Mountpoint-cache hash table entries: 2048 (order: 1, 8192 bytes)
    [    0.001578] Initializing cgroup subsys io
    [    0.001597] Initializing cgroup subsys memory
    [    0.001623] Initializing cgroup subsys devices
    [    0.001636] Initializing cgroup subsys freezer
    [    0.001648] Initializing cgroup subsys perf_event
    [    0.001660] Initializing cgroup subsys pids
    [    0.001687] CPU: Testing write buffer coherency: ok
    [    0.001918] /cpus/cpu@0 missing clock-frequency property
    [    0.001935] /cpus/cpu@1 missing clock-frequency property
    [    0.001946] CPU0: thread -1, cpu 0, socket 0, mpidr 80000000
    [    0.001984] Setting up static identity map for 0x800082c0 - 0x80008320
    [    0.080212] CPU1: thread -1, cpu 1, socket 0, mpidr 80000001
    [    0.080277] Brought up 2 CPUs
    [    0.080291] SMP: Total of 2 processors activated (23.60 BogoMIPS).
    [    0.080298] CPU: All CPU(s) started in HYP mode.
    [    0.080304] CPU: Virtualization extensions available.
    [    0.080714] devtmpfs: initialized
    [    0.111345] VFP support v0.3: implementor 41 architecture 4 part 30 variant f rev 0
    [    0.112435] omap_hwmod: l3_main_2 using broken dt data from ocp
    [    0.306970] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns
    [    0.308455] pinctrl core: initialized pinctrl subsystem
    [    0.309371] NET: Registered protocol family 16
    [    0.310415] DMA: preallocated 256 KiB pool for atomic coherent allocations
    [    0.340251] cpuidle: using governor ladder
    [    0.370276] cpuidle: using governor menu
    [    0.378691] OMAP GPIO hardware version 0.1
    [    0.382886] GPIO line 161 (radio_rst) hogged as output/low
    [    0.385261] irq: no irq domain found for /ocp/l4@4a000000/scm@2000/pinmux@1400 !
    [    0.411258] hw-breakpoint: found 5 (+1 reserved) breakpoint and 4 watchpoint registers.
    [    0.411268] hw-breakpoint: maximum watchpoint size is 8 bytes.
    [    0.412115] OMAP DMA hardware revision 0.0
    [    0.451929] omap-dma-engine 4a056000.dma-controller: OMAP DMA engine driver (LinkedList1/2/3 supported)
    [    0.453150] edma 43300000.edma: memcpy is disabled
    [    0.458031] edma 43300000.edma: TI EDMA DMA engine driver
    [    0.465371] palmas 0-0058: IRQ missing: skipping irq request
    [    0.480854] palmas 0-0058: Muxing GPIO a, PWM 0, LED 0
    [    0.480975] @@@@1
    [    0.481195] @@@@3
    [    0.481207] @@@@4
    [    0.481216] @@@@5
    [    0.481582] @@@@6
    [    0.481758] @@@@7
    [    0.490449] @@@@8
    [    0.552504] @@@@9(0)
    [    0.552522] @@@@2
    [    0.552797] omap_i2c 48070000.i2c: bus 0 rev0.12 at 400 kHz
    [    0.553242] omap_i2c 48072000.i2c: bus 1 rev0.12 at 400 kHz
    [    0.553624] omap_i2c 48060000.i2c: bus 2 rev0.12 at 400 kHz
    [    0.553998] omap_i2c 4807a000.i2c: bus 3 rev0.12 at 400 kHz
    [    0.554360] omap_i2c 4807c000.i2c: bus 4 rev0.12 at 400 kHz
    [    0.554567] media: Linux media interface: v0.10
    [    0.554623] Linux video capture interface: v2.00
    [    0.554664] pps_core: LinuxPPS API ver. 1 registered
    [    0.554671] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>
    [    0.554695] PTP clock support registered
    [    0.554741] EDAC MC: Ver: 3.0.0
    [    0.555547] Advanced Linux Sound Architecture Driver Initialized.
    [    0.556499] clocksource: Switched to clocksource arch_sys_counter
    [    0.567222] NET: Registered protocol family 2
    [    0.567750] TCP established hash table entries: 8192 (order: 3, 32768 bytes)
    [    0.567816] TCP bind hash table entries: 8192 (order: 4, 65536 bytes)
    [    0.567941] TCP: Hash tables configured (established 8192 bind 8192)
    [    0.567992] UDP hash table entries: 512 (order: 2, 16384 bytes)
    [    0.568023] UDP-Lite hash table entries: 512 (order: 2, 16384 bytes)
    [    0.568245] NET: Registered protocol family 1
    [    0.568516] RPC: Registered named UNIX socket transport module.
    [    0.568525] RPC: Registered udp transport module.
    [    0.568532] RPC: Registered tcp transport module.
    [    0.568538] RPC: Registered tcp NFSv4.1 backchannel transport module.
    [    0.569632] hw perfevents: enabled with armv7_cortex_a15 PMU driver, 7 counters available
    [    0.572348] futex hash table entries: 512 (order: 3, 32768 bytes)
    [    0.580194] squashfs: version 4.0 (2009/01/31) Phillip Lougher
    [    0.580864] NFS: Registering the id_resolver key type
    [    0.580894] Key type id_resolver registered
    [    0.580902] Key type id_legacy registered
    [    0.580959] ntfs: driver 2.1.32 [Flags: R/O].
    [    0.583013] bounce: pool size: 64 pages
    [    0.583179] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247)
    [    0.583195] io scheduler noop registered (default)
    [    0.583207] io scheduler deadline registered
    [    0.583243] io scheduler cfq registered
    [    0.588710] pinctrl-single 4a003400.pinmux: 282 pins at pa fc003400 size 1128
    [    0.652510] Serial: 8250/16550 driver, 10 ports, IRQ sharing disabled
    [    0.656135] 4806a000.serial: ttyS0 at MMIO 0x4806a000 (irq = 299, base_baud = 3000000) is a 8250
    [    0.657089] 4806c000.serial: ttyS1 at MMIO 0x4806c000 (irq = 300, base_baud = 3000000) is a 8250
    [    0.657954] 48020000.serial: ttyS2 at MMIO 0x48020000 (irq = 301, base_baud = 3000000) is a 8250
    [    0.658811] console [ttyS3] disabled
    [    0.658859] 4806e000.serial: ttyS3 at MMIO 0x4806e000 (irq = 302, base_baud = 3000000) is a 8250
    [    1.448740] console [ttyS3] enabled
    [    1.453112] 48066000.serial: ttyS4 at MMIO 0x48066000 (irq = 303, base_baud = 3000000) is a 8250
    [    1.462774] 48422000.serial: ttyS7 at MMIO 0x48422000 (irq = 304, base_baud = 3000000) is a 8250
    [    1.472436] 48424000.serial: ttyS8 at MMIO 0x48424000 (irq = 305, base_baud = 3000000) is a 8250
    [    1.482104] 4ae2b000.serial: ttyS9 at MMIO 0x4ae2b000 (irq = 306, base_baud = 3000000) is a 8250
    [    1.492223] [drm] Initialized drm 1.1.0 20060810
    [    1.498380] OMAP DSS rev 6.1
    [    1.502188] omapdss_dss 58000000.dss: bound 58001000.dispc (ops dispc_component_ops)
    [    1.518627] loop: module loaded
    [    1.528044] m25p80 spi32766.0: s25fl256s1 (32768 Kbytes)
    [    1.533448] 7 ofpart partitions found on MTD device spi32766.0
    [    1.539328] Creating 7 MTD partitions on "spi32766.0":
    [    1.544494] 0x000000000000-0x000000040000 : "QSPI.MLO"
    [    1.550607] 0x000000040000-0x000000140000 : "QSPI.u-boot"
    [    1.556939] 0x000000140000-0x0000001c0000 : "QSPI.DRA7-evm.dtb"
    [    1.563749] 0x0000001c0000-0x0000001d0000 : "QSPI.u-boot-env"
    [    1.570424] 0x0000001d0000-0x0000001e0000 : "QSPI.u-boot-env.backup1"
    [    1.577801] 0x0000001e0000-0x0000009e0000 : "QSPI.uImage"
    [    1.584071] 0x0000009e0000-0x000002000000 : "QSPI.IPU-exe"
    [    1.591526] libphy: Fixed MDIO Bus: probed
    [    1.596236] CAN device driver interface
    [    1.600504] pinctrl-single 4a003400.pinmux: pin 4a003818.0 already requested by 4a003400.pinmux; cannot claim for 4ae3c000.can
    [    1.611967] pinctrl-single 4a003400.pinmux: pin-262 (4ae3c000.can) status -22
    [    1.619149] pinctrl-single 4a003400.pinmux: could not request pin 262 (4a003818.0) from group dcan1_pins_sleep  on device pinctrl-single
    [    1.631478] c_can_platform 4ae3c000.can: Error applying setting, reverse things back
    [    1.639951] c_can_platform 4ae3c000.can: c_can_platform device registered (regs=fce3c000, irq=354)
    [    1.696532] davinci_mdio 48485000.mdio: davinci mdio revision 1.6
    [    1.702657] libphy: 48485000.mdio: probed
    [    1.706710] mdio_bus 48485000.mdio: /ocp/ethernet@48484000/mdio@48485000/micrel_phy_config has invalid PHY address
    [    1.717124] mdio_bus 48485000.mdio: /ocp/ethernet@48484000/mdio@48485000/rmii-ref has invalid PHY address
    [    1.726752] mdio_bus 48485000.mdio: scan phy micrel_phy_config at address 0
    [    1.735977] mdio_bus 48485000.mdio: scan phy micrel_phy_config at address 1
    [    1.743778] mdio_bus 48485000.mdio: scan phy micrel_phy_config at address 2
    [    1.751576] mdio_bus 48485000.mdio: scan phy micrel_phy_config at address 3
    [    1.759378] mdio_bus 48485000.mdio: scan phy micrel_phy_config at address 4
    [    1.768533] mdio_bus 48485000.mdio: scan phy micrel_phy_config at address 5
    [    1.776538] mdio_bus 48485000.mdio: scan phy micrel_phy_config at address 6
    [    1.784336] mdio_bus 48485000.mdio: scan phy micrel_phy_config at address 7
    [    1.792137] mdio_bus 48485000.mdio: scan phy micrel_phy_config at address 8
    [    1.799937] mdio_bus 48485000.mdio: scan phy micrel_phy_config at address 9
    [    1.807735] mdio_bus 48485000.mdio: scan phy micrel_phy_config at address 10
    [    1.815536] mdio_bus 48485000.mdio: scan phy micrel_phy_config at address 11
    [    1.823335] mdio_bus 48485000.mdio: scan phy micrel_phy_config at address 12
    [    1.831135] mdio_bus 48485000.mdio: scan phy micrel_phy_config at address 13
    [    1.838936] mdio_bus 48485000.mdio: scan phy micrel_phy_config at address 14
    [    1.846736] mdio_bus 48485000.mdio: scan phy micrel_phy_config at address 15
    [    1.854535] mdio_bus 48485000.mdio: scan phy micrel_phy_config at address 16
    [    1.862335] mdio_bus 48485000.mdio: scan phy micrel_phy_config at address 17
    [    1.870136] mdio_bus 48485000.mdio: scan phy micrel_phy_config at address 18
    [    1.877936] mdio_bus 48485000.mdio: scan phy micrel_phy_config at address 19
    [    1.885735] mdio_bus 48485000.mdio: scan phy micrel_phy_config at address 20
    [    1.893536] mdio_bus 48485000.mdio: scan phy micrel_phy_config at address 21
    [    1.901336] mdio_bus 48485000.mdio: scan phy micrel_phy_config at address 22
    [    1.909136] mdio_bus 48485000.mdio: scan phy micrel_phy_config at address 23
    [    1.916935] mdio_bus 48485000.mdio: scan phy micrel_phy_config at address 24
    [    1.924735] mdio_bus 48485000.mdio: scan phy micrel_phy_config at address 25
    [    1.932539] mdio_bus 48485000.mdio: scan phy micrel_phy_config at address 26
    [    1.940336] mdio_bus 48485000.mdio: scan phy micrel_phy_config at address 27
    [    1.948136] mdio_bus 48485000.mdio: scan phy micrel_phy_config at address 28
    [    1.955935] mdio_bus 48485000.mdio: scan phy micrel_phy_config at address 29
    [    1.963734] mdio_bus 48485000.mdio: scan phy micrel_phy_config at address 30
    [    1.971535] mdio_bus 48485000.mdio: scan phy micrel_phy_config at address 31
    [    1.979338] mdio_bus 48485000.mdio: scan phy rmii-ref at address 1
    [    1.986095] mdio_bus 48485000.mdio: scan phy rmii-ref at address 2
    [    1.992856] mdio_bus 48485000.mdio: scan phy rmii-ref at address 3
    [    1.999615] mdio_bus 48485000.mdio: scan phy rmii-ref at address 5
    [    2.006375] mdio_bus 48485000.mdio: scan phy rmii-ref at address 6
    [    2.013136] mdio_bus 48485000.mdio: scan phy rmii-ref at address 7
    [    2.019896] mdio_bus 48485000.mdio: scan phy rmii-ref at address 8
    [    2.026656] mdio_bus 48485000.mdio: scan phy rmii-ref at address 9
    [    2.033415] mdio_bus 48485000.mdio: scan phy rmii-ref at address 10
    [    2.040700] mdio_bus 48485000.mdio: scan phy rmii-ref at address 11
    [    2.047977] mdio_bus 48485000.mdio: scan phy rmii-ref at address 12
    [    2.055256] mdio_bus 48485000.mdio: scan phy rmii-ref at address 13
    [    2.062536] mdio_bus 48485000.mdio: scan phy rmii-ref at address 14
    [    2.069817] mdio_bus 48485000.mdio: scan phy rmii-ref at address 15
    [    2.077097] mdio_bus 48485000.mdio: scan phy rmii-ref at address 16
    [    2.084375] mdio_bus 48485000.mdio: scan phy rmii-ref at address 17
    [    2.091656] mdio_bus 48485000.mdio: scan phy rmii-ref at address 18
    [    2.098936] mdio_bus 48485000.mdio: scan phy rmii-ref at address 19
    [    2.106216] mdio_bus 48485000.mdio: scan phy rmii-ref at address 20
    [    2.113496] mdio_bus 48485000.mdio: scan phy rmii-ref at address 21
    [    2.120776] mdio_bus 48485000.mdio: scan phy rmii-ref at address 22
    [    2.128056] mdio_bus 48485000.mdio: scan phy rmii-ref at address 23
    [    2.135335] mdio_bus 48485000.mdio: scan phy rmii-ref at address 24
    [    2.142616] mdio_bus 48485000.mdio: scan phy rmii-ref at address 25
    [    2.149896] mdio_bus 48485000.mdio: scan phy rmii-ref at address 26
    [    2.157180] mdio_bus 48485000.mdio: scan phy rmii-ref at address 27
    [    2.164455] mdio_bus 48485000.mdio: scan phy rmii-ref at address 28
    [    2.171736] mdio_bus 48485000.mdio: scan phy rmii-ref at address 29
    [    2.179016] mdio_bus 48485000.mdio: scan phy rmii-ref at address 30
    [    2.186295] mdio_bus 48485000.mdio: scan phy rmii-ref at address 31
    [    2.193578] davinci_mdio 48485000.mdio: phy[0]: device 48485000.mdio:00, driver Micrel KSZ8081 or KSZ8091
    [    2.203204] davinci_mdio 48485000.mdio: phy[4]: device 48485000.mdio:04, driver Micrel KSZ8081 or KSZ8091
    [    2.213243] cpsw 48484000.ethernet: No slave[0] phy_id, phy-handle, or fixed-link property
    [    2.221724] cpsw 48484000.ethernet: Detected MACID = 74:e1:82:de:18:9e
    [    2.228389] cpsw 48484000.ethernet: cpts: overflow check period 800
    [    2.236586] mousedev: PS/2 mouse device common for all mice
    [    2.253207] input: LDC 3001 TouchScreen Controller as /devices/platform/44000000.ocp/48070000.i2c/i2c-0/0-0018/input/input0
    [    2.264820] i2c /dev entries driver
    [    2.269872] vpe 489d0000.vpe: loading firmware vpdma-1b8.bin
    [    2.280503] omap_hsmmc 4809c000.mmc: Got CD GPIO
    [    2.286714] vpe 489d0000.vpe: Device registered as /dev/video0
    [    2.327212] omap_hsmmc 480b4000.mmc: no pinctrl state for sdr25 mode
    [    2.333599] omap_hsmmc 480b4000.mmc: no pinctrl state for sdr12 mode
    [    2.340172] evm_3v3_sw: supplied by sysen1
    [    2.376875] omap_hsmmc 480ad000.mmc: no pinctrl state for sdr50 mode
    [    2.383262] omap_hsmmc 480ad000.mmc: no pinctrl state for sdr25 mode
    [    2.389662] omap_hsmmc 480ad000.mmc: no pinctrl state for sdr12 mode
    [    2.396043] omap_hsmmc 480ad000.mmc: no pinctrl state for hs mode
    [    2.437203] GPIO line 511 (LVDS_CLKSel) hogged as output/high
    [    2.443517] ledtrig-cpu: registered to indicate activity on CPUs
    [    2.453768] hwspinlock_user gatemp: requested 10 hwspinlocks
    [    2.460255] 
    [    2.460255] [spidrv_init]
    [    2.464373] spidrv_init called
    [    2.467464] 
    [    2.467464] [mcaspdrv_init]
    [    2.471752] mcaspdrv_init called
    [    2.476119] davinci-mcasp 48460000.mcasp: DAI is shared
    [    2.481465] 
    [    2.481465] [McASP1][mcasp->base][FA460000]
    [    2.487185] 
    [    2.487185] [non return]
    [    2.491799] davinci-mcasp 48464000.mcasp: DAI is shared
    [    2.497105] 
    [    2.497105] [McASP2][mcasp->base][FA464000]
    [    2.497105] 
    [    2.504273] 
    [    2.504273] [non return]
    [    2.508555] davinci-mcasp 48468000.mcasp: DAI is shared
    [    2.513818] 
    [    2.513818] [McASP3][mcasp->base][FA468000]
    [    2.513818] 
    [    2.521018] 
    [    2.521018] [non return]
    [    2.526647] NET: Registered protocol family 10
    [    2.541946] sit: IPv6 over IPv4 tunneling driver
    [    2.547161] NET: Registered protocol family 17
    [    2.551643] can: controller area network core (rev 20120528 abi 9)
    [    2.557944] NET: Registered protocol family 29
    [    2.562427] can: raw protocol (rev 20120528)
    [    2.566951] Key type dns_resolver registered
    [    2.571395] omap_voltage_late_init: Voltage driver support not added
    [    2.578460] Power Management for TI OMAP4+ devices.
    [    2.583623] Registering SWP/SWPB emulation handler
    [    2.591596] dmm 4e000000.dmm: initialized all PAT entries
    [    2.623904] mmc0: host does not support reading read-only switch, assuming write-enable
    [    2.633942] @SD max clock = 10000000@
    [    2.637786] mmc0: new high speed SDHC card at address 59b4
    [    2.646593] [drm] Supports vblank timestamp caching Rev 2 (21.10.2013).
    [    2.653241] [drm] No driver support for vblank timestamp query.
    [    2.659280] mmcblk0: mmc0:59b4 SD    14.7 GiB 
    [    2.665551]  mmcblk0: p1 p2
    [    2.674081] [drm] Enabling DMM ywrap scrolling
    [    2.710246] mmc1: MAN_BKOPS_EN bit is not set
    [    2.711209] @eMMC max clock = 25000000@
    [    2.711277] mmc1: new MMC card at address 0001
    [    2.711774] mmcblk1: mmc1:0001 MMC04G 3.58 GiB 
    [    2.722032] mmcblk1boot0: mmc1:0001 MMC04G partition 1 16.0 MiB
    [    2.722251] mmcblk1boot1: mmc1:0001 MMC04G partition 2 16.0 MiB
    [    2.724285]  mmcblk1: p1 p2
    [    2.768738] Console: switching to colour frame buffer device 160x50
    [    2.817526] omapdrm omapdrm.0: fb0: omapdrm frame buffer device
    [    2.852801] [drm] Initialized omapdrm 1.0.0 20110917 on minor 0
    [    2.859364] backlight supply power not found, using dummy regulator
    [    2.865951] leds backlight: Failed setting brightness
    [    2.871894] hctosys: unable to open rtc device (rtc0)
    [    2.889403] aic_dvdd: disabling
    [    2.892566] vmmcwl_fixed: disabling
    [    2.896311] ldousb: disabling
    [    2.899848] ALSA device list:
    [    2.902836]   No soundcards found.
    [    3.924892] EXT4-fs (mmcblk0p2): recovery complete
    [    3.933270] EXT4-fs (mmcblk0p2): mounted filesystem with ordered data mode. Opts: (null)
    [    3.941459] VFS: Mounted root (ext4 filesystem) on device 179:2.
    [    3.981041] devtmpfs: mounted
    [    3.984267] Freeing unused kernel memory: 336K (c08f0000 - c0944000)
    [    3.990676] This architecture does not have kernel memory protection.
    [    4.818028] systemd[1]: System time before build time, advancing clock.
    [    4.927585] random: systemd: uninitialized urandom read (16 bytes read, 22 bits of entropy available)
    [    4.941713] random: systemd: uninitialized urandom read (16 bytes read, 22 bits of entropy available)
    [    4.953442] 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)
    [    4.972019] systemd[1]: Detected architecture arm.
    
    Welcome to Arago 2016.12!
    
    [    5.008389] systemd[1]: Set hostname to <dra7xx-evm>.
    [    5.167586] random: systemd-sysv-ge: uninitialized urandom read (16 bytes read, 24 bits of entropy available)
    [    5.199037] random: systemd-gpt-aut: uninitialized urandom read (16 bytes read, 24 bits of entropy available)
    [    5.209352] random: systemd-gpt-aut: uninitialized urandom read (16 bytes read, 24 bits of entropy available)
    [    5.271582] random: systemd-sysv-ge: uninitialized urandom read (16 bytes read, 25 bits of entropy available)
    [    5.297208] random: systemd-sysv-ge: uninitialized urandom read (16 bytes read, 26 bits of entropy available)
    [    5.584703] random: systemd: uninitialized urandom read (16 bytes read, 29 bits of entropy available)
    [    5.594312] random: systemd: uninitialized urandom read (16 bytes read, 29 bits of entropy available)
    [    5.604337] random: systemd: uninitialized urandom read (16 bytes read, 29 bits of entropy available)
    [    5.747852] systemd[1]: Configuration file /etc/systemd/system/multi-user.target.wants/MAN2020.service is marked executable. Please remove executable permission bits. Proceeding anyway.
    [    5.764549] systemd[1]: Configuration file /etc/systemd/system/multi-user.target.wants/MAN2020.service is marked world-writable. Please remove world writability permission bits. Proceeding anyway.
    [    5.785334] systemd[1]: gplv3-notice.service: Service lacks both ExecStart= and ExecStop= setting. Refusing.
    [    5.931493] systemd[1]: gplv3-notice.service: Cannot add dependency job, ignoring: Unit gplv3-notice.service is not loaded properly: Invalid argument.
    [    5.945709] systemd[1]: sysinit.target: Found ordering cycle on sysinit.target/start
    [    5.953539] systemd[1]: sysinit.target: Found dependency on alignment.service/start
    [    5.961316] systemd[1]: sysinit.target: Found dependency on basic.target/start
    [    5.968593] systemd[1]: sysinit.target: Found dependency on sockets.target/start
    [    5.976033] systemd[1]: sysinit.target: Found dependency on dbus.socket/start
    [    5.983225] systemd[1]: sysinit.target: Found dependency on sysinit.target/start
    [    5.990678] systemd[1]: sysinit.target: Breaking ordering cycle by deleting job alignment.service/start
    [    6.000139] 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
    [    6.039356] systemd[1]: Listening on udev Kernel Socket.
    [  OK  ] Listening on udev Kernel Socket.
    [    6.066927] systemd[1]: Started Forward Password Requests to Wall Directory Watch.
    [  OK  ] Started Forward Password Requests to Wall Directory Watch.
    [    6.098199] systemd[1]: Created slice User and Session Slice.
    [  OK  ] Created slice User and Session Slice.
    [    6.126821] systemd[1]: Listening on udev Control Socket.
    [  OK  ] Listening on udev Control Socket.
    [    6.156769] systemd[1]: Listening on Journal Socket (/dev/log).
    [  OK  ] Listening on Journal Socket (/dev/log).
    [    6.186658] systemd[1]: Reached target Swap.
    [  OK  ] Reached target Swap.
    [    6.206826] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.
    [  OK  ] Started Dispatch Password Requests to Console Directory Watch.
    [    6.236654] systemd[1]: Reached target Paths.
    [  OK  ] Reached target Paths.
    [    6.256891] systemd[1]: Listening on Network Service Netlink Socket.
    [  OK  ] Listening on Network Service Netlink Socket.
    [    6.286683] systemd[1]: Reached target Remote File Systems.
    [  OK  ] Reached target Remote File Systems.
    [    6.316716] systemd[1]: Listening on Syslog Socket.
    [  OK  ] Listening on Syslog Socket.
    [    6.336762] systemd[1]: Listening on /dev/initctl Compatibility Named Pipe.
    [  OK  ] Listening on /dev/initctl Compatibility Named Pipe.
    [    6.400069] systemd[1]: Listening on Journal Socket.
    [  OK  ] Listening on Journal Socket.
    [    6.427454] systemd[1]: Created slice System Slice.
    [  OK  ] Created slice System Slice.
    [    6.486775] systemd[1]: Mounting Debug File System...
             Mounting Debug File System...
    [    6.576780] systemd[1]: Mounting Temporary Directory...
             Mounting Temporary Directory...
    [    6.609318] systemd[1]: Mounting POSIX Message Queue File System...
             Mounting POSIX Message Queue File System...
    [    6.639331] systemd[1]: Starting Journal Service...
             Starting Journal Service...
    [    6.652577] systemd[1]: Starting Remount Root and Kernel File Systems...
             Starting Remount Root and Kernel File Systems...
    [    6.676737] systemd[1]: Reached target Slices.
    [  OK  ] Reached target Slices.
    [    6.688642] EXT4-fs (mmcblk0p2): re-mounted. Opts: (null)
    [    6.756985] systemd[1]: Starting Load Kernel Modules...
             Starting Load Kernel Modules...
    [    6.789665] 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...
    [    6.829316] systemd[1]: Starting Setup Virtual Console...
             Starting Setup Virtual Console...
    [    6.857655] systemd[1]: Created slice system-serial\x2dgetty.slice.
    [  OK  ] Created slice system-serial\x2dgetty.slice.
    [    6.894549] systemd[1]: Mounted Debug File System.
    [  OK  ] Mounted Debug File System.
    [    6.916794] systemd[1]: Mounted POSIX Message Queue File System.
    [  OK  ] Mounted POSIX Message Queue File System.
    [    6.946751] systemd[1]: Mounted Temporary Directory.
    [  OK  ] Mounted Temporary Directory.
    [    6.966953] systemd[1]: Started Journal Service.
    [  OK  ] Started Journal Service.
    [  OK  ] Started Remount Root and Kernel File Systems.
    [FAILED] Failed to start Load Kernel Modules.
    See 'systemctl status systemd-modules-load.service' for details.
    [  OK  ] Started Create list of required sta...ce nodes for the current kernel.
    [  OK  ] Started Setup Virtual Console.
             Starting Create Static Device Nodes in /dev...
             Starting Apply Kernel Variables...
             Starting udev Coldplug all Devices...
             Starting Flush Journal to Persistent Storage...
    [  OK  ] Started Apply Kernel Variables.
    [    7.730770] systemd-journald[109]: Received request to flush runtime journal from PID 1
    [  OK  ] Started Flush Journal to Persistent Storage.
    [  OK  ] Started Create Static Device Nodes in /dev.
    [  OK  ] Reached target Local File Systems (Pre).
             Mounting /var/volatile...
             Mounting /media/ram...
             Starting udev Kernel Device Manager...
    [  OK  ] Mounted /var/volatile.
    [  OK  ] Mounted /media/ram.
             Starting Load/Save Random Seed...
    [  OK  ] Reached target Local File Systems.
             Starting Create Volatile Files and Directories...
    [  OK  ] Started udev Coldplug all Devices.
    [  OK  ] Started Load/Save Random Seed.
    [  OK  ] Started Create Volatile Files and Directories.
    [  OK  ] Started udev Kernel Device Manager.
             Starting Network Time Synchronization...
             Starting Update UTMP about System Boot/Shutdown...
    [    8.933996] omap-des 480a5000.des: OMAP DES hw accel rev: 2.2
    [  OK  ] Started Network Time Synchronization.[    8.949514] alg: skcipher: setkey failed on test 5 for ecb-des-omap: flags=100
    
    [    9.022521] alg: skcipher-ddst: setkey failed on test 5 for ecb-des-omap: flags=100
    [  OK  ] Found device /dev/ttyS3.
    [    9.061929] alg: skcipher-ddst: setkey failed on test 5 for ecb-des-omap: flags=100
    [    9.108033] omap_rng 48090000.rng: OMAP Random Number Generator ver. 20
    [  OK  ] Started Update UTMP about System Boot/Shutdown.
    [  OK  ] Reached target System Time Synchronized.
             Starting Synchronize System and HW clocks...
    [    9.335175] omap_rtc 48838000.rtc: rtc core: registered 48838000.rtc as rtc0
    [  OK  ] Started Synchronize System and HW clocks.
    [  OK  ] Reached target System Initialization.[    9.571812] omap_wdt: OMAP Watchdog Timer Rev 0x01: initial timeout 60 sec
    
    [  OK  ] Started Daily Cleanup of Temporary Directories.
    [  OK  ] Reached target Timers.
    [  OK  ] Listening on RPCbind Server Activation Socket.
    [  OK  ] Listening on Avahi mDNS/DNS-SD Stack Activation Socket.
    [    9.658679] omap-sham 4b101000.sham: hw accel on OMAP rev 4.3
    [    9.686873] SCSI subsystem initialized
    [  OK  ] Listening on dropbear.socket.
    [  OK  ] Listening on D-Bus System Message Bus Socket.
    [  OK  ] Reached target Sockets.
    [  OK  ] Reached target Basic System.
             Starting Permit User Sessions...
    [  OK  ] Started strongSwan IPsec IKEv1/IKEv2 daemon using ipsec.conf.
             Starting uim-sysfs.service...
    [  OK  ] Started System Logging Service.
    [  OK  ] Started Start helloworld.
             Starting Login Service...
             Starting hw-id.service...
    [  OK  ] Started Kernel Logging Service.
    
    
             Starting Telephony service...
    [SYS ] paring option: token=3, option=(null)
    [INFO] R3 init, iface=/dev/ttyS7
    [INFO] R2 init
    [INFO] watchdog init
    [ERR ] open ID_D0 error
             Starting Avahi mDNS/DNS-SD Stack...
    [  OK  ] Started D-Bus System Message Bus.
    [   10.838833] omap-aes 4b500000.aes: OMAP AES hw accel rev: 3.3
    [INFO] CLIENT ready
    [   11.101741] omap-aes 4b700000.aes: OMAP AES hw accel rev: 3.3
    [   11.129859] [drm] Initialized pvr 1.14.3699939 20110701 on minor 1
    [   11.137399] ahci 4a140000.sata: SSS flag set, parallel bus scan disabled
    [   11.149873] ahci 4a140000.sata: AHCI 0001.0300 32 slots 1 ports 3 Gbps 0x1 impl platform mode
    [   11.203997] ahci 4a140000.sata: flags: 64bit ncq sntf stag pm led clo only pmp pio slum part ccc apst 
    [   11.276319] scsi host0: ahci
    [   11.284386] ata1: SATA max UDMA/133 mmio [mem 0x4a140000-0x4a1410ff] port 0x100 irq 339
    [  OK  ] Started Avahi mDNS/DNS-SD Stack.
    [  OK  ] Started Telephony service.
    [   11.787089] NET: Registered protocol family 15
             Starting Network Service...
    [   11.856743] ata1: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
    [   11.867187] ata1.00: ATA-11: 8GB SATA Flash Drive, SFMA002A, max UDMA/133
    [  OK  ] Started Permit User Sessions.
    [   11.909969] ata1.00: 15649200 sectors, multi 16: LBA48 NCQ (depth 31/32)
    [  OK  ] Started hw-id.service.
    [   11.938570] Bluetooth: Core ver 2.21
    [   11.942225] NET: Registered protocol family 31
    [   11.955927] Bluetooth: HCI device and connection manager initialized
    [   11.960376] ata1.00: configured for UDMA/133
    [   12.013857] Bluetooth: HCI socket layer initialized
    [   12.014421] scsi 0:0:0:0: Direct-Access     ATA      8GB SATA Flash D 002A PQ: 0 ANSI: 5
    [   12.056647] Bluetooth: L2CAP socket layer initialized
    [   12.061791] Bluetooth: SCO socket layer initialized
    [   12.240829] EXT4-fs (mmcblk1p2): recovery complete
    [   12.246012] EXT4-fs (mmcblk1p2): mounted filesystem with ordered data mode. Opts: (null)
    [   12.251054] net eth0: initializing cpsw version 1.15 (0)
    [  OK  ] Started Network Service.[   12.261372] Initializing XFRM netlink socket
    
    [   12.278928] FAT-fs (mmcblk0p1): Volume was not properly unmounted. Some data may be corrupt. Please run fsck.
    [   12.286546] net eth0: initialized cpsw ale version 1.4
    [   12.286552] net eth0: ALE Table size 1024
    [   12.288697] libphy: PHY  not found
    [   12.288705] net eth0: phy "" not found on slave 0, err -19
    [   12.381826] FAT-fs (mmcblk1p1): Volume was not properly unmounted. Some data may be corrupt. Please run fsck.
    [   12.449193] net eth0: phy found : id is : 0x221560
    [   12.454173] cpsw-phy-sel 4a002554.cpsw-phy-sel: RMII External clock is not supported
    [   13.296430] sd 0:0:0:0: [sda] 15649200 512-byte logical blocks: (8.01 GB/7.46 GiB)
    [   13.322787] @@USB2PHY_TERMINATION_CONTROL=0x220004
    [   13.322976] sd 0:0:0:0: [sda] Write Protect is off
    [   13.323105] sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
    [   13.327729]  sda: sda1
    [   13.333278] sd 0:0:0:0: [sda] Attached SCSI disk
    [   13.516859] ata1.00: exception Emask 0x0 SAct 0x38 SErr 0x0 action 0x0
    [   13.525913] ata1.00: irq_stat 0x40000000
    [   13.540728] ata1.00: failed command: READ FPDMA QUEUED
    [   13.566229] ata1.00: cmd 60/18:18:20:00:00/00:00:00:00:00/40 tag 3 ncq 12288 in
    [   13.566229]          res 41/40:18:20:00:00/00:00:00:00:00/40 Emask 0x409 (media error) <F>
    [   13.616773] ata1.00: status: { DRDY ERR }
    [   13.621142] ata1.00: error: { UNC }
    [   13.647213] ata1.00: configured for UDMA/133
    [   13.651887] sd 0:0:0:0: [sda] tag#3 UNKNOWN(0x2003) Result: hostbyte=0x00 driverbyte=0x08
    [   13.682299] sd 0:0:0:0: [sda] tag#3 Sense Key : 0x3 [current] [descriptor] 
    [   13.706554] sd 0:0:0:0: [sda] tag#3 ASC=0x11 ASCQ=0x4 
    [   13.726950] sd 0:0:0:0: [sda] tag#3 CDB: opcode=0x28 28 00 00 00 00 20 00 00 18 00
    [   13.734576] blk_update_request: I/O error, dev sda, sector 32
    [   13.763083] ata1: EH complete
    [   13.887018] ata1.00: exception Emask 0x0 SAct 0x800 SErr 0x0 action 0x0
    [   13.893679] ata1.00: irq_stat 0x40000000
    [   13.905845] ata1.00: failed command: READ FPDMA QUEUED
    [   13.936531] ata1.00: cmd 60/08:58:20:00:00/00:00:00:00:00/40 tag 11 ncq 4096 in
    [   13.936531]          res 41/40:58:20:00:00/00:00:00:00:00/40 Emask 0x409 (media error) <F>
    [   13.971863] ata1.00: status: { DRDY ERR }
    [   13.978745] @@USB2PHY_TERMINATION_CONTROL=0x220004
    [   13.996669] ata1.00: error: { UNC }
    [   14.000494] ata1.00: configured for UDMA/133
    [   14.004826] sd 0:0:0:0: [sda] tag#11 UNKNOWN(0x2003) Result: hostbyte=0x00 driverbyte=0x08
    [   14.036519] sd 0:0:0:0: [sda] tag#11 Sense Key : 0x3 [current] [descriptor] 
    [   14.043614] sd 0:0:0:0: [sda] tag#11 ASC=0x11 ASCQ=0x4 
    [   14.076641] sd 0:0:0:0: [sda] tag#11 CDB: opcode=0x28 28 00 00 00 00 20 00 00 08 00
    [   14.084348] blk_update_request: I/O error, dev sda, sector 32
    [   14.116524] Buffer I/O error on dev sda, logical block 4, async page read
    [  OK  ] Started uim-sysfs.service.
    [   14.146981] ata1: EH complete
    [   14.266765] ata1.00: exception Emask 0x0 SAct 0x4000000 SErr 0x0 action 0x0
    [   14.273769] ata1.00: irq_stat 0x40000000
    [   14.296517] ata1.00: failed command: READ FPDMA QUEUED
    [   14.301697] ata1.00: cmd 60/08:d0:20:00:00/00:00:00:00:00/40 tag 26 ncq 4096 in
    [   14.301697]          res 41/40:d0:20:00:00/00:00:00:00:00/40 Emask 0x409 (media error) <F>
    [   14.356512] ata1.00: status: { DRDY ERR }
    [   14.360548] ata1.00: error: { UNC }
    [   14.386722] ata1.00: configured for UDMA/133
    [   14.391054] sd 0:0:0:0: [sda] tag#26 UNKNOWN(0x2003) Result: hostbyte=0x00 driverbyte=0x08
    [   14.416524] sd 0:0:0:0: [sda] tag#26 Sense Key : 0x3 [current] [descriptor] 
    [   14.423617] sd 0:0:0:0: [sda] tag#26 ASC=0x11 ASCQ=0x4 
    [   14.456552] sd 0:0:0:0: [sda] tag#26 CDB: opcode=0x28 28 00 00 00 00 20 00 00 08 00
    [   14.464263] blk_update_request: I/O error, dev sda, sector 32
    [   14.486580] Buffer I/O error on dev sda, logical block 4, async page read
    [   14.493472] ata1: EH complete
    [   14.574402] usbcore: registered new interface driver usbfs
    [   14.590208] usbcore: registered new interface driver hub
    [   14.607902] usbcore: registered new device driver usb
    [   14.617642] ata1.00: exception Emask 0x0 SAct 0x1000 SErr 0x0 action 0x0
    [   14.624393] ata1.00: irq_stat 0x40000000
    [   14.636601] ata1.00: failed command: READ FPDMA QUEUED
    [   14.641789] ata1.00: cmd 60/08:60:20:00:00/00:00:00:00:00/40 tag 12 ncq 4096 in
    [   14.641789]          res 41/40:60:20:00:00/00:00:00:00:00/40 Emask 0x409 (media error) <F>
    [   14.675191] xhci-hcd xhci-hcd.0.auto: xHCI Host Controller
    [   14.688141] xhci-hcd xhci-hcd.0.auto: new USB bus registered, assigned bus number 1
    [   14.696277] xhci-hcd xhci-hcd.0.auto: hcc params 0x0220f04c hci version 0x100 quirks 0x00210010
    [   14.716867] ata1.00: status: { DRDY ERR }
    [   14.720903] ata1.00: error: { UNC }
    [   14.736609] xhci-hcd xhci-hcd.0.auto: irq 429, io mem 0x48890000
    [   14.742932] ata1.00: configured for UDMA/133
    [   14.749261] sd 0:0:0:0: [sda] tag#12 UNKNOWN(0x2003) Result: hostbyte=0x00 driverbyte=0x08
    [   14.767640] sd 0:0:0:0: [sda] tag#12 Sense Key : 0x3 [current] [descriptor] 
    [   14.776265] hub 1-0:1.0: USB hub found
    [   14.784430] hub 1-0:1.0: 1 port detected
    [   14.806619] sd 0:0:0:0: [sda] tag#12 ASC=0x11 ASCQ=0x4 
    [   14.807696] xhci-hcd xhci-hcd.0.auto: xHCI Host Controller
    [   14.807713] xhci-hcd xhci-hcd.0.auto: new USB bus registered, assigned bus number 2
    [   14.807813] usb usb2: We don't know the algorithms for LPM for this host, disabling LPM.
    [   14.808652] hub 2-0:1.0: USB hub found
    [   14.808690] hub 2-0:1.0: 1 port detected
    [   14.809831] xhci-hcd xhci-hcd.1.auto: xHCI Host Controller
    [   14.809854] xhci-hcd xhci-hcd.1.auto: new USB bus registered, assigned bus number 3
    [   14.855492] xhci-hcd xhci-hcd.1.auto: hcc params 0x0220f04c hci version 0x100 quirks 0x00210010
    [   14.870311] xhci-hcd xhci-hcd.1.auto: irq 430, io mem 0x488d0000
    [   14.882114] sd 0:0:0:0: [sda] tag#12 CDB: opcode=0x28 28 00 00 00 00 20 00 00 08 00
    [   14.893186] blk_update_request: I/O error, dev sda, sector 32
    [   14.894660] hub 3-0:1.0: USB hub found
    [   14.894699] hub 3-0:1.0: 1 port detected
    [   14.895763] xhci-hcd xhci-hcd.1.auto: xHCI Host Controller
    [   14.895778] xhci-hcd xhci-hcd.1.auto: new USB bus registered, assigned bus number 4
    [   14.895876] usb usb4: We don't know the algorithms for LPM for this host, disabling LPM.
    [   14.906722] hub 4-0:1.0: USB hub found
    [   14.906759] hub 4-0:1.0: 1 port detected
             Starting rc.pvr.service...
    [  OK  ] Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch.
    [  OK  ] Created slice system-systemd\x2dbacklight.slice.
             Starting Load/Save Screen Backlight...htness of backlight:backlight...
    [  OK  ] Reached target Network.
             Starting Lightning Fast Webserver With Light System Requirements...
             Starting Network Name Resolution...
    [   15.095644] Buffer I/O error on dev sda, logical block 4, async page read
    [   15.115678] ata1: EH complete
    [  OK  ] Started Serial Getty on ttyS3.
    [  OK  ] Reached target Login Prompts.
    [  OK  ] Started Load/Save Screen Backlight Brightness of backlight:backlight.
    [  OK  ] Started Login Service.
    [  OK  ] Started Lightning Fast Webserver With Light System Requirements.
    [  OK  ] Started Network Name Resolution.
    [   15.441440] PVR_K: UM DDK-(3699939) and KM DDK-(3699939) match. [ OK ]
    [   15.500589] EXT4-fs (sda1): mounted filesystem with ordered data mode. Opts: (null)
    [  OK  ] Started rc.pvr.service.
             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.
    [   15.994781] random: nonblocking pool is initialized
             Starting gdbserverproxy.service...
    [  OK  ] Started gdbserverproxy.service.
             Starting matrix-gui-2.0.service...
    [  OK  ] Started matrix-gui-2.0.service.
             Starting thermal-zone-init.service...
    [  OK  ] Started thermal-zone-init.service.
    [  OK  ] Reached target Multi-User System.
             Starting Update UTMP about System Runlevel Changes...
    [  OK  ] Started Update UTMP about System Runlevel Changes.
    
     _____                    _____           _         _   
    |  _  |___ ___ ___ ___   |  _  |___ ___  |_|___ ___| |_ 
    |     |  _| .'| . | . |  |   __|  _| . | | | -_|  _|  _|
    |__|__|_| |__,|_  |___|  |__|  |_| |___|_| |___|___|_|  
                  |___|                    |___|            
    
    Arago Project http://arago-project.org dra7xx-evm ttyS3
    
    Arago 2016.12 dra7xx-evm ttyS3
    
    dra7xx-evm login: root (automatic login)
    
    
    root@dra7xx-evm:~# [SYS ] iface=[sata-1], cmd=[sata], host arg=[] client arg=[]
    [SYS ] testing...[sata]
    [APP ] mkdir: can't create directory '/mnt/sata_mnt': File exists
    [   46.023282] EXT4-fs (sda1): mounted filesystem with ordered data mode. Opts: (null)
    [APP ] ========================================INFO 
    [APP ] ============= SATA test Send=============
    [APP ] INFO Checksum:		50
    [APP ] INFO Checksum:		50
    [APP ] INFO ========================================
    [APP ] INFO ========================================
    [APP ] INFO SATA test PASS!
    [APP ] [SATA] test complete
    
       

    Here is the complete boot log(with SATA mount fail)=> 

    U-Boot SPL 2016.05-g7716941-dirty (Oct 03 2018 - 11:44:58)
    DRA752-GP ES2.0
    no pinctrl for hs200_1_8v
    no pinctrl for ddr_1_8v
    @SD max clock = 10000000@
    @eMMC max clock = 10000000@
    *** Warning - bad CRC, using default environment
    
    Trying to boot from MMC1
    *** Warning - bad CRC, using default environment
    
    reading u-boot.img
    reading u-boot.img
    reading u-boot.img
    reading u-boot.img
    
    
    U-Boot 2016.05-g7716941-dirty (Oct 03 2018 - 11:44:58 +0800)
    
    CPU  : DRA752-GP ES2.0
    Model: TI DRA742
    Board: DRA74x EVM REV 
    DRAM:  2 GiB
    NAND:  0 MiB
    MMC:   OMAP SD/MMC: 0, OMAP SD/MMC: 1
    @eMMC max clock = 10000000@
    *** Warning - bad CRC, using default environment
    
    current ID is 0
    Warning: fastboot.board_rev: unknown board revision
    GUID Partition Table Header signature is wrong: 0x0 != 0x5452415020494645
    part_get_info_efi: *** ERROR: Invalid GPT ***
    GUID Partition Table Header signature is wrong: 0x0 != 0x5452415020494645
    part_get_info_efi: *** ERROR: Invalid Backup GPT ***
    ERROR: cannot find partition: 'userdata'
    
    at arch/arm/cpu/armv7/omap-common/utils.c:199/mmc_get_part_size()
    Warning: fastboot.userdata_size: unable to calc
    SCSI:  Target spinup took 0 ms.
    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...
      Device 0: (0:0) Vendor: ATA Prod.: 8GB SATA Flash D Rev: SFMA
                Type: Hard Disk
                Capacity: 7641.2 MB = 7.4 GB (15649200 x 512)
    Found 1 device(s).
    Net:   
    Warning: ethernet@48484000 using MAC address from ROM
    eth0: ethernet@48484000
    Hit any key to stop autoboot:  0 
    load MAN2020_original.dtb
    load MAN2020_2g.dtb
    switch to partitions #0, OK
    mmc0 is current device
    @SD max clock = 10000000@
    SD/MMC found on device 0
    reading boot.scr
    ** Unable to read file boot.scr **
    reading uEnv.txt
    222 bytes read in 3 ms (72.3 KiB/s)
    Loaded env from uEnv.txt
    Importing environment from mmc0 ...
    @SD max clock = 10000000@
    switch to partitions #0, OK
    mmc0 is current device
    @SD max clock = 10000000@
    SD/MMC found on device 0
    3639240 bytes read in 782 ms (4.4 MiB/s)
    111797 bytes read in 86 ms (1.2 MiB/s)
    Booting from mmc0 ...
    Kernel image @ 0x82000000 [ 0x000000 - 0x3787c8 ]
    ## Flattened Device Tree blob at 88000000
       Booting using the fdt blob at 0x88000000
       Loading Device Tree to 8ffe1000, end 8ffff4b4 ... 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.45-g55ec0e9-dirty (hsuyz@hsuyz-VirtualBox) (gcc version 5.3.1 20160113 (Linaro GCC 5.3-2016.02) ) #16 SMP PREEMPT Wed Oct 3 11:45:32 CST 2018
    [    0.000000] CPU: ARMv7 Processor [412fc0f2] revision 2 (ARMv7), cr=30c5387d
    [    0.000000] CPU: PIPT / VIPT nonaliasing data cache, PIPT instruction cache
    [    0.000000] Machine model: TI DRA742
    [    0.000000] cma: Reserved 64 MiB at 0x00000000fac00000
    [    0.000000] Forcing write-allocate cache policy for SMP
    [    0.000000] Memory policy: Data cache writealloc
    [    0.000000] DRA752 ES2.0
    [    0.000000] PERCPU: Embedded 11 pages/cpu @eed9f000 s14848 r8192 d22016 u45056
    [    0.000000] Built 1 zonelists in Zone order, mobility grouping on.  Total pages: 517952
    [    0.000000] Kernel command line: console=ttyO3,115200n8 elevator=noop root=/dev/mmcblk0p2 rw rootwait earlyprintk fixrtc omapdrm.num_crtc=2 consoleblank=0 cma=64M rootfstype=ext4 snd.slots_reserved=1,1
    [    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: 1983324K/2078720K available (6643K kernel code, 364K rwdata, 2472K rodata, 336K init, 302K bss, 29860K reserved, 65536K cma-reserved, 1226752K 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 - 0xc08ef09c   (9117 kB)
    [    0.000000]       .init : 0xc08f0000 - 0xc0944000   ( 336 kB)
    [    0.000000]       .data : 0xc0944000 - 0xc099f310   ( 365 kB)
    [    0.000000]        .bss : 0xc09a1000 - 0xc09eca78   ( 303 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 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.000005] sched_clock: 56 bits at 5MHz, resolution 169ns, wraps every 4398046511093ns
    [    0.000017] Switching to timer-based delay loop, resolution 169ns
    [    0.000336] clocksource: 32k_counter: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 58327039986419 ns
    [    0.000344] OMAP clocksource: 32k_counter at 32768 Hz
    [    0.000812] Console: colour dummy device 80x30
    [    0.000829] WARNING: Your 'console=ttyO3' has been replaced by 'ttyS3'
    [    0.000836] This ensures that you still see kernel messages. Please
    [    0.000841] update your kernel commandline.
    [    0.000855] Calibrating delay loop (skipped), value calculated using timer frequency.. 11.80 BogoMIPS (lpj=59016)
    [    0.000869] pid_max: default: 32768 minimum: 301
    [    0.000974] Mount-cache hash table entries: 2048 (order: 1, 8192 bytes)
    [    0.000985] Mountpoint-cache hash table entries: 2048 (order: 1, 8192 bytes)
    [    0.001575] Initializing cgroup subsys io
    [    0.001597] Initializing cgroup subsys memory
    [    0.001622] Initializing cgroup subsys devices
    [    0.001637] Initializing cgroup subsys freezer
    [    0.001648] Initializing cgroup subsys perf_event
    [    0.001660] Initializing cgroup subsys pids
    [    0.001689] CPU: Testing write buffer coherency: ok
    [    0.001925] /cpus/cpu@0 missing clock-frequency property
    [    0.001944] /cpus/cpu@1 missing clock-frequency property
    [    0.001955] CPU0: thread -1, cpu 0, socket 0, mpidr 80000000
    [    0.001993] Setting up static identity map for 0x800082c0 - 0x80008320
    [    0.080207] CPU1: thread -1, cpu 1, socket 0, mpidr 80000001
    [    0.080272] Brought up 2 CPUs
    [    0.080286] SMP: Total of 2 processors activated (23.60 BogoMIPS).
    [    0.080294] CPU: All CPU(s) started in HYP mode.
    [    0.080299] CPU: Virtualization extensions available.
    [    0.080702] devtmpfs: initialized
    [    0.111452] VFP support v0.3: implementor 41 architecture 4 part 30 variant f rev 0
    [    0.112541] omap_hwmod: l3_main_2 using broken dt data from ocp
    [    0.308300] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns
    [    0.309781] pinctrl core: initialized pinctrl subsystem
    [    0.310725] NET: Registered protocol family 16
    [    0.311750] DMA: preallocated 256 KiB pool for atomic coherent allocations
    [    0.340242] cpuidle: using governor ladder
    [    0.370267] cpuidle: using governor menu
    [    0.378658] OMAP GPIO hardware version 0.1
    [    0.382841] GPIO line 161 (radio_rst) hogged as output/low
    [    0.385197] irq: no irq domain found for /ocp/l4@4a000000/scm@2000/pinmux@1400 !
    [    0.411194] hw-breakpoint: found 5 (+1 reserved) breakpoint and 4 watchpoint registers.
    [    0.411205] hw-breakpoint: maximum watchpoint size is 8 bytes.
    [    0.412049] OMAP DMA hardware revision 0.0
    [    0.451786] omap-dma-engine 4a056000.dma-controller: OMAP DMA engine driver (LinkedList1/2/3 supported)
    [    0.453012] edma 43300000.edma: memcpy is disabled
    [    0.457871] edma 43300000.edma: TI EDMA DMA engine driver
    [    0.465174] palmas 0-0058: IRQ missing: skipping irq request
    [    0.480799] palmas 0-0058: Muxing GPIO a, PWM 0, LED 0
    [    0.480920] @@@@1
    [    0.481140] @@@@3
    [    0.481151] @@@@4
    [    0.481160] @@@@5
    [    0.481530] @@@@6
    [    0.481694] @@@@7
    [    0.490346] @@@@8
    [    0.552470] @@@@9(0)
    [    0.552489] @@@@2
    [    0.552765] omap_i2c 48070000.i2c: bus 0 rev0.12 at 400 kHz
    [    0.553211] omap_i2c 48072000.i2c: bus 1 rev0.12 at 400 kHz
    [    0.553587] omap_i2c 48060000.i2c: bus 2 rev0.12 at 400 kHz
    [    0.553958] omap_i2c 4807a000.i2c: bus 3 rev0.12 at 400 kHz
    [    0.554324] omap_i2c 4807c000.i2c: bus 4 rev0.12 at 400 kHz
    [    0.554527] media: Linux media interface: v0.10
    [    0.554580] Linux video capture interface: v2.00
    [    0.554620] pps_core: LinuxPPS API ver. 1 registered
    [    0.554627] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>
    [    0.554651] PTP clock support registered
    [    0.554699] EDAC MC: Ver: 3.0.0
    [    0.555506] Advanced Linux Sound Architecture Driver Initialized.
    [    0.556444] clocksource: Switched to clocksource arch_sys_counter
    [    0.567189] NET: Registered protocol family 2
    [    0.567717] TCP established hash table entries: 8192 (order: 3, 32768 bytes)
    [    0.567780] TCP bind hash table entries: 8192 (order: 4, 65536 bytes)
    [    0.567906] TCP: Hash tables configured (established 8192 bind 8192)
    [    0.567964] UDP hash table entries: 512 (order: 2, 16384 bytes)
    [    0.567996] UDP-Lite hash table entries: 512 (order: 2, 16384 bytes)
    [    0.568222] NET: Registered protocol family 1
    [    0.568497] RPC: Registered named UNIX socket transport module.
    [    0.568508] RPC: Registered udp transport module.
    [    0.568515] RPC: Registered tcp transport module.
    [    0.568521] RPC: Registered tcp NFSv4.1 backchannel transport module.
    [    0.569614] hw perfevents: enabled with armv7_cortex_a15 PMU driver, 7 counters available
    [    0.572333] futex hash table entries: 512 (order: 3, 32768 bytes)
    [    0.580168] squashfs: version 4.0 (2009/01/31) Phillip Lougher
    [    0.580841] NFS: Registering the id_resolver key type
    [    0.580873] Key type id_resolver registered
    [    0.580881] Key type id_legacy registered
    [    0.580940] ntfs: driver 2.1.32 [Flags: R/O].
    [    0.582999] bounce: pool size: 64 pages
    [    0.583168] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247)
    [    0.583183] io scheduler noop registered (default)
    [    0.583196] io scheduler deadline registered
    [    0.583229] io scheduler cfq registered
    [    0.588695] pinctrl-single 4a003400.pinmux: 282 pins at pa fc003400 size 1128
    [    0.652254] Serial: 8250/16550 driver, 10 ports, IRQ sharing disabled
    [    0.655862] 4806a000.serial: ttyS0 at MMIO 0x4806a000 (irq = 299, base_baud = 3000000) is a 8250
    [    0.656817] 4806c000.serial: ttyS1 at MMIO 0x4806c000 (irq = 300, base_baud = 3000000) is a 8250
    [    0.657676] 48020000.serial: ttyS2 at MMIO 0x48020000 (irq = 301, base_baud = 3000000) is a 8250
    [    0.658505] console [ttyS3] disabled
    [    0.658552] 4806e000.serial: ttyS3 at MMIO 0x4806e000 (irq = 302, base_baud = 3000000) is a 8250
    [    1.448427] console [ttyS3] enabled
    [    1.452803] 48066000.serial: ttyS4 at MMIO 0x48066000 (irq = 303, base_baud = 3000000) is a 8250
    [    1.462475] 48422000.serial: ttyS7 at MMIO 0x48422000 (irq = 304, base_baud = 3000000) is a 8250
    [    1.472129] 48424000.serial: ttyS8 at MMIO 0x48424000 (irq = 305, base_baud = 3000000) is a 8250
    [    1.481801] 4ae2b000.serial: ttyS9 at MMIO 0x4ae2b000 (irq = 306, base_baud = 3000000) is a 8250
    [    1.491933] [drm] Initialized drm 1.1.0 20060810
    [    1.498081] OMAP DSS rev 6.1
    [    1.501884] omapdss_dss 58000000.dss: bound 58001000.dispc (ops dispc_component_ops)
    [    1.518396] loop: module loaded
    [    1.527839] m25p80 spi32766.0: s25fl256s1 (32768 Kbytes)
    [    1.533244] 7 ofpart partitions found on MTD device spi32766.0
    [    1.539129] Creating 7 MTD partitions on "spi32766.0":
    [    1.544295] 0x000000000000-0x000000040000 : "QSPI.MLO"
    [    1.550396] 0x000000040000-0x000000140000 : "QSPI.u-boot"
    [    1.556726] 0x000000140000-0x0000001c0000 : "QSPI.DRA7-evm.dtb"
    [    1.563554] 0x0000001c0000-0x0000001d0000 : "QSPI.u-boot-env"
    [    1.570238] 0x0000001d0000-0x0000001e0000 : "QSPI.u-boot-env.backup1"
    [    1.577615] 0x0000001e0000-0x0000009e0000 : "QSPI.uImage"
    [    1.583877] 0x0000009e0000-0x000002000000 : "QSPI.IPU-exe"
    [    1.591338] libphy: Fixed MDIO Bus: probed
    [    1.596033] CAN device driver interface
    [    1.600298] pinctrl-single 4a003400.pinmux: pin 4a003818.0 already requested by 4a003400.pinmux; cannot claim for 4ae3c000.can
    [    1.611763] pinctrl-single 4a003400.pinmux: pin-262 (4ae3c000.can) status -22
    [    1.618952] pinctrl-single 4a003400.pinmux: could not request pin 262 (4a003818.0) from group dcan1_pins_sleep  on device pinctrl-single
    [    1.631281] c_can_platform 4ae3c000.can: Error applying setting, reverse things back
    [    1.639751] c_can_platform 4ae3c000.can: c_can_platform device registered (regs=fce3c000, irq=354)
    [    1.696481] davinci_mdio 48485000.mdio: davinci mdio revision 1.6
    [    1.702608] libphy: 48485000.mdio: probed
    [    1.706661] mdio_bus 48485000.mdio: /ocp/ethernet@48484000/mdio@48485000/micrel_phy_config has invalid PHY address
    [    1.717076] mdio_bus 48485000.mdio: /ocp/ethernet@48484000/mdio@48485000/rmii-ref has invalid PHY address
    [    1.726706] mdio_bus 48485000.mdio: scan phy micrel_phy_config at address 0
    [    1.735984] mdio_bus 48485000.mdio: scan phy micrel_phy_config at address 1
    [    1.743795] mdio_bus 48485000.mdio: scan phy micrel_phy_config at address 2
    [    1.751592] mdio_bus 48485000.mdio: scan phy micrel_phy_config at address 3
    [    1.759394] mdio_bus 48485000.mdio: scan phy micrel_phy_config at address 4
    [    1.768546] mdio_bus 48485000.mdio: scan phy micrel_phy_config at address 5
    [    1.776551] mdio_bus 48485000.mdio: scan phy micrel_phy_config at address 6
    [    1.784351] mdio_bus 48485000.mdio: scan phy micrel_phy_config at address 7
    [    1.792152] mdio_bus 48485000.mdio: scan phy micrel_phy_config at address 8
    [    1.799952] mdio_bus 48485000.mdio: scan phy micrel_phy_config at address 9
    [    1.807753] mdio_bus 48485000.mdio: scan phy micrel_phy_config at address 10
    [    1.815550] mdio_bus 48485000.mdio: scan phy micrel_phy_config at address 11
    [    1.823352] mdio_bus 48485000.mdio: scan phy micrel_phy_config at address 12
    [    1.831150] mdio_bus 48485000.mdio: scan phy micrel_phy_config at address 13
    [    1.838951] mdio_bus 48485000.mdio: scan phy micrel_phy_config at address 14
    [    1.846751] mdio_bus 48485000.mdio: scan phy micrel_phy_config at address 15
    [    1.854550] mdio_bus 48485000.mdio: scan phy micrel_phy_config at address 16
    [    1.862351] mdio_bus 48485000.mdio: scan phy micrel_phy_config at address 17
    [    1.870151] mdio_bus 48485000.mdio: scan phy micrel_phy_config at address 18
    [    1.877951] mdio_bus 48485000.mdio: scan phy micrel_phy_config at address 19
    [    1.885750] mdio_bus 48485000.mdio: scan phy micrel_phy_config at address 20
    [    1.893552] mdio_bus 48485000.mdio: scan phy micrel_phy_config at address 21
    [    1.901350] mdio_bus 48485000.mdio: scan phy micrel_phy_config at address 22
    [    1.909151] mdio_bus 48485000.mdio: scan phy micrel_phy_config at address 23
    [    1.916951] mdio_bus 48485000.mdio: scan phy micrel_phy_config at address 24
    [    1.924751] mdio_bus 48485000.mdio: scan phy micrel_phy_config at address 25
    [    1.932549] mdio_bus 48485000.mdio: scan phy micrel_phy_config at address 26
    [    1.940351] mdio_bus 48485000.mdio: scan phy micrel_phy_config at address 27
    [    1.948151] mdio_bus 48485000.mdio: scan phy micrel_phy_config at address 28
    [    1.955950] mdio_bus 48485000.mdio: scan phy micrel_phy_config at address 29
    [    1.963749] mdio_bus 48485000.mdio: scan phy micrel_phy_config at address 30
    [    1.971551] mdio_bus 48485000.mdio: scan phy micrel_phy_config at address 31
    [    1.979355] mdio_bus 48485000.mdio: scan phy rmii-ref at address 1
    [    1.986110] mdio_bus 48485000.mdio: scan phy rmii-ref at address 2
    [    1.992870] mdio_bus 48485000.mdio: scan phy rmii-ref at address 3
    [    1.999631] mdio_bus 48485000.mdio: scan phy rmii-ref at address 5
    [    2.006390] mdio_bus 48485000.mdio: scan phy rmii-ref at address 6
    [    2.013151] mdio_bus 48485000.mdio: scan phy rmii-ref at address 7
    [    2.019911] mdio_bus 48485000.mdio: scan phy rmii-ref at address 8
    [    2.026671] mdio_bus 48485000.mdio: scan phy rmii-ref at address 9
    [    2.033430] mdio_bus 48485000.mdio: scan phy rmii-ref at address 10
    [    2.040714] mdio_bus 48485000.mdio: scan phy rmii-ref at address 11
    [    2.047991] mdio_bus 48485000.mdio: scan phy rmii-ref at address 12
    [    2.055270] mdio_bus 48485000.mdio: scan phy rmii-ref at address 13
    [    2.062551] mdio_bus 48485000.mdio: scan phy rmii-ref at address 14
    [    2.069831] mdio_bus 48485000.mdio: scan phy rmii-ref at address 15
    [    2.077111] mdio_bus 48485000.mdio: scan phy rmii-ref at address 16
    [    2.084390] mdio_bus 48485000.mdio: scan phy rmii-ref at address 17
    [    2.091671] mdio_bus 48485000.mdio: scan phy rmii-ref at address 18
    [    2.098951] mdio_bus 48485000.mdio: scan phy rmii-ref at address 19
    [    2.106230] mdio_bus 48485000.mdio: scan phy rmii-ref at address 20
    [    2.113511] mdio_bus 48485000.mdio: scan phy rmii-ref at address 21
    [    2.120791] mdio_bus 48485000.mdio: scan phy rmii-ref at address 22
    [    2.128072] mdio_bus 48485000.mdio: scan phy rmii-ref at address 23
    [    2.135350] mdio_bus 48485000.mdio: scan phy rmii-ref at address 24
    [    2.142631] mdio_bus 48485000.mdio: scan phy rmii-ref at address 25
    [    2.149911] mdio_bus 48485000.mdio: scan phy rmii-ref at address 26
    [    2.157195] mdio_bus 48485000.mdio: scan phy rmii-ref at address 27
    [    2.164470] mdio_bus 48485000.mdio: scan phy rmii-ref at address 28
    [    2.171751] mdio_bus 48485000.mdio: scan phy rmii-ref at address 29
    [    2.179031] mdio_bus 48485000.mdio: scan phy rmii-ref at address 30
    [    2.186310] mdio_bus 48485000.mdio: scan phy rmii-ref at address 31
    [    2.193596] davinci_mdio 48485000.mdio: phy[0]: device 48485000.mdio:00, driver Micrel KSZ8081 or KSZ8091
    [    2.203221] davinci_mdio 48485000.mdio: phy[4]: device 48485000.mdio:04, driver Micrel KSZ8081 or KSZ8091
    [    2.213256] cpsw 48484000.ethernet: No slave[0] phy_id, phy-handle, or fixed-link property
    [    2.221729] cpsw 48484000.ethernet: Detected MACID = 74:e1:82:de:18:9e
    [    2.228392] cpsw 48484000.ethernet: cpts: overflow check period 800
    [    2.236605] mousedev: PS/2 mouse device common for all mice
    [    2.253223] input: LDC 3001 TouchScreen Controller as /devices/platform/44000000.ocp/48070000.i2c/i2c-0/0-0018/input/input0
    [    2.264848] i2c /dev entries driver
    [    2.269910] vpe 489d0000.vpe: loading firmware vpdma-1b8.bin
    [    2.280547] omap_hsmmc 4809c000.mmc: Got CD GPIO
    [    2.286661] vpe 489d0000.vpe: Device registered as /dev/video0
    [    2.327176] omap_hsmmc 480b4000.mmc: no pinctrl state for sdr25 mode
    [    2.333562] omap_hsmmc 480b4000.mmc: no pinctrl state for sdr12 mode
    [    2.340133] evm_3v3_sw: supplied by sysen1
    [    2.376820] omap_hsmmc 480ad000.mmc: no pinctrl state for sdr50 mode
    [    2.383206] omap_hsmmc 480ad000.mmc: no pinctrl state for sdr25 mode
    [    2.389606] omap_hsmmc 480ad000.mmc: no pinctrl state for sdr12 mode
    [    2.395989] omap_hsmmc 480ad000.mmc: no pinctrl state for hs mode
    [    2.437154] GPIO line 511 (LVDS_CLKSel) hogged as output/high
    [    2.443465] ledtrig-cpu: registered to indicate activity on CPUs
    [    2.453722] hwspinlock_user gatemp: requested 10 hwspinlocks
    [    2.460225] 
    [    2.460225] [spidrv_init]
    [    2.464341] spidrv_init called
    [    2.467431] 
    [    2.467431] [mcaspdrv_init]
    [    2.471718] mcaspdrv_init called
    [    2.476092] davinci-mcasp 48460000.mcasp: DAI is shared
    [    2.481440] 
    [    2.481440] [McASP1][mcasp->base][FA460000]
    [    2.487158] 
    [    2.487158] [non return]
    [    2.491779] davinci-mcasp 48464000.mcasp: DAI is shared
    [    2.497086] 
    [    2.497086] [McASP2][mcasp->base][FA464000]
    [    2.497086] 
    [    2.504254] 
    [    2.504254] [non return]
    [    2.508536] davinci-mcasp 48468000.mcasp: DAI is shared
    [    2.513800] 
    [    2.513800] [McASP3][mcasp->base][FA468000]
    [    2.513800] 
    [    2.520999] 
    [    2.520999] [non return]
    [    2.526646] NET: Registered protocol family 10
    [    2.541939] sit: IPv6 over IPv4 tunneling driver
    [    2.547151] NET: Registered protocol family 17
    [    2.551629] can: controller area network core (rev 20120528 abi 9)
    [    2.557928] NET: Registered protocol family 29
    [    2.562410] can: raw protocol (rev 20120528)
    [    2.566945] Key type dns_resolver registered
    [    2.571390] omap_voltage_late_init: Voltage driver support not added
    [    2.578453] Power Management for TI OMAP4+ devices.
    [    2.583615] Registering SWP/SWPB emulation handler
    [    2.591428] dmm 4e000000.dmm: initialized all PAT entries
    [    2.640200] [drm] Supports vblank timestamp caching Rev 2 (21.10.2013).
    [    2.646895] [drm] No driver support for vblank timestamp query.
    [    2.652863] mmc0: host does not support reading read-only switch, assuming write-enable
    [    2.662949] @SD max clock = 10000000@
    [    2.666763] mmc0: new high speed SDHC card at address 59b4
    [    2.667954] [drm] Enabling DMM ywrap scrolling
    [    2.687407] mmcblk0: mmc0:59b4 SD    14.7 GiB 
    [    2.689221]  mmcblk0: p1 p2
    [    2.730219] mmc1: MAN_BKOPS_EN bit is not set
    [    2.731190] @eMMC max clock = 25000000@
    [    2.731264] mmc1: new MMC card at address 0001
    [    2.731712] mmcblk1: mmc1:0001 MMC04G 3.58 GiB 
    [    2.741961] mmcblk1boot0: mmc1:0001 MMC04G partition 1 16.0 MiB
    [    2.752223] mmcblk1boot1: mmc1:0001 MMC04G partition 2 16.0 MiB
    [    2.754218]  mmcblk1: p1 p2
    [    2.769037] Console: switching to colour frame buffer device 160x50
    [    2.825078] omapdrm omapdrm.0: fb0: omapdrm frame buffer device
    [    2.862734] [drm] Initialized omapdrm 1.0.0 20110917 on minor 0
    [    2.879285] backlight supply power not found, using dummy regulator
    [    2.885877] leds backlight: Failed setting brightness
    [    2.891786] hctosys: unable to open rtc device (rtc0)
    [    2.909390] aic_dvdd: disabling
    [    2.912555] vmmcwl_fixed: disabling
    [    2.916304] ldousb: disabling
    [    2.919847] ALSA device list:
    [    2.922828]   No soundcards found.
    [    3.017417] EXT4-fs (mmcblk0p2): mounted filesystem with ordered data mode. Opts: (null)
    [    3.025580] VFS: Mounted root (ext4 filesystem) on device 179:2.
    [    3.064486] devtmpfs: mounted
    [    3.067777] Freeing unused kernel memory: 336K (c08f0000 - c0944000)
    [    3.074165] This architecture does not have kernel memory protection.
    [    4.029363] systemd[1]: System time before build time, advancing clock.
    [    4.169476] random: systemd: uninitialized urandom read (16 bytes read, 14 bits of entropy available)
    [    4.183280] random: systemd: uninitialized urandom read (16 bytes read, 14 bits of entropy available)
    [    4.195158] 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)
    [    4.213723] systemd[1]: Detected architecture arm.
    
    Welcome to Arago 2016.12!
    
    [    4.248081] systemd[1]: Set hostname to <dra7xx-evm>.
    [    4.442939] random: systemd-gpt-aut: uninitialized urandom read (16 bytes read, 16 bits of entropy available)
    [    4.453135] random: systemd-gpt-aut: uninitialized urandom read (16 bytes read, 16 bits of entropy available)
    [    4.463250] random: systemd-sysv-ge: uninitialized urandom read (16 bytes read, 16 bits of entropy available)
    [    4.527671] random: systemd-sysv-ge: uninitialized urandom read (16 bytes read, 17 bits of entropy available)
    [    4.551922] random: systemd-sysv-ge: uninitialized urandom read (16 bytes read, 18 bits of entropy available)
    [    4.839618] random: systemd: uninitialized urandom read (16 bytes read, 21 bits of entropy available)
    [    4.849213] random: systemd: uninitialized urandom read (16 bytes read, 21 bits of entropy available)
    [    4.859199] random: systemd: uninitialized urandom read (16 bytes read, 21 bits of entropy available)
    [    5.031149] systemd[1]: Configuration file /etc/systemd/system/multi-user.target.wants/MAN2020.service is marked executable. Please remove executable permission bits. Proceeding anyway.
    [    5.047870] systemd[1]: Configuration file /etc/systemd/system/multi-user.target.wants/MAN2020.service is marked world-writable. Please remove world writability permission bits. Proceeding anyway.
    [    5.067745] systemd[1]: gplv3-notice.service: Service lacks both ExecStart= and ExecStop= setting. Refusing.
    [    5.208203] systemd[1]: gplv3-notice.service: Cannot add dependency job, ignoring: Unit gplv3-notice.service is not loaded properly: Invalid argument.
    [    5.222629] systemd[1]: sysinit.target: Found ordering cycle on sysinit.target/start
    [    5.230447] systemd[1]: sysinit.target: Found dependency on alignment.service/start
    [    5.238185] systemd[1]: sysinit.target: Found dependency on sysinit.target/start
    [    5.245626] systemd[1]: sysinit.target: Breaking ordering cycle by deleting job alignment.service/start
    [    5.255094] 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
    [    5.289299] systemd[1]: Listening on Journal Socket (/dev/log).
    [  OK  ] Listening on Journal Socket (/dev/log).
    [    5.316735] systemd[1]: Listening on Syslog Socket.
    [  OK  ] Listening on Syslog Socket.
    [    5.336733] systemd[1]: Listening on udev Control Socket.
    [  OK  ] Listening on udev Control Socket.
    [    5.366610] systemd[1]: Reached target Remote File Systems.
    [  OK  ] Reached target Remote File Systems.
    [    5.386723] systemd[1]: Listening on Journal Socket.
    [  OK  ] Listening on Journal Socket.
    [    5.406880] systemd[1]: Listening on Network Service Netlink Socket.
    [  OK  ] Listening on Network Service Netlink Socket.
    [    5.426842] systemd[1]: Started Forward Password Requests to Wall Directory Watch.
    [  OK  ] Started Forward Password Requests to Wall Directory Watch.
    [    5.448169] systemd[1]: Created slice System Slice.
    [  OK  ] Created slice System Slice.
    [    5.467348] systemd[1]: Created slice system-serial\x2dgetty.slice.
    [  OK  ] Created slice system-serial\x2dgetty.slice.
    [    5.516687] systemd[1]: Mounting Temporary Directory...
             Mounting Temporary Directory...
    [    5.646884] systemd[1]: Starting Load Kernel Modules...
             Starting Load Kernel Modules...
    [    5.669586] systemd[1]: Mounting POSIX Message Queue File System...
             Mounting POSIX Message Queue File System...
    [    5.699235] systemd[1]: Mounting Debug File System...
             Mounting Debug File System...
    [    5.755487] systemd[1]: Created slice User and Session Slice.
    [  OK  ] Created slice User and Session Slice.
    [    5.786655] systemd[1]: Reached target Slices.
    [  OK  ] Reached target Slices.
    [    5.836926] 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...
    [    5.866921] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.
    [  OK  ] Started Dispatch Password Requests to Console Directory Watch.
    [    5.896648] systemd[1]: Reached target Paths.
    [  OK  ] Reached target Paths.
    [    5.956906] systemd[1]: Starting Setup Virtual Console...
             Starting Setup Virtual Console...
    [    5.986792] systemd[1]: Listening on /dev/initctl Compatibility Named Pipe.
    [  OK  ] Listening on /dev/initctl Compatibility Named Pipe.
    [    6.016766] systemd[1]: Listening on udev Kernel Socket.
    [  OK  ] Listening on udev Kernel Socket.
    [    6.046634] systemd[1]: Reached target Swap.
    [  OK  ] Reached target Swap.
    [    6.096897] systemd[1]: Starting Remount Root and Kernel File Systems...
             Starting Remount Root and Kernel File Systems...
    [    6.119027] EXT4-fs (mmcblk0p2): re-mounted. Opts: (null)
    [    6.129507] systemd[1]: Starting Journal Service...
             Starting Journal Service...
    [    6.164037] systemd[1]: Mounted Debug File System.
    [  OK  ] Mounted Debug File System.
    [    6.186730] systemd[1]: Mounted POSIX Message Queue File System.
    [  OK  ] Mounted POSIX Message Queue File System.
    [    6.216665] systemd[1]: Mounted Temporary Directory.
    [  OK  ] Mounted Temporary Directory.
    [    6.247304] systemd[1]: systemd-modules-load.service: Main process exited, code=exited, status=1/FAILURE
    [    6.258259] systemd[1]: Failed to start Load Kernel Modules.
    [FAILED] Failed to start Load Kernel Modules.
    See 'systemctl status systemd-modules-load.service' for details.
    [    6.306628] systemd[1]: systemd-modules-load.service: Unit entered failed state.
    [    6.314084] systemd[1]: systemd-modules-load.service: Failed with result 'exit-code'.
    [    6.323695] systemd[1]: Started Create list of required static device nodes for the current kernel.
    [  OK  ] Started Create list of required sta...ce nodes for the current kernel.
    [    6.357968] systemd[1]: Started Setup Virtual Console.
    [  OK  ] Started Setup Virtual Console.
    [    6.387859] systemd[1]: Started Remount Root and Kernel File Systems.
    [  OK  ] Started Remount Root and Kernel File Systems.
    [    6.417122] systemd[1]: Started Journal Service.
    [  OK  ] Started Journal Service.
             Starting udev Coldplug all Devices...
             Starting Flush Journal to Persistent Storage...
             Starting Create Static Device Nodes in /dev...
             Starting Apply Kernel Variables...
    [    6.994930] systemd-journald[128]: Received request to flush runtime journal from PID 1
    [  OK  ] Started Flush Journal to Persistent Storage.
    [  OK  ] Started Apply Kernel Variables.
    [  OK  ] Started Create Static Device Nodes in /dev.
    [  OK  ] Reached target Local File Systems (Pre).
             Mounting /media/ram...
             Mounting /var/volatile...
             Starting udev Kernel Device Manager...
    [  OK  ] Mounted /var/volatile.
    [  OK  ] Mounted /media/ram.
    [  OK  ] Started udev Coldplug all Devices.
    [  OK  ] Started udev Kernel Device Manager.
    [  OK  ] Reached target Local File Systems.
             Starting Create Volatile Files and Directories...
             Starting Load/Save Random Seed...
    [  OK  ] Started Load/Save Random Seed.
    [  OK  ] Started Create Volatile Files and Directories.
             Starting Network Time Synchronization...
             Starting Update UTMP about System Boot/Shutdown...
    [  OK  ] Started Network Time Synchronization.
    [    8.231737] omap-des 480a5000.des: OMAP DES hw accel rev: 2.2
    [    8.267493] alg: skcipher: setkey failed on test 5 for ecb-des-omap: flags=100
    [    8.286867] alg: skcipher-ddst: setkey failed on test 5 for ecb-des-omap: flags=100
    [  OK  ] Found device /dev/ttyS3.[    8.299598] alg: skcipher-ddst: setkey failed on test 5 for ecb-des-omap: flags=100
    
    [  OK  ] Reached target System Time Synchronized.
    [    8.413600] omap_rng 48090000.rng: OMAP Random Number Generator ver. 20
             Starting Synchronize System and HW clocks...
    [  OK  ] Started Update UTMP about System Boot/Shutdown.
    [FAILED] Failed to start Synchronize System and HW clocks.
    [    8.640188] omap_rtc 48838000.rtc: rtc core: registered 48838000.rtc as rtc0
    See 'systemctl status sync-clocks.service' for details.
    [  OK  ] Reached target System Initialization.
    [  OK  ] Listening on D-Bus System Message Bus Socket.
    [  OK  ] Listening on dropbear.socket.
    [  OK  ] Listening on Avahi mDNS/DNS-SD Stack Activation Socket.
    [  OK  ] Listening on RPCbind Server Activation Socket.
    [  OK  ] Reached target Sockets.
    [  OK  ] Reached target Basic System.
             Starting Avahi mDNS/DNS-SD Stack...
    [    8.928620] omap_wdt: OMAP Watchdog Timer Rev 0x01: initial timeout 60 sec
    [  OK  ] Started strongSwan IPsec IKEv1/IKEv2 daemon using ipsec.conf.
    [    8.961135] omap-sham 4b101000.sham: hw accel on OMAP rev 4.3
    [    8.968238] SCSI subsystem initialized
             Starting uim-sysfs.service...
    [  OK  ] Started D-Bus System Message Bus.
    [    9.996399] omap-aes 4b500000.aes: OMAP AES hw accel rev: 3.3
    [   10.142771] omap-aes 4b700000.aes: OMAP AES hw accel rev: 3.3
    [   10.176233] [drm] Initialized pvr 1.14.3699939 20110701 on minor 1
    [   10.187743] ahci 4a140000.sata: SSS flag set, parallel bus scan disabled
    [   10.196334] ahci 4a140000.sata: AHCI 0001.0300 32 slots 1 ports 3 Gbps 0x1 impl platform mode
    [   10.265729] ahci 4a140000.sata: flags: 64bit ncq sntf stag pm led clo only pmp pio slum part ccc apst 
    [  OK  ] Started Avahi mDNS/DNS-SD Stack.
             Starting Network Service...
    [   10.389844] scsi host0: ahci
    [   10.393273] ata1: SATA max UDMA/133 mmio [mem 0x4a140000-0x4a1410ff] port 0x100 irq 339
    [  10.405967] NET: Registered protocol family 15
             Starting hw-id.service...
    [  OK  ] Started Start helloworld.
    [  OK  ] Started System Logging Service.
             Starting Permit User Sessions...
    [  OK  ] Started Kernel Logging Service.
    [  OK  ] Started Daily Cleanup of Temporary Directories.
    [  OK  ] Reached target Timers.
             Starting Login Service...
             Starting Telephony service...
    
    
    [SYS ] paring option: token=3, option=(null)
    [INFO] R3 init, iface=/dev/ttyS7
    [INFO] R2 init
    [INFO] watchdog init
    [INFO] CLIENT ready
    [  OK  ] Started hw-id.service.
    [  OK  ] Started Permit User Sessions.
    [   10.996548] ata1: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
    [   11.004058] ata1.00: ATA-11: 8GB SATA Flash Drive, SFMA002A, max UDMA/133
    [   11.019137] net eth0: initializing cpsw version 1.15 (0)
    [   11.036546] ata1.00: 15649200 sectors, multi 16: LBA48 NCQ (depth 31/32)
    [   11.051172] net eth0: initialized cpsw ale version 1.4
    [   11.051177] net eth0: ALE Table size 1024
    [   11.053223] libphy: PHY  not found
    [   11.053232] net eth0: phy "" not found on slave 0, err -19
    [   11.076701] ata1.00: configured for UDMA/133
    [   11.097609] scsi 0:0:0:0: Direct-Access     ATA      8GB SATA Flash D 002A PQ: 0 ANSI: 5
    [   11.108212] Initializing XFRM netlink socket
    [   11.138329] net eth0: phy found : id is : 0x221560
    [   11.138524] cpsw-phy-sel 4a002554.cpsw-phy-sel: RMII External clock is not supported
    [   11.279622] EXT4-fs (mmcblk1p2): recovery complete
    [   11.279638] EXT4-fs (mmcblk1p2): mounted filesystem with ordered data mode. Opts: (null)
    [  OK  ] Started Network Service.
    [   11.640691] FAT-fs (mmcblk1p1): Volume was not properly unmounted. Some data may be corrupt. Please run fsck.
    [   11.809104] Bluetooth: Core ver 2.21
    [   11.812757] NET: Registered protocol family 31
    [   11.821100] FAT-fs (mmcblk0p1): Volume was not properly unmounted. Some data may be corrupt. Please run fsck.
    [   11.845483] Bluetooth: HCI device and connection manager initialized
    [   11.885095] Bluetooth: HCI socket layer initialized
    [   11.909518] Bluetooth: L2CAP socket layer initialized
    [   11.914659] Bluetooth: SCO socket layer initialized
    [   12.412091] sd 0:0:0:0: [sda] 15649200 512-byte logical blocks: (8.01 GB/7.46 GiB)
    [   12.429174] sd 0:0:0:0: [sda] Write Protect is off
    [   12.443480] sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
    [   12.480671]  sda: sda1
    [   12.488728] sd 0:0:0:0: [sda] Attached SCSI disk
    [   12.493584] @@USB2PHY_TERMINATION_CONTROL=0x220004
    [   12.618857] omap_l3_noc 44000000.ocp: L3 application error: target 5 mod:1 (unclearable)
    [   12.627052] omap_l3_noc 44000000.ocp: L3 debug error: target 5 mod:1 (unclearable)
    [  OK  ] Started uim-sysfs.service.
    [   12.963157] @@USB2PHY_TERMINATION_CONTROL=0x220004
    [   13.598539] usbcore: registered new interface driver usbfs
    [   13.646969] usbcore: registered new interface driver hub
    [   13.652450] usbcore: registered new device driver usb
    [   13.707415] xhci-hcd xhci-hcd.0.auto: xHCI Host Controller
    [   13.712962] xhci-hcd xhci-hcd.0.auto: new USB bus registered, assigned bus number 1
    [  OK  ] Started Telephony service.
    [  OK  ] Started Login Service.
    [  OK  ] Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch.
    [   13.741837] xhci-hcd xhci-hcd.0.auto: hcc params 0x0220f04c hci version 0x100 quirks 0x00210010
    [   13.741897] xhci-hcd xhci-hcd.0.auto: irq 429, io mem 0x48890000
    [   13.752010] hub 1-0:1.0: USB hub found
    [   13.753022] hub 1-0:1.0: 1 port detected
    [   13.754119] xhci-hcd xhci-hcd.0.auto: xHCI Host Controller
    [   13.754135] xhci-hcd xhci-hcd.0.auto: new USB bus registered, assigned bus number 2
    [   13.754226] usb usb2: We don't know the algorithms for LPM for this host, disabling LPM.
    [   13.755041] hub 2-0:1.0: USB hub found
    [   13.755081] hub 2-0:1.0: 1 port detected
    [   13.756146] xhci-hcd xhci-hcd.1.auto: xHCI Host Controller
    [   13.756170] xhci-hcd xhci-hcd.1.auto: new USB bus registered, assigned bus number 3
    [   13.757223] xhci-hcd xhci-hcd.1.auto: hcc params 0x0220f04c hci version 0x100 quirks 0x00210010
    [   13.757284] xhci-hcd xhci-hcd.1.auto: irq 430, io mem 0x488d0000
    [   13.758144] hub 3-0:1.0: USB hub found
    [   13.758182] hub 3-0:1.0: 1 port detected
    [   13.758985] xhci-hcd xhci-hcd.1.auto: xHCI Host Controller
    [   13.759000] xhci-hcd xhci-hcd.1.auto: new USB bus registered, assigned bus number 4
    [   13.759086] usb usb4: We don't know the algorithms for LPM for this host, disabling LPM.
    [   13.759782] hub 4-0:1.0: USB hub found
    [   13.759821] hub 4-0:1.0: 1 port detected
             Starting rc.pvr.service...
    [  OK  ] Created slice system-systemd\x2dbacklight.slice.
    [   14.057556] PVR_K: UM DDK-(3699939) and KM DDK-(3699939) match. [ OK ]
             Starting Load/Save Screen Backlight...htness of backlight:backlight...
    [  OK  ] Reached target Network.
             Starting Lightning Fast Webserver With Light System Requirements...
             Starting Network Name Resolution...
    [  OK  ] Started Serial Getty on ttyS3.
    [  OK  ] Reached target Login Prompts.
    [  OK  ] Started Load/Save Screen Backlight Brightness of backlight:backlight.
    [  OK  ] Started Lightning Fast Webserver With Light System Requirements.
    [  OK  ] Started rc.pvr.service.
    [  OK  ] Started Network Name Resolution.
             Starting tiipclad-daemon.service...
             Starting telnetd.service...
    [  OK  ] Started tiipclad-daemon.service.
    [  OK  ] Started telnetd.service.
             Starting thttpd.service...
    [  OK  ] Started thttpd.service.
             Starting rng-tools.service...
    [  OK  ] Started rng-tools.service.
             Starting gdbserverproxy.service...[   14.872609] random: nonblocking pool is initialized
    
    [  OK  ] Started gdbserverproxy.service.
             Starting matrix-gui-2.0.service...
    [  OK  ] Started matrix-gui-2.0.service.
             Starting thermal-zone-init.service...
    [  OK  ] Started thermal-zone-init.service.
    [  OK  ] Reached target Multi-User System.
             Starting Update UTMP about System Runlevel Changes...
    [  OK  ] Started Update UTMP about System Runlevel Changes.
    
     _____                    _____           _         _   
    |  _  |___ ___ ___ ___   |  _  |___ ___  |_|___ ___| |_ 
    |     |  _| .'| . | . |  |   __|  _| . | | | -_|  _|  _|
    |__|__|_| |__,|_  |___|  |__|  |_| |___|_| |___|___|_|  
                  |___|                    |___|            
    
    Arago Project http://arago-project.org dra7xx-evm ttyS3
    
    Arago 2016.12 dra7xx-evm ttyS3
    
    dra7xx-evm login: root (automatic login)
    
    
    root@dra7xx-evm:~# 
    [SYS ] iface=[sata-1], cmd=[sata], host arg=[] client arg=[]
    [SYS ] testing...[sata]
    [APP ] umount: /dev/sda1: not mounted
    [APP ] mkdir: can't create directory '/mnt/sata_mnt': File exists
    [   55.036495] ata1.00: exception Emask 0x0 SAct 0x78 SErr 0x0 action 0x6 frozen
    [   55.043680] ata1.00: failed command: READ FPDMA QUEUED
    [   55.050932] ata1.00: cmd 60/18:18:20:00:00/00:00:00:00:00/40 tag 3 ncq 12288 in
    [   55.050932]          res 40/00:00:00:00:00/00:00:00:00:00/00 Emask 0x4 (timeout)
    [   55.067271] ata1.00: status: { DRDY }
    [   55.070954] ata1.00: failed command: READ FPDMA QUEUED
    [   55.076122] ata1.00: cmd 60/38:20:40:00:00/00:00:00:00:00/40 tag 4 ncq 28672 in
    [   55.076122]          res 40/00:00:00:00:00/00:00:00:00:00/00 Emask 0x4 (timeout)
    [   55.093824] ata1.00: status: { DRDY }
    [   55.098346] ata1.00: failed command: READ FPDMA QUEUED
    [   55.103517] ata1.00: cmd 60/90:28:80:00:00/00:00:00:00:00/40 tag 5 ncq 73728 in
    [   55.103517]          res 40/00:00:00:00:00/00:00:00:00:00/00 Emask 0x4 (timeout)
    [   55.120479] ata1.00: status: { DRDY }
    [   55.124161] ata1.00: failed command: READ FPDMA QUEUED
    [   55.130853] ata1.00: cmd 60/02:30:02:08:00/00:00:00:00:00/40 tag 6 ncq 1024 in
    [   55.130853]          res 40/00:00:00:00:00/00:00:00:00:00/00 Emask 0x4 (timeout)
    [   55.151534] ata1.00: status: { DRDY }
    [   55.155224] ata1: hard resetting link
    [   65.146468] ata1: softreset failed (1st FIS failed)
    [   65.151374] ata1: hard resetting link
    
        

    Compare these two log, if mount success, then i can see the mount info(as below) at boot stage.

    => EXT4-fs (sda1): mounted filesystem with ordered data mode. Opts: (null)

    After booting up, we write a simple AP to test SATA interface. If the SATA device mount fail at boot stage, then i can't re-mount it at test AP. The only way is reboot.

    Fail happend randomly.

    Regards,

    Shawn

  • Shawn

    Even with the passing case, do see error being reported. Can you review if you are consistently seeing errors across multiple iterations of boot.
    Also, review if it is due to a faulty, bad quality connector. Check with shorter , good quality SATA cables and also the power supply for the SATA drive(if it is being powered from your board)
  • Hi Sriram,

    Srirama Govindarajan said:
    Can you review if you are consistently seeing errors across multiple iterations of boot.

    Yes.

    Srirama Govindarajan said:
    review if it is due to a faulty, bad quality connector. Check with shorter , good quality SATA cables and also the power supply for the SATA drive(if it is being powered from your board)

    Ok, i will check with our HW team.

    By the way, i want to know, if the situation happend(system fail to mount SATA device and i can't mount it through "mount" command), is there anyway to recover it without rebooting? Can i let system to re-mount SATA device, or let system to re-do the SATA probe process?

    P.S. Since our custom board have NAND flash and connect with GPMC pins of DRA74x, after i add GPMC node at dts file, then fail situation disappear(system can always mount SATA device for multiple iterations). Is there any relationship between SATA and GPMC interface? Will system probe sequence change due to add new GPMC node at dts file?

    Regards,

    Shawn

  • Hi Sriram,

    Currently, system will mount SATA device at boot sequence automatically. May i know which driver is response for mount SATA device?

    Can i set this driver as module instead of build-in? Because i want to load it manually after boot up.

    Regards,

    Shawn

  • Hi Sriram,

    Update the latest info.
    I found the driver which may related with SATA device => ahci_platform. This driver is already build as module.
    When i remove this module, then /dev/sda is disappear, and back again when i re-load this module. I have some questions,
    1.Am i right about the driver(ahci_platform) which response for SATA device probe?
    2.Can i disable SATA device probe at system booting up? Which mean i want to proceed SATA probe manually sometime later.
    3.Each time the SATA fail, will have "omap_l3_noc 44000000.ocp: L3 application error: target 5 mod:1 (unclearable)" error message, what error could cause this message appear?

    BTW, according to below TI website description, Gen-3 SATA device seems not guaranteed to be supported on OMAP5 and DRA7, and our SATA device is Revision 3.1(up to 6Gbps). Will it be the reason for error occur? 

    =>


     
    Regards,
    Shawn

  • Shawn
    You can build the sata driver(ahci_platform) as module and insert it later if need be. Please review and confirm if it consistently works when built as module. If it does, it might indicate some kind of instability when all system modules are initialized concurrently as part of the initiial bootup (SATA power supply?).
    On the SATA failure message - can you let me know when does this error occur ?
    Usually the noc interrupts are reported if any of the masters in the system are trying to access an invalid address (either the address itself is invalid or the peripheral you are trying to access does not have clock enabled)
  • Hi Sriram,

    Thank for your reply. The default SATA driver(ahci_platform) is build as module, but it will be loaded automatically by system at bootup. I don't know why(maybe loaded by service?). Do you know how to find out which service may load the SATA driver?

    Srirama Govindarajan said:
    On the SATA failure message - can you let me know when does this error occur ?

    The error message "omap_l3_noc" will show up at middle of bootup(at about 12~13 seconds), like the log i post before.

    Regards,

    Shawn

  • Shawn
    after the root filesystem is successfully mounted, startup scripts are run from the /etc/.. folder. You may want to check if any of the scripts are invoking insmod or modprobe for loading the SATA kernel module(and comment that out if need be)