RV220W High CPU Usage/ Poor Router Performance

Danny Yehya · ‎09-13-2013

I have tried using QoS to help with performance for two VLANs but it doesn't seem to affect the performance. Also I am seeing very high CPU usage sometimes and cannot determine the cause as I have disabled Content filtering which seemed to be the major issue with it.

Running the latest firmware

See the the logs below:

Errors:

Fri Sep 13 11:52:07 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] wpaAuthRecvPTKMsg2: mic check failed
Fri Sep 13 11:52:07 2013(GMT-0500) [rv220w][System][PLATFORM] /pfrm2.0/bin/bwLimitConfig /tmp/system.db 9 dot11STA 40 failed. status=-1
Fri Sep 13 11:52:07 2013(GMT-0500) [rv220w][System][EVTDSPTCH] umiIoctl (18, UMI_CMD_DB_UPDATE(4)) failed. table=dot11STA row=40
Fri Sep 13 11:52:07 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] wpaAuthRecvPTKMsg2: mic check failed
Fri Sep 13 11:52:07 2013(GMT-0500) [rv220w][System][PLATFORM] /pfrm2.0/bin/bwLimitConfig /tmp/system.db 18 dot11STA 42 failed. status=-1
Fri Sep 13 11:52:07 2013(GMT-0500) [rv220w][System][EVTDSPTCH] umiIoctl (18, UMI_CMD_DB_UPDATE(4)) failed. table=dot11STA row=42
Fri Sep 13 11:52:08 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] wpaAuthRecvPTKMsg2: mic check failed
Fri Sep 13 11:52:08 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] wpaAuthRecvPTKMsg2: mic check failed
Fri Sep 13 11:52:08 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] wpaAuthRecvPTKMsg2: mic check failed
Fri Sep 13 11:52:08 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] wpaAuthRecvPTKMsg2: mic check failed
Fri Sep 13 11:52:09 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] wpaAuthRecvPTKMsg2: mic check failed
Fri Sep 13 11:52:09 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] wpaAuthRecvPTKMsg2: mic check failed
Fri Sep 13 11:52:09 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] wpaAuthRecvPTKMsg2: mic check failed
Fri Sep 13 11:52:10 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] wpaAuthRecvPTKMsg2: mic check failed
Fri Sep 13 11:52:10 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] wpaAuthRecvPTKMsg2: mic check failed
Fri Sep 13 11:52:11 2013(GMT-0500) [rv220w][System][PLATFORM] /pfrm2.0/bin/bwLimitConfig /tmp/system.db 9 dot11STA 42 failed. status=-1
Fri Sep 13 11:52:11 2013(GMT-0500) [rv220w][System][EVTDSPTCH] umiIoctl (18, UMI_CMD_DB_UPDATE(4)) failed. table=dot11STA row=42
Fri Sep 13 11:52:43 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] wpaAuthRecvPTKMsg2: mic check failed
Fri Sep 13 11:52:43 2013(GMT-0500) [rv220w][System][PLATFORM] /pfrm2.0/bin/bwLimitConfig /tmp/system.db 18 dot11STA 42 failed. status=-1
Fri Sep 13 11:52:43 2013(GMT-0500) [rv220w][System][EVTDSPTCH] umiIoctl (18, UMI_CMD_DB_UPDATE(4)) failed. table=dot11STA row=42
Fri Sep 13 11:52:43 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] wpaAuthRecvPTKMsg2: mic check failed
Fri Sep 13 11:52:44 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] wpaAuthRecvPTKMsg2: mic check failed
Fri Sep 13 11:52:44 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] wpaAuthRecvPTKMsg2: mic check failed
Fri Sep 13 11:52:45 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] wpaAuthRecvPTKMsg2: mic check failed
Fri Sep 13 11:52:45 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] wpaAuthRecvPTKMsg2: mic check failed
Fri Sep 13 11:52:46 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] wpaAuthRecvPTKMsg2: mic check failed
Fri Sep 13 11:52:46 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] wpaAuthRecvPTKMsg2: mic check failed
Fri Sep 13 11:52:47 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] wpaAuthRecvPTKMsg2: mic check failed
Fri Sep 13 11:52:48 2013(GMT-0500) [rv220w][System][PLATFORM] /pfrm2.0/bin/bwLimitConfig /tmp/system.db 9 dot11STA 42 failed. status=-1
Fri Sep 13 11:52:48 2013(GMT-0500) [rv220w][System][EVTDSPTCH] umiIoctl (18, UMI_CMD_DB_UPDATE(4)) failed. table=dot11STA row=42
Fri Sep 13 11:52:58 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] wpaAuthRecvPTKMsg2: mic check failed
Fri Sep 13 11:52:58 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] wpaAuthRecvPTKMsg2: mic check failed
Fri Sep 13 11:52:59 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] wpaAuthRecvPTKMsg2: mic check failed
Fri Sep 13 11:52:59 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] wpaAuthRecvPTKMsg2: mic check failed
Fri Sep 13 11:53:00 2013(GMT-0500) [rv220w][System][PLATFORM] /pfrm2.0/bin/bwLimitConfig /tmp/system.db 18 dot11STA 42 failed. status=-1
Fri Sep 13 11:53:00 2013(GMT-0500) [rv220w][System][EVTDSPTCH] umiIoctl (18, UMI_CMD_DB_UPDATE(4)) failed. table=dot11STA row=42
Fri Sep 13 11:53:01 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] wpaAuthRecvPTKMsg2: mic check failed
Fri Sep 13 11:53:01 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] wpaAuthRecvPTKMsg2: mic check failed
Fri Sep 13 11:53:02 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] wpaAuthRecvPTKMsg2: mic check failed
Fri Sep 13 11:53:03 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] wpaAuthRecvPTKMsg2: mic check failed
Fri Sep 13 11:53:03 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] wpaAuthRecvPTKMsg2: mic check failed
Fri Sep 13 11:53:04 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] wpaAuthRecvPTKMsg2: mic check failed
Fri Sep 13 11:53:04 2013(GMT-0500) [rv220w][System][PLATFORM] /pfrm2.0/bin/bwLimitConfig /tmp/system.db 9 dot11STA 42 failed. status=-1
Fri Sep 13 11:53:04 2013(GMT-0500) [rv220w][System][EVTDSPTCH] umiIoctl (18, UMI_CMD_DB_UPDATE(4)) failed. table=dot11STA row=42
Fri Sep 13 11:53:14 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] wpaAuthRecvPTKMsg2: mic check failed
Fri Sep 13 11:53:16 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] wpaAuthRecvPTKMsg2: mic check failed
Fri Sep 13 11:53:20 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] wpaAuthRecvPTKMsg2: mic check failed
Fri Sep 13 11:53:21 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] wpaAuthRecvPTKMsg2: mic check failed
Fri Sep 13 11:53:22 2013(GMT-0500) [rv220w][System][PLATFORM] /pfrm2.0/bin/bwLimitConfig /tmp/system.db 18 dot11STA 42 failed. status=-1
Fri Sep 13 11:53:22 2013(GMT-0500) [rv220w][System][EVTDSPTCH] umiIoctl (18, UMI_CMD_DB_UPDATE(4)) failed. table=dot11STA row=42
Fri Sep 13 11:53:23 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] wpaAuthRecvPTKMsg2: mic check failed
Fri Sep 13 11:53:24 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] wpaAuthRecvPTKMsg2: mic check failed
Fri Sep 13 11:53:25 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] wpaAuthRecvPTKMsg2: mic check failed
Fri Sep 13 11:53:26 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] wpaAuthRecvPTKMsg2: mic check failed
Fri Sep 13 11:53:27 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] wpaAuthRecvPTKMsg2: mic check failed
Fri Sep 13 11:53:28 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] wpaAuthRecvPTKMsg2: mic check failed
Fri Sep 13 11:53:29 2013(GMT-0500) [rv220w][System][PLATFORM] /pfrm2.0/bin/bwLimitConfig /tmp/system.db 9 dot11STA 42 failed. status=-1
Fri Sep 13 11:53:29 2013(GMT-0500) [rv220w][System][EVTDSPTCH] umiIoctl (18, UMI_CMD_DB_UPDATE(4)) failed. table=dot11STA row=42

