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.

Linux/AM5726: Kernel crash on SPI command

Part Number: AM5726

Tool/software: Linux

Hello,

We are working on AM5726 custom board.

We have connected SPI slave, and communicating on that through SPI read/write command.

But we are getting below crash

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

[   77.688365] Length = 32
[   77.693206] ######################### FINAL Write DONE #####################
[   77.700590] BUG: unsupported FP instruction in kernel mode
[   77.706100] Internal error: Oops - undefined instruction: 0 [#1] PREEMPT SMP ARM
[   77.713523] Modules linked in: ov798cam rpmsg_proto 8021q cbc jitterentropy_rng drbg xfrm_user xfrm4_tunnel ipcomp xfrm_ipcomp esp4 ah4 xhci_pl)
[   77.779752] CPU: 0 PID: 1126 Comm: ov798_app_spifl Tainted: G           O    4.4.32-gadde2ca9f8 #9
[   77.788744] Hardware name: Generic DRA74X (Flattened Device Tree)
[   77.794859] task: ed8cb600 ti: eda98000 task.ti: eda98000
[   77.800280] PC is at vfp_reload_hw+0x30/0x44
[   77.804566] LR is at __und_usr_fault_32+0x0/0x8
[   77.809112] pc : [<c000ae2c>]    lr : [<c0014240>]    psr: a0000013
[   77.809112] sp : eda99fb0  ip : 00021f3c  fp : 00000000
[   77.820634] r10: eda981f8  r9 : c00142a0  r8 : eda99e30
[   77.825877] r7 : 00000001  r6 : c0982054  r5 : 00000000  r4 : ec4a40f8
[   77.832427] r3 : c0982058  r2 : b6e576c2  r1 : ecd08540  r0 : ed2d8b02
[   77.838978] Flags: NzCv  IRQs on  FIQs on  Mode SVC_32  ISA ARM  Segment user
[   77.846139] Control: 30c5387d  Table: adb33400  DAC: fffffffd
[   77.851905] Process ov798_app_spifl (pid: 1126, stack limit = 0xeda98210)
[   77.858718] Stack: (0xeda99fb0 to 0xeda9a000)
[   77.863089] 9fa0:                                     be9658f8 00011940 be9659d0 000b89e8
[   77.871299] 9fc0: 00000000 b6f2e4c0 b6f0fd00 00011940 be9659d0 be9659e4 b6f3c000 00000000
[   77.879507] 9fe0: 00021f3c be9658cc b6e6f457 b6e576c2 60000030 ffffffff e1805005 e3a0100f
[   77.887713] Backtrace: no frame pointer
[   77.891575] Code: 128aa080 e89a0162 e3110102 0a000003 (eee96a10)
[   77.897691] ---[ end trace 4d201cb41bd81d96 ]---
[   77.902325] note: ov798_app_spifl[1126] exited with preempt_count 2
Segmentation fault

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

 

We have gone through kgdb also and in that we are getting

Program received signal SIGSEGV, Segmentation fault.
[Switching to Thread 1420]
vfp_support_entry () at arch/arm/vfp/vfphw.S:164
164             VFPFMXR FPINST, r6              @ restore FPINST (only if FPEXC.EX is set)
(gdb) bt
#0  vfp_support_entry () at arch/arm/vfp/vfphw.S:164
#1  0xc0014240 in no_fp () at arch/arm/kernel/entry-armv.S:723
Backtrace stopped: previous frame identical to this frame (corrupt stack?)
 
 

Can you please guide us for the same error?, Do the needful.

 

Regards,

