_Request log details for session: W00000088-01-5f4ff039 _

Time 	Message
2020-09-02 15:19:21,560 	[RequestHandler-1-0x7fdd9cde6700
r=psauto-1593027735-385908 h=223 r=W00000088-01-5f4ff039] INFO
Core.ServiceReqHandler - Service classification result = Grange-Wired
Guest WebAuth
2020-09-02 15:19:21,570 	[RequestHandler-1-0x7fdd9cde6700
r=psauto-1593027735-385909 h=239 r=W00000088-01-5f4ff039] INFO
TAT.TagAttrTableUtil - buildTagAttrTableInput: Connection:NAD-IP-Address
is not found
2020-09-02 15:19:21,570 	[RequestHandler-1-0x7fdd9cde6700
r=psauto-1593027735-385909 h=239 r=W00000088-01-5f4ff039] INFO
Common.EndpointTable - Returning NULL (EndpointPtr) for macAddr 64b9e8c6c90e
2020-09-02 15:19:21,570 	[RequestHandler-1-0x7fdd9cde6700
r=psauto-1593027735-385909 h=239 r=W00000088-01-5f4ff039] INFO
Common.TagDefinitionCacheTable - No InstanceTagDefCacheMap found for
instance id = 0 entity id = 29
2020-09-02 15:19:21,570 	[RequestHandler-1-0x7fdd9cde6700
r=psauto-1593027735-385909 h=239 r=W00000088-01-5f4ff039] WARN
Common.TagDefinitionCacheTable - Failed to build TagDefinitionMap.
Unknown NadClient for Id=0
2020-09-02 15:19:21,570 	[RequestHandler-1-0x7fdd9cde6700
r=psauto-1593027735-385909 h=239 r=W00000088-01-5f4ff039] INFO
TAT.TagAttrHolderBuilder - No tags built for instanceId=0|entity=Device
2020-09-02 15:19:21,570 	[RequestHandler-1-0x7fdd9cde6700
r=psauto-1593027735-385909 h=239 r=W00000088-01-5f4ff039] INFO
TAT.AluTagAttrHolderBuilder - buildAttrHolder: Tags cannot be built for
instanceId=0 (NULL AuthLocalUser)
2020-09-02 15:19:21,570 	[RequestHandler-1-0x7fdd9cde6700
r=psauto-1593027735-385909 h=239 r=W00000088-01-5f4ff039] INFO
TAT.GuTagAttrHolderBuilder - buildAttrHolder: Tags cannot be built for
instanceId=0 (NULL GuestUser)
2020-09-02 15:19:21,570 	[RequestHandler-1-0x7fdd9cde6700
r=psauto-1593027735-385909 h=239 r=W00000088-01-5f4ff039] INFO
TAT.EndpointTagAttrHolderBuilder - buildAttrHolder: Tags cannot be built
for instanceId=0 (NULL Endpoint)
2020-09-02 15:19:21,570 	[RequestHandler-1-0x7fdd9cde6700
r=psauto-1593027735-385909 h=239 r=W00000088-01-5f4ff039] INFO
TAT.OnboardTagAttrHolderBuilder - buildAttrHolder: Tags cannot be built
for instanceId=0 (NULL Onboard Device User)
2020-09-02 15:19:21,571 	[RequestHandler-1-0x7fdd9cde6700 h=949619
c=W00000088-01-5f4ff039] INFO Core.PETaskScheduler - ***
PE_TASK_SCHEDULE_SOAP_WEBAUTH Started ***
2020-09-02 15:19:21,571 	[RequestHandler-1-0x7fdd9cde6700 h=949619
c=W00000088-01-5f4ff039] INFO Core.PETaskScheduler - ** Starting
PETaskAuthSourceRestriction **
2020-09-02 15:19:21,571 	[RequestHandler-1-0x7fdd9cde6700 h=949619
c=W00000088-01-5f4ff039] INFO Core.PETaskScheduler - ** Starting
PETaskRoleMapping **
2020-09-02 15:19:21,571 	[RequestHandler-1-0x7fdd9cde6700 h=949620
c=W00000088-01-5f4ff039] WARN REC.EvaluatorCtx - Prerequisites set is
empty, not populating the Request Map
2020-09-02 15:19:21,571 	[RequestHandler-1-0x7fdd9cde6700
r=W00000088-01-5f4ff039 h=949619 c=W00000088-01-5f4ff039] INFO
Core.PETaskScheduler - ** Completed PETaskAuthSourceRestriction **
2020-09-02 15:19:21,573 	[RequestHandler-1-0x7fdd9cde6700 h=949621
c=W00000088-01-5f4ff039] INFO Core.PETaskRoleMapping - Roles: User
Authenticated]
2020-09-02 15:19:21,573 	[RequestHandler-1-0x7fdd9cde6700
r=W00000088-01-5f4ff039 h=949619 c=W00000088-01-5f4ff039] INFO
Core.PETaskScheduler - ** Completed PETaskRoleMapping **
2020-09-02 15:19:21,574 	[RequestHandler-1-0x7fdd9cde6700
r=W00000088-01-5f4ff039 h=949619 c=W00000088-01-5f4ff039] INFO
Core.PETaskScheduler - ** Starting PETaskPolicyResult **
2020-09-02 15:19:21,574 	[RequestHandler-1-0x7fdd9cde6700
r=W00000088-01-5f4ff039 h=949619 c=W00000088-01-5f4ff039] INFO
Core.PETaskScheduler - ** Completed PETaskPolicyResult **
2020-09-02 15:19:21,574 	[RequestHandler-1-0x7fdd9cde6700
r=W00000088-01-5f4ff039 h=949619 c=W00000088-01-5f4ff039] INFO
Core.PETaskScheduler - ** Starting PETaskEnforcement **
2020-09-02 15:19:21,574 	[RequestHandler-1-0x7fdd9cde6700 h=949624
c=W00000088-01-5f4ff039] INFO Core.PETaskEnforcement - EnfProfiles:
ArubaOS Switching - Bounce Switch Port]
2020-09-02 15:19:21,574 	[RequestHandler-1-0x7fdd9cde6700
r=W00000088-01-5f4ff039 h=949619 c=W00000088-01-5f4ff039] INFO
Core.PETaskScheduler - ** Completed PETaskEnforcement **
2020-09-02 15:19:21,575 	[RequestHandler-1-0x7fdd9cde6700
r=W00000088-01-5f4ff039 h=949619 c=W00000088-01-5f4ff039] INFO
Core.PETaskScheduler - ** Starting PETaskAppEnfProfileBuilder **
2020-09-02 15:19:21,575 	[RequestHandler-1-0x7fdd9cde6700
r=W00000088-01-5f4ff039 h=949619 c=W00000088-01-5f4ff039] INFO
Core.PETaskScheduler - ** Starting PETaskSnmpEnforcement **
2020-09-02 15:19:21,575 	[RequestHandler-1-0x7fdd9cde6700
r=W00000088-01-5f4ff039 h=949619 c=W00000088-01-5f4ff039] INFO
Core.PETaskScheduler - ** Starting PETaskRadiusCoAEnfProfileBuilder **
2020-09-02 15:19:21,575 	[RequestHandler-1-0x7fdd9cde6700
r=W00000088-01-5f4ff039 h=949619 c=W00000088-01-5f4ff039] INFO
Core.PETaskScheduler - ** Starting PETaskAgentEnfProfileBuilder **
2020-09-02 15:19:21,575 	[RequestHandler-1-0x7fdd9cde6700
r=W00000088-01-5f4ff039 h=949619 c=W00000088-01-5f4ff039] INFO
Core.PETaskScheduler - ** Starting PETaskPostAuthEnfProfileBuilder **
2020-09-02 15:19:21,575 	[RequestHandler-1-0x7fdd9cde6700
r=W00000088-01-5f4ff039 h=949619 c=W00000088-01-5f4ff039] INFO
Core.PETaskScheduler - ** Starting PETaskGenericEnfProfileBuilder **
2020-09-02 15:19:21,575 	[RequestHandler-1-0x7fdd9cde6700 h=949630
c=W00000088-01-5f4ff039] INFO Core.PETaskGenericEnfProfileBuilder -
getApplicableProfiles: No App enforcement (Generic) profiles applicable
for this device
2020-09-02 15:19:21,575 	[RequestHandler-1-0x7fdd9cde6700
r=W00000088-01-5f4ff039 h=949619 c=W00000088-01-5f4ff039] INFO
Core.PETaskScheduler - ** Completed PETaskGenericEnfProfileBuilder **
2020-09-02 15:19:21,575 	[RequestHandler-1-0x7fdd9cde6700
r=W00000088-01-5f4ff039 h=949619 c=W00000088-01-5f4ff039] INFO
Core.PETaskScheduler - ** Completed PETaskAgentEnfProfileBuilder **
2020-09-02 15:19:21,575 	[RequestHandler-1-0x7fdd9cde6700
r=W00000088-01-5f4ff039 h=949619 c=W00000088-01-5f4ff039] INFO
Core.PETaskScheduler - ** Completed PETaskSnmpEnforcement **
2020-09-02 15:19:21,575 	[RequestHandler-1-0x7fdd9cde6700
r=W00000088-01-5f4ff039 h=949619 c=W00000088-01-5f4ff039] INFO
Core.PETaskScheduler - ** Completed PETaskAppEnfProfileBuilder **
2020-09-02 15:19:21,575 	[RequestHandler-1-0x7fdd9cde6700
r=W00000088-01-5f4ff039 h=949619 c=W00000088-01-5f4ff039] INFO
Core.PETaskScheduler - ** Starting PETaskCliEnforcement **
2020-09-02 15:19:21,575 	[RequestHandler-1-0x7fdd9cde6700 h=949631
c=W00000088-01-5f4ff039] INFO Core.PETaskCliEnforcement - startHandler:
No commands for CLI enforcement
2020-09-02 15:19:21,575 	[RequestHandler-1-0x7fdd9cde6700
r=W00000088-01-5f4ff039 h=949619 c=W00000088-01-5f4ff039] INFO
Core.PETaskScheduler - ** Completed PETaskCliEnforcement **
2020-09-02 15:19:21,576 	[RequestHandler-1-0x7fdd9cde6700
r=W00000088-01-5f4ff039 h=949627 c=W00000088-01-5f4ff039] INFO
Core.PETaskRadiusCoAEnfProfileBuilder - Radius_CoA enfProfiles used:
ArubaOS Switching - Bounce Switch Port]
2020-09-02 15:19:21,576 	[RequestHandler-1-0x7fdd9cde6700
r=W00000088-01-5f4ff039 h=949627 c=W00000088-01-5f4ff039] INFO
Core.PETaskRadiusCoAEnfProfileBuilder - UnknownAutzParams to fetch for
RadiusCoAEnfProfiles: :
2020-09-02 15:19:21,576 	[RequestHandler-1-0x7fdd9cde6700
r=W00000088-01-5f4ff039 h=949627 c=W00000088-01-5f4ff039] INFO
Core.PETaskRadiusCoAEnfProfileBuilder - UnknownNAutzParams to fetch for
RadiusCoAEnfProfiles: : Radius:IETF:Calling-Station-Id,
Radius:IETF:Event-Timestamp, Radius:IETF:NAS-IP-Address,
Radius:IETF:NAS-Port, Radius:IETF:User-Name
2020-09-02 15:19:21,576 	[RequestHandler-1-0x7fdd9cde6700
r=W00000088-01-5f4ff039 h=949627 c=W00000088-01-5f4ff039] WARN
Util.ParameterizedString - getReplacedStrings: Failed to replace
parameString =%{Radius:IETF:User-Name}, error=No values for
param=Radius:IETF:User-Name
2020-09-02 15:19:21,576 	[RequestHandler-1-0x7fdd9cde6700
r=W00000088-01-5f4ff039 h=949627 c=W00000088-01-5f4ff039] WARN
Core.PETaskRadiusCoAEnfProfileBuilder -
addParamsFromParameterizedProfile: Failed to find finalValue for name=
Radius:IETF:User-Name value = %{Radius:IETF:User-Name}. Searching
attributes from battery
2020-09-02 15:19:21,576 	[RequestHandler-1-0x7fdd9cde6700
r=W00000088-01-5f4ff039 h=949627 c=W00000088-01-5f4ff039] WARN
Util.ParameterizedString - getReplacedStrings: Failed to replace
parameString =%{Radius:IETF:Calling-Station-Id}, error=No values for
param=Radius:IETF:Calling-Station-Id
2020-09-02 15:19:21,576 	[RequestHandler-1-0x7fdd9cde6700
r=W00000088-01-5f4ff039 h=949627 c=W00000088-01-5f4ff039] 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
2020-09-02 15:19:21,576 	[RequestHandler-1-0x7fdd9cde6700
r=W00000088-01-5f4ff039 h=949627 c=W00000088-01-5f4ff039] WARN
Util.ParameterizedString - getReplacedStrings: Failed to replace
parameString =%{Radius:IETF:NAS-Port}, error=No values for
param=Radius:IETF:NAS-Port
2020-09-02 15:19:21,576 	[RequestHandler-1-0x7fdd9cde6700
r=W00000088-01-5f4ff039 h=949627 c=W00000088-01-5f4ff039] WARN
Core.PETaskRadiusCoAEnfProfileBuilder -
addParamsFromParameterizedProfile: Failed to find finalValue for name=
Radius:IETF:NAS-Port value = %{Radius:IETF:NAS-Port}. Searching
attributes from battery
2020-09-02 15:19:21,576 	[RequestHandler-1-0x7fdd9cde6700
r=W00000088-01-5f4ff039 h=949627 c=W00000088-01-5f4ff039] WARN
Util.ParameterizedString - getReplacedStrings: Failed to replace
parameString =%{Radius:IETF:NAS-IP-Address}, error=No values for
param=Radius:IETF:NAS-IP-Address
2020-09-02 15:19:21,576 	[RequestHandler-1-0x7fdd9cde6700
r=W00000088-01-5f4ff039 h=949627 c=W00000088-01-5f4ff039] WARN
Core.PETaskRadiusCoAEnfProfileBuilder -
addParamsFromParameterizedProfile: Failed to find finalValue for name=
Radius:IETF:NAS-IP-Address value = %{Radius:IETF:NAS-IP-Address}.
Searching attributes from battery
2020-09-02 15:19:21,576 	[RequestHandler-1-0x7fdd9cde6700
r=W00000088-01-5f4ff039 h=949627 c=W00000088-01-5f4ff039] WARN
Util.ParameterizedString - getReplacedStrings: Failed to replace
parameString =%{Radius:IETF:Event-Timestamp}, error=No values for
param=Radius:IETF:Event-Timestamp
2020-09-02 15:19:21,576 	[RequestHandler-1-0x7fdd9cde6700
r=W00000088-01-5f4ff039 h=949627 c=W00000088-01-5f4ff039] WARN
Core.PETaskRadiusCoAEnfProfileBuilder -
addParamsFromParameterizedProfile: Failed to find finalValue for name=
Radius:IETF:Event-Timestamp value = %{Radius:IETF:Event-Timestamp}.
Searching attributes from battery
2020-09-02 15:19:21,577 	[RequestHandler-1-0x7fdd9cde6700
r=W00000088-01-5f4ff039 h=949619 c=W00000088-01-5f4ff039] INFO
Core.PETaskScheduler - ** Completed PETaskRadiusCoAEnfProfileBuilder **
2020-09-02 15:19:21,580 	[RequestHandler-1-0x7fdd9cde6700
r=W00000088-01-5f4ff039 h=949629 c=W00000088-01-5f4ff039] INFO
Core.PETaskPostAuthEnfProfileBuilder - getApplicableProfiles: No Post
auth enforcement profiles applicable for this device
2020-09-02 15:19:21,580 	[RequestHandler-1-0x7fdd9cde6700
r=W00000088-01-5f4ff039 h=949619 c=W00000088-01-5f4ff039] INFO
Core.PETaskScheduler - ** Completed PETaskPostAuthEnfProfileBuilder **
2020-09-02 15:19:21,580 	[RequestHandler-1-0x7fdd9cde6700
r=W00000088-01-5f4ff039 h=949619 c=W00000088-01-5f4ff039] INFO
Core.PETaskScheduler - ** Starting PETaskAuthStatusInfo **
2020-09-02 15:19:21,580 	[RequestHandler-1-0x7fdd9cde6700
r=W00000088-01-5f4ff039 h=949619 c=W00000088-01-5f4ff039] INFO
Core.PETaskScheduler - ** Starting PETaskOutputPolicyRes **
2020-09-02 15:19:21,580 	[RequestHandler-1-0x7fdd9cde6700
r=W00000088-01-5f4ff039 h=949619 c=W00000088-01-5f4ff039] INFO
Core.PETaskScheduler - ** Starting PETaskSessionLog **
2020-09-02 15:19:21,583 	[RequestHandler-1-0x7fdd9cde6700 h=949633
c=W00000088-01-5f4ff039] INFO Core.PolicyResCollector - getSohr: Failed
to generate Sohr
2020-09-02 15:19:21,583 	[RequestHandler-1-0x7fdd9cde6700 h=949632
c=W00000088-01-5f4ff039] INFO Core.PolicyResCollector - getSohr: Failed
to generate Sohr
2020-09-02 15:19:21,583 	[RequestHandler-1-0x7fdd9cde6700
r=W00000088-01-5f4ff039 h=949619 c=W00000088-01-5f4ff039] INFO
Core.PETaskScheduler - ** Completed PETaskSessionLog **
2020-09-02 15:19:21,583 	[RequestHandler-1-0x7fdd9cde6700
r=W00000088-01-5f4ff039 h=949619 c=W00000088-01-5f4ff039] INFO
Core.PETaskScheduler - ** Completed PETaskOutputPolicyRes **
2020-09-02 15:19:21,583 	[RequestHandler-1-0x7fdd9cde6700
r=W00000088-01-5f4ff039 h=949619 c=W00000088-01-5f4ff039] INFO
Core.PETaskScheduler - ** Completed PETaskAuthStatusInfo **
2020-09-02 15:19:21,583 	[RequestHandler-1-0x7fdd9cde6700
r=W00000088-01-5f4ff039 h=949619 c=W00000088-01-5f4ff039] INFO
Core.PETaskScheduler - *** PE_TASK_SCHEDULE_SOAP_WEBAUTH Completed ***