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'