Bug 203787 - iwlwifi: no wireless connection when on battery
Summary: iwlwifi: no wireless connection when on battery
Status: CLOSED INVALID
Alias: None
Product: Drivers
Classification: Unclassified
Component: network-wireless (show other bugs)
Hardware: x86-64 Linux
: P1 normal
Assignee: DO NOT USE - assign "network-wireless-intel" component instead
URL:
Keywords:
Depends on:
Blocks:
 
Reported: 2019-06-03 09:25 UTC by Jannis Vajen
Modified: 2019-06-05 15:44 UTC (History)
1 user (show)

See Also:
Kernel Version: 5.1.5-050105-generic
Subsystem:
Regression: No
Bisected commit-id:


Attachments
trace-cmd record -e iwlwifi (1.39 MB, application/gzip)
2019-06-03 09:25 UTC, Jannis Vajen
Details
output of lscpi in AC mode (444.00 KB, text/plain)
2019-06-03 11:19 UTC, Jannis Vajen
Details
ouput of lspci in battery mode (443.98 KB, text/plain)
2019-06-03 11:19 UTC, Jannis Vajen
Details
trace-cmd record -e iwlwifi -e mac80211 -e cfg80211 -e iwlwifi_msg (1.38 MB, application/gzip)
2019-06-03 14:42 UTC, Jannis Vajen
Details
trace-cmd record -e iwlwifi -e mac80211 -e cfg80211 -e iwlwifi_msg (1.58 MB, application/gzip)
2019-06-03 19:20 UTC, Jannis Vajen
Details
debug patch (828 bytes, patch)
2019-06-03 20:38 UTC, Emmanuel Grumbach
Details | Diff
trace-cmd record -e iwlwifi -e mac80211 -e cfg80211 -e iwlwifi_msg (debug) (1.80 MB, application/gzip)
2019-06-04 13:59 UTC, Jannis Vajen
Details
dmesg (modprobe -r iwlwifi) (164.40 KB, text/plain)
2019-06-04 21:34 UTC, Jannis Vajen
Details
dmesg (boot in battery mode, modprobe -r iwlwifi, modprobe iwlwifi) (141.92 KB, text/plain)
2019-06-04 21:41 UTC, Jannis Vajen
Details

Description Jannis Vajen 2019-06-03 09:25:45 UTC
Created attachment 283043 [details]
trace-cmd record -e iwlwifi

When I disconnect my AC adapter or run `sudo tlp bat` to switch to battery mode my wifi stops working. Reconnecting the AC adapter or running `sudo tlp ac` returns the wifi adapter to a functioning state.

I use the default TLP configuration without modifications and at first I was thinking that this is a bug in TLP. The FAQs already have an entry about this and state that this is due to the "kernel driver does not implement power saving properly" [1]. This is why I'm reporting the issue here.

I'm using Ubuntu Disco and I remember this working in previous Ubuntu versions / kernels. This happened on kernel 5.0.0-15-generic but also on the 5.1.5-050105-generic mainline kernel. I already created a ticket at https://bugs.launchpad.net/ubuntu/+source/linux-firmware/+bug/1830883 but was advised to file a report here.

Interestingly, running `sudo iwconig wlp2s0 power on` to switch on the device's power management doesn't change wireless connectivity at all.

Below is a snippet of /var/log/syslog at the moment of switching TLP to battery mode. Battery mode was activated at 11:25 and AC mode was re-activated around 11:27.


[1] https://linrunner.de/en/tlp/docs/tlp-faq.html#radios

--- 

Linux x1cbn 5.1.5-050105-generic #201905251333 SMP Sat May 25 17:36:23 UTC 2019 x86_64 x86_64 x86_64 GNU/Linux

processor	: 7
vendor_id	: GenuineIntel
cpu family	: 6
model		: 142
model name	: Intel(R) Core(TM) i7-8550U CPU @ 1.80GHz
stepping	: 10
microcode	: 0xb4
cpu MHz		: 800.036
cache size	: 8192 KB
physical id	: 0
siblings	: 8
core id		: 3
cpu cores	: 4
apicid		: 7
initial apicid	: 7
fpu		: yes
fpu_exception	: yes
cpuid level	: 22
wp		: yes
flags		: fpu vme de pse tsc msr pae mce cx8 apic sep mtrr pge mca cmov pat pse36 clflush dts acpi mmx fxsr sse sse2 ss ht tm pbe syscall nx pdpe1gb rdtscp lm constant_tsc art arch_perfmon pebs bts rep_good nopl xtopology nonstop_tsc cpuid aperfmperf tsc_known_freq pni pclmulqdq dtes64 monitor ds_cpl vmx est tm2 ssse3 sdbg fma cx16 xtpr pdcm pcid sse4_1 sse4_2 x2apic movbe popcnt tsc_deadline_timer aes xsave avx f16c rdrand lahf_lm abm 3dnowprefetch cpuid_fault epb invpcid_single pti ssbd ibrs ibpb stibp tpr_shadow vnmi flexpriority ept vpid ept_ad fsgsbase tsc_adjust bmi1 avx2 smep bmi2 erms invpcid mpx rdseed adx smap clflushopt intel_pt xsaveopt xsavec xgetbv1 xsaves dtherm ida arat pln pts hwp hwp_notify hwp_act_window hwp_epp md_clear flush_l1d
bugs		: cpu_meltdown spectre_v1 spectre_v2 spec_store_bypass l1tf mds
bogomips	: 3984.00
clflush size	: 64
cache_alignment	: 64
address sizes	: 39 bits physical, 48 bits virtual
power management:

