[cas-user] Error enabling passwordPolicyCheck

714 views
Skip to first unread message

ray

unread,
Jan 2, 2014, 10:22:56 AM1/2/14
to cas-...@lists.jasig.org
Hi,
I am trying to integrate lppe with cas for days now.

I followed: https://wiki.jasig.org/pages/viewpage.action?pageId=26149328

I am trying to authenticate with a valid user and for some reason i am being redirected to cas error page(CAS is Unavailable
There was an error trying to complete your request. Please notify your support desk or try again..)

This is from the logs:


Audit trail record BEGIN
=============================================================
WHO: [username: iuser]
WHAT: supplied credentials: [username: iuser]
ACTION: AUTHENTICATION_SUCCESS
APPLICATION: CAS
WHEN: Thu Jan 02 17:16:34 IST 2014
CLIENT IP ADDRESS: 192.168.108.195
SERVER IP ADDRESS: 192.168.2.101
=============================================================


Audit trail record BEGIN
=============================================================
WHO: [username: iuser]
WHAT: TGT-2-0DcVF0eM01afDjmlzqwJaleis9dxcQXfUhau1xOdycZYsfLJnX-cas
ACTION: TICKET_GRANTING_TICKET_CREATED
APPLICATION: CAS
WHEN: Thu Jan 02 17:16:34 IST 2014
CLIENT IP ADDRESS: 192.168.108.195
SERVER IP ADDRESS: 192.168.2.101
=============================================================


2014-01-02 17:16:34,973 DEBUG [org.springframework.webflow.execution.AnnotatedAction] - <Clearing action execution attributes map[[empty]]>
2014-01-02 17:16:34,973 DEBUG [org.springframework.webflow.execution.ActionExecutor] - <Finished executing [EvaluateAction@7ea94b8e expression = authenticationViaFormAction.submit(flowRequestContext, flowScope.credentials, messageContext), resultExpression = [null]]; result = success>
2014-01-02 17:16:34,974 DEBUG [org.springframework.webflow.engine.Transition] - <Executing [Transition@7d71c009 on = success, to = passwordPolicyCheck]>
2014-01-02 17:16:34,974 DEBUG [org.springframework.webflow.engine.Transition] - <Exiting state 'realSubmit'>
2014-01-02 17:16:34,974 DEBUG [org.springframework.webflow.engine.ActionState] - <Entering state 'passwordPolicyCheck' of flow 'login'>
2014-01-02 17:16:34,974 DEBUG [org.springframework.webflow.execution.ActionExecutor] - <Executing [EvaluateAction@db2e9f6 expression = passwordPolicyAction, resultExpression = [null]]>
2014-01-02 17:16:34,974 DEBUG [org.springframework.webflow.execution.AnnotatedAction] - <Putting action execution attributes map[[empty]]>
2014-01-02 17:16:34,974 DEBUG [org.springframework.webflow.execution.ActionExecutor] - <Executing org.jasig.cas.web.flow.PasswordPolicyEnforcementAction@5d1e1c80>
2014-01-02 17:16:34,983 DEBUG [org.springframework.webflow.execution.AnnotatedAction] - <Clearing action execution attributes map[[empty]]>
2014-01-02 17:16:34,983 DEBUG [org.springframework.webflow.engine.impl.FlowExecutionImpl] - <Attempting to handle [org.springframework.webflow.execution.ActionExecutionException: Exception thrown executing org.jasig.cas.web.flow.PasswordPolicyEnforcementAction@5d1e1c80 in state 'passwordPolicyCheck' of flow 'login' -- action execution attributes were 'map[[empty]]'] with root cause [java.lang.NullPointerException]>
2014-01-02 17:16:34,983 DEBUG [org.springframework.webflow.engine.impl.FlowExecutionImpl] - <Rethrowing unhandled flow execution exception>
2014-01-02 17:16:34,983 DEBUG [org.springframework.webflow.conversation.impl.SessionBindingConversationManager] - <Unlocking conversation 1>


This happens after I modified the login-flow.xml:

from

<transition on="success" to="sendTicketGrantingTicket" />

to

<transition on="success" to="passwordPolicyCheck" />

I followed everything step but can't nail that one.

I attached lppe-configuration.xml , deployerConfigContext.xml,login-webflow.xml


Any idea?
thanks,
ray.



--
You are currently subscribed to cas-...@lists.jasig.org as: jasig-cas-user...@googlegroups.com
To unsubscribe, change settings or access archives, see http://www.ja-sig.org/wiki/display/JSG/cas-user
deployerConfigContext.xml
login-webflow.xml
lppe-configuration.xml

Misagh Moayyed

unread,
Jan 2, 2014, 10:47:49 AM1/2/14
to cas-...@lists.jasig.org
Somewhere in the logs, there's more info on the following exception. See if you could track that down and post back please.

2014-01-02 17:16:34,983 DEBUG [org.springframework.webflow.engine.impl.FlowExecutionImpl] - <Attempting to handle [org.springframework.webflow.execution.ActionExecutionException: Exception thrown executing org.jasig.cas.web.flow.PasswordPolicyEnforcementAction@5d1e1c80 in state 'passwordPolicyCheck' of flow 'login' -- action execution attributes were 'map[[empty]]'] with root cause [java.lang.NullPointerException


Misagh


From: "ray" <id...@cellebrite.com>
To: cas-...@lists.jasig.org
Sent: Thursday, January 2, 2014 8:22:56 AM
Subject: [cas-user] Error enabling passwordPolicyCheck
You are currently subscribed to cas-...@lists.jasig.org as: mmoa...@unicon.net

To unsubscribe, change settings or access archives, see http://www.ja-sig.org/wiki/display/JSG/cas-user

Idan Fridman

unread,
Jan 2, 2014, 10:56:19 AM1/2/14
to cas-...@lists.jasig.org

Hi,

I modified  the logs into trace. This is how it looks now:

 

 

 

 

 

2014-01-02 17:54:59,325 DEBUG [org.springframework.jdbc.datasource.DataSourceTransactionManager] - <Switching JDBC Connection [com.mchange.v2.c3p0.impl.NewProxyConnection@47a2da98] to manual commit>

2014-01-02 17:54:59,325 DEBUG [org.springframework.webflow.execution.AnnotatedAction] - <Clearing action execution attributes map[[empty]]>

2014-01-02 17:54:59,325 DEBUG [org.springframework.webflow.execution.ActionExecutor] - <Finished executing [EvaluateAction@7ea94b8e expression = authenticationViaFormAction.submit(flowRequestContext, flowScope.credentials, messageContext), resultExpression = [null]]; result = success>

2014-01-02 17:54:59,325 DEBUG [org.springframework.webflow.engine.Transition] - <Executing [Transition@7d71c009 on = success, to = passwordPolicyCheck]>

2014-01-02 17:54:59,325 DEBUG [org.springframework.webflow.engine.Transition] - <Exiting state 'realSubmit'>

2014-01-02 17:54:59,325 DEBUG [org.springframework.webflow.engine.ActionState] - <Entering state 'passwordPolicyCheck' of flow 'login'>

2014-01-02 17:54:59,326 DEBUG [org.springframework.webflow.execution.ActionExecutor] - <Executing [EvaluateAction@db2e9f6 expression = passwordPolicyAction, resultExpression = [null]]>

2014-01-02 17:54:59,326 DEBUG [org.springframework.webflow.execution.AnnotatedAction] - <Putting action execution attributes map[[empty]]>

2014-01-02 17:54:59,326 DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory] - <Returning cached instance of singleton bean 'passwordPolicyAction'>

2014-01-02 17:54:59,326 DEBUG [org.springframework.webflow.execution.ActionExecutor] - <Executing org.jasig.cas.web.flow.PasswordPolicyEnforcementAction@5d1e1c80>

2014-01-02 17:54:59,328 DEBUG [org.springframework.ldap.core.support.AbstractContextSource] - <Got Ldap context on server 'ldap://localhost:389'>

2014-01-02 17:54:59,335 DEBUG [org.springframework.webflow.execution.AnnotatedAction] - <Clearing action execution attributes map[[empty]]>

2014-01-02 17:54:59,335 DEBUG [org.springframework.webflow.engine.impl.FlowExecutionImpl] - <Attempting to handle [org.springframework.webflow.execution.ActionExecutionException: Exception thrown executing org.jasig.cas.web.flow.PasswordPolicyEnforcementAction@5d1e1c80 in state 'passwordPolicyCheck' of flow 'login' -- action execution attributes were 'map[[empty]]'] with root cause [java.lang.NullPointerException]>

2014-01-02 17:54:59,335 DEBUG [org.springframework.webflow.engine.impl.FlowExecutionImpl] - <Rethrowing unhandled flow execution exception>

2014-01-02 17:54:59,335 DEBUG [org.springframework.webflow.conversation.impl.SessionBindingConversationManager] - <Unlocking conversation 1>

2014-01-02 17:54:59,336 TRACE [org.springframework.web.servlet.DispatcherServlet] - <Cleared thread-bound request context: org.apache.catalina.connector.RequestFacade@14b08fe9>

2014-01-02 17:54:59,336 DEBUG [org.springframework.web.servlet.DispatcherServlet] - <Could not complete request>

org.springframework.webflow.execution.ActionExecutionException: Exception thrown executing org.jasig.cas.web.flow.PasswordPolicyEnforcementAction@5d1e1c80 in state 'passwordPolicyCheck' of flow 'login' -- action execution attributes were 'map[[empty]]'

        at org.springframework.webflow.execution.ActionExecutor.execute(ActionExecutor.java:60)

        at org.springframework.webflow.action.EvaluateAction.doExecute(EvaluateAction.java:77)

        at org.springframework.webflow.action.AbstractAction.execute(AbstractAction.java:188)

        at org.springframework.webflow.execution.AnnotatedAction.execute(AnnotatedAction.java:145)

        at org.springframework.webflow.execution.ActionExecutor.execute(ActionExecutor.java:51)

        at org.springframework.webflow.engine.ActionState.doEnter(ActionState.java:101)

        at org.springframework.webflow.engine.State.enter(State.java:194)

        at org.springframework.webflow.engine.Transition.execute(Transition.java:227)

        at org.springframework.webflow.engine.impl.FlowExecutionImpl.execute(FlowExecutionImpl.java:393)

        at org.springframework.webflow.engine.impl.RequestControlContextImpl.execute(RequestControlContextImpl.java:214)

        at org.springframework.webflow.engine.TransitionableState.handleEvent(TransitionableState.java:119)

        at org.springframework.webflow.engine.Flow.handleEvent(Flow.java:555)

        at org.springframework.webflow.engine.impl.FlowExecutionImpl.handleEvent(FlowExecutionImpl.java:388)

        at org.springframework.webflow.engine.impl.RequestControlContextImpl.handleEvent(RequestControlContextImpl.java:210)

        at org.springframework.webflow.engine.ActionState.doEnter(ActionState.java:105)

        at org.springframework.webflow.engine.State.enter(State.java:194)

        at org.springframework.webflow.engine.Transition.execute(Transition.java:227)

        at org.springframework.webflow.engine.impl.FlowExecutionImpl.execute(FlowExecutionImpl.java:393)

        at org.springframework.webflow.engine.impl.RequestControlContextImpl.execute(RequestControlContextImpl.java:214)

        at org.springframework.webflow.engine.TransitionableState.handleEvent(TransitionableState.java:119)

        at org.springframework.webflow.engine.Flow.handleEvent(Flow.java:555)

        at org.springframework.webflow.engine.impl.FlowExecutionImpl.handleEvent(FlowExecutionImpl.java:388)

        at org.springframework.webflow.engine.impl.RequestControlContextImpl.handleEvent(RequestControlContextImpl.java:210)

        at org.springframework.webflow.engine.ViewState.handleEvent(ViewState.java:232)

        at org.springframework.webflow.engine.ViewState.resume(ViewState.java:196)

        at org.springframework.webflow.engine.Flow.resume(Flow.java:545)

        at org.springframework.webflow.engine.impl.FlowExecutionImpl.resume(FlowExecutionImpl.java:261)

        at org.springframework.webflow.executor.FlowExecutorImpl.resumeExecution(FlowExecutorImpl.java:169)

        at org.springframework.webflow.mvc.servlet.FlowHandlerAdapter.handle(FlowHandlerAdapter.java:183)

        at org.springframework.web.servlet.DispatcherServlet.doDispatch(DispatcherServlet.java:923)

        at org.springframework.web.servlet.DispatcherServlet.doService(DispatcherServlet.java:852)

        at org.springframework.web.servlet.FrameworkServlet.processRequest(FrameworkServlet.java:882)

        at org.springframework.web.servlet.FrameworkServlet.doPost(FrameworkServlet.java:789)

        at javax.servlet.http.HttpServlet.service(HttpServlet.java:647)

        at javax.servlet.http.HttpServlet.service(HttpServlet.java:728)

        at org.jasig.cas.web.init.SafeDispatcherServlet.service_aroundBody2(SafeDispatcherServlet.java:128)

        at org.jasig.cas.web.init.SafeDispatcherServlet.service_aroundBody3$advice(SafeDispatcherServlet.java:57)

        at org.jasig.cas.web.init.SafeDispatcherServlet.service(SafeDispatcherServlet.java:1)

        at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:305)

        at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:210)

        at org.springframework.web.filter.CharacterEncodingFilter.doFilterInternal(CharacterEncodingFilter.java:88)

        at org.springframework.web.filter.OncePerRequestFilter.doFilter(OncePerRequestFilter.java:76)

        at org.springframework.web.filter.DelegatingFilterProxy.invokeDelegate(DelegatingFilterProxy.java:346)

        at org.springframework.web.filter.DelegatingFilterProxy.doFilter(DelegatingFilterProxy.java:259)

        at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:243)

        at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:210)

        at com.github.inspektr.common.web.ClientInfoThreadLocalFilter.doFilter(ClientInfoThreadLocalFilter.java:63)

        at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:243)

        at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:210)

        at org.apache.catalina.core.StandardWrapperValve.invoke(StandardWrapperValve.java:222)

        at org.apache.catalina.core.StandardContextValve.invoke(StandardContextValve.java:123)

        at org.apache.catalina.authenticator.AuthenticatorBase.invoke(AuthenticatorBase.java:502)

        at org.apache.catalina.core.StandardHostValve.invoke(StandardHostValve.java:171)

        at org.apache.catalina.valves.ErrorReportValve.invoke(ErrorReportValve.java:99)

        at org.apache.catalina.valves.AccessLogValve.invoke(AccessLogValve.java:953)

        at org.apache.catalina.core.StandardEngineValve.invoke(StandardEngineValve.java:118)

        at org.apache.catalina.connector.CoyoteAdapter.service(CoyoteAdapter.java:408)

        at org.apache.coyote.http11.AbstractHttp11Processor.process(AbstractHttp11Processor.java:1023)

        at org.apache.coyote.AbstractProtocol$AbstractConnectionHandler.process(AbstractProtocol.java:589)

        at org.apache.tomcat.util.net.JIoEndpoint$SocketProcessor.run(JIoEndpoint.java:312)

        at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145)

        at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)

        at java.lang.Thread.run(Thread.java:724)

