Bug 187461 - iwlwifi: 8260: UMAC ASSERT 309 - WIFILNX-111
Summary: iwlwifi: 8260: UMAC ASSERT 309 - WIFILNX-111
Status: CLOSED CODE_FIX
Alias: None
Product: Drivers
Classification: Unclassified
Component: network-wireless (show other bugs)
Hardware: Intel Linux
: P1 normal
Assignee: DO NOT USE - assign "network-wireless-intel" component instead
URL:
Keywords:
: 187741 (view as bug list)
Depends on:
Blocks:
 
Reported: 2016-11-11 20:39 UTC by Paul Donohue
Modified: 2017-03-07 05:38 UTC (History)
4 users (show)

See Also:
Kernel Version: v4.9-rc4, 4.8.7
Subsystem:
Regression: No
Bisected commit-id:


Attachments
latest Core19 FW (2.02 MB, application/octet-stream)
2016-11-17 21:24 UTC, Emmanuel Grumbach
Details
Fix backport-iwlwifi Core24 on 4.9 (569 bytes, patch)
2017-01-21 21:23 UTC, Matthew Turnbull
Details | Diff
backport-iwlwifi Core24 on 4.9.1 dmesg (167.75 KB, text/plain)
2017-01-21 21:34 UTC, Matthew Turnbull
Details
backport-iwlwifi Core24 on 4.9.1 dmesg with IWL_UCODE_TLV_PAGING break (164.39 KB, text/plain)
2017-01-23 10:03 UTC, Matthew Turnbull
Details
Journal log including wireless disconnect events for AC-8260 in Intel NUC (268.57 KB, text/plain)
2017-02-20 21:45 UTC, Mike Cloaked
Details
Debugging info for 4.9.13 and 4.10.1 (32.12 KB, application/gzip)
2017-03-04 23:19 UTC, Matthew Turnbull
Details
Dmesg and other info using 4.10.1 with iwlwifi master (66.01 KB, text/plain)
2017-03-05 18:47 UTC, Paul Donohue
Details

Description Paul Donohue 2016-11-11 20:39:56 UTC
Running Ubuntu 16.04 with kernel from http://kernel.ubuntu.com/~kernel-ppa/mainline/v4.9-rc4/ and linux-firmware package version 1.161 on a laptop with an Intel 8260 wireless card.

Running `ifconfig wlan0 up` results in a firmware crash.  Not sure if it is relevant, but I ran `ip link set dev wlan0 address DE:AD:BE:EF:00:05` before running ifconfig.

Stock kernel (4.8.0-22) with stock linux-firmware (1.157.4 containing iwlwifi-8000C-16.ucode) works fine.

