=============================================================
WHO: audit:unknown
WHAT: [event=success,timestamp=Mon May 07 18:00:25 CEST 2018,source=RankedAuthenticationProviderWebflowEventResolver]
ACTION: AUTHENTICATION_EVENT_TRIGGERED
APPLICATION: CAS
WHEN: Mon May 07 18:00:25 CEST 2018
CLIENT IP ADDRESS: 10.35.12.194
SERVER IP ADDRESS: 10.35.14.239
=============================================================

>
2018-05-07 18:00:25,940 DEBUG [org.apereo.cas.services.web.ChainingThemeResolver] - <Attempting to resolve theme via [CookieThemeResolver]>
2018-05-07 18:00:25,940 DEBUG [org.apereo.cas.services.web.ChainingThemeResolver] - <Attempting to resolve theme via [SessionThemeResolver]>
2018-05-07 18:00:25,941 DEBUG [org.apereo.cas.services.web.ChainingThemeResolver] - <Attempting to resolve theme via [RequestHeaderThemeResolver]>
2018-05-07 18:00:25,941 DEBUG [org.apereo.cas.services.web.ChainingThemeResolver] - <Attempting to resolve theme via [ServiceThemeResolver]>
2018-05-07 18:00:25,943 DEBUG [org.apereo.cas.services.web.ServiceThemeResolver] - <No service is found in the request context. Falling back to the default theme [cas-theme-default]>
2018-05-07 18:00:25,944 DEBUG [org.apereo.cas.services.web.ChainingThemeResolver] - <Attempting to resolve theme via [FixedThemeResolver]>
2018-05-07 18:00:25,945 DEBUG [org.apereo.cas.services.web.ChainingThemeResolver] - <No specific theme could be found. Using default theme [cas-theme-default}>
2018-05-07 18:00:29,112 DEBUG [org.apereo.cas.web.view.CasReloadableMessageBundle] - <No properties file found for [classpath:custom_messages_en] - neither plain properties nor XML>
2018-05-07 18:00:29,116 DEBUG [org.apereo.cas.web.view.CasReloadableMessageBundle] - <No properties file found for [classpath:custom_messages] - neither plain properties nor XML>
2018-05-07 18:00:29,118 DEBUG [org.apereo.cas.web.view.CasReloadableMessageBundle] - <No properties file found for [classpath:messages_en] - neither plain properties nor XML>
2018-05-07 18:00:29,121 DEBUG [org.apereo.cas.web.view.CasReloadableMessageBundle] - <Loading properties [messages.properties] with encoding 'UTF-8'>
2018-05-07 18:00:29,293 DEBUG [org.apereo.cas.services.web.ChainingThemeResolver] - <Attempting to resolve theme via [CookieThemeResolver]>
2018-05-07 18:00:29,294 DEBUG [org.apereo.cas.services.web.ChainingThemeResolver] - <Attempting to resolve theme via [SessionThemeResolver]>
2018-05-07 18:00:29,295 DEBUG [org.apereo.cas.services.web.ChainingThemeResolver] - <Attempting to resolve theme via [RequestHeaderThemeResolver]>
2018-05-07 18:00:29,295 DEBUG [org.apereo.cas.services.web.ChainingThemeResolver] - <Attempting to resolve theme via [ServiceThemeResolver]>
2018-05-07 18:00:29,297 DEBUG [org.apereo.cas.services.web.ServiceThemeResolver] - <No service is found in the request context. Falling back to the default theme [cas-theme-default]>
2018-05-07 18:00:29,298 DEBUG [org.apereo.cas.services.web.ChainingThemeResolver] - <Attempting to resolve theme via [FixedThemeResolver]>
2018-05-07 18:00:29,298 DEBUG [org.apereo.cas.services.web.ChainingThemeResolver] - <No specific theme could be found. Using default theme [cas-theme-default}>
2018-05-07 18:00:38,397 DEBUG [org.apereo.cas.services.web.ChainingThemeResolver] - <Attempting to resolve theme via [CookieThemeResolver]>
2018-05-07 18:00:38,398 DEBUG [org.apereo.cas.services.web.ChainingThemeResolver] - <Attempting to resolve theme via [SessionThemeResolver]>
2018-05-07 18:00:38,399 DEBUG [org.apereo.cas.services.web.ChainingThemeResolver] - <Attempting to resolve theme via [RequestHeaderThemeResolver]>
2018-05-07 18:00:38,399 DEBUG [org.apereo.cas.services.web.ChainingThemeResolver] - <Attempting to resolve theme via [ServiceThemeResolver]>
2018-05-07 18:00:38,400 DEBUG [org.apereo.cas.services.web.ServiceThemeResolver] - <No service is found in the request context. Falling back to the default theme [cas-theme-default]>
2018-05-07 18:00:38,400 DEBUG [org.apereo.cas.services.web.ChainingThemeResolver] - <Attempting to resolve theme via [FixedThemeResolver]>
2018-05-07 18:00:38,401 DEBUG [org.apereo.cas.services.web.ChainingThemeResolver] - <No specific theme could be found. Using default theme [cas-theme-default}>
2018-05-07 18:00:38,456 DEBUG [org.apereo.cas.authentication.adaptive.DefaultAdaptiveAuthenticationPolicy] - <Located client IP address as [10.35.12.194]>
2018-05-07 18:00:38,456 DEBUG [org.apereo.cas.authentication.adaptive.DefaultAdaptiveAuthenticationPolicy] - <User agent [Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Geck
o) Chrome/66.0.3359.139 Safari/537.36] is authorized to proceed>
2018-05-07 18:00:38,457 DEBUG [org.apereo.cas.authentication.adaptive.DefaultAdaptiveAuthenticationPolicy] - <Adaptive authentication policy has authorized client [10.35.12.194] to proceed.>
2018-05-07 18:00:38,459 DEBUG [org.apereo.cas.web.support.WebUtils] - <Evaluating request to determine if warning cookie should be generated>
2018-05-07 18:00:38,460 DEBUG [org.apereo.cas.web.support.WebUtils] - <Evaluating request to determine if warning cookie should be generated>
2018-05-07 18:00:38,501 DEBUG [org.apereo.cas.authentication.RegisteredServiceAuthenticationHandlerResolver] - <Authentication handlers used for this transaction are [LdapAuthenticationHandler,HttpBas
edServiceCredentialsAuthenticationHandler,ClientAuthenticationHandler,OpenIdCredentialsAuthenticationHandler]>
2018-05-07 18:00:38,510 DEBUG [org.apereo.cas.authentication.handler.support.AbstractUsernamePasswordAuthenticationHandler] - <Examining credential [jhallett] eligibility for authentication handler [L
dapAuthenticationHandler]>
2018-05-07 18:00:38,511 DEBUG [org.apereo.cas.authentication.handler.support.AbstractUsernamePasswordAuthenticationHandler] - <Credential [jhallett] eligibility is [LdapAuthenticationHandler] for auth
entication handler [true]>
2018-05-07 18:00:38,513 DEBUG [org.apereo.cas.authentication.handler.support.AbstractUsernamePasswordAuthenticationHandler] - <Transforming credential username via [org.apereo.cas.util.transforms.Chai
ningPrincipalNameTransformer]>
2018-05-07 18:00:38,514 DEBUG [org.apereo.cas.authentication.handler.support.AbstractUsernamePasswordAuthenticationHandler] - <Attempting to encode credential password via [org.springframework.securit
y.crypto.password.NoOpPasswordEncoder] for [jhallett]>
2018-05-07 18:00:38,515 DEBUG [org.apereo.cas.authentication.handler.support.AbstractUsernamePasswordAuthenticationHandler] - <Attempting authentication internally for transformed credential [jhallett
]>
2018-05-07 18:00:38,517 DEBUG [org.apereo.cas.authentication.LdapAuthenticationHandler] - <Attempting LDAP authentication for [jhallett]. Authenticator pre-configured attributes are [null], additional
 requested attributes for this authentication request are [[]]>
