| Time | Message |
|---|---|
| 2021-03-01 08:46:41,881 | [Th 152 Req 112527392 SessId R00014c11-01-603c9be1] INFO RadiusServer.Radius - rlm_service: Starting Service Categorization - 253:409:c8-d3-ff-a0-b1-2a |
| 2021-03-01 08:46:41,884 | [RequestHandler-1-0x7f59fa5f2700 r=psauto-1611304817-629893 h=223 r=R00014c11-01-603c9be1] WARN RL.ScopePyHash - Failed to determine the datatype adding as string into Python Dictionary AttributeID = Radius:IETF:EAP-Message;; DataType = OctetArray;; AttributeValue = 0x0213002601686f73742f43493032333633382e46727579746965722e6a6673672e6c6f63616c ...&.host/CI023638.Fruytier.jfsg.local] |
| 2021-03-01 08:46:41,884 | [RequestHandler-1-0x7f59fa5f2700 r=psauto-1611304817-629893 h=223 r=R00014c11-01-603c9be1] WARN RL.ScopePyHash - Adding the Attribute into Python Dictionary AttributeID = Radius:IETF:NAS-Port-Type;; DataType = Integer32;; AttributeValue = 15 |
| 2021-03-01 08:46:41,884 | [RequestHandler-1-0x7f59fa5f2700 r=psauto-1611304817-629893 h=223 r=R00014c11-01-603c9be1] WARN RL.ScopePyHash - Adding the Attribute into Python Dictionary AttributeID = Radius:IETF:Service-Type;; DataType = Integer32;; AttributeValue = 2 |
| 2021-03-01 08:46:41,884 | [RequestHandler-1-0x7f59fa5f2700 r=psauto-1611304817-629893 h=223 r=R00014c11-01-603c9be1] WARN RL.ScopePyHash - Adding the Attribute into Python Dictionary AttributeID = Radius:IETF:User-Name;; DataType = String;; AttributeValue = host/CI023638.Fruytier.jfsg.local |
| 2021-03-01 08:46:41,884 | [RequestHandler-1-0x7f59fa5f2700 r=psauto-1611304817-629893 h=223 r=R00014c11-01-603c9be1] INFO Core.ServiceReqHandler - Service classification result = JFSG - 802.1X Wired |
| 2021-03-01 08:46:41,885 | [Th 152 Req 112527392 SessId R00014c11-01-603c9be1] INFO RadiusServer.Radius - Service Categorization time = 4 ms |
| 2021-03-01 08:46:41,885 | [Th 152 Req 112527392 SessId R00014c11-01-603c9be1] INFO RadiusServer.Radius - rlm_service: The request has been categorized into service "JFSG - 802.1X Wired" |
| 2021-03-01 08:46:41,885 | [Th 152 Req 112527392 SessId R00014c11-01-603c9be1] INFO RadiusServer.Radius - rlm_ldap: searching for user host/CI023638.Fruytier.jfsg.local in AD:vm119o.onderwijs.fruytier.jfsg.local |
| 2021-03-01 08:46:41,886 | [Th 152 Req 112527392 SessId R00014c11-01-603c9be1] INFO RadiusServer.Radius - rlm_ldap: searching for user host/CI023638.Fruytier.jfsg.local in AD:10.1.1.70 |
| 2021-03-01 08:46:41,887 | [Th 152 Req 112527392 SessId R00014c11-01-603c9be1] INFO RadiusServer.Radius - rlm_ldap: found user CI023638$ in AD:10.1.1.70 |
| 2021-03-01 08:46:41,887 | [Th 152 Req 112527392 SessId R00014c11-01-603c9be1] INFO RadiusServer.Radius - LDAP/AD User lookup time = 1 ms |
| 2021-03-01 08:46:41,887 | [Th 152 Req 112527392 SessId R00014c11-01-603c9be1] INFO RadiusServer.Radius - rlm_eap_peap: Initiate |
| 2021-03-01 08:46:41,887 | [Th 152 Req 112527392 SessId R00014c11-01-603c9be1] INFO RadiusServer.Radius - reqst_update_state: Access-Challenge 253:88:c8-d3-ff-a0-b1-2a:ANsAfADWACIgCLUGk/nMut8Uo2+B5aNFEcVDKg== |
| 2021-03-01 08:46:41,995 | [Th 151 Req 112527393 SessId R00014c11-01-603c9be1] INFO RadiusServer.Radius - rlm_service: The request was categorized into service "JFSG - 802.1X Wired" - 254:579:c8-d3-ff-a0-b1-2a |
| 2021-03-01 08:46:41,996 | [Th 151 Req 112527393 SessId R00014c11-01-603c9be1] INFO RadiusServer.Radius - TLS_accept:error in SSLv3 read client key exchange A |
| 2021-03-01 08:46:41,996 | [Th 151 Req 112527393 SessId R00014c11-01-603c9be1] INFO RadiusServer.Radius - TLS_accept:error in SSLv3 read client key exchange A |
| 2021-03-01 08:46:41,997 | [Th 151 Req 112527393 SessId R00014c11-01-603c9be1] INFO RadiusServer.Radius - reqst_update_state: Access-Challenge 254:1124:c8-d3-ff-a0-b1-2a:AD8ANADHADUhCLUGHiFfRlbNXG9m+GD+dIdCMw== |
| 2021-03-01 08:46:42,000 | [Th 148 Req 112527394 SessId R00014c11-01-603c9be1] INFO RadiusServer.Radius - rlm_service: The request was categorized into service "JFSG - 802.1X Wired" - 255:419:c8-d3-ff-a0-b1-2a |
| 2021-03-01 08:46:42,000 | [Th 148 Req 112527394 SessId R00014c11-01-603c9be1] INFO RadiusServer.Radius - reqst_update_state: Access-Challenge 255:1120:c8-d3-ff-a0-b1-2a:AOwAVAC2ADYiCLUGLXWpL9XLXrFNsW6ptt16Hg== |
| 2021-03-01 08:46:42,004 | [Th 157 Req 112527395 SessId R00014c11-01-603c9be1] INFO RadiusServer.Radius - rlm_service: The request was categorized into service "JFSG - 802.1X Wired" - 4:419:c8-d3-ff-a0-b1-2a |
| 2021-03-01 08:46:42,004 | [Th 157 Req 112527395 SessId R00014c11-01-603c9be1] INFO RadiusServer.Radius - reqst_update_state: Access-Challenge 4:1120:c8-d3-ff-a0-b1-2a:ACsAzABFADwjCLUGS64nqR/dWEePBVJEDq2tmA== |
| 2021-03-01 08:46:42,007 | [Th 154 Req 112527396 SessId R00014c11-01-603c9be1] INFO RadiusServer.Radius - rlm_service: The request was categorized into service "JFSG - 802.1X Wired" - 5:419:c8-d3-ff-a0-b1-2a |
| 2021-03-01 08:46:42,007 | [Th 154 Req 112527396 SessId R00014c11-01-603c9be1] INFO RadiusServer.Radius - reqst_update_state: Access-Challenge 5:339:c8-d3-ff-a0-b1-2a:APYAxQDzABUkCLUGK9O1nIC/wH/EthJyx8sKNQ== |
| 2021-03-01 08:47:34,030 | [main SessId R00014c11-01-603c9be1] ERROR RadiusServer.Radius - reqst_clean_list: Deleting request sessid - R00014c11-01-603c9be1, state - APYAxQDzABUkCLUGK9O1nIC/wH/EthJyx8sKNQ= |
| 2021-03-01 08:47:34,030 | [main SessId R00014c11-01-603c9be1] ERROR RadiusServer.Radius - reqst_clean_list: Packet 253:409:88:c8-d3-ff-a0-b1-2a recv 1614584801.881067 - resp 1614584801.887672 |
| 2021-03-01 08:47:34,030 | [main SessId R00014c11-01-603c9be1] ERROR RadiusServer.Radius - reqst_clean_list: Packet 254:579:1124:c8-d3-ff-a0-b1-2a recv 1614584801.994976 - resp 1614584801.997004 |
| 2021-03-01 08:47:34,030 | [main SessId R00014c11-01-603c9be1] ERROR RadiusServer.Radius - reqst_clean_list: Packet 255:419:1120:c8-d3-ff-a0-b1-2a recv 1614584802.177 - resp 1614584802.875 |
| 2021-03-01 08:47:34,030 | [main SessId R00014c11-01-603c9be1] ERROR RadiusServer.Radius - reqst_clean_list: Packet 4:419:1120:c8-d3-ff-a0-b1-2a recv 1614584802.3847 - resp 1614584802.4552 |
| 2021-03-01 08:47:34,030 | [main SessId R00014c11-01-603c9be1] ERROR RadiusServer.Radius - reqst_clean_list: Packet 5:419:339:c8-d3-ff-a0-b1-2a recv 1614584802.7379 - resp 1614584802.7949 |
| 2021-03-01 08:47:34,030 | [main SessId R00014c11-01-603c9be1] INFO RadiusServer.Radius - rlm_policy: Starting Policy Evaluation. |
| 2021-03-01 08:47:34,033 | [RequestHandler-2-0x7f59fa3f1700 r=psauto-1611304817-629955 h=240 r=R00014c11-01-603c9be1] INFO Common.EndpointTable - Returning EndpointSPtr for macAddr c8d3ffa0b12a |
| 2021-03-01 08:47:34,033 | [RequestHandler-2-0x7f59fa3f1700 r=psauto-1611304817-629955 h=240 r=R00014c11-01-603c9be1] INFO TAT.AluTagAttrHolderBuilder - buildAttrHolder: Tags cannot be built for instanceId=0 (NULL AuthLocalUser) |
| 2021-03-01 08:47:34,033 | [RequestHandler-2-0x7f59fa3f1700 r=psauto-1611304817-629955 h=240 r=R00014c11-01-603c9be1] INFO TAT.GuTagAttrHolderBuilder - buildAttrHolder: Tags cannot be built for instanceId=0 (NULL GuestUser) |
| 2021-03-01 08:47:34,033 | [RequestHandler-2-0x7f59fa3f1700 r=psauto-1611304817-629955 h=240 r=R00014c11-01-603c9be1] INFO TAT.OnboardTagAttrHolderBuilder - buildAttrHolder: Tags cannot be built for instanceId=0 (NULL Onboard Device User) |
| 2021-03-01 08:47:34,033 | [RequestHandler-2-0x7f59fa3f1700 h=5486000 c=R00014c11-01-603c9be1] INFO Core.PETaskScheduler - *** PE_TASK_SCHEDULE_RADIUS Started *** |
| 2021-03-01 08:47:34,033 | [RequestHandler-2-0x7f59fa3f1700 h=5486000 c=R00014c11-01-603c9be1] INFO Core.PETaskScheduler - ** Starting PETaskAuthSourceRestriction ** |
| 2021-03-01 08:47:34,034 | [RequestHandler-2-0x7f59fa3f1700 h=5486000 c=R00014c11-01-603c9be1] INFO Core.PETaskScheduler - ** Starting PETaskRoleMapping ** |
| 2021-03-01 08:47:34,034 | [RequestHandler-2-0x7f59fa3f1700 r=R00014c11-01-603c9be1 h=5486000 c=R00014c11-01-603c9be1] INFO Core.PETaskScheduler - ** Completed PETaskAuthSourceRestriction ** |
| 2021-03-01 08:47:34,035 | [RequestHandler-2-0x7f59fa3f1700 h=5486004 c=R00014c11-01-603c9be1] INFO Core.PETaskRoleMapping - Roles: Onbekend Apparaat |
| 2021-03-01 08:47:34,035 | [RequestHandler-2-0x7f59fa3f1700 r=R00014c11-01-603c9be1 h=5486000 c=R00014c11-01-603c9be1] INFO Core.PETaskScheduler - ** Completed PETaskRoleMapping ** |
| 2021-03-01 08:47:34,035 | [RequestHandler-2-0x7f59fa3f1700 r=R00014c11-01-603c9be1 h=5486000 c=R00014c11-01-603c9be1] INFO Core.PETaskScheduler - ** Starting PETaskPolicyResult ** |
| 2021-03-01 08:47:34,035 | [RequestHandler-2-0x7f59fa3f1700 r=R00014c11-01-603c9be1 h=5486000 c=R00014c11-01-603c9be1] INFO Core.PETaskScheduler - ** Completed PETaskPolicyResult ** |
| 2021-03-01 08:47:34,035 | [RequestHandler-2-0x7f59fa3f1700 r=R00014c11-01-603c9be1 h=5486000 c=R00014c11-01-603c9be1] INFO Core.PETaskScheduler - ** Starting PETaskEnforcement ** |
| 2021-03-01 08:47:34,036 | [RequestHandler-2-0x7f59fa3f1700 h=5486010 c=R00014c11-01-603c9be1] INFO Core.PETaskEnforcement - EnfProfiles: Deny Access Profile] |
| 2021-03-01 08:47:34,036 | [RequestHandler-2-0x7f59fa3f1700 r=R00014c11-01-603c9be1 h=5486000 c=R00014c11-01-603c9be1] INFO Core.PETaskScheduler - ** Completed PETaskEnforcement ** |
| 2021-03-01 08:47:34,036 | [RequestHandler-2-0x7f59fa3f1700 r=R00014c11-01-603c9be1 h=5486000 c=R00014c11-01-603c9be1] INFO Core.PETaskScheduler - ** Starting PETaskRadiusEnfProfileBuilder ** |
| 2021-03-01 08:47:34,036 | [RequestHandler-2-0x7f59fa3f1700 r=R00014c11-01-603c9be1 h=5486000 c=R00014c11-01-603c9be1] INFO Core.PETaskScheduler - ** Starting PETaskRadiusCoAEnfProfileBuilder ** |
| 2021-03-01 08:47:34,036 | [RequestHandler-2-0x7f59fa3f1700 r=R00014c11-01-603c9be1 h=5486000 c=R00014c11-01-603c9be1] INFO Core.PETaskScheduler - ** Starting PETaskAppEnfProfileBuilder ** |
| 2021-03-01 08:47:34,036 | [RequestHandler-2-0x7f59fa3f1700 r=R00014c11-01-603c9be1 h=5486000 c=R00014c11-01-603c9be1] INFO Core.PETaskScheduler - ** Starting PETaskAgentEnfProfileBuilder ** |
| 2021-03-01 08:47:34,036 | [RequestHandler-2-0x7f59fa3f1700 r=R00014c11-01-603c9be1 h=5486000 c=R00014c11-01-603c9be1] INFO Core.PETaskScheduler - ** Starting PETaskPostAuthEnfProfileBuilder ** |
| 2021-03-01 08:47:34,036 | [RequestHandler-2-0x7f59fa3f1700 r=R00014c11-01-603c9be1 h=5486000 c=R00014c11-01-603c9be1] INFO Core.PETaskScheduler - ** Starting PETaskGenericEnfProfileBuilder ** |
| 2021-03-01 08:47:34,036 | [RequestHandler-2-0x7f59fa3f1700 h=5486022 c=R00014c11-01-603c9be1] INFO Core.PETaskGenericEnfProfileBuilder - getApplicableProfiles: No App enforcement (Generic) profiles applicable for this device |
| 2021-03-01 08:47:34,037 | [RequestHandler-2-0x7f59fa3f1700 h=5486015 c=R00014c11-01-603c9be1] INFO Core.PETaskRadiusEnfProfileBuilder - EnfProfileAction=DENY |
| 2021-03-01 08:47:34,038 | [RequestHandler-2-0x7f59fa3f1700 h=5486015 c=R00014c11-01-603c9be1] INFO Core.PETaskRadiusEnfProfileBuilder - Radius enfProfiles used: Deny Access Profile] |
| 2021-03-01 08:47:34,038 | [RequestHandler-2-0x7f59fa3f1700 h=5486015 c=R00014c11-01-603c9be1] INFO Core.EnfProfileComputer - getFinalSessionTimeout: sessionTimeout = 0 |
| 2021-03-01 08:47:34,038 | [RequestHandler-2-0x7f59fa3f1700 r=R00014c11-01-603c9be1 h=5486000 c=R00014c11-01-603c9be1] INFO Core.PETaskScheduler - ** Completed PETaskGenericEnfProfileBuilder ** |
| 2021-03-01 08:47:34,038 | [RequestHandler-2-0x7f59fa3f1700 r=R00014c11-01-603c9be1 h=5486000 c=R00014c11-01-603c9be1] INFO Core.PETaskScheduler - ** Completed PETaskAgentEnfProfileBuilder ** |
| 2021-03-01 08:47:34,038 | [RequestHandler-2-0x7f59fa3f1700 r=R00014c11-01-603c9be1 h=5486000 c=R00014c11-01-603c9be1] INFO Core.PETaskScheduler - ** Completed PETaskAppEnfProfileBuilder ** |
| 2021-03-01 08:47:34,038 | [RequestHandler-2-0x7f59fa3f1700 r=R00014c11-01-603c9be1 h=5486000 c=R00014c11-01-603c9be1] INFO Core.PETaskScheduler - ** Starting PETaskCliEnforcement ** |
| 2021-03-01 08:47:34,038 | [RequestHandler-2-0x7f59fa3f1700 h=5486023 c=R00014c11-01-603c9be1] INFO Core.PETaskCliEnforcement - startHandler: Request rejected. Skip CLI enforcement |
| 2021-03-01 08:47:34,039 | [RequestHandler-2-0x7f59fa3f1700 r=R00014c11-01-603c9be1 h=5486021 c=R00014c11-01-603c9be1] INFO Core.PETaskPostAuthEnfProfileBuilder - getApplicableProfiles: No Post auth enforcement profiles applicable for this device |
| 2021-03-01 08:47:34,039 | [RequestHandler-2-0x7f59fa3f1700 r=R00014c11-01-603c9be1 h=5486018 c=R00014c11-01-603c9be1] INFO Core.PETaskRadiusCoAEnfProfileBuilder - getApplicableProfiles: No radius_coa enforcement profiles applicable for this device |
| 2021-03-01 08:47:34,039 | [RequestHandler-2-0x7f59fa3f1700 r=R00014c11-01-603c9be1 h=5486000 c=R00014c11-01-603c9be1] INFO Core.PETaskScheduler - ** Completed PETaskRadiusEnfProfileBuilder ** |
| 2021-03-01 08:47:34,039 | [RequestHandler-2-0x7f59fa3f1700 r=R00014c11-01-603c9be1 h=5486000 c=R00014c11-01-603c9be1] INFO Core.PETaskScheduler - ** Completed PETaskCliEnforcement ** |
| 2021-03-01 08:47:34,039 | [RequestHandler-2-0x7f59fa3f1700 r=R00014c11-01-603c9be1 h=5486000 c=R00014c11-01-603c9be1] INFO Core.PETaskScheduler - ** Completed PETaskPostAuthEnfProfileBuilder ** |
| 2021-03-01 08:47:34,039 | [RequestHandler-2-0x7f59fa3f1700 r=R00014c11-01-603c9be1 h=5486000 c=R00014c11-01-603c9be1] INFO Core.PETaskScheduler - ** Completed PETaskRadiusCoAEnfProfileBuilder ** |
| 2021-03-01 08:47:34,039 | [RequestHandler-2-0x7f59fa3f1700 r=R00014c11-01-603c9be1 h=5486000 c=R00014c11-01-603c9be1] INFO Core.PETaskScheduler - ** Starting PETaskAuthStatusInfo ** |
| 2021-03-01 08:47:34,039 | [RequestHandler-2-0x7f59fa3f1700 r=R00014c11-01-603c9be1 h=5486000 c=R00014c11-01-603c9be1] INFO Core.PETaskScheduler - ** Starting PETaskOutputPolicyRes ** |
| 2021-03-01 08:47:34,039 | [RequestHandler-2-0x7f59fa3f1700 r=R00014c11-01-603c9be1 h=5486000 c=R00014c11-01-603c9be1] INFO Core.PETaskScheduler - ** Starting PETaskSessionLog ** |
| 2021-03-01 08:47:34,044 | [RequestHandler-2-0x7f59fa3f1700 h=5486026 c=R00014c11-01-603c9be1] INFO Core.XpipPolicyResHandler - populateResponseTlv: PETaskPostureOutput does not exist. Skip sending posture VAFs |
| 2021-03-01 08:47:34,044 | [RequestHandler-2-0x7f59fa3f1700 h=5486026 c=R00014c11-01-603c9be1] INFO Core.PolicyResCollector - getSohr: Failed to generate Sohr |
| 2021-03-01 08:47:34,044 | [RequestHandler-2-0x7f59fa3f1700 h=5486025 c=R00014c11-01-603c9be1] INFO Core.PolicyResCollector - getSohr: Failed to generate Sohr |
| 2021-03-01 08:47:34,044 | [RequestHandler-2-0x7f59fa3f1700 r=R00014c11-01-603c9be1 h=5486000 c=R00014c11-01-603c9be1] INFO Core.PETaskScheduler - ** Completed PETaskSessionLog ** |
| 2021-03-01 08:47:34,044 | [RequestHandler-2-0x7f59fa3f1700 r=R00014c11-01-603c9be1 h=5486000 c=R00014c11-01-603c9be1] INFO Core.PETaskScheduler - ** Completed PETaskOutputPolicyRes ** |
| 2021-03-01 08:47:34,044 | [RequestHandler-2-0x7f59fa3f1700 r=R00014c11-01-603c9be1 h=5486000 c=R00014c11-01-603c9be1] INFO Core.PETaskScheduler - ** Completed PETaskAuthStatusInfo ** |
| 2021-03-01 08:47:34,044 | [RequestHandler-2-0x7f59fa3f1700 r=R00014c11-01-603c9be1 h=5486000 c=R00014c11-01-603c9be1] INFO Core.PETaskScheduler - *** PE_TASK_SCHEDULE_RADIUS Completed *** |
| 2021-03-01 08:47:34,045 | [main SessId R00014c11-01-603c9be1] INFO RadiusServer.Radius - Policy Evaluation time = 15 ms |
| 2021-03-01 08:47:34,045 | [main SessId R00014c11-01-603c9be1] INFO RadiusServer.Radius - rlm_policy: Received Deny Enforcement Profile |
| 2021-03-01 08:47:34,045 | [main SessId R00014c11-01-603c9be1] INFO RadiusServer.Radius - rlm_policy: Policy Server reply does not contain Posture-Validation-Response |