Thread Info
  • State Not Answered
  • Replies 52 replies
  • Subscribers 0 subscribers
  • Views 33426 views
  • Users 0 members are here
Options
Suggested

[8.910][BUG] Wireless in not working after update

wingman
Hi All

It seems that the wireless is not working anymore after the update to client 8.910. Users are not able to connect to either the guest networks or the primary network (different vlan)

Anyone else experiencing the same issues with 8.910?

Thanks 


2012:03:19-21:33:43 ap-10 hostapd: wlan1: RADIUS Received RADIUS message

2012:03:19-21:33:43 ap-10 hostapd: wlan1: STA 00:1d:e0:72:09:a9 RADIUS: Received RADIUS packet matched with a pending request, round trip time 0.00 sec

2012:03:19-21:33:43 ap-10 hostapd: wlan1: STA 00:1d:e0:72:09:a9 IEEE 802.1X: decapsulated EAP packet (code=1 id=204 len=43) from RADIUS server: EAP-Request-(null) (25)

2012:03:19-21:33:43 ap-10 hostapd: wlan1: STA 00:1d:e0:72:09:a9 IEEE 802.1X: Sending EAP Packet (identifier 204)

2012:03:19-21:33:43 ap-10 hostapd: wlan1: STA 00:1d:e0:72:09:a9 IEEE 802.1X: received EAP packet (code=2 id=204 len=43) from STA: EAP Response-(null) (25)

2012:03:19-21:33:43 ap-10 hostapd: wlan1: RADIUS Sending RADIUS message to authentication server

2012:03:19-21:33:43 ap-10 hostapd: wlan1: RADIUS Next RADIUS client retransmit in 3 seconds

2012:03:19-21:33:43 ap-10 hostapd: wlan1: RADIUS Received 133 bytes from RADIUS server

2012:03:19-21:33:43 ap-10 hostapd: wlan1: RADIUS Received RADIUS message

2012:03:19-21:33:43 ap-10 hostapd: wlan1: STA 00:1d:e0:72:09:a9 RADIUS: Received RADIUS packet matched with a pending request, round trip time 0.00 sec

2012:03:19-21:33:43 ap-10 hostapd: wlan1: STA 00:1d:e0:72:09:a9 IEEE 802.1X: decapsulated EAP packet (code=1 id=205 len=75) from RADIUS server: EAP-Request-(null) (25)

2012:03:19-21:33:43 ap-10 hostapd: wlan1: STA 00:1d:e0:72:09:a9 IEEE 802.1X: Sending EAP Packet (identifier 205)

2012:03:19-21:33:44 ap-10 hostapd: wlan1: STA 00:1d:e0:72:09:a9 IEEE 802.1X: received EAP packet (code=2 id=205 len=107) from STA: EAP Response-(null) (25)

2012:03:19-21:33:44 ap-10 hostapd: wlan1: RADIUS Sending RADIUS message to authentication server

2012:03:19-21:33:44 ap-10 hostapd: wlan1: RADIUS Next RADIUS client retransmit in 3 seconds

2012:03:19-21:33:44 ap-10 hostapd: wlan1: RADIUS Received 149 bytes from RADIUS server

2012:03:19-21:33:44 ap-10 hostapd: wlan1: RADIUS Received RADIUS message

2012:03:19-21:33:44 ap-10 hostapd: wlan1: STA 00:1d:e0:72:09:a9 RADIUS: Received RADIUS packet matched with a pending request, round trip time 0.00 sec

2012:03:19-21:33:44 ap-10 hostapd: wlan1: STA 00:1d:e0:72:09:a9 IEEE 802.1X: decapsulated EAP packet (code=1 id=206 len=91) from RADIUS server: EAP-Request-(null) (25)

2012:03:19-21:33:44 ap-10 hostapd: wlan1: STA 00:1d:e0:72:09:a9 IEEE 802.1X: Sending EAP Packet (identifier 206)

2012:03:19-21:33:44 ap-10 hostapd: wlan1: STA 00:1d:e0:72:09:a9 IEEE 802.1X: received EAP packet (code=2 id=206 len=43) from STA: EAP Response-(null) (25)