2018-05-07 18:00:38,619 DEBUG [org.apereo.cas.authentication.LdapAuthenticationHandler] - <LDAP response: [[org.ldaptive.auth.AuthenticationResponse@1009748495::authenticationResultCode=AUTHENTICATION
_HANDLER_SUCCESS, resolvedDn=cn=John Hallett,ou=people,o=sevenSeas, ldapEntry=[dn=cn=John Hallett,ou=people,o=sevenSeas[[uid[jhallett]], [manager[cn=William Bligh,ou=people,o=sevenSeas]], [mail[jhalle
tt@royalnavy.mod.uk]], [givenname[John]], [description[Midshipman John Hallett]], [sn[Hallett]], [cn[John Hallett]], [objectclass[top, person, organizationalPerson, inetOrgPerson]], [userpassword[e1NI
QX1uVTRlSTcxYmNuQkdxZU8wdDl0WHZZMXU1b1E9]]], responseControls=null, messageId=-1], accountState=null, result=true, resultCode=SUCCESS, message=null, controls=[[org.ldaptive.control.PasswordPolicyContr
ol@-350057371::criticality=false, timeBeforeExpiration=0, graceAuthNsRemaining=0, error=null]]]]>
2018-05-07 18:00:38,623 DEBUG [org.apereo.cas.authentication.support.LdapPasswordPolicyHandlingStrategy] - <Authentication response [DefaultLdapPasswordPolicyHandlingStrategy] is supported by password
 policy handling strategy [{}]>
2018-05-07 18:00:38,624 DEBUG [org.apereo.cas.authentication.LdapAuthenticationHandler] - <Attempting to examine and handle LDAP password policy via [DefaultLdapPasswordPolicyHandlingStrategy]>
2018-05-07 18:00:38,626 DEBUG [org.apereo.cas.authentication.support.DefaultLdapPasswordPolicyHandlingStrategy] - <Applying password policy [[org.ldaptive.auth.AuthenticationResponse@1009748495::authe
nticationResultCode=AUTHENTICATION_HANDLER_SUCCESS, resolvedDn=cn=John Hallett,ou=people,o=sevenSeas, ldapEntry=[dn=cn=John Hallett,ou=people,o=sevenSeas[[uid[jhallett]], [manager[cn=William Bligh,ou=
people,o=sevenSeas]], [mail[jhallett@royalnavy.mod.uk]], [givenname[John]], [description[Midshipman John Hallett]], [sn[Hallett]], [cn[John Hallett]], [objectclass[top, person, organizationalPerson, i
netOrgPerson]], [userpassword[e1NIQX1uVTRlSTcxYmNuQkdxZU8wdDl0WHZZMXU1b1E9]]], responseControls=null, messageId=-1], accountState=null, result=true, resultCode=SUCCESS, message=null, controls=[[org.ld
aptive.control.PasswordPolicyControl@-350057371::criticality=false, timeBeforeExpiration=0, graceAuthNsRemaining=0, error=null]]]] to [org.apereo.cas.authentication.support.DefaultAccountStateHandler@
5b882dce]>
2018-05-07 18:00:38,628 DEBUG [org.apereo.cas.authentication.support.DefaultAccountStateHandler] - <Attempting to handle LDAP account state for [[org.ldaptive.auth.AuthenticationResponse@1009748495::a
uthenticationResultCode=AUTHENTICATION_HANDLER_SUCCESS, resolvedDn=cn=John Hallett,ou=people,o=sevenSeas, ldapEntry=[dn=cn=John Hallett,ou=people,o=sevenSeas[[uid[jhallett]], [manager[cn=William Bligh
,ou=people,o=sevenSeas]], [mail[jhallett@royalnavy.mod.uk]], [givenname[John]], [description[Midshipman John Hallett]], [sn[Hallett]], [cn[John Hallett]], [objectclass[top, person, organizationalPerso
n, inetOrgPerson]], [userpassword[e1NIQX1uVTRlSTcxYmNuQkdxZU8wdDl0WHZZMXU1b1E9]]], responseControls=null, messageId=-1], accountState=null, result=true, resultCode=SUCCESS, message=null, controls=[[or
g.ldaptive.control.PasswordPolicyControl@-350057371::criticality=false, timeBeforeExpiration=0, graceAuthNsRemaining=0, error=null]]]]>
2018-05-07 18:00:38,630 DEBUG [org.apereo.cas.authentication.support.DefaultAccountStateHandler] - <Account state not defined. Returning empty list of messages.>
2018-05-07 18:00:38,631 DEBUG [org.apereo.cas.authentication.LdapAuthenticationHandler] - <LDAP response returned a result. Creating the final LDAP principal>
2018-05-07 18:00:38,635 DEBUG [org.apereo.cas.authentication.LdapAuthenticationHandler] - <Creating LDAP principal for [jhallett] based on [cn=John Hallett,ou=people,o=sevenSeas] and attributes [[uid,
 manager, mail, givenname, description, sn, cn, objectclass, userpassword]]>
2018-05-07 18:00:38,635 DEBUG [org.apereo.cas.authentication.LdapAuthenticationHandler] - <Principal id attribute is not defined. Using the default provided user id [jhallett]>
2018-05-07 18:00:38,635 DEBUG [org.apereo.cas.authentication.LdapAuthenticationHandler] - <LDAP principal identifier created is [jhallett]>
2018-05-07 18:00:38,635 DEBUG [org.apereo.cas.authentication.LdapAuthenticationHandler] - <The following attributes are requested to be retrieved and mapped: [[]]>
2018-05-07 18:00:38,641 DEBUG [org.apereo.cas.authentication.LdapAuthenticationHandler] - <Created LDAP principal for id [jhallett] and [0] attributes>
2018-05-07 18:00:38,646 DEBUG [org.apereo.cas.authentication.PolicyBasedAuthenticationManager] - <Authentication handler [LdapAuthenticationHandler] successfully authenticated [jhallett]>
2018-05-07 18:00:38,650 DEBUG [org.apereo.cas.authentication.principal.resolvers.ChainingPrincipalResolver] - <Invoking principal resolver [org.apereo.cas.authentication.principal.resolvers.EchoingPri
ncipalResolver@2589b9a1[]]>
2018-05-07 18:00:38,651 DEBUG [org.apereo.cas.authentication.principal.resolvers.ChainingPrincipalResolver] - <Resolved principal [jhallett]>
2018-05-07 18:00:38,656 DEBUG [org.apereo.cas.authentication.principal.resolvers.ChainingPrincipalResolver] - <Final principal constructed by the chain of resolvers is [jhallett]>
2018-05-07 18:00:38,657 DEBUG [org.apereo.cas.authentication.PolicyBasedAuthenticationManager] - <[org.apereo.cas.authentication.principal.resolvers.ChainingPrincipalResolver@3c22c9a[chain=[org.apereo
.cas.authentication.principal.resolvers.EchoingPrincipalResolver@2589b9a1[]]]] resolved [jhallett] from [jhallett]>
2018-05-07 18:00:38,658 DEBUG [org.apereo.cas.authentication.PolicyBasedAuthenticationManager] - <Final principal resolved for this authentication event is [jhallett]>
2018-05-07 18:00:38,665 DEBUG [org.apereo.cas.authentication.PolicyBasedAuthenticationManager] - <Executing authentication policy [AnyAuthenticationPolicy]>
2018-05-07 18:00:38,667 DEBUG [org.apereo.cas.authentication.policy.AnyAuthenticationPolicy] - <Authentication policy is satisfied having found at least one authentication transactions>
2018-05-07 18:00:38,669 DEBUG [org.apereo.cas.authentication.PolicyBasedAuthenticationManager] - <Invoking authentication metadata populators for authentication transaction>
2018-05-07 18:00:38,671 DEBUG [org.apereo.cas.authentication.DefaultAuthenticationEventExecutionPlan] - <Sorted and registered metadata populators for this transaction are [[org.apereo.cas.authenticat
ion.metadata.SuccessfulHandlerMetaDataPopulator@78e6a2c5[order=-2147483648], org.apereo.cas.authentication.metadata.RememberMeAuthenticationMetaDataPopulator@7221e9e7[order=-2147483648], org.apereo.ca
s.authentication.metadata.AuthenticationCredentialTypeMetaDataPopulator@617df1a3[order=-2147483648], org.apereo.cas.support.pac4j.authentication.ClientAuthenticationMetaDataPopulator@4fb4b9f3[order=-2
147483648]]]>
2018-05-07 18:00:38,679 DEBUG [org.apereo.cas.authentication.PolicyBasedAuthenticationManager] - <Invoking authentication post processors for authentication transaction>
2018-05-07 18:00:38,681 DEBUG [org.apereo.cas.authentication.DefaultAuthenticationEventExecutionPlan] - <Sorted and registered authentication post processors for this transaction are [[]]>
2018-05-07 18:00:38,683 INFO [org.apereo.cas.authentication.PolicyBasedAuthenticationManager] - <Authenticated principal [jhallett] with attributes [{}] via credentials [[jhallett]].>
2018-05-07 18:00:38,689 INFO [org.apereo.inspektr.audit.support.Slf4jLoggingAuditTrailManager] - <Audit trail record BEGIN
=============================================================
WHO: jhallett
WHAT: Supplied credentials: [jhallett]
ACTION: AUTHENTICATION_SUCCESS
APPLICATION: CAS
WHEN: Mon May 07 18:00:38 CEST 2018
CLIENT IP ADDRESS: 10.35.12.194
SERVER IP ADDRESS: 10.35.14.239
=============================================================