Prerak

  • What Linux version is this? What are the SPI commands you use? Please post the complete log file, SPI pinmux and SPI section of the device tree.
  • Hi Biser,

    We are using Linux Kernel 4.4.32.

    We are using below command to communicate with spi.

    status = spi_read(ginfo->spi, param->cmd, length);

    status = spi_write(ginfo->spi, param->cmd, length);

    Attached logs for booting, dmesg prints during crash and spi pinmux

    /* spi1 muxing */
    	spi1_pins_default: spi1_pins_default {
    		pinctrl-single,pins = <
    			0x3B0 (PIN_OUTPUT_PULLUP | MUX_MODE0) /* spi1_cs0.spi1_cs0 */
    			0x3A4 (PIN_INPUT_PULLUP | MUX_MODE0) /* spi1_sclk.spi1_sclk */
    			0x3AC (PIN_INPUT_PULLUP | MUX_MODE0) /* spi1_d0.spi1_d0 */
    			0x3A8 (PIN_OUTPUT_PULLUP | MUX_MODE0) /* spi1_d1.spi1_d1 */
    		>;
    	};
    
    /* spi2 muxing */
    	spi2_pins_default: spi2_pins_default {                     
    		pinctrl-single,pins = <                                
    			0x3CC (PIN_OUTPUT_PULLUP | MUX_MODE0) /* spi2_cs0.spi2_cs0 */      
    			0x3C0 (PIN_INPUT_PULLUP | MUX_MODE0) /* spi2_sclk.spi2_sclk */     
    			0x3C8 (PIN_INPUT_PULLDOWN | MUX_MODE0) /* spi2_d0.spi2_d0 */       
    			0x3C4 (PIN_OUTPUT_PULLUP | MUX_MODE0)  /*spi2_d1.spi2_d1 */        
    		>;                                     
    	};   
    
    .
    I2C:   ready
    DRAM:  2 GiB
    NAND:  1024 MiB
    MMC:   OMAP SD/MMC: 0, OMAP SD/MMC: 1
    initializing smps
    PHY is initialized
    OV798 Sensor reset 
    GPIO7_5 reset
    GPIO7_4 reset
    Main Filesystem is ok
    Value Read is 0x50
    Kernel is ok 
    Value Read is 0x50
    SCSI:  SATA link 0 timeout.
    AHCI 0001.0300 32 slots 1 ports 3 Gbps 0x1 impl SATA mode
    flags: 64bit ncq stag pm led clo only pmp pio slum part ccc apst 
    scanning bus for devices...
    Found 0 device(s).
    Net:   cpsw
    Hit any key to stop autoboot:  0 
    Booting from nand (Backup partition ) ...
    
    NAND read: device 0 offset 0x140000, size 0x40000
     262144 bytes read: OK
    
    NAND read: device 0 offset 0xc00000, size 0x800000
     8388608 bytes read: OK
    Kernel is ok 
    Value Read is 0x50
     WDT Started 
    Kernel image @ 0x82000000 [ 0x000000 - 0x371140 ]
    ## Flattened Device Tree blob at 88000000
       Booting using the fdt blob at 0x88000000
       Loading Device Tree to 8ffe4000, end 8ffff723 ... 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.32-gadde2ca9f8 (root@AHMCPU1852) (gcc version 5.3.1 20160113 (Linaro GCC 5.3-2016.02) ) #1 SMP PREEMPT Wed Jan 3 18:22:07 IST 2018
    [    0.000000] CPU: ARMv7 Processor [412fc0f2] revision 2 (ARMv7), cr=30c5387d
    [    0.000000] CPU: PIPT / VIPT nonaliasing data cache, PIPT instruction cache
    [    0.000000] Machine model: TI AM5728 BeagleBoard-X15
    [    0.000000] Reserved memory: created CMA memory pool at 0x0000000095800000, size 56 MiB
    [    0.000000] Reserved memory: initialized node ipu2_cma@95800000, compatible id shared-dma-pool
    [    0.000000] Reserved memory: created CMA memory pool at 0x0000000099000000, size 64 MiB
    [    0.000000] Reserved memory: initialized node dsp1_cma@99000000, compatible id shared-dma-pool
    [    0.000000] Reserved memory: created CMA memory pool at 0x000000009d000000, size 32 MiB
    [    0.000000] Reserved memory: initialized node ipu1_cma@9d000000, compatible id shared-dma-pool
    [    0.000000] Reserved memory: created CMA memory pool at 0x000000009f000000, size 8 MiB
    [    0.000000] Reserved memory: initialized node dsp2_cma@9f000000, compatible id shared-dma-pool
    [    0.000000] cma: Reserved 24 MiB at 0x00000000fe400000
    [    0.000000] Forcing write-allocate cache policy for SMP
    [    0.000000] Memory policy: Data cache writealloc
    [    0.000000] OMAP4: Map 0x00000000ffd00000 to fe600000 for dram barrier
    [    0.000000] DRA752 ES2.0
    [    0.000000] PERCPU: Embedded 11 pages/cpu @eed31000 s14848 r8192 d22016 u45056
    [    0.000000] Built 1 zonelists in Zone order, mobility grouping on.  Total pages: 472640
    [    0.000000] Kernel command line: console=ttyO2,115200n8 root=ubi0:rootfs ro ubi.mtd=NAND.file-system,4096 gdb kgdboc=tty02,115200 rootfstype=ubifs rootwait=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: 1680592K/1897472K available (6599K kernel code, 361K rwdata, 2412K rodata, 336K init, 314K bss, 28464K reserved, 188416K cma-reserved, 1283072K 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 - 0xc08d5044   (9013 kB)
    [    0.000000]       .init : 0xc08d6000 - 0xc092a000   ( 336 kB)
    [    0.000000]       .data : 0xc092a000 - 0xc09847b0   ( 362 kB)
    [    0.000000]        .bss : 0xc0986000 - 0xc09d4bc0   ( 315 kB)
    [    0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=2, Nodes=1
    [    0.000000] Preemptible hierarchical RCU implementation.
    [    0.000000]  Build-time adjustment of leaf fanout to 32.
    [    0.000000] NR_IRQS:16 nr_irqs:16 16
    [    0.000000] ti_dt_clocks_register: failed to lookup clock node gmac_gmii_ref_clk_div
    [    0.000000] OMAP clockevent source: timer1 at 32786 Hz
    [    0.000000] Architected cp15 timer(s) running at 6.14MHz (virt).
    [    0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x16af5adb9, max_idle_ns: 440795202250 ns
    [    0.000004] sched_clock: 56 bits at 6MHz, resolution 162ns, wraps every 4398046511023ns
    [    0.000015] Switching to timer-based delay loop, resolution 162ns
    [    0.000327] clocksource: 32k_counter: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 58327039986419 ns
    [    0.000334] OMAP clocksource: 32k_counter at 32768 Hz
    [    0.000747] Console: colour dummy device 80x30
    [    0.000763] WARNING: Your 'console=ttyO2' has been replaced by 'ttyS2'
    [    0.000768] This ensures that you still see kernel messages. Please
    [    0.000773] update your kernel commandline.
    [    0.000786] Calibrating delay loop (skipped), value calculated using timer frequency.. 12.29 BogoMIPS (lpj=61475)
    [    0.000799] pid_max: default: 32768 minimum: 301
    [    0.000892] Mount-cache hash table entries: 2048 (order: 1, 8192 bytes)
    [    0.000901] Mountpoint-cache hash table entries: 2048 (order: 1, 8192 bytes)
    [    0.001433] Initializing cgroup subsys io
    [    0.001449] Initializing cgroup subsys memory
    [    0.001472] Initializing cgroup subsys devices
    [    0.001485] Initializing cgroup subsys freezer
    [    0.001495] Initializing cgroup subsys perf_event
    [    0.001507] Initializing cgroup subsys pids
    [    0.001532] CPU: Testing write buffer coherency: ok
    [    0.001731] /cpus/cpu@0 missing clock-frequency property
    [    0.001746] /cpus/cpu@1 missing clock-frequency property
    [    0.001756] CPU0: thread -1, cpu 0, socket 0, mpidr 80000000
    [    0.001789] Setting up static identity map for 0x800082c0 - 0x80008318
    [    0.080088] CPU1: thread -1, cpu 1, socket 0, mpidr 80000001
    [    0.080148] Brought up 2 CPUs
    [    0.080161] SMP: Total of 2 processors activated (24.59 BogoMIPS).
    [    0.080167] CPU: All CPU(s) started in SVC mode.
    [    0.080536] devtmpfs: initialized
    [    0.107421] VFP support v0.3: implementor 41 architecture 4 part 30 variant f rev 0
    [    0.108350] omap_hwmod: l3_main_2 using broken dt data from ocp
    [    0.249135] omap_hwmod: gpu: _wait_target_ready failed: -16
    [    0.249147] omap_hwmod: gpu: cannot be enabled for reset (3)
    [    0.315520] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns
    [    0.319451] pinctrl core: initialized pinctrl subsystem
    [    0.320321] NET: Registered protocol family 16
    [    0.321257] DMA: preallocated 256 KiB pool for atomic coherent allocations
    [    0.350245] cpuidle: using governor ladder
    [    0.380275] cpuidle: using governor menu
    [    0.389436] OMAP GPIO hardware version 0.1
    [    0.395973] irq: no irq domain found for /ocp/l4@4a000000/scm@2000/pinmux@1400 !
    [    0.396401] irq: no irq domain found for /ocp/l4@4a000000/scm@2000/pinmux@1400 !
    [    0.396780] irq: no irq domain found for /ocp/l4@4a000000/scm@2000/pinmux@1400 !
    [    0.397164] irq: no irq domain found for /ocp/l4@4a000000/scm@2000/pinmux@1400 !
    [    0.397541] irq: no irq domain found for /ocp/l4@4a000000/scm@2000/pinmux@1400 !
    [    0.414375] omap-gpmc 50000000.gpmc: could not find pctldev for node /ocp/l4@4a000000/scm@2000/pinmux@1400/gpmc_pins_default, deferring probe
    [    0.422679] hw-breakpoint: found 5 (+1 reserved) breakpoint and 4 watchpoint registers.
    [    0.422689] hw-breakpoint: maximum watchpoint size is 8 bytes.
    [    0.423140] omap4_sram_init:Unable to allocate sram needed to handle errata I688
    [    0.423149] omap4_sram_init:Unable to get sram pool needed to handle errata I688
    [    0.423729] OMAP DMA hardware revision 0.0
    [    0.461340] omap-dma-engine 4a056000.dma-controller: OMAP DMA engine driver (LinkedList1/2/3 supported)
    [    0.462494] edma 43300000.edma: memcpy is disabled
    [    0.467226] edma 43300000.edma: TI EDMA DMA engine driver
    [    0.471166] omap-iommu 40d01000.mmu: 40d01000.mmu registered
    [    0.471347] omap-iommu 40d02000.mmu: 40d02000.mmu registered
    [    0.471502] omap-iommu 58882000.mmu: 58882000.mmu registered
    [    0.471657] omap-iommu 55082000.mmu: 55082000.mmu registered
    [    0.471932] omap-iommu 41501000.mmu: 41501000.mmu registered
    [    0.472119] omap-iommu 41502000.mmu: 41502000.mmu registered
    [    0.474977] palmas 0-0058: Irq flag is 0x00000008
    [    0.492065] palmas 0-0058: Muxing GPIO 2f, PWM 0, LED 0
    [    0.573989] omap_i2c 48070000.i2c: bus 0 rev0.12 at 400 kHz
    [    0.574386] omap_i2c 48072000.i2c: bus 1 rev0.12 at 400 kHz
    [    0.574936] omap_i2c 48060000.i2c: bus 2 rev0.12 at 400 kHz
    [    0.575128] media: Linux media interface: v0.10
    [    0.575180] Linux video capture interface: v2.00
    [    0.575223] pps_core: LinuxPPS API ver. 1 registered
    [    0.575230] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>
    [    0.575255] PTP clock support registered
    [    0.575309] EDAC MC: Ver: 3.0.0
    [    0.576051] omap-mailbox 4883c000.mailbox: omap mailbox rev 0x400
    [    0.576339] omap-mailbox 4883e000.mailbox: omap mailbox rev 0x400
    [    0.576629] omap-mailbox 48840000.mailbox: omap mailbox rev 0x400
    [    0.576913] omap-mailbox 48842000.mailbox: omap mailbox rev 0x400
    [    0.577238] Advanced Linux Sound Architecture Driver Initialized.
    [    0.578057] clocksource: Switched to clocksource arch_sys_counter
    [    0.588926] NET: Registered protocol family 2
    [    0.589422] TCP established hash table entries: 8192 (order: 3, 32768 bytes)
    [    0.589485] TCP bind hash table entries: 8192 (order: 4, 65536 bytes)
    [    0.589610] TCP: Hash tables configured (established 8192 bind 8192)
    [    0.589657] UDP hash table entries: 512 (order: 2, 16384 bytes)
    [    0.589688] UDP-Lite hash table entries: 512 (order: 2, 16384 bytes)
    [    0.589896] NET: Registered protocol family 1
    [    0.590163] RPC: Registered named UNIX socket transport module.
    [    0.590172] RPC: Registered udp transport module.
    [    0.590179] RPC: Registered tcp transport module.
    [    0.590184] RPC: Registered tcp NFSv4.1 backchannel transport module.
    [    0.591187] hw perfevents: enabled with armv7_cortex_a15 PMU driver, 7 counters available
    [    0.593571] futex hash table entries: 512 (order: 3, 32768 bytes)
    [    0.601113] squashfs: version 4.0 (2009/01/31) Phillip Lougher
    [    0.601743] NFS: Registering the id_resolver key type
    [    0.601766] Key type id_resolver registered
    [    0.601774] Key type id_legacy registered
    [    0.601829] ntfs: driver 2.1.32 [Flags: R/O].
    [    0.603742] bounce: pool size: 64 pages
    [    0.603895] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247)
    [    0.603910] io scheduler noop registered
    [    0.603922] io scheduler deadline registered
    [    0.603955] io scheduler cfq registered (default)
    [    0.609593] pinctrl-single 4a003400.pinmux: 282 pins at pa fc003400 size 1128
    [    0.612855] PCI host bridge /ocp/axi@0/pcie_rc@51000000 ranges:
    [    0.612865]   No bus range found for /ocp/axi@0/pcie_rc@51000000, using [bus 00-ff]
    [    0.612897]    IO 0x20003000..0x20012fff -> 0x00000000
    [    0.612917]   MEM 0x20013000..0x2fffffff -> 0x20013000
    [    0.643022] dra7-pcie 51000000.pcie_rc: link is not up
    [    0.643193] dra7-pcie 51000000.pcie_rc: PCI host bridge to bus 0000:00
    [    0.643206] pci_bus 0000:00: root bus resource [bus 00-ff]
    [    0.643216] pci_bus 0000:00: root bus resource [io  0x0000-0xffff]
    [    0.643225] pci_bus 0000:00: root bus resource [mem 0x20013000-0x2fffffff]
    [    0.643636] PCI: bus0: Fast back to back transfers disabled
    [    0.643756] PCI: bus1: Fast back to back transfers enabled
    [    0.643841] pci 0000:00:00.0: BAR 0: assigned [mem 0x20100000-0x201fffff]
    [    0.643856] pci 0000:00:00.0: BAR 1: assigned [mem 0x20020000-0x2002ffff]
    [    0.643868] pci 0000:00:00.0: PCI bridge to [bus 01]
    [    0.644085] pcieport 0000:00:00.0: Signaling PME through PCIe PME interrupt
    [    0.644702] PCI host bridge /ocp/axi@1/pcie@51800000 ranges:
    [    0.644712]   No bus range found for /ocp/axi@1/pcie@51800000, using [bus 00-ff]
    [    0.644743]    IO 0x30003000..0x30012fff -> 0x00000000
    [    0.644763]   MEM 0x30013000..0x3fffffff -> 0x30013000
    [    0.676364] dra7-pcie 51800000.pcie: link is not up
    [    0.676524] dra7-pcie 51800000.pcie: PCI host bridge to bus 0001:00
    [    0.676536] pci_bus 0001:00: root bus resource [bus 00-ff]
    [    0.676548] pci_bus 0001:00: root bus resource [io  0x10000-0x1ffff] (bus address [0x0000-0xffff])
    [    0.676557] pci_bus 0001:00: root bus resource [mem 0x30013000-0x3fffffff]
    [    0.676939] PCI: bus0: Fast back to back transfers disabled
    [    0.677052] PCI: bus1: Fast back to back transfers enabled
    [    0.677153] pci 0001:00:00.0: BAR 0: assigned [mem 0x30100000-0x301fffff]
    [    0.677168] pci 0001:00:00.0: BAR 1: assigned [mem 0x30020000-0x3002ffff]
    [    0.677179] pci 0001:00:00.0: PCI bridge to [bus 01]
    [    0.677386] pcieport 0001:00:00.0: Signaling PME through PCIe PME interrupt
    [    0.738140] Serial: 8250/16550 driver, 10 ports, IRQ sharing disabled
    [    0.741560] 48020000.serial: ttyS2 at MMIO 0x48020000 (irq = 303, base_baud = 3000000) is a 8250
    [    1.841845] console [ttyS2] enabled
    [    1.846191] 48420000.serial: ttyS6 at MMIO 0x48420000 (irq = 304, base_baud = 3000000) is a 8250
    [    1.855851] 48422000.serial: ttyS7 at MMIO 0x48422000 (irq = 305, base_baud = 3000000) is a 8250
    [    1.865515] 48424000.serial: ttyS8 at MMIO 0x48424000 (irq = 306, base_baud = 3000000) is a 8250
    [    1.875176] 4ae2b000.serial: ttyS9 at MMIO 0x4ae2b000 (irq = 307, base_baud = 3000000) is a 8250
    [    1.885245] KGDB: Registered I/O driver kgdboc
    [    1.918094] Entered ccflash_init
    [    1.921779] [drm] Initialized drm 1.1.0 20060810
    [    1.928425] OMAP DSS rev 6.1
    [    1.932198] omapdss_dss 58000000.dss: bound 58001000.dispc (ops dispc_component_ops)
    [    1.940666] omapdss_dss 58000000.dss: bound 58040000.encoder (ops hdmi5_component_ops)
    [    1.956635] loop: module loaded
    [    1.963432] #### spi_ov798 device init ####
    [    1.967676] ### In SPI PROBE ###
    [    1.970959] #### input-clock-freq: 32000000
    [    1.975160] #### pixel-clock-freq: 96000000
    [    1.979374] #### Before v4l2 #####
    [    1.982788] #### sd = ee2c0e00
    [    1.985854] #### After v4l2 #####
    [    1.989193] ### Before register ### 
    [    1.992782] ### After register ### 
    [    1.996283] #### ov798 sensor driver registered !! ####
    [    2.001567] #### spi_ov798 device registration DONE ####
    [    2.006898] #### spi_ov798 device init done ####
    [    2.011548] #### spi_camov798 device init ####
    [    2.016045] ### In SPI PROBE ###
    [    2.019320] #### input-clock-freq: 32000000
    [    2.023521] #### pixel-clock-freq: 96000000
    [    2.027720] #### Before v4l2 #####
    [    2.031145] #### sd = ee2c1000
    [    2.034212] #### After v4l2 #####
    [    2.037538] ### Before register ### 
    [    2.041209] ### After register ### 
    [    2.044711] #### camov798 sensor driver registered !! ####
    [    2.050269] #### spi_camov798 device registration DONE ####
    [    2.055863] #### spi_camov798 device init done ####
    [    2.061451] libphy: Fixed MDIO Bus: probed
    [    2.108091] davinci_mdio 48485000.mdio: davinci mdio revision 1.6
    [    2.114213] davinci_mdio 48485000.mdio: detected phy mask fffffff7
    [    2.132815] libphy: 48485000.mdio: probed
    [    2.136845] davinci_mdio 48485000.mdio: phy[3]: device 48485000.mdio:03, driver Micrel KSZ9031 Gigabit PHY
    [    2.147102] cpsw 48484000.ethernet: No slave[1] phy_id, phy-handle, or fixed-link property
    [    2.155451] cpsw 48484000.ethernet: Missing dual_emac_res_vlan in DT.
    [    2.161975] cpsw 48484000.ethernet: Using 2 as Reserved VLAN for 1 slave
    [    2.168746] cpsw 48484000.ethernet: Detected MACID = fc:0f:4b:9b:06:2e
    [    2.175395] cpsw 48484000.ethernet: cpts: overflow check period 800
    [    2.182397] cpsw 48484000.ethernet: cpsw: Detected MACID = fc:0f:4b:9b:06:2f
    [    2.190888] mousedev: PS/2 mouse device common for all mice
    [    2.196929] i2c /dev entries driver
    [    2.202234] tmp102 0-0048: error reading config register
    [    2.207579] tmp102: probe of 0-0048 failed with error -121
    [    2.215953] omap_hsmmc 4809c000.mmc: Got CD GPIO
    [    2.258643] omap_hsmmc 480b4000.mmc: no pinctrl state for sdr25 mode
    [    2.265027] omap_hsmmc 480b4000.mmc: no pinctrl state for sdr12 mode
    [    2.288069] omap_hsmmc 480b4000.mmc: no pinctrl state for ddr_1_8v mode
    [    2.294713] omap_hsmmc 480b4000.mmc: no pinctrl state for hs mode
    [    2.318066] omap_hsmmc 480b4000.mmc: no pinctrl state for hs200_1_8v mode
    [    2.325019] vdd_3v3: supplied by regen1
    [    2.368842] ledtrig-cpu: registered to indicate activity on CPUs
    [    2.378659] NET: Registered protocol family 10
    [    2.393979] sit: IPv6 over IPv4 tunneling driver
    [    2.399217] NET: Registered protocol family 17
    [    2.403902] Key type dns_resolver registered
    [    2.408365] omap_voltage_late_init: Voltage driver support not added
    [    2.415262] Adding alias for supply vdd,cpu0 -> vdd,4a003b20.oppdm
    [    2.421506] Adding alias for supply vbb,cpu0 -> vbb,4a003b20.oppdm
    [    2.428212] Adding alias for supply vdd,cpu0 -> vdd,4a003b20.oppdm
    [    2.434421] Adding alias for supply vbb,cpu0 -> vbb,4a003b20.oppdm
    [    2.442604] Power Management for TI OMAP4+ devices.
    [    2.447696] Registering SWP/SWPB emulation handler
    [    2.454568] dmm 4e000000.dmm: initialized all PAT entries
    [    2.463455] [drm] Supports vblank timestamp caching Rev 2 (21.10.2013).
    [    2.470166] [drm] No driver support for vblank timestamp query.
    [    2.578344] mmc0: host does not support reading read-only switch, assuming write-enable
    [    2.591298] mmc0: new high speed SDHC card at address aaaa
    [    2.605239] EDID block is all zeroes
    [    2.608897] mmcblk0: mmc0:aaaa ABLCD 29.7 GiB 
    [    2.611836] [drm] Enabling DMM ywrap scrolling
    [    2.622055]  mmcblk0: p1 p2 p3
    [    2.622471] Console: switching to colour frame buffer device 128x48
    [    2.635688] omapdrm omapdrm.0: fb0: omapdrm frame buffer device
    [    2.678323] [drm] Initialized omapdrm 1.0.0 20110917 on minor 0
    [    2.694625] omap-gpmc 50000000.gpmc: GPMC revision 6.0
    [    2.699809] gpmc_mem_init: disabling cs 0 mapped at 0x0-0x1000000
    [    2.706711] nand: device found, Manufacturer ID: 0x2c, Chip ID: 0xd3
    [    2.713161] nand: Micron MT29F8G08ABACAWP
    [    2.717215] nand: 1024 MiB, SLC, erase size: 256 KiB, page size: 4096, OOB size: 224
    [    2.725062] using OMAP_ECC_BCH16_CODE_HW ECC scheme
    [    2.730807] 14 ofpart partitions found on MTD device 8000000.nand
    [    2.736941] Creating 14 MTD partitions on "8000000.nand":
    [    2.742380] 0x000000000000-0x000000040000 : "NAND.SPL"
    [    2.758266] 0x000000040000-0x000000080000 : "NAND.SPL.backup1"
    [    2.774770] 0x000000080000-0x0000000c0000 : "NAND.SPL.backup2"
    [    2.791288] 0x0000000c0000-0x000000100000 : "NAND.SPL.backup3"
    [    2.807805] 0x000000100000-0x000000140000 : "NAND.u-boot-spl-os"
    [    2.824499] 0x000000140000-0x000000180000 : "NAND.u-boot-spl-os-backup"
    [    2.841783] 0x000000180000-0x000000280000 : "NAND.u-boot"
    [    2.857912] 0x000000280000-0x000000380000 : "NAND.u-boot-backup"
    [    2.874674] 0x000000380000-0x0000003c0000 : "NAND.u-boot-env"
    [    2.891095] 0x0000003c0000-0x000000400000 : "NAND.u-boot-env.backup1"
    [    2.903640] EDID block is all zeroes
    [    2.908238] 0x000000400000-0x000000c00000 : "NAND.kernel"
    [    2.924980] 0x000000c00000-0x000001400000 : "NAND.kernel-bkp"
    [    2.942081] 0x000001400000-0x000039700000 : "NAND.file-system"
    [    3.037920] 0x000039700000-0x000040000000 : "NAND.application"
    [    3.063838] ubi0: attaching mtd12
    [    5.968669] ubi0: scanning is finished
    [    5.980432] ubi0: attached mtd12 (name "NAND.file-system", size 899 MiB)
    [    5.987166] ubi0: PEB size: 262144 bytes (256 KiB), LEB size: 253952 bytes
    [    5.994080] ubi0: min./max. I/O unit sizes: 4096/4096, sub-page size 1024
    [    6.000901] ubi0: VID header offset: 4096 (aligned 4096), data offset: 8192
    [    6.007892] ubi0: good PEBs: 3589, bad PEBs: 7, corrupted PEBs: 0
    [    6.014014] ubi0: user volume: 1, internal volumes: 1, max. volumes count: 128
    [    6.021294] ubi0: max/mean erase counter: 1/0, WL threshold: 4096, image sequence number: 1272452213
    [    6.030468] ubi0: available PEBs: 0, total reserved PEBs: 3589, PEBs reserved for bad PEB handling: 73
    [    6.039857] ubi0: background thread "ubi_bgt0d" started, PID 82
    [    6.040154] hctosys: unable to open rtc device (rtc0)
    [    6.050601] vdd_3v3: disabling
    [    6.050605] aic_dvdd_fixed: disabling
    [    6.057768] ldousb: disabling
    [    6.061163] ALSA device list:
    [    6.064138]   No soundcards found.
    [    6.068367] System boot with main Kernel partition
    [    6.181199] UBIFS (ubi0:0): UBIFS: mounted UBI device 0, volume 0, name "rootfs", R/O mode
    [    6.189514] UBIFS (ubi0:0): LEB size: 253952 bytes (248 KiB), min./max. I/O unit sizes: 4096 bytes/4096 bytes
    [    6.199499] UBIFS (ubi0:0): FS size: 889339904 bytes (848 MiB, 3502 LEBs), journal size 9404416 bytes (8 MiB, 38 LEBs)
    [    6.210250] UBIFS (ubi0:0): reserved for root: 0 bytes (0 KiB)
    [    6.216106] UBIFS (ubi0:0): media format: w4/r0 (latest is w4/r0), UUID 538A53AB-C31D-4C08-A5D9-C5EB43298CA4, small LPT model
    [    6.230663] VFS: Mounted root (ubifs filesystem) readonly on device 0:15.
    [    6.237482] Filesystem Mounted Successfully So Disabling Watchdog Timer !! 
    [    6.244478] Watchdog Timer Disabled !! 
    [    6.248332] Now Watchdog Timer Enabled for init process !! 
    [    6.254090] Read from EEPROM at 0x08: 0x0
    [    6.258123] Write to EEPROM at 0x08: 0x1
    [    6.267263] Write to EEPROM at 0x04: 0x25
    [    6.274501] devtmpfs: mounted
    [    6.277612] Freeing unused kernel memory: 336K (c08d6000 - c092a000)
    [    6.284001] This architecture does not have kernel memory protection.
    [    6.537686] systemd[1]: System time before build time, advancing clock.
    [    6.608652] random: systemd: uninitialized urandom read (16 bytes read, 11 bits of entropy available)
    [    6.637072] random: systemd: uninitialized urandom read (16 bytes read, 11 bits of entropy available)
    [    6.647986] 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)
    [    6.666778] systemd[1]: Detected architecture arm.
    
    Welcome to Arago 2016.10!
    
    [    6.701875] systemd[1]: Set hostname to <am57xx-evm>.
    [    6.863866] random: systemd-sysv-ge: uninitialized urandom read (16 bytes read, 12 bits of entropy available)
    [    6.905842] random: systemd-sysv-ge: uninitialized urandom read (16 bytes read, 12 bits of entropy available)
    [    6.945883] random: systemd-sysv-ge: uninitialized urandom read (16 bytes read, 12 bits of entropy available)
    [    7.075461] random: systemd: uninitialized urandom read (16 bytes read, 12 bits of entropy available)
    [    7.084909] random: systemd: uninitialized urandom read (16 bytes read, 12 bits of entropy available)
    [    7.174900] random: systemd: uninitialized urandom read (16 bytes read, 12 bits of entropy available)
    [    7.184536] random: systemd: uninitialized urandom read (16 bytes read, 12 bits of entropy available)
    [    7.247820] random: systemd: uninitialized urandom read (16 bytes read, 12 bits of entropy available)
    [    7.446166] systemd[1]: sysinit.target: Found ordering cycle on sysinit.target/start
    [    7.453979] systemd[1]: sysinit.target: Found dependency on alignment.service/start
    [    7.461742] systemd[1]: sysinit.target: Found dependency on sysinit.target/start
    [    7.469192] systemd[1]: sysinit.target: Breaking ordering cycle by deleting job alignment.service/start
    [    7.478637] 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
    [    7.518105] systemd[1]: Listening on udev Control Socket.
    [  OK  ] Listening on udev Control Socket.
    [    7.538799] systemd[1]: systemd-networkd.socket: Socket service systemd-networkd.service not loaded, refusing.
    [    7.548900] systemd[1]: Failed to listen on Network Service Netlink Socket.
    [FAILED] Failed to listen on Network Service Netlink Socket.
    See 'systemctl status systemd-networkd.socket' for details.
    [    7.588233] systemd[1]: Listening on udev Kernel Socket.
    [  OK  ] Listening on udev Kernel Socket.
    [    7.608263] systemd[1]: Listening on Syslog Socket.
    [  OK  ] Listening on Syslog Socket.
    [    7.628336] systemd[1]: Started Forward Password Requests to Wall Directory Watch.
    [  OK  ] Started Forward Password Requests to Wall Directory Watch.
    [    7.650900] systemd[1]: Created slice System Slice.
    [  OK  ] Created slice System Slice.
    [    7.668680] systemd[1]: Created slice system-serial\x2dgetty.slice.
    [  OK  ] Created slice system-serial\x2dgetty.slice.
    [    7.688645] systemd[1]: Created slice system-getty.slice.
    [  OK  ] Created slice system-getty.slice.
    [    7.708282] systemd[1]: Listening on /dev/initctl Compatibility Named Pipe.
    [  OK  ] Listening on /dev/initctl Compatibility Named Pipe.
    [    7.754777] systemd[1]: Reached target Swap.
    [  OK  ] Reached target Swap.
    [    7.778323] systemd[1]: Listening on Journal Socket.
    [  OK  ] Listening on Journal Socket.
    [    7.838246] systemd[1]: Mounting Temporary Directory...
             Mounting Temporary Directory...
    [    7.886372] systemd[1]: Starting Load Kernel Modules...
             Starting Load Kernel Modules...
    [    7.920312] systemd[1]: Starting File System Check on Root Device...
             Starting File System Check on Root Device...
    [    7.939710] CMEMK module: reference Linux version 4.4.32
    [    7.945692] allocated heap buffer 0x40500000 of size 0x100000
    [    7.951629] cmemk initialized
    [    7.962020] 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...
    [    7.984181] systemd[1]: Starting Setup Virtual Console...
             Starting Setup Virtual Console...
    [    8.010591] systemd[1]: Mounting POSIX Message Queue File System...
             Mounting POSIX Message Queue File System...
    [    8.038642] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.
    [  OK  ] Started Dispatch Password Requests to Console Directory Watch.
    [    8.068395] systemd[1]: Reached target Paths.
    [  OK  ] Reached target Paths.
    [    8.088290] systemd[1]: Reached target Remote File Systems.
    [    8.091497] cryptodev: driver 1.8 loaded.
    [  OK  ] Reached target Remote File Systems.
    [    8.119056] systemd[1]: Created slice User and Session Slice.
    [  OK  ] Created slice User and Session Slice.
    [    8.148339] systemd[1]: Reached target Slices.
    [  OK  ] Reached target Slices.
    [    8.198247] systemd[1]: Mounting Debug File System...
             Mounting Debug File System...
    [    8.218392] systemd[1]: Listening on Journal Socket (/dev/log).
    [  OK  ] Listening on Journal Socket (/dev/log).
    [    8.250291] systemd[1]: Starting Journal Service...
             Starting Journal Service...
    [    8.284782] systemd[1]: Mounted Debug File System.
    [  OK  ] Mounted Debug File System.
    [    8.318349] systemd[1]: Mounted POSIX Message Queue File System.
    [  OK  ] Mounted POSIX Message Queue File System.
    [    8.338513] systemd[1]: Mounted Temporary Directory.
    [  OK  ] Mounted Temporary Directory.
    [    8.369900] systemd[1]: Started Load Kernel Modules.
    [  OK  ] Started Load Kernel Modules.
    [    8.399507] systemd[1]: Started File System Check on Root Device.
    [  OK  ] Started File System Check on Root Device.
    [    8.419852] 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.
    [    8.449539] systemd[1]: Started Setup Virtual Console.
    [  OK  ] Started Setup Virtual Console.
    [    8.479574] systemd[1]: Started Journal Service.
    [  OK  ] Started Journal Service.
             Starting Create Static Device Nodes in /dev...
             Starting Remount Root and Kernel File Systems...
             Starting Apply Kernel Variables...
    [    8.826542] UBIFS (ubi0:0): background thread "ubifs_bgt0_0" started, PID 183
    [  OK  ] Started Remount Root and Kernel File Systems.
             Starting Flush Journal to Persistent Storage...
             Starting udev Coldplug all Devices...
    [  OK  ] Started Create Static Device Nodes in /dev.
    [  OK  ] Started Apply Kernel Variables.
    [    9.142195] systemd-journald[153]: Received request to flush runtime journal from PID 1
             Starting udev Kernel Device Manager...
    [  OK  ] Reached target Local File Systems (Pre).
             Mounting /media/ram...
             Mounting /var/volatile...
    [  OK  ] Mounted /var/volatile.
    [  OK  ] Mounted /media/ram.
    [  OK  ] Started udev Kernel Device Manager.
    [  OK  ] Started Flush Journal to Persistent Storage.
    [  OK  ] Reached target Local File Systems.
             Starting Create Volatile Files and Directories...
             Starting Load/Save Random Seed...
    [  OK  ] Started udev Coldplug all Devices.
    [  OK  ] Started Load/Save Random Seed.
    [  OK  ] Started Create Volatile Files and Directories.
    [  OK  ] Found device /dev/ttyS2.
    [    9.917665] omap-rproc 58820000.ipu: assigned reserved memory node ipu1_cma@9d000000
    [    9.936912]  remoteproc0: 58820000.ipu is available
    [    9.948716]  remoteproc0: Note: remoteproc is still under development and considered experimental.
             Starting Network Time Synchronization...
    [    9.987697]  remoteproc0: THE BINARY FORMAT IS NOT YET FINALIZED, and backward compatibility isn't yet guaranteed.
    [   10.018699] omap-rproc 55020000.ipu: assigned reserved memory node ipu2_cma@95800000
    [   10.026514]  remoteproc1: 55020000.ipu is available
    [   10.052174]  remoteproc1: Note: remoteproc is still under development and considered experimental.
    [   10.062607]  remoteproc1: THE BINARY FORMAT IS NOT YET FINALIZED, and backward compatibility isn't yet guaranteed.
    [   10.082939] omap-rproc 40800000.dsp: assigned reserved memory node dsp1_cma@99000000
    [   10.107203]  remoteproc2: 40800000.dsp is available
    [   10.114201]  remoteproc2: Note: remoteproc is still under development and considered experimental.
    [   10.123614] mt9t11x 2-003c: input-clock-freq: 32000000
    [   10.124382]  remoteproc2: THE BINARY FORMAT IS NOT YET FINALIZED, and backward compatibility isn't yet guaranteed.
    [   10.124748] omap-rproc 41000000.dsp: assigned reserved memory node dsp2_cma@9f000000
    [   10.124788]  remoteproc3: 41000000.dsp is available
    [   10.124790]  remoteproc3: Note: remoteproc is still under development and considered experimental.
    [   10.124792]  remoteproc3: THE BINARY FORMAT IS NOT YET FINALIZED, and backward compatibility isn't yet guaranteed.
    [   10.173063] omap_rng 48090000.rng: OMAP Random Number Generator ver. 20
    [   10.188905] mt9t11x 2-003c: pixel-clock-freq: 96000000
    [   10.252130] mt9t11x 2-003c: mt9t112 chip ID 2682
    [   10.282441] mt9t11x 2-003c: mt9t11x sensor driver registered !!
             Starting Update UTMP about System Boot/Shutdown...
    [   10.491483] omap-des 480a5000.des: OMAP DES hw accel rev: 2.2
    [   10.508599] alg: skcipher: setkey failed on test 5 for ecb-des-omap: flags=100
    [   10.517064] alg: skcipher-ddst: setkey failed on test 5 for ecb-des-omap: flags=100
    [   10.540468] alg: skcipher-ddst: setkey failed on test 5 for ecb-des-omap: flags=100
    [   10.578229] omap_rtc 48838000.rtc: rtc core: registered 48838000.rtc as rtc2
    [  OK  ] Started Network Time Synchronization.
    [  OK  ] Reached target System Time Synchronized.
    [   10.723429] palmas-rtc 48070000.i2c:tps659038@58:tps659038_rtc: rtc core: registered 48070000.i2c:tps659 as rtc1
             Starting Synchronize System and HW clocks...
    [  OK  ] Started Update UTMP about System Boot/Shutdown.
    [FAILED] Failed to start Synchronize System and HW clocks.
    See 'systemctl status sync-clocks.service' for details.
    [  OK  ] Reached target System Initialization.
    [  OK  ] Listening on RPCbind Server Activation Socket.
    [  OK  ] Listening on D-Bus System Message Bus Socket.
    [  OK  ] Reached target Sockets.[   11.171539] omap_wdt: OMAP Watchdog Timer Rev 0x01: initial timeout 60 sec
    
    [  OK  ] Reached target Basic System.
    [   11.234928] ti-pruss 4b200000.pruss: creating PRU cores and other child platform devices
             Starting uim-sysfs.service...
    [   11.274143] irq: no irq domain found for /ocp/pruss@4b200000/intc@4b220000 !
    [   11.293174] irq: no irq domain found for /ocp/pruss@4b200000/intc@4b220000 !
    [   11.300874] ti-pruss 4b280000.pruss: creating PRU cores and other child platform devices
    [   11.310042] irq: no irq domain found for /ocp/pruss@4b280000/intc@4b2a0000 !
    [   11.317511] irq: no irq domain found for /ocp/pruss@4b280000/intc@4b2a0000 !
    [  OK  ] Started System Logging Service.
             Starting Lightning Fast Webserver With Light System Requirements...
             Starting Login Service...
    [  OK  ] Started Kernel Logging Service.
    [  OK  ] Started Daily Cleanup of Temporary Directories.
    [  OK  ] Reached target Timers.[   11.566764] omap-sham 4b101000.sham: hw accel on OMAP rev 4.3
    
    [   11.630424]  remoteproc4: 4b234000.pru0 is available
             Starting Network Name Resolution...[   11.651895]  remoteproc4: Note: remoteproc is still under development and considered experimental.
    
             Starting Print notice about GPLv3 packages...
    [  OK  ] Started strongSwan IPsec IKEv1/IKEv2 daemon using ipsec.conf.
             Starting Permit User Sessions...
    [   11.788271]  remoteproc4: THE BINARY FORMAT IS NOT YET FINALIZED, and backward compatibility isn't yet guaranteed.
             Starting Telephony service...
    [  OK  ] Started D-Bus System Message Bus.
    [   12.092477]  remoteproc4: registered virtio0 (type 7)
    [   12.097585] pru-rproc 4b234000.pru0: PRU rproc node /ocp/pruss@4b200000/pru0@4b234000 probed successfully
    [   12.138557]  remoteproc5: 4b238000.pru1 is available
    [   12.143548]  remoteproc5: Note: remoteproc is still under development and considered experimental.
    [   12.236436]  remoteproc5: THE BINARY FORMAT IS NOT YET FINALIZED, and backward compatibility isn't yet guaranteed.
    [   12.926356]  remoteproc5: registered virtio1 (type 7)
    [   12.933220] pru-rproc 4b238000.pru1: PRU rproc node /ocp/pruss@4b200000/pru1@4b238000 probed successfully
    [   12.946648]  remoteproc6: 4b2b4000.pru0 is available
    [   12.970293]  remoteproc6: Note: remoteproc is still under development and considered experimental.
    [   12.989102]  remoteproc6: THE BINARY FORMAT IS NOT YET FINALIZED, and backward compatibility isn't yet guaranteed.
    [   13.109389] EXT4-fs (mmcblk0p2): recovery complete
    [   13.121845] EXT4-fs (mmcblk0p2): mounted filesystem with ordered data mode. Opts: (null)
    [   14.032537]  remoteproc6: registered virtio2 (type 7)
    [   14.037640] pru-rproc 4b2b4000.pru0: PRU rproc node /ocp/pruss@4b280000/pru0@4b2b4000 probed successfully
    [   14.051361]  remoteproc7: 4b2b8000.pru1 is available
    [   14.062107]  remoteproc7: Note: remoteproc is still under development and considered experimental.
    [   14.079960]  remoteproc7: THE BINARY FORMAT IS NOT YET FINALIZED, and backward compatibility isn't yet guaranteed.
    [   14.870592]  remoteproc7: registered virtio3 (type 7)
    [   14.875703] pru-rproc 4b2b8000.pru1: PRU rproc node /ocp/pruss@4b280000/pru1@4b2b8000 probed successfully
    [   15.700901] omap-hdmi-audio omap-hdmi-audio.0.auto: snd-soc-dummy-dai <-> 58040000.encoder mapping ok
    [  OK  ] Started Telephony service.
    [  OK  ] Started Making network connection up.
    [  OK  ] Started uim-sysfs.service.
    [FAILED] Failed to start Lightning Fast Webs... With Light System Requirements.
    See 'systemctl status lighttpd.service' for details.
    [  OK  ] Started Permit User Sessions.
    [  OK  ] Started Network Name Resolution.
    [   16.196117] net eth0: initializing cpsw version 1.15 (0)
    [   16.228176] net eth0: initialized cpsw ale version 1.4
    [   16.258328] net eth0: ALE Table size 1024
    [   16.331831] vpe 489d0000.vpe: loading firmware vpdma-1b8.bin
    [   16.338247] omap-aes 4b500000.aes: OMAP AES hw accel rev: 3.3
    [   16.376129] net eth0: phy found : id is : 0x221622
    [   16.415061] vpe 489d0000.vpe: Device registered as /dev/video0
    [   16.484667] IPv6: ADDRCONF(NETDEV_UP): eth0: link is not ready
    [   16.574609] FAT-fs (mmcblk0p1): Volume was not properly unmounted. Some data may be corrupt. Please run fsck.
    [   16.858178] omap-aes 4b700000.aes: OMAP AES hw accel rev: 3.3
    [   16.878099] vip 48970000.vip: loading firmware vpdma-1b8.bin
    [   16.898083] vip 48970000.vip: VPDMA firmware loaded
    [   16.904704] vip1-s0: Port A: Using subdev mt9t11x for capture
    [   16.915069] vip1-s0: device registered as video1
    [   17.071819] NET: Registered protocol family 15
    [   17.111910] SCSI subsystem initialized
    [   17.881327]  remoteproc4: powering up 4b234000.pru0
    [   17.886360]  remoteproc4: Booting fw image am57xx-pru1_0-fw, size 75804
    [   17.908228] ti-pruss 4b200000.pruss: configured system_events = 0x0000000000030000 intr_channels = 0x00000005 host_intr = 0x00000005
    [   17.920680]  remoteproc4: remote processor 4b234000.pru0 is now up
    [   17.938823] virtio_rpmsg_bus virtio0: rpmsg host is online
    [   17.938848] virtio_rpmsg_bus virtio0: creating channel rpmsg-pru addr 0x1e
    [   17.951818]  remoteproc5: powering up 4b238000.pru1
    [   17.957336]  remoteproc5: Booting fw image am57xx-pru1_1-fw, size 75804
    [   17.964151] ti-pruss 4b200000.pruss: configured system_events = 0x00000000000c0000 intr_channels = 0x0000000a host_intr = 0x0000000a
    [   17.976395]  remoteproc5: remote processor 4b238000.pru1 is now up
    [   17.988561] virtio_rpmsg_bus virtio1: rpmsg host is online
    [   17.994102] virtio_rpmsg_bus virtio1: creating channel rpmsg-pru addr 0x1f
    [   18.005467]  remoteproc6: powering up 4b2b4000.pru0
    [   18.011230]  remoteproc6: Booting fw image am57xx-pru2_0-fw, size 75804
    [   18.021662] ti-pruss 4b280000.pruss: configured system_events = 0x0000000000030000 intr_channels = 0x00000005 host_intr = 0x00000005
    [   18.033780]  remoteproc6: remote processor 4b2b4000.pru0 is now up
    [   18.045196] virtio_rpmsg_bus virtio2: rpmsg host is online
    [   18.045219] virtio_rpmsg_bus virtio2: creating channel rpmsg-pru addr 0x20
    [   18.058435]  remoteproc7: powering up 4b2b8000.pru1
    [   18.070626]  remoteproc7: Booting fw image am57xx-pru2_1-fw, size 75804
    [   18.077549] ti-pruss 4b280000.pruss: configured system_events = 0x00000000000c0000 intr_channels = 0x0000000a host_intr = 0x0000000a
    [   18.100236]  remoteproc7: remote processor 4b2b8000.pru1 is now up
    [   18.109237] virtio_rpmsg_bus virtio3: rpmsg host is online
    [   18.109264] virtio_rpmsg_bus virtio3: creating channel rpmsg-pru addr 0x21
    [   18.234549] rpmsg_pru rpmsg0: new rpmsg_pru device: /dev/rpmsg_pru30
    [   18.241641] rpmsg_pru rpmsg1: new rpmsg_pru device: /dev/rpmsg_pru31
    [   18.253466] rpmsg_pru rpmsg2: new rpmsg_pru device: /dev/rpmsg_pru32
    [   18.260508] rpmsg_pru rpmsg3: new rpmsg_pru device: /dev/rpmsg_pru33
    [   19.030892]  remoteproc1: powering up 55020000.ipu
    [   19.035752]  remoteproc1: Booting fw image dra7-ipu2-fw.xem4, size 3743072
    [   19.047637] omap-iommu 55082000.mmu: 55082000.mmu: version 2.1
    [   19.153192]  remoteproc1: remote processor 55020000.ipu is now up
    [   19.171585] virtio_rpmsg_bus virtio4: rpmsg host is online
    [   19.174647] virtio_rpmsg_bus virtio4: creating channel rpmsg-rpc addr 0x65
    [   19.175536] virtio_rpmsg_bus virtio4: creating channel rpmsg-rpc addr 0x66
    [   19.192200]  remoteproc1: registered virtio4 (type 7)
    [   19.334394]  remoteproc0: powering up 58820000.ipu
    [   19.339292]  remoteproc0: Booting fw image dra7-ipu1-fw.xem4, size 4890352
    [   19.346847] omap-iommu 58882000.mmu: 58882000.mmu: version 2.1
    [   19.365807]  remoteproc0: remote processor 58820000.ipu is now up
    [   19.373317] virtio_rpmsg_bus virtio5: rpmsg host is online
    [   19.374960] virtio_rpmsg_bus virtio5: creating channel rpmsg-client-sample addr 0x32
    [   19.375097] virtio_rpmsg_bus virtio5: creating channel rpmsg-client-sample addr 0x33
    [   19.376873] virtio_rpmsg_bus virtio5: creating channel rpmsg-omx addr 0x3c
    [   19.376991] virtio_rpmsg_bus virtio5: creating channel rpmsg-rpc addr 0x65
    [  OK  ] Reached target Sound Card.
    [  OK  ] Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch.[   19.432477]  remoteproc0: registered virtio5 (type 7)
    
    [  OK  ] Started Serial Getty on ttyS2.
    [  OK  ] Started Getty on tty1.
             Starting rc.pvr.service...
    [  OK  ] Started Login Service.
    [   19.629755] rpmsg_rpc rpmsg4: probing service dce-callback with src 1024 dst 101
    [   19.637361] Initializing XFRM netlink socket
    [   19.650279] rpmsg_rpc rpmsg5: probing service rpmsg-dce with src 1025 dst 102
    [   19.658368] rpmsg_rpc rpmsg4: published functions = 4
    [   19.669005] rpmsg_rpc rpmsg9: probing service rpc_example_2 with src 1024 dst 101
    [   19.676536] rpmsg_rpc rpmsg5: published functions = 9
    [   19.684882] rpmsg_rpc rpmsg9: published functions = 8
    [   20.457012] cpsw 48484000.ethernet eth0: Link is Up - 100Mbps/Full - flow control rx/tx
    [   20.465105] IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready
    [   21.048884] ahci 4a140000.sata: SSS flag set, parallel bus scan disabled
    [   21.055695] ahci 4a140000.sata: AHCI 0001.0300 32 slots 1 ports 3 Gbps 0x1 impl platform mode
    [   21.064353] ahci 4a140000.sata: flags: 64bit ncq sntf stag pm led clo only pmp pio slum part ccc apst 
    [   21.085818] scsi host0: ahci
    [   21.089063] ata1: SATA max UDMA/133 mmio [mem 0x4a140000-0x4a1410ff] port 0x100 irq 354
    [   21.293297] omap_hwmod: gpu: _wait_target_ready failed: -16
    [   21.300128] PVR_K:(Error): EnableSGXClocks: pm_runtime_get_sync failed (16)
    [   21.307158] PVR_K:(Error): SysInitialise: Failed to Enable system clocks (175)
    [   21.448079] ata1: SATA link down (SStatus 0 SControl 300)
    [  OK  ] Started rc.pvr.service.
             Starting weston.service...
    [  OK  ] Started weston.service.
             Starting tiipclad-daemon.service...
    [  OK  ] Started tiipclad-daemon.service.
             Starting rng-tools.service...
    [  OK  ] Started rng-tools.service.
             Starting thermal-zone-init.service...
    [  OK  ] Started thermal-zone-init.service.
             Starting g3z-init.service...
    [   22.549334] Watchdog Timer is disabled
    [   22.708105] UBIFS (ubi0:0): background thread "ubifs_bgt0_0" stops
    [   22.753786] ubi1: attaching mtd13
    [   22.771992] Bluetooth: Core ver 2.21
    [   22.775620] NET: Registered protocol family 31
    [   22.781561] Bluetooth: HCI device and connection manager initialized
    [   22.789285] Bluetooth: HCI socket layer initialized
    [   22.795036] Bluetooth: L2CAP socket layer initialized
    [   22.801391] Bluetooth: SCO socket layer initialized
    [   23.201305] ubi1: scanning is finished
    [   23.218129] ubi1: attached mtd13 (name "NAND.application", size 105 MiB)
    [   23.224863] ubi1: PEB size: 262144 bytes (256 KiB), LEB size: 253952 bytes
    [   23.233046] ubi1: min./max. I/O unit sizes: 4096/4096, sub-page size 1024
    [   23.240575] ubi1: VID header offset: 4096 (aligned 4096), data offset: 8192
    [   23.247600] ubi1: good PEBs: 419, bad PEBs: 1, corrupted PEBs: 0
    [   23.254852] ubi1: user volume: 1, internal volumes: 1, max. volumes count: 128
    [   23.263030] ubi1: max/mean erase counter: 1/0, WL threshold: 4096, image sequence number: 1842642788
    [   23.272897] ubi1: available PEBs: 0, total reserved PEBs: 419, PEBs reserved for bad PEB handling: 79
    [   23.282813] ubi1: background thread "ubi_bgt1d" started, PID 979
    [   23.322052] UBIFS (ubi1:0): background thread "ubifs_bgt1_0" started, PID 981
    [   23.380227] UBIFS (ubi1:0): recovery needed
    [   23.529606] UBIFS (ubi1:0): recovery completed
    [   23.534184] UBIFS (ubi1:0): UBIFS: mounted UBI device 1, volume 0, name "application"
    [   23.545578] UBIFS (ubi1:0): LEB size: 253952 bytes (248 KiB), min./max. I/O unit sizes: 4096 bytes/4096 bytes
    [   23.556216] UBIFS (ubi1:0): FS size: 82788352 bytes (78 MiB, 326 LEBs), journal size 4063232 bytes (3 MiB, 16 LEBs)
    [   23.558110] usbcore: registered new interface driver usbfs
    [   23.558702] usbcore: registered new interface driver hub
    [   23.559291] usbcore: registered new device driver usb
    [   23.584962] UBIFS (ubi1:0): reserved for root: 3910297 bytes (3818 KiB)
    [   23.592217] UBIFS (ubi1:0): media format: w4/r0 (latest is w4/r0), UUID 3C2F7B69-E38B-460C-841A-526E25BEF82A, small LPT model
    info, udhcpc (v0.9.8_0.3) started
    Error in conf file opening 
    Zooter udhcpc Timeout: 30 Mins
    eth0      Link encap:Ethernet  HWaddr FC:0F:4B:9B:06:2E  
              inet6 addr: fe80::fe0f:4bff:fe9b:62e%3068400408/64 Scope:Link
              UP BROADCAST RUNNING MULTICAST  MTU:1500  Metric:1
              RX packets:56 errors:0 dropped:8 overruns:0 frame:0
              TX packets:11 errors:0 dropped:0 overruns:0 carrier:0
              collisions:0 txqueuelen:1000 
              RX bytes:4248 (4.1 KiB)  TX bytes:978 (978.0 B)
              Interrupt:107 
    
    am57xx-evm
    variable is null Default is US/Estern
    debug, Sending discover...
    [   24.328670] xhci-hcd xhci-hcd.1.auto: xHCI Host Controller
    [   24.334200] xhci-hcd xhci-hcd.1.auto: new USB bus registered, assigned bus number 1
    [   24.378384] xhci-hcd xhci-hcd.1.auto: hcc params 0x0220f04c hci version 0x100 quirks 0x00010010
    [   24.387163] xhci-hcd xhci-hcd.1.auto: irq 520, io mem 0x48890000
    [   24.438730] hub 1-0:1.0: USB hub found
    [   24.438835] hub 1-0:1.0: 1 port detected
    [   24.439522] xhci-hcd xhci-hcd.1.auto: xHCI Host Controller
    [   24.439537] xhci-hcd xhci-hcd.1.auto: new USB bus registered, assigned bus number 2
    [   24.439618] usb usb2: We don't know the algorithms for LPM for this host, disabling LPM.
    [   24.440263] hub 2-0:1.0: USB hub found
    [   24.440296] hub 2-0:1.0: 1 port detected
    ***************************************************************
    ***************************************************************
    NOTICE: This file system contains the following GPLv3 packages:
            binutils-dev
            binutils
            bison-dev
            bison
            cpp-symlinks
            cpp
            dosfstools
            gawk-dev
            gawk
            gstreamer1.0-libav
            hidapi
            libgmp10
            libidn11
            libmpc3
            libmpfr4
            m4-dev
            m4
            make
            nettle
            parted
            swig-dev
            swig
    
    If you do not wish to distribute GPLv3 components please remove
    the above packages prior to distribution.  This can be done using
    the opkg remove command.  i.e.:
        opkg remove <package>
    Where <package> is the name printed in the list above
    
    NOTE: If the package is a dependency of another package you
          will be notified of the dependent packages.  You should
          use the --force-removal-of-dependent-packages option to
          also remove the dependent packages as well
    ***************************************************************
    ***************************************************************
    
     _____                    _____           _         _   
    |  _  |___ ___ ___ ___   |  _  |___ ___  |_|___ ___| |_ 
    |     |  _| .'| . | . |  |   __|  _| . | | | -_|  _|  _|
    |__|__|_| |__,|_  |___|  |__|  |_| |___|_| |___|___|_|  
                  |___|                    |___|            
    
    Arago Project http://arago-project.org am57xx-evm ttyS2
    
    Arago 2016.10 am57xx-evm ttyS2
    
    am57xx-evm login: 
    
    
    
    [  958.830314] BUG: unsupported FP instruction in kernel mode
    [  958.835836] Internal error: Oops - undefined instruction: 0 [#1] PREEMPT SMP ARM
    [  958.843264] Modules linked in: ov798cam rpmsg_proto 8021q bluetooth xhci_plat_hcd xhci_hcd cbc jitterentropy_rng drbg usbcore xfrm_user pvrsrvkm(O) xfrm4_tunnel ahci_platform ipcomp libahci_platform xfrm_ipcomp libahci esp4 libata ah4 rpmsg_rpc af_key xfrm_algo dwc3 scsi_mod rpmsg_pru udc_core virtio_rpmsg_bus ecb sha512_generic sha512_arm sha256_generic snd_soc_omap_hdmi_audio hmac md5 sha1_generic sha1_arm_neon sha1_arm ti_vip omap_aes_driver omap_sham pru_rproc pruss_intc pruss omap_wdt ti_vpe ti_sc ti_csc dwc3_omap ti_vpdma rtc_omap omap_des extcon_palmas omap_rng rtc_palmas mt9t11x extcon rng_core omap_remoteproc remoteproc virtio debugss_kmodule(O) virtio_ring sch_fq_codel uio_module_drv(O) uio gdbserverproxy(O) cryptodev(O) cmemk(O)
    [  958.909625] CPU: 0 PID: 1467 Comm: ov798_app_spifl Tainted: G           O    4.4.32-gadde2ca9f8 #1
    [  958.918622] Hardware name: Generic DRA74X (Flattened Device Tree)
    [  958.924739] task: d32dc800 ti: d3354000 task.ti: d3354000
    [  958.928038] dmm 4e000000.dmm: timed out waiting for done
    [  958.928046] omapdrm omapdrm.0: could not unpin pages: -110
    [  958.940993] PC is at vfp_reload_hw+0x30/0x44
    [  958.945282] LR is at __und_usr_fault_32+0x0/0x8
    [  958.949831] pc : [<c000ae2c>]    lr : [<c0014240>]    psr: a0000013
                   sp : d3355fb0  ip : 00021f3c  fp : 00000000
    [  958.961356] r10: d33541f8  r9 : c00142a0  r8 : 00000000
    [  958.966603] r7 : 00000001  r6 : 005b8d80  r5 : ee2cc248  r4 : ec54c0f8
    [  958.973156] r3 : c0986058  r2 : b6f016c2  r1 : eed33e40  r0 : ed2d8b02
    [  958.979711] Flags: NzCv  IRQs on  FIQs on  Mode SVC_32  ISA ARM  Segment user
    [  958.986876] Control: 30c5387d  Table: ac697580  DAC: fffffffd
    [  958.992644] Process ov798_app_spifl (pid: 1467, stack limit = 0xd3354210)
    [  958.999460] Stack: (0xd3355fb0 to 0xd3356000)
    [  959.003836] 5fa0:                                     be93f8e8 00011940 be93f9c0 000b89e8
    [  959.012049] 5fc0: 00000000 b6fd84c0 b6fb9d00 00011940 be93f9c0 be93f9d4 b6fe6000 00000000
    [  959.020262] 5fe0: 00021f3c be93f8bc b6f19457 b6f016c2 60000030 ffffffff 00000000 00000000
    [  959.028037] dmm 4e000000.dmm: timed out waiting for done
    [  959.028045] omapdrm omapdrm.0: could not unpin pages: -110
    [  959.039301] Backtrace: no frame pointer
    [  959.043169] Code: 128aa080 e89a0162 e3110102 0a000003 (eee96a10) 
    [  959.055127] ---[ end trace 0e236320650dfe87 ]---
    [  959.059766] note: ov798_app_spifl[1467] exited with preempt_count 2
    
    

    Regards,

    Prerak

  • Hi Prerak,

    The kernel log message:
    [ 958.830314] BUG: unsupported FP instruction in kernel mode
    seems not directly related to the SPI read/write but seems caused by use of unsupported instruction in the kernel. For example floating point operation in kernel space.
    Could you specify is the issue appear every time after executing some specific code or it happens sometimes?
    Are you running some custom driver or function related to ov798cam or NEON?

    BR
    Tsvetolin Shulev
  • Hi Shulev,

    This issue appears 2 time per 15 time of code execution.

    We are using costom driver, to communicate with spi.

    Regards,
    Prerak