You are not logged in.

Pages: 1

#1 2012-04-03 23:44:11

therealsuperuser
Member
Registered: 2010-11-08
Posts: 6

Wlan connection stalls, but Wlan is not reconnected (wpa_supplicant)

Hello,

I sometimes have the problem that my WPA wlan connection dies, but is still displayed "active" and no reconnect is issued. After that I cannot even ping my router anymore. I have to issue a manual reconnect to the access point (via netcfg). I presume this to be a problem of wpa_supplicant.

/var/log/messages

Apr  1 22:46:04 bellerophon acpid: 1 client rule loaded
Apr  1 22:46:12 bellerophon kernel: [   26.271028] ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready
Apr  1 22:46:13 bellerophon wpa_actiond[822]: Interface 'wlan0' connected to network 'WLAN-ESSID'
Apr  1 22:46:15 bellerophon ntpd[648]: Listen normally on 4 wlan0 fe80::222:fbff:fec7:feb6 UDP 123
Apr  1 22:46:15 bellerophon ntpd[648]: peers refreshed
Apr  1 22:46:15 bellerophon dhcpcd[1000]: version 5.5.4 starting
Apr  1 22:46:15 bellerophon dhcpcd[1000]: wlan0: sending IPv6 Router Solicitation
Apr  1 22:46:15 bellerophon dhcpcd[1000]: wlan0: broadcasting for a lease
Apr  1 22:46:16 bellerophon dhcpcd[1000]: wlan0: offered 192.168.2.107 from 192.168.2.1
Apr  1 22:46:16 bellerophon dhcpcd[1000]: wlan0: acknowledged 192.168.2.107 from 192.168.2.1
Apr  1 22:46:16 bellerophon dhcpcd[1000]: wlan0: checking for 192.168.2.107
Apr  1 22:46:19 bellerophon dhcpcd[1000]: wlan0: sending IPv6 Router Solicitation
Apr  1 22:46:21 bellerophon dhcpcd[1000]: wlan0: leased 192.168.2.107 for 864000 seconds
Apr  1 22:46:21 bellerophon dhcpcd[1000]: forked to background, child pid 1039
Apr  1 22:46:23 bellerophon ntpd[648]: Listen normally on 5 wlan0 192.168.2.107 UDP 123
Apr  1 22:46:23 bellerophon ntpd[648]: peers refreshed
Apr  1 22:46:23 bellerophon dhcpcd[1039]: wlan0: sending IPv6 Router Solicitation
Apr  1 22:46:24 bellerophon dbus[600]: [system] Activating service name='org.freedesktop.NetworkManager' (using servicehelper)
Apr  1 22:46:24 bellerophon dbus[600]: [system] Activated service 'org.freedesktop.NetworkManager' failed: Launch helper exited with unknown return code 1
Apr  1 22:46:25 bellerophon ntpd_intres[710]: DNS ptbtime1.ptb.de -> 192.53.103.108
Apr  1 22:46:27 bellerophon dhcpcd[1039]: wlan0: sending IPv6 Router Solicitation
Apr  1 22:46:27 bellerophon dhcpcd[1039]: wlan0: no IPv6 Routers available
Apr  1 22:49:00 bellerophon dbus[600]: [system] Activating service name='org.freedesktop.NetworkManager' (using servicehelper)
Apr  1 22:49:00 bellerophon dbus[600]: [system] Activated service 'org.freedesktop.NetworkManager' failed: Launch helper exited with unknown return code 1
Apr  1 23:57:08 bellerophon dbus[600]: [system] Activating service name='org.freedesktop.NetworkManager' (using servicehelper)
Apr  1 23:57:08 bellerophon dbus[600]: [system] Activated service 'org.freedesktop.NetworkManager' failed: Launch helper exited with unknown return code 1
Apr  1 23:58:23 bellerophon dbus[600]: [system] Activating service name='org.freedesktop.NetworkManager' (using servicehelper)
Apr  1 23:58:23 bellerophon dbus[600]: [system] Activated service 'org.freedesktop.NetworkManager' failed: Launch helper exited with unknown return code 1
Apr  1 23:58:36 bellerophon dbus[600]: [system] Activating service name='org.freedesktop.RealtimeKit1' (using servicehelper)
Apr  1 23:58:36 bellerophon dbus[600]: [system] Successfully activated service 'org.freedesktop.RealtimeKit1'
Apr  1 21:58:36 bellerophon rtkit-daemon[2710]: Successfully made thread 2708 of process 2708 (/usr/bin/pulseaudio) owned by '1000' high priority at nice level -11.
Apr  1 23:58:53 bellerophon dbus[600]: [system] Activating service name='org.freedesktop.NetworkManager' (using servicehelper)
Apr  1 23:58:53 bellerophon dbus[600]: [system] Activated service 'org.freedesktop.NetworkManager' failed: Launch helper exited with unknown return code 1
Apr  2 00:05:59 bellerophon dbus[600]: [system] Activating service name='org.freedesktop.NetworkManager' (using servicehelper)
Apr  2 00:05:59 bellerophon dbus[600]: [system] Activated service 'org.freedesktop.NetworkManager' failed: Launch helper exited with unknown return code 1
Apr  2 00:21:22 bellerophon dbus[600]: [system] Activating service name='org.freedesktop.NetworkManager' (using servicehelper)
Apr  2 00:21:22 bellerophon dbus[600]: [system] Activated service 'org.freedesktop.NetworkManager' failed: Launch helper exited with unknown return code 1
Apr  2 00:35:02 bellerophon laptop-mode: Laptop mode 
Apr  2 00:35:02 bellerophon laptop-mode: disabled, not active
Apr  2 00:35:03 bellerophon kernel: [ 2958.411419] uhci_hcd 0000:00:1a.1: PCI INT B disabled
Apr  2 00:35:03 bellerophon kernel: [ 2958.422138] uhci_hcd 0000:00:1a.2: PCI INT C disabled
Apr  2 00:35:03 bellerophon kernel: [ 2958.434776] uhci_hcd 0000:00:1d.0: PCI INT A disabled
Apr  2 00:35:03 bellerophon kernel: [ 2958.445334] uhci_hcd 0000:00:1d.1: PCI INT B disabled
Apr  2 00:35:03 bellerophon kernel: [ 2958.455383] uhci_hcd 0000:00:1d.2: PCI INT C disabled
Apr  2 00:35:03 bellerophon kernel: [ 2958.465488] ehci_hcd 0000:00:1d.7: PCI INT A disabled
Apr  2 00:35:04 bellerophon logger: ACPI action undefined: ACPI0003:00
Apr  2 00:35:04 bellerophon laptop-mode: Laptop mode 
Apr  2 00:35:04 bellerophon laptop-mode: disabled, 
Apr  2 00:35:04 bellerophon laptop-mode: not active [unchanged]
Apr  2 00:35:04 bellerophon logger: ACPI group/action undefined: processor / LNXCPU:00
Apr  2 00:35:04 bellerophon logger: ACPI group/action undefined: processor / LNXCPU:01
Apr  2 00:35:04 bellerophon laptop-mode: Laptop mode 
Apr  2 00:35:04 bellerophon laptop-mode: disabled, 
Apr  2 00:35:04 bellerophon laptop-mode: not active [unchanged]
Apr  2 00:35:04 bellerophon logger: ACPI action undefined: PNP0C0A:00
Apr  2 00:35:20 bellerophon logger: ACPI group/action undefined: processor / LNXCPU:00
Apr  2 00:35:20 bellerophon logger: ACPI group/action undefined: processor / LNXCPU:01
Apr  2 00:35:54 bellerophon kernel: [ 3009.382400] sd 6:0:0:0: [sdb] Test WP failed, assume Write Enabled
Apr  2 00:36:46 bellerophon kernel: [ 3061.009531] sd 6:0:0:0: [sdb] Test WP failed, assume Write Enabled
Apr  2 00:37:37 bellerophon kernel: [ 3112.208575] sd 6:0:0:0: [sdb] Test WP failed, assume Write Enabled
Apr  2 00:38:28 bellerophon kernel: [ 3163.408760] sd 6:0:0:0: [sdb] Test WP failed, assume Write Enabled
Apr  2 00:39:19 bellerophon kernel: [ 3214.609304] sd 6:0:0:0: [sdb] Test WP failed, assume Write Enabled
Apr  2 00:40:11 bellerophon kernel: [ 3265.808730] sd 6:0:0:0: [sdb] Test WP failed, assume Write Enabled
Apr  2 00:41:02 bellerophon kernel: [ 3317.008836] sd 6:0:0:0: [sdb] Test WP failed, assume Write Enabled
Apr  2 00:41:53 bellerophon kernel: [ 3368.209051] sd 6:0:0:0: [sdb] Test WP failed, assume Write Enabled
Apr  2 00:42:44 bellerophon kernel: [ 3419.408561] sd 6:0:0:0: [sdb] Test WP failed, assume Write Enabled
Apr  2 00:43:35 bellerophon kernel: [ 3470.607699] sd 6:0:0:0: [sdb] Test WP failed, assume Write Enabled
Apr  2 00:44:27 bellerophon kernel: [ 3521.808769] sd 6:0:0:0: [sdb] Test WP failed, assume Write Enabled
Apr  2 00:44:51 bellerophon laptop-mode: Laptop mode 
Apr  2 00:44:51 bellerophon laptop-mode: disabled, 
Apr  2 00:44:51 bellerophon laptop-mode: not active [unchanged]
Apr  2 00:45:18 bellerophon kernel: [ 3573.007661] sd 6:0:0:0: [sdb] Test WP failed, assume Write Enabled
Apr  2 00:46:09 bellerophon kernel: [ 3624.207690] sd 6:0:0:0: [sdb] Test WP failed, assume Write Enabled

