Linux/AM5728: Jailhouse RTOS inmate error

Nir Geller

Part Number: AM5728
Other Parts Discussed in Thread: TPIC2810, DRA752, TPD12S015, PMP

Tool/software: Linux

Hi there,

I'm working with Linux processor-sdk 05.02.00.10 and RTOS processor-sdk 4.03.00.05.
I successfully worked with jailhouse hypervisor on the AM5728 evaluation board.

Now I'm trying to startup jailhouse on a AM5728 based custom board.

I can startup my SYS/BIOS inmate application and debug it. During the init phase I get the famous dump from the kernel:

[ 147.605931] ------------[ cut here ]------------
[ 147.610600] WARNING: CPU: 0 PID: 28 at /home/stx-ti/Projects/tisdk/build/arago-tmp-external-linaro-toolchain/work-shared/am57xx-evm/kernel-source/drivers/bus/omap_l3_noc.c:147 l3_interrupt_handler+0x254/0x370
[ 147.629222] 44000000.ocp:L3 Custom Error: MASTER MPU TARGET GPMC (Read): Data Access in User mode during Functional access
[ 147.640328] Modules linked in: jailhouse(O) vnetdevice(O) sha512_generic sha512_arm sha256_generic sha1_generic sha1_arm md5 cbc xhci_plat_hcd xhci_hcd usbcore dwc3 udc_core usb_common ti_prueth pru_rproc pruss pruss_intc snd_soc_omap_hdmi_audio omap_aes_driver pruss_soc_bus c_can_platform c_can can_dev omap_sham omap_wdt phy_omap_usb2 ahci_platform libahci_platform libahci libata scsi_mod ti_vpe ti_vip ti_sc ti_csc ti_vpdma dwc3_omap rtc_omap extcon_palmas rtc_palmas gpio_tpic2810 ov2659 v4l2_fwnode omap_des des_generic crypto_engine omap_crypto omap_remoteproc virtio_rpmsg_bus rpmsg_core remoteproc sch_fq_codel cryptodev(O)
[ 147.696164] CPU: 0 PID: 28 Comm: irq/23-l3-app-i Tainted: G O 4.14.79-rt47-gd9200ca684 #9
[ 147.696167] Hardware name: Generic DRA74X (Flattened Device Tree)
[ 147.696169] Backtrace:
[ 147.696187] [<c010b808>] (dump_backtrace) from [<c010baec>] (show_stack+0x18/0x1c)
[ 147.696194] r7:00000009 r6:60030013 r5:00000000 r4:c0d575a0
[ 147.696204] [<c010bad4>] (show_stack) from [<c082245c>] (dump_stack+0x90/0xa4)
[ 147.696215] [<c08223cc>] (dump_stack) from [<c012aea0>] (__warn+0xec/0x104)
[ 147.696220] r7:00000009 r6:c0ad5790 r5:00000000 r4:d4a31e40
[ 147.696229] [<c012adb4>] (__warn) from [<c012aef8>] (warn_slowpath_fmt+0x40/0x48)
[ 147.696234] r9:0000000b r8:d4a14b10 r7:c0ad55fc r6:00000002 r5:c0ad56bc r4:c0ad5760
[ 147.696243] [<c012aebc>] (warn_slowpath_fmt) from [<c041e108>] (l3_interrupt_handler+0x254/0x370)
[ 147.696246] r3:d4a14980 r2:c0ad5760
[ 147.696249] r4:80080003
[ 147.696258] [<c041deb4>] (l3_interrupt_handler) from [<c0181dd8>] (irq_forced_thread_fn+0x28/0x7c)
[ 147.696263] r10:c0181db0 r9:d4a14e80 r8:d49df100 r7:00000001 r6:00000000 r5:d49df100
[ 147.696265] r4:d4a14e80
[ 147.696272] [<c0181db0>] (irq_forced_thread_fn) from [<c0182130>] (irq_thread+0x130/0x208)
[ 147.696276] r7:00000001 r6:00000000 r5:ffffe000 r4:d4a14ea4
[ 147.696284] [<c0182000>] (irq_thread) from [<c0149368>] (kthread+0x164/0x16c)
[ 147.696289] r10:d4871b20 r9:c0182000 r8:d4a14e80 r7:d4a30000 r6:00000000 r5:d4a14ec0
[ 147.696291] r4:d49e3080
[ 147.696299] [<c0149204>] (kthread) from [<c0107a90>] (ret_from_fork+0x14/0x24)
[ 147.696303] r10:00000000 r9:00000000 r8:00000000 r7:00000000 r6:00000000 r5:c0149204
[ 147.696306] r4:d4a14ec0
[ 147.696308] ---[ end trace 0000000000000002 ]---

Specifically this dump is generated when SPI_open() goes down all the way to McSPIReset(), which attempts to read and write McSPI4 register MCSPI_SYSSTATUS(0x480BA114).

Additionally when the error is generated GPMC related registers are set to:
GPMC_ERR_ADDRESS (0x50000044) = 0x0480B110
GPMC_ERR_TYPE (0x50000048) = 0x00000211

Previously I made sure that ALL the SPI's are disabled in the Linux kernel dts, and SPI is disabled in the kernel config, so I'm trying to figure out what causes this error.

Going through AM572x_Sitara_Processors_Technical_Reference_Manual, L3_MAIN Interconnect (fascinating stuff) I found out that a configurable firewall exists, and
an interrupt is generated to the Cortex-A15 INTC upon protection violation. (14.2.3.7.3.4 L3_MAIN Firewall Error Logging)

I suspect the firewall needs to be configured to allow user access, but I'm not sure where and how.
I also don't understand why an attempt to read a SPI related register causes an error in GPMC.

In attempt to prevent the interrupt, setting the GPMC_FW registers
GPMC_FW_START_REGION_i_1 (0x4A210090) = 0x480BA000
GPMC_FW_END_REGION_i_1 (0x4A210094) = 0x480BB000
didn't change the behavior.

Can you please explain why SPI is related to GPMC, and more important how can I configure the firewall to allow user access to SPI registers?

Thanks a lot,

Nir.

over 6 years ago

0 Nir Geller over 6 years ago

Expert 1140 points

After reading 14.3.3.3 L4 Firewalls

and more specific

14.3.4.1.2.3.1 Main Sequence: L4 Interconnect Firewall Configuration Mode,

maybe L4 firewall should be configured to allow access to McSPI4?

If so, I didn't understand how to do so. Could you please give an example?

An attempt to write L4_AP_SEGMENT_i_L (0x4800 0100) or L4_AP_REGION_l_L (0x4800 0300) fails. The registers still hold the value 0x00000000

Thanks,

Nir.

0 Garrett Ding over 6 years ago in reply to Nir Geller

TI__Mastermind 43296 points

[edited]

Nir,

>>Previously I made sure that ALL the SPI's are disabled in the Linux kernel dts, and SPI is disabled in the kernel config,

Does the kernel crash happen only when both SPI and GPMC are enabled, and if either one is disabled, the crash goes away?

Have you tried to test your application on AM57x evaluation board?

>>Can you please explain why SPI is related to GPMC

The drivers are really independent, but maybe there are pins need to be properly multiplexed on your board, have you confirmed the pinmux is correctly generated?

Regards,

Garrett

0 Rasty Slutsker over 6 years ago in reply to Garrett Ding

Expert 1910 points

Hi Garret,

We do not find where linux or TI-RTOS configure firewall.

I'd like you team to focus on question "can I configure the firewall to allow user access to SPI registers and any other peripheral behind firewall ?"

Reference to Linux and TI-RTOS source that deal with this will help.

Examples would be even better.

Best regards

Rasty

0 Garrett Ding over 6 years ago in reply to Rasty Slutsker

TI__Mastermind 43296 points

Hi Rasty,

>>An attempt to write L4_AP_SEGMENT_i_L (0x4800 0100) or L4_AP_REGION_l_L (0x4800 0300) fails. The registers still hold the value 0x00000000

It appears that you have followed the procedure described in Table 14-413. Firewall Configuration. There is no example of the firewall configuration for L4 interconnect in PRSDK or Linux. I will check if we have some internal verification code for your reference.

Have you looked into the CTRL_CORE_SEC_ERR_STATUS_FUNC_1 (0x4A00 2148) bit 16 - L4_PERIPH1_FW_ERROR, and CTRL_CORE_SEC_ERR_STATUS_DEBUG_1(0x4A00 2150) bit 16 for possible error from firewall?

With regard to the kernel message '[ 147.629222] 44000000.ocp:L3 Custom Error: MASTER MPU TARGET GPMC (Read): Data Access in User mode during Functional access', there are a few related threads in the forum and kernel.org, but none of them seems to indicate this is a firewall configuration issue -

e2e.ti.com/.../437761

Regards,
Garrett

0 Nir Geller over 6 years ago in reply to Garrett Ding

Expert 1140 points

Hi Garrett,

Before and after causing the kernel dump, both registers CTRL_CORE_SEC_ERR_STATUS_FUNC_1 (0x4A00 2148) and CTRL_CORE_SEC_ERR_STATUS_DEBUG_1(0x4A00 2150) hold the value 0x0.
Maybe it hints that this is not a firewall issue after all.

During RTOS inmate start up I have a ClockInit function that sets up all the relevant clocks.
This function enables all four

CM_L4PER_MCSPI1_CLKCTRL 0x4A00 97F0
CM_L4PER_MCSPI2_CLKCTRL 0x4A00 97F8
CM_L4PER_MCSPI3_CLKCTRL 0x4A00 9800
CM_L4PER_MCSPI4_CLKCTRL 0x4A00 9808

After this is done, on the linux side I can read the base addresses of all 4 McSPIs

#McSPI1
root@am57xx-evm:~# devmem2 0x48098000

#McSPI2
root@am57xx-evm:~# devmem2 0x4809A000

#McSPI3
root@am57xx-evm:~# devmem2 0x480B8000

#McSPI4
root@am57xx-evm:~# devmem2 0x480BA000

However, on the RTOS inamte side I can read/write only the address spaces of McSPI 1,2 and 3.
I can't read any McSPI4 memory related address. The read/write fails and the linux kernel throws the famous dump.

SPI is completely disabled both in the dts and the kernel config.

CM_L4PER_MCSPI4_CLKCTRL (0x4A00 9808) is set properly to 0x00000002

Why can't the RTOS inmate access McSPI4 address space?

Thanks a lot,

Nir.

0 Garrett Ding over 6 years ago in reply to Nir Geller

TI__Mastermind 43296 points

Nir.

>>on the RTOS inamte side I can read/write only the address spaces of McSPI 1,2 and 3. I can't read any McSPI4 memory related address.

This is interesting. Is this also reproducible on TI IDK so we can look into locally?

>>SPI is completely disabled both in the dts and the kernel config.

Can you please post the complete Linux kernel boot log here?

Regards,

Garrett

0 Nir Geller over 6 years ago in reply to Garrett Ding

Expert 1140 points

Please see attached dmesg