>
2018-05-07 18:00:38,689 DEBUG [org.apereo.cas.authentication.DefaultAuthenticationTransactionManager] - <Successful authentication; Collecting authentication result [org.apereo.cas.authentication.Defa
ultAuthentication@883d70e2]>
2018-05-07 18:00:38,705 DEBUG [org.apereo.cas.web.support.WebUtils] - <Evaluating request to determine if warning cookie should be generated>
2018-05-07 18:00:38,748 DEBUG [org.apereo.cas.web.support.WebUtils] - <Evaluating request to determine if warning cookie should be generated>
2018-05-07 18:00:38,766 DEBUG [org.apereo.cas.web.support.WebUtils] - <Evaluating request to determine if warning cookie should be generated>
2018-05-07 18:00:38,782 DEBUG [org.apereo.cas.web.support.WebUtils] - <Evaluating request to determine if warning cookie should be generated>
2018-05-07 18:00:38,803 DEBUG [org.apereo.cas.web.support.WebUtils] - <Evaluating request to determine if warning cookie should be generated>
2018-05-07 18:00:38,803 DEBUG [org.apereo.cas.web.support.WebUtils] - <Evaluating request to determine if warning cookie should be generated>
2018-05-07 18:00:38,819 DEBUG [org.apereo.cas.web.support.WebUtils] - <Evaluating request to determine if warning cookie should be generated>
2018-05-07 18:00:38,851 DEBUG [org.apereo.cas.web.support.WebUtils] - <Evaluating request to determine if warning cookie should be generated>
2018-05-07 18:00:38,867 DEBUG [org.apereo.cas.web.support.WebUtils] - <Evaluating request to determine if warning cookie should be generated>
2018-05-07 18:00:38,886 DEBUG [org.apereo.cas.web.support.WebUtils] - <Evaluating request to determine if warning cookie should be generated>
2018-05-07 18:00:38,903 DEBUG [org.apereo.cas.web.support.WebUtils] - <Evaluating request to determine if warning cookie should be generated>
2018-05-07 18:00:38,905 DEBUG [org.apereo.cas.authentication.DefaultAuthenticationResultBuilder] - <Collecting authentication history based on [1] authentication events>
2018-05-07 18:00:38,907 DEBUG [org.apereo.cas.authentication.DefaultAuthenticationResultBuilder] - <Evaluating authentication principal [jhallett] for inclusion in result>
2018-05-07 18:00:38,907 DEBUG [org.apereo.cas.authentication.DefaultAuthenticationResultBuilder] - <Collected principal attributes [{}] for inclusion in this result for principal [jhallett]>
2018-05-07 18:00:38,908 DEBUG [org.apereo.cas.authentication.DefaultAuthenticationResultBuilder] - <Collected single authentication attribute [credentialType] -> [UsernamePasswordCredential]>
2018-05-07 18:00:38,909 DEBUG [org.apereo.cas.authentication.DefaultAuthenticationResultBuilder] - <Collected single authentication attribute [authenticationMethod] -> [LdapAuthenticationHandler]>
2018-05-07 18:00:38,909 DEBUG [org.apereo.cas.authentication.DefaultAuthenticationResultBuilder] - <Collected single authentication attribute [successfulAuthenticationHandlers] -> [[LdapAuthentication
Handler]]>
2018-05-07 18:00:38,910 DEBUG [org.apereo.cas.authentication.DefaultAuthenticationResultBuilder] - <Finalized authentication attributes [{credentialType=UsernamePasswordCredential, authenticationMetho
d=LdapAuthenticationHandler, successfulAuthenticationHandlers=[LdapAuthenticationHandler]}] for inclusion in this authentication result>
2018-05-07 18:00:38,913 DEBUG [org.apereo.cas.authentication.DefaultPrincipalElectionStrategy] - <Nominated [jhallett] as the primary principal>
2018-05-07 18:00:38,913 DEBUG [org.apereo.cas.authentication.DefaultAuthenticationResultBuilder] - <Determined primary authentication principal to be [jhallett]>
2018-05-07 18:00:38,914 DEBUG [org.apereo.cas.authentication.DefaultAuthenticationResultBuilder] - <Collected authentication attributes for this result are [{credentialType=UsernamePasswordCredential,
 authenticationMethod=LdapAuthenticationHandler, successfulAuthenticationHandlers=[LdapAuthenticationHandler]}]>