May 29 11:23:40 x1cbn systemd-resolved[1037]: message repeated 3 times: [ Server returned error NXDOMAIN, mitigating potential DNS violation DVE-2018-0001, retrying transaction with reduced feature level UDP.]
May 29 11:23:41 x1cbn colord[1285]: failed to get session [pid 1158]: Keine Daten verfügbar
May 29 11:25:37 x1cbn wpa_supplicant[1144]: wlp2s0: CTRL-EVENT-SCAN-FAILED ret=-110
May 29 11:25:37 x1cbn geoclue[1741]: WiFi scan failed
May 29 11:25:37 x1cbn kernel: [ 4645.773659] iwlwifi 0000:02:00.0: Error sending SCAN_REQ_UMAC: time out after 2000ms.
May 29 11:25:37 x1cbn kernel: [ 4645.773670] iwlwifi 0000:02:00.0: Current CMD queue read_ptr 229 write_ptr 230
May 29 11:25:37 x1cbn kernel: [ 4645.775119] iwlwifi 0000:02:00.0: Start IWL Error Log Dump:
May 29 11:25:37 x1cbn kernel: [ 4645.775125] iwlwifi 0000:02:00.0: Status: 0x00000100, count: 6
May 29 11:25:37 x1cbn kernel: [ 4645.775130] iwlwifi 0000:02:00.0: Loaded firmware version: 36.9f0a2d68.0
May 29 11:25:37 x1cbn kernel: [ 4645.775137] iwlwifi 0000:02:00.0: 0x00000084 | NMI_INTERRUPT_UNKNOWN
May 29 11:25:37 x1cbn kernel: [ 4645.775142] iwlwifi 0000:02:00.0: 0x00000200 | trm_hw_status0
May 29 11:25:37 x1cbn kernel: [ 4645.775147] iwlwifi 0000:02:00.0: 0x00000000 | trm_hw_status1
May 29 11:25:37 x1cbn kernel: [ 4645.775152] iwlwifi 0000:02:00.0: 0x0002485C | branchlink2
May 29 11:25:37 x1cbn kernel: [ 4645.775157] iwlwifi 0000:02:00.0: 0x0003A7CA | interruptlink1
May 29 11:25:37 x1cbn kernel: [ 4645.775161] iwlwifi 0000:02:00.0: 0x00005D56 | interruptlink2
May 29 11:25:37 x1cbn kernel: [ 4645.775166] iwlwifi 0000:02:00.0: 0x00000000 | data1
May 29 11:25:37 x1cbn kernel: [ 4645.775171] iwlwifi 0000:02:00.0: 0x00000080 | data2
May 29 11:25:37 x1cbn kernel: [ 4645.775175] iwlwifi 0000:02:00.0: 0x07A30000 | data3
May 29 11:25:37 x1cbn kernel: [ 4645.775180] iwlwifi 0000:02:00.0: 0xBBC109E2 | beacon time
May 29 11:25:37 x1cbn kernel: [ 4645.775185] iwlwifi 0000:02:00.0: 0x10C87621 | tsf low
May 29 11:25:37 x1cbn kernel: [ 4645.775190] iwlwifi 0000:02:00.0: 0x0000019F | tsf hi
May 29 11:25:37 x1cbn kernel: [ 4645.775194] iwlwifi 0000:02:00.0: 0x00000000 | time gp1
May 29 11:25:37 x1cbn kernel: [ 4645.775199] iwlwifi 0000:02:00.0: 0xCC95B750 | time gp2
May 29 11:25:37 x1cbn kernel: [ 4645.775204] iwlwifi 0000:02:00.0: 0x00000001 | uCode revision type
May 29 11:25:37 x1cbn kernel: [ 4645.775209] iwlwifi 0000:02:00.0: 0x00000024 | uCode version major
May 29 11:25:37 x1cbn kernel: [ 4645.775214] iwlwifi 0000:02:00.0: 0x9F0A2D68 | uCode version minor
May 29 11:25:37 x1cbn kernel: [ 4645.775219] iwlwifi 0000:02:00.0: 0x00000230 | hw version
May 29 11:25:37 x1cbn kernel: [ 4645.775224] iwlwifi 0000:02:00.0: 0x00080000 | board version
May 29 11:25:37 x1cbn kernel: [ 4645.775228] iwlwifi 0000:02:00.0: 0x0000001C | hcmd
May 29 11:25:37 x1cbn kernel: [ 4645.775233] iwlwifi 0000:02:00.0: 0x24022000 | isr0
May 29 11:25:37 x1cbn kernel: [ 4645.775238] iwlwifi 0000:02:00.0: 0x01800000 | isr1
May 29 11:25:37 x1cbn kernel: [ 4645.775242] iwlwifi 0000:02:00.0: 0x0800180A | isr2
May 29 11:25:37 x1cbn kernel: [ 4645.775247] iwlwifi 0000:02:00.0: 0x00417CC0 | isr3
May 29 11:25:37 x1cbn kernel: [ 4645.775252] iwlwifi 0000:02:00.0: 0x00000000 | isr4
May 29 11:25:37 x1cbn kernel: [ 4645.775257] iwlwifi 0000:02:00.0: 0x0AD9001C | last cmd Id
May 29 11:25:37 x1cbn kernel: [ 4645.775262] iwlwifi 0000:02:00.0: 0x00000000 | wait_event
May 29 11:25:37 x1cbn kernel: [ 4645.775267] iwlwifi 0000:02:00.0: 0x00004288 | l2p_control
May 29 11:25:37 x1cbn kernel: [ 4645.775271] iwlwifi 0000:02:00.0: 0x00018034 | l2p_duration
May 29 11:25:37 x1cbn kernel: [ 4645.775276] iwlwifi 0000:02:00.0: 0x000003BF | l2p_mhvalid
May 29 11:25:37 x1cbn kernel: [ 4645.775281] iwlwifi 0000:02:00.0: 0x000000E7 | l2p_addr_match
May 29 11:25:37 x1cbn kernel: [ 4645.775286] iwlwifi 0000:02:00.0: 0x0000000D | lmpm_pmg_sel
May 29 11:25:37 x1cbn kernel: [ 4645.775291] iwlwifi 0000:02:00.0: 0x04120134 | timestamp
May 29 11:25:37 x1cbn kernel: [ 4645.775296] iwlwifi 0000:02:00.0: 0x00340050 | flow_handler
May 29 11:25:37 x1cbn kernel: [ 4645.775563] iwlwifi 0000:02:00.0: Start IWL Error Log Dump:
May 29 11:25:37 x1cbn kernel: [ 4645.775572] iwlwifi 0000:02:00.0: Status: 0x00000100, count: 7
May 29 11:25:37 x1cbn kernel: [ 4645.775578] iwlwifi 0000:02:00.0: 0x00000070 | NMI_INTERRUPT_LMAC_FATAL
May 29 11:25:37 x1cbn kernel: [ 4645.775583] iwlwifi 0000:02:00.0: 0x00000000 | umac branchlink1
May 29 11:25:37 x1cbn kernel: [ 4645.775589] iwlwifi 0000:02:00.0: 0xC00868A4 | umac branchlink2
May 29 11:25:37 x1cbn kernel: [ 4645.775594] iwlwifi 0000:02:00.0: 0xC0083A94 | umac interruptlink1
May 29 11:25:37 x1cbn kernel: [ 4645.775600] iwlwifi 0000:02:00.0: 0xC0083A94 | umac interruptlink2
May 29 11:25:37 x1cbn kernel: [ 4645.775604] iwlwifi 0000:02:00.0: 0x00000800 | umac data1
May 29 11:25:37 x1cbn kernel: [ 4645.775609] iwlwifi 0000:02:00.0: 0xC0083A94 | umac data2
May 29 11:25:37 x1cbn kernel: [ 4645.775612] iwlwifi 0000:02:00.0: 0xDEADBEEF | umac data3
May 29 11:25:37 x1cbn kernel: [ 4645.775616] iwlwifi 0000:02:00.0: 0x00000024 | umac major
May 29 11:25:37 x1cbn kernel: [ 4645.775619] iwlwifi 0000:02:00.0: 0x9F0A2D68 | umac minor
May 29 11:25:37 x1cbn kernel: [ 4645.775622] iwlwifi 0000:02:00.0: 0xC088628C | frame pointer
May 29 11:25:37 x1cbn kernel: [ 4645.775625] iwlwifi 0000:02:00.0: 0xC088628C | stack pointer
May 29 11:25:37 x1cbn kernel: [ 4645.775628] iwlwifi 0000:02:00.0: 0x00E5010D | last host cmd
May 29 11:25:37 x1cbn kernel: [ 4645.775632] iwlwifi 0000:02:00.0: 0x00000000 | isr status reg
May 29 11:25:37 x1cbn kernel: [ 4645.775651] ieee80211 phy0: Hardware restart was requested
May 29 11:25:37 x1cbn kernel: [ 4645.775664] iwlwifi 0000:02:00.0: Scan failed! ret -110
May 29 11:25:38 x1cbn kernel: [ 4646.457858] iwlwifi 0000:02:00.0: Queue 0 is active on fifo 7 and stuck for 2500 ms. SW [229, 230] HW [230, 230] FH TRB=0x07000e5
May 29 11:25:44 x1cbn kernel: [ 4652.749901] iwlwifi 0000:02:00.0: Error sending STATISTICS_CMD: time out after 2000ms.
May 29 11:25:44 x1cbn kernel: [ 4652.749911] iwlwifi 0000:02:00.0: Current CMD queue read_ptr 79 write_ptr 80
May 29 11:25:44 x1cbn kernel: [ 4652.998492] iwlwifi 0000:02:00.0: Start IWL Error Log Dump:
May 29 11:25:44 x1cbn kernel: [ 4652.998496] iwlwifi 0000:02:00.0: Status: 0x00000100, count: 6
May 29 11:25:44 x1cbn kernel: [ 4652.998497] iwlwifi 0000:02:00.0: Loaded firmware version: 36.9f0a2d68.0
May 29 11:25:44 x1cbn kernel: [ 4652.998499] iwlwifi 0000:02:00.0: 0x00000034 | NMI_INTERRUPT_WDG
May 29 11:25:44 x1cbn kernel: [ 4652.998500] iwlwifi 0000:02:00.0: 0x000002A0 | trm_hw_status0
May 29 11:25:44 x1cbn kernel: [ 4652.998502] iwlwifi 0000:02:00.0: 0x00000000 | trm_hw_status1
May 29 11:25:44 x1cbn kernel: [ 4652.998503] iwlwifi 0000:02:00.0: 0x0002485C | branchlink2
May 29 11:25:44 x1cbn kernel: [ 4652.998504] iwlwifi 0000:02:00.0: 0x0003A7CA | interruptlink1
May 29 11:25:44 x1cbn kernel: [ 4652.998505] iwlwifi 0000:02:00.0: 0x0004B2B6 | interruptlink2
May 29 11:25:44 x1cbn kernel: [ 4652.998506] iwlwifi 0000:02:00.0: 0x00000000 | data1
May 29 11:25:44 x1cbn kernel: [ 4652.998507] iwlwifi 0000:02:00.0: 0x00000002 | data2
May 29 11:25:44 x1cbn kernel: [ 4652.998508] iwlwifi 0000:02:00.0: 0x03230000 | data3
May 29 11:25:44 x1cbn kernel: [ 4652.998509] iwlwifi 0000:02:00.0: 0x0A4075B2 | beacon time
May 29 11:25:44 x1cbn kernel: [ 4652.998511] iwlwifi 0000:02:00.0: 0x1117149E | tsf low
May 29 11:25:44 x1cbn kernel: [ 4652.998512] iwlwifi 0000:02:00.0: 0x0000019F | tsf hi
May 29 11:25:44 x1cbn kernel: [ 4652.998513] iwlwifi 0000:02:00.0: 0x00000000 | time gp1
May 29 11:25:44 x1cbn kernel: [ 4652.998514] iwlwifi 0000:02:00.0: 0x0040DBE6 | time gp2
May 29 11:25:44 x1cbn kernel: [ 4652.998515] iwlwifi 0000:02:00.0: 0x00000001 | uCode revision type
May 29 11:25:44 x1cbn kernel: [ 4652.998516] iwlwifi 0000:02:00.0: 0x00000024 | uCode version major
May 29 11:25:44 x1cbn kernel: [ 4652.998517] iwlwifi 0000:02:00.0: 0x9F0A2D68 | uCode version minor
May 29 11:25:44 x1cbn kernel: [ 4652.998518] iwlwifi 0000:02:00.0: 0x00000230 | hw version
May 29 11:25:44 x1cbn kernel: [ 4652.998520] iwlwifi 0000:02:00.0: 0x00080000 | board version
May 29 11:25:44 x1cbn kernel: [ 4652.998521] iwlwifi 0000:02:00.0: 0x004F019C | hcmd
May 29 11:25:44 x1cbn kernel: [ 4652.998522] iwlwifi 0000:02:00.0: 0xA7F12080 | isr0
May 29 11:25:44 x1cbn kernel: [ 4652.998523] iwlwifi 0000:02:00.0: 0x00080000 | isr1
May 29 11:25:44 x1cbn kernel: [ 4652.998524] iwlwifi 0000:02:00.0: 0x00201802 | isr2
May 29 11:25:44 x1cbn kernel: [ 4652.998525] iwlwifi 0000:02:00.0: 0x40417DC5 | isr3
May 29 11:25:44 x1cbn kernel: [ 4652.998526] iwlwifi 0000:02:00.0: 0x00000000 | isr4
May 29 11:25:44 x1cbn kernel: [ 4652.998527] iwlwifi 0000:02:00.0: 0x004F019C | last cmd Id
May 29 11:25:44 x1cbn kernel: [ 4652.998528] iwlwifi 0000:02:00.0: 0x00000000 | wait_event
May 29 11:25:44 x1cbn kernel: [ 4652.998529] iwlwifi 0000:02:00.0: 0x00004A88 | l2p_control
May 29 11:25:44 x1cbn kernel: [ 4652.998531] iwlwifi 0000:02:00.0: 0x00018020 | l2p_duration
May 29 11:25:44 x1cbn kernel: [ 4652.998532] iwlwifi 0000:02:00.0: 0x00000000 | l2p_mhvalid
May 29 11:25:44 x1cbn kernel: [ 4652.998533] iwlwifi 0000:02:00.0: 0x000000E7 | l2p_addr_match
May 29 11:25:44 x1cbn kernel: [ 4652.998534] iwlwifi 0000:02:00.0: 0x0000000D | lmpm_pmg_sel
May 29 11:25:44 x1cbn kernel: [ 4652.998535] iwlwifi 0000:02:00.0: 0x04120134 | timestamp
May 29 11:25:44 x1cbn kernel: [ 4652.998536] iwlwifi 0000:02:00.0: 0x17BC4848 | flow_handler
May 29 11:25:44 x1cbn kernel: [ 4652.998736] iwlwifi 0000:02:00.0: Start IWL Error Log Dump:
May 29 11:25:44 x1cbn kernel: [ 4652.998737] iwlwifi 0000:02:00.0: Status: 0x00000100, count: 7
May 29 11:25:44 x1cbn kernel: [ 4652.998738] iwlwifi 0000:02:00.0: 0x00000034 | NMI_INTERRUPT_WDG
May 29 11:25:44 x1cbn kernel: [ 4652.998739] iwlwifi 0000:02:00.0: 0x00000000 | umac branchlink1
May 29 11:25:44 x1cbn kernel: [ 4652.998740] iwlwifi 0000:02:00.0: 0xC00868A4 | umac branchlink2
May 29 11:25:44 x1cbn kernel: [ 4652.998741] iwlwifi 0000:02:00.0: 0xC0083A94 | umac interruptlink1
May 29 11:25:44 x1cbn kernel: [ 4652.998742] iwlwifi 0000:02:00.0: 0xC0083A94 | umac interruptlink2
May 29 11:25:44 x1cbn kernel: [ 4652.998744] iwlwifi 0000:02:00.0: 0x00000400 | umac data1
May 29 11:25:44 x1cbn kernel: [ 4652.998745] iwlwifi 0000:02:00.0: 0xC0083A94 | umac data2
May 29 11:25:44 x1cbn kernel: [ 4652.998746] iwlwifi 0000:02:00.0: 0xDEADBEEF | umac data3
May 29 11:25:44 x1cbn kernel: [ 4652.998747] iwlwifi 0000:02:00.0: 0x00000024 | umac major
May 29 11:25:44 x1cbn kernel: [ 4652.998748] iwlwifi 0000:02:00.0: 0x9F0A2D68 | umac minor
May 29 11:25:44 x1cbn kernel: [ 4652.998749] iwlwifi 0000:02:00.0: 0xC088628C | frame pointer
May 29 11:25:44 x1cbn kernel: [ 4652.998750] iwlwifi 0000:02:00.0: 0xC088628C | stack pointer
May 29 11:25:44 x1cbn kernel: [ 4652.998751] iwlwifi 0000:02:00.0: 0x004F019C | last host cmd
May 29 11:25:44 x1cbn kernel: [ 4652.998752] iwlwifi 0000:02:00.0: 0x00000000 | isr status reg
May 29 11:25:44 x1cbn kernel: [ 4652.998756] ieee80211 phy0: Hardware restart was requested
May 29 11:25:45 x1cbn kernel: [ 4653.344961] iwlwifi 0000:02:00.0: Queue 0 is active on fifo 7 and stuck for 2500 ms. SW [79, 80] HW [80, 80] FH TRB=0x070004f
May 29 11:25:50 x1cbn kernel: [ 4658.738090] iwlwifi 0000:02:00.0: Error sending STATISTICS_CMD: time out after 2000ms.
May 29 11:25:50 x1cbn kernel: [ 4658.738103] iwlwifi 0000:02:00.0: Current CMD queue read_ptr 77 write_ptr 78
May 29 11:25:50 x1cbn kernel: [ 4658.986902] iwlwifi 0000:02:00.0: Start IWL Error Log Dump:
May 29 11:25:50 x1cbn kernel: [ 4658.986906] iwlwifi 0000:02:00.0: Status: 0x00000100, count: 6
May 29 11:25:50 x1cbn kernel: [ 4658.986908] iwlwifi 0000:02:00.0: Loaded firmware version: 36.9f0a2d68.0
May 29 11:25:50 x1cbn kernel: [ 4658.986910] iwlwifi 0000:02:00.0: 0x000022CE | ADVANCED_SYSASSERT
May 29 11:25:50 x1cbn kernel: [ 4658.986911] iwlwifi 0000:02:00.0: 0x059002A0 | trm_hw_status0
May 29 11:25:50 x1cbn kernel: [ 4658.986912] iwlwifi 0000:02:00.0: 0x00000000 | trm_hw_status1
May 29 11:25:50 x1cbn kernel: [ 4658.986913] iwlwifi 0000:02:00.0: 0x0002485C | branchlink2
May 29 11:25:50 x1cbn kernel: [ 4658.986915] iwlwifi 0000:02:00.0: 0x0003A7CA | interruptlink1
May 29 11:25:50 x1cbn kernel: [ 4658.986916] iwlwifi 0000:02:00.0: 0x00000000 | interruptlink2
May 29 11:25:50 x1cbn kernel: [ 4658.986917] iwlwifi 0000:02:00.0: 0x0000012C | data1
May 29 11:25:50 x1cbn kernel: [ 4658.986918] iwlwifi 0000:02:00.0: 0x03230000 | data2
May 29 11:25:50 x1cbn kernel: [ 4658.986919] iwlwifi 0000:02:00.0: 0xDEADBEEF | data3
May 29 11:25:50 x1cbn kernel: [ 4658.986921] iwlwifi 0000:02:00.0: 0x0B001DC0 | beacon time
May 29 11:25:50 x1cbn kernel: [ 4658.986922] iwlwifi 0000:02:00.0: 0x11899243 | tsf low
May 29 11:25:50 x1cbn kernel: [ 4658.986923] iwlwifi 0000:02:00.0: 0x0000019F | tsf hi
May 29 11:25:50 x1cbn kernel: [ 4658.986924] iwlwifi 0000:02:00.0: 0x00000000 | time gp1
May 29 11:25:50 x1cbn kernel: [ 4658.986925] iwlwifi 0000:02:00.0: 0x0044514F | time gp2
May 29 11:25:50 x1cbn kernel: [ 4658.986926] iwlwifi 0000:02:00.0: 0x00000001 | uCode revision type
May 29 11:25:50 x1cbn kernel: [ 4658.986928] iwlwifi 0000:02:00.0: 0x00000024 | uCode version major
May 29 11:25:50 x1cbn kernel: [ 4658.986929] iwlwifi 0000:02:00.0: 0x9F0A2D68 | uCode version minor
May 29 11:25:50 x1cbn kernel: [ 4658.986930] iwlwifi 0000:02:00.0: 0x00000230 | hw version
May 29 11:25:50 x1cbn kernel: [ 4658.986931] iwlwifi 0000:02:00.0: 0x00080000 | board version
May 29 11:25:50 x1cbn kernel: [ 4658.986932] iwlwifi 0000:02:00.0: 0x0000001C | hcmd
May 29 11:25:50 x1cbn kernel: [ 4658.986933] iwlwifi 0000:02:00.0: 0xA3F13840 | isr0
May 29 11:25:50 x1cbn kernel: [ 4658.986935] iwlwifi 0000:02:00.0: 0x00050000 | isr1
May 29 11:25:50 x1cbn kernel: [ 4658.986936] iwlwifi 0000:02:00.0: 0x00001802 | isr2
May 29 11:25:50 x1cbn kernel: [ 4658.986937] iwlwifi 0000:02:00.0: 0x404155C5 | isr3
May 29 11:25:50 x1cbn kernel: [ 4658.986938] iwlwifi 0000:02:00.0: 0x00000000 | isr4
May 29 11:25:50 x1cbn kernel: [ 4658.986939] iwlwifi 0000:02:00.0: 0x004D019C | last cmd Id
May 29 11:25:50 x1cbn kernel: [ 4658.986940] iwlwifi 0000:02:00.0: 0x00000000 | wait_event
May 29 11:25:50 x1cbn kernel: [ 4658.986941] iwlwifi 0000:02:00.0: 0x00004A88 | l2p_control
May 29 11:25:50 x1cbn kernel: [ 4658.986943] iwlwifi 0000:02:00.0: 0x00018020 | l2p_duration
May 29 11:25:50 x1cbn kernel: [ 4658.986944] iwlwifi 0000:02:00.0: 0x000003BF | l2p_mhvalid
May 29 11:25:50 x1cbn kernel: [ 4658.986945] iwlwifi 0000:02:00.0: 0x000000E7 | l2p_addr_match
May 29 11:25:50 x1cbn kernel: [ 4658.986946] iwlwifi 0000:02:00.0: 0x0000000D | lmpm_pmg_sel
May 29 11:25:50 x1cbn kernel: [ 4658.986947] iwlwifi 0000:02:00.0: 0x04120134 | timestamp
May 29 11:25:50 x1cbn kernel: [ 4658.986948] iwlwifi 0000:02:00.0: 0x18004040 | flow_handler
May 29 11:25:50 x1cbn kernel: [ 4658.987308] iwlwifi 0000:02:00.0: Start IWL Error Log Dump:
May 29 11:25:50 x1cbn kernel: [ 4658.987309] iwlwifi 0000:02:00.0: Status: 0x00000100, count: 7
May 29 11:25:50 x1cbn kernel: [ 4658.987310] iwlwifi 0000:02:00.0: 0x00000070 | NMI_INTERRUPT_LMAC_FATAL
May 29 11:25:50 x1cbn kernel: [ 4658.987312] iwlwifi 0000:02:00.0: 0x00000000 | umac branchlink1
May 29 11:25:50 x1cbn kernel: [ 4658.987313] iwlwifi 0000:02:00.0: 0xC00868A4 | umac branchlink2
May 29 11:25:50 x1cbn kernel: [ 4658.987314] iwlwifi 0000:02:00.0: 0xC0083A94 | umac interruptlink1
May 29 11:25:50 x1cbn kernel: [ 4658.987315] iwlwifi 0000:02:00.0: 0xC0083A94 | umac interruptlink2
May 29 11:25:50 x1cbn kernel: [ 4658.987316] iwlwifi 0000:02:00.0: 0x00000800 | umac data1
May 29 11:25:50 x1cbn kernel: [ 4658.987318] iwlwifi 0000:02:00.0: 0xC0083A94 | umac data2
May 29 11:25:50 x1cbn kernel: [ 4658.987319] iwlwifi 0000:02:00.0: 0xDEADBEEF | umac data3
May 29 11:25:50 x1cbn kernel: [ 4658.987320] iwlwifi 0000:02:00.0: 0x00000024 | umac major
May 29 11:25:50 x1cbn kernel: [ 4658.987321] iwlwifi 0000:02:00.0: 0x9F0A2D68 | umac minor
May 29 11:25:50 x1cbn kernel: [ 4658.987322] iwlwifi 0000:02:00.0: 0xC088628C | frame pointer
May 29 11:25:50 x1cbn kernel: [ 4658.987324] iwlwifi 0000:02:00.0: 0xC088628C | stack pointer
May 29 11:25:50 x1cbn kernel: [ 4658.987325] iwlwifi 0000:02:00.0: 0x004D019C | last host cmd
May 29 11:25:50 x1cbn kernel: [ 4658.987326] iwlwifi 0000:02:00.0: 0x00000000 | isr status reg
May 29 11:25:50 x1cbn kernel: [ 4658.987330] ieee80211 phy0: Hardware restart was requested
May 29 11:25:51 x1cbn kernel: [ 4659.454041] iwlwifi 0000:02:00.0: Queue 0 is active on fifo 7 and stuck for 2500 ms. SW [77, 78] HW [78, 78] FH TRB=0x070004d
May 29 11:25:56 x1cbn kernel: [ 4664.750307] iwlwifi 0000:02:00.0: Error sending STATISTICS_CMD: time out after 2000ms.
May 29 11:25:56 x1cbn kernel: [ 4664.750318] iwlwifi 0000:02:00.0: Current CMD queue read_ptr 76 write_ptr 77
May 29 11:25:56 x1cbn kernel: [ 4664.998635] iwlwifi 0000:02:00.0: Start IWL Error Log Dump:
May 29 11:25:56 x1cbn kernel: [ 4664.998643] iwlwifi 0000:02:00.0: Status: 0x00000100, count: 6
May 29 11:25:56 x1cbn kernel: [ 4664.998646] iwlwifi 0000:02:00.0: Loaded firmware version: 36.9f0a2d68.0
May 29 11:25:56 x1cbn kernel: [ 4664.998649] iwlwifi 0000:02:00.0: 0x000022CE | ADVANCED_SYSASSERT
May 29 11:25:56 x1cbn kernel: [ 4664.998655] iwlwifi 0000:02:00.0: 0x059002A3 | trm_hw_status0
May 29 11:25:56 x1cbn kernel: [ 4664.998657] iwlwifi 0000:02:00.0: 0x00000000 | trm_hw_status1
May 29 11:25:56 x1cbn kernel: [ 4664.998660] iwlwifi 0000:02:00.0: 0x0002485C | branchlink2
May 29 11:25:56 x1cbn kernel: [ 4664.998662] iwlwifi 0000:02:00.0: 0x0003A7CA | interruptlink1
May 29 11:25:56 x1cbn kernel: [ 4664.998664] iwlwifi 0000:02:00.0: 0x00000000 | interruptlink2
May 29 11:25:56 x1cbn kernel: [ 4664.998666] iwlwifi 0000:02:00.0: 0x0000012C | data1
May 29 11:25:56 x1cbn kernel: [ 4664.998669] iwlwifi 0000:02:00.0: 0x03230000 | data2
May 29 11:25:56 x1cbn kernel: [ 4664.998671] iwlwifi 0000:02:00.0: 0xDEADBEEF | data3
May 29 11:25:56 x1cbn kernel: [ 4664.998672] iwlwifi 0000:02:00.0: 0x05418A0D | beacon time
May 29 11:25:56 x1cbn kernel: [ 4664.998674] iwlwifi 0000:02:00.0: 0x11C9C791 | tsf low
May 29 11:25:56 x1cbn kernel: [ 4664.998675] iwlwifi 0000:02:00.0: 0x0000019F | tsf hi
May 29 11:25:56 x1cbn kernel: [ 4664.998676] iwlwifi 0000:02:00.0: 0x00000000 | time gp1
May 29 11:25:56 x1cbn kernel: [ 4664.998678] iwlwifi 0000:02:00.0: 0x00210788 | time gp2
May 29 11:25:56 x1cbn kernel: [ 4664.998679] iwlwifi 0000:02:00.0: 0x00000001 | uCode revision type
May 29 11:25:56 x1cbn kernel: [ 4664.998680] iwlwifi 0000:02:00.0: 0x00000024 | uCode version major
May 29 11:25:56 x1cbn kernel: [ 4664.998682] iwlwifi 0000:02:00.0: 0x9F0A2D68 | uCode version minor
May 29 11:25:56 x1cbn kernel: [ 4664.998683] iwlwifi 0000:02:00.0: 0x00000230 | hw version
May 29 11:25:56 x1cbn kernel: [ 4664.998684] iwlwifi 0000:02:00.0: 0x00080000 | board version
May 29 11:25:56 x1cbn kernel: [ 4664.998685] iwlwifi 0000:02:00.0: 0x0A16001C | hcmd
May 29 11:25:56 x1cbn kernel: [ 4664.998687] iwlwifi 0000:02:00.0: 0xA7F93802 | isr0
May 29 11:25:56 x1cbn kernel: [ 4664.998688] iwlwifi 0000:02:00.0: 0x000D0000 | isr1
May 29 11:25:56 x1cbn kernel: [ 4664.998689] iwlwifi 0000:02:00.0: 0x0000180A | isr2
May 29 11:25:56 x1cbn kernel: [ 4664.998690] iwlwifi 0000:02:00.0: 0x404109C5 | isr3
May 29 11:25:56 x1cbn kernel: [ 4664.998691] iwlwifi 0000:02:00.0: 0x00000000 | isr4
May 29 11:25:56 x1cbn kernel: [ 4664.998692] iwlwifi 0000:02:00.0: 0x0A15001C | last cmd Id
May 29 11:25:56 x1cbn kernel: [ 4664.998694] iwlwifi 0000:02:00.0: 0x00000000 | wait_event
May 29 11:25:56 x1cbn kernel: [ 4664.998695] iwlwifi 0000:02:00.0: 0x00004A88 | l2p_control
May 29 11:25:56 x1cbn kernel: [ 4664.998696] iwlwifi 0000:02:00.0: 0x00019C20 | l2p_duration
May 29 11:25:56 x1cbn kernel: [ 4664.998697] iwlwifi 0000:02:00.0: 0x000003BF | l2p_mhvalid
May 29 11:25:56 x1cbn kernel: [ 4664.998699] iwlwifi 0000:02:00.0: 0x000000E7 | l2p_addr_match
May 29 11:25:56 x1cbn kernel: [ 4664.998700] iwlwifi 0000:02:00.0: 0x0000000D | lmpm_pmg_sel
May 29 11:25:56 x1cbn kernel: [ 4664.998701] iwlwifi 0000:02:00.0: 0x04120134 | timestamp
May 29 11:25:56 x1cbn kernel: [ 4664.998702] iwlwifi 0000:02:00.0: 0x177C4040 | flow_handler
May 29 11:25:56 x1cbn kernel: [ 4664.998767] iwlwifi 0000:02:00.0: Start IWL Error Log Dump:
May 29 11:25:56 x1cbn kernel: [ 4664.998768] iwlwifi 0000:02:00.0: Status: 0x00000100, count: 7
May 29 11:25:56 x1cbn kernel: [ 4664.998770] iwlwifi 0000:02:00.0: 0x00000070 | NMI_INTERRUPT_LMAC_FATAL
May 29 11:25:56 x1cbn kernel: [ 4664.998771] iwlwifi 0000:02:00.0: 0x00000000 | umac branchlink1
May 29 11:25:56 x1cbn kernel: [ 4664.998772] iwlwifi 0000:02:00.0: 0xC00868A4 | umac branchlink2
May 29 11:25:56 x1cbn kernel: [ 4664.998774] iwlwifi 0000:02:00.0: 0xC0083A94 | umac interruptlink1
May 29 11:25:56 x1cbn kernel: [ 4664.998775] iwlwifi 0000:02:00.0: 0xC0083A94 | umac interruptlink2
May 29 11:25:56 x1cbn kernel: [ 4664.998776] iwlwifi 0000:02:00.0: 0x00000800 | umac data1
May 29 11:25:56 x1cbn kernel: [ 4664.998777] iwlwifi 0000:02:00.0: 0xC0083A94 | umac data2
May 29 11:25:56 x1cbn kernel: [ 4664.998778] iwlwifi 0000:02:00.0: 0xDEADBEEF | umac data3
May 29 11:25:56 x1cbn kernel: [ 4664.998780] iwlwifi 0000:02:00.0: 0x00000024 | umac major
May 29 11:25:56 x1cbn kernel: [ 4664.998781] iwlwifi 0000:02:00.0: 0x9F0A2D68 | umac minor
May 29 11:25:56 x1cbn kernel: [ 4664.998782] iwlwifi 0000:02:00.0: 0xC088628C | frame pointer
May 29 11:25:56 x1cbn kernel: [ 4664.998783] iwlwifi 0000:02:00.0: 0xC088628C | stack pointer
May 29 11:25:56 x1cbn kernel: [ 4664.998784] iwlwifi 0000:02:00.0: 0x004B012C | last host cmd
May 29 11:25:56 x1cbn kernel: [ 4664.998786] iwlwifi 0000:02:00.0: 0x00000000 | isr status reg
May 29 11:25:56 x1cbn kernel: [ 4664.998790] ieee80211 phy0: Hardware restart was requested
May 29 11:25:57 x1cbn kernel: [ 4665.484282] iwlwifi 0000:02:00.0: Queue 0 is active on fifo 7 and stuck for 2500 ms. SW [76, 77] HW [76, 77] FH TRB=0x070004c
May 29 11:25:59 x1cbn kernel: [ 4667.794364] iwlwifi 0000:02:00.0: Error sending STATISTICS_CMD: time out after 2000ms.
May 29 11:25:59 x1cbn kernel: [ 4667.794373] iwlwifi 0000:02:00.0: Current CMD queue read_ptr 76 write_ptr 77
May 29 11:25:59 x1cbn kernel: [ 4667.795823] iwlwifi 0000:02:00.0: Start IWL Error Log Dump:
May 29 11:25:59 x1cbn kernel: [ 4667.795829] iwlwifi 0000:02:00.0: Status: 0x00000100, count: 6
May 29 11:25:59 x1cbn kernel: [ 4667.795833] iwlwifi 0000:02:00.0: Loaded firmware version: 36.9f0a2d68.0
May 29 11:25:59 x1cbn kernel: [ 4667.795838] iwlwifi 0000:02:00.0: 0x00000084 | NMI_INTERRUPT_UNKNOWN
May 29 11:25:59 x1cbn kernel: [ 4667.795842] iwlwifi 0000:02:00.0: 0x200002A0 | trm_hw_status0
May 29 11:25:59 x1cbn kernel: [ 4667.795845] iwlwifi 0000:02:00.0: 0x00000000 | trm_hw_status1
May 29 11:25:59 x1cbn kernel: [ 4667.795849] iwlwifi 0000:02:00.0: 0x0002485C | branchlink2
May 29 11:25:59 x1cbn kernel: [ 4667.795852] iwlwifi 0000:02:00.0: 0x0003A7CA | interruptlink1
May 29 11:25:59 x1cbn kernel: [ 4667.795855] iwlwifi 0000:02:00.0: 0x00004DCA | interruptlink2
May 29 11:25:59 x1cbn kernel: [ 4667.795858] iwlwifi 0000:02:00.0: 0x00000000 | data1
May 29 11:25:59 x1cbn kernel: [ 4667.795861] iwlwifi 0000:02:00.0: 0x00000080 | data2
May 29 11:25:59 x1cbn kernel: [ 4667.795865] iwlwifi 0000:02:00.0: 0x03230000 | data3
May 29 11:25:59 x1cbn kernel: [ 4667.795868] iwlwifi 0000:02:00.0: 0x0500F7A0 | beacon time
May 29 11:25:59 x1cbn kernel: [ 4667.795871] iwlwifi 0000:02:00.0: 0x12187863 | tsf low
May 29 11:25:59 x1cbn kernel: [ 4667.795874] iwlwifi 0000:02:00.0: 0x0000019F | tsf hi
May 29 11:25:59 x1cbn kernel: [ 4667.795878] iwlwifi 0000:02:00.0: 0x00000000 | time gp1
May 29 11:25:59 x1cbn kernel: [ 4667.795881] iwlwifi 0000:02:00.0: 0x001FEE19 | time gp2
May 29 11:25:59 x1cbn kernel: [ 4667.795884] iwlwifi 0000:02:00.0: 0x00000001 | uCode revision type
May 29 11:25:59 x1cbn kernel: [ 4667.795888] iwlwifi 0000:02:00.0: 0x00000024 | uCode version major
May 29 11:25:59 x1cbn kernel: [ 4667.795891] iwlwifi 0000:02:00.0: 0x9F0A2D68 | uCode version minor
May 29 11:25:59 x1cbn kernel: [ 4667.795894] iwlwifi 0000:02:00.0: 0x00000230 | hw version
May 29 11:25:59 x1cbn kernel: [ 4667.795897] iwlwifi 0000:02:00.0: 0x00080000 | board version
May 29 11:25:59 x1cbn kernel: [ 4667.795900] iwlwifi 0000:02:00.0: 0x0A25001C | hcmd
May 29 11:25:59 x1cbn kernel: [ 4667.795903] iwlwifi 0000:02:00.0: 0x02212000 | isr0
May 29 11:25:59 x1cbn kernel: [ 4667.795907] iwlwifi 0000:02:00.0: 0x00000000 | isr1
May 29 11:25:59 x1cbn kernel: [ 4667.795910] iwlwifi 0000:02:00.0: 0x00001802 | isr2
May 29 11:25:59 x1cbn kernel: [ 4667.795913] iwlwifi 0000:02:00.0: 0x00417C80 | isr3
May 29 11:25:59 x1cbn kernel: [ 4667.795916] iwlwifi 0000:02:00.0: 0x00000000 | isr4
May 29 11:25:59 x1cbn kernel: [ 4667.795919] iwlwifi 0000:02:00.0: 0x0A25001C | last cmd Id
May 29 11:25:59 x1cbn kernel: [ 4667.795922] iwlwifi 0000:02:00.0: 0x00000000 | wait_event
May 29 11:25:59 x1cbn kernel: [ 4667.795925] iwlwifi 0000:02:00.0: 0x00004A88 | l2p_control
May 29 11:25:59 x1cbn kernel: [ 4667.795928] iwlwifi 0000:02:00.0: 0x00018020 | l2p_duration
May 29 11:25:59 x1cbn kernel: [ 4667.795932] iwlwifi 0000:02:00.0: 0x000003BF | l2p_mhvalid
May 29 11:25:59 x1cbn kernel: [ 4667.795935] iwlwifi 0000:02:00.0: 0x000000E7 | l2p_addr_match
May 29 11:25:59 x1cbn kernel: [ 4667.795938] iwlwifi 0000:02:00.0: 0x0000000D | lmpm_pmg_sel
May 29 11:25:59 x1cbn kernel: [ 4667.795941] iwlwifi 0000:02:00.0: 0x04120134 | timestamp
May 29 11:25:59 x1cbn kernel: [ 4667.795944] iwlwifi 0000:02:00.0: 0x17F44040 | flow_handler
May 29 11:25:59 x1cbn kernel: [ 4667.796319] iwlwifi 0000:02:00.0: Start IWL Error Log Dump:
May 29 11:25:59 x1cbn kernel: [ 4667.796322] iwlwifi 0000:02:00.0: Status: 0x00000100, count: 7
May 29 11:25:59 x1cbn kernel: [ 4667.796327] iwlwifi 0000:02:00.0: 0x00000070 | NMI_INTERRUPT_LMAC_FATAL
May 29 11:25:59 x1cbn kernel: [ 4667.796330] iwlwifi 0000:02:00.0: 0x00000000 | umac branchlink1
May 29 11:25:59 x1cbn kernel: [ 4667.796333] iwlwifi 0000:02:00.0: 0xC00868A4 | umac branchlink2
May 29 11:25:59 x1cbn kernel: [ 4667.796336] iwlwifi 0000:02:00.0: 0xC0083A94 | umac interruptlink1
May 29 11:25:59 x1cbn kernel: [ 4667.796340] iwlwifi 0000:02:00.0: 0xC0083A94 | umac interruptlink2
May 29 11:25:59 x1cbn kernel: [ 4667.796343] iwlwifi 0000:02:00.0: 0x00000800 | umac data1
May 29 11:25:59 x1cbn kernel: [ 4667.796346] iwlwifi 0000:02:00.0: 0xC0083A94 | umac data2
May 29 11:25:59 x1cbn kernel: [ 4667.796349] iwlwifi 0000:02:00.0: 0xDEADBEEF | umac data3
May 29 11:25:59 x1cbn kernel: [ 4667.796352] iwlwifi 0000:02:00.0: 0x00000024 | umac major
May 29 11:25:59 x1cbn kernel: [ 4667.796355] iwlwifi 0000:02:00.0: 0x9F0A2D68 | umac minor
May 29 11:25:59 x1cbn kernel: [ 4667.796358] iwlwifi 0000:02:00.0: 0xC088628C | frame pointer
May 29 11:25:59 x1cbn kernel: [ 4667.796362] iwlwifi 0000:02:00.0: 0xC088628C | stack pointer
May 29 11:25:59 x1cbn kernel: [ 4667.796365] iwlwifi 0000:02:00.0: 0x004C019C | last host cmd
May 29 11:25:59 x1cbn kernel: [ 4667.796368] iwlwifi 0000:02:00.0: 0x00000000 | isr status reg
May 29 11:25:59 x1cbn kernel: [ 4667.796378] ieee80211 phy0: Hardware restart was requested
May 29 11:26:00 x1cbn kernel: [ 4668.462864] iwlwifi 0000:02:00.0: Queue 0 is active on fifo 7 and stuck for 2500 ms. SW [76, 77] HW [77, 77] FH TRB=0x070004c
May 29 11:26:02 x1cbn kernel: [ 4670.766428] iwlwifi 0000:02:00.0: Error sending MAC_PM_POWER_TABLE: time out after 2000ms.
May 29 11:26:02 x1cbn kernel: [ 4670.766438] iwlwifi 0000:02:00.0: Current CMD queue read_ptr 77 write_ptr 78
May 29 11:26:02 x1cbn kernel: [ 4670.767947] iwlwifi 0000:02:00.0: Start IWL Error Log Dump:
May 29 11:26:02 x1cbn kernel: [ 4670.767955] iwlwifi 0000:02:00.0: Status: 0x00000100, count: 6
May 29 11:26:02 x1cbn kernel: [ 4670.767960] iwlwifi 0000:02:00.0: Loaded firmware version: 36.9f0a2d68.0
May 29 11:26:02 x1cbn kernel: [ 4670.767967] iwlwifi 0000:02:00.0: 0x00000084 | NMI_INTERRUPT_UNKNOWN
May 29 11:26:02 x1cbn kernel: [ 4670.767972] iwlwifi 0000:02:00.0: 0x00000230 | trm_hw_status0
May 29 11:26:02 x1cbn kernel: [ 4670.767978] iwlwifi 0000:02:00.0: 0x00000000 | trm_hw_status1
May 29 11:26:02 x1cbn kernel: [ 4670.767983] iwlwifi 0000:02:00.0: 0x0002485C | branchlink2
May 29 11:26:02 x1cbn kernel: [ 4670.767989] iwlwifi 0000:02:00.0: 0x0003A7CA | interruptlink1
May 29 11:26:02 x1cbn kernel: [ 4670.767994] iwlwifi 0000:02:00.0: 0x0003A7CA | interruptlink2
May 29 11:26:02 x1cbn kernel: [ 4670.767999] iwlwifi 0000:02:00.0: 0x00000000 | data1
May 29 11:26:02 x1cbn kernel: [ 4670.768004] iwlwifi 0000:02:00.0: 0x00000080 | data2
May 29 11:26:02 x1cbn kernel: [ 4670.768010] iwlwifi 0000:02:00.0: 0x07830000 | data3
May 29 11:26:02 x1cbn kernel: [ 4670.768015] iwlwifi 0000:02:00.0: 0x0500EDFE | beacon time
May 29 11:26:02 x1cbn kernel: [ 4670.768020] iwlwifi 0000:02:00.0: 0x1245D205 | tsf low
May 29 11:26:02 x1cbn kernel: [ 4670.768025] iwlwifi 0000:02:00.0: 0x0000019F | tsf hi
May 29 11:26:02 x1cbn kernel: [ 4670.768032] iwlwifi 0000:02:00.0: 0x00000000 | time gp1
May 29 11:26:02 x1cbn kernel: [ 4670.768036] iwlwifi 0000:02:00.0: 0x001FDC5E | time gp2
May 29 11:26:02 x1cbn kernel: [ 4670.768041] iwlwifi 0000:02:00.0: 0x00000001 | uCode revision type
May 29 11:26:02 x1cbn kernel: [ 4670.768047] iwlwifi 0000:02:00.0: 0x00000024 | uCode version major
May 29 11:26:02 x1cbn kernel: [ 4670.768052] iwlwifi 0000:02:00.0: 0x9F0A2D68 | uCode version minor
May 29 11:26:02 x1cbn kernel: [ 4670.768057] iwlwifi 0000:02:00.0: 0x00000230 | hw version
May 29 11:26:02 x1cbn kernel: [ 4670.768062] iwlwifi 0000:02:00.0: 0x00080000 | board version
May 29 11:26:02 x1cbn kernel: [ 4670.768067] iwlwifi 0000:02:00.0: 0x0A2D001C | hcmd
May 29 11:26:02 x1cbn kernel: [ 4670.768071] iwlwifi 0000:02:00.0: 0x24022000 | isr0
May 29 11:26:02 x1cbn kernel: [ 4670.768076] iwlwifi 0000:02:00.0: 0x00800000 | isr1
May 29 11:26:02 x1cbn kernel: [ 4670.768081] iwlwifi 0000:02:00.0: 0x08001802 | isr2
May 29 11:26:02 x1cbn kernel: [ 4670.768085] iwlwifi 0000:02:00.0: 0x00400080 | isr3
May 29 11:26:02 x1cbn kernel: [ 4670.768089] iwlwifi 0000:02:00.0: 0x00000000 | isr4
May 29 11:26:02 x1cbn kernel: [ 4670.768094] iwlwifi 0000:02:00.0: 0x0A2D001C | last cmd Id
May 29 11:26:02 x1cbn kernel: [ 4670.768099] iwlwifi 0000:02:00.0: 0x00000000 | wait_event
May 29 11:26:02 x1cbn kernel: [ 4670.768105] iwlwifi 0000:02:00.0: 0x000010C4 | l2p_control
May 29 11:26:02 x1cbn kernel: [ 4670.768111] iwlwifi 0000:02:00.0: 0x00010030 | l2p_duration
May 29 11:26:02 x1cbn kernel: [ 4670.768116] iwlwifi 0000:02:00.0: 0x00000007 | l2p_mhvalid
May 29 11:26:02 x1cbn kernel: [ 4670.768123] iwlwifi 0000:02:00.0: 0x00000000 | l2p_addr_match
May 29 11:26:02 x1cbn kernel: [ 4670.768129] iwlwifi 0000:02:00.0: 0x0000000D | lmpm_pmg_sel
May 29 11:26:02 x1cbn kernel: [ 4670.768134] iwlwifi 0000:02:00.0: 0x04120134 | timestamp
May 29 11:26:02 x1cbn kernel: [ 4670.768141] iwlwifi 0000:02:00.0: 0x00344870 | flow_handler
May 29 11:26:02 x1cbn kernel: [ 4670.768404] iwlwifi 0000:02:00.0: Start IWL Error Log Dump:
May 29 11:26:02 x1cbn kernel: [ 4670.768410] iwlwifi 0000:02:00.0: Status: 0x00000100, count: 7
May 29 11:26:02 x1cbn kernel: [ 4670.768423] iwlwifi 0000:02:00.0: 0x00000070 | NMI_INTERRUPT_LMAC_FATAL
May 29 11:26:02 x1cbn kernel: [ 4670.768431] iwlwifi 0000:02:00.0: 0x00000000 | umac branchlink1
May 29 11:26:02 x1cbn kernel: [ 4670.768436] iwlwifi 0000:02:00.0: 0xC00868A4 | umac branchlink2
May 29 11:26:02 x1cbn kernel: [ 4670.768442] iwlwifi 0000:02:00.0: 0xC0083A94 | umac interruptlink1
May 29 11:26:02 x1cbn kernel: [ 4670.768447] iwlwifi 0000:02:00.0: 0xC0083A94 | umac interruptlink2
May 29 11:26:02 x1cbn kernel: [ 4670.768453] iwlwifi 0000:02:00.0: 0x00000800 | umac data1
May 29 11:26:02 x1cbn kernel: [ 4670.768459] iwlwifi 0000:02:00.0: 0xC0083A94 | umac data2
May 29 11:26:02 x1cbn kernel: [ 4670.768465] iwlwifi 0000:02:00.0: 0xDEADBEEF | umac data3
May 29 11:26:02 x1cbn kernel: [ 4670.768471] iwlwifi 0000:02:00.0: 0x00000024 | umac major
May 29 11:26:02 x1cbn kernel: [ 4670.768476] iwlwifi 0000:02:00.0: 0x9F0A2D68 | umac minor
May 29 11:26:02 x1cbn kernel: [ 4670.768481] iwlwifi 0000:02:00.0: 0xC088628C | frame pointer
May 29 11:26:02 x1cbn kernel: [ 4670.768485] iwlwifi 0000:02:00.0: 0xC088628C | stack pointer
May 29 11:26:02 x1cbn kernel: [ 4670.768488] iwlwifi 0000:02:00.0: 0x004D01A9 | last host cmd
May 29 11:26:02 x1cbn kernel: [ 4670.768491] iwlwifi 0000:02:00.0: 0x00000000 | isr status reg
May 29 11:26:02 x1cbn kernel: [ 4670.768501] ieee80211 phy0: Hardware restart was requested
May 29 11:26:02 x1cbn kernel: [ 4670.768518] iwlwifi 0000:02:00.0: failed to update power mode
May 29 11:26:06 x1cbn wpa_supplicant[1144]: wlp2s0: CTRL-EVENT-SCAN-FAILED ret=-110
May 29 11:26:06 x1cbn geoclue[1741]: WiFi scan failed
May 29 11:26:06 x1cbn kernel: [ 4674.766779] iwlwifi 0000:02:00.0: Error sending SCAN_REQ_UMAC: time out after 2000ms.
May 29 11:26:06 x1cbn kernel: [ 4674.766790] iwlwifi 0000:02:00.0: Current CMD queue read_ptr 77 write_ptr 78
May 29 11:26:06 x1cbn kernel: [ 4674.768239] iwlwifi 0000:02:00.0: Start IWL Error Log Dump:
May 29 11:26:06 x1cbn kernel: [ 4674.768245] iwlwifi 0000:02:00.0: Status: 0x00000100, count: 6
May 29 11:26:06 x1cbn kernel: [ 4674.768251] iwlwifi 0000:02:00.0: Loaded firmware version: 36.9f0a2d68.0
May 29 11:26:06 x1cbn kernel: [ 4674.768257] iwlwifi 0000:02:00.0: 0x00000084 | NMI_INTERRUPT_UNKNOWN
May 29 11:26:06 x1cbn kernel: [ 4674.768263] iwlwifi 0000:02:00.0: 0x000002A0 | trm_hw_status0
May 29 11:26:06 x1cbn kernel: [ 4674.768268] iwlwifi 0000:02:00.0: 0x00000000 | trm_hw_status1
May 29 11:26:06 x1cbn kernel: [ 4674.768273] iwlwifi 0000:02:00.0: 0x0002485C | branchlink2
May 29 11:26:06 x1cbn kernel: [ 4674.768278] iwlwifi 0000:02:00.0: 0x0003A7CA | interruptlink1
May 29 11:26:06 x1cbn kernel: [ 4674.768282] iwlwifi 0000:02:00.0: 0x00049A00 | interruptlink2
May 29 11:26:06 x1cbn kernel: [ 4674.768287] iwlwifi 0000:02:00.0: 0x00000000 | data1
May 29 11:26:06 x1cbn kernel: [ 4674.768292] iwlwifi 0000:02:00.0: 0x00000080 | data2
May 29 11:26:06 x1cbn kernel: [ 4674.768297] iwlwifi 0000:02:00.0: 0x07830000 | data3
May 29 11:26:06 x1cbn kernel: [ 4674.768302] iwlwifi 0000:02:00.0: 0x0800D3A2 | beacon time
May 29 11:26:06 x1cbn kernel: [ 4674.768307] iwlwifi 0000:02:00.0: 0x1282DC61 | tsf low
May 29 11:26:06 x1cbn kernel: [ 4674.768312] iwlwifi 0000:02:00.0: 0x0000019F | tsf hi
May 29 11:26:06 x1cbn kernel: [ 4674.768317] iwlwifi 0000:02:00.0: 0x00000000 | time gp1
May 29 11:26:06 x1cbn kernel: [ 4674.768322] iwlwifi 0000:02:00.0: 0x002FE32C | time gp2
May 29 11:26:06 x1cbn kernel: [ 4674.768327] iwlwifi 0000:02:00.0: 0x00000001 | uCode revision type
May 29 11:26:06 x1cbn kernel: [ 4674.768332] iwlwifi 0000:02:00.0: 0x00000024 | uCode version major
May 29 11:26:06 x1cbn kernel: [ 4674.768337] iwlwifi 0000:02:00.0: 0x9F0A2D68 | uCode version minor
May 29 11:26:06 x1cbn kernel: [ 4674.768342] iwlwifi 0000:02:00.0: 0x00000230 | hw version
May 29 11:26:06 x1cbn kernel: [ 4674.768346] iwlwifi 0000:02:00.0: 0x00080000 | board version
May 29 11:26:06 x1cbn kernel: [ 4674.768351] iwlwifi 0000:02:00.0: 0x0000001C | hcmd
May 29 11:26:06 x1cbn kernel: [ 4674.768356] iwlwifi 0000:02:00.0: 0x00022000 | isr0
May 29 11:26:06 x1cbn kernel: [ 4674.768360] iwlwifi 0000:02:00.0: 0x00800000 | isr1
May 29 11:26:06 x1cbn kernel: [ 4674.768365] iwlwifi 0000:02:00.0: 0x08001803 | isr2
May 29 11:26:06 x1cbn kernel: [ 4674.768369] iwlwifi 0000:02:00.0: 0x0041D4C0 | isr3
May 29 11:26:06 x1cbn kernel: [ 4674.768374] iwlwifi 0000:02:00.0: 0x00000000 | isr4
May 29 11:26:06 x1cbn kernel: [ 4674.768379] iwlwifi 0000:02:00.0: 0x0A36001C | last cmd Id
May 29 11:26:06 x1cbn kernel: [ 4674.768384] iwlwifi 0000:02:00.0: 0x00000000 | wait_event
May 29 11:26:06 x1cbn kernel: [ 4674.768389] iwlwifi 0000:02:00.0: 0x000000D4 | l2p_control
May 29 11:26:06 x1cbn kernel: [ 4674.768393] iwlwifi 0000:02:00.0: 0x00018020 | l2p_duration
May 29 11:26:06 x1cbn kernel: [ 4674.768398] iwlwifi 0000:02:00.0: 0x00000007 | l2p_mhvalid
May 29 11:26:06 x1cbn kernel: [ 4674.768403] iwlwifi 0000:02:00.0: 0x00000081 | l2p_addr_match
May 29 11:26:06 x1cbn kernel: [ 4674.768408] iwlwifi 0000:02:00.0: 0x0000000D | lmpm_pmg_sel
May 29 11:26:06 x1cbn kernel: [ 4674.768412] iwlwifi 0000:02:00.0: 0x04120134 | timestamp
May 29 11:26:06 x1cbn kernel: [ 4674.768417] iwlwifi 0000:02:00.0: 0x00344078 | flow_handler
May 29 11:26:06 x1cbn kernel: [ 4674.768623] iwlwifi 0000:02:00.0: Start IWL Error Log Dump:
May 29 11:26:06 x1cbn kernel: [ 4674.768628] iwlwifi 0000:02:00.0: Status: 0x00000100, count: 7
May 29 11:26:06 x1cbn kernel: [ 4674.768633] iwlwifi 0000:02:00.0: 0x00000070 | NMI_INTERRUPT_LMAC_FATAL
May 29 11:26:06 x1cbn kernel: [ 4674.768638] iwlwifi 0000:02:00.0: 0x00000000 | umac branchlink1
May 29 11:26:06 x1cbn kernel: [ 4674.768643] iwlwifi 0000:02:00.0: 0xC00868A4 | umac branchlink2
May 29 11:26:06 x1cbn kernel: [ 4674.768647] iwlwifi 0000:02:00.0: 0xC0083A94 | umac interruptlink1
May 29 11:26:06 x1cbn kernel: [ 4674.768652] iwlwifi 0000:02:00.0: 0xC0083A94 | umac interruptlink2
May 29 11:26:06 x1cbn kernel: [ 4674.768657] iwlwifi 0000:02:00.0: 0x00000800 | umac data1
May 29 11:26:06 x1cbn kernel: [ 4674.768662] iwlwifi 0000:02:00.0: 0xC0083A94 | umac data2
May 29 11:26:06 x1cbn kernel: [ 4674.768666] iwlwifi 0000:02:00.0: 0xDEADBEEF | umac data3
May 29 11:26:06 x1cbn kernel: [ 4674.768671] iwlwifi 0000:02:00.0: 0x00000024 | umac major
May 29 11:26:06 x1cbn kernel: [ 4674.768676] iwlwifi 0000:02:00.0: 0x9F0A2D68 | umac minor
May 29 11:26:06 x1cbn kernel: [ 4674.768680] iwlwifi 0000:02:00.0: 0xC088628C | frame pointer
May 29 11:26:06 x1cbn kernel: [ 4674.768685] iwlwifi 0000:02:00.0: 0xC088628C | stack pointer
May 29 11:26:06 x1cbn kernel: [ 4674.768689] iwlwifi 0000:02:00.0: 0x004D010D | last host cmd
May 29 11:26:06 x1cbn kernel: [ 4674.768694] iwlwifi 0000:02:00.0: 0x00000000 | isr status reg
May 29 11:26:06 x1cbn kernel: [ 4674.768714] ieee80211 phy0: Hardware restart was requested
May 29 11:26:06 x1cbn kernel: [ 4674.768729] iwlwifi 0000:02:00.0: Scan failed! ret -110
May 29 11:26:07 x1cbn kernel: [ 4675.408043] iwlwifi 0000:02:00.0: Queue 0 is active on fifo 7 and stuck for 2500 ms. SW [77, 78] HW [78, 78] FH TRB=0x070004d
May 29 11:26:07 x1cbn wpa_supplicant[1144]: wlp2s0: CTRL-EVENT-SIGNAL-CHANGE above=1 signal=-38 noise=9999 txrate=300000
May 29 11:27:27 x1cbn wpa_supplicant[1144]: wlp2s0: CTRL-EVENT-SIGNAL-CHANGE above=1 signal=-34 noise=9999 txrate=270000
May 29 11:30:01 x1cbn CRON[14713]: (root) CMD ([ -x /etc/init.d/anacron ] && if [ ! -d /run/systemd/system ]; then /usr/sbin/invoke-rc.d anacron start >/dev/null; fi)
Comment 1 Emmanuel Grumbach 2019-06-03 10:04:42 UTC
Can you please attach the output of
sudo lspci -xxxxvvvv
In AC and bat mode 

