This thread has been locked.

If you have a related question, please click the "Ask a related question" button in the top right corner. The newly created question will be automatically linked to this question.

AM5K2E04: Problem getting 4.19.59 working on our custom board.

Part Number: AM5K2E04
Other Parts Discussed in Thread: 66AK2E05, DA8XX

We have a custom designed board using the AM5K2E04 processor.  The design was originally based on the K2E EVM design with modifications for larger NAND, more DDR3, etc.  We have been successfully running with the 3.10.61 kernel but need to upgrade to a 4.x kernel to support Xen.  We are able to take the Processor SDK version 06.01.00.08 from 10/2019 with the 4.19.59 kernel and get everything working on the K2E EVM that we have.  We have re-built the kernel, the bootloader, etc. and are able to run successfully on the EVM.  We have never successfully been able to run on our custom board.  Our device trees are minimally different from the ones for the EVM.  We have tried running 4.x kernels since the 4.x series was released in the Processor SDK and have never been able to get past a certain point. We have worked with two kernels most recently, the 4.19.38 kernel and the 4.19.59 kernel.  Both exhibit the same problem.  The crash occurs in the netcp subsystem and involves the knav_qmss subsystem. 

What we don't understand is why this code works on the K2E EVM and not on our custom designed board.  The only significant difference we can tell is that the K2E EVM has a 66AK2E05 processor and our board has a AM5K2E04 device.  We are wondering if there is some difference between these two parts that would cause the netcp subsystem to work differently on the two different devices.

The following is a startup log file showing the crash for the 4.19.38 kernel with added:


U-Boot 2018.01 (Mar 13 2020 - 09:19:23 -0400)

CPU: 66AK2Ex SR1.0
Model: BAE Systems NTCDL NIU
I2C: ready
DRAM: Configuring DDR3 speed: 1600
Detected DDR3 Memory: [BAE NIU DDR1600]
DDR3 Frequency: 400000000
DRAM: 8 GiB

Clearing entire DDR3 memory to enable ECC
Ram size: 80000000
2 GiB
NAND: 1024 MiB
In: serial@02530c00
Out: serial@02530c00
Err: serial@02530c00
Net: eth0: netcp@24000000
Error: netcp@slave-1 address not set.

Hit any key to stop autoboot: 0
=> tftpboot 0x87000000 bae-dmniu-k2e-guy.dtb; tftpboot 0x82000000 zImage_guy_4.19.38; run init_${boot} init_fw_rd_${boot}; run get_mon_${boot} run_mon ;bootz 0x82000000 - 0x87000000 0x88000000
. done
Using netcp@24000000 device
TFTP from server 192.168.1.150; our IP address is 192.168.1.20
Filename 'bae-dmniu-k2e-guy.dtb'.
Load address: 0x87000000
Loading: ###
2.4 MiB/s
done
Bytes transferred = 29843 (7493 hex)
. done
Using netcp@24000000 device
TFTP from server 192.168.1.150; our IP address is 192.168.1.20
Filename 'zImage_guy_4.19.38'.
Load address: 0x82000000
Loading: #################################################################
#################################################################
#################################################################
#################################################################
#################################################################
#######################
2.7 MiB/s
done
Bytes transferred = 5103648 (4de020 hex)
ubi0: attaching mtd1
ubi0: scanning is finished
ubi0: attached mtd1 (name "mtd=2", size 1022 MiB)
ubi0: PEB size: 262144 bytes (256 KiB), LEB size: 253952 bytes
ubi0: min./max. I/O unit sizes: 4096/4096, sub-page size 4096
ubi0: VID header offset: 4096 (aligned 4096), data offset: 8192
ubi0: good PEBs: 4086, bad PEBs: 4, corrupted PEBs: 0
ubi0: user volume: 3, internal volumes: 1, max. volumes count: 128
ubi0: max/mean erase counter: 2/0, WL threshold: 4096, image sequence number: 1153696818
ubi0: available PEBs: 0, total reserved PEBs: 4086, PEBs reserved for bad PEB handling: 1
Loading file 'bae.cpio.gz' to addr 0x88000000...
Done
Loading file 'skern-k2e.bin' to addr 0x0c140000...
Done
K2_BM_15.07-53-ge1f885a SoC:k2e built:04:49:50, Jul 7 2019

## installed monitor @ 0xc1f0000, freq [208333333], status 203358208
## Flattened Device Tree blob at 87000000
Booting using the fdt blob at 0x87000000
Using Device Tree in place at 87000000, end 8700a492

Starting kernel ...