Nov 11 15:02:07 Mooninite kernel: iwlwifi 0000:02:00.0: L1 Enabled - LTR Enabled
Nov 11 15:02:07 Mooninite kernel: iwlwifi 0000:02:00.0: L1 Enabled - LTR Enabled
Nov 11 15:02:07 Mooninite kernel: iwlwifi 0000:02:00.0: L1 Enabled - LTR Enabled
Nov 11 15:02:07 Mooninite kernel: iwlwifi 0000:02:00.0: L1 Enabled - LTR Enabled
Nov 11 15:02:07 Mooninite kernel: iwlwifi 0000:02:00.0: Microcode SW error detected.  Restarting 0x82000000.
Nov 11 15:02:07 Mooninite kernel: iwlwifi 0000:02:00.0: CSR values:
Nov 11 15:02:07 Mooninite kernel: iwlwifi 0000:02:00.0: (2nd byte of CSR_INT_COALESCING is CSR_INT_PERIODIC_REG)
Nov 11 15:02:07 Mooninite kernel: iwlwifi 0000:02:00.0:        CSR_HW_IF_CONFIG_REG: 0X00489008
Nov 11 15:02:07 Mooninite kernel: iwlwifi 0000:02:00.0:          CSR_INT_COALESCING: 0X0000ff40
Nov 11 15:02:07 Mooninite kernel: iwlwifi 0000:02:00.0:                     CSR_INT: 0X10000000
Nov 11 15:02:07 Mooninite kernel: iwlwifi 0000:02:00.0:                CSR_INT_MASK: 0X00000000
Nov 11 15:02:07 Mooninite kernel: iwlwifi 0000:02:00.0:           CSR_FH_INT_STATUS: 0X00000000
Nov 11 15:02:07 Mooninite kernel: iwlwifi 0000:02:00.0:                 CSR_GPIO_IN: 0X00000019
Nov 11 15:02:07 Mooninite kernel: iwlwifi 0000:02:00.0:                   CSR_RESET: 0X00000000
Nov 11 15:02:07 Mooninite kernel: iwlwifi 0000:02:00.0:                CSR_GP_CNTRL: 0X08040005
Nov 11 15:02:07 Mooninite kernel: iwlwifi 0000:02:00.0:                  CSR_HW_REV: 0X00000201
Nov 11 15:02:07 Mooninite kernel: iwlwifi 0000:02:00.0:              CSR_EEPROM_REG: 0Xd55555d5
Nov 11 15:02:07 Mooninite kernel: iwlwifi 0000:02:00.0:               CSR_EEPROM_GP: 0Xd55555d5
Nov 11 15:02:07 Mooninite kernel: iwlwifi 0000:02:00.0:              CSR_OTP_GP_REG: 0Xd55555d5
Nov 11 15:02:07 Mooninite kernel: iwlwifi 0000:02:00.0:                 CSR_GIO_REG: 0X001f0042
Nov 11 15:02:07 Mooninite kernel: iwlwifi 0000:02:00.0:            CSR_GP_UCODE_REG: 0X00000000
Nov 11 15:02:07 Mooninite kernel: iwlwifi 0000:02:00.0:           CSR_GP_DRIVER_REG: 0X00000000
Nov 11 15:02:07 Mooninite kernel: iwlwifi 0000:02:00.0:           CSR_UCODE_DRV_GP1: 0X00000000
Nov 11 15:02:07 Mooninite kernel: iwlwifi 0000:02:00.0:           CSR_UCODE_DRV_GP2: 0X00000000
Nov 11 15:02:07 Mooninite kernel: iwlwifi 0000:02:00.0:                 CSR_LED_REG: 0X00000020
Nov 11 15:02:07 Mooninite kernel: iwlwifi 0000:02:00.0:        CSR_DRAM_INT_TBL_REG: 0X88054dfd
Nov 11 15:02:07 Mooninite kernel: iwlwifi 0000:02:00.0:        CSR_GIO_CHICKEN_BITS: 0X07800200
Nov 11 15:02:07 Mooninite kernel: iwlwifi 0000:02:00.0:             CSR_ANA_PLL_CFG: 0Xd55555d5
Nov 11 15:02:07 Mooninite kernel: iwlwifi 0000:02:00.0:      CSR_MONITOR_STATUS_REG: 0X3f7f8610
Nov 11 15:02:07 Mooninite kernel: iwlwifi 0000:02:00.0:           CSR_HW_REV_WA_REG: 0X0001001a
Nov 11 15:02:07 Mooninite kernel: iwlwifi 0000:02:00.0:        CSR_DBG_HPET_MEM_REG: 0Xffff0000
Nov 11 15:02:07 Mooninite kernel: iwlwifi 0000:02:00.0: FH register values:
Nov 11 15:02:07 Mooninite kernel: iwlwifi 0000:02:00.0:         FH_RSCSR_CHNL0_STTS_WPTR_REG: 0X054dfe80
Nov 11 15:02:07 Mooninite kernel: iwlwifi 0000:02:00.0:        FH_RSCSR_CHNL0_RBDCB_BASE_REG: 0X0054dfe0
Nov 11 15:02:07 Mooninite kernel: iwlwifi 0000:02:00.0:                  FH_RSCSR_CHNL0_WPTR: 0X00000000
Nov 11 15:02:07 Mooninite kernel: iwlwifi 0000:02:00.0:         FH_MEM_RCSR_CHNL0_CONFIG_REG: 0X00801054
Nov 11 15:02:07 Mooninite kernel: iwlwifi 0000:02:00.0:          FH_MEM_RSSR_SHARED_CTRL_REG: 0X000000fc
Nov 11 15:02:07 Mooninite kernel: iwlwifi 0000:02:00.0:            FH_MEM_RSSR_RX_STATUS_REG: 0X03030000
Nov 11 15:02:07 Mooninite kernel: iwlwifi 0000:02:00.0:    FH_MEM_RSSR_RX_ENABLE_ERR_IRQ2DRV: 0X00000000
Nov 11 15:02:07 Mooninite kernel: iwlwifi 0000:02:00.0:                FH_TSSR_TX_STATUS_REG: 0X07ff0003
Nov 11 15:02:07 Mooninite kernel: iwlwifi 0000:02:00.0:                 FH_TSSR_TX_ERROR_REG: 0X00000000
Nov 11 15:02:07 Mooninite kernel: iwlwifi 0000:02:00.0: Start IWL Error Log Dump:
Nov 11 15:02:07 Mooninite kernel: iwlwifi 0000:02:00.0: Status: 0x00000000, count: 6
Nov 11 15:02:07 Mooninite kernel: iwlwifi 0000:02:00.0: Loaded firmware version: 22.361476.0
Nov 11 15:02:07 Mooninite kernel: iwlwifi 0000:02:00.0: 0x00000071 | ADVANCED_SYSASSERT          
Nov 11 15:02:07 Mooninite kernel: iwlwifi 0000:02:00.0: 0x000002F0 | trm_hw_status0
Nov 11 15:02:07 Mooninite kernel: iwlwifi 0000:02:00.0: 0x00000000 | trm_hw_status1
Nov 11 15:02:07 Mooninite kernel: iwlwifi 0000:02:00.0: 0x0000E9C8 | branchlink2
Nov 11 15:02:07 Mooninite kernel: iwlwifi 0000:02:00.0: 0x0002843C | interruptlink1
Nov 11 15:02:07 Mooninite kernel: iwlwifi 0000:02:00.0: 0x0002843C | interruptlink2
Nov 11 15:02:07 Mooninite kernel: iwlwifi 0000:02:00.0: 0x00000000 | data1
Nov 11 15:02:07 Mooninite kernel: iwlwifi 0000:02:00.0: 0x00001000 | data2
Nov 11 15:02:07 Mooninite kernel: iwlwifi 0000:02:00.0: 0x02039842 | data3
Nov 11 15:02:07 Mooninite kernel: iwlwifi 0000:02:00.0: 0x003FE349 | beacon time
Nov 11 15:02:07 Mooninite kernel: iwlwifi 0000:02:00.0: 0x00001CB5 | tsf low
Nov 11 15:02:07 Mooninite kernel: iwlwifi 0000:02:00.0: 0x00000000 | tsf hi
Nov 11 15:02:07 Mooninite kernel: iwlwifi 0000:02:00.0: 0x00000000 | time gp1
Nov 11 15:02:07 Mooninite kernel: iwlwifi 0000:02:00.0: 0x00001CB6 | time gp2
Nov 11 15:02:07 Mooninite kernel: iwlwifi 0000:02:00.0: 0x00000000 | uCode revision type
Nov 11 15:02:07 Mooninite kernel: iwlwifi 0000:02:00.0: 0x00000016 | uCode version major
Nov 11 15:02:07 Mooninite kernel: iwlwifi 0000:02:00.0: 0x00058404 | uCode version minor
Nov 11 15:02:07 Mooninite kernel: iwlwifi 0000:02:00.0: 0x00000201 | hw version
Nov 11 15:02:07 Mooninite kernel: iwlwifi 0000:02:00.0: 0x00489008 | board version
Nov 11 15:02:07 Mooninite kernel: iwlwifi 0000:02:00.0: 0x00000000 | hcmd
Nov 11 15:02:07 Mooninite kernel: iwlwifi 0000:02:00.0: 0x00022000 | isr0
Nov 11 15:02:07 Mooninite kernel: iwlwifi 0000:02:00.0: 0x10800000 | isr1
Nov 11 15:02:07 Mooninite kernel: iwlwifi 0000:02:00.0: 0x18001802 | isr2
Nov 11 15:02:07 Mooninite kernel: iwlwifi 0000:02:00.0: 0x004000C0 | isr3
Nov 11 15:02:07 Mooninite kernel: iwlwifi 0000:02:00.0: 0x00000000 | isr4
Nov 11 15:02:07 Mooninite kernel: iwlwifi 0000:02:00.0: 0x00000110 | last cmd Id
Nov 11 15:02:07 Mooninite kernel: iwlwifi 0000:02:00.0: 0x00000000 | wait_event
Nov 11 15:02:07 Mooninite kernel: iwlwifi 0000:02:00.0: 0x000026F9 | l2p_control
Nov 11 15:02:07 Mooninite kernel: iwlwifi 0000:02:00.0: 0x00000000 | l2p_duration
Nov 11 15:02:07 Mooninite kernel: iwlwifi 0000:02:00.0: 0x00000000 | l2p_mhvalid
Nov 11 15:02:07 Mooninite kernel: iwlwifi 0000:02:00.0: 0x00000000 | l2p_addr_match
Nov 11 15:02:07 Mooninite kernel: iwlwifi 0000:02:00.0: 0x0000000F | lmpm_pmg_sel
Nov 11 15:02:07 Mooninite kernel: iwlwifi 0000:02:00.0: 0x03071928 | timestamp
Nov 11 15:02:07 Mooninite kernel: iwlwifi 0000:02:00.0: 0x00340008 | flow_handler
Nov 11 15:02:07 Mooninite kernel: iwlwifi 0000:02:00.0: Start IWL Error Log Dump:
Nov 11 15:02:07 Mooninite kernel: iwlwifi 0000:02:00.0: Status: 0x00000000, count: 7
Nov 11 15:02:07 Mooninite kernel: iwlwifi 0000:02:00.0: 0x00000309 | ADVANCED_SYSASSERT
Nov 11 15:02:07 Mooninite kernel: iwlwifi 0000:02:00.0: 0x00000000 | umac branchlink1
Nov 11 15:02:07 Mooninite kernel: iwlwifi 0000:02:00.0: 0xC008383C | umac branchlink2
Nov 11 15:02:07 Mooninite kernel: iwlwifi 0000:02:00.0: 0xC008166C | umac interruptlink1
Nov 11 15:02:07 Mooninite kernel: iwlwifi 0000:02:00.0: 0x00000000 | umac interruptlink2
Nov 11 15:02:07 Mooninite kernel: iwlwifi 0000:02:00.0: 0xDEADBEEF | umac data1
Nov 11 15:02:07 Mooninite kernel: iwlwifi 0000:02:00.0: 0xDEADBEEF | umac data2
Nov 11 15:02:07 Mooninite kernel: iwlwifi 0000:02:00.0: 0xDEADBEEF | umac data3
Nov 11 15:02:07 Mooninite kernel: iwlwifi 0000:02:00.0: 0x00000016 | umac major
Nov 11 15:02:07 Mooninite kernel: iwlwifi 0000:02:00.0: 0x00058404 | umac minor
Nov 11 15:02:07 Mooninite kernel: iwlwifi 0000:02:00.0: 0xC0887DEC | frame pointer
Nov 11 15:02:07 Mooninite kernel: iwlwifi 0000:02:00.0: 0xC0887DEC | stack pointer
Nov 11 15:02:07 Mooninite kernel: iwlwifi 0000:02:00.0: 0x0900014F | last host cmd
Nov 11 15:02:07 Mooninite kernel: iwlwifi 0000:02:00.0: 0x00080002 | isr status reg
Nov 11 15:02:07 Mooninite kernel: ieee80211 phy0: Hardware restart was requested
Nov 11 15:02:07 Mooninite kernel: iwlwifi 0000:02:00.0: FW error in SYNC CMD FW_PAGING_BLOCK_CMD
Nov 11 15:02:07 Mooninite kernel: CPU: 6 PID: 2323 Comm: ifconfig Tainted: G           OE   4.8.0-22-generic #24-Ubuntu
Nov 11 15:02:07 Mooninite kernel: Hardware name: Dell Inc. Precision 7510/0HV79W, BIOS 1.8.3 10/07/2016
Nov 11 15:02:07 Mooninite kernel: 0000000000000286 00000000981d90cf ffff94de05b67890 ffffffff9ca2f5d2
Nov 11 15:02:07 Mooninite kernel: ffff94ddfdf88000 00000000fffffffb ffff94de05b67900 ffffffffc0959831
Nov 11 15:02:07 Mooninite kernel: 000000004dffc240 0000000000000000 ffff94ddfe7c5880 ffffffff9c6c73a0
Nov 11 15:02:07 Mooninite kernel: Call Trace:
Nov 11 15:02:07 Mooninite kernel: [<ffffffff9ca2f5d2>] dump_stack+0x63/0x81
Nov 11 15:02:07 Mooninite kernel: [<ffffffffc0959831>] iwl_trans_pcie_send_hcmd+0x461/0x580 [iwlwifi]
Nov 11 15:02:07 Mooninite kernel: [<ffffffff9c6c73a0>] ? wake_atomic_t_function+0x60/0x60
Nov 11 15:02:07 Mooninite kernel: [<ffffffffc09602ae>] iwl_trans_send_cmd+0x3e/0x90 [iwlwifi]
Nov 11 15:02:07 Mooninite kernel: [<ffffffffc0ac0222>] iwl_mvm_send_cmd+0x32/0xb0 [iwlmvm]
Nov 11 15:02:07 Mooninite kernel: [<ffffffffc0ac02ef>] iwl_mvm_send_cmd_pdu+0x4f/0x70 [iwlmvm]
Nov 11 15:02:07 Mooninite kernel: [<ffffffffc0ab24ac>] iwl_send_paging_cmd.isra.16+0xac/0xd0 [iwlmvm]
Nov 11 15:02:07 Mooninite kernel: [<ffffffffc0ab2efe>] iwl_mvm_load_ucode_wait_alive+0x74e/0x8b0 [iwlmvm]
Nov 11 15:02:07 Mooninite kernel: [<ffffffffc0ab2000>] ? 0xffffffffc0ab2000
Nov 11 15:02:07 Mooninite kernel: [<ffffffffc0ab34b6>] iwl_mvm_up+0x136/0x9b0 [iwlmvm]
Nov 11 15:02:07 Mooninite kernel: [<ffffffff9c80a996>] ? kmem_cache_alloc+0x146/0x1a0
Nov 11 15:02:07 Mooninite kernel: [<ffffffff9c84d887>] ? __d_alloc+0x27/0x1d0
Nov 11 15:02:07 Mooninite kernel: [<ffffffff9c85f7f4>] ? __inode_wait_for_writeback+0x74/0xf0
Nov 11 15:02:07 Mooninite kernel: [<ffffffff9c80a996>] ? kmem_cache_alloc+0x146/0x1a0
Nov 11 15:02:07 Mooninite kernel: [<ffffffff9c84d887>] ? __d_alloc+0x27/0x1d0
Nov 11 15:02:07 Mooninite kernel: [<ffffffffc0ab9a1e>] __iwl_mvm_mac_start+0x20e/0x350 [iwlmvm]
Nov 11 15:02:07 Mooninite kernel: [<ffffffffc0ab9baa>] iwl_mvm_mac_start+0x4a/0x130 [iwlmvm]
Nov 11 15:02:07 Mooninite kernel: [<ffffffff9cdffbbd>] ? inetdev_event+0x3d/0x4e0
Nov 11 15:02:07 Mooninite kernel: [<ffffffff9cd6bad9>] ? skb_dequeue+0x59/0x70
Nov 11 15:02:07 Mooninite kernel: [<ffffffffc09cb724>] drv_start+0x44/0x100 [mac80211]
Nov 11 15:02:07 Mooninite kernel: [<ffffffffc09e1826>] ieee80211_do_open+0x2a6/0x980 [mac80211]
Nov 11 15:02:07 Mooninite kernel: [<ffffffffc09e1f52>] ieee80211_open+0x52/0x60 [mac80211]
Nov 11 15:02:07 Mooninite kernel: [<ffffffff9cd863b6>] __dev_open+0xc6/0x140
Nov 11 15:02:07 Mooninite kernel: [<ffffffff9ce9ecde>] ? _raw_spin_unlock_bh+0x1e/0x20
Nov 11 15:02:07 Mooninite kernel: [<ffffffff9cd866cd>] __dev_change_flags+0x9d/0x160
Nov 11 15:02:07 Mooninite kernel: [<ffffffff9cd867b9>] dev_change_flags+0x29/0x60
Nov 11 15:02:07 Mooninite kernel: [<ffffffff9cdff665>] devinet_ioctl+0x5f5/0x6c0
Nov 11 15:02:07 Mooninite kernel: [<ffffffff9ce012b0>] inet_ioctl+0x50/0x70
Nov 11 15:02:07 Mooninite kernel: [<ffffffff9cd62495>] sock_do_ioctl+0x25/0x50
Nov 11 15:02:07 Mooninite kernel: [<ffffffff9cd629b2>] sock_ioctl+0x1d2/0x290
Nov 11 15:02:07 Mooninite kernel: [<ffffffff9c847843>] do_vfs_ioctl+0xa3/0x610
Nov 11 15:02:07 Mooninite kernel: [<ffffffff9c8432b4>] ? putname+0x54/0x60
Nov 11 15:02:07 Mooninite kernel: [<ffffffff9c83158c>] ? do_sys_open+0x1bc/0x280
Nov 11 15:02:07 Mooninite kernel: [<ffffffff9c847e29>] SyS_ioctl+0x79/0x90
Nov 11 15:02:07 Mooninite kernel: [<ffffffff9ce9f076>] entry_SYSCALL_64_fastpath+0x1e/0xa8
Nov 11 15:02:07 Mooninite kernel: iwlwifi 0000:02:00.0: failed to send the paging cmd
Nov 11 15:02:07 Mooninite kernel: iwlwifi 0000:02:00.0: Failed to start RT ucode: -5
Comment 1 Paul Donohue 2016-11-11 21:04:26 UTC
No change if I run `ifconfig wlan0 up` without running `ip link set dev wlan0 address ...` first.

Stock kernel (4.8.0-22) with linux-firmware 1.161 (containing iwlwifi-8000C-22.ucode) also crashes.  Removing the -22 firmware and using -21 (21.302800.0) works fine.
Comment 2 Emmanuel Grumbach 2016-11-17 21:14:48 UTC
*** Bug 187741 has been marked as a duplicate of this bug. ***
Comment 3 Emmanuel Grumbach 2016-11-17 21:24:12 UTC
Created attachment 244951 [details]
latest Core19 FW

Hi,

does it reproduce with the firmware attached?

thanks
Comment 4 Paul Donohue 2016-11-18 01:02:28 UTC
Yes, still fails:

