The error doesn't show every time. The user can connect to wifi network, but doesn't get the ip address from DHCP server.
Tcpdump -i eth0 can't find any vlan40 packet.
The vlan40 doesn't have any traffic:
cat /proc/net/vlan/*
vlan40 VID: 40 REORDER_HDR: 1 dev->priv_flags: 1
total frames received 0
total bytes received 0
Broadcast/Multicast Rcvd 0
total frames transmitted 0
total bytes transmitted 0
total headroom inc 0
total encap on xmit 0
Device: eth0
INGRESS priority mappings: 0:0 1:0 2:0 3:0 4:0 5:0 6:0 7:0
EGRESS priority mappings:
I can't find what could be wrong. Can you check the new log?
wlan0: RADIUS Received 149 bytes from RADIUS server
wlan0: RADIUS Received RADIUS message
RADIUS message: code=11 (Access-Challenge) identifier=21 length=149
Attribute 79 (EAP-Message) length=93
Value: 01 ee 00 5b 19 00 17 03 01 00 50 c3 98 fd 4f c9 e7 61 23 84 4c 37 b3 ae 60 f9 db 9f e2 ac 72 15 02 f2 30 25 b0 18 d9 0a c1 aa cc 49 72 b0 dc 6e 47 f5 f1 a9 63 95 0f 90 21 24 a0 cf 26 04 a5 ca fb f2 19 ae 16 08 7a e2 55 8a cb c4 23 3d 01 3b 76 d4 f2 cc 55 37 ff 26 ef a1 55
Attribute 80 (Message-Authenticator) length=18
Value: b4 96 4b c8 5c ab 6c 84 49 50 a3 07 c2 db c7 0b
Attribute 24 (State) length=18
Value: 59 93 66 27 5c 7d 7f 2e 4f 1e 25 50 e4 5b 7b b5
wlan0: STA e0:b9:a5:00:52:cb RADIUS: Received RADIUS packet matched with a pending request, round trip time 0.00 sec
RADIUS packet matching with station e0:b9:a5:00:52:cb
wlan0: STA e0:b9:a5:00:52:cb IEEE 802.1X: decapsulated EAP packet (code=1 id=238 len=91) from RADIUS server: EAP-Request-PEAP (25)
EAP: EAP entering state AAA_RESPONSE
EAP: getId: id=238
EAP: EAP entering state SEND_REQUEST2
EAP: EAP entering state IDLE2
EAP: retransmit timeout 3 seconds (from dynamic back off; retransCount=0)
IEEE 802.1X: e0:b9:a5:00:52:cb BE_AUTH entering state REQUEST
wlan0: STA e0:b9:a5:00:52:cb IEEE 802.1X: Sending EAP Packet (identifier 238)
IEEE 802.1X: e0:b9:a5:00:52:cb TX status - version=2 type=0 length=91 - ack=1
IEEE 802.1X: 47 bytes from e0:b9:a5:00:52:cb
IEEE 802.1X: version=1 type=0 length=43
EAP: code=2 identifier=238 length=43
(response)
wlan0: STA e0:b9:a5:00:52:cb IEEE 802.1X: received EAP packet (code=2 id=238 len=43) from STA: EAP Response-PEAP (25)
IEEE 802.1X: e0:b9:a5:00:52:cb BE_AUTH entering state RESPONSE
EAP: EAP entering state RECEIVED2
EAP: parseEapResp: rxResp=1 respId=238 respMethod=25 respVendor=0 respVendorMethod=0
EAP: EAP entering state AAA_REQUEST
EAP: EAP entering state AAA_IDLE
Encapsulating EAP message into a RADIUS packet
Copied RADIUS State Attribute
wlan0: RADIUS Sending RADIUS message to authentication server
RADIUS message: code=1 (Access-Request) identifier=22 length=209
Attribute 1 (User-Name) length=21
Value: 'leszek.niewiadomski'
Attribute 32 (NAS-Identifier) length=2
Value: ''
Attribute 5 (NAS-Port) length=6
Value: 1
Attribute 30 (Called-Station-Id) length=24
Value: '54-E6-FC-DC-C8-2A:Havo'
Attribute 31 (Calling-Station-Id) length=19
Value: 'E0-B9-A5-00-52-CB'
Attribute 12 (Framed-MTU) length=6
Value: 1400
Attribute 61 (NAS-Port-Type) length=6
Value: 19
Attribute 77 (Connect-Info) length=24
Value: 'CONNECT 54Mbps 802.11g'
Attribute 79 (EAP-Message) length=45
Value: 02 ee 00 2b 19 00 17 03 01 00 20 07 93 66 3a be 7f 94 ac 69 fa f1 98 0d 59 e2 2b 0d 47 30 ff 49 b9 d4 e0 b8 3a f4 8d e1 97 8c 70
Attribute 24 (State) length=18
Value: 59 93 66 27 5c 7d 7f 2e 4f 1e 25 50 e4 5b 7b b5
Attribute 80 (Message-Authenticator) length=18
Value: f4 af f3 66 f6 a7 94 2c c3 1c 4a 57 83 ce 2b e8
wlan0: RADIUS Next RADIUS client retransmit in 3 seconds
wlan0: RADIUS Received 101 bytes from RADIUS server
wlan0: RADIUS Received RADIUS message
RADIUS message: code=11 (Access-Challenge) identifier=22 length=101
Attribute 79 (EAP-Message) length=45
Value: 01 ef 00 2b 19 00 17 03 01 00 20 f9 75 07 bb 64 77 c2 d3 39 36 2e 05 0a eb 7c 7a 0f 8c 53 21 0b 98 97 41 89 07 0f bc 5b e1 99 d4
Attribute 80 (Message-Authenticator) length=18
Value: 63 9b 86 cf b1 8e 2a 74 ea db 36 de 7f 30 86 c5
Attribute 24 (State) length=18
Value: 59 93 66 27 5f 7c 7f 2e 4f 1e 25 50 e4 5b 7b b5
wlan0: STA e0:b9:a5:00:52:cb RADIUS: Received RADIUS packet matched with a pending request, round trip time 0.00 sec
RADIUS packet matching with station e0:b9:a5:00:52:cb
wlan0: STA e0:b9:a5:00:52:cb IEEE 802.1X: decapsulated EAP packet (code=1 id=239 len=43) from RADIUS server: EAP-Request-PEAP (25)
EAP: EAP entering state AAA_RESPONSE
EAP: getId: id=239
EAP: EAP entering state SEND_REQUEST2
EAP: EAP entering state IDLE2
EAP: retransmit timeout 3 seconds (from dynamic back off; retransCount=0)
IEEE 802.1X: e0:b9:a5:00:52:cb BE_AUTH entering state REQUEST
wlan0: STA e0:b9:a5:00:52:cb IEEE 802.1X: Sending EAP Packet (identifier 239)
IEEE 802.1X: e0:b9:a5:00:52:cb TX status - version=2 type=0 length=43 - ack=1
IEEE 802.1X: 47 bytes from e0:b9:a5:00:52:cb
IEEE 802.1X: version=1 type=0 length=43
EAP: code=2 identifier=239 length=43
(response)
wlan0: STA e0:b9:a5:00:52:cb IEEE 802.1X: received EAP packet (code=2 id=239 len=43) from STA: EAP Response-PEAP (25)
IEEE 802.1X: e0:b9:a5:00:52:cb BE_AUTH entering state RESPONSE
EAP: EAP entering state RECEIVED2
EAP: parseEapResp: rxResp=1 respId=239 respMethod=25 respVendor=0 respVendorMethod=0
EAP: EAP entering state AAA_REQUEST
EAP: EAP entering state AAA_IDLE
Encapsulating EAP message into a RADIUS packet
Copied RADIUS State Attribute
wlan0: RADIUS Sending RADIUS message to authentication server
RADIUS message: code=1 (Access-Request) identifier=23 length=209
Attribute 1 (User-Name) length=21
Value: 'leszek.niewiadomski'
Attribute 32 (NAS-Identifier) length=2
Value: ''
Attribute 5 (NAS-Port) length=6
Value: 1
Attribute 30 (Called-Station-Id) length=24
Value: '54-E6-FC-DC-C8-2A:Havo'
Attribute 31 (Calling-Station-Id) length=19
Value: 'E0-B9-A5-00-52-CB'
Attribute 12 (Framed-MTU) length=6
Value: 1400
Attribute 61 (NAS-Port-Type) length=6
Value: 19
Attribute 77 (Connect-Info) length=24
Value: 'CONNECT 54Mbps 802.11g'
Attribute 79 (EAP-Message) length=45
Value: 02 ef 00 2b 19 00 17 03 01 00 20 9d fb bb d1 e4 c7 ec bf 3b 71 5a ff 76 c3 a3 0d 50 71 89 0b e1 73 d9 cf e8 1a 12 ac 8b ae bd 30
Attribute 24 (State) length=18
Value: 59 93 66 27 5f 7c 7f 2e 4f 1e 25 50 e4 5b 7b b5
Attribute 80 (Message-Authenticator) length=18
Value: 0b a3 88 6c a1 33 ec ef ba fe c1 ae 39 48 26 ad
wlan0: RADIUS Next RADIUS client retransmit in 3 seconds
wlan0: RADIUS Received 197 bytes from RADIUS server
wlan0: RADIUS Received RADIUS message
RADIUS message: code=2 (Access-Accept) identifier=23 length=197
Attribute 64 (Tunnel-Type) length=6
Value: 00 00 00 0d
Attribute 65 (Tunnel-Medium-Type) length=6
Value: 00 00 00 06
Attribute 81 (Tunnel-Private-Group-Id) length=4
Value: 34 30
Attribute 1 (User-Name) length=21
Value: 'leszek.niewiadomski'
Attribute 26 (Vendor-Specific) length=58
Value: 00 00 01 37 11 34 a2 cc 96 6a 56 99 70 32 df 5f e8 c1 56 db ad 43 18 0c f0 e4 ea f8 95 10 47 dd 20 96 ca 4c d7 27 3b 58 ea 9c bd 4b 67 3b a1 2c 75 19 8c 98 08 0a f8 25
Attribute 26 (Vendor-Specific) length=58
Value: 00 00 01 37 10 34 ab 29 c3 f2 12 a1 c9 c6 fa b7 e2 58 38 a7 83 40 22 ec c3 4c fb dd 43 60 cd ea 6a 72 15 55 26 60 a1 2a 23 64 7b 6e d1 32 91 20 55 5b 50 1d a2 03 c6 f5
Attribute 79 (EAP-Message) length=6
Value: 03 ef 00 04
Attribute 80 (Message-Authenticator) length=18
Value: 4b 74 4d 68 33 b7 87 7b 31 5a 30 3a 87 1e 1d 3e
wlan0: STA e0:b9:a5:00:52:cb RADIUS: Received RADIUS packet matched with a pending request, round trip time 0.00 sec
RADIUS packet matching with station e0:b9:a5:00:52:cb
wlan0: STA e0:b9:a5:00:52:cb RADIUS: VLAN ID 40
VLAN: vlan_add_dynamic(vlan_id=40 ifname=wlan0.#)
Failed to create interface wlan0.40: -23 (Too many open files in system)
Try to remove and re-create wlan0.40
nl80211: Remove interface ifindex=31
nl80211: New interface wlan0.40 created: ifindex=33
nl80211: Add own interface ifindex 33
VLAN: Set interface wlan0.40 up
wlan0: STA e0:b9:a5:00:52:cb IEEE 802.11: added new dynamic VLAN interface 'wlan0.40'
wlan0: STA e0:b9:a5:00:52:cb IEEE 802.11: binding station to interface 'wlan0.40'
WPA: Moving STA e0:b9:a5:00:52:cb to use group state machine for VLAN ID 40
MS-MPPE-Send-Key - hexdump(len=32): [REMOVED]
MS-MPPE-Recv-Key - hexdump(len=32): [REMOVED]
wlan0: STA e0:b9:a5:00:52:cb IEEE 802.1X: old identity 'leszek.niewiadomski' updated with User-Name from Access-Accept 'leszek.niewiadomski'
wlan0: STA e0:b9:a5:00:52:cb IEEE 802.1X: decapsulated EAP packet (code=3 id=239 len=4) from RADIUS server: EAP Success
EAP: EAP entering state SUCCESS2
IEEE 802.1X: e0:b9:a5:00:52:cb BE_AUTH entering state SUCCESS
wlan0: STA e0:b9:a5:00:52:cb IEEE 802.1X: Sending EAP Packet (identifier 239)
IEEE 802.1X: e0:b9:a5:00:52:cb BE_AUTH entering state IDLE
nl80211: Ignore event for foreign ifindex 31
RTM_DELLINK, IFLA_IFNAME: Interface 'wlan0.40' removed
Unknown event 5
RTM_NEWLINK: operstate=0 ifi_flags=0x1002 ()
RTM_NEWLINK, IFLA_IFNAME: Interface 'wlan0.40' added
Unknown event 5
RTM_NEWLINK: operstate=0 ifi_flags=0x1002 ()
RTM_NEWLINK, IFLA_IFNAME: Interface 'wlan0.40' added
Unknown event 5
RTM_NEWLINK: operstate=0 ifi_flags=0x11043 ([UP][RUNNING][LOWER_UP])
RTM_NEWLINK, IFLA_IFNAME: Interface 'wlan0.40' added
Unknown event 5
VLAN: vlan_newlink(wlan0.40)
VLAN: br_addbr(brvlan40)
VLAN: Set interface brvlan40 up
VLAN: vlan_add(if_name=eth0, vid=40)
VLAN: Set interface eth0 up
VLAN: vlan_add: if_name vlan40 exists already
VLAN: br_addif(brvlan40, vlan40)
VLAN: Set interface vlan40 up
VLAN: br_addif(brvlan40, wlan0.40)
VLAN: Set interface wlan0.40 up
WPA: e0:b9:a5:00:52:cb WPA_PTK entering state INITPMK
WPA: PMK from EAPOL state machine (len=64)
WPA: e0:b9:a5:00:52:cb WPA_PTK entering state PTKSTART
wlan0: STA e0:b9:a5:00:52:cb WPA: sending 1/4 msg of 4-Way Handshake
WPA: Send EAPOL(version=2 secure=0 mic=0 ack=1 install=0 pairwise=8 kde_len=22 keyidx=0 encr=0)
nl80211: Ignore event for foreign ifindex 19
nl80211: Ignore event for foreign ifindex 19
nl80211: Ignore event for foreign ifindex 19
nl80211: Ignore event for foreign ifindex 19
nl80211: Ignore event for foreign ifindex 19
RTM_NEWLINK: operstate=0 ifi_flags=0x11043 ([UP][RUNNING][LOWER_UP])
RTM_NEWLINK, IFLA_IFNAME: Interface 'wlan0.40' added
Unknown event 5
nl80211: Add ifindex 18 for bridge brvlan40
nl80211: Add own interface ifindex 18
IEEE 802.1X: e0:b9:a5:00:52:cb TX status - version=2 type=0 length=4 - ack=1
VLAN: vlan_dellink(wlan0.40)
VLAN: br_delif(brvlan40, wlan0.40)
VLAN: Set interface vlan40 down
RTM_DELLINK, IFLA_IFNAME: Interface 'wlan0.40' removed
Unknown event 5
nl80211: Remove ifindex 18 for bridge brvlan40
nl80211: Ignore event for foreign ifindex 19
nl80211: Ignore event for foreign ifindex 19
IEEE 802.1X: e0:b9:a5:00:52:cb TX status - version=2 type=3 length=117 - ack=1
VLAN: vlan_newlink(wlan0.40)
VLAN: vlan_newlink(wlan0.40)
VLAN: vlan_newlink(wlan0.40)
VLAN: vlan_newlink(vlan40)
VLAN: vlan_newlink(vlan40)
VLAN: vlan_newlink(vlan40)
VLAN: vlan_newlink(vlan40)
VLAN: vlan_newlink(vlan40)
VLAN: vlan_newlink(wlan0.40)
VLAN: vlan_dellink(wlan0.40)
VLAN: vlan_newlink(vlan40)
VLAN: vlan_newlink(vlan40)
IEEE 802.1X: 121 bytes from e0:b9:a5:00:52:cb
IEEE 802.1X: version=1 type=3 length=117
wlan0: STA e0:b9:a5:00:52:cb WPA: received EAPOL-Key frame (2/4 Pairwise)
WPA: e0:b9:a5:00:52:cb WPA_PTK entering state PTKCALCNEGOTIATING
WPA: PTK derivation - A1=54:e6:fc:dc:c8:2a A2=e0:b9:a5:00:52:cb
WPA: PMK - hexdump(len=32): [REMOVED]
WPA: PTK - hexdump(len=48): [REMOVED]
WPA: e0:b9:a5:00:52:cb WPA_PTK entering state PTKCALCNEGOTIATING2
WPA: e0:b9:a5:00:52:cb WPA_PTK entering state PTKINITNEGOTIATING
wlan0: STA e0:b9:a5:00:52:cb WPA: sending 3/4 msg of 4-Way Handshake
WPA: Send EAPOL(version=2 secure=1 mic=1 ack=1 install=1 pairwise=8 kde_len=46 keyidx=1 encr=1)
Plaintext EAPOL-Key Key Data - hexdump(len=56): [REMOVED]
IEEE 802.1X: e0:b9:a5:00:52:cb TX status - version=2 type=3 length=151 - ack=1
IEEE 802.1X: 99 bytes from e0:b9:a5:00:52:cb
IEEE 802.1X: version=1 type=3 length=95
wlan0: STA e0:b9:a5:00:52:cb WPA: received EAPOL-Key frame (4/4 Pairwise)
WPA: e0:b9:a5:00:52:cb WPA_PTK entering state PTKINITDONE
wpa_driver_nl80211_set_key: ifindex=6 alg=3 addr=0x4d3df8 key_idx=0 set_tx=1 seq_len=0 key_len=16
addr=e0:b9:a5:00:52:cb
wlan0: STA e0:b9:a5:00:52:cb WPA: pairwise key handshake completed (RSN)
IEEE 802.1X: e0:b9:a5:00:52:cb AUTH_PAE entering state AUTHENTICATED
AP-STA-CONNECTED e0:b9:a5:00:52:cb
wlan0: STA e0:b9:a5:00:52:cb IEEE 802.1X: authorizing port
wlan0: STA e0:b9:a5:00:52:cb RADIUS: starting accounting session 4F44976B-00000002
wlan0: STA e0:b9:a5:00:52:cb IEEE 802.1X: authenticated - EAP type: 25 (PEAP)
RSN: added PMKSA cache entry for e0:b9:a5:00:52:cb
RSN: added PMKID - hexdump(len=16): 9c 23 05 76 00 f3 a4 31 1c c0 a9 69 07 22 27 20
wlan0: STA e0:b9:a5:00:52:cb WPA: Added PMKSA cache entry (IEEE 802.1X)
IEEE 802.1X: e0:b9:a5:00:52:cb - (EAP) retransWhile --> 0
wlan0: WPA rekeying GTK
WPA: group state machine entering state SETKEYS (VLAN-ID 0)
GMK - hexdump(len=32): [REMOVED]
GTK - hexdump(len=16): [REMOVED]
WPA: e0:b9:a5:00:52:cb WPA_PTK_GROUP entering state REKEYNEGOTIATING
wlan0: STA e0:b9:a5:00:52:cb WPA: sending 1/2 msg of Group Key Handshake
WPA: Send EAPOL(version=2 secure=1 mic=1 ack=1 install=0 pairwise=0 kde_len=24 keyidx=1 encr=1)
Plaintext EAPOL-Key Key Data - hexdump(len=32): [REMOVED]
wpa_group_setkeys: GKeyDoneStations=0
WPA: group state machine entering state SETKEYS (VLAN-ID 40)
GMK - hexdump(len=32): [REMOVED]
GTK - hexdump(len=16): [REMOVED]
wlan0: STA e0:b9:a5:00:52:cb WPA: GUpdateStationKeys already set - do not increment GKeyDoneStations
wpa_group_setkeys: GKeyDoneStations=1
IEEE 802.1X: 99 bytes from e0:b9:a5:00:52:cb
IEEE 802.1X: version=1 type=3 length=95
wlan0: STA e0:b9:a5:00:52:cb WPA: received EAPOL-Key frame (2/2 Group)
WPA: e0:b9:a5:00:52:cb WPA_PTK_GROUP entering state REKEYESTABLISHED
wlan0: STA e0:b9:a5:00:52:cb WPA: group key handshake completed (RSN)
WPA: group state machine entering state SETKEYSDONE (VLAN-ID 40)
wpa_driver_nl80211_set_key: ifindex=0 alg=3 addr=(nil) key_idx=2 set_tx=1 seq_len=0 key_len=16
nl80211: set_key failed; err=-19 No such device)
WPA: e0:b9:a5:00:52:cb WPA_PTK_GROUP entering state IDLE
IEEE 802.1X: e0:b9:a5:00:52:cb - aWhile --> 0
(Last edited by farfocel on 22 Feb 2012, 09:42)