| Time | Message |
|---|---|
| 2018-01-18 16:04:24,794 | [Th 39 Req 210207 SessId R00001a44-01-5a609b58] INFO RadiusServer.Radius - rlm_service: Starting Service Categorization - 168:212:a4db3079cc1c |
| 2018-01-18 16:04:24,797 | [Th 39 Req 210207 SessId R00001a44-01-5a609b58] INFO RadiusServer.Radius - Service Categorization time = 3 ms |
| 2018-01-18 16:04:24,797 | [Th 39 Req 210207 SessId R00001a44-01-5a609b58] INFO RadiusServer.Radius - rlm_service: The request has been categorized into service "Beluga-Guest MAC Authentication" |
| 2018-01-18 16:04:24,797 | [Th 39 Req 210207 SessId R00001a44-01-5a609b58] INFO RadiusServer.Radius - rlm_sql: searching for user a4-db-30-79-cc-1c in Local:localhost |
| 2018-01-18 16:04:24,797 | [RequestHandler-1-0x7f43eb1d8700 r=psauto-1516093153-23873 h=223 r=R00001a44-01-5a609b58] INFO Core.ServiceReqHandler - Service classification result = Beluga-Guest MAC Authentication |
| 2018-01-18 16:04:24,798 | [Th 39 Req 210207 SessId R00001a44-01-5a609b58] INFO RadiusServer.Radius - rlm_macauth: Rejecting MAC auth request from Unknown/Disabled client |
| 2018-01-18 16:04:24,798 | [Th 39 Req 210207 SessId R00001a44-01-5a609b58] INFO RadiusServer.Radius - rlm_policy: Starting Policy Evaluation. |
| 2018-01-18 16:04:24,800 | [RequestHandler-1-0x7f43eb1d8700 r=psauto-1516093153-23874 h=239 r=R00001a44-01-5a609b58] INFO Common.EndpointTable - Returning NULL (EndpointPtr) for macAddr a4db3079cc1c |
| 2018-01-18 16:04:24,800 | [RequestHandler-1-0x7f43eb1d8700 r=psauto-1516093153-23874 h=239 r=R00001a44-01-5a609b58] INFO Common.TagDefinitionCacheTable - No InstanceTagDefCacheMap found for instance id = 3001 entity id = 29 |
| 2018-01-18 16:04:24,800 | [RequestHandler-1-0x7f43eb1d8700 r=psauto-1516093153-23874 h=239 r=R00001a44-01-5a609b58] INFO Common.TagDefinitionCacheTable - Building the TagDefMapTable for NAD instance=3001 |
| 2018-01-18 16:04:24,800 | [RequestHandler-1-0x7f43eb1d8700 r=psauto-1516093153-23874 h=239 r=R00001a44-01-5a609b58] INFO Common.TagDefinitionCacheTable - Built 0 tag(s) for NAD instanceId=3001|entityId=29 |
| 2018-01-18 16:04:24,800 | [RequestHandler-1-0x7f43eb1d8700 r=psauto-1516093153-23874 h=239 r=R00001a44-01-5a609b58] INFO TAT.TagAttrHolderBuilder - No tags built for instanceId=3001|entity=Device |
| 2018-01-18 16:04:24,800 | [RequestHandler-1-0x7f43eb1d8700 r=psauto-1516093153-23874 h=239 r=R00001a44-01-5a609b58] INFO TAT.AluTagAttrHolderBuilder - buildAttrHolder: Tags cannot be built for instanceId=0 (NULL AuthLocalUser) |
| 2018-01-18 16:04:24,800 | [RequestHandler-1-0x7f43eb1d8700 r=psauto-1516093153-23874 h=239 r=R00001a44-01-5a609b58] INFO TAT.GuTagAttrHolderBuilder - buildAttrHolder: Tags cannot be built for instanceId=0 (NULL GuestUser) |
| 2018-01-18 16:04:24,800 | [RequestHandler-1-0x7f43eb1d8700 r=psauto-1516093153-23874 h=239 r=R00001a44-01-5a609b58] INFO TAT.EndpointTagAttrHolderBuilder - buildAttrHolder: Tags cannot be built for instanceId=0 (NULL Endpoint) |
| 2018-01-18 16:04:24,800 | [RequestHandler-1-0x7f43eb1d8700 r=psauto-1516093153-23874 h=239 r=R00001a44-01-5a609b58] INFO TAT.OnboardTagAttrHolderBuilder - buildAttrHolder: Tags cannot be built for instanceId=0 (NULL Onboard Device User) |
| 2018-01-18 16:04:24,800 | [RequestHandler-1-0x7f43eb1d8700 h=154033 c=R00001a44-01-5a609b58] INFO Core.PETaskScheduler - *** PE_TASK_SCHEDULE_RADIUS Started *** |
| 2018-01-18 16:04:24,800 | [RequestHandler-1-0x7f43eb1d8700 h=154033 c=R00001a44-01-5a609b58] INFO Core.PETaskScheduler - ** Starting PETaskAuthSourceRestriction ** |
| 2018-01-18 16:04:24,800 | [RequestHandler-1-0x7f43eb1d8700 h=154033 c=R00001a44-01-5a609b58] INFO Core.PETaskScheduler - ** Starting PETaskRoleMapping ** |
| 2018-01-18 16:04:24,801 | [RequestHandler-1-0x7f43eb1d8700 r=R00001a44-01-5a609b58 h=154033 c=R00001a44-01-5a609b58] INFO Core.PETaskScheduler - ** Completed PETaskAuthSourceRestriction ** |
| 2018-01-18 16:04:24,801 | [AuthReqThreadPool-30-0x7f44ec9e4700 r=R00001a44-01-5a609b58 h=74] WARN Util.ParameterizedString - getReplacedStrings: Failed to replace parameString =SELECT |
| 2018-01-18 16:04:24,801 | [AuthReqThreadPool-30-0x7f44ec9e4700 r=R00001a44-01-5a609b58 h=74] ERROR ExtDB.DBQuery - execute: Failed to construct filter=SELECT |
| 2018-01-18 16:04:24,801 | [AuthReqThreadPool-30-0x7f44ec9e4700 r=R00001a44-01-5a609b58 h=74] ERROR ExtDB.DBQuery - Failed to get value for attributes=AccountEnabled, AccountExpired] |
| 2018-01-18 16:04:24,802 | [RequestHandler-1-0x7f43eb1d8700 h=154035 c=R00001a44-01-5a609b58] INFO Core.PETaskRoleMapping - Roles: Other] |
| 2018-01-18 16:04:24,802 | [RequestHandler-1-0x7f43eb1d8700 r=R00001a44-01-5a609b58 h=154033 c=R00001a44-01-5a609b58] INFO Core.PETaskScheduler - ** Completed PETaskRoleMapping ** |
| 2018-01-18 16:04:24,802 | [RequestHandler-1-0x7f43eb1d8700 r=R00001a44-01-5a609b58 h=154033 c=R00001a44-01-5a609b58] INFO Core.PETaskScheduler - ** Starting PETaskPolicyResult ** |
| 2018-01-18 16:04:24,803 | [RequestHandler-1-0x7f43eb1d8700 r=R00001a44-01-5a609b58 h=154033 c=R00001a44-01-5a609b58] INFO Core.PETaskScheduler - ** Completed PETaskPolicyResult ** |
| 2018-01-18 16:04:24,803 | [RequestHandler-1-0x7f43eb1d8700 r=R00001a44-01-5a609b58 h=154033 c=R00001a44-01-5a609b58] INFO Core.PETaskScheduler - ** Starting PETaskEnforcement ** |
| 2018-01-18 16:04:24,803 | [RequestHandler-1-0x7f43eb1d8700 h=154038 c=R00001a44-01-5a609b58] INFO Core.PETaskEnforcement - EnfProfiles: Deny Access Profile] |
| 2018-01-18 16:04:24,803 | [RequestHandler-1-0x7f43eb1d8700 r=R00001a44-01-5a609b58 h=154033 c=R00001a44-01-5a609b58] INFO Core.PETaskScheduler - ** Completed PETaskEnforcement ** |
| 2018-01-18 16:04:24,803 | [RequestHandler-1-0x7f43eb1d8700 r=R00001a44-01-5a609b58 h=154033 c=R00001a44-01-5a609b58] INFO Core.PETaskScheduler - ** Starting PETaskRadiusEnfProfileBuilder ** |
| 2018-01-18 16:04:24,803 | [RequestHandler-1-0x7f43eb1d8700 r=R00001a44-01-5a609b58 h=154033 c=R00001a44-01-5a609b58] INFO Core.PETaskScheduler - ** Starting PETaskRadiusCoAEnfProfileBuilder ** |
| 2018-01-18 16:04:24,803 | [RequestHandler-1-0x7f43eb1d8700 r=R00001a44-01-5a609b58 h=154033 c=R00001a44-01-5a609b58] INFO Core.PETaskScheduler - ** Starting PETaskAppEnfProfileBuilder ** |
| 2018-01-18 16:04:24,803 | [RequestHandler-1-0x7f43eb1d8700 r=R00001a44-01-5a609b58 h=154033 c=R00001a44-01-5a609b58] INFO Core.PETaskScheduler - ** Starting PETaskAgentEnfProfileBuilder ** |
| 2018-01-18 16:04:24,803 | [RequestHandler-1-0x7f43eb1d8700 r=R00001a44-01-5a609b58 h=154033 c=R00001a44-01-5a609b58] INFO Core.PETaskScheduler - ** Starting PETaskPostAuthEnfProfileBuilder ** |
| 2018-01-18 16:04:24,803 | [RequestHandler-1-0x7f43eb1d8700 r=R00001a44-01-5a609b58 h=154033 c=R00001a44-01-5a609b58] INFO Core.PETaskScheduler - ** Starting PETaskGenericEnfProfileBuilder ** |
| 2018-01-18 16:04:24,803 | [RequestHandler-1-0x7f43eb1d8700 h=154044 c=R00001a44-01-5a609b58] INFO Core.PETaskGenericEnfProfileBuilder - getApplicableProfiles: No App enforcement (Generic) profiles applicable for this device |
| 2018-01-18 16:04:24,804 | [RequestHandler-1-0x7f43eb1d8700 h=154039 c=R00001a44-01-5a609b58] INFO Core.PETaskRadiusEnfProfileBuilder - EnfProfileAction=DENY |
| 2018-01-18 16:04:24,806 | [RequestHandler-1-0x7f43eb1d8700 h=154039 c=R00001a44-01-5a609b58] INFO Core.PETaskRadiusEnfProfileBuilder - Radius enfProfiles used: Deny Access Profile] |
| 2018-01-18 16:04:24,806 | [RequestHandler-1-0x7f43eb1d8700 h=154039 c=R00001a44-01-5a609b58] INFO Core.EnfProfileComputer - getFinalSessionTimeout: sessionTimeout = 0 |
| 2018-01-18 16:04:24,806 | [RequestHandler-1-0x7f43eb1d8700 r=R00001a44-01-5a609b58 h=154033 c=R00001a44-01-5a609b58] INFO Core.PETaskScheduler - ** Completed PETaskGenericEnfProfileBuilder ** |
| 2018-01-18 16:04:24,806 | [RequestHandler-1-0x7f43eb1d8700 r=R00001a44-01-5a609b58 h=154033 c=R00001a44-01-5a609b58] INFO Core.PETaskScheduler - ** Completed PETaskAgentEnfProfileBuilder ** |
| 2018-01-18 16:04:24,806 | [RequestHandler-1-0x7f43eb1d8700 r=R00001a44-01-5a609b58 h=154033 c=R00001a44-01-5a609b58] INFO Core.PETaskScheduler - ** Completed PETaskAppEnfProfileBuilder ** |
| 2018-01-18 16:04:24,806 | [RequestHandler-1-0x7f43eb1d8700 r=R00001a44-01-5a609b58 h=154033 c=R00001a44-01-5a609b58] INFO Core.PETaskScheduler - ** Starting PETaskCliEnforcement ** |
| 2018-01-18 16:04:24,806 | [RequestHandler-1-0x7f43eb1d8700 h=154045 c=R00001a44-01-5a609b58] INFO Core.PETaskCliEnforcement - startHandler: Request rejected. Skip CLI enforcement |
| 2018-01-18 16:04:24,806 | [RequestHandler-1-0x7f43eb1d8700 r=R00001a44-01-5a609b58 h=154033 c=R00001a44-01-5a609b58] INFO Core.PETaskScheduler - ** Completed PETaskRadiusEnfProfileBuilder ** |
| 2018-01-18 16:04:24,806 | [RequestHandler-1-0x7f43eb1d8700 r=R00001a44-01-5a609b58 h=154033 c=R00001a44-01-5a609b58] INFO Core.PETaskScheduler - ** Completed PETaskCliEnforcement ** |
| 2018-01-18 16:04:24,808 | [RequestHandler-1-0x7f43eb1d8700 r=R00001a44-01-5a609b58 h=154043 c=R00001a44-01-5a609b58] WARN Core.PETaskPostAuthEnfProfileBuilder - handleHttpResponseEv: Fetching Radius attributes from battery failed, errMsg= |
| 2018-01-18 16:04:24,808 | [RequestHandler-1-0x7f43eb1d8700 r=R00001a44-01-5a609b58 h=154043 c=R00001a44-01-5a609b58] INFO Core.PETaskPostAuthEnfProfileBuilder - getApplicableProfiles: No Post auth enforcement profiles applicable for this device |
| 2018-01-18 16:04:24,808 | [RequestHandler-1-0x7f43eb1d8700 r=R00001a44-01-5a609b58 h=154040 c=R00001a44-01-5a609b58] WARN Core.PETaskRadiusCoAEnfProfileBuilder - handleHttpResponseEv: Fetching Radius attributes from battery failed, errMsg= |
| 2018-01-18 16:04:24,808 | [RequestHandler-1-0x7f43eb1d8700 r=R00001a44-01-5a609b58 h=154033 c=R00001a44-01-5a609b58] INFO Core.PETaskScheduler - ** Completed PETaskPostAuthEnfProfileBuilder ** |
| 2018-01-18 16:04:24,808 | [RequestHandler-1-0x7f43eb1d8700 r=R00001a44-01-5a609b58 h=154033 c=R00001a44-01-5a609b58] INFO Core.PETaskScheduler - ** Completed PETaskRadiusCoAEnfProfileBuilder ** |
| 2018-01-18 16:04:24,808 | [RequestHandler-1-0x7f43eb1d8700 r=R00001a44-01-5a609b58 h=154033 c=R00001a44-01-5a609b58] INFO Core.PETaskScheduler - ** Starting PETaskAuthStatusInfo ** |
| 2018-01-18 16:04:24,808 | [RequestHandler-1-0x7f43eb1d8700 r=R00001a44-01-5a609b58 h=154033 c=R00001a44-01-5a609b58] INFO Core.PETaskScheduler - ** Starting PETaskOutputPolicyRes ** |
| 2018-01-18 16:04:24,808 | [RequestHandler-1-0x7f43eb1d8700 r=R00001a44-01-5a609b58 h=154033 c=R00001a44-01-5a609b58] INFO Core.PETaskScheduler - ** Starting PETaskSessionLog ** |
| 2018-01-18 16:04:24,811 | [RequestHandler-1-0x7f43eb1d8700 h=154047 c=R00001a44-01-5a609b58] INFO Core.XpipPolicyResHandler - populateResponseTlv: PETaskPostureOutput does not exist. Skip sending posture VAFs |
| 2018-01-18 16:04:24,811 | [RequestHandler-1-0x7f43eb1d8700 h=154047 c=R00001a44-01-5a609b58] INFO Core.PolicyResCollector - getSohr: Failed to generate Sohr |
| 2018-01-18 16:04:24,811 | [RequestHandler-1-0x7f43eb1d8700 h=154046 c=R00001a44-01-5a609b58] INFO Core.PolicyResCollector - getSohr: Failed to generate Sohr |
| 2018-01-18 16:04:24,811 | [RequestHandler-1-0x7f43eb1d8700 r=R00001a44-01-5a609b58 h=154033 c=R00001a44-01-5a609b58] INFO Core.PETaskScheduler - ** Completed PETaskSessionLog ** |
| 2018-01-18 16:04:24,812 | [Th 39 Req 210207 SessId R00001a44-01-5a609b58] INFO RadiusServer.Radius - Policy Evaluation time = 13 ms |
| 2018-01-18 16:04:24,812 | [Th 39 Req 210207 SessId R00001a44-01-5a609b58] INFO RadiusServer.Radius - rlm_policy: Received Deny Enforcement Profile |
| 2018-01-18 16:04:24,812 | [Th 39 Req 210207 SessId R00001a44-01-5a609b58] INFO RadiusServer.Radius - rlm_policy: Policy Server reply does not contain Posture-Validation-Response |
| 2018-01-18 16:04:24,812 | [Th 39 Req 210207 SessId R00001a44-01-5a609b58] INFO RadiusServer.Radius - Request processing time = 17 ms |
| 2018-01-18 16:04:24,812 | [RequestHandler-1-0x7f43eb1d8700 r=R00001a44-01-5a609b58 h=154033 c=R00001a44-01-5a609b58] INFO Core.PETaskScheduler - ** Completed PETaskOutputPolicyRes ** |
| 2018-01-18 16:04:24,812 | [RequestHandler-1-0x7f43eb1d8700 r=R00001a44-01-5a609b58 h=154033 c=R00001a44-01-5a609b58] INFO Core.PETaskScheduler - ** Completed PETaskAuthStatusInfo ** |
| 2018-01-18 16:04:24,812 | [RequestHandler-1-0x7f43eb1d8700 r=R00001a44-01-5a609b58 h=154033 c=R00001a44-01-5a609b58] INFO Core.PETaskScheduler - *** PE_TASK_SCHEDULE_RADIUS Completed *** |