| Time | Message |
|---|---|
| 2023-10-09 14:53:18,184 | [Th 40 Req 189 SessId R00000015-01-6523cd8e] INFO RadiusServer.Radius - rlm_service: Starting Service Categorization - 233:230:5414F3C72653 |
| 2023-10-09 14:53:18,184 | [Th 40 Req 189 SessId R00000015-01-6523cd8e] INFO RadiusServer.Radius - Service Categorization time = 8 ms |
| 2023-10-09 14:53:18,184 | [Th 40 Req 189 SessId R00000015-01-6523cd8e] INFO RadiusServer.Radius - rlm_service: The request has been categorized into service "ws_ArubaInstant_Wireless_802.1x" |
| 2023-10-09 14:53:18,184 | [Th 40 Req 189 SessId R00000015-01-6523cd8e] INFO RadiusServer.Radius - rlm_ldap: searching for user host/ATR-NDP-IT14.ndp.local in AD:ATDC01.ndp.local |
| 2023-10-09 14:53:18,184 | [Th 40 Req 189 SessId R00000015-01-6523cd8e] INFO RadiusServer.Radius - rlm_eap_peap: Initiate |
| 2023-10-09 14:53:18,184 | [Th 40 Req 189 SessId R00000015-01-6523cd8e] INFO RadiusServer.Radius - reqst_update_state: Access-Challenge 233:88:5414F3C72653:ABcAVQCfAPq9AAAA6Dba+RICsjqG/tw49eJ3XQ== |
| 2023-10-09 14:53:18,184 | [Th 41 Req 190 SessId R00000015-01-6523cd8e] INFO RadiusServer.Radius - rlm_service: The request was categorized into service "ws_ArubaInstant_Wireless_802.1x" - 234:412:5414F3C72653 |
| 2023-10-09 14:53:18,184 | [Th 41 Req 190 SessId R00000015-01-6523cd8e] INFO RadiusServer.Radius - rlm_ldap: searching for user host/ATR-NDP-IT14.ndp.local in AD:ATDC01.ndp.local |
| 2023-10-09 14:53:18,184 | [Th 41 Req 190 SessId R00000015-01-6523cd8e] INFO RadiusServer.Radius - TLS_accept:error in SSLv3 read client key exchange A |
| 2023-10-09 14:53:18,184 | [Th 41 Req 190 SessId R00000015-01-6523cd8e] INFO RadiusServer.Radius - TLS_accept:error in SSLv3 read client key exchange A |
| 2023-10-09 14:53:18,184 | [Th 41 Req 190 SessId R00000015-01-6523cd8e] INFO RadiusServer.Radius - reqst_update_state: Access-Challenge 234:1124:5414F3C72653:AAEAzQDwACa+AAAAL2PUfC07ihYMFnrdpAf1aA== |
| 2023-10-09 14:53:18,184 | [Th 42 Req 191 SessId R00000015-01-6523cd8e] INFO RadiusServer.Radius - rlm_service: The request was categorized into service "ws_ArubaInstant_Wireless_802.1x" - 235:246:5414F3C72653 |
| 2023-10-09 14:53:18,184 | [Th 42 Req 191 SessId R00000015-01-6523cd8e] INFO RadiusServer.Radius - rlm_ldap: searching for user host/ATR-NDP-IT14.ndp.local in AD:ATDC01.ndp.local |
| 2023-10-09 14:53:18,184 | [Th 42 Req 191 SessId R00000015-01-6523cd8e] INFO RadiusServer.Radius - reqst_update_state: Access-Challenge 235:1120:5414F3C72653:AAAA+QDKAKa/AAAAPuvGRwK99ZoWkhMgruq/8w== |
| 2023-10-09 14:53:18,184 | [Th 39 Req 192 SessId R00000015-01-6523cd8e] INFO RadiusServer.Radius - rlm_service: The request was categorized into service "ws_ArubaInstant_Wireless_802.1x" - 236:246:5414F3C72653 |
| 2023-10-09 14:53:18,184 | [Th 39 Req 192 SessId R00000015-01-6523cd8e] INFO RadiusServer.Radius - rlm_ldap: searching for user host/ATR-NDP-IT14.ndp.local in AD:ATDC01.ndp.local |
| 2023-10-09 14:53:18,184 | [Th 39 Req 192 SessId R00000015-01-6523cd8e] INFO RadiusServer.Radius - reqst_update_state: Access-Challenge 236:827:5414F3C72653:AH0A1QDLAOfAAAAAcusC2bCrKUIm0hG/C8ydrQ== |
| 2023-10-09 14:53:18,184 | [Th 40 Req 193 SessId R00000015-01-6523cd8e] INFO RadiusServer.Radius - rlm_service: The request was categorized into service "ws_ArubaInstant_Wireless_802.1x" - 237:376:5414F3C72653 |
| 2023-10-09 14:53:18,184 | [Th 40 Req 193 SessId R00000015-01-6523cd8e] INFO RadiusServer.Radius - rlm_ldap: searching for user host/ATR-NDP-IT14.ndp.local in AD:ATDC01.ndp.local |
| 2023-10-09 14:53:18,184 | [Th 40 Req 193 SessId R00000015-01-6523cd8e] INFO RadiusServer.Radius - reqst_update_state: Access-Challenge 237:139:5414F3C72653:AHgAugA4ACDBAAAAyjX8Q5kiqt750oq1EX9GnA== |
| 2023-10-09 14:53:18,192 | [RequestHandler-1-0x7fb79cfe7700 r=psauto-1696835602-43 h=223 r=R00000015-01-6523cd8e] INFO Core.ServiceReqHandler - Service classification result = ws_ArubaInstant_Wireless_802.1x |
| 2023-10-09 14:53:19,953 | [Th 41 Req 194 SessId R00000015-01-6523cd8e] INFO RadiusServer.Radius - rlm_service: The request was categorized into service "ws_ArubaInstant_Wireless_802.1x" - 238:246:5414F3C72653 |
| 2023-10-09 14:53:19,953 | [Th 41 Req 194 SessId R00000015-01-6523cd8e] INFO RadiusServer.Radius - rlm_ldap: searching for user host/ATR-NDP-IT14.ndp.local in AD:ATDC01.ndp.local |
| 2023-10-09 14:53:19,953 | [Th 41 Req 194 SessId R00000015-01-6523cd8e] INFO RadiusServer.Radius - rlm_eap_peap: Session established. |
| 2023-10-09 14:53:19,953 | [Th 41 Req 194 SessId R00000015-01-6523cd8e] INFO RadiusServer.Radius - reqst_update_state: Access-Challenge 238:122:5414F3C72653:ABwAygDKAGzCAAAAqvoceCCW8A4ASiPOKsyM/Q== |
| 2023-10-09 14:53:19,953 | [Th 42 Req 195 SessId R00000015-01-6523cd8e] INFO RadiusServer.Radius - rlm_service: The request was categorized into service "ws_ArubaInstant_Wireless_802.1x" - 239:303:5414F3C72653 |
| 2023-10-09 14:53:19,953 | [Th 42 Req 195 SessId R00000015-01-6523cd8e] INFO RadiusServer.Radius - rlm_ldap: searching for user host/ATR-NDP-IT14.ndp.local in AD:ATDC01.ndp.local |
| 2023-10-09 14:53:19,953 | [Th 42 Req 195 SessId R00000015-01-6523cd8e] INFO RadiusServer.Radius - rlm_ldap: searching for user host/ATR-NDP-IT14.ndp.local in AD:ATDC01.ndp.local |
| 2023-10-09 14:53:19,953 | [Th 42 Req 195 SessId R00000015-01-6523cd8e] INFO RadiusServer.Radius - rlm_eap_mschapv2: Issuing Challenge |
| 2023-10-09 14:53:19,953 | [Th 42 Req 195 SessId R00000015-01-6523cd8e] INFO RadiusServer.Radius - reqst_update_state: Access-Challenge 239:166:5414F3C72653:AC4AJgDAAHnDAAAA36DXYDHsmYTK//Gn0nII9A== |
| 2023-10-09 14:53:19,953 | [Th 39 Req 196 SessId R00000015-01-6523cd8e] INFO RadiusServer.Radius - rlm_service: The request was categorized into service "ws_ArubaInstant_Wireless_802.1x" - 240:357:5414F3C72653 |
| 2023-10-09 14:53:19,953 | [Th 39 Req 196 SessId R00000015-01-6523cd8e] INFO RadiusServer.Radius - rlm_ldap: searching for user host/ATR-NDP-IT14.ndp.local in AD:ATDC01.ndp.local |
| 2023-10-09 14:53:19,953 | [Th 39 Req 196 SessId R00000015-01-6523cd8e] INFO RadiusServer.Radius - rlm_service: The request was categorized into service "ws_ArubaInstant_Wireless_802.1x" - 196:0:5414F3C72653 |
| 2023-10-09 14:53:19,953 | [Th 39 Req 196 SessId R00000015-01-6523cd8e] INFO RadiusServer.Radius - rlm_ldap: searching for user host/ATR-NDP-IT14.ndp.local in AD:ATDC01.ndp.local |
| 2023-10-09 14:53:19,953 | [Th 39 Req 196 SessId R00000015-01-6523cd8e] INFO RadiusServer.Radius - rlm_eap_mschapv2: Received MSCHAPv2 Response from client |
| 2023-10-09 14:53:19,953 | [Th 39 Req 196 SessId R00000015-01-6523cd8e] INFO RadiusServer.Radius - rlm_mschap: MSCHAPv2 username used for challenge computation host/ATR-NDP-IT14.ndp.local |
| 2023-10-09 14:53:19,953 | [Th 39 Req 196 SessId R00000015-01-6523cd8e] ERROR RadiusServer.Radius - rlm_mschap: FAILED: No NT/LM-Password. Cannot perform authentication. |
| 2023-10-09 14:53:19,953 | [Th 39 Req 196 SessId R00000015-01-6523cd8e] INFO RadiusServer.Radius - MS-Chap User Authentication time = 0 ms |
| 2023-10-09 14:53:19,953 | [Th 39 Req 196 SessId R00000015-01-6523cd8e] INFO RadiusServer.Radius - rlm_eap_mschapv2: Sending MSCHAPv2 Failure |
| 2023-10-09 14:53:19,953 | [Th 39 Req 196 SessId R00000015-01-6523cd8e] INFO RadiusServer.Radius - rlm_policy: Starting Policy Evaluation. |
| 2023-10-09 14:53:19,953 | [Th 39 Req 196 SessId R00000015-01-6523cd8e] INFO RadiusServer.Radius - Policy Evaluation time = 11 ms |
| 2023-10-09 14:53:19,953 | [Th 39 Req 196 SessId R00000015-01-6523cd8e] INFO RadiusServer.Radius - rlm_policy: Received Accept Enforcement Profile |
| 2023-10-09 14:53:19,953 | [Th 39 Req 196 SessId R00000015-01-6523cd8e] INFO RadiusServer.Radius - rlm_policy: Policy Server reply does not contain Posture-Validation-Response |
| 2023-10-09 14:53:19,953 | [Th 39 Req 196 SessId R00000015-01-6523cd8e] INFO RadiusServer.Radius - reqst_update_state: Access-Challenge 240:128:5414F3C72653:ANUALQDZAK3EAAAAZ+FvRIZXZB8ce0MptBnjGw== |
| 2023-10-09 14:53:19,953 | [Th 40 Req 197 SessId R00000015-01-6523cd8e] INFO RadiusServer.Radius - rlm_service: The request was categorized into service "ws_ArubaInstant_Wireless_802.1x" - 241:286:5414F3C72653 |
| 2023-10-09 14:53:19,953 | [Th 40 Req 197 SessId R00000015-01-6523cd8e] INFO RadiusServer.Radius - rlm_ldap: searching for user host/ATR-NDP-IT14.ndp.local in AD:ATDC01.ndp.local |
| 2023-10-09 14:53:19,953 | [Th 40 Req 197 SessId R00000015-01-6523cd8e] INFO RadiusServer.Radius - rlm_policy: Bypassing Policy Evaluation. |
| 2023-10-09 14:53:19,953 | [Th 40 Req 197 SessId R00000015-01-6523cd8e] INFO RadiusServer.Radius - Request processing time = 1808 ms |
| 2023-10-09 14:53:19,979 | [RequestHandler-1-0x7fb79cfe7700 r=psauto-1696835602-44 h=239 r=R00000015-01-6523cd8e] INFO Common.EndpointTable - Returning EndpointSPtr for macAddr 5414f3c72653 |
| 2023-10-09 14:53:19,979 | [RequestHandler-1-0x7fb79cfe7700 r=psauto-1696835602-44 h=239 r=R00000015-01-6523cd8e] INFO Common.TagDefinitionCacheTable - No InstanceTagDefCacheMap found for instance id = 3001 entity id = 29 |
| 2023-10-09 14:53:19,979 | [RequestHandler-1-0x7fb79cfe7700 r=psauto-1696835602-44 h=239 r=R00000015-01-6523cd8e] INFO Common.TagDefinitionCacheTable - Building the TagDefMapTable for NAD instance=3001 |
| 2023-10-09 14:53:19,979 | [RequestHandler-1-0x7fb79cfe7700 r=psauto-1696835602-44 h=239 r=R00000015-01-6523cd8e] INFO Common.TagDefinitionCacheTable - Built 0 tag(s) for NAD instanceId=3001|entityId=29 |
| 2023-10-09 14:53:19,979 | [RequestHandler-1-0x7fb79cfe7700 r=psauto-1696835602-44 h=239 r=R00000015-01-6523cd8e] INFO TAT.TagAttrHolderBuilder - No tags built for instanceId=3001|entity=Device |
| 2023-10-09 14:53:19,979 | [RequestHandler-1-0x7fb79cfe7700 r=psauto-1696835602-44 h=239 r=R00000015-01-6523cd8e] INFO TAT.AluTagAttrHolderBuilder - buildAttrHolder: Tags cannot be built for instanceId=0 (NULL AuthLocalUser) |
| 2023-10-09 14:53:19,979 | [RequestHandler-1-0x7fb79cfe7700 r=psauto-1696835602-44 h=239 r=R00000015-01-6523cd8e] INFO TAT.GuTagAttrHolderBuilder - buildAttrHolder: Tags cannot be built for instanceId=0 (NULL GuestUser) |
| 2023-10-09 14:53:19,979 | [RequestHandler-1-0x7fb79cfe7700 r=psauto-1696835602-44 h=239 r=R00000015-01-6523cd8e] INFO Common.TagDefinitionCacheTable - No InstanceTagDefCacheMap found for instance id = 3005 entity id = 72 |
| 2023-10-09 14:53:19,979 | [RequestHandler-1-0x7fb79cfe7700 r=psauto-1696835602-44 h=239 r=R00000015-01-6523cd8e] INFO Common.TagDefinitionCacheTable - Building the TagDefMapTable for Endpoint instance=3005 |
| 2023-10-09 14:53:19,979 | [RequestHandler-1-0x7fb79cfe7700 r=psauto-1696835602-44 h=239 r=R00000015-01-6523cd8e] INFO Common.TagDefinitionCacheTable - Built 0 tag(s) for instanceId=3005|entityId=72|entityName=Endpoint |
| 2023-10-09 14:53:19,979 | [RequestHandler-1-0x7fb79cfe7700 r=psauto-1696835602-44 h=239 r=R00000015-01-6523cd8e] INFO TAT.TagAttrHolderBuilder - No tags built for instanceId=3005|entity=Endpoint |
| 2023-10-09 14:53:19,979 | [RequestHandler-1-0x7fb79cfe7700 r=psauto-1696835602-44 h=239 r=R00000015-01-6523cd8e] INFO TAT.OnboardTagAttrHolderBuilder - buildAttrHolder: Tags cannot be built for instanceId=0 (NULL Onboard Device User) |
| 2023-10-09 14:53:19,979 | [RequestHandler-1-0x7fb79cfe7700 h=692 c=R00000015-01-6523cd8e] INFO Core.PETaskScheduler - *** PE_TASK_SCHEDULE_RADIUS Started *** |
| 2023-10-09 14:53:19,980 | [RequestHandler-1-0x7fb79cfe7700 h=692 c=R00000015-01-6523cd8e] INFO Core.PETaskScheduler - ** Starting PETaskAuthSourceRestriction ** |
| 2023-10-09 14:53:19,980 | [RequestHandler-1-0x7fb79cfe7700 h=692 c=R00000015-01-6523cd8e] INFO Core.PETaskScheduler - ** Starting PETaskRoleMapping ** |
| 2023-10-09 14:53:19,980 | [RequestHandler-1-0x7fb79cfe7700 r=R00000015-01-6523cd8e h=692 c=R00000015-01-6523cd8e] INFO Core.PETaskScheduler - ** Completed PETaskAuthSourceRestriction ** |
| 2023-10-09 14:53:19,980 | [RequestHandler-1-0x7fb79cfe7700 h=694 c=R00000015-01-6523cd8e] INFO Core.PETaskRoleMapping - Roles: |
| 2023-10-09 14:53:19,980 | [RequestHandler-1-0x7fb79cfe7700 r=R00000015-01-6523cd8e h=692 c=R00000015-01-6523cd8e] INFO Core.PETaskScheduler - ** Completed PETaskRoleMapping ** |
| 2023-10-09 14:53:19,980 | [RequestHandler-1-0x7fb79cfe7700 r=R00000015-01-6523cd8e h=692 c=R00000015-01-6523cd8e] INFO Core.PETaskScheduler - ** Starting PETaskPolicyResult ** |
| 2023-10-09 14:53:19,980 | [RequestHandler-1-0x7fb79cfe7700 r=R00000015-01-6523cd8e h=692 c=R00000015-01-6523cd8e] INFO Core.PETaskScheduler - ** Completed PETaskPolicyResult ** |
| 2023-10-09 14:53:19,980 | [RequestHandler-1-0x7fb79cfe7700 r=R00000015-01-6523cd8e h=692 c=R00000015-01-6523cd8e] INFO Core.PETaskScheduler - ** Starting PETaskEnforcement ** |
| 2023-10-09 14:53:19,981 | [RequestHandler-1-0x7fb79cfe7700 h=697 c=R00000015-01-6523cd8e] INFO Core.PETaskEnforcement - EnfProfiles: Allow Access Profile] |
| 2023-10-09 14:53:19,981 | [RequestHandler-1-0x7fb79cfe7700 r=R00000015-01-6523cd8e h=692 c=R00000015-01-6523cd8e] INFO Core.PETaskScheduler - ** Completed PETaskEnforcement ** |
| 2023-10-09 14:53:19,981 | [RequestHandler-1-0x7fb79cfe7700 r=R00000015-01-6523cd8e h=692 c=R00000015-01-6523cd8e] INFO Core.PETaskScheduler - ** Starting PETaskRadiusEnfProfileBuilder ** |
| 2023-10-09 14:53:19,981 | [RequestHandler-1-0x7fb79cfe7700 r=R00000015-01-6523cd8e h=692 c=R00000015-01-6523cd8e] INFO Core.PETaskScheduler - ** Starting PETaskRadiusCoAEnfProfileBuilder ** |
| 2023-10-09 14:53:19,981 | [RequestHandler-1-0x7fb79cfe7700 r=R00000015-01-6523cd8e h=692 c=R00000015-01-6523cd8e] INFO Core.PETaskScheduler - ** Starting PETaskAppEnfProfileBuilder ** |
| 2023-10-09 14:53:19,981 | [RequestHandler-1-0x7fb79cfe7700 r=R00000015-01-6523cd8e h=692 c=R00000015-01-6523cd8e] INFO Core.PETaskScheduler - ** Starting PETaskAgentEnfProfileBuilder ** |
| 2023-10-09 14:53:19,981 | [RequestHandler-1-0x7fb79cfe7700 r=R00000015-01-6523cd8e h=692 c=R00000015-01-6523cd8e] INFO Core.PETaskScheduler - ** Starting PETaskPostAuthEnfProfileBuilder ** |
| 2023-10-09 14:53:19,981 | [RequestHandler-1-0x7fb79cfe7700 r=R00000015-01-6523cd8e h=692 c=R00000015-01-6523cd8e] INFO Core.PETaskScheduler - ** Starting PETaskGenericEnfProfileBuilder ** |
| 2023-10-09 14:53:19,981 | [RequestHandler-1-0x7fb79cfe7700 h=703 c=R00000015-01-6523cd8e] INFO Core.PETaskGenericEnfProfileBuilder - getApplicableProfiles: No App enforcement (Generic) profiles applicable for this device |
| 2023-10-09 14:53:19,982 | [RequestHandler-1-0x7fb79cfe7700 h=698 c=R00000015-01-6523cd8e] INFO Core.PETaskRadiusEnfProfileBuilder - EnfProfileAction=ACCEPT |
| 2023-10-09 14:53:19,982 | [RequestHandler-1-0x7fb79cfe7700 h=698 c=R00000015-01-6523cd8e] INFO Core.PETaskRadiusEnfProfileBuilder - Radius enfProfiles used: Allow Access Profile] |
| 2023-10-09 14:53:19,982 | [RequestHandler-1-0x7fb79cfe7700 h=698 c=R00000015-01-6523cd8e] INFO Core.EnfProfileComputer - getFinalSessionTimeout: sessionTimeout = 0 |
| 2023-10-09 14:53:19,982 | [RequestHandler-1-0x7fb79cfe7700 r=R00000015-01-6523cd8e h=692 c=R00000015-01-6523cd8e] INFO Core.PETaskScheduler - ** Completed PETaskGenericEnfProfileBuilder ** |
| 2023-10-09 14:53:19,982 | [RequestHandler-1-0x7fb79cfe7700 r=R00000015-01-6523cd8e h=692 c=R00000015-01-6523cd8e] INFO Core.PETaskScheduler - ** Completed PETaskAgentEnfProfileBuilder ** |
| 2023-10-09 14:53:19,982 | [RequestHandler-1-0x7fb79cfe7700 r=R00000015-01-6523cd8e h=692 c=R00000015-01-6523cd8e] INFO Core.PETaskScheduler - ** Completed PETaskAppEnfProfileBuilder ** |
| 2023-10-09 14:53:19,982 | [RequestHandler-1-0x7fb79cfe7700 r=R00000015-01-6523cd8e h=692 c=R00000015-01-6523cd8e] INFO Core.PETaskScheduler - ** Starting PETaskCliEnforcement ** |
| 2023-10-09 14:53:19,982 | [RequestHandler-1-0x7fb79cfe7700 h=704 c=R00000015-01-6523cd8e] INFO Core.PETaskCliEnforcement - startHandler: No commands for CLI enforcement |
| 2023-10-09 14:53:19,982 | [RequestHandler-1-0x7fb79cfe7700 r=R00000015-01-6523cd8e h=692 c=R00000015-01-6523cd8e] INFO Core.PETaskScheduler - ** Completed PETaskRadiusEnfProfileBuilder ** |
| 2023-10-09 14:53:19,982 | [RequestHandler-1-0x7fb79cfe7700 r=R00000015-01-6523cd8e h=692 c=R00000015-01-6523cd8e] INFO Core.PETaskScheduler - ** Completed PETaskCliEnforcement ** |
| 2023-10-09 14:53:19,982 | [RequestHandler-1-0x7fb79cfe7700 r=R00000015-01-6523cd8e h=699 c=R00000015-01-6523cd8e] WARN Core.PETaskRadiusCoAEnfProfileBuilder - handleHttpResponseEv: Fetching Radius attributes from battery failed, errMsg= |
| 2023-10-09 14:53:19,982 | [RequestHandler-1-0x7fb79cfe7700 r=R00000015-01-6523cd8e h=692 c=R00000015-01-6523cd8e] INFO Core.PETaskScheduler - ** Completed PETaskRadiusCoAEnfProfileBuilder ** |
| 2023-10-09 14:53:19,982 | [RequestHandler-1-0x7fb79cfe7700 r=R00000015-01-6523cd8e h=702 c=R00000015-01-6523cd8e] WARN Core.PETaskPostAuthEnfProfileBuilder - handleHttpResponseEv: Fetching Radius attributes from battery failed, errMsg= |
| 2023-10-09 14:53:19,983 | [RequestHandler-1-0x7fb79cfe7700 r=R00000015-01-6523cd8e h=702 c=R00000015-01-6523cd8e] INFO Core.PETaskPostAuthEnfProfileBuilder - getApplicableProfiles: No Post auth enforcement profiles applicable for this device |
| 2023-10-09 14:53:19,983 | [RequestHandler-1-0x7fb79cfe7700 r=R00000015-01-6523cd8e h=692 c=R00000015-01-6523cd8e] INFO Core.PETaskScheduler - ** Completed PETaskPostAuthEnfProfileBuilder ** |
| 2023-10-09 14:53:19,983 | [RequestHandler-1-0x7fb79cfe7700 r=R00000015-01-6523cd8e h=692 c=R00000015-01-6523cd8e] INFO Core.PETaskScheduler - ** Starting PETaskAuthStatusInfo ** |
| 2023-10-09 14:53:19,983 | [RequestHandler-1-0x7fb79cfe7700 r=R00000015-01-6523cd8e h=692 c=R00000015-01-6523cd8e] INFO Core.PETaskScheduler - ** Starting PETaskOutputPolicyRes ** |
| 2023-10-09 14:53:19,983 | [RequestHandler-1-0x7fb79cfe7700 r=R00000015-01-6523cd8e h=692 c=R00000015-01-6523cd8e] INFO Core.PETaskScheduler - ** Starting PETaskSessionLog ** |
| 2023-10-09 14:53:19,985 | [RequestHandler-1-0x7fb79cfe7700 h=706 c=R00000015-01-6523cd8e] INFO Core.XpipPolicyResHandler - populateResponseTlv: PETaskPostureOutput does not exist. Skip sending posture VAFs |
| 2023-10-09 14:53:19,985 | [RequestHandler-1-0x7fb79cfe7700 h=706 c=R00000015-01-6523cd8e] INFO Core.PolicyResCollector - getSohr: Failed to generate Sohr |
| 2023-10-09 14:53:19,985 | [RequestHandler-1-0x7fb79cfe7700 h=705 c=R00000015-01-6523cd8e] INFO Core.PolicyResCollector - getSohr: Failed to generate Sohr |
| 2023-10-09 14:53:19,985 | [RequestHandler-1-0x7fb79cfe7700 r=R00000015-01-6523cd8e h=692 c=R00000015-01-6523cd8e] INFO Core.PETaskScheduler - ** Completed PETaskSessionLog ** |
| 2023-10-09 14:53:19,985 | [RequestHandler-1-0x7fb79cfe7700 r=R00000015-01-6523cd8e h=692 c=R00000015-01-6523cd8e] INFO Core.PETaskScheduler - ** Completed PETaskOutputPolicyRes ** |
| 2023-10-09 14:53:19,985 | [RequestHandler-1-0x7fb79cfe7700 r=R00000015-01-6523cd8e h=692 c=R00000015-01-6523cd8e] INFO Core.PETaskScheduler - ** Completed PETaskAuthStatusInfo ** |
| 2023-10-09 14:53:19,985 | [RequestHandler-1-0x7fb79cfe7700 r=R00000015-01-6523cd8e h=692 c=R00000015-01-6523cd8e] INFO Core.PETaskScheduler - *** PE_TASK_SCHEDULE_RADIUS Completed *** |