Advertisement
Not a member of Pastebin yet?
Sign Up,
it unlocks many cool features!
- INFO | jvm 1 | 2016/03/30 17:00:23 | 2016-03-30 17:00:23,060 DEBUG [org.xdi.oxauth.service.external.ExternalAuthenticationService] Executing python 'authenticate' authenticator method
- INFO | jvm 1 | 2016/03/30 17:00:23 | 2016-03-30 17:00:23,061 DEBUG [org.xdi.oxauth.service.AuthenticationService] Authenticating user with LDAP: username: admin
- INFO | jvm 1 | 2016/03/30 17:00:23 | 2016-03-30 17:00:23,073 DEBUG [org.xdi.oxauth.service.AuthenticationService] Attempting to find userDN by primary key: 'uid' and key value: 'admin'
- INFO | jvm 1 | 2016/03/30 17:00:23 | 2016-03-30 17:00:23,074 DEBUG [org.xdi.oxauth.service.AuthenticationService] Getting user information from LDAP: attributeName = 'uid', attributeValue = 'admin'
- INFO | jvm 1 | 2016/03/30 17:00:23 | 2016-03-30 17:00:23,101 DEBUG [org.xdi.oxauth.service.AuthenticationService] Found '1' entries
- INFO | jvm 1 | 2016/03/30 17:00:23 | 2016-03-30 17:00:23,105 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
- INFO | jvm 1 | 2016/03/30 17:00:23 | 2016-03-30 17:00:23,107 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
- INFO | jvm 1 | 2016/03/30 17:00:23 | 2016-03-30 17:00:23,107 DEBUG [org.xdi.oxauth.service.AuthenticationService] Attempting to find userDN by local primary key: uid
- INFO | jvm 1 | 2016/03/30 17:00:23 | 2016-03-30 17:00:23,108 DEBUG [org.xdi.oxauth.service.UserService] Getting user information from LDAP: attributeName = 'uid', attributeValue = 'admin'
- INFO | jvm 1 | 2016/03/30 17:00:23 | 2016-03-30 17:00:23,112 DEBUG [org.xdi.oxauth.service.UserService] Found '1' entries
- INFO | jvm 1 | 2016/03/30 17:00:23 | 2016-03-30 17:00:23,125 DEBUG [org.xdi.oxauth.auth.Authenticator] Authentication result for user 'admin'. auth_step: '1', result: 'true'
- INFO | jvm 1 | 2016/03/30 17:00:23 | 2016-03-30 17:00:23,125 DEBUG [org.xdi.oxauth.service.external.ExternalAuthenticationService] Executing python 'getCountAuthenticationSteps' authenticator method
- INFO | jvm 1 | 2016/03/30 17:00:23 | 2016-03-30 17:00:23,146 DEBUG [org.xdi.oxauth.auth.Authenticator] Sending event to trigger user redirection: 'admin'
- INFO | jvm 1 | 2016/03/30 17:00:23 | 2016-03-30 17:00:23,168 INFO [org.xdi.oxauth.service.AuthenticationService] Attempting to redirect user. SessionUser: SessionState, dn='uniqueIdentifier=5372ea4f-cfbf-46f3-a505-1e7ea50b36e1,ou=session,o=@!759B.55C7.4942.00EA!0001!9B71.FFC6,o=gluu', id='5372ea4f-cfbf-46f3-a505-1e7ea50b36e1', lastUsedAt=Wed Mar 30 17:00:23 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:00:23 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}
- INFO | jvm 1 | 2016/03/30 17:00:23 | 2016-03-30 17:00:23,172 INFO [org.xdi.oxauth.service.AuthenticationService] Attempting to redirect user. User: org.xdi.oxauth.model.common.User@13542cae
- INFO | jvm 1 | 2016/03/30 17:00:23 | 2016-03-30 17:00:23,233 INFO [org.xdi.oxauth.auth.Authenticator] Authentication success for User: 'admin'
- INFO | jvm 1 | 2016/03/30 17:00:23 | 2016-03-30 17:00:23,394 DEBUG [org.xdi.oxauth.service.ClientService] Found 1 entries for client id = @!759B.55C7.4942.00EA!0001!9B71.FFC6!0008!A80C.3119
- INFO | jvm 1 | 2016/03/30 17:00:23 | 2016-03-30 17:00:23,395 DEBUG [org.xdi.oxauth.service.ClientService] Found 1 entries for client id = @!759B.55C7.4942.00EA!0001!9B71.FFC6!0008!A80C.3119
- INFO | jvm 1 | 2016/03/30 17:00:23 | 2016-03-30 17:00:23,395 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
- INFO | jvm 1 | 2016/03/30 17:00:23 | 2016-03-30 17:00:23,395 DEBUG [org.xdi.oxauth.service.RedirectionUriService] Comparing https://gluu.example.com/identity/scim/auth == https://gluu.example.com/identity/authentication/authcode
- INFO | jvm 1 | 2016/03/30 17:00:23 | 2016-03-30 17:00:23,395 DEBUG [org.xdi.oxauth.service.RedirectionUriService] Comparing https://gluu.example.com/identity/authentication/authcode == https://gluu.example.com/identity/authentication/authcode
- INFO | jvm 1 | 2016/03/30 17:00:23 | 2016-03-30 17:00:23,437 DEBUG [org.xdi.oxauth.service.ClientService] Found 1 entries for client id = @!759B.55C7.4942.00EA!0001!9B71.FFC6!0008!A80C.3119
- INFO | jvm 1 | 2016/03/30 17:00:23 | 2016-03-30 17:00:23,629 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
- INFO | jvm 1 | 2016/03/30 17:00:23 | 2016-03-30 17:00:23,629 DEBUG [xdi.oxauth.authorize.ws.rs.AuthorizeRestWebServiceImpl] Attempting to request authorization: acrValues = null, amrValues = null, originHeaders = {4}
- INFO | jvm 1 | 2016/03/30 17:00:23 | 2016-03-30 17:00:23,634 DEBUG [org.xdi.oxauth.service.ClientService] Found 1 entries for client id = @!759B.55C7.4942.00EA!0001!9B71.FFC6!0008!A80C.3119
- INFO | jvm 1 | 2016/03/30 17:00:23 | 2016-03-30 17:00:23,634 DEBUG [org.xdi.oxauth.model.authorize.ScopeChecker] Checking scopes policy for: openid profile email user_name
- INFO | jvm 1 | 2016/03/30 17:00:23 | 2016-03-30 17:00:23,684 DEBUG [org.xdi.oxauth.model.authorize.ScopeChecker] Granted scopes: [user_name, email, openid, profile]
- INFO | jvm 1 | 2016/03/30 17:00:23 | 2016-03-30 17:00:23,685 DEBUG [org.xdi.oxauth.service.ClientService] Found 1 entries for client id = @!759B.55C7.4942.00EA!0001!9B71.FFC6!0008!A80C.3119
- INFO | jvm 1 | 2016/03/30 17:00:23 | 2016-03-30 17:00:23,685 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
- INFO | jvm 1 | 2016/03/30 17:00:23 | 2016-03-30 17:00:23,685 DEBUG [org.xdi.oxauth.service.RedirectionUriService] Comparing https://gluu.example.com/identity/scim/auth == https://gluu.example.com/identity/authentication/authcode
- INFO | jvm 1 | 2016/03/30 17:00:23 | 2016-03-30 17:00:23,685 DEBUG [org.xdi.oxauth.service.RedirectionUriService] Comparing https://gluu.example.com/identity/authentication/authcode == https://gluu.example.com/identity/authentication/authcode
- INFO | jvm 1 | 2016/03/30 17:00:23 | 2016-03-30 17:00:23,743 ERROR [xdi.oxauth.authorize.ws.rs.AuthorizeRestWebServiceImpl]
- INFO | jvm 1 | 2016/03/30 17:00:23 | java.lang.NullPointerException
- INFO | jvm 1 | 2016/03/30 17:00:23 | at org.xdi.oxauth.authorize.ws.rs.AuthorizeRestWebServiceImpl.requestAuthorization(AuthorizeRestWebServiceImpl.java:499)
- INFO | jvm 1 | 2016/03/30 17:00:23 | at org.xdi.oxauth.authorize.ws.rs.AuthorizeRestWebServiceImpl.requestAuthorizationGet(AuthorizeRestWebServiceImpl.java:103)
- INFO | jvm 1 | 2016/03/30 17:00:23 | at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
- INFO | jvm 1 | 2016/03/30 17:00:23 | at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
- INFO | jvm 1 | 2016/03/30 17:00:23 | at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
- INFO | jvm 1 | 2016/03/30 17:00:23 | at java.lang.reflect.Method.invoke(Method.java:606)
- INFO | jvm 1 | 2016/03/30 17:00:23 | at org.jboss.seam.util.Reflections.invoke(Reflections.java:22)
- INFO | jvm 1 | 2016/03/30 17:00:23 | at org.jboss.seam.intercept.RootInvocationContext.proceed(RootInvocationContext.java:32)
- INFO | jvm 1 | 2016/03/30 17:00:23 | at org.jboss.seam.intercept.SeamInvocationContext.proceed(SeamInvocationContext.java:56)
- INFO | jvm 1 | 2016/03/30 17:00:23 | at org.jboss.seam.resteasy.ResteasyContextInjectionInterceptor.aroundInvoke(ResteasyContextInjectionInterceptor.java:59)
- INFO | jvm 1 | 2016/03/30 17:00:23 | at org.jboss.seam.intercept.SeamInvocationContext.proceed(SeamInvocationContext.java:68)
- INFO | jvm 1 | 2016/03/30 17:00:23 | at org.jboss.seam.transaction.RollbackInterceptor.aroundInvoke(RollbackInterceptor.java:28)
- INFO | jvm 1 | 2016/03/30 17:00:23 | at org.jboss.seam.intercept.SeamInvocationContext.proceed(SeamInvocationContext.java:68)
- INFO | jvm 1 | 2016/03/30 17:00:23 | at org.jboss.seam.core.BijectionInterceptor.aroundInvoke(BijectionInterceptor.java:77)
- INFO | jvm 1 | 2016/03/30 17:00:23 | at org.jboss.seam.intercept.SeamInvocationContext.proceed(SeamInvocationContext.java:68)
- INFO | jvm 1 | 2016/03/30 17:00:23 | at org.jboss.seam.core.MethodContextInterceptor.aroundInvoke(MethodContextInterceptor.java:44)
- INFO | jvm 1 | 2016/03/30 17:00:23 | at org.jboss.seam.intercept.SeamInvocationContext.proceed(SeamInvocationContext.java:68)
- INFO | jvm 1 | 2016/03/30 17:00:23 | at org.jboss.seam.intercept.RootInterceptor.invoke(RootInterceptor.java:107)
- INFO | jvm 1 | 2016/03/30 17:00:23 | at org.jboss.seam.intercept.JavaBeanInterceptor.interceptInvocation(JavaBeanInterceptor.java:185)
- INFO | jvm 1 | 2016/03/30 17:00:23 | at org.jboss.seam.intercept.JavaBeanInterceptor.invoke(JavaBeanInterceptor.java:103)
- INFO | jvm 1 | 2016/03/30 17:00:23 | at org.xdi.oxauth.authorize.ws.rs.AuthorizeRestWebServiceImpl_$$_javassist_seam_49.requestAuthorizationGet(AuthorizeRestWebServiceImpl_$$_javassist_seam_49.java)
- INFO | jvm 1 | 2016/03/30 17:00:23 | at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
- INFO | jvm 1 | 2016/03/30 17:00:23 | at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
- INFO | jvm 1 | 2016/03/30 17:00:23 | at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
- INFO | jvm 1 | 2016/03/30 17:00:23 | at java.lang.reflect.Method.invoke(Method.java:606)
- INFO | jvm 1 | 2016/03/30 17:00:23 | at org.jboss.resteasy.core.MethodInjectorImpl.invoke(MethodInjectorImpl.java:167)
- INFO | jvm 1 | 2016/03/30 17:00:23 | at org.jboss.resteasy.core.ResourceMethod.invokeOnTarget(ResourceMethod.java:269)
- INFO | jvm 1 | 2016/03/30 17:00:23 | at org.jboss.resteasy.core.ResourceMethod.invoke(ResourceMethod.java:227)
- INFO | jvm 1 | 2016/03/30 17:00:23 | at org.jboss.resteasy.core.ResourceMethod.invoke(ResourceMethod.java:216)
- INFO | jvm 1 | 2016/03/30 17:00:23 | at org.jboss.resteasy.core.SynchronousDispatcher.getResponse(SynchronousDispatcher.java:542)
- INFO | jvm 1 | 2016/03/30 17:00:23 | at org.jboss.resteasy.core.SynchronousDispatcher.invoke(SynchronousDispatcher.java:524)
- INFO | jvm 1 | 2016/03/30 17:00:23 | at org.jboss.resteasy.core.SynchronousDispatcher.invoke(SynchronousDispatcher.java:126)
- INFO | jvm 1 | 2016/03/30 17:00:23 | at org.jboss.seam.resteasy.ResteasyResourceAdapter$1.process(ResteasyResourceAdapter.java:145)
- INFO | jvm 1 | 2016/03/30 17:00:23 | at org.jboss.seam.servlet.ContextualHttpServletRequest.run(ContextualHttpServletRequest.java:65)
- INFO | jvm 1 | 2016/03/30 17:00:23 | at org.jboss.seam.resteasy.ResteasyResourceAdapter.getResource(ResteasyResourceAdapter.java:120)
- INFO | jvm 1 | 2016/03/30 17:00:23 | at org.jboss.seam.servlet.SeamResourceServlet.service(SeamResourceServlet.java:80)
- INFO | jvm 1 | 2016/03/30 17:00:23 | at javax.servlet.http.HttpServlet.service(HttpServlet.java:727)
- INFO | jvm 1 | 2016/03/30 17:00:23 | at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:303)
- INFO | jvm 1 | 2016/03/30 17:00:23 | at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:208)
- INFO | jvm 1 | 2016/03/30 17:00:23 | at org.apache.tomcat.websocket.server.WsFilter.doFilter(WsFilter.java:52)
- INFO | jvm 1 | 2016/03/30 17:00:23 | at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:241)
- INFO | jvm 1 | 2016/03/30 17:00:23 | at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:208)
- INFO | jvm 1 | 2016/03/30 17:00:23 | at org.jboss.seam.servlet.SeamFilter$FilterChainImpl.doFilter(SeamFilter.java:83)
- INFO | jvm 1 | 2016/03/30 17:00:23 | at org.jboss.seam.web.RewriteFilter.doFilter(RewriteFilter.java:63)
- INFO | jvm 1 | 2016/03/30 17:00:23 | at org.jboss.seam.servlet.SeamFilter$FilterChainImpl.doFilter(SeamFilter.java:69)
- INFO | jvm 1 | 2016/03/30 17:00:23 | at org.jboss.seam.web.LoggingFilter.doFilter(LoggingFilter.java:60)
- INFO | jvm 1 | 2016/03/30 17:00:23 | at org.jboss.seam.servlet.SeamFilter$FilterChainImpl.doFilter(SeamFilter.java:69)
- INFO | jvm 1 | 2016/03/30 17:00:23 | at org.xdi.oxauth.auth.AuthenticationFilter.processSessionAuth(AuthenticationFilter.java:140)
- INFO | jvm 1 | 2016/03/30 17:00:23 | at org.xdi.oxauth.auth.AuthenticationFilter.access$500(AuthenticationFilter.java:59)
- INFO | jvm 1 | 2016/03/30 17:00:23 | at org.xdi.oxauth.auth.AuthenticationFilter$1.process(AuthenticationFilter.java:113)
- INFO | jvm 1 | 2016/03/30 17:00:23 | at org.jboss.seam.servlet.ContextualHttpServletRequest.run(ContextualHttpServletRequest.java:65)
- INFO | jvm 1 | 2016/03/30 17:00:23 | at org.xdi.oxauth.auth.AuthenticationFilter.doFilter(AuthenticationFilter.java:73)
- INFO | jvm 1 | 2016/03/30 17:00:23 | at org.jboss.seam.servlet.SeamFilter$FilterChainImpl.doFilter(SeamFilter.java:69)
- INFO | jvm 1 | 2016/03/30 17:00:23 | at org.jboss.seam.web.IdentityFilter.doFilter(IdentityFilter.java:40)
- INFO | jvm 1 | 2016/03/30 17:00:23 | at org.jboss.seam.servlet.SeamFilter$FilterChainImpl.doFilter(SeamFilter.java:69)
- INFO | jvm 1 | 2016/03/30 17:00:23 | at org.jboss.seam.web.MultipartFilter.doFilter(MultipartFilter.java:90)
- INFO | jvm 1 | 2016/03/30 17:00:23 | at org.jboss.seam.servlet.SeamFilter$FilterChainImpl.doFilter(SeamFilter.java:69)
- INFO | jvm 1 | 2016/03/30 17:00:23 | at org.jboss.seam.web.ExceptionFilter.doFilter(ExceptionFilter.java:64)
- INFO | jvm 1 | 2016/03/30 17:00:23 | at org.jboss.seam.servlet.SeamFilter$FilterChainImpl.doFilter(SeamFilter.java:69)
- INFO | jvm 1 | 2016/03/30 17:00:23 | at org.jboss.seam.web.RedirectFilter.doFilter(RedirectFilter.java:45)
- INFO | jvm 1 | 2016/03/30 17:00:23 | at org.jboss.seam.servlet.SeamFilter$FilterChainImpl.doFilter(SeamFilter.java:69)
- INFO | jvm 1 | 2016/03/30 17:00:23 | at org.jboss.seam.servlet.SeamFilter.doFilter(SeamFilter.java:158)
- INFO | jvm 1 | 2016/03/30 17:00:23 | at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:241)
- INFO | jvm 1 | 2016/03/30 17:00:23 | at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:208)
- INFO | jvm 1 | 2016/03/30 17:00:23 | at org.apache.catalina.core.StandardWrapperValve.invoke(StandardWrapperValve.java:220)
- INFO | jvm 1 | 2016/03/30 17:00:23 | at org.apache.catalina.core.StandardContextValve.invoke(StandardContextValve.java:122)
- INFO | jvm 1 | 2016/03/30 17:00:23 | at org.apache.catalina.authenticator.AuthenticatorBase.invoke(AuthenticatorBase.java:501)
- INFO | jvm 1 | 2016/03/30 17:00:23 | at org.apache.catalina.core.StandardHostValve.invoke(StandardHostValve.java:171)
- INFO | jvm 1 | 2016/03/30 17:00:23 | at org.apache.catalina.valves.ErrorReportValve.invoke(ErrorReportValve.java:103)
- INFO | jvm 1 | 2016/03/30 17:00:23 | at org.apache.catalina.core.StandardEngineValve.invoke(StandardEngineValve.java:116)
- INFO | jvm 1 | 2016/03/30 17:00:23 | at org.apache.catalina.connector.CoyoteAdapter.service(CoyoteAdapter.java:408)
- INFO | jvm 1 | 2016/03/30 17:00:23 | at org.apache.coyote.ajp.AjpProcessor.process(AjpProcessor.java:190)
- INFO | jvm 1 | 2016/03/30 17:00:23 | at org.apache.coyote.AbstractProtocol$AbstractConnectionHandler.process(AbstractProtocol.java:611)
- INFO | jvm 1 | 2016/03/30 17:00:23 | at org.apache.tomcat.util.net.JIoEndpoint$SocketProcessor.run(JIoEndpoint.java:314)
- INFO | jvm 1 | 2016/03/30 17:00:23 | at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145)
- INFO | jvm 1 | 2016/03/30 17:00:23 | at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
- INFO | jvm 1 | 2016/03/30 17:00:23 | at org.apache.tomcat.util.threads.TaskThread$WrappingRunnable.run(TaskThread.java:61)
- INFO | jvm 1 | 2016/03/30 17:00:23 | at java.lang.Thread.run(Thread.java:745)
Advertisement
Add Comment
Please, Sign In to add comment
Advertisement