[jboss-dev-forums] [JBoss AS7 Development] - Authentication issue in AS7

From the trace output it seems as though the DatabaseServerLoginModule is successfully authenticating, but I'm redirected back to the login page and it then indicate that authentication fails.

11:05:23,550 TRACE [org.jboss.security.auth.spi.DatabaseServerLoginModule] (http-localhost-127.0.0.1-8080-1) resumeAnyTransaction 11:05:23,550 TRACE [org.jboss.security.auth.spi.DatabaseServerLoginModule] (http-localhost-127.0.0.1-8080-1) User 'rich' authenticated, loginOk=true 11:05:23,550 TRACE [org.jboss.security.auth.spi.DatabaseServerLoginModule] (http-localhost-127.0.0.1-8080-1) commit, loginOk=true 11:05:23,550 TRACE [org.jboss.security.auth.spi.DatabaseServerLoginModule] (http-localhost-127.0.0.1-8080-1) getRoleSets using rolesQuery: SELECT role, 'Roles' FROM role WHERE username=?, username: rich 11:05:23,551 TRACE [org.jboss.modules] (http-localhost-127.0.0.1-8080-1) Finding class org.jboss.tm.TransactionManagerLocator from Module "deployment.fizio.ear.jsf-web.war:main" from Service Module Loader 11:05:23,551 TRACE [org.jboss.modules] (http-localhost-127.0.0.1-8080-1) Class org.jboss.tm.TransactionManagerLocator not found from Module "deployment.fizio.ear.jsf-web.war:main" from Service Module Loader 11:05:23,551 TRACE [org.jboss.modules] (http-localhost-127.0.0.1-8080-1) Finding class org.jboss.modules.ModuleClassLoader from Module "deployment.fizio.ear.jsf-web.war:main" from Service Module Loader 11:05:23,551 TRACE [org.jboss.modules] (http-localhost-127.0.0.1-8080-1) Class org.jboss.modules.ModuleClassLoader not found from Module "deployment.fizio.ear.jsf-web.war:main" from Service Module Loader 11:05:23,551 TRACE [org.jboss.modules] (http-localhost-127.0.0.1-8080-1) Finding class org.jboss.modules.ModuleClassLoader from Module "org.jboss.logmanager:main" from local module loader @17386918 (roots: /Users/rich/Documents/jboss-as/build/target/jboss-7.0.0.Beta4-SNAPSHOT/modules) 11:05:23,551 TRACE [org.jboss.modules] (http-localhost-127.0.0.1-8080-1) Class org.jboss.modules.ModuleClassLoader not found from Module "org.jboss.logmanager:main" from local module loader @17386918 (roots: /Users/rich/Documents/jboss-as/build/target/jboss-7.0.0.Beta4-SNAPSHOT/modules) 11:05:23,551 TRACE [org.jboss.modules] (http-localhost-127.0.0.1-8080-1) Finding class org.jboss.modules.ConcurrentClassLoader from Module "deployment.fizio.ear.jsf-web.war:main" from Service Module Loader 11:05:23,551 TRACE [org.jboss.modules] (http-localhost-127.0.0.1-8080-1) Class org.jboss.modules.ConcurrentClassLoader not found from Module "deployment.fizio.ear.jsf-web.war:main" from Service Module Loader 11:05:23,551 TRACE [org.jboss.modules] (http-localhost-127.0.0.1-8080-1) Finding class org.jboss.modules.ConcurrentClassLoader from Module "org.jboss.logmanager:main" from local module loader @17386918 (roots: /Users/rich/Documents/jboss-as/build/target/jboss-7.0.0.Beta4-SNAPSHOT/modules) 11:05:23,551 TRACE [org.jboss.modules] (http-localhost-127.0.0.1-8080-1) Class org.jboss.modules.ConcurrentClassLoader not found from Module "org.jboss.logmanager:main" from local module loader @17386918 (roots: /Users/rich/Documents/jboss-as/build/target/jboss-7.0.0.Beta4-SNAPSHOT/modules) 11:05:23,552 TRACE [org.jboss.modules] (http-localhost-127.0.0.1-8080-1) Finding class org.jboss.security.auth.spi.DbUtil from Module "deployment.fizio.ear.jsf-web.war:main" from Service Module Loader 11:05:23,552 TRACE [org.jboss.modules] (http-localhost-127.0.0.1-8080-1) Finding local class org.jboss.security.auth.spi.DbUtil from Module "org.picketbox:main" from local module loader @17386918 (roots: /Users/rich/Documents/jboss-as/build/target/jboss-7.0.0.Beta4-SNAPSHOT/modules) 11:05:23,552 TRACE [org.jboss.modules] (http-localhost-127.0.0.1-8080-1) Found previously loaded class org.jboss.security.auth.spi.DbUtil from Module "org.picketbox:main" from local module loader @17386918 (roots: /Users/rich/Documents/jboss-as/build/target/jboss-7.0.0.Beta4-SNAPSHOT/modules) 11:05:23,552 TRACE [org.jboss.modules] (http-localhost-127.0.0.1-8080-1) Finding class org.jboss.security.auth.spi.Util from Module "deployment.fizio.ear.jsf-web.war:main" from Service Module Loader 11:05:23,552 TRACE [org.jboss.modules] (http-localhost-127.0.0.1-8080-1) Finding local class org.jboss.security.auth.spi.Util from Module "org.picketbox:main" from local module loader @17386918 (roots: /Users/rich/Documents/jboss-as/build/target/jboss-7.0.0.Beta4-SNAPSHOT/modules) 11:05:23,552 TRACE [org.jboss.modules] (http-localhost-127.0.0.1-8080-1) Found previously loaded class org.jboss.security.auth.spi.Util from Module "org.picketbox:main" from local module loader @17386918 (roots: /Users/rich/Documents/jboss-as/build/target/jboss-7.0.0.Beta4-SNAPSHOT/modules) 11:05:23,552 TRACE [org.jboss.modules] (http-localhost-127.0.0.1-8080-1) Finding class org.jboss.security.auth.spi.AbstractServerLoginModule from Module "deployment.fizio.ear.jsf-web.war:main" from Service Module Loader 11:05:23,552 TRACE [org.jboss.modules] (http-localhost-127.0.0.1-8080-1) Finding local class org.jboss.security.auth.spi.AbstractServerLoginModule from Module "org.picketbox:main" from local module loader @17386918 (roots: /Users/rich/Documents/jboss-as/build/target/jboss-7.0.0.Beta4-SNAPSHOT/modules) 11:05:23,552 TRACE [org.jboss.modules] (http-localhost-127.0.0.1-8080-1) Found previously loaded class org.jboss.security.auth.spi.AbstractServerLoginModule from Module "org.picketbox:main" from local module loader @17386918 (roots: /Users/rich/Documents/jboss-as/build/target/jboss-7.0.0.Beta4-SNAPSHOT/modules) 11:05:23,551 TRACE [org.jboss.security.plugins.TransactionManagerLocator] (http-localhost-127.0.0.1-8080-1) Exception in getJBossTM:: java.lang.ClassNotFoundException: org.jboss.tm.TransactionManagerLocator from [Module "deployment.fizio.ear.jsf-web.war:main" from Service Module Loader]           at org.jboss.modules.ModuleClassLoader.findClass(ModuleClassLoader.java:191)           at org.jboss.modules.ConcurrentClassLoader.performLoadClassChecked(ConcurrentClassLoader.java:358)           at org.jboss.modules.ConcurrentClassLoader.performLoadClass(ConcurrentClassLoader.java:307)           at org.jboss.modules.ConcurrentClassLoader.loadClass(ConcurrentClassLoader.java:101)           at org.jboss.security.plugins.TransactionManagerLocator.getJBossTM(TransactionManagerLocator.java:86) [picketbox-4.0.0.CR1.jar:4.0.0.CR1]           at org.jboss.security.plugins.TransactionManagerLocator.getTM(TransactionManagerLocator.java:70) [picketbox-4.0.0.CR1.jar:4.0.0.CR1]           at org.jboss.security.auth.spi.DbUtil.getRoleSets(DbUtil.java:75) [picketbox-4.0.0.CR1.jar:4.0.0.CR1]           at org.jboss.security.auth.spi.Util.getRoleSets(Util.java:157) [picketbox-4.0.0.CR1.jar:4.0.0.CR1]           at org.jboss.security.auth.spi.DatabaseServerLoginModule.getRoleSets(DatabaseServerLoginModule.java:264) [picketbox-4.0.0.CR1.jar:4.0.0.CR1]           at org.jboss.security.auth.spi.AbstractServerLoginModule.commit(AbstractServerLoginModule.java:228) [picketbox-4.0.0.CR1.jar:4.0.0.CR1]           at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) [:1.6.0_22]           at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39) [:1.6.0_22]           at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25) [:1.6.0_22]           at java.lang.reflect.Method.invoke(Method.java:597) [:1.6.0_22]           at javax.security.auth.login.LoginContext.invoke(LoginContext.java:769) [:1.6.0_22]           at javax.security.auth.login.LoginContext.access$000(LoginContext.java:186) [:1.6.0_22]           at javax.security.auth.login.LoginContext$4.run(LoginContext.java:683) [:1.6.0_22]           at java.security.AccessController.doPrivileged(Native Method) [:1.6.0_22]           at javax.security.auth.login.LoginContext.invokePriv(LoginContext.java:680) [:1.6.0_22]           at javax.security.auth.login.LoginContext.login(LoginContext.java:580) [:1.6.0_22]           at org.jboss.security.authentication.JBossCachedAuthenticationManager.defaultLogin(JBossCachedAuthenticationManager.java:411) [picketbox-infinispan-4.0.0.CR1.jar:4.0.0.CR1]           at org.jboss.security.authentication.JBossCachedAuthenticationManager.authenticate(JBossCachedAuthenticationManager.java:345) [picketbox-infinispan-4.0.0.CR1.jar:4.0.0.CR1]           at org.jboss.security.authentication.JBossCachedAuthenticationManager.isValid(JBossCachedAuthenticationManager.java:154) [picketbox-infinispan-4.0.0.CR1.jar:4.0.0.CR1]           at org.jboss.as.web.security.JBossWebRealm.authenticate(JBossWebRealm.java:127) [jboss-as-web-7.0.0.Beta4-SNAPSHOT.jar:7.0.0.Beta4-SNAPSHOT]           at org.apache.catalina.authenticator.FormAuthenticator.authenticate(FormAuthenticator.java:280) [jbossweb-7.0.0.CR4.jar:7.0.0.Beta4-SNAPSHOT]           at org.apache.catalina.authenticator.AuthenticatorBase.invoke(AuthenticatorBase.java:372) [jbossweb-7.0.0.CR4.jar:7.0.0.Beta4-SNAPSHOT]           at org.jboss.as.web.NamingValve.invoke(NamingValve.java:57) [jboss-as-web-7.0.0.Beta4-SNAPSHOT.jar:7.0.0.Beta4-SNAPSHOT]           at org.jboss.as.jpa.interceptor.WebNonTxEmCloserValve.invoke(WebNonTxEmCloserValve.java:49) [jboss-as-jpa-7.0.0.Beta4-SNAPSHOT.jar:7.0.0.Beta4-SNAPSHOT]           at org.apache.catalina.core.StandardHostValve.invoke(StandardHostValve.java:154) [jbossweb-7.0.0.CR4.jar:7.0.0.Beta4-SNAPSHOT]           at org.apache.catalina.valves.ErrorReportValve.invoke(ErrorReportValve.java:102) [jbossweb-7.0.0.CR4.jar:7.0.0.Beta4-SNAPSHOT]                     at org.apache.catalina.core.StandardEngineValve.invoke(StandardEngineValve.java:109) [jbossweb-7.0.0.CR4.jar:7.0.0.Beta4-SNAPSHOT]           at org.apache.catalina.connector.CoyoteAdapter.service(CoyoteAdapter.java:362) [jbossweb-7.0.0.CR4.jar:7.0.0.Beta4-SNAPSHOT]           at org.apache.coyote.http11.Http11Processor.process(Http11Processor.java:877) [jbossweb-7.0.0.CR4.jar:7.0.0.Beta4-SNAPSHOT]           at org.apache.coyote.http11.Http11Protocol$Http11ConnectionHandler.process(Http11Protocol.java:667) [jbossweb-7.0.0.CR4.jar:7.0.0.Beta4-SNAPSHOT]           at org.apache.tomcat.util.net.JIoEndpoint$Worker.run(JIoEndpoint.java:951) [jbossweb-7.0.0.CR4.jar:7.0.0.Beta4-SNAPSHOT]           at java.lang.Thread.run(Thread.java:680) [:1.6.0_22] 11:05:23,554 TRACE [org.jboss.security.auth.spi.DatabaseServerLoginModule] (http-localhost-127.0.0.1-8080-1) suspendAnyTransaction 11:05:23,554 TRACE [org.jboss.jca.core.connectionmanager.TxConnectionManager] (http-localhost-127.0.0.1-8080-1) Subject: null 11:05:23,554 TRACE [org.jboss.jca.core.connectionmanager.TxConnectionManager] (http-localhost-127.0.0.1-8080-1) getManagedConnection interleaving=false , tx=null 11:05:23,555 TRACE [org.jboss.jca.core.connectionmanager.pool.strategy.OnePool] (http-localhost-127.0.0.1-8080-1) supplying ManagedConnection from pool: org.jboss.jca.core.connectionmanager.listener.TxConnectionListener@2f057e4f[state=NORMAL managed connection=org.jboss.jca.adapters.jdbc.local.LocalManagedConnection@11a78ca7 connection handles=0 lastUse=1308996323550 trackByTx=false pool=org.jboss.jca.core.connectionmanager.pool.strategy.OnePool@41556f4c pool internal context=org.jboss.jca.core.connectionmanager.pool.mcp.SemaphoreArrayListManagedConnectionPool@f0330ff xaResource=org.jboss.jca.core.tx.jbossts.LocalXAResourceImpl@1a85bd0c txSync=null] 11:05:23,555 TRACE [org.jboss.jca.core.connectionmanager.pool.strategy.OnePool] (http-localhost-127.0.0.1-8080-1) Got connection from pool: org.jboss.jca.core.connectionmanager.listener.TxConnectionListener@2f057e4f[state=NORMAL managed connection=org.jboss.jca.adapters.jdbc.local.LocalManagedConnection@11a78ca7 connection handles=0 lastUse=1308996323550 trackByTx=false pool=org.jboss.jca.core.connectionmanager.pool.strategy.OnePool@41556f4c pool internal context=org.jboss.jca.core.connectionmanager.pool.mcp.SemaphoreArrayListManagedConnectionPool@f0330ff xaResource=org.jboss.jca.core.tx.jbossts.LocalXAResourceImpl@1a85bd0c txSync=null] 11:05:23,555 TRACE [org.jboss.jca.core.connectionmanager.listener.TxConnectionListener] (http-localhost-127.0.0.1-8080-1) No transaction, no need to enlist: org.jboss.jca.core.connectionmanager.listener.TxConnectionListener@2f057e4f[state=NORMAL managed connection=org.jboss.jca.adapters.jdbc.local.LocalManagedConnection@11a78ca7 connection handles=0 lastUse=1308996323550 trackByTx=false pool=org.jboss.jca.core.connectionmanager.pool.strategy.OnePool@41556f4c pool internal context=org.jboss.jca.core.connectionmanager.pool.mcp.SemaphoreArrayListManagedConnectionPool@f0330ff xaResource=org.jboss.jca.core.tx.jbossts.LocalXAResourceImpl@1a85bd0c txSync=null] 11:05:23,555 TRACE [org.jboss.security.auth.spi.DatabaseServerLoginModule] (http-localhost-127.0.0.1-8080-1) Excuting query: SELECT role, 'Roles' FROM role WHERE username=?, with username: rich 11:05:23,556 TRACE [org.jboss.security.auth.spi.DatabaseServerLoginModule] (http-localhost-127.0.0.1-8080-1) Assign user to role admin 11:05:23,556 TRACE [org.jboss.jca.core.connectionmanager.listener.TxConnectionListener] (http-localhost-127.0.0.1-8080-1) connectionClosed called mc=org.jboss.jca.adapters.jdbc.local.LocalManagedConnection@11a78ca7 11:05:23,556 TRACE [org.jboss.jca.core.connectionmanager.listener.TxConnectionListener] (http-localhost-127.0.0.1-8080-1) unregisterConnection: 0 handles left 11:05:23,557 TRACE [org.jboss.jca.core.connectionmanager.listener.TxConnectionListener] (http-localhost-127.0.0.1-8080-1) delisting org.jboss.jca.core.connectionmanager.listener.TxConnectionListener@2f057e4f[state=NORMAL managed connection=org.jboss.jca.adapters.jdbc.local.LocalManagedConnection@11a78ca7 connection handles=0 lastUse=1308996323550 trackByTx=false pool=org.jboss.jca.core.connectionmanager.pool.strategy.OnePool@41556f4c pool internal context=org.jboss.jca.core.connectionmanager.pool.mcp.SemaphoreArrayListManagedConnectionPool@f0330ff xaResource=org.jboss.jca.core.tx.jbossts.LocalXAResourceImpl@1a85bd0c txSync=null] 11:05:23,557 TRACE [org.jboss.jca.core.connectionmanager.listener.TxConnectionListener] (http-localhost-127.0.0.1-8080-1) isManagedConnectionFree=true mc=org.jboss.jca.adapters.jdbc.local.LocalManagedConnection@11a78ca7 11:05:23,557 TRACE [org.jboss.jca.core.connectionmanager.pool.strategy.OnePool] (http-localhost-127.0.0.1-8080-1) putting ManagedConnection back into pool kill=false cl=org.jboss.jca.core.connectionmanager.listener.TxConnectionListener@2f057e4f[state=NORMAL managed connection=org.jboss.jca.adapters.jdbc.local.LocalManagedConnection@11a78ca7 connection handles=0 lastUse=1308996323550 trackByTx=false pool=org.jboss.jca.core.connectionmanager.pool.strategy.OnePool@41556f4c pool internal context=org.jboss.jca.core.connectionmanager.pool.mcp.SemaphoreArrayListManagedConnectionPool@f0330ff xaResource=org.jboss.jca.core.tx.jbossts.LocalXAResourceImpl@1a85bd0c txSync=null] 11:05:23,557 TRACE [org.jboss.jca.core.connectionmanager.pool.strategy.OnePool] (http-localhost-127.0.0.1-8080-1) Returning connection to pool org.jboss.jca.core.connectionmanager.listener.TxConnectionListener@2f057e4f[state=NORMAL managed connection=org.jboss.jca.adapters.jdbc.local.LocalManagedConnection@11a78ca7 connection handles=0 lastUse=1308996323557 trackByTx=false pool=org.jboss.jca.core.connectionmanager.pool.strategy.OnePool@41556f4c pool internal context=org.jboss.jca.core.connectionmanager.pool.mcp.SemaphoreArrayListManagedConnectionPool@f0330ff xaResource=org.jboss.jca.core.tx.jbossts.LocalXAResourceImpl@1a85bd0c txSync=null] 11:05:23,557 TRACE [org.jboss.security.auth.spi.DatabaseServerLoginModule] (http-localhost-127.0.0.1-8080-1) resumeAnyTransaction 11:05:23,557 TRACE [org.jboss.security.authentication.JBossCachedAuthenticationManager] (http-localhost-127.0.0.1-8080-1) defaultLogin, lc=javax.security.auth.login.LoginContext@9cb9124, subject=Subject(329635714).principals=org.jboss.security.SimplePrincipal@398892923(rich)org.jboss.security.SimpleGroup@1896894574(CallerPrincipal(members:rich))org.jboss.security.SimpleGroup@1896894574(Roles(members:admin)) 11:05:23,557 TRACE [org.jboss.security.authentication.JBossCachedAuthenticationManager] (http-localhost-127.0.0.1-8080-1) updateCache, inputSubject=Subject(329635714).principals=org.jboss.security.SimplePrincipal@398892923(rich)org.jboss.security.SimpleGroup@1896894574(CallerPrincipal(members:rich))org.jboss.security.SimpleGroup@1896894574(Roles(members:admin)), cacheSubject=Subject(1827119585).principals=org.jboss.security.SimplePrincipal@398892923(rich)org.jboss.security.SimpleGroup@1896894574(CallerPrincipal(members:rich))org.jboss.security.SimpleGroup@1896894574(Roles(members:admin)) 11:05:23,558 TRACE [org.jboss.security.authentication.JBossCachedAuthenticationManager] (http-localhost-127.0.0.1-8080-1) Inserted cache info: org.jboss.security.authentication.JBossCachedAuthenticationManager$DomainInfo@5b34f2a2 11:05:23,558 TRACE [org.jboss.security.authentication.JBossCachedAuthenticationManager] (http-localhost-127.0.0.1-8080-1) End isValid, true 11:05:23,560 TRACE [org.jboss.as.web.security.JBossWebRealm] (http-localhost-127.0.0.1-8080-1) User: rich is authenticated 11:05:23,560 DEBUG [org.apache.catalina.authenticator.FormAuthenticator] (http-localhost-127.0.0.1-8080-1) Authentication of 'rich' was successful 11:05:23,560 DEBUG [org.apache.catalina.authenticator.FormAuthenticator] (http-localhost-127.0.0.1-8080-1) Redirecting to original '/jsf-web/' 11:05:23,560 DEBUG [org.apache.catalina.authenticator.AuthenticatorBase] (http-localhost-127.0.0.1-8080-1)  Failed authenticate() test ??/jsf-web/j_security_check