hostapd + madwifi Failed to get WPA/RSN IE

Mon Dec 21 21:55:53 EST 2009

Hi!

MotherBoard:IXP425
kernel:2.6.30.5
hostapd:hostapd-0.6.9
madwifi:madwifi-0.9.4-r4100-20090929
Wireless Card: Atheros AR5212 CM9-GP on mini-PCI

Attempting to connect from a PC using wireless  to an Access Point running
hostapd and madwifi with WPA with Pre-shared key (PSK) on IXP425 board.
Hostapd (with -dd) emits following message on the access point: (see
below).  Also, the ath0 interface is included in a bridge br0 on the
access point.

#dmesg
......
<6>ath_hal: 0.9.18.0 (AR5210, AR5211, AR5212, RF5111, RF5112, RF2413,
RF5413, REGOPS_FUNC)
<6>wlan: 0.9.4.1
<6>ath_rate_amrr: 0.1 (0.9.4.1)
<6>ath_rate_onoe: 1.0 (0.9.4.1)
<6>wlan: mac acl policy registered
<6>ath_rate_sample: 1.2 (0.9.4.1)
<6>ath_pci: 0.9.4.1
<4>PCI: enabling device 0000:00:01.0 (0340 -> 0342)
<4>wifi0: 11a rates: 6Mbps 9Mbps 12Mbps 18Mbps 24Mbps 36Mbps 48Mbps 54Mbps
<4>wifi0: 11b rates: 1Mbps 2Mbps 5.5Mbps 11Mbps
<4>wifi0: 11g rates: 1Mbps 2Mbps 5.5Mbps 11Mbps 6Mbps 9Mbps 12Mbps
18Mbps 24Mbps 36Mbps 48Mbps 54Mbps
<4>wifi0: turboA rates: 6Mbps 9Mbps 12Mbps 18Mbps 24Mbps 36Mbps 48Mbps 54Mbps
<4>wifi0: turboG rates: 6Mbps 12Mbps 18Mbps 24Mbps 36Mbps 48Mbps 54Mbps
<4>wifi0: H/W encryption support: WEP AES AES_CCM TKIP
<4>wifi0: mac 5.9 phy 4.3 radio 3.6
<4>wifi0: Use hw queue 1 for WME_AC_BE traffic
<4>wifi0: Use hw queue 0 for WME_AC_BK traffic
<4>wifi0: Use hw queue 2 for WME_AC_VI traffic
<4>wifi0: Use hw queue 3 for WME_AC_VO traffic
<4>wifi0: Use hw queue 8 for CAB traffic
<4>wifi0: Use hw queue 9 for beacons
<6>wifi0: Atheros 5212: mem=0x48000000, irq=28
<6>ath_rate_minstrel: Minstrel automatic rate control algorithm 1.2 (0.9.4.1)
<6>ath_rate_minstrel: Look around rate set to 10%
<6>ath_rate_minstrel: EWMA rolloff level set to 75%
<6>ath_rate_minstrel: Max Segment size in the mrr set to 6000 us
<6>device eth0 entered promiscuous mode
<6>device ath0 entered promiscuous mode
......

# cat /etc/hostapd.conf
bridge=br0
interface=ath0
driver=madwifi
logger_syslog=-1
logger_syslog_level=2
logger_stdout=-1
logger_stdout_level=1
debug=0
dump_file=/tmp/hostapd.dump
ctrl_interface=/var/run/hostapd
ctrl_interface_group=0
ssid=hello
auth_algs=1
wpa=1
wpa_passphrase=hellotestmywpa
wpa_key_mgmt=WPA-PSK
wpa_pairwise=TKIP CCMP

# hostapd -dd /etc/hostapd.conf
=================================================
Configuration file: /etc/hostapd.conf
Line 8: DEPRECATED: 'debug' configuration variable is not used anymore
ctrl_interface_group=0
br0: port 2(ath0) entering disabled state
Configure bridge br0 for EAPOL traffic.
madwifi_set_iface_flags: dev_up=0
madwifi_set_privacy: enabled=0
BSS count 1, BSSID mask ff:ff:ff:ff:ff:ff (0 bits)
SIOCGIWRANGE: WE(compiled)=22 WE(source)=13 enc_capa=0xf
ath0: IEEE 802.11 Fetching hardware channel/rate support not supported.
Flushing old station entries
madwifi_sta_deauth: addr=ff:ff:ff:ff:ff:ff reason_code=3
ioctl[IEEE80211_IOCTL_SETMLME]: Argument list too long
madwifi_sta_deauth: Failed to deauth STA (addr ff:ff:ff:ff:ff:ff reason 3)
Could not connect to kernel driver.
Deauthenticate all stations
madwifi_sta_deauth: addr=ff:ff:ff:ff:ff:ff reason_code=2
ioctl[IEEE80211_IOCTL_SETMLME]: Argument list too long
madwifi_sta_deauth: Failed to deauth STA (addr ff:ff:ff:ff:ff:ff reason 2)
madwifi_set_privacy: enabled=0
madwifi_del_key: addr=00:00:00:00:00:00 key_idx=0
madwifi_del_key: addr=00:00:00:00:00:00 key_idx=1
madwifi_del_key: addr=00:00:00:00:00:00 key_idx=2
madwifi_del_key: addr=00:00:00:00:00:00 key_idx=3
Using interface ath0 with hwaddr 00:0b:6b:0a:4f:e5 and ssid 'hello'
SSID - hexdump_ascii(len=5):
     68 65 6c 6c 6f                                    hello