/var/log/daemon.log :

Apr  1 22:45:55 bellerophon [    0.704671] udevd[37]: starting version 181
Apr  1 22:45:55 bellerophon [    4.941978] udevd[137]: starting version 181
Apr  1 22:45:56 bellerophon ntpd[647]: ntpd 4.2.6p5@1.2349-o Thu Mar  1 21:32:56 UTC 2012 (1)
Apr  1 22:45:56 bellerophon ntpd[648]: proto: precision = 1.117 usec
Apr  1 22:45:56 bellerophon ntpd[648]: ntp_io: estimated max descriptors: 1024, initial socket boundary: 16
Apr  1 22:45:56 bellerophon ntpd[648]: Listen and drop on 0 v4wildcard 0.0.0.0 UDP 123
Apr  1 22:45:56 bellerophon ntpd[648]: Listen and drop on 1 v6wildcard :: UDP 123
Apr  1 22:45:56 bellerophon ntpd[648]: Listen normally on 2 lo 127.0.0.1 UDP 123
Apr  1 22:45:56 bellerophon ntpd[648]: Listen normally on 3 lo ::1 UDP 123
Apr  1 22:45:56 bellerophon ntpd[648]: peers refreshed
Apr  1 22:45:56 bellerophon ntpd[648]: Listening on routing socket on fd #20 for interface updates
Apr  1 22:45:56 bellerophon acpid: starting up with netlink and the input layer
Apr  1 22:45:56 bellerophon ntpd[648]: Deferring DNS for ptbtime1.ptb.de 1
Apr  1 22:45:56 bellerophon ntpd[710]: signal_no_reset: signal 17 had flags 4000000
Apr  1 22:45:56 bellerophon acpid: 4 rules loaded
Apr  1 22:45:56 bellerophon acpid: waiting for events: event logging is off
Apr  1 22:45:57 bellerophon acpid: client connected from 790[82:82]
Apr  1 22:45:57 bellerophon acpid: 1 client rule loaded
Apr  1 22:45:58 bellerophon ntpd_intres[710]: host name not found: ptbtime1.ptb.de
Apr  1 22:46:00 bellerophon wpa_actiond[822]: Starting wpa_actiond session for interface 'wlan0'
Apr  1 22:46:02 bellerophon dbus[600]: [system] Activating service name='org.freedesktop.ConsoleKit' (using servicehelper)
Apr  1 22:46:03 bellerophon dbus[600]: [system] Activating service name='org.freedesktop.PolicyKit1' (using servicehelper)
Apr  1 22:46:03 bellerophon polkitd[891]: started daemon version 0.104 using authority implementation `local' version `0.104'
Apr  1 22:46:03 bellerophon dbus[600]: [system] Successfully activated service 'org.freedesktop.PolicyKit1'
Apr  1 22:46:03 bellerophon dbus[600]: [system] Successfully activated service 'org.freedesktop.ConsoleKit'
Apr  1 22:46:04 bellerophon acpid: client connected from 924[0:100]
Apr  1 22:46:04 bellerophon acpid: 1 client rule loaded
Apr  1 22:46:13 bellerophon wpa_actiond[822]: Interface 'wlan0' connected to network 'WLAN-ESSID'
Apr  1 22:46:15 bellerophon ntpd[648]: Listen normally on 4 wlan0 fe80::222:fbff:fec7:feb6 UDP 123
Apr  1 22:46:15 bellerophon ntpd[648]: peers refreshed
Apr  1 22:46:15 bellerophon ntpd[648]: new interface(s) found: waking up resolver
Apr  1 22:46:15 bellerophon dhcpcd[1000]: version 5.5.4 starting
Apr  1 22:46:15 bellerophon dhcpcd[1000]: wlan0: sending IPv6 Router Solicitation
Apr  1 22:46:15 bellerophon dhcpcd[1000]: wlan0: broadcasting for a lease
Apr  1 22:46:16 bellerophon dhcpcd[1000]: wlan0: offered 192.168.2.107 from 192.168.2.1
Apr  1 22:46:16 bellerophon dhcpcd[1000]: wlan0: acknowledged 192.168.2.107 from 192.168.2.1
Apr  1 22:46:16 bellerophon dhcpcd[1000]: wlan0: checking for 192.168.2.107
Apr  1 22:46:17 bellerophon ntpd_intres[710]: host name not found: ptbtime1.ptb.de
Apr  1 22:46:19 bellerophon dhcpcd[1000]: wlan0: sending IPv6 Router Solicitation
Apr  1 22:46:21 bellerophon dhcpcd[1000]: wlan0: leased 192.168.2.107 for 864000 seconds
Apr  1 22:46:21 bellerophon dhcpcd[1000]: forked to background, child pid 1039
Apr  1 22:46:23 bellerophon ntpd[648]: Listen normally on 5 wlan0 192.168.2.107 UDP 123
Apr  1 22:46:23 bellerophon ntpd[648]: peers refreshed
Apr  1 22:46:23 bellerophon ntpd[648]: new interface(s) found: waking up resolver
Apr  1 22:46:23 bellerophon dhcpcd[1039]: wlan0: sending IPv6 Router Solicitation
Apr  1 22:46:24 bellerophon dbus[600]: [system] Activating service name='org.freedesktop.NetworkManager' (using servicehelper)
Apr  1 22:46:24 bellerophon dbus[600]: [system] Activated service 'org.freedesktop.NetworkManager' failed: Launch helper exited with unknown return code 1
Apr  1 22:46:25 bellerophon ntpd_intres[710]: DNS ptbtime1.ptb.de -> 192.53.103.108
Apr  1 22:46:27 bellerophon dhcpcd[1039]: wlan0: sending IPv6 Router Solicitation
Apr  1 22:46:27 bellerophon dhcpcd[1039]: wlan0: no IPv6 Routers available
Apr  1 22:49:00 bellerophon dbus[600]: [system] Activating service name='org.freedesktop.NetworkManager' (using servicehelper)
Apr  1 22:49:00 bellerophon dbus[600]: [system] Activated service 'org.freedesktop.NetworkManager' failed: Launch helper exited with unknown return code 1
Apr  1 23:57:08 bellerophon dbus[600]: [system] Activating service name='org.freedesktop.NetworkManager' (using servicehelper)
Apr  1 23:57:08 bellerophon dbus[600]: [system] Activated service 'org.freedesktop.NetworkManager' failed: Launch helper exited with unknown return code 1
Apr  1 23:58:23 bellerophon dbus[600]: [system] Activating service name='org.freedesktop.NetworkManager' (using servicehelper)
Apr  1 23:58:23 bellerophon dbus[600]: [system] Activated service 'org.freedesktop.NetworkManager' failed: Launch helper exited with unknown return code 1
Apr  1 23:58:36 bellerophon dbus[600]: [system] Activating service name='org.freedesktop.RealtimeKit1' (using servicehelper)
Apr  1 23:58:36 bellerophon dbus[600]: [system] Successfully activated service 'org.freedesktop.RealtimeKit1'
Apr  1 21:58:36 bellerophon rtkit-daemon[2710]: Successfully called chroot.
Apr  1 21:58:36 bellerophon rtkit-daemon[2710]: Successfully dropped privileges.
Apr  1 21:58:36 bellerophon rtkit-daemon[2710]: Successfully limited resources.
Apr  1 21:58:36 bellerophon rtkit-daemon[2710]: Running.
Apr  1 21:58:36 bellerophon rtkit-daemon[2710]: Watchdog thread running.
Apr  1 21:58:36 bellerophon rtkit-daemon[2710]: Canary thread running.
Apr  1 21:58:36 bellerophon rtkit-daemon[2710]: Successfully made thread 2708 of process 2708 (/usr/bin/pulseaudio) owned by '1000' high priority at nice level -11.
Apr  1 21:58:36 bellerophon rtkit-daemon[2710]: Supervising 1 threads of 1 processes of 1 users.
Apr  1 23:58:53 bellerophon dbus[600]: [system] Activating service name='org.freedesktop.NetworkManager' (using servicehelper)
Apr  1 23:58:53 bellerophon dbus[600]: [system] Activated service 'org.freedesktop.NetworkManager' failed: Launch helper exited with unknown return code 1
Apr  2 00:05:59 bellerophon dbus[600]: [system] Activating service name='org.freedesktop.NetworkManager' (using servicehelper)
Apr  2 00:05:59 bellerophon dbus[600]: [system] Activated service 'org.freedesktop.NetworkManager' failed: Launch helper exited with unknown return code 1
Apr  2 00:21:22 bellerophon dbus[600]: [system] Activating service name='org.freedesktop.NetworkManager' (using servicehelper)
Apr  2 00:21:22 bellerophon dbus[600]: [system] Activated service 'org.freedesktop.NetworkManager' failed: Launch helper exited with unknown return code 1
Apr  2 00:35:02 bellerophon laptop-mode: Laptop mode 
Apr  2 00:35:02 bellerophon laptop-mode: disabled, not active
Apr  2 00:35:03 bellerophon laptop-mode: failed - udev not active?
Apr  2 00:35:03 bellerophon laptop-mode: failed - udev not active?
Apr  2 00:35:03 bellerophon laptop-mode: failed - udev not active?
Apr  2 00:35:03 bellerophon laptop-mode: failed - udev not active?
Apr  2 00:35:03 bellerophon laptop-mode: failed - udev not active?
Apr  2 00:35:03 bellerophon laptop-mode: failed - udev not active?
Apr  2 00:35:03 bellerophon laptop-mode: failed - udev not active?
Apr  2 00:35:03 bellerophon laptop-mode: failed - udev not active?
Apr  2 00:35:04 bellerophon laptop-mode: Laptop mode 
Apr  2 00:35:04 bellerophon laptop-mode: disabled, 
Apr  2 00:35:04 bellerophon laptop-mode: not active [unchanged]
Apr  2 00:35:04 bellerophon laptop-mode: Laptop mode 
Apr  2 00:35:04 bellerophon laptop-mode: disabled, 
Apr  2 00:35:04 bellerophon laptop-mode: not active [unchanged]
Apr  2 00:44:51 bellerophon laptop-mode: Laptop mode 
Apr  2 00:44:51 bellerophon laptop-mode: disabled, 
Apr  2 00:44:51 bellerophon laptop-mode: not active [unchanged]


