PROCESSOR-SDK-AM65X: Kernel boot hangs

Andrea Wang

We are testing the Linux 06.00.00.07 release for the AM65x processor on our custom board, which is very close in design to the AM65 EVM. Using the prebuilt U-Boot, Linux image, and DTB for the AM65 EVM on our custom board, we see that it hangs fairly early in the boot process. These are the last lines we see output on our board:

[ 0.000005] sched_clock: 56 bits at 200MHz, resolution 5ns, wraps every 4398046511102ns

[ 0.008695] Console: colour dummy device 80x25

[ 0.013248] console [tty0] enabled

[ 0.016729] bootconsole [ns16550a0] disabled

When we run the identical executables and DTB on the EVM we see the same four lines at the same point in the boot process, followed by this line:

I/TC: Dynamic shared memory is disabled

At which point the Linux kernel restarts, but this time the "I/TC: Dynamic shared memory is disabled” is not output and Linux boots through to the Arago command line.

We are obviously somewhat suspicious that the EVM performs a restart at pretty exactly the same place the Linux kernel hangs on our board. Also, the “I/TC” message output on the EVM is an indication that this is being output from the OP-TEE trusted execution environment. As a result, we’re wondering if it’s possible our board is hanging at this point because of a TEE-related issue. Questions:

1) Can you tell us why the Linux kernel running on the EVM is restarting following the "I/TC: Dynamic shared memory is disabled” message?

2) Is it possible our custom board is hanging at this point because of a TEE-related issue?

3) Can you identify what we might have missed related to the TEE that could be causing this hang on our custom board?"

over 6 years ago

0 Andreas Dannenberg over 6 years ago

TI__Guru 69887 points

Hi Andrea,

Andrea Wang said:
Using the prebuilt U-Boot, Linux image, and DTB for the AM65 EVM

Andrea Wang said:
When we run the identical executables and DTB on the EVM

Andrea Wang said:
We are obviously somewhat suspicious that the EVM performs a restart at pretty exactly the same place the Linux kernel hangs on our board.

just to clarify, are you saying that the unmodified original images part of the Linux SDK 06.00.00.07 exhibit an issue where it restarts and only the second time boots to prompt? I'm going to try this but I don't recall seeing such an issue.

As for your questions it is hard to say at this point if the issue is TEE related or not. But since it seems like you can re-create this issue on the TI EVM, which means I should be able to re-create this locally and be able to have a closer look, so I suggest we start debugging from there.

Thanks and Regards, Andreas

0 Andrea Wang over 6 years ago in reply to Andreas Dannenberg

Intellectual 380 points

Hi Andreas,

Correct, we are using the unmodified prebuilt images from the Linux SDK 06.00.00.07 to boot the EVM board.

Thanks,
Andrea

0 Andreas Dannenberg over 6 years ago in reply to Andrea Wang

TI__Guru 69887 points

Andrea,

thanks for confirming. I just tried a fresh install of PROCESSOR-SDK-LINUX-AM65X 06_00_00_07 and used the provided create-sdcard.sh SD card creation script to write a new card. With this, using an AM654x EVM, I have made several attempts to boot, both from a power-on RESET as well as through operating the on-board PORz reset button, and had no problem booting to Linux prompt in all cases always on the first attempt. Attached is a boot log for your reference.

Some next steps to help us verify a few basic assumptions first:

Do you have the ability to try your SD card on a different EVM?
Can you try use a different SD card, setup from scratch, using a freshly(!) installed Linux SDK?
Can you please share your complete boot log?

Thanks, Andreas

Fullscreen procsdk-linux-am65xx-evm-06.00.00.07-boot-log.txt Download

U-Boot SPL 2019.01-g8b90adfb16 (Jul 07 2019 - 05:46:46 +0000)
SYSFW ABI: 2.6 (firmware rev 0x0013 '19.4.1-v2019.04a (Curious Crow)')
Trying to boot from MMC2
Starting ATF on ARM64 core...

NOTICE:  BL31: v2.1(release):ti2019.01-rc2
NOTICE:  BL31: Built : 04:28:26, Jul  7 2019
I/TC: 
I/TC: OP-TEE version: 3.2.0-583-g251f7c6-dev #1 Sun Jul  7 04:40:43 UTC 2019 aarch64
I/TC: Initialized

U-Boot SPL 2019.01-g8b90adfb16 (Jul 07 2019 - 05:09:26 +0000)
detected AM6-IDKAPPEVM
detected SER-PCIE2LEVM
Trying to boot from MMC2


U-Boot 2019.01-g8b90adfb16 (Jul 07 2019 - 05:09:26 +0000)

Model: Texas Instruments AM654 Base Board
DRAM:  4 GiB
MMC:   sdhci@4f80000: 0, sdhci@04FA0000: 1
Loading Environment from MMC... OK
In:    serial
Out:   serial
Err:   serial
detected AM6-IDKAPPEVM
detected SER-PCIE2LEVM
Net:   eth2: pruss0_eth
Warning: cpsw_nuss@046000000 using MAC address from ROM
, eth0: cpsw_nuss@046000000, eth1: pruss2_eth
Hit any key to stop autoboot:  0 
switch to partitions #0, OK
mmc1 is current device
SD/MMC found on device 1
** Unable to read file boot.scr **
1490 bytes read in 2 ms (727.5 KiB/s)
Loaded env from uEnv.txt
Importing environment from mmc1 ...
Running uenvcmd ...
1 bytes read in 0 ms
Already setup.
12632072 bytes read in 1065 ms (11.3 MiB/s)
71809 bytes read in 9 ms (7.6 MiB/s)
11402 bytes read in 3 ms (3.6 MiB/s)
2084 bytes read in 2 ms (1017.6 KiB/s)
## Flattened Device Tree blob at 82000000
   Booting using the fdt blob at 0x82000000
   Loading Device Tree to 00000000fddb7000, end 00000000fdecbfff ... OK

Starting kernel ...

