Bug 1599383 - frequent errors with iwlwifi
Summary: frequent errors with iwlwifi
Keywords:
Status: CLOSED INSUFFICIENT_DATA
Alias: None
Product: Fedora
Classification: Fedora
Component: kernel
Version: 28
Hardware: Unspecified
OS: Unspecified
unspecified
unspecified
Target Milestone: ---
Assignee: Kernel Maintainer List
QA Contact: Fedora Extras Quality Assurance
URL:
Whiteboard:
Depends On:
Blocks:
TreeView+ depends on / blocked
 
Reported: 2018-07-09 16:19 UTC by Tomasz Torcz
Modified: 2019-10-25 08:58 UTC (History)
17 users (show)

Fixed In Version:
Doc Type: If docs needed, set a value
Doc Text:
Clone Of:
Environment:
Last Closed: 2018-11-26 21:04:01 UTC
Type: Bug
Embargoed:
tomek: needinfo-


Attachments (Terms of Use)
dmesg.txt (226.06 KB, text/plain)
2018-07-09 16:19 UTC, Tomasz Torcz
no flags Details

Description Tomasz Torcz 2018-07-09 16:19:23 UTC
Created attachment 1457540 [details]
dmesg.txt

Description of problem:
Somewhere with the kernel 4.16.x, my Intel wireless card started to disconnect often. This most often happen when I pick up my laptop which was unusued for few minutes – long enough to blank screen. When the screen unblanks, wifi reconnects, there are errors in dmesg. Afterwards wifi is rather stable for few hours.
This laptop never had such problems in few years I'm using it with Fedora.


Version-Release number of selected component (if applicable):
4.17.0-200.fc28.x86_64
It has started with 4.16.x, but I waited hoping 4.17 will improve situation. It did not.

How reproducible:
Almost always.

Steps to Reproduce:
1.
2.
3.