[    6.861462] iwlwifi 0000:02:00.0: Unsupported splx structure
[    6.873188] iwlwifi 0000:02:00.0: Direct firmware load for iwlwifi-8000C-26.ucode failed with error -2
[    6.873206] iwlwifi 0000:02:00.0: Direct firmware load for iwlwifi-8000C-25.ucode failed with error -2
[    6.873214] iwlwifi 0000:02:00.0: Direct firmware load for iwlwifi-8000C-24.ucode failed with error -2
[    6.873222] iwlwifi 0000:02:00.0: Direct firmware load for iwlwifi-8000C-23.ucode failed with error -2
[    6.903570] iwlwifi 0000:02:00.0: loaded firmware version 22.391740.0 op_mode iwlmvm
[    6.952444] iwlwifi 0000:02:00.0: Detected Intel(R) Dual Band Wireless AC 8260, REV=0x208
[    6.954499] iwlwifi 0000:02:00.0: L1 Enabled - LTR Enabled
[    6.955196] iwlwifi 0000:02:00.0: L1 Enabled - LTR Enabled
[    7.085015] ieee80211 phy0: Selected rate control algorithm 'iwl-mvm-rs'
[   17.190749] iwlwifi 0000:02:00.0: L1 Enabled - LTR Enabled
[   17.191013] iwlwifi 0000:02:00.0: L1 Enabled - LTR Enabled
[   17.318661] iwlwifi 0000:02:00.0: L1 Enabled - LTR Enabled
[   17.318938] iwlwifi 0000:02:00.0: L1 Enabled - LTR Enabled
[   17.382801] iwlwifi 0000:02:00.0: Microcode SW error detected.  Restarting 0x82000000.
[   17.384094] iwlwifi 0000:02:00.0: CSR values:
[   17.385367] iwlwifi 0000:02:00.0: (2nd byte of CSR_INT_COALESCING is CSR_INT_PERIODIC_REG)
[   17.386718] iwlwifi 0000:02:00.0:        CSR_HW_IF_CONFIG_REG: 0X00489008
[   17.388065] iwlwifi 0000:02:00.0:          CSR_INT_COALESCING: 0X0000ff40
[   17.389345] iwlwifi 0000:02:00.0:                     CSR_INT: 0X10000000
[   17.390666] iwlwifi 0000:02:00.0:                CSR_INT_MASK: 0X00000000
[   17.391999] iwlwifi 0000:02:00.0:           CSR_FH_INT_STATUS: 0X00000000
[   17.393276] iwlwifi 0000:02:00.0:                 CSR_GPIO_IN: 0X00000019
[   17.394538] iwlwifi 0000:02:00.0:                   CSR_RESET: 0X00000000
[   17.395874] iwlwifi 0000:02:00.0:                CSR_GP_CNTRL: 0X08040005
[   17.397231] iwlwifi 0000:02:00.0:                  CSR_HW_REV: 0X00000201
[   17.398489] iwlwifi 0000:02:00.0:              CSR_EEPROM_REG: 0Xd55555d5
[   17.399820] iwlwifi 0000:02:00.0:               CSR_EEPROM_GP: 0Xd55555d5
[   17.401155] iwlwifi 0000:02:00.0:              CSR_OTP_GP_REG: 0Xd55555d5
[   17.402408] iwlwifi 0000:02:00.0:                 CSR_GIO_REG: 0X001f0042
[   17.403722] iwlwifi 0000:02:00.0:            CSR_GP_UCODE_REG: 0X00000000
[   17.405046] iwlwifi 0000:02:00.0:           CSR_GP_DRIVER_REG: 0X00000000
[   17.406249] iwlwifi 0000:02:00.0:           CSR_UCODE_DRV_GP1: 0X00000000
[   17.407557] iwlwifi 0000:02:00.0:           CSR_UCODE_DRV_GP2: 0X00000000
[   17.408831] iwlwifi 0000:02:00.0:                 CSR_LED_REG: 0X00000020
[   17.410053] iwlwifi 0000:02:00.0:        CSR_DRAM_INT_TBL_REG: 0X88054dfe
[   17.411337] iwlwifi 0000:02:00.0:        CSR_GIO_CHICKEN_BITS: 0X07800200
[   17.412597] iwlwifi 0000:02:00.0:             CSR_ANA_PLL_CFG: 0Xd55555d5
[   17.413830] iwlwifi 0000:02:00.0:      CSR_MONITOR_STATUS_REG: 0Xc03803c0
[   17.415106] iwlwifi 0000:02:00.0:           CSR_HW_REV_WA_REG: 0X0001001a
[   17.416344] iwlwifi 0000:02:00.0:        CSR_DBG_HPET_MEM_REG: 0Xffff0000
[   17.417533] iwlwifi 0000:02:00.0: FH register values:
[   17.418886] iwlwifi 0000:02:00.0:         FH_RSCSR_CHNL0_STTS_WPTR_REG: 0X054dff80
[   17.420115] iwlwifi 0000:02:00.0:        FH_RSCSR_CHNL0_RBDCB_BASE_REG: 0X0054dff0
[   17.421318] iwlwifi 0000:02:00.0:                  FH_RSCSR_CHNL0_WPTR: 0X00000000
[   17.422513] iwlwifi 0000:02:00.0:         FH_MEM_RCSR_CHNL0_CONFIG_REG: 0X00801054
[   17.423834] iwlwifi 0000:02:00.0:          FH_MEM_RSSR_SHARED_CTRL_REG: 0X000000fc
[   17.425033] iwlwifi 0000:02:00.0:            FH_MEM_RSSR_RX_STATUS_REG: 0X03030000
[   17.426202] iwlwifi 0000:02:00.0:    FH_MEM_RSSR_RX_ENABLE_ERR_IRQ2DRV: 0X00000000
[   17.427523] iwlwifi 0000:02:00.0:                FH_TSSR_TX_STATUS_REG: 0X07ff0003
[   17.428705] iwlwifi 0000:02:00.0:                 FH_TSSR_TX_ERROR_REG: 0X00000000
[   17.429954] iwlwifi 0000:02:00.0: Start IWL Error Log Dump:
[   17.431109] iwlwifi 0000:02:00.0: Status: 0x00000000, count: 6
[   17.432278] iwlwifi 0000:02:00.0: Loaded firmware version: 22.391740.0
[   17.433375] iwlwifi 0000:02:00.0: 0x00000071 | ADVANCED_SYSASSERT          
[   17.434471] iwlwifi 0000:02:00.0: 0x000002F0 | trm_hw_status0
[   17.435599] iwlwifi 0000:02:00.0: 0x00000000 | trm_hw_status1
[   17.436763] iwlwifi 0000:02:00.0: 0x0000E9C8 | branchlink2
[   17.437846] iwlwifi 0000:02:00.0: 0x00028454 | interruptlink1
[   17.438961] iwlwifi 0000:02:00.0: 0x00028454 | interruptlink2
[   17.440073] iwlwifi 0000:02:00.0: 0x00000000 | data1
[   17.441129] iwlwifi 0000:02:00.0: 0x00001000 | data2
[   17.442183] iwlwifi 0000:02:00.0: 0x02039842 | data3
[   17.443269] iwlwifi 0000:02:00.0: 0x003FE34A | beacon time
[   17.444412] iwlwifi 0000:02:00.0: 0x00001CB4 | tsf low
[   17.445440] iwlwifi 0000:02:00.0: 0x00000000 | tsf hi
[   17.446480] iwlwifi 0000:02:00.0: 0x00000000 | time gp1
[   17.447533] iwlwifi 0000:02:00.0: 0x00001CB5 | time gp2
[   17.448640] iwlwifi 0000:02:00.0: 0x00000000 | uCode revision type
[   17.449646] iwlwifi 0000:02:00.0: 0x00000016 | uCode version major
[   17.450691] iwlwifi 0000:02:00.0: 0x0005FA3C | uCode version minor
[   17.451780] iwlwifi 0000:02:00.0: 0x00000201 | hw version
[   17.452770] iwlwifi 0000:02:00.0: 0x00489008 | board version
[   17.453759] iwlwifi 0000:02:00.0: 0x00000000 | hcmd
[   17.454798] iwlwifi 0000:02:00.0: 0x00022000 | isr0
[   17.455866] iwlwifi 0000:02:00.0: 0x10800000 | isr1
[   17.456812] iwlwifi 0000:02:00.0: 0x18001802 | isr2
[   17.457777] iwlwifi 0000:02:00.0: 0x004000C0 | isr3
[   17.458800] iwlwifi 0000:02:00.0: 0x00000000 | isr4
[   17.459858] iwlwifi 0000:02:00.0: 0x00000110 | last cmd Id
[   17.460839] iwlwifi 0000:02:00.0: 0x00000000 | wait_event
[   17.461806] iwlwifi 0000:02:00.0: 0x00002EF9 | l2p_control
[   17.462807] iwlwifi 0000:02:00.0: 0x00000000 | l2p_duration
[   17.463848] iwlwifi 0000:02:00.0: 0x00000000 | l2p_mhvalid
[   17.464801] iwlwifi 0000:02:00.0: 0x00000000 | l2p_addr_match
[   17.465739] iwlwifi 0000:02:00.0: 0x0000000F | lmpm_pmg_sel
[   17.466719] iwlwifi 0000:02:00.0: 0x25091923 | timestamp
[   17.467738] iwlwifi 0000:02:00.0: 0x00340008 | flow_handler
[   17.468751] iwlwifi 0000:02:00.0: Start IWL Error Log Dump:
[   17.469686] iwlwifi 0000:02:00.0: Status: 0x00000000, count: 7
[   17.470663] iwlwifi 0000:02:00.0: 0x00000309 | ADVANCED_SYSASSERT
[   17.471697] iwlwifi 0000:02:00.0: 0x00000000 | umac branchlink1
[   17.472620] iwlwifi 0000:02:00.0: 0xC008383C | umac branchlink2
[   17.473520] iwlwifi 0000:02:00.0: 0xC008166C | umac interruptlink1
[   17.474440] iwlwifi 0000:02:00.0: 0x00000000 | umac interruptlink2
[   17.475416] iwlwifi 0000:02:00.0: 0xDEADBEEF | umac data1
[   17.476405] iwlwifi 0000:02:00.0: 0xDEADBEEF | umac data2
[   17.477308] iwlwifi 0000:02:00.0: 0xDEADBEEF | umac data3
[   17.478201] iwlwifi 0000:02:00.0: 0x00000016 | umac major
[   17.479128] iwlwifi 0000:02:00.0: 0x0005FA3C | umac minor
[   17.480075] iwlwifi 0000:02:00.0: 0xC0887DEC | frame pointer
[   17.480958] iwlwifi 0000:02:00.0: 0xC0887DEC | stack pointer
[   17.481809] iwlwifi 0000:02:00.0: 0x0900014F | last host cmd
[   17.482715] iwlwifi 0000:02:00.0: 0x00080002 | isr status reg
[   17.483652] ieee80211 phy0: Hardware restart was requested
[   17.483699] iwlwifi 0000:02:00.0: FW error in SYNC CMD FW_PAGING_BLOCK_CMD
[   17.484623] CPU: 0 PID: 1632 Comm: wpa_supplicant Tainted: G           OE   4.9.0-040900rc5-generic #201611131431
[   17.484623] Hardware name: Dell Inc. Precision 7510/0HV79W, BIOS 1.8.3 10/07/2016
[   17.484624]  ffffb3ee46e9b7f0 ffffffffbc614df3 ffffa00101960018 ffffb3ee46e9b8b0
[   17.484625]  ffffb3ee46e9b860 ffffffffc0aa4c74 ffffa00101967e70 0000000000000000
[   17.484627]  ffffa00106574b00 ffffffffbc2c88e0 ffffb3ee46e9b820 ffffb3ee46e9b820
[   17.484628] Call Trace:
[   17.484631]  [<ffffffffbc614df3>] dump_stack+0x63/0x90
[   17.484638]  [<ffffffffc0aa4c74>] iwl_trans_pcie_send_hcmd+0x454/0x580 [iwlwifi]
[   17.484639]  [<ffffffffbc2c88e0>] ? wake_atomic_t_function+0x60/0x60
[   17.484643]  [<ffffffffc0aaba47>] iwl_trans_send_cmd+0x57/0xb0 [iwlwifi]
[   17.484648]  [<ffffffffc0bf8162>] iwl_mvm_send_cmd+0x32/0xb0 [iwlmvm]
[   17.484651]  [<ffffffffc0bf822f>] iwl_mvm_send_cmd_pdu+0x4f/0x70 [iwlmvm]
[   17.484654]  [<ffffffffc0bea4fa>] iwl_send_paging_cmd.isra.16+0xfa/0x120 [iwlmvm]
[   17.484656]  [<ffffffffc0beaf31>] iwl_mvm_load_ucode_wait_alive+0x731/0x8a0 [iwlmvm]
[   17.484657]  [<ffffffffc0bea000>] ? 0xffffffffc0bea000
[   17.484660]  [<ffffffffc0beb4f1>] iwl_mvm_up+0x131/0x980 [iwlmvm]
[   17.484661]  [<ffffffffbc40db66>] ? kmem_cache_alloc+0x156/0x1b0
[   17.484662]  [<ffffffffbc450087>] ? __d_alloc+0x27/0x1e0
[   17.484664]  [<ffffffffbc953ae9>] ? skb_dequeue+0x59/0x70
[   17.484666]  [<ffffffffbca64404>] ? wireless_nlevent_flush+0x54/0x90
[   17.484669]  [<ffffffffc0bf1b9a>] __iwl_mvm_mac_start+0x21a/0x320 [iwlmvm]
[   17.484670]  [<ffffffffbca6e28d>] ? rfkill_fop_open+0x1cd/0x1f0
[   17.484673]  [<ffffffffc0bf1cea>] iwl_mvm_mac_start+0x4a/0x130 [iwlmvm]
[   17.484674]  [<ffffffffbc9e8d7d>] ? inetdev_event+0x3d/0x4f0
[   17.484675]  [<ffffffffbc953ae9>] ? skb_dequeue+0x59/0x70
[   17.484686]  [<ffffffffc0afc844>] drv_start+0x44/0x110 [mac80211]
[   17.484693]  [<ffffffffc0b13336>] ieee80211_do_open+0x2a6/0x990 [mac80211]
[   17.484699]  [<ffffffffc0b13a78>] ieee80211_open+0x58/0x60 [mac80211]
[   17.484701]  [<ffffffffbc96e7b8>] __dev_open+0xc8/0x140
[   17.484702]  [<ffffffffbc96eacd>] __dev_change_flags+0x9d/0x160
[   17.484703]  [<ffffffffbc96ebb9>] dev_change_flags+0x29/0x70  
[   17.484704]  [<ffffffffbc9e880d>] devinet_ioctl+0x5fd/0x6d0
[   17.484706]  [<ffffffffbca85432>] ? mutex_lock+0x12/0x30
[   17.484707]  [<ffffffffbc9ea420>] inet_ioctl+0x50/0x70
[   17.484707]  [<ffffffffbc94a495>] sock_do_ioctl+0x25/0x50
[   17.484708]  [<ffffffffbc94a9b2>] sock_ioctl+0x1d2/0x290
[   17.484709]  [<ffffffffbc44a0c1>] do_vfs_ioctl+0xa1/0x5e0
[   17.484711]  [<ffffffffbc5769cb>] ? security_file_permission+0x9b/0xc0
[   17.484712]  [<ffffffffbc44a679>] SyS_ioctl+0x79/0x90
[   17.484713]  [<ffffffffbca8827b>] entry_SYSCALL_64_fastpath+0x1e/0xad
[   17.484714] iwlwifi 0000:02:00.0: failed to send the paging cmd
[   17.485581] iwlwifi 0000:02:00.0: Failed to start RT ucode: -5
Comment 5 Luca Coelho 2016-11-23 12:21:06 UTC
Just for the record, the reporter of bug 187741 (duplicate) saw the same problem happen with 4.8.7.
Comment 6 Luca Coelho 2016-11-24 06:35:00 UTC
Created internal bug for tracking.
Comment 7 Matteo Pescarin 2016-12-04 15:27:39 UTC
I'm having the same problem and it's becoming an annoyance, both with kernel 4.7 and 4.8.

