Dear Scott,
We are using
- RHEL 5 x86-64
- Tomcat5
- java-1.6.0
- httpd using ajp
- Content switch
Ticket State at this point is not the issue. We can not reproduce the same
events for direct connect to single server vs single server behind content
switch which is doing round robin at the moment, so
server 1 10.0.0.1
server 2 10.0.0.2
content switch IP 11.0.0.1
Direct Connect to Server
https-> 10.0.0.1 things work correctly and no authentication is required for
login to second SSO application
Connect to Server with only "server 1" available behind the content switch.
https->11.0.0.1 -> 10.0.0.1 we are asked to login again for the second
application
I have attached the logs that show
AuthenticationViaFormAction vs GenerateServiceTicketAction
I have also attached the log for the start of the server.
Yours sincerely
David Ruwoldt
--
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
Second login gets passed through
2009-02-26 15:28:09,784 DEBUG [org.jasig.cas.web.flow.InitialFlowSetupAction] -
<Action 'InitialFlowSetupAction' beginning execution>
2009-02-26 15:28:09,785 DEBUG [org.jasig.cas.web.support.CasArgumentExtractor]
- <Extractor generated service for: http://itsbst067.its.adelaide.edu.au:3002/>
2009-02-26 15:28:09,785 DEBUG [org.jasig.cas.web.flow.InitialFlowSetupAction] -
<Placing service in FlowScope: http://itsbst067.its.adelaide.edu.au:3002/>
2009-02-26 15:28:09,785 DEBUG [org.jasig.cas.web.flow.InitialFlowSetupAction] -
<Action 'InitialFlowSetupAction' completed execution; result is 'success'>
2009-02-26 15:28:09,786 DEBUG
[org.jasig.cas.web.flow.AuthenticationViaFormAction] - <Action
'AuthenticationViaFormAction' beginning execution>
2009-02-26 15:28:09,786 DEBUG
[org.jasig.cas.web.flow.AuthenticationViaFormAction] - <Executing setupForm>
2009-02-26 15:28:09,786 DEBUG
[org.jasig.cas.web.flow.AuthenticationViaFormAction] - <Creating new form
object with name 'credentials'>
2009-02-26 15:28:09,786 DEBUG
[org.jasig.cas.web.flow.AuthenticationViaFormAction] - <Creating new instance
of form object class [class
org.jasig.cas.authentication.principal.UsernamePasswordCredentials]>
2009-02-26 15:28:09,786 DEBUG
[org.jasig.cas.web.flow.AuthenticationViaFormAction] - <Putting form object of
type [class org.jasig.cas.authentication.principal.UsernamePasswordCredentials]
in scope Flow with name 'credentials'>
2009-02-26 15:28:09,786 DEBUG
[org.jasig.cas.web.flow.AuthenticationViaFormAction] - <Creating new form
errors for object with name 'credentials'>
2009-02-26 15:28:09,786 DEBUG
[org.jasig.cas.web.flow.AuthenticationViaFormAction] - <No property editor
registrar set, no custom editors to register>
2009-02-26 15:28:09,787 DEBUG
[org.jasig.cas.web.flow.AuthenticationViaFormAction] - <Putting form errors
instance in scope Flash>
2009-02-26 15:28:09,787 DEBUG
[org.jasig.cas.web.flow.AuthenticationViaFormAction] - <Action
'AuthenticationViaFormAction' completed execution; result is 'success'>
2009-02-26 15:28:09,787 DEBUG
[org.jasig.cas.web.flow.AuthenticationViaFormAction] - <Action
'AuthenticationViaFormAction' beginning execution>
2009-02-26 15:28:09,787 DEBUG
[org.jasig.cas.web.flow.AuthenticationViaFormAction] - <Action
'AuthenticationViaFormAction' completed execution; result is 'success'>
2009-02-26 15:28:14,613 DEBUG
[org.jasig.cas.web.flow.AuthenticationViaFormAction] - <Action
'AuthenticationViaFormAction' beginning execution>
2009-02-26 15:28:14,613 DEBUG
[org.jasig.cas.web.flow.AuthenticationViaFormAction] - <Executing bind>
2009-02-26 15:28:14,613 DEBUG
[org.jasig.cas.web.flow.AuthenticationViaFormAction] - <Found existing form
object with name 'credentials' of type [class
org.jasig.cas.authentication.principal.UsernamePasswordCredentials] in scope
Flow>
2009-02-26 15:28:14,613 DEBUG
[org.jasig.cas.web.flow.AuthenticationViaFormAction] - <No property editor
registrar set, no custom editors to register>
2009-02-26 15:28:14,613 DEBUG
[org.jasig.cas.web.flow.AuthenticationViaFormAction] - <Binding allowed request
parameters in map['lt' ->
'_c40B73EB5-660B-70A0-5AC1-F4D8DB4DE826_k449230C2-2049-32DE-9BAB-6DBAA616204F',
'service' -> 'http://itsbst067.its.adelaide.edu.au:3002/', '_eventId' ->
'submit', 'password' -> '', 'submit' -> 'LOGIN', 'username' -> 'a1066345'] to
form object with name 'credentials', pre-bind formObject toString = [username:
null]>
2009-02-26 15:28:14,613 DEBUG
[org.jasig.cas.web.flow.AuthenticationViaFormAction] - <(Any field is allowed)>
2009-02-26 15:28:14,613 DEBUG
[org.jasig.cas.web.flow.AuthenticationViaFormAction] - <Binding completed for
form object with name 'credentials', post-bind formObject toString = [username:
a1066345]>
2009-02-26 15:28:14,613 DEBUG
[org.jasig.cas.web.flow.AuthenticationViaFormAction] - <There are [0] errors,
details: []>
2009-02-26 15:28:14,614 DEBUG
[org.jasig.cas.web.flow.AuthenticationViaFormAction] - <Executing validation>
2009-02-26 15:28:14,614 DEBUG
[org.jasig.cas.web.flow.AuthenticationViaFormAction] - <Invoking validator
org.jasig.cas.validation.usernamepasswordcredentialsvalida...@20272fec>
2009-02-26 15:28:14,614 DEBUG
[org.jasig.cas.web.flow.AuthenticationViaFormAction] - <Validation completed
for form object>
2009-02-26 15:28:14,614 DEBUG
[org.jasig.cas.web.flow.AuthenticationViaFormAction] - <There are [0] errors,
details: []>
2009-02-26 15:28:14,614 DEBUG
[org.jasig.cas.web.flow.AuthenticationViaFormAction] - <Putting form errors
instance in scope Flash>
2009-02-26 15:28:14,614 DEBUG
[org.jasig.cas.web.flow.AuthenticationViaFormAction] - <Action
'AuthenticationViaFormAction' completed execution; result is 'success'>
2009-02-26 15:28:14,614 DEBUG
[org.jasig.cas.web.flow.AuthenticationViaFormAction] - <Action
'AuthenticationViaFormAction' beginning execution>
2009-02-26 15:28:14,614 DEBUG
[org.jasig.cas.web.flow.AuthenticationViaFormAction] - <Found existing form
object with name 'credentials' of type [class
org.jasig.cas.authentication.principal.UsernamePasswordCredentials] in scope
Flow>
2009-02-26 15:28:14,614 DEBUG [org.jasig.cas.CentralAuthenticationServiceImpl]
- <Attempting to create TicketGrantingTicket for [username: a1066345]>
2009-02-26 15:28:14,630 INFO
[org.jasig.cas.authentication.AuthenticationManagerImpl] -
<AuthenticationHandler:
org.jasig.cas.adaptors.ldap.BindLdapAuthenticationHandler successfully
authenticated the user which provided the following credentials: [username:
a1066345]>
2009-02-26 15:28:14,630 DEBUG
[org.jasig.cas.authentication.principal.UsernamePasswordCredentialsToPrincipalResolver]
- <Attempting to resolve a principal...>
2009-02-26 15:28:14,630 DEBUG
[org.jasig.cas.authentication.principal.UsernamePasswordCredentialsToPrincipalResolver]
- <Creating SimplePrincipal for [a1066345]>
2009-02-26 15:28:14,631 DEBUG
[org.jasig.cas.ticket.registry.JBossCacheTicketRegistry] - <Adding ticket to
registry for: TGT-5-PiPjZMVFtWjMaafZcWqfUH5yQ2GALaryVKij7bDsFAW44aLmJv-cas>
2009-02-26 15:28:14,635 DEBUG
[org.jasig.cas.web.support.CookieRetrievingCookieGenerator] - <Removed cookie
with name [CASPRIVACY]>
2009-02-26 15:28:14,635 DEBUG
[org.jasig.cas.web.flow.AuthenticationViaFormAction] - <Action
'AuthenticationViaFormAction' completed execution; result is 'success'>
2009-02-26 15:28:14,635 DEBUG
[org.jasig.cas.web.flow.SendTicketGrantingTicketAction] - <Action
'SendTicketGrantingTicketAction' beginning execution>
2009-02-26 15:28:14,635 DEBUG
[org.jasig.cas.web.support.CookieRetrievingCookieGenerator] - <Added cookie
with name [CASTGC] and value
[TGT-5-PiPjZMVFtWjMaafZcWqfUH5yQ2GALaryVKij7bDsFAW44aLmJv-cas]>
2009-02-26 15:28:14,635 DEBUG
[org.jasig.cas.web.flow.SendTicketGrantingTicketAction] - <Action
'SendTicketGrantingTicketAction' completed execution; result is 'success'>
2009-02-26 15:28:14,635 DEBUG
[org.jasig.cas.web.flow.GenerateServiceTicketAction] - <Action
'GenerateServiceTicketAction' beginning execution>
2009-02-26 15:28:14,636 DEBUG
[org.jasig.cas.ticket.registry.JBossCacheTicketRegistry] - <Retrieving ticket
from registry for: TGT-5-PiPjZMVFtWjMaafZcWqfUH5yQ2GALaryVKij7bDsFAW44aLmJv-cas>
2009-02-26 15:28:14,641 DEBUG
[org.jasig.cas.ticket.registry.JBossCacheTicketRegistry] - <Adding ticket to
registry for: ST-6-6qeWugVpccH4kWZ9iPtG-cas>
2009-02-26 15:28:14,645 INFO [org.jasig.cas.CentralAuthenticationServiceImpl] -
<Granted service ticket [ST-6-6qeWugVpccH4kWZ9iPtG-cas] for service
[http://itsbst067.its.adelaide.edu.au:3002/] for user [a1066345]>
2009-02-26 15:28:14,645 DEBUG
[org.jasig.cas.web.flow.GenerateServiceTicketAction] - <Action
'GenerateServiceTicketAction' completed execution; result is 'success'>
2009-02-26 15:28:15,185 DEBUG [org.jasig.cas.web.support.CasArgumentExtractor]
- <Extractor generated service for: http://itsbst067.its.adelaide.edu.au:3002/>
2009-02-26 15:28:15,185 DEBUG
[org.jasig.cas.ticket.registry.JBossCacheTicketRegistry] - <Retrieving ticket
from registry for: ST-6-6qeWugVpccH4kWZ9iPtG-cas>
2009-02-26 15:28:15,194 DEBUG
[org.jasig.cas.ticket.registry.JBossCacheTicketRegistry] - <Removing ticket
from registry for: ST-6-6qeWugVpccH4kWZ9iPtG-cas>
2009-02-26 15:29:06,015 DEBUG [org.jasig.cas.web.flow.InitialFlowSetupAction] -
<Action 'InitialFlowSetupAction' beginning execution>
2009-02-26 15:29:06,015 DEBUG [org.jasig.cas.web.support.CasArgumentExtractor]
- <Extractor did not generate service.>
2009-02-26 15:29:06,015 DEBUG [org.jasig.cas.web.support.SamlArgumentExtractor]
- <Extractor did not generate service.>
2009-02-26 15:29:06,018 DEBUG
[org.jasig.cas.web.support.GoogleAccountsArgumentExtractor] - <Extractor
generated service for: https://www.google.com/a/stuuat.adelaide.edu.au/acs>
2009-02-26 15:29:06,019 DEBUG [org.jasig.cas.web.flow.InitialFlowSetupAction] -
<Placing service in FlowScope:
https://www.google.com/a/stuuat.adelaide.edu.au/acs>
2009-02-26 15:29:06,019 DEBUG [org.jasig.cas.web.flow.InitialFlowSetupAction] -
<Action 'InitialFlowSetupAction' completed execution; result is 'success'>
2009-02-26 15:29:06,020 DEBUG
[org.jasig.cas.web.flow.GenerateServiceTicketAction] - <Action
'GenerateServiceTicketAction' beginning execution>
2009-02-26 15:29:06,020 DEBUG
[org.jasig.cas.ticket.registry.JBossCacheTicketRegistry] - <Retrieving ticket
from registry for: TGT-5-PiPjZMVFtWjMaafZcWqfUH5yQ2GALaryVKij7bDsFAW44aLmJv-cas>
2009-02-26 15:29:06,028 DEBUG
[org.jasig.cas.ticket.registry.JBossCacheTicketRegistry] - <Adding ticket to
registry for: ST-7-XV2un3Hjk5I7rWikahWw-cas>
2009-02-26 15:29:06,034 INFO [org.jasig.cas.CentralAuthenticationServiceImpl] -
<Granted service ticket [ST-7-XV2un3Hjk5I7rWikahWw-cas] for service
[https://www.google.com/a/stuuat.adelaide.edu.au/acs] for user [a1066345]>
2009-02-26 15:29:06,034 DEBUG
[org.jasig.cas.web.flow.GenerateServiceTicketAction] - <Action
'GenerateServiceTicketAction' completed execution; result is 'success'>
Failed pass through authentication for second login
2009-02-26 15:23:58,779 DEBUG [org.jasig.cas.web.flow.InitialFlowSetupAction] -
<Action 'InitialFlowSetupAction' beginning execution>
2009-02-26 15:23:58,780 DEBUG [org.jasig.cas.web.support.CasArgumentExtractor]
- <Extractor generated service for: https://apps-int.adelaide.edu.au/redmine/>
2009-02-26 15:23:58,780 DEBUG [org.jasig.cas.web.flow.InitialFlowSetupAction] -
<Placing service in FlowScope: https://apps-int.adelaide.edu.au/redmine/>
2009-02-26 15:23:58,780 DEBUG [org.jasig.cas.web.flow.InitialFlowSetupAction] -
<Action 'InitialFlowSetupAction' completed execution; result is 'success'>
2009-02-26 15:23:58,780 DEBUG
[org.jasig.cas.web.flow.AuthenticationViaFormAction] - <Action
'AuthenticationViaFormAction' beginning execution>
2009-02-26 15:23:58,780 DEBUG
[org.jasig.cas.web.flow.AuthenticationViaFormAction] - <Executing setupForm>
2009-02-26 15:23:58,780 DEBUG
[org.jasig.cas.web.flow.AuthenticationViaFormAction] - <Creating new form
object with name 'credentials'>
2009-02-26 15:23:58,780 DEBUG
[org.jasig.cas.web.flow.AuthenticationViaFormAction] - <Creating new instance
of form object class [class
org.jasig.cas.authentication.principal.UsernamePasswordCredentials]>
2009-02-26 15:23:58,781 DEBUG
[org.jasig.cas.web.flow.AuthenticationViaFormAction] - <Putting form object of
type [class org.jasig.cas.authentication.principal.UsernamePasswordCredentials]
in scope Flow with name 'credentials'>
2009-02-26 15:23:58,781 DEBUG
[org.jasig.cas.web.flow.AuthenticationViaFormAction] - <Creating new form
errors for object with name 'credentials'>
2009-02-26 15:23:58,781 DEBUG
[org.jasig.cas.web.flow.AuthenticationViaFormAction] - <No property editor
registrar set, no custom editors to register>
2009-02-26 15:23:58,781 DEBUG
[org.jasig.cas.web.flow.AuthenticationViaFormAction] - <Putting form errors
instance in scope Flash>
2009-02-26 15:23:58,781 DEBUG
[org.jasig.cas.web.flow.AuthenticationViaFormAction] - <Action
'AuthenticationViaFormAction' completed execution; result is 'success'>
2009-02-26 15:23:58,781 DEBUG
[org.jasig.cas.web.flow.AuthenticationViaFormAction] - <Action
'AuthenticationViaFormAction' beginning execution>
2009-02-26 15:23:58,781 DEBUG
[org.jasig.cas.web.flow.AuthenticationViaFormAction] - <Action
'AuthenticationViaFormAction' completed execution; result is 'success'>
2009-02-26 15:24:16,808 DEBUG
[org.jasig.cas.web.flow.AuthenticationViaFormAction] - <Action
'AuthenticationViaFormAction' beginning execution>
2009-02-26 15:24:16,808 DEBUG
[org.jasig.cas.web.flow.AuthenticationViaFormAction] - <Executing bind>
2009-02-26 15:24:16,808 DEBUG
[org.jasig.cas.web.flow.AuthenticationViaFormAction] - <Found existing form
object with name 'credentials' of type [class
org.jasig.cas.authentication.principal.UsernamePasswordCredentials] in scope
Flow>
2009-02-26 15:24:16,808 DEBUG
[org.jasig.cas.web.flow.AuthenticationViaFormAction] - <No property editor
registrar set, no custom editors to register>
2009-02-26 15:24:16,808 DEBUG
[org.jasig.cas.web.flow.AuthenticationViaFormAction] - <Binding allowed request
parameters in map['lt' ->
'_cDF5EA4CC-0F5B-215F-235F-CFC315BF144B_kD496481F-6BB2-8F8F-1AD5-DFB425F250B7',
'service' -> 'https://apps-int.adelaide.edu.au/redmine/', '_eventId' ->
'submit', 'password' -> '', 'submit' -> 'LOGIN', 'username' -> 'a1066345'] to
form object with name 'credentials', pre-bind formObject toString = [username:
null]>
2009-02-26 15:24:16,808 DEBUG
[org.jasig.cas.web.flow.AuthenticationViaFormAction] - <(Any field is allowed)>
2009-02-26 15:24:16,808 DEBUG
[org.jasig.cas.web.flow.AuthenticationViaFormAction] - <Binding completed for
form object with name 'credentials', post-bind formObject toString = [username:
a1066345]>
2009-02-26 15:24:16,808 DEBUG
[org.jasig.cas.web.flow.AuthenticationViaFormAction] - <There are [0] errors,
details: []>
2009-02-26 15:24:16,808 DEBUG
[org.jasig.cas.web.flow.AuthenticationViaFormAction] - <Executing validation>
2009-02-26 15:24:16,809 DEBUG
[org.jasig.cas.web.flow.AuthenticationViaFormAction] - <Invoking validator
org.jasig.cas.validation.usernamepasswordcredentialsvalida...@20272fec>
2009-02-26 15:24:16,809 DEBUG
[org.jasig.cas.web.flow.AuthenticationViaFormAction] - <Validation completed
for form object>
2009-02-26 15:24:16,809 DEBUG
[org.jasig.cas.web.flow.AuthenticationViaFormAction] - <There are [0] errors,
details: []>
2009-02-26 15:24:16,809 DEBUG
[org.jasig.cas.web.flow.AuthenticationViaFormAction] - <Putting form errors
instance in scope Flash>
2009-02-26 15:24:16,809 DEBUG
[org.jasig.cas.web.flow.AuthenticationViaFormAction] - <Action
'AuthenticationViaFormAction' completed execution; result is 'success'>
2009-02-26 15:24:16,809 DEBUG
[org.jasig.cas.web.flow.AuthenticationViaFormAction] - <Action
'AuthenticationViaFormAction' beginning execution>
2009-02-26 15:24:16,809 DEBUG
[org.jasig.cas.web.flow.AuthenticationViaFormAction] - <Found existing form
object with name 'credentials' of type [class
org.jasig.cas.authentication.principal.UsernamePasswordCredentials] in scope
Flow>
2009-02-26 15:24:16,809 DEBUG [org.jasig.cas.CentralAuthenticationServiceImpl]
- <Attempting to create TicketGrantingTicket for [username: a1066345]>
2009-02-26 15:24:16,825 INFO
[org.jasig.cas.authentication.AuthenticationManagerImpl] -
<AuthenticationHandler:
org.jasig.cas.adaptors.ldap.BindLdapAuthenticationHandler successfully
authenticated the user which provided the following credentials: [username:
a1066345]>
2009-02-26 15:24:16,825 DEBUG
[org.jasig.cas.authentication.principal.UsernamePasswordCredentialsToPrincipalResolver]
- <Attempting to resolve a principal...>
2009-02-26 15:24:16,826 DEBUG
[org.jasig.cas.authentication.principal.UsernamePasswordCredentialsToPrincipalResolver]
- <Creating SimplePrincipal for [a1066345]>
2009-02-26 15:24:16,826 DEBUG
[org.jasig.cas.ticket.registry.JBossCacheTicketRegistry] - <Adding ticket to
registry for: TGT-3-vnXQ4YzwZjwJnZ6hGMhN6ZfzKMGQnHfswLH0AferdJQquAMDK1-cas>
2009-02-26 15:24:16,831 DEBUG
[org.jasig.cas.web.support.CookieRetrievingCookieGenerator] - <Removed cookie
with name [CASPRIVACY]>
2009-02-26 15:24:16,831 DEBUG
[org.jasig.cas.web.flow.AuthenticationViaFormAction] - <Action
'AuthenticationViaFormAction' completed execution; result is 'success'>
2009-02-26 15:24:16,831 DEBUG
[org.jasig.cas.web.flow.SendTicketGrantingTicketAction] - <Action
'SendTicketGrantingTicketAction' beginning execution>
2009-02-26 15:24:16,832 DEBUG
[org.jasig.cas.web.support.CookieRetrievingCookieGenerator] - <Added cookie
with name [CASTGC] and value
[TGT-3-vnXQ4YzwZjwJnZ6hGMhN6ZfzKMGQnHfswLH0AferdJQquAMDK1-cas]>
2009-02-26 15:24:16,832 DEBUG
[org.jasig.cas.web.flow.SendTicketGrantingTicketAction] - <Action
'SendTicketGrantingTicketAction' completed execution; result is 'success'>
2009-02-26 15:24:16,832 DEBUG
[org.jasig.cas.web.flow.GenerateServiceTicketAction] - <Action
'GenerateServiceTicketAction' beginning execution>
2009-02-26 15:24:16,832 DEBUG
[org.jasig.cas.ticket.registry.JBossCacheTicketRegistry] - <Retrieving ticket
from registry for: TGT-3-vnXQ4YzwZjwJnZ6hGMhN6ZfzKMGQnHfswLH0AferdJQquAMDK1-cas>
2009-02-26 15:24:16,837 DEBUG
[org.jasig.cas.ticket.registry.JBossCacheTicketRegistry] - <Adding ticket to
registry for: ST-4-71vwXIxywnNyNRmPUZUP-cas>
2009-02-26 15:24:16,843 INFO [org.jasig.cas.CentralAuthenticationServiceImpl] -
<Granted service ticket [ST-4-71vwXIxywnNyNRmPUZUP-cas] for service
[https://apps-int.adelaide.edu.au/redmine/] for user [a1066345]>
2009-02-26 15:24:16,843 DEBUG
[org.jasig.cas.web.flow.GenerateServiceTicketAction] - <Action
'GenerateServiceTicketAction' completed execution; result is 'success'>
2009-02-26 15:24:18,066 DEBUG [org.jasig.cas.web.support.CasArgumentExtractor]
- <Extractor generated service for: https://apps-int.adelaide.edu.au/redmine/>
2009-02-26 15:24:18,066 DEBUG
[org.jasig.cas.ticket.registry.JBossCacheTicketRegistry] - <Retrieving ticket
from registry for: ST-4-71vwXIxywnNyNRmPUZUP-cas>
2009-02-26 15:24:18,074 DEBUG
[org.jasig.cas.ticket.registry.JBossCacheTicketRegistry] - <Removing ticket
from registry for: ST-4-71vwXIxywnNyNRmPUZUP-cas>
2009-02-26 15:24:28,748 DEBUG [org.jasig.cas.web.flow.InitialFlowSetupAction] -
<Action 'InitialFlowSetupAction' beginning execution>
2009-02-26 15:24:28,749 DEBUG [org.jasig.cas.web.support.CasArgumentExtractor]
- <Extractor did not generate service.>
2009-02-26 15:24:28,749 DEBUG [org.jasig.cas.web.support.SamlArgumentExtractor]
- <Extractor did not generate service.>
2009-02-26 15:24:28,753 DEBUG
[org.jasig.cas.web.support.GoogleAccountsArgumentExtractor] - <Extractor
generated service for: https://www.google.com/a/stuuat.adelaide.edu.au/acs>
2009-02-26 15:24:28,754 DEBUG [org.jasig.cas.web.flow.InitialFlowSetupAction] -
<Placing service in FlowScope:
https://www.google.com/a/stuuat.adelaide.edu.au/acs>
2009-02-26 15:24:28,754 DEBUG [org.jasig.cas.web.flow.InitialFlowSetupAction] -
<Action 'InitialFlowSetupAction' completed execution; result is 'success'>
2009-02-26 15:24:28,755 DEBUG
[org.jasig.cas.web.flow.AuthenticationViaFormAction] - <Action
'AuthenticationViaFormAction' beginning execution>
2009-02-26 15:24:28,755 DEBUG
[org.jasig.cas.web.flow.AuthenticationViaFormAction] - <Executing setupForm>
2009-02-26 15:24:28,755 DEBUG
[org.jasig.cas.web.flow.AuthenticationViaFormAction] - <Creating new form
object with name 'credentials'>
2009-02-26 15:24:28,755 DEBUG
[org.jasig.cas.web.flow.AuthenticationViaFormAction] - <Creating new instance
of form object class [class
org.jasig.cas.authentication.principal.UsernamePasswordCredentials]>
2009-02-26 15:24:28,755 DEBUG
[org.jasig.cas.web.flow.AuthenticationViaFormAction] - <Putting form object of
type [class org.jasig.cas.authentication.principal.UsernamePasswordCredentials]
in scope Flow with name 'credentials'>
2009-02-26 15:24:28,755 DEBUG
[org.jasig.cas.web.flow.AuthenticationViaFormAction] - <Creating new form
errors for object with name 'credentials'>
2009-02-26 15:24:28,755 DEBUG
[org.jasig.cas.web.flow.AuthenticationViaFormAction] - <No property editor
registrar set, no custom editors to register>
2009-02-26 15:24:28,755 DEBUG
[org.jasig.cas.web.flow.AuthenticationViaFormAction] - <Putting form errors
instance in scope Flash>
2009-02-26 15:24:28,755 DEBUG
[org.jasig.cas.web.flow.AuthenticationViaFormAction] - <Action
'AuthenticationViaFormAction' completed execution; result is 'success'>
2009-02-26 15:24:28,755 DEBUG
[org.jasig.cas.web.flow.AuthenticationViaFormAction] - <Action
'AuthenticationViaFormAction' beginning execution>
2009-02-26 15:24:28,756 DEBUG
[org.jasig.cas.web.flow.AuthenticationViaFormAction] - <Action
'AuthenticationViaFormAction' completed execution; result is 'success'>
Using CATALINA_BASE: /usr/share/tomcat5
Using CATALINA_HOME: /usr/share/tomcat5
Using CATALINA_TMPDIR: /usr/share/tomcat5/temp
Using JRE_HOME:
Feb 26, 2009 3:49:09 PM org.apache.catalina.core.AprLifecycleListener
lifecycleEvent
INFO: The Apache Tomcat Native library which allows optimal performance in
production environments was not found on the java.library.path:
/usr/lib/jvm/java-1.6.0-sun-1.6.0.7.x86_64/jre/lib/amd64/server:/usr/lib/jvm/java-1.6.0-sun-1.6.0.7.x86_64/jre/lib/amd64:/usr/lib/jvm/java-1.6.0-sun-1.6.0.7.x86_64/jre/../lib/amd64:/usr/java/packages/lib/amd64:/lib:/usr/lib
Feb 26, 2009 3:49:09 PM org.apache.coyote.http11.Http11BaseProtocol init
INFO: Initializing Coyote HTTP/1.1 on http-8080
Feb 26, 2009 3:49:09 PM org.apache.catalina.startup.Catalina load
INFO: Initialization processed in 1209 ms
Feb 26, 2009 3:49:09 PM org.apache.catalina.core.StandardService start
INFO: Starting service Catalina
Feb 26, 2009 3:49:09 PM org.apache.catalina.core.StandardEngine start
INFO: Starting Servlet Engine: Apache Tomcat/5.5.23
Feb 26, 2009 3:49:09 PM org.apache.catalina.cluster.tcp.SimpleTcpCluster start
INFO: Cluster is about to start
Feb 26, 2009 3:49:09 PM org.apache.catalina.cluster.tcp.ReplicationTransmitter
start
INFO: Start ClusterSender at cluster Catalina:type=Cluster with name
Catalina:type=ClusterSender
Feb 26, 2009 3:49:09 PM org.apache.catalina.cluster.mcast.McastServiceImpl
setupSocket
INFO: Setting cluster mcast TTL to 1
Feb 26, 2009 3:49:09 PM org.apache.catalina.cluster.mcast.McastService start
INFO: Sleeping for 2000 milliseconds to establish cluster membership
Feb 26, 2009 3:49:11 PM org.apache.catalina.cluster.mcast.McastService
registerMBean
INFO: membership mbean registered (Catalina:type=ClusterMembership)
Feb 26, 2009 3:49:11 PM org.apache.catalina.core.StandardHost start
INFO: XML validation disabled
Feb 26, 2009 3:49:12 PM org.apache.catalina.cluster.session.DeltaManager start
INFO: Register manager to cluster element Engine with name Catalina
Feb 26, 2009 3:49:12 PM org.apache.catalina.cluster.session.DeltaManager start
INFO: Starting clustering manager at
Feb 26, 2009 3:49:12 PM org.apache.catalina.cluster.session.DeltaManager
getAllClusterSessions
INFO: Manager [localhost]: skipping state transfer. No members active in
cluster group.
-------------------------------------------------------
GMS: address is 10.230.100.1:10955
-------------------------------------------------------
2009-02-26 15:49:19,593 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)]>
2009-02-26 15:49:19,594 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)]>
2009-02-26 15:49:19,695 INFO
[org.jasig.cas.web.flow.AuthenticationViaFormAction] - <FormObjectClass not
set. Using default class of
org.jasig.cas.authentication.principal.UsernamePasswordCredentials with
formObjectName credentials and validator
org.jasig.cas.validation.UsernamePasswordCredentialsValidator.>
Feb 26, 2009 3:49:19 PM org.apache.catalina.startup.HostConfig deployWAR
INFO: Deploying web application archive cas.war
Feb 26, 2009 3:49:20 PM org.apache.catalina.cluster.session.DeltaManager start
INFO: Register manager /cas to cluster element Engine with name Catalina
Feb 26, 2009 3:49:20 PM org.apache.catalina.cluster.session.DeltaManager start
INFO: Starting clustering manager at /cas
Feb 26, 2009 3:49:20 PM org.apache.catalina.cluster.session.DeltaManager
getAllClusterSessions
INFO: Manager [localhost/cas]: skipping state transfer. No members active in
cluster group.
-------------------------------------------------------
GMS: address is 10.230.100.1:26327
-------------------------------------------------------
2009-02-26 15:49:25,380 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)]>
2009-02-26 15:49:25,380 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)]>
2009-02-26 15:49:25,610 INFO
[org.jasig.cas.web.flow.AuthenticationViaFormAction] - <FormObjectClass not
set. Using default class of
org.jasig.cas.authentication.principal.UsernamePasswordCredentials with
formObjectName credentials and validator
org.jasig.cas.validation.UsernamePasswordCredentialsValidator.>
Feb 26, 2009 3:49:25 PM org.apache.coyote.http11.Http11BaseProtocol start
INFO: Starting Coyote HTTP/1.1 on http-8080
Feb 26, 2009 3:49:25 PM org.apache.jk.common.ChannelSocket init
INFO: JK: ajp13 listening on /0.0.0.0:8009
Feb 26, 2009 3:49:25 PM org.apache.jk.server.JkMain start
INFO: Jk running ID=0 time=0/92 config=null
Feb 26, 2009 3:49:25 PM org.apache.catalina.storeconfig.StoreLoader load
INFO: Find registry server-registry.xml at classpath resource
Feb 26, 2009 3:49:26 PM org.apache.catalina.startup.Catalina start
INFO: Server startup in 16494 ms
2009-02-26 15:49:38,478 INFO
[org.jasig.cas.ticket.registry.support.DefaultTicketRegistryCleaner] -
<Starting cleaning of expired tickets from ticket registry at [Thu Feb 26
15:49:38 CST 2009]>
2009-02-26 15:49:38,484 INFO
[org.jasig.cas.ticket.registry.support.DefaultTicketRegistryCleaner] - <0 found
to be removed. Removing now.>
2009-02-26 15:49:38,484 INFO
[org.jasig.cas.ticket.registry.support.DefaultTicketRegistryCleaner] -
<Finished cleaning of expired tickets from ticket registry at [Thu Feb 26
15:49:38 CST 2009]>
2009-02-26 15:49:44,412 INFO
[org.jasig.cas.ticket.registry.support.DefaultTicketRegistryCleaner] -
<Starting cleaning of expired tickets from ticket registry at [Thu Feb 26
15:49:44 CST 2009]>
2009-02-26 15:49:44,433 INFO
[org.jasig.cas.ticket.registry.support.DefaultTicketRegistryCleaner] - <0 found
to be removed. Removing now.>
2009-02-26 15:49:44,433 INFO
[org.jasig.cas.ticket.registry.support.DefaultTicketRegistryCleaner] -
<Finished cleaning of expired tickets from ticket registry at [Thu Feb 26
15:49:44 CST 2009]>
