Random spinlock at ft-11aa branch

[jlopex]

Running the test-041-gcr.sh on hwsim_test

Nov 26 04:05:14 garfield kernel: [ 42.318549] ieee80211 phy0: Received ROBUST action Nov 26 04:05:14 garfield kernel: [ 42.318602] ieee80211 phy1: mac80211_hwsim_bss_info_changed(changed=0x100) Nov 26 04:05:14 garfield kernel: [ 42.318700] ieee80211 phy1: Received ROBUST action Nov 26 04:05:14 garfield kernel: [ 42.318743] 11aa (mesh1): 42:00:00:00:00:00 has GCM enabled Nov 26 04:05:14 garfield kernel: [ 42.320098] 11aa (mesh1): ieee80211aa_init_struct 42:00:00:00:00:00 Nov 26 04:05:14 garfield kernel: [ 42.362053] 11aa (mesh1): Sent BAR to 42:00:00:00:00:00 with Ws=896 Nov 26 04:05:14 garfield kernel: [ 42.362129] 11aa (mesh0): BAR received with new window_start 896 previous was:0 Nov 26 04:05:14 garfield kernel: [ 42.362138] Nov 26 04:05:14 garfield kernel: [ 42.362140] ================================= Nov 26 04:05:14 garfield kernel: [ 42.362141] [ INFO: inconsistent lock state ] Nov 26 04:05:14 garfield kernel: [ 42.362146] 3.5.0-bb3fe45+ #422 Not tainted Nov 26 04:05:14 garfield kernel: [ 42.362147] --------------------------------- Nov 26 04:05:14 garfield kernel: [ 42.362148] inconsistent {IN-SOFTIRQ-W} -> {SOFTIRQ-ON-W} usage. Nov 26 04:05:14 garfield kernel: [ 42.362150] kworker/u:0/5 [HC0[0]:SC0[0]:HE1:SE1] takes: Nov 26 04:05:14 garfield kernel: 42.362152{+.?...}, at: [] ieee80211aa_process_bar+0x9b/0xc0 [mac80211] Nov 26 04:05:14 garfield kernel: [ 42.362210] {IN-SOFTIRQ-W} state was registered at: Nov 26 04:05:14 garfield kernel: [ 42.362215] [] lock_acquire+0x5d9/0x1b30 Nov 26 04:05:14 garfield kernel: [ 42.362241] [] lock_acquire+0x95/0x130 Nov 26 04:05:14 garfield kernel: [ 42.362247] [] _raw_spin_lock_bh+0x36/0x50 Nov 26 04:05:14 garfield kernel: [ 42.362256] [] ieee80211aa_process_rx_data+0x3d/0xb0 [mac80211] Nov 26 04:05:14 garfield kernel: [ 42.362277] [] ieee80211aa_check_rx+0x8a/0x100 [mac80211] Nov 26 04:05:14 garfield kernel: [ 42.362296] [] ieee80211_rx_handlers+0xfff/0x2520 [mac80211] Nov 26 04:05:14 garfield kernel: [ 42.362319] [] ieee80211_prepare_and_rx_handle+0x162/0xab0 [mac80211] Nov 26 04:05:14 garfield kernel: [ 42.362342] [] ieee80211_rx+0x367/0x910 [mac80211] Nov 26 04:05:14 garfield kernel: [ 42.362365] [] ieee80211_tasklet_handler+0xb2/0x190 [mac80211] Nov 26 04:05:14 garfield kernel: [ 42.362382] [] tasklet_action+0x6c/0xe0 Nov 26 04:05:14 garfield kernel: [ 42.362399] [] __do_softirq+0xbd/0x250 Nov 26 04:05:14 garfield kernel: [ 42.362404] [] call_softirq+0x1c/0x30 Nov 26 04:05:14 garfield kernel: [ 42.362409] [] do_softirq+0x8d/0xc0 Nov 26 04:05:14 garfield kernel: [ 42.362416] [] local_bh_enable+0xdb/0xf0 Nov 26 04:05:14 garfield kernel: [ 42.362420] [] dev_queue_xmit+0x235/0x8a0 Nov 26 04:05:14 garfield kernel: [ 42.362436] [] ip_finish_output+0x1f0/0x540 Nov 26 04:05:14 garfield kernel: [ 42.362449] [] ip_mc_output+0x14d/0x2d0 Nov 26 04:05:14 garfield kernel: [ 42.362453] [] ip_local_out+0x2d/0x80 Nov 26 04:05:14 garfield kernel: [ 42.362458] [] ip_send_skb+0x1b/0x50 Nov 26 04:05:14 garfield kernel: [ 42.362462] [] udp_send_skb+0x106/0x370 Nov 26 04:05:14 garfield kernel: [ 42.362472] [] udp_sendmsg+0x34b/0x950 Nov 26 04:05:14 garfield kernel: [ 42.362478] [] inet_sendmsg+0xbc/0xf0 Nov 26 04:05:14 garfield kernel: [ 42.362483] [] sock_aio_write+0x14c/0x170 Nov 26 04:05:14 garfield kernel: [ 42.362490] [] do_sync_write+0xda/0x120 Nov 26 04:05:14 garfield kernel: [ 42.362505] [] vfs_write+0x16d/0x180 Nov 26 04:05:14 garfield kernel: [ 42.362510] [] sys_write+0x4a/0x90 Nov 26 04:05:14 garfield kernel: [ 42.362514] [] system_call_fastpath+0x16/0x1b Nov 26 04:05:14 garfield kernel: [ 42.362521] irq event stamp: 57771 Nov 26 04:05:14 garfield kernel: [ 42.362524] hardirqs last enabled at (57771): [] vprintk_emit+0x1b1/0x4e0 Nov 26 04:05:14 garfield kernel: [ 42.362531] hardirqs last disabled at (57770): [] vprintk_emit+0x42/0x4e0 Nov 26 04:05:14 garfield kernel: [ 42.362537] softirqs last enabled at (57750): [] mesh_rx_plink_frame+0x326/0xc30 [mac80211] Nov 26 04:05:14 garfield kernel: [ 42.362558] softirqs last disabled at (57748): [] _raw_spin_lock_bh+0x16/0x50 Nov 26 04:05:14 garfield kernel: [ 42.362564] Nov 26 04:05:14 garfield kernel: [ 42.362564] other info that might help us debug this: Nov 26 04:05:14 garfield kernel: [ 42.362567] Possible unsafe locking scenario: Nov 26 04:05:14 garfield kernel: [ 42.362567] Nov 26 04:05:14 garfield kernel: [ 42.362569] CPU0 Nov 26 04:05:14 garfield kernel: [ 42.362571] ---- Nov 26 04:05:14 garfield kernel: [ 42.362573] lock(&(&p->receiver.lock)->rlock); Nov 26 04:05:14 garfield kernel: [ 42.362577] Nov 26 04:05:14 garfield kernel: [ 42.362579] lock(&(&p->receiver.lock)->rlock); Nov 26 04:05:14 garfield kernel: [ 42.362583] Nov 26 04:05:14 garfield kernel: [ 42.362583] * DEADLOCK * Nov 26 04:05:14 garfield kernel: [ 42.362583] Nov 26 04:05:14 garfield kernel: [ 42.362587] 2 locks held by kworker/u:0/5: Nov 26 04:05:14 garfield kernel: [ 42.362590] #0: (wiphy_name(local->hw.wiphy)){++++.+}, at: [] process_one_work+0x11e/0x510 Nov 26 04:05:14 garfield kernel: [ 42.362616] #1: ((&sdata->work)){+.+.+.}, at: [] process_one_work+0x11e/0x510 Nov 26 04:05:14 garfield kernel: [ 42.362633] Nov 26 04:05:14 garfield kernel: [ 42.362633] stack backtrace: Nov 26 04:05:14 garfield kernel: [ 42.362641] Pid: 5, comm: kworker/u:0 Not tainted 3.5.0-bb3fe45+ #422 Nov 26 04:05:14 garfield kernel: [ 42.362645] Call Trace: Nov 26 04:05:14 garfield kernel: [ 42.362653] [] print_usage_bug+0x1f5/0x206 Nov 26 04:05:14 garfield kernel: [ 42.362662] [] ? save_stack_trace+0x2f/0x50 Nov 26 04:05:14 garfield kernel: [ 42.362683] [] ? put_dec+0x72/0x90 Nov 26 04:05:14 garfield kernel: [ 42.362689] [] mark_lock+0x294/0x2f0 Nov 26 04:05:14 garfield kernel: [ 42.362695] [] ? check_usage_forwards+0x140/0x140 Nov 26 04:05:14 garfield kernel: [ 42.362700] [] lock_acquire+0x64f/0x1b30 Nov 26 04:05:14 garfield kernel: [ 42.362705] [] ? mark_held_locks+0x82/0x130 Nov 26 04:05:14 garfield kernel: [ 42.362710] [] ? trace_hardirqs_off+0xd/0x10 Nov 26 04:05:14 garfield kernel: [ 42.362716] [] ? vprintk_emit+0x1b1/0x4e0 Nov 26 04:05:14 garfield kernel: [ 42.362722] [] ? trace_hardirqs_on_caller+0x105/0x190 Nov 26 04:05:14 garfield kernel: [ 42.362742] [] ? ieee80211aa_process_bar+0x9b/0xc0 [mac80211] Nov 26 04:05:14 garfield kernel: [ 42.362748] [] lock_acquire+0x95/0x130 Nov 26 04:05:14 garfield kernel: [ 42.362764] [] ? ieee80211aa_process_bar+0x9b/0xc0 [mac80211] Nov 26 04:05:14 garfield kernel: [ 42.362771] [] _raw_spin_lock+0x31/0x40 Nov 26 04:05:14 garfield kernel: [ 42.362806] [] ? ieee80211aa_process_bar+0x9b/0xc0 [mac80211] Nov 26 04:05:14 garfield kernel: [ 42.362825] [] ieee80211aa_process_bar+0x9b/0xc0 [mac80211] Nov 26 04:05:14 garfield kernel: [ 42.362832] [] ? _raw_spin_unlock_irqrestore+0x3f/0x70 Nov 26 04:05:14 garfield kernel: [ 42.362848] [] ieee80211aa_handle_bar+0xa1/0x100 [mac80211] Nov 26 04:05:14 garfield kernel: [ 42.362867] [] ieee80211_mesh_rx_queued_ctl+0x6e/0x80 [mac80211] Nov 26 04:05:14 garfield kernel: [ 42.362883] [] ieee80211_iface_work+0x2fd/0x380 [mac80211] Nov 26 04:05:14 garfield kernel: [ 42.362899] [] ? ieee80211_teardown_sdata+0xf0/0xf0 [mac80211] Nov 26 04:05:14 garfield kernel: [ 42.362905] [] process_one_work+0x18a/0x510 Nov 26 04:05:14 garfield kernel: [ 42.362911] [] ? process_one_work+0x11e/0x510 Nov 26 04:05:14 garfield kernel: [ 42.362918] [] worker_thread+0x15f/0x350 Nov 26 04:05:14 garfield kernel: [ 42.362925] [] ? manage_workers.isra.27+0x230/0x230 Nov 26 04:05:14 garfield kernel: [ 42.362931] [] kthread+0x93/0xa0 Nov 26 04:05:14 garfield kernel: [ 42.362938] [] kernel_thread_helper+0x4/0x10 Nov 26 04:05:14 garfield kernel: [ 42.362944] [] ? retint_restore_args+0xe/0xe Nov 26 04:05:14 garfield kernel: [ 42.362950] [] ? __init_kthread_worker+0x70/0x70 Nov 26 04:05:14 garfield kernel: [ 42.362956] [] ? gs_change+0xb/0xb Nov 26 04:05:14 garfield kernel: [ 42.362973] 11aa (mesh0): BA request 64 missing frames

[jcard0na]

Root cause or commit id?