2012:03:19-21:33:44 ap-10 hostapd: wlan1: RADIUS Sending RADIUS message to authentication server

2012:03:19-21:33:44 ap-10 hostapd: wlan1: RADIUS Next RADIUS client retransmit in 3 seconds

2012:03:19-21:33:44 ap-10 hostapd: wlan1: RADIUS Received 101 bytes from RADIUS server

2012:03:19-21:33:44 ap-10 hostapd: wlan1: RADIUS Received RADIUS message

2012:03:19-21:33:44 ap-10 hostapd: wlan1: STA 00:1d:e0:72:09:a9 RADIUS: Received RADIUS packet matched with a pending request, round trip time 0.00 sec

2012:03:19-21:33:44 ap-10 hostapd: wlan1: STA 00:1d:e0:72:09:a9 IEEE 802.1X: decapsulated EAP packet (code=1 id=207 len=43) from RADIUS server: EAP-Request-(null) (25)

2012:03:19-21:33:44 ap-10 hostapd: wlan1: STA 00:1d:e0:72:09:a9 IEEE 802.1X: Sending EAP Packet (identifier 207)

2012:03:19-21:33:44 ap-10 hostapd: wlan1: STA 00:1d:e0:72:09:a9 IEEE 802.1X: received EAP packet (code=2 id=207 len=43) from STA: EAP Response-(null) (25)

2012:03:19-21:33:44 ap-10 hostapd: wlan1: RADIUS Sending RADIUS message to authentication server

2012:03:19-21:33:44 ap-10 hostapd: wlan1: RADIUS Next RADIUS client retransmit in 3 seconds

2012:03:19-21:33:44 ap-10 hostapd: wlan1: RADIUS Received 170 bytes from RADIUS server

2012:03:19-21:33:44 ap-10 hostapd: wlan1: RADIUS Received RADIUS message

2012:03:19-21:33:44 ap-10 hostapd: wlan1: STA 00:1d:e0:72:09:a9 RADIUS: Received RADIUS packet matched with a pending request, round trip time 0.00 sec

2012:03:19-21:33:44 ap-10 hostapd: wlan1: STA 00:1d:e0:72:09:a9 IEEE 802.1X: old identity 'geostuff' updated with User-Name from Access-Accept 'geostuff'

2012:03:19-21:33:44 ap-10 hostapd: wlan1: STA 00:1d:e0:72:09:a9 IEEE 802.1X: decapsulated EAP packet (code=3 id=207 len=4) from RADIUS server: EAP Success

2012:03:19-21:33:44 ap-10 hostapd: wlan1: STA 00:1d:e0:72:09:a9 IEEE 802.1X: Sending EAP Packet (identifier 207)

2012:03:19-21:33:44 ap-10 hostapd: wlan1: STA 00:1d:e0:72:09:a9 WPA: sending 1/4 msg of 4-Way Handshake

2012:03:19-21:33:44 ap-10 hostapd: wlan1: STA 00:1d:e0:72:09:a9 WPA: received EAPOL-Key frame (2/4 Pairwise)

2012:03:19-21:33:44 ap-10 hostapd: wlan1: STA 00:1d:e0:72:09:a9 WPA: sending 3/4 msg of 4-Way Handshake

2012:03:19-21:33:44 ap-10 hostapd: wlan1: STA 00:1d:e0:72:09:a9 WPA: received EAPOL-Key frame (4/4 Pairwise)

2012:03:19-21:33:44 ap-10 hostapd: wlan1: STA 00:1d:e0:72:09:a9 WPA: pairwise key handshake completed (RSN)

2012:03:19-21:33:44 ap-10 awelogger[3087]: 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="00:1d:e0:72:09:a9"

2012:03:19-21:33:44 ap-10 hostapd: wlan1: STA 00:1d:e0:72:09:a9 IEEE 802.1X: authorizing port

2012:03:19-21:33:44 ap-10 hostapd: wlan1: STA 00:1d:e0:72:09:a9 RADIUS: starting accounting session 4F67A5F0-00000000

2012:03:19-21:33:44 ap-10 hostapd: wlan1: STA 00:1d:e0:72:09:a9 IEEE 802.1X: authenticated - EAP type: 25 ((null))

