Request log details for session: R004e36d8-01-58760f7a

Time	Message
2017-01-11 11:56:58,640	[Th 179 Req 40930044 SessId R004e36d8-01-58760f7a] INFO RadiusServer.Radius - rlm_service: Starting Service Categorization - 63:266:A088B4710AA8
2017-01-11 11:56:58,643	[Th 179 Req 40930044 SessId R004e36d8-01-58760f7a] INFO RadiusServer.Radius - Service Categorization time = 3 ms
2017-01-11 11:56:58,643	[RequestHandler-1-0x7f7acfbdd700 r=psauto-1467958883-10900511 h=223 r=R004e36d8-01-58760f7a] INFO Core.ServiceReqHandler - Service classification result = Municipality Aruba 802.1X Wireless
2017-01-11 11:56:58,644	[Th 179 Req 40930044 SessId R004e36d8-01-58760f7a] INFO RadiusServer.Radius - rlm_service: The request has been categorized into service "Municipality Aruba 802.1X Wireless"
2017-01-11 11:56:58,644	[Th 179 Req 40930044 SessId R004e36d8-01-58760f7a] INFO RadiusServer.Radius - rlm_ldap: searching for user computer1.municipality.intra in AD:ad-01.municipality.intra
2017-01-11 11:56:58,645	[Th 179 Req 40930044 SessId R004e36d8-01-58760f7a] INFO RadiusServer.Radius - rlm_ldap: found user computer1$ in AD:ad-01.municipality.intra
2017-01-11 11:56:58,645	[Th 179 Req 40930044 SessId R004e36d8-01-58760f7a] INFO RadiusServer.Radius - LDAP/AD User lookup time = 1 ms
2017-01-11 11:56:58,645	[Th 179 Req 40930044 SessId R004e36d8-01-58760f7a] INFO RadiusServer.Radius - rlm_eap_peap: Initiate
2017-01-11 11:56:58,645	[Th 179 Req 40930044 SessId R004e36d8-01-58760f7a] INFO RadiusServer.Radius - reqst_update_state: Access-Challenge 63:76:A088B4710AA8:0x00bc00ba002b0067fc8a70025c4fa5aa95d083b278924749eaacd189
2017-01-11 11:56:58,654	[Th 175 Req 40930045 SessId R004e36d8-01-58760f7a] INFO RadiusServer.Radius - rlm_service: The request was categorized into service "Municipality Aruba 802.1X Wireless" - 90:261:A088B4710AA8
2017-01-11 11:56:58,654	[Th 175 Req 40930045 SessId R004e36d8-01-58760f7a] INFO RadiusServer.Radius - rlm_eap_tls: Initiate
2017-01-11 11:56:58,654	[Th 175 Req 40930045 SessId R004e36d8-01-58760f7a] INFO RadiusServer.Radius - reqst_update_state: Access-Challenge 90:76:A088B4710AA8:0x0004008e00a8008dfd8a7002b5f79b1ad699506f5818b3a4c8330921
2017-01-11 11:56:58,859	[Th 182 Req 40930046 SessId R004e36d8-01-58760f7a] INFO RadiusServer.Radius - rlm_service: The request was categorized into service "Municipality Aruba 802.1X Wireless" - 66:366:A088B4710AA8
2017-01-11 11:56:58,861	[Th 182 Req 40930046 SessId R004e36d8-01-58760f7a] INFO RadiusServer.Radius - TLS_accept:error in SSLv3 read client certificate A
2017-01-11 11:56:58,861	[Th 182 Req 40930046 SessId R004e36d8-01-58760f7a] INFO RadiusServer.Radius - reqst_update_state: Access-Challenge 66:1112:A088B4710AA8:0x001a00f3005a007ffe8a7002c17004e75db487685cd48dad8decc028
2017-01-11 11:56:58,878	[Th 176 Req 40930047 SessId R004e36d8-01-58760f7a] INFO RadiusServer.Radius - rlm_service: The request was categorized into service "Municipality Aruba 802.1X Wireless" - 125:261:A088B4710AA8
2017-01-11 11:56:58,879	[Th 176 Req 40930047 SessId R004e36d8-01-58760f7a] INFO RadiusServer.Radius - reqst_update_state: Access-Challenge 125:1108:A088B4710AA8:0x0032004700b20053ff8a7002a63e1878238731b37aa9fcdef6aeb930
2017-01-11 11:56:58,896	[Th 177 Req 40930048 SessId R004e36d8-01-58760f7a] INFO RadiusServer.Radius - rlm_service: The request was categorized into service "Municipality Aruba 802.1X Wireless" - 93:261:A088B4710AA8
2017-01-11 11:56:58,896	[Th 177 Req 40930048 SessId R004e36d8-01-58760f7a] INFO RadiusServer.Radius - reqst_update_state: Access-Challenge 93:1108:A088B4710AA8:0x00e900d7002f002a008b70025c58f400f1397246922ef730f30f08a8
2017-01-11 11:56:58,914	[Th 180 Req 40930049 SessId R004e36d8-01-58760f7a] INFO RadiusServer.Radius - rlm_service: The request was categorized into service "Municipality Aruba 802.1X Wireless" - 94:261:A088B4710AA8
2017-01-11 11:56:58,914	[Th 180 Req 40930049 SessId R004e36d8-01-58760f7a] INFO RadiusServer.Radius - reqst_update_state: Access-Challenge 94:1108:A088B4710AA8:0x006c001f00bd007c018b700298f73bcc98aaf677069a1a5edbfef0b4
2017-01-11 11:56:58,931	[Th 181 Req 40930050 SessId R004e36d8-01-58760f7a] INFO RadiusServer.Radius - rlm_service: The request was categorized into service "Municipality Aruba 802.1X Wireless" - 91:261:A088B4710AA8
2017-01-11 11:56:58,931	[Th 181 Req 40930050 SessId R004e36d8-01-58760f7a] INFO RadiusServer.Radius - reqst_update_state: Access-Challenge 91:703:A088B4710AA8:0x00d600c1007500ca028b70022202d34e98bae7f419150fd52c60aa73
2017-01-11 11:56:58,964	[Th 178 Req 40930053 SessId R004e36d8-01-58760f7a] INFO RadiusServer.Radius - rlm_service: The request was categorized into service "Municipality Aruba 802.1X Wireless" - 136:1757:A088B4710AA8
2017-01-11 11:56:58,964	[Th 178 Req 40930053 SessId R004e36d8-01-58760f7a] INFO RadiusServer.Radius - reqst_update_state: Access-Challenge 136:76:A088B4710AA8:0x00330086005d00a2058b700299019d403d67faffdd7ad4337f55e6a4
2017-01-11 11:56:58,974	[Th 179 Req 40930054 SessId R004e36d8-01-58760f7a] INFO RadiusServer.Radius - rlm_service: The request was categorized into service "Municipality Aruba 802.1X Wireless" - 21:949:A088B4710AA8
2017-01-11 11:56:58,975	[Th 179 Req 40930054 SessId R004e36d8-01-58760f7a] INFO RadiusServer.Radius - chain-depth=1,
2017-01-11 11:56:58,975	[Th 179 Req 40930054 SessId R004e36d8-01-58760f7a] INFO RadiusServer.Radius - error=0
2017-01-11 11:56:58,975	[Th 179 Req 40930054 SessId R004e36d8-01-58760f7a] INFO RadiusServer.Radius - --> User-Name = host/computer1.municipality.intra
2017-01-11 11:56:58,975	[Th 179 Req 40930054 SessId R004e36d8-01-58760f7a] INFO RadiusServer.Radius - --> subject = /DC=intra/DC=municipality/CN=municipalityRootCA
2017-01-11 11:56:58,975	[Th 179 Req 40930054 SessId R004e36d8-01-58760f7a] INFO RadiusServer.Radius - --> issuer = /DC=intra/DC=municipality/CN=municipalityRootCA
2017-01-11 11:56:58,975	[Th 179 Req 40930054 SessId R004e36d8-01-58760f7a] INFO RadiusServer.Radius - --> verify return:1
2017-01-11 11:56:58,975	[Th 179 Req 40930054 SessId R004e36d8-01-58760f7a] INFO RadiusServer.Radius - chain-depth=0,
2017-01-11 11:56:58,975	[Th 179 Req 40930054 SessId R004e36d8-01-58760f7a] INFO RadiusServer.Radius - error=0
2017-01-11 11:56:58,975	[Th 179 Req 40930054 SessId R004e36d8-01-58760f7a] INFO RadiusServer.Radius - --> User-Name = host/computer1.municipality.intra
2017-01-11 11:56:58,975	[Th 179 Req 40930054 SessId R004e36d8-01-58760f7a] INFO RadiusServer.Radius - --> subject = /CN=computer1.municipality.intra
2017-01-11 11:56:58,975	[Th 179 Req 40930054 SessId R004e36d8-01-58760f7a] INFO RadiusServer.Radius - --> issuer = /DC=intra/DC=municipality/CN=municipalityRootCA
2017-01-11 11:56:58,975	[Th 179 Req 40930054 SessId R004e36d8-01-58760f7a] INFO RadiusServer.Radius - --> verify return:1
2017-01-11 11:56:58,977	[Th 179 Req 40930054 SessId R004e36d8-01-58760f7a] INFO RadiusServer.Radius - reqst_update_state: Access-Challenge 21:139:A088B4710AA8:0x00a900da00e700ad068b7002862dd215a73466eaae672999347d8c3f
2017-01-11 11:56:58,999	[Th 175 Req 40930055 SessId R004e36d8-01-58760f7a] INFO RadiusServer.Radius - rlm_service: The request was categorized into service "Municipality Aruba 802.1X Wireless" - 89:261:A088B4710AA8
2017-01-11 11:56:58,999	[Th 175 Req 40930055 SessId R004e36d8-01-58760f7a] INFO RadiusServer.Radius - rlm_eap_tls: Session established.
2017-01-11 11:56:58,999	[Th 175 Req 40930055 SessId R004e36d8-01-58760f7a] INFO RadiusServer.Radius - rlm_policy: Starting Policy Evaluation.
2017-01-11 11:56:59,002	[RequestHandler-1-0x7f7acfbdd700 r=psauto-1467958883-10900512 h=239 r=R004e36d8-01-58760f7a] ERROR Common.NadClientTable - getNadClient: Unknown NadClient X.X.X.X
2017-01-11 11:56:59,002	[RequestHandler-1-0x7f7acfbdd700 r=psauto-1467958883-10900512 h=239 r=R004e36d8-01-58760f7a] ERROR Common.NadClientTable - getNadClient: Unknown NadClient X.X.X.X
2017-01-11 11:56:59,002	[RequestHandler-1-0x7f7acfbdd700 r=psauto-1467958883-10900512 h=239 r=R004e36d8-01-58760f7a] INFO Common.EndpointTable - Returning EndpointSPtr for macAddr a088b4710aa8
2017-01-11 11:56:59,002	[RequestHandler-1-0x7f7acfbdd700 r=psauto-1467958883-10900512 h=239 r=R004e36d8-01-58760f7a] INFO Common.TagDefinitionCacheTable - No InstanceTagDefCacheMap found for instance id = 0 entity id = 29
2017-01-11 11:56:59,002	[RequestHandler-1-0x7f7acfbdd700 r=psauto-1467958883-10900512 h=239 r=R004e36d8-01-58760f7a] WARN Common.TagDefinitionCacheTable - Failed to build TagDefinitionMap. Unknown NadClient for Id=0
2017-01-11 11:56:59,002	[RequestHandler-1-0x7f7acfbdd700 r=psauto-1467958883-10900512 h=239 r=R004e36d8-01-58760f7a] INFO TAT.TagAttrHolderBuilder - No tags built for instanceId=0|entity=Device
2017-01-11 11:56:59,002	[RequestHandler-1-0x7f7acfbdd700 r=psauto-1467958883-10900512 h=239 r=R004e36d8-01-58760f7a] INFO TAT.AluTagAttrHolderBuilder - buildAttrHolder: Tags cannot be built for instanceId=0 (NULL AuthLocalUser)
2017-01-11 11:56:59,002	[RequestHandler-1-0x7f7acfbdd700 r=psauto-1467958883-10900512 h=239 r=R004e36d8-01-58760f7a] INFO TAT.GuTagAttrHolderBuilder - buildAttrHolder: Tags cannot be built for instanceId=0 (NULL GuestUser)
2017-01-11 11:56:59,002	[RequestHandler-1-0x7f7acfbdd700 r=psauto-1467958883-10900512 h=239 r=R004e36d8-01-58760f7a] INFO TAT.OnboardTagAttrHolderBuilder - buildAttrHolder: Tags cannot be built for instanceId=0 (NULL Onboard Device User)
2017-01-11 11:56:59,003	[RequestHandler-1-0x7f7acfbdd700 h=92935528 c=R004e36d8-01-58760f7a] INFO Core.PETaskScheduler - *** PE_TASK_SCHEDULE_RADIUS Started ***
2017-01-11 11:56:59,003	[RequestHandler-1-0x7f7acfbdd700 h=92935528 c=R004e36d8-01-58760f7a] INFO Core.PETaskScheduler - ** Starting PETaskAuthSourceRestriction **
2017-01-11 11:56:59,003	[RequestHandler-1-0x7f7acfbdd700 h=92935528 c=R004e36d8-01-58760f7a] INFO Core.PETaskScheduler - ** Starting PETaskRoleMapping **
2017-01-11 11:56:59,003	[RequestHandler-1-0x7f7acfbdd700 h=92935529 c=R004e36d8-01-58760f7a] WARN REC.EvaluatorCtx - Prerequisites set is empty, not populating the Request Map
2017-01-11 11:56:59,003	[RequestHandler-1-0x7f7acfbdd700 r=R004e36d8-01-58760f7a h=92935528 c=R004e36d8-01-58760f7a] INFO Core.PETaskScheduler - ** Completed PETaskAuthSourceRestriction **
2017-01-11 11:56:59,005	[RequestHandler-1-0x7f7acfbdd700 h=92935530 c=R004e36d8-01-58760f7a] INFO Core.PETaskRoleMapping - Roles: AD-Personal-Dator, Machine Authenticated]
2017-01-11 11:56:59,005	[RequestHandler-1-0x7f7acfbdd700 r=R004e36d8-01-58760f7a h=92935528 c=R004e36d8-01-58760f7a] INFO Core.PETaskScheduler - ** Completed PETaskRoleMapping **
2017-01-11 11:56:59,005	[RequestHandler-1-0x7f7acfbdd700 r=R004e36d8-01-58760f7a h=92935528 c=R004e36d8-01-58760f7a] INFO Core.PETaskScheduler - ** Starting PETaskPolicyResult **
2017-01-11 11:56:59,006	[RequestHandler-1-0x7f7acfbdd700 r=R004e36d8-01-58760f7a h=92935528 c=R004e36d8-01-58760f7a] INFO Core.PETaskScheduler - ** Completed PETaskPolicyResult **
2017-01-11 11:56:59,006	[RequestHandler-1-0x7f7acfbdd700 r=R004e36d8-01-58760f7a h=92935528 c=R004e36d8-01-58760f7a] INFO Core.PETaskScheduler - ** Starting PETaskEnforcement **
2017-01-11 11:56:59,006	[RequestHandler-1-0x7f7acfbdd700 h=92935533 c=R004e36d8-01-58760f7a] INFO Core.PETaskEnforcement - EnfProfiles: Municipality Aruba 802.1X Wireless Update Endpoint Location, Municipality Aruba 802.1X - AD-Personal-Dator
2017-01-11 11:56:59,006	[RequestHandler-1-0x7f7acfbdd700 r=R004e36d8-01-58760f7a h=92935528 c=R004e36d8-01-58760f7a] INFO Core.PETaskScheduler - ** Completed PETaskEnforcement **
2017-01-11 11:56:59,006	[RequestHandler-1-0x7f7acfbdd700 r=R004e36d8-01-58760f7a h=92935528 c=R004e36d8-01-58760f7a] INFO Core.PETaskScheduler - ** Starting PETaskRadiusEnfProfileBuilder **
2017-01-11 11:56:59,006	[RequestHandler-1-0x7f7acfbdd700 r=R004e36d8-01-58760f7a h=92935528 c=R004e36d8-01-58760f7a] INFO Core.PETaskScheduler - ** Starting PETaskRadiusCoAEnfProfileBuilder **
2017-01-11 11:56:59,006	[RequestHandler-1-0x7f7acfbdd700 r=R004e36d8-01-58760f7a h=92935528 c=R004e36d8-01-58760f7a] INFO Core.PETaskScheduler - ** Starting PETaskAppEnfProfileBuilder **
2017-01-11 11:56:59,006	[RequestHandler-1-0x7f7acfbdd700 r=R004e36d8-01-58760f7a h=92935528 c=R004e36d8-01-58760f7a] INFO Core.PETaskScheduler - ** Starting PETaskPostAuthEnfProfileBuilder **
2017-01-11 11:56:59,006	[RequestHandler-1-0x7f7acfbdd700 r=R004e36d8-01-58760f7a h=92935528 c=R004e36d8-01-58760f7a] INFO Core.PETaskScheduler - ** Starting PETaskGenericEnfProfileBuilder **
2017-01-11 11:56:59,006	[RequestHandler-1-0x7f7acfbdd700 h=92935538 c=R004e36d8-01-58760f7a] INFO Core.PETaskGenericEnfProfileBuilder - getApplicableProfiles: No App enforcement (Generic) profiles applicable for this device
2017-01-11 11:56:59,007	[RequestHandler-1-0x7f7acfbdd700 h=92935536 c=R004e36d8-01-58760f7a] ERROR Common.AppEnfProfileTable - getAppType: Failed for id=3003
2017-01-11 11:56:59,007	[RequestHandler-1-0x7f7acfbdd700 h=92935536 c=R004e36d8-01-58760f7a] ERROR Common.AppEnfProfileCacheTable -
2017-01-11 11:56:59,007	[RequestHandler-1-0x7f7acfbdd700 h=92935534 c=R004e36d8-01-58760f7a] WARN Core.SessionInfoOperations - Skip SessionInfoOperations::persistSessionInfo because of NULL NAD or NAD IP matching localhost
2017-01-11 11:56:59,007	[RequestHandler-1-0x7f7acfbdd700 h=92935534 c=R004e36d8-01-58760f7a] ERROR Common.NadClientTable - getNadClient: Unknown NadClient X.X.X.X
2017-01-11 11:56:59,007	[RequestHandler-1-0x7f7acfbdd700 h=92935534 c=R004e36d8-01-58760f7a] INFO Core.PETaskRadiusEnfProfileBuilder - EnfProfileAction=ACCEPT
2017-01-11 11:56:59,007	[RequestHandler-1-0x7f7acfbdd700 h=92935534 c=R004e36d8-01-58760f7a] INFO Core.PETaskRadiusEnfProfileBuilder - Radius enfProfiles used: Municipality Aruba 802.1X - AD-Personal-Dator
2017-01-11 11:56:59,007	[RequestHandler-1-0x7f7acfbdd700 h=92935534 c=R004e36d8-01-58760f7a] INFO Core.EnfProfileComputer - getFinalSessionTimeout: sessionTimeout = 0
2017-01-11 11:56:59,007	[RequestHandler-1-0x7f7acfbdd700 r=R004e36d8-01-58760f7a h=92935528 c=R004e36d8-01-58760f7a] INFO Core.PETaskScheduler - ** Completed PETaskGenericEnfProfileBuilder **
2017-01-11 11:56:59,007	[RequestHandler-1-0x7f7acfbdd700 r=R004e36d8-01-58760f7a h=92935528 c=R004e36d8-01-58760f7a] INFO Core.PETaskScheduler - ** Completed PETaskAppEnfProfileBuilder **
2017-01-11 11:56:59,007	[RequestHandler-1-0x7f7acfbdd700 r=R004e36d8-01-58760f7a h=92935528 c=R004e36d8-01-58760f7a] INFO Core.PETaskScheduler - ** Starting PETaskCliEnforcement **
2017-01-11 11:56:59,007	[RequestHandler-1-0x7f7acfbdd700 h=92935539 c=R004e36d8-01-58760f7a] INFO Core.PETaskCliEnforcement - startHandler: No commands for CLI enforcement
2017-01-11 11:56:59,007	[RequestHandler-1-0x7f7acfbdd700 r=R004e36d8-01-58760f7a h=92935528 c=R004e36d8-01-58760f7a] INFO Core.PETaskScheduler - ** Completed PETaskRadiusEnfProfileBuilder **
2017-01-11 11:56:59,007	[RequestHandler-1-0x7f7acfbdd700 r=R004e36d8-01-58760f7a h=92935528 c=R004e36d8-01-58760f7a] INFO Core.PETaskScheduler - ** Completed PETaskCliEnforcement **
2017-01-11 11:56:59,008	[RequestHandler-1-0x7f7acfbdd700 r=R004e36d8-01-58760f7a h=92935535 c=R004e36d8-01-58760f7a] INFO Core.PETaskRadiusCoAEnfProfileBuilder - getApplicableProfiles: No radius_coa enforcement profiles applicable for this device
2017-01-11 11:56:59,008	[RequestHandler-1-0x7f7acfbdd700 r=R004e36d8-01-58760f7a h=92935537 c=R004e36d8-01-58760f7a] INFO Core.PETaskPostAuthEnfProfileBuilder - Post auth enforcement profiles used: Municipality Aruba 802.1X Wireless Update Endpoint Location
2017-01-11 11:56:59,008	[RequestHandler-1-0x7f7acfbdd700 r=R004e36d8-01-58760f7a h=92935537 c=R004e36d8-01-58760f7a] INFO Core.PETaskPostAuthEnfProfileBuilder - UnknownAutzParams to fetch for PostAuthEnfProfiles: :
2017-01-11 11:56:59,008	[RequestHandler-1-0x7f7acfbdd700 r=R004e36d8-01-58760f7a h=92935537 c=R004e36d8-01-58760f7a] INFO Core.PETaskPostAuthEnfProfileBuilder - UnknownNAutzParams to fetch for PostAuthEnfProfiles: : Radius:Aruba:Aruba-Location-Id, Radius:IETF:NAS-IP-Address
2017-01-11 11:56:59,008	[RequestHandler-1-0x7f7acfbdd700 r=R004e36d8-01-58760f7a h=92935537 c=R004e36d8-01-58760f7a] INFO Core.PETaskPostAuthEnfProfileBuilder - Discarding attr name=Endpoint:Last Known Location|attr value=X.X.X.X:Vitsippan_AP1 as the entity = a088b4710aa8 already possesses this value
2017-01-11 11:56:59,008	[RequestHandler-1-0x7f7acfbdd700 r=R004e36d8-01-58760f7a h=92935537 c=R004e36d8-01-58760f7a] INFO Core.PETaskPostAuthEnfProfileBuilder - sendPostAuthResponse: Sending PostAuthEnfRequest={"content":{"auth_source_id":3001,"mac_address":"a088b4710aa8","nas_ip":"","post_auth_actions":{"enf_profile_name":"Municipality Aruba 802.1X Wireless Update Endpoint Location","enf_profile_type":"EntityUpdate","params":null}],"user_id":"computer1$"},"id":"R004d8489-01-5874b9c0","name":"pactrl_enf_request"}
2017-01-11 11:56:59,008	[RequestHandler-1-0x7f7acfbdd700 r=R004e36d8-01-58760f7a h=92935537 c=R004e36d8-01-58760f7a] INFO Core.PETaskPostAuthEnfProfileBuilder - sendPostAuthResponse: Done sending the PostAuthEnfRequest
2017-01-11 11:56:59,008	[RequestHandler-1-0x7f7acfbdd700 r=R004e36d8-01-58760f7a h=92935528 c=R004e36d8-01-58760f7a] INFO Core.PETaskScheduler - ** Completed PETaskRadiusCoAEnfProfileBuilder **
2017-01-11 11:56:59,008	[RequestHandler-1-0x7f7acfbdd700 r=R004e36d8-01-58760f7a h=92935528 c=R004e36d8-01-58760f7a] INFO Core.PETaskScheduler - ** Completed PETaskPostAuthEnfProfileBuilder **
2017-01-11 11:56:59,008	[RequestHandler-1-0x7f7acfbdd700 r=R004e36d8-01-58760f7a h=92935528 c=R004e36d8-01-58760f7a] INFO Core.PETaskScheduler - ** Starting PETaskAuthStatusInfo **
2017-01-11 11:56:59,008	[RequestHandler-1-0x7f7acfbdd700 r=R004e36d8-01-58760f7a h=92935528 c=R004e36d8-01-58760f7a] INFO Core.PETaskScheduler - ** Starting PETaskOutputPolicyRes **
2017-01-11 11:56:59,008	[RequestHandler-1-0x7f7acfbdd700 r=R004e36d8-01-58760f7a h=92935528 c=R004e36d8-01-58760f7a] INFO Core.PETaskScheduler - ** Starting PETaskSessionLog **
2017-01-11 11:56:59,012	[RequestHandler-1-0x7f7acfbdd700 h=92935541 c=R004e36d8-01-58760f7a] INFO Core.XpipPolicyResHandler - populateResponseTlv: PETaskPostureOutput does not exist. Skip sending posture VAFs
2017-01-11 11:56:59,012	[RequestHandler-1-0x7f7acfbdd700 h=92935541 c=R004e36d8-01-58760f7a] INFO Core.PolicyResCollector - getSohr: Failed to generate Sohr
2017-01-11 11:56:59,012	[RequestHandler-1-0x7f7acfbdd700 h=92935540 c=R004e36d8-01-58760f7a] INFO Core.PolicyResCollector - getSohr: Failed to generate Sohr
2017-01-11 11:56:59,013	[Th 175 Req 40930055 SessId R004e36d8-01-58760f7a] INFO RadiusServer.Radius - Policy Evaluation time = 12 ms
2017-01-11 11:56:59,013	[Th 175 Req 40930055 SessId R004e36d8-01-58760f7a] INFO RadiusServer.Radius - rlm_policy: Received Accept Enforcement Profile
2017-01-11 11:56:59,013	[Th 175 Req 40930055 SessId R004e36d8-01-58760f7a] INFO RadiusServer.Radius - rlm_policy: Added Class attribute with value Class = 0x10ecd1e85a504a90b2726b3a6b85cf97b90b0000000000005230303465333664382d30312d35383736306637610000000000000000000000
2017-01-11 11:56:59,013	[Th 175 Req 40930055 SessId R004e36d8-01-58760f7a] INFO RadiusServer.Radius - rlm_policy: Policy Server reply does not contain Posture-Validation-Response
2017-01-11 11:56:59,013	[Th 175 Req 40930055 SessId R004e36d8-01-58760f7a] INFO RadiusServer.Radius - Request processing time = 373 ms
2017-01-11 11:56:59,013	[RequestHandler-1-0x7f7acfbdd700 r=R004e36d8-01-58760f7a h=92935528 c=R004e36d8-01-58760f7a] INFO Core.PETaskScheduler - ** Completed PETaskSessionLog **
2017-01-11 11:56:59,013	[RequestHandler-1-0x7f7acfbdd700 r=R004e36d8-01-58760f7a h=92935528 c=R004e36d8-01-58760f7a] INFO Core.PETaskScheduler - ** Completed PETaskOutputPolicyRes **
2017-01-11 11:56:59,013	[RequestHandler-1-0x7f7acfbdd700 r=R004e36d8-01-58760f7a h=92935528 c=R004e36d8-01-58760f7a] INFO Core.PETaskScheduler - ** Completed PETaskAuthStatusInfo **
2017-01-11 11:56:59,013	[RequestHandler-1-0x7f7acfbdd700 r=R004e36d8-01-58760f7a h=92935528 c=R004e36d8-01-58760f7a] INFO Core.PETaskScheduler - *** PE_TASK_SCHEDULE_RADIUS Completed ***