clone 833507 -1 retitle -1 network-manager drives broadcom driver into confused state reassign -1 network-manager thanks
On Sat, 3 Sep 2016 22:00:48 +0200 "Eduard Bloch" <bl...@unix-ag.uni-kl.de> wrote: > On Tue, 9 Aug 2016 17:27:58 +1000 Barry Kitson <b.kit...@gmail.com> wrote: > > Just confirming version 2.5-2+v2.4-1 currently in the debian repository > > works for me. > > Sorry, for me that does NOT work, at least not fully. I have a Lenovo > laptop with a similar Broadcom chip, i.e. only non-free driver supports > it. Still getting lots of: > > wlan0: CTRL-EVENT-SCAN-FAILED ret=-22 retry=1 > > and nothing works, apart from getting the initial AP list. > > However, this happens only with network-manager. Just for testing, I > configured a wpa_supplicant.conf manually and run wpa_supplicant > manually with that file, and also run dhclient manually. And I got > a working network connection! > > So IMHO this might be somehow connected to network-manager. So I tried more of that for a while and it seems to be reproducible. a) I stop wpasupplicant service and run wpa_supplicant manually. I see chaotic disconnects/reconects (see below, slightly anonymized) which seem to be triggered by network-manager. b) I stop network-manager service and run wpa_supplicant manually. It seems to associate just fine. I run dhclient manually, it gets the IP, and the link is OK for days. Even after suspend/resume cycles, it reassociates and/or switches the AP as needed and continues without any glitches (see the last log below). So for me there seems to be a trend... whatever NM is doing to wpa_supplicant or the driver, it's no good. And it has been working fine for a couple of years before one of the recent upgrades broke it. Successfully initialized wpa_supplicant rfkill: Cannot open RFKILL control device wlan0: Trying to associate with 00:1f:3f:15:f4:2d (SSID='ANON_nomap' freq=2472 MHz) wlan0: Associated with 00:1f:3f:15:f4:2d wlan0: CTRL-EVENT-DISCONNECTED bssid=00:1f:3f:15:f4:2d reason=0 wlan0: WPA: 4-Way Handshake failed - pre-shared key may be incorrect wlan0: CTRL-EVENT-SSID-TEMP-DISABLED id=0 ssid="ANON_nomap" auth_failures=1 duration=10 reason=WRONG_KEY wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD wlan0: CTRL-EVENT-REGDOM-CHANGE init=BEACON_HINT type=UNKNOWN wlan0: Trying to associate with 00:24:fe:04:fc:bb (SSID='ANON2_nomap' freq=2452 MHz) wlan0: CTRL-EVENT-DISCONNECTED bssid=00:24:fe:04:fc:bb reason=0 wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD wlan0: Associated with 00:24:fe:04:fc:bb wlan0: CTRL-EVENT-DISCONNECTED bssid=00:24:fe:04:fc:bb reason=0 wlan0: WPA: 4-Way Handshake failed - pre-shared key may be incorrect wlan0: CTRL-EVENT-SSID-TEMP-DISABLED id=1 ssid="ANON2_nomap" auth_failures=1 duration=10 reason=WRONG_KEY wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD wlan0: CTRL-EVENT-REGDOM-CHANGE init=BEACON_HINT type=UNKNOWN wlan0: CTRL-EVENT-SSID-REENABLED id=0 ssid="ANON_nomap" wlan0: Trying to associate with 00:1f:3f:15:f4:2d (SSID='ANON_nomap' freq=2472 MHz) wlan0: CTRL-EVENT-DISCONNECTED bssid=00:1f:3f:15:f4:2d reason=0 wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD wlan0: CTRL-EVENT-SCAN-FAILED ret=-16 retry=1 wlan0: CTRL-EVENT-REGDOM-CHANGE init=BEACON_HINT type=UNKNOWN wlan0: Associated with 00:1f:3f:15:f4:2d wlan0: CTRL-EVENT-DISCONNECTED bssid=00:1f:3f:15:f4:2d reason=0 wlan0: WPA: 4-Way Handshake failed - pre-shared key may be incorrect wlan0: CTRL-EVENT-SSID-TEMP-DISABLED id=0 ssid="ANON_nomap" auth_failures=2 duration=23 reason=WRONG_KEY wlan0: CTRL-EVENT-SSID-TEMP-DISABLED id=0 ssid="ANON_nomap" auth_failures=3 duration=46 reason=CONN_FAILED wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD wlan0: CTRL-EVENT-REGDOM-CHANGE init=BEACON_HINT type=UNKNOWN wlan0: CTRL-EVENT-SSID-REENABLED id=1 ssid="ANON2_nomap" wlan0: Trying to associate with 00:24:fe:04:fc:bb (SSID='ANON2_nomap' freq=2452 MHz) wlan0: CTRL-EVENT-DISCONNECTED bssid=00:24:fe:04:fc:bb reason=0 wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD wlan0: Associated with 00:24:fe:04:fc:bb wlan0: CTRL-EVENT-DISCONNECTED bssid=00:24:fe:04:fc:bb reason=0 wlan0: WPA: 4-Way Handshake failed - pre-shared key may be incorrect wlan0: CTRL-EVENT-SSID-TEMP-DISABLED id=1 ssid="ANON2_nomap" auth_failures=2 duration=37 reason=WRONG_KEY wlan0: CTRL-EVENT-SSID-TEMP-DISABLED id=1 ssid="ANON2_nomap" auth_failures=3 duration=55 reason=CONN_FAILED wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD wlan0: CTRL-EVENT-REGDOM-CHANGE init=BEACON_HINT type=UNKNOWN wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD wlan0: CTRL-EVENT-REGDOM-CHANGE init=BEACON_HINT type=UNKNOWN wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD wlan0: CTRL-EVENT-REGDOM-CHANGE init=BEACON_HINT type=UNKNOWN wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD wlan0: CTRL-EVENT-REGDOM-CHANGE init=BEACON_HINT type=UNKNOWN wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD wlan0: CTRL-EVENT-REGDOM-CHANGE init=BEACON_HINT type=UNKNOWN wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD wlan0: CTRL-EVENT-REGDOM-CHANGE init=BEACON_HINT type=UNKNOWN wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD wlan0: CTRL-EVENT-REGDOM-CHANGE init=BEACON_HINT type=UNKNOWN wlan0: CTRL-EVENT-SSID-REENABLED id=0 ssid="ANON_nomap" wlan0: Trying to associate with 00:1f:3f:15:f4:2d (SSID='ANON_nomap' freq=2472 MHz) wlan0: CTRL-EVENT-DISCONNECTED bssid=00:1f:3f:15:f4:2d reason=0 wlan0: CTRL-EVENT-SSID-TEMP-DISABLED id=0 ssid="ANON_nomap" auth_failures=4 duration=93 reason=CONN_FAILED wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD wlan0: CTRL-EVENT-REGDOM-CHANGE init=BEACON_HINT type=UNKNOWN q^Cnl80211: deinit ifname=wlan0 disabled_11b_rates=0 wlan0: CTRL-EVENT-TERMINATING dmesg, dozens of such messages: [ 969.074570] ------------[ cut here ]------------ [ 969.074666] WARNING: CPU: 1 PID: 428 at net/wireless/sme.c:878 wl_notify_roaming_status+0xb9/0xff [wl] [ 969.074674] Modules linked in: nls_utf8 fuse btrfs xor raid6_pq ufs qnx4 hfsplus minix ntfs vfat msdos fat xfs libcrc32c crc32c_generic ext2 bnep nfsd nfs_acl nfs lockd grace fscache sunrpc binfmt_misc btusb btrtl btintel btbcm bluetooth uvcvideo videobuf2_vmalloc videobuf2_memops videobuf2_v4l2 videodev videobuf2_core kvm_amd input_leds joydev evdev kvm irqbypass serio_raw pcspkr fam15h_power wl(PO) k10temp i2c_piix4 cfg80211 snd_hda_codec_hdmi radeon snd_hda_codec_realtek snd_hda_codec_generic thinkpad_acpi nvram snd_hda_intel sg snd_hda_codec wmi snd_hda_core snd_hwdep video battery button ac snd_pcm snd_timer snd soundcore acpi_cpufreq tpm_tis tpm processor amdgpu mfd_core ttm drm_kms_helper drm i2c_algo_bit i2c_core fb_sys_fops syscopyarea sysfillrect sysimgblt autofs4 ext4 crc16 jbd2 mbcache [ 969.074884] algif_skcipher af_alg dm_crypt dm_mod sd_mod crct10dif_pclmul crc32_pclmul crc32c_intel ghash_clmulni_intel jitterentropy_rng drbg ansi_cprng aesni_intel aes_x86_64 lrw ohci_pci gf128mul glue_helper ablk_helper cryptd ahci libahci psmouse libata ohci_hcd ehci_pci ehci_hcd scsi_mod xhci_pci xhci_hcd usbcore usb_common r8169 mii thermal [ 969.074974] CPU: 1 PID: 428 Comm: wl_event_handle Tainted: P W O 4.7.0-rc5+ #33 [ 969.074982] Hardware name: LENOVO 20BC0006GE/20BC0006GE, BIOS HSET57WW (2.02 ) 02/24/2014 [ 969.074990] 0000000000000006 ffffffff811d5d5a 0000000000000000 0000000000000000 [ 969.075002] ffffffff81042b00 ffff8802147ca6e0 ffff8802117e5734 ffff8802147caefa [ 969.075014] ffff8802156a7000 0000000000000000 ffff880214e7b300 ffffffffa07a53c5 [ 969.075026] Call Trace: [ 969.075044] [<ffffffff811d5d5a>] ? dump_stack+0x46/0x59 [ 969.075054] [<ffffffff81042b00>] ? __warn+0xc8/0xe1 [ 969.075124] [<ffffffffa07a53c5>] ? wl_notify_roaming_status+0xb9/0xff [wl] [ 969.075193] [<ffffffffa07a5a01>] ? wl_event_handler+0x14c/0x18a [wl] [ 969.075261] [<ffffffffa07a58b5>] ? wl_cfg80211_resume+0x337/0x337 [wl] [ 969.075274] [<ffffffff81057fbe>] ? kthread+0x95/0x9d [ 969.075285] [<ffffffff813ba0ff>] ? ret_from_fork+0x1f/0x40 [ 969.075296] [<ffffffff81057f29>] ? init_completion+0x1d/0x1d [ 969.075304] ---[ end trace 4708b32528aa9e2d ]--- [ 975.218409] ------------[ cut here ]------------ Good log, no NM is active: Successfully initialized wpa_supplicant rfkill: Cannot open RFKILL control device wlan0: Trying to associate with 00:1f:3f:15:f4:2d (SSID='K-PAX_nomap' freq=2472 MHz) wlan0: Associated with 00:1f:3f:15:f4:2d wlan0: WPA: Key negotiation completed with 00:1f:3f:15:f4:2d [PTK=CCMP GTK=TKIP] wlan0: CTRL-EVENT-CONNECTED - Connection to 00:1f:3f:15:f4:2d completed [id=0 id_str=] wlan0: WPA: Group rekeying completed with 00:1f:3f:15:f4:2d [GTK=TKIP] wlan0: WPA: Group rekeying completed with 00:1f:3f:15:f4:2d [GTK=TKIP] wlan0: CTRL-EVENT-DISCONNECTED bssid=00:1f:3f:15:f4:2d reason=0 wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD wlan0: CTRL-EVENT-REGDOM-CHANGE init=BEACON_HINT type=UNKNOWN wlan0: Trying to associate with 00:1f:3f:15:f4:2d (SSID='K-PAX_nomap' freq=2472 MHz) wlan0: Associated with 00:1f:3f:15:f4:2d wlan0: WPA: Key negotiation completed with 00:1f:3f:15:f4:2d [PTK=CCMP GTK=TKIP] wlan0: CTRL-EVENT-CONNECTED - Connection to 00:1f:3f:15:f4:2d completed [id=0 id_str=] wlan0: CTRL-EVENT-DISCONNECTED bssid=00:1f:3f:15:f4:2d reason=0 wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD wlan0: Trying to associate with 00:1f:3f:15:f4:2d (SSID='K-PAX_nomap' freq=2472 MHz) wlan0: CTRL-EVENT-REGDOM-CHANGE init=BEACON_HINT type=UNKNOWN wlan0: Associated with 00:1f:3f:15:f4:2d wlan0: WPA: Key negotiation completed with 00:1f:3f:15:f4:2d [PTK=CCMP GTK=TKIP] wlan0: CTRL-EVENT-CONNECTED - Connection to 00:1f:3f:15:f4:2d completed [id=0 id_str=] wlan0: WPA: Group rekeying completed with 00:1f:3f:15:f4:2d [GTK=TKIP] wlan0: WPA: Group rekeying completed with 00:1f:3f:15:f4:2d [GTK=TKIP] wlan0: WPA: Group rekeying completed with 00:1f:3f:15:f4:2d [GTK=TKIP] wlan0: WPA: Group rekeying completed with 00:1f:3f:15:f4:2d [GTK=TKIP] wlan0: WPA: Group rekeying completed with 00:1f:3f:15:f4:2d [GTK=TKIP] Regards, Eduard.
