6 Replies Latest reply on Mar 18, 2006 11:29 PM by Craig Rutfield

    User and Management portlets non-operational with 2.2

    Craig Rutfield Newbie


      I did a clean install using JBOSS 4.0.18, MYSQL 5, on Linux.

      The portal comes up fine. No errors in the server.log (lots of debugging statements).
      I can log in as user or admin.

      However, When I log in, the User portlet still says 'standard login'. It doe snot give the Edit your profile, list users, etc. I can not create a new account.

      Similarly, On the Management tab, I can not expand the "root" tree, and the Manager, Properties, Security, do nothing.

      CMS and test work fine.

      The user has all rights to the jbossportaldatabase.

      It is connecting to the database, or I would not be able to log in.

      Any ideas? Besides server.log, where else should I look for errors?

      Thanks.
      Craig

        • 1. Re: User and Management portlets non-operational with 2.2
          Craig Rutfield Newbie


          Additonal information by running JBOSS by hand....
          (It appears to use a lot of defaults because items are not defined or found, but that could be normal.....)

          01:05:53,131 INFO [Server] JBoss (MX MicroKernel) [4.0.3SP1 (build: CVSTag=JBoss_4_0_3_SP1 date=200510231054)] Started in 38s:751ms
          01:11:32,501 INFO [STDOUT] Mar 17, 2006 1:11:32 AM com.sun.facelets.compiler.TagLibraryConfig loadImplicit
          INFO: Added Library from: jar:file:/usr/local/jboss/server/default/tmp/deploy/tmp52425jboss-portal.sar-contents/lib/jsf-facelets.jar!/ME
          TA-INF/jstl-core.taglib.xml
          01:11:32,605 INFO [STDOUT] Mar 17, 2006 1:11:32 AM com.sun.facelets.compiler.TagLibraryConfig loadImplicit
          INFO: Added Library from: jar:file:/usr/local/jboss/server/default/tmp/deploy/tmp52425jboss-portal.sar-contents/lib/jsf-facelets.jar!/ME
          TA-INF/jsf-ui.taglib.xml
          01:11:32,616 INFO [STDOUT] Mar 17, 2006 1:11:32 AM com.sun.facelets.compiler.TagLibraryConfig loadImplicit
          INFO: Added Library from: jar:file:/usr/local/jboss/server/default/tmp/deploy/tmp52425jboss-portal.sar-contents/lib/jsf-facelets.jar!/ME
          TA-INF/jsf-html.taglib.xml
          01:11:32,774 INFO [STDOUT] Mar 17, 2006 1:11:32 AM com.sun.facelets.compiler.TagLibraryConfig loadImplicit
          INFO: Added Library from: jar:file:/usr/local/jboss/server/default/tmp/deploy/tmp52425jboss-portal.sar-contents/lib/jsf-facelets.jar!/ME
          TA-INF/jstl-fn.taglib.xml
          01:11:32,814 INFO [STDOUT] Mar 17, 2006 1:11:32 AM com.sun.facelets.compiler.TagLibraryConfig loadImplicit
          INFO: Added Library from: jar:file:/usr/local/jboss/server/default/tmp/deploy/tmp52425jboss-portal.sar-contents/lib/jsf-facelets.jar!/ME
          TA-INF/jsf-core.taglib.xml
          01:11:46,255 INFO [STDOUT] Mar 17, 2006 1:11:46 AM com.sun.facelets.tag.jsf.ComponentRule warnAttr
          WARNING: /WEB-INF/jsp/management/index.xhtml @90,98 border="0" Property 'border' is not on type: javax.faces.component.html.HtmlGraphicI
          mage
          01:11:46,261 INFO [STDOUT] Mar 17, 2006 1:11:46 AM com.sun.facelets.tag.jsf.ComponentRule warnAttr
          WARNING: /WEB-INF/jsp/management/index.xhtml @105,110 border="0" Property 'border' is not on type: javax.faces.component.html.HtmlGraphi
          cImage
          01:11:46,282 INFO [STDOUT] Mar 17, 2006 1:11:46 AM com.sun.facelets.tag.jsf.ComponentRule warnAttr
          WARNING: /WEB-INF/jsp/management/index.xhtml @117,114 border="0" Property 'border' is not on type: javax.faces.component.html.HtmlGraphi
          cImage
          01:11:46,286 INFO [STDOUT] Mar 17, 2006 1:11:46 AM com.sun.facelets.tag.jsf.ComponentRule warnAttr
          WARNING: /WEB-INF/jsp/management/index.xhtml @129,112 border="0" Property 'border' is not on type: javax.faces.component.html.HtmlGraphi
          cImage
          01:11:46,290 INFO [STDOUT] Mar 17, 2006 1:11:46 AM com.sun.facelets.tag.jsf.ComponentRule warnAttr
          WARNING: /WEB-INF/jsp/management/index.xhtml @141,109 border="0" Property 'border' is not on type: javax.faces.component.html.HtmlGraphi
          cImage
          01:11:46,663 INFO [STDOUT] Mar 17, 2006 1:11:46 AM com.sun.facelets.tag.jsf.ComponentRule warnAttr
          WARNING: /WEB-INF/jsp/management/plugins/manager.xhtml @55,109 border="0" Property 'border' is not on type: javax.faces.component.html.H
          tmlGraphicImage
          01:11:46,665 INFO [STDOUT] Mar 17, 2006 1:11:46 AM com.sun.facelets.tag.jsf.ComponentRule warnAttr
          WARNING: /WEB-INF/jsp/management/plugins/manager.xhtml @59,111 border="0" Property 'border' is not on type: javax.faces.component.html.H
          tmlGraphicImage
          01:11:46,667 INFO [STDOUT] Mar 17, 2006 1:11:46 AM com.sun.facelets.tag.jsf.ComponentRule warnAttr
          WARNING: /WEB-INF/jsp/management/plugins/manager.xhtml @63,111 border="0" Property 'border' is not on type: javax.faces.component.html.H
          tmlGraphicImage
          01:11:46,669 INFO [STDOUT] Mar 17, 2006 1:11:46 AM com.sun.facelets.tag.jsf.ComponentRule warnAttr
          WARNING: /WEB-INF/jsp/management/plugins/manager.xhtml @67,112 border="0" Property 'border' is not on type: javax.faces.component.html.H
          tmlGraphicImage
          01:11:46,675 INFO [STDOUT] Mar 17, 2006 1:11:46 AM com.sun.facelets.tag.jsf.ComponentRule warnAttr
          WARNING: /WEB-INF/jsp/management/plugins/manager.xhtml @111,104 border="0" Property 'border' is not on type: javax.faces.component.html.
          HtmlGraphicImage
          01:11:46,718 INFO [MyfacesConfig] No context init parameter 'org.apache.myfaces.PRETTY_HTML' found, using default value true
          01:11:46,719 INFO [MyfacesConfig] No context init parameter 'org.apache.myfaces.DETECT_JAVASCRIPT' found, using default value false
          01:11:46,719 INFO [MyfacesConfig] No context init parameter 'org.apache.myfaces.AUTO_SCROLL' found, using default value false
          01:11:46,728 INFO [[/portal-core]] No state saving method defined, assuming default server state saving
          01:13:08,946 ERROR [[/portal-core]] Exception in FacesServlet
          java.lang.NullPointerException
          at org.apache.myfaces.portlet.MyFacesGenericPortlet.facesRender(MyFacesGenericPortlet.java:393)
          at org.apache.myfaces.portlet.MyFacesGenericPortlet.doView(MyFacesGenericPortlet.java:266)
          at javax.portlet.GenericPortlet.doDispatch(GenericPortlet.java:154)
          at javax.portlet.GenericPortlet.render(GenericPortlet.java:394)
          at org.jboss.portal.portlet.PortletContainer.invokeRender(PortletContainer.java:499)
          at org.jboss.portal.portlet.PortletContainer.dispatch(PortletContainer.java:416)
          at org.jboss.portal.server.app.ComponentInvocation.dispatch(ComponentInvocation.java:66)
          at org.jboss.portal.server.invocation.Invocation.invokeNext(Invocation.java:127)
          at org.jboss.portal.core.aspects.component.TransactionInterceptor.invoke(TransactionInterceptor.java:59)
          at org.jboss.portal.server.app.ComponentInterceptor.invoke(ComponentInterceptor.java:25)
          at org.jboss.portal.server.invocation.Invocation.invokeNext(Invocation.java:117)
          at org.jboss.portal.core.aspects.component.HeaderInterceptor.invoke(HeaderInterceptor.java:37)
          at org.jboss.portal.server.app.ComponentInterceptor.invoke(ComponentInterceptor.java:25)
          at org.jboss.portal.server.invocation.Invocation.invokeNext(Invocation.java:117)
          at org.jboss.portal.server.aspects.component.NavigationInterceptor.invoke(NavigationInterceptor.java:63)
          at org.jboss.portal.server.app.ComponentInterceptor.invoke(ComponentInterceptor.java:25)
          --More--

          • 2. Re: User and Management portlets non-operational with 2.2
            Craig Rutfield Newbie

            I also took the packaged binaries and copied the sar directory into the deploy while moving mine out.... same results. This implies either a database or configuration issue.

            • 3. Re: User and Management portlets non-operational with 2.2
              Roy Russo Master

              Look in the portal wiki for the MySQL5 truncation problem and solution.

              • 4. Re: User and Management portlets non-operational with 2.2
                Craig Rutfield Newbie


                Already did that with the truncation issue.

                I then dropped the database and let the application recreate everything... same affect.

                • 5. Re: User and Management portlets non-operational with 2.2
                  Craig Rutfield Newbie


                  I'm still stumped on this one. (User and Management portlet not working, but CMS and test are after a completely clean install)

                  Tried the 2.2.1RC3 with the same issues. Recreated the database from scratch.

                  In addition to the server.log, can someone suggest other files I should look at for errors?
                  Could it be related to hibernate? I hav enot used that much, and I see there are multiple options.

                  Any guidance would be appreciated.
                  CR

                  • 6. Re: User and Management portlets non-operational with 2.2
                    Craig Rutfield Newbie

                    In addition to my question on files to look for, I have also upgraded the ConnectorJ from 3.0.9 to 3.1.12. I am not 100% sure how to tell if the database is being read, but it does take the admin and user logins.

                    Here is the server.log on start up as well as when I start up the portal through a browser, and then log in to the User Portal as admin.

                    Is there a cache maintaining the admin username, or is it getitng it form the database?

                    How are the maangement and User protlets different from CMS and test?

                    Shutting down JBoss: Stopped
                    Stopping httpd: [ OK ]
                    Shutting down MySQL.. SUCCESS!
                    Starting MySQL SUCCESS!
                    Starting httpd: [ OK ]
                    Starting JBoss: ............Started
                    [root@rutfield ~]# tail -1000f /./usr/local/jboss/server/default/log/server.log
                    2006-03-19 04:15:31,858 DEBUG [org.hibernate.transaction.CacheSynchronization] automatically flushing session
                    2006-03-19 04:15:31,858 DEBUG [org.hibernate.impl.SessionImpl] automatically flushing session
                    2006-03-19 04:15:31,858 DEBUG [org.hibernate.event.def.AbstractFlushingEventListener] flushing session
                    2006-03-19 04:15:31,858 DEBUG [org.hibernate.event.def.AbstractFlushingEventListener] processing flush-time cascades
                    2006-03-19 04:15:31,858 DEBUG [org.hibernate.engine.Cascade] processing cascade ACTION_SAVE_UPDATE for: org.jboss.portal.core.impl.model.instance.InstanceImpl
                    2006-03-19 04:15:31,858 DEBUG [org.hibernate.engine.Cascade] cascade ACTION_SAVE_UPDATE for collection: org.jboss.portal.core.impl.model.instance.InstanceImpl.preferencesMap
                    2006-03-19 04:15:31,858 DEBUG [org.hibernate.engine.Cascade] done cascade ACTION_SAVE_UPDATE for collection: org.jboss.portal.core.impl.model.instance.InstanceImpl.preferencesMap
                    2006-03-19 04:15:31,858 DEBUG [org.hibernate.engine.Cascade] deleting orphans for collection: org.jboss.portal.core.impl.model.instance.InstanceImpl.preferencesMap
                    2006-03-19 04:15:31,858 DEBUG [org.hibernate.engine.Cascade] done deleting orphans for collection: org.jboss.portal.core.impl.model.instance.InstanceImpl.preferencesMap
                    2006-03-19 04:15:31,858 DEBUG [org.hibernate.engine.Cascade] done processing cascade ACTION_SAVE_UPDATE for: org.jboss.portal.core.impl.model.instance.InstanceImpl
                    2006-03-19 04:15:31,858 DEBUG [org.hibernate.engine.Cascade] processing cascade ACTION_SAVE_UPDATE for: org.jboss.portal.core.impl.model.instance.InstanceImpl
                    2006-03-19 04:15:31,858 DEBUG [org.hibernate.engine.Cascade] cascade ACTION_SAVE_UPDATE for collection: org.jboss.portal.core.impl.model.instance.InstanceImpl.preferencesMap
                    2006-03-19 04:15:31,858 DEBUG [org.hibernate.engine.Cascade] done cascade ACTION_SAVE_UPDATE for collection: org.jboss.portal.core.impl.model.instance.InstanceImpl.preferencesMap
                    2006-03-19 04:15:31,858 DEBUG [org.hibernate.engine.Cascade] deleting orphans for collection: org.jboss.portal.core.impl.model.instance.InstanceImpl.preferencesMap
                    2006-03-19 04:15:31,858 DEBUG [org.hibernate.engine.Cascade] done deleting orphans for collection: org.jboss.portal.core.impl.model.instance.InstanceImpl.preferencesMap
                    2006-03-19 04:15:31,858 DEBUG [org.hibernate.engine.Cascade] done processing cascade ACTION_SAVE_UPDATE for: org.jboss.portal.core.impl.model.instance.InstanceImpl
                    2006-03-19 04:15:31,858 DEBUG [org.hibernate.engine.Cascade] processing cascade ACTION_SAVE_UPDATE for: org.jboss.portal.core.impl.model.instance.InstanceImpl
                    2006-03-19 04:15:31,858 DEBUG [org.hibernate.engine.Cascade] cascade ACTION_SAVE_UPDATE for collection: org.jboss.portal.core.impl.model.instance.InstanceImpl.preferencesMap
                    2006-03-19 04:15:31,858 DEBUG [org.hibernate.engine.Cascade] done cascade ACTION_SAVE_UPDATE for collection: org.jboss.portal.core.impl.model.instance.InstanceImpl.preferencesMap
                    2006-03-19 04:15:31,858 DEBUG [org.hibernate.engine.Cascade] deleting orphans for collection: org.jboss.portal.core.impl.model.instance.InstanceImpl.preferencesMap
                    2006-03-19 04:15:31,858 DEBUG [org.hibernate.engine.Cascade] done deleting orphans for collection: org.jboss.portal.core.impl.model.instance.InstanceImpl.preferencesMap
                    2006-03-19 04:15:31,858 DEBUG [org.hibernate.engine.Cascade] done processing cascade ACTION_SAVE_UPDATE for: org.jboss.portal.core.impl.model.instance.InstanceImpl
                    2006-03-19 04:15:31,858 DEBUG [org.hibernate.engine.Cascade] processing cascade ACTION_SAVE_UPDATE for: org.jboss.portal.core.impl.model.instance.InstanceImpl
                    2006-03-19 04:15:31,858 DEBUG [org.hibernate.engine.Cascade] cascade ACTION_SAVE_UPDATE for collection: org.jboss.portal.core.impl.model.instance.InstanceImpl.preferencesMap
                    2006-03-19 04:15:31,859 DEBUG [org.hibernate.engine.Cascade] done cascade ACTION_SAVE_UPDATE for collection: org.jboss.portal.core.impl.model.instance.InstanceImpl.preferencesMap
                    2006-03-19 04:15:31,859 DEBUG [org.hibernate.engine.Cascade] deleting orphans for collection: org.jboss.portal.core.impl.model.instance.InstanceImpl.preferencesMap
                    2006-03-19 04:15:31,859 DEBUG [org.hibernate.engine.Cascade] done deleting orphans for collection: org.jboss.portal.core.impl.model.instance.InstanceImpl.preferencesMap
                    2006-03-19 04:15:31,859 DEBUG [org.hibernate.engine.Cascade] done processing cascade ACTION_SAVE_UPDATE for: org.jboss.portal.core.impl.model.instance.InstanceImpl
                    2006-03-19 04:15:31,859 DEBUG [org.hibernate.engine.Cascade] processing cascade ACTION_SAVE_UPDATE for: org.jboss.portal.core.impl.model.instance.InstanceImpl
                    2006-03-19 04:15:31,859 DEBUG [org.hibernate.engine.Cascade] cascade ACTION_SAVE_UPDATE for collection: org.jboss.portal.core.impl.model.instance.InstanceImpl.preferencesMap
                    2006-03-19 04:15:31,859 DEBUG [org.hibernate.engine.Cascade] done cascade ACTION_SAVE_UPDATE for collection: org.jboss.portal.core.impl.model.instance.InstanceImpl.preferencesMap
                    2006-03-19 04:15:31,859 DEBUG [org.hibernate.engine.Cascade] deleting orphans for collection: org.jboss.portal.core.impl.model.instance.InstanceImpl.preferencesMap
                    2006-03-19 04:15:31,859 DEBUG [org.hibernate.engine.Cascade] done deleting orphans for collection: org.jboss.portal.core.impl.model.instance.InstanceImpl.preferencesMap
                    2006-03-19 04:15:31,859 DEBUG [org.hibernate.engine.Cascade] done processing cascade ACTION_SAVE_UPDATE for: org.jboss.portal.core.impl.model.instance.InstanceImpl
                    2006-03-19 04:15:31,859 DEBUG [org.hibernate.engine.Cascade] processing cascade ACTION_SAVE_UPDATE for: org.jboss.portal.core.impl.model.instance.InstanceImpl
                    2006-03-19 04:15:31,859 DEBUG [org.hibernate.engine.Cascade] cascade ACTION_SAVE_UPDATE for collection: org.jboss.portal.core.impl.model.instance.InstanceImpl.preferencesMap
                    2006-03-19 04:15:31,859 DEBUG [org.hibernate.engine.Cascade] done cascade ACTION_SAVE_UPDATE for collection: org.jboss.portal.core.impl.model.instance.InstanceImpl.preferencesMap
                    2006-03-19 04:15:31,859 DEBUG [org.hibernate.engine.Cascade] deleting orphans for collection: org.jboss.portal.core.impl.model.instance.InstanceImpl.preferencesMap
                    2006-03-19 04:15:31,859 DEBUG [org.hibernate.engine.Cascade] done deleting orphans for collection: org.jboss.portal.core.impl.model.instance.InstanceImpl.preferencesMap
                    2006-03-19 04:15:31,859 DEBUG [org.hibernate.engine.Cascade] done processing cascade ACTION_SAVE_UPDATE for: org.jboss.portal.core.impl.model.instance.InstanceImpl
                    2006-03-19 04:15:31,859 DEBUG [org.hibernate.engine.Cascade] processing cascade ACTION_SAVE_UPDATE for: org.jboss.portal.core.impl.model.instance.InstanceImpl
                    2006-03-19 04:15:31,859 DEBUG [org.hibernate.engine.Cascade] cascade ACTION_SAVE_UPDATE for collection: org.jboss.portal.core.impl.model.instance.InstanceImpl.preferencesMap
                    2006-03-19 04:15:31,859 DEBUG [org.hibernate.engine.Cascade] done cascade ACTION_SAVE_UPDATE for collection: org.jboss.portal.core.impl.model.instance.InstanceImpl.preferencesMap
                    2006-03-19 04:15:31,859 DEBUG [org.hibernate.engine.Cascade] deleting orphans for collection: org.jboss.portal.core.impl.model.instance.InstanceImpl.preferencesMap
                    2006-03-19 04:15:31,859 DEBUG [org.hibernate.engine.Cascade] done deleting orphans for collection: org.jboss.portal.core.impl.model.instance.InstanceImpl.preferencesMap
                    2006-03-19 04:15:31,859 DEBUG [org.hibernate.engine.Cascade] done processing cascade ACTION_SAVE_UPDATE for: org.jboss.portal.core.impl.model.instance.InstanceImpl
                    2006-03-19 04:15:31,859 DEBUG [org.hibernate.engine.Cascade] processing cascade ACTION_SAVE_UPDATE for: org.jboss.portal.core.impl.model.instance.InstanceImpl
                    2006-03-19 04:15:31,859 DEBUG [org.hibernate.engine.Cascade] cascade ACTION_SAVE_UPDATE for collection: org.jboss.portal.core.impl.model.instance.InstanceImpl.preferencesMap
                    2006-03-19 04:15:31,859 DEBUG [org.hibernate.engine.CascadingAction] cascading to saveOrUpdate: org.jboss.portal.core.impl.model.instance.PreferenceImpl
                    2006-03-19 04:15:31,859 DEBUG [org.hibernate.event.def.AbstractSaveEventListener] persistent instance of: org.jboss.portal.core.impl.model.instance.PreferenceImpl
                    2006-03-19 04:15:31,859 DEBUG [org.hibernate.event.def.DefaultSaveOrUpdateEventListener] ignoring persistent instance
                    2006-03-19 04:15:31,859 DEBUG [org.hibernate.event.def.DefaultSaveOrUpdateEventListener] object already associated with session: [org.jboss.portal.core.impl.model.instance.PreferenceImpl#1]
                    2006-03-19 04:15:31,859 DEBUG [org.hibernate.engine.CascadingAction] cascading to saveOrUpdate: org.jboss.portal.core.impl.model.instance.PreferenceImpl
                    2006-03-19 04:15:31,859 DEBUG [org.hibernate.event.def.AbstractSaveEventListener] persistent instance of: org.jboss.portal.core.impl.model.instance.PreferenceImpl
                    2006-03-19 04:15:31,859 DEBUG [org.hibernate.event.def.DefaultSaveOrUpdateEventListener] ignoring persistent instance
                    2006-03-19 04:15:31,859 DEBUG [org.hibernate.event.def.DefaultSaveOrUpdateEventListener] object already associated with session: [org.jboss.portal.core.impl.model.instance.PreferenceImpl#2]
                    2006-03-19 04:15:31,859 DEBUG [org.hibernate.engine.CascadingAction] cascading to saveOrUpdate: org.jboss.portal.core.impl.model.instance.PreferenceImpl
                    2006-03-19 04:15:31,859 DEBUG [org.hibernate.event.def.AbstractSaveEventListener] persistent instance of: org.jboss.portal.core.impl.model.instance.PreferenceImpl
                    2006-03-19 04:15:31,859 DEBUG [org.hibernate.event.def.DefaultSaveOrUpdateEventListener] ignoring persistent instance
                    2006-03-19 04:15:31,859 DEBUG [org.hibernate.event.def.DefaultSaveOrUpdateEventListener] object already associated with session: [org.jboss.portal.core.impl.model.instance.PreferenceImpl#3]
                    2006-03-19 04:15:31,859 DEBUG [org.hibernate.engine.Cascade] done cascade ACTION_SAVE_UPDATE for collection: org.jboss.portal.core.impl.model.instance.InstanceImpl.preferencesMap
                    2006-03-19 04:15:31,859 DEBUG [org.hibernate.engine.Cascade] deleting orphans for collection: org.jboss.portal.core.impl.model.instance.InstanceImpl.preferencesMap
                    2006-03-19 04:15:31,859 DEBUG [org.hibernate.engine.Cascade] done deleting orphans for collection: org.jboss.portal.core.impl.model.instance.InstanceImpl.preferencesMap
                    2006-03-19 04:15:31,859 DEBUG [org.hibernate.engine.Cascade] done processing cascade ACTION_SAVE_UPDATE for: org.jboss.portal.core.impl.model.instance.InstanceImpl
                    2006-03-19 04:15:31,859 DEBUG [org.hibernate.engine.Cascade] processing cascade ACTION_SAVE_UPDATE for: org.jboss.portal.core.impl.model.instance.PreferenceImpl
                    2006-03-19 04:15:31,859 DEBUG [org.hibernate.engine.Cascade] done processing cascade ACTION_SAVE_UPDATE for: org.jboss.portal.core.impl.model.instance.PreferenceImpl
                    2006-03-19 04:15:31,860 DEBUG [org.hibernate.engine.Cascade] processing cascade ACTION_SAVE_UPDATE for: org.jboss.portal.core.impl.model.instance.PreferenceImpl
                    2006-03-19 04:15:31,860 DEBUG [org.hibernate.engine.Cascade] done processing cascade ACTION_SAVE_UPDATE for: org.jboss.portal.core.impl.model.instance.PreferenceImpl
                    2006-03-19 04:15:31,860 DEBUG [org.hibernate.engine.Cascade] processing cascade ACTION_SAVE_UPDATE for: org.jboss.portal.core.impl.model.instance.PreferenceImpl
                    2006-03-19 04:15:31,860 DEBUG [org.hibernate.engine.Cascade] done processing cascade ACTION_SAVE_UPDATE for: org.jboss.portal.core.impl.model.instance.PreferenceImpl
                    2006-03-19 04:15:31,860 DEBUG [org.hibernate.engine.Cascade] processing cascade ACTION_SAVE_UPDATE for: org.jboss.portal.core.impl.model.instance.InstanceImpl
                    2006-03-19 04:15:31,860 DEBUG [org.hibernate.engine.Cascade] cascade ACTION_SAVE_UPDATE for collection: org.jboss.portal.core.impl.model.instance.InstanceImpl.preferencesMap
                    2006-03-19 04:15:31,860 DEBUG [org.hibernate.engine.Cascade] done cascade ACTION_SAVE_UPDATE for collection: org.jboss.portal.core.impl.model.instance.InstanceImpl.preferencesMap
                    2006-03-19 04:15:31,860 DEBUG [org.hibernate.engine.Cascade] deleting orphans for collection: org.jboss.portal.core.impl.model.instance.InstanceImpl.preferencesMap
                    2006-03-19 04:15:31,860 DEBUG [org.hibernate.engine.Cascade] done deleting orphans for collection: org.jboss.portal.core.impl.model.instance.InstanceImpl.preferencesMap
                    2006-03-19 04:15:31,860 DEBUG [org.hibernate.engine.Cascade] done processing cascade ACTION_SAVE_UPDATE for: org.jboss.portal.core.impl.model.instance.InstanceImpl
                    2006-03-19 04:15:31,860 DEBUG [org.hibernate.engine.Cascade] processing cascade ACTION_SAVE_UPDATE for: org.jboss.portal.core.impl.model.instance.InstanceImpl
                    2006-03-19 04:15:31,860 DEBUG [org.hibernate.engine.Cascade] cascade ACTION_SAVE_UPDATE for collection: org.jboss.portal.core.impl.model.instance.InstanceImpl.preferencesMap
                    2006-03-19 04:15:31,860 DEBUG [org.hibernate.engine.Cascade] done cascade ACTION_SAVE_UPDATE for collection: org.jboss.portal.core.impl.model.instance.InstanceImpl.preferencesMap
                    2006-03-19 04:15:31,860 DEBUG [org.hibernate.engine.Cascade] deleting orphans for collection: org.jboss.portal.core.impl.model.instance.InstanceImpl.preferencesMap
                    2006-03-19 04:15:31,860 DEBUG [org.hibernate.engine.Cascade] done deleting orphans for collection: org.jboss.portal.core.impl.model.instance.InstanceImpl.preferencesMap
                    2006-03-19 04:15:31,860 DEBUG [org.hibernate.engine.Cascade] done processing cascade ACTION_SAVE_UPDATE for: org.jboss.portal.core.impl.model.instance.InstanceImpl
                    2006-03-19 04:15:31,860 DEBUG [org.hibernate.engine.Cascade] processing cascade ACTION_SAVE_UPDATE for: org.jboss.portal.core.impl.model.instance.InstanceImpl
                    2006-03-19 04:15:31,860 DEBUG [org.hibernate.engine.Cascade] cascade ACTION_SAVE_UPDATE for collection: org.jboss.portal.core.impl.model.instance.InstanceImpl.preferencesMap
                    2006-03-19 04:15:31,860 DEBUG [org.hibernate.engine.Cascade] done cascade ACTION_SAVE_UPDATE for collection: org.jboss.portal.core.impl.model.instance.InstanceImpl.preferencesMap
                    2006-03-19 04:15:31,860 DEBUG [org.hibernate.engine.Cascade] deleting orphans for collection: org.jboss.portal.core.impl.model.instance.InstanceImpl.preferencesMap
                    2006-03-19 04:15:31,860 DEBUG [org.hibernate.engine.Cascade] done deleting orphans for collection: org.jboss.portal.core.impl.model.instance.InstanceImpl.preferencesMap
                    2006-03-19 04:15:31,860 DEBUG [org.hibernate.engine.Cascade] done processing cascade ACTION_SAVE_UPDATE for: org.jboss.portal.core.impl.model.instance.InstanceImpl
                    2006-03-19 04:15:31,860 DEBUG [org.hibernate.engine.Cascade] processing cascade ACTION_SAVE_UPDATE for: org.jboss.portal.core.impl.model.instance.InstanceImpl
                    2006-03-19 04:15:31,860 DEBUG [org.hibernate.engine.Cascade] cascade ACTION_SAVE_UPDATE for collection: org.jboss.portal.core.impl.model.instance.InstanceImpl.preferencesMap
                    2006-03-19 04:15:31,861 DEBUG [org.hibernate.engine.Cascade] done cascade ACTION_SAVE_UPDATE for collection: org.jboss.portal.core.impl.model.instance.InstanceImpl.preferencesMap
                    2006-03-19 04:15:31,861 DEBUG [org.hibernate.engine.Cascade] deleting orphans for collection: org.jboss.portal.core.impl.model.instance.InstanceImpl.preferencesMap
                    2006-03-19 04:15:31,861 DEBUG [org.hibernate.engine.Cascade] done deleting orphans for collection: org.jboss.portal.core.impl.model.instance.InstanceImpl.preferencesMap
                    2006-03-19 04:15:31,861 DEBUG [org.hibernate.engine.Cascade] done processing cascade ACTION_SAVE_UPDATE for: org.jboss.portal.core.impl.model.instance.InstanceImpl
                    2006-03-19 04:15:31,861 DEBUG [org.hibernate.engine.Cascade] processing cascade ACTION_SAVE_UPDATE for: org.jboss.portal.core.impl.model.instance.InstanceImpl
                    2006-03-19 04:15:31,861 DEBUG [org.hibernate.engine.Cascade] cascade ACTION_SAVE_UPDATE for collection: org.jboss.portal.core.impl.model.instance.InstanceImpl.preferencesMap
                    2006-03-19 04:15:31,861 DEBUG [org.hibernate.engine.Cascade] done cascade ACTION_SAVE_UPDATE for collection: org.jboss.portal.core.impl.model.instance.InstanceImpl.preferencesMap
                    2006-03-19 04:15:31,861 DEBUG [org.hibernate.engine.Cascade] deleting orphans for collection: org.jboss.portal.core.impl.model.instance.InstanceImpl.preferencesMap
                    2006-03-19 04:15:31,861 DEBUG [org.hibernate.engine.Cascade] done deleting orphans for collection: org.jboss.portal.core.impl.model.instance.InstanceImpl.preferencesMap
                    2006-03-19 04:15:31,861 DEBUG [org.hibernate.engine.Cascade] done processing cascade ACTION_SAVE_UPDATE for: org.jboss.portal.core.impl.model.instance.InstanceImpl
                    2006-03-19 04:15:31,861 DEBUG [org.hibernate.engine.Cascade] processing cascade ACTION_SAVE_UPDATE for: org.jboss.portal.core.impl.model.instance.InstanceImpl
                    2006-03-19 04:15:31,861 DEBUG [org.hibernate.engine.Cascade] cascade ACTION_SAVE_UPDATE for collection: org.jboss.portal.core.impl.model.instance.InstanceImpl.preferencesMap
                    2006-03-19 04:15:31,861 DEBUG [org.hibernate.engine.Cascade] done cascade ACTION_SAVE_UPDATE for collection: org.jboss.portal.core.impl.model.instance.InstanceImpl.preferencesMap
                    2006-03-19 04:15:31,861 DEBUG [org.hibernate.engine.Cascade] deleting orphans for collection: org.jboss.portal.core.impl.model.instance.InstanceImpl.preferencesMap
                    2006-03-19 04:15:31,861 DEBUG [org.hibernate.engine.Cascade] done deleting orphans for collection: org.jboss.portal.core.impl.model.instance.InstanceImpl.preferencesMap
                    2006-03-19 04:15:31,861 DEBUG [org.hibernate.engine.Cascade] done processing cascade ACTION_SAVE_UPDATE for: org.jboss.portal.core.impl.model.instance.InstanceImpl
                    2006-03-19 04:15:31,861 DEBUG [org.hibernate.engine.Cascade] processing cascade ACTION_SAVE_UPDATE for: org.jboss.portal.core.impl.model.instance.InstanceImpl
                    2006-03-19 04:15:31,861 DEBUG [org.hibernate.engine.Cascade] cascade ACTION_SAVE_UPDATE for collection: org.jboss.portal.core.impl.model.instance.InstanceImpl.preferencesMap
                    2006-03-19 04:15:31,861 DEBUG [org.hibernate.engine.Cascade] done cascade ACTION_SAVE_UPDATE for collection: org.jboss.portal.core.impl.model.instance.InstanceImpl.preferencesMap
                    2006-03-19 04:15:31,861 DEBUG [org.hibernate.engine.Cascade] deleting orphans for collection: org.jboss.portal.core.impl.model.instance.InstanceImpl.preferencesMap
                    2006-03-19 04:15:31,861 DEBUG [org.hibernate.engine.Cascade] done deleting orphans for collection: org.jboss.portal.core.impl.model.instance.InstanceImpl.preferencesMap
                    2006-03-19 04:15:31,861 DEBUG [org.hibernate.engine.Cascade] done processing cascade ACTION_SAVE_UPDATE for: org.jboss.portal.core.impl.model.instance.InstanceImpl
                    2006-03-19 04:15:31,861 DEBUG [org.hibernate.event.def.AbstractFlushingEventListener] dirty checking collections
                    2006-03-19 04:15:31,861 DEBUG [org.hibernate.event.def.AbstractFlushingEventListener] Flushing entities and processing referenced collections
                    2006-03-19 04:15:31,861 DEBUG [org.hibernate.engine.Collections] Collection found: [org.jboss.portal.core.impl.model.instance.InstanceImpl.preferencesMap#10], was: [org.jboss.portal.core.impl.model.instance.InstanceImpl.preferencesMap#10] (initialized)
                    2006-03-19 04:15:31,861 DEBUG [org.hibernate.engine.Collections] Collection found: [org.jboss.portal.core.impl.model.instance.InstanceImpl.preferencesMap#11], was: [org.jboss.portal.core.impl.model.instance.InstanceImpl.preferencesMap#11] (initialized)
                    2006-03-19 04:15:31,861 DEBUG [org.hibernate.engine.Collections] Collection found: [org.jboss.portal.core.impl.model.instance.InstanceImpl.preferencesMap#12], was: [org.jboss.portal.core.impl.model.instance.InstanceImpl.preferencesMap#12] (initialized)
                    2006-03-19 04:15:31,861 DEBUG [org.hibernate.engine.Collections] Collection found: [org.jboss.portal.core.impl.model.instance.InstanceImpl.preferencesMap#13], was: [org.jboss.portal.core.impl.model.instance.InstanceImpl.preferencesMap#13] (initialized)
                    2006-03-19 04:15:31,861 DEBUG [org.hibernate.engine.Collections] Collection found: [org.jboss.portal.core.impl.model.instance.InstanceImpl.preferencesMap#14], was: [org.jboss.portal.core.impl.model.instance.InstanceImpl.preferencesMap#14] (initialized)
                    2006-03-19 04:15:31,862 DEBUG [org.hibernate.engine.Collections] Collection found: [org.jboss.portal.core.impl.model.instance.InstanceImpl.preferencesMap#15], was: [org.jboss.portal.core.impl.model.instance.InstanceImpl.preferencesMap#15] (initialized)
                    2006-03-19 04:15:31,862 DEBUG [org.hibernate.engine.Collections] Collection found: [org.jboss.portal.core.impl.model.instance.InstanceImpl.preferencesMap#16], was: [org.jboss.portal.core.impl.model.instance.InstanceImpl.preferencesMap#16] (initialized)
                    2006-03-19 04:15:31,862 DEBUG [org.hibernate.engine.Collections] Collection found: [org.jboss.portal.core.impl.model.instance.InstanceImpl.preferencesMap#17], was: [org.jboss.portal.core.impl.model.instance.InstanceImpl.preferencesMap#17] (initialized)
                    2006-03-19 04:15:31,862 DEBUG [org.hibernate.engine.Collections] Collection found: [org.jboss.portal.core.impl.model.instance.PreferenceImpl.strings#1], was: [org.jboss.portal.core.impl.model.instance.PreferenceImpl.strings#1] (initialized)
                    2006-03-19 04:15:31,862 DEBUG [org.hibernate.engine.Collections] Collection found: [org.jboss.portal.core.impl.model.instance.PreferenceImpl.strings#2], was: [org.jboss.portal.core.impl.model.instance.PreferenceImpl.strings#2] (initialized)
                    2006-03-19 04:15:31,862 DEBUG [org.hibernate.engine.Collections] Collection found: [org.jboss.portal.core.impl.model.instance.PreferenceImpl.strings#3], was: [org.jboss.portal.core.impl.model.instance.PreferenceImpl.strings#3] (initialized)
                    2006-03-19 04:15:31,862 DEBUG [org.hibernate.engine.Collections] Collection found: [org.jboss.portal.core.impl.model.instance.InstanceImpl.preferencesMap#18], was: [org.jboss.portal.core.impl.model.instance.InstanceImpl.preferencesMap#18] (initialized)
                    2006-03-19 04:15:31,862 DEBUG [org.hibernate.engine.Collections] Collection found: [org.jboss.portal.core.impl.model.instance.InstanceImpl.preferencesMap#19], was: [org.jboss.portal.core.impl.model.instance.InstanceImpl.preferencesMap#19] (initialized)
                    2006-03-19 04:15:31,862 DEBUG [org.hibernate.engine.Collections] Collection found: [org.jboss.portal.core.impl.model.instance.InstanceImpl.preferencesMap#20], was: [org.jboss.portal.core.impl.model.instance.InstanceImpl.preferencesMap#20] (initialized)
                    2006-03-19 04:15:31,862 DEBUG [org.hibernate.engine.Collections] Collection found: [org.jboss.portal.core.impl.model.instance.InstanceImpl.preferencesMap#21], was: [org.jboss.portal.core.impl.model.instance.InstanceImpl.preferencesMap#21] (initialized)
                    2006-03-19 04:15:31,862 DEBUG [org.hibernate.engine.Collections] Collection found: [org.jboss.portal.core.impl.model.instance.InstanceImpl.preferencesMap#22], was: [org.jboss.portal.core.impl.model.instance.InstanceImpl.preferencesMap#22] (initialized)
                    2006-03-19 04:15:31,862 DEBUG [org.hibernate.engine.Collections] Collection found: [org.jboss.portal.core.impl.model.instance.InstanceImpl.preferencesMap#23], was: [org.jboss.portal.core.impl.model.instance.InstanceImpl.preferencesMap#23] (initialized)
                    2006-03-19 04:15:31,862 DEBUG [org.hibernate.engine.Collections] Collection found: [org.jboss.portal.core.impl.model.instance.InstanceImpl.preferencesMap#24], was: [org.jboss.portal.core.impl.model.instance.InstanceImpl.preferencesMap#24] (initialized)
                    2006-03-19 04:15:31,862 DEBUG [org.hibernate.event.def.AbstractFlushingEventListener] Processing unreferenced collections
                    2006-03-19 04:15:31,862 DEBUG [org.hibernate.event.def.AbstractFlushingEventListener] Scheduling collection removes/(re)creates/updates
                    2006-03-19 04:15:31,862 DEBUG [org.hibernate.event.def.AbstractFlushingEventListener] Flushed: 0 insertions, 0 updates, 0 deletions to 18 objects
                    2006-03-19 04:15:31,862 DEBUG [org.hibernate.event.def.AbstractFlushingEventListener] Flushed: 0 (re)creations, 0 updates, 0 removals to 18 collections
                    2006-03-19 04:15:31,862 DEBUG [org.hibernate.pretty.Printer] listing entities:
                    2006-03-19 04:15:31,862 DEBUG [org.hibernate.pretty.Printer] org.jboss.portal.core.impl.model.instance.InstanceImpl{key=12, componentRef=samples.CachedCounterPortlet, preferencesMap=[], instanceId=CachedCounterPortletInstance}
                    2006-03-19 04:15:31,862 DEBUG [org.hibernate.pretty.Printer] org.jboss.portal.core.impl.model.instance.InstanceImpl{key=20, componentRef=samples.HeaderContentPortlet, preferencesMap=[], instanceId=HeaderContentInstance}
                    2006-03-19 04:15:31,862 DEBUG [org.hibernate.pretty.Printer] org.jboss.portal.core.impl.model.instance.PreferenceImpl{strings=[1.5], key=1, type=1, name=one}
                    2006-03-19 04:15:31,862 DEBUG [org.hibernate.pretty.Printer] org.jboss.portal.core.impl.model.instance.InstanceImpl{key=13, componentRef=samples.PortletSessionPortlet, preferencesMap=[], instanceId=PortletSessionPortletInstance}
                    2006-03-19 04:15:31,862 DEBUG [org.hibernate.pretty.Printer] org.jboss.portal.core.impl.model.instance.InstanceImpl{key=24, componentRef=samples.WsrpSelectorTestPortlet, preferencesMap=[], instanceId=WsrpSelectorTestPortletInstance}
                    2006-03-19 04:15:31,862 DEBUG [org.hibernate.pretty.Printer] org.jboss.portal.core.impl.model.instance.InstanceImpl{key=23, componentRef=samples.HintPortlet, preferencesMap=[], instanceId=HintPortletInstance}
                    2006-03-19 04:15:31,863 DEBUG [org.hibernate.pretty.Printer] org.jboss.portal.core.impl.model.instance.InstanceImpl{key=19, componentRef=samples.PortletB, preferencesMap=[], instanceId=PortletBInstance}
                    2006-03-19 04:15:31,863 DEBUG [org.hibernate.pretty.Printer] org.jboss.portal.core.impl.model.instance.InstanceImpl{key=16, componentRef=samples.MissingPortlet, preferencesMap=[], instanceId=MissingPortletInstance}
                    2006-03-19 04:15:31,863 DEBUG [org.hibernate.pretty.Printer] org.jboss.portal.core.impl.model.instance.InstanceImpl{key=14, componentRef=samples.CharsetPortlet, preferencesMap=[], instanceId=CharsetPortletInstance}
                    2006-03-19 04:15:31,863 DEBUG [org.hibernate.pretty.Printer] org.jboss.portal.core.impl.model.instance.InstanceImpl{key=22, componentRef=samples.SecuredTestPortlet, preferencesMap=[], instanceId=SecuredTestPortletInstance}
                    2006-03-19 04:15:31,863 DEBUG [org.hibernate.pretty.Printer] org.jboss.portal.core.impl.model.instance.InstanceImpl{key=17, componentRef=samples.PreferencesPortlet, preferencesMap=[org.jboss.portal.core.impl.model.instance.PreferenceImpl#1, org.jboss.portal.core.impl.model.instance.PreferenceImpl#2, org.jboss.portal.core.impl.model.instance.PreferenceImpl#3], instanceId=PreferencesPortletInstance}
                    2006-03-19 04:15:31,863 DEBUG [org.hibernate.pretty.Printer] org.jboss.portal.core.impl.model.instance.PreferenceImpl{strings=[3.5], key=3, type=1, name=three}
                    2006-03-19 04:15:31,863 DEBUG [org.hibernate.pretty.Printer] org.jboss.portal.core.impl.model.instance.InstanceImpl{key=11, componentRef=samples.CounterPortlet, preferencesMap=[], instanceId=CounterPortletInstance}
                    2006-03-19 04:15:31,863 DEBUG [org.hibernate.pretty.Printer] org.jboss.portal.core.impl.model.instance.InstanceImpl{key=18, componentRef=samples.PortletA, preferencesMap=[], instanceId=PortletAInstance}
                    2006-03-19 04:15:31,863 DEBUG [org.hibernate.pretty.Printer] org.jboss.portal.core.impl.model.instance.InstanceImpl{key=10, componentRef=samples.TestPortlet, preferencesMap=[], instanceId=TestPortletInstance}
                    2006-03-19 04:15:31,863 DEBUG [org.hibernate.pretty.Printer] org.jboss.portal.core.impl.model.instance.InstanceImpl{key=21, componentRef=samples.ContentRewritePortlet, preferencesMap=[], instanceId=ContentRewriteInstance}
                    2006-03-19 04:15:31,863 DEBUG [org.hibernate.pretty.Printer] org.jboss.portal.core.impl.model.instance.PreferenceImpl{strings=[2.5, 3.5, , 4.5], key=2, type=1, name=two}
                    2006-03-19 04:15:31,863 DEBUG [org.hibernate.pretty.Printer] org.jboss.portal.core.impl.model.instance.InstanceImpl{key=15, componentRef=samples.ExceptionPortlet, preferencesMap=[], instanceId=ExceptionPortletInstance}
                    2006-03-19 04:15:31,863 DEBUG [org.hibernate.event.def.AbstractFlushingEventListener] executing flush
                    2006-03-19 04:15:31,863 DEBUG [org.hibernate.event.def.AbstractFlushingEventListener] post flush
                    2006-03-19 04:15:31,863 DEBUG [org.hibernate.jdbc.JDBCContext] before transaction completion
                    2006-03-19 04:15:31,863 DEBUG [org.hibernate.impl.SessionImpl] before transaction completion
                    2006-03-19 04:15:31,863 DEBUG [org.hibernate.transaction.CacheSynchronization] transaction after completion callback, status: 3
                    2006-03-19 04:15:31,863 DEBUG [org.hibernate.jdbc.JDBCContext] after transaction completion
                    2006-03-19 04:15:31,863 DEBUG [org.hibernate.impl.SessionImpl] after transaction completion
                    2006-03-19 04:15:31,863 DEBUG [org.hibernate.transaction.CacheSynchronization] automatically closing session
                    2006-03-19 04:15:31,863 DEBUG [org.hibernate.impl.SessionImpl] automatically closing session
                    2006-03-19 04:15:31,863 DEBUG [org.hibernate.impl.SessionImpl] closing session
                    2006-03-19 04:15:31,863 DEBUG [org.hibernate.jdbc.ConnectionManager] connection already null in cleanup : no action
                    2006-03-19 04:15:31,863 DEBUG [org.hibernate.transaction.CacheSynchronization] transaction after completion callback, status: 3
                    2006-03-19 04:15:31,863 DEBUG [org.hibernate.jdbc.JDBCContext] after transaction completion
                    2006-03-19 04:15:31,864 DEBUG [org.hibernate.impl.SessionImpl] after transaction completion
                    2006-03-19 04:15:31,864 DEBUG [org.hibernate.transaction.CacheSynchronization] automatically closing session
                    2006-03-19 04:15:31,864 DEBUG [org.hibernate.impl.SessionImpl] automatically closing session
                    2006-03-19 04:15:31,864 DEBUG [org.hibernate.impl.SessionImpl] closing session
                    2006-03-19 04:15:31,864 DEBUG [org.hibernate.jdbc.ConnectionManager] connection already null in cleanup : no action
                    2006-03-19 04:15:31,864 DEBUG [org.jboss.deployment.MainDeployer] End deployment start on package: default-object.xml
                    2006-03-19 04:15:31,864 DEBUG [org.jboss.deployment.MainDeployer] Begin deployment start file:/usr/local/jboss/server/default/deploy/jboss-portal.sar/portal-samples.war/WEB-INF/portlet.xml
                    2006-03-19 04:15:31,865 DEBUG [org.jboss.cache.Node] acquiring RL: fqn=/component, caller=Thread[main,5,jboss], lock=
                    2006-03-19 04:15:31,865 DEBUG [org.jboss.cache.Node] acquired RL: fqn=/component, caller=Thread[main,5,jboss], lock=read owners=[Thread[main,5,jboss]]
                    2006-03-19 04:15:31,865 DEBUG [org.jboss.cache.Node] createChild: fqn=/component/samples.CounterPortlet, child_name=samples.CounterPortlet
                    2006-03-19 04:15:31,865 DEBUG [org.jboss.cache.interceptors.LockInterceptor] created child samples.CounterPortlet
                    2006-03-19 04:15:31,865 DEBUG [org.jboss.cache.Node] acquiring WL: fqn=/component/samples.CounterPortlet, caller=Thread[main,5,jboss], lock=
                    2006-03-19 04:15:31,865 DEBUG [org.jboss.cache.Node] acquired WL: fqn=/component/samples.CounterPortlet, caller=Thread[main,5,jboss], lock=write owner=Thread[main,5,jboss]
                    2006-03-19 04:15:31,865 DEBUG [org.jboss.cache.TreeCache] _put(null, "/component/samples.CounterPortlet", {type=org.jboss.portal.security.impl.store.ObjectEntry@1b0590b, constraints=org.jboss.portal.security.impl.store.ObjectEntry@a60905})
                    2006-03-19 04:15:31,865 DEBUG [org.jboss.cache.TreeCache] _get("/component/samples.CounterPortlet", type, "false")
                    2006-03-19 04:15:31,865 DEBUG [org.jboss.cache.TreeCache] _get("/component/samples.CounterPortlet", constraints, "false")
                    2006-03-19 04:15:31,867 DEBUG [org.jboss.cache.interceptors.UnlockInterceptor] releasing lock for /component/samples.CounterPortlet: write owner=Thread[main,5,jboss]
                    2006-03-19 04:15:31,867 DEBUG [org.jboss.cache.interceptors.UnlockInterceptor] releasing lock for /component: read owners=[Thread[main,5,jboss]]
                    2006-03-19 04:15:31,867 DEBUG [org.jboss.cache.Node] acquiring RL: fqn=/component, caller=Thread[main,5,jboss], lock=
                    2006-03-19 04:15:31,867 DEBUG [org.jboss.cache.Node] acquired RL: fqn=/component, caller=Thread[main,5,jboss], lock=read owners=[Thread[main,5,jboss]]
                    2006-03-19 04:15:31,867 DEBUG [org.jboss.cache.Node] createChild: fqn=/component/samples.ThemeSelectorPortlet, child_name=samples.ThemeSelectorPortlet
                    2006-03-19 04:15:31,867 DEBUG [org.jboss.cache.interceptors.LockInterceptor] created child samples.ThemeSelectorPortlet
                    2006-03-19 04:15:31,867 DEBUG [org.jboss.cache.Node] acquiring WL: fqn=/component/samples.ThemeSelectorPortlet, caller=Thread[main,5,jboss], lock=
                    2006-03-19 04:15:31,867 DEBUG [org.jboss.cache.Node] acquired WL: fqn=/component/samples.ThemeSelectorPortlet, caller=Thread[main,5,jboss], lock=write owner=Thread[main,5,jboss]
                    2006-03-19 04:15:31,867 DEBUG [org.jboss.cache.TreeCache] _put(null, "/component/samples.ThemeSelectorPortlet", {type=org.jboss.portal.security.impl.store.ObjectEntry@1df9c23, constraints=org.jboss.portal.security.impl.store.ObjectEntry@183eae7})
                    2006-03-19 04:15:31,868 DEBUG [org.jboss.cache.TreeCache] _get("/component/samples.ThemeSelectorPortlet", type, "false")
                    2006-03-19 04:15:31,868 DEBUG [org.jboss.cache.TreeCache] _get("/component/samples.ThemeSelectorPortlet", constraints, "false")
                    2006-03-19 04:15:31,869 DEBUG [org.jboss.cache.interceptors.UnlockInterceptor] releasing lock for /component/samples.ThemeSelectorPortlet: write owner=Thread[main,5,jboss]
                    2006-03-19 04:15:31,869 DEBUG [org.jboss.cache.interceptors.UnlockInterceptor] releasing lock for /component: read owners=[Thread[main,5,jboss]]
                    2006-03-19 04:15:31,869 DEBUG [org.jboss.cache.Node] acquiring RL: fqn=/component, caller=Thread[main,5,jboss], lock=
                    2006-03-19 04:15:31,869 DEBUG [org.jboss.cache.Node] acquired RL: fqn=/component, caller=Thread[main,5,jboss], lock=read owners=[Thread[main,5,jboss]]
                    2006-03-19 04:15:31,869 DEBUG [org.jboss.cache.Node] createChild: fqn=/component/samples.PortletA, child_name=samples.PortletA
                    2006-03-19 04:15:31,869 DEBUG [org.jboss.cache.interceptors.LockInterceptor] created child samples.PortletA
                    2006-03-19 04:15:31,869 DEBUG [org.jboss.cache.Node] acquiring WL: fqn=/component/samples.PortletA, caller=Thread[main,5,jboss], lock=
                    2006-03-19 04:15:31,869 DEBUG [org.jboss.cache.Node] acquired WL: fqn=/component/samples.PortletA, caller=Thread[main,5,jboss], lock=write owner=Thread[main,5,jboss]
                    2006-03-19 04:15:31,869 DEBUG [org.jboss.cache.TreeCache] _put(null, "/component/samples.PortletA", {type=org.jboss.portal.security.impl.store.ObjectEntry@1b2f0c7, constraints=org.jboss.portal.security.impl.store.ObjectEntry@ddab20})
                    2006-03-19 04:15:31,869 DEBUG [org.jboss.cache.TreeCache] _get("/component/samples.PortletA", type, "false")
                    2006-03-19 04:15:31,869 DEBUG [org.jboss.cache.TreeCache] _get("/component/samples.PortletA", constraints, "false")
                    2006-03-19 04:15:31,875 DEBUG [org.jboss.cache.interceptors.UnlockInterceptor] releasing lock for /component/samples.PortletA: write owner=Thread[main,5,jboss]
                    2006-03-19 04:15:31,875 DEBUG [org.jboss.cache.interceptors.UnlockInterceptor] releasing lock for /component: read owners=[Thread[main,5,jboss]]
                    2006-03-19 04:15:31,876 DEBUG [org.jboss.cache.Node] acquiring RL: fqn=/component, caller=Thread[main,5,jboss], lock=
                    2006-03-19 04:15:31,876 DEBUG [org.jboss.cache.Node] acquired RL: fqn=/component, caller=Thread[main,5,jboss], lock=read owners=[Thread[main,5,jboss]]
                    2006-03-19 04:15:31,876 DEBUG [org.jboss.cache.Node] createChild: fqn=/component/samples.CharsetPortlet, child_name=samples.CharsetPortlet
                    2006-03-19 04:15:31,876 DEBUG [org.jboss.cache.interceptors.LockInterceptor] created child samples.CharsetPortlet
                    2006-03-19 04:15:31,876 DEBUG [org.jboss.cache.Node] acquiring WL: fqn=/component/samples.CharsetPortlet, caller=Thread[main,5,jboss], lock=
                    2006-03-19 04:15:31,876 DEBUG [org.jboss.cache.Node] acquired WL: fqn=/component/samples.CharsetPortlet, caller=Thread[main,5,jboss], lock=write owner=Thread[main,5,jboss]
                    2006-03-19 04:15:31,876 DEBUG [org.jboss.cache.TreeCache] _put(null, "/component/samples.CharsetPortlet", {type=org.jboss.portal.security.impl.store.ObjectEntry@10ddf0a, constraints=org.jboss.portal.security.impl.store.ObjectEntry@1ae3b4d})
                    2006-03-19 04:15:31,876 DEBUG [org.jboss.cache.TreeCache] _get("/component/samples.CharsetPortlet", type, "false")
                    2006-03-19 04:15:31,876 DEBUG [org.jboss.cache.TreeCache] _get("/component/samples.CharsetPortlet", constraints, "false")
                    2006-03-19 04:15:31,877 DEBUG [org.jboss.cache.interceptors.UnlockInterceptor] releasing lock for /component/samples.CharsetPortlet: write owner=Thread[main,5,jboss]
                    2006-03-19 04:15:31,877 DEBUG [org.jboss.cache.interceptors.UnlockInterceptor] releasing lock for /component: read owners=[Thread[main,5,jboss]]
                    2006-03-19 04:15:31,877 DEBUG [org.jboss.cache.Node] acquiring RL: fqn=/component, caller=Thread[main,5,jboss], lock=
                    2006-03-19 04:15:31,877 DEBUG [org.jboss.cache.Node] acquired RL: fqn=/component, caller=Thread[main,5,jboss], lock=read owners=[Thread[main,5,jboss]]
                    2006-03-19 04:15:31,877 DEBUG [org.jboss.cache.Node] createChild: fqn=/component/samples.WsrpSelectorTestPortlet, child_name=samples.WsrpSelectorTestPortlet
                    2006-03-19 04:15:31,877 DEBUG [org.jboss.cache.interceptors.LockInterceptor] created child samples.WsrpSelectorTestPortlet
                    2006-03-19 04:15:31,877 DEBUG [org.jboss.cache.Node] acquiring WL: fqn=/component/samples.WsrpSelectorTestPortlet, caller=Thread[main,5,jboss], lock=
                    2006-03-19 04:15:31,877 DEBUG [org.jboss.cache.Node] acquired WL: fqn=/component/samples.WsrpSelectorTestPortlet, caller=Thread[main,5,jboss], lock=write owner=Thread[main,5,jboss]
                    2006-03-19 04:15:31,877 DEBUG [org.jboss.cache.TreeCache] _put(null, "/component/samples.WsrpSelectorTestPortlet", {type=org.jboss.portal.security.impl.store.ObjectEntry@1b7682d, constraints=org.jboss.portal.security.impl.store.ObjectEntry@1873a6})
                    2006-03-19 04:15:31,877 DEBUG [org.jboss.cache.TreeCache] _get("/component/samples.WsrpSelectorTestPortlet", type, "false")
                    2006-03-19 04:15:31,877 DEBUG [org.jboss.cache.TreeCache] _get("/component/samples.WsrpSelectorTestPortlet", constraints, "false")
                    2006-03-19 04:15:31,878 DEBUG [org.jboss.cache.interceptors.UnlockInterceptor] releasing lock for /component/samples.WsrpSelectorTestPortlet: write owner=Thread[main,5,jboss]
                    2006-03-19 04:15:31,878 DEBUG [org.jboss.cache.interceptors.UnlockInterceptor] releasing lock for /component: read owners=[Thread[main,5,jboss]]
                    2006-03-19 04:15:31,878 DEBUG [org.jboss.cache.Node] acquiring RL: fqn=/component, caller=Thread[main,5,jboss], lock=
                    2006-03-19 04:15:31,878 DEBUG [org.jboss.cache.Node] acquired RL: fqn=/component, caller=Thread[main,5,jboss], lock=read owners=[Thread[main,5,jboss]]
                    2006-03-19 04:15:31,878 DEBUG [org.jboss.cache.Node] createChild: fqn=/component/samples.PortletB, child_name=samples.PortletB
                    2006-03-19 04:15:31,878 DEBUG [org.jboss.cache.interceptors.LockInterceptor] created child samples.PortletB
                    2006-03-19 04:15:31,878 DEBUG [org.jboss.cache.Node] acquiring WL: fqn=/component/samples.PortletB, caller=Thread[main,5,jboss], lock=
                    2006-03-19 04:15:31,878 DEBUG [org.jboss.cache.Node] acquired WL: fqn=/component/samples.PortletB, caller=Thread[main,5,jboss], lock=write owner=Thread[main,5,jboss]
                    2006-03-19 04:15:31,878 DEBUG [org.jboss.cache.TreeCache] _put(null, "/component/samples.PortletB", {type=org.jboss.portal.security.impl.store.ObjectEntry@1509f8b, constraints=org.jboss.portal.security.impl.store.ObjectEntry@1a7a05})
                    2006-03-19 04:15:31,878 DEBUG [org.jboss.cache.TreeCache] _get("/component/samples.PortletB", type, "false")
                    2006-03-19 04:15:31,878 DEBUG [org.jboss.cache.TreeCache] _get("/component/samples.PortletB", constraints, "false")
                    2006-03-19 04:15:31,879 DEBUG [org.jboss.cache.interceptors.UnlockInterceptor] releasing lock for /component/samples.PortletB: write owner=Thread[main,5,jboss]
                    2006-03-19 04:15:31,879 DEBUG [org.jboss.cache.interceptors.UnlockInterceptor] releasing lock for /component: read owners=[Thread[main,5,jboss]]
                    2006-03-19 04:15:31,879 DEBUG [org.jboss.cache.Node] acquiring RL: fqn=/component, caller=Thread[main,5,jboss], lock=
                    2006-03-19 04:15:31,879 DEBUG [org.jboss.cache.Node] acquired RL: fqn=/component, caller=Thread[main,5,jboss], lock=read owners=[Thread[main,5,jboss]]
                    2006-03-19 04:15:31,879 DEBUG [org.jboss.cache.Node] createChild: fqn=/component/samples.CachedCounterPortlet, child_name=samples.CachedCounterPortlet
                    2006-03-19 04:15:31,879 DEBUG [org.jboss.cache.interceptors.LockInterceptor] created child samples.CachedCounterPortlet
                    2006-03-19 04:15:31,879 DEBUG [org.jboss.cache.Node] acquiring WL: fqn=/component/samples.CachedCounterPortlet, caller=Thread[main,5,jboss], lock=
                    2006-03-19 04:15:31,879 DEBUG [org.jboss.cache.Node] acquired WL: fqn=/component/samples.CachedCounterPortlet, caller=Thread[main,5,jboss], lock=write owner=Thread[main,5,jboss]
                    2006-03-19 04:15:31,879 DEBUG [org.jboss.cache.TreeCache] _put(null, "/component/samples.CachedCounterPortlet", {type=org.jboss.portal.security.impl.store.ObjectEntry@40ff24, constraints=org.jboss.portal.security.impl.store.ObjectEntry@12e99be})
                    2006-03-19 04:15:31,879 DEBUG [org.jboss.cache.TreeCache] _get("/component/samples.CachedCounterPortlet", type, "false")
                    2006-03-19 04:15:31,879 DEBUG [org.jboss.cache.TreeCache] _get("/component/samples.CachedCounterPortlet", constraints, "false")
                    2006-03-19 04:15:31,886 DEBUG [org.jboss.cache.interceptors.UnlockInterceptor] releasing lock for /component/samples.CachedCounterPortlet: write owner=Thread[main,5,jboss]
                    2006-03-19 04:15:31,886 DEBUG [org.jboss.cache.interceptors.UnlockInterceptor] releasing lock for /component: read owners=[Thread[main,5,jboss]]
                    2006-03-19 04:15:31,886 DEBUG [org.jboss.cache.Node] acquiring RL: fqn=/component, caller=Thread[main,5,jboss], lock=
                    2006-03-19 04:15:31,886 DEBUG [org.jboss.cache.Node] acquired RL: fqn=/component, caller=Thread[main,5,jboss], lock=read owners=[Thread[main,5,jboss]]
                    2006-03-19 04:15:31,886 DEBUG [org.jboss.cache.Node] createChild: fqn=/component/samples.TestPortlet, child_name=samples.TestPortlet
                    2006-03-19 04:15:31,886 DEBUG [org.jboss.cache.interceptors.LockInterceptor] created child samples.TestPortlet
                    2006-03-19 04:15:31,886 DEBUG [org.jboss.cache.Node] acquiring WL: fqn=/component/samples.TestPortlet, caller=Thread[main,5,jboss], lock=
                    2006-03-19 04:15:31,886 DEBUG [org.jboss.cache.Node] acquired WL: fqn=/component/samples.TestPortlet, caller=Thread[main,5,jboss], lock=write owner=Thread[main,5,jboss]
                    2006-03-19 04:15:31,886 DEBUG [org.jboss.cache.TreeCache] _put(null, "/component/samples.TestPortlet", {type=org.jboss.portal.security.impl.store.ObjectEntry@6fa22b, constraints=org.jboss.portal.security.impl.store.ObjectEntry@ffeba4})
                    2006-03-19 04:15:31,887 DEBUG [org.jboss.cache.TreeCache] _get("/component/samples.TestPortlet", type, "false")
                    2006-03-19 04:15:31,887 DEBUG [org.jboss.cache.TreeCache] _get("/component/samples.TestPortlet", constraints, "false")
                    2006-03-19 04:15:31,887 DEBUG [org.jboss.cache.interceptors.UnlockInterceptor] releasing lock for /component/samples.TestPortlet: write owner=Thread[main,5,jboss]
                    2006-03-19 04:15:31,887 DEBUG [org.jboss.cache.interceptors.UnlockInterceptor] releasing lock for /component: read owners=[Thread[main,5,jboss]]
                    2006-03-19 04:15:31,887 DEBUG [org.jboss.cache.Node] acquiring RL: fqn=/component, caller=Thread[main,5,jboss], lock=
                    2006-03-19 04:15:31,887 DEBUG [org.jboss.cache.Node] acquired RL: fqn=/component, caller=Thread[main,5,jboss], lock=read owners=[Thread[main,5,jboss]]
                    2006-03-19 04:15:31,887 DEBUG [org.jboss.cache.Node] createChild: fqn=/component/samples.ExceptionPortlet, child_name=samples.ExceptionPortlet
                    2006-03-19 04:15:31,887 DEBUG [org.jboss.cache.interceptors.LockInterceptor] created child samples.ExceptionPortlet
                    2006-03-19 04:15:31,888 DEBUG [org.jboss.cache.Node] acquiring WL: fqn=/component/samples.ExceptionPortlet, caller=Thread[main,5,jboss], lock=
                    2006-03-19 04:15:31,888 DEBUG [org.jboss.cache.Node] acquired WL: fqn=/component/samples.ExceptionPortlet, caller=Thread[main,5,jboss], lock=write owner=Thread[main,5,jboss]
                    2006-03-19 04:15:31,888 DEBUG [org.jboss.cache.TreeCache] _put(null, "/component/samples.ExceptionPortlet", {type=org.jboss.portal.security.impl.store.ObjectEntry@1c78066, constraints=org.jboss.portal.security.impl.store.ObjectEntry@becf68})
                    2006-03-19 04:15:31,888 DEBUG [org.jboss.cache.TreeCache] _get("/component/samples.ExceptionPortlet", type, "false")
                    2006-03-19 04:15:31,888 DEBUG [org.jboss.cache.TreeCache] _get("/component/samples.ExceptionPortlet", constraints, "false")
                    2006-03-19 04:15:31,888 DEBUG [org.jboss.cache.interceptors.UnlockInterceptor] releasing lock for /component/samples.ExceptionPortlet: write owner=Thread[main,5,jboss]
                    2006-03-19 04:15:31,888 DEBUG [org.jboss.cache.interceptors.UnlockInterceptor] releasing lock for /component: read owners=[Thread[main,5,jboss]]
                    2006-03-19 04:15:31,889 DEBUG [org.jboss.cache.Node] acquiring RL: fqn=/component, caller=Thread[main,5,jboss], lock=
                    2006-03-19 04:15:31,889 DEBUG [org.jboss.cache.Node] acquired RL: fqn=/component, caller=Thread[main,5,jboss], lock=read owners=[Thread[main,5,jboss]]
                    2006-03-19 04:15:31,889 DEBUG [org.jboss.cache.Node] createChild: fqn=/component/samples.SecuredTestPortlet, child_name=samples.SecuredTestPortlet
                    2006-03-19 04:15:31,889 DEBUG [org.jboss.cache.interceptors.LockInterceptor] created child samples.SecuredTestPortlet
                    2006-03-19 04:15:31,889 DEBUG [org.jboss.cache.Node] acquiring WL: fqn=/component/samples.SecuredTestPortlet, caller=Thread[main,5,jboss], lock=
                    2006-03-19 04:15:31,889 DEBUG [org.jboss.cache.Node] acquired WL: fqn=/component/samples.SecuredTestPortlet, caller=Thread[main,5,jboss], lock=write owner=Thread[main,5,jboss]
                    2006-03-19 04:15:31,889 DEBUG [org.jboss.cache.TreeCache] _put(null, "/component/samples.SecuredTestPortlet", {type=org.jboss.portal.security.impl.store.ObjectEntry@122c082, constraints=org.jboss.portal.security.impl.store.ObjectEntry@8cbc51})
                    2006-03-19 04:15:31,889 DEBUG [org.jboss.cache.TreeCache] _get("/component/samples.SecuredTestPortlet", type, "false")
                    2006-03-19 04:15:31,889 DEBUG [org.jboss.cache.TreeCache] _get("/component/samples.SecuredTestPortlet", constraints, "false")
                    2006-03-19 04:15:31,896 DEBUG [org.jboss.cache.interceptors.UnlockInterceptor] releasing lock for /component/samples.SecuredTestPortlet: write owner=Thread[main,5,jboss]
                    2006-03-19 04:15:31,896 DEBUG [org.jboss.cache.interceptors.UnlockInterceptor] releasing lock for /component: read owners=[Thread[main,5,jboss]]
                    2006-03-19 04:15:31,896 DEBUG [org.jboss.cache.Node] acquiring RL: fqn=/component, caller=Thread[main,5,jboss], lock=
                    2006-03-19 04:15:31,896 DEBUG [org.jboss.cache.Node] acquired RL: fqn=/component, caller=Thread[main,5,jboss], lock=read owners=[Thread[main,5,jboss]]
                    2006-03-19 04:15:31,896 DEBUG [org.jboss.cache.Node] createChild: fqn=/component/samples.HeaderContentPortlet, child_name=samples.HeaderContentPortlet
                    2006-03-19 04:15:31,896 DEBUG [org.jboss.cache.interceptors.LockInterceptor] created child samples.HeaderContentPortlet
                    2006-03-19 04:15:31,896 DEBUG [org.jboss.cache.Node] acquiring WL: fqn=/component/samples.HeaderContentPortlet, caller=Thread[main,5,jboss], lock=
                    2006-03-19 04:15:31,896 DEBUG [org.jboss.cache.Node] acquired WL: fqn=/component/samples.HeaderContentPortlet, caller=Thread[main,5,jboss], lock=write owner=Thread[main,5,jboss]
                    2006-03-19 04:15:31,896 DEBUG [org.jboss.cache.TreeCache] _put(null, "/component/samples.HeaderContentPortlet", {type=org.jboss.portal.security.impl.store.ObjectEntry@19f6e39, constraints=org.jboss.portal.security.impl.store.ObjectEntry@1fd8905})
                    2006-03-19 04:15:31,896 DEBUG [org.jboss.cache.TreeCache] _get("/component/samples.HeaderContentPortlet", type, "false")
                    2006-03-19 04:15:31,896 DEBUG [org.jboss.cache.TreeCache] _get("/component/samples.HeaderContentPortlet", constraints, "false")
                    2006-03-19 04:15:31,897 DEBUG [org.jboss.cache.interceptors.UnlockInterceptor] releasing lock for /component/samples.HeaderContentPortlet: write owner=Thread[main,5,jboss]
                    2006-03-19 04:15:31,897 DEBUG [org.jboss.cache.interceptors.UnlockInterceptor] releasing lock for /component: read owners=[Thread[main,5,jboss]]
                    2006-03-19 04:15:31,897 DEBUG [org.jboss.cache.Node] acquiring RL: fqn=/component, caller=Thread[main,5,jboss], lock=
                    2006-03-19 04:15:31,897 DEBUG [org.jboss.cache.Node] acquired RL: fqn=/component, caller=Thread[main,5,jboss], lock=read owners=[Thread[main,5,jboss]]
                    2006-03-19 04:15:31,897 DEBUG [org.jboss.cache.Node] createChild: fqn=/component/samples.PreferencesPortlet, child_name=samples.PreferencesPortlet
                    2006-03-19 04:15:31,897 DEBUG [org.jboss.cache.interceptors.LockInterceptor] created child samples.PreferencesPortlet
                    2006-03-19 04:15:31,897 DEBUG [org.jboss.cache.Node] acquiring WL: fqn=/component/samples.PreferencesPortlet, caller=Thread[main,5,jboss], lock=
                    2006-03-19 04:15:31,897 DEBUG [org.jboss.cache.Node] acquired WL: fqn=/component/samples.PreferencesPortlet, caller=Thread[main,5,jboss], lock=write owner=Thread[main,5,jboss]
                    2006-03-19 04:15:31,897 DEBUG [org.jboss.cache.TreeCache] _put(null, "/component/samples.PreferencesPortlet", {type=org.jboss.portal.security.impl.store.ObjectEntry@2edea6, constraints=org.jboss.portal.security.impl.store.ObjectEntry@11b8e71})
                    2006-03-19 04:15:31,897 DEBUG [org.jboss.cache.TreeCache] _get("/component/samples.PreferencesPortlet", type, "false")
                    2006-03-19 04:15:31,897 DEBUG [org.jboss.cache.TreeCache] _get("/component/samples.PreferencesPortlet", constraints, "false")
                    2006-03-19 04:15:31,898 DEBUG [org.jboss.cache.interceptors.UnlockInterceptor] releasing lock for /component/samples.PreferencesPortlet: write owner=Thread[main,5,jboss]
                    2006-03-19 04:15:31,898 DEBUG [org.jboss.cache.interceptors.UnlockInterceptor] releasing lock for /component: read owners=[Thread[main,5,jboss]]
                    2006-03-19 04:15:31,898 DEBUG [org.jboss.cache.Node] acquiring RL: fqn=/component, caller=Thread[main,5,jboss], lock=
                    2006-03-19 04:15:31,898 DEBUG [org.jboss.cache.Node] acquired RL: fqn=/component, caller=Thread[main,5,jboss], lock=read owners=[Thread[main,5,jboss]]
                    2006-03-19 04:15:31,898 DEBUG [org.jboss.cache.Node] createChild: fqn=/component/samples.HintPortlet, child_name=samples.HintPortlet
                    2006-03-19 04:15:31,898 DEBUG [org.jboss.cache.interceptors.LockInterceptor] created child samples.HintPortlet
                    2006-03-19 04:15:31,898 DEBUG [org.jboss.cache.Node] acquiring WL: fqn=/component/samples.HintPortlet, caller=Thread[main,5,jboss], lock=
                    2006-03-19 04:15:31,898 DEBUG [org.jboss.cache.Node] acquired WL: fqn=/component/samples.HintPortlet, caller=Thread[main,5,jboss], lock=write owner=Thread[main,5,jboss]
                    2006-03-19 04:15:31,898 DEBUG [org.jboss.cache.TreeCache] _put(null, "/component/samples.HintPortlet", {type=org.jboss.portal.security.impl.store.ObjectEntry@249e4f, constraints=org.jboss.portal.security.impl.store.ObjectEntry@1557af})
                    2006-03-19 04:15:31,898 DEBUG [org.jboss.cache.TreeCache] _get("/component/samples.HintPortlet", type, "false")
                    2006-03-19 04:15:31,898 DEBUG [org.jboss.cache.TreeCache] _get("/component/samples.HintPortlet", constraints, "false")
                    2006-03-19 04:15:31,899 DEBUG [org.jboss.cache.interceptors.UnlockInterceptor] releasing lock for /component/samples.HintPortlet: write owner=Thread[main,5,jboss]
                    2006-03-19 04:15:31,899 DEBUG [org.jboss.cache.interceptors.UnlockInterceptor] releasing lock for /component: read owners=[Thread[main,5,jboss]]
                    2006-03-19 04:15:31,899 DEBUG [org.jboss.cache.Node] acquiring RL: fqn=/component, caller=Thread[main,5,jboss], lock=
                    2006-03-19 04:15:31,899 DEBUG [org.jboss.cache.Node] acquired RL: fqn=/component, caller=Thread[main,5,jboss], lock=read owners=[Thread[main,5,jboss]]
                    2006-03-19 04:15:31,899 DEBUG [org.jboss.cache.Node] createChild: fqn=/component/samples.PortletSessionPortlet, child_name=samples.PortletSessionPortlet
                    2006-03-19 04:15:31,899 DEBUG [org.jboss.cache.interceptors.LockInterceptor] created child samples.PortletSessionPortlet
                    2006-03-19 04:15:31,899 DEBUG [org.jboss.cache.Node] acquiring WL: fqn=/component/samples.PortletSessionPortlet, caller=Thread[main,5,jboss], lock=
                    2006-03-19 04:15:31,899 DEBUG [org.jboss.cache.Node] acquired WL: fqn=/component/samples.PortletSessionPortlet, caller=Thread[main,5,jboss], lock=write owner=Thread[main,5,jboss]
                    2006-03-19 04:15:31,899 DEBUG [org.jboss.cache.TreeCache] _put(null, "/component/samples.PortletSessionPortlet", {type=org.jboss.portal.security.impl.store.ObjectEntry@12fca35, constraints=org.jboss.portal.security.impl.store.ObjectEntry@848c58})
                    2006-03-19 04:15:31,900 DEBUG [org.jboss.cache.TreeCache] _get("/component/samples.PortletSessionPortlet", type, "false")
                    2006-03-19 04:15:31,900 DEBUG [org.jboss.cache.TreeCache] _get("/component/samples.PortletSessionPortlet", constraints, "false")
                    2006-03-19 04:15:31,906 DEBUG [org.jboss.cache.interceptors.UnlockInterceptor] releasing lock for /component/samples.PortletSessionPortlet: write owner=Thread[main,5,jboss]
                    2006-03-19 04:15:31,906 DEBUG [org.jboss.cache.interceptors.UnlockInterceptor] releasing lock for /component: read owners=[Thread[main,5,jboss]]
                    2006-03-19 04:15:31,906 DEBUG [org.jboss.cache.Node] acquiring RL: fqn=/component, caller=Thread[main,5,jboss], lock=
                    2006-03-19 04:15:31,906 DEBUG [org.jboss.cache.Node] acquired RL: fqn=/component, caller=Thread[main,5,jboss], lock=read owners=[Thread[main,5,jboss]]
                    2006-03-19 04:15:31,906 DEBUG [org.jboss.cache.Node] createChild: fqn=/component/samples.ContentRewritePortlet, child_name=samples.ContentRewritePortlet
                    2006-03-19 04:15:31,906 DEBUG [org.jboss.cache.interceptors.LockInterceptor] created child samples.ContentRewritePortlet
                    2006-03-19 04:15:31,906 DEBUG [org.jboss.cache.Node] acquiring WL: fqn=/component/samples.ContentRewritePortlet, caller=Thread[main,5,jboss], lock=
                    2006-03-19 04:15:31,906 DEBUG [org.jboss.cache.Node] acquired WL: fqn=/component/samples.ContentRewritePortlet, caller=Thread[main,5,jboss], lock=write owner=Thread[main,5,jboss]
                    2006-03-19 04:15:31,907 DEBUG [org.jboss.cache.TreeCache] _put(null, "/component/samples.ContentRewritePortlet", {type=org.jboss.portal.security.impl.store.ObjectEntry@9d4a90, constraints=org.jboss.portal.security.impl.store.ObjectEntry@8a605c})
                    2006-03-19 04:15:31,907 DEBUG [org.jboss.cache.TreeCache] _get("/component/samples.ContentRewritePortlet", type, "false")
                    2006-03-19 04:15:31,907 DEBUG [org.jboss.cache.TreeCache] _get("/component/samples.ContentRewritePortlet", constraints, "false")
                    2006-03-19 04:15:31,908 DEBUG [org.jboss.cache.interceptors.UnlockInterceptor] releasing lock for /component/samples.ContentRewritePortlet: write owner=Thread[main,5,jboss]
                    2006-03-19 04:15:31,908 DEBUG [org.jboss.cache.interceptors.UnlockInterceptor] releasing lock for /component: read owners=[Thread[main,5,jboss]]
                    2006-03-19 04:15:31,908 DEBUG [org.jboss.deployment.MainDeployer] End deployment start on package: portlet.xml
                    2006-03-19 04:15:31,908 DEBUG [org.jboss.deployment.MainDeployer] End deployment start on package: WEB-INF
                    2006-03-19 04:15:31,908 DEBUG [org.jboss.deployment.MainDeployer] Deployed package: file:/usr/local/jboss/server/default/deploy/jboss-portal.sar/portal-samples.war/WEB-INF/
                    2006-03-19 04:15:31,908 DEBUG [org.jboss.portal.server.deployment.WebAppAdapter] Seen URL file:/usr/local/jboss/server/default/deploy/jboss-portal.sar/portal-server.war/ about to deploy
                    2006-03-19 04:15:31,915 DEBUG [org.jboss.web.tomcat.filters.ReplyHeaderFilter] Adding header name: X-Powered-By='Servlet 2.4; JBoss-4.0.3SP1 (build: CVSTag=JBoss_4_0_3_SP1 date=200510231054)/Tomcat-5.5'
                    2006-03-19 04:15:31,915 DEBUG [org.jboss.portal.server.deployment.PortalWebTomcat5App] RunAsListener detected
                    2006-03-19 04:15:31,916 DEBUG [org.jboss.portal.server.deployment.PortalWebTomcat5App] RunAsListener detected
                    2006-03-19 04:15:31,916 DEBUG [org.jboss.deployment.MainDeployer] Starting deployment of package: file:/usr/local/jboss/server/default/deploy/jboss-portal.sar/portal-server.war/WEB-INF/
                    2006-03-19 04:15:31,916 DEBUG [org.jboss.deployment.MainDeployer] Starting deployment (init step) of package at: file:/usr/local/jboss/server/default/deploy/jboss-portal.sar/portal-server.war/WEB-INF/
                    2006-03-19 04:15:31,916 DEBUG [org.jboss.deployment.MainDeployer] using existing deployer org.jboss.portal.server.deployment.jboss.ServerDeployer@543937
                    2006-03-19 04:15:31,919 DEBUG [org.jboss.deployment.DeploymentInfo] createLoaderRepository from config: LoaderRepositoryConfig(repositoryName: JMImplementation:service=LoaderRepository,name=Default, repositoryClassName: null, configParserClassName: null, repositoryConfig: null)
                    2006-03-19 04:15:31,919 DEBUG [org.jboss.mx.loading.UnifiedClassLoader] New jmx UCL with url file:/usr/local/jboss/server/default/deploy/jboss-portal.sar/portal-server.war/WEB-INF/
                    2006-03-19 04:15:31,919 DEBUG [org.jboss.mx.loading.RepositoryClassLoader] setRepository, repository=org.jboss.mx.loading.UnifiedLoaderRepository3@a17083, cl=org.jboss.mx.loading.UnifiedClassLoader3@bc71bd{ url=file:/usr/local/jboss/server/default/deploy/jboss-portal.sar/portal-server.war/WEB-INF/ ,addedOrder=0}
                    2006-03-19 04:15:31,919 DEBUG [org.jboss.mx.loading.RepositoryClassLoader] setRepository, repository=org.jboss.mx.loading.UnifiedLoaderRepository3@a17083, cl=org.jboss.mx.loading.UnifiedClassLoader3@bc71bd{ url=file:/usr/local/jboss/server/default/deploy/jboss-portal.sar/portal-server.war/WEB-INF/ ,addedOrder=0}
                    2006-03-19 04:15:31,919 DEBUG [org.jboss.mx.loading.UnifiedLoaderRepository3] Adding org.jboss.mx.loading.UnifiedClassLoader3@bc71bd{ url=file:/usr/local/jboss/server/default/deploy/jboss-portal.sar/portal-server.war/WEB-INF/ ,addedOrder=0}
                    2006-03-19 04:15:31,919 DEBUG [org.jboss.mx.loading.ClassLoaderUtils] Multiple class loaders found for pkg:
                    2006-03-19 04:15:31,986 DEBUG [org.jboss.deployment.MainDeployer] found 1 subpackages of file:/usr/local/jboss/server/default/deploy/jboss-portal.sar/portal-server.war/WEB-INF/
                    2006-03-19 04:15:31,986 DEBUG [org.jboss.deployment.MainDeployer] Starting deployment (init step) of package at: file:/usr/local/jboss/server/default/deploy/jboss-portal.sar/portal-server.war/WEB-INF/server-layouts.xml
                    2006-03-19 04:15:31,987 DEBUG [org.jboss.deployment.MainDeployer] Copying file:/usr/local/jboss/server/default/deploy/jboss-portal.sar/portal-server.war/WEB-INF/server-layouts.xml -> /usr/local/jboss/server/default/tmp/deploy/tmp8007server-layouts.xml
                    2006-03-19 04:15:32,085 DEBUG [org.jboss.deployment.MainDeployer] using existing deployer org.jboss.portal.server.deployment.jboss.ServerDeployer@543937
                    2006-03-19 04:15:32,086 DEBUG [org.jboss.mx.loading.RepositoryClassLoader] Added url: file:/usr/local/jboss/server/default/tmp/deploy/tmp8007server-layouts.xml, to ucl: org.jboss.mx.loading.UnifiedClassLoader3@bc71bd{ url=file:/usr/local/jboss/server/default/deploy/jboss-portal.sar/portal-server.war/WEB-INF/ ,addedOrder=44}
                    2006-03-19 04:15:32,086 DEBUG [org.jboss.deployment.MainDeployer] found 0 subpackages of file:/usr/local/jboss/server/default/deploy/jboss-portal.sar/portal-server.war/WEB-INF/server-layouts.xml
                    2006-03-19 04:15:32,086 DEBUG [org.jboss.deployment.MainDeployer] Watching new file: file:/usr/local/jboss/server/default/deploy/jboss-portal.sar/portal-server.war/WEB-INF/server-layouts.xml
                    2006-03-19 04:15:32,086 DEBUG [org.jboss.deployment.MainDeployer] Watching new file: file:/usr/local/jboss/server/default/deploy/jboss-portal.sar/portal-server.war/WEB-INF/
                    2006-03-19 04:15:32,086 DEBUG [org.jboss.deployment.MainDeployer] create step for deployment file:/usr/local/jboss/server/default/deploy/jboss-portal.sar/portal-server.war/WEB-INF/
                    2006-03-19 04:15:32,086 DEBUG [org.jboss.deployment.MainDeployer] create step for deployment file:/usr/local/jboss/server/default/deploy/jboss-portal.sar/portal-server.war/WEB-INF/server-layouts.xml
                    2006-03-19 04:15:32,086 INFO [org.jboss.portal.theme.deployment.jboss.LayoutDeployment] create new layout(s), found in : /portal
                    2006-03-19 04:15:32,116 DEBUG [org.hibernate.jdbc.ConnectionManager] running Session.finalize()
                    2006-03-19 04:15:32,181 DEBUG [org.jboss.portal.theme.deployment.jboss.LayoutDeployment] adding strategies...
                    2006-03-19 04:15:32,183 DEBUG [org.jboss.portal.theme.deployment.jboss.LayoutDeployment] adding rendersets...
                    2006-03-19 04:15:32,183 INFO [org.jboss.portal.theme.deployment.jboss.LayoutDeployment] done creating new layout(s), found in : /portal
                    2006-03-19 04:15:32,183 DEBUG [org.jboss.deployment.MainDeployer] Done with create step of deploying server-layouts.xml
                    2006-03-19 04:15:32,183 DEBUG [org.jboss.deployment.MainDeployer] Done with create step of deploying WEB-INF
                    2006-03-19 04:15:32,183 DEBUG [org.jboss.deployment.MainDeployer] Begin deployment start file:/usr/local/jboss/server/default/deploy/jboss-portal.sar/portal-server.war/WEB-INF/
                    2006-03-19 04:15:32,183 DEBUG [org.jboss.deployment.MainDeployer] Begin deployment start file:/usr/local/jboss/server/default/deploy/jboss-portal.sar/portal-server.war/WEB-INF/server-layouts.xml
                    2006-03-19 04:15:32,183 DEBUG [org.jboss.deployment.MainDeployer] End deployment start on package: server-layouts.xml
                    2006-03-19 04:15:32,184 DEBUG [org.jboss.deployment.MainDeployer] End deployment start on package: WEB-INF
                    2006-03-19 04:15:32,184 DEBUG [org.jboss.deployment.MainDeployer] Deployed package: file:/usr/local/jboss/server/default/deploy/jboss-portal.sar/portal-server.war/WEB-INF/
                    2006-03-19 04:15:32,193 DEBUG [org.jboss.portal.server.deployment.WebAppAdapter] Seen URL file:/usr/local/jboss/server/default/deploy/jms/jbossmq-httpil.sar/jbossmq-httpil.war/ about to deploy
                    2006-03-19 04:15:32,207 DEBUG [org.jboss.web.tomcat.filters.ReplyHeaderFilter] Adding header name: X-Powered-By='Servlet 2.4; JBoss-4.0.3SP1 (build: CVSTag=JBoss_4_0_3_SP1 date=200510231054)/Tomcat-5.5'
                    2006-03-19 04:15:32,207 DEBUG [org.jboss.portal.server.deployment.PortalWebTomcat5App] RunAsListener detected
                    2006-03-19 04:15:32,208 DEBUG [org.jboss.portal.server.deployment.PortalWebTomcat5App] RunAsListener detected
                    2006-03-19 04:15:32,208 DEBUG [org.jboss.deployment.MainDeployer] Starting deployment of package: file:/usr/local/jboss/server/default/deploy/jms/jbossmq-httpil.sar/jbossmq-httpil.war/WEB-INF/
                    2006-03-19 04:15:32,208 DEBUG [org.jboss.deployment.MainDeployer] Starting deployment (init step) of package at: file:/usr/local/jboss/server/default/deploy/jms/jbossmq-httpil.sar/jbossmq-httpil.war/WEB-INF/
                    2006-03-19 04:15:32,208 DEBUG [org.jboss.deployment.MainDeployer] using existing deployer org.jboss.portal.server.deployment.jboss.ServerDeployer@543937
                    2006-03-19 04:15:32,229 DEBUG [org.jboss.deployment.DeploymentInfo] createLoaderRepository from config: LoaderRepositoryConfig(repositoryName: JMImplementation:service=LoaderRepository,name=Default, repositoryClassName: null, configParserClassName: null, repositoryConfig: null)
                    2006-03-19 04:15:32,229 DEBUG [org.jboss.mx.loading.UnifiedClassLoader] New jmx UCL with url file:/usr/local/jboss/server/default/deploy/jms/jbossmq-httpil.sar/jbossmq-httpil.war/WEB-INF/
                    2006-03-19 04:15:32,229 DEBUG [org.jboss.mx.loading.RepositoryClassLoader] setRepository, repository=org.jboss.mx.loading.UnifiedLoaderRepository3@a17083, cl=org.jboss.mx.loading.UnifiedClassLoader3@161305f{ url=file:/usr/local/jboss/server/default/deploy/jms/jbossmq-httpil.sar/jbossmq-httpil.war/WEB-INF/ ,addedOrder=0}
                    2006-03-19 04:15:32,229 DEBUG [org.jboss.mx.loading.RepositoryClassLoader] setRepository, repository=org.jboss.mx.loading.UnifiedLoaderRepository3@a17083, cl=org.jboss.mx.loading.UnifiedClassLoader3@161305f{ url=file:/usr/local/jboss/server/default/deploy/jms/jbossmq-httpil.sar/jbossmq-httpil.war/WEB-INF/ ,addedOrder=0}
                    2006-03-19 04:15:32,229 DEBUG [org.jboss.mx.loading.UnifiedLoaderRepository3] Adding org.jboss.mx.loading.UnifiedClassLoader3@161305f{ url=file:/usr/local/jboss/server/default/deploy/jms/jbossmq-httpil.sar/jbossmq-httpil.war/WEB-INF/ ,addedOrder=0}
                    2006-03-19 04:15:32,230 DEBUG [org.jboss.mx.loading.ClassLoaderUtils] Multiple class loaders found for pkg:
                    2006-03-19 04:15:32,252 DEBUG [org.jboss.deployment.MainDeployer] found 0 subpackages of file:/usr/local/jboss/server/default/deploy/jms/jbossmq-httpil.sar/jbossmq-httpil.war/WEB-INF/
                    2006-03-19 04:15:32,252 D