This discussion has been locked.
You can no longer post new replies to this discussion. If you have a question you can start a new discussion

AP 10 disconnecting

Hi All

I am getting quite few disconnects over the past month and this is relevant log

2012:09:03-18:47:52 ap-10 hostapd: wlan1: STA 68:a8:6d:cd:2e:07 WPA: sending 1/2 msg of Group Key Handshake
2012:09:03-18:47:52 ap-10 hostapd: wlan1: STA 70:56:81:c2:3a:19 WPA: sending 1/2 msg of Group Key Handshake
2012:09:03-18:47:52 ap-10 hostapd: wlan1: STA 70:56:81:c2:3a:19 WPA: received EAPOL-Key frame (2/2 Group)
2012:09:03-18:47:52 ap-10 hostapd: wlan1: STA 70:56:81:c2:3a:19 WPA: group key handshake completed (RSN)
2012:09:03-18:47:52 ap-10 hostapd: wlan1: STA 68:a8:6d:cd:2e:07 WPA: received EAPOL-Key frame (2/2 Group)
2012:09:03-18:47:52 ap-10 hostapd: wlan1: STA 68:a8:6d:cd:2e:07 WPA: group key handshake completed (RSN)
2012:09:03-18:49:35 ap-10 sysinit: CTRL-EVENT-EAP-PROPOSED-METHOD vendor=0 method=1alrm_handler(): timeout, reason 1
2012:09:03-18:49:35 ap-10 init: starting pid 20657, tty '': '/etc/init.d/rcS K shutdown'
2012:09:03-18:49:35 ap-10 sysinit: interface not found.
2012:09:03-18:50:02 * awed[6916]: [AP10 A40000D709AC807] disconnected. Close socket and kill process.
2012:09:03-18:50:04 * awed[4157]: [MASTER] new connection from 192.168.2.1:45989
2012:09:03-18:50:06 * awed[6568]: [AP10 A40000D709AC807] (Re-)loaded identity and/or configuration
2012:09:03-18:50:08 ap-10 sysinit: Channel 2 busy time 17/230ms, 7%


and 

2012:08:29-06:32:42 ap-10 hostapd: wlan1: STA 68:a8:6d:cd:2e:07 IEEE 802.1X: authenticated - EAP type: 21 ((null))
2012:08:29-06:32:42 ap-10 hostapd: wlan1: STA 68:a8:6d:cd:2e:07 WPA: Added PMKSA cache entry (IEEE 802.1X)
2012:08:29-06:41:32 ap-10 hostapd: wlan1: WPA rekeying GTK
2012:08:29-06:41:32 ap-10 hostapd: wlan1: STA 68:a8:6d:cd:2e:07 WPA: sending 1/2 msg of Group Key Handshake
2012:08:29-06:41:32 ap-10 hostapd: wlan1: STA 68:a8:6d:cd:2e:07 WPA: received EAPOL-Key frame (2/2 Group)
2012:08:29-06:41:32 ap-10 hostapd: wlan1: STA 68:a8:6d:cd:2e:07 WPA: group key handshake completed (RSN)
2012:08:29-06:47:38 ap-10 sysinit: CTRL-EVENT-EAP-PROPOSED-METHOD vendor=0 method=1alrm_handler(): timeout, reason 1
2012:08:29-06:47:38 ap-10 sysinit: interface not found.
2012:08:29-06:48:06 * awed[15443]: [AP10 A40000D709AC807] disconnected. Close socket and kill process.
2012:08:29-06:48:09 * awed[4146]: [MASTER] new connection from 192.168.2.1:45989
2012:08:29-06:48:10 * awed[26308]: [AP10 A40000D709AC807] (Re-)loaded identity and/or configuration
2012:08:29-06:48:11 ap-10 sysinit: Channel 2 busy time 33/242ms, 13%



MAC 68:a8:6d:cd:2e:07 is : iphone 4S IOS 5.1.1

Same issue as here: https://community.sophos.com/products/unified-threat-management/astaroorg/f/76/t/64639 Is there a solution on this one?

Thanks