Actual results:
[474862.944035] iwlwifi 0000:03:00.0: Error sending REPLY_TXFIFO_FLUSH: time out after 2000ms.
[474862.944041] iwlwifi 0000:03:00.0: Current CMD queue read_ptr 231 write_ptr 233
[474862.958743] iwlwifi 0000:03:00.0: iwlwifi transaction failed, dumping registers
[474862.958743] iwlwifi 0000:03:00.0: iwlwifi device config registers:
[474862.958743] iwlwifi 0000:03:00.0: Read failed at 0x0
[474862.962309] iwlwifi 0000:03:00.0: Loaded firmware version: 8.83.5.1 build 33692
[474862.980640] iwlwifi 0000:03:00.0: Start IWL Error Log Dump:
[474862.980645] iwlwifi 0000:03:00.0: Status: 0x0000004C, count: -23496
[474862.980648] iwlwifi 0000:03:00.0: 0x758EED00 | ADVANCED_SYSASSERT          
[474862.980651] iwlwifi 0000:03:00.0: 0xB0C04A1E | uPc
[474862.980653] iwlwifi 0000:03:00.0: 0xC0B04AF7 | branchlink1
[474862.980656] iwlwifi 0000:03:00.0: 0xFFFFFFFF | branchlink2
[474862.980658] iwlwifi 0000:03:00.0: 0x000260C0 | interruptlink1
[474862.980660] iwlwifi 0000:03:00.0: 0x00000000 | interruptlink2
[474862.980662] iwlwifi 0000:03:00.0: 0x48737768 | data1
[474862.980664] iwlwifi 0000:03:00.0: 0xFFFFA438 | data2
[474862.980667] iwlwifi 0000:03:00.0: 0xC0C6E5AA | line
[474862.980677] iwlwifi 0000:03:00.0: 0xFFFFFFFF | beacon time
[474862.980679] iwlwifi 0000:03:00.0: 0xD9EA6014 | tsf low
[474862.980681] iwlwifi 0000:03:00.0: 0xFFFF9272 | tsf hi
[474862.980683] iwlwifi 0000:03:00.0: 0xC0B04AF7 | time gp1
[474862.980686] iwlwifi 0000:03:00.0: 0xFFFFFFFF | time gp2
[474862.980688] iwlwifi 0000:03:00.0: 0x487377C8 | time gp3
[474862.980690] iwlwifi 0000:03:00.0: 0xFFFFA438 | uCode version
[474862.980692] iwlwifi 0000:03:00.0: 0xC0AF70A6 | hw version
[474862.980694] iwlwifi 0000:03:00.0: 0xFFFFFFFF | board version
[474862.980696] iwlwifi 0000:03:00.0: 0xFFFFA438 | hcmd
[474862.980699] iwlwifi 0000:03:00.0: 0x00000020 | isr0
[474862.980701] iwlwifi 0000:03:00.0: 0x00000000 | isr1
[474862.980703] iwlwifi 0000:03:00.0: 0x487377D8 | isr2
[474862.980705] iwlwifi 0000:03:00.0: 0xFFFFA438 | isr3
[474862.980707] iwlwifi 0000:03:00.0: 0x48737798 | isr4
[474862.980709] iwlwifi 0000:03:00.0: 0xFFFFA438 | isr_pref
[474862.980711] iwlwifi 0000:03:00.0: 0x758EED00 | wait_event
[474862.980714] iwlwifi 0000:03:00.0: 0xB0C04A1E | l2p_control
[474862.980723] iwlwifi 0000:03:00.0: 0xCA7E0028 | l2p_duration
[474862.980726] iwlwifi 0000:03:00.0: 0xFFFF9270 | l2p_mhvalid
[474862.980728] iwlwifi 0000:03:00.0: 0x00A01C30 | l2p_addr_match
[474862.980730] iwlwifi 0000:03:00.0: 0x00000000 | lmpm_pmg_sel
[474862.980732] iwlwifi 0000:03:00.0: 0x00000080 | timestamp
[474862.980742] iwlwifi 0000:03:00.0: 0x00000000 | flow_handler
[474862.999116] WARNING: CPU: 1 PID: 522781 at drivers/net/wireless/intel/iwlwifi/dvm/../iwl-trans.h:1104 iwl_dump_nic_event_log+0x396/0x430 [iwldvm]
[474862.999120] Modules linked in: xt_CHECKSUM ipt_MASQUERADE nf_nat_masquerade_ipv4 ip6t_rpfilter ip6t_REJECT nf_reject_ipv6 xt_conntrack ebtable_nat ebtable_broute ip6table_nat nf_conntrack_ipv6 nf_defrag_ipv6 nf_nat_ipv6 ip6table_mangle ip6table_raw ip6table_security iptable_nat nf_conntrack_ipv4 nf_defrag_ipv4 nf_nat_ipv4 nf_nat nf_conntrack iptable_mangle iptable_raw iptable_security ebtable_filter ebtables ip6table_filter ip6_tables nfnetlink_queue nfnetlink_log pl2303 nls_utf8 isofs uas usb_storage rfcomm fuse tun ccm devlink ip_set nfnetlink bridge stp llc bnep coretemp kvm_intel iTCO_wdt gpio_ich iTCO_vendor_support kvm wmi_bmof mei_wdt arc4 bcache iwldvm mac80211 irqbypass iwlwifi btusb btrtl btbcm btintel uvcvideo videobuf2_vmalloc videobuf2_memops videobuf2_v4l2 bluetooth videobuf2_common
[474862.999161]  joydev i2c_i801 videodev cfg80211 media ecdh_generic r592 memstick lpc_ich snd_hda_codec_conexant snd_hda_codec_generic thinkpad_acpi snd_hda_intel wmi snd_hda_codec rfkill snd_hda_core snd_hwdep snd_seq snd_seq_device snd_pcm snd_timer snd mei_me mei shpchp acpi_cpufreq soundcore tcp_bbr binfmt_misc nfsd nfs_acl lockd auth_rpcgss grace sunrpc btrfs libcrc32c xor zstd_decompress zstd_compress xxhash raid6_pq dm_crypt i915 sdhci_pci i2c_algo_bit cqhci sdhci firewire_ohci drm_kms_helper serio_raw firewire_core e1000e mmc_core crc_itu_t yenta_socket drm video bfq sha256_ssse3(E) [last unloaded: ip6_tables]
[474862.999205] CPU: 1 PID: 522781 Comm: kworker/u8:4 Tainted: G      D W   E     4.17.0-200.fc28.x86_64 #1
[474862.999207] Hardware name: LENOVO 7417CTO/7417CTO, BIOS 7UET94WW (3.24 ) 10/17/2012
[474862.999213] Workqueue: pciehp-1 pciehp_power_thread
[474862.999221] RIP: 0010:iwl_dump_nic_event_log+0x396/0x430 [iwldvm]
[474862.999223] RSP: 0000:ffffa43848737778 EFLAGS: 00010286
[474862.999225] RAX: 00000000fffffff0 RBX: 0000000000800b94 RCX: 0000000000000000
[474862.999227] RDX: 00000000fffffff0 RSI: 0000000000000246 RDI: 0000000000000246
[474862.999229] RBP: ffff9271c6d39528 R08: 0000000000003a98 R09: 0000000000012857
[474862.999231] R10: 3030303030303078 R11: 30203a302e30303a R12: 00000000b0c04a1e
[474862.999233] R13: 0000000000000100 R14: 0000000000000000 R15: ffff9270ca7e0028
[474862.999235] FS:  0000000000000000(0000) GS:ffff9272fbc80000(0000) knlGS:0000000000000000
[474862.999237] CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
[474862.999239] CR2: 0000354674e9aff8 CR3: 000000019a20a000 CR4: 00000000000006e0
[474862.999241] Call Trace:
[474862.999251]  iwl_nic_error+0x3a/0x50 [iwldvm]
[474862.999264]  iwl_trans_pcie_send_hcmd+0x42a/0x550 [iwlwifi]
[474862.999269]  ? finish_wait+0x80/0x80
[474862.999278]  iwl_trans_send_cmd+0x61/0xd0 [iwlwifi]
[474862.999285]  iwlagn_txfifo_flush+0x12e/0x140 [iwldvm]
[474862.999293]  iwlagn_mac_flush+0x118/0x1b0 [iwldvm]
[474862.999340]  __ieee80211_flush_queues+0xad/0x1b0 [mac80211]
[474862.999363]  ieee80211_set_disassoc+0x330/0x570 [mac80211]
[474862.999385]  ieee80211_mgd_deauth.cold.47+0x47/0x1b1 [mac80211]
[474862.999390]  ? ttwu_do_wakeup+0x19/0x140
[474862.999436]  cfg80211_mlme_deauth+0xb3/0x1d0 [cfg80211]
[474862.999458]  cfg80211_mlme_down+0x66/0x90 [cfg80211]
[474862.999478]  cfg80211_disconnect+0x128/0x1e0 [cfg80211]
[474862.999496]  cfg80211_leave+0x26/0x40 [cfg80211]
[474862.999515]  cfg80211_netdev_notifier_call+0x357/0x610 [cfg80211]
[474862.999518]  ? pick_next_task_fair+0x376/0x610
[474862.999522]  ? __switch_to+0xa3/0x4b0
[474862.999525]  ? dequeue_task_fair+0x3e/0x6d0
[474862.999529]  ? addrconf_notify+0x6d/0xa80
[474862.999532]  ? __schedule+0x23c/0x850
[474862.999535]  ? inetdev_event+0x77/0x500
[474862.999540]  notifier_call_chain+0x47/0x70
[474862.999544]  __dev_close_many+0x5f/0x110
[474862.999548]  dev_close_many+0x9f/0x160
[474862.999551]  dev_close.part.98+0x64/0xa0
[474862.999568]  cfg80211_shutdown_all_interfaces+0x6d/0xc0 [cfg80211]
[474862.999590]  ieee80211_remove_interfaces+0x5a/0x230 [mac80211]
[474862.999594]  ? synchronize_sched+0x92/0xa0
[474862.999596]  ? __call_rcu+0x2c0/0x2c0
[474862.999616]  ieee80211_unregister_hw+0x46/0x130 [mac80211]
[474862.999623]  iwlagn_mac_unregister+0x24/0x30 [iwldvm]
[474862.999630]  iwl_op_mode_dvm_stop+0x34/0xd0 [iwldvm]
[474862.999637]  _iwl_op_mode_stop.isra.12+0x2c/0x50 [iwlwifi]
[474862.999645]  iwl_drv_stop+0x28/0xa0 [iwlwifi]
[474862.999652]  iwl_pci_remove+0x20/0x40 [iwlwifi]
[474862.999657]  pci_device_remove+0x3b/0xb0
[474862.999661]  device_release_driver_internal+0x15a/0x220
[474862.999665]  pci_stop_bus_device+0x80/0xa0
[474862.999668]  pci_stop_and_remove_bus_device+0xe/0x20
[474862.999670]  pciehp_unconfigure_device+0xb8/0x160
[474862.999673]  pciehp_disable_slot+0x51/0xd0
[474862.999676]  pciehp_power_thread+0x82/0xa0
[474862.999680]  process_one_work+0x187/0x340
[474862.999683]  worker_thread+0x2e/0x380
[474862.999685]  ? pwq_unbound_release_workfn+0xd0/0xd0
[474862.999688]  kthread+0x112/0x130
[474862.999691]  ? kthread_create_worker_on_cpu+0x70/0x70
[474862.999695]  ret_from_fork+0x35/0x40
[474862.999697] Code: 70 fe ff ff 44 39 eb 72 58 41 52 44 29 eb 8b 4c 24 14 44 89 ea 89 de 4d 89 f1 45 31 c0 48 89 ef e8 d0 d7 ff ff 5a e9 49 fe ff ff <0f> 0b 41 bc a5 a5 a5 a5 e9 ed fc ff ff 0f 0b bb a5 a5 a5 a5 e9 
[474862.999729] ---[ end trace d1a5c5a78787bac6 ]---


