| Time | Message |
|---|---|
| 2021-08-18 16:27:31,168 | [Th 44 Req 10314 SessId R000005c8-03-611cd283] INFO RadiusServer.Radius - rlm_service: Starting Service Categorization - 55:201:646ee0b393d6 |
| 2021-08-18 16:27:31,174 | [RequestHandler-1-0x7fa0f75fa700 r=psauto-1627367049-3210 h=223 r=R000005c8-03-611cd283] INFO Core.ServiceReqHandler - Service classification result = 1. DLVN Onboard Provisioning |
| 2021-08-18 16:27:31,175 | [Th 44 Req 10314 SessId R000005c8-03-611cd283] INFO RadiusServer.Radius - Service Categorization time = 7 ms |
| 2021-08-18 16:27:31,176 | [Th 44 Req 10314 SessId R000005c8-03-611cd283] INFO RadiusServer.Radius - rlm_service: The request has been categorized into service "1. DLVN Onboard Provisioning" |
| 2021-08-18 16:27:31,176 | [Th 44 Req 10314 SessId R000005c8-03-611cd283] INFO RadiusServer.Radius - rlm_ldap: searching for user testarubaallstaff in AD:headoffice.dai-ichi-life.com.vn |
| 2021-08-18 16:27:31,178 | [Th 44 Req 10314 SessId R000005c8-03-611cd283] INFO RadiusServer.Radius - rlm_ldap: found user testarubaallstaff in AD:headoffice.dai-ichi-life.com.vn |
| 2021-08-18 16:27:31,178 | [Th 44 Req 10314 SessId R000005c8-03-611cd283] INFO RadiusServer.Radius - LDAP/AD User lookup time = 2 ms |
| 2021-08-18 16:27:31,178 | [Th 44 Req 10314 SessId R000005c8-03-611cd283] INFO RadiusServer.Radius - rlm_eap_peap: Initiate |
| 2021-08-18 16:27:31,178 | [Th 44 Req 10314 SessId R000005c8-03-611cd283] INFO RadiusServer.Radius - reqst_update_state: Access-Challenge 55:88:646ee0b393d6:AFoAKABpAHRKKAAAESQ3AyYSoGzPjkk9pE12uA== |
| 2021-08-18 16:27:31,188 | [Th 43 Req 10315 SessId R000005c8-03-611cd283] INFO RadiusServer.Radius - rlm_service: The request was categorized into service "1. DLVN Onboard Provisioning" - 56:393:646ee0b393d6 |
| 2021-08-18 16:27:31,191 | [Th 43 Req 10315 SessId R000005c8-03-611cd283] INFO RadiusServer.Radius - TLS_accept:error in SSLv3 read client key exchange A |
| 2021-08-18 16:27:31,191 | [Th 43 Req 10315 SessId R000005c8-03-611cd283] INFO RadiusServer.Radius - TLS_accept:error in SSLv3 read client key exchange A |
| 2021-08-18 16:27:31,192 | [Th 43 Req 10315 SessId R000005c8-03-611cd283] INFO RadiusServer.Radius - reqst_update_state: Access-Challenge 56:1124:646ee0b393d6:APEAWADbAMFLKAAA+NtrSdnQBkjQ/1nK8Q7wQg== |
| 2021-08-18 16:27:31,204 | [Th 46 Req 10316 SessId R000005c8-03-611cd283] INFO RadiusServer.Radius - rlm_service: The request was categorized into service "1. DLVN Onboard Provisioning" - 44:227:646ee0b393d6 |
| 2021-08-18 16:27:31,204 | [Th 46 Req 10316 SessId R000005c8-03-611cd283] INFO RadiusServer.Radius - reqst_update_state: Access-Challenge 44:1120:646ee0b393d6:AMMA/QBKAJ9MKAAAUp2Dwph7CRg/T0t4HnTLdg== |
| 2021-08-18 16:27:31,216 | [Th 42 Req 10317 SessId R000005c8-03-611cd283] INFO RadiusServer.Radius - rlm_service: The request was categorized into service "1. DLVN Onboard Provisioning" - 57:227:646ee0b393d6 |
| 2021-08-18 16:27:31,217 | [Th 42 Req 10317 SessId R000005c8-03-611cd283] INFO RadiusServer.Radius - reqst_update_state: Access-Challenge 57:1120:646ee0b393d6:AG8AHgAhAMxNKAAApJexwBt3BvCjuzod0Hmgrg== |
| 2021-08-18 16:27:31,229 | [Th 47 Req 10318 SessId R000005c8-03-611cd283] INFO RadiusServer.Radius - rlm_service: The request was categorized into service "1. DLVN Onboard Provisioning" - 64:227:646ee0b393d6 |
| 2021-08-18 16:27:31,230 | [Th 47 Req 10318 SessId R000005c8-03-611cd283] INFO RadiusServer.Radius - reqst_update_state: Access-Challenge 64:676:646ee0b393d6:AHQA3wAzAOhOKAAAGdKzxj4qHFe64e184fzjlw== |
| 2021-08-18 16:27:31,244 | [Th 45 Req 10319 SessId R000005c8-03-611cd283] INFO RadiusServer.Radius - rlm_service: The request was categorized into service "1. DLVN Onboard Provisioning" - 31:357:646ee0b393d6 |
| 2021-08-18 16:27:31,245 | [Th 45 Req 10319 SessId R000005c8-03-611cd283] INFO RadiusServer.Radius - reqst_update_state: Access-Challenge 31:139:646ee0b393d6:AOwAJAApAExPKAAARxnAURZgorzQ/MerbfkGFw== |
| 2021-08-18 16:27:31,997 | [Th 41 Req 10320 SessId R000005c8-03-611cd283] INFO RadiusServer.Radius - rlm_service: The request was categorized into service "1. DLVN Onboard Provisioning" - 32:227:646ee0b393d6 |
| 2021-08-18 16:27:31,997 | [Th 41 Req 10320 SessId R000005c8-03-611cd283] INFO RadiusServer.Radius - rlm_eap_peap: Session established. |
| 2021-08-18 16:27:31,997 | [Th 41 Req 10320 SessId R000005c8-03-611cd283] INFO RadiusServer.Radius - reqst_update_state: Access-Challenge 32:122:646ee0b393d6:AFAALABcAIxQKAAAVfZawkR/Mth3O3GZtiXuhA== |
| 2021-08-18 16:27:32,008 | [Th 44 Req 10321 SessId R000005c8-03-611cd283] INFO RadiusServer.Radius - rlm_service: The request was categorized into service "1. DLVN Onboard Provisioning" - 38:274:646ee0b393d6 |
| 2021-08-18 16:27:32,009 | [Th 44 Req 10321 SessId R000005c8-03-611cd283] INFO RadiusServer.Radius - rlm_eap_mschapv2: Issuing Challenge |
| 2021-08-18 16:27:32,009 | [Th 44 Req 10321 SessId R000005c8-03-611cd283] INFO RadiusServer.Radius - reqst_update_state: Access-Challenge 38:156:646ee0b393d6:AC8ATgDDAHZRKAAAZwh8ffGDZLP2bsfnQ53YoA== |
| 2021-08-18 16:27:32,019 | [Th 43 Req 10322 SessId R000005c8-03-611cd283] INFO RadiusServer.Radius - rlm_service: The request was categorized into service "1. DLVN Onboard Provisioning" - 65:328:646ee0b393d6 |
| 2021-08-18 16:27:32,019 | [Th 43 Req 10322 SessId R000005c8-03-611cd283] INFO RadiusServer.Radius - rlm_service: The request was categorized into service "1. DLVN Onboard Provisioning" - 82:0:646ee0b393d6 |
| 2021-08-18 16:27:32,019 | [Th 43 Req 10322 SessId R000005c8-03-611cd283] INFO RadiusServer.Radius - rlm_eap_mschapv2: Received MSCHAPv2 Response from client |
| 2021-08-18 16:27:32,020 | [Th 43 Req 10322 SessId R000005c8-03-611cd283] INFO RadiusServer.Radius - rlm_mschap: MSCHAPv2 username used for challenge computation testarubaallstaff |
| 2021-08-18 16:27:32,020 | [Th 43 Req 10322 SessId R000005c8-03-611cd283] INFO RadiusServer.Radius - rlm_mschap: Using domain HEADOFFICE from objectSid attribute |
| 2021-08-18 16:27:32,020 | [Th 43 Req 10322 SessId R000005c8-03-611cd283] INFO RadiusServer.Radius - rlm_mschap: authenticating user testarubaallstaff, domain HEADOFFICE |
| 2021-08-18 16:27:32,024 | [Th 43 Req 10322 SessId R000005c8-03-611cd283] INFO RadiusServer.Radius - rlm_mschap: user testarubaallstaff authenticated successfully |
| 2021-08-18 16:27:32,024 | [Th 43 Req 10322 SessId R000005c8-03-611cd283] INFO RadiusServer.Radius - MS-Chap User Authentication time = 4 ms |
| 2021-08-18 16:27:32,024 | [Th 43 Req 10322 SessId R000005c8-03-611cd283] INFO RadiusServer.Radius - rlm_eap_mschapv2: Sending MSCHAPv2 Success reply |
| 2021-08-18 16:27:32,025 | [Th 43 Req 10322 SessId R000005c8-03-611cd283] INFO RadiusServer.Radius - reqst_update_state: Access-Challenge 65:164:646ee0b393d6:AAMA8QDaAAlSKAAAj+pw+GzTDF1f8BiCMaTNSA== |
| 2021-08-18 16:27:32,035 | [Th 46 Req 10323 SessId R000005c8-03-611cd283] INFO RadiusServer.Radius - rlm_service: The request was categorized into service "1. DLVN Onboard Provisioning" - 45:258:646ee0b393d6 |
| 2021-08-18 16:27:32,035 | [Th 46 Req 10323 SessId R000005c8-03-611cd283] INFO RadiusServer.Radius - rlm_service: The request was categorized into service "1. DLVN Onboard Provisioning" - 83:0:646ee0b393d6 |
| 2021-08-18 16:27:32,035 | [Th 46 Req 10323 SessId R000005c8-03-611cd283] INFO RadiusServer.Radius - rlm_eap_mschapv2: Received MSCHAPv2 Success from client |
| 2021-08-18 16:27:32,035 | [Th 46 Req 10323 SessId R000005c8-03-611cd283] INFO RadiusServer.Radius - rlm_policy: Starting Policy Evaluation. |
| 2021-08-18 16:27:32,038 | [RequestHandler-1-0x7fa0f75fa700 r=psauto-1627367049-3211 h=239 r=R000005c8-03-611cd283] ERROR Common.NadClientTable - getNadClient: Unknown NadClient 10.170.1.219 |
| 2021-08-18 16:27:32,039 | [RequestHandler-1-0x7fa0f75fa700 r=psauto-1627367049-3211 h=239 r=R000005c8-03-611cd283] ERROR Common.NadClientTable - getNadClient: Unknown NadClient 10.170.1.219 |
| 2021-08-18 16:27:32,039 | [RequestHandler-1-0x7fa0f75fa700 r=psauto-1627367049-3211 h=239 r=R000005c8-03-611cd283] INFO Common.EndpointTable - Returning NULL (EndpointPtr) for macAddr 646ee0b393d6 |
| 2021-08-18 16:27:32,039 | [RequestHandler-1-0x7fa0f75fa700 r=psauto-1627367049-3211 h=239 r=R000005c8-03-611cd283] INFO Common.TagDefinitionCacheTable - No InstanceTagDefCacheMap found for instance id = 0 entity id = 29 |
| 2021-08-18 16:27:32,039 | [RequestHandler-1-0x7fa0f75fa700 r=psauto-1627367049-3211 h=239 r=R000005c8-03-611cd283] WARN Common.TagDefinitionCacheTable - Failed to build TagDefinitionMap. Unknown NadClient for Id=0 |
| 2021-08-18 16:27:32,040 | [RequestHandler-1-0x7fa0f75fa700 r=psauto-1627367049-3211 h=239 r=R000005c8-03-611cd283] INFO TAT.TagAttrHolderBuilder - No tags built for instanceId=0|entity=Device |
| 2021-08-18 16:27:32,040 | [RequestHandler-1-0x7fa0f75fa700 r=psauto-1627367049-3211 h=239 r=R000005c8-03-611cd283] INFO TAT.AluTagAttrHolderBuilder - buildAttrHolder: Tags cannot be built for instanceId=0 (NULL AuthLocalUser) |
| 2021-08-18 16:27:32,040 | [RequestHandler-1-0x7fa0f75fa700 r=psauto-1627367049-3211 h=239 r=R000005c8-03-611cd283] INFO TAT.GuTagAttrHolderBuilder - buildAttrHolder: Tags cannot be built for instanceId=0 (NULL GuestUser) |
| 2021-08-18 16:27:32,040 | [RequestHandler-1-0x7fa0f75fa700 r=psauto-1627367049-3211 h=239 r=R000005c8-03-611cd283] INFO TAT.EndpointTagAttrHolderBuilder - buildAttrHolder: Tags cannot be built for instanceId=0 (NULL Endpoint) |
| 2021-08-18 16:27:32,040 | [RequestHandler-1-0x7fa0f75fa700 r=psauto-1627367049-3211 h=239 r=R000005c8-03-611cd283] INFO TAT.OnboardTagAttrHolderBuilder - buildAttrHolder: Tags cannot be built for instanceId=0 (NULL Onboard Device User) |
| 2021-08-18 16:27:32,040 | [RequestHandler-1-0x7fa0f75fa700 h=28722 c=R000005c8-03-611cd283] INFO Core.PETaskScheduler - *** PE_TASK_SCHEDULE_RADIUS Started *** |
| 2021-08-18 16:27:32,041 | [RequestHandler-1-0x7fa0f75fa700 h=28722 c=R000005c8-03-611cd283] INFO Core.PETaskScheduler - ** Starting PETaskAuthSourceRestriction ** |
| 2021-08-18 16:27:32,041 | [RequestHandler-1-0x7fa0f75fa700 h=28722 c=R000005c8-03-611cd283] INFO Core.PETaskScheduler - ** Starting PETaskRoleMapping ** |
| 2021-08-18 16:27:32,041 | [RequestHandler-1-0x7fa0f75fa700 h=28723 c=R000005c8-03-611cd283] WARN REC.EvaluatorCtx - Prerequisites set is empty, not populating the Request Map |
| 2021-08-18 16:27:32,042 | [RequestHandler-1-0x7fa0f75fa700 r=R000005c8-03-611cd283 h=28722 c=R000005c8-03-611cd283] INFO Core.PETaskScheduler - ** Completed PETaskAuthSourceRestriction ** |
| 2021-08-18 16:27:32,043 | [RequestHandler-1-0x7fa0f75fa700 r=R000005c8-03-611cd283 h=28724 c=R000005c8-03-611cd283] INFO Core.PETaskRoleMapping - Roles: User Authenticated] |
| 2021-08-18 16:27:32,043 | [RequestHandler-1-0x7fa0f75fa700 r=R000005c8-03-611cd283 h=28722 c=R000005c8-03-611cd283] INFO Core.PETaskScheduler - ** Completed PETaskRoleMapping ** |
| 2021-08-18 16:27:32,043 | [RequestHandler-1-0x7fa0f75fa700 r=R000005c8-03-611cd283 h=28722 c=R000005c8-03-611cd283] INFO Core.PETaskScheduler - ** Starting PETaskPolicyResult ** |
| 2021-08-18 16:27:32,047 | [RequestHandler-1-0x7fa0f75fa700 r=R000005c8-03-611cd283 h=28726 c=R000005c8-03-611cd283] ERROR Core.PETaskPolicyResult - handleHttpResponseEv: All policy result cache lookups failed |
| 2021-08-18 16:27:32,047 | [RequestHandler-1-0x7fa0f75fa700 r=R000005c8-03-611cd283 h=28722 c=R000005c8-03-611cd283] INFO Core.PETaskScheduler - ** Completed PETaskPolicyResult ** |
| 2021-08-18 16:27:32,047 | [RequestHandler-1-0x7fa0f75fa700 r=R000005c8-03-611cd283 h=28722 c=R000005c8-03-611cd283] INFO Core.PETaskScheduler - ** Starting PETaskEnforcement ** |
| 2021-08-18 16:27:32,050 | [AuthReqThreadPool-22-0x7fa1d5fef700 r=R000005c8-03-611cd283 h=74] ERROR ExtDB.DBQuery - ResultSet is empty |
| 2021-08-18 16:27:32,050 | [AuthReqThreadPool-22-0x7fa1d5fef700 r=R000005c8-03-611cd283 h=74] ERROR ExtDB.DBQuery - Failed to get value for attributes=Category] |
| 2021-08-18 16:27:32,052 | [RequestHandler-1-0x7fa0f75fa700 h=28727 c=R000005c8-03-611cd283] INFO Core.PETaskEnforcement - EnfProfiles: ArubaOS Switching - Terminate Session] |
| 2021-08-18 16:27:32,052 | [RequestHandler-1-0x7fa0f75fa700 r=R000005c8-03-611cd283 h=28722 c=R000005c8-03-611cd283] INFO Core.PETaskScheduler - ** Completed PETaskEnforcement ** |
| 2021-08-18 16:27:32,052 | [RequestHandler-1-0x7fa0f75fa700 r=R000005c8-03-611cd283 h=28722 c=R000005c8-03-611cd283] INFO Core.PETaskScheduler - ** Starting PETaskRadiusEnfProfileBuilder ** |
| 2021-08-18 16:27:32,052 | [RequestHandler-1-0x7fa0f75fa700 r=R000005c8-03-611cd283 h=28722 c=R000005c8-03-611cd283] INFO Core.PETaskScheduler - ** Starting PETaskRadiusCoAEnfProfileBuilder ** |
| 2021-08-18 16:27:32,052 | [RequestHandler-1-0x7fa0f75fa700 r=R000005c8-03-611cd283 h=28722 c=R000005c8-03-611cd283] INFO Core.PETaskScheduler - ** Starting PETaskAppEnfProfileBuilder ** |
| 2021-08-18 16:27:32,053 | [RequestHandler-1-0x7fa0f75fa700 r=R000005c8-03-611cd283 h=28722 c=R000005c8-03-611cd283] INFO Core.PETaskScheduler - ** Starting PETaskAgentEnfProfileBuilder ** |
| 2021-08-18 16:27:32,053 | [RequestHandler-1-0x7fa0f75fa700 r=R000005c8-03-611cd283 h=28722 c=R000005c8-03-611cd283] INFO Core.PETaskScheduler - ** Starting PETaskPostAuthEnfProfileBuilder ** |
| 2021-08-18 16:27:32,053 | [RequestHandler-1-0x7fa0f75fa700 r=R000005c8-03-611cd283 h=28722 c=R000005c8-03-611cd283] INFO Core.PETaskScheduler - ** Starting PETaskGenericEnfProfileBuilder ** |
| 2021-08-18 16:27:32,053 | [RequestHandler-1-0x7fa0f75fa700 h=28734 c=R000005c8-03-611cd283] ERROR Common.NadClientTable - getNadClient: Unknown NadClient 10.170.1.219 |
| 2021-08-18 16:27:32,053 | [RequestHandler-1-0x7fa0f75fa700 h=28734 c=R000005c8-03-611cd283] INFO Core.PETaskGenericEnfProfileBuilder - getApplicableProfiles: No App enforcement (Generic) profiles applicable for this device |
| 2021-08-18 16:27:32,054 | [RequestHandler-1-0x7fa0f75fa700 h=28729 c=R000005c8-03-611cd283] ERROR Common.NadClientTable - getNadClient: Unknown NadClient 10.170.1.219 |
| 2021-08-18 16:27:32,054 | [RequestHandler-1-0x7fa0f75fa700 h=28729 c=R000005c8-03-611cd283] INFO Core.PETaskRadiusEnfProfileBuilder - EnfProfileAction=ACCEPT |
| 2021-08-18 16:27:32,054 | [RequestHandler-1-0x7fa0f75fa700 h=28729 c=R000005c8-03-611cd283] WARN Core.PETaskRadiusEnfProfileBuilder - filterProfileIdList: No radius enforcement profiles applicable for this device. Allowing Access |
| 2021-08-18 16:27:32,054 | [RequestHandler-1-0x7fa0f75fa700 h=28729 c=R000005c8-03-611cd283] INFO Core.EnfProfileComputer - getFinalSessionTimeout: sessionTimeout = 0 |
| 2021-08-18 16:27:32,054 | [RequestHandler-1-0x7fa0f75fa700 h=28729 c=R000005c8-03-611cd283] WARN Core.SessionInfoOperations - Skip SessionInfoOperations::persistSessionInfo because of NULL NAD or NAD IP matching localhost |
| 2021-08-18 16:27:32,055 | [RequestHandler-1-0x7fa0f75fa700 r=R000005c8-03-611cd283 h=28722 c=R000005c8-03-611cd283] INFO Core.PETaskScheduler - ** Completed PETaskGenericEnfProfileBuilder ** |
| 2021-08-18 16:27:32,055 | [RequestHandler-1-0x7fa0f75fa700 r=R000005c8-03-611cd283 h=28722 c=R000005c8-03-611cd283] INFO Core.PETaskScheduler - ** Completed PETaskAgentEnfProfileBuilder ** |
| 2021-08-18 16:27:32,055 | [RequestHandler-1-0x7fa0f75fa700 r=R000005c8-03-611cd283 h=28722 c=R000005c8-03-611cd283] INFO Core.PETaskScheduler - ** Completed PETaskAppEnfProfileBuilder ** |
| 2021-08-18 16:27:32,055 | [RequestHandler-1-0x7fa0f75fa700 r=R000005c8-03-611cd283 h=28722 c=R000005c8-03-611cd283] INFO Core.PETaskScheduler - ** Starting PETaskCliEnforcement ** |
| 2021-08-18 16:27:32,055 | [RequestHandler-1-0x7fa0f75fa700 h=28735 c=R000005c8-03-611cd283] INFO Core.PETaskCliEnforcement - startHandler: No commands for CLI enforcement |
| 2021-08-18 16:27:32,055 | [RequestHandler-1-0x7fa0f75fa700 r=R000005c8-03-611cd283 h=28722 c=R000005c8-03-611cd283] INFO Core.PETaskScheduler - ** Completed PETaskRadiusEnfProfileBuilder ** |
| 2021-08-18 16:27:32,056 | [RequestHandler-1-0x7fa0f75fa700 r=R000005c8-03-611cd283 h=28722 c=R000005c8-03-611cd283] INFO Core.PETaskScheduler - ** Completed PETaskCliEnforcement ** |
| 2021-08-18 16:27:32,056 | [RequestHandler-1-0x7fa0f75fa700 r=R000005c8-03-611cd283 h=28733 c=R000005c8-03-611cd283] WARN Core.PETaskPostAuthEnfProfileBuilder - handleHttpResponseEv: Fetching Radius attributes from battery failed, errMsg= |
| 2021-08-18 16:27:32,057 | [RequestHandler-1-0x7fa0f75fa700 r=R000005c8-03-611cd283 h=28733 c=R000005c8-03-611cd283] ERROR Common.NadClientTable - getNadClient: Unknown NadClient 10.170.1.219 |
| 2021-08-18 16:27:32,057 | [RequestHandler-1-0x7fa0f75fa700 r=R000005c8-03-611cd283 h=28733 c=R000005c8-03-611cd283] INFO Core.PETaskPostAuthEnfProfileBuilder - getApplicableProfiles: No Post auth enforcement profiles applicable for this device |
| 2021-08-18 16:27:32,057 | [RequestHandler-1-0x7fa0f75fa700 r=R000005c8-03-611cd283 h=28730 c=R000005c8-03-611cd283] WARN Core.PETaskRadiusCoAEnfProfileBuilder - handleHttpResponseEv: Fetching Radius attributes from battery failed, errMsg= |
| 2021-08-18 16:27:32,057 | [RequestHandler-1-0x7fa0f75fa700 r=R000005c8-03-611cd283 h=28722 c=R000005c8-03-611cd283] INFO Core.PETaskScheduler - ** Completed PETaskPostAuthEnfProfileBuilder ** |
| 2021-08-18 16:27:32,057 | [RequestHandler-1-0x7fa0f75fa700 r=R000005c8-03-611cd283 h=28722 c=R000005c8-03-611cd283] INFO Core.PETaskScheduler - ** Completed PETaskRadiusCoAEnfProfileBuilder ** |
| 2021-08-18 16:27:32,057 | [RequestHandler-1-0x7fa0f75fa700 r=R000005c8-03-611cd283 h=28722 c=R000005c8-03-611cd283] INFO Core.PETaskScheduler - ** Starting PETaskAuthStatusInfo ** |
| 2021-08-18 16:27:32,058 | [RequestHandler-1-0x7fa0f75fa700 r=R000005c8-03-611cd283 h=28722 c=R000005c8-03-611cd283] INFO Core.PETaskScheduler - ** Starting PETaskOutputPolicyRes ** |
| 2021-08-18 16:27:32,058 | [RequestHandler-1-0x7fa0f75fa700 r=R000005c8-03-611cd283 h=28722 c=R000005c8-03-611cd283] INFO Core.PETaskScheduler - ** Starting PETaskSessionLog ** |
| 2021-08-18 16:27:32,065 | [RequestHandler-1-0x7fa0f75fa700 h=28737 c=R000005c8-03-611cd283] INFO Core.XpipPolicyResHandler - populateResponseTlv: PETaskPostureOutput does not exist. Skip sending posture VAFs |
| 2021-08-18 16:27:32,065 | [RequestHandler-1-0x7fa0f75fa700 h=28737 c=R000005c8-03-611cd283] INFO Core.PolicyResCollector - getSohr: Failed to generate Sohr |
| 2021-08-18 16:27:32,065 | [RequestHandler-1-0x7fa0f75fa700 h=28736 c=R000005c8-03-611cd283] INFO Core.PolicyResCollector - getSohr: Failed to generate Sohr |
| 2021-08-18 16:27:32,066 | [Th 46 Req 10323 SessId R000005c8-03-611cd283] INFO RadiusServer.Radius - Policy Evaluation time = 30 ms |
| 2021-08-18 16:27:32,066 | [Th 46 Req 10323 SessId R000005c8-03-611cd283] INFO RadiusServer.Radius - rlm_policy: Received Accept Enforcement Profile |
| 2021-08-18 16:27:32,066 | [Th 46 Req 10323 SessId R000005c8-03-611cd283] INFO RadiusServer.Radius - rlm_policy: Policy Server reply does not contain Posture-Validation-Response |
| 2021-08-18 16:27:32,066 | [RequestHandler-1-0x7fa0f75fa700 r=R000005c8-03-611cd283 h=28722 c=R000005c8-03-611cd283] INFO Core.PETaskScheduler - ** Completed PETaskSessionLog ** |
| 2021-08-18 16:27:32,066 | [RequestHandler-1-0x7fa0f75fa700 r=R000005c8-03-611cd283 h=28722 c=R000005c8-03-611cd283] INFO Core.PETaskScheduler - ** Completed PETaskOutputPolicyRes ** |
| 2021-08-18 16:27:32,066 | [RequestHandler-1-0x7fa0f75fa700 r=R000005c8-03-611cd283 h=28722 c=R000005c8-03-611cd283] INFO Core.PETaskScheduler - ** Completed PETaskAuthStatusInfo ** |
| 2021-08-18 16:27:32,066 | [RequestHandler-1-0x7fa0f75fa700 r=R000005c8-03-611cd283 h=28722 c=R000005c8-03-611cd283] INFO Core.PETaskScheduler - *** PE_TASK_SCHEDULE_RADIUS Completed *** |
| 2021-08-18 16:27:32,067 | [Th 46 Req 10323 SessId R000005c8-03-611cd283] INFO RadiusServer.Radius - reqst_update_state: Access-Challenge 45:128:646ee0b393d6:APgAqABoAFBTKAAAf22ZeUSDSYAiW9pM+e02JQ== |
| 2021-08-18 16:27:32,080 | [Th 42 Req 10324 SessId R000005c8-03-611cd283] INFO RadiusServer.Radius - rlm_service: The request was categorized into service "1. DLVN Onboard Provisioning" - 58:267:646ee0b393d6 |
| 2021-08-18 16:27:32,080 | [Th 42 Req 10324 SessId R000005c8-03-611cd283] INFO RadiusServer.Radius - rlm_policy: Bypassing Policy Evaluation. |
| 2021-08-18 16:27:32,080 | [Th 42 Req 10324 SessId R000005c8-03-611cd283] INFO RadiusServer.Radius - rlm_policy: Added Class attribute with value Class = 0xd68964a976884ffbae0ea408685565a5cc0b0000000000005230303030303563382d30332d36313163643238330000000000000000000000 |
| 2021-08-18 16:27:32,081 | [Th 42 Req 10324 SessId R000005c8-03-611cd283] INFO RadiusServer.Radius - Request processing time = 914 ms |