Object not found for root page on new install
jrb2460 May 25, 2006 11:55 AMHello,
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