Time | Message |
2017-07-21 18:23:28,994 | [RequestHandler-1-0x7f811ffff700 r=psauto-1500680040-76 h=223 r=W0000000e-01-59729b00] INFO Core.ServiceReqHandler - Service classification result = MACTrac |
2017-07-21 18:23:29,011 | [RequestHandler-1-0x7f811ffff700 r=psauto-1500680040-77 h=239 r=W0000000e-01-59729b00] INFO TAT.TagAttrTableUtil - buildTagAttrTableInput: Connection:NAD-IP-Address is not found |
2017-07-21 18:23:29,011 | [RequestHandler-1-0x7f811ffff700 r=psauto-1500680040-77 h=239 r=W0000000e-01-59729b00] INFO Common.EndpointTable - Returning NULL (EndpointPtr) for macAddr 2c0e3dxxxxxx |
2017-07-21 18:23:29,012 | [RequestHandler-1-0x7f811ffff700 r=psauto-1500680040-77 h=239 r=W0000000e-01-59729b00] INFO Common.GuestUserTable - Returning NULL (GuestUserPtr) for user Id 2c-0e-3d-xx-xx-xx |
2017-07-21 18:23:29,012 | [RequestHandler-1-0x7f811ffff700 r=psauto-1500680040-77 h=239 r=W0000000e-01-59729b00] INFO TAT.TagAttrTableUtil - getGuestUserId: Guest user not found. Username=2C-0E-3D-XX-XX-XX |
2017-07-21 18:23:29,012 | [RequestHandler-1-0x7f811ffff700 r=psauto-1500680040-77 h=239 r=W0000000e-01-59729b00] INFO Common.TagDefinitionCacheTable - No InstanceTagDefCacheMap found for instance id = 0 entity id = 29 |
2017-07-21 18:23:29,012 | [RequestHandler-1-0x7f811ffff700 r=psauto-1500680040-77 h=239 r=W0000000e-01-59729b00] WARN Common.TagDefinitionCacheTable - Failed to build TagDefinitionMap. Unknown NadClient for Id=0 |
2017-07-21 18:23:29,012 | [RequestHandler-1-0x7f811ffff700 r=psauto-1500680040-77 h=239 r=W0000000e-01-59729b00] INFO TAT.TagAttrHolderBuilder - No tags built for instanceId=0|entity=Device |
2017-07-21 18:23:29,012 | [RequestHandler-1-0x7f811ffff700 r=psauto-1500680040-77 h=239 r=W0000000e-01-59729b00] INFO TAT.AluTagAttrHolderBuilder - buildAttrHolder: Tags cannot be built for instanceId=0 (NULL AuthLocalUser) |
2017-07-21 18:23:29,012 | [RequestHandler-1-0x7f811ffff700 r=psauto-1500680040-77 h=239 r=W0000000e-01-59729b00] INFO TAT.GuTagAttrHolderBuilder - buildAttrHolder: Tags cannot be built for instanceId=0 (NULL GuestUser) |
2017-07-21 18:23:29,012 | [RequestHandler-1-0x7f811ffff700 r=psauto-1500680040-77 h=239 r=W0000000e-01-59729b00] INFO TAT.EndpointTagAttrHolderBuilder - buildAttrHolder: Tags cannot be built for instanceId=0 (NULL Endpoint) |
2017-07-21 18:23:29,012 | [RequestHandler-1-0x7f811ffff700 r=psauto-1500680040-77 h=239 r=W0000000e-01-59729b00] INFO TAT.OnboardTagAttrHolderBuilder - buildAttrHolder: Tags cannot be built for instanceId=0 (NULL Onboard Device User) |
2017-07-21 18:23:29,013 | [RequestHandler-1-0x7f811ffff700 h=963 c=W0000000e-01-59729b00] INFO Core.PETaskScheduler - *** PE_TASK_SCHEDULE_SOAP_WEBAUTH Started *** |
2017-07-21 18:23:29,013 | [RequestHandler-1-0x7f811ffff700 h=963 c=W0000000e-01-59729b00] INFO Core.PETaskScheduler - ** Starting PETaskAuthSourceRestriction ** |
2017-07-21 18:23:29,013 | [RequestHandler-1-0x7f811ffff700 h=963 c=W0000000e-01-59729b00] INFO Core.PETaskScheduler - ** Starting PETaskRoleMapping ** |
2017-07-21 18:23:29,014 | [RequestHandler-1-0x7f811ffff700 h=964 c=W0000000e-01-59729b00] WARN REC.EvaluatorCtx - Prerequisites set is empty, not populating the Request Map |
2017-07-21 18:23:29,015 | [RequestHandler-1-0x7f811ffff700 r=W0000000e-01-59729b00 h=963 c=W0000000e-01-59729b00] INFO Core.PETaskScheduler - ** Completed PETaskAuthSourceRestriction ** |
2017-07-21 18:23:29,017 | [RequestHandler-1-0x7f811ffff700 r=W0000000e-01-59729b00 h=965 c=W0000000e-01-59729b00] INFO Common.GuestUserTable - Returning NULL (GuestUserPtr) for user Id 2c-0e-3d-xx-xx-xx |
2017-07-21 18:23:29,017 | [RequestHandler-1-0x7f811ffff700 r=W0000000e-01-59729b00 h=965 c=W0000000e-01-59729b00] INFO Core.PETaskRoleMapping - Roles: User Authenticated] |
2017-07-21 18:23:29,017 | [RequestHandler-1-0x7f811ffff700 r=W0000000e-01-59729b00 h=963 c=W0000000e-01-59729b00] INFO Core.PETaskScheduler - ** Completed PETaskRoleMapping ** |
2017-07-21 18:23:29,017 | [RequestHandler-1-0x7f811ffff700 r=W0000000e-01-59729b00 h=963 c=W0000000e-01-59729b00] INFO Core.PETaskScheduler - ** Starting PETaskPolicyResult ** |
2017-07-21 18:23:29,019 | [RequestHandler-1-0x7f811ffff700 r=W0000000e-01-59729b00 h=963 c=W0000000e-01-59729b00] INFO Core.PETaskScheduler - ** Completed PETaskPolicyResult ** |
2017-07-21 18:23:29,019 | [RequestHandler-1-0x7f811ffff700 r=W0000000e-01-59729b00 h=963 c=W0000000e-01-59729b00] INFO Core.PETaskScheduler - ** Starting PETaskEnforcement ** |
2017-07-21 18:23:29,020 | [RequestHandler-1-0x7f811ffff700 h=968 c=W0000000e-01-59729b00] INFO Core.PETaskEnforcement - EnfProfiles: Aruba Terminate Session] |
2017-07-21 18:23:29,020 | [RequestHandler-1-0x7f811ffff700 r=W0000000e-01-59729b00 h=963 c=W0000000e-01-59729b00] INFO Core.PETaskScheduler - ** Completed PETaskEnforcement ** |
2017-07-21 18:23:29,021 | [RequestHandler-1-0x7f811ffff700 r=W0000000e-01-59729b00 h=963 c=W0000000e-01-59729b00] INFO Core.PETaskScheduler - ** Starting PETaskAppEnfProfileBuilder ** |
2017-07-21 18:23:29,021 | [RequestHandler-1-0x7f811ffff700 r=W0000000e-01-59729b00 h=963 c=W0000000e-01-59729b00] INFO Core.PETaskScheduler - ** Starting PETaskSnmpEnforcement ** |
2017-07-21 18:23:29,021 | [RequestHandler-1-0x7f811ffff700 r=W0000000e-01-59729b00 h=963 c=W0000000e-01-59729b00] INFO Core.PETaskScheduler - ** Starting PETaskRadiusCoAEnfProfileBuilder ** |
2017-07-21 18:23:29,021 | [RequestHandler-1-0x7f811ffff700 r=W0000000e-01-59729b00 h=963 c=W0000000e-01-59729b00] INFO Core.PETaskScheduler - ** Starting PETaskAgentEnfProfileBuilder ** |
2017-07-21 18:23:29,021 | [RequestHandler-1-0x7f811ffff700 r=W0000000e-01-59729b00 h=963 c=W0000000e-01-59729b00] INFO Core.PETaskScheduler - ** Starting PETaskPostAuthEnfProfileBuilder ** |
2017-07-21 18:23:29,021 | [RequestHandler-1-0x7f811ffff700 r=W0000000e-01-59729b00 h=963 c=W0000000e-01-59729b00] INFO Core.PETaskScheduler - ** Starting PETaskGenericEnfProfileBuilder ** |
2017-07-21 18:23:29,021 | [RequestHandler-1-0x7f811ffff700 h=974 c=W0000000e-01-59729b00] INFO Core.PETaskGenericEnfProfileBuilder - getApplicableProfiles: No App enforcement (Generic) profiles applicable for this device |
2017-07-21 18:23:29,022 | [RequestHandler-1-0x7f811ffff700 r=W0000000e-01-59729b00 h=963 c=W0000000e-01-59729b00] INFO Core.PETaskScheduler - ** Completed PETaskGenericEnfProfileBuilder ** |
2017-07-21 18:23:29,022 | [RequestHandler-1-0x7f811ffff700 r=W0000000e-01-59729b00 h=963 c=W0000000e-01-59729b00] INFO Core.PETaskScheduler - ** Completed PETaskAgentEnfProfileBuilder ** |
2017-07-21 18:23:29,023 | [RequestHandler-1-0x7f811ffff700 r=W0000000e-01-59729b00 h=963 c=W0000000e-01-59729b00] INFO Core.PETaskScheduler - ** Completed PETaskSnmpEnforcement ** |
2017-07-21 18:23:29,023 | [RequestHandler-1-0x7f811ffff700 r=W0000000e-01-59729b00 h=963 c=W0000000e-01-59729b00] INFO Core.PETaskScheduler - ** Completed PETaskAppEnfProfileBuilder ** |
2017-07-21 18:23:29,023 | [RequestHandler-1-0x7f811ffff700 r=W0000000e-01-59729b00 h=963 c=W0000000e-01-59729b00] INFO Core.PETaskScheduler - ** Starting PETaskCliEnforcement ** |
2017-07-21 18:23:29,023 | [RequestHandler-1-0x7f811ffff700 h=975 c=W0000000e-01-59729b00] INFO Core.PETaskCliEnforcement - startHandler: No commands for CLI enforcement |
2017-07-21 18:23:29,023 | [RequestHandler-1-0x7f811ffff700 r=W0000000e-01-59729b00 h=963 c=W0000000e-01-59729b00] INFO Core.PETaskScheduler - ** Completed PETaskCliEnforcement ** |
2017-07-21 18:23:29,024 | [RequestHandler-1-0x7f811ffff700 r=W0000000e-01-59729b00 h=973 c=W0000000e-01-59729b00] INFO Core.PETaskPostAuthEnfProfileBuilder - getApplicableProfiles: No Post auth enforcement profiles applicable for this device |
2017-07-21 18:23:29,024 | [RequestHandler-1-0x7f811ffff700 r=W0000000e-01-59729b00 h=963 c=W0000000e-01-59729b00] INFO Core.PETaskScheduler - ** Completed PETaskPostAuthEnfProfileBuilder ** |
2017-07-21 18:23:29,025 | [RequestHandler-1-0x7f811ffff700 r=W0000000e-01-59729b00 h=971 c=W0000000e-01-59729b00] INFO Core.PETaskRadiusCoAEnfProfileBuilder - Radius_CoA enfProfiles used: Aruba Terminate Session] |
2017-07-21 18:23:29,025 | [RequestHandler-1-0x7f811ffff700 r=W0000000e-01-59729b00 h=971 c=W0000000e-01-59729b00] INFO Core.PETaskRadiusCoAEnfProfileBuilder - UnknownAutzParams to fetch for RadiusCoAEnfProfiles: : |
2017-07-21 18:23:29,025 | [RequestHandler-1-0x7f811ffff700 r=W0000000e-01-59729b00 h=971 c=W0000000e-01-59729b00] INFO Core.PETaskRadiusCoAEnfProfileBuilder - UnknownNAutzParams to fetch for RadiusCoAEnfProfiles: : Radius:IETF:Calling-Station-Id |
2017-07-21 18:23:29,025 | [RequestHandler-1-0x7f811ffff700 r=W0000000e-01-59729b00 h=971 c=W0000000e-01-59729b00] WARN Util.ParameterizedString - getReplacedStrings: Failed to replace parameString =%{Radius:IETF:Calling-Station-Id}, error=No values for param=Radius:IETF:Calling-Station-Id |
2017-07-21 18:23:29,025 | [RequestHandler-1-0x7f811ffff700 r=W0000000e-01-59729b00 h=971 c=W0000000e-01-59729b00] WARN Core.PETaskRadiusCoAEnfProfileBuilder - addParamsFromParameterizedProfile: Failed to find finalValue for name= Radius:IETF:Calling-Station-Id value = %{Radius:IETF:Calling-Station-Id}. Searching attributes from battery |
2017-07-21 18:23:29,026 | [RequestHandler-1-0x7f811ffff700 r=W0000000e-01-59729b00 h=963 c=W0000000e-01-59729b00] INFO Core.PETaskScheduler - ** Completed PETaskRadiusCoAEnfProfileBuilder ** |
2017-07-21 18:23:29,027 | [RequestHandler-1-0x7f811ffff700 r=W0000000e-01-59729b00 h=963 c=W0000000e-01-59729b00] INFO Core.PETaskScheduler - ** Starting PETaskAuthStatusInfo ** |
2017-07-21 18:23:29,027 | [RequestHandler-1-0x7f811ffff700 r=W0000000e-01-59729b00 h=963 c=W0000000e-01-59729b00] INFO Core.PETaskScheduler - ** Starting PETaskOutputPolicyRes ** |
2017-07-21 18:23:29,027 | [RequestHandler-1-0x7f811ffff700 r=W0000000e-01-59729b00 h=963 c=W0000000e-01-59729b00] INFO Core.PETaskScheduler - ** Starting PETaskSessionLog ** |
2017-07-21 18:23:29,033 | [RequestHandler-1-0x7f811ffff700 h=977 c=W0000000e-01-59729b00] INFO Core.PolicyResCollector - getSohr: Failed to generate Sohr |
2017-07-21 18:23:29,034 | [RequestHandler-1-0x7f811ffff700 h=976 c=W0000000e-01-59729b00] INFO Core.PolicyResCollector - getSohr: Failed to generate Sohr |
2017-07-21 18:23:29,035 | [RequestHandler-1-0x7f811ffff700 r=W0000000e-01-59729b00 h=963 c=W0000000e-01-59729b00] INFO Core.PETaskScheduler - ** Completed PETaskSessionLog ** |
2017-07-21 18:23:29,035 | [RequestHandler-1-0x7f811ffff700 r=W0000000e-01-59729b00 h=963 c=W0000000e-01-59729b00] INFO Core.PETaskScheduler - ** Completed PETaskOutputPolicyRes ** |
2017-07-21 18:23:29,035 | [RequestHandler-1-0x7f811ffff700 r=W0000000e-01-59729b00 h=963 c=W0000000e-01-59729b00] INFO Core.PETaskScheduler - ** Completed PETaskAuthStatusInfo ** |
2017-07-21 18:23:29,035 | [RequestHandler-1-0x7f811ffff700 r=W0000000e-01-59729b00 h=963 c=W0000000e-01-59729b00] INFO Core.PETaskScheduler - *** PE_TASK_SCHEDULE_SOAP_WEBAUTH Completed *** |