[8.160][BUG][FIXED] Wireless Client looses connection

Hi there,

My wireless clients (Cisco 7921) are timing out since i use the Beta. In my asterisk BOX i get the following:


[Mar  2 10:19:26] NOTICE[14684]: sccp_socket.c:277 sccp_socket_device_thread: SEP001C58CD4BC6: Closing session because connection timed out after 70 seconds (timeout: 70000).


wireless.log:

2011:03:02-10:19:42 10.10.122.107 awelogger[2036]: id="***X" severity="info" sys="System" sub="WiFi" name="STA disconnected" ssid="INT" ssid_id="WLAN0" bssid="00:1a:8c:07:a3:50" sta="d8:a2:5e:22:c9:e9"
2011:03:02-10:19:42 10.10.122.107 hostapd: wlan0: STA d8:a2:5e:22:c9:e9 WPA: event 2 notification
2011:03:02-10:19:42 10.10.122.107 hostapd: wlan0: STA d8:a2:5e:22:c9:e9 IEEE 802.11: disassociated
2011:03:02-10:19:42 10.10.122.107 hostapd: wlan0: STA d8:a2:5e:22:c9:e9 MLME: MLME-DISASSOCIATE.indication(d8:a2:5e:22:c9:e9, 8)
2011:03:02-10:19:42 10.10.122.107 hostapd: wlan0: STA d8:a2:5e:22:c9:e9 MLME: MLME-DELETEKEYS.request(d8:a2:5e:22:c9:e9)
2011:03:02-10:19:43 10.10.122.107 hostapd: wlan0: STA d8:a2:5e:22:c9:e9 IEEE 802.11: deauthenticated due to inactivity
2011:03:02-10:19:43 10.10.122.107 hostapd: wlan0: STA d8:a2:5e:22:c9:e9 MLME: MLME-DEAUTHENTICATE.indication(d8:a2:5e:22:c9:e9, 2)
2011:03:02-10:19:43 10.10.122.107 hostapd: wlan0: STA d8:a2:5e:22:c9:e9 MLME: MLME-DELETEKEYS.request(d8:a2:5e:22:c9:e9)


This happens since my update from 8.102 to 8.160

A Ping shows that the Phone seems to loose the connection after a time.

Robert
Parents

  • 2011:03:02-10:19:42 10.10.122.107 awelogger[2036]: id="***X" severity="info" sys="System" sub="WiFi" name="STA disconnected" ssid="INT" ssid_id="WLAN0" bssid="00:1a:8c:07:a3:50" sta="d8:a2:5e:22:c9:e9"
    2011:03:02-10:19:42 10.10.122.107 hostapd: wlan0: STA d8:a2:5e:22:c9:e9 WPA: event 2 notification
    2011:03:02-10:19:42 10.10.122.107 hostapd: wlan0: STA d8:a2:5e:22:c9:e9 IEEE 802.11: disassociated
    2011:03:02-10:19:42 10.10.122.107 hostapd: wlan0: STA d8:a2:5e:22:c9:e9 MLME: MLME-DISASSOCIATE.indication(d8:a2:5e:22:c9:e9, 8)
    2011:03:02-10:19:42 10.10.122.107 hostapd: wlan0: STA d8:a2:5e:22:c9:e9 MLME: MLME-DELETEKEYS.request(d8:a2:5e:22:c9:e9)
    2011:03:02-10:19:43 10.10.122.107 hostapd: wlan0: STA d8:a2:5e:22:c9:e9 IEEE 802.11: deauthenticated due to inactivity
    2011:03:02-10:19:43 10.10.122.107 hostapd: wlan0: STA d8:a2:5e:22:c9:e9 MLME: MLME-DEAUTHENTICATE.indication(d8:a2:5e:22:c9:e9, 2)
    2011:03:02-10:19:43 10.10.122.107 hostapd: wlan0: STA d8:a2:5e:22:c9:e9 MLME: MLME-DELETEKEYS.request(d8:a2:5e:22:c9:e9) 


    So, the phone is disassociating itself due to "inactivity".
    The question is why this happens and why it didn't happen in 8.100.

    Maybe traffic is stuck somehow and the phone doesn't receive anything anymore. Or it is related to the phone entering aggressive powersaving. We'll see.

    Helmut