[ 0.000000] Booting Linux on physical CPU 0x0
[ 0.000000] Linux version 4.14.79-rt47-gd9200ca684 (oe-user@oe-host) (gcc version 7.2.1 20171011 (Linaro GCC 7.2-2017.11)) #12 SMP PREEMPT RT Wed Jul 24 10:25:28 UTC 2019
[ 0.000000] CPU: ARMv7 Processor [412fc0f2] revision 2 (ARMv7), cr=10c5387d
[ 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: TI AM5728 JAILHOUSE IDK
[ 0.000000] Memory policy: Data cache writealloc
[ 0.000000] efi: Getting EFI parameters from FDT:
[ 0.000000] efi: UEFI not found.
[ 0.000000] Reserved memory: created CMA memory pool at 0x95800000, size 56 MiB
[ 0.000000] OF: reserved mem: initialized node ipu2-memory@95800000, compatible id shared-dma-pool
[ 0.000000] Reserved memory: created CMA memory pool at 0x99000000, size 64 MiB
[ 0.000000] OF: reserved mem: initialized node dsp1-memory@99000000, compatible id shared-dma-pool
[ 0.000000] Reserved memory: created CMA memory pool at 0x9d000000, size 32 MiB
[ 0.000000] OF: reserved mem: initialized node ipu1-memory@9d000000, compatible id shared-dma-pool
[ 0.000000] Reserved memory: created CMA memory pool at 0x9f000000, size 8 MiB
[ 0.000000] OF: reserved mem: initialized node dsp2-memory@9f000000, compatible id shared-dma-pool
[ 0.000000] cma: Reserved 24 MiB at 0xbe400000
[ 0.000000] OMAP4: Map 0xbfe00000 to fe600000 for dram barrier
[ 0.000000] On node 0 totalpages: 196096
[ 0.000000] free_area_init_node: node 0, pgdat c0d57640, node_mem_map d4ea8000
[ 0.000000] Normal zone: 1134 pages used for memmap
[ 0.000000] Normal zone: 0 pages reserved
[ 0.000000] Normal zone: 129024 pages, LIFO batch:31
[ 0.000000] HighMem zone: 67072 pages, LIFO batch:15
[ 0.000000] DRA752 ES2.0
[ 0.000000] percpu: Embedded 15 pages/cpu @d4e3a000 s29792 r8192 d23456 u61440
[ 0.000000] pcpu-alloc: s29792 r8192 d23456 u61440 alloc=15*4096
[ 0.000000] pcpu-alloc: [0] 0 [0] 1
[ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 194962
[ 0.000000] Kernel command line: console=ttyO9,115200n8 root=PARTUUID=5b37c775-02 rw rootfstype=ext4 rootwait vmalloc=512M
[ 0.000000] PID hash table entries: 2048 (order: 1, 8192 bytes)
[ 0.000000] Dentry cache hash table entries: 65536 (order: 7, 524288 bytes)
[ 0.000000] Inode-cache hash table entries: 32768 (order: 5, 131072 bytes)
[ 0.000000] Memory: 574568K/784384K available (8192K kernel code, 355K rwdata, 2596K rodata, 1024K init, 287K bss, 21400K reserved, 188416K cma-reserved, 243712K highmem)
[ 0.000000] Virtual kernel memory layout:
vector : 0xffff0000 - 0xffff1000 ( 4 kB)
fixmap : 0xffc00000 - 0xfff00000 (3072 kB)
vmalloc : 0xe0000000 - 0xff800000 ( 504 MB)
lowmem : 0xc0000000 - 0xdf800000 ( 504 MB)
pkmap : 0xbfe00000 - 0xc0000000 ( 2 MB)
modules : 0xbf000000 - 0xbfe00000 ( 14 MB)
.text : 0xc0008000 - 0xc0900000 (9184 kB)
.init : 0xc0c00000 - 0xc0d00000 (1024 kB)
.data : 0xc0d00000 - 0xc0d58f00 ( 356 kB)
.bss : 0xc0d5a000 - 0xc0da1c50 ( 288 kB)
[ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=2, Nodes=1
[ 0.000000] Preemptible hierarchical RCU implementation.
[ 0.000000] RCU priority boosting: priority 1 delay 500 ms.
[ 0.000000] No expedited grace period (rcu_normal_after_boot).
[ 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] OMAP clockevent source: timer1 at 32786 Hz
[ 0.000000] arch_timer: cp15 timer(s) running at 6.14MHz (phys).
[ 0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x16af5adb9, max_idle_ns: 440795202250 ns
[ 0.000005] sched_clock: 56 bits at 6MHz, resolution 162ns, wraps every 4398046511023ns
[ 0.000011] Switching to timer-based delay loop, resolution 162ns
[ 0.000334] clocksource: 32k_counter: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 58327039986419 ns
[ 0.000337] OMAP clocksource: 32k_counter at 32768 Hz
[ 0.000798] Console: colour dummy device 80x30
[ 0.000921] WARNING: Your 'console=ttyO9' has been replaced by 'ttyS9'
[ 0.000922] This ensures that you still see kernel messages. Please
[ 0.000924] update your kernel commandline.
[ 0.000940] Calibrating delay loop (skipped), value calculated using timer frequency.. 12.29 BogoMIPS (lpj=61475)
[ 0.000947] pid_max: default: 32768 minimum: 301
[ 0.001081] Mount-cache hash table entries: 1024 (order: 0, 4096 bytes)
[ 0.001087] Mountpoint-cache hash table entries: 1024 (order: 0, 4096 bytes)
[ 0.001762] CPU: Testing write buffer coherency: ok
[ 0.001795] CPU0: Spectre v2: using ICIALLU workaround
[ 0.002043] /cpus/cpu@0 missing clock-frequency property
[ 0.002080] /cpus/cpu@1 missing clock-frequency property
[ 0.002092] CPU0: thread -1, cpu 0, socket 0, mpidr 80000000
[ 0.039930] Setting up static identity map for 0x80100000 - 0x80100060
[ 0.079915] Hierarchical SRCU implementation.
[ 0.140418] EFI services will not be available.
[ 0.160041] smp: Bringing up secondary CPUs ...
[ 0.260391] CPU1: thread -1, cpu 1, socket 0, mpidr 80000001
[ 0.260395] CPU1: Spectre v2: using ICIALLU workaround
[ 0.260506] smp: Brought up 1 node, 2 CPUs
[ 0.260522] SMP: Total of 2 processors activated (24.59 BogoMIPS).
[ 0.260529] CPU: All CPU(s) started in HYP mode.
[ 0.260536] CPU: Virtualization extensions available.
[ 0.261869] devtmpfs: initialized
[ 0.292635] random: get_random_u32 called from bucket_table_alloc+0x14c/0x288 with crng_init=0
[ 0.292959] VFP support v0.3: implementor 41 architecture 4 part 30 variant f rev 0
[ 0.293215] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns
[ 0.293234] futex hash table entries: 512 (order: 3, 32768 bytes)
[ 0.297543] pinctrl core: initialized pinctrl subsystem
[ 0.298377] DMI not present or invalid.
[ 0.298854] NET: Registered protocol family 16
[ 0.301592] DMA: preallocated 256 KiB pool for atomic coherent allocations
[ 0.302563] omap_hwmod: l3_main_2 using broken dt data from ocp
[ 0.439736] omap_hwmod: gpu: _wait_target_ready failed: -16
[ 0.439750] omap_hwmod: gpu: cannot be enabled for reset (3)
[ 0.519027] gpio gpiochip0: (gpio): added GPIO chardev (254:0)
[ 0.519133] gpiochip_setup_dev: registered GPIOs 0 to 31 on device: gpiochip0 (gpio)
[ 0.519366] OMAP GPIO hardware version 0.1
[ 0.520321] gpio gpiochip1: (gpio): added GPIO chardev (254:1)
[ 0.520423] gpiochip_setup_dev: registered GPIOs 32 to 63 on device: gpiochip1 (gpio)
[ 0.521483] gpio gpiochip2: (gpio): added GPIO chardev (254:2)
[ 0.521586] gpiochip_setup_dev: registered GPIOs 64 to 95 on device: gpiochip2 (gpio)
[ 0.522623] gpio gpiochip3: (gpio): added GPIO chardev (254:3)
[ 0.522741] gpiochip_setup_dev: registered GPIOs 96 to 127 on device: gpiochip3 (gpio)
[ 0.523774] gpio gpiochip4: (gpio): added GPIO chardev (254:4)
[ 0.523877] gpiochip_setup_dev: registered GPIOs 128 to 159 on device: gpiochip4 (gpio)
[ 0.524889] gpio gpiochip5: (gpio): added GPIO chardev (254:5)
[ 0.524992] gpiochip_setup_dev: registered GPIOs 160 to 191 on device: gpiochip5 (gpio)
[ 0.526016] gpio gpiochip6: (gpio): added GPIO chardev (254:6)
[ 0.526116] gpiochip_setup_dev: registered GPIOs 192 to 223 on device: gpiochip6 (gpio)
[ 0.552639] hw-breakpoint: found 5 (+1 reserved) breakpoint and 4 watchpoint registers.
[ 0.552651] hw-breakpoint: maximum watchpoint size is 8 bytes.
[ 0.553056] omap4_sram_init:Unable to allocate sram needed to handle errata I688
[ 0.553066] omap4_sram_init:Unable to get sram pool needed to handle errata I688
[ 0.554321] OMAP DMA hardware revision 0.0
[ 0.570573] edma 43300000.edma: memcpy is disabled
[ 0.576561] edma 43300000.edma: TI EDMA DMA engine driver
[ 0.589056] omap-dma-engine 4a056000.dma-controller: OMAP DMA engine driver (LinkedList1/2/3 supported)
[ 0.589801] of_get_named_gpiod_flags: can't parse 'gpio' property of node '/fixedregulator-vmain[0]'
[ 0.590173] of_get_named_gpiod_flags: can't parse 'gpio' property of node '/fixedregulator-v3_3d[0]'
[ 0.590576] of_get_named_gpiod_flags: can't parse 'gpio' property of node '/fixedregulator-vtt[0]'
[ 0.594795] omap-iommu 40d01000.mmu: 40d01000.mmu registered
[ 0.595279] omap-iommu 40d02000.mmu: 40d02000.mmu registered
[ 0.595848] omap-iommu 58882000.mmu: 58882000.mmu registered
[ 0.596417] omap-iommu 55082000.mmu: 55082000.mmu registered
[ 0.597131] omap-iommu 41501000.mmu: 41501000.mmu registered
[ 0.597606] omap-iommu 41502000.mmu: 41502000.mmu registered
[ 0.598068] iommu: Adding device 58820000.ipu to group 1
[ 0.598264] iommu: Adding device 55020000.ipu to group 2
[ 0.598606] iommu: Adding device 40800000.dsp to group 0
[ 0.599189] iommu: Adding device 41000000.dsp to group 3
[ 0.602137] palmas 0-0058: Irq flag is 0x00000004
[ 0.632749] palmas 0-0058: Muxing GPIO 2b, PWM 0, LED 0
[ 0.634473] SMPS12: supplied by regulator-dummy
[ 0.636316] SMPS3: supplied by VMAIN
[ 0.636706] smps3: Bringing 1500000uV into 1350000-1350000uV
[ 0.638873] SMPS45: supplied by regulator-dummy
[ 0.640867] SMPS6: supplied by VMAIN
[ 0.642926] SMPS7: supplied by VMAIN
[ 0.645068] SMPS8: supplied by VMAIN
[ 0.646641] SMPS9: supplied by VMAIN
[ 0.647893] LDO1: supplied by VMAIN
[ 0.652020] LDO2: supplied by VMAIN
[ 0.661881] LDO3: supplied by VMAIN
[ 0.671917] LDO4: supplied by VMAIN
[ 0.681898] LDO5: supplied by regulator-dummy
[ 0.682881] LDO6: supplied by regulator-dummy
[ 0.683867] LDO7: supplied by regulator-dummy
[ 0.684862] LDO8: supplied by regulator-dummy
[ 0.685824] LDO9: supplied by VMAIN
[ 0.692284] LDOLN: supplied by VMAIN
[ 0.701962] LDOUSB: supplied by VMAIN
[ 0.715166] gpiochip_find_base: found new base at 504
[ 0.715370] gpio gpiochip7: (48070000.i2c:tps659038@58:tps659038_gpio): added GPIO chardev (254:7)
[ 0.715478] gpiochip_setup_dev: registered GPIOs 504 to 511 on device: gpiochip7 (48070000.i2c:tps659038@58:tps659038_gpio)
[ 0.716162] omap_i2c 48070000.i2c: bus 0 rev0.12 at 400 kHz
[ 0.716457] media: Linux media interface: v0.10
[ 0.716516] Linux video capture interface: v2.00
[ 0.716644] pps_core: LinuxPPS API ver. 1 registered
[ 0.716653] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>
[ 0.716677] PTP clock support registered
[ 0.716714] EDAC MC: Ver: 3.0.0
[ 0.717115] dmi: Firmware registration failed.
[ 0.717698] omap-mailbox 48840000.mailbox: omap mailbox rev 0x400
[ 0.718104] omap-mailbox 48842000.mailbox: omap mailbox rev 0x400
[ 0.718668] Advanced Linux Sound Architecture Driver Initialized.
[ 0.719808] clocksource: Switched to clocksource arch_sys_counter
[ 0.732464] NET: Registered protocol family 2
[ 0.733255] TCP established hash table entries: 4096 (order: 2, 16384 bytes)
[ 0.733308] TCP bind hash table entries: 4096 (order: 4, 114688 bytes)
[ 0.733451] TCP: Hash tables configured (established 4096 bind 4096)
[ 0.733587] UDP hash table entries: 256 (order: 2, 16384 bytes)
[ 0.733621] UDP-Lite hash table entries: 256 (order: 2, 16384 bytes)
[ 0.733887] NET: Registered protocol family 1
[ 0.734455] RPC: Registered named UNIX socket transport module.
[ 0.734467] RPC: Registered udp transport module.
[ 0.734476] RPC: Registered tcp transport module.
[ 0.734485] RPC: Registered tcp NFSv4.1 backchannel transport module.
[ 0.734498] PCI: CLS 0 bytes, default 64
[ 0.735868] hw perfevents: no interrupt-affinity property for /pmu, guessing.
[ 0.736197] hw perfevents: enabled with armv7_cortex_a15 PMU driver, 7 counters available
[ 0.738080] workingset: timestamp_bits=14 max_order=18 bucket_order=4
[ 0.745036] squashfs: version 4.0 (2009/01/31) Phillip Lougher
[ 0.745894] NFS: Registering the id_resolver key type
[ 0.745929] Key type id_resolver registered
[ 0.745939] Key type id_legacy registered
[ 0.746013] ntfs: driver 2.1.32 [Flags: R/O].
[ 0.748003] bounce: pool size: 64 pages
[ 0.748063] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 245)
[ 0.748076] io scheduler noop registered
[ 0.748086] io scheduler deadline registered
[ 0.748228] io scheduler cfq registered (default)
[ 0.748240] io scheduler mq-deadline registered
[ 0.748250] io scheduler kyber registered
[ 0.754771] pinctrl-single 4a003400.pinmux: 282 pins at pa fc003400 size 1128
[ 0.759306] dra7-pcie 51000000.pcie: Linked as a consumer to phy-4a094000.pciephy.1
[ 0.759550] dra7-pcie 51000000.pcie: GPIO lookup for consumer (null)
[ 0.759557] dra7-pcie 51000000.pcie: using device tree for GPIO lookup
[ 0.759592] of_get_named_gpiod_flags: parsed 'gpios' property of node '/ocp/axi@0/pcie@51000000[0]' - status (0)
[ 0.759922] OF: PCI: host bridge /ocp/axi@0/pcie@51000000 ranges:
[ 0.759958] OF: PCI: IO 0x20003000..0x20012fff -> 0x00000000
[ 0.759984] OF: PCI: MEM 0x20013000..0x2fffffff -> 0x20013000
[ 1.759826] dra7-pcie 51000000.pcie: phy link never came up
[ 1.760045] dra7-pcie 51000000.pcie: PCI host bridge to bus 0000:00
[ 1.760064] pci_bus 0000:00: root bus resource [bus 00-ff]
[ 1.760076] pci_bus 0000:00: root bus resource [io 0x0000-0xffff]
[ 1.760086] pci_bus 0000:00: root bus resource [mem 0x20013000-0x2fffffff]
[ 1.760130] pci 0000:00:00.0: [104c:8888] type 01 class 0x060400
[ 1.760163] pci 0000:00:00.0: reg 0x10: [mem 0x00000000-0x000fffff 64bit]
[ 1.760230] pci 0000:00:00.0: supports D1
[ 1.760237] pci 0000:00:00.0: PME# supported from D0 D1 D3hot
[ 1.760492] PCI: bus0: Fast back to back transfers disabled
[ 1.760628] PCI: bus1: Fast back to back transfers enabled
[ 1.760671] pci 0000:00:00.0: BAR 0: assigned [mem 0x20100000-0x201fffff 64bit]
[ 1.760690] pci 0000:00:00.0: PCI bridge to [bus 01-ff]
[ 1.761349] pcieport 0000:00:00.0: Signaling PME with IRQ 168
[ 1.761632] pcieport 0000:00:00.0: AER enabled with IRQ 168
[ 1.767677] V3_3D: supplied by smps9
[ 1.767994] vtt_fixed: supplied by V3_3D
[ 1.842813] Serial: 8250/16550 driver, 10 ports, IRQ sharing disabled
[ 1.847475] 4ae2b000.serial: ttyS9 at MMIO 0x4ae2b000 (irq = 44, base_baud = 3000000) is a 8250
[ 2.918363] console [ttyS9] enabled
[ 2.924542] omap_rng 48090000.rng: Random Number Generator ver. 20
[ 2.932029] DSS: OMAP DSS rev 6.1
[ 2.937940] omapdss_dss 58000000.dss: bound 58001000.dispc (ops dispc_component_ops)
[ 2.946708] omapdss_dss 58000000.dss: bound 58040000.encoder (ops hdmi5_component_ops)
[ 2.956433] tpd12s015 encoder@0: GPIO lookup for consumer (null)
[ 2.956441] tpd12s015 encoder@0: using device tree for GPIO lookup
[ 2.956452] of_get_named_gpiod_flags: can't parse 'gpios' property of node '/encoder@0[0]'
[ 2.956460] of_get_named_gpiod_flags: can't parse 'gpio' property of node '/encoder@0[0]'
[ 2.956468] tpd12s015 encoder@0: using lookup tables for GPIO lookup
[ 2.956475] tpd12s015 encoder@0: lookup for GPIO (null) failed
[ 2.956483] tpd12s015 encoder@0: GPIO lookup for consumer (null)
[ 2.956489] tpd12s015 encoder@0: using device tree for GPIO lookup
[ 2.956497] of_get_named_gpiod_flags: can't parse 'gpios' property of node '/encoder@0[1]'
[ 2.956505] of_get_named_gpiod_flags: can't parse 'gpio' property of node '/encoder@0[1]'
[ 2.956511] tpd12s015 encoder@0: using lookup tables for GPIO lookup
[ 2.956518] tpd12s015 encoder@0: lookup for GPIO (null) failed
[ 2.956525] tpd12s015 encoder@0: GPIO lookup for consumer (null)
[ 2.956530] tpd12s015 encoder@0: using device tree for GPIO lookup
[ 2.956562] of_get_named_gpiod_flags: parsed 'gpios' property of node '/encoder@0[2]' - status (0)
[ 2.958130] of_get_named_gpiod_flags: can't parse 'hpd-gpios' property of node '/connector@0[0]'
[ 2.975439] loop: module loaded
[ 2.983165] mdio_bus fixed-0: GPIO lookup for consumer reset
[ 2.983173] mdio_bus fixed-0: using lookup tables for GPIO lookup
[ 2.983181] mdio_bus fixed-0: lookup for GPIO reset failed
[ 2.983207] libphy: Fixed MDIO Bus: probed
[ 2.991709] mdio_bus 48485000.mdio: GPIO lookup for consumer reset
[ 2.991717] mdio_bus 48485000.mdio: using lookup tables for GPIO lookup
[ 2.991724] mdio_bus 48485000.mdio: lookup for GPIO reset failed
[ 3.059841] davinci_mdio 48485000.mdio: davinci mdio revision 1.6, bus freq 1000000
[ 3.067538] davinci_mdio 48485000.mdio: detected phy mask fffffffe
[ 3.086314] libphy: 48485000.mdio: probed
[ 3.090351] davinci_mdio 48485000.mdio: phy[0]: device 48485000.mdio:00, driver Micrel KSZ9031 Gigabit PHY
[ 3.101344] cpsw 48484000.ethernet: Detected MACID = 88:3f:4a:4b:c5:82
[ 3.107996] cpsw 48484000.ethernet: initialized cpsw ale version 1.4
[ 3.114382] cpsw 48484000.ethernet: ALE Table size 1024
[ 3.119646] cpsw 48484000.ethernet: device node lookup for pps timer failed
[ 3.126683] cpsw 48484000.ethernet: cpts: overflow check period 500 (jiffies)
[ 3.135049] cpsw 48484000.ethernet: cpsw: Detected MACID = 88:3f:4a:4b:c5:83
[ 3.144211] i2c /dev entries driver
[ 3.149150] IR NEC protocol handler initialized
[ 3.153704] IR RC5(x/sz) protocol handler initialized
[ 3.158781] IR RC6 protocol handler initialized
[ 3.163333] IR JVC protocol handler initialized
[ 3.167885] IR Sony protocol handler initialized
[ 3.172524] IR SANYO protocol handler initialized
[ 3.177249] IR Sharp protocol handler initialized
[ 3.182116] IR MCE Keyboard/mouse protocol handler initialized
[ 3.187975] IR XMP protocol handler initialized
[ 3.202085] sdhci: Secure Digital Host Controller Interface driver
[ 3.208296] sdhci: Copyright(c) Pierre Ossman
[ 3.213542] sdhci-pltfm: SDHCI platform and OF driver helper
[ 3.220635] sdhci-omap 480b4000.mmc: GPIO lookup for consumer wp
[ 3.220642] sdhci-omap 480b4000.mmc: using device tree for GPIO lookup
[ 3.220654] of_get_named_gpiod_flags: can't parse 'wp-gpios' property of node '/ocp/mmc@480b4000[0]'
[ 3.220662] of_get_named_gpiod_flags: can't parse 'wp-gpio' property of node '/ocp/mmc@480b4000[0]'
[ 3.220669] sdhci-omap 480b4000.mmc: using lookup tables for GPIO lookup
[ 3.220676] sdhci-omap 480b4000.mmc: lookup for GPIO wp failed
[ 3.289900] mmc0: SDHCI controller on 480b4000.mmc [480b4000.mmc] using ADMA
[ 3.299964] of_get_named_gpiod_flags: parsed 'gpios' property of node '/status-leds/cpu0-led[0]' - status (0)
[ 3.300005] no flags found for gpios
[ 3.300165] of_get_named_gpiod_flags: parsed 'gpios' property of node '/status-leds/usr0-led[0]' - status (0)
[ 3.300179] no flags found for gpios
[ 3.300326] of_get_named_gpiod_flags: parsed 'gpios' property of node '/status-leds/heartbeat-led[0]' - status (0)
[ 3.300339] no flags found for gpios
[ 3.300482] of_get_named_gpiod_flags: parsed 'gpios' property of node '/status-leds/cpu1-led[0]' - status (0)
[ 3.300495] no flags found for gpios
[ 3.300646] of_get_named_gpiod_flags: parsed 'gpios' property of node '/status-leds/usr1-led[0]' - status (0)
[ 3.300659] no flags found for gpios
[ 3.300808] of_get_named_gpiod_flags: parsed 'gpios' property of node '/status-leds/mmc0-led[0]' - status (0)
[ 3.300821] no flags found for gpios
[ 3.309068] NET: Registered protocol family 10
[ 3.314960] Segment Routing with IPv6
[ 3.318737] sit: IPv6, IPv4 and MPLS over IPv4 tunneling driver
[ 3.325426] NET: Registered protocol family 17
[ 3.330365] Key type dns_resolver registered
[ 3.334811] omap_voltage_late_init: Voltage driver support not added
[ 3.341198] Power Management for TI OMAP4+ devices.
[ 3.346430] Registering SWP/SWPB emulation handler
[ 3.358778] mmc0: new DDR MMC card at address 0001
[ 3.364172] mmcblk0: mmc0:0001 Q2J54A 3.64 GiB
[ 3.379915] mmcblk0boot0: mmc0:0001 Q2J54A partition 1 2.00 MiB
[ 3.386158] mmcblk0boot1: mmc0:0001 Q2J54A partition 2 2.00 MiB
[ 3.392413] mmcblk0rpmb: mmc0:0001 Q2J54A partition 3 512 KiB
[ 3.399660] mmcblk0: p1 p2
[ 3.404536] dmm 4e000000.dmm: workaround for errata i878 in use
[ 3.412959] dmm 4e000000.dmm: initialized all PAT entries
[ 3.420759] [drm] Supports vblank timestamp caching Rev 2 (21.10.2013).
[ 3.427408] [drm] No driver support for vblank timestamp query.
[ 3.561991] EDID block is all zeroes
[ 3.578136] [drm] Enabling DMM ywrap scrolling
[ 3.606388] Console: switching to colour frame buffer device 128x48
[ 3.618709] omapdrm omapdrm.0: fb0: omapdrm frame buffer device
[ 3.626011] [drm] Initialized omapdrm 1.0.0 20110917 for omapdrm.0 on minor 0
[ 3.633946] hctosys: unable to open rtc device (rtc0)
[ 3.640464] pbias_mmc_omap5: disabling
[ 3.644251] ALSA device list:
[ 3.647236] No soundcards found.
[ 3.954604] EXT4-fs (mmcblk0p2): recovery complete
[ 3.960258] EXT4-fs (mmcblk0p2): mounted filesystem with ordered data mode. Opts: (null)
[ 3.968447] VFS: Mounted root (ext4 filesystem) on device 179:2.
[ 3.975350] devtmpfs: mounted
[ 3.979933] Freeing unused kernel memory: 1024K
[ 4.128941] systemd[1]: System time before build time, advancing clock.
[ 4.173161] 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)
[ 4.194687] systemd[1]: Detected architecture arm.
[ 4.230588] systemd[1]: Set hostname to <am57xx-evm>.
[ 4.542790] random: systemd: uninitialized urandom read (16 bytes read)
[ 4.561146] systemd[1]: Listening on Process Core Dump Socket.
[ 4.590228] random: systemd: uninitialized urandom read (16 bytes read)
[ 4.597174] systemd[1]: Listening on Journal Socket (/dev/log).
[ 4.629980] random: systemd: uninitialized urandom read (16 bytes read)
[ 4.640587] systemd[1]: Created slice User and Session Slice.
[ 4.670400] systemd[1]: Started Forward Password Requests to Wall Directory Watch.
[ 4.710313] systemd[1]: Listening on Network Service Netlink Socket.
[ 4.750448] systemd[1]: Listening on Journal Socket.
[ 4.771324] EDID block is all zeroes
[ 4.780062] systemd[1]: Reached target Swap.
[ 5.120193] cryptodev: loading out-of-tree module taints kernel.
[ 5.127479] cryptodev: driver 1.9 loaded.
[ 5.312756] EXT4-fs (mmcblk0p2): re-mounted. Opts: (null)
[ 6.017299] systemd-journald[119]: Received request to flush runtime journal from PID 1
[ 7.593718] omap-rproc 58820000.ipu: assigned reserved memory node ipu1-memory@9d000000
[ 7.617653] remoteproc remoteproc0: 58820000.ipu is available
[ 7.644154] remoteproc remoteproc0: Direct firmware load for dra7-ipu1-fw.xem4 failed with error -2
[ 7.653615] remoteproc remoteproc0: powering up 58820000.ipu
[ 7.671156] omap-rproc 55020000.ipu: assigned reserved memory node ipu2-memory@95800000
[ 7.681610] remoteproc remoteproc0: Direct firmware load for dra7-ipu1-fw.xem4 failed with error -2
[ 7.690816] remoteproc remoteproc0: request_firmware failed: -2
[ 7.706504] remoteproc remoteproc1: 55020000.ipu is available
[ 7.712485] remoteproc remoteproc1: Direct firmware load for dra7-ipu2-fw.xem4 failed with error -2
[ 7.731472] remoteproc remoteproc1: powering up 55020000.ipu
[ 7.737206] remoteproc remoteproc1: Direct firmware load for dra7-ipu2-fw.xem4 failed with error -2
[ 7.756288] remoteproc remoteproc1: request_firmware failed: -2
[ 7.770505] omap-rproc 40800000.dsp: assigned reserved memory node dsp1-memory@99000000
[ 7.777906] remoteproc remoteproc2: 40800000.dsp is available
[ 7.779488] remoteproc remoteproc2: Direct firmware load for dra7-dsp1-fw.xe66 failed with error -2
[ 7.779501] remoteproc remoteproc2: powering up 40800000.dsp
[ 7.779532] remoteproc remoteproc2: Direct firmware load for dra7-dsp1-fw.xe66 failed with error -2
[ 7.779542] remoteproc remoteproc2: request_firmware failed: -2
[ 7.782117] omap-rproc 41000000.dsp: assigned reserved memory node dsp2-memory@9f000000
[ 7.785567] remoteproc remoteproc3: 41000000.dsp is available
[ 7.786126] remoteproc remoteproc3: Direct firmware load for dra7-dsp2-fw.xe66 failed with error -2
[ 7.786140] remoteproc remoteproc3: powering up 41000000.dsp
[ 7.786179] remoteproc remoteproc3: Direct firmware load for dra7-dsp2-fw.xe66 failed with error -2
[ 7.786191] remoteproc remoteproc3: request_firmware failed: -2
[ 8.350872] omap-des 480a5000.des: OMAP DES hw accel rev: 2.2
[ 8.408012] gpiochip_find_base: found new base at 496
[ 8.408480] gpio gpiochip8: (tpic2810): added GPIO chardev (254:8)
[ 8.408695] gpiochip_setup_dev: registered GPIOs 496 to 503 on device: gpiochip8 (tpic2810)
[ 8.411412] ov2659 0-0030: GPIO lookup for consumer pwrdn
[ 8.411419] ov2659 0-0030: using device tree for GPIO lookup
[ 8.411462] of_get_named_gpiod_flags: parsed 'pwrdn-gpios' property of node '/ocp/i2c@48070000/ov2659@30[0]' - status (0)
[ 8.420236] ov2659 0-0030: Sensor detection failed (3030, 0)
[ 8.442783] omap-des 480a5000.des: will run requests pump with realtime priority
[ 8.536581] rtc rtc0: 48070000.i2c:tps659038@58:tps659038_rtc: dev (253:0)
[ 8.536631] palmas-rtc 48070000.i2c:tps659038@58:tps659038_rtc: rtc core: registered 48070000.i2c:tps659038@58:tps659038_rtc as rtc0
[ 8.597541] palmas-usb 48070000.i2c:tps659038@58:tps659038_usb: GPIO lookup for consumer id
[ 8.597552] palmas-usb 48070000.i2c:tps659038@58:tps659038_usb: using device tree for GPIO lookup
[ 8.597574] of_get_named_gpiod_flags: can't parse 'id-gpios' property of node '/ocp/i2c@48070000/tps659038@58/tps659038_usb[0]'
[ 8.597684] of_get_named_gpiod_flags: parsed 'id-gpio' property of node '/ocp/i2c@48070000/tps659038@58/tps659038_usb[0]' - status (0)
[ 8.597711] palmas-usb 48070000.i2c:tps659038@58:tps659038_usb: GPIO lookup for consumer vbus
[ 8.597720] palmas-usb 48070000.i2c:tps659038@58:tps659038_usb: using device tree for GPIO lookup
[ 8.597731] of_get_named_gpiod_flags: can't parse 'vbus-gpios' property of node '/ocp/i2c@48070000/tps659038@58/tps659038_usb[0]'
[ 8.597763] of_get_named_gpiod_flags: parsed 'vbus-gpio' property of node '/ocp/i2c@48070000/tps659038@58/tps659038_usb[0]' - status (0)
[ 8.597790] omap_gpio 48057000.gpio: Could not set line 16 debounce to 20000 microseconds (-22)
[ 8.756041] omap_rtc 48838000.rtc: char device (253:1)
[ 8.762064] omap_rtc 48838000.rtc: registered as rtc1
[ 8.857056] vpe 489d0000.vpe: loading firmware vpdma-1b8.bin
[ 8.904438] vpe 489d0000.vpe: Device registered as /dev/video0
[ 8.918896] vip 48990000.vip: loading firmware vpdma-1b8.bin
[ 8.949925] vip 48990000.vip: VPDMA firmware loaded
[ 9.203556] SCSI subsystem initialized
[ 9.307569] libata version 3.00 loaded.
[ 9.369044] ahci 4a140000.sata: SSS flag set, parallel bus scan disabled
[ 9.417656] ahci 4a140000.sata: AHCI 0001.0300 32 slots 1 ports 3 Gbps 0x1 impl platform mode
[ 9.417665] ahci 4a140000.sata: flags: 64bit ncq sntf stag pm led clo only pmp pio slum part ccc apst
[ 9.451537] scsi host0: ahci
[ 9.452124] ata1: SATA max UDMA/133 mmio [mem 0x4a140000-0x4a1410ff] port 0x100 irq 75
[ 9.784625] ata1: SATA link down (SStatus 0 SControl 300)
[ 10.145418] CAN device driver interface
[ 10.150802] omap_wdt: OMAP Watchdog Timer Rev 0x01: initial timeout 60 sec
[ 10.188380] omap-sham 4b101000.sham: hw accel on OMAP rev 4.3
[ 10.336782] FAT-fs (mmcblk0p1): Volume was not properly unmounted. Some data may be corrupt. Please run fsck.
[ 10.374730] c_can_platform 4ae3c000.can: c_can_platform device registered (regs=fce3c000, irq=83)
[ 10.409570] omap-aes 4b500000.aes: OMAP AES hw accel rev: 3.3
[ 10.416518] omap-aes 4b500000.aes: will run requests pump with realtime priority
[ 10.440402] net eth1: initializing cpsw version 1.15 (0)
[ 10.448752] libphy: PHY 48485000.mdio:01 not found
[ 10.458973] omap-aes 4b700000.aes: OMAP AES hw accel rev: 3.3
[ 10.469314] omap-aes 4b700000.aes: will run requests pump with realtime priority
[ 10.550777] net eth1: phy "48485000.mdio:01" not found on slave 1, err -19
[ 10.736730] IPv6: ADDRCONF(NETDEV_UP): eth1: link is not ready
[ 10.929324] prueth pruss2_eth: unable to get PRU0: -19
[ 10.942050] omap-hdmi-audio omap-hdmi-audio.0.auto: snd-soc-dummy-dai <-> 58040000.encoder mapping ok
[ 11.015380] omap-hdmi-audio omap-hdmi-audio.0.auto: ASoC: no DMI vendor name!
[ 11.050272] net eth0: initializing cpsw version 1.15 (0)
[ 11.170834] Micrel KSZ9031 Gigabit PHY 48485000.mdio:00: attached PHY driver [Micrel KSZ9031 Gigabit PHY] (mii_bus:phy_addr=48485000.mdio:00, irq=POLL)
[ 11.344082] IPv6: ADDRCONF(NETDEV_UP): eth0: link is not ready
[ 13.311424] usbcore: registered new interface driver usbfs
[ 13.317466] usbcore: registered new interface driver hub
[ 13.330346] usbcore: registered new device driver usb
[ 13.357788] xhci-hcd xhci-hcd.1.auto: xHCI Host Controller
[ 13.363354] xhci-hcd xhci-hcd.1.auto: new USB bus registered, assigned bus number 1
[ 13.384977] xhci-hcd xhci-hcd.1.auto: hcc params 0x0220f04c hci version 0x100 quirks 0x02010010
[ 13.418583] xhci-hcd xhci-hcd.1.auto: irq 173, io mem 0x48890000
[ 13.424997] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002
[ 13.442053] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[ 13.462247] usb usb1: Product: xHCI Host Controller
[ 13.467163] usb usb1: Manufacturer: Linux 4.14.79-rt47-gd9200ca684 xhci-hcd
[ 13.484441] usb usb1: SerialNumber: xhci-hcd.1.auto
[ 13.505465] hub 1-0:1.0: USB hub found
[ 13.509308] hub 1-0:1.0: 1 port detected
[ 13.522710] xhci-hcd xhci-hcd.1.auto: xHCI Host Controller
[ 13.528403] xhci-hcd xhci-hcd.1.auto: new USB bus registered, assigned bus number 2
[ 13.536674] xhci-hcd xhci-hcd.1.auto: Host supports USB 3.0 SuperSpeed
[ 13.544484] usb usb2: We don't know the algorithms for LPM for this host, disabling LPM.
[ 13.558340] usb usb2: New USB device found, idVendor=1d6b, idProduct=0003
[ 13.565348] usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[ 13.572884] usb usb2: Product: xHCI Host Controller
[ 13.577975] usb usb2: Manufacturer: Linux 4.14.79-rt47-gd9200ca684 xhci-hcd
[ 13.590202] usb usb2: SerialNumber: xhci-hcd.1.auto
[ 13.596132] hub 2-0:1.0: USB hub found
[ 13.600643] hub 2-0:1.0: 1 port detected
[ 13.614051] xhci-hcd xhci-hcd.2.auto: xHCI Host Controller
[ 13.622678] xhci-hcd xhci-hcd.2.auto: new USB bus registered, assigned bus number 3
[ 13.634751] xhci-hcd xhci-hcd.2.auto: hcc params 0x0220f04c hci version 0x100 quirks 0x02010010
[ 13.648785] xhci-hcd xhci-hcd.2.auto: irq 174, io mem 0x488d0000
[ 13.659327] usb usb3: New USB device found, idVendor=1d6b, idProduct=0002
[ 13.670547] usb usb3: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[ 13.678422] usb usb3: Product: xHCI Host Controller
[ 13.689227] usb usb3: Manufacturer: Linux 4.14.79-rt47-gd9200ca684 xhci-hcd
[ 13.698024] usb usb3: SerialNumber: xhci-hcd.2.auto
[ 13.708463] hub 3-0:1.0: USB hub found
[ 13.725765] hub 3-0:1.0: 1 port detected
[ 13.731399] xhci-hcd xhci-hcd.2.auto: xHCI Host Controller
[ 13.737558] xhci-hcd xhci-hcd.2.auto: new USB bus registered, assigned bus number 4
[ 13.746755] xhci-hcd xhci-hcd.2.auto: Host supports USB 3.0 SuperSpeed
[ 13.760373] usb usb4: We don't know the algorithms for LPM for this host, disabling LPM.
[ 13.768689] usb usb4: New USB device found, idVendor=1d6b, idProduct=0003
[ 13.775575] usb usb4: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[ 13.797967] usb usb4: Product: xHCI Host Controller
[ 13.802987] usb usb4: Manufacturer: Linux 4.14.79-rt47-gd9200ca684 xhci-hcd
[ 13.816139] usb usb4: SerialNumber: xhci-hcd.2.auto
[ 13.836076] hub 4-0:1.0: USB hub found
[ 13.840123] hub 4-0:1.0: 1 port detected
[ 14.418680] cpsw 48484000.ethernet eth0: Link is Up - 1Gbps/Full - flow control rx/tx
[ 14.426715] IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready

I'm producing this problem with my RTOS app. Unfortunately I don't have a TI supplied example that produces this issue.

Thanks a lot,

Nir.

0 Nir Geller over 6 years ago in reply to Nir Geller

Expert 1140 points

Fullscreen stx_dmesg.txt Download

[    0.000000] Booting Linux on physical CPU 0x0
[    0.000000] Linux version 4.14.79-rt47-gd9200ca684 (oe-user@oe-host) (gcc version 7.2.1 20171011 (Linaro GCC 7.2-2017.11)) #12 SMP PREEMPT RT Wed Jul 24 10:25:28 UTC 2019
[    0.000000] CPU: ARMv7 Processor [412fc0f2] revision 2 (ARMv7), cr=10c5387d
[    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: TI AM5728 JAILHOUSE IDK
[    0.000000] Memory policy: Data cache writealloc
[    0.000000] efi: Getting EFI parameters from FDT:
[    0.000000] efi: UEFI not found.
[    0.000000] Reserved memory: created CMA memory pool at 0x95800000, size 56 MiB
[    0.000000] OF: reserved mem: initialized node ipu2-memory@95800000, compatible id shared-dma-pool
[    0.000000] Reserved memory: created CMA memory pool at 0x99000000, size 64 MiB
[    0.000000] OF: reserved mem: initialized node dsp1-memory@99000000, compatible id shared-dma-pool
[    0.000000] Reserved memory: created CMA memory pool at 0x9d000000, size 32 MiB
[    0.000000] OF: reserved mem: initialized node ipu1-memory@9d000000, compatible id shared-dma-pool
[    0.000000] Reserved memory: created CMA memory pool at 0x9f000000, size 8 MiB
[    0.000000] OF: reserved mem: initialized node dsp2-memory@9f000000, compatible id shared-dma-pool
[    0.000000] cma: Reserved 24 MiB at 0xbe400000
[    0.000000] OMAP4: Map 0xbfe00000 to fe600000 for dram barrier
[    0.000000] On node 0 totalpages: 196096
[    0.000000] free_area_init_node: node 0, pgdat c0d57640, node_mem_map d4ea8000
[    0.000000]   Normal zone: 1134 pages used for memmap
[    0.000000]   Normal zone: 0 pages reserved
[    0.000000]   Normal zone: 129024 pages, LIFO batch:31
[    0.000000]   HighMem zone: 67072 pages, LIFO batch:15
[    0.000000] DRA752 ES2.0
[    0.000000] percpu: Embedded 15 pages/cpu @d4e3a000 s29792 r8192 d23456 u61440
[    0.000000] pcpu-alloc: s29792 r8192 d23456 u61440 alloc=15*4096
[    0.000000] pcpu-alloc: [0] 0 [0] 1 
[    0.000000] Built 1 zonelists, mobility grouping on.  Total pages: 194962
[    0.000000] Kernel command line: console=ttyO9,115200n8 root=PARTUUID=5b37c775-02 rw rootfstype=ext4 rootwait vmalloc=512M
[    0.000000] PID hash table entries: 2048 (order: 1, 8192 bytes)
[    0.000000] Dentry cache hash table entries: 65536 (order: 7, 524288 bytes)
[    0.000000] Inode-cache hash table entries: 32768 (order: 5, 131072 bytes)
[    0.000000] Memory: 574568K/784384K available (8192K kernel code, 355K rwdata, 2596K rodata, 1024K init, 287K bss, 21400K reserved, 188416K cma-reserved, 243712K highmem)
[    0.000000] Virtual kernel memory layout:
                   vector  : 0xffff0000 - 0xffff1000   (   4 kB)
                   fixmap  : 0xffc00000 - 0xfff00000   (3072 kB)
                   vmalloc : 0xe0000000 - 0xff800000   ( 504 MB)
                   lowmem  : 0xc0000000 - 0xdf800000   ( 504 MB)
                   pkmap   : 0xbfe00000 - 0xc0000000   (   2 MB)
                   modules : 0xbf000000 - 0xbfe00000   (  14 MB)
                     .text : 0xc0008000 - 0xc0900000   (9184 kB)
                     .init : 0xc0c00000 - 0xc0d00000   (1024 kB)
                     .data : 0xc0d00000 - 0xc0d58f00   ( 356 kB)
                      .bss : 0xc0d5a000 - 0xc0da1c50   ( 288 kB)
[    0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=2, Nodes=1
[    0.000000] Preemptible hierarchical RCU implementation.
[    0.000000] 	RCU priority boosting: priority 1 delay 500 ms.
[    0.000000] 	No expedited grace period (rcu_normal_after_boot).
[    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] OMAP clockevent source: timer1 at 32786 Hz
[    0.000000] arch_timer: cp15 timer(s) running at 6.14MHz (phys).
[    0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x16af5adb9, max_idle_ns: 440795202250 ns
[    0.000005] sched_clock: 56 bits at 6MHz, resolution 162ns, wraps every 4398046511023ns
[    0.000011] Switching to timer-based delay loop, resolution 162ns
[    0.000334] clocksource: 32k_counter: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 58327039986419 ns
[    0.000337] OMAP clocksource: 32k_counter at 32768 Hz
[    0.000798] Console: colour dummy device 80x30
[    0.000921] WARNING: Your 'console=ttyO9' has been replaced by 'ttyS9'
[    0.000922] This ensures that you still see kernel messages. Please
[    0.000924] update your kernel commandline.
[    0.000940] Calibrating delay loop (skipped), value calculated using timer frequency.. 12.29 BogoMIPS (lpj=61475)
[    0.000947] pid_max: default: 32768 minimum: 301
[    0.001081] Mount-cache hash table entries: 1024 (order: 0, 4096 bytes)
[    0.001087] Mountpoint-cache hash table entries: 1024 (order: 0, 4096 bytes)
[    0.001762] CPU: Testing write buffer coherency: ok
[    0.001795] CPU0: Spectre v2: using ICIALLU workaround
[    0.002043] /cpus/cpu@0 missing clock-frequency property
[    0.002080] /cpus/cpu@1 missing clock-frequency property
[    0.002092] CPU0: thread -1, cpu 0, socket 0, mpidr 80000000
[    0.039930] Setting up static identity map for 0x80100000 - 0x80100060
[    0.079915] Hierarchical SRCU implementation.
[    0.140418] EFI services will not be available.
[    0.160041] smp: Bringing up secondary CPUs ...
[    0.260391] CPU1: thread -1, cpu 1, socket 0, mpidr 80000001
[    0.260395] CPU1: Spectre v2: using ICIALLU workaround
[    0.260506] smp: Brought up 1 node, 2 CPUs
[    0.260522] SMP: Total of 2 processors activated (24.59 BogoMIPS).
[    0.260529] CPU: All CPU(s) started in HYP mode.
[    0.260536] CPU: Virtualization extensions available.
[    0.261869] devtmpfs: initialized
[    0.292635] random: get_random_u32 called from bucket_table_alloc+0x14c/0x288 with crng_init=0
[    0.292959] VFP support v0.3: implementor 41 architecture 4 part 30 variant f rev 0
[    0.293215] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns
[    0.293234] futex hash table entries: 512 (order: 3, 32768 bytes)
[    0.297543] pinctrl core: initialized pinctrl subsystem
[    0.298377] DMI not present or invalid.
[    0.298854] NET: Registered protocol family 16
[    0.301592] DMA: preallocated 256 KiB pool for atomic coherent allocations
[    0.302563] omap_hwmod: l3_main_2 using broken dt data from ocp
[    0.439736] omap_hwmod: gpu: _wait_target_ready failed: -16
[    0.439750] omap_hwmod: gpu: cannot be enabled for reset (3)
[    0.519027] gpio gpiochip0: (gpio): added GPIO chardev (254:0)
[    0.519133] gpiochip_setup_dev: registered GPIOs 0 to 31 on device: gpiochip0 (gpio)
[    0.519366] OMAP GPIO hardware version 0.1
[    0.520321] gpio gpiochip1: (gpio): added GPIO chardev (254:1)
[    0.520423] gpiochip_setup_dev: registered GPIOs 32 to 63 on device: gpiochip1 (gpio)
[    0.521483] gpio gpiochip2: (gpio): added GPIO chardev (254:2)
[    0.521586] gpiochip_setup_dev: registered GPIOs 64 to 95 on device: gpiochip2 (gpio)
[    0.522623] gpio gpiochip3: (gpio): added GPIO chardev (254:3)
[    0.522741] gpiochip_setup_dev: registered GPIOs 96 to 127 on device: gpiochip3 (gpio)
[    0.523774] gpio gpiochip4: (gpio): added GPIO chardev (254:4)
[    0.523877] gpiochip_setup_dev: registered GPIOs 128 to 159 on device: gpiochip4 (gpio)
[    0.524889] gpio gpiochip5: (gpio): added GPIO chardev (254:5)
[    0.524992] gpiochip_setup_dev: registered GPIOs 160 to 191 on device: gpiochip5 (gpio)
[    0.526016] gpio gpiochip6: (gpio): added GPIO chardev (254:6)
[    0.526116] gpiochip_setup_dev: registered GPIOs 192 to 223 on device: gpiochip6 (gpio)
[    0.552639] hw-breakpoint: found 5 (+1 reserved) breakpoint and 4 watchpoint registers.
[    0.552651] hw-breakpoint: maximum watchpoint size is 8 bytes.
[    0.553056] omap4_sram_init:Unable to allocate sram needed to handle errata I688
[    0.553066] omap4_sram_init:Unable to get sram pool needed to handle errata I688
[    0.554321] OMAP DMA hardware revision 0.0
[    0.570573] edma 43300000.edma: memcpy is disabled
[    0.576561] edma 43300000.edma: TI EDMA DMA engine driver
[    0.589056] omap-dma-engine 4a056000.dma-controller: OMAP DMA engine driver (LinkedList1/2/3 supported)
[    0.589801] of_get_named_gpiod_flags: can't parse 'gpio' property of node '/fixedregulator-vmain[0]'
[    0.590173] of_get_named_gpiod_flags: can't parse 'gpio' property of node '/fixedregulator-v3_3d[0]'
[    0.590576] of_get_named_gpiod_flags: can't parse 'gpio' property of node '/fixedregulator-vtt[0]'
[    0.594795] omap-iommu 40d01000.mmu: 40d01000.mmu registered
[    0.595279] omap-iommu 40d02000.mmu: 40d02000.mmu registered
[    0.595848] omap-iommu 58882000.mmu: 58882000.mmu registered
[    0.596417] omap-iommu 55082000.mmu: 55082000.mmu registered
[    0.597131] omap-iommu 41501000.mmu: 41501000.mmu registered
[    0.597606] omap-iommu 41502000.mmu: 41502000.mmu registered
[    0.598068] iommu: Adding device 58820000.ipu to group 1
[    0.598264] iommu: Adding device 55020000.ipu to group 2
[    0.598606] iommu: Adding device 40800000.dsp to group 0
[    0.599189] iommu: Adding device 41000000.dsp to group 3
[    0.602137] palmas 0-0058: Irq flag is 0x00000004
[    0.632749] palmas 0-0058: Muxing GPIO 2b, PWM 0, LED 0
[    0.634473] SMPS12: supplied by regulator-dummy
[    0.636316] SMPS3: supplied by VMAIN
[    0.636706] smps3: Bringing 1500000uV into 1350000-1350000uV
[    0.638873] SMPS45: supplied by regulator-dummy
[    0.640867] SMPS6: supplied by VMAIN
[    0.642926] SMPS7: supplied by VMAIN
[    0.645068] SMPS8: supplied by VMAIN
[    0.646641] SMPS9: supplied by VMAIN
[    0.647893] LDO1: supplied by VMAIN
[    0.652020] LDO2: supplied by VMAIN
[    0.661881] LDO3: supplied by VMAIN
[    0.671917] LDO4: supplied by VMAIN
[    0.681898] LDO5: supplied by regulator-dummy
[    0.682881] LDO6: supplied by regulator-dummy
[    0.683867] LDO7: supplied by regulator-dummy
[    0.684862] LDO8: supplied by regulator-dummy
[    0.685824] LDO9: supplied by VMAIN
[    0.692284] LDOLN: supplied by VMAIN
[    0.701962] LDOUSB: supplied by VMAIN
[    0.715166] gpiochip_find_base: found new base at 504
[    0.715370] gpio gpiochip7: (48070000.i2c:tps659038@58:tps659038_gpio): added GPIO chardev (254:7)
[    0.715478] gpiochip_setup_dev: registered GPIOs 504 to 511 on device: gpiochip7 (48070000.i2c:tps659038@58:tps659038_gpio)
[    0.716162] omap_i2c 48070000.i2c: bus 0 rev0.12 at 400 kHz
[    0.716457] media: Linux media interface: v0.10
[    0.716516] Linux video capture interface: v2.00
[    0.716644] pps_core: LinuxPPS API ver. 1 registered
[    0.716653] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>
[    0.716677] PTP clock support registered
[    0.716714] EDAC MC: Ver: 3.0.0
[    0.717115] dmi: Firmware registration failed.
[    0.717698] omap-mailbox 48840000.mailbox: omap mailbox rev 0x400
[    0.718104] omap-mailbox 48842000.mailbox: omap mailbox rev 0x400
[    0.718668] Advanced Linux Sound Architecture Driver Initialized.
[    0.719808] clocksource: Switched to clocksource arch_sys_counter
[    0.732464] NET: Registered protocol family 2
[    0.733255] TCP established hash table entries: 4096 (order: 2, 16384 bytes)
[    0.733308] TCP bind hash table entries: 4096 (order: 4, 114688 bytes)
[    0.733451] TCP: Hash tables configured (established 4096 bind 4096)
[    0.733587] UDP hash table entries: 256 (order: 2, 16384 bytes)
[    0.733621] UDP-Lite hash table entries: 256 (order: 2, 16384 bytes)
[    0.733887] NET: Registered protocol family 1
[    0.734455] RPC: Registered named UNIX socket transport module.
[    0.734467] RPC: Registered udp transport module.
[    0.734476] RPC: Registered tcp transport module.
[    0.734485] RPC: Registered tcp NFSv4.1 backchannel transport module.
[    0.734498] PCI: CLS 0 bytes, default 64
[    0.735868] hw perfevents: no interrupt-affinity property for /pmu, guessing.
[    0.736197] hw perfevents: enabled with armv7_cortex_a15 PMU driver, 7 counters available
[    0.738080] workingset: timestamp_bits=14 max_order=18 bucket_order=4
[    0.745036] squashfs: version 4.0 (2009/01/31) Phillip Lougher
[    0.745894] NFS: Registering the id_resolver key type
[    0.745929] Key type id_resolver registered
[    0.745939] Key type id_legacy registered
[    0.746013] ntfs: driver 2.1.32 [Flags: R/O].
[    0.748003] bounce: pool size: 64 pages
[    0.748063] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 245)
[    0.748076] io scheduler noop registered
[    0.748086] io scheduler deadline registered
[    0.748228] io scheduler cfq registered (default)
[    0.748240] io scheduler mq-deadline registered
[    0.748250] io scheduler kyber registered
[    0.754771] pinctrl-single 4a003400.pinmux: 282 pins at pa fc003400 size 1128
[    0.759306] dra7-pcie 51000000.pcie: Linked as a consumer to phy-4a094000.pciephy.1
[    0.759550] dra7-pcie 51000000.pcie: GPIO lookup for consumer (null)
[    0.759557] dra7-pcie 51000000.pcie: using device tree for GPIO lookup
[    0.759592] of_get_named_gpiod_flags: parsed 'gpios' property of node '/ocp/axi@0/pcie@51000000[0]' - status (0)
[    0.759922] OF: PCI: host bridge /ocp/axi@0/pcie@51000000 ranges:
[    0.759958] OF: PCI:    IO 0x20003000..0x20012fff -> 0x00000000
[    0.759984] OF: PCI:   MEM 0x20013000..0x2fffffff -> 0x20013000
[    1.759826] dra7-pcie 51000000.pcie: phy link never came up
[    1.760045] dra7-pcie 51000000.pcie: PCI host bridge to bus 0000:00
[    1.760064] pci_bus 0000:00: root bus resource [bus 00-ff]
[    1.760076] pci_bus 0000:00: root bus resource [io  0x0000-0xffff]
[    1.760086] pci_bus 0000:00: root bus resource [mem 0x20013000-0x2fffffff]
[    1.760130] pci 0000:00:00.0: [104c:8888] type 01 class 0x060400
[    1.760163] pci 0000:00:00.0: reg 0x10: [mem 0x00000000-0x000fffff 64bit]
[    1.760230] pci 0000:00:00.0: supports D1
[    1.760237] pci 0000:00:00.0: PME# supported from D0 D1 D3hot
[    1.760492] PCI: bus0: Fast back to back transfers disabled
[    1.760628] PCI: bus1: Fast back to back transfers enabled
[    1.760671] pci 0000:00:00.0: BAR 0: assigned [mem 0x20100000-0x201fffff 64bit]
[    1.760690] pci 0000:00:00.0: PCI bridge to [bus 01-ff]
[    1.761349] pcieport 0000:00:00.0: Signaling PME with IRQ 168
[    1.761632] pcieport 0000:00:00.0: AER enabled with IRQ 168
[    1.767677] V3_3D: supplied by smps9
[    1.767994] vtt_fixed: supplied by V3_3D
[    1.842813] Serial: 8250/16550 driver, 10 ports, IRQ sharing disabled
[    1.847475] 4ae2b000.serial: ttyS9 at MMIO 0x4ae2b000 (irq = 44, base_baud = 3000000) is a 8250
[    2.918363] console [ttyS9] enabled
[    2.924542] omap_rng 48090000.rng: Random Number Generator ver. 20
[    2.932029] DSS: OMAP DSS rev 6.1
[    2.937940] omapdss_dss 58000000.dss: bound 58001000.dispc (ops dispc_component_ops)
[    2.946708] omapdss_dss 58000000.dss: bound 58040000.encoder (ops hdmi5_component_ops)
[    2.956433] tpd12s015 encoder@0: GPIO lookup for consumer (null)
[    2.956441] tpd12s015 encoder@0: using device tree for GPIO lookup
[    2.956452] of_get_named_gpiod_flags: can't parse 'gpios' property of node '/encoder@0[0]'
[    2.956460] of_get_named_gpiod_flags: can't parse 'gpio' property of node '/encoder@0[0]'
[    2.956468] tpd12s015 encoder@0: using lookup tables for GPIO lookup
[    2.956475] tpd12s015 encoder@0: lookup for GPIO (null) failed
[    2.956483] tpd12s015 encoder@0: GPIO lookup for consumer (null)
[    2.956489] tpd12s015 encoder@0: using device tree for GPIO lookup
[    2.956497] of_get_named_gpiod_flags: can't parse 'gpios' property of node '/encoder@0[1]'
[    2.956505] of_get_named_gpiod_flags: can't parse 'gpio' property of node '/encoder@0[1]'
[    2.956511] tpd12s015 encoder@0: using lookup tables for GPIO lookup
[    2.956518] tpd12s015 encoder@0: lookup for GPIO (null) failed
[    2.956525] tpd12s015 encoder@0: GPIO lookup for consumer (null)
[    2.956530] tpd12s015 encoder@0: using device tree for GPIO lookup
[    2.956562] of_get_named_gpiod_flags: parsed 'gpios' property of node '/encoder@0[2]' - status (0)
[    2.958130] of_get_named_gpiod_flags: can't parse 'hpd-gpios' property of node '/connector@0[0]'
[    2.975439] loop: module loaded
[    2.983165] mdio_bus fixed-0: GPIO lookup for consumer reset
[    2.983173] mdio_bus fixed-0: using lookup tables for GPIO lookup
[    2.983181] mdio_bus fixed-0: lookup for GPIO reset failed
[    2.983207] libphy: Fixed MDIO Bus: probed
[    2.991709] mdio_bus 48485000.mdio: GPIO lookup for consumer reset
[    2.991717] mdio_bus 48485000.mdio: using lookup tables for GPIO lookup
[    2.991724] mdio_bus 48485000.mdio: lookup for GPIO reset failed
[    3.059841] davinci_mdio 48485000.mdio: davinci mdio revision 1.6, bus freq 1000000
[    3.067538] davinci_mdio 48485000.mdio: detected phy mask fffffffe
[    3.086314] libphy: 48485000.mdio: probed
[    3.090351] davinci_mdio 48485000.mdio: phy[0]: device 48485000.mdio:00, driver Micrel KSZ9031 Gigabit PHY
[    3.101344] cpsw 48484000.ethernet: Detected MACID = 88:3f:4a:4b:c5:82
[    3.107996] cpsw 48484000.ethernet: initialized cpsw ale version 1.4
[    3.114382] cpsw 48484000.ethernet: ALE Table size 1024
[    3.119646] cpsw 48484000.ethernet: device node lookup for pps timer failed
[    3.126683] cpsw 48484000.ethernet: cpts: overflow check period 500 (jiffies)
[    3.135049] cpsw 48484000.ethernet: cpsw: Detected MACID = 88:3f:4a:4b:c5:83
[    3.144211] i2c /dev entries driver
[    3.149150] IR NEC protocol handler initialized
[    3.153704] IR RC5(x/sz) protocol handler initialized
[    3.158781] IR RC6 protocol handler initialized
[    3.163333] IR JVC protocol handler initialized
[    3.167885] IR Sony protocol handler initialized
[    3.172524] IR SANYO protocol handler initialized
[    3.177249] IR Sharp protocol handler initialized
[    3.182116] IR MCE Keyboard/mouse protocol handler initialized
[    3.187975] IR XMP protocol handler initialized
[    3.202085] sdhci: Secure Digital Host Controller Interface driver
[    3.208296] sdhci: Copyright(c) Pierre Ossman
[    3.213542] sdhci-pltfm: SDHCI platform and OF driver helper
[    3.220635] sdhci-omap 480b4000.mmc: GPIO lookup for consumer wp
[    3.220642] sdhci-omap 480b4000.mmc: using device tree for GPIO lookup
[    3.220654] of_get_named_gpiod_flags: can't parse 'wp-gpios' property of node '/ocp/mmc@480b4000[0]'
[    3.220662] of_get_named_gpiod_flags: can't parse 'wp-gpio' property of node '/ocp/mmc@480b4000[0]'
[    3.220669] sdhci-omap 480b4000.mmc: using lookup tables for GPIO lookup
[    3.220676] sdhci-omap 480b4000.mmc: lookup for GPIO wp failed
[    3.289900] mmc0: SDHCI controller on 480b4000.mmc [480b4000.mmc] using ADMA
[    3.299964] of_get_named_gpiod_flags: parsed 'gpios' property of node '/status-leds/cpu0-led[0]' - status (0)
[    3.300005] no flags found for gpios
[    3.300165] of_get_named_gpiod_flags: parsed 'gpios' property of node '/status-leds/usr0-led[0]' - status (0)
[    3.300179] no flags found for gpios
[    3.300326] of_get_named_gpiod_flags: parsed 'gpios' property of node '/status-leds/heartbeat-led[0]' - status (0)
[    3.300339] no flags found for gpios
[    3.300482] of_get_named_gpiod_flags: parsed 'gpios' property of node '/status-leds/cpu1-led[0]' - status (0)
[    3.300495] no flags found for gpios
[    3.300646] of_get_named_gpiod_flags: parsed 'gpios' property of node '/status-leds/usr1-led[0]' - status (0)
[    3.300659] no flags found for gpios
[    3.300808] of_get_named_gpiod_flags: parsed 'gpios' property of node '/status-leds/mmc0-led[0]' - status (0)
[    3.300821] no flags found for gpios
[    3.309068] NET: Registered protocol family 10
[    3.314960] Segment Routing with IPv6
[    3.318737] sit: IPv6, IPv4 and MPLS over IPv4 tunneling driver
[    3.325426] NET: Registered protocol family 17
[    3.330365] Key type dns_resolver registered
[    3.334811] omap_voltage_late_init: Voltage driver support not added
[    3.341198] Power Management for TI OMAP4+ devices.
[    3.346430] Registering SWP/SWPB emulation handler
[    3.358778] mmc0: new DDR MMC card at address 0001
[    3.364172] mmcblk0: mmc0:0001 Q2J54A 3.64 GiB 
[    3.379915] mmcblk0boot0: mmc0:0001 Q2J54A partition 1 2.00 MiB
[    3.386158] mmcblk0boot1: mmc0:0001 Q2J54A partition 2 2.00 MiB
[    3.392413] mmcblk0rpmb: mmc0:0001 Q2J54A partition 3 512 KiB
[    3.399660]  mmcblk0: p1 p2
[    3.404536] dmm 4e000000.dmm: workaround for errata i878 in use
[    3.412959] dmm 4e000000.dmm: initialized all PAT entries
[    3.420759] [drm] Supports vblank timestamp caching Rev 2 (21.10.2013).
[    3.427408] [drm] No driver support for vblank timestamp query.
[    3.561991] EDID block is all zeroes
[    3.578136] [drm] Enabling DMM ywrap scrolling
[    3.606388] Console: switching to colour frame buffer device 128x48
[    3.618709] omapdrm omapdrm.0: fb0: omapdrm frame buffer device
[    3.626011] [drm] Initialized omapdrm 1.0.0 20110917 for omapdrm.0 on minor 0
[    3.633946] hctosys: unable to open rtc device (rtc0)
[    3.640464] pbias_mmc_omap5: disabling
[    3.644251] ALSA device list:
[    3.647236]   No soundcards found.
[    3.954604] EXT4-fs (mmcblk0p2): recovery complete
[    3.960258] EXT4-fs (mmcblk0p2): mounted filesystem with ordered data mode. Opts: (null)
[    3.968447] VFS: Mounted root (ext4 filesystem) on device 179:2.
[    3.975350] devtmpfs: mounted
[    3.979933] Freeing unused kernel memory: 1024K
[    4.128941] systemd[1]: System time before build time, advancing clock.
[    4.173161] 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)
[    4.194687] systemd[1]: Detected architecture arm.
[    4.230588] systemd[1]: Set hostname to <am57xx-evm>.
[    4.542790] random: systemd: uninitialized urandom read (16 bytes read)
[    4.561146] systemd[1]: Listening on Process Core Dump Socket.
[    4.590228] random: systemd: uninitialized urandom read (16 bytes read)
[    4.597174] systemd[1]: Listening on Journal Socket (/dev/log).
[    4.629980] random: systemd: uninitialized urandom read (16 bytes read)
[    4.640587] systemd[1]: Created slice User and Session Slice.
[    4.670400] systemd[1]: Started Forward Password Requests to Wall Directory Watch.
[    4.710313] systemd[1]: Listening on Network Service Netlink Socket.
[    4.750448] systemd[1]: Listening on Journal Socket.
[    4.771324] EDID block is all zeroes
[    4.780062] systemd[1]: Reached target Swap.
[    5.120193] cryptodev: loading out-of-tree module taints kernel.
[    5.127479] cryptodev: driver 1.9 loaded.
[    5.312756] EXT4-fs (mmcblk0p2): re-mounted. Opts: (null)
[    6.017299] systemd-journald[119]: Received request to flush runtime journal from PID 1
[    7.593718] omap-rproc 58820000.ipu: assigned reserved memory node ipu1-memory@9d000000
[    7.617653] remoteproc remoteproc0: 58820000.ipu is available
[    7.644154] remoteproc remoteproc0: Direct firmware load for dra7-ipu1-fw.xem4 failed with error -2
[    7.653615] remoteproc remoteproc0: powering up 58820000.ipu
[    7.671156] omap-rproc 55020000.ipu: assigned reserved memory node ipu2-memory@95800000
[    7.681610] remoteproc remoteproc0: Direct firmware load for dra7-ipu1-fw.xem4 failed with error -2
[    7.690816] remoteproc remoteproc0: request_firmware failed: -2
[    7.706504] remoteproc remoteproc1: 55020000.ipu is available
[    7.712485] remoteproc remoteproc1: Direct firmware load for dra7-ipu2-fw.xem4 failed with error -2
[    7.731472] remoteproc remoteproc1: powering up 55020000.ipu
[    7.737206] remoteproc remoteproc1: Direct firmware load for dra7-ipu2-fw.xem4 failed with error -2
[    7.756288] remoteproc remoteproc1: request_firmware failed: -2
[    7.770505] omap-rproc 40800000.dsp: assigned reserved memory node dsp1-memory@99000000
[    7.777906] remoteproc remoteproc2: 40800000.dsp is available
[    7.779488] remoteproc remoteproc2: Direct firmware load for dra7-dsp1-fw.xe66 failed with error -2
[    7.779501] remoteproc remoteproc2: powering up 40800000.dsp
[    7.779532] remoteproc remoteproc2: Direct firmware load for dra7-dsp1-fw.xe66 failed with error -2
[    7.779542] remoteproc remoteproc2: request_firmware failed: -2
[    7.782117] omap-rproc 41000000.dsp: assigned reserved memory node dsp2-memory@9f000000
[    7.785567] remoteproc remoteproc3: 41000000.dsp is available
[    7.786126] remoteproc remoteproc3: Direct firmware load for dra7-dsp2-fw.xe66 failed with error -2
[    7.786140] remoteproc remoteproc3: powering up 41000000.dsp
[    7.786179] remoteproc remoteproc3: Direct firmware load for dra7-dsp2-fw.xe66 failed with error -2
[    7.786191] remoteproc remoteproc3: request_firmware failed: -2
[    8.350872] omap-des 480a5000.des: OMAP DES hw accel rev: 2.2
[    8.408012] gpiochip_find_base: found new base at 496
[    8.408480] gpio gpiochip8: (tpic2810): added GPIO chardev (254:8)
[    8.408695] gpiochip_setup_dev: registered GPIOs 496 to 503 on device: gpiochip8 (tpic2810)
[    8.411412] ov2659 0-0030: GPIO lookup for consumer pwrdn
[    8.411419] ov2659 0-0030: using device tree for GPIO lookup
[    8.411462] of_get_named_gpiod_flags: parsed 'pwrdn-gpios' property of node '/ocp/i2c@48070000/ov2659@30[0]' - status (0)
[    8.420236] ov2659 0-0030: Sensor detection failed (3030, 0)
[    8.442783] omap-des 480a5000.des: will run requests pump with realtime priority
[    8.536581] rtc rtc0: 48070000.i2c:tps659038@58:tps659038_rtc: dev (253:0)
[    8.536631] palmas-rtc 48070000.i2c:tps659038@58:tps659038_rtc: rtc core: registered 48070000.i2c:tps659038@58:tps659038_rtc as rtc0
[    8.597541] palmas-usb 48070000.i2c:tps659038@58:tps659038_usb: GPIO lookup for consumer id
[    8.597552] palmas-usb 48070000.i2c:tps659038@58:tps659038_usb: using device tree for GPIO lookup
[    8.597574] of_get_named_gpiod_flags: can't parse 'id-gpios' property of node '/ocp/i2c@48070000/tps659038@58/tps659038_usb[0]'
[    8.597684] of_get_named_gpiod_flags: parsed 'id-gpio' property of node '/ocp/i2c@48070000/tps659038@58/tps659038_usb[0]' - status (0)
[    8.597711] palmas-usb 48070000.i2c:tps659038@58:tps659038_usb: GPIO lookup for consumer vbus
[    8.597720] palmas-usb 48070000.i2c:tps659038@58:tps659038_usb: using device tree for GPIO lookup
[    8.597731] of_get_named_gpiod_flags: can't parse 'vbus-gpios' property of node '/ocp/i2c@48070000/tps659038@58/tps659038_usb[0]'
[    8.597763] of_get_named_gpiod_flags: parsed 'vbus-gpio' property of node '/ocp/i2c@48070000/tps659038@58/tps659038_usb[0]' - status (0)
[    8.597790] omap_gpio 48057000.gpio: Could not set line 16 debounce to 20000 microseconds (-22)
[    8.756041] omap_rtc 48838000.rtc: char device (253:1)
[    8.762064] omap_rtc 48838000.rtc: registered as rtc1
[    8.857056] vpe 489d0000.vpe: loading firmware vpdma-1b8.bin
[    8.904438] vpe 489d0000.vpe: Device registered as /dev/video0
[    8.918896] vip 48990000.vip: loading firmware vpdma-1b8.bin
[    8.949925] vip 48990000.vip: VPDMA firmware loaded
[    9.203556] SCSI subsystem initialized
[    9.307569] libata version 3.00 loaded.
[    9.369044] ahci 4a140000.sata: SSS flag set, parallel bus scan disabled
[    9.417656] ahci 4a140000.sata: AHCI 0001.0300 32 slots 1 ports 3 Gbps 0x1 impl platform mode
[    9.417665] ahci 4a140000.sata: flags: 64bit ncq sntf stag pm led clo only pmp pio slum part ccc apst 
[    9.451537] scsi host0: ahci
[    9.452124] ata1: SATA max UDMA/133 mmio [mem 0x4a140000-0x4a1410ff] port 0x100 irq 75
[    9.784625] ata1: SATA link down (SStatus 0 SControl 300)
[   10.145418] CAN device driver interface
[   10.150802] omap_wdt: OMAP Watchdog Timer Rev 0x01: initial timeout 60 sec
[   10.188380] omap-sham 4b101000.sham: hw accel on OMAP rev 4.3
[   10.336782] FAT-fs (mmcblk0p1): Volume was not properly unmounted. Some data may be corrupt. Please run fsck.
[   10.374730] c_can_platform 4ae3c000.can: c_can_platform device registered (regs=fce3c000, irq=83)
[   10.409570] omap-aes 4b500000.aes: OMAP AES hw accel rev: 3.3
[   10.416518] omap-aes 4b500000.aes: will run requests pump with realtime priority
[   10.440402] net eth1: initializing cpsw version 1.15 (0)
[   10.448752] libphy: PHY 48485000.mdio:01 not found
[   10.458973] omap-aes 4b700000.aes: OMAP AES hw accel rev: 3.3
[   10.469314] omap-aes 4b700000.aes: will run requests pump with realtime priority
[   10.550777] net eth1: phy "48485000.mdio:01" not found on slave 1, err -19
[   10.736730] IPv6: ADDRCONF(NETDEV_UP): eth1: link is not ready
[   10.929324] prueth pruss2_eth: unable to get PRU0: -19
[   10.942050] omap-hdmi-audio omap-hdmi-audio.0.auto: snd-soc-dummy-dai <-> 58040000.encoder mapping ok
[   11.015380] omap-hdmi-audio omap-hdmi-audio.0.auto: ASoC: no DMI vendor name!
[   11.050272] net eth0: initializing cpsw version 1.15 (0)
[   11.170834] Micrel KSZ9031 Gigabit PHY 48485000.mdio:00: attached PHY driver [Micrel KSZ9031 Gigabit PHY] (mii_bus:phy_addr=48485000.mdio:00, irq=POLL)
[   11.344082] IPv6: ADDRCONF(NETDEV_UP): eth0: link is not ready
[   13.311424] usbcore: registered new interface driver usbfs
[   13.317466] usbcore: registered new interface driver hub
[   13.330346] usbcore: registered new device driver usb
[   13.357788] xhci-hcd xhci-hcd.1.auto: xHCI Host Controller
[   13.363354] xhci-hcd xhci-hcd.1.auto: new USB bus registered, assigned bus number 1
[   13.384977] xhci-hcd xhci-hcd.1.auto: hcc params 0x0220f04c hci version 0x100 quirks 0x02010010
[   13.418583] xhci-hcd xhci-hcd.1.auto: irq 173, io mem 0x48890000
[   13.424997] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002
[   13.442053] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[   13.462247] usb usb1: Product: xHCI Host Controller
[   13.467163] usb usb1: Manufacturer: Linux 4.14.79-rt47-gd9200ca684 xhci-hcd
[   13.484441] usb usb1: SerialNumber: xhci-hcd.1.auto
[   13.505465] hub 1-0:1.0: USB hub found
[   13.509308] hub 1-0:1.0: 1 port detected
[   13.522710] xhci-hcd xhci-hcd.1.auto: xHCI Host Controller
[   13.528403] xhci-hcd xhci-hcd.1.auto: new USB bus registered, assigned bus number 2
[   13.536674] xhci-hcd xhci-hcd.1.auto: Host supports USB 3.0  SuperSpeed
[   13.544484] usb usb2: We don't know the algorithms for LPM for this host, disabling LPM.
[   13.558340] usb usb2: New USB device found, idVendor=1d6b, idProduct=0003
[   13.565348] usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[   13.572884] usb usb2: Product: xHCI Host Controller
[   13.577975] usb usb2: Manufacturer: Linux 4.14.79-rt47-gd9200ca684 xhci-hcd
[   13.590202] usb usb2: SerialNumber: xhci-hcd.1.auto
[   13.596132] hub 2-0:1.0: USB hub found
[   13.600643] hub 2-0:1.0: 1 port detected
[   13.614051] xhci-hcd xhci-hcd.2.auto: xHCI Host Controller
[   13.622678] xhci-hcd xhci-hcd.2.auto: new USB bus registered, assigned bus number 3
[   13.634751] xhci-hcd xhci-hcd.2.auto: hcc params 0x0220f04c hci version 0x100 quirks 0x02010010
[   13.648785] xhci-hcd xhci-hcd.2.auto: irq 174, io mem 0x488d0000
[   13.659327] usb usb3: New USB device found, idVendor=1d6b, idProduct=0002
[   13.670547] usb usb3: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[   13.678422] usb usb3: Product: xHCI Host Controller
[   13.689227] usb usb3: Manufacturer: Linux 4.14.79-rt47-gd9200ca684 xhci-hcd
[   13.698024] usb usb3: SerialNumber: xhci-hcd.2.auto
[   13.708463] hub 3-0:1.0: USB hub found
[   13.725765] hub 3-0:1.0: 1 port detected
[   13.731399] xhci-hcd xhci-hcd.2.auto: xHCI Host Controller
[   13.737558] xhci-hcd xhci-hcd.2.auto: new USB bus registered, assigned bus number 4
[   13.746755] xhci-hcd xhci-hcd.2.auto: Host supports USB 3.0  SuperSpeed
[   13.760373] usb usb4: We don't know the algorithms for LPM for this host, disabling LPM.
[   13.768689] usb usb4: New USB device found, idVendor=1d6b, idProduct=0003
[   13.775575] usb usb4: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[   13.797967] usb usb4: Product: xHCI Host Controller
[   13.802987] usb usb4: Manufacturer: Linux 4.14.79-rt47-gd9200ca684 xhci-hcd
[   13.816139] usb usb4: SerialNumber: xhci-hcd.2.auto
[   13.836076] hub 4-0:1.0: USB hub found
[   13.840123] hub 4-0:1.0: 1 port detected
[   14.418680] cpsw 48484000.ethernet eth0: Link is Up - 1Gbps/Full - flow control rx/tx
[   14.426715] IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready

stx_dtb.txt

Please see attached dmesg and dtb

0 Garrett Ding over 6 years ago in reply to Nir Geller

TI__Mastermind 43296 points

Thanks Nir.

Can you please also dump 0x4800 0000 to 0x4800 7FFF, and share the two data dump files?

a) from Linux when McSPI 1/2/3/4 access is OK

b) from RTOS inmate when McSPI 1/2/3 access is OK, but not McSPI4.

Regards,

Garrett

0 Nir Geller over 6 years ago in reply to Garrett Ding

Expert 1140 points

Hi Garrett,

When I say "reading from the RTOS side" I mean that I'm debugging the inmate with CCS and I'm using the memory browser.

As I said, after enabling the clocks I can read address spaces of McSPI1,2,3,4 from the Linux side, but when debugging the RTOS inamte, in the memory browser I can see only McSPI1,2,3.

When trying to read McSPI4 (0x480BA000) with the memory browser I get the dump from the kernel, and all the values are zeroed in the registers.

With regard to reading L4_PER1 interconnect address space (0x4800_0000 to 0x4800_1FFF) when McSPI clocks are enabled, I can read it with Linux, and I attached the output.

Fullscreen Linux_Side_dump_0x48000000_0x48001FFF.txt Download

Trying to read an address higher than 0x4800_1FFF from the Linux side results the famous kernel dump:

[ 323.668989] WARNING: CPU: 0 PID: 28 at /home/stx-ti/Projects/tisdk/build/arago-tmp-external-linaro-toolchain/work-shared/am57xx-evm/kernel-source/drivers/bus/omap_l3_noc.c:147 l3_interrupt_handler+0x254/0x370
[ 323.687610] 44000000.ocp:L3 Custom Error: MASTER MPU TARGET L4_PER1_P3 (Read): Data Access in User mode during Functional access

....

According to the TRM 0x4800_2000 to 0x4801_FFFF memory space is reserved (2.4.1 L4_PER1 Memory Map)

From the RTOS side, the memory browser in CCS shows only zeros.

Thanks a lot,

Nir.

0 Garrett Ding over 6 years ago in reply to Nir Geller

TI__Mastermind 43296 points

Nir.

I looked into the Table 4-2. Ball Characteristics in datasheet, it appears only McSPI4 (not McSPI1-3) pins are multiplexed with GPMC pins. Have you checked your board schematic and pinmux to figure out how these two modules (McSPI4/GPMC) can be used in parallel without conflict? How many address bits are used on your board? gmpc_a10 is multiplexed with spi4_d0...

Regards,
Garrett

0 Nir Geller over 6 years ago in reply to Garrett Ding

Expert 1140 points

Hi Garrett,

Without jailhouse my RTOS app is running with no problem at all. I compared the pinmux between the native rtos and jailhouse rtos and found some minor differences.

There was no conflict between GPMC and SPI4, but in some SPI related pins there were mismatches in slew control or pull up/down or receive mode.

After fixing those the situation is the same. RTOS still can't access SPI4 address space....

Any additional data I can supply?

Thanks a lot,

Nir.

0 Nir Geller over 6 years ago in reply to Nir Geller

Expert 1140 points

Another problem, probably not related to SPI4 address space:

In my custom board F21 is pinmuxed to be GPIO6_16. When the RTOS inmate is launched this gpio is set to '1' by some peripheral.

On the Linux side a registered interrupt starts running:

165: 0 0 4805d000.gpio 16 Level palmas

I think this interrupt is triggered by GPIO6_16 because after exporting gpio144 on the linux side I get:

# cat /sys/kernel/debug/gpio

.......

gpiochip4: GPIOs 128-159, parent: platform/4805d000.gpio, gpio:
gpio-144 ( |sysfs ) in lo IRQ

.....

After the RTOS inmate is launched the pin is set to high and the interrupt is running. The kernel then reports

[ 1349.533675] irq 0, desc: d4803700, depth: 1, count: 0, unhandled: 0
[ 1349.533678] ->handle_irq(): c01807f8,
[ 1349.533689] handle_bad_irq+0x0/0x2a4
[ 1349.533691] ->irq_data.chip(): c0d1aae4,
[ 1349.533698] no_irq_chip+0x0/0x88
[ 1349.533701] ->action(): (null)
[ 1349.533704] IRQ_NOPROBE set
[ 1349.533706] IRQ_NOREQUEST set
[ 1349.533709] unexpected IRQ trap at vector 00

and once the interrupt count reaches 100001:

[ 1424.726062] irq 165: nobody cared (try booting with the "irqpoll" option)
[ 1424.726070] CPU: 0 PID: 34 Comm: irq/41-4805d000 Tainted: G O 4.14.79-rt47-gd9200ca684 #18
[ 1424.726073] Hardware name: Generic DRA74X (Flattened Device Tree)
[ 1424.726075] Backtrace:
[ 1424.726096] [<c010b808>] (dump_backtrace) from [<c010baec>] (show_stack+0x18/0x1c)
[ 1424.726102] r7:000000a5 r6:200f0093 r5:00000000 r4:c0d57560
[ 1424.726111] [<c010bad4>] (show_stack) from [<c082207c>] (dump_stack+0x90/0xa4)
[ 1424.726120] [<c0821fec>] (dump_stack) from [<c01837b8>] (__report_bad_irq+0x30/0xd4)
[ 1424.726126] r7:000000a5 r6:d4b27a00 r5:00000000 r4:d4b27a00
[ 1424.726133] [<c0183788>] (__report_bad_irq) from [<c0183bf0>] (note_interrupt+0x270/0x2bc)
[ 1424.726139] r9:600f0013 r8:00000001 r7:000000a5 r6:d4b27a00 r5:00000000 r4:d4b27a00
[ 1424.726147] [<c0183980>] (note_interrupt) from [<c0180cb0>] (handle_irq_event_percpu+0x74/0x80)
[ 1424.726152] r10:00000000 r9:600f0013 r8:00000001 r7:00000002 r6:d4b27a00 r5:00000000
[ 1424.726155] r4:00000000 r3:00000000
[ 1424.726161] [<c0180c3c>] (handle_irq_event_percpu) from [<c0180d3c>] (handle_irq_event+0x80/0xb8)
[ 1424.726166] r7:d4a60468 r6:d4a60410 r5:d4b27a70 r4:d4b27a00
[ 1424.726172] [<c0180cbc>] (handle_irq_event) from [<c018472c>] (handle_level_irq+0xb0/0x194)
[ 1424.726177] r7:d4a60468 r6:d4a60410 r5:d4a60464 r4:d4b27a00
[ 1424.726183] [<c018467c>] (handle_level_irq) from [<c017fdb0>] (generic_handle_irq+0x2c/0x3c)
[ 1424.726186] r5:d4a60464 r4:00000010
[ 1424.726194] [<c017fd84>] (generic_handle_irq) from [<c04329e8>] (omap_gpio_irq_handler+0x108/0x15c)
[ 1424.726201] [<c04328e0>] (omap_gpio_irq_handler) from [<c0181dd8>] (irq_forced_thread_fn+0x28/0x7c)
[ 1424.726206] r10:c0181db0 r9:d4a43340 r8:d4a38400 r7:00000001 r6:00000000 r5:d4a38400
[ 1424.726208] r4:d4a43340
[ 1424.726214] [<c0181db0>] (irq_forced_thread_fn) from [<c0182130>] (irq_thread+0x130/0x208)
[ 1424.726219] r7:00000001 r6:00000000 r5:ffffe000 r4:d4a43364
[ 1424.726226] [<c0182000>] (irq_thread) from [<c0149368>] (kthread+0x164/0x16c)
[ 1424.726232] r10:d4871ac8 r9:c0182000 r8:d4a43340 r7:d4a72000 r6:00000000 r5:d4a43380
[ 1424.726233] r4:d49e3200
[ 1424.726241] [<c0149204>] (kthread) from [<c0107a90>] (ret_from_fork+0x14/0x24)
[ 1424.726246] r10:00000000 r9:00000000 r8:00000000 r7:00000000 r6:00000000 r5:c0149204
[ 1424.726248] r4:d4a43380
[ 1424.726252] handlers:
[ 1424.726257] [<c0180dc8>] irq_default_primary_handler threaded [<c056c164>] regmap_irq_thread
[ 1424.726269] Disabling IRQ #165

It seems like GPIO6_16 is set to high which causes this interrupt to run without being handled and then it is shutdown by the kernel.

I think it is related to some palmas driver.

I tried to disable GPIO6 in the device tree but then the kernel got stuck upon power up.

Can you direct me how to either disable this driver completely, or move the irq to a different gpio?

Thanks a lot,

Nir.

0 Garrett Ding over 6 years ago in reply to Nir Geller

TI__Mastermind 43296 points

Hi Nir.

I am out of office today, will look more closely to the McSPI4 and GPIO6_16 issues. Are you able to disable GPMC to see if McSPI4 still cause kernel dump?

Regards,

Garrett

0 Nir Geller over 6 years ago in reply to Garrett Ding

Expert 1140 points

Hi Garrett,

Thanks for your efforts.

One thing to clarify about McSPI4 issue.

After I fixed the pinmux the kernel doesn't output the famous dump anymore, but in CCS memory browser McSPI4 address space is still zeroed out, and I can't read/write.

However from the linux side I get valid values when reading McSPI4 registers with devmem2.

Thanks a lot,

Nir.

0 Nir Geller over 6 years ago in reply to Nir Geller

Expert 1140 points

Hi Garrett,

Regarding palmas interrupt running loose, the output from the kernel:

Is not related.

Thanks,

Nir.

0 Garrett Ding over 6 years ago in reply to Nir Geller

TI__Mastermind 43296 points

Hi Nir,

For McSPI4 CCS access, did you select 'Physical Memory View' in Memory browser? I don't see McSPI4 memory read issue with CCS after enabling jailhouse (http://software-dl.ti.com/processor-sdk-linux/esd/docs/latest/linux/Foundational_Components_Virtualization.html?highlight=jailhouse) and McSPI4 clock.

For GPIO6_16, have you confirmed that disabling GPIO6_16 in inmate, the kernel wont' shut down?

Regards,

Garrett

0 Nir Geller over 6 years ago in reply to Garrett Ding

Expert 1140 points

Hi Garrett,

McSPI4:

When setting the memory browser to "Physical memory view" I can read SPI4 memory space, but with CPU memory view the address space is zeroed out.

Unlike SPI1,2,3 that work fine. The RTOS inmate app gets into an infinite loop attempting to reset SPI4, because it reads 0x0 from the SYSSTATUS register.

Why can the memory browser display properly SPI4 in "Physical" mode, but not in "CPU" mode? Why does the RTOS app fail to read this memory block?

GPIO6_16:

How can I disable this gpio in particular from triggering and interrupt in A15 core 0?

Thanks,

Nir.

0 Garrett Ding over 6 years ago in reply to Nir Geller

TI__Mastermind 43296 points

Nir.

How does your MMU configuration look like for McSPI4?

Also would you mind to close this core dump thread and open new ones for the CCS memory view and GPIO6_16 disabling in A15 Linux core so more experts may get involved?

Regards,
Garrett

0 Nir Geller over 6 years ago in reply to Garrett Ding

Expert 1140 points

Hi Garrett,

I found a bug in the inmate cell config.

After fixing it McSPI4 address space is available.

Thanks,

Nir.

Processors

Processors forum

Linux/AM5728: Jailhouse RTOS inmate error