This thread was automatically locked due to age.
Parents
  • Have you tried the AP re-flashing utility in Support Tools?

    Cheers - Bob
     
    Sophos UTM Community Moderator
    Sophos Certified Architect - UTM
    Sophos Certified Engineer - XG
    Gold Solution Partner since 2005
    MediaSoft, Inc. USA
  • Have you tried the AP re-flashing utility in Support Tools?

    Cheers - Bob

    haven't tried this yet Bob as I know this is a known issue that the dev/qa are working on and I would like to provide further logs if required.

    Helmut let me know if you want me to provide any more logs/debugs etc

    latest one today

    2012:09:04-18:53:55 ap-10 hostapd: wlan1: STA 70:56:81:c2:3a:19 IEEE 802.1X: Sending EAP Packet (identifier 237)
    2012:09:04-18:53:55 ap-10 hostapd: wlan1: STA 70:56:81:c2:3a:19 WPA: sending 1/4 msg of 4-Way Handshake
    2012:09:04-18:53:55 ap-10 hostapd: wlan1: STA 70:56:81:c2:3a:19 WPA: received EAPOL-Key frame (2/4 Pairwise)
    2012:09:04-18:53:55 ap-10 hostapd: wlan1: STA 70:56:81:c2:3a:19 WPA: sending 3/4 msg of 4-Way Handshake
    2012:09:04-18:53:55 ap-10 hostapd: wlan1: STA 70:56:81:c2:3a:19 WPA: received EAPOL-Key frame (4/4 Pairwise)
    2012:09:04-18:53:55 ap-10 hostapd: wlan1: STA 70:56:81:c2:3a:19 WPA: pairwise key handshake completed (RSN)
    2012:09:04-18:53:55 ap-10 awelogger[1946]: id="4101" severity="info" sys="System" sub="WiFi" name="STA connected" ssid="Stoufospito" ssid_id="WLAN2.0" bssid="00:1a:8c:06:13:51" sta="70:56:81:c2:3a:19"
    2012:09:04-18:53:55 ap-10 hostapd: wlan1: STA 70:56:81:c2:3a:19 IEEE 802.1X: authorizing port
    2012:09:04-18:53:55 ap-10 hostapd: wlan1: STA 70:56:81:c2:3a:19 RADIUS: starting accounting session 5045A524-00000002
    2012:09:04-18:53:55 ap-10 hostapd: wlan1: STA 70:56:81:c2:3a:19 IEEE 802.1X: authenticated - EAP type: 21 ((null))
    2012:09:04-18:53:55 ap-10 hostapd: wlan1: STA 70:56:81:c2:3a:19 WPA: Added PMKSA cache entry (IEEE 802.1X)
    2012:09:04-18:54:55 ap-10 sysinit: alrm_handler(): timeout, reason 1
    2012:09:04-18:54:55 ap-10 init: starting pid 21431, tty '': '/etc/init.d/rcS K shutdown'
    2012:09:04-18:54:55 ap-10 sysinit: interface not found.
    2012:09:04-18:55:29 * awed[20088]: [AP10 A40000D709AC807] error while writing to socket, dropping.
    2012:09:04-18:55:38 * awed[4157]: [MASTER] new connection from 192.168.2.1:45989
    2011:12:31-00:00:38 ap-10 sysinit: Channel 2 busy time 17/230ms, 7%
    2011:12:31-00:00:38 ap-10 sysinit: Channel 3 busy time 15/230ms, 6%
    2011:12:31-00:00:39 ap-10 sysinit: Channel 4 busy time 11/230ms, 4%


    70:56:81:c2:3a:19: is my MBA 2012 (another apple product [:P])

    Thanks
Reply
  • Have you tried the AP re-flashing utility in Support Tools?

    Cheers - Bob

    haven't tried this yet Bob as I know this is a known issue that the dev/qa are working on and I would like to provide further logs if required.

    Helmut let me know if you want me to provide any more logs/debugs etc

    latest one today

    2012:09:04-18:53:55 ap-10 hostapd: wlan1: STA 70:56:81:c2:3a:19 IEEE 802.1X: Sending EAP Packet (identifier 237)
    2012:09:04-18:53:55 ap-10 hostapd: wlan1: STA 70:56:81:c2:3a:19 WPA: sending 1/4 msg of 4-Way Handshake
    2012:09:04-18:53:55 ap-10 hostapd: wlan1: STA 70:56:81:c2:3a:19 WPA: received EAPOL-Key frame (2/4 Pairwise)
    2012:09:04-18:53:55 ap-10 hostapd: wlan1: STA 70:56:81:c2:3a:19 WPA: sending 3/4 msg of 4-Way Handshake
    2012:09:04-18:53:55 ap-10 hostapd: wlan1: STA 70:56:81:c2:3a:19 WPA: received EAPOL-Key frame (4/4 Pairwise)
    2012:09:04-18:53:55 ap-10 hostapd: wlan1: STA 70:56:81:c2:3a:19 WPA: pairwise key handshake completed (RSN)
    2012:09:04-18:53:55 ap-10 awelogger[1946]: id="4101" severity="info" sys="System" sub="WiFi" name="STA connected" ssid="Stoufospito" ssid_id="WLAN2.0" bssid="00:1a:8c:06:13:51" sta="70:56:81:c2:3a:19"
    2012:09:04-18:53:55 ap-10 hostapd: wlan1: STA 70:56:81:c2:3a:19 IEEE 802.1X: authorizing port
    2012:09:04-18:53:55 ap-10 hostapd: wlan1: STA 70:56:81:c2:3a:19 RADIUS: starting accounting session 5045A524-00000002
    2012:09:04-18:53:55 ap-10 hostapd: wlan1: STA 70:56:81:c2:3a:19 IEEE 802.1X: authenticated - EAP type: 21 ((null))
    2012:09:04-18:53:55 ap-10 hostapd: wlan1: STA 70:56:81:c2:3a:19 WPA: Added PMKSA cache entry (IEEE 802.1X)
    2012:09:04-18:54:55 ap-10 sysinit: alrm_handler(): timeout, reason 1
    2012:09:04-18:54:55 ap-10 init: starting pid 21431, tty '': '/etc/init.d/rcS K shutdown'
    2012:09:04-18:54:55 ap-10 sysinit: interface not found.
    2012:09:04-18:55:29 * awed[20088]: [AP10 A40000D709AC807] error while writing to socket, dropping.
    2012:09:04-18:55:38 * awed[4157]: [MASTER] new connection from 192.168.2.1:45989
    2011:12:31-00:00:38 ap-10 sysinit: Channel 2 busy time 17/230ms, 7%
    2011:12:31-00:00:38 ap-10 sysinit: Channel 3 busy time 15/230ms, 6%
    2011:12:31-00:00:39 ap-10 sysinit: Channel 4 busy time 11/230ms, 4%


    70:56:81:c2:3a:19: is my MBA 2012 (another apple product [:P])

    Thanks
Children
  • The "alrm_handler" message in the log indicates that the TCP control connection between ASG and AP died even though the AP can still send loglines to the ASG. So it is not a generic network failure.

    Maybe you could run a tcpdump on the ASG ("tcpdump -i ethX -s0 -w wifi.pcap") and send it to me when the next "alrm_handler" connection failure happens?

    Thanks,
    Helmut