1 Reply Latest reply on Jun 30, 2006 3:21 PM by anil.saldhana

    Authentication not working even though it should

      Hi

      I need help with this one. I've configured my files correctly, I am trying to authenticate a user burgers@scmbdirectory.com, but it does not seem to work. From the logging, it seems likes its authenticated, but halfway throught the process the authenticate fails. Please see log below:

      06-06-30 19:57:28,749 DEBUG [org.apache.catalina.authenticator.AuthenticatorBase] Security checking request GET /corpcredit/svc
      2006-06-30 19:57:28,749 DEBUG [org.apache.catalina.realm.RealmBase] Checking constraint 'SecurityConstraint[viewCol]' against GET /svc --> true
      2006-06-30 19:57:28,749 DEBUG [org.apache.catalina.realm.RealmBase] Checking constraint 'SecurityConstraint[captureCol]' against GET /svc --> false
      2006-06-30 19:57:28,749 DEBUG [org.apache.catalina.realm.RealmBase] Checking constraint 'SecurityConstraint[adminCol]' against GET /svc --> false
      2006-06-30 19:57:28,749 DEBUG [org.apache.catalina.authenticator.AuthenticatorBase] Calling hasUserDataPermission()
      2006-06-30 19:57:28,749 DEBUG [org.apache.catalina.realm.RealmBase] User data constraint has no restrictions
      2006-06-30 19:57:28,749 DEBUG [org.apache.catalina.authenticator.AuthenticatorBase] Calling authenticate()
      2006-06-30 19:57:28,780 DEBUG [org.apache.catalina.authenticator.FormAuthenticator] Save request in session '9BA1A22C78369E56A6412D6E97FE102C'
      2006-06-30 19:57:28,811 DEBUG [org.apache.catalina.core.ApplicationDispatcher] servletPath=/jsp/access/login.jsp, pathInfo=null, queryString=null, name=null
      2006-06-30 19:57:28,811 DEBUG [org.apache.catalina.core.ApplicationDispatcher] Path Based Forward
      2006-06-30 19:57:28,827 DEBUG [org.apache.catalina.core.StandardWrapper] Returning non-STM instance
      2006-06-30 19:57:28,827 TRACE [org.jboss.web.tomcat.security.RunAsListener] jsp, runAs: null
      2006-06-30 19:57:28,827 TRACE [org.jboss.web.tomcat.security.RunAsListener] jsp, runAs: null
      2006-06-30 19:57:28,952 DEBUG [org.apache.catalina.loader.WebappClassLoader] loadClass(org.apache.jasper.runtime.JspSourceDependent, false)
      2006-06-30 19:57:28,952 DEBUG [org.apache.catalina.loader.WebappClassLoader] Searching local repositories
      2006-06-30 19:57:28,952 DEBUG [org.apache.catalina.loader.WebappClassLoader] findClass(org.apache.jasper.runtime.JspSourceDependent)
      2006-06-30 19:57:28,952 DEBUG [org.apache.catalina.loader.WebappClassLoader] findClassInternal(org.apache.jasper.runtime.JspSourceDependent)
      2006-06-30 19:57:28,952 DEBUG [org.apache.catalina.loader.WebappClassLoader] --> Passing on ClassNotFoundException
      2006-06-30 19:57:28,952 DEBUG [org.apache.catalina.loader.WebappClassLoader] Delegating to parent classloader at end: java.net.FactoryURLClassLoader@1b8aeb1
      2006-06-30 19:57:28,952 DEBUG [org.apache.catalina.loader.WebappClassLoader] Loading class from parent
      2006-06-30 19:57:28,952 DEBUG [org.apache.catalina.loader.WebappClassLoader] loadClass(org.apache.jasper.runtime.HttpJspBase, false)
      2006-06-30 19:57:28,952 DEBUG [org.apache.catalina.loader.WebappClassLoader] Searching local repositories
      2006-06-30 19:57:28,952 DEBUG [org.apache.catalina.loader.WebappClassLoader] findClass(org.apache.jasper.runtime.HttpJspBase)
      2006-06-30 19:57:28,952 DEBUG [org.apache.catalina.loader.WebappClassLoader] findClassInternal(org.apache.jasper.runtime.HttpJspBase)
      2006-06-30 19:57:28,952 DEBUG [org.apache.catalina.loader.WebappClassLoader] --> Passing on ClassNotFoundException
      2006-06-30 19:57:28,952 DEBUG [org.apache.catalina.loader.WebappClassLoader] Delegating to parent classloader at end: java.net.FactoryURLClassLoader@1b8aeb1
      2006-06-30 19:57:28,968 DEBUG [org.apache.catalina.loader.WebappClassLoader] Loading class from parent
      2006-06-30 19:57:28,968 DEBUG [org.apache.catalina.loader.WebappClassLoader] loadClass(java.lang.Object, false)
      2006-06-30 19:57:28,968 DEBUG [org.apache.catalina.loader.WebappClassLoader] Returning class from cache
      2006-06-30 19:57:28,968 DEBUG [org.apache.catalina.loader.WebappClassLoader] loadClass(java.lang.Throwable, false)
      2006-06-30 19:57:28,968 DEBUG [org.apache.catalina.loader.WebappClassLoader] Returning class from cache
      2006-06-30 19:57:28,968 DEBUG [org.apache.catalina.loader.WebappClassLoader] loadClass(java.lang.ClassNotFoundException, false)
      2006-06-30 19:57:28,968 DEBUG [org.apache.catalina.loader.WebappClassLoader] Returning class from cache
      2006-06-30 19:57:28,968 DEBUG [org.apache.catalina.loader.WebappClassLoader] loadClass(java.lang.NoClassDefFoundError, false)
      2006-06-30 19:57:28,968 DEBUG [org.apache.catalina.loader.WebappClassLoader] Returning class from cache
      2006-06-30 19:57:28,968 DEBUG [org.apache.catalina.loader.WebappClassLoader] loadClass(javax.servlet.jsp.tagext.Tag, false)
      2006-06-30 19:57:28,968 DEBUG [org.apache.catalina.loader.WebappClassLoader] Delegating to parent classloader1 java.net.FactoryURLClassLoader@1b8aeb1
      2006-06-30 19:57:28,968 DEBUG [org.apache.catalina.loader.WebappClassLoader] Loading class from parent
      2006-06-30 19:57:28,968 DEBUG [org.apache.catalina.loader.WebappClassLoader] loadClass(javax.servlet.Servlet, false)
      2006-06-30 19:57:28,968 DEBUG [org.apache.catalina.loader.WebappClassLoader] Delegating to parent classloader1 java.net.FactoryURLClassLoader@1b8aeb1
      2006-06-30 19:57:28,968 DEBUG [org.apache.catalina.loader.WebappClassLoader] Loading class from parent
      2006-06-30 19:57:28,968 DEBUG [org.apache.catalina.loader.WebappClassLoader] loadClass(javax.servlet.ServletRequest, false)
      2006-06-30 19:57:28,968 DEBUG [org.apache.catalina.loader.WebappClassLoader] Returning class from cache
      2006-06-30 19:57:28,968 DEBUG [org.apache.catalina.loader.WebappClassLoader] loadClass(javax.servlet.ServletResponse, false)
      2006-06-30 19:57:28,968 DEBUG [org.apache.catalina.loader.WebappClassLoader] Returning class from cache
      2006-06-30 19:57:28,968 DEBUG [org.apache.catalina.loader.WebappClassLoader] loadClass(java.util.List, false)
      2006-06-30 19:57:28,968 DEBUG [org.apache.catalina.loader.WebappClassLoader] Returning class from cache
      2006-06-30 19:57:28,983 DEBUG [org.apache.catalina.loader.WebappClassLoader] loadClass(java.util.Vector, false)
      2006-06-30 19:57:28,983 DEBUG [org.apache.catalina.loader.WebappClassLoader] Returning class from cache
      2006-06-30 19:57:28,983 DEBUG [org.apache.catalina.loader.WebappClassLoader] loadClass(org.apache.jasper.runtime.TagHandlerPool, false)
      2006-06-30 19:57:28,983 DEBUG [org.apache.catalina.loader.WebappClassLoader] Searching local repositories
      2006-06-30 19:57:28,983 DEBUG [org.apache.catalina.loader.WebappClassLoader] findClass(org.apache.jasper.runtime.TagHandlerPool)
      2006-06-30 19:57:28,983 DEBUG [org.apache.catalina.loader.WebappClassLoader] findClassInternal(org.apache.jasper.runtime.TagHandlerPool)
      2006-06-30 19:57:28,983 DEBUG [org.apache.catalina.loader.WebappClassLoader] --> Passing on ClassNotFoundException
      2006-06-30 19:57:28,983 DEBUG [org.apache.catalina.loader.WebappClassLoader] Delegating to parent classloader at end: java.net.FactoryURLClassLoader@1b8aeb1
      2006-06-30 19:57:28,983 DEBUG [org.apache.catalina.loader.WebappClassLoader] Loading class from parent
      2006-06-30 19:57:29,030 DEBUG [org.apache.catalina.loader.WebappClassLoader] loadClass(javax.servlet.jsp.JspFactory, false)
      2006-06-30 19:57:29,030 DEBUG [org.apache.catalina.loader.WebappClassLoader] Delegating to parent classloader1 java.net.FactoryURLClassLoader@1b8aeb1
      2006-06-30 19:57:29,030 DEBUG [org.apache.catalina.loader.WebappClassLoader] Loading class from parent
      2006-06-30 19:57:29,030 DEBUG [org.apache.catalina.loader.WebappClassLoader] loadClass(javax.servlet.http.HttpServletResponse, false)
      2006-06-30 19:57:29,030 DEBUG [org.apache.catalina.loader.WebappClassLoader] Returning class from cache
      2006-06-30 19:57:29,061 DEBUG [org.apache.catalina.loader.WebappClassLoader] loadClass(javax.servlet.jsp.PageContext, false)
      2006-06-30 19:57:29,061 DEBUG [org.apache.catalina.loader.WebappClassLoader] Delegating to parent classloader1 java.net.FactoryURLClassLoader@1b8aeb1
      2006-06-30 19:57:29,061 DEBUG [org.apache.catalina.loader.WebappClassLoader] Loading class from parent
      2006-06-30 19:57:29,061 DEBUG [org.apache.catalina.loader.WebappClassLoader] loadClass(javax.servlet.jsp.JspWriter, false)
      2006-06-30 19:57:29,061 DEBUG [org.apache.catalina.loader.WebappClassLoader] Delegating to parent classloader1 java.net.FactoryURLClassLoader@1b8aeb1
      2006-06-30 19:57:29,061 DEBUG [org.apache.catalina.loader.WebappClassLoader] Loading class from parent
      2006-06-30 19:57:29,061 DEBUG [org.apache.catalina.loader.WebappClassLoader] loadClass(java.lang.String, false)
      2006-06-30 19:57:29,061 DEBUG [org.apache.catalina.loader.WebappClassLoader] Returning class from cache
      2006-06-30 19:57:29,061 DEBUG [org.apache.catalina.loader.WebappClassLoader] loadClass(javax.servlet.http.HttpServletRequest, false)
      2006-06-30 19:57:29,061 DEBUG [org.apache.catalina.loader.WebappClassLoader] Returning class from cache
      2006-06-30 19:57:29,061 DEBUG [org.apache.catalina.loader.WebappClassLoader] loadClass(za.co.scmb.www.corpcredit.common.Constants, false)
      2006-06-30 19:57:29,061 DEBUG [org.apache.catalina.loader.WebappClassLoader] Returning class from cache
      2006-06-30 19:57:29,061 DEBUG [org.apache.catalina.loader.WebappClassLoader] loadClass(java.util.GregorianCalendar, false)
      2006-06-30 19:57:29,077 DEBUG [org.apache.catalina.loader.WebappClassLoader] loadClass(java.lang.Class, false)
      2006-06-30 19:57:29,077 DEBUG [org.apache.catalina.loader.WebappClassLoader] Returning class from cache
      2006-06-30 19:57:29,077 DEBUG [org.apache.catalina.loader.WebappClassLoader] loadClass(za.co.scmb.util.tags.SelectTag, false)
      2006-06-30 19:57:29,077 DEBUG [org.apache.catalina.loader.WebappClassLoader] Searching local repositories
      2006-06-30 19:57:29,077 DEBUG [org.apache.catalina.loader.WebappClassLoader] findClass(za.co.scmb.util.tags.SelectTag)
      2006-06-30 19:57:29,077 DEBUG [org.apache.catalina.loader.WebappClassLoader] findClassInternal(za.co.scmb.util.tags.SelectTag)
      2006-06-30 19:57:29,108 DEBUG [org.apache.catalina.loader.WebappClassLoader] loadClass(javax.servlet.jsp.tagext.TagSupport, false)
      2006-06-30 19:57:29,124 DEBUG [org.apache.catalina.loader.WebappClassLoader] Delegating to parent classloader1 java.net.FactoryURLClassLoader@1b8aeb1
      2006-06-30 19:57:29,124 DEBUG [org.apache.catalina.loader.WebappClassLoader] Loading class from parent
      2006-06-30 19:57:29,124 DEBUG [org.apache.catalina.loader.WebappClassLoader] Returning class class za.co.scmb.util.tags.SelectTag
      2006-06-30 19:57:29,124 DEBUG [org.apache.catalina.loader.WebappClassLoader] Loaded by WebappClassLoader
      delegate: false
      repositories:
      /WEB-INF/classes/
      ----------> Parent Classloader:
      java.net.FactoryURLClassLoader@1b8aeb1

      2006-06-30 19:57:29,124 DEBUG [org.apache.catalina.loader.WebappClassLoader] Loading class from local repository
      2006-06-30 19:57:29,124 DEBUG [org.apache.catalina.loader.WebappClassLoader] loadClass(javax.servlet.jsp.PageContext, false)
      2006-06-30 19:57:29,124 DEBUG [org.apache.catalina.loader.WebappClassLoader] Delegating to parent classloader1 java.net.FactoryURLClassLoader@1b8aeb1
      2006-06-30 19:57:29,124 DEBUG [org.apache.catalina.loader.WebappClassLoader] Loading class from parent
      2006-06-30 19:57:29,124 DEBUG [org.apache.catalina.loader.WebappClassLoader] loadClass(javax.servlet.jsp.JspWriter, false)
      2006-06-30 19:57:29,124 DEBUG [org.apache.catalina.loader.WebappClassLoader] Delegating to parent classloader1 java.net.FactoryURLClassLoader@1b8aeb1
      2006-06-30 19:57:29,124 DEBUG [org.apache.catalina.loader.WebappClassLoader] Loading class from parent
      2006-06-30 19:57:29,124 TRACE [org.jboss.web.tomcat.security.RunAsListener] jsp, runAs: null
      2006-06-30 19:57:29,124 TRACE [org.jboss.web.tomcat.security.RunAsListener] jsp, runAs: null
      2006-06-30 19:57:29,124 DEBUG [org.apache.catalina.core.ApplicationDispatcher] Disabling the response for futher output
      2006-06-30 19:57:29,124 DEBUG [org.apache.catalina.authenticator.AuthenticatorBase] Failed authenticate() test
      2006-06-30 19:57:29,139 TRACE [org.jboss.security.SecurityAssociation] clear, server=true
      2006-06-30 19:57:29,218 DEBUG [org.apache.catalina.connector.CoyoteAdapter] Requested cookie session id is 9BA1A22C78369E56A6412D6E97FE102C
      2006-06-30 19:57:29,218 DEBUG [org.apache.catalina.authenticator.AuthenticatorBase] Security checking request GET /corpcredit/resources/window.js
      2006-06-30 19:57:29,233 DEBUG [org.apache.catalina.realm.RealmBase] Checking constraint 'SecurityConstraint[viewCol]' against GET /resources/window.js --> false
      2006-06-30 19:57:29,233 DEBUG [org.apache.catalina.realm.RealmBase] Checking constraint 'SecurityConstraint[captureCol]' against GET /resources/window.js --> false
      2006-06-30 19:57:29,233 DEBUG [org.apache.catalina.realm.RealmBase] Checking constraint 'SecurityConstraint[adminCol]' against GET /resources/window.js --> false
      2006-06-30 19:57:29,233 DEBUG [org.apache.catalina.realm.RealmBase] Checking constraint 'SecurityConstraint[viewCol]' against GET /resources/window.js --> false
      2006-06-30 19:57:29,233 DEBUG [org.apache.catalina.realm.RealmBase] Checking constraint 'SecurityConstraint[captureCol]' against GET /resources/window.js --> false
      2006-06-30 19:57:29,233 DEBUG [org.apache.catalina.realm.RealmBase] Checking constraint 'SecurityConstraint[adminCol]' against GET /resources/window.js --> false
      2006-06-30 19:57:29,233 DEBUG [org.apache.catalina.realm.RealmBase] Checking constraint 'SecurityConstraint[viewCol]' against GET /resources/window.js --> false
      2006-06-30 19:57:29,233 DEBUG [org.apache.catalina.realm.RealmBase] Checking constraint 'SecurityConstraint[captureCol]' against GET /resources/window.js --> false
      2006-06-30 19:57:29,233 DEBUG [org.apache.catalina.realm.RealmBase] Checking constraint 'SecurityConstraint[adminCol]' against GET /resources/window.js --> false
      2006-06-30 19:57:29,233 DEBUG [org.apache.catalina.realm.RealmBase] Checking constraint 'SecurityConstraint[viewCol]' against GET /resources/window.js --> false
      2006-06-30 19:57:29,233 DEBUG [org.apache.catalina.realm.RealmBase] Checking constraint 'SecurityConstraint[captureCol]' against GET /resources/window.js --> false
      2006-06-30 19:57:29,233 DEBUG [org.apache.catalina.realm.RealmBase] Checking constraint 'SecurityConstraint[adminCol]' against GET /resources/window.js --> false
      2006-06-30 19:57:29,233 DEBUG [org.apache.catalina.realm.RealmBase] No applicable constraint located
      2006-06-30 19:57:29,233 DEBUG [org.apache.catalina.authenticator.AuthenticatorBase] Not subject to any constraint
      2006-06-30 19:57:29,233 TRACE [org.jboss.web.tomcat.security.SecurityAssociationValve] Begin invoke, callernull
      2006-06-30 19:57:29,233 TRACE [org.jboss.security.SecurityAssociation] pushRunAsIdentity, runAs=null
      2006-06-30 19:57:29,233 DEBUG [org.apache.catalina.core.StandardWrapper] Returning non-STM instance
      2006-06-30 19:57:29,233 TRACE [org.jboss.web.tomcat.security.RunAsListener] default, runAs: null
      2006-06-30 19:57:29,233 TRACE [org.jboss.web.tomcat.security.RunAsListener] default, runAs: null
      2006-06-30 19:57:29,233 TRACE [org.jboss.web.tomcat.security.RunAsListener] default, runAs: null
      2006-06-30 19:57:29,233 TRACE [org.jboss.web.tomcat.security.RunAsListener] default, runAs: null
      2006-06-30 19:57:29,233 TRACE [org.jboss.security.SecurityAssociation] popRunAsIdentity, runAs=null
      2006-06-30 19:57:29,233 TRACE [org.jboss.web.tomcat.security.SecurityAssociationValve] End invoke, callernull
      2006-06-30 19:57:29,233 TRACE [org.jboss.security.SecurityAssociation] clear, server=true
      2006-06-30 19:57:29,233 DEBUG [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] Processing ErrorPage[errorCode=404, location=/jsp/error/http/404.jsp]
      2006-06-30 19:57:29,233 DEBUG [org.apache.catalina.core.ApplicationDispatcher] servletPath=/jsp/error/http/404.jsp, pathInfo=null, queryString=null, name=null
      2006-06-30 19:57:29,233 DEBUG [org.apache.catalina.core.ApplicationDispatcher] Path Based Forward
      2006-06-30 19:57:29,233 DEBUG [org.apache.catalina.core.StandardWrapper] Returning non-STM instance
      2006-06-30 19:57:29,233 TRACE [org.jboss.web.tomcat.security.RunAsListener] jsp, runAs: null
      2006-06-30 19:57:29,233 TRACE [org.jboss.web.tomcat.security.RunAsListener] jsp, runAs: null
      2006-06-30 19:57:29,249 DEBUG [org.apache.catalina.loader.WebappClassLoader] loadClass(org.apache.jasper.runtime.JspSourceDependent, false)
      2006-06-30 19:57:29,249 DEBUG [org.apache.catalina.loader.WebappClassLoader] Searching local repositories
      2006-06-30 19:57:29,249 DEBUG [org.apache.catalina.loader.WebappClassLoader] findClass(org.apache.jasper.runtime.JspSourceDependent)
      2006-06-30 19:57:29,249 DEBUG [org.apache.catalina.loader.WebappClassLoader] findClassInternal(org.apache.jasper.runtime.JspSourceDependent)
      2006-06-30 19:57:29,249 DEBUG [org.apache.catalina.loader.WebappClassLoader] --> Passing on ClassNotFoundException
      2006-06-30 19:57:29,249 DEBUG [org.apache.catalina.loader.WebappClassLoader] Delegating to parent classloader at end: java.net.FactoryURLClassLoader@1b8aeb1
      2006-06-30 19:57:29,249 DEBUG [org.apache.catalina.loader.WebappClassLoader] Loading class from parent
      2006-06-30 19:57:29,249 DEBUG [org.apache.catalina.loader.WebappClassLoader] loadClass(org.apache.jasper.runtime.HttpJspBase, false)
      2006-06-30 19:57:29,264 DEBUG [org.apache.catalina.loader.WebappClassLoader] Searching local repositories
      2006-06-30 19:57:29,264 DEBUG [org.apache.catalina.loader.WebappClassLoader] findClass(org.apache.jasper.runtime.HttpJspBase)
      2006-06-30 19:57:29,264 DEBUG [org.apache.catalina.loader.WebappClassLoader] findClassInternal(org.apache.jasper.runtime.HttpJspBase)
      2006-06-30 19:57:29,264 DEBUG [org.apache.catalina.loader.WebappClassLoader] --> Passing on ClassNotFoundException
      2006-06-30 19:57:29,264 DEBUG [org.apache.catalina.loader.WebappClassLoader] Delegating to parent classloader at end: java.net.FactoryURLClassLoader@1b8aeb1
      2006-06-30 19:57:29,264 DEBUG [org.apache.catalina.loader.WebappClassLoader] Loading class from parent
      2006-06-30 19:57:29,264 DEBUG [org.apache.catalina.loader.WebappClassLoader] loadClass(java.lang.Object, false)
      2006-06-30 19:57:29,264 DEBUG [org.apache.catalina.loader.WebappClassLoader] Returning class from cache
      2006-06-30 19:57:29,264 DEBUG [org.apache.catalina.loader.WebappClassLoader] loadClass(javax.servlet.Servlet, false)
      2006-06-30 19:57:29,264 DEBUG [org.apache.catalina.loader.WebappClassLoader] Delegating to parent classloader1 java.net.FactoryURLClassLoader@1b8aeb1
      2006-06-30 19:57:29,264 DEBUG [org.apache.catalina.loader.WebappClassLoader] Loading class from parent
      2006-06-30 19:57:29,264 DEBUG [org.apache.catalina.loader.WebappClassLoader] loadClass(javax.servlet.ServletRequest, false)
      2006-06-30 19:57:29,264 DEBUG [org.apache.catalina.loader.WebappClassLoader] Returning class from cache
      2006-06-30 19:57:29,264 DEBUG [org.apache.catalina.loader.WebappClassLoader] loadClass(javax.servlet.ServletResponse, false)
      2006-06-30 19:57:29,264 DEBUG [org.apache.catalina.loader.WebappClassLoader] Returning class from cache
      2006-06-30 19:57:29,264 DEBUG [org.apache.catalina.loader.WebappClassLoader] loadClass(java.util.List, false)
      2006-06-30 19:57:29,264 DEBUG [org.apache.catalina.loader.WebappClassLoader] Returning class from cache
      2006-06-30 19:57:29,264 DEBUG [org.apache.catalina.loader.WebappClassLoader] loadClass(java.util.Vector, false)
      2006-06-30 19:57:29,264 DEBUG [org.apache.catalina.loader.WebappClassLoader] Returning class from cache
      2006-06-30 19:57:29,264 DEBUG [org.apache.catalina.loader.WebappClassLoader] loadClass(javax.servlet.jsp.JspFactory, false)
      2006-06-30 19:57:29,264 DEBUG [org.apache.catalina.loader.WebappClassLoader] Delegating to parent classloader1 java.net.FactoryURLClassLoader@1b8aeb1
      2006-06-30 19:57:29,264 DEBUG [org.apache.catalina.loader.WebappClassLoader] Loading class from parent
      2006-06-30 19:57:29,264 DEBUG [org.apache.catalina.loader.WebappClassLoader] loadClass(javax.servlet.http.HttpServletResponse, false)
      2006-06-30 19:57:29,264 DEBUG [org.apache.catalina.loader.WebappClassLoader] Returning class from cache
      2006-06-30 19:57:29,264 DEBUG [org.apache.catalina.loader.WebappClassLoader] loadClass(javax.servlet.jsp.PageContext, false)
      2006-06-30 19:57:29,280 DEBUG [org.apache.catalina.loader.WebappClassLoader] Returning class from cache
      2006-06-30 19:57:29,280 DEBUG [org.apache.catalina.loader.WebappClassLoader] loadClass(javax.servlet.jsp.JspWriter, false)
      2006-06-30 19:57:29,280 DEBUG [org.apache.catalina.loader.WebappClassLoader] Returning class from cache
      2006-06-30 19:57:29,280 DEBUG [org.apache.catalina.loader.WebappClassLoader] loadClass(javax.servlet.http.HttpServletRequest, false)
      2006-06-30 19:57:29,280 DEBUG [org.apache.catalina.loader.WebappClassLoader] Returning class from cache
      2006-06-30 19:57:29,280 DEBUG [org.apache.catalina.loader.WebappClassLoader] loadClass(java.lang.String, false)
      2006-06-30 19:57:29,280 DEBUG [org.apache.catalina.loader.WebappClassLoader] Returning class from cache
      2006-06-30 19:57:29,280 DEBUG [org.apache.catalina.loader.WebappClassLoader] loadClass(java.util.GregorianCalendar, false)
      2006-06-30 19:57:29,280 DEBUG [org.apache.catalina.loader.WebappClassLoader] loadClass(za.co.scmb.www.corpcredit.common.Constants, false)
      2006-06-30 19:57:29,280 DEBUG [org.apache.catalina.loader.WebappClassLoader] Returning class from cache
      2006-06-30 19:57:29,280 TRACE [org.jboss.web.tomcat.security.RunAsListener] jsp, runAs: null
      2006-06-30 19:57:29,280 TRACE [org.jboss.web.tomcat.security.RunAsListener] jsp, runAs: null
      2006-06-30 19:57:29,280 DEBUG [org.apache.catalina.core.ApplicationDispatcher] Disabling the response for futher output
      2006-06-30 19:57:29,296 DEBUG [org.apache.catalina.connector.CoyoteAdapter] Requested cookie session id is 9BA1A22C78369E56A6412D6E97FE102C
      2006-06-30 19:57:29,296 DEBUG [org.apache.catalina.authenticator.AuthenticatorBase] Security checking request GET /corpcredit/resources/popUp.js
      2006-06-30 19:57:29,296 DEBUG [org.apache.catalina.realm.RealmBase] Checking constraint 'SecurityConstraint[viewCol]' against GET /resources/popUp.js --> false
      2006-06-30 19:57:29,296 DEBUG [org.apache.catalina.realm.RealmBase] Checking constraint 'SecurityConstraint[captureCol]' against GET /resources/popUp.js --> false
      2006-06-30 19:57:29,296 DEBUG [org.apache.catalina.realm.RealmBase] Checking constraint 'SecurityConstraint[adminCol]' against GET /resources/popUp.js --> false
      2006-06-30 19:57:29,296 DEBUG [org.apache.catalina.realm.RealmBase] Checking constraint 'SecurityConstraint[viewCol]' against GET /resources/popUp.js --> false
      2006-06-30 19:57:29,296 DEBUG [org.apache.catalina.realm.RealmBase] Checking constraint 'SecurityConstraint[captureCol]' against GET /resources/popUp.js --> false
      2006-06-30 19:57:29,296 DEBUG [org.apache.catalina.realm.RealmBase] Checking constraint 'SecurityConstraint[adminCol]' against GET /resources/popUp.js --> false
      2006-06-30 19:57:29,296 DEBUG [org.apache.catalina.realm.RealmBase] Checking constraint 'SecurityConstraint[viewCol]' against GET /resources/popUp.js --> false
      2006-06-30 19:57:29,296 DEBUG [org.apache.catalina.realm.RealmBase] Checking constraint 'SecurityConstraint[captureCol]' against GET /resources/popUp.js --> false
      2006-06-30 19:57:29,296 DEBUG [org.apache.catalina.realm.RealmBase] Checking constraint 'SecurityConstraint[adminCol]' against GET /resources/popUp.js --> false
      2006-06-30 19:57:29,296 DEBUG [org.apache.catalina.realm.RealmBase] Checking constraint 'SecurityConstraint[viewCol]' against GET /resources/popUp.js --> false
      2006-06-30 19:57:29,296 DEBUG [org.apache.catalina.realm.RealmBase] Checking constraint 'SecurityConstraint[captureCol]' against GET /resources/popUp.js --> false
      2006-06-30 19:57:29,296 DEBUG [org.apache.catalina.realm.RealmBase] Checking constraint 'SecurityConstraint[adminCol]' against GET /resources/popUp.js --> false
      2006-06-30 19:57:29,296 DEBUG [org.apache.catalina.realm.RealmBase] No applicable constraint located
      2006-06-30 19:57:29,296 DEBUG [org.apache.catalina.authenticator.AuthenticatorBase] Not subject to any constraint
      2006-06-30 19:57:29,296 TRACE [org.jboss.web.tomcat.security.SecurityAssociationValve] Begin invoke, callernull
      2006-06-30 19:57:29,296 TRACE [org.jboss.security.SecurityAssociation] pushRunAsIdentity, runAs=null
      2006-06-30 19:57:29,296 DEBUG [org.apache.catalina.core.StandardWrapper] Returning non-STM instance
      2006-06-30 19:57:29,296 TRACE [org.jboss.web.tomcat.security.RunAsListener] default, runAs: null
      2006-06-30 19:57:29,296 TRACE [org.jboss.web.tomcat.security.RunAsListener] default, runAs: null
      2006-06-30 19:57:29,296 TRACE [org.jboss.web.tomcat.security.RunAsListener] default, runAs: null
      2006-06-30 19:57:29,296 TRACE [org.jboss.web.tomcat.security.RunAsListener] default, runAs: null
      2006-06-30 19:57:29,296 TRACE [org.jboss.security.SecurityAssociation] popRunAsIdentity, runAs=null
      2006-06-30 19:57:29,296 TRACE [org.jboss.web.tomcat.security.SecurityAssociationValve] End invoke, callernull
      2006-06-30 19:57:29,296 TRACE [org.jboss.security.SecurityAssociation] clear, server=true
      2006-06-30 19:57:29,296 DEBUG [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] Processing ErrorPage[errorCode=404, location=/jsp/error/http/404.jsp]
      2006-06-30 19:57:29,296 DEBUG [org.apache.catalina.core.ApplicationDispatcher] servletPath=/jsp/error/http/404.jsp, pathInfo=null, queryString=null, name=null
      2006-06-30 19:57:29,296 DEBUG [org.apache.catalina.core.ApplicationDispatcher] Path Based Forward
      2006-06-30 19:57:29,296 DEBUG [org.apache.catalina.core.StandardWrapper] Returning non-STM instance
      2006-06-30 19:57:29,296 TRACE [org.jboss.web.tomcat.security.RunAsListener] jsp, runAs: null
      2006-06-30 19:57:29,296 TRACE [org.jboss.web.tomcat.security.RunAsListener] jsp, runAs: null
      2006-06-30 19:57:29,311 TRACE [org.jboss.web.tomcat.security.RunAsListener] jsp, runAs: null
      2006-06-30 19:57:29,311 TRACE [org.jboss.web.tomcat.security.RunAsListener] jsp, runAs: null
      2006-06-30 19:57:29,311 DEBUG [org.apache.catalina.core.ApplicationDispatcher] Disabling the response for futher output
      2006-06-30 19:57:34,936 DEBUG [org.apache.catalina.connector.CoyoteAdapter] Requested cookie session id is 9BA1A22C78369E56A6412D6E97FE102C
      2006-06-30 19:57:34,936 DEBUG [org.apache.catalina.authenticator.AuthenticatorBase] Security checking request POST /corpcredit/j_security_check
      2006-06-30 19:57:34,936 DEBUG [org.apache.catalina.authenticator.FormAuthenticator] Authenticating username 'burgers@scmbdirectory.com'
      2006-06-30 19:57:34,936 TRACE [org.jboss.web.tomcat.security.JBossSecurityMgrRealm] Begin authenticate, username=burgers@scmbdirectory.com
      2006-06-30 19:57:34,936 DEBUG [org.apache.catalina.loader.WebappClassLoader] findResources(jndi.properties)
      2006-06-30 19:57:34,936 DEBUG [org.apache.catalina.loader.WebappClassLoader] loadClass(org.jnp.interfaces.NamingContextFactory, false)
      2006-06-30 19:57:34,936 DEBUG [org.apache.catalina.loader.WebappClassLoader] Searching local repositories
      2006-06-30 19:57:34,936 DEBUG [org.apache.catalina.loader.WebappClassLoader] findClass(org.jnp.interfaces.NamingContextFactory)
      2006-06-30 19:57:34,936 DEBUG [org.apache.catalina.loader.WebappClassLoader] findClassInternal(org.jnp.interfaces.NamingContextFactory)
      2006-06-30 19:57:34,936 DEBUG [org.apache.catalina.loader.WebappClassLoader] --> Passing on ClassNotFoundException
      2006-06-30 19:57:34,936 DEBUG [org.apache.catalina.loader.WebappClassLoader] Delegating to parent classloader at end: java.net.FactoryURLClassLoader@1b8aeb1
      2006-06-30 19:57:34,936 DEBUG [org.apache.catalina.loader.WebappClassLoader] Loading class from parent
      2006-06-30 19:57:34,936 DEBUG [org.apache.catalina.loader.WebappClassLoader] loadClass(org.jboss.naming.java.javaURLContextFactory, false)
      2006-06-30 19:57:34,952 DEBUG [org.apache.catalina.loader.WebappClassLoader] Searching local repositories
      2006-06-30 19:57:34,952 DEBUG [org.apache.catalina.loader.WebappClassLoader] findClass(org.jboss.naming.java.javaURLContextFactory)
      2006-06-30 19:57:34,952 DEBUG [org.apache.catalina.loader.WebappClassLoader] findClassInternal(org.jboss.naming.java.javaURLContextFactory)
      2006-06-30 19:57:34,952 DEBUG [org.apache.catalina.loader.WebappClassLoader] --> Passing on ClassNotFoundException
      2006-06-30 19:57:34,952 DEBUG [org.apache.catalina.loader.WebappClassLoader] Delegating to parent classloader at end: java.net.FactoryURLClassLoader@1b8aeb1
      2006-06-30 19:57:34,952 DEBUG [org.apache.catalina.loader.WebappClassLoader] Loading class from parent
      2006-06-30 19:57:34,952 DEBUG [org.apache.catalina.loader.WebappClassLoader] loadClass(org.jboss.naming.ENCFactory, false)
      2006-06-30 19:57:34,952 DEBUG [org.apache.catalina.loader.WebappClassLoader] Searching local repositories
      2006-06-30 19:57:34,952 DEBUG [org.apache.catalina.loader.WebappClassLoader] findClass(org.jboss.naming.ENCFactory)
      2006-06-30 19:57:34,952 DEBUG [org.apache.catalina.loader.WebappClassLoader] findClassInternal(org.jboss.naming.ENCFactory)
      2006-06-30 19:57:34,952 DEBUG [org.apache.catalina.loader.WebappClassLoader] --> Passing on ClassNotFoundException
      2006-06-30 19:57:34,952 DEBUG [org.apache.catalina.loader.WebappClassLoader] Delegating to parent classloader at end: java.net.FactoryURLClassLoader@1b8aeb1
      2006-06-30 19:57:34,952 DEBUG [org.apache.catalina.loader.WebappClassLoader] Loading class from parent
      2006-06-30 19:57:34,952 DEBUG [org.apache.catalina.loader.WebappClassLoader] loadClass(org.jboss.security.plugins.JaasSecurityManagerService$SecurityDomainObjectFactory, false)
      2006-06-30 19:57:34,952 DEBUG [org.apache.catalina.loader.WebappClassLoader] Searching local repositories
      2006-06-30 19:57:34,952 DEBUG [org.apache.catalina.loader.WebappClassLoader] findClass(org.jboss.security.plugins.JaasSecurityManagerService$SecurityDomainObjectFactory)
      2006-06-30 19:57:34,952 DEBUG [org.apache.catalina.loader.WebappClassLoader] findClassInternal(org.jboss.security.plugins.JaasSecurityManagerService$SecurityDomainObjectFactory)
      2006-06-30 19:57:34,952 DEBUG [org.apache.catalina.loader.WebappClassLoader] --> Passing on ClassNotFoundException
      2006-06-30 19:57:34,952 DEBUG [org.apache.catalina.loader.WebappClassLoader] Delegating to parent classloader at end: java.net.FactoryURLClassLoader@1b8aeb1
      2006-06-30 19:57:34,952 DEBUG [org.apache.catalina.loader.WebappClassLoader] Loading class from parent
      2006-06-30 19:57:34,968 DEBUG [org.apache.catalina.loader.WebappClassLoader] loadClass(java.lang.reflect.Proxy, false)
      2006-06-30 19:57:34,968 DEBUG [org.apache.catalina.loader.WebappClassLoader] loadClass(javax.naming.NamingException, false)
      2006-06-30 19:57:34,968 DEBUG [org.apache.catalina.loader.WebappClassLoader] loadClass(java.lang.reflect.UndeclaredThrowableException, false)
      2006-06-30 19:57:34,968 DEBUG [org.apache.catalina.loader.WebappClassLoader] loadClass(java.lang.reflect.InvocationHandler, false)
      2006-06-30 19:57:34,968 DEBUG [org.apache.catalina.loader.WebappClassLoader] loadClass(javax.naming.Name, false)
      2006-06-30 19:57:34,968 TRACE [org.jboss.security.plugins.JaasSecurityManager] Constructing
      2006-06-30 19:57:34,968 DEBUG [org.jboss.security.plugins.JaasSecurityManager.my-web] CallbackHandler: org.jboss.security.auth.callback.SecurityAssociationHandler@126172f
      2006-06-30 19:57:34,968 DEBUG [org.jboss.security.plugins.JaasSecurityManagerService] Created securityMgr=org.jboss.security.plugins.JaasSecurityManager@1bc2e06
      2006-06-30 19:57:34,968 DEBUG [org.apache.catalina.loader.WebappClassLoader] loadClass(org.jboss.security.plugins.JaasSecurityManagerService$DefaultCacheObjectFactory, false)
      2006-06-30 19:57:34,968 DEBUG [org.apache.catalina.loader.WebappClassLoader] Searching local repositories
      2006-06-30 19:57:34,968 DEBUG [org.apache.catalina.loader.WebappClassLoader] findClass(org.jboss.security.plugins.JaasSecurityManagerService$DefaultCacheObjectFactory)
      2006-06-30 19:57:34,968 DEBUG [org.apache.catalina.loader.WebappClassLoader] findClassInternal(org.jboss.security.plugins.JaasSecurityManagerService$DefaultCacheObjectFactory)
      2006-06-30 19:57:34,968 DEBUG [org.apache.catalina.loader.WebappClassLoader] --> Passing on ClassNotFoundException
      2006-06-30 19:57:34,968 DEBUG [org.apache.catalina.loader.WebappClassLoader] Delegating to parent classloader at end: java.net.FactoryURLClassLoader@1b8aeb1
      2006-06-30 19:57:34,968 DEBUG [org.apache.catalina.loader.WebappClassLoader] Loading class from parent
      2006-06-30 19:57:34,968 DEBUG [org.jboss.security.plugins.JaasSecurityManager.my-web] CachePolicy set to: org.jboss.util.TimedCachePolicy@17193fc
      2006-06-30 19:57:34,968 DEBUG [org.jboss.security.plugins.JaasSecurityManagerService] setCachePolicy, c=org.jboss.util.TimedCachePolicy@17193fc
      2006-06-30 19:57:34,983 DEBUG [org.jboss.security.plugins.JaasSecurityManagerService] Added my-web, org.jboss.security.plugins.SecurityDomainContext@1aa8b42 to map
      2006-06-30 19:57:34,983 TRACE [org.jboss.security.plugins.JaasSecurityManager.my-web] Begin isValid, principal:burgers@scmbdirectory.com, cache info: null
      2006-06-30 19:57:34,983 TRACE [org.jboss.security.plugins.JaasSecurityManager.my-web] defaultLogin, principal=burgers@scmbdirectory.com
      2006-06-30 19:57:34,983 TRACE [org.jboss.security.auth.login.XMLLoginConfigImpl] Begin getAppConfigurationEntry(my-web), size=9
      2006-06-30 19:57:34,983 TRACE [org.jboss.security.auth.login.XMLLoginConfigImpl] End getAppConfigurationEntry(my-web), authInfo=AppConfigurationEntry[]:
      [0]
      LoginModule Class: org.jboss.security.auth.spi.UsersRolesLoginModule
      ControlFlag: LoginModuleControlFlag: required
      Options:name=rolesProperties, value=props/tomcat-roles.properties
      name=usersProperties, value=props/tomcat-users.properties

      2006-06-30 19:57:34,983 DEBUG [org.apache.catalina.loader.WebappClassLoader] loadClass(org.jboss.security.auth.spi.UsersRolesLoginModule, false)
      2006-06-30 19:57:34,983 DEBUG [org.apache.catalina.loader.WebappClassLoader] Searching local repositories
      2006-06-30 19:57:34,983 DEBUG [org.apache.catalina.loader.WebappClassLoader] findClass(org.jboss.security.auth.spi.UsersRolesLoginModule)
      2006-06-30 19:57:34,983 DEBUG [org.apache.catalina.loader.WebappClassLoader] findClassInternal(org.jboss.security.auth.spi.UsersRolesLoginModule)
      2006-06-30 19:57:34,983 DEBUG [org.apache.catalina.loader.WebappClassLoader] --> Passing on ClassNotFoundException
      2006-06-30 19:57:34,983 DEBUG [org.apache.catalina.loader.WebappClassLoader] Delegating to parent classloader at end: java.net.FactoryURLClassLoader@1b8aeb1
      2006-06-30 19:57:34,983 DEBUG [org.apache.catalina.loader.WebappClassLoader] Loading class from parent
      2006-06-30 19:57:34,983 TRACE [org.jboss.security.auth.spi.UsersRolesLoginModule] initialize, instance=@6321860
      2006-06-30 19:57:34,999 DEBUG [org.apache.catalina.loader.WebappClassLoader] findResource(defaultUsers.properties)
      2006-06-30 19:57:34,999 DEBUG [org.apache.catalina.loader.WebappClassLoader] --> Resource not found, returning null
      2006-06-30 19:57:34,999 DEBUG [org.apache.catalina.loader.WebappClassLoader] findResource(props/tomcat-users.properties)
      2006-06-30 19:57:34,999 DEBUG [org.apache.catalina.loader.WebappClassLoader] --> Resource not found, returning null
      2006-06-30 19:57:34,999 TRACE [org.jboss.security.auth.spi.UsersRolesLoginModule] findResource: null
      2006-06-30 19:57:34,999 DEBUG [org.apache.catalina.loader.WebappClassLoader] getResource(defaultUsers.properties)
      2006-06-30 19:57:34,999 DEBUG [org.apache.catalina.loader.WebappClassLoader] findResource(defaultUsers.properties)
      2006-06-30 19:57:34,999 DEBUG [org.apache.catalina.loader.WebappClassLoader] --> Resource not found, returning null
      2006-06-30 19:57:34,999 DEBUG [org.apache.catalina.loader.WebappClassLoader] --> Resource not found, returning null
      2006-06-30 19:57:34,999 DEBUG [org.apache.catalina.loader.WebappClassLoader] getResource(props/tomcat-users.properties)
      2006-06-30 19:57:34,999 DEBUG [org.apache.catalina.loader.WebappClassLoader] findResource(props/tomcat-users.properties)
      2006-06-30 19:57:34,999 DEBUG [org.apache.catalina.loader.WebappClassLoader] --> Resource not found, returning null
      2006-06-30 19:57:34,999 DEBUG [org.apache.catalina.loader.WebappClassLoader] --> Returning 'file:/C:/app/jboss-4.0.3SP1/server/default/conf/props/tomcat-users.properties'
      2006-06-30 19:57:34,999 TRACE [org.jboss.security.auth.spi.UsersRolesLoginModule] Properties file=file:/C:/app/jboss-4.0.3SP1/server/default/conf/props/tomcat-users.properties, defaults=null
      2006-06-30 19:57:34,999 DEBUG [org.jboss.security.auth.spi.UsersRolesLoginModule] Loaded properties, users=[admin, burgers@scmbdirectory.com]
      2006-06-30 19:57:34,999 DEBUG [org.apache.catalina.loader.WebappClassLoader] findResource(defaultRoles.properties)
      2006-06-30 19:57:34,999 DEBUG [org.apache.catalina.loader.WebappClassLoader] --> Resource not found, returning null
      2006-06-30 19:57:34,999 DEBUG [org.apache.catalina.loader.WebappClassLoader] findResource(props/tomcat-roles.properties)
      2006-06-30 19:57:34,999 DEBUG [org.apache.catalina.loader.WebappClassLoader] --> Resource not found, returning null
      2006-06-30 19:57:34,999 TRACE [org.jboss.security.auth.spi.UsersRolesLoginModule] findResource: null
      2006-06-30 19:57:34,999 DEBUG [org.apache.catalina.loader.WebappClassLoader] getResource(defaultRoles.properties)
      2006-06-30 19:57:34,999 DEBUG [org.apache.catalina.loader.WebappClassLoader] findResource(defaultRoles.properties)
      2006-06-30 19:57:34,999 DEBUG [org.apache.catalina.loader.WebappClassLoader] --> Resource not found, returning null
      2006-06-30 19:57:35,015 DEBUG [org.apache.catalina.loader.WebappClassLoader] --> Resource not found, returning null
      2006-06-30 19:57:35,015 DEBUG [org.apache.catalina.loader.WebappClassLoader] getResource(props/tomcat-roles.properties)
      2006-06-30 19:57:35,015 DEBUG [org.apache.catalina.loader.WebappClassLoader] findResource(props/tomcat-roles.properties)
      2006-06-30 19:57:35,015 DEBUG [org.apache.catalina.loader.WebappClassLoader] --> Resource not found, returning null
      2006-06-30 19:57:35,015 DEBUG [org.apache.catalina.loader.WebappClassLoader] --> Returning 'file:/C:/app/jboss-4.0.3SP1/server/default/conf/props/tomcat-roles.properties'
      2006-06-30 19:57:35,015 TRACE [org.jboss.security.auth.spi.UsersRolesLoginModule] Properties file=file:/C:/app/jboss-4.0.3SP1/server/default/conf/props/tomcat-roles.properties, defaults=null
      2006-06-30 19:57:35,015 DEBUG [org.jboss.security.auth.spi.UsersRolesLoginModule] Loaded properties, users=[admin, burgers@scmbdirectory.com]
      2006-06-30 19:57:35,015 TRACE [org.jboss.security.auth.spi.UsersRolesLoginModule] login
      2006-06-30 19:57:35,015 TRACE [org.jboss.security.auth.spi.UsersRolesLoginModule] User 'burgers@scmbdirectory.com' authenticated, loginOk=true
      2006-06-30 19:57:35,015 TRACE [org.jboss.security.auth.spi.UsersRolesLoginModule] commit, loginOk=true
      2006-06-30 19:57:35,015 TRACE [org.jboss.security.plugins.JaasSecurityManager.my-web] defaultLogin, lc=javax.security.auth.login.LoginContext@13596e0, subject=Subject(21257512).principals=org.jboss.security.SimplePrincipal@31077996(burgers@scmbdirectory.com)org.jboss.security.SimpleGroup@23598633(Roles(members))org.jboss.security.SimpleGroup@23598633(com(members:secure_capture_role,secure_view_role,secure_admin_role))
      2006-06-30 19:57:35,030 TRACE [org.jboss.security.plugins.JaasSecurityManager.my-web] updateCache, inputSubject=Subject(21257512).principals=org.jboss.security.SimplePrincipal@31077996(burgers@scmbdirectory.com)org.jboss.security.SimpleGroup@23598633(Roles(members))org.jboss.security.SimpleGroup@23598633(com(members:secure_capture_role,secure_view_role,secure_admin_role)), cacheSubject=Subject(20975775).principals=org.jboss.security.SimplePrincipal@31077996(burgers@scmbdirectory.com)org.jboss.security.SimpleGroup@23598633(Roles(members))org.jboss.security.SimpleGroup@23598633(com(members:secure_capture_role,secure_view_role,secure_admin_role))
      2006-06-30 19:57:35,030 TRACE [org.jboss.security.plugins.JaasSecurityManager.my-web] Inserted cache info: org.jboss.security.plugins.JaasSecurityManager$DomainInfo@1033c7e[Subject(20975775).principals=org.jboss.security.SimplePrincipal@31077996(burgers@scmbdirectory.com)org.jboss.security.SimpleGroup@23598633(Roles(members))org.jboss.security.SimpleGroup@23598633(com(members:secure_capture_role,secure_view_role,secure_admin_role)),credential.class=java.lang.String@31054905,expirationTime=1151692054968]
      2006-06-30 19:57:35,030 TRACE [org.jboss.security.plugins.JaasSecurityManager.my-web] End isValid, true
      2006-06-30 19:57:35,030 TRACE [org.jboss.web.tomcat.security.JBossSecurityMgrRealm] User: burgers@scmbdirectory.com is authenticated
      2006-06-30 19:57:35,030 TRACE [org.jboss.security.SecurityAssociation] pushSubjectContext, subject=Subject:
      Principal: burgers@scmbdirectory.com
      Principal: Roles(members)
      Principal: com(members:secure_capture_role,secure_view_role,secure_admin_role)
      , sc=org.jboss.security.SecurityAssociation$SubjectContext@650016{principal=burgers@scmbdirectory.com,subject=15250942}
      2006-06-30 19:57:35,030 TRACE [org.jboss.security.plugins.JaasSecurityManager.my-web] getPrincipal, cache info: org.jboss.security.plugins.JaasSecurityManager$DomainInfo@1033c7e[Subject(20975775).principals=org.jboss.security.SimplePrincipal@31077996(burgers@scmbdirectory.com)org.jboss.security.SimpleGroup@23598633(Roles(members))org.jboss.security.SimpleGroup@23598633(com(members:secure_capture_role,secure_view_role,secure_admin_role)),credential.class=java.lang.String@31054905,expirationTime=1151692054968]
      2006-06-30 19:57:35,030 TRACE [org.jboss.web.tomcat.security.JBossSecurityMgrRealm] Mapped from input principal: burgers@scmbdirectory.comto: burgers@scmbdirectory.com
      2006-06-30 19:57:35,030 TRACE [org.jboss.security.SecurityAssociation] getSubject, sc=org.jboss.security.SecurityAssociation$SubjectContext@650016{principal=burgers@scmbdirectory.com,subject=15250942}
      2006-06-30 19:57:35,030 TRACE [org.jboss.security.plugins.JaasSecurityManager.my-web] getUserRoles, subject: Subject:
      Principal: burgers@scmbdirectory.com
      Principal: Roles(members)
      Principal: com(members:secure_capture_role,secure_view_role,secure_admin_role)

      2006-06-30 19:57:35,046 TRACE [org.jboss.web.tomcat.security.JBossSecurityMgrRealm] End authenticate, principal=GenericPrincipal[burgers@scmbdirectory.com()]
      2006-06-30 19:57:35,046 DEBUG [org.apache.catalina.authenticator.FormAuthenticator] Authentication of 'burgers@scmbdirectory.com' was successful
      2006-06-30 19:57:35,046 DEBUG [org.apache.catalina.authenticator.FormAuthenticator] Redirecting to original '/corpcredit/svc?event=LOGIN_EVENT'
      2006-06-30 19:57:35,046 DEBUG [org.apache.catalina.authenticator.AuthenticatorBase] Failed authenticate() test ??/corpcredit/j_security_check
      2006-06-30 19:57:35,046 TRACE [org.jboss.security.SecurityAssociation] clear, server=true
      2006-06-30 19:57:35,046 DEBUG [org.apache.catalina.connector.CoyoteAdapter] Requested cookie session id is 9BA1A22C78369E56A6412D6E97FE102C
      2006-06-30 19:57:35,046 DEBUG [org.apache.catalina.authenticator.AuthenticatorBase] Security checking request GET /corpcredit/svc
      2006-06-30 19:57:35,046 DEBUG [org.apache.catalina.realm.RealmBase] Checking constraint 'SecurityConstraint[viewCol]' against GET /svc --> true
      2006-06-30 19:57:35,046 DEBUG [org.apache.catalina.realm.RealmBase] Checking constraint 'SecurityConstraint[captureCol]' against GET /svc --> false
      2006-06-30 19:57:35,046 DEBUG [org.apache.catalina.realm.RealmBase] Checking constraint 'SecurityConstraint[adminCol]' against GET /svc --> false
      2006-06-30 19:57:35,046 DEBUG [org.apache.catalina.authenticator.AuthenticatorBase] Calling hasUserDataPermission()
      2006-06-30 19:57:35,046 DEBUG [org.apache.catalina.realm.RealmBase] User data constraint has no restrictions
      2006-06-30 19:57:35,046 DEBUG [org.apache.catalina.authenticator.AuthenticatorBase] Calling authenticate()
      2006-06-30 19:57:35,046 DEBUG [org.apache.catalina.authenticator.FormAuthenticator] Restore request from session '9BA1A22C78369E56A6412D6E97FE102C'
      2006-06-30 19:57:35,046 DEBUG [org.apache.catalina.authenticator.AuthenticatorBase] Authenticated 'burgers@scmbdirectory.com' with type 'FORM'
      2006-06-30 19:57:35,046 DEBUG [org.apache.catalina.authenticator.FormAuthenticator] Proceed to restored request
      2006-06-30 19:57:35,046 DEBUG [org.apache.catalina.authenticator.AuthenticatorBase] Calling accessControl()
      2006-06-30 19:57:35,046 DEBUG [org.apache.catalina.realm.RealmBase] Checking roles GenericPrincipal[burgers@scmbdirectory.com()]
      2006-06-30 19:57:35,046 DEBUG [org.apache.catalina.realm.RealmBase] Username burgers@scmbdirectory.com does NOT have role secure_admin_role
      2006-06-30 19:57:35,046 DEBUG [org.apache.catalina.realm.RealmBase] No role found: secure_admin_role
      2006-06-30 19:57:35,046 DEBUG [org.apache.catalina.realm.RealmBase] Username burgers@scmbdirectory.com does NOT have role secure_view_role
      2006-06-30 19:57:35,046 DEBUG [org.apache.catalina.realm.RealmBase] No role found: secure_view_role
      2006-06-30 19:57:35,046 DEBUG [org.apache.catalina.realm.RealmBase] Username burgers@scmbdirectory.com does NOT have role secure_capture_role
      2006-06-30 19:57:35,046 DEBUG [org.apache.catalina.realm.RealmBase] No role found: secure_capture_role
      2006-06-30 19:57:35,046 DEBUG [org.apache.catalina.authenticator.AuthenticatorBase] Failed accessControl() test
      2006-06-30 19:57:35,046 TRACE [org.jboss.security.SecurityAssociation] clear, server=true
      2006-06-30 19:57:35,046 DEBUG [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] Processing ErrorPage[errorCode=403, location=/jsp/error/http/403.jsp]
      2006-06-30 19:57:35,046 DEBUG [org.apache.catalina.core.ApplicationDispatcher] servletPath=/jsp/error/http/403.jsp, pathInfo=null, queryString=null, name=null
      2006-06-30 19:57:35,046 DEBUG [org.apache.catalina.core.ApplicationDispatcher] Path Based Forward
      2006-06-30 19:57:35,046 DEBUG [org.apache.catalina.core.StandardWrapper] Returning non-STM instance
      2006-06-30 19:57:35,046 TRACE [org.jboss.web.tomcat.security.RunAsListener] jsp, runAs: null
      2006-06-30 19:57:35,046 TRACE [org.jboss.web.tomcat.security.RunAsListener] jsp, runAs: null
      2006-06-30 19:57:35,061 DEBUG [org.apache.catalina.loader.WebappClassLoader] loadClass(org.apache.jasper.runtime.JspSourceDependent, false)
      2006-06-30 19:57:35,061 DEBUG [org.apache.catalina.loader.WebappClassLoader] Searching local repositories
      2006-06-30 19:57:35,061 DEBUG [org.apache.catalina.loader.WebappClassLoader] findClass(org.apache.jasper.runtime.JspSourceDependent)
      2006-06-30 19:57:35,061 DEBUG [org.apache.catalina.loader.WebappClassLoader] findClassInternal(org.apache.jasper.runtime.JspSourceDependent)
      2006-06-30 19:57:35,061 DEBUG [org.apache.catalina.loader.WebappClassLoader] --> Passing on ClassNotFoundException
      2006-06-30 19:57:35,061 DEBUG [org.apache.catalina.loader.WebappClassLoader] Delegating to parent classloader at end: java.net.FactoryURLClassLoader@1b8aeb1
      2006-06-30 19:57:35,061 DEBUG [org.apache.catalina.loader.WebappClassLoader] Loading class from parent
      2006-06-30 19:57:35,061 DEBUG [org.apache.catalina.loader.WebappClassLoader] loadClass(org.apache.jasper.runtime.HttpJspBase, false)
      2006-06-30 19:57:35,061 DEBUG [org.apache.catalina.loader.WebappClassLoader] Searching local repositories
      2006-06-30 19:57:35,061 DEBUG [org.apache.catalina.loader.WebappClassLoader] findClass(org.apache.jasper.runtime.HttpJspBase)
      2006-06-30 19:57:35,061 DEBUG [org.apache.catalina.loader.WebappClassLoader] findClassInternal(org.apache.jasper.runtime.HttpJspBase)
      2006-06-30 19:57:35,061 DEBUG [org.apache.catalina.loader.WebappClassLoader] --> Passing on ClassNotFoundException
      2006-06-30 19:57:35,061 DEBUG [org.apache.catalina.loader.WebappClassLoader] Delegating to parent classloader at end: java.net.FactoryURLClassLoader@1b8aeb1
      2006-06-30 19:57:35,061 DEBUG [org.apache.catalina.loader.WebappClassLoader] Loading class from parent
      2006-06-30 19:57:35,077 DEBUG [org.apache.catalina.loader.WebappClassLoader] loadClass(java.lang.Object, false)
      2006-06-30 19:57:35,077 DEBUG [org.apache.catalina.loader.WebappClassLoader] Returning class from cache
      2006-06-30 19:57:35,077 DEBUG [org.apache.catalina.loader.WebappClassLoader] loadClass(javax.servlet.Servlet, false)
      2006-06-30 19:57:35,077 DEBUG [org.apache.catalina.loader.WebappClassLoader] Delegating to parent classloader1 java.net.FactoryURLClassLoader@1b8aeb1
      2006-06-30 19:57:35,077 DEBUG [org.apache.catalina.loader.WebappClassLoader] Loading class from parent
      2006-06-30 19:57:35,077 DEBUG [org.apache.catalina.loader.WebappClassLoader] loadClass(javax.servlet.ServletRequest, false)
      2006-06-30 19:57:35,077 DEBUG [org.apache.catalina.loader.WebappClassLoader] Returning class from cache
      2006-06-30 19:57:35,077 DEBUG [org.apache.catalina.loader.WebappClassLoader] loadClass(javax.servlet.ServletResponse, false)
      2006-06-30 19:57:35,077 DEBUG [org.apache.catalina.loader.WebappClassLoader] Returning class from cache
      2006-06-30 19:57:35,077 DEBUG [org.apache.catalina.loader.WebappClassLoader] loadClass(java.util.List, false)
      2006-06-30 19:57:35,077 DEBUG [org.apache.catalina.loader.WebappClassLoader] Returning class from cache
      2006-06-30 19:57:35,077 DEBUG [org.apache.catalina.loader.WebappClassLoader] loadClass(java.util.Vector, false)
      2006-06-30 19:57:35,077 DEBUG [org.apache.catalina.loader.WebappClassLoader] Returning class from cache
      2006-06-30 19:57:35,077 DEBUG [org.apache.catalina.loader.WebappClassLoader] loadClass(javax.servlet.jsp.JspFactory, false)
      2006-06-30 19:57:35,077 DEBUG [org.apache.catalina.loader.WebappClassLoader] Delegating to parent classloader1 java.net.FactoryURLClassLoader@1b8aeb1
      2006-06-30 19:57:35,077 DEBUG [org.apache.catalina.loader.WebappClassLoader] Loading class from parent
      2006-06-30 19:57:35,077 DEBUG [org.apache.catalina.loader.WebappClassLoader] loadClass(javax.servlet.http.HttpServletResponse, false)
      2006-06-30 19:57:35,077 DEBUG [org.apache.catalina.loader.WebappClassLoader] Returning class from cache
      2006-06-30 19:57:35,077 DEBUG [org.apache.catalina.loader.WebappClassLoader] loadClass(javax.servlet.jsp.PageContext, false)
      2006-06-30 19:57:35,077 DEBUG [org.apache.catalina.loader.WebappClassLoader] Returning class from cache
      2006-06-30 19:57:35,077 DEBUG [org.apache.catalina.loader.WebappClassLoader] loadClass(javax.servlet.jsp.JspWriter, false)
      2006-06-30 19:57:35,077 DEBUG [org.apache.catalina.loader.WebappClassLoader] Returning class from cache
      2006-06-30 19:57:35,077 DEBUG [org.apache.catalina.loader.WebappClassLoader] loadClass(javax.servlet.http.HttpServletRequest, false)
      2006-06-30 19:57:35,077 DEBUG [org.apache.catalina.loader.WebappClassLoader] Returning class from cache
      2006-06-30 19:57:35,077 DEBUG [org.apache.catalina.loader.WebappClassLoader] loadClass(java.lang.String, false)
      2006-06-30 19:57:35,077 DEBUG [org.apache.catalina.loader.WebappClassLoader] Returning class from cache
      2006-06-30 19:57:35,077 DEBUG [org.apache.catalina.loader.WebappClassLoader] loadClass(java.util.GregorianCalendar, false)
      2006-06-30 19:57:35,077 DEBUG [org.apache.catalina.realm.RealmBase] Username burgers@scmbdirectory.com does NOT have role secure_capture_role
      2006-06-30 19:57:35,077 DEBUG [org.apache.catalina.realm.RealmBase] Username burgers@scmbdirectory.com does NOT have role secure_capture_role
      2006-06-30 19:57:35,077 DEBUG [org.apache.catalina.realm.RealmBase] Username burgers@scmbdirectory.com does NOT have role secure_capture_role
      2006-06-30 19:57:35,077 DEBUG [org.apache.catalina.loader.WebappClassLoader] loadClass(za.co.scmb.www.corpcredit.common.Constants, false)
      2006-06-30 19:57:35,077 DEBUG [org.apache.catalina.loader.WebappClassLoader] Returning class from cache
      2006-06-30 19:57:35,077 TRACE [org.jboss.web.tomcat.security.RunAsListener] jsp, runAs: null
      2006-06-30 19:57:35,077 TRACE [org.jboss.web.tomcat.security.RunAsListener] jsp, runAs: null
      2006-06-30 19:57:35,077 DEBUG [org.apache.catalina.core.ApplicationDispatcher] Disabling the response for futher output

      ----------------------------------

      Exctract of : config files:
      <application-policy name = "my-web">

      <login-module
      code="org.jboss.security.auth.spi.UsersRolesLoginModule"
      flag = "required">
      <module-option name="usersProperties">props/tomcat-users.properties</module-option>
      <module-option name="rolesProperties">props/tomcat-roles.properties</module-option>
      </login-module>

      </application-policy>
      -------------------
      tomcat-users.properties
      admin=admin
      burgers@scmbdirectory.com=burgers
      ------------
      tomcat-roles.properties
      admin=JBossAdmin,HttpInvoker
      burgers@scmbdirectory.com=secure_view_role,secure_capture_role,secure_admin_role

      ------------
      web.xml (extract)

      DB Connection
      <res-ref-name>jdbc/corpcredit</res-ref-name>
      <res-type>javax.sql.DataSource</res-type>
      <res-auth>Container</res-auth>
      <res-sharing-scope>Shareable</res-sharing-scope>
      </resource-ref>
      <security-constraint>
      <display-name>view</display-name>
      <web-resource-collection>
      <web-resource-name>viewCol</web-resource-name>
      All view resources will be protected
      <url-pattern>/svc</url-pattern>
      <http-method>GET</http-method>
      <http-method>POST</http-method>
      </web-resource-collection>
      <auth-constraint>
      <role-name>secure_admin_role</role-name>
      <role-name>secure_view_role</role-name>
      <role-name>secure_capture_role</role-name>
      </auth-constraint>
      </security-constraint>
      <security-constraint>
      <display-name>capture</display-name>
      <web-resource-collection>
      <web-resource-name>captureCol</web-resource-name>
      All capture resources will be protected
      <url-pattern>/scc</url-pattern>
      <http-method>GET</http-method>
      <http-method>POST</http-method>
      </web-resource-collection>
      <auth-constraint>
      <role-name>secure_capture_role</role-name>
      </auth-constraint>
      </security-constraint>
      <security-constraint>
      <display-name>admin</display-name>
      <web-resource-collection>
      <web-resource-name>adminCol</web-resource-name>
      All admin resources will be protected
      <url-pattern>/sac</url-pattern>
      <http-method>GET</http-method>
      <http-method>POST</http-method>
      </web-resource-collection>
      <auth-constraint>
      <role-name>secure_admin_role</role-name>
      </auth-constraint>
      </security-constraint>
      <login-config>
      <auth-method>FORM</auth-method>
      <form-login-config>
      <form-login-page>/jsp/access/login.jsp</form-login-page>
      <form-error-page>/jsp/access/accessDenied.jsp</form-error-page>
      </form-login-config>
      </login-config>
      <security-role>
      Secure role for administrators
      <role-name>secure_admin_role</role-name>
      </security-role>
      <security-role>
      Secure role for capturers
      <role-name>secure_capture_role</role-name>
      </security-role>
      <security-role>
      Secure role for viewers
      <role-name>secure_view_role</role-name>
      </security-role>
      </web-app>

      To me this all is configured properly, but my login keeps failing.

        • 1. Re: Authentication not working even though it should
          anil.saldhana

          Looking at your authenticated subject:

          2006-06-30 19:57:35,030 TRACE [org.jboss.security.plugins.JaasSecurityManager.my-web] getUserRoles, subject: Subject:
           Principal: burgers@scmbdirectory.com
           Principal: Roles(members)
           Principal: com(members:secure_capture_role,secure_view_role,secure_admin_role)
          


          I can tell you that there is some custom tomcat valve or a Login Module
          that is screwing up the subject by adding the roles under a principal/group
          called as "com". It should have been under Roles.