2018-05-07 18:00:38,915 DEBUG [org.apereo.cas.authentication.DefaultAuthenticationResultBuilder] - <Authentication result commenced at [2018-05-07T18:00:38.915+02:00[Europe/Berlin]]>
2018-05-07 18:00:38,916 DEBUG [org.apereo.cas.authentication.DefaultAuthenticationResultBuilder] - <Building an authentication result for authentication [org.apereo.cas.authentication.DefaultAuthentic
ation@4f049762] and service [null]>
2018-05-07 18:00:38,919 DEBUG [org.apereo.cas.authentication.PseudoPlatformTransactionManager] - <Creating new transaction with name [org.apereo.cas.DefaultCentralAuthenticationService.createTicketGra
ntingTicket]: PROPAGATION_REQUIRED,ISOLATION_DEFAULT; 'ticketTransactionManager'>
2018-05-07 18:00:38,920 DEBUG [org.apereo.cas.authentication.PseudoPlatformTransactionManager] - <Creating new transaction with name [org.apereo.cas.DefaultCentralAuthenticationService.createTicketGra
ntingTicket]: PROPAGATION_REQUIRED,ISOLATION_DEFAULT; 'ticketTransactionManager'>
2018-05-07 18:00:38,938 DEBUG [org.apereo.cas.ticket.factory.DefaultTicketGrantingTicketFactory] - <Attempting to encode ticket-granting ticket [TGT-******j7wEAriGdJ-LYIq0p2MAuf93dT5vKkyp9j_4DeHGPp8Nt
CaN-QDXn341TObxZSpfZrA-idiv-dev1]>
2018-05-07 18:00:38,938 DEBUG [org.apereo.cas.ticket.factory.DefaultTicketGrantingTicketFactory] - <Encoded ticket-granting ticket id [TGT-******j7wEAriGdJ-LYIq0p2MAuf93dT5vKkyp9j_4DeHGPp8NtCaN-QDXn34
1TObxZSpfZrA-idiv-dev1]>
2018-05-07 18:00:38,940 DEBUG [org.apereo.cas.ticket.registry.AbstractMapBasedTicketRegistry] - <Added ticket [TGT-******j7wEAriGdJ-LYIq0p2MAuf93dT5vKkyp9j_4DeHGPp8NtCaN-QDXn341TObxZSpfZrA-idiv-dev1]
to registry.>
2018-05-07 18:00:38,941 DEBUG [org.apereo.cas.AbstractCentralAuthenticationService] - <Publishing [org.apereo.cas.support.events.ticket.CasTicketGrantingTicketCreatedEvent@4cd0fe43[ticketGrantingTicke
t=TGT-******j7wEAriGdJ-LYIq0p2MAuf93dT5vKkyp9j_4DeHGPp8NtCaN-QDXn341TObxZSpfZrA-idiv-dev1]]>
2018-05-07 18:00:38,944 INFO [org.apereo.inspektr.audit.support.Slf4jLoggingAuditTrailManager] - <Audit trail record BEGIN
=============================================================
WHO: jhallett
WHAT: TGT-******j7wEAriGdJ-LYIq0p2MAuf93dT5vKkyp9j_4DeHGPp8NtCaN-QDXn341TObxZSpfZrA-idiv-dev1
ACTION: TICKET_GRANTING_TICKET_CREATED
APPLICATION: CAS
WHEN: Mon May 07 18:00:38 CEST 2018
CLIENT IP ADDRESS: 10.35.12.194
SERVER IP ADDRESS: 10.35.14.239
=============================================================

