iwlwifi - L1 Enabled - LTR Enabled loop

iwlwifi - L1 Enabled - LTR Enabled loop

[Luis R. Rodriguez]

Using 4.4-rc4 and today's wireless-testing (master-2015-12-10), and
the latest firmware on linux-firmware, my wireless driver runs into a
non-functional loop rambling about "L1 Enabled - LTR Enabled loop".
Reverting back to an older kernel works. I could bisect *if this issue
is not known*, but it may take a while... If you are already
suspicious of a commit lemme know, and I can try to revert. Otherwise
if there's a firmware version that is supposed to work I'm happy to
try that.

Device: Intel dual band wireless AC 7260, REV=0x144
Firmware version: 16.242414.0 (linux-firmware upstream)

I also tried:

Firmware version: 17.246894.0 op_mode iwlmvm (latest on
iwlwifi/linux-firmware.git)

I run into the same issue with this version as well though.

The log:

[   18.914370] cfg80211: World regulatory domain updated:
[   18.915114] cfg80211:  DFS Master region: unset
[   18.915127] cfg80211:   (start_freq - end_freq @ bandwidth),
(max_antenna_gain, max_eirp), (dfs_cac_time)
[   18.916599] cfg80211:   (2402000 KHz - 2472000 KHz @ 40000 KHz),
(N/A, 2000 mBm), (N/A)
[   18.917351] cfg80211:   (2457000 KHz - 2482000 KHz @ 40000 KHz),
(N/A, 2000 mBm), (N/A)
[   18.918082] cfg80211:   (2474000 KHz - 2494000 KHz @ 20000 KHz),
(N/A, 2000 mBm), (N/A)
[   18.918805] cfg80211:   (5170000 KHz - 5250000 KHz @ 80000 KHz,
160000 KHz AUTO), (N/A, 2000 mBm), (N/A)
[   18.919530] cfg80211:   (5250000 KHz - 5330000 KHz @ 80000 KHz,
160000 KHz AUTO), (N/A, 2000 mBm), (0 s)
[   18.920247] cfg80211:   (5490000 KHz - 5730000 KHz @ 160000 KHz),
(N/A, 2000 mBm), (0 s)
[   18.920965] cfg80211:   (5735000 KHz - 5835000 KHz @ 80000 KHz),
(N/A, 2000 mBm), (N/A)
[   18.921692] cfg80211:   (57240000 KHz - 63720000 KHz @ 2160000
KHz), (N/A, 0 mBm), (N/A)
[   19.059414] Intel(R) Wireless WiFi driver for Linux
[   19.060123] Copyright(c) 2003- 2015 Intel Corporation
[   19.061091] iwlwifi 0000:03:00.0: Direct firmware load for
iwlwifi-7260-19.ucode failed with error -2
[   19.061792] iwlwifi 0000:03:00.0: Direct firmware load for
iwlwifi-7260-18.ucode failed with error -2
[   19.062414] iwlwifi 0000:03:00.0: Direct firmware load for
iwlwifi-7260-17.ucode failed with error -2
[   19.590098] uvcvideo: Found UVC 1.00 device Integrated Camera (04f2:b39a)
[   19.673094] iwlwifi 0000:03:00.0: loaded firmware version
16.242414.0 op_mode iwlmvm
[   22.439870] iwlwifi 0000:03:00.0: Detected Intel(R) Dual Band
Wireless AC 7260, REV=0x144
[   22.440809] iwlwifi 0000:03:00.0: L1 Enabled - LTR Enabled
[   22.442616] iwlwifi 0000:03:00.0: L1 Enabled - LTR Enabled
[   22.645773] ieee80211 phy0: Selected rate control algorithm 'iwl-mvm-rs'
[   22.737314] iwlwifi 0000:03:00.0 wlp3s0: renamed from wlan0
[   33.288237] iwlwifi 0000:03:00.0: L1 Enabled - LTR Enabled
[   33.288489] iwlwifi 0000:03:00.0: L1 Enabled - LTR Enabled
[   33.477404] iwlwifi 0000:03:00.0: L1 Enabled - LTR Enabled

[ etc ]

[  100.421430] iwlwifi 0000:03:00.0: L1 Enabled - LTR Enabled
[  100.421686] iwlwifi 0000:03:00.0: L1 Enabled - LTR Enabled
[  100.604345] iwlwifi 0000:03:00.0: L1 Enabled - LTR Enabled
[  100.604633] iwlwifi 0000:03:00.0: L1 Enabled - LTR Enabled

[ it goes on forever ]

  Luis

Re: iwlwifi - L1 Enabled - LTR Enabled loop

[Luis R. Rodriguez]

On Fri, Dec 11, 2015 at 10:59 AM, Luis R. Rodriguez
<mcgrof@do-not-panic.com> wrote:
> I could bisect *if this issue
> is not known*, but it may take a while...

So this is interesting..

