Bug 196603

Summary: WARNING: CPU: 1 PID: 0 at net/mac80211/rx.c:630 ieee80211_rx_napi+0x931/0x9f0 [mac80211]
Product: Drivers Reporter: Gerardo Exequiel Pozzi (vmlinuz386)
Component: network-wirelessAssignee: drivers_network-wireless (drivers_network-wireless)
Status: CLOSED PATCH_ALREADY_AVAILABLE    
Severity: normal CC: code, johannes, pauloedgarcastro
Priority: P1    
Hardware: All   
OS: Linux   
Kernel Version: 4.12 Subsystem:
Regression: No Bisected commit-id:
Attachments: complete dmesg
linux.config

Description Gerardo Exequiel Pozzi 2017-08-07 02:58:57 UTC
Created attachment 257843 [details]
complete dmesg

I just upgraded from 4.4 to 4.12, I start to see these king of warnings.
I am using TP-LINK TL-WN781ND V2.2 (Qualcomm Atheros AR9485), configured as AP with hostapd WPA2-PSK-CCMP.

If more info is needed please let me know.


[ 9868.410208] ------------[ cut here ]------------
[ 9868.410235] WARNING: CPU: 1 PID: 0 at /usr/src/linux-4.12.5/net/mac80211/rx.c:630 ieee80211_rx_napi+0x931/0x9f0 [mac80211]
[ 9868.410237] Modules linked in: pppoe pppox ppp_generic slhc overlay tun ctr ccm 8021q xt_TCPMSS ipt_REJECT nf_reject_ipv4 xt_pkttype nf_log_ipv4 nf_log_common xt_LOG dm_crypt ecb xt_conntrack aes_x86_64 xts algif_skcipher af_alg iptable_filter ipt_MASQUERADE nf_nat_masquerade_ipv4 xt_nat xt_tcpudp iptable_nat nf_conntrack_ipv4 nf_defrag_ipv4 nf_nat_ipv4 nf_nat nf_conntrack xfs libcrc32c crc32c_generic input_leds dm_mod nvidia_drm(PO) nvidia_modeset(PO) dax nvidia(PO) arc4 ath9k ath9k_common ath9k_hw evdev drm_kms_helper cfbfillrect syscopyarea cfbimgblt sysfillrect sysimgblt fb_sys_fops ath snd_emu10k1 cfbcopyarea mac80211 drm snd_hwdep snd_util_mem i2c_core snd_ac97_codec cfg80211 fb kvm_amd kvm ac97_bus snd_rawmidi snd_seq_device snd_pcm snd_timer snd soundcore irqbypass thermal k8temp asus_atk0110
[ 9868.410280]  hwmon led_class forcedeth processor button fan fbdev sch_fq_codel vboxnetflt(O) vboxnetadp(O) vboxpci(O) nfsd auth_rpcgss binfmt_misc oid_registry lockd grace sunrpc vboxdrv(O) sg ip_tables x_tables ipv6 autofs4 ext4 crc16 jbd2 mbcache uas usb_storage hid_generic hid_logitech usbhid hid sd_mod ohci_pci ohci_hcd ehci_pci sata_nv ehci_hcd libata scsi_mod usbcore usb_common
[ 9868.410306] CPU: 1 PID: 0 Comm: swapper/1 Tainted: P           O    4.12.5 #1
[ 9868.410308] Hardware name: System manufacturer System Product Name/M2N32-SLI DELUXE, BIOS ASUS M2N32-SLI DELUXE ACPI BIOS Revision 1603 12/17/2007
[ 9868.410310] task: ffff8801230d9800 task.stack: ffffc90000074000
[ 9868.410320] RIP: 0010:ieee80211_rx_napi+0x931/0x9f0 [mac80211]
[ 9868.410322] RSP: 0018:ffff880127d03d48 EFLAGS: 00010246
[ 9868.410325] RAX: 0000000000010000 RBX: ffff8800c4f46400 RCX: 0000000000000002
[ 9868.410327] RDX: 0000000000000004 RSI: 0000000000000000 RDI: ffff88011b8f0660
[ 9868.410329] RBP: ffff880127d03df0 R08: 0000000000000000 R09: 00000000000007b6
[ 9868.410331] R10: 0000000000000000 R11: ffffea0000000000 R12: ffff88011b8f0660
[ 9868.410332] R13: 0000000000000000 R14: 0000000000000000 R15: 0000000000000004
[ 9868.410335] FS:  0000000000000000(0000) GS:ffff880127d00000(0000) knlGS:0000000000000000
[ 9868.410337] CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
[ 9868.410339] CR2: 00001e66fb46ad58 CR3: 0000000110e54000 CR4: 00000000000006e0
[ 9868.410340] Call Trace:
[ 9868.410343]  <IRQ>
[ 9868.410348]  ? __netdev_alloc_skb+0x8b/0x100
[ 9868.410352]  ? ath9k_cmn_rx_skb_postprocess+0x51/0x140 [ath9k_common]
[ 9868.410358]  ath_rx_tasklet+0xa1f/0xf50 [ath9k]
[ 9868.410362]  ath9k_tasklet+0x170/0x240 [ath9k]
[ 9868.410366]  tasklet_action+0x50/0xb0
[ 9868.410369]  __do_softirq+0xc6/0x210
[ 9868.410372]  irq_exit+0xb5/0xc0
[ 9868.410375]  do_IRQ+0x89/0xe0
[ 9868.410379]  common_interrupt+0x83/0x83
[ 9868.410382] RIP: 0010:amd_e400_idle+0xd/0x30
[ 9868.410384] RSP: 0018:ffffc90000077ec0 EFLAGS: 00000246 ORIG_RAX: ffffffffffffff2e
[ 9868.410387] RAX: 6874754100002d40 RBX: ffff8801230d9800 RCX: 0000000000000000
[ 9868.410389] RDX: 0000000000000000 RSI: 0000000000000000 RDI: 0000000000000000
[ 9868.410390] RBP: ffffc90000077ec8 R08: 000008f9ab3b9af6 R09: ffff880120d8a900
[ 9868.410392] R10: 0000000000000000 R11: 0000000000000000 R12: ffffffff81647738
[ 9868.410394] R13: ffff8801230d9800 R14: 0000000000000000 R15: ffff8801230d9800
[ 9868.410396]  </IRQ>
[ 9868.410399]  ? arch_cpu_idle+0xa/0x10
[ 9868.410402]  default_idle_call+0x1e/0x30
[ 9868.410405]  do_idle+0x16d/0x190
[ 9868.410408]  cpu_startup_entry+0x1d/0x20
[ 9868.410410]  start_secondary+0xf3/0x100
[ 9868.410413]  secondary_startup_64+0x9f/0x9f
[ 9868.410415] Code: 41 c7 81 bc 00 00 00 00 04 66 41 89 81 c2 00 00 00 41 0f b6 81 90 00 00 00 83 e0 38 83 c8 43 41 88 81 90 00 00 00 e9 bd fb ff ff <0f> ff 48 89 df e8 b5 27 bd e0 e9 29 fa ff ff 0f ff e9 72 f7 ff 
[ 9868.410446] ---[ end trace 14224729480cca3b ]---
Comment 1 Gerardo Exequiel Pozzi 2017-08-07 02:59:39 UTC
Created attachment 257845 [details]
linux.config
Comment 2 Paulo Castro 2018-01-22 23:13:08 UTC
[   56.776170] wlp8s0: authenticate with ec:f4:51:8d:22:74
[   56.802313] wlp8s0: send auth to ec:f4:51:8d:22:74 (try 1/3)
[   56.804299] wlp8s0: authenticated
[   56.806895] wlp8s0: associate with ec:f4:51:8d:22:74 (try 1/3)
[   56.813930] wlp8s0: RX AssocResp from ec:f4:51:8d:22:74 (capab=0x411 status=0 aid=1)
[   56.814598] wlp8s0: associated
[   56.868416] IPv6: ADDRCONF(NETDEV_CHANGE): wlp8s0: link becomes ready
[   78.508831] IPv6: ADDRCONF(NETDEV_UP): bridge1: link is not ready
[   93.092704] CPU2: Core temperature above threshold, cpu clock throttled (total events = 1)
[   93.092705] CPU3: Core temperature above threshold, cpu clock throttled (total events = 1)
[   93.092745] CPU4: Package temperature above threshold, cpu clock throttled (total events = 1)
[   93.092747] CPU7: Package temperature above threshold, cpu clock throttled (total events = 1)
[   93.092747] CPU3: Package temperature above threshold, cpu clock throttled (total events = 1)
[   93.092750] CPU1: Package temperature above threshold, cpu clock throttled (total events = 1)
[   93.092751] CPU0: Package temperature above threshold, cpu clock throttled (total events = 1)
[   93.092752] CPU2: Package temperature above threshold, cpu clock throttled (total events = 1)
[   93.092752] CPU6: Package temperature above threshold, cpu clock throttled (total events = 1)
[   93.092754] CPU5: Package temperature above threshold, cpu clock throttled (total events = 1)
[   93.093700] CPU2: Core temperature/speed normal
[   93.093701] CPU1: Package temperature/speed normal
[   93.093703] CPU5: Package temperature/speed normal
[   93.093704] CPU3: Core temperature/speed normal
[   93.093704] CPU6: Package temperature/speed normal
[   93.093705] CPU4: Package temperature/speed normal
[   93.093706] CPU7: Package temperature/speed normal
[   93.093707] CPU0: Package temperature/speed normal
[   93.093708] CPU3: Package temperature/speed normal
[   93.093715] CPU2: Package temperature/speed normal
[  182.328612] WARNING: CPU: 4 PID: 0 at net/mac80211/rx.c:629 ieee80211_rx_napi+0xacc/0xbe0 [mac80211]
[  182.328631] Modules linked in: ctr ccm rfcomm bnep fuse dm_crypt algif_skcipher af_alg nouveau ip6t_rpfilter ip6t_REJECT nf_reject_ipv6 xt_conntrack ip_set nfnetlink ebtable_broute bridge stp llc ebtable_nat ip6table_security ip6table_mang
le ip6table_nat nf_conntrack_ipv6 nf_defrag_ipv6 nf_nat_ipv6 ip6table_raw iptable_security iptable_mangle iptable_nat nf_conntrack_ipv4 nf_defrag_ipv4 nf_nat_ipv4 nf_nat nf_conntrack libcrc32c iptable_raw ebtable_filter ebtables ip6table_filt
er ip6_tables ttm arc4 ath9k ath9k_common ath9k_hw mac80211 ath3k x86_pkg_temp_thermal ath snd_hda_codec_realtek coretemp btusb snd_hda_codec_hdmi snd_hda_codec_generic kvm_intel snd_hda_intel btrtl snd_hda_codec kvm btbcm btintel cfg80211 sn
d_hwdep bluetooth snd_hda_core uvcvideo snd_seq irqbypass videobuf2_vmalloc crct10dif_pclmul
[  182.328693]  crc32_pclmul videobuf2_memops ghash_clmulni_intel videobuf2_v4l2 iTCO_wdt snd_seq_device pcbc videobuf2_core snd_pcm videodev iTCO_vendor_support aesni_intel rtsx_pci mei_me aes_x86_64 mei crypto_simd cryptd glue_helper sha256
_generic snd_timer snd media drbg lpc_ich joydev i2c_i801 ansi_cprng shpchp mfd_core ecdh_generic ideapad_laptop thermal soundcore sparse_keymap ie31200_edac rfkill battery ac i915 iosf_mbi intel_gtt i2c_algo_bit drm_kms_helper cfbfillrect sy
scopyarea cfbimgblt sysfillrect sysimgblt fb_sys_fops cfbcopyarea drm xhci_pci xhci_hcd i2c_core serio_raw crc32c_intel video wmi button configfs ipv6 crc_ccitt autofs4 [last unloaded: netconsole]
[  182.328751] CPU: 4 PID: 0 Comm: swapper/4 Not tainted 4.15.0-rc8.1.PEC40-00179-g3763646 #1
[  182.328752] Hardware name: LENOVO 20378/Lenovo Y50-70, BIOS 9ECN31WW(V1.14) 08/18/2014
[  182.328766] RIP: 0010:ieee80211_rx_napi+0xacc/0xbe0 [mac80211]
[  182.328768] RSP: 0018:ffff9bbadf303d10 EFLAGS: 00010246
[  182.328770] RAX: 0000000000010000 RBX: ffff9bba6e83af00 RCX: 00000000000000ff
[  182.328771] RDX: 0000000000000004 RSI: 00000000ffffffff RDI: ffffffff9d05f7a0
[  182.328773] RBP: ffff9bbadf303de8 R08: ffffffffc0d26029 R09: 0000000000000000
[  182.328774] R10: ffff9bbadf303ca0 R11: ffff9bbacc9d8000 R12: 0000000000000000
[  182.328776] R13: 0000000000000000 R14: ffff9bbac8352360 R15: ffff9bbac8350b00
[  182.328777] FS:  0000000000000000(0000) GS:ffff9bbadf300000(0000) knlGS:0000000000000000
[  182.328779] CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
[  182.328780] CR2: 00001a2e181fb000 CR3: 0000000369010004 CR4: 00000000001606e0
[  182.328782] Call Trace:
[  182.328784]  <IRQ>
[  182.328790]  ? lock_acquire+0xdb/0x1d0
[  182.328793]  ? mark_held_locks+0x64/0x90
[  182.328796]  ? _raw_spin_unlock_irqrestore+0x45/0x60
[  182.328804]  ath_rx_tasklet+0xaf3/0x1190 [ath9k]
[  182.328807]  ? try_to_del_timer_sync+0x4d/0x80
[  182.328816]  ath9k_tasklet+0x1df/0x240 [ath9k]
[  182.328821]  tasklet_action+0x67/0x1f0
[  182.328825]  __do_softirq+0xce/0x4d8
[  182.328829]  irq_exit+0xc9/0xd0
[  182.328832]  do_IRQ+0x60/0x110
[  182.328834]  common_interrupt+0x9f/0x9f
[  182.328836]  </IRQ>
[  182.328838] RIP: 0010:cpuidle_enter_state+0xab/0x390
[  182.328840] RSP: 0018:ffffb7368194fe98 EFLAGS: 00000202 ORIG_RAX: ffffffffffffffde
[  182.328842] RAX: ffff9bbacc9d8000 RBX: 0000002a739fab01 RCX: 0000000000000001
[  182.328843] RDX: 20c49ba5e353f7cf RSI: 0000000000000001 RDI: ffff9bbacc9d8000
[  182.328845] RBP: ffffd7367fd09d00 R08: 0000000000000b6a R09: 0000000000000018
[  182.328846] R10: 0000000000000000 R11: 0000000000000000 R12: 0000000000000005
[  182.328848] R13: 0000000000000005 R14: 0000000000000005 R15: 0000002a73924ad3
[  182.328853]  ? cpuidle_enter_state+0xa0/0x390
[  182.328856]  do_idle+0x18a/0x1f0
[  182.328859]  cpu_startup_entry+0x6f/0x80
[  182.328862]  start_secondary+0x17b/0x1c0
[  182.328865]  secondary_startup_64+0xa5/0xb0
[  182.328870] Code: f0 48 c7 c7 58 6d d8 c0 c6 05 0f a3 09 00 01 e8 ab 3c 33 db 0f ff e9 21 f6 ff ff 48 89 de 4c 89 ff e8 59 91 fd ff e9 54 f8 ff ff <0f> ff 48 89 df e8 6a 64 99 db e9 ff fc ff ff 0f ff e9 fb f5 ff 
[  182.328920] ---[ end trace 87410096c1295e90 ]---
[  182.328958] WARNING: CPU: 4 PID: 0 at net/mac80211/rx.c:629 ieee80211_rx_napi+0xacc/0xbe0 [mac80211]
[  182.328960] Modules linked in: ctr ccm rfcomm bnep fuse dm_crypt algif_skcipher af_alg nouveau ip6t_rpfilter ip6t_REJECT nf_reject_ipv6 xt_conntrack ip_set nfnetlink ebtable_broute bridge stp llc ebtable_nat ip6table_security ip6table_mangle ip6table_nat nf_conntrack_ipv6 nf_defrag_ipv6 nf_nat_ipv6 ip6table_raw iptable_security iptable_mangle iptable_nat nf_conntrack_ipv4 nf_defrag_ipv4 nf_nat_ipv4 nf_nat nf_conntrack libcrc32c iptable_raw ebtable_filter ebtables ip6table_filter ip6_tables ttm arc4 ath9k ath9k_common ath9k_hw mac80211 ath3k x86_pkg_temp_thermal ath snd_hda_codec_realtek coretemp btusb snd_hda_codec_hdmi snd_hda_codec_generic kvm_intel snd_hda_intel btrtl snd_hda_codec kvm btbcm btintel cfg80211 snd_hwdep bluetooth snd_hda_core uvcvideo snd_seq irqbypass videobuf2_vmalloc crct10dif_pclmul
[  182.329040]  crc32_pclmul videobuf2_memops ghash_clmulni_intel videobuf2_v4l2 iTCO_wdt snd_seq_device pcbc videobuf2_core snd_pcm videodev iTCO_vendor_support aesni_intel rtsx_pci mei_me aes_x86_64 mei crypto_simd cryptd glue_helper sha256_generic snd_timer snd media drbg lpc_ich joydev i2c_i801 ansi_cprng shpchp mfd_core ecdh_generic ideapad_laptop thermal soundcore sparse_keymap ie31200_edac rfkill battery ac i915 iosf_mbi intel_gtt i2c_algo_bit drm_kms_helper cfbfillrect syscopyarea cfbimgblt sysfillrect sysimgblt fb_sys_fops cfbcopyarea drm xhci_pci xhci_hcd i2c_core serio_raw crc32c_intel video wmi button configfs ipv6 crc_ccitt autofs4 [last unloaded: netconsole]
[  182.329121] CPU: 4 PID: 0 Comm: swapper/4 Tainted: G        W        4.15.0-rc8.1.PEC40-00179-g3763646 #1
[  182.329124] Hardware name: LENOVO 20378/Lenovo Y50-70, BIOS 9ECN31WW(V1.14) 08/18/2014
[  182.329141] RIP: 0010:ieee80211_rx_napi+0xacc/0xbe0 [mac80211]
[  182.329144] RSP: 0018:ffff9bbadf303d10 EFLAGS: 00010246
[  182.329148] RAX: 0000000000010000 RBX: ffff9bba6e83b800 RCX: 00000000000000ff
[  182.329150] RDX: 0000000000000004 RSI: 00000000ffffffff RDI: ffffffff9d05f7a0
[  182.329152] RBP: ffff9bbadf303de8 R08: ffffffffc0d26029 R09: 0000000000000000
[  182.329154] R10: ffff9bbadf303ca0 R11: ffff9bbacc9d8000 R12: 0000000000000000
[  182.329156] R13: 0000000000000000 R14: ffff9bbac8352360 R15: ffff9bbac8350b00
[  182.329159] FS:  0000000000000000(0000) GS:ffff9bbadf300000(0000) knlGS:0000000000000000
[  182.329162] CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
[  182.329164] CR2: 00001a2e181fb000 CR3: 0000000369010004 CR4: 00000000001606e0
[  182.329166] Call Trace:
[  182.329169]  <IRQ>
[  182.329175]  ? lock_acquire+0xdb/0x1d0
[  182.329181]  ? mark_held_locks+0x64/0x90
[  182.329184]  ? _raw_spin_unlock_irqrestore+0x45/0x60
[  182.329192]  ath_rx_tasklet+0xaf3/0x1190 [ath9k]
[  182.329196]  ? try_to_del_timer_sync+0x4d/0x80
[  182.329209]  ath9k_tasklet+0x1df/0x240 [ath9k]
[  182.329215]  tasklet_action+0x67/0x1f0
[  182.329220]  __do_softirq+0xce/0x4d8
[  182.329227]  irq_exit+0xc9/0xd0
[  182.329230]  do_IRQ+0x60/0x110
[  182.329235]  common_interrupt+0x9f/0x9f
[  182.329238]  </IRQ>
[  182.329241] RIP: 0010:cpuidle_enter_state+0xab/0x390
[  182.329244] RSP: 0018:ffffb7368194fe98 EFLAGS: 00000202 ORIG_RAX: ffffffffffffffde
[  182.329248] RAX: ffff9bbacc9d8000 RBX: 0000002a739fab01 RCX: 0000000000000001
[  182.329250] RDX: 20c49ba5e353f7cf RSI: 0000000000000001 RDI: ffff9bbacc9d8000
[  182.329254] RBP: ffffd7367fd09d00 R08: 0000000000000b6a R09: 0000000000000018
[  182.329256] R10: 0000000000000000 R11: 0000000000000000 R12: 0000000000000005
[  182.329259] R13: 0000000000000005 R14: 0000000000000005 R15: 0000002a73924ad3
[  182.329268]  ? cpuidle_enter_state+0xa0/0x390
[  182.329274]  do_idle+0x18a/0x1f0
[  182.329279]  cpu_startup_entry+0x6f/0x80
[  182.329283]  start_secondary+0x17b/0x1c0
[  182.329287]  secondary_startup_64+0xa5/0xb0
[  182.329296] Code: f0 48 c7 c7 58 6d d8 c0 c6 05 0f a3 09 00 01 e8 ab 3c 33 db 0f ff e9 21 f6 ff ff 48 89 de 4c 89 ff e8 59 91 fd ff e9 54 f8 ff ff <0f> ff 48 89 df e8 6a 64 99 db e9 ff fc ff ff 0f ff e9 fb f5 ff 
[  182.329383] ---[ end trace 87410096c1295e91 ]---
Comment 3 Johannes Berg 2018-01-23 07:55:11 UTC
This is a driver bug, fixed by https://patchwork.kernel.org/patch/10168963/
Comment 4 Gerardo Exequiel Pozzi 2018-01-25 17:43:54 UTC
Thanks you! Tested and fixed! Will be submitted for stable/longterm and mainline branch?
Comment 5 Gerardo Exequiel Pozzi 2018-02-06 18:38:02 UTC
@Johannes: I tested against the USB version (ath9k_htc), and the same thing happens in function ath9k_rx_prepare(). I need to open a separate bug request?
Comment 6 Johannes Berg 2018-02-06 18:41:05 UTC
probably better, yeah, otherwise somebody's going to get confused