* [PATCH] wlcore: Fix regression in wlcore_set_partition()
@ 2016-02-10 1:22 Emil Goode
2016-02-10 17:34 ` Kalle Valo
2016-02-12 9:45 ` Kalle Valo
0 siblings, 2 replies; 10+ messages in thread
From: Emil Goode @ 2016-02-10 1:22 UTC (permalink / raw)
To: Kalle Valo, Shahar Patury, Guy Mishol, Igor Grinberg,
Uri Mashiach, Ross Green
Cc: linux-wireless, netdev, linux-kernel
The below commit introduced a regression causing the wlcore
to time out and go into recovery.
commit 3719c17e1816695f415dd3b4ddcb679f7dc617c8
("wlcore/wl18xx: fw logger over sdio")
Reverting the changes regarding write of the last partition size
brings the module back to it's functional state.
Reported-by: Ross Green <rgkernel@gmail.com>
Signed-off-by: Emil Goode <emil.fsw@goode.io>
---
drivers/net/wireless/ti/wlcore/io.c | 8 ++++----
drivers/net/wireless/ti/wlcore/io.h | 4 ++--
2 files changed, 6 insertions(+), 6 deletions(-)
diff --git a/drivers/net/wireless/ti/wlcore/io.c b/drivers/net/wireless/ti/wlcore/io.c
index 9ac118e..564ca75 100644
--- a/drivers/net/wireless/ti/wlcore/io.c
+++ b/drivers/net/wireless/ti/wlcore/io.c
@@ -175,14 +175,14 @@ int wlcore_set_partition(struct wl1271 *wl,
if (ret < 0)
goto out;
+ /* We don't need the size of the last partition, as it is
+ * automatically calculated based on the total memory size and
+ * the sizes of the previous partitions.
+ */
ret = wlcore_raw_write32(wl, HW_PART3_START_ADDR, p->mem3.start);
if (ret < 0)
goto out;
- ret = wlcore_raw_write32(wl, HW_PART3_SIZE_ADDR, p->mem3.size);
- if (ret < 0)
- goto out;
-
out:
return ret;
}
diff --git a/drivers/net/wireless/ti/wlcore/io.h b/drivers/net/wireless/ti/wlcore/io.h
index 6c257b5..10cf374 100644
--- a/drivers/net/wireless/ti/wlcore/io.h
+++ b/drivers/net/wireless/ti/wlcore/io.h
@@ -36,8 +36,8 @@
#define HW_PART1_START_ADDR (HW_PARTITION_REGISTERS_ADDR + 12)
#define HW_PART2_SIZE_ADDR (HW_PARTITION_REGISTERS_ADDR + 16)
#define HW_PART2_START_ADDR (HW_PARTITION_REGISTERS_ADDR + 20)
-#define HW_PART3_SIZE_ADDR (HW_PARTITION_REGISTERS_ADDR + 24)
-#define HW_PART3_START_ADDR (HW_PARTITION_REGISTERS_ADDR + 28)
+#define HW_PART3_START_ADDR (HW_PARTITION_REGISTERS_ADDR + 24)
+
#define HW_ACCESS_REGISTER_SIZE 4
#define HW_ACCESS_PRAM_MAX_RANGE 0x3c000
--
2.1.4
^ permalink raw reply related [flat|nested] 10+ messages in thread* Re: [PATCH] wlcore: Fix regression in wlcore_set_partition() 2016-02-10 1:22 [PATCH] wlcore: Fix regression in wlcore_set_partition() Emil Goode @ 2016-02-10 17:34 ` Kalle Valo 2016-02-12 9:45 ` Kalle Valo 1 sibling, 0 replies; 10+ messages in thread From: Kalle Valo @ 2016-02-10 17:34 UTC (permalink / raw) To: Emil Goode Cc: Shahar Patury, Guy Mishol, Igor Grinberg, Uri Mashiach, Ross Green, linux-wireless, netdev, linux-kernel Emil Goode <emil.fsw@goode.io> writes: > The below commit introduced a regression causing the wlcore > to time out and go into recovery. > > commit 3719c17e1816695f415dd3b4ddcb679f7dc617c8 > ("wlcore/wl18xx: fw logger over sdio") > > Reverting the changes regarding write of the last partition size > brings the module back to it's functional state. > > Reported-by: Ross Green <rgkernel@gmail.com> > Signed-off-by: Emil Goode <emil.fsw@goode.io> A proper "Fixes:" line is good to have, but I can add it. I'm planning to queue this to 4.5. -- Kalle Valo ^ permalink raw reply [flat|nested] 10+ messages in thread
* Re: wlcore: Fix regression in wlcore_set_partition() 2016-02-10 1:22 [PATCH] wlcore: Fix regression in wlcore_set_partition() Emil Goode 2016-02-10 17:34 ` Kalle Valo @ 2016-02-12 9:45 ` Kalle Valo 2016-02-15 4:01 ` Ross Green 1 sibling, 1 reply; 10+ messages in thread From: Kalle Valo @ 2016-02-12 9:45 UTC (permalink / raw) To: Emil Goode Cc: Shahar Patury, Guy Mishol, Igor Grinberg, Uri Mashiach, Ross Green, linux-wireless, netdev, linux-kernel > The commit 3719c17e1816 ("wlcore/wl18xx: fw logger over sdio") introduced a > regression causing the wlcore to time out and go into recovery. Reverting the > changes regarding write of the last partition size brings the module back to > it's functional state. > > Fixes: 3719c17e1816 ("wlcore/wl18xx: fw logger over sdio") > Reported-by: Ross Green <rgkernel@gmail.com> > Signed-off-by: Emil Goode <emil.fsw@goode.io> > [kvalo@codeaurora.org: improved commit log] Thanks, applied to wireless-drivers.git. Kalle Valo ^ permalink raw reply [flat|nested] 10+ messages in thread
* Re: wlcore: Fix regression in wlcore_set_partition() 2016-02-12 9:45 ` Kalle Valo @ 2016-02-15 4:01 ` Ross Green 2016-02-16 15:24 ` Kalle Valo 0 siblings, 1 reply; 10+ messages in thread From: Ross Green @ 2016-02-15 4:01 UTC (permalink / raw) To: Kalle Valo Cc: Emil Goode, Shahar Patury, Guy Mishol, Igor Grinberg, Uri Mashiach, linux-wireless, netdev, linux-kernel [-- Attachment #1: Type: text/plain, Size: 1004 bytes --] On Fri, Feb 12, 2016 at 8:45 PM, Kalle Valo <kvalo@codeaurora.org> wrote: > >> The commit 3719c17e1816 ("wlcore/wl18xx: fw logger over sdio") introduced a >> regression causing the wlcore to time out and go into recovery. Reverting the >> changes regarding write of the last partition size brings the module back to >> it's functional state. >> >> Fixes: 3719c17e1816 ("wlcore/wl18xx: fw logger over sdio") >> Reported-by: Ross Green <rgkernel@gmail.com> >> Signed-off-by: Emil Goode <emil.fsw@goode.io> >> [kvalo@codeaurora.org: improved commit log] > > Thanks, applied to wireless-drivers.git. > > Kalle Valo I just tested linux-4.5-rc4 it appears the above fix missed the release for rc4! So the behaviour of firmware reset being called after the access of the last partition timesout. Again tested patch with the new release - 4.5-rc4 and found everything to work as expected again. So Hopefully for rc5 - Please! please find attached a copy of the dmesg output for 4.5-rc4 Regards, Ross Green [-- Attachment #2: dmesg-4.5-rc4 --] [-- Type: application/octet-stream, Size: 6255 bytes --] # uname -a Linux buildroot 4.5.0-rc4-panda #1 SMP PREEMPT Mon Feb 15 12:35:52 AEDT 2016 armv7l GNU/Linux # # dmesg [ 0.000000] Booting Linux on physical CPU 0x0 [ 0.000000] Linux version 4.5.0-rc4-panda (rfg@red.dwarf) (gcc version 5.3.0 (Buildroot 2016.02-git-00183-g1682aee) ) #1 SMP PREEMPT Mon Feb 15 12:35:52 AEDT 2016 [ 0.000000] CPU: ARMv7 Processor [412fc09a] revision 10 (ARMv7), cr=10c5387d [ 0.000000] CPU: PIPT / VIPT nonaliasing data cache, VIPT aliasing instruction cache ... ... [ 84.805572] ---[ end trace db8549b99c2fdcea ]--- [ 84.810607] wlcore: Hardware recovery in progress. FW ver: Rev 6.3.10.0.133 [ 84.818389] wlcore: pc: 0x0, hint_sts: 0x00000020 count: 2 [ 84.824554] wlcore: down [ 84.827301] ieee80211 phy0: Hardware restart was requested [ 85.451293] wlcore: firmware booted (Rev 6.3.10.0.133) [ 90.525634] wlcore: ERROR Tx stuck (in FW) for 5000 ms. Starting recovery [ 90.532836] ------------[ cut here ]------------ [ 90.537750] WARNING: CPU: 0 PID: 423 at drivers/net/wireless/ti/wlcore/main.c:787 wl1271_op_cancel_hw_scan+0x838/0x83c [wl) [ 90.550170] Modules linked in: btwilink st_drv wl12xx wlcore_sdio wlcore mac80211 cfg80211 bluetooth [ 90.559906] CPU: 0 PID: 423 Comm: kworker/u4:2 Tainted: G W 4.5.0-rc4-panda #1 [ 90.559906] Hardware name: Generic OMAP4 (Flattened Device Tree) [ 90.569885] Workqueue: phy0 wl12xx_tx_watchdog_work [wlcore] [ 90.575714] Backtrace: [ 90.581573] [<c0013f80>] (dump_backtrace) from [<c001417c>] (show_stack+0x18/0x1c) [ 90.591735] r7:bf0e6bf4 r6:60080113 r5:c0947d34 r4:00000000 [ 90.591735] [<c0014164>] (show_stack) from [<c03a19c4>] (dump_stack+0x94/0xa8) [ 90.599884] [<c03a1930>] (dump_stack) from [<c0034c98>] (warn_slowpath_common+0x88/0xb8) [ 90.613983] r7:bf0e6bf4 r6:00000313 r5:00000009 r4:00000000 [ 90.619903] [<c0034c10>] (warn_slowpath_common) from [<c0034d6c>] (warn_slowpath_null+0x24/0x2c) [ 90.619903] r8:00000000 r7:eea98d40 r6:eea98d7c r5:eea98d48 r4:eea98d40 [ 90.635833] [<c0034d48>] (warn_slowpath_null) from [<bf0e6bf4>] (wl1271_op_cancel_hw_scan+0x838/0x83c [wlcore]) [ 90.647125] [<bf0e6b94>] (wl1271_op_cancel_hw_scan [wlcore]) from [<bf0e6d1c>] (wl12xx_tx_watchdog_work+0x124/0x128 [wlcor) [ 90.655426] r5:eea98d48 r4:eea99b08 [ 90.655426] [<bf0e6bf8>] (wl12xx_tx_watchdog_work [wlcore]) from [<c004a6ec>] (process_one_work+0x128/0x400) [ 90.669891] r7:eeaf6500 r6:ef00b000 r5:ef0c3f80 r4:eea99b08 [ 90.673309] [<c004a5c4>] (process_one_work) from [<c004aa18>] (worker_thread+0x54/0x58c) [ 90.673309] r10:ef0c3f80 r9:ef3f0020 r8:ef00b018 r7:c092a100 r6:00000088 r5:ef0c3f98 [ 90.693115] r4:ef00b000 [ 90.696197] [<c004a9c4>] (worker_thread) from [<c0050534>] (kthread+0xe4/0xfc) [ 90.703582] r10:00000000 r9:00000000 r8:00000000 r7:c004a9c4 r6:ef0c3f80 r5:ef0ba280 [ 90.709899] r4:00000000 [ 90.714843] [<c0050450>] (kthread) from [<c000fb98>] (ret_from_fork+0x14/0x3c) [ 90.714843] r7:00000000 r6:00000000 r5:c0050450 r4:ef0ba280 [ 90.731231] ---[ end trace db8549b99c2fdceb ]--- [ 90.742095] wlcore: Hardware recovery in progress. FW ver: Rev 6.3.10.0.133 [ 90.749877] wlcore: pc: 0x0, hint_sts: 0x00000020 count: 3 [ 90.756011] wlcore: down [ 90.758789] ieee80211 phy0: Hardware restart was requested [ 91.310150] wlcore: firmware booted (Rev 6.3.10.0.133) [ 96.372070] wlcore: ERROR Tx stuck (in FW) for 5000 ms. Starting recovery [ 96.379302] ------------[ cut here ]------------ [ 96.385894] WARNING: CPU: 0 PID: 17 at drivers/net/wireless/ti/wlcore/main.c:787 wl1271_op_cancel_hw_scan+0x838/0x83c [wlc) [ 96.398284] Modules linked in: btwilink st_drv wl12xx wlcore_sdio wlcore mac80211 cfg80211 bluetooth [ 96.401611] CPU: 0 PID: 17 Comm: kworker/u4:1 Tainted: G W 4.5.0-rc4-panda #1 [ 96.401611] Hardware name: Generic OMAP4 (Flattened Device Tree) [ 96.418182] Workqueue: phy0 wl12xx_tx_watchdog_work [wlcore] [ 96.429290] Backtrace: [ 96.431915] [<c0013f80>] (dump_backtrace) from [<c001417c>] (show_stack+0x18/0x1c) [ 96.431915] r7:bf0e6bf4 r6:60080113 r5:c0947d34 r4:00000000 [ 96.431915] [<c0014164>] (show_stack) from [<c03a19c4>] (dump_stack+0x94/0xa8) [ 96.448455] [<c03a1930>] (dump_stack) from [<c0034c98>] (warn_slowpath_common+0x88/0xb8) [ 96.462158] r7:bf0e6bf4 r6:00000313 r5:00000009 r4:00000000 [ 96.462158] [<c0034c10>] (warn_slowpath_common) from [<c0034d6c>] (warn_slowpath_null+0x24/0x2c) [ 96.477478] r8:00000000 r7:eea98d40 r6:eea98d7c r5:eea98d48 r4:eea98d40 [ 96.481567] [<c0034d48>] (warn_slowpath_null) from [<bf0e6bf4>] (wl1271_op_cancel_hw_scan+0x838/0x83c [wlcore]) [ 96.481567] [<bf0e6b94>] (wl1271_op_cancel_hw_scan [wlcore]) from [<bf0e6d1c>] (wl12xx_tx_watchdog_work+0x124/0x128 [wlcor) [ 96.499908] r5:eea98d48 r4:eea99b08 [ 96.509887] [<bf0e6bf8>] (wl12xx_tx_watchdog_work [wlcore]) from [<c004a6ec>] (process_one_work+0x128/0x400) [ 96.519897] r7:eeaf6500 r6:ef00b000 r5:ef043f80 r4:eea99b08 [ 96.519897] [<c004a5c4>] (process_one_work) from [<c004aa18>] (worker_thread+0x54/0x58c) [ 96.529907] r10:ef043f80 r9:ef14a020 r8:ef00b018 r7:c092a100 r6:00000088 r5:ef043f98 [ 96.529907] r4:ef00b000 [ 96.529907] [<c004a9c4>] (worker_thread) from [<c0050534>] (kthread+0xe4/0xfc) [ 96.552673] r10:00000000 r9:00000000 r8:00000000 r7:c004a9c4 r6:ef043f80 r5:ef1404c0 [ 96.562683] r4:00000000 [ 96.562683] [<c0050450>] (kthread) from [<c000fb98>] (ret_from_fork+0x14/0x3c) [ 96.569885] r7:00000000 r6:00000000 r5:c0050450 r4:ef1404c0 [ 96.582153] ---[ end trace db8549b99c2fdcec ]--- [ 96.593139] wlcore: Hardware recovery in progress. FW ver: Rev 6.3.10.0.133 [ 96.600982] wlcore: pc: 0x0, hint_sts: 0x00000020 count: 4 [ 96.607116] wlcore: down [ 96.611175] ieee80211 phy0: Hardware restart was requested [ 97.197265] wlcore: firmware booted (Rev 6.3.10.0.133) ^ permalink raw reply [flat|nested] 10+ messages in thread
* Re: wlcore: Fix regression in wlcore_set_partition() 2016-02-15 4:01 ` Ross Green @ 2016-02-16 15:24 ` Kalle Valo 2016-02-17 5:34 ` Ross Green 0 siblings, 1 reply; 10+ messages in thread From: Kalle Valo @ 2016-02-16 15:24 UTC (permalink / raw) To: Ross Green Cc: Emil Goode, Shahar Patury, Guy Mishol, Igor Grinberg, Uri Mashiach, linux-wireless, netdev, linux-kernel Ross Green <rgkernel@gmail.com> writes: > On Fri, Feb 12, 2016 at 8:45 PM, Kalle Valo <kvalo@codeaurora.org> wrote: >> >>> The commit 3719c17e1816 ("wlcore/wl18xx: fw logger over sdio") introduced a >>> regression causing the wlcore to time out and go into recovery. Reverting the >>> changes regarding write of the last partition size brings the module back to >>> it's functional state. >>> >>> Fixes: 3719c17e1816 ("wlcore/wl18xx: fw logger over sdio") >>> Reported-by: Ross Green <rgkernel@gmail.com> >>> Signed-off-by: Emil Goode <emil.fsw@goode.io> >>> [kvalo@codeaurora.org: improved commit log] >> >> Thanks, applied to wireless-drivers.git. >> >> Kalle Valo > > I just tested linux-4.5-rc4 it appears the above fix missed the release for rc4! > So the behaviour of firmware reset being called after the access of > the last partition timesout. > > Again tested patch with the new release - 4.5-rc4 and found everything > to work as expected again. > > So Hopefully for rc5 - Please! It takes some time to get patches into Linus' tree. And being in a conference and then getting sick is not really helping. I'm not sure if this patch makes to rc5 on time, but I'll try. -- Kalle Valo ^ permalink raw reply [flat|nested] 10+ messages in thread
* Re: wlcore: Fix regression in wlcore_set_partition() 2016-02-16 15:24 ` Kalle Valo @ 2016-02-17 5:34 ` Ross Green 2016-02-24 5:40 ` Ross Green 0 siblings, 1 reply; 10+ messages in thread From: Ross Green @ 2016-02-17 5:34 UTC (permalink / raw) To: Kalle Valo Cc: Emil Goode, Shahar Patury, Guy Mishol, Igor Grinberg, Uri Mashiach, linux-wireless, netdev, linux-kernel Appreciate your efforts! Just trying to make sure it does not get lost. Introduced in rc1, not fixed by ... rc4. Anyway, I will continue to test, lots of other things still to chase even in rc4! Regards, Ross Green On Wed, Feb 17, 2016 at 2:24 AM, Kalle Valo <kvalo@codeaurora.org> wrote: > Ross Green <rgkernel@gmail.com> writes: > >> On Fri, Feb 12, 2016 at 8:45 PM, Kalle Valo <kvalo@codeaurora.org> wrote: >>> >>>> The commit 3719c17e1816 ("wlcore/wl18xx: fw logger over sdio") introduced a >>>> regression causing the wlcore to time out and go into recovery. Reverting the >>>> changes regarding write of the last partition size brings the module back to >>>> it's functional state. >>>> >>>> Fixes: 3719c17e1816 ("wlcore/wl18xx: fw logger over sdio") >>>> Reported-by: Ross Green <rgkernel@gmail.com> >>>> Signed-off-by: Emil Goode <emil.fsw@goode.io> >>>> [kvalo@codeaurora.org: improved commit log] >>> >>> Thanks, applied to wireless-drivers.git. >>> >>> Kalle Valo >> >> I just tested linux-4.5-rc4 it appears the above fix missed the release for rc4! >> So the behaviour of firmware reset being called after the access of >> the last partition timesout. >> >> Again tested patch with the new release - 4.5-rc4 and found everything >> to work as expected again. >> >> So Hopefully for rc5 - Please! > > It takes some time to get patches into Linus' tree. And being in a > conference and then getting sick is not really helping. I'm not sure if > this patch makes to rc5 on time, but I'll try. > > -- > Kalle Valo ^ permalink raw reply [flat|nested] 10+ messages in thread
* Re: wlcore: Fix regression in wlcore_set_partition() 2016-02-17 5:34 ` Ross Green @ 2016-02-24 5:40 ` Ross Green 2016-02-25 0:11 ` Emil Goode 0 siblings, 1 reply; 10+ messages in thread From: Ross Green @ 2016-02-24 5:40 UTC (permalink / raw) To: Kalle Valo Cc: Emil Goode, Shahar Patury, Guy Mishol, Igor Grinberg, Uri Mashiach, linux-wireless, netdev, lkml [-- Attachment #1: Type: text/plain, Size: 2145 bytes --] On Wed, Feb 17, 2016 at 4:34 PM, Ross Green <rgkernel@gmail.com> wrote: > Appreciate your efforts! > > Just trying to make sure it does not get lost. > Introduced in rc1, not fixed by ... rc4. > > Anyway, I will continue to test, lots of other things still to chase > even in rc4! > > Regards, > > Ross Green > > On Wed, Feb 17, 2016 at 2:24 AM, Kalle Valo <kvalo@codeaurora.org> wrote: >> Ross Green <rgkernel@gmail.com> writes: >> >>> On Fri, Feb 12, 2016 at 8:45 PM, Kalle Valo <kvalo@codeaurora.org> wrote: >>>> >>>>> The commit 3719c17e1816 ("wlcore/wl18xx: fw logger over sdio") introduced a >>>>> regression causing the wlcore to time out and go into recovery. Reverting the >>>>> changes regarding write of the last partition size brings the module back to >>>>> it's functional state. >>>>> >>>>> Fixes: 3719c17e1816 ("wlcore/wl18xx: fw logger over sdio") >>>>> Reported-by: Ross Green <rgkernel@gmail.com> >>>>> Signed-off-by: Emil Goode <emil.fsw@goode.io> >>>>> [kvalo@codeaurora.org: improved commit log] >>>> >>>> Thanks, applied to wireless-drivers.git. >>>> >>>> Kalle Valo >>> >>> I just tested linux-4.5-rc4 it appears the above fix missed the release for rc4! >>> So the behaviour of firmware reset being called after the access of >>> the last partition timesout. >>> >>> Again tested patch with the new release - 4.5-rc4 and found everything >>> to work as expected again. >>> >>> So Hopefully for rc5 - Please! >> >> It takes some time to get patches into Linus' tree. And being in a >> conference and then getting sick is not really helping. I'm not sure if >> this patch makes to rc5 on time, but I'll try. >> >> -- >> Kalle Valo G'day all, I have tested Emil's patch with each 4.5-rc release. Seems to work fine with rc2, rc3, rc4. I tried it with rc5 and get the following output from dmesg see attachment. So it looks like there is a reset that it recovers from and then proceeds OK. I see the patch has been queued by David Miller so it might make it into rc6. That will be great. It still does not look quite as clean as it should be however, given the noise in the dmesg output from rc5 Regards, Ross Green [-- Attachment #2: dmesg-4.5-rc5-wlcore --] [-- Type: application/octet-stream, Size: 26262 bytes --] $ uptime 07:41:55 up 1 day, 6:27, load average: 0.01, 0.05, 0.10 $ dmesg [ 0.000000] Booting Linux on physical CPU 0x0 [ 0.000000] Linux version 4.5.0-rc5-panda (rfg@red.dwarf) (gcc version 5.3.0 (Buildroot 2016.02-git-00183-g1682aee) ) #2 SMP PREEMPT Mon Feb 22 23:28:57 AEDT 2016 [ 0.000000] CPU: ARMv7 Processor [412fc09a] revision 10 (ARMv7), cr=10c5387d [ 0.000000] CPU: PIPT / VIPT nonaliasing data cache, VIPT aliasing instruction cache [ 0.000000] Machine model: TI OMAP4 PandaBoard-ES [ 0.000000] Memory policy: Data cache writealloc [ 0.000000] OMAP4: Map 0xbfe00000 to fe600000 for dram barrier [ 0.000000] On node 0 totalpages: 261632 [ 0.000000] free_area_init_node: node 0, pgdat c0966300, node_mem_map ef7f9000 [ 0.000000] Normal zone: 1536 pages used for memmap [ 0.000000] Normal zone: 0 pages reserved [ 0.000000] Normal zone: 196608 pages, LIFO batch:31 [ 0.000000] HighMem zone: 65024 pages, LIFO batch:15 [ 0.000000] OMAP4460 ES1.1 [ 0.000000] PERCPU: Embedded 12 pages/cpu @ef7ab000 s17984 r8192 d22976 u49152 [ 0.000000] pcpu-alloc: s17984 r8192 d22976 u49152 alloc=12*4096 [ 0.000000] pcpu-alloc: [0] 0 [0] 1 [ 0.000000] Built 1 zonelists in Zone order, mobility grouping on. Total pages: 260096 [ 0.000000] Kernel command line: root=/dev/mmcblk0p2 rootwait console=ttyO2,115200 [ 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: 1027232K/1046528K available (6883K kernel code, 252K rwdata, 2156K rodata, 300K init, 298K bss, 19296K reserved, 0K cma-reserved, 260096K highmem) [ 0.000000] Virtual kernel memory layout: vector : 0xffff0000 - 0xffff1000 ( 4 kB) fixmap : 0xffc00000 - 0xfff00000 (3072 kB) vmalloc : 0xf0800000 - 0xff800000 ( 240 MB) lowmem : 0xc0000000 - 0xf0000000 ( 768 MB) pkmap : 0xbfe00000 - 0xc0000000 ( 2 MB) modules : 0xbf000000 - 0xbfe00000 ( 14 MB) .text : 0xc0008000 - 0xc08dc0d4 (9041 kB) .init : 0xc08dd000 - 0xc0928000 ( 300 kB) .data : 0xc0928000 - 0xc0967300 ( 253 kB) .bss : 0xc096a000 - 0xc09b48b8 ( 299 kB) [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=2, Nodes=1 [ 0.000000] Preemptible hierarchical RCU implementation. [ 0.000000] Build-time adjustment of leaf fanout to 32. [ 0.000000] NR_IRQS:16 nr_irqs:16 16 [ 0.000000] L2C: platform modifies aux control register: 0x0e070000 -> 0x3e470000 [ 0.000000] L2C: DT/platform modifies aux control register: 0x0e070000 -> 0x3e470000 [ 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, 1024 kB [ 0.000000] L2C-310: CACHE_ID 0x410000c7, AUX_CTRL 0x4e470000 [ 0.000000] ti_dt_clocks_register: failed to lookup clock node dss_fck [ 0.000000] ti_dt_clocks_register: failed to lookup clock node dss_fck [ 0.000000] ti_dt_clocks_register: failed to lookup clock node bandgap_fclk [ 0.000000] OMAP clockevent source: timer1 at 32768 Hz [ 0.000000] clocksource: 32k_counter: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 58327039986419 ns [ 0.000000] sched_clock: 32 bits at 32kHz, resolution 30517ns, wraps every 65535999984741ns [ 0.000000] OMAP clocksource: 32k_counter at 32768 Hz [ 0.000854] Console: colour dummy device 80x30 [ 0.000885] Calibrating delay loop... 1397.55 BogoMIPS (lpj=6987776) [ 0.089202] pid_max: default: 32768 minimum: 301 [ 0.089263] Security Framework initialized [ 0.089324] Mount-cache hash table entries: 2048 (order: 1, 8192 bytes) [ 0.089324] Mountpoint-cache hash table entries: 2048 (order: 1, 8192 bytes) [ 0.089965] CPU: Testing write buffer coherency: ok [ 0.090179] CPU0: thread -1, cpu 0, socket 0, mpidr 80000000 [ 0.090240] Setting up static identity map for 0x80008280 - 0x800082d8 [ 0.209197] CPU1: thread -1, cpu 1, socket 0, mpidr 80000001 [ 0.209259] Brought up 2 CPUs [ 0.209289] SMP: Total of 2 processors activated (2795.11 BogoMIPS). [ 0.209289] CPU: All CPU(s) started in SVC mode. [ 0.210083] devtmpfs: initialized [ 0.226806] VFP support v0.3: implementor 41 architecture 3 part 30 variant 9 rev 4 [ 0.227294] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns [ 0.227447] xor: measuring software checksum speed [ 0.319183] arm4regs : 1000.000 MB/sec [ 0.419158] 8regs : 804.000 MB/sec [ 0.519165] 32regs : 760.800 MB/sec [ 0.519195] xor: using function: arm4regs (1000.000 MB/sec) [ 0.519439] pinctrl core: initialized pinctrl subsystem [ 0.521331] NET: Registered protocol family 16 [ 0.522186] DMA: preallocated 256 KiB pool for atomic coherent allocations [ 0.523864] omap_hwmod: l3_main_3 using broken dt data from ocp [ 0.525390] omap_hwmod: l3_main_2 using broken dt data from ocp [ 0.630828] OMAP GPIO hardware version 0.1 [ 0.636108] omap-gpmc 50000000.gpmc: GPMC revision 6.0 [ 0.636108] gpmc_mem_init: disabling cs 0 mapped at 0x0-0x1000000 [ 0.636657] irq: no irq domain found for /ocp/l4@4a000000/scm@100000/pinmux@40 ! [ 0.637023] irq: no irq domain found for /ocp/l4@4a000000/scm@100000/pinmux@40 ! [ 0.637420] irq: no irq domain found for /ocp/l4@4a000000/scm@100000/pinmux@40 ! [ 0.649230] platform 4b501000.aes: Cannot lookup hwmod 'aes' [ 0.649536] platform 480a5000.des: Cannot lookup hwmod 'des' [ 0.655517] hw-breakpoint: found 5 (+1 reserved) breakpoint and 1 watchpoint registers. [ 0.655517] hw-breakpoint: maximum watchpoint size is 4 bytes. [ 0.656158] omap4_sram_init:Unable to get sram pool needed to handle errata I688 [ 0.656372] OMAP DMA hardware revision 0.0 [ 0.909423] raid6: int32x1 gen() 103 MB/s [ 1.079467] raid6: int32x1 xor() 82 MB/s [ 1.249237] raid6: int32x2 gen() 134 MB/s [ 1.419403] raid6: int32x2 xor() 98 MB/s [ 1.589385] raid6: int32x4 gen() 145 MB/s [ 1.759429] raid6: int32x4 xor() 97 MB/s [ 1.929260] raid6: int32x8 gen() 160 MB/s [ 2.099487] raid6: int32x8 xor() 94 MB/s [ 2.099487] raid6: using algorithm int32x8 gen() 160 MB/s [ 2.099487] raid6: .... xor() 94 MB/s, rmw enabled [ 2.099517] raid6: using intx1 recovery algorithm [ 2.105529] omap-dma-engine 4a056000.dma-controller: OMAP DMA engine driver [ 2.179321] reg-fixed-voltage wl12xx_vmmc: could not find pctldev for node /ocp/l4@4a000000/scm@100000/pinmux@40/pinmux_wl12xx_gpio, deferring probe [ 2.183563] omap-iommu 4a066000.mmu: 4a066000.mmu registered [ 2.183685] omap-iommu 55082000.mmu: 55082000.mmu registered [ 2.185760] SCSI subsystem initialized [ 2.186065] usbcore: registered new interface driver usbfs [ 2.186187] usbcore: registered new interface driver hub [ 2.186340] usbcore: registered new device driver usb [ 2.187286] omap_i2c 48070000.i2c: could not find pctldev for node /ocp/l4@4a000000/scm@100000/pinmux@40/pinmux_i2c1_pins, deferring probe [ 2.187347] omap_i2c 48072000.i2c: could not find pctldev for node /ocp/l4@4a000000/scm@100000/pinmux@40/pinmux_i2c2_pins, deferring probe [ 2.187377] omap_i2c 48060000.i2c: could not find pctldev for node /ocp/l4@4a000000/scm@100000/pinmux@40/pinmux_i2c3_pins, deferring probe [ 2.187408] omap_i2c 48350000.i2c: could not find pctldev for node /ocp/l4@4a000000/scm@100000/pinmux@40/pinmux_i2c4_pins, deferring probe [ 2.187683] Linux video capture interface: v2.00 [ 2.188415] Advanced Linux Sound Architecture Driver Initialized. [ 2.189880] clocksource: Switched to clocksource 32k_counter [ 2.204132] NET: Registered protocol family 2 [ 2.204803] TCP established hash table entries: 8192 (order: 3, 32768 bytes) [ 2.204925] TCP bind hash table entries: 8192 (order: 4, 65536 bytes) [ 2.205047] TCP: Hash tables configured (established 8192 bind 8192) [ 2.205139] UDP hash table entries: 512 (order: 2, 16384 bytes) [ 2.205169] UDP-Lite hash table entries: 512 (order: 2, 16384 bytes) [ 2.205383] NET: Registered protocol family 1 [ 2.205749] RPC: Registered named UNIX socket transport module. [ 2.205780] RPC: Registered udp transport module. [ 2.205780] RPC: Registered tcp transport module. [ 2.205780] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 2.208068] hw perfevents: enabled with armv7_cortex_a9 PMU driver, 7 counters available [ 2.211242] futex hash table entries: 512 (order: 3, 32768 bytes) [ 2.233947] NFS: Registering the id_resolver key type [ 2.233978] Key type id_resolver registered [ 2.234008] Key type id_legacy registered [ 2.234008] nfs4filelayout_init: NFSv4 File Layout Driver Registering... [ 2.234039] Installing knfsd (copyright (C) 1996 okir@monad.swb.de). [ 2.235382] ntfs: driver 2.1.32 [Flags: R/O]. [ 2.235687] fuse init (API version 7.24) [ 2.238464] jitterentropy: Initialization failed with host not compliant with requirements: 2 [ 2.238555] bounce: pool size: 64 pages [ 2.238555] io scheduler noop registered [ 2.238586] io scheduler deadline registered [ 2.238616] io scheduler cfq registered (default) [ 2.239501] pinctrl-single 4a100040.pinmux: 203 pins at pa fc100040 size 406 [ 2.239715] pinctrl-single 4a31e040.pinmux: 28 pins at pa fc31e040 size 56 [ 2.242767] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 2.244354] omap_uart 4806a000.serial: no wakeirq for uart0 [ 2.244476] 4806a000.serial: ttyO0 at MMIO 0x4806a000 (irq = 226, base_baud = 3000000) is a OMAP UART0 [ 2.244995] 4806c000.serial: ttyO1 at MMIO 0x4806c000 (irq = 227, base_baud = 3000000) is a OMAP UART1 [ 2.245452] 48020000.serial: ttyO2 at MMIO 0x48020000 (irq = 228, base_baud = 3000000) is a OMAP UART2 [ 3.142425] console [ttyO2] enabled [ 3.146545] 4806e000.serial: ttyO3 at MMIO 0x4806e000 (irq = 229, base_baud = 3000000) is a OMAP UART3 [ 3.157287] [drm] Initialized drm 1.1.0 20060810 [ 3.163757] 58000000.dss supply vdda_video not found, using dummy regulator [ 3.171142] OMAP DSS rev 4.0 [ 3.174316] omapdss_dss 58000000.dss: bound 58001000.dispc (ops dispc_component_ops) [ 3.182647] omapdss_dss 58000000.dss: bound 58005000.encoder (ops dsi_component_ops) [ 3.191192] omapdss_dss 58000000.dss: bound 58006000.encoder (ops hdmi4_component_ops) [ 3.243072] loop: module loaded [ 3.249145] usbcore: registered new interface driver smsc95xx [ 3.255279] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver [ 3.262115] ehci-omap: OMAP-EHCI Host Controller driver [ 3.279907] ehci-omap 4a064c00.ehci: EHCI Host Controller [ 3.285614] ehci-omap 4a064c00.ehci: new USB bus registered, assigned bus number 1 [ 3.293670] ehci-omap 4a064c00.ehci: irq 336, io mem 0x4a064c00 [ 3.319885] ehci-omap 4a064c00.ehci: USB 2.0 started, EHCI 1.00 [ 3.326263] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002 [ 3.333374] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1 [ 3.340942] usb usb1: Product: EHCI Host Controller [ 3.346069] usb usb1: Manufacturer: Linux 4.5.0-rc5-panda ehci_hcd [ 3.352539] usb usb1: SerialNumber: 4a064c00.ehci [ 3.358123] hub 1-0:1.0: USB hub found [ 3.362091] hub 1-0:1.0: 3 ports detected [ 3.367004] usbcore: registered new interface driver cdc_wdm [ 3.373077] usbcore: registered new interface driver usb-storage [ 3.379791] i2c /dev entries driver [ 3.383605] usbcore: registered new interface driver uvcvideo [ 3.389617] USB Video Class driver (1.1.1) [ 3.395111] omap_wdt: OMAP Watchdog Timer Rev 0x01: initial timeout 60 sec [ 3.402679] device-mapper: uevent: version 1.0.3 [ 3.407836] device-mapper: ioctl: 4.34.0-ioctl (2015-10-28) initialised: dm-devel@redhat.com [ 3.480865] hidraw: raw HID events driver (C) Jiri Kosina [ 3.486755] usbcore: registered new interface driver usbhid [ 3.492584] usbhid: USB HID core driver [ 3.497100] emif 4c000000.emif: emif_probe: device configured with addr = f08a8000 and IRQ247 [ 3.506408] emif 4d000000.emif: emif_probe: device configured with addr = f08aa000 and IRQ248 [ 3.517333] omap-abe-twl6040 sound: ASoC: CODEC DAI twl6040-legacy not registered [ 3.525299] omap-abe-twl6040 sound: snd_soc_register_card() failed: -517 [ 3.532775] ip_tables: (C) 2000-2006 Netfilter Core Team [ 3.538482] Initializing XFRM netlink socket [ 3.543029] NET: Registered protocol family 17 [ 3.547973] Key type dns_resolver registered [ 3.552673] twl: not initialized [ 3.556091] twl6030_uv_to_vsel:OUT OF RANGE! non mapped vsel for 1375000 Vs max 1316660 [ 3.564697] twl6030_uv_to_vsel:OUT OF RANGE! non mapped vsel for 1375000 Vs max 1316660 [ 3.573211] twl6030_uv_to_vsel:OUT OF RANGE! non mapped vsel for 1375000 Vs max 1316660 [ 3.581695] twl6030_uv_to_vsel:OUT OF RANGE! non mapped vsel for 1375000 Vs max 1316660 [ 3.590179] twl6030_uv_to_vsel:OUT OF RANGE! non mapped vsel for 1375000 Vs max 1316660 [ 3.598663] twl6030_uv_to_vsel:OUT OF RANGE! non mapped vsel for 1375000 Vs max 1316660 [ 3.607147] twl6030_uv_to_vsel:OUT OF RANGE! non mapped vsel for 1410000 Vs max 1316660 [ 3.615661] omap2_set_init_voltage: unable to find boot up OPP for vdd_mpu [ 3.623199] omap2_set_init_voltage: unable to set vdd_mpu [ 3.628936] omap2_set_init_voltage: unable to find boot up OPP for vdd_core [ 3.636322] omap2_set_init_voltage: unable to set vdd_core [ 3.642150] omap2_set_init_voltage: unable to find boot up OPP for vdd_iva [ 3.649414] omap2_set_init_voltage: unable to set vdd_iva [ 3.656066] Power Management for TI OMAP4+ devices. [ 3.661254] OMAP4 PM: u-boot >= v2012.07 is required for full PM support [ 3.668487] ThumbEE CPU extension supported. [ 3.673034] Registering SWP/SWPB emulation handler [ 3.673065] SmartReflex Class3 initialized [ 3.682525] usb 1-1: new high-speed USB device number 2 using ehci-omap [ 3.707427] Btrfs loaded [ 3.712707] dmm 4e000000.dmm: initialized all PAT entries [ 3.730163] Skipping twl internal clock init and using bootloader value (unknown osc rate) [ 3.760406] twl 0-0048: PIH (irq 340) nested IRQs [ 3.766815] twl_rtc 48070000.i2c:twl@48:rtc: Enabling TWL-RTC [ 3.775482] twl_rtc 48070000.i2c:twl@48:rtc: rtc core: registered 48070000.i2c:twl@48 as rtc0 [ 3.797241] twl6040 0-004b: clk32k is not handled [ 3.804412] omap_i2c 48070000.i2c: bus 0 rev0.11 at 400 kHz [ 3.811981] omap_i2c 48072000.i2c: bus 1 rev0.11 at 400 kHz [ 3.819000] omap_i2c 48060000.i2c: bus 2 rev0.11 at 100 kHz [ 3.825622] omap_i2c 48350000.i2c: bus 3 rev0.11 at 400 kHz [ 3.832000] omap_hsmmc 4809c000.mmc: omap_device: omap_device_enable() called from invalid state 1 [ 3.850494] usb 1-1: New USB device found, idVendor=0424, idProduct=9514 [ 3.857666] usb 1-1: New USB device strings: Mfr=0, Product=0, SerialNumber=0 [ 3.865997] hub 1-1:1.0: USB hub found [ 3.870147] hub 1-1:1.0: 5 ports detected [ 3.885772] omap_hsmmc 480d5000.mmc: omap_device: omap_device_enable() called from invalid state 1 [ 4.026062] omap_hsmmc 480d5000.mmc: card claims to support voltages below defined range [ 4.041259] omap-abe-twl6040 sound: twl6040-legacy <-> 40132000.mcpdm mapping ok [ 4.053192] twl_rtc 48070000.i2c:twl@48:rtc: setting system clock to 2016-02-22 14:14:55 UTC (1456150495) [ 4.063568] sr_init: No PMIC hook to init smartreflex [ 4.069244] smartreflex smartreflex.0: omap_sr_probe: SmartReflex driver initialized [ 4.077728] mmc1: new SDIO card at address 0001 [ 4.077728] smartreflex smartreflex.1: omap_sr_probe: SmartReflex driver initialized [ 4.077941] smartreflex smartreflex.2: omap_sr_probe: SmartReflex driver initialized [ 4.088684] VDAC: disabling [ 4.089141] VANA: disabling [ 4.089599] VUSB: disabling [ 4.089965] ALSA device list: [ 4.089965] #0: PandaBoardES [ 4.114654] Waiting for root device /dev/mmcblk0p2... [ 4.129089] mmc0: host does not support reading read-only switch, assuming write-enable [ 4.140960] mmc0: new high speed SDHC card at address e624 [ 4.161804] mmcblk0: mmc0:e624 SL32G 28.8 GiB [ 4.179931] usb 1-1.1: new high-speed USB device number 3 using ehci-omap [ 4.196685] mmcblk0: p1 p2 p3 p4 [ 4.241821] EXT4-fs (mmcblk0p2): couldn't mount as ext3 due to feature incompatibilities [ 4.251098] EXT4-fs (mmcblk0p2): mounting ext2 file system using the ext4 subsystem [ 4.281799] EXT4-fs (mmcblk0p2): mounted filesystem without journal. Opts: (null) [ 4.283599] VFS: Mounted root (ext2 filesystem) readonly on device 179:2. [ 4.304168] usb 1-1.1: New USB device found, idVendor=0424, idProduct=ec00 [ 4.311523] usb 1-1.1: New USB device strings: Mfr=0, Product=0, SerialNumber=0 [ 4.322143] smsc95xx v1.0.4 [ 4.325714] devtmpfs: mounted [ 4.328979] Freeing unused kernel memory: 300K (c08dd000 - c0928000) [ 4.410034] smsc95xx 1-1.1:1.0 eth0: register 'smsc95xx' at usb-4a064c00.ehci-1.1, smsc95xx USB 2.0 Ethernet, c2:03:46:11:42:fc [ 4.672088] EXT4-fs (mmcblk0p2): warning: mounting unchecked fs, running e2fsck is recommended [ 4.720947] EXT4-fs (mmcblk0p2): re-mounted. Opts: (null) [ 4.859893] random: dd urandom read with 31 bits of entropy available [ 9.680908] smsc95xx 1-1.1:1.0 eth0: hardware isn't capable of remote wakeup [ 19.578399] Bluetooth: Core ver 2.21 [ 19.582489] NET: Registered protocol family 31 [ 19.587188] Bluetooth: HCI device and connection manager initialized [ 19.594635] Bluetooth: HCI socket layer initialized [ 19.599731] Bluetooth: L2CAP socket layer initialized [ 19.605316] Bluetooth: SCO socket layer initialized [ 27.174468] NFSD: the nfsdcld client tracking upcall will be removed in 3.10. Please transition to using nfsdcltrack. [ 27.185882] NFSD: starting 90-second grace period (net c0960800) [ 30.303527] random: nonblocking pool is initialized [ 56.984252] wlcore: loaded [ 65.072265] wlcore: firmware booted (Rev 6.3.10.0.133) [ 65.807983] wlcore: ERROR ELP wakeup timeout! [ 65.812622] ------------[ cut here ]------------ [ 65.817535] WARNING: CPU: 1 PID: 1116 at drivers/net/wireless/ti/wlcore/main.c:787 wl1271_op_cancel_hw_scan+0x838/0x83c [wlcore]() [ 65.830047] Modules linked in: btwilink st_drv wl12xx wlcore_sdio wlcore mac80211 cfg80211 bluetooth [ 65.830047] CPU: 1 PID: 1116 Comm: wpa_supplicant Not tainted 4.5.0-rc5-panda #2 [ 65.839874] Hardware name: Generic OMAP4 (Flattened Device Tree) [ 65.853942] Backtrace: [ 65.853942] [<c0013f80>] (dump_backtrace) from [<c001417c>] (show_stack+0x18/0x1c) [ 65.864562] r7:bf0e6bf4 r6:60030113 r5:c0947d74 r4:00000000 [ 65.869873] [<c0014164>] (show_stack) from [<c03a2364>] (dump_stack+0x94/0xa8) [ 65.878265] [<c03a22d0>] (dump_stack) from [<c0034c98>] (warn_slowpath_common+0x88/0xb8) [ 65.886810] r7:bf0e6bf4 r6:00000313 r5:00000009 r4:00000000 [ 65.890624] [<c0034c10>] (warn_slowpath_common) from [<c0034d6c>] (warn_slowpath_null+0x24/0x2c) [ 65.899871] r8:bf103540 r7:ee9a8d94 r6:00000000 r5:c092a100 r4:ee9a8d40 [ 65.899871] [<c0034d48>] (warn_slowpath_null) from [<bf0e6bf4>] (wl1271_op_cancel_hw_scan+0x838/0x83c [wlcore]) [ 65.919952] [<bf0e6b94>] (wl1271_op_cancel_hw_scan [wlcore]) from [<bf0e8e98>] (wl12xx_queue_recovery_work+0x1c/0x20 [wlcore]) [ 65.929870] r5:c092a100 r4:ee9a8d40 [ 65.929870] [<bf0e8e7c>] (wl12xx_queue_recovery_work [wlcore]) from [<bf0f26d4>] (wl1271_ps_elp_wakeup+0x200/0x208 [wlcore]) [ 65.947723] [<bf0f24d4>] (wl1271_ps_elp_wakeup [wlcore]) from [<bf0e4114>] (wl1271_op_hw_scan+0x68/0xe0 [wlcore]) [ 65.951965] r10:ef0034f8 r9:ee9a83e0 r8:00000000 r7:ef355e00 r6:ee9a8d7c r5:00000000 [ 65.959869] r4:ee9a8d40 [ 65.959869] [<bf0e40ac>] (wl1271_op_hw_scan [wlcore]) from [<bf0938a0>] (ieee80211_sched_scan_stopped+0x294/0x4dc [mac80211]) [ 65.959869] r10:00000000 r9:ee9ffcf0 r8:00000000 r7:ee9a8ab0 r6:ef003400 r5:eebeb4c0 [ 65.989868] r4:ee9a83e0 [ 65.989868] [<bf0936a0>] (ieee80211_sched_scan_stopped [mac80211]) from [<bf09456c>] (ieee80211_request_scan+0x30/0x48 [mac80211]) [ 65.999877] r9:ee9ffcf0 r8:00000112 r7:ee9a8000 r6:ef003400 r5:0000064c r4:eebeb4c0 [ 66.009857] [<bf09453c>] (ieee80211_request_scan [mac80211]) from [<bf0a3184>] (ieee80211_scan+0x8c/0xc8 [mac80211]) [ 66.024505] r7:ee9a8000 r6:ef003400 r5:00000000 r4:eebeb4c0 [ 66.030609] [<bf0a30f8>] (ieee80211_scan [mac80211]) from [<bf072770>] (nl80211_trigger_scan+0x600/0x65c [cfg80211]) [ 66.039855] r5:00000000 r4:ee863924 [ 66.039855] [<bf072170>] (nl80211_trigger_scan [cfg80211]) from [<c06141e4>] (genl_rcv_msg+0x264/0x400) [ 66.055511] r10:edc58400 r9:c0960800 r8:00000000 r7:ef377a80 r6:ee863914 r5:bf07a158 [ 66.055511] r4:bf07ba48 [ 66.064208] [<c0613f80>] (genl_rcv_msg) from [<c0613430>] (netlink_rcv_skb+0xa8/0xc4) [ 66.068542] r10:ef377a80 r9:00000000 r8:00000000 r7:ef377a80 r6:ef377a80 r5:c0613f80 [ 66.083099] r4:ee863900 [ 66.083099] [<c0613388>] (netlink_rcv_skb) from [<c0613f70>] (genl_rcv+0x2c/0x3c) [ 66.089874] r7:ef377a80 r6:00000024 r5:ef377a80 r4:c09620a4 [ 66.093719] [<c0613f44>] (genl_rcv) from [<c0612d48>] (netlink_unicast+0x170/0x258) [ 66.093719] r5:ee86b800 r4:ef153800 [ 66.111633] [<c0612bd8>] (netlink_unicast) from [<c06131f8>] (netlink_sendmsg+0x2f8/0x360) [ 66.120361] r8:00000000 r7:00000024 r6:ee86b800 r5:00000000 r4:ee9fff4c [ 66.120361] [<c0612f00>] (netlink_sendmsg) from [<c05d43c0>] (sock_sendmsg+0x1c/0x2c) [ 66.135772] r10:00000000 r9:ee9ffe28 r8:00000000 r7:eed4dc80 r6:00000000 r5:00000000 [ 66.135772] r4:ee9fff4c [ 66.135772] [<c05d43a4>] (sock_sendmsg) from [<c05d4a60>] (___sys_sendmsg+0x1f4/0x1fc) [ 66.152099] [<c05d486c>] (___sys_sendmsg) from [<c05d5824>] (__sys_sendmsg+0x44/0x70) [ 66.152099] r10:00000000 r9:ee9fe000 r8:c000fce8 r7:00000128 r6:eed4dc80 r5:00000000 [ 66.171752] r4:bed4aa34 [ 66.174438] [<c05d57e0>] (__sys_sendmsg) from [<c05d5860>] (SyS_sendmsg+0x10/0x14) [ 66.180175] r6:0008d9d8 r5:0008e188 r4:0008da50 [ 66.180175] [<c05d5850>] (SyS_sendmsg) from [<c000fae0>] (ret_fast_syscall+0x0/0x3c) [ 66.195648] ---[ end trace 0a879fbe88a91723 ]--- [ 66.200653] wlcore: Hardware recovery in progress. FW ver: Rev 6.3.10.0.133 [ 66.200653] ------------[ cut here ]------------ [ 66.217193] WARNING: CPU: 1 PID: 17 at drivers/net/wireless/ti/wlcore/io.h:64 wlcore_set_partition+0xa8/0x450 [wlcore]() [ 66.228729] Modules linked in: btwilink st_drv wl12xx wlcore_sdio wlcore mac80211 cfg80211 bluetooth [ 66.231811] CPU: 1 PID: 17 Comm: kworker/u4:1 Tainted: G W 4.5.0-rc5-panda #2 [ 66.246429] Hardware name: Generic OMAP4 (Flattened Device Tree) [ 66.246429] Workqueue: phy0 wl1271_recovery_work [wlcore] [ 66.253631] Backtrace: [ 66.259918] [<c0013f80>] (dump_backtrace) from [<c001417c>] (show_stack+0x18/0x1c) [ 66.269958] r7:bf0ee8b0 r6:600c0113 r5:c0947d74 r4:00000000 [ 66.269958] [<c0014164>] (show_stack) from [<c03a2364>] (dump_stack+0x94/0xa8) [ 66.269958] [<c03a22d0>] (dump_stack) from [<c0034c98>] (warn_slowpath_common+0x88/0xb8) [ 66.269958] r7:bf0ee8b0 r6:00000040 r5:00000009 r4:00000000 [ 66.296539] [<c0034c10>] (warn_slowpath_common) from [<c0034d6c>] (warn_slowpath_null+0x24/0x2c) [ 66.296539] r8:ee9a8d7c r7:ee878200 r6:fffffffb r5:bf1212cc r4:ee9a8d40 [ 66.296539] [<c0034d48>] (warn_slowpath_null) from [<bf0ee8b0>] (wlcore_set_partition+0xa8/0x450 [wlcore]) [ 66.324890] [<bf0ee808>] (wlcore_set_partition [wlcore]) from [<bf0e60fc>] (wl1271_recovery_work+0x74/0x334 [wlcore]) [ 66.324890] r6:ee9a8d40 r5:ef043f80 r4:ee9a8fec [ 66.324890] [<bf0e6088>] (wl1271_recovery_work [wlcore]) from [<c004a6f4>] (process_one_work+0x128/0x400) [ 66.346649] r8:00000000 r7:ee878200 r6:ef00b000 r5:ef043f80 r4:ee9a8fec [ 66.346649] [<c004a5cc>] (process_one_work) from [<c004aa20>] (worker_thread+0x54/0x58c) [ 66.366851] r10:ef043f80 r9:ef14a020 r8:ef00b018 r7:c092a100 r6:00000088 r5:ef043f98 [ 66.366851] r4:ef00b000 [ 66.366851] [<c004a9cc>] (worker_thread) from [<c005053c>] (kthread+0xe4/0xfc) [ 66.385498] r10:00000000 r9:00000000 r8:00000000 r7:c004a9cc r6:ef043f80 r5:ef1404c0 [ 66.385498] r4:00000000 [ 66.385498] [<c0050458>] (kthread) from [<c000fb98>] (ret_from_fork+0x14/0x3c) [ 66.404144] r7:00000000 r6:00000000 r5:c0050458 r4:ef1404c0 [ 66.410247] ---[ end trace 0a879fbe88a91724 ]--- [ 66.411651] wlcore: down [ 66.418945] ieee80211 phy0: Hardware restart was requested [ 66.997100] wlcore: firmware booted (Rev 6.3.10.0.133) [ 70.594848] wlan0: authenticate with 90:f6:52:81:ee:70 [ 70.609497] wlan0: send auth to 90:f6:52:81:ee:70 (try 1/3) [ 70.616821] wlan0: send auth to 90:f6:52:81:ee:70 (try 2/3) [ 70.623901] wlan0: send auth to 90:f6:52:81:ee:70 (try 3/3) [ 70.631286] wlan0: authentication with 90:f6:52:81:ee:70 timed out [ 74.182769] wlan0: authenticate with 90:f6:52:81:ee:70 [ 74.196655] wlan0: send auth to 90:f6:52:81:ee:70 (try 1/3) [ 74.203765] wlan0: send auth to 90:f6:52:81:ee:70 (try 2/3) [ 74.210845] wlan0: send auth to 90:f6:52:81:ee:70 (try 3/3) [ 74.217895] wlan0: authentication with 90:f6:52:81:ee:70 timed out [ 78.127929] wlan0: authenticate with 90:f6:52:81:ee:70 [ 78.180389] wlan0: send auth to 90:f6:52:81:ee:70 (try 1/3) [ 78.188415] wlan0: authenticated [ 78.204956] wlan0: associate with 90:f6:52:81:ee:70 (try 1/3) [ 78.215667] wlan0: RX AssocResp from 90:f6:52:81:ee:70 (capab=0x431 status=0 aid=1) [ 78.230895] wlan0: associated [ 78.291473] wlcore: Association completed. ^ permalink raw reply [flat|nested] 10+ messages in thread
* Re: wlcore: Fix regression in wlcore_set_partition() 2016-02-24 5:40 ` Ross Green @ 2016-02-25 0:11 ` Emil Goode 2016-02-25 2:13 ` Ross Green 0 siblings, 1 reply; 10+ messages in thread From: Emil Goode @ 2016-02-25 0:11 UTC (permalink / raw) To: Ross Green Cc: Kalle Valo, Shahar Patury, Guy Mishol, Igor Grinberg, Uri Mashiach, linux-wireless, netdev, lkml [-- Attachment #1: Type: text/plain, Size: 3238 bytes --] Hello Ross On Wed, Feb 24, 2016 at 04:40:50PM +1100, Ross Green wrote: > On Wed, Feb 17, 2016 at 4:34 PM, Ross Green <rgkernel@gmail.com> wrote: > > Appreciate your efforts! > > > > Just trying to make sure it does not get lost. > > Introduced in rc1, not fixed by ... rc4. > > > > Anyway, I will continue to test, lots of other things still to chase > > even in rc4! > > > > Regards, > > > > Ross Green > > > > On Wed, Feb 17, 2016 at 2:24 AM, Kalle Valo <kvalo@codeaurora.org> wrote: > >> Ross Green <rgkernel@gmail.com> writes: > >> > >>> On Fri, Feb 12, 2016 at 8:45 PM, Kalle Valo <kvalo@codeaurora.org> wrote: > >>>> > >>>>> The commit 3719c17e1816 ("wlcore/wl18xx: fw logger over sdio") introduced a > >>>>> regression causing the wlcore to time out and go into recovery. Reverting the > >>>>> changes regarding write of the last partition size brings the module back to > >>>>> it's functional state. > >>>>> > >>>>> Fixes: 3719c17e1816 ("wlcore/wl18xx: fw logger over sdio") > >>>>> Reported-by: Ross Green <rgkernel@gmail.com> > >>>>> Signed-off-by: Emil Goode <emil.fsw@goode.io> > >>>>> [kvalo@codeaurora.org: improved commit log] > >>>> > >>>> Thanks, applied to wireless-drivers.git. > >>>> > >>>> Kalle Valo > >>> > >>> I just tested linux-4.5-rc4 it appears the above fix missed the release for rc4! > >>> So the behaviour of firmware reset being called after the access of > >>> the last partition timesout. > >>> > >>> Again tested patch with the new release - 4.5-rc4 and found everything > >>> to work as expected again. > >>> > >>> So Hopefully for rc5 - Please! > >> > >> It takes some time to get patches into Linus' tree. And being in a > >> conference and then getting sick is not really helping. I'm not sure if > >> this patch makes to rc5 on time, but I'll try. > >> > >> -- > >> Kalle Valo > > G'day all, > > I have tested Emil's patch with each 4.5-rc release. > > Seems to work fine with rc2, rc3, rc4. > I tried it with rc5 and get the following output from dmesg see attachment. > > So it looks like there is a reset that it recovers from and then proceeds OK. > > I see the patch has been queued by David Miller so it might make it into rc6. > That will be great. It still does not look quite as clean as it should > be however, given the noise in the dmesg output from rc5 > > Regards, > > > Ross Green I'm unable to reproduce that ELP wakeup timeout with v4.5-rc5 on my pandaboard es. Can you easily reproduce it and are you able to reproduce it with commit 3719c17e1816 reverted? However, I'm seeing another bug that occurs when the wlan is not configured to connect to an AP directly after boot (dmesg attached). It is not related to any recent changes and goes back before the 3.14 kernel. There seem to be an issue with the looped IRQ handling implementation. A scan on 2GHZ is performed and the wlcore_fw_status() call in wlcore_irq_locked() returns WL1271_ACX_INTR_HW_AVAILABLE, then nothing seem to happen until the delayed work queue scan_complete_work starts executing after the 30 sec timeout runs out and the wlcore goes into recovery. I guess a new scan should be initiated after wlcore_fw_status() return hw available, does anyone have input on that? Best regards, Emil Goode [-- Attachment #2: dmesg_v4.5-rc5_wl1271 --] [-- Type: text/plain, Size: 30430 bytes --] [ 0.000000] Booting Linux on physical CPU 0x0 [ 0.000000] Linux version 4.5.0-rc5-armv7-x1-00001-gffdb57e (emil@lianli) (gcc version 4.9.3 20141031 (prerelease) (Linaro GCC 2014.11) ) #23 SMP Wed Feb 24 20:36:15 CET 2016 [ 0.000000] CPU: ARMv7 Processor [412fc09a] revision 10 (ARMv7), cr=10c5387d [ 0.000000] CPU: PIPT / VIPT nonaliasing data cache, VIPT aliasing instruction cache [ 0.000000] Machine model: TI OMAP4 PandaBoard-ES [ 0.000000] cma: Reserved 16 MiB at 0xbe800000 [ 0.000000] Memory policy: Data cache writealloc [ 0.000000] OMAP4: Map 0xbfe00000 to fe600000 for dram barrier [ 0.000000] On node 0 totalpages: 261632 [ 0.000000] free_area_init_node: node 0, pgdat c0cbab40, node_mem_map ef6f9000 [ 0.000000] Normal zone: 1728 pages used for memmap [ 0.000000] Normal zone: 0 pages reserved [ 0.000000] Normal zone: 196608 pages, LIFO batch:31 [ 0.000000] HighMem zone: 65024 pages, LIFO batch:15 [ 0.000000] OMAP4460 ES1.1 [ 0.000000] PERCPU: Embedded 14 pages/cpu @ef696000 s24832 r8192 d24320 u57344 [ 0.000000] pcpu-alloc: s24832 r8192 d24320 u57344 alloc=14*4096 [ 0.000000] pcpu-alloc: [0] 0 [0] 1 [ 0.000000] Built 1 zonelists in Zone order, mobility grouping on. Total pages: 259904 [ 0.000000] Kernel command line: console=ttyO2,115200n8 root=/dev/mmcblk0p5 ro rootfstype=ext4 rootwait quiet [ 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: 999440K/1046528K available (7346K kernel code, 810K rwdata, 2404K rodata, 1024K init, 8123K bss, 30704K reserved, 16384K cma-reserved, 243712K highmem) [ 0.000000] Virtual kernel memory layout: vector : 0xffff0000 - 0xffff1000 ( 4 kB) fixmap : 0xffc00000 - 0xfff00000 (3072 kB) vmalloc : 0xf0800000 - 0xff800000 ( 240 MB) lowmem : 0xc0000000 - 0xf0000000 ( 768 MB) pkmap : 0xbfe00000 - 0xc0000000 ( 2 MB) modules : 0xbf000000 - 0xbfe00000 ( 14 MB) .text : 0xc0008000 - 0xc0a85b08 (10743 kB) .init : 0xc0b00000 - 0xc0c00000 (1024 kB) .data : 0xc0c00000 - 0xc0cca874 ( 811 kB) .bss : 0xc0ccd000 - 0xc14bbfe0 (8124 kB) [ 0.000000] Running RCU self tests [ 0.000000] Hierarchical RCU implementation. [ 0.000000] RCU lockdep checking is enabled. [ 0.000000] Build-time adjustment of leaf fanout to 32. [ 0.000000] NR_IRQS:16 nr_irqs:16 16 [ 0.000000] L2C: platform modifies aux control register: 0x0e070000 -> 0x3e470000 [ 0.000000] L2C: DT/platform modifies aux control register: 0x0e070000 -> 0x3e470000 [ 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, 1024 kB [ 0.000000] L2C-310: CACHE_ID 0x410000c7, AUX_CTRL 0x4e470000 [ 0.000000] ti_dt_clocks_register: failed to lookup clock node dss_fck [ 0.000000] ti_dt_clocks_register: failed to lookup clock node dss_fck [ 0.000000] ti_dt_clocks_register: failed to lookup clock node bandgap_fclk [ 0.000000] OMAP clockevent source: timer1 at 32768 Hz [ 0.000000] clocksource: 32k_counter: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 58327039986419 ns [ 0.000000] sched_clock: 32 bits at 32kHz, resolution 30517ns, wraps every 65535999984741ns [ 0.000000] OMAP clocksource: 32k_counter at 32768 Hz [ 0.001373] Console: colour dummy device 80x30 [ 0.001434] Lock dependency validator: Copyright (c) 2006 Red Hat, Inc., Ingo Molnar [ 0.001434] ... MAX_LOCKDEP_SUBCLASSES: 8 [ 0.001464] ... MAX_LOCK_DEPTH: 48 [ 0.001464] ... MAX_LOCKDEP_KEYS: 8191 [ 0.001464] ... CLASSHASH_SIZE: 4096 [ 0.001464] ... MAX_LOCKDEP_ENTRIES: 32768 [ 0.001495] ... MAX_LOCKDEP_CHAINS: 65536 [ 0.001495] ... CHAINHASH_SIZE: 32768 [ 0.001495] memory used by lock dependency info: 5167 kB [ 0.001495] per task-struct memory footprint: 1536 bytes [ 0.001525] Calibrating delay loop... 1397.55 BogoMIPS (lpj=6987776) [ 0.079437] pid_max: default: 32768 minimum: 301 [ 0.079895] Security Framework initialized [ 0.080047] Mount-cache hash table entries: 2048 (order: 1, 8192 bytes) [ 0.080078] Mountpoint-cache hash table entries: 2048 (order: 1, 8192 bytes) [ 0.083679] CPU: Testing write buffer coherency: ok [ 0.085357] CPU0: thread -1, cpu 0, socket 0, mpidr 80000000 [ 0.085449] Setting up static identity map for 0x80100000 - 0x80100070 [ 0.179412] CPU1: thread -1, cpu 1, socket 0, mpidr 80000001 [ 0.180053] Brought up 2 CPUs [ 0.180053] SMP: Total of 2 processors activated (2795.11 BogoMIPS). [ 0.180084] CPU: All CPU(s) started in SVC mode. [ 0.183349] devtmpfs: initialized [ 0.240203] VFP support v0.3: implementor 41 architecture 3 part 30 variant 9 rev 4 [ 0.241119] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns [ 0.243041] pinctrl core: initialized pinctrl subsystem [ 0.248199] NET: Registered protocol family 16 [ 0.253479] DMA: preallocated 256 KiB pool for atomic coherent allocations [ 0.258422] omap_hwmod: l3_main_3 using broken dt data from ocp [ 0.263183] omap_hwmod: l3_main_2 using broken dt data from ocp [ 0.469573] cpuidle: using governor menu [ 0.483856] gpiochip_add_data: registered GPIOs 0 to 31 on device: gpio [ 0.485168] OMAP GPIO hardware version 0.1 [ 0.486450] gpiochip_add_data: registered GPIOs 32 to 63 on device: gpio [ 0.488739] gpiochip_add_data: registered GPIOs 64 to 95 on device: gpio [ 0.491058] gpiochip_add_data: registered GPIOs 96 to 127 on device: gpio [ 0.493255] gpiochip_add_data: registered GPIOs 128 to 159 on device: gpio [ 0.495422] gpiochip_add_data: registered GPIOs 160 to 191 on device: gpio [ 0.497344] omap-gpmc 50000000.gpmc: GPMC revision 6.0 [ 0.497497] gpmc_mem_init: disabling cs 0 mapped at 0x0-0x1000000 [ 0.498565] irq: no irq domain found for /ocp/l4@4a000000/scm@100000/pinmux@40 ! [ 0.499206] irq: no irq domain found for /ocp/l4@4a000000/scm@100000/pinmux@40 ! [ 0.499908] irq: no irq domain found for /ocp/l4@4a000000/scm@100000/pinmux@40 ! [ 0.522094] platform 4b501000.aes: Cannot lookup hwmod 'aes' [ 0.522644] platform 480a5000.des: Cannot lookup hwmod 'des' [ 0.532501] No ATAGs? [ 0.532592] hw-breakpoint: found 5 (+1 reserved) breakpoint and 1 watchpoint registers. [ 0.532623] hw-breakpoint: maximum watchpoint size is 4 bytes. [ 0.534942] OMAP DMA hardware revision 0.0 [ 0.595001] omap-dma-engine 4a056000.dma-controller: OMAP DMA engine driver [ 0.596771] of_get_named_gpiod_flags: parsed 'gpio' property of node '/hsusb1_power_reg[0]' - status (0) [ 0.669830] reg-fixed-voltage wl12xx_vmmc: could not find pctldev for node /ocp/l4@4a000000/scm@100000/pinmux@40/pinmux_wl12xx_gpio, deferring probe [ 0.674224] SCSI subsystem initialized [ 0.674713] libata version 3.00 loaded. [ 0.675445] usb_phy_generic hsusb1_phy: GPIO lookup for consumer reset [ 0.675476] usb_phy_generic hsusb1_phy: using device tree for GPIO lookup [ 0.675537] of_get_named_gpiod_flags: parsed 'reset-gpios' property of node '/hsusb1_phy[0]' - status (0) [ 0.675598] no flags found for reset [ 0.675628] usb_phy_generic hsusb1_phy: GPIO lookup for consumer vbus-detect [ 0.675628] usb_phy_generic hsusb1_phy: using device tree for GPIO lookup [ 0.675659] of_get_named_gpiod_flags: can't parse 'vbus-detect-gpios' property of node '/hsusb1_phy[0]' [ 0.675659] of_get_named_gpiod_flags: can't parse 'vbus-detect-gpio' property of node '/hsusb1_phy[0]' [ 0.675689] usb_phy_generic hsusb1_phy: using lookup tables for GPIO lookup [ 0.675811] usb_phy_generic hsusb1_phy: lookup for GPIO vbus-detect failed [ 0.676910] omap_i2c 48070000.i2c: could not find pctldev for node /ocp/l4@4a000000/scm@100000/pinmux@40/pinmux_i2c1_pins, deferring probe [ 0.677001] omap_i2c 48072000.i2c: could not find pctldev for node /ocp/l4@4a000000/scm@100000/pinmux@40/pinmux_i2c2_pins, deferring probe [ 0.677093] omap_i2c 48060000.i2c: could not find pctldev for node /ocp/l4@4a000000/scm@100000/pinmux@40/pinmux_i2c3_pins, deferring probe [ 0.677185] omap_i2c 48350000.i2c: could not find pctldev for node /ocp/l4@4a000000/scm@100000/pinmux@40/pinmux_i2c4_pins, deferring probe [ 0.677581] pps_core: LinuxPPS API ver. 1 registered [ 0.677612] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it> [ 0.677642] PTP clock support registered [ 0.681610] clocksource: Switched to clocksource 32k_counter [ 0.791687] VFS: Disk quotas dquot_6.6.0 [ 0.791870] VFS: Dquot-cache hash table entries: 1024 (order 0, 4096 bytes) [ 0.825286] NET: Registered protocol family 2 [ 0.827362] TCP established hash table entries: 8192 (order: 3, 32768 bytes) [ 0.827545] TCP bind hash table entries: 8192 (order: 6, 294912 bytes) [ 0.829925] TCP: Hash tables configured (established 8192 bind 8192) [ 0.830230] UDP hash table entries: 512 (order: 3, 40960 bytes) [ 0.830566] UDP-Lite hash table entries: 512 (order: 3, 40960 bytes) [ 0.831726] NET: Registered protocol family 1 [ 0.833190] RPC: Registered named UNIX socket transport module. [ 0.833221] RPC: Registered udp transport module. [ 0.833221] RPC: Registered tcp transport module. [ 0.833221] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 0.838531] hw perfevents: enabled with armv7_cortex_a9 PMU driver, 7 counters available [ 0.844146] futex hash table entries: 512 (order: 3, 32768 bytes) [ 0.844451] audit: initializing netlink subsys (disabled) [ 0.844665] audit: type=2000 audit(0.830:1): initialized [ 0.851715] NFS: Registering the id_resolver key type [ 0.852111] Key type id_resolver registered [ 0.852111] Key type id_legacy registered [ 0.852325] jffs2: version 2.2. (NAND) (SUMMARY) © 2001-2006 Red Hat, Inc. [ 0.857574] bounce: pool size: 64 pages [ 0.857696] io scheduler noop registered [ 0.857727] io scheduler deadline registered [ 0.857757] io scheduler cfq registered (default) [ 0.861755] pinctrl-single 4a100040.pinmux: 203 pins at pa fc100040 size 406 [ 0.862274] pinctrl-single 4a31e040.pinmux: 28 pins at pa fc31e040 size 56 [ 0.869232] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 0.875091] omap_uart 4806a000.serial: no wakeirq for uart0 [ 0.875122] of_get_named_gpiod_flags: can't parse 'rts-gpio' property of node '/ocp/serial@4806a000[0]' [ 0.875579] 4806a000.serial: ttyO0 at MMIO 0x4806a000 (irq = 226, base_baud = 3000000) is a OMAP UART0 [ 0.877319] of_get_named_gpiod_flags: can't parse 'rts-gpio' property of node '/ocp/serial@4806c000[0]' [ 0.877593] 4806c000.serial: ttyO1 at MMIO 0x4806c000 (irq = 227, base_baud = 3000000) is a OMAP UART1 [ 0.878723] of_get_named_gpiod_flags: can't parse 'rts-gpio' property of node '/ocp/serial@48020000[0]' [ 0.878997] 48020000.serial: ttyO2 at MMIO 0x48020000 (irq = 228, base_baud = 3000000) is a OMAP UART2 [ 0.892547] console [ttyO2] enabled [ 0.893859] of_get_named_gpiod_flags: can't parse 'rts-gpio' property of node '/ocp/serial@4806e000[0]' [ 0.894134] 4806e000.serial: ttyO3 at MMIO 0x4806e000 (irq = 229, base_baud = 3000000) is a OMAP UART3 [ 0.927947] brd: module loaded [ 0.948852] loop: module loaded [ 0.951965] mtdoops: mtd device (mtddev=name/number) must be supplied [ 0.972167] mousedev: PS/2 mouse device common for all mice [ 0.972198] i2c /dev entries driver [ 0.973693] omap_hsmmc 4809c000.mmc: GPIO lookup for consumer cd [ 0.973724] omap_hsmmc 4809c000.mmc: using device tree for GPIO lookup [ 0.973724] of_get_named_gpiod_flags: can't parse 'cd-gpios' property of node '/ocp/mmc@4809c000[0]' [ 0.973754] of_get_named_gpiod_flags: can't parse 'cd-gpio' property of node '/ocp/mmc@4809c000[0]' [ 0.973754] omap_hsmmc 4809c000.mmc: using lookup tables for GPIO lookup [ 0.973785] omap_hsmmc 4809c000.mmc: lookup for GPIO cd failed [ 0.973785] omap_hsmmc 4809c000.mmc: GPIO lookup for consumer wp [ 0.973815] omap_hsmmc 4809c000.mmc: using device tree for GPIO lookup [ 0.973815] of_get_named_gpiod_flags: can't parse 'wp-gpios' property of node '/ocp/mmc@4809c000[0]' [ 0.973846] of_get_named_gpiod_flags: can't parse 'wp-gpio' property of node '/ocp/mmc@4809c000[0]' [ 0.973846] omap_hsmmc 4809c000.mmc: using lookup tables for GPIO lookup [ 0.973876] omap_hsmmc 4809c000.mmc: lookup for GPIO wp failed [ 1.003784] omap_hsmmc 480d5000.mmc: GPIO lookup for consumer wp [ 1.003814] omap_hsmmc 480d5000.mmc: using device tree for GPIO lookup [ 1.003814] of_get_named_gpiod_flags: can't parse 'wp-gpios' property of node '/ocp/mmc@480d5000[0]' [ 1.003845] of_get_named_gpiod_flags: can't parse 'wp-gpio' property of node '/ocp/mmc@480d5000[0]' [ 1.003845] omap_hsmmc 480d5000.mmc: using lookup tables for GPIO lookup [ 1.003875] omap_hsmmc 480d5000.mmc: lookup for GPIO wp failed [ 1.032287] ledtrig-cpu: registered to indicate activity on CPUs [ 1.033538] oprofile: using arm/armv7-ca9 [ 1.034271] Initializing XFRM netlink socket [ 1.034698] NET: Registered protocol family 10 [ 1.037139] sit: IPv6 over IPv4 tunneling driver [ 1.039337] NET: Registered protocol family 17 [ 1.039459] NET: Registered protocol family 15 [ 1.039825] Key type dns_resolver registered [ 1.040069] twl: not initialized [ 1.043487] twl6030_uv_to_vsel:OUT OF RANGE! non mapped vsel for 1375000 Vs max 1316660 [ 1.051879] twl6030_uv_to_vsel:OUT OF RANGE! non mapped vsel for 1375000 Vs max 1316660 [ 1.060272] twl6030_uv_to_vsel:OUT OF RANGE! non mapped vsel for 1375000 Vs max 1316660 [ 1.068695] twl6030_uv_to_vsel:OUT OF RANGE! non mapped vsel for 1375000 Vs max 1316660 [ 1.077087] twl6030_uv_to_vsel:OUT OF RANGE! non mapped vsel for 1375000 Vs max 1316660 [ 1.085479] twl6030_uv_to_vsel:OUT OF RANGE! non mapped vsel for 1375000 Vs max 1316660 [ 1.093872] twl6030_uv_to_vsel:OUT OF RANGE! non mapped vsel for 1410000 Vs max 1316660 [ 1.102294] omap2_set_init_voltage: unable to find boot up OPP for vdd_mpu [ 1.109497] omap2_set_init_voltage: unable to set vdd_mpu [ 1.115173] omap2_set_init_voltage: unable to find boot up OPP for vdd_core [ 1.122497] omap2_set_init_voltage: unable to set vdd_core [ 1.128234] omap2_set_init_voltage: unable to find boot up OPP for vdd_iva [ 1.135437] omap2_set_init_voltage: unable to set vdd_iva [ 1.143280] Power Management for TI OMAP4+ devices. [ 1.143280] OMAP4 PM: u-boot >= v2012.07 is required for full PM support [ 1.143585] ThumbEE CPU extension supported. [ 1.144744] Registering SWP/SWPB emulation handler [ 1.144744] SmartReflex Class3 initialized [ 1.150482] of_get_named_gpiod_flags: parsed 'gpio' property of node '/wl12xx_vmmc[0]' - status (0) [ 1.159118] Skipping twl internal clock init and using bootloader value (unknown osc rate) [ 1.172943] hw-breakpoint: Failed to enable monitor mode on CPU 0. [ 1.173767] hw-breakpoint: CPU 0 failed to disable vector catch [ 1.173919] twl 0-0048: PIH (irq 340) nested IRQs [ 1.201599] twl6040 0-004b: clk32k is not handled [ 1.205047] of_get_named_gpiod_flags: parsed 'ti,audpwron-gpio' property of node '/ocp/i2c@48070000/twl@4b[0]' - status (0) [ 1.207366] omap_i2c 48070000.i2c: bus 0 rev0.11 at 400 kHz [ 1.210357] omap_i2c 48072000.i2c: bus 1 rev0.11 at 400 kHz [ 1.212463] omap_i2c 48060000.i2c: bus 2 rev0.11 at 100 kHz [ 1.215057] omap_i2c 48350000.i2c: bus 3 rev0.11 at 400 kHz [ 1.215057] omap_hsmmc 4809c000.mmc: GPIO lookup for consumer cd [ 1.215698] omap_hsmmc 4809c000.mmc: using device tree for GPIO lookup [ 1.215698] of_get_named_gpiod_flags: can't parse 'cd-gpios' property of node '/ocp/mmc@4809c000[0]' [ 1.215728] of_get_named_gpiod_flags: can't parse 'cd-gpio' property of node '/ocp/mmc@4809c000[0]' [ 1.215728] omap_hsmmc 4809c000.mmc: using lookup tables for GPIO lookup [ 1.215759] omap_hsmmc 4809c000.mmc: lookup for GPIO cd failed [ 1.215820] omap_hsmmc 4809c000.mmc: GPIO lookup for consumer wp [ 1.215820] omap_hsmmc 4809c000.mmc: using device tree for GPIO lookup [ 1.215850] of_get_named_gpiod_flags: can't parse 'wp-gpios' property of node '/ocp/mmc@4809c000[0]' [ 1.215850] of_get_named_gpiod_flags: can't parse 'wp-gpio' property of node '/ocp/mmc@4809c000[0]' [ 1.215881] omap_hsmmc 4809c000.mmc: using lookup tables for GPIO lookup [ 1.215881] omap_hsmmc 4809c000.mmc: lookup for GPIO wp failed [ 1.216186] omap_hsmmc 4809c000.mmc: omap_device: omap_device_enable() called from invalid state 1 [ 1.282104] omap_hsmmc 480d5000.mmc: GPIO lookup for consumer wp [ 1.282104] omap_hsmmc 480d5000.mmc: using device tree for GPIO lookup [ 1.282165] of_get_named_gpiod_flags: can't parse 'wp-gpios' property of node '/ocp/mmc@480d5000[0]' [ 1.282196] of_get_named_gpiod_flags: can't parse 'wp-gpio' property of node '/ocp/mmc@480d5000[0]' [ 1.282196] omap_hsmmc 480d5000.mmc: using lookup tables for GPIO lookup [ 1.282226] omap_hsmmc 480d5000.mmc: lookup for GPIO wp failed [ 1.282592] omap_hsmmc 480d5000.mmc: omap_device: omap_device_enable() called from invalid state 1 [ 1.403289] hctosys: unable to open rtc device (rtc0) [ 1.403778] sr_init: No PMIC hook to init smartreflex [ 1.404144] smartreflex smartreflex.0: omap_sr_probe: SmartReflex driver initialized [ 1.404785] smartreflex smartreflex.1: omap_sr_probe: SmartReflex driver initialized [ 1.405303] smartreflex smartreflex.2: omap_sr_probe: SmartReflex driver initialized [ 1.452880] omap_hsmmc 480d5000.mmc: card claims to support voltages below defined range [ 1.453979] VDAC: disabling [ 1.454956] VANA: disabling [ 1.455902] VUSB: disabling [ 1.459991] Waiting for root device /dev/mmcblk0p5... [ 1.470062] mmc0: host does not support reading read-only switch, assuming write-enable [ 1.473083] mmc0: new high speed SDHC card at address e624 [ 1.474975] mmc1: new SDIO card at address 0001 [ 1.477569] mmcblk0: mmc0:e624 SE16G 14.8 GiB [ 1.491607] mmcblk0: p1 p2 < p5 p6 > [ 1.580749] EXT4-fs (mmcblk0p5): mounted filesystem with ordered data mode. Opts: (null) [ 1.580749] VFS: Mounted root (ext4 filesystem) readonly on device 179:5. [ 1.590820] devtmpfs: mounted [ 1.591949] Freeing unused kernel memory: 1024K (c0b00000 - c0c00000) [ 1.803192] random: systemd urandom read with 13 bits of entropy available [ 2.734191] systemd[1]: systemd-modules-load.service: main process exited, code=exited, status=1/FAILURE [ 2.791809] systemd[1]: Failed to start Load Kernel Modules. [ 2.798126] systemd[1]: Unit systemd-modules-load.service entered failed state. [ 3.156219] systemd-udevd[125]: starting version 215 [ 4.275451] twl_rtc 48070000.i2c:twl@48:rtc: Enabling TWL-RTC [ 4.335205] twl_rtc 48070000.i2c:twl@48:rtc: rtc core: registered 48070000.i2c:twl@48 as rtc0 [ 4.367614] of_get_named_gpiod_flags: parsed 'gpios' property of node '/ocp/bandgap[0]' - status (0) [ 4.516265] usbcore: registered new interface driver usbfs [ 4.516265] usbcore: registered new interface driver hub [ 4.517303] usbcore: registered new device driver usb [ 4.643615] HS USB OTG: no transceiver configured [ 4.649047] musb-hdrc musb-hdrc.0.auto: musb_init_controller failed with status -517 [ 4.677398] omap_wdt: OMAP Watchdog Timer Rev 0x01: initial timeout 60 sec [ 4.679351] omap4-keypad 4a31c000.keypad: number of keypad rows/columns not specified [ 4.679351] omap_hwmod: ocp2scp_usb_phy: _wait_target_disable failed [ 4.687927] omap4-keypad: probe of 4a31c000.keypad failed with error -22 [ 4.701110] musb-hdrc musb-hdrc.0.auto: MUSB HDRC host driver [ 4.702209] musb-hdrc musb-hdrc.0.auto: new USB bus registered, assigned bus number 1 [ 4.706909] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver [ 4.715728] ehci-omap: OMAP-EHCI Host Controller driver [ 4.722503] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002 [ 4.722503] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1 [ 4.722534] usb usb1: Product: MUSB HDRC host driver [ 4.722534] usb usb1: Manufacturer: Linux 4.5.0-rc5-armv7-x1-00001-gffdb57e musb-hcd [ 4.722564] usb usb1: SerialNumber: musb-hdrc.0.auto [ 4.731781] ehci-omap 4a064c00.ehci: EHCI Host Controller [ 4.742370] hub 1-0:1.0: USB hub found [ 4.743743] hub 1-0:1.0: 1 port detected [ 4.765441] ehci-omap 4a064c00.ehci: new USB bus registered, assigned bus number 2 [ 4.766906] ehci-omap 4a064c00.ehci: irq 336, io mem 0x4a064c00 [ 4.767913] ohci_hcd: USB 1.1 'Open' Host Controller (OHCI) Driver [ 4.772308] ohci-omap3: OHCI OMAP3 driver [ 4.784729] cpufreq: cpufreq_online: CPU0: Running at unlisted freq: 699977 KHz [ 4.785369] cpufreq: cpufreq_online: CPU0: Unlisted initial frequency changed to: 700000 KHz [ 4.792022] ehci-omap 4a064c00.ehci: USB 2.0 started, EHCI 1.00 [ 4.799041] usb usb2: New USB device found, idVendor=1d6b, idProduct=0002 [ 4.799102] usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1 [ 4.799133] usb usb2: Product: EHCI Host Controller [ 4.799133] usb usb2: Manufacturer: Linux 4.5.0-rc5-armv7-x1-00001-gffdb57e ehci_hcd [ 4.799163] usb usb2: SerialNumber: 4a064c00.ehci [ 4.809844] hub 2-0:1.0: USB hub found [ 4.811401] hub 2-0:1.0: 3 ports detected [ 4.817260] ohci-omap3 4a064800.ohci: OHCI Host Controller [ 4.817260] ohci-omap3 4a064800.ohci: new USB bus registered, assigned bus number 3 [ 4.817810] ohci-omap3 4a064800.ohci: irq 335, io mem 0x4a064800 [ 4.839324] of_get_named_gpiod_flags: parsed 'gpios' property of node '/leds/heartbeat[0]' - status (0) [ 4.839324] of_get_named_gpiod_flags: parsed 'gpios' property of node '/leds/mmc[0]' - status (0) [ 4.922210] usb usb3: New USB device found, idVendor=1d6b, idProduct=0001 [ 4.922271] usb usb3: New USB device strings: Mfr=3, Product=2, SerialNumber=1 [ 4.922271] usb usb3: Product: OHCI Host Controller [ 4.922302] usb usb3: Manufacturer: Linux 4.5.0-rc5-armv7-x1-00001-gffdb57e ohci_hcd [ 4.922302] usb usb3: SerialNumber: 4a064800.ohci [ 4.948394] hub 3-0:1.0: USB hub found [ 4.952056] hub 3-0:1.0: 3 ports detected [ 4.984863] omap-abe-twl6040 sound: twl6040-legacy <-> 40132000.mcpdm mapping ok [ 5.136840] usb 2-1: new high-speed USB device number 2 using ehci-omap [ 5.293243] usb 2-1: New USB device found, idVendor=0424, idProduct=9514 [ 5.293243] usb 2-1: New USB device strings: Mfr=0, Product=0, SerialNumber=0 [ 5.298767] hub 2-1:1.0: USB hub found [ 5.299499] hub 2-1:1.0: 5 ports detected [ 5.347503] EXT4-fs (mmcblk0p5): re-mounted. Opts: errors=remount-ro [ 5.624664] usb 2-1.1: new high-speed USB device number 3 using ehci-omap [ 5.732421] usb 2-1.1: New USB device found, idVendor=0424, idProduct=ec00 [ 5.732421] usb 2-1.1: New USB device strings: Mfr=0, Product=0, SerialNumber=0 [ 5.831756] usb 2-1.2: new high-speed USB device number 4 using ehci-omap [ 5.942352] usb 2-1.2: New USB device found, idVendor=0409, idProduct=005a [ 5.942413] usb 2-1.2: New USB device strings: Mfr=0, Product=0, SerialNumber=0 [ 5.945037] hub 2-1.2:1.0: USB hub found [ 5.945220] hub 2-1.2:1.0: 4 ports detected [ 6.135894] random: nonblocking pool is initialized [ 6.221710] usb 2-1.2.1: new low-speed USB device number 5 using ehci-omap [ 6.338348] usb 2-1.2.1: New USB device found, idVendor=0603, idProduct=0002 [ 6.338348] usb 2-1.2.1: New USB device strings: Mfr=1, Product=2, SerialNumber=0 [ 6.338439] usb 2-1.2.1: Product: USB Composite Device [ 6.338439] usb 2-1.2.1: Manufacturer: SINO WEALTH [ 6.606079] usbcore: registered new interface driver usbhid [ 6.606079] usbhid: USB HID core driver [ 6.614166] usbcore: registered new interface driver usbkbd [ 6.629364] systemd-journald[134]: Received request to flush runtime journal from PID 1 [ 6.636413] usbcore: registered new interface driver usbmouse [ 6.702484] input: SINO WEALTH USB Composite Device as /devices/platform/44000000.ocp/4a064000.usbhshost/4a064c00.ehci/usb2/2-1/2-1.2/2-1.2.1/2-1.2.1:1.0/0003:0603:0002.0001/input/input0 [ 6.752655] wlcore: loaded [ 6.761840] hid-generic 0003:0603:0002.0001: input: USB HID v1.10 Keyboard [SINO WEALTH USB Composite Device] on usb-4a064c00.ehci-1.2.1/input0 [ 6.763793] input: SINO WEALTH USB Composite Device as /devices/platform/44000000.ocp/4a064000.usbhshost/4a064c00.ehci/usb2/2-1/2-1.2/2-1.2.1/2-1.2.1:1.1/0003:0603:0002.0002/input/input1 [ 6.840820] hid-generic 0003:0603:0002.0002: input,hiddev0: USB HID v1.10 Mouse [SINO WEALTH USB Composite Device] on usb-4a064c00.ehci-1.2.1/input1 [ 7.657531] smsc95xx v1.0.4 [ 7.774017] smsc95xx 2-1.1:1.0 eth0: register 'smsc95xx' at usb-4a064c00.ehci-1.1, smsc95xx USB 2.0 Ethernet, 5e:f8:81:f6:c7:42 [ 7.774291] usbcore: registered new interface driver smsc95xx [ 11.320007] wlcore: firmware booted (Rev 6.3.10.0.133) [ 11.532196] smsc95xx 2-1.1:1.0 eth0: hardware isn't capable of remote wakeup [ 13.042724] smsc95xx 2-1.1:1.0 eth0: link up, 100Mbps, full-duplex, lpa 0xC5E1 [ 141.113433] wlcore: Scan completed due to error. [ 141.113433] ------------[ cut here ]------------ [ 141.113983] WARNING: CPU: 1 PID: 67 at drivers/net/wireless/ti/wlcore/main.c:787 wl12xx_queue_recovery_work.part.8+0x54/0x60 [wlcore]() [ 141.113983] Modules linked in: smsc95xx usbnet input_leds evdev arc4 hid_generic usbmouse usbkbd usbhid wl12xx wlcore mac80211 cfg80211 snd_soc_omap_abe_twl6040 omapfb snd_soc_twl6040 cfbfillrect cfbimgblt cfbcopyarea leds_gpio led_class cpufreq_dt ohci_omap3 omapdss ohci_hcd ehci_omap phy_omap_usb2 omap4_keypad ehci_hcd matrix_keymap omap_wdt omap2430 wlcore_sdio musb_hdrc udc_core usbcore ti_soc_thermal thermal_sys hwmon rtc_twl pwm_twl_led pwm_twl snd_soc_omap_mcpdm snd_soc_omap_mcbsp snd_soc_omap snd_soc_core snd_pcm_dmaengine snd_pcm snd_timer snd soundcore autofs4 [ 141.113983] CPU: 1 PID: 67 Comm: kworker/u4:1 Not tainted 4.5.0-rc5-armv7-x1-00001-gffdb57e #23 [ 141.114532] Hardware name: Generic OMAP4 (Flattened Device Tree) [ 141.114532] Workqueue: phy0 wl1271_scan_complete_work [wlcore] [ 141.114807] [<c0110cb4>] (unwind_backtrace) from [<c010cd28>] (show_stack+0x10/0x14) [ 141.114807] [<c010cd28>] (show_stack) from [<c0469060>] (dump_stack+0xb0/0xe4) [ 141.114807] [<c0469060>] (dump_stack) from [<c0136f54>] (warn_slowpath_common+0x78/0xb4) [ 141.115020] [<c0136f54>] (warn_slowpath_common) from [<c013702c>] (warn_slowpath_null+0x1c/0x24) [ 141.115020] [<c013702c>] (warn_slowpath_null) from [<bf499390>] (wl12xx_queue_recovery_work.part.8+0x54/0x60 [wlcore]) [ 141.115020] [<bf499390>] (wl12xx_queue_recovery_work.part.8 [wlcore]) from [<bf4ae728>] (wl1271_scan_complete_work+0x10c/0x120 [wlcore]) [ 141.115020] [<bf4ae728>] (wl1271_scan_complete_work [wlcore]) from [<c01515bc>] (process_one_work+0x1b0/0x528) [ 141.116180] [<c01515bc>] (process_one_work) from [<c015196c>] (worker_thread+0x38/0x4e8) [ 141.116180] [<c015196c>] (worker_thread) from [<c01577d8>] (kthread+0xd4/0xf0) [ 141.116180] [<c01577d8>] (kthread) from [<c0108510>] (ret_from_fork+0x14/0x24) [ 141.116180] ---[ end trace e7192270724dfef3 ]--- [ 141.118103] wlcore: Hardware recovery in progress. FW ver: Rev 6.3.10.0.133 [ 141.128051] wlcore: pc: 0x0, hint_sts: 0x00000020 count: 1 [ 141.129791] wlcore: down [ 141.136169] ieee80211 phy0: Hardware restart was requested [ 141.654876] wlcore: firmware booted (Rev 6.3.10.0.133) [ 194.072662] wlcore: Scan completed due to error. [ 194.072662] ------------[ cut here ]------------ [ 194.073242] WARNING: CPU: 1 PID: 67 at drivers/net/wireless/ti/wlcore/main.c:787 wl12xx_queue_recovery_work.part.8+0x54/0x60 [wlcore]() [ 194.073242] Modules linked in: smsc95xx usbnet input_leds evdev arc4 hid_generic usbmouse usbkbd usbhid wl12xx wlcore mac80211 cfg80211 snd_soc_omap_abe_twl6040 omapfb snd_soc_twl6040 cfbfillrect cfbimgblt cfbcopyarea leds_gpio led_class cpufreq_dt ohci_omap3 omapdss ohci_hcd ehci_omap phy_omap_usb2 omap4_keypad ehci_hcd matrix_keymap omap_wdt omap2430 wlcore_sdio musb_hdrc udc_core usbcore ti_soc_thermal thermal_sys hwmon rtc_twl pwm_twl_led pwm_twl snd_soc_omap_mcpdm snd_soc_omap_mcbsp snd_soc_omap snd_soc_core snd_pcm_dmaengine snd_pcm snd_timer snd soundcore autofs4 [ 194.073242] CPU: 1 PID: 67 Comm: kworker/u4:1 Tainted: G W 4.5.0-rc5-armv7-x1-00001-gffdb57e #23 [ 194.073791] Hardware name: Generic OMAP4 (Flattened Device Tree) [ 194.074066] Workqueue: phy0 wl1271_scan_complete_work [wlcore] [ 194.074066] [<c0110cb4>] (unwind_backtrace) from [<c010cd28>] (show_stack+0x10/0x14) [ 194.074066] [<c010cd28>] (show_stack) from [<c0469060>] (dump_stack+0xb0/0xe4) [ 194.074249] [<c0469060>] (dump_stack) from [<c0136f54>] (warn_slowpath_common+0x78/0xb4) [ 194.074249] [<c0136f54>] (warn_slowpath_common) from [<c013702c>] (warn_slowpath_null+0x1c/0x24) [ 194.074249] [<c013702c>] (warn_slowpath_null) from [<bf499390>] (wl12xx_queue_recovery_work.part.8+0x54/0x60 [wlcore]) [ 194.075012] [<bf499390>] (wl12xx_queue_recovery_work.part.8 [wlcore]) from [<bf4ae728>] (wl1271_scan_complete_work+0x10c/0x120 [wlcore]) [ 194.075286] [<bf4ae728>] (wl1271_scan_complete_work [wlcore]) from [<c01515bc>] (process_one_work+0x1b0/0x528) [ 194.075286] [<c01515bc>] (process_one_work) from [<c015196c>] (worker_thread+0x38/0x4e8) [ 194.075378] [<c015196c>] (worker_thread) from [<c01577d8>] (kthread+0xd4/0xf0) [ 194.075378] [<c01577d8>] (kthread) from [<c0108510>] (ret_from_fork+0x14/0x24) [ 194.075439] ---[ end trace e7192270724dfef4 ]--- [ 194.075653] wlcore: Hardware recovery in progress. FW ver: Rev 6.3.10.0.133 [ 194.079010] wlcore: pc: 0x0, hint_sts: 0x00000020 count: 2 [ 194.080413] wlcore: down [ 194.085205] ieee80211 phy0: Hardware restart was requested [ 194.623474] wlcore: firmware booted (Rev 6.3.10.0.133) ^ permalink raw reply [flat|nested] 10+ messages in thread
* Re: wlcore: Fix regression in wlcore_set_partition() 2016-02-25 0:11 ` Emil Goode @ 2016-02-25 2:13 ` Ross Green 2016-02-25 4:56 ` Ross Green 0 siblings, 1 reply; 10+ messages in thread From: Ross Green @ 2016-02-25 2:13 UTC (permalink / raw) To: Emil Goode Cc: Kalle Valo, Shahar Patury, Guy Mishol, Igor Grinberg, Uri Mashiach, linux-wireless, netdev, lkml On Thu, Feb 25, 2016 at 11:11 AM, Emil Goode <emil.fsw@goode.io> wrote: > Hello Ross > > On Wed, Feb 24, 2016 at 04:40:50PM +1100, Ross Green wrote: >> On Wed, Feb 17, 2016 at 4:34 PM, Ross Green <rgkernel@gmail.com> wrote: >> > Appreciate your efforts! >> > >> > Just trying to make sure it does not get lost. >> > Introduced in rc1, not fixed by ... rc4. >> > >> > Anyway, I will continue to test, lots of other things still to chase >> > even in rc4! >> > >> > Regards, >> > >> > Ross Green >> > >> > On Wed, Feb 17, 2016 at 2:24 AM, Kalle Valo <kvalo@codeaurora.org> wrote: >> >> Ross Green <rgkernel@gmail.com> writes: >> >> >> >>> On Fri, Feb 12, 2016 at 8:45 PM, Kalle Valo <kvalo@codeaurora.org> wrote: >> >>>> >> >>>>> The commit 3719c17e1816 ("wlcore/wl18xx: fw logger over sdio") introduced a >> >>>>> regression causing the wlcore to time out and go into recovery. Reverting the >> >>>>> changes regarding write of the last partition size brings the module back to >> >>>>> it's functional state. >> >>>>> >> >>>>> Fixes: 3719c17e1816 ("wlcore/wl18xx: fw logger over sdio") >> >>>>> Reported-by: Ross Green <rgkernel@gmail.com> >> >>>>> Signed-off-by: Emil Goode <emil.fsw@goode.io> >> >>>>> [kvalo@codeaurora.org: improved commit log] >> >>>> >> >>>> Thanks, applied to wireless-drivers.git. >> >>>> >> >>>> Kalle Valo >> >>> >> >>> I just tested linux-4.5-rc4 it appears the above fix missed the release for rc4! >> >>> So the behaviour of firmware reset being called after the access of >> >>> the last partition timesout. >> >>> >> >>> Again tested patch with the new release - 4.5-rc4 and found everything >> >>> to work as expected again. >> >>> >> >>> So Hopefully for rc5 - Please! >> >> >> >> It takes some time to get patches into Linus' tree. And being in a >> >> conference and then getting sick is not really helping. I'm not sure if >> >> this patch makes to rc5 on time, but I'll try. >> >> >> >> -- >> >> Kalle Valo >> >> G'day all, >> >> I have tested Emil's patch with each 4.5-rc release. >> >> Seems to work fine with rc2, rc3, rc4. >> I tried it with rc5 and get the following output from dmesg see attachment. >> >> So it looks like there is a reset that it recovers from and then proceeds OK. >> >> I see the patch has been queued by David Miller so it might make it into rc6. >> That will be great. It still does not look quite as clean as it should >> be however, given the noise in the dmesg output from rc5 >> >> Regards, >> >> >> Ross Green > > I'm unable to reproduce that ELP wakeup timeout with v4.5-rc5 on my pandaboard es. > Can you easily reproduce it and are you able to reproduce it with commit 3719c17e1816 reverted? > > However, I'm seeing another bug that occurs when the wlan is not configured to connect to > an AP directly after boot (dmesg attached). It is not related to any recent changes and goes back > before the 3.14 kernel. There seem to be an issue with the looped IRQ handling implementation. > A scan on 2GHZ is performed and the wlcore_fw_status() call in wlcore_irq_locked() returns > WL1271_ACX_INTR_HW_AVAILABLE, then nothing seem to happen until the delayed work queue > scan_complete_work starts executing after the 30 sec timeout runs out and the wlcore goes into > recovery. I guess a new scan should be initiated after wlcore_fw_status() return hw available, > does anyone have input on that? > > Best regards, > > Emil Goode Sorry Emil had not tried a reboot since getting that message. The wifi module did recover and associate properly after that. So I just kept on using the system. Trying to track down another problem that takes "AGES" before it shows up so just kept the system running. Hoping to get some debug regarding and RCU problem. I will check it again later when i get a chance to reboot. At least with your patch in place the system will keep functioning. Plus I had not noticed any problems running rc2, rc3, rc4 with your patch in place. Everything behaved pre the 4.5 changes. I'll get back to you with what I find. It does look however that there might be some other problems sitting there that possibly the new changes show up more. Some slightly different timing situations. Regards, Ross Green ^ permalink raw reply [flat|nested] 10+ messages in thread
* Re: wlcore: Fix regression in wlcore_set_partition() 2016-02-25 2:13 ` Ross Green @ 2016-02-25 4:56 ` Ross Green 0 siblings, 0 replies; 10+ messages in thread From: Ross Green @ 2016-02-25 4:56 UTC (permalink / raw) To: Emil Goode Cc: Kalle Valo, Shahar Patury, Guy Mishol, Igor Grinberg, Uri Mashiach, linux-wireless, netdev, lkml [-- Attachment #1: Type: text/plain, Size: 5052 bytes --] On Thu, Feb 25, 2016 at 1:13 PM, Ross Green <rgkernel@gmail.com> wrote: > On Thu, Feb 25, 2016 at 11:11 AM, Emil Goode <emil.fsw@goode.io> wrote: >> Hello Ross >> >> On Wed, Feb 24, 2016 at 04:40:50PM +1100, Ross Green wrote: >>> On Wed, Feb 17, 2016 at 4:34 PM, Ross Green <rgkernel@gmail.com> wrote: >>> > Appreciate your efforts! >>> > >>> > Just trying to make sure it does not get lost. >>> > Introduced in rc1, not fixed by ... rc4. >>> > >>> > Anyway, I will continue to test, lots of other things still to chase >>> > even in rc4! >>> > >>> > Regards, >>> > >>> > Ross Green >>> > >>> > On Wed, Feb 17, 2016 at 2:24 AM, Kalle Valo <kvalo@codeaurora.org> wrote: >>> >> Ross Green <rgkernel@gmail.com> writes: >>> >> >>> >>> On Fri, Feb 12, 2016 at 8:45 PM, Kalle Valo <kvalo@codeaurora.org> wrote: >>> >>>> >>> >>>>> The commit 3719c17e1816 ("wlcore/wl18xx: fw logger over sdio") introduced a >>> >>>>> regression causing the wlcore to time out and go into recovery. Reverting the >>> >>>>> changes regarding write of the last partition size brings the module back to >>> >>>>> it's functional state. >>> >>>>> >>> >>>>> Fixes: 3719c17e1816 ("wlcore/wl18xx: fw logger over sdio") >>> >>>>> Reported-by: Ross Green <rgkernel@gmail.com> >>> >>>>> Signed-off-by: Emil Goode <emil.fsw@goode.io> >>> >>>>> [kvalo@codeaurora.org: improved commit log] >>> >>>> >>> >>>> Thanks, applied to wireless-drivers.git. >>> >>>> >>> >>>> Kalle Valo >>> >>> >>> >>> I just tested linux-4.5-rc4 it appears the above fix missed the release for rc4! >>> >>> So the behaviour of firmware reset being called after the access of >>> >>> the last partition timesout. >>> >>> >>> >>> Again tested patch with the new release - 4.5-rc4 and found everything >>> >>> to work as expected again. >>> >>> >>> >>> So Hopefully for rc5 - Please! >>> >> >>> >> It takes some time to get patches into Linus' tree. And being in a >>> >> conference and then getting sick is not really helping. I'm not sure if >>> >> this patch makes to rc5 on time, but I'll try. >>> >> >>> >> -- >>> >> Kalle Valo >>> >>> G'day all, >>> >>> I have tested Emil's patch with each 4.5-rc release. >>> >>> Seems to work fine with rc2, rc3, rc4. >>> I tried it with rc5 and get the following output from dmesg see attachment. >>> >>> So it looks like there is a reset that it recovers from and then proceeds OK. >>> >>> I see the patch has been queued by David Miller so it might make it into rc6. >>> That will be great. It still does not look quite as clean as it should >>> be however, given the noise in the dmesg output from rc5 >>> >>> Regards, >>> >>> >>> Ross Green >> >> I'm unable to reproduce that ELP wakeup timeout with v4.5-rc5 on my pandaboard es. >> Can you easily reproduce it and are you able to reproduce it with commit 3719c17e1816 reverted? >> >> However, I'm seeing another bug that occurs when the wlan is not configured to connect to >> an AP directly after boot (dmesg attached). It is not related to any recent changes and goes back >> before the 3.14 kernel. There seem to be an issue with the looped IRQ handling implementation. >> A scan on 2GHZ is performed and the wlcore_fw_status() call in wlcore_irq_locked() returns >> WL1271_ACX_INTR_HW_AVAILABLE, then nothing seem to happen until the delayed work queue >> scan_complete_work starts executing after the 30 sec timeout runs out and the wlcore goes into >> recovery. I guess a new scan should be initiated after wlcore_fw_status() return hw available, >> does anyone have input on that? >> >> Best regards, >> >> Emil Goode > > > Sorry Emil had not tried a reboot since getting that message. The wifi > module did recover and associate properly after that. So I just kept > on using the system. > > Trying to track down another problem that takes "AGES" before it shows > up so just kept the system running. Hoping to get some debug regarding > and RCU problem. > > I will check it again later when i get a chance to reboot. > > At least with your patch in place the system will keep functioning. > Plus I had not noticed any problems running rc2, rc3, rc4 with your > patch in place. > Everything behaved pre the 4.5 changes. > > I'll get back to you with what I find. It does look however that there > might be some other problems sitting there that possibly the new > changes show up more. Some slightly different timing situations. > > Regards, > > Ross Green G'day Emil, Just tried a reboot of the pandaboard es with the patches applied to the 4.5-rc5. Everything worked fine!!! So it might be a strange timing thing that happens "sometimes". the wl processor is running its own software from the firmware downloaded, there might be times when it accessed too soon. So there are still a few things to watch with the changes that have been been added during 4.5. I had assumed that this driver had been fairly stable for some time, but there might have always been a few problems lurking. attached is a small section of the dmesg output, showing good working order for the wl driver. Regards, Ross Green [-- Attachment #2: dmesg-4.5-rc5-wlcore-2 --] [-- Type: application/octet-stream, Size: 483 bytes --] [ 57.245941] wlcore: loaded [ 71.707397] wlcore: firmware booted (Rev 6.3.10.0.133) [ 75.277832] wlan0: authenticate with 90:f6:52:81:ee:70 [ 75.310668] wlan0: send auth to 90:f6:52:81:ee:70 (try 1/3) [ 75.318817] wlan0: authenticated [ 75.329986] wlan0: associate with 90:f6:52:81:ee:70 (try 1/3) [ 75.340728] wlan0: RX AssocResp from 90:f6:52:81:ee:70 (capab=0x431 status=0 aid=1) [ 75.356048] wlan0: associated [ 75.413482] wlcore: Association completed. # ^ permalink raw reply [flat|nested] 10+ messages in thread
end of thread, other threads:[~2016-02-25 4:56 UTC | newest] Thread overview: 10+ messages (download: mbox.gz follow: Atom feed -- links below jump to the message on this page -- 2016-02-10 1:22 [PATCH] wlcore: Fix regression in wlcore_set_partition() Emil Goode 2016-02-10 17:34 ` Kalle Valo 2016-02-12 9:45 ` Kalle Valo 2016-02-15 4:01 ` Ross Green 2016-02-16 15:24 ` Kalle Valo 2016-02-17 5:34 ` Ross Green 2016-02-24 5:40 ` Ross Green 2016-02-25 0:11 ` Emil Goode 2016-02-25 2:13 ` Ross Green 2016-02-25 4:56 ` Ross Green
This is a public inbox, see mirroring instructions for how to clone and mirror all data and code used for this inbox; as well as URLs for NNTP newsgroup(s).