Hi Jerome,
I have enabled debug log. The logs are attached here.
--
You are currently subscribed to [email protected] as:
[email protected]
To unsubscribe, change settings or access archives, see
http://www.ja-sig.org/wiki/display/JSG/cas-user
23 Oct, 2013 4:43:04 PM org.apache.tomcat.util.digester.SetPropertiesRule begin
WARNING: [SetPropertiesRule]{Server/Service/Engine/Host/Context} Setting
property 'source' to 'org.eclipse.jst.jee.server:CASServer' did not find a
matching property.
23 Oct, 2013 4:43:04 PM org.apache.catalina.core.AprLifecycleListener init
INFO: The APR based Apache Tomcat Native library which allows optimal
performance in production environments was not found on the java.library.path:
D:\Cardiff\WebLogic\jdk160_05\bin;.;C:\Windows\Sun\Java\bin;C:\Windows\system32;C:\Windows;C:\Windows\system32;C:\Windows;C:\Windows\System32\Wbem;C:\Program
Files\Windows Imaging\;C:\Program Files\Bitvise Tunnelier
23 Oct, 2013 4:43:04 PM org.apache.coyote.http11.Http11Protocol init
INFO: Initializing Coyote HTTP/1.1 on http-8080
23 Oct, 2013 4:43:04 PM org.apache.coyote.http11.Http11Protocol init
INFO: Initializing Coyote HTTP/1.1 on http-8090
23 Oct, 2013 4:43:04 PM org.apache.catalina.startup.Catalina load
INFO: Initialization processed in 678 ms
23 Oct, 2013 4:43:04 PM org.apache.catalina.core.StandardService start
INFO: Starting service Catalina
23 Oct, 2013 4:43:04 PM org.apache.catalina.core.StandardEngine start
INFO: Starting Servlet Engine: Apache Tomcat/6.0.16
23 Oct, 2013 4:43:05 PM org.apache.catalina.core.StandardContext
addApplicationListener
INFO: The listener "org.jasig.cas.web.init.SafeContextLoaderListener" is
already configured for this context. The duplicate definition has been ignored.
23 Oct, 2013 4:43:05 PM org.apache.catalina.core.StandardContext
addApplicationListener
INFO: The listener "com.tcs.sgv.util.CASContextListner" is already configured
for this context. The duplicate definition has been ignored.
23 Oct, 2013 4:43:05 PM org.apache.catalina.core.ApplicationContext log
INFO: Initializing Spring root WebApplicationContext
2013-10-23 16:43:08,332 DEBUG
[org.jasig.cas.services.DefaultServicesManagerImpl] - <Adding registered
service ^(https?|imaps?)://.*>
2013-10-23 16:43:08,333 DEBUG
[org.jasig.cas.services.DefaultServicesManagerImpl] - <Adding registered
service https://01hw394314:8090/CASServer/oauth2.0/callbackAuthorize>
2013-10-23 16:43:08,333 DEBUG
[org.jasig.cas.services.DefaultServicesManagerImpl] - <Adding registered
service http://mycasserver/login>
2013-10-23 16:43:08,333 INFO
[org.jasig.cas.services.DefaultServicesManagerImpl] - <Loaded 2 services.>
2013-10-23 16:43:08,772 DEBUG
[org.jasig.cas.util.AutowiringSchedulerFactoryBean] - <Autowired the following
triggers defined in application context:
[triggerJobDetailTicketRegistryCleaner, periodicServiceRegistryReloaderTrigger]>
Inside CustomAuthenticationHandler
Inside setDataSource
2013-10-23 16:43:10,002 INFO
[org.jasig.cas.util.AutowiringSchedulerFactoryBean] - <Starting Quartz
Scheduler now>
23 Oct, 2013 4:43:10 PM org.apache.catalina.core.ApplicationContext log
INFO: Initializing Spring FrameworkServlet 'cas'
2013-10-23 16:43:10,483 DEBUG
[org.jasig.cas.services.web.ManageRegisteredServicesMultiActionController] -
<Found action method [public org.springframework.web.servlet.ModelAndView
org.jasig.cas.services.web.ManageRegisteredServicesMultiActionController.deleteRegisteredService(javax.servlet.http.HttpServletRequest,javax.servlet.http.HttpServletResponse)]>
2013-10-23 16:43:10,484 DEBUG
[org.jasig.cas.services.web.ManageRegisteredServicesMultiActionController] -
<Found action method [public org.springframework.web.servlet.ModelAndView
org.jasig.cas.services.web.ManageRegisteredServicesMultiActionController.manage(javax.servlet.http.HttpServletRequest,javax.servlet.http.HttpServletResponse)]>
2013-10-23 16:43:10,484 DEBUG
[org.jasig.cas.services.web.ManageRegisteredServicesMultiActionController] -
<Found action method [public org.springframework.web.servlet.ModelAndView
org.jasig.cas.services.web.ManageRegisteredServicesMultiActionController.updateRegisteredServiceEvaluationOrder(javax.servlet.http.HttpServletRequest,javax.servlet.http.HttpServletResponse)]>
2013-10-23 16:43:10,749 DEBUG
[org.springframework.webflow.definition.registry.FlowDefinitionRegistryImpl] -
<Registering flow definition 'ServletContext resource
[/WEB-INF/login-webflow.xml]' under id 'login'>
2013-10-23 16:43:10,780 DEBUG
[org.springframework.webflow.execution.factory.ConditionalFlowExecutionListenerLoader]
- <Adding flow execution listener
org.jasig.cas.web.flow.TerminateWebSessionListener@13ccea with criteria *>
23 Oct, 2013 4:43:10 PM org.apache.catalina.core.StandardContext resourcesStart
SEVERE: Error starting static Resources
java.lang.IllegalArgumentException: Document base
D:\Cardiff\apache-tomcat-6.0.16\webapps\zksample2_v5_0613(zk5.0.6) does not
exist or is not a readable directory
at
org.apache.naming.resources.FileDirContext.setDocBase(FileDirContext.java:141)
at
org.apache.catalina.core.StandardContext.resourcesStart(StandardContext.java:3957)
at
org.apache.catalina.core.StandardContext.start(StandardContext.java:4126)
at
org.apache.catalina.core.ContainerBase.addChildInternal(ContainerBase.java:791)
at
org.apache.catalina.core.ContainerBase.addChild(ContainerBase.java:771)
at org.apache.catalina.core.StandardHost.addChild(StandardHost.java:525)
at
org.apache.catalina.startup.HostConfig.deployDescriptor(HostConfig.java:626)
at
org.apache.catalina.startup.HostConfig.deployDescriptors(HostConfig.java:553)
at
org.apache.catalina.startup.HostConfig.deployApps(HostConfig.java:488)
at org.apache.catalina.startup.HostConfig.start(HostConfig.java:1147)
at
org.apache.catalina.startup.HostConfig.lifecycleEvent(HostConfig.java:311)
at
org.apache.catalina.util.LifecycleSupport.fireLifecycleEvent(LifecycleSupport.java:117)
at org.apache.catalina.core.ContainerBase.start(ContainerBase.java:1053)
at org.apache.catalina.core.StandardHost.start(StandardHost.java:719)
at org.apache.catalina.core.ContainerBase.start(ContainerBase.java:1045)
at
org.apache.catalina.core.StandardEngine.start(StandardEngine.java:443)
at
org.apache.catalina.core.StandardService.start(StandardService.java:516)
at
org.apache.catalina.core.StandardServer.start(StandardServer.java:710)
at org.apache.catalina.startup.Catalina.start(Catalina.java:578)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
at
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
at java.lang.reflect.Method.invoke(Method.java:597)
at org.apache.catalina.startup.Bootstrap.start(Bootstrap.java:288)
at org.apache.catalina.startup.Bootstrap.main(Bootstrap.java:413)
23 Oct, 2013 4:43:10 PM org.apache.catalina.core.StandardContext start
SEVERE: Error in resourceStart()
23 Oct, 2013 4:43:10 PM org.apache.catalina.core.StandardContext start
SEVERE: Error getConfigured
23 Oct, 2013 4:43:10 PM org.apache.catalina.core.StandardContext start
SEVERE: Context [/zksample2_v5_0613(zk5.0.6)] startup failed due to previous
errors
23 Oct, 2013 4:43:10 PM org.apache.catalina.core.StandardContext stop
INFO: Container
org.apache.catalina.core.ContainerBase.[Catalina].[localhost].[/zksample2_v5_0613(zk5.0.6)]
has not been started
23 Oct, 2013 4:43:10 PM org.apache.catalina.startup.HostConfig deployWAR
INFO: Deploying web application archive PDFTConverter.war
23 Oct, 2013 4:43:11 PM org.apache.catalina.core.StandardContext
addApplicationListener
INFO: The listener "org.springframework.web.context.ContextLoaderListener" is
already configured for this context. The duplicate definition has been ignored.
23 Oct, 2013 4:43:11 PM org.apache.catalina.core.ApplicationContext log
INFO: Initializing Spring root WebApplicationContext
23 Oct, 2013 4:43:11 PM org.apache.catalina.core.StandardContext
addApplicationListener
INFO: The listener "org.jasig.cas.web.init.SafeContextLoaderListener" is
already configured for this context. The duplicate definition has been ignored.
23 Oct, 2013 4:43:11 PM org.apache.catalina.core.ApplicationContext log
INFO: Initializing Spring root WebApplicationContext
log4j:ERROR Attempted to append to closed appender named [cas].
2013-10-23 16:43:14,521 DEBUG
[org.jasig.cas.services.DefaultServicesManagerImpl] - <Adding registered
service ^(https?|imaps?)://.*>
log4j:ERROR Attempted to append to closed appender named [cas].
2013-10-23 16:43:14,525 INFO
[org.jasig.cas.services.DefaultServicesManagerImpl] - <Loaded 1 services.>
log4j:ERROR Attempted to append to closed appender named [cas].
2013-10-23 16:43:14,696 WARN
[org.jasig.cas.authentication.handler.support.SimpleTestUsernamePasswordAuthenticationHandler]
-
<org.jasig.cas.authentication.handler.support.SimpleTestUsernamePasswordAuthenticationHandler
is only to be used in a testing environment. NEVER enable this in a
production environment.>
log4j:ERROR Attempted to append to closed appender named [cas].
2013-10-23 16:43:14,877 DEBUG
[org.jasig.cas.util.AutowiringSchedulerFactoryBean] - <Autowired the following
triggers defined in application context:
[triggerJobDetailTicketRegistryCleaner, periodicServiceRegistryReloaderTrigger]>
log4j:ERROR Attempted to append to closed appender named [cas].
2013-10-23 16:43:15,778 INFO
[org.jasig.cas.util.AutowiringSchedulerFactoryBean] - <Starting Quartz
Scheduler now>
23 Oct, 2013 4:43:15 PM org.apache.catalina.core.ApplicationContext log
INFO: Initializing Spring FrameworkServlet 'cas'
log4j:ERROR Attempted to append to closed appender named [cas].
2013-10-23 16:43:16,086 DEBUG
[org.jasig.cas.services.web.ManageRegisteredServicesMultiActionController] -
<Found action method [public org.springframework.web.servlet.ModelAndView
org.jasig.cas.services.web.ManageRegisteredServicesMultiActionController.deleteRegisteredService(javax.servlet.http.HttpServletRequest,javax.servlet.http.HttpServletResponse)]>
log4j:ERROR Attempted to append to closed appender named [cas].
2013-10-23 16:43:16,089 DEBUG
[org.jasig.cas.services.web.ManageRegisteredServicesMultiActionController] -
<Found action method [public org.springframework.web.servlet.ModelAndView
org.jasig.cas.services.web.ManageRegisteredServicesMultiActionController.manage(javax.servlet.http.HttpServletRequest,javax.servlet.http.HttpServletResponse)]>
log4j:ERROR Attempted to append to closed appender named [cas].
2013-10-23 16:43:16,092 DEBUG
[org.jasig.cas.services.web.ManageRegisteredServicesMultiActionController] -
<Found action method [public org.springframework.web.servlet.ModelAndView
org.jasig.cas.services.web.ManageRegisteredServicesMultiActionController.updateRegisteredServiceEvaluationOrder(javax.servlet.http.HttpServletRequest,javax.servlet.http.HttpServletResponse)]>
23 Oct, 2013 4:43:16 PM org.apache.catalina.core.StandardContext
addApplicationListener
INFO: The listener "listeners.ContextListener" is already configured for this
context. The duplicate definition has been ignored.
23 Oct, 2013 4:43:16 PM org.apache.catalina.core.StandardContext
addApplicationListener
INFO: The listener "listeners.SessionListener" is already configured for this
context. The duplicate definition has been ignored.
23 Oct, 2013 4:43:16 PM org.apache.catalina.core.ApplicationContext log
INFO: ContextListener: contextInitialized()
23 Oct, 2013 4:43:16 PM org.apache.catalina.core.ApplicationContext log
INFO: SessionListener: contextInitialized()
23 Oct, 2013 4:43:17 PM org.apache.coyote.http11.Http11Protocol start
INFO: Starting Coyote HTTP/1.1 on http-8080
23 Oct, 2013 4:43:17 PM org.apache.coyote.http11.Http11Protocol start
INFO: Starting Coyote HTTP/1.1 on http-8090
23 Oct, 2013 4:43:17 PM org.apache.jk.common.ChannelSocket init
INFO: JK: ajp13 listening on /0.0.0.0:8009
23 Oct, 2013 4:43:17 PM org.apache.jk.server.JkMain start
INFO: Jk running ID=0 time=0/21 config=null
23 Oct, 2013 4:43:17 PM org.apache.catalina.startup.Catalina start
INFO: Server startup in 12510 ms
log4j:ERROR Attempted to append to closed appender named [cas].
2013-10-23 16:43:27,934 INFO
[org.jasig.cas.ticket.registry.support.DefaultTicketRegistryCleaner] -
<Beginning ticket cleanup.>
log4j:ERROR Attempted to append to closed appender named [cas].
2013-10-23 16:43:27,935 DEBUG
[org.jasig.cas.ticket.registry.support.DefaultTicketRegistryCleaner] -
<Attempting to acquire ticket cleanup lock.>
log4j:ERROR Attempted to append to closed appender named [cas].
2013-10-23 16:43:27,935 DEBUG
[org.jasig.cas.ticket.registry.support.DefaultTicketRegistryCleaner] -
<Acquired lock. Proceeding with cleanup.>
log4j:ERROR Attempted to append to closed appender named [cas].
2013-10-23 16:43:27,935 INFO
[org.jasig.cas.ticket.registry.support.DefaultTicketRegistryCleaner] - <0
tickets found to be removed.>
log4j:ERROR Attempted to append to closed appender named [cas].
2013-10-23 16:43:27,936 DEBUG
[org.jasig.cas.ticket.registry.support.DefaultTicketRegistryCleaner] -
<Releasing ticket cleanup lock.>
log4j:ERROR Attempted to append to closed appender named [cas].
2013-10-23 16:43:27,936 INFO
[org.jasig.cas.ticket.registry.support.DefaultTicketRegistryCleaner] -
<Finished ticket cleanup.>
log4j:ERROR Attempted to append to closed appender named [cas].
2013-10-23 16:43:34,213 INFO
[org.jasig.cas.ticket.registry.support.DefaultTicketRegistryCleaner] -
<Beginning ticket cleanup.>
log4j:ERROR Attempted to append to closed appender named [cas].
2013-10-23 16:43:34,215 DEBUG
[org.jasig.cas.ticket.registry.support.DefaultTicketRegistryCleaner] -
<Attempting to acquire ticket cleanup lock.>
log4j:ERROR Attempted to append to closed appender named [cas].
2013-10-23 16:43:34,216 DEBUG
[org.jasig.cas.ticket.registry.support.DefaultTicketRegistryCleaner] -
<Acquired lock. Proceeding with cleanup.>
log4j:ERROR Attempted to append to closed appender named [cas].
2013-10-23 16:43:34,216 INFO
[org.jasig.cas.ticket.registry.support.DefaultTicketRegistryCleaner] - <0
tickets found to be removed.>
log4j:ERROR Attempted to append to closed appender named [cas].
2013-10-23 16:43:34,217 DEBUG
[org.jasig.cas.ticket.registry.support.DefaultTicketRegistryCleaner] -
<Releasing ticket cleanup lock.>
log4j:ERROR Attempted to append to closed appender named [cas].
2013-10-23 16:43:34,217 INFO
[org.jasig.cas.ticket.registry.support.DefaultTicketRegistryCleaner] -
<Finished ticket cleanup.>
log4j:ERROR Attempted to append to closed appender named [cas].
2013-10-23 16:44:59,838 INFO [org.jasig.cas.web.flow.InitialFlowSetupAction] -
<Setting path for cookies to: /CASServer/>
log4j:ERROR Attempted to append to closed appender named [cas].
2013-10-23 16:44:59,845 DEBUG [org.jasig.cas.web.support.CasArgumentExtractor]
- <Extractor did not generate service.>
log4j:ERROR Attempted to append to closed appender named [cas].
2013-10-23 16:44:59,848 DEBUG [org.jasig.cas.web.support.SamlArgumentExtractor]
- <Extractor did not generate service.>
log4j:ERROR Attempted to append to closed appender named [cas].
log4j:ERROR Attempted to append to closed appender named [cas].
2013-10-23 16:44:59,857 DEBUG
[org.jasig.cas.support.oauth.web.flow.CustomAuthAction] - <providerType : null>
Inside the do Execute of CustomOAuth:false
log4j:ERROR Attempted to append to closed appender named [cas].
2013-10-23 16:44:59,871 DEBUG [org.scribe.up.provider.BaseOAuth20Provider] -
<authorizationUrl :
https://accounts.google.com/o/oauth2/auth?client_id=1007449103001.apps.googleusercontent.com&redirect_uri=https%3A%2F%2F01hw394314%3A8090%2FCASServer%2Flogin%3Foauth_provider%3DGoogle2Provider&scope=https%3A%2F%2Fwww.googleapis.com%2Fauth%2Fuserinfo.email&response_type=code>
log4j:ERROR Attempted to append to closed appender named [cas].
log4j:ERROR Attempted to append to closed appender named [cas].
2013-10-23 16:44:59,871 DEBUG
[org.jasig.cas.support.oauth.web.flow.CustomAuthAction] - <Google2ProviderUrl
->
https://accounts.google.com/o/oauth2/auth?client_id=1007449103001.apps.googleusercontent.com&redirect_uri=https%3A%2F%2F01hw394314%3A8090%2FCASServer%2Flogin%3Foauth_provider%3DGoogle2Provider&scope=https%3A%2F%2Fwww.googleapis.com%2Fauth%2Fuserinfo.email&response_type=code>
log4j:ERROR Attempted to append to closed appender named [cas].
2013-10-23 16:44:59,872 DEBUG [org.scribe.up.provider.BaseOAuth20Provider] -
<authorizationUrl :
https://github.com/login/oauth/authorize?client_id=64c6e690fc9406efa462&redirect_uri=https%3A%2F%2F01hw394314%3A8090%2FCASServer%2Flogin%3Foauth_provider%3DGitHubProvider&scope=user>
log4j:ERROR Attempted to append to closed appender named [cas].
log4j:ERROR Attempted to append to closed appender named [cas].
2013-10-23 16:44:59,873 DEBUG
[org.jasig.cas.support.oauth.web.flow.CustomAuthAction] - <GitHubProviderUrl ->
https://github.com/login/oauth/authorize?client_id=64c6e690fc9406efa462&redirect_uri=https%3A%2F%2F01hw394314%3A8090%2FCASServer%2Flogin%3Foauth_provider%3DGitHubProvider&scope=user>
log4j:ERROR Attempted to append to closed appender named [cas].
2013-10-23 16:44:59,876 DEBUG [org.scribe.up.provider.BaseOAuth20Provider] -
<authorizationUrl :
https://01hw394314:8090/CASServer/oauth2.0/authorize?client_id=this_is_the_key&redirect_uri=https%3A%2F%2F01hw394314%3A8090%2FCASServer%2Flogin%3Foauth_provider%3DCasWrapperProvider20>
log4j:ERROR Attempted to append to closed appender named [cas].
log4j:ERROR Attempted to append to closed appender named [cas].
2013-10-23 16:44:59,877 DEBUG
[org.jasig.cas.support.oauth.web.flow.CustomAuthAction] -
<CasWrapperProvider20Url ->
https://01hw394314:8090/CASServer/oauth2.0/authorize?client_id=this_is_the_key&redirect_uri=https%3A%2F%2F01hw394314%3A8090%2FCASServer%2Flogin%3Foauth_provider%3DCasWrapperProvider20>
log4j:ERROR Attempted to append to closed appender named [cas].
log4j:ERROR Attempted to append to closed appender named [cas].
2013-10-23 16:44:59,877 DEBUG
[org.jasig.cas.support.oauth.web.flow.CustomAuthAction] - <YahooProviderUrl ->
/CASServer/oauth10login?oauth_provider=YahooProvider>
log4j:ERROR Attempted to append to closed appender named [cas].
2013-10-23 16:45:02,237 DEBUG [org.jasig.cas.web.support.CasArgumentExtractor]
- <Extractor did not generate service.>
log4j:ERROR Attempted to append to closed appender named [cas].
2013-10-23 16:45:02,237 DEBUG [org.jasig.cas.web.support.SamlArgumentExtractor]
- <Extractor did not generate service.>
log4j:ERROR Attempted to append to closed appender named [cas].
2013-10-23 16:45:02,668 DEBUG [org.jasig.cas.web.support.CasArgumentExtractor]
- <Extractor did not generate service.>
log4j:ERROR Attempted to append to closed appender named [cas].
2013-10-23 16:45:02,668 DEBUG [org.jasig.cas.web.support.SamlArgumentExtractor]
- <Extractor did not generate service.>
log4j:ERROR Attempted to append to closed appender named [cas].
log4j:ERROR Attempted to append to closed appender named [cas].
2013-10-23 16:45:02,668 DEBUG
[org.jasig.cas.support.oauth.web.flow.CustomAuthAction] - <providerType : null>
Inside the do Execute of CustomOAuth:false
log4j:ERROR Attempted to append to closed appender named [cas].
2013-10-23 16:45:02,669 DEBUG [org.scribe.up.provider.BaseOAuth20Provider] -
<authorizationUrl :
https://accounts.google.com/o/oauth2/auth?client_id=1007449103001.apps.googleusercontent.com&redirect_uri=https%3A%2F%2F01hw394314%3A8090%2FCASServer%2Flogin%3Foauth_provider%3DGoogle2Provider&scope=https%3A%2F%2Fwww.googleapis.com%2Fauth%2Fuserinfo.email&response_type=code>
log4j:ERROR Attempted to append to closed appender named [cas].
log4j:ERROR Attempted to append to closed appender named [cas].
2013-10-23 16:45:02,669 DEBUG
[org.jasig.cas.support.oauth.web.flow.CustomAuthAction] - <Google2ProviderUrl
->
https://accounts.google.com/o/oauth2/auth?client_id=1007449103001.apps.googleusercontent.com&redirect_uri=https%3A%2F%2F01hw394314%3A8090%2FCASServer%2Flogin%3Foauth_provider%3DGoogle2Provider&scope=https%3A%2F%2Fwww.googleapis.com%2Fauth%2Fuserinfo.email&response_type=code>
log4j:ERROR Attempted to append to closed appender named [cas].
2013-10-23 16:45:02,670 DEBUG [org.scribe.up.provider.BaseOAuth20Provider] -
<authorizationUrl :
https://github.com/login/oauth/authorize?client_id=64c6e690fc9406efa462&redirect_uri=https%3A%2F%2F01hw394314%3A8090%2FCASServer%2Flogin%3Foauth_provider%3DGitHubProvider&scope=user>
log4j:ERROR Attempted to append to closed appender named [cas].
log4j:ERROR Attempted to append to closed appender named [cas].
2013-10-23 16:45:02,670 DEBUG
[org.jasig.cas.support.oauth.web.flow.CustomAuthAction] - <GitHubProviderUrl ->
https://github.com/login/oauth/authorize?client_id=64c6e690fc9406efa462&redirect_uri=https%3A%2F%2F01hw394314%3A8090%2FCASServer%2Flogin%3Foauth_provider%3DGitHubProvider&scope=user>
log4j:ERROR Attempted to append to closed appender named [cas].
2013-10-23 16:45:02,671 DEBUG [org.scribe.up.provider.BaseOAuth20Provider] -
<authorizationUrl :
https://01hw394314:8090/CASServer/oauth2.0/authorize?client_id=this_is_the_key&redirect_uri=https%3A%2F%2F01hw394314%3A8090%2FCASServer%2Flogin%3Foauth_provider%3DCasWrapperProvider20>
log4j:ERROR Attempted to append to closed appender named [cas].
log4j:ERROR Attempted to append to closed appender named [cas].
2013-10-23 16:45:02,671 DEBUG
[org.jasig.cas.support.oauth.web.flow.CustomAuthAction] -
<CasWrapperProvider20Url ->
https://01hw394314:8090/CASServer/oauth2.0/authorize?client_id=this_is_the_key&redirect_uri=https%3A%2F%2F01hw394314%3A8090%2FCASServer%2Flogin%3Foauth_provider%3DCasWrapperProvider20>
log4j:ERROR Attempted to append to closed appender named [cas].
log4j:ERROR Attempted to append to closed appender named [cas].
2013-10-23 16:45:02,671 DEBUG
[org.jasig.cas.support.oauth.web.flow.CustomAuthAction] - <YahooProviderUrl ->
/CASServer/oauth10login?oauth_provider=YahooProvider>
log4j:ERROR Attempted to append to closed appender named [cas].
2013-10-23 16:45:02,672 DEBUG [org.jasig.cas.web.support.CasArgumentExtractor]
- <Extractor did not generate service.>
log4j:ERROR Attempted to append to closed appender named [cas].
2013-10-23 16:45:02,673 DEBUG [org.jasig.cas.web.support.SamlArgumentExtractor]
- <Extractor did not generate service.>
log4j:ERROR Attempted to append to closed appender named [cas].
2013-10-23 16:45:08,303 DEBUG [org.jasig.cas.web.support.CasArgumentExtractor]
- <Extractor did not generate service.>
log4j:ERROR Attempted to append to closed appender named [cas].
2013-10-23 16:45:08,303 DEBUG [org.jasig.cas.web.support.SamlArgumentExtractor]
- <Extractor did not generate service.>
log4j:ERROR Attempted to append to closed appender named [cas].
log4j:ERROR Attempted to append to closed appender named [cas].
2013-10-23 16:45:08,304 DEBUG
[org.jasig.cas.support.oauth.web.flow.CustomAuthAction] - <providerType :
GitHubProvider>
Inside the do Execute of CustomOAuth:true
log4j:ERROR Attempted to append to closed appender named [cas].
log4j:ERROR Attempted to append to closed appender named [cas].
2013-10-23 16:45:08,305 DEBUG
[org.jasig.cas.support.oauth.web.flow.CustomAuthAction] - <provider :
org.scribe.up.provider.impl.GitHubProvider@b9ec2b>
log4j:ERROR Attempted to append to closed appender named [cas].
2013-10-23 16:45:08,306 DEBUG [org.scribe.up.provider.BaseOAuth20Provider] -
<parameters={oauth_provider=[Ljava.lang.String;@4030c4,
code=[Ljava.lang.String;@419710}>
log4j:ERROR Attempted to append to closed appender named [cas].
2013-10-23 16:45:08,306 DEBUG [org.scribe.up.provider.BaseOAuth20Provider] -
<sessionorg.scribe.up.session.HttpUserSession@24ccce>
log4j:ERROR Attempted to append to closed appender named [cas].
2013-10-23 16:45:08,306 DEBUG [org.scribe.up.provider.BaseOAuth20Provider] -
<Verifiers:[Ljava.lang.String;@419710>
log4j:ERROR Attempted to append to closed appender named [cas].
2013-10-23 16:45:08,307 DEBUG [org.scribe.up.provider.BaseOAuth20Provider] -
<Utility.getRequest().getParameter(access_token)null>
log4j:ERROR Attempted to append to closed appender named [cas].
2013-10-23 16:45:08,307 DEBUG [org.scribe.up.provider.BaseOAuth20Provider] -
<Verifiers:ACC_TOKEN[Ljava.lang.String;@419710>
log4j:ERROR Attempted to append to closed appender named [cas].
2013-10-23 16:45:08,307 DEBUG [org.scribe.up.provider.BaseOAuth20Provider] -
<STr:437d58775b05136bd6ac>
log4j:ERROR Attempted to append to closed appender named [cas].
2013-10-23 16:45:08,308 DEBUG [org.scribe.up.provider.BaseOAuth20Provider] -
<verifier : 437d58775b05136bd6ac>
log4j:ERROR Attempted to append to closed appender named [cas].
log4j:ERROR Attempted to append to closed appender named [cas].
2013-10-23 16:45:08,308 DEBUG
[org.jasig.cas.support.oauth.web.flow.CustomAuthAction] - <credential :
OAuthCredential{requestToken:null,token:null,verifier:437d58775b05136bd6ac,providerType:GitHubProvider}>
log4j:ERROR Attempted to append to closed appender named [cas].
log4j:ERROR Attempted to append to closed appender named [cas].
2013-10-23 16:45:08,337 DEBUG
[org.jasig.cas.support.oauth.authentication.handler.support.OAuthAuthenticationHandler]
- <credential :
org.jasig.cas.support.oauth.authentication.principal.OAuthCredentials@ce4f39>
log4j:ERROR Attempted to append to closed appender named [cas].
log4j:ERROR Attempted to append to closed appender named [cas].
2013-10-23 16:45:08,338 DEBUG
[org.jasig.cas.support.oauth.authentication.handler.support.OAuthAuthenticationHandler]
- <providerType : GitHubProvider>
log4j:ERROR Attempted to append to closed appender named [cas].
log4j:ERROR Attempted to append to closed appender named [cas].
2013-10-23 16:45:08,338 DEBUG
[org.jasig.cas.support.oauth.authentication.handler.support.OAuthAuthenticationHandler]
- <provider : org.scribe.up.provider.impl.GitHubProvider@b9ec2b>
log4j:ERROR Attempted to append to closed appender named [cas].
2013-10-23 16:45:08,339 DEBUG [org.scribe.up.provider.BaseOAuth20Provider] -
<verifier : 437d58775b05136bd6ac>
log4j:ERROR Attempted to append to closed appender named [cas].
2013-10-23 16:45:08,374 INFO
[org.jasig.cas.services.DefaultServicesManagerImpl] - <Reloading registered
services.>
log4j:ERROR Attempted to append to closed appender named [cas].
2013-10-23 16:45:08,375 DEBUG
[org.jasig.cas.services.DefaultServicesManagerImpl] - <Adding registered
service ^(https?|imaps?)://.*>
log4j:ERROR Attempted to append to closed appender named [cas].
2013-10-23 16:45:08,375 DEBUG
[org.jasig.cas.services.DefaultServicesManagerImpl] - <Adding registered
service https://01hw394314:8090/CASServer/oauth2.0/callbackAuthorize>
log4j:ERROR Attempted to append to closed appender named [cas].
2013-10-23 16:45:08,375 DEBUG
[org.jasig.cas.services.DefaultServicesManagerImpl] - <Adding registered
service http://mycasserver/login>
log4j:ERROR Attempted to append to closed appender named [cas].
2013-10-23 16:45:08,376 INFO
[org.jasig.cas.services.DefaultServicesManagerImpl] - <Loaded 2 services.>
log4j:ERROR Attempted to append to closed appender named [cas].
2013-10-23 16:45:09,562 DEBUG [org.scribe.up.provider.BaseOAuth20Provider] -
<accessToken : Token[942ac6e711e1c6b8e8206cd057de79c247dc6a3c , ]>
log4j:ERROR Attempted to append to closed appender named [cas].
2013-10-23 16:45:09,563 DEBUG [org.scribe.up.provider.BaseOAuthProvider] -
<accessToken : Token[942ac6e711e1c6b8e8206cd057de79c247dc6a3c , ] / dataUrl :
https://api.github.com/user>
log4j:ERROR Attempted to append to closed appender named [cas].
2013-10-23 16:45:12,322 DEBUG [org.scribe.up.provider.BaseOAuthProvider] -
<Request took : 2759 ms for : https://api.github.com/user>
log4j:ERROR Attempted to append to closed appender named [cas].
2013-10-23 16:45:12,326 DEBUG [org.scribe.up.provider.BaseOAuthProvider] -
<response code : 200 / response body :
{"login":"hardikJsheth","id":5735836,"avatar_url":"https://2.gravatar.com/avatar/49bceebf0451f681cd4091932af92a83?d=https%3A%2F%2Fidenticons.github.com%2F2e89ce9928b71b36e2f4fe85b08e2676.png&r=x","gravatar_id":"49bceebf0451f681cd4091932af92a83","url":"https://api.github.com/users/hardikJsheth","html_url":"https://github.com/hardikJsheth","followers_url":"https://api.github.com/users/hardikJsheth/followers","following_url":"https://api.github.com/users/hardikJsheth/f
com/users/hardikJsheth/subscriptions","organizations_url":"https://api.github.com/users/hardikJsheth/orgs","repos_url":"https://api.github.com/users/hardikJsheth/repos","events_url":"https://api.github.com/users/hardikJsheth/events{/privacy}","received_events_url":"https://api.github.com/users/hardikJsheth/received_events","type":"User","site_admin":false,"public_repos":0,"followers":0,"following":0,"created_at":"2013-10-21T09:07:56Z","updated_at":"2013-10-23T05:19:25Z","public_gists":0,"total_private_repos":0,"owned_private_repos":0,"disk_usage":0,"collaborators":0,"plan":{"na
2013-10-23 16:45:12,338 INFO
[com.github.inspektr.audit.support.Slf4jLoggingAuditTrailManager] - <Audit
trail record BEGIN
=============================================================
WHO:
org.jasig.cas.support.oauth.authentication.principal.OAuthCredentials@ce4f39
WHAT: 'principal' cannot be null.
Check the correctness of @Audit annotation at the following audit point:
execution(public abstract org.jasig.cas.authentication.Authentication
org.jasig.cas.authentication.AuthenticationManager.authenticate(org.jasig.cas.authentication.principal.Credentials))
ACTION: TICKET_GRANTING_TICKET_NOT_CREATED
APPLICATION: CAS
WHEN: Wed Oct 23 16:45:12 IST 2013
CLIENT IP ADDRESS: 2002:e61:150d:0:0:0:e61:150d
SERVER IP ADDRESS: 0.0.0.0
=============================================================
>
java.lang.IllegalArgumentException: 'principal' cannot be null.
Check the correctness of @Audit annotation at the following audit point:
execution(public abstract org.jasig.cas.authentication.Authentication
org.jasig.cas.authentication.AuthenticationManager.authenticate(org.jasig.cas.authentication.principal.Credentials))
at
com.github.inspektr.audit.AuditActionContext.assertNotNull(AuditActionContext.java:81)
at
com.github.inspektr.audit.AuditActionContext.<init>(AuditActionContext.java:63)
at
com.github.inspektr.audit.AuditTrailManagementAspect.executeAuditCode(AuditTrailManagementAspect.java:149)
at
com.github.inspektr.audit.AuditTrailManagementAspect.handleAuditTrail(AuditTrailManagementAspect.java:139)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
at
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
at java.lang.reflect.Method.invoke(Method.java:597)
at
org.springframework.aop.aspectj.AbstractAspectJAdvice.invokeAdviceMethodWithGivenArgs(AbstractAspectJAdvice.java:621)
at
org.springframework.aop.aspectj.AbstractAspectJAdvice.invokeAdviceMethod(AbstractAspectJAdvice.java:610)
at
org.springframework.aop.aspectj.AspectJAroundAdvice.invoke(AspectJAroundAdvice.java:65)
at
org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:161)
at
org.springframework.aop.aspectj.MethodInvocationProceedingJoinPoint.proceed(MethodInvocationProceedingJoinPoint.java:80)
at
org.perf4j.aop.AbstractTimingAspect$1.proceed(AbstractTimingAspect.java:47)
at
org.perf4j.aop.AgnosticTimingAspect.runProfiledMethod(AgnosticTimingAspect.java:53)
at
org.perf4j.aop.AbstractTimingAspect.doPerfLogging(AbstractTimingAspect.java:45)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
at
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
at java.lang.reflect.Method.invoke(Method.java:597)
at
org.springframework.aop.aspectj.AbstractAspectJAdvice.invokeAdviceMethodWithGivenArgs(AbstractAspectJAdvice.java:621)
at
org.springframework.aop.aspectj.AbstractAspectJAdvice.invokeAdviceMethod(AbstractAspectJAdvice.java:610)
at
org.springframework.aop.aspectj.AspectJAroundAdvice.invoke(AspectJAroundAdvice.java:65)
at
org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:161)
at
org.springframework.aop.interceptor.ExposeInvocationInterceptor.invoke(ExposeInvocationInterceptor.java:90)
at
org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172)
at
org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDynamicAopProxy.java:202)
at $Proxy23.authenticate(Unknown Source)
at
org.jasig.cas.CentralAuthenticationServiceImpl.createTicketGrantingTicket_aroundBody10(CentralAuthenticationServiceImpl.java:477)
at
org.jasig.cas.CentralAuthenticationServiceImpl.createTicketGrantingTicket_aroundBody11$advice(CentralAuthenticationServiceImpl.java:57)
at
org.jasig.cas.CentralAuthenticationServiceImpl.createTicketGrantingTicket(CentralAuthenticationServiceImpl.java:1)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
at
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
at java.lang.reflect.Method.invoke(Method.java:597)
at
org.springframework.aop.support.AopUtils.invokeJoinpointUsingReflection(AopUtils.java:318)
at
org.springframework.aop.framework.ReflectiveMethodInvocation.invokeJoinpoint(ReflectiveMethodInvocation.java:183)
at
org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:150)
at
org.springframework.aop.aspectj.MethodInvocationProceedingJoinPoint.proceed(MethodInvocationProceedingJoinPoint.java:80)
at
com.github.inspektr.audit.AuditTrailManagementAspect.handleAuditTrail(AuditTrailManagementAspect.java:126)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
at
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
at java.lang.reflect.Method.invoke(Method.java:597)
at
org.springframework.aop.aspectj.AbstractAspectJAdvice.invokeAdviceMethodWithGivenArgs(AbstractAspectJAdvice.java:621)
at
org.springframework.aop.aspectj.AbstractAspectJAdvice.invokeAdviceMethod(AbstractAspectJAdvice.java:610)
at
org.springframework.aop.aspectj.AspectJAroundAdvice.invoke(AspectJAroundAdvice.java:65)
at
org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:161)
at
org.springframework.aop.aspectj.MethodInvocationProceedingJoinPoint.proceed(MethodInvocationProceedingJoinPoint.java:80)
at
org.perf4j.aop.AbstractTimingAspect$1.proceed(AbstractTimingAspect.java:47)
at
org.perf4j.aop.AgnosticTimingAspect.runProfiledMethod(AgnosticTimingAspect.java:53)
at
org.perf4j.aop.AbstractTimingAspect.doPerfLogging(AbstractTimingAspect.java:45)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
at
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
at java.lang.reflect.Method.invoke(Method.java:597)
at
org.springframework.aop.aspectj.AbstractAspectJAdvice.invokeAdviceMethodWithGivenArgs(AbstractAspectJAdvice.java:621)
at
org.springframework.aop.aspectj.AbstractAspectJAdvice.invokeAdviceMethod(AbstractAspectJAdvice.java:610)
at
org.springframework.aop.aspectj.AspectJAroundAdvice.invoke(AspectJAroundAdvice.java:65)
at
org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:161)
at
org.springframework.aop.interceptor.ExposeInvocationInterceptor.invoke(ExposeInvocationInterceptor.java:90)
at
org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172)
at
org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDynamicAopProxy.java:202)
at $Proxy24.createTicketGrantingTicket(Unknown Source)
at
org.jasig.cas.support.oauth.web.flow.CustomAuthAction.doExecute(CustomAuthAction.java:103)
at
org.springframework.webflow.action.AbstractAction.execute(AbstractAction.java:188)
at
org.springframework.webflow.execution.ActionExecutor.execute(ActionExecutor.java:53)
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:53)
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.Flow.start(Flow.java:535)
at
org.springframework.webflow.engine.impl.FlowExecutionImpl.start(FlowExecutionImpl.java:366)
at
org.springframework.webflow.engine.impl.FlowExecutionImpl.start(FlowExecutionImpl.java:225)
at
org.springframework.webflow.executor.FlowExecutorImpl.launchExecution(FlowExecutorImpl.java:140)
at
org.springframework.webflow.mvc.servlet.FlowHandlerAdapter.handle(FlowHandlerAdapter.java:193)
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.doGet(FrameworkServlet.java:778)
at javax.servlet.http.HttpServlet.service(HttpServlet.java:690)
at javax.servlet.http.HttpServlet.service(HttpServlet.java:803)
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:290)
at
org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:206)
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:235)
at
org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:206)
at
com.github.inspektr.common.web.ClientInfoThreadLocalFilter.doFilter(ClientInfoThreadLocalFilter.java:63)
at
org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:235)
at
org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:206)
at
org.apache.catalina.core.StandardWrapperValve.invoke(StandardWrapperValve.java:233)
at
org.apache.catalina.core.StandardContextValve.invoke(StandardContextValve.java:175)
at
org.apache.catalina.authenticator.AuthenticatorBase.invoke(AuthenticatorBase.java:433)
at
org.apache.catalina.core.StandardHostValve.invoke(StandardHostValve.java:128)
at
org.apache.catalina.valves.ErrorReportValve.invoke(ErrorReportValve.java:102)
at
org.apache.catalina.core.StandardEngineValve.invoke(StandardEngineValve.java:109)
at
org.apache.catalina.connector.CoyoteAdapter.service(CoyoteAdapter.java:286)
at
org.apache.coyote.http11.Http11Processor.process(Http11Processor.java:844)
at
org.apache.coyote.http11.Http11Protocol$Http11ConnectionHandler.process(Http11Protocol.java:583)
at
org.apache.tomcat.util.net.JIoEndpoint$Worker.run(JIoEndpoint.java:447)
at java.lang.Thread.run(Thread.java:619)
log4j:ERROR Attempted to append to closed appender named [cas].
2013-10-23 16:45:12,574 DEBUG
[org.jasig.cas.web.FlowExecutionExceptionResolver] - <Ignoring the received
exception due to a type mismatch>
org.springframework.webflow.execution.ActionExecutionException: Exception
thrown executing org.jasig.cas.support.oauth.web.flow.CustomAuthAction@815832
in state 'oauthAction' of flow 'login' -- action execution attributes were
'map[[empty]]'
at
org.springframework.webflow.execution.ActionExecutor.execute(ActionExecutor.java:63)
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:53)
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.Flow.start(Flow.java:535)
at
org.springframework.webflow.engine.impl.FlowExecutionImpl.start(FlowExecutionImpl.java:366)
at
org.springframework.webflow.engine.impl.FlowExecutionImpl.start(FlowExecutionImpl.java:225)
at
org.springframework.webflow.executor.FlowExecutorImpl.launchExecution(FlowExecutorImpl.java:140)
at
org.springframework.webflow.mvc.servlet.FlowHandlerAdapter.handle(FlowHandlerAdapter.java:193)
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.doGet(FrameworkServlet.java:778)
at javax.servlet.http.HttpServlet.service(HttpServlet.java:690)
at javax.servlet.http.HttpServlet.service(HttpServlet.java:803)
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:290)
at
org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:206)
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:235)
at
org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:206)
at
com.github.inspektr.common.web.ClientInfoThreadLocalFilter.doFilter(ClientInfoThreadLocalFilter.java:63)
at
org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:235)
at
org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:206)
at
org.apache.catalina.core.StandardWrapperValve.invoke(StandardWrapperValve.java:233)
at
org.apache.catalina.core.StandardContextValve.invoke(StandardContextValve.java:175)
at
org.apache.catalina.authenticator.AuthenticatorBase.invoke(AuthenticatorBase.java:433)
at
org.apache.catalina.core.StandardHostValve.invoke(StandardHostValve.java:128)
at
org.apache.catalina.valves.ErrorReportValve.invoke(ErrorReportValve.java:102)
at
org.apache.catalina.core.StandardEngineValve.invoke(StandardEngineValve.java:109)
at
org.apache.catalina.connector.CoyoteAdapter.service(CoyoteAdapter.java:286)
at
org.apache.coyote.http11.Http11Processor.process(Http11Processor.java:844)
at
org.apache.coyote.http11.Http11Protocol$Http11ConnectionHandler.process(Http11Protocol.java:583)
at
org.apache.tomcat.util.net.JIoEndpoint$Worker.run(JIoEndpoint.java:447)
at java.lang.Thread.run(Thread.java:619)
Caused by: java.lang.IllegalArgumentException: 'principal' cannot be null.
Check the correctness of @Audit annotation at the following audit point:
execution(public abstract org.jasig.cas.authentication.Authentication
org.jasig.cas.authentication.AuthenticationManager.authenticate(org.jasig.cas.authentication.principal.Credentials))
at
com.github.inspektr.audit.AuditActionContext.assertNotNull(AuditActionContext.java:81)
at
com.github.inspektr.audit.AuditActionContext.<init>(AuditActionContext.java:63)
at
com.github.inspektr.audit.AuditTrailManagementAspect.executeAuditCode(AuditTrailManagementAspect.java:149)
at
com.github.inspektr.audit.AuditTrailManagementAspect.handleAuditTrail(AuditTrailManagementAspect.java:139)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
at
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
at java.lang.reflect.Method.invoke(Method.java:597)
at
org.springframework.aop.aspectj.AbstractAspectJAdvice.invokeAdviceMethodWithGivenArgs(AbstractAspectJAdvice.java:621)
at
org.springframework.aop.aspectj.AbstractAspectJAdvice.invokeAdviceMethod(AbstractAspectJAdvice.java:610)
at
org.springframework.aop.aspectj.AspectJAroundAdvice.invoke(AspectJAroundAdvice.java:65)
at
org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:161)
at
org.springframework.aop.aspectj.MethodInvocationProceedingJoinPoint.proceed(MethodInvocationProceedingJoinPoint.java:80)
at
org.perf4j.aop.AbstractTimingAspect$1.proceed(AbstractTimingAspect.java:47)
at
org.perf4j.aop.AgnosticTimingAspect.runProfiledMethod(AgnosticTimingAspect.java:53)
at
org.perf4j.aop.AbstractTimingAspect.doPerfLogging(AbstractTimingAspect.java:45)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
at
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
at java.lang.reflect.Method.invoke(Method.java:597)
at
org.springframework.aop.aspectj.AbstractAspectJAdvice.invokeAdviceMethodWithGivenArgs(AbstractAspectJAdvice.java:621)
at
org.springframework.aop.aspectj.AbstractAspectJAdvice.invokeAdviceMethod(AbstractAspectJAdvice.java:610)
at
org.springframework.aop.aspectj.AspectJAroundAdvice.invoke(AspectJAroundAdvice.java:65)
at
org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:161)
at
org.springframework.aop.interceptor.ExposeInvocationInterceptor.invoke(ExposeInvocationInterceptor.java:90)
at
org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172)
at
org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDynamicAopProxy.java:202)
at $Proxy23.authenticate(Unknown Source)
at
org.jasig.cas.CentralAuthenticationServiceImpl.createTicketGrantingTicket_aroundBody10(CentralAuthenticationServiceImpl.java:477)
at
org.jasig.cas.CentralAuthenticationServiceImpl.createTicketGrantingTicket_aroundBody11$advice(CentralAuthenticationServiceImpl.java:57)
at
org.jasig.cas.CentralAuthenticationServiceImpl.createTicketGrantingTicket(CentralAuthenticationServiceImpl.java:1)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
at
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
at java.lang.reflect.Method.invoke(Method.java:597)
at
org.springframework.aop.support.AopUtils.invokeJoinpointUsingReflection(AopUtils.java:318)
at
org.springframework.aop.framework.ReflectiveMethodInvocation.invokeJoinpoint(ReflectiveMethodInvocation.java:183)
at
org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:150)
at
org.springframework.aop.aspectj.MethodInvocationProceedingJoinPoint.proceed(MethodInvocationProceedingJoinPoint.java:80)
at
com.github.inspektr.audit.AuditTrailManagementAspect.handleAuditTrail(AuditTrailManagementAspect.java:126)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
at
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
at java.lang.reflect.Method.invoke(Method.java:597)
at
org.springframework.aop.aspectj.AbstractAspectJAdvice.invokeAdviceMethodWithGivenArgs(AbstractAspectJAdvice.java:621)
at
org.springframework.aop.aspectj.AbstractAspectJAdvice.invokeAdviceMethod(AbstractAspectJAdvice.java:610)
at
org.springframework.aop.aspectj.AspectJAroundAdvice.invoke(AspectJAroundAdvice.java:65)
at
org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:161)
at
org.springframework.aop.aspectj.MethodInvocationProceedingJoinPoint.proceed(MethodInvocationProceedingJoinPoint.java:80)
at
org.perf4j.aop.AbstractTimingAspect$1.proceed(AbstractTimingAspect.java:47)
at
org.perf4j.aop.AgnosticTimingAspect.runProfiledMethod(AgnosticTimingAspect.java:53)
at
org.perf4j.aop.AbstractTimingAspect.doPerfLogging(AbstractTimingAspect.java:45)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
at
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
at java.lang.reflect.Method.invoke(Method.java:597)
at
org.springframework.aop.aspectj.AbstractAspectJAdvice.invokeAdviceMethodWithGivenArgs(AbstractAspectJAdvice.java:621)
at
org.springframework.aop.aspectj.AbstractAspectJAdvice.invokeAdviceMethod(AbstractAspectJAdvice.java:610)
at
org.springframework.aop.aspectj.AspectJAroundAdvice.invoke(AspectJAroundAdvice.java:65)
at
org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:161)
at
org.springframework.aop.interceptor.ExposeInvocationInterceptor.invoke(ExposeInvocationInterceptor.java:90)
at
org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172)
at
org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDynamicAopProxy.java:202)
at $Proxy24.createTicketGrantingTicket(Unknown Source)
at
org.jasig.cas.support.oauth.web.flow.CustomAuthAction.doExecute(CustomAuthAction.java:103)
at
org.springframework.webflow.action.AbstractAction.execute(AbstractAction.java:188)
at
org.springframework.webflow.execution.ActionExecutor.execute(ActionExecutor.java:53)
... 42 more
log4j:ERROR Attempted to append to closed appender named [cas].
2013-10-23 16:45:14,603 INFO
[org.jasig.cas.services.DefaultServicesManagerImpl] - <Reloading registered
services.>
log4j:ERROR Attempted to append to closed appender named [cas].
2013-10-23 16:45:14,684 DEBUG
[org.jasig.cas.services.DefaultServicesManagerImpl] - <Adding registered
service ^(https?|imaps?)://.*>
log4j:ERROR Attempted to append to closed appender named [cas].
2013-10-23 16:45:14,688 INFO
[org.jasig.cas.services.DefaultServicesManagerImpl] - <Loaded 1 services.>
![https://2.gravatar.com/avatar/49bceebf0451f681cd4091932af92a83?d=https%3A%2F%2Fidenticons.github.com%2F2e89ce9928b71b36e2f4fe85b08e2676.png&r=x","gravatar_id":"49bceebf0451f681cd4091932af92a83","url":"https://api.github.com/users/hardikJsheth","html_url":"https://github.com/hardikJsheth","followers_url":"https://api.github.com/users/hardikJsheth/followers","following_url":"https://api.github.com/users/hardikJsheth/f](https://2.gravatar.com/avatar/49bceebf0451f681cd4091932af92a83?d=https%3A%2F%2Fidenticons.github.com%2F2e89ce9928b71b36e2f4fe85b08e2676.png&r=x","gravatar_id":"49bceebf0451f681cd4091932af92a83","url":"https://api.github.com/users/hardikJsheth","html_url":"https://github.com/hardikJsheth","followers_url":"https://api.github.com/users/hardikJsheth/followers","following_url":"https://api.github.com/users/hardikJsheth/f){kind=link}