2012:03:19-21:33:44 ap-10 hostapd: wlan1: STA 00:1d:e0:72:09:a9 WPA: Added PMKSA cache entry (IEEE 802.1X)

2012:03:19-21:34:23 ap-10 awelogger[3081]: id="4102" severity="info" sys="System" sub="WiFi" name="STA disconnected" ssid="StoufoGuest" ssid_id="WLAN1.0" bssid="00:1a:8c:06:13:50" sta="68:a8:6d:cd:2e:07"

2012:03:19-21:34:23 ap-10 hostapd: wlan0: STA 68:a8:6d:cd:2e:07 IEEE 802.1X: unauthorizing port

2012:03:19-21:34:23 ap-10 hostapd: wlan0: STA 68:a8:6d:cd:2e:07 IEEE 802.11: disassociated

2012:03:19-21:34:23 ap-10 hostapd: wlan0: STA 68:a8:6d:cd:2e:07 MLME: MLME-DISASSOCIATE.indication(68:a8:6d:cd:2e:07, 8)

2012:03:19-21:34:23 ap-10 hostapd: wlan0: STA 68:a8:6d:cd:2e:07 MLME: MLME-DELETEKEYS.request(68:a8:6d:cd:2e:07)

2012:03:19-21:34:24 ap-10 hostapd: wlan0: STA 68:a8:6d:cd:2e:07 IEEE 802.11: deauthenticated due to inactivity

2012:03:19-21:34:24 ap-10 hostapd: wlan0: STA 68:a8:6d:cd:2e:07 MLME: MLME-DEAUTHENTICATE.indication(68:a8:6d:cd:2e:07, 2)

2012:03:19-21:34:24 ap-10 hostapd: wlan0: STA 68:a8:6d:cd:2e:07 MLME: MLME-DELETEKEYS.request(68:a8:6d:cd:2e:07)

2012:03:19-21:34:24 ap-10 awelogger[3081]: id="4102" severity="info" sys="System" sub="WiFi" name="STA disconnected" ssid="StoufoGuest" ssid_id="WLAN1.0" bssid="00:1a:8c:06:13:50" sta="68:a8:6d:cd:2e:07"

2012:03:19-21:34:25 ap-10 hostapd: wlan0: STA 68:a8:6d:cd:2e:07 IEEE 802.11: authentication OK (open system)

2012:03:19-21:34:25 ap-10 hostapd: wlan0: STA 68:a8:6d:cd:2e:07 MLME: MLME-AUTHENTICATE.indication(68:a8:6d:cd:2e:07, OPEN_SYSTEM)

2012:03:19-21:34:25 ap-10 hostapd: wlan0: STA 68:a8:6d:cd:2e:07 MLME: MLME-DELETEKEYS.request(68:a8:6d:cd:2e:07)

2012:03:19-21:34:25 ap-10 awelogger[3081]: id="4103" severity="info" sys="System" sub="WiFi" name="STA authentication" ssid="StoufoGuest" ssid_id="WLAN1.0" bssid="00:1a:8c:06:13:50" sta="68:a8:6d:cd:2e:07" status_code="0"

2012:03:19-21:34:25 ap-10 hostapd: wlan0: STA 68:a8:6d:cd:2e:07 IEEE 802.11: authenticated

2012:03:19-21:34:25 ap-10 hostapd: wlan0: STA 68:a8:6d:cd:2e:07 IEEE 802.11: association OK (aid 1)

2012:03:19-21:34:25 ap-10 awelogger[3081]: id="4104" severity="info" sys="System" sub="WiFi" name="STA association" ssid="StoufoGuest" ssid_id="WLAN1.0" bssid="00:1a:8c:06:13:50" sta="68:a8:6d:cd:2e:07" status_code="0"

2012:03:19-21:34:25 ap-10 hostapd: wlan0: STA 68:a8:6d:cd:2e:07 IEEE 802.11: associated (aid 1)

2012:03:19-21:34:25 ap-10 hostapd: wlan0: STA 68:a8:6d:cd:2e:07 MLME: MLME-ASSOCIATE.indication(68:a8:6d:cd:2e:07)

