By: Farhan Ahmed user 23 Jul 2015 at 9:11 a.m. CDT

18 Responses
Farhan Ahmed gravatar
I have configured apache Shibboleth SP and also configured Shibboleth SP metadata in GLUU server succesfully. When i try to access: https://sp.example.com/Shibboleth.sso/Session I get this message: A valid session was not found. I also configured the parameters in the shibboleth2.xml file as per the instructions in the docs but getting this when trying to access a protected site: Trying to get a protected resource: https://sp.example.com/secure shibsp::ConfigurationException The system encountered an error at Thu Jul 23 01:46:32 2015 To report this problem, please contact the site administrator at root@localhost. Please include the following message in any email: shibsp::ConfigurationException at (https://sp.example.com/secure) No MetadataProvider available.

By Michael Schwartz Account Admin 23 Jul 2015 at 9:20 a.m. CDT

Michael Schwartz gravatar
Did you check the sample Google Code in [github](https://github.com/GluuFederation/oxAuth/tree/master/Server/integrations/gplus)

By Michael Schwartz Account Admin 23 Jul 2015 at 9:21 a.m. CDT

Michael Schwartz gravatar
Oh, I see you meant SAML. Did you read this [article](http://www.gluu.org/docs/articles/google-saml/) For Shib SP configuration instructions, check these [docs](http://www.gluu.org/docs/admin-guide/saml/saml-sp-configuration/)

By Farhan Ahmed user 23 Jul 2015 at 9:43 a.m. CDT

Farhan Ahmed gravatar
So it means that Google can't be setup as authentication method other than default ldap. What I mean is that only via Federation we can have Google come into picture during authentication. In other words is this possible: 1) User tries to access protected resource 2) GLUU redirects the user to Google page for authentication 3) After authentication, GLUU first check user is in its local repo matching or not if not then dynamically creating it. 4) Re-directs the user to the protected resource (Granting access) The sample in github has no instructions. Can we get a high level instructions where to put what script and what needs to be done in the GLUU server admin console itself related with the sample. And can you advise why i am getting "No MetadataProvider available." when accessing a protected resource from shibboleth SP. Thanks.

By Mohib Zico staff 23 Jul 2015 at 9:50 a.m. CDT

Mohib Zico gravatar
>> And can you advise why i am getting "No MetadataProvider available." when accessing a protected resource from shibboleth SP. This is pretty common Shibboleth SP error. I think if you google; you will get lot of related answers. Mostly the problem is in your SP's shibboleth2.xml. The translation of this error is: Shibboleth SP is not getting IDP's metadata.

By Farhan Ahmed user 23 Jul 2015 at 9:57 a.m. CDT

Farhan Ahmed gravatar
Mohib, What about the answer to the following question: So it means that Google can't be setup as authentication method other than default ldap. What I mean is that only via Federation we can have Google come into picture during authentication. In other words is this possible: 1) User tries to access protected resource 2) GLUU redirects the user to Google page for authentication 3) After authentication, GLUU first check user is in its local repo matching or not if not then dynamically creating it. 4) Re-directs the user to the protected resource (Granting access) The sample in github has no instructions. Can we get a high level instructions where to put what script and what needs to be done in the GLUU server admin console itself related with the sample. Thanks.

By Michael Schwartz Account Admin 23 Jul 2015 at 2:54 p.m. CDT

Michael Schwartz gravatar
Please review the [docs on custom authentication scripts](http://www.gluu.org/docs/reference/interception-scripts/#authentication) This [workflow.txt](https://github.com/GluuFederation/oxAuth/blob/master/Server/integrations/gplus/workflow.txt) is also included in the [github folder](https://github.com/GluuFederation/oxAuth/tree/master/Server/integrations/gplus) for this script. Fastest procedure is : 1) Create a custom authentication script and paste in the sample code. 2) Set the default authentication mode to "google" (or whatever you name it when you add the script) 3) Try to login to oxTrust... (you can use it as the test RP) It may make sense to continue your testing with the OpenID Connect apache module so you can request the "google" acr param.

By Farhan Ahmed user 23 Jul 2015 at 5:18 p.m. CDT

Farhan Ahmed gravatar
I did the following: Create a new custom script by using the GooglePlusExternalAuthenticator.py and added 5 properties as per the snapshot from the custom_script_entry.ldif After saving that i changed the Manage Authentication from Default to this new Custom Script (GoolgeCustomScript) Also i placed the file at: /etc/certs/gplus_client_secrets.json (which i downloaded from google for my app). GLUU.root@gluu:/etc/certs# cat gplus_client_secrets.json {"web":{"client_id":"1011868174354-94h15m0ahpqeafo5k99ui8c26v1nm7l3.apps.googleusercontent.com","auth_uri":"https://accounts.google.com/o/oauth2/auth","token_uri":"https://accounts.google.com/o/oauth2/token","auth_provider_x509_cert_url":"https://www.googleapis.com/oauth2/v1/certs","client_email":"","client_x509_cert_url":"","client_secret":"S669TbRRsQbU6Y3Jrj3-9Zgw","redirect_uris":["https://openid.example.com/protected/redirect_doesnt_exist_on_apache"],"javascript_origins":["https://www.example.com"]}} When i try to access my protected apache i was intercepted by openID Connect module at openid.example.com and discovery page came. I chooose GLUU and then it redirects me to login and i login as local GLUU repo user and was granted access. When I open https://gluu.example.com to see my logged in profile, It just simply came with a page saying "Failed to authenticate". So not sure where its breaking. Definately something is not working as expected. Tomcat shows the following logs: GLUU.root@gluu:/opt/apache-tomcat-7.0.55/logs# cat wrapper.log INFO | jvm 1 | 2015/07/23 22:14:50 | 2015-07-23 22:14:50,534 DEBUG [org.gluu.oxtrust.servlet.FaviconImageServlet] Starting organization favicon upload INFO | jvm 1 | 2015/07/23 22:14:50 | 2015-07-23 22:14:50,536 DEBUG [org.gluu.oxtrust.servlet.FaviconImageServlet] Successfully send organization favicon with size INFO | jvm 1 | 2015/07/23 22:14:50 | 2015-07-23 22:14:50,612 DEBUG [org.gluu.oxtrust.servlet.LogoImageServlet] Starting organization logo upload INFO | jvm 1 | 2015/07/23 22:14:50 | 2015-07-23 22:14:50,614 DEBUG [org.gluu.oxtrust.servlet.LogoImageServlet] Successfully send organization logo with size INFO | jvm 1 | 2015/07/23 22:14:57 | 2015-07-23 22:14:57,546 DEBUG [org.gluu.oxtrust.ldap.service.MetadataValidationTimer] Starting metadata validation INFO | jvm 1 | 2015/07/23 22:14:57 | 2015-07-23 22:14:57,546 DEBUG [org.gluu.oxtrust.ldap.service.MetadataValidationTimer] Metadata validation finished INFO | jvm 1 | 2015/07/23 22:15:10 | 2015-07-23 22:15:10,044 TRACE [org.xdi.oxauth.service.SessionIdService] Found session_id cookie: '6485fab7-501f-4ccf-8a4c-a3a3afcff26c' INFO | jvm 1 | 2015/07/23 22:15:10 | 2015-07-23 22:15:10,049 TRACE [org.xdi.oxauth.service.SessionIdService] Try to get session by id: 6485fab7-501f-4ccf-8a4c-a3a3afcff26c ... INFO | jvm 1 | 2015/07/23 22:15:10 | 2015-07-23 22:15:10,049 TRACE [org.xdi.oxauth.service.SessionIdService] Session dn: uniqueIdentifier=6485fab7-501f-4ccf-8a4c-a3a3afcff26c,ou=session,o=@!F02E.AFC0.DFCC.E37A!0001!8AC9.BC0B,o=gluu INFO | jvm 1 | 2015/07/23 22:15:10 | 2015-07-23 22:15:10,049 TRACE [org.xdi.oxauth.service.SessionIdService] Failed to get session by id: 6485fab7-501f-4ccf-8a4c-a3a3afcff26c INFO | jvm 1 | 2015/07/23 22:15:10 | 2015-07-23 22:15:10,049 ERROR [org.xdi.oxauth.auth.Authenticator] Failed to get attributes from session INFO | jvm 1 | 2015/07/23 22:15:14 | 2015-07-23 22:15:14,791 TRACE [org.xdi.service.custom.script.CustomScriptManager] Last finished time '7/23/15 10:15 PM' INFO | jvm 1 | 2015/07/23 22:15:16 | 2015-07-23 22:15:16,652 DEBUG [org.gluu.oxtrust.config.OxTrustConfiguration] oxTrustConfig:{"baseDN":"o=gluu","orgInum":"@!F02E.AFC0.DFCC.E37A!0001!8AC9.BC0B","orgIname":null,"orgDisplayName":"Security","orgShortName":"None","orgSupportEmail":"admin@example.com","applianceIname":null,"applianceInum":"@!F02E.AFC0.DFCC.E37A!0002!D308.CEFD","applianceUrl":"https://gluu.example.com","schemaAddObjectClassWithoutAttributeTypesDefinition":"( %s NAME '%s' SUP top STRUCTURAL MUST objectClass X-SCHEMA-FILE '100-user.ldif' X-ORIGIN 'gluu' )","schemaAddObjectClassWithAttributeTypesDefinition":"( %s NAME '%s' SUP top STRUCTURAL MUST objectClass MAY ( %s ) X-SCHEMA-FILE '100-user.ldif' X-ORIGIN 'gluu' )","personObjectClassTypes":["inetOrgPerson","gluuPerson","eduPerson","ox-F02EAFC0DFCCE37A00018AC9BC0B"],"personCustomObjectClass":"ox-F02EAFC0DFCCE37A00018AC9BC0B","personObjectClassDisplayNames":["inetOrgPerson","gluuPerson","eduPerson","Custom"],"schemaAddAttributeDefinition":"( %s NAME '%s' EQUALITY caseIgnoreMatch ORDERING caseIgnoreOrderingMatch SUBSTR caseIgnoreSubstringsMatch SYNTAX 1.3.6.1.4.1.1466.115.121.1.15 USAGE userApplications X-SCHEMA-FILE '100-user.ldif' X-ORIGIN 'gluu' )","contactObjectClassTypes":[],"contactObjectClassDisplayNames":[],"photoRepositoryRootDir":"/var/ox/photos","photoRepositoryThumbWidth":300,"photoRepositoryThumbHeight":300,"photoRepositoryCountLeveles":3,"photoRepositoryCountFoldersPerLevel":20,"authMode":"","ldifStore":"/var/ox/oxtrust/removed","shibboleth2IdpRootDir":"/opt/idp","shibboleth2SpConfDir":"/opt/idp/sp","pokenApplicationSecret":null,"updateApplianceStatus":true,"svnConfigurationStoreRoot":"unused","svnConfigurationStorePassword":"unused","keystorePath":"/etc/certs/shibIDP.jks","keystorePassword":"XpHiSVGjVu8X","allowPersonModification":true,"idpUrl":"https://gluu.example.com","velocityLog":"/opt/tomcat/logs/velocity.log","spMetadataPath":null,"logoLocation":"/var/ox/photos","idpSecurityKey":"/etc/certs/shibIDP.key","idpSecurityKeyPassword":"zP1MJbEuMWV5SiUccDXLCQ==","idpSecurityCert":"/etc/certs/shibIDP.crt","gluuSpAttributes":[],"configGeneration":true,"idpLdapProtocol":"ldaps","idpLdapServer":"localhost:1636","idpBindDn":"cn=Directory Manager","idpBindPassword":"BMjSJTjfHT+TNcwvx4XCaw==","idpUserFields":null,"gluuSpCert":"/etc/certs/shibIDP.crt","mysqlUrl":null,"mysqlUser":null,"mysqlPassword":null,"shibboleth2FederationRootDir":"/opt/shibboleth-federation","cacheRefreshEnabled":false,"cacheRefreshIntervalMinutes":-1,"caCertsLocation":"/usr/java/latest/jre/lib/security/cacerts","caCertsPassphrase":null,"tempCertDir":"/etc/certs/temp","certDir":"/etc/certs/","servicesRestartTrigger":"/opt/gluu/essential_files/trigger_restart_of_services_delete_me_to_do_so","persistSVN":false,"oxAuthAuthorizeUrl":"https://gluu.example.com/oxauth/authorize","oxAuthTokenUrl":"https://gluu.example.com/oxauth/seam/resource/restv1/oxauth/token","oxAuthValidateTokenUrl":"https://gluu.example.com/oxauth/seam/resource/restv1/oxauth/validate","oxAuthEndSessionUrl":null,"oxAuthLogoutUrl":"https://gluu.example.com/oxauth/logout","oxAuthTokenValidationUrl":"https://gluu.example.com/oxauth/seam/resource/restv1/oxauth/validate","oxAuthUserInfo":"https://gluu.example.com/oxauth/seam/resource/restv1/oxauth/userinfo","oxAuthClientId":"@!F02E.AFC0.DFCC.E37A!0008!9E0A.A308","oxAuthClientPassword":"E6vb3Qq9nKBflygTt/9nRg==","oxAuthClientScope":"openid+profile+email+user_name","loginRedirectUrl":"https://gluu.example.com/identity/authentication/authcode","logoutRedirectUrl":"https://gluu.example.com/identity/authentication/finishlogout","clusteredInums":[],"clientAssociationAttribute":"inum","oxAuthIssuer":null,"ignoreValidation":false,"umaIssuer":null,"umaClientId":null,"umaClientPassword":null,"umaResourceId":null,"umaScope":null,"cssLocation":null,"jsLocation":null} INFO | jvm 1 | 2015/07/23 22:15:18 | 2015-07-23 22:15:17,934 TRACE [org.xdi.oxauth.service.SessionIdService] Found session_id cookie: '6485fab7-501f-4ccf-8a4c-a3a3afcff26c' INFO | jvm 1 | 2015/07/23 22:15:18 | 2015-07-23 22:15:17,943 TRACE [org.xdi.oxauth.service.SessionIdService] Try to get session by id: 6485fab7-501f-4ccf-8a4c-a3a3afcff26c ... INFO | jvm 1 | 2015/07/23 22:15:18 | 2015-07-23 22:15:17,943 TRACE [org.xdi.oxauth.service.SessionIdService] Session dn: uniqueIdentifier=6485fab7-501f-4ccf-8a4c-a3a3afcff26c,ou=session,o=@!F02E.AFC0.DFCC.E37A!0001!8AC9.BC0B,o=gluu INFO | jvm 1 | 2015/07/23 22:15:18 | 2015-07-23 22:15:17,943 TRACE [org.xdi.oxauth.service.SessionIdService] Failed to get session by id: 6485fab7-501f-4ccf-8a4c-a3a3afcff26c INFO | jvm 1 | 2015/07/23 22:15:18 | 2015-07-23 22:15:17,948 TRACE [org.xdi.oxauth.service.SessionIdService] Try to get session by id: 6485fab7-501f-4ccf-8a4c-a3a3afcff26c ... INFO | jvm 1 | 2015/07/23 22:15:18 | 2015-07-23 22:15:17,948 TRACE [org.xdi.oxauth.service.SessionIdService] Session dn: uniqueIdentifier=6485fab7-501f-4ccf-8a4c-a3a3afcff26c,ou=session,o=@!F02E.AFC0.DFCC.E37A!0001!8AC9.BC0B,o=gluu INFO | jvm 1 | 2015/07/23 22:15:18 | 2015-07-23 22:15:17,948 TRACE [org.xdi.oxauth.service.SessionIdService] Failed to get session by id: 6485fab7-501f-4ccf-8a4c-a3a3afcff26c INFO | jvm 1 | 2015/07/23 22:15:18 | 2015-07-23 22:15:17,950 DEBUG [org.xdi.oxauth.service.external.ExternalAuthenticationService] Executing python 'getPageForStep' authenticator method INFO | jvm 1 | 2015/07/23 22:15:18 | 2015-07-23 22:15:17,950 ERROR [org.xdi.oxauth.service.external.ExternalAuthenticationService] INFO | jvm 1 | 2015/07/23 22:15:18 | java.lang.NullPointerException INFO | jvm 1 | 2015/07/23 22:15:18 | at org.xdi.oxauth.service.external.ExternalAuthenticationService.executeExternalGetPageForStep(ExternalAuthenticationService.java:215) INFO | jvm 1 | 2015/07/23 22:15:18 | at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) INFO | jvm 1 | 2015/07/23 22:15:18 | at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57) INFO | jvm 1 | 2015/07/23 22:15:18 | at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) INFO | jvm 1 | 2015/07/23 22:15:18 | at java.lang.reflect.Method.invoke(Method.java:606) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.jboss.seam.util.Reflections.invoke(Reflections.java:22) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.jboss.seam.intercept.RootInvocationContext.proceed(RootInvocationContext.java:32) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.jboss.seam.intercept.SeamInvocationContext.proceed(SeamInvocationContext.java:56) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.jboss.seam.transaction.RollbackInterceptor.aroundInvoke(RollbackInterceptor.java:28) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.jboss.seam.intercept.SeamInvocationContext.proceed(SeamInvocationContext.java:68) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.jboss.seam.core.BijectionInterceptor.aroundInvoke(BijectionInterceptor.java:77) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.jboss.seam.intercept.SeamInvocationContext.proceed(SeamInvocationContext.java:68) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.jboss.seam.core.MethodContextInterceptor.aroundInvoke(MethodContextInterceptor.java:44) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.jboss.seam.intercept.SeamInvocationContext.proceed(SeamInvocationContext.java:68) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.jboss.seam.intercept.RootInterceptor.invoke(RootInterceptor.java:107) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.jboss.seam.intercept.JavaBeanInterceptor.interceptInvocation(JavaBeanInterceptor.java:185) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.jboss.seam.intercept.JavaBeanInterceptor.invoke(JavaBeanInterceptor.java:103) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.xdi.oxauth.service.external.ExternalAuthenticationService_$$_javassist_seam_8.executeExternalGetPageForStep(ExternalAuthenticationService_$$_javassist_seam_8.java) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.xdi.oxauth.authorize.ws.rs.AuthorizeAction.checkPermissionGranted(AuthorizeAction.java:173) INFO | jvm 1 | 2015/07/23 22:15:18 | at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) INFO | jvm 1 | 2015/07/23 22:15:18 | at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57) INFO | jvm 1 | 2015/07/23 22:15:18 | at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) INFO | jvm 1 | 2015/07/23 22:15:18 | at java.lang.reflect.Method.invoke(Method.java:606) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.jboss.seam.util.Reflections.invoke(Reflections.java:22) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.jboss.seam.intercept.RootInvocationContext.proceed(RootInvocationContext.java:32) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.jboss.seam.intercept.SeamInvocationContext.proceed(SeamInvocationContext.java:56) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.jboss.seam.transaction.RollbackInterceptor.aroundInvoke(RollbackInterceptor.java:28) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.jboss.seam.intercept.SeamInvocationContext.proceed(SeamInvocationContext.java:68) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.jboss.seam.core.BijectionInterceptor.aroundInvoke(BijectionInterceptor.java:77) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.jboss.seam.intercept.SeamInvocationContext.proceed(SeamInvocationContext.java:68) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.jboss.seam.core.MethodContextInterceptor.aroundInvoke(MethodContextInterceptor.java:44) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.jboss.seam.intercept.SeamInvocationContext.proceed(SeamInvocationContext.java:68) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.jboss.seam.intercept.RootInterceptor.invoke(RootInterceptor.java:107) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.jboss.seam.intercept.JavaBeanInterceptor.interceptInvocation(JavaBeanInterceptor.java:185) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.jboss.seam.intercept.JavaBeanInterceptor.invoke(JavaBeanInterceptor.java:103) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.xdi.oxauth.authorize.ws.rs.AuthorizeAction_$$_javassist_seam_41.checkPermissionGranted(AuthorizeAction_$$_javassist_seam_41.java) INFO | jvm 1 | 2015/07/23 22:15:18 | at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) INFO | jvm 1 | 2015/07/23 22:15:18 | at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57) INFO | jvm 1 | 2015/07/23 22:15:18 | at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) INFO | jvm 1 | 2015/07/23 22:15:18 | at java.lang.reflect.Method.invoke(Method.java:606) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.jboss.el.util.ReflectionUtil.invokeMethod(ReflectionUtil.java:335) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.jboss.el.util.ReflectionUtil.invokeMethod(ReflectionUtil.java:348) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.jboss.el.parser.AstPropertySuffix.invoke(AstPropertySuffix.java:58) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.jboss.el.parser.AstValue.invoke(AstValue.java:96) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.jboss.el.MethodExpressionImpl.invoke(MethodExpressionImpl.java:276) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.jboss.seam.core.Expressions$2.invoke(Expressions.java:221) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.jboss.seam.navigation.Page.preRender(Page.java:311) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.jboss.seam.navigation.Pages.preRender(Pages.java:351) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.jboss.seam.jsf.SeamPhaseListener.preRenderPage(SeamPhaseListener.java:560) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.jboss.seam.jsf.SeamPhaseListener.beforeRenderResponse(SeamPhaseListener.java:471) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.jboss.seam.jsf.SeamPhaseListener.beforeServletPhase(SeamPhaseListener.java:147) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.jboss.seam.jsf.SeamPhaseListener.beforePhase(SeamPhaseListener.java:117) INFO | jvm 1 | 2015/07/23 22:15:18 | at com.sun.faces.lifecycle.Phase.handleBeforePhase(Phase.java:214) INFO | jvm 1 | 2015/07/23 22:15:18 | at com.sun.faces.lifecycle.Phase.doPhase(Phase.java:96) INFO | jvm 1 | 2015/07/23 22:15:18 | at com.sun.faces.lifecycle.LifecycleImpl.render(LifecycleImpl.java:139) INFO | jvm 1 | 2015/07/23 22:15:18 | at javax.faces.webapp.FacesServlet.service(FacesServlet.java:266) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:303) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:208) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.apache.tomcat.websocket.server.WsFilter.doFilter(WsFilter.java:52) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:241) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:208) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.apache.catalina.core.ApplicationDispatcher.invoke(ApplicationDispatcher.java:748) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.apache.catalina.core.ApplicationDispatcher.processRequest(ApplicationDispatcher.java:486) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.apache.catalina.core.ApplicationDispatcher.doForward(ApplicationDispatcher.java:411) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.apache.catalina.core.ApplicationDispatcher.forward(ApplicationDispatcher.java:338) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.jboss.seam.web.RewriteFilter.process(RewriteFilter.java:98) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.jboss.seam.web.RewriteFilter.doFilter(RewriteFilter.java:57) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.jboss.seam.servlet.SeamFilter$FilterChainImpl.doFilter(SeamFilter.java:69) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.jboss.seam.web.LoggingFilter.doFilter(LoggingFilter.java:60) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.jboss.seam.servlet.SeamFilter$FilterChainImpl.doFilter(SeamFilter.java:69) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.jboss.seam.servlet.SeamFilter$FilterChainImpl.doFilter(SeamFilter.java:73) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.jboss.seam.web.IdentityFilter.doFilter(IdentityFilter.java:40) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.jboss.seam.servlet.SeamFilter$FilterChainImpl.doFilter(SeamFilter.java:69) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.jboss.seam.web.MultipartFilter.doFilter(MultipartFilter.java:90) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.jboss.seam.servlet.SeamFilter$FilterChainImpl.doFilter(SeamFilter.java:69) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.jboss.seam.web.ExceptionFilter.doFilter(ExceptionFilter.java:64) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.jboss.seam.servlet.SeamFilter$FilterChainImpl.doFilter(SeamFilter.java:69) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.jboss.seam.web.RedirectFilter.doFilter(RedirectFilter.java:45) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.jboss.seam.servlet.SeamFilter$FilterChainImpl.doFilter(SeamFilter.java:69) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.jboss.seam.servlet.SeamFilter$FilterChainImpl.doFilter(SeamFilter.java:73) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.jboss.seam.servlet.SeamFilter.doFilter(SeamFilter.java:158) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:241) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:208) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.apache.catalina.core.StandardWrapperValve.invoke(StandardWrapperValve.java:220) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.apache.catalina.core.StandardContextValve.invoke(StandardContextValve.java:122) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.apache.catalina.authenticator.AuthenticatorBase.invoke(AuthenticatorBase.java:501) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.apache.catalina.core.StandardHostValve.invoke(StandardHostValve.java:171) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.apache.catalina.valves.ErrorReportValve.invoke(ErrorReportValve.java:103) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.apache.catalina.core.StandardEngineValve.invoke(StandardEngineValve.java:116) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.apache.catalina.connector.CoyoteAdapter.service(CoyoteAdapter.java:408) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.apache.coyote.ajp.AjpProcessor.process(AjpProcessor.java:190) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.apache.coyote.AbstractProtocol$AbstractConnectionHandler.process(AbstractProtocol.java:611) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.apache.tomcat.util.net.JIoEndpoint$SocketProcessor.run(JIoEndpoint.java:314) INFO | jvm 1 | 2015/07/23 22:15:18 | at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145) INFO | jvm 1 | 2015/07/23 22:15:18 | at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.apache.tomcat.util.threads.TaskThread$WrappingRunnable.run(TaskThread.java:61) INFO | jvm 1 | 2015/07/23 22:15:18 | at java.lang.Thread.run(Thread.java:745) INFO | jvm 1 | 2015/07/23 22:15:18 | 2015-07-23 22:15:17,957 TRACE [org.xdi.oxauth.service.SessionIdService] Generated new session, id = '28cc4253-b758-47e7-9387-c184f855d20d', state = 'unauthenticated', persisted = 'false' INFO | jvm 1 | 2015/07/23 22:15:18 | 2015-07-23 22:15:17,964 TRACE [xdi.oxauth.authorize.ws.rs.AuthorizeAction] Session '28cc4253-b758-47e7-9387-c184f855d20d' persisted to LDAP INFO | jvm 1 | 2015/07/23 22:15:18 | 2015-07-23 22:15:17,973 TRACE [org.xdi.oxauth.service.SessionIdService] Found session_id cookie: '28cc4253-b758-47e7-9387-c184f855d20d' INFO | jvm 1 | 2015/07/23 22:15:18 | 2015-07-23 22:15:17,977 TRACE [org.xdi.oxauth.service.SessionIdService] Try to get session by id: 28cc4253-b758-47e7-9387-c184f855d20d ... INFO | jvm 1 | 2015/07/23 22:15:18 | 2015-07-23 22:15:17,977 TRACE [org.xdi.oxauth.service.SessionIdService] Session dn: uniqueIdentifier=28cc4253-b758-47e7-9387-c184f855d20d,ou=session,o=@!F02E.AFC0.DFCC.E37A!0001!8AC9.BC0B,o=gluu INFO | jvm 1 | 2015/07/23 22:15:18 | 2015-07-23 22:15:17,978 DEBUG [org.xdi.oxauth.service.external.ExternalAuthenticationService] Validating auth_mode: 'Google-Custom-Script' INFO | jvm 1 | 2015/07/23 22:15:18 | 2015-07-23 22:15:17,978 DEBUG [org.xdi.oxauth.service.external.ExternalAuthenticationService] Executing python 'isValidAuthenticationMethod' authenticator method INFO | jvm 1 | 2015/07/23 22:15:18 | 2015-07-23 22:15:17,978 ERROR [org.xdi.oxauth.service.external.ExternalAuthenticationService] INFO | jvm 1 | 2015/07/23 22:15:18 | java.lang.NullPointerException INFO | jvm 1 | 2015/07/23 22:15:18 | at org.xdi.oxauth.service.external.ExternalAuthenticationService.executeExternalIsValidAuthenticationMethod(ExternalAuthenticationService.java:125) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.xdi.oxauth.service.external.ExternalAuthenticationService.determineExternalAuthenticatorForWorkflow(ExternalAuthenticationService.java:303) INFO | jvm 1 | 2015/07/23 22:15:18 | at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) INFO | jvm 1 | 2015/07/23 22:15:18 | at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57) INFO | jvm 1 | 2015/07/23 22:15:18 | at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) INFO | jvm 1 | 2015/07/23 22:15:18 | at java.lang.reflect.Method.invoke(Method.java:606) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.jboss.seam.util.Reflections.invoke(Reflections.java:22) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.jboss.seam.intercept.RootInvocationContext.proceed(RootInvocationContext.java:32) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.jboss.seam.intercept.SeamInvocationContext.proceed(SeamInvocationContext.java:56) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.jboss.seam.transaction.RollbackInterceptor.aroundInvoke(RollbackInterceptor.java:28) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.jboss.seam.intercept.SeamInvocationContext.proceed(SeamInvocationContext.java:68) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.jboss.seam.core.BijectionInterceptor.aroundInvoke(BijectionInterceptor.java:77) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.jboss.seam.intercept.SeamInvocationContext.proceed(SeamInvocationContext.java:68) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.jboss.seam.core.MethodContextInterceptor.aroundInvoke(MethodContextInterceptor.java:44) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.jboss.seam.intercept.SeamInvocationContext.proceed(SeamInvocationContext.java:68) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.jboss.seam.intercept.RootInterceptor.invoke(RootInterceptor.java:107) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.jboss.seam.intercept.JavaBeanInterceptor.interceptInvocation(JavaBeanInterceptor.java:185) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.jboss.seam.intercept.JavaBeanInterceptor.invoke(JavaBeanInterceptor.java:103) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.xdi.oxauth.service.external.ExternalAuthenticationService_$$_javassist_seam_8.determineExternalAuthenticatorForWorkflow(ExternalAuthenticationService_$$_javassist_seam_8.java) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.xdi.oxauth.auth.Authenticator.prepareAuthenticationForStep(Authenticator.java:372) INFO | jvm 1 | 2015/07/23 22:15:18 | at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) INFO | jvm 1 | 2015/07/23 22:15:18 | at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57) INFO | jvm 1 | 2015/07/23 22:15:18 | at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) INFO | jvm 1 | 2015/07/23 22:15:18 | at java.lang.reflect.Method.invoke(Method.java:606) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.jboss.seam.util.Reflections.invoke(Reflections.java:22) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.jboss.seam.intercept.RootInvocationContext.proceed(RootInvocationContext.java:32) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.jboss.seam.intercept.SeamInvocationContext.proceed(SeamInvocationContext.java:56) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.jboss.seam.transaction.RollbackInterceptor.aroundInvoke(RollbackInterceptor.java:28) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.jboss.seam.intercept.SeamInvocationContext.proceed(SeamInvocationContext.java:68) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.jboss.seam.core.BijectionInterceptor.aroundInvoke(BijectionInterceptor.java:77) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.jboss.seam.intercept.SeamInvocationContext.proceed(SeamInvocationContext.java:68) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.jboss.seam.core.MethodContextInterceptor.aroundInvoke(MethodContextInterceptor.java:44) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.jboss.seam.intercept.SeamInvocationContext.proceed(SeamInvocationContext.java:68) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.jboss.seam.intercept.RootInterceptor.invoke(RootInterceptor.java:107) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.jboss.seam.intercept.JavaBeanInterceptor.interceptInvocation(JavaBeanInterceptor.java:185) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.jboss.seam.intercept.JavaBeanInterceptor.invoke(JavaBeanInterceptor.java:103) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.xdi.oxauth.auth.Authenticator_$$_javassist_seam_38.prepareAuthenticationForStep(Authenticator_$$_javassist_seam_38.java) INFO | jvm 1 | 2015/07/23 22:15:18 | at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) INFO | jvm 1 | 2015/07/23 22:15:18 | at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57) INFO | jvm 1 | 2015/07/23 22:15:18 | at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) INFO | jvm 1 | 2015/07/23 22:15:18 | at java.lang.reflect.Method.invoke(Method.java:606) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.jboss.el.util.ReflectionUtil.invokeMethod(ReflectionUtil.java:335) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.jboss.el.util.ReflectionUtil.invokeMethod(ReflectionUtil.java:348) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.jboss.el.parser.AstPropertySuffix.invoke(AstPropertySuffix.java:58) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.jboss.el.parser.AstValue.invoke(AstValue.java:96) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.jboss.el.MethodExpressionImpl.invoke(MethodExpressionImpl.java:276) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.jboss.seam.core.Expressions$2.invoke(Expressions.java:221) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.jboss.seam.navigation.Page.preRender(Page.java:311) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.jboss.seam.navigation.Pages.preRender(Pages.java:351) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.jboss.seam.jsf.SeamPhaseListener.preRenderPage(SeamPhaseListener.java:560) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.jboss.seam.jsf.SeamPhaseListener.beforeRenderResponse(SeamPhaseListener.java:471) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.jboss.seam.jsf.SeamPhaseListener.beforeServletPhase(SeamPhaseListener.java:147) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.jboss.seam.jsf.SeamPhaseListener.beforePhase(SeamPhaseListener.java:117) INFO | jvm 1 | 2015/07/23 22:15:18 | at com.sun.faces.lifecycle.Phase.handleBeforePhase(Phase.java:214) INFO | jvm 1 | 2015/07/23 22:15:18 | at com.sun.faces.lifecycle.Phase.doPhase(Phase.java:96) INFO | jvm 1 | 2015/07/23 22:15:18 | at com.sun.faces.lifecycle.LifecycleImpl.render(LifecycleImpl.java:139) INFO | jvm 1 | 2015/07/23 22:15:18 | at javax.faces.webapp.FacesServlet.service(FacesServlet.java:266) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:303) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:208) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.apache.tomcat.websocket.server.WsFilter.doFilter(WsFilter.java:52) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:241) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:208) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.apache.catalina.core.ApplicationDispatcher.invoke(ApplicationDispatcher.java:748) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.apache.catalina.core.ApplicationDispatcher.processRequest(ApplicationDispatcher.java:486) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.apache.catalina.core.ApplicationDispatcher.doForward(ApplicationDispatcher.java:411) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.apache.catalina.core.ApplicationDispatcher.forward(ApplicationDispatcher.java:338) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.jboss.seam.web.RewriteFilter.process(RewriteFilter.java:98) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.jboss.seam.web.RewriteFilter.doFilter(RewriteFilter.java:57) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.jboss.seam.servlet.SeamFilter$FilterChainImpl.doFilter(SeamFilter.java:69) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.jboss.seam.web.LoggingFilter.doFilter(LoggingFilter.java:60) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.jboss.seam.servlet.SeamFilter$FilterChainImpl.doFilter(SeamFilter.java:69) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.jboss.seam.servlet.SeamFilter$FilterChainImpl.doFilter(SeamFilter.java:73) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.jboss.seam.web.IdentityFilter.doFilter(IdentityFilter.java:40) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.jboss.seam.servlet.SeamFilter$FilterChainImpl.doFilter(SeamFilter.java:69) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.jboss.seam.web.MultipartFilter.doFilter(MultipartFilter.java:90) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.jboss.seam.servlet.SeamFilter$FilterChainImpl.doFilter(SeamFilter.java:69) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.jboss.seam.web.ExceptionFilter.doFilter(ExceptionFilter.java:64) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.jboss.seam.servlet.SeamFilter$FilterChainImpl.doFilter(SeamFilter.java:69) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.jboss.seam.web.RedirectFilter.doFilter(RedirectFilter.java:45) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.jboss.seam.servlet.SeamFilter$FilterChainImpl.doFilter(SeamFilter.java:69) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.jboss.seam.servlet.SeamFilter$FilterChainImpl.doFilter(SeamFilter.java:73) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.jboss.seam.servlet.SeamFilter.doFilter(SeamFilter.java:158) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:241) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:208) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.apache.catalina.core.StandardWrapperValve.invoke(StandardWrapperValve.java:220) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.apache.catalina.core.StandardContextValve.invoke(StandardContextValve.java:122) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.apache.catalina.authenticator.AuthenticatorBase.invoke(AuthenticatorBase.java:501) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.apache.catalina.core.StandardHostValve.invoke(StandardHostValve.java:171) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.apache.catalina.valves.ErrorReportValve.invoke(ErrorReportValve.java:103) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.apache.catalina.core.StandardEngineValve.invoke(StandardEngineValve.java:116) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.apache.catalina.connector.CoyoteAdapter.service(CoyoteAdapter.java:408) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.apache.coyote.ajp.AjpProcessor.process(AjpProcessor.java:190) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.apache.coyote.AbstractProtocol$AbstractConnectionHandler.process(AbstractProtocol.java:611) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.apache.tomcat.util.net.JIoEndpoint$SocketProcessor.run(JIoEndpoint.java:314) INFO | jvm 1 | 2015/07/23 22:15:18 | at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145) INFO | jvm 1 | 2015/07/23 22:15:18 | at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.apache.tomcat.util.threads.TaskThread$WrappingRunnable.run(TaskThread.java:61) INFO | jvm 1 | 2015/07/23 22:15:18 | at java.lang.Thread.run(Thread.java:745) INFO | jvm 1 | 2015/07/23 22:15:18 | 2015-07-23 22:15:17,980 WARN [org.xdi.oxauth.service.external.ExternalAuthenticationService] Current auth_mode: 'Google-Custom-Script' isn't valid INFO | jvm 1 | 2015/07/23 22:15:18 | 2015-07-23 22:15:17,980 DEBUG [org.xdi.oxauth.service.external.ExternalAuthenticationService] Executing python 'getAlternativeAuthenticationMethod' authenticator method INFO | jvm 1 | 2015/07/23 22:15:18 | 2015-07-23 22:15:17,980 ERROR [org.xdi.oxauth.service.external.ExternalAuthenticationService] INFO | jvm 1 | 2015/07/23 22:15:18 | java.lang.NullPointerException INFO | jvm 1 | 2015/07/23 22:15:18 | at org.xdi.oxauth.service.external.ExternalAuthenticationService.executeExternalGetAlternativeAuthenticationMethod(ExternalAuthenticationService.java:138) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.xdi.oxauth.service.external.ExternalAuthenticationService.determineExternalAuthenticatorForWorkflow(ExternalAuthenticationService.java:307) INFO | jvm 1 | 2015/07/23 22:15:18 | at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) INFO | jvm 1 | 2015/07/23 22:15:18 | at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57) INFO | jvm 1 | 2015/07/23 22:15:18 | at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) INFO | jvm 1 | 2015/07/23 22:15:18 | at java.lang.reflect.Method.invoke(Method.java:606) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.jboss.seam.util.Reflections.invoke(Reflections.java:22) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.jboss.seam.intercept.RootInvocationContext.proceed(RootInvocationContext.java:32) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.jboss.seam.intercept.SeamInvocationContext.proceed(SeamInvocationContext.java:56) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.jboss.seam.transaction.RollbackInterceptor.aroundInvoke(RollbackInterceptor.java:28) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.jboss.seam.intercept.SeamInvocationContext.proceed(SeamInvocationContext.java:68) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.jboss.seam.core.BijectionInterceptor.aroundInvoke(BijectionInterceptor.java:77) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.jboss.seam.intercept.SeamInvocationContext.proceed(SeamInvocationContext.java:68) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.jboss.seam.core.MethodContextInterceptor.aroundInvoke(MethodContextInterceptor.java:44) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.jboss.seam.intercept.SeamInvocationContext.proceed(SeamInvocationContext.java:68) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.jboss.seam.intercept.RootInterceptor.invoke(RootInterceptor.java:107) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.jboss.seam.intercept.JavaBeanInterceptor.interceptInvocation(JavaBeanInterceptor.java:185) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.jboss.seam.intercept.JavaBeanInterceptor.invoke(JavaBeanInterceptor.java:103) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.xdi.oxauth.service.external.ExternalAuthenticationService_$$_javassist_seam_8.determineExternalAuthenticatorForWorkflow(ExternalAuthenticationService_$$_javassist_seam_8.java) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.xdi.oxauth.auth.Authenticator.prepareAuthenticationForStep(Authenticator.java:372) INFO | jvm 1 | 2015/07/23 22:15:18 | at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) INFO | jvm 1 | 2015/07/23 22:15:18 | at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57) INFO | jvm 1 | 2015/07/23 22:15:18 | at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) INFO | jvm 1 | 2015/07/23 22:15:18 | at java.lang.reflect.Method.invoke(Method.java:606) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.jboss.seam.util.Reflections.invoke(Reflections.java:22) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.jboss.seam.intercept.RootInvocationContext.proceed(RootInvocationContext.java:32) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.jboss.seam.intercept.SeamInvocationContext.proceed(SeamInvocationContext.java:56) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.jboss.seam.transaction.RollbackInterceptor.aroundInvoke(RollbackInterceptor.java:28) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.jboss.seam.intercept.SeamInvocationContext.proceed(SeamInvocationContext.java:68) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.jboss.seam.core.BijectionInterceptor.aroundInvoke(BijectionInterceptor.java:77) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.jboss.seam.intercept.SeamInvocationContext.proceed(SeamInvocationContext.java:68) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.jboss.seam.core.MethodContextInterceptor.aroundInvoke(MethodContextInterceptor.java:44) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.jboss.seam.intercept.SeamInvocationContext.proceed(SeamInvocationContext.java:68) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.jboss.seam.intercept.RootInterceptor.invoke(RootInterceptor.java:107) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.jboss.seam.intercept.JavaBeanInterceptor.interceptInvocation(JavaBeanInterceptor.java:185) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.jboss.seam.intercept.JavaBeanInterceptor.invoke(JavaBeanInterceptor.java:103) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.xdi.oxauth.auth.Authenticator_$$_javassist_seam_38.prepareAuthenticationForStep(Authenticator_$$_javassist_seam_38.java) INFO | jvm 1 | 2015/07/23 22:15:18 | at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) INFO | jvm 1 | 2015/07/23 22:15:18 | at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57) INFO | jvm 1 | 2015/07/23 22:15:18 | at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) INFO | jvm 1 | 2015/07/23 22:15:18 | at java.lang.reflect.Method.invoke(Method.java:606) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.jboss.el.util.ReflectionUtil.invokeMethod(ReflectionUtil.java:335) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.jboss.el.util.ReflectionUtil.invokeMethod(ReflectionUtil.java:348) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.jboss.el.parser.AstPropertySuffix.invoke(AstPropertySuffix.java:58) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.jboss.el.parser.AstValue.invoke(AstValue.java:96) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.jboss.el.MethodExpressionImpl.invoke(MethodExpressionImpl.java:276) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.jboss.seam.core.Expressions$2.invoke(Expressions.java:221) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.jboss.seam.navigation.Page.preRender(Page.java:311) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.jboss.seam.navigation.Pages.preRender(Pages.java:351) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.jboss.seam.jsf.SeamPhaseListener.preRenderPage(SeamPhaseListener.java:560) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.jboss.seam.jsf.SeamPhaseListener.beforeRenderResponse(SeamPhaseListener.java:471) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.jboss.seam.jsf.SeamPhaseListener.beforeServletPhase(SeamPhaseListener.java:147) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.jboss.seam.jsf.SeamPhaseListener.beforePhase(SeamPhaseListener.java:117) INFO | jvm 1 | 2015/07/23 22:15:18 | at com.sun.faces.lifecycle.Phase.handleBeforePhase(Phase.java:214) INFO | jvm 1 | 2015/07/23 22:15:18 | at com.sun.faces.lifecycle.Phase.doPhase(Phase.java:96) INFO | jvm 1 | 2015/07/23 22:15:18 | at com.sun.faces.lifecycle.LifecycleImpl.render(LifecycleImpl.java:139) INFO | jvm 1 | 2015/07/23 22:15:18 | at javax.faces.webapp.FacesServlet.service(FacesServlet.java:266) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:303) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:208) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.apache.tomcat.websocket.server.WsFilter.doFilter(WsFilter.java:52) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:241) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:208) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.apache.catalina.core.ApplicationDispatcher.invoke(ApplicationDispatcher.java:748) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.apache.catalina.core.ApplicationDispatcher.processRequest(ApplicationDispatcher.java:486) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.apache.catalina.core.ApplicationDispatcher.doForward(ApplicationDispatcher.java:411) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.apache.catalina.core.ApplicationDispatcher.forward(ApplicationDispatcher.java:338) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.jboss.seam.web.RewriteFilter.process(RewriteFilter.java:98) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.jboss.seam.web.RewriteFilter.doFilter(RewriteFilter.java:57) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.jboss.seam.servlet.SeamFilter$FilterChainImpl.doFilter(SeamFilter.java:69) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.jboss.seam.web.LoggingFilter.doFilter(LoggingFilter.java:60) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.jboss.seam.servlet.SeamFilter$FilterChainImpl.doFilter(SeamFilter.java:69) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.jboss.seam.servlet.SeamFilter$FilterChainImpl.doFilter(SeamFilter.java:73) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.jboss.seam.web.IdentityFilter.doFilter(IdentityFilter.java:40) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.jboss.seam.servlet.SeamFilter$FilterChainImpl.doFilter(SeamFilter.java:69) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.jboss.seam.web.MultipartFilter.doFilter(MultipartFilter.java:90) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.jboss.seam.servlet.SeamFilter$FilterChainImpl.doFilter(SeamFilter.java:69) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.jboss.seam.web.ExceptionFilter.doFilter(ExceptionFilter.java:64) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.jboss.seam.servlet.SeamFilter$FilterChainImpl.doFilter(SeamFilter.java:69) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.jboss.seam.web.RedirectFilter.doFilter(RedirectFilter.java:45) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.jboss.seam.servlet.SeamFilter$FilterChainImpl.doFilter(SeamFilter.java:69) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.jboss.seam.servlet.SeamFilter$FilterChainImpl.doFilter(SeamFilter.java:73) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.jboss.seam.servlet.SeamFilter.doFilter(SeamFilter.java:158) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:241) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:208) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.apache.catalina.core.StandardWrapperValve.invoke(StandardWrapperValve.java:220) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.apache.catalina.core.StandardContextValve.invoke(StandardContextValve.java:122) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.apache.catalina.authenticator.AuthenticatorBase.invoke(AuthenticatorBase.java:501) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.apache.catalina.core.StandardHostValve.invoke(StandardHostValve.java:171) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.apache.catalina.valves.ErrorReportValve.invoke(ErrorReportValve.java:103) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.apache.catalina.core.StandardEngineValve.invoke(StandardEngineValve.java:116) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.apache.catalina.connector.CoyoteAdapter.service(CoyoteAdapter.java:408) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.apache.coyote.ajp.AjpProcessor.process(AjpProcessor.java:190) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.apache.coyote.AbstractProtocol$AbstractConnectionHandler.process(AbstractProtocol.java:611) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.apache.tomcat.util.net.JIoEndpoint$SocketProcessor.run(JIoEndpoint.java:314) INFO | jvm 1 | 2015/07/23 22:15:18 | at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145) INFO | jvm 1 | 2015/07/23 22:15:18 | at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615) INFO | jvm 1 | 2015/07/23 22:15:18 | at org.apache.tomcat.util.threads.TaskThread$WrappingRunnable.run(TaskThread.java:61) INFO | jvm 1 | 2015/07/23 22:15:18 | at java.lang.Thread.run(Thread.java:745) INFO | jvm 1 | 2015/07/23 22:15:18 | 2015-07-23 22:15:17,982 ERROR [org.xdi.oxauth.service.external.ExternalAuthenticationService] Failed to determine alternative authentication mode for auth_mode: 'Google-Custom-Script' INFO | jvm 1 | 2015/07/23 22:15:44 | 2015-07-23 22:15:44,790 TRACE [org.xdi.service.custom.script.CustomScriptManager] Last finished time '7/23/15 10:15 PM'

By Mohib Zico staff 24 Jul 2015 at 2:11 a.m. CDT

Mohib Zico gravatar
I can see couple of errors in log....

By Farhan Ahmed user 24 Jul 2015 at 9:16 a.m. CDT

Farhan Ahmed gravatar
What errors .. how to resolve them Mohib ? Can you explain a little bit.

By Mohib Zico staff 24 Jul 2015 at 9:31 a.m. CDT

Mohib Zico gravatar
>> What errors >>>> INFO | jvm 1 | 2015/07/23 22:15:10 | 2015-07-23 22:15:10,049 ERROR [org.xdi.oxauth.auth.Authenticator] Failed to get attributes from session >>>> [org.xdi.oxauth.service.external.ExternalAuthenticationService] Executing python 'getPageForStep' authenticator method INFO | jvm 1 | 2015/07/23 22:15:18 | 2015-07-23 22:15:17,950 ERROR [org.xdi.oxauth.service.external.ExternalAuthenticationService] >>>> INFO | jvm 1 | 2015/07/23 22:15:18 | 2015-07-23 22:15:17,980 WARN [org.xdi.oxauth.service.external.ExternalAuthenticationService] Current auth_mode: 'Google-Custom-Script' isn't valid I don't know yet how to resolve that. If you can tell us how to reproduce this situation step by step; we will try to reproduce the situation and get back to you by next week.

By Farhan Ahmed user 24 Jul 2015 at 9:39 a.m. CDT

Farhan Ahmed gravatar
I did the following: 1.Create a new custom script by using the GooglePlusExternalAuthenticator.py and added 5 properties as mentioned in custom_script_entry.ldif 2.After saving that i changed the Manage Authentication from Default to this new Custom Script (GoolgeCustomScript) 3.Also i placed the file at: /etc/certs/gplus_client_secrets.json (which i downloaded from google for my app). GLUU.root@gluu:/etc/certs# cat gplus_client_secrets.json {"web":{"client_id":"1011868174354-94h15m0ahpqeafo5k99ui8c26v1nm7l3.apps.googleusercontent.com","auth_uri":"https://accounts.google.com/o/oauth2/auth","token_uri":"https://accounts.google.com/o/oauth2/token","auth_provider_x509_cert_url":"https://www.googleapis.com/oauth2/v1/certs","client_email":"","client_x509_cert_url":"","client_secret":"S669TbRRsQbU6Y3Jrj3-9Zgw","redirect_uris":["https://openid.example.com/protected/redirect_doesnt_exist_on_apache"],"javascript_origins":["https://www.example.com"]}} 4.When i try to access my protected apache i was intercepted by openID Connect module at openid.example.com and discovery page came. I chooose gluu.example.com (i.e. my gluu server) and then it redirects me to login and i login as local GLUU repo user and was granted access. I was expecting not to get authenticated via GLUU but Google since i change the default authentication method to GoogleCustomScript rather than GLUU. 5. If i now start in a fresh browser and try to access https://gluu.example.com, instead of login page of GLUU i am getting Authentication Failed and i can't login with any account. Can you tell me step by step how to achieve what is given in this below link: https://github.com/GluuFederation/oxAuth/tree/master/Server/integrations/gplus Regards, Farhan.

By Mohib Zico staff 24 Jul 2015 at 9:45 a.m. CDT

Mohib Zico gravatar
Thanks but this is one part. How about others? Such as: 1. How you configured your 'protected page' 2. What you configured in Google admin panel ( googleapis.com ) My intention is to follow 'exactly your step' to reproduce this issue.

By Farhan Ahmed user 24 Jul 2015 at 9:51 a.m. CDT

Farhan Ahmed gravatar
1. OpenID Connect module for apache is configured by following: http://ox.gluu.org/doku.php?id=mod_auth_oidc_ubuntu 2. Google configuration done at https://console.developers.google.com/project and from their I got my cliendID, clientSecret and the JSON

By Farhan Ahmed user 24 Jul 2015 at 9:52 a.m. CDT

Farhan Ahmed gravatar
But currently I am asking only 1 question: Can you tell me step by step how to achieve what is given in this below link: https://github.com/GluuFederation/oxAuth/tree/master/Server/integrations/gplus

By Mohib Zico staff 27 Jul 2015 at 3:16 a.m. CDT

Mohib Zico gravatar
Just opened a [ticket](https://github.com/GluuFederation/docs/issues/26). A howto will be published in 'Article' section of our www.gluu.org/docs

By Farhan Ahmed user 27 Jul 2015 at 10:25 a.m. CDT

Farhan Ahmed gravatar
Thanks Mohib. What's the ETA?

By Mohib Zico staff 27 Jul 2015 at 10:30 a.m. CDT

Mohib Zico gravatar
Two weeks.

By Mohib Zico staff 30 Jul 2015 at 9:27 a.m. CDT

Mohib Zico gravatar
Farhan, Ireneusz from your place resolved the [issue](https://support.gluu.org/view/application-integration/oxauth-gplus-custom-authn-script-fixes/2013) ( Thanks a bunch to him! ). We will publish our gplus [enhancement](https://github.com/GluuFederation/oxAuth/issues/55) in next Gluu Server release.