Thanks.
Comment 2 Jannis Vajen 2019-06-03 11:19:09 UTC
Created attachment 283049 [details]
output of lscpi in AC mode
Comment 3 Jannis Vajen 2019-06-03 11:19:29 UTC
Created attachment 283051 [details]
ouput of lspci in battery mode
Comment 4 Jannis Vajen 2019-06-03 11:20:10 UTC
Thank you for looking into this, Emmanuel.
Comment 5 Emmanuel Grumbach 2019-06-03 14:14:09 UTC
Can you please record tracing but with the following flags:

-e iwlwifi_dgb -e mac80211 -e cfg80211 -e iwlwifi

?

Thanks.
Comment 6 Jannis Vajen 2019-06-03 14:42:03 UTC
Created attachment 283055 [details]
trace-cmd record -e iwlwifi -e mac80211 -e cfg80211 -e iwlwifi_msg

`sudo trace-cmd record -e iwlwifi_dgb -e mac80211 -e cfg80211 -e iwlwifi` didn't work as I don't seem to have the iwlwifi_dbg module/file available. 
I ran `sudo trace-cmd record -e iwlwifi -e mac80211 -e cfg80211 -e iwlwifi` instead. 

Please let me know if that works or if I need to provide more data.
Comment 7 Emmanuel Grumbach 2019-06-03 18:18:29 UTC
Sorry, can you use:

sudo trace-cmd record -e iwlwifi -e mac80211 -e cfg80211 -e iwlwifi_msg

thanks.
Comment 8 Jannis Vajen 2019-06-03 19:20:00 UTC
Created attachment 283059 [details]
trace-cmd record -e iwlwifi -e mac80211 -e cfg80211 -e iwlwifi_msg

Sure, here's the output with iwlwifi_msg. Thanks again for taking the time!
Comment 9 Emmanuel Grumbach 2019-06-03 19:52:21 UTC
[007]  8129.890391: iwlwifi_dbg:          iwl_pcie_irq_handler[U] -- Rx interrupt
[002]  8134.836434: iwlwifi_err:          Error sending STATISTICS_CMD: time out after 2000ms.

No interrupt after 8129.890391 until the firmware crashes....
Can you please tell me when you switched to BAT mode?

I am pretty sure that it is after 8129.890391.
In this case, it'd mean that we don't get any interrupt in BAT mode which is not very healthy.

Another weird thing:


  NetworkManager-1197  [002]  8130.806037: iwlwifi_dbg:          iwl_pcie_send_hcmd_sync[U] -- Attempting to send sync command STATISTICS_CMD
  NetworkManager-1197  [002]  8130.806038: iwlwifi_dbg:          iwl_pcie_send_hcmd_sync[U] -- Setting HCMD_ACTIVE for command STATISTICS_CMD
  NetworkManager-1197  [002]  8132.820393: iwlwifi_dbg:          iwl_pcie_enqueue_hcmd[I] -- Sending command STATISTICS_CMD (01.9c), seq: 0x0015, 12 bytes at 21[21]:0
  NetworkManager-1197  [002]  8132.820399: iwlwifi_dev_hcmd:     STATISTICS_CMD (0x019c) / seq 0x15/sync/wantskb (12 bytes)
  NetworkManager-1197  [002]  8134.836434: iwlwifi_err:          Error sending STATISTICS_CMD: time out after 2000ms.