Following the relevant output from the kernel:

Nov 22 18:43:43 lifetree kernel: iwlwifi 0000:03:00.0: Microcode SW error detected.  Restarting 0x2000000.
Nov 22 18:43:43 lifetree kernel: iwlwifi 0000:03:00.0: CSR values:
Nov 22 18:43:43 lifetree kernel: iwlwifi 0000:03:00.0: (2nd byte of CSR_INT_COALESCING is CSR_INT_PERIODIC_REG)
Nov 22 18:43:43 lifetree kernel: iwlwifi 0000:03:00.0:        CSR_HW_IF_CONFIG_REG: 0X0048d704
Nov 22 18:43:43 lifetree kernel: iwlwifi 0000:03:00.0:          CSR_INT_COALESCING: 0X00000040
Nov 22 18:43:43 lifetree kernel: iwlwifi 0000:03:00.0:                     CSR_INT: 0X00000000
Nov 22 18:43:43 lifetree kernel: iwlwifi 0000:03:00.0:                CSR_INT_MASK: 0X00000000
Nov 22 18:43:43 lifetree kernel: iwlwifi 0000:03:00.0:           CSR_FH_INT_STATUS: 0X00000000
Nov 22 18:43:43 lifetree kernel: iwlwifi 0000:03:00.0:                 CSR_GPIO_IN: 0X0000000f
Nov 22 18:43:43 lifetree kernel: iwlwifi 0000:03:00.0:                   CSR_RESET: 0X00000000
Nov 22 18:43:43 lifetree kernel: iwlwifi 0000:03:00.0:                CSR_GP_CNTRL: 0X080403c5
Nov 22 18:43:43 lifetree kernel: iwlwifi 0000:03:00.0:                  CSR_HW_REV: 0X00000074
Nov 22 18:43:43 lifetree kernel: iwlwifi 0000:03:00.0:              CSR_EEPROM_REG: 0X54400ffd
Nov 22 18:43:43 lifetree kernel: iwlwifi 0000:03:00.0:               CSR_EEPROM_GP: 0X90000001
Nov 22 18:43:43 lifetree kernel: iwlwifi 0000:03:00.0:              CSR_OTP_GP_REG: 0X00030001
Nov 22 18:43:43 lifetree kernel: iwlwifi 0000:03:00.0:                 CSR_GIO_REG: 0X00080042
Nov 22 18:43:43 lifetree kernel: iwlwifi 0000:03:00.0:            CSR_GP_UCODE_REG: 0X000022cc
Nov 22 18:43:43 lifetree kernel: iwlwifi 0000:03:00.0:           CSR_GP_DRIVER_REG: 0X00000002
Nov 22 18:43:43 lifetree kernel: iwlwifi 0000:03:00.0:           CSR_UCODE_DRV_GP1: 0X00000000
Nov 22 18:43:43 lifetree kernel: iwlwifi 0000:03:00.0:           CSR_UCODE_DRV_GP2: 0X00000000
Nov 22 18:43:43 lifetree kernel: iwlwifi 0000:03:00.0:                 CSR_LED_REG: 0X00000040
Nov 22 18:43:43 lifetree kernel: iwlwifi 0000:03:00.0:        CSR_DRAM_INT_TBL_REG: 0X88231178
Nov 22 18:43:43 lifetree kernel: iwlwifi 0000:03:00.0:        CSR_GIO_CHICKEN_BITS: 0X27800200
Nov 22 18:43:43 lifetree kernel: iwlwifi 0000:03:00.0:             CSR_ANA_PLL_CFG: 0X00000000
Nov 22 18:43:43 lifetree kernel: iwlwifi 0000:03:00.0:      CSR_MONITOR_STATUS_REG: 0X7bf7f757
Nov 22 18:43:43 lifetree kernel: iwlwifi 0000:03:00.0:           CSR_HW_REV_WA_REG: 0X0001001a
Nov 22 18:43:43 lifetree kernel: iwlwifi 0000:03:00.0:        CSR_DBG_HPET_MEM_REG: 0Xffff0000
Nov 22 18:43:43 lifetree kernel: iwlwifi 0000:03:00.0: FH register values:
Nov 22 18:43:43 lifetree kernel: iwlwifi 0000:03:00.0:         FH_RSCSR_CHNL0_STTS_WPTR_REG: 0X228fe800
Nov 22 18:43:43 lifetree kernel: iwlwifi 0000:03:00.0:        FH_RSCSR_CHNL0_RBDCB_BASE_REG: 0X0228cc90
Nov 22 18:43:43 lifetree kernel: iwlwifi 0000:03:00.0:                  FH_RSCSR_CHNL0_WPTR: 0X00000000
Nov 22 18:43:43 lifetree kernel: iwlwifi 0000:03:00.0:         FH_MEM_RCSR_CHNL0_CONFIG_REG: 0X00801114
Nov 22 18:43:43 lifetree kernel: iwlwifi 0000:03:00.0:          FH_MEM_RSSR_SHARED_CTRL_REG: 0X000000fc
Nov 22 18:43:43 lifetree kernel: iwlwifi 0000:03:00.0:            FH_MEM_RSSR_RX_STATUS_REG: 0X07030000
Nov 22 18:43:43 lifetree kernel: iwlwifi 0000:03:00.0:    FH_MEM_RSSR_RX_ENABLE_ERR_IRQ2DRV: 0X00000000
Nov 22 18:43:43 lifetree kernel: iwlwifi 0000:03:00.0:                FH_TSSR_TX_STATUS_REG: 0X07ff0001
Nov 22 18:43:43 lifetree kernel: iwlwifi 0000:03:00.0:                 FH_TSSR_TX_ERROR_REG: 0X00000000
Nov 22 18:43:43 lifetree kernel: iwlwifi 0000:03:00.0: Loaded firmware version: 9.221.4.1 build 25532
Nov 22 18:43:43 lifetree kernel: iwlwifi 0000:03:00.0: Start IWL Error Log Dump:
Nov 22 18:43:43 lifetree kernel: iwlwifi 0000:03:00.0: Status: 0x0000004C, count: 5
Nov 22 18:43:43 lifetree kernel: iwlwifi 0000:03:00.0: 0x00000004 | NMI_INTERRUPT_WDG           
Nov 22 18:43:43 lifetree kernel: iwlwifi 0000:03:00.0: 0x000006B4 | uPc
Nov 22 18:43:43 lifetree kernel: iwlwifi 0000:03:00.0: 0x0000067A | branchlink1
Nov 22 18:43:43 lifetree kernel: iwlwifi 0000:03:00.0: 0x0000071A | branchlink2
Nov 22 18:43:43 lifetree kernel: iwlwifi 0000:03:00.0: 0x00001532 | interruptlink1
Nov 22 18:43:43 lifetree kernel: iwlwifi 0000:03:00.0: 0x00000A5A | interruptlink2
Nov 22 18:43:43 lifetree kernel: iwlwifi 0000:03:00.0: 0x00000002 | data1
Nov 22 18:43:43 lifetree kernel: iwlwifi 0000:03:00.0: 0x07030000 | data2
Nov 22 18:43:43 lifetree kernel: iwlwifi 0000:03:00.0: 0x00000109 | line
Nov 22 18:43:43 lifetree kernel: iwlwifi 0000:03:00.0: 0xDF4010CC | beacon time
Nov 22 18:43:43 lifetree kernel: iwlwifi 0000:03:00.0: 0xEC82FF34 | tsf low
Nov 22 18:43:43 lifetree kernel: iwlwifi 0000:03:00.0: 0x000000F7 | tsf hi
Nov 22 18:43:43 lifetree kernel: iwlwifi 0000:03:00.0: 0x00000000 | time gp1
Nov 22 18:43:43 lifetree kernel: iwlwifi 0000:03:00.0: 0x05A8E2F7 | time gp2
Nov 22 18:43:43 lifetree kernel: iwlwifi 0000:03:00.0: 0x00000000 | time gp3
Nov 22 18:43:43 lifetree kernel: iwlwifi 0000:03:00.0: 0x000109DD | uCode version
Nov 22 18:43:43 lifetree kernel: iwlwifi 0000:03:00.0: 0x00000074 | hw version
Nov 22 18:43:43 lifetree kernel: iwlwifi 0000:03:00.0: 0x0048D704 | board version
Nov 22 18:43:43 lifetree kernel: iwlwifi 0000:03:00.0: 0x04140048 | hcmd
Nov 22 18:43:43 lifetree kernel: iwlwifi 0000:03:00.0: 0xA7E63002 | isr0
Nov 22 18:43:43 lifetree kernel: iwlwifi 0000:03:00.0: 0x0103E000 | isr1
Nov 22 18:43:43 lifetree kernel: iwlwifi 0000:03:00.0: 0x0000001F | isr2
Nov 22 18:43:43 lifetree kernel: iwlwifi 0000:03:00.0: 0x1143F4C2 | isr3
Nov 22 18:43:43 lifetree kernel: iwlwifi 0000:03:00.0: 0x00000000 | isr4
Nov 22 18:43:43 lifetree kernel: iwlwifi 0000:03:00.0: 0x10800112 | isr_pref
Nov 22 18:43:43 lifetree kernel: iwlwifi 0000:03:00.0: 0x00000109 | wait_event
Nov 22 18:43:43 lifetree kernel: iwlwifi 0000:03:00.0: 0x000000B4 | l2p_control
Nov 22 18:43:43 lifetree kernel: iwlwifi 0000:03:00.0: 0x000000F0 | l2p_duration
Nov 22 18:43:43 lifetree kernel: iwlwifi 0000:03:00.0: 0x0000000F | l2p_mhvalid
Nov 22 18:43:43 lifetree kernel: iwlwifi 0000:03:00.0: 0x0010F0C6 | l2p_addr_match
Nov 22 18:43:43 lifetree kernel: iwlwifi 0000:03:00.0: 0x00000005 | lmpm_pmg_sel
Nov 22 18:43:43 lifetree kernel: iwlwifi 0000:03:00.0: 0x02061043 | timestamp
Nov 22 18:43:43 lifetree kernel: iwlwifi 0000:03:00.0: 0x00000018 | flow_handler
Nov 22 18:43:43 lifetree kernel: iwlwifi 0000:03:00.0: Log capacity 1024 is bogus, limit to 512 entries
Nov 22 18:43:43 lifetree kernel: iwlwifi 0000:03:00.0: Log write index 521 is bogus, limit to 512
Nov 22 18:43:43 lifetree kernel: iwlwifi 0000:03:00.0: Start IWL Event Log Dump: display last 20 entries
Nov 22 18:43:43 lifetree kernel: iwlwifi 0000:03:00.0: EVT_LOGT:0094952723:0x000000c4:0322
Nov 22 18:43:43 lifetree kernel: iwlwifi 0000:03:00.0: EVT_LOGT:0094952957:0x00000000:0302
Nov 22 18:43:43 lifetree kernel: iwlwifi 0000:03:00.0: EVT_LOGT:0094953014:0x000000b4:0303
Nov 22 18:43:43 lifetree kernel: iwlwifi 0000:03:00.0: EVT_LOGT:0094953020:0x0000000e:0322
Nov 22 18:43:43 lifetree kernel: iwlwifi 0000:03:00.0: EVT_LOGT:0094953020:0x0000003c:0322
Nov 22 18:43:43 lifetree kernel: iwlwifi 0000:03:00.0: EVT_LOGT:0094953024:0x000000c4:0322
Nov 22 18:43:43 lifetree kernel: iwlwifi 0000:03:00.0: EVT_LOGT:0094953187:0x00000000:0302
Nov 22 18:43:43 lifetree kernel: iwlwifi 0000:03:00.0: EVT_LOGT:0094953243:0x000000b4:0303
Nov 22 18:43:43 lifetree kernel: iwlwifi 0000:03:00.0: EVT_LOGT:0094953249:0x0000000e:0322
Nov 22 18:43:43 lifetree kernel: iwlwifi 0000:03:00.0: EVT_LOGT:0094953249:0x0000003c:0322
Nov 22 18:43:43 lifetree kernel: iwlwifi 0000:03:00.0: EVT_LOGT:0094953253:0x000000c4:0322
Nov 22 18:43:43 lifetree kernel: iwlwifi 0000:03:00.0: EVT_LOGT:0094953470:0x00000000:0302
Nov 22 18:43:43 lifetree kernel: iwlwifi 0000:03:00.0: EVT_LOGT:0094953526:0x000000b4:0303
Nov 22 18:43:43 lifetree kernel: iwlwifi 0000:03:00.0: EVT_LOGT:0094953532:0x0000000e:0322
Nov 22 18:43:43 lifetree kernel: iwlwifi 0000:03:00.0: EVT_LOGT:0094953532:0x0000003c:0322
Nov 22 18:43:43 lifetree kernel: iwlwifi 0000:03:00.0: EVT_LOGT:0094953536:0x000000c4:0322
Nov 22 18:43:43 lifetree kernel: iwlwifi 0000:03:00.0: EVT_LOGT:0094953635:0x00000000:0302
Nov 22 18:43:43 lifetree kernel: iwlwifi 0000:03:00.0: EVT_LOGT:0094953692:0x000000b4:0303
Nov 22 18:43:43 lifetree kernel: iwlwifi 0000:03:00.0: EVT_LOGT:0094953698:0x0000000e:0322
Nov 22 18:43:43 lifetree kernel: iwlwifi 0000:03:00.0: EVT_LOGT:0094953698:0x0000003c:0322
Nov 22 18:43:43 lifetree kernel: ieee80211 phy0: Hardware restart was requested
Nov 22 18:43:43 lifetree kernel: iwlwifi 0000:03:00.0: L1 Enabled - LTR Disabled
Nov 22 18:43:43 lifetree kernel: iwlwifi 0000:03:00.0: L1 Enabled - LTR Disabled
Nov 22 18:43:43 lifetree kernel: iwlwifi 0000:03:00.0: Radio type=0x1-0x3-0x1
Nov 22 18:43:43 lifetree kernel: iwlwifi 0000:03:00.0: L1 Enabled - LTR Disabled
Nov 22 18:43:43 lifetree kernel: iwlwifi 0000:03:00.0: L1 Enabled - LTR Disabled
Nov 22 18:43:43 lifetree kernel: iwlwifi 0000:03:00.0: Radio type=0x1-0x3-0x1
Comment 8 Emmanuel Grumbach 2016-12-04 16:52:54 UTC
Matteo, this is a completely differently thing. This error is a firmware bug on an old device for which we don't provide firmware support.
Comment 9 Matteo Pescarin 2016-12-04 19:54:19 UTC
@Emmanuel: I noticed it was on a different bug. I've opened a new bug report specific for my problem: https://bugzilla.kernel.org/show_bug.cgi?id=189651
Comment 10 Luca Coelho 2017-01-19 15:55:28 UTC
Paul, could you retest this with our Core release as described in our wiki[1]? Please take Core24 and the new firmware we released in our copy of linux-firmware.git[2]? This is our latest official release and it is on its way to the mainline.

