Request log details for session: R0005f4e1-01-5d72afc8

Time Message
2019-09-06 15:13:12,907 [Th 456 Req 2283837 SessId R0005f4e1-01-5d72afc8] INFO RadiusServer.Radius - rlm_service: Starting Service Categorization - 228:193:48437CBEB8B3
2019-09-06 15:13:12,912 [RequestHandler-1-0x7fd6ea5b2700 r=psauto-1561986666-5093514 h=607 r=R0005f4e1-01-5d72afc8] INFO Core.ServiceReqHandler - Service classification result = LAGCC - 802.1x Wireless Authentication
2019-09-06 15:13:12,913 [Th 456 Req 2283837 SessId R0005f4e1-01-5d72afc8] INFO RadiusServer.Radius - Service Categorization time = 6 ms
2019-09-06 15:13:12,914 [Th 456 Req 2283837 SessId R0005f4e1-01-5d72afc8] INFO RadiusServer.Radius - rlm_service: The request has been categorized into service "LAGCC - 802.1x Wireless Authentication"
2019-09-06 15:13:12,914 [Th 456 Req 2283837 SessId R0005f4e1-01-5d72afc8] INFO RadiusServer.Radius - rlm_ldap: searching for user kortizabreu in AD:lgccdc2008.laguardia.cc
2019-09-06 15:13:12,915 [Th 456 Req 2283837 SessId R0005f4e1-01-5d72afc8] INFO RadiusServer.Radius - rlm_ldap: found user kortizabreu in AD:lgccdc2008.laguardia.cc
2019-09-06 15:13:12,915 [Th 456 Req 2283837 SessId R0005f4e1-01-5d72afc8] INFO RadiusServer.Radius - LDAP/AD User lookup time = 1 ms
2019-09-06 15:13:12,915 [Th 456 Req 2283837 SessId R0005f4e1-01-5d72afc8] INFO RadiusServer.Radius - rlm_eap_peap: Initiate
2019-09-06 15:13:12,915 [Th 456 Req 2283837 SessId R0005f4e1-01-5d72afc8] INFO RadiusServer.Radius - reqst_update_state: Access-Challenge 228:88:48437CBEB8B3:AEwA/QCPAFI92SIACkGcMfCd1zFAh3J4X/kqBw==
2019-09-06 15:13:12,926 [Th 463 Req 2283838 SessId R0005f4e1-01-5d72afc8] INFO RadiusServer.Radius - rlm_service: The request was categorized into service "LAGCC - 802.1x Wireless Authentication" - 180:380:48437CBEB8B3
2019-09-06 15:13:12,929 [Th 463 Req 2283838 SessId R0005f4e1-01-5d72afc8] INFO RadiusServer.Radius - TLS_accept:error in SSLv3 read client key exchange A
2019-09-06 15:13:12,929 [Th 463 Req 2283838 SessId R0005f4e1-01-5d72afc8] INFO RadiusServer.Radius - TLS_accept:error in SSLv3 read client key exchange A
2019-09-06 15:13:12,929 [Th 463 Req 2283838 SessId R0005f4e1-01-5d72afc8] INFO RadiusServer.Radius - reqst_update_state: Access-Challenge 180:1124:48437CBEB8B3:ANAAqgDrAD4+2SIAe4jMlD3cQ5x6iKNFurIpsw==
2019-09-06 15:13:12,955 [Th 458 Req 2283839 SessId R0005f4e1-01-5d72afc8] INFO RadiusServer.Radius - rlm_service: The request was categorized into service "LAGCC - 802.1x Wireless Authentication" - 126:225:48437CBEB8B3
2019-09-06 15:13:12,955 [Th 458 Req 2283839 SessId R0005f4e1-01-5d72afc8] INFO RadiusServer.Radius - reqst_update_state: Access-Challenge 126:1120:48437CBEB8B3:AIMA2wDLANE/2SIAYkpD7AoNiUJwglFWEh+5ZA==
2019-09-06 15:13:12,971 [Th 460 Req 2283840 SessId R0005f4e1-01-5d72afc8] INFO RadiusServer.Radius - rlm_service: The request was categorized into service "LAGCC - 802.1x Wireless Authentication" - 124:225:48437CBEB8B3
2019-09-06 15:13:12,972 [Th 460 Req 2283840 SessId R0005f4e1-01-5d72afc8] INFO RadiusServer.Radius - reqst_update_state: Access-Challenge 124:1120:48437CBEB8B3:AAMAvwDJAIlA2SIAtAkt/hTzvIw1S94wqTjuXg==
2019-09-06 15:13:12,983 [Th 455 Req 2283841 SessId R0005f4e1-01-5d72afc8] INFO RadiusServer.Radius - rlm_service: The request was categorized into service "LAGCC - 802.1x Wireless Authentication" - 45:225:48437CBEB8B3
2019-09-06 15:13:12,984 [Th 455 Req 2283841 SessId R0005f4e1-01-5d72afc8] INFO RadiusServer.Radius - reqst_update_state: Access-Challenge 45:977:48437CBEB8B3:AE0AVABeAH1B2SIApJ/cVGKP7kqGz6pJhDBzCA==
2019-09-06 15:13:13,037 [Th 457 Req 2283842 SessId R0005f4e1-01-5d72afc8] INFO RadiusServer.Radius - rlm_service: The request was categorized into service "LAGCC - 802.1x Wireless Authentication" - 1:355:48437CBEB8B3
2019-09-06 15:13:13,038 [Th 457 Req 2283842 SessId R0005f4e1-01-5d72afc8] INFO RadiusServer.Radius - reqst_update_state: Access-Challenge 1:143:48437CBEB8B3:APQAvwC4ACJC2SIAkN8SqB+pWBXZtraRPOo3KA==
2019-09-06 15:13:13,054 [Th 464 Req 2283843 SessId R0005f4e1-01-5d72afc8] INFO RadiusServer.Radius - rlm_service: The request was categorized into service "LAGCC - 802.1x Wireless Authentication" - 158:225:48437CBEB8B3
2019-09-06 15:13:13,054 [Th 464 Req 2283843 SessId R0005f4e1-01-5d72afc8] INFO RadiusServer.Radius - rlm_eap_peap: Session established.
2019-09-06 15:13:13,055 [Th 464 Req 2283843 SessId R0005f4e1-01-5d72afc8] INFO RadiusServer.Radius - reqst_update_state: Access-Challenge 158:122:48437CBEB8B3:AN0AywCQAKtD2SIAgEWFnRz5CEhrSL5Fa+oSrA==
2019-09-06 15:13:13,063 [Th 462 Req 2283844 SessId R0005f4e1-01-5d72afc8] INFO RadiusServer.Radius - rlm_service: The request was categorized into service "LAGCC - 802.1x Wireless Authentication" - 218:270:48437CBEB8B3
2019-09-06 15:13:13,063 [Th 462 Req 2283844 SessId R0005f4e1-01-5d72afc8] INFO RadiusServer.Radius - rlm_eap_mschapv2: Issuing Challenge
2019-09-06 15:13:13,064 [Th 462 Req 2283844 SessId R0005f4e1-01-5d72afc8] INFO RadiusServer.Radius - reqst_update_state: Access-Challenge 218:154:48437CBEB8B3:AMIAvwDDAO9E2SIAR1RXRDNZ4DHzWcww0IjMiA==
2019-09-06 15:13:13,076 [Th 459 Req 2283845 SessId R0005f4e1-01-5d72afc8] INFO RadiusServer.Radius - rlm_service: The request was categorized into service "LAGCC - 802.1x Wireless Authentication" - 170:324:48437CBEB8B3
2019-09-06 15:13:13,076 [Th 459 Req 2283845 SessId R0005f4e1-01-5d72afc8] INFO RadiusServer.Radius - rlm_service: The request was categorized into service "LAGCC - 802.1x Wireless Authentication" - 69:0:48437CBEB8B3
2019-09-06 15:13:13,076 [Th 459 Req 2283845 SessId R0005f4e1-01-5d72afc8] INFO RadiusServer.Radius - rlm_eap_mschapv2: Received MSCHAPv2 Response from client
2019-09-06 15:13:13,077 [Th 459 Req 2283845 SessId R0005f4e1-01-5d72afc8] INFO RadiusServer.Radius - rlm_mschap: MSCHAPv2 username used for challenge computation kortizabreu
2019-09-06 15:13:13,077 [Th 459 Req 2283845 SessId R0005f4e1-01-5d72afc8] INFO RadiusServer.Radius - rlm_mschap: Using domain LAGUARDIA from objectSid attribute
2019-09-06 15:13:13,077 [Th 459 Req 2283845 SessId R0005f4e1-01-5d72afc8] INFO RadiusServer.Radius - rlm_mschap: authenticating user kortizabreu, domain LAGUARDIA
2019-09-06 15:13:13,083 [Th 459 Req 2283845 SessId R0005f4e1-01-5d72afc8] INFO RadiusServer.Radius - rlm_mschap: user kortizabreu authenticated successfully
2019-09-06 15:13:13,083 [Th 459 Req 2283845 SessId R0005f4e1-01-5d72afc8] INFO RadiusServer.Radius - MS-Chap User Authentication time = 6 ms
2019-09-06 15:13:13,083 [Th 459 Req 2283845 SessId R0005f4e1-01-5d72afc8] INFO RadiusServer.Radius - rlm_eap_mschapv2: Sending MSCHAPv2 Success reply
2019-09-06 15:13:13,084 [Th 459 Req 2283845 SessId R0005f4e1-01-5d72afc8] INFO RadiusServer.Radius - reqst_update_state: Access-Challenge 170:168:48437CBEB8B3:AMkAngDSAJ5F2SIAdCxFo72OJZ6i6yH2FesodQ==
2019-09-06 15:13:13,195 [Th 461 Req 2283846 SessId R0005f4e1-01-5d72afc8] INFO RadiusServer.Radius - rlm_service: The request was categorized into service "LAGCC - 802.1x Wireless Authentication" - 161:260:48437CBEB8B3
2019-09-06 15:13:13,195 [Th 461 Req 2283846 SessId R0005f4e1-01-5d72afc8] INFO RadiusServer.Radius - rlm_service: The request was categorized into service "LAGCC - 802.1x Wireless Authentication" - 70:0:48437CBEB8B3
2019-09-06 15:13:13,195 [Th 461 Req 2283846 SessId R0005f4e1-01-5d72afc8] INFO RadiusServer.Radius - rlm_eap_mschapv2: Received MSCHAPv2 Success from client
2019-09-06 15:13:13,195 [Th 461 Req 2283846 SessId R0005f4e1-01-5d72afc8] INFO RadiusServer.Radius - rlm_policy: Starting Policy Evaluation.
2019-09-06 15:13:13,199 [RequestHandler-1-0x7fd6ea5b2700 r=psauto-1561986666-5093515 h=655 r=R0005f4e1-01-5d72afc8] INFO Common.EndpointTable - Returning NULL (EndpointPtr) for macAddr 48437cbeb8b3
2019-09-06 15:13:13,199 [RequestHandler-1-0x7fd6ea5b2700 r=psauto-1561986666-5093515 h=655 r=R0005f4e1-01-5d72afc8] INFO Common.TagDefinitionCacheTable - No InstanceTagDefCacheMap found for instance id = 3003 entity id = 29
2019-09-06 15:13:13,199 [RequestHandler-1-0x7fd6ea5b2700 r=psauto-1561986666-5093515 h=655 r=R0005f4e1-01-5d72afc8] INFO Common.TagDefinitionCacheTable - Building the TagDefMapTable for NAD instance=3003
2019-09-06 15:13:13,199 [RequestHandler-1-0x7fd6ea5b2700 r=psauto-1561986666-5093515 h=655 r=R0005f4e1-01-5d72afc8] INFO Common.TagDefinitionCacheTable - Built 0 tag(s) for NAD instanceId=3003|entityId=29
2019-09-06 15:13:13,199 [RequestHandler-1-0x7fd6ea5b2700 r=psauto-1561986666-5093515 h=655 r=R0005f4e1-01-5d72afc8] INFO TAT.TagAttrHolderBuilder - No tags built for instanceId=3003|entity=Device
2019-09-06 15:13:13,200 [RequestHandler-1-0x7fd6ea5b2700 r=psauto-1561986666-5093515 h=655 r=R0005f4e1-01-5d72afc8] INFO TAT.AluTagAttrHolderBuilder - buildAttrHolder: Tags cannot be built for instanceId=0 (NULL AuthLocalUser)
2019-09-06 15:13:13,200 [RequestHandler-1-0x7fd6ea5b2700 r=psauto-1561986666-5093515 h=655 r=R0005f4e1-01-5d72afc8] INFO TAT.GuTagAttrHolderBuilder - buildAttrHolder: Tags cannot be built for instanceId=0 (NULL GuestUser)
2019-09-06 15:13:13,200 [RequestHandler-1-0x7fd6ea5b2700 r=psauto-1561986666-5093515 h=655 r=R0005f4e1-01-5d72afc8] INFO TAT.EndpointTagAttrHolderBuilder - buildAttrHolder: Tags cannot be built for instanceId=0 (NULL Endpoint)
2019-09-06 15:13:13,200 [RequestHandler-1-0x7fd6ea5b2700 r=psauto-1561986666-5093515 h=655 r=R0005f4e1-01-5d72afc8] INFO TAT.OnboardTagAttrHolderBuilder - buildAttrHolder: Tags cannot be built for instanceId=0 (NULL Onboard Device User)
2019-09-06 15:13:13,200 [RequestHandler-1-0x7fd6ea5b2700 h=44403114 c=R0005f4e1-01-5d72afc8] INFO Core.PETaskScheduler - *** PE_TASK_SCHEDULE_RADIUS Started ***
2019-09-06 15:13:13,200 [RequestHandler-1-0x7fd6ea5b2700 h=44403114 c=R0005f4e1-01-5d72afc8] INFO Core.PETaskScheduler - ** Starting PETaskAuthSourceRestriction **
2019-09-06 15:13:13,201 [RequestHandler-1-0x7fd6ea5b2700 h=44403114 c=R0005f4e1-01-5d72afc8] INFO Core.PETaskScheduler - ** Starting PETaskRoleMapping **
2019-09-06 15:13:13,201 [RequestHandler-1-0x7fd6ea5b2700 h=44403115 c=R0005f4e1-01-5d72afc8] WARN REC.EvaluatorCtx - Prerequisites set is empty, not populating the Request Map
2019-09-06 15:13:13,202 [RequestHandler-1-0x7fd6ea5b2700 r=R0005f4e1-01-5d72afc8 h=44403114 c=R0005f4e1-01-5d72afc8] INFO Core.PETaskScheduler - ** Completed PETaskAuthSourceRestriction **
2019-09-06 15:13:13,206 [RequestHandler-1-0x7fd6ea5b2700 h=44403116 c=R0005f4e1-01-5d72afc8] INFO Core.PETaskRoleMapping - Roles: Other], User Authenticated]
2019-09-06 15:13:13,206 [RequestHandler-1-0x7fd6ea5b2700 r=R0005f4e1-01-5d72afc8 h=44403114 c=R0005f4e1-01-5d72afc8] INFO Core.PETaskScheduler - ** Completed PETaskRoleMapping **
2019-09-06 15:13:13,206 [RequestHandler-1-0x7fd6ea5b2700 r=R0005f4e1-01-5d72afc8 h=44403114 c=R0005f4e1-01-5d72afc8] INFO Core.PETaskScheduler - ** Starting PETaskPolicyResult **
2019-09-06 15:13:13,207 [RequestHandler-1-0x7fd6ea5b2700 r=R0005f4e1-01-5d72afc8 h=44403114 c=R0005f4e1-01-5d72afc8] INFO Core.PETaskScheduler - ** Completed PETaskPolicyResult **
2019-09-06 15:13:13,207 [RequestHandler-1-0x7fd6ea5b2700 r=R0005f4e1-01-5d72afc8 h=44403114 c=R0005f4e1-01-5d72afc8] INFO Core.PETaskScheduler - ** Starting PETaskEnforcement **
2019-09-06 15:13:13,208 [RequestHandler-1-0x7fd6ea5b2700 h=44403119 c=R0005f4e1-01-5d72afc8] INFO Core.PETaskEnforcement - EnfProfiles: Deny Access Profile]
2019-09-06 15:13:13,208 [RequestHandler-1-0x7fd6ea5b2700 r=R0005f4e1-01-5d72afc8 h=44403114 c=R0005f4e1-01-5d72afc8] INFO Core.PETaskScheduler - ** Completed PETaskEnforcement **
2019-09-06 15:13:13,208 [RequestHandler-1-0x7fd6ea5b2700 r=R0005f4e1-01-5d72afc8 h=44403114 c=R0005f4e1-01-5d72afc8] INFO Core.PETaskScheduler - ** Starting PETaskRadiusEnfProfileBuilder **
2019-09-06 15:13:13,208 [RequestHandler-1-0x7fd6ea5b2700 r=R0005f4e1-01-5d72afc8 h=44403114 c=R0005f4e1-01-5d72afc8] INFO Core.PETaskScheduler - ** Starting PETaskRadiusCoAEnfProfileBuilder **
2019-09-06 15:13:13,208 [RequestHandler-1-0x7fd6ea5b2700 r=R0005f4e1-01-5d72afc8 h=44403114 c=R0005f4e1-01-5d72afc8] INFO Core.PETaskScheduler - ** Starting PETaskAppEnfProfileBuilder **
2019-09-06 15:13:13,209 [RequestHandler-1-0x7fd6ea5b2700 r=R0005f4e1-01-5d72afc8 h=44403114 c=R0005f4e1-01-5d72afc8] INFO Core.PETaskScheduler - ** Starting PETaskAgentEnfProfileBuilder **
2019-09-06 15:13:13,209 [RequestHandler-1-0x7fd6ea5b2700 r=R0005f4e1-01-5d72afc8 h=44403114 c=R0005f4e1-01-5d72afc8] INFO Core.PETaskScheduler - ** Starting PETaskPostAuthEnfProfileBuilder **
2019-09-06 15:13:13,209 [RequestHandler-1-0x7fd6ea5b2700 r=R0005f4e1-01-5d72afc8 h=44403114 c=R0005f4e1-01-5d72afc8] INFO Core.PETaskScheduler - ** Starting PETaskGenericEnfProfileBuilder **
2019-09-06 15:13:13,209 [RequestHandler-1-0x7fd6ea5b2700 h=44403125 c=R0005f4e1-01-5d72afc8] INFO Core.PETaskGenericEnfProfileBuilder - getApplicableProfiles: No App enforcement (Generic) profiles applicable for this device
2019-09-06 15:13:13,209 [RequestHandler-1-0x7fd6ea5b2700 h=44403120 c=R0005f4e1-01-5d72afc8] INFO Core.PETaskRadiusEnfProfileBuilder - EnfProfileAction=DENY
2019-09-06 15:13:13,209 [RequestHandler-1-0x7fd6ea5b2700 h=44403120 c=R0005f4e1-01-5d72afc8] INFO Core.PETaskRadiusEnfProfileBuilder - Radius enfProfiles used: Deny Access Profile]
2019-09-06 15:13:13,210 [RequestHandler-1-0x7fd6ea5b2700 h=44403120 c=R0005f4e1-01-5d72afc8] INFO Core.EnfProfileComputer - getFinalSessionTimeout: sessionTimeout = 0
2019-09-06 15:13:13,210 [RequestHandler-1-0x7fd6ea5b2700 r=R0005f4e1-01-5d72afc8 h=44403114 c=R0005f4e1-01-5d72afc8] INFO Core.PETaskScheduler - ** Completed PETaskGenericEnfProfileBuilder **
2019-09-06 15:13:13,210 [RequestHandler-1-0x7fd6ea5b2700 r=R0005f4e1-01-5d72afc8 h=44403114 c=R0005f4e1-01-5d72afc8] INFO Core.PETaskScheduler - ** Completed PETaskAgentEnfProfileBuilder **
2019-09-06 15:13:13,210 [RequestHandler-1-0x7fd6ea5b2700 r=R0005f4e1-01-5d72afc8 h=44403114 c=R0005f4e1-01-5d72afc8] INFO Core.PETaskScheduler - ** Completed PETaskAppEnfProfileBuilder **
2019-09-06 15:13:13,210 [RequestHandler-1-0x7fd6ea5b2700 r=R0005f4e1-01-5d72afc8 h=44403114 c=R0005f4e1-01-5d72afc8] INFO Core.PETaskScheduler - ** Starting PETaskCliEnforcement **
2019-09-06 15:13:13,210 [RequestHandler-1-0x7fd6ea5b2700 h=44403126 c=R0005f4e1-01-5d72afc8] INFO Core.PETaskCliEnforcement - startHandler: Request rejected. Skip CLI enforcement
2019-09-06 15:13:13,210 [RequestHandler-1-0x7fd6ea5b2700 r=R0005f4e1-01-5d72afc8 h=44403114 c=R0005f4e1-01-5d72afc8] INFO Core.PETaskScheduler - ** Completed PETaskRadiusEnfProfileBuilder **
2019-09-06 15:13:13,211 [RequestHandler-1-0x7fd6ea5b2700 r=R0005f4e1-01-5d72afc8 h=44403121 c=R0005f4e1-01-5d72afc8] WARN Core.PETaskRadiusCoAEnfProfileBuilder - handleHttpResponseEv: Fetching Radius attributes from battery failed, errMsg=
2019-09-06 15:13:13,211 [RequestHandler-1-0x7fd6ea5b2700 r=R0005f4e1-01-5d72afc8 h=44403124 c=R0005f4e1-01-5d72afc8] WARN Core.PETaskPostAuthEnfProfileBuilder - handleHttpResponseEv: Fetching Radius attributes from battery failed, errMsg=
2019-09-06 15:13:13,211 [RequestHandler-1-0x7fd6ea5b2700 r=R0005f4e1-01-5d72afc8 h=44403124 c=R0005f4e1-01-5d72afc8] INFO Core.PETaskPostAuthEnfProfileBuilder - getApplicableProfiles: No Post auth enforcement profiles applicable for this device
2019-09-06 15:13:13,211 [RequestHandler-1-0x7fd6ea5b2700 r=R0005f4e1-01-5d72afc8 h=44403114 c=R0005f4e1-01-5d72afc8] INFO Core.PETaskScheduler - ** Completed PETaskCliEnforcement **
2019-09-06 15:13:13,211 [RequestHandler-1-0x7fd6ea5b2700 r=R0005f4e1-01-5d72afc8 h=44403114 c=R0005f4e1-01-5d72afc8] INFO Core.PETaskScheduler - ** Completed PETaskRadiusCoAEnfProfileBuilder **
2019-09-06 15:13:13,211 [RequestHandler-1-0x7fd6ea5b2700 r=R0005f4e1-01-5d72afc8 h=44403114 c=R0005f4e1-01-5d72afc8] INFO Core.PETaskScheduler - ** Completed PETaskPostAuthEnfProfileBuilder **
2019-09-06 15:13:13,211 [RequestHandler-1-0x7fd6ea5b2700 r=R0005f4e1-01-5d72afc8 h=44403114 c=R0005f4e1-01-5d72afc8] INFO Core.PETaskScheduler - ** Starting PETaskAuthStatusInfo **
2019-09-06 15:13:13,211 [RequestHandler-1-0x7fd6ea5b2700 r=R0005f4e1-01-5d72afc8 h=44403114 c=R0005f4e1-01-5d72afc8] INFO Core.PETaskScheduler - ** Starting PETaskOutputPolicyRes **
2019-09-06 15:13:13,211 [RequestHandler-1-0x7fd6ea5b2700 r=R0005f4e1-01-5d72afc8 h=44403114 c=R0005f4e1-01-5d72afc8] INFO Core.PETaskScheduler - ** Starting PETaskSessionLog **
2019-09-06 15:13:13,217 [RequestHandler-1-0x7fd6ea5b2700 h=44403128 c=R0005f4e1-01-5d72afc8] INFO Core.XpipPolicyResHandler - populateResponseTlv: PETaskPostureOutput does not exist. Skip sending posture VAFs
2019-09-06 15:13:13,217 [RequestHandler-1-0x7fd6ea5b2700 h=44403128 c=R0005f4e1-01-5d72afc8] INFO Core.PolicyResCollector - getSohr: Failed to generate Sohr
2019-09-06 15:13:13,217 [RequestHandler-1-0x7fd6ea5b2700 h=44403127 c=R0005f4e1-01-5d72afc8] INFO Core.PolicyResCollector - getSohr: Failed to generate Sohr
2019-09-06 15:13:13,218 [Th 461 Req 2283846 SessId R0005f4e1-01-5d72afc8] INFO RadiusServer.Radius - Policy Evaluation time = 22 ms
2019-09-06 15:13:13,218 [Th 461 Req 2283846 SessId R0005f4e1-01-5d72afc8] INFO RadiusServer.Radius - rlm_policy: Received Deny Enforcement Profile
2019-09-06 15:13:13,218 [Th 461 Req 2283846 SessId R0005f4e1-01-5d72afc8] INFO RadiusServer.Radius - rlm_policy: Policy Server reply does not contain Posture-Validation-Response
2019-09-06 15:13:13,218 [RequestHandler-1-0x7fd6ea5b2700 r=R0005f4e1-01-5d72afc8 h=44403114 c=R0005f4e1-01-5d72afc8] INFO Core.PETaskScheduler - ** Completed PETaskSessionLog **
2019-09-06 15:13:13,218 [RequestHandler-1-0x7fd6ea5b2700 r=R0005f4e1-01-5d72afc8 h=44403114 c=R0005f4e1-01-5d72afc8] INFO Core.PETaskScheduler - ** Completed PETaskOutputPolicyRes **
2019-09-06 15:13:13,218 [RequestHandler-1-0x7fd6ea5b2700 r=R0005f4e1-01-5d72afc8 h=44403114 c=R0005f4e1-01-5d72afc8] INFO Core.PETaskScheduler - ** Completed PETaskAuthStatusInfo **
2019-09-06 15:13:13,218 [RequestHandler-1-0x7fd6ea5b2700 r=R0005f4e1-01-5d72afc8 h=44403114 c=R0005f4e1-01-5d72afc8] INFO Core.PETaskScheduler - *** PE_TASK_SCHEDULE_RADIUS Completed ***
2019-09-06 15:13:13,219 [Th 461 Req 2283846 SessId R0005f4e1-01-5d72afc8] INFO RadiusServer.Radius - reqst_update_state: Access-Challenge 161:128:48437CBEB8B3:AK0AEADOAHlG2SIAfPn+P64UuCoZun4ajDsZWg==
2019-09-06 15:13:13,224 [Th 456 Req 2283847 SessId R0005f4e1-01-5d72afc8] INFO RadiusServer.Radius - rlm_service: The request was categorized into service "LAGCC - 802.1x Wireless Authentication" - 178:265:48437CBEB8B3
2019-09-06 15:13:13,224 [Th 456 Req 2283847 SessId R0005f4e1-01-5d72afc8] INFO RadiusServer.Radius - rlm_ldap: searching for user kortizabreu in AD:lgccdc2008.laguardia.cc
2019-09-06 15:13:13,225 [Th 456 Req 2283847 SessId R0005f4e1-01-5d72afc8] INFO RadiusServer.Radius - rlm_ldap: found user kortizabreu in AD:lgccdc2008.laguardia.cc
2019-09-06 15:13:13,225 [Th 456 Req 2283847 SessId R0005f4e1-01-5d72afc8] INFO RadiusServer.Radius - LDAP/AD User lookup time = 1 ms
2019-09-06 15:13:13,225 [Th 456 Req 2283847 SessId R0005f4e1-01-5d72afc8] INFO RadiusServer.Radius - rlm_policy: Bypassing Policy Evaluation.
2019-09-06 15:13:13,226 [Th 456 Req 2283847 SessId R0005f4e1-01-5d72afc8] INFO RadiusServer.Radius - Request processing time = 319 ms