[    0.000000] Booting Linux on physical CPU 0x0000000000 [0x410fd034]
[    0.000000] Linux version 4.19.38-g4dae378bbe (oe-user@oe-host) (gcc version 8.3.0 (GNU Toolchain for the A-profile Architecture 8.3-2019.03 (arm-rel-8.36))) #1 SMP PREEMPT Sun Jul 7 03:42:51 UTC 2019
[    0.000000] Machine model: Texas Instruments AM654 Base Board
[    0.000000] earlycon: ns16550a0 at MMIO32 0x0000000002800000 (options '')
[    0.000000] bootconsole [ns16550a0] enabled
[    0.000000] Reserved memory: created DMA memory pool at 0x000000009b000000, size 1 MiB
[    0.000000] OF: reserved mem: initialized node r5f-dma-memory@9b000000, compatible id shared-dma-pool
[    0.000000] Reserved memory: created DMA memory pool at 0x000000009b100000, size 15 MiB
[    0.000000] OF: reserved mem: initialized node r5f-memory@9b100000, compatible id shared-dma-pool
[    0.000000] Reserved memory: created DMA memory pool at 0x000000009c000000, size 1 MiB
[    0.000000] OF: reserved mem: initialized node r5f-dma-memory@9c000000, compatible id shared-dma-pool
[    0.000000] Reserved memory: created DMA memory pool at 0x000000009c100000, size 7 MiB
[    0.000000] OF: reserved mem: initialized node r5f-memory@9c100000, compatible id shared-dma-pool
[    0.000000] cma: Reserved 512 MiB at 0x00000000c0000000
[    0.000000] psci: probing for conduit method from DT.
[    0.000000] psci: PSCIv1.1 detected in firmware.
[    0.000000] psci: Using standard PSCI v0.2 function IDs
[    0.000000] psci: Trusted OS migration not required
[    0.000000] psci: SMC Calling Convention v1.1
[    0.000000] random: get_random_bytes called from start_kernel+0x94/0x3e4 with crng_init=0
[    0.000000] percpu: Embedded 2 pages/cpu s48536 r8192 d74344 u131072
[    0.000000] Detected VIPT I-cache on CPU0
[    0.000000] CPU features: enabling workaround for ARM erratum 845719
[    0.000000] Speculative Store Bypass Disable mitigation not required
[    0.000000] CPU features: detected: Kernel page table isolation (KPTI)
[    0.000000] Built 1 zonelists, mobility grouping on.  Total pages: 64704
[    0.000000] Kernel command line: console=ttyS2,115200n8 earlycon=ns16550a,mmio32,0x02800000 mtdparts=47040000.spi.0:512k(ospi.tiboot3),2m(ospi.tispl),4m(ospi.u-boot),128k(ospi.env),128k(ospi.env.backup),1m(ospi.sysfw),-@8m(ospi.rootfs) root=PARTUUID=dcf4f8d0-02 rw rootfstype=ext4 rootwait
[    0.000000] Dentry cache hash table entries: 524288 (order: 6, 4194304 bytes)
[    0.000000] Inode-cache hash table entries: 262144 (order: 5, 2097152 bytes)
[    0.000000] software IO TLB: mapped [mem 0xf9db0000-0xfddb0000] (64MB)
[    0.000000] Memory: 3529856K/4145152K available (8062K kernel code, 752K rwdata, 2880K rodata, 576K init, 614K bss, 91008K reserved, 524288K cma-reserved)
[    0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1
[    0.000000] rcu: Preemptible hierarchical RCU implementation.
[    0.000000] rcu: 	RCU restricting CPUs from NR_CPUS=64 to nr_cpu_ids=4.
[    0.000000] 	Tasks RCU enabled.
[    0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4
[    0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0
[    0.000000] GICv3: GIC: Using split EOI/Deactivate mode
[    0.000000] GICv3: Distributor has no Range Selector support
[    0.000000] GICv3: no VLPI support, no direct LPI support
[    0.000000] ITS [mem 0x01820000-0x0182ffff]
[    0.000000] GIC: enabling workaround for ITS: Socionext Synquacer pre-ITS
[    0.000000] ITS@0x0000000001820000: allocated 1048576 Devices @8c0800000 (flat, esz 8, psz 64K, shr 0)
[    0.000000] ITS: using cache flushing for cmd queue
[    0.000000] GIC: using LPI property table @0x00000008c00c0000
[    0.000000] GICv3: CPU0: found redistributor 0 region 0:0x0000000001880000
[    0.000000] CPU0: using LPI pending table @0x00000008c00d0000
[    0.000000] GIC: using cache flushing for LPI property table
[    0.000000] arch_timer: cp15 timer(s) running at 200.00MHz (phys).
[    0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x2e2049d3e8, max_idle_ns: 440795210634 ns
[    0.000006] sched_clock: 56 bits at 200MHz, resolution 5ns, wraps every 4398046511102ns
[    0.008698] Console: colour dummy device 80x25
[    0.013294] Calibrating delay loop (skipped), value calculated using timer frequency.. 400.00 BogoMIPS (lpj=800000)
[    0.023985] pid_max: default: 32768 minimum: 301
[    0.028809] Security Framework initialized
[    0.033071] Mount-cache hash table entries: 8192 (order: 0, 65536 bytes)
[    0.039953] Mountpoint-cache hash table entries: 8192 (order: 0, 65536 bytes)
[    0.071395] ASID allocator initialised with 32768 entries
[    0.084963] rcu: Hierarchical SRCU implementation.
[    0.098033] Platform MSI: gic-its@18200000 domain created
[    0.103845] PCI/MSI: /interconnect@100000/interrupt-controller@1800000/gic-its@18200000 domain created
[    0.121412] smp: Bringing up secondary CPUs ...
[    0.158541] Detected VIPT I-cache on CPU1
[    0.158579] GICv3: CPU1: found redistributor 1 region 0:0x00000000018a0000
[    0.158636] CPU1: using LPI pending table @0x00000008c0330000
[    0.158681] CPU1: Booted secondary processor 0x0000000001 [0x410fd034]
[    0.220240] Detected VIPT I-cache on CPU2
[    0.220282] GICv3: CPU2: found redistributor 100 region 0:0x00000000018c0000
[    0.220343] CPU2: using LPI pending table @0x00000008c03c0000
[    0.220391] CPU2: Booted secondary processor 0x0000000100 [0x410fd034]
[    0.252657] Detected VIPT I-cache on CPU3
[    0.252685] GICv3: CPU3: found redistributor 101 region 0:0x00000000018e0000
[    0.252740] CPU3: using LPI pending table @0x00000008c0450000
[    0.252773] CPU3: Booted secondary processor 0x0000000101 [0x410fd034]
[    0.252917] smp: Brought up 1 node, 4 CPUs
[    0.328551] SMP: Total of 4 processors activated.
[    0.333372] CPU features: detected: GIC system register CPU interface
[    0.339984] CPU features: detected: 32-bit EL0 Support
[    0.367778] CPU: All CPU(s) started at EL2
[    0.371998] alternatives: patching kernel code
[    0.378003] devtmpfs: initialized
[    0.393140] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns
[    0.403145] futex hash table entries: 1024 (order: 0, 65536 bytes)
[    0.410866] pinctrl core: initialized pinctrl subsystem
[    0.416865] NET: Registered protocol family 16
[    0.422649] vdso: 2 pages (1 code @ (____ptrval____), 1 data @ (____ptrval____))
[    0.430239] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers.
[    0.443710] DMA: preallocated 256 KiB pool for atomic allocations
[    0.452572] davinci_gpio 42110000.wkup_gpio0: IRQ not populated, err = -517
[    0.466856] davinci_gpio 600000.main_gpio0: IRQ not populated, err = -517
[    0.474063] davinci_gpio 601000.main_gpio1: IRQ not populated, err = -517
[    0.492155] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages
[    0.499033] HugeTLB registered 512 MiB page size, pre-allocated 0 pages
[    0.507765] cryptd: max_cpu_qlen set to 1000
[    0.513957] media: Linux media interface: v0.10
[    0.518638] videodev: Linux video capture interface: v2.00
[    0.524296] pps_core: LinuxPPS API ver. 1 registered
[    0.529379] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>
[    0.538740] PTP clock support registered
[    0.542781] EDAC MC: Ver: 3.0.0
[    0.547310] Advanced Linux Sound Architecture Driver Initialized.
[    0.554493] clocksource: Switched to clocksource arch_sys_counter
[    0.560941] VFS: Disk quotas dquot_6.6.0
[    0.565051] VFS: Dquot-cache hash table entries: 8192 (order 0, 65536 bytes)
[    0.579402] NET: Registered protocol family 2
[    0.584607] tcp_listen_portaddr_hash hash table entries: 4096 (order: 0, 65536 bytes)
[    0.592767] TCP established hash table entries: 32768 (order: 2, 262144 bytes)
[    0.600466] TCP bind hash table entries: 32768 (order: 3, 524288 bytes)
[    0.607822] TCP: Hash tables configured (established 32768 bind 32768)
[    0.614664] UDP hash table entries: 2048 (order: 0, 65536 bytes)
[    0.620945] UDP-Lite hash table entries: 2048 (order: 0, 65536 bytes)
[    0.627841] NET: Registered protocol family 1
[    0.632929] RPC: Registered named UNIX socket transport module.
[    0.639007] RPC: Registered udp transport module.
[    0.643825] RPC: Registered tcp transport module.
[    0.648641] RPC: Registered tcp NFSv4.1 backchannel transport module.
[    0.656412] hw perfevents: enabled with armv8_pmuv3 PMU driver, 7 counters available
[    0.669580] Initialise system trusted keyrings
[    0.674377] workingset: timestamp_bits=46 max_order=16 bucket_order=0
[    0.686286] squashfs: version 4.0 (2009/01/31) Phillip Lougher
[    0.692964] NFS: Registering the id_resolver key type
[    0.698175] Key type id_resolver registered
[    0.702463] Key type id_legacy registered
[    0.706586] nfs4filelayout_init: NFSv4 File Layout Driver Registering...
[    0.713619] 9p: Installing v9fs 9p2000 file system support
[    0.724381] Key type asymmetric registered
[    0.728603] Asymmetric key parser 'x509' registered
[    0.733659] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 245)
[    0.741244] io scheduler noop registered
[    0.745264] io scheduler deadline registered
[    0.749774] io scheduler cfq registered (default)
[    0.754599] io scheduler mq-deadline registered
[    0.759248] io scheduler kyber registered
[    0.765110] pinctrl-single 4301c000.pinmux: 70 pins, size 280
[    0.771713] pinctrl-single 11c000.pinmux: 185 pins, size 740
[    0.777667] pinctrl-single 11c2e8.pinmux: 9 pins, size 36
[    0.784419] pinctrl-single a40000.timesync_router: 512 pins, size 2048
[    0.799899] Serial: 8250/16550 driver, 10 ports, IRQ sharing enabled
[    0.823036] brd: module loaded
[    0.833480] loop: module loaded
[    0.839188] libphy: Fixed MDIO Bus: probed
[    0.843950] tun: Universal TUN/TAP device driver, 1.6
[    0.849917] igbvf: Intel(R) Gigabit Virtual Function Network Driver - version 2.4.0-k
[    0.857953] igbvf: Copyright (c) 2009 - 2012 Intel Corporation.
[    0.864067] sky2: driver version 1.30
[    0.869356] VFIO - User Level meta-driver version: 0.3
[    0.875493] i2c /dev entries driver
[    0.880472] sdhci: Secure Digital Host Controller Interface driver
[    0.886806] sdhci: Copyright(c) Pierre Ossman
[    0.891737] sdhci-pltfm: SDHCI platform and OF driver helper
[    0.898899] ledtrig-cpu: registered to indicate activity on CPUs
[    0.905151] ti-sci interconnect@100000:interconnect@28380000:interconnect@42040000:dmsc: invalid resource
[    0.915994] optee: probing for conduit method from DT.
I/TC: Dynamic shared memory is disabled
[    0.921288] optee: revision 3.4 (251f7c63)
[    0.925225] optee: initialized driver
[    0.934615] NET: Registered protocol family 17
[    0.939246] 9pnet: Installing 9P2000 support
[    0.943685] Key type dns_resolver registered
[    0.948714] registered taskstats version 1
[    0.952922] Loading compiled-in X.509 certificates
[    0.958904] davinci_gpio 42110000.wkup_gpio0: IRQ not populated, err = -517
[    0.966464] davinci_gpio 600000.main_gpio0: IRQ not populated, err = -517
[    0.973676] davinci_gpio 601000.main_gpio1: IRQ not populated, err = -517
[    0.990824] ti-sci interconnect@100000:interconnect@28380000:interconnect@42040000:dmsc: invalid resource
[    1.000836] ti-sci interconnect@100000:interconnect@28380000:interconnect@42040000:dmsc: ABI: 2.6 (firmware rev 0x0013 '19.4.1-v2019.04a (Curious Crow)')
[    1.034093] random: fast init done
[    1.535890] davinci_gpio 42110000.wkup_gpio0: IRQ not populated, err = -517
[    1.543463] davinci_gpio 600000.main_gpio0: IRQ not populated, err = -517
[    1.550685] davinci_gpio 601000.main_gpio1: IRQ not populated, err = -517
[    1.559075] omap_i2c 42120000.i2c: bus 0 rev0.12 at 400 kHz
[    1.565611] omap_i2c 40b00000.i2c: bus 1 rev0.12 at 100 kHz
[    1.595872] omap_i2c 2000000.i2c: bus 2 rev0.12 at 400 kHz
[    1.602215] omap_i2c 2010000.i2c: bus 3 rev0.12 at 400 kHz
[    1.608550] omap_i2c 2020000.i2c: bus 4 rev0.12 at 400 kHz
[    1.614853] omap_i2c 2030000.i2c: bus 5 rev0.12 at 100 kHz
[    1.629179] keystone-pcie 5500000.pcie: Linked as a consumer to phy-900000.serdes.0
[    1.637063] keystone-pcie 5500000.pcie: Linked as a consumer to phy-910000.serdes.1
[    1.650398] keystone-pcie 5500000.pcie: host bridge /interconnect@100000/pcie@5500000 ranges:
[    1.659158] keystone-pcie 5500000.pcie:    IO 0x10020000..0x1002ffff -> 0x00000000
[    1.666927] keystone-pcie 5500000.pcie:   MEM 0x10030000..0x17ffffff -> 0x10030000
[    2.675006] keystone-pcie 5500000.pcie: Phy link never came up
[    2.681111] keystone-pcie 5500000.pcie: PCI host bridge to bus 0000:00
[    2.687807] pci_bus 0000:00: root bus resource [bus 00-ff]
[    2.693430] pci_bus 0000:00: root bus resource [io  0x0000-0xffff]
[    2.699765] pci_bus 0000:00: root bus resource [mem 0x10030000-0x17ffffff]
[    2.715724] pci 0000:00:00.0: BAR 6: assigned [mem 0x10030000-0x1003ffff pref]
[    2.723133] pci 0000:00:00.0: PCI bridge to [bus 01-ff]
[    2.729563] k3-ringacc 2b800000.ringacc: Ring Accelerator probed rings:286, gp-rings[96,160] sci-dev-id:195
[    2.739558] k3-ringacc 2b800000.ringacc: dma-ring-reset-quirk: enabled
[    2.746247] k3-ringacc 2b800000.ringacc: RA Proxy rev. 66344100, num_proxies:64
[    2.754106] k3-ringacc 3c000000.ringacc: Ring Accelerator probed rings:818, gp-rings[304,464] sci-dev-id:187
[    2.764188] k3-ringacc 3c000000.ringacc: dma-ring-reset-quirk: enabled
[    2.770877] k3-ringacc 3c000000.ringacc: RA Proxy rev. 66344100, num_proxies:64
[    2.778694] omap8250 40a00000.serial: PM domain ti_sci_pd will not be powered off
[    2.786774] 40a00000.serial: ttyS1 at MMIO 0x40a00000 (irq = 8, base_baud = 6000000) is a 8250
[    2.796486] 2800000.serial: ttyS2 at MMIO 0x2800000 (irq = 22, base_baud = 3000000) is a 8250
[    2.805264] console [ttyS2] enabled
[    2.805264] console [ttyS2] enabled
[    2.812319] bootconsole [ns16550a0] disabled
[    2.812319] bootconsole [ns16550a0] disabled
[    2.821760] 2810000.serial: ttyS3 at MMIO 0x2810000 (irq = 23, base_baud = 3000000) is a 8250
[    2.831060] 2820000.serial: ttyS4 at MMIO 0x2820000 (irq = 24, base_baud = 3000000) is a 8250
[    2.842065] cadence-qspi 47040000.spi: mt35xu512aba (65536 Kbytes)
[    2.848279] 7 cmdlinepart partitions found on MTD device 47040000.spi.0
[    2.854900] Creating 7 MTD partitions on "47040000.spi.0":
[    2.860390] 0x000000000000-0x000000080000 : "ospi.tiboot3"
[    2.866690] 0x000000080000-0x000000280000 : "ospi.tispl"
[    2.872627] 0x000000280000-0x000000680000 : "ospi.u-boot"
[    2.878762] 0x000000680000-0x0000006a0000 : "ospi.env"
[    2.884600] 0x0000006a0000-0x0000006c0000 : "ospi.env.backup"
[    2.890984] 0x0000006c0000-0x0000007c0000 : "ospi.sysfw"
[    2.896902] 0x000000800000-0x000004000000 : "ospi.rootfs"
[    3.102739] cadence-qspi 47040000.spi: Cadence QSPI NOR probe failed -517
[    3.117446] am65-cpsw-nuss 46000000.ethernet: rx-flow-id-base is not set -22
[    3.124620] am65-cpsw-nuss 46000000.ethernet: initializing am65 cpsw nuss version 0x6BA07100, cpsw version 0x6BA80100 Ports: 2
[    3.136224] UDMA has not been proped
[    3.139823] am65-cpsw-nuss 46000000.ethernet: Failed to request tx dma channel -517
[    3.149966] am65-cpts 310d0000.cpts: CPTS ver 0x4e8a2109, freq:200000000, add_val:4 pps:0
[    3.158728] thermal thermal_zone1: failed to read out thermal zone (-22)
[    3.165455] thermal thermal_zone2: failed to read out thermal zone (-22)
[    3.172904] OF: /interconnect@100000/interconnect@28380000/rti@40610000: arguments longer than property
[    3.182311] keystone-wdt 40610000.rti: heartbeat 60 sec
[    3.188472] mmc0: Unknown controller version (4). You may experience problems.
[    3.226808] mmc0: SDHCI controller on 4f80000.sdhci [4f80000.sdhci] using ADMA 64-bit
[    3.235431] mmc1: Unknown controller version (4). You may experience problems.
[    3.273796] mmc1: SDHCI controller on 4fa0000.sdhci [4fa0000.sdhci] using ADMA 64-bit
[    3.289436] mmc0: new HS200 MMC card at address 0001
[    3.295604] mmcblk0: mmc0:0001 R1J56L 14.7 GiB 
[    3.302254] mmcblk0boot0: mmc0:0001 R1J56L partition 1 8.00 MiB
[    3.312169] mmcblk0boot1: mmc0:0001 R1J56L partition 2 8.00 MiB
[    3.318293] mmcblk0rpmb: mmc0:0001 R1J56L partition 3 4.00 MiB, chardev (243:0)
[    3.327225]  mmcblk0: p1 p2
[    3.336139] omap-mailbox 31f80000.mailbox: omap mailbox rev 0x66fc5900
[    3.343250] omap-mailbox 31f81000.mailbox: omap mailbox rev 0x66fc5900
[    3.350898] mmc1: new high speed SDHC card at address 0007
[    3.356606] ti-udma 285c0000.udmap: Channels: 96 (tchan: 48, echan: 0, rchan: 48, rflow: 96)
[    3.365856] mmcblk1: mmc1:0007 SD16G 14.9 GiB 
[    3.374716]  mmcblk1: p1 p2
[    3.381044] ti-udma 31150000.udmap: Channels: 267 (tchan: 120, echan: 32, rchan: 150, rflow: 300)
[    3.405950] cadence-qspi 47040000.spi: mt35xu512aba (65536 Kbytes)
[    3.412167] 7 cmdlinepart partitions found on MTD device 47040000.spi.0
[    3.418783] Creating 7 MTD partitions on "47040000.spi.0":
[    3.424273] 0x000000000000-0x000000080000 : "ospi.tiboot3"
[    3.430612] 0x000000080000-0x000000280000 : "ospi.tispl"
[    3.436560] 0x000000280000-0x000000680000 : "ospi.u-boot"
[    3.442654] 0x000000680000-0x0000006a0000 : "ospi.env"
[    3.448412] 0x0000006a0000-0x0000006c0000 : "ospi.env.backup"
[    3.454785] 0x0000006c0000-0x0000007c0000 : "ospi.sysfw"
[    3.460758] 0x000000800000-0x000004000000 : "ospi.rootfs"
[    3.473611] m25p80 spi7.0: unrecognized JEDEC id bytes: 3f, ff, 1f
[    3.479828] m25p80: probe of spi7.0 failed with error -2
[    3.485678] am65-cpsw-nuss 46000000.ethernet: rx-flow-id-base is not set -22
[    3.492839] am65-cpsw-nuss 46000000.ethernet: initializing am65 cpsw nuss version 0x6BA07100, cpsw version 0x6BA80100 Ports: 2
[    3.513054] am65-cpsw-nuss 46000000.ethernet: set new flow-id-base 48
[    3.526518] pps pps0: new PPS source ptp1
[    3.530721] am65-cpsw-nuss 46000000.ethernet: CPTS ver 0x4e8a2109, freq:200000000, add_val:4 pps:1
[    3.539698] am65-cpsw-nuss 46000000.ethernet: initialized cpsw ale version 0.4
[    3.546918] am65-cpsw-nuss 46000000.ethernet: ALE Table size 64
[    3.590513] am65-cpsw-nuss 46000000.ethernet: davinci mdio revision 9.7, bus freq 1000000
[    3.598690] libphy: 46000000.ethernet: probed
[    3.606410] input: gpio-keys as /devices/platform/gpio-keys/input/input0
[    3.613424] hctosys: unable to open rtc device (rtc0)
[    3.714913] ALSA device list:
[    3.717882]   No soundcards found.
[   14.885483] EXT4-fs (mmcblk1p2): recovery complete
[   14.893308] EXT4-fs (mmcblk1p2): mounted filesystem with ordered data mode. Opts: (null)
[   14.901461] VFS: Mounted root (ext4 filesystem) on device 179:98.
[   14.915610] devtmpfs: mounted
[   14.918861] Freeing unused kernel memory: 576K
[   14.923349] Run /sbin/init as init process
[   15.241763] systemd[1]: System time before build time, advancing clock.
[   15.362814] NET: Registered protocol family 10
[   15.368369] Segment Routing with IPv6
[   15.409751] systemd[1]: systemd 239 running in system mode. (+PAM -AUDIT -SELINUX +IMA -APPARMOR +SMACK +SYSVINIT +UTMP -LIBCRYPTSETUP -GCRYPT -GNUTLS +ACL +XZ -LZ4 -SECCOMP +BLKID -ELFUTILS +KMOD -IDN2 -IDN -PCRE2 default-hierarchy=hybrid)
[   15.431526] systemd[1]: Detected architecture arm64.

Welcome to Arago 2019.05!

[   15.471560] systemd[1]: Set hostname to <am65xx-evm>.
[   15.664360] systemd[1]: File /lib/systemd/system/systemd-journald.service:36 configures an IP firewall (IPAddressDeny=any), but the local system does not support BPF/cgroup based firewalling.
[   15.681416] systemd[1]: Proceeding WITHOUT firewalling in effect! (This warning is only shown for the first loaded unit using IP firewalling.)
[   15.920791] random: systemd: uninitialized urandom read (16 bytes read)
[   15.927954] systemd[1]: Started Forward Password Requests to Wall Directory Watch.
[  OK  ] Started Forward Password Requests to Wall Directory Watch.
[   15.950676] random: systemd: uninitialized urandom read (16 bytes read)
[   15.957772] systemd[1]: Listening on udev Control Socket.
[  OK  ] Listening on udev Control Socket.
[   15.974598] random: systemd: uninitialized urandom read (16 bytes read)
[   15.981258] systemd[1]: Reached target Remote File Systems.
[  OK  ] Reached target Remote File Systems.
[   15.999035] systemd[1]: Listening on Network Service Netlink Socket.
[  OK  ] Listening on Network Service Netlink Socket.
[   16.021273] systemd[1]: Created slice system-getty.slice.
[  OK  ] Created slice system-getty.slice.
[  OK  ] Listening on initctl Compatibility Named Pipe.
[  OK  ] Started Dispatch Password Requests to Console Directory Watch.
[  OK  ] Reached target Paths.
[  OK  ] Listening on udev Kernel Socket.
[  OK  ] Listening on Process Core Dump Socket.
[  OK  ] Created slice User and Session Slice.
[  OK  ] Reached target Slices.
[  OK  ] Created slice system-serial\x2dgetty.slice.
[  OK  ] Reached target Swap.
[  OK  ] Listening on Journal Socket.
         Mounting POSIX Message Queue File System...
         Mounting Kernel Debug File System...
[  OK  ] Started Hardware RNG Entropy Gatherer Daemon.
         Mounting Huge Pages File System...
         Mounting Temporary Directory (/tmp)...
         Starting udev Coldplug all Devices...
         Starting Remount Root and Kernel File Systems...
         Starting Create list of required st…ce nodes for the [   16.312078] EXT4-fs (mmcblk1p2): re-mounted. Opts: (null)
current kernel...
         Starting Load Kernel Modules...
[  OK  ] Listening on Journal Socket (/dev/log).
[   16.361818] cryptodev: loading out-of-tree module taints kernel.
[   16.373507] cryptodev: driver 1.9 loaded.
         Starting Journal Service...
[  OK  ] Mounted POSIX Message Queue File System.
[  OK  ] Mounted Kernel Debug File System.
[  OK  ] Mounted Huge Pages File System.
[  OK  ] Mounted Temporary Directory (/tmp).
[  OK  ] Started Remount Root and Kernel File Systems.
[  OK  ] Started Journal Service.
[  OK  ] Started Create list of required sta…vice nodes for the current kernel.
[  OK  ] Started Load Kernel Modules.
         Starting Apply Kernel Variables...
         Mounting Kernel Configuration File System...
         Starting Flush Journal to Persistent Storage...
         Starting Create Static Device Nodes in /dev...
[  OK  ] Started Apply Kernel Variables.
[  OK  ] Mounted Kernel Configuration File System.
[  OK  ] Started Create Static Device Nodes in /dev.
         Starting udev Kernel Device Manager...
[  OK  ] Reached target Local File Systems (Pre).
[  OK  ] Reached target Containers.
         Mounting /media/ram...
         Mounting /var/volatile...
[  OK  ] Mounted /media/ram.
[  OK  ] Mounted /var/volatile.
[   16.784008] systemd-journald[166]: Received request to flush runtime journal from PID 1
         Starting Load/Save Random Seed...
[  OK  ] Reached target Local File Systems.
[  OK  ] Started udev Kernel Device Manager.
[  OK  ] Started Flush Journal to Persistent Storage.
[  OK  ] Started Load/Save Random Seed.
         Starting Create Volatile Files and Directories...
[  OK  ] Started Create Volatile Files and Directories.
         Starting Network Time Synchronization...
         Starting Update UTMP about System Boot/Shutdown...
         Starting Network Service...
[   17.024195] input: gpio-decoder as /devices/platform/gpio-decoder/input/input1
[  OK  ] Started udev Coldplug all Devices.
[  OK  ] Started Update UTMP about System Boot/Shutdown.
[  OK  ] Started Network Service.
         Starting Network Name Resolution...
[  OK  ] Found device /dev/ttyS2.
[   17.330146] omap_rng 4e10000.trng: Random Number Generator ver. 230b34c
[  OK  ] Started Network Time Synchronization.
[  OK  ] Reached target System Initialization.
[  OK  ] Listening on dropbear.socket.
[  OK  ] Started Daily Cleanup of Temporary Directories.
[  OK  ] Listening on RPCbind Server Activation Socket.
[  OK  ] Listening on D-Bus System Message Bus Socket.
[  OK  ] Reached target Sockets.
[   17.446314] CAN device driver interface
[  OK  ] Reached target Basic System.
[  OK  ] Started Job spooling tools.
[  OK  ] Started D-Bus System Message Bus.
[   17.506065] m_can 40528000.mcan: m_can device registered (irq=17, version=32)
[   17.523871] m_can 40568000.mcan: m_can device registered (irq=19, version=32)
[   17.535116] [drm] Initialized pvr 1.17.4948957 20110701 for 7000000.gpu on minor 0
         Starting TI IPC Daemon...
[  OK  ] Started Periodic Command Scheduler.
         Starting rng-tools.service...
         Starting Login Service...
         Starting Print notice about GPLv3 packages...
[  OK  ] Started strongSwan IPsec IKEv1/IKEv2 daemon using ipsec.conf.
         Starting Reboot and dump vmcore via kexec...
[  OK  ] Started irqbalance daemon.
         Starting RPC Bind Service...
[  OK  ] Reached target System Time Synchronized.
[  OK  ] Started Daily rotation of log files.
[  OK  ] Reached target Timers.
[  OK  ] Started Network Name Resolution.
[  OK  ] Started TI IPC Daemon.
[   17.795568] random: crng init done
[   17.800052] random: 7 urandom warning(s) missed due to ratelimiting
[  OK  ] Started rng-tools.service.
[  OK  ] Started Reboot and dump vmcore via kexec.
         Starting rc.pvr.service...
[  OK  ] Reached target Host and Network Name Lookups.
[  OK  ] Reached target Network.
         Starting Lightning Fast Webserver With Light System Requirements...
         Starting Permit User Sessions...
         Starting Simple Network Management Protocol (SNMP) Daemon....
[   17.935432] TI DP83867 k3-cpsw-mdio:00: attached PHY driver [TI DP83867] (mii_bus:phy_addr=k3-cpsw-mdio:00, irq=POLL)
[   17.950310] IPv6: ADDRCONF(NETDEV_UP): eth0: link is not ready
[   18.017710] PVR_K: UM DDK-(4948957) and KM DDK-(4948957) match. [ OK ]
[  OK  ] Started Login Service.
[  OK  ] Started RPC Bind Service.
[  OK  ] Started rc.pvr.service.
[  OK  ] Started Permit User Sessions.
[  OK  ] Started Lightning Fast Webserver With Light System Requirements.
[   18.186906] k3_r5_rproc interconnect@100000:interconnect@28380000:r5fss@41000000: creating child devices for R5F cores
[   18.216540] platform 41000000.r5f: assigned reserved memory node r5f-dma-memory@9c000000
[   18.226140] remoteproc remoteproc0: 41000000.r5f is available
[   18.546632] remoteproc remoteproc0: powering up 41000000.r5f
[   18.552647] remoteproc remoteproc0: Booting fw image am65x-mcu-r5f0_0-fw, size 4050720
[   18.565578] platform 41000000.r5f: booting R5F core using boot addr = 0x0
[   18.575329] remoteproc remoteproc0: registered virtio0 (type 7)
[   18.584975] remoteproc remoteproc0: remote processor 41000000.r5f is now up
[  OK  ] Started Getty on tty1.
[  OK  ] Started Serial Getty on ttyS2.
[  OK  ] Reached target Login Prompts.
         Starting Synchronize System and HW clocks...
         Starting weston.service...
[  OK  ] Started NFS status monitor for NFSv2/3 locking..
[FAILED] Failed to start Synchronize System and HW clocks.
See 'systemctl status sync-clocks.service' for details.
[   18.767302] NET: Registered protocol family 15
[   18.819318] davinci_mdio b032400.mdio: davinci mdio revision 1.7, bus freq 1000000
[   18.829956] libphy: b032400.mdio: probed
[  OK  ] Started weston.service.
[   18.886411] davinci_mdio b032400.mdio: phy[0]: device b032400.mdio:00, driver TI DP83867
[   18.921892] davinci_mdio b032400.mdio: phy[3]: device b032400.mdio:03, driver TI DP83867
         Starting telnetd.service...
         Starting Matrix GUI...
[   18.981295] am65-cpsw-nuss 46000000.ethernet eth0: Link is Down
[   19.011336] davinci_mdio b132400.mdio: davinci mdio revision 1.7, bus freq 1000000
[  OK  ] Started Matrix GUI.
[   19.049421] Initializing XFRM netlink socket
[  OK  ] Started telnetd.service.
[   19.068798] libphy: b132400.mdio: probed
         Starting thttpd.service...
[   19.128829] davinci_mdio b132400.mdio: phy[0]: device b132400.mdio:00, driver TI DP83867
[   19.145206] davinci_mdio b132400.mdio: phy[3]: device b132400.mdio:03, driver TI DP83867
[   19.207090] davinci_mdio b232400.mdio: davinci mdio revision 1.7, bus freq 1000000
[   19.217767] libphy: b232400.mdio: probed
[   19.264980] davinci_mdio b232400.mdio: phy[0]: device b232400.mdio:00, driver TI DP83867
[   19.276494] davinci_mdio b232400.mdio: phy[3]: device b232400.mdio:03, driver TI DP83867
[  OK  ] Started thttpd.service.
[   19.345938] remoteproc remoteproc1: b034000.pru is available
[   19.358838] pru-rproc b034000.pru: PRU rproc node pru@b034000 probed successfully
[   19.387383] remoteproc remoteproc2: b004000.rtu is available
[   19.395912] pru-rproc b004000.rtu: PRU rproc node rtu@b004000 probed successfully
[   19.432185] remoteproc remoteproc3: b038000.pru is available
[   19.450278] pru-rproc b038000.pru: PRU rproc node pru@b038000 probed successfully
[   19.476404] remoteproc remoteproc4: b006000.rtu is available
[   19.531938] pru-rproc b006000.rtu: PRU rproc node rtu@b006000 probed successfully
[   19.553677] remoteproc remoteproc5: b134000.pru is available
[   19.561650] pru-rproc b134000.pru: PRU rproc node pru@b134000 probed successfully
[   19.570291] remoteproc remoteproc6: b104000.rtu is available
[   19.576818] pru-rproc b104000.rtu: PRU rproc node rtu@b104000 probed successfully
[   19.585046] remoteproc remoteproc7: b138000.pru is available
[   19.591059] pru-rproc b138000.pru: PRU rproc node pru@b138000 probed successfully
[   19.598960] remoteproc remoteproc8: b106000.rtu is available
[   19.604784] pru-rproc b106000.rtu: PRU rproc node rtu@b106000 probed successfully
[   19.612563] remoteproc remoteproc9: b234000.pru is available
[   19.618294] pru-rproc b234000.pru: PRU rproc node pru@b234000 probed successfully
[   19.626188] remoteproc remoteproc10: b204000.rtu is available
[   19.632014] pru-rproc b204000.rtu: PRU rproc node rtu@b204000 probed successfully
[   19.639759] remoteproc remoteproc11: b238000.pru is available
[   19.645578] pru-rproc b238000.pru: PRU rproc node pru@b238000 probed successfully
[   19.656578] remoteproc remoteproc12: b206000.rtu is available
[   19.662582] pru-rproc b206000.rtu: PRU rproc node rtu@b206000 probed successfully
         Starting thermal-zone-init.service...
[  OK  ] Created slice system-systemd\x2dfsck.slice.
[  OK  ] Started thermal-zone-init.service.
[   20.390831] virtio_rpmsg_bus virtio0: rpmsg host is online
[   20.391705] virtio_rpmsg_bus virtio0: creating channel rpmsg-proto addr 0x3d
[  OK  ] Found device /dev/mmcblk0p2.
         Starting File System Check on /dev/mmcblk0p2...
[   20.443327] icssg-prueth pruss1_eth: TI PRU ethernet initialized: dual EMAC mode, dual_icssg 0
[   20.487440] NET: Registered protocol family 45
[   20.504189] icssg-prueth pruss0_eth: TI PRU ethernet initialized: dual EMAC mode, dual_icssg 0
[  OK  ] Found device /dev/mmcblk0p1.
[   20.517186] dwc3 4010000.usb: Failed to get clk 'ref': -2
         Starting File System Check on /dev/mmcblk0p1...
[   20.549672] dwc3 4030000.usb: Failed to get clk 'ref': -2
[   20.555930] OF: graph: no port node found in /interconnect@100000/phy@4110000
[   20.573588] icssg-prueth pruss2_eth: TI PRU ethernet initialized: dual EMAC mode, dual_icssg 0
[  OK  ] Started File System Check on /dev/mmcblk0p1.
         Mounting /run/media/mmcblk0p1...
[  OK  ] Mounted /run/media/mmcblk0p1.
[   21.350756] usbcore: registered new interface driver usbfs
[   21.356447] usbcore: registered new interface driver hub
[   21.361935] usbcore: registered new device driver usb
[   21.460642] icssg-prueth pruss2_eth eth6: flow id base = 150
[   21.473107] icssg-prueth pruss2_eth eth6: mgm flow id base = 154
[   21.488429] remoteproc remoteproc11: powering up b238000.pru
[   21.501699] xhci-hcd xhci-hcd.2.auto: xHCI Host Controller
[  OK  ] [   21.507686] xhci-hcd xhci-hcd.2.auto: new USB bus registered, assigned bus number 1
Started File System Check on /dev/mmcblk0p2.
[   21.519465] xhci-hcd xhci-hcd.2.auto: hcc params 0x0298fe6d hci version 0x110 quirks 0x0000000000010010
[   21.530591] xhci-hcd xhci-hcd.2.auto: irq 309, io mem 0x04010000
[   21.537979] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 4.19
[   21.546375] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[   21.546572] remoteproc remoteproc11: Booting fw image ti-pruss/am65x-pru1-prueth-fw.elf, size 14868
[   21.553774] usb usb1: Product: xHCI Host Controller
[   21.562872] remoteproc remoteproc11: remote processor b238000.pru is now up
[   21.567773] usb usb1: Manufacturer: Linux 4.19.38-g4dae378bbe xhci-hcd
[   21.574801] remoteproc remoteproc12: powering up b206000.rtu
[   21.581831] usb usb1: SerialNumber: xhci-hcd.2.auto
[   21.593109] hub 1-0:1.0: USB hub found
[   21.597055] hub 1-0:1.0: 1 port detected
[   21.601903] xhci-hcd xhci-hcd.2.auto: xHCI Host Controller
         Mounting /run/media/mmcblk0p2...
[   21.608527] xhci-hcd xhci-hcd.2.auto: new USB bus registered, assigned bus number 2
[   21.619634] xhci-hcd xhci-hcd.2.auto: Host supports USB 3.0  SuperSpeed
[   21.619676] remoteproc remoteproc12: Booting fw image ti-pruss/am65x-rtu1-prueth-fw.elf, size 15488
[   21.626528] usb usb2: We don't know the algorithms for LPM for this host, disabling LPM.
[   21.636072] remoteproc remoteproc12: remote processor b206000.rtu is now up
[   21.643819] usb usb2: New USB device found, idVendor=1d6b, idProduct=0003, bcdDevice= 4.19
[   21.651805] net eth6: started
[   21.659104] usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[   21.662259] IPv6: ADDRCONF(NETDEV_UP): eth6: link is not ready
[   21.669511] usb usb2: Product: xHCI Host Controller
[   21.680478] usb usb2: Manufacturer: Linux 4.19.38-g4dae378bbe xhci-hcd
[   21.687250] usb usb2: SerialNumber: xhci-hcd.2.auto
[   21.693459] hub 2-0:1.0: USB hub found
[   21.697451] hub 2-0:1.0: 1 port detected
[   21.702638] xhci-hcd xhci-hcd.3.auto: xHCI Host Controller
[   21.708340] xhci-hcd xhci-hcd.3.auto: new USB bus registered, assigned bus number 3
[   21.717158] xhci-hcd xhci-hcd.3.auto: hcc params 0x0298fe6d hci version 0x110 quirks 0x0000000000010010
[   21.718617] icssg-prueth pruss1_eth eth1: flow id base = 157
[   21.726786] xhci-hcd xhci-hcd.3.auto: irq 311, io mem 0x04030000
[   21.734860] EXT4-fs (mmcblk0p2): mounted filesystem with ordered data mode. Opts: (null)
[   21.735892] icssg-prueth pruss1_eth eth1: mgm flow id base = 161
[   21.738546] remoteproc remoteproc5: powering up b134000.pru
[   21.738884] usb usb3: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 4.19
[   21.738892] usb usb3: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[   21.738898] usb usb3: Product: xHCI Host Controller
[   21.738904] usb usb3: Manufacturer: Linux 4.19.38-g4dae378bbe xhci-hcd
[   21.738909] usb usb3: SerialNumber: xhci-hcd.3.auto
[   21.740326] hub 3-0:1.0: USB hub found
[   21.740360] hub 3-0:1.0: 1 port detected
[   21.741421] xhci-hcd xhci-hcd.3.auto: xHCI Host Controller
[   21.741439] xhci-hcd xhci-hcd.3.auto: new USB bus registered, assigned bus number 4
[   21.741460] xhci-hcd xhci-hcd.3.auto: Host supports USB 3.0  SuperSpeed
[   21.741528] usb usb4: We don't know the algorithms for LPM for this host, disabling LPM.
[   21.741641] usb usb4: New USB device found, idVendor=1d6b, idProduct=0003, bcdDevice= 4.19
[   21.741648] usb usb4: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[   21.741654] usb usb4: Product: xHCI Host Controller
[   21.741660] usb usb4: Manufacturer: Linux 4.19.38-g4dae378bbe xhci-hcd
[   21.741665] usb usb4: SerialNumber: xhci-hcd.3.auto
[   21.742779] hub 4-0:1.0: USB hub found
[   21.742811] hub 4-0:1.0: 1 port detected
[  OK  ] Mounted /run/media/mmcblk0p2.
[   21.883396] remoteproc remoteproc5: Booting fw image ti-pruss/am65x-pru0-prueth-fw.elf, size 14868
[   21.892478] remoteproc remoteproc5: remote processor b134000.pru is now up
[   21.899409] remoteproc remoteproc6: powering up b104000.rtu
[   21.929700] remoteproc remoteproc6: Booting fw image ti-pruss/am65x-rtu0-prueth-fw.elf, size 15488
[   21.938790] remoteproc remoteproc6: remote processor b104000.rtu is now up
[   21.947512] net eth1: started
[   21.951217] IPv6: ADDRCONF(NETDEV_UP): eth1: link is not ready
[   21.971124] icssg-prueth pruss2_eth eth5: flow id base = 164
[   21.980384] icssg-prueth pruss2_eth eth5: mgm flow id base = 168
[   21.989230] remoteproc remoteproc9: powering up b234000.pru
[   21.995380] remoteproc remoteproc9: Booting fw image ti-pruss/am65x-pru0-prueth-fw.elf, size 14868
[   22.004433] remoteproc remoteproc9: remote processor b234000.pru is now up
[   22.011373] remoteproc remoteproc10: powering up b204000.rtu
[   22.017194] remoteproc remoteproc10: Booting fw image ti-pruss/am65x-rtu0-prueth-fw.elf, size 15488
[   22.026302] remoteproc remoteproc10: remote processor b204000.rtu is now up
[   22.034405] net eth5: started
[   22.037650] IPv6: ADDRCONF(NETDEV_UP): eth5: link is not ready
[   22.049682] icssg-prueth pruss1_eth eth2: flow id base = 171
[   22.059516] icssg-prueth pruss1_eth eth2: mgm flow id base = 175
[   22.059988] am65-cpsw-nuss 46000000.ethernet eth0: Link is Up - 1Gbps/Full - flow control rx/tx
[   22.068340] remoteproc remoteproc7: powering up b138000.pru
[   22.080007] remoteproc remoteproc7: Booting fw image ti-pruss/am65x-pru1-prueth-fw.elf, size 14868
[   22.089041] remoteproc remoteproc7: remote processor b138000.pru is now up
[   22.095960] remoteproc remoteproc8: powering up b106000.rtu
[   22.101683] remoteproc remoteproc8: Booting fw image ti-pruss/am65x-rtu1-prueth-fw.elf, size 15488
[   22.111083] remoteproc remoteproc8: remote processor b106000.rtu is now up
[   22.119086] net eth2: started
[   22.122253] IPv6: ADDRCONF(NETDEV_UP): eth2: link is not ready
[   22.129087] IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready
[   22.139553] icssg-prueth pruss0_eth eth4: flow id base = 178
[   22.149188] icssg-prueth pruss0_eth eth4: mgm flow id base = 182
[   22.158658] remoteproc remoteproc3: powering up b038000.pru
[   22.164762] remoteproc remoteproc3: Booting fw image ti-pruss/am65x-pru1-prueth-fw.elf, size 14868
[   22.173795] remoteproc remoteproc3: remote processor b038000.pru is now up
[   22.180728] remoteproc remoteproc4: powering up b006000.rtu
[   22.186461] remoteproc remoteproc4: Booting fw image ti-pruss/am65x-rtu1-prueth-fw.elf, size 15488
[   22.195490] remoteproc remoteproc4: remote processor b006000.rtu is now up
[   22.203557] net eth4: started
[   22.206795] IPv6: ADDRCONF(NETDEV_UP): eth4: link is not ready
[   22.223794] icssg-prueth pruss0_eth eth3: flow id base = 185
[   22.233675] icssg-prueth pruss0_eth eth3: mgm flow id base = 189
[   22.242695] remoteproc remoteproc1: powering up b034000.pru
[   22.248758] remoteproc remoteproc1: Booting fw image ti-pruss/am65x-pru0-prueth-fw.elf, size 14868
[   22.257792] remoteproc remoteproc1: remote processor b034000.pru is now up
[   22.264705] remoteproc remoteproc2: powering up b004000.rtu
[   22.270388] remoteproc remoteproc2: Booting fw image ti-pruss/am65x-rtu0-prueth-fw.elf, size 15488
[   22.279410] remoteproc remoteproc2: remote processor b004000.rtu is now up
[   22.287472] net eth3: started
[   22.290717] IPv6: ADDRCONF(NETDEV_UP): eth3: link is not ready
[  OK  ] Found device /dev/mmcblk1p1.
         Starting File System Check on /dev/mmcblk1p1...
[  OK  ] Started Simple Network Management Protocol (SNMP) Daemon..
[  OK  ] Started File System Check on /dev/mmcblk1p1.
         Mounting /run/media/mmcblk1p1...
[  OK  ] Mounted /run/media/mmcblk1p1.

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

Arago Project http://arago-project.org am65xx-evm ttyS2

Arago 2019.05 am65xx-evm ttyS2

am65xx-evm login: ***************************************************************
***************************************************************
NOTICE: This file system contains the following GPLv3 packages:
	autoconf
	bash
	bc
	binutils
	cifs-utils
	cpio
	cpp-symlinks
	cpp
	dosfstools
	elfutils
	g++-symlinks
	g++
	gawk
	gcc-symlinks
	gcc
	gdb
	gdbserver
	gettext
	glmark2
	gstreamer1.0-libav
	gzip
	hidapi
	libbfd
	libdw1
	libelf1
	libgdbm-compat4
	libgdbm6
	libgettextlib
	libgettextsrc
	libgmp10
	libidn2-0
	libmpc3
	libmpfr6
	libreadline7
	libunistring2
	m4
	make
	nettle
	parted
	pdm-anomaly-detection
	which

If you do not wish to distribute GPLv3 components please remove
the above packages prior to distribution.  This can be done using
the opkg remove command.  i.e.:
    opkg remove <package>
Where <package> is the name printed in the list above

NOTE: If the package is a dependency of another package you
      will be notified of the dependent packages.  You should
      use the --force-removal-of-dependent-packages option to
      also remove the dependent packages as well
***************************************************************
***************************************************************

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

Arago Project http://arago-project.org am65xx-evm ttyS2

Arago 2019.05 am65xx-evm ttyS2

am65xx-evm login:

0 Andrea Wang over 6 years ago in reply to Andreas Dannenberg

Intellectual 380 points

Hi Andreas,

Here is a boot log from the EVM board. We have been using the SD card for u-boot and TFTP for the kernel. We're trying to duplicate this environment for our custom board since layout issues made the SD card an unreliable boot device.

Thanks,
Andrea

U-Boot SPL 2019.01-g8b90adfb16 (Jul 07 2019 - 05:46:46 +0000)
SYSFW ABI: 2.6 (firmware rev 0x0013 '19.4.1-v2019.04a (Curious Crow)')
Trying to boot from MMC2
Starting ATF on ARM64 core...

NOTICE: BL31: v2.1(release):ti2019.01-rc2
NOTICE: BL31: Built : 04:28:26, Jul 7 2019
I/TC:
I/TC: OP-TEE version: 3.2.0-583-g251f7c6-dev #1 Sun Jul 7 04:40:43 UTC 2019 aarch64
I/TC: Initialized

U-Boot SPL 2019.01-g8b90adfb16 (Jul 07 2019 - 05:09:26 +0000)
detected AM6-IDKAPPEVM
detected SER-PCIE2LEVM
Trying to boot from MMC2

U-Boot 2019.01-g8b90adfb16 (Jul 07 2019 - 05:09:26 +0000)

Model: Texas Instruments AM654 Base Board
DRAM: 4 GiB
MMC: sdhci@4f80000: 0, sdhci@04FA0000: 1
Loading Environment from MMC... OK
In: serial@2800000
Out: serial@2800000
Err: serial@2800000
detected AM6-IDKAPPEVM
detected SER-PCIE2LEVM
Net: eth2: pruss0_eth, eth0: cpsw_nuss@046000000, eth1: pruss2_eth
Hit any key to stop autoboot: 0
=> printenv
arch=arm
args_all=setenv optargs earlycon=ns16550a,mmio32,0x02800000 ${mtdparts}
args_mmc=run finduuid;setenv bootargs console=${console} ${optargs} root=PARTUUID=${uuid} rw rootfstype=${mmcrootfstype}
args_net=setenv bootargs ${optargs} rootfstype=nfs root=/dev/nfs rw nfsroot=${serverip}:${nfs_root},${nfs_options} ip=dhcp
args_ubi=setenv bootargs ${console} ${optargs} rootfstype=ubifs root=ubi0:rootfs rw ubi.mtd=ospi.rootfs
baudrate=115200
board=am65x
board_name=am65x
board_rev=E3
board_serial=0165
board_software_revision=01
boot=mmc
boot_fit=0
bootargs=androidboot.serialno=${serial#} console=ttyS2,115200 androidboot.console=ttyS2 androidboot.hardware=am65xevmboard
bootcmd=run findfdt; run envboot; run setup_${kern_boot}; run init_${rootfs_boot}; run get_kern_${kern_boot}; run get_fdt_${kern_boot}; run get_overlay_${kern_boot}; run run_kern
bootdelay=2
bootdir=/tftpboot
bootenvfile=uEnv.txt
bootpart=1:2
bootscript=echo Running bootscript from mmc${mmcdev} ...; source ${loadaddr}
check_android=setenv mmcdev 0; env delete boot_start; part start mmc ${mmcdev} boot boot_start; if test "$boot_start" = ""; then env set is_android 0; else env set is_android 1; fi; env delete boot_start
check_dofastboot=if test "${dofastboot}" -eq 1; then echo Boot fastboot requested, resetting dofastboot ...;setenv dofastboot 0; env save; echo Booting into fastboot ...; fastboot 0; fi
console=ttyS2,115200n8
cpu=armv8
dfu_alt_info_emmc=rawemmc raw 0 0x800000 mmcpart 1;rootfs part 0 1 mmcpart 0;tiboot3.bin.raw raw 0x0 0x400 mmcpart 1;tispl.bin.raw raw 0x400 0x1000 mmcpart 1;u-boot.img.raw raw 0x1400 0x2000 mmcpart 1;u-1
dfu_alt_info_mmc=boot part 1 1;rootfs part 1 2;tiboot3.bin fat 1 1;tispl.bin fat 1 1;u-boot.img fat 1 1;uEnv.txt fat 1 1;sysfw.itb fat 1 1
dfu_alt_info_ospi=tiboot3.bin raw 0x0 0x080000;tispl.bin raw 0x080000 0x200000;u-boot.img raw 0x280000 0x400000;u-boot-env raw 0x680000 0x020000;sysfw.itb raw 0x6c0000 0x100000;rootfs raw 0x800000 0x38000
dfu_bufsiz=0x20000
dofastboot=0
emmc_android_boot=echo Trying to boot Android from eMMC ...; setenv fit_loadaddr 0x87000000; setenv loadaddr ${fit_loadaddr}; setenv eval_bootargs setenv bootargs $bootargs; run eval_bootargs; setenv mmc}
envboot=mmc dev ${mmcdev}; if mmc rescan; then echo SD/MMC found on device ${mmcdev};if run loadbootscript; then run bootscript;else if run loadbootenv; then echo Loaded env from ${bootenvfile};run impor;
eth1addr=70:ff:76:1d:53:f0
eth2addr=70:ff:76:1d:53:f1
eth3addr=70:ff:76:1d:5a:52
eth4addr=70:ff:76:1d:5a:53
eth5addr=70:ff:76:1d:5a:54
eth6addr=70:ff:76:1d:5a:55
ethaddr=f4:84:4c:ed:2c:1e
fastboot.board_rev=E3
fastboot.cpu=AM654
fastboot.secure=GP
fdtaddr=0x82000000
fdtcontroladdr=fdecdb98
findfdt=setenv name_fdt k3-am654-base-board.dtb;setenv fdtfile ${name_fdt};setenv overlay_files ${name_overlays}
finduuid=part uuid mmc ${bootpart} uuid
fit_bootfile=fitImage
fit_loadaddr=0x90000000
get_fdt_mmc=load mmc ${bootpart} ${fdtaddr} ${bootdir}/${name_fdt}
get_fdt_net=tftp ${fdtaddr} ${name_fdt}
get_fdt_ubi=ubifsload ${fdtaddr} ${bootdir}/${name_fdt}
get_fit_mmc=load mmc ${bootpart} ${fit_loadaddr} ${bootdir}/${fit_bootfile}
get_kern_mmc=load mmc ${bootpart} ${loadaddr} ${bootdir}/${name_kern}
get_kern_net=tftp ${loadaddr} ${name_kern}
get_kern_ubi=ubifsload ${loadaddr} ${bootdir}/${name_kern}
get_overlay_mmc=fdt address ${fdtaddr};fdt resize 0x100000;for overlay in $overlay_files;do;load mmc ${bootpart} ${overlayaddr} ${bootdir}/${overlay};fdt apply ${overlayaddr};done;
get_overlay_net=fdt address ${fdtaddr};fdt resize 0x100000;for overlay in ${overlay_files};do;tftp ${overlayaddr} ${overlay};fdt apply ${overlayaddr}; done
get_overlaystring=for overlay in $overlay_files;do;setenv overlaystring ${overlaystring}'#'${overlay};done;
importbootenv=echo Importing environment from mmc${mmcdev} ...; env import -t ${loadaddr} ${filesize}
init_mmc=run args_all args_mmc
init_net=run args_all args_net; setenv autoload no; dhcp
init_ubi=run args_all args_ubi; sf probe; ubi part ospi.rootfs; ubifsmount ubi:rootfs;
kern_boot=net
loadaddr=0x80080000
loadbootenv=fatload mmc ${mmcdev} ${loadaddr} ${bootenvfile}
loadbootscript=load mmc ${mmcdev} ${loadaddr} boot.scr
loadfdt=load ${devtype} ${bootpart} ${fdtaddr} ${bootdir}/${fdtfile}
loadfit=run args_mmc; run run_fit;
loadimage=load ${devtype} ${bootpart} ${loadaddr} ${bootdir}/${bootfile}
mmcboot=mmc dev ${mmcdev}; setenv devnum ${mmcdev}; setenv devtype mmc; if mmc rescan; then echo SD/MMC found on device ${mmcdev};if run loadimage; then if test ${boot_fit} -eq 1; then run loadfit; else ;
mmcdev=1
mmcloados=run args_mmc; if test ${boot_fdt} = yes || test ${boot_fdt} = try; then if run loadfdt; then bootz ${loadaddr} - ${fdtaddr}; else if test ${boot_fdt} = try; then bootz; else echo WARN: Cannot l;
mmcrootfstype=ext4 rootwait
mtdids=nor0=47040000.ospi.0
mtdparts=mtdparts=47040000.ospi.0:512k(ospi.tiboot3),2m(ospi.tispl),4m(ospi.u-boot),128k(ospi.env),128k(ospi.env.backup),1m(ospi.sysfw),-@8m(ospi.rootfs)
name_kern=Image
name_overlays=k3-am654-idk.dtbo k3-am654-pcie-usb2.dtbo
nfs_options=nolock,v3,tcp,rsize=4096,wsize=4096
nfs_root=/home/lam/ti-processor-sdk-linux-am65xx-evm-06.00.00.07/targetNFS
overlayaddr=0x83000000
partitions=uuid_disk=${uuid_gpt_disk};name=rootfs,start=0,size=-,uuid=${uuid_gpt_rootfs}
partitions_android=uuid_disk=${uuid_gpt_disk};name=bootloader,start=5M,size=8M,uuid=${uuid_gpt_bootloader};name=tiboot3,start=4M,size=1M,uuid=${uuid_gpt_tiboot3};name=boot,start=13M,size=40M,uuid=${uuid_}
rd_spec=-
rootfs_boot=net
run_fit=bootm ${fit_loadaddr}#${fdtfile}${overlaystring}
run_kern=booti ${loadaddr} ${rd_spec} ${fdtaddr}
serial#=0000000000000165
serverip=192.168.230.129
setup_net=setenv autoload no; dhcp
soc=k3
stderr=serial@2800000
stdin=serial@2800000
stdout=serial@2800000
update_to_fit=setenv loadaddr ${fit_loadaddr}; setenv bootfile ${fit_bootfile}
vendor=ti

Environment size: 5880/131067 bytes
=> run bootcmd
syntax error
link up on port 1, speed 1000, full duplex
BOOTP broadcast 1
BOOTP broadcast 2
BOOTP broadcast 3
DHCP client bound to address 192.168.1.6 (1014 ms)
link up on port 1, speed 1000, full duplex
BOOTP broadcast 1
DHCP client bound to address 192.168.1.6 (4 ms)
link up on port 1, speed 1000, full duplex
Using cpsw_nuss@046000000 device
TFTP from server 192.168.1.20; our IP address is 192.168.1.6
Filename 'Image'.
Load address: 0x80080000
Loading: #################################################################
#################################################################
#################################################################
#################################################################
#################################################################
#################################################################
#################################################################
#################################################################
#################################################################
#################################################################
#################################################################
#################################################################
#################################################################
################
2.5 MiB/s
done
Bytes transferred = 12632072 (c0c008 hex)
link up on port 1, speed 1000, full duplex
Using cpsw_nuss@046000000 device
TFTP from server 192.168.1.20; our IP address is 192.168.1.6
Filename 'k3-am654-base-board.dtb'.
Load address: 0x82000000
Loading: #####
1.6 MiB/s
done
Bytes transferred = 71809 (11881 hex)
link up on port 1, speed 1000, full duplex
Using cpsw_nuss@046000000 device
TFTP from server 192.168.1.20; our IP address is 192.168.1.6
Filename 'k3-am654-idk.dtbo'.
Load address: 0x83000000
Loading: #
1.1 MiB/s
done
Bytes transferred = 11402 (2c8a hex)
link up on port 1, speed 1000, full duplex
Using cpsw_nuss@046000000 device
TFTP from server 192.168.1.20; our IP address is 192.168.1.6
Filename 'k3-am654-pcie-usb2.dtbo'.
Load address: 0x83000000
Loading: #
406.3 KiB/s
done
Bytes transferred = 2084 (824 hex)
## Flattened Device Tree blob at 82000000
Booting using the fdt blob at 0x82000000
Loading Device Tree to 00000000fddb7000, end 00000000fdecbfff ... OK

Starting kernel ...

[ 0.000000] Booting Linux on physical CPU 0x0000000000 [0x410fd034]
[ 0.000000] Linux version 4.19.38-g4dae378bbe (oe-user@oe-host) (gcc version 8.3.0 (GNU Toolchain for the A-profile Architecture 8.3-2019.03 (arm-rel-8.36))) #1 SMP PREEMPT Sun Jul 7 03:42:51 UTC 2019
[ 0.000000] Machine model: Texas Instruments AM654 Base Board
[ 0.000000] earlycon: ns16550a0 at MMIO32 0x0000000002800000 (options '')
[ 0.000000] bootconsole [ns16550a0] enabled
[ 0.000000] Reserved memory: created DMA memory pool at 0x000000009b000000, size 1 MiB
[ 0.000000] OF: reserved mem: initialized node r5f-dma-memory@9b000000, compatible id shared-dma-pool
[ 0.000000] Reserved memory: created DMA memory pool at 0x000000009b100000, size 15 MiB
[ 0.000000] OF: reserved mem: initialized node r5f-memory@9b100000, compatible id shared-dma-pool
[ 0.000000] Reserved memory: created DMA memory pool at 0x000000009c000000, size 1 MiB
[ 0.000000] OF: reserved mem: initialized node r5f-dma-memory@9c000000, compatible id shared-dma-pool
[ 0.000000] Reserved memory: created DMA memory pool at 0x000000009c100000, size 7 MiB
[ 0.000000] OF: reserved mem: initialized node r5f-memory@9c100000, compatible id shared-dma-pool
[ 0.000000] cma: Reserved 512 MiB at 0x00000000c0000000
[ 0.000000] psci: probing for conduit method from DT.
[ 0.000000] psci: PSCIv1.1 detected in firmware.
[ 0.000000] psci: Using standard PSCI v0.2 function IDs
[ 0.000000] psci: Trusted OS migration not required
[ 0.000000] psci: SMC Calling Convention v1.1
[ 0.000000] random: get_random_bytes called from start_kernel+0x94/0x3e4 with crng_init=0
[ 0.000000] percpu: Embedded 2 pages/cpu s48536 r8192 d74344 u131072
[ 0.000000] Detected VIPT I-cache on CPU0
[ 0.000000] CPU features: enabling workaround for ARM erratum 845719
[ 0.000000] Speculative Store Bypass Disable mitigation not required
[ 0.000000] CPU features: detected: Kernel page table isolation (KPTI)
[ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 64704
[ 0.000000] Kernel command line: earlycon=ns16550a,mmio32,0x02800000 mtdparts=47040000.ospi.0:512k(ospi.tiboot3),2m(ospi.tispl),4m(ospi.u-boot),128k(ospi.env),128k(ospi.env.backup),1m(ospi.sysfw),-@8m(ospi.rootfs) rootfstype=nfs root=/dev/nfs rw nfsroot=192.168.1.20:/home/lam/ti-processor-sdk-linux-am65xx-evm-06.00.00.07/targetNFS,nolock,v3,tcp,rsize=4096,wsize=4096 ip=dhcp
[ 0.000000] Dentry cache hash table entries: 524288 (order: 6, 4194304 bytes)
[ 0.000000] Inode-cache hash table entries: 262144 (order: 5, 2097152 bytes)
[ 0.000000] software IO TLB: mapped [mem 0xf9db0000-0xfddb0000] (64MB)
[ 0.000000] Memory: 3529856K/4145152K available (8062K kernel code, 752K rwdata, 2880K rodata, 576K init, 614K bss, 91008K reserved, 524288K cma-reserved)
[ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1
[ 0.000000] rcu: Preemptible hierarchical RCU implementation.
[ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=64 to nr_cpu_ids=4.
[ 0.000000] Tasks RCU enabled.
[ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4
[ 0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0
[ 0.000000] GICv3: GIC: Using split EOI/Deactivate mode
[ 0.000000] GICv3: Distributor has no Range Selector support
[ 0.000000] GICv3: no VLPI support, no direct LPI support
[ 0.000000] ITS [mem 0x01820000-0x0182ffff]
[ 0.000000] GIC: enabling workaround for ITS: Socionext Synquacer pre-ITS
[ 0.000000] ITS@0x0000000001820000: allocated 1048576 Devices @8c0800000 (flat, esz 8, psz 64K, shr 0)
[ 0.000000] ITS: using cache flushing for cmd queue
[ 0.000000] GIC: using LPI property table @0x00000008c00c0000
[ 0.000000] GICv3: CPU0: found redistributor 0 region 0:0x0000000001880000
[ 0.000000] CPU0: using LPI pending table @0x00000008c00d0000
[ 0.000000] GIC: using cache flushing for LPI property table
[ 0.000000] arch_timer: cp15 timer(s) running at 200.00MHz (phys).
[ 0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x2e2049d3e8, max_idle_ns: 440795210634 ns
[ 0.000006] sched_clock: 56 bits at 200MHz, resolution 5ns, wraps every 4398046511102ns
[ 0.008700] Console: colour dummy device 80x25
[ 0.013254] console [tty0] enabled
[ 0.016737] bootconsole [ns16550a0] disabled
I/TC: Dynamic shared memory is disabled
[ 0.000000] Booting Linux on physical CPU 0x0000000000 [0x410fd034]
[ 0.000000] Linux version 4.19.38-g4dae378bbe (oe-user@oe-host) (gcc version 8.3.0 (GNU Toolchain for the A-profile Architecture 8.3-2019.03 (arm-rel-8.36))) #1 SMP PREEMPT Sun Jul 7 03:42:51 UTC 2019
[ 0.000000] Machine model: Texas Instruments AM654 Base Board
[ 0.000000] earlycon: ns16550a0 at MMIO32 0x0000000002800000 (options '')
[ 0.000000] bootconsole [ns16550a0] enabled
[ 0.000000] Reserved memory: created DMA memory pool at 0x000000009b000000, size 1 MiB
[ 0.000000] OF: reserved mem: initialized node r5f-dma-memory@9b000000, compatible id shared-dma-pool
[ 0.000000] Reserved memory: created DMA memory pool at 0x000000009b100000, size 15 MiB
[ 0.000000] OF: reserved mem: initialized node r5f-memory@9b100000, compatible id shared-dma-pool
[ 0.000000] Reserved memory: created DMA memory pool at 0x000000009c000000, size 1 MiB
[ 0.000000] OF: reserved mem: initialized node r5f-dma-memory@9c000000, compatible id shared-dma-pool
[ 0.000000] Reserved memory: created DMA memory pool at 0x000000009c100000, size 7 MiB
[ 0.000000] OF: reserved mem: initialized node r5f-memory@9c100000, compatible id shared-dma-pool
[ 0.000000] cma: Reserved 512 MiB at 0x00000000c0000000
[ 0.000000] psci: probing for conduit method from DT.
[ 0.000000] psci: PSCIv1.1 detected in firmware.
[ 0.000000] psci: Using standard PSCI v0.2 function IDs
[ 0.000000] psci: Trusted OS migration not required
[ 0.000000] psci: SMC Calling Convention v1.1
[ 0.000000] random: get_random_bytes called from start_kernel+0x94/0x3e4 with crng_init=0
[ 0.000000] percpu: Embedded 2 pages/cpu s48536 r8192 d74344 u131072
[ 0.000000] Detected VIPT I-cache on CPU0
[ 0.000000] CPU features: enabling workaround for ARM erratum 845719
[ 0.000000] Speculative Store Bypass Disable mitigation not required
[ 0.000000] CPU features: detected: Kernel page table isolation (KPTI)
[ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 64704
[ 0.000000] Kernel command line: earlycon=ns16550a,mmio32,0x02800000 mtdparts=47040000.ospi.0:512k(ospi.tiboot3),2m(ospi.tispl),4m(ospi.u-boot),128k(ospi.env),128k(ospi.env.backup),1m(ospi.sysfw),-@8m(ospi.rootfs) rootfstype=nfs root=/dev/nfs rw nfsroot=192.168.1.20:/home/lam/ti-processor-sdk-linux-am65xx-evm-06.00.00.07/targetNFS,nolock,v3,tcp,rsize=4096,wsize=4096 ip=dhcp
[ 0.000000] Dentry cache hash table entries: 524288 (order: 6, 4194304 bytes)
[ 0.000000] Inode-cache hash table entries: 262144 (order: 5, 2097152 bytes)
[ 0.000000] software IO TLB: mapped [mem 0xf9db0000-0xfddb0000] (64MB)
[ 0.000000] Memory: 3529856K/4145152K available (8062K kernel code, 752K rwdata, 2880K rodata, 576K init, 614K bss, 91008K reserved, 524288K cma-reserved)
[ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1
[ 0.000000] rcu: Preemptible hierarchical RCU implementation.
[ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=64 to nr_cpu_ids=4.
[ 0.000000] Tasks RCU enabled.
[ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4
[ 0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0
[ 0.000000] GICv3: GIC: Using split EOI/Deactivate mode
[ 0.000000] GICv3: Distributor has no Range Selector support
[ 0.000000] GICv3: no VLPI support, no direct LPI support
[ 0.000000] ITS [mem 0x01820000-0x0182ffff]
[ 0.000000] GIC: enabling workaround for ITS: Socionext Synquacer pre-ITS
[ 0.000000] ITS@0x0000000001820000: allocated 1048576 Devices @8c0800000 (flat, esz 8, psz 64K, shr 0)
[ 0.000000] ITS: using cache flushing for cmd queue
[ 0.000000] GIC: using LPI property table @0x00000008c00c0000
[ 0.000000] GICv3: CPU0: found redistributor 0 region 0:0x0000000001880000
[ 0.000000] CPU0: using LPI pending table @0x00000008c00d0000
[ 0.000000] GIC: using cache flushing for LPI property table
[ 0.000000] arch_timer: cp15 timer(s) running at 200.00MHz (phys).
[ 0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x2e2049d3e8, max_idle_ns: 440795210634 ns
[ 0.000006] sched_clock: 56 bits at 200MHz, resolution 5ns, wraps every 4398046511102ns
[ 0.008700] Console: colour dummy device 80x25
[ 0.013254] console [tty0] enabled
[ 0.016737] bootconsole [ns16550a0] disabled
[ 0.021180] Calibrating delay loop (skipped), value calculated using timer frequency.. 400.00 BogoMIPS (lpj=800000)
[ 0.021208] pid_max: default: 32768 minimum: 301
[ 0.021323] Security Framework initialized
[ 0.021402] Mount-cache hash table entries: 8192 (order: 0, 65536 bytes)
[ 0.021438] Mountpoint-cache hash table entries: 8192 (order: 0, 65536 bytes)
[ 0.045247] ASID allocator initialised with 32768 entries
[ 0.053238] rcu: Hierarchical SRCU implementation.
[ 0.061394] Platform MSI: gic-its@18200000 domain created
[ 0.061712] PCI/MSI: /interconnect@100000/interrupt-controller@1800000/gic-its@18200000 domain created
[ 0.069296] smp: Bringing up secondary CPUs ...
[ 0.101777] Detected VIPT I-cache on CPU1
[ 0.101815] GICv3: CPU1: found redistributor 1 region 0:0x00000000018a0000
[ 0.101873] CPU1: using LPI pending table @0x00000008c0330000
[ 0.101920] CPU1: Booted secondary processor 0x0000000001 [0x410fd034]
[ 0.163464] Detected VIPT I-cache on CPU2
[ 0.163507] GICv3: CPU2: found redistributor 100 region 0:0x00000000018c0000
[ 0.163568] CPU2: using LPI pending table @0x00000008c03c0000
[ 0.163619] CPU2: Booted secondary processor 0x0000000100 [0x410fd034]
[ 0.195881] Detected VIPT I-cache on CPU3
[ 0.195911] GICv3: CPU3: found redistributor 101 region 0:0x00000000018e0000
[ 0.195971] CPU3: using LPI pending table @0x00000008c0450000
[ 0.196005] CPU3: Booted secondary processor 0x0000000101 [0x410fd034]
[ 0.196151] smp: Brought up 1 node, 4 CPUs
[ 0.196309] SMP: Total of 4 processors activated.
[ 0.196327] CPU features: detected: GIC system register CPU interface
[ 0.196345] CPU features: detected: 32-bit EL0 Support
[ 0.218916] CPU: All CPU(s) started at EL2
[ 0.218957] alternatives: patching kernel code
[ 0.220429] devtmpfs: initialized
[ 0.232245] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns
[ 0.232293] futex hash table entries: 1024 (order: 0, 65536 bytes)
[ 0.233703] pinctrl core: initialized pinctrl subsystem
[ 0.234341] NET: Registered protocol family 16
[ 0.235643] vdso: 2 pages (1 code @ (____ptrval____), 1 data @ (____ptrval____))
[ 0.235674] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers.
[ 0.242129] DMA: preallocated 256 KiB pool for atomic allocations
[ 0.244774] davinci_gpio 42110000.wkup_gpio0: IRQ not populated, err = -517
[ 0.251954] davinci_gpio 600000.main_gpio0: IRQ not populated, err = -517
[ 0.252223] davinci_gpio 601000.main_gpio1: IRQ not populated, err = -517
[ 0.263290] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages
[ 0.263325] HugeTLB registered 512 MiB page size, pre-allocated 0 pages
[ 0.264769] cryptd: max_cpu_qlen set to 1000
[ 0.266721] media: Linux media interface: v0.10
[ 0.266779] videodev: Linux video capture interface: v2.00
[ 0.266836] pps_core: LinuxPPS API ver. 1 registered
[ 0.266852] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>
[ 0.266886] PTP clock support registered
[ 0.266923] EDAC MC: Ver: 3.0.0
[ 0.268235] Advanced Linux Sound Architecture Driver Initialized.
[ 0.269149] clocksource: Switched to clocksource arch_sys_counter
[ 0.269341] VFS: Disk quotas dquot_6.6.0
[ 0.269441] VFS: Dquot-cache hash table entries: 8192 (order 0, 65536 bytes)
[ 0.276397] NET: Registered protocol family 2
[ 0.277101] tcp_listen_portaddr_hash hash table entries: 4096 (order: 0, 65536 bytes)
[ 0.277270] TCP established hash table entries: 32768 (order: 2, 262144 bytes)
[ 0.277591] TCP bind hash table entries: 32768 (order: 3, 524288 bytes)
[ 0.278233] TCP: Hash tables configured (established 32768 bind 32768)
[ 0.278399] UDP hash table entries: 2048 (order: 0, 65536 bytes)
[ 0.278526] UDP-Lite hash table entries: 2048 (order: 0, 65536 bytes)
[ 0.278824] NET: Registered protocol family 1
[ 0.279462] RPC: Registered named UNIX socket transport module.
[ 0.279486] RPC: Registered udp transport module.
[ 0.279500] RPC: Registered tcp transport module.
[ 0.279515] RPC: Registered tcp NFSv4.1 backchannel transport module.
[ 0.280612] hw perfevents: enabled with armv8_pmuv3 PMU driver, 7 counters available
[ 0.285635] Initialise system trusted keyrings
[ 0.285837] workingset: timestamp_bits=46 max_order=16 bucket_order=0
[ 0.291177] squashfs: version 4.0 (2009/01/31) Phillip Lougher
[ 0.292046] NFS: Registering the id_resolver key type
[ 0.292083] Key type id_resolver registered
[ 0.292098] Key type id_legacy registered
[ 0.292119] nfs4filelayout_init: NFSv4 File Layout Driver Registering...
[ 0.292302] 9p: Installing v9fs 9p2000 file system support
[ 0.296955] Key type asymmetric registered
[ 0.296984] Asymmetric key parser 'x509' registered
[ 0.297061] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 245)
[ 0.297085] io scheduler noop registered
[ 0.297100] io scheduler deadline registered
[ 0.297301] io scheduler cfq registered (default)
[ 0.297321] io scheduler mq-deadline registered
[ 0.297337] io scheduler kyber registered
[ 0.299095] pinctrl-single 4301c000.pinmux: 70 pins, size 280
[ 0.299804] pinctrl-single 11c000.pinmux: 185 pins, size 740
[ 0.299956] pinctrl-single 11c2e8.pinmux: 9 pins, size 36
[ 0.301282] pinctrl-single a40000.timesync_router: 512 pins, size 2048
[ 0.310079] Serial: 8250/16550 driver, 10 ports, IRQ sharing enabled
[ 0.326747] brd: module loaded
[ 0.334069] loop: module loaded
[ 0.336472] libphy: Fixed MDIO Bus: probed
[ 0.337038] tun: Universal TUN/TAP device driver, 1.6
[ 0.337899] igbvf: Intel(R) Gigabit Virtual Function Network Driver - version 2.4.0-k
[ 0.337924] igbvf: Copyright (c) 2009 - 2012 Intel Corporation.
[ 0.337986] sky2: driver version 1.30
[ 0.339447] VFIO - User Level meta-driver version: 0.3
[ 0.340306] i2c /dev entries driver
[ 0.341761] sdhci: Secure Digital Host Controller Interface driver
[ 0.341780] sdhci: Copyright(c) Pierre Ossman
[ 0.342254] sdhci-pltfm: SDHCI platform and OF driver helper
[ 0.343611] ledtrig-cpu: registered to indicate activity on CPUs
[ 0.343725] ti-sci interconnect@100000:interconnect@28380000:interconnect@42040000:dmsc: invalid resource
[ 0.344796] optee: probing for conduit method from DT.
[ 0.344837] optee: revision 3.4 (251f7c63)
[ 0.348793] optee: initialized driver
[ 0.350294] NET: Registered protocol family 17
[ 0.350392] 9pnet: Installing 9P2000 support
[ 0.350464] Key type dns_resolver registered
[ 0.351145] registered taskstats version 1
[ 0.351161] Loading compiled-in X.509 certificates
[ 0.352208] davinci_gpio 42110000.wkup_gpio0: IRQ not populated, err = -517
[ 0.352647] davinci_gpio 600000.main_gpio0: IRQ not populated, err = -517
[ 0.352904] davinci_gpio 601000.main_gpio1: IRQ not populated, err = -517
[ 0.363126] ti-sci interconnect@100000:interconnect@28380000:interconnect@42040000:dmsc: invalid resource
[ 0.363347] ti-sci interconnect@100000:interconnect@28380000:interconnect@42040000:dmsc: ABI: 2.6 (firmware rev 0x0013 '19.4.1-v2019.04a (Curious Crow)')
[ 0.381967] random: fast init done
[ 0.875750] davinci_gpio 42110000.wkup_gpio0: IRQ not populated, err = -517
[ 0.876167] davinci_gpio 600000.main_gpio0: IRQ not populated, err = -517
[ 0.876428] davinci_gpio 601000.main_gpio1: IRQ not populated, err = -517
[ 0.877871] omap_i2c 42120000.i2c: bus 0 rev0.12 at 400 kHz
[ 0.878651] omap_i2c 40b00000.i2c: bus 1 rev0.12 at 100 kHz
[ 0.902566] omap_i2c 2000000.i2c: bus 2 rev0.12 at 400 kHz
[ 0.903296] omap_i2c 2010000.i2c: bus 3 rev0.12 at 400 kHz
[ 0.903998] omap_i2c 2020000.i2c: bus 4 rev0.12 at 400 kHz
[ 0.904665] omap_i2c 2030000.i2c: bus 5 rev0.12 at 100 kHz
[ 0.913411] keystone-pcie 5500000.pcie: Linked as a consumer to phy-900000.serdes.0
[ 0.913460] keystone-pcie 5500000.pcie: Linked as a consumer to phy-910000.serdes.1
[ 0.918945] keystone-pcie 5500000.pcie: host bridge /interconnect@100000/pcie@5500000 ranges:
[ 0.918991] keystone-pcie 5500000.pcie: IO 0x10020000..0x1002ffff -> 0x00000000
[ 0.919026] keystone-pcie 5500000.pcie: MEM 0x10030000..0x17ffffff -> 0x10030000
[ 1.919375] keystone-pcie 5500000.pcie: Phy link never came up
[ 1.919523] keystone-pcie 5500000.pcie: PCI host bridge to bus 0000:00
[ 1.919550] pci_bus 0000:00: root bus resource [bus 00-ff]
[ 1.919570] pci_bus 0000:00: root bus resource [io 0x0000-0xffff]
[ 1.919590] pci_bus 0000:00: root bus resource [mem 0x10030000-0x17ffffff]
[ 1.928448] pci 0000:00:00.0: BAR 6: assigned [mem 0x10030000-0x1003ffff pref]
[ 1.928483] pci 0000:00:00.0: PCI bridge to [bus 01-ff]
[ 1.929641] k3-ringacc 2b800000.ringacc: Ring Accelerator probed rings:286, gp-rings[96,160] sci-dev-id:195
[ 1.929704] k3-ringacc 2b800000.ringacc: dma-ring-reset-quirk: enabled
[ 1.929726] k3-ringacc 2b800000.ringacc: RA Proxy rev. 66344100, num_proxies:64
[ 1.930135] k3-ringacc 3c000000.ringacc: Ring Accelerator probed rings:818, gp-rings[304,464] sci-dev-id:187
[ 1.930164] k3-ringacc 3c000000.ringacc: dma-ring-reset-quirk: enabled
[ 1.930184] k3-ringacc 3c000000.ringacc: RA Proxy rev. 66344100, num_proxies:64
[ 1.930524] omap8250 40a00000.serial: PM domain ti_sci_pd will not be powered off
[ 1.930970] 40a00000.serial: ttyS1 at MMIO 0x40a00000 (irq = 8, base_baud = 6000000) is a 8250
[ 1.931882] 2800000.serial: ttyS2 at MMIO 0x2800000 (irq = 22, base_baud = 3000000) is a 8250
[ 3.171355] console [ttyS2] enabled
[ 3.175651] 2810000.serial: ttyS3 at MMIO 0x2810000 (irq = 23, base_baud = 3000000) is a 8250
[ 3.184945] 2820000.serial: ttyS4 at MMIO 0x2820000 (irq = 24, base_baud = 3000000) is a 8250
[ 3.195996] cadence-qspi 47040000.spi: mt35xu512aba (65536 Kbytes)
[ 3.217408] cadence-qspi 47040000.spi: Cadence QSPI NOR probe failed -517
[ 3.232107] am65-cpsw-nuss 46000000.ethernet: rx-flow-id-base is not set -22
[ 3.239303] am65-cpsw-nuss 46000000.ethernet: initializing am65 cpsw nuss version 0x6BA07100, cpsw version 0x6BA80100 Ports: 2
[ 3.250846] UDMA has not been proped
[ 3.254550] am65-cpsw-nuss 46000000.ethernet: Failed to request tx dma channel -517
[ 3.264689] am65-cpts 310d0000.cpts: CPTS ver 0x4e8a2109, freq:200000000, add_val:4 pps:0
[ 3.273495] thermal thermal_zone1: failed to read out thermal zone (-22)
[ 3.280231] thermal thermal_zone2: failed to read out thermal zone (-22)
[ 3.287709] OF: /interconnect@100000/interconnect@28380000/rti@40610000: arguments longer than property
[ 3.297148] keystone-wdt 40610000.rti: heartbeat 60 sec
[ 3.303327] mmc0: Unknown controller version (4). You may experience problems.
[ 3.341766] mmc0: SDHCI controller on 4f80000.sdhci [4f80000.sdhci] using ADMA 64-bit
[ 3.350442] mmc1: Unknown controller version (4). You may experience problems.
[ 3.388879] mmc1: SDHCI controller on 4fa0000.sdhci [4fa0000.sdhci] using ADMA 64-bit
[ 3.404506] mmc0: new HS200 MMC card at address 0001
[ 3.410665] mmcblk0: mmc0:0001 R1J56L 14.7 GiB
[ 3.417457] mmcblk0boot0: mmc0:0001 R1J56L partition 1 8.00 MiB
[ 3.427333] mmcblk0boot1: mmc0:0001 R1J56L partition 2 8.00 MiB
[ 3.433466] mmcblk0rpmb: mmc0:0001 R1J56L partition 3 4.00 MiB, chardev (243:0)
[ 3.443395] mmcblk0: p1
[ 3.450846] omap-mailbox 31f80000.mailbox: omap mailbox rev 0x66fc5900
[ 3.457959] omap-mailbox 31f81000.mailbox: omap mailbox rev 0x66fc5900
[ 3.465175] mmc1: new high speed SDHC card at address 1234
[ 3.471823] mmcblk1: mmc1:1234 SA08G 7.21 GiB
[ 3.476954] ti-udma 285c0000.udmap: Channels: 96 (tchan: 48, echan: 0, rchan: 48, rflow: 96)
[ 3.485499] mmcblk1: p1 p2
[ 3.495469] ti-udma 31150000.udmap: Channels: 267 (tchan: 120, echan: 32, rchan: 150, rflow: 300)
[ 3.520480] cadence-qspi 47040000.spi: mt35xu512aba (65536 Kbytes)
[ 3.534501] m25p80 spi7.0: n25q128a13 (16384 Kbytes)
[ 3.540844] am65-cpsw-nuss 46000000.ethernet: rx-flow-id-base is not set -22
[ 3.548029] am65-cpsw-nuss 46000000.ethernet: initializing am65 cpsw nuss version 0x6BA07100, cpsw version 0x6BA80100 Ports: 2
[ 3.568327] am65-cpsw-nuss 46000000.ethernet: set new flow-id-base 48
[ 3.581799] pps pps0: new PPS source ptp1
[ 3.586021] am65-cpsw-nuss 46000000.ethernet: CPTS ver 0x4e8a2109, freq:200000000, add_val:4 pps:1
[ 3.595016] am65-cpsw-nuss 46000000.ethernet: initialized cpsw ale version 0.4
[ 3.602250] am65-cpsw-nuss 46000000.ethernet: ALE Table size 64
[ 3.645164] am65-cpsw-nuss 46000000.ethernet: davinci mdio revision 9.7, bus freq 1000000
[ 3.653357] libphy: 46000000.ethernet: probed
[ 3.660718] input: gpio-keys as /devices/platform/gpio-keys/input/input0
[ 3.667716] hctosys: unable to open rtc device (rtc0)
[ 3.684645] TI DP83867 k3-cpsw-mdio:00: attached PHY driver [TI DP83867] (mii_bus:phy_addr=k3-cpsw-mdio:00, irq=POLL)
[ 4.726266] am65-cpsw-nuss 46000000.ethernet eth0: Link is Down
[ 7.798459] am65-cpsw-nuss 46000000.ethernet eth0: Link is Up - 1Gbps/Full - flow control off
[ 7.829156] Sending DHCP requests ., OK
[ 7.849019] IP-Config: Got DHCP answer from 192.168.1.20, my address is 192.168.1.6
[ 7.856695] IP-Config: Complete:
[ 7.859936] device=eth0, hwaddr=f4:84:4c:ed:2c:1e, ipaddr=192.168.1.6, mask=255.255.255.0, gw=255.255.255.255
[ 7.870294] host=192.168.1.6, domain=example.org, nis-domain=(none)
[ 7.877003] bootserver=192.168.1.20, rootserver=192.168.1.20, rootpath=
[ 7.877007] nameserver0=192.168.1.20
[ 7.979033] ALSA device list:
[ 7.982035] No soundcards found.
[ 7.995785] VFS: Mounted root (nfs filesystem) on device 0:17.
[ 8.002166] devtmpfs: mounted
[ 8.005411] Freeing unused kernel memory: 576K
[ 8.009916] Run /sbin/init as init process
[ 8.478837] systemd[1]: System time before build time, advancing clock.
[ 8.625768] NET: Registered protocol family 10
[ 8.632175] Segment Routing with IPv6
[ 8.660640] systemd[1]: Configuration file /etc/systemd/system.conf is marked executable. Please remove executable permission bits. Proceeding anyway.
[ 8.674173] systemd[1]: Configuration file /etc/systemd/system.conf is marked world-writable. Please remove world writability permission bits. Proceeding anyway.
[ 8.690690] systemd[1]: systemd 239 running in system mode. (+PAM -AUDIT -SELINUX +IMA -APPARMOR +SMACK +SYSVINIT +UTMP -LIBCRYPTSETUP -GCRYPT -GNUTLS +ACL +XZ -LZ4 -SECCOMP +BLKID -ELFUTILS +KMOD -IDN2 -IDN -PCRE2 default-hierarchy=hybrid)
[ 8.712475] systemd[1]: Detected architecture arm64.

Welcome to Arago 2019.05!

[ 8.764956] systemd[1]: Set hostname to <am65xx-evm>.
[ 9.014602] systemd[1]: Configuration file /lib/systemd/system/local-fs.target is marked executable. Please remove executable permission bits. Proceeding anyway.
[ 9.029148] systemd[1]: Configuration file /lib/systemd/system/local-fs.target is marked world-writable. Please remove world writability permission bits. Proceeding anyway.
[ 9.060733] systemd[1]: Configuration file /lib/systemd/system/swap.target is marked executable. Please remove executable permission bits. Proceeding anyway.
[ 9.074872] systemd[1]: Configuration file /lib/systemd/system/swap.target is marked world-writable. Please remove world writability permission bits. Proceeding anyway.
[ 9.093696] systemd[1]: Configuration file /lib/systemd/system/umount.target is marked executable. Please remove executable permission bits. Proceeding anyway.
[ 9.107993] systemd[1]: Configuration file /lib/systemd/system/umount.target is marked world-writable. Please remove world writability permission bits. Proceeding anyway.
[ 9.126865] systemd[1]: Configuration file /lib/systemd/system/systemd-journald.socket is marked executable. Please remove executable permission bits. Proceeding anyway.
[ 9.553778] random: systemd: uninitialized urandom read (16 bytes read)
[ OK ] Listening on Journal Socket.
[ 9.577353] random: systemd: uninitialized urandom read (16 bytes read)
[ OK ] Created slice User and Session Slice.
[ 9.597353] random: systemd: uninitialized urandom read (16 bytes read)
[ OK ] Started Forward Password Requests to Wall Directory Watch.
[ OK ] Created slice system-getty.slice.
Starting Load Kernel Modules...
[ OK ] Started Dispatch Password Requests to Console Directory Watch.
Mounting POSIX Message Queue File System...
[ 9.684444] cryptodev: loading out-of-tree module taints kernel.
[ OK ] Reached target Remote File Systems.
[ 9.696466] cryptodev: driver 1.9 loaded.
[ OK ] Listening on udev Kernel Socket.
[ OK ] Listening on Process Core Dump Socket.
Starting Create list of required st…ce nodes for the [ 9.782662] random: crng init done
[ 9.788328] random: 7 urandom warning(s) missed due to ratelimiting
current kernel...
Starting Remount Root and Kernel File Systems...
[ OK ] Listening on Network Service Netlink Socket.
[ OK ] Reached target Paths.
[ OK ] Listening on Journal Socket (/dev/log).
Starting Journal Service...
[ OK ] Reached target Slices.
[ OK ] Started Hardware RNG Entropy Gatherer Daemon.
[ 9.924992] systemd-journald[168]: Configuration file /etc/systemd/journald.conf is marked executable. Please remove executable permission bits. Proceeding anyway.
[ 9.939735] systemd-journald[168]: Configuration file /etc/systemd/journald.conf is marked world-writable. Please remove world writability permission bits. Proceeding anyway.
Mounting Huge Pages File System...
Mounting Kernel Debug File System...
[ OK ] Listening on udev Control Socket.
Starting udev Coldplug all Devices...
[ OK ] Listening on initctl Compatibility Named Pipe.
[ OK ] Reached target Swap.
Mounting Temporary Directory (/tmp)...
[ OK ] Created slice system-serial\x2dgetty.slice.
[ OK ] Started Journal Service.
[ OK ] Started Load Kernel Modules.
[ OK ] Mounted POSIX Message Queue File System.
[ OK ] Started Create list of required sta…vice nodes for the current kernel.
[ OK ] Started Remount Root and Kernel File Systems.
[ OK ] Mounted Huge Pages File System.
[ OK ] Mounted Kernel Debug File System.
[ OK ] Mounted Temporary Directory (/tmp).
Starting Create Static Device Nodes in /dev...
Starting Apply Kernel Variables...
Mounting Kernel Configuration File System...
Starting Flush Journal to Persistent Storage...
[ OK ] Mounted Kernel Configuration File System.
[ 10.339029] systemd-journald[168]: Received request to flush runtime journal from PID 1
[ OK ] Started Flush Journal to Persistent Storage.
[ OK ] Started Apply Kernel Variables.
[ OK ] Started Create Static Device Nodes in /dev.
Starting udev Kernel Device Manager...
[ OK ] Reached target Local File Systems (Pre).
Mounting /media/ram...
[ OK ] Reached target Containers.
Mounting /var/volatile...
[ OK ] Mounted /media/ram.
[ OK ] Mounted /var/volatile.
Starting Load/Save Random Seed...
[ OK ] Reached target Local File Systems.
Starting Create Volatile Files and Directories...
[ OK ] Started Load/Save Random Seed.
[ OK ] Started udev Kernel Device Manager.
[ OK ] Started udev Coldplug all Devices.
[ OK ] Started Create Volatile Files and Directories.
Starting Update UTMP about System Boot/Shutdown...
[ 10.949228] input: gpio-decoder as /devices/platform/gpio-decoder/input/input1
Starting Network Service...
Starting Network Time Synchronization...
[ OK ] Started Update UTMP about System Boot/Shutdown.
[ 11.571373] omap_rng 4e10000.trng: Random Number Generator ver. 230b34c
[ OK ] Found device /dev/ttyS2.
[ 11.914900] CAN device driver interface
[ 11.928279] [drm] Initialized pvr 1.17.4948957 20110701 for 7000000.gpu on minor 0
[ 11.971188] m_can 40528000.mcan: m_can device registered (irq=17, version=32)
[ 12.000364] m_can 40568000.mcan: m_can device registered (irq=19, version=32)
[ OK ] Started Network Service.
[ 12.091736] k3_r5_rproc interconnect@100000:interconnect@28380000:r5fss@41000000: creating child devices for R5F cores
[ 12.118546] platform 41000000.r5f: assigned reserved memory node r5f-dma-memory@9c000000
[ 12.140333] remoteproc remoteproc0: 41000000.r5f is available
Starting Network Name Resolution...
[ OK ] Started Network Time Synchronization.
[ OK ] Reached target System Time Synchronized.
[ OK ] Reached target System Initialization.
[ OK ] Listening on D-Bus System Message Bus Socket.
[ OK ] Listening on RPCbind Server Activation Socket.
[ OK ] Started Daily Cleanup of Temporary Directories.
Starting Reboot and dump vmcore via kexec...
[ 12.515442] remoteproc remoteproc0: powering up 41000000.r5f
[ 12.521657] remoteproc remoteproc0: Booting fw image am65x-mcu-r5f0_0-fw, size 4050720
[ 12.536599] platform 41000000.r5f: booting R5F core using boot addr = 0x0
[ 12.546295] remoteproc remoteproc0: registered virtio0 (type 7)
[ 12.554754] remoteproc remoteproc0: remote processor 41000000.r5f is now up
[ OK ] Listening on dropbear.socket.
[ OK ] Reached target Sockets.
[ OK ] Reached target Basic System.
[ OK ] Started Job spooling tools.
Starting Print notice about GPLv3 packages...
Starting Login Service...
Starting rng-tools.service...
Starting TI IPC Daemon...
[ OK ] Started strongSwan IPsec IKEv1/IKEv2 daemon using ipsec.conf.
[ OK ] Started Periodic Command Scheduler.
[ OK ] Started irqbalance daemon.
[ OK ] Started Daily rotation of log files.
[ OK ] Reached target Timers.
[ OK ] Started D-Bus System Message Bus.
Starting RPC Bind Service...
[ OK ] Started Reboot and dump vmcore via kexec.
[ OK ] Started Network Name Resolution.
[ OK ] Started rng-tools.service.
[ OK ] Started TI IPC Daemon.
[ OK ] Started RPC Bind Service.
[ 13.401337] davinci_mdio b032400.mdio: davinci mdio revision 1.7, bus freq 1000000
[ 13.410241] libphy: b032400.mdio: probed
[ 13.424953] NET: Registered protocol family 15
[ 13.588826] davinci_mdio b032400.mdio: phy[0]: device b032400.mdio:00, driver TI DP83867
[ 13.608901] davinci_mdio b032400.mdio: phy[3]: device b032400.mdio:03, driver TI DP83867
[ 13.677331] davinci_mdio b132400.mdio: davinci mdio revision 1.7, bus freq 1000000
[ 13.685095] libphy: b132400.mdio: probed
[ 13.819741] davinci_mdio b132400.mdio: phy[0]: device b132400.mdio:00, driver TI DP83867
[ 13.830370] davinci_mdio b132400.mdio: phy[3]: device b132400.mdio:03, driver TI DP83867
[ 13.901321] davinci_mdio b232400.mdio: davinci mdio revision 1.7, bus freq 1000000
[ 13.909125] libphy: b232400.mdio: probed
[ 14.084755] davinci_mdio b232400.mdio: phy[0]: device b232400.mdio:00, driver TI DP83867
[ 14.093264] davinci_mdio b232400.mdio: phy[3]: device b232400.mdio:03, driver TI DP83867
[ 14.158039] remoteproc remoteproc1: b034000.pru is available
[ 14.166016] pru-rproc b034000.pru: PRU rproc node pru@b034000 probed successfully
[ 14.175990] remoteproc remoteproc2: b004000.rtu is available
[ 14.185297] pru-rproc b004000.rtu: PRU rproc node rtu@b004000 probed successfully
[ 14.199381] remoteproc remoteproc3: b038000.pru is available
[ 14.205408] pru-rproc b038000.pru: PRU rproc node pru@b038000 probed successfully
[ 14.213790] remoteproc remoteproc4: b006000.rtu is available
[ 14.219970] pru-rproc b006000.rtu: PRU rproc node rtu@b006000 probed successfully
[ 14.228424] remoteproc remoteproc5: b134000.pru is available
[ 14.234737] pru-rproc b134000.pru: PRU rproc node pru@b134000 probed successfully
[ 14.250952] remoteproc remoteproc6: b104000.rtu is available
[ 14.257090] pru-rproc b104000.rtu: PRU rproc node rtu@b104000 probed successfully
[ 14.265740] remoteproc remoteproc7: b138000.pru is available
[ 14.271927] pru-rproc b138000.pru: PRU rproc node pru@b138000 probed successfully
[ 14.280361] remoteproc remoteproc8: b106000.rtu is available
[ 14.286732] pru-rproc b106000.rtu: PRU rproc node rtu@b106000 probed successfully
[ 14.295618] remoteproc remoteproc9: b234000.pru is available
[ 14.302004] pru-rproc b234000.pru: PRU rproc node pru@b234000 probed successfully
[ 14.310826] remoteproc remoteproc10: b204000.rtu is available
[ 14.317302] pru-rproc b204000.rtu: PRU rproc node rtu@b204000 probed successfully
[ 14.326572] remoteproc remoteproc11: b238000.pru is available
[ 14.334261] pru-rproc b238000.pru: PRU rproc node pru@b238000 probed successfully
[ 14.345239] remoteproc remoteproc12: b206000.rtu is available
[ 14.351312] pru-rproc b206000.rtu: PRU rproc node rtu@b206000 probed successfully
[ 14.474232] Initializing XFRM netlink socket
[ 14.504529] icssg-prueth pruss1_eth: TI PRU ethernet initialized: dual EMAC mode, dual_icssg 0
[ 14.566252] icssg-prueth pruss0_eth: TI PRU ethernet initialized: dual EMAC mode, dual_icssg 0
[ 14.627503] icssg-prueth pruss2_eth: TI PRU ethernet initialized: dual EMAC mode, dual_icssg 0
[ 15.007455] dwc3 4010000.usb: Failed to get clk 'ref': -2
[ 15.018156] dwc3 4030000.usb: Failed to get clk 'ref': -2
[ 15.024488] OF: graph: no port node found in /interconnect@100000/phy@4110000
[ 15.239791] virtio_rpmsg_bus virtio0: rpmsg host is online
[ 15.240698] virtio_rpmsg_bus virtio0: creating channel rpmsg-proto addr 0x3d
[ 15.704333] NET: Registered protocol family 45
[ 15.832628] usbcore: registered new interface driver usbfs
[ 15.839745] usbcore: registered new interface driver hub
[ 15.845778] usbcore: registered new device driver usb
[ 15.940159] xhci-hcd xhci-hcd.2.auto: xHCI Host Controller
[ 15.945971] xhci-hcd xhci-hcd.2.auto: new USB bus registered, assigned bus number 1
[ 15.983327] xhci-hcd xhci-hcd.2.auto: hcc params 0x0298fe6d hci version 0x110 quirks 0x0000000000010010
[ 15.993067] xhci-hcd xhci-hcd.2.auto: irq 309, io mem 0x04010000
[ 16.000212] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 4.19
[ 16.008564] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[ 16.015849] usb usb1: Product: xHCI Host Controller
[ 16.020805] usb usb1: Manufacturer: Linux 4.19.38-g4dae378bbe xhci-hcd
[ 16.027402] usb usb1: SerialNumber: xhci-hcd.2.auto
[ 16.033002] hub 1-0:1.0: USB hub found
[ 16.036912] hub 1-0:1.0: 1 port detected
[ 16.041413] xhci-hcd xhci-hcd.2.auto: xHCI Host Controller
[ 16.046970] xhci-hcd xhci-hcd.2.auto: new USB bus registered, assigned bus number 2
[ 16.055043] xhci-hcd xhci-hcd.2.auto: Host supports USB 3.0 SuperSpeed
[ 16.061831] usb usb2: We don't know the algorithms for LPM for this host, disabling LPM.
[ 16.070168] usb usb2: New USB device found, idVendor=1d6b, idProduct=0003, bcdDevice= 4.19
[ 16.078491] usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[ 16.085777] usb usb2: Product: xHCI Host Controller
[ 16.090721] usb usb2: Manufacturer: Linux 4.19.38-g4dae378bbe xhci-hcd
[ 16.097327] usb usb2: SerialNumber: xhci-hcd.2.auto
[ 16.103016] hub 2-0:1.0: USB hub found
[ 16.106884] hub 2-0:1.0: 1 port detected
[ 16.111856] xhci-hcd xhci-hcd.3.auto: xHCI Host Controller
[ 16.117496] xhci-hcd xhci-hcd.3.auto: new USB bus registered, assigned bus number 3
[ 16.126747] xhci-hcd xhci-hcd.3.auto: hcc params 0x0298fe6d hci version 0x110 quirks 0x0000000000010010
[ 16.136354] xhci-hcd xhci-hcd.3.auto: irq 311, io mem 0x04030000
[ 16.142972] usb usb3: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 4.19
[ 16.151316] usb usb3: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[ 16.158608] usb usb3: Product: xHCI Host Controller
[ 16.163514] usb usb3: Manufacturer: Linux 4.19.38-g4dae378bbe xhci-hcd
[ 16.170103] usb usb3: SerialNumber: xhci-hcd.3.auto
[ 16.175728] hub 3-0:1.0: USB hub found
[ 16.179593] hub 3-0:1.0: 1 port detected
[ 16.184073] xhci-hcd xhci-hcd.3.auto: xHCI Host Controller
[ 16.189677] xhci-hcd xhci-hcd.3.auto: new USB bus registered, assigned bus number 4
[ 16.197431] xhci-hcd xhci-hcd.3.auto: Host supports USB 3.0 SuperSpeed
[ 16.204244] usb usb4: We don't know the algorithms for LPM for this host, disabling LPM.
[ 16.212554] usb usb4: New USB device found, idVendor=1d6b, idProduct=0003, bcdDevice= 4.19
[ 16.220877] usb usb4: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[ 16.228203] usb usb4: Product: xHCI Host Controller
[ 16.233188] usb usb4: Manufacturer: Linux 4.19.38-g4dae378bbe xhci-hcd
[ 16.239792] usb usb4: SerialNumber: xhci-hcd.3.auto
[ 16.245507] hub 4-0:1.0: USB hub found
[ 16.249411] hub 4-0:1.0: 1 port detected
[ OK ] Created slice system-systemd\x2dfsck.slice.
Starting File System Check on /dev/mmcblk1p1...
Starting File System Check on /dev/mmcblk1p2...
Starting rc.pvr.service...
[ OK ] Reached target Host and Network Name Lookups.
[ OK ] Reached target Network.
Starting Permit User Sessions...
Starting Lightning Fast Webserver With Light System Requirements...
Starting Simple Network Management Protocol (SNMP) Daemon....
[ OK ] Started NFS status monitor for NFSv2/3 locking..
[ OK ] Started Permit User Sessions.
[ OK ] Started Serial Getty on ttyS2.
[ OK ] Started Getty on tty1.
[ OK ] Reached target Login Prompts.
Starting Synchronize System and HW clocks...
[ OK ] Started Lightning Fast Webserver With Light System Requirements.
[ OK ] Started Login Service.
[FAILED] Failed to start Synchronize System and HW clocks.
See 'systemctl status sync-clocks.service' for details.
[ 18.095322] PVR_K: UM DDK-(4948957) and KM DDK-(4948957) match. [ OK ]
[ OK ] Started rc.pvr.service.
Starting weston.service...
[ OK ] Started File System Check on /dev/mmcblk1p1.
Mounting /run/media/mmcblk1p1...
[ OK ] Started weston.service.
Starting Matrix GUI...
Starting telnetd.service...
[ OK ] Started Matrix GUI.
[ OK ] Started telnetd.service.
Starting thttpd.service...
[ OK ] Started thttpd.service.
Starting thermal-zone-init.service...
[ OK ] Started thermal-zone-init.service.
[ OK ] Started Simple Network Management Protocol (SNMP) Daemon..
[ OK ] Started Getty on tty4.
[ OK ] Mounted /run/media/mmcblk1p1.
[ OK ] Started File System Check on /dev/mmcblk1p2.
Mounting /run/media/mmcblk1p2...
[ 20.512505] EXT4-fs (mmcblk1p2): mounting ext3 file system using the ext4 subsystem
[ 20.649821] EXT4-fs (mmcblk1p2): mounted filesystem with ordered data mode. Opts: (null)
[ OK ] Mounted /run/media/mmcblk1p2.
[ 20.993217] systemd-coredump[970]: Configuration file /etc/systemd/coredump.conf is marked executable. Please remove executable permission bits. Proceeding anyway.
[ 21.007950] systemd-coredump[970]: Configuration file /etc/systemd/coredump.conf is marked world-writable. Please remove world writability permission bits. Proceeding anyway.
[ OK ] Created slice system-systemd\x2dcoredump.slice.
[ OK ] Started Process Core Dump (PID 970/UID 0).
***************************************************************
***************************************************************
NOTICE: This file system contains the following GPLv3 packages:
autoconf
bash
bc
binutils
cifs-utils
cpio
cpp-symlinks
cpp
dosfstools
elfutils
g++-symlinks
g++
gawk
gcc-symlinks
gcc
gdb
gdbserver
gettext
glmark2
gstreamer1.0-libav
gzip
hidapi
libbfd
libdw1
libelf1
libgdbm-compat4
libgdbm6
libgettextlib
libgettextsrc
libgmp10
libidn2-0
libmpc3
libmpfr6
libreadline7
libunistring2
m4
make
nettle
parted
pdm-anomaly-detection
which

If you do not wish to distribute GPLv3 components please remove
the above packages prior to distribution. This can be done using
the opkg remove command. i.e.:
opkg remove <package>
Where <package> is the name printed in the list above

NOTE: If the package is a dependency of another package you
will be notified of the dependent packages. You should
use the --force-removal-of-dependent-packages option to
also remove the dependent packages as well
***************************************************************
***************************************************************

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

Arago Project http://arago-project.org am65xx-evm ttyS2

Arago 2019.05 am65xx-evm ttyS2

am65xx-evm login:

0 Andreas Dannenberg over 6 years ago in reply to Andrea Wang

TI__Guru 69887 points

Hi Andrea,

Andrea Wang said:
Here is a boot log from the EVM board. We have been using the SD card for u-boot and TFTP for the kernel. We're trying to duplicate this environment for our custom board since layout issues made the SD card an unreliable boot device.

I don't see any immediate anomalies in the boot log you provided showing the mid-boot restart you had mentioned. Can you share a complete boot log showing the issue you reported originally on the EVM? In order to debug this more quickly I'd need a way to re-create the issue you are seeing on the TI EVM so I need your help to get it to fail the same way that you see it failing.

Also if you think your SD card HW access is unreliable you probably also want to boot the entire system from network (via NFS) in addition to loading Kernel etc. via TFTP to eliminate this as a cause for the boot issues you were describing.

Regards, Andreas

0 Andrea Wang over 6 years ago in reply to Andreas Dannenberg

Intellectual 380 points

Hi Andreas,

The log from the previous reply shows the mid-boot restart:

[ 0.013254] console [tty0] enabled
[ 0.016737] bootconsole [ns16550a0] disabled
I/TC: Dynamic shared memory is disabled
[ 0.000000] Booting Linux on physical CPU 0x0000000000 [0x410fd034]
[ 0.000000] Linux version 4.19.38-g4dae378bbe (oe-user@oe-host) (gcc version 8.3.0 (GNU Toolchain for the A-profile Architecture 8.3-2019.03 (arm-rel-8.36))) #1 SMP PREEMPT Sun Jul 7 03:42:51 UTC 2019

And are there instructions as to how we can boot the entire system through NFS? Our file system is mounted through NFS right now but we still need u-boot.

Thanks,
Andrea

0 Andreas Dannenberg over 6 years ago in reply to Andrea Wang

TI__Guru 69887 points

Hi Andrea,

Andrea Wang said:
The log from the previous reply shows the mid-boot restart:

What you are seeing is actually not a re-start of the Kernel in any way but instead a simple duplication of the initial ~50 lines of the Kernel log which is caused by an improperly configured Kernel command line. Digging a bit deeper the U-Boot "script" that determines the arguments for network boot in your setup is this (taken from your ENV)

args_net=setenv bootargs ${optargs} rootfstype=nfs root=/dev/nfs rw nfsroot=${serverip}:${nfs_root},${nfs_options} ip=dhcp

The issue is it is missing the explicit specification of the Kernel console. If it is missing, some fallback mechanism in the Kernel that will kick in later will activate the proper console, however only after initial prints were already made using the early console (haven't analyzed that in detail but it is a moot right now point really), leading to a duplication of the initial prints. If you update your args_net as follows those duplicated prints should go away:

env set args_net 'setenv bootargs console=${console} ${optargs} rootfstype=nfs root=/dev/nfs rw nfsroot=${serverip}:${nfs_root},${nfs_options} ip=dhcp'
env save

You can see how other boot modes already do it like this (args_mmc). The incorrect command line comes from the SDK's setup-uboot-env.sh script that you ran during installation. I've filed an issue against this script (internally tracked as PLSDK-3105) so we can get this fixed on our end moving forward.

Andrea Wang said:
And are there instructions as to how we can boot the entire system through NFS? Our file system is mounted through NFS right now but we still need u-boot.

Looks like you are already booting the Linux filesystem via NFS, I did not notice that earlier. As for U-Boot, we don't currently support booting directly from Network on AM654x, so with what you currently have you essentially have already off-loaded as much as possible to the network.

All this being said, there does not seem to be any actual issue with the boot log you shared from the AM654x EVM, and with that it looks like I won't be readily able to re-create the issue you see on your actual board on our EVM, so we need to go back and figure out how else to debug your system.

I propose the following:

You go back to your custom board
Update the args_net variable as indicated earlier to eliminate Kernel log duplication/confusion
Perform a network-based boot and capture the boot log as far as you get (and provide it for review)
For good measure, try a few more times, and see if there are any differences between the boot logs (in terms how far it goes, or any non-trivial differences in the timestamps)

Regards,
Andreas

0 Andreas Dannenberg over 6 years ago in reply to Andreas Dannenberg

TI__Guru 69887 points

Hi Andrea,

since I have not heard back I suppose you were able to resolve the issue with the duplicated Kernel log as per the suggestions given as well as the hang during your boot? I'm going to close this thread for now. Should you still need support regarding this specific issue please do let us know by amending this conversation with additional details or steps to re-create the issue.

Thanks and Regards,
Andreas

Processors

Processors forum

PROCESSOR-SDK-AM65X: Kernel boot hangs