iwlwifi Error sending STATISTICS_CMD: time out after 2000ms

[Po-Hsu Lin]

CID: 201706-25581 Dell Inspiron 7577

Steps:
  1. Install Bionic + update with LiveUSB
  2. Boot to the desktop

Result:
  Wifi connection looks OK on both 2.4g and 5g network, but error message for iwlwifi could be found in dmesg:
[ 73.936841] iwlwifi 0000:3c:00.0: Error sending STATISTICS_CMD: time out after 2000ms.
[ 73.936843] iwlwifi 0000:3c:00.0: Current CMD queue read_ptr 198 write_ptr 199
[ 73.937703] iwlwifi 0000:3c:00.0: Loaded firmware version: 34.0.1
[ 73.937705] iwlwifi 0000:3c:00.0: 0x00000000 | ADVANCED_SYSASSERT
[ 73.937707] iwlwifi 0000:3c:00.0: 0x00000000 | trm_hw_status0
[ 73.937708] iwlwifi 0000:3c:00.0: 0x00000000 | trm_hw_status1
[ 73.937709] iwlwifi 0000:3c:00.0: 0x00000000 | branchlink2
[ 73.937710] iwlwifi 0000:3c:00.0: 0x00000000 | interruptlink1
[ 73.937712] iwlwifi 0000:3c:00.0: 0x00000000 | interruptlink2
[ 73.937713] iwlwifi 0000:3c:00.0: 0x00000000 | data1
[ 73.937714] iwlwifi 0000:3c:00.0: 0x00000000 | data2
[ 73.937715] iwlwifi 0000:3c:00.0: 0x00000000 | data3
[ 73.937716] iwlwifi 0000:3c:00.0: 0x00000000 | beacon time
[ 73.937718] iwlwifi 0000:3c:00.0: 0x00000000 | tsf low
[ 73.937719] iwlwifi 0000:3c:00.0: 0x0000000C | tsf hi
[ 73.937720] iwlwifi 0000:3c:00.0: 0x00000000 | time gp1
[ 73.937722] iwlwifi 0000:3c:00.0: 0x0390CA61 | time gp2
[ 73.937723] iwlwifi 0000:3c:00.0: 0x00000001 | uCode revision type
[ 73.937724] iwlwifi 0000:3c:00.0: 0x00000022 | uCode version major
[ 73.937725] iwlwifi 0000:3c:00.0: 0x00000000 | uCode version minor
[ 73.937727] iwlwifi 0000:3c:00.0: 0x00000230 | hw version
[ 73.937728] iwlwifi 0000:3c:00.0: 0x00489000 | board version
[ 73.937729] iwlwifi 0000:3c:00.0: 0x8078F500 | hcmd
[ 73.937731] iwlwifi 0000:3c:00.0: 0x02722000 | isr0
[ 73.937732] iwlwifi 0000:3c:00.0: 0x00800000 | isr1
[ 73.937733] iwlwifi 0000:3c:00.0: 0x08001802 | isr2
[ 73.937734] iwlwifi 0000:3c:00.0: 0x00400082 | isr3
[ 73.937736] iwlwifi 0000:3c:00.0: 0x00000000 | isr4
[ 73.937737] iwlwifi 0000:3c:00.0: 0x00C6019C | last cmd Id
[ 73.937738] iwlwifi 0000:3c:00.0: 0x00000000 | wait_event
[ 73.937739] iwlwifi 0000:3c:00.0: 0x0000B30D | l2p_control
[ 73.937741] iwlwifi 0000:3c:00.0: 0x00001C20 | l2p_duration
[ 73.937742] iwlwifi 0000:3c:00.0: 0x00000000 | l2p_mhvalid
[ 73.937743] iwlwifi 0000:3c:00.0: 0x000000F0 | l2p_addr_match
[ 73.937744] iwlwifi 0000:3c:00.0: 0x0000000D | lmpm_pmg_sel
[ 73.937746] iwlwifi 0000:3c:00.0: 0x30101345 | timestamp
[ 73.937747] iwlwifi 0000:3c:00.0: 0x0034D8F0 | flow_handler
[ 73.937822] iwlwifi 0000:3c:00.0: 0x00000000 | ADVANCED_SYSASSERT
[ 73.937823] iwlwifi 0000:3c:00.0: 0x00000000 | umac branchlink1
[ 73.937824] iwlwifi 0000:3c:00.0: 0x00000000 | umac branchlink2
[ 73.937825] iwlwifi 0000:3c:00.0: 0x00000000 | umac interruptlink1
[ 73.937827] iwlwifi 0000:3c:00.0: 0x00000000 | umac interruptlink2
[ 73.937828] iwlwifi 0000:3c:00.0: 0x00000000 | umac data1
[ 73.937829] iwlwifi 0000:3c:00.0: 0x00000000 | umac data2
[ 73.937830] iwlwifi 0000:3c:00.0: 0x00000000 | umac data3
[ 73.937832] iwlwifi 0000:3c:00.0: 0x00000000 | umac major
[ 73.937833] iwlwifi 0000:3c:00.0: 0x00000000 | umac minor
[ 73.937834] iwlwifi 0000:3c:00.0: 0x00000000 | frame pointer
[ 73.937835] iwlwifi 0000:3c:00.0: 0x00000000 | stack pointer
[ 73.937837] iwlwifi 0000:3c:00.0: 0x00000000 | last host cmd
[ 73.937838] iwlwifi 0000:3c:00.0: 0x00000000 | isr status reg