Caused by: java.lang.NullPointerException

        at org.joda.time.format.DateTimeFormatterBuilder$NumberFormatter.parseInto(DateTimeFormatterBuilder.java:1292)

        at org.joda.time.format.DateTimeFormatterBuilder$Composite.parseInto(DateTimeFormatterBuilder.java:2695)

        at org.joda.time.format.DateTimeFormatter.parseDateTime(DateTimeFormatter.java:846)

        at org.joda.time.DateTime.parse(DateTime.java:144)

        at org.jasig.cas.adaptors.ldap.LdapPasswordPolicyEnforcer.formatDateByPattern(LdapPasswordPolicyEnforcer.java:394)

        at org.jasig.cas.adaptors.ldap.LdapPasswordPolicyEnforcer.getExpirationDateToUse(LdapPasswordPolicyEnforcer.java:408)

        at org.jasig.cas.adaptors.ldap.LdapPasswordPolicyEnforcer.getNumberOfDaysToPasswordExpirationDate(LdapPasswordPolicyEnforcer.java:225)

        at org.jasig.cas.web.flow.PasswordPolicyEnforcementAction.doExecute(PasswordPolicyEnforcementAction.java:120)

        at org.springframework.webflow.action.AbstractAction.execute(AbstractAction.java:188)

        at org.springframework.webflow.execution.ActionExecutor.execute(ActionExecutor.java:51)

        ... 62 more

2014-01-02 17:54:59,341 TRACE [org.springframework.web.context.support.XmlWebApplicationContext] - <Publishing event in WebApplicationContext for namespace 'cas-servlet': ServletRequestHandledEvent: url=[/cas/login;jsessionid=BCA3489B9FFC2B9BB096D5D74C45C0BC]; client=[192.168.108.195]; method=[POST]; servlet=[cas]; session=[BCA3489B9FFC2B9BB096D5D74C45C0BC]; user=[null]; time=[91ms]; status=[failed: org.springframework.webflow.execution.ActionExecutionException: Exception thrown executing org.jasig.cas.web.flow.PasswordPolicyEnforcementAction@5d1e1c80 in state 'passwordPolicyCheck' of flow 'login' -- action execution attributes were 'map[[empty]]']>

2014-01-02 17:54:59,342 TRACE [org.springframework.web.context.support.XmlWebApplicationContext] - <Publishing event in Root WebApplicationContext: ServletRequestHandledEvent: url=[/cas/login;jsessionid=BCA3489B9FFC2B9BB096D5D74C45C0BC]; client=[192.168.108.195]; method=[POST]; servlet=[cas]; session=[BCA3489B9FFC2B9BB096D5D74C45C0BC]; user=[null]; time=[91ms]; status=[failed: org.springframework.webflow.execution.ActionExecutionException: Exception thrown executing org.jasig.cas.web.flow.PasswordPolicyEnforcementAction@5d1e1c80 in state 'passwordPolicyCheck' of flow 'login' -- action execution attributes were 'map[[empty]]']>

