kernel deadlock warning during driver load

[mpvader]

Hi Larry,

see message at 24.146556 below. It doesn't seem to do any harm, at least, I did not find anything that doesn't work yet.

Any thoughts on below? Or should I perhaps go to connman with this?

best, Matthijs

[    0.000000] Booting Linux on physical CPU 0x0
[    0.000000] Linux version 4.1.15-venus (matthijs@matthijs-VirtualBox) (gcc version 5.2.0 (GCC) ) #1 Sun Oct 9 21:47:33 CEST 2016
[    0.000000] CPU: ARMv7 Processor [413fc082] revision 2 (ARMv7), cr=10c5387d
[    0.000000] CPU: PIPT / VIPT nonaliasing data cache, VIPT aliasing instruction cache
[    0.000000] Machine model: TI AM335x BeagleBone Black
[    0.000000] cma: Reserved 16 MiB at 0xbf000000
[    0.000000] Memory policy: Data cache writeback
[    0.000000] CPU: All CPU(s) started in SVC mode.
[    0.000000] AM335X ES2.1 (sgx neon )
[    0.000000] Built 1 zonelists in Zone order, mobility grouping on.  Total pages: 260624
[    0.000000] Kernel command line: console=ttyO0,115200n8 consoleblank=0 root=/dev/mmcblk1p3 rootfstype=ext4 rootwait
[    0.000000] PID hash table entries: 4096 (order: 2, 16384 bytes)
[    0.000000] Dentry cache hash table entries: 131072 (order: 7, 524288 bytes)
[    0.000000] Inode-cache hash table entries: 65536 (order: 6, 262144 bytes)
[    0.000000] Memory: 1007408K/1048576K available (4993K kernel code, 322K rwdata, 1752K rodata, 276K init, 8172K bss, 24784K reserved, 16384K cma-reserved, 253952K highmem)
[    0.000000] Virtual kernel memory layout:
[    0.000000]     vector  : 0xffff0000 - 0xffff1000   (   4 kB)
[    0.000000]     fixmap  : 0xffc00000 - 0xfff00000   (3072 kB)
[    0.000000]     vmalloc : 0xf0000000 - 0xff000000   ( 240 MB)
[    0.000000]     lowmem  : 0xc0000000 - 0xef800000   ( 760 MB)
[    0.000000]     pkmap   : 0xbfe00000 - 0xc0000000   (   2 MB)
[    0.000000]     modules : 0xbf000000 - 0xbfe00000   (  14 MB)
[    0.000000]       .text : 0xc0008000 - 0xc069e928   (6747 kB)
[    0.000000]       .init : 0xc069f000 - 0xc06e4000   ( 276 kB)
[    0.000000]       .data : 0xc06e4000 - 0xc0734a38   ( 323 kB)
[    0.000000]        .bss : 0xc0734a38 - 0xc0f2fc18   (8173 kB)
[    0.000000] Running RCU self tests
[    0.000000] NR_IRQS:16 nr_irqs:16 16
[    0.000000] IRQ: Found an INTC at 0xfa200000 (revision 5.0) with 128 interrupts
[    0.000000] OMAP clockevent source: timer2 at 24000000 Hz
[    0.000012] sched_clock: 32 bits at 24MHz, resolution 41ns, wraps every 89478484971ns
[    0.000034] clocksource timer1: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 79635851949 ns
[    0.000075] OMAP clocksource: timer1 at 24000000 Hz
[    0.000739] Console: colour dummy device 80x30
[    0.000790] Lock dependency validator: Copyright (c) 2006 Red Hat, Inc., Ingo Molnar
[    0.000798] ... MAX_LOCKDEP_SUBCLASSES:  8
[    0.000804] ... MAX_LOCK_DEPTH:          48
[    0.000810] ... MAX_LOCKDEP_KEYS:        8191
[    0.000816] ... CLASSHASH_SIZE:          4096
[    0.000822] ... MAX_LOCKDEP_ENTRIES:     32768
[    0.000828] ... MAX_LOCKDEP_CHAINS:      65536
[    0.000834] ... CHAINHASH_SIZE:          32768
[    0.000840]  memory used by lock dependency info: 5167 kB
[    0.000846]  per task-struct memory footprint: 1152 bytes
[    0.000871] Calibrating delay loop... 996.14 BogoMIPS (lpj=4980736)
[    0.078946] pid_max: default: 32768 minimum: 301
[    0.079148] Mount-cache hash table entries: 2048 (order: 1, 8192 bytes)
[    0.079162] Mountpoint-cache hash table entries: 2048 (order: 1, 8192 bytes)
[    0.081389] CPU: Testing write buffer coherency: ok
[    0.082578] Setting up static identity map for 0x80008200 - 0x80008258
[    0.086560] devtmpfs: initialized
[    0.111873] VFP support v0.3: implementor 41 architecture 3 part 30 variant c rev 3
[    0.147591] omap_hwmod: tptc0 using broken dt data from edma
[    0.147993] omap_hwmod: tptc1 using broken dt data from edma
[    0.148375] omap_hwmod: tptc2 using broken dt data from edma
[    0.156163] omap_hwmod: debugss: _wait_target_disable failed
[    0.211265] clocksource jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns
[    0.213701] pinctrl core: initialized pinctrl subsystem
[    0.218328] NET: Registered protocol family 16
[    0.224825] DMA: preallocated 256 KiB pool for atomic coherent allocations
[    0.227542] cpuidle: using governor ladder
[    0.227579] cpuidle: using governor menu
[    0.235124] OMAP GPIO hardware version 0.1
[    0.253063] No ATAGs?
[    0.253095] hw-breakpoint: debug architecture 0x4 unsupported.
[    0.322501] edma-dma-engine edma-dma-engine.0: TI EDMA DMA engine driver
[    0.326947] usbcore: registered new interface driver usbfs
[    0.327105] usbcore: registered new interface driver hub
[    0.327257] usbcore: registered new device driver usb
[    0.328163] omap_i2c 44e0b000.i2c: could not find pctldev for node /ocp/l4_wkup@44c00000/scm@210000/pinmux@800/pinmux_i2c0_pins, deferring probe
[    0.328263] omap_i2c 4802a000.i2c: could not find pctldev for node /ocp/l4_wkup@44c00000/scm@210000/pinmux@800/pinmux_i2c1_pins, deferring probe
[    0.331784] Switched to clocksource timer1
[    0.469459] NET: Registered protocol family 2
[    0.471154] TCP established hash table entries: 8192 (order: 3, 32768 bytes)
[    0.471370] TCP bind hash table entries: 8192 (order: 6, 294912 bytes)
[    0.473841] TCP: Hash tables configured (established 8192 bind 8192)
[    0.474060] UDP hash table entries: 512 (order: 3, 40960 bytes)
[    0.474384] UDP-Lite hash table entries: 512 (order: 3, 40960 bytes)
[    0.475285] NET: Registered protocol family 1
[    0.477176] CPU PMU: Failed to parse /pmu/interrupt-affinity[0]
[    0.477247] hw perfevents: enabled with armv7_cortex_a8 PMU driver, 5 counters available
[    0.480989] futex hash table entries: 256 (order: 1, 11264 bytes)
[    0.490396] VFS: Disk quotas dquot_6.6.0
[    0.490500] VFS: Dquot-cache hash table entries: 1024 (order 0, 4096 bytes)
[    0.496350] bounce: pool size: 64 pages
[    0.496475] io scheduler noop registered
[    0.496494] io scheduler deadline registered
[    0.496530] io scheduler cfq registered (default)
[    0.499745] pinctrl-single 44e10800.pinmux: 142 pins at pa f9e10800 size 568
[    0.503436] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled
[    0.509900] omap_uart 44e09000.serial: no wakeirq for uart0
[    0.510488] 44e09000.serial: ttyO0 at MMIO 0x44e09000 (irq = 154, base_baud = 3000000) is a OMAP UART0
[    1.087377] console [ttyO0] enabled
[    1.094205] omap_uart 48024000.serial: no wakeirq for uart2
[    1.100334] 48024000.serial: ttyO2 at MMIO 0x48024000 (irq = 155, base_baud = 3000000) is a OMAP UART2
[    1.111746] omap_uart 481a8000.serial: no wakeirq for uart4
[    1.117922] 481a8000.serial: ttyO4 at MMIO 0x481a8000 (irq = 156, base_baud = 3000000) is a OMAP UART4
[    1.129201] omap_uart 481aa000.serial: no wakeirq for uart5
[    1.135362] 481aa000.serial: ttyO5 at MMIO 0x481aa000 (irq = 157, base_baud = 3000000) is a OMAP UART5
[    1.148309] omap_rng 48310000.rng: OMAP Random Number Generator ver. 20
[    1.187793] brd: module loaded
[    1.208978] loop: module loaded
[    1.214155] vcan: Virtual CAN interface driver
[    1.218830] slcan: serial line CAN interface driver
[    1.223991] slcan: 10 dynamic interface channels.
[    1.228935] CAN device driver interface
[    1.235447] c_can_platform 481cc000.can: c_can_platform device registered (regs=fa1cc000, irq=163)
[    1.246700] c_can_platform 481d0000.can: c_can_platform device registered (regs=fa1d0000, irq=164)
[    1.257634] usbcore: registered new interface driver asix
[    1.263481] usbcore: registered new interface driver ax88179_178a
[    1.269973] usbcore: registered new interface driver cdc_ether
[    1.276262] usbcore: registered new interface driver smsc95xx
[    1.282409] usbcore: registered new interface driver net1080
[    1.288444] usbcore: registered new interface driver cdc_subset
[    1.294772] usbcore: registered new interface driver zaurus
[    1.300810] usbcore: registered new interface driver cdc_ncm
[    1.316996] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver
[    1.323935] ehci-omap: OMAP-EHCI Host Controller driver
[    1.329766] ohci_hcd: USB 1.1 'Open' Host Controller (OHCI) Driver
[    1.336490] usbcore: registered new interface driver cdc_wdm
[    1.342628] usbcore: registered new interface driver usbtest
[    1.351212] 47401300.usb-phy supply vcc not found, using dummy regulator
[    1.367708] 47401b00.usb-phy supply vcc not found, using dummy regulator
[    1.379982] musb-hdrc musb-hdrc.1.auto: MUSB HDRC host driver
[    1.389293] musb-hdrc musb-hdrc.1.auto: new USB bus registered, assigned bus number 1
[    1.399385] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002
[    1.406561] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[    1.414151] usb usb1: Product: MUSB HDRC host driver
[    1.419357] usb usb1: Manufacturer: Linux 4.1.15-venus musb-hcd
[    1.425576] usb usb1: SerialNumber: musb-hdrc.1.auto
[    1.434956] hub 1-0:1.0: USB hub found
[    1.439725] hub 1-0:1.0: 1 port detected
[    1.450617] mousedev: PS/2 mouse device common for all mice
[    1.459746] i2c /dev entries driver
[    1.463585] Driver for 1-wire Dallas network protocol.
[    1.474127] omap_wdt: OMAP Watchdog Timer Rev 0x01: initial timeout 60 sec
[    1.483866] omap_hsmmc 48060000.mmc: Got CD GPIO
[    1.569587] mmc0: host does not support reading read-only switch, assuming write-enable
[    1.581511] ledtrig-cpu: registered to indicate activity on CPUs
[    1.588401] usbcore: registered new interface driver usbhid
[    1.594278] usbhid: USB HID core driver
[    1.599320] oprofile: using arm/armv7
[    1.603825] Initializing XFRM netlink socket
[    1.608629] NET: Registered protocol family 10
[    1.615829] mmc0: new high speed SDHC card at address 59b4
[    1.628610] mmcblk0: mmc0:59b4 00000 14.9 GiB
[    1.638440] sit: IPv6 over IPv4 tunneling driver
[    1.645522] NET: Registered protocol family 17
[    1.650261] NET: Registered protocol family 15
[    1.654993] can: controller area network core (rev 20120528 abi 9)
[    1.661602] NET: Registered protocol family 29
[    1.666382] can: raw protocol (rev 20120528)
[    1.670998] can: broadcast manager protocol (rev 20120528 t)
[    1.677431] Key type dns_resolver registered
[    1.682109] omap_voltage_late_init: Voltage driver support not added
[    1.689753] ThumbEE CPU extension supported.
[    1.695966]  mmcblk0: p1
[    1.749152] tps65217 0-0024: TPS65217 ID 0x6 version 1.2
[    1.754925] omap_i2c 44e0b000.i2c: bus 0 rev0.11 at 400 kHz
[    1.761992] mmc1: MAN_BKOPS_EN bit is not set
[    1.773774] mmc1: new high speed MMC card at address 0001
[    1.780844] mmcblk1: mmc1:0001 P1XXXX 3.60 GiB
[    1.786767] mmcblk1boot0: mmc1:0001 P1XXXX partition 1 2.00 MiB
[    1.793969] mmcblk1boot1: mmc1:0001 P1XXXX partition 2 2.00 MiB
[    1.803662]  mmcblk1: p1 p2 p3 p4 < p5 p6 >
[    1.851881] usb 1-1: new high-speed USB device number 2 using musb-hdrc
[    1.992158] usb 1-1: New USB device found, idVendor=0424, idProduct=2514
[    1.999200] usb 1-1: New USB device strings: Mfr=0, Product=0, SerialNumber=0
[    2.009726] hub 1-1:1.0: USB hub found
[    2.014460] hub 1-1:1.0: 4 ports detected
[    2.291837] usb 1-1.4: new high-speed USB device number 3 using musb-hdrc
[    2.412580] usb 1-1.4: New USB device found, idVendor=0bda, idProduct=b720
[    2.419795] usb 1-1.4: New USB device strings: Mfr=1, Product=2, SerialNumber=3
[    2.427487] usb 1-1.4: Product: 802.11n WLAN Adapter
[    2.432710] usb 1-1.4: Manufacturer: Realtek
[    2.437186] usb 1-1.4: SerialNumber: 00e04c000001
[    2.781829] omap_i2c 4802a000.i2c: timeout waiting for bus ready
[    2.788261] rtc-pcf8523: probe of 1-0068 failed with error -110
[    2.794542] omap_i2c 4802a000.i2c: bus 1 rev0.11 at 400 kHz
[    2.861851] davinci_mdio 4a101000.mdio: davinci mdio revision 1.6
[    2.868261] davinci_mdio 4a101000.mdio: detected phy mask fffffffe
[    2.877943] libphy: 4a101000.mdio: probed
[    2.882242] davinci_mdio 4a101000.mdio: phy[0]: device 4a101000.mdio:00, driver Atheros 8035 ethernet
[    2.892998] cpsw 4a100000.ethernet: Detected MACID = b0:d5:cc:f1:b4:c2
[    2.903099] hctosys: unable to open rtc device (rtc0)
[    2.941936] EXT4-fs (mmcblk1p3): mounted filesystem with ordered data mode. Opts: (null)
[    2.950584] VFS: Mounted root (ext4 filesystem) readonly on device 179:11.
[    2.962455] devtmpfs: mounted
[    2.966073] Freeing unused kernel memory: 276K (c069f000 - c06e4000)
INIT: version 2.88 booting
[    3.313916] EXT4-fs (mmcblk1p5): mounted filesystem with ordered data mode. Opts: (null)
[    3.329037] EXT4-fs (mmcblk1p6): mounted filesystem with ordered data mode. Opts: (null)
Starting udev
udev: Not using udev cache because of changes detected in the following files:
udev:     /proc/version /proc/cmdline /proc/devices
udev:     lib/udev/rules.d/* etc/udev/rules.d/*
udev: The udev cache will be regenerated. To identify the detected changes,
udev: compare the cached sysconf at   /etc/udev/cache.data
udev: against the current sysconf at  /dev/shm/udev.cache
[    3.576089] udevd[680]: starting version 182
[    4.176437] cfg80211: Calling CRDA to update world regulatory domain
[    4.568469] RTL871X: module init start
[    4.572530] RTL871X: rtl8723bu v4.3.6.11_12942.20141204_BTCOEX20140507-4E40
[    4.579831] RTL871X: rtl8723bu BT-Coex version = BTCOEX20140507-4E40
[    7.333506] cfg80211: Calling CRDA to update world regulatory domain
[    7.855974] RTL871X: rtw_ndev_init(wlan0)
[    7.862894] RTL871X: rtw_ndev_init(wlan1)
[    7.869668] usbcore: registered new interface driver rtl8723bu
[    7.875860] RTL871X: module init ret=0
[    8.176733] cfg80211: World regulatory domain updated:
[    8.182236] cfg80211:  DFS Master region: unset
[    8.186806] cfg80211:   (start_freq - end_freq @ bandwidth), (max_antenna_gain, max_eirp), (dfs_cac_time)
[    8.197042] cfg80211:   (2402000 KHz - 2472000 KHz @ 40000 KHz), (N/A, 2000 mBm), (N/A)
[    8.205449] cfg80211:   (2457000 KHz - 2482000 KHz @ 40000 KHz), (N/A, 2000 mBm), (N/A)
[    8.213855] cfg80211:   (2474000 KHz - 2494000 KHz @ 20000 KHz), (N/A, 2000 mBm), (N/A)
[    8.222262] cfg80211:   (5170000 KHz - 5250000 KHz @ 80000 KHz, 160000 KHz AUTO), (N/A, 2000 mBm), (N/A)
[    8.232217] cfg80211:   (5250000 KHz - 5330000 KHz @ 80000 KHz, 160000 KHz AUTO), (N/A, 2000 mBm), (0 s)
[    8.242185] cfg80211:   (5490000 KHz - 5730000 KHz @ 160000 KHz), (N/A, 2000 mBm), (0 s)
[    8.250669] cfg80211:   (5735000 KHz - 5835000 KHz @ 80000 KHz), (N/A, 2000 mBm), (N/A)
[    8.259077] cfg80211:   (57240000 KHz - 63720000 KHz @ 2160000 KHz), (N/A, 0 mBm), (N/A)
[    8.445930] EXT4-fs (mmcblk1p3): re-mounted. Opts: data=ordered
bootlogd: cannot allocate pseudo tty: No such file or directory
[    8.598357] random: dd urandom read with 106 bits of entropy available
Populating dev cache
Starting watchdog
[    9.694001] watchdog watchdog0: watchdog did not stop!
INIT: Entering runlevel: 5
Configuring network interfaces... [   11.381624] net eth0: initializing cpsw version 1.12 (0)
[   11.462457] net eth0: phy found : id is : 0x4dd072
[   11.467641] libphy: PHY 4a101000.mdio:01 not found
[   11.472697] net eth0: phy 4a101000.mdio:01 not found on slave 1
[   11.484881] IPv6: ADDRCONF(NETDEV_UP): eth0: link is not ready
udhcpc (v1.23.2) started
Sending discover...
Sending discover...
Sending discover...
No lease, forking to background
done.
Starting system message bus: Setting up watches.
Watches established.
/var/run/dbus/ CREATE system_bus_socket
hwclock: can't open '/dev/misc/rtc': No such file or directory
Starting syslogd/klogd: done
Starting php-fpm [   21.061920] random: nonblocking pool is initialized
 done
Starting Connection Manager
Starting Hiawatha Web Server: dbus[1402]: [system] Activating service name='fi.w1.wpa_supplicant1' (using servicehelper)
hiawatha.
Starting crond: dbus[1402]: [system] Successfully activated service 'fi.w1.wpa_supplicant1'
[   24.144964]
[   24.146556] =============================================
[   24.152214] [ INFO: possible recursive locking detected ]
[   24.157877] 4.1.15-venus #1 Tainted: G           O
[   24.163168] ---------------------------------------------
[   24.168824] connmand/1524 is trying to acquire lock:
[   24.174025]  (pmutex){+.+...}, at: [<bf10c37c>] usbctrl_vendorreq+0x9c/0x29c [8723bu]
[   24.182581]
[   24.182581] but task is already holding lock:
[   24.188695]  (pmutex){+.+...}, at: [<bf10af0c>] netdev_open+0x28/0x50 [8723bu]
[   24.196390]
[   24.196390] other info that might help us debug this:
[   24.203233]  Possible unsafe locking scenario:
[   24.203233]
[   24.209436]        CPU0
[   24.211994]        ----
[   24.214551]   lock(pmutex);
[   24.217482]   lock(pmutex);
[   24.220413]
[   24.220413]  *** DEADLOCK ***
[   24.220413]
[   24.226618]  May be due to missing lock nesting notation
[   24.226618]
[   24.233734] 2 locks held by connmand/1524:
[   24.238022]  #0:  (rtnl_mutex){+.+.+.}, at: [<c042c530>] devinet_ioctl+0xd0/0x7d4
[   24.245904]  #1:  (pmutex){+.+...}, at: [<bf10af0c>] netdev_open+0x28/0x50 [8723bu]
[   24.254042]
[   24.254042] stack backtrace:
[   24.258614] CPU: 0 PID: 1524 Comm: connmand Tainted: G           O    4.1.15-venus #1
[   24.266821] Hardware name: Generic AM33XX (Flattened Device Tree)
[   24.273234] [<c0014844>] (unwind_backtrace) from [<c0012994>] (show_stack+0x10/0x14)
[   24.281362] [<c0012994>] (show_stack) from [<c0067fe0>] (__lock_acquire+0x1454/0x1da0)
[   24.289665] [<c0067fe0>] (__lock_acquire) from [<c006922c>] (lock_acquire+0xac/0x12c)
[   24.297886] [<c006922c>] (lock_acquire) from [<c04bf4b4>] (mutex_lock_interruptible_nested+0x48/0x3bc)
[   24.307746] [<c04bf4b4>] (mutex_lock_interruptible_nested) from [<bf10c37c>] (usbctrl_vendorreq+0x9c/0x29c [8723bu])
[   24.318989] [<bf10c37c>] (usbctrl_vendorreq [8723bu]) from [<bf13ce9c>] (usb_read8+0x30/0x3c [8723bu])
[   24.328918] [<bf13ce9c>] (usb_read8 [8723bu]) from [<bf0db2a8>] (rtw_read8+0x18/0x1c [8723bu])
[   24.338108] [<bf0db2a8>] (rtw_read8 [8723bu]) from [<bf13b2f4>] (rtl8723bu_hal_init+0x30/0xb8c [8723bu])
[   24.348240] [<bf13b2f4>] (rtl8723bu_hal_init [8723bu]) from [<bf11fc60>] (rtw_hal_init+0x18/0x154 [8723bu])
[   24.358632] [<bf11fc60>] (rtw_hal_init [8723bu]) from [<bf10a9fc>] (_netdev_open+0x60/0x2b8 [8723bu])
[   24.368464] [<bf10a9fc>] (_netdev_open [8723bu]) from [<bf10af14>] (netdev_open+0x30/0x50 [8723bu])
[   24.378045] [<bf10af14>] (netdev_open [8723bu]) from [<c03c7d64>] (__dev_open+0xb8/0x11c)
[   24.386625] [<c03c7d64>] (__dev_open) from [<c03c7fe8>] (__dev_change_flags+0x94/0x144)
[   24.395021] [<c03c7fe8>] (__dev_change_flags) from [<c03c80b0>] (dev_change_flags+0x18/0x48)
[   24.403872] [<c03c80b0>] (dev_change_flags) from [<c042cb2c>] (devinet_ioctl+0x6cc/0x7d4)
[   24.412462] [<c042cb2c>] (devinet_ioctl) from [<c03adbec>] (sock_ioctl+0x1d8/0x2b0)
[   24.420496] [<c03adbec>] (sock_ioctl) from [<c011c39c>] (do_vfs_ioctl+0x3f0/0x5c0)
[   24.428436] [<c011c39c>] (do_vfs_ioctl) from [<c011c5a0>] (SyS_ioctl+0x34/0x5c)
[   24.436104] [<c011c5a0>] (SyS_ioctl) from [<c000f1e0>] (ret_fast_syscall+0x0/0x54)
OK
Checking available software versions...
[   24.932718] EXT4-fs (mmcblk1p2): mounted filesystem with ordered data mode. Opts: (null)
Starting simple-upnpd

[   26.566484] RTL871X: RTW_ADAPTIVITY_EN_AUTO, chplan:0x20, Regulation:3,3
[   26.573663] RTL871X: RTW_ADAPTIVITY_MODE_NORMAL
[   27.912415] IPv6: ADDRCONF(NETDEV_UP): wlan0: link is not ready
[   29.074533] RTL871X: nolinked power save enter
[   31.459176] RTL871X: RTW_ADAPTIVITY_EN_AUTO, chplan:0x20, Regulation:3,3
[   31.466352] RTL871X: RTW_ADAPTIVITY_MODE_NORMAL
[   35.041026] RTL871X: nolinked power save leave
[   35.264525] RTL871X: rtw_set_802_11_connect(wlan0)  fw_state=0x00000008
[   35.300008] c_can_platform 481cc000.can can0: setting BTR=1c05 BRPE=0000
[   35.307172] c_can_platform 481cc000.can: obtain a copy of previously claimed pinctrl
[   35.315461] IPv6: ADDRCONF(NETDEV_CHANGE): can0: link becomes ready
[   35.393186] RTL871X: start auth
[   35.398277] RTL871X: auth success, start assoc
[   35.405995] RTL871X: rtw_cfg80211_indicate_connect(wlan0) BSS not found !!
[   35.413307] RTL871X: assoc success
[   35.417140] IPv6: ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready
[   36.405464] RTL871X: send eapol packet
[   36.414788] RTL871X: send eapol packet
[   36.419568] RTL871X: set pairwise key camid:4, addr:98:fc:11:ae:b5:04, kid:0, type:AES
bgscan simple: Failed to enable signal strength monitoring
[   36.456327] RTL871X: set group key camid:5, addr:98:fc:11:ae:b5:04, kid:1, type:AES

[mpvader]

ps. this is on a Beaglebone Enhanced, running venus. Kernel 4.1.15.

[lwfinger]

That warning is harmless. It has been in the driver from the start, but I have been unable to fix it.

[mpvader]

thank you for the quick reply. I'll stop worrying about it.