[ 1056.908386] iwlwifi 0000:3c:00.0: Microcode SW error detected. Restarting 0x2000000.
[ 1056.908546] iwlwifi 0000:3c:00.0: Start IWL Error Log Dump:
[ 1056.908552] iwlwifi 0000:3c:00.0: Status: 0x00000100, count: 6
[ 1056.908558] iwlwifi 0000:3c:00.0: Loaded firmware version: 34.0.1
[ 1056.908565] iwlwifi 0000:3c:00.0: 0x00000071 | ADVANCED_SYSASSERT
[ 1056.908570] iwlwifi 0000:3c:00.0: 0x000002F0 | trm_hw_status0
[ 1056.908575] iwlwifi 0000:3c:00.0: 0x00000000 | trm_hw_status1
[ 1056.908581] iwlwifi 0000:3c:00.0: 0x00023FDC | branchlink2
[ 1056.908586] iwlwifi 0000:3c:00.0: 0x0003915A | interruptlink1
[ 1056.908591] iwlwifi 0000:3c:00.0: 0x0003915A | interruptlink2
[ 1056.908596] iwlwifi 0000:3c:00.0: 0x00000000 | data1
[ 1056.908602] iwlwifi 0000:3c:00.0: 0x00001000 | data2
[ 1056.908607] iwlwifi 0000:3c:00.0: 0x07830000 | data3
[ 1056.908612] iwlwifi 0000:3c:00.0: 0x01419617 | beacon time
[ 1056.908618] iwlwifi 0000:3c:00.0: 0x0ED741E2 | tsf low
[ 1056.908623] iwlwifi 0000:3c:00.0: 0x0000000D | tsf hi
[ 1056.908628] iwlwifi 0000:3c:00.0: 0x00000000 | time gp1
[ 1056.908634] iwlwifi 0000:3c:00.0: 0x39CCC22B | time gp2
[ 1056.908639] iwlwifi 0000:3c:00.0: 0x00000001 | uCode revision type
[ 1056.908644] iwlwifi 0000:3c:00.0: 0x00000022 | uCode version major
[ 1056.908649] iwlwifi 0000:3c:00.0: 0x00000000 | uCode version minor
[ 1056.908654] iwlwifi 0000:3c:00.0: 0x00000230 | hw version
[ 1056.908660] iwlwifi 0000:3c:00.0: 0x00489000 | board version
[ 1056.908665] iwlwifi 0000:3c:00.0: 0x0000001C | hcmd
[ 1056.908670] iwlwifi 0000:3c:00.0: 0x00022000 | isr0
[ 1056.908676] iwlwifi 0000:3c:00.0: 0x00800000 | isr1
[ 1056.908681] iwlwifi 0000:3c:00.0: 0x08001802 | isr2
[ 1056.908686] iwlwifi 0000:3c:00.0: 0x00417CC1 | isr3
[ 1056.908691] iwlwifi 0000:3c:00.0: 0x00000001 | isr4
[ 1056.908696] iwlwifi 0000:3c:00.0: 0x0A59001C | last cmd Id
[ 1056.908701] iwlwifi 0000:3c:00.0: 0x00000000 | wait_event
[ 1056.908706] iwlwifi 0000:3c:00.0: 0x00000000 | l2p_control
[ 1056.908711] iwlwifi 0000:3c:00.0: 0x00000000 | l2p_duration
[ 1056.908717] iwlwifi 0000:3c:00.0: 0x0000003F | l2p_mhvalid
[ 1056.908722] iwlwifi 0000:3c:00.0: 0x000000CE | l2p_addr_match
[ 1056.908727] iwlwifi 0000:3c:00.0: 0x0000000D | lmpm_pmg_sel
[ 1056.908733] iwlwifi 0000:3c:00.0: 0x30101345 | timestamp
[ 1056.908738] iwlwifi 0000:3c:00.0: 0x0034E0F0 | flow_handler
[ 1056.908815] iwlwifi 0000:3c:00.0: Start IWL Error Log Dump:
[ 1056.908821] iwlwifi 0000:3c:00.0: Status: 0x00000100, count: 7
[ 1056.908826] iwlwifi 0000:3c:00.0: 0x00100116 | ADVANCED_SYSASSERT
[ 1056.908831] iwlwifi 0000:3c:00.0: 0x00000000 | umac branchlink1
[ 1056.908837] iwlwifi 0000:3c:00.0: 0xC0086964 | umac branchlink2
[ 1056.908842] iwlwifi 0000:3c:00.0: 0xC0083A94 | umac interruptlink1
[ 1056.908847] iwlwifi 0000:3c:00.0: 0x00000000 | umac interruptlink2
[ 1056.908852] iwlwifi 0000:3c:00.0: 0xDEADBEEF | umac data1
[ 1056.908858] iwlwifi 0000:3c:00.0: 0xDEADBEEF | umac data2
[ 1056.908863] iwlwifi 0000:3c:00.0: 0xDEADBEEF | umac data3
[ 1056.908868] iwlwifi 0000:3c:00.0: 0x00000022 | umac major
[ 1056.908873] iwlwifi 0000:3c:00.0: 0x00000000 | umac minor
[ 1056.908878] iwlwifi 0000:3c:00.0: 0xC0887F60 | frame pointer
[ 1056.908884] iwlwifi 0000:3c:00.0: 0xC0887F60 | stack pointer
[ 1056.908889] iwlwifi 0000:3c:00.0: 0x0029019C | last host cmd
[ 1056.908894] iwlwifi 0000:3c:00.0: 0x00000020 | isr status reg