Reply

  • 2011:03:02-10:19:42 10.10.122.107 awelogger[2036]: id="***X" severity="info" sys="System" sub="WiFi" name="STA disconnected" ssid="INT" ssid_id="WLAN0" bssid="00:1a:8c:07:a3:50" sta="d8:a2:5e:22:c9:e9"
    2011:03:02-10:19:42 10.10.122.107 hostapd: wlan0: STA d8:a2:5e:22:c9:e9 WPA: event 2 notification
    2011:03:02-10:19:42 10.10.122.107 hostapd: wlan0: STA d8:a2:5e:22:c9:e9 IEEE 802.11: disassociated
    2011:03:02-10:19:42 10.10.122.107 hostapd: wlan0: STA d8:a2:5e:22:c9:e9 MLME: MLME-DISASSOCIATE.indication(d8:a2:5e:22:c9:e9, 8)
    2011:03:02-10:19:42 10.10.122.107 hostapd: wlan0: STA d8:a2:5e:22:c9:e9 MLME: MLME-DELETEKEYS.request(d8:a2:5e:22:c9:e9)
    2011:03:02-10:19:43 10.10.122.107 hostapd: wlan0: STA d8:a2:5e:22:c9:e9 IEEE 802.11: deauthenticated due to inactivity
    2011:03:02-10:19:43 10.10.122.107 hostapd: wlan0: STA d8:a2:5e:22:c9:e9 MLME: MLME-DEAUTHENTICATE.indication(d8:a2:5e:22:c9:e9, 2)
    2011:03:02-10:19:43 10.10.122.107 hostapd: wlan0: STA d8:a2:5e:22:c9:e9 MLME: MLME-DELETEKEYS.request(d8:a2:5e:22:c9:e9) 


    So, the phone is disassociating itself due to "inactivity".
    The question is why this happens and why it didn't happen in 8.100.

    Maybe traffic is stuck somehow and the phone doesn't receive anything anymore. Or it is related to the phone entering aggressive powersaving. We'll see.

    Helmut
