This thread has been locked.

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

AM4378: WiFi Driver Takes Long Time to Load

Part Number: AM4378

Hello,

I recently upgraded to SDK version 06.03.00.106, and have noticed that something related to WiFi is taking a very long time to load during the boot. The kernel goes through it's whole boot process and gets to the login screen, and then approximately two minutes later I get the following printout:

[ 127.865844] NET: Registered protocol family 15
[ 128.371934] Initializing XFRM netlink socket

Before this prints out, WiFi does not work. I also have a program setup to run after boot, and the program does not run until after these messages are printed out. But this only happens if I run everything off of the SD card. If I use the NFS for the filesystem, I don't have this problem. Any ideas about what is going on?

  • Hi Tanner,

    Is this on custom board or TI EVM?

    What interface is the WIFI connected to?

    Please attach the full kernel boot log.

  • This is on a custom board, WiFi is connected to the mmc2 interface. The attached file contains the boot log.

    5531.bootlog.txt
    �<debug_uart> 
    *********Scaling vcores****************
    Getting ready to scale...
    almost scaling...
    1330mV
    Getting DDR pll settings
    DDR set
    
    U-Boot SPL 2019.01-ga764a50-dirty (Sep 23 2020 - 16:19:11 -0500)
    Trying to boot from MMC1
    SPL: Please implement spl_start_uboot() for your board
    SPL: Direct Linux boot not active!
    
    
    U-Boot 2019.01-ga764a50-dirty (Sep 23 2020 - 16:19:11 -0500)
    
    CPU  : AM437X-GP rev 1.2
    Model: TI AM437x UTI BOARD
    DRAM:  512 MiB
    PMIC:  TPS65218
    NAND:  0 MiB
    MMC:   OMAP SD/MMC: 0
    Loading Environment from FAT... OK
    Net:   eth0: ethernet@4a100000
    Hit any key to stop autoboot:  0 
    ## Error: "findfdt" not defined
    717 bytes read in 2 ms (349.6 KiB/s)
    Importing environment from mmc0 ...
    4297216 bytes read in 221 ms (18.5 MiB/s)
    52522 bytes read in 4 ms (12.5 MiB/s)
    ## Flattened Device Tree blob at 88000000
       Booting using the fdt blob at 0x88000000
       Loading Device Tree to 8fff0000, end 8ffffd29 ... OK
    
    Starting kernel ...
    
    [    0.000000] Booting Linux on physical CPU 0x0
    [    0.000000] Linux version 4.19.94-gbe5389fd85 (root@UTI-CPELNX-02) (gcc version 8.3.0 (GNU Toolchain for the A-profile Architecture 8.3-2019.03 (arm-rel-8.36))) #1 PREEMPT Wed Oct 14 16:34:56 CDT 2020
    [    0.000000] CPU: ARMv7 Processor [412fc09a] revision 10 (ARMv7), cr=10c53c7d
    [    0.000000] CPU: PIPT / VIPT nonaliasing data cache, VIPT aliasing instruction cache
    [    0.000000] OF: fdt: Machine model: TI AM437x UTI BOARD
    [    0.000000] Memory policy: Data cache writeback
    [    0.000000] efi: Getting EFI parameters from FDT:
    [    0.000000] efi: UEFI not found.
    [    0.000000] cma: Reserved 48 MiB at 0x9d000000
    [    0.000000] CPU: All CPU(s) started in SVC mode.
    [    0.000000] AM437x ES1.2 (sgx neon)
    [    0.000000] random: get_random_bytes called from start_kernel+0xa4/0x434 with crng_init=0
    [    0.000000] Built 1 zonelists, mobility grouping on.  Total pages: 129920
    [    0.000000] Kernel command line: console=ttyO0,115200n8 video=HDMI-A-1:800x600 root=PARTUUID=69d6b921-02 rw rootfstype=ext4 rootwait
    [    0.000000] Dentry cache hash table entries: 65536 (order: 6, 262144 bytes)
    [    0.000000] Inode-cache hash table entries: 32768 (order: 5, 131072 bytes)
    [    0.000000] Memory: 455888K/524288K available (9216K kernel code, 316K rwdata, 2736K rodata, 1024K init, 254K bss, 19248K reserved, 49152K cma-reserved, 0K highmem)
    [    0.000000] Virtual kernel memory layout:
    [    0.000000]     vector  : 0xffff0000 - 0xffff1000   (   4 kB)
    [    0.000000]     fixmap  : 0xffc00000 - 0xfff00000   (3072 kB)
    [    0.000000]     vmalloc : 0xe0800000 - 0xff800000   ( 496 MB)
    [    0.000000]     lowmem  : 0xc0000000 - 0xe0000000   ( 512 MB)
    [    0.000000]     pkmap   : 0xbfe00000 - 0xc0000000   (   2 MB)
    [    0.000000]     modules : 0xbf000000 - 0xbfe00000   (  14 MB)
    [    0.000000]       .text : 0x(ptrval) - 0x(ptrval)   (10208 kB)
    [    0.000000]       .init : 0x(ptrval) - 0x(ptrval)   (1024 kB)
    [    0.000000]       .data : 0x(ptrval) - 0x(ptrval)   ( 317 kB)
    [    0.000000]        .bss : 0x(ptrval) - 0x(ptrval)   ( 255 kB)
    [    0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1
    [    0.000000] rcu: Preemptible hierarchical RCU implementation.
    [    0.000000]  Tasks RCU enabled.
    [    0.000000] NR_IRQS: 16, nr_irqs: 16, preallocated irqs: 16
    [    0.000000] L2C: platform modifies aux control register: 0x0e030000 -> 0x3e430000
    [    0.000000] L2C: DT/platform modifies aux control register: 0x0e030000 -> 0x3e430000
    [    0.000000] L2C-310 enabling early BRESP for Cortex-A9
    [    0.000000] OMAP L2C310: ROM does not support power control setting
    [    0.000000] L2C-310 dynamic clock gating disabled, standby mode disabled
    [    0.000000] L2C-310 cache controller enabled, 16 ways, 256 kB
    [    0.000000] L2C-310: CACHE_ID 0x410000c9, AUX_CTRL 0x4e430000
    [    0.000000] OMAP clockevent source: timer2 at 24000000 Hz
    [    0.000013] sched_clock: 32 bits at 24MHz, resolution 41ns, wraps every 89478484971ns
    [    0.000030] clocksource: timer1: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 79635851949 ns
    [    0.000039] OMAP clocksource: timer1 at 24000000 Hz
    [    0.000444] clocksource: 32k_counter: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 58327039986419 ns
    [    0.000454] OMAP clocksource: 32k_counter at 32768 Hz
    [    0.000846] Console: colour dummy device 80x30
    [    0.000873] WARNING: Your 'console=ttyO0' has been replaced by 'ttyS0'
    [    0.000880] This ensures that you still see kernel messages. Please
    [    0.000885] update your kernel commandline.
    [    0.000926] Calibrating delay loop... 1987.37 BogoMIPS (lpj=9936896)
    [    0.060187] pid_max: default: 32768 minimum: 301
    [    0.060356] Mount-cache hash table entries: 1024 (order: 0, 4096 bytes)
    [    0.060372] Mountpoint-cache hash table entries: 1024 (order: 0, 4096 bytes)
    [    0.061097] CPU: Testing write buffer coherency: ok
    [    0.061150] CPU0: Spectre v2: using BPIALL workaround
    [    0.062029] Setting up static identity map for 0x80100000 - 0x80100060
    [    0.062176] rcu: Hierarchical SRCU implementation.
    [    0.062513] EFI services will not be available.
    [    0.063332] devtmpfs: initialized
    [    0.072693] VFP support v0.3: implementor 41 architecture 3 part 30 variant 9 rev 4
    [    0.073068] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns
    [    0.073090] futex hash table entries: 256 (order: -1, 3072 bytes)
    [    0.076176] pinctrl core: initialized pinctrl subsystem
    [    0.076881] DMI not present or invalid.
    [    0.077280] NET: Registered protocol family 16
    [    0.079011] DMA: preallocated 256 KiB pool for atomic coherent allocations
    [    0.151975] cpuidle: using governor ladder
    [    0.152014] cpuidle: using governor menu
    [    0.153736] omap_l3_noc 44000000.ocp: L3 debug error: target 8 mod:0 (unclearable)
    [    0.153809] omap_l3_noc 44000000.ocp: L3 application error: target 8 mod:0 (unclearable)
    [    0.158872] OMAP GPIO hardware version 0.1
    [    0.166950] No ATAGs?
    [    0.166971] hw-breakpoint: found 5 (+1 reserved) breakpoint and 1 watchpoint registers.
    [    0.166989] hw-breakpoint: maximum watchpoint size is 4 bytes.
    [    0.180821] edma 49000000.edma: TI EDMA DMA engine driver
    [    0.184614] SCSI subsystem initialized
    [    0.185117] media: Linux media interface: v0.10
    [    0.185158] videodev: Linux video capture interface: v2.00
    [    0.185275] pps_core: LinuxPPS API ver. 1 registered
    [    0.185285] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>
    [    0.185308] PTP clock support registered
    [    0.185341] EDAC MC: Ver: 3.0.0
    [    0.186326] omap-mailbox 480c8000.mailbox: omap mailbox rev 0x400
    [    0.186977] Advanced Linux Sound Architecture Driver Initialized.
    [    0.188077] clocksource: Switched to clocksource timer1
    [    0.195710] NET: Registered protocol family 2
    [    0.196463] tcp_listen_portaddr_hash hash table entries: 512 (order: 0, 4096 bytes)
    [    0.196494] TCP established hash table entries: 4096 (order: 2, 16384 bytes)
    [    0.196528] TCP bind hash table entries: 4096 (order: 2, 16384 bytes)
    [    0.196570] TCP: Hash tables configured (established 4096 bind 4096)
    [    0.196692] UDP hash table entries: 256 (order: 0, 4096 bytes)
    [    0.196709] UDP-Lite hash table entries: 256 (order: 0, 4096 bytes)
    [    0.196849] NET: Registered protocol family 1
    [    0.197404] RPC: Registered named UNIX socket transport module.
    [    0.197417] RPC: Registered udp transport module.
    [    0.197422] RPC: Registered tcp transport module.
    [    0.197428] RPC: Registered tcp NFSv4.1 backchannel transport module.
    [    0.199387] Initialise system trusted keyrings
    [    0.199699] workingset: timestamp_bits=14 max_order=17 bucket_order=3
    [    0.203826] squashfs: version 4.0 (2009/01/31) Phillip Lougher
    [    0.204543] NFS: Registering the id_resolver key type
    [    0.204576] Key type id_resolver registered
    [    0.204583] Key type id_legacy registered
    [    0.204622] ntfs: driver 2.1.32 [Flags: R/O].
    [    0.206920] Key type asymmetric registered
    [    0.206936] Asymmetric key parser 'x509' registered
    [    0.207008] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 245)
    [    0.207020] io scheduler noop registered
    [    0.207026] io scheduler deadline registered
    [    0.207273] io scheduler cfq registered (default)
    [    0.207286] io scheduler mq-deadline registered
    [    0.207293] io scheduler kyber registered
    [    0.209725] pinctrl-single 44e10800.pinmux: 199 pins, size 796
    [    0.213160] pwm-backlight backlight: backlight supply power not found, using dummy regulator
    [    0.213261] pwm-backlight backlight: Linked as a consumer to regulator.0
    [    0.256124] Serial: 8250/16550 driver, 10 ports, IRQ sharing enabled
    [    0.259457] omap8250 44e09000.serial: No clock speed specified: using default: 48000000
    [    0.260155] 44e09000.serial: ttyS0 at MMIO 0x44e09000 (irq = 31, base_baud = 3000000) is a 8250
    [    0.962286] console [ttyS0] enabled
    [    0.966396] omap8250 481a6000.serial: No clock speed specified: using default: 48000000
    [    0.975181] 481a6000.serial: ttyS3 at MMIO 0x481a6000 (irq = 32, base_baud = 3000000) is a 8250
    [    0.985949] omap_rng 48310000.rng: Random Number Generator ver. 20
    [    0.992405] random: fast init done
    [    0.996031] random: crng init done
    [    1.000786] omapdss_dss 4832a000.dss: 4832a000.dss supply vdda_video not found, using dummy regulator
    [    1.010812] omapdss_dss 4832a000.dss: Linked as a consumer to regulator.0
    [    1.017735] omapdss_dss 4832a000.dss: Dropping the link to regulator.0
    [    1.027298] panel-simple display: display supply power not found, using dummy regulator
    [    1.035515] panel-simple display: Linked as a consumer to regulator.0
    [    1.052947] brd: module loaded
    [    1.064700] loop: module loaded
    [    1.072178] libphy: Fixed MDIO Bus: probed
    [    1.148135] davinci_mdio 4a101000.mdio: davinci mdio revision 1.6, bus freq 1000000
    [    1.155838] davinci_mdio 4a101000.mdio: detected phy mask fffffffd
    [    1.163014] libphy: 4a101000.mdio: probed
    [    1.167059] davinci_mdio 4a101000.mdio: phy[1]: device 4a101000.mdio:01, driver NS DP83848C 10/100 Mbps PHY
    [    1.177770] cpsw 4a100000.ethernet: Detected MACID = f0:b5:d1:3e:8c:7d
    [    1.184553] cpsw 4a100000.ethernet: initialized cpsw ale version 1.4
    [    1.191017] cpsw 4a100000.ethernet: ALE Table size 1024
    [    1.196300] cpsw 4a100000.ethernet: cpts: overflow check period 500 (jiffies)
    [    1.204855] i2c /dev entries driver
    [    1.210447] cpuidle: enable-method property 'ti,am4372' found operations
    [    1.217617] sdhci: Secure Digital Host Controller Interface driver
    [    1.223929] sdhci: Copyright(c) Pierre Ossman
    [    1.229398] omap_hsmmc 48060000.mmc: Linked as a consumer to regulator.1
    [    1.261904] omap_hsmmc 47810000.mmc: Linked as a consumer to regulator.2
    [    1.298151] mmc0: host does not support reading read-only switch, assuming write-enable
    [    1.308061] mmc0: new high speed SDHC card at address 1234
    [    1.314788] mmcblk0: mmc0:1234 SA16G 14.5 GiB 
    [    1.321210]  mmcblk0: p1 p2
    [    2.033654] sdhci-pltfm: SDHCI platform and OF driver helper
    [    2.040750] ledtrig-cpu: registered to indicate activity on CPUs
    [    2.050162] omap_hsmmc 47810000.mmc: card claims to support voltages below defined range
    [    2.060633] NET: Registered protocol family 10
    [    2.066335] Segment Routing with IPv6
    [    2.070239] sit: IPv6, IPv4 and MPLS over IPv4 tunneling driver
    [    2.076955] NET: Registered protocol family 17
    [    2.081867] Key type dns_resolver registered
    [    2.086412] omap_voltage_late_init: Voltage driver support not added
    [    2.093805] Loading compiled-in X.509 certificates
    [    2.105762] mmc1: new high speed SDIO card at address 0001
    [    2.119328] GPIO line 147 (AVDD_EN) hogged as output/high
    [    2.163705] omap_i2c 44e0b000.i2c: bus 0 rev0.12 at 100 kHz
    [    2.170756] omap_i2c 4802a000.i2c: bus 1 rev0.12 at 100 kHz
    [    2.176909] omapdss_dss 4832a000.dss: 4832a000.dss supply vdda_video not found, using dummy regulator
    [    2.186327] omapdss_dss 4832a000.dss: Linked as a consumer to regulator.0
    [    2.193262] DSS: OMAP DSS rev 2.0
    [    2.197651] omapdss_dss 4832a000.dss: bound 4832a400.dispc (ops dispc_component_ops)
    [    2.205995] omapdrm omapdrm.0: DMM not available, disable DMM support
    [    2.212794] [drm] Supports vblank timestamp caching Rev 2 (21.10.2013).
    [    2.219472] [drm] No driver support for vblank timestamp query.
    [    2.238926] Console: switching to colour frame buffer device 128x37
    [    2.251364] omapdrm omapdrm.0: fb0: omapdrm frame buffer device
    [    2.258049] [drm] Initialized omapdrm 1.0.0 20110917 for omapdrm.0 on minor 0
    [    2.265773] cpu cpu0: Linked as a consumer to regulator.4
    [    2.271335] cpu cpu0: Dropping the link to regulator.4
    [    2.276638] cpu cpu0: Linked as a consumer to regulator.4
    [    2.285840] input: gpio-keys as /devices/platform/gpio-keys/input/input0
    [    2.293294] hctosys: unable to open rtc device (rtc0)
    [    2.299285] ALSA device list:
    [    2.302275]   No soundcards found.
    [    2.354636] EXT4-fs (mmcblk0p2): mounted filesystem with ordered data mode. Opts: (null)
    [    2.363216] VFS: Mounted root (ext4 filesystem) on device 179:2.
    [    2.378842] devtmpfs: mounted
    [    2.383949] Freeing unused kernel memory: 1024K
    [    2.388737] Run /sbin/init as init process
    [    2.756874] systemd[1]: System time before build time, advancing clock.
    [    2.805208] systemd[1]: systemd 239 running in system mode. (+PAM -AUDIT -SELINUX +IMA -APPARMOR +SMACK +SYSVINIT +UTMP -LIBCRYPTSETUP -GCRYPT -GNUTLS +ACL +XZ -LZ4 -SECCOMP +BLKID -ELFUTILS +KMOD -ID)
    [    2.827751] systemd[1]: Detected architecture arm.
    
    Welcome to Arago 2019.11!
    
    [    2.889650] systemd[1]: Set hostname to <am437x-evm>.
    [    3.215075] systemd[1]: File /lib/systemd/system/systemd-journald.service:36 configures an IP firewall (IPAddressDeny=any), but the local system does not support BPF/cgroup based firewalling.
    [    3.232454] systemd[1]: Proceeding WITHOUT firewalling in effect! (This warning is only shown for the first loaded unit using IP firewalling.)
    [    3.637966] systemd[1]: Listening on initctl Compatibility Named Pipe.
    [  OK  ] Listening on initctl Compatibility Named Pipe.
    [    3.679178] systemd[1]: Listening on Network Service Netlink Socket.
    [  OK  ] Listening on Network Service Netlink Socket.
    [    3.719224] systemd[1]: Started Forward Password Requests to Wall Directory Watch.
    [  OK  ] Started Forward Password Requests to Wall Directory Watch.
    [    3.789451] systemd[1]: Listening on Process Core Dump Socket.
    [  OK  ] Listening on Process Core Dump Socket.
    [    3.821457] systemd[1]: Listening on Journal Socket.
    [  OK  ] Listening on Journal Socket.
             Starting Load Kernel Modules...
             Starting Create list of required st…ce nodes for the current kernel...
    [    3.947359] cmemk: loading out-of-tree module taints kernel.
    [  OK  ] Listening on udev Kernel Socket.
    [    3.962797] CMEMK module: reference Linux version 4.19.94
    [    3.970697] no physical memory specified
    [    3.974669] cmemk initialized
    [  OK  ] Reached target Swap.
    [    3.988972] cryptodev: driver 1.9 loaded.
             Mounting Temporary Directory (/tmp)...
    [  OK  ] Created slice User and Session Slice.
    [    4.055969] usbcore: registered new interface driver usbfs
    [    4.064245] usbcore: registered new interface driver hub
    [    4.071646] usbcore: registered new device driver usb
    [  OK  ] Reached target Slices.
    [    4.103273] usbcore: registered new interface driver ftdi_sio
    [  OK  ] Started Hardware RNG Entropy Gatherer Daemon.[    4.115862] usbserial: USB Serial support registered for FTDI USB Serial Device
    
    [  OK  ] Created slice system-getty.slice.
             Mounting POSIX Message Queue File System...
    [  OK  ] Listening on udev Control Socket.
             Starting udev Coldplug all Devices...
             Mounting Kernel Debug File System...
    [  OK  ] Started Dispatch Password Requests to Console Directory Watch.
    [  OK  ] Reached target Paths.
             Starting Remount Root and Kernel File Systems...
    [  OK  ] Reached target Remote File Systems.
    [  OK  ] Listening on Journal Socket (/dev/log).
    [    4.426283] EXT4-fs (mmcblk0p2): re-mounted. Opts: (null)
             Starting Journal Service...
    [  OK  ] Created slice system-serial\x2dgetty.slice.
    [  OK  ] Started Load Kernel Modules.
    [  OK  ] Started Create list of required sta…vice nodes for the current kernel.
    [  OK  ] Mounted Temporary Directory (/tmp).
    [  OK  ] Mounted POSIX Message Queue File System.
    [  OK  ] Started Journal Service.
    [  OK  ] Mounted Kernel Debug File System.
    [  OK  ] Started Remount Root and Kernel File Systems.
             Starting Flush Journal to Persistent Storage...
             Starting Create Static Device Nodes in /dev...
             Mounting Kernel Configuration File System...
             Starting Apply Kernel Variables...
    [  OK  ] Mounted Kernel Configuration File System.
    [  OK  ] Started Create Static Device Nodes in /dev.
    [  OK  ] Started Apply Kernel Variables.
    [    5.119109] systemd-journald[86]: Received request to flush runtime journal from PID 1
             Starting udev Kernel Device Manager...
    [  OK  ] Reached target Local File Systems (Pre).
    [  OK  ] Reached target Containers.
             Mounting /media/ram...
             Mounting /var/volatile...
    [  OK  ] Started Flush Journal to Persistent Storage.
    [  OK  ] Mounted /media/ram.
    [  OK  ] Mounted /var/volatile.
    [  OK  ] Started udev Kernel Device Manager.
             Starting Load/Save Random Seed...
    [  OK  ] Reached target Local File Systems.
             Starting Create Volatile Files and Directories...
    [  OK  ] Started Load/Save Random Seed.
    [  OK  ] Started Create Volatile Files and Directories.
             Starting Update UTMP about System Boot/Shutdown...
             Starting Network Time Synchronization...
             Starting Network Service...
    [  OK  ] Started Update UTMP about System Boot/Shutdown.
    [  OK  ] Started Network Service.
             Starting Network Name Resolution...
             Starting Wait for Network to be Configured...
    [  OK  ] Started Network Time Synchronization.
    [  OK  ] Reached target System Time Synchronized.
    [    6.998849] omap_wdt: OMAP Watchdog Timer Rev 0x01: initial timeout 60 sec
    [    7.010278] omap_rtc 44e3e000.rtc: registered as rtc0
    [    7.157096] remoteproc remoteproc0: wkup_m3 is available
    [  OK  ] Found device /dev/ttyS0.
    [  OK  ] Started udev Coldplug all Devices.
    [  OK  ] Reached target System Initialization.
    [  OK  ] Started Daily Cleanup of Temporary Directories.
    [  OK  ] Started Daily rotation of log files.
    [    7.525448] remoteproc remoteproc0: powering up wkup_m3
    [  OK  ] Reached target Timers.
    [  OK  ] Listening on RPCbind Server Activation Socket.
    [    7.588737] tlv320aic3x-codec 1-0018: Using deprecated property "gpio-reset", please update your DT
    [    7.597909] tlv320aic3x-codec 1-0018: 1-0018 supply IOVDD not found, using dummy regulator
    [  OK  ] Listening on Avahi mDNS/DNS-SD Stack Activation Socket.
    [    7.639919] remoteproc remoteproc0: Booting fw image am335x-pm-firmware.elf, size 242888
    [    7.781554] remoteproc remoteproc0: remote processor wkup_m3 is now up
    [    7.788227] wkup_m3_ipc 44e11324.wkup_m3_ipc: CM3 Firmware Version = 0x192
    [  OK  ] Listening on dropbear.socket.
    [    7.840232] tlv320aic3x-codec 1-0018: Linked as a consumer to regulator.0
    [  OK  ] Listening on D-Bus System Message Bus Socket.
    [  OK  ] Reached target Sockets.
    [  OK  ] Reached target Basic System.
    [    7.961606] tlv320aic3x-codec 1-0018: 1-0018 supply DVDD not found, using dummy regulator
    [  OK  ] Started Job spooling tools.
    [    8.055914] tlv320aic3x-codec 1-0018: 1-0018 supply AVDD not found, using dummy regulator
             Starting RPC Bind Service...
    [    8.108341] tlv320aic3x-codec 1-0018: 1-0018 supply DRVDD not found, using dummy regulator
    [  OK  ] Started D-Bus System Message Bus.
    [    8.245100] tlv320aic3x-codec 1-0018: Invalid supply voltage(s) AVDD: -22, DVDD: -22
             Starting Print notice about GPLv3 packages...
             Starting Login Service...
    [  OK  ] Started Periodic Command Scheduler.
    [   10.033680] PM: bootloader does not support rtc-only!
    [   10.072152] input: matrix_keypad0 as /devices/platform/matrix_keypad0/input/input1
    [   10.084455] omap-sham 53100000.sham: hw accel on OMAP rev 0.0
             Starting Reboot and dump vmcore via kexec...
             Starting rc.pvr.service...
    [  OK  ] Started Network Name Resolution.
    [   10.254966] omap-aes 53501000.aes: OMAP AES hw accel rev: 0.1
    [   10.339728] omap-des 53701000.des: OMAP DES hw accel rev: 0.33
    [  OK  ] Started RPC Bind Service.
    [   10.408342] omap-aes 53501000.aes: will run requests pump with realtime priority
    [   10.434403] omap-des 53701000.des: will run requests pump with realtime priority
    [   10.537086] [drm] Initialized pvr 1.17.4948957 20110701 for 56000000.sgx on minor 1
    [  OK  ] Started Reboot and dump vmcore via kexec.
    [   10.797819] PVR_K: UM DDK-(4948957) and KM DDK-(4948957) match. [ OK ]
    [   11.021427] asoc-simple-card sound0: tlv320aic3x-hifi <-> 4803c000.mcasp mapping ok
    [  OK  ] Started rc.pvr.service.
    [   11.098337] asoc-simple-card sound0: ASoC: no DMI vendor name!
    [   11.184916] asoc-simple-card sound1: silabs_pcm <-> 48038000.mcasp mapping ok
    [   11.270045] asoc-simple-card sound1: ASoC: no DMI vendor name!
    [   11.406525] net eth0: initializing cpsw version 1.15 (0)
    [   11.549023] NS DP83848C 10/100 Mbps PHY 4a101000.mdio:01: attached PHY driver [NS DP83848C 10/100 Mbps PHY] (mii_bus:phy_addr=4a101000.mdio:01, irq=POLL)
    [   11.654790] IPv6: ADDRCONF(NETDEV_UP): eth0: link is not ready
    [   15.722475] input: ti-tsc as /devices/platform/44000000.ocp/44e0d000.tscadc/TI-am335x-tsc.3.auto/input/input2
    [   17.915477] cfg80211: Loading compiled-in X.509 certificates for regulatory database
    [   18.260967] cfg80211: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'
    [   19.343989] wl18xx_driver wl18xx.4.auto: Direct firmware load for ti-connectivity/wl1271-nvs.bin failed with error -2
    ***************************************************************
    ***************************************************************
    NOTICE: This file system contains the following GPLv3 packages:
            autoconf
            bash-dev
            bash
            bc
            binutils
            cifs-utils
            cpio
            cpp-symlinks
            cpp
            dosfstools
            elfutils
            findutils
            g++-symlinks
            g++
            gawk
            gcc-symlinks
            gcc
            gdb
            gdbserver
            gettext
            glmark2
            gstreamer1.0-libav
            gzip
            hidapi
            libbfd
            libcairo-perf-utils
            libdw1
            libelf1
            libgdbm-compat4
            libgdbm-dev
            libgdbm6
            libgettextlib
            libgettextsrc
            libgmp10
            libidn2-0
            libmavconn
            libmpc3
            libmpfr6
            libreadline-dev
            libreadline7
            libunistring2
            m4-dev
            m4
            make
            mavlink
            mavros-extras
            mavros-msgs
            mavros
            nettle
            pdm-anomaly-detection
            socketcan-interface
            swig-dev
            swig
            which
    
    If you do not wish to distribute GPLv3 components please remove
    the above packages prior to distribution.  This can be done using
    the opkg remove command.  i.e.:
        opkg remove <package>
    Where <package> is the name printed in the list above
    
    NOTE: If the package is a dependency of another package you
          will be notified of the dependent packages.  You should
          use the --force-removal-of-dependent-packages option to
          also remove the dependent packages as well
    ***************************************************************
    ***************************************************************
    [  OK  ] Started Print notice about GPLv3 packages.
    [   21.229397] wlcore: wl18xx HW: 183x or 180x, PG 2.2 (ROM 0x11)
    [   21.299890] wlcore: loaded
    [   23.417750] wlcore: PHY firmware version: Rev 8.2.0.0.245
    [   23.541548] wlcore: firmware booted (Rev 8.9.0.0.85)
    [   23.570195] IPv6: ADDRCONF(NETDEV_UP): wlan0: link is not ready
             Starting Save/Restore Sound Card State...
    [  OK  ] Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch.
    [  OK  ] Created slice system-systemd\x2dfsck.slice.
             Starting File System Check on /dev/mmcblk0p1...
             Starting telnetd.service...
    [  OK  ] Created slice system-systemd\x2dbacklight.slice.
             Starting Load/Save Screen Backlight…ightness of backlight:backlight...
             Starting Avahi mDNS/DNS-SD Stack...
    [  OK  ] Reached target Network.
             Starting Permit User Sessions...
             Starting Lightning Fast Webserver With Light System Requirements...
             Starting Simple Network Management Protocol (SNMP) Daemon....
    [  OK  ] Started Redis In-Memory Data Store.
             Starting Enable and configure wl18xx bluetooth stack...
    [  OK  ] Reached target Host and Network Name Lookups.
    [  OK  ] Started NFS status monitor for NFSv2/3 locking..
    [  OK  ] Started Save/Restore Sound Card State.
    [  OK  ] Started File System Check on /dev/mmcblk0p1.
    [  OK  ] Started telnetd.service.
    [  OK  ] Started Load/Save Screen Backlight Brightness of backlight:backlight.
    [  OK  ] Started Permit User Sessions.
    [  OK  ] Started Lightning Fast Webserver With Light System Requirements.
    [  OK  ] Started Enable and configure wl18xx bluetooth stack.
    [  OK  ] Started Avahi mDNS/DNS-SD Stack.
             Starting Load/Save RF Kill Switch Status...
    [  OK  ] Started Getty on tty1.
    [  OK  ] Started Serial Getty on ttyS0.
    [  OK  ] Reached target Login Prompts.
             Starting Synchronize System and HW clocks...
             Starting thttpd.service...
             Mounting /run/media/mmcblk0p1...
    [  OK  ] Reached target Sound Card.
    [  OK  ] Started Load/Save RF Kill Switch Status.
    [  OK  ] Started Synchronize System and HW clocks.
    [  OK  ] Started Login Service.
    [  OK  ] Started thttpd.service.
    [  OK  ] Mounted /run/media/mmcblk0p1.
    [  OK  ] Started Simple Network Management Protocol (SNMP) Daemon..
    
     _____                    _____           _         _   
    |  _  |___ ___ ___ ___   |  _  |___ ___  |_|___ ___| |_ 
    |     |  _| .'| . | . |  |   __|  _| . | | | -_|  _|  _|
    |__|__|_| |__,|_  |___|  |__|  |_| |___|_| |___|___|_|  
                  |___|                    |___|            
    
    Arago Project http://arago-project.org am437x-evm ttyS0
    
    Arago 2019.11 am437x-evm ttyS0
    
    am437x-evm login: [  127.297004] NET: Registered protocol family 15
    [  127.759591] Initializing XFRM netlink socket
    

  • Tanner,

    The kernel log has the following error. It seems your wifi isn't setup properly.

    [   19.343989] wl18xx_driver wl18xx.4.auto: Direct firmware load for ti-connectivity/wl1271-nvs.bin failed with error -2

  • What does this error mean? I have the same wl1271-nvs.bin file in /lib/firmware/ti-connectivity/ on both the NFS and my SD card, and I get this error during the bootup for both cases, but I am only having this issue when booting from the SD card. So if this really is the issue, how do I fix it and why do I only see the delay when booting from the SD card?

  • I also did some searching around it is noted in the below E2E post that the wl1271-nvs.bin file is not needed

    e2e.ti.com/.../592929

  • Tanner,

    I am forwarding your query to the wl18xx expert. Response will be posted here soon.

  • Tanner,

    wl1271-nvs.bin is optional - it is used to configure custom WLAN MAC address. Consult section 7.3 for instructions on how to create this file : https://www.ti.com/lit/ug/swru562/swru562.pdf

  • Okay well are you saying you believe that is what is causing the delay? I never did anything to create that file on my NFS and I am not seeing the delay when I use the NFS, so I can't imagine that's what the issue is

  • Tanner,

    We are not saying that NVS file is causing the issue - this was just a troubleshooting step . You may test wl8 kernel module loading by running load/unload scripts in /usr/share/wl18xx

    Saurabh

  • This is the output I got from running the load/unload scripts:

    root@am437x-evm:~# /usr/share/wl18xx/unload_wlcore.sh
    [ 120.047899] wlcore: down
    killall: hostapd: no process killed
    [ 120.109892] wlcore: down
    rmmod: ERROR: Module wl12xx is not currently loaded
    rmmod: ERROR: Module compat is not currently loaded

    root@am437x-evm:~# /usr/share/wl18xx/load_wlcore.sh
    [ 125.715266] cfg80211: Loading compiled-in X.509 certificates for regulatory database
    [ 125.745517] cfg80211: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'
    root@am437x-evm:~# [ 126.700511] wl18xx_driver wl18xx.4.auto: Direct firmware load for ti-connectivity/wl1271-nvs.bin failed with error -2
    [ 128.587931] wlcore: wl18xx HW: 183x or 180x, PG 2.2 (ROM 0x11)
    [ 128.632847] wlcore: loaded
    [ 130.816606] wlcore: PHY firmware version: Rev 8.2.0.0.240
    [ 130.910800] wlcore: firmware booted (Rev 8.9.0.0.76)
    [ 130.931373] IPv6: ADDRCONF(NETDEV_UP): wlan0: link is not ready

    These are not the same messages that are coming up minutes after booting:

    [ 127.865844] NET: Registered protocol family 15
    [ 128.371934] Initializing XFRM netlink socket

    Is there a different startup script that for some reason is taking a long time to run?

  • Hi,

    I tested it on AM335x beagblebone with wifi cape and didn't see the issue. Probably missing nvs file is a non issue here. It could be something to do with start up services but that should be same for NFS. I don't believe if it's a WiFi driver issue. May be you can try checking with Sitara sdk team

    Saurabh