ifconfig wlan0 :

wlan0: flags=4163<UP,BROADCAST,RUNNING,MULTICAST>  mtu 1500  metric 1
        inet 192.168.2.107  netmask 255.255.255.0  broadcast 192.168.2.255
        inet6 fe80::222:fbff:fec7:feb6  prefixlen 64  scopeid 0x20<link>
        ether 00:22:fb:c7:fe:b6  txqueuelen 1000  (Ethernet)
        RX packets 15343  bytes 12126268 (11.5 MiB)
        RX errors 0  dropped 0  overruns 0  frame 0
        TX packets 15045  bytes 2455394 (2.3 MiB)
        TX errors 0  dropped 0 overruns 0  carrier 0  collisions 0

iwconfig : 

wlan0     IEEE 802.11abgn  ESSID:"WLAN-ESSID"  
          Mode:Managed  Frequency:2.462 GHz  Access Point: 00:1A:4F:00:E0:CB   
          Bit Rate=36 Mb/s   Tx-Power=15 dBm   
          Retry  long limit:7   RTS thr:off   Fragment thr:off
          Power Management:on
          Link Quality=70/70  Signal level=-29 dBm  
          Rx invalid nwid:0  Rx invalid crypt:0  Rx invalid frag:0
          Tx excessive retries:0  Invalid misc:10   Missed beacon:0

Appreciate any help.
TheRealSuperUser

Offline

#2 2012-04-06 22:00:19

masutu
Member
Registered: 2010-01-30
Posts: 40

Re: Wlan connection stalls, but Wlan is not reconnected (wpa_supplicant)

Question: which wifi card do you have/which module is loaded? According to your mac address ("ether 00:22:fb:c7:fe:b6") it's an intel chip.
Maybe your issue is not "wpa_supplicant" related, but a driver bug. Since a couple of month the iwlwifi driver seems to a bit buggy, many people have similar problems (just google e.g. for "iwlwifi drops connection" and you get a bunch of up to date hits). What might help is disabling n-draft for your card (and disabling hardware crypto).

#/etc/modprobe.d/iwlwifi.conf
options iwlwifi 11n_disable=1 #swcrypto=1

or manually

modprobe iwlwifi 11n_disable=1 #swcrypto=1

Have a look at this thread.
If you don't use the iwlwifi module, please just ignore my post smile

Last edited by masutu (2012-04-06 22:00:43)

Offline

Pages: 1

Board footer

Powered by FluxBB