Hello, on CAS 5.0.6 we are trying to run our internal authentication application (runs successfully on CAS4) which calls cas/serviceVerify and cas/proxy after cas/login?service= and the cas/proxy is failing with 505 error. So far we have tried alternate service json rules but with no success so far. I have attached a log of our CAS messages.
Any ideas on how to proceed with this one? Duane -- - CAS gitter chatroom: https://gitter.im/apereo/cas - CAS mailing list guidelines: https://apereo.github.io/cas/Mailing-Lists.html - CAS documentation website: https://apereo.github.io/cas - CAS project website: https://github.com/apereo/cas --- You received this message because you are subscribed to the Google Groups "CAS Community" group. To unsubscribe from this group and stop receiving emails from it, send an email to [email protected]. To view this discussion on the web visit https://groups.google.com/a/apereo.org/d/msgid/cas-user/5e285ec6-d42f-47b5-b248-74e430539c3a%40apereo.org.
2017-06-26 08:41:58,454 DEBUG [org.apereo.cas.services.DefaultRegisteredServiceUsernameProvider] - <Returning the default principal id [xxxxx] f or username.> 2017-06-26 08:41:58,464 DEBUG [org.apereo.cas.CentralAuthenticationServiceImpl] - <Publishing org.apereo.cas.support.events.CasServiceTicketVali datedEvent@6834ddf4[assertion=org.apereo.cas.authentication.DefaultAuthentication@1c99ae30:https://cas-hostname/NAUappl/Manager,serviceTicket =ST-1-1Ck9h31F79dXIb5Gsu5e-xxxx.xxxx.xxxx.xxxx]> 2017-06-26 08:41:58,466 DEBUG [org.apereo.cas.ticket.support.MultiTimeUseOrTimeoutExpirationPolicy] - <Ticket usage count 1 is greater than or e qual to 1> 2017-06-26 08:41:58,466 DEBUG [org.apereo.cas.ticket.registry.EhCacheTicketRegistry] - <Attempting to decode [e419e5b448c00fecc71dc226a9a444c6bb 28812eeba4dbb88fe1404e82569cb0ce0dcbf2110d8817176e889ef6950b99fec724ae2d974df1b22b86b0c3f59dcd]> 2017-06-26 08:41:58,477 INFO [org.apereo.cas.ticket.registry.EhCacheTicketRegistry] - <Decoded ST-1-1Ck9h31F79dXIb5Gsu5e-xxxx.xxxx.xxxx.xxxx> 2017-06-26 08:41:58,478 DEBUG [org.apereo.cas.ticket.registry.EhCacheTicketRegistry] - <Removing ticket [ST-1-1Ck9h31F79dXIb5Gsu5e-cinder.ucc.na u.edu] from the registry.> 2017-06-26 08:41:58,479 DEBUG [org.apereo.cas.ticket.registry.EhCacheTicketRegistry] - <Attempting to decode [e419e5b448c00fecc71dc226a9a444c6bb 28812eeba4dbb88fe1404e82569cb0ce0dcbf2110d8817176e889ef6950b99fec724ae2d974df1b22b86b0c3f59dcd]> 2017-06-26 08:41:58,483 INFO [org.apereo.cas.ticket.registry.EhCacheTicketRegistry] - <Decoded ST-1-1Ck9h31F79dXIb5Gsu5e-xxxx.xxxx.xxxx.xxxx> 2017-06-26 08:41:58,485 DEBUG [net.sf.ehcache.distribution.RMICacheManagerPeerProvider] - <Lookup URL //pumice.ucc.nau.edu:41001/org.apereo.cas. ticket.TicketCache> 2017-06-26 08:41:58,490 DEBUG [org.apereo.cas.audit.spi.ThreadLocalPrincipalResolver] - <Resolving principal at audit point [execution(Assertion org.apereo.cas.CentralAuthenticationServiceImpl.validateServiceTicket(String,Service))]> 2017-06-26 08:41:58,491 INFO [org.apereo.inspektr.audit.support.Slf4jLoggingAuditTrailManager] - <Audit trail record BEGIN ============================================================= WHO: xxxxx WHAT: ST-1-1Ck9h31F79dXIb5Gsu5e-xxxx.xxxx.xxxx.xxxx ACTION: SERVICE_TICKET_VALIDATED APPLICATION: CAS WHEN: Mon Jun 26 08:41:58 MST 2017 CLIENT IP ADDRESS: x.x.x.x SERVER IP ADDRESS: x.x.x.x ============================================================= > 2017-06-26 08:41:58,491 DEBUG [org.springframework.integration.transaction.PseudoTransactionManager] - <Initiating transaction commit> 2017-06-26 08:41:58,491 DEBUG [org.springframework.integration.transaction.PseudoTransactionManager] - <Resuming suspended transaction after com pletion of inner transaction> 2017-06-26 08:41:58,491 DEBUG [org.springframework.integration.transaction.PseudoTransactionManager] - <Initiating transaction commit> 2017-06-26 08:41:58,494 DEBUG [org.springframework.validation.DataBinder] - <DataBinder requires binding of required fields [renew]> 2017-06-26 08:41:58,506 DEBUG [org.apereo.cas.validation.Cas20WithoutProxyingValidationSpecification] - <Is validation specification set to enforce [renew] protocol behavior? [no]. Is assertion issued from a new login? [yes]> 2017-06-26 08:41:58,507 DEBUG [org.apereo.cas.validation.Cas20WithoutProxyingValidationSpecification] - <Number of chained authentications in the assertion 1> 2017-06-26 08:41:58,507 DEBUG [org.apereo.cas.validation.Cas20WithoutProxyingValidationSpecification] - <Validation specification is satisfied by the produced assertion> 2017-06-26 08:41:58,507 DEBUG [org.apereo.cas.web.ServiceValidateController] - <Locating the primary authentication associated with this service request https://cas-hostname/NAUappl/Manager> 2017-06-26 08:41:58,511 DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory] - <Returning cached instance of singleton bean 'duoAuthenticationProvider'> 2017-06-26 08:41:58,513 DEBUG [org.apereo.cas.web.ServiceValidateController] - <No particular authentication context is required for this request> 2017-06-26 08:41:58,666 DEBUG [org.apereo.cas.util.http.SimpleHttpClient] - <Response code from server matched 200.> 2017-06-26 08:41:58,668 DEBUG [org.apereo.cas.ticket.proxy.support.Cas20ProxyHandler] - <Sent ProxyIou of PGTIOU-*************************************************U72UiIkfJa-xxxx.xxxx.xxxx.xxxx for service: https://cas-hostname/NAUappl/NAUapplServlet?ApplicationPath=https%3A%2F%2Fcas-hostname%2FNAUappl%2FManager> 2017-06-26 08:41:58,669 DEBUG [org.apereo.cas.web.ServiceValidateController] - <Successfully validated service ticket ST-1-1Ck9h31F79dXIb5Gsu5e-xxxx.xxxx.xxxx.xxxx for service [https://cas-hostname/NAUappl/Manager]> 2017-06-26 08:41:58,672 DEBUG [org.springframework.web.servlet.DispatcherServlet] - <Rendering view [org.apereo.cas.web.view.Cas20ResponseView: name 'cas2ServiceSuccessView'] in DispatcherServlet with name 'dispatcherServlet'> 2017-06-26 08:41:58,675 DEBUG [org.apereo.cas.web.view.Cas20ResponseView] - <Preparing the output model to render view...> 2017-06-26 08:41:58,733 DEBUG [org.springframework.web.servlet.DispatcherServlet] - <Successfully completed request> 2017-06-26 08:41:58,734 DEBUG [org.springframework.boot.web.filter.OrderedRequestContextFilter] - <Cleared thread-bound request context: org.apache.catalina.connector.RequestFacade@28f2eed1> 2017-06-26 08:41:58,998 DEBUG [org.springframework.boot.web.filter.OrderedRequestContextFilter] - <Bound request context to thread: org.apache.catalina.connector.RequestFacade@69cfe4ea> 2017-06-26 08:41:58,999 DEBUG [org.springframework.web.servlet.DispatcherServlet] - <DispatcherServlet with name 'dispatcherServlet' processing GET request for [/cas/proxy]> 2017-06-26 08:41:58,999 DEBUG [org.springframework.boot.actuate.endpoint.mvc.EndpointHandlerMapping] - <Looking up handler method for path /proxy> 2017-06-26 08:41:59,002 DEBUG [org.springframework.boot.actuate.endpoint.mvc.EndpointHandlerMapping] - <Did not find handler method for [/proxy]> 2017-06-26 08:41:59,003 DEBUG [org.springframework.web.servlet.mvc.method.annotation.RequestMappingHandlerMapping] - <Looking up handler method for path /proxy> 2017-06-26 08:41:59,003 DEBUG [org.springframework.web.servlet.mvc.method.annotation.RequestMappingHandlerMapping] - <Returning handler method [protected org.springframework.web.servlet.ModelAndView org.apereo.cas.web.ProxyController.handleRequestInternal(javax.servlet.http.HttpServletRequest,javax.servlet.http.HttpServletResponse)]> 2017-06-26 08:41:59,003 DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory] - <Returning cached instance of singleton bean 'proxyController'> 2017-06-26 08:41:59,003 DEBUG [org.springframework.web.servlet.DispatcherServlet] - <Last-Modified value for [/cas/proxy] is: -1> 2017-06-26 08:41:59,003 DEBUG [org.springframework.web.servlet.mvc.WebContentInterceptor] - <Looking up cache seconds for [/proxy]> 2017-06-26 08:41:59,004 DEBUG [org.springframework.web.servlet.mvc.WebContentInterceptor] - <Applying default cache seconds to [/proxy]> 2017-06-26 08:41:59,008 DEBUG [org.springframework.web.servlet.mvc.method.annotation.ExceptionHandlerExceptionResolver] - <Resolving exception from handler [protected org.springframework.web.servlet.ModelAndView org.apereo.cas.web.ProxyController.handleRequestInternal(javax.servlet.http.HttpServletRequest,javax.servlet.http.HttpServletResponse)]: java.lang.NullPointerException> 2017-06-26 08:41:59,012 DEBUG [org.springframework.web.servlet.mvc.annotation.ResponseStatusExceptionResolver] - <Resolving exception from handler [protected org.springframework.web.servlet.ModelAndView org.apereo.cas.web.ProxyController.handleRequestInternal(javax.servlet.http.HttpServletRequest,javax.servlet.http.HttpServletResponse)]: java.lang.NullPointerException> 2017-06-26 08:41:59,013 DEBUG [org.springframework.web.servlet.mvc.support.DefaultHandlerExceptionResolver] - <Resolving exception from handler [protected org.springframework.web.servlet.ModelAndView org.apereo.cas.web.ProxyController.handleRequestInternal(javax.servlet.http.HttpServletRequest,javax.servlet.http.HttpServletResponse)]: java.lang.NullPointerException> 2017-06-26 08:41:59,015 DEBUG [org.apereo.cas.web.FlowExecutionExceptionResolver] - <Ignoring the received exception due to a type mismatch> java.lang.NullPointerException at org.apereo.cas.web.ProxyController.getTargetService(ProxyController.java:102) ~[cas-server-support-validation-5.0.6.jar:5.0.6] at org.apereo.cas.web.ProxyController.handleRequestInternal(ProxyController.java:78) ~[cas-server-support-validation-5.0.6.jar:5.0.6] at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) ~[?:1.8.0_121] at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) ~[?:1.8.0_121] at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) ~[?:1.8.0_121] at java.lang.reflect.Method.invoke(Method.java:498) ~[?:1.8.0_121] at org.springframework.web.method.support.InvocableHandlerMethod.doInvoke(InvocableHandlerMethod.java:220) ~[spring-web-4.3.4.RELEASE.jar:4.3.4.RELEASE] x.x.x.x - - [26/Jun/2017:08:41:41 -0700] "POST /cas/login?service=https%3A%2F%2Fcas-hostname%2FNAUappl%2FManager HTTP/1.1" 401 5593 "https://cas-hostname/cas/login?ser vice=https%3A%2F%2Fcas-hostname%2FNAUappl%2FManager" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10.12; rv:54.0) Gecko/20100101 Firefox/54.0" x.x.x.x - - [26/Jun/2017:08:41:48 -0700] "POST /cas/login?service=https%3A%2F%2Fcas-hostname%2FNAUappl%2FManager HTTP/1.1" 401 5583 "https://cas-hostname/cas/login?ser vice=https%3A%2F%2Fcas-hostname%2FNAUappl%2FManager" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10.12; rv:54.0) Gecko/20100101 Firefox/54.0" x.x.x.x - - [26/Jun/2017:08:41:57 -0700] "POST /cas/login?service=https%3A%2F%2Fcas-hostname%2FNAUappl%2FManager HTTP/1.1" 302 - "https://cas-hostname/cas/login?servic e=https%3A%2F%2Fcas-hostname%2FNAUappl%2FManager" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10.12; rv:54.0) Gecko/20100101 Firefox/54.0" x.x.x.x - - [26/Jun/2017:08:41:58 -0700] "GET /NAUappl/NAUapplServlet?ApplicationPath=https%3A%2F%2Fcas-hostname%2FNAUappl%2FManager HTTP/1.1" 200 - "-" "Apache-HttpClien t/4.5.2 (Java/1.8.0_121)" x.x.x.x - - [26/Jun/2017:08:41:58 -0700] "GET /NAUappl/NAUapplServlet?ApplicationPath=https%3A%2F%2Fcas-hostname%2FNAUappl%2FManager&pgtIou=PGTIOU-1-pmDSVpWVaRwt2g1MITjBe nCHURfMCRZNWnCKMZRcU72UiIkfJa-xxxx.xxxx.xxxx.xxxx&pgtId=PGT-2-mQh1d54OcYDHRxixgExHe6Taxsq3udDA9HWkzkGLvWoso96jwh-xxxx.xxxx.xxxx.xxxx HTTP/1.1" 200 58 "-" "Apache-HttpClient/4.5.2 (Jav a/1.8.0_121)" x.x.x.x - - [26/Jun/2017:08:41:58 -0700] "GET /cas/serviceValidate?ticket=ST-1-1Ck9h31F79dXIb5Gsu5e-xxxx.xxxx.xxxx.xxxx&service=https%3A%2F%2Fcas-hostname%2FNAUappl%2FMana ger&pgtUrl=https%3A%2F%2Fcas-hostname%2FNAUappl%2FNAUapplServlet%3FApplicationPath%3Dhttps%253A%252F%252Fcas-hostname%252FNAUappl%252FManager HTTP/1.1" 200 338 "-" "Java/1.8.0 _121" x.x.x.x - - [26/Jun/2017:08:41:58 -0700] "GET /NAUappl/Manager?ticket=ST-1-1Ck9h31F79dXIb5Gsu5e-xxxx.xxxx.xxxx.xxxx HTTP/1.1" 302 - "https://cas-hostname/cas/login?service =https%3A%2F%2Fcas-hostname%2FNAUappl%2FManager" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10.12; rv:54.0) Gecko/20100101 Firefox/54.0" x.x.x.x - - [26/Jun/2017:08:41:59 -0700] "GET /cas/proxy?pgt=PGT-2-mQh1d54OcYDHRxixgExHe6Taxsq3udDA9HWkzkGLvWoso96jwh-xxxx.xxxx.xxxx.xxxx&targetService=https://cas-hostname/NAUappl/Manager HTTP/1.1" 500 12099 "-" "Java/1.8.0_121" x.x.x.x - - [26/Jun/2017:08:41:59 -0700] "GET /NAUappl/NAUapplServlet?pgtiou=PGTIOU-1-pmDSVpWVaRwt2g1MITjBenCHURfMCRZNWnCKMZRcU72UiIkfJa-xxxx.xxxx.xxxx.xxxx&targetService=https://cas-hostname/NAUappl/Manager HTTP/1.1" 500 126 "-" "Java/1.8.0_121" x.x.x.x - - [26/Jun/2017:08:41:59 -0700] "GET /NAUappl/Manager HTTP/1.1" 302 - "https://cas-hostname/cas/login?service=https%3A%2F%2Fcas-hostname%2FNAUappl%2FManager"; "Mozilla/5.0 (Macintosh; Intel Mac OS X 10.12; rv:54.0) Gecko/20100101 Firefox/54.0" { "@class" : "org.apereo.cas.services.RegexRegisteredService", "serviceId" : ".*", "name" : "allow all", "id" : 10000005, "evaluationOrder" : 5, "evaluationOrder" : 10005 "proxyPolicy" : { "@class" : "org.apereo.cas.services.RegexMatchingRegisteredServiceProxyPolicy", "pattern" : ".*" } }
