Dell Sputnik

Bug #1091372
Comment #31

Comment 31 for bug 1091372

Revision history for this message

Daniel Farina (drfarina) wrote on 2013-04-16: Re: wifi slows and/or drops frequently

#31

Here is an annotated dump from kern.log of what's going on when I suffer and then fix this issue. Given the traffic of the log, it seems highly likely that the log lines annotate as being associated with wireless trouble is tightly connected.

# Last line from previous association
Apr 15 17:04:22 sputnik kernel: [ 16.183171] wlan0: Limiting TX power to 27 (27 - 0) dBm as advertised by 00:18:0a:30:10:1c

# Everything goes sideways after this spurt
Apr 15 17:35:22 sputnik kernel: [ 1872.623038] cfg80211: Calling CRDA to update world regulatory domain
Apr 15 17:35:22 sputnik kernel: [ 1872.632242] cfg80211: World regulatory domain updated:
Apr 15 17:35:22 sputnik kernel: [ 1872.632251] cfg80211: (start_freq - end_freq @ bandwidth), (max_antenna_gain, max_eirp)
Apr 15 17:35:22 sputnik kernel: [ 1872.632256] cfg80211: (2402000 KHz - 2472000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
Apr 15 17:35:22 sputnik kernel: [ 1872.632261] cfg80211: (2457000 KHz - 2482000 KHz @ 20000 KHz), (300 mBi, 2000 mBm)
Apr 15 17:35:22 sputnik kernel: [ 1872.632264] cfg80211: (2474000 KHz - 2494000 KHz @ 20000 KHz), (300 mBi, 2000 mBm)
Apr 15 17:35:22 sputnik kernel: [ 1872.632268] cfg80211: (5170000 KHz - 5250000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
Apr 15 17:35:22 sputnik kernel: [ 1872.632272] cfg80211: (5735000 KHz - 5835000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
Apr 15 17:35:22 sputnik kernel: [ 1873.085904] wlan0: authenticate with 02:18:1a:30:10:1c
Apr 15 17:35:22 sputnik kernel: [ 1873.090772] wlan0: send auth to 02:18:1a:30:10:1c (try 1/3)
Apr 15 17:35:22 sputnik kernel: [ 1873.292670] wlan0: send auth to 02:18:1a:30:10:1c (try 2/3)
Apr 15 17:35:23 sputnik kernel: [ 1873.300550] wlan0: authenticated
Apr 15 17:35:23 sputnik kernel: [ 1873.304635] wlan0: associate with 02:18:1a:30:10:1c (try 1/3)
Apr 15 17:35:23 sputnik kernel: [ 1873.332528] wlan0: RX AssocResp from 02:18:1a:30:10:1c (capab=0x411 status=0 aid=3)
Apr 15 17:35:23 sputnik kernel: [ 1873.335828] wlan0: associated
Apr 15 17:35:23 sputnik kernel: [ 1873.336220] cfg80211: Calling CRDA for country: US
Apr 15 17:35:23 sputnik kernel: [ 1873.347205] cfg80211: Regulatory domain changed to country: US
Apr 15 17:35:23 sputnik kernel: [ 1873.347215] cfg80211: (start_freq - end_freq @ bandwidth), (max_antenna_gain, max_eirp)
Apr 15 17:35:23 sputnik kernel: [ 1873.347223] cfg80211: (2402000 KHz - 2472000 KHz @ 40000 KHz), (300 mBi, 2700 mBm)
Apr 15 17:35:23 sputnik kernel: [ 1873.347231] cfg80211: (5170000 KHz - 5250000 KHz @ 40000 KHz), (300 mBi, 1700 mBm)
Apr 15 17:35:23 sputnik kernel: [ 1873.347237] cfg80211: (5250000 KHz - 5330000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
Apr 15 17:35:23 sputnik kernel: [ 1873.347244] cfg80211: (5490000 KHz - 5600000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
Apr 15 17:35:23 sputnik kernel: [ 1873.347251] cfg80211: (5650000 KHz - 5710000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
Apr 15 17:35:23 sputnik kernel: [ 1873.347257] cfg80211: (5735000 KHz - 5835000 KHz @ 40000 KHz), (300 mBi, 3000 mBm)
Apr 15 17:35:23 sputnik kernel: [ 1873.386907] wlan0: Limiting TX power to 27 (30 - 3) dBm as advertised by 02:18:1a:30:10:1c

# RFKill-cycle restores service temporarily

Apr 15 17:37:42 sputnik kernel: [ 2013.009060] iwlwifi 0000:01:00.0: RF_KILL bit toggled to disable radio.
Apr 15 17:37:42 sputnik kernel: [ 2013.009255] wlan0: deauthenticating from 02:18:1a:30:10:1c by local choice (reason=3)
Apr 15 17:37:42 sputnik kernel: [ 2013.009620] iwlwifi 0000:01:00.0: Not sending command - RF KILL
Apr 15 17:37:42 sputnik kernel: [ 2013.009625] iwlwifi 0000:01:00.0: Not sending command - RF KILL
Apr 15 17:37:42 sputnik kernel: [ 2013.009627] wlan0: HW problem - can not stop rx aggregation for tid 0
Apr 15 17:37:42 sputnik kernel: [ 2013.009650] iwlwifi 0000:01:00.0: Not sending command - RF KILL
Apr 15 17:37:42 sputnik kernel: [ 2013.009652] wlan0: failed to remove key (0, 02:18:1a:30:10:1c) from hardware (-5)
Apr 15 17:37:42 sputnik kernel: [ 2013.016803] iwlwifi 0000:01:00.0: Not sending command - RF KILL
Apr 15 17:37:42 sputnik kernel: [ 2013.016829] iwlwifi 0000:01:00.0: Not sending command - RF KILL
Apr 15 17:37:42 sputnik kernel: [ 2013.016851] iwlwifi 0000:01:00.0: Not sending command - RF KILL
Apr 15 17:37:42 sputnik kernel: [ 2013.016870] iwlwifi 0000:01:00.0: Not sending command - RF KILL
Apr 15 17:37:42 sputnik kernel: [ 2013.016882] iwlwifi 0000:01:00.0: Not sending command - RF KILL
Apr 15 17:37:42 sputnik kernel: [ 2013.016960] iwlwifi 0000:01:00.0: Not sending command - RF KILL
Apr 15 17:37:42 sputnik kernel: [ 2013.016970] wlan0: failed to remove key (2, ff:ff:ff:ff:ff:ff) from hardware (-5)
Apr 15 17:37:42 sputnik kernel: [ 2013.017340] cfg80211: Calling CRDA to update world regulatory domain
Apr 15 17:37:42 sputnik kernel: [ 2013.024747] iwlwifi 0000:01:00.0: Not sending command - RF KILL
Apr 15 17:37:42 sputnik kernel: [ 2013.024787] iwlwifi 0000:01:00.0: Not sending command - RF KILL
Apr 15 17:37:42 sputnik kernel: [ 2013.028581] cfg80211: World regulatory domain updated:
Apr 15 17:37:42 sputnik kernel: [ 2013.028584] cfg80211: (start_freq - end_freq @ bandwidth), (max_antenna_gain, max_eirp)
Apr 15 17:37:42 sputnik kernel: [ 2013.028587] cfg80211: (2402000 KHz - 2472000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
Apr 15 17:37:42 sputnik kernel: [ 2013.028591] cfg80211: (2457000 KHz - 2482000 KHz @ 20000 KHz), (300 mBi, 2000 mBm)
Apr 15 17:37:42 sputnik kernel: [ 2013.028592] cfg80211: (2474000 KHz - 2494000 KHz @ 20000 KHz), (300 mBi, 2000 mBm)
Apr 15 17:37:42 sputnik kernel: [ 2013.028594] cfg80211: (5170000 KHz - 5250000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
Apr 15 17:37:42 sputnik kernel: [ 2013.028595] cfg80211: (5735000 KHz - 5835000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
Apr 15 17:37:42 sputnik kernel: [ 2013.034172] usb 2-1.5: USB disconnect, device number 3
Apr 15 17:37:43 sputnik kernel: [ 2013.210276] IPv6: ADDRCONF(NETDEV_UP): wlan0: link is not ready
Apr 15 17:37:46 sputnik kernel: [ 2016.472461] iwlwifi 0000:01:00.0: RF_KILL bit toggled to enable radio.
Apr 15 17:37:46 sputnik kernel: [ 2016.473946] iwlwifi 0000:01:00.0: L1 Enabled; Disabling L0S
Apr 15 17:37:46 sputnik kernel: [ 2016.480950] iwlwifi 0000:01:00.0: Radio type=0x2-0x1-0x0
Apr 15 17:37:46 sputnik kernel: [ 2016.707250] usb 2-1.5: new full-speed USB device number 4 using ehci-pci
Apr 15 17:37:46 sputnik kernel: [ 2016.720031] IPv6: ADDRCONF(NETDEV_UP): wlan0: link is not ready
Apr 15 17:37:46 sputnik kernel: [ 2016.903794] usb 2-1.5: New USB device found, idVendor=8087, idProduct=07da
Apr 15 17:37:46 sputnik kernel: [ 2016.903799] usb 2-1.5: New USB device strings: Mfr=0, Product=0, SerialNumber=0
Apr 15 17:37:53 sputnik kernel: [ 2023.089951] wlan0: authenticate with 00:18:0a:30:10:1c
Apr 15 17:37:53 sputnik kernel: [ 2023.102535] wlan0: send auth to 00:18:0a:30:10:1c (try 1/3)
Apr 15 17:37:53 sputnik kernel: [ 2023.111619] wlan0: authenticated
Apr 15 17:37:53 sputnik kernel: [ 2023.113539] wlan0: associate with 00:18:0a:30:10:1c (try 1/3)
Apr 15 17:37:53 sputnik kernel: [ 2023.147173] wlan0: RX AssocResp from 00:18:0a:30:10:1c (capab=0x431 status=0 aid=2)
Apr 15 17:37:53 sputnik kernel: [ 2023.154851] wlan0: associated
Apr 15 17:37:53 sputnik kernel: [ 2023.154880] IPv6: ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready
Apr 15 17:37:53 sputnik kernel: [ 2023.154958] cfg80211: Calling CRDA for country: US
Apr 15 17:37:53 sputnik kernel: [ 2023.159505] cfg80211: Regulatory domain changed to country: US
Apr 15 17:37:53 sputnik kernel: [ 2023.159514] cfg80211: (start_freq - end_freq @ bandwidth), (max_antenna_gain, max_eirp)
Apr 15 17:37:53 sputnik kernel: [ 2023.159516] cfg80211: (2402000 KHz - 2472000 KHz @ 40000 KHz), (300 mBi, 2700 mBm)
Apr 15 17:37:53 sputnik kernel: [ 2023.159518] cfg80211: (5170000 KHz - 5250000 KHz @ 40000 KHz), (300 mBi, 1700 mBm)
Apr 15 17:37:53 sputnik kernel: [ 2023.159519] cfg80211: (5250000 KHz - 5330000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
Apr 15 17:37:53 sputnik kernel: [ 2023.159520] cfg80211: (5490000 KHz - 5600000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
Apr 15 17:37:53 sputnik kernel: [ 2023.159521] cfg80211: (5650000 KHz - 5710000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
Apr 15 17:37:53 sputnik kernel: [ 2023.159522] cfg80211: (5735000 KHz - 5835000 KHz @ 40000 KHz), (300 mBi, 3000 mBm)
Apr 15 17:37:53 sputnik kernel: [ 2023.183750] wlan0: Limiting TX power to 27 (27 - 0) dBm as advertised by 00:18:0a:30:10:1c

Here is an annotated dump from kern.log of what's going on when I suffer and then fix this issue.  Given the traffic of the log, it seems highly likely that the log lines annotate as being associated with wireless trouble is tightly connected.

# Last line from previous association
Apr 15 17:04:22 sputnik kernel: [   16.183171] wlan0: Limiting TX power to 27 (27 - 0) dBm as advertised by 00:18:0a:30:10:1c

# Everything goes sideways after this spurt
Apr 15 17:35:22 sputnik kernel: [ 1872.623038] cfg80211: Calling CRDA to update world regulatory domain
Apr 15 17:35:22 sputnik kernel: [ 1872.632242] cfg80211: World regulatory domain updated:
Apr 15 17:35:22 sputnik kernel: [ 1872.632251] cfg80211:   (start_freq - end_freq @ bandwidth), (max_antenna_gain, max_eirp)
Apr 15 17:35:22 sputnik kernel: [ 1872.632256] cfg80211:   (2402000 KHz - 2472000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
Apr 15 17:35:22 sputnik kernel: [ 1872.632261] cfg80211:   (2457000 KHz - 2482000 KHz @ 20000 KHz), (300 mBi, 2000 mBm)
Apr 15 17:35:22 sputnik kernel: [ 1872.632264] cfg80211:   (2474000 KHz - 2494000 KHz @ 20000 KHz), (300 mBi, 2000 mBm)
Apr 15 17:35:22 sputnik kernel: [ 1872.632268] cfg80211:   (5170000 KHz - 5250000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
Apr 15 17:35:22 sputnik kernel: [ 1872.632272] cfg80211:   (5735000 KHz - 5835000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
Apr 15 17:35:22 sputnik kernel: [ 1873.085904] wlan0: authenticate with 02:18:1a:30:10:1c
Apr 15 17:35:22 sputnik kernel: [ 1873.090772] wlan0: send auth to 02:18:1a:30:10:1c (try 1/3)
Apr 15 17:35:22 sputnik kernel: [ 1873.292670] wlan0: send auth to 02:18:1a:30:10:1c (try 2/3)
Apr 15 17:35:23 sputnik kernel: [ 1873.300550] wlan0: authenticated
Apr 15 17:35:23 sputnik kernel: [ 1873.304635] wlan0: associate with 02:18:1a:30:10:1c (try 1/3)
Apr 15 17:35:23 sputnik kernel: [ 1873.332528] wlan0: RX AssocResp from 02:18:1a:30:10:1c (capab=0x411 status=0 aid=3)
Apr 15 17:35:23 sputnik kernel: [ 1873.335828] wlan0: associated
Apr 15 17:35:23 sputnik kernel: [ 1873.336220] cfg80211: Calling CRDA for country: US
Apr 15 17:35:23 sputnik kernel: [ 1873.347205] cfg80211: Regulatory domain changed to country: US
Apr 15 17:35:23 sputnik kernel: [ 1873.347215] cfg80211:   (start_freq - end_freq @ bandwidth), (max_antenna_gain, max_eirp)
Apr 15 17:35:23 sputnik kernel: [ 1873.347223] cfg80211:   (2402000 KHz - 2472000 KHz @ 40000 KHz), (300 mBi, 2700 mBm)
Apr 15 17:35:23 sputnik kernel: [ 1873.347231] cfg80211:   (5170000 KHz - 5250000 KHz @ 40000 KHz), (300 mBi, 1700 mBm)
Apr 15 17:35:23 sputnik kernel: [ 1873.347237] cfg80211:   (5250000 KHz - 5330000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
Apr 15 17:35:23 sputnik kernel: [ 1873.347244] cfg80211:   (5490000 KHz - 5600000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
Apr 15 17:35:23 sputnik kernel: [ 1873.347251] cfg80211:   (5650000 KHz - 5710000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
Apr 15 17:35:23 sputnik kernel: [ 1873.347257] cfg80211:   (5735000 KHz - 5835000 KHz @ 40000 KHz), (300 mBi, 3000 mBm)
Apr 15 17:35:23 sputnik kernel: [ 1873.386907] wlan0: Limiting TX power to 27 (30 - 3) dBm as advertised by 02:18:1a:30:10:1c

# RFKill-cycle restores service temporarily

Apr 15 17:37:42 sputnik kernel: [ 2013.009060] iwlwifi 0000:01:00.0: RF_KILL bit toggled to disable radio.
Apr 15 17:37:42 sputnik kernel: [ 2013.009255] wlan0: deauthenticating from 02:18:1a:30:10:1c by local choice (reason=3)
Apr 15 17:37:42 sputnik kernel: [ 2013.009620] iwlwifi 0000:01:00.0: Not sending command - RF KILL
Apr 15 17:37:42 sputnik kernel: [ 2013.009625] iwlwifi 0000:01:00.0: Not sending command - RF KILL
Apr 15 17:37:42 sputnik kernel: [ 2013.009627] wlan0: HW problem - can not stop rx aggregation for tid 0
Apr 15 17:37:42 sputnik kernel: [ 2013.009650] iwlwifi 0000:01:00.0: Not sending command - RF KILL
Apr 15 17:37:42 sputnik kernel: [ 2013.009652] wlan0: failed to remove key (0, 02:18:1a:30:10:1c) from hardware (-5)
Apr 15 17:37:42 sputnik kernel: [ 2013.016803] iwlwifi 0000:01:00.0: Not sending command - RF KILL
Apr 15 17:37:42 sputnik kernel: [ 2013.016829] iwlwifi 0000:01:00.0: Not sending command - RF KILL
Apr 15 17:37:42 sputnik kernel: [ 2013.016851] iwlwifi 0000:01:00.0: Not sending command - RF KILL
Apr 15 17:37:42 sputnik kernel: [ 2013.016870] iwlwifi 0000:01:00.0: Not sending command - RF KILL
Apr 15 17:37:42 sputnik kernel: [ 2013.016882] iwlwifi 0000:01:00.0: Not sending command - RF KILL
Apr 15 17:37:42 sputnik kernel: [ 2013.016960] iwlwifi 0000:01:00.0: Not sending command - RF KILL
Apr 15 17:37:42 sputnik kernel: [ 2013.016970] wlan0: failed to remove key (2, ff:ff:ff:ff:ff:ff) from hardware (-5)
Apr 15 17:37:42 sputnik kernel: [ 2013.017340] cfg80211: Calling CRDA to update world regulatory domain
Apr 15 17:37:42 sputnik kernel: [ 2013.024747] iwlwifi 0000:01:00.0: Not sending command - RF KILL
Apr 15 17:37:42 sputnik kernel: [ 2013.024787] iwlwifi 0000:01:00.0: Not sending command - RF KILL
Apr 15 17:37:42 sputnik kernel: [ 2013.028581] cfg80211: World regulatory domain updated:
Apr 15 17:37:42 sputnik kernel: [ 2013.028584] cfg80211:   (start_freq - end_freq @ bandwidth), (max_antenna_gain, max_eirp)
Apr 15 17:37:42 sputnik kernel: [ 2013.028587] cfg80211:   (2402000 KHz - 2472000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
Apr 15 17:37:42 sputnik kernel: [ 2013.028591] cfg80211:   (2457000 KHz - 2482000 KHz @ 20000 KHz), (300 mBi, 2000 mBm)
Apr 15 17:37:42 sputnik kernel: [ 2013.028592] cfg80211:   (2474000 KHz - 2494000 KHz @ 20000 KHz), (300 mBi, 2000 mBm)
Apr 15 17:37:42 sputnik kernel: [ 2013.028594] cfg80211:   (5170000 KHz - 5250000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
Apr 15 17:37:42 sputnik kernel: [ 2013.028595] cfg80211:   (5735000 KHz - 5835000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
Apr 15 17:37:42 sputnik kernel: [ 2013.034172] usb 2-1.5: USB disconnect, device number 3
Apr 15 17:37:43 sputnik kernel: [ 2013.210276] IPv6: ADDRCONF(NETDEV_UP): wlan0: link is not ready
Apr 15 17:37:46 sputnik kernel: [ 2016.472461] iwlwifi 0000:01:00.0: RF_KILL bit toggled to enable radio.
Apr 15 17:37:46 sputnik kernel: [ 2016.473946] iwlwifi 0000:01:00.0: L1 Enabled; Disabling L0S
Apr 15 17:37:46 sputnik kernel: [ 2016.480950] iwlwifi 0000:01:00.0: Radio type=0x2-0x1-0x0
Apr 15 17:37:46 sputnik kernel: [ 2016.707250] usb 2-1.5: new full-speed USB device number 4 using ehci-pci
Apr 15 17:37:46 sputnik kernel: [ 2016.720031] IPv6: ADDRCONF(NETDEV_UP): wlan0: link is not ready
Apr 15 17:37:46 sputnik kernel: [ 2016.903794] usb 2-1.5: New USB device found, idVendor=8087, idProduct=07da
Apr 15 17:37:46 sputnik kernel: [ 2016.903799] usb 2-1.5: New USB device strings: Mfr=0, Product=0, SerialNumber=0
Apr 15 17:37:53 sputnik kernel: [ 2023.089951] wlan0: authenticate with 00:18:0a:30:10:1c
Apr 15 17:37:53 sputnik kernel: [ 2023.102535] wlan0: send auth to 00:18:0a:30:10:1c (try 1/3)
Apr 15 17:37:53 sputnik kernel: [ 2023.111619] wlan0: authenticated
Apr 15 17:37:53 sputnik kernel: [ 2023.113539] wlan0: associate with 00:18:0a:30:10:1c (try 1/3)
Apr 15 17:37:53 sputnik kernel: [ 2023.147173] wlan0: RX AssocResp from 00:18:0a:30:10:1c (capab=0x431 status=0 aid=2)
Apr 15 17:37:53 sputnik kernel: [ 2023.154851] wlan0: associated
Apr 15 17:37:53 sputnik kernel: [ 2023.154880] IPv6: ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready
Apr 15 17:37:53 sputnik kernel: [ 2023.154958] cfg80211: Calling CRDA for country: US
Apr 15 17:37:53 sputnik kernel: [ 2023.159505] cfg80211: Regulatory domain changed to country: US
Apr 15 17:37:53 sputnik kernel: [ 2023.159514] cfg80211:   (start_freq - end_freq @ bandwidth), (max_antenna_gain, max_eirp)
Apr 15 17:37:53 sputnik kernel: [ 2023.159516] cfg80211:   (2402000 KHz - 2472000 KHz @ 40000 KHz), (300 mBi, 2700 mBm)
Apr 15 17:37:53 sputnik kernel: [ 2023.159518] cfg80211:   (5170000 KHz - 5250000 KHz @ 40000 KHz), (300 mBi, 1700 mBm)
Apr 15 17:37:53 sputnik kernel: [ 2023.159519] cfg80211:   (5250000 KHz - 5330000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
Apr 15 17:37:53 sputnik kernel: [ 2023.159520] cfg80211:   (5490000 KHz - 5600000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
Apr 15 17:37:53 sputnik kernel: [ 2023.159521] cfg80211:   (5650000 KHz - 5710000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
Apr 15 17:37:53 sputnik kernel: [ 2023.159522] cfg80211:   (5735000 KHz - 5835000 KHz @ 40000 KHz), (300 mBi, 3000 mBm)
Apr 15 17:37:53 sputnik kernel: [ 2023.183750] wlan0: Limiting TX power to 27 (27 - 0) dBm as advertised by 00:18:0a:30:10:1c