WLC 5520 Call Home issue

[Clem58]

Hello,

We have recently upgraded our WLC 5520 from 8.2.170.0 to 8.5.171.0.

Since we have this message on the smart license status :

It seems it's working, we are correctly registered and Authorized, however we can see "Last Communication Attempt : Failed" each time.

Is it a "cosmetic" bug ? Have you ever faced this ?

Thanks by advance.

[balaji.bandi]

Due to some reason, some time it fails, but check in 1 or 2 days see if the able to connect to smart license server.still have issue investigate controller can reach internet cisco smart License servers. by telnet port 443

check the config : (do you have any proxy )

https://www.cisco.com/c/en/us/td/docs/wireless/technology/mesh/8-2/b_Smart_Licensing_Deployment_Guide.html

[Clem58]

Yes I had observed this before with the previous firmware, but now it fails everytime.
No we don't have proxy.

[balaji.bandi]

May be enable debug and check what is wrong ?

[Arshad Safrulla]

Hey it's 5520, why not use RTU licensing?

You really don't need to go through the smart licensing hassle.

https://www.cisco.com/c/en/us/td/docs/wireless/controller/8-10/config-guide/b_cg810/managing_licenses.html#concept_11C0DB0BFF074D27AC1C3A0DC80BAC50

[Clem58]

Because we have some smart licenses for this controller. And if I want to revert to RTU I will have to reboot the controller ☹

[Clem58]

Furthermore, to be honest I don't understand why we should revert to RTU
while there is a smart licensing feature existing ! It should be running
correctly.

[balaji.bandi]

Right to use only when you do first time, but  RTU is not License, it is for Test License or Trail License, until you get License in place.

Being a License holder, going back to RTU (not a great idea for me) - For now your device is Registered, Nothing to happens for your License here.

It keep Tries, still you see next time Failed, worth to Open a TAC case to investigate.

[craig.beck]

RTU is perfectly valid. It isn't for trial or test purposes. It's an honour-based concept...

Licensing on 3504, 5520 and 8540 Wireless LAN Controllers: RTU FAQ - Cisco

The evaluation license is for test/trial.

I'd maybe look at firewall logs to see if the WLC's traffic is being blocked, or if traffic is even reaching it from the WLC.

[balaji.bandi]

I may have mentioned on different way : Corrected here  for RTU as per the document :

[Clem58]

I've tried to register again the WLC with a new token and "force".

Now it's failing even for the registration ...

Here is the licence debug log :

(Cisco Controller) >
(Cisco Controller) >*fp_main_task: Aug 17 10:08:19.531: [PA] Open /tmp/wlc-to-sl-fifo FIFO in O_WRONLY | O_NONBLOCK mode success

*emWeb: Aug 17 10:17:49.136: [PA] Open /tmp/wlc-to-sl-fifo FIFO in O_WRONLY | O_NONBLOCK mode success