the diff in timings between iwl_pcie_send_hcmd_sync and iwl_pcie_enqueue_hcmd is ... 2 seconds!!
This is absolutely insane.
More work needed here to debug.

I'll need to send you debug patches. Will you be able to apply them to test?
You can take our backport driver if it's more convenient.
Comment 10 Emmanuel Grumbach 2019-06-03 20:38:41 UTC
Created attachment 283061 [details]
debug patch

Please reproduce with trace-cmd running as before with the patch attached.

Thanks!
Comment 11 Jannis Vajen 2019-06-04 13:59:05 UTC
Created attachment 283099 [details]
trace-cmd record -e iwlwifi -e mac80211 -e cfg80211 -e iwlwifi_msg (debug)

Thank you Emmanuel. I managed to apply the patch and ran another trace-cmd. This time I switched to battery mode around 315 and reverted to AC mode around the 399 mark.
Comment 12 Emmanuel Grumbach 2019-06-04 16:57:16 UTC
Hm... 315 is not even in the tracing output

Moreover I can see that this time, the two seconds delay in the command transmission didn't occur.

Can you try to reload the iwlwifi module while in BAT mode?

Just do:

sudo modprobe -r iwlwifi
sudo modprobe iwlwifi

then, please send the dmesg output.

Thanks.
Comment 13 Emmanuel Grumbach 2019-06-04 17:09:55 UTC
Forget my last request, unnecessary.
Comment 14 Emmanuel Grumbach 2019-06-04 17:21:04 UTC
What is the value of the d0i3_disable module parameter?

