PROCESSOR-SDK-AM335X: Processor halt issue

Kevin Boldingh

Part Number: PROCESSOR-SDK-AM335X
Other Parts Discussed in Thread: AM3352

I'm having an issue with certain custom boards with the AM3352 processor halting (I think) shortly after booting up. When I say halted, I mean all the peripherals stop working, the screen stops updating, and I cannot even connect over the JTAG header to the processor. When trying to connect through JTAG using CCS, I just get "Unable to halt device. Reset the device, and retry the operation".

On Windows CE 7, I start up the OS, I see the desktop, and usually the start menu graphic has not been loaded (just a blue box in the corner, the same color as the desktop background). I can attach screenshots if that will help.

I realize TI does not support Windows CE, but I see this as more of a processor-specific issue that I'm having. I have run both Windows CE 7 and TI's Arago Linux distro (the bug is easier to replicate on Windows) and have seen this halting issue in both OSs and I'm wondering what kinds of situations can cause the processor to behave this way.

Any assistance would be appreciated,

Kevin

over 8 years ago

0 Biser Gatchev-XID over 8 years ago

TI__Guru**** 393215 points

Hi,

Do you have any boot logs that you can provide? Have you done the DDR configuration and software leveling procedure?

0 Kevin Boldingh over 8 years ago in reply to Biser Gatchev-XID

Prodigy 130 points

I have done the DDR configuration timing calculations according to and I'm using mDDR so it doesn't look like I need to do anything further than that. I've run multiple RAM tests on mDDR and never had an issue. Before I did that any of that, the board would not even get past spl into u-boot.