Warnings:

Fri Sep 13 11:56:37 2013(GMT-0500) [rv220w][Kernel][KERNEL] ERROR:endChantRecv: protocol PNAC returned !OK
Fri Sep 13 11:56:37 2013(GMT-0500) [rv220w][Kernel][KERNEL] ERROR:endChantRecv: protocol PNAC returned !OK
Fri Sep 13 11:56:37 2013(GMT-0500) [rv220w][Kernel][KERNEL] ERROR:endChantRecv: protocol PNAC returned !OK
Fri Sep 13 11:56:37 2013(GMT-0500) [rv220w][Kernel][KERNEL] ERROR:endChantRecv: protocol PNAC returned !OK
Fri Sep 13 11:56:37 2013(GMT-0500) [rv220w][Kernel][KERNEL] ERROR:endChantRecv: protocol PNAC returned !OK
Fri Sep 13 11:56:37 2013(GMT-0500) [rv220w][Kernel][KERNEL] ERROR:endChantRecv: protocol PNAC returned !OK
Fri Sep 13 11:56:37 2013(GMT-0500) [rv220w][Kernel][KERNEL] ERROR:endChantRecv: protocol PNAC returned !OK
Fri Sep 13 11:56:42 2013(GMT-0500) [rv220w][Kernel][KERNEL] ERROR:endChantRecv: protocol PNAC returned !OK
Fri Sep 13 11:58:47 2013(GMT-0500) [rv220w][Kernel][KERNEL] ERROR:endChantRecv: protocol PNAC returned !OK
Fri Sep 13 11:58:47 2013(GMT-0500) [rv220w][Kernel][KERNEL] ERROR:endChantRecv: protocol PNAC returned !OK
Fri Sep 13 11:58:47 2013(GMT-0500) [rv220w][Kernel][KERNEL] ERROR:endChantRecv: protocol PNAC returned !OK
Fri Sep 13 11:58:47 2013(GMT-0500) [rv220w][Kernel][KERNEL] ERROR:endChantRecv: protocol PNAC returned !OK
Fri Sep 13 11:58:47 2013(GMT-0500) [rv220w][Kernel][KERNEL] ERROR:endChantRecv: protocol PNAC returned !OK
Fri Sep 13 11:58:47 2013(GMT-0500) [rv220w][Kernel][KERNEL] ERROR:endChantRecv: protocol PNAC returned !OK
Fri Sep 13 11:58:47 2013(GMT-0500) [rv220w][Kernel][KERNEL] ERROR:endChantRecv: protocol PNAC returned !OK
Fri Sep 13 11:58:47 2013(GMT-0500) [rv220w][Kernel][KERNEL] ERROR:endChantRecv: protocol PNAC returned !OK
Fri Sep 13 11:58:52 2013(GMT-0500) [rv220w][Kernel][KERNEL] ERROR:endChantRecv: protocol PNAC returned !OK
Fri Sep 13 11:58:52 2013(GMT-0500) [rv220w][Kernel][KERNEL] ERROR:endChantRecv: protocol PNAC returned !OK
Fri Sep 13 11:58:52 2013(GMT-0500) [rv220w][Kernel][KERNEL] ERROR:endChantRecv: protocol PNAC returned !OK
Fri Sep 13 11:56:36 2013(GMT-0500) [rv220w][Kernel][KERNEL] ERROR:endChantRecv: protocol PNAC returned !OK

Debugging:

Fri Sep 13 12:01:36 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] ANonce
Fri Sep 13 12:01:36 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] PTK data
Fri Sep 13 12:01:36 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] sending EAPOL pdu to PNAC...
Fri Sep 13 12:01:36 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] ANonce
Fri Sep 13 12:01:36 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] PTK data
Fri Sep 13 12:01:36 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] sending EAPOL pdu to PNAC...
Fri Sep 13 12:01:36 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] ANonce
Fri Sep 13 12:01:36 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] PTK data
Fri Sep 13 12:01:36 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] sending EAPOL pdu to PNAC...
Fri Sep 13 12:01:36 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] ANonce
Fri Sep 13 12:01:36 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] PTK data
Fri Sep 13 12:01:36 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] sending EAPOL pdu to PNAC...
Fri Sep 13 12:01:36 2013(GMT-0500) [rv220w][System][PLATFORM] platformHandleDBUpdate:dot11STA op=9 row=42
Fri Sep 13 12:01:43 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] creating pnac authenticator with values 10 3600 - NONE
Fri Sep 13 12:01:43 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] ASSendRtn: 0 ASToAuthRecv: 0
Fri Sep 13 12:01:43 2013(GMT-0500) [rv220w][System][PLATFORM] platformHandleDBUpdate:dot11STA op=18 row=42
Fri Sep 13 12:01:43 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] sending EAPOL pdu to PNAC...
Fri Sep 13 12:01:43 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] ANonce
Fri Sep 13 12:01:43 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] PTK data
Fri Sep 13 12:01:43 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] sending EAPOL pdu to PNAC...
Fri Sep 13 12:01:43 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] ANonce
Fri Sep 13 12:01:43 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] PTK data
Fri Sep 13 12:01:44 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] sending EAPOL pdu to PNAC...
Fri Sep 13 12:01:44 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] ANonce
Fri Sep 13 12:01:44 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] PTK data
Fri Sep 13 12:01:44 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] sending EAPOL pdu to PNAC...
Fri Sep 13 12:01:45 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] sending EAPOL pdu to PNAC...
Fri Sep 13 12:01:45 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] ANonce
Fri Sep 13 12:01:45 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] PTK data
Fri Sep 13 12:01:45 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] sending EAPOL pdu to PNAC...
Fri Sep 13 12:01:45 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] ANonce
Fri Sep 13 12:01:45 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] PTK data
Fri Sep 13 12:01:46 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] sending EAPOL pdu to PNAC...
Fri Sep 13 12:01:46 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] sending EAPOL pdu to PNAC...
Fri Sep 13 12:01:46 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] ANonce
Fri Sep 13 12:01:47 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] PTK data
Fri Sep 13 12:01:47 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] sending EAPOL pdu to PNAC...
Fri Sep 13 12:01:47 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] ANonce
Fri Sep 13 12:01:47 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] PTK data
Fri Sep 13 12:01:47 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] sending EAPOL pdu to PNAC...
Fri Sep 13 12:01:48 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] sending EAPOL pdu to PNAC...
Fri Sep 13 12:01:48 2013(GMT-0500) [rv220w][System][PLATFORM] platformHandleDBUpdate:dot11STA op=9 row=42
Fri Sep 13 12:01:58 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] creating pnac authenticator with values 10 3600 - NONE
Fri Sep 13 12:01:58 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] ASSendRtn: 0 ASToAuthRecv: 0
Fri Sep 13 12:01:59 2013(GMT-0500) [rv220w][System][PLATFORM] platformHandleDBUpdate:dot11STA op=18 row=42
Fri Sep 13 12:01:59 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] sending EAPOL pdu to PNAC...
Fri Sep 13 12:01:59 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] sending EAPOL pdu to PNAC...
Fri Sep 13 12:01:59 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] sending EAPOL pdu to PNAC...
Fri Sep 13 12:01:59 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] sending EAPOL pdu to PNAC...
Fri Sep 13 12:02:00 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] ANonce
Fri Sep 13 12:02:00 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] PTK data
Fri Sep 13 12:02:00 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] sending EAPOL pdu to PNAC...
Fri Sep 13 12:02:00 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] sending EAPOL pdu to PNAC...
Fri Sep 13 12:02:01 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] ANonce
Fri Sep 13 12:02:01 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] PTK data
Fri Sep 13 12:02:01 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] sending EAPOL pdu to PNAC...
Fri Sep 13 12:02:02 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] ANonce
Fri Sep 13 12:02:02 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] PTK data
Fri Sep 13 12:02:02 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] sending EAPOL pdu to PNAC...
Fri Sep 13 12:02:02 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] ANonce
Fri Sep 13 12:02:03 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] PTK data
Fri Sep 13 12:02:04 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] sending EAPOL pdu to PNAC...
Fri Sep 13 12:02:04 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] ANonce
Fri Sep 13 12:02:04 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] PTK data
Fri Sep 13 12:02:04 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] sending EAPOL pdu to PNAC...
Fri Sep 13 12:02:06 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] ANonce
Fri Sep 13 12:02:06 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] PTK data
Fri Sep 13 12:02:07 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] sending EAPOL pdu to PNAC...
Fri Sep 13 12:02:08 2013(GMT-0500) [rv220w][System][PLATFORM] platformHandleDBUpdate:dot11STA op=9 row=42
Fri Sep 13 12:01:36 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] sending EAPOL pdu to PNAC...