[1] https://wireless.wiki.kernel.org/en/users/drivers/iwlwifi/core_release
[2] http://git.kernel.org/cgit/linux/kernel/git/iwlwifi/linux-firmware.git/tree/iwlwifi-8000C-27.ucode
Comment 11 Paul Donohue 2017-01-21 17:11:34 UTC
I'm having trouble compiling Core24 against 4.10-rc3.

First, I got:
/home/user/backport-iwlwifi/backport-include/linux/kconfig.h:25:28: error: implicit declaration of function ‘config_enabled’ [-Werror=implicit-function-declaration]
 #define IS_BUILTIN(option) config_enabled(option)

This is apparently due to https://git.kernel.org/cgit/linux/kernel/git/stable/linux-stable.git/commit/include/linux/kconfig.h?id=c0a0aba8e478229b2f0956918542152fbad3f794

To fix, I edited backport-include/linux/kconfig.h and replaced:
#define IS_BUILTIN(option) config_enabled(option)
with:
#define IS_BUILTIN(option) __is_defined(option)

Now I get several more errors:
/home/user/backport-iwlwifi/net/mac80211/status.c:705:38: error: request for member ‘tv64’ in something not a structure or union
  ts[IEEE80211_TX_LAT_ENTER] = skb_arv.tv64 >> 32;
                                      ^
/home/psd/backport-iwlwifi/net/mac80211/status.c:706:15: error: request for member ‘tv64’ in something not a structure or union
  tmp = skb_arv.tv64 & 0xFFFFFFFF;
               ^
/home/psd/backport-iwlwifi/net/wireless/nl80211.c:44:8: error: ‘GENL_ID_GENERATE’ undeclared here (not in a function)
  .id = GENL_ID_GENERATE,  /* don't bother with a hardcoded ID */
        ^~~~~~~~~~~~~~~~