My issue presents itself after the kernel has already loaded, occasionally failing when I try to connect to the board through ssh from my development PC (that's the only way I can replicate this bug in Linux right now). Sometimes it will fail immediately when I try to ssh in, other times it will work, but even a simple "cat /bin/grep" through ssh can crash the system. In Windows, the whole system comes up, sometimes I can even open explorer, but once I close it, I have the issue again. No matter the OS, the issue is the same: the whole system halts and I cannot connect to it through JTAG.

I've attached a log of u-boot and kernel booting up, but I suspect it won't be very useful.

Fullscreen teraterm26.log Download

CCCCCCCC
U-Boot SPL 2017.01-g450462eb7f (Dec 07 2017 - 15:09:06)
Board ID = ffff
PMIC set reg: 0x40 to 0x34
Trying to boot from MMC1
reading uboot.env
reading u-boot.img
reading u-boot.img
reading u-boot.img
reading u-boot.img


U-Boot 2017.01-g450462eb7f (Dec 07 2017 - 15:09:06 -0600)

CPU  : AM335X-GP rev 2.1
Model: TI AM335x CDS
DRAM:  256 MiB
NAND:  512 MiB
MMC:   OMAP SD/MMC: 0
reading uboot.env
Net:   eth0: ethernet@4a100000
=> boot

NAND erase.chip: device 0 whole chip
Erasing at 0x1ffe0000 -- 100% complete.
OK
reading MLO_nand
75088 bytes read in 13 ms (5.5 MiB/s)

NAND erase.part: device 0 offset 0x0, size 0x20000
Erasing at 0x0 -- 100% complete.
OK

NAND write: device 0 offset 0x0, size 0x12550
 75088 bytes written: OK

NAND erase.part: device 0 offset 0x20000, size 0x20000
Erasing at 0x20000 -- 100% complete.
OK

NAND write: device 0 offset 0x20000, size 0x12550
 75088 bytes written: OK

NAND erase.part: device 0 offset 0x40000, size 0x20000
Erasing at 0x40000 -- 100% complete.
OK

NAND write: device 0 offset 0x40000, size 0x12550
 75088 bytes written: OK

NAND erase.part: device 0 offset 0x60000, size 0x20000
Erasing at 0x60000 -- 100% complete.
OK

NAND write: device 0 offset 0x60000, size 0x12550
 75088 bytes written: OK
38851 bytes read in 10 ms (3.7 MiB/s)

NAND erase.part: device 0 offset 0x80000, size 0x40000
Erasing at 0xa0000 -- 100% complete.
OK

NAND write: device 0 offset 0x80000, size 0x97c3
 38851 bytes written: OK
reading u-boot_nand.img
511320 bytes read in 50 ms (9.8 MiB/s)

NAND erase.part: device 0 offset 0xc0000, size 0x100000
Erasing at 0x1a0000 -- 100% complete.
OK

NAND write: device 0 offset 0xc0000, size 0x7cd58
 511320 bytes written: OK
3565248 bytes read in 308 ms (11 MiB/s)

NAND erase.part: device 0 offset 0x200000, size 0x800000
Erasing at 0x9e0000 -- 100% complete.
OK

NAND write: device 0 offset 0x200000, size 0x3666c0
 3565248 bytes written: OK
193200128 bytes read in 16047 ms (11.5 MiB/s)

NAND erase.part: device 0 offset 0xa00000, size 0x1f600000
Erasing at 0x1ffe0000 -- 100% complete.
OK

NAND write: device 0 offset 0xa00000, size 0xb840000
 193200128 bytes written: OK
Booting from nand ...

NAND read: device 0 offset 0x80000, size 0x40000
 262144 bytes read: OK

NAND read: device 0 offset 0x200000, size 0x800000
 8388608 bytes read: OK
## Flattened Device Tree blob at 88000000
   Booting using the fdt blob at 0x88000000
   Loading Device Tree to 8df09000, end 8df157c2 ... OK

Starting kernel ...

[    0.000000] Booting Linux on physical CPU 0x0
[    0.000000] Linux version 4.9.41-rt23-ga0a63f2d6b (boldinghk@softbuild-ubuntu) (gcc version 6.2.1 20161016 (Linaro GCC 6.2-2016.11) ) #1 PREEMPT RT Fri Dec 1 13:33:45 CST 2017
[    0.000000] CPU: ARMv7 Processor [413fc082] revision 2 (ARMv7), cr=10c5387d
[    0.000000] CPU: PIPT / VIPT nonaliasing data cache, VIPT aliasing instruction cache
[    0.000000] OF: fdt:Machine model: TI AM335x CDS
[    0.000000] efi: Getting EFI parameters from FDT:
[    0.000000] efi: UEFI not found.
[    0.000000] cma: Reserved 48 MiB at 0x8a800000
[    0.000000] Memory policy: Data cache writeback
[    0.000000] CPU: All CPU(s) started in SVC mode.
[    0.000000] AM335X ES2.1 (neon)
[    0.000000] Built 1 zonelists in Zone order, mobility grouping on.  Total pages: 64960
[    0.000000] Kernel command line: console=ttyS0,115200n8 root=ubi0:rootfs rw ubi.mtd=NAND.file-system,2048 rootfstype=ubifs rootwait=1
[    0.000000] PID hash table entries: 1024 (order: 0, 4096 bytes)
[    0.000000] Dentry cache hash table entries: 32768 (order: 5, 131072 bytes)
[    0.000000] Inode-cache hash table entries: 16384 (order: 4, 65536 bytes)
[    0.000000] Memory: 198276K/262144K available (7168K kernel code, 291K rwdata, 2404K rodata, 1024K init, 324K bss, 14716K reserved, 49152K cma-reserved, 0K highmem)
[    0.000000] Virtual kernel memory layout:
[    0.000000]     vector  : 0xffff0000 - 0xffff1000   (   4 kB)
[    0.000000]     fixmap  : 0xffc00000 - 0xfff00000   (3072 kB)
[    0.000000]     vmalloc : 0xd0800000 - 0xff800000   ( 752 MB)
[    0.000000]     lowmem  : 0xc0000000 - 0xd0000000   ( 256 MB)
[    0.000000]     pkmap   : 0xbfe00000 - 0xc0000000   (   2 MB)
[    0.000000]     modules : 0xbf000000 - 0xbfe00000   (  14 MB)
[    0.000000]       .text : 0xc0008000 - 0xc0800000   (8160 kB)
[    0.000000]       .init : 0xc0b00000 - 0xc0c00000   (1024 kB)
[    0.000000]       .data : 0xc0c00000 - 0xc0c48ce0   ( 292 kB)
[    0.000000]        .bss : 0xc0c48ce0 - 0xc0c99d60   ( 325 kB)
[    0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1
[    0.000000] Preemptible hierarchical RCU implementation.
[    0.000000] 	RCU kthread priority: 1.
[    0.000000] NR_IRQS:16 nr_irqs:16 16
[    0.000000] IRQ: Found an INTC at 0xfa200000 (revision 5.0) with 128 interrupts
[    0.000000] OMAP clockevent source: timer2 at 24000000 Hz
[    0.000015] sched_clock: 32 bits at 24MHz, resolution 41ns, wraps every 89478484971ns
[    0.000028] clocksource: timer1: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 79635851949 ns
[    0.000034] OMAP clocksource: timer1 at 24000000 Hz
[    0.000185] clocksource_probe: no matching clocksources found
[    0.000370] Console: colour dummy device 80x30
[    0.089242] Calibrating delay loop... 996.14 BogoMIPS (lpj=4980736)
[    0.089247] pid_max: default: 32768 minimum: 301
[    0.089379] Mount-cache hash table entries: 1024 (order: 0, 4096 bytes)
[    0.089385] Mountpoint-cache hash table entries: 1024 (order: 0, 4096 bytes)
[    0.090332] CPU: Testing write buffer coherency: ok
[    0.090713] Setting up static identity map for 0x80100000 - 0x80100060
[    0.091917] EFI services will not be available.
[    0.093144] devtmpfs: initialized
[    0.104802] VFP support v0.3: implementor 41 architecture 3 part 30 variant c rev 3
[    0.105189] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns
[    0.105220] futex hash table entries: 256 (order: 1, 8192 bytes)
[    0.109009] pinctrl core: initialized pinctrl subsystem
[    0.110631] NET: Registered protocol family 16
[    0.112774] DMA: preallocated 256 KiB pool for atomic coherent allocations
[    0.182039] OMAP GPIO hardware version 0.1
[    0.195662] omap-gpmc 50000000.gpmc: could not find pctldev for node /ocp/l4_wkup@44c00000/scm@210000/pinmux@800/nandflash_pins_s0, deferring probe
[    0.197899] No ATAGs?
[    0.197922] hw-breakpoint: debug architecture 0x4 unsupported.
[    0.213872] edma 49000000.edma: TI EDMA DMA engine driver
[    0.217940] omap_i2c 44e0b000.i2c: could not find pctldev for node /ocp/l4_wkup@44c00000/scm@210000/pinmux@800/pinmux_i2c0_pins, deferring probe
[    0.218077] media: Linux media interface: v0.10
[    0.218135] Linux video capture interface: v2.00
[    0.218197] pps_core: LinuxPPS API ver. 1 registered
[    0.218204] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>
[    0.218231] PTP clock support registered
[    0.218272] EDAC MC: Ver: 3.0.0
[    0.219626] omap-mailbox 480c8000.mailbox: omap mailbox rev 0x400
[    0.220008] Advanced Linux Sound Architecture Driver Initialized.
[    0.221418] clocksource: Switched to clocksource timer1
[    0.231927] NET: Registered protocol family 2
[    0.232809] TCP established hash table entries: 2048 (order: 1, 8192 bytes)
[    0.232853] TCP bind hash table entries: 2048 (order: 3, 49152 bytes)
[    0.232936] TCP: Hash tables configured (established 2048 bind 2048)
[    0.233026] UDP hash table entries: 256 (order: 2, 16384 bytes)
[    0.233058] UDP-Lite hash table entries: 256 (order: 2, 16384 bytes)
[    0.233243] NET: Registered protocol family 1
[    0.233781] RPC: Registered named UNIX socket transport module.
[    0.233794] RPC: Registered udp transport module.
[    0.233800] RPC: Registered tcp transport module.
[    0.233806] RPC: Registered tcp NFSv4.1 backchannel transport module.
[    0.234828] hw perfevents: enabled with armv7_cortex_a8 PMU driver, 5 counters available
[    0.237191] workingset: timestamp_bits=14 max_order=16 bucket_order=2
[    0.244874] squashfs: version 4.0 (2009/01/31) Phillip Lougher
[    0.245898] NFS: Registering the id_resolver key type
[    0.245948] Key type id_resolver registered
[    0.245955] Key type id_legacy registered
[    0.246004] ntfs: driver 2.1.32 [Flags: R/O].
[    0.247961] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247)
[    0.247980] io scheduler noop registered
[    0.247987] io scheduler deadline registered
[    0.248141] io scheduler cfq registered (default)
[    0.249516] pinctrl-single 44e10800.pinmux: 142 pins at pa f9e10800 size 568
[    0.312209] Serial: 8250/16550 driver, 10 ports, IRQ sharing disabled
[    0.315731] console [ttyS0] disabled
[    0.315892] 44e09000.serial: ttyS0 at MMIO 0x44e09000 (irq = 158, base_baud = 3000000) is a 8250
[    0.883374] console [ttyS0] enabled
[    0.888903] omap_rng 48310000.rng: OMAP Random Number Generator ver. 20
[    0.895729] [drm] Initialized
[    0.900661] [drm] Supports vblank timestamp caching Rev 2 (21.10.2013).
[    0.907322] [drm] No driver support for vblank timestamp query.
[    0.947832] Console: switching to colour frame buffer device 128x37
[    0.961859] tilcdc 4830e000.lcdc: fb0:  frame buffer device
[    0.980825] tilcdc 4830e000.lcdc: tilcdc_crtc_irq(0x00000020): FIFO underflow
[    0.992011] brd: module loaded
[    0.992422] tilcdc 4830e000.lcdc: tilcdc_crtc_irq(0x00000004): Sync lost
[    0.993408] tilcdc 4830e000.lcdc: tilcdc_crtc_irq(0x00000004): Sync lost
[    1.016188] loop: module loaded
[    1.022545] libphy: Fixed MDIO Bus: probed
[    1.101483] davinci_mdio 4a101000.mdio: davinci mdio revision 1.6
[    1.107627] davinci_mdio 4a101000.mdio: detected phy mask fffffffd
[    1.115013] libphy: 4a101000.mdio: probed
[    1.119061] davinci_mdio 4a101000.mdio: phy[1]: device 4a101000.mdio:01, driver SMSC LAN8710/LAN8720
[    1.129073] cpsw 4a100000.ethernet: No slave[0] phy_id, phy-handle, or fixed-link property
[    1.137541] cpsw 4a100000.ethernet: Detected MACID = 98:84:e3:a9:c5:59
[    1.144255] cpsw 4a100000.ethernet: cpts: overflow check period 500 (jiffies)
[    1.153736] mousedev: PS/2 mouse device common for all mice
[    1.160018] i2c /dev entries driver
[    1.169033] NET: Registered protocol family 10
[    1.175202] sit: IPv6, IPv4 and MPLS over IPv4 tunneling driver
[    1.182224] NET: Registered protocol family 17
[    1.187038] Key type dns_resolver registered
[    1.191758] omap_voltage_late_init: Voltage driver support not added
[    1.205905] omap-gpmc 50000000.gpmc: GPMC revision 6.0
[    1.211103] gpmc_mem_init: disabling cs 0 mapped at 0x0-0x1000000
[    1.219758] nand: device found, Manufacturer ID: 0x2c, Chip ID: 0xbc
[    1.226199] nand: Micron MT29F4G16ABBDAHC
[    1.230227] nand: 512 MiB, SLC, erase size: 128 KiB, page size: 2048, OOB size: 64
[    1.237861] nand: using OMAP_ECC_BCH8_CODE_HW ECC scheme
[    1.243301] 10 ofpart partitions found on MTD device omap2-nand.0
[    1.249422] Creating 10 MTD partitions on "omap2-nand.0":
[    1.254857] 0x000000000000-0x000000020000 : "NAND.SPL"
[    1.261982] 0x000000020000-0x000000040000 : "NAND.SPL.backup1"
[    1.269244] 0x000000040000-0x000000060000 : "NAND.SPL.backup2"
[    1.276464] 0x000000060000-0x000000080000 : "NAND.SPL.backup3"
[    1.283638] 0x000000080000-0x0000000c0000 : "NAND.u-boot-spl-os"
[    1.290917] 0x0000000c0000-0x0000001c0000 : "NAND.u-boot"
[    1.297878] 0x0000001c0000-0x0000001e0000 : "NAND.u-boot-env"
[    1.304908] 0x0000001e0000-0x000000200000 : "NAND.u-boot-env.backup1"
[    1.312745] 0x000000200000-0x000000a00000 : "NAND.kernel"
[    1.322159] 0x000000a00000-0x000020000000 : "NAND.file-system"
[    1.528140] vrtc: supplied by vbat
[    1.537389] vio: supplied by vbat
[    1.543760] vdd1: supplied by vbat
[    1.551095] vdd2: supplied by vbat
[    1.561948] vdig1: supplied by vbat
[    1.568614] vdig2: supplied by vbat
[    1.575034] random: fast init done
[    1.578741] vpll: supplied by vbat
[    1.585333] vdac: supplied by vbat
[    1.591943] vaux1: supplied by vbat
[    1.598619] vaux2: supplied by vbat
[    1.605308] vaux33: supplied by vbat
[    1.612091] vmmc: supplied by vbat
[    1.618692] vbb: supplied by vbat
[    1.636077] tps65910-rtc tps65910-rtc: rtc core: registered tps65910-rtc as rtc0
[    1.643838] omap_i2c 44e0b000.i2c: bus 0 rev0.11 at 100 kHz
[    1.701929] ubi0: attaching mtd9
[    1.758677] mmc0: host does not support reading read-only switch, assuming write-enable
[    1.778236] mmc0: new high speed SDHC card at address 0001
[    1.794509] mmcblk0: mmc0:0001 SD8GB 7.24 GiB 
[    1.801033]  mmcblk0: p1 p2
[    2.719517] ubi0: scanning is finished
[    2.742111] ubi0: volume 0 ("rootfs") re-sized from 1472 to 3932 LEBs
[    2.749590] ubi0: attached mtd9 (name "NAND.file-system", size 502 MiB)
[    2.756248] ubi0: PEB size: 131072 bytes (128 KiB), LEB size: 126976 bytes
[    2.763156] ubi0: min./max. I/O unit sizes: 2048/2048, sub-page size 512
[    2.769886] ubi0: VID header offset: 2048 (aligned 2048), data offset: 4096
[    2.776876] ubi0: good PEBs: 4016, bad PEBs: 0, corrupted PEBs: 0
[    2.782996] ubi0: user volume: 1, internal volumes: 1, max. volumes count: 128
[    2.790250] ubi0: max/mean erase counter: 1/0, WL threshold: 4096, image sequence number: 1368378995
[    2.799423] ubi0: available PEBs: 0, total reserved PEBs: 4016, PEBs reserved for bad PEB handling: 80
[    2.808781] ubi0: background thread "ubi_bgt0d" started, PID 95
[    2.818220] tps65910-rtc tps65910-rtc: setting system clock to 2017-12-02 18:12:17 UTC (1512238337)
[    2.835109] lis3_reg: disabling
[    2.838280] wlan_en_regulator: disabling
[    2.852636] ALSA device list:
[    2.855622]   No soundcards found.
[    2.881355] UBIFS (ubi0:0): background thread "ubifs_bgt0_0" started, PID 97
[    2.916832] UBIFS (ubi0:0): start fixing up free space
[   11.186490] UBIFS (ubi0:0): free space fixup complete
[   11.202689] UBIFS (ubi0:0): UBIFS: mounted UBI device 0, volume 0, name "rootfs"
[   11.210141] UBIFS (ubi0:0): LEB size: 126976 bytes (124 KiB), min./max. I/O unit sizes: 2048 bytes/2048 bytes
[   11.220108] UBIFS (ubi0:0): FS size: 495841280 bytes (472 MiB, 3905 LEBs), journal size 9023488 bytes (8 MiB, 72 LEBs)
[   11.230852] UBIFS (ubi0:0): reserved for root: 0 bytes (0 KiB)
[   11.236715] UBIFS (ubi0:0): media format: w4/r0 (latest is w4/r0), UUID 9BD15FA4-8474-47F3-89E2-0AED8B3B7748, small LPT model
[   11.249649] VFS: Mounted root (ubifs filesystem) on device 0:15.
[   11.256778] devtmpfs: mounted
[   11.261659] Freeing unused kernel memory: 1024K (c0b00000 - c0c00000)
[   11.483774] systemd[1]: systemd 230 running in system mode. (+PAM -AUDIT -SELINUX +IMA -APPARMOR +SMACK +SYSVINIT +UTMP -LIBCRYPTSETUP -GCRYPT -GNUTLS +ACL +XZ -LZ4 -SECCOMP +BLKID -ELFUTILS +KMOD -IDN)
[   11.503312] systemd[1]: Detected architecture arm.

Welcome to Arago 2017.06!

[   11.542698] systemd[1]: Set hostname to <am335x-cds>.
[   11.552086] systemd[1]: Initializing machine ID from random generator.
[   12.065853] systemd[1]: Listening on Journal Socket (/dev/log).
[  OK  ] Listening on Journal Socket (/dev/log).
[   12.102198] systemd[1]: Listening on udev Control Socket.
[  OK  ] Listening on udev Control Socket.
[   12.131770] systemd[1]: Reached target Remote File Systems.
[  OK  ] Reached target Remote File Systems.
[   12.162029] systemd[1]: Listening on /dev/initctl Compatibility Named Pipe.
[  OK  ] Listening on /dev/initctl Compatibility Named Pipe.
[   12.202140] systemd[1]: Started Forward Password Requests to Wall Directory Watch.
[  OK  ] Started Forward Password Requests to Wall Directory Watch.
[   12.245480] systemd[1]: Created slice User and Session Slice.
[  OK  ] Created slice User and Session Slice.
[  OK  ] Listening on Network Service Netlink Socket.
[  OK  ] Listening on Process Core Dump Socket.
[  OK  ] Reached target Swap.
[  OK  ] Started Dispatch Password Requests to Console Directory Watch.
[  OK  ] Reached target Paths.
[  OK  ] Listening on udev Kernel Socket.
[  OK  ] Listening on Journal Socket.
[  OK  ] Listening on Syslog Socket.
[  OK  ] Created slice System Slice.
[  OK  ] Created slice system-serial\x2dgetty.slice.
         Mounting Temporary Directory...
         Starting Load Kernel Modules...
[   12.658531] cryptodev: loading out-of-tree module taints kernel.
         [   12.678389] cryptodev: driver 1.8 loaded.
Starting Create Static Device Nodes in /dev...
         Mounting POSIX Message Queue File System...
[  OK  ] Reached target Slices.
         Starting Journal Service...
[  OK  ] Created slice system-getty.slice.
         Mounting Debug File System...
         Starting Remount Root and Kernel File Systems...
         Starting Setup Virtual Console...
[  OK  ] Mounted Debug File System.
[  OK  ] Mounted POSIX Message Queue File System.
[  OK  ] Mounted Temporary Directory.
[  OK  ] Started Journal Service.
[  OK  ] Started Load Kernel Modules.
[  OK  ] Started Create Static Device Nodes in /dev.
[  OK  ] Started Remount Root and Kernel File Systems.
[  OK  ] Started Setup Virtual Console.
         Starting Rebuild Hardware Database...
[  OK  ] Reached target Local File Systems (Pre).
         Mounting /var/volatile...
         Mounting /media/ram...
         Starting udev Kernel Device Manager...
         Mounting Configuration File System...
         Starting Apply Kernel Variables...
         Starting Flush Journal to Persistent Storage...
[  OK  ] Mounted Configuration File System.
[  OK  ] Mounted /var/volatile.
[  OK  ] Mounted /media/ram.
[  OK  ] Started Apply Kernel Variables.
[  OK  ] Started udev Kernel Device Manager.
[   13.911738] systemd-journald[118]: Received request to flush runtime journal from PID 1
         Starting Load/Save Random Seed...
[  OK  ] Reached target Local File Systems.
         Starting Rebuild Dynamic Linker Cache...
         Starting Rebuild Journal Catalog...
[  OK  ] Started Flush Journal to Persistent Storage.
[  OK  ] Started Load/Save Random Seed.
         Starting Create Volatile Files and Directories...
[  OK  ] Started Rebuild Journal Catalog.
[  OK  ] Started Create Volatile Files and Directories.
         Starting Opkg first boot configure...
         Starting Network Time Synchronization...
         Starting Update UTMP about System Boot/Shutdown...
[  OK  ] Started Update UTMP about System Boot/Shutdown.
[  OK  ] Started Network Time Synchronization.
[  OK  ] Reached target System Time Synchronized.
[  OK  ] Started Rebuild Dynamic Linker Cache.
[  OK  ] Started Opkg first boot configure.
[  OK  ] Started Rebuild Hardware Database.
         Starting Update is Completed...
         Starting udev Coldplug all Devices...
[  OK  ] Started Update is Completed.
[   21.094312] omap_wdt: OMAP Watchdog Timer Rev 0x01: initial timeout 60 sec
[  OK  ] Found device /dev/ttyS0.
[  OK  ] Started udev Coldplug all Devices.
[  OK  ] Reached target System Initialization.
         Starting sshd.socket.
[  OK  ] Listening on RPCbind Server Activation Socket.
[  OK  ] Listening on D-Bus System Message Bus Socket.
[  OK  ] Started Daily Cleanup of Temporary Directories.
[  OK  ] Reached target Timers.
[  OK  ] Listening on sshd.socket.
[  OK  ] Reached target Sockets.
[  OK  ] Reached target Basic System.
[  OK  ] Started System Logging Service.
[  OK  ] Started D-Bus System Message Bus.
[   22.139675] omap_hwmod: pruss: _wait_target_ready failed: -16
[   22.225178] pruss-soc-bus 4a326000.pruss-soc-bus: use pm_runtime_put_sync_suspend() in driver?
[   22.285976] pruss-soc-bus 4a326000.pruss-soc-bus: couldn't enable module
[   22.376721] pruss-soc-bus: probe of 4a326000.pruss-soc-bus failed with error -16
[   22.464368] omap-sham 53100000.sham: hw accel on OMAP rev 4.3
[   22.603158] omap-aes 53500000.aes: OMAP AES hw accel rev: 3.2
[   22.626785] omap-aes 53500000.aes: will run requests pump with realtime priority
[   23.923809] remoteproc remoteproc0: wkup_m3 is available
         Starting Network Service...
[  OK  ] Started Kernel Logging Service.
         Starting telnetd.service...
         Starting Login Service...
[   24.491650] remoteproc remoteproc0: powering up wkup_m3
         Starting Print notice about GPLv3 packages...
[   24.863348] remoteproc remoteproc0: Booting fw image am335x-pm-firmware.elf, size 224304
[   24.926997] wkup_m3_ipc 44e11324.wkup_m3_ipc: CM3 Firmware Version = 0x192
[   24.974474] remoteproc remoteproc0: remote processor wkup_m3 is now up
[  OK  ] Started telnetd.service.
         Starting thttpd.service...
[  OK  ] Started Network Service.
[  OK  ] Started Login Service.
[  OK  ] Reached target Network.
         Starting Permit User Sessions...
[   26.660768] net eth0: initializing cpsw version 1.12 (0)
[   26.769950] net eth0: initialized cpsw ale version 1.4
         Starting Network Name Resolution...
[   26.824247] net eth0: ALE Table size 1024
[   26.931104] libphy: PHY  not found
[   26.999900] net eth0: phy "" not found on slave 0, err -19
[  OK  ] Started Permit User Sessions.
[   27.142400] SMSC LAN8710/LAN8720 4a101000.mdio:01: attached PHY driver [SMSC LAN8710/LAN8720] (mii_bus:phy_addr=4a101000.mdio:01, irq=-1)
[   27.378414] IPv6: ADDRCONF(NETDEV_UP): eth0: link is not ready
[  OK  ] Started thttpd.service.
[   27.938849] EXT4-fs (mmcblk0p2): mounting ext3 file system using the ext4 subsystem
[   28.059466] tilcdc 4830e000.lcdc: tilcdc_crtc_irq(0x00000020): FIFO underflow
[   28.066830] tilcdc 4830e000.lcdc: tilcdc_crtc_irq(0x00000020): FIFO underflow
[   28.074028] tilcdc 4830e000.lcdc: tilcdc_crtc_irq(0x00000104): Sync lost
[   28.097813] EXT4-fs (mmcblk0p2): mounted filesystem with ordered data mode. Opts: (null)
[   28.215378] FAT-fs (mmcblk0p1): Volume was not properly unmounted. Some data may be corrupt. Please run fsck.
[  OK  ] Started Network Name Resolution.
[  OK  ] Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch.
         Starting thermal-zone-init.service...
[  OK  ] Started Serial Getty on ttyS0.
[  OK  ] Started Getty on tty1.
[  OK  ] Reached target Login Prompts.
         Starting Synchronize System and HW clocks...
[  OK  ] Started thermal-zone-init.service.
[  OK  ] Started Synchronize System and HW clocks.
[   30.253922] TI-am335x-tsc TI-am335x-tsc: ti,charge-delay not specified
[   30.306462] input: ti-tsc as /devices/platform/ocp/44e0d000.tscadc/TI-am335x-tsc/input/input0
[   30.402480] cpsw 4a100000.ethernet eth0: Link is Up - 100Mbps/Full - flow control rx/tx
[   30.453057] IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready
[   31.150418] 47401300.usb-phy supply vcc not found, using dummy regulator
[   31.226949] 47401b00.usb-phy supply vcc not found, using dummy regulator
[   31.499923] usbcore: registered new interface driver usbfs
[   31.554827] usbcore: registered new interface driver hub
[   31.599068] usbcore: registered new device driver usb
[   31.847123] musb-hdrc musb-hdrc.0: MUSB HDRC host driver
[   31.886840] musb-hdrc musb-hdrc.0: new USB bus registered, assigned bus number 1
[   31.958185] hub 1-0:1.0: USB hub found
[   31.990693] hub 1-0:1.0: 1 port detected
[   32.120786] musb-hdrc musb-hdrc.1: MUSB HDRC host driver
[   32.153231] musb-hdrc musb-hdrc.1: new USB bus registered, assigned bus number 2
[   32.234503] hub 2-0:1.0: USB hub found
[   32.270236] hub 2-0:1.0: 1 port detected
[   32.471725] usb 1-1: new high-speed USB device number 2 using musb-hdrc
[   32.674141] hub 1-1:1.0: USB hub found
[   32.686507] hub 1-1:1.0: 4 ports detected
[   33.031757] usb 1-1.1: new low-speed USB device number 3 using musb-hdrc
[   33.288075] input: PixArt Dell MS116 USB Optical Mouse as /devices/platform/ocp/47400000.usb/47401400.usb/musb-hdrc.0/usb1/1-1/1-1.1/1-1.1:1.0/0003:413C:301A.0001/input/input1
[   33.340726] hid-generic 0003:413C:301A.0001: input: USB HID v1.11 Mouse [PixArt Dell MS116 USB Optical Mouse] on usb-musb-hdrc.0-1.1/input0
[   33.391744] usbcore: registered new interface driver usbhid
[   33.409040] usbhid: USB HID core driver
[   33.955635] tilcdc 4830e000.lcdc: tilcdc_crtc_irq(0x00000020): FIFO underflow
[   33.955686] tilcdc 4830e000.lcdc: tilcdc_crtc_irq(0x00000020): FIFO underflow
[   33.960877] tilcdc 4830e000.lcdc: tilcdc_crtc_irq(0x00000020): FIFO underflow
[   33.961066] tilcdc 4830e000.lcdc: tilcdc_crtc_irq(0x00000020): FIFO underflow
[   33.965412] tilcdc 4830e000.lcdc: tilcdc_crtc_irq(0x00000004): Sync lost
[   33.966397] tilcdc 4830e000.lcdc: tilcdc_crtc_irq(0x00000004): Sync lost
[   33.985885] tilcdc 4830e000.lcdc: tilcdc_crtc_irq(0x00000020): FIFO underflow
[   33.997814] tilcdc 4830e000.lcdc: tilcdc_crtc_irq(0x00000020): FIFO underflow
[   33.998104] tilcdc 4830e000.lcdc: tilcdc_crtc_irq(0x00000020): FIFO underflow
[   34.000377] tilcdc 4830e000.lcdc: tilcdc_crtc_irq(0x00000004): Sync lost
[   34.001394] tilcdc 4830e000.lcdc: tilcdc_crtc_irq(0x00000004): Sync lost
[   34.022287] tilcdc 4830e000.lcdc: tilcdc_crtc_irq(0x00000020): FIFO underflow
[   34.035380] tilcdc 4830e000.lcdc: tilcdc_crtc_irq(0x00000004): Sync lost
[   34.036361] tilcdc 4830e000.lcdc: tilcdc_crtc_irq(0x00000004): Sync lost

 _____                    _____           _         _   
|  _  |___ ___ ___ ___   |  _  |___ ___  |_|___ ___| |_ 
|     |  _| .'| . | . |  |   __|  _| . | | | -_|  _|  _|
|__|__|_| |__,|_  |___|  |__|  |_| |___|_| |___|___|_|  
              |___|                    |___|            

Arago Project http://arago-project.org am335x-cds ttyS0

Arago 2017.06 am335x-cds ttyS0

am335x-cds login: ***************************************************************
***************************************************************
NOTICE: This file system contains the following GPLv3 packages:
	autoconf
	binutils
	ccache
	cifs-utils
	coreutils
	cpp-symlinks
	cpp
	diffutils
	dosfstools
	findutils
	g++-symlinks
	g++
	gcc-symlinks
	gcc
	gdb
	gdbserver
	less
	libgmp10
	libmpc3
	libmpfr4
	libreadline6
	m4
	make
	patch

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
***************************************************************
***************************************************************
[   38.458197] random: crng init done
[   51.151456] usb 1-1.2: new low-speed USB device number 4 using musb-hdrc
[   51.302495] input: DELL Dell USB Entry Keyboard as /devices/platform/ocp/47400000.usb/47401400.usb/musb-hdrc.0/usb1/1-1/1-1.2/1-1.2:1.0/0003:413C:2107.0002/input/input2
[   51.386721] hid-generic 0003:413C:2107.0002: input: USB HID v1.11 Keyboard [DELL Dell USB Entry Keyboard] on usb-musb-hdrc.0-1.2/input0

EDIT: I forgot to state this, but this happens consistently on about 30% of the boards (the others work fine). It may certainly be a hardware issue, which is why I'm wondering specifically about what could cause the processor to behave this way (from software errors like device timing misconfigurations to hardware issues like unexpected fluctuations in processor input voltage, for example).

0 Kevin Boldingh over 8 years ago in reply to Biser Gatchev-XID

Prodigy 130 points

Any ideas that might help me clear this up?

Thanks,
Kevin

0 Biser Gatchev-XID over 8 years ago in reply to Kevin Boldingh

TI__Guru**** 393215 points

I have asked the software team to look at this. They will respond here.

0 Kevin Boldingh over 8 years ago in reply to Biser Gatchev-XID

Prodigy 130 points

I suspected that the NAND timing/config registers may have been the culprit (I've been flashing to NAND from MMC, then booting), but I removed all NAND functionality and booted straight from MMC, but I can still reproduce the error.

0 Kevin Boldingh over 8 years ago in reply to Kevin Boldingh

Prodigy 130 points

I found & fixed the issue. Once I realized I could run both OSs at 800MHz, I found this post on E2E: e2e.ti.com/.../1302015 . Turns out I had a very similar issue, that the MPU voltage would drop under the minimum spec voltage when the processor was under load.

Processors

Processors forum

PROCESSOR-SDK-AM335X: Processor halt issue