and


[477124.944441] iwlwifi 0000:03:00.0: Log capacity -1515870811 is bogus, limit to 256 entries
[477124.944446] iwlwifi 0000:03:00.0: Log write index -1515870811 is bogus, limit to 256
[477124.944449] iwlwifi 0000:03:00.0: Start IWL Event Log Dump: display last 20 entries
[477124.962756] iwlwifi 0000:03:00.0: flush request fail
[477126.907127] ieee80211 phy483: Hardware restart was requested
[477126.907171] iwlwifi 0000:03:00.0: iwl_trans_wait_tx_queues_empty bad state = 0
[477126.907181] iwlwifi 0000:03:00.0: Fw not loaded - dropping CMD: 18
[477126.907185] wlp3s0: HW problem - can not stop rx aggregation for 20:e5:2a:00:fc:bc tid 0
[477126.907190] iwlwifi 0000:03:00.0: Fw not loaded - dropping CMD: 18
[477126.907192] wlp3s0: HW problem - can not stop rx aggregation for 20:e5:2a:00:fc:bc tid 1
[477126.907197] iwlwifi 0000:03:00.0: Fw not loaded - dropping CMD: 18
[477126.907199] wlp3s0: HW problem - can not stop rx aggregation for 20:e5:2a:00:fc:bc tid 5
[477126.909069] iwlwifi 0000:03:00.0: iwl_trans_wait_tx_queues_empty bad state = 0
[477127.104462] pciehp 0000:00:1c.1:pcie004: Slot(1-1): Link Up event ignored; already powering on
[477127.208152] pci 0000:03:00.0: [8086:4236] type 00 class 0x028000
[477127.208243] pci 0000:03:00.0: reg 0x10: [mem 0x00000000-0x00001fff 64bit]
[477127.208523] pci 0000:03:00.0: PME# supported from D0 D3hot D3cold
[477127.208858] pcieport 0000:00:1c.1: ASPM: current common clock configuration is broken, reconfiguring
[477127.216129] pci 0000:03:00.0: BAR 0: assigned [mem 0xf4300000-0xf4301fff 64bit]
[477127.216167] pcieport 0000:00:1c.1: PCI bridge to [bus 03]
[477127.216177] pcieport 0000:00:1c.1:   bridge window [io  0x9000-0x9fff]
[477127.216191] pcieport 0000:00:1c.1:   bridge window [mem 0xf4300000-0xf43fffff]
[477127.216202] pcieport 0000:00:1c.1:   bridge window [mem 0xc0400000-0xc05fffff 64bit pref]
[477127.216328] iwlwifi 0000:03:00.0: enabling device (0000 -> 0002)
[477127.216444] iwlwifi 0000:03:00.0: can't disable ASPM; OS doesn't have ASPM control
[477127.224358] iwlwifi 0000:03:00.0: loaded firmware version 8.83.5.1 build 33692 op_mode iwldvm
[477127.224405] iwlwifi 0000:03:00.0: CONFIG_IWLWIFI_DEBUG enabled
[477127.224408] iwlwifi 0000:03:00.0: CONFIG_IWLWIFI_DEBUGFS enabled
[477127.224410] iwlwifi 0000:03:00.0: CONFIG_IWLWIFI_DEVICE_TRACING disabled
[477127.224413] iwlwifi 0000:03:00.0: Detected Intel(R) Ultimate N WiFi Link 5300 AGN, REV=0x24
[477127.265449] ieee80211 phy484: Selected rate control algorithm 'iwl-agn-rs'
[477127.320521] iwlwifi 0000:03:00.0 wlp3s0: renamed from wlan0
[477127.351874] IPv6: ADDRCONF(NETDEV_UP): wlp3s0: link is not ready
[477127.361133] iwlwifi 0000:03:00.0: Radio type=0x0-0x2-0x0
[477127.514130] iwlwifi 0000:03:00.0: Radio type=0x0-0x2-0x0
[477127.539719] IPv6: ADDRCONF(NETDEV_UP): wlp3s0: link is not ready
[477127.568210] iwlwifi 0000:03:00.0: Radio type=0x0-0x2-0x0
[477127.722308] iwlwifi 0000:03:00.0: Radio type=0x0-0x2-0x0
[477127.753252] IPv6: ADDRCONF(NETDEV_UP): wlp3s0: link is not ready
[477127.788072] IPv6: ADDRCONF(NETDEV_UP): wlp3s0: link is not ready
[477130.564173] iwlwifi 0000:03:00.0: Radio type=0x0-0x2-0x0


