Below is the execution log: 2014-02-20 09:49:37,868 DEBUG [org.springframework.web.servlet.DispatcherServlet] - <DispatcherServlet with name 'cas' processing POST request for [/login]> 2014-02-20 09:49:37,868 DEBUG [org.springframework.webflow.mvc.servlet.FlowHandlerMapping] - <Mapping request with URI '/login' to flow with id 'login'> 2014-02-20 09:49:37,868 DEBUG [org.springframework.webflow.executor.FlowExecutorImpl] - <Resuming flow execution with key 'e1s1> 2014-02-20 09:49:37,869 DEBUG [org.springframework.webflow.mvc.servlet.FlowHandlerAdapter] - <Restarting a new execution of previously ended flow 'login'> 2014-02-20 09:49:37,869 DEBUG [org.springframework.web.servlet.DispatcherServlet] - <Null ModelAndView returned to DispatcherServlet with name 'cas': assuming HandlerAdapter completed request handling> 2014-02-20 09:49:37,869 DEBUG [org.springframework.web.servlet.DispatcherServlet] - <Successfully completed request> 2014-02-20 09:49:37,981 DEBUG [org.springframework.web.servlet.DispatcherServlet] - <DispatcherServlet with name 'cas' processing GET request for [/login]> 2014-02-20 09:49:37,981 DEBUG [org.springframework.webflow.mvc.servlet.FlowHandlerMapping] - <Mapping request with URI '/login' to flow with id 'login'> 2014-02-20 09:49:37,981 DEBUG [org.springframework.web.servlet.DispatcherServlet] - <Last-Modified value for [/login] is: -1> 2014-02-20 09:49:37,982 DEBUG [org.springframework.webflow.executor.FlowExecutorImpl] - <Launching new execution of flow 'login' with input map['service' -> 'http://localmoodle2.hbmeu.ac.ae/login/index.php']> 2014-02-20 09:49:37,982 DEBUG [org.springframework.webflow.definition.registry.FlowDefinitionRegistryImpl] - <Getting FlowDefinition with id 'login'> 2014-02-20 09:49:37,982 DEBUG [org.springframework.webflow.engine.impl.FlowExecutionImplFactory] - <Creating new execution of 'login'> 2014-02-20 09:49:37,982 DEBUG [org.springframework.webflow.execution.factory.ConditionalFlowExecutionListenerLoader] - <Loaded [1] of possible 1 listeners for this execution request for flow 'login', the listeners to attach are list[org.jasig.cas.web.flow.TerminateWebSessionListener@59730e89]> 2014-02-20 09:49:37,982 DEBUG [org.springframework.webflow.engine.impl.FlowExecutionImpl] - <Starting in org.springframework.webflow.mvc.servlet.MvcExternalContext@c663e57 with input map['service' -> 'http://localmoodle2.hbmeu.ac.ae/login/index.php']> 2014-02-20 09:49:37,982 DEBUG [org.springframework.webflow.engine.Flow] - <Creating [FlowVariable@3149af25 name = 'credentials', valueFactory = [BeanFactoryVariableValueFactory@5c89bf6a type = UsernamePasswordCredentials]]> 2014-02-20 09:49:37,982 DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory] - <Creating instance of bean 'org.jasig.cas.authentication.principal.UsernamePasswordCredentials'> 2014-02-20 09:49:37,982 DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory] - <Finished creating instance of bean 'org.jasig.cas.authentication.principal.UsernamePasswordCredentials'> 2014-02-20 09:49:37,983 DEBUG [org.springframework.webflow.execution.ActionExecutor] - <Executing [EvaluateAction@5e9fcf2 expression = initialFlowSetupAction, resultExpression = [null]]> 2014-02-20 09:49:37,983 DEBUG [org.springframework.webflow.execution.AnnotatedAction] - <Putting action execution attributes map[[empty]]> 2014-02-20 09:49:37,983 DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory] - <Returning cached instance of singleton bean 'initialFlowSetupAction'> 2014-02-20 09:49:37,983 DEBUG [org.springframework.webflow.execution.ActionExecutor] - <Executing org.jasig.cas.web.flow.InitialFlowSetupAction@1a392bfc> 2014-02-20 09:49:37,983 DEBUG [org.jasig.cas.web.support.CasArgumentExtractor] - <Extractor generated service for: http://localmoodle2.hbmeu.ac.ae/login/index.php> 2014-02-20 09:49:37,983 DEBUG [org.jasig.cas.web.flow.InitialFlowSetupAction] - <Placing service in FlowScope: http://localmoodle2.hbmeu.ac.ae/login/index.php> 2014-02-20 09:49:37,984 DEBUG [org.springframework.webflow.execution.ActionExecutor] - <Finished executing org.jasig.cas.web.flow.InitialFlowSetupAction@1a392bfc; result = success> 2014-02-20 09:49:37,984 DEBUG [org.springframework.webflow.execution.AnnotatedAction] - <Clearing action execution attributes map[[empty]]> 2014-02-20 09:49:37,984 DEBUG [org.springframework.webflow.execution.ActionExecutor] - <Finished executing [EvaluateAction@5e9fcf2 expression = initialFlowSetupAction, resultExpression = [null]]; result = success> 2014-02-20 09:49:37,984 DEBUG [org.springframework.webflow.engine.DecisionState] - <Entering state 'ticketGrantingTicketExistsCheck' of flow 'login'> 2014-02-20 09:49:37,984 DEBUG [org.springframework.webflow.engine.Transition] - <Executing [Transition@bd4272c on = flowScope.ticketGrantingTicketId != null, to = hasServiceCheck]> 2014-02-20 09:49:37,984 DEBUG [org.springframework.webflow.engine.Transition] - <Exiting state 'ticketGrantingTicketExistsCheck'> 2014-02-20 09:49:37,984 DEBUG [org.springframework.webflow.engine.DecisionState] - <Entering state 'hasServiceCheck' of flow 'login'> 2014-02-20 09:49:37,984 DEBUG [org.springframework.webflow.engine.Transition] - <Executing [Transition@1557bfc5 on = flowScope.service != null, to = renewRequestCheck]> 2014-02-20 09:49:37,984 DEBUG [org.springframework.webflow.engine.Transition] - <Exiting state 'hasServiceCheck'> 2014-02-20 09:49:37,984 DEBUG [org.springframework.webflow.engine.DecisionState] - <Entering state 'renewRequestCheck' of flow 'login'> 2014-02-20 09:49:37,984 DEBUG [org.springframework.webflow.engine.Transition] - <Executing [Transition@7bfacf04 on = *, to = generateServiceTicket]> 2014-02-20 09:49:37,984 DEBUG [org.springframework.webflow.engine.Transition] - <Exiting state 'renewRequestCheck'> 2014-02-20 09:49:37,984 DEBUG [org.springframework.webflow.engine.ActionState] - <Entering state 'generateServiceTicket' of flow 'login'> 2014-02-20 09:49:37,985 DEBUG [org.springframework.webflow.execution.ActionExecutor] - <Executing [EvaluateAction@f2d4f33 expression = generateServiceTicketAction, resultExpression = [null]]> 2014-02-20 09:49:37,985 DEBUG [org.springframework.webflow.execution.AnnotatedAction] - <Putting action execution attributes map[[empty]]> 2014-02-20 09:49:37,985 DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory] - <Returning cached instance of singleton bean 'generateServiceTicketAction'> 2014-02-20 09:49:37,985 DEBUG [org.springframework.webflow.execution.ActionExecutor] - <Executing org.jasig.cas.web.flow.GenerateServiceTicketAction@67aaa50d> 2014-02-20 09:49:37,985 DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory] - <Returning cached instance of singleton bean 'transactionManager'> 2014-02-20 09:49:37,985 DEBUG [org.springframework.orm.jpa.JpaTransactionManager] - <Creating new transaction with name [org.jasig.cas.CentralAuthenticationServiceImpl.grantServiceTicket]: PROPAGATION_REQUIRED,ISOLATION_DEFAULT; ''> 2014-02-20 09:49:37,985 DEBUG [org.springframework.orm.jpa.JpaTransactionManager] - <Opened new EntityManager [org.hibernate.ejb.EntityManagerImpl@c86c81d] for JPA transaction> 2014-02-20 09:49:37,985 DEBUG [org.springframework.orm.jpa.JpaTransactionManager] - <Exposing JPA transaction as JDBC transaction [org.springframework.orm.jpa.vendor.HibernateJpaDialect$HibernateConnectionHandle@64bbee57]> 2014-02-20 09:49:37,988 DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory] - <Returning cached instance of singleton bean 'transactionManager'> 2014-02-20 09:49:37,988 DEBUG [org.springframework.orm.jpa.JpaTransactionManager] - <Found thread-bound EntityManager [org.hibernate.ejb.EntityManagerImpl@c86c81d] for JPA transaction> 2014-02-20 09:49:37,988 DEBUG [org.springframework.orm.jpa.JpaTransactionManager] - <Participating in existing transaction> 2014-02-20 09:49:37,989 INFO [com.github.inspektr.audit.support.Slf4jLoggingAuditTrailManager] - <Audit trail record BEGIN 2014-02-20 09:49:37,989 DEBUG [org.springframework.orm.jpa.JpaTransactionManager] - <Initiating transaction commit> 2014-02-20 09:49:37,989 DEBUG [org.springframework.orm.jpa.JpaTransactionManager] - <Committing JPA transaction on EntityManager [org.hibernate.ejb.EntityManagerImpl@c86c81d]> 2014-02-20 09:49:37,990 DEBUG [org.springframework.orm.jpa.JpaTransactionManager] - <Closing JPA EntityManager [org.hibernate.ejb.EntityManagerImpl@c86c81d] after transaction> 2014-02-20 09:49:37,990 DEBUG [org.springframework.orm.jpa.EntityManagerFactoryUtils] - <Closing JPA EntityManager> 2014-02-20 09:49:37,990 DEBUG [org.springframework.webflow.execution.ActionExecutor] - <Finished executing org.jasig.cas.web.flow.GenerateServiceTicketAction@67aaa50d; result = error> 2014-02-20 09:49:37,990 DEBUG [org.springframework.webflow.execution.AnnotatedAction] - <Clearing action execution attributes map[[empty]]> 2014-02-20 09:49:37,990 DEBUG [org.springframework.webflow.execution.ActionExecutor] - <Finished executing [EvaluateAction@f2d4f33 expression = generateServiceTicketAction, resultExpression = [null]]; result = error> 2014-02-20 09:49:37,990 DEBUG [org.springframework.webflow.engine.Transition] - <Executing [Transition@12e0781e on = error, to = generateLoginTicket]> 2014-02-20 09:49:37,991 DEBUG [org.springframework.webflow.engine.Transition] - <Exiting state 'generateServiceTicket'> 2014-02-20 09:49:37,991 DEBUG [org.springframework.webflow.engine.ActionState] - <Entering state 'generateLoginTicket' of flow 'login'> 2014-02-20 09:49:37,991 DEBUG [org.springframework.webflow.execution.ActionExecutor] - <Executing [EvaluateAction@4eee0340 expression = generateLoginTicketAction.generate(flowRequestContext), resultExpression = [null]]> 2014-02-20 09:49:37,991 DEBUG [org.springframework.webflow.execution.AnnotatedAction] - <Putting action execution attributes map[[empty]]> 2014-02-20 09:49:37,992 DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory] - <Returning cached instance of singleton bean 'generateLoginTicketAction'> 2014-02-20 09:49:37,992 DEBUG [org.jasig.cas.web.flow.GenerateLoginTicketAction] - <Generated login ticket LT-3-pO3TzjeHqKRyeMH3PKewlYYVeDCIxm> 2014-02-20 09:49:37,992 DEBUG [org.springframework.webflow.execution.AnnotatedAction] - <Clearing action execution attributes map[[empty]]> 2014-02-20 09:49:37,992 DEBUG [org.springframework.webflow.execution.ActionExecutor] - <Finished executing [EvaluateAction@4eee0340 expression = generateLoginTicketAction.generate(flowRequestContext), resultExpression = [null]]; result = generated> 2014-02-20 09:49:37,992 DEBUG [org.springframework.webflow.engine.Transition] - <Executing [Transition@27d8080 on = generated, to = viewLoginForm]> 2014-02-20 09:49:37,992 DEBUG [org.springframework.webflow.engine.Transition] - <Exiting state 'generateLoginTicket'> 2014-02-20 09:49:37,992 DEBUG [org.springframework.webflow.engine.ViewState] - <Entering state 'viewLoginForm' of flow 'login'> 2014-02-20 09:49:37,992 DEBUG [org.springframework.webflow.execution.ActionExecutor] - <Executing [SetAction@7d7f54c6 name = viewScope.commandName, value = 'credentials']> 2014-02-20 09:49:37,992 DEBUG [org.springframework.webflow.execution.AnnotatedAction] - <Putting action execution attributes map[[empty]]> 2014-02-20 09:49:37,992 DEBUG [org.springframework.webflow.execution.AnnotatedAction] - <Clearing action execution attributes map[[empty]]> 2014-02-20 09:49:37,992 DEBUG [org.springframework.webflow.execution.ActionExecutor] - <Finished executing [SetAction@7d7f54c6 name = viewScope.commandName, value = 'credentials']; result = success> 2014-02-20 09:49:37,995 DEBUG [org.springframework.webflow.conversation.impl.SessionBindingConversationManager] - <Putting conversation attribute 'name' with value login> 2014-02-20 09:49:37,995 DEBUG [org.springframework.webflow.conversation.impl.SessionBindingConversationManager] - <Putting conversation attribute 'caption' with value null> 2014-02-20 09:49:37,995 DEBUG [org.springframework.webflow.conversation.impl.SessionBindingConversationManager] - <Putting conversation attribute 'description' with value null> 2014-02-20 09:49:37,995 DEBUG [org.springframework.webflow.conversation.impl.SessionBindingConversationManager] - <Putting conversation attribute 'flowExecutionSnapshotGroup' with value org.springframework.webflow.execution.repository.impl.SimpleFlowExecutionSnapshotGroup@1689e34b> 2014-02-20 09:49:37,995 DEBUG [org.springframework.webflow.engine.impl.FlowExecutionImpl] - <Assigned key e1s1> 2014-02-20 09:49:37,995 DEBUG [org.springframework.webflow.engine.ViewState] - <Rendering + [ServletMvcView@36a95c7c view = org.springframework.web.servlet.view.JstlView: name 'casLoginView'; URL [/WEB-INF/view/jsp/ecampus/ui/casLoginView.jsp]]> 2014-02-20 09:49:37,995 DEBUG [org.springframework.webflow.engine.ViewState] - < Flash scope = map[[empty]]> 2014-02-20 09:49:37,995 DEBUG [org.springframework.webflow.engine.ViewState] - < Messages = [DefaultMessageContext@28f6eced sourceMessages = map[[null] -> list[[empty]]]]> 2014-02-20 09:49:37,996 DEBUG [org.springframework.webflow.mvc.view.AbstractMvcView] - <Rendering MVC [org.springframework.web.servlet.view.JstlView: name 'casLoginView'; URL [/WEB-INF/view/jsp/ecampus/ui/casLoginView.jsp]] with model map [{flowRequestContext=[RequestControlContextImpl@65a42a6b externalContext = org.springframework.webflow.mvc.servlet.MvcExternalContext@c663e57, currentEvent = generated, requestScope = map[[empty]], attributes = map[[empty]], messageContext = [DefaultMessageContext@28f6eced sourceMessages = map[[null] -> list[[empty]]]], flowExecution = [FlowExecutionImpl@7cdd0aee flow = 'login', flowSessions = list[[FlowSessionImpl@13f8cf4d flow = 'login', state = 'viewLoginForm', scope = map['loginTicket' -> 'LT-3-pO3TzjeHqKRyeMH3PKewlYYVeDCIxm', 'service' -> http://localmoodle2.hbmeu.ac.ae/login/index.php, 'credentials' -> [username: null], 'warnCookieValue' -> false, 'ticketGrantingTicketId' -> 'TGT-1-xaZasCngmUOPbeTLFEqlvJmzNOgQ7T61mEDmHC9J0NMQGpipaH-localcas.hbmeu.ac.ae', 'viewScope' -> map['commandName' -> 'credentials']]]]]], flashScope=map[[empty]], currentUser=null, loginTicket=LT-3-pO3TzjeHqKRyeMH3PKewlYYVeDCIxm, service=http://localmoodle2.hbmeu.ac.ae/login/index.php, org.springframework.validation.BindingResult.credentials=org.springframework.webflow.mvc.view.BindingModel: 0 errors, commandName=credentials, credentials=[username: null], flowExecutionKey=e1s1, warnCookieValue=false, flowExecutionUrl=/login?service=%5BLjava.lang.String%3B%406b3cd09d, ticketGrantingTicketId=TGT-1-xaZasCngmUOPbeTLFEqlvJmzNOgQ7T61mEDmHC9J0NMQGpipaH-localcas.hbmeu.ac.ae, viewScope=map['commandName' -> 'credentials']}]> 2014-02-20 09:49:37,996 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 'casLoginView'> 2014-02-20 09:49:37,996 DEBUG [org.springframework.web.servlet.view.JstlView] - <Removed model object 'currentUser' from request in view with name 'casLoginView'> 2014-02-20 09:49:37,997 DEBUG [org.springframework.web.servlet.view.JstlView] - <Added model object 'loginTicket' of type [java.lang.String] to request in view with name 'casLoginView'> 2014-02-20 09:49:37,997 DEBUG [org.springframework.web.servlet.view.JstlView] - <Added model object 'org.springframework.validation.BindingResult.credentials' of type [org.springframework.webflow.mvc.view.BindingModel] to request in view with name 'casLoginView'> 2014-02-20 09:49:37,997 DEBUG [org.springframework.web.servlet.view.JstlView] - <Added model object 'flowExecutionKey' of type [java.lang.String] to request in view with name 'casLoginView'> 2014-02-20 09:49:37,997 DEBUG [org.springframework.web.servlet.view.JstlView] - <Added model object 'warnCookieValue' of type [java.lang.Boolean] to request in view with name 'casLoginView'> 2014-02-20 09:49:37,997 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 'casLoginView'> 2014-02-20 09:49:37,997 DEBUG [org.springframework.web.servlet.view.JstlView] - <Added model object 'service' of type [org.jasig.cas.authentication.principal.SimpleWebApplicationServiceImpl] to request in view with name 'casLoginView'> 2014-02-20 09:49:37,997 DEBUG [org.springframework.web.servlet.view.JstlView] - <Added model object 'commandName' of type [java.lang.String] to request in view with name 'casLoginView'> 2014-02-20 09:49:37,997 DEBUG [org.springframework.web.servlet.view.JstlView] - <Added model object 'credentials' of type [org.jasig.cas.authentication.principal.UsernamePasswordCredentials] to request in view with name 'casLoginView'> 2014-02-20 09:49:37,997 DEBUG [org.springframework.web.servlet.view.JstlView] - <Added model object 'flowExecutionUrl' of type [java.lang.String] to request in view with name 'casLoginView'> 2014-02-20 09:49:37,997 DEBUG [org.springframework.web.servlet.view.JstlView] - <Added model object 'ticketGrantingTicketId' of type [java.lang.String] to request in view with name 'casLoginView'> 2014-02-20 09:49:37,997 DEBUG [org.springframework.web.servlet.view.JstlView] - <Added model object 'viewScope' of type [org.springframework.webflow.core.collection.LocalAttributeMap] to request in view with name 'casLoginView'> 2014-02-20 09:49:37,997 DEBUG [org.springframework.web.servlet.view.JstlView] - <Forwarding to resource [/WEB-INF/view/jsp/ecampus/ui/casLoginView.jsp] in InternalResourceView 'casLoginView'> 2014-02-20 09:49:37,998 DEBUG [org.jasig.cas.web.support.CasArgumentExtractor] - <Extractor generated service for: http://localmoodle2.hbmeu.ac.ae/login/index.php> 2014-02-20 09:49:38,001 DEBUG [org.springframework.webflow.engine.Transition] - <Completed transition execution. As a result, the new state is 'viewLoginForm' in flow 'login'> 2014-02-20 09:49:38,001 DEBUG [org.springframework.webflow.engine.Transition] - <Completed transition execution. As a result, the new state is 'viewLoginForm' in flow 'login'> 2014-02-20 09:49:38,002 DEBUG [org.springframework.webflow.engine.Transition] - <Completed transition execution. As a result, the new state is 'viewLoginForm' in flow 'login'> 2014-02-20 09:49:38,002 DEBUG [org.springframework.webflow.engine.Transition] - <Completed transition execution. As a result, the new state is 'viewLoginForm' in flow 'login'> 2014-02-20 09:49:38,002 DEBUG [org.springframework.webflow.engine.Transition] - <Completed transition execution. As a result, the new state is 'viewLoginForm' in flow 'login'> 2014-02-20 09:49:38,002 DEBUG [org.springframework.webflow.conversation.impl.SessionBindingConversationManager] - <Locking conversation 1> 2014-02-20 09:49:38,002 DEBUG [org.springframework.webflow.execution.repository.impl.DefaultFlowExecutionRepository] - <Putting flow execution '[FlowExecutionImpl@7cdd0aee flow = 'login', flowSessions = list[[FlowSessionImpl@13f8cf4d flow = 'login', state = 'viewLoginForm', scope = map['loginTicket' -> 'LT-3-pO3TzjeHqKRyeMH3PKewlYYVeDCIxm', 'service' -> http://localmoodle2.hbmeu.ac.ae/login/index.php, 'credentials' -> [username: null], 'warnCookieValue' -> false, 'ticketGrantingTicketId' -> 'TGT-1-xaZasCngmUOPbeTLFEqlvJmzNOgQ7T61mEDmHC9J0NMQGpipaH-localcas.hbmeu.ac.ae', 'viewScope' -> map['commandName' -> 'credentials']]]]]' into repository> 2014-02-20 09:49:38,002 DEBUG [org.springframework.webflow.execution.repository.impl.DefaultFlowExecutionRepository] - <Adding snapshot to group with id 1> 2014-02-20 09:49:38,003 DEBUG [org.springframework.webflow.conversation.impl.SessionBindingConversationManager] - <Putting conversation attribute 'scope' with value map['flashScope' -> map['messagesMemento' -> map[[empty]]]]> 2014-02-20 09:49:38,003 DEBUG [org.springframework.webflow.conversation.impl.SessionBindingConversationManager] - <Unlocking conversation 1> 2014-02-20 09:49:38,003 DEBUG [org.springframework.web.servlet.DispatcherServlet] - <Null ModelAndView returned to DispatcherServlet with name 'cas': assuming HandlerAdapter completed request handling> 2014-02-20 09:49:38,003 DEBUG [org.springframework.web.servlet.DispatcherServlet] - <Successfully completed request>
-- You are currently subscribed to [email protected] as: [email protected] To unsubscribe, change settings or access archives, see http://www.ja-sig.org/wiki/display/JSG/cas-user