/home/psd/backport-iwlwifi/net/wireless/nl80211.c:15340:8: error: implicit declaration of function ‘genl_register_family_with_ops_group’ [-Werror=implicit-function-declaration]
  err = genl_register_family_with_ops_groups(&nl80211_fam, nl80211_ops,
        ^~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~
/home/psd/backport-iwlwifi/drivers/net/wireless/intel/iwlwifi/pcie/tx.c:1995:24: error: request for member ‘tv64’ in something not a structure or union
  s64 ts_1 = skb->tstamp.tv64 >> 32;
                        ^
/home/psd/backport-iwlwifi/drivers/net/wireless/intel/iwlwifi/pcie/tx.c:1998:13: error: request for member ‘tv64’ in something not a structure or union
  skb->tstamp.tv64 += diff << 16;
             ^
/home/psd/backport-iwlwifi/drivers/net/wireless/intel/iwlwifi/trans_slave/shared.c:800:32: error: request for member ‘tv64’ in something not a structure or union
  ts_1 = data_entry->skb->tstamp.tv64 >> 32;
                                ^
/home/psd/backport-iwlwifi/drivers/net/wireless/intel/iwlwifi/trans_slave/shared.c:802:25: error: request for member ‘tv64’ in something not a structure or union
  data_entry->skb->tstamp.tv64 += diff << 16;
                         ^

I don't have enough time at the moment to work through all of these ... but will look at it again when I have more time.
Comment 12 Matthew Turnbull 2017-01-21 21:23:33 UTC
Created attachment 252731 [details]
Fix backport-iwlwifi Core24 on 4.9

Paul, I ran into the same build problems. Thanks to the change set you linked, I came up with this patch. Not sure if the version check is correct, but it works* for me building against kernel 4.9.1.

* and by works, it builds successfully, but not necessarily correctly. However the modules do load successfully on reboot.
Comment 13 Matthew Turnbull 2017-01-21 21:34:13 UTC
Created attachment 252741 [details]
backport-iwlwifi Core24 on 4.9.1 dmesg

Luca, unfortunately I'm still running into failures with 27.455470.0 and release/LinuxCore24:5768:2a86abaf on kernel 4.9.1.

If there is more information you need, or different configs I should try, please let me know.
Comment 14 Emmanuel Grumbach 2017-01-22 21:54:16 UTC
@Matthew

Are you using the WLAN device that came with the LENOVO system you have, or you replaced it?

Also just as a wild guess, can you try this:

diff --git a/drivers/net/wireless/intel/iwlwifi/iwl-drv.c b/drivers/net/wireless/intel/iwlwifi/iwl-drv.c
index 8a1825e..617b773 100644
--- a/drivers/net/wireless/intel/iwlwifi/iwl-drv.c
+++ b/drivers/net/wireless/intel/iwlwifi/iwl-drv.c
@@ -1273,6 +1273,7 @@ fw_dbg_conf:
                        if (tlv_len != sizeof(u32))
                                goto invalid_tlv_len;
                        paging_mem_size = le32_to_cpup((__le32 *)tlv_data);
+                       break;
 
                        IWL_DEBUG_FW(drv,
                                     "Paging: paging enabled (size = %u bytes)\n",


---

(this is a horrid W/A, just trying to see what's going wrong here)

thanks.
Comment 15 Matthew Turnbull 2017-01-23 10:03:20 UTC
Created attachment 252881 [details]
backport-iwlwifi Core24 on 4.9.1 dmesg with IWL_UCODE_TLV_PAGING break

@Emmanuel Yes, it is the original "Intel 8260AC+BT 2x2 vPro" configured at the factory. However I do not know if it has been modified in any way (i.e. firmware updates) due to Windows driver or BIOS/EFI updates.

Adding the break changed a handful of the hex codes, but since it's called out in the title:

0x00000309 | ADVANCED_SYSASSERT

changed to

0x00100232 | ADVANCED_SYSASSERT

and

FW error in SYNC CMD FW_PAGING_BLOCK_CMD

changed to

FW error in SYNC CMD SCAN_CFG_CMD

(also just noticed that the old umac data hex codes were 0xDEADBEEF - very cute!)
Comment 16 Emmanuel Grumbach 2017-01-23 10:26:43 UTC
Ok - so that's a total fiasco.
Thanks for trying.

It is a vPRO machine.. Hm... Is it provisioned with Intel Active Management Technology?
Comment 17 Paul Donohue 2017-01-24 14:31:47 UTC
The .tv64 errors are due to https://git.kernel.org/cgit/linux/kernel/git/torvalds/linux.git/commit/?id=2456e855354415bfaeb7badaa14e11b3e02c8466 ... changing tstamp.tv64 to just tstamp fixes those.

The genl_* errors are due to https://git.kernel.org/cgit/linux/kernel/git/torvalds/linux.git/commit/?id=a07ea4d9941af5a0c6f0be2a71b51ac9c083c5e5 and https://git.kernel.org/cgit/linux/kernel/git/torvalds/linux.git/commit/?id=489111e5c25b93be80340c3113d71903d7c8213 ... this one isn't as straightforward to fix as the other compilation issues.

My machine is a Dell 7510 without AMT.  I'm using the original Intel 8260 device that came with the machine.
Comment 18 Emmanuel Grumbach 2017-01-24 20:36:07 UTC
can you paste the output of:

sudo hexdump /sys/kernel/debug/ieee80211/phy0/iwlwifi/iwlmvm/nvm_sw


Thanks.
Comment 19 Paul Donohue 2017-01-24 20:47:15 UTC
0000000 0e56 0000 0000 0014 7ffe 0000 0001 0100
0000010 0000 3544 4446 4431 3033 5736 485a 3438
0000020 3030 3033 3530 0000 3ae9 48f4 0328 0000
0000030 0909 000c 0006 00c9 0a12 0500 0000 1922
0000040
Comment 20 Luca Coelho 2017-01-25 07:06:48 UTC
Paul, sorry, I haven't got to make our backports work with v4.9+ yet.  As you know, the mainline is a moving target and we need to keep adjusting the backports project to keep things compiling and working properly.

Thanks for the NVM dump.  We will have someone check whether these values are all fine.
Comment 21 Matthew Turnbull 2017-01-25 15:22:01 UTC
I believe my laptop does have AMT, or at least there are BIOS options that I leave disabled.

Also, are you interested in the nvm_sw hex dump on a working configuration, a broken configuration, or both?
Comment 22 Emmanuel Grumbach 2017-01-25 15:34:19 UTC
both is always better :)
Comment 23 Matthew Turnbull 2017-01-25 16:31:05 UTC
My nvm_sw dumps were the same in both a good (4.9.5 and firmware 21.302800.0) and bad state (4.9.5 with Core24 and firmware 27.455470.0).

0000000 0e56 0000 0000 0014 7ffe 0000 0001 0100
0000010 0000 3642 3132 3832 3431 5736 485a 3039
0000020 3231 3033 3430 0000 74bb 4882 04b9 0000
0000030 0909 0306 0006 00c9 0a12 0500 0000 1922
0000040
Comment 24 Mike Cloaked 2017-02-20 21:44:02 UTC
I am running arch linux with kernel 4.9.9-1-ARCH, after installing on an Intel NUC 6i5SYK with AC-8260 a month or two back and had what seems to be the analogous problem from the time I installed the system.

01:00.0 Network controller: Intel Corporation Wireless 8260 (rev 3a)
        Subsystem: Intel Corporation Device 9010
        Flags: bus master, fast devsel, latency 0, IRQ 278
        Memory at df000000 (64-bit, non-prefetchable) [size=8K]
        Capabilities: <access denied>
        Kernel driver in use: iwlwifi
        Kernel modules: iwlwifi

I will attach the journal log during a disconnect/reconnect for wireless which is controlled using NetworkManager and has regular disconnects particularly when transferring large files but also when using rsync for smaller files on my LAN.

Lines in the log which are relevant include:

Feb 20 21:12:25 home2 kernel: iwlwifi 0000:01:00.0: Queue 2 stuck for 10000 ms.
Feb 20 21:12:25 home2 kernel: iwlwifi 0000:01:00.0: Current SW read_ptr 40 write_ptr 104
Feb 20 21:12:25 home2 kernel: iwl data: 00000000: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00  ................
Feb 20 21:12:25 home2 kernel: iwlwifi 0000:01:00.0: FH TRBs(0) = 0x8915fa87
Feb 20 21:12:25 home2 kernel: iwlwifi 0000:01:00.0: FH TRBs(1) = 0xc0110007
Feb 20 21:12:25 home2 kernel: iwlwifi 0000:01:00.0: FH TRBs(2) = 0xeda9b79d
Feb 20 21:12:25 home2 kernel: iwlwifi 0000:01:00.0: FH TRBs(3) = 0x8030000b
Feb 20 21:12:25 home2 kernel: iwlwifi 0000:01:00.0: FH TRBs(4) = 0x27cdb943
Feb 20 21:12:25 home2 kernel: iwlwifi 0000:01:00.0: FH TRBs(5) = 0x417d9080
Feb 20 21:12:25 home2 kernel: iwlwifi 0000:01:00.0: FH TRBs(6) = 0x44b7d97c
Feb 20 21:12:25 home2 kernel: iwlwifi 0000:01:00.0: FH TRBs(7) = 0x007090d7
Feb 20 21:12:25 home2 kernel: iwlwifi 0000:01:00.0: Q 0 is active and mapped to fifo 3 ra_tid 0x0000 [12,12]
Feb 20 21:12:25 home2 kernel: iwlwifi 0000:01:00.0: Q 1 is active and mapped to fifo 2 ra_tid 0x0000 [0,0]
Feb 20 21:12:25 home2 kernel: iwlwifi 0000:01:00.0: Q 2 is active and mapped to fifo 1 ra_tid 0x0000 [40,104]
Feb 20 21:12:25 home2 kernel: iwlwifi 0000:01:00.0: Q 3 is active and mapped to fifo 0 ra_tid 0x0000 [0,0]
Feb 20 21:12:25 home2 kernel: iwlwifi 0000:01:00.0: Q 4 is inactive and mapped to fifo 0 ra_tid 0x0000 [0,0]
Feb 20 21:12:25 home2 kernel: iwlwifi 0000:01:00.0: Q 5 is inactive and mapped to fifo 0 ra_tid 0x0000 [0,0]
Feb 20 21:12:25 home2 kernel: iwlwifi 0000:01:00.0: Q 6 is inactive and mapped to fifo 0 ra_tid 0x0000 [0,0]
Feb 20 21:12:25 home2 kernel: iwlwifi 0000:01:00.0: Q 7 is inactive and mapped to fifo 0 ra_tid 0x0000 [0,0]
Feb 20 21:12:25 home2 kernel: iwlwifi 0000:01:00.0: Q 8 is active and mapped to fifo 3 ra_tid 0x0000 [0,0]
Feb 20 21:12:25 home2 kernel: iwlwifi 0000:01:00.0: Q 9 is active and mapped to fifo 7 ra_tid 0x0000 [216,216]
Feb 20 21:12:25 home2 kernel: iwlwifi 0000:01:00.0: Q 10 is inactive and mapped to fifo 0 ra_tid 0x0000 [0,0]
Feb 20 21:12:25 home2 kernel: iwlwifi 0000:01:00.0: Q 11 is inactive and mapped to fifo 0 ra_tid 0x0000 [0,0]
Feb 20 21:12:25 home2 kernel: iwlwifi 0000:01:00.0: Q 12 is inactive and mapped to fifo 0 ra_tid 0x0000 [0,0]
Feb 20 21:12:25 home2 kernel: iwlwifi 0000:01:00.0: Q 13 is inactive and mapped to fifo 0 ra_tid 0x0000 [0,0]
Feb 20 21:12:25 home2 kernel: iwlwifi 0000:01:00.0: Q 14 is inactive and mapped to fifo 0 ra_tid 0x0000 [0,0]
Feb 20 21:12:25 home2 kernel: iwlwifi 0000:01:00.0: Q 15 is active and mapped to fifo 5 ra_tid 0x0000 [0,0]
Feb 20 21:12:25 home2 kernel: iwlwifi 0000:01:00.0: Q 16 is active and mapped to fifo 1 ra_tid 0x00ff [248,153]
Feb 20 21:12:25 home2 kernel: iwlwifi 0000:01:00.0: Q 17 is inactive and mapped to fifo 0 ra_tid 0x0000 [0,0]
Feb 20 21:12:25 home2 kernel: iwlwifi 0000:01:00.0: Q 18 is inactive and mapped to fifo 0 ra_tid 0x0000 [0,0]
Feb 20 21:12:25 home2 kernel: iwlwifi 0000:01:00.0: Q 19 is inactive and mapped to fifo 0 ra_tid 0x0000 [0,0]
Feb 20 21:12:25 home2 kernel: iwlwifi 0000:01:00.0: Q 20 is inactive and mapped to fifo 0 ra_tid 0x0000 [0,0]
Feb 20 21:12:25 home2 kernel: iwlwifi 0000:01:00.0: Q 21 is inactive and mapped to fifo 0 ra_tid 0xff00 [0,0]
Feb 20 21:12:25 home2 kernel: iwlwifi 0000:01:00.0: Q 22 is inactive and mapped to fifo 0 ra_tid 0x0000 [0,0]
Feb 20 21:12:25 home2 kernel: iwlwifi 0000:01:00.0: Q 23 is inactive and mapped to fifo 0 ra_tid 0x0000 [0,0]
Feb 20 21:12:25 home2 kernel: iwlwifi 0000:01:00.0: Q 24 is inactive and mapped to fifo 0 ra_tid 0x0000 [0,0]
Feb 20 21:12:25 home2 kernel: iwlwifi 0000:01:00.0: Q 25 is inactive and mapped to fifo 0 ra_tid 0x0000 [0,0]
Feb 20 21:12:25 home2 kernel: iwlwifi 0000:01:00.0: Q 26 is inactive and mapped to fifo 0 ra_tid 0x0000 [0,0]
Feb 20 21:12:25 home2 kernel: iwlwifi 0000:01:00.0: Q 27 is inactive and mapped to fifo 0 ra_tid 0x0000 [0,0]
Feb 20 21:12:25 home2 kernel: iwlwifi 0000:01:00.0: Q 28 is inactive and mapped to fifo 0 ra_tid 0x0000 [0,0]
Feb 20 21:12:25 home2 kernel: iwlwifi 0000:01:00.0: Q 29 is inactive and mapped to fifo 0 ra_tid 0x0000 [0,0]
Feb 20 21:12:25 home2 kernel: iwlwifi 0000:01:00.0: Q 30 is inactive and mapped to fifo 0 ra_tid 0x0000 [0,0]
Feb 20 21:12:25 home2 kernel: iwlwifi 0000:01:00.0: Microcode SW error detected.  Restarting 0x2000000.
Feb 20 21:12:25 home2 kernel: iwlwifi 0000:01:00.0: CSR values:
Feb 20 21:12:25 home2 kernel: iwlwifi 0000:01:00.0: (2nd byte of CSR_INT_COALESCING is CSR_INT_PERIODIC_REG)
Feb 20 21:12:25 home2 kernel: iwlwifi 0000:01:00.0:        CSR_HW_IF_CONFIG_REG: 0X00c89008
Feb 20 21:12:25 home2 kernel: iwlwifi 0000:01:00.0:          CSR_INT_COALESCING: 0X00000040
Feb 20 21:12:25 home2 kernel: iwlwifi 0000:01:00.0:                     CSR_INT: 0X00000000
Feb 20 21:12:25 home2 kernel: iwlwifi 0000:01:00.0:                CSR_INT_MASK: 0X00000000
Feb 20 21:12:25 home2 kernel: iwlwifi 0000:01:00.0:           CSR_FH_INT_STATUS: 0X00000000
Feb 20 21:12:25 home2 kernel: iwlwifi 0000:01:00.0:                 CSR_GPIO_IN: 0X00000019
Feb 20 21:12:25 home2 kernel: iwlwifi 0000:01:00.0:                   CSR_RESET: 0X00000000
Feb 20 21:12:25 home2 kernel: iwlwifi 0000:01:00.0:                CSR_GP_CNTRL: 0X08040005
Feb 20 21:12:25 home2 kernel: iwlwifi 0000:01:00.0:                  CSR_HW_REV: 0X00000201
Feb 20 21:12:25 home2 kernel: iwlwifi 0000:01:00.0:              CSR_EEPROM_REG: 0Xd55555d5
Feb 20 21:12:25 home2 kernel: iwlwifi 0000:01:00.0:               CSR_EEPROM_GP: 0Xd55555d5
Feb 20 21:12:25 home2 kernel: iwlwifi 0000:01:00.0:              CSR_OTP_GP_REG: 0Xd55555d5
Feb 20 21:12:25 home2 kernel: iwlwifi 0000:01:00.0:                 CSR_GIO_REG: 0X001f0042
Feb 20 21:12:25 home2 kernel: iwlwifi 0000:01:00.0:            CSR_GP_UCODE_REG: 0X00000000
Feb 20 21:12:25 home2 kernel: iwlwifi 0000:01:00.0:           CSR_GP_DRIVER_REG: 0X00000000
Feb 20 21:12:25 home2 kernel: iwlwifi 0000:01:00.0:           CSR_UCODE_DRV_GP1: 0X00000000
Feb 20 21:12:25 home2 kernel: iwlwifi 0000:01:00.0:           CSR_UCODE_DRV_GP2: 0X00000000
Feb 20 21:12:25 home2 kernel: iwlwifi 0000:01:00.0:                 CSR_LED_REG: 0X00000060
Feb 20 21:12:25 home2 kernel: iwlwifi 0000:01:00.0:        CSR_DRAM_INT_TBL_REG: 0X88455b8c
Feb 20 21:12:25 home2 kernel: iwlwifi 0000:01:00.0:        CSR_GIO_CHICKEN_BITS: 0X07800200
Feb 20 21:12:25 home2 kernel: iwlwifi 0000:01:00.0:             CSR_ANA_PLL_CFG: 0Xd55555d5
Feb 20 21:12:25 home2 kernel: iwlwifi 0000:01:00.0:      CSR_MONITOR_STATUS_REG: 0Xc03803c0
Feb 20 21:12:25 home2 kernel: iwlwifi 0000:01:00.0:           CSR_HW_REV_WA_REG: 0X0001001a
Feb 20 21:12:25 home2 kernel: iwlwifi 0000:01:00.0:        CSR_DBG_HPET_MEM_REG: 0Xffff0010
Feb 20 21:12:25 home2 kernel: iwlwifi 0000:01:00.0: FH register values:
Feb 20 21:12:25 home2 kernel: iwlwifi 0000:01:00.0:         FH_RSCSR_CHNL0_STTS_WPTR_REG: 0X45ac0e00
Feb 20 21:12:25 home2 kernel: iwlwifi 0000:01:00.0:        FH_RSCSR_CHNL0_RBDCB_BASE_REG: 0X045ac0d0
Feb 20 21:12:25 home2 kernel: iwlwifi 0000:01:00.0:                  FH_RSCSR_CHNL0_WPTR: 0X000000e8
Feb 20 21:12:25 home2 kernel: iwlwifi 0000:01:00.0:         FH_MEM_RCSR_CHNL0_CONFIG_REG: 0X00801054
Feb 20 21:12:25 home2 kernel: iwlwifi 0000:01:00.0:          FH_MEM_RSSR_SHARED_CTRL_REG: 0X000000fc
Feb 20 21:12:25 home2 kernel: iwlwifi 0000:01:00.0:            FH_MEM_RSSR_RX_STATUS_REG: 0X03030000
Feb 20 21:12:25 home2 kernel: iwlwifi 0000:01:00.0:    FH_MEM_RSSR_RX_ENABLE_ERR_IRQ2DRV: 0X00000000
Feb 20 21:12:25 home2 kernel: iwlwifi 0000:01:00.0:                FH_TSSR_TX_STATUS_REG: 0X07ff0001
Feb 20 21:12:25 home2 kernel: iwlwifi 0000:01:00.0:                 FH_TSSR_TX_ERROR_REG: 0X00000000
Feb 20 21:12:25 home2 kernel: iwlwifi 0000:01:00.0: Start IWL Error Log Dump:
Feb 20 21:12:25 home2 kernel: iwlwifi 0000:01:00.0: Status: 0x00000000, count: 6
Feb 20 21:12:25 home2 kernel: iwlwifi 0000:01:00.0: Loaded firmware version: 22.361476.0
Feb 20 21:12:25 home2 kernel: iwlwifi 0000:01:00.0: 0x00000084 | NMI_INTERRUPT_UNKNOWN       
Feb 20 21:12:25 home2 kernel: iwlwifi 0000:01:00.0: 0x000002F0 | trm_hw_status0
Feb 20 21:12:25 home2 kernel: iwlwifi 0000:01:00.0: 0x00000000 | trm_hw_status1
Feb 20 21:12:25 home2 kernel: iwlwifi 0000:01:00.0: 0x0000E9C8 | branchlink2
Feb 20 21:12:25 home2 kernel: iwlwifi 0000:01:00.0: 0x0002843C | interruptlink1
Feb 20 21:12:25 home2 kernel: iwlwifi 0000:01:00.0: 0x00000180 | interruptlink2
Feb 20 21:12:25 home2 kernel: iwlwifi 0000:01:00.0: 0x00000000 | data1
Feb 20 21:12:25 home2 kernel: iwlwifi 0000:01:00.0: 0x00000080 | data2
Feb 20 21:12:25 home2 kernel: iwlwifi 0000:01:00.0: 0x07830000 | data3
Feb 20 21:12:25 home2 kernel: iwlwifi 0000:01:00.0: 0x1E001F39 | beacon time
Feb 20 21:12:25 home2 kernel: iwlwifi 0000:01:00.0: 0x6CD140C9 | tsf low
Feb 20 21:12:25 home2 kernel: iwlwifi 0000:01:00.0: 0x0000006B | tsf hi
Feb 20 21:12:25 home2 kernel: iwlwifi 0000:01:00.0: 0x00000000 | time gp1
Feb 20 21:12:25 home2 kernel: iwlwifi 0000:01:00.0: 0x073DA50A | time gp2
Feb 20 21:12:25 home2 kernel: iwlwifi 0000:01:00.0: 0x00000000 | uCode revision type
Feb 20 21:12:25 home2 kernel: iwlwifi 0000:01:00.0: 0x00000016 | uCode version major
Feb 20 21:12:25 home2 kernel: iwlwifi 0000:01:00.0: 0x00058404 | uCode version minor
Feb 20 21:12:25 home2 kernel: iwlwifi 0000:01:00.0: 0x00000201 | hw version
Feb 20 21:12:25 home2 kernel: iwlwifi 0000:01:00.0: 0x00C89008 | board version
Feb 20 21:12:25 home2 kernel: iwlwifi 0000:01:00.0: 0x10F8001C | hcmd
Feb 20 21:12:25 home2 kernel: iwlwifi 0000:01:00.0: 0x00022002 | isr0
Feb 20 21:12:25 home2 kernel: iwlwifi 0000:01:00.0: 0x00800000 | isr1
Feb 20 21:12:25 home2 kernel: iwlwifi 0000:01:00.0: 0x0800180A | isr2
Feb 20 21:12:25 home2 kernel: iwlwifi 0000:01:00.0: 0x004174C0 | isr3
Feb 20 21:12:25 home2 kernel: iwlwifi 0000:01:00.0: 0x00000000 | isr4
Feb 20 21:12:25 home2 kernel: iwlwifi 0000:01:00.0: 0x00000110 | last cmd Id
Feb 20 21:12:25 home2 kernel: iwlwifi 0000:01:00.0: 0x00000000 | wait_event
Feb 20 21:12:25 home2 kernel: iwlwifi 0000:01:00.0: 0x00000934 | l2p_control
Feb 20 21:12:25 home2 kernel: iwlwifi 0000:01:00.0: 0x00000020 | l2p_duration
Feb 20 21:12:25 home2 kernel: iwlwifi 0000:01:00.0: 0x00000003 | l2p_mhvalid
Feb 20 21:12:25 home2 kernel: iwlwifi 0000:01:00.0: 0x00000010 | l2p_addr_match
Feb 20 21:12:25 home2 kernel: iwlwifi 0000:01:00.0: 0x0000000D | lmpm_pmg_sel
Feb 20 21:12:25 home2 kernel: iwlwifi 0000:01:00.0: 0x03071928 | timestamp
Feb 20 21:12:25 home2 kernel: iwlwifi 0000:01:00.0: 0x0034E8F8 | flow_handler
Feb 20 21:12:25 home2 kernel: iwlwifi 0000:01:00.0: Start IWL Error Log Dump:
Feb 20 21:12:25 home2 kernel: iwlwifi 0000:01:00.0: Status: 0x00000000, count: 7
Feb 20 21:12:25 home2 kernel: iwlwifi 0000:01:00.0: 0x00000070 | ADVANCED_SYSASSERT
Feb 20 21:12:25 home2 kernel: iwlwifi 0000:01:00.0: 0x00000000 | umac branchlink1
Feb 20 21:12:25 home2 kernel: iwlwifi 0000:01:00.0: 0xC008383C | umac branchlink2
Feb 20 21:12:25 home2 kernel: iwlwifi 0000:01:00.0: 0xC008166C | umac interruptlink1
Feb 20 21:12:25 home2 kernel: iwlwifi 0000:01:00.0: 0xC008166C | umac interruptlink2
Feb 20 21:12:25 home2 kernel: iwlwifi 0000:01:00.0: 0x00000800 | umac data1
Feb 20 21:12:25 home2 kernel: iwlwifi 0000:01:00.0: 0xC008166C | umac data2
Feb 20 21:12:25 home2 kernel: iwlwifi 0000:01:00.0: 0xDEADBEEF | umac data3
Feb 20 21:12:25 home2 kernel: iwlwifi 0000:01:00.0: 0x00000016 | umac major
Feb 20 21:12:25 home2 kernel: iwlwifi 0000:01:00.0: 0x00058404 | umac minor
Feb 20 21:12:25 home2 kernel: iwlwifi 0000:01:00.0: 0xC0886280 | frame pointer
Feb 20 21:12:25 home2 kernel: iwlwifi 0000:01:00.0: 0xC0886280 | stack pointer
Feb 20 21:12:25 home2 kernel: iwlwifi 0000:01:00.0: 0x09D7014E | last host cmd
Feb 20 21:12:25 home2 kernel: iwlwifi 0000:01:00.0: 0x00000000 | isr status reg
Feb 20 21:12:25 home2 kernel: ieee80211 phy0: Hardware restart was requested
Feb 20 21:12:25 home2 kernel: iwlwifi 0000:01:00.0: L1 Enabled - LTR Enabled
Feb 20 21:12:25 home2 kernel: iwlwifi 0000:01:00.0: L1 Enabled - LTR Enabled
Feb 20 21:12:25 home2 kernel: iwlwifi 0000:01:00.0: L1 Enabled - LTR Enabled
Feb 20 21:12:25 home2 kernel: iwlwifi 0000:01:00.0: L1 Enabled - LTR Enabled
Comment 25 Mike Cloaked 2017-02-20 21:45:10 UTC
Created attachment 254841 [details]
Journal log including wireless disconnect events for AC-8260 in Intel NUC
Comment 26 Mike Cloaked 2017-02-20 22:08:23 UTC
Switching off power_save did not stop the wireless disconnecting at fairly frequent intervals. (the default seems to be on).

# iw dev wlp1s0 get power_save
Power save: on
Comment 27 Emmanuel Grumbach 2017-02-21 03:36:29 UTC
@Mike Cloaked this is a different issue.
Comment 28 Mike Cloaked 2017-02-21 08:12:19 UTC
OK I will open a new report
Comment 29 Emmanuel Grumbach 2017-02-27 20:19:48 UTC
We fixed our backport tree.

Can someone give it a try?
Please use the master branch and not the Core24 branch.

Thank you.
Comment 30 Emmanuel Grumbach 2017-03-01 17:45:30 UTC
Anyone? :)
Comment 31 Matthew Turnbull 2017-03-04 18:56:59 UTC
Sorry for tacking so long. It has been a crazy week.

Tested iwlwifi-stack-public:master:5997:1458d072 with firmware version 27.455470.0

Linux 4.9.11 + backport is still broken
Linux 4.10.1 + backport is working

If interested, I can dump all of the previously requested debugging information for 4.9.
Comment 32 Emmanuel Grumbach 2017-03-04 21:13:44 UTC
if you use backport, the base kernel shouldn't make any difference.

Please paste the output of:

ethtool -i <interface name> in both cases?
Comment 33 Matthew Turnbull 2017-03-04 23:19:29 UTC
Created attachment 255079 [details]
Debugging info for 4.9.13 and 4.10.1

4.9.13 + backport
* dmesg
* ethtool
* nvm_sw

4.10.1 + backport
* dmesg
* ethtool
* nvm_sw missing due to CONFIG_DEBUG_FS build error
Comment 34 Emmanuel Grumbach 2017-03-05 11:24:46 UTC
This is ... surprising.

You are using the same driver / firmware combo on both kernels, and you get different results?! Timings maybe?

I am afraid I won't be able to do much here...
Is there anyone else on this bug who could run tests?

thank you.
Comment 35 Paul Donohue 2017-03-05 18:32:26 UTC
Building the master branch against 4.10.1, I still get tv64 errors:
/home/psd/backport-iwlwifi/net/mac80211/status.c:712:38: error: request for member ‘tv64’ in something not a structure or union
  ts[IEEE80211_TX_LAT_ENTER] = skb_arv.tv64 >> 32;
/home/psd/backport-iwlwifi/drivers/net/wireless/intel/iwlwifi/pcie/tx.c:1934:24: error: request for member ‘tv64’ in something not a structure or union
  s64 ts_1 = skb->tstamp.tv64 >> 32;
I changed tstamp.tv64 to just tstamp to get past those, and after that it compiled fine.
Comment 36 Emmanuel Grumbach 2017-03-05 18:35:26 UTC
Ok - thanks.

So let me know how it goes with the firmware assert.

Thanks!
Comment 37 Paul Donohue 2017-03-05 18:47:51 UTC
Created attachment 255087 [details]
Dmesg and other info using 4.10.1 with iwlwifi master

No firmware assert ... However, the driver doesn't actually work...
Comment 38 Emmanuel Grumbach 2017-03-05 19:40:28 UTC
Do you use wicd?
Comment 39 Paul Donohue 2017-03-05 22:42:01 UTC
No, I just use wpa_supplicant and /etc/network/interfaces in Ubuntu.  When using the master branch driver, wpa_supplicant fails to start:
Mar  5 13:33:32 Mooninite wpa_supplicant[1517]: Successfully initialized wpa_supplicant
Mar  5 13:33:32 Mooninite wpa_supplicant[1517]: ioctl[SIOCSIWMODE]: Operation not supported
Mar  5 13:33:32 Mooninite wpa_supplicant[1517]: ioctl[SIOCGIWRANGE]: Operation not supported
Mar  5 13:33:32 Mooninite wpa_supplicant[1517]: ioctl[SIOCGIWMODE]: Operation not supported
Mar  5 13:33:32 Mooninite kernel: IPv6: ADDRCONF(NETDEV_UP): wlan0: link is not ready
Mar  5 13:33:32 Mooninite wpa_supplicant[1517]: ioctl[SIOCSIWAP]: Operation not supported
Mar  5 13:33:32 Mooninite wpa_supplicant[1517]: ioctl[SIOCSIWESSID]: Operation not supported
Mar  5 13:33:32 Mooninite wpa_supplicant[1517]: ioctl[SIOCSIWENCODEEXT]: Operation not supported
Mar  5 13:33:32 Mooninite wpa_supplicant[1517]: ioctl[SIOCSIWENCODE]: Operation not supported
Mar  5 13:33:32 Mooninite wpa_supplicant[1517]: ioctl[SIOCSIWENCODEEXT]: Operation not supported
Mar  5 13:33:32 Mooninite wpa_supplicant[1517]: ioctl[SIOCSIWENCODE]: Operation not supported
Mar  5 13:33:32 Mooninite wpa_supplicant[1517]: ioctl[SIOCSIWENCODEEXT]: Operation not supported
Mar  5 13:33:32 Mooninite wpa_supplicant[1517]: ioctl[SIOCSIWENCODE]: Operation not supported
Mar  5 13:33:32 Mooninite wpa_supplicant[1517]: ioctl[SIOCSIWENCODEEXT]: Operation not supported
Mar  5 13:33:32 Mooninite wpa_supplicant[1517]: ioctl[SIOCSIWENCODE]: Operation not supported
Mar  5 13:33:32 Mooninite wpa_supplicant[1517]: ioctl[SIOCSIWENCODEEXT]: Operation not supported
Mar  5 13:33:32 Mooninite wpa_supplicant[1517]: ioctl[SIOCSIWENCODE]: Operation not supported
Mar  5 13:33:32 Mooninite wpa_supplicant[1517]: ioctl[SIOCSIWENCODEEXT]: Operation not supported
Mar  5 13:33:32 Mooninite wpa_supplicant[1517]: ioctl[SIOCSIWENCODE]: Operation not supported
Mar  5 13:33:32 Mooninite wpa_supplicant[1517]: dbus: wpa_dbus_get_object_properties: failed to get object properties: (none) none
Mar  5 13:33:32 Mooninite wpa_supplicant[1517]: dbus: Failed to construct signal
Mar  5 13:33:32 Mooninite wpa_supplicant[1532]: ioctl[SIOCSIWSCAN]: Operation not supported
Mar  5 13:33:32 Mooninite wpa_supplicant[1532]: wlan0: CTRL-EVENT-SCAN-FAILED ret=-1 retry=1

Disabling wpa_supplicant and all related configuration in /etc/network/interfaces, then manually attempting to bring up the interface results in the output shown in my last attachment.
Comment 40 Emmanuel Grumbach 2017-03-06 04:31:31 UTC
So please use the nl80211 driver of the supplicant and not wext.

Thanks.
Comment 41 Paul Donohue 2017-03-06 22:26:05 UTC
Sorry, I was totally unaware of nl80211...

nl80211 didn't work with the wpasupplicant in Ubuntu: https://bugs.launchpad.net/ubuntu/+source/wpa/+bug/1670494

However, after patching wpasupplicant, nl80211 works fine for me in kernel 4.9.13, 4.10.0, and 4.10.1.

Thanks!
Comment 42 Emmanuel Grumbach 2017-03-07 02:58:25 UTC
Ok, but what about the original bug?
Comment 43 Paul Donohue 2017-03-07 03:28:03 UTC
Original bug is gone ... Using master branch of backport-iwlwifi with firmware 8000C-27 and nl80211 works fine for me on kernel 4.9.13, 4.10.0, and 4.10.1 ... I get no firmware crash on any of those kernels.
Comment 44 Emmanuel Grumbach 2017-03-07 05:38:27 UTC
Glad to hear.

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