Request log details for session: R0000e6cf-01-5dc53c0d
Time	Message
2019-11-08 10:57:33,384	[Th 44 Req 104472 SessId R0000e6cf-01-5dc53c0d] INFO RadiusServer.Radius - rlm_service: Starting Service Categorization - 164:205:D8F2CAA08967
2019-11-08 10:57:33,388	[RequestHandler-1-0x7fee7d7eb700 r=psauto-1566804968-120954 h=223 r=R0000e6cf-01-5dc53c0d] INFO Core.ServiceReqHandler - Service classification result = 802.1X Wireless test 802.1X Wireless
2019-11-08 10:57:33,389	[Th 44 Req 104472 SessId R0000e6cf-01-5dc53c0d] INFO RadiusServer.Radius - Service Categorization time = 4 ms
2019-11-08 10:57:33,389	[Th 44 Req 104472 SessId R0000e6cf-01-5dc53c0d] INFO RadiusServer.Radius - rlm_service: The request has been categorized into service "802.1X Wireless test 802.1X Wireless"
2019-11-08 10:57:33,389	[Th 44 Req 104472 SessId R0000e6cf-01-5dc53c0d] INFO RadiusServer.Radius - rlm_sql: searching for user K28581@u-ssi.net in Local:localhost
2019-11-08 10:57:33,392	[Th 44 Req 104472 SessId R0000e6cf-01-5dc53c0d] INFO RadiusServer.Radius - rlm_eap_peap: Initiate
2019-11-08 10:57:33,392	[Th 44 Req 104472 SessId R0000e6cf-01-5dc53c0d] INFO RadiusServer.Radius - reqst_update_state: Access-Challenge 164:88:D8F2CAA08967:AKwA5wCtAJoYmAEAXDwqRLbxIy2+XqmOKGGZAA==
2019-11-08 10:57:33,398	[Th 43 Req 104473 SessId R0000e6cf-01-5dc53c0d] INFO RadiusServer.Radius - rlm_service: The request was categorized into service "802.1X Wireless test 802.1X Wireless" - 174:232:D8F2CAA08967
2019-11-08 10:57:33,399	[Th 43 Req 104473 SessId R0000e6cf-01-5dc53c0d] INFO RadiusServer.Radius - rlm_sql: searching for user K28581@u-ssi.net in Local:localhost
2019-11-08 10:57:33,400	[Th 43 Req 104473 SessId R0000e6cf-01-5dc53c0d] INFO RadiusServer.Radius - rlm_eap_tls: Initiate
2019-11-08 10:57:33,401	[Th 43 Req 104473 SessId R0000e6cf-01-5dc53c0d] INFO RadiusServer.Radius - reqst_update_state: Access-Challenge 174:88:D8F2CAA08967:ADUA7wAIAJsZmAEAHb8wPGadVPhriD6gmyIE4A==
2019-11-08 10:57:33,567	[Th 45 Req 104474 SessId R0000e6cf-01-5dc53c0d] INFO RadiusServer.Radius - rlm_service: The request was categorized into service "802.1X Wireless test 802.1X Wireless" - 187:392:D8F2CAA08967
2019-11-08 10:57:33,567	[Th 45 Req 104474 SessId R0000e6cf-01-5dc53c0d] INFO RadiusServer.Radius - rlm_sql: searching for user K28581@u-ssi.net in Local:localhost
2019-11-08 10:57:33,571	[Th 45 Req 104474 SessId R0000e6cf-01-5dc53c0d] INFO RadiusServer.Radius - TLS_accept:error in SSLv3 read client certificate A
2019-11-08 10:57:33,571	[Th 45 Req 104474 SessId R0000e6cf-01-5dc53c0d] INFO RadiusServer.Radius - TLS_accept:error in SSLv3 read client certificate A
2019-11-08 10:57:33,571	[Th 45 Req 104474 SessId R0000e6cf-01-5dc53c0d] INFO RadiusServer.Radius - reqst_update_state: Access-Challenge 187:1124:D8F2CAA08967:AI8AOgBgANQamAEAQRhEZR1tGkbQPzr8Q8hFBw==
2019-11-08 10:57:33,580	[Th 42 Req 104475 SessId R0000e6cf-01-5dc53c0d] INFO RadiusServer.Radius - rlm_service: The request was categorized into service "802.1X Wireless test 802.1X Wireless" - 173:232:D8F2CAA08967
2019-11-08 10:57:33,580	[Th 42 Req 104475 SessId R0000e6cf-01-5dc53c0d] INFO RadiusServer.Radius - rlm_sql: searching for user K28581@u-ssi.net in Local:localhost
2019-11-08 10:57:33,582	[Th 42 Req 104475 SessId R0000e6cf-01-5dc53c0d] INFO RadiusServer.Radius - reqst_update_state: Access-Challenge 173:1120:D8F2CAA08967:AF4AFgAmALobmAEAXwJruQbje1Mi1p01FDfg6A==
2019-11-08 10:57:33,590	[Th 41 Req 104476 SessId R0000e6cf-01-5dc53c0d] INFO RadiusServer.Radius - rlm_service: The request was categorized into service "802.1X Wireless test 802.1X Wireless" - 186:232:D8F2CAA08967
2019-11-08 10:57:33,590	[Th 41 Req 104476 SessId R0000e6cf-01-5dc53c0d] INFO RadiusServer.Radius - rlm_sql: searching for user K28581@u-ssi.net in Local:localhost
2019-11-08 10:57:33,592	[Th 41 Req 104476 SessId R0000e6cf-01-5dc53c0d] INFO RadiusServer.Radius - reqst_update_state: Access-Challenge 186:979:D8F2CAA08967:AOcAPwAKAKEcmAEAyqLcDyqEQXzJpBbMdj4WKA==
2019-11-08 10:57:34,174	[Th 44 Req 104477 SessId R0000e6cf-01-5dc53c0d] INFO RadiusServer.Radius - rlm_service: The request was categorized into service "802.1X Wireless test 802.1X Wireless" - 175:1728:D8F2CAA08967
2019-11-08 10:57:34,174	[Th 44 Req 104477 SessId R0000e6cf-01-5dc53c0d] INFO RadiusServer.Radius - rlm_sql: searching for user K28581@u-ssi.net in Local:localhost
2019-11-08 10:57:34,176	[Th 44 Req 104477 SessId R0000e6cf-01-5dc53c0d] INFO RadiusServer.Radius - reqst_update_state: Access-Challenge 175:88:D8F2CAA08967:ACMAxwD8AHgdmAEA+o5RuftMwt9BVlzm2YL64g==
2019-11-08 10:57:34,185	[Th 43 Req 104478 SessId R0000e6cf-01-5dc53c0d] INFO RadiusServer.Radius - rlm_service: The request was categorized into service "802.1X Wireless test 802.1X Wireless" - 194:1728:D8F2CAA08967
2019-11-08 10:57:34,185	[Th 43 Req 104478 SessId R0000e6cf-01-5dc53c0d] INFO RadiusServer.Radius - rlm_sql: searching for user K28581@u-ssi.net in Local:localhost
2019-11-08 10:57:34,186	[Th 43 Req 104478 SessId R0000e6cf-01-5dc53c0d] INFO RadiusServer.Radius - reqst_update_state: Access-Challenge 194:88:D8F2CAA08967:AIgAMgCGAAAemAEApFSJjwv6E1RmV56SMtOo+Q==
2019-11-08 10:57:34,195	[Th 45 Req 104479 SessId R0000e6cf-01-5dc53c0d] INFO RadiusServer.Radius - rlm_service: The request was categorized into service "802.1X Wireless test 802.1X Wireless" - 168:1728:D8F2CAA08967
2019-11-08 10:57:34,195	[Th 45 Req 104479 SessId R0000e6cf-01-5dc53c0d] INFO RadiusServer.Radius - rlm_sql: searching for user K28581@u-ssi.net in Local:localhost
2019-11-08 10:57:34,196	[Th 45 Req 104479 SessId R0000e6cf-01-5dc53c0d] INFO RadiusServer.Radius - reqst_update_state: Access-Challenge 168:88:D8F2CAA08967:AF0A7gDxAGMfmAEA1jjsxgZvJbIbPs/C0blyHQ==
2019-11-08 10:57:34,204	[Th 42 Req 104480 SessId R0000e6cf-01-5dc53c0d] INFO RadiusServer.Radius - rlm_service: The request was categorized into service "802.1X Wireless test 802.1X Wireless" - 183:972:D8F2CAA08967
2019-11-08 10:57:34,205	[Th 42 Req 104480 SessId R0000e6cf-01-5dc53c0d] INFO RadiusServer.Radius - rlm_sql: searching for user K28581@u-ssi.net in Local:localhost
2019-11-08 10:57:34,207	[Th 42 Req 104480 SessId R0000e6cf-01-5dc53c0d] INFO RadiusServer.Radius - chain-depth=3,
2019-11-08 10:57:34,207	[Th 42 Req 104480 SessId R0000e6cf-01-5dc53c0d] INFO RadiusServer.Radius - error=0
2019-11-08 10:57:34,207	[Th 42 Req 104480 SessId R0000e6cf-01-5dc53c0d] INFO RadiusServer.Radius - --> User-Name = K28581@u-ssi.net
2019-11-08 10:57:34,207	[Th 42 Req 104480 SessId R0000e6cf-01-5dc53c0d] INFO RadiusServer.Radius - --> subject = /C=DE/O=D-Trust GmbH/CN=D-TRUST Root CA 3 2013
2019-11-08 10:57:34,207	[Th 42 Req 104480 SessId R0000e6cf-01-5dc53c0d] INFO RadiusServer.Radius - --> issuer = /C=DE/O=D-Trust GmbH/CN=D-TRUST Root CA 3 2013
2019-11-08 10:57:34,207	[Th 42 Req 104480 SessId R0000e6cf-01-5dc53c0d] INFO RadiusServer.Radius - --> verify return:1
2019-11-08 10:57:34,207	[Th 42 Req 104480 SessId R0000e6cf-01-5dc53c0d] INFO RadiusServer.Radius - chain-depth=2,
2019-11-08 10:57:34,207	[Th 42 Req 104480 SessId R0000e6cf-01-5dc53c0d] INFO RadiusServer.Radius - error=0
2019-11-08 10:57:34,207	[Th 42 Req 104480 SessId R0000e6cf-01-5dc53c0d] INFO RadiusServer.Radius - --> User-Name = K28581@u-ssi.net
2019-11-08 10:57:34,207	[Th 42 Req 104480 SessId R0000e6cf-01-5dc53c0d] INFO RadiusServer.Radius - --> subject = /C=DE/O=D-Trust GmbH/CN=E.ON Group CA 2 2013
2019-11-08 10:57:34,207	[Th 42 Req 104480 SessId R0000e6cf-01-5dc53c0d] INFO RadiusServer.Radius - --> issuer = /C=DE/O=D-Trust GmbH/CN=D-TRUST Root CA 3 2013
2019-11-08 10:57:34,207	[Th 42 Req 104480 SessId R0000e6cf-01-5dc53c0d] INFO RadiusServer.Radius - --> verify return:1
2019-11-08 10:57:34,208	[Th 42 Req 104480 SessId R0000e6cf-01-5dc53c0d] INFO RadiusServer.Radius - chain-depth=1,
2019-11-08 10:57:34,208	[Th 42 Req 104480 SessId R0000e6cf-01-5dc53c0d] INFO RadiusServer.Radius - error=0
2019-11-08 10:57:34,208	[Th 42 Req 104480 SessId R0000e6cf-01-5dc53c0d] INFO RadiusServer.Radius - --> User-Name = K28581@u-ssi.net
2019-11-08 10:57:34,208	[Th 42 Req 104480 SessId R0000e6cf-01-5dc53c0d] INFO RadiusServer.Radius - --> subject = /C=DE/O=E.ON SE/OU=CA/CN=E.ON CA 2 2013 XXI
2019-11-08 10:57:34,208	[Th 42 Req 104480 SessId R0000e6cf-01-5dc53c0d] INFO RadiusServer.Radius - --> issuer = /C=DE/O=D-Trust GmbH/CN=E.ON Group CA 2 2013
2019-11-08 10:57:34,208	[Th 42 Req 104480 SessId R0000e6cf-01-5dc53c0d] INFO RadiusServer.Radius - --> verify return:1
2019-11-08 10:57:34,208	[Th 42 Req 104480 SessId R0000e6cf-01-5dc53c0d] INFO RadiusServer.Radius - chain-depth=0,
2019-11-08 10:57:34,208	[Th 42 Req 104480 SessId R0000e6cf-01-5dc53c0d] INFO RadiusServer.Radius - error=0
2019-11-08 10:57:34,208	[Th 42 Req 104480 SessId R0000e6cf-01-5dc53c0d] INFO RadiusServer.Radius - --> User-Name = K28581@u-ssi.net
2019-11-08 10:57:34,208	[Th 42 Req 104480 SessId R0000e6cf-01-5dc53c0d] INFO RadiusServer.Radius - --> subject = /C=DE/O=EON/OU=EEA/OU=USERS/CN=K28581
2019-11-08 10:57:34,208	[Th 42 Req 104480 SessId R0000e6cf-01-5dc53c0d] INFO RadiusServer.Radius - --> issuer = /C=DE/O=E.ON SE/OU=CA/CN=E.ON CA 2 2013 XXI
2019-11-08 10:57:34,208	[Th 42 Req 104480 SessId R0000e6cf-01-5dc53c0d] INFO RadiusServer.Radius - --> verify return:1
2019-11-08 10:57:34,210	[Th 42 Req 104480 SessId R0000e6cf-01-5dc53c0d] INFO RadiusServer.Radius - reqst_update_state: Access-Challenge 183:143:D8F2CAA08967:AB4A5QB9AH0gmAEAz7WoEtitLlA+N1l99t7d6Q==
2019-11-08 10:57:34,219	[Th 41 Req 104481 SessId R0000e6cf-01-5dc53c0d] INFO RadiusServer.Radius - rlm_service: The request was categorized into service "802.1X Wireless test 802.1X Wireless" - 199:232:D8F2CAA08967
2019-11-08 10:57:34,219	[Th 41 Req 104481 SessId R0000e6cf-01-5dc53c0d] INFO RadiusServer.Radius - rlm_sql: searching for user K28581@u-ssi.net in Local:localhost
2019-11-08 10:57:34,220	[Th 41 Req 104481 SessId R0000e6cf-01-5dc53c0d] INFO RadiusServer.Radius - rlm_eap_tls: Session established.
2019-11-08 10:57:34,221	[Th 41 Req 104481 SessId R0000e6cf-01-5dc53c0d] INFO RadiusServer.Radius - rlm_policy: Starting Policy Evaluation.
2019-11-08 10:57:34,221	[Th 41 Req 104481 SessId R0000e6cf-01-5dc53c0d] INFO RadiusServer.Radius - rlm_policy: Request does not have Authentication-Source-Name attribute
2019-11-08 10:57:34,224	[RequestHandler-1-0x7fee7d7eb700 r=psauto-1566804968-120955 h=239 r=R0000e6cf-01-5dc53c0d] ERROR Common.NadClientTable - getNadClient: Unknown NadClient 100.93.4.252
2019-11-08 10:57:34,224	[RequestHandler-1-0x7fee7d7eb700 r=psauto-1566804968-120955 h=239 r=R0000e6cf-01-5dc53c0d] ERROR Common.NadClientTable - getNadClient: Unknown NadClient 100.93.4.252
2019-11-08 10:57:34,224	[RequestHandler-1-0x7fee7d7eb700 r=psauto-1566804968-120955 h=239 r=R0000e6cf-01-5dc53c0d] INFO Common.EndpointTable - Returning NULL (EndpointPtr) for macAddr d8f2caa08967
2019-11-08 10:57:34,225	[RequestHandler-1-0x7fee7d7eb700 r=psauto-1566804968-120955 h=239 r=R0000e6cf-01-5dc53c0d] INFO Common.TagDefinitionCacheTable - No TagDefCacheMap could be found for instance id = 0 entity id = 29
2019-11-08 10:57:34,225	[RequestHandler-1-0x7fee7d7eb700 r=psauto-1566804968-120955 h=239 r=R0000e6cf-01-5dc53c0d] WARN Common.TagDefinitionCacheTable - Failed to build TagDefinitionMap. Unknown NadClient for Id=0
2019-11-08 10:57:34,225	[RequestHandler-1-0x7fee7d7eb700 r=psauto-1566804968-120955 h=239 r=R0000e6cf-01-5dc53c0d] INFO TAT.TagAttrHolderBuilder - No tags built for instanceId=0|entity=Device
2019-11-08 10:57:34,225	[RequestHandler-1-0x7fee7d7eb700 r=psauto-1566804968-120955 h=239 r=R0000e6cf-01-5dc53c0d] INFO TAT.AluTagAttrHolderBuilder - buildAttrHolder: Tags cannot be built for instanceId=0 (NULL AuthLocalUser)
2019-11-08 10:57:34,225	[RequestHandler-1-0x7fee7d7eb700 r=psauto-1566804968-120955 h=239 r=R0000e6cf-01-5dc53c0d] INFO TAT.GuTagAttrHolderBuilder - buildAttrHolder: Tags cannot be built for instanceId=0 (NULL GuestUser)
2019-11-08 10:57:34,225	[RequestHandler-1-0x7fee7d7eb700 r=psauto-1566804968-120955 h=239 r=R0000e6cf-01-5dc53c0d] INFO TAT.EndpointTagAttrHolderBuilder - buildAttrHolder: Tags cannot be built for instanceId=0 (NULL Endpoint)
2019-11-08 10:57:34,225	[RequestHandler-1-0x7fee7d7eb700 r=psauto-1566804968-120955 h=239 r=R0000e6cf-01-5dc53c0d] INFO TAT.OnboardTagAttrHolderBuilder - buildAttrHolder: Tags cannot be built for instanceId=0 (NULL Onboard Device User)
2019-11-08 10:57:34,225	[RequestHandler-1-0x7fee7d7eb700 h=1076482 c=R0000e6cf-01-5dc53c0d] INFO Core.PETaskScheduler - *** PE_TASK_SCHEDULE_RADIUS Started ***
2019-11-08 10:57:34,225	[RequestHandler-1-0x7fee7d7eb700 h=1076482 c=R0000e6cf-01-5dc53c0d] INFO Core.PETaskScheduler - ** Starting PETaskAuthSourceRestriction **
2019-11-08 10:57:34,225	[RequestHandler-1-0x7fee7d7eb700 h=1076482 c=R0000e6cf-01-5dc53c0d] INFO Core.PETaskScheduler - ** Starting PETaskRoleMapping **
2019-11-08 10:57:34,226	[RequestHandler-1-0x7fee7d7eb700 r=R0000e6cf-01-5dc53c0d h=1076482 c=R0000e6cf-01-5dc53c0d] INFO Core.PETaskScheduler - ** Completed PETaskAuthSourceRestriction **
2019-11-08 10:57:34,231	[RequestHandler-1-0x7fee7d7eb700 r=R0000e6cf-01-5dc53c0d h=1076484 c=R0000e6cf-01-5dc53c0d] INFO Core.PETaskRoleMapping - Roles: User Authenticated]
2019-11-08 10:57:34,231	[RequestHandler-1-0x7fee7d7eb700 r=R0000e6cf-01-5dc53c0d h=1076482 c=R0000e6cf-01-5dc53c0d] INFO Core.PETaskScheduler - ** Completed PETaskRoleMapping **
2019-11-08 10:57:34,231	[RequestHandler-1-0x7fee7d7eb700 r=R0000e6cf-01-5dc53c0d h=1076482 c=R0000e6cf-01-5dc53c0d] INFO Core.PETaskScheduler - ** Starting PETaskPolicyResult **
2019-11-08 10:57:34,231	[RequestHandler-1-0x7fee7d7eb700 r=R0000e6cf-01-5dc53c0d h=1076482 c=R0000e6cf-01-5dc53c0d] INFO Core.PETaskScheduler - ** Completed PETaskPolicyResult **
2019-11-08 10:57:34,231	[RequestHandler-1-0x7fee7d7eb700 r=R0000e6cf-01-5dc53c0d h=1076482 c=R0000e6cf-01-5dc53c0d] INFO Core.PETaskScheduler - ** Starting PETaskEnforcement **
2019-11-08 10:57:34,232	[RequestHandler-1-0x7fee7d7eb700 h=1076487 c=R0000e6cf-01-5dc53c0d] INFO Core.PETaskEnforcement - EnfProfiles: Allow Access Profile]
2019-11-08 10:57:34,232	[RequestHandler-1-0x7fee7d7eb700 r=R0000e6cf-01-5dc53c0d h=1076482 c=R0000e6cf-01-5dc53c0d] INFO Core.PETaskScheduler - ** Completed PETaskEnforcement **
2019-11-08 10:57:34,232	[RequestHandler-1-0x7fee7d7eb700 r=R0000e6cf-01-5dc53c0d h=1076482 c=R0000e6cf-01-5dc53c0d] INFO Core.PETaskScheduler - ** Starting PETaskRadiusEnfProfileBuilder **
2019-11-08 10:57:34,232	[RequestHandler-1-0x7fee7d7eb700 r=R0000e6cf-01-5dc53c0d h=1076482 c=R0000e6cf-01-5dc53c0d] INFO Core.PETaskScheduler - ** Starting PETaskRadiusCoAEnfProfileBuilder **
2019-11-08 10:57:34,232	[RequestHandler-1-0x7fee7d7eb700 r=R0000e6cf-01-5dc53c0d h=1076482 c=R0000e6cf-01-5dc53c0d] INFO Core.PETaskScheduler - ** Starting PETaskAppEnfProfileBuilder **
2019-11-08 10:57:34,232	[RequestHandler-1-0x7fee7d7eb700 r=R0000e6cf-01-5dc53c0d h=1076482 c=R0000e6cf-01-5dc53c0d] INFO Core.PETaskScheduler - ** Starting PETaskAgentEnfProfileBuilder **
2019-11-08 10:57:34,232	[RequestHandler-1-0x7fee7d7eb700 r=R0000e6cf-01-5dc53c0d h=1076482 c=R0000e6cf-01-5dc53c0d] INFO Core.PETaskScheduler - ** Starting PETaskPostAuthEnfProfileBuilder **
2019-11-08 10:57:34,232	[RequestHandler-1-0x7fee7d7eb700 r=R0000e6cf-01-5dc53c0d h=1076482 c=R0000e6cf-01-5dc53c0d] INFO Core.PETaskScheduler - ** Starting PETaskGenericEnfProfileBuilder **
2019-11-08 10:57:34,232	[RequestHandler-1-0x7fee7d7eb700 h=1076493 c=R0000e6cf-01-5dc53c0d] INFO Core.PETaskGenericEnfProfileBuilder - getApplicableProfiles: No App enforcement (Generic) profiles applicable for this device
2019-11-08 10:57:34,233	[RequestHandler-1-0x7fee7d7eb700 h=1076488 c=R0000e6cf-01-5dc53c0d] ERROR Common.NadClientTable - getNadClient: Unknown NadClient 100.93.4.252
2019-11-08 10:57:34,233	[RequestHandler-1-0x7fee7d7eb700 h=1076488 c=R0000e6cf-01-5dc53c0d] INFO Core.PETaskRadiusEnfProfileBuilder - EnfProfileAction=ACCEPT
2019-11-08 10:57:34,233	[RequestHandler-1-0x7fee7d7eb700 h=1076488 c=R0000e6cf-01-5dc53c0d] INFO Core.PETaskRadiusEnfProfileBuilder - Radius enfProfiles used: Allow Access Profile]
2019-11-08 10:57:34,233	[RequestHandler-1-0x7fee7d7eb700 h=1076488 c=R0000e6cf-01-5dc53c0d] INFO Core.EnfProfileComputer - getFinalSessionTimeout: sessionTimeout = 0
2019-11-08 10:57:34,233	[RequestHandler-1-0x7fee7d7eb700 h=1076488 c=R0000e6cf-01-5dc53c0d] WARN Core.SessionInfoOperations - Skip SessionInfoOperations::persistSessionInfo because of NULL NAD or NAD IP matching localhost
2019-11-08 10:57:34,233	[RequestHandler-1-0x7fee7d7eb700 r=R0000e6cf-01-5dc53c0d h=1076482 c=R0000e6cf-01-5dc53c0d] INFO Core.PETaskScheduler - ** Completed PETaskGenericEnfProfileBuilder **
2019-11-08 10:57:34,233	[RequestHandler-1-0x7fee7d7eb700 r=R0000e6cf-01-5dc53c0d h=1076482 c=R0000e6cf-01-5dc53c0d] INFO Core.PETaskScheduler - ** Completed PETaskAgentEnfProfileBuilder **
2019-11-08 10:57:34,233	[RequestHandler-1-0x7fee7d7eb700 r=R0000e6cf-01-5dc53c0d h=1076482 c=R0000e6cf-01-5dc53c0d] INFO Core.PETaskScheduler - ** Completed PETaskAppEnfProfileBuilder **
2019-11-08 10:57:34,233	[RequestHandler-1-0x7fee7d7eb700 r=R0000e6cf-01-5dc53c0d h=1076482 c=R0000e6cf-01-5dc53c0d] INFO Core.PETaskScheduler - ** Starting PETaskCliEnforcement **
2019-11-08 10:57:34,233	[RequestHandler-1-0x7fee7d7eb700 h=1076494 c=R0000e6cf-01-5dc53c0d] INFO Core.PETaskCliEnforcement - startHandler: No commands for CLI enforcement
2019-11-08 10:57:34,233	[RequestHandler-1-0x7fee7d7eb700 r=R0000e6cf-01-5dc53c0d h=1076482 c=R0000e6cf-01-5dc53c0d] INFO Core.PETaskScheduler - ** Completed PETaskRadiusEnfProfileBuilder **
2019-11-08 10:57:34,233	[RequestHandler-1-0x7fee7d7eb700 r=R0000e6cf-01-5dc53c0d h=1076492 c=R0000e6cf-01-5dc53c0d] WARN Core.PETaskPostAuthEnfProfileBuilder - handleHttpResponseEv: Fetching Radius attributes from battery failed, errMsg=
2019-11-08 10:57:34,233	[RequestHandler-1-0x7fee7d7eb700 r=R0000e6cf-01-5dc53c0d h=1076492 c=R0000e6cf-01-5dc53c0d] INFO Core.PETaskPostAuthEnfProfileBuilder - getApplicableProfiles: No Post auth enforcement profiles applicable for this device
2019-11-08 10:57:34,233	[RequestHandler-1-0x7fee7d7eb700 r=R0000e6cf-01-5dc53c0d h=1076482 c=R0000e6cf-01-5dc53c0d] INFO Core.PETaskScheduler - ** Completed PETaskCliEnforcement **
2019-11-08 10:57:34,234	[RequestHandler-1-0x7fee7d7eb700 r=R0000e6cf-01-5dc53c0d h=1076482 c=R0000e6cf-01-5dc53c0d] INFO Core.PETaskScheduler - ** Completed PETaskPostAuthEnfProfileBuilder **
2019-11-08 10:57:34,237	[RequestHandler-1-0x7fee7d7eb700 r=R0000e6cf-01-5dc53c0d h=1076489 c=R0000e6cf-01-5dc53c0d] WARN Core.PETaskRadiusCoAEnfProfileBuilder - handleHttpResponseEv: Fetching Radius attributes from battery failed, errMsg=
2019-11-08 10:57:34,238	[RequestHandler-1-0x7fee7d7eb700 r=R0000e6cf-01-5dc53c0d h=1076482 c=R0000e6cf-01-5dc53c0d] INFO Core.PETaskScheduler - ** Completed PETaskRadiusCoAEnfProfileBuilder **
2019-11-08 10:57:34,238	[RequestHandler-1-0x7fee7d7eb700 r=R0000e6cf-01-5dc53c0d h=1076482 c=R0000e6cf-01-5dc53c0d] INFO Core.PETaskScheduler - ** Starting PETaskAuthStatusInfo **
2019-11-08 10:57:34,238	[RequestHandler-1-0x7fee7d7eb700 r=R0000e6cf-01-5dc53c0d h=1076482 c=R0000e6cf-01-5dc53c0d] INFO Core.PETaskScheduler - ** Starting PETaskOutputPolicyRes **
2019-11-08 10:57:34,238	[RequestHandler-1-0x7fee7d7eb700 r=R0000e6cf-01-5dc53c0d h=1076482 c=R0000e6cf-01-5dc53c0d] INFO Core.PETaskScheduler - ** Starting PETaskSessionLog **
2019-11-08 10:57:34,241	[RequestHandler-1-0x7fee7d7eb700 h=1076496 c=R0000e6cf-01-5dc53c0d] INFO Core.XpipPolicyResHandler - populateResponseTlv: PETaskPostureOutput does not exist. Skip sending posture VAFs
2019-11-08 10:57:34,241	[RequestHandler-1-0x7fee7d7eb700 h=1076496 c=R0000e6cf-01-5dc53c0d] INFO Core.PolicyResCollector - getSohr: Failed to generate Sohr
2019-11-08 10:57:34,241	[RequestHandler-1-0x7fee7d7eb700 h=1076495 c=R0000e6cf-01-5dc53c0d] INFO Core.PolicyResCollector - getSohr: Failed to generate Sohr
2019-11-08 10:57:34,242	[Th 41 Req 104481 SessId R0000e6cf-01-5dc53c0d] INFO RadiusServer.Radius - Policy Evaluation time = 21 ms
2019-11-08 10:57:34,242	[Th 41 Req 104481 SessId R0000e6cf-01-5dc53c0d] INFO RadiusServer.Radius - rlm_policy: Received Accept Enforcement Profile
2019-11-08 10:57:34,242	[Th 41 Req 104481 SessId R0000e6cf-01-5dc53c0d] INFO RadiusServer.Radius - rlm_policy: Added Class attribute with value Class = 0x7b4e5effc8ba49f99906e8d211a6064cca0b0000000000005230303030653663662d30312d35646335336330640000000000000000000000
2019-11-08 10:57:34,242	[Th 41 Req 104481 SessId R0000e6cf-01-5dc53c0d] INFO RadiusServer.Radius - rlm_policy: Policy Server reply does not contain Posture-Validation-Response
2019-11-08 10:57:34,242	[Th 41 Req 104481 SessId R0000e6cf-01-5dc53c0d] INFO RadiusServer.Radius - Request processing time = 859 ms
2019-11-08 10:57:34,242	[RequestHandler-1-0x7fee7d7eb700 r=R0000e6cf-01-5dc53c0d h=1076482 c=R0000e6cf-01-5dc53c0d] INFO Core.PETaskScheduler - ** Completed PETaskSessionLog **
2019-11-08 10:57:34,242	[RequestHandler-1-0x7fee7d7eb700 r=R0000e6cf-01-5dc53c0d h=1076482 c=R0000e6cf-01-5dc53c0d] INFO Core.PETaskScheduler - ** Completed PETaskOutputPolicyRes **
2019-11-08 10:57:34,242	[RequestHandler-1-0x7fee7d7eb700 r=R0000e6cf-01-5dc53c0d h=1076482 c=R0000e6cf-01-5dc53c0d] INFO Core.PETaskScheduler - ** Completed PETaskAuthStatusInfo **
2019-11-08 10:57:34,242	[RequestHandler-1-0x7fee7d7eb700 r=R0000e6cf-01-5dc53c0d h=1076482 c=R0000e6cf-01-5dc53c0d] INFO Core.PETaskScheduler - *** PE_TASK_SCHEDULE_RADIUS Completed ***