7 Replies Latest reply on Mar 16, 2006 4:28 PM by chris griffith

    SingleSignOn in JBoss 4.0.2

    Tyrone Hed Newbie

      I have been spending weeks reading the documentation and poring over the web trying to figure out how to achieve Single Sign On between two webapps that are deployed in JBoss 4.02.
      All I can say is that this process is maddeningly difficult and complicated. I have a 3rd-party application that I have gotten to keep the JSESSIONIDSSO cookie but I cannot for the life of me figure out how to get our application to set or share that cookie. Here is where (I think) I am and the configuration files that are involved.
      If anyone has ANY complete configuration for this problem, I would be deeply grateful. Here are the steps that I know of. If I am wrong, I welcome any comments--positive or negative--if it leads to a final solution. Here goes:

      1.) <JBoss4.02Home>\server\default\deploy\jbossweb-tomcat55.sar
      Within this directory there is a file server.xml that contains this line:


      This line needs to be un-commented out.

      2.) <JBoss4.02Home>\server\apollo\conf\
      Within this directory there must be a file login-config.xml that needs to
      contain a block:

      <application-policy name = "MyRealm">

      <login-module code = "com.mycompany.authorization.jaas.DBLoginModule" flag = "required">
      <module-option name = "unauthenticatedIdentity">system</module-option>
      <module-option name="dsJndiName">java:/my-datasource</module-option>
      <module-option name="password-stacking">useFirstPass</module-option>
      <module-option name="rolesQuery">SELECT PERMISSIBLE_ROLE_IID, 'Roles' FROM PERMISSIBLE_ROLE_USER, PERMISSIBLE_USER WHERE PERMISSIBLE_USER.PERMISSIBLE_USER_IID = PERMISSIBLE_ROLE_USER, PERMISSIBLE_USER_IID AND PERMISSIBLE_USER.username = ?</module-option>
      </login-module>

      <login-module code = "org.jboss.security.ClientLoginModule" flag = "required"></login-module>

      </application-policy>

      3.) Within the same directory, there must be a file auth.conf that contains the following block:

      srp-client
      {
      // Example client auth.conf for using the SRPLoginModule
      org.jboss.security.srp.jaas.SRPLoginModule required
      password-stacking="useFirstPass"
      principalClassName="org.jboss.security.SimplePrincipal"
      srpServerJndiName="SRPServerInterface"
      debug=true
      ;

      // jBoss LoginModule
      org.jboss.security.ClientLoginModule required
      password-stacking="useFirstPass"
      ;

      // Put your login modules that need jBoss here
      };

      other
      {
      // jBoss LoginModule
      org.jboss.security.ClientLoginModule required
      ;

      // Put your login modules that need jBoss here
      };


      MyRealm
      {
      // MyRealm
      com.myco.authorization.jaas.DBLoginModule required
      password-stacking="useFirstPass"
      debug=true;
      };


      4.) The auth.conf from point #3 must also appear in the
      <JBoss4.02Home>\client directory.


      5.) Within the <JBoss4.02Home>\server\default\deploy\ directory the
      MyProduct.ear is of course placed. Within MyProduct.ear is
      MyProductWeb.war and within that is the WEB-INF directory.

      Within the WEB-INF directory there must of course be the web.xml file
      That web.xml file must contain the security constraints that apply and,
      most importantly, a block like the next three:

      <security-constraint>
      <web-resource-collection>
      <web-resource-name>Entry points</web-resource-name>
      <url-pattern>/MyProductWeb/*</url-pattern>
      </web-resource-collection>
      <auth-constraint>
      <role-name>myRole</role-name>
      </auth-constraint>
      </security-constraint>


      <login-config>
      <realm-name>MyRealm</realm-name>
      <auth-method>FORM</auth-method>
      <form-login-config>
      <form-login-page>/mylogin.jsp</form-login-page>
      </form-login-config>
      </login-config>

      <security-role>
      <role-name>myRole</role-name>
      </security-role>

      6.) Within the same directory as the web.xml, there must also be a file added called jboss-web.xml. This file must contain within its structure:

      <security-domain>java:/jaas/MyRealm</security-domain>

      <security-role>
      <role-name>myRole</role-name>
      <principal-name>myRole</principal-name>
      </security-role>

      7.) Moving back a few directories to just inside MyProduct.ear, we step into the META-INF directory and edit the application.xml file there to add the same role that exists in both the web.xml AND the jboss-web.xml

      <security-role>
      <role-name>myRole</role-name>
      </security-role>


      8.) Having done all this infernal configuration, I am still not getting SingleSignOn to work. Can anyone--PLEASE--tell me what I have done wrong? This difficulty is compounded by a habit of the fine folks at JBoss to change this with every release or even sub-release. For all I know, I may have a hodgepodge of configuration for JBoss3.2.6 and JBoss4.02.

      9.) I have at my disposal the JBoss 4 Application Server Guide 4.0.3.pdf (Which I am darned glad to have) but it has about a few paragraphs about SingleSignOn. (I hope I am wrong). Most guides tell you all you have to do is "Enable the SingleSignOn valve" but that's only the beginning of the odyssey.

      Thank you!
      Ty

        • 1. Re: SingleSignOn in JBoss 4.0.2
          chris griffith Expert

          tyronehed,

          Your first configuration step does not actually show what you uncommented. Just to make sure, what you want to do is uncomment (i.e. enable) the org.apache.catalina.authenticator.SingleSignOn valve. Your remaining configuration steps only deal with securing your application, and what to do with username and credential to authenticate user. Since I am assuming the user is authenticating correctly, I do not think that is where the problem is. What I think you need to do is enable TRACE loggin to see if authenticated Principal that is stored under SSO session is being handed over to security layer for authorization when moving between apps.

          Hope you can find a solution, cgriffith

          • 2. Re: SingleSignOn in JBoss 4.0.2
            Tyrone Hed Newbie

            Sorry about the missing line. Taking off the angle brackets, this is the missing line:

            Valve className="org.apache.catalina.authenticator.SingleSignOn"

            That is the most trivial part of enabling SingleSignOn. Annoyingly, every article you read on SSO blithely act as if it were as simple as uncommenting that line. I will assure you that it's not.

            • 3. Re: SingleSignOn in JBoss 4.0.2
              chris griffith Expert

              tyronhead,

              Just so you know, SSO is exclusively handled by Tomcat, not JBoss. If you are not using clusterd SSO. So any configuration needed to get it working, is not JBoss specific. That said. Provide some TRACE logging of Tomcat's security layer.

              cgriffith

              • 4. Re: SingleSignOn in JBoss 4.0.2
                Brian Stansberry Master

                tyronehed,

                1) Have you been able to properly authenticate to both your webapps individually? If not, the SSO valve is not going to help you.

                2) Not sure what you are doing with the SRP stuff. Are you sure you need this?

                3) In your step #6 (jboss-web.xml) the security-role element is not needed.

                4) Step #7 is not needed.

                • 5. Re: SingleSignOn in JBoss 4.0.2
                  Tyrone Hed Newbie

                  Brian,
                  Thank you for the reply. I have been able to get each of the apps to independantly authenticate and authorize.
                  I have been able to get one app (a third party) place the JSESSIONIDSSO cookie in session. I have not been able to get ours to do so, even though I have made the two configurations nearly identical.

                  • 6. Re: SingleSignOn in JBoss 4.0.2 [Log of a TRACE]
                    Tyrone Hed Newbie

                    2006-03-16 12:55:38,856 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] ---------------------------------------------------------------
                    2006-03-16 12:55:38,856 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] authType=null
                    2006-03-16 12:55:38,866 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] contentLength=-1
                    2006-03-16 12:55:38,866 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] contentType=text/html;charset=UTF-8
                    2006-03-16 12:55:38,866 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] cookie=JSESSIONID=267BFBD2DA9CDF5CE66450B84B7806BE; domain=null; path=/
                    2006-03-16 12:55:38,866 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] header=X-Powered-By=Servlet 2.4; JBoss-4.0.2 (build: CVSTag=JBoss_4_0_2 date=200507030743)/Tomcat-5.5
                    2006-03-16 12:55:38,866 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] header=Set-Cookie=JSESSIONID=267BFBD2DA9CDF5CE66450B84B7806BE; Path=/
                    2006-03-16 12:55:38,866 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] header=Content-Type=text/html;charset=UTF-8
                    2006-03-16 12:55:38,866 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] header=Transfer-Encoding=chunked
                    2006-03-16 12:55:38,866 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] header=Date=Thu, 16 Mar 2006 19:55:38 GMT
                    2006-03-16 12:55:38,866 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] message=null
                    2006-03-16 12:55:38,866 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] remoteUser=null
                    2006-03-16 12:55:38,866 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] status=200
                    2006-03-16 12:55:38,866 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] ===============================================================
                    2006-03-16 12:55:38,876 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] REQUEST URI =/ices/common/main.css
                    2006-03-16 12:55:38,876 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] authType=null
                    2006-03-16 12:55:38,876 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] characterEncoding=null
                    2006-03-16 12:55:38,876 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] contentLength=-1
                    2006-03-16 12:55:38,876 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] contentType=null
                    2006-03-16 12:55:38,876 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] contextPath=/ices
                    2006-03-16 12:55:38,876 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] cookie=JSESSIONID=267BFBD2DA9CDF5CE66450B84B7806BE
                    2006-03-16 12:55:38,876 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] header=accept=*/*
                    2006-03-16 12:55:38,876 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] header=referer=http://localhost:8080/ices/app
                    2006-03-16 12:55:38,876 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] header=accept-language=en-us
                    2006-03-16 12:55:38,876 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] header=accept-encoding=gzip, deflate
                    2006-03-16 12:55:38,876 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] header=if-modified-since=Thu, 16 Mar 2006 00:05:46 GMT
                    2006-03-16 12:55:38,876 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] header=if-none-match=W/"3106-1142467546376"
                    2006-03-16 12:55:38,876 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] header=user-agent=Mozilla/4.0 (compatible; MSIE 6.0; Windows NT 5.1; SV1; .NET CLR 2.0.50727)
                    2006-03-16 12:55:38,876 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] header=host=localhost:8080
                    2006-03-16 12:55:38,876 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] header=connection=Keep-Alive
                    2006-03-16 12:55:38,876 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] header=cookie=JSESSIONID=267BFBD2DA9CDF5CE66450B84B7806BE
                    2006-03-16 12:55:38,876 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] locale=en_US
                    2006-03-16 12:55:38,876 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] method=GET
                    2006-03-16 12:55:38,876 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] pathInfo=null
                    2006-03-16 12:55:38,876 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] protocol=HTTP/1.1
                    2006-03-16 12:55:38,876 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] queryString=null
                    2006-03-16 12:55:38,876 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] remoteAddr=127.0.0.1
                    2006-03-16 12:55:38,876 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] remoteHost=127.0.0.1
                    2006-03-16 12:55:38,876 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] remoteUser=null
                    2006-03-16 12:55:38,876 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] requestedSessionId=267BFBD2DA9CDF5CE66450B84B7806BE
                    2006-03-16 12:55:38,876 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] scheme=http
                    2006-03-16 12:55:38,876 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] serverName=localhost
                    2006-03-16 12:55:38,876 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] serverPort=8080
                    2006-03-16 12:55:38,876 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] servletPath=/common/main.css
                    2006-03-16 12:55:38,876 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] isSecure=false
                    2006-03-16 12:55:38,876 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] ---------------------------------------------------------------
                    2006-03-16 12:55:38,876 DEBUG [org.apache.catalina.authenticator.AuthenticatorBase] Security checking request GET /ices/common/main.css
                    2006-03-16 12:55:41,840 DEBUG [org.apache.catalina.authenticator.AuthenticatorBase] Not subject to any constraint
                    2006-03-16 12:55:41,860 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] ---------------------------------------------------------------
                    2006-03-16 12:55:41,860 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] authType=null
                    2006-03-16 12:55:41,860 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] contentLength=-1
                    2006-03-16 12:55:41,860 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] contentType=null
                    2006-03-16 12:55:41,860 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] header=X-Powered-By=Servlet 2.4; JBoss-4.0.2 (build: CVSTag=JBoss_4_0_2 date=200507030743)/Tomcat-5.5
                    2006-03-16 12:55:41,860 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] message=null
                    2006-03-16 12:55:41,860 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] remoteUser=null
                    2006-03-16 12:55:41,860 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] status=304
                    2006-03-16 12:55:41,860 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] ===============================================================
                    2006-03-16 12:55:41,900 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] REQUEST URI =/ices/images/topbar_repeater.gif
                    2006-03-16 12:55:41,900 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] authType=null
                    2006-03-16 12:55:41,900 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] characterEncoding=null
                    2006-03-16 12:55:41,900 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] contentLength=-1
                    2006-03-16 12:55:41,900 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] contentType=null
                    2006-03-16 12:55:41,900 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] contextPath=/ices
                    2006-03-16 12:55:41,900 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] cookie=JSESSIONID=267BFBD2DA9CDF5CE66450B84B7806BE
                    2006-03-16 12:55:41,900 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] header=accept=*/*
                    2006-03-16 12:55:41,900 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] header=referer=http://localhost:8080/ices/app
                    2006-03-16 12:55:41,900 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] header=accept-language=en-us
                    2006-03-16 12:55:41,900 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] header=accept-encoding=gzip, deflate
                    2006-03-16 12:55:41,900 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] header=if-modified-since=Thu, 16 Mar 2006 00:05:46 GMT
                    2006-03-16 12:55:41,900 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] header=if-none-match=W/"303-1142467546777"
                    2006-03-16 12:55:41,900 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] header=user-agent=Mozilla/4.0 (compatible; MSIE 6.0; Windows NT 5.1; SV1; .NET CLR 2.0.50727)
                    2006-03-16 12:55:41,900 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] header=host=localhost:8080
                    2006-03-16 12:55:41,910 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] REQUEST URI =/ices/images/topbar.gif
                    2006-03-16 12:55:41,910 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] header=connection=Keep-Alive
                    2006-03-16 12:55:41,910 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] authType=null
                    2006-03-16 12:55:41,910 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] characterEncoding=null
                    2006-03-16 12:55:41,910 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] contentLength=-1
                    2006-03-16 12:55:41,910 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] contentType=null
                    2006-03-16 12:55:41,910 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] contextPath=/ices
                    2006-03-16 12:55:41,910 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] cookie=JSESSIONID=267BFBD2DA9CDF5CE66450B84B7806BE
                    2006-03-16 12:55:41,910 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] header=cookie=JSESSIONID=267BFBD2DA9CDF5CE66450B84B7806BE
                    2006-03-16 12:55:41,910 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] header=accept=*/*
                    2006-03-16 12:55:41,910 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] locale=en_US
                    2006-03-16 12:55:41,910 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] header=referer=http://localhost:8080/ices/app
                    2006-03-16 12:55:41,910 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] method=GET
                    2006-03-16 12:55:41,910 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] pathInfo=null
                    2006-03-16 12:55:41,910 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] protocol=HTTP/1.1
                    2006-03-16 12:55:41,910 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] queryString=null
                    2006-03-16 12:55:41,920 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] header=accept-language=en-us
                    2006-03-16 12:55:41,920 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] remoteAddr=127.0.0.1
                    2006-03-16 12:55:41,920 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] header=accept-encoding=gzip, deflate
                    2006-03-16 12:55:41,920 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] remoteHost=127.0.0.1
                    2006-03-16 12:55:41,920 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] header=if-modified-since=Thu, 16 Mar 2006 00:05:46 GMT
                    2006-03-16 12:55:41,920 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] header=if-none-match=W/"10564-1142467546737"
                    2006-03-16 12:55:41,920 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] header=user-agent=Mozilla/4.0 (compatible; MSIE 6.0; Windows NT 5.1; SV1; .NET CLR 2.0.50727)
                    2006-03-16 12:55:41,920 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] remoteUser=null
                    2006-03-16 12:55:41,920 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] header=host=localhost:8080
                    2006-03-16 12:55:41,920 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] requestedSessionId=267BFBD2DA9CDF5CE66450B84B7806BE
                    2006-03-16 12:55:41,920 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] header=connection=Keep-Alive
                    2006-03-16 12:55:41,920 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] scheme=http
                    2006-03-16 12:55:41,920 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] serverName=localhost
                    2006-03-16 12:55:41,920 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] serverPort=8080
                    2006-03-16 12:55:41,920 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] servletPath=/images/topbar_repeater.gif
                    2006-03-16 12:55:41,920 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] header=cookie=JSESSIONID=267BFBD2DA9CDF5CE66450B84B7806BE
                    2006-03-16 12:55:41,920 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] isSecure=false
                    2006-03-16 12:55:41,920 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] locale=en_US
                    2006-03-16 12:55:41,920 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] ---------------------------------------------------------------
                    2006-03-16 12:55:41,920 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] method=GET
                    2006-03-16 12:55:41,920 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] pathInfo=null
                    2006-03-16 12:55:41,920 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] protocol=HTTP/1.1
                    2006-03-16 12:55:41,920 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] queryString=null
                    2006-03-16 12:55:41,920 DEBUG [org.apache.catalina.authenticator.AuthenticatorBase] Security checking request GET /ices/images/topbar_repeater.gif
                    2006-03-16 12:55:41,920 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] remoteAddr=127.0.0.1
                    2006-03-16 12:55:41,920 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] remoteHost=127.0.0.1
                    2006-03-16 12:55:41,920 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] remoteUser=null
                    2006-03-16 12:55:41,920 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] requestedSessionId=267BFBD2DA9CDF5CE66450B84B7806BE
                    2006-03-16 12:55:41,920 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] scheme=http
                    2006-03-16 12:55:41,920 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] serverName=localhost
                    2006-03-16 12:55:41,920 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] serverPort=8080
                    2006-03-16 12:55:42,571 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] servletPath=/images/topbar.gif
                    2006-03-16 12:55:42,571 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] isSecure=false
                    2006-03-16 12:55:42,571 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] ---------------------------------------------------------------
                    2006-03-16 12:55:42,571 DEBUG [org.apache.catalina.authenticator.AuthenticatorBase] Security checking request GET /ices/images/topbar.gif
                    2006-03-16 12:55:43,993 DEBUG [org.apache.catalina.authenticator.AuthenticatorBase] Not subject to any constraint
                    2006-03-16 12:55:43,993 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] ---------------------------------------------------------------
                    2006-03-16 12:55:43,993 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] authType=null
                    2006-03-16 12:55:43,993 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] contentLength=-1
                    2006-03-16 12:55:43,993 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] contentType=null
                    2006-03-16 12:55:43,993 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] header=X-Powered-By=Servlet 2.4; JBoss-4.0.2 (build: CVSTag=JBoss_4_0_2 date=200507030743)/Tomcat-5.5
                    2006-03-16 12:55:43,993 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] message=null
                    2006-03-16 12:55:43,993 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] remoteUser=null
                    2006-03-16 12:55:43,993 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] status=304
                    2006-03-16 12:55:43,993 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] ===============================================================
                    2006-03-16 12:55:44,003 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] REQUEST URI =/ices/images/3linebg.gif
                    2006-03-16 12:55:44,003 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] authType=null
                    2006-03-16 12:55:44,003 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] characterEncoding=null
                    2006-03-16 12:55:44,003 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] contentLength=-1
                    2006-03-16 12:55:44,003 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] contentType=null
                    2006-03-16 12:55:44,003 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] contextPath=/ices
                    2006-03-16 12:55:44,583 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] cookie=JSESSIONID=267BFBD2DA9CDF5CE66450B84B7806BE
                    2006-03-16 12:55:44,583 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] header=accept=*/*
                    2006-03-16 12:55:44,583 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] header=referer=http://localhost:8080/ices/app
                    2006-03-16 12:55:44,583 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] header=accept-language=en-us
                    2006-03-16 12:55:44,583 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] header=accept-encoding=gzip, deflate
                    2006-03-16 12:55:44,583 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] header=if-modified-since=Thu, 16 Mar 2006 00:05:46 GMT
                    2006-03-16 12:55:44,583 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] header=if-none-match=W/"54-1142467546437"
                    2006-03-16 12:55:44,583 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] header=user-agent=Mozilla/4.0 (compatible; MSIE 6.0; Windows NT 5.1; SV1; .NET CLR 2.0.50727)
                    2006-03-16 12:55:44,583 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] header=host=localhost:8080
                    2006-03-16 12:55:44,583 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] header=connection=Keep-Alive
                    2006-03-16 12:55:44,583 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] header=cookie=JSESSIONID=267BFBD2DA9CDF5CE66450B84B7806BE
                    2006-03-16 12:55:44,583 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] locale=en_US
                    2006-03-16 12:55:44,583 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] method=GET
                    2006-03-16 12:55:44,583 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] pathInfo=null
                    2006-03-16 12:55:44,583 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] protocol=HTTP/1.1
                    2006-03-16 12:55:44,583 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] queryString=null
                    2006-03-16 12:55:44,583 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] remoteAddr=127.0.0.1
                    2006-03-16 12:55:44,583 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] remoteHost=127.0.0.1
                    2006-03-16 12:55:44,583 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] remoteUser=null
                    2006-03-16 12:55:44,583 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] requestedSessionId=267BFBD2DA9CDF5CE66450B84B7806BE
                    2006-03-16 12:55:44,583 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] scheme=http
                    2006-03-16 12:55:44,583 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] serverName=localhost
                    2006-03-16 12:55:44,583 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] serverPort=8080
                    2006-03-16 12:55:44,583 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] servletPath=/images/3linebg.gif
                    2006-03-16 12:55:44,583 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] isSecure=false
                    2006-03-16 12:55:44,583 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] ---------------------------------------------------------------
                    2006-03-16 12:55:44,583 DEBUG [org.apache.catalina.authenticator.AuthenticatorBase] Security checking request GET /ices/images/3linebg.gif
                    2006-03-16 12:55:45,645 DEBUG [org.apache.catalina.authenticator.AuthenticatorBase] Not subject to any constraint
                    2006-03-16 12:55:45,645 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] ---------------------------------------------------------------
                    2006-03-16 12:55:45,645 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] authType=null
                    2006-03-16 12:55:45,645 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] contentLength=-1
                    2006-03-16 12:55:45,645 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] contentType=null
                    2006-03-16 12:55:45,645 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] header=X-Powered-By=Servlet 2.4; JBoss-4.0.2 (build: CVSTag=JBoss_4_0_2 date=200507030743)/Tomcat-5.5
                    2006-03-16 12:55:45,645 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] message=null
                    2006-03-16 12:55:45,645 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] remoteUser=null
                    2006-03-16 12:55:45,835 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] status=304
                    2006-03-16 12:55:45,835 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] ===============================================================
                    2006-03-16 12:55:46,586 DEBUG [org.apache.catalina.authenticator.AuthenticatorBase] Not subject to any constraint
                    2006-03-16 12:55:46,586 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] ---------------------------------------------------------------
                    2006-03-16 12:55:46,586 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] authType=null
                    2006-03-16 12:55:46,586 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] contentLength=-1
                    2006-03-16 12:55:46,586 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] contentType=null
                    2006-03-16 12:55:46,586 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] header=X-Powered-By=Servlet 2.4; JBoss-4.0.2 (build: CVSTag=JBoss_4_0_2 date=200507030743)/Tomcat-5.5
                    2006-03-16 12:55:46,586 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] message=null
                    2006-03-16 12:55:46,586 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] remoteUser=null
                    2006-03-16 12:55:46,586 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] status=304
                    2006-03-16 12:55:46,586 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] ===============================================================
                    2006-03-16 12:56:04,550 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] REQUEST URI =/ices/app
                    2006-03-16 12:56:04,550 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] authType=null
                    2006-03-16 12:56:04,550 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] characterEncoding=null
                    2006-03-16 12:56:04,550 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] contentLength=171
                    2006-03-16 12:56:04,550 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] contentType=application/x-www-form-urlencoded
                    2006-03-16 12:56:04,550 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] contextPath=/ices
                    2006-03-16 12:56:04,550 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] cookie=JSESSIONID=267BFBD2DA9CDF5CE66450B84B7806BE
                    2006-03-16 12:56:04,550 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] header=accept=image/gif, image/x-xbitmap, image/jpeg, image/pjpeg, application/vnd.ms-excel, application/vnd.ms-powerpoint, application/msword, application/x-shockwave-flash, */*
                    2006-03-16 12:56:04,550 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] header=referer=http://localhost:8080/ices/app
                    2006-03-16 12:56:04,560 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] header=accept-language=en-us
                    2006-03-16 12:56:04,560 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] header=content-type=application/x-www-form-urlencoded
                    2006-03-16 12:56:04,560 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] header=accept-encoding=gzip, deflate
                    2006-03-16 12:56:04,560 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] header=user-agent=Mozilla/4.0 (compatible; MSIE 6.0; Windows NT 5.1; SV1; .NET CLR 2.0.50727)
                    2006-03-16 12:56:04,560 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] header=host=localhost:8080
                    2006-03-16 12:56:04,560 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] header=content-length=171
                    2006-03-16 12:56:04,560 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] header=connection=Keep-Alive
                    2006-03-16 12:56:04,560 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] header=cache-control=no-cache
                    2006-03-16 12:56:04,560 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] header=cookie=JSESSIONID=267BFBD2DA9CDF5CE66450B84B7806BE
                    2006-03-16 12:56:04,560 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] locale=en_US
                    2006-03-16 12:56:04,560 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] method=POST
                    2006-03-16 12:56:04,560 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] parameter=formids=userName,password,Submit
                    2006-03-16 12:56:04,560 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] parameter=submitmode=submit
                    2006-03-16 12:56:04,560 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] parameter=service=direct
                    2006-03-16 12:56:04,560 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] parameter=page=Home
                    2006-03-16 12:56:04,560 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] parameter=session=T
                    2006-03-16 12:56:04,560 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] parameter=component=LoginForm
                    2006-03-16 12:56:04,560 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] parameter=submitname=
                    2006-03-16 12:56:04,560 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] parameter=userName=rtsAdmin
                    2006-03-16 12:56:04,560 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] parameter=password=rtsAdmin
                    2006-03-16 12:56:04,560 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] parameter=Submit=Log In
                    2006-03-16 12:56:04,560 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] pathInfo=null
                    2006-03-16 12:56:04,560 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] protocol=HTTP/1.1
                    2006-03-16 12:56:04,560 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] queryString=null
                    2006-03-16 12:56:04,560 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] remoteAddr=127.0.0.1
                    2006-03-16 12:56:04,560 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] remoteHost=127.0.0.1
                    2006-03-16 12:56:04,560 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] remoteUser=null
                    2006-03-16 12:56:04,560 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] requestedSessionId=267BFBD2DA9CDF5CE66450B84B7806BE
                    2006-03-16 12:56:04,560 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] scheme=http
                    2006-03-16 12:56:04,560 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] serverName=localhost
                    2006-03-16 12:56:04,560 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] serverPort=8080
                    2006-03-16 12:56:04,560 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] servletPath=/app
                    2006-03-16 12:56:04,560 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] isSecure=false
                    2006-03-16 12:56:04,560 INFO [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost]] ---------------------------------------------------------------
                    2006-03-16 12:56:04,560 DEBUG [org.apache.catalina.authenticator.AuthenticatorBase] Security checking request POST /ices/app
                    2006-03-16 12:56:07,214 DEBUG [org.apache.catalina.authenticator.AuthenticatorBase] Not subject to any constraint


                    2006-03-16 12:56:09,096 INFO [com.tyco.provider.authorization.umg.AuthorizationFrameworkInitializer] Got Role Table Name PERMISSIBLE_ROLE_USER
                    2006-03-16 12:56:09,296 DEBUG [com.tyco.provider.authorization.ejb.AuthorizationServiceDelegateFactory] AuthorizationService.encrypt 20
                    2006-03-16 12:56:11,840 DEBUG [com.tyco.provider.authorization.jaas.DBLoginModule] initialize
                    2006-03-16 12:56:11,850 DEBUG [com.tyco.provider.authorization.jaas.DBLoginModule] Saw unauthenticatedIdentity=system
                    2006-03-16 12:56:11,850 DEBUG [com.tyco.provider.authorization.jaas.DBLoginModule] DatabaseServerLoginModule, dsJndiName=java:/apollo-datasource
                    2006-03-16 12:56:11,850 DEBUG [com.tyco.provider.authorization.jaas.DBLoginModule] rolesQuery=SELECT PERMISSIBLE_ROLE_IID, 'Roles' FROM PERMISSIBLE_ROLE_USER, PERMISSIBLE_USER WHERE PERMISSIBLE_USER.PERMISSIBLE_USER_IID = PERMISSIBLE_ROLE_USER.PERMISSIBLE_USER_IID AND PERMISSIBLE_USER.username = ?
                    2006-03-16 12:56:13,222 DEBUG [com.tyco.provider.authorization.jaas.DBLoginModule] login
                    2006-03-16 12:56:13,242 INFO [com.tyco.provider.util.hibernate.HibernateUtil] Initializing Hibernate using: apollo.cfg.xml
                    2006-03-16 12:56:15,004 INFO [com.tyco.provider.util.hibernate.HibernateUtil] Hibernate is using the dialect: org.hibernate.dialect.SQLServerDialect
                    2006-03-16 12:56:15,004 INFO [com.tyco.provider.util.hibernate.HibernateUtil] Configuring for SQL Server.
                    2006-03-16 12:56:15,495 DEBUG [net.sf.ehcache.CacheManager] Creating new CacheManager with default config
                    2006-03-16 12:56:15,505 DEBUG [net.sf.ehcache.CacheManager] Configuring ehcache from classpath.
                    2006-03-16 12:56:15,515 WARN [net.sf.ehcache.config.Configurator] No configuration found. Configuring ehcache from ehcache-failsafe.xml found in the classpath: jar:file:/C:/dev/JBossAll/JBossZelda/server/apollo/tmp/deploy/tmp63581ehcache-1.1.jar!/ehcache-failsafe.xml
                    2006-03-16 12:56:15,525 DEBUG [net.sf.ehcache.config.Configuration$DiskStore] Disk Store Path: C:\DOCUME~1\thunter\LOCALS~1\Temp\
                    2006-03-16 12:56:17,297 DEBUG [com.tyco.provider.presentation.metadata.model.TableDataImpl] Setting table name to null
                    2006-03-16 12:56:17,297 DEBUG [com.tyco.provider.presentation.metadata.model.TableDataImpl] Setting sort field to null
                    2006-03-16 12:56:19,190 WARN [org.hibernate.impl.SessionFactoryObjectFactory] InitialContext did not implement EventContext
                    2006-03-16 12:56:19,750 INFO [STDOUT] Hibernate: select top 1 userimpl0_.PERMISSIBLE_USER_IID as PERMISSI1_2_, userimpl0_.DEFAULT_ENTERPRISE_IID as DEFAULT2_2_, userimpl0_.USERNAME as USERNAME2_, userimpl0_.RESET_PASSWORD as RESET4_2_, userimpl0_.EXPIRATION_DATE as EXPIRATION5_2_, userimpl0_.FULL_NAME as FULL6_2_, userimpl0_.PHONE as PHONE2_, userimpl0_.STATUS as STATUS2_, userimpl0_.TITLE as TITLE2_, userimpl0_.EMAIL as EMAIL2_, userimpl0_.FAILED_LOGIN_ATTEMPTS as FAILED11_2_, userimpl0_.LAST_FAILED_LOGIN_ATTEMPT as LAST12_2_, userimpl0_.STATUS_CHANGE_DATE as STATUS13_2_ from PERMISSIBLE_USER userimpl0_ where userimpl0_.USERNAME=?
                    2006-03-16 12:56:19,840 INFO [STDOUT] Hibernate: select enterprise0_.PERMISSIBLE_USER_IID as PERMISSI1_2_, enterprise0_.ENTERPRISE_IID as ENTERPRISE2_2_, enterprise1_.ENTERPRISE_IID as ENTERPRISE1_9_0_, enterprise1_.PARENT_ID as PARENT6_9_0_, enterprise1_.ROUTING_ID as ROUTING2_9_0_, enterprise1_.NAME as NAME9_0_, enterprise1_.DESCRIPTION as DESCRIPT4_9_0_, enterprise1_.STATUS as STATUS9_0_, enterprise2_.ENTERPRISE_IID as ENTERPRISE1_9_1_, enterprise2_.PARENT_ID as PARENT6_9_1_, enterprise2_.ROUTING_ID as ROUTING2_9_1_, enterprise2_.NAME as NAME9_1_, enterprise2_.DESCRIPTION as DESCRIPT4_9_1_, enterprise2_.STATUS as STATUS9_1_ from PERMISSIBLE_USER_ENTERPRISE enterprise0_ left outer join ENTERPRISE enterprise1_ on enterprise0_.ENTERPRISE_IID=enterprise1_.ENTERPRISE_IID left outer join ENTERPRISE enterprise2_ on enterprise1_.PARENT_ID=enterprise2_.ENTERPRISE_IID where enterprise0_.PERMISSIBLE_USER_IID=?
                    2006-03-16 12:56:19,840 INFO [STDOUT] Hibernate: select userpasswo0_.PERMISSIBLE_USER_IID as PERMISSI5_1_, userpasswo0_.PERMISSIBLE_PASSWORD_IID as PERMISSI1_1_, userpasswo0_.PERMISSIBLE_PASSWORD_IID as PERMISSI1_4_0_, userpasswo0_.ENCRYPTED_PASSWORD as ENCRYPTED2_4_0_, userpasswo0_.DATE_SET as DATE3_4_0_, userpasswo0_.STATUS as STATUS4_0_, userpasswo0_.PERMISSIBLE_USER_IID as PERMISSI5_4_0_ from PERMISSIBLE_PASSWORD userpasswo0_ where userpasswo0_.PERMISSIBLE_USER_IID=? order by userpasswo0_.DATE_SET desc
                    2006-03-16 12:56:23,646 DEBUG [com.tyco.provider.authorization.jaas.DBLoginModule] User 'rtsAdmin' authenticated, loginOk=true
                    2006-03-16 12:56:23,646 DEBUG [com.tyco.provider.authorization.jaas.DBLoginModule] commit, loginOk=true
                    2006-03-16 12:56:23,646 INFO [STDOUT] Attempting to connect: rtsAdmin
                    2006-03-16 12:56:25,338 DEBUG [com.tyco.provider.authorization.ejb.SecurityManagerSession] Getting user by userName rtsAdmin
                    2006-03-16 12:56:27,010 INFO [STDOUT] Hibernate: select top 1 userimpl0_.PERMISSIBLE_USER_IID as PERMISSI1_2_, userimpl0_.DEFAULT_ENTERPRISE_IID as DEFAULT2_2_, userimpl0_.USERNAME as USERNAME2_, userimpl0_.RESET_PASSWORD as RESET4_2_, userimpl0_.EXPIRATION_DATE as EXPIRATION5_2_, userimpl0_.FULL_NAME as FULL6_2_, userimpl0_.PHONE as PHONE2_, userimpl0_.STATUS as STATUS2_, userimpl0_.TITLE as TITLE2_, userimpl0_.EMAIL as EMAIL2_, userimpl0_.FAILED_LOGIN_ATTEMPTS as FAILED11_2_, userimpl0_.LAST_FAILED_LOGIN_ATTEMPT as LAST12_2_, userimpl0_.STATUS_CHANGE_DATE as STATUS13_2_ from PERMISSIBLE_USER userimpl0_ where userimpl0_.USERNAME=?
                    2006-03-16 12:56:27,010 INFO [STDOUT] Hibernate: select enterprise0_.PERMISSIBLE_USER_IID as PERMISSI1_2_, enterprise0_.ENTERPRISE_IID as ENTERPRISE2_2_, enterprise1_.ENTERPRISE_IID as ENTERPRISE1_9_0_, enterprise1_.PARENT_ID as PARENT6_9_0_, enterprise1_.ROUTING_ID as ROUTING2_9_0_, enterprise1_.NAME as NAME9_0_, enterprise1_.DESCRIPTION as DESCRIPT4_9_0_, enterprise1_.STATUS as STATUS9_0_, enterprise2_.ENTERPRISE_IID as ENTERPRISE1_9_1_, enterprise2_.PARENT_ID as PARENT6_9_1_, enterprise2_.ROUTING_ID as ROUTING2_9_1_, enterprise2_.NAME as NAME9_1_, enterprise2_.DESCRIPTION as DESCRIPT4_9_1_, enterprise2_.STATUS as STATUS9_1_ from PERMISSIBLE_USER_ENTERPRISE enterprise0_ left outer join ENTERPRISE enterprise1_ on enterprise0_.ENTERPRISE_IID=enterprise1_.ENTERPRISE_IID left outer join ENTERPRISE enterprise2_ on enterprise1_.PARENT_ID=enterprise2_.ENTERPRISE_IID where enterprise0_.PERMISSIBLE_USER_IID=?
                    2006-03-16 12:56:27,010 INFO [STDOUT] Hibernate: select userpasswo0_.PERMISSIBLE_USER_IID as PERMISSI5_1_, userpasswo0_.PERMISSIBLE_PASSWORD_IID as PERMISSI1_1_, userpasswo0_.PERMISSIBLE_PASSWORD_IID as PERMISSI1_4_0_, userpasswo0_.ENCRYPTED_PASSWORD as ENCRYPTED2_4_0_, userpasswo0_.DATE_SET as DATE3_4_0_, userpasswo0_.STATUS as STATUS4_0_, userpasswo0_.PERMISSIBLE_USER_IID as PERMISSI5_4_0_ from PERMISSIBLE_PASSWORD userpasswo0_ where userpasswo0_.PERMISSIBLE_USER_IID=? order by userpasswo0_.DATE_SET desc
                    2006-03-16 12:56:27,020 DEBUG [com.tyco.provider.authorization.umg.TransactIdentityValidator] Getting User Identity 3
                    2006-03-16 12:56:27,020 DEBUG [com.tyco.provider.authorization.ejb.SecurityManagerSession] User rtsAdmin currently has 5 roles
                    2006-03-16 12:56:27,020 DEBUG [com.tyco.provider.authorization.ejb.SecurityManagerSession] User has role 2
                    2006-03-16 12:56:27,020 DEBUG [com.tyco.provider.authorization.ejb.SecurityManagerSession] Getting role by id 2
                    2006-03-16 12:56:27,020 DEBUG [com.tyco.provider.authorization.ejb.SecurityManagerSession] Getting com.tyco.provider.authorization.model.RoleImpl with IID 2
                    2006-03-16 12:56:27,020 INFO [STDOUT] Hibernate: select roleimpl0_.PERMISSIBLE_ROLE_IID as PERMISSI1_1_0_, roleimpl0_.ROLE_NAME as ROLE2_1_0_, roleimpl0_.DESCRIPTION as DESCRIPT3_1_0_, roleimpl0_.STATUS as STATUS1_0_, roleimpl0_.SYSTEM as SYSTEM1_0_ from PERMISSIBLE_ROLE roleimpl0_ where roleimpl0_.PERMISSIBLE_ROLE_IID=?
                    2006-03-16 12:56:27,020 DEBUG [com.tyco.provider.authorization.ejb.SecurityManagerSession] Adding association data to { ID=2 Name=System Administrator Description=Administers user and role information. They manage user account and their corresponding passwords. Can also modify the system password policy. Also have access to most aspects of the system in order to help monitor and configure it. Status=1 is system=true}
                    2006-03-16 12:56:27,020 DEBUG [com.tyco.provider.authorization.umg.TransactIdentityValidator] Getting Role Identity 2
                    2006-03-16 12:56:27,040 DEBUG [com.tyco.provider.authorization.ejb.SecurityManagerSession] Permission (com.tyco.provider.api.authorization.FreyaPermission user modify, view) Granted to 2
                    2006-03-16 12:56:27,040 DEBUG [com.tyco.provider.authorization.ejb.SecurityManagerSession] Permission (com.tyco.provider.api.authorization.FreyaPermission enterprise create, modify, peer) Granted to 2
                    2006-03-16 12:56:27,040 DEBUG [com.tyco.provider.authorization.ejb.SecurityManagerSession] Permission (com.tyco.provider.api.authorization.FreyaPermission knowledge base modify, view) Granted to 2
                    2006-03-16 12:56:27,040 DEBUG [com.tyco.provider.authorization.ejb.SecurityManagerSession] Permission (com.tyco.provider.api.authorization.FreyaPermission role modify, view) Granted to 2
                    2006-03-16 12:56:27,040 DEBUG [com.tyco.provider.authorization.ejb.SecurityManagerSession] User has role 4
                    2006-03-16 12:56:27,040 DEBUG [com.tyco.provider.authorization.ejb.SecurityManagerSession] Getting role by id 4
                    2006-03-16 12:56:27,040 DEBUG [com.tyco.provider.authorization.ejb.SecurityManagerSession] Getting com.tyco.provider.authorization.model.RoleImpl with IID 4
                    2006-03-16 12:56:27,040 INFO [STDOUT] Hibernate: select roleimpl0_.PERMISSIBLE_ROLE_IID as PERMISSI1_1_0_, roleimpl0_.ROLE_NAME as ROLE2_1_0_, roleimpl0_.DESCRIPTION as DESCRIPT3_1_0_, roleimpl0_.STATUS as STATUS1_0_, roleimpl0_.SYSTEM as SYSTEM1_0_ from PERMISSIBLE_ROLE roleimpl0_ where roleimpl0_.PERMISSIBLE_ROLE_IID=?
                    2006-03-16 12:56:27,040 DEBUG [com.tyco.provider.authorization.ejb.SecurityManagerSession] Adding association data to { ID=4 Name=System Manager Description=Not sure what this one does. Status=1 is system=true}
                    2006-03-16 12:56:27,040 DEBUG [com.tyco.provider.authorization.umg.TransactIdentityValidator] Getting Role Identity 4
                    2006-03-16 12:56:27,040 DEBUG [com.tyco.provider.authorization.ejb.SecurityManagerSession] Permission (com.tyco.provider.api.authorization.FreyaPermission knowledge base modify, view) Granted to 4
                    2006-03-16 12:56:27,040 DEBUG [com.tyco.provider.authorization.ejb.SecurityManagerSession] User has role 6
                    2006-03-16 12:56:27,040 DEBUG [com.tyco.provider.authorization.ejb.SecurityManagerSession] Getting role by id 6
                    2006-03-16 12:56:27,040 DEBUG [com.tyco.provider.authorization.ejb.SecurityManagerSession] Getting com.tyco.provider.authorization.model.RoleImpl with IID 6
                    2006-03-16 12:56:27,040 INFO [STDOUT] Hibernate: select roleimpl0_.PERMISSIBLE_ROLE_IID as PERMISSI1_1_0_, roleimpl0_.ROLE_NAME as ROLE2_1_0_, roleimpl0_.DESCRIPTION as DESCRIPT3_1_0_, roleimpl0_.STATUS as STATUS1_0_, roleimpl0_.SYSTEM as SYSTEM1_0_ from PERMISSIBLE_ROLE roleimpl0_ where roleimpl0_.PERMISSIBLE_ROLE_IID=?
                    2006-03-16 12:56:27,050 DEBUG [com.tyco.provider.authorization.ejb.SecurityManagerSession] Adding association data to { ID=6 Name=Rules Description=Responsible for teaching the system. Status=1 is system=true}
                    2006-03-16 12:56:27,050 DEBUG [com.tyco.provider.authorization.umg.TransactIdentityValidator] Getting Role Identity 6
                    2006-03-16 12:56:27,050 DEBUG [com.tyco.provider.authorization.ejb.SecurityManagerSession] Permission (com.tyco.provider.api.authorization.FreyaPermission knowledge base modify, view) Granted to 6
                    2006-03-16 12:56:27,050 DEBUG [com.tyco.provider.authorization.ejb.SecurityManagerSession] Permission (com.tyco.provider.api.authorization.FreyaPermission reports view) Granted to 6
                    2006-03-16 12:56:27,050 DEBUG [com.tyco.provider.authorization.ejb.SecurityManagerSession] User has role 8
                    2006-03-16 12:56:27,050 DEBUG [com.tyco.provider.authorization.ejb.SecurityManagerSession] Getting role by id 8
                    2006-03-16 12:56:27,050 DEBUG [com.tyco.provider.authorization.ejb.SecurityManagerSession] Getting com.tyco.provider.authorization.model.RoleImpl with IID 8
                    2006-03-16 12:56:27,050 INFO [STDOUT] Hibernate: select roleimpl0_.PERMISSIBLE_ROLE_IID as PERMISSI1_1_0_, roleimpl0_.ROLE_NAME as ROLE2_1_0_, roleimpl0_.DESCRIPTION as DESCRIPT3_1_0_, roleimpl0_.STATUS as STATUS1_0_, roleimpl0_.SYSTEM as SYSTEM1_0_ from PERMISSIBLE_ROLE roleimpl0_ where roleimpl0_.PERMISSIBLE_ROLE_IID=?
                    2006-03-16 12:56:27,050 DEBUG [com.tyco.provider.authorization.ejb.SecurityManagerSession] Adding association data to { ID=8 Name=Enterprise Manager Description=An assistant system administrator that can perform many of the functions of a systems administrator, but not all. Status=1 is system=true}
                    2006-03-16 12:56:27,050 DEBUG [com.tyco.provider.authorization.umg.TransactIdentityValidator] Getting Role Identity 8
                    2006-03-16 12:56:27,050 DEBUG [com.tyco.provider.authorization.ejb.SecurityManagerSession] Permission (com.tyco.provider.api.authorization.FreyaPermission enterprise modify) Granted to 8
                    2006-03-16 12:56:27,050 DEBUG [com.tyco.provider.authorization.ejb.SecurityManagerSession] User has role 10
                    2006-03-16 12:56:27,050 DEBUG [com.tyco.provider.authorization.ejb.SecurityManagerSession] Getting role by id 10
                    2006-03-16 12:56:27,050 DEBUG [com.tyco.provider.authorization.ejb.SecurityManagerSession] Getting com.tyco.provider.authorization.model.RoleImpl with IID 10
                    2006-03-16 12:56:27,050 INFO [STDOUT] Hibernate: select roleimpl0_.PERMISSIBLE_ROLE_IID as PERMISSI1_1_0_, roleimpl0_.ROLE_NAME as ROLE2_1_0_, roleimpl0_.DESCRIPTION as DESCRIPT3_1_0_, roleimpl0_.STATUS as STATUS1_0_, roleimpl0_.SYSTEM as SYSTEM1_0_ from PERMISSIBLE_ROLE roleimpl0_ where roleimpl0_.PERMISSIBLE_ROLE_IID=?
                    2006-03-16 12:56:27,050 DEBUG [com.tyco.provider.authorization.ejb.SecurityManagerSession] Adding association data to { ID=10 Name=rtsAdministrator Description=rules admin Status=1 is system=true}
                    2006-03-16 12:56:27,060 DEBUG [com.tyco.provider.authorization.umg.TransactIdentityValidator] Getting Role Identity 10
                    2006-03-16 12:56:27,060 DEBUG [com.tyco.provider.authorization.ejb.AuthorizationServiceDelegateFactory] AuthorizationService.getUserByUserName 1722
                    2006-03-16 12:56:27,060 DEBUG [com.tyco.provider.enterprise.ejb.EnterpriseManagerSession] Getting com.tyco.provider.enterprise.model.EnterpriseImpl with IID 1
                    2006-03-16 12:56:27,060 INFO [STDOUT] Hibernate: select enterprise0_.ENTERPRISE_IID as ENTERPRISE1_9_1_, enterprise0_.PARENT_ID as PARENT6_9_1_, enterprise0_.ROUTING_ID as ROUTING2_9_1_, enterprise0_.NAME as NAME9_1_, enterprise0_.DESCRIPTION as DESCRIPT4_9_1_, enterprise0_.STATUS as STATUS9_1_, enterprise1_.ENTERPRISE_IID as ENTERPRISE1_9_0_, enterprise1_.PARENT_ID as PARENT6_9_0_, enterprise1_.ROUTING_ID as ROUTING2_9_0_, enterprise1_.NAME as NAME9_0_, enterprise1_.DESCRIPTION as DESCRIPT4_9_0_, enterprise1_.STATUS as STATUS9_0_ from ENTERPRISE enterprise0_ left outer join ENTERPRISE enterprise1_ on enterprise0_.PARENT_ID=enterprise1_.ENTERPRISE_IID where enterprise0_.ENTERPRISE_IID=?
                    2006-03-16 12:56:27,060 INFO [STDOUT] Hibernate: select childrenen0_.PARENT_ID as PARENT6_1_, childrenen0_.ENTERPRISE_IID as ENTERPRISE1_1_, childrenen0_.ENTERPRISE_IID as ENTERPRISE1_9_0_, childrenen0_.PARENT_ID as PARENT6_9_0_, childrenen0_.ROUTING_ID as ROUTING2_9_0_, childrenen0_.NAME as NAME9_0_, childrenen0_.DESCRIPTION as DESCRIPT4_9_0_, childrenen0_.STATUS as STATUS9_0_ from ENTERPRISE childrenen0_ where childrenen0_.PARENT_ID=? order by childrenen0_.NAME
                    2006-03-16 12:56:27,060 DEBUG [com.tyco.provider.enterprise.ejb.EnterpriseServiceDelegateFactory] EnterpriseService.getEnterpriseByID 0
                    2006-03-16 12:56:27,060 DEBUG [tapestry.parse.SpecificationParser] BEGIN parsePageSpecification(context:/WEB-INF/pages/navigation/controlPanel.page)
                    2006-03-16 12:56:27,070 DEBUG [tapestry.parse.SpecificationParser] END parsePageSpecification() [ComponentSpecification[componentClassName=com.tyco.provider.web.pages.navigation.ControlPanel pageSpecification=true specificationLocation=context:/WEB-INF/pages/navigation/controlPanel.page allowBody=true allowInformalParameter=true]]
                    2006-03-16 12:56:27,100 DEBUG [tapestry.enhance.ComponentConstructorFactory] Creating class:


                    2006-03-16 12:56:27,331 DEBUG [tapestry.page.PageLoader] Establishing inherited bindings
                    2006-03-16 12:56:27,331 DEBUG [tapestry.page.PageLoader] Loaded page $ControlPanel_42@96d430[controlPanel] with 47 components (maximum depth 5)
                    2006-03-16 12:56:27,431 DEBUG [com.tyco.provider.authorization.umg.TransactIdentityValidator] Getting User Identity 3
                    2006-03-16 12:56:27,431 DEBUG [com.tyco.provider.authorization.umg.TransactIdentityValidator] Getting Role Identity 2
                    2006-03-16 12:56:27,431 DEBUG [com.tyco.provider.authorization.umg.TransactIdentityValidator] Getting Role Identity 4
                    2006-03-16 12:56:27,441 DEBUG [com.tyco.provider.authorization.umg.TransactIdentityValidator] Getting Role Identity 6
                    2006-03-16 12:56:27,441 DEBUG [com.tyco.provider.authorization.umg.TransactIdentityValidator] Getting Role Identity 8
                    2006-03-16 12:56:27,441 DEBUG [com.tyco.provider.authorization.umg.TransactIdentityValidator] Getting Role Identity 10
                    2006-03-16 12:56:27,441 DEBUG [com.tyco.provider.authorization.ejb.AuthorizationServiceDelegateFactory] AuthorizationService.isActionPermitted 20
                    2006-03-16 12:56:27,441 DEBUG [tapestry.data.DataSqueezer] Constructing core service implementation for service tapestry.data.DataSqueezer
                    2006-03-16 12:56:27,561 DEBUG [tapestry.data.SerializableAdaptor] Creating SingletonProxy for service tapestry.data.SerializableAdaptor
                    2006-03-16 12:56:27,601 DEBUG [tapestry.data.SerializableAdaptor] Constructing core service implementation for service tapestry.data.SerializableAdaptor
                    2006-03-16 12:56:27,641 DEBUG [tapestry.data.SerializableAdaptor] Set property resolver to org.apache.hivemind.impl.DefaultClassResolver@1dfeace
                    2006-03-16 12:56:27,641 DEBUG [tapestry.data.DataSqueezer] Set property squeezeAdaptors to [org.apache.tapestry.util.io.BooleanAdaptor@333bdb, org.apache.tapestry.util.io.ByteAdaptor@19ec4b6, org.apache.tapestry.util.io.CharacterAdaptor@84c71b, org.apache.tapestry.util.io.ComponentAddressAdaptor@12bd15e, org.apache.tapestry.util.io.DoubleAdaptor@1be9f07, org.apache.tapestry.util.io.FloatAdaptor@1bac50f, org.apache.tapestry.util.io.IntegerAdaptor@df9411, org.apache.tapestry.util.io.LongAdaptor@71e889, <SingletonProxy for tapestry.data.SerializableAdaptor(org.apache.tapestry.util.io.SqueezeAdaptor)>, org.apache.tapestry.util.io.ShortAdaptor@59a6b5, org.apache.tapestry.util.io.StringAdaptor@1cfde49]
                    2006-03-16 12:56:27,651 DEBUG [com.tyco.provider.authorization.umg.TransactIdentityValidator] Getting User Identity 3
                    2006-03-16 12:56:27,651 DEBUG [com.tyco.provider.authorization.umg.TransactIdentityValidator] Getting Role Identity 2
                    2006-03-16 12:56:27,651 DEBUG [com.tyco.provider.authorization.umg.TransactIdentityValidator] Getting Role Identity 4
                    2006-03-16 12:56:27,651 DEBUG [com.tyco.provider.authorization.umg.TransactIdentityValidator] Getting Role Identity 6
                    2006-03-16 12:56:27,651 DEBUG [com.tyco.provider.authorization.umg.TransactIdentityValidator] Getting Role Identity 8
                    2006-03-16 12:56:27,651 DEBUG [com.tyco.provider.authorization.umg.TransactIdentityValidator] Getting Role Identity 10
                    2006-03-16 12:56:27,651 DEBUG [com.tyco.provider.authorization.ejb.AuthorizationServiceDelegateFactory] AuthorizationService.isActionPermitted 0
                    2006-03-16 12:56:27,671 DEBUG [com.tyco.provider.authorization.umg.TransactIdentityValidator] Getting User Identity 3
                    2006-03-16 12:56:27,671 DEBUG [com.tyco.provider.authorization.umg.TransactIdentityValidator] Getting Role Identity 2
                    2006-03-16 12:56:27,671 DEBUG [com.tyco.provider.authorization.umg.TransactIdentityValidator] Getting Role Identity 4
                    2006-03-16 12:56:27,681 DEBUG [com.tyco.provider.authorization.umg.TransactIdentityValidator] Getting Role Identity 6
                    2006-03-16 12:56:27,681 DEBUG [com.tyco.provider.authorization.umg.TransactIdentityValidator] Getting Role Identity 8
                    2006-03-16 12:56:27,681 DEBUG [com.tyco.provider.authorization.umg.TransactIdentityValidator] Getting Role Identity 10
                    2006-03-16 12:56:27,681 DEBUG [com.tyco.provider.authorization.ejb.AuthorizationServiceDelegateFactory] AuthorizationService.isActionPermitted 10
                    2006-03-16 12:56:27,741 DEBUG [com.tyco.provider.authorization.umg.TransactIdentityValidator] Getting User Identity 3
                    2006-03-16 12:56:27,741 DEBUG [com.tyco.provider.authorization.umg.TransactIdentityValidator] Getting Role Identity 2
                    2006-03-16 12:56:27,741 DEBUG [com.tyco.provider.authorization.umg.TransactIdentityValidator] Getting Role Identity 4
                    2006-03-16 12:56:27,741 DEBUG [com.tyco.provider.authorization.umg.TransactIdentityValidator] Getting Role Identity 6
                    2006-03-16 12:56:27,741 DEBUG [com.tyco.provider.authorization.umg.TransactIdentityValidator] Getting Role Identity 8
                    2006-03-16 12:56:27,741 DEBUG [com.tyco.provider.authorization.umg.TransactIdentityValidator] Getting Role Identity 10
                    2006-03-16 12:56:27,741 DEBUG [com.tyco.provider.authorization.ejb.AuthorizationServiceDelegateFactory] AuthorizationService.isActionPermitted 10
                    2006-03-16 12:56:27,751 DEBUG [tapestry.services.Page] Constructing core service implementation for service tapestry.services.Page
                    2006-03-16 12:56:27,771 DEBUG [tapestry.services.Page] Set property responseRenderer to <SingletonProxy for tapestry.request.ResponseRenderer(org.apache.tapestry.services.ResponseRenderer)>
                    2006-03-16 12:56:27,771 DEBUG [tapestry.services.Page] Set property linkFactory to <SingletonProxy for tapestry.url.LinkFactory(org.apache.tapestry.services.LinkFactory)>
                    2006-03-16 12:56:27,781 DEBUG [com.tyco.provider.authorization.umg.TransactIdentityValidator] Getting User Identity 3
                    2006-03-16 12:56:27,781 DEBUG [com.tyco.provider.authorization.umg.TransactIdentityValidator] Getting Role Identity 2
                    2006-03-16 12:56:27,781 DEBUG [com.tyco.provider.authorization.umg.TransactIdentityValidator] Getting Role Identity 4
                    2006-03-16 12:56:27,781 DEBUG [com.tyco.provider.authorization.umg.TransactIdentityValidator] Getting Role Identity 6
                    2006-03-16 12:56:27,781 DEBUG [com.tyco.provider.authorization.umg.TransactIdentityValidator] Getting Role Identity 8
                    2006-03-16 12:56:27,781 DEBUG [com.tyco.provider.authorization.umg.TransactIdentityValidator] Getting Role Identity 10
                    2006-03-16 12:56:27,791 DEBUG [com.tyco.provider.authorization.ejb.AuthorizationServiceDelegateFactory] AuthorizationService.isActionPermitted 10
                    2006-03-16 12:56:27,791 DEBUG [com.tyco.provider.authorization.umg.TransactIdentityValidator] Getting User Identity 3
                    2006-03-16 12:56:27,791 DEBUG [com.tyco.provider.authorization.umg.TransactIdentityValidator] Getting Role Identity 2
                    2006-03-16 12:56:27,801 DEBUG [com.tyco.provider.authorization.umg.TransactIdentityValidator] Getting Role Identity 4
                    2006-03-16 12:56:27,801 DEBUG [com.tyco.provider.authorization.umg.TransactIdentityValidator] Getting Role Identity 6
                    2006-03-16 12:56:27,801 DEBUG [com.tyco.provider.authorization.umg.TransactIdentityValidator] Getting Role Identity 8
                    2006-03-16 12:56:27,801 DEBUG [com.tyco.provider.authorization.umg.TransactIdentityValidator] Getting Role Identity 10
                    2006-03-16 12:56:27,801 DEBUG [com.tyco.provider.authorization.ejb.AuthorizationServiceDelegateFactory] AuthorizationService.isActionPermitted 10
                    2006-03-16 12:56:27,821 DEBUG [com.tyco.provider.authorization.umg.TransactIdentityValidator] Getting User Identity 3
                    2006-03-16 12:56:27,821 DEBUG [com.tyco.provider.authorization.umg.TransactIdentityValidator] Getting Role Identity 2
                    2006-03-16 12:56:27,821 DEBUG [com.tyco.provider.authorization.umg.TransactIdentityValidator] Getting Role Identity 4
                    2006-03-16 12:56:27,821 DEBUG [com.tyco.provider.authorization.umg.TransactIdentityValidator] Getting Role Identity 6
                    2006-03-16 12:56:27,831 DEBUG [com.tyco.provider.authorization.umg.TransactIdentityValidator] Getting Role Identity 8
                    2006-03-16 12:56:27,831 DEBUG [com.tyco.provider.authorization.umg.TransactIdentityValidator] Getting Role Identity 10
                    2006-03-16 12:56:27,831 DEBUG [com.tyco.provider.authorization.ejb.AuthorizationServiceDelegateFactory] AuthorizationService.isActionPermitted 10
                    2006-03-16 12:56:27,831 DEBUG [com.tyco.provider.authorization.umg.TransactIdentityValidator] Getting User Identity 3
                    2006-03-16 12:56:27,831 DEBUG [com.tyco.provider.authorization.umg.TransactIdentityValidator] Getting Role Identity 2
                    2006-03-16 12:56:27,841 DEBUG [com.tyco.provider.authorization.umg.TransactIdentityValidator] Getting Role Identity 4
                    2006-03-16 12:56:27,841 DEBUG [com.tyco.provider.authorization.umg.TransactIdentityValidator] Getting Role Identity 6
                    2006-03-16 12:56:27,841 DEBUG [com.tyco.provider.authorization.umg.TransactIdentityValidator] Getting Role Identity 8
                    2006-03-16 12:56:27,841 DEBUG [com.tyco.provider.authorization.umg.TransactIdentityValidator] Getting Role Identity 10
                    2006-03-16 12:56:27,841 DEBUG [com.tyco.provider.authorization.ejb.AuthorizationServiceDelegateFactory] AuthorizationService.isActionPermitted 10
                    2006-03-16 12:56:27,841 DEBUG [com.tyco.provider.authorization.umg.TransactIdentityValidator] Getting User Identity 3
                    2006-03-16 12:56:27,841 DEBUG [com.tyco.provider.authorization.umg.TransactIdentityValidator] Getting Role Identity 2
                    2006-03-16 12:56:27,861 DEBUG [com.tyco.provider.authorization.umg.TransactIdentityValidator] Getting Role Identity 4
                    2006-03-16 12:56:27,861 DEBUG [com.tyco.provider.authorization.umg.TransactIdentityValidator] Getting Role Identity 6
                    2006-03-16 12:56:27,861 DEBUG [com.tyco.provider.authorization.umg.TransactIdentityValidator] Getting Role Identity 8
                    2006-03-16 12:56:27,861 DEBUG [com.tyco.provider.authorization.umg.TransactIdentityValidator] Getting Role Identity 10
                    2006-03-16 12:56:27,871 DEBUG [com.tyco.provider.authorization.ejb.AuthorizationServiceDelegateFactory] AuthorizationService.isActionPermitted 30
                    2006-03-16 12:56:27,871 DEBUG [com.tyco.provider.authorization.umg.TransactIdentityValidator] Getting User Identity 3
                    2006-03-16 12:56:27,871 DEBUG [com.tyco.provider.authorization.umg.TransactIdentityValidator] Getting Role Identity 2
                    2006-03-16 12:56:27,871 DEBUG [com.tyco.provider.authorization.umg.TransactIdentityValidator] Getting Role Identity 4
                    2006-03-16 12:56:27,871 DEBUG [com.tyco.provider.authorization.umg.TransactIdentityValidator] Getting Role Identity 6
                    2006-03-16 12:56:27,871 DEBUG [com.tyco.provider.authorization.umg.TransactIdentityValidator] Getting Role Identity 8
                    2006-03-16 12:56:27,871 DEBUG [com.tyco.provider.authorization.umg.TransactIdentityValidator] Getting Role Identity 10
                    2006-03-16 12:56:27,871 DEBUG [com.tyco.provider.authorization.ejb.AuthorizationServiceDelegateFactory] AuthorizationService.isActionPermitted 0
                    2006-03-16 12:56:27,881 DEBUG [com.tyco.provider.authorization.umg.TransactIdentityValidator] Getting User Identity 3
                    2006-03-16 12:56:27,881 DEBUG [com.tyco.provider.authorization.umg.TransactIdentityValidator] Getting Role Identity 2
                    2006-03-16 12:56:27,881 DEBUG [com.tyco.provider.authorization.umg.TransactIdentityValidator] Getting Role Identity 4
                    2006-03-16 12:56:27,891 DEBUG [com.tyco.provider.authorization.umg.TransactIdentityValidator] Getting Role Identity 6
                    2006-03-16 12:56:27,891 DEBUG [com.tyco.provider.authorization.umg.TransactIdentityValidator] Getting Role Identity 8
                    2006-03-16 12:56:27,891 DEBUG [com.tyco.provider.authorization.umg.TransactIdentityValidator] Getting Role Identity 10
                    2006-03-16 12:56:27,891 DEBUG [com.tyco.provider.authorization.ejb.AuthorizationServiceDelegateFactory] AuthorizationService.isActionPermitted 10
                    2006-03-16 12:56:27,901 DEBUG [com.tyco.provider.authorization.umg.TransactIdentityValidator] Getting User Identity 3
                    2006-03-16 12:56:27,901 DEBUG [com.tyco.provider.authorization.umg.TransactIdentityValidator] Getting Role Identity 2
                    2006-03-16 12:56:27,901 DEBUG [com.tyco.provider.authorization.umg.TransactIdentityValidator] Getting Role Identity 4
                    2006-03-16 12:56:27,901 DEBUG [com.tyco.provider.authorization.umg.TransactIdentityValidator] Getting Role Identity 6
                    2006-03-16 12:56:27,901 DEBUG [com.tyco.provider.authorization.umg.TransactIdentityValidator] Getting Role Identity 8
                    2006-03-16 12:56:27,901 DEBUG [com.tyco.provider.authorization.umg.TransactIdentityValidator] Getting Role Identity 10
                    2006-03-16 12:56:27,901 DEBUG [com.tyco.provider.authorization.ejb.AuthorizationServiceDelegateFactory] AuthorizationService.isActionPermitted 0
                    2006-03-16 12:56:27,911 DEBUG [com.tyco.provider.authorization.umg.TransactIdentityValidator] Getting User Identity 3
                    2006-03-16 12:56:27,911 DEBUG [com.tyco.provider.authorization.umg.TransactIdentityValidator] Getting Role Identity 2
                    2006-03-16 12:56:27,911 DEBUG [com.tyco.provider.authorization.umg.TransactIdentityValidator] Getting Role Identity 4
                    2006-03-16 12:56:27,921 DEBUG [com.tyco.provider.authorization.umg.TransactIdentityValidator] Getting Role Identity 6
                    2006-03-16 12:56:27,921 DEBUG [com.tyco.provider.authorization.umg.TransactIdentityValidator] Getting Role Identity 8
                    2006-03-16 12:56:27,921 DEBUG [com.tyco.provider.authorization.umg.TransactIdentityValidator] Getting Role Identity 10
                    2006-03-16 12:56:27,921 DEBUG [com.tyco.provider.authorization.ejb.AuthorizationServiceDelegateFactory] AuthorizationService.isActionPermitted 10
                    2006-03-16 12:56:27,921 DEBUG [com.tyco.provider.authorization.umg.TransactIdentityValidator] Getting User Identity 3
                    2006-03-16 12:56:27,931 DEBUG [com.tyco.provider.authorization.umg.TransactIdentityValidator] Getting Role Identity 2
                    2006-03-16 12:56:27,931 DEBUG [com.tyco.provider.authorization.umg.TransactIdentityValidator] Getting Role Identity 4
                    2006-03-16 12:56:27,931 DEBUG [com.tyco.provider.authorization.umg.TransactIdentityValidator] Getting Role Identity 6
                    2006-03-16 12:56:27,931 DEBUG [com.tyco.provider.authorization.umg.TransactIdentityValidator] Getting Role Identity 8
                    2006-03-16 12:56:27,931 DEBUG [com.tyco.provider.authorization.umg.TransactIdentityValidator] Getting Role Identity 10
                    2006-03-16 12:56:27,951 DEBUG [com.tyco.provider.authorization.ejb.AuthorizationServiceDelegateFactory] AuthorizationService.isActionPermitted 30
                    2006-03-16 12:56:27,971 DEBUG [com.tyco.provider.authorization.umg.TransactIdentityValidator] Getting User Identity 3
                    2006-03-16 12:56:27,971 DEBUG [com.tyco.provider.authorization.umg.TransactIdentityValidator] Getting Role Identity 2
                    2006-03-16 12:56:27,981 DEBUG [com.tyco.provider.authorization.umg.TransactIdentityValidator] Getting Role Identity 4
                    2006-03-16 12:56:27,981 DEBUG [com.tyco.provider.authorization.umg.TransactIdentityValidator] Getting Role Identity 6
                    2006-03-16 12:56:27,981 DEBUG [com.tyco.provider.authorization.umg.TransactIdentityValidator] Getting Role Identity 8
                    2006-03-16 12:56:27,981 DEBUG [com.tyco.provider.authorization.umg.TransactIdentityValidator] Getting Role Identity 10
                    2006-03-16 12:56:27,981 DEBUG [com.tyco.provider.authorization.ejb.AuthorizationServiceDelegateFactory] AuthorizationService.isActionPermitted 10
                    2006-03-16 12:56:27,991 DEBUG [com.tyco.provider.authorization.umg.TransactIdentityValidator] Getting User Identity 3
                    2006-03-16 12:56:27,991 DEBUG [com.tyco.provider.authorization.umg.TransactIdentityValidator] Getting Role Identity 2
                    2006-03-16 12:56:27,991 DEBUG [com.tyco.provider.authorization.umg.TransactIdentityValidator] Getting Role Identity 4
                    2006-03-16 12:56:28,001 DEBUG [com.tyco.provider.authorization.umg.TransactIdentityValidator] Getting Role Identity 6
                    2006-03-16 12:56:28,001 DEBUG [com.tyco.provider.authorization.umg.TransactIdentityValidator] Getting Role Identity 8
                    2006-03-16 12:56:28,001 DEBUG [com.tyco.provider.authorization.umg.TransactIdentityValidator] Getting Role Identity 10
                    2006-03-16 12:56:28,001 DEBUG [com.tyco.provider.authorization.ejb.AuthorizationServiceDelegateFactory] AuthorizationService.isActionPermitted 20
                    2006-03-16 12:56:28,011 DEBUG [com.tyco.provider.authorization.umg.TransactIdentityValidator] Getting User Identity 3
                    2006-03-16 12:56:28,011 DEBUG [com.tyco.provider.authorization.umg.TransactIdentityValidator] Getting Role Identity 2
                    2006-03-16 12:56:28,011 DEBUG [com.tyco.provider.authorization.umg.TransactIdentityValidator] Getting Role Identity 4
                    2006-03-16 12:56:28,011 DEBUG [com.tyco.provider.authorization.umg.TransactIdentityValidator] Getting Role Identity 6
                    2006-03-16 12:56:28,011 DEBUG [com.tyco.provider.authorization.umg.TransactIdentityValidator] Getting Role Identity 8
                    2006-03-16 12:56:28,011 DEBUG [com.tyco.provider.authorization.umg.TransactIdentityValidator] Getting Role Identity 10
                    2006-03-16 12:56:28,011 DEBUG [com.tyco.provider.authorization.ejb.AuthorizationServiceDelegateFactory] AuthorizationService.isActionPermitted 10
                    2006-03-16 12:56:28,021 DEBUG [com.tyco.provider.authorization.umg.TransactIdentityValidator] Getting User Identity 3
                    2006-03-16 12:56:28,021 DEBUG [com.tyco.provider.authorization.umg.TransactIdentityValidator] Getting Role Identity 2
                    2006-03-16 12:56:28,062 DEBUG [com.tyco.provider.authorization.umg.TransactIdentityValidator] Getting Role Identity 4
                    2006-03-16 12:56:28,062 DEBUG [com.tyco.provider.authorization.umg.TransactIdentityValidator] Getting Role Identity 6
                    2006-03-16 12:56:28,062 DEBUG [com.tyco.provider.authorization.umg.TransactIdentityValidator] Getting Role Identity 8
                    2006-03-16 12:56:28,062 DEBUG [com.tyco.provider.authorization.umg.TransactIdentityValidator] Getting Role Identity 10
                    2006-03-16 12:56:28,062 DEBUG [com.tyco.provider.authorization.ejb.AuthorizationServiceDelegateFactory] AuthorizationService.isActionPermitted 51
                    2006-03-16 12:56:28,072 DEBUG [com.tyco.provider.authorizati

                    • 7. Re: SingleSignOn in JBoss 4.0.2
                      chris griffith Expert

                      tyronehed,

                      The Tomcat output shows that all the url's that were requested are not secured by security constraints in web.xml. For example, the last request before your com.tyco.xxxx logging is a request for url = 'http://logcalhost:8080/ices/app'. This is not covered under your securtiy constraints. Unless I am missing something here.

                      How is com.tyco.xxx authentication package invoked from Tomcat's request pipeline?

                      Also, it would be nice to see what happens when a user successfully logs in using your mylogin.jsp form.

                      cgriffith