Informational:

Fri Sep 13 12:01:36 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] pnacUmiIoctlHandler: cmd: PNAC_PDU_TX(43)
Fri Sep 13 12:01:36 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] pnacUmiIoctlHandler: cmd: PNAC_PDU_RX(44)
Fri Sep 13 12:01:36 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] received a pdu on vap0
Fri Sep 13 12:01:36 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] from pnacRecvMapi: pkt body len = 135, pktType = 3
Fri Sep 13 12:01:36 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] from pnacPDUProcess: received PNAC_EAPOL_KEY_PACKET
Fri Sep 13 12:01:36 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] Recvied DOT11_EAPOL_KEYMSG
Fri Sep 13 12:01:36 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] eapolRecvAuthKeyMsg: received key message
Fri Sep 13 12:01:36 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] processing pairwise key message 2
Fri Sep 13 12:01:36 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] PTK
Fri Sep 13 12:01:36 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] received MIC
Fri Sep 13 12:01:36 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] calculated MIC:
Fri Sep 13 12:01:36 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] PTK state changed from PTKStart to PTKStart
Fri Sep 13 12:01:36 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] Sending PTK Msg1
Fri Sep 13 12:01:36 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] pnacUmiIoctlHandler: cmd: PNAC_PDU_TX(43)
Fri Sep 13 12:01:36 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] pnacUmiIoctlHandler: cmd: PNAC_PDU_RX(44)
Fri Sep 13 12:01:36 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] received a pdu on vap0
Fri Sep 13 12:01:36 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] from pnacRecvMapi: pkt body len = 135, pktType = 3
Fri Sep 13 12:01:36 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] from pnacPDUProcess: received PNAC_EAPOL_KEY_PACKET
Fri Sep 13 12:01:36 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] Recvied DOT11_EAPOL_KEYMSG
Fri Sep 13 12:01:36 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] eapolRecvAuthKeyMsg: received key message
Fri Sep 13 12:01:36 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] processing pairwise key message 2
Fri Sep 13 12:01:36 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] PTK
Fri Sep 13 12:01:36 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] received MIC
Fri Sep 13 12:01:36 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] calculated MIC:
Fri Sep 13 12:01:36 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] PTK state changed from PTKStart to PTKStart
Fri Sep 13 12:01:36 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] Sending PTK Msg1
Fri Sep 13 12:01:36 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] pnacUmiIoctlHandler: cmd: PNAC_PDU_TX(43)
Fri Sep 13 12:01:36 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] pnacUmiIoctlHandler: cmd: PNAC_PDU_RX(44)
Fri Sep 13 12:01:36 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] received a pdu on vap0
Fri Sep 13 12:01:36 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] from pnacRecvMapi: pkt body len = 135, pktType = 3
Fri Sep 13 12:01:36 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] from pnacPDUProcess: received PNAC_EAPOL_KEY_PACKET
Fri Sep 13 12:01:36 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] Recvied DOT11_EAPOL_KEYMSG
Fri Sep 13 12:01:36 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] eapolRecvAuthKeyMsg: received key message
Fri Sep 13 12:01:36 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] processing pairwise key message 2
Fri Sep 13 12:01:36 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] PTK
Fri Sep 13 12:01:36 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] received MIC
Fri Sep 13 12:01:36 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] calculated MIC:
Fri Sep 13 12:01:36 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] PTK state changed from PTKStart to PTKStart
Fri Sep 13 12:01:36 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] Sending PTK Msg1
Fri Sep 13 12:01:36 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] pnacUmiIoctlHandler: cmd: PNAC_PDU_TX(43)
Fri Sep 13 12:01:36 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] pnacUmiIoctlHandler: cmd: PNAC_PDU_RX(44)
Fri Sep 13 12:01:36 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] received a pdu on vap0
Fri Sep 13 12:01:36 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] from pnacRecvMapi: pkt body len = 135, pktType = 3
Fri Sep 13 12:01:36 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] from pnacPDUProcess: received PNAC_EAPOL_KEY_PACKET
Fri Sep 13 12:01:36 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] Recvied DOT11_EAPOL_KEYMSG
Fri Sep 13 12:01:36 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] eapolRecvAuthKeyMsg: received key message
Fri Sep 13 12:01:36 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] processing pairwise key message 2
Fri Sep 13 12:01:36 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] PTK
Fri Sep 13 12:01:36 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] received MIC
Fri Sep 13 12:01:36 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] calculated MIC:
Fri Sep 13 12:01:36 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] PTK state changed from PTKStart to PTKStart
Fri Sep 13 12:01:36 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] Sending PTK Msg1
Fri Sep 13 12:01:36 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] pnacUmiIoctlHandler: cmd: PNAC_PDU_TX(43)
Fri Sep 13 12:01:36 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] PTK state changed from PTKStart to Disconnect
Fri Sep 13 12:01:36 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] PTK state changed from Disconnect to Disconnected
Fri Sep 13 12:01:36 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] pnacUmiIoctlHandler: cmd: PNAC_PORT_BY_MAC_DECONFIG(54)
Fri Sep 13 12:01:36 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] NodeFree request for node : 38:aa:3c:fc:24:9a
Fri Sep 13 12:01:36 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] pnacUmiIoctlHandler: cmd: PNAC_PDU_RX(44)
Fri Sep 13 12:01:36 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] received a pdu on vap0
Fri Sep 13 12:01:36 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] pnacRecvMapi: protoType: 888e pPhyPort->authToASSendRtn:0
Fri Sep 13 12:01:36 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] port not found 
Fri Sep 13 12:01:36 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] could not process PDU received from the wire 
Fri Sep 13 12:01:43 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] join event for new node 38:aa:3c:fc:24:9a
Fri Sep 13 12:01:43 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] WPA:Auth method=WPA-PSK pairwise cipher=CCMP IE size=22
Fri Sep 13 12:01:43 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] pnacUmiIoctlHandler: cmd: PNAC_AUTH_CONFIG(34)
Fri Sep 13 12:01:43 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] PTK state changed from No State to Initialize
Fri Sep 13 12:01:43 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] (node=38:aa:3c:fc:24:9a) setting encryptionEnabled to val = 1
Fri Sep 13 12:01:43 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] pnacUmiIoctlHandler: cmd: PNAC_SET_PARAM(52)
Fri Sep 13 12:01:43 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] (node=38:aa:3c:fc:24:9a) setting portSecure to val = 0
Fri Sep 13 12:01:43 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] pnacUmiIoctlHandler: cmd: PNAC_SET_PARAM(52)
Fri Sep 13 12:01:43 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] (node=38:aa:3c:fc:24:9a) setting portControl to val = 1
Fri Sep 13 12:01:43 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] pnacUmiIoctlHandler: cmd: PNAC_SET_PARAM(52)
Fri Sep 13 12:01:43 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] (node=38:aa:3c:fc:24:9a) setting portStatus to val = 1
Fri Sep 13 12:01:43 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] pnacUmiIoctlHandler: cmd: PNAC_SET_PARAM(52)
Fri Sep 13 12:01:43 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] PTK state changed from Initialize to Authentication
Fri Sep 13 12:01:43 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] (node=38:aa:3c:fc:24:9a) setting portControl to val = 1
Fri Sep 13 12:01:43 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] pnacUmiIoctlHandler: cmd: PNAC_SET_PARAM(52)
Fri Sep 13 12:01:43 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] PTK state changed from Authentication to Authentication2
Fri Sep 13 12:01:43 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] PTK state changed from Authentication2 to InitPSK
Fri Sep 13 12:01:43 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] PMK
Fri Sep 13 12:01:43 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] PTK state changed from InitPSK to PTKStart
Fri Sep 13 12:01:43 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] Sending PTK Msg1
Fri Sep 13 12:01:43 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] pnacUmiIoctlHandler: cmd: PNAC_PDU_TX(43)
Fri Sep 13 12:01:43 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] pnacUmiIoctlHandler: cmd: PNAC_PDU_RX(44)
Fri Sep 13 12:01:43 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] received a pdu on vap0
Fri Sep 13 12:01:43 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] from pnacRecvMapi: pkt body len = 135, pktType = 3
Fri Sep 13 12:01:43 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] from pnacPDUProcess: received PNAC_EAPOL_KEY_PACKET
Fri Sep 13 12:01:43 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] Recvied DOT11_EAPOL_KEYMSG
Fri Sep 13 12:01:43 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] eapolRecvAuthKeyMsg: received key message
Fri Sep 13 12:01:43 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] processing pairwise key message 2
Fri Sep 13 12:01:43 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] PTK
Fri Sep 13 12:01:43 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] received MIC
Fri Sep 13 12:01:43 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] calculated MIC:
Fri Sep 13 12:01:43 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] PTK state changed from PTKStart to PTKStart
Fri Sep 13 12:01:43 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] Sending PTK Msg1
Fri Sep 13 12:01:43 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] pnacUmiIoctlHandler: cmd: PNAC_PDU_TX(43)
Fri Sep 13 12:01:43 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] pnacUmiIoctlHandler: cmd: PNAC_PDU_RX(44)
Fri Sep 13 12:01:43 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] received a pdu on vap0
Fri Sep 13 12:01:43 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] from pnacRecvMapi: pkt body len = 135, pktType = 3
Fri Sep 13 12:01:43 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] from pnacPDUProcess: received PNAC_EAPOL_KEY_PACKET
Fri Sep 13 12:01:43 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] Recvied DOT11_EAPOL_KEYMSG
Fri Sep 13 12:01:43 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] eapolRecvAuthKeyMsg: received key message
Fri Sep 13 12:01:43 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] processing pairwise key message 2
Fri Sep 13 12:01:43 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] PTK
Fri Sep 13 12:01:43 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] received MIC
Fri Sep 13 12:01:43 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] calculated MIC:
Fri Sep 13 12:01:44 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] PTK state changed from PTKStart to PTKStart
Fri Sep 13 12:01:44 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] Sending PTK Msg1
Fri Sep 13 12:01:44 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] pnacUmiIoctlHandler: cmd: PNAC_PDU_TX(43)
Fri Sep 13 12:01:44 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] pnacUmiIoctlHandler: cmd: PNAC_PDU_RX(44)
Fri Sep 13 12:01:44 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] received a pdu on vap0
Fri Sep 13 12:01:44 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] from pnacRecvMapi: pkt body len = 135, pktType = 3
Fri Sep 13 12:01:44 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] from pnacPDUProcess: received PNAC_EAPOL_KEY_PACKET
Fri Sep 13 12:01:44 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] Recvied DOT11_EAPOL_KEYMSG
Fri Sep 13 12:01:44 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] eapolRecvAuthKeyMsg: received key message
Fri Sep 13 12:01:44 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] processing pairwise key message 2
Fri Sep 13 12:01:44 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] PTK
Fri Sep 13 12:01:44 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] received MIC
Fri Sep 13 12:01:44 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] calculated MIC:
Fri Sep 13 12:01:44 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] PTK state changed from PTKStart to PTKStart
Fri Sep 13 12:01:44 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] Sending PTK Msg1
Fri Sep 13 12:01:44 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] pnacUmiIoctlHandler: cmd: PNAC_PDU_TX(43)
Fri Sep 13 12:01:45 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] PTK state changed from PTKStart to PTKStart
Fri Sep 13 12:01:45 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] Sending PTK Msg1
Fri Sep 13 12:01:45 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] pnacUmiIoctlHandler: cmd: PNAC_PDU_TX(43)
Fri Sep 13 12:01:45 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] pnacUmiIoctlHandler: cmd: PNAC_PDU_RX(44)
Fri Sep 13 12:01:45 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] received a pdu on vap0
Fri Sep 13 12:01:45 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] from pnacRecvMapi: pkt body len = 135, pktType = 3
Fri Sep 13 12:01:45 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] from pnacPDUProcess: received PNAC_EAPOL_KEY_PACKET
Fri Sep 13 12:01:45 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] Recvied DOT11_EAPOL_KEYMSG
Fri Sep 13 12:01:45 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] eapolRecvAuthKeyMsg: received key message
Fri Sep 13 12:01:45 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] processing pairwise key message 2
Fri Sep 13 12:01:45 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] PTK
Fri Sep 13 12:01:45 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] received MIC
Fri Sep 13 12:01:45 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] calculated MIC:
Fri Sep 13 12:01:45 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] PTK state changed from PTKStart to PTKStart
Fri Sep 13 12:01:45 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] Sending PTK Msg1
Fri Sep 13 12:01:45 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] pnacUmiIoctlHandler: cmd: PNAC_PDU_TX(43)
Fri Sep 13 12:01:45 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] pnacUmiIoctlHandler: cmd: PNAC_PDU_RX(44)
Fri Sep 13 12:01:45 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] received a pdu on vap0
Fri Sep 13 12:01:45 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] from pnacRecvMapi: pkt body len = 135, pktType = 3
Fri Sep 13 12:01:45 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] from pnacPDUProcess: received PNAC_EAPOL_KEY_PACKET
Fri Sep 13 12:01:45 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] Recvied DOT11_EAPOL_KEYMSG
Fri Sep 13 12:01:45 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] eapolRecvAuthKeyMsg: received key message
Fri Sep 13 12:01:45 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] processing pairwise key message 2
Fri Sep 13 12:01:45 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] PTK
Fri Sep 13 12:01:45 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] received MIC
Fri Sep 13 12:01:45 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] calculated MIC:
Fri Sep 13 12:01:46 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] PTK state changed from PTKStart to PTKStart
Fri Sep 13 12:01:46 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] Sending PTK Msg1
Fri Sep 13 12:01:46 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] pnacUmiIoctlHandler: cmd: PNAC_PDU_TX(43)
Fri Sep 13 12:01:46 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] PTK state changed from PTKStart to PTKStart
Fri Sep 13 12:01:46 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] Sending PTK Msg1
Fri Sep 13 12:01:46 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] pnacUmiIoctlHandler: cmd: PNAC_PDU_TX(43)
Fri Sep 13 12:01:46 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] pnacUmiIoctlHandler: cmd: PNAC_PDU_RX(44)
Fri Sep 13 12:01:46 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] received a pdu on vap0
Fri Sep 13 12:01:46 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] from pnacRecvMapi: pkt body len = 135, pktType = 3
Fri Sep 13 12:01:46 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] from pnacPDUProcess: received PNAC_EAPOL_KEY_PACKET
Fri Sep 13 12:01:46 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] pnacUmiIoctlHandler: cmd: PNAC_PDU_RX(44)
Fri Sep 13 12:01:46 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] received a pdu on vap0
Fri Sep 13 12:01:46 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] from pnacRecvMapi: pkt body len = 135, pktType = 3
Fri Sep 13 12:01:46 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] from pnacPDUProcess: received PNAC_EAPOL_KEY_PACKET
Fri Sep 13 12:01:46 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] Recvied DOT11_EAPOL_KEYMSG
Fri Sep 13 12:01:46 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] eapolRecvAuthKeyMsg: received key message
Fri Sep 13 12:01:46 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] eapolRecvKeyMsg: replay counter is not same 
Fri Sep 13 12:01:46 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] Recvied DOT11_EAPOL_KEYMSG
Fri Sep 13 12:01:46 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] eapolRecvAuthKeyMsg: received key message
Fri Sep 13 12:01:46 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] processing pairwise key message 2
Fri Sep 13 12:01:47 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] PTK
Fri Sep 13 12:01:47 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] received MIC
Fri Sep 13 12:01:47 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] calculated MIC:
Fri Sep 13 12:01:47 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] PTK state changed from PTKStart to PTKStart
Fri Sep 13 12:01:47 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] Sending PTK Msg1
Fri Sep 13 12:01:47 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] pnacUmiIoctlHandler: cmd: PNAC_PDU_TX(43)
Fri Sep 13 12:01:47 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] pnacUmiIoctlHandler: cmd: PNAC_PDU_RX(44)
Fri Sep 13 12:01:47 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] received a pdu on vap0
Fri Sep 13 12:01:47 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] from pnacRecvMapi: pkt body len = 135, pktType = 3
Fri Sep 13 12:01:47 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] from pnacPDUProcess: received PNAC_EAPOL_KEY_PACKET
Fri Sep 13 12:01:47 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] Recvied DOT11_EAPOL_KEYMSG
Fri Sep 13 12:01:47 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] eapolRecvAuthKeyMsg: received key message
Fri Sep 13 12:01:47 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] processing pairwise key message 2
Fri Sep 13 12:01:47 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] PTK
Fri Sep 13 12:01:47 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] received MIC
Fri Sep 13 12:01:47 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] calculated MIC:
Fri Sep 13 12:01:47 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] PTK state changed from PTKStart to PTKStart
Fri Sep 13 12:01:47 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] Sending PTK Msg1
Fri Sep 13 12:01:47 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] pnacUmiIoctlHandler: cmd: PNAC_PDU_TX(43)
Fri Sep 13 12:01:48 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] PTK state changed from PTKStart to PTKStart
Fri Sep 13 12:01:48 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] Sending PTK Msg1
Fri Sep 13 12:01:48 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] pnacUmiIoctlHandler: cmd: PNAC_PDU_TX(43)
Fri Sep 13 12:01:48 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] PTK state changed from PTKStart to Disconnect
Fri Sep 13 12:01:48 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] PTK state changed from Disconnect to Disconnected
Fri Sep 13 12:01:48 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] pnacUmiIoctlHandler: cmd: PNAC_PORT_BY_MAC_DECONFIG(54)
Fri Sep 13 12:01:48 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] NodeFree request for node : 38:aa:3c:fc:24:9a
Fri Sep 13 12:01:48 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] pnacUmiIoctlHandler: cmd: PNAC_PDU_RX(44)
Fri Sep 13 12:01:48 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] received a pdu on vap0
Fri Sep 13 12:01:48 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] pnacRecvMapi: protoType: 888e pPhyPort->authToASSendRtn:0
Fri Sep 13 12:01:48 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] port not found 
Fri Sep 13 12:01:48 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] could not process PDU received from the wire 
Fri Sep 13 12:01:48 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] pnacUmiIoctlHandler: cmd: PNAC_PDU_RX(44)
Fri Sep 13 12:01:48 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] received a pdu on vap0
Fri Sep 13 12:01:48 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] pnacRecvMapi: protoType: 888e pPhyPort->authToASSendRtn:0
Fri Sep 13 12:01:48 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] port not found 
Fri Sep 13 12:01:48 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] could not process PDU received from the wire 
Fri Sep 13 12:01:57 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] join event for new node 38:aa:3c:fc:24:9a
Fri Sep 13 12:01:58 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] WPA:Auth method=WPA-PSK pairwise cipher=CCMP IE size=22
Fri Sep 13 12:01:58 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] pnacUmiIoctlHandler: cmd: PNAC_AUTH_CONFIG(34)
Fri Sep 13 12:01:58 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] PTK state changed from No State to Initialize
Fri Sep 13 12:01:58 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] (node=38:aa:3c:fc:24:9a) setting encryptionEnabled to val = 1
Fri Sep 13 12:01:58 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] pnacUmiIoctlHandler: cmd: PNAC_SET_PARAM(52)
Fri Sep 13 12:01:58 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] (node=38:aa:3c:fc:24:9a) setting portSecure to val = 0
Fri Sep 13 12:01:59 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] pnacUmiIoctlHandler: cmd: PNAC_SET_PARAM(52)
Fri Sep 13 12:01:59 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] (node=38:aa:3c:fc:24:9a) setting portControl to val = 1
Fri Sep 13 12:01:59 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] pnacUmiIoctlHandler: cmd: PNAC_SET_PARAM(52)
Fri Sep 13 12:01:59 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] (node=38:aa:3c:fc:24:9a) setting portStatus to val = 1
Fri Sep 13 12:01:59 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] pnacUmiIoctlHandler: cmd: PNAC_SET_PARAM(52)
Fri Sep 13 12:01:59 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] PTK state changed from Initialize to Authentication
Fri Sep 13 12:01:59 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] (node=38:aa:3c:fc:24:9a) setting portControl to val = 1
Fri Sep 13 12:01:59 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] pnacUmiIoctlHandler: cmd: PNAC_SET_PARAM(52)
Fri Sep 13 12:01:59 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] PTK state changed from Authentication to Authentication2
Fri Sep 13 12:01:59 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] PTK state changed from Authentication2 to InitPSK
Fri Sep 13 12:01:59 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] PMK
Fri Sep 13 12:01:59 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] PTK state changed from InitPSK to PTKStart
Fri Sep 13 12:01:59 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] Sending PTK Msg1
Fri Sep 13 12:01:59 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] pnacUmiIoctlHandler: cmd: PNAC_PDU_TX(43)
Fri Sep 13 12:01:59 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] PTK state changed from PTKStart to PTKStart
Fri Sep 13 12:01:59 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] Sending PTK Msg1
Fri Sep 13 12:01:59 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] pnacUmiIoctlHandler: cmd: PNAC_PDU_TX(43)
Fri Sep 13 12:01:59 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] PTK state changed from PTKStart to PTKStart
Fri Sep 13 12:01:59 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] Sending PTK Msg1
Fri Sep 13 12:01:59 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] pnacUmiIoctlHandler: cmd: PNAC_PDU_TX(43)
Fri Sep 13 12:01:59 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] PTK state changed from PTKStart to PTKStart
Fri Sep 13 12:01:59 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] Sending PTK Msg1
Fri Sep 13 12:01:59 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] pnacUmiIoctlHandler: cmd: PNAC_PDU_TX(43)
Fri Sep 13 12:02:00 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] pnacUmiIoctlHandler: cmd: PNAC_PDU_RX(44)
Fri Sep 13 12:02:00 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] received a pdu on vap0
Fri Sep 13 12:02:00 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] from pnacRecvMapi: pkt body len = 135, pktType = 3
Fri Sep 13 12:02:00 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] from pnacPDUProcess: received PNAC_EAPOL_KEY_PACKET
Fri Sep 13 12:02:00 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] Recvied DOT11_EAPOL_KEYMSG
Fri Sep 13 12:02:00 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] eapolRecvAuthKeyMsg: received key message
Fri Sep 13 12:02:00 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] eapolRecvKeyMsg: replay counter is not same 
Fri Sep 13 12:02:00 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] pnacUmiIoctlHandler: cmd: PNAC_PDU_RX(44)
Fri Sep 13 12:02:00 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] received a pdu on vap0
Fri Sep 13 12:02:00 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] from pnacRecvMapi: pkt body len = 135, pktType = 3
Fri Sep 13 12:02:00 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] from pnacPDUProcess: received PNAC_EAPOL_KEY_PACKET
Fri Sep 13 12:02:00 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] Recvied DOT11_EAPOL_KEYMSG
Fri Sep 13 12:02:00 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] eapolRecvAuthKeyMsg: received key message
Fri Sep 13 12:02:00 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] eapolRecvKeyMsg: replay counter is not same 
Fri Sep 13 12:02:00 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] pnacUmiIoctlHandler: cmd: PNAC_PDU_RX(44)
Fri Sep 13 12:02:00 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] received a pdu on vap0
Fri Sep 13 12:02:00 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] from pnacRecvMapi: pkt body len = 135, pktType = 3
Fri Sep 13 12:02:00 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] from pnacPDUProcess: received PNAC_EAPOL_KEY_PACKET
Fri Sep 13 12:02:00 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] pnacUmiIoctlHandler: cmd: PNAC_PDU_RX(44)
Fri Sep 13 12:02:00 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] received a pdu on vap0
Fri Sep 13 12:02:00 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] from pnacRecvMapi: pkt body len = 135, pktType = 3
Fri Sep 13 12:02:00 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] from pnacPDUProcess: received PNAC_EAPOL_KEY_PACKET
Fri Sep 13 12:02:00 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] Recvied DOT11_EAPOL_KEYMSG
Fri Sep 13 12:02:00 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] eapolRecvAuthKeyMsg: received key message
Fri Sep 13 12:02:00 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] eapolRecvKeyMsg: replay counter is not same 
Fri Sep 13 12:02:00 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] Recvied DOT11_EAPOL_KEYMSG
Fri Sep 13 12:02:00 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] eapolRecvAuthKeyMsg: received key message
Fri Sep 13 12:02:00 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] processing pairwise key message 2
Fri Sep 13 12:02:00 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] PTK
Fri Sep 13 12:02:00 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] received MIC
Fri Sep 13 12:02:00 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] calculated MIC:
Fri Sep 13 12:02:00 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] PTK state changed from PTKStart to PTKStart
Fri Sep 13 12:02:00 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] Sending PTK Msg1
Fri Sep 13 12:02:00 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] pnacUmiIoctlHandler: cmd: PNAC_PDU_TX(43)
Fri Sep 13 12:02:00 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] PTK state changed from PTKStart to PTKStart
Fri Sep 13 12:02:00 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] Sending PTK Msg1
Fri Sep 13 12:02:00 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] pnacUmiIoctlHandler: cmd: PNAC_PDU_TX(43)
Fri Sep 13 12:02:00 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] pnacUmiIoctlHandler: cmd: PNAC_PDU_RX(44)
Fri Sep 13 12:02:00 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] received a pdu on vap0
Fri Sep 13 12:02:01 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] from pnacRecvMapi: pkt body len = 135, pktType = 3
Fri Sep 13 12:02:01 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] from pnacPDUProcess: received PNAC_EAPOL_KEY_PACKET
Fri Sep 13 12:02:01 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] Recvied DOT11_EAPOL_KEYMSG
Fri Sep 13 12:02:01 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] eapolRecvAuthKeyMsg: received key message
Fri Sep 13 12:02:01 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] eapolRecvKeyMsg: replay counter is not same 
Fri Sep 13 12:02:01 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] pnacUmiIoctlHandler: cmd: PNAC_PDU_RX(44)
Fri Sep 13 12:02:01 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] received a pdu on vap0
Fri Sep 13 12:02:01 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] from pnacRecvMapi: pkt body len = 135, pktType = 3
Fri Sep 13 12:02:01 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] from pnacPDUProcess: received PNAC_EAPOL_KEY_PACKET
Fri Sep 13 12:02:01 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] Recvied DOT11_EAPOL_KEYMSG
Fri Sep 13 12:02:01 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] eapolRecvAuthKeyMsg: received key message
Fri Sep 13 12:02:01 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] processing pairwise key message 2
Fri Sep 13 12:02:01 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] PTK
Fri Sep 13 12:02:01 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] received MIC
Fri Sep 13 12:02:01 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] calculated MIC:
Fri Sep 13 12:02:01 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] PTK state changed from PTKStart to PTKStart
Fri Sep 13 12:02:01 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] Sending PTK Msg1
Fri Sep 13 12:02:01 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] pnacUmiIoctlHandler: cmd: PNAC_PDU_TX(43)
Fri Sep 13 12:02:01 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] pnacUmiIoctlHandler: cmd: PNAC_PDU_RX(44)
Fri Sep 13 12:02:01 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] received a pdu on vap0
Fri Sep 13 12:02:01 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] from pnacRecvMapi: pkt body len = 135, pktType = 3
Fri Sep 13 12:02:02 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] from pnacPDUProcess: received PNAC_EAPOL_KEY_PACKET
Fri Sep 13 12:02:02 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] Recvied DOT11_EAPOL_KEYMSG
Fri Sep 13 12:02:02 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] eapolRecvAuthKeyMsg: received key message
Fri Sep 13 12:02:02 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] processing pairwise key message 2
Fri Sep 13 12:02:02 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] PTK
Fri Sep 13 12:02:02 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] received MIC
Fri Sep 13 12:02:02 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] calculated MIC:
Fri Sep 13 12:02:02 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] PTK state changed from PTKStart to PTKStart
Fri Sep 13 12:02:02 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] Sending PTK Msg1
Fri Sep 13 12:02:02 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] pnacUmiIoctlHandler: cmd: PNAC_PDU_TX(43)
Fri Sep 13 12:02:02 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] pnacUmiIoctlHandler: cmd: PNAC_PDU_RX(44)
Fri Sep 13 12:02:02 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] received a pdu on vap0
Fri Sep 13 12:02:02 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] from pnacRecvMapi: pkt body len = 135, pktType = 3
Fri Sep 13 12:02:02 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] from pnacPDUProcess: received PNAC_EAPOL_KEY_PACKET
Fri Sep 13 12:02:02 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] Recvied DOT11_EAPOL_KEYMSG
Fri Sep 13 12:02:02 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] eapolRecvAuthKeyMsg: received key message
Fri Sep 13 12:02:02 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] processing pairwise key message 2
Fri Sep 13 12:02:03 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] PTK
Fri Sep 13 12:02:03 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] received MIC
Fri Sep 13 12:02:03 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] calculated MIC:
Fri Sep 13 12:02:03 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] PTK state changed from PTKStart to PTKStart
Fri Sep 13 12:02:04 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] Sending PTK Msg1
Fri Sep 13 12:02:04 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] pnacUmiIoctlHandler: cmd: PNAC_PDU_TX(43)
Fri Sep 13 12:02:04 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] pnacUmiIoctlHandler: cmd: PNAC_PDU_RX(44)
Fri Sep 13 12:02:04 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] received a pdu on vap0
Fri Sep 13 12:02:04 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] from pnacRecvMapi: pkt body len = 135, pktType = 3
Fri Sep 13 12:02:04 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] from pnacPDUProcess: received PNAC_EAPOL_KEY_PACKET
Fri Sep 13 12:02:04 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] Recvied DOT11_EAPOL_KEYMSG
Fri Sep 13 12:02:04 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] eapolRecvAuthKeyMsg: received key message
Fri Sep 13 12:02:04 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] processing pairwise key message 2
Fri Sep 13 12:02:04 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] PTK
Fri Sep 13 12:02:04 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] received MIC
Fri Sep 13 12:02:04 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] calculated MIC:
Fri Sep 13 12:02:04 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] PTK state changed from PTKStart to PTKStart
Fri Sep 13 12:02:04 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] Sending PTK Msg1
Fri Sep 13 12:02:04 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] pnacUmiIoctlHandler: cmd: PNAC_PDU_TX(43)
Fri Sep 13 12:02:04 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] pnacUmiIoctlHandler: cmd: PNAC_PDU_RX(44)
Fri Sep 13 12:02:04 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] received a pdu on vap0
Fri Sep 13 12:02:04 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] from pnacRecvMapi: pkt body len = 135, pktType = 3
Fri Sep 13 12:02:05 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] from pnacPDUProcess: received PNAC_EAPOL_KEY_PACKET
Fri Sep 13 12:02:05 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] Recvied DOT11_EAPOL_KEYMSG
Fri Sep 13 12:02:06 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] eapolRecvAuthKeyMsg: received key message
Fri Sep 13 12:02:06 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] processing pairwise key message 2
Fri Sep 13 12:02:06 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] PTK
Fri Sep 13 12:02:07 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] received MIC
Fri Sep 13 12:02:07 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] calculated MIC:
Fri Sep 13 12:02:07 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] PTK state changed from PTKStart to PTKStart
Fri Sep 13 12:02:07 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] Sending PTK Msg1
Fri Sep 13 12:02:07 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] pnacUmiIoctlHandler: cmd: PNAC_PDU_TX(43)
Fri Sep 13 12:02:07 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] PTK state changed from PTKStart to Disconnect
Fri Sep 13 12:02:07 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] PTK state changed from Disconnect to Disconnected
Fri Sep 13 12:02:07 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] pnacUmiIoctlHandler: cmd: PNAC_PORT_BY_MAC_DECONFIG(54)
Fri Sep 13 12:02:07 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] pnacUmiIoctlHandler: cmd: PNAC_PDU_RX(44)
Fri Sep 13 12:02:07 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] received a pdu on vap0
Fri Sep 13 12:02:07 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] pnacRecvMapi: protoType: 888e pPhyPort->authToASSendRtn:0
Fri Sep 13 12:02:07 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] port not found 
Fri Sep 13 12:02:07 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] could not process PDU received from the wire 
Fri Sep 13 12:02:08 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] NodeFree request for node : 38:aa:3c:fc:24:9a
Fri Sep 13 12:02:20 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] join event for new node 38:aa:3c:fc:24:9a
Fri Sep 13 12:02:20 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] WPA:Auth method=WPA-PSK pairwise cipher=CCMP IE size=22
Fri Sep 13 12:02:20 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] pnacUmiIoctlHandler: cmd: PNAC_AUTH_CONFIG(34)
Fri Sep 13 12:02:21 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] PTK state changed from No State to Initialize
Fri Sep 13 12:02:21 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] (node=38:aa:3c:fc:24:9a) setting encryptionEnabled to val = 1
Fri Sep 13 12:02:21 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] pnacUmiIoctlHandler: cmd: PNAC_SET_PARAM(52)
Fri Sep 13 12:02:21 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] (node=38:aa:3c:fc:24:9a) setting portSecure to val = 0
Fri Sep 13 12:02:21 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] pnacUmiIoctlHandler: cmd: PNAC_SET_PARAM(52)
Fri Sep 13 12:02:21 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] (node=38:aa:3c:fc:24:9a) setting portControl to val = 1
Fri Sep 13 12:02:21 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] pnacUmiIoctlHandler: cmd: PNAC_SET_PARAM(52)
Fri Sep 13 12:02:21 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] (node=38:aa:3c:fc:24:9a) setting portStatus to val = 1
Fri Sep 13 12:02:21 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] pnacUmiIoctlHandler: cmd: PNAC_SET_PARAM(52)
Fri Sep 13 12:02:21 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] PTK state changed from Initialize to Authentication
Fri Sep 13 12:02:21 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] (node=38:aa:3c:fc:24:9a) setting portControl to val = 1
Fri Sep 13 12:02:21 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] pnacUmiIoctlHandler: cmd: PNAC_SET_PARAM(52)
Fri Sep 13 12:02:21 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] PTK state changed from Authentication to Authentication2
Fri Sep 13 12:02:21 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] PTK state changed from Authentication2 to InitPSK
Fri Sep 13 12:02:21 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] PMK
Fri Sep 13 12:02:21 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] PTK state changed from InitPSK to PTKStart
Fri Sep 13 12:02:21 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] Sending PTK Msg1
Fri Sep 13 12:02:21 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] pnacUmiIoctlHandler: cmd: PNAC_PDU_TX(43)
Fri Sep 13 12:02:21 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] pnacUmiIoctlHandler: cmd: PNAC_PDU_RX(44)
Fri Sep 13 12:02:21 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] received a pdu on vap0
Fri Sep 13 12:02:21 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] from pnacRecvMapi: pkt body len = 135, pktType = 3
Fri Sep 13 12:02:21 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] from pnacPDUProcess: received PNAC_EAPOL_KEY_PACKET
Fri Sep 13 12:02:21 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] Recvied DOT11_EAPOL_KEYMSG
Fri Sep 13 12:02:21 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] eapolRecvAuthKeyMsg: received key message
Fri Sep 13 12:02:21 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] processing pairwise key message 2
Fri Sep 13 12:02:21 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] PTK
Fri Sep 13 12:02:21 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] received MIC
Fri Sep 13 12:02:22 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] calculated MIC:
Fri Sep 13 12:02:22 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] PTK state changed from PTKStart to PTKStart
Fri Sep 13 12:02:22 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] Sending PTK Msg1
Fri Sep 13 12:02:22 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] pnacUmiIoctlHandler: cmd: PNAC_PDU_TX(43)
Fri Sep 13 12:02:22 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] pnacUmiIoctlHandler: cmd: PNAC_PDU_RX(44)
Fri Sep 13 12:02:22 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] received a pdu on vap0
Fri Sep 13 12:02:22 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] from pnacRecvMapi: pkt body len = 135, pktType = 3
Fri Sep 13 12:02:22 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] from pnacPDUProcess: received PNAC_EAPOL_KEY_PACKET
Fri Sep 13 12:02:22 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] Recvied DOT11_EAPOL_KEYMSG
Fri Sep 13 12:02:22 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] eapolRecvAuthKeyMsg: received key message
Fri Sep 13 12:02:22 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] processing pairwise key message 2
Fri Sep 13 12:02:22 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] PTK
Fri Sep 13 12:02:22 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] received MIC
Fri Sep 13 12:02:22 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] calculated MIC:
Fri Sep 13 12:02:22 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] PTK state changed from PTKStart to PTKStart
Fri Sep 13 12:02:22 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] Sending PTK Msg1
Fri Sep 13 12:02:22 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] pnacUmiIoctlHandler: cmd: PNAC_PDU_TX(43)
Fri Sep 13 12:02:22 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] pnacUmiIoctlHandler: cmd: PNAC_PDU_RX(44)
Fri Sep 13 12:02:22 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] received a pdu on vap0
Fri Sep 13 12:02:22 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] from pnacRecvMapi: pkt body len = 135, pktType = 3
Fri Sep 13 12:02:22 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] from pnacPDUProcess: received PNAC_EAPOL_KEY_PACKET
Fri Sep 13 12:02:22 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] Recvied DOT11_EAPOL_KEYMSG
Fri Sep 13 12:02:22 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] eapolRecvAuthKeyMsg: received key message
Fri Sep 13 12:02:22 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] processing pairwise key message 2
Fri Sep 13 12:02:23 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] PTK
Fri Sep 13 12:02:23 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] received MIC
Fri Sep 13 12:02:23 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] calculated MIC:
Fri Sep 13 12:02:23 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] PTK state changed from PTKStart to PTKStart
Fri Sep 13 12:02:23 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] Sending PTK Msg1
Fri Sep 13 12:02:23 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] pnacUmiIoctlHandler: cmd: PNAC_PDU_TX(43)
Fri Sep 13 12:02:23 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] pnacUmiIoctlHandler: cmd: PNAC_PDU_RX(44)
Fri Sep 13 12:02:23 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] received a pdu on vap0
Fri Sep 13 12:02:23 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] from pnacRecvMapi: pkt body len = 135, pktType = 3
Fri Sep 13 12:02:23 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] from pnacPDUProcess: received PNAC_EAPOL_KEY_PACKET
Fri Sep 13 12:02:23 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] Recvied DOT11_EAPOL_KEYMSG
Fri Sep 13 12:02:23 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] eapolRecvAuthKeyMsg: received key message
Fri Sep 13 12:02:23 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] processing pairwise key message 2
Fri Sep 13 12:02:23 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] PTK
Fri Sep 13 12:02:23 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] received MIC
Fri Sep 13 12:02:23 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] calculated MIC:
Fri Sep 13 12:02:23 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] PTK state changed from PTKStart to PTKStart
Fri Sep 13 12:02:23 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] Sending PTK Msg1
Fri Sep 13 12:02:23 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] pnacUmiIoctlHandler: cmd: PNAC_PDU_TX(43)
Fri Sep 13 12:02:24 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] pnacUmiIoctlHandler: cmd: PNAC_PDU_RX(44)
Fri Sep 13 12:02:24 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] received a pdu on vap0
Fri Sep 13 12:02:24 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] from pnacRecvMapi: pkt body len = 135, pktType = 3
Fri Sep 13 12:02:24 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] from pnacPDUProcess: received PNAC_EAPOL_KEY_PACKET
Fri Sep 13 12:02:24 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] Recvied DOT11_EAPOL_KEYMSG
Fri Sep 13 12:02:24 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] eapolRecvAuthKeyMsg: received key message
Fri Sep 13 12:02:24 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] processing pairwise key message 2
Fri Sep 13 12:02:24 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] PTK
Fri Sep 13 12:02:24 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] received MIC
Fri Sep 13 12:02:24 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] calculated MIC:
Fri Sep 13 12:02:24 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] PTK state changed from PTKStart to PTKStart
Fri Sep 13 12:02:24 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] Sending PTK Msg1
Fri Sep 13 12:02:24 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] pnacUmiIoctlHandler: cmd: PNAC_PDU_TX(43)
Fri Sep 13 12:02:24 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] pnacUmiIoctlHandler: cmd: PNAC_PDU_RX(44)
Fri Sep 13 12:02:24 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] received a pdu on vap0
Fri Sep 13 12:02:24 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] from pnacRecvMapi: pkt body len = 135, pktType = 3
Fri Sep 13 12:02:24 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] from pnacPDUProcess: received PNAC_EAPOL_KEY_PACKET
Fri Sep 13 12:02:24 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] Recvied DOT11_EAPOL_KEYMSG
Fri Sep 13 12:02:24 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] eapolRecvAuthKeyMsg: received key message
Fri Sep 13 12:02:24 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] processing pairwise key message 2
Fri Sep 13 12:02:25 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] PTK
Fri Sep 13 12:02:25 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] received MIC
Fri Sep 13 12:02:25 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] calculated MIC:
Fri Sep 13 12:02:25 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] PTK state changed from PTKStart to PTKStart
Fri Sep 13 12:02:25 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] Sending PTK Msg1
Fri Sep 13 12:02:25 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] pnacUmiIoctlHandler: cmd: PNAC_PDU_TX(43)
Fri Sep 13 12:02:25 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] pnacUmiIoctlHandler: cmd: PNAC_PDU_RX(44)
Fri Sep 13 12:02:25 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] received a pdu on vap0
Fri Sep 13 12:02:25 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] from pnacRecvMapi: pkt body len = 135, pktType = 3
Fri Sep 13 12:02:25 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] from pnacPDUProcess: received PNAC_EAPOL_KEY_PACKET
Fri Sep 13 12:02:25 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] Recvied DOT11_EAPOL_KEYMSG
Fri Sep 13 12:02:25 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] eapolRecvAuthKeyMsg: received key message
Fri Sep 13 12:02:25 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] processing pairwise key message 2
Fri Sep 13 12:02:25 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] PTK
Fri Sep 13 12:02:25 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] received MIC
Fri Sep 13 12:02:25 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] calculated MIC:
Fri Sep 13 12:02:25 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] PTK state changed from PTKStart to PTKStart
Fri Sep 13 12:02:25 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] Sending PTK Msg1
Fri Sep 13 12:02:25 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] pnacUmiIoctlHandler: cmd: PNAC_PDU_TX(43)
Fri Sep 13 12:02:26 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] pnacUmiIoctlHandler: cmd: PNAC_PDU_RX(44)
Fri Sep 13 12:02:26 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] received a pdu on vap0
Fri Sep 13 12:02:26 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] from pnacRecvMapi: pkt body len = 135, pktType = 3
Fri Sep 13 12:02:26 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] from pnacPDUProcess: received PNAC_EAPOL_KEY_PACKET
Fri Sep 13 12:02:26 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] Recvied DOT11_EAPOL_KEYMSG
Fri Sep 13 12:02:26 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] eapolRecvAuthKeyMsg: received key message
Fri Sep 13 12:02:26 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] processing pairwise key message 2
Fri Sep 13 12:02:26 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] PTK
Fri Sep 13 12:02:26 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] received MIC
Fri Sep 13 12:02:26 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] calculated MIC:
Fri Sep 13 12:02:26 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] PTK state changed from PTKStart to PTKStart
Fri Sep 13 12:02:26 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] Sending PTK Msg1
Fri Sep 13 12:02:26 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] pnacUmiIoctlHandler: cmd: PNAC_PDU_TX(43)
Fri Sep 13 12:02:26 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] pnacUmiIoctlHandler: cmd: PNAC_PDU_RX(44)
Fri Sep 13 12:02:26 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] received a pdu on vap0
Fri Sep 13 12:02:26 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] from pnacRecvMapi: pkt body len = 135, pktType = 3
Fri Sep 13 12:02:26 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] from pnacPDUProcess: received PNAC_EAPOL_KEY_PACKET
Fri Sep 13 12:02:26 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] Recvied DOT11_EAPOL_KEYMSG
Fri Sep 13 12:02:26 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] eapolRecvAuthKeyMsg: received key message
Fri Sep 13 12:02:26 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] processing pairwise key message 2
Fri Sep 13 12:02:27 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] PTK
Fri Sep 13 12:02:27 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] received MIC
Fri Sep 13 12:02:27 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] calculated MIC:
Fri Sep 13 12:02:27 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] PTK state changed from PTKStart to PTKStart
Fri Sep 13 12:02:27 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] Sending PTK Msg1
Fri Sep 13 12:02:27 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] pnacUmiIoctlHandler: cmd: PNAC_PDU_TX(43)
Fri Sep 13 12:02:27 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] pnacUmiIoctlHandler: cmd: PNAC_PDU_RX(44)
Fri Sep 13 12:02:27 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] received a pdu on vap0
Fri Sep 13 12:02:27 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] from pnacRecvMapi: pkt body len = 135, pktType = 3
Fri Sep 13 12:02:27 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] from pnacPDUProcess: received PNAC_EAPOL_KEY_PACKET
Fri Sep 13 12:02:27 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] Recvied DOT11_EAPOL_KEYMSG
Fri Sep 13 12:02:27 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] eapolRecvAuthKeyMsg: received key message
Fri Sep 13 12:02:27 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] processing pairwise key message 2
Fri Sep 13 12:02:27 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] PTK
Fri Sep 13 12:02:27 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] received MIC
Fri Sep 13 12:02:27 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] calculated MIC:
Fri Sep 13 12:02:27 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] PTK state changed from PTKStart to PTKStart
Fri Sep 13 12:02:27 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] Sending PTK Msg1
Fri Sep 13 12:02:27 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] pnacUmiIoctlHandler: cmd: PNAC_PDU_TX(43)
Fri Sep 13 12:02:27 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] PTK state changed from PTKStart to Disconnect
Fri Sep 13 12:02:27 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] PTK state changed from Disconnect to Disconnected
Fri Sep 13 12:02:28 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] pnacUmiIoctlHandler: cmd: PNAC_PORT_BY_MAC_DECONFIG(54)
Fri Sep 13 12:02:28 2013(GMT-0500) [rv220w][Local0-wireless][UDOT11] NodeFree request for node : 38:aa:3c:fc:24:9a
Fri Sep 13 12:02:28 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] pnacUmiIoctlHandler: cmd: PNAC_PDU_RX(44)
Fri Sep 13 12:02:28 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] received a pdu on vap0
Fri Sep 13 12:02:28 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] pnacRecvMapi: protoType: 888e pPhyPort->authToASSendRtn:0
Fri Sep 13 12:02:28 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] port not found 
Fri Sep 13 12:02:28 2013(GMT-0500) [rv220w][Local0-wireless][PNAC_USR] could not process PDU received from the wire

