4.6-rc5 on arm (nokia n900): spinlock bad magic on CPU#0,

4.6-rc5 on arm (nokia n900): spinlock bad magic on CPU#0,

[Pavel Machek]

Hi!

First time such thing happened, normally N900 is very stable.

Any ideas?

							Pavel

[ 6274.123779] DSS: context saved
[ 6410.643981] BUG: spinlock bad magic on CPU#0, kworker/u2:1/18947
[ 6410.644409]  lock: 0xce648f40, .magic: 00000000, .owner: <none>/-1,
.owner_cpu: 0
[ 6410.644836] CPU: 0 PID: 18947 Comm: kworker/u2:1 Not tainted
4.6.0-rc5-176732-g177b741-dirty #243
[ 6410.647949] Hardware name: Nokia RX-51 board
[ 6410.650909] Workqueue: phy1 wl1251_irq_work
[ 6410.653869] [<c010eebc>] (unwind_backtrace) from [<c010b150>]
(show_stack+0x10/0x14)
[ 6410.657135] [<c010b150>] (show_stack) from [<c04232b0>]
(dump_stack+0x8c/0xac)
[ 6410.660583] [<c04232b0>] (dump_stack) from [<c017cf78>]
(do_raw_spin_lock+0x198/0x1b8)
[ 6410.664245] [<c017cf78>] (do_raw_spin_lock) from [<c08393f8>]
(_raw_spin_lock_irqsave+0x10/0x18)
[ 6410.668243] [<c08393f8>] (_raw_spin_lock_irqsave) from [<c05aaf20>]
(wl1251_op_tx+0x38/0x5c)
[ 6410.672485] [<c05aaf20>] (wl1251_op_tx) from [<c0802dec>]
(ieee80211_tx_frags+0xd0/0x3d4)
[ 6410.677001] [<c0802dec>] (ieee80211_tx_frags) from [<c080315c>]
(__ieee80211_tx+0x6c/0x134)
[ 6410.681793] [<c080315c>] (__ieee80211_tx) from [<c0804698>]
(ieee80211_tx+0xb4/0xdc)
[ 6410.686828] [<c0804698>] (ieee80211_tx) from [<c080539c>]
(__ieee80211_subif_start_xmit+0x540/0x6d0)
[ 6410.697448] [<c080539c>] (__ieee80211_subif_start_xmit) from
[<c0805538>] (ieee80211_subif_start_xmit+0xc/0x14)
[ 6410.709625] [<c0805538>] (ieee80211_subif_start_xmit) from
[<c06905a4>] (dev_hard_start_xmit+0x228/0x440)
[ 6410.723205] [<c06905a4>] (dev_hard_start_xmit) from [<c06b0754>]
(sch_direct_xmit+0xf4/0x220)
[ 6410.730712] [<c06b0754>] (sch_direct_xmit) from [<c0690b64>]
(__dev_queue_xmit+0x25c/0x62c)
[ 6410.738494] [<c0690b64>] (__dev_queue_xmit) from [<c06c2738>]
(ip_finish_output2+0x1b8/0x3d0)
[ 6410.746551] [<c06c2738>] (ip_finish_output2) from [<c06c380c>]
(ip_output+0x130/0x144)
[ 6410.754852] [<c06c380c>] (ip_output) from [<c06c2eb8>]
(ip_local_out+0x38/0x3c)
[ 6410.763366] [<c06c2eb8>] (ip_local_out) from [<c06db674>]
(tcp_transmit_skb+0x510/0x984)
[ 6410.772003] [<c06db674>] (tcp_transmit_skb) from [<c06d8300>]
(tcp_rcv_established+0x360/0x728)
[ 6410.780822] [<c06d8300>] (tcp_rcv_established) from [<c06e1088>]
(tcp_v4_do_rcv+0x14c/0x1b0)
[ 6410.789764] [<c06e1088>] (tcp_v4_do_rcv) from [<c06e3698>]
(tcp_v4_rcv+0xc3c/0xc58)
[ 6410.798919] [<c06e3698>] (tcp_v4_rcv) from [<c06bd9bc>]
(ip_local_deliver_finish+0x5c/0x29c)
[ 6410.808197] [<c06bd9bc>] (ip_local_deliver_finish) from
[<c06bddf4>] (ip_local_deliver+0xd0/0xd8)
[ 6410.817565] [<c06bddf4>] (ip_local_deliver) from [<c06bd6f8>]
(ip_rcv_finish+0x1f0/0x458)
[ 6410.826873] [<c06bd6f8>] (ip_rcv_finish) from [<c06be198>]
(ip_rcv+0x39c/0x56c)
[ 6410.836059] [<c06be198>] (ip_rcv) from [<c0688934>]
(__netif_receive_skb_core+0x2e8/0x958)
[ 6410.845214] [<c0688934>] (__netif_receive_skb_core) from
[<c068d1b0>] (netif_receive_skb_internal+0x30/0xa0)
[ 6410.863647] [<c068d1b0>] (netif_receive_skb_internal) from
[<c07fcaf4>] (ieee80211_deliver_skb+0x184/0x1bc)
[ 6410.882598] [<c07fcaf4>] (ieee80211_deliver_skb) from [<c07fdd44>]
(ieee80211_rx_handlers+0xe9c/0x201c)
[ 6410.901916] [<c07fdd44>] (ieee80211_rx_handlers) from [<c07ff07c>]
(ieee80211_prepare_and_rx_handle+0x1b8/0x9b4)
[ 6410.921600] [<c07ff07c>] (ieee80211_prepare_and_rx_handle) from
[<c07ffef8>] (ieee80211_rx_napi+0x680/0x7f0)
[ 6410.941650] [<c07ffef8>] (ieee80211_rx_napi) from [<c05ad0c0>]
(wl1251_rx+0x324/0x49c)
[ 6410.951843] [<c05ad0c0>] (wl1251_rx) from [<c05ab660>]
(wl1251_irq_work+0x154/0x1b0)
[ 6410.961944] [<c05ab660>] (wl1251_irq_work) from [<c014ce64>]
(process_one_work+0x130/0x428)
[ 6410.971923] [<c014ce64>] (process_one_work) from [<c014d2f0>]
(worker_thread+0x15c/0x490)
[ 6410.981842] [<c014d2f0>] (worker_thread) from [<c01522f4>]
(kthread+0xd4/0xf0)
[ 6410.991577] [<c01522f4>] (kthread) from [<c0107918>]
(ret_from_fork+0x14/0x3c)
[ 6417.062316] DISPC: dispc_runtime_get
[ 6417.062408] DSS: dss_restore_context
[ 6417.062408] DSS: context restored