PSK (ASCII passphrase) - hexdump_ascii(len=14):
     68 65 6c 6c 6f 74 65 73 74 6d 79 77 70 61         hellotestmywpa
PSK (from passphrase) - hexdump(len=32): 12 d2 62 62 3b 1f ba e1 d6 bb
72 c3 e4 f5 a1 f0 cb 13 af c9 7d 0d 24 3b 67 1b d5 76 c9 br0: port
2(ath0) ee
4e 53 33
madwifi_set_ieee8021x: enabled=1
madwifi_configure_wpa: group key cipher=1
madwifi_configure_wpa: pairwise key ciphers=0xa
madwifi_configure_wpa: key management algorithms=0x2
madwifi_configure_wpa: rsn capabilities=0x0
madwifi_configure_wpa: enable WPA=0x1
WPA: group state machine entering state GTK_INIT (VLAN-ID 0)
GMK - hexdump(len=32): [REMOVED]
GTK - hexdump(len=32): [REMOVED]
WPA: group state machine entering state SETKEYSDONE (VLAN-ID 0)
madwifi_set_key: alg=TKIP addr=00:00:00:00:00:00 key_idx=1
madwifi_set_privacy: enabled=1
madwifi_set_iface_flags: dev_up=1
ath0: Setup of interface done.
l2_packet_receive - recvfrom: Network is down
Wireless event: cmd=0x8c03 len=20
ath0: STA 00:92:c3:80:05:7d IEEE 802.11: associated
  New STA
ioctl[unknown???]: Invalid argument
madwifi_process_wpa_ie: Failed to get WPA/RSN IE
Failed to get WPA/RSN information element.
Wireless event: cmd=0x8c03 len=20
ath0: STA 00:92:c3:80:05:88 IEEE 802.11: associated
  New STA
ioctl[unknown???]: Invalid argument
madwifi_process_wpa_ie: Failed to get WPA/RSN IE
Failed to get WPA/RSN information element.
Wireless event: cmd=0x8c03 len=20
ath0: STA 00:92:c3:80:05:88 IEEE 802.11: associated
ioctl[unknown???]: Invalid argument
madwifi_process_wpa_ie: Failed to get WPA/RSN IE
Failed to get WPA/RSN information element.
Wireless event: cmd=0x8c03 len=20
ath0: STA 00:92:c3:80:05:7d IEEE 802.11: associated
ioctl[unknown???]: Invalid argument
madwifi_process_wpa_ie: Failed to get WPA/RSN IE
Failed to get WPA/RSN information element.
Wireless event: cmd=0x8c02 len=95
Custom wireless event: 'STA-TRAFFIC-STAT
mac=00:92:c3:80:05:88
rx_packets=0
rx_bytes=0
tx_packets=0
tx_bytes=0
'
Wireless event: cmd=0x8c04 len=20
ath0: STA 00:92:c3:80:05:88 IEEE 802.11: disassociated
Wireless event: cmd=0x8c03 len=20
ath0: STA 00:92:c3:80:05:88 IEEE 802.11: associated
  New STA
ioctl[unknown???]: Invalid argument
madwifi_process_wpa_ie: Failed to get WPA/RSN IE
Failed to get WPA/RSN information element.
Wireless event: cmd=0x8c02 len=95
Custom wireless event: 'STA-TRAFFIC-STAT
mac=00:92:c3:80:05:7d
rx_packets=0
rx_bytes=0
tx_packets=0
tx_bytes=0
'
Wireless event: cmd=0x8c04 len=20
ath0: STA 00:92:c3:80:05:7d IEEE 802.11: disassociated
Wireless event: cmd=0x8c03 len=20
ath0: STA 00:92:c3:80:05:7d IEEE 802.11: associated
  New STA
ioctl[unknown???]: Invalid argument
madwifi_process_wpa_ie: Failed to get WPA/RSN IE      //here failed to
get WPA/RSN IE and return
Failed to get WPA/RSN information element.

^CSignal 2 received - terminating           //I can't connect to the
Ap from PC, so abort it using CTRL+C

Removing station 00:92:c3:80:05:7d
Removing station 00:92:c3:80:05:88
Flushing old station entries
madwifi_sta_deauth: addr=ff:ff:ff:ff:ff:ff reason_code=3
br0: port 2(ath0) entering disabled state
ioctl[IEEE80211_IOCTL_SETMLME]: Argument list too long
madwifi_sta_deauth: Failed to deauth STA (addr ff:ff:ff:ff:ff:ff reason 3)
Could not connect to kernel driver.
Deauthenticate all stations
madwifi_sta_deauth: addr=ff:ff:ff:ff:ff:ff reason_code=2
ioctl[IEEE80211_IOCTL_SETMLME]: Argument list too long
madwifi_sta_deauth: Failed to deauth STA (addr ff:ff:ff:ff:ff:ff reason 2)
madwifi_set_privacy: enabled=0
madwifi_set_ieee8021x: enabled=0
madwifi_set_iface_flags: dev_up=0

=============================================================
We can see error is comming from ioctl call in driver_madwifi::set80211priv for
op-code IEEE80211_IOCTL_GETWPAIE
This is defined in madwifi code /net80211/ieee80211_ioctl.h as
SIOCDEVPRIVATE+4.  SIOCDEVPRIVATE is defined in linux/include/sockios.h
as 0x89F0.

Who can help me with this problem ?
Thank you!
-------------- next part --------------
An HTML attachment was scrubbed...
URL: http://lists.shmoo.com/pipermail/hostap/attachments/20091222/7a2c49a1/attachment.htm 