[ 0.000000] Booting Linux on physical CPU 0x0
[ 0.000000] Linux version 4.19.38-g4dae378bbe (winterg@ubuntu) (gcc version 8.3.0 (GNU Toolchain for the A-profile Architecture 8.3-2019.03 (arm-rel-8.36))) #24 SMP PREEMPT Thu Sep 5 17:33:56 EDT 2019
[ 0.000000] CPU: ARMv7 Processor [412fc0f4] revision 4 (ARMv7), cr=30c5387d
[ 0.000000] CPU: div instructions available: patching division code
[ 0.000000] CPU: PIPT / VIPT nonaliasing data cache, PIPT instruction cache
[ 0.000000] OF: fdt: Machine model: Texas Instruments Keystone 2 Edison EVM
[ 0.000000] bootconsole [earlycon0] enabled
[ 0.000000] Memory policy: Data cache writealloc
[ 0.000000] Invalid address space for memory (80000000-100000000)
[ 0.000000] psci: probing for conduit method from DT.
[ 0.000000] psci: Using PSCI v0.1 Function IDs from DT
[ 0.000000] random: get_random_bytes called from start_kernel+0xa4/0x490 with crng_init=0
[ 0.000000] percpu: Embedded 16 pages/cpu s36684 r8192 d20660 u65536
[ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 522824
[ 0.000000] Kernel command line: console=ttyS0,115200n8 rootwait=1 earlyprintk mem=2048M coherent_pool=4M vmalloc=284M rdinit=/sbin/init rw root=/dev/ram0 initrd=0x88000000,50M
[ 0.000000] log_buf_len individual max cpu contribution: 4096 bytes
[ 0.000000] log_buf_len total cpu_extra contributions: 12288 bytes
[ 0.000000] log_buf_len min size: 16384 bytes
[ 0.000000] log_buf_len: 32768 bytes
[ 0.000000] early log buf free: 14520(88%)
[ 0.000000] Dentry cache hash table entries: 131072 (order: 7, 524288 bytes)
[ 0.000000] Inode-cache hash table entries: 65536 (order: 6, 262144 bytes)
[ 0.000000] Memory: 2013376K/2097152K available (8192K kernel code, 437K rwdata, 2240K rodata, 2048K init, 196K bss, 83776K reserved, 0K cma-reserved, 1347584K highmem)
[ 0.000000] Virtual kernel memory layout:
[ 0.000000] vector : 0xffff0000 - 0xffff1000 ( 4 kB)
[ 0.000000] fixmap : 0xffc00000 - 0xfff00000 (3072 kB)
[ 0.000000] vmalloc : 0xee000000 - 0xff800000 ( 280 MB)
[ 0.000000] lowmem : 0xc0000000 - 0xedc00000 ( 732 MB)
[ 0.000000] pkmap : 0xbfe00000 - 0xc0000000 ( 2 MB)
[ 0.000000] modules : 0xbf000000 - 0xbfe00000 ( 14 MB)
[ 0.000000] .text : 0x(ptrval) - 0x(ptrval) (10208 kB)
[ 0.000000] .init : 0x(ptrval) - 0x(ptrval) (2048 kB)
[ 0.000000] .data : 0x(ptrval) - 0x(ptrval) ( 438 kB)
[ 0.000000] .bss : 0x(ptrval) - 0x(ptrval) ( 197 kB)
[ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1
[ 0.000000] rcu: Preemptible hierarchical RCU implementation.
[ 0.000000] Tasks RCU enabled.
[ 0.000000] NR_IRQS: 16, nr_irqs: 16, preallocated irqs: 16
[ 0.000000] GIC: Using split EOI/Deactivate mode
[ 0.000000] arch_timer: cp15 timer(s) running at 208.33MHz (phys).
[ 0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x300c4cdf1c, max_idle_ns: 440795202901 ns
[ 0.000003] sched_clock: 56 bits at 208MHz, resolution 4ns, wraps every 4398046511101ns
[ 0.008046] Switching to timer-based delay loop, resolution 4ns
[ 0.014112] keystone timer clock @208333333 Hz
[ 0.018762] Console: colour dummy device 80x30
[ 0.023242] Calibrating delay loop (skipped), value calculated using timer frequency.. 416.66 BogoMIPS (lpj=2083333)
[ 0.033830] pid_max: default: 4096 minimum: 301
[ 0.038460] Mount-cache hash table entries: 2048 (order: 1, 8192 bytes)
[ 0.045116] Mountpoint-cache hash table entries: 2048 (order: 1, 8192 bytes)
[ 0.052548] CPU: Testing write buffer coherency: ok
[ 0.057474] CPU0: Spectre v2: firmware did not set auxiliary control register IBE bit, system vulnerable
[ 0.067204] CPU0: update cpu_capacity 1024
[ 0.071331] CPU0: thread -1, cpu 0, socket 0, mpidr 80000000
[ 0.131415] Setting up static identity map for 0x80200000 - 0x80200138
[ 0.138046] rcu: Hierarchical SRCU implementation.
[ 0.182912] smp: Bringing up secondary CPUs ...
[ 0.247685] CPU1: update cpu_capacity 1024
[ 0.247692] CPU1: thread -1, cpu 1, socket 0, mpidr 80000001
[ 0.247696] CPU1: Spectre v2: firmware did not set auxiliary control register IBE bit, system vulnerable
[ 0.347791] CPU2: update cpu_capacity 1024
[ 0.347798] CPU2: thread -1, cpu 2, socket 0, mpidr 80000002
[ 0.347803] CPU2: Spectre v2: firmware did not set auxiliary control register IBE bit, system vulnerable
[ 0.447892] CPU3: update cpu_capacity 1024
[ 0.447898] CPU3: thread -1, cpu 3, socket 0, mpidr 80000003
[ 0.447902] CPU3: Spectre v2: firmware did not set auxiliary control register IBE bit, system vulnerable
[ 0.467351] smp: Brought up 1 node, 4 CPUs
[ 0.471465] SMP: Total of 4 processors activated (1666.66 BogoMIPS).
[ 0.477878] CPU: All CPU(s) started in HYP mode.
[ 0.482513] CPU: Virtualization extensions available.
[ 0.488109] devtmpfs: initialized
[ 0.495523] VFP support v0.3: implementor 41 architecture 4 part 30 variant f rev 0
[ 0.503525] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns
[ 0.513434] futex hash table entries: 16 (order: -2, 1024 bytes)
[ 0.519552] pinctrl core: initialized pinctrl subsystem
[ 0.525514] NET: Registered protocol family 16
[ 0.530920] DMA: preallocated 4096 KiB pool for atomic coherent allocations
[ 0.539165] hw-breakpoint: found 5 (+1 reserved) breakpoint and 4 watchpoint registers.
[ 0.547207] hw-breakpoint: maximum watchpoint size is 8 bytes.
[ 0.573575] vgaarb: loaded
[ 0.576530] SCSI subsystem initialized
[ 0.580659] usbcore: registered new interface driver usbfs
[ 0.586253] usbcore: registered new interface driver hub
[ 0.591683] usbcore: registered new device driver usb
[ 0.598967] clocksource: Switched to clocksource arch_sys_counter
[ 0.645717] NET: Registered protocol family 2
[ 0.650481] tcp_listen_portaddr_hash hash table entries: 512 (order: 0, 6144 bytes)
[ 0.658186] TCP established hash table entries: 8192 (order: 3, 32768 bytes)
[ 0.665330] TCP bind hash table entries: 8192 (order: 4, 65536 bytes)
[ 0.671906] TCP: Hash tables configured (established 8192 bind 8192)
[ 0.678345] UDP hash table entries: 512 (order: 2, 16384 bytes)
[ 0.684328] UDP-Lite hash table entries: 512 (order: 2, 16384 bytes)
[ 0.690834] NET: Registered protocol family 1
[ 0.695482] RPC: Registered named UNIX socket transport module.
[ 0.701448] RPC: Registered udp transport module.
[ 0.706171] RPC: Registered tcp transport module.
[ 0.710904] RPC: Registered tcp NFSv4.1 backchannel transport module.
[ 0.717492] Unpacking initramfs...
[ 2.831402] Freeing initrd memory: 51200K
[ 2.835660] hw perfevents: no interrupt-affinity property for /pmu, guessing.
[ 2.843112] hw perfevents: enabled with armv7_cortex_a15 PMU driver, 7 counters available
[ 2.853389] workingset: timestamp_bits=30 max_order=19 bucket_order=0
[ 2.867977] Installing knfsd (copyright (C) 1996 okir@monad.swb.de).
[ 2.874551] ntfs: driver 2.1.32 [Flags: R/O].
[ 2.879266] jffs2: version 2.2. (NAND) © 2001-2006 Red Hat, Inc.
[ 2.888201] NET: Registered protocol family 38
[ 2.892710] bounce: pool size: 64 pages
[ 2.896624] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 252)
[ 2.904070] io scheduler noop registered
[ 2.908007] io scheduler deadline registered
[ 2.912321] io scheduler cfq registered (default)
[ 2.917044] io scheduler mq-deadline registered
[ 2.921605] io scheduler kyber registered
[ 2.925904] keystone_irq 26202a0.keystone_irq: irqchip registered, nr_irqs 28
[ 2.934087] keystone-pcie 21800000.pcie: host bridge /soc@0/pcie@21800000 ranges:
[ 2.941638] keystone-pcie 21800000.pcie: IO 0x50000000..0x500fffff -> 0x00000000
[ 2.949354] keystone-pcie 21800000.pcie: MEM 0x50100000..0x5fffffff -> 0x50100000
[ 2.957490] keystone-pcie 21800000.pcie: failed to initialize host
[ 2.963972] keystone-pcie 21020000.pcie: host bridge /soc@0/pcie@21020000 ranges:
[ 2.971519] keystone-pcie 21020000.pcie: IO 0x60000000..0x600fffff -> 0x00000000
[ 2.979232] keystone-pcie 21020000.pcie: MEM 0x60100000..0x6fffffff -> 0x60100000
[ 3.978975] keystone-pcie 21020000.pcie: Phy link never came up
[ 3.985050] keystone-pcie 21020000.pcie: PCI host bridge to bus 0000:00
[ 3.991715] pci_bus 0000:00: root bus resource [bus 00-ff]
[ 3.997226] pci_bus 0000:00: root bus resource [mem 0x60100000-0x6fffffff]
[ 4.005882] PCI: bus0: Fast back to back transfers disabled
[ 4.013007] PCI: bus1: Fast back to back transfers enabled
[ 4.018541] pci 0000:00:00.0: PCI bridge to [bus 01-ff]
[ 4.024625] Line: 1770, knav_queue_probe: Entered
[ 4.029377] keystone-navigator-qmss soc@0:qmss@2a40000: qmgr start queue 0, number of queues 8192
[ 4.038382] keystone-navigator-qmss soc@0:qmss@2a40000: added qmgr start queue 0, num of queues 8192, reg_peek (ptrval), reg_status (ptrval), reg_config (ptrval), reg_region (ptrval), reg_push (ptrval), reg_pop (ptrval)
[ 4.058098] keystone-navigator-qmss soc@0:qmss@2a40000: firmware file ks2_qmss_pdsp_acc48.bin downloaded for PDSP
[ 4.070307] Line: 1927, knav_queue_probe: Exiting, normal exit no error
[ 4.077358] keystone-navigator-dma soc@0:knav_dmas@0: DMA dma_gbe registered 149 logical channels, flows 128, tx chans: 21, rx chans: 91
[ 4.144047] Serial: 8250/16550 driver, 4 ports, IRQ sharing disabled
[ 4.153384] console [ttyS0] disabled
[ 4.157005] 2530c00.serial: ttyS0 at MMIO 0x2530c00 (irq = 27, base_baud = 13020833) is a TI DA8xx/66AK2x
[ 4.166665] console [ttyS0] enabled
[ 4.166665] console [ttyS0] enabled
[ 4.173656] bootconsole [earlycon0] disabled
[ 4.173656] bootconsole [earlycon0] disabled
[ 4.182952] 2531000.serial: ttyS1 at MMIO 0x2531000 (irq = 28, base_baud = 13020833) is a TI DA8xx/66AK2x
[ 4.199273] loop: module loaded
[ 5.248978] i2c_davinci 2530000.i2c: controller timed out
[ 5.257195] spi_davinci 21000400.spi: DMA is not supported (-19)
[ 5.264086] m25p80 spi0.0: n25q128a11 (16384 Kbytes)
[ 5.269079] 2 fixed-partitions partitions found on MTD device spi0.0
[ 5.275425] Creating 2 MTD partitions on "spi0.0":
[ 5.280221] 0x000000000000-0x000000080000 : "u-boot-spl"
[ 5.286206] 0x000000080000-0x000001000000 : "misc"
[ 5.291721] spi_davinci 21000400.spi: Controller at 0x(ptrval)
[ 5.297788] spi_davinci 21000600.spi: DMA is not supported (-19)
[ 5.304091] spi_davinci 21000600.spi: Controller at 0x(ptrval)
[ 5.310147] spi_davinci 21000800.spi: DMA is not supported (-19)
[ 5.316433] spi_davinci 21000800.spi: Controller at 0x(ptrval)
[ 5.322627] libphy: Fixed MDIO Bus: probed
[ 5.378981] davinci_mdio 24200f00.mdio: davinci mdio revision 1.7, bus freq 2500000
[ 5.386629] libphy: 24200f00.mdio: probed
[ 5.393240] davinci_mdio 24200f00.mdio: phy[0]: device 24200f00.mdio:00, driver Marvell 88E1510
[ 5.402204] Line: 2222, netcp_create_interface: rx_pool_size from dtb: 1024
[ 5.409172] Line: 2225, netcp_create_interface: rx_pool_region_id from dtb: 12
[ 5.416652] netcp-1.0 2620110.netcp: initialized cpsw ale version 1.4
[ 5.423103] netcp-1.0 2620110.netcp: ALE Table size 8192
[ 5.429083] netcp-1.0 2620110.netcp: module(netcp-xgbe) not used for device
[ 5.436149] keystone-usbphy 2620738.usb_phy: 2620738.usb_phy supply vcc not found, using dummy regulator
[ 5.445685] keystone-usbphy 2620738.usb_phy: Linked as a consumer to regulator.0
[ 5.453232] keystone-usbphy 2620750.usb_phy: 2620750.usb_phy supply vcc not found, using dummy regulator
[ 5.462755] keystone-usbphy 2620750.usb_phy: Linked as a consumer to regulator.0
[ 5.470868] dwc3 2690000.dwc3: Failed to get clk 'ref': -2
[ 5.476997] dwc3 25010000.dwc3: Failed to get clk 'ref': -2
[ 5.482588] dwc3 25010000.dwc3: Configuration mismatch. dr_mode forced to host
[ 5.490808] xhci-hcd xhci-hcd.0.auto: xHCI Host Controller
[ 5.496439] xhci-hcd xhci-hcd.0.auto: new USB bus registered, assigned bus number 1
[ 5.504196] xhci-hcd xhci-hcd.0.auto: hcc params 0x0298f06d hci version 0x100 quirks 0x0000000002010010
[ 5.513607] xhci-hcd xhci-hcd.0.auto: irq 35, io mem 0x02690000
[ 5.519810] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 4.19
[ 5.528067] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[ 5.535295] usb usb1: Product: xHCI Host Controller
[ 5.540177] usb usb1: Manufacturer: Linux 4.19.38-g4dae378bbe xhci-hcd
[ 5.546696] usb usb1: SerialNumber: xhci-hcd.0.auto
[ 5.551959] hub 1-0:1.0: USB hub found
[ 5.555724] hub 1-0:1.0: 1 port detected
[ 5.559964] xhci-hcd xhci-hcd.0.auto: xHCI Host Controller
[ 5.565589] xhci-hcd xhci-hcd.0.auto: new USB bus registered, assigned bus number 2
[ 5.573259] xhci-hcd xhci-hcd.0.auto: Host supports USB 3.0 SuperSpeed
[ 5.579928] usb usb2: We don't know the algorithms for LPM for this host, disabling LPM.
[ 5.588102] usb usb2: New USB device found, idVendor=1d6b, idProduct=0003, bcdDevice= 4.19
[ 5.596371] usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[ 5.603597] usb usb2: Product: xHCI Host Controller
[ 5.608467] usb usb2: Manufacturer: Linux 4.19.38-g4dae378bbe xhci-hcd
[ 5.614996] usb usb2: SerialNumber: xhci-hcd.0.auto
[ 5.620235] hub 2-0:1.0: USB hub found
[ 5.623999] hub 2-0:1.0: 1 port detected
[ 5.628309] xhci-hcd xhci-hcd.1.auto: xHCI Host Controller
[ 5.633950] xhci-hcd xhci-hcd.1.auto: new USB bus registered, assigned bus number 3
[ 5.641699] xhci-hcd xhci-hcd.1.auto: hcc params 0x0298f06d hci version 0x100 quirks 0x0000000002010010
[ 5.651105] xhci-hcd xhci-hcd.1.auto: irq 70, io mem 0x25010000
[ 5.657270] usb usb3: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 4.19
[ 5.665540] usb usb3: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[ 5.672822] usb usb3: Product: xHCI Host Controller
[ 5.677693] usb usb3: Manufacturer: Linux 4.19.38-g4dae378bbe xhci-hcd
[ 5.684225] usb usb3: SerialNumber: xhci-hcd.1.auto
[ 5.689460] hub 3-0:1.0: USB hub found
[ 5.693223] hub 3-0:1.0: 1 port detected
[ 5.697433] xhci-hcd xhci-hcd.1.auto: xHCI Host Controller
[ 5.703062] xhci-hcd xhci-hcd.1.auto: new USB bus registered, assigned bus number 4
[ 5.710731] xhci-hcd xhci-hcd.1.auto: Host supports USB 3.0 SuperSpeed
[ 5.717390] usb usb4: We don't know the algorithms for LPM for this host, disabling LPM.
[ 5.725575] usb usb4: New USB device found, idVendor=1d6b, idProduct=0003, bcdDevice= 4.19
[ 5.733934] usb usb4: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[ 5.741161] usb usb4: Product: xHCI Host Controller
[ 5.746032] usb usb4: Manufacturer: Linux 4.19.38-g4dae378bbe xhci-hcd
[ 5.752563] usb usb4: SerialNumber: xhci-hcd.1.auto
[ 5.757797] hub 4-0:1.0: USB hub found
[ 5.761574] hub 4-0:1.0: 1 port detected
[ 5.765992] usbcore: registered new interface driver usb-storage
[ 5.772145] usbcore: registered new interface driver ftdi_sio
[ 5.777941] usbserial: USB Serial support registered for FTDI USB Serial Device
[ 5.785606] i2c /dev entries driver
[ 5.789864] davinci-wdt 22f0080.wdt: heartbeat 60 sec
[ 5.795619] sdhci: Secure Digital Host Controller Interface driver
[ 5.801879] sdhci: Copyright(c) Pierre Ossman
[ 5.806389] sdhci-pltfm: SDHCI platform and OF driver helper
[ 5.812382] ledtrig-cpu: registered to indicate activity on CPUs
[ 5.818914] usbcore: registered new interface driver usbhid
[ 5.824494] usbhid: USB HID core driver
[ 5.829227] nand: device found, Manufacturer ID: 0x2c, Chip ID: 0xa3
[ 5.835573] nand: Micron MT29F8G08ABBCAH4
[ 5.839589] nand: 1024 MiB, SLC, erase size: 256 KiB, page size: 4096, OOB size: 224
[ 5.847330] nand: WARNING: 30000000.nand: the ECC used on your system is too weak compared to the one required by the NAND chip
[ 5.859320] Bad block table found at page 262080, version 0x01
[ 5.866068] Bad block table found at page 262016, version 0x01
[ 5.872533] 3 fixed-partitions partitions found on MTD device 30000000.nand
[ 5.879499] Creating 3 MTD partitions on "30000000.nand":
[ 5.884891] 0x000000000000-0x000000100000 : "u-boot"
[ 5.890567] 0x000000100000-0x000000180000 : "params"
[ 5.896185] 0x000000180000-0x000020000000 : "ubifs"
[ 5.903499] davinci_nand 30000000.nand: controller rev. 2.5
[ 5.909736] oprofile: using timer interrupt.
[ 5.914683] ipip: IPv4 and MPLS over IPv4 tunneling driver
[ 5.920567] gre: GRE over IPv4 demultiplexor driver
[ 5.925438] ip_gre: GRE over IPv4 tunneling driver
[ 5.931620] ipt_CLUSTERIP: ClusterIP Version 0.8 loaded successfully
[ 5.938026] Initializing XFRM netlink socket
[ 5.942972] NET: Registered protocol family 10
[ 5.948313] Segment Routing with IPv6
[ 5.952047] NET: Registered protocol family 17
[ 5.956494] NET: Registered protocol family 15
[ 5.960971] sctp: Hash tables configured (bind 512/512)
[ 5.966555] Registering SWP/SWPB emulation handler
[ 5.996739] Freeing unused kernel memory: 2048K
[ 6.001391] Run /sbin/init as init process
System time before build time, advancing clock.
[ 6.043275] systemd[1]: systemd 234 running in system mode. (+PAM -AUDIT -SELINUX +IMA -APPARMOR +SMACK +SYSVINIT +UTMP -LIBCRYPTSETUP -GCRYPT -GNUTLS +ACL +XZ -LZ4 -SECCOMP +BLKID -ELFUTILS +KMOD -IDN2 -IDN default-hierarchy=hybrid)
[ 6.064268] systemd[1]: Detected architecture arm.

Welcome to Arago 2018.10!

[ 6.099051] systemd[1]: Set hostname to <ntcdl-niu>.
[ 6.104142] random: systemd: uninitialized urandom read (16 bytes read)
[ 6.110888] systemd[1]: Initializing machine ID from random generator.
[ 6.183411] systemd[1]: /lib/systemd/system/gplv3-notice.service:8: Failed to parse output specifier, ignoring: /dev/null
[ 6.214593] random: systemd: uninitialized urandom read (16 bytes read)
[ 6.226046] systemd[1]: Listening on Process Core Dump Socket.
[ OK ] Listening on Process Core Dump Socket.
[ 6.259075] random: systemd: uninitialized urandom read (16 bytes read)
[ 6.265707] systemd[1]: Reached target Swap.
[ OK ] Reached target Swap.
[ 6.300890] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.
[ OK ] Started Dispatch Password Requests to Console Directory Watch.
[ 6.339192] systemd[1]: Listening on Journal Socket.
[ OK ] Listening on Journal Socket.
[ 6.369069] systemd[1]: Reached target Remote File Systems.
[ OK ] Reached target Remote File Systems.
[ OK ] Started Forward Password Requests to Wall Directory Watch.
[ OK ] Reached target Paths.
[ OK ] Listening on Network Service Netlink Socket.
[ OK ] Listening on udev Kernel Socket.
[ OK ] Listening on udev Control Socket.
[ OK ] Listening on Syslog Socket.
[ OK ] Listening on /dev/initctl Compatibility Named Pipe.
[ OK ] Created slice User and Session Slice.
[ OK ] Created slice System Slice.
Mounting Temporary Directory (/tmp)...
[ OK ] Created slice system-serial\x2dgetty.slice.
Mounting POSIX Message Queue File System...
Mounting Kernel Debug File System...
Starting Remount Root and Kernel File Systems...
[ OK ] Reached target Slices.
[ OK ] Created slice system-getty.slice.
[ OK ] Listening on Journal Socket (/dev/log).
Starting Journal Service...
Starting Load Kernel Modules...
[ OK ] Mounted Kernel Debug File System.
[ OK ] Mounted POSIX Message Queue File System.
[ OK ] Mounted Temporary Directory (/tmp).
[ OK ] Started Journal Service.
[ OK ] Started Remount Root and Kernel File Systems.
[FAILED] Failed to start Load Kernel Modules.
See 'systemctl status systemd-modules-load.service' for details.
Starting Apply Kernel Variables...
Starting Rebuild Hardware Database...
Starting Create System Users...
Starting Flush Journal to Persistent Storage...
[ OK ] Started Apply Kernel Variables.
[ OK ] Started Create System Users.
Starting Create Static Device Nodes in /dev...
[ OK ] Started Create Static Device Nodes in /dev.
[ 7.550095] systemd-journald[1314]: Received request to flush runtime journal from PID 1
[ OK ] Reached target Local File Systems (Pre).
Mounting /media/ram...
Mounting /var/volatile...
Starting udev Kernel Device Manager...
[ OK ] Mounted /var/volatile.
[ OK ] Mounted /media/ram.
[ OK ] Started udev Kernel Device Manager.
[ OK ] Started Flush Journal to Persistent Storage.
Starting Load/Save Random Seed...
[ OK ] Reached target Local File Systems.
Starting Create Volatile Files and Directories...
Starting Rebuild Dynamic Linker Cache...
Starting Rebuild Journal Catalog...
[ OK ] Started Load/Save Random Seed.
[ OK ] Started Create Volatile Files and Directories.
[ 8.056450] random: fast init done
[ OK ] Started Rebuild Dynamic Linker Cache.
[ OK ] Started Rebuild Journal Catalog.
Starting Opkg first boot configure...
Starting Network Time Synchronization...
Starting Update UTMP about System Boot/Shutdown...
[ OK ] Started Rebuild Hardware Database.
[ OK ] Started Update UTMP about System Boot/Shutdown.
[ OK ] Started Opkg first boot configure.
Starting udev Coldplug all Devices...
Starting Update is Completed...
[ OK ] Started Network Time Synchronization.
[ OK ] Started Update is Completed.
[ OK ] Reached target System Time Synchronized.
[ OK ] Started udev Coldplug all Devices.
[ OK ] Found device /dev/ttyS0.
[ OK ] Reached target System Initialization.
[ OK ] Listening on RPCbind Server Activation Socket.
Starting Network Service...
[ OK ] Started Daily Cleanup of Temporary Directories.
[ OK ] Listening on D-Bus System Message Bus Socket.
[ OK ] Reached target Sockets.
[ OK ] Reached target Basic System.
Starting Print notice about GPLv3 packages...
[ OK ] Reached target Containers.
[ OK ] Started Job spooling tools.
[ OK ] Started Periodic Command Scheduler.
[ OK ] Started System Logging Service.
Starting Network Time Service...
Starting rng-tools.service...
Starting Attach UBI Volume...
[ 9.284606] ubi0: attaching mtd2
[ 9.287835] ubi0: MTD device 2 is write-protected, attach in read-only mode
[ 9.296960] ubi0: scanning is finished
[ 9.300786] ubi0: empty MTD device detected
[ 9.304997] ubi0 error: ubi_io_sync_erase: read-only mode
[ 9.312596] ubi0 error: ubi_io_sync_erase: read-only mode
Starting TI IPC [ 9.318711] ubi0 error: ubi_io_sync_erase: read-only mode
Daemon...
[ 9.325598] ubi0 error: ubi_io_sync_erase: read-only mode
[ 9.331838] ubi0 error: ubi_early_get_peb: no free eraseblocks
[ 9.337678] ubi0 error: ubi_attach_mtd_dev: failed to attach mtd2, error -28
Starting Login Service...
[ OK ] Started Daily rotation of log files.
[ OK ] Reached target Timers.
Starting RPC Bind Service...
[ OK ] Started Kernel Logging Service.
[ OK ] Started Hardware RNG Entropy [ 9.535126] random: crng init done
Gatherer Daemon.[ 9.539017] random: 7 urandom warning(s) missed due to ratelimiting

[ OK ] Started D-Bus System Message Bus.
[ 9.611544] Line: 1725, netcp_setup_navigator_resources: knav_pool_create returned: netcp->rx_pool: 0xcaffa1c0
[ 9.621565] Line: 1728, netcp_setup_navigator_resources: knav_pool_create, pool size: netcp->rx_pool_size: 1024
[ OK ] Started Network Service.
[FAILED] Failed to start Print notice about GPLv3 packages.
See 'systemctl status gplv3-notice.service' for details.
[ OK ] Started Network Time Service.
[ OK 9.751519] netcp-1.0 2620110.netcp eth0: Link is Down
m] Started rng-t[ 9.757229] Line: 1865, netcp_ndo_open: Calling netcp_rxpool_refill
ools.service.
[ 9.764904] Line: 1032, netcp_rxpool_refill: netcp->rx_queue_depths[0]:128
[ 9.773068] Line: 1036, netcp_rxpool_refill: knav_queue_get_count(netcp->rx_fdq[0]):0
[ 9.780922] Line: 1043, netcp_rxpool_refill: fdq_deficit[0]:128
[ 9.786848]
[ 9.786848] Line: 923, netcp_allocate_rx_buf: Entered
[ 9.793450] Line: 929, netcp_allocate_rx_buf: knav_pool_desc_get return: hwdesc: 0xcae00000
[ 9.801868] Line: 940, netcp_allocate_rx_buf: knav_pool_desc_get return: hwdesc->next_desc: 0xe7fddef0
[ 9.811205] Line: 1001, netcp_allocate_rx_buf: calling knav_queue_push: hwdesc: 0xcae00000
[ 9.819490] Line: 1004, netcp_allocate_rx_buf: calling knav_queue_push: hwdesc->next_desc: 0x00000000
[FAILED Line: 1007, netcp_allocate_rx_buf: calling knav_queue_push: dma: 0x0ae00000
[0m] [ 9.838189]
[ 9.838189] Line: 1012, netcp_allocate_rx_buf: Normal Exit
Failed to start [ 9.845586] Line: 1050, netcp_rxpool_refill: netcp_allocate_rx_buf[0] returned: 0x00000000
Attach UBI Volum[ 9.855237]
[ 9.855237] Line: 923, netcp_allocate_rx_buf: Entered
e.
[ 9.863147] Line: 929, netcp_allocate_rx_buf: knav_pool_desc_get return: hwdesc: 0xfffffff4
[ 9.871837] Line: 933, netcp_allocate_rx_buf: out of rx pool desc
[ 9.877925] Line: 1050, netcp_rxpool_refill: netcp_allocate_rx_buf[0] returned: 0xfffffff4
[ 9.886202] Line: 1032, netcp_rxpool_refill: netcp->rx_queue_depths[1]:128
[ 9.893101] Line: 1036, netcp_rxpool_refill: knav_queue_get_count(netcp->rx_fdq[1]):0
[ 9.900953] Line: 1043, netcp_rxpool_refill: fdq_deficit[1]:128
[ 9.906867] Line: 1870, netcp_ndo_open: netcp device eth0 opened
See 'systemctl status nand-attach.service' for details.
[ 9.923692] IPv6: ADDRCONF(NETDEV_UP): eth0: link is not ready
[DEPEND] Dependency failed for Master NAND Flash volume.
[DEPEND] Dependency failed for Startup of NTCDL Radio Software and Services.
[ OK ] Started TI IPC Daemon.
[ OK ] Started RPC Bind Service.
Starting thermal-zone-init.service...
[ OK ] Reached target Network.
Starting Permit User Sessions...
Starting Network Name Resolution...
[ OK ] Started thermal-zone-init.service.
[ OK ] Started Permit User Sessions.
[ OK ] Started Login Service.
[ OK ] Started Getty on tty1.
[ OK ] Started Serial Getty on ttyS0.
[ OK ] Reached target Login Prompts.
Starting Synchronize System and HW clocks...
[ OK ] Started Network Name Resolution.
[FAILED] Failed to start Synchronize System and HW clocks.
See 'systemctl status sync-clocks.service' for details.
[ OK ] Reached target Host and Network Name Lookups.
[ OK ] Started NFS status monitor for NFSv2/3 locking..
[ OK ] Reached target Multi-User System.
Starting Update UTMP about System Runlevel Changes...
[ OK ] Started Update UTMP about System Runlevel Changes.

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

Arago Project http://arago-project.org ntcdl-niu ttyS0

Arago 2018.10 ntcdl-niu ttyS0

ntcdl-niu login: [ 13.090040] netcp-1.0 2620110.netcp eth0: Link is Up - 1Gbps/Full - flow control off
[ 13.097791] IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready
[ 13.106273] Line: 1208, netcp_tx_map_skb: knav_pool_desc_get returned: desc: 0xcae80000
[ 13.114295] Line: 1217, netcp_tx_map_skb: set_pkt_info: desc->next_desc: 0x00000000
[ 13.147851] netcp_process_one_rx_packet: knav_pool_desc_unmap returned: desc: 0xcae00000
[ 13.155940] netcp_process_one_rx_packet: get_pkt_info returned: (dma_addr_t)buff: 0xe7fddef0
[ 13.164381] netcp_process_one_rx_packet: get_pkt_info returned: (dma_addr_t)buf_len: 0xe7fddef0
[ 13.173083] Line: 692, netcp_process_one_rx_packet: get_pkt_info returned: (dma_addr_t)next_desc: 0x00000000
[ 13.182911] Line: 698, netcp_process_one_rx_packet: org_buf_ptr: 0xca5378c0
[ 13.189877] Line: 700, netcp_process_one_rx_packet: org_buf_len: 0x00000740
[ 13.196830] Line: 720, netcp_process_one_rx_packet: Calling build_skb with args: org_buf_ptr: 0xca5378c0
[ 13.206311] Line: 722, netcp_process_one_rx_packet: Calling build_skb with args: org_buf_len: 0x00000740
[ 13.215804] Line: 1032, netcp_rxpool_refill: netcp->rx_queue_depths[0]:128
[ 13.222681] Line: 1036, netcp_rxpool_refill: knav_queue_get_count(netcp->rx_fdq[0]):0
[ 13.230516] Line: 1043, netcp_rxpool_refill: fdq_deficit[0]:128
[ 13.236426]
[ 13.236426] Line: 923, netcp_allocate_rx_buf: Entered
[ 13.242961] Line: 929, netcp_allocate_rx_buf: knav_pool_desc_get return: hwdesc: 0xc0000010
[ 13.251316] Line: 940, netcp_allocate_rx_buf: knav_pool_desc_get return: hwdesc->next_desc: 0x00000000
[ 13.260627] Line: 1001, netcp_allocate_rx_buf: calling knav_queue_push: hwdesc: 0xc0000010
[ 13.268883] Line: 1004, netcp_allocate_rx_buf: calling knav_queue_push: hwdesc->next_desc: 0x00000000
[ 13.278104] Line: 1007, netcp_allocate_rx_buf: calling knav_queue_push: dma: 0x00000010
[ 13.286111]
[ 13.286111] Line: 1012, netcp_allocate_rx_buf: Normal Exit
[ 13.293076] Line: 1050, netcp_rxpool_refill: netcp_allocate_rx_buf[0] returned: 0x00000000
[ 13.301346]
[ 13.301346] Line: 923, netcp_allocate_rx_buf: Entered
[ 13.307865] Line: 929, netcp_allocate_rx_buf: knav_pool_desc_get return: hwdesc: 0xc0000020
[ 13.316217] Line: 940, netcp_allocate_rx_buf: knav_pool_desc_get return: hwdesc->next_desc: 0x00000000
[ 13.325527] Line: 1001, netcp_allocate_rx_buf: calling knav_queue_push: hwdesc: 0xc0000020
[ 13.333793] Line: 1004, netcp_allocate_rx_buf: calling knav_queue_push: hwdesc->next_desc: 0x00000000
[ 13.343014] Line: 1007, netcp_allocate_rx_buf: calling knav_queue_push: dma: 0x00000020
[ 13.351018]
[ 13.351018] Line: 1012, netcp_allocate_rx_buf: Normal Exit
[ 13.357973] Line: 1050, netcp_rxpool_refill: netcp_allocate_rx_buf[0] returned: 0x00000000
[ 13.366238]
[ 13.366238] Line: 923, netcp_allocate_rx_buf: Entered
[ 13.372770] Line: 929, netcp_allocate_rx_buf: knav_pool_desc_get return: hwdesc: 0xc0000030
[ 13.381123] Line: 940, netcp_allocate_rx_buf: knav_pool_desc_get return: hwdesc->next_desc: 0x00000000
[ 13.390432] Line: 1001, netcp_allocate_rx_buf: calling knav_queue_push: hwdesc: 0xc0000030
[ 13.398686] Line: 1004, netcp_allocate_rx_buf: calling knav_queue_push: hwdesc->next_desc: 0x00000000
[ 13.407906] Line: 1007, netcp_allocate_rx_buf: calling knav_queue_push: dma: 0x00000030
[ 13.415913]
[ 13.415913] Line: 1012, netcp_allocate_rx_buf: Normal Exit
[ 13.422876] Line: 1050, netcp_rxpool_refill: netcp_allocate_rx_buf[0] returned: 0x00000000
[ 13.431144]
[ 13.431144] Line: 923, netcp_allocate_rx_buf: Entered
[ 13.437663] Line: 929, netcp_allocate_rx_buf: knav_pool_desc_get return: hwdesc: 0xc0000040
[ 13.446016] Line: 940, netcp_allocate_rx_buf: knav_pool_desc_get return: hwdesc->next_desc: 0x00000000
[ 13.455326] Line: 1001, netcp_allocate_rx_buf: calling knav_queue_push: hwdesc: 0xc0000040
[ 13.463591] Line: 1004, netcp_allocate_rx_buf: calling knav_queue_push: hwdesc->next_desc: 0x00000000
[ 13.472813] Line: 1007, netcp_allocate_rx_buf: calling knav_queue_push: dma: 0x00000040
[ 13.480818]
[ 13.480818] Line: 1012, netcp_allocate_rx_buf: Normal Exit
[ 13.487773] Line: 1050, netcp_rxpool_refill: netcp_allocate_rx_buf[0] returned: 0x00000000
[ 13.496037]
[ 13.496037] Line: 923, netcp_allocate_rx_buf: Entered
[ 13.502570] Line: 929, netcp_allocate_rx_buf: knav_pool_desc_get return: hwdesc: 0xc0000050
[ 13.510922] Line: 940, netcp_allocate_rx_buf: knav_pool_desc_get return: hwdesc->next_desc: 0x00000000
[ 13.520232] Line: 1001, netcp_allocate_rx_buf: calling knav_queue_push: hwdesc: 0xc0000050
[ 13.528485] Line: 1004, netcp_allocate_rx_buf: calling knav_queue_push: hwdesc->next_desc: 0x00000000
[ 13.537706] Line: 1007, netcp_allocate_rx_buf: calling knav_queue_push: dma: 0x00000050
[ 13.545714]
[ 13.545714] Line: 1012, netcp_allocate_rx_buf: Normal Exit
[ 13.552677] Line: 1050, netcp_rxpool_refill: netcp_allocate_rx_buf[0] returned: 0x00000000
[ 13.560945]
[ 13.560945] Line: 923, netcp_allocate_rx_buf: Entered
[ 13.567464] Line: 929, netcp_allocate_rx_buf: knav_pool_desc_get return: hwdesc: 0xc0000060
[ 13.575816] Line: 940, netcp_allocate_rx_buf: knav_pool_desc_get return: hwdesc->next_desc: 0x00000000
[ 13.585126] Line: 1001, netcp_allocate_rx_buf: calling knav_queue_push: hwdesc: 0xc0000060
[ 13.593391] Line: 1004, netcp_allocate_rx_buf: calling knav_queue_push: hwdesc->next_desc: 0x00000000
[ 13.602612] Line: 1007, netcp_allocate_rx_buf: calling knav_queue_push: dma: 0x00000060
[ 13.610618]
[ 13.610618] Line: 1012, netcp_allocate_rx_buf: Normal Exit
[ 13.617573] Line: 1050, netcp_rxpool_refill: netcp_allocate_rx_buf[0] returned: 0x00000000
[ 13.625837]
[ 13.625837] Line: 923, netcp_allocate_rx_buf: Entered
[ 13.632369] Line: 929, netcp_allocate_rx_buf: knav_pool_desc_get return: hwdesc: 0xc0000070
[ 13.640721] Line: 940, netcp_allocate_rx_buf: knav_pool_desc_get return: hwdesc->next_desc: 0x00000740
[ 13.650031] Line: 1001, netcp_allocate_rx_buf: calling knav_queue_push: hwdesc: 0xc0000070
[ 13.658284] Line: 1004, netcp_allocate_rx_buf: calling knav_queue_push: hwdesc->next_desc: 0x00000000
[ 13.667504] Line: 1007, netcp_allocate_rx_buf: calling knav_queue_push: dma: 0x00000070
[ 13.675510]
[ 13.675510] Line: 1012, netcp_allocate_rx_buf: Normal Exit
[ 13.682474] Line: 1050, netcp_rxpool_refill: netcp_allocate_rx_buf[0] returned: 0x00000000
[ 13.690742]
[ 13.690742] Line: 923, netcp_allocate_rx_buf: Entered
[ 13.697260] Line: 929, netcp_allocate_rx_buf: knav_pool_desc_get return: hwdesc: 0xc0000080
[ 13.705612] Line: 940, netcp_allocate_rx_buf: knav_pool_desc_get return: hwdesc->next_desc: 0x00000740
[ 13.714922] Line: 1001, netcp_allocate_rx_buf: calling knav_queue_push: hwdesc: 0xc0000080
[ 13.723186] Line: 1004, netcp_allocate_rx_buf: calling knav_queue_push: hwdesc->next_desc: 0x00000000
[ 13.732409] Line: 1007, netcp_allocate_rx_buf: calling knav_queue_push: dma: 0x00000080
[ 13.740414]
[ 13.740414] Line: 1012, netcp_allocate_rx_buf: Normal Exit
[ 13.747368] Line: 1050, netcp_rxpool_refill: netcp_allocate_rx_buf[0] returned: 0x00000000
[ 13.755633]
[ 13.755633] Line: 923, netcp_allocate_rx_buf: Entered
[ 13.762164] Line: 929, netcp_allocate_rx_buf: knav_pool_desc_get return: hwdesc: 0xc0000090
[ 13.770517] Line: 940, netcp_allocate_rx_buf: knav_pool_desc_get return: hwdesc->next_desc: 0x00000740
[ 13.779826] Line: 1001, netcp_allocate_rx_buf: calling knav_queue_push: hwdesc: 0xc0000090
[ 13.788080] Line: 1004, netcp_allocate_rx_buf: calling knav_queue_push: hwdesc->next_desc: 0x00000000
[ 13.797299] Line: 1007, netcp_allocate_rx_buf: calling knav_queue_push: dma: 0x00000090
[ 13.805307]
[ 13.805307] Line: 1012, netcp_allocate_rx_buf: Normal Exit
[ 13.812271] Line: 1050, netcp_rxpool_refill: netcp_allocate_rx_buf[0] returned: 0x00000000
[ 13.820540]
[ 13.820540] Line: 923, netcp_allocate_rx_buf: Entered
[ 13.827059] Line: 929, netcp_allocate_rx_buf: knav_pool_desc_get return: hwdesc: 0xc00000a0
[ 13.835411] Line: 940, netcp_allocate_rx_buf: knav_pool_desc_get return: hwdesc->next_desc: 0x00000740
[ 13.844721] Line: 1001, netcp_allocate_rx_buf: calling knav_queue_push: hwdesc: 0xc00000a0
[ 13.852985] Line: 1004, netcp_allocate_rx_buf: calling knav_queue_push: hwdesc->next_desc: 0x00000000
[ 13.862208] Line: 1007, netcp_allocate_rx_buf: calling knav_queue_push: dma: 0x000000a0
[ 13.870212]
[ 13.870212] Line: 1012, netcp_allocate_rx_buf: Normal Exit
[ 13.877167] Line: 1050, netcp_rxpool_refill: netcp_allocate_rx_buf[0] returned: 0x00000000
[ 13.885432]
[ 13.885432] Line: 923, netcp_allocate_rx_buf: Entered
[ 13.891964] Line: 929, netcp_allocate_rx_buf: knav_pool_desc_get return: hwdesc: 0xc00000b0
[ 13.900316] Line: 940, netcp_allocate_rx_buf: knav_pool_desc_get return: hwdesc->next_desc: 0x00000740
[ 13.909625] Line: 1001, netcp_allocate_rx_buf: calling knav_queue_push: hwdesc: 0xc00000b0
[ 13.917878] Line: 1004, netcp_allocate_rx_buf: calling knav_queue_push: hwdesc->next_desc: 0x00000000
[ 13.927098] Line: 1007, netcp_allocate_rx_buf: calling knav_queue_push: dma: 0x000000b0
[ 13.935105]
[ 13.935105] Line: 1012, netcp_allocate_rx_buf: Normal Exit
[ 13.942069] Line: 1050, netcp_rxpool_refill: netcp_allocate_rx_buf[0] returned: 0x00000000
[ 13.950337]
[ 13.950337] Line: 923, netcp_allocate_rx_buf: Entered
[ 13.956857] Line: 929, netcp_allocate_rx_buf: knav_pool_desc_get return: hwdesc: 0xc00000c0
[ 13.965209] Line: 940, netcp_allocate_rx_buf: knav_pool_desc_get return: hwdesc->next_desc: 0x00000740
[ 13.974519] Line: 1001, netcp_allocate_rx_buf: calling knav_queue_push: hwdesc: 0xc00000c0
[ 13.982783] Line: 1004, netcp_allocate_rx_buf: calling knav_queue_push: hwdesc->next_desc: 0x00000000
[ 13.992005] Line: 1007, netcp_allocate_rx_buf: calling knav_queue_push: dma: 0x000000c0
[ 14.000010]
[ 14.000010] Line: 1012, netcp_allocate_rx_buf: Normal Exit
[ 14.006964] Line: 1050, netcp_rxpool_refill: netcp_allocate_rx_buf[0] returned: 0x00000000
[ 14.015229]
[ 14.015229] Line: 923, netcp_allocate_rx_buf: Entered
[ 14.021761] Line: 929, netcp_allocate_rx_buf: knav_pool_desc_get return: hwdesc: 0xc00000d0
[ 14.030114] Line: 940, netcp_allocate_rx_buf: knav_pool_desc_get return: hwdesc->next_desc: 0x00000740
[ 14.039423] Line: 1001, netcp_allocate_rx_buf: calling knav_queue_push: hwdesc: 0xc00000d0
[ 14.047677] Line: 1004, netcp_allocate_rx_buf: calling knav_queue_push: hwdesc->next_desc: 0x00000000
[ 14.056896] Line: 1007, netcp_allocate_rx_buf: calling knav_queue_push: dma: 0x000000d0
[ 14.064904]
[ 14.064904] Line: 1012, netcp_allocate_rx_buf: Normal Exit
[ 14.071867] Line: 1050, netcp_rxpool_refill: netcp_allocate_rx_buf[0] returned: 0x00000000
[ 14.080137]
[ 14.080137] Line: 923, netcp_allocate_rx_buf: Entered
[ 14.086656] Line: 929, netcp_allocate_rx_buf: knav_pool_desc_get return: hwdesc: 0xc00000e0
[ 14.095008] Line: 940, netcp_allocate_rx_buf: knav_pool_desc_get return: hwdesc->next_desc: 0x00000740
[ 14.104319] Line: 1001, netcp_allocate_rx_buf: calling knav_queue_push: hwdesc: 0xc00000e0
[ 14.112583] Line: 1004, netcp_allocate_rx_buf: calling knav_queue_push: hwdesc->next_desc: 0x00000000
[ 14.121805] Line: 1007, netcp_allocate_rx_buf: calling knav_queue_push: dma: 0x000000e0
[ 14.129809]
[ 14.129809] Line: 1012, netcp_allocate_rx_buf: Normal Exit
[ 14.136764] Line: 1050, netcp_rxpool_refill: netcp_allocate_rx_buf[0] returned: 0x00000000
[ 14.145029]
[ 14.145029] Line: 923, netcp_allocate_rx_buf: Entered
[ 14.151561] Line: 929, netcp_allocate_rx_buf: knav_pool_desc_get return: hwdesc: 0xc00000f0
[ 14.159913] Line: 940, netcp_allocate_rx_buf: knav_pool_desc_get return: hwdesc->next_desc: 0x00000740
[ 14.169222] Line: 1001, netcp_allocate_rx_buf: calling knav_queue_push: hwdesc: 0xc00000f0
[ 14.177476] Line: 1004, netcp_allocate_rx_buf: calling knav_queue_push: hwdesc->next_desc: 0x00000000
[ 14.186696] Line: 1007, netcp_allocate_rx_buf: calling knav_queue_push: dma: 0x000000f0
[ 14.194702]
[ 14.194702] Line: 1012, netcp_allocate_rx_buf: Normal Exit
[ 14.201666] Line: 1050, netcp_rxpool_refill: netcp_allocate_rx_buf[0] returned: 0x00000000
[ 14.209934]
[ 14.209934] Line: 923, netcp_allocate_rx_buf: Entered
[ 14.216453] Line: 929, netcp_allocate_rx_buf: knav_pool_desc_get return: hwdesc: 0xc0000100
[ 14.224806] Line: 940, netcp_allocate_rx_buf: knav_pool_desc_get return: hwdesc->next_desc: 0x00000740
[ 14.234118] Line: 1001, netcp_allocate_rx_buf: calling knav_queue_push: hwdesc: 0xc0000100
[ 14.242383] Line: 1004, netcp_allocate_rx_buf: calling knav_queue_push: hwdesc->next_desc: 0x00000000
[ 14.251605] Line: 1007, netcp_allocate_rx_buf: calling knav_queue_push: dma: 0x00000100
[ 14.259611]
[ 14.259611] Line: 1012, netcp_allocate_rx_buf: Normal Exit
[ 14.266564] Line: 1050, netcp_rxpool_refill: netcp_allocate_rx_buf[0] returned: 0x00000000
[ 14.274829]
[ 14.274829] Line: 923, netcp_allocate_rx_buf: Entered
[ 14.281360] Line: 929, netcp_allocate_rx_buf: knav_pool_desc_get return: hwdesc: 0xc0000110
[ 14.289713] Line: 940, netcp_allocate_rx_buf: knav_pool_desc_get return: hwdesc->next_desc: 0x00000740
[ 14.299026] Line: 1001, netcp_allocate_rx_buf: calling knav_queue_push: hwdesc: 0xc0000110
[ 14.307280] Line: 1004, netcp_allocate_rx_buf: calling knav_queue_push: hwdesc->next_desc: 0x00000000
[ 14.316500] Line: 1007, netcp_allocate_rx_buf: calling knav_queue_push: dma: 0x00000110
[ 14.324508]
[ 14.324508] Line: 1012, netcp_allocate_rx_buf: Normal Exit
[ 14.331471] Line: 1050, netcp_rxpool_refill: netcp_allocate_rx_buf[0] returned: 0x00000000
[ 14.339739]
[ 14.339739] Line: 923, netcp_allocate_rx_buf: Entered
[ 14.346258] Line: 929, netcp_allocate_rx_buf: knav_pool_desc_get return: hwdesc: 0xc0000120
[ 14.354610] Line: 940, netcp_allocate_rx_buf: knav_pool_desc_get return: hwdesc->next_desc: 0x00000740
[ 14.363921] Line: 1001, netcp_allocate_rx_buf: calling knav_queue_push: hwdesc: 0xc0000120
[ 14.372185] Line: 1004, netcp_allocate_rx_buf: calling knav_queue_push: hwdesc->next_desc: 0x00000000
[ 14.381407] Line: 1007, netcp_allocate_rx_buf: calling knav_queue_push: dma: 0x00000120
[ 14.389412]
[ 14.389412] Line: 1012, netcp_allocate_rx_buf: Normal Exit
[ 14.396366] Line: 1050, netcp_rxpool_refill: netcp_allocate_rx_buf[0] returned: 0x00000000
[ 14.404630]
[ 14.404630] Line: 923, netcp_allocate_rx_buf: Entered
[ 14.411162] Line: 929, netcp_allocate_rx_buf: knav_pool_desc_get return: hwdesc: 0xc0000130
[ 14.419514] Line: 940, netcp_allocate_rx_buf: knav_pool_desc_get return: hwdesc->next_desc: 0x00000740
[ 14.428811] Line: 1001, netcp_allocate_rx_buf: calling knav_queue_push: hwdesc: 0xc0000130
[ 14.437076] Line: 1004, netcp_allocate_rx_buf: calling knav_queue_push: hwdesc->next_desc: 0x00000000
[ 14.446299] Line: 1007, netcp_allocate_rx_buf: calling knav_queue_push: dma: 0x00000130
[ 14.454304]
[ 14.454304] Line: 1012, netcp_allocate_rx_buf: Normal Exit
[ 14.461270] Line: 1050, netcp_rxpool_refill: netcp_allocate_rx_buf[0] returned: 0x00000000
[ 14.469535]
[ 14.469535] Line: 923, netcp_allocate_rx_buf: Entered
[ 14.476056] Line: 929, netcp_allocate_rx_buf: knav_pool_desc_get return: hwdesc: 0xc0000140
[ 14.484408] Line: 940, netcp_allocate_rx_buf: knav_pool_desc_get return: hwdesc->next_desc: 0x00000740
[ 14.493718] Line: 1001, netcp_allocate_rx_buf: calling knav_queue_push: hwdesc: 0xc0000140
[ 14.501983] Line: 1004, netcp_allocate_rx_buf: calling knav_queue_push: hwdesc->next_desc: 0x00000000
[ 14.511204] Line: 1007, netcp_allocate_rx_buf: calling knav_queue_push: dma: 0x00000140
[ 14.519209]
[ 14.519209] Line: 1012, netcp_allocate_rx_buf: Normal Exit
[ 14.526164] Line: 1050, netcp_rxpool_refill: netcp_allocate_rx_buf[0] returned: 0x00000000
[ 14.534428]
[ 14.534428] Line: 923, netcp_allocate_rx_buf: Entered
[ 14.540960] Line: 929, netcp_allocate_rx_buf: knav_pool_desc_get return: hwdesc: 0xc0000150
[ 14.549312] Line: 940, netcp_allocate_rx_buf: knav_pool_desc_get return: hwdesc->next_desc: 0x00000740
[ 14.558610] Line: 1001, netcp_allocate_rx_buf: calling knav_queue_push: hwdesc: 0xc0000150
[ 14.566875] Line: 1004, netcp_allocate_rx_buf: calling knav_queue_push: hwdesc->next_desc: 0x00000000
[ 14.576097] Line: 1007, netcp_allocate_rx_buf: calling knav_queue_push: dma: 0x00000150
[ 14.584102]
[ 14.584102] Line: 1012, netcp_allocate_rx_buf: Normal Exit
[ 14.591068] Line: 1050, netcp_rxpool_refill: netcp_allocate_rx_buf[0] returned: 0x00000000
[ 14.599333]
[ 14.599333] Line: 923, netcp_allocate_rx_buf: Entered
[ 14.605854] Line: 929, netcp_allocate_rx_buf: knav_pool_desc_get return: hwdesc: 0xc0000160
[ 14.614206] Line: 940, netcp_allocate_rx_buf: knav_pool_desc_get return: hwdesc->next_desc: 0x00000740
[ 14.623515] Line: 1001, netcp_allocate_rx_buf: calling knav_queue_push: hwdesc: 0xc0000160
[ 14.631780] Line: 1004, netcp_allocate_rx_buf: calling knav_queue_push: hwdesc->next_desc: 0x00000000
[ 14.641002] Line: 1007, netcp_allocate_rx_buf: calling knav_queue_push: dma: 0x00000160
[ 14.649006]
[ 14.649006] Line: 1012, netcp_allocate_rx_buf: Normal Exit
[ 14.655961] Line: 1050, netcp_rxpool_refill: netcp_allocate_rx_buf[0] returned: 0x00000000
[ 14.664225]
[ 14.664225] Line: 923, netcp_allocate_rx_buf: Entered
[ 14.670757] Line: 929, netcp_allocate_rx_buf: knav_pool_desc_get return: hwdesc: 0xc0000170
[ 14.679109] Line: 940, netcp_allocate_rx_buf: knav_pool_desc_get return: hwdesc->next_desc: 0x00000740
[ 14.688406] Line: 1001, netcp_allocate_rx_buf: calling knav_queue_push: hwdesc: 0xc0000170
[ 14.696671] Line: 1004, netcp_allocate_rx_buf: calling knav_queue_push: hwdesc->next_desc: 0x00000000
[ 14.705894] Line: 1007, netcp_allocate_rx_buf: calling knav_queue_push: dma: 0x00000170
[ 14.713899]
[ 14.713899] Line: 1012, netcp_allocate_rx_buf: Normal Exit
[ 14.720865] Line: 1050, netcp_rxpool_refill: netcp_allocate_rx_buf[0] returned: 0x00000000
[ 14.729130]
[ 14.729130] Line: 923, netcp_allocate_rx_buf: Entered
[ 14.735652] Line: 929, netcp_allocate_rx_buf: knav_pool_desc_get return: hwdesc: 0xfffffff4
[ 14.744003] Line: 933, netcp_allocate_rx_buf: out of rx pool desc
[ 14.750100] Line: 1050, netcp_rxpool_refill: netcp_allocate_rx_buf[0] returned: 0xfffffff4
[ 14.758354] Line: 1032, netcp_rxpool_refill: netcp->rx_queue_depths[1]:128
[ 14.765234] Line: 1036, netcp_rxpool_refill: knav_queue_get_count(netcp->rx_fdq[1]):0
[ 14.773067] Line: 1043, netcp_rxpool_refill: fdq_deficit[1]:128
[ 14.779035] Line: 1208, netcp_tx_map_skb: knav_pool_desc_get returned: desc: 0xfffffff4
[ 14.787031] net eth0: out of TX desc
[ 18.128969] ------------[ cut here ]------------
[ 18.133593] WARNING: CPU: 0 PID: 0 at net/sched/sch_generic.c:461 dev_watchdog+0x248/0x24c
[ 18.141864] NETDEV WATCHDOG: eth0 (netcp-1.0): transmit queue 0 timed out
[ 18.148646] Modules linked in:
[ 18.151713] CPU: 0 PID: 0 Comm: swapper/0 Not tainted 4.19.38-g4dae378bbe #24
[ 18.158842] Hardware name: Keystone
[ 18.162335] [<c021122c>] (unwind_backtrace) from [<c020c41c>] (show_stack+0x10/0x14)
[ 18.170074] [<c020c41c>] (show_stack) from [<c08a1840>] (dump_stack+0x78/0x8c)
[ 18.177294] [<c08a1840>] (dump_stack) from [<c0220540>] (__warn.part.3+0xbc/0xd8)
[ 18.184773] [<c0220540>] (__warn.part.3) from [<c02205c4>] (warn_slowpath_fmt+0x68/0x8c)
[ 18.192859] [<c02205c4>] (warn_slowpath_fmt) from [<c074faec>] (dev_watchdog+0x248/0x24c)
[ 18.201033] [<c074faec>] (dev_watchdog) from [<c028aab0>] (call_timer_fn+0x3c/0x200)
[ 18.208770] [<c028aab0>] (call_timer_fn) from [<c028ae44>] (run_timer_softirq+0x1d0/0x2a4)
[ 18.217029] [<c028ae44>] (run_timer_softirq) from [<c0202184>] (__do_softirq+0x14c/0x420)
[ 18.225201] [<c0202184>] (__do_softirq) from [<c02261a4>] (irq_exit+0x94/0xa0)
[ 18.232418] [<c02261a4>] (irq_exit) from [<c02722ec>] (__handle_domain_irq+0x80/0xec)
[ 18.240246] [<c02722ec>] (__handle_domain_irq) from [<c05411e0>] (gic_handle_irq+0x4c/0x90)
[ 18.248591] [<c05411e0>] (gic_handle_irq) from [<c02019f8>] (__irq_svc+0x58/0x8c)
[ 18.256064] Exception stack(0xc1001f20 to 0xc1001f68)
[ 18.261108] 1f20: 00000000 00005098 ecba748c c021a560 ffffe000 c10060bc c1006104 00000001
[ 18.269278] 1f40: c1064193 c0b73c38 c106d540 c1006088 00000000 c1001f70 c0209148 c020914c
[ 18.277445] 1f60: 60010013 ffffffff
[ 18.280932] [<c02019f8>] (__irq_svc) from [<c020914c>] (arch_cpu_idle+0x38/0x3c)
[ 18.288324] [<c020914c>] (arch_cpu_idle) from [<c024e5cc>] (do_idle+0xf4/0x134)
[ 18.295627] [<c024e5cc>] (do_idle) from [<c024e8d4>] (cpu_startup_entry+0x18/0x1c)
[ 18.303192] [<c024e8d4>] (cpu_startup_entry) from [<c0e00e2c>] (start_kernel+0x460/0x490)
[ 18.311375] ---[ end trace 1dffe896bf553e22 ]---
[ 18.315987] net eth0: transmit timed out tx descs(1022)
[ 18.321259] Line: 1208, netcp_tx_map_skb: knav_pool_desc_get returned: desc: 0xc0000010
[ 18.329271] Line: 1217, netcp_tx_map_skb: set_pkt_info: desc->next_desc: 0x00000000
[ 18.336922] Line: 1208, netcp_tx_map_skb: knav_pool_desc_get returned: desc: 0xc0000020
[ 18.344933] Line: 1217, netcp_tx_map_skb: set_pkt_info: desc->next_desc: 0x00000000
[ 18.352596] Line: 1208, netcp_tx_map_skb: knav_pool_desc_get returned: desc: 0xc0000030
[ 18.360606] Line: 1217, netcp_tx_map_skb: set_pkt_info: desc->next_desc: 0x00000000
[ 18.368256] Line: 1208, netcp_tx_map_skb: knav_pool_desc_get returned: desc: 0xc0000040
[ 18.376265] Line: 1217, netcp_tx_map_skb: set_pkt_info: desc->next_desc: 0x00000000
[ 18.383928] Line: 1208, netcp_tx_map_skb: knav_pool_desc_get returned: desc: 0xc0000050
[ 18.391936] Line: 1217, netcp_tx_map_skb: set_pkt_info: desc->next_desc: 0x00000000
[ 18.399599] Line: 1208, netcp_tx_map_skb: knav_pool_desc_get returned: desc: 0xc0000060
[ 18.407593] Line: 1217, netcp_tx_map_skb: set_pkt_info: desc->next_desc: 0x00000000
[ 18.415255] Line: 1208, netcp_tx_map_skb: knav_pool_desc_get returned: desc: 0xc0000070
[ 18.423262] Line: 1217, netcp_tx_map_skb: set_pkt_info: desc->next_desc: 0x00000000
[ 18.430924] Line: 1208, netcp_tx_map_skb: knav_pool_desc_get returned: desc: 0xc0000080
[ 18.438918] Line: 1217, netcp_tx_map_skb: set_pkt_info: desc->next_desc: 0x00000000
[ 18.446581] Line: 1208, netcp_tx_map_skb: knav_pool_desc_get returned: desc: 0xc0000090
[ 18.454589] Line: 1217, netcp_tx_map_skb: set_pkt_info: desc->next_desc: 0x00000000
[ 18.462251] Line: 1208, netcp_tx_map_skb: knav_pool_desc_get returned: desc: 0xc00000a0
[ 18.470259] Line: 1217, netcp_tx_map_skb: set_pkt_info: desc->next_desc: 0x00000000
[ 18.478049] Line: 1208, netcp_tx_map_skb: knav_pool_desc_get returned: desc: 0xc00000b0
[ 18.486063] Line: 1217, netcp_tx_map_skb: set_pkt_info: desc->next_desc: 0x00000000
[ 18.493757] Line: 1208, netcp_tx_map_skb: knav_pool_desc_get returned: desc: 0xc00000c0
[ 18.501766] Line: 1217, netcp_tx_map_skb: set_pkt_info: desc->next_desc: 0x00000000
[ 18.509429] Line: 1208, netcp_tx_map_skb: knav_pool_desc_get returned: desc: 0xc00000d0
[ 18.517422] Line: 1217, netcp_tx_map_skb: set_pkt_info: desc->next_desc: 0x00000000
[ 18.525085] Line: 1208, netcp_tx_map_skb: knav_pool_desc_get returned: desc: 0xc00000e0
[ 18.533092] Line: 1217, netcp_tx_map_skb: set_pkt_info: desc->next_desc: 0x00000000
[ 18.664031] Line: 1208, netcp_tx_map_skb: knav_pool_desc_get returned: desc: 0xc00000f0
[ 18.672038] Line: 1217, netcp_tx_map_skb: set_pkt_info: desc->next_desc: 0x00000000
[ 19.576386] Line: 1208, netcp_tx_map_skb: knav_pool_desc_get returned: desc: 0xc0000100
[ 19.584394] Line: 1217, netcp_tx_map_skb: set_pkt_info: desc->next_desc: 0x00000000
[ 21.996277] Line: 1208, netcp_tx_map_skb: knav_pool_desc_get returned: desc: 0xc0000110
[ 22.004283] Line: 1217, netcp_tx_map_skb: set_pkt_info: desc->next_desc: 0x00000000
[ 22.591025] Line: 1208, netcp_tx_map_skb: knav_pool_desc_get returned: desc: 0xc0000120
[ 22.599033] Line: 1217, netcp_tx_map_skb: set_pkt_info: desc->next_desc: 0x00000000
[ 24.238135] netcp_process_one_rx_packet: knav_pool_desc_unmap returned: desc: 0xc0000010
[ 24.246226] netcp_process_one_rx_packet: get_pkt_info returned: (dma_addr_t)buff: 0x0000005a
[ 24.254665] netcp_process_one_rx_packet: get_pkt_info returned: (dma_addr_t)buf_len: 0x0000005a
[ 24.263368] Line: 692, netcp_process_one_rx_packet: get_pkt_info returned: (dma_addr_t)next_desc: 0x00000001
[ 24.273195] Line: 698, netcp_process_one_rx_packet: org_buf_ptr: 0x00000046
[ 24.280161] Line: 700, netcp_process_one_rx_packet: org_buf_len: 0x00000001
[ 24.287113] Line: 720, netcp_process_one_rx_packet: Calling build_skb with args: org_buf_ptr: 0x00000046
[ 24.296594] Line: 722, netcp_process_one_rx_packet: Calling build_skb with args: org_buf_len: 0x00000001
[ 24.306079] Unable to handle kernel paging request at virtual address ffffff47
[ 24.313304] pgd = 6bc10873
[ 24.316006] [ffffff47] *pgd=80000080007003, *pmd=adbfd003, *pte=00000000
[ 24.322727] Internal error: Oops: a07 [#1] PREEMPT SMP ARM
[ 24.328205] Modules linked in:
[ 24.331257] CPU: 0 PID: 0 Comm: swapper/0 Tainted: G W 4.19.38-g4dae378bbe #24
[ 24.339771] Hardware name: Keystone
[ 24.343262] PC is at memset+0x9c/0xa8
[ 24.346919] LR is at __build_skb+0x80/0x98
[ 24.351007] pc : [<c08a039c>] lr : [<c0708a30>] psr: 20030113
[ 24.357264] sp : c1001d58 ip : ffffff47 fp : 00000046
[ 24.362480] r10: 00000001 r9 : c1006088 r8 : c0bc0a5c
[ 24.367696] r7 : ec41d4c0 r6 : 00000001 r5 : 000000c1 r4 : cad77180
[ 24.374214] r3 : 00000003 r2 : 00000030 r1 : 00000000 r0 : ffffff47
[ 24.380734] Flags: nzCv IRQs on FIQs on Mode SVC_32 ISA ARM Segment user
[ 24.387860] Control: 30c5387d Table: 8ac3aac0 DAC: fffffffd
[ 24.393597] Process swapper/0 (pid: 0, stack limit = 0xaf0642e9)
[ 24.399594] Stack: (0xc1001d58 to 0xc1002000)
[ 24.403943] 1d40: 00000046 00000001
[ 24.412111] 1d60: ec41d510 c0708a58 00000000 00000040 ec41d510 c064006c 00000030 00000000
[ 24.420280] 1d80: c1001de0 c0360ad4 00000000 c0bc0aa8 c0bc0af8 00000001 c0000010 0000005a
[ 24.428448] 1da0: 00000001 c02608c8 00000000 00000001 c103d944 00000030 0000005a 00000000
[ 24.436617] 1dc0: c1006618 00000001 c0e61484 ecba7484 c10064c0 00000001 c0e64e84 00000000
[ 24.444785] 1de0: 00000060 00000001 c1001f20 6e5c1634 c1006618 ec41d510 00000001 00000040
[ 24.452953] 1e00: c0e65d80 c1003d00 c1001e44 ffff9443 0000012c c071fe50 ffffffff c10672b8
[ 24.461122] 1e20: c1006088 ecbabd80 c0e65d80 2bd46000 ffffe000 c10060bc c10069f4 c0e64ec0
[ 24.469290] 1e40: c0e64ec0 c1001e44 c1001e44 c1001e4c c1001e4c 6e5c1634 ec430000 c100308c
[ 24.477458] 1e60: 00000004 00000003 00000008 c1064644 00000101 c0b6e938 ffffe000 c0202184
[ 24.485626] 1e80: c1006088 00000001 c1003080 c0e5d308 c0e64ec0 c10060bc 0000000a c0bb01bc
[ 24.493795] 1ea0: ffff9443 c1003d00 00200102 c08bcba0 cacc2800 c0e64ec0 00000000 00000060
[ 24.501963] 1ec0: 00000001 00000000 c1001f20 ec430000 c1006088 c02261a4 c0e64e84 c02722ec
[ 24.510131] 1ee0: c1006618 c10372ac ee00200c ee002000 c1001f20 ee003000 c106d540 c05411e0
[ 24.518299] 1f00: c020914c 60030013 ffffffff c1001f54 c1064193 c1000000 c106d540 c02019f8
[ 24.526467] 1f20: 00000000 00006310 ecba748c c021a560 ffffe000 c10060bc c1006104 00000001
[ 24.534635] 1f40: c1064193 c0b73c38 c106d540 c1006088 00000000 c1001f70 c0209148 c020914c
[ 24.542804] 1f60: 60030013 ffffffff c1064193 00000000 ffffe000 c024e5cc 000000c5 00000000
[ 24.550972] 1f80: 00000000 c106d540 c1006080 ffffffff c106d540 c024e8d4 c106d58c c0e00e2c
[ 24.559141] 1fa0: ffffffff ffffffff 00000000 c0e00584 ffffffff 00000000 00000000 c0e51a30
[ 24.567309] 1fc0: 00000000 c0e51a30 6e580512 00000000 00000000 c0e00330 00000000 30c0387d
[ 24.575477] 1fe0: 00000000 87000000 412fc0f4 30c5387d 00000000 00000000 00000000 00000000
[ 24.583650] [<c08a039c>] (memset) from [<c0708a30>] (__build_skb+0x80/0x98)
[ 24.590606] [<c0708a30>] (__build_skb) from [<c0708a58>] (build_skb+0x10/0x64)
[ 24.597825] [<c0708a58>] (build_skb) from [<c064006c>] (netcp_rx_poll+0x284/0x650)
[ 24.605391] [<c064006c>] (netcp_rx_poll) from [<c071fe50>] (net_rx_action+0x130/0x41c)
[ 24.613304] [<c071fe50>] (net_rx_action) from [<c0202184>] (__do_softirq+0x14c/0x420)
[ 24.621127] [<c0202184>] (__do_softirq) from [<c02261a4>] (irq_exit+0x94/0xa0)
[ 24.628342] [<c02261a4>] (irq_exit) from [<c02722ec>] (__handle_domain_irq+0x80/0xec)
[ 24.636167] [<c02722ec>] (__handle_domain_irq) from [<c05411e0>] (gic_handle_irq+0x4c/0x90)
[ 24.644510] [<c05411e0>] (gic_handle_irq) from [<c02019f8>] (__irq_svc+0x58/0x8c)
[ 24.651983] Exception stack(0xc1001f20 to 0xc1001f68)
[ 24.657027] 1f20: 00000000 00006310 ecba748c c021a560 ffffe000 c10060bc c1006104 00000001
[ 24.665195] 1f40: c1064193 c0b73c38 c106d540 c1006088 00000000 c1001f70 c0209148 c020914c
[ 24.673361] 1f60: 60030013 ffffffff
[ 24.676847] [<c02019f8>] (__irq_svc) from [<c020914c>] (arch_cpu_idle+0x38/0x3c)
[ 24.684237] [<c020914c>] (arch_cpu_idle) from [<c024e5cc>] (do_idle+0xf4/0x134)
[ 24.691540] [<c024e5cc>] (do_idle) from [<c024e8d4>] (cpu_startup_entry+0x18/0x1c)
[ 24.699104] [<c024e8d4>] (cpu_startup_entry) from [<c0e00e2c>] (start_kernel+0x460/0x490)
[ 24.707275] Code: bafffff7 e3530002 b4cc1001 d4cc1001 (e4cc1001)
[ 24.713376] ---[ end trace 1dffe896bf553e23 ]---
[ 24.717987] Kernel panic - not syncing: Fatal exception in interrupt
[ 24.724340] CPU3: stopping
[ 24.727044] CPU: 3 PID: 0 Comm: swapper/3 Tainted: G D W 4.19.38-g4dae378bbe #24
[ 24.735558] Hardware name: Keystone
[ 24.739047] [<c021122c>] (unwind_backtrace) from [<c020c41c>] (show_stack+0x10/0x14)
[ 24.746784] [<c020c41c>] (show_stack) from [<c08a1840>] (dump_stack+0x78/0x8c)
[ 24.754001] [<c08a1840>] (dump_stack) from [<c021022c>] (handle_IPI+0x3f0/0x418)
[ 24.761394] [<c021022c>] (handle_IPI) from [<c0541220>] (gic_handle_irq+0x8c/0x90)
[ 24.768958] [<c0541220>] (gic_handle_irq) from [<c02019f8>] (__irq_svc+0x58/0x8c)
[ 24.776431] Exception stack(0xec49ff78 to 0xec49ffc0)
[ 24.781474] ff60: caa1264c 00000080
[ 24.789643] ff80: 00000000 00000000 ffffe000 c10060bc c1006104 00000008 c1064193 c0b73c38
[ 24.797813] ffa0: 00000000 00000000 00000000 ec49ffc8 c024e548 c08bcf74 600b0013 ffffffff
[ 24.805987] [<c02019f8>] (__irq_svc) from [<c08bcf74>] (_raw_read_unlock+0x30/0x64)
[ 24.813638] [<c08bcf74>] (_raw_read_unlock) from [<c024e548>] (do_idle+0x70/0x134)
[ 24.821201] [<c024e548>] (do_idle) from [<c024e8d4>] (cpu_startup_entry+0x18/0x1c)
[ 24.828763] [<c024e8d4>] (cpu_startup_entry) from [<802024ec>] (0x802024ec)
[ 24.835717] CPU1: stopping
[ 24.838422] CPU: 1 PID: 1314 Comm: systemd-journal Tainted: G D W 4.19.38-g4dae378bbe #24
[ 24.847717] Hardware name: Keystone
[ 24.851207] [<c021122c>] (unwind_backtrace) from [<c020c41c>] (show_stack+0x10/0x14)
[ 24.858944] [<c020c41c>] (show_stack) from [<c08a1840>] (dump_stack+0x78/0x8c)
[ 24.866161] [<c08a1840>] (dump_stack) from [<c021022c>] (handle_IPI+0x3f0/0x418)
[ 24.873553] [<c021022c>] (handle_IPI) from [<c0541220>] (gic_handle_irq+0x8c/0x90)
[ 24.881118] [<c0541220>] (gic_handle_irq) from [<c0201d44>] (__irq_usr+0x44/0x60)
[ 24.888591] Exception stack(0xcad79fb0 to 0xcad79ff8)
[ 24.893635] 9fa0: b6f13448 79797979 6d6c6b6a 71706f6e
[ 24.901805] 9fc0: 00000000 00000000 00000000 00000000 00000079 be80af3e b6f1342c 00000000
[ 24.909974] 9fe0: 01010101 be80aee8 b6eead4c b6cc51f4 60000830 ffffffff
[ 24.916580] CPU2: stopping
[ 24.919284] CPU: 2 PID: 0 Comm: swapper/2 Tainted: G D W 4.19.38-g4dae378bbe #24
[ 24.927799] Hardware name: Keystone
[ 24.931287] [<c021122c>] (unwind_backtrace) from [<c020c41c>] (show_stack+0x10/0x14)
[ 24.939025] [<c020c41c>] (show_stack) from [<c08a1840>] (dump_stack+0x78/0x8c)
[ 24.946241] [<c08a1840>] (dump_stack) from [<c021022c>] (handle_IPI+0x3f0/0x418)
[ 24.953632] [<c021022c>] (handle_IPI) from [<c0541220>] (gic_handle_irq+0x8c/0x90)
[ 24.961196] [<c0541220>] (gic_handle_irq) from [<c02019f8>] (__irq_svc+0x58/0x8c)
[ 24.968669] Exception stack(0xec49df78 to 0xec49dfc0)
[ 24.973712] df60: 00000000 00006110
[ 24.981882] df80: ecbc748c c021a560 ffffe000 c10060bc c1006104 00000004 c1064193 c0b73c38
[ 24.990051] dfa0: 00000000 00000000 00000000 ec49dfc8 c0209148 c020914c 600f0013 ffffffff
[ 24.998224] [<c02019f8>] (__irq_svc) from [<c020914c>] (arch_cpu_idle+0x38/0x3c)
[ 25.005615] [<c020914c>] (arch_cpu_idle) from [<c024e5cc>] (do_idle+0xf4/0x134)
[ 25.012917] [<c024e5cc>] (do_idle) from [<c024e8d4>] (cpu_startup_entry+0x18/0x1c)
[ 25.020480] [<c024e8d4>] (cpu_startup_entry) from [<802024ec>] (0x802024ec)
[ 25.027442] ---[ end Kernel panic - not syncing: Fatal exception in interrupt ]---

  • Hi, Guy,

    Your board was successful when running kernel 3.10, but not 4.19. My understanding of this is you were using MCSDK before and the u-boot on the board has been upgraded to ProcSDK. When you upgrade the u-boot, have the u-boot env variables been reset with "env default -f -a" command?

    By looking at the command to boot:

    => tftpboot 0x87000000 bae-dmniu-k2e-guy.dtb; tftpboot 0x82000000 zImage_guy_4.19.38; run init_${boot} init_fw_rd_${boot}; run get_mon_${boot} run_mon ;bootz 0x82000000 - 0x87000000 0x88000000
    

    It is different from the u-boot script in PLSDK:

    bootcmd=run findfdt; run envboot; run init_${boot}; run get_mon_${boot} run_mon; run set_name_pmmc get_pmmc_${boot} run_pmmc; run get_kern_${boot}; run init_fw_rd_${boot}; run get_fdt_${boot}; run run_kern

    Please note the new step on "get_pmmc_${boot}", also, the bootz takes ${rd_spec} as in "run_kern=bootz ${loadaddr} ${rd_spec} ${fdtaddr}" where "set_rd_spec=setenv rd_spec ${rdaddr}:${filesize}" which I don't see the filesize in the bootz command of yours.

    It apparantly that you are booting using ubi boot. I would suggest that you follow the boot=ubi for the u-boot env setup and be sure they set up the way TI's EVM setup. I am not sure if these are the root causes of not booting, but something to start with to align to what TI's env.

    Rex

  • We switch back and forth between booting the board via tftp and booting from the NAND flash from the boot partition in the UBI volume.  We typically boot unstable test kernels via tftp since to burn them into the boot partition would mean writing an image into flash that cannot boot completely from start up.  

    We have the bootargs set so that whether we boot from nand or tftp boot, the bootargs are the same, the kernel, initramfs, .dtb image are being loaded from the same memory addresses.

    So, I don't believe that our problems are caused by using tftp versus 

    If we disable the networking stack in the kernel by not enabling the NETCP subsystem, then the kernel boots and we can burn this into the boot partition and the board boots from power up.  But we don't have any networking.

    If we turn on the NETCP subsystem in the kernel, then the kernel crashes on boot when the netcp / knav_qmss code is allocating dma buffers.

    The issue is that when we run this same kernel on the K2E EVM, we get no crashes.  

    And the only major difference we can tell is that the K2E has a 66AK2E05 and our board has an AM5K2E04.

    Is there some difference between the two devices (66AK2E05 vs AM5K2E04) that would lead to this.

  • Hi, Guy,

    You missed the point. TFTP is not the focus, but the following are missing or different from TI's in your bootcmd line, "run init_fw_rd_${boot}", "set_rd_spec=setenv rd_spec ${rdaddr}:${filesize}", and "bootz ${loadaddr} ${rd_spec} ${fdtaddr}".

    I don;t have the latest K2H u-boot printenv, so I took that from K2G device. In K2G, it also includes "get_pmmc". I don't know if this is K2G specific or generic to all KS2 familiy devices. I suggest you compare your u-boot env variable configuration with TI EVM's to be sure nothing missing.

    You need to be compliant with these boot steps and init_fw_rd be loaded. The bootz takes extra argument which is also missing in your bootcmd. At least these are what I see missing from your bootcmd. Compare with TI's setup and see if anything is missing or different. If you boot using NAND/UBI, follow the setup with boot=ubi. If you use tftp boot, follow boot=net for the env variables setup. That is is booting from UBI, you should follow init_ubi for the init_${boot}, and init_fw_rd_ubi for init_fw_rd_${boot}.

    Rex

  • The PMMC subsystem in the K2G processor has no equivalent in the K2E, that I can see.  The PMMC has a Cortex M3 processor and there is no such subsystem in the K2E device.

    The crash we are experiencing is in the knav_qmss_queue.c code which loads the firmware file, knav_qmss_pdsp_acc48.bin.

    The fact that we don't initialize the init_fw_rd_... variable is also not material since the file referenced by this variable, i.e. k2-fw-initrd.cpio.gz is a cpio.gz archive of firmware files that we have already in our file system archive in /lib/firmware... The files in our /lib/firmware root file system are identical to the files in k2-fw-initrd-cpio.gz, specifically, the knav_qmss_pdsp_acc48.bin files are binary identical.

    So, none of the items you have mentioned have anything to do with our problem.

    We cannot run with the ubi file system as the root file system since our board is part of an embedded system which can have the power removed at anytime without notice.  We therefore run from an initramfs and mount the ubi volume later.  Writes to the ubi volume and ubifs partitions are limited to maintenance operations thereby ensuring that the file system never gets corrupted.  So, we cannot and will not modify our boot sequence to be exactly like the EVM, since we operate under different conditions. 

    Guy Winter

  • Hi, Guy,

    I reviewed your boot logs and it seems to me that the GbE serdes is not loaded. On TI EVM, I see the following lines:

    [ 0.415961] keystone_irq 26202a0.keystone_irq: irqchip registered, nr_irqs 28
    [ 0.416780] ti,keystone-serdes 232a000.phy: init fw ks2_gbe_serdes.bin: version 3.3.0.2c
    [ 0.421514] keystone-navigator-qmss soc@0:qmss@2a40000: qmgr start queue 0, number of queues 8192

    but they don't appears in your logs. Hence, further down the logs, I don't see the ethernet link comes up as TI:

    [ 2.765162] Loading compiled-in X.509 certificates
    [ 2.775788] hctosys: unable to open rtc device (rtc0)
    [ 2.987171] netcp-1.0 2620110.netcp eth0: Link is Down
    [ 2.994472] IPv6: ADDRCONF(NETDEV_UP): eth0: link is not ready
    [ 3.126685] (NULL net_device): Link is Down
    [ 3.130884] (NULL net_device): Link is Down
    [ 3.207163] netcp-1.0 2620110.netcp eth1: Link is Down
    [ 3.213176] IPv6: ADDRCONF(NETDEV_UP): eth1: link is not ready
    [ 6.247796] netcp-1.0 2620110.netcp eth0: Link is Up - 1Gbps/Full - flow control rx/tx
    [ 6.276719] IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready

    You may want to look into why the serdes driver isn't loaded. 

    Rex

  • I don't know why it wasn't in our earlier logs, but we definitely load the serdes binary for the gige Ethernet.

    [ 3.079917] keystone_irq 26202a0.keystone_irq: irqchip registered, nr_irqs 28

    [ 3.087446] ti,keystone-serdes 2320000.phy: init fw ks2_pcie_serdes.bin: version 3.3.0.2c
    [ 3.096316] ti,keystone-serdes 2326000.phy: init fw ks2_pcie_serdes.bin: version 3.3.0.2c
    [ 3.105338] ti,keystone-serdes 232a000.phy: init fw ks2_gbe_serdes.bin: version 3.3.0.2c
    [ 3.114812] keystone-pcie 21800000.pcie: host bridge /soc@0/pcie@21800000 ranges:
    [ 3.122366] keystone-pcie 21800000.pcie: IO 0x50000000..0x500fffff -> 0x00000000
    [ 3.130084] keystone-pcie 21800000.pcie: MEM 0x50100000..0x5fffffff -> 0x50100000
    [ 3.138218] keystone-pcie 21800000.pcie: failed to initialize host
    [ 3.144700] keystone-pcie 21020000.pcie: host bridge /soc@0/pcie@21020000 ranges:
    [ 3.152247] keystone-pcie 21020000.pcie: IO 0x60000000..0x600fffff -> 0x00000000
    [ 3.159998] keystone-pcie 21020000.pcie: MEM 0x60100000..0x6fffffff -> 0x60100000
    [ 4.158429] keystone-pcie 21020000.pcie: Phy link never came up
    [ 4.164469] keystone-pcie 21020000.pcie: PCI host bridge to bus 0000:00

    Guy Winter

  • Guy,

    Can you attach the latest console output including the u-boot printenv and kernel boot logs?

    Rex