Children
  • As far as I am concerned that is a new feature. I don't remember ticking it in the previous version.

    Ian M
  • iam experiencing the same issue....


    Mind to attach the relevant part of your wireless.log to verify if it is the exact same issue? Also what clients are you using?

    Helmut
  • pls check this log from kernel eth3 is wireles 

      2011:03:02-16:00:36 acenn kernel: [97730.474956] eth3: link up, 100Mbps, full-duplex, lpa 0x45E1
    
    2011:03:02-16:01:10 acenn kernel: [97764.744164] device redw0 entered promiscuous mode
    2011:03:02-16:01:10 acenn kernel: [97764.747444] device redw0.100 entered promiscuous mode
    2011:03:02-16:01:40 acenn kernel: [97795.313582] eth3: link down
    2011:03:02-16:01:42 acenn kernel: [97797.025044] eth3: link up, 100Mbps, full-duplex, lpa 0x45E1
    2011:03:02-16:01:46 acenn kernel: [97800.704006] Machine check events logged
    2011:03:02-16:04:00 acenn kernel: [97934.842653] eth3: link down
    2011:03:02-16:04:02 acenn kernel: [97936.478467] eth3: link up, 100Mbps, full-duplex, lpa 0x45E1
    2011:03:02-16:06:19 acenn kernel: [98074.371639] eth3: link down
    2011:03:02-16:06:21 acenn kernel: [98076.089125] eth3: link up, 100Mbps, full-duplex, lpa 0x45E1
    2011:03:02-16:08:39 acenn kernel: [98213.900668] eth3: link down
    2011:03:02-16:08:41 acenn kernel: [98215.542538] eth3: link up, 100Mbps, full-duplex, lpa 0x45E1
    2011:03:02-16:10:58 acenn kernel: [98353.429739] eth3: link down
    2011:03:02-16:11:00 acenn kernel: [98355.153256] eth3: link up, 100Mbps, full-duplex, lpa 0x45E1
    2011:03:02-16:13:18 acenn kernel: [98492.958819] eth3: link down
    2011:03:02-16:13:20 acenn kernel: [98494.606716] eth3: link up, 100Mbps, full-duplex, lpa 0x45E1
    2011:03:02-16:15:38 acenn kernel: [98632.487916] eth3: link down
    2011:03:02-16:15:39 acenn kernel: [98634.217473] eth3: link up, 100Mbps, full-duplex, lpa 0x45E1
    2011:03:02-16:17:57 acenn kernel: [98772.017023] eth3: link down
    2011:03:02-16:17:59 acenn kernel: [98773.670962] eth3: link up, 100Mbps, full-duplex, lpa 0x45E1
    2011:03:02-16:20:17 acenn kernel: [98911.546135] eth3: link down
    2011:03:02-16:20:18 acenn kernel: [98913.229308] eth3: link up, 100Mbps, full-duplex, lpa 0x45E1


    pls check this log from wifi :

     2011:03:01-02:19:26 172.16.28.3 hostapd: wlan0: STA 00:25:56:31:e6:38 IEEE 802.11: disassociated
    
    2011:03:01-02:19:26 172.16.28.3 awelogger[1827]: id="***X" severity="info" sys="System" sub="WiFi" name="STA disconnected" ssid="AstaroGuest" ssid_id="WLAN0" bssid="00:1a:8c:06:0f:90" sta="00:25:56:31:e6:38"
    2011:03:01-02:19:26 172.16.28.3 hostapd: wlan0: STA 00:25:56:31:e6:38 MLME: MLME-DISASSOCIATE.indication(00:25:56:31:e6:38, 8)
    2011:03:01-02:19:26 172.16.28.3 hostapd: wlan0: STA 00:25:56:31:e6:38 MLME: MLME-DELETEKEYS.request(00:25:56:31:e6:38)
    2011:03:01-02:19:27 172.16.28.3 hostapd: wlan0: STA 00:25:56:31:e6:38 IEEE 802.11: deauthenticated due to inactivity
    2011:03:01-02:19:27 172.16.28.3 hostapd: wlan0: STA 00:25:56:31:e6:38 MLME: MLME-DEAUTHENTICATE.indication(00:25:56:31:e6:38, 2)
    2011:03:01-02:19:27 172.16.28.3 hostapd: wlan0: STA 00:25:56:31:e6:38 MLME: MLME-DELETEKEYS.request(00:25:56:31:e6:38)
    2011:03:01-02:20:36 acenn awed[6385]: send msg: ip:'172.16.28.3', id:'A40000CDDE45D2A', type:'3', length:'16'
    2011:03:01-02:20:51 acenn awed[6385]: send msg: ip:'172.16.28.3', id:'A40000CDDE45D2A', type:'3', length:'16'
    2011:03:01-02:21:06 acenn awed[6385]: send msg: ip:'172.16.28.3', id:'A40000CDDE45D2A', type:'3', length:'16'
    2011:03:01-02:21:21 acenn awed[6385]: send msg: ip:'172.16.28.3', id:'A40000CDDE45D2A', type:'3', length:'16'
    2011:03:01-02:21:22 acenn awed[6385]: Client 00:25:56:31:e6:38 timed out after 61 seconds
    2011:03:01-02:21:36 acenn awed[6385]: send msg: ip:'172.16.28.3', id:'A40000CDDE45D2A', type:'3', length:'16'


      2011:03:01-11:33:32 172.16.28.3 hostapd: wlan0: STA 00:25:56:31:e6:38 IEEE 802.11: disassociated
    
    2011:03:01-11:33:32 172.16.28.3 awelogger[1254]: id="***X" severity="info" sys="System" sub="WiFi" name="STA disconnected" ssid="AstaroGuest" ssid_id="WLAN0" bssid="00:1a:8c:06:0f:90" sta="00:25:56:31:e6:38"
    2011:03:01-11:33:32 172.16.28.3 hostapd: wlan0: STA 00:25:56:31:e6:38 MLME: MLME-DISASSOCIATE.indication(00:25:56:31:e6:38, 8)
    2011:03:01-11:33:32 172.16.28.3 hostapd: wlan0: STA 00:25:56:31:e6:38 MLME: MLME-DELETEKEYS.request(00:25:56:31:e6:38)
    2011:03:01-11:33:33 172.16.28.3 hostapd: wlan0: STA 00:25:56:31:e6:38 IEEE 802.11: deauthenticated due to inactivity
    2011:03:01-11:33:33 172.16.28.3 hostapd: wlan0: STA 00:25:56:31:e6:38 MLME: MLME-DEAUTHENTICATE.indication(00:25:56:31:e6:38, 2)
    2011:03:01-11:33:33 172.16.28.3 hostapd: wlan0: STA 00:25:56:31:e6:38 MLME: MLME-DELETEKEYS.request(00:25:56:31:e6:38)
    2011:03:01-11:33:39 acenn awed[6227]: send msg: ip:'172.16.28.3', id:'A40000CDDE45D2A', type:'3', length:'16'
    2011:03:01-11:34:24 acenn awed[6227]: Client 00:25:56:31:e6:38 timed out after 61 seconds
    2011:03:01-11:34:40 acenn awed[6227]: AP 'A40000CDDE45D2A': timed out after 61 seconds


    every time it disconnected after 61 seconds for me 

    thx

  • pls check this log from wifi :

     2011:03:01-02:19:26 172.16.28.3 hostapd: wlan0: STA 00:25:56:31:e6:38 IEEE 802.11: disassociated
    
    2011:03:01-02:19:26 172.16.28.3 awelogger[1827]: id="***X" severity="info" sys="System" sub="WiFi" name="STA disconnected" ssid="AstaroGuest" ssid_id="WLAN0" bssid="00:1a:8c:06:0f:90" sta="00:25:56:31:e6:38"
    2011:03:01-02:19:26 172.16.28.3 hostapd: wlan0: STA 00:25:56:31:e6:38 MLME: MLME-DISASSOCIATE.indication(00:25:56:31:e6:38, 8)
    2011:03:01-02:19:26 172.16.28.3 hostapd: wlan0: STA 00:25:56:31:e6:38 MLME: MLME-DELETEKEYS.request(00:25:56:31:e6:38)
    2011:03:01-02:19:27 172.16.28.3 hostapd: wlan0: STA 00:25:56:31:e6:38 IEEE 802.11: deauthenticated due to inactivity
    2011:03:01-02:19:27 172.16.28.3 hostapd: wlan0: STA 00:25:56:31:e6:38 MLME: MLME-DEAUTHENTICATE.indication(00:25:56:31:e6:38, 2)
    2011:03:01-02:19:27 172.16.28.3 hostapd: wlan0: STA 00:25:56:31:e6:38 MLME: MLME-DELETEKEYS.request(00:25:56:31:e6:38)
    2011:03:01-02:20:36 acenn awed[6385]: send msg: ip:'172.16.28.3', id:'A40000CDDE45D2A', type:'3', length:'16'
    2011:03:01-02:20:51 acenn awed[6385]: send msg: ip:'172.16.28.3', id:'A40000CDDE45D2A', type:'3', length:'16'
    2011:03:01-02:21:06 acenn awed[6385]: send msg: ip:'172.16.28.3', id:'A40000CDDE45D2A', type:'3', length:'16'
    2011:03:01-02:21:21 acenn awed[6385]: send msg: ip:'172.16.28.3', id:'A40000CDDE45D2A', type:'3', length:'16'
    2011:03:01-02:21:22 acenn awed[6385]: Client 00:25:56:31:e6:38 timed out after 61 seconds
    2011:03:01-02:21:36 acenn awed[6385]: send msg: ip:'172.16.28.3', id:'A40000CDDE45D2A', type:'3', length:'16'


    every time it disconnected after 61 seconds for me 


    The 61 second timeout just tells you that the ASG discards the client from its internal list. So, this is not a good indication of the real timespan between assoc and disassoc.

    What type of client do you use?

    Helmut
  • pls check this log from kernel eth3 is wireles 

      2011:03:02-16:00:36 acenn kernel: [97730.474956] eth3: link up, 100Mbps, full-duplex, lpa 0x45E1
    
    2011:03:02-16:01:10 acenn kernel: [97764.744164] device redw0 entered promiscuous mode
    2011:03:02-16:01:10 acenn kernel: [97764.747444] device redw0.100 entered promiscuous mode
    2011:03:02-16:01:40 acenn kernel: [97795.313582] eth3: link down
    2011:03:02-16:01:42 acenn kernel: [97797.025044] eth3: link up, 100Mbps, full-duplex, lpa 0x45E1
    2011:03:02-16:01:46 acenn kernel: [97800.704006] Machine check events logged
    2011:03:02-16:04:00 acenn kernel: [97934.842653] eth3: link down
    2011:03:02-16:04:02 acenn kernel: [97936.478467] eth3: link up, 100Mbps, full-duplex, lpa 0x45E1
    2011:03:02-16:06:19 acenn kernel: [98074.371639] eth3: link down
    2011:03:02-16:06:21 acenn kernel: [98076.089125] eth3: link up, 100Mbps, full-duplex, lpa 0x45E1
    2011:03:02-16:08:39 acenn kernel: [98213.900668] eth3: link down
    2011:03:02-16:08:41 acenn kernel: [98215.542538] eth3: link up, 100Mbps, full-duplex, lpa 0x45E1
    2011:03:02-16:10:58 acenn kernel: [98353.429739] eth3: link down
    2011:03:02-16:11:00 acenn kernel: [98355.153256] eth3: link up, 100Mbps, full-duplex, lpa 0x45E1
    2011:03:02-16:13:18 acenn kernel: [98492.958819] eth3: link down
    2011:03:02-16:13:20 acenn kernel: [98494.606716] eth3: link up, 100Mbps, full-duplex, lpa 0x45E1
    2011:03:02-16:15:38 acenn kernel: [98632.487916] eth3: link down
    2011:03:02-16:15:39 acenn kernel: [98634.217473] eth3: link up, 100Mbps, full-duplex, lpa 0x45E1
    2011:03:02-16:17:57 acenn kernel: [98772.017023] eth3: link down
    2011:03:02-16:17:59 acenn kernel: [98773.670962] eth3: link up, 100Mbps, full-duplex, lpa 0x45E1
    2011:03:02-16:20:17 acenn kernel: [98911.546135] eth3: link down
    2011:03:02-16:20:18 acenn kernel: [98913.229308] eth3: link up, 100Mbps, full-duplex, lpa 0x45E1



    That looks strange. eth3 goes down for 2 seconds every 2 minutes ... Might however be unrelated to the wifi issue ...

  • What type of client do you use?

    Helmut


    i am using nintendo dsi and dell vostro 1520 latptop with win xp sp3 with up2date update 

    thx
    edit :i have deleted all config and delete the wireless assign interface also now how do i start from basic 
    do i have to use 172.x.x.x is it mandatory ?
    or any other script which will take me to the basic setup 

    thx
  • i am using nintendo dsi and dell vostro 1520 latptop with win xp sp3 with up2date update 


    And both show the same symptoms?
  • And both show the same symptoms?


    Sorry i dont rember that as i just take my AP 10 1 day back to wireless thing inface i notice 61 seconds time out yesterday only and it was working fine for me with transpart poxy with nintendo and latpop but i want to understand the full wifi setup that's why i delete the config now ap10 is getting ip but nintendo and laptop are not gettting ip from dhcp server 

    but yesterday nintendo was working fine and latptop was not getting ip after messed some setup 

    that's why i want to start from scratch 

    thx
  • Sorry i dont rember that as i just take my AP 10 1 day back to wireless thing inface i notice 61 seconds time out yesterday only and it was working fine for me with transpart poxy with nintendo and latpop but i want to understand the full wifi setup that's why i delete the config now ap10 is getting ip but nintendo and laptop are not gettting ip from dhcp server 

    but yesterday nintendo was working fine and latptop was not getting ip after messed some setup 

    that's why i want to start from scratch 

    thx


    Ok, then it's time for a new thread instead of hijacking this one [;)]