Time	Message
2024-04-01 11:08:09,385	[Th 40 Req 118248 SessId R00000760-01-660a6b69] INFO RadiusServer.Radius - rlm_service: Starting Service Categorization - 87:303:34-8A-12-C7-A1-34
2024-04-01 11:08:09,385	[Th 40 Req 118248 SessId R00000760-01-660a6b69] INFO RadiusServer.Radius - Service Categorization time = 8 ms
2024-04-01 11:08:09,385	[Th 40 Req 118248 SessId R00000760-01-660a6b69] INFO RadiusServer.Radius - rlm_service: The request has been categorized into service "MEDO-AP Wired Aruba Access Point DYN VLAN 802.1X"
2024-04-01 11:08:09,385	[Th 40 Req 118248 SessId R00000760-01-660a6b69] INFO RadiusServer.Radius - rlm_sql: searching for user MEDOA-WH-20 in Local:localhost
2024-04-01 11:08:09,385	[Th 40 Req 118248 SessId R00000760-01-660a6b69] INFO RadiusServer.Radius - rlm_sql: found user MEDOA-WH-20 in Local:localhost
2024-04-01 11:08:09,385	[Th 40 Req 118248 SessId R00000760-01-660a6b69] INFO RadiusServer.Radius - SQL User lookup time = 1 ms
2024-04-01 11:08:09,385	[Th 40 Req 118248 SessId R00000760-01-660a6b69] INFO RadiusServer.Radius - rlm_eap_tls: Initiate
2024-04-01 11:08:09,385	[Th 40 Req 118248 SessId R00000760-01-660a6b69] INFO RadiusServer.Radius - reqst_update_state: Access-Challenge 87:88:34-8A-12-C7-A1-34:AN0ATAC/AC7ozQEAwvtvfga73Glv0IQJr1sBkg==
2024-04-01 11:08:09,385	[Th 37 Req 118249 SessId R00000760-01-660a6b69] INFO RadiusServer.Radius - rlm_service: The request was categorized into service "MEDO-AP Wired Aruba Access Point DYN VLAN 802.1X" - 95:419:34-8A-12-C7-A1-34
2024-04-01 11:08:09,385	[Th 37 Req 118249 SessId R00000760-01-660a6b69] INFO RadiusServer.Radius - TLS_accept:error in SSLv3/TLS write server done
2024-04-01 11:08:09,385	[Th 37 Req 118249 SessId R00000760-01-660a6b69] INFO RadiusServer.Radius - reqst_update_state: Access-Challenge 95:1124:34-8A-12-C7-A1-34:ANcA7wDEAF/pzQEA+nb5RsJj546pdPMj8BxmwQ==
2024-04-01 11:08:09,385	[Th 44 Req 118250 SessId R00000760-01-660a6b69] INFO RadiusServer.Radius - rlm_service: The request was categorized into service "MEDO-AP Wired Aruba Access Point DYN VLAN 802.1X" - 103:335:34-8A-12-C7-A1-34
2024-04-01 11:08:09,385	[Th 44 Req 118250 SessId R00000760-01-660a6b69] INFO RadiusServer.Radius - reqst_update_state: Access-Challenge 103:1120:34-8A-12-C7-A1-34:AIoAzgB7AIvqzQEALeNK+33FDf2u3+ObFgu40A==
2024-04-01 11:08:09,385	[Th 38 Req 118251 SessId R00000760-01-660a6b69] INFO RadiusServer.Radius - rlm_service: The request was categorized into service "MEDO-AP Wired Aruba Access Point DYN VLAN 802.1X" - 111:335:34-8A-12-C7-A1-34
2024-04-01 11:08:09,385	[Th 38 Req 118251 SessId R00000760-01-660a6b69] INFO RadiusServer.Radius - reqst_update_state: Access-Challenge 111:1120:34-8A-12-C7-A1-34:AC0A9gAFAF/rzQEAC250uYRJz5/4y3UXYRaUoQ==
2024-04-01 11:08:09,385	[Th 42 Req 118252 SessId R00000760-01-660a6b69] INFO RadiusServer.Radius - rlm_service: The request was categorized into service "MEDO-AP Wired Aruba Access Point DYN VLAN 802.1X" - 119:335:34-8A-12-C7-A1-34
2024-04-01 11:08:09,385	[Th 42 Req 118252 SessId R00000760-01-660a6b69] INFO RadiusServer.Radius - reqst_update_state: Access-Challenge 119:1120:34-8A-12-C7-A1-34:ANcANwDUAD/szQEAc7CoBiYlDfO+8Vd+ZtRgVw==
2024-04-01 11:08:09,385	[Th 45 Req 118253 SessId R00000760-01-660a6b69] INFO RadiusServer.Radius - rlm_service: The request was categorized into service "MEDO-AP Wired Aruba Access Point DYN VLAN 802.1X" - 127:335:34-8A-12-C7-A1-34
2024-04-01 11:08:09,385	[Th 45 Req 118253 SessId R00000760-01-660a6b69] INFO RadiusServer.Radius - reqst_update_state: Access-Challenge 127:265:34-8A-12-C7-A1-34:ACUAPAA3AEjtzQEAAfW53L2qH9yE2NYIrHn4Ew==
2024-04-01 11:08:09,385	[Th 46 Req 118254 SessId R00000760-01-660a6b69] INFO RadiusServer.Radius - rlm_service: The request was categorized into service "MEDO-AP Wired Aruba Access Point DYN VLAN 802.1X" - 135:335:34-8A-12-C7-A1-34
2024-04-01 11:08:09,385	[Th 46 Req 118254 SessId R00000760-01-660a6b69] INFO RadiusServer.Radius - reqst_update_state: Access-Challenge 135:92:34-8A-12-C7-A1-34:ANgAiABXAEvuzQEA/RO3EgZsq1iKeFANiHX/yA==
2024-04-01 11:08:09,385	[Th 43 Req 118255 SessId R00000760-01-660a6b69] INFO RadiusServer.Radius - rlm_service: The request was categorized into service "MEDO-AP Wired Aruba Access Point DYN VLAN 802.1X" - 143:335:34-8A-12-C7-A1-34
2024-04-01 11:08:09,385	[Th 43 Req 118255 SessId R00000760-01-660a6b69] INFO RadiusServer.Radius - reqst_update_state: Access-Challenge 143:92:34-8A-12-C7-A1-34:AHkABACyAGLvzQEAMHa/ZkBNav6ic9PQ26x+oA==
2024-04-01 11:08:09,640	[RequestHandler-1-0x7f2883fff700 r=psauto-1711690116-63695 h=223 r=R00000760-01-660a6b69] INFO Core.ServiceReqHandler - Service classification result = MEDO-AP Wired Aruba Access Point DYN VLAN 802.1X
2024-04-01 11:08:57,178	[main SessId R00000760-01-660a6b69] ERROR RadiusServer.Radius - reqst_clean_list: Deleting request sessid - R00000760-01-660a6b69, state - AHkABACyAGLvzQEAMHa/ZkBNav6ic9PQ26x+oA=
2024-04-01 11:08:57,178	[main SessId R00000760-01-660a6b69] ERROR RadiusServer.Radius - reqst_clean_list: Packet 87:303:88:34-8A-12-C7-A1-34 recv 1711958889.633015 - resp 1711958889.643030
2024-04-01 11:08:57,178	[main SessId R00000760-01-660a6b69] ERROR RadiusServer.Radius - reqst_clean_list: Packet 95:419:1124:34-8A-12-C7-A1-34 recv 1711958889.653358 - resp 1711958889.654464
2024-04-01 11:08:57,178	[main SessId R00000760-01-660a6b69] ERROR RadiusServer.Radius - reqst_clean_list: Packet 103:335:1120:34-8A-12-C7-A1-34 recv 1711958889.663000 - resp 1711958889.663822
2024-04-01 11:08:57,178	[main SessId R00000760-01-660a6b69] ERROR RadiusServer.Radius - reqst_clean_list: Packet 111:335:1120:34-8A-12-C7-A1-34 recv 1711958889.670907 - resp 1711958889.672412
2024-04-01 11:08:57,178	[main SessId R00000760-01-660a6b69] ERROR RadiusServer.Radius - reqst_clean_list: Packet 119:335:1120:34-8A-12-C7-A1-34 recv 1711958889.680291 - resp 1711958889.681287
2024-04-01 11:08:57,178	[main SessId R00000760-01-660a6b69] ERROR RadiusServer.Radius - reqst_clean_list: Packet 127:335:265:34-8A-12-C7-A1-34 recv 1711958889.690107 - resp 1711958889.690750
2024-04-01 11:08:57,178	[main SessId R00000760-01-660a6b69] ERROR RadiusServer.Radius - reqst_clean_list: Packet 135:335:92:34-8A-12-C7-A1-34 recv 1711958889.715251 - resp 1711958889.715826
2024-04-01 11:08:57,178	[main SessId R00000760-01-660a6b69] ERROR RadiusServer.Radius - reqst_clean_list: Packet 143:335:92:34-8A-12-C7-A1-34 recv 1711958889.726122 - resp 1711958889.726673
2024-04-01 11:08:57,178	[main SessId R00000760-01-660a6b69] INFO RadiusServer.Radius - Last EAP Packet Processing Time = 0 ms
2024-04-01 11:08:57,178	[main SessId R00000760-01-660a6b69] INFO RadiusServer.Radius - rlm_policy: Starting Policy Evaluation.
2024-04-01 11:08:57,178	[main SessId R00000760-01-660a6b69] INFO RadiusServer.Radius - Policy Evaluation time = 19 ms
2024-04-01 11:08:57,178	[main SessId R00000760-01-660a6b69] INFO RadiusServer.Radius - rlm_policy: Received Accept Enforcement Profile
2024-04-01 11:08:57,178	[main SessId R00000760-01-660a6b69] INFO RadiusServer.Radius - rlm_policy: Policy Server reply does not contain Posture-Validation-Response
2024-04-01 11:08:57,182	[RequestHandler-1-0x7f2883fff700 r=psauto-1711690116-63708 h=239 r=R00000760-01-660a6b69] INFO Common.EndpointTable - Returning EndpointSPtr for macAddr 348a12c7a134
2024-04-01 11:08:57,182	[RequestHandler-1-0x7f2883fff700 r=psauto-1711690116-63708 h=239 r=R00000760-01-660a6b69] INFO Common.TagDefinitionCacheTable - No InstanceTagDefCacheMap found for instance id = 3031 entity id = 29
2024-04-01 11:08:57,182	[RequestHandler-1-0x7f2883fff700 r=psauto-1711690116-63708 h=239 r=R00000760-01-660a6b69] INFO Common.TagDefinitionCacheTable - Building the TagDefMapTable for NAD instance=3031
2024-04-01 11:08:57,182	[RequestHandler-1-0x7f2883fff700 r=psauto-1711690116-63708 h=239 r=R00000760-01-660a6b69] INFO Common.TagDefinitionCacheTable - Built 0 tag(s) for NAD instanceId=3031|entityId=29
2024-04-01 11:08:57,182	[RequestHandler-1-0x7f2883fff700 r=psauto-1711690116-63708 h=239 r=R00000760-01-660a6b69] INFO TAT.TagAttrHolderBuilder - No tags built for instanceId=3031|entity=Device
2024-04-01 11:08:57,182	[RequestHandler-1-0x7f2883fff700 r=psauto-1711690116-63708 h=239 r=R00000760-01-660a6b69] INFO TAT.AluTagAttrHolderBuilder - buildAttrHolder: Tags cannot be built for instanceId=0 (NULL AuthLocalUser)
2024-04-01 11:08:57,182	[RequestHandler-1-0x7f2883fff700 r=psauto-1711690116-63708 h=239 r=R00000760-01-660a6b69] INFO TAT.GuTagAttrHolderBuilder - buildAttrHolder: Tags cannot be built for instanceId=0 (NULL GuestUser)
2024-04-01 11:08:57,182	[RequestHandler-1-0x7f2883fff700 r=psauto-1711690116-63708 h=239 r=R00000760-01-660a6b69] INFO Common.TagDefinitionCacheTable - No TagDefCacheMap could be found for instance id = 9190 entity id = 72
2024-04-01 11:08:57,182	[RequestHandler-1-0x7f2883fff700 r=psauto-1711690116-63708 h=239 r=R00000760-01-660a6b69] INFO Common.TagDefinitionCacheTable - Building the TagDefMapTable for Endpoint instance=9190
2024-04-01 11:08:57,182	[RequestHandler-1-0x7f2883fff700 r=psauto-1711690116-63708 h=239 r=R00000760-01-660a6b69] INFO Common.TagDefinitionCacheTable - Built 0 tag(s) for instanceId=9190|entityId=72|entityName=Endpoint
2024-04-01 11:08:57,182	[RequestHandler-1-0x7f2883fff700 r=psauto-1711690116-63708 h=239 r=R00000760-01-660a6b69] INFO TAT.TagAttrHolderBuilder - No tags built for instanceId=9190|entity=Endpoint
2024-04-01 11:08:57,182	[RequestHandler-1-0x7f2883fff700 r=psauto-1711690116-63708 h=239 r=R00000760-01-660a6b69] INFO TAT.OnboardTagAttrHolderBuilder - buildAttrHolder: Tags cannot be built for instanceId=0 (NULL Onboard Device User)
2024-04-01 11:08:57,182	[RequestHandler-1-0x7f2883fff700 h=532877 c=R00000760-01-660a6b69] INFO Core.PETaskScheduler - *** PE_TASK_SCHEDULE_RADIUS Started ***
2024-04-01 11:08:57,182	[RequestHandler-1-0x7f2883fff700 h=532877 c=R00000760-01-660a6b69] INFO Core.PETaskScheduler - ** Starting PETaskAuthSourceRestriction **
2024-04-01 11:08:57,182	[RequestHandler-1-0x7f2883fff700 h=532877 c=R00000760-01-660a6b69] INFO Core.PETaskScheduler - ** Starting PETaskRoleMapping **
2024-04-01 11:08:57,183	[RequestHandler-1-0x7f2883fff700 r=R00000760-01-660a6b69 h=532877 c=R00000760-01-660a6b69] INFO Core.PETaskScheduler - ** Completed PETaskAuthSourceRestriction **
2024-04-01 11:08:57,186	[RequestHandler-1-0x7f2883fff700 h=532879 c=R00000760-01-660a6b69] INFO Core.PETaskRoleMapping - Roles: Medo - Aruba Access Point
2024-04-01 11:08:57,186	[RequestHandler-1-0x7f2883fff700 r=R00000760-01-660a6b69 h=532877 c=R00000760-01-660a6b69] INFO Core.PETaskScheduler - ** Completed PETaskRoleMapping **
2024-04-01 11:08:57,187	[RequestHandler-1-0x7f2883fff700 r=R00000760-01-660a6b69 h=532877 c=R00000760-01-660a6b69] INFO Core.PETaskScheduler - ** Starting PETaskPolicyResult **
2024-04-01 11:08:57,187	[RequestHandler-1-0x7f2883fff700 r=R00000760-01-660a6b69 h=532877 c=R00000760-01-660a6b69] INFO Core.PETaskScheduler - ** Completed PETaskPolicyResult **
2024-04-01 11:08:57,187	[RequestHandler-1-0x7f2883fff700 r=R00000760-01-660a6b69 h=532877 c=R00000760-01-660a6b69] INFO Core.PETaskScheduler - ** Starting PETaskEnforcement **
2024-04-01 11:08:57,187	[RequestHandler-1-0x7f2883fff700 h=532882 c=R00000760-01-660a6b69] INFO Core.PETaskEnforcement - EnfProfiles: Update Endpoint Known], Medo VL-100] 802.1X Wired AAA Aruba AP], Medo Wired Filter-ID - ALLOWALL
2024-04-01 11:08:57,188	[RequestHandler-1-0x7f2883fff700 r=R00000760-01-660a6b69 h=532877 c=R00000760-01-660a6b69] INFO Core.PETaskScheduler - ** Completed PETaskEnforcement **
2024-04-01 11:08:57,188	[RequestHandler-1-0x7f2883fff700 r=R00000760-01-660a6b69 h=532877 c=R00000760-01-660a6b69] INFO Core.PETaskScheduler - ** Starting PETaskRadiusEnfProfileBuilder **
2024-04-01 11:08:57,188	[RequestHandler-1-0x7f2883fff700 r=R00000760-01-660a6b69 h=532877 c=R00000760-01-660a6b69] INFO Core.PETaskScheduler - ** Starting PETaskRadiusCoAEnfProfileBuilder **
2024-04-01 11:08:57,188	[RequestHandler-1-0x7f2883fff700 r=R00000760-01-660a6b69 h=532877 c=R00000760-01-660a6b69] INFO Core.PETaskScheduler - ** Starting PETaskAppEnfProfileBuilder **
2024-04-01 11:08:57,188	[RequestHandler-1-0x7f2883fff700 r=R00000760-01-660a6b69 h=532877 c=R00000760-01-660a6b69] INFO Core.PETaskScheduler - ** Starting PETaskAgentEnfProfileBuilder **
2024-04-01 11:08:57,188	[RequestHandler-1-0x7f2883fff700 r=R00000760-01-660a6b69 h=532877 c=R00000760-01-660a6b69] INFO Core.PETaskScheduler - ** Starting PETaskPostAuthEnfProfileBuilder **
2024-04-01 11:08:57,188	[RequestHandler-1-0x7f2883fff700 r=R00000760-01-660a6b69 h=532877 c=R00000760-01-660a6b69] INFO Core.PETaskScheduler - ** Starting PETaskGenericEnfProfileBuilder **
2024-04-01 11:08:57,188	[RequestHandler-1-0x7f2883fff700 h=532888 c=R00000760-01-660a6b69] INFO Core.PETaskGenericEnfProfileBuilder - getApplicableProfiles: No App enforcement (Generic) profiles applicable for this device
2024-04-01 11:08:57,188	[RequestHandler-1-0x7f2883fff700 h=532885 c=R00000760-01-660a6b69] WARN Common.AppEnfProfileTable - getAppType: Failed for id=51
2024-04-01 11:08:57,189	[RequestHandler-1-0x7f2883fff700 h=532883 c=R00000760-01-660a6b69] INFO Core.PETaskRadiusEnfProfileBuilder - EnfProfileAction=ACCEPT
2024-04-01 11:08:57,189	[RequestHandler-1-0x7f2883fff700 h=532883 c=R00000760-01-660a6b69] INFO Core.PETaskRadiusEnfProfileBuilder - Radius enfProfiles used: Medo VL-100] 802.1X Wired AAA Aruba AP], Medo Wired Filter-ID - ALLOWALL
2024-04-01 11:08:57,189	[RequestHandler-1-0x7f2883fff700 h=532883 c=R00000760-01-660a6b69] INFO Core.EnfProfileComputer - getFinalSessionTimeout: sessionTimeout = 86400
2024-04-01 11:08:57,189	[RequestHandler-1-0x7f2883fff700 r=R00000760-01-660a6b69 h=532877 c=R00000760-01-660a6b69] INFO Core.PETaskScheduler - ** Completed PETaskGenericEnfProfileBuilder **
2024-04-01 11:08:57,189	[RequestHandler-1-0x7f2883fff700 r=R00000760-01-660a6b69 h=532877 c=R00000760-01-660a6b69] INFO Core.PETaskScheduler - ** Completed PETaskAgentEnfProfileBuilder **
2024-04-01 11:08:57,189	[RequestHandler-1-0x7f2883fff700 r=R00000760-01-660a6b69 h=532877 c=R00000760-01-660a6b69] INFO Core.PETaskScheduler - ** Completed PETaskAppEnfProfileBuilder **
2024-04-01 11:08:57,189	[RequestHandler-1-0x7f2883fff700 r=R00000760-01-660a6b69 h=532877 c=R00000760-01-660a6b69] INFO Core.PETaskScheduler - ** Starting PETaskCliEnforcement **
2024-04-01 11:08:57,189	[RequestHandler-1-0x7f2883fff700 h=532889 c=R00000760-01-660a6b69] INFO Core.PETaskCliEnforcement - startHandler: No commands for CLI enforcement
2024-04-01 11:08:57,190	[RequestHandler-1-0x7f2883fff700 r=R00000760-01-660a6b69 h=532877 c=R00000760-01-660a6b69] INFO Core.PETaskScheduler - ** Completed PETaskRadiusEnfProfileBuilder **
2024-04-01 11:08:57,190	[RequestHandler-1-0x7f2883fff700 r=R00000760-01-660a6b69 h=532887 c=R00000760-01-660a6b69] INFO Core.PETaskPostAuthEnfProfileBuilder - Post auth enforcement profiles used: Update Endpoint Known]
2024-04-01 11:08:57,190	[RequestHandler-1-0x7f2883fff700 r=R00000760-01-660a6b69 h=532887 c=R00000760-01-660a6b69] INFO Core.PETaskPostAuthEnfProfileBuilder - UnknownAutzParams to fetch for PostAuthEnfProfiles: :
2024-04-01 11:08:57,190	[RequestHandler-1-0x7f2883fff700 r=R00000760-01-660a6b69 h=532887 c=R00000760-01-660a6b69] INFO Core.PETaskPostAuthEnfProfileBuilder - UnknownNAutzParams to fetch for PostAuthEnfProfiles: :
2024-04-01 11:08:57,190	[RequestHandler-1-0x7f2883fff700 r=R00000760-01-660a6b69 h=532887 c=R00000760-01-660a6b69] INFO Common.EndpointTable - Returning EndpointSPtr for macAddr 348a12c7a134
2024-04-01 11:08:57,190	[RequestHandler-1-0x7f2883fff700 r=R00000760-01-660a6b69 h=532887 c=R00000760-01-660a6b69] INFO Core.PETaskPostAuthEnfProfileBuilder - Discarding attr name=Status-Update:Endpoint|attr value=Known as the entity = 348a12c7a134 already possesses this value
2024-04-01 11:08:57,190	[RequestHandler-1-0x7f2883fff700 r=R00000760-01-660a6b69 h=532887 c=R00000760-01-660a6b69] INFO Core.PETaskPostAuthEnfProfileBuilder - sendPostAuthHTTPRequest: Sending PostAuthEnfRequest {"content":{"auth_source_id":0,"mac_address":"348a12c7a134","nas_ip":"10.2.200.12","post_auth_actions":{"enf_profile_name":"Update Endpoint Known]","enf_profile_type":"EntityUpdate","params":null}],"user_id":"MEDOA-WH-20"},"id":"R00004758-01-66092efe","name":"pactrl_enf_request"}
2024-04-01 11:08:57,190	[RequestHandler-1-0x7f2883fff700 r=R00000760-01-660a6b69 h=532887 c=R00000760-01-660a6b69] INFO Core.PETaskPostAuthEnfProfileBuilder - sendPostAuthResponse: Done sending the PostAuthEnfRequest
2024-04-01 11:08:57,190	[RequestHandler-1-0x7f2883fff700 r=R00000760-01-660a6b69 h=532884 c=R00000760-01-660a6b69] INFO Core.PETaskRadiusCoAEnfProfileBuilder - getApplicableProfiles: No radius_coa enforcement profiles applicable for this device
2024-04-01 11:08:57,191	[RequestHandler-1-0x7f2883fff700 r=R00000760-01-660a6b69 h=532877 c=R00000760-01-660a6b69] INFO Core.PETaskScheduler - ** Completed PETaskCliEnforcement **
2024-04-01 11:08:57,191	[RequestHandler-1-0x7f2883fff700 r=R00000760-01-660a6b69 h=532877 c=R00000760-01-660a6b69] INFO Core.PETaskScheduler - ** Completed PETaskPostAuthEnfProfileBuilder **
2024-04-01 11:08:57,191	[RequestHandler-1-0x7f2883fff700 r=R00000760-01-660a6b69 h=532877 c=R00000760-01-660a6b69] INFO Core.PETaskScheduler - ** Completed PETaskRadiusCoAEnfProfileBuilder **
2024-04-01 11:08:57,191	[RequestHandler-1-0x7f2883fff700 r=R00000760-01-660a6b69 h=532877 c=R00000760-01-660a6b69] INFO Core.PETaskScheduler - ** Starting PETaskAuthStatusInfo **
2024-04-01 11:08:57,191	[RequestHandler-1-0x7f2883fff700 r=R00000760-01-660a6b69 h=532877 c=R00000760-01-660a6b69] INFO Core.PETaskScheduler - ** Starting PETaskOutputPolicyRes **
2024-04-01 11:08:57,191	[RequestHandler-1-0x7f2883fff700 r=R00000760-01-660a6b69 h=532877 c=R00000760-01-660a6b69] INFO Core.PETaskScheduler - ** Starting PETaskSessionLog **
2024-04-01 11:08:57,196	[RequestHandler-1-0x7f2883fff700 h=532891 c=R00000760-01-660a6b69] INFO Core.XpipPolicyResHandler - populateResponseTlv: PETaskPostureOutput does not exist. Skip sending posture VAFs
2024-04-01 11:08:57,196	[RequestHandler-1-0x7f2883fff700 h=532891 c=R00000760-01-660a6b69] INFO Core.PolicyResCollector - getSohr: Failed to generate Sohr
2024-04-01 11:08:57,196	[RequestHandler-1-0x7f2883fff700 h=532890 c=R00000760-01-660a6b69] INFO Core.PolicyResCollector - getSohr: Failed to generate Sohr
2024-04-01 11:08:57,196	[RequestHandler-1-0x7f2883fff700 r=R00000760-01-660a6b69 h=532877 c=R00000760-01-660a6b69] INFO Core.PETaskScheduler - ** Completed PETaskSessionLog **
2024-04-01 11:08:57,196	[RequestHandler-1-0x7f2883fff700 r=R00000760-01-660a6b69 h=532877 c=R00000760-01-660a6b69] INFO Core.PETaskScheduler - ** Completed PETaskOutputPolicyRes **
2024-04-01 11:08:57,196	[RequestHandler-1-0x7f2883fff700 r=R00000760-01-660a6b69 h=532877 c=R00000760-01-660a6b69] INFO Core.PETaskScheduler - ** Completed PETaskAuthStatusInfo **
2024-04-01 11:08:57,196	[RequestHandler-1-0x7f2883fff700 r=R00000760-01-660a6b69 h=532877 c=R00000760-01-660a6b69] INFO Core.PETaskScheduler - *** PE_TASK_SCHEDULE_RADIUS Completed ***