Authentication issue in AS7
cs02rm0 Jun 25, 2011 6:19 AMFrom 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.
I suspect that it might be caused by the ClassNotFoundException. Any ideas?
Thanks in advance.
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