2012:03:19-21:34:25 ap-10 hostapd: wlan0: STA 68:a8:6d:cd:2e:07 MLME: MLME-DELETEKEYS.request(68:a8:6d:cd:2e:07)

2012:03:19-21:34:25 ap-10 hostapd: wlan0: STA 68:a8:6d:cd:2e:07 WPA: event 1 notification

2012:03:19-21:34:25 ap-10 hostapd: wlan0: STA 68:a8:6d:cd:2e:07 WPA: start authentication

2012:03:19-21:34:25 ap-10 hostapd: wlan0: STA 68:a8:6d:cd:2e:07 IEEE 802.1X: unauthorizing port

2012:03:19-21:34:25 ap-10 hostapd: wlan0: STA 68:a8:6d:cd:2e:07 WPA: sending 1/4 msg of 4-Way Handshake

2012:03:19-21:34:25 ap-10 hostapd: wlan0: STA 68:a8:6d:cd:2e:07 WPA: received EAPOL-Key frame (2/4 Pairwise)

2012:03:19-21:34:25 ap-10 hostapd: wlan0: STA 68:a8:6d:cd:2e:07 WPA: sending 3/4 msg of 4-Way Handshake

2012:03:19-21:34:25 ap-10 hostapd: wlan0: STA 68:a8:6d:cd:2e:07 WPA: received EAPOL-Key frame (4/4 Pairwise)

2012:03:19-21:34:25 ap-10 awelogger[3081]: id="4101" severity="info" sys="System" sub="WiFi" name="STA connected" ssid="StoufoGuest" ssid_id="WLAN1.0" bssid="00:1a:8c:06:13:50" sta="68:a8:6d:cd:2e:07"

2012:03:19-21:34:25 ap-10 hostapd: wlan0: STA 68:a8:6d:cd:2e:07 IEEE 802.1X: authorizing port

2012:03:19-21:34:25 ap-10 hostapd: wlan0: STA 68:a8:6d:cd:2e:07 RADIUS: starting accounting session 4F67A5F0-00000002