-- 
(english) http://www.livejournal.com/~pavelmachek
(cesky, pictures) http://atrey.karlin.mff.cuni.cz/~pavel/picture/horses/blog.html

4.6-rc5 on arm (nokia n900): spinlock bad magic on CPU#0,

[Pavel Machek]

Hi!

> First time such thing happened, normally N900 is very stable.

Second time, within a day. It seems rather repeatable. I tested 4.4
rather heavily, and seen any backtraces... 

Oh, and some saboteur seemed to turn on ipv6 on my wifi. Probably
unrelated....

Best regards,
								Pavel


[  157.867431] DSS: dss_save_context
[  157.867462] DSS: context saved
[  322.248291] BUG: spinlock bad magic on CPU#0, kworker/u2:3/2693
[  322.248687]  lock: 0xce670f40, .magic: 00000000, .owner: <none>/-1,
.owner_cpu: 0
[  322.249114] CPU: 0 PID: 2693 Comm: kworker/u2:3 Not tainted
4.6.0-rc5-176733-g8b658a3-dirty #244
[  322.249603] Hardware name: Nokia RX-51 board
[  322.249877] Workqueue: phy1 wl1251_irq_work
[  322.250183] [<c010eebc>] (unwind_backtrace) from [<c010b150>]
(show_stack+0x10/0x14)
[  322.250640] [<c010b150>] (show_stack) from [<c04232b0>]
(dump_stack+0x8c/0xac)
[  322.251068] [<c04232b0>] (dump_stack) from [<c017cf78>]
(do_raw_spin_lock+0x198/0x1b8)
[  322.251495] [<c017cf78>] (do_raw_spin_lock) from [<c084ed98>]
(_raw_spin_lock_irqsave+0x10/0x18)
[  322.251922] [<c084ed98>] (_raw_spin_lock_irqsave) from [<c05aaf20>]
(wl1251_op_tx+0x38/0x5c)
[  322.252380] [<c05aaf20>] (wl1251_op_tx) from [<c0818788>]
(ieee80211_tx_frags+0xd0/0x3d4)
[  322.252807] [<c0818788>] (ieee80211_tx_frags) from [<c0818af8>]
(__ieee80211_tx+0x6c/0x134)
[  322.253234] [<c0818af8>] (__ieee80211_tx) from [<c081a034>]
(ieee80211_tx+0xb4/0xdc)
[  322.253631] [<c081a034>] (ieee80211_tx) from [<c081ad38>]
(__ieee80211_subif_start_xmit+0x540/0x6d0)
[  322.254089] [<c081ad38>] (__ieee80211_subif_start_xmit) from
[<c081aed4>] (ieee80211_subif_start_xmit+0xc/0x14)
[  322.254608] [<c081aed4>] (ieee80211_subif_start_xmit) from
[<c06a5f40>] (dev_hard_start_xmit+0x228/0x440)
[  322.255096] [<c06a5f40>] (dev_hard_start_xmit) from [<c06c60f0>]
(sch_direct_xmit+0xf4/0x220)
[  322.255523] [<c06c60f0>] (sch_direct_xmit) from [<c06a6500>]
(__dev_queue_xmit+0x25c/0x62c)
[  322.255981] [<c06a6500>] (__dev_queue_xmit) from [<c06d80bc>]
(ip_finish_output2+0x1a0/0x3d0)
[  322.256408] [<c06d80bc>] (ip_finish_output2) from [<c06d91a8>]
(ip_output+0x130/0x144)
[  322.256805] [<c06d91a8>] (ip_output) from [<c06d8854>]
(ip_local_out+0x38/0x3c)
[  322.257202] [<c06d8854>] (ip_local_out) from [<c06f1010>]
(tcp_transmit_skb+0x510/0x984)
[  322.257598] [<c06f1010>] (tcp_transmit_skb) from [<c06edc9c>]
(tcp_rcv_established+0x360/0x728)
[  322.258056] [<c06edc9c>] (tcp_rcv_established) from [<c06f6a24>]
(tcp_v4_do_rcv+0x14c/0x1b0)
[  322.258483] [<c06f6a24>] (tcp_v4_do_rcv) from [<c06f9034>]
(tcp_v4_rcv+0xc3c/0xc58)
[  322.258880] [<c06f9034>] (tcp_v4_rcv) from [<c06d3358>]
(ip_local_deliver_finish+0x5c/0x29c)
[  322.268096] [<c06d3358>] (ip_local_deliver_finish) from
[<c06d3790>] (ip_local_deliver+0xd0/0xd8)
[  322.277374] [<c06d3790>] (ip_local_deliver) from [<c06d3094>]
(ip_rcv_finish+0x1f0/0x458)
[  322.286590] [<c06d3094>] (ip_rcv_finish) from [<c06d3b34>]
(ip_rcv+0x39c/0x56c)
[  322.295715] [<c06d3b34>] (ip_rcv) from [<c069e2d0>]
(__netif_receive_skb_core+0x2e8/0x958)
[  322.304809] [<c069e2d0>] (__netif_receive_skb_core) from
[<c06a2b4c>] (netif_receive_skb_internal+0x30/0xa0)
[  322.323059] [<c06a2b4c>] (netif_receive_skb_internal) from
[<c0812490>] (ieee80211_deliver_skb+0x184/0x1bc)
[  322.341827] [<c0812490>] (ieee80211_deliver_skb) from [<c08136e0>]
(ieee80211_rx_handlers+0xe9c/0x201c)
[  322.360961] [<c08136e0>] (ieee80211_rx_handlers) from [<c0814a18>]
(ieee80211_prepare_and_rx_handle+0x1b8/0x9b4)
[  322.380493] [<c0814a18>] (ieee80211_prepare_and_rx_handle) from
[<c0815894>] (ieee80211_rx_napi+0x680/0x7f0)
[  322.400390] [<c0815894>] (ieee80211_rx_napi) from [<c05ad0c0>]
(wl1251_rx+0x324/0x49c)
[  322.410522] [<c05ad0c0>] (wl1251_rx) from [<c05ab660>]
(wl1251_irq_work+0x154/0x1b0)
[  322.420532] [<c05ab660>] (wl1251_irq_work) from [<c014ce64>]
(process_one_work+0x130/0x428)
[  322.430450] [<c014ce64>] (process_one_work) from [<c014d2f0>]
(worker_thread+0x15c/0x490)
[  322.440277] [<c014d2f0>] (worker_thread) from [<c01522f4>]
(kthread+0xd4/0xf0)
[  322.449951] [<c01522f4>] (kthread) from [<c0107918>]
(ret_from_fork+0x14/0x3c)
pavel at n900:/my$


