Request log details for session: R00005c1e-15-53fef200

Time / Message
2014-08-28 12:10:24,350 / [Th 112 Req 148538 SessId R00005c1e-15-53fef200] INFO RadiusServer.Radius - rlm_service: Starting Service Categorization - 253:118:00-25-64-B5-FA-61
2014-08-28 12:10:24,353 / [RequestHandler-1-0x7f1c99b6c700 r=psauto-1408634439-133961 h=127 r=R00005c1e-15-53fef200] INFO Core.ServiceReqHandler - Service classification result = 802.1X Wired
2014-08-28 12:10:24,354 / [Th 112 Req 148538 SessId R00005c1e-15-53fef200] INFO RadiusServer.Radius - rlm_service: The request has been categorized into service "802.1X Wired"
2014-08-28 12:10:24,354 / [Th 112 Req 148538 SessId R00005c1e-15-53fef200] INFO RadiusServer.Radius - rlm_ldap: searching for user AUC\usama.s in AD:auc.egy
2014-08-28 12:10:24,355 / [Th 112 Req 148538 SessId R00005c1e-15-53fef200] INFO RadiusServer.Radius - rlm_ldap: found user AUC\usama.s in AD:auc.egy
2014-08-28 12:10:24,355 / [Th 112 Req 148538 SessId R00005c1e-15-53fef200] INFO RadiusServer.Radius - rlm_eap_mschapv2: Issuing Challenge
2014-08-28 12:10:24,355 / [Th 112 Req 148538 SessId R00005c1e-15-53fef200] INFO RadiusServer.Radius - reqst_update_state: Access-Challenge 253:107:00-25-64-B5-FA-61:0x0076002c003800593a4402007ff33153e216ab8650b1320774bf057a
2014-08-28 12:10:24,361 / [Th 109 Req 148539 SessId R00005c1e-15-53fef200] INFO RadiusServer.Radius - rlm_service: The request was categorized into service "802.1X Wired" - 254:138:00-25-64-B5-FA-61
2014-08-28 12:10:24,361 / [Th 109 Req 148539 SessId R00005c1e-15-53fef200] INFO RadiusServer.Radius - rlm_eap_peap: Initiate
2014-08-28 12:10:24,361 / [Th 109 Req 148539 SessId R00005c1e-15-53fef200] INFO RadiusServer.Radius - reqst_update_state: Access-Challenge 254:76:00-25-64-B5-FA-61:0x007d000300c1006b3b4402007546ba0fedcc2d45ff63ce84dcd853d0
2014-08-28 12:10:24,397 / [Th 108 Req 148540 SessId R00005c1e-15-53fef200] INFO RadiusServer.Radius - rlm_service: The request was categorized into service "802.1X Wired" - 255:269:00-25-64-B5-FA-61
2014-08-28 12:10:24,398 / [Th 108 Req 148540 SessId R00005c1e-15-53fef200] INFO RadiusServer.Radius - TLS_accept:error in SSLv3 read finished A
2014-08-28 12:10:24,398 / [Th 108 Req 148540 SessId R00005c1e-15-53fef200] INFO RadiusServer.Radius - reqst_update_state: Access-Challenge 255:224:00-25-64-B5-FA-61:0x004b007d00c3009a3c44020009cef6bf78c24b6f5e9182b574a5029c
2014-08-28 12:10:24,464 / [Th 110 Req 148541 SessId R00005c1e-15-53fef200] INFO RadiusServer.Radius - rlm_service: The request was categorized into service "802.1X Wired" - 0:201:00-25-64-B5-FA-61
2014-08-28 12:10:24,464 / [Th 110 Req 148541 SessId R00005c1e-15-53fef200] INFO RadiusServer.Radius - rlm_eap_peap: Session established.
2014-08-28 12:10:24,464 / [Th 110 Req 148541 SessId R00005c1e-15-53fef200] INFO RadiusServer.Radius - rlm_eap_peap: Skipping Phase2 because of session resumption & fast reconnect.
2014-08-28 12:10:24,464 / [Th 110 Req 148541 SessId R00005c1e-15-53fef200] INFO RadiusServer.Radius - reqst_update_state: Access-Challenge 0:113:00-25-64-B5-FA-61:0x0031006f0069009d3d440200e89f5bbf15572e61c4f891a6c07aa5dd
2014-08-28 12:10:24,472 / [Th 111 Req 148542 SessId R00005c1e-15-53fef200] INFO RadiusServer.Radius - rlm_service: The request was categorized into service "802.1X Wired" - 1:175:00-25-64-B5-FA-61
2014-08-28 12:10:24,472 / [Th 111 Req 148542 SessId R00005c1e-15-53fef200] INFO RadiusServer.Radius - reqst_update_state: Access-Challenge 1:113:00-25-64-B5-FA-61:0x00ba00f5005a00593e440200173bcbc2265da2d5fb1165c66ba3f032
2014-08-28 12:10:24,478 / [Th 112 Req 148543 SessId R00005c1e-15-53fef200] INFO RadiusServer.Radius - rlm_service: The request was categorized into service "802.1X Wired" - 2:191:00-25-64-B5-FA-61
2014-08-28 12:10:24,478 / [Th 112 Req 148543 SessId R00005c1e-15-53fef200] INFO RadiusServer.Radius - rlm_eap_mschapv2: Issuing Challenge
2014-08-28 12:10:24,478 / [Th 112 Req 148543 SessId R00005c1e-15-53fef200] INFO RadiusServer.Radius - reqst_update_state: Access-Challenge 2:145:00-25-64-B5-FA-61:0x002000c500d9001d3f44020079cc1914745ddd04607f2b379c0b817f
2014-08-28 12:10:24,574 / [Th 109 Req 148544 SessId R00005c1e-15-53fef200] INFO RadiusServer.Radius - rlm_service: The request was categorized into service "802.1X Wired" - 3:239:00-25-64-B5-FA-61
2014-08-28 12:10:24,574 / [Th 109 Req 148544 SessId R00005c1e-15-53fef200] INFO RadiusServer.Radius - rlm_service: The request was categorized into service "802.1X Wired" - 64:0:00-25-64-B5-FA-61
2014-08-28 12:10:24,574 / [Th 109 Req 148544 SessId R00005c1e-15-53fef200] INFO RadiusServer.Radius - rlm_eap_mschapv2: Received MSCHAPv2 Response from client
2014-08-28 12:10:24,574 / [Th 109 Req 148544 SessId R00005c1e-15-53fef200] INFO RadiusServer.Radius - rlm_mschap: authenticating user usama.s, domain AUC
2014-08-28 12:10:24,577 / [Th 109 Req 148544 SessId R00005c1e-15-53fef200] INFO RadiusServer.Radius - rlm_mschap: user usama.s authenticated successfully
2014-08-28 12:10:24,577 / [Th 109 Req 148544 SessId R00005c1e-15-53fef200] INFO RadiusServer.Radius - rlm_eap_mschapv2: Sending MSCHAPv2 Success reply
2014-08-28 12:10:24,577 / [Th 109 Req 148544 SessId R00005c1e-15-53fef200] INFO RadiusServer.Radius - reqst_update_state: Access-Challenge 3:161:00-25-64-B5-FA-61:0x0045006000ed004640440200a558daa9ae08f0c37213d1d0fe72f6c1
2014-08-28 12:10:24,591 / [Th 108 Req 148545 SessId R00005c1e-15-53fef200] INFO RadiusServer.Radius - rlm_service: The request was categorized into service "802.1X Wired" - 4:175:00-25-64-B5-FA-61
2014-08-28 12:10:24,591 / [Th 108 Req 148545 SessId R00005c1e-15-53fef200] INFO RadiusServer.Radius - rlm_service: The request was categorized into service "802.1X Wired" - 65:0:00-25-64-B5-FA-61
2014-08-28 12:10:24,591 / [Th 108 Req 148545 SessId R00005c1e-15-53fef200] INFO RadiusServer.Radius - rlm_eap_mschapv2: Received MSCHAPv2 Success from client
2014-08-28 12:10:24,591 / [Th 108 Req 148545 SessId R00005c1e-15-53fef200] INFO RadiusServer.Radius - rlm_policy: Starting Policy Evaluation.
2014-08-28 12:10:24,593 / [RequestHandler-1-0x7f1c99b6c700 r=psauto-1408634439-133964 h=135 r=R00005c1e-15-53fef200] INFO Common.EndpointTable - Returning EndpointSPtr for macAddr 002564b5fa61
2014-08-28 12:10:24,593 / [RequestHandler-1-0x7f1c99b6c700 r=psauto-1408634439-133964 h=135 r=R00005c1e-15-53fef200] INFO Common.TagDefinitionCacheTable - No InstanceTagDefCacheMap found for instance id = 3575 entity id = 29
2014-08-28 12:10:24,593 / [RequestHandler-1-0x7f1c99b6c700 r=psauto-1408634439-133964 h=135 r=R00005c1e-15-53fef200] INFO Common.TagDefinitionCacheTable - Building the TagDefMapTable for NAD instance=3575
2014-08-28 12:10:24,593 / [RequestHandler-1-0x7f1c99b6c700 r=psauto-1408634439-133964 h=135 r=R00005c1e-15-53fef200] INFO Common.TagDefinitionCacheTable - Built 0 tag(s) for NAD instanceId=3575|entityId=29
2014-08-28 12:10:24,593 / [RequestHandler-1-0x7f1c99b6c700 r=psauto-1408634439-133964 h=135 r=R00005c1e-15-53fef200] INFO TAT.TagAttrHolderBuilder - No tags built for instanceId=3575|entity=Device
2014-08-28 12:10:24,593 / [RequestHandler-1-0x7f1c99b6c700 r=psauto-1408634439-133964 h=135 r=R00005c1e-15-53fef200] INFO TAT.AluTagAttrHolderBuilder - buildAttrHolder: Tags cannot be built for instanceId=0 (NULL AuthLocalUser)
2014-08-28 12:10:24,593 / [RequestHandler-1-0x7f1c99b6c700 r=psauto-1408634439-133964 h=135 r=R00005c1e-15-53fef200] INFO TAT.GuTagAttrHolderBuilder - buildAttrHolder: Tags cannot be built for instanceId=0 (NULL GuestUser)
2014-08-28 12:10:24,594 / [RequestHandler-1-0x7f1c99b6c700 r=psauto-1408634439-133964 h=135 r=R00005c1e-15-53fef200] INFO TAT.OnboardTagAttrHolderBuilder - buildAttrHolder: Tags cannot be built for instanceId=0 (NULL Onboard Device User)
2014-08-28 12:10:24,594 / [RequestHandler-1-0x7f1c99b6c700 h=968474 c=R00005c1e-15-53fef200] INFO Core.PETaskScheduler - *** PE_TASK_SCHEDULE_RADIUS Started ***
2014-08-28 12:10:24,594 / [RequestHandler-1-0x7f1c99b6c700 h=968474 c=R00005c1e-15-53fef200] INFO Core.PETaskScheduler - ** Starting PETaskAuthSourceRestriction **
2014-08-28 12:10:24,594 / [RequestHandler-1-0x7f1c99b6c700 h=968474 c=R00005c1e-15-53fef200] INFO Core.PETaskScheduler - ** Starting PETaskRoleMapping **
2014-08-28 12:10:24,594 / [RequestHandler-1-0x7f1c99b6c700 h=968475 c=R00005c1e-15-53fef200] WARN REC.EvaluatorCtx - Prerequisites set is empty, not populating the Request Map
2014-08-28 12:10:24,594 / [RequestHandler-1-0x7f1c99b6c700 r=R00005c1e-15-53fef200 h=968474 c=R00005c1e-15-53fef200] INFO Core.PETaskScheduler - ** Completed PETaskAuthSourceRestriction **
2014-08-28 12:10:24,595 / [AuthReqThreadPool-13-0x7f1ce6df6700 r=R00005c1e-15-53fef200 h=38] WARN Util.ParameterizedString - getReplacedStrings: Failed to replace parameString =(&(sAMAccountName=%{Host:Name}$)(objectClass=computer)), error=No values for param=Host:Name
2014-08-28 12:10:24,595 / [AuthReqThreadPool-13-0x7f1ce6df6700 r=R00005c1e-15-53fef200 h=38] WARN Ldap.LdapQuery - execute: Failed to construct filter=(&(sAMAccountName=%{Host:Name}$)(objectClass=computer))
2014-08-28 12:10:24,595 / [AuthReqThreadPool-12-0x7f1ce6ff7700 r=R00005c1e-15-53fef200 h=37] WARN Util.ParameterizedString - getReplacedStrings: Failed to replace parameString =(&(sAMAccountName=%{Host:Name}$)(objectClass=computer)), error=No values for param=Host:Name
2014-08-28 12:10:24,595 / [AuthReqThreadPool-13-0x7f1ce6df6700 r=R00005c1e-15-53fef200 h=38] WARN Util.ParameterizedString - getReplacedStrings: Failed to replace parameString =(&(sAMAccountName=%{Onboard:Owner})(objectClass=user)), error=No values for param=Onboard:Owner
2014-08-28 12:10:24,595 / [AuthReqThreadPool-12-0x7f1ce6ff7700 r=R00005c1e-15-53fef200 h=37] WARN Ldap.LdapQuery - execute: Failed to construct filter=(&(sAMAccountName=%{Host:Name}$)(objectClass=computer))
2014-08-28 12:10:24,595 / [AuthReqThreadPool-13-0x7f1ce6df6700 r=R00005c1e-15-53fef200 h=38] WARN Ldap.LdapQuery - execute: Failed to construct filter=(&(sAMAccountName=%{Onboard:Owner})(objectClass=user))
2014-08-28 12:10:24,595 / [AuthReqThreadPool-13-0x7f1ce6df6700 r=R00005c1e-15-53fef200 h=38] WARN Ldap.LdapQuery - Failed to get value for attributes=Department, HostName, OSServicePack, Onboard memberOf, OperatingSystem, Phone, Title, cn, distinguishedName]
2014-08-28 12:10:24,595 / [AuthReqThreadPool-12-0x7f1ce6ff7700 r=R00005c1e-15-53fef200 h=37] WARN Util.ParameterizedString - getReplacedStrings: Failed to replace parameString =(&(sAMAccountName=%{Onboard:Owner})(objectClass=user)), error=No values for param=Onboard:Owner
2014-08-28 12:10:24,595 / [AuthReqThreadPool-12-0x7f1ce6ff7700 r=R00005c1e-15-53fef200 h=37] WARN Ldap.LdapQuery - execute: Failed to construct filter=(&(sAMAccountName=%{Onboard:Owner})(objectClass=user))
2014-08-28 12:10:24,595 / [AuthReqThreadPool-12-0x7f1ce6ff7700 r=R00005c1e-15-53fef200 h=37] WARN Util.ParameterizedString - getReplacedStrings: Failed to replace parameString =(distinguishedName=%{Onboard memberOf}), error=No values for param=Onboard memberOf
2014-08-28 12:10:24,595 / [AuthReqThreadPool-12-0x7f1ce6ff7700 r=R00005c1e-15-53fef200 h=37] WARN Ldap.LdapQuery - execute: Failed to construct filter=(distinguishedName=%{Onboard memberOf})
2014-08-28 12:10:24,595 / [AuthReqThreadPool-12-0x7f1ce6ff7700 r=R00005c1e-15-53fef200 h=37] WARN Ldap.LdapQuery - Failed to get value for attributes=Department, HostName, OSServicePack, Onboard Groups, Onboard Name, Onboard memberOf, OperatingSystem, Phone, Title]
2014-08-28 12:10:24,596 / [RequestHandler-1-0x7f1c99b6c700 h=968476 c=R00005c1e-15-53fef200] INFO Core.PETaskRoleMapping - Roles: Machine Authenticated], User Authenticated], staff
2014-08-28 12:10:24,596 / [RequestHandler-1-0x7f1c99b6c700 r=R00005c1e-15-53fef200 h=968474 c=R00005c1e-15-53fef200] INFO Core.PETaskScheduler - ** Completed PETaskRoleMapping **
2014-08-28 12:10:24,596 / [RequestHandler-1-0x7f1c99b6c700 r=R00005c1e-15-53fef200 h=968474 c=R00005c1e-15-53fef200] INFO Core.PETaskScheduler - ** Starting PETaskPolicyResult **
2014-08-28 12:10:24,597 / [RequestHandler-1-0x7f1c99b6c700 r=R00005c1e-15-53fef200 h=968478 c=R00005c1e-15-53fef200] ERROR Core.PETaskPolicyResult - handleHttpResponseEv: All policy result cache lookups failed
2014-08-28 12:10:24,598 / [RequestHandler-1-0x7f1c99b6c700 r=R00005c1e-15-53fef200 h=968474 c=R00005c1e-15-53fef200] INFO Core.PETaskScheduler - ** Completed PETaskPolicyResult **
2014-08-28 12:10:24,598 / [RequestHandler-1-0x7f1c99b6c700 r=R00005c1e-15-53fef200 h=968474 c=R00005c1e-15-53fef200] INFO Core.PETaskScheduler - ** Starting PETaskEnforcement **
2014-08-28 12:10:24,598 / [RequestHandler-1-0x7f1c99b6c700 h=968479 c=R00005c1e-15-53fef200] INFO Core.PETaskEnforcement - EnfProfiles: Set_Quarantine, Remedy-Vlan-Permenant
2014-08-28 12:10:24,598 / [RequestHandler-1-0x7f1c99b6c700 r=R00005c1e-15-53fef200 h=968474 c=R00005c1e-15-53fef200] INFO Core.PETaskScheduler - ** Completed PETaskEnforcement **
2014-08-28 12:10:24,598 / [RequestHandler-1-0x7f1c99b6c700 r=R00005c1e-15-53fef200 h=968474 c=R00005c1e-15-53fef200] INFO Core.PETaskScheduler - ** Starting PETaskRadiusEnfProfileBuilder **
2014-08-28 12:10:24,598 / [RequestHandler-1-0x7f1c99b6c700 r=R00005c1e-15-53fef200 h=968474 c=R00005c1e-15-53fef200] INFO Core.PETaskScheduler - ** Starting PETaskRadiusCoAEnfProfileBuilder **
2014-08-28 12:10:24,598 / [RequestHandler-1-0x7f1c99b6c700 r=R00005c1e-15-53fef200 h=968474 c=R00005c1e-15-53fef200] INFO Core.PETaskScheduler - ** Starting PETaskAppEnfProfileBuilder **
2014-08-28 12:10:24,599 / [RequestHandler-1-0x7f1c99b6c700 r=R00005c1e-15-53fef200 h=968474 c=R00005c1e-15-53fef200] INFO Core.PETaskScheduler - ** Starting PETaskPostAuthEnfProfileBuilder **
2014-08-28 12:10:24,599 / [RequestHandler-1-0x7f1c99b6c700 r=R00005c1e-15-53fef200 h=968474 c=R00005c1e-15-53fef200] INFO Core.PETaskScheduler - ** Starting PETaskGenericEnfProfileBuilder **
2014-08-28 12:10:24,599 / [RequestHandler-1-0x7f1c99b6c700 h=968484 c=R00005c1e-15-53fef200] INFO Core.PETaskGenericEnfProfileBuilder - getApplicableProfiles: No App enforcement (Generic) profiles applicable for this device
2014-08-28 12:10:24,599 / [RequestHandler-1-0x7f1c99b6c700 h=968482 c=R00005c1e-15-53fef200] ERROR Common.AppEnfProfileTable - getAppType: Failed for id=3171
2014-08-28 12:10:24,599 / [RequestHandler-1-0x7f1c99b6c700 h=968482 c=R00005c1e-15-53fef200] ERROR Common.AppEnfProfileCacheTable - asic_string<char, std::char_traits<char>, std::allocator<char> >, ValueType = ACE_Refcounted_Auto_PtrClientAuthInfo, ACE_Thread_Mutex>]
2014-08-28 12:10:24,599 / [RequestHandler-1-0x7f1c99b6c700 h=968480 c=R00005c1e-15-53fef200] INFO Core.PETaskRadiusEnfProfileBuilder - EnfProfileAction=ACCEPT
2014-08-28 12:10:24,599 / [RequestHandler-1-0x7f1c99b6c700 h=968480 c=R00005c1e-15-53fef200] INFO Core.PETaskRadiusEnfProfileBuilder - Radius enfProfiles used: Remedy-Vlan-Permenant
2014-08-28 12:10:24,599 / [RequestHandler-1-0x7f1c99b6c700 h=968480 c=R00005c1e-15-53fef200] INFO Core.EnfProfileComputer - getFinalSessionTimeout: sessionTimeout = 86400
2014-08-28 12:10:24,600 / [RequestHandler-1-0x7f1c99b6c700 r=R00005c1e-15-53fef200 h=968474 c=R00005c1e-15-53fef200] INFO Core.PETaskScheduler - ** Completed PETaskGenericEnfProfileBuilder **
2014-08-28 12:10:24,600 / [RequestHandler-1-0x7f1c99b6c700 r=R00005c1e-15-53fef200 h=968474 c=R00005c1e-15-53fef200] INFO Core.PETaskScheduler - ** Completed PETaskAppEnfProfileBuilder **
2014-08-28 12:10:24,600 / [RequestHandler-1-0x7f1c99b6c700 r=R00005c1e-15-53fef200 h=968474 c=R00005c1e-15-53fef200] INFO Core.PETaskScheduler - ** Starting PETaskCliEnforcement **
2014-08-28 12:10:24,600 / [RequestHandler-1-0x7f1c99b6c700 h=968485 c=R00005c1e-15-53fef200] INFO Core.PETaskCliEnforcement - startHandler: No commands for CLI enforcement
2014-08-28 12:10:24,600 / [RequestHandler-1-0x7f1c99b6c700 r=R00005c1e-15-53fef200 h=968483 c=R00005c1e-15-53fef200] INFO Core.PETaskPostAuthEnfProfileBuilder - Post auth enforcement profiles used: Set_Quarantine
2014-08-28 12:10:24,600 / [RequestHandler-1-0x7f1c99b6c700 r=R00005c1e-15-53fef200 h=968483 c=R00005c1e-15-53fef200] INFO Core.PETaskPostAuthEnfProfileBuilder - UnknownAutzParams to fetch for PostAuthEnfProfiles: :
2014-08-28 12:10:24,600 / [RequestHandler-1-0x7f1c99b6c700 r=R00005c1e-15-53fef200 h=968483 c=R00005c1e-15-53fef200] INFO Core.PETaskPostAuthEnfProfileBuilder - UnknownNAutzParams to fetch for PostAuthEnfProfiles: :
2014-08-28 12:10:24,601 / [RequestHandler-1-0x7f1c99b6c700 r=R00005c1e-15-53fef200 h=968481 c=R00005c1e-15-53fef200] INFO Core.PETaskRadiusCoAEnfProfileBuilder - getApplicableProfiles: No radius_coa enforcement profiles applicable for this device
2014-08-28 12:10:24,601 / [RequestHandler-1-0x7f1c99b6c700 r=R00005c1e-15-53fef200 h=968474 c=R00005c1e-15-53fef200] INFO Core.PETaskScheduler - ** Completed PETaskRadiusEnfProfileBuilder **
2014-08-28 12:10:24,601 / [RequestHandler-1-0x7f1c99b6c700 r=R00005c1e-15-53fef200 h=968474 c=R00005c1e-15-53fef200] INFO Core.PETaskScheduler - ** Completed PETaskCliEnforcement **
2014-08-28 12:10:24,601 / [RequestHandler-1-0x7f1c99b6c700 r=R00005c1e-15-53fef200 h=968474 c=R00005c1e-15-53fef200] INFO Core.PETaskScheduler - ** Completed PETaskPostAuthEnfProfileBuilder **
2014-08-28 12:10:24,601 / [RequestHandler-1-0x7f1c99b6c700 r=R00005c1e-15-53fef200 h=968474 c=R00005c1e-15-53fef200] INFO Core.PETaskScheduler - ** Completed PETaskRadiusCoAEnfProfileBuilder **
2014-08-28 12:10:24,601 / [RequestHandler-1-0x7f1c99b6c700 r=R00005c1e-15-53fef200 h=968474 c=R00005c1e-15-53fef200] INFO Core.PETaskScheduler - ** Starting PETaskAuthStatusInfo **
2014-08-28 12:10:24,601 / [RequestHandler-1-0x7f1c99b6c700 r=R00005c1e-15-53fef200 h=968474 c=R00005c1e-15-53fef200] INFO Core.PETaskScheduler - ** Starting PETaskOutputPolicyRes **
2014-08-28 12:10:24,601 / [RequestHandler-1-0x7f1c99b6c700 r=R00005c1e-15-53fef200 h=968474 c=R00005c1e-15-53fef200] INFO Core.PETaskScheduler - ** Starting PETaskSessionLog **
2014-08-28 12:10:24,603 / [RequestHandler-1-0x7f1c99b6c700 h=968487 c=R00005c1e-15-53fef200] INFO Core.XpipPolicyResHandler - populateResponseTlv: PETaskPostureOutput does not exist. Skip sending posture VAFs
2014-08-28 12:10:24,603 / [RequestHandler-1-0x7f1c99b6c700 h=968487 c=R00005c1e-15-53fef200] INFO Core.PolicyResCollector - getSohr: Failed to generate Sohr
2014-08-28 12:10:24,603 / [RequestHandler-1-0x7f1c99b6c700 h=968486 c=R00005c1e-15-53fef200] INFO Core.PolicyResCollector - getSohr: Failed to generate Sohr
2014-08-28 12:10:24,604 / [Th 108 Req 148545 SessId R00005c1e-15-53fef200] INFO RadiusServer.Radius - rlm_policy: Received Accept Enforcement Profile
2014-08-28 12:10:24,604 / [Th 108 Req 148545 SessId R00005c1e-15-53fef200] INFO RadiusServer.Radius - rlm_policy: Policy Server reply does not contain Posture-Validation-Response
2014-08-28 12:10:24,604 / [Th 108 Req 148545 SessId R00005c1e-15-53fef200] INFO RadiusServer.Radius - reqst_update_state: Access-Challenge 4:113:00-25-64-B5-FA-61:0x00e1008f004300db414402001fe0eefa103045a1caf29768961958ee
2014-08-28 12:10:24,604 / [RequestHandler-1-0x7f1c99b6c700 r=R00005c1e-15-53fef200 h=968474 c=R00005c1e-15-53fef200] INFO Core.PETaskScheduler - ** Completed PETaskSessionLog **
2014-08-28 12:10:24,604 / [RequestHandler-1-0x7f1c99b6c700 r=R00005c1e-15-53fef200 h=968474 c=R00005c1e-15-53fef200] INFO Core.PETaskScheduler - ** Completed PETaskOutputPolicyRes **
2014-08-28 12:10:24,604 / [RequestHandler-1-0x7f1c99b6c700 r=R00005c1e-15-53fef200 h=968474 c=R00005c1e-15-53fef200] INFO Core.PETaskScheduler - ** Completed PETaskAuthStatusInfo **
2014-08-28 12:10:24,604 / [RequestHandler-1-0x7f1c99b6c700 r=R00005c1e-15-53fef200 h=968474 c=R00005c1e-15-53fef200] INFO Core.PETaskScheduler - *** PE_TASK_SCHEDULE_RADIUS Completed ***
2014-08-28 12:10:24,609 / [Th 110 Req 148546 SessId R00005c1e-15-53fef200] INFO RadiusServer.Radius - rlm_service: The request was categorized into service "802.1X Wired" - 5:175:00-25-64-B5-FA-61
2014-08-28 12:10:24,609 / [Th 110 Req 148546 SessId R00005c1e-15-53fef200] INFO RadiusServer.Radius - rlm_policy: Bypassing Policy Evaluation.
2014-08-28 12:10:24,609 / [Th 110 Req 148546 SessId R00005c1e-15-53fef200] INFO RadiusServer.Radius - rlm_policy: Added Class attribute with value Class = 0x63d7beb236254e4d96d401024520dd48bf0b0000000000005230303030356331652d31352d35336665663230300000000000000000000000