*spamApTask2: Oct 14 07:27:42.930: 94:f6:d6:80:35:58 apfUpdateDeleteAckInMscb (apf_api.c:54425) Expiring Mobile! *apfOpenDtlSocket: Oct 14 07:27:49.427: 94:f6:d6:80:35:58 Received management frame ASSOCIATION REQUEST on BSSID 1c:de:a7:c4:7a:4f destination addr 1c:de:a7:c4:7a:4f *apfMsConnTask_3: Oct 14 07:27:49.428: 94:f6:d6:80:35:58 Updating 11r vendor IE *apfMsConnTask_3: Oct 14 07:27:49.428: 94:f6:d6:80:35:58 Processing assoc-req station:94:f6:d6:80:35:58 AP:1c:de:a7:c4:7a:40-01 ssid : TEST-GUEST thread:175f6a00 *apfMsConnTask_3: Oct 14 07:27:49.428: 94:f6:d6:80:35:58 apfCreateMobileStationEntryWrapper (apf_ms.c:4434) Changing state for mobile 94:f6:d6:80:35:58 on AP 1c:de:a7:c4:7a:40 from Idle to Idle *apfMsConnTask_3: Oct 14 07:27:49.428: 94:f6:d6:80:35:58 Adding mobile on LWAPP AP 1c:de:a7:c4:7a:40(1) *apfMsConnTask_3: Oct 14 07:27:49.428: 94:f6:d6:80:35:58 Created Acct-Session-ID (5da42375/94:f6:d6:80:35:58/15) for the mobile *apfMsConnTask_3: Oct 14 07:27:49.428: 94:f6:d6:80:35:58 Client AVC Roaming context transfer needed? NO *apfMsConnTask_3: Oct 14 07:27:49.428: 94:f6:d6:80:35:58 Setting RTTS enabled to 0 *apfMsConnTask_3: Oct 14 07:27:49.428: 94:f6:d6:80:35:58 Association received from mobile on BSSID 1c:de:a7:c4:7a:4d AP TEST-AP-02 *apfMsConnTask_3: Oct 14 07:27:49.428: 94:f6:d6:80:35:58 Station: 94:F6:D6:80:35:58 11v BSS Transition not enabled on the AP 1C:DE:A7:C4:7A:40 *apfMsConnTask_3: Oct 14 07:27:49.428: 94:f6:d6:80:35:58 Global 200 Clients are allowed to AP radio *apfMsConnTask_3: Oct 14 07:27:49.428: 94:f6:d6:80:35:58 Max Client Trap Threshold: 0 cur: 1 *apfMsConnTask_3: Oct 14 07:27:49.428: 94:f6:d6:80:35:58 Rf profile 600 Clients are allowed to AP wlan *apfMsConnTask_3: Oct 14 07:27:49.428: 94:f6:d6:80:35:58 override for default ap group, marking intgrp NULL *apfMsConnTask_3: Oct 14 07:27:49.428: 94:f6:d6:80:35:58 Applying Interface(xxx_guest) policy on Mobile, role Unassociated. Ms NAC State 0 Quarantine Vlan 0 Access Vlan 0 *apfMsConnTask_3: Oct 14 07:27:49.428: 94:f6:d6:80:35:58 Re-applying interface policy for client *apfMsConnTask_3: Oct 14 07:27:49.428: 94:f6:d6:80:35:58 0.0.0.0 START (0) Changing IPv4 ACL 'none' (ACL ID 255) ===> 'none' (ACL ID 255) --- (caller apf_policy.c:3140) *apfMsConnTask_3: Oct 14 07:27:49.428: 94:f6:d6:80:35:58 0.0.0.0 START (0) Changing Url ACL 'none' (ACL ID 255) ===> 'none' (ACL ID 255),Default action is '0' --- (caller apf_policy.c:3160) *apfMsConnTask_3: Oct 14 07:27:49.428: 94:f6:d6:80:35:58 0.0.0.0 START (0) Changing IPv6 ACL 'none' (ACL ID 255) ===> 'none' (ACL ID 255) --- (caller apf_policy.c:3181) *apfMsConnTask_3: Oct 14 07:27:49.428: 94:f6:d6:80:35:58 Values before applying NASID - interfacetype:3, ovrd:0, mscb nasid:, interface nasid:, APgrpset:0 *apfMsConnTask_3: Oct 14 07:27:49.428: 94:f6:d6:80:35:58 apfApplyWlanPolicy: Apply WLAN Policy over PMIPv6 Client Mobility Type, Tunnel User - 0 *apfMsConnTask_3: Oct 14 07:27:49.428: 94:f6:d6:80:35:58 Check before Setting the NAS Id to WLAN specific Id 'xxxWLC01' *apfMsConnTask_3: Oct 14 07:27:49.428: 94:f6:d6:80:35:58 Setting the NAS Id to WLAN specific Id 'xxxWLC01' *apfMsConnTask_3: Oct 14 07:27:49.428: 94:f6:d6:80:35:58 apf_policy.c:2454 Assigning the SGT 0 to mobile (earlier sgt 0) *apfMsConnTask_3: Oct 14 07:27:49.428: 94:f6:d6:80:35:58 Increment the SGT 0 policy count reference by the clients 2 *apfMsConnTask_3: Oct 14 07:27:49.428: 94:f6:d6:80:35:58 Check the client SGT 0 policy and push it to AP 1c:de:a7:c4:7a:40 *apfMsConnTask_3: Oct 14 07:27:49.428: 94:f6:d6:80:35:58 In processSsidIE:6947 setting Central switched to TRUE *apfMsConnTask_3: Oct 14 07:27:49.428: 94:f6:d6:80:35:58 In processSsidIE:6950 apVapId = 1 and Split Acl Id = 65535 *apfMsConnTask_3: Oct 14 07:27:49.428: 94:f6:d6:80:35:58 Applying site-specific Local Bridging override for station 94:f6:d6:80:35:58 - vapId 3, site 'Perth', interface 'xxx_guest' *apfMsConnTask_3: Oct 14 07:27:49.428: 94:f6:d6:80:35:58 Applying Local Bridging Interface Policy for station 94:f6:d6:80:35:58 - vlan 2256, interface id 5, interface 'xxx_guest', nasId:'xxxWLC01' *apfMsConnTask_3: Oct 14 07:27:49.428: 94:f6:d6:80:35:58 override from ap group, removing intf group from mscb *apfMsConnTask_3: Oct 14 07:27:49.428: 94:f6:d6:80:35:58 Applying site-specific override for station 94:f6:d6:80:35:58 - vapId 3, site 'Perth', interface 'xxx_guest' *apfMsConnTask_3: Oct 14 07:27:49.428: 94:f6:d6:80:35:58 Not applying Local Bridge Policy because Site Specific Interface(xxx_guest) Policy is already applied. *apfMsConnTask_3: Oct 14 07:27:49.428: 94:f6:d6:80:35:58 Applying Interface(xxx_guest) policy on Mobile, role Unassociated. Ms NAC State 2 Quarantine Vlan 0 Access Vlan 2256 *apfMsConnTask_3: Oct 14 07:27:49.428: 94:f6:d6:80:35:58 Re-applying interface policy for client *apfMsConnTask_3: Oct 14 07:27:49.428: 94:f6:d6:80:35:58 0.0.0.0 START (0) Changing IPv4 ACL 'none' (ACL ID 255) ===> 'none' (ACL ID 255) --- (caller apf_policy.c:3140) *apfMsConnTask_3: Oct 14 07:27:49.428: 94:f6:d6:80:35:58 0.0.0.0 START (0) Changing Url ACL 'none' (ACL ID 255) ===> 'none' (ACL ID 255),Default action is '0' --- (caller apf_policy.c:3160) *apfMsConnTask_3: Oct 14 07:27:49.428: 94:f6:d6:80:35:58 0.0.0.0 START (0) Changing IPv6 ACL 'none' (ACL ID 255) ===> 'none' (ACL ID 255) --- (caller apf_policy.c:3181) *apfMsConnTask_3: Oct 14 07:27:49.428: 94:f6:d6:80:35:58 Values before applying NASID - interfacetype:3, ovrd:0, mscb nasid:xxxWLC01, interface nasid:, APgrpset:0 *apfMsConnTask_3: Oct 14 07:27:49.428: 94:f6:d6:80:35:58 Setting the NAS Id to AP group specific Id 'xxxWLC01' *apfMsConnTask_3: Oct 14 07:27:49.428: 94:f6:d6:80:35:58 processSsidIE statusCode is 0 and status is 0 *apfMsConnTask_3: Oct 14 07:27:49.428: 94:f6:d6:80:35:58 processSsidIE ssid_done_flag is 0 finish_flag is 0 *apfMsConnTask_3: Oct 14 07:27:49.428: 94:f6:d6:80:35:58 STA - rates (8): 140 18 152 36 176 72 96 108 0 0 0 0 0 0 0 0 *apfMsConnTask_3: Oct 14 07:27:49.428: 94:f6:d6:80:35:58 suppRates statusCode is 0 and gotSuppRatesElement is 1 *apfMsConnTask_3: Oct 14 07:27:49.428: RSNIE in Assoc. Req.: (20) *apfMsConnTask_3: Oct 14 07:27:49.428: [0000] 01 00 00 0f ac 04 01 00 00 0f ac 04 01 00 00 0f *apfMsConnTask_3: Oct 14 07:27:49.428: [0016] ac 02 0c 00 *apfMsConnTask_3: Oct 14 07:27:49.428: 94:f6:d6:80:35:58 Processing RSN IE type 48, length 20 for mobile 94:f6:d6:80:35:58 *apfMsConnTask_3: Oct 14 07:27:49.428: 94:f6:d6:80:35:58 Selected Unicast cipher CCMP128 for client device *apfMsConnTask_3: Oct 14 07:27:49.428: 94:f6:d6:80:35:58 Received 802.11i PSK key management suite, enabling Authentication *apfMsConnTask_3: Oct 14 07:27:49.428: 94:f6:d6:80:35:58 RSN Capabilities: 12 *apfMsConnTask_3: Oct 14 07:27:49.428: 94:f6:d6:80:35:58 non-11w Capable mobile *apfMsConnTask_3: Oct 14 07:27:49.428: 94:f6:d6:80:35:58 Received RSN IE with 0 PMKIDs from mobile 94:f6:d6:80:35:58 *apfMsConnTask_3: Oct 14 07:27:49.428: 94:f6:d6:80:35:58 Assigning flex webauth ACL ID :65535 for vlan : 1 *apfMsConnTask_3: Oct 14 07:27:49.428: 94:f6:d6:80:35:58 Updating AID for REAP AP Client 1c:de:a7:c4:7a:40 - AID ===> 2 *apfMsConnTask_3: Oct 14 07:27:49.428: 94:f6:d6:80:35:58 0.0.0.0 START (0) Initializing policy *apfMsConnTask_3: Oct 14 07:27:49.428: 94:f6:d6:80:35:58 0.0.0.0 START (0) Change state to AUTHCHECK (2) last state START (0) *apfMsConnTask_3: Oct 14 07:27:49.428: 94:f6:d6:80:35:58 apfVapSecurity=0x40004000 L2=16384 SkipWeb=0 *apfMsConnTask_3: Oct 14 07:27:49.428: 94:f6:d6:80:35:58 AuthenticationRequired = 1 *apfMsConnTask_3: Oct 14 07:27:49.428: 94:f6:d6:80:35:58 0.0.0.0 AUTHCHECK (2) Change state to 8021X_REQD (3) last state AUTHCHECK (2) *apfMsConnTask_3: Oct 14 07:27:49.428: 94:f6:d6:80:35:58 Encryption policy is set to 0x80000001 *apfMsConnTask_3: Oct 14 07:27:49.428: 94:f6:d6:80:35:58 Not Using WMM Compliance code qosCap 00 *apfMsConnTask_3: Oct 14 07:27:49.428: 94:f6:d6:80:35:58 flex webauth acl id to be sent when fabric is disabled:65535 *apfMsConnTask_3: Oct 14 07:27:49.428: 94:f6:d6:80:35:58 flex webauth acl id to be sent :65535 name : client acl id : 65535 *apfMsConnTask_3: Oct 14 07:27:49.428: 94:f6:d6:80:35:58 Vlan while overriding the policy = -1 *apfMsConnTask_3: Oct 14 07:27:49.428: 94:f6:d6:80:35:58 sending to spamAddMobile vlanId -1 aclName = , flexAclId 65535 *apfMsConnTask_3: Oct 14 07:27:49.428: 94:f6:d6:80:35:58 0.0.0.0 8021X_REQD (3) Plumbed mobile LWAPP rule on AP 1c:de:a7:c4:7a:40 vapId 3 apVapId 1 flex-acl-name: *apfMsConnTask_3: Oct 14 07:27:49.428: 94:f6:d6:80:35:58 apfMsAssoStateInc *apfMsConnTask_3: Oct 14 07:27:49.428: 94:f6:d6:80:35:58 apfMsWepPskStateInc *apfMsConnTask_3: Oct 14 07:27:49.428: 94:f6:d6:80:35:58 apfPemAddUser2 (apf_policy.c:438) Changing state for mobile 94:f6:d6:80:35:58 on AP 1c:de:a7:c4:7a:40 from Idle to Associated *apfMsConnTask_3: Oct 14 07:27:49.428: 94:f6:d6:80:35:58 apfPemAddUser2:session timeout forstation 94:f6:d6:80:35:58 - Session Tout 14400, apfMsTimeOut '14400' and sessionTimerRunning flag is 0 *apfMsConnTask_3: Oct 14 07:27:49.428: 94:f6:d6:80:35:58 Scheduling deletion of Mobile Station: (callerId: 49) in 14400 seconds *apfMsConnTask_3: Oct 14 07:27:49.428: 94:f6:d6:80:35:58 Func: apfPemAddUser2, Ms Timeout = 14400, Session Timeout = 14400 *apfMsConnTask_3: Oct 14 07:27:49.428: 94:f6:d6:80:35:58 Sending assoc-resp with status 0 station:94:f6:d6:80:35:58 AP:1c:de:a7:c4:7a:40-01 on apVapId 1 *apfMsConnTask_3: Oct 14 07:27:49.428: 94:f6:d6:80:35:58 Sending Assoc Response (status: '0') to station on AP TEST-AP-02 on BSSID 1c:de:a7:c4:7a:4f ApVapId 1 Slot 1, mobility role 0 *apfMsConnTask_3: Oct 14 07:27:49.428: 94:f6:d6:80:35:58 apfProcessAssocReq (apf_80211.c:12012) Changing state for mobile 94:f6:d6:80:35:58 on AP 1c:de:a7:c4:7a:40 from Associated to Associated *spamApTask2: Oct 14 07:27:49.428: 94:f6:d6:80:35:58 Add SGT:0 to AP 1c:de:a7:c4:7a:40 *spamApTask2: Oct 14 07:27:49.428: 94:f6:d6:80:35:58 Add CTS mobile SGT - Encoded the capwap payload for the mobile with SGT 0 *spamApTask2: Oct 14 07:27:49.428: 94:f6:d6:80:35:58 Successful transmission of LWAPP Add-Mobile to AP 1c:de:a7:c4:7a:40 *spamApTask2: Oct 14 07:27:49.428: 94:f6:d6:80:35:58 Setting ADD_MOBILE (idx 0, seqno 0, action 1, count 778308776) ack state for STA on AP 1c:de:a7:c4:7a:40 *spamApTask2: Oct 14 07:27:49.436: 94:f6:d6:80:35:58 Received ADD_MOBILE ack - Initiating 1x to STA 94:f6:d6:80:35:58 (idx 37) *spamApTask2: Oct 14 07:27:49.436: 94:f6:d6:80:35:58 APF Initiating 1x to STA 94:f6:d6:80:35:58 *spamApTask2: Oct 14 07:27:49.436: 94:f6:d6:80:35:58 Sent dot1x auth initiate message for mobile 94:f6:d6:80:35:58 *Dot1x_NW_MsgTask_0: Oct 14 07:27:49.436: 94:f6:d6:80:35:58 dot1xProcessInitiate1XtoMobile to mobile station 94:f6:d6:80:35:58 (mscb 1, msg 1) *Dot1x_NW_MsgTask_0: Oct 14 07:27:49.436: 94:f6:d6:80:35:58 reauth_sm state transition 0 ---> 1 for mobile 94:f6:d6:80:35:58 at 1x_reauth_sm.c:47 *Dot1x_NW_MsgTask_0: Oct 14 07:27:49.436: 94:f6:d6:80:35:58 Normal psk client, full auth *Dot1x_NW_MsgTask_0: Oct 14 07:27:49.436: 94:f6:d6:80:35:58 Created PKC PMK Cache entry for station 94:f6:d6:80:35:58 (RSN 2) *Dot1x_NW_MsgTask_0: Oct 14 07:27:49.436: 94:f6:d6:80:35:58 Resetting MSCB PMK Cache Entry @index 0 for station 94:f6:d6:80:35:58 *Dot1x_NW_MsgTask_0: Oct 14 07:27:49.436: 94:f6:d6:80:35:58 Setting active key cache index 8 ---> 8 *Dot1x_NW_MsgTask_0: Oct 14 07:27:49.436: 94:f6:d6:80:35:58 Setting active key cache index 8 ---> 0 *Dot1x_NW_MsgTask_0: Oct 14 07:27:49.436: 94:f6:d6:80:35:58 Created PMKID PMK Cache for BSSID 1c:de:a7:c4:7a:4f at index 0 for station 94:f6:d6:80:35:58 *Dot1x_NW_MsgTask_0: Oct 14 07:27:49.436: New PMKID: (16) *Dot1x_NW_MsgTask_0: Oct 14 07:27:49.436: [0000] d5 0b 03 ba 7d d9 df 6e b8 5c 27 b0 f2 38 25 22 *Dot1x_NW_MsgTask_0: Oct 14 07:27:49.436: 94:f6:d6:80:35:58 Initiating RSN PSK to mobile 94:f6:d6:80:35:58keyMgmtType : 0 *Dot1x_NW_MsgTask_0: Oct 14 07:27:49.436: 94:f6:d6:80:35:58 unsetting PmkIdValidatedByAp *Dot1x_NW_MsgTask_0: Oct 14 07:27:49.436: 94:f6:d6:80:35:58 EAP-PARAM Debug - eap-params for Wlan-Id :3 is disabled - applying Global eap timers and retries *Dot1x_NW_MsgTask_0: Oct 14 07:27:49.436: 94:f6:d6:80:35:58 Disable re-auth, use PMK lifetime. *Dot1x_NW_MsgTask_0: Oct 14 07:27:49.436: 94:f6:d6:80:35:58 dot1x - moving mobile 94:f6:d6:80:35:58 into Force Auth state *Dot1x_NW_MsgTask_0: Oct 14 07:27:49.436: 94:f6:d6:80:35:58 Skipping EAP-Success to mobile 94:f6:d6:80:35:58 (encryptBit:0) *Dot1x_NW_MsgTask_0: Oct 14 07:27:49.436: 94:f6:d6:80:35:58 key Desc Version FT - 0 *Dot1x_NW_MsgTask_0: Oct 14 07:27:49.436: 94:f6:d6:80:35:58 Found an cache entry for BSSID 1c:de:a7:c4:7a:4f in PMKID cache at index 0 of station 94:f6:d6:80:35:58 *Dot1x_NW_MsgTask_0: Oct 14 07:27:49.436: 94:f6:d6:80:35:58 Found an cache entry for BSSID 1c:de:a7:c4:7a:4f in PMKID cache at index 0 of station 94:f6:d6:80:35:58 *Dot1x_NW_MsgTask_0: Oct 14 07:27:49.436: Including PMKID in M1 (16) *Dot1x_NW_MsgTask_0: Oct 14 07:27:49.436: [0000] d5 0b 03 ba 7d d9 df 6e b8 5c 27 b0 f2 38 25 22 *Dot1x_NW_MsgTask_0: Oct 14 07:27:49.436: M1 - Key Data: (22) *Dot1x_NW_MsgTask_0: Oct 14 07:27:49.436: [0016] 27 b0 f2 38 25 22 *Dot1x_NW_MsgTask_0: Oct 14 07:27:49.436: 94:f6:d6:80:35:58 Sending EAPOL-Key Message to mobile 94:f6:d6:80:35:58 state INITPMK (message 1), replay counter 00.00.00.00.00.00.00.00 *Dot1x_NW_MsgTask_0: Oct 14 07:27:49.436: 94:f6:d6:80:35:58 Allocating EAP Pkt for retransmission to mobile 94:f6:d6:80:35:58 *dot1xSocketTask: Oct 14 07:27:49.441: 94:f6:d6:80:35:58 validating eapol pkt: key version = 2 *Dot1x_NW_MsgTask_0: Oct 14 07:27:49.441: 94:f6:d6:80:35:58 Received EAPOL-Key from mobile 94:f6:d6:80:35:58 *Dot1x_NW_MsgTask_0: Oct 14 07:27:49.441: 94:f6:d6:80:35:58 key Desc Version FT - 0 *Dot1x_NW_MsgTask_0: Oct 14 07:27:49.441: 94:f6:d6:80:35:58 Received EAPOL-key in PTK_START state (message 2) from mobile 94:f6:d6:80:35:58 *Dot1x_NW_MsgTask_0: Oct 14 07:27:49.441: 94:f6:d6:80:35:58 Encryption Policy: 4, PTK Key Length: 48 *Dot1x_NW_MsgTask_0: Oct 14 07:27:49.441: 94:f6:d6:80:35:58 Successfully computed PTK from PMK!!! *Dot1x_NW_MsgTask_0: Oct 14 07:27:49.441: 94:f6:d6:80:35:58 Received valid MIC in EAPOL Key Message M2!!!!! *Dot1x_NW_MsgTask_0: Oct 14 07:27:49.441: 94:f6:d6:80:35:58 Compare RSN IE in association and EAPOL-M2 frame(rsnie_len :20, and grpMgmtCipherLen:0) *Dot1x_NW_MsgTask_0: Oct 14 07:27:49.441: 94:f6:d6:80:35:58 rsnieCapabilty = c rsnie_len =20 *Dot1x_NW_MsgTask_0: Oct 14 07:27:49.441: 94:f6:d6:80:35:58 Dumping RSNIE received in Association request(len = 22): *Dot1x_NW_MsgTask_0: Oct 14 07:27:49.442: 00000000: 30 14 01 00 00 0f ac 04 01 00 00 0f ac 04 01 00 0............... *Dot1x_NW_MsgTask_0: Oct 14 07:27:49.442: 00000010: 00 0f ac 02 0c 00 ...... *Dot1x_NW_MsgTask_0: Oct 14 07:27:49.442: 94:f6:d6:80:35:58 Dumping RSNIE received in EAPOL M2 (len = 20): *Dot1x_NW_MsgTask_0: Oct 14 07:27:49.442: 00000000: 01 00 00 0f ac 04 01 00 00 0f ac 04 01 00 00 0f ................ *Dot1x_NW_MsgTask_0: Oct 14 07:27:49.442: 00000010: ac 02 0c 00 .... *Dot1x_NW_MsgTask_0: Oct 14 07:27:49.442: 94:f6:d6:80:35:58 key Desc Version FT - 0 *Dot1x_NW_MsgTask_0: Oct 14 07:27:49.442: 94:f6:d6:80:35:58 key Desc Version FT - 0 *Dot1x_NW_MsgTask_0: Oct 14 07:27:49.442: 94:f6:d6:80:35:58 Sending EAPOL-Key Message to mobile 94:f6:d6:80:35:58 state PTKINITNEGOTIATING (message 3), replay counter 00.00.00.00.00.00.00.01 *Dot1x_NW_MsgTask_0: Oct 14 07:27:49.442: 94:f6:d6:80:35:58 Reusing allocated memory for EAP Pkt for retransmission to mobile 94:f6:d6:80:35:58 *dot1xSocketTask: Oct 14 07:27:49.445: 94:f6:d6:80:35:58 validating eapol pkt: key version = 2 *Dot1x_NW_MsgTask_0: Oct 14 07:27:49.445: 94:f6:d6:80:35:58 Received EAPOL-Key from mobile 94:f6:d6:80:35:58 *Dot1x_NW_MsgTask_0: Oct 14 07:27:49.445: 94:f6:d6:80:35:58 key Desc Version FT - 0 *Dot1x_NW_MsgTask_0: Oct 14 07:27:49.445: 94:f6:d6:80:35:58 Received EAPOL-key in PTKINITNEGOTIATING state (message 4) from mobile 94:f6:d6:80:35:58 *Dot1x_NW_MsgTask_0: Oct 14 07:27:49.445: 94:f6:d6:80:35:58 Stopping retransmission timer for mobile 94:f6:d6:80:35:58 *Dot1x_NW_MsgTask_0: Oct 14 07:27:49.445: 94:f6:d6:80:35:58 Freeing EAP Retransmit Bufer for mobile 94:f6:d6:80:35:58 *Dot1x_NW_MsgTask_0: Oct 14 07:27:49.445: 94:f6:d6:80:35:58 apfMs1xStateInc *Dot1x_NW_MsgTask_0: Oct 14 07:27:49.445: 94:f6:d6:80:35:58 0.0.0.0 8021X_REQD (3) Change state to L2AUTHCOMPLETE (4) last state 8021X_REQD (3) *Dot1x_NW_MsgTask_0: Oct 14 07:27:49.445: 94:f6:d6:80:35:58 Mobility query, PEM State: L2AUTHCOMPLETE *Dot1x_NW_MsgTask_0: Oct 14 07:27:49.445: 94:f6:d6:80:35:58 0.0.0.0 L2AUTHCOMPLETE (4) NO release MSCB *Dot1x_NW_MsgTask_0: Oct 14 07:27:49.445: 94:f6:d6:80:35:58 Building Mobile Announce : *Dot1x_NW_MsgTask_0: Oct 14 07:27:49.445: 94:f6:d6:80:35:58 Building Client Payload: *Dot1x_NW_MsgTask_0: Oct 14 07:27:49.445: 94:f6:d6:80:35:58 Client Ip: 0.0.0.0 *Dot1x_NW_MsgTask_0: Oct 14 07:27:49.445: 94:f6:d6:80:35:58 Client Vlan Ip: 192.168.113.253, Vlan mask : 255.255.255.0 *Dot1x_NW_MsgTask_0: Oct 14 07:27:49.445: 94:f6:d6:80:35:58 Client Vap Security: 1073758208 *Dot1x_NW_MsgTask_0: Oct 14 07:27:49.445: 94:f6:d6:80:35:58 Virtual Ip: 1.1.1.1 *Dot1x_NW_MsgTask_0: Oct 14 07:27:49.445: 94:f6:d6:80:35:58 ssid: TEST-GUEST *Dot1x_NW_MsgTask_0: Oct 14 07:27:49.445: 94:f6:d6:80:35:58 Building VlanIpPayload. *Dot1x_NW_MsgTask_0: Oct 14 07:27:49.445: 94:f6:d6:80:35:58 Mobile Announce sent to 1 members of the local group. *Dot1x_NW_MsgTask_0: Oct 14 07:27:49.445: 94:f6:d6:80:35:58 Not Using WMM Compliance code qosCap 00 *Dot1x_NW_MsgTask_0: Oct 14 07:27:49.445: 94:f6:d6:80:35:58 flex webauth acl id to be sent when fabric is disabled:65535 *Dot1x_NW_MsgTask_0: Oct 14 07:27:49.445: 94:f6:d6:80:35:58 flex webauth acl id to be sent :65535 name : client acl id : 65535 *Dot1x_NW_MsgTask_0: Oct 14 07:27:49.445: 94:f6:d6:80:35:58 Vlan while overriding the policy = -1 *Dot1x_NW_MsgTask_0: Oct 14 07:27:49.445: 94:f6:d6:80:35:58 sending to spamAddMobile vlanId -1 aclName = , flexAclId 65535 *Dot1x_NW_MsgTask_0: Oct 14 07:27:49.445: 94:f6:d6:80:35:58 0.0.0.0 L2AUTHCOMPLETE (4) Plumbed mobile LWAPP rule on AP 1c:de:a7:c4:7a:40 vapId 3 apVapId 1 flex-acl-name: *Dot1x_NW_MsgTask_0: Oct 14 07:27:49.445: 94:f6:d6:80:35:58 0.0.0.0 L2AUTHCOMPLETE (4) Change state to DHCP_REQD (7) last state L2AUTHCOMPLETE (4) *Dot1x_NW_MsgTask_0: Oct 14 07:27:49.445: 94:f6:d6:80:35:58 0.0.0.0 DHCP_REQD (7) pemAdvanceState2 7010, Adding TMP rule *Dot1x_NW_MsgTask_0: Oct 14 07:27:49.445: 94:f6:d6:80:35:58 0.0.0.0 DHCP_REQD (7) Adding Fast Path rule type = Airespace AP - Learn IP address on AP 1c:de:a7:c4:7a:40, slot 1, interface = 1, QOS = 0 IPv4 ACL ID = 255, IPv *Dot1x_NW_MsgTask_0: Oct 14 07:27:49.445: 94:f6:d6:80:35:58 0.0.0.0 DHCP_REQD (7) Fast Path rule (contd...) 802.1P = 0, DSCP = 0, TokenID = 64206, IntfId = 5 Local Bridging Vlan = 2256, Local Bridging intf id = 5 *Dot1x_NW_MsgTask_0: Oct 14 07:27:49.445: 94:f6:d6:80:35:58 0.0.0.0 DHCP_REQD (7) Fast Path rule (contd...) AVC Ratelimit: AppID = 0 ,AppAction = 0, AppToken = 64206 AverageRate = 0, BurstRate = 0 *Dot1x_NW_MsgTask_0: Oct 14 07:27:49.445: 94:f6:d6:80:35:58 0.0.0.0 DHCP_REQD (7) Fast Path rule (contd...) AVC Ratelimit: AppID = 0 ,AppAction = 0, AppToken = 64206 AverageRate = 0, BurstRate = 0 *Dot1x_NW_MsgTask_0: Oct 14 07:27:49.445: 94:f6:d6:80:35:58 0.0.0.0 DHCP_REQD (7) Fast Path rule (contd...) AVC Ratelimit: AppID = 0 ,AppAction = 0, AppToken = 64206 AverageRate = 0, BurstRate = 0 *Dot1x_NW_MsgTask_0: Oct 14 07:27:49.445: 94:f6:d6:80:35:58 0.0.0.0 DHCP_REQD (7) Successfully plumbed mobile rule (IPv4 ACL ID 255, IPv6 ACL ID 255, L2 ACL ID 255,URL ACL ID 255,URL ACL Action 0) *Dot1x_NW_MsgTask_0: Oct 14 07:27:49.445: 94:f6:d6:80:35:58 0.0.0.0 DHCP_REQD (7) NO release MSCB *Dot1x_NW_MsgTask_0: Oct 14 07:27:49.445: 94:f6:d6:80:35:58 Successfully Plumbed PTK session Keysfor mobile 94:f6:d6:80:35:58 *spamApTask2: Oct 14 07:27:49.445: 94:f6:d6:80:35:58 Add SGT:0 to AP 1c:de:a7:c4:7a:40 *spamApTask2: Oct 14 07:27:49.445: 94:f6:d6:80:35:58 Add CTS mobile SGT - Encoded the capwap payload for the mobile with SGT 0 *pemReceiveTask: Oct 14 07:27:49.445: 94:f6:d6:80:35:58 0.0.0.0 Added NPU entry of type 9, dtlFlags 0x0 *spamApTask2: Oct 14 07:27:49.445: 94:f6:d6:80:35:58 Successful transmission of LWAPP Add-Mobile to AP 1c:de:a7:c4:7a:40 *apfOpenDtlSocket: Oct 14 07:27:49.446: 94:f6:d6:80:35:58 Received management frame ACTION on BSSID 1c:de:a7:c4:7a:4f destination addr 1c:de:a7:c4:7a:4f *apfMsConnTask_3: Oct 14 07:27:49.446: 94:f6:d6:80:35:58 Received management action frame (category code:5) from the client. *apfMsConnTask_3: Oct 14 07:27:49.446: 94:f6:d6:80:35:58 Found RM action category code *apfMsConnTask_3: Oct 14 07:27:49.446: 94:f6:d6:80:35:58 Found Radio Measurement report action code datalen = 60 *apfOpenDtlSocket: Oct 14 07:27:49.446: 94:f6:d6:80:35:58 Received management frame ACTION on BSSID 1c:de:a7:c4:7a:4f destination addr 1c:de:a7:c4:7a:4f *apfMsConnTask_3: Oct 14 07:27:49.446: 94:f6:d6:80:35:58 Received management action frame (category code:5) from the client. *apfMsConnTask_3: Oct 14 07:27:49.446: 94:f6:d6:80:35:58 Found RM action category code *DHCP Socket Task: Oct 14 07:27:49.643: 94:f6:d6:80:35:58 DHCP received op BOOTREQUEST (1) (len 308,vlan 2261, port 1, encap 0xec03, xid 0x2c949cbe) *DHCP Socket Task: Oct 14 07:27:49.643: 94:f6:d6:80:35:58 DHCP option len (including the magic cookie) 72 *DHCP Socket Task: Oct 14 07:27:49.643: 94:f6:d6:80:35:58 DHCP option: message type = DHCP DISCOVER *DHCP Socket Task: Oct 14 07:27:49.643: 94:f6:d6:80:35:58 DHCP option: 55 (len 7) - skipping *DHCP Socket Task: Oct 14 07:27:49.643: 94:f6:d6:80:35:58 DHCP option: 57 (len 2) - skipping *DHCP Socket Task: Oct 14 07:27:49.643: 94:f6:d6:80:35:58 DHCP option: 61 (len 7) - skipping *DHCP Socket Task: Oct 14 07:27:49.643: 94:f6:d6:80:35:58 DHCP option: lease time = 7776000 seconds *DHCP Socket Task: Oct 14 07:27:49.643: 94:f6:d6:80:35:58 DHCP option: hostname = Daves-iPhoneX (len 13) *DHCP Socket Task: Oct 14 07:27:49.643: 94:f6:d6:80:35:58 DHCP options end, len 72, actual 64 *DHCP Socket Task: Oct 14 07:27:49.643: 94:f6:d6:80:35:58 DHCP (encap type 0xec03) mstype 0ff:ff:ff:ff:ff:ff *DHCP Socket Task: Oct 14 07:27:49.643: 94:f6:d6:80:35:58 DHCP dropping packet due to ongoing mobility handshake exchange, (siaddr 0.0.0.0, mobility state = 'apfMsMmQueryRequested' *DHCP Socket Task: Oct 14 07:27:50.864: 94:f6:d6:80:35:58 DHCP received op BOOTREQUEST (1) (len 308,vlan 2261, port 1, encap 0xec03, xid 0x2c949cbe) *DHCP Socket Task: Oct 14 07:27:50.864: 94:f6:d6:80:35:58 DHCP option len (including the magic cookie) 72 *DHCP Socket Task: Oct 14 07:27:50.864: 94:f6:d6:80:35:58 DHCP option: message type = DHCP DISCOVER *DHCP Socket Task: Oct 14 07:27:50.864: 94:f6:d6:80:35:58 DHCP option: 55 (len 7) - skipping *DHCP Socket Task: Oct 14 07:27:50.864: 94:f6:d6:80:35:58 DHCP option: 57 (len 2) - skipping *DHCP Socket Task: Oct 14 07:27:50.864: 94:f6:d6:80:35:58 DHCP option: 61 (len 7) - skipping *DHCP Socket Task: Oct 14 07:27:50.864: 94:f6:d6:80:35:58 DHCP option: lease time = 7776000 seconds *DHCP Socket Task: Oct 14 07:27:50.864: 94:f6:d6:80:35:58 DHCP option: hostname = Daves-iPhoneX (len 13) *DHCP Socket Task: Oct 14 07:27:50.864: 94:f6:d6:80:35:58 DHCP options end, len 72, actual 64 *DHCP Socket Task: Oct 14 07:27:50.864: 94:f6:d6:80:35:58 DHCP (encap type 0xec03) mstype 0ff:ff:ff:ff:ff:ff *DHCP Socket Task: Oct 14 07:27:50.864: 94:f6:d6:80:35:58 DHCP dropping packet due to ongoing mobility handshake exchange, (siaddr 0.0.0.0, mobility state = 'apfMsMmQueryRequested' *apfReceiveTask: Oct 14 07:27:52.312: 94:f6:d6:80:35:58 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 = 192.168.100.226 *apfReceiveTask: Oct 14 07:27:52.312: 94:f6:d6:80:35:58 pemAdvanceState2 (pem_api.c:6511) Changing state for mobile 94:f6:d6:80:35:58 on AP 1c:de:a7:c4:7a:40 from Associated to Associated *apfReceiveTask: Oct 14 07:27:52.312: 94:f6:d6:80:35:58 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: Oct 14 07:27:52.312: 94:f6:d6:80:35:58 0.0.0.0 DHCP_REQD (7) pemAdvanceState2 6648, Adding TMP rule *apfReceiveTask: Oct 14 07:27:52.312: 94:f6:d6:80:35:58 0.0.0.0 DHCP_REQD (7) Replacing Fast Path rule type = Airespace AP - Learn IP address on AP 1c:de:a7:c4:7a:40, slot 1, interface = 1, QOS = 0 IPv4 ACL ID = 255, *apfReceiveTask: Oct 14 07:27:52.312: 94:f6:d6:80:35:58 0.0.0.0 DHCP_REQD (7) Fast Path rule (contd...) 802.1P = 0, DSCP = 0, TokenID = 64206, IntfId = 5 Local Bridging Vlan = 2256, Local Bridging intf id = 5 *apfReceiveTask: Oct 14 07:27:52.312: 94:f6:d6:80:35:58 0.0.0.0 DHCP_REQD (7) Fast Path rule (contd...) AVC Ratelimit: AppID = 0 ,AppAction = 0, AppToken = 64206 AverageRate = 0, BurstRate = 0 *apfReceiveTask: Oct 14 07:27:52.312: 94:f6:d6:80:35:58 0.0.0.0 DHCP_REQD (7) Fast Path rule (contd...) AVC Ratelimit: AppID = 0 ,AppAction = 0, AppToken = 64206 AverageRate = 0, BurstRate = 0 *apfReceiveTask: Oct 14 07:27:52.312: 94:f6:d6:80:35:58 0.0.0.0 DHCP_REQD (7) Fast Path rule (contd...) AVC Ratelimit: AppID = 0 ,AppAction = 0, AppToken = 64206 AverageRate = 0, BurstRate = 0 *apfReceiveTask: Oct 14 07:27:52.312: 94:f6:d6:80:35:58 0.0.0.0 DHCP_REQD (7) Successfully plumbed mobile rule (IPv4 ACL ID 255, IPv6 ACL ID 255, L2 ACL ID 255,URL ACL ID 255,URL ACL Action 0) *apfReceiveTask: Oct 14 07:27:52.312: 94:f6:d6:80:35:58 0.0.0.0 DHCP_REQD (7) NO release MSCB *pemReceiveTask: Oct 14 07:27:52.312: 94:f6:d6:80:35:58 0.0.0.0 Added NPU entry of type 9, dtlFlags 0x0 *pemReceiveTask: Oct 14 07:27:52.312: 94:f6:d6:80:35:58 Sent an XID frame *DHCP Socket Task: Oct 14 07:27:53.650: 94:f6:d6:80:35:58 DHCP received op BOOTREQUEST (1) (len 308,vlan 2261, port 1, encap 0xec03, xid 0x2c949cbe) *DHCP Socket Task: Oct 14 07:27:53.650: 94:f6:d6:80:35:58 DHCP option len (including the magic cookie) 72 *DHCP Socket Task: Oct 14 07:27:53.650: 94:f6:d6:80:35:58 DHCP option: message type = DHCP DISCOVER *DHCP Socket Task: Oct 14 07:27:53.650: 94:f6:d6:80:35:58 DHCP option: 55 (len 7) - skipping *DHCP Socket Task: Oct 14 07:27:53.650: 94:f6:d6:80:35:58 DHCP option: 57 (len 2) - skipping *DHCP Socket Task: Oct 14 07:27:53.650: 94:f6:d6:80:35:58 DHCP option: 61 (len 7) - skipping *DHCP Socket Task: Oct 14 07:27:53.650: 94:f6:d6:80:35:58 DHCP option: lease time = 7776000 seconds *DHCP Socket Task: Oct 14 07:27:53.650: 94:f6:d6:80:35:58 DHCP option: hostname = Daves-iPhoneX (len 13) *DHCP Socket Task: Oct 14 07:27:53.650: 94:f6:d6:80:35:58 DHCP options end, len 72, actual 64 *DHCP Socket Task: Oct 14 07:27:53.650: 94:f6:d6:80:35:58 DHCP (encap type 0xec03) mstype 0ff:ff:ff:ff:ff:ff *DHCP Socket Task: Oct 14 07:27:53.650: 94:f6:d6:80:35:58 DHCP intf:xxx_guest dhcp proxy mode disabled *DHCP Socket Task: Oct 14 07:27:53.650: 94:f6:d6:80:35:58 DHCP intf:xxx_guest dhcp proxy mode disabled *DHCP Socket Task: Oct 14 07:27:53.650: 94:f6:d6:80:35:58 DHCP processing DHCP DISCOVER (1) *DHCP Socket Task: Oct 14 07:27:53.650: 94:f6:d6:80:35:58 DHCP op: BOOTREQUEST, htype: Ethernet, hlen: 6, hops: 0 *DHCP Socket Task: Oct 14 07:27:53.650: 94:f6:d6:80:35:58 DHCP xid: 0xbe9c942c (3197932588), secs: 1024, flags: 0 *DHCP Socket Task: Oct 14 07:27:53.650: 94:f6:d6:80:35:58 DHCP chaddr: 94:f6:d6:80:35:58 *DHCP Socket Task: Oct 14 07:27:53.650: 94:f6:d6:80:35:58 DHCP ciaddr: 0.0.0.0, yiaddr: 0.0.0.0 *DHCP Socket Task: Oct 14 07:27:53.650: 94:f6:d6:80:35:58 DHCP siaddr: 0.0.0.0, giaddr: 0.0.0.0 *DHCP Socket Task: Oct 14 07:27:53.650: 94:f6:d6:80:35:58 DHCP Opt82 bridge mode insertion enabled, inserts opt82 if opt82 is enabled vlan=2256, datalen =18, optlen=64 *IPv6_Msg_Task: Oct 14 07:27:53.704: 94:f6:d6:80:35:58 Link Local address fe80::1c11:2243:133f:aa97 updated to mscb. Not Advancing pem state.Current state: mscb in apfMsMmInitial mobility state and client state APF_MS_STATE_A *DHCP Socket Task: Oct 14 07:27:57.846: 94:f6:d6:80:35:58 DHCP received op BOOTREQUEST (1) (len 308,vlan 2261, port 1, encap 0xec03, xid 0x2c949cbe) *DHCP Socket Task: Oct 14 07:27:57.846: 94:f6:d6:80:35:58 DHCP option len (including the magic cookie) 72 *DHCP Socket Task: Oct 14 07:27:57.846: 94:f6:d6:80:35:58 DHCP option: message type = DHCP DISCOVER *DHCP Socket Task: Oct 14 07:27:57.846: 94:f6:d6:80:35:58 DHCP option: 55 (len 7) - skipping *DHCP Socket Task: Oct 14 07:27:57.846: 94:f6:d6:80:35:58 DHCP option: 57 (len 2) - skipping *DHCP Socket Task: Oct 14 07:27:57.846: 94:f6:d6:80:35:58 DHCP option: 61 (len 7) - skipping *DHCP Socket Task: Oct 14 07:27:57.846: 94:f6:d6:80:35:58 DHCP option: lease time = 7776000 seconds *DHCP Socket Task: Oct 14 07:27:57.846: 94:f6:d6:80:35:58 DHCP option: hostname = Daves-iPhoneX (len 13) *DHCP Socket Task: Oct 14 07:27:57.846: 94:f6:d6:80:35:58 DHCP options end, len 72, actual 64 *DHCP Socket Task: Oct 14 07:27:57.846: 94:f6:d6:80:35:58 DHCP (encap type 0xec03) mstype 0ff:ff:ff:ff:ff:ff *DHCP Socket Task: Oct 14 07:27:57.846: 94:f6:d6:80:35:58 DHCP intf:xxx_guest dhcp proxy mode disabled *DHCP Socket Task: Oct 14 07:27:57.846: 94:f6:d6:80:35:58 DHCP intf:xxx_guest dhcp proxy mode disabled *DHCP Socket Task: Oct 14 07:27:57.846: 94:f6:d6:80:35:58 DHCP processing DHCP DISCOVER (1) *DHCP Socket Task: Oct 14 07:27:57.846: 94:f6:d6:80:35:58 DHCP op: BOOTREQUEST, htype: Ethernet, hlen: 6, hops: 0 *DHCP Socket Task: Oct 14 07:27:57.846: 94:f6:d6:80:35:58 DHCP xid: 0xbe9c942c (3197932588), secs: 2048, flags: 0 *DHCP Socket Task: Oct 14 07:27:57.846: 94:f6:d6:80:35:58 DHCP chaddr: 94:f6:d6:80:35:58 *DHCP Socket Task: Oct 14 07:27:57.846: 94:f6:d6:80:35:58 DHCP ciaddr: 0.0.0.0, yiaddr: 0.0.0.0 *DHCP Socket Task: Oct 14 07:27:57.846: 94:f6:d6:80:35:58 DHCP siaddr: 0.0.0.0, giaddr: 0.0.0.0 *DHCP Socket Task: Oct 14 07:27:57.846: 94:f6:d6:80:35:58 DHCP Opt82 bridge mode insertion enabled, inserts opt82 if opt82 is enabled vlan=2256, datalen =18, optlen=64 *DHCP Socket Task: Oct 14 07:28:06.702: 94:f6:d6:80:35:58 DHCP received op BOOTREQUEST (1) (len 308,vlan 2261, port 1, encap 0xec03, xid 0x2c949cbe) *DHCP Socket Task: Oct 14 07:28:06.702: 94:f6:d6:80:35:58 DHCP option: message type = DHCP DISCOVER *DHCP Socket Task: Oct 14 07:28:06.702: 94:f6:d6:80:35:58 DHCP option: 55 (len 7) - skipping *DHCP Socket Task: Oct 14 07:28:06.702: 94:f6:d6:80:35:58 DHCP option: 57 (len 2) - skipping *DHCP Socket Task: Oct 14 07:28:06.702: 94:f6:d6:80:35:58 DHCP option: 61 (len 7) - skipping *DHCP Socket Task: Oct 14 07:28:06.702: 94:f6:d6:80:35:58 DHCP option: lease time = 7776000 seconds *DHCP Socket Task: Oct 14 07:28:06.702: 94:f6:d6:80:35:58 DHCP option: hostname = Daves-iPhoneX (len 13) *DHCP Socket Task: Oct 14 07:28:06.702: 94:f6:d6:80:35:58 DHCP options end, len 72, actual 64 *DHCP Socket Task: Oct 14 07:28:06.702: 94:f6:d6:80:35:58 DHCP intf:xxx_guest dhcp proxy mode disabled *DHCP Socket Task: Oct 14 07:28:06.702: 94:f6:d6:80:35:58 DHCP intf:xxx_guest dhcp proxy mode disabled *DHCP Socket Task: Oct 14 07:28:06.702: 94:f6:d6:80:35:58 DHCP processing DHCP DISCOVER (1) *DHCP Socket Task: Oct 14 07:28:06.702: 94:f6:d6:80:35:58 DHCP op: BOOTREQUEST, htype: Ethernet, hlen: 6, hops: 0 *DHCP Socket Task: Oct 14 07:28:06.702: 94:f6:d6:80:35:58 DHCP xid: 0xbe9c942c (3197932588), secs: 4352, flags: 0 *DHCP Socket Task: Oct 14 07:28:06.702: 94:f6:d6:80:35:58 DHCP chaddr: 94:f6:d6:80:35:58 *DHCP Socket Task: Oct 14 07:28:06.702: 94:f6:d6:80:35:58 DHCP ciaddr: 0.0.0.0, yiaddr: 0.0.0.0 *DHCP Socket Task: Oct 14 07:28:06.702: 94:f6:d6:80:35:58 DHCP siaddr: 0.0.0.0, giaddr: 0.0.0.0 *DHCP Socket Task: Oct 14 07:28:06.702: 94:f6:d6:80:35:58 DHCP Opt82 bridge mode insertion enabled, inserts opt82 if opt82 is enabled vlan=2256, datalen =18, optlen=64 *DHCP Socket Task: Oct 14 07:28:06.702: 94:f6:d6:80:35:58 DHCP successfully bridged packet to DS *DHCP Socket Task: Oct 14 07:28:06.702: 94:f6:d6:80:35:58 Interface Group was NULL.Number of DHCP Discovery 3 from client *apfOrphanSocketTask: Oct 14 07:28:08.562: 94:f6:d6:80:35:58 Orphan Packet from STA - IP 169.254.12.120 *apfOrphanSocketTask: Oct 14 07:28:08.562: 94:f6:d6:80:35:58 In apfMsDhcpStateClear for station 94:f6:d6:80:35:58 to clear dhcp flag *apfOrphanSocketTask: Oct 14 07:28:08.562: 94:f6:d6:80:35:58 In apfRegisterIpAddrOnMscb_debug: regType=2 Invalid src IP address, 169.254.12.120 is part of reserved ip address range (caller apf_broff.c:232) *apfOrphanSocketTask: Oct 14 07:28:08.562: 94:f6:d6:80:35:58 IPv4 Addr: 169:254:12:120 *DHCP Socket Task: Oct 14 07:28:14.873: 94:f6:d6:80:35:58 DHCP received op BOOTREQUEST (1) (len 308,vlan 2261, port 1, encap 0xec03, xid 0x2c949cbe) *DHCP Socket Task: Oct 14 07:28:14.873: 94:f6:d6:80:35:58 DHCP option len (including the magic cookie) 72 *DHCP Socket Task: Oct 14 07:28:14.873: 94:f6:d6:80:35:58 DHCP option: message type = DHCP DISCOVER *DHCP Socket Task: Oct 14 07:28:14.873: 94:f6:d6:80:35:58 DHCP option: 55 (len 7) - skipping *DHCP Socket Task: Oct 14 07:28:14.873: 94:f6:d6:80:35:58 DHCP option: 57 (len 2) - skipping *DHCP Socket Task: Oct 14 07:28:14.873: 94:f6:d6:80:35:58 DHCP option: 61 (len 7) - skipping *DHCP Socket Task: Oct 14 07:28:14.873: 94:f6:d6:80:35:58 DHCP option: lease time = 7776000 seconds *DHCP Socket Task: Oct 14 07:28:14.873: 94:f6:d6:80:35:58 DHCP option: hostname = Daves-iPhoneX (len 13) *DHCP Socket Task: Oct 14 07:28:14.873: 94:f6:d6:80:35:58 DHCP options end, len 72, actual 64 *DHCP Socket Task: Oct 14 07:28:14.873: 94:f6:d6:80:35:58 DHCP (encap type 0xec03) mstype 0ff:ff:ff:ff:ff:ff *DHCP Socket Task: Oct 14 07:28:14.873: 94:f6:d6:80:35:58 DHCP intf:xxx_guest dhcp proxy mode disabled *DHCP Socket Task: Oct 14 07:28:14.873: 94:f6:d6:80:35:58 DHCP intf:xxx_guest dhcp proxy mode disabled *DHCP Socket Task: Oct 14 07:28:14.873: 94:f6:d6:80:35:58 DHCP processing DHCP DISCOVER (1) *DHCP Socket Task: Oct 14 07:28:14.873: 94:f6:d6:80:35:58 DHCP op: BOOTREQUEST, htype: Ethernet, hlen: 6, hops: 0 *DHCP Socket Task: Oct 14 07:28:14.873: 94:f6:d6:80:35:58 DHCP xid: 0xbe9c942c (3197932588), secs: 6400, flags: 0 *DHCP Socket Task: Oct 14 07:28:14.873: 94:f6:d6:80:35:58 DHCP chaddr: 94:f6:d6:80:35:58 *DHCP Socket Task: Oct 14 07:28:14.873: 94:f6:d6:80:35:58 DHCP ciaddr: 0.0.0.0, yiaddr: 0.0.0.0 *DHCP Socket Task: Oct 14 07:28:14.873: 94:f6:d6:80:35:58 DHCP siaddr: 0.0.0.0, giaddr: 0.0.0.0 *DHCP Socket Task: Oct 14 07:28:14.873: 94:f6:d6:80:35:58 DHCP Opt82 bridge mode insertion enabled, inserts opt82 if opt82 is enabled vlan=2256, datalen =18, optlen=64 *DHCP Socket Task: Oct 14 07:28:14.873: 94:f6:d6:80:35:58 DHCP successfully bridged packet to DS *DHCP Socket Task: Oct 14 07:28:23.242: 94:f6:d6:80:35:58 DHCP received op BOOTREQUEST (1) (len 308,vlan 2261, port 1, encap 0xec03, xid 0x2c949cbe) *DHCP Socket Task: Oct 14 07:28:23.242: 94:f6:d6:80:35:58 DHCP option len (including the magic cookie) 72 *DHCP Socket Task: Oct 14 07:28:23.242: 94:f6:d6:80:35:58 DHCP option: message type = DHCP DISCOVER *DHCP Socket Task: Oct 14 07:28:23.242: 94:f6:d6:80:35:58 DHCP option: 55 (len 7) - skipping *DHCP Socket Task: Oct 14 07:28:23.242: 94:f6:d6:80:35:58 DHCP option: 57 (len 2) - skipping *DHCP Socket Task: Oct 14 07:28:23.242: 94:f6:d6:80:35:58 DHCP option: 61 (len 7) - skipping *DHCP Socket Task: Oct 14 07:28:23.242: 94:f6:d6:80:35:58 DHCP option: lease time = 7776000 seconds *DHCP Socket Task: Oct 14 07:28:23.242: 94:f6:d6:80:35:58 DHCP option: hostname = Daves-iPhoneX (len 13) *DHCP Socket Task: Oct 14 07:28:23.242: 94:f6:d6:80:35:58 DHCP options end, len 72, actual 64 *DHCP Socket Task: Oct 14 07:28:23.242: 94:f6:d6:80:35:58 DHCP (encap type 0xec03) mstype 0ff:ff:ff:ff:ff:ff *DHCP Socket Task: Oct 14 07:28:23.242: 94:f6:d6:80:35:58 DHCP intf:xxx_guest dhcp proxy mode disabled *DHCP Socket Task: Oct 14 07:28:23.242: 94:f6:d6:80:35:58 DHCP intf:xxx_guest dhcp proxy mode disabled *DHCP Socket Task: Oct 14 07:28:23.242: 94:f6:d6:80:35:58 DHCP processing DHCP DISCOVER (1) *DHCP Socket Task: Oct 14 07:28:23.242: 94:f6:d6:80:35:58 DHCP op: BOOTREQUEST, htype: Ethernet, hlen: 6, hops: 0 *DHCP Socket Task: Oct 14 07:28:23.242: 94:f6:d6:80:35:58 DHCP xid: 0xbe9c942c (3197932588), secs: 8704, flags: 0 *DHCP Socket Task: Oct 14 07:28:23.242: 94:f6:d6:80:35:58 DHCP chaddr: 94:f6:d6:80:35:58 *DHCP Socket Task: Oct 14 07:28:23.242: 94:f6:d6:80:35:58 DHCP ciaddr: 0.0.0.0, yiaddr: 0.0.0.0 *DHCP Socket Task: Oct 14 07:28:23.242: 94:f6:d6:80:35:58 DHCP siaddr: 0.0.0.0, giaddr: 0.0.0.0 *DHCP Socket Task: Oct 14 07:28:23.242: 94:f6:d6:80:35:58 DHCP Opt82 bridge mode insertion enabled, inserts opt82 if opt82 is enabled vlan=2256, datalen =18, optlen=64 *DHCP Socket Task: Oct 14 07:28:23.242: 94:f6:d6:80:35:58 DHCP successfully bridged packet to DS *dhcpClientTimerTask: Oct 14 07:28:26.581: Sync DHCP Flex stats *DHCP Socket Task: Oct 14 07:28:31.633: 94:f6:d6:80:35:58 DHCP received op BOOTREQUEST (1) (len 308,vlan 2261, port 1, encap 0xec03, xid 0x2c949cbe) *DHCP Socket Task: Oct 14 07:28:31.633: 94:f6:d6:80:35:58 DHCP option len (including the magic cookie) 72 *DHCP Socket Task: Oct 14 07:28:31.633: 94:f6:d6:80:35:58 DHCP option: message type = DHCP DISCOVER *DHCP Socket Task: Oct 14 07:28:31.633: 94:f6:d6:80:35:58 DHCP option: 55 (len 7) - skipping *DHCP Socket Task: Oct 14 07:28:31.633: 94:f6:d6:80:35:58 DHCP option: 57 (len 2) - skipping *DHCP Socket Task: Oct 14 07:28:31.633: 94:f6:d6:80:35:58 DHCP option: 61 (len 7) - skipping *DHCP Socket Task: Oct 14 07:28:31.633: 94:f6:d6:80:35:58 DHCP option: lease time = 7776000 seconds *DHCP Socket Task: Oct 14 07:28:31.633: 94:f6:d6:80:35:58 DHCP option: hostname = Daves-iPhoneX (len 13) *DHCP Socket Task: Oct 14 07:28:31.633: 94:f6:d6:80:35:58 DHCP options end, len 72, actual 64 *DHCP Socket Task: Oct 14 07:28:31.633: 94:f6:d6:80:35:58 DHCP (encap type 0xec03) mstype 0ff:ff:ff:ff:ff:ff *DHCP Socket Task: Oct 14 07:28:31.633: 94:f6:d6:80:35:58 DHCP intf:xxx_guest dhcp proxy mode disabled *DHCP Socket Task: Oct 14 07:28:31.633: 94:f6:d6:80:35:58 DHCP intf:xxx_guest dhcp proxy mode disabled *DHCP Socket Task: Oct 14 07:28:31.633: 94:f6:d6:80:35:58 DHCP processing DHCP DISCOVER (1) *DHCP Socket Task: Oct 14 07:28:31.633: 94:f6:d6:80:35:58 DHCP op: BOOTREQUEST, htype: Ethernet, hlen: 6, hops: 0 *DHCP Socket Task: Oct 14 07:28:31.633: 94:f6:d6:80:35:58 DHCP xid: 0xbe9c942c (3197932588), secs: 10752, flags: 0 *DHCP Socket Task: Oct 14 07:28:31.633: 94:f6:d6:80:35:58 DHCP chaddr: 94:f6:d6:80:35:58 *DHCP Socket Task: Oct 14 07:28:31.633: 94:f6:d6:80:35:58 DHCP ciaddr: 0.0.0.0, yiaddr: 0.0.0.0 *DHCP Socket Task: Oct 14 07:28:31.633: 94:f6:d6:80:35:58 DHCP siaddr: 0.0.0.0, giaddr: 0.0.0.0 *DHCP Socket Task: Oct 14 07:28:31.633: 94:f6:d6:80:35:58 DHCP Opt82 bridge mode insertion enabled, inserts opt82 if opt82 is enabled vlan=2256, datalen =18, optlen=64 *DHCP Socket Task: Oct 14 07:28:31.633: 94:f6:d6:80:35:58 DHCP successfully bridged packet to DS *DHCP Socket Task: Oct 14 07:28:31.633: 94:f6:d6:80:35:58 Interface Group was NULL.Number of DHCP Discovery 3 from client *DHCP Socket Task: Oct 14 07:28:39.942: 94:f6:d6:80:35:58 DHCP received op BOOTREQUEST (1) (len 308,vlan 2261, port 1, encap 0xec03, xid 0x2c949cbe) *DHCP Socket Task: Oct 14 07:28:39.942: 94:f6:d6:80:35:58 DHCP option len (including the magic cookie) 72 *DHCP Socket Task: Oct 14 07:28:39.942: 94:f6:d6:80:35:58 DHCP option: message type = DHCP DISCOVER *DHCP Socket Task: Oct 14 07:28:39.942: 94:f6:d6:80:35:58 DHCP option: 55 (len 7) - skipping *DHCP Socket Task: Oct 14 07:28:39.942: 94:f6:d6:80:35:58 DHCP option: 57 (len 2) - skipping *DHCP Socket Task: Oct 14 07:28:39.942: 94:f6:d6:80:35:58 DHCP option: 61 (len 7) - skipping *DHCP Socket Task: Oct 14 07:28:39.942: 94:f6:d6:80:35:58 DHCP option: lease time = 7776000 seconds *DHCP Socket Task: Oct 14 07:28:39.942: 94:f6:d6:80:35:58 DHCP option: hostname = Daves-iPhoneX (len 13) *DHCP Socket Task: Oct 14 07:28:39.942: 94:f6:d6:80:35:58 DHCP options end, len 72, actual 64 *DHCP Socket Task: Oct 14 07:28:39.942: 94:f6:d6:80:35:58 DHCP (encap type 0xec03) mstype 0ff:ff:ff:ff:ff:ff *DHCP Socket Task: Oct 14 07:28:39.942: 94:f6:d6:80:35:58 DHCP intf:xxx_guest dhcp proxy mode disabled *DHCP Socket Task: Oct 14 07:28:39.942: 94:f6:d6:80:35:58 DHCP intf:xxx_guest dhcp proxy mode disabled *DHCP Socket Task: Oct 14 07:28:39.942: 94:f6:d6:80:35:58 DHCP processing DHCP DISCOVER (1) *DHCP Socket Task: Oct 14 07:28:39.942: 94:f6:d6:80:35:58 DHCP op: BOOTREQUEST, htype: Ethernet, hlen: 6, hops: 0 *DHCP Socket Task: Oct 14 07:28:39.942: 94:f6:d6:80:35:58 DHCP xid: 0xbe9c942c (3197932588), secs: 13056, flags: 0 *DHCP Socket Task: Oct 14 07:28:39.942: 94:f6:d6:80:35:58 DHCP chaddr: 94:f6:d6:80:35:58 *DHCP Socket Task: Oct 14 07:28:39.942: 94:f6:d6:80:35:58 DHCP ciaddr: 0.0.0.0, yiaddr: 0.0.0.0 *DHCP Socket Task: Oct 14 07:28:39.942: 94:f6:d6:80:35:58 DHCP siaddr: 0.0.0.0, giaddr: 0.0.0.0 *DHCP Socket Task: Oct 14 07:28:39.942: 94:f6:d6:80:35:58 DHCP Opt82 bridge mode insertion enabled, inserts opt82 if opt82 is enabled vlan=2256, datalen =18, optlen=64 *DHCP Socket Task: Oct 14 07:28:39.942: 94:f6:d6:80:35:58 DHCP successfully bridged packet to DS *DHCP Socket Task: Oct 14 07:28:48.567: 94:f6:d6:80:35:58 DHCP received op BOOTREQUEST (1) (len 308,vlan 2261, port 1, encap 0xec03, xid 0x2c949cbe) *DHCP Socket Task: Oct 14 07:28:48.567: 94:f6:d6:80:35:58 DHCP option len (including the magic cookie) 72 *DHCP Socket Task: Oct 14 07:28:48.567: 94:f6:d6:80:35:58 DHCP option: message type = DHCP DISCOVER *DHCP Socket Task: Oct 14 07:28:48.567: 94:f6:d6:80:35:58 DHCP option: 55 (len 7) - skipping *DHCP Socket Task: Oct 14 07:28:48.567: 94:f6:d6:80:35:58 DHCP option: 57 (len 2) - skipping *DHCP Socket Task: Oct 14 07:28:48.567: 94:f6:d6:80:35:58 DHCP option: 61 (len 7) - skipping *DHCP Socket Task: Oct 14 07:28:48.567: 94:f6:d6:80:35:58 DHCP option: lease time = 7776000 seconds *DHCP Socket Task: Oct 14 07:28:48.567: 94:f6:d6:80:35:58 DHCP option: hostname = Daves-iPhoneX (len 13) *DHCP Socket Task: Oct 14 07:28:48.567: 94:f6:d6:80:35:58 DHCP options end, len 72, actual 64 *DHCP Socket Task: Oct 14 07:28:48.567: 94:f6:d6:80:35:58 DHCP (encap type 0xec03) mstype 0ff:ff:ff:ff:ff:ff *DHCP Socket Task: Oct 14 07:28:48.567: 94:f6:d6:80:35:58 DHCP intf:xxx_guest dhcp proxy mode disabled *DHCP Socket Task: Oct 14 07:28:48.567: 94:f6:d6:80:35:58 DHCP intf:xxx_guest dhcp proxy mode disabled *DHCP Socket Task: Oct 14 07:28:48.567: 94:f6:d6:80:35:58 DHCP processing DHCP DISCOVER (1) *DHCP Socket Task: Oct 14 07:28:48.567: 94:f6:d6:80:35:58 DHCP op: BOOTREQUEST, htype: Ethernet, hlen: 6, hops: 0 *DHCP Socket Task: Oct 14 07:28:48.567: 94:f6:d6:80:35:58 DHCP xid: 0xbe9c942c (3197932588), secs: 15104, flags: 0 *DHCP Socket Task: Oct 14 07:28:48.567: 94:f6:d6:80:35:58 DHCP chaddr: 94:f6:d6:80:35:58 *DHCP Socket Task: Oct 14 07:28:48.567: 94:f6:d6:80:35:58 DHCP ciaddr: 0.0.0.0, yiaddr: 0.0.0.0 *DHCP Socket Task: Oct 14 07:28:48.567: 94:f6:d6:80:35:58 DHCP siaddr: 0.0.0.0, giaddr: 0.0.0.0 *DHCP Socket Task: Oct 14 07:28:48.567: 94:f6:d6:80:35:58 DHCP Opt82 bridge mode insertion enabled, inserts opt82 if opt82 is enabled vlan=2256, datalen =18, optlen=64