>
2018-05-07 18:00:38,946 DEBUG [org.apereo.cas.authentication.PseudoPlatformTransactionManager] - <Initiating transaction commit>
2018-05-07 18:00:38,949 DEBUG [org.apereo.cas.authentication.PseudoPlatformTransactionManager] - <Resuming suspended transaction after completion of inner transaction>
2018-05-07 18:00:38,951 DEBUG [org.apereo.cas.authentication.PseudoPlatformTransactionManager] - <Initiating transaction commit>
2018-05-07 18:00:38,955 DEBUG [org.apereo.cas.web.support.DefaultCasCookieValueManager] - <Encoding cookie value [TGT-******j7wEAriGdJ-LYIq0p2MAuf93dT5vKkyp9j_4DeHGPp8NtCaN-QDXn341TObxZSpfZrA-idiv-dev
1@10.35.12.194@Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/66.0.3359.139 Safari/537.36]>
2018-05-07 18:00:38,956 DEBUG [org.apereo.cas.util.EncodingUtils] - <Encrypting via [A128CBC-HS256]>
2018-05-07 18:00:38,969 DEBUG [org.apereo.cas.web.support.CookieRetrievingCookieGenerator] - <Creating cookie [TGC]>
2018-05-07 18:00:38,970 DEBUG [org.apereo.cas.web.support.TGCCookieRetrievingCookieGenerator] - <Added cookie with name [TGC] and value [eyJhbGciOiJIUzUxMiJ9.WlhsS05tRllRV2xQYVVwRlVsVlphVXhEU21oaVIyTn
BUMmxLYTJGWVNXbE1RMHBzWW0xTmFVOXBTa0pOVkVrMFVUQktSRXhWYUZSTmFsVXlTVzR3TGk1UmRsQjFjSEpJVFZObVRVMW1XRlo2U1VaMFRteFJMbk5EWlVGaWFrZHhZV0pVUkc5NU1FMW1lazQzYTBGZlFXWXRNRWh6VlhVMVVIcDBVamREYUhWaU9WOUxSV3d6Ul
MwdE5rNUdTMFZtYkVsVWMzVm1YMWMxUTFVMlRrVmhhRFUzUWtoMmRIcGZZWGcwVlUxS1dsQXljMjQwTlhaVGRtZ3lNRWh4TW1OcWNYSmhOVWx1VEdaVllVbFNRMEZVWkZWRWJYTnZWMHhYY0V0ck4wUjBNMFowY0RoWmJrZDJTVGxDVlZneWFFcHhXbGwxYWsxeVJ6Ql
lTRFppUlZjNFZ6UjVaSFZ5YURCM1JrdE1SRXBEVkVSaVVHUnlVMmgzVERaNlgyaHBObmsxUmt3eE0zRm9ZUzFoWjBwc1NIVk5hR2d5TUcxTlNYbFlaWE5mUVdKNWNFWllhamRYYzFJM2RUSk9jMkpEVFRac1RVWlNRVWxLWkRSTlNsaDBNbkJEWDJOdE0ybGhYelpsUT
JWTmFVeFJMblF0VjNGc1JXWnhhRU5FUW1aNU5UVTNaMWRTTUVFPQ.7GD_r2DsRSHhukd52W-vEQT4EstDTqUhjPVv0sw_YsFFeeowVMrI65XVfGnPrzaIzTuu1WbEOkHVQhRaUqJO8g]>
2018-05-07 18:00:38,978 DEBUG [org.apereo.cas.authentication.PseudoPlatformTransactionManager] - <Creating new transaction with name [org.apereo.cas.DefaultCentralAuthenticationService.getTicket]: PRO
PAGATION_REQUIRED,ISOLATION_DEFAULT; 'ticketTransactionManager',+org.apereo.cas.ticket.InvalidTicketException>
2018-05-07 18:00:38,979 DEBUG [org.apereo.cas.authentication.PseudoPlatformTransactionManager] - <Creating new transaction with name [org.apereo.cas.DefaultCentralAuthenticationService.getTicket]: PRO
PAGATION_REQUIRED,ISOLATION_DEFAULT; 'ticketTransactionManager',+org.apereo.cas.ticket.InvalidTicketException>
2018-05-07 18:00:38,986 DEBUG [org.apereo.cas.authentication.PseudoPlatformTransactionManager] - <Initiating transaction commit>
2018-05-07 18:00:38,986 DEBUG [org.apereo.cas.authentication.PseudoPlatformTransactionManager] - <Resuming suspended transaction after completion of inner transaction>
2018-05-07 18:00:38,987 DEBUG [org.apereo.cas.authentication.PseudoPlatformTransactionManager] - <Initiating transaction commit>
2018-05-07 18:00:38,988 DEBUG [org.apereo.cas.services.web.ChainingThemeResolver] - <Attempting to resolve theme via [CookieThemeResolver]>
2018-05-07 18:00:38,988 DEBUG [org.apereo.cas.services.web.ChainingThemeResolver] - <Attempting to resolve theme via [SessionThemeResolver]>
2018-05-07 18:00:38,989 DEBUG [org.apereo.cas.services.web.ChainingThemeResolver] - <Attempting to resolve theme via [RequestHeaderThemeResolver]>
2018-05-07 18:00:38,989 DEBUG [org.apereo.cas.services.web.ChainingThemeResolver] - <Attempting to resolve theme via [ServiceThemeResolver]>
2018-05-07 18:00:38,990 DEBUG [org.apereo.cas.services.web.ServiceThemeResolver] - <No service is found in the request context. Falling back to the default theme [cas-theme-default]>
2018-05-07 18:00:38,990 DEBUG [org.apereo.cas.services.web.ChainingThemeResolver] - <Attempting to resolve theme via [FixedThemeResolver]>
2018-05-07 18:00:38,990 DEBUG [org.apereo.cas.services.web.ChainingThemeResolver] - <No specific theme could be found. Using default theme [cas-theme-default}>
2018-05-07 18:00:39,093 DEBUG [org.apereo.cas.services.web.ChainingThemeResolver] - <Attempting to resolve theme via [CookieThemeResolver]>
2018-05-07 18:00:39,093 DEBUG [org.apereo.cas.services.web.ChainingThemeResolver] - <Attempting to resolve theme via [SessionThemeResolver]>
2018-05-07 18:00:39,093 DEBUG [org.apereo.cas.services.web.ChainingThemeResolver] - <Attempting to resolve theme via [RequestHeaderThemeResolver]>
2018-05-07 18:00:39,093 DEBUG [org.apereo.cas.services.web.ChainingThemeResolver] - <Attempting to resolve theme via [ServiceThemeResolver]>
2018-05-07 18:00:39,093 DEBUG [org.apereo.cas.services.web.ServiceThemeResolver] - <No service is found in the request context. Falling back to the default theme [cas-theme-default]>
2018-05-07 18:00:39,093 DEBUG [org.apereo.cas.services.web.ChainingThemeResolver] - <Attempting to resolve theme via [FixedThemeResolver]>
2018-05-07 18:00:39,093 DEBUG [org.apereo.cas.services.web.ChainingThemeResolver] - <No specific theme could be found. Using default theme [cas-theme-default}>
2018-05-07 18:00:39,137 DEBUG [org.apereo.cas.util.EncodingUtils] - <Decrypting value...>
2018-05-07 18:00:39,137 DEBUG [org.apereo.cas.util.EncodingUtils] - <Decrypting value...>
2018-05-07 18:00:39,137 DEBUG [org.apereo.cas.util.EncodingUtils] - <Decrypting value...>
2018-05-07 18:00:39,145 DEBUG [org.apereo.cas.web.support.DefaultCasCookieValueManager] - <Decoded cookie value is [TGT-******j7wEAriGdJ-LYIq0p2MAuf93dT5vKkyp9j_4DeHGPp8NtCaN-QDXn341TObxZSpfZrA-idiv-d
ev1@10.35.12.194@Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/66.0.3359.139 Safari/537.36]>
2018-05-07 18:00:39,146 DEBUG [org.apereo.cas.authentication.PseudoPlatformTransactionManager] - <Creating new transaction with name [org.apereo.cas.ticket.registry.DefaultTicketRegistrySupport.getAut
henticatedPrincipalFrom]: PROPAGATION_REQUIRED,ISOLATION_DEFAULT; 'ticketTransactionManager'>
2018-05-07 18:00:39,146 DEBUG [org.apereo.cas.authentication.PseudoPlatformTransactionManager] - <Creating new transaction with name [org.apereo.cas.ticket.registry.DefaultTicketRegistrySupport.getAut
henticatedPrincipalFrom]: PROPAGATION_REQUIRED,ISOLATION_DEFAULT; 'ticketTransactionManager'>
2018-05-07 18:00:39,146 DEBUG [org.apereo.cas.util.EncodingUtils] - <Decrypting value...>
2018-05-07 18:00:39,147 DEBUG [org.apereo.cas.web.support.DefaultCasCookieValueManager] - <Decoded cookie value is [TGT-******j7wEAriGdJ-LYIq0p2MAuf93dT5vKkyp9j_4DeHGPp8NtCaN-QDXn341TObxZSpfZrA-idiv-d
ev1@10.35.12.194@Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/66.0.3359.139 Safari/537.36]>
2018-05-07 18:00:39,147 DEBUG [org.apereo.cas.authentication.PseudoPlatformTransactionManager] - <Creating new transaction with name [org.apereo.cas.ticket.registry.DefaultTicketRegistrySupport.getAut
henticatedPrincipalFrom]: PROPAGATION_REQUIRED,ISOLATION_DEFAULT; 'ticketTransactionManager'>
2018-05-07 18:00:39,148 DEBUG [org.apereo.cas.authentication.PseudoPlatformTransactionManager] - <Creating new transaction with name [org.apereo.cas.ticket.registry.DefaultTicketRegistrySupport.getAut
henticatedPrincipalFrom]: PROPAGATION_REQUIRED,ISOLATION_DEFAULT; 'ticketTransactionManager'>
2018-05-07 18:00:39,148 DEBUG [org.apereo.cas.web.support.DefaultCasCookieValueManager] - <Decoded cookie value is [TGT-******j7wEAriGdJ-LYIq0p2MAuf93dT5vKkyp9j_4DeHGPp8NtCaN-QDXn341TObxZSpfZrA-idiv-d
ev1@10.35.12.194@Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/66.0.3359.139 Safari/537.36]>
2018-05-07 18:00:39,148 DEBUG [org.apereo.cas.util.EncodingUtils] - <Decrypting value...>
2018-05-07 18:00:39,148 DEBUG [org.apereo.cas.authentication.PseudoPlatformTransactionManager] - <Creating new transaction with name [org.apereo.cas.ticket.registry.DefaultTicketRegistrySupport.getAut
henticatedPrincipalFrom]: PROPAGATION_REQUIRED,ISOLATION_DEFAULT; 'ticketTransactionManager'>
2018-05-07 18:00:39,149 DEBUG [org.apereo.cas.authentication.PseudoPlatformTransactionManager] - <Creating new transaction with name [org.apereo.cas.ticket.registry.DefaultTicketRegistrySupport.getAut
henticatedPrincipalFrom]: PROPAGATION_REQUIRED,ISOLATION_DEFAULT; 'ticketTransactionManager'>
2018-05-07 18:00:39,149 DEBUG [org.apereo.cas.web.support.DefaultCasCookieValueManager] - <Decoded cookie value is [TGT-******j7wEAriGdJ-LYIq0p2MAuf93dT5vKkyp9j_4DeHGPp8NtCaN-QDXn341TObxZSpfZrA-idiv-d
ev1@10.35.12.194@Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/66.0.3359.139 Safari/537.36]>
2018-05-07 18:00:39,149 DEBUG [org.apereo.cas.authentication.PseudoPlatformTransactionManager] - <Creating new transaction with name [org.apereo.cas.ticket.registry.DefaultTicketRegistrySupport.getAut
henticatedPrincipalFrom]: PROPAGATION_REQUIRED,ISOLATION_DEFAULT; 'ticketTransactionManager'>
2018-05-07 18:00:39,150 DEBUG [org.apereo.cas.authentication.PseudoPlatformTransactionManager] - <Creating new transaction with name [org.apereo.cas.ticket.registry.DefaultTicketRegistrySupport.getAut
henticatedPrincipalFrom]: PROPAGATION_REQUIRED,ISOLATION_DEFAULT; 'ticketTransactionManager'>
2018-05-07 18:00:39,154 DEBUG [org.apereo.cas.web.support.DefaultCasCookieValueManager] - <Decoded cookie value is [TGT-******j7wEAriGdJ-LYIq0p2MAuf93dT5vKkyp9j_4DeHGPp8NtCaN-QDXn341TObxZSpfZrA-idiv-d
ev1@10.35.12.194@Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/66.0.3359.139 Safari/537.36]>
2018-05-07 18:00:39,155 DEBUG [org.apereo.cas.authentication.PseudoPlatformTransactionManager] - <Creating new transaction with name [org.apereo.cas.ticket.registry.DefaultTicketRegistrySupport.getAut
henticatedPrincipalFrom]: PROPAGATION_REQUIRED,ISOLATION_DEFAULT; 'ticketTransactionManager'>
2018-05-07 18:00:39,155 DEBUG [org.apereo.cas.authentication.PseudoPlatformTransactionManager] - <Creating new transaction with name [org.apereo.cas.ticket.registry.DefaultTicketRegistrySupport.getAut
henticatedPrincipalFrom]: PROPAGATION_REQUIRED,ISOLATION_DEFAULT; 'ticketTransactionManager'>
2018-05-07 18:00:39,163 DEBUG [org.apereo.cas.authentication.PseudoPlatformTransactionManager] - <Initiating transaction commit>
2018-05-07 18:00:39,164 DEBUG [org.apereo.cas.authentication.PseudoPlatformTransactionManager] - <Resuming suspended transaction after completion of inner transaction>
2018-05-07 18:00:39,164 DEBUG [org.apereo.cas.authentication.PseudoPlatformTransactionManager] - <Initiating transaction commit>
2018-05-07 18:00:39,166 DEBUG [org.apereo.cas.authentication.PseudoPlatformTransactionManager] - <Initiating transaction commit>
2018-05-07 18:00:39,166 DEBUG [org.apereo.cas.authentication.PseudoPlatformTransactionManager] - <Resuming suspended transaction after completion of inner transaction>
2018-05-07 18:00:39,167 DEBUG [org.apereo.cas.authentication.PseudoPlatformTransactionManager] - <Initiating transaction commit>
2018-05-07 18:00:39,185 DEBUG [org.apereo.cas.authentication.PseudoPlatformTransactionManager] - <Initiating transaction commit>
2018-05-07 18:00:39,186 DEBUG [org.apereo.cas.authentication.PseudoPlatformTransactionManager] - <Resuming suspended transaction after completion of inner transaction>
2018-05-07 18:00:39,186 DEBUG [org.apereo.cas.authentication.PseudoPlatformTransactionManager] - <Initiating transaction commit>
2018-05-07 18:00:39,188 DEBUG [org.apereo.cas.authentication.PseudoPlatformTransactionManager] - <Initiating transaction commit>
2018-05-07 18:00:39,188 DEBUG [org.apereo.cas.authentication.PseudoPlatformTransactionManager] - <Initiating transaction commit>
2018-05-07 18:00:39,189 DEBUG [org.apereo.cas.authentication.PseudoPlatformTransactionManager] - <Resuming suspended transaction after completion of inner transaction>
2018-05-07 18:00:39,189 DEBUG [org.apereo.cas.authentication.PseudoPlatformTransactionManager] - <Resuming suspended transaction after completion of inner transaction>
2018-05-07 18:00:39,189 DEBUG [org.apereo.cas.authentication.PseudoPlatformTransactionManager] - <Initiating transaction commit>
2018-05-07 18:00:39,189 DEBUG [org.apereo.cas.authentication.PseudoPlatformTransactionManager] - <Initiating transaction commit>
2018-05-07 18:00:39,192 DEBUG [org.apereo.cas.util.EncodingUtils] - <Decrypting value...>
2018-05-07 18:00:39,193 DEBUG [org.apereo.cas.web.support.DefaultCasCookieValueManager] - <Decoded cookie value is [TGT-******j7wEAriGdJ-LYIq0p2MAuf93dT5vKkyp9j_4DeHGPp8NtCaN-QDXn341TObxZSpfZrA-idiv-d
ev1@10.35.12.194@Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/66.0.3359.139 Safari/537.36]>
2018-05-07 18:00:39,193 DEBUG [org.apereo.cas.authentication.PseudoPlatformTransactionManager] - <Creating new transaction with name [org.apereo.cas.ticket.registry.DefaultTicketRegistrySupport.getAut
henticatedPrincipalFrom]: PROPAGATION_REQUIRED,ISOLATION_DEFAULT; 'ticketTransactionManager'>
2018-05-07 18:00:39,193 DEBUG [org.apereo.cas.authentication.PseudoPlatformTransactionManager] - <Creating new transaction with name [org.apereo.cas.ticket.registry.DefaultTicketRegistrySupport.getAut
henticatedPrincipalFrom]: PROPAGATION_REQUIRED,ISOLATION_DEFAULT; 'ticketTransactionManager'>
2018-05-07 18:00:39,194 DEBUG [org.apereo.cas.authentication.PseudoPlatformTransactionManager] - <Initiating transaction commit>
2018-05-07 18:00:39,194 DEBUG [org.apereo.cas.authentication.PseudoPlatformTransactionManager] - <Resuming suspended transaction after completion of inner transaction>
2018-05-07 18:00:39,194 DEBUG [org.apereo.cas.authentication.PseudoPlatformTransactionManager] - <Initiating transaction commit>
2018-05-07 18:00:39,223 DEBUG [org.apereo.cas.util.EncodingUtils] - <Decrypting value...>
2018-05-07 18:00:39,224 DEBUG [org.apereo.cas.web.support.DefaultCasCookieValueManager] - <Decoded cookie value is [TGT-******j7wEAriGdJ-LYIq0p2MAuf93dT5vKkyp9j_4DeHGPp8NtCaN-QDXn341TObxZSpfZrA-idiv-d
ev1@10.35.12.194@Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/66.0.3359.139 Safari/537.36]>
2018-05-07 18:00:39,224 DEBUG [org.apereo.cas.authentication.PseudoPlatformTransactionManager] - <Creating new transaction with name [org.apereo.cas.ticket.registry.DefaultTicketRegistrySupport.getAut
henticatedPrincipalFrom]: PROPAGATION_REQUIRED,ISOLATION_DEFAULT; 'ticketTransactionManager'>
2018-05-07 18:00:39,225 DEBUG [org.apereo.cas.authentication.PseudoPlatformTransactionManager] - <Creating new transaction with name [org.apereo.cas.ticket.registry.DefaultTicketRegistrySupport.getAut
henticatedPrincipalFrom]: PROPAGATION_REQUIRED,ISOLATION_DEFAULT; 'ticketTransactionManager'>
2018-05-07 18:00:39,225 DEBUG [org.apereo.cas.authentication.PseudoPlatformTransactionManager] - <Initiating transaction commit>
2018-05-07 18:00:39,225 DEBUG [org.apereo.cas.authentication.PseudoPlatformTransactionManager] - <Resuming suspended transaction after completion of inner transaction>
2018-05-07 18:00:39,225 DEBUG [org.apereo.cas.authentication.PseudoPlatformTransactionManager] - <Initiating transaction commit>
2018-05-07 18:00:39,246 DEBUG [org.apereo.cas.util.EncodingUtils] - <Decrypting value...>
2018-05-07 18:00:39,247 DEBUG [org.apereo.cas.web.support.DefaultCasCookieValueManager] - <Decoded cookie value is [TGT-******j7wEAriGdJ-LYIq0p2MAuf93dT5vKkyp9j_4DeHGPp8NtCaN-QDXn341TObxZSpfZrA-idiv-d
ev1@10.35.12.194@Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/66.0.3359.139 Safari/537.36]>
2018-05-07 18:00:39,248 DEBUG [org.apereo.cas.authentication.PseudoPlatformTransactionManager] - <Creating new transaction with name [org.apereo.cas.ticket.registry.DefaultTicketRegistrySupport.getAut
henticatedPrincipalFrom]: PROPAGATION_REQUIRED,ISOLATION_DEFAULT; 'ticketTransactionManager'>
2018-05-07 18:00:39,249 DEBUG [org.apereo.cas.authentication.PseudoPlatformTransactionManager] - <Creating new transaction with name [org.apereo.cas.ticket.registry.DefaultTicketRegistrySupport.getAut
henticatedPrincipalFrom]: PROPAGATION_REQUIRED,ISOLATION_DEFAULT; 'ticketTransactionManager'>
2018-05-07 18:00:39,249 DEBUG [org.apereo.cas.authentication.PseudoPlatformTransactionManager] - <Initiating transaction commit>
2018-05-07 18:00:39,249 DEBUG [org.apereo.cas.authentication.PseudoPlatformTransactionManager] - <Resuming suspended transaction after completion of inner transaction>
2018-05-07 18:00:39,250 DEBUG [org.apereo.cas.authentication.PseudoPlatformTransactionManager] - <Initiating transaction commit>
2018-05-07 18:00:39,251 DEBUG [org.apereo.cas.util.EncodingUtils] - <Decrypting value...>
2018-05-07 18:00:39,251 DEBUG [org.apereo.cas.web.support.DefaultCasCookieValueManager] - <Decoded cookie value is [TGT-******j7wEAriGdJ-LYIq0p2MAuf93dT5vKkyp9j_4DeHGPp8NtCaN-QDXn341TObxZSpfZrA-idiv-d
ev1@10.35.12.194@Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/66.0.3359.139 Safari/537.36]>
2018-05-07 18:00:39,251 DEBUG [org.apereo.cas.authentication.PseudoPlatformTransactionManager] - <Creating new transaction with name [org.apereo.cas.ticket.registry.DefaultTicketRegistrySupport.getAut
henticatedPrincipalFrom]: PROPAGATION_REQUIRED,ISOLATION_DEFAULT; 'ticketTransactionManager'>
2018-05-07 18:00:39,251 DEBUG [org.apereo.cas.authentication.PseudoPlatformTransactionManager] - <Creating new transaction with name [org.apereo.cas.ticket.registry.DefaultTicketRegistrySupport.getAut
henticatedPrincipalFrom]: PROPAGATION_REQUIRED,ISOLATION_DEFAULT; 'ticketTransactionManager'>
2018-05-07 18:00:39,251 DEBUG [org.apereo.cas.authentication.PseudoPlatformTransactionManager] - <Initiating transaction commit>
2018-05-07 18:00:39,251 DEBUG [org.apereo.cas.authentication.PseudoPlatformTransactionManager] - <Resuming suspended transaction after completion of inner transaction>
2018-05-07 18:00:39,251 DEBUG [org.apereo.cas.authentication.PseudoPlatformTransactionManager] - <Initiating transaction commit>
2018-05-07 18:00:39,266 DEBUG [org.apereo.cas.util.EncodingUtils] - <Decrypting value...>
2018-05-07 18:00:39,266 DEBUG [org.apereo.cas.web.support.DefaultCasCookieValueManager] - <Decoded cookie value is [TGT-******j7wEAriGdJ-LYIq0p2MAuf93dT5vKkyp9j_4DeHGPp8NtCaN-QDXn341TObxZSpfZrA-idiv-d
ev1@10.35.12.194@Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/66.0.3359.139 Safari/537.36]>
2018-05-07 18:00:39,266 DEBUG [org.apereo.cas.authentication.PseudoPlatformTransactionManager] - <Creating new transaction with name [org.apereo.cas.ticket.registry.DefaultTicketRegistrySupport.getAut
henticatedPrincipalFrom]: PROPAGATION_REQUIRED,ISOLATION_DEFAULT; 'ticketTransactionManager'>
2018-05-07 18:00:39,266 DEBUG [org.apereo.cas.authentication.PseudoPlatformTransactionManager] - <Creating new transaction with name [org.apereo.cas.ticket.registry.DefaultTicketRegistrySupport.getAut
henticatedPrincipalFrom]: PROPAGATION_REQUIRED,ISOLATION_DEFAULT; 'ticketTransactionManager'>
2018-05-07 18:00:39,266 DEBUG [org.apereo.cas.authentication.PseudoPlatformTransactionManager] - <Initiating transaction commit>
2018-05-07 18:00:39,266 DEBUG [org.apereo.cas.authentication.PseudoPlatformTransactionManager] - <Resuming suspended transaction after completion of inner transaction>
2018-05-07 18:00:39,266 DEBUG [org.apereo.cas.authentication.PseudoPlatformTransactionManager] - <Initiating transaction commit>
2018-05-07 18:00:39,285 DEBUG [org.apereo.cas.util.EncodingUtils] - <Decrypting value...>
2018-05-07 18:00:39,286 DEBUG [org.apereo.cas.web.support.DefaultCasCookieValueManager] - <Decoded cookie value is [TGT-******j7wEAriGdJ-LYIq0p2MAuf93dT5vKkyp9j_4DeHGPp8NtCaN-QDXn341TObxZSpfZrA-idiv-d
ev1@10.35.12.194@Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/66.0.3359.139 Safari/537.36]>
2018-05-07 18:00:39,287 DEBUG [org.apereo.cas.authentication.PseudoPlatformTransactionManager] - <Creating new transaction with name [org.apereo.cas.ticket.registry.DefaultTicketRegistrySupport.getAut
henticatedPrincipalFrom]: PROPAGATION_REQUIRED,ISOLATION_DEFAULT; 'ticketTransactionManager'>
2018-05-07 18:00:39,288 DEBUG [org.apereo.cas.authentication.PseudoPlatformTransactionManager] - <Creating new transaction with name [org.apereo.cas.ticket.registry.DefaultTicketRegistrySupport.getAut
henticatedPrincipalFrom]: PROPAGATION_REQUIRED,ISOLATION_DEFAULT; 'ticketTransactionManager'>
2018-05-07 18:00:39,288 DEBUG [org.apereo.cas.authentication.PseudoPlatformTransactionManager] - <Initiating transaction commit>
2018-05-07 18:00:39,289 DEBUG [org.apereo.cas.authentication.PseudoPlatformTransactionManager] - <Resuming suspended transaction after completion of inner transaction>
2018-05-07 18:00:39,289 DEBUG [org.apereo.cas.authentication.PseudoPlatformTransactionManager] - <Initiating transaction commit>
2018-05-07 18:00:39,750 DEBUG [org.apereo.cas.util.EncodingUtils] - <Decrypting value...>
2018-05-07 18:00:39,751 DEBUG [org.apereo.cas.web.support.DefaultCasCookieValueManager] - <Decoded cookie value is [TGT-******j7wEAriGdJ-LYIq0p2MAuf93dT5vKkyp9j_4DeHGPp8NtCaN-QDXn341TObxZSpfZrA-idiv-d
ev1@10.35.12.194@Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/66.0.3359.139 Safari/537.36]>
2018-05-07 18:00:39,751 DEBUG [org.apereo.cas.authentication.PseudoPlatformTransactionManager] - <Creating new transaction with name [org.apereo.cas.ticket.registry.DefaultTicketRegistrySupport.getAut
henticatedPrincipalFrom]: PROPAGATION_REQUIRED,ISOLATION_DEFAULT; 'ticketTransactionManager'>
2018-05-07 18:00:39,751 DEBUG [org.apereo.cas.authentication.PseudoPlatformTransactionManager] - <Creating new transaction with name [org.apereo.cas.ticket.registry.DefaultTicketRegistrySupport.getAut
henticatedPrincipalFrom]: PROPAGATION_REQUIRED,ISOLATION_DEFAULT; 'ticketTransactionManager'>
2018-05-07 18:00:39,751 DEBUG [org.apereo.cas.authentication.PseudoPlatformTransactionManager] - <Initiating transaction commit>
2018-05-07 18:00:39,751 DEBUG [org.apereo.cas.authentication.PseudoPlatformTransactionManager] - <Resuming suspended transaction after completion of inner transaction>
2018-05-07 18:00:39,751 DEBUG [org.apereo.cas.authentication.PseudoPlatformTransactionManager] - <Initiating transaction commit>
2018-05-07 18:01:00,325 DEBUG [org.apereo.cas.services.AbstractServicesManager] - <Loading services from [JsonServiceRegistryDao]>
2018-05-07 18:01:00,335 DEBUG [org.apereo.cas.services.AbstractServiceRegistryDao] - <Publishing event [org.apereo.cas.support.events.service.CasRegisteredServiceLoadedEvent@57cd9440[registeredService
=id=10000002,name=Apereo,description=Apereo foundation sample service,serviceId=^(https|imaps|http)://.*,usernameAttributeProvider=org.apereo.cas.services.DefaultRegisteredServiceUsernameProvider@d,th
eme=apereo,evaluationOrder=1,logoutType=BACK_CHANNEL,attributeReleasePolicy=org.apereo.cas.services.ReturnAllowedAttributeReleasePolicy@33d11549[attributeFilter=<null>,principalAttributesRepository=or
g.apereo.cas.authentication.principal.DefaultPrincipalAttributesRepository@2fef2e03[],authorizedToReleaseCredentialPassword=false,authorizedToReleaseAuthenticationAttributes=true,authorizedToReleasePr
oxyGrantingTicket=false,excludeDefaultAttributes=false,principalIdAttribute=<null>,consentPolicy=org.apereo.cas.services.consent.DefaultRegisteredServiceConsentPolicy@62360ee7[excludedAttributes=<null
>,includeOnlyAttributes=<null>,enabled=true],allowedAttributes=[]],accessStrategy=org.apereo.cas.services.DefaultRegisteredServiceAccessStrategy@59e2f975[enabled=true,ssoEnabled=true,requireAllAttribu
tes=true,requiredAttributes={},unauthorizedRedirectUrl=<null>,caseInsensitive=false,rejectedAttributes={}],publicKey=<null>,proxyPolicy=org.apereo.cas.services.RefuseRegisteredServiceProxyPolicy@43250
254,logo=<null>,logoutUrl=<null>,requiredHandlers=[],properties={},multifactorPolicy=org.apereo.cas.services.DefaultRegisteredServiceMultifactorPolicy@6222c45d[multifactorAuthenticationProviders=[],fa
ilureMode=NOT_SET,principalAttributeNameTrigger=<null>,principalAttributeValueToMatch=<null>,bypassEnabled=false],informationUrl=<null>,privacyUrl=<null>,contacts=[],expirationPolicy=org.apereo.cas.se
rvices.DefaultRegisteredServiceExpirationPolicy@34eb612c[deleteWhenExpired=false,notifyWhenDeleted=false,expirationDate=<null>],<null>]]>
2018-05-07 18:01:00,336 DEBUG [org.apereo.cas.services.AbstractServiceRegistryDao] - <Publishing event [org.apereo.cas.support.events.service.CasRegisteredServiceLoadedEvent@66781060[registeredService
=id=10000001,name=HTTPS and IMAPS,description=This service definition authorized all application urls that support HTTPS and IMAPS protocols.,serviceId=https://idiv-dev1:8443/iDivWebApp/.*,usernameAtt
ributeProvider=org.apereo.cas.services.DefaultRegisteredServiceUsernameProvider@d,theme=<null>,evaluationOrder=10000,logoutType=BACK_CHANNEL,attributeReleasePolicy=org.apereo.cas.services.ReturnAllowe
dAttributeReleasePolicy@289f748b[attributeFilter=<null>,principalAttributesRepository=org.apereo.cas.authentication.principal.DefaultPrincipalAttributesRepository@5ceb5de9[],authorizedToReleaseCredent
ialPassword=false,authorizedToReleaseAuthenticationAttributes=true,authorizedToReleaseProxyGrantingTicket=true,excludeDefaultAttributes=false,principalIdAttribute=<null>,consentPolicy=org.apereo.cas.s
ervices.consent.DefaultRegisteredServiceConsentPolicy@3366b559[excludedAttributes=<null>,includeOnlyAttributes=<null>,enabled=true],allowedAttributes=[]],accessStrategy=org.apereo.cas.services.Default
RegisteredServiceAccessStrategy@15bb5845[enabled=true,ssoEnabled=true,requireAllAttributes=true,requiredAttributes={},unauthorizedRedirectUrl=<null>,caseInsensitive=false,rejectedAttributes={}],public
Key=<null>,proxyPolicy=org.apereo.cas.services.RefuseRegisteredServiceProxyPolicy@471b6df0,logo=<null>,logoutUrl=https://idiv-dev1:8443/iDivWebApp/CasLogout,requiredHandlers=[],properties={},multifact
orPolicy=org.apereo.cas.services.DefaultRegisteredServiceMultifactorPolicy@46534d55[multifactorAuthenticationProviders=[],failureMode=NOT_SET,principalAttributeNameTrigger=<null>,principalAttributeVal
ueToMatch=<null>,bypassEnabled=false],informationUrl=<null>,privacyUrl=<null>,contacts=[],expirationPolicy=org.apereo.cas.services.DefaultRegisteredServiceExpirationPolicy@40514463[deleteWhenExpired=f
alse,notifyWhenDeleted=false,expirationDate=<null>],<null>]]>
2018-05-07 18:01:00,338 DEBUG [org.apereo.cas.services.AbstractServiceRegistryDao] - <Publishing event [org.apereo.cas.support.events.service.CasRegisteredServiceLoadedEvent@469bed4d[registeredService
=id=10000003,name=sample,description=Sample web service,serviceId=https://idiv-dev1:8443/cas-sample-java-webapp/,usernameAttributeProvider=org.apereo.cas.services.DefaultRegisteredServiceUsernameProvi
der@d,theme=sample,evaluationOrder=10001,logoutType=BACK_CHANNEL,attributeReleasePolicy=org.apereo.cas.services.ReturnAllowedAttributeReleasePolicy@92dfcab[attributeFilter=<null>,principalAttributesRe
pository=org.apereo.cas.authentication.principal.DefaultPrincipalAttributesRepository@5548213e[],authorizedToReleaseCredentialPassword=false,authorizedToReleaseAuthenticationAttributes=true,authorized
ToReleaseProxyGrantingTicket=false,excludeDefaultAttributes=false,principalIdAttribute=<null>,consentPolicy=org.apereo.cas.services.consent.DefaultRegisteredServiceConsentPolicy@1f7005a8[excludedAttri
butes=<null>,includeOnlyAttributes=<null>,enabled=true],allowedAttributes=[]],accessStrategy=org.apereo.cas.services.DefaultRegisteredServiceAccessStrategy@698728e[enabled=true,ssoEnabled=true,require
AllAttributes=true,requiredAttributes={},unauthorizedRedirectUrl=<null>,caseInsensitive=false,rejectedAttributes={}],publicKey=<null>,proxyPolicy=org.apereo.cas.services.RefuseRegisteredServiceProxyPo
licy@219f869,logo=<null>,logoutUrl=https://idiv-dev1:8443/cas-sample-java-webapp/Logout,requiredHandlers=[],properties={},multifactorPolicy=org.apereo.cas.services.DefaultRegisteredServiceMultifactorP
olicy@60361e52[multifactorAuthenticationProviders=[],failureMode=NOT_SET,principalAttributeNameTrigger=<null>,principalAttributeValueToMatch=<null>,bypassEnabled=false],informationUrl=<null>,privacyUr
l=<null>,contacts=[],expirationPolicy=org.apereo.cas.services.DefaultRegisteredServiceExpirationPolicy@746382bb[deleteWhenExpired=false,notifyWhenDeleted=false,expirationDate=<null>],<null>]]>
2018-05-07 18:01:00,338 DEBUG [org.apereo.cas.services.AbstractServicesManager] - <Adding registered service [^(https|imaps|http)://.*]>
2018-05-07 18:01:00,339 DEBUG [org.apereo.cas.services.AbstractServicesManager] - <Adding registered service [https://idiv-dev1:8443/iDivWebApp/.*]>
2018-05-07 18:01:00,339 DEBUG [org.apereo.cas.services.AbstractServicesManager] - <Adding registered service [https://idiv-dev1:8443/cas-sample-java-webapp/]>
2018-05-07 18:01:00,340 INFO [org.apereo.cas.services.AbstractServicesManager] - <Loaded [3] service(s) from [JsonServiceRegistryDao].>