*fp_main_task: Aug 17 10:17:49.795: [PA] readFromWLCFifo, read success,Rcvd message msgId=25
*fp_main_task: Aug 17 10:17:49.795: [PA] wlcSlFifoRcvHandler received WLC_SLHA_MSG_ACTIVE2STDBY_CHKPTDATA_MSG from SL
*fp_main_task: Aug 17 10:17:49.795: [PA] Check-Active Message to Gensync
*fp_main_task: Aug 17 10:17:49.795: [PA] Key:19 Len:8 Key:SAStorageRegisterTime
*fp_main_task: Aug 17 10:17:50.315: [PA] readFromWLCFifo, read success,Rcvd message msgId=25
*fp_main_task: Aug 17 10:17:50.315: [PA] wlcSlFifoRcvHandler received WLC_SLHA_MSG_ACTIVE2STDBY_CHKPTDATA_MSG from SL
*fp_main_task: Aug 17 10:17:50.315: [PA] Check-Active Message to Gensync
*fp_main_task: Aug 17 10:17:50.315: [PA] Key:20 Len:8 Key:SAStorageRegisterStatus
*fp_main_task: Aug 17 10:17:50.835: [PA] readFromWLCFifo, read success,Rcvd message msgId=25
*fp_main_task: Aug 17 10:17:50.835: [PA] wlcSlFifoRcvHandler received WLC_SLHA_MSG_ACTIVE2STDBY_CHKPTDATA_MSG from SL
*fp_main_task: Aug 17 10:17:50.835: [PA] Check-Active Message to Gensync
*fp_main_task: Aug 17 10:17:50.835: [PA] Key:21 Len:40 Key:SAStorageRegisterFailureReason
*fp_main_task: Aug 17 10:17:51.355: [PA] readFromWLCFifo, read success,Rcvd message msgId=25
*fp_main_task: Aug 17 10:17:51.355: [PA] wlcSlFifoRcvHandler received WLC_SLHA_MSG_ACTIVE2STDBY_CHKPTDATA_MSG from SL
*fp_main_task: Aug 17 10:17:51.355: [PA] Check-Active Message to Gensync
*fp_main_task: Aug 17 10:17:51.355: [PA] Key:12 Len:1704 Key:SAStoragePrivateKey
*emWeb: Aug 17 10:18:20.955: [PA] Open /tmp/wlc-to-sl-fifo FIFO in O_WRONLY | O_NONBLOCK mode success

*fp_main_task: Aug 17 10:18:21.579: [PA] readFromWLCFifo, read success,Rcvd message msgId=5
*fp_main_task: Aug 17 10:18:21.579: [PA] wlcSlFifoRcvHandler received WLC_SL_MSG_SA_INFO_UPDATE from SL
*fp_main_task: Aug 17 10:18:21.579: [PA] wlcSlProcessSAInfoUpdateMsg() - rcvd SL msg:5 , info msg id:0 msg:WLC_SL_INFO_MSG_STATUS
*emWeb: Aug 17 10:18:39.939: [PA] Open /tmp/wlc-to-sl-fifo FIFO in O_WRONLY | O_NONBLOCK mode success

*fp_main_task: Aug 17 10:18:40.707: [PA] readFromWLCFifo, read success,Rcvd message msgId=5
*fp_main_task: Aug 17 10:18:40.707: [PA] wlcSlFifoRcvHandler received WLC_SL_MSG_SA_INFO_UPDATE from SL
*fp_main_task: Aug 17 10:18:40.707: [PA] wlcSlProcessSAInfoUpdateMsg() - rcvd SL msg:5 , info msg id:1 msg:WLC_SL_INFO_MSG_SUMMERY
*emWeb: Aug 17 10:18:47.889: [PA] Open /tmp/wlc-to-sl-fifo FIFO in O_WRONLY | O_NONBLOCK mode success