and similar.

Expected results:
Stable wifi.

Additional info:
03:00.0 Network controller: Intel Corporation Ultimate N WiFi Link 5300
Using GNOME and NetworkManager.

Comment 1 Tomasz Torcz 2018-07-12 15:41:17 UTC
Happens with latest updates:
iwl5000-firmware-8.83.5.1_1-85.fc28.1.noarch
linux-firmware-20180525-85.git7518922b.fc28.noarch
kernel-core-4.17.4-200.fc28.x86_64

Comment 2 Laura Abbott 2018-10-01 21:17:40 UTC
We apologize for the inconvenience.  There is a large number of bugs to go through and several of them have gone stale.  Due to this, we are doing a mass bug update across all of the Fedora 28 kernel bugs.
 
Fedora 28 has now been rebased to 4.18.10-300.fc28.  Please test this kernel update (or newer) and let us know if you issue has been resolved or if it is still present with the newer kernel.
 
If you have moved on to Fedora 29, and are still experiencing this issue, please change the version to Fedora 29.
 
If you experience different issues, please open a new bug report for those.

Comment 3 Laura Abbott 2018-11-26 21:04:01 UTC
This bug is being closed with INSUFFICIENT_DATA as there has not been a response in 2 weeks. If you are still experiencing this issue, please reopen and let us know if the bug is still present on the latest kernel.

(Please note: sometimes bugs get mistakenly closed during our mass closing. If you think your bug was closed in error please reopen)


Note You need to log in before you can comment on or make changes to this bug.