The opensuse 4.2.4 kernel worked, but I tried my own vanilla 4.2.4
kernel and that didn't work. This lead me to believe this might be a
config issue. I tried the same config on v4.3, v4.2 and v4.2.4 and all
fail with the same issue!

I just tried a base config from opensuse, then localmodconfig, then
'make xenconfig' (which I need) and that worked. I can't debug further
but I think this config might help debug this further:

http://drvbp1.linux-foundation.org/~mcgrof/tmp/config-iwl-fail.txt

So -- my new config works, but I have no idea what fixed it, a config
option obviously. Not sure what though.

 Luis

Re: iwlwifi - L1 Enabled - LTR Enabled loop

[Johannes Berg]

On Fri, 2015-12-11 at 15:17 -0800, Luis R. Rodriguez wrote:
> 
> I just tried a base config from opensuse, then localmodconfig, then
> 'make xenconfig' (which I need) and that worked. I can't debug
> further but I think this config might help debug this further:
> 
> http://drvbp1.linux-foundation.org/~mcgrof/tmp/config-iwl-fail.txt
> 
> So -- my new config works, but I have no idea what fixed it, a config
> option obviously. Not sure what though.
> 

Can you post the good one too?

johannes

Re: iwlwifi - L1 Enabled - LTR Enabled loop

[Arend van Spriel]

On 12/12/2015 12:32 AM, Johannes Berg wrote:
> On Fri, 2015-12-11 at 15:17 -0800, Luis R. Rodriguez wrote:
>>
>> I just tried a base config from opensuse, then localmodconfig, then
>> 'make xenconfig' (which I need) and that worked. I can't debug
>> further but I think this config might help debug this further:
>>
>> http://drvbp1.linux-foundation.org/~mcgrof/tmp/config-iwl-fail.txt
>>
>> So -- my new config works, but I have no idea what fixed it, a config
>> option obviously. Not sure what though.
>>
>
> Can you post the good one too?

I had the same issue occasionally when coming out of suspend. Running 
4.1 vanilla kernel with 3160 device. The issue also occurred more often 
with particular ucode version. Running iwlwifi-3160-10.ucode now and 
have not seen the issue pop up since.

Gr. AvS

Re: iwlwifi - L1 Enabled - LTR Enabled loop

[Grumbach, Emmanuel]

On 12/14/2015 01:04 AM, Arend van Spriel wrote:
> On 12/12/2015 12:32 AM, Johannes Berg wrote:
>> On Fri, 2015-12-11 at 15:17 -0800, Luis R. Rodriguez wrote:
>>> I just tried a base config from opensuse, then localmodconfig, then
>>> 'make xenconfig' (which I need) and that worked. I can't debug
>>> further but I think this config might help debug this further:
>>>
>>> http://drvbp1.linux-foundation.org/~mcgrof/tmp/config-iwl-fail.txt
>>>
>>> So -- my new config works, but I have no idea what fixed it, a config
>>> option obviously. Not sure what though.
>>>
>> Can you post the good one too?
> I had the same issue occasionally when coming out of suspend. Running 
> 4.1 vanilla kernel with 3160 device. The issue also occurred more often 
> with particular ucode version. Running iwlwifi-3160-10.ucode now and 
> have not seen the issue pop up since.

This is really weird. I don't see how that could be firmware related.
BTW, -10.ucode is fairly old :) we have -16.ucode out there.
>
> Gr. AvS
>
>

Re: iwlwifi - L1 Enabled - LTR Enabled loop

[Johannes Berg]

On Mon, 2015-12-14 at 04:00 +0000, Grumbach, Emmanuel wrote:
> 
> This is really weird. I don't see how that could be firmware related.
> BTW, -10.ucode is fairly old :) we have -16.ucode out there.

I guess the question is how we get into this situation to start with?

About the only thing I can imagine is that we fail somehow during
initialization (without printing any other message) and higher layers
like wpa_supplicant/network-manager/... simply try to bring up the
device over and over again?

johannes

Re: iwlwifi - L1 Enabled - LTR Enabled loop

[Emmanuel Grumbach]

On Mon, Dec 14, 2015 at 10:48 AM, Johannes Berg
<johannes@sipsolutions.net> wrote:
> On Mon, 2015-12-14 at 04:00 +0000, Grumbach, Emmanuel wrote:
>>
>> This is really weird. I don't see how that could be firmware related.
>> BTW, -10.ucode is fairly old :) we have -16.ucode out there.
>
> I guess the question is how we get into this situation to start with?
>
> About the only thing I can imagine is that we fail somehow during
> initialization (without printing any other message) and higher layers
> like wpa_supplicant/network-manager/... simply try to bring up the
> device over and over again?
>

I can reproduce this now when I unload / load iwlwifi until I kill the
supplicant.
Seems like the wpa_s is indeed toggling the device.
I don't have time to play with that right now.