TDA4VM: MMC2_CLK not outputting

Spencer,

Greetings.  Have you powered on and enabled the clocks to MMC2 module?  Please also make sure the RXACTIVE register bit in the corresponding PADCONFIG register is set to 0x1 for retiming purposes.

Thanks & Regards,

Shiou Mei

Shiou,

It should be enabled, this is from within Linux with the device tree provided by the PSDK. How should I go about verifying that Linux configured the clock correctly?

Spencer,

You can check arch/arm64/boot/dts/ti/k3-j721e-main.dtsi to see if the corresponding module is enabled.  You can also use devmem2 to access the MMCSD2 host registers and confirm the read values are expected.

Best Regards,

Shiou Mei

Shiou,

We have confirmed that the device is powered on and responds at 0x4F90000 with the expected subsystem ID: 0x68403200

Thanks,

Spencer

Spencer,

Is this issue still active?  If so, please dump the HOST CONTROLLER registers (i.e. SYSCONFIG, PRESENTSTATE, HOST_CONTROL1/2, etc).  Also, please clarify what you meant by setting MMCSD2 CLK to input; it should be output.

Best Regards,

Shiou Mei

Shiou,
Yes this question is still active, sorry if I was not clear before that the issue is still occurring.
To clarify the comment about having the register set to input, I was using it to verify that we did in fact have the correct pin. When trying to run the clock it is correctly configured as an output.
This is the register dump that Linux is performing when the device times out, this should include all the registers you were asking for.

[  535.859592] mmc2: Timeout waiting for hardware cmd interrupt.
[  535.865327] mmc2: sdhci: ============ SDHCI REGISTER DUMP ===========
[  535.871749] mmc2: sdhci: Sys addr:  0x00000000 | Version:  0x00001004
[  535.878170] mmc2: sdhci: Blk size:  0x00000000 | Blk cnt:  0x00000000
[  535.884591] mmc2: sdhci: Argument:  0x00000000 | Trn mode: 0x00000000
[  535.891012] mmc2: sdhci: Present:   0x01f20001 | Host ctl: 0x00000001
[  535.897433] mmc2: sdhci: Power:     0x0000000e | Blk gap:  0x00000080
[  535.903854] mmc2: sdhci: Wake-up:   0x00000000 | Clock:    0x0000e8c7
[  535.910274] mmc2: sdhci: Timeout:   0x00000000 | Int stat: 0x00000000
[  535.916695] mmc2: sdhci: Int enab:  0x00ff0003 | Sig enab: 0x00ff0003
[  535.923116] mmc2: sdhci: ACmd stat: 0x00000000 | Slot int: 0x00000000
[  535.929537] mmc2: sdhci: Caps:      0x7fe8c801 | Caps_1:   0x18000407
[  535.935957] mmc2: sdhci: Cmd:       0x00000102 | Max curr: 0x00000000
[  535.942378] mmc2: sdhci: Resp[0]:   0x00000000 | Resp[1]:  0x00000000
[  535.948798] mmc2: sdhci: Resp[2]:   0x00000000 | Resp[3]:  0x00000000
[  535.955218] mmc2: sdhci: Host ctl2: 0x00000000
[  535.959646] mmc2: sdhci: ADMA Err:  0x00000000 | ADMA Ptr: 0x0000000000000000
[  535.966759] mmc2: sdhci: ============================================

Thanks,

Spencer

Spencer,

