Request log details for session: R0000cc05-02-52e27e5c

Time Message
2014-01-24 08:53:16,216 [Th 5847 Req 1057956 SessId R0000cc05-02-52e27e5c] INFO RadiusServer.Radius - rlm_service: Starting Service Categorization - 142:210:7446A06C5044
2014-01-24 08:53:16,219 [Th 5847 Req 1057956 SessId R0000cc05-02-52e27e5c] INFO RadiusServer.Radius - rlm_service: The request has been categorized into service "TIGERNETSTUDENT"
2014-01-24 08:53:16,219 [Th 5847 Req 1057956 SessId R0000cc05-02-52e27e5c] INFO RadiusServer.Radius - rlm_sql: searching for user 74:46:a0:6c:50:44 in Sql:wlreg.fhsu.edu
2014-01-24 08:53:16,219 [RequestHandler-1-0x7f726c5e2700 r=psauto-1390321763-216441 h=223 r=R0000cc05-02-52e27e5c] INFO Core.ServiceReqHandler - Service classification result = TIGERNETSTUDENT
2014-01-24 08:53:16,220 [Th 5847 Req 1057956 SessId R0000cc05-02-52e27e5c] INFO RadiusServer.Radius - rlm_policy: Starting Policy Evaluation.
2014-01-24 08:53:16,220 [Th 5847 Req 1057956 SessId R0000cc05-02-52e27e5c] INFO RadiusServer.Radius - rlm_policy: Request does not have Authentication-Source-Name attribute
2014-01-24 08:53:16,222 [RequestHandler-1-0x7f726c5e2700 r=psauto-1390321763-216442 h=239 r=R0000cc05-02-52e27e5c] INFO Common.EndpointTable - Returning NULL (EndpointPtr) for macAddr 7446a06c5044
2014-01-24 08:53:16,222 [RequestHandler-1-0x7f726c5e2700 r=psauto-1390321763-216442 h=239 r=R0000cc05-02-52e27e5c] INFO Common.TagDefinitionCacheTable - No InstanceTagDefCacheMap found for instance id = 3001 entity id = 29
2014-01-24 08:53:16,222 [RequestHandler-1-0x7f726c5e2700 r=psauto-1390321763-216442 h=239 r=R0000cc05-02-52e27e5c] INFO Common.TagDefinitionCacheTable - Building the TagDefMapTable for NAD instance=3001
2014-01-24 08:53:16,222 [RequestHandler-1-0x7f726c5e2700 r=psauto-1390321763-216442 h=239 r=R0000cc05-02-52e27e5c] INFO Common.TagDefinitionCacheTable - Built 0 tag(s) for NAD instanceId=3001|entityId=29
2014-01-24 08:53:16,222 [RequestHandler-1-0x7f726c5e2700 r=psauto-1390321763-216442 h=239 r=R0000cc05-02-52e27e5c] INFO TAT.TagAttrHolderBuilder - No tags built for instanceId=3001|entity=Device
2014-01-24 08:53:16,222 [RequestHandler-1-0x7f726c5e2700 r=psauto-1390321763-216442 h=239 r=R0000cc05-02-52e27e5c] INFO TAT.AluTagAttrHolderBuilder - buildAttrHolder: Tags cannot be built for instanceId=0 (NULL AuthLocalUser)
2014-01-24 08:53:16,222 [RequestHandler-1-0x7f726c5e2700 r=psauto-1390321763-216442 h=239 r=R0000cc05-02-52e27e5c] INFO TAT.GuTagAttrHolderBuilder - buildAttrHolder: Tags cannot be built for instanceId=0 (NULL GuestUser)
2014-01-24 08:53:16,222 [RequestHandler-1-0x7f726c5e2700 r=psauto-1390321763-216442 h=239 r=R0000cc05-02-52e27e5c] INFO TAT.EndpointTagAttrHolderBuilder - buildAttrHolder: Tags cannot be built for instanceId=0 (NULL Endpoint)
2014-01-24 08:53:16,222 [RequestHandler-1-0x7f726c5e2700 r=psauto-1390321763-216442 h=239 r=R0000cc05-02-52e27e5c] INFO TAT.OnboardTagAttrHolderBuilder - buildAttrHolder: Tags cannot be built for instanceId=0 (NULL Onboard Device User)
2014-01-24 08:53:16,222 [RequestHandler-1-0x7f726c5e2700 h=1357312 c=R0000cc05-02-52e27e5c] INFO Core.PETaskScheduler - *** PE_TASK_SCHEDULE_RADIUS Started ***
2014-01-24 08:53:16,223 [RequestHandler-1-0x7f726c5e2700 h=1357312 c=R0000cc05-02-52e27e5c] INFO Core.PETaskScheduler - ** Starting PETaskAuthSourceRestriction **
2014-01-24 08:53:16,223 [RequestHandler-1-0x7f726c5e2700 h=1357312 c=R0000cc05-02-52e27e5c] INFO Core.PETaskScheduler - ** Starting PETaskRoleMapping **
2014-01-24 08:53:16,223 [RequestHandler-1-0x7f726c5e2700 r=R0000cc05-02-52e27e5c h=1357312 c=R0000cc05-02-52e27e5c] INFO Core.PETaskScheduler - ** Completed PETaskAuthSourceRestriction **
2014-01-24 08:53:16,224 [AuthReqThreadPool-9-0x7f7378323700 r=R0000cc05-02-52e27e5c h=74] ERROR ExtDB.DBQuery - ResultSet is empty
2014-01-24 08:53:16,224 [AuthReqThreadPool-9-0x7f7378323700 r=R0000cc05-02-52e27e5c h=74] ERROR ExtDB.DBQuery - Failed to get value for attributes=username]
2014-01-24 08:53:16,226 [RequestHandler-1-0x7f726c5e2700 h=1357314 c=R0000cc05-02-52e27e5c] INFO Core.PETaskRoleMapping - Roles: DENY, User Authenticated]
2014-01-24 08:53:16,226 [RequestHandler-1-0x7f726c5e2700 r=R0000cc05-02-52e27e5c h=1357312 c=R0000cc05-02-52e27e5c] INFO Core.PETaskScheduler - ** Completed PETaskRoleMapping **
2014-01-24 08:53:16,226 [RequestHandler-1-0x7f726c5e2700 r=R0000cc05-02-52e27e5c h=1357312 c=R0000cc05-02-52e27e5c] INFO Core.PETaskScheduler - ** Starting PETaskPolicyResult **
2014-01-24 08:53:16,226 [RequestHandler-1-0x7f726c5e2700 r=R0000cc05-02-52e27e5c h=1357312 c=R0000cc05-02-52e27e5c] INFO Core.PETaskScheduler - ** Completed PETaskPolicyResult **
2014-01-24 08:53:16,226 [RequestHandler-1-0x7f726c5e2700 r=R0000cc05-02-52e27e5c h=1357312 c=R0000cc05-02-52e27e5c] INFO Core.PETaskScheduler - ** Starting PETaskEnforcement **
2014-01-24 08:53:16,227 [RequestHandler-1-0x7f726c5e2700 h=1357317 c=R0000cc05-02-52e27e5c] INFO Core.PETaskEnforcement - EnfProfiles: Blacklist Client via CLI
2014-01-24 08:53:16,227 [RequestHandler-1-0x7f726c5e2700 r=R0000cc05-02-52e27e5c h=1357312 c=R0000cc05-02-52e27e5c] INFO Core.PETaskScheduler - ** Completed PETaskEnforcement **
2014-01-24 08:53:16,227 [RequestHandler-1-0x7f726c5e2700 r=R0000cc05-02-52e27e5c h=1357312 c=R0000cc05-02-52e27e5c] INFO Core.PETaskScheduler - ** Starting PETaskRadiusEnfProfileBuilder **
2014-01-24 08:53:16,227 [RequestHandler-1-0x7f726c5e2700 r=R0000cc05-02-52e27e5c h=1357312 c=R0000cc05-02-52e27e5c] INFO Core.PETaskScheduler - ** Starting PETaskRadiusCoAEnfProfileBuilder **
2014-01-24 08:53:16,227 [RequestHandler-1-0x7f726c5e2700 r=R0000cc05-02-52e27e5c h=1357312 c=R0000cc05-02-52e27e5c] INFO Core.PETaskScheduler - ** Starting PETaskAppEnfProfileBuilder **
2014-01-24 08:53:16,227 [RequestHandler-1-0x7f726c5e2700 r=R0000cc05-02-52e27e5c h=1357312 c=R0000cc05-02-52e27e5c] INFO Core.PETaskScheduler - ** Starting PETaskPostAuthEnfProfileBuilder **
2014-01-24 08:53:16,227 [RequestHandler-1-0x7f726c5e2700 r=R0000cc05-02-52e27e5c h=1357312 c=R0000cc05-02-52e27e5c] INFO Core.PETaskScheduler - ** Starting PETaskGenericEnfProfileBuilder **
2014-01-24 08:53:16,227 [RequestHandler-1-0x7f726c5e2700 h=1357322 c=R0000cc05-02-52e27e5c] INFO Core.PETaskGenericEnfProfileBuilder - getApplicableProfiles: No App enforcement (Generic) profiles applicable for this device
2014-01-24 08:53:16,228 [RequestHandler-1-0x7f726c5e2700 h=1357318 c=R0000cc05-02-52e27e5c] INFO Core.PETaskRadiusEnfProfileBuilder - EnfProfileAction=ACCEPT
2014-01-24 08:53:16,228 [RequestHandler-1-0x7f726c5e2700 h=1357318 c=R0000cc05-02-52e27e5c] WARN Core.PETaskRadiusEnfProfileBuilder - filterProfileIdList: No radius enforcement profiles applicable for this device. Allowing Access
2014-01-24 08:53:16,228 [RequestHandler-1-0x7f726c5e2700 h=1357318 c=R0000cc05-02-52e27e5c] INFO Core.EnfProfileComputer - getFinalSessionTimeout: sessionTimeout = 0
2014-01-24 08:53:16,228 [RequestHandler-1-0x7f726c5e2700 r=R0000cc05-02-52e27e5c h=1357312 c=R0000cc05-02-52e27e5c] INFO Core.PETaskScheduler - ** Completed PETaskGenericEnfProfileBuilder **
2014-01-24 08:53:16,228 [RequestHandler-1-0x7f726c5e2700 r=R0000cc05-02-52e27e5c h=1357312 c=R0000cc05-02-52e27e5c] INFO Core.PETaskScheduler - ** Completed PETaskAppEnfProfileBuilder **
2014-01-24 08:53:16,228 [RequestHandler-1-0x7f726c5e2700 r=R0000cc05-02-52e27e5c h=1357312 c=R0000cc05-02-52e27e5c] INFO Core.PETaskScheduler - ** Starting PETaskCliEnforcement **
2014-01-24 08:53:16,228 [RequestHandler-1-0x7f726c5e2700 h=1357323 c=R0000cc05-02-52e27e5c] WARN Util.DatatypeUtils - Converting string 192.168.127.250 to integer failed. Trailing characters
2014-01-24 08:53:16,228 [RequestHandler-1-0x7f726c5e2700 r=R0000cc05-02-52e27e5c h=1357312 c=R0000cc05-02-52e27e5c] INFO Core.PETaskScheduler - ** Completed PETaskRadiusEnfProfileBuilder **
2014-01-24 08:53:16,228 [RequestHandler-1-0x7f726c5e2700 r=R0000cc05-02-52e27e5c h=1357319 c=R0000cc05-02-52e27e5c] WARN Core.PETaskRadiusCoAEnfProfileBuilder - handleHttpResponseEv: Fetching Radius attributes from battery failed, errMsg=
2014-01-24 08:53:16,228 [RequestHandler-1-0x7f726c5e2700 r=R0000cc05-02-52e27e5c h=1357312 c=R0000cc05-02-52e27e5c] INFO Core.PETaskScheduler - ** Completed PETaskRadiusCoAEnfProfileBuilder **
2014-01-24 08:53:16,228 [RequestHandler-1-0x7f726c5e2700 r=R0000cc05-02-52e27e5c h=1357321 c=R0000cc05-02-52e27e5c] WARN Core.PETaskPostAuthEnfProfileBuilder - handleHttpResponseEv: Fetching Radius attributes from battery failed, errMsg=
2014-01-24 08:53:16,228 [RequestHandler-1-0x7f726c5e2700 r=R0000cc05-02-52e27e5c h=1357321 c=R0000cc05-02-52e27e5c] INFO Core.PETaskPostAuthEnfProfileBuilder - getApplicableProfiles: No Post auth enforcement profiles applicable for this device
2014-01-24 08:53:16,228 [RequestHandler-1-0x7f726c5e2700 r=R0000cc05-02-52e27e5c h=1357312 c=R0000cc05-02-52e27e5c] INFO Core.PETaskScheduler - ** Completed PETaskPostAuthEnfProfileBuilder **
2014-01-24 08:53:16,229 [RequestHandler-1-0x7f726c5e2700 r=R0000cc05-02-52e27e5c h=1357312 c=R0000cc05-02-52e27e5c] INFO Core.PETaskScheduler - ** Starting PETaskAuthStatusInfo **
2014-01-24 08:53:16,229 [RequestHandler-1-0x7f726c5e2700 r=R0000cc05-02-52e27e5c h=1357312 c=R0000cc05-02-52e27e5c] INFO Core.PETaskScheduler - ** Starting PETaskMacAuthResetHandler **
2014-01-24 08:53:16,229 [RequestHandler-1-0x7f726c5e2700 r=R0000cc05-02-52e27e5c h=1357312 c=R0000cc05-02-52e27e5c] INFO Core.PETaskScheduler - ** Starting PETaskOutputPolicyRes **
2014-01-24 08:53:16,229 [RequestHandler-1-0x7f726c5e2700 r=R0000cc05-02-52e27e5c h=1357312 c=R0000cc05-02-52e27e5c] INFO Core.PETaskScheduler - ** Starting PETaskSessionLog **
2014-01-24 08:53:16,231 [RequestHandler-1-0x7f726c5e2700 h=1357326 c=R0000cc05-02-52e27e5c] INFO Core.XpipPolicyResHandler - populateResponseTlv: PETaskPostureOutput does not exist. Skip sending posture VAFs
2014-01-24 08:53:16,231 [RequestHandler-1-0x7f726c5e2700 h=1357326 c=R0000cc05-02-52e27e5c] INFO Core.PolicyResCollector - getSohr: Failed to generate Sohr
2014-01-24 08:53:16,232 [Th 5847 Req 1057956 SessId R0000cc05-02-52e27e5c] INFO RadiusServer.Radius - rlm_policy: Received Accept Enforcement Profile
2014-01-24 08:53:16,232 [Th 5847 Req 1057956 SessId R0000cc05-02-52e27e5c] INFO RadiusServer.Radius - rlm_policy: Added Class attribute with value Class = 0x27fb634eb63c4458b8f3bf77bb9441a1f20b0000000000005230303030636330352d30322d35326532376535630000000000000000000000
2014-01-24 08:53:16,232 [Th 5847 Req 1057956 SessId R0000cc05-02-52e27e5c] INFO RadiusServer.Radius - rlm_policy: Policy Server reply does not contain Posture-Validation-Response
2014-01-24 08:53:16,232 [RequestHandler-1-0x7f726c5e2700 h=1357325 c=R0000cc05-02-52e27e5c] WARN Core.RadiusEnfProfileHelper - getSessionTimeoutInSecs: SessionTimeout attribute missing in output
2014-01-24 08:53:16,232 [RequestHandler-1-0x7f726c5e2700 h=1357324 c=R0000cc05-02-52e27e5c] INFO Core.PolicyResCollector - getSohr: Failed to generate Sohr
2014-01-24 08:53:16,232 [RequestHandler-1-0x7f726c5e2700 r=R0000cc05-02-52e27e5c h=1357312 c=R0000cc05-02-52e27e5c] INFO Core.PETaskScheduler - ** Completed PETaskSessionLog **
2014-01-24 08:53:16,232 [RequestHandler-1-0x7f726c5e2700 r=R0000cc05-02-52e27e5c h=1357312 c=R0000cc05-02-52e27e5c] INFO Core.PETaskScheduler - ** Completed PETaskOutputPolicyRes **
2014-01-24 08:53:16,232 [RequestHandler-1-0x7f726c5e2700 r=R0000cc05-02-52e27e5c h=1357312 c=R0000cc05-02-52e27e5c] INFO Core.PETaskScheduler - ** Completed PETaskAuthStatusInfo **
2014-01-24 08:53:16,232 [RequestHandler-1-0x7f726c5e2700 r=R0000cc05-02-52e27e5c h=1357312 c=R0000cc05-02-52e27e5c] INFO Core.PETaskScheduler - ** Completed PETaskCliEnforcement **
2014-01-24 08:53:16,233 [RequestHandler-1-0x7f726c5e2700 r=R0000cc05-02-52e27e5c h=1357328] ERROR Core.MacAuthSessionQueryEventHandler - Failed to get MacAuth session info for 7446a06c5044
2014-01-24 08:53:16,233 [RequestHandler-1-0x7f726c5e2700 h=1357325 c=R0000cc05-02-52e27e5c] WARN Core.PETaskMacAuthResetHandler - handleMacAuthSessionResponseEv: Error reading MacAuth session info. Error=Failed to get MacAuth session info for 7446a06c5044
2014-01-24 08:53:16,233 [RequestHandler-1-0x7f726c5e2700 r=R0000cc05-02-52e27e5c h=1357312 c=R0000cc05-02-52e27e5c] INFO Core.PETaskScheduler - ** Completed PETaskMacAuthResetHandler **
2014-01-24 08:53:16,233 [RequestHandler-1-0x7f726c5e2700 r=R0000cc05-02-52e27e5c h=1357312 c=R0000cc05-02-52e27e5c] INFO Core.PETaskScheduler - *** PE_TASK_SCHEDULE_RADIUS Completed ***