| Time | Message |
|---|---|
| 2014-03-09 10:15:07,943 | [Th 41 Req 3966 SessId R000001ab-01-531c14fb] INFO RadiusServer.Radius - rlm_service: Starting Service Categorization - 121:190:4C2578556454 |
| 2014-03-09 10:15:07,946 | [Th 41 Req 3966 SessId R000001ab-01-531c14fb] INFO RadiusServer.Radius - rlm_service: The request has been categorized into service "CMUQ Guest MAC Authentication" |
| 2014-03-09 10:15:07,946 | [RequestHandler-1-0x7f3843bfd700 r=psauto-1393918268-1409 h=223 r=R000001ab-01-531c14fb] INFO Core.ServiceReqHandler - Service classification result = CMUQ Guest MAC Authentication |
| 2014-03-09 10:15:07,947 | [Th 41 Req 3966 SessId R000001ab-01-531c14fb] INFO RadiusServer.Radius - rlm_sql: searching for user 4C2578556454 in Local:localhost |
| 2014-03-09 10:15:07,947 | [Th 41 Req 3966 SessId R000001ab-01-531c14fb] INFO RadiusServer.Radius - rlm_sql: searching for user 4C2578556454 in Local:localhost |
| 2014-03-09 10:15:07,948 | [Th 41 Req 3966 SessId R000001ab-01-531c14fb] INFO RadiusServer.Radius - rlm_sql: searching for user 4C2578556454 in Local:localhost |
| 2014-03-09 10:15:07,948 | [Th 41 Req 3966 SessId R000001ab-01-531c14fb] INFO RadiusServer.Radius - rlm_sql: searching for user 4C2578556454 in Local:172.20.48.143 |
| 2014-03-09 10:15:07,950 | [Th 41 Req 3966 SessId R000001ab-01-531c14fb] INFO RadiusServer.Radius - rlm_sql: searching for user 4C2578556454 in Local:localhost |
| 2014-03-09 10:15:07,950 | [Th 41 Req 3966 SessId R000001ab-01-531c14fb] INFO RadiusServer.Radius - rlm_policy: Starting Policy Evaluation. |
| 2014-03-09 10:15:07,950 | [Th 41 Req 3966 SessId R000001ab-01-531c14fb] INFO RadiusServer.Radius - rlm_policy: Request does not have Authentication-Source-Name attribute |
| 2014-03-09 10:15:07,951 | [RequestHandler-1-0x7f3843bfd700 r=psauto-1393918268-1410 h=239 r=R000001ab-01-531c14fb] INFO Common.EndpointTable - Returning NULL (EndpointPtr) for macAddr 4c2578556454 |
| 2014-03-09 10:15:07,951 | [RequestHandler-1-0x7f3843bfd700 r=psauto-1393918268-1410 h=239 r=R000001ab-01-531c14fb] INFO Common.TagDefinitionCacheTable - No InstanceTagDefCacheMap found for instance id = 3003 entity id = 29 |
| 2014-03-09 10:15:07,951 | [RequestHandler-1-0x7f3843bfd700 r=psauto-1393918268-1410 h=239 r=R000001ab-01-531c14fb] INFO Common.TagDefinitionCacheTable - Building the TagDefMapTable for NAD instance=3003 |
| 2014-03-09 10:15:07,951 | [RequestHandler-1-0x7f3843bfd700 r=psauto-1393918268-1410 h=239 r=R000001ab-01-531c14fb] INFO Common.TagDefinitionCacheTable - Built 0 tag(s) for NAD instanceId=3003|entityId=29 |
| 2014-03-09 10:15:07,951 | [RequestHandler-1-0x7f3843bfd700 r=psauto-1393918268-1410 h=239 r=R000001ab-01-531c14fb] INFO TAT.TagAttrHolderBuilder - No tags built for instanceId=3003|entity=Device |
| 2014-03-09 10:15:07,952 | [RequestHandler-1-0x7f3843bfd700 r=psauto-1393918268-1410 h=239 r=R000001ab-01-531c14fb] INFO TAT.AluTagAttrHolderBuilder - buildAttrHolder: Tags cannot be built for instanceId=0 (NULL AuthLocalUser) |
| 2014-03-09 10:15:07,952 | [RequestHandler-1-0x7f3843bfd700 r=psauto-1393918268-1410 h=239 r=R000001ab-01-531c14fb] INFO TAT.GuTagAttrHolderBuilder - buildAttrHolder: Tags cannot be built for instanceId=0 (NULL GuestUser) |
| 2014-03-09 10:15:07,952 | [RequestHandler-1-0x7f3843bfd700 r=psauto-1393918268-1410 h=239 r=R000001ab-01-531c14fb] INFO TAT.EndpointTagAttrHolderBuilder - buildAttrHolder: Tags cannot be built for instanceId=0 (NULL Endpoint) |
| 2014-03-09 10:15:07,952 | [RequestHandler-1-0x7f3843bfd700 r=psauto-1393918268-1410 h=239 r=R000001ab-01-531c14fb] INFO TAT.OnboardTagAttrHolderBuilder - buildAttrHolder: Tags cannot be built for instanceId=0 (NULL Onboard Device User) |
| 2014-03-09 10:15:07,952 | [RequestHandler-1-0x7f3843bfd700 h=11011 c=R000001ab-01-531c14fb] INFO Core.PETaskScheduler - *** PE_TASK_SCHEDULE_RADIUS Started *** |
| 2014-03-09 10:15:07,953 | [AuthReqThreadPool-12-0x7f39471f8700 r=R000001ab-01-531c14fb h=74] WARN Util.ParameterizedString - getReplacedStrings: Failed to replace parameString =SELECT FLOOR(EXTRACT(EPOCH FROM (NOW() - timestamp)))::integer AS seconds_since_auth, FLOOR((EXTRACT(EPOCH FROM (NOW() - timestamp)))/60)::integer AS minutes_since_auth, FLOOR((EXTRACT(EPOCH FROM (NOW() - timestamp)))/3600)::integer AS hours_since_auth, FLOOR((EXTRACT(EPOCH FROM (NOW() - timestamp)))/86400)::integer AS days_since_auth FROM auth WHERE auth.timestamp < NOW() AND auth.error_code = 0 AND auth.username = '%{Endpoint:Username}' AND auth.mac = '%{Connection:Client-Mac-Address-NoDelim}' ORDER BY timestamp DESC LIMIT 1, error=No values for param=Endpoint:Username |
| 2014-03-09 10:15:07,953 | [AuthReqThreadPool-12-0x7f39471f8700 r=R000001ab-01-531c14fb h=74] ERROR ExtDB.DBQuery - execute: Failed to construct filter=SELECT FLOOR(EXTRACT(EPOCH FROM (NOW() - timestamp)))::integer AS seconds_since_auth, FLOOR((EXTRACT(EPOCH FROM (NOW() - timestamp)))/60)::integer AS minutes_since_auth, FLOOR((EXTRACT(EPOCH FROM (NOW() - timestamp)))/3600)::integer AS hours_since_auth, FLOOR((EXTRACT(EPOCH FROM (NOW() - timestamp)))/86400)::integer AS days_since_auth FROM auth WHERE auth.timestamp < NOW() AND auth.error_code = 0 AND auth.username = '%{Endpoint:Username}' AND auth.mac = '%{Connection:Client-Mac-Address-NoDelim}' ORDER BY timestamp DESC LIMIT 1 |
| 2014-03-09 10:15:07,953 | [AuthReqThreadPool-12-0x7f39471f8700 r=R000001ab-01-531c14fb h=74] ERROR ExtDB.DBQuery - Failed to get value for attributes=Days-Since-Auth, Hours-Since-Auth, Minutes-Since-Auth, Seconds-Since-Auth] |
| 2014-03-09 10:15:07,953 | [RequestHandler-1-0x7f3843bfd700 r=R000001ab-01-531c14fb h=11013 c=R000001ab-01-531c14fb] INFO Core.PETaskRoleMapping - Roles: MAC Caching], User Authenticated] |
| 2014-03-09 10:15:07,954 | [AuthReqThreadPool-22-0x7f3945dee700 r=R000001ab-01-531c14fb h=74] WARN Util.ParameterizedString - getReplacedStrings: Failed to replace parameString =SELECT user_id as guest_device_user FROM tips_guest_users WHERE ((guest_type = 'USER') AND (user_id = '%{Endpoint:Username}') AND (app_name != 'Onboard') AND (enabled = 't') AND ((expire_time is null) OR (expire_time > CURRENT_TIMESTAMP))), error=No values for param=Endpoint:Username |
| 2014-03-09 10:15:07,954 | [AuthReqThreadPool-22-0x7f3945dee700 r=R000001ab-01-531c14fb h=74] ERROR ExtDB.DBQuery - execute: Failed to construct filter=SELECT user_id as guest_device_user FROM tips_guest_users WHERE ((guest_type = 'USER') AND (user_id = '%{Endpoint:Username}') AND (app_name != 'Onboard') AND (enabled = 't') AND ((expire_time is null) OR (expire_time > CURRENT_TIMESTAMP))) |
| 2014-03-09 10:15:07,954 | [AuthReqThreadPool-22-0x7f3945dee700 r=R000001ab-01-531c14fb h=74] ERROR ExtDB.DBQuery - Failed to get value for attributes=UserName] |
| 2014-03-09 10:15:07,954 | [AuthReqThreadPool-30-0x7f3944de6700 r=R000001ab-01-531c14fb h=74] WARN Util.ParameterizedString - getReplacedStrings: Failed to replace parameString =SELECT FLOOR(EXTRACT(EPOCH FROM (NOW() - timestamp)))::integer AS seconds_since_auth, FLOOR((EXTRACT(EPOCH FROM (NOW() - timestamp)))/60)::integer AS minutes_since_auth, FLOOR((EXTRACT(EPOCH FROM (NOW() - timestamp)))/3600)::integer AS hours_since_auth, FLOOR((EXTRACT(EPOCH FROM (NOW() - timestamp)))/86400)::integer AS days_since_auth FROM auth WHERE auth.timestamp < NOW() AND auth.error_code = 0 AND auth.username = '%{Endpoint:Username}' AND auth.mac = '%{Connection:Client-Mac-Address-NoDelim}' ORDER BY timestamp DESC LIMIT 1, error=No values for param=Endpoint:Username |
| 2014-03-09 10:15:07,954 | [AuthReqThreadPool-30-0x7f3944de6700 r=R000001ab-01-531c14fb h=74] ERROR ExtDB.DBQuery - execute: Failed to construct filter=SELECT FLOOR(EXTRACT(EPOCH FROM (NOW() - timestamp)))::integer AS seconds_since_auth, FLOOR((EXTRACT(EPOCH FROM (NOW() - timestamp)))/60)::integer AS minutes_since_auth, FLOOR((EXTRACT(EPOCH FROM (NOW() - timestamp)))/3600)::integer AS hours_since_auth, FLOOR((EXTRACT(EPOCH FROM (NOW() - timestamp)))/86400)::integer AS days_since_auth FROM auth WHERE auth.timestamp < NOW() AND auth.error_code = 0 AND auth.username = '%{Endpoint:Username}' AND auth.mac = '%{Connection:Client-Mac-Address-NoDelim}' ORDER BY timestamp DESC LIMIT 1 |
| 2014-03-09 10:15:07,954 | [AuthReqThreadPool-30-0x7f3944de6700 r=R000001ab-01-531c14fb h=74] ERROR ExtDB.DBQuery - Failed to get value for attributes=Days-Since-Auth] |
| 2014-03-09 10:15:07,955 | [RequestHandler-1-0x7f3843bfd700 h=11016 c=R000001ab-01-531c14fb] INFO Core.PETaskEnforcement - EnfProfiles: Deny Access Profile] |
| 2014-03-09 10:15:07,955 | [RequestHandler-1-0x7f3843bfd700 h=11022 c=R000001ab-01-531c14fb] INFO Core.PETaskGenericEnfProfileBuilder - getApplicableProfiles: No App enforcement (Generic) profiles applicable for this device |
| 2014-03-09 10:15:07,955 | [RequestHandler-1-0x7f3843bfd700 h=11018 c=R000001ab-01-531c14fb] INFO Core.PETaskRadiusEnfProfileBuilder - EnfProfileAction=DENY |
| 2014-03-09 10:15:07,955 | [RequestHandler-1-0x7f3843bfd700 h=11018 c=R000001ab-01-531c14fb] INFO Core.PETaskRadiusEnfProfileBuilder - Radius enfProfiles used: Deny Access Profile] |
| 2014-03-09 10:15:07,955 | [RequestHandler-1-0x7f3843bfd700 h=11018 c=R000001ab-01-531c14fb] INFO Core.EnfProfileComputer - getFinalSessionTimeout: sessionTimeout = 0 |
| 2014-03-09 10:15:07,956 | [RequestHandler-1-0x7f3843bfd700 h=11023 c=R000001ab-01-531c14fb] INFO Core.PETaskCliEnforcement - startHandler: Request rejected. Skip CLI enforcement |
| 2014-03-09 10:15:07,956 | [RequestHandler-1-0x7f3843bfd700 r=R000001ab-01-531c14fb h=11019 c=R000001ab-01-531c14fb] INFO Core.PETaskRadiusCoAEnfProfileBuilder - getApplicableProfiles: No radius_coa enforcement profiles applicable for this device |
| 2014-03-09 10:15:07,956 | [RequestHandler-1-0x7f3843bfd700 r=R000001ab-01-531c14fb h=11021 c=R000001ab-01-531c14fb] INFO Core.PETaskPostAuthEnfProfileBuilder - getApplicableProfiles: No Post auth enforcement profiles applicable for this device |
| 2014-03-09 10:15:07,959 | [Th 41 Req 3966 SessId R000001ab-01-531c14fb] INFO RadiusServer.Radius - rlm_policy: Received Deny Enforcement Profile |
| 2014-03-09 10:15:07,959 | [Th 41 Req 3966 SessId R000001ab-01-531c14fb] INFO RadiusServer.Radius - rlm_policy: Policy Server reply does not contain Posture-Validation-Response |
| 2014-03-09 10:15:07,959 | [RequestHandler-1-0x7f3843bfd700 h=11026 c=R000001ab-01-531c14fb] INFO Core.XpipPolicyResHandler - populateResponseTlv: PETaskPostureOutput does not exist. Skip sending posture VAFs |
| 2014-03-09 10:15:07,959 | [RequestHandler-1-0x7f3843bfd700 h=11026 c=R000001ab-01-531c14fb] INFO Core.PolicyResCollector - getSohr: Failed to generate Sohr |
| 2014-03-09 10:15:07,959 | [RequestHandler-1-0x7f3843bfd700 h=11025 c=R000001ab-01-531c14fb] WARN Core.RadiusEnfProfileHelper - getSessionTimeoutInSecs: SessionTimeout attribute missing in output |
| 2014-03-09 10:15:07,959 | [RequestHandler-1-0x7f3843bfd700 h=11024 c=R000001ab-01-531c14fb] INFO Core.PolicyResCollector - getSohr: Failed to generate Sohr |
| 2014-03-09 10:15:07,960 | [RequestHandler-1-0x7f3843bfd700 r=R000001ab-01-531c14fb h=11011 c=R000001ab-01-531c14fb] INFO Core.PETaskScheduler - *** PE_TASK_SCHEDULE_RADIUS Completed *** |