Looks like your system did not recognize any card connection so turned off the bus power.  Do you have anything connected to the MMC2_SDCD pin (mux'd onto I3C0_SCL)?  

Moreover, right now the clock is still operating at 400 kHz.  When you probe the signals please make sure to zoom out far enough for the clock to be captured.  You may have missed the initial toggling on the signal lanes because of this. 

Best Regards,

Shiou Mei

Shiou,
The MMC2_SDCD is currently configured as GPIO1_5 and is being used as the enable line for some GMSL parts.

Thanks,

Spencer

Spencer, 

The SDCD should be connected for the interface to work.  If that is not an option, try coding the SDCD status in SW: set bit [7] in Host Control 1 and modify the SDCD status on the fly using bit [6] of the same register.

Best Regards,

Shiou Mei

Shiou,
Currently Linux is in charge of bringing up the device, and has the driver configured as non-removable, which it was my understanding would cause it to ignore the state of the SDCD pin. I did however attempt setting the Host Control 1 bits [7] and [6], and was able to confirm that the change was persisting as the timeout register dumps that Linux was performing showed the changed registers. This didn't change the behavior of the clock not being output.

Thanks,

Spencer

Spencer,

Can you share the latest log as well as screenshots of the waveform capture?  Please also monitor the voltage levels to make sure they are stable.

Thanks & Regards,

Shiou Mei

Shiou,
Here is a picture of the waveform during boot as seen on my oscilloscope. I've also attached the boot log from Linux on the J7.

U-Boot SPL 2020.01-00006-g503df94037-dirty (Jul 13 2021 - 16:42:54 -0400)
SYSFW ABI: 3.0 (firmware rev 0x0014 '20.04.1-v2020.04a (Terrific Lla')
Reading on-board EEPROM at 0x50 failed -1
Trying to boot from MMC2
Loading Environment from MMC... *** Warning - No MMC card found, using default environment

Remoteproc 2 started successfully
** File not found /lib/firmware/j7-mcu-r5f0_0-fw **
Starting ATF on ARM64 core...

NOTICE:  BL31: v2.3():07.00.00.005-dirty
NOTICE:  BL31: Built : 14:33:55, Jun 17 2020

U-Boot SPL 2020.01-00006-g503df94037-dirty (Jul 13 2021 - 16:42:45 -0400)
SYSFW ABI: 3.0 (firmware rev 0x0014 '20.04.1-v2020.04a (Terrific Lla')
Reading on-board EEPROM at 0x50 failed -1
Trying to boot from MMC2
i2c_write: error waiting for data ACK (status=0x116)
Error reading output register


U-Boot 2020.01-00006-g503df94037-dirty (Jul 13 2021 - 16:42:45 -0400)

SoC:   J721E SR1.0
Model: Texas Instruments K3 J721E SoC
Reading on-board EEPROM at 0x50 failed -1
Board: J721EX-PM1-SOM rev E2
DRAM:  4 GiB
not found for dev hbmc-mux
Flash: 0 Bytes
MMC:   sdhci@4f80000: 0, sdhci@4fb0000: 1
Loading Environment from MMC... *** Warning - bad CRC, using default environment

In:    serial@2800000
Out:   serial@2800000
Err:   serial@2800000
Reading on-board EEPROM at 0x50 failed -1
Net:   Could not get PHY for ethernet@046000000: addr 0
phy_connect() failed
No ethernet found.

Hit any key to stop autoboot:  0
gpio: pin 35 (gpio 35) value is 1
Booting with fdtfile k3-j721e-d3-board.dtb
i2c_write: error waiting for data ACK (status=0x116)
Error reading output register
switch to partitions #0, OK
mmc1 is current device
i2c_write: error waiting for data ACK (status=0x116)
Error reading output register
SD/MMC found on device 1
55 bytes read in 2 ms (26.4 KiB/s)
Loaded env from uEnv.txt
Importing environment from mmc1 ...
1513584 bytes read in 67 ms (21.5 MiB/s)
Load Remote Processor 6 with data@addr=0x80080000 1513584 bytes: Success!
1513584 bytes read in 68 ms (21.2 MiB/s)
Load Remote Processor 7 with data@addr=0x80080000 1513584 bytes: Success!
9314288 bytes read in 390 ms (22.8 MiB/s)
Load Remote Processor 8 with data@addr=0x80080000 9314288 bytes: Success!
17446920 bytes read in 725 ms (22.9 MiB/s)
98030 bytes read in 8 ms (11.7 MiB/s)
1593 bytes read in 4 ms (388.7 KiB/s)
## Flattened Device Tree blob at 82000000
   Booting using the fdt blob at 0x82000000
   Loading Device Tree to 00000000fdd9f000, end 00000000fdeb9fff ... OK

Starting kernel ...

[    0.000000] Booting Linux on physical CPU 0x0000000000 [0x411fd080]
[    0.000000] Linux version 5.4.40-g66cf445b76 (shill@D3290SXH) (gcc version 9.2.1 20191025 (GNU Toolchain for the A-profile Architecture 9.2-2019.12 (arm-9.10))) #2 SMP PREEMPT Tue Jul 13 14:19:53 EDT 2021
[    0.000000] Machine model: Texas Instruments K3 J721E SoC
[    0.000000] earlycon: ns16550a0 at MMIO32 0x0000000002800000 (options '')
[    0.000000] printk: bootconsole [ns16550a0] enabled
[    0.000000] Reserved memory: created DMA memory pool at 0x00000000a0000000, size 1 MiB
[    0.000000] OF: reserved mem: initialized node r5f-dma-memory@a0000000, compatible id shared-dma-pool
[    0.000000] Reserved memory: created DMA memory pool at 0x00000000a0100000, size 15 MiB
[    0.000000] OF: reserved mem: initialized node r5f-memory@a0100000, compatible id shared-dma-pool
[    0.000000] Reserved memory: created DMA memory pool at 0x00000000a1000000, size 1 MiB
[    0.000000] OF: reserved mem: initialized node r5f-dma-memory@a1000000, compatible id shared-dma-pool
[    0.000000] Reserved memory: created DMA memory pool at 0x00000000a1100000, size 31 MiB
[    0.000000] OF: reserved mem: initialized node r5f-memory@a1100000, compatible id shared-dma-pool
[    0.000000] Reserved memory: created DMA memory pool at 0x00000000a3000000, size 1 MiB
[    0.000000] OF: reserved mem: initialized node r5f-dma-memory@a3000000, compatible id shared-dma-pool
[    0.000000] Reserved memory: created DMA memory pool at 0x00000000a3100000, size 15 MiB
[    0.000000] OF: reserved mem: initialized node r5f-memory@a3100000, compatible id shared-dma-pool
[    0.000000] Reserved memory: created DMA memory pool at 0x00000000a4000000, size 1 MiB
[    0.000000] OF: reserved mem: initialized node r5f-dma-memory@a4000000, compatible id shared-dma-pool
[    0.000000] Reserved memory: created DMA memory pool at 0x00000000a4100000, size 15 MiB
[    0.000000] OF: reserved mem: initialized node r5f-memory@a4100000, compatible id shared-dma-pool
[    0.000000] Reserved memory: created DMA memory pool at 0x00000000a5000000, size 1 MiB
[    0.000000] OF: reserved mem: initialized node r5f-dma-memory@a5000000, compatible id shared-dma-pool
[    0.000000] Reserved memory: created DMA memory pool at 0x00000000a5100000, size 15 MiB
[    0.000000] OF: reserved mem: initialized node r5f-memory@a5100000, compatible id shared-dma-pool
[    0.000000] Reserved memory: created DMA memory pool at 0x00000000a6000000, size 1 MiB
[    0.000000] OF: reserved mem: initialized node c66-dma-memory@a6000000, compatible id shared-dma-pool
[    0.000000] Reserved memory: created DMA memory pool at 0x00000000a6100000, size 15 MiB
[    0.000000] OF: reserved mem: initialized node c66-memory@a6100000, compatible id shared-dma-pool
[    0.000000] Reserved memory: created DMA memory pool at 0x00000000a7000000, size 1 MiB
[    0.000000] OF: reserved mem: initialized node c66-dma-memory@a7000000, compatible id shared-dma-pool
[    0.000000] Reserved memory: created DMA memory pool at 0x00000000a7100000, size 15 MiB
[    0.000000] OF: reserved mem: initialized node c66-memory@a7100000, compatible id shared-dma-pool
[    0.000000] Reserved memory: created DMA memory pool at 0x00000000a8000000, size 1 MiB
[    0.000000] OF: reserved mem: initialized node c71-dma-memory@a8000000, compatible id shared-dma-pool
[    0.000000] Reserved memory: created DMA memory pool at 0x00000000a8100000, size 15 MiB
[    0.000000] OF: reserved mem: initialized node c71-memory@a8100000, compatible id shared-dma-pool
[    0.000000] Reserved memory: created DMA memory pool at 0x00000000ac000000, size 32 MiB
[    0.000000] OF: reserved mem: initialized node vision_apps-dma-memory@ac000000, compatible id shared-dma-pool
[    0.000000] OF: reserved mem: initialized node vision_apps_shared-memories, compatible id dma-heap-carveout
[    0.000000] Reserved memory: created DMA memory pool at 0x00000000ce000000, size 720 MiB
[    0.000000] OF: reserved mem: initialized node vision_apps-core-heap-memory@ce000000, compatible id shared-dma-pool
[    0.000000] Reserved memory: created DMA memory pool at 0x00000000fb000000, size 1 MiB
[    0.000000] OF: reserved mem: initialized node r5f-dma-memory@fb000000, compatible id shared-dma-pool
[    0.000000] Reserved memory: created DMA memory pool at 0x00000000fb100000, size 15 MiB
[    0.000000] OF: reserved mem: initialized node r5f-memory@fb100000, compatible id shared-dma-pool
[    0.000000] cma: Failed to reserve 512 MiB
[    0.000000] psci: probing for conduit method from DT.
[    0.000000] psci: PSCIv1.1 detected in firmware.
[    0.000000] psci: Using standard PSCI v0.2 function IDs
[    0.000000] psci: Trusted OS migration not required
[    0.000000] psci: SMC Calling Convention v1.0
[    0.000000] percpu: Embedded 2 pages/cpu s48408 r8192 d74472 u131072
[    0.000000] Detected PIPT I-cache on CPU0
[    0.000000] CPU features: detected: GIC system register CPU interface
[    0.000000] CPU features: detected: EL2 vector hardening
[    0.000000] Built 1 zonelists, mobility grouping off.  Total pages: 35779
[    0.000000] Kernel command line: console=ttyS2,115200n8 earlycon=ns16550a,mmio32,0x02800000 mtdparts=47040000.spi.0:512k(ospi.tiboot3),2m(ospi.tispl),4m(ospi.u-boot),128k(ospi.env),128k(ospi.env.backup),1m(ospi.sysfw),-@8m(ospi.rootfs);47034000.hyperbus:512k(hbmc.tiboot3),2m(hbmc.tispl),4m(hbmc.u-boot),256k(hbmc.env),1m(hbmc.sysfw),-@8m(hbmc.rootfs) root=PARTUUID=a8d32d68-02 rw rootfstype=ext4 rootwait
[    0.000000] Dentry cache hash table entries: 524288 (order: 6, 4194304 bytes, linear)
[    0.000000] Inode-cache hash table entries: 262144 (order: 5, 2097152 bytes, linear)
[    0.000000] mem auto-init: stack:off, heap alloc:off, heap free:off
[    0.000000] software IO TLB: mapped [mem 0x9a800000-0x9e800000] (64MB)
[    0.000000] Memory: 2583168K/2293760K available (9662K kernel code, 974K rwdata, 4096K rodata, 1920K init, 689K bss, 18446744073709262208K reserved, 0K cma-reserved)
[    0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=2, Nodes=1
[    0.000000] rcu: Preemptible hierarchical RCU implementation.
[    0.000000] rcu:     RCU restricting CPUs from NR_CPUS=256 to nr_cpu_ids=2.
[    0.000000]  Tasks RCU enabled.
[    0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies.
[    0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=2
[    0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0
[    0.000000] GICv3: GIC: Using split EOI/Deactivate mode
[    0.000000] GICv3: 960 SPIs implemented
[    0.000000] GICv3: 0 Extended SPIs implemented
[    0.000000] GICv3: Distributor has no Range Selector support
[    0.000000] GICv3: 16 PPIs implemented
[    0.000000] GICv3: no VLPI support, no direct LPI support
[    0.000000] GICv3: CPU0: found redistributor 0 region 0:0x0000000001900000
[    0.000000] ITS [mem 0x01820000-0x0182ffff]
[    0.000000] GIC: enabling workaround for ITS: Socionext Synquacer pre-ITS
[    0.000000] ITS@0x0000000001820000: allocated 1048576 Devices @8c0800000 (flat, esz 8, psz 64K, shr 0)
[    0.000000] ITS: using cache flushing for cmd queue
[    0.000000] GICv3: using LPI property table @0x00000008c00c0000
[    0.000000] GIC: using cache flushing for LPI property table
[    0.000000] GICv3: CPU0: using allocated LPI pending table @0x00000008c00d0000
[    0.000000] random: get_random_bytes called from start_kernel+0x2b8/0x438 with crng_init=0
[    0.000000] arch_timer: cp15 timer(s) running at 200.00MHz (phys).
[    0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x2e2049d3e8, max_idle_ns: 440795210634 ns
[    0.000001] sched_clock: 56 bits at 200MHz, resolution 5ns, wraps every 4398046511102ns
[    0.008377] Console: colour dummy device 80x25
[    0.012938] Calibrating delay loop (skipped), value calculated using timer frequency.. 400.00 BogoMIPS (lpj=800000)
[    0.023606] pid_max: default: 32768 minimum: 301
[    0.028354] LSM: Security Framework initializing
[    0.033100] Mount-cache hash table entries: 8192 (order: 0, 65536 bytes, linear)
[    0.040669] Mountpoint-cache hash table entries: 8192 (order: 0, 65536 bytes, linear)
[    0.072724] ASID allocator initialised with 32768 entries
[    0.086253] rcu: Hierarchical SRCU implementation.
[    0.099788] Platform MSI: gic-its@1820000 domain created
[    0.105400] PCI/MSI: /interconnect@100000/interrupt-controller@1800000/gic-its@1820000 domain created
[    0.122835] smp: Bringing up secondary CPUs ...
[    0.159798] Detected PIPT I-cache on CPU1
[    0.159819] GICv3: CPU1: found redistributor 1 region 0:0x0000000001920000
[    0.159830] GICv3: CPU1: using allocated LPI pending table @0x00000008c00e0000
[    0.159858] CPU1: Booted secondary processor 0x0000000001 [0x411fd080]
[    0.159909] smp: Brought up 1 node, 2 CPUs
[    0.189254] SMP: Total of 2 processors activated.
[    0.194058] CPU features: detected: 32-bit EL0 Support
[    0.199312] CPU features: detected: CRC32 instructions
[    0.210908] CPU: All CPU(s) started at EL2
[    0.215100] alternatives: patching kernel code
[    0.220138] devtmpfs: initialized
[    0.228396] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns
[    0.238366] futex hash table entries: 512 (order: -1, 32768 bytes, linear)
[    0.245539] pinctrl core: initialized pinctrl subsystem
[    0.251284] NET: Registered protocol family 16
[    0.256008] DMA: preallocated 256 KiB pool for atomic allocations
[    0.262490] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers.
[    0.277839] HugeTLB registered 16.0 GiB page size, pre-allocated 0 pages
[    0.284692] HugeTLB registered 512 MiB page size, pre-allocated 0 pages
[    0.291451] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages
[    0.300092] cryptd: max_cpu_qlen set to 1000
[    0.307315] vsys_3v3: supplied by evm_12v0
[    0.311630] vsys_5v0: supplied by evm_12v0
[    0.316239] iommu: Default domain type: Translated
[    0.321388] SCSI subsystem initialized
[    0.325675] mc: Linux media interface: v0.10
[    0.330056] videodev: Linux video capture interface: v2.00
[    0.335667] pps_core: LinuxPPS API ver. 1 registered
[    0.340736] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>
[    0.350072] PTP clock support registered
[    0.354082] EDAC MC: Ver: 3.0.0
[    0.357824] FPGA manager framework
[    0.361339] Advanced Linux Sound Architecture Driver Initialized.
[    0.367969] clocksource: Switched to clocksource arch_sys_counter
[    0.374284] VFS: Disk quotas dquot_6.6.0
[    0.378328] VFS: Dquot-cache hash table entries: 8192 (order 0, 65536 bytes)
[    0.387728] Carveout Heap: Exported 512 MiB at 0x00000000ae000000
[    0.393961] thermal_sys: Registered thermal governor 'step_wise'
[    0.393963] thermal_sys: Registered thermal governor 'power_allocator'
[    0.400371] NET: Registered protocol family 2
[    0.411759] tcp_listen_portaddr_hash hash table entries: 4096 (order: 0, 65536 bytes, linear)
[    0.420518] TCP established hash table entries: 32768 (order: 2, 262144 bytes, linear)
[    0.428713] TCP bind hash table entries: 32768 (order: 3, 524288 bytes, linear)
[    0.436479] TCP: Hash tables configured (established 32768 bind 32768)
[    0.443217] UDP hash table entries: 2048 (order: 0, 65536 bytes, linear)
[    0.450108] UDP-Lite hash table entries: 2048 (order: 0, 65536 bytes, linear)
[    0.457506] NET: Registered protocol family 1
[    0.462222] RPC: Registered named UNIX socket transport module.
[    0.468277] RPC: Registered udp transport module.
[    0.473080] RPC: Registered tcp transport module.
[    0.477882] RPC: Registered tcp NFSv4.1 backchannel transport module.
[    0.484466] PCI: CLS 0 bytes, default 64
[    0.488810] hw perfevents: enabled with armv8_pmuv3 PMU driver, 7 counters available
[    0.500272] Initialise system trusted keyrings
[    0.504887] workingset: timestamp_bits=46 max_order=16 bucket_order=0
[    0.513443] squashfs: version 4.0 (2009/01/31) Phillip Lougher
[    0.519725] NFS: Registering the id_resolver key type
[    0.524898] Key type id_resolver registered
[    0.529169] Key type id_legacy registered
[    0.533264] nfs4filelayout_init: NFSv4 File Layout Driver Registering...
[    0.540217] 9p: Installing v9fs 9p2000 file system support
[    0.553550] Key type asymmetric registered
[    0.557735] Asymmetric key parser 'x509' registered
[    0.562745] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 244)
[    0.570307] io scheduler mq-deadline registered
[    0.574932] io scheduler kyber registered
[    0.580168] pinctrl-single 4301c000.pinmux: 94 pins, size 376
[    0.586243] pinctrl-single 11c000.pinmux: 173 pins, size 692
[    0.592387] pinctrl-single a40000.timesync_router: 512 pins, size 2048
[    0.601536] k3-ringacc 2b800000.ringacc: Failed to get MSI domain
[    0.607804] k3-ringacc 3c000000.ringacc: Failed to get MSI domain
[    0.614136] ti-pat 31010000.pat: Found PAT Rev 1.0 with 16384 pages
[    0.620546] debugfs: Directory '31010000.pat' with parent 'regmap' already present!
[    0.628526] ti-pat 31011000.pat: Found PAT Rev 1.0 with 16384 pages
[    0.634936] debugfs: Directory '31011000.pat' with parent 'regmap' already present!
[    0.642855] ti-pat 31012000.pat: Found PAT Rev 1.0 with 16384 pages
[    0.649271] debugfs: Directory '31012000.pat' with parent 'regmap' already present!
[    0.657232] ti-pat 31013000.pat: Found PAT Rev 1.0 with 2048 pages
[    0.663558] debugfs: Directory '31013000.pat' with parent 'regmap' already present!
[    0.671481] ti-pat 31014000.pat: Found PAT Rev 1.0 with 2048 pages
[    0.677802] debugfs: Directory '31014000.pat' with parent 'regmap' already present!
[    0.687155] Serial: 8250/16550 driver, 10 ports, IRQ sharing enabled
[    0.699110] brd: module loaded
[    0.705123] loop: module loaded
[    0.708673] sysfs: cannot create duplicate filename '/devices/platform/dma_buf_phys'
[    0.716599] CPU: 0 PID: 1 Comm: swapper/0 Not tainted 5.4.40-g66cf445b76 #2
[    0.723708] Hardware name: Texas Instruments K3 J721E SoC (DT)
[    0.729663] Call trace:
[    0.732161]  dump_backtrace+0x0/0x168
[    0.735895]  show_stack+0x14/0x20
[    0.739278]  dump_stack+0xb4/0xf8
[    0.742663]  sysfs_warn_dup+0x5c/0x78
[    0.746397]  sysfs_create_dir_ns+0xd8/0xf0
[    0.750578]  kobject_add_internal+0x98/0x2c8
[    0.754934]  kobject_add+0x90/0xf8
[    0.758404]  device_add+0xe0/0x628
[    0.761871]  platform_device_add+0x100/0x268
[    0.766227]  platform_device_register_full+0xc8/0x140
[    0.771385]  dma_buf_phys_init+0x68/0x94
[    0.775387]  do_one_initcall+0x50/0x190
[    0.779302]  kernel_init_freeable+0x194/0x22c
[    0.783749]  kernel_init+0x10/0xfc
[    0.787217]  ret_from_fork+0x10/0x18
[    0.790871] kobject_add_internal failed for dma_buf_phys with -EEXIST, don't try to register things with the same name in the same directory.
[    0.804994] libphy: Fixed MDIO Bus: probed
[    0.809387] tun: Universal TUN/TAP device driver, 1.6
[    0.814836] igbvf: Intel(R) Gigabit Virtual Function Network Driver - version 2.4.0-k
[    0.822839] igbvf: Copyright (c) 2009 - 2012 Intel Corporation.
[    0.828905] sky2: driver version 1.30
[    0.833167] VFIO - User Level meta-driver version: 0.3
[    0.838796] i2c /dev entries driver
[    0.842874] sdhci: Secure Digital Host Controller Interface driver
[    0.849187] sdhci: Copyright(c) Pierre Ossman
[    0.853874] sdhci-pltfm: SDHCI platform and OF driver helper
[    0.860177] ledtrig-cpu: registered to indicate activity on CPUs
[    0.867062] optee: probing for conduit method from DT.
[    0.872326] optee: revision 3.8 (199fca17)
[    0.872626] optee: initialized driver
[    0.881484] NET: Registered protocol family 17
[    0.886104] 9pnet: Installing 9P2000 support
[    0.890496] Key type dns_resolver registered
[    0.895007] registered taskstats version 1
[    0.899194] Loading compiled-in X.509 certificates
[    0.907793] k3-ringacc 2b800000.ringacc: Failed to get MSI domain
[    0.914129] k3-ringacc 3c000000.ringacc: Failed to get MSI domain
[    0.921893] ti-sci 44083000.dmsc: ABI: 3.0 (firmware rev 0x0014 '20.04.1-v2020.04a (Terrific Lla')
[    0.947997] random: fast init done
[    0.992943] rtc-abx80x 0-0069: model 0805, revision 2.3, lot 373, wafer 1, uid 6eb6
[    1.002766] rtc-abx80x 0-0069: Oscillator failure, data is invalid.
[    1.009217] rtc-abx80x 0-0069: registered as rtc0
[    1.014045] omap_i2c 40b00000.i2c: bus 0 rev0.12 at 100 kHz
[    1.020294] omap_i2c 40b10000.i2c: bus 1 rev0.12 at 400 kHz
[    1.026448] omap_i2c 42120000.i2c: bus 2 rev0.12 at 400 kHz
[    1.032467] omap_i2c 2000000.i2c: bus 3 rev0.12 at 400 kHz
[    1.038359] omap_i2c 2010000.i2c: bus 4 rev0.12 at 400 kHz
[    1.044223] omap_i2c 2030000.i2c: bus 6 rev0.12 at 100 kHz
[    1.050134] omap_i2c 2040000.i2c: bus 7 rev0.12 at 100 kHz
[    1.056035] omap_i2c 2050000.i2c: bus 8 rev0.12 at 100 kHz
[    1.061912] omap_i2c 2060000.i2c: bus 9 rev0.12 at 100 kHz
[    1.068231] ti-sci-intr interconnect@100000:interconnect@28380000:interrupt-controller2: Interrupt Router 137 domain created
[    1.079797] ti-sci-intr interconnect@100000:interrupt-controller0: Interrupt Router 131 domain created
[    1.089405] ti-sci-intr interconnect@100000:navss@30000000:interrupt-controller1: Interrupt Router 213 domain created
[    1.100413] ti-sci-inta 33d00000.interrupt-controller: Interrupt Aggregator domain 209 created
[    1.112740] k3-ringacc 2b800000.ringacc: Ring Accelerator probed rings:286, gp-rings[96,20] sci-dev-id:235
[    1.122619] k3-ringacc 2b800000.ringacc: dma-ring-reset-quirk: disabled
[    1.129379] k3-ringacc 2b800000.ringacc: RA Proxy rev. 66346100, num_proxies:64
[    1.138241] k3-ringacc 3c000000.ringacc: Ring Accelerator probed rings:1024, gp-rings[440,150] sci-dev-id:211
[    1.148395] k3-ringacc 3c000000.ringacc: dma-ring-reset-quirk: disabled
[    1.155154] k3-ringacc 3c000000.ringacc: RA Proxy rev. 66346100, num_proxies:64
[    1.162859] omap8250 2800000.serial: PM domain pd:146 will not be powered off
[    1.170295] printk: console [ttyS2] disabled
[    1.174676] 2800000.serial: ttyS2 at MMIO 0x2800000 (irq = 17, base_baud = 3000000) is a 8250
[    1.183412] printk: console [ttyS2] enabled
[    1.183412] printk: console [ttyS2] enabled
[    1.191847] printk: bootconsole [ns16550a0] disabled
[    1.191847] printk: bootconsole [ns16550a0] disabled
[    1.202243] 2810000.serial: ttyS3 at MMIO 0x2810000 (irq = 18, base_baud = 3000000) is a 8250
[    1.211091] 2840000.serial: ttyS6 at MMIO 0x2840000 (irq = 19, base_baud = 3000000) is a 8250
[    1.219850] arm-smmu-v3 36600000.smmu: ias 48-bit, oas 48-bit (features 0x00001faf)
[    1.228757] arm-smmu-v3 36600000.smmu: allocated 524288 entries for cmdq
[    1.237620] arm-smmu-v3 36600000.smmu: allocated 524288 entries for evtq
[    1.245174] arm-smmu-v3 36600000.smmu: msi_domain absent - falling back to wired irqs
[    1.253948] cdns-ufshcd 4e84000.ufs: ufshcd_populate_vreg: Unable to find vdd-hba-supply regulator, assuming enabled
[    1.264462] cdns-ufshcd 4e84000.ufs: ufshcd_populate_vreg: Unable to find vcc-supply regulator, assuming enabled
[    1.274614] cdns-ufshcd 4e84000.ufs: ufshcd_populate_vreg: Unable to find vccq-supply regulator, assuming enabled
[    1.284852] cdns-ufshcd 4e84000.ufs: ufshcd_populate_vreg: Unable to find vccq2-supply regulator, assuming enabled
[    1.295609] scsi host0: ufshcd
[    1.302403] cadence-qspi 47040000.spi: mt35xu512aba (65536 Kbytes)
[    1.308582] 7 cmdlinepart partitions found on MTD device 47040000.spi.0
[    1.315179] Creating 7 MTD partitions on "47040000.spi.0":
[    1.320652] 0x000000000000-0x000000080000 : "ospi.tiboot3"
[    1.326652] 0x000000080000-0x000000280000 : "ospi.tispl"
[    1.332368] 0x000000280000-0x000000680000 : "ospi.u-boot"
[    1.338103] 0x000000680000-0x0000006a0000 : "ospi.env"
[    1.343664] 0x0000006a0000-0x0000006c0000 : "ospi.env.backup"
[    1.349751] 0x0000006c0000-0x0000007c0000 : "ospi.sysfw"
[    1.355457] 0x000000800000-0x000004000000 : "ospi.rootfs"
[    1.596206] cadence-qspi 47040000.spi: Cadence QSPI NOR probe failed -517
[    1.603875] cadence-qspi 47050000.spi: unrecognized JEDEC id bytes: 00 00 00 00 00 00
[    1.611700] cadence-qspi 47050000.spi: Cadence QSPI NOR probe failed -2
[    1.618531] cadence-qspi: probe of 47050000.spi failed with error -2
[    1.663973] davinci_mdio 46000f00.mdio: davinci mdio revision 9.7, bus freq 1000000
[    1.671616] libphy: 46000f00.mdio: probed
[    1.675832] mdio_bus 46000f00.mdio: MDIO device at address 0 is missing.
[    1.682536] am65-cpsw-nuss 46000000.ethernet: initializing am65 cpsw nuss version 0x6BA00101, cpsw version 0x6BA80100 Ports: 2
[    1.693956] am65-cpsw-nuss 46000000.ethernet: Failed to request tx dma channel -517
[    1.702583] am65-cpts 310d0000.cpts: CPTS ver 0x4e8a010a, freq:200000000, add_val:4 pps:0
[    1.711167] mmc0: CQHCI version 5.10
[    1.715858] cdns-ufshcd 4e84000.ufs: link startup failed 1
[    1.721339] cdns-ufshcd 4e84000.ufs: UFS Host state=0
[    1.726378] cdns-ufshcd 4e84000.ufs: lrb in use=0x0, outstanding reqs=0x0 tasks=0x0
[    1.734016] cdns-ufshcd 4e84000.ufs: saved_err=0x0, saved_uic_err=0x0
[    1.740441] cdns-ufshcd 4e84000.ufs: Device power mode=1, UIC link state=0
[    1.747298] cdns-ufshcd 4e84000.ufs: PM in progress=0, sys. suspended=0
[    1.748535] mmc0: SDHCI controller on 4f80000.sdhci [4f80000.sdhci] using ADMA 64-bit
[    1.753896] cdns-ufshcd 4e84000.ufs: Auto BKOPS=0, Host self-block=0
[    1.753898] cdns-ufshcd 4e84000.ufs: Clk gate=1
[    1.768230] vdd_mmc1: supplied by vsys_3v3
[    1.772564] cdns-ufshcd 4e84000.ufs: error handling flags=0x0, req. abort count=0
[    1.772566] cdns-ufshcd 4e84000.ufs: Host capabilities=0x1587031f, caps=0x0
[    1.772568] cdns-ufshcd 4e84000.ufs: quirks=0x0, dev. quirks=0x0
[    1.772573] cdns-ufshcd 4e84000.ufs: ufshcd_print_pwr_info:[RX, TX]: gear=[0, 0], lane[0, 0], pwr[INVALID MODE, INVALID MODE], rate = 0
[    1.776869] vdd_wifi_en: supplied by vsys_3v3
[    1.784133] host_regs: 00000000: 1587031f 00000000 00000210 00000000
[    1.791248] vdd_mmc2: supplied by vdd_wifi_en
[    1.797057] host_regs: 00000010: 00000000 00000000 00000000 00000000
[    1.797059] host_regs: 00000020: 00000000 00000470 00000000 00000000
[    1.797062] host_regs: 00000030: 00000008 00000001 00000000 00000000
[    1.797064] host_regs: 00000040: 00000000 00000000 00000000 00000000
[    1.809764] omap-mailbox 31f80000.mailbox: omap mailbox rev 0x66fc7100
[    1.813549] host_regs: 00000050: 00000000 00000000 00000000 00000000
[    1.813551] host_regs: 00000060: 00000000 00000000 00000000 00000000
[    1.813553] host_regs: 00000070: 00000000 00000000 00000000 00000000
[    1.813555] host_regs: 00000080: 00000000 00000000 00000000 00000000
[    1.820343] omap-mailbox 31f81000.mailbox: omap mailbox rev 0x66fc7100
[    1.824239] host_regs: 00000090: 00000000 00000000 00000000 00000000
[    1.830889] omap-mailbox 31f82000.mailbox: omap mailbox rev 0x66fc7100
[    1.836909] cdns-ufshcd 4e84000.ufs: hba->ufs_version = 0x210, hba->capabilities = 0x1587031f
[    1.836913] cdns-ufshcd 4e84000.ufs: hba->outstanding_reqs = 0x0, hba->outstanding_tasks = 0x0
[    1.843579] omap-mailbox 31f83000.mailbox: omap mailbox rev 0x66fc7100
[    1.849587] cdns-ufshcd 4e84000.ufs: last_hibern8_exit_tstamp at 0 us, hibern8_exit_cnt = 0
[    1.849590] cdns-ufshcd 4e84000.ufs: No record of pa_err errors
[    1.849593] cdns-ufshcd 4e84000.ufs: No record of dl_err errors
[    1.849595] cdns-ufshcd 4e84000.ufs: No record of nl_err errors
[    1.856478] omap-mailbox 31f84000.mailbox: omap mailbox rev 0x66fc7100
[    1.862439] cdns-ufshcd 4e84000.ufs: No record of tl_err errors
[    1.862442] cdns-ufshcd 4e84000.ufs: No record of dme_err errors
[    1.862444] cdns-ufshcd 4e84000.ufs: No record of auto_hibern8_err errors
[    1.919529] mmc0: Command Queue Engine enabled
[    1.924418] cdns-ufshcd 4e84000.ufs: No record of fatal_err errors
[    1.932749] mmc0: new HS400 MMC card at address 0001
[    1.938645] cdns-ufshcd 4e84000.ufs: link_startup_fail[0] = 0x1 at 1627894 us
[    1.938647] cdns-ufshcd 4e84000.ufs: No record of resume_fail errors
[    1.938650] cdns-ufshcd 4e84000.ufs: No record of suspend_fail errors
[    1.938652] cdns-ufshcd 4e84000.ufs: No record of dev_reset errors
[    1.938654] cdns-ufshcd 4e84000.ufs: No record of host_reset errors
[    1.938657] cdns-ufshcd 4e84000.ufs: No record of task_abort errors
[    1.948880] mmcblk0: mmc0:0001 S0J56X 14.8 GiB
[    1.950471] cdns-ufshcd 4e84000.ufs: clk: core_clk, rate: 250000000
[    1.957105] mmcblk0boot0: mmc0:0001 S0J56X partition 1 31.5 MiB
[    1.962878] cdns-ufshcd 4e84000.ufs: clk: phy_clk, rate: 19200000
[    1.968991] mmcblk0boot1: mmc0:0001 S0J56X partition 2 31.5 MiB
[    1.975635] cdns-ufshcd 4e84000.ufs: clk: ref_clk, rate: 19200000
[    2.064529] mmcblk0rpmb: mmc0:0001 S0J56X partition 3 4.00 MiB, chardev (240:0)
[    2.871888] j721e-pcie 2930000.pcie: host bridge /interconnect@100000/pcie@2930000 ranges:
[    2.880166] j721e-pcie 2930000.pcie:    IO 0x4410001000..0x4410010fff -> 0x00001000
[    2.887813] j721e-pcie 2930000.pcie:   MEM 0x4410011000..0x4417ffffff -> 0x00011000
[    2.895556] j721e-pcie 2930000.pcie: PCI host bridge to bus 0000:00
[    2.901811] pci_bus 0000:00: root bus resource [bus 00-0f]
[    2.907285] pci_bus 0000:00: root bus resource [io  0x0000-0xffff] (bus address [0x1000-0x10fff])
[    2.916137] pci_bus 0000:00: root bus resource [mem 0x4410011000-0x4417ffffff] (bus address [0x00011000-0x07ffffff])
[    2.926652] pci 0000:00:00.0: [104c:b00d] type 01 class 0x060400
[    2.932655] pci_bus 0000:00: 2-byte config write to 0000:00:00.0 offset 0x4 may corrupt adjacent RW1C bits
[    2.942291] pci_bus 0000:00: 2-byte config write to 0000:00:00.0 offset 0x4 may corrupt adjacent RW1C bits
[    2.951944] pci_bus 0000:00: 2-byte config write to 0000:00:00.0 offset 0xe8 may corrupt adjacent RW1C bits
[    2.961664] pci_bus 0000:00: 2-byte config write to 0000:00:00.0 offset 0x3e may corrupt adjacent RW1C bits
[    2.971392] pci_bus 0000:00: 2-byte config write to 0000:00:00.0 offset 0x92 may corrupt adjacent RW1C bits
[    2.981115] pci_bus 0000:00: 2-byte config write to 0000:00:00.0 offset 0xb2 may corrupt adjacent RW1C bits
[    2.990859] pci 0000:00:00.0: supports D1
[    2.994857] pci 0000:00:00.0: PME# supported from D0 D1 D3hot
[    3.000591] pci_bus 0000:00: 2-byte config write to 0000:00:00.0 offset 0x84 may corrupt adjacent RW1C bits
[    3.012289] pci 0000:00:00.0: bridge configuration invalid ([bus 00-00]), reconfiguring
[    3.020276] pci_bus 0000:00: 2-byte config write to 0000:00:00.0 offset 0x3e may corrupt adjacent RW1C bits
[    3.029996] pci_bus 0000:00: 2-byte config write to 0000:00:00.0 offset 0x3e may corrupt adjacent RW1C bits
[    3.039716] pci_bus 0000:00: 2-byte config write to 0000:00:00.0 offset 0x3e may corrupt adjacent RW1C bits
[    3.051095] pci_bus 0000:01: busn_res: [bus 01-0f] end is updated to 01
[    3.057705] pci 0000:00:00.0: PCI bridge to [bus 01]
[    3.062872] pcieport 0000:00:00.0: PME: Signaling with IRQ 474
[    3.068825] pcieport 0000:00:00.0: AER: enabled with IRQ 474
[    3.075544] ti-udma 285c0000.dma-controller: Channels: 24 (tchan: 12, rchan: 12, gp-rflow: 8)
[    3.085904] ti-udma 31150000.dma-controller: Channels: 84 (tchan: 42, rchan: 42, gp-rflow: 16)
[    3.097982] cadence-qspi 47040000.spi: mt35xu512aba (65536 Kbytes)
[    3.104160] 7 cmdlinepart partitions found on MTD device 47040000.spi.0
[    3.110759] Creating 7 MTD partitions on "47040000.spi.0":
[    3.116231] 0x000000000000-0x000000080000 : "ospi.tiboot3"
[    3.122208] 0x000000080000-0x000000280000 : "ospi.tispl"
[    3.127880] 0x000000280000-0x000000680000 : "ospi.u-boot"
[    3.133667] 0x000000680000-0x0000006a0000 : "ospi.env"
[    3.139206] 0x0000006a0000-0x0000006c0000 : "ospi.env.backup"
[    3.145287] 0x0000006c0000-0x0000007c0000 : "ospi.sysfw"
[    3.150967] 0x000000800000-0x000004000000 : "ospi.rootfs"
[    3.195989] davinci_mdio 46000f00.mdio: davinci mdio revision 9.7, bus freq 1000000
[    3.203635] libphy: 46000f00.mdio: probed
[    3.207851] mdio_bus 46000f00.mdio: MDIO device at address 0 is missing.
[    3.214553] am65-cpsw-nuss 46000000.ethernet: initializing am65 cpsw nuss version 0x6BA00101, cpsw version 0x6BA80100 Ports: 2
[    3.226719] am65-cpsw-nuss 46000000.ethernet: set new flow-id-base 48
[    3.233904] pps pps0: new PPS source ptp1
[    3.238046] am65-cpsw-nuss 46000000.ethernet: CPTS ver 0x4e8a010a, freq:500000000, add_val:1 pps:1
[    3.246991] am65-cpsw-nuss 46000000.ethernet: initialized cpsw ale version 57.4
[    3.254283] am65-cpsw-nuss 46000000.ethernet: ALE Table size 64
[    3.261229] mmc1: CQHCI version 5.10
[    3.295653] mmc1: SDHCI controller on 4fb0000.sdhci [4fb0000.sdhci] using ADMA 64-bit
[    3.303910] mmc2: CQHCI version 5.10
[    3.342365] mmc2: SDHCI controller on 4f98000.sdhci [4f98000.sdhci] using ADMA 64-bit
[    3.350740] gpio-mux analog-mux0: 2-way mux-controller registered
[    3.358065] debugfs: Directory 'pd:242' with parent 'pm_genpd' already present!
[    3.365440] debugfs: Directory 'pd:241' with parent 'pm_genpd' already present!
[    3.372745] debugfs: Directory 'pd:240' with parent 'pm_genpd' already present!
[    3.380048] debugfs: Directory 'pd:239' with parent 'pm_genpd' already present!
[    3.388939] rtc-abx80x 0-0069: Oscillator failure, data is invalid.
[    3.395217] rtc-abx80x 0-0069: hctosys: unable to read the hardware clock
[    3.413400] ALSA device list:
[    3.416361]   No soundcards found.
[    3.420071] Waiting for root device PARTUUID=a8d32d68-02...
[    4.804083] mmc1: new high speed SDHC card at address aaaa
[    4.809947] mmcblk1: mmc1:aaaa SA16G 14.8 GiB
[    4.820125]  mmcblk1: p1 p2
[    5.151686] EXT4-fs (mmcblk1p2): recovery complete
[    5.163011] EXT4-fs (mmcblk1p2): mounted filesystem with ordered data mode. Opts: (null)
[    5.171121] VFS: Mounted root (ext4 filesystem) on device 179:98.
[    5.181591] devtmpfs: mounted
[    5.184994] Freeing unused kernel memory: 1920K
[    5.189571] Run /sbin/init as init process
[    5.454515] systemd[1]: System time before build time, advancing clock.
[    5.538472] NET: Registered protocol family 10
[    5.543322] Segment Routing with IPv6
[    5.568828] systemd[1]: systemd 244.3+ running in system mode. (+PAM -AUDIT -SELINUX +IMA -APPARMOR -SMACK +SYSVINIT +UTMP -LIBCRYPTSETUP -GCRYPT -GNUTLS +ACL +XZ -LZ4 -SECCOMP +BLKID -ELFUTILS +KMOD -IDN2 -IDN -PCRE2 default-hierarchy=hybrid)
[    5.590586] systemd[1]: Detected architecture arm64.

Welcome to Arago 2020.05!

[    5.638027] systemd[1]: Set hostname to <j7-evm>.
[    5.897703] systemd[1]: /lib/systemd/system/docker.socket:6: ListenStream= references a path below legacy directory /var/run/, updating /var/run/docker.sock → /run/docker.sock; please update the unit file accordingly.
[    5.973252] random: systemd: uninitialized urandom read (16 bytes read)
[    5.979995] systemd[1]: system-getty.slice: unit configures an IP firewall, but the local system does not support BPF/cgroup firewalling.
[    5.992327] systemd[1]: (This warning is only shown for the first unit using IP firewalling.)
[    6.002260] systemd[1]: Created slice system-getty.slice.
[  OK  ] Created slice system-getty.slice.
[    6.024053] random: systemd: uninitialized urandom read (16 bytes read)
[    6.031281] systemd[1]: Created slice system-serial\x2dgetty.slice.
[  OK  ] Created slice system-serial\x2dgetty.slice.
[    6.048024] random: systemd: uninitialized urandom read (16 bytes read)
[    6.055127] systemd[1]: Created slice User and Session Slice.
[  OK  ] Created slice User and Session Slice.
[    6.076144] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.
[  OK  ] Started Dispatch Password …ts to Console Directory Watch.
[    6.100073] systemd[1]: Started Forward Password Requests to Wall Directory Watch.
[  OK  ] Started Forward Password R…uests to Wall Directory Watch.
[    6.124067] systemd[1]: Reached target Paths.
[  OK  ] Reached target Paths.
[    6.140023] systemd[1]: Reached target Remote File Systems.
[  OK  ] Reached target Remote File Systems.
[    6.156015] systemd[1]: Reached target Slices.
[  OK  ] Reached target Slices.
[    6.172019] systemd[1]: Reached target Swap.
[  OK  ] Reached target Swap.
[    6.196536] systemd[1]: Listening on RPCbind Server Activation Socket.
[  OK  ] Listening on RPCbind Server Activation Socket.
[    6.220049] systemd[1]: Reached target RPC Port Mapper.
[  OK  ] Reached target RPC Port Mapper.
[    6.238859] systemd[1]: Listening on Process Core Dump Socket.
[  OK  ] Listening on Process Core Dump Socket.
[    6.256158] systemd[1]: Listening on initctl Compatibility Named Pipe.
[  OK  ] Listening on initctl Compatibility Named Pipe.
[    6.291647] systemd[1]: Condition check resulted in Journal Audit Socket being skipped.
[    6.299993] systemd[1]: Listening on Journal Socket (/dev/log).
[  OK  ] Listening on Journal Socket (/dev/log).
[    6.316219] systemd[1]: Listening on Journal Socket.
[  OK  ] Listening on Journal Socket.
[    6.332289] systemd[1]: Listening on Network Service Netlink Socket.
[  OK  ] Listening on Network Service Netlink Socket.
[    6.352188] systemd[1]: Listening on udev Control Socket.
[  OK  ] Listening on udev Control Socket.
[    6.372118] systemd[1]: Listening on udev Kernel Socket.
[  OK  ] Listening on udev Kernel Socket.
[    6.395310] systemd[1]: Mounting Huge Pages File System...
         Mounting Huge Pages File System...
[    6.414597] systemd[1]: Mounting POSIX Message Queue File System...
         Mounting POSIX Message Queue File System...
[    6.439277] systemd[1]: Mounting Kernel Debug File System...
         Mounting Kernel Debug File System...
[    6.464338] systemd[1]: Mounting Temporary Directory (/tmp)...
         Mounting Temporary Directory (/tmp)...
[    6.483429] systemd[1]: Starting Create list of static device nodes for the current kernel...
         Starting Create list of st…odes for the current kernel...
[    6.511119] systemd[1]: Starting Start psplash boot splash screen...
         Starting Start psplash boot splash screen...
[    6.543635] systemd[1]: Started Hardware RNG Entropy Gatherer Daemon.
[  OK  ] Started Hardware RNG Entropy Gatherer Daemon.
[    6.566864] systemd[1]: Starting RPC Bind...
         Starting RPC Bind...
[    6.580139] systemd[1]: Condition check resulted in File System Check on Root Device being skipped.
[    6.592639] systemd[1]: Starting Journal Service...
         Starting Journal Service...
[    6.618342] systemd[1]: Starting Load Kernel Modules...
         Starting Load Kernel Modules...
[    6.631946] cryptodev: loading out-of-tree module taints kernel.
[    6.643474] systemd[1]: Starting Remount Root and Kernel File Systems...
[    6.648132] cryptodev: driver 1.10 loaded.
         Starting Remount Root and Kernel File Systems...
[    6.674729] systemd[1]: Starting udev Coldplug all Devices...
[    6.683229] EXT4-fs (mmcblk1p2): re-mounted. Opts: (null)
         Starting udev Coldplug all Devices...
[    6.702758] systemd[1]: Started RPC Bind.
[  OK  ] Started RPC Bind.
[    6.721132] systemd[1]: Mounted Huge Pages File System.
[  OK  ] Mounted Huge Pages File System.
[    6.740384] systemd[1]: Started Journal Service.
[  OK  ] Started Journal Service.
[  OK  ] Mounted POSIX Message Queue File System.
[  OK  ] Mounted Kernel Debug File System.
[  OK  ] Mounted Temporary Directory (/tmp).
[  OK  ] Started Create list of sta… nodes for the current kernel.
[FAILED] Failed to start Start psplash boot splash screen.
See 'systemctl status psplash-start.service' for details.
[DEPEND] Dependency failed for Star…progress communication helper.
[  OK  ] Started Load Kernel Modules.
[  OK  ] Started Remount Root and Kernel File Systems.
         Mounting Kernel Configuration File System...
         Starting Flush Journal to Persistent Storage...
[    6.931779] systemd-journald[221]: Received client request to flush runtime journal.
         Starting Apply Kernel Variables...
         Starting Create Static Device Nodes in /dev...
[  OK  ] Mounted Kernel Configuration File System.
[  OK  ] Started Flush Journal to Persistent Storage.
[  OK  ] Started Apply Kernel Variables.
[  OK  ] Started Create Static Device Nodes in /dev.
[  OK  ] Reached target Local File Systems (Pre).
         Mounting /media/ram...
         Mounting /var/volatile...
         Starting udev Kernel Device Manager...
[  OK  ] Mounted /media/ram.
[  OK  ] Mounted /var/volatile.
         Starting Load/Save Random Seed...
[  OK  ] Reached target Local File Systems.
         Starting Create Volatile Files and Directories...
[  OK  ] Started udev Coldplug all Devices.
[  OK  ] Started Create Volatile Files and Directories.
[  OK  ] Started udev Kernel Device Manager.
         Starting Network Time Synchronization...
         Starting Update UTMP about System Boot/Shutdown...
[  OK  ] Started Update UTMP about System Boot/Shutdown.
[  OK  ] Started Network Time Synchronization.
[  OK  ] Reached target System Initialization.
[  OK  ] Started Daily Cleanup of Temporary Directories.
[  OK  ] Reached target System Time Set.
[  OK  ] Reached target System Time Synchronized.
[  OK  ] Started Daily rotation of log files.
[  OK  ] Reached target Timers.
[  OK  ] Listening on Avahi mDNS/DNS-SD Stack Activation Socket.
[  OK  ] Listening on D-Bus System Message Bus Socket.
         Starting Docker Socket for the API.
[  OK  ] Listening on dropbear.socket.
         Starting Reboot and dump vmcore via kexec...
[  OK  ] Listening on Docker Socket for the API.
[  OK  ] Reached target Sockets.
[  OK  ] Reached target Basic System.
[  OK  ] Started Job spooling tools.
[  OK  ] Started Periodic Command Scheduler.
[  OK  ] Started D-Bus System Message Bus.
         Starting Print notice about GPLv3 packages...
         Starting IPv6 Packet Filtering Framework...
         Starting IPv4 Packet Filtering Framework...
[  OK  ] Started irqbalance daemon.
         Starting rc.pvr.service...
         Starting Login Service...
[  OK  ] Started TEE Supplicant.
[  OK  ] Started Reboot and dump vmcore via kexec.
[  OK  ] Started IPv6 Packet Filtering Framework.
[  OK  ] Started IPv4 Packet Filtering Framework.
[  OK  ] Reached target Network (Pre).
         Starting Network Service...
[  OK  ] Started Network Service.
         Starting Wait for Network to be Configured...
[    7.978247] k3-dsp-rproc 4d80800000.dsp: assigned reserved memory node c66-dma-memory@a7000000
         Starting Network Name Resolution...
[    8.010326] PVR_K:  302: Read BVNC 22.104.208.318 from HW device registers
[    8.017988] rti-wdt 2200000.rti: heartbeat 60 sec
[    8.023545] PVR_K:  302: RGX Device initialised with BVNC 22.104.208.318
[    8.039715] [drm] Initialized pvr 1.10.5371573 20170530 for 4e20000000.gpu on minor 0
[  OK  ] Started Login Service.
[  OK  ] Started Network Name Resolution.
[  OK  ] Reached target Network.
[  OK  ] Reached target Host and Network Name Lookups.
         Starting Avahi mDNS/DNS-SD Stack...
[  OK  ] Started NFS status monitor for NFSv2/3 locking..
         Starting Permit User Sessions...
[  OK  ] Started Permit User Sessions.
[  OK  ] Started Getty on tty1.
[  OK  ] Started Serial Getty on ttyS2.
[  OK  ] Started Serial Getty on ttyS3.
[  OK  ] Reached target Login Prompts.
         Starting Synchronize System and HW clocks...
[  OK  ] Started Avahi mDNS/DNS-SD Stack.
[    8.273071] rti-wdt 2210000.rti: heartbeat 60 sec
[    8.329246] k3-dsp-rproc 4d80800000.dsp: configured DSP for IPC-only mode
[  OK  ] Started Synchronize System and HW clocks.
[    8.607234] remoteproc remoteproc0: 4d80800000.dsp is available
[    8.817232] remoteproc remoteproc0: powering up 4d80800000.dsp
[    8.823115] remoteproc remoteproc0: Booting fw image j7-c66_0-fw, size 1513584
[    8.832935] k3-dsp-rproc 4d81800000.dsp: assigned reserved memory node c66-dma-memory@a6000000
[    8.836106] k3-dsp-rproc 4d80800000.dsp: DSP initialized in IPC-only mode
[    8.852640]  remoteproc0#vdev0buffer: assigned reserved memory node c66-dma-memory@a7000000
[    8.865156] virtio_rpmsg_bus virtio0: rpmsg host is online
[    8.873472]  remoteproc0#vdev0buffer: registered virtio0 (type 7)
[    8.882953] remoteproc remoteproc0: remote processor 4d80800000.dsp is now up
[    8.892914] virtio_rpmsg_bus virtio0: creating channel rpmsg_chrdev addr 0xd
[    8.974984] lm75 0-0048: hwmon0: sensor 'tmp101'
[    9.098520] lm75 0-0049: hwmon2: sensor 'tmp101'
[    9.127260] at24 1-0051: 65536 byte 24c512 EEPROM, writable, 128 bytes/write
[    9.144216] k3-dsp-rproc 4d81800000.dsp: configured DSP for IPC-only mode
[    9.191990] random: crng init done
[    9.195383] random: 7 urandom warning(s) missed due to ratelimiting
[    9.214795] lm75 1-0048: hwmon3: sensor 'tmp101'
[    9.232723] remoteproc remoteproc1: 4d81800000.dsp is available
[    9.260178] at24 2-0050: 65536 byte 24c512 EEPROM, writable, 128 bytes/write
[    9.277594] lm75 1-0049: hwmon4: sensor 'tmp101'
[    9.328895] k3-dsp-rproc 64800000.dsp: assigned reserved memory node c71-dma-memory@a8000000
[    9.345117] lm75 1-004a: hwmon5: sensor 'tmp101'
[    9.400078] remoteproc remoteproc1: powering up 4d81800000.dsp
[    9.407091] remoteproc remoteproc1: Booting fw image j7-c66_1-fw, size 1513584
[    9.419056] k3-dsp-rproc 4d81800000.dsp: DSP initialized in IPC-only mode

[    9.435736]  remoteproc1#vdev0buffer: assigned reserved memory node c66-dma-memory@a6000000
[    9.449791] virtio_rpmsg_bus virtio1: rpmsg host is online
[    9.458459]  remoteproc1#vdev0buffer: registered virtio1 (type 7)
[    9.467247] remoteproc remoteproc1: remote processor 4d81800000.dsp is now up
[    9.478511] virtio_rpmsg_bus virtio1: creating channel rpmsg_chrdev addr 0xd
[    9.504302] k3-dsp-rproc 64800000.dsp: configured DSP for IPC-only mode
[    9.518850] am65-cpsw-nuss 46000000.ethernet: phy /interconnect@100000/interconnect@28380000/ethernet@46000000/mdio@f00/ethernet-phy@0 not found on slave 1
[    9.685987] remoteproc remoteproc2: 64800000.dsp is available
[    9.790810] platform 41000000.r5f: configured R5F for remoteproc mode
[    9.963868] platform 41000000.r5f: assigned reserved memory node r5f-dma-memory@a0000000
[   10.141654] remoteproc remoteproc3: 41000000.r5f is available
[   10.225156] remoteproc remoteproc3: Direct firmware load for j7-mcu-r5f0_0-fw failed with error -2
[   10.237146] remoteproc remoteproc3: powering up 41000000.r5f
[   10.246969] remoteproc remoteproc3: Direct firmware load for j7-mcu-r5f0_0-fw failed with error -2
[   10.251542] platform 5c00000.r5f: R5F core may have been powered on by a different host, programmed state (0) != actual state (1)
[   10.260008] remoteproc remoteproc3: request_firmware failed: -2
[   10.273860] remoteproc remoteproc2: powering up 64800000.dsp
[   10.279607] remoteproc remoteproc2: Booting fw image j7-c71_0-fw, size 9314288
[   10.291477] k3-dsp-rproc 64800000.dsp: DSP initialized in IPC-only mode
[   10.301475]  remoteproc2#vdev0buffer: assigned reserved memory node c71-dma-memory@a8000000
[   10.314765] virtio_rpmsg_bus virtio2: rpmsg host is online
[   10.322779] platform 5c00000.r5f: configured R5F for IPC-only mode
[   10.323315]  remoteproc2#vdev0buffer: registered virtio2 (type 7)
[   10.340789] remoteproc remoteproc2: remote processor 64800000.dsp is now up
[   10.346655] platform 5c00000.r5f: assigned reserved memory node r5f-dma-memory@a1000000
[   10.351837] virtio_rpmsg_bus virtio2: creating channel rpmsg_chrdev addr 0xd
[   10.390825] remoteproc remoteproc4: 5c00000.r5f is available
[   10.411070] platform 5d00000.r5f: configured R5F for remoteproc mode
[   10.480049] platform 5d00000.r5f: assigned reserved memory node r5f-dma-memory@a3000000
[   10.592966] load_switch lsw-crgsns: Found gpio "en"
[   10.603932] remoteproc remoteproc4: powering up 5c00000.r5f
[   10.609548] remoteproc remoteproc4: Booting fw image j7-main-r5f0_0-fw, size 4503876
[   10.617928] load_switch lsw-crgsns: Found gpio "pgood"
[   10.623284] platform 5c00000.r5f: R5F core initialized in IPC-only mode
[   10.630963]  remoteproc4#vdev0buffer: assigned reserved memory node r5f-dma-memory@a1000000
[   10.643354] virtio_rpmsg_bus virtio3: rpmsg host is online
[   10.649946]  remoteproc4#vdev0buffer: registered virtio3 (type 7)
[   10.654378] remoteproc remoteproc5: 5d00000.r5f is available
[   10.659984] remoteproc remoteproc4: remote processor 5c00000.r5f is now up
[   10.670139] virtio_rpmsg_bus virtio2: creating channel rpmsg_chrdev addr 0x15
[   10.680037] virtio_rpmsg_bus virtio2: creating channel ti.ipc4.ping-pong addr 0xe
[   10.688216] load_switch lsw-crgsns: Found gpio "fault"
[   10.693565] virtio_rpmsg_bus virtio3: creating channel rpmsg_chrdev addr 0xd
[   10.700738] virtio_rpmsg_bus virtio3: creating channel rpmsg_chrdev addr 0x15
[   10.709555] virtio_rpmsg_bus virtio0: creating channel rpmsg_chrdev addr 0x15
[   10.720107] virtio_rpmsg_bus virtio0: creating channel ti.ipc4.ping-pong addr 0xe
[   10.727725] virtio_rpmsg_bus virtio1: creating channel rpmsg_chrdev addr 0x15
[   10.741640] virtio_rpmsg_bus virtio1: creating channel ti.ipc4.ping-pong addr 0xe
[   10.786191] load_switch lsw-crglht: Found gpio "en"
[   10.794442] remoteproc remoteproc5: Direct firmware load for j7-main-r5f0_1-fw failed with error -2
[   10.809564] remoteproc remoteproc5: powering up 5d00000.r5f
[   10.820000] remoteproc remoteproc5: Direct firmware load for j7-main-r5f0_1-fw failed with error -2
[   10.833634] remoteproc remoteproc5: request_firmware failed: -2
[   10.852884] platform 5e00000.r5f: configured R5F for remoteproc mode
[   10.884500] platform 5e00000.r5f: assigned reserved memory node r5f-dma-memory@a4000000
[   10.884540] load_switch lsw-crglht: Found gpio "pgood"
[   10.979596] remoteproc remoteproc6: 5e00000.r5f is available
[   11.013505] remoteproc remoteproc6: Direct firmware load for j7-main-r5f1_0-fw failed with error -2
[   11.024889] load_switch lsw-crglht: Found gpio "fault"
[   11.030309] remoteproc remoteproc6: powering up 5e00000.r5f
[   11.035919] remoteproc remoteproc6: Direct firmware load for j7-main-r5f1_0-fw failed with error -2
[   11.056013] remoteproc remoteproc6: request_firmware failed: -2
[   11.067061] platform 5f00000.r5f: configured R5F for remoteproc mode
[   11.121690] load_switch lsw-joystick: Found gpio "en"
[   11.136900] platform 5f00000.r5f: assigned reserved memory node r5f-dma-memory@a5000000
[   11.152649] load_switch lsw-joystick: Found gpio "pgood"
[   11.168343] load_switch lsw-joystick: Found gpio "fault"
[   11.189702] load_switch lsw-latch: Found gpio "en"
[   11.192616] remoteproc remoteproc7: 5f00000.r5f is available
[   11.207694] load_switch lsw-latch: Found gpio "pgood"
***************************************************************
***************************************************************
NOTICE: This file system contains the following GPLv3 packages:
        bash-dev
        bash
        bc
        binutils
        cifs-utils
        cpio
        dosfs[   11.232748] load_switch lsw-latch: Found gpio "fault"
tools
        elfutils
        gawk
        gdb
        gdbserver
        glmark2
        gstreamer1.0-libav
        gzip
        less
        libasm1
        libbfd
        libdw1
        libelf1
        libgdbm-compat4
        libgdbm-dev
        libgdbm6
        libgmp10
        libidn2-0
        libreadline-dev
        libreadline8
        libunistring2
        m4-dev
        m4
        nettle
        parted
        tar
        which

If you do not wish to distribute GPLv3 components please remove
the above packages prior to di[   11.274456] remoteproc remoteproc7: Direct firmware load for j7-main-r5f1_1-fw failed with error -2
stribution.  This can be done using
the opkg remove command.  i[   11.289072] remoteproc remoteproc7: powering up 5f00000.r5f
.e.:
    opkg remove <package>
Where <package> is the name pri[   11.300028] remoteproc remoteproc7: Direct firmware load for j7-main-r5f1_1-fw failed with error -2
nted in the list above

NOTE: If the package is a dependency o[   11.316026] remoteproc remoteproc7: request_firmware failed: -2
f another package you
      will be notified of the dependent packages.  You should
      use the --force-removal-of-dependent-packages option to
      also remove the dependent packages as well
***************************************************************
***************************************************************
[  OK  ] Started Print notice about GPLv3 packages.
[   11.409332] load_switch lsw-snsa: Found gpio "en"
[   11.455127] load_switch lsw-snsa: Found gpio "pgood"
[   11.527251] load_switch lsw-snsa: Found gpio "fault"
[   11.588581] load_switch lsw-snsb: Found gpio "en"
[   11.653859] load_switch lsw-snsb: Found gpio "pgood"
[  OK  ] Created slice system-systemd\x2dfsck.slice.
[   11.733736] load_switch lsw-snsb: Found gpio "fault"
[   11.809059] load_switch lsw-enet: Found gpio "en"
[   11.877414] load_switch lsw-enet: Found gpio "pgood"
[   11.928806] load_switch lsw-enet: Found gpio "fault"
[   11.985498] load_switch lsw-t1: Found gpio "en"
[   12.014963] load_switch lsw-t1: Found gpio "pgood"
[   12.036155] load_switch lsw-t1: Found gpio "fault"
[   12.067376] load_switch lsw-gps: Found gpio "en"
[   12.105611] load_switch lsw-gps: Found gpio "fault"
[  OK  ] Created slice system-weston.slice.
[  OK  ] Started Weston Wayland Compositor.
[   12.185150] cdns-usb3 6000000.usb: DRD version v1 (ID: 0004024e, rev: 00000200)
[  OK  ] Created slice User Slice of UID 0.
         Starting User Runtime Directory /run/user/0...
[  OK  ] Started User Runtime Directory /run/user/0.
         Starting User Manager for UID 0...
[   12.324821] remoteproc remoteproc8: b034000.pru is available
[   12.389170] cdns-usb3 6400000.usb: DRD version v1 (ID: 0004024e, rev: 00000200)
[   12.545221] pru-rproc b034000.pru: PRU rproc node /interconnect@100000/icssg@b000000/pru@34000 probed successfully
[   12.572153] omap_rng 4e10000.trng: Random Number Generator ver. 241b34c

[  OK  ] Found device /dev/mmcblk1p1.
[  OK  ] Started Session c1 of user root.
         Starting File System Check on /dev/mmcblk1p1...
[   12.712770] remoteproc remoteproc9: b004000.rtu is available
[   12.778331] pru-rproc b004000.rtu: PRU rproc node /interconnect@100000/icssg@b000000/rtu@4000 probed successfully
[   12.828384] pru-rproc b00a000.txpru: IRQ vring not found
[   12.880754] pru-rproc b00a000.txpru: IRQ kick not found
[   12.896272] remoteproc remoteproc10: b00a000.txpru is available
[   12.924174] pru-rproc b00a000.txpru: PRU rproc node /interconnect@100000/icssg@b000000/txpru@a000 probed successfully
[  OK  ] Started File System Check on /dev/mmcblk1p1.
         Mounting /run/media/mmcblk1p1...
[   13.085606] remoteproc remoteproc11: b038000.pru is available
[   13.145130] pru-rproc b038000.pru: PRU rproc node /interconnect@100000/icssg@b000000/pru@38000 probed successfully
[  OK  ] Started rc.pvr.service.
[   13.189108] remoteproc remoteproc12: b006000.rtu is available
[   13.207382] usbcore: registered new interface driver usbfs
[   13.218503] pru-rproc b006000.rtu: PRU rproc node /interconnect@100000/icssg@b000000/rtu@6000 probed successfully
[   13.247770] usbcore: registered new interface driver hub
[   13.263068] usbcore: registered new device driver usb
[   13.277994] pru-rproc b00c000.txpru: IRQ vring not found
[   13.292521] pru-rproc b00c000.txpru: IRQ kick not found
[   13.308387] remoteproc remoteproc13: b00c000.txpru is available
[   13.324530] pru-rproc b00c000.txpru: PRU rproc node /interconnect@100000/icssg@b000000/txpru@c000 probed successfully
[   13.336800] remoteproc remoteproc14: b134000.pru is available
[   13.347886] pru-rproc b134000.pru: PRU rproc node /interconnect@100000/icssg@b100000/pru@34000 probed successfully
[   13.365825] xhci-hcd xhci-hcd.2.auto: xHCI Host Controller
[   13.371324] mmc2: Timeout waiting for hardware cmd interrupt.
[   13.371327] mmc2: sdhci: ============ SDHCI REGISTER DUMP ===========
[   13.371330] mmc2: sdhci: Sys addr:  0x00000000 | Version:  0x00001004
[   13.371332] mmc2: sdhci: Blk size:  0x00000000 | Blk cnt:  0x00000000
[   13.371334] mmc2: sdhci: Argument:  0x00000c00 | Trn mode: 0x00000000
[   13.371336] mmc2: sdhci: Present:   0x01f20001 | Host ctl: 0x00000001
[   13.371338] mmc2: sdhci: Power:     0x0000000e | Blk gap:  0x00000080
[   13.371340] mmc2: sdhci: Wake-up:   0x00000000 | Clock:    0x0000fa07
[   13.371342] mmc2: sdhci: Timeout:   0x00000000 | Int stat: 0x00000000
[   13.371344] mmc2: sdhci: Int enab:  0x00ff0003 | Sig enab: 0x00ff0003
[   13.371346] mmc2: sdhci: ACmd stat: 0x00000000 | Slot int: 0x00000000
[   13.371348] mmc2: sdhci: Caps:      0x7fe8c801 | Caps_1:   0x18000407
[   13.371350] mmc2: sdhci: Cmd:       0x0000341a | Max curr: 0x00000000
[   13.371352] mmc2: sdhci: Resp[0]:   0x00000000 | Resp[1]:  0x00000000
[   13.371354] mmc2: sdhci: Resp[2]:   0x00000000 | Resp[3]:  0x00000000
[   13.371355] mmc2: sdhci: Host ctl2: 0x00000000
[   13.371357] mmc2: sdhci: ADMA Err:  0x00000000 | ADMA Ptr: 0x0000000000000000
[   13.371358] mmc2: sdhci: ============================================
[   13.371631] remoteproc remoteproc15: b104000.rtu is available
[   13.391988] xhci-hcd xhci-hcd.2.auto: new USB bus registered, assigned bus number 1
[   13.424839] pru-rproc b104000.rtu: PRU rproc node /interconnect@100000/icssg@b100000/rtu@4000 probed successfully
[   13.468114] xhci-hcd xhci-hcd.2.auto: hcc params 0x200073c9 hci version 0x100 quirks 0x0000000000010010
[   13.556013] xhci-hcd xhci-hcd.2.auto: irq 585, io mem 0x06010000
[   13.562429] pru-rproc b10a000.txpru: IRQ vring not found
[   13.572163] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 5.04
[   13.587978] pru-rproc b10a000.txpru: IRQ kick not found
[   13.593279] remoteproc remoteproc16: b10a000.txpru is available
[   13.603975] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[   13.611220] pru-rproc b10a000.txpru: PRU rproc node /interconnect@100000/icssg@b100000/txpru@a000 probed successfully
[   13.627973] usb usb1: Product: xHCI Host Controller
[   13.635971] usb usb1: Manufacturer: Linux 5.4.40-g66cf445b76 xhci-hcd
[   13.636631] remoteproc remoteproc17: b138000.pru is available
[   13.643992] usb usb1: SerialNumber: xhci-hcd.2.auto
[   13.660003] pru-rproc b138000.pru: PRU rproc node /interconnect@100000/icssg@b100000/pru@38000 probed successfully
[   13.664217] hub 1-0:1.0: USB hub found
[   13.680752] remoteproc remoteproc18: b106000.rtu is available
[   13.684003] hub 1-0:1.0: 1 port detected
[   13.695105] pru-rproc b106000.rtu: PRU rproc node /interconnect@100000/icssg@b100000/rtu@6000 probed successfully
[   13.696469] xhci-hcd xhci-hcd.2.auto: xHCI Host Controller
[   13.715605] pru-rproc b10c000.txpru: IRQ vring not found
[   13.725012] xhci-hcd xhci-hcd.2.auto: new USB bus registered, assigned bus number 2
[   13.727289] pru-rproc b10c000.txpru: IRQ kick not found
[   13.739987] xhci-hcd xhci-hcd.2.auto: Host supports USB 3.0 SuperSpeed
[   13.748145] remoteproc remoteproc19: b10c000.txpru is available
[   13.754368] usb usb2: We don't know the algorithms for LPM for this host, disabling LPM.
[   13.762806] pru-rproc b10c000.txpru: PRU rproc node /interconnect@100000/icssg@b100000/txpru@c000 probed successfully
[   13.772022] usb usb2: New USB device found, idVendor=1d6b, idProduct=0003, bcdDevice= 5.04
[   13.791507] usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[   13.904062] usb usb2: Product: xHCI Host Controller
[   13.924009] usb usb2: Manufacturer: Linux 5.4.40-g66cf445b76 xhci-hcd
[   13.968757] usb usb2: SerialNumber: xhci-hcd.2.auto
[   13.984909] hub 2-0:1.0: USB hub found
[   13.996904] hub 2-0:1.0: 1 port detected
[   14.002027] xhci-hcd xhci-hcd.3.auto: xHCI Host Controller
[   14.015982] xhci-hcd xhci-hcd.3.auto: new USB bus registered, assigned bus number 3
[   14.032112] xhci-hcd xhci-hcd.3.auto: hcc params 0x200073c9 hci version 0x100 quirks 0x0000000000010010
[   14.048001] xhci-hcd xhci-hcd.3.auto: irq 588, io mem 0x06410000
[   14.060121] usb usb3: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 5.04
[   14.071992] usb usb3: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[   14.083969] usb usb3: Product: xHCI Host Controller
[   14.088840] usb usb3: Manufacturer: Linux 5.4.40-g66cf445b76 xhci-hcd
[   14.103969] usb usb3: SerialNumber: xhci-hcd.3.auto
[   14.112315] hub 3-0:1.0: USB hub found
[   14.116069] hub 3-0:1.0: 1 port detected
[   14.124714] xhci-hcd xhci-hcd.3.auto: xHCI Host Controller
[   14.130204] xhci-hcd xhci-hcd.3.auto: new USB bus registered, assigned bus number 4
[   14.147979] xhci-hcd xhci-hcd.3.auto: Host supports USB 3.0 SuperSpeed
[   14.159988] usb usb4: We don't know the algorithms for LPM for this host, disabling LPM.
[   14.172012] usb usb4: New USB device found, idVendor=1d6b, idProduct=0003, bcdDevice= 5.04
[   14.183970] usb usb4: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[   14.191179] usb usb4: Product: xHCI Host Controller
[   14.203967] usb usb4: Manufacturer: Linux 5.4.40-g66cf445b76 xhci-hcd
[   14.210397] usb usb4: SerialNumber: xhci-hcd.3.auto
[   14.220391] hub 4-0:1.0: USB hub found
[   14.224151] hub 4-0:1.0: 1 port detected

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

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

Arago 2020.05 j7-evm ttyS2

j7-evm login: [   23.607972] mmc2: Timeout waiting for hardware cmd interrupt.
[   23.613711] mmc2: sdhci: ============ SDHCI REGISTER DUMP ===========
[   23.620133] mmc2: sdhci: Sys addr:  0x00000000 | Version:  0x00001004
[   23.626554] mmc2: sdhci: Blk size:  0x00000000 | Blk cnt:  0x00000000
[   23.632975] mmc2: sdhci: Argument:  0x80000c08 | Trn mode: 0x00000000
[   23.639396] mmc2: sdhci: Present:   0x01f20001 | Host ctl: 0x00000001
[   23.645815] mmc2: sdhci: Power:     0x0000000e | Blk gap:  0x00000080
[   23.652236] mmc2: sdhci: Wake-up:   0x00000000 | Clock:    0x0000fa07
[   23.658657] mmc2: sdhci: Timeout:   0x00000000 | Int stat: 0x00000000
[   23.665078] mmc2: sdhci: Int enab:  0x00ff0003 | Sig enab: 0x00ff0003
[   23.671499] mmc2: sdhci: ACmd stat: 0x00000000 | Slot int: 0x00000000
[   23.677920] mmc2: sdhci: Caps:      0x7fe8c801 | Caps_1:   0x18000407
[   23.684341] mmc2: sdhci: Cmd:       0x0000341a | Max curr: 0x00000000
[   23.690761] mmc2: sdhci: Resp[0]:   0x00000000 | Resp[1]:  0x00000000
[   23.697182] mmc2: sdhci: Resp[2]:   0x00000000 | Resp[3]:  0x00000000
[   23.703602] mmc2: sdhci: Host ctl2: 0x00000000
[   23.708030] mmc2: sdhci: ADMA Err:  0x00000000 | ADMA Ptr: 0x0000000000000000
[   23.715142] mmc2: sdhci: ============================================
[   33.847968] mmc2: Timeout waiting for hardware cmd interrupt.
[   33.847987] tlv71033: disabling
[   33.853701] mmc2: sdhci: ============ SDHCI REGISTER DUMP ===========
[   33.853704] mmc2: sdhci: Sys addr:  0x00000000 | Version:  0x00001004
[   33.853706] mmc2: sdhci: Blk size:  0x00000000 | Blk cnt:  0x00000000
[   33.853708] mmc2: sdhci: Argument:  0x00000000 | Trn mode: 0x00000000
[   33.882508] mmc2: sdhci: Present:   0x01f20001 | Host ctl: 0x00000001
[   33.888929] mmc2: sdhci: Power:     0x0000000e | Blk gap:  0x00000080
[   33.895350] mmc2: sdhci: Wake-up:   0x00000000 | Clock:    0x0000fa07
[   33.901771] mmc2: sdhci: Timeout:   0x00000000 | Int stat: 0x00000000
[   33.908192] mmc2: sdhci: Int enab:  0x00ff0003 | Sig enab: 0x00ff0003
[   33.914612] mmc2: sdhci: ACmd stat: 0x00000000 | Slot int: 0x00000000
[   33.921033] mmc2: sdhci: Caps:      0x7fe8c801 | Caps_1:   0x18000407
[   33.927453] mmc2: sdhci: Cmd:       0x00000000 | Max curr: 0x00000000
[   33.933874] mmc2: sdhci: Resp[0]:   0x00000000 | Resp[1]:  0x00000000
[   33.940294] mmc2: sdhci: Resp[2]:   0x00000000 | Resp[3]:  0x00000000
[   33.946714] mmc2: sdhci: Host ctl2: 0x00000000
[   33.951142] mmc2: sdhci: ADMA Err:  0x00000000 | ADMA Ptr: 0x0000000000000000
[   33.958254] mmc2: sdhci: ============================================
[   44.087968] mmc2: Timeout waiting for hardware cmd interrupt.
[   44.093704] mmc2: sdhci: ============ SDHCI REGISTER DUMP ===========
[   44.100126] mmc2: sdhci: Sys addr:  0x00000000 | Version:  0x00001004
[   44.106547] mmc2: sdhci: Blk size:  0x00000000 | Blk cnt:  0x00000000
[   44.112968] mmc2: sdhci: Argument:  0x000001aa | Trn mode: 0x00000000
[   44.119389] mmc2: sdhci: Present:   0x01f20001 | Host ctl: 0x00000001
[   44.125810] mmc2: sdhci: Power:     0x0000000e | Blk gap:  0x00000080
[   44.132230] mmc2: sdhci: Wake-up:   0x00000000 | Clock:    0x0000fa07
[   44.138651] mmc2: sdhci: Timeout:   0x00000000 | Int stat: 0x00000000
[   44.145072] mmc2: sdhci: Int enab:  0x00ff0003 | Sig enab: 0x00ff0003
[   44.151492] mmc2: sdhci: ACmd stat: 0x00000000 | Slot int: 0x00000000
[   44.157913] mmc2: sdhci: Caps:      0x7fe8c801 | Caps_1:   0x18000407
[   44.164333] mmc2: sdhci: Cmd:       0x0000081a | Max curr: 0x00000000
[   44.170754] mmc2: sdhci: Resp[0]:   0x00000000 | Resp[1]:  0x00000000
[   44.177175] mmc2: sdhci: Resp[2]:   0x00000000 | Resp[3]:  0x00000000
[   44.183595] mmc2: sdhci: Host ctl2: 0x00000000
[   44.188022] mmc2: sdhci: ADMA Err:  0x00000000 | ADMA Ptr: 0x0000000000000000
[   44.195135] mmc2: sdhci: ============================================

Spencer,

On top of the log and waveform, please also share your device tree file. MAIN_SDHCI2 should not have "non-removable" listed.  It should follow MAIN_SDHCI1 definition with the status = "disabled"; line removed.  What voltage are you powering the device? Is it the same as what you are programming in the HOST_CONTROL2 register?

Thanks & Regards,

Shiou Mei

Hello Shiou,
I've extracted the related parts of our device tree into one block since they are spread out between a few files. Everything not listed in these parts is unchanged from the parts provided by Ti.

// unrelated parts removed.
    
    lsw_wifi: lsw-wifi {
		compatible = "d3,load-switch";
		pinctrl-names = "default";
		pinctrl-0 = <&main_gpio_lsw_wifi_pins_default>;
		en-gpios = <&main_gpio0 84 GPIO_ACTIVE_HIGH>;
		pgood-gpios = <&main_gpio0 21 GPIO_ACTIVE_HIGH>;
	    status = "disabled";
    };

	vdd_wifi_en: fixedregulator-sd2-en {
		compatible = "regulator-fixed";
		pinctrl-names = "default";
		pinctrl-0 = <&wkup_gpio_wifi_pins_default>;
		regulator-name = "vdd_wifi_en";
		regulator-min-microvolt = <3300000>;
		regulator-max-microvolt = <3300000>;
		enable-active-high;
		vin-supply = <&vsys_3v3>;
		gpio = <&wkup_gpio0 66 GPIO_ACTIVE_HIGH>;
	};

	vdd_mmc2: fixedregulator-sd2 {
		compatible = "regulator-fixed";
		pinctrl-names = "default";
		pinctrl-0 = <&main_gpio_lsw_wifi_pins_default &main_gpio_wifi_pins_default>;
		regulator-name = "vdd_mmc2";
		regulator-min-microvolt = <3300000>;
		regulator-max-microvolt = <3300000>;
		enable-active-high;
		vin-supply = <&vdd_wifi_en>;
		gpio = <&main_gpio0 84 GPIO_ACTIVE_HIGH>;
	};

&main_sdhci2 {
	pinctrl-names = "default";
	pinctrl-0 = <&mmc2_pins_default>;

	vmmc-supply = <&vdd_mmc2>;

    // broken-cd 
	non-removable;
	// bus-width = <4>;
	bus-width = <1>;
	no-1-8-v;
	status = "ok";
};

// Pinmux, in a separate file.

&main_pmx0 {

// Other parts removed.

	main_gpio_lsw_wifi_pins_default: main_gpio_lsw_wifi_pins_default {
		pinctrl-single,pins = <
			J721E_IOPAD(0x58, PIN_INPUT, 7) /* (AE22) PRG1_PRU1_GPO0.GPIO0_21 PGOOD_VDD_3V8_WIFI */
			J721E_IOPAD(0x154, PIN_OUTPUT, 7) /* (AA27) PRG0_MDIO0_MDC.GPIO0_84 VDD_3V8_WIFI_EN */
		>;
	};

	main_gpio_wifi_pins_default: main_gpio_wifi_pins_default {
		pinctrl-single,pins = <
			J721E_IOPAD(0x130, PIN_OUTPUT, 7) /* (AF27) PRG0_PRU1_GPO12.GPIO0_75 BT_EN */
		>;
	};
}

&wkup_pmx0 {

// Other parts removed

	wkup_gpio_wifi_pins_default: wkup_gpio_wifi_pins_default {
		pinctrl-single,pins = <
			J721E_WKUP_IOPAD(0x108, PIN_OUTPUT_PULLUP, 7) /* (E26) PMIC_POWER_EN0.WKUP_GPIO0_66 WIFI_EN */
		>;
	};
}

Spencer,

While you modify the device tree, please retake the waveform capture with all three CLK, CMD, and DAT0 signals. Also your previous waveform seems to be pretty zoomed out. There is a glitch on the CLK line in the previous waveform you showed, please increase your resolution and zoom in to that, to confirm whether it is a proper toggle or not.

Thanks!

Best Regards,

Shiou Mei

Hello Shiou,
I modified the device tree and write the bits using devmem2 once Linux has started, I wasn't sure where to perform the writes such that they can be done before the clock is initialized, since that memory is not mapped while uboot is running. However, writing them using devmem2 did cause the clock to begin being output.
Our wifi device is failing to initialize still, with the following errors

[   19.082505] brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43455-sdio for chip BCM4345/6
[   19.094558] brcmfmac mmc2:0001:1: Direct firmware load for brcm/brcmfmac43455-sdio.bin failed with error -2
[   20.119449] brcmfmac: brcmf_sdio_htclk: HT Avail timeout (1000000): clkctl 0x50

However I suspect that we can investigate this on our own, and it likely would be a separate e2e issue if we need assistance, unless you think that this is related to setting these bits after Linux has finished initializing. If that is the case and you could suggest a method to set them earlier (I attempted to set them in the Linux sdhci_am654 driver to little success) that would be great.

Either way,
Thank you very much for the help,
Spencer