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/AM5728: Kernel internal error

Part Number: AM5728
Other Parts Discussed in Thread: BEAGLEBOARD-X15

Tool/software: Linux

Hi, smart Sitarra users!

We have a custom-built AM5728 board that's almost a direct copy of the EVM. Some systems will occasionally fail to start. It's rare enough that we almost never have the debug port connected when it happens. However, we found that sometimes we fail to start because of an internal error.

I'll attach the whole log, but here are the bits I think are interesting:

[ 12.349002] Unable to handle kernel paging request at virtual address 00002248
[ 12.356265] pgd = c0003000
[ 12.356274] [00002248] *pgd=80000080004003, *pmd=00000000
[ 12.356281] Internal error: Oops: 205 [#1] PREEMPT SMP ARM
[ 12.356381] Modules linked in: sha512_generic uvcvideo videobuf2_vmalloc sha1_generic md5 cbc sha256_generic hmac drbg bluetooth esp4 rpmsg_proto rpmsg_pru rpmsg_rpc xhci_plat_hcd xhci_hcd usbcore dwc3 udc_core evdev virtio_rpmsg_bus gpio_keys pru_rproc pruss_intc leds_gpio pruss omap_aes_driver omap_sham phy_omap_usb2 omap_wdt dwc3_omap rtc_omap extcon_palmas omap_rng extcon rtc_palmas omap_des rng_core pixcir_i2c_ts palmas_pwrbutton mt9t11x rtc_ds1307 omap_remoteproc remoteproc virtio virtio_ring sch_fq_codel uio_module_drv(O) uio cryptodev(O) cmemk(O) ipv6 autofs4
[ 12.356388] CPU: 1 PID: 6 Comm: kworker/u4:0 Tainted: G O 4.4.32-gadde2ca9f8 #1
[ 12.356390] Hardware name: Generic DRA74X (Flattened Device Tree)
[ 12.356402] Workqueue: events_unbound flush_to_ldisc
[ 12.356407] task: ef885900 ti: ef8a4000 task.ti: ef8a4000
[ 12.356416] PC is at n_tty_receive_buf_common+0x7c/0xa60
[ 12.356422] LR is at n_tty_receive_buf_common+0x78/0xa60

Google searches on "n_tty_receive_buf_common+0x" brings up a couple links that appear to indicate this is a race condition in the kernel. Can anyone confirm this, and is there anything I should ask my SW folks to do, like apply a patch or update the kernel?

log:

U-Boot SPL 2016.05-00311-ga066aa1 (Jun 07 2017 - 12:59:05)
DRA752-GP ES2.0
Trying to boot from MMC2_2
reading args
spl_load_image_fat_os: error reading image args, err - -1
reading u-boot.img
reading u-boot.img


U-Boot 2016.05-00311-ga066aa1 (Jun 07 2017 - 12:59:05 -0700)

CPU  : DRA752-GP ES2.0
Model: TI AM5728 BeagleBoard-X15
Board: UNKNOWN(BeagleBoard X15?) REV UNKNOWN
DRAM:  4 GiB
CONFIG_SYS_SDRAM_BASE= -2147483648:
MMC:   failed to get property pinctrl-1
no pinctrl for hs
no pinctrl for sdr104
no pinctrl for ddr50
no pinctrl for sdr50
no pinctrl for sdr25
no pinctrl for sdr12
OMAP SD/MMC: 0, OMAP SD/MMC: 1
MMC: no card present
** Bad device mmc 0 **
Using default environment

I2C chip 50: requested alen 2 does not match chip offset_len 1
SCSI:  SATA link 0 timeout.
AHCI 0001.0300 32 slots 1 ports 3 Gbps 0x1 impl SATA mode
flags: 64bit ncq stag pm led clo only pmp pio slum part ccc apst
scanning bus for devices...
Found 0 device(s).
Net:
Warning: ethernet@48484000 using MAC address from ROM
eth0: ethernet@48484000
Hit any key to stop autoboot:  0
MMC: no card present
MMC: no card present
MMC: no card present
MMC: no card present
switch to partitions #0, OK
mmc1(part 0) is current device
SD/MMC found on device 1
reading boot.scr
** Unable to read file boot.scr **
reading uEnv.txt
574 bytes read in 3 ms (186.5 KiB/s)
Loaded env from uEnv.txt
Importing environment from mmc1 ...
switch to partitions #0, OK
mmc1(part 0) is current device
SD/MMC found on device 1
3324432 bytes read in 51 ms (62.2 MiB/s)
104139 bytes read in 21 ms (4.7 MiB/s)
Kernel image @ 0x82000000 [ 0x000000 - 0x32ba10 ]
## Flattened Device Tree blob at 88000000
   Booting using the fdt blob at 0x88000000
   Loading Device Tree to 8ffe3000, end 8ffff6ca ... 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 (ubuntu@ubuntu-OptiPlex-380) (gcc version 5.3.1 20160113 (Linaro GCC 5.3-2016.02) ) #1 SMP PREEMPT Tue Mar 7 15:16:05 PST 2017
[    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 0x00000000fd800000
[    0.000000] Forcing write-allocate cache policy for SMP
[    0.000000] Memory policy: Data cache writealloc
[    0.000000] OMAP4: Map 0x000000027fd00000 to fe600000 for dram barrier
[    0.000000] DRA752 ES2.0
[    0.000000] PERCPU: Embedded 12 pages/cpu @eff26000 s19136 r8192 d21824 u49152
[    0.000000] Built 1 zonelists in Zone order, mobility grouping on.  Total pages: 992832
[    0.000000] Kernel command line: console=ttyO2,115200n8 root=PARTUUID=b5337f12-02 rw rootfstype=ext4 rootwait
[    0.000000] PID hash table entries: 2048 (order: 1, 8192 bytes)
[    0.000000] Dentry cache hash table entries: 65536 (order: 6, 262144 bytes)
[    0.000000] Inode-cache hash table entries: 32768 (order: 5, 131072 bytes)
[    0.000000] Memory: 3744204K/3978240K available (6167K kernel code, 265K rwdata, 2192K rodata, 348K init, 260K bss, 45620K reserved, 188416K cma-reserved, 3363840K 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 - 0xc0832074   (8361 kB)
[    0.000000]       .init : 0xc0833000 - 0xc088a000   ( 348 kB)
[    0.000000]       .data : 0xc088a000 - 0xc08cc570   ( 266 kB)
[    0.000000]        .bss : 0xc08ce000 - 0xc090f344   ( 261 kB)
[    0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=2, Nodes=1
[    0.000000] Preemptible hierarchical RCU implementation.
[    0.000000]  Build-time adjustment of leaf fanout to 32.
[    0.000000] NR_IRQS:16 nr_irqs:16 16
[    0.000000] ti_dt_clocks_register: failed to lookup clock node gmac_gmii_ref_clk_div
[    0.000000] OMAP clockevent source: timer1 at 32786 Hz
[    0.000000] Architected cp15 timer(s) running at 6.14MHz (phys).
[    0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x16af5adb9, max_idle_ns: 440795202250 ns
[    0.000004] sched_clock: 56 bits at 6MHz, resolution 162ns, wraps every 4398046511023ns
[    0.000015] Switching to timer-based delay loop, resolution 162ns
[    0.000325] clocksource: 32k_counter: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 58327039986419 ns
[    0.000332] OMAP clocksource: 32k_counter at 32768 Hz
[    0.000766] Console: colour dummy device 80x30
[    0.000781] WARNING: Your 'console=ttyO2' has been replaced by 'ttyS2'
[    0.000787] This ensures that you still see kernel messages. Please
[    0.000793] update your kernel commandline.
[    0.000806] Calibrating delay loop (skipped), value calculated using timer frequency.. 12.29 BogoMIPS (lpj=61475)
[    0.000818] pid_max: default: 32768 minimum: 301
[    0.000890] Security Framework initialized
[    0.000927] Mount-cache hash table entries: 1024 (order: 0, 4096 bytes)
[    0.000936] Mountpoint-cache hash table entries: 1024 (order: 0, 4096 bytes)
[    0.001453] Initializing cgroup subsys io
[    0.001469] Initializing cgroup subsys memory
[    0.001492] Initializing cgroup subsys devices
[    0.001504] Initializing cgroup subsys freezer
[    0.001515] Initializing cgroup subsys perf_event
[    0.001536] CPU: Testing write buffer coherency: ok
[    0.001734] /cpus/cpu@0 missing clock-frequency property
[    0.001750] /cpus/cpu@1 missing clock-frequency property
[    0.001760] CPU0: thread -1, cpu 0, socket 0, mpidr 80000000
[    0.001792] Setting up static identity map for 0x800082c0 - 0x80008318
[    0.080081] CPU1: thread -1, cpu 1, socket 0, mpidr 80000001
[    0.080138] Brought up 2 CPUs
[    0.080151] SMP: Total of 2 processors activated (24.59 BogoMIPS).
[    0.080158] CPU: All CPU(s) started in HYP mode.
[    0.080163] CPU: Virtualization extensions available.
[    0.080487] devtmpfs: initialized
[    0.109102] VFP support v0.3: implementor 41 architecture 4 part 30 variant f rev 0
[    0.110059] omap_hwmod: l3_main_2 using broken dt data from ocp
[    0.311233] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns
[    0.315210] pinctrl core: initialized pinctrl subsystem
[    0.316036] NET: Registered protocol family 16
[    0.316930] DMA: preallocated 256 KiB pool for atomic coherent allocations
[    0.340239] cpuidle: using governor ladder
[    0.370263] cpuidle: using governor menu
[    0.379026] OMAP GPIO hardware version 0.1
[    0.385491] irq: no irq domain found for /ocp/l4@4a000000/scm@2000/pinmux@1400 !
[    0.402668] omap-gpmc 50000000.gpmc: GPMC revision 6.0
[    0.402681] gpmc_mem_init: disabling cs 0 mapped at 0x0-0x1000000
[    0.412298] No ATAGs?
[    0.412327] hw-breakpoint: found 5 (+1 reserved) breakpoint and 4 watchpoint registers.
[    0.412335] hw-breakpoint: maximum watchpoint size is 8 bytes.
[    0.412721] omap4_sram_init:Unable to allocate sram needed to handle errata I688
[    0.412730] omap4_sram_init:Unable to get sram pool needed to handle errata I688
[    0.413310] OMAP DMA hardware revision 0.0
[    0.451676] omap-dma-engine 4a056000.dma-controller: OMAP DMA engine driver (LinkedList1/2/3 supported)
[    0.452659] edma 43300000.edma: memcpy is disabled
[    0.457379] edma 43300000.edma: TI EDMA DMA engine driver
[    0.462948] omap-iommu 40d01000.mmu: 40d01000.mmu registered
[    0.463123] omap-iommu 40d02000.mmu: 40d02000.mmu registered
[    0.463279] omap-iommu 58882000.mmu: 58882000.mmu registered
[    0.463432] omap-iommu 55082000.mmu: 55082000.mmu registered
[    0.463720] omap-iommu 41501000.mmu: 41501000.mmu registered
[    0.463906] omap-iommu 41502000.mmu: 41502000.mmu registered
[    0.465802] vgaarb: loaded
[    0.466132] SCSI subsystem initialized
[    0.467233] palmas 0-0058: Irq flag is 0x00000008
[    0.482802] palmas 0-0058: Muxing GPIO 2f, PWM 0, LED 0
[    0.564241] omap_i2c 48070000.i2c: bus 0 rev0.12 at 400 kHz
[    0.564726] omap_i2c 48060000.i2c: bus 2 rev0.12 at 400 kHz
[    0.565456] omap_i2c 4807a000.i2c: bus 3 rev0.12 at 400 kHz
[    0.566276] omap_i2c 4807c000.i2c: bus 4 rev0.12 at 400 kHz
[    0.566492] media: Linux media interface: v0.10
[    0.566548] Linux video capture interface: v2.00
[    0.566586] pps_core: LinuxPPS API ver. 1 registered
[    0.566592] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>
[    0.566618] PTP clock support registered
[    0.567194] omap-mailbox 4883c000.mailbox: omap mailbox rev 0x400
[    0.567485] omap-mailbox 4883e000.mailbox: omap mailbox rev 0x400
[    0.567772] omap-mailbox 48840000.mailbox: omap mailbox rev 0x400
[    0.568061] omap-mailbox 48842000.mailbox: omap mailbox rev 0x400
[    0.568935] clocksource: Switched to clocksource arch_sys_counter
[    0.579744] NET: Registered protocol family 2
[    0.580238] TCP established hash table entries: 4096 (order: 2, 16384 bytes)
[    0.580277] TCP bind hash table entries: 4096 (order: 3, 32768 bytes)
[    0.580343] TCP: Hash tables configured (established 4096 bind 4096)
[    0.580389] UDP hash table entries: 256 (order: 1, 8192 bytes)
[    0.580410] UDP-Lite hash table entries: 256 (order: 1, 8192 bytes)
[    0.580597] NET: Registered protocol family 1
[    0.580857] RPC: Registered named UNIX socket transport module.
[    0.580866] RPC: Registered udp transport module.
[    0.580873] RPC: Registered tcp transport module.
[    0.580879] RPC: Registered tcp NFSv4.1 backchannel transport module.
[    0.581854] hw perfevents: enabled with armv7_cortex_a15 PMU driver, 7 counters available
[    0.584209] futex hash table entries: 512 (order: 3, 32768 bytes)
[    0.584280] audit: initializing netlink subsys (disabled)
[    0.584313] audit: type=2000 audit(0.569:1): initialized
[    0.591327] VFS: Disk quotas dquot_6.6.0
[    0.591479] VFS: Dquot-cache hash table entries: 1024 (order 0, 4096 bytes)
[    0.593136] NFS: Registering the id_resolver key type
[    0.593164] Key type id_resolver registered
[    0.593172] Key type id_legacy registered
[    0.593227] jffs2: version 2.2. (NAND) (SUMMARY)  � 2001-2006 Red Hat, Inc.
[    0.594652] NET: Registered protocol family 38
[    0.594725] bounce: pool size: 64 pages
[    0.594739] io scheduler noop registered
[    0.594750] io scheduler deadline registered
[    0.594785] io scheduler cfq registered (default)
[    0.598487] pinctrl-single 4a003400.pinmux: 282 pins at pa fc003400 size 1128
[    0.602326] PCI host bridge /ocp/axi@0/pcie_rc@51000000 ranges:
[    0.602337]   No bus range found for /ocp/axi@0/pcie_rc@51000000, using [bus 00-ff]
[    0.602368]    IO 0x20003000..0x20012fff -> 0x00000000
[    0.602389]   MEM 0x20013000..0x2fffffff -> 0x20013000
[    0.632224] dra7-pcie 51000000.pcie_rc: link is not up
[    0.632388] dra7-pcie 51000000.pcie_rc: PCI host bridge to bus 0000:00
[    0.632401] pci_bus 0000:00: root bus resource [bus 00-ff]
[    0.632411] pci_bus 0000:00: root bus resource [io  0x0000-0xffff]
[    0.632420] pci_bus 0000:00: root bus resource [mem 0x20013000-0x2fffffff]
[    0.632813] PCI: bus0: Fast back to back transfers disabled
[    0.632931] PCI: bus1: Fast back to back transfers enabled
[    0.633017] pci 0000:00:00.0: BAR 0: assigned [mem 0x20100000-0x201fffff]
[    0.633032] pci 0000:00:00.0: BAR 1: assigned [mem 0x20020000-0x2002ffff]
[    0.633044] pci 0000:00:00.0: PCI bridge to [bus 01]
[    0.633995] backlight supply power not found, using dummy regulator
[    0.637307] Serial: 8250/16550 driver, 10 ports, IRQ sharing enabled
[    0.640276] 48020000.serial: ttyS2 at MMIO 0x48020000 (irq = 301, base_baud = 3000000) is a 8250
[    1.626575] console [ttyS2] enabled
[    1.630683] 48422000.serial: ttyS7 at MMIO 0x48422000 (irq = 302, base_baud = 3000000) is a 8250
[    1.640798] [drm] Initialized drm 1.1.0 20060810
[    1.648100] OMAP DSS rev 6.1
[    1.651868] omapdss_dss 58000000.dss: bound 58001000.dispc (ops dispc_component_ops)
[    1.660311] omapdss_dss 58000000.dss: bound 58040000.encoder (ops hdmi5_component_ops)
[    1.680718] brd: module loaded
[    1.688908] loop: module loaded
[    1.692481] eqep 48440180.eqep: ver. 1.0
[    1.696535] eqep 48440180.eqep: count_mode:0
[    1.700894] eqep 48440180.eqep: invert_qa:0
[    1.705097] eqep 48440180.eqep: invert_qb:0
[    1.709333] eqep 48440180.eqep: invert_qi:0
[    1.713536] eqep 48440180.eqep: invert_qs:0
[    1.717738] eqep 48440180.eqep: swap_inputs:0
[    1.722128] eqep 48440180.eqep: QDECCTL:0x0000
[    1.726592] eqep 48440180.eqep: QPOSINIT:0x00000000
[    1.731505] eqep 48440180.eqep: QPOSMAX:0xffffffff
[    1.736316] eqep 48440180.eqep: QPOSCNT:0x00000000
[    1.741140] eqep 48440180.eqep: omit_interrupt:0
[    1.745777] eqep 48440180.eqep: QEINT:0x0800
[    1.750078] eqep 48440180.eqep: QUPRD:0x07ed6b40
[    1.754714] eqep 48440180.eqep: QEPCTL:0x009e write
[    1.759623] eqep 48440180.eqep: QEPCTL:0x009e read
[    1.764448] eqep 48440180.eqep: irq:438, clk_rate:133000000
[    1.770262] irq_number = 156
[    1.774367] mtdoops: mtd device (mtddev=name/number) must be supplied
[    1.784772] spi spi1.1: not using DMA for McSPI (-19)
[    1.838968] davinci_mdio 48485000.mdio: davinci mdio revision 1.6
[    1.845092] davinci_mdio 48485000.mdio: detected phy mask fffffffd
[    1.853665] libphy: 48485000.mdio: probed
[    1.857698] davinci_mdio 48485000.mdio: phy[1]: device 48485000.mdio:01, driver Micrel KSZ9031 Gigabit PHY
[    1.867953] cpsw 48484000.ethernet: No slave[1] phy_id, phy-handle, or fixed-link property
[    1.876320] cpsw 48484000.ethernet: Detected MACID = e8:eb:11:67:85:ac
[    1.883018] cpsw 48484000.ethernet: cpts: overflow check period 800
[    1.890880] mousedev: PS/2 mouse device common for all mice
[    1.896483] i2c /dev entries driver
[    1.901196] vpe 489d0000.vpe: loading firmware vpdma-1b8.bin
[    1.906940] vpe 489d0000.vpe: Direct firmware load for vpdma-1b8.bin failed with error -2
[    1.915566] vpe 489d0000.vpe: Falling back to user helper
[    1.922159] gpio-fan gpio_fan: GPIO fan initialized
[    1.939348] ina2xx 3-0042: power monitor ina219 (Rshunt = 1000 uOhm)
[    1.946187] ina2xx 3-0045: power monitor ina219 (Rshunt = 1000 uOhm)
[    1.953597] ina2xx 3-0048: power monitor ina219 (Rshunt = 1000 uOhm)
[    1.960481] ina2xx 3-004a: power monitor ina219 (Rshunt = 1000 uOhm)
[    1.979360] ina2xx 4-0040: power monitor ina219 (Rshunt = 1000 uOhm)
[    1.986208] ina2xx 4-0041: power monitor ina219 (Rshunt = 1000 uOhm)
[    1.992747] ina2xx 4-0048: error configuring the device: -121
[    1.999460] tmp102 0-0048: initialized
[    2.005975] omap_hsmmc 4809c000.mmc: Got CD GPIO
[    2.049582] omap_hsmmc 480b4000.mmc: no pinctrl state for sdr25 mode
[    2.055965] omap_hsmmc 480b4000.mmc: no pinctrl state for sdr12 mode
[    2.079078] vdd_3v3: supplied by regen1
[    2.139288] ledtrig-cpu: registered to indicate activity on CPUs
[    2.146252] oprofile: using timer interrupt.
[    2.150798] Initializing XFRM netlink socket
[    2.155103] NET: Registered protocol family 17
[    2.159610] NET: Registered protocol family 15
[    2.164147] Key type dns_resolver registered
[    2.168510] omap_voltage_late_init: Voltage driver support not added
[    2.175139] Power Management for TI OMAP4+ devices.
[    2.180290] ThumbEE CPU extension supported.
[    2.184630] Registering SWP/SWPB emulation handler
[    2.192151] dmm 4e000000.dmm: initialized all PAT entries
[    2.213009] [drm] Supports vblank timestamp caching Rev 2 (21.10.2013).
[    2.219713] [drm] No driver support for vblank timestamp query.
[    2.228311] [drm] Enabling DMM ywrap scrolling
[    2.272001] Console: switching to colour frame buffer device 100x30
[    2.272798] mmc1: MAN_BKOPS_EN bit is not set
[    2.285503] omapdrm omapdrm.0: fb0: omapdrm frame buffer device
[    2.292069] mmc1: new DDR MMC card at address 0001
[    2.297318] mmcblk0: mmc1:0001 M62704 3.56 GiB
[    2.302103] mmcblk0boot0: mmc1:0001 M62704 partition 1 2.00 MiB
[    2.308224] mmcblk0boot1: mmc1:0001 M62704 partition 2 2.00 MiB
[    2.315064]  mmcblk0: p1 p2 p3
[    2.328962] [drm] Initialized omapdrm 1.0.0 20110917 on minor 0
[    2.335629] hctosys: unable to open rtc device (rtc0)
[    2.352644] aic_dvdd_fixed: disabling
[    2.356330] vmmcwl_fixed: disabling
[    2.360058] ldousb: disabling
[    2.559987] EXT4-fs (mmcblk0p2): recovery complete
[    2.565015] EXT4-fs (mmcblk0p2): mounted filesystem with ordered data mode. Opts: (null)
[    2.573195] VFS: Mounted root (ext4 filesystem) on device 179:2.
[    2.579804] devtmpfs: mounted
[    2.582991] Freeing unused kernel memory: 348K (c0833000 - c088a000)
[    2.589391] This architecture does not have kernel memory protection.
[    2.670977] systemd[1]: System time before build time, advancing clock.
[    2.804506] NET: Registered protocol family 10
[    2.825042] random: systemd: uninitialized urandom read (16 bytes read, 27 bits of entropy available)
[    2.836622] random: systemd: uninitialized urandom read (16 bytes read, 27 bits of entropy available)
[    2.848516] systemd[1]: systemd 229 running in system mode. (+PAM -AUDIT -SELINUX +IMA -APPARMOR +SMACK +SYSVINIT +UTMP -LIBCRYPTSETUP -GCRYPT -GNUTLS +ACL +XZ -LZ4 -SECCOMP +BLKID -ELFUTILS +KMOD -IDN)
[    2.867167] systemd[1]: Detected architecture arm.

Welcome to Arago 2016.10!

[    2.889529] systemd[1]: Set hostname to <am57xx-evm>.
[    2.939640] random: systemd-gpt-aut: uninitialized urandom read (16 bytes read, 28 bits of entropy available)
[    2.945060] random: systemd-sysv-ge: uninitialized urandom read (16 bytes read, 29 bits of entropy available)
[    2.949190] random: systemd-sysv-ge: uninitialized urandom read (16 bytes read, 29 bits of entropy available)
[    2.955175] random: systemd-sysv-ge: uninitialized urandom read (16 bytes read, 29 bits of entropy available)
[    2.980406] random: systemd-gpt-aut: uninitialized urandom read (16 bytes read, 31 bits of entropy available)
[    3.017712] random: systemd: uninitialized urandom read (16 bytes read, 33 bits of entropy available)
[    3.027354] random: systemd: uninitialized urandom read (16 bytes read, 33 bits of entropy available)
[    3.037366] random: systemd: uninitialized urandom read (16 bytes read, 33 bits of entropy available)
[    3.277407] systemd[1]: sysinit.target: Found ordering cycle on sysinit.target/start
[    3.285311] systemd[1]: sysinit.target: Found dependency on alignment.service/start
[    3.293051] systemd[1]: sysinit.target: Found dependency on basic.target/start
[    3.300377] systemd[1]: sysinit.target: Found dependency on sockets.target/start
[    3.307811] systemd[1]: sysinit.target: Found dependency on avahi-daemon.socket/start
[    3.315697] systemd[1]: sysinit.target: Found dependency on sysinit.target/start
[    3.323145] systemd[1]: sysinit.target: Breaking ordering cycle by deleting job alignment.service/start
[    3.332599] systemd[1]: alignment.service: Job alignment.service/start deleted to break ordering cycle starting with sysinit.target/start
[ SKIP ] Ordering cycle found, skipping alignment.service
[    3.371948] systemd[1]: Listening on Syslog Socket.
[  OK  ] Listening on Syslog Socket.
[    3.400469] systemd[1]: Created slice System Slice.
[  OK  ] Created slice System Slice.
[    3.429383] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.
[  OK  ] Started Dispatch Password Requests to Console Directory Watch.
[    3.459139] systemd[1]: Reached target Remote File Systems.
[  OK  ] Reached target Remote File Systems.
[    3.489635] systemd[1]: Created slice system-getty.slice.
[  OK  ] Created slice system-getty.slice.
[    3.519608] systemd[1]: Created slice User and Session Slice.
[  OK  ] Created slice User and Session Slice.
[    3.549247] systemd[1]: Listening on Journal Socket (/dev/log).
[  OK  ] Listening on Journal Socket (/dev/log).
[    3.579297] systemd[1]: Started Forward Password Requests to Wall Directory Watch.
[  OK  ] Started Forward Password Requests to Wall Directory Watch.
[    3.609136] systemd[1]: Reached target Slices.
[  OK  ] Reached target Slices.
[    3.629402] systemd[1]: Listening on Journal Audit Socket.
[  OK  ] Listening on Journal Audit Socket.
[    3.659137] systemd[1]: Reached target Swap.
[  OK  ] Reached target Swap.
[    3.679189] systemd[1]: Listening on udev Kernel Socket.
[  OK  ] Listening on udev Kernel Socket.
[    3.709247] systemd[1]: Listening on Journal Socket.
[  OK  ] Listening on Journal Socket.
[    3.759353] systemd[1]: Starting Setup Virtual Console...
         Starting Setup Virtual Console...
[    3.791421] 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...
[    3.821239] systemd[1]: Mounting POSIX Message Queue File System...
         Mounting POSIX Message Queue File System...
[    3.851838] systemd[1]: Mounting Temporary Directory...
         Mounting Temporary Directory...
[    3.882723] systemd[1]: Starting Load Kernel Modules...
         Starting Load Kernel Modules...
[    3.898407] CMEMK module: reference Linux version 4.4.32
[    3.904516] allocated heap buffer 0x40500000 of size 0x100000
[    3.910442] cmemk initialized
[    3.921980] systemd[1]: Starting Remount Root and Kernel File Systems...
         Starting Remount Root and Kernel File Systems...
[    3.940440] EXT4-fs (mmcblk0p2): re-mounted. Opts: (null)
[    3.946122] cryptodev: driver 1.8 loaded.
[    3.951827] gdbserverproxy: disagrees about version of symbol module_layout
[    3.961552] systemd[1]: Starting Journal Service...
         Starting Journal Service...
[    3.989287] systemd[1]: Reached target Paths.
[  OK  ] Reached target Paths.
[    4.009569] systemd[1]: Listening on Network Service Netlink Socket.
[  OK  ] Listening on Network Service Netlink Socket.
[    4.079192] systemd[1]: Mounting Debug File System...
         Mounting Debug File System...
[    4.099370] systemd[1]: Listening on /dev/initctl Compatibility Named Pipe.
[  OK  ] Listening on /dev/initctl Compatibility Named Pipe.
[    4.129754] systemd[1]: Created slice system-serial\x2dgetty.slice.
[  OK  ] Created slice system-serial\x2dgetty.slice.
[    4.159299] systemd[1]: Listening on udev Control Socket.
[  OK  ] Listening on udev Control Socket.
[    4.223693] systemd[1]: Mounted Debug File System.
[  OK  ] Mounted Debug File System.
[    4.249284] systemd[1]: Mounted POSIX Message Queue File System.
[  OK  ] Mounted POSIX Message Queue File System.
[    4.279201] systemd[1]: Mounted Temporary Directory.
[  OK  ] Mounted Temporary Directory.
[    4.299392] systemd[1]: Started Journal Service.
[  OK  ] Started Journal Service.
[  OK  ] Started Setup Virtual Console.
[  OK  ] Started Create list of required sta...ce nodes for the current kernel.
[FAILED] Failed to start Load Kernel Modules.
See 'systemctl status systemd-modules-load.service' for details.
[  OK  ] Started Remount Root and Kernel File Systems.
         Starting udev Coldplug all Devices...
         Mounting Configuration File System...
         Starting Apply Kernel Variables...
         Starting Create Static Device Nodes in /dev...
         Starting Flush Journal to Persistent Storage...
[  OK  ] Mounted Configuration File System.
[  OK  ] Started Apply Kernel Variables.
[  OK  ] Started Create Static Device Nodes in /dev.
[    4.996487] systemd-journald[147]: Received request to flush runtime journal from PID 1
[  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 Kernel Device Manager.
[  OK  ] Started Flush Journal to Persistent Storage.
[  OK  ] Started udev Coldplug all Devices.
[    5.377676] omap-rproc 58820000.ipu: assigned reserved memory node ipu1_cma@9d000000
[    5.406273]  remoteproc0: 58820000.ipu is available
[    5.411266]  remoteproc0: Note: remoteproc is still under development and considered experimental.
[    5.444962] debugss_kmodule: disagrees about version of symbol module_layout
[    5.464988]  remoteproc0: THE BINARY FORMAT IS NOT YET FINALIZED, and backward compatibility isn't yet guaranteed.
[  OK  ] Reached target Local File Systems.
[    5.483598] debugss_kmodule: disagrees about version of symbol module_layout
[    5.500484] omap-rproc 55020000.ipu: assigned reserved memory node ipu2_cma@95800000
         Starting Create Volatile Files and Directories...
[    5.560296]  remoteproc1: 55020000.ipu is available
         Starting Load/Save Random Seed...[    5.580078]  remoteproc1: Note: remoteproc is still under development and considered experimental.

[  OK  ] Started Create Volatile Files and Directories.
[    5.623774]  remoteproc1: THE BINARY FORMAT IS NOT YET FINALIZED, and backward compatibility isn't yet guaranteed.
[    5.643793]  remoteproc0: registered virtio0 (type 7)
[  OK  ] Started Load/Save Random Seed.
[    5.657139] omap-rproc 40800000.dsp: assigned reserved memory node dsp1_cma@99000000
[  OK  ] Found device /dev/ttyS2.
[    5.722127]  remoteproc2: 40800000.dsp is available
[    5.729468]  remoteproc1: registered virtio1 (type 7)
[    5.748262]  remoteproc2: Note: remoteproc is still under development and considered experimental.
[    5.760648] rtc-ds1307 2-006f: SET TIME!
[    5.767993] rtc-ds1307 2-006f: rtc core: registered mcp7941x as rtc0
[    5.782254] mt9t11x 4-003c: input-clock-freq: 32000000
[    5.787423] mt9t11x 4-003c: pixel-clock-freq: 96000000
[    5.787746]  remoteproc2: THE BINARY FORMAT IS NOT YET FINALIZED, and backward compatibility isn't yet guaranteed.
[    5.791687] omap-rproc 41000000.dsp: assigned reserved memory node dsp2_cma@9f000000
[    5.791750]  remoteproc3: 41000000.dsp is available
[    5.791754]  remoteproc3: Note: remoteproc is still under development and considered experimental.
[    5.791758]  remoteproc3: THE BINARY FORMAT IS NOT YET FINALIZED, and backward compatibility isn't yet guaranteed.
[    5.861833] rtc-ds1307 2-006f: 64 bytes nvram
[    5.953202] palmas_pwrbutton 48070000.i2c:tps659038@58:tps659038_pwr_button: h/w controlled shutdown duration=12 seconds
[    5.992848] omap-des 480a5000.des: OMAP DES hw accel rev: 2.2
[    6.006983] mt9t11x 4-003c: Product ID error ffffff87
         Starting Update UTMP about System Boot/Shutdown...[    6.016243] input: palmas_pwron as /devices/platform/44000000.ocp/48070000.i2c/i2c-0/0-0058/48070000.i2c:tps659038@58:tps659038_pwr_button/input/input0

[    6.034540] omap_rng 48090000.rng: OMAP Random Number Generator ver. 20
[    6.063815] palmas-rtc 48070000.i2c:tps659038@58:tps659038_rtc: rtc core: registered 48070000.i2c:tps659 as rtc1
         Starting Network Time Synchronization...
[    6.161312] pixcir_ts 4-005c: pixcir_set_power_mode: can't read reg 0x33 : -121
[    6.173517] pixcir_ts 4-005c: Failed to set IDLE mode
[    6.192578] omap_rtc 48838000.rtc: rtc core: registered 48838000.rtc as rtc2
[    6.221356] pixcir_ts: probe of 4-005c failed with error -121
[  OK  ] Started Update UTMP about System Boot/Shutdown.
[    6.449730] omap_wdt: OMAP Watchdog Timer Rev 0x01: initial timeout 60 sec
[    6.459716] vpe 489d0000.vpe: Device registered as /dev/video0
[    6.473948] omap-sham 4b101000.sham: hw accel on OMAP rev 4.3
[    6.521163] omap-aes 4b500000.aes: OMAP AES hw accel rev: 3.3
[    6.546245] omap-aes 4b700000.aes: OMAP AES hw accel rev: 3.3
[    6.556858] ti-pruss 4b200000.pruss: creating PRU cores and other child platform devices
[    6.569649] irq: no irq domain found for /ocp/pruss@4b200000/intc@4b220000 !
[    6.613897] irq: no irq domain found for /ocp/pruss@4b200000/intc@4b220000 !
[    6.636642] ti-pruss 4b280000.pruss: creating PRU cores and other child platform devices
[    6.666172] pvrsrvkm: disagrees about version of symbol module_layout
[    6.666863] irq: no irq domain found for /ocp/pruss@4b280000/intc@4b2a0000 !
[    6.667454] irq: no irq domain found for /ocp/pruss@4b280000/intc@4b2a0000 !
[  OK  ] Created slice system-systemd\x2dbacklight.slice.[    6.697526]  remoteproc3: registered virtio2 (type 7)

[    6.704991]  remoteproc2: registered virtio3 (type 7)
[    6.718466]  remoteproc4: 4b234000.pru0 is available
[    6.735023] input: gpio_keys as /devices/platform/gpio_keys/input/input2
[    6.740702] pvrsrvkm: disagrees about version of symbol module_layout
[    6.742064]  remoteproc4: Note: remoteproc is still under development and considered experimental.
[    6.742067]  remoteproc4: THE BINARY FORMAT IS NOT YET FINALIZED, and backward compatibility isn't yet guaranteed.
[    6.743520] pru-rproc 4b234000.pru0: booting the PRU core manually
[    6.743525]  remoteproc4: powering up 4b234000.pru0
[    6.743602]  remoteproc4: Booting fw image am57xx-pru1_0-fw, size 28228
[    6.743627]  remoteproc4: remote processor 4b234000.pru0 is now up
[    6.743642] pru-rproc 4b234000.pru0: PRU rproc node /ocp/pruss@4b200000/pru0@4b234000 probed successfully
[    6.743978]  remoteproc5: 4b238000.pru1 is available
[    6.743981]  remoteproc5: Note: remoteproc is still under development and considered experimental.
[    6.743984]  remoteproc5: THE BINARY FORMAT IS NOT YET FINALIZED, and backward compatibility isn't yet guaranteed.
[    6.744210] pru-rproc 4b238000.pru1: booting the PRU core manually
[    6.744214]  remoteproc5: powering up 4b238000.pru1
[    6.744290]  remoteproc5: Booting fw image am57xx-pru1_1-fw, size 28228
[    6.744312]  remoteproc5: remote processor 4b238000.pru1 is now up
[    6.744324] pru-rproc 4b238000.pru1: PRU rproc node /ocp/pruss@4b200000/pru1@4b238000 probed successfully
[    6.744571]  remoteproc6: 4b2b4000.pru0 is available
[    6.744574]  remoteproc6: Note: remoteproc is still under development and considered experimental.
[    6.744577]  remoteproc6: THE BINARY FORMAT IS NOT YET FINALIZED, and backward compatibility isn't yet guaranteed.
[    6.748180]  remoteproc6: registered virtio4 (type 7)
[    6.748252] pru-rproc 4b2b4000.pru0: PRU rproc node /ocp/pruss@4b280000/pru0@4b2b4000 probed successfully
[    6.748545]  remoteproc7: 4b2b8000.pru1 is available
[    6.748549]  remoteproc7: Note: remoteproc is still under development and considered experimental.
[    6.748552]  remoteproc7: THE BINARY FORMAT IS NOT YET FINALIZED, and backward compatibility isn't yet guaranteed.
[    6.748781] pru-rproc 4b2b8000.pru1: booting the PRU core manually
[    6.748786]  remoteproc7: powering up 4b2b8000.pru1
[    6.748855]  remoteproc7: Booting fw image am57xx-pru2_1-fw, size 28228
[    6.748880]  remoteproc7: remote processor 4b2b8000.pru1 is now up
[    6.748893] pru-rproc 4b2b8000.pru1: PRU rproc node /ocp/pruss@4b280000/pru1@4b2b8000 probed successfully
         Starting Load/Save Screen Backlight...htness of backlight:backlight...
[  OK  ] Started Network Time Synchronization.
[  OK  ] Started Load/Save Screen Backlight Brightness of backlight:backlight.
[    7.649727]  remoteproc0: powering up 58820000.ipu
[    7.709803] FAT-fs (mmcblk0p1): Volume was not properly unmounted. Some data may be corrupt. Please run fsck.
[    7.741791]  remoteproc0: Booting fw image dra7-ipu1-fw.xem4, size 4890352
[    7.748823] omap-iommu 58882000.mmu: 58882000.mmu: version 2.1
[    7.814878]  remoteproc0: remote processor 58820000.ipu is now up
[    7.831073] virtio_rpmsg_bus virtio0: rpmsg host is online
[    7.836639] virtio_rpmsg_bus virtio0: creating channel rpmsg-client-sample addr 0x32
[    7.850856] virtio_rpmsg_bus virtio0: creating channel rpmsg-client-sample addr 0x33
[    7.858907] virtio_rpmsg_bus virtio0: creating channel rpmsg-omx addr 0x3c
[    7.867019] virtio_rpmsg_bus virtio0: creating channel rpmsg-rpc addr 0x65
         Starting Synchronize System and HW clocks...[    7.888155]  remoteproc1: powering up 55020000.ipu

[    7.904074]  remoteproc1: Booting fw image dra7-ipu2-fw.xem4, size 3743072
[  OK  ] Reached target System Time Synchronized.[    7.915160] omap-iommu 55082000.mmu: 55082000.mmu: version 2.1

[  OK  ] Started Synchronize System and HW clocks.[    7.937873] EXT4-fs (mmcblk0p3): recovery complete

[    7.943457] EXT4-fs (mmcblk0p3): mounted filesystem with ordered data mode. Opts: (null)
[  OK  ] Reached target System Initialization.
[    8.092233]  remoteproc1: remote processor 55020000.ipu is now up
[    8.099804] virtio_rpmsg_bus virtio1: rpmsg host is online
[    8.102877] virtio_rpmsg_bus virtio1: creating channel rpmsg-rpc addr 0x65
[    8.103765] virtio_rpmsg_bus virtio1: creating channel rpmsg-rpc addr 0x66
[  OK  ] Started Daily Cleanup of Temporary Directories.[    8.120766]  remoteproc3: powering up 41000000.dsp

[    8.156742]  remoteproc3: Booting fw image dra7-dsp2-fw.xe66, size 21998880
[  OK  ] Reached target Timers.
[    8.171383] omap_hwmod: mmu0_dsp2: _wait_target_disable failed
[    8.177281] omap-iommu 41501000.mmu: 41501000.mmu: version 3.0
[    8.183232] omap-iommu 41502000.mmu: 41502000.mmu: version 3.0
[  OK  ] Listening on RPCbind Server Activation Socket.[    8.201320]  remoteproc3: remote processor 41000000.dsp is now up

[    8.214634] virtio_rpmsg_bus virtio2: rpmsg host is online
[    8.214718] virtio_rpmsg_bus virtio2: creating channel rpmsg-proto addr 0x3d
[    8.228514]  remoteproc2: powering up 40800000.dsp
[  OK  ] Listening on D-Bus System Message Bus Socket.
[    8.264342]  remoteproc2: Booting fw image dra7-dsp1-fw.xe66, size 21998880
[  OK  ] Listening on dropbear.socket.
[    8.278914] omap_hwmod: mmu0_dsp1: _wait_target_disable failed
[    8.284822] omap-iommu 40d01000.mmu: 40d01000.mmu: version 3.0
[    8.290783] omap-iommu 40d02000.mmu: 40d02000.mmu: version 3.0
[  OK  ] Listening on Avahi mDNS/DNS-SD Stack Activation Socket.[    8.309582]  remoteproc2: remote processor 40800000.dsp is now up

[  OK  ] Reached target Sockets.[    8.327409] virtio_rpmsg_bus virtio3: rpmsg host is online

[    8.327493] virtio_rpmsg_bus virtio3: creating channel rpmsg-proto addr 0x3d
[    8.345583]  remoteproc6: powering up 4b2b4000.pru0
[    8.351794]  remoteproc6: Booting fw image am57xx-pru2_0-fw, size 186124
[    8.352039] ti-pruss 4b280000.pruss: configured system_events = 0x0000000000030080 intr_channels = 0x00000005 host_intr = 0x00000005
[    8.355251] usbcore: registered new interface driver usbfs
[    8.355320] usbcore: registered new interface driver hub
[    8.355428] usbcore: registered new device driver usb
[    8.358900]  remoteproc6: remote processor 4b2b4000.pru0 is now up
[    8.372809] virtio_rpmsg_bus virtio4: rpmsg host is online
[    8.372848] virtio_rpmsg_bus virtio4: creating channel rpmsg-pru addr 0x20
[    8.391603] xhci-hcd xhci-hcd.1.auto: xHCI Host Controller
[    8.391630] xhci-hcd xhci-hcd.1.auto: new USB bus registered, assigned bus number 1
[    8.404051] rpmsg_rpc rpmsg3: probing service rpc_example_2 with src 1024 dst 101
[    8.406070] rpmsg_rpc rpmsg4: probing service dce-callback with src 1024 dst 101
[    8.406618] rpmsg_rpc rpmsg3: published functions = 8
[    8.406860] rpmsg_rpc rpmsg5: probing service rpmsg-dce with src 1025 dst 102
[    8.407832] rpmsg_rpc rpmsg4: published functions = 4
[    8.418292] rpmsg_pru rpmsg8: new rpmsg_pru device: /dev/rpmsg_pru32
[    8.422093] rpmsg_rpc rpmsg5: published functions = 9
[    8.427675] NET: Registered protocol family 41
[    8.433710] xhci-hcd xhci-hcd.1.auto: hcc params 0x0220f04c hci version 0x100 quirks 0x00010010
[    8.433779] xhci-hcd xhci-hcd.1.auto: irq 444, io mem 0x48890000
[    8.433996] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002
[    8.434007] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[    8.434017] usb usb1: Product: xHCI Host Controller
[    8.434022] usb usb1: Manufacturer: Linux 4.4.32-gadde2ca9f8 xhci-hcd
[    8.434027] usb usb1: SerialNumber: xhci-hcd.1.auto
[    8.435733] hub 1-0:1.0: USB hub found
[    8.436183] hub 1-0:1.0: 1 port detected
[    8.436610] xhci-hcd xhci-hcd.1.auto: xHCI Host Controller
[    8.436624] xhci-hcd xhci-hcd.1.auto: new USB bus registered, assigned bus number 2
[    8.436706] usb usb2: We don't know the algorithms for LPM for this host, disabling LPM.
[    8.436846] usb usb2: New USB device found, idVendor=1d6b, idProduct=0003
[    8.436853] usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[    8.436857] usb usb2: Product: xHCI Host Controller
[    8.436862] usb usb2: Manufacturer: Linux 4.4.32-gadde2ca9f8 xhci-hcd
[    8.436870] usb usb2: SerialNumber: xhci-hcd.1.auto
[    8.439971] hub 2-0:1.0: USB hub found
[    8.440375] hub 2-0:1.0: 1 port detected
[    8.440944] xhci-hcd xhci-hcd.2.auto: xHCI Host Controller
[    8.440964] xhci-hcd xhci-hcd.2.auto: new USB bus registered, assigned bus number 3
[    8.446455] xhci-hcd xhci-hcd.2.auto: hcc params 0x0220f04c hci version 0x100 quirks 0x00010010
[    8.446511] xhci-hcd xhci-hcd.2.auto: irq 445, io mem 0x488d0000
[    8.447155] usb usb3: New USB device found, idVendor=1d6b, idProduct=0002
[    8.447161] usb usb3: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[    8.447167] usb usb3: Product: xHCI Host Controller
[    8.447171] usb usb3: Manufacturer: Linux 4.4.32-gadde2ca9f8 xhci-hcd
[    8.447175] usb usb3: SerialNumber: xhci-hcd.2.auto
[    8.453796] hub 3-0:1.0: USB hub found
[    8.454251] hub 3-0:1.0: 1 port detected
[    8.454657] xhci-hcd xhci-hcd.2.auto: xHCI Host Controller
[    8.454673] xhci-hcd xhci-hcd.2.auto: new USB bus registered, assigned bus number 4
[    8.454805] usb usb4: We don't know the algorithms for LPM for this host, disabling LPM.
[    8.454951] usb usb4: New USB device found, idVendor=1d6b, idProduct=0003
[    8.454957] usb usb4: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[    8.454962] usb usb4: Product: xHCI Host Controller
[    8.454966] usb usb4: Manufacturer: Linux 4.4.32-gadde2ca9f8 xhci-hcd
[    8.454971] usb usb4: SerialNumber: xhci-hcd.2.auto
[    8.456392] hub 4-0:1.0: USB hub found
[    8.457298] hub 4-0:1.0: 1 port detected
[    8.768983] usb 3-1: new high-speed USB device number 2 using xhci-hcd
[  OK  ] Reached target Basic System.
         Starting Avahi mDNS/DNS-SD Stack...
         Starting Print notice about GPLv3 packages...
         Starting Permit User Sessions...
[  OK  ] Started SAMCA application started.
[    8.909504] usb 3-1: New USB device found, idVendor=0451, idProduct=8142
[  OK  ] Started strongSwan IPsec IKEv1/IKEv2 daemon using ipsec.conf.
[  OK  ] Started Kernel Logging Service.
         Starting Login Service...
         Starting uim-sysfs.service...
[    8.947151] usb 3-1: New USB device strings: Mfr=0, Product=0, SerialNumber=1
[  OK  ] Started SAMCA application started.
         Starting Telephony service...
[    8.979276] usb 3-1: SerialNumber: AD070089F21F
[  OK  ] Started System Logging Service.
[  OK  ] Started D-Bus System Message Bus.
[    9.043931] hub 3-1:1.0: USB hub found
[    9.057327] hub 3-1:1.0: 4 ports detected
[  OK  ] Started Avahi mDNS/DNS-SD Stack.
[  OK  ] Started Telephony service.
[    9.174964] random: nonblocking pool is initialized
         Starting Network Service...
[  OK  ] Started Permit User Sessions.
[    9.280008] Bluetooth: Core ver 2.21
[    9.287139] net eth0: initializing cpsw version 1.15 (0)
[    9.292006] NET: Registered protocol family 31
[    9.292010] Bluetooth: HCI device and connection manager initialized
[    9.292026] Bluetooth: HCI socket layer initialized
[    9.292036] Bluetooth: L2CAP socket layer initialized
[    9.292072] Bluetooth: SCO socket layer initialized
[  OK  ] Started Network Service.
[    9.330873] net eth0: initialized cpsw ale version 1.4
[    9.344862] net eth0: ALE Table size 1024
[    9.409037] usb 3-1.1: new high-speed USB device number 3 using xhci-hcd
[    9.461599] net eth0: phy found : id is : 0x221622
[    9.479538] libphy: PHY  not found
[    9.491567] net eth0: phy "" not found on slave 1, err -19
[    9.538611] IPv6: ADDRCONF(NETDEV_UP): eth0: link is not ready
[    9.592400] usb 3-1.1: New USB device found, idVendor=05e3, idProduct=f11d
[    9.603338] usb 3-1.1: New USB device strings: Mfr=2, Product=1, SerialNumber=0
[    9.632622] usb 3-1.1: Product: CK1002-PB-U-LT00
[    9.650708] usb 3-1.1: Manufacturer: CZM100200431
[  OK  ] Reached target Network.
         Starting Lightning Fast Webserver With Light System Requirements...
         Starting Network Name Resolution...
[  OK  ] Started Serial Getty on ttyS2.
[  OK  ] Started Getty on tty1.
[    9.838996] usb 3-1.2: new high-speed USB device number 4 using xhci-hcd
[  OK  ] Started Lightning Fast Webserver With Light System Requirements.
[  OK  ] Started Network Name Resolution.
[  OK  ] Started Login Service.
[    9.999821] uvcvideo: Found UVC 1.00 device CK1002-PB-U-LT00 (05e3:f11d)
[   10.032677] usb 3-1.2: New USB device found, idVendor=05e3, idProduct=f11d
[   10.046670] usb 3-1.2: New USB device strings: Mfr=2, Product=1, SerialNumber=0
[   10.069035] usb 3-1.2: Product: CK1002-PB-U-LT00
[   10.073814] usb 3-1.2: Manufacturer: CZM100200393
[   10.201921] input: CK1002-PB-U-LT00 as /devices/platform/44000000.ocp/488c0000.omap_dwc3_2/488d0000.usb/xhci-hcd.2.auto/usb3/3-1/3-1.1/3-1.1:1.0/input/input3
[   10.241639] uvcvideo: Found UVC 1.00 device CK1002-PB-U-LT00 (05e3:f11d)
[   10.670039] input: CK1002-PB-U-LT00 as /devices/platform/44000000.ocp/488c0000.omap_dwc3_2/488d0000.usb/xhci-hcd.2.auto/usb3/3-1/3-1.2/3-1.2:1.0/input/input4
[   10.690098] usbcore: registered new interface driver uvcvideo
[   10.695888] USB Video Class driver (1.1.1)
[   10.768984] usb 3-1.3: new high-speed USB device number 5 using xhci-hcd
***************************************************************
***************************************************************
NOTICE: This file system contains the following GPLv3 packages:
        autoconf
        binutils-dev
        binutils
        bison-dev
        bison
        cpp-symlinks
        cpp
        dosfstools
        g++-symlinks
        g++
        gawk-dev
        gawk
        gcc-symlinks
        gcc
        gdb
        gdbc6x
        gdbserver
        gstreamer1.0-libav
        hidapi
        libcairo-perf-utils
        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
***************************************************************
***************************************************************
[  OK  ] Started Print notice about GPLv3 packages.
[   10.960745] usb 3-1.3: New USB device found, idVendor=05e3, idProduct=f11d
0;32m  OK  ] Started uim-sysfs.service.
         Startingrc.pvr.service...
[   10.977385] usb 3-1.3: New USB device strings: Mfr=2, Product=1, SerialNumber=0
[   10.982154] pvrsrvkm: disagrees about version of symbol module_layout
[   10.997951] usb 3-1.3: Product: CK1002-PB-U-LT00
[   11.002628] usb 3-1.3: Manufacturer: CZM100200392
[   11.073565] uvcvideo: Found UVC 1.00 device CK1002-PB-U-LT00 (05e3:f11d)
[   11.501989] input: CK1002-PB-U-LT00 as /devices/platform/44000000.ocp/488c0000.omap_dwc3_2/488d0000.usb/xhci-hcd.2.auto/usb3/3-1/3-1.3/3-1.3:1.0/input/input5
[   12.272951] pvrsrvkm: disagrees about version of symbol module_layout
[[;32m  OK   ]Started rc.pvr.service.
         tSarting weston.service...
[   12.349002] Unable to handle kernel paging request at virtual address 00002248
[   12.356265] pgd = c0003000
[   12.356274] [00002248] *pgd=80000080004003, *pmd=00000000
[   12.356281] Internal error: Oops: 205 [#1] PREEMPT SMP ARM
[   12.356381] Modules linked in: sha512_generic uvcvideo videobuf2_vmalloc sha1_generic md5 cbc sha256_generic hmac drbg bluetooth esp4 rpmsg_proto rpmsg_pru rpmsg_rpc xhci_plat_hcd xhci_hcd usbcore dwc3 udc_core evdev virtio_rpmsg_bus gpio_keys pru_rproc pruss_intc leds_gpio pruss omap_aes_driver omap_sham phy_omap_usb2 omap_wdt dwc3_omap rtc_omap extcon_palmas omap_rng extcon rtc_palmas omap_des rng_core pixcir_i2c_ts palmas_pwrbutton mt9t11x rtc_ds1307 omap_remoteproc remoteproc virtio virtio_ring sch_fq_codel uio_module_drv(O) uio cryptodev(O) cmemk(O) ipv6 autofs4
[   12.356388] CPU: 1 PID: 6 Comm: kworker/u4:0 Tainted: G           O    4.4.32-gadde2ca9f8 #1
[   12.356390] Hardware name: Generic DRA74X (Flattened Device Tree)
[   12.356402] Workqueue: events_unbound flush_to_ldisc
[   12.356407] task: ef885900 ti: ef8a4000 task.ti: ef8a4000
[   12.356416] PC is at n_tty_receive_buf_common+0x7c/0xa60
[   12.356422] LR is at n_tty_receive_buf_common+0x78/0xa60
[   12.356428] pc : [<c03624a0>]    lr : [<c036249c>]    psr: 200e0013
[   12.356428] sp : ef8a5e28  ip : ef8a5e28  fp : ef8a5e94
[   12.356431] r10: ee9a1600  r9 : ef809000  r8 : ef809014
[   12.356434] r7 : ee9a1600  r6 : ee9a1784  r5 : ee9a1680  r4 : c062e910
[   12.356437] r3 : 00002000  r2 : 00000001  r1 : 00000000  r0 : ee9a1680
[   12.356442] Flags: nzCv  IRQs on  FIQs on  Mode SVC_32  ISA ARM  Segment kernel
[   12.356446] Control: 30c5387d  Table: af8d1880  DAC: 55555555
[   12.356450] Process kworker/u4:0 (pid: 6, stack limit = 0xef8a4218)
[   12.356454] Stack: (0xef8a5e28 to 0xef8a6000)
[   12.356460] 5e20:                   ee9a1680 00000000 ef8a5e5c 00020001 ee9a1784 55555556
[   12.356465] 5e40: ef885948 c088c744 c062e910 eff35f40 00002000 00000000 00000000 ee10d418
[   12.356471] 5e60: 00000100 ee10d518 ef8a5e9c ee10d400 00000000 ef809004 ee9a1600 ef809014
[   12.356476] 5e80: ef809000 c0362e84 ef8a5eac ef8a5e98 c0362ea0 c0362430 00000001 ef858f80
[   12.356481] 5ea0: ef8a5ee4 ef8a5eb0 c0366510 c0362e90 00000001 ee106440 ef8a5f0c ef809004
[   12.356485] 5ec0: ef858f80 ef89c000 00000000 ef859400 00000000 ef89c000 ef8a5f24 ef8a5ee8
[   12.356490] 5ee0: c004964c c0366434 ef89c000 ef89c014 ef8a4000 00000088 ef858f80 ef89c000
[   12.356496] 5f00: ef858f98 ef89c014 ef8a4000 00000088 ef858f80 ef89c000 ef8a5f5c ef8a5f28
[   12.356501] 5f20: c0049968 c0049534 ef89c164 c088c100 00000000 00000000 ef85c200 ef858f80
[   12.356506] 5f40: c004991c 00000000 00000000 00000000 ef8a5fac ef8a5f60 c004f4b0 c0049928
[   12.356510] 5f60: c64e66ac 00000000 c64e66ac ef858f80 00000000 00000000 ef8a5f78 ef8a5f78
[   12.356516] 5f80: 00000000 00000000 ef8a5f88 ef8a5f88 ef85c200 c004f3cc 00000000 00000000
[   12.356521] 5fa0: 00000000 ef8a5fb0 c000fa48 c004f3d8 00000000 00000000 00000000 00000000
[   12.356525] 5fc0: 00000000 00000000 00000000 00000000 00000000 00000000 00000000 00000000
[   12.356530] 5fe0: 00000000 00000000 00000000 00000000 00000013 00000000 1e5cd436 1e5cd436
[   12.356534] Backtrace:
[   12.356544] [<c0362424>] (n_tty_receive_buf_common) from [<c0362ea0>] (n_tty_receive_buf2+0x1c/0x24)
[   12.356559]  r10:c0362e84 r9:ef809000 r8:ef809014 r7:ee9a1600 r6:ef809004 r5:00000000
[   12.356564]  r4:ee10d400
[   12.356571] [<c0362e84>] (n_tty_receive_buf2) from [<c0366510>] (flush_to_ldisc+0xe8/0x134)
[   12.356581] [<c0366428>] (flush_to_ldisc) from [<c004964c>] (process_one_work+0x124/0x3f4)
[   12.356593]  r10:ef89c000 r9:00000000 r8:ef859400 r7:00000000 r6:ef89c000 r5:ef858f80
[   12.356597]  r4:ef809004
[   12.356605] [<c0049528>] (process_one_work) from [<c0049968>] (worker_thread+0x4c/0x524)
[   12.356617]  r10:ef89c000 r9:ef858f80 r8:00000088 r7:ef8a4000 r6:ef89c014 r5:ef858f98
[   12.356621]  r4:ef89c000
[   12.356629] [<c004991c>] (worker_thread) from [<c004f4b0>] (kthread+0xe4/0xfc)
[   12.356640]  r10:00000000 r9:00000000 r8:00000000 r7:c004991c r6:ef858f80 r5:ef85c200
[   12.356644]  r4:00000000
[   12.356653] [<c004f3cc>] (kthread) from [<c000fa48>] (ret_from_fork+0x14/0x2c)
[   12.356663]  r7:00000000 r6:00000000 r5:c004f3cc r4:ef85c200
[   12.356669] Code: e50b2030 e50b3034 eb0a9890 e51b3044 (e5930248)
[   12.356674] ---[ end trace d1d28340ed431a4a ]---
[   12.356720] Unable to handle kernel paging request at virtual address ffffffec
[   12.356723] pgd = c0003000
[   12.356733] [ffffffec] *pgd=80000080007003, *pmd=affae003, *pte=00000000
[   12.356737] Internal error: Oops: 207 [#2] PREEMPT SMP ARM
[   12.356826] Modules linked in: sha512_generic uvcvideo videobuf2_vmalloc sha1_generic md5 cbc sha256_generic hmac drbg bluetooth esp4 rpmsg_proto rpmsg_pru rpmsg_rpc xhci_plat_hcd xhci_hcd usbcore dwc3 udc_core evdev virtio_rpmsg_bus gpio_keys pru_rproc pruss_intc leds_gpio pruss omap_aes_driver omap_sham phy_omap_usb2 omap_wdt dwc3_omap rtc_omap extcon_palmas omap_rng extcon rtc_palmas omap_des rng_core pixcir_i2c_ts palmas_pwrbutton mt9t11x rtc_ds1307 omap_remoteproc remoteproc virtio virtio_ring sch_fq_codel uio_module_drv(O) uio cryptodev(O) cmemk(O) ipv6 autofs4
[   12.356831] CPU: 1 PID: 6 Comm: kworker/u4:0 Tainted: G      D    O    4.4.32-gadde2ca9f8 #1
[   12.356834] Hardware name: Generic DRA74X (Flattened Device Tree)
[   12.356845] task: ef885900 ti: ef8a4000 task.ti: ef8a4000
[   12.356851] PC is at kthread_data+0x10/0x18
[   12.356856] LR is at wq_worker_sleeping+0x14/0xd8
[   12.356861] pc : [<c004f8fc>]    lr : [<c004a718>]    psr: 200e0193
[   12.356861] sp : ef8a5b60  ip : ef8a5b70  fp : ef8a5b6c
[   12.356864] r10: 00000000  r9 : 00000001  r8 : c088c744
[   12.356868] r7 : ef885bb0  r6 : ef885900  r5 : eff35f40  r4 : 00000001
[   12.356871] r3 : 00000000  r2 : 00000020  r1 : 00000001  r0 : ef885900
[   12.356876] Flags: nzCv  IRQs off  FIQs on  Mode SVC_32  ISA ARM  Segment user
[   12.356879] Control: 30c5387d  Table: af8d1880  DAC: 55555555
[   12.356883] Process kworker/u4:0 (pid: 6, stack limit = 0xef8a4218)
[   12.356887] Stack: (0xef8a5b60 to 0xef8a6000)
[   12.356893] 5b60: ef8a5b84 ef8a5b70 c004a718 c004f8f8 c0888f40 eff35f40 ef8a5bcc ef8a5b88
[   12.356899] 5b80: c0606344 c004a710 00000000 efb99240 efb9924c 600e0113 ef8a5bc4 c0606568
[   12.356904] 5ba0: c02b7194 ef8a4000 ef8a59ac ef884000 ef8a5bf0 ef885b28 c03624a2 c03624a4
[   12.356909] 5bc0: ef8a5be4 ef8a5bd0 c0606568 c060600c ef885900 ef8a59ac ef8a5c0c ef8a5be8
[   12.356914] 5be0: c00374a4 c0606520 00000001 00000000 ef8a5bf0 ef8a5bf0 c0034818 0000000b
[   12.356919] 5c00: ef8a5c94 ef8a5c10 c0013684 c0036e44 ef8a4218 0000000b c077c578 c077c570
[   12.356924] 5c20: 00000008 00000000 00000000 600e0113 658a5c5c 32623035 20303330 62303565
[   12.356928] 5c40: 34333033 30626520 39383961 35652030 30336231 28203434 33393565 38343230
[   12.356933] 5c60: c0002029 00000000 00000000 00002248 ef8a5dd8 00000205 00000000 00000000
[   12.356938] 5c80: ef809000 ee9a1600 ef8a5cac ef8a5c98 c002141c c0013254 ef8a5dd8 00002248
[   12.356944] 5ca0: ef8a5cfc ef8a5cb0 c060b298 c00213bc 00007818 004898ed 00000000 ea6f7103
[   12.356949] 5cc0: ef8a5cf4 ef8a5cd0 c005e18c c02d8678 eff35f80 c08905e4 00000205 c060b3e8
[   12.356954] 5ce0: 00002248 ef8a5dd8 ef809000 ee9a1600 ef8a5d24 ef8a5d00 c060b4ec c060b014
[   12.356958] 5d00: c08905e4 00000205 c060b3e8 00002248 ef8a5dd8 ef809000 ef8a5dd4 ef8a5d28
[   12.356964] 5d20: c0009298 c060b3f4 0000163d 00000000 0000163d 00000000 ef8a5d6c ef8a5d48
[   12.356969] 5d40: c00603a8 c006ff34 eff35f80 ef885948 eff35f80 ef885948 035d2361 00000000
[   12.356974] 5d60: ef8a5df4 ef8a5d70 c0061ba0 c0060410 00000002 efb99240 ef8a5db4 ef8a5d88
[   12.356979] 5d80: c006e4e8 c060a1e8 00000000 efb99240 ef8a5dac c08e79a8 ef8a5de4 ef885948
[   12.356984] 5da0: 035ebe3c 00000000 00000c00 00ffffff 00000001 c03624a0 200e0013 ffffffff
[   12.356989] 5dc0: ef8a5e0c ef809014 ef8a5e94 ef8a5dd8 c060a958 c000926c ee9a1680 00000000
[   12.356994] 5de0: 00000001 00002000 c062e910 ee9a1680 ee9a1784 ee9a1600 ef809014 ef809000
[   12.356999] 5e00: ee9a1600 ef8a5e94 ef8a5e28 ef8a5e28 c036249c c03624a0 200e0013 ffffffff
[   12.357004] 5e20: c036249c c06086ec ee9a1680 00000000 ef8a5e5c 00020001 ee9a1784 55555556
[   12.357008] 5e40: ef885948 c088c744 c062e910 eff35f40 00002000 00000000 00000000 ee10d418
[   12.357013] 5e60: 00000100 ee10d518 ef8a5e9c ee10d400 00000000 ef809004 ee9a1600 ef809014
[   12.357019] 5e80: ef809000 c0362e84 ef8a5eac ef8a5e98 c0362ea0 c0362430 00000001 ef858f80
[   12.357024] 5ea0: ef8a5ee4 ef8a5eb0 c0366510 c0362e90 00000001 ee106440 ef8a5f0c ef809004
[   12.357028] 5ec0: ef858f80 ef89c000 00000000 ef859400 00000000 ef89c000 ef8a5f24 ef8a5ee8
[   12.357033] 5ee0: c004964c c0366434 ef89c000 ef89c014 ef8a4000 00000088 ef858f80 ef89c000
[   12.357039] 5f00: ef858f98 ef89c014 ef8a4000 00000088 ef858f80 ef89c000 ef8a5f5c ef8a5f28
[   12.357044] 5f20: c0049968 c0049534 ef89c164 c088c100 00000000 00000000 ef85c200 ef858f80
[   12.357048] 5f40: c004991c 00000000 00000000 00000000 ef8a5fac ef8a5f60 c004f4b0 c0049928
[   12.357053] 5f60: c64e66ac 00000000 c64e66ac ef858f80 00000000 00000000 ef8a5f78 ef8a5f78
[   12.357058] 5f80: 00000001 00010001 ef8a5f88 ef8a5f88 ef85c200 c004f3cc 00000000 00000000
[   12.357063] 5fa0: 00000000 ef8a5fb0 c000fa48 c004f3d8 00000000 00000000 00000000 00000000
[   12.357067] 5fc0: 00000000 00000000 00000000 00000000 00000000 00000000 00000000 00000000
[   12.357072] 5fe0: 00000000 00000000 00000000 00000000 00000013 00000000 1e5cd436 1e5cd436
[   12.357075] Backtrace:
[   12.357084] [<c004f8ec>] (kthread_data) from [<c004a718>] (wq_worker_sleeping+0x14/0xd8)
[   12.357095] [<c004a704>] (wq_worker_sleeping) from [<c0606344>] (__schedule+0x344/0x514)
[   12.357102]  r5:eff35f40 r4:c0888f40
[   12.357109] [<c0606000>] (__schedule) from [<c0606568>] (schedule+0x54/0xc4)
[   12.357122]  r10:c03624a4 r9:c03624a2 r8:ef885b28 r7:ef8a5bf0 r6:ef884000 r5:ef8a59ac
[   12.357125]  r4:ef8a4000
[   12.357133] [<c0606514>] (schedule) from [<c00374a4>] (do_exit+0x66c/0x9e8)
[   12.357140]  r5:ef8a59ac r4:ef885900
[   12.357148] [<c0036e38>] (do_exit) from [<c0013684>] (die+0x43c/0x44c)
[   12.357152]  r7:0000000b
[   12.357162] [<c0013248>] (die) from [<c002141c>] (__do_kernel_fault.part.0+0x6c/0x1ec)
[   12.357174]  r10:ee9a1600 r9:ef809000 r8:00000000 r7:00000000 r6:00000205 r5:ef8a5dd8
[   12.357178]  r4:00002248
[   12.357186] [<c00213b0>] (__do_kernel_fault.part.0) from [<c060b298>] (do_page_fault+0x290/0x3e0)
[   12.357192]  r7:00002248 r4:ef8a5dd8
[   12.357198] [<c060b008>] (do_page_fault) from [<c060b4ec>] (do_translation_fault+0x104/0x108)
[   12.357210]  r10:ee9a1600 r9:ef809000 r8:ef8a5dd8 r7:00002248 r6:c060b3e8 r5:00000205
[   12.357214]  r4:c08905e4
[   12.357220] [<c060b3e8>] (do_translation_fault) from [<c0009298>] (do_DataAbort+0x38/0xb8)
[   12.357231]  r9:ef809000 r8:ef8a5dd8 r7:00002248 r6:c060b3e8 r5:00000205 r4:c08905e4
[   12.357240] [<c0009260>] (do_DataAbort) from [<c060a958>] (__dabt_svc+0x38/0x60)
[   12.357243] Exception stack(0xef8a5dd8 to 0xef8a5e20)
[   12.357247] 5dc0:                                                       ee9a1680 00000000
[   12.357252] 5de0: 00000001 00002000 c062e910 ee9a1680 ee9a1784 ee9a1600 ef809014 ef809000
[   12.357257] 5e00: ee9a1600 ef8a5e94 ef8a5e28 ef8a5e28 c036249c c03624a0 200e0013 ffffffff
[   12.357268]  r8:ef809014 r7:ef8a5e0c r6:ffffffff r5:200e0013 r4:c03624a0
[   12.357278] [<c0362424>] (n_tty_receive_buf_common) from [<c0362ea0>] (n_tty_receive_buf2+0x1c/0x24)
[   12.357289]  r10:c0362e84 r9:ef809000 r8:ef809014 r7:ee9a1600 r6:ef809004 r5:00000000
[   12.357293]  r4:ee10d400
[   12.357300] [<c0362e84>] (n_tty_receive_buf2) from [<c0366510>] (flush_to_ldisc+0xe8/0x134)
[   12.357307] [<c0366428>] (flush_to_ldisc) from [<c004964c>] (process_one_work+0x124/0x3f4)
[   12.357318]  r10:ef89c000 r9:00000000 r8:ef859400 r7:00000000 r6:ef89c000 r5:ef858f80
[   12.357322]  r4:ef809004
[   12.357331] [<c0049528>] (process_one_work) from [<c0049968>] (worker_thread+0x4c/0x524)
[   12.357343]  r10:ef89c000 r9:ef858f80 r8:00000088 r7:ef8a4000 r6:ef89c014 r5:ef858f98
[   12.357347]  r4:ef89c000
[   12.357354] [<c004991c>] (worker_thread) from [<c004f4b0>] (kthread+0xe4/0xfc)
[   12.357365]  r10:00000000 r9:00000000 r8:00000000 r7:c004991c r6:ef858f80 r5:ef85c200
[   12.357369]  r4:00000000
[   12.357376] [<c004f3cc>] (kthread) from [<c000fa48>] (ret_from_fork+0x14/0x2c)
[   12.357385]  r7:00000000 r6:00000000 r5:c004f3cc r4:ef85c200
[   12.357391] Code: e1a0c00d e92dd800 e24cb004 e5903280 (e5130014)
[   12.357396] ---[ end trace d1d28340ed431a4b ]---
[   12.357399] Fixing recursive fault but reboot is needed!

Thanks for the help, all!

MADman

  • Adding links to the google result:
    www.spinics.net/.../ is the index of a bunch of threads
    www.spinics.net/.../msg32188.html is the top of the thread that appears to be talking about a race condition, and maybe a patch (?)
  • Hi Michael,

    Could you check whether the issue appears with the last Processor SDK Linux release v4.02 at:
    software-dl.ti.com/.../index_FDS.html
    Also found a suspicious message in the attached booting log:
    [FAILED] Failed to start Load Kernel Modules.
    Therefore pay attention for errors when build and install modules.

    BR
    Tsvetolin Shulev
  • I get that FAILED message every time it boots, successful or not. The PREEMPT error / system hang only happens once in, say, 50 starts, and only on some systems (as far as we know).

    Let me ask a different question: When this "reboot is needed" message appears, can I do any tests to see if the DDR3 and peripherals are functioning? It's plain the debug comms are still up, so can I do some queries?

  • Michael,

    I'm sorry for the delayed response. It is hard to debug the issue without your hardware but I compared the tty driver source code in both Linux kernels - 4.4.32 (which you are using) and Linux kernel 4.9.59 (kernel used in the last Processor SDK 4.02) and found changes which could related to the described crash. Therefore I suggest you to test behaviour of your board with Processor SDK 4.02.00.09.

    BR
    Tsvetolin Shulev
  • Hi all
    SW guy says rebuilding w/ new Linux kernel is non-trivial. Not everything on my board matches the EVM: different DDR3 chips, one less ethernet PHY, moved USB hub to the USB2 port (and changed to USB2 hub), using GPMC instead of video out. When we built the current version, it took our consultant about 3 months to put it all together. Presumably it would go faster the 2nd time around, but we'd still have to bring in outside help.

    That being said, if it fixes a start-up bug, management will pay for the outside help. Can you describe the change(s) you think are at play here? It would help me make the case for mgmt to pull out its wallet.

    Also, we had a similar start-up error today, with a slightly different behavior at the end. I'll put that into a new post on this thread.
  • Adding this post to describe similar, but new start-up failure. In this case, after sending the "Fixing recursive fault but reboot is needed!" message, it didn't just stop. Instead, it appeared to attempt and fail to do something on I2C. It looks like it's trying to talk to the PMIC:

    [ 15.008886] omap_i2c 48070000.i2c: controller timed out
    [ 16.048899] omap_i2c 48070000.i2c: controller timed out
    [ 17.088883] omap_i2c 48070000.i2c: controller timed out
    [ 17.108932] palmas-gpio 48070000.i2c:tps659038@58:tps659038_gpio: Reg 0x05 write failed, -110
    [ 18.128884] omap_i2c 48070000.i2c: controller timed out
    [ 21.168897] omap_i2c 48070000.i2c: controller timed out
    [ 22.208897] omap_i2c 48070000.i2c: controller timed out
    [ 23.248899] omap_i2c 48070000.i2c: controller timed out
    [ 26.288896] omap_i2c 48070000.i2c: controller timed out
    [ 27.328897] omap_i2c 48070000.i2c: controller timed out
    [ 28.368896] omap_i2c 48070000.i2c: controller timed out
    [ 29.408883] omap_i2c 48070000.i2c: controller timed out
    [ 29.428927] palmas-gpio 48070000.i2c:tps659038@58:tps659038_gpio: Reg 0x04 write failed, -110
    [ 30.448883] omap_i2c 48070000.i2c: controller timed out
    [ 30.468923] palmas-gpio 48070000.i2c:tps659038@58:tps659038_gpio: Reg 0x04 write failed, -110
    [ 33.138859] INFO: rcu_sched detected stalls on CPUs/tasks:
    [ 33.144383] 1-...: (0 ticks this GP) idle=771/140000000000000/0 softirq=3942/3942 fqs=2092
    [ 33.152855] (detected by 0, t=2102 jiffies, g=-46, c=-47, q=1)
    [ 33.158809] Task dump for CPU 1:
    [ 33.162049] kworker/u4:1 D c06061c8 0 72 0 0x00000000
    [ 33.168452] Backtrace:
    [ 33.170914] Backtrace aborted due to bad frame pointer <ef217f0c>
    [ 33.177055] INFO: rcu_preempt detected stalls on CPUs/tasks:
    [ 33.182750] 1-...: (1 GPs behind) idle=771/140000000000000/0 softirq=3941/3942 fqs=2091
    [ 33.190959] (detected by 0, t=2104 jiffies, g=379, c=378, q=339)
    [ 33.197086] Task dump for CPU 1:
    [ 33.200324] kworker/u4:1 D c06061c8 0 72 0 0x00000000
    [ 33.206725] Backtrace:
    [ 33.209188] Backtrace aborted due to bad frame pointer <ef217f0c>
    [ 33.488916] omap_i2c 48070000.i2c: controller timed out
    [ 34.528915] omap_i2c 48070000.i2c: controller timed out
    [ 35.568894] omap_i2c 48070000.i2c: controller timed out
    [ 35.588914] palmas-gpio 48070000.i2c:tps659038@58:tps659038_gpio: Reg 0x05 write failed, -110
    [ 36.608897] omap_i2c 48070000.i2c: controller timed out
    [ 39.648916] omap_i2c 48070000.i2c: controller timed out
    [ 40.688894] omap_i2c 48070000.i2c: controller timed out
    [ 40.708913] palmas-gpio 48070000.i2c:tps659038@58:tps659038_gpio: Reg 0x04 write failed, -110
    [ 41.728893] omap_i2c 48070000.i2c: controller timed out
    [ 42.768912] omap_i2c 48070000.i2c: controller timed out
    [ 45.808917] omap_i2c 48070000.i2c: controller timed out
    [ 46.848915] omap_i2c 48070000.i2c: controller timed out
    [ 47.888915] omap_i2c 48070000.i2c: controller timed out

    Or maybe there's more than one thread trying to run. It definitely didn't respond to to the Enter key - we didn't try anything else before hitting the reset switch.

    What we're trying to do is see if the console can talk to the processor after the "reboot is needed" message, so we're (by "we" I mean a poor intern who finds all these bugs for me) continuing to power-cycle the system to induce the start-up error again. Assuming we do, what should we look at? check the dev directory? Anything else that might point to what didn't load?

    Cheers!
    MADman

    (see also my other reply in this thread)
  • Hi,

    Michael, did you manage to track down the cause of the internal error?
    Did you by chance try switching to a later SDK with the 4.9 kernel?
    What is meant by sending the "Recursive fault message..."? Did your team get to this point with the original SDK with the 4.4 kernel?

    I encountered a similar issue (but apparently with different modules involved), Internal Error, yesterday on our custom board with the 5728 but which copies the 572x EVM rev a3 schematic, please see below. I'm wondering if this issue has been more widely experienced now, and what some common causes are.

    This happened on 1/6 bootups yesterday, so I'd classify this as intermittent for now.

    Full disclosure, I'm running debian 8.10 and kernel 4.4.110-ti-r142 from beagleboard.org. When I ask questions on here, I switch over to the latest TI SDK, but since this appears to be intermittent/hard to reproduce,

    would you please consider looking at my kernel boot/FS mount log along side Michalel's?

    I've also posted this on beagleboard.org to see if anyone over there may have encountered/seen this..

    Thanks!!!!

    Jeff

    [ 9.999478] Unable to handle kernel paging request at virtual address 00002248

    [ 0.006772] pgd = c0004000

    [ 10.009402] [00002248] *pgd=00000000

    [ 10.013025] Internal error: Oops: 17 [#1] SMP ARM

    [ 10.017747] Modules linked in: snd_soc_simple_card etnaviv snd_soc_omap_hdmi_audio ftdi_sio usbseris

    [ 10.057143] CPU: 1 PID: 6 Comm: kworker/u4:0 Not tainted 4.4.110-ti-r142 #9
    [ 10.064128] Hardware name: Generic DRA74X (Flattened Device Tree)
    [ 10.070251] Workqueue: events_unbound flush_to_ldisc
    [ 10.075238] task: ee16a080 ti: ee188000 task.ti: ee188000

    [ 10.080657] PC is at n_tty_receive_buf_common+0x84/0xa68

    [ 10.085991] LR is at down_read+0x1c/0x4c
    [ 10.089926] pc : [<c0676368>] lr : [<c0aaef50>] psr: 200f0013
    [ 10.089926] sp : ee189e18 ip : ee189e00 fp : ee189e84
    [ 10.101449] r10: ed4b7c00 r9 : ee03d000 r8 : ee03d014
    [ 10.106691] r7 : ed4b7c00 r6 : ed4b7d84 r5 : ed4b7c80 r4 : c0af0c30
    [ 10.113241] r3 : 00002000 r2 : 00000000 r1 : ee69e0a0 r0 : 00000000
    [ 10.119793] Flags: nzCv IRQs on FIQs on Mode SVC_32 ISA ARM Segment none
    [ 10.126954] Control: 10c5387d Table: ad53c06a DAC: 00000051
    [ 10.132720] Process kworker/u4:0 (pid: 6, stack limit = 0xee188218)
    [ 10.139009] Stack: (0xee189e18 to 0xee18a000)
    [ 10.143380] 9e00: ed4b7c80 ee189e28
    [ 10.151589] 9e20: c00867a4 00020001 ed4b7d84 55555556 ee16a44c c1011a48 c0af0c30 c100d300
    [ 10.159798] 9e40: 00002000 00000000 00000000 ee69e0a0 00000003 00000000 ee189e8c ee69e000
    [ 10.168008] 9e60: 00000001 ee03d004 ed4b7c00 ee03d014 ee03d000 c0676d4c ee189e9c ee189e88
    [ 10.184425] 9ea0: c0aac9a0 ee5b09c0 ee189ed4 ee03d004 ee0b5f80 ee03fc00 00000000 ee022b00
    [ 10.192635] 9ec0: c10e5fd8 ee022b05 ee189f14 ee189ed8 c005fee4 c067a968 ee188000 c0061030
    [ 10.200846] 9ee0: ee189efc 00000000 c0061030 ee0b5f80 ee0b5f98 ee03fc00 00000088 ee03fc14
    [ 10.209055] 9f00: ee188000 ee03fc00 ee189f54 ee189f18 c0060284 c005fd9c c1033e04 c0d48010
    [ 10.217264] 9f20: ee188000 c10e5bb9 00000000 00000000 ee0b7400 ee188000 ee0b5f80 c0060224
    [ 10.225473] 9f40: 00000000 00000000 ee189fac ee189f58 c00664a8 c0060230 00000000 2dd1a000
    [ 10.233683] 9f60: ee0b5f80 00000000 00000000 ee189f6c ee189f6c 00000000 00000000 ee189f7c
    [ 10.241892] 9f80: ee189f7c dc8ba66e c00747b4 ee0b7400 c0066390 00000000 00000000 00000000
    [ 10.250101] 9fa0: 00000000 ee189fb0 c0010ef8 c006639c 00000000 00000000 00000000 00000000
    [ 10.258310] 9fc0: 00000000 00000000 00000000 00000000 00000000 00000000 00000000 00000000
    [ 10.266518] 9fe0: 00000000 00000000 00000000 00000000 00000013 00000000 00000000 08000000
    [ 10.274732] [<c0676368>] (n_tty_receive_buf_common) from [<c0676d70>] (n_tty_receive_buf2+0x24/0x2c)
    [ 10.283903] [<c0676d70>] (n_tty_receive_buf2) from [<c067aa4c>] (flush_to_ldisc+0xf0/0x13c)
    [ 10.292290] [<c067aa4c>] (flush_to_ldisc) from [<c005fee4>] (process_one_work+0x154/0x494)
    [ 10.300588] [<c005fee4>] (process_one_work) from [<c0060284>] (worker_thread+0x60/0x548)
    [ 10.308712] [<c0060284>] (worker_thread) from [<c00664a8>] (kthread+0x118/0x130)
    [ 10.316140] [<c00664a8>] (kthread) from [<c0010ef8>] (ret_from_fork+0x14/0x3c)
    [ 10.323392] Code: e50b2030 e50b3034 eb10e2f3 e51b3044 (e5930248)
    [ 10.329557] ---[ end trace 406ed7c803d6bcc7 ]---

    [ 10.335007] Unable to handle kernel paging request at virtual address ffffffec
  • Hi Jeff
    We have not found a solution yet. We have found that there's more than one way for start-up to fail. We're trying to see if there's any HW issue involved, so we've started instrumenting reset signals and core voltage rails to see if we can catch a sequencing error that associates with a start failure. It's dull repetitive work, which is why I thank my employer for interns! So far no luck, though.

    Our SW guys have made a couple changes that gets our start-up failure rate down to about 1%. Not quite good enough, but bearable for now.
    One had to do with the DSPs - SW was hanging the first time we tried to start one of them. Commenting out a line of code fixed that. I don't remember the details, but I can dig around and find them if needed.
    There was a similar problem with the PRU not starting. For that, SW added a check which makes sure the PRU is loaded in the dev folder. It runs just before our app launches. If the PRU is not in the dev folder, this checker issues a reboot command.

    We're trying to get a SW resource to rebuild the Linux kernel with the latest version, but it will be weeks before that's working.

    I will post more if I learn anything. Good luck, all!
  • Thanks for the quick reply!

    I'll let you know if we find anything out or if we hear anything from the beagleboard community, since that's the distro we're currently using as a baseline.

    We seem to have different, much shorter, modules linked in reported in the kernel crash, so I'm wondering if these are just the offending modules.

    Regards,

    Jeff
  • One of the things I've neglected to do with our custom board is to tune/customize the IO timing delays in the SPL in mux_data.h from the pinmux tool. The TI support engineer we spoke with indicated that strange behavior can happen, sighting that we'd eventually need to take this step.

    wonder if this intermittent kernel crash, internal error could be related to incorrect timings/incompatible delays in the interface to the DDR3 or other components???

    Did you by chance tune the IO delays for your custom hardware, or just take the defaults from the pinmux tool or the reference design (e.g. 572xEVM, IDK, BB-X15)? I've spent the bulk of my time focusing on the pad configuration rather than the IO delay.. Maybe its time to focus on the latter??

    Thanks!!

    Jeff
  • I spent days in Hyperlynx getting the DDR3 and other I/O tuned in the layout. Then the FW engineer spent days getting the DDR3 settings right in the kernel. I'm happy with that. That said, we are on a hunt for an intermittently bad signal, especially power sequencing and resets.
  • Hi,

    Doyle , Do you find the reason and the solution?

    BR,

    vefone

  • Hi Michael,

    Every time you get the Kernel Internal Error, do you see essentially the same console log:

    For instance?

    Unable to handle kernel paging request at the same virtual address?

    pgd = same address?

    *pgd = same dereferenced data?

    Modules linked in:  Same module list?

    I haven't heard TI chime in on this recently, so I'm contacting someone there to see if they may be able to steer us in the right direction, IO delay problem, wiring problem, or something else?

    Thanks!

  • Also, I THINK the BeagleBoard guy, when he saw our console log of the internal error was also thinking DDR3. I'm cross posting there.

    If this is a DDR3 issue, why would this crop up, after the kernel boots, but when the kernel is mounting the FS? Why isn't there an issue in u-boot or the kernel boot?

    Also, if the TI/Beagleboard/BeagleBone pundits think this is a DDR3 problem, is there a test tool that can run an extended DDR3 test to test reads and write to the DDR3 and also the IO Pad configuration?

    One of the u-boot guys from denx said that TI has test tools for the 5728. For instance, we briefly had a 5718 IDK, which came with a test tool on an SD card which was DOS-based or TI-RTOS based..
  • No, still investigating.
    Mike
  • My colleague went back through the logs (interns are great resources!). Here's what he found. We seem to have two failure modes.

    I went through the log files of 5 failures that display “Unable to handle kernel paging request” error message.

    The system first displays the message PC is at n_tty_receive_buf_common+0x7c/0xa60 and then sometimes it also displays PC is at kthread_data+0x10/0x18 at the same startup after few lines and sometimes it just displays the message PC is at n_tty_receive_buf_common+0x7c/0xa60

    Here is what I can see form the log files, sometimes the system displays unable to hande kernel paging request at 00002248 and also at ffffffec, whenever the address is 00002248 – the PC is at n_tty and when the address is ffffffec – the PC is at kthread.

    The pgd is always at pgd = c0003000 in case of both the error messages.
    *pgd is different for both the cases but same for all the failures.
    *pgd=80000080004003
    *pgd=80000080007003
  • Hi,

    Mike, please feed back if you solve the problem.I have the same problem on my custom-built board.But I have not idea how to test it.

    BR,

    vefone

  • Thanks Michael,

    Will update the BeagleBoard thread on the new info, you provided late on Friday. It seems like they were thinking something was going on with the external RAM/DDR3.

    Have kind of set this on the back burner as we need to test other peripherals and functions on our board, so that our application team can run tests of their latest code. But I'm still trying to track it down.

    Also, I'm still looking for test programs from TI and elsewhere., to hopefully do some extended DDR3 tests.

    Thanks!!
  • Vefone,

    Since you're experiencing the same issue as Micheal, I'd be very curious as to what you're experiencing?

    *What SOC are you using, the 5728?  

    *What DDR3's are y'all using?  We're using the Micron,

    MT41K256M16TW

    *Do you have any console logs you could post as well, if they're different from what we're seeing or is the mode of failure the same?

    *What's the frequency of occurrence?

    *Have you all taken the time to tune/compute the IO delays specifically for your custom hardware or are you using the stock IO delay values from the TI Pinmux tool, or existing values from the boot loader?

     

    Thanks!!

    Jeff

    ....

  • Also,

    Here is a thread on here about DDR3 compliance testing. I haven't gone through these exercises, so presently I have no idea whether their relevant to this particular issue. But it looks like some of the material COULD be relevant..

    The thread references sprac36.pdf, which I found a zip archive of here,

    www.ti.com/.../getliterature.tsp

    e2e.ti.com/.../2249691.
  • The first link is the EMIF spreadsheet/workbook.

    This is the associated app note document from TI:

    www.ti.com/.../sprac36a.pdf
  • So much yup. We used the spreadsheet and followed that app note. We're using the 1GB version of the Micron DDR3, so we had to tailor almost everything.

    BTW, shameless plug for TI Apps: they do a VERY thorough schematic review. Anybody following this thread: if you haven't sent your design to fab already (or maybe even if you have), definitely work with your TI rep to submit your schematic to the apps engineers to review. They caught tons of issues that would have cost me weeks to track down if I had gone to fab with those issues. I think there's a link on the website somewhere.
  • Hi,

    Jeff, I am using the AM5728. I am using the Micron MT41K256M16HA-125.

    Here are the log:

    am5728 log.txt

    The probability of the occurrence is about three times of 1000 times. By the way, not all the board have the problem.

    *Have you all taken the time to tune/compute the IO delays specifically for your custom hardware or are you using the stock IO delay values from the TI Pinmux tool, or existing values from the boot loader?

    No, I do not test it.

    What the reason it may be?

    BR,

    vefone

  • Hi,
    Jeff, now I am using the existing values from the uboot.Do not change it.
  • Thanks Vefone for posting the log and this info!

    So are you getting the same error rate using the existing values from uboot?

    If I may ask, is your design a close copy of one of the existing TI designs (e.g. am572x EVM) ?

    I would think that TI's BSP team would have encountered this error when developing the various development boards (e.g. 572XEVM, 5718IDK, 5728IDK).  

    If so, it would be great if TI could provide some clues as to what these errors mean!!!

  • Hi Jeff
    What version of the Linux kernel are you using? My board is on 4.4.32, and vefone is using 4.4.19.
    Mike
  • Hi Michael,

    We're using kernel version 4.4.110, patched for the beagleboard-X15, as we started with a Debian-based image from BeagleBoard.org.