2012:03:19-21:34:25 ap-10 hostapd: wlan0: STA 68:a8:6d:cd:2e:07 WPA: pairwise key handshake completed (RSN) 
  • 0
    Unfortunately, now it can't even detect the AP 10. I have deleted the existing AP  from the UTM and rebooted the AP

    I've powered it off and during power on I was pressing the reset button for 1 minute. I can't see anything on the wireless logs as well. The AP is flashing like it should be...but the UTM is not able to find the new AP

    I have tried to also disable the service via UTM and re enable it in a n attempt to force the UTM to find the AP but no luck either [:(]

    Is there anything else  I can do or should I flash it with the tool?
  • 0
    I've tried to enabled the process with debug flag but the only logs on the wireless.log are:

    commands I've used:
    /var/mdw/scripts/awed stop
    awed.plx --debug

    2012:03:20-09:05:28 ***** awed[14388]: [MASTER] SIGTERM received, sending SIGTERM to siblings, exiting
    2012:03:20-09:05:38 ***** awed[18181]: [MASTER] awed_ng starting
    2012:03:20-09:05:38 ***** awed[18181]: [MASTER] access point firmware available: AP10:3017 AP50:3017 AP5:1 AP30:3017


    Also it seems that the AP is requesting DHCP address all the time
    2012:03:20-09:13:24 ***** dhcpd: DHCPOFFER on 192.168.2.1 to 00:1a:8c:06:13:58 via eth1.10
    2012:03:20-09:13:27 ***** dhcpd: DHCPDISCOVER from 00:1a:8c:06:13:58 via eth1.10
    2012:03:20-09:13:27 ***** dhcpd: DHCPOFFER on 192.168.2.1 to 00:1a:8c:06:13:58 via eth1.10
    2012:03:20-09:13:30 ***** dhcpd: DHCPDISCOVER from 00:1a:8c:06:13:58 via eth1.10
    2012:03:20-09:13:30 ***** dhcpd: DHCPOFFER on 192.168.2.1 to 00:1a:8c:06:13:58 via eth1.10
    2012:03:20-09:13:33 ***** dhcpd: DHCPDISCOVER from 00:1a:8c:06:13:58 via eth1.10
    2012:03:20-09:13:33 ***** dhcpd: DHCPOFFER on 192.168.2.1 to 00:1a:8c:06:13:58 via eth1.10
    2012:03:20-09:13:36 ***** dhcpd: DHCPDISCOVER from 00:1a:8c:06:13:58 via eth1.10
    2012:03:20-09:13:36 ***** dhcpd: DHCPOFFER on 192.168.2.1 to 00:1a:8c:06:13:58 via eth1.10
    2012:03:20-09:13:39 ***** dhcpd: DHCPDISCOVER from 00:1a:8c:06:13:58 via eth1.10
    2012:03:20-09:13:39 ***** dhcpd: DHCPOFFER on 192.168.2.1 to 00:1a:8c:06:13:58 via eth1.10
    2012:03:20-09:13:42 ***** dhcpd: DHCPDISCOVER from 00:1a:8c:06:13:58 via eth1.10
    2012:03:20-09:13:42 ***** dhcpd: DHCPOFFER on 192.168.2.1 to 00:1a:8c:06:13:58 via eth1.10
    2012:03:20-09:13:45 ***** dhcpd: DHCPDISCOVER from 00:1a:8c:06:13:58 via eth1.10
    2012:03:20-09:13:45 ***** dhcpd: DHCPOFFER on 192.168.2.1 to 00:1a:8c:06:13:58 via eth1.10
    2012:03:20-09:13:48 ***** dhcpd: DHCPDISCOVER from 00:1a:8c:06:13:58 via eth1.10
    2012:03:20-09:13:48 ***** dhcpd: DHCPOFFER on 192.168.2.1 to 00:1a:8c:06:13:58 via eth1.10
    2012:03:20-09:13:51 ***** dhcpd: DHCPDISCOVER from 00:1a:8c:06:13:58 via eth1.10
    2012:03:20-09:13:51 ***** dhcpd: DHCPOFFER on 192.168.2.1 to 00:1a:8c:06:13:58 via eth1.10
    2012:03:20-09:13:54 ***** dhcpd: DHCPDISCOVER from 00:1a:8c:06:13:58 via eth1.10
    2012:03:20-09:13:54 ***** dhcpd: DHCPOFFER on 192.168.2.1 to 00:1a:8c:06:13:58 via eth1.10
    2012:03:20-09:13:57 ***** dhcpd: DHCPDISCOVER from 00:1a:8c:06:13:58 via eth1.10
    2012:03:20-09:13:57 ***** dhcpd: DHCPOFFER on 192.168.2.1 to 00:1a:8c:06:13:58 via eth1.10
    2012:03:20-09:14:00 ***** dhcpd: DHCPDISCOVER from 00:1a:8c:06:13:58 via eth1.10
    2012:03:20-09:14:00 ***** dhcpd: DHCPOFFER on 192.168.2.1 to 00:1a:8c:06:13:58 via eth1.10
    2012:03:20-09:14:03 ***** dhcpd: DHCPDISCOVER from 00:1a:8c:06:13:58 via eth1.10
    2012:03:20-09:14:03 ***** dhcpd: DHCPOFFER on 192.168.2.1 to 00:1a:8c:06:13:58 via eth1.10
    2012:03:20-09:14:06 ***** dhcpd: DHCPDISCOVER from 00:1a:8c:06:13:58 via eth1.10
    2012:03:20-09:14:06 ***** dhcpd: DHCPOFFER on 192.168.2.1 to 00:1a:8c:06:13:58 via eth1.10
    2012:03:20-09:14:09 ***** dhcpd: DHCPDISCOVER from 00:1a:8c:06:13:58 via eth1.10
    2012:03:20-09:14:09 ***** dhcpd: DHCPOFFER on 192.168.2.1 to 00:1a:8c:06:13:58 via eth1.10


    If I restart the DHCP (AP is a static mapping) the only entry that comes up constantly is the AP one

    vlan 10 is the private lan (user vlan)

    Still nothing on the UTM and the AP is not appearing as new AP
  • 0
    Also every time I try to restart or stop and then start  the service I am getting

    [coded]I check Failed increment awed_running counter 1 - 3[/code]

    The wireless log isn't very useful as it seems something is blocking the connection between AP and UTM 

    2012:03:20-11:53:16 ***** awed[31365]: [MASTER] SIGTERM received, sending SIGTERM to siblings, exiting
    2012:03:20-11:53:17 ***** awed[31983]: [MASTER] awed_ng starting
    2012:03:20-11:53:17 ***** awed[31983]: [MASTER] access point firmware available: AP10:3017 AP50:3017 AP5:1 AP30:3017
    2012:03:20-11:55:00 ***** awed[31983]: [MASTER] SIGTERM received, sending SIGTERM to siblings, exiting
    2012:03:20-11:55:10 ***** awed[32372]: [MASTER] awed_ng starting
    2012:03:20-11:55:10 ***** awed[32372]: [MASTER] access point firmware available: AP10:3017 AP50:3017 AP5:1 AP30:3017
    2012:03:20-11:55:27 ***** awed[32372]: [MASTER] SIGTERM received, sending SIGTERM to siblings, exiting
    2012:03:20-11:55:36 ***** awed[32441]: [MASTER] awed_ng starting
    2012:03:20-11:55:36 ***** awed[32441]: [MASTER] access point firmware available: AP10:3017 AP50:3017 AP5:1 AP30:3017


    I will have a go with wireshark and post back
  • 0 in reply to wingman
    hi Wingman ,
    how r u .

    for me i installed ap10 today only last beta  had problem with vlan ,i am using vlan and hotspot (it work without hotspot also )


    but this time it work me at 1nd attempt 
    and i know you have know better then me 

    if you want to access my asg pls send me pm 

    thx
  • 0
    Hi utm_kid  

    I hope you are well. The AP is no showing as pending as there is an "communication" issue between AP (192.168.2.1) and the UTM (192.168.2.100)

    I have used tcpdump whilst restarting the Wireless service and the only communication was regarding DHCP (attached). If the AP is not getting the IP then I wouldn';t expect to see traffic for 1.2.3.4.
     
    It's like AP is not responding 

    (the way it should work is :When the AP boots up, it grabs an IP from DHCP, and then looks for the "magic" IP of 1.2.3.4, which is the IP for the Wifi controller.)
    DHCP offer from AP-ASG.zip
  • 0 in reply to wingman
    Hi,

    the problems which you actually have is exclusive of vlantagging.
    In the past vlantagging was a global wifi option. In this beta we moved 
    vlantagging from being a global option to become an option per AP.
    So now you can choose which AP shall use vlantagging and which shouldn't.
    However, in this moving I produced a bug, which leads to a wrong vlantag, used
    by the SSIDs which bridge to VLAN.
    I append a *.rpm which should fix the problem right now.
    Before installing this *.rpm, you should stop awed by "/var/mdw/scripts/awed stop"
    and after installation you should start it again.
    This is tracked in Mantis ID 20790, and will be fixed in a proper way for the next beta
    release.

    Regards,
    Emanuel
    ep-awed-8.90-21.gba0c770.i686.rpm
  • 0
    Thanks Emanuel

    could you please let me know how to install the RPM?
  • 0
    Hi wingman,

    running "awed.plx --debug" doesn't throw an error? In the current beta there shouldn't be such a file. Instead you should try "awed_ng.plx --debug --daemon".

    Regards,
    Emanuel
  • 0
    Hi,
    to install the rpm you have to log in via ssh to your asg and copy this rpm via scp. 
    Then run "rpm -U ep-awed-8..." as root. If it doesn't work you have to add the option 
    "--oldpackage".

    But your second problem, that AP10 didn't connect to the awed is another issue,
    which won't be fixed by this *.rpm.

    Maybe the output of "ps aux | grep awed" may give me a hint, if you would be so 
    kind and post it here.

    Regards,
    Emanuel
  • 0 in reply to etaube
    Hi wingman,

    running "awed.plx --debug" doesn't throw an error? In the current beta there shouldn't be such a file. Instead you should try "awed_ng.plx --debug --daemon".

    Regards,
    Emanuel
     

    yeap you are right. I googled for the answer and forgot to added it [:)]
Unfiltered HTML