3c:00.0 Network controller: Intel Corporation Wireless 8265 / 8275 (rev 78)
ubuntu@ubuntu-Inspiron-7577:~$ dmesg | grep iwl
[ 3.487385] iwlwifi 0000:3c:00.0: enabling device (0000 -> 0002)
[ 3.508532] iwlwifi 0000:3c:00.0: loaded firmware version 34.0.1 op_mode iwlmvm
[ 3.576632] iwlwifi 0000:3c:00.0: Detected Intel(R) Dual Band Wireless AC 8265, REV=0x230
[ 3.636579] iwlwifi 0000:3c:00.0: base HW address: a0:af:bd:5a:64:69
[ 3.748769] ieee80211 phy0: Selected rate control algorithm 'iwl-mvm-rs'
[ 3.929400] iwlwifi 0000:3c:00.0 wlp60s0: renamed from wlan0

ProblemType: Bug
DistroRelease: Ubuntu 18.04
Package: linux-image-4.15.0-22-generic 4.15.0-22.24
ProcVersionSignature: User Name 4.15.0-22.24-generic 4.15.17
Uname: Linux 4.15.0-22-generic x86_64
ApportVersion: 2.20.9-0ubuntu7
Architecture: amd64
CurrentDesktop: ubuntu:GNOME
Date: Wed May 30 11:27:14 2018
InstallationDate: Installed on 2018-05-29 (0 days ago)
InstallationMedia: Ubuntu 18.04 LTS "Bionic Beaver" - Release amd64 (20180426)
SourcePackage: linux-signed
UpgradeStatus: No upgrade log present (probably fresh install)

[Po-Hsu Lin (cypressyew)]

BTW this issue is a bit difficult to reproduce.
I can't see this just after a reboot. Nor with the upstream kernel 4.17rc7 installed.