cat /sys/module/iwlwifi/parameters/d0i3_disable
Comment 15 Jannis Vajen 2019-06-04 21:34:51 UTC
Created attachment 283103 [details]
dmesg (modprobe -r iwlwifi)

cat /sys/module/iwlwifi/parameters/d0i3_disable
N 

Very strange; I reloaded the iwlwifi module and I can use wireless in battery mode since. I will reboot and see if it's reproducible.
Comment 16 Jannis Vajen 2019-06-04 21:41:33 UTC
Created attachment 283105 [details]
dmesg (boot in battery mode, modprobe -r iwlwifi, modprobe iwlwifi)

I rebooted with the AC adapter being disconnected and didn't have wifi at first. At around 120seconds I reloaded the module and internet was working!
Comment 17 Emmanuel Grumbach 2019-06-05 04:50:43 UTC
I believe it's all related to the d0i3 module parameter and runtime PM.
Although I can't explain why reloading the module helps.

Can you please set the module parameter to true in iwlwifi.conf under /etc/modprobe.d/ and see what happens.

Have you installed the backport driver?
Apparently, the default value differs between mainline kernel and our backport tree. Not very healthy.
Comment 18 Jannis Vajen 2019-06-05 07:23:37 UTC
Disabling d0i3 in /etc/modprobe.d/iwlwifi.conf helps and makes wifi available directly after boot in battery mode. 

