There it goes. I added both the with and without transactions versions (both are original versions, just munged sensitive data). Let me know if you need anything else.
Regards, Nicolás. El 23/09/15 a las 17:36, Misagh Moayyed escribió: > > I think I have managed to diagnose the problem, but even so, if you > could attach DEBUG logs for org.springframework and org.jasig, that > would be great. The fix will likely go into 4.1.1. > > *From:*Nicolás [mailto:[email protected]] > *Sent:* Wednesday, September 23, 2015 9:26 AM > *To:* [email protected] > *Subject:* Re: [cas-user] CAS returns to the main page instead of > authenticating > > Unfortunately it seems that this isn't the only problem. I commented > those out, now the error doesn't show up (and no other shows up) but I > still get the same problem: after authentication, I get redirected to > the main CAS page to enter login again. > > I think we will definitely switch to Hazelcast for ticketing, seems at > this point the JPA driver is quite problematic in our case (only for > ticketing, for service storage it works quite fine). > > I can provide more info if you're interested in debugging it, though. > > Regards, > > Nicolás. > > El 23/09/15 a las 16:49, Misagh Moayyed escribió: > > Yes, those should do it. > > *From:*Nicolás [mailto:[email protected]] > *Sent:* Wednesday, September 23, 2015 8:44 AM > *To:* [email protected] <mailto:[email protected]> > *Subject:* Re: [cas-user] CAS returns to the main page instead of > authenticating > > Hi Misagh, > > El 23/09/15 a las 11:29, Misagh Moayyed escribió: > > This seems to be an issue with annotation-based transactions. > Try disabling transactions for now, or consider switching to a > cache-based registry for better performance. > > > Is it enough to comment out the transactionManager bean and > <tx:annotation-driven transaction-manager="transactionManager" /> > to disable transactions? > > Thanks. > > > *From:*Nicolás [mailto:[email protected]] > *Sent:* Tuesday, September 22, 2015 11:02 AM > *To:* [email protected] <mailto:[email protected]> > *Subject:* [cas-user] CAS returns to the main page instead of > authenticating > > Hi, > > What can be generating the behavior described in the log > below? Each time an application tries to authenticate with > CAS, it just returns again to the CAS main page. No error, no > warning, nothing. Just like if I just accessed /cas. > Especially significant seems this line: > > 2015-09-22 18:28:26,233 ERROR > [org.jasig.cas.ticket.registry.JpaTicketRegistry] - Error > getting ticket > > [TGT-**********************************************Bd1falzoKR-mydomain.com, > javax.persistence.TransactionRequiredException: no > transaction is in progress] from registry. > > > What can be the problem? I followed the guide for JPA at > http://jasig.github.io/cas/4.1.x/installation/JPA-Ticket-Registry.html > for CAS 4.1.x. > > 2015-09-22 18:28:26,123 DEBUG > [org.jasig.cas.audit.spi.TicketOrCredentialPrincipalResolver] > - <Resolving argument [UsernamePasswordCredential] for audit> > > 2015-09-22 18:28:26,130 INFO > [org.jasig.inspektr.audit.support.Slf4jLoggingAuditTrailManager] > - Audit trail record BEGIN > ============================================================= > WHO: myuser+password > WHAT: supplied credentials: [myuser+password] > ACTION: AUTHENTICATION_SUCCESS > APPLICATION: CAS > WHEN: Tue Sep 22 18:28:26 WEST 2015 > CLIENT IP ADDRESS: 192.168.1.X > SERVER IP ADDRESS: 192.168.1.Y > ============================================================= > > 2015-09-22 18:28:26,138 DEBUG > [org.jasig.cas.ticket.registry.JpaTicketRegistry] - Added > ticket > > [TGT-**********************************************Bd1falzoKR-mydomain.com] > to registry. > > 2015-09-22 18:28:26,138 DEBUG > [org.jasig.cas.ticket.registry.JpaTicketRegistry] - <Added > ticket > > [TGT-**********************************************Bd1falzoKR-mydomain.com] > to registry.> > Hibernate: insert into TICKETGRANTINGTICKET > (NUMBER_OF_TIMES_USED, CREATION_TIME, EXPIRATION_POLICY, > LAST_TIME_USED, PREVIOUS_LAST_TIME_USED, > ticketGrantingTicket_ID, AUTHENTICATION, EXPIRED, > PROXIED_BY, SERVICES_GRANTED_ACCESS_TO, > SUPPLEMENTAL_AUTHENTICATIONS, ID) values (?, ?, ?, ?, ?, > ?, ?, ?, ?, ?, ?, ?) > Hibernate: update TICKETGRANTINGTICKET set > NUMBER_OF_TIMES_USED=?, CREATION_TIME=?, > EXPIRATION_POLICY=?, LAST_TIME_USED=?, > PREVIOUS_LAST_TIME_USED=?, ticketGrantingTicket_ID=?, > AUTHENTICATION=?, EXPIRED=?, PROXIED_BY=?, > SERVICES_GRANTED_ACCESS_TO=?, > SUPPLEMENTAL_AUTHENTICATIONS=? where ID=? > > 2015-09-22 18:28:26,193 DEBUG > [org.jasig.cas.audit.spi.TicketOrCredentialPrincipalResolver] > - <Resolving argument [UsernamePasswordCredential] for audit> > > 2015-09-22 18:28:26,195 INFO > [org.jasig.inspektr.audit.support.Slf4jLoggingAuditTrailManager] > - Audit trail record BEGIN > ============================================================= > WHO: myuser+password > WHAT: > > TGT-**********************************************Bd1falzoKR-mydomain.com > ACTION: TICKET_GRANTING_TICKET_CREATED > APPLICATION: CAS > WHEN: Tue Sep 22 18:28:26 WEST 2015 > CLIENT IP ADDRESS: 192.168.1.X > SERVER IP ADDRESS: 192.168.1.Y > ============================================================= > > > 2015-09-22 18:28:26,195 INFO > [org.jasig.inspektr.audit.support.Slf4jLoggingAuditTrailManager] > - Audit trail record BEGIN > ============================================================= > WHO: myuser+password > WHAT: > > TGT-**********************************************Bd1falzoKR-mydomain.com > ACTION: TICKET_GRANTING_TICKET_CREATED > APPLICATION: CAS > WHEN: Tue Sep 22 18:28:26 WEST 2015 > CLIENT IP ADDRESS: 192.168.1.X > SERVER IP ADDRESS: 192.168.1.Y > ============================================================= > > 2015-09-22 18:28:26,203 DEBUG > [org.jasig.cas.web.support.CookieRetrievingCookieGenerator] - > Removed cookie with name [CASPRIVACY] > > 2015-09-22 18:28:26,214 DEBUG > [org.jasig.cas.web.support.DefaultCasCookieValueManager] - > Encoding cookie value > > [TGT-**********************************************[email protected]@Mozilla/5.0 > > <mailto:TGT-**********************************************[email protected]@Mozilla/5.0> > (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) > Ubuntu Chromium/44.0.2403.89 Chrome/44.0.2403.89 > Safari/537.36] > > 2015-09-22 18:28:26,214 DEBUG > [org.jasig.cas.web.support.DefaultCasCookieValueManager] - > <Encoding cookie value > > [TGT-**********************************************[email protected]@Mozilla/5.0 > > <mailto:TGT-**********************************************[email protected]@Mozilla/5.0> > (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) > Ubuntu Chromium/44.0.2403.89 Chrome/44.0.2403.89 > Safari/537.36]> > > 2015-09-22 18:28:26,215 DEBUG > [org.jasig.cas.util.DefaultCipherExecutor] - <Encrypting > via [A128CBC-HS256]> > > 2015-09-22 18:28:26,219 DEBUG > [org.jasig.cas.web.support.CookieRetrievingCookieGenerator] - > <Added cookie with name [TGC] and value > > [eyJhbGciOiJIUzUxMiJ9.ZXlKaGJHY2lPaUprYVhJaUxDSmxibU1pT2lKQk1USTRRMEpETFVoVE1qVTJJbjAuLnZoREdWdlo5ZFBwaUxrN0dobFE1T3cuQnpNaEtTM3EwNkVjYW4yNWROTkxyRXUwbDFWd2pIeUZGaW1rXy1xSzhXMGZkUGc5SFlvY3A1YUM4ZUNEaU1kYmVrV0NNMVlJQWFXdjNzbkM1eEp5dEtSS0VzWVA4OWE2aGpubkYxeXR5dHFnbTFNVnJjanlYRnQ4RlRNUG9zYXlBUW5OYVRjUVRWOFlpdjZTdll0NmFlRGd5TzljM0R4c3dkeUdoWjV5Y1MzdE5oZEU4V2FBRUtpMW45LUZNQko1NlVudUU4SDNKb0JNUDF6RzNPSjNkWkd1Q3FvNnJ1dlJxNU92S3JGNkZ2MlZsbUk3Zl8tOWFzUWNJMC01VUFJWHk1bzNvUHJYMldySlp1YTdLTndqZUdYSVBCdGN3NE9hV1RWdTRsM2pfWUxZMTZxZXpqT0RuNGN4UjRVdEpCZTUudUxMTmN3ejNSTXhlejk3c1J6ZVh4UQ.fQonmfjuy9o_Rce6TpanOfL27J9oXmvcsivUQzBuEwf1GLhj_EhozFUAqUDITNvkwrj51eQU9IT5kJNYjND29g]> > > 2015-09-22 18:28:26,233 ERROR > [org.jasig.cas.ticket.registry.JpaTicketRegistry] - Error > getting ticket > > [TGT-**********************************************Bd1falzoKR-mydomain.com, > javax.persistence.TransactionRequiredException: no > transaction is in progress] from registry. > > 2015-09-22 18:28:26,240 DEBUG > [org.jasig.cas.CentralAuthenticationServiceImpl] - Ticket > > [TGT-**********************************************Bd1falzoKR-mydomain.com] > by type [TicketGrantingTicket] cannot be found in the > ticket registry. > > 2015-09-22 18:28:26,248 DEBUG > [org.jasig.cas.audit.spi.TicketOrCredentialPrincipalResolver] > - Resolving argument [String] for audit > > 2015-09-22 18:28:26,250 ERROR > [org.jasig.cas.ticket.registry.JpaTicketRegistry] - Error > getting ticket > > [TGT-**********************************************Bd1falzoKR-mydomain.com, > javax.persistence.TransactionRequiredException: no > transaction is in progress] from registry. > > It's important to note that the ticket is indeed stored in the > MySQL database. > > Thanks. > > > > -- > > You are currently subscribed [email protected] > <mailto:[email protected]> as:[email protected] > <mailto:[email protected]> > > To unsubscribe, change settings or access archives, > seehttp://www.ja-sig.org/wiki/display/JSG/cas-user > > -- > > You are currently subscribed [email protected] > <mailto:[email protected]> as:[email protected] > <mailto:[email protected]> > > To unsubscribe, change settings or access archives, > seehttp://www.ja-sig.org/wiki/display/JSG/cas-user > > > > -- > > You are currently subscribed [email protected] > <mailto:[email protected]> as:[email protected] > <mailto:[email protected]> > > To unsubscribe, change settings or access archives, > seehttp://www.ja-sig.org/wiki/display/JSG/cas-user > > -- > > You are currently subscribed [email protected] > <mailto:[email protected]> as:[email protected] > <mailto:[email protected]> > > To unsubscribe, change settings or access archives, > seehttp://www.ja-sig.org/wiki/display/JSG/cas-user > > -- > You are currently subscribed [email protected] > <mailto:[email protected]> as:[email protected] > <mailto:[email protected]> > To unsubscribe, change settings or access archives, > seehttp://www.ja-sig.org/wiki/display/JSG/cas-user > -- > 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 -- 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
2015-09-23 18:29:29,463 DEBUG [org.springframework.binding.mapping.impl.DefaultMapper] - <Beginning mapping between source [org.springframework.webflow.core.collection.LocalParameterMap] and target [org.jasig.cas.authentication.UsernamePasswordCredential]> 2015-09-23 18:29:29,502 DEBUG [org.springframework.binding.mapping.impl.DefaultMapping] - <Adding mapping result [Success@7cd65078 mapping = parameter:'username' -> username, code = 'success', error = false, originalValue = 'myuser', mappedValue = 'myuser']> 2015-09-23 18:29:29,505 DEBUG [org.springframework.binding.mapping.impl.DefaultMapping] - <Adding mapping result [Success@5a3d364f mapping = parameter:'password' -> password, code = 'success', error = false, originalValue = '***********', mappedValue = '***********']> 2015-09-23 18:29:29,507 DEBUG [org.springframework.binding.mapping.impl.DefaultMapper] - <Completing mapping between source [org.springframework.webflow.core.collection.LocalParameterMap] and target [org.jasig.cas.authentication.UsernamePasswordCredential]; total mappings = 2; total errors = 0> 2015-09-23 18:29:29,517 DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory] - <Returning cached instance of singleton bean 'authenticationViaFormAction'> ==> cas.log <== 2015-09-23 18:29:29,697 DEBUG [org.jasig.cas.authentication.LdapAuthenticationHandler] - Attempting LDAP authentication for myuser+password ==> catalina.out <== 2015-09-23 18:29:29,697 DEBUG [org.jasig.cas.authentication.LdapAuthenticationHandler] - <Attempting LDAP authentication for myuser+password> ==> cas.log <== 2015-09-23 18:29:29,753 DEBUG [org.jasig.cas.authentication.LdapAuthenticationHandler] - LDAP response: [org.ldaptive.auth.AuthenticationResponse@1915276369::authenticationResultCode=AUTHENTICATION_HANDLER_SUCCESS, ldapEntry=[dn=uid=myuser,cn=...,dc=...,dc=...[[uid[myuser]], [mail[[email protected]]], [sn[XXXXXXXXX XXXXXXXXX]], [cn[Nicolás]], [homePhone[XXXXXXXXX]], [mobile[XXXXXXXXX]]], responseControls=null, messageId=-1], accountState=null, result=true, resultCode=SUCCESS, message=null, controls=null] ==> catalina.out <== 2015-09-23 18:29:29,753 DEBUG [org.jasig.cas.authentication.LdapAuthenticationHandler] - <LDAP response: [org.ldaptive.auth.AuthenticationResponse@1915276369::authenticationResultCode=AUTHENTICATION_HANDLER_SUCCESS, ldapEntry=[dn=uid=myuser,cn=...,dc=...,dc=...[[uid[myuser]], [mail[[email protected]]], [sn[XXXXXXXXX XXXXXXXXX]], [cn[Nicolás]], [homePhone[XXXXXXXXX]], [mobile[XXXXXXXXX]]], responseControls=null, messageId=-1], accountState=null, result=true, resultCode=SUCCESS, message=null, controls=null]> ==> cas.log <== 2015-09-23 18:29:29,754 DEBUG [org.jasig.cas.authentication.LdapAuthenticationHandler] - Found principal attribute: [mail[[email protected]]] ==> catalina.out <== 2015-09-23 18:29:29,754 DEBUG [org.jasig.cas.authentication.LdapAuthenticationHandler] - <Found principal attribute: [mail[[email protected]]]> ==> cas.log <== 2015-09-23 18:29:29,755 DEBUG [org.jasig.cas.authentication.LdapAuthenticationHandler] - Found principal attribute: [cn[Nicolás]] ==> catalina.out <== 2015-09-23 18:29:29,755 DEBUG [org.jasig.cas.authentication.LdapAuthenticationHandler] - <Found principal attribute: [cn[Nicolás]]> ==> cas.log <== 2015-09-23 18:29:29,756 DEBUG [org.jasig.cas.authentication.LdapAuthenticationHandler] - Found principal attribute: [sn[XXXXXXXXX XXXXXXXXX]] ==> catalina.out <== 2015-09-23 18:29:29,756 DEBUG [org.jasig.cas.authentication.LdapAuthenticationHandler] - <Found principal attribute: [sn[XXXXXXXXX XXXXXXXXX]]> ==> cas.log <== 2015-09-23 18:29:29,756 DEBUG [org.jasig.cas.authentication.LdapAuthenticationHandler] - Found principal attribute: [uid[myuser]] ==> catalina.out <== 2015-09-23 18:29:29,756 DEBUG [org.jasig.cas.authentication.LdapAuthenticationHandler] - <Found principal attribute: [uid[myuser]]> ==> cas.log <== 2015-09-23 18:29:29,757 DEBUG [org.jasig.cas.authentication.LdapAuthenticationHandler] - Found principal attribute: [mobile[XXXXXXXXX]] ==> catalina.out <== 2015-09-23 18:29:29,757 DEBUG [org.jasig.cas.authentication.LdapAuthenticationHandler] - <Found principal attribute: [mobile[XXXXXXXXX]]> ==> cas.log <== 2015-09-23 18:29:29,758 DEBUG [org.jasig.cas.authentication.LdapAuthenticationHandler] - Found principal attribute: [homePhone[XXXXXXXXX]] ==> catalina.out <== 2015-09-23 18:29:29,758 DEBUG [org.jasig.cas.authentication.LdapAuthenticationHandler] - <Found principal attribute: [homePhone[XXXXXXXXX]]> ==> cas.log <== 2015-09-23 18:29:29,778 INFO [org.jasig.cas.authentication.PolicyBasedAuthenticationManager] - LdapAuthenticationHandler successfully authenticated myuser+password ==> catalina.out <== 2015-09-23 18:29:29,778 INFO [org.jasig.cas.authentication.PolicyBasedAuthenticationManager] - <LdapAuthenticationHandler successfully authenticated myuser+password> ==> cas.log <== 2015-09-23 18:29:29,781 DEBUG [org.jasig.cas.authentication.PolicyBasedAuthenticationManager] - No resolver configured for LdapAuthenticationHandler. Falling back to handler principal myuser ==> catalina.out <== 2015-09-23 18:29:29,781 DEBUG [org.jasig.cas.authentication.PolicyBasedAuthenticationManager] - <No resolver configured for LdapAuthenticationHandler. Falling back to handler principal myuser> ==> cas.log <== 2015-09-23 18:29:29,806 INFO [org.jasig.cas.authentication.PolicyBasedAuthenticationManager] - Authenticated myuser with credentials [myuser+password]. ==> catalina.out <== 2015-09-23 18:29:29,806 INFO [org.jasig.cas.authentication.PolicyBasedAuthenticationManager] - <Authenticated myuser with credentials [myuser+password].> ==> cas.log <== 2015-09-23 18:29:29,812 DEBUG [org.jasig.cas.authentication.PolicyBasedAuthenticationManager] - Attribute map for myuser: {[email protected], nombre=Nicolás, apellidos=XXXXXXXXX XXXXXXXXX, uid=myuser, movil=XXXXXXXXX, telefono=XXXXXXXXX} ==> catalina.out <== 2015-09-23 18:29:29,812 DEBUG [org.jasig.cas.authentication.PolicyBasedAuthenticationManager] - <Attribute map for myuser: {[email protected], nombre=Nicolás, apellidos=XXXXXXXXX XXXXXXXXX, uid=myuser, movil=XXXXXXXXX, telefono=XXXXXXXXX}> ==> cas.log <== 2015-09-23 18:29:29,814 DEBUG [org.jasig.cas.audit.spi.TicketOrCredentialPrincipalResolver] - Resolving argument [UsernamePasswordCredential] for audit ==> catalina.out <== 2015-09-23 18:29:29,814 DEBUG [org.jasig.cas.audit.spi.TicketOrCredentialPrincipalResolver] - <Resolving argument [UsernamePasswordCredential] for audit> ==> cas.log <== 2015-09-23 18:29:29,817 INFO [org.jasig.inspektr.audit.support.Slf4jLoggingAuditTrailManager] - Audit trail record BEGIN ============================================================= WHO: myuser+password WHAT: supplied credentials: [myuser+password] ACTION: AUTHENTICATION_SUCCESS APPLICATION: CAS WHEN: Wed Sep 23 18:29:29 WEST 2015 CLIENT IP ADDRESS: 192.168.1.111 SERVER IP ADDRESS: 192.168.1.40 ============================================================= 2015-09-23 18:29:29,817 INFO [org.jasig.inspektr.audit.support.Slf4jLoggingAuditTrailManager] - Audit trail record BEGIN ============================================================= WHO: myuser+password WHAT: supplied credentials: [myuser+password] ACTION: AUTHENTICATION_SUCCESS APPLICATION: CAS WHEN: Wed Sep 23 18:29:29 WEST 2015 CLIENT IP ADDRESS: 192.168.1.111 SERVER IP ADDRESS: 192.168.1.40 ============================================================= ==> catalina.out <== 2015-09-23 18:29:29,817 INFO [org.jasig.inspektr.audit.support.Slf4jLoggingAuditTrailManager] - <Audit trail record BEGIN ============================================================= WHO: myuser+password WHAT: supplied credentials: [myuser+password] ACTION: AUTHENTICATION_SUCCESS APPLICATION: CAS WHEN: Wed Sep 23 18:29:29 WEST 2015 CLIENT IP ADDRESS: 192.168.1.111 SERVER IP ADDRESS: 192.168.1.40 ============================================================= > ==> cas.log <== 2015-09-23 18:29:29,824 DEBUG [org.jasig.cas.audit.spi.TicketOrCredentialPrincipalResolver] - Resolving argument [UsernamePasswordCredential] for audit ==> catalina.out <== 2015-09-23 18:29:29,824 DEBUG [org.jasig.cas.audit.spi.TicketOrCredentialPrincipalResolver] - <Resolving argument [UsernamePasswordCredential] for audit> ==> cas.log <== 2015-09-23 18:29:29,825 INFO [org.jasig.inspektr.audit.support.Slf4jLoggingAuditTrailManager] - Audit trail record BEGIN ============================================================= WHO: myuser+password WHAT: No transactional EntityManager available ACTION: TICKET_GRANTING_TICKET_NOT_CREATED APPLICATION: CAS WHEN: Wed Sep 23 18:29:29 WEST 2015 CLIENT IP ADDRESS: 192.168.1.111 SERVER IP ADDRESS: 192.168.1.40 ============================================================= 2015-09-23 18:29:29,825 INFO [org.jasig.inspektr.audit.support.Slf4jLoggingAuditTrailManager] - Audit trail record BEGIN ============================================================= WHO: myuser+password WHAT: No transactional EntityManager available ACTION: TICKET_GRANTING_TICKET_NOT_CREATED APPLICATION: CAS WHEN: Wed Sep 23 18:29:29 WEST 2015 CLIENT IP ADDRESS: 192.168.1.111 SERVER IP ADDRESS: 192.168.1.40 ============================================================= ==> catalina.out <== 2015-09-23 18:29:29,825 INFO [org.jasig.inspektr.audit.support.Slf4jLoggingAuditTrailManager] - <Audit trail record BEGIN ============================================================= WHO: myuser+password WHAT: No transactional EntityManager available ACTION: TICKET_GRANTING_TICKET_NOT_CREATED APPLICATION: CAS WHEN: Wed Sep 23 18:29:29 WEST 2015 CLIENT IP ADDRESS: 192.168.1.111 SERVER IP ADDRESS: 192.168.1.40 ============================================================= > 2015-09-23 18:29:29,827 DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory] - <Returning cached instance of singleton bean 'generateLoginTicketAction'> 2015-09-23 18:29:29,851 DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory] - <Returning cached instance of singleton bean 'requestDataValueProcessor'> 2015-09-23 18:29:29,854 DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory] - <Returning cached instance of singleton bean 'requestDataValueProcessor'>
==> catalina.out <== 2015-09-23 18:07:29,384 DEBUG [org.springframework.binding.mapping.impl.DefaultMapper] - <Beginning mapping between source [org.springframework.webflow.core.collection.LocalParameterMap] and target [org.jasig.cas.authentication.UsernamePasswordCredential]> 2015-09-23 18:07:29,407 DEBUG [org.springframework.binding.mapping.impl.DefaultMapping] - <Adding mapping result [Success@91d8657 mapping = parameter:'username' -> username, code = 'success', error = false, originalValue = 'myuser', mappedValue = 'myuser']> 2015-09-23 18:07:29,418 DEBUG [org.springframework.binding.mapping.impl.DefaultMapping] - <Adding mapping result [Success@715ebe6d mapping = parameter:'password' -> password, code = 'success', error = false, originalValue = '***********', mappedValue = '***********']> 2015-09-23 18:07:29,422 DEBUG [org.springframework.binding.mapping.impl.DefaultMapper] - <Completing mapping between source [org.springframework.webflow.core.collection.LocalParameterMap] and target [org.jasig.cas.authentication.UsernamePasswordCredential]; total mappings = 2; total errors = 0> 2015-09-23 18:07:29,433 DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory] - <Returning cached instance of singleton bean 'authenticationViaFormAction'> ==> cas.log <== 2015-09-23 18:07:29,561 DEBUG [org.jasig.cas.authentication.LdapAuthenticationHandler] - Attempting LDAP authentication for myuser+password ==> catalina.out <== 2015-09-23 18:07:29,561 DEBUG [org.jasig.cas.authentication.LdapAuthenticationHandler] - <Attempting LDAP authentication for myuser+password> ==> cas.log <== 2015-09-23 18:07:29,616 DEBUG [org.jasig.cas.authentication.LdapAuthenticationHandler] - LDAP response: [org.ldaptive.auth.AuthenticationResponse@1946650700::authenticationResultCode=AUTHENTICATION_HANDLER_SUCCESS, ldapEntry=[dn=uid=myuser,cn=...,dc=...,dc=...[[uid[myuser]], [mail[[email protected]]], [sn[XXXXXXXXX XXXXXXXXX]], [cn[Nicolás]], [homePhone[XXXXXXXXX]], [mobile[XXXXXXXXX]]], responseControls=null, messageId=-1], accountState=null, result=true, resultCode=SUCCESS, message=null, controls=null] ==> catalina.out <== 2015-09-23 18:07:29,616 DEBUG [org.jasig.cas.authentication.LdapAuthenticationHandler] - <LDAP response: [org.ldaptive.auth.AuthenticationResponse@1946650700::authenticationResultCode=AUTHENTICATION_HANDLER_SUCCESS, ldapEntry=[dn=uid=myuser,cn=...,dc=...,dc=...[[uid[myuser]], [mail[[email protected]]], [sn[XXXXXXXXX XXXXXXXXX]], [cn[Nicolás]], [homePhone[XXXXXXXXX]], [mobile[XXXXXXXXX]]], responseControls=null, messageId=-1], accountState=null, result=true, resultCode=SUCCESS, message=null, controls=null]> ==> cas.log <== 2015-09-23 18:07:29,618 DEBUG [org.jasig.cas.authentication.LdapAuthenticationHandler] - Found principal attribute: [mail[[email protected]]] ==> catalina.out <== 2015-09-23 18:07:29,618 DEBUG [org.jasig.cas.authentication.LdapAuthenticationHandler] - <Found principal attribute: [mail[[email protected]]]> ==> cas.log <== 2015-09-23 18:07:29,619 DEBUG [org.jasig.cas.authentication.LdapAuthenticationHandler] - Found principal attribute: [cn[Nicolás]] ==> catalina.out <== 2015-09-23 18:07:29,619 DEBUG [org.jasig.cas.authentication.LdapAuthenticationHandler] - <Found principal attribute: [cn[Nicolás]]> ==> cas.log <== 2015-09-23 18:07:29,620 DEBUG [org.jasig.cas.authentication.LdapAuthenticationHandler] - Found principal attribute: [sn[XXXXXXXXX XXXXXXXXX]] ==> catalina.out <== 2015-09-23 18:07:29,620 DEBUG [org.jasig.cas.authentication.LdapAuthenticationHandler] - <Found principal attribute: [sn[XXXXXXXXX XXXXXXXXX]]> ==> cas.log <== 2015-09-23 18:07:29,621 DEBUG [org.jasig.cas.authentication.LdapAuthenticationHandler] - Found principal attribute: [uid[myuser]] ==> catalina.out <== 2015-09-23 18:07:29,621 DEBUG [org.jasig.cas.authentication.LdapAuthenticationHandler] - <Found principal attribute: [uid[myuser]]> ==> cas.log <== 2015-09-23 18:07:29,622 DEBUG [org.jasig.cas.authentication.LdapAuthenticationHandler] - Found principal attribute: [mobile[XXXXXXXXX]] ==> catalina.out <== 2015-09-23 18:07:29,622 DEBUG [org.jasig.cas.authentication.LdapAuthenticationHandler] - <Found principal attribute: [mobile[XXXXXXXXX]]> ==> cas.log <== 2015-09-23 18:07:29,623 DEBUG [org.jasig.cas.authentication.LdapAuthenticationHandler] - Found principal attribute: [homePhone[XXXXXXXXX]] ==> catalina.out <== 2015-09-23 18:07:29,623 DEBUG [org.jasig.cas.authentication.LdapAuthenticationHandler] - <Found principal attribute: [homePhone[XXXXXXXXX]]> ==> cas.log <== 2015-09-23 18:07:29,644 INFO [org.jasig.cas.authentication.PolicyBasedAuthenticationManager] - LdapAuthenticationHandler successfully authenticated myuser+password ==> catalina.out <== 2015-09-23 18:07:29,644 INFO [org.jasig.cas.authentication.PolicyBasedAuthenticationManager] - <LdapAuthenticationHandler successfully authenticated myuser+password> ==> cas.log <== 2015-09-23 18:07:29,646 DEBUG [org.jasig.cas.authentication.PolicyBasedAuthenticationManager] - No resolver configured for LdapAuthenticationHandler. Falling back to handler principal myuser ==> catalina.out <== 2015-09-23 18:07:29,646 DEBUG [org.jasig.cas.authentication.PolicyBasedAuthenticationManager] - <No resolver configured for LdapAuthenticationHandler. Falling back to handler principal myuser> ==> cas.log <== 2015-09-23 18:07:29,704 INFO [org.jasig.cas.authentication.PolicyBasedAuthenticationManager] - Authenticated myuser with credentials [myuser+password]. ==> catalina.out <== 2015-09-23 18:07:29,704 INFO [org.jasig.cas.authentication.PolicyBasedAuthenticationManager] - <Authenticated myuser with credentials [myuser+password].> ==> cas.log <== 2015-09-23 18:07:29,715 DEBUG [org.jasig.cas.authentication.PolicyBasedAuthenticationManager] - Attribute map for myuser: {[email protected], nombre=Nicolás, apellidos=XXXXXXXXX XXXXXXXXX, uid=myuser, movil=XXXXXXXXX, telefono=XXXXXXXXX} ==> catalina.out <== 2015-09-23 18:07:29,715 DEBUG [org.jasig.cas.authentication.PolicyBasedAuthenticationManager] - <Attribute map for myuser: {[email protected], nombre=Nicolás, apellidos=XXXXXXXXX XXXXXXXXX, uid=myuser, movil=XXXXXXXXX, telefono=XXXXXXXXX}> ==> cas.log <== 2015-09-23 18:07:29,717 DEBUG [org.jasig.cas.audit.spi.TicketOrCredentialPrincipalResolver] - Resolving argument [UsernamePasswordCredential] for audit ==> catalina.out <== 2015-09-23 18:07:29,717 DEBUG [org.jasig.cas.audit.spi.TicketOrCredentialPrincipalResolver] - <Resolving argument [UsernamePasswordCredential] for audit> ==> cas.log <== 2015-09-23 18:07:29,724 INFO [org.jasig.inspektr.audit.support.Slf4jLoggingAuditTrailManager] - Audit trail record BEGIN ============================================================= WHO: myuser+password WHAT: supplied credentials: [myuser+password] ACTION: AUTHENTICATION_SUCCESS APPLICATION: CAS WHEN: Wed Sep 23 18:07:29 WEST 2015 CLIENT IP ADDRESS: 192.168.1.X SERVER IP ADDRESS: 192.168.1.Y ============================================================= 2015-09-23 18:07:29,724 INFO [org.jasig.inspektr.audit.support.Slf4jLoggingAuditTrailManager] - Audit trail record BEGIN ============================================================= WHO: myuser+password WHAT: supplied credentials: [myuser+password] ACTION: AUTHENTICATION_SUCCESS APPLICATION: CAS WHEN: Wed Sep 23 18:07:29 WEST 2015 CLIENT IP ADDRESS: 192.168.1.X SERVER IP ADDRESS: 192.168.1.Y ============================================================= ==> catalina.out <== 2015-09-23 18:07:29,724 INFO [org.jasig.inspektr.audit.support.Slf4jLoggingAuditTrailManager] - <Audit trail record BEGIN ============================================================= WHO: myuser+password WHAT: supplied credentials: [myuser+password] ACTION: AUTHENTICATION_SUCCESS APPLICATION: CAS WHEN: Wed Sep 23 18:07:29 WEST 2015 CLIENT IP ADDRESS: 192.168.1.X SERVER IP ADDRESS: 192.168.1.Y ============================================================= > 2015-09-23 18:07:29,731 DEBUG [org.springframework.orm.jpa.JpaTransactionManager] - <Creating new transaction with name [org.jasig.cas.ticket.registry.JpaTicketRegistry.addTicket]: PROPAGATION_REQUIRED,ISOLATION_DEFAULT; ''> 2015-09-23 18:07:29,732 DEBUG [org.springframework.orm.jpa.JpaTransactionManager] - <Opened new EntityManager [org.hibernate.jpa.internal.EntityManagerImpl@d3f4bbe] for JPA transaction> 2015-09-23 18:07:29,734 DEBUG [org.springframework.orm.jpa.JpaTransactionManager] - <Exposing JPA transaction as JDBC transaction [org.springframework.orm.jpa.vendor.HibernateJpaDialect$HibernateConnectionHandle@58d266ab]> ==> cas.log <== 2015-09-23 18:07:29,786 DEBUG [org.jasig.cas.ticket.registry.JpaTicketRegistry] - Added ticket [TGT-**********************************************qIPy4QXAJz-mydomain.com] to registry. ==> catalina.out <== 2015-09-23 18:07:29,786 DEBUG [org.jasig.cas.ticket.registry.JpaTicketRegistry] - <Added ticket [TGT-**********************************************qIPy4QXAJz-mydomain.com] to registry.> 2015-09-23 18:07:29,787 DEBUG [org.springframework.orm.jpa.JpaTransactionManager] - <Initiating transaction commit> 2015-09-23 18:07:29,787 DEBUG [org.springframework.orm.jpa.JpaTransactionManager] - <Committing JPA transaction on EntityManager [org.hibernate.jpa.internal.EntityManagerImpl@d3f4bbe]> Hibernate: insert into TICKETGRANTINGTICKET (NUMBER_OF_TIMES_USED, CREATION_TIME, EXPIRATION_POLICY, LAST_TIME_USED, PREVIOUS_LAST_TIME_USED, ticketGrantingTicket_ID, AUTHENTICATION, EXPIRED, PROXIED_BY, SERVICES_GRANTED_ACCESS_TO, SUPPLEMENTAL_AUTHENTICATIONS, ID) values (?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?) Hibernate: update TICKETGRANTINGTICKET set NUMBER_OF_TIMES_USED=?, CREATION_TIME=?, EXPIRATION_POLICY=?, LAST_TIME_USED=?, PREVIOUS_LAST_TIME_USED=?, ticketGrantingTicket_ID=?, AUTHENTICATION=?, EXPIRED=?, PROXIED_BY=?, SERVICES_GRANTED_ACCESS_TO=?, SUPPLEMENTAL_AUTHENTICATIONS=? where ID=? 2015-09-23 18:07:29,856 DEBUG [org.springframework.orm.jpa.JpaTransactionManager] - <Closing JPA EntityManager [org.hibernate.jpa.internal.EntityManagerImpl@d3f4bbe] after transaction> 2015-09-23 18:07:29,856 DEBUG [org.springframework.orm.jpa.EntityManagerFactoryUtils] - <Closing JPA EntityManager> ==> cas.log <== 2015-09-23 18:07:29,859 DEBUG [org.jasig.cas.audit.spi.TicketOrCredentialPrincipalResolver] - Resolving argument [UsernamePasswordCredential] for audit ==> catalina.out <== 2015-09-23 18:07:29,859 DEBUG [org.jasig.cas.audit.spi.TicketOrCredentialPrincipalResolver] - <Resolving argument [UsernamePasswordCredential] for audit> ==> cas.log <== 2015-09-23 18:07:29,862 INFO [org.jasig.inspektr.audit.support.Slf4jLoggingAuditTrailManager] - Audit trail record BEGIN ============================================================= WHO: myuser+password WHAT: TGT-**********************************************qIPy4QXAJz-mydomain.com ACTION: TICKET_GRANTING_TICKET_CREATED APPLICATION: CAS WHEN: Wed Sep 23 18:07:29 WEST 2015 CLIENT IP ADDRESS: 192.168.1.X SERVER IP ADDRESS: 192.168.1.Y ============================================================= 2015-09-23 18:07:29,862 INFO [org.jasig.inspektr.audit.support.Slf4jLoggingAuditTrailManager] - Audit trail record BEGIN ============================================================= WHO: myuser+password WHAT: TGT-**********************************************qIPy4QXAJz-mydomain.com ACTION: TICKET_GRANTING_TICKET_CREATED APPLICATION: CAS WHEN: Wed Sep 23 18:07:29 WEST 2015 CLIENT IP ADDRESS: 192.168.1.X SERVER IP ADDRESS: 192.168.1.Y ============================================================= ==> catalina.out <== 2015-09-23 18:07:29,862 INFO [org.jasig.inspektr.audit.support.Slf4jLoggingAuditTrailManager] - <Audit trail record BEGIN ============================================================= WHO: myuser+password WHAT: TGT-**********************************************qIPy4QXAJz-mydomain.com ACTION: TICKET_GRANTING_TICKET_CREATED APPLICATION: CAS WHEN: Wed Sep 23 18:07:29 WEST 2015 CLIENT IP ADDRESS: 192.168.1.X SERVER IP ADDRESS: 192.168.1.Y ============================================================= > ==> cas.log <== 2015-09-23 18:07:29,866 DEBUG [org.jasig.cas.web.support.CookieRetrievingCookieGenerator] - Removed cookie with name [CASPRIVACY] ==> catalina.out <== 2015-09-23 18:07:29,866 DEBUG [org.jasig.cas.web.support.CookieRetrievingCookieGenerator] - <Removed cookie with name [CASPRIVACY]> 2015-09-23 18:07:29,870 DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory] - <Returning cached instance of singleton bean 'sendTicketGrantingTicketAction'> ==> cas.log <== 2015-09-23 18:07:29,876 DEBUG [org.jasig.cas.web.support.DefaultCasCookieValueManager] - Encoding cookie value [TGT-**********************************************[email protected]@Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Ubuntu Chromium/44.0.2403.89 Chrome/44.0.2403.89 Safari/537.36] ==> catalina.out <== 2015-09-23 18:07:29,876 DEBUG [org.jasig.cas.web.support.DefaultCasCookieValueManager] - <Encoding cookie value [TGT-**********************************************[email protected]@Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Ubuntu Chromium/44.0.2403.89 Chrome/44.0.2403.89 Safari/537.36]> ==> cas.log <== 2015-09-23 18:07:29,879 DEBUG [org.jasig.cas.util.DefaultCipherExecutor] - Encrypting via [A128CBC-HS256] ==> catalina.out <== 2015-09-23 18:07:29,879 DEBUG [org.jasig.cas.util.DefaultCipherExecutor] - <Encrypting via [A128CBC-HS256]> ==> cas.log <== 2015-09-23 18:07:29,889 DEBUG [org.jasig.cas.web.support.CookieRetrievingCookieGenerator] - Added cookie with name [TGC] and value [eyJhbGciOiJIUzUxMiJ9.ZXlKaGJHY2lPaUprYVhJaUxDSmxibU1pT2lKQk1USTRRMEpETFVoVE1qVTJJbjAuLnVPWVc0Y1F4YTRFV2hQcEdCUkVWUVEuaFdMN3FMUmhNcU0zTjh6QmZKWkxHbjV5dUNkZlJwYWxJYzZGZEU2NDFoeUxsekptYnFUdWxVZldqaDctOTZCQmdCVXhWeGo3RE5UdVBDNE53aDNxQ1BVRGJfcHVicTZLS1JZRnl6WG5NWm9oMktvakdNMnlVUkNmaWM4bkJ5aE9UWVZTZlNwdC1qY05TdVd5TkFqVjE0U05UeVNsQUVHUEFRVmE1b2NaeWxMNDVZemszZ05aSzNPZFZnTGNycHU2YzhaOFBob1ZfUkpaY3FEeVJBUmNnTUN0aVZsdTNmbHEwWllDMERSaFdlNHZJcVY5OFRzS0VOUjhhQnZ0czVjcEZuRjdmemFZblA3aHdMbWVDX1VrZGNjZGNqdTdQTWVPOGIwcHZtbV80cU1FckZ3ZVhwSFU4aGs1ZEtfd3RYWUIuWENZbUxKMTNJcmZOUF9aenBFTDNMQQ.6eBXMuSfYxqAFhzFmGX4_9sKxSOUr5qkto_eT0IIhSl3llRelT2wCrGsTBmrjh_WBacFslawaQyjQXZbfdpbOg] ==> catalina.out <== 2015-09-23 18:07:29,889 DEBUG [org.jasig.cas.web.support.CookieRetrievingCookieGenerator] - <Added cookie with name [TGC] and value [eyJhbGciOiJIUzUxMiJ9.ZXlKaGJHY2lPaUprYVhJaUxDSmxibU1pT2lKQk1USTRRMEpETFVoVE1qVTJJbjAuLnVPWVc0Y1F4YTRFV2hQcEdCUkVWUVEuaFdMN3FMUmhNcU0zTjh6QmZKWkxHbjV5dUNkZlJwYWxJYzZGZEU2NDFoeUxsekptYnFUdWxVZldqaDctOTZCQmdCVXhWeGo3RE5UdVBDNE53aDNxQ1BVRGJfcHVicTZLS1JZRnl6WG5NWm9oMktvakdNMnlVUkNmaWM4bkJ5aE9UWVZTZlNwdC1qY05TdVd5TkFqVjE0U05UeVNsQUVHUEFRVmE1b2NaeWxMNDVZemszZ05aSzNPZFZnTGNycHU2YzhaOFBob1ZfUkpaY3FEeVJBUmNnTUN0aVZsdTNmbHEwWllDMERSaFdlNHZJcVY5OFRzS0VOUjhhQnZ0czVjcEZuRjdmemFZblA3aHdMbWVDX1VrZGNjZGNqdTdQTWVPOGIwcHZtbV80cU1FckZ3ZVhwSFU4aGs1ZEtfd3RYWUIuWENZbUxKMTNJcmZOUF9aenBFTDNMQQ.6eBXMuSfYxqAFhzFmGX4_9sKxSOUr5qkto_eT0IIhSl3llRelT2wCrGsTBmrjh_WBacFslawaQyjQXZbfdpbOg]> 2015-09-23 18:07:29,892 DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory] - <Returning cached instance of singleton bean 'generateServiceTicketAction'> 2015-09-23 18:07:29,900 DEBUG [org.springframework.orm.jpa.SharedEntityManagerCreator$SharedEntityManagerInvocationHandler] - <Creating new EntityManager for shared EntityManager invocation> 2015-09-23 18:07:29,902 DEBUG [org.springframework.orm.jpa.EntityManagerFactoryUtils] - <Closing JPA EntityManager> ==> cas.log <== 2015-09-23 18:07:29,904 ERROR [org.jasig.cas.ticket.registry.JpaTicketRegistry] - Error getting ticket [TGT-**********************************************qIPy4QXAJz-mydomain.com, javax.persistence.TransactionRequiredException: no transaction is in progress] from registry. ==> catalina.out <== 2015-09-23 18:07:29,904 ERROR [org.jasig.cas.ticket.registry.JpaTicketRegistry] - <Error getting ticket [TGT-**********************************************qIPy4QXAJz-mydomain.com, javax.persistence.TransactionRequiredException: no transaction is in progress] from registry.> ==> cas.log <== 2015-09-23 18:07:29,905 DEBUG [org.jasig.cas.CentralAuthenticationServiceImpl] - Ticket [TGT-**********************************************qIPy4QXAJz-mydomain.com] by type [TicketGrantingTicket] cannot be found in the ticket registry. ==> catalina.out <== 2015-09-23 18:07:29,905 DEBUG [org.jasig.cas.CentralAuthenticationServiceImpl] - <Ticket [TGT-**********************************************qIPy4QXAJz-mydomain.com] by type [TicketGrantingTicket] cannot be found in the ticket registry.> ==> cas.log <== 2015-09-23 18:07:29,909 DEBUG [org.jasig.cas.audit.spi.TicketOrCredentialPrincipalResolver] - Resolving argument [String] for audit ==> catalina.out <== 2015-09-23 18:07:29,909 DEBUG [org.jasig.cas.audit.spi.TicketOrCredentialPrincipalResolver] - <Resolving argument [String] for audit> 2015-09-23 18:07:29,910 DEBUG [org.springframework.orm.jpa.SharedEntityManagerCreator$SharedEntityManagerInvocationHandler] - <Creating new EntityManager for shared EntityManager invocation> 2015-09-23 18:07:29,911 DEBUG [org.springframework.orm.jpa.EntityManagerFactoryUtils] - <Closing JPA EntityManager> ==> cas.log <== 2015-09-23 18:07:29,912 ERROR [org.jasig.cas.ticket.registry.JpaTicketRegistry] - Error getting ticket [TGT-**********************************************qIPy4QXAJz-mydomain.com, javax.persistence.TransactionRequiredException: no transaction is in progress] from registry. ==> catalina.out <== 2015-09-23 18:07:29,912 ERROR [org.jasig.cas.ticket.registry.JpaTicketRegistry] - <Error getting ticket [TGT-**********************************************qIPy4QXAJz-mydomain.com, javax.persistence.TransactionRequiredException: no transaction is in progress] from registry.> ==> cas.log <== 2015-09-23 18:07:29,913 DEBUG [org.jasig.cas.CentralAuthenticationServiceImpl] - Ticket [TGT-**********************************************qIPy4QXAJz-mydomain.com] by type [Ticket] cannot be found in the ticket registry. ==> catalina.out <== 2015-09-23 18:07:29,913 DEBUG [org.jasig.cas.CentralAuthenticationServiceImpl] - <Ticket [TGT-**********************************************qIPy4QXAJz-mydomain.com] by type [Ticket] cannot be found in the ticket registry.> ==> cas.log <== 2015-09-23 18:07:29,923 DEBUG [org.jasig.cas.audit.spi.TicketOrCredentialPrincipalResolver] - Could not locate ticket [TGT-**********************************************qIPy4QXAJz-mydomain.com] in the registry ==> catalina.out <== 2015-09-23 18:07:29,923 DEBUG [org.jasig.cas.audit.spi.TicketOrCredentialPrincipalResolver] - <Could not locate ticket [TGT-**********************************************qIPy4QXAJz-mydomain.com] in the registry> ==> cas.log <== 2015-09-23 18:07:29,924 DEBUG [org.jasig.cas.audit.spi.TicketOrCredentialPrincipalResolver] - Unable to determine the audit argument. Returning [audit:unknown] ==> catalina.out <== 2015-09-23 18:07:29,924 DEBUG [org.jasig.cas.audit.spi.TicketOrCredentialPrincipalResolver] - <Unable to determine the audit argument. Returning [audit:unknown]> ==> cas.log <== 2015-09-23 18:07:29,932 INFO [org.jasig.inspektr.audit.support.Slf4jLoggingAuditTrailManager] - Audit trail record BEGIN ============================================================= WHO: audit:unknown WHAT: https://mydomain.com:8443/cas-management/login/cas ACTION: SERVICE_TICKET_NOT_CREATED APPLICATION: CAS WHEN: Wed Sep 23 18:07:29 WEST 2015 CLIENT IP ADDRESS: 192.168.1.X SERVER IP ADDRESS: 192.168.1.Y ============================================================= 2015-09-23 18:07:29,932 INFO [org.jasig.inspektr.audit.support.Slf4jLoggingAuditTrailManager] - Audit trail record BEGIN ============================================================= WHO: audit:unknown WHAT: https://mydomain.com:8443/cas-management/login/cas ACTION: SERVICE_TICKET_NOT_CREATED APPLICATION: CAS WHEN: Wed Sep 23 18:07:29 WEST 2015 CLIENT IP ADDRESS: 192.168.1.X SERVER IP ADDRESS: 192.168.1.Y ============================================================= ==> catalina.out <== 2015-09-23 18:07:29,932 INFO [org.jasig.inspektr.audit.support.Slf4jLoggingAuditTrailManager] - <Audit trail record BEGIN ============================================================= WHO: audit:unknown WHAT: https://mydomain.com:8443/cas-management/login/cas ACTION: SERVICE_TICKET_NOT_CREATED APPLICATION: CAS WHEN: Wed Sep 23 18:07:29 WEST 2015 CLIENT IP ADDRESS: 192.168.1.X SERVER IP ADDRESS: 192.168.1.Y ============================================================= > ==> cas.log <== 2015-09-23 18:07:29,939 DEBUG [org.jasig.cas.CentralAuthenticationServiceImpl] - Removing ticket [TGT-**********************************************qIPy4QXAJz-mydomain.com] from registry... ==> catalina.out <== 2015-09-23 18:07:29,939 DEBUG [org.jasig.cas.CentralAuthenticationServiceImpl] - <Removing ticket [TGT-**********************************************qIPy4QXAJz-mydomain.com] from registry...> 2015-09-23 18:07:29,940 DEBUG [org.springframework.orm.jpa.SharedEntityManagerCreator$SharedEntityManagerInvocationHandler] - <Creating new EntityManager for shared EntityManager invocation> 2015-09-23 18:07:29,941 DEBUG [org.springframework.orm.jpa.EntityManagerFactoryUtils] - <Closing JPA EntityManager> ==> cas.log <== 2015-09-23 18:07:29,942 ERROR [org.jasig.cas.ticket.registry.JpaTicketRegistry] - Error getting ticket [TGT-**********************************************qIPy4QXAJz-mydomain.com, javax.persistence.TransactionRequiredException: no transaction is in progress] from registry. ==> catalina.out <== 2015-09-23 18:07:29,942 ERROR [org.jasig.cas.ticket.registry.JpaTicketRegistry] - <Error getting ticket [TGT-**********************************************qIPy4QXAJz-mydomain.com, javax.persistence.TransactionRequiredException: no transaction is in progress] from registry.> ==> cas.log <== 2015-09-23 18:07:29,949 DEBUG [org.jasig.cas.CentralAuthenticationServiceImpl] - Ticket [TGT-**********************************************qIPy4QXAJz-mydomain.com] by type [TicketGrantingTicket] cannot be found in the ticket registry. ==> catalina.out <== 2015-09-23 18:07:29,949 DEBUG [org.jasig.cas.CentralAuthenticationServiceImpl] - <Ticket [TGT-**********************************************qIPy4QXAJz-mydomain.com] by type [TicketGrantingTicket] cannot be found in the ticket registry.> ==> cas.log <== 2015-09-23 18:07:29,950 DEBUG [org.jasig.cas.CentralAuthenticationServiceImpl] - TicketGrantingTicket [TGT-**********************************************qIPy4QXAJz-mydomain.com] cannot be found in the ticket registry. ==> catalina.out <== 2015-09-23 18:07:29,950 DEBUG [org.jasig.cas.CentralAuthenticationServiceImpl] - <TicketGrantingTicket [TGT-**********************************************qIPy4QXAJz-mydomain.com] cannot be found in the ticket registry.> ==> cas.log <== 2015-09-23 18:07:29,951 DEBUG [org.jasig.cas.audit.spi.TicketOrCredentialPrincipalResolver] - Resolving argument [String] for audit ==> catalina.out <== 2015-09-23 18:07:29,951 DEBUG [org.jasig.cas.audit.spi.TicketOrCredentialPrincipalResolver] - <Resolving argument [String] for audit> 2015-09-23 18:07:29,952 DEBUG [org.springframework.orm.jpa.SharedEntityManagerCreator$SharedEntityManagerInvocationHandler] - <Creating new EntityManager for shared EntityManager invocation> 2015-09-23 18:07:29,954 DEBUG [org.springframework.orm.jpa.EntityManagerFactoryUtils] - <Closing JPA EntityManager> ==> cas.log <== 2015-09-23 18:07:29,958 ERROR [org.jasig.cas.ticket.registry.JpaTicketRegistry] - Error getting ticket [TGT-**********************************************qIPy4QXAJz-mydomain.com, javax.persistence.TransactionRequiredException: no transaction is in progress] from registry. ==> catalina.out <== 2015-09-23 18:07:29,958 ERROR [org.jasig.cas.ticket.registry.JpaTicketRegistry] - <Error getting ticket [TGT-**********************************************qIPy4QXAJz-mydomain.com, javax.persistence.TransactionRequiredException: no transaction is in progress] from registry.> ==> cas.log <== 2015-09-23 18:07:29,959 DEBUG [org.jasig.cas.CentralAuthenticationServiceImpl] - Ticket [TGT-**********************************************qIPy4QXAJz-mydomain.com] by type [Ticket] cannot be found in the ticket registry. ==> catalina.out <== 2015-09-23 18:07:29,959 DEBUG [org.jasig.cas.CentralAuthenticationServiceImpl] - <Ticket [TGT-**********************************************qIPy4QXAJz-mydomain.com] by type [Ticket] cannot be found in the ticket registry.> ==> cas.log <== 2015-09-23 18:07:29,970 DEBUG [org.jasig.cas.audit.spi.TicketOrCredentialPrincipalResolver] - Could not locate ticket [TGT-**********************************************qIPy4QXAJz-mydomain.com] in the registry ==> catalina.out <== 2015-09-23 18:07:29,970 DEBUG [org.jasig.cas.audit.spi.TicketOrCredentialPrincipalResolver] - <Could not locate ticket [TGT-**********************************************qIPy4QXAJz-mydomain.com] in the registry> ==> cas.log <== 2015-09-23 18:07:29,971 DEBUG [org.jasig.cas.audit.spi.TicketOrCredentialPrincipalResolver] - Unable to determine the audit argument. Returning [audit:unknown] ==> catalina.out <== 2015-09-23 18:07:29,971 DEBUG [org.jasig.cas.audit.spi.TicketOrCredentialPrincipalResolver] - <Unable to determine the audit argument. Returning [audit:unknown]> ==> cas.log <== 2015-09-23 18:07:29,972 INFO [org.jasig.inspektr.audit.support.Slf4jLoggingAuditTrailManager] - Audit trail record BEGIN ============================================================= WHO: audit:unknown WHAT: TGT-**********************************************qIPy4QXAJz-mydomain.com ACTION: TICKET_GRANTING_TICKET_DESTROYED APPLICATION: CAS WHEN: Wed Sep 23 18:07:29 WEST 2015 CLIENT IP ADDRESS: 192.168.1.X SERVER IP ADDRESS: 192.168.1.Y ============================================================= 2015-09-23 18:07:29,972 INFO [org.jasig.inspektr.audit.support.Slf4jLoggingAuditTrailManager] - Audit trail record BEGIN ============================================================= WHO: audit:unknown WHAT: TGT-**********************************************qIPy4QXAJz-mydomain.com ACTION: TICKET_GRANTING_TICKET_DESTROYED APPLICATION: CAS WHEN: Wed Sep 23 18:07:29 WEST 2015 CLIENT IP ADDRESS: 192.168.1.X SERVER IP ADDRESS: 192.168.1.Y ============================================================= ==> catalina.out <== 2015-09-23 18:07:29,972 INFO [org.jasig.inspektr.audit.support.Slf4jLoggingAuditTrailManager] - <Audit trail record BEGIN ============================================================= WHO: audit:unknown WHAT: TGT-**********************************************qIPy4QXAJz-mydomain.com ACTION: TICKET_GRANTING_TICKET_DESTROYED APPLICATION: CAS WHEN: Wed Sep 23 18:07:29 WEST 2015 CLIENT IP ADDRESS: 192.168.1.X SERVER IP ADDRESS: 192.168.1.Y ============================================================= > 2015-09-23 18:07:29,974 DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory] - <Returning cached instance of singleton bean 'generateLoginTicketAction'> 2015-09-23 18:07:29,992 DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory] - <Returning cached instance of singleton bean 'requestDataValueProcessor'> 2015-09-23 18:07:29,998 DEBUG [org.springframework.beans.factory.support.DefaultListableBeanFactory] - <Returning cached instance of singleton bean 'requestDataValueProcessor'>