2014-01-02 17:54:59,345 TRACE [org.springframework.beans.factory.support.DefaultListableBeanFactory] - <No bean named 'requestDataValueProcessor' found in org.springframework.beans.factory.support.DefaultListableBeanFactory@3ee839a5: defining beans [log4jInitialization,ticketRegistry,ticketRegistryCleaner,jobDetailTicketRegistryCleaner,triggerJobDetailTicketRegistryCleaner,ticketGrantingTicketCookieGenerator,pp1,org.springframework.aop.config.internalAutoProxyCreator,auditTrailManagementAspect,saveServiceResourceResolver,deleteServiceResourceResolver,saveServiceActionResolver,deleteServiceActionResolver,auditablePrincipalResolver,authenticationActionResolver,ticketCreationActionResolver,ticketValidationActionResolver,returnValueResourceResolver,ticketResourceResolver,inspektrTransactionManager,inspektrTransactionTemplate,auditManager,ticketGrantingTicketUniqueIdGenerator,serviceTicketUniqueIdGenerator,loginTicketUniqueIdGenerator,proxy20TicketUniqueIdGenerator,samlServiceTicketUniqueIdGenerator,uniqueIdGeneratorsMap,characterEncodingFilter,warnCookieGenerator,SECONDS,serviceTicketExpirationPolicy,grantingTicketExpirationPolicy,passwordPolicyAction,ldapPasswordPolicyEnforcer,timingAspect,messageSource,servicesManager,serviceRegistryReloaderJobDetail,periodicServiceRegistryReloaderTrigger,httpClient,noRedirectHttpClient,persistentIdGenerator,centralAuthenticationService,proxy10Handler,proxy20Handler,advisorAutoProxyCreator,validationAnnotationBeanPostProcessor,scheduler,flywayInit,org.springframework.security.filterChains,org.springframework.security.filterChainProxy,org.springframework.security.web.DefaultSecurityFilterChain#0,org.springframework.security.web.PortMapperImpl#0,org.springframework.security.config.authentication.AuthenticationManagerFactoryBean#0,org.springframework.security.authentication.ProviderManager#0,org.springframework.security.web.context.HttpSessionSecurityContextRepository#0,org.springframework.security.web.authentication.session.SessionFixationProtectionStrategy#0,org.springframework.security.web.savedrequest.HttpSessionRequestCache#0,org.springframework.security.web.access.expression.DefaultWebSecurityExpressionHandler#0,org.springframework.security.access.vote.AffirmativeBased#0,org.springframework.security.web.access.intercept.FilterSecurityInterceptor#0,org.springframework.security.web.access.DefaultWebInvocationPrivilegeEvaluator#0,org.springframework.security.authentication.AnonymousAuthenticationProvider#0,org.springframework.security.web.authentication.www.BasicAuthenticationEntryPoint#0,org.springframework.security.web.authentication.UsernamePasswordAuthenticationFilter#0,org.springframework.security.userDetailsServiceFactory,org.springframework.security.web.DefaultSecurityFilterChain#1,org.springframework.security.web.PortMapperImpl#1,org.springframework.security.config.authentication.AuthenticationManagerFactoryBean#1,org.springframework.security.authentication.ProviderManager#1,org.springframework.security.web.context.HttpSessionSecurityContextRepository#1,org.springframework.security.web.authentication.session.SessionFixationProtectionStrategy#1,org.springframework.security.web.savedrequest.HttpSessionRequestCache#1,org.springframework.security.access.vote.AffirmativeBased#1,org.springframework.security.web.access.intercept.FilterSecurityInterceptor#1,org.springframework.security.web.access.DefaultWebInvocationPrivilegeEvaluator#1,org.springframework.security.authentication.AnonymousAuthenticationProvider#1,org.springframework.security.web.authentication.www.BasicAuthenticationEntryPoint#1,org.springframework.security.web.authentication.UsernamePasswordAuthenticationFilter#1,org.springframework.security.web.DefaultSecurityFilterChain#2,org.springframework.security.authentication.DefaultAuthenticationEventPublisher#0,org.springframework.security.authenticationManager,serviceProperties,casProcessingFilter,casProcessingFilterEntryPoint,notAuthorizedEntryPoint,casAuthenticationProvider,casArgumentExtractor,samlArgumentExtractor,argumentExtractors,authenticationManager,contextSource,userDetailsService,attributeRepository,serviceRegistryDao,auditTrailManager,healthCheckMonitor,dataSource]; root of factory hierarchy>

2014-01-02 17:54:59,365 TRACE [org.springframework.transaction.support.TransactionSynchronizationManager] - <Bound value [org.springframework.jdbc.datasource.ConnectionHolder@17ddd4a1] for key [com.mchange.v2.c3p0.ComboPooledDataSource [ acquireIncrement -> 6, acquireRetryAttempts -> 5, acquireRetryDelay -> 2000, autoCommitOnClose -> false, automaticTestTable -> null, breakAfterAcquireFailure -> false, checkoutTimeout -> 10000, connectionCustomizerClassName -> null, connectionTesterClassName -> com.mchange.v2.c3p0.impl.DefaultConnectionTester, dataSourceName -> 1hge1d18zn5y3leur7ym0|6f3c1f5c, debugUnreturnedConnectionStackTraces -> false, description -> null, driverClass -> com.mysql.jdbc.Driver, factoryClassLocation -> null, forceIgnoreUnresolvedTransactions -> false, identityToken -> 1hge1d18zn5y3leur7ym0|6f3c1f5c, idleConnectionTestPeriod -> 30, initialPoolSize -> 6, jdbcUrl -> jdbc:mysql://localhost:3306/CIS?autoReconnect=true, maxAdministrativeTaskTime -> 0, maxConnectionAge -> 0, maxIdleTime -> 0, maxIdleTimeExcessConnections -> 120, maxPoolSize -> 18, maxStatements -> 0, maxStatementsPerConnection -> 0, minPoolSize -> 6, numHelperThreads -> 3, numThreadsAwaitingCheckoutDefaultUser -> 0, preferredTestQuery -> select 1, properties -> {user=******, password=******}, propertyCycle -> 0, testConnectionOnCheckin -> false, testConnectionOnCheckout -> false, unreturnedConnectionTimeout -> 0, usesTraditionalReflectiveProxies -> false ]] to thread [pool-1-thread-1]>

2014-01-02 17:54:59,365 TRACE [org.springframework.transaction.support.TransactionSynchronizationManager] - <Initializing transaction synchronization>

2014-01-02 17:54:59,366 DEBUG [org.springframework.jdbc.core.JdbcTemplate] - <Executing prepared SQL update>

2014-01-02 17:54:59,366 DEBUG [org.springframework.jdbc.core.JdbcTemplate] - <Executing prepared SQL statement [INSERT INTO COM_AUDIT_TRAIL (AUD_USER, AUD_CLIENT_IP, AUD_SERVER_IP, AUD_RESOURCE, AUD_ACTION, APPLIC_CD, AUD_DATE) VALUES (?, ?, ?, ?, ?, ?, ?)]>

2014-01-02 17:54:59,388 TRACE [org.springframework.transaction.support.TransactionSynchronizationManager] - <Retrieved value [org.springframework.jdbc.datasource.ConnectionHolder@17ddd4a1] for key [com.mchange.v2.c3p0.ComboPooledDataSource [ acquireIncrement -> 6, acquireRetryAttempts -> 5, acquireRetryDelay -> 2000, autoCommitOnClose -> false, automaticTestTable -> null, breakAfterAcquireFailure -> false, checkoutTimeout -> 10000, connectionCustomizerClassName -> null, connectionTesterClassName -> com.mchange.v2.c3p0.impl.DefaultConnectionTester, dataSourceName -> 1hge1d18zn5y3leur7ym0|6f3c1f5c, debugUnreturnedConnectionStackTraces -> false, description -> null, driverClass -> com.mysql.jdbc.Driver, factoryClassLocation -> null, forceIgnoreUnresolvedTransactions -> false, identityToken -> 1hge1d18zn5y3leur7ym0|6f3c1f5c, idleConnectionTestPeriod -> 30, initialPoolSize -> 6, jdbcUrl -> jdbc:mysql://localhost:3306/CIS?autoReconnect=true, maxAdministrativeTaskTime -> 0, maxConnectionAge -> 0, maxIdleTime -> 0, maxIdleTimeExcessConnections -> 120, maxPoolSize -> 18, maxStatements -> 0, maxStatementsPerConnection -> 0, minPoolSize -> 6, numHelperThreads -> 3, numThreadsAwaitingCheckoutDefaultUser -> 0, preferredTestQuery -> select 1, properties -> {user=******, password=******}, propertyCycle -> 0, testConnectionOnCheckin -> false, testConnectionOnCheckout -> false, unreturnedConnectionTimeout -> 0, usesTraditionalReflectiveProxies -> false ]] bound to thread [pool-1-thread-1]>

2014-01-02 17:54:59,402 TRACE [org.springframework.transaction.support.TransactionSynchronizationManager] - <Retrieved value [org.springframework.jdbc.datasource.ConnectionHolder@17ddd4a1] for key [com.mchange.v2.c3p0.ComboPooledDataSource [ acquireIncrement -> 6, acquireRetryAttempts -> 5, acquireRetryDelay -> 2000, autoCommitOnClose -> false, automaticTestTable -> null, breakAfterAcquireFailure -> false, checkoutTimeout -> 10000, connectionCustomizerClassName -> null, connectionTesterClassName -> com.mchange.v2.c3p0.impl.DefaultConnectionTester, dataSourceName -> 1hge1d18zn5y3leur7ym0|6f3c1f5c, debugUnreturnedConnectionStackTraces -> false, description -> null, driverClass -> com.mysql.jdbc.Driver, factoryClassLocation -> null, forceIgnoreUnresolvedTransactions -> false, identityToken -> 1hge1d18zn5y3leur7ym0|6f3c1f5c, idleConnectionTestPeriod -> 30, initialPoolSize -> 6, jdbcUrl -> jdbc:mysql://localhost:3306/CIS?autoReconnect=true, maxAdministrativeTaskTime -> 0, maxConnectionAge -> 0, maxIdleTime -> 0, maxIdleTimeExcessConnections -> 120, maxPoolSize -> 18, maxStatements -> 0, maxStatementsPerConnection -> 0, minPoolSize -> 6, numHelperThreads -> 3, numThreadsAwaitingCheckoutDefaultUser -> 0, preferredTestQuery -> select 1, properties -> {user=******, password=******}, propertyCycle -> 0, testConnectionOnCheckin -> false, testConnectionOnCheckout -> false, unreturnedConnectionTimeout -> 0, usesTraditionalReflectiveProxies -> false ]] bound to thread [pool-1-thread-1]>

2014-01-02 17:54:59,402 TRACE [org.springframework.jdbc.core.StatementCreatorUtils] - <Setting SQL statement parameter value: column index 1, parameter value [[username: ifridman]], value class [java.lang.String], SQL type unknown>

2014-01-02 17:54:59,402 TRACE [org.springframework.jdbc.core.StatementCreatorUtils] - <Setting SQL statement parameter value: column index 2, parameter value [192.168.108.195], value class [java.lang.String], SQL type unknown>

2014-01-02 17:54:59,403 TRACE [org.springframework.jdbc.core.StatementCreatorUtils] - <Setting SQL statement parameter value: column index 3, parameter value [192.168.2.101], value class [java.lang.String], SQL type unknown>

2014-01-02 17:54:59,403 TRACE [org.springframework.jdbc.core.StatementCreatorUtils] - <Setting SQL statement parameter value: column index 4, parameter value [supplied credentials: [username: ifridman]], value class [java.lang.String], SQL type unknown>

2014-01-02 17:54:59,403 TRACE [org.springframework.jdbc.core.StatementCreatorUtils] - <Setting SQL statement parameter value: column index 5, parameter value [AUTHENTICATION_SUCCESS], value class [java.lang.String], SQL type unknown>

2014-01-02 17:54:59,403 TRACE [org.springframework.jdbc.core.StatementCreatorUtils] - <Setting SQL statement parameter value: column index 6, parameter value [CAS], value class [java.lang.String], SQL type unknown>

2014-01-02 17:54:59,403 TRACE [org.springframework.jdbc.core.StatementCreatorUtils] - <Setting SQL statement parameter value: column index 7, parameter value [Thu Jan 02 17:54:59 IST 2014], value class [java.util.Date], SQL type unknown>

2014-01-02 17:54:59,404 DEBUG [org.springframework.jdbc.core.JdbcTemplate] - <SQL update affected 1 rows>

2014-01-02 17:54:59,419 TRACE [org.springframework.transaction.support.TransactionSynchronizationManager] - <Retrieved value [org.springframework.jdbc.datasource.ConnectionHolder@17ddd4a1] for key [com.mchange.v2.c3p0.ComboPooledDataSource [ acquireIncrement -> 6, acquireRetryAttempts -> 5, acquireRetryDelay -> 2000, autoCommitOnClose -> false, automaticTestTable -> null, breakAfterAcquireFailure -> false, checkoutTimeout -> 10000, connectionCustomizerClassName -> null, connectionTesterClassName -> com.mchange.v2.c3p0.impl.DefaultConnectionTester, dataSourceName -> 1hge1d18zn5y3leur7ym0|6f3c1f5c, debugUnreturnedConnectionStackTraces -> false, description -> null, driverClass -> com.mysql.jdbc.Driver, factoryClassLocation -> null, forceIgnoreUnresolvedTransactions -> false, identityToken -> 1hge1d18zn5y3leur7ym0|6f3c1f5c, idleConnectionTestPeriod -> 30, initialPoolSize -> 6, jdbcUrl -> jdbc:mysql://localhost:3306/CIS?autoReconnect=true, maxAdministrativeTaskTime -> 0, maxConnectionAge -> 0, maxIdleTime -> 0, maxIdleTimeExcessConnections -> 120, maxPoolSize -> 18, maxStatements -> 0, maxStatementsPerConnection -> 0, minPoolSize -> 6, numHelperThreads -> 3, numThreadsAwaitingCheckoutDefaultUser -> 0, preferredTestQuery -> select 1, properties -> {user=******, password=******}, propertyCycle -> 0, testConnectionOnCheckin -> false, testConnectionOnCheckout -> false, unreturnedConnectionTimeout -> 0, usesTraditionalReflectiveProxies -> false ]] bound to thread [pool-1-thread-1]>

2014-01-02 17:54:59,419 TRACE [org.springframework.jdbc.datasource.DataSourceTransactionManager] - <Triggering beforeCommit synchronization>

2014-01-02 17:54:59,419 TRACE [org.springframework.jdbc.datasource.DataSourceTransactionManager] - <Triggering beforeCompletion synchronization>

2014-01-02 17:54:59,419 DEBUG [org.springframework.jdbc.datasource.DataSourceTransactionManager] - <Initiating transaction commit>

2014-01-02 17:54:59,419 DEBUG [org.springframework.jdbc.datasource.DataSourceTransactionManager] - <Committing JDBC transaction on Connection [com.mchange.v2.c3p0.impl.NewProxyConnection@47a2da98]>

2014-01-02 17:54:59,420 TRACE [org.springframework.jdbc.datasource.DataSourceTransactionManager] - <Triggering afterCommit synchronization>

2014-01-02 17:54:59,420 TRACE [org.springframework.jdbc.datasource.DataSourceTransactionManager] - <Triggering afterCompletion synchronization>

2014-01-02 17:54:59,420 TRACE [org.springframework.transaction.support.TransactionSynchronizationManager] - <Clearing transaction synchronization>

2014-01-02 17:54:59,433 TRACE [org.springframework.transaction.support.TransactionSynchronizationManager] - <Removed value [org.springframework.jdbc.datasource.ConnectionHolder@17ddd4a1] for key [com.mchange.v2.c3p0.ComboPooledDataSource [ acquireIncrement -> 6, acquireRetryAttempts -> 5, acquireRetryDelay -> 2000, autoCommitOnClose -> false, automaticTestTable -> null, breakAfterAcquireFailure -> false, checkoutTimeout -> 10000, connectionCustomizerClassName -> null, connectionTesterClassName -> com.mchange.v2.c3p0.impl.DefaultConnectionTester, dataSourceName -> 1hge1d18zn5y3leur7ym0|6f3c1f5c, debugUnreturnedConnectionStackTraces -> false, description -> null, driverClass -> com.mysql.jdbc.Driver, factoryClassLocation -> null, forceIgnoreUnresolvedTransactions -> false, identityToken -> 1hge1d18zn5y3leur7ym0|6f3c1f5c, idleConnectionTestPeriod -> 30, initialPoolSize -> 6, jdbcUrl -> jdbc:mysql://localhost:3306/CIS?autoReconnect=true, maxAdministrativeTaskTime -> 0, maxConnectionAge -> 0, maxIdleTime -> 0, maxIdleTimeExcessConnections -> 120, maxPoolSize -> 18, maxStatements -> 0, maxStatementsPerConnection -> 0, minPoolSize -> 6, numHelperThreads -> 3, numThreadsAwaitingCheckoutDefaultUser -> 0, preferredTestQuery -> select 1, properties -> {user=******, password=******}, propertyCycle -> 0, testConnectionOnCheckin -> false, testConnectionOnCheckout -> false, unreturnedConnectionTimeout -> 0, usesTraditionalReflectiveProxies -> false ]] from thread [pool-1-thread-1]>

2014-01-02 17:54:59,434 DEBUG [org.springframework.jdbc.datasource.DataSourceUtils] - <Resetting isolation level of JDBC Connection [com.mchange.v2.c3p0.impl.NewProxyConnection@47a2da98] to 4>

2014-01-02 17:54:59,434 DEBUG [org.springframework.jdbc.datasource.DataSourceTransactionManager] - <Releasing JDBC Connection [com.mchange.v2.c3p0.impl.NewProxyConnection@47a2da98] after transaction>

2014-01-02 17:54:59,435 DEBUG [org.springframework.jdbc.datasource.DataSourceUtils] - <Returning JDBC Connection to DataSource>

2014-01-02 17:54:59,435 DEBUG [com.mchange.v2.resourcepool.BasicResourcePool] - <trace com.mchange.v2.resourcepool.BasicResourcePool@1574a7af [managed: 6, unused: 5, excluded: 0] (e.g. com.mchange.v2.c3p0.impl.NewPooledConnection@4950ce1d)>

2014-01-02 17:54:59,435 DEBUG [org.springframework.jdbc.datasource.DataSourceTransactionManager] - <Creating new transaction with name [null]: PROPAGATION_REQUIRED,ISOLATION_READ_COMMITTED>

2014-01-02 17:54:59,435 DEBUG [com.mchange.v2.resourcepool.BasicResourcePool] - <trace com.mchange.v2.resourcepool.BasicResourcePool@1574a7af [managed: 6, unused: 5, excluded: 0] (e.g. com.mchange.v2.c3p0.impl.NewPooledConnection@4950ce1d)>

2014-01-02 17:54:59,435 DEBUG [org.springframework.jdbc.datasource.DataSourceTransactionManager] - <Acquired Connection [com.mchange.v2.c3p0.impl.NewProxyConnection@309bff13] for JDBC transaction>

2014-01-02 17:54:59,435 DEBUG [org.springframework.jdbc.datasource.DataSourceUtils] - <Changing isolation level of JDBC Connection [com.mchange.v2.c3p0.impl.NewProxyConnection@309bff13] to 2>

2014-01-02 17:54:59,436 DEBUG [org.springframework.jdbc.datasource.DataSourceTransactionManager] - <Switching JDBC Connection [com.mchange.v2.c3p0.impl.NewProxyConnection@309bff13] to manual commit>

2014-01-02 17:54:59,450 TRACE [org.springframework.transaction.support.TransactionSynchronizationManager] - <Bound value [org.springframework.jdbc.datasource.ConnectionHolder@1eebbb14] for key [com.mchange.v2.c3p0.ComboPooledDataSource [ acquireIncrement -> 6, acquireRetryAttempts -> 5, acquireRetryDelay -> 2000, autoCommitOnClose -> false, automaticTestTable -> null, breakAfterAcquireFailure -> false, checkoutTimeout -> 10000, connectionCustomizerClassName -> null, connectionTesterClassName -> com.mchange.v2.c3p0.impl.DefaultConnectionTester, dataSourceName -> 1hge1d18zn5y3leur7ym0|6f3c1f5c, debugUnreturnedConnectionStackTraces -> false, description -> null, driverClass -> com.mysql.jdbc.Driver, factoryClassLocation -> null, forceIgnoreUnresolvedTransactions -> false, identityToken -> 1hge1d18zn5y3leur7ym0|6f3c1f5c, idleConnectionTestPeriod -> 30, initialPoolSize -> 6, jdbcUrl -> jdbc:mysql://localhost:3306/CIS?autoReconnect=true, maxAdministrativeTaskTime -> 0, maxConnectionAge -> 0, maxIdleTime -> 0, maxIdleTimeExcessConnections -> 120, maxPoolSize -> 18, maxStatements -> 0, maxStatementsPerConnection -> 0, minPoolSize -> 6, numHelperThreads -> 3, numThreadsAwaitingCheckoutDefaultUser -> 0, preferredTestQuery -> select 1, properties -> {user=******, password=******}, propertyCycle -> 0, testConnectionOnCheckin -> false, testConnectionOnCheckout -> false, unreturnedConnectionTimeout -> 0, usesTraditionalReflectiveProxies -> false ]] to thread [pool-1-thread-1]>

2014-01-02 17:54:59,450 TRACE [org.springframework.transaction.support.TransactionSynchronizationManager] - <Initializing transaction synchronization>

2014-01-02 17:54:59,450 DEBUG [org.springframework.jdbc.core.JdbcTemplate] - <Executing prepared SQL update>

2014-01-02 17:54:59,450 DEBUG [org.springframework.jdbc.core.JdbcTemplate] - <Executing prepared SQL statement [INSERT INTO COM_AUDIT_TRAIL (AUD_USER, AUD_CLIENT_IP, AUD_SERVER_IP, AUD_RESOURCE, AUD_ACTION, APPLIC_CD, AUD_DATE) VALUES (?, ?, ?, ?, ?, ?, ?)]>

2014-01-02 17:54:59,465 TRACE [org.springframework.transaction.support.TransactionSynchronizationManager] - <Retrieved value [org.springframework.jdbc.datasource.ConnectionHolder@1eebbb14] for key [com.mchange.v2.c3p0.ComboPooledDataSource [ acquireIncrement -> 6, acquireRetryAttempts -> 5, acquireRetryDelay -> 2000, autoCommitOnClose -> false, automaticTestTable -> null, breakAfterAcquireFailure -> false, checkoutTimeout -> 10000, connectionCustomizerClassName -> null, connectionTesterClassName -> com.mchange.v2.c3p0.impl.DefaultConnectionTester, dataSourceName -> 1hge1d18zn5y3leur7ym0|6f3c1f5c, debugUnreturnedConnectionStackTraces -> false, description -> null, driverClass -> com.mysql.jdbc.Driver, factoryClassLocation -> null, forceIgnoreUnresolvedTransactions -> false, identityToken -> 1hge1d18zn5y3leur7ym0|6f3c1f5c, idleConnectionTestPeriod -> 30, initialPoolSize -> 6, jdbcUrl -> jdbc:mysql://localhost:3306/CIS?autoReconnect=true, maxAdministrativeTaskTime -> 0, maxConnectionAge -> 0, maxIdleTime -> 0, maxIdleTimeExcessConnections -> 120, maxPoolSize -> 18, maxStatements -> 0, maxStatementsPerConnection -> 0, minPoolSize -> 6, numHelperThreads -> 3, numThreadsAwaitingCheckoutDefaultUser -> 0, preferredTestQuery -> select 1, properties -> {user=******, password=******}, propertyCycle -> 0, testConnectionOnCheckin -> false, testConnectionOnCheckout -> false, unreturnedConnectionTimeout -> 0, usesTraditionalReflectiveProxies -> false ]] bound to thread [pool-1-thread-1]>

2014-01-02 17:54:59,479 TRACE [org.springframework.transaction.support.TransactionSynchronizationManager] - <Retrieved value [org.springframework.jdbc.datasource.ConnectionHolder@1eebbb14] for key [com.mchange.v2.c3p0.ComboPooledDataSource [ acquireIncrement -> 6, acquireRetryAttempts -> 5, acquireRetryDelay -> 2000, autoCommitOnClose -> false, automaticTestTable -> null, breakAfterAcquireFailure -> false, checkoutTimeout -> 10000, connectionCustomizerClassName -> null, connectionTesterClassName -> com.mchange.v2.c3p0.impl.DefaultConnectionTester, dataSourceName -> 1hge1d18zn5y3leur7ym0|6f3c1f5c, debugUnreturnedConnectionStackTraces -> false, description -> null, driverClass -> com.mysql.jdbc.Driver, factoryClassLocation -> null, forceIgnoreUnresolvedTransactions -> false, identityToken -> 1hge1d18zn5y3leur7ym0|6f3c1f5c, idleConnectionTestPeriod -> 30, initialPoolSize -> 6, jdbcUrl -> jdbc:mysql://localhost:3306/CIS?autoReconnect=true, maxAdministrativeTaskTime -> 0, maxConnectionAge -> 0, maxIdleTime -> 0, maxIdleTimeExcessConnections -> 120, maxPoolSize -> 18, maxStatements -> 0, maxStatementsPerConnection -> 0, minPoolSize -> 6, numHelperThreads -> 3, numThreadsAwaitingCheckoutDefaultUser -> 0, preferredTestQuery -> select 1, properties -> {user=******, password=******}, propertyCycle -> 0, testConnectionOnCheckin -> false, testConnectionOnCheckout -> false, unreturnedConnectionTimeout -> 0, usesTraditionalReflectiveProxies -> false ]] bound to thread [pool-1-thread-1]>

2014-01-02 17:54:59,479 TRACE [org.springframework.jdbc.core.StatementCreatorUtils] - <Setting SQL statement parameter value: column index 1, parameter value [[username: ifridman]], value class [java.lang.String], SQL type unknown>

2014-01-02 17:54:59,479 TRACE [org.springframework.jdbc.core.StatementCreatorUtils] - <Setting SQL statement parameter value: column index 2, parameter value [192.168.108.195], value class [java.lang.String], SQL type unknown>

2014-01-02 17:54:59,479 TRACE [org.springframework.jdbc.core.StatementCreatorUtils] - <Setting SQL statement parameter value: column index 3, parameter value [192.168.2.101], value class [java.lang.String], SQL type unknown>

2014-01-02 17:54:59,479 TRACE [org.springframework.jdbc.core.StatementCreatorUtils] - <Setting SQL statement parameter value: column index 4, parameter value [TGT-3-JvOFJ5P1dWDknV7tYPrn5QwDwiJ1JqQte9b3cJiiTSMV1jgjOM-cas], value class [java.lang.String], SQL type unknown>

2014-01-02 17:54:59,479 TRACE [org.springframework.jdbc.core.StatementCreatorUtils] - <Setting SQL statement parameter value: column index 5, parameter value [TICKET_GRANTING_TICKET_CREATED], value class [java.lang.String], SQL type unknown>

2014-01-02 17:54:59,479 TRACE [org.springframework.jdbc.core.StatementCreatorUtils] - <Setting SQL statement parameter value: column index 6, parameter value [CAS], value class [java.lang.String], SQL type unknown>

2014-01-02 17:54:59,480 TRACE [org.springframework.jdbc.core.StatementCreatorUtils] - <Setting SQL statement parameter value: column index 7, parameter value [Thu Jan 02 17:54:59 IST 2014], value class [java.util.Date], SQL type unknown>

2014-01-02 17:54:59,481 DEBUG [org.springframework.jdbc.core.JdbcTemplate] - <SQL update affected 1 rows>

2014-01-02 17:54:59,494 TRACE [org.springframework.transaction.support.TransactionSynchronizationManager] - <Retrieved value [org.springframework.jdbc.datasource.ConnectionHolder@1eebbb14] for key [com.mchange.v2.c3p0.ComboPooledDataSource [ acquireIncrement -> 6, acquireRetryAttempts -> 5, acquireRetryDelay -> 2000, autoCommitOnClose -> false, automaticTestTable -> null, breakAfterAcquireFailure -> false, checkoutTimeout -> 10000, connectionCustomizerClassName -> null, connectionTesterClassName -> com.mchange.v2.c3p0.impl.DefaultConnectionTester, dataSourceName -> 1hge1d18zn5y3leur7ym0|6f3c1f5c, debugUnreturnedConnectionStackTraces -> false, description -> null, driverClass -> com.mysql.jdbc.Driver, factoryClassLocation -> null, forceIgnoreUnresolvedTransactions -> false, identityToken -> 1hge1d18zn5y3leur7ym0|6f3c1f5c, idleConnectionTestPeriod -> 30, initialPoolSize -> 6, jdbcUrl -> jdbc:mysql://localhost:3306/CIS?autoReconnect=true, maxAdministrativeTaskTime -> 0, maxConnectionAge -> 0, maxIdleTime -> 0, maxIdleTimeExcessConnections -> 120, maxPoolSize -> 18, maxStatements -> 0, maxStatementsPerConnection -> 0, minPoolSize -> 6, numHelperThreads -> 3, numThreadsAwaitingCheckoutDefaultUser -> 0, preferredTestQuery -> select 1, properties -> {user=******, password=******}, propertyCycle -> 0, testConnectionOnCheckin -> false, testConnectionOnCheckout -> false, unreturnedConnectionTimeout -> 0, usesTraditionalReflectiveProxies -> false ]] bound to thread [pool-1-thread-1]>

2014-01-02 17:54:59,494 TRACE [org.springframework.jdbc.datasource.DataSourceTransactionManager] - <Triggering beforeCommit synchronization>

2014-01-02 17:54:59,494 TRACE [org.springframework.jdbc.datasource.DataSourceTransactionManager] - <Triggering beforeCompletion synchronization>

2014-01-02 17:54:59,494 DEBUG [org.springframework.jdbc.datasource.DataSourceTransactionManager] - <Initiating transaction commit>

2014-01-02 17:54:59,494 DEBUG [org.springframework.jdbc.datasource.DataSourceTransactionManager] - <Committing JDBC transaction on Connection [com.mchange.v2.c3p0.impl.NewProxyConnection@309bff13]>

2014-01-02 17:54:59,495 TRACE [org.springframework.jdbc.datasource.DataSourceTransactionManager] - <Triggering afterCommit synchronization>

2014-01-02 17:54:59,495 TRACE [org.springframework.jdbc.datasource.DataSourceTransactionManager] - <Triggering afterCompletion synchronization>

2014-01-02 17:54:59,495 TRACE [org.springframework.transaction.support.TransactionSynchronizationManager] - <Clearing transaction synchronization>

2014-01-02 17:54:59,507 TRACE [org.springframework.transaction.support.TransactionSynchronizationManager] - <Removed value [org.springframework.jdbc.datasource.ConnectionHolder@1eebbb14] for key [com.mchange.v2.c3p0.ComboPooledDataSource [ acquireIncrement -> 6, acquireRetryAttempts -> 5, acquireRetryDelay -> 2000, autoCommitOnClose -> false, automaticTestTable -> null, breakAfterAcquireFailure -> false, checkoutTimeout -> 10000, connectionCustomizerClassName -> null, connectionTesterClassName -> com.mchange.v2.c3p0.impl.DefaultConnectionTester, dataSourceName -> 1hge1d18zn5y3leur7ym0|6f3c1f5c, debugUnreturnedConnectionStackTraces -> false, description -> null, driverClass -> com.mysql.jdbc.Driver, factoryClassLocation -> null, forceIgnoreUnresolvedTransactions -> false, identityToken -> 1hge1d18zn5y3leur7ym0|6f3c1f5c, idleConnectionTestPeriod -> 30, initialPoolSize -> 6, jdbcUrl -> jdbc:mysql://localhost:3306/CIS?autoReconnect=true, maxAdministrativeTaskTime -> 0, maxConnectionAge -> 0, maxIdleTime -> 0, maxIdleTimeExcessConnections -> 120, maxPoolSize -> 18, maxStatements -> 0, maxStatementsPerConnection -> 0, minPoolSize -> 6, numHelperThreads -> 3, numThreadsAwaitingCheckoutDefaultUser -> 0, preferredTestQuery -> select 1, properties -> {user=******, password=******}, propertyCycle -> 0, testConnectionOnCheckin -> false, testConnectionOnCheckout -> false, unreturnedConnectionTimeout -> 0, usesTraditionalReflectiveProxies -> false ]] from thread [pool-1-thread-1]>

2014-01-02 17:54:59,508 DEBUG [org.springframework.jdbc.datasource.DataSourceUtils] - <Resetting isolation level of JDBC Connection [com.mchange.v2.c3p0.impl.NewProxyConnection@309bff13] to 4>

2014-01-02 17:54:59,508 DEBUG [org.springframework.jdbc.datasource.DataSourceTransactionManager] - <Releasing JDBC Connection [com.mchange.v2.c3p0.impl.NewProxyConnection@309bff13] after transaction>

2014-01-02 17:54:59,508 DEBUG [org.springframework.jdbc.datasource.DataSourceUtils] - <Returning JDBC Connection to DataSource>

2014-01-02 17:54:59,508 DEBUG [com.mchange.v2.resourcepool.BasicResourcePool] - <trace com.mchange.v2.resourcepool.BasicResourcePool@1574a7af [managed: 6, unused: 5, excluded: 0] (e.g. com.mchange.v2.c3p0.impl.NewPooledConnection@4950ce1d)>

-- 
You are currently subscribed to cas-...@lists.jasig.org as: id...@cellebrite.com

To unsubscribe, change settings or access archives, see http://www.ja-sig.org/wiki/display/JSG/cas-user



This e-mail and the information it contains may be privileged and/or confidential. It is intended solely for the use of the named recipient(s). If you are not the intended recipient you may not disclose, copy, distribute or retain any part of this message or
 attachments. If you have received this e-mail in error please notify the sender immediately [by clicking 'Reply'] and delete this e-mail.


Idan Fridman
unread,
Jan 2, 2014, 10:58:46 AM1/2/14
to cas-...@lists.jasig.org










Here a complete log trace after authentication:


 


 


2014-01-02 17:57:37,435 TRACE [org.springframework.web.servlet.DispatcherServlet] - <Bound request context to thread: org.apache.catalina.connector.RequestFacade@14b08fe9>


2014-01-02 17:57:37,438 DEBUG [org.springframework.web.servlet.DispatcherServlet] - <DispatcherServlet with name 'cas' processing POST request for [/cas/login]>


2014-01-02 17:57:37,438 TRACE [org.springframework.web.servlet.DispatcherServlet] - <Testing handler map [org.springframework.webflow.mvc.servlet.FlowHandlerMapping@3ef0cd12]
 in DispatcherServlet with name 'cas'>


2014-01-02 17:57:37,438 DEBUG [org.springframework.webflow.mvc.servlet.FlowHandlerMapping] - <Mapping request with URI '/cas/login;jsessionid=1B886CB1EED3D5CB61EEBAAD38B52ECE'
 to flow with id 'login'>


2014-01-02 17:57:37,438 TRACE [org.springframework.web.servlet.DispatcherServlet] - <Testing handler adapter [org.springframework.web.servlet.mvc.SimpleControllerHandlerAdapter@33277906]>


2014-01-02 17:57:37,438 TRACE [org.springframework.web.servlet.DispatcherServlet] - <Testing handler adapter [org.springframework.webflow.mvc.servlet.FlowHandlerAdapter@647a9326]>


2014-01-02 17:57:37,439 DEBUG [org.springframework.webflow.executor.FlowExecutorImpl] - <Resuming flow execution with key 'e1s1>


2014-01-02 17:57:37,439 DEBUG [org.springframework.webflow.conversation.impl.SessionBindingConversationManager] - <Locking conversation 1>


2014-01-02 17:57:37,440 DEBUG [org.springframework.webflow.execution.repository.impl.DefaultFlowExecutionRepository] - <Getting flow execution with key 'e1s1'>


2014-01-02 17:57:37,440 DEBUG [org.springframework.webflow.definition.registry.FlowDefinitionRegistryImpl] - <Getting FlowDefinition with id 'login'>


2014-01-02 17:57:37,442 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@53798a3c]>


2014-01-02 17:57:37,443 DEBUG [org.springframework.webflow.engine.impl.FlowExecutionImpl] - <Resuming in org.springframework.webflow.mvc.servlet.MvcExternalContext@31fdb3b4>


2014-01-02 17:57:37,443 DEBUG [org.springframework.webflow.engine.Flow] - <Restoring [FlowVariable@15e1a244 name = 'credentials', valueFactory = [BeanFactoryVariableValueFactory@10642995
 type = UsernamePasswordCredentials]]>


2014-01-02 17:57:37,443 DEBUG [org.springframework.webflow.mvc.view.AbstractMvcView] - <Processing user event 'submit'>


2014-01-02 17:57:37,444 DEBUG [org.springframework.webflow.mvc.view.AbstractMvcView] - <Resolved model [username: null]>


2014-01-02 17:57:37,444 DEBUG [org.springframework.webflow.mvc.view.AbstractMvcView] - <Binding to model>


2014-01-02 17:57:37,444 DEBUG [org.springframework.webflow.mvc.view.AbstractMvcView] - <Adding mapping for parameter 'username'>


2014-01-02 17:57:37,444 DEBUG [org.springframework.webflow.mvc.view.AbstractMvcView] - <Adding mapping for parameter 'password'>


2014-01-02 17:57:37,444 DEBUG [org.springframework.binding.mapping.impl.DefaultMapper] - <Beginning mapping between source [org.springframework.webflow.core.collection.LocalParameterMap]
 and target [org.jasig.cas.authentication.principal.UsernamePasswordCredentials]>


2014-01-02 17:57:37,445 DEBUG [org.springframework.binding.mapping.impl.DefaultMapping] - <Adding mapping result [Success@9985618 mapping = parameter:'username'
 -> username, code = 'success', error = false, originalValue = 'iuser', mappedValue = 'iuser']>


2014-01-02 17:57:37,445 DEBUG [org.springframework.binding.mapping.impl.DefaultMapping] - <Adding mapping result [Success@53d9676 mapping = parameter:'password'
 -> password, code = 'success', error = false, originalValue = 'idan', mappedValue = 'idan']>


2014-01-02 17:57:37,445 DEBUG [org.springframework.binding.mapping.impl.DefaultMapper] - <Completing mapping between source [org.springframework.webflow.core.collection.LocalParameterMap]
 and target [org.jasig.cas.authentication.principal.UsernamePasswordCredentials]; total mappings = 2; total errors = 0>


2014-01-02 17:57:37,445 DEBUG [org.springframework.webflow.mvc.view.AbstractMvcView] - <Validating model>


2014-01-02 17:57:37,446 DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory] - <Returning cached instance of singleton bean 'credentialsValidator'>


2014-01-02 17:57:37,446 DEBUG [org.springframework.webflow.validation.ValidationHelper] - <Invoking Spring Validator 'LocalValidatorFactoryBean'>


2014-01-02 17:57:37,446 TRACE [org.hibernate.validator.engine.resolver.JPATraversableResolver] - <Calling isReachable on object [username: iuser] with node
 name username>


2014-01-02 17:57:37,447 TRACE [org.hibernate.validator.engine.ConstraintTree] - <Validating value iuser against constraint defined by ConstraintDescriptorImpl{annotation=javax.validation.constraints.NotNull,
 payloads=[], hasComposingConstraints=true, isReportAsSingleInvalidConstraint=false, elementType=FIELD, definedOn=DEFINED_LOCALLY, groups=[interface javax.validation.groups.Default], attributes={message={javax.validation.constraints.NotNull.message}, payload=[Ljava.lang.Class;@1f2b8983,
 groups=[Ljava.lang.Class;@63510771}}>


2014-01-02 17:57:37,447 DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory] - <Creating instance of bean 'org.hibernate.validator.constraints.impl.NotNullValidator'>


2014-01-02 17:57:37,447 DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory] - <Finished creating instance of bean 'org.hibernate.validator.constraints.impl.NotNullValidator'>


2014-01-02 17:57:37,448 TRACE [org.hibernate.validator.engine.resolver.JPATraversableResolver] - <Calling isReachable on object [username: iuser] with node
 name password>


2014-01-02 17:57:37,448 TRACE [org.hibernate.validator.engine.ConstraintTree] - <Validating value idan against constraint defined by ConstraintDescriptorImpl{annotation=javax.validation.constraints.Size,
 payloads=[], hasComposingConstraints=true, isReportAsSingleInvalidConstraint=false, elementType=FIELD, definedOn=DEFINED_LOCALLY, groups=[interface javax.validation.groups.Default], attributes={message=required.password, min=1, payload=[Ljava.lang.Class;@307df583,
 max=2147483647, groups=[Ljava.lang.Class;@1d818454}}>


2014-01-02 17:57:37,448 DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory] - <Creating instance of bean 'org.hibernate.validator.constraints.impl.SizeValidatorForString'>


2014-01-02 17:57:37,448 DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory] - <Finished creating instance of bean 'org.hibernate.validator.constraints.impl.SizeValidatorForString'>


2014-01-02 17:57:37,448 TRACE [org.hibernate.validator.engine.ConstraintTree] - <Validating value idan against constraint defined by ConstraintDescriptorImpl{annotation=javax.validation.constraints.NotNull,
 payloads=[], hasComposingConstraints=true, isReportAsSingleInvalidConstraint=false, elementType=FIELD, definedOn=DEFINED_LOCALLY, groups=[interface javax.validation.groups.Default], attributes={message={javax.validation.constraints.NotNull.message}, payload=[Ljava.lang.Class;@1f2b8983,
 groups=[Ljava.lang.Class;@63510771}}>


2014-01-02 17:57:37,448 DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory] - <Creating instance of bean 'org.hibernate.validator.constraints.impl.NotNullValidator'>


2014-01-02 17:57:37,449 DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory] - <Finished creating instance of bean 'org.hibernate.validator.constraints.impl.NotNullValidator'>


2014-01-02 17:57:37,449 TRACE [org.hibernate.validator.engine.ConstraintTree] - <Validating value iuser against constraint defined by ConstraintDescriptorImpl{annotation=javax.validation.constraints.Size,
 payloads=[], hasComposingConstraints=true, isReportAsSingleInvalidConstraint=false, elementType=FIELD, definedOn=DEFINED_LOCALLY, groups=[interface javax.validation.groups.Default], attributes={message=required.username, min=1, payload=[Ljava.lang.Class;@307df583,
 max=2147483647, groups=[Ljava.lang.Class;@1d818454}}>


2014-01-02 17:57:37,449 DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory] - <Creating instance of bean 'org.hibernate.validator.constraints.impl.SizeValidatorForString'>


2014-01-02 17:57:37,449 DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory] - <Finished creating instance of bean 'org.hibernate.validator.constraints.impl.SizeValidatorForString'>


2014-01-02 17:57:37,450 DEBUG [org.springframework.webflow.engine.ViewState] - <Event 'submit' returned from view [ServletMvcView@49f984de view = org.springframework.web.servlet.view.JstlView:
 name 'casLoginView'; URL [/WEB-INF/view/jsp/default/ui/casLoginView.jsp]]>


2014-01-02 17:57:37,450 DEBUG [org.springframework.webflow.execution.ActionExecutor] - <Executing [EvaluateAction@76789748 expression = authenticationViaFormAction.doBind(flowRequestContext,
 flowScope.credentials), resultExpression = [null]]>


2014-01-02 17:57:37,450 DEBUG [org.springframework.webflow.execution.AnnotatedAction] - <Putting action execution attributes map[[empty]]>


2014-01-02 17:57:37,450 DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory] - <Returning cached instance of singleton bean 'authenticationViaFormAction'>


2014-01-02 17:57:37,451 DEBUG [org.springframework.webflow.execution.AnnotatedAction] - <Clearing action execution attributes map[[empty]]>


2014-01-02 17:57:37,451 DEBUG [org.springframework.webflow.execution.ActionExecutor] - <Finished executing [EvaluateAction@76789748 expression = authenticationViaFormAction.doBind(flowRequestContext,
 flowScope.credentials), resultExpression = [null]]; result = success>


2014-01-02 17:57:37,452 DEBUG [org.springframework.webflow.engine.Transition] - <Executing [Transition@3a460c3 on = submit, to = realSubmit]>


2014-01-02 17:57:37,452 DEBUG [org.springframework.webflow.engine.Transition] - <Exiting state 'viewLoginForm'>


2014-01-02 17:57:37,456 DEBUG [org.springframework.webflow.engine.ActionState] - <Entering state 'realSubmit' of flow 'login'>


2014-01-02 17:57:37,456 DEBUG [org.springframework.webflow.execution.ActionExecutor] - <Executing [EvaluateAction@7ea94b8e expression = authenticationViaFormAction.submit(flowRequestContext,
 flowScope.credentials, messageContext), resultExpression = [null]]>


2014-01-02 17:57:37,456 DEBUG [org.springframework.webflow.execution.AnnotatedAction] - <Putting action execution attributes map[[empty]]>


2014-01-02 17:57:37,457 DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory] - <Returning cached instance of singleton bean 'authenticationViaFormAction'>


2014-01-02 17:57:37,463 DEBUG [org.springframework.ldap.core.support.AbstractContextSource] - <Got Ldap context on server 'ldap://localhost:389'>


2014-01-02 17:57:37,473 DEBUG [org.springframework.ldap.core.support.AbstractContextSource] - <Got Ldap context on server 'ldap://localhost:389'>


2014-01-02 17:57:37,477 DEBUG [org.springframework.ldap.core.support.AbstractContextSource] - <Got Ldap context on server 'ldap://localhost:389'>


2014-01-02 17:57:37,479 DEBUG [org.springframework.jdbc.core.JdbcTemplate] - <Executing prepared SQL query>


2014-01-02 17:57:37,479 DEBUG [org.springframework.jdbc.core.JdbcTemplate] - <Executing prepared SQL statement [select group_concat(p.PERMISSION_NAME SEPARATOR
 ',') as PERMISSION_NAME from PERMISSIONS p,USERS_PROFILE u,ROLES_PERMISSIONS rp, USERNAME_ROLES ur    where Username=ur.LOGINNAME and ur.roleId=rp.ROLE_ID and rp.PERMISSION_ID=p.PERMISSION_ID and Username = ?]>


2014-01-02 17:57:37,479 DEBUG [org.springframework.jdbc.datasource.DataSourceUtils] - <Fetching JDBC Connection from DataSource>


2014-01-02 17:57:37,480 DEBUG [com.mchange.v2.resourcepool.BasicResourcePool] - <trace com.mchange.v2.resourcepool.BasicResourcePool@1574a7af [managed: 6,
 unused: 5, excluded: 0] (e.g. com.mchange.v2.c3p0.impl.NewPooledConnection@4950ce1d)>


2014-01-02 17:57:37,480 TRACE [org.springframework.jdbc.core.StatementCreatorUtils] - <Setting SQL statement parameter value: column index 1, parameter value
 [iuser], value class [java.lang.String], SQL type unknown>


2014-01-02 17:57:37,482 DEBUG [org.springframework.jdbc.datasource.DataSourceUtils] - <Returning JDBC Connection to DataSource>


2014-01-02 17:57:37,482 DEBUG [com.mchange.v2.resourcepool.BasicResourcePool] - <trace com.mchange.v2.resourcepool.BasicResourcePool@1574a7af [managed: 6,
 unused: 5, excluded: 0] (e.g. com.mchange.v2.c3p0.impl.NewPooledConnection@4950ce1d)>


2014-01-02 17:57:37,485 DEBUG [org.springframework.ldap.core.support.AbstractContextSource] - <Got Ldap context on server 'ldap://localhost:389'>


Audit trail record BEGIN


=============================================================


WHO: [username: iuser]


WHAT: supplied credentials: [username: iuser]


ACTION: AUTHENTICATION_SUCCESS


APPLICATION: CAS


WHEN: Thu Jan 02 17:57:37 IST 2014


CLIENT IP ADDRESS: 192.168.108.195


SERVER IP ADDRESS: 192.168.2.101


=============================================================


 


 


2014-01-02 17:57:37,490 DEBUG [org.springframework.jdbc.datasource.DataSourceTransactionManager] - <Creating new transaction with name [null]: PROPAGATION_REQUIRED,ISOLATION_READ_COMMITTED>


2014-01-02 17:57:37,491 DEBUG [com.mchange.v2.resourcepool.BasicResourcePool] - <trace com.mchange.v2.resourcepool.BasicResourcePool@1574a7af [managed: 6,
 unused: 5, excluded: 0] (e.g. com.mchange.v2.c3p0.impl.NewPooledConnection@4950ce1d)>


2014-01-02 17:57:37,491 DEBUG [org.springframework.jdbc.datasource.DataSourceTransactionManager] - <Acquired Connection [com.mchange.v2.c3p0.impl.NewProxyConnection@cb2d029]
 for JDBC transaction>


2014-01-02 17:57:37,491 DEBUG [org.springframework.jdbc.datasource.DataSourceUtils] - <Changing isolation level of JDBC Connection [com.mchange.v2.c3p0.impl.NewProxyConnection@cb2d029]
 to 2>


Audit trail record BEGIN


=============================================================


WHO: [username: iuser]


WHAT: TGT-4-0VC2TbTyuLUkIM9eElWZ1BUchgVtWEdAnBLZ5DpCGvIUbusMsH-cas


ACTION: TICKET_GRANTING_TICKET_CREATED


APPLICATION: CAS


WHEN: Thu Jan 02 17:57:37 IST 2014


CLIENT IP ADDRESS: 192.168.108.195


SERVER IP ADDRESS: 192.168.2.101


=============================================================


 


 


2014-01-02 17:57:37,492 DEBUG [org.springframework.jdbc.datasource.DataSourceTransactionManager] - <Switching JDBC Connection [com.mchange.v2.c3p0.impl.NewProxyConnection@cb2d029]
 to manual commit>


2014-01-02 17:57:37,493 DEBUG [org.springframework.webflow.execution.AnnotatedAction] - <Clearing action execution attributes map[[empty]]>


2014-01-02 17:57:37,493 DEBUG [org.springframework.webflow.execution.ActionExecutor] - <Finished executing [EvaluateAction@7ea94b8e expression = authenticationViaFormAction.submit(flowRequestContext,
 flowScope.credentials, messageContext), resultExpression = [null]]; result = success>


2014-01-02 17:57:37,493 DEBUG [org.springframework.webflow.engine.Transition] - <Executing [Transition@7d71c009 on = success, to = passwordPolicyCheck]>


2014-01-02 17:57:37,493 DEBUG [org.springframework.webflow.engine.Transition] - <Exiting state 'realSubmit'>


2014-01-02 17:57:37,493 DEBUG [org.springframework.webflow.engine.ActionState] - <Entering state 'passwordPolicyCheck' of flow 'login'>


2014-01-02 17:57:37,493 DEBUG [org.springframework.webflow.execution.ActionExecutor] - <Executing [EvaluateAction@db2e9f6 expression = passwordPolicyAction,
 resultExpression = [null]]>


2014-01-02 17:57:37,494 DEBUG [org.springframework.webflow.execution.AnnotatedAction] - <Putting action execution attributes map[[empty]]>


2014-01-02 17:57:37,494 DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory] - <Returning cached instance of singleton bean 'passwordPolicyAction'>


2014-01-02 17:57:37,494 DEBUG [org.springframework.webflow.execution.ActionExecutor] - <Executing org.jasig.cas.web.flow.PasswordPolicyEnforcementAction@5d1e1c80>


2014-01-02 17:57:37,498 DEBUG [org.springframework.ldap.core.support.AbstractContextSource] - <Got Ldap context on server 'ldap://localhost:389'>


2014-01-02 17:57:37,500 DEBUG [org.springframework.webflow.execution.AnnotatedAction] - <Clearing action execution attributes map[[empty]]>


2014-01-02 17:57:37,500 DEBUG [org.springframework.webflow.engine.impl.FlowExecutionImpl] - <Attempting to handle [org.springframework.webflow.execution.ActionExecutionException:
 Exception thrown executing org.jasig.cas.web.flow.PasswordPolicyEnforcementAction@5d1e1c80 in state 'passwordPolicyCheck' of flow 'login' -- action execution attributes were 'map[[empty]]'] with root cause [java.lang.NullPointerException]>


2014-01-02 17:57:37,501 DEBUG [org.springframework.webflow.engine.impl.FlowExecutionImpl] - <Rethrowing unhandled flow execution exception>


2014-01-02 17:57:37,501 DEBUG [org.springframework.webflow.conversation.impl.SessionBindingConversationManager] - <Unlocking conversation 1>


2014-01-02 17:57:37,501 TRACE [org.springframework.web.servlet.DispatcherServlet] - <Cleared thread-bound request context: org.apache.catalina.connector.RequestFacade@14b08fe9>


2014-01-02 17:57:37,501 DEBUG [org.springframework.web.servlet.DispatcherServlet] - <Could not complete request>


org.springframework.webflow.execution.ActionExecutionException: Exception thrown executing org.jasig.cas.web.flow.PasswordPolicyEnforcementAction@5d1e1c80
 in state 'passwordPolicyCheck' of flow 'login' -- action execution attributes were 'map[[empty]]'


        at org.springframework.webflow.execution.ActionExecutor.execute(ActionExecutor.java:60)
2014-01-02 17:57:37,506 TRACE [org.springframework.web.context.support.XmlWebApplicationContext] - <Publishing event in WebApplicationContext for namespace
 'cas-servlet': ServletRequestHandledEvent: url=[/cas/login;jsessionid=1B886CB1EED3D5CB61EEBAAD38B52ECE]; client=[192.168.108.195]; method=[POST]; servlet=[cas]; session=[1B886CB1EED3D5CB61EEBAAD38B52ECE]; user=[null]; time=[71ms]; status=[failed: org.springframework.webflow.execution.ActionExecutionException:
 Exception thrown executing org.jasig.cas.web.flow.PasswordPolicyEnforcementAction@5d1e1c80 in state 'passwordPolicyCheck' of flow 'login' -- action execution attributes were 'map[[empty]]']>


2014-01-02 17:57:37,506 TRACE [org.springframework.web.context.support.XmlWebApplicationContext] - <Publishing event in Root WebApplicationContext: ServletRequestHandledEvent:
 url=[/cas/login;jsessionid=1B886CB1EED3D5CB61EEBAAD38B52ECE]; client=[192.168.108.195]; method=[POST]; servlet=[cas]; session=[1B886CB1EED3D5CB61EEBAAD38B52ECE]; user=[null]; time=[71ms]; status=[failed: org.springframework.webflow.execution.ActionExecutionException:
 Exception thrown executing org.jasig.cas.web.flow.PasswordPolicyEnforcementAction@5d1e1c80 in state 'passwordPolicyCheck' of flow 'login' -- action execution attributes were 'map[[empty]]']>


2014-01-02 17:57:37,510 TRACE [org.springframework.beans.factory.support.DefaultListableBeanFactory] - <No bean named 'requestDataValueProcessor' found in
 org.springframework.beans.factory.support.DefaultListableBeanFactory@3ee839a5: defining beans [log4jInitialization,ticketRegistry,ticketRegistryCleaner,jobDetailTicketRegistryCleaner,triggerJobDetailTicketRegistryCleaner,ticketGrantingTicketCookieGenerator,pp1,org.springframework.aop.config.internalAutoProxyCreator,auditTrailManagementAspect,saveServiceResourceResolver,deleteServiceResourceResolver,saveServiceActionResolver,deleteServiceActionResolver,auditablePrincipalResolver,authenticationActionResolver,ticketCreationActionResolver,ticketValidationActionResolver,returnValueResourceResolver,ticketResourceResolver,inspektrTransactionManager,inspektrTransactionTemplate,auditManager,ticketGrantingTicketUniqueIdGenerator,serviceTicketUniqueIdGenerator,loginTicketUniqueIdGenerator,proxy20TicketUniqueIdGenerator,samlServiceTicketUniqueIdGenerator,uniqueIdGeneratorsMap,characterEncodingFilter,warnCookieGenerator,SECONDS,serviceTicketExpirationPolicy,grantingTicketExpirationPolicy,passwordPolicyAction,ldapPasswordPolicyEnforcer,timingAspect,messageSource,servicesManager,serviceRegistryReloaderJobDetail,periodicServiceRegistryReloaderTrigger,httpClient,noRedirectHttpClient,persistentIdGenerator,centralAuthenticationService,proxy10Handler,proxy20Handler,advisorAutoProxyCreator,validationAnnotationBeanPostProcessor,scheduler,flywayInit,org.springframework.security.filterChains,org.springframework.security.filterChainProxy,org.springframework.security.web.DefaultSecurityFilterChain#0,org.springframework.security.web.PortMapperImpl#0,org.springframework.security.config.authentication.AuthenticationManagerFactoryBean#0,org.springframework.security.authentication.ProviderManager#0,org.springframework.security.web.context.HttpSessionSecurityContextRepository#0,org.springframework.security.web.authentication.session.SessionFixationProtectionStrategy#0,org.springframework.security.web.savedrequest.HttpSessionRequestCache#0,org.springframework.security.web.access.expression.DefaultWebSecurityExpressionHandler#0,org.springframework.security.access.vote.AffirmativeBased#0,org.springframework.security.web.access.intercept.FilterSecurityInterceptor#0,org.springframework.security.web.access.DefaultWebInvocationPrivilegeEvaluator#0,org.springframework.security.authentication.AnonymousAuthenticationProvider#0,org.springframework.security.web.authentication.www.BasicAuthenticationEntryPoint#0,org.springframework.security.web.authentication.UsernamePasswordAuthenticationFilter#0,org.springframework.security.userDetailsServiceFactory,org.springframework.security.web.DefaultSecurityFilterChain#1,org.springframework.security.web.PortMapperImpl#1,org.springframework.security.config.authentication.AuthenticationManagerFactoryBean#1,org.springframework.security.authentication.ProviderManager#1,org.springframework.security.web.context.HttpSessionSecurityContextRepository#1,org.springframework.security.web.authentication.session.SessionFixationProtectionStrategy#1,org.springframework.security.web.savedrequest.HttpSessionRequestCache#1,org.springframework.security.access.vote.AffirmativeBased#1,org.springframework.security.web.access.intercept.FilterSecurityInterceptor#1,org.springframework.security.web.access.DefaultWebInvocationPrivilegeEvaluator#1,org.springframework.security.authentication.AnonymousAuthenticationProvider#1,org.springframework.security.web.authentication.www.BasicAuthenticationEntryPoint#1,org.springframework.security.web.authentication.UsernamePasswordAuthenticationFilter#1,org.springframework.security.web.DefaultSecurityFilterChain#2,org.springframework.security.authentication.DefaultAuthenticationEventPublisher#0,org.springframework.security.authenticationManager,serviceProperties,casProcessingFilter,casProcessingFilterEntryPoint,notAuthorizedEntryPoint,casAuthenticationProvider,casArgumentExtractor,samlArgumentExtractor,argumentExtractors,authenticationManager,contextSource,userDetailsService,attributeRepository,serviceRegistryDao,auditTrailManager,healthCheckMonitor,dataSource];
 root of factory hierarchy>


2014-01-02 17:57:37,511 TRACE [org.springframework.transaction.support.TransactionSynchronizationManager] - <Bound value [org.springframework.jdbc.datasource.ConnectionHolder@6607d66f]
 for key [com.mchange.v2.c3p0.ComboPooledDataSource [ acquireIncrement -> 6, acquireRetryAttempts -> 5, acquireRetryDelay -> 2000, autoCommitOnClose -> false, automaticTestTable -> null, breakAfterAcquireFailure -> false, checkoutTimeout -> 10000, connectionCustomizerClassName
 -> null, connectionTesterClassName -> com.mchange.v2.c3p0.impl.DefaultConnectionTester, dataSourceName -> 1hge1d18zn5y3leur7ym0|6f3c1f5c, debugUnreturnedConnectionStackTraces -> false, description -> null, driverClass -> com.mysql.jdbc.Driver, factoryClassLocation
 -> null, forceIgnoreUnresolvedTransactions -> false, identityToken -> 1hge1d18zn5y3leur7ym0|6f3c1f5c, idleConnectionTestPeriod -> 30, initialPoolSize -> 6, jdbcUrl -> jdbc:mysql://localhost:3306/CIS?autoReconnect=true, maxAdministrativeTaskTime -> 0, maxConnectionAge
 -> 0, maxIdleTime -> 0, maxIdleTimeExcessConnections -> 120, maxPoolSize -> 18, maxStatements -> 0, maxStatementsPerConnection -> 0, minPoolSize -> 6, numHelperThreads -> 3, numThreadsAwaitingCheckoutDefaultUser -> 0, preferredTestQuery -> select 1, properties
 -> {user=******, password=******}, propertyCycle -> 0, testConnectionOnCheckin -> false, testConnectionOnCheckout -> false, unreturnedConnectionTimeout -> 0, usesTraditionalReflectiveProxies -> false ]] to thread [pool-1-thread-1]>


2014-01-02 17:57:37,512 TRACE [org.springframework.transaction.support.TransactionSynchronizationManager] - <Initializing transaction synchronization>


2014-01-02 17:57:37,512 DEBUG [org.springframework.jdbc.core.JdbcTemplate] - <Executing prepared SQL update>


2014-01-02 17:57:37,512 DEBUG [org.springframework.jdbc.core.JdbcTemplate] - <Executing prepared SQL statement [INSERT INTO COM_AUDIT_TRAIL (AUD_USER, AUD_CLIENT_IP,
 AUD_SERVER_IP, AUD_RESOURCE, AUD_ACTION, APPLIC_CD, AUD_DATE) VALUES (?, ?, ?, ?, ?, ?, ?)]>


2014-01-02 17:57:37,528 TRACE [org.springframework.transaction.support.TransactionSynchronizationManager] - <Retrieved value [org.springframework.jdbc.datasource.ConnectionHolder@6607d66f]
 for key [com.mchange.v2.c3p0.ComboPooledDataSource [ acquireIncrement -> 6, acquireRetryAttempts -> 5, acquireRetryDelay -> 2000, autoCommitOnClose -> false, automaticTestTable -> null, breakAfterAcquireFailure -> false, checkoutTimeout -> 10000, connectionCustomizerClassName
 -> null, connectionTesterClassName -> com.mchange.v2.c3p0.impl.DefaultConnectionTester, dataSourceName -> 1hge1d18zn5y3leur7ym0|6f3c1f5c, debugUnreturnedConnectionStackTraces -> false, description -> null, driverClass -> com.mysql.jdbc.Driver, factoryClassLocation
 -> null, forceIgnoreUnresolvedTransactions -> false, identityToken -> 1hge1d18zn5y3leur7ym0|6f3c1f5c, idleConnectionTestPeriod -> 30, initialPoolSize -> 6, jdbcUrl -> jdbc:mysql://localhost:3306/CIS?autoReconnect=true, maxAdministrativeTaskTime -> 0, maxConnectionAge
 -> 0, maxIdleTime -> 0, maxIdleTimeExcessConnections -> 120, maxPoolSize -> 18, maxStatements -> 0, maxStatementsPerConnection -> 0, minPoolSize -> 6, numHelperThreads -> 3, numThreadsAwaitingCheckoutDefaultUser -> 0, preferredTestQuery -> select 1, properties
 -> {user=******, password=******}, propertyCycle -> 0, testConnectionOnCheckin -> false, testConnectionOnCheckout -> false, unreturnedConnectionTimeout -> 0, usesTraditionalReflectiveProxies -> false ]] bound to thread [pool-1-thread-1]>


2014-01-02 17:57:37,546 TRACE [org.springframework.transaction.support.TransactionSynchronizationManager] - <Retrieved value [org.springframework.jdbc.datasource.ConnectionHolder@6607d66f]
 for key [com.mchange.v2.c3p0.ComboPooledDataSource [ acquireIncrement -> 6, acquireRetryAttempts -> 5, acquireRetryDelay -> 2000, autoCommitOnClose -> false, automaticTestTable -> null, breakAfterAcquireFailure -> false, checkoutTimeout -> 10000, connectionCustomizerClassName
 -> null, connectionTesterClassName -> com.mchange.v2.c3p0.impl.DefaultConnectionTester, dataSourceName -> 1hge1d18zn5y3leur7ym0|6f3c1f5c, debugUnreturnedConnectionStackTraces -> false, description -> null, driverClass -> com.mysql.jdbc.Driver, factoryClassLocation
 -> null, forceIgnoreUnresolvedTransactions -> false, identityToken -> 1hge1d18zn5y3leur7ym0|6f3c1f5c, idleConnectionTestPeriod -> 30, initialPoolSize -> 6, jdbcUrl -> jdbc:mysql://localhost:3306/CIS?autoReconnect=true, maxAdministrativeTaskTime -> 0, maxConnectionAge
 -> 0, maxIdleTime -> 0, maxIdleTimeExcessConnections -> 120, maxPoolSize -> 18, maxStatements -> 0, maxStatementsPerConnection -> 0, minPoolSize -> 6, numHelperThreads -> 3, numThreadsAwaitingCheckoutDefaultUser -> 0, preferredTestQuery -> select 1, properties
 -> {user=******, password=******}, propertyCycle -> 0, testConnectionOnCheckin -> false, testConnectionOnCheckout -> false, unreturnedConnectionTimeout -> 0, usesTraditionalReflectiveProxies -> false ]] bound to thread [pool-1-thread-1]>


2014-01-02 17:57:37,546 TRACE [org.springframework.jdbc.core.StatementCreatorUtils] - <Setting SQL statement parameter value: column index 1, parameter value
 [[username: iuser]], value class [java.lang.String], SQL type unknown>


2014-01-02 17:57:37,546 TRACE [org.springframework.jdbc.core.StatementCreatorUtils] - <Setting SQL statement parameter value: column index 2, parameter value
 [192.168.108.195], value class [java.lang.String], SQL type unknown>


2014-01-02 17:57:37,546 TRACE [org.springframework.jdbc.core.StatementCreatorUtils] - <Setting SQL statement parameter value: column index 3, parameter value
 [192.168.2.101], value class [java.lang.String], SQL type unknown>


2014-01-02 17:57:37,546 TRACE [org.springframework.jdbc.core.StatementCreatorUtils] - <Setting SQL statement parameter value: column index 4, parameter value
 [supplied credentials: [username: iuser]], value class [java.lang.String], SQL type unknown>


2014-01-02 17:57:37,546 TRACE [org.springframework.jdbc.core.StatementCreatorUtils] - <Setting SQL statement parameter value: column index 5, parameter value
 [AUTHENTICATION_SUCCESS], value class [java.lang.String], SQL type unknown>


2014-01-02 17:57:37,547 TRACE [org.springframework.jdbc.core.StatementCreatorUtils] - <Setting SQL statement parameter value: column index 6, parameter value
 [CAS], value class [java.lang.String], SQL type unknown>


2014-01-02 17:57:37,547 TRACE [org.springframework.jdbc.core.StatementCreatorUtils] - <Setting SQL statement parameter value: column index 7, parameter value
 [Thu Jan 02 17:57:37 IST 2014], value class [java.util.Date], SQL type unknown>


2014-01-02 17:57:37,548 DEBUG [org.springframework.jdbc.core.JdbcTemplate] - <SQL update affected 1 rows>


2014-01-02 17:57:37,563 TRACE [org.springframework.transaction.support.TransactionSynchronizationManager] - <Retrieved value [org.springframework.jdbc.datasource.ConnectionHolder@6607d66f]
 for key [com.mchange.v2.c3p0.ComboPooledDataSource [ acquireIncrement -> 6, acquireRetryAttempts -> 5, acquireRetryDelay -> 2000, autoCommitOnClose -> false, automaticTestTable -> null, breakAfterAcquireFailure -> false, checkoutTimeout -> 10000, connectionCustomizerClassName
 -> null, connectionTesterClassName -> com.mchange.v2.c3p0.impl.DefaultConnectionTester, dataSourceName -> 1hge1d18zn5y3leur7ym0|6f3c1f5c, debugUnreturnedConnectionStackTraces -> false, description -> null, driverClass -> com.mysql.jdbc.Driver, factoryClassLocation
 -> null, forceIgnoreUnresolvedTransactions -> false, identityToken -> 1hge1d18zn5y3leur7ym0|6f3c1f5c, idleConnectionTestPeriod -> 30, initialPoolSize -> 6, jdbcUrl -> jdbc:mysql://localhost:3306/CIS?autoReconnect=true, maxAdministrativeTaskTime -> 0, maxConnectionAge
 -> 0, maxIdleTime -> 0, maxIdleTimeExcessConnections -> 120, maxPoolSize -> 18, maxStatements -> 0, maxStatementsPerConnection -> 0, minPoolSize -> 6, numHelperThreads -> 3, numThreadsAwaitingCheckoutDefaultUser -> 0, preferredTestQuery -> select 1, properties
 -> {user=******, password=******}, propertyCycle -> 0, testConnectionOnCheckin -> false, testConnectionOnCheckout -> false, unreturnedConnectionTimeout -> 0, usesTraditionalReflectiveProxies -> false ]] bound to thread [pool-1-thread-1]>


2014-01-02 17:57:37,563 TRACE [org.springframework.jdbc.datasource.DataSourceTransactionManager] - <Triggering beforeCommit synchronization>


2014-01-02 17:57:37,563 TRACE [org.springframework.jdbc.datasource.DataSourceTransactionManager] - <Triggering beforeCompletion synchronization>


2014-01-02 17:57:37,563 DEBUG [org.springframework.jdbc.datasource.DataSourceTransactionManager] - <Initiating transaction commit>


2014-01-02 17:57:37,563 DEBUG [org.springframework.jdbc.datasource.DataSourceTransactionManager] - <Committing JDBC transaction on Connection [com.mchange.v2.c3p0.impl.NewProxyConnection@cb2d029]>


2014-01-02 17:57:37,564 TRACE [org.springframework.jdbc.datasource.DataSourceTransactionManager] - <Triggering afterCommit synchronization>


2014-01-02 17:57:37,564 TRACE [org.springframework.jdbc.datasource.DataSourceTransactionManager] - <Triggering afterCompletion synchronization>


2014-01-02 17:57:37,564 TRACE [org.springframework.transaction.support.TransactionSynchronizationManager] - <Clearing transaction synchronization>


2014-01-02 17:57:37,596 TRACE [org.springframework.transaction.support.TransactionSynchronizationManager] - <Removed value [org.springframework.jdbc.datasource.ConnectionHolder@6607d66f]
 for key [com.mchange.v2.c3p0.ComboPooledDataSource [ acquireIncrement -> 6, acquireRetryAttempts -> 5, acquireRetryDelay -> 2000, autoCommitOnClose -> false, automaticTestTable -> null, breakAfterAcquireFailure -> false, checkoutTimeout -> 10000, connectionCustomizerClassName
 -> null, connectionTesterClassName -> com.mchange.v2.c3p0.impl.DefaultConnectionTester, dataSourceName -> 1hge1d18zn5y3leur7ym0|6f3c1f5c, debugUnreturnedConnectionStackTraces -> false, description -> null, driverClass -> com.mysql.jdbc.Driver, factoryClassLocation
 -> null, forceIgnoreUnresolvedTransactions -> false, identityToken -> 1hge1d18zn5y3leur7ym0|6f3c1f5c, idleConnectionTestPeriod -> 30, initialPoolSize -> 6, jdbcUrl -> jdbc:mysql://localhost:3306/CIS?autoReconnect=true, maxAdministrativeTaskTime -> 0, maxConnectionAge
 -> 0, maxIdleTime -> 0, maxIdleTimeExcessConnections -> 120, maxPoolSize -> 18, maxStatements -> 0, maxStatementsPerConnection -> 0, minPoolSize -> 6, numHelperThreads -> 3, numThreadsAwaitingCheckoutDefaultUser -> 0, preferredTestQuery -> select 1, properties
 -> {user=******, password=******}, propertyCycle -> 0, testConnectionOnCheckin -> false, testConnectionOnCheckout -> false, unreturnedConnectionTimeout -> 0, usesTraditionalReflectiveProxies -> false ]] from thread [pool-1-thread-1]>


2014-01-02 17:57:37,597 DEBUG [org.springframework.jdbc.datasource.DataSourceUtils] - <Resetting isolation level of JDBC Connection [com.mchange.v2.c3p0.impl.NewProxyConnection@cb2d029]
 to 4>


2014-01-02 17:57:37,598 DEBUG [org.springframework.jdbc.datasource.DataSourceTransactionManager] - <Releasing JDBC Connection [com.mchange.v2.c3p0.impl.NewProxyConnection@cb2d029]
 after transaction>


2014-01-02 17:57:37,598 DEBUG [org.springframework.jdbc.datasource.DataSourceUtils] - <Returning JDBC Connection to DataSource>


2014-01-02 17:57:37,598 DEBUG [com.mchange.v2.resourcepool.BasicResourcePool] - <trace com.mchange.v2.resourcepool.BasicResourcePool@1574a7af [managed: 6,
 unused: 5, excluded: 0] (e.g. com.mchange.v2.c3p0.impl.NewPooledConnection@4950ce1d)>


2014-01-02 17:57:37,598 DEBUG [org.springframework.jdbc.datasource.DataSourceTransactionManager] - <Creating new transaction with name [null]: PROPAGATION_REQUIRED,ISOLATION_READ_COMMITTED>


2014-01-02 17:57:37,598 DEBUG [com.mchange.v2.resourcepool.BasicResourcePool] - <trace com.mchange.v2.resourcepool.BasicResourcePool@1574a7af [managed: 6,
 unused: 5, excluded: 0] (e.g. com.mchange.v2.c3p0.impl.NewPooledConnection@4950ce1d)>


2014-01-02 17:57:37,598 DEBUG [org.springframework.jdbc.datasource.DataSourceTransactionManager] - <Acquired Connection [com.mchange.v2.c3p0.impl.NewProxyConnection@18c3b0ca]
 for JDBC transaction>


2014-01-02 17:57:37,598 DEBUG [org.springframework.jdbc.datasource.DataSourceUtils] - <Changing isolation level of JDBC Connection [com.mchange.v2.c3p0.impl.NewProxyConnection@18c3b0ca]
 to 2>


2014-01-02 17:57:37,599 DEBUG [org.springframework.jdbc.datasource.DataSourceTransactionManager] - <Switching JDBC Connection [com.mchange.v2.c3p0.impl.NewProxyConnection@18c3b0ca]
 to manual commit>


2014-01-02 17:57:37,615 TRACE [org.springframework.transaction.support.TransactionSynchronizationManager] - <Bound value [org.springframework.jdbc.datasource.ConnectionHolder@2bc4e109]
 for key [com.mchange.v2.c3p0.ComboPooledDataSource [ acquireIncrement -> 6, acquireRetryAttempts -> 5, acquireRetryDelay -> 2000, autoCommitOnClose -> false, automaticTestTable -> null, breakAfterAcquireFailure -> false, checkoutTimeout -> 10000, connectionCustomizerClassName
 -> null, connectionTesterClassName -> com.mchange.v2.c3p0.impl.DefaultConnectionTester, dataSourceName -> 1hge1d18zn5y3leur7ym0|6f3c1f5c, debugUnreturnedConnectionStackTraces -> false, description -> null, driverClass -> com.mysql.jdbc.Driver, factoryClassLocation
 -> null, forceIgnoreUnresolvedTransactions -> false, identityToken -> 1hge1d18zn5y3leur7ym0|6f3c1f5c, idleConnectionTestPeriod -> 30, initialPoolSize -> 6, jdbcUrl -> jdbc:mysql://localhost:3306/CIS?autoReconnect=true, maxAdministrativeTaskTime -> 0, maxConnectionAge
 -> 0, maxIdleTime -> 0, maxIdleTimeExcessConnections -> 120, maxPoolSize -> 18, maxStatements -> 0, maxStatementsPerConnection -> 0, minPoolSize -> 6, numHelperThreads -> 3, numThreadsAwaitingCheckoutDefaultUser -> 0, preferredTestQuery -> select 1, properties
 -> {user=******, password=******}, propertyCycle -> 0, testConnectionOnCheckin -> false, testConnectionOnCheckout -> false, unreturnedConnectionTimeout -> 0, usesTraditionalReflectiveProxies -> false ]] to thread [pool-1-thread-1]>


2014-01-02 17:57:37,615 TRACE [org.springframework.transaction.support.TransactionSynchronizationManager] - <Initializing transaction synchronization>


2014-01-02 17:57:37,615 DEBUG [org.springframework.jdbc.core.JdbcTemplate] - <Executing prepared SQL update>


2014-01-02 17:57:37,615 DEBUG [org.springframework.jdbc.core.JdbcTemplate] - <Executing prepared SQL statement [INSERT INTO COM_AUDIT_TRAIL (AUD_USER, AUD_CLIENT_IP,
 AUD_SERVER_IP, AUD_RESOURCE, AUD_ACTION, APPLIC_CD, AUD_DATE) VALUES (?, ?, ?, ?, ?, ?, ?)]>


2014-01-02 17:57:37,629 TRACE [org.springframework.transaction.support.TransactionSynchronizationManager] - <Retrieved value [org.springframework.jdbc.datasource.ConnectionHolder@2bc4e109]
 for key [com.mchange.v2.c3p0.ComboPooledDataSource [ acquireIncrement -> 6, acquireRetryAttempts -> 5, acquireRetryDelay -> 2000, autoCommitOnClose -> false, automaticTestTable -> null, breakAfterAcquireFailure -> false, checkoutTimeout -> 10000, connectionCustomizerClassName
 -> null, connectionTesterClassName -> com.mchange.v2.c3p0.impl.DefaultConnectionTester, dataSourceName -> 1hge1d18zn5y3leur7ym0|6f3c1f5c, debugUnreturnedConnectionStackTraces -> false, description -> null, driverClass -> com.mysql.jdbc.Driver, factoryClassLocation
 -> null, forceIgnoreUnresolvedTransactions -> false, identityToken -> 1hge1d18zn5y3leur7ym0|6f3c1f5c, idleConnectionTestPeriod -> 30, initialPoolSize -> 6, jdbcUrl -> jdbc:mysql://localhost:3306/CIS?autoReconnect=true, maxAdministrativeTaskTime -> 0, maxConnectionAge
 -> 0, maxIdleTime -> 0, maxIdleTimeExcessConnections -> 120, maxPoolSize -> 18, maxStatements -> 0, maxStatementsPerConnection -> 0, minPoolSize -> 6, numHelperThreads -> 3, numThreadsAwaitingCheckoutDefaultUser -> 0, preferredTestQuery -> select 1, properties
 -> {user=******, password=******}, propertyCycle -> 0, testConnectionOnCheckin -> false, testConnectionOnCheckout -> false, unreturnedConnectionTimeout -> 0, usesTraditionalReflectiveProxies -> false ]] bound to thread [pool-1-thread-1]>


2014-01-02 17:57:37,643 TRACE [org.springframework.transaction.support.TransactionSynchronizationManager] - <Retrieved value [org.springframework.jdbc.datasource.ConnectionHolder@2bc4e109]
 for key [com.mchange.v2.c3p0.ComboPooledDataSource [ acquireIncrement -> 6, acquireRetryAttempts -> 5, acquireRetryDelay -> 2000, autoCommitOnClose -> false, automaticTestTable -> null, breakAfterAcquireFailure -> false, checkoutTimeout -> 10000, connectionCustomizerClassName
 -> null, connectionTesterClassName -> com.mchange.v2.c3p0.impl.DefaultConnectionTester, dataSourceName -> 1hge1d18zn5y3leur7ym0|6f3c1f5c, debugUnreturnedConnectionStackTraces -> false, description -> null, driverClass -> com.mysql.jdbc.Driver, factoryClassLocation
 -> null, forceIgnoreUnresolvedTransactions -> false, identityToken -> 1hge1d18zn5y3leur7ym0|6f3c1f5c, idleConnectionTestPeriod -> 30, initialPoolSize -> 6, jdbcUrl -> jdbc:mysql://localhost:3306/CIS?autoReconnect=true, maxAdministrativeTaskTime -> 0, maxConnectionAge
 -> 0, maxIdleTime -> 0, maxIdleTimeExcessConnections -> 120, maxPoolSize -> 18, maxStatements -> 0, maxStatementsPerConnection -> 0, minPoolSize -> 6, numHelperThreads -> 3, numThreadsAwaitingCheckoutDefaultUser -> 0, preferredTestQuery -> select 1, properties
 -> {user=******, password=******}, propertyCycle -> 0, testConnectionOnCheckin -> false, testConnectionOnCheckout -> false, unreturnedConnectionTimeout -> 0, usesTraditionalReflectiveProxies -> false ]] bound to thread [pool-1-thread-1]>


2014-01-02 17:57:37,643 TRACE [org.springframework.jdbc.core.StatementCreatorUtils] - <Setting SQL statement parameter value: column index 1, parameter value
 [[username: iuser]], value class [java.lang.String], SQL type unknown>


2014-01-02 17:57:37,644 TRACE [org.springframework.jdbc.core.StatementCreatorUtils] - <Setting SQL statement parameter value: column index 2, parameter value
 [192.168.108.195], value class [java.lang.String], SQL type unknown>


2014-01-02 17:57:37,644 TRACE [org.springframework.jdbc.core.StatementCreatorUtils] - <Setting SQL statement parameter value: column index 3, parameter value
 [192.168.2.101], value class [java.lang.String], SQL type unknown>


2014-01-02 17:57:37,644 TRACE [org.springframework.jdbc.core.StatementCreatorUtils] - <Setting SQL statement parameter value: column index 4, parameter value
 [TGT-4-0VC2TbTyuLUkIM9eElWZ1BUchgVtWEdAnBLZ5DpCGvIUbusMsH-cas], value class [java.lang.String], SQL type unknown>


2014-01-02 17:57:37,644 TRACE [org.springframework.jdbc.core.StatementCreatorUtils] - <Setting SQL statement parameter value: column index 5, parameter value
 [TICKET_GRANTING_TICKET_CREATED], value class [java.lang.String], SQL type unknown>


2014-01-02 17:57:37,644 TRACE [org.springframework.jdbc.core.StatementCreatorUtils] - <Setting SQL statement parameter value: column index 6, parameter value
 [CAS], value class [java.lang.String], SQL type unknown>


2014-01-02 17:57:37,644 TRACE [org.springframework.jdbc.core.StatementCreatorUtils] - <Setting SQL statement parameter value: column index 7, parameter value
 [Thu Jan 02 17:57:37 IST 2014], value class [java.util.Date], SQL type unknown>


2014-01-02 17:57:37,645 DEBUG [org.springframework.jdbc.core.JdbcTemplate] - <SQL update affected 1 rows>


2014-01-02 17:57:37,660 TRACE [org.springframework.transaction.support.TransactionSynchronizationManager] - <Retrieved value [org.springframework.jdbc.datasource.ConnectionHolder@2bc4e109]
 for key [com.mchange.v2.c3p0.ComboPooledDataSource [ acquireIncrement -> 6, acquireRetryAttempts -> 5, acquireRetryDelay -> 2000, autoCommitOnClose -> false, automaticTestTable -> null, breakAfterAcquireFailure -> false, checkoutTimeout -> 10000, connectionCustomizerClassName
 -> null, connectionTesterClassName -> com.mchange.v2.c3p0.impl.DefaultConnectionTester, dataSourceName -> 1hge1d18zn5y3leur7ym0|6f3c1f5c, debugUnreturnedConnectionStackTraces -> false, description -> null, driverClass -> com.mysql.jdbc.Driver, factoryClassLocation
 -> null, forceIgnoreUnresolvedTransactions -> false, identityToken -> 1hge1d18zn5y3leur7ym0|6f3c1f5c, idleConnectionTestPeriod -> 30, initialPoolSize -> 6, jdbcUrl -> jdbc:mysql://localhost:3306/CIS?autoReconnect=true, maxAdministrativeTaskTime -> 0, maxConnectionAge
 -> 0, maxIdleTime -> 0, maxIdleTimeExcessConnections -> 120, maxPoolSize -> 18, maxStatements -> 0, maxStatementsPerConnection -> 0, minPoolSize -> 6, numHelperThreads -> 3, numThreadsAwaitingCheckoutDefaultUser -> 0, preferredTestQuery -> select 1, properties
 -> {user=******, password=******}, propertyCycle -> 0, testConnectionOnCheckin -> false, testConnectionOnCheckout -> false, unreturnedConnectionTimeout -> 0, usesTraditionalReflectiveProxies -> false ]] bound to thread [pool-1-thread-1]>


2014-01-02 17:57:37,660 TRACE [org.springframework.jdbc.datasource.DataSourceTransactionManager] - <Triggering beforeCommit synchronization>


2014-01-02 17:57:37,660 TRACE [org.springframework.jdbc.datasource.DataSourceTransactionManager] - <Triggering beforeCompletion synchronization>


2014-01-02 17:57:37,660 DEBUG [org.springframework.jdbc.datasource.DataSourceTransactionManager] - <Initiating transaction commit>


2014-01-02 17:57:37,660 DEBUG [org.springframework.jdbc.datasource.DataSourceTransactionManager] - <Committing JDBC transaction on Connection [com.mchange.v2.c3p0.impl.NewProxyConnection@18c3b0ca]>


2014-01-02 17:57:37,661 TRACE [org.springframework.jdbc.datasource.DataSourceTransactionManager] - <Triggering afterCommit synchronization>


2014-01-02 17:57:37,661 TRACE [org.springframework.jdbc.datasource.DataSourceTransactionManager] - <Triggering afterCompletion synchronization>


2014-01-02 17:57:37,661 TRACE [org.springframework.transaction.support.TransactionSynchronizationManager] - <Clearing transaction synchronization>


2014-01-02 17:57:37,675 TRACE [org.springframework.transaction.support.TransactionSynchronizationManager] - <Removed value [org.springframework.jdbc.datasource.ConnectionHolder@2bc4e109]
 for key [com.mchange.v2.c3p0.ComboPooledDataSource [ acquireIncrement -> 6, acquireRetryAttempts -> 5, acquireRetryDelay -> 2000, autoCommitOnClose -> false, automaticTestTable -> null, breakAfterAcquireFailure -> false, checkoutTimeout -> 10000, connectionCustomizerClassName
 -> null, connectionTesterClassName -> com.mchange.v2.c3p0.impl.DefaultConnectionTester, dataSourceName -> 1hge1d18zn5y3leur7ym0|6f3c1f5c, debugUnreturnedConnectionStackTraces -> false, description -> null, driverClass -> com.mysql.jdbc.Driver, factoryClassLocation
 -> null, forceIgnoreUnresolvedTransactions -> false, identityToken -> 1hge1d18zn5y3leur7ym0|6f3c1f5c, idleConnectionTestPeriod -> 30, initialPoolSize -> 6, jdbcUrl -> jdbc:mysql://localhost:3306/CIS?autoReconnect=true, maxAdministrativeTaskTime -> 0, maxConnectionAge
 -> 0, maxIdleTime -> 0, maxIdleTimeExcessConnections -> 120, maxPoolSize -> 18, maxStatements -> 0, maxStatementsPerConnection -> 0, minPoolSize -> 6, numHelperThreads -> 3, numThreadsAwaitingCheckoutDefaultUser -> 0, preferredTestQuery -> select 1, properties
 -> {user=******, password=******}, propertyCycle -> 0, testConnectionOnCheckin -> false, testConnectionOnCheckout -> false, unreturnedConnectionTimeout -> 0, usesTraditionalReflectiveProxies -> false ]] from thread [pool-1-thread-1]>


 


 






From: Misagh Moayyed [mailto:mmoa...@unicon.net]


Sent: Thursday, January 02, 2014 5:48 PM

To: cas-...@lists.jasig.org

Subject: Re: [cas-user] Error enabling passwordPolicyCheck






 




Somewhere in the logs, there's more info on the following exception. See if you could track that down and post back please.


-- 
You are currently subscribed to cas-...@lists.jasig.org as: id...@cellebrite.com

To unsubscribe, change settings or access archives, see http://www.ja-sig.org/wiki/display/JSG/cas-user



This e-mail and the information it contains may be privileged and/or confidential. It is intended solely for the use of the named recipient(s). If you are not the intended recipient you may not disclose, copy, distribute or retain any part of this message or
 attachments. If you have received this e-mail in error please notify the sender immediately [by clicking 'Reply'] and delete this e-mail.


-- 
Scott Battaglia
unread,
Jan 2, 2014, 11:10:50 AM1/2/14
to cas-...@lists.jasig.org
I think you are looking for this specifically:

Caused by: java.lang.NullPointerException



        at org.joda.time.format.DateTimeFormatterBuilder$NumberFormatter.parseInto(DateTimeFormatterBuilder.java:1292)



        at org.joda.time.format.DateTimeFormatterBuilder$Composite.parseInto(DateTimeFormatterBuilder.java:2695)



        at org.joda.time.format.DateTimeFormatter.parseDateTime(DateTimeFormatter.java:846)



        at org.joda.time.DateTime.parse(DateTime.java:144)



        at org.jasig.cas.adaptors.ldap.LdapPasswordPolicyEnforcer.formatDateByPattern(LdapPasswordPolicyEnforcer.java:394)



        at org.jasig.cas.adaptors.ldap.LdapPasswordPolicyEnforcer.getExpirationDateToUse(LdapPasswordPolicyEnforcer.java:408)



        at org.jasig.cas.adaptors.ldap.LdapPasswordPolicyEnforcer.getNumberOfDaysToPasswordExpirationDate(LdapPasswordPolicyEnforcer.java:225)



        at org.jasig.cas.web.flow.PasswordPolicyEnforcementAction.doExecute(PasswordPolicyEnforcementAction.java:120)



        at org.springframework.webflow.action.AbstractAction.execute(AbstractAction.java:188)



        at org.springframework.webflow.execution.ActionExecutor.execute(ActionExecutor.java:51)



-- 

You are currently subscribed to cas-...@lists.jasig.org as: scott.b...@gmail.com



To unsubscribe, change settings or access archives, see http://www.ja-sig.org/wiki/display/JSG/cas-user










-- 
Idan Fridman
unread,
Jan 2, 2014, 11:16:45 AM1/2/14
to cas-...@lists.jasig.org









Yes, but no idea why it occurs?? 
Misagh Moayyed
unread,
Jan 2, 2014, 11:43:01 AM1/2/14
to cas-...@lists.jasig.org
Likely, an attribute value for a date field set in the configuration is coming up null or empty. 

Misagh


From: "Idan Fridman" <id...@cellebrite.com>
To: cas-...@lists.jasig.org
Sent: Thursday, January 2, 2014 9:16:45 AM
Reply all
Reply to author
Forward
0 new messages