-- 
(english) http://www.livejournal.com/~pavelmachek
(cesky, pictures) http://atrey.karlin.mff.cuni.cz/~pavel/picture/horses/blog.html

4.6-rc5 on arm (nokia n900): spinlock bad magic on CPU#0,

[Pavel Machek]

On Mon 2016-04-25 16:59:33, Pavel Machek wrote:
> Hi!
> 
> > First time such thing happened, normally N900 is very stable.
> 
> Second time, within a day. It seems rather repeatable. I tested 4.4
> rather heavily, and seen any backtraces... 
> 
> Oh, and some saboteur seemed to turn on ipv6 on my wifi. Probably
> unrelated....

Happened again today. Definitely a regression from 4.4...

									Pavel

[  266.151550] BUG: spinlock bad magic on CPU#0, kworker/u2:0/6
[  266.151611]  lock: 0xcd644f40, .magic: 00000000, .owner: <none>/-1,
.owner_cpu: 0
[  266.151641] CPU: 0 PID: 6 Comm: kworker/u2:0 Tainted: G        W
4.6.0-rc5-176860-gd6fbb13-dirty #251
[  266.151672] Hardware name: Nokia RX-51 board
[  266.151702] Workqueue: phy1 wl1251_irq_work
[  266.151794] [<c010eebc>] (unwind_backtrace) from [<c010b150>]
(show_stack+0x10/0x14)
[  266.151824] [<c010b150>] (show_stack) from [<c0423a90>]
(dump_stack+0x8c/0xac)
[  266.151855] [<c0423a90>] (dump_stack) from [<c017d770>]
(do_raw_spin_lock+0x198/0x1b8)
[  266.151916] [<c017d770>] (do_raw_spin_lock) from [<c0834078>]
(_raw_spin_lock_irqsave+0x10/0x18)
[  266.151947] [<c0834078>] (_raw_spin_lock_irqsave) from [<c05a9bec>]
(wl1251_op_tx+0x38/0x5c)
[  266.151977] [<c05a9bec>] (wl1251_op_tx) from [<c07fda44>]
(ieee80211_tx_frags+0xd0/0x3d4)
[  266.152008] [<c07fda44>] (ieee80211_tx_frags) from [<c07fddb4>]
(__ieee80211_tx+0x6c/0x134)
[  266.152069] [<c07fddb4>] (__ieee80211_tx) from [<c07ff2f0>]
(ieee80211_tx+0xb4/0xdc)
[  266.152099] [<c07ff2f0>] (ieee80211_tx) from [<c0800018>]
(__ieee80211_subif_start_xmit+0x540/0x6d0)
[  266.152130] [<c0800018>] (__ieee80211_subif_start_xmit) from
[<c08001b4>] (ieee80211_subif_start_xmit+0xc/0x14)
[  266.152160] [<c08001b4>] (ieee80211_subif_start_xmit) from
[<c068c394>] (dev_hard_start_xmit+0x228/0x440)
[  266.152191] [<c068c394>] (dev_hard_start_xmit) from [<c06ac500>]
(sch_direct_xmit+0xf4/0x220)
[  266.152221] [<c06ac500>] (sch_direct_xmit) from [<c068c954>]
(__dev_queue_xmit+0x25c/0x62c)
[  266.152252] [<c068c954>] (__dev_queue_xmit) from [<c06be4cc>]
(ip_finish_output2+0x1a0/0x3d0)
[  266.152282] [<c06be4cc>] (ip_finish_output2) from [<c06bf5b8>]
(ip_output+0x130/0x144)
[  266.152313] [<c06bf5b8>] (ip_output) from [<c06bec64>]
(ip_local_out+0x38/0x3c)
[  266.152343] [<c06bec64>] (ip_local_out) from [<c06d7420>]
(tcp_transmit_skb+0x510/0x984)
[  266.152404] [<c06d7420>] (tcp_transmit_skb) from [<c06d40ac>]
(tcp_rcv_established+0x360/0x728)
[  266.152435] [<c06d40ac>] (tcp_rcv_established) from [<c06dce34>]
(tcp_v4_do_rcv+0x14c/0x1b0)
[  266.152465] [<c06dce34>] (tcp_v4_do_rcv) from [<c06df444>]
(tcp_v4_rcv+0xc3c/0xc58)
[  266.152496] [<c06df444>] (tcp_v4_rcv) from [<c06b9768>]
(ip_local_deliver_finish+0x5c/0x29c)
[  266.152526] [<c06b9768>] (ip_local_deliver_finish) from
[<c06b9ba0>] (ip_local_deliver+0xd0/0xd8)
[  266.152557] [<c06b9ba0>] (ip_local_deliver) from [<c06b94a4>]
(ip_rcv_finish+0x1f0/0x458)
[  266.152587] [<c06b94a4>] (ip_rcv_finish) from [<c06b9f44>]
(ip_rcv+0x39c/0x56c)
[  266.152618] [<c06b9f44>] (ip_rcv) from [<c0684724>]
(__netif_receive_skb_core+0x2e8/0x958)
[  266.152648] [<c0684724>] (__netif_receive_skb_core) from
[<c0688fa0>] (netif_receive_skb_internal+0x30/0xa0)
[  266.152679] [<c0688fa0>] (netif_receive_skb_internal) from
[<c07f7750>] (ieee80211_deliver_skb+0x184/0x1bc)
[  266.152709] [<c07f7750>] (ieee80211_deliver_skb) from [<c07f89a0>]
(ieee80211_rx_handlers+0xe9c/0x201c)
[  266.152740] [<c07f89a0>] (ieee80211_rx_handlers) from [<c07f9cd8>]
(ieee80211_prepare_and_rx_handle+0x1b8/0x9b4)
[  266.152770] [<c07f9cd8>] (ieee80211_prepare_and_rx_handle) from
[<c07fab54>] (ieee80211_rx_napi+0x680/0x7f0)
[  266.152832] [<c07fab54>] (ieee80211_rx_napi) from [<c05abf08>]
(wl1251_rx+0x324/0x49c)
[  266.152862] [<c05abf08>] (wl1251_rx) from [<c05aa320>]
(wl1251_irq_work+0x144/0x1b0)
[  266.152893] [<c05aa320>] (wl1251_irq_work) from [<c014d65c>]
(process_one_work+0x130/0x428)
[  266.152923] [<c014d65c>] (process_one_work) from [<c014dae8>]
(worker_thread+0x15c/0x490)
[  266.152954] [<c014dae8>] (worker_thread) from [<c0152aec>]
(kthread+0xd4/0xf0)
[  266.152984] [<c0152aec>] (kthread) from [<c0107918>]
(ret_from_fork+0x14/0x3c)


-- 
(english) http://www.livejournal.com/~pavelmachek
(cesky, pictures) http://atrey.karlin.mff.cuni.cz/~pavel/picture/horses/blog.html