empty map

[marco.bencivenni]

Dear all,

we use casshib version 3.4.2a and all was ok until this morning when
suddenly we have a problem in our casshib configuration, the error
reported on the browser is:

"Authentication Error

The system wasn't able to properly detect your authentication
credentials. This could be a problem with the configuration of the
system. Please contact your administrator."

we didn't touch the filter in cas and in shibboleth but the logs are:

2012-04-26 12:09:52,924 DEBUG
[org.springframework.web.servlet.DispatcherServlet] -
<DispatcherServlet with name 'cas' determining Last-Modified value for
[/casshib/shib/app1/login]>
2012-04-26 12:09:52,924 DEBUG
[org.springframework.webflow.mvc.servlet.FlowHandlerMapping] - <No
flow mapping found for request with URI '/casshib/shib/app1/login'>
2012-04-26 12:09:52,925 DEBUG
[org.springframework.web.servlet.handler.SimpleUrlHandlerMapping] -
<Matching patterns for request [/shib/app1/login] are [/shib/*/login]>
2012-04-26 12:09:52,925 DEBUG
[org.springframework.web.servlet.handler.SimpleUrlHandlerMapping] -
<Mapping [/shib/app1/login] to handler
'org.springframework.webflow.mvc.servlet.FlowController@28caea19'>
2012-04-26 12:09:52,925 DEBUG
[org.springframework.web.servlet.DispatcherServlet] - <Last-Modified
value for [/casshib/shib/app1/login] is: -1>
2012-04-26 12:09:52,926 DEBUG
[org.springframework.web.servlet.DispatcherServlet] -
<DispatcherServlet with name 'cas' processing GET request for [/
casshib/shib/app1/login]>
2012-04-26 12:09:52,926 DEBUG
[org.springframework.webflow.executor.FlowExecutorImpl] - <Launching
new execution of flow 'login' with input map['service' -> 'https://
halfback.cnaf.infn.it/app1/index.jsp']>
2012-04-26 12:09:52,926 DEBUG
[org.springframework.webflow.definition.registry.FlowDefinitionRegistryImpl]
- <Getting FlowDefinition with id 'login'>
2012-04-26 12:09:52,926 DEBUG
[org.springframework.webflow.engine.impl.FlowExecutionImplFactory] -
<Creating new execution of 'login'>
2012-04-26 12:09:52,926 DEBUG
[org.springframework.webflow.engine.impl.FlowExecutionImpl] -
<Starting in
org.springframework.webflow.mvc.servlet.MvcExternalContext@2c5e5c15
with input map['service' -> 'https://halfback.cnaf.infn.it/app1/
index.jsp']>
2012-04-26 12:09:52,926 DEBUG
[org.springframework.webflow.execution.ActionExecutor] - <Executing
[EvaluateAction@6399f443 expression = casShibInitialFlowSetupAction,
resultExposer = [null]]>
2012-04-26 12:09:52,926 DEBUG
[org.springframework.webflow.execution.AnnotatedAction] - <Putting
action execution attributes map[[empty]]>
2012-04-26 12:09:52,926 DEBUG
[org.springframework.beans.factory.support.DefaultListableBeanFactory]
- <Returning cached instance of singleton bean
'casShibInitialFlowSetupAction'>
2012-04-26 12:09:52,926 DEBUG
[org.springframework.webflow.execution.ActionExecutor] - <Executing
edu.ucmerced.cas.web.flow.CasShibInitialFlowSetupAction@30b5ac2c>
2012-04-26 12:09:52,927 DEBUG
[org.springframework.webflow.execution.ActionExecutor] - <Finished
executing
edu.ucmerced.cas.web.flow.CasShibInitialFlowSetupAction@30b5ac2c;
result = success>
2012-04-26 12:09:52,928 DEBUG
[org.springframework.webflow.execution.AnnotatedAction] - <Clearing
action execution attributes map[[empty]]>
2012-04-26 12:09:52,928 DEBUG
[org.springframework.webflow.execution.ActionExecutor] - <Finished
executing [EvaluateAction@6399f443 expression =
casShibInitialFlowSetupAction, resultExposer = [null]]; result =
success>
2012-04-26 12:09:52,928 DEBUG
[org.springframework.webflow.engine.DecisionState] - <Entering state
'ticketGrantingTicketExistsCheck' of flow 'login'>
2012-04-26 12:09:52,928 DEBUG
[org.springframework.webflow.engine.Transition] - <Executing
[Transition@6719b1ca on = *, to = gatewayRequestCheck]>
2012-04-26 12:09:52,928 DEBUG
[org.springframework.webflow.engine.Transition] - <Exiting state
'ticketGrantingTicketExistsCheck'>
2012-04-26 12:09:52,928 DEBUG
[org.springframework.webflow.engine.DecisionState] - <Entering state
'gatewayRequestCheck' of flow 'login'>
2012-04-26 12:09:52,928 DEBUG
[org.springframework.webflow.engine.Transition] - <Executing
[Transition@5e6e3fe4 on = *, to = remoteAuthenticate]>
2012-04-26 12:09:52,928 DEBUG
[org.springframework.webflow.engine.Transition] - <Exiting state
'gatewayRequestCheck'>
2012-04-26 12:09:52,928 DEBUG
[org.springframework.webflow.engine.ActionState] - <Entering state
'remoteAuthenticate' of flow 'login'>
2012-04-26 12:09:52,928 DEBUG
[org.springframework.webflow.execution.ActionExecutor] - <Executing
[EvaluateAction@1824c22b expression = principalFromRemoteAction,
resultExposer = [null]]>
2012-04-26 12:09:52,928 DEBUG
[org.springframework.webflow.execution.AnnotatedAction] - <Putting
action execution attributes map[[empty]]>
2012-04-26 12:09:52,928 DEBUG
[org.springframework.beans.factory.support.DefaultListableBeanFactory]
- <Returning cached instance of singleton bean
'principalFromRemoteAction'>
2012-04-26 12:09:52,928 DEBUG
[org.springframework.webflow.execution.ActionExecutor] - <Executing
edu.ucmerced.cas.adaptors.casshib.web.flow.PrincipalFromHttpHeadersNonInteractiveCredentialsAction@1839474b>
2012-04-26 12:09:52,929 DEBUG
[org.springframework.webflow.execution.ActionExecutor] - <Finished
executing
edu.ucmerced.cas.adaptors.casshib.web.flow.PrincipalFromHttpHeadersNonInteractiveCredentialsAction@1839474b;
result = error>
2012-04-26 12:09:52,929 DEBUG
[org.springframework.webflow.execution.AnnotatedAction] - <Clearing
action execution attributes map[[empty]]>
2012-04-26 12:09:52,929 DEBUG
[org.springframework.webflow.execution.ActionExecutor] - <Finished
executing [EvaluateAction@1824c22b expression =
principalFromRemoteAction, resultExposer = [null]]; result = error>
2012-04-26 12:09:52,929 DEBUG
[org.springframework.webflow.engine.Transition] - <Executing
[Transition@1547a16f on = error, to = casShibAuthErrorView]>
2012-04-26 12:09:52,929 DEBUG
[org.springframework.webflow.engine.Transition] - <Exiting state
'remoteAuthenticate'>
2012-04-26 12:09:52,929 DEBUG
[org.springframework.webflow.engine.EndState] - <Entering state
'casShibAuthErrorView' of flow 'login'>
2012-04-26 12:09:52,929 DEBUG
[org.springframework.webflow.execution.ActionExecutor] - <Executing
org.springframework.webflow.action.ViewFactoryActionAdapter@11b78953>
2012-04-26 12:09:52,929 DEBUG
[org.springframework.webflow.mvc.view.AbstractMvcView] - <Rendering
MVC [org.springframework.web.servlet.view.JstlView: name
'casShibAuthErrorView'; URL [/WEB-INF/view/jsp/default/ui/
casShibAuthErrorView.jsp]] with model map
[{flowRequestContext=[RequestControlContextImpl@66ef7d74
externalContext =
org.springframework.webflow.mvc.servlet.MvcExternalContext@2c5e5c15,
currentEvent = error, requestScope = map[[empty]], attributes =
map[[empty]], messageContext = [DefaultMessageContext@75157f77
sourceMessages = map[[null] -> list[[empty]]]], flowExecution =
[FlowExecutionImpl@566399ae flow = 'login', flowSessions =
list[[FlowSessionImpl@24fe9ad1 flow = 'login', state =
'casShibAuthErrorView', scope = map['service' ->
https://halfback.cnaf.infn.it/app1/index.jsp, 'warnCookieValue' ->
false, 'ticketGrantingTicketId' -> [null]]]]]],
flashScope=map[[empty]], currentUser=null, service=https://
halfback.cnaf.infn.it/app1/index.jsp, warnCookieValue=false,
ticketGrantingTicketId=null}]>
2012-04-26 12:09:52,929 DEBUG
[org.springframework.web.servlet.view.JstlView] - <Added model object
'service' of type
[edu.ucmerced.cas.authentication.principal.CasShibWebApplicationServiceImpl]
to request in view with name 'casShibAuthErrorView'>
2012-04-26 12:09:52,929 DEBUG
[org.springframework.web.servlet.view.JstlView] - <Added model object
'flashScope' of type
[org.springframework.webflow.core.collection.LocalAttributeMap] to
request in view with name 'casShibAuthErrorView'>
2012-04-26 12:09:52,929 DEBUG
[org.springframework.web.servlet.view.JstlView] - <Added model object
'flowRequestContext' of type
[org.springframework.webflow.engine.impl.RequestControlContextImpl] to
request in view with name 'casShibAuthErrorView'>
2012-04-26 12:09:52,929 DEBUG
[org.springframework.web.servlet.view.JstlView] - <Added model object
'warnCookieValue' of type [java.lang.Boolean] to request in view with
name 'casShibAuthErrorView'>
2012-04-26 12:09:52,929 DEBUG
[org.springframework.web.servlet.view.JstlView] - <Removed model
object 'ticketGrantingTicketId' from request in view with name
'casShibAuthErrorView'>
2012-04-26 12:09:52,929 DEBUG
[org.springframework.web.servlet.view.JstlView] - <Removed model
object 'currentUser' from request in view with name
'casShibAuthErrorView'>
2012-04-26 12:09:52,930 DEBUG
[org.springframework.web.servlet.view.JstlView] - <Forwarding to
resource [/WEB-INF/view/jsp/default/ui/casShibAuthErrorView.jsp] in
InternalResourceView 'casShibAuthErrorView'>
2012-04-26 12:09:52,931 DEBUG
[org.jasig.cas.web.support.CasArgumentExtractor] - <Extractor
generated service for: https://halfback.cnaf.infn.it/app1/index.jsp>
2012-04-26 12:09:52,932 DEBUG
[org.springframework.webflow.execution.ActionExecutor] - <Finished
executing
org.springframework.webflow.action.ViewFactoryActionAdapter@11b78953;
result = success>
2012-04-26 12:09:52,932 DEBUG
[org.springframework.webflow.engine.Transition] - <Completed
transition execution. As a result, the flow execution has ended>
2012-04-26 12:09:52,933 DEBUG
[org.springframework.webflow.engine.Transition] - <Completed
transition execution. As a result, the flow execution has ended>
2012-04-26 12:09:52,933 DEBUG
[org.springframework.webflow.engine.Transition] - <Completed
transition execution. As a result, the flow execution has ended>
2012-04-26 12:09:52,933 DEBUG
[org.springframework.web.servlet.DispatcherServlet] - <Null
ModelAndView returned to DispatcherServlet with name 'cas': assuming
HandlerAdapter completed request handling>
2012-04-26 12:09:52,933 DEBUG
[org.springframework.web.servlet.DispatcherServlet] - <Successfully
completed request>
2012-04-26 12:09:52,979 DEBUG
[org.springframework.web.servlet.DispatcherServlet] -
<DispatcherServlet with name 'cas' determining Last-Modified value for
[/casshib/shib/app1/css/cas.css]>
2012-04-26 12:09:52,981 DEBUG
[org.springframework.webflow.mvc.servlet.FlowHandlerMapping] - <No
flow mapping found for request with URI '/casshib/shib/app1/css/
cas.css'>
2012-04-26 12:09:52,982 DEBUG
[org.springframework.web.servlet.DispatcherServlet] - <No handler
found in getLastModified>
2012-04-26 12:09:52,982 DEBUG
[org.springframework.web.servlet.DispatcherServlet] -
<DispatcherServlet with name 'cas' processing GET request for [/
casshib/shib/app1/css/cas.css]>
2012-04-26 12:09:52,982 DEBUG
[org.springframework.webflow.mvc.servlet.FlowHandlerMapping] - <No
flow mapping found for request with URI '/casshib/shib/app1/css/
cas.css'>
2012-04-26 12:09:52,983 WARN
[org.springframework.web.servlet.PageNotFound] - <No mapping found for
HTTP request with URI [/casshib/shib/app1/css/cas.css] in
DispatcherServlet with name 'cas'>
2012-04-26 12:09:52,983 DEBUG
[org.springframework.web.servlet.DispatcherServlet] - <Successfully
completed request>

Does someone have any suggestion?

Regards,
marco B