Although I've seen multiple cases of clients becoming stuck in "DHCP REQD," in my case, it appears from the debug output that there have been no discovery or BOOTREQUEST from the client (which should be?).
We are using 8.0.152.0, I want to find out if this behavior is due to a bug. Or is there a problem with APs? or do certain parameters need to be checked? Thanks
*apfReceiveTask: Jul 05 20:44:41.675: 00:12:f3:41:64:e4 0.0.0.0 START (0) Initializing policy
*apfReceiveTask: Jul 05 20:44:41.675: 00:12:f3:41:64:e4 0.0.0.0 START (0) Change state to AUTHCHECK (2) last state START (0)
*apfReceiveTask: Jul 05 20:44:41.675: 00:12:f3:41:64:e4 0.0.0.0 AUTHCHECK (2) Change state to L2AUTHCOMPLETE (4) last state AUTHCHECK (2)
*apfReceiveTask: Jul 05 20:44:41.676: 00:12:f3:41:64:e4 Not Using WMM Compliance code qosCap 00
*apfReceiveTask: Jul 05 20:44:41.676: 00:12:f3:41:64:e4 Vlan while overriding the policy = -1
*apfReceiveTask: Jul 05 20:44:41.676: 00:12:f3:41:64:e4 sending to spamAddMobile vlanId -1 aclName = , flexAclId 65535
*apfReceiveTask: Jul 05 20:44:41.676: 00:12:f3:41:64:e4 0.0.0.0 L2AUTHCOMPLETE (4) Plumbed mobile LWAPP rule on AP 00:3a:9a:68:e0:50 vapId 26 apVapId 2 flex-acl-name:
*apfReceiveTask: Jul 05 20:44:41.676: 00:12:f3:41:64:e4 0.0.0.0 L2AUTHCOMPLETE (4) Change state to DHCP_REQD (7) last state L2AUTHCOMPLETE (4)
*apfReceiveTask: Jul 05 20:44:41.676: 00:12:f3:41:64:e4 apfMsAssoStateInc
*apfReceiveTask: Jul 05 20:44:41.676: 00:12:f3:41:64:e4 apfPemAddUser2 (apf_policy.c:353) Changing state for mobile 00:12:f3:41:64:e4 on AP 00:3a:9a:68:e0:50 from AAA Pending to Associated
*apfReceiveTask: Jul 05 20:44:41.676: 00:12:f3:41:64:e4 apfPemAddUser2:session timeout forstation 00:12:f3:41:64:e4 - Session Tout 1800, apfMsTimeOut '1800' and sessionTimerRunning flag is 0
*apfReceiveTask: Jul 05 20:44:41.676: 00:12:f3:41:64:e4 Mscb del, deleteImm:no, MS State:3, reasoncode:4 ANCHOR ROLE=0
*apfReceiveTask: Jul 05 20:44:41.676: 00:12:f3:41:64:e4 Scheduling deletion of Mobile Station: (callerId: 49) in 1800 seconds
*apfReceiveTask: Jul 05 20:44:41.676: 00:12:f3:41:64:e4 Func: apfPemAddUser2, Ms Timeout = 1800, Session Timeout = 1800
*apfReceiveTask: Jul 05 20:44:41.676: 00:12:f3:41:64:e4 Sending assoc-resp with status 0 station:00:12:f3:41:64:e4 AP:00:3a:9a:68:e0:50-00 on apVapId 2
*apfReceiveTask: Jul 05 20:44:41.676: 00:12:f3:41:64:e4 Sending Assoc Response to station on BSSID 00:3a:9a:68:e0:51 (status 0) ApVapId 2 Slot 0
*apfReceiveTask: Jul 05 20:44:41.676: 00:12:f3:41:64:e4 apfProcessRadiusAssocResp (apf_80211.c:4012) Changing state for mobile 00:12:f3:41:64:e4 on AP 00:3a:9a:68:e0:50 from Associated to Associated
*apfReceiveTask: Jul 05 20:44:41.676: 00:12:f3:41:64:e4 Mobility query, PEM State: DHCP_REQD
*apfReceiveTask: Jul 05 20:44:41.676: 00:12:f3:41:64:e4 Building Mobile Announce :
*apfReceiveTask: Jul 05 20:44:41.676: 00:12:f3:41:64:e4 Building Client Payload:
*apfReceiveTask: Jul 05 20:44:41.676: 00:12:f3:41:64:e4 Client Ip: 0.0.0.0
*apfReceiveTask: Jul 05 20:44:41.676: 00:12:f3:41:64:e4 Client Vlan Ip: 192.168.0.10, Vlan mask : 255.255.240.0
*apfReceiveTask: Jul 05 20:44:41.676: 00:12:f3:41:64:e4 Client Vap Security: 262208
*apfReceiveTask: Jul 05 20:44:41.676: 00:12:f3:41:64:e4 Virtual Ip: 1.1.1.1
*apfReceiveTask: Jul 05 20:44:41.676: 00:12:f3:41:64:e4 ssid: adient-guest
*apfReceiveTask: Jul 05 20:44:41.677: 00:12:f3:41:64:e4 Building VlanIpPayload.
*spamApTask3: Jul 05 20:44:41.677: 00:12:f3:41:64:e4 Successful transmission of LWAPP Add-Mobile to AP 00:3a:9a:68:e0:50
*apfReceiveTask: Jul 05 20:44:43.847: 00:12:f3:41:64:9b 0.0.0.0 DHCP_REQD (7) mobility role update request from Unassociated to Local
Peer = 0.0.0.0, Old Anchor = 0.0.0.0, New Anchor = 172.26.228.62
*apfReceiveTask: Jul 05 20:44:43.848: 00:12:f3:41:64:9b 0.0.0.0 DHCP_REQD (7) State Update from Mobility-Incomplete to Mobility-Complete, mobility role=Local, client state=APF_MS_STATE_ASSOCIATED
*apfReceiveTask: Jul 05 20:44:43.848: 00:12:f3:41:64:9b 0.0.0.0 DHCP_REQD (7) pemAdvanceState2 6227, Adding TMP rule
*apfReceiveTask: Jul 05 20:44:43.848: 00:12:f3:41:64:9b 0.0.0.0 DHCP_REQD (7) Adding Fast Path rule
type = Airespace AP - Learn IP address
on AP 00:3a:9a:68:e0:50, slot 0, interface = 13, QOS = 0
IPv4 ACL ID = 255, IP
*apfReceiveTask: Jul 05 20:44:43.848: 00:12:f3:41:64:9b 0.0.0.0 DHCP_REQD (7) Fast Path rule (contd...) 802.1P = 0, DSCP = 0, TokenID = 15206, IntfId = 15 Local Bridging Vlan = 689, Local Bridging intf id = 15
*apfReceiveTask: Jul 05 20:44:43.848: 00:12:f3:41:64:9b 0.0.0.0 DHCP_REQD (7) Fast Path rule (contd...) AVC Ratelimit: AppID = 0 ,AppAction = 0, AppToken = 15206 AverageRate = 0, BurstRate = 0
*apfReceiveTask: Jul 05 20:44:43.848: 00:12:f3:41:64:9b 0.0.0.0 DHCP_REQD (7) Fast Path rule (contd...) AVC Ratelimit: AppID = 0 ,AppAction = 0, AppToken = 15206 AverageRate = 0, BurstRate = 0
*apfReceiveTask: Jul 05 20:44:43.848: 00:12:f3:41:64:9b 0.0.0.0 DHCP_REQD (7) Fast Path rule (contd...) AVC Ratelimit: AppID = 0 ,AppAction = 0, AppToken = 15206 AverageRate = 0, BurstRate = 0
*apfReceiveTask: Jul 05 20:44:43.848: 00:12:f3:41:64:9b 0.0.0.0 DHCP_REQD (7) Successfully plumbed mobile rule (IPv4 ACL ID 255, IPv6 ACL ID 255, L2 ACL ID 255)
*pemReceiveTask: Jul 05 20:44:43.848: 00:12:f3:41:64:9b 0.0.0.0 Added NPU entry of type 9, dtlFlags 0x0
*pemReceiveTask: Jul 05 20:44:43.848: 00:12:f3:41:64:9b Sent an XID frame
*apfReceiveTask: Jul 05 20:44:44.647: 00:12:f3:41:64:e4 0.0.0.0 DHCP_REQD (7) mobility role update request from Unassociated to Local
Peer = 0.0.0.0, Old Anchor = 0.0.0.0, New Anchor = 172.26.228.62
*apfReceiveTask: Jul 05 20:44:44.647: 00:12:f3:41:64:e4 0.0.0.0 DHCP_REQD (7) State Update from Mobility-Incomplete to Mobility-Complete, mobility role=Local, client state=APF_MS_STATE_ASSOCIATED
*apfReceiveTask: Jul 05 20:44:44.647: 00:12:f3:41:64:e4 0.0.0.0 DHCP_REQD (7) pemAdvanceState2 6227, Adding TMP rule
*apfReceiveTask: Jul 05 20:44:44.648: 00:12:f3:41:64:e4 0.0.0.0 DHCP_REQD (7) Adding Fast Path rule
type = Airespace AP - Learn IP address
on AP 00:3a:9a:68:e0:50, slot 0, interface = 13, QOS = 0
IPv4 ACL ID = 255, IP
*apfReceiveTask: Jul 05 20:44:44.648: 00:12:f3:41:64:e4 0.0.0.0 DHCP_REQD (7) Fast Path rule (contd...) 802.1P = 0, DSCP = 0, TokenID = 15206, IntfId = 15 Local Bridging Vlan = 689, Local Bridging intf id = 15
*apfReceiveTask: Jul 05 20:44:44.648: 00:12:f3:41:64:e4 0.0.0.0 DHCP_REQD (7) Fast Path rule (contd...) AVC Ratelimit: AppID = 0 ,AppAction = 0, AppToken = 15206 AverageRate = 0, BurstRate = 0
*apfReceiveTask: Jul 05 20:44:44.648: 00:12:f3:41:64:e4 0.0.0.0 DHCP_REQD (7) Fast Path rule (contd...) AVC Ratelimit: AppID = 0 ,AppAction = 0, AppToken = 15206 AverageRate = 0, BurstRate = 0
*apfReceiveTask: Jul 05 20:44:44.648: 00:12:f3:41:64:e4 0.0.0.0 DHCP_REQD (7) Fast Path rule (contd...) AVC Ratelimit: AppID = 0 ,AppAction = 0, AppToken = 15206 AverageRate = 0, BurstRate = 0
*apfReceiveTask: Jul 05 20:44:44.648: 00:12:f3:41:64:e4 0.0.0.0 DHCP_REQD (7) Successfully plumbed mobile rule (IPv4 ACL ID 255, IPv6 ACL ID 255, L2 ACL ID 255)
*pemReceiveTask: Jul 05 20:44:44.648: 00:12:f3:41:64:e4 0.0.0.0 Added NPU entry of type 9, dtlFlags 0x0
*pemReceiveTask: Jul 05 20:44:44.648: 00:12:f3:41:64:e4 Sent an XID frame
*apfReceiveTask: Jul 05 20:46:40.847: 00:12:f3:41:64:9b 0.0.0.0 DHCP_REQD (7) DHCP Policy timeout. Number of DHCP Discover 0, DHCP Request 0 from client
*apfReceiveTask: Jul 05 20:46:40.847: 00:12:f3:41:64:9b Interface Group was NULL.Number of DHCP Discovery 0 from client
*apfReceiveTask: Jul 05 20:46:40.847: 00:12:f3:41:64:9b 0.0.0.0 DHCP_REQD (7) Pem timed out, Try to delete client in 10 secs.
*apfReceiveTask: Jul 05 20:46:40.847: 00:12:f3:41:64:9b Mscb del, deleteImm:no, MS State:3, reasoncode:4 ANCHOR ROLE=0
*apfReceiveTask: Jul 05 20:46:40.847: 00:12:f3:41:64:9b Scheduling deletion of Mobile Station: (callerId: 12) in 10 seconds
*apfReceiveTask: Jul 05 20:46:41.647: 00:12:f3:41:64:e4 0.0.0.0 DHCP_REQD (7) DHCP Policy timeout. Number of DHCP Discover 0, DHCP Request 0 from client
*apfReceiveTask: Jul 05 20:46:41.647: 00:12:f3:41:64:e4 Interface Group was NULL.Number of DHCP Discovery 0 from client
*apfReceiveTask: Jul 05 20:46:41.647: 00:12:f3:41:64:e4 0.0.0.0 DHCP_REQD (7) Pem timed out, Try to delete client in 10 secs.
*apfReceiveTask: Jul 05 20:46:41.647: 00:12:f3:41:64:e4 Mscb del, deleteImm:no, MS State:3, reasoncode:4 ANCHOR ROLE=0
*apfReceiveTask: Jul 05 20:46:41.647: 00:12:f3:41:64:e4 Scheduling deletion of Mobile