Any Help will be appreciated.

jeffrrod · ‎09-16-2013

Dear Danny,

Thank you for reaching the Small Business Support Community.

You mention to have the latest firmware release v1.0.4.17, was the same thing happening prior the upgrade? Something we can try is a MTU size test;

http://www6.nohold.net/Cisco2/ukp.aspx?pid=80&vw=1&articleid=386

Besides that we can check on the IPsec policies, if any, and try changing the mode from "Aggressive" to "Main" and use a smaller authentication and DH.

You may also contact the Small Business Support Center if you'd like to have a case opened to make sure we fix this problem as soon as possible,

https://www.cisco.com/en/US/support/tsd_cisco_small_business_support_center_contacts.html

Please do not hesitate to reach me back if there is anything else I may assist you with.

Kind regards,

Jeffrey Rodriguez S. .:|:.:|:.
Cisco Customer Support Engineer

*Please rate the Post so other will know when an answer has been found.

Jeffrey Rodriguez S. .:|:.:|:. Cisco Customer Support Engineer *Please rate the Post so other will know when an answer has been found.

Danny Yehya · ‎09-20-2013

Hey Jeffrey,

I tried looking at the MTU and am not using IPsec or any VPN at this time. However, whether pinging internally or externally I have an MTU of 1300 even though the router is set to 1500 in the WAN settings. I do not believe this has anything to do with my ISP as pinging the router has the same issue.

Please advise.

Thanks.