I didn't install the backport driver.
Comment 19 Emmanuel Grumbach 2019-06-05 07:35:05 UTC
Ok, then I guess I need to understand why d0i3_disable is not enabled by default.

According to https://git.kernel.org/pub/scm/linux/kernel/git/stable/linux.git/tree/drivers/net/wireless/intel/iwlwifi/iwl-drv.c?h=linux-5.1.y#n1670

It should be disabled by default...
Comment 20 Emmanuel Grumbach 2019-06-05 08:34:36 UTC
Can you please check what is the value of the IWLWIFI_PCIE_RTPM Kconfig option?
Comment 21 Jannis Vajen 2019-06-05 10:05:02 UTC
$ grep CONFIG_IWLWIFI_PCIE_RTPM config-*
         
config-5.0.0-13-generic:# CONFIG_IWLWIFI_PCIE_RTPM is not set
config-5.0.0-15-generic:# CONFIG_IWLWIFI_PCIE_RTPM is not set
config-5.1.5-050105-generic:# CONFIG_IWLWIFI_PCIE_RTPM is not set
config-5.1.6:# CONFIG_IWLWIFI_PCIE_RTPM is not set
config-5.1.6.old:# CONFIG_IWLWIFI_PCIE_RTPM is not set
Comment 22 Emmanuel Grumbach 2019-06-05 10:42:39 UTC
Ok thanks.

In any case, I'd like to get a clear picture now.

1) Is it established that when d0i3 is disabled (module parameter is set), no bug?
2) when you don't set any value in /etc/modprobe/iwlwifi.conf, what default value for d0i3_disable module parameter do you get?
Comment 23 Jannis Vajen 2019-06-05 15:27:29 UTC
Dear Emmanuel,

I'm deeply sorry for overlooking this: in order to save power consumption I had copied a configuration from a blog post that enabled d0i3 (among other things). I now removed that line completely and there's no quirks anymore when switching to battery mode.

Regarding your questions:

1) correct, when d0i3 is disabled there is no bug.
2) having removed the offending config line, everything is as it should be and d0i3_disable is at its default (Y).

Again, I'm sorry for not seeing this earlier. Without your efforts I probably wouldn't have figured this out.
Comment 24 Emmanuel Grumbach 2019-06-05 15:44:35 UTC
No worries.

Please let the blog owner know that he is suggesting a dangerous configuration that is not default for a reason.

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