Object not found for root page on new install
Hello,
I just installed Jboss Portal 2.2.1 SP 1 on Jboss 4.0.4 CR2 / MySQL 5 and I'm unable to view the main page. Tomcat returns a 404 "requested resource() is not available." The server log contains this:
2006-05-25 11:09:47,087 DEBUG [org.hibernate.jdbc.JDBCContext] successfully registered Synchronization
2006-05-25 11:09:47,087 DEBUG [org.hibernate.impl.SessionImpl] opened session at timestamp: 4704541847904256
2006-05-25 11:09:47,087 DEBUG [org.hibernate.cache.StandardQueryCache] checking cached query results in region: org.hibernate.cache.StandardQueryCache
2006-05-25 11:09:47,087 DEBUG [org.hibernate.cache.EhCache] key: sql: select objectnode0_.PK as PK11_, objectnode0_.PATH as PATH11_, objectnode0_.NAME as NAME11_, objectnode0_.PARENT_KEY as PARENT4_11_ from JBP_OBJECT_NODE objectnode0_ where objectnode0_.PATH=? or objectnode0_.PATH is null; parameters: ; named parameters: {path=}
2006-05-25 11:09:47,087 DEBUG [net.sf.ehcache.Cache] org.hibernate.cache.StandardQueryCache: Is element with key sql: select objectnode0_.PK as PK11_, objectnode0_.PATH as PATH11_, objectnode0_.NAME as NAME11_, objectnode0_.PARENT_KEY as PARENT4_11_ from JBP_OBJECT_NODE objectnode0_ where objectnode0_.PATH=? or objectnode0_.PATH is null; parameters: ; named parameters: {path=} expired?: false
2006-05-25 11:09:47,087 DEBUG [org.hibernate.cache.StandardQueryCache] Checking query spaces for up-to-dateness: [JBP_OBJECT_NODE]
2006-05-25 11:09:47,087 DEBUG [org.hibernate.cache.EhCache] key: JBP_OBJECT_NODE
2006-05-25 11:09:47,087 DEBUG [org.hibernate.cache.EhCache] Element for JBP_OBJECT_NODE is null
2006-05-25 11:09:47,087 DEBUG [org.hibernate.cache.StandardQueryCache] returning cached query results
2006-05-25 11:09:47,087 DEBUG [org.hibernate.cache.EhCache] key: org.jboss.portal.core.impl.model.portal.ObjectNode#1
2006-05-25 11:09:47,087 DEBUG [net.sf.ehcache.Cache] org.jboss.portal.core.impl.model.portal.ObjectNode: Is element with key org.jboss.portal.core.impl.model.portal.ObjectNode#1 expired?: false
2006-05-25 11:09:47,088 DEBUG [org.hibernate.cache.EhCache] key: org.jboss.portal.core.impl.model.portal.PortalObjectImpl#1
2006-05-25 11:09:47,088 DEBUG [org.hibernate.cache.EhCache] Element for org.jboss.portal.core.impl.model.portal.PortalObjectImpl#1 is null
2006-05-25 11:09:47,088 DEBUG [org.hibernate.loader.Loader] loading entity: [org.jboss.portal.core.impl.model.portal.PortalObjectImpl#1]
2006-05-25 11:09:47,088 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to open PreparedStatement (open PreparedStatements: 0, globally: 0)
2006-05-25 11:09:47,088 DEBUG [org.hibernate.jdbc.ConnectionManager] opening JDBC connection
2006-05-25 11:09:47,088 DEBUG [org.hibernate.SQL] select portalobje0_.PK as PK12_0_, portalobje0_.LISTENER as LISTENER12_0_, portalobje0_4_.INSTANCE_REF as INSTANCE2_19_0_, case when portalobje0_1_.PK is not null then 1 when portalobje0_2_.PK is not null then 2 when portalobje0_3_.PK is not null then 3 when portalobje0_4_.PK is not null then 4 when portalobje0_.PK is not null then 0 end as clazz_0_, declaredpr1_.OBJECT_KEY as OBJECT1_2_, declaredpr1_.VALUE as VALUE2_, declaredpr1_.NAME as NAME2_, modes2_.PK as PK3_, modes2_.name as name3_, windowstat3_.PK as PK4_, windowstat3_.name as name4_ from JBP_PORTAL_OBJECT portalobje0_ left outer join JBP_CONTEXT portalobje0_1_ on portalobje0_.PK=portalobje0_1_.PK left outer join JBP_PORTAL portalobje0_2_ on portalobje0_.PK=portalobje0_2_.PK left outer join JBP_PAGE portalobje0_3_ on portalobje0_.PK=portalobje0_3_.PK left outer join JBP_WINDOW portalobje0_4_ on portalobje0_.PK=portalobje0_4_.PK left outer join JBP_PORTAL_OBJECT_PROPS declaredpr1_ on portalobje0_.PK=declaredpr1_.OBJECT_KEY left outer join JBP_PORTAL_MODE modes2_ on portalobje0_.PK=modes2_.PK left outer join JBP_PORTAL_WINDOW_STATE windowstat3_ on portalobje0_.PK=windowstat3_.PK where portalobje0_.PK=?
2006-05-25 11:09:47,090 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to open ResultSet (open ResultSets: 0, globally: 0)
2006-05-25 11:09:47,090 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to close ResultSet (open ResultSets: 1, globally: 1)
2006-05-25 11:09:47,090 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to close PreparedStatement (open PreparedStatements: 1, globally: 1)
2006-05-25 11:09:47,090 DEBUG [org.hibernate.jdbc.ConnectionManager] aggressively releasing JDBC connection
2006-05-25 11:09:47,090 DEBUG [org.hibernate.jdbc.ConnectionManager] releasing JDBC connection [ (open PreparedStatements: 0, globally: 0) (open ResultSets: 0, globally: 0)]
2006-05-25 11:09:47,090 DEBUG [org.hibernate.engine.CollectionLoadContext] 0 collections were found in result set for role: org.jboss.portal.core.impl.model.portal.PortalObjectImpl.declaredProperties
2006-05-25 11:09:47,090 DEBUG [org.hibernate.engine.CollectionLoadContext] 0 collections initialized for role: org.jboss.portal.core.impl.model.portal.PortalObjectImpl.declaredProperties
2006-05-25 11:09:47,090 DEBUG [org.hibernate.engine.CollectionLoadContext] 0 collections were found in result set for role: org.jboss.portal.core.impl.model.portal.PortalImpl.modes
2006-05-25 11:09:47,090 DEBUG [org.hibernate.engine.CollectionLoadContext] 0 collections initialized for role: org.jboss.portal.core.impl.model.portal.PortalImpl.modes
2006-05-25 11:09:47,090 DEBUG [org.hibernate.engine.CollectionLoadContext] 0 collections were found in result set for role: org.jboss.portal.core.impl.model.portal.PortalImpl.windowStates
2006-05-25 11:09:47,090 DEBUG [org.hibernate.engine.CollectionLoadContext] 0 collections initialized for role: org.jboss.portal.core.impl.model.portal.PortalImpl.windowStates
2006-05-25 11:09:47,090 DEBUG [org.hibernate.engine.StatefulPersistenceContext] initializing non-lazy collections
2006-05-25 11:09:47,090 DEBUG [org.hibernate.loader.Loader] done entity load
2006-05-25 11:09:47,091 DEBUG [org.hibernate.engine.StatefulPersistenceContext] initializing non-lazy collections
2006-05-25 11:09:47,092 ERROR [org.jboss.portal.core.CoreController] Object not found
org.jboss.portal.core.ObjectNotFoundException: Not allowed to access resourceDefault context does not exist
at org.jboss.portal.core.model.portal.DefaultPortalCommandMapper.doMapping(DefaultPortalCommandMapper.java:89)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
at java.lang.reflect.Method.invoke(Method.java:585)
at org.jboss.mx.interceptor.ReflectedDispatcher.invoke(ReflectedDispatcher.java:155)
at org.jboss.mx.server.Invocation.dispatch(Invocation.java:94)
at org.jboss.mx.interceptor.AbstractInterceptor.invoke(AbstractInterceptor.java:133)
at org.jboss.mx.server.Invocation.invoke(Invocation.java:88)
at org.jboss.mx.interceptor.ModelMBeanOperationInterceptor.invoke(ModelMBeanOperationInterceptor.java:142)
at org.jboss.mx.server.Invocation.invoke(Invocation.java:88)
at org.jboss.mx.server.AbstractMBeanInvoker.invoke(AbstractMBeanInvoker.java:260)
at org.jboss.mx.server.MBeanServerImpl.invoke(MBeanServerImpl.java:659)
at org.jboss.mx.util.MBeanProxyExt.invoke(MBeanProxyExt.java:190)
at $Proxy45.doMapping(Unknown Source)
at org.jboss.portal.core.CoreController.handle(CoreController.java:189)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
at java.lang.reflect.Method.invoke(Method.java:585)
at org.jboss.mx.interceptor.ReflectedDispatcher.invoke(ReflectedDispatcher.java:155)
at org.jboss.mx.server.Invocation.dispatch(Invocation.java:94)
at org.jboss.mx.interceptor.AbstractInterceptor.invoke(AbstractInterceptor.java:133)
at org.jboss.mx.server.Invocation.invoke(Invocation.java:88)
at org.jboss.mx.interceptor.ModelMBeanOperationInterceptor.invoke(ModelMBeanOperationInterceptor.java:142)
at org.jboss.mx.server.Invocation.invoke(Invocation.java:88)
at org.jboss.mx.server.AbstractMBeanInvoker.invoke(AbstractMBeanInvoker.java:260)
at org.jboss.mx.server.MBeanServerImpl.invoke(MBeanServerImpl.java:659)
at org.jboss.mx.util.JMXInvocationHandler.invoke(JMXInvocationHandler.java:287)
at $Proxy186.handle(Unknown Source)
at org.jboss.portal.server.ServerInvocation.dispatch(ServerInvocation.java:76)
at org.jboss.portal.server.invocation.Invocation.invokeNext(Invocation.java:140)
at org.jboss.portal.core.aspects.server.SubjectAssociationInterceptor.invoke(SubjectAssociationInterceptor.java:47)
at org.jboss.portal.server.ServerInterceptor.invoke(ServerInterceptor.java:38)
at org.jboss.portal.server.invocation.Invocation.invokeNext(Invocation.java:130)
at org.jboss.portal.core.aspects.server.PolicyAssociationInterceptor.invoke(PolicyAssociationInterceptor.java:52)
at org.jboss.portal.server.ServerInterceptor.invoke(ServerInterceptor.java:38)
at org.jboss.portal.server.invocation.Invocation.invokeNext(Invocation.java:130)
at org.jboss.portal.server.aspects.server.ContentTypeInterceptor.invoke(ContentTypeInterceptor.java:68)
at org.jboss.portal.server.ServerInterceptor.invoke(ServerInterceptor.java:38)
at org.jboss.portal.server.invocation.Invocation.invokeNext(Invocation.java:130)
at org.jboss.portal.core.aspects.server.LocaleInterceptor.invoke(LocaleInterceptor.java:69)
at org.jboss.portal.server.ServerInterceptor.invoke(ServerInterceptor.java:38)
at org.jboss.portal.server.invocation.Invocation.invokeNext(Invocation.java:130)
at org.jboss.portal.core.aspects.server.UserInterceptor.invoke(UserInterceptor.java:184)
at org.jboss.portal.server.ServerInterceptor.invoke(ServerInterceptor.java:38)
at org.jboss.portal.server.invocation.Invocation.invokeNext(Invocation.java:130)
at org.jboss.portal.server.aspects.server.SessionInvalidatorInterceptor.invoke(SessionInvalidatorInterceptor.java:87)
at org.jboss.portal.server.ServerInterceptor.invoke(ServerInterceptor.java:38)
at org.jboss.portal.server.invocation.Invocation.invokeNext(Invocation.java:130)
at org.jboss.portal.core.aspects.server.TransactionInterceptor.invoke(TransactionInterceptor.java:75)
at org.jboss.portal.server.ServerInterceptor.invoke(ServerInterceptor.java:38)
at org.jboss.portal.server.invocation.Invocation.invokeNext(Invocation.java:130)
at org.jboss.portal.server.invocation.Invocation.invoke(Invocation.java:175)
at org.jboss.portal.server.servlet.PortalServlet.doGet(PortalServlet.java:221)
at javax.servlet.http.HttpServlet.service(HttpServlet.java:697)
at javax.servlet.http.HttpServlet.service(HttpServlet.java:810)
at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:252)
at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:173)
at org.jboss.web.tomcat.filters.ReplyHeaderFilter.doFilter(ReplyHeaderFilter.java:96)
at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:202)
at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:173)
at org.apache.catalina.core.StandardWrapperValve.invoke(StandardWrapperValve.java:213)
at org.apache.catalina.core.StandardContextValve.invoke(StandardContextValve.java:178)
at org.jboss.web.tomcat.security.CustomPrincipalValve.invoke(CustomPrincipalValve.java:54)
at org.jboss.web.tomcat.security.SecurityAssociationValve.invoke(SecurityAssociationValve.java:174)
at org.apache.catalina.authenticator.AuthenticatorBase.invoke(AuthenticatorBase.java:432)
at org.jboss.web.tomcat.security.JaccContextValve.invoke(JaccContextValve.java:74)
at org.apache.catalina.core.StandardHostValve.invoke(StandardHostValve.java:126)
at org.apache.catalina.valves.ErrorReportValve.invoke(ErrorReportValve.java:105)
at org.apache.catalina.core.StandardEngineValve.invoke(StandardEngineValve.java:107)
at org.apache.catalina.connector.CoyoteAdapter.service(CoyoteAdapter.java:148)
at org.apache.coyote.http11.Http11Processor.process(Http11Processor.java:869)
at org.apache.coyote.http11.Http11BaseProtocol$Http11ConnectionHandler.processConnection(Http11BaseProtocol.java:664)
at org.apache.tomcat.util.net.PoolTcpEndpoint.processSocket(PoolTcpEndpoint.java:527)
at org.apache.tomcat.util.net.MasterSlaveWorkerThread.run(MasterSlaveWorkerThread.java:112)
at java.lang.Thread.run(Thread.java:595)
2006-05-25 11:09:47,094 DEBUG [org.hibernate.event.def.AbstractFlushingEventListener] processing flush-time cascades
2006-05-25 11:09:47,094 DEBUG [org.hibernate.event.def.AbstractFlushingEventListener] dirty checking collections
2006-05-25 11:09:47,094 DEBUG [org.hibernate.engine.Collections] Collection found: [org.jboss.portal.core.impl.model.portal.ObjectNode.children#1], was: [org.jboss.portal.core.impl.model.portal.ObjectNode.children#1] (uninitialized)
2006-05-25 11:09:47,094 DEBUG [org.hibernate.event.def.AbstractFlushingEventListener] Flushed: 0 insertions, 0 updates, 0 deletions to 1 objects
2006-05-25 11:09:47,094 DEBUG [org.hibernate.event.def.AbstractFlushingEventListener] Flushed: 0 (re)creations, 0 updates, 0 removals to 1 collections
2006-05-25 11:09:47,094 DEBUG [org.hibernate.pretty.Printer] listing entities:
2006-05-25 11:09:47,094 DEBUG [org.hibernate.pretty.Printer] org.jboss.portal.core.impl.model.portal.ObjectNode{key=1, object=null, path=, name=, parent=null, children=<uninitialized>}
2006-05-25 11:09:47,094 DEBUG [org.hibernate.jdbc.ConnectionManager] aggressively releasing JDBC connection
I had a look at the DML coming from Hibernate above and the "PK=?" at the end of the WHERE clause looked suspicious. The query, of course, fails because of it when run against the database manually. I have verified that the database is populated, of course. It just looks like the query is bad. Any ideas, folks?
Other possibly pertinent info:
System: Solaris 11 Nevada Build 31A (EMT64)
JRE: Java HotSpot(TM) Client VM (build 1.5.0_06-b04, mixed mode)
MySQL: 5.0.20a
MySQL Connector/J: 3.1.12
Thanks!
JB