*fp_main_task: Aug 17 10:18:48.675: [PA] readFromWLCFifo, read success,Rcvd message msgId=5
*fp_main_task: Aug 17 10:18:48.675: [PA] wlcSlFifoRcvHandler received WLC_SL_MSG_SA_INFO_UPDATE from SL
*fp_main_task: Aug 17 10:18:48.675: [PA] wlcSlProcessSAInfoUpdateMsg() - rcvd SL msg:5 , info msg id:0 msg:WLC_SL_INFO_MSG_STATUS
*fp_main_task: Aug 17 10:19:20.639: [PA] readFromWLCFifo, read success,Rcvd message msgId=8
*fp_main_task: Aug 17 10:19:20.639: [PA] wlcSlFifoRcvHandler received WLC_SL_NOTIFICATION_FROM_SA from SL
*fp_main_task: Aug 17 10:19:20.639: [PA] readFromWLCFifo, read success,Rcvd message msgId=5
*fp_main_task: Aug 17 10:19:20.639: [PA] wlcSlFifoRcvHandler received WLC_SL_MSG_SA_INFO_UPDATE from SL
*fp_main_task: Aug 17 10:19:20.639: [PA] wlcSlProcessSAInfoUpdateMsg() - rcvd SL msg:5 , info msg id:0 msg:WLC_SL_INFO_MSG_STATUS
*fp_main_task: Aug 17 10:19:20.639: [PA] readFromWLCFifo, read success,Rcvd message msgId=5
*fp_main_task: Aug 17 10:19:20.639: [PA] wlcSlFifoRcvHandler received WLC_SL_MSG_SA_INFO_UPDATE from SL
*fp_main_task: Aug 17 10:19:20.639: [PA] readFromWLCFifo, read success,Rcvd message msgId=5
*fp_main_task: Aug 17 10:19:20.639: [PA] wlcSlFifoRcvHandler received WLC_SL_MSG_SA_INFO_UPDATE from SL
*fp_main_task: Aug 17 10:19:20.639: [PA] wlcSlProcessSAInfoUpdateMsg() - rcvd SL msg:5 , info msg id:2 msg:WLC_SL_INFO_MSG_USAGE
*fp_main_task: Aug 17 10:19:20.639: [PA] readFromWLCFifo, read success,Rcvd message msgId=5
*fp_main_task: Aug 17 10:19:20.639: [PA] wlcSlFifoRcvHandler received WLC_SL_MSG_SA_INFO_UPDATE from SL
*fp_main_task: Aug 17 10:19:20.639: [PA] wlcSlProcessSAInfoUpdateMsg() - rcvd SL msg:5 , info msg id:3 msg:WLC_SL_INFO_MSG_DETAIL
*fp_main_task: Aug 17 10:19:20.639: [PA] readFromWLCFifo, read success,Rcvd message msgId=5
*fp_main_task: Aug 17 10:19:20.639: [PA] wlcSlFifoRcvHandler received WLC_SL_MSG_SA_INFO_UPDATE from SL
*fp_main_task: Aug 17 10:19:20.639: [PA] wlcSlProcessSAInfoUpdateMsg() - rcvd SL msg:5 , info msg id:4 msg:WLC_SL_INFO_MSG_TECH_SUPPORT
*fp_main_task: Aug 17 10:19:20.639: [PA] readFromWLCFifo, read success,Rcvd message msgId=5
*fp_main_task: Aug 17 10:19:20.639: [PA] wlcSlFifoRcvHandler received WLC_SL_MSG_SA_INFO_UPDATE from SL
*fp_main_task: Aug 17 10:19:20.639: [PA] wlcSlProcessSAInfoUpdateMsg() - rcvd SL msg:5 , info msg id:5 msg:WLC_SL_INFO_MSG_UDI
*fp_main_task: Aug 17 10:19:20.639: [PA] readFromWLCFifo, read success,Rcvd message msgId=8
*fp_main_task: Aug 17 10:19:20.639: [PA] wlcSlFifoRcvHandler received WLC_SL_NOTIFICATION_FROM_SA from SL
*fp_main_task: Aug 17 10:19:20.639: [PA] SA Notification received on WLC, Id:11 Message:SA notification Critical:Yes
*fp_main_task: Aug 17 10:19:20.639: [PA] readFromWLCFifo, read success,Rcvd message msgId=5
*fp_main_task: Aug 17 10:19:20.639: [PA] wlcSlFifoRcvHandler received WLC_SL_MSG_SA_INFO_UPDATE from SL
*fp_main_task: Aug 17 10:19:20.639: [PA] wlcSlProcessSAInfoUpdateMsg() - rcvd SL msg:5 , info msg id:0 msg:WLC_SL_INFO_MSG_STATUS
*fp_main_task: Aug 17 10:19:20.639: [PA] readFromWLCFifo, read success,Rcvd message msgId=5
*fp_main_task: Aug 17 10:19:20.639: [PA] wlcSlFifoRcvHandler received WLC_SL_MSG_SA_INFO_UPDATE from SL
*fp_main_task: Aug 17 10:19:20.639: [PA] wlcSlProcessSAInfoUpdateMsg() - rcvd SL msg:5 , info msg id:1 msg:WLC_SL_INFO_MSG_SUMMERY
*fp_main_task: Aug 17 10:19:20.639: [PA] readFromWLCFifo, read success,Rcvd message msgId=5
*fp_main_task: Aug 17 10:19:20.639: [PA] wlcSlFifoRcvHandler received WLC_SL_MSG_SA_INFO_UPDATE from SL
*fp_main_task: Aug 17 10:19:20.639: [PA] wlcSlProcessSAInfoUpdateMsg() - rcvd SL msg:5 , info msg id:2 msg:WLC_SL_INFO_MSG_USAGE
*fp_main_task: Aug 17 10:19:20.639: [PA] wlcSlFifoRcvHandler received WLC_SL_MSG_SA_INFO_UPDATE from SL
*fp_main_task: Aug 17 10:19:20.639: [PA] wlcSlProcessSAInfoUpdateMsg() - rcvd SL msg:5 , info msg id:3 msg:WLC_SL_INFO_MSG_DETAIL
*fp_main_task: Aug 17 10:19:20.639: [PA] wlcSlFifoRcvHandler received WLC_SL_MSG_SA_INFO_UPDATE from SL
*fp_main_task: Aug 17 10:19:20.639: [PA] wlcSlProcessSAInfoUpdateMsg() - rcvd SL msg:5 , info msg id:4 msg:WLC_SL_INFO_MSG_TECH_SUPPORT
*fp_main_task: Aug 17 10:19:20.640: [PA] wlcSlFifoRcvHandler received WLC_SL_MSG_SA_INFO_UPDATE from SL
*fp_main_task: Aug 17 10:19:20.640: [PA] wlcSlProcessSAInfoUpdateMsg() - rcvd SL msg:5 , info msg id:5 msg:WLC_SL_INFO_MSG_UDI
*fp_main_task: Aug 17 10:19:20.640: [PA] readFromWLCFifo, read success,Rcvd message msgId=25
*fp_main_task: Aug 17 10:19:20.640: [PA] wlcSlFifoRcvHandler received WLC_SLHA_MSG_ACTIVE2STDBY_CHKPTDATA_MSG from SL
*fp_main_task: Aug 17 10:19:20.640: [PA] Check-Active Message to Gensync
*fp_main_task: Aug 17 10:19:20.640: [PA] Key:19 Len:8 Key:SAStorageRegisterTime
*fp_main_task: Aug 17 10:19:21.167: [PA] readFromWLCFifo, read success,Rcvd message msgId=25
*fp_main_task: Aug 17 10:19:21.167: [PA] wlcSlFifoRcvHandler received WLC_SLHA_MSG_ACTIVE2STDBY_CHKPTDATA_MSG from SL
*fp_main_task: Aug 17 10:19:21.167: [PA] Check-Active Message to Gensync
*fp_main_task: Aug 17 10:19:21.167: [PA] Key:20 Len:8 Key:SAStorageRegisterStatus
*fp_main_task: Aug 17 10:19:21.691: [PA] readFromWLCFifo, read success,Rcvd message msgId=25
*fp_main_task: Aug 17 10:19:21.691: [PA] wlcSlFifoRcvHandler received WLC_SLHA_MSG_ACTIVE2STDBY_CHKPTDATA_MSG from SL
*fp_main_task: Aug 17 10:19:21.691: [PA] Check-Active Message to Gensync
*fp_main_task: Aug 17 10:19:21.691: [PA] Key:21 Len:40 Key:SAStorageRegisterFailureReason
*fp_main_task: Aug 17 10:19:22.219: [PA] readFromWLCFifo, read success,Rcvd message msgId=25
*fp_main_task: Aug 17 10:19:22.219: [PA] wlcSlFifoRcvHandler received WLC_SLHA_MSG_ACTIVE2STDBY_CHKPTDATA_MSG from SL
*fp_main_task: Aug 17 10:19:22.219: [PA] Key:12 Len:1704 Key:SAStoragePrivateKey
*fp_main_task: Aug 17 10:19:22.643: [PA] readFromWLCFifo, read success,Rcvd message msgId=25
*fp_main_task: Aug 17 10:19:22.643: [PA] wlcSlFifoRcvHandler received WLC_SLHA_MSG_ACTIVE2STDBY_CHKPTDATA_MSG from SL
*fp_main_task: Aug 17 10:19:22.643: [PA] Key:19 Len:8 Key:SAStorageRegisterTime
*fp_main_task: Aug 17 10:19:23.171: [PA] readFromWLCFifo, read success,Rcvd message msgId=25
*fp_main_task: Aug 17 10:19:23.171: [PA] wlcSlFifoRcvHandler received WLC_SLHA_MSG_ACTIVE2STDBY_CHKPTDATA_MSG from SL
*fp_main_task: Aug 17 10:19:23.171: [PA] Check-Active Message to Gensync
*fp_main_task: Aug 17 10:19:23.171: [PA] Key:20 Len:8 Key:SAStorageRegisterStatus
*fp_main_task: Aug 17 10:19:23.699: [PA] readFromWLCFifo, read success,Rcvd message msgId=25
*fp_main_task: Aug 17 10:19:23.699: [PA] wlcSlFifoRcvHandler received WLC_SLHA_MSG_ACTIVE2STDBY_CHKPTDATA_MSG from SL
*fp_main_task: Aug 17 10:19:23.699: [PA] Check-Active Message to Gensync
*fp_main_task: Aug 17 10:19:23.699: [PA] Key:21 Len:40 Key:SAStorageRegisterFailureReason
*fp_main_task: Aug 17 10:19:24.231: [PA] readFromWLCFifo, read success,Rcvd message msgId=24
*fp_main_task: Aug 17 10:19:24.231: [PA] wlcSlFifoRcvHandler received WLC_SLHA_MSG_STDBY2ACTIVE_EVENT_SENDMSG from SL
*fp_main_task: Aug 17 10:19:24.755: [PA] readFromWLCFifo, read success,Rcvd message msgId=24
*fp_main_task: Aug 17 10:19:24.755: [PA] wlcSlFifoRcvHandler received WLC_SLHA_MSG_STDBY2ACTIVE_EVENT_SENDMSG from SL
*fp_main_task: Aug 17 10:19:25.171: [PA] readFromWLCFifo, read success,Rcvd message msgId=24
*fp_main_task: Aug 17 10:19:25.171: [PA] wlcSlFifoRcvHandler received WLC_SLHA_MSG_STDBY2ACTIVE_EVENT_SENDMSG from SL
*fp_main_task: Aug 17 10:19:25.691: [PA] readFromWLCFifo, read success,Rcvd message msgId=24
*fp_main_task: Aug 17 10:19:25.691: [PA] wlcSlFifoRcvHandler received WLC_SLHA_MSG_STDBY2ACTIVE_EVENT_SENDMSG from SL
*fp_main_task: Aug 17 10:20:53.899: [PA] wlcSlPeriodicEntitlementCheck() - current ap count:306, last entitlement count:306, sending entitlement req, loopcnt:3600

I don't understand what's going on ...

[Arshad Safrulla]

I guess its time for a TAC case, if you don't have the entitlement to open a case, you may try with licensing TAC.

[Clem58]

Thanks Arshadsaf.

I didn't test to do a de-registration before register the WLC again, can I do that without any problem ? I mean it won't need to WLC to reboot or won't disrupt anything ?

[Clem58 · Accepted solution]

I had opened a support case within the Smart Licensing portal assistance, and they helped me to solve this issue.

I had to do on both WLCs :

test smart-lic stop

test smart-lic start

Then I registered again with a token our WLC, and this time it's registered successfully !