cancel
Showing results for 
Search instead for 
Did you mean: 
cancel
1349
Views
15
Helpful
6
Replies
marc.groenen
Beginner

1142 AP not joining Virtual WLC

So im running a virtual WLC with v8.2.121.0 and am currently testing if AP's can join.

Since:

http://www.cisco.com/c/en/us/support/docs/wireless/virtual-wireless-controller/113677-virtual-wlan-dg-00.html

i upgraded the AP to c1140-k9w8-tar.153-3.JD.tar so he should be able to join.

Still the AP cannot join the vWLC.

In the debugging i can only see the following:

spamApTask6: Sep 09 10:19:27.416: <<<< Start of CAPWAP Packet >>>>
*spamApTask6: Sep 09 10:19:27.416: CAPWAP Control mesg Recd from 10.10.14.51, Port 1499
*spamApTask6: Sep 09 10:19:27.416: Msg Type : CAPWAP_DISCOVERY_REQUEST
*spamApTask6: Sep 09 10:19:27.416: Msg Length : 165
*spamApTask6: Sep 09 10:19:27.416: Msg SeqNum : 0
*spamApTask6: Sep 09 10:19:27.416:
*spamApTask6: Sep 09 10:19:27.416: Type : CAPWAP_MSGELE_DISCOVERY_TYPE, Length 1
*spamApTask6: Sep 09 10:19:27.416: Discovery Type : CAPWAP_DISCOVERY_TYPE_DHCP
*spamApTask6: Sep 09 10:19:27.416:
*spamApTask6: Sep 09 10:19:27.416: Type : CAPWAP_MSGELE_WTP_BOARD_DATA, Length 62
*spamApTask6: Sep 09 10:19:27.416: Vendor Identifier : 0x00409600
*spamApTask6: Sep 09 10:19:27.416: WTP_SERIAL_NUMBER : AIR-LAP1142N-E-K9
*spamApTask6: Sep 09 10:19:27.416:
*spamApTask6: Sep 09 10:19:27.416: Type : CAPWAP_MSGELE_WTP_DESCRIPTOR, Length 40
*spamApTask6: Sep 09 10:19:27.416: Maximum Radios Supported : 2
*spamApTask6: Sep 09 10:19:27.416: Radios in Use : 2
*spamApTask6: Sep 09 10:19:27.416: Encryption Capabilities : 0x00 0x01
*spamApTask6: Sep 09 10:19:27.416:
*spamApTask6: Sep 09 10:19:27.416: Type : CAPWAP_MSGELE_WTP_FRAME_TUNNEL, Length 1
*spamApTask6: Sep 09 10:19:27.416: WTP Frame Tunnel Mode : NATIVE_FRAME_TUNNEL_MODE
*spamApTask6: Sep 09 10:19:27.416:
*spamApTask6: Sep 09 10:19:27.416: Type : CAPWAP_MSGELE_WTP_MAC_TYPE, Length 1
*spamApTask6: Sep 09 10:19:27.416: WTP Mac Type : SPLIT_MAC
*spamApTask6: Sep 09 10:19:27.416:
*spamApTask6: Sep 09 10:19:27.416: Type : CAPWAP_MSGELE_VENDOR_SPECIFIC_PAYLOAD, Length 10
*spamApTask6: Sep 09 10:19:27.416: Vendor Identifier : 0x00409600
*spamApTask6: Sep 09 10:19:27.416:
IE : UNKNOWN IE 207

*spamApTask6: Sep 09 10:19:27.416: IE Length : 4

*spamApTask6: Sep 09 10:19:27.416: Decode routine not available, Printing Hex Dump

*spamApTask6: Sep 09 10:19:27.416: 00000000: 01 00 00 01 ....


*spamApTask6: Sep 09 10:19:27.416:
*spamApTask6: Sep 09 10:19:27.416: Type : CAPWAP_MSGELE_VENDOR_SPECIFIC_PAYLOAD, Length 22
*spamApTask6: Sep 09 10:19:27.416: Vendor Identifier : 0x00409600
*spamApTask6: Sep 09 10:19:27.416:
IE : RAD_NAME_PAYLOAD

*spamApTask6: Sep 09 10:19:27.416: IE Length : 16

*spamApTask6: Sep 09 10:19:27.416: Rad Name :
*spamApTask6: Sep 09 10:19:27.416: AP649e.f390.5da5

*spamApTask6: Sep 09 10:19:27.416: <<<< End of CAPWAP Packet >>>>

*spamApTask6: Sep 09 10:19:27.416: 0c:85:25:33:36:20 Discovery Request from 10.10.14.51:1499

*spamApTask6: Sep 09 10:19:27.416: 0c:85:25:33:36:20 Discovery request: Total msgEleLen = 165

*spamApTask6: Sep 09 10:19:27.416: 0c:85:25:33:36:20 Discovery Type = DHCP

*spamApTask6: Sep 09 10:19:27.416: 0c:85:25:33:36:20 ApModel: AIR-LAP1142N-E-K9

*spamApTask6: Sep 09 10:19:27.416: 0c:85:25:33:36:20 Discovery request: Vendor payload type = 207, length = 10

*spamApTask6: Sep 09 10:19:27.416: 0c:85:25:33:36:20 Discovery request: Vendor payload type = 5, length = 22

*spamApTask6: Sep 09 10:19:27.416: 0c:85:25:33:36:20 Join Priority Processing status = 0, Incoming Ap's Priority 1, MaxLrads = 200, joined Aps =0
*spamApTask6: Sep 09 10:19:27.416: 0c:85:25:33:36:20 Send Discovery response to 10.10.14.51:1499

*spamApTask6: Sep 09 10:19:27.416: apModel: AIR-LAP1142N-E-K9

*spamApTask6: Sep 09 10:19:27.416: apType = 15 apModel: AIR-LAP1142N-E-K9

*spamApTask6: Sep 09 10:19:27.416: apType: Oxf bundleApImageVer: 8.2.110.0
*spamApTask6: Sep 09 10:19:27.416: version:8 release:2 maint:110 build:0
*spamApTask6: Sep 09 10:19:27.416: 0c:85:25:33:36:20 msgLength = 36

*spamApTask6: Sep 09 10:19:27.416: 0c:85:25:33:36:20 Discovery resp:IPv4WtpManager[0]: a157040a

*spamApTask6: Sep 09 10:19:27.416: acSendDiscoveryResponsePacket: ipv4 lrad 10.10.14.51, mwar 10.1.4.10

*spamApTask6: Sep 09 10:19:27.416: acSendDiscoveryResponsePacket.2097: ipv4 lrad 10.10.14.51, mwar 10.1.4.10 Len 171

*spamApTask6: Sep 09 10:19:27.416: 0c:85:25:33:36:20 Discovery Response sent to 10.10.14.51 port 1499

*spamApTask6: Sep 09 10:19:27.416: 0c:85:25:33:36:20 Discovery Response sent to 10.10.14.51:1499

*osapiBsnTimer: Sep 09 10:19:28.763: 06:b4:52:00:0c:61 Wait join timer expired for connection 10.10.14.51:1500
*spamApTask7: Sep 09 10:19:28.763: 06:b4:52:00:0c:61 Disconnecting due to a wait join timer expiry event for (10.10.14.51:1500) server details (10.1.4.10:5246)

*spamApTask7: Sep 09 10:19:28.763: 0c:85:25:33:36:20 Found DTLS connection 0x8c62ff0, disconnecting it
*spamApTask7: Sep 09 10:19:28.763: 06:b4:52:00:0c:61 Deleting AP entry 10.10.14.51:1500 from temporary database.
*spamApTask7: Sep 09 10:19:28.763: 0c:85:25:33:36:20 DTLS connection closed event receivedserver (10.1.4.10/5246) client (10.10.14.51/1500)
*spamApTask7: Sep 09 10:19:28.763: 0c:85:25:33:36:20 No entry exists for AP (10.10.14.51/1500)
*spamApTask7: Sep 09 10:19:28.763: 0c:85:25:33:36:20 DTLS Connection not found for 10.10.14.51:1500

*spamApTask7: Sep 09 10:19:28.763: 0c:85:25:33:36:20 No AP entry exist in temporary database for 10.10.14.51:1500
*capwapSocketTask: Sep 09 10:19:37.414: acParsePacket: LRAD IP: 10.10.14.51, MWAR IP: 10.1.4.10

*spamApTask6: Sep 09 10:19:37.414: 06:b4:52:00:0c:61 DTLS Connection not found for 10.10.14.51:1499

*spamApTask6: Sep 09 10:19:37.414: 06:b4:52:00:0c:61 DTLS connection not found, creating new connection for 10:110:14:51 (1499) 10:1:4:10 (5246)

*spamApTask6: Sep 09 10:19:37.414: 06:b4:52:00:0c:61 record_consumed or sucess
*capwapSocketTask: Sep 09 10:19:37.424: acParsePacket: LRAD IP: 10.10.14.51, MWAR IP: 10.1.4.10

*spamApTask6: Sep 09 10:19:37.424: 06:b4:52:00:0c:61 record_consumed or sucess
*capwapSocketTask: Sep 09 10:19:37.719: acParsePacket: LRAD IP: 10.10.14.51, MWAR IP: 10.1.4.10

*capwapSocketTask: Sep 09 10:19:37.719: acParsePacket: LRAD IP: 10.10.14.51, MWAR IP: 10.1.4.10

*capwapSocketTask: Sep 09 10:19:37.719: acParsePacket: LRAD IP: 10.10.14.51, MWAR IP: 10.1.4.10

*capwapSocketTask: Sep 09 10:19:37.719: acParsePacket: LRAD IP: 10.10.14.51, MWAR IP: 10.1.4.10

*spamApTask6: Sep 09 10:19:37.719: 06:b4:52:00:0c:61 record_consumed or sucess
*spamApTask6: Sep 09 10:19:37.719: 06:b4:52:00:0c:61 record_consumed or sucess
*spamApTask6: Sep 09 10:19:37.720: 06:b4:52:00:0c:61 record_consumed or sucess
*spamApTask6: Sep 09 10:19:37.722: 06:b4:52:00:0c:61 record_consumed or sucess
*capwapSocketTask: Sep 09 10:19:37.924: acParsePacket: LRAD IP: 10.10.14.51, MWAR IP: 10.1.4.10

*capwapSocketTask: Sep 09 10:19:37.924: acParsePacket: LRAD IP: 10.10.14.51, MWAR IP: 10.1.4.10

*capwapSocketTask: Sep 09 10:19:37.924: acParsePacket: LRAD IP: 10.10.14.51, MWAR IP: 10.1.4.10

*spamApTask6: Sep 09 10:19:37.924: 06:b4:52:00:0c:61 record_consumed or sucess
*spamApTask6: Sep 09 10:19:37.924: 06:b4:52:00:0c:61 record_consumed or sucess
*spamApTask6: Sep 09 10:19:37.925: 06:b4:52:00:0c:61 record_consumed or sucess
*spamApTask3: Sep 09 10:19:37.925: 06:b4:52:00:0c:61 DTLS Session established server (10.1.4.10:5246), client (10.10.14.51:1499)
*spamApTask3: Sep 09 10:19:37.925: 06:b4:52:00:0c:61 Starting wait join timer for AP: 10.10.14.51:1499

*capwapSocketTask: Sep 09 10:19:42.935: acParsePacket: LRAD IP: 10.10.14.51, MWAR IP: 10.1.4.10

There seems to be some weird join timer expiry:

*spamApTask7: Sep 09 10:19:28.763: 06:b4:52:00:0c:61 Disconnecting due to a wait join timer expiry event for (10.10.14.51:1500) server details (10.1.4.10:5246)

*spamApTask7: Sep 09 10:19:28.763: 0c:85:25:33:36:20 Found DTLS connection 0x8c62ff0, disconnecting it
*spamApTask7: Sep 09 10:19:28.763: 06:b4:52:00:0c:61 Deleting AP entry 10.10.14.51:1500 from temporary database.

Attached full debugging

6 REPLIES 6
marc.groenen
Beginner

Also did some debugging on the AP:

=~=~=~=~=~=~=~=~=~=~=~= PuTTY log 2016.09.09 13:33:48 =~=~=~=~=~=~=~=~=~=~=~=

*Sep 9 11:27:16.151: %CAPWAP-3-EVENTLOG: Calling wtpGetAcToJoin from timer expiry.
*Sep 9 11:27:16.151: %CAPWAP-3-EVENTLOG: !mwarname
*Sep 9 11:27:16.152: %CAPWAP-3-EVENTLOG: !mwarname
*Sep 9 11:27:16.152: %CAPWAP-3-EVENTLOG: !mwarname
*Sep 9 11:27:16.152: %CAPWAP-3-EVENTLOG: Selected MWAR 'wlc01' (index 0).
*Sep 9 11:27:16.152: %CAPWAP-3-EVENTLOG: Ap mgr count=1
*Sep 9 11:27:16.152: %CAPWAP-3-EVENTLOG: Controller: wlc01. ApMgr count is 1 ipTransportTried 0 prefer-mode 0

*Sep 9 11:27:16.152: %CAPWAP-3-EVENTLOG: Adding Ipv4 AP manager 10.1.4.10 to least load
*Sep 9 11:27:16.152: %CAPWAP-3-EVENTLOG: IPv4 Pref mode. Choosing AP Mgr with index 0, IP = 10.1.4.10, load = 0..
*Sep 9 11:27:21.517: %CAPWAP-3-EVENTLOG: Join request: version=134376960
*Sep 9 11:27:21.517: %CAPWAP-3-EVENTLOG: Join request: hasMaximum Message Payload
*Sep 9 11:27:21.517: CAPWAP Control mesg Sent to 10.1.4.10, Port 5246
*Sep 9 11:27:21.518: Msg Type : CAPWAP_JOIN_REQUEST
*Sep 9 11:27:21.518: Msg Length : 254
*Sep 9 11:27:21.518: Msg SeqNum : 0
*Sep 9 11:27:21.518: %CAPWAP-5-SENDJOIN: sending Join Request to 10.1.4.10
AP649e.f390.5ca5#
AP649e.f390.5ca5#
AP649e.f390.5ca5#
-- about 60 seconds ---
AP649e.f390.5ca5#
AP649e.f390.5ca5#
AP649e.f390.5ca5#
*Sep 9 11:28:15.999: %CAPWAP-3-EVENTLOG: Wait DTLS timer has expired
*Sep 9 11:28:15.999: %CAPWAP-3-EVENTLOG: Did not get join response
*Sep 9 11:28:16.000: %DTLS-5-SEND_ALERT: Send FATAL : Close notify Alert to 10.1.4.10:5246
*Sep 9 11:28:16.000: %CAPWAP-3-EVENTLOG: CAPWAP State: DTLS Teardown.
*Sep 9 11:28:16.008: %CAPWAP-3-EVENTLOG: DTLS session cleanup completed. Restarting capwap state machine.
*Sep 9 11:28:16.089: %LINK-5-CHANGED: Interface Dot11Radio0, changed state to administratively down
*Sep 9 11:28:16.089: %LINK-5-CHANGED: Interface Dot11Radio1, changed state to administratively down
*Sep 9 11:28:16.091: %LWAPP-4-CLIENTEVENTLOG: Not sending change state post as the radio admin is down, lrad state = 5
*Sep 9 11:28:16.091: %CAPWAP-3-EVENTLOG: CAPWAP state not up. Abort sending channel and power levels info. 10.1.4.10

*Sep 9 11:28:16.148: CAPWAP Control mesg Sent to 10.1.4.10, Port 5246
*Sep 9 11:28:16.148: Msg Type : CAPWAP_DISCOVERY_REQUEST
*Sep 9 11:28:16.148: Msg Length : 165
*Sep 9 11:28:16.148: Msg SeqNum : 0
*Sep 9 11:28:16.149: CAPWAP Control mesg Sent to 10.1.4.11, Port 5246
*Sep 9 11:28:16.149: Msg Type : CAPWAP_DISCOVERY_REQUEST
*Sep 9 11:28:16.149: Msg Length : 165
*Sep 9 11:28:16.149: Msg SeqNum : 0
*Sep 9 11:28:16.150: CAPWAP Control mesg Sent to 255.255.255.255, Port 5246
*Sep 9 11:28:16.150: Msg Type : CAPWAP_DISCOVERY_REQUEST
*Sep 9 11:28:16.150: Msg Length : 165
*Sep 9 11:28:16.150: Msg SeqNum : 0
*Sep 9 11:28:16.151: CAPWAP Control mesg Sent to 255.1.0.0, Port 5246
*Sep 9 11:28:16.151: Msg Type : CAPWAP_DISCOVERY_REQUEST
*Sep 9 11:28:16.151: Msg Length : 165
*Sep 9 11:28:16.151: Msg SeqNum : 0
*Sep 9 11:28:16.162: CAPWAP Control mesg Recd from 10.1.4.10, Port 5246
*Sep 9 11:28:16.162: HLEN 2, Radio ID 0, WBID 1
*Sep 9 11:28:16.162: Msg Type : CAPWAP_DISCOVERY_RESPONSE
*Sep 9 11:28:16.162: Msg Length : 113
*Sep 9 11:28:16.162: Msg SeqNum : 0
*Sep 9 11:28:17.088: %LINEPROTO-5-UPDOWN: Line protocol on Interface Dot11Radio0, changed state to down
*Sep 9 11:28:17.111: %CAPWAP-3-EVENTLOG: CAPWAP state not up. Abort sending channel and power levels info. 10.1.4.10

*Sep 9 11:28:17.117: %LINK-6-UPDOWN: Interface Dot11Radio1, changed state to down
*Sep 9 11:28:17.122: %LINK-5-CHANGED: Interface Dot11Radio1, changed state to reset
*Sep 9 11:28:18.112: %LINEPROTO-5-UPDOWN: Line protocol on Interface Dot11Radio0, changed state to up
*Sep 9 11:28:18.117: %LINEPROTO-5-UPDOWN: Line protocol on Interface Dot11Radio1, changed state to down
*Sep 9 11:28:18.143: %CAPWAP-3-EVENTLOG: CAPWAP state not up. Abort sending channel and power levels info. 10.1.4.10

*Sep 9 11:28:18.144: %LINK-6-UPDOWN: Interface Dot11Radio1, changed state to up
*Sep 9 11:28:18.149: %LINK-6-UPDOWN: Interface Dot11Radio0, changed state to down
*Sep 9 11:28:18.154: %LINK-5-CHANGED: Interface Dot11Radio0, changed state to reset
*Sep 9 11:28:19.144: %LINEPROTO-5-UPDOWN: Line protocol on Interface Dot11Radio1, changed state to up
*Sep 9 11:28:19.149: %LINEPROTO-5-UPDOWN: Line protocol on Interface Dot11Radio0, changed state to down
*Sep 9 11:28:19.174: %CAPWAP-3-EVENTLOG: CAPWAP state not up. Abort sending channel and power levels info. 10.1.4.10

*Sep 9 11:28:19.175: %LINK-6-UPDOWN: Interface Dot11Radio0, changed state to up
*Sep 9 11:28:20.175: %LINEPROTO-5-UPDOWN: Line protocol on Interface Dot11Radio0, changed state to up
AP649e.f390.5ca5#
AP649e.f390.5ca5#
AP649e.f390.5ca5#
AP649e.f390.5ca5#
*Sep 9 11:28:26.151: %CAPWAP-3-EVENTLOG: Calling wtpGetAcToJoin from timer expiry.
*Sep 9 11:28:26.151: %CAPWAP-3-EVENTLOG: !mwarname
*Sep 9 11:28:26.152: %CAPWAP-3-EVENTLOG: !mwarname
*Sep 9 11:28:26.152: %CAPWAP-3-EVENTLOG: !mwarname
*Sep 9 11:28:26.152: %CAPWAP-3-EVENTLOG: Selected MWAR 'wlc01' (index 0).
*Sep 9 11:28:26.152: %CAPWAP-3-EVENTLOG: Ap mgr count=1
*Sep 9 11:28:26.152: %CAPWAP-3-EVENTLOG: Controller: wlc01. ApMgr count is 1 ipTransportTried 0 prefer-mode 0

*Sep 9 11:28:26.152: %CAPWAP-3-EVENTLOG: Adding Ipv4 AP manager 10.1.4.10 to least load
*Sep 9 11:28:26.152: %CAPWAP-3-EVENTLOG: IPv4 Pref mode. Choosing AP Mgr with index 0, IP = 10.1.4.10, load = 0..
AP649e.f390.5ca5#
AP649e.f390.5ca5#
AP649e.f390.5ca5#
*Sep 9 11:28:31.513: %CAPWAP-3-EVENTLOG: Join request: version=134376960
*Sep 9 11:28:31.513: %CAPWAP-3-EVENTLOG: Join request: hasMaximum Message Payload
*Sep 9 11:28:31.513: CAPWAP Control mesg Sent to 10.1.4.10, Port 5246
*Sep 9 11:28:31.514: Msg Type : CAPWAP_JOIN_REQUEST
*Sep 9 11:28:31.514: Msg Length : 254
*Sep 9 11:28:31.514: Msg SeqNum : 0
*Sep 9 11:28:31.514: %CAPWAP-5-SENDJOIN: sending Join Request to 10.1.4.10

So after the join request it stays silent on the AP for about 60 seconds.

Since the WLC receive the message there is probably something going wrong from the WLC to the AP.

Going to check the virtual environment :S and possible a firewall i guess...

Hi,

What are are AP policies configured under Security > AP Policies. If AP authorization is enabled ?

AP policies are default.

Also checked if the MIC certificate had expired but that isnt the case.

The test AP can join a normal 5508 WLC running v8.x without any problem.

Currently doing some more tests in my test ESX environment and cant seem to get it to work there either.

Created Data and Service port adapter and enabled promiscous mode + all VLAN's.

Also trying the v7.4.150.0 Cisco advised version but need to get an eval license

Run "debug capwap errors enable" on the WLC and provide output

What is the WLC IP address, 10.17.4.10, 10.1.4.11 or 10.17.4.10. And what is AP's IP 10.16.14.51 or 10.10.14.51. 

mustehsan thank you for youre commitment.

I filtered the customer IP space appearantly i didnt use the same values my apoligizes.

I just solved the issue appearantly you need to activiate the license on the vWLC although it says it supports 200/3000 AP's.

So after activating the already active license and accepting the EULA the AP's could join.

Strange i didnt get anything in the debugging about the licenses not being active.

 

marc.groenen - YOU ARE THE MAN! THANKS!
Content for Community-Ad