Time | Message |
2015-03-02 10:56:01,741 | [Th 33 Req 332210 SessId R00009163-01-54f4b241] INFO RadiusServer.Radius - rlm_service: Starting Service Categorization - 146:212:B4-B5-2F-31-1B-17 |
2015-03-02 10:56:01,745 | [RequestHandler-1-0x7f9474de6700 r=psauto-1424888011-77498 h=223 r=R00009163-01-54f4b241] INFO Core.ServiceReqHandler - Service classification result = PSC Secured Wired Cisco Switches 802.1X Wired |
2015-03-02 10:56:01,747 | [Th 33 Req 332210 SessId R00009163-01-54f4b241] INFO RadiusServer.Radius - Service Categorization time = 5 ms |
2015-03-02 10:56:01,747 | [Th 33 Req 332210 SessId R00009163-01-54f4b241] INFO RadiusServer.Radius - rlm_service: The request has been categorized into service "PSC Secured Wired Cisco Switches 802.1X Wired" |
2015-03-02 10:56:01,747 | [Th 33 Req 332210 SessId R00009163-01-54f4b241] INFO RadiusServer.Radius - rlm_ldap: searching for user b4b52f311b17 in AD:pscvm-dc-01.peregrine.int |
2015-03-02 10:56:01,748 | [Th 33 Req 332210 SessId R00009163-01-54f4b241] INFO RadiusServer.Radius - rlm_auth_check: Auth-Type not set. |
2015-03-02 10:56:01,748 | [Th 33 Req 332210 SessId R00009163-01-54f4b241] ERROR RadiusServer.Radius - rlm_auth_check: Auth-Type not set or authentication methods have not been configured. Rejecting it. |
2015-03-02 10:56:01,748 | [Th 33 Req 332210 SessId R00009163-01-54f4b241] INFO RadiusServer.Radius - rlm_policy: Starting Policy Evaluation. |
2015-03-02 10:56:01,750 | [RequestHandler-1-0x7f9474de6700 r=psauto-1424888011-77499 h=239 r=R00009163-01-54f4b241] INFO Common.EndpointTable - Returning NULL (EndpointPtr) for macAddr b4b52f311b17 |
2015-03-02 10:56:01,750 | [RequestHandler-1-0x7f9474de6700 r=psauto-1424888011-77499 h=239 r=R00009163-01-54f4b241] INFO Common.TagDefinitionCacheTable - No InstanceTagDefCacheMap found for instance id = 3005 entity id = 29 |
2015-03-02 10:56:01,750 | [RequestHandler-1-0x7f9474de6700 r=psauto-1424888011-77499 h=239 r=R00009163-01-54f4b241] INFO Common.TagDefinitionCacheTable - Building the TagDefMapTable for NAD instance=3005 |
2015-03-02 10:56:01,750 | [RequestHandler-1-0x7f9474de6700 r=psauto-1424888011-77499 h=239 r=R00009163-01-54f4b241] INFO Common.TagDefinitionCacheTable - Built 0 tag(s) for NAD instanceId=3005|entityId=29 |
2015-03-02 10:56:01,750 | [RequestHandler-1-0x7f9474de6700 r=psauto-1424888011-77499 h=239 r=R00009163-01-54f4b241] INFO TAT.TagAttrHolderBuilder - No tags built for instanceId=3005|entity=Device |
2015-03-02 10:56:01,750 | [RequestHandler-1-0x7f9474de6700 r=psauto-1424888011-77499 h=239 r=R00009163-01-54f4b241] INFO TAT.AluTagAttrHolderBuilder - buildAttrHolder: Tags cannot be built for instanceId=0 (NULL AuthLocalUser) |
2015-03-02 10:56:01,750 | [RequestHandler-1-0x7f9474de6700 r=psauto-1424888011-77499 h=239 r=R00009163-01-54f4b241] INFO TAT.GuTagAttrHolderBuilder - buildAttrHolder: Tags cannot be built for instanceId=0 (NULL GuestUser) |
2015-03-02 10:56:01,750 | [RequestHandler-1-0x7f9474de6700 r=psauto-1424888011-77499 h=239 r=R00009163-01-54f4b241] INFO TAT.EndpointTagAttrHolderBuilder - buildAttrHolder: Tags cannot be built for instanceId=0 (NULL Endpoint) |
2015-03-02 10:56:01,750 | [RequestHandler-1-0x7f9474de6700 r=psauto-1424888011-77499 h=239 r=R00009163-01-54f4b241] INFO TAT.OnboardTagAttrHolderBuilder - buildAttrHolder: Tags cannot be built for instanceId=0 (NULL Onboard Device User) |
2015-03-02 10:56:01,750 | [RequestHandler-1-0x7f9474de6700 h=678851 c=R00009163-01-54f4b241] INFO Core.PETaskScheduler - *** PE_TASK_SCHEDULE_RADIUS Started *** |
2015-03-02 10:56:01,750 | [RequestHandler-1-0x7f9474de6700 h=678851 c=R00009163-01-54f4b241] INFO Core.PETaskScheduler - ** Starting PETaskAuthSourceRestriction ** |
2015-03-02 10:56:01,751 | [RequestHandler-1-0x7f9474de6700 h=678851 c=R00009163-01-54f4b241] INFO Core.PETaskScheduler - ** Starting PETaskRoleMapping ** |
2015-03-02 10:56:01,752 | [RequestHandler-1-0x7f9474de6700 r=R00009163-01-54f4b241 h=678851 c=R00009163-01-54f4b241] INFO Core.PETaskScheduler - ** Completed PETaskAuthSourceRestriction ** |
2015-03-02 10:56:01,752 | [RequestHandler-1-0x7f9474de6700 h=678853 c=R00009163-01-54f4b241] INFO Core.PETaskRoleMapping - Roles: |
2015-03-02 10:56:01,752 | [RequestHandler-1-0x7f9474de6700 r=R00009163-01-54f4b241 h=678851 c=R00009163-01-54f4b241] INFO Core.PETaskScheduler - ** Completed PETaskRoleMapping ** |
2015-03-02 10:56:01,753 | [RequestHandler-1-0x7f9474de6700 r=R00009163-01-54f4b241 h=678851 c=R00009163-01-54f4b241] INFO Core.PETaskScheduler - ** Starting PETaskPolicyResult ** |
2015-03-02 10:56:01,753 | [RequestHandler-1-0x7f9474de6700 r=R00009163-01-54f4b241 h=678851 c=R00009163-01-54f4b241] INFO Core.PETaskScheduler - ** Completed PETaskPolicyResult ** |
2015-03-02 10:56:01,753 | [RequestHandler-1-0x7f9474de6700 r=R00009163-01-54f4b241 h=678851 c=R00009163-01-54f4b241] INFO Core.PETaskScheduler - ** Starting PETaskEnforcement ** |
2015-03-02 10:56:01,754 | [RequestHandler-1-0x7f9474de6700 h=678856 c=R00009163-01-54f4b241] INFO Core.PETaskEnforcement - EnfProfiles: PSC Secured Wired Cisco Switches 802.1X Wired Default Profile |
2015-03-02 10:56:01,754 | [RequestHandler-1-0x7f9474de6700 r=R00009163-01-54f4b241 h=678851 c=R00009163-01-54f4b241] INFO Core.PETaskScheduler - ** Completed PETaskEnforcement ** |
2015-03-02 10:56:01,754 | [RequestHandler-1-0x7f9474de6700 r=R00009163-01-54f4b241 h=678851 c=R00009163-01-54f4b241] INFO Core.PETaskScheduler - ** Starting PETaskRadiusEnfProfileBuilder ** |
2015-03-02 10:56:01,754 | [RequestHandler-1-0x7f9474de6700 r=R00009163-01-54f4b241 h=678851 c=R00009163-01-54f4b241] INFO Core.PETaskScheduler - ** Starting PETaskRadiusCoAEnfProfileBuilder ** |
2015-03-02 10:56:01,754 | [RequestHandler-1-0x7f9474de6700 r=R00009163-01-54f4b241 h=678851 c=R00009163-01-54f4b241] INFO Core.PETaskScheduler - ** Starting PETaskAppEnfProfileBuilder ** |
2015-03-02 10:56:01,754 | [RequestHandler-1-0x7f9474de6700 r=R00009163-01-54f4b241 h=678851 c=R00009163-01-54f4b241] INFO Core.PETaskScheduler - ** Starting PETaskPostAuthEnfProfileBuilder ** |
2015-03-02 10:56:01,754 | [RequestHandler-1-0x7f9474de6700 r=R00009163-01-54f4b241 h=678851 c=R00009163-01-54f4b241] INFO Core.PETaskScheduler - ** Starting PETaskGenericEnfProfileBuilder ** |
2015-03-02 10:56:01,754 | [RequestHandler-1-0x7f9474de6700 h=678862 c=R00009163-01-54f4b241] INFO Core.PETaskGenericEnfProfileBuilder - getApplicableProfiles: No App enforcement (Generic) profiles applicable for this device |
2015-03-02 10:56:01,755 | [RequestHandler-1-0x7f9474de6700 h=678858 c=R00009163-01-54f4b241] INFO Core.PETaskRadiusEnfProfileBuilder - EnfProfileAction=ACCEPT |
2015-03-02 10:56:01,755 | [RequestHandler-1-0x7f9474de6700 h=678858 c=R00009163-01-54f4b241] INFO Core.PETaskRadiusEnfProfileBuilder - Radius enfProfiles used: PSC Secured Wired Cisco Switches 802.1X Wired Default Profile |
2015-03-02 10:56:01,755 | [RequestHandler-1-0x7f9474de6700 h=678858 c=R00009163-01-54f4b241] INFO Core.EnfProfileComputer - getFinalSessionTimeout: sessionTimeout = 10800 |
2015-03-02 10:56:01,755 | [RequestHandler-1-0x7f9474de6700 r=R00009163-01-54f4b241 h=678851 c=R00009163-01-54f4b241] INFO Core.PETaskScheduler - ** Completed PETaskGenericEnfProfileBuilder ** |
2015-03-02 10:56:01,755 | [RequestHandler-1-0x7f9474de6700 r=R00009163-01-54f4b241 h=678851 c=R00009163-01-54f4b241] INFO Core.PETaskScheduler - ** Completed PETaskAppEnfProfileBuilder ** |
2015-03-02 10:56:01,755 | [RequestHandler-1-0x7f9474de6700 r=R00009163-01-54f4b241 h=678851 c=R00009163-01-54f4b241] INFO Core.PETaskScheduler - ** Starting PETaskCliEnforcement ** |
2015-03-02 10:56:01,755 | [RequestHandler-1-0x7f9474de6700 h=678863 c=R00009163-01-54f4b241] INFO Core.PETaskCliEnforcement - startHandler: No commands for CLI enforcement |
2015-03-02 10:56:01,755 | [RequestHandler-1-0x7f9474de6700 r=R00009163-01-54f4b241 h=678851 c=R00009163-01-54f4b241] INFO Core.PETaskScheduler - ** Completed PETaskRadiusEnfProfileBuilder ** |
2015-03-02 10:56:01,756 | [RequestHandler-1-0x7f9474de6700 r=R00009163-01-54f4b241 h=678851 c=R00009163-01-54f4b241] INFO Core.PETaskScheduler - ** Completed PETaskCliEnforcement ** |
2015-03-02 10:56:01,756 | [RequestHandler-1-0x7f9474de6700 r=R00009163-01-54f4b241 h=678859 c=R00009163-01-54f4b241] INFO Core.PETaskRadiusCoAEnfProfileBuilder - getApplicableProfiles: No radius_coa enforcement profiles applicable for this device |
2015-03-02 10:56:01,756 | [RequestHandler-1-0x7f9474de6700 r=R00009163-01-54f4b241 h=678861 c=R00009163-01-54f4b241] INFO Core.PETaskPostAuthEnfProfileBuilder - getApplicableProfiles: No Post auth enforcement profiles applicable for this device |
2015-03-02 10:56:01,756 | [RequestHandler-1-0x7f9474de6700 r=R00009163-01-54f4b241 h=678851 c=R00009163-01-54f4b241] INFO Core.PETaskScheduler - ** Completed PETaskRadiusCoAEnfProfileBuilder ** |
2015-03-02 10:56:01,756 | [RequestHandler-1-0x7f9474de6700 r=R00009163-01-54f4b241 h=678851 c=R00009163-01-54f4b241] INFO Core.PETaskScheduler - ** Completed PETaskPostAuthEnfProfileBuilder ** |
2015-03-02 10:56:01,756 | [RequestHandler-1-0x7f9474de6700 r=R00009163-01-54f4b241 h=678851 c=R00009163-01-54f4b241] INFO Core.PETaskScheduler - ** Starting PETaskAuthStatusInfo ** |
2015-03-02 10:56:01,756 | [RequestHandler-1-0x7f9474de6700 r=R00009163-01-54f4b241 h=678851 c=R00009163-01-54f4b241] INFO Core.PETaskScheduler - ** Starting PETaskOutputPolicyRes ** |
2015-03-02 10:56:01,756 | [RequestHandler-1-0x7f9474de6700 r=R00009163-01-54f4b241 h=678851 c=R00009163-01-54f4b241] INFO Core.PETaskScheduler - ** Starting PETaskSessionLog ** |
2015-03-02 10:56:01,759 | [RequestHandler-1-0x7f9474de6700 h=678865 c=R00009163-01-54f4b241] INFO Core.XpipPolicyResHandler - populateResponseTlv: PETaskPostureOutput does not exist. Skip sending posture VAFs |
2015-03-02 10:56:01,759 | [RequestHandler-1-0x7f9474de6700 h=678865 c=R00009163-01-54f4b241] INFO Core.PolicyResCollector - getSohr: Failed to generate Sohr |
2015-03-02 10:56:01,759 | [RequestHandler-1-0x7f9474de6700 h=678864 c=R00009163-01-54f4b241] INFO Core.PolicyResCollector - getSohr: Failed to generate Sohr |
2015-03-02 10:56:01,759 | [RequestHandler-1-0x7f9474de6700 r=R00009163-01-54f4b241 h=678851 c=R00009163-01-54f4b241] INFO Core.PETaskScheduler - ** Completed PETaskSessionLog ** |
2015-03-02 10:56:01,759 | [RequestHandler-1-0x7f9474de6700 r=R00009163-01-54f4b241 h=678851 c=R00009163-01-54f4b241] INFO Core.PETaskScheduler - ** Completed PETaskOutputPolicyRes ** |
2015-03-02 10:56:01,759 | [RequestHandler-1-0x7f9474de6700 r=R00009163-01-54f4b241 h=678851 c=R00009163-01-54f4b241] INFO Core.PETaskScheduler - ** Completed PETaskAuthStatusInfo ** |
2015-03-02 10:56:01,759 | [RequestHandler-1-0x7f9474de6700 r=R00009163-01-54f4b241 h=678851 c=R00009163-01-54f4b241] INFO Core.PETaskScheduler - *** PE_TASK_SCHEDULE_RADIUS Completed *** |
2015-03-02 10:56:01,760 | [Th 33 Req 332210 SessId R00009163-01-54f4b241] INFO RadiusServer.Radius - Policy Evaluation time = 12 ms |
2015-03-02 10:56:01,760 | [Th 33 Req 332210 SessId R00009163-01-54f4b241] INFO RadiusServer.Radius - rlm_policy: Received Accept Enforcement Profile |
2015-03-02 10:56:01,760 | [Th 33 Req 332210 SessId R00009163-01-54f4b241] INFO RadiusServer.Radius - rlm_policy: Policy Server reply does not contain Posture-Validation-Response |
2015-03-02 10:56:01,760 | [Th 33 Req 332210 SessId R00009163-01-54f4b241] INFO RadiusServer.Radius - Request processing time = 19 ms |