API tools faq
paste
Advertisement
tecoholic

oxuth-debug-log a

tecoholic
Mar 30th, 2016
2,809
0
Never
Add comment
Not a member of Pastebin yet? Sign Up, it unlocks many cool features!
Openoffice BASIC 13.95 KB | None | 0 0
raw download clone embed print report
  1. INFO   | jvm 1    | 2016/03/30 17:25:08 | 2016-03-30 17:25:08,763 DEBUG [org.xdi.oxauth.service.external.ExternalAuthenticationService] Executing python 'authenticate' authenticator method
  2. INFO   | jvm 1    | 2016/03/30 17:25:08 | 2016-03-30 17:25:08,803 DEBUG [org.xdi.oxauth.service.AuthenticationService] Authenticating user with LDAP: username: admin
  3. INFO   | jvm 1    | 2016/03/30 17:25:08 | 2016-03-30 17:25:08,826 DEBUG [org.xdi.oxauth.service.AuthenticationService] Attempting to find userDN by primary key: 'uid' and key value: 'admin'
  4. INFO   | jvm 1    | 2016/03/30 17:25:08 | 2016-03-30 17:25:08,826 DEBUG [org.xdi.oxauth.service.AuthenticationService] Getting user information from LDAP: attributeName = 'uid', attributeValue = 'admin'
  5. INFO   | jvm 1    | 2016/03/30 17:25:08 | 2016-03-30 17:25:08,871 DEBUG [org.xdi.oxauth.service.AuthenticationService] Found '1' entries
  6. INFO   | jvm 1    | 2016/03/30 17:25:08 | 2016-03-30 17:25:08,882 DEBUG [org.xdi.oxauth.service.AuthenticationService] Attempting to authenticate userDN: inum=@!759B.55C7.4942.00EA!0001!9B71.FFC6!0000!A8F2.DE1E.D7FB,ou=people,o=@!759B.55C7.4942.00EA!0001!9B71.FFC6,o=gluu
  7. INFO   | jvm 1    | 2016/03/30 17:25:08 | 2016-03-30 17:25:08,888 DEBUG [org.xdi.oxauth.service.AuthenticationService] User authenticated: inum=@!759B.55C7.4942.00EA!0001!9B71.FFC6!0000!A8F2.DE1E.D7FB,ou=people,o=@!759B.55C7.4942.00EA!0001!9B71.FFC6,o=gluu
  8. INFO   | jvm 1    | 2016/03/30 17:25:08 | 2016-03-30 17:25:08,888 DEBUG [org.xdi.oxauth.service.AuthenticationService] Attempting to find userDN by local primary key: uid
  9. INFO   | jvm 1    | 2016/03/30 17:25:08 | 2016-03-30 17:25:08,889 DEBUG [org.xdi.oxauth.service.UserService] Getting user information from LDAP: attributeName = 'uid', attributeValue = 'admin'
  10. INFO   | jvm 1    | 2016/03/30 17:25:08 | 2016-03-30 17:25:08,894 DEBUG [org.xdi.oxauth.service.UserService] Found '1' entries
  11. INFO   | jvm 1    | 2016/03/30 17:25:08 | 2016-03-30 17:25:08,909 DEBUG [org.xdi.oxauth.auth.Authenticator] Authentication result for user 'admin'. auth_step: '1', result: 'true'
  12. INFO   | jvm 1    | 2016/03/30 17:25:08 | 2016-03-30 17:25:08,910 DEBUG [org.xdi.oxauth.service.external.ExternalAuthenticationService] Executing python 'getCountAuthenticationSteps' authenticator method
  13. INFO   | jvm 1    | 2016/03/30 17:25:08 | 2016-03-30 17:25:08,946 DEBUG [org.xdi.oxauth.auth.Authenticator] Sending event to trigger user redirection: 'admin'
  14. INFO   | jvm 1    | 2016/03/30 17:25:08 | 2016-03-30 17:25:08,949 INFO  [org.xdi.oxauth.service.AuthenticationService] Attempting to redirect user. SessionUser: SessionState, dn='uniqueIdentifier=48927839-e31a-45c4-aa42-ce1449c19564,ou=session,o=@!759B.55C7.4942.00EA!0001!9B71.FFC6,o=gluu', id='48927839-e31a-45c4-aa42-ce1449c19564', lastUsedAt=Wed Mar 30 17:25:08 UTC 2016, userDn='inum=@!759B.55C7.4942.00EA!0001!9B71.FFC6!0000!A8F2.DE1E.D7FB,ou=people,o=@!759B.55C7.4942.00EA!0001!9B71.FFC6,o=gluu', authenticationTime=Wed Mar 30 17:25:08 UTC 2016, state=authenticated, permissionGranted=null, permissionGrantedMap=null, sessionAttributes={scope=openid profile email user_name, response_type=code id_token, nonce=nonce, redirect_uri=https://gluu.example.com/identity/authentication/authcode, auth_step=1, client_id=@!759B.55C7.4942.00EA!0001!9B71.FFC6!0008!A80C.3119, acr=internal}, persisted=true}
  15. INFO   | jvm 1    | 2016/03/30 17:25:08 | 2016-03-30 17:25:08,954 INFO  [org.xdi.oxauth.service.AuthenticationService] Attempting to redirect user. User: org.xdi.oxauth.model.common.User@65239b14
  16. INFO   | jvm 1    | 2016/03/30 17:25:09 | 2016-03-30 17:25:08,996 INFO  [org.xdi.oxauth.auth.Authenticator] Authentication success for User: 'admin'
  17. INFO   | jvm 1    | 2016/03/30 17:25:09 | 2016-03-30 17:25:09,107 DEBUG [org.xdi.oxauth.service.ClientService] Found 1 entries for client id = @!759B.55C7.4942.00EA!0001!9B71.FFC6!0008!A80C.3119
  18. INFO   | jvm 1    | 2016/03/30 17:25:09 | 2016-03-30 17:25:09,109 DEBUG [org.xdi.oxauth.service.ClientService] Found 1 entries for client id = @!759B.55C7.4942.00EA!0001!9B71.FFC6!0008!A80C.3119
  19. INFO   | jvm 1    | 2016/03/30 17:25:09 | 2016-03-30 17:25:09,109 DEBUG [org.xdi.oxauth.service.RedirectionUriService] Validating redirection URI: clientIdentifier = @!759B.55C7.4942.00EA!0001!9B71.FFC6!0008!A80C.3119, redirectionUri = https://gluu.example.com/identity/authentication/authcode, found = 3
  20. INFO   | jvm 1    | 2016/03/30 17:25:09 | 2016-03-30 17:25:09,109 DEBUG [org.xdi.oxauth.service.RedirectionUriService] Comparing https://gluu.example.com/identity/scim/auth == https://gluu.example.com/identity/authentication/authcode
  21. INFO   | jvm 1    | 2016/03/30 17:25:09 | 2016-03-30 17:25:09,109 DEBUG [org.xdi.oxauth.service.RedirectionUriService] Comparing https://gluu.example.com/identity/authentication/authcode == https://gluu.example.com/identity/authentication/authcode
  22. INFO   | jvm 1    | 2016/03/30 17:25:09 | 2016-03-30 17:25:09,162 DEBUG [org.xdi.oxauth.service.ClientService] Found 1 entries for client id = @!759B.55C7.4942.00EA!0001!9B71.FFC6!0008!A80C.3119
  23. INFO   | jvm 1    | 2016/03/30 17:25:09 | 2016-03-30 17:25:09,476 DEBUG [xdi.oxauth.authorize.ws.rs.AuthorizeRestWebServiceImpl] Attempting to request authorization: responseType = code id_token, clientId = @!759B.55C7.4942.00EA!0001!9B71.FFC6!0008!A80C.3119, scope = openid profile email user_name, redirectUri = https://gluu.example.com/identity/authentication/authcode, nonce = nonce, state = null, request = null, isSecure = true, requestSessionState = null, sessionState = null
  24. INFO   | jvm 1    | 2016/03/30 17:25:09 | 2016-03-30 17:25:09,476 DEBUG [xdi.oxauth.authorize.ws.rs.AuthorizeRestWebServiceImpl] Attempting to request authorization: acrValues = null, amrValues = null, originHeaders = {4}
  25. INFO   | jvm 1    | 2016/03/30 17:25:09 | 2016-03-30 17:25:09,504 DEBUG [org.xdi.oxauth.service.ClientService] Found 1 entries for client id = @!759B.55C7.4942.00EA!0001!9B71.FFC6!0008!A80C.3119
  26. INFO   | jvm 1    | 2016/03/30 17:25:09 | 2016-03-30 17:25:09,505 DEBUG [org.xdi.oxauth.model.authorize.ScopeChecker] Checking scopes policy for: openid profile email user_name
  27. INFO   | jvm 1    | 2016/03/30 17:25:09 | 2016-03-30 17:25:09,633 DEBUG [org.xdi.oxauth.model.authorize.ScopeChecker] Granted scopes: [user_name, email, openid, profile]
  28. INFO   | jvm 1    | 2016/03/30 17:25:09 | 2016-03-30 17:25:09,639 DEBUG [org.xdi.oxauth.service.ClientService] Found 1 entries for client id = @!759B.55C7.4942.00EA!0001!9B71.FFC6!0008!A80C.3119
  29. INFO   | jvm 1    | 2016/03/30 17:25:09 | 2016-03-30 17:25:09,640 DEBUG [org.xdi.oxauth.service.RedirectionUriService] Validating redirection URI: clientIdentifier = @!759B.55C7.4942.00EA!0001!9B71.FFC6!0008!A80C.3119, redirectionUri = https://gluu.example.com/identity/authentication/authcode, found = 3
  30. INFO   | jvm 1    | 2016/03/30 17:25:09 | 2016-03-30 17:25:09,640 DEBUG [org.xdi.oxauth.service.RedirectionUriService] Comparing https://gluu.example.com/identity/scim/auth == https://gluu.example.com/identity/authentication/authcode
  31. INFO   | jvm 1    | 2016/03/30 17:25:09 | 2016-03-30 17:25:09,640 DEBUG [org.xdi.oxauth.service.RedirectionUriService] Comparing https://gluu.example.com/identity/authentication/authcode == https://gluu.example.com/identity/authentication/authcode
  32. INFO   | jvm 1    | 2016/03/30 17:25:10 | 2016-03-30 17:25:10,382 DEBUG [org.xdi.oxauth.model.authorize.ScopeChecker] Checking scopes policy for: openid profile email user_name
  33. INFO   | jvm 1    | 2016/03/30 17:25:10 | 2016-03-30 17:25:10,467 DEBUG [org.xdi.oxauth.model.authorize.ScopeChecker] Granted scopes: [user_name, email, openid, profile]
  34. INFO   | jvm 1    | 2016/03/30 17:25:11 | 2016-03-30 17:25:11,533 INFO  [org.gluu.oxtrust.action.Authenticator] authorizationCode : 5645b4a9-d28e-42b5-99d2-85d416aec7af
  35. INFO   | jvm 1    | 2016/03/30 17:25:11 | 2016-03-30 17:25:11,539 INFO  [org.gluu.oxtrust.action.Authenticator]  scopes : user_name email openid profile
  36. INFO   | jvm 1    | 2016/03/30 17:25:11 | 2016-03-30 17:25:11,539 INFO  [org.gluu.oxtrust.action.Authenticator] clientID : @!759B.55C7.4942.00EA!0001!9B71.FFC6!0008!A80C.3119
  37. INFO   | jvm 1    | 2016/03/30 17:25:11 | 2016-03-30 17:25:11,539 INFO  [org.gluu.oxtrust.action.Authenticator] getting accessToken
  38. INFO   | jvm 1    | 2016/03/30 17:25:11 | 2016-03-30 17:25:11,539 INFO  [org.gluu.oxtrust.action.Authenticator] tokenURL : https://gluu.example.com/oxauth/seam/resource/restv1/oxauth/token
  39. INFO   | jvm 1    | 2016/03/30 17:25:11 | 2016-03-30 17:25:11,544 INFO  [org.gluu.oxtrust.action.Authenticator] Sending request to token endpoint
  40. INFO   | jvm 1    | 2016/03/30 17:25:11 | 2016-03-30 17:25:11,545 INFO  [org.gluu.oxtrust.action.Authenticator] redirectURI : https://gluu.example.com/identity/authentication/authcode
  41. INFO   | jvm 1    | 2016/03/30 17:25:12 | 2016-03-30 17:25:12,045 DEBUG [org.xdi.oxauth.service.ClientService] Found 1 entries for client id = @!759B.55C7.4942.00EA!0001!9B71.FFC6!0008!A80C.3119
  42. INFO   | jvm 1    | 2016/03/30 17:25:12 | 2016-03-30 17:25:12,047 DEBUG [org.xdi.oxauth.service.ClientService] Authenticating Client with LDAP: clientId = @!759B.55C7.4942.00EA!0001!9B71.FFC6!0008!A80C.3119
  43. INFO   | jvm 1    | 2016/03/30 17:25:12 | 2016-03-30 17:25:12,047 DEBUG [org.xdi.oxauth.service.ClientService] Found 1 entries for client id = @!759B.55C7.4942.00EA!0001!9B71.FFC6!0008!A80C.3119
  44. INFO   | jvm 1    | 2016/03/30 17:25:12 | 2016-03-30 17:25:12,071 DEBUG [org.xdi.oxauth.service.ClientService] Found 1 entries for client id = @!759B.55C7.4942.00EA!0001!9B71.FFC6!0008!A80C.3119
  45. INFO   | jvm 1    | 2016/03/30 17:25:12 | 2016-03-30 17:25:12,094 INFO  [org.xdi.oxauth.auth.Authenticator] Authentication success for Client: '@!759B.55C7.4942.00EA!0001!9B71.FFC6!0008!A80C.3119'
  46. INFO   | jvm 1    | 2016/03/30 17:25:12 | 2016-03-30 17:25:12,108 DEBUG [xdi.oxauth.token.ws.rs.TokenRestWebServiceImpl] Attempting to request access token: grantType = authorization_code, code = 5645b4a9-d28e-42b5-99d2-85d416aec7af, redirectUri = https://gluu.example.com/identity/authentication/authcode, username = null, refreshToken = null, clientId = null, ExtraParams = {grant_type=[Ljava.lang.String;@123d16e5, redirect_uri=[Ljava.lang.String;@68aa1dbd, code=[Ljava.lang.String;@788698f9}, isSecure = true
  47. INFO   | jvm 1    | 2016/03/30 17:25:12 | 2016-03-30 17:25:12,118 DEBUG [org.xdi.oxauth.service.UserService] Getting user information from LDAP: userId = admin
  48. INFO   | jvm 1    | 2016/03/30 17:25:12 | 2016-03-30 17:25:12,144 DEBUG [org.xdi.oxauth.service.UserService] Found 1 entries for user id = admin
  49. INFO   | jvm 1    | 2016/03/30 17:25:12 | 2016-03-30 17:25:12,162 DEBUG [org.xdi.oxauth.service.ClientService] Found 1 entries for client id = @!759B.55C7.4942.00EA!0001!9B71.FFC6!0008!A80C.3119
  50. INFO   | jvm 1    | 2016/03/30 17:25:12 | 2016-03-30 17:25:12,298 DEBUG [xdi.oxauth.token.ws.rs.TokenRestWebServiceImpl] Issuing access token: 0c14c2b4-76e0-42b0-87ba-c347a45d13a3
  51. INFO   | jvm 1    | 2016/03/30 17:25:13 | 2016-03-30 17:25:12,899 INFO  [org.gluu.oxtrust.action.Authenticator]  tokenResponse : org.xdi.oxauth.client.TokenResponse@3b3b2418
  52. INFO   | jvm 1    | 2016/03/30 17:25:13 | 2016-03-30 17:25:12,899 INFO  [org.gluu.oxtrust.action.Authenticator]  tokenResponse.getErrorType() : null
  53. INFO   | jvm 1    | 2016/03/30 17:25:13 | 2016-03-30 17:25:12,899 INFO  [org.gluu.oxtrust.action.Authenticator]  accessToken : 0c14c2b4-76e0-42b0-87ba-c347a45d13a3
  54. INFO   | jvm 1    | 2016/03/30 17:25:13 | 2016-03-30 17:25:12,899 INFO  [org.gluu.oxtrust.action.Authenticator]  validating AccessToken
  55. INFO   | jvm 1    | 2016/03/30 17:25:13 | 2016-03-30 17:25:12,985 DEBUG [xdi.oxauth.token.ws.rs.ValidateTokenRestWebServiceImpl] Attempting to validate access token: 0c14c2b4-76e0-42b0-87ba-c347a45d13a3, Is Secure = true
  56. INFO   | jvm 1    | 2016/03/30 17:25:13 | 2016-03-30 17:25:13,014 DEBUG [org.xdi.oxauth.service.UserService] Getting user information from LDAP: userId = admin
  57. INFO   | jvm 1    | 2016/03/30 17:25:13 | 2016-03-30 17:25:13,018 DEBUG [org.xdi.oxauth.service.UserService] Found 1 entries for user id = admin
  58. INFO   | jvm 1    | 2016/03/30 17:25:13 | 2016-03-30 17:25:13,019 DEBUG [org.xdi.oxauth.service.ClientService] Found 1 entries for client id = @!759B.55C7.4942.00EA!0001!9B71.FFC6!0008!A80C.3119
  59. INFO   | jvm 1    | 2016/03/30 17:25:13 | 2016-03-30 17:25:13,023 INFO  [org.gluu.oxtrust.action.Authenticator]  response3.getStatus() : 200
  60. INFO   | jvm 1    | 2016/03/30 17:25:13 | 2016-03-30 17:25:13,023 INFO  [org.gluu.oxtrust.action.Authenticator] validate check session status:200
  61. INFO   | jvm 1    | 2016/03/30 17:25:13 | 2016-03-30 17:25:13,023 INFO  [org.gluu.oxtrust.action.Authenticator] Session validation successful. User is logged in
  62. INFO   | jvm 1    | 2016/03/30 17:25:13 | 2016-03-30 17:25:13,135 DEBUG [xdi.oxauth.userinfo.ws.rs.UserInfoRestWebServiceImpl] Attempting to request User Info, Access token = 0c14c2b4-76e0-42b0-87ba-c347a45d13a3, Is Secure = true
  63. INFO   | jvm 1    | 2016/03/30 17:25:13 | 2016-03-30 17:25:13,152 DEBUG [org.xdi.oxauth.service.UserService] Getting user information from LDAP: userId = admin
  64. INFO   | jvm 1    | 2016/03/30 17:25:13 | 2016-03-30 17:25:13,162 DEBUG [org.xdi.oxauth.service.UserService] Found 1 entries for user id = admin
  65. INFO   | jvm 1    | 2016/03/30 17:25:13 | 2016-03-30 17:25:13,163 DEBUG [org.xdi.oxauth.service.ClientService] Found 1 entries for client id = @!759B.55C7.4942.00EA!0001!9B71.FFC6!0008!A80C.3119
  66. INFO   | jvm 1    | 2016/03/30 17:25:13 | 2016-03-30 17:25:13,248 INFO  [org.gluu.oxtrust.action.Authenticator] user uid:admin
  67. INFO   | jvm 1    | 2016/03/30 17:25:13 | 2016-03-30 17:25:13,383 INFO  [org.gluu.oxtrust.action.Authenticator] Authenticating user 'admin'
  68. INFO   | jvm 1    | 2016/03/30 17:25:13 | 2016-03-30 17:25:13,413 DEBUG [org.gluu.oxtrust.action.Authenticator] Configuring application after user 'admin' login
  69. INFO   | jvm 1    | 2016/03/30 17:25:13 | 2016-03-30 17:25:13,900 DEBUG [org.gluu.oxtrust.action.Authenticator] Get '[manager]' user roles
  70. INFO   | jvm 1    | 2016/03/30 17:25:13 | 2016-03-30 17:25:13,900 INFO  [org.gluu.oxtrust.action.Authenticator] User 'admin' authenticated successfully
Advertisement
Add Comment
Please, Sign In to add comment
Advertisement
Public Pastes
Advertisement
We use cookies for various purposes including analytics. By continuing to use Pastebin, you agree to our use of cookies as described in the Cookies Policy.  OK, I Understand
Not a member of Pastebin yet?
Sign Up, it unlocks many cool features!