9 Replies Latest reply on Jul 22, 2008 10:46 AM by jaikiran

    Session bean lookup fails JBoss 5, worked in 4.0.5

    lpmon

      I have bean doing jndi session bean lookups for quite a while. All were pre-JBoss 5.

      Using JNDIView I can clearly see the path I am using in the lookup is exactly what I expect it to be and is in the Global Namespace. I get the well known and dreaded javax.naming.NameNotFoundException: ec20 not bound

      My lookup uses ec20/DBUtilBean/local. Here it is in JNDIview:

      +- ec20 (class: org.jnp.interfaces.NamingContext)
      | +- DBUtilBean (class: org.jnp.interfaces.NamingContext)
      | | +- local (proxy: $Proxy193 implements interface

      (this next part is a line wrap)
      org.jboss.ejb3.proxy.JBossProxy,interface com.pearson.ejb3.session.DBUtil)

      The very first path entry, ec20, is what is not found. ?????

      Suggestions?

      Stack Trace:
      javax.naming.NameNotFoundException: ec20 not bound
      at org.jnp.server.NamingServer.getBinding(NamingServer.java:542)
      at org.jnp.server.NamingServer.getBinding(NamingServer.java:550)
      at org.jnp.server.NamingServer.getObject(NamingServer.java:556)
      at org.jnp.server.NamingServer.lookup(NamingServer.java:267)
      at org.jnp.interfaces.NamingContext.lookup(NamingContext.java:669)
      at org.jnp.interfaces.NamingContext.lookup(NamingContext.java:629)
      at javax.naming.InitialContext.lookup(InitialContext.java:351)
      at com.pearson.util.LookupUtil.lookupEJB(LookupUtil.java:59)
      at com.pearson.util.SWStartup.initApp(SWStartup.java:36)
      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.seam.util.Reflections.invoke(Reflections.java:21)
      at org.jboss.seam.util.Reflections.invokeAndWrap(Reflections.java:125)
      at org.jboss.seam.Component.callComponentMethod(Component.java:2092)
      at org.jboss.seam.Component.callPostConstructMethod(Component.java:2039)
      at org.jboss.seam.intercept.JavaBeanInterceptor$1.proceed(JavaBeanInterceptor.java:126)
      at org.jboss.seam.intercept.SeamInvocationContext.proceed(SeamInvocationContext.java:56)
      at org.jboss.seam.intercept.Interceptor.postConstruct(Interceptor.java:184)
      at org.jboss.seam.intercept.SeamInvocationContext.proceed(SeamInvocationContext.java:74)
      at org.jboss.seam.intercept.Interceptor.postConstruct(Interceptor.java:184)
      at org.jboss.seam.intercept.SeamInvocationContext.proceed(SeamInvocationContext.java:74)
      at org.jboss.seam.intercept.RootInterceptor.invoke(RootInterceptor.java:107)
      at org.jboss.seam.intercept.RootInterceptor.invokeAndHandle(RootInterceptor.java:84)
      at org.jboss.seam.intercept.JavaBeanInterceptor.callPostConstruct(JavaBeanInterceptor.java:131)
      at org.jboss.seam.intercept.JavaBeanInterceptor.postConstruct(JavaBeanInterceptor.java:116)
      at org.jboss.seam.Component.instantiateJavaBean(Component.java:1325)
      at org.jboss.seam.Component.instantiate(Component.java:1271)
      at org.jboss.seam.Component.newInstance(Component.java:1970)
      at org.jboss.seam.contexts.Contexts.startup(Contexts.java:304)
      at org.jboss.seam.contexts.Contexts.startup(Contexts.java:278)
      at org.jboss.seam.contexts.ServletLifecycle.endInitialization(ServletLifecycle.java:95)
      at org.jboss.seam.init.Initialization.init(Initialization.java:596)
      at org.jboss.seam.servlet.SeamListener.contextInitialized(SeamListener.java:34)
      at org.apache.catalina.core.StandardContext.listenerStart(StandardContext.java:3862)
      at org.apache.catalina.core.StandardContext.start(StandardContext.java:4356)
      at org.jboss.web.tomcat.service.deployers.TomcatDeployment.performDeployInternal(TomcatDeployment.java:346)
      at org.jboss.web.tomcat.service.deployers.TomcatDeployment.performDeploy(TomcatDeployment.java:139)
      at org.jboss.web.deployers.AbstractWarDeployment.start(AbstractWarDeployment.java:431)
      at org.jboss.web.deployers.WebModule.startModule(WebModule.java:112)
      at org.jboss.web.deployers.WebModule.start(WebModule.java:90)
      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:157)
      at org.jboss.mx.server.Invocation.dispatch(Invocation.java:96)
      at org.jboss.mx.server.Invocation.invoke(Invocation.java:88)
      at org.jboss.mx.server.AbstractMBeanInvoker.invoke(AbstractMBeanInvoker.java:264)
      at org.jboss.mx.server.MBeanServerImpl.invoke(MBeanServerImpl.java:668)
      at org.jboss.system.microcontainer.ServiceProxy.invoke(ServiceProxy.java:206)
      at $Proxy35.start(Unknown Source)
      at org.jboss.system.microcontainer.StartStopLifecycleAction.installAction(StartStopLifecycleAction.java:42)
      at org.jboss.system.microcontainer.StartStopLifecycleAction.installAction(StartStopLifecycleAction.java:37)
      at org.jboss.dependency.plugins.action.SimpleControllerContextAction.simpleInstallAction(SimpleControllerContextAction.java:62)
      at org.jboss.dependency.plugins.action.AccessControllerContextAction.install(AccessControllerContextAction.java:71)
      at org.jboss.dependency.plugins.AbstractControllerContextActions.install(AbstractControllerContextActions.java:51)
      at org.jboss.dependency.plugins.AbstractControllerContext.install(AbstractControllerContext.java:348)
      at org.jboss.system.microcontainer.ServiceControllerContext.install(ServiceControllerContext.java:271)
      at org.jboss.dependency.plugins.AbstractController.install(AbstractController.java:1392)
      at org.jboss.dependency.plugins.AbstractController.incrementState(AbstractController.java:784)
      at org.jboss.dependency.plugins.AbstractController.resolveContexts(AbstractController.java:912)
      at org.jboss.dependency.plugins.AbstractController.resolveContexts(AbstractController.java:834)
      at org.jboss.dependency.plugins.AbstractController.install(AbstractController.java:624)
      at org.jboss.dependency.plugins.AbstractController.install(AbstractController.java:442)
      at org.jboss.deployers.vfs.deployer.kernel.BeanMetaDataDeployer.deploy(BeanMetaDataDeployer.java:88)
      at org.jboss.deployers.vfs.deployer.kernel.BeanMetaDataDeployer.deploy(BeanMetaDataDeployer.java:46)
      at org.jboss.deployers.spi.deployer.helpers.AbstractSimpleRealDeployer.internalDeploy(AbstractSimpleRealDeployer.java:62)
      at org.jboss.deployers.spi.deployer.helpers.AbstractRealDeployer.deploy(AbstractRealDeployer.java:50)
      at org.jboss.deployers.plugins.deployers.DeployerWrapper.deploy(DeployerWrapper.java:174)
      at org.jboss.deployers.plugins.deployers.DeployersImpl.doInstallParentFirst(DeployersImpl.java:970)
      at org.jboss.deployers.plugins.deployers.DeployersImpl.doInstallParentFirst(DeployersImpl.java:991)
      at org.jboss.deployers.plugins.deployers.DeployersImpl.install(DeployersImpl.java:911)
      at org.jboss.dependency.plugins.AbstractControllerContext.install(AbstractControllerContext.java:348)
      at org.jboss.dependency.plugins.AbstractController.install(AbstractController.java:1392)
      at org.jboss.dependency.plugins.AbstractController.incrementState(AbstractController.java:784)
      at org.jboss.dependency.plugins.AbstractController.resolveContexts(AbstractController.java:912)
      at org.jboss.dependency.plugins.AbstractController.resolveContexts(AbstractController.java:834)
      at org.jboss.dependency.plugins.AbstractController.change(AbstractController.java:672)
      at org.jboss.dependency.plugins.AbstractController.change(AbstractController.java:455)
      at org.jboss.deployers.plugins.deployers.DeployersImpl.process(DeployersImpl.java:594)
      at org.jboss.deployers.plugins.main.MainDeployerImpl.process(MainDeployerImpl.java:541)
      at org.jboss.system.server.profileservice.ProfileServiceBootstrap.loadProfile(ProfileServiceBootstrap.java:257)
      at org.jboss.system.server.profileservice.ProfileServiceBootstrap.start(ProfileServiceBootstrap.java:135)
      at org.jboss.bootstrap.AbstractServerImpl.start(AbstractServerImpl.java:409)
      at org.jboss.Main.boot(Main.java:209)
      at org.jboss.Main$1.run(Main.java:544)
      at java.lang.Thread.run(Thread.java:595)

        • 1. Re: Session bean lookup fails JBoss 5, worked in 4.0.5
          lpmon

          Version is: JBoss 5.0.0.CR1

          • 2. Re: Session bean lookup fails JBoss 5, worked in 4.0.5
            jaikiran

             

            javax.naming.NameNotFoundException: ec20 not bound
            at org.jnp.server.NamingServer.getBinding(NamingServer.java:542)
            at org.jnp.server.NamingServer.getBinding(NamingServer.java:550)
            at org.jnp.server.NamingServer.getObject(NamingServer.java:556)
            at org.jnp.server.NamingServer.lookup(NamingServer.java:267)
            at org.jnp.interfaces.NamingContext.lookup(NamingContext.java:669)
            at org.jnp.interfaces.NamingContext.lookup(NamingContext.java:629)
            at javax.naming.InitialContext.lookup(InitialContext.java:351)
            at com.pearson.util.LookupUtil.lookupEJB(LookupUtil.java:59)
            at com.pearson.util.SWStartup.initApp(SWStartup.java:36)
            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.seam.util.Reflections.invoke(Reflections.java:21)
            at org.jboss.seam.util.Reflections.invokeAndWrap(Reflections.java:125)
            at org.jboss.seam.Component.callComponentMethod(Component.java:2092)
            at org.jboss.seam.Component.callPostConstructMethod(Component.java:2039)
            at org.jboss.seam.intercept.JavaBeanInterceptor$1.proceed(JavaBeanInterceptor.java:126)
            at org.jboss.seam.intercept.SeamInvocationContext.proceed(SeamInvocationContext.java:56)
            at org.jboss.seam.intercept.Interceptor.postConstruct(Interceptor.java:184)
            at org.jboss.seam.intercept.SeamInvocationContext.proceed(SeamInvocationContext.java:74)
            at org.jboss.seam.intercept.Interceptor.postConstruct(Interceptor.java:184)
            at org.jboss.seam.intercept.SeamInvocationContext.proceed(SeamInvocationContext.java:74)
            at org.jboss.seam.intercept.RootInterceptor.invoke(RootInterceptor.java:107)
            at org.jboss.seam.intercept.RootInterceptor.invokeAndHandle(RootInterceptor.java:84)
            at org.jboss.seam.intercept.JavaBeanInterceptor.callPostConstruct(JavaBeanInterceptor.java:131)
            at org.jboss.seam.intercept.JavaBeanInterceptor.postConstruct(JavaBeanInterceptor.java:116)
            at org.jboss.seam.Component.instantiateJavaBean(Component.java:1325)
            at org.jboss.seam.Component.instantiate(Component.java:1271)
            at org.jboss.seam.Component.newInstance(Component.java:1970)
            at org.jboss.seam.contexts.Contexts.startup(Contexts.java:304)
            at org.jboss.seam.contexts.Contexts.startup(Contexts.java:278)
            at org.jboss.seam.contexts.ServletLifecycle.endInitialization(ServletLifecycle.java:95)
            at org.jboss.seam.init.Initialization.init(Initialization.java:596)
            at org.jboss.seam.servlet.SeamListener.contextInitialized(SeamListener.java:34)
            at org.apache.catalina.core.StandardContext.listenerStart(StandardContext.java:3862)
            at org.apache.catalina.core.StandardContext.start(StandardContext.java:4356)
            at org.jboss.web.tomcat.service.deployers.TomcatDeployment.performDeployInternal(TomcatDeployment.java:346)
            at org.jboss.web.tomcat.service.deployers.TomcatDeployment.performDeploy(TomcatDeployment.java:139)
            at org.jboss.web.deployers.AbstractWarDeployment.start(AbstractWarDeployment.java:431)
            at org.jboss.web.deployers.WebModule.startModule(WebModule.java:112)
            at org.jboss.web.deployers.WebModule.start(WebModule.java:90)


            The lookup seems to be happening when the web application (WAR) is being deployed and probably before the EJB is deployed and bound to JNDI. Looks like a deployment ordering issue. Is this EJB and WAR part of an EAR? If yes, then the deployment order is determined by the order in which the "module" are mentioned in the application.xml. What does your application.xml look like? And do you have a jboss-app.xml?

            While posting the logs or code or xml content, remember to wrap it in a code block using the Code button in the message editor window and please hit the Preview button to make sure your post is correctly formatted


            • 3. Re: Session bean lookup fails JBoss 5, worked in 4.0.5
              lpmon

              Thanks for the reply. When I read it I thought this may be the answer. But, it was not. I have since realized this additional info is relevant:

              It is an ear deployment that contains my jar and war file (now listed in that order in application.xml)

              The war file contains no classes, all are in the jar. There is a Seam class that is annotated with @Startup and it tries to lookup the SLSB which fails. I can clearly see in the log that the SLSB is successfully installed before the problematic Seam component load starts. In the log output the jndi name is what I expect. I am wondering if I need to move some classes into the war.

              • 4. Re: Session bean lookup fails JBoss 5, worked in 4.0.5
                lpmon

                One more thing: The attempted SLSB lookup that is failing is in the @PostContruct method of the Seam class that is an @Startup class.

                But, all indications are the SLSB it is trying to lookup is already loaded and should be found with the jndi name I am using.

                • 5. Re: Session bean lookup fails JBoss 5, worked in 4.0.5
                  jaikiran

                   

                  "lpmon" wrote:
                  One more thing: The attempted SLSB lookup that is failing is in the @PostContruct method of the Seam class that is an @Startup class.


                  I don't have any experience on Seam, so i can't say whether this issue is related to Seam.

                  "lpmon" wrote:

                  But, all indications are the SLSB it is trying to lookup is already loaded and should be found with the jndi name I am using.


                  Logs please :-) Preferably the server.log contents.

                  Also please post the jndi-tree contents (follow the steps mentioned here http://wiki.jboss.org/wiki/DisplayTheJNDITreeWithTheJMXConsole)

                  • 6. Re: Session bean lookup fails JBoss 5, worked in 4.0.5
                    lpmon

                    I have included excerpts from the log and JNDIView. I am still stuck on this problem.

                    I notice that:

                    1) The "added bean" message for DBUtilBean is 1st (with expected jndi name)
                    2) The Seam "welcome" message is next
                    3) The failed lookup is next (this is now in a servlet)
                    4) then I see: STARTED EJB: com.pearson.ejb3.session.DBUtilBean

                    If the SLSB must be started before it can be looked up then that explains the behavior. But, I do not know how to force ordering.

                    I have tried changing the order of the modules in application.xml with no success.

                    IMPORTANT: By changing from an @Startup Seam component to a startup servlet I was able to get my .jar and .war to deploy successfully. The startup method, servlet.init(), still fails when trying to lookup DBUtilBean but this change allows me to get the app up and running. Subsequent lookups using the same jndi name are successful! It looks like the jndi lookups fail unless the entire bootup process has totally completed. It is not a matter of using the correct jndi name or the latter lookup would fail too.


                    2008-07-21 14:49:27,741 INFO [org.jboss.ejb3.deployers.JBossASKernel] (main) Created KernelDeployment for: ec20.jar
                    ....
                    2008-07-21 14:49:29,007 INFO [org.jboss.ejb3.deployers.JBossASKernel] (main) installing bean: jboss.j2ee:ear=ec20.ear,jar=ec20.jar,name=DBUtilBean,service=EJB3
                    2008-07-21 14:49:29,007 INFO [org.jboss.ejb3.deployers.JBossASKernel] (main) with dependencies:
                    2008-07-21 14:49:29,007 INFO [org.jboss.ejb3.deployers.JBossASKernel] (main) and demands:
                    2008-07-21 14:49:29,007 INFO [org.jboss.ejb3.deployers.JBossASKernel] (main) persistence.units:ear=ec20.ear,jar=ec20.jar,unitName=ec20
                    2008-07-21 14:49:29,022 INFO [org.jboss.ejb3.deployers.JBossASKernel] (main) jboss.ejb:service=EJBTimerService
                    2008-07-21 14:49:29,038 INFO [org.jboss.ejb3.deployers.JBossASKernel] (main) and supplies:
                    2008-07-21 14:49:29,038 INFO [org.jboss.ejb3.deployers.JBossASKernel] (main) jndi:ec20/DBUtilBean/local-com.pearson.ejb3.session.DBUtil
                    2008-07-21 14:49:29,038 INFO [org.jboss.ejb3.deployers.JBossASKernel] (main) jndi:ec20/DBUtilBean/local
                    2008-07-21 14:49:29,038 INFO [org.jboss.ejb3.deployers.JBossASKernel] (main) Class:com.pearson.ejb3.session.DBUtil
                    2008-07-21 14:49:29,038 INFO [org.jboss.ejb3.deployers.JBossASKernel] (main) jndi:DBUtilBean
                    2008-07-21 14:49:29,038 INFO [org.jboss.ejb3.deployers.JBossASKernel] (main) Added bean(jboss.j2ee:ear=ec20.ear,jar=ec20.jar,name=DBUtilBean,service=EJB3) to KernelDeploym
                    ....
                    07-21 14:49:52,538 INFO [javax.servlet.ServletContextListener] (main) Welcome to Seam 2.0.3.CR1
                    ....
                    2008-07-21 14:50:29,507 FATAL [com.pearson.util.LookupUtil] (main) for Interface: DBUtilBean context is null: false (my logging for the exception)
                    javax.naming.NameNotFoundException: ec20 not bound
                    at org.jnp.server.NamingServer.getBinding(NamingServer.java:542)
                    at org.jnp.server.NamingServer.getBinding(NamingServer.java:550)
                    at org.jnp.server.NamingServer.getObject(NamingServer.java:556)
                    at org.jnp.server.NamingServer.lookup(NamingServer.java:267)
                    at org.jnp.interfaces.NamingContext.lookup(NamingContext.java:669)
                    at org.jnp.interfaces.NamingContext.lookup(NamingContext.java:629)
                    at javax.naming.InitialContext.lookup(InitialContext.java:351)
                    at com.pearson.util.LookupUtil.lookupEJB(LookupUtil.java:61)
                    at com.pearson.servlet.StartupServlet.init(StartupServlet.java:51)


                    2008-07-21 14:51:17,851 INFO [org.jboss.ejb3.EJBContainer] (main) STARTED EJB: com.pearson.ejb3.session.DBUtilBean ejbName: DBUtilBean


                    Global JNDI Namespace

                    ...
                    +- ec20 (class: org.jnp.interfaces.NamingContext)
                    | +- IButtonMgrBean (class: org.jnp.interfaces.NamingContext)
                    | | +- local (proxy: $Proxy217 implements interface org.jboss.ejb3.proxy.JBossProxy,interface com.pearson.ejb3.session.IButtonMgr)
                    | +- DBUtilBean (class: org.jnp.interfaces.NamingContext)
                    | | +- local (proxy: $Proxy186 implements interface org.jboss.ejb3.proxy.JBossProxy,interface com.pearson.ejb3.session.DBUtil)

                    • 7. Re: Session bean lookup fails JBoss 5, worked in 4.0.5
                      jaikiran

                       

                      2008-07-21 14:51:17,851 INFO [org.jboss.ejb3.EJBContainer] (main) STARTED EJB: com.pearson.ejb3.session.DBUtilBean ejbName: DBUtilBean


                      This is the point where the JNDI binding happens. You can confirm that in the server.log file, which will contain something like:
                      2008-07-01 14:38:01,359 INFO [org.jboss.ejb3.EJBContainer] (main) STARTED EJB: org.myapp.ejb.impl.UserManagerBean ejbName: UserManagerBean
                       2008-07-01 14:38:01,437 DEBUG [org.jboss.ejb3.proxy.factory.ProxyFactoryHelper] (main) Obtaining JNDI name from policy org.jboss.ejb3.jndipolicy.impl.PackagingBasedJndiBindingPolicy
                      2008-07-01 14:38:01,437 DEBUG [org.jboss.ejb3.proxy.factory.ProxyFactoryHelper] (main) Obtaining JNDI name from policy org.jboss.ejb3.jndipolicy.impl.PackagingBasedJndiBindingPolicy
                      2008-07-01 14:38:01,515 DEBUG [org.jboss.ejb3.proxy.factory.BaseSessionProxyFactory] (main) Binding proxy for UserManagerBean in JNDI at RemoteUserManagerBean


                      So based on your logs, it looks like a deployment ordering issue.

                      IMPORTANT: By changing from an @Startup Seam component to a startup servlet I was able to get my .jar and .war to deploy successfully.


                      You mean, no error messages and JBoss boots fine?

                      The startup method, servlet.init(), still fails when trying to lookup DBUtilBean


                      With what exception? Same as the previous? Can you post those logs too? Also, if the servlet.init() fails, then the deployment too should fail, which seems to contradict your previous statement.

                      Based on what you have explained so far, this appears to be deployment ordering issue (with Seam?) in 5.0 CR1. I have seen some other user too asking about the deployment ordering based on the contents of application.xml. Let me try out a sample application (without Seam) and see if there are any issues with 5.0 CR1.


                      • 8. Re: Session bean lookup fails JBoss 5, worked in 4.0.5
                        lpmon

                        jaikiran, Thanks for your help on this. I was also convinced it is an ordering issue. The key point here is that @Startup and a servlet configured with load-on-startup appears to load the class and execute the startup method before the jndi binding has completed.
                        To clarify: when using @Startup with an @PostConstruct method the Seam component startup method failed which caused the module deployment to fail. When I moved the startup code to a startup servlet the lookup failure did not cause the module deployment to fail. FYI: I was not catching this exception.
                        The exception is the same in either scenario (NameNotFoundException).

                        IMO startup code should not be executed until EJBs are loaded/bound. Otherwise we cannot use EJBs in startup code. Any session beans anotated with @Startup it should be loaded after those that are not @Startup.

                        Log:

                        2008-07-21 15:53:11,689 INFO [org.jboss.ejb3.deployers.JBossASKernel] (main) Created KernelDeployment for: ec20.jar
                        2008-07-21 15:53:11,736 INFO [org.jboss.ejb3.deployers.JBossASKernel] (main) installing bean: persistence.units:ear=ec20.ear,jar=ec20.jar,unitName=ec20
                        2008-07-21 15:53:11,736 INFO [org.jboss.ejb3.deployers.JBossASKernel] (main) with dependencies:
                        2008-07-21 15:53:11,736 INFO [org.jboss.ejb3.deployers.JBossASKernel] (main) and demands:
                        2008-07-21 15:53:11,736 INFO [org.jboss.ejb3.deployers.JBossASKernel] (main) jboss.jca:name=ec20Datasource,service=DataSourceBinding
                        2008-07-21 15:53:11,736 INFO [org.jboss.ejb3.deployers.JBossASKernel] (main) and supplies:
                        2008-07-21 15:53:11,736 INFO [org.jboss.ejb3.deployers.JBossASKernel] (main) persistence.units:unitName=ec20
                        2008-07-21 15:53:11,736 INFO [org.jboss.ejb3.deployers.JBossASKernel] (main) Added bean(persistence.units:ear=ec20.ear,jar=ec20.jar,unitName=ec20) to KernelDeployment of: ec20.jar
                        2008-07-21 15:53:12,908 INFO [org.jboss.ejb3.deployers.JBossASKernel] (main) installing bean: jboss.j2ee:ear=ec20.ear,jar=ec20.jar,name=DBUtilBean,service=EJB3
                        2008-07-21 15:53:12,908 INFO [org.jboss.ejb3.deployers.JBossASKernel] (main) with dependencies:
                        2008-07-21 15:53:12,908 INFO [org.jboss.ejb3.deployers.JBossASKernel] (main) and demands:
                        2008-07-21 15:53:12,908 INFO [org.jboss.ejb3.deployers.JBossASKernel] (main) persistence.units:ear=ec20.ear,jar=ec20.jar,unitName=ec20
                        2008-07-21 15:53:12,908 INFO [org.jboss.ejb3.deployers.JBossASKernel] (main) jboss.ejb:service=EJBTimerService
                        2008-07-21 15:53:12,908 INFO [org.jboss.ejb3.deployers.JBossASKernel] (main) and supplies:
                        2008-07-21 15:53:12,908 INFO [org.jboss.ejb3.deployers.JBossASKernel] (main) jndi:ec20/DBUtilBean/local-com.pearson.ejb3.session.DBUtil
                        2008-07-21 15:53:12,908 INFO [org.jboss.ejb3.deployers.JBossASKernel] (main) jndi:ec20/DBUtilBean/local
                        2008-07-21 15:53:12,908 INFO [org.jboss.ejb3.deployers.JBossASKernel] (main) Class:com.pearson.ejb3.session.DBUtil
                        2008-07-21 15:53:12,908 INFO [org.jboss.ejb3.deployers.JBossASKernel] (main) jndi:DBUtilBean
                        2008-07-21 15:53:12,908 INFO [org.jboss.ejb3.deployers.JBossASKernel] (main) Added bean(jboss.j2ee:ear=ec20.ear,jar=ec20.jar,name=DBUtilBean,service=EJB3) to KernelDeployment of: ec20.jar

                        .. other beans ommited to reduce file size

                        2008-07-21 15:53:13,174 INFO [STDOUT] (main) ======> Creating interceptor metadata bridge
                        2008-07-21 15:53:13,424 WARN [org.jboss.ejb3.interceptors.aop.InjectInterceptorsFactory] (main) WEIRDNESS IN AOP: advisor org.jboss.ejb3.aop.ExtendedManagedObjectAdvisor@9bbe3e
                        2008-07-21 15:53:13,471 WARN [org.jboss.ejb3.interceptors.aop.InjectInterceptorsFactory] (main) WEIRDNESS IN AOP: advisor org.jboss.ejb3.aop.ExtendedManagedObjectAdvisor@9bbe3e
                        2008-07-21 15:53:13,517 WARN [org.jboss.ejb3.interceptors.aop.InjectInterceptorsFactory] (main) WEIRDNESS IN AOP: advisor org.jboss.ejb3.aop.ExtendedManagedObjectAdvisor@9bbe3e
                        2008-07-21 15:53:13,517 WARN [org.jboss.ejb3.interceptors.aop.InjectInterceptorsFactory] (main) WEIRDNESS IN AOP: advisor org.jboss.ejb3.aop.ExtendedManagedObjectAdvisor@9bbe3e
                        2008-07-21 15:53:13,767 INFO [STDOUT] (main) ======> Creating interceptor metadata bridge
                        2008-07-21 15:53:14,642 INFO [org.jboss.ejb3.deployers.JBossASKernel] (main) Created KernelDeployment for: jboss-seam.jar
                        2008-07-21 15:53:14,658 INFO [org.jboss.ejb3.deployers.JBossASKernel] (main) installing bean: jboss.j2ee:ear=ec20.ear,jar=jboss-seam.jar,name=TimerServiceDispatcher,service=EJB3
                        2008-07-21 15:53:14,658 INFO [org.jboss.ejb3.deployers.JBossASKernel] (main) with dependencies:
                        2008-07-21 15:53:14,658 INFO [org.jboss.ejb3.deployers.JBossASKernel] (main) and demands:
                        2008-07-21 15:53:14,658 INFO [org.jboss.ejb3.deployers.JBossASKernel] (main) jboss.ejb:service=EJBTimerService
                        2008-07-21 15:53:14,658 INFO [org.jboss.ejb3.deployers.JBossASKernel] (main) and supplies:
                        2008-07-21 15:53:14,658 INFO [org.jboss.ejb3.deployers.JBossASKernel] (main) jndi:TimerServiceDispatcher
                        2008-07-21 15:53:14,658 INFO [org.jboss.ejb3.deployers.JBossASKernel] (main) jndi:ec20/TimerServiceDispatcher/local
                        2008-07-21 15:53:14,658 INFO [org.jboss.ejb3.deployers.JBossASKernel] (main) Class:org.jboss.seam.async.LocalTimerServiceDispatcher
                        2008-07-21 15:53:14,674 INFO [org.jboss.ejb3.deployers.JBossASKernel] (main) jndi:ec20/TimerServiceDispatcher/local-org.jboss.seam.async.LocalTimerServiceDispatcher
                        2008-07-21 15:53:14,674 INFO [org.jboss.ejb3.deployers.JBossASKernel] (main) Added bean(jboss.j2ee:ear=ec20.ear,jar=jboss-seam.jar,name=TimerServiceDispatcher,service=EJB3) to KernelDeployment of: jboss-seam.jar
                        2008-07-21 15:53:14,689 INFO [org.jboss.ejb3.deployers.JBossASKernel] (main) installing bean: jboss.j2ee:ear=ec20.ear,jar=jboss-seam.jar,name=EjbSynchronizations,service=EJB3
                        2008-07-21 15:53:14,689 INFO [org.jboss.ejb3.deployers.JBossASKernel] (main) with dependencies:
                        2008-07-21 15:53:14,689 INFO [org.jboss.ejb3.deployers.JBossASKernel] (main) and demands:
                        2008-07-21 15:53:14,689 INFO [org.jboss.ejb3.deployers.JBossASKernel] (main) jboss.ejb:service=EJBTimerService
                        2008-07-21 15:53:14,689 INFO [org.jboss.ejb3.deployers.JBossASKernel] (main) and supplies:
                        2008-07-21 15:53:14,689 INFO [org.jboss.ejb3.deployers.JBossASKernel] (main) jndi:ec20/EjbSynchronizations/local
                        2008-07-21 15:53:14,689 INFO [org.jboss.ejb3.deployers.JBossASKernel] (main) Class:org.jboss.seam.transaction.LocalEjbSynchronizations
                        2008-07-21 15:53:14,689 INFO [org.jboss.ejb3.deployers.JBossASKernel] (main) jndi:ec20/EjbSynchronizations/local-org.jboss.seam.transaction.LocalEjbSynchronizations
                        2008-07-21 15:53:14,689 INFO [org.jboss.ejb3.deployers.JBossASKernel] (main) jndi:EjbSynchronizations
                        2008-07-21 15:53:14,689 INFO [org.jboss.ejb3.deployers.JBossASKernel] (main) Added bean(jboss.j2ee:ear=ec20.ear,jar=jboss-seam.jar,name=EjbSynchronizations,service=EJB3) to KernelDeployment of: jboss-seam.jar
                        2008-07-21 15:53:16,189 INFO [org.jboss.wsf.stack.jbws.NativeServerConfig] (main) JBoss Web Services - Stack Native Core
                        2008-07-21 15:53:16,189 INFO [org.jboss.wsf.stack.jbws.NativeServerConfig] (main) 3.0.2.GA
                        2008-07-21 15:53:25,314 INFO [org.jboss.web.tomcat.service.deployers.TomcatDeployment] (main) deploy, ctxPath=/invoker, vfsUrl=http-invoker.sar/invoker.war
                        2008-07-21 15:53:26,596 INFO [org.jboss.web.tomcat.service.deployers.TomcatDeployment] (main) deploy, ctxPath=/pearson, vfsUrl=ec20.ear/ec20.war
                        2008-07-21 15:53:33,486 INFO [javax.servlet.ServletContextListener] (main) Welcome to Seam 2.0.3.CR1
                        2008-07-21 15:54:06,033 INFO [org.jboss.seam.init.Initialization] (main) Namespace: http://jboss.com/products/seam/security, package: org.jboss.seam.security, prefix: org.jboss.seam.security
                        2008-07-21 15:54:06,111 INFO [org.jboss.seam.init.Initialization] (main) Namespace: http://jboss.com/products/seam/international, package: org.jboss.seam.international, prefix: org.jboss.seam.international
                        2008-07-21 15:54:06,111 INFO [org.jboss.seam.init.Initialization] (main) Namespace: http://jboss.com/products/seam/persistence, package: org.jboss.seam.persistence, prefix: org.jboss.seam.persistence
                        2008-07-21 15:54:06,111 INFO [org.jboss.seam.init.Initialization] (main) Namespace: http://jboss.com/products/seam/remoting, package: org.jboss.seam.remoting, prefix: org.jboss.seam.remoting
                        2008-07-21 15:54:06,111 INFO [org.jboss.seam.init.Initialization] (main) Namespace: http://jboss.com/products/seam/core, package: org.jboss.seam.core, prefix: org.jboss.seam.core
                        2008-07-21 15:54:06,111 INFO [org.jboss.seam.init.Initialization] (main) Namespace: http://jboss.com/products/seam/captcha, package: org.jboss.seam.captcha, prefix: org.jboss.seam.captcha
                        2008-07-21 15:54:06,111 INFO [org.jboss.seam.init.Initialization] (main) Namespace: http://jboss.com/products/seam/async, package: org.jboss.seam.async, prefix: org.jboss.seam.async
                        2008-07-21 15:54:06,111 INFO [org.jboss.seam.init.Initialization] (main) Namespace: http://jboss.com/products/seam/drools, package: org.jboss.seam.drools, prefix: org.jboss.seam.drools
                        2008-07-21 15:54:06,111 INFO [org.jboss.seam.init.Initialization] (main) Namespace: http://jboss.com/products/seam/spring, package: org.jboss.seam.ioc.spring, prefix: org.jboss.seam.ioc.spring
                        2008-07-21 15:54:06,111 INFO [org.jboss.seam.init.Initialization] (main) Namespace: http://jboss.com/products/seam/pdf, package: org.jboss.seam.pdf, prefix:
                        2008-07-21 15:54:06,111 INFO [org.jboss.seam.init.Initialization] (main) Namespace: http://jboss.com/products/seam/mail, package: org.jboss.seam.mail, prefix: org.jboss.seam.mail
                        2008-07-21 15:54:06,111 INFO [org.jboss.seam.init.Initialization] (main) Namespace: http://jboss.com/products/seam/transaction, package: org.jboss.seam.transaction, prefix: org.jboss.seam.transaction
                        2008-07-21 15:54:06,111 INFO [org.jboss.seam.init.Initialization] (main) Namespace: http://jboss.com/products/seam/web, package: org.jboss.seam.web, prefix: org.jboss.seam.web
                        2008-07-21 15:54:06,111 INFO [org.jboss.seam.init.Initialization] (main) Namespace: http://jboss.com/products/seam/theme, package: org.jboss.seam.theme, prefix: org.jboss.seam.theme
                        2008-07-21 15:54:06,111 INFO [org.jboss.seam.init.Initialization] (main) Namespace: http://jboss.com/products/seam/navigation, package: org.jboss.seam.navigation, prefix: org.jboss.seam.navigation
                        2008-07-21 15:54:06,111 INFO [org.jboss.seam.init.Initialization] (main) Namespace: http://jboss.com/products/seam/bpm, package: org.jboss.seam.bpm, prefix: org.jboss.seam.bpm
                        2008-07-21 15:54:06,111 INFO [org.jboss.seam.init.Initialization] (main) Namespace: http://jboss.com/products/seam/framework, package: org.jboss.seam.framework, prefix: org.jboss.seam.core.framework
                        2008-07-21 15:54:06,111 INFO [org.jboss.seam.init.Initialization] (main) Namespace: http://jboss.com/products/seam/jms, package: org.jboss.seam.jms, prefix: org.jboss.seam.jms
                        2008-07-21 15:54:06,127 INFO [org.jboss.seam.init.Initialization] (main) reading /WEB-INF/components.xml
                        2008-07-21 15:54:06,236 INFO [org.jboss.seam.init.Initialization] (main) reading vfszip:/C:/app/jboss-5.0.0.CR1/server/default/deploy/ec20.ear/ec20.war/WEB-INF/lib/jboss-seam-remoting.jar/META-INF/components.xml
                        2008-07-21 15:54:06,252 INFO [org.jboss.seam.init.Initialization] (main) reading vfszip:/C:/app/jboss-5.0.0.CR1/server/default/deploy/ec20.ear/ec20.war/WEB-INF/lib/jboss-seam-ui.jar/META-INF/components.xml
                        2008-07-21 15:54:06,252 INFO [org.jboss.seam.init.Initialization] (main) reading vfszip:/C:/app/jboss-5.0.0.CR1/server/default/deploy/ec20.ear/jboss-seam.jar/META-INF/components.xml
                        2008-07-21 15:54:06,267 INFO [org.jboss.seam.init.Initialization] (main) reading vfszip:/C:/app/jboss-5.0.0.CR1/server/default/deploy/ec20.ear/ec20.war/WEB-INF/lib/jboss-seam-ioc.jar/META-INF/components.xml
                        2008-07-21 15:54:06,267 INFO [org.jboss.seam.init.Initialization] (main) reading properties from: /seam.properties
                        2008-07-21 15:54:06,283 INFO [org.jboss.seam.init.Initialization] (main) reading properties from: /jndi.properties
                        2008-07-21 15:54:06,299 INFO [org.jboss.seam.init.Initialization] (main) initializing Seam
                        2008-07-21 15:54:06,314 INFO [org.jboss.seam.init.Initialization] (main) two components with same name, higher precedence wins: org.jboss.seam.transaction.synchronizations
                        2008-07-21 15:54:06,314 INFO [org.jboss.seam.init.Initialization] (main) two components with same name, higher precedence wins: org.jboss.seam.core.locale
                        2008-07-21 15:54:06,314 INFO [org.jboss.seam.init.Initialization] (main) two components with same name, higher precedence wins: org.jboss.seam.core.expressions
                        2008-07-21 15:54:06,314 INFO [org.jboss.seam.init.Initialization] (main) two components with same name, higher precedence wins: org.jboss.seam.web.parameters
                        2008-07-21 15:54:06,314 INFO [org.jboss.seam.init.Initialization] (main) two components with same name, higher precedence wins: org.jboss.seam.persistence.persistenceProvider
                        2008-07-21 15:54:06,314 INFO [org.jboss.seam.init.Initialization] (main) two components with same name, higher precedence wins: org.jboss.seam.core.resourceLoader
                        2008-07-21 15:54:06,314 INFO [org.jboss.seam.init.Initialization] (main) two components with same name, higher precedence wins: org.jboss.seam.web.isUserInRole
                        2008-07-21 15:54:06,314 INFO [org.jboss.seam.init.Initialization] (main) two components with same name, higher precedence wins: org.jboss.seam.security.entityPermissionChecker
                        2008-07-21 15:54:06,314 INFO [org.jboss.seam.init.Initialization] (main) two components with same name, higher precedence wins: org.jboss.seam.web.userPrincipal
                        2008-07-21 15:54:06,314 INFO [org.jboss.seam.init.Initialization] (main) two components with same name, higher precedence wins: org.jboss.seam.bpm.businessProcess
                        2008-07-21 15:54:06,330 INFO [org.jboss.seam.init.Initialization] (main) two components with same name, higher precedence wins: org.jboss.seam.security.identity
                        2008-07-21 15:54:06,330 INFO [org.jboss.seam.init.Initialization] (main) two components with same name, higher precedence wins: org.jboss.seam.core.locale
                        2008-07-21 15:54:06,330 INFO [org.jboss.seam.init.Initialization] (main) two components with same name, higher precedence wins: org.jboss.seam.core.manager
                        2008-07-21 15:54:06,549 INFO [org.jboss.seam.Component] (main) Component: org.jboss.seam.core.init, scope: APPLICATION, type: JAVA_BEAN, class: org.jboss.seam.core.Init
                        2008-07-21 15:54:06,564 INFO [org.jboss.seam.init.Initialization] (main) Installing components...
                        2008-07-21 15:54:06,627 INFO [org.jboss.seam.Component] (main) Component: DebugSupport, scope: SESSION, type: JAVA_BEAN, class: com.pearson.util.DebugSupport
                        2008-07-21 15:54:06,674 INFO [org.jboss.seam.Component] (main) Component: IButtonMgr, scope: CONVERSATION, type: STATELESS_SESSION_BEAN, class: com.pearson.ejb3.session.IButtonMgrBean, JNDI: ec20/IButtonMgrBean/local
                        2008-07-21 15:54:06,674 INFO [org.jboss.seam.Component] (main) Component: NetworkInfo, scope: EVENT, type: JAVA_BEAN, class: com.pearson.util.NetworkInfo
                        2008-07-21 15:54:06,689 INFO [org.jboss.seam.Component] (main) Component: OTASender, scope: CONVERSATION, type: STATELESS_SESSION_BEAN, class: com.pearson.ejb3.session.ota.OTASenderBean, JNDI: ec20/OTASenderBean/local
                        2008-07-21 15:54:06,705 INFO [org.jboss.seam.Component] (main) Component: Processor, scope: STATELESS, type: STATELESS_SESSION_BEAN, class: com.pearson.ejb3.session.util.ProcessorBean, JNDI: ec20/ProcessorBean/local
                        ....
                        ....
                        2008-07-21 15:54:08,736 INFO [org.jboss.seam.contexts.Contexts] (main) starting up: org.jboss.seam.navigation.pages
                        2008-07-21 15:54:08,752 INFO [org.jboss.seam.contexts.Contexts] (main) starting up: org.jboss.seam.security.facesSecurityEvents
                        2008-07-21 15:54:08,752 INFO [org.jboss.seam.contexts.Contexts] (main) starting up: swStartup
                        2008-07-21 15:54:08,877 ERROR [STDERR] (main) Error loading properties from-C:\app\jboss-5.0.0.CR1\server\default\properties\\pearson.properties C:\app\jboss-5.0.0.CR1\server\default\properties\pearson.properties (The system cannot find the path specified)
                        2008-07-21 15:54:08,877 INFO [STDOUT] (main) Using default property URL: pearson.properties
                        2008-07-21 15:54:08,924 ERROR [STDERR] (main) Error loading properties from - pearson.properties null
                        2008-07-21 15:54:08,924 INFO [STDOUT] (main) Could not load properties
                        2008-07-21 15:54:08,924 WARN [com.pearson] (main) sw.Server.Version = 2.0.1
                        2008-07-21 15:54:08,924 WARN [com.pearson] (main) sw.property.dir = C:\app\jboss-5.0.0.CR1\server\default\properties\
                        2008-07-21 15:54:08,939 INFO [org.jboss.seam.init.Initialization] (main) done initializing Seam
                        2008-07-21 15:54:08,939 INFO [org.jboss.seam.servlet.SeamFilter] (main) Initializing filter: org.jboss.seam.web.loggingFilter
                        2008-07-21 15:54:08,939 INFO [org.jboss.seam.servlet.SeamFilter] (main) Initializing filter: org.jboss.seam.web.ajax4jsfFilter
                        2008-07-21 15:54:09,033 INFO [org.ajax4jsf.cache.CacheManager] (main) Selected [org.ajax4jsf.cache.LRUMapCacheFactory] cache factory
                        2008-07-21 15:54:09,033 INFO [org.ajax4jsf.cache.LRUMapCacheFactory] (main) Creating LRUMap cache instance using parameters: {com.sun.faces.injectionProvider=org.jboss.web.jsf.integration.injection.JBossInjectionProvider, facelets.DEVELOPMENT=true, org.richfaces.SKIN=DEFAULT, javax.faces.DEFAULT_SUFFIX=.xhtml}
                        2008-07-21 15:54:09,033 INFO [org.ajax4jsf.cache.LRUMapCacheFactory] (main) Creating LRUMap cache instance of default capacity
                        2008-07-21 15:54:09,111 INFO [org.ajax4jsf.cache.CacheManager] (main) Selected [org.ajax4jsf.cache.LRUMapCacheFactory] cache factory
                        2008-07-21 15:54:09,111 INFO [org.ajax4jsf.cache.LRUMapCacheFactory] (main) Creating LRUMap cache instance using parameters: {com.sun.faces.injectionProvider=org.jboss.web.jsf.integration.injection.JBossInjectionProvider, facelets.DEVELOPMENT=true, org.richfaces.SKIN=DEFAULT, javax.faces.DEFAULT_SUFFIX=.xhtml}
                        2008-07-21 15:54:09,127 INFO [org.ajax4jsf.cache.LRUMapCacheFactory] (main) Creating LRUMap cache instance of default capacity
                        2008-07-21 15:54:09,127 INFO [org.jboss.seam.servlet.SeamFilter] (main) Initializing filter: org.jboss.seam.web.redirectFilter
                        2008-07-21 15:54:09,142 INFO [org.jboss.seam.servlet.SeamFilter] (main) Initializing filter: org.jboss.seam.web.exceptionFilter
                        2008-07-21 15:54:09,142 INFO [org.jboss.seam.servlet.SeamFilter] (main) Initializing filter: org.jboss.seam.web.multipartFilter
                        2008-07-21 15:54:09,142 INFO [org.jboss.seam.servlet.SeamFilter] (main) Initializing filter: org.jboss.seam.debug.hotDeployFilter
                        2008-07-21 15:54:09,142 FATAL [com.pearson.util.LookupUtil] (main) for Interface: DBUtilBean context is null: false
                        javax.naming.NameNotFoundException: ec20 not bound
                        at org.jnp.server.NamingServer.getBinding(NamingServer.java:542)
                        at org.jnp.server.NamingServer.getBinding(NamingServer.java:550)
                        at org.jnp.server.NamingServer.getObject(NamingServer.java:556)
                        at org.jnp.server.NamingServer.lookup(NamingServer.java:267)
                        at org.jnp.interfaces.NamingContext.lookup(NamingContext.java:669)
                        at org.jnp.interfaces.NamingContext.lookup(NamingContext.java:629)
                        at javax.naming.InitialContext.lookup(InitialContext.java:351)
                        at com.pearson.util.LookupUtil.lookupEJB(LookupUtil.java:61)
                        at com.pearson.servlet.StartupServlet.init(StartupServlet.java:51)
                        at org.apache.catalina.core.StandardWrapper.loadServlet(StandardWrapper.java:1048)
                        at org.apache.catalina.core.StandardWrapper.load(StandardWrapper.java:950)
                        at org.apache.catalina.core.StandardContext.loadOnStartup(StandardContext.java:4074)
                        at org.apache.catalina.core.StandardContext.start(StandardContext.java:4384)
                        at org.jboss.web.tomcat.service.deployers.TomcatDeployment.performDeployInternal(TomcatDeployment.java:346)
                        at org.jboss.web.tomcat.service.deployers.TomcatDeployment.performDeploy(TomcatDeployment.java:139)
                        at org.jboss.web.deployers.AbstractWarDeployment.start(AbstractWarDeployment.java:431)
                        at org.jboss.web.deployers.WebModule.startModule(WebModule.java:112)
                        at org.jboss.web.deployers.WebModule.start(WebModule.java:90)
                        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:157)
                        at org.jboss.mx.server.Invocation.dispatch(Invocation.java:96)
                        at org.jboss.mx.server.Invocation.invoke(Invocation.java:88)
                        at org.jboss.mx.server.AbstractMBeanInvoker.invoke(AbstractMBeanInvoker.java:264)
                        at org.jboss.mx.server.MBeanServerImpl.invoke(MBeanServerImpl.java:668)
                        at org.jboss.system.microcontainer.ServiceProxy.invoke(ServiceProxy.java:206)
                        at $Proxy35.start(Unknown Source)
                        at org.jboss.system.microcontainer.StartStopLifecycleAction.installAction(StartStopLifecycleAction.java:42)
                        at org.jboss.system.microcontainer.StartStopLifecycleAction.installAction(StartStopLifecycleAction.java:37)
                        at org.jboss.dependency.plugins.action.SimpleControllerContextAction.simpleInstallAction(SimpleControllerContextAction.java:62)
                        at org.jboss.dependency.plugins.action.AccessControllerContextAction.install(AccessControllerContextAction.java:71)
                        at org.jboss.dependency.plugins.AbstractControllerContextActions.install(AbstractControllerContextActions.java:51)
                        at org.jboss.dependency.plugins.AbstractControllerContext.install(AbstractControllerContext.java:348)
                        at org.jboss.system.microcontainer.ServiceControllerContext.install(ServiceControllerContext.java:271)
                        at org.jboss.dependency.plugins.AbstractController.install(AbstractController.java:1392)
                        at org.jboss.dependency.plugins.AbstractController.incrementState(AbstractController.java:784)
                        at org.jboss.dependency.plugins.AbstractController.resolveContexts(AbstractController.java:912)
                        at org.jboss.dependency.plugins.AbstractController.resolveContexts(AbstractController.java:834)
                        at org.jboss.dependency.plugins.AbstractController.install(AbstractController.java:624)
                        at org.jboss.dependency.plugins.AbstractController.install(AbstractController.java:442)
                        at org.jboss.deployers.vfs.deployer.kernel.BeanMetaDataDeployer.deploy(BeanMetaDataDeployer.java:88)
                        at org.jboss.deployers.vfs.deployer.kernel.BeanMetaDataDeployer.deploy(BeanMetaDataDeployer.java:46)
                        at org.jboss.deployers.spi.deployer.helpers.AbstractSimpleRealDeployer.internalDeploy(AbstractSimpleRealDeployer.java:62)
                        at org.jboss.deployers.spi.deployer.helpers.AbstractRealDeployer.deploy(AbstractRealDeployer.java:50)
                        at org.jboss.deployers.plugins.deployers.DeployerWrapper.deploy(DeployerWrapper.java:174)
                        at org.jboss.deployers.plugins.deployers.DeployersImpl.doInstallParentFirst(DeployersImpl.java:970)
                        at org.jboss.deployers.plugins.deployers.DeployersImpl.doInstallParentFirst(DeployersImpl.java:991)
                        at org.jboss.deployers.plugins.deployers.DeployersImpl.install(DeployersImpl.java:911)
                        at org.jboss.dependency.plugins.AbstractControllerContext.install(AbstractControllerContext.java:348)
                        at org.jboss.dependency.plugins.AbstractController.install(AbstractController.java:1392)
                        at org.jboss.dependency.plugins.AbstractController.incrementState(AbstractController.java:784)
                        at org.jboss.dependency.plugins.AbstractController.resolveContexts(AbstractController.java:912)
                        at org.jboss.dependency.plugins.AbstractController.resolveContexts(AbstractController.java:834)
                        at org.jboss.dependency.plugins.AbstractController.change(AbstractController.java:672)
                        at org.jboss.dependency.plugins.AbstractController.change(AbstractController.java:455)
                        at org.jboss.deployers.plugins.deployers.DeployersImpl.process(DeployersImpl.java:594)
                        at org.jboss.deployers.plugins.main.MainDeployerImpl.process(MainDeployerImpl.java:541)
                        at org.jboss.system.server.profileservice.ProfileServiceBootstrap.loadProfile(ProfileServiceBootstrap.java:257)
                        at org.jboss.system.server.profileservice.ProfileServiceBootstrap.start(ProfileServiceBootstrap.java:135)
                        at org.jboss.bootstrap.AbstractServerImpl.start(AbstractServerImpl.java:409)
                        at org.jboss.Main.boot(Main.java:209)
                        at org.jboss.Main$1.run(Main.java:544)
                        at java.lang.Thread.run(Thread.java:595)
                        .....
                        .....
                        2008-07-21 15:54:09,205 INFO [org.jboss.web.tomcat.service.deployers.TomcatDeployment] (main) deploy, ctxPath=/, vfsUrl=ROOT.war
                        2008-07-21 15:54:09,299 INFO [org.jboss.web.tomcat.service.deployers.TomcatDeployment] (main) deploy, ctxPath=/jbossws, vfsUrl=jbossws.sar/jbossws-management.war
                        2008-07-21 15:54:09,533 INFO [org.jboss.web.tomcat.service.deployers.TomcatDeployment] (main) deploy, ctxPath=/jmx-console, vfsUrl=jmx-console.war
                        2008-07-21 15:54:09,674 INFO [org.jboss.web.tomcat.service.deployers.TomcatDeployment] (main) deploy, ctxPath=/web-console, vfsUrl=management/console-mgr.sar/web-console.war
                        2008-07-21 15:54:11,221 INFO [org.jboss.resource.connectionmanager.ConnectionFactoryBindingService] (main) Bound ConnectionManager 'jboss.jca:service=DataSourceBinding,name=ec20Datasource' to JNDI name 'java:ec20Datasource'
                        2008-07-21 15:54:11,252 INFO [org.jboss.resource.connectionmanager.ConnectionFactoryBindingService] (main) Bound ConnectionManager 'jboss.jca:service=DataSourceBinding,name=DefaultDS' to JNDI name 'java:DefaultDS'
                        ......
                        2008-07-21 15:54:15,377 INFO [org.jboss.jms.server.ServerPeer] (main) JBoss Messaging 1.4.1.CR1 server [0] started
                        2008-07-21 15:54:15,455 INFO [org.jboss.resource.connectionmanager.ConnectionFactoryBindingService] (main) Bound ConnectionManager 'jboss.jca:service=ConnectionFactoryBinding,name=JmsXA' to JNDI name 'java:JmsXA'
                        2008-07-21 15:54:17,377 INFO [org.jboss.ejb3.EJBContainer] (main) STARTED EJB: org.jboss.seam.async.TimerServiceDispatcher ejbName: TimerServiceDispatcher
                        2008-07-21 15:54:17,705 INFO [org.jboss.ejb3.entity.PersistenceUnitDeployment] (main) Starting persistence unit persistence.units:ear=ec20.ear,jar=ec20.jar,unitName=ec20
                        2008-07-21 15:54:18,111 INFO [org.hibernate.cfg.annotations.Version] (main) Hibernate Annotations 3.4.0.CR1
                        2008-07-21 15:54:18,267 INFO [org.hibernate.cfg.Environment] (main) Hibernate 3.3.0.CR1
                        2008-07-21 15:54:18,314 INFO [org.hibernate.cfg.Environment] (main) hibernate.properties not found
                        2008-07-21 15:54:18,346 INFO [org.hibernate.cfg.Environment] (main) Bytecode provider name : javassist
                        2008-07-21 15:54:18,408 INFO [org.hibernate.cfg.Environment] (main) using JDK 1.4 java.sql.Timestamp handling
                        2008-07-21 15:54:19,096 INFO [org.hibernate.annotations.common.Version] (main) Hibernate Commons Annotations 3.1.0.CR1
                        2008-07-21 15:54:19,142 INFO [org.hibernate.ejb.Version] (main) Hibernate EntityManager 3.4.0.CR1
                        2008-07-21 15:54:22,564 WARN [org.hibernate.ejb.Ejb3Configuration] (main) Persistence provider caller does not implement the EJB3 spec correctly. PersistenceUnitInfo.getNewTempClassLoader() is null.
                        2008-07-21 15:54:23,158 INFO [org.hibernate.cfg.AnnotationBinder] (main) Binding entity from annotated class: com.pearson.ejb3.entity.Eventfactory
                        2008-07-21 15:54:23,252 INFO [org.hibernate.cfg.annotations.QueryBinder] (main) Binding Named query: evt_factory_srcEvtCode_asc => select ef from Eventfactory ef order by ef.eventtypes.ik asc
                        2008-07-21 15:54:23,705 INFO [org.hibernate.cfg.annotations.EntityBinder] (main) Bind entity com.pearson.ejb3.entity.Eventfactory on table eventfactory
                        2008-07-21 15:54:24,299 INFO [org.hibernate.cfg.AnnotationBinder] (main) Binding entity from annotated class: com.pearson.ejb3.entity.Eventtypes
                        2008-07-21 15:54:24,299 INFO [org.hibernate.cfg.annotations.QueryBinder] (main) Binding Named query: eventtypes_all => select et from Eventtypes et
                        2008-07-21 15:54:24,299 INFO [org.hibernate.cfg.annotations.EntityBinder] (main) Bind entity com.pearson.ejb3.entity.Eventtypes on table eventtypes
                        2008-07-21 15:54:24,580 INFO [org.hibernate.cfg.AnnotationBinder] (main) Binding entity from annotated class: com.pearson.ejb3.entity.Units
                        2008-07-21 15:54:24,580 INFO [org.hibernate.cfg.annotations.QueryBinder] (main) Binding Named query: unit_all_sorted_name => select u from Units u order by unitName
                        ......
                        2008-07-21 15:54:24,580 INFO [org.hibernate.cfg.annotations.EntityBinder] (main) Bind entity com.pearson.ejb3.entity.Units on table units
                        ......
                        2008-07-21 15:54:25,736 INFO [org.hibernate.cfg.annotations.QueryBinder] (main) Binding Named query: find_by_key_serial => select a from Assignedkeys a where a.keySerial = :keySerial
                        .....
                        2008-07-21 15:54:26,939 INFO [org.hibernate.validator.Version] (main) Hibernate Validator 3.1.0.CR1
                        2008-07-21 15:54:29,549 INFO [org.hibernate.connection.ConnectionProviderFactory] (main) Initializing connection provider: org.hibernate.ejb.connection.InjectedDataSourceConnectionProvider
                        ......
                        2008-07-21 15:55:30,017 INFO [org.hibernate.util.NamingHelper] (main) JNDI InitialContext properties:{java.naming.factory.initial=org.jnp.interfaces.NamingContextFactory, java.naming.factory.url.pkgs=org.jboss.naming:org.jnp.interfaces}
                        2008-07-21 15:55:37,283 INFO [org.jboss.ejb3.EJBContainer] (main) STARTED EJB: com.pearson.ejb3.session.util.ProcessorBean ejbName: ProcessorBean
                        2008-07-21 15:55:37,330 INFO [org.jboss.ejb3.EJBContainer] (main) STARTED EJB: org.jboss.seam.transaction.EjbSynchronizations ejbName: EjbSynchronizations
                        2008-07-21 15:55:41,549 INFO [org.jboss.ejb3.EJBContainer] (main) STARTED EJB: com.pearson.ejb3.session.DBUtilBean ejbName: DBUtilBean
                        2008-07-21 15:55:41,611 INFO [org.jboss.ejb3.EJBContainer] (main) STARTED EJB: com.pearson.ejb3.session.FeatureMgrBean ejbName: FeatureMgrBean
                        2008-07-21 15:55:41,658 INFO [org.jboss.ejb3.EJBContainer] (main) STARTED EJB: com.pearson.ejb3.session.AuthenticatorBean ejbName: AuthenticatorBean
                        2008-07-21 15:55:41,689 INFO [org.jboss.ejb3.EJBContainer] (main) STARTED EJB: com.pearson.ejb3.session.util.CertUtilBean ejbName: CertUtilBean
                        2008-07-21 15:55:41,736 INFO [org.jboss.ejb3.EJBContainer] (main) STARTED EJB: com.pearson.ejb3.session.rfserver.UnitRunnerBean ejbName: UnitRunnerBean
                        2008-07-21 15:55:41,783 INFO [org.jboss.ejb3.EJBContainer] (main) STARTED EJB: com.pearson.ejb3.session.PersonnelAssignBean ejbName: PersonnelAssignBean
                        2008-07-21 15:55:41,814 INFO [org.jboss.ejb3.EJBContainer] (main) STARTED EJB: com.pearson.ejb3.session.util.SessionUtilBean ejbName: SessionUtilBean
                        2008-07-21 15:55:41,846 INFO [org.jboss.ejb3.EJBContainer] (main) STARTED EJB: com.pearson.ejb3.session.UnitStatusDataBean ejbName: UnitStatusDataBean
                        2008-07-21 15:55:42,580 INFO [org.jboss.ejb3.EJBContainer] (main) STARTED EJB: com.pearson.ejb3.session.ota.UnitLocatorBean ejbName: UnitLocatorBean
                        2008-07-21 15:55:43,205 INFO [org.jboss.ejb3.EJBContainer] (main) STARTED EJB: com.pearson.ejb3.session.util.EventUtilBean ejbName: EventUtilBean
                        2008-07-21 15:55:44,002 INFO [org.jboss.ejb3.EJBContainer] (main) STARTED EJB: com.pearson.ejb3.session.util.UnitUtilBean ejbName: UnitUtilBean
                        2008-07-21 15:55:46,205 INFO [org.jboss.ejb3.EJBContainer] (main) STARTED EJB: com.pearson.ejb3.session.PacketProcessorBean ejbName: PacketProcessorBean
                        2008-07-21 15:55:46,252 INFO [org.jboss.ejb3.EJBContainer] (main) STARTED EJB: com.pearson.ejb3.session.IButtonMgrBean ejbName: IButtonMgrBean
                        2008-07-21 15:55:46,299 INFO [org.jboss.ejb3.EJBContainer] (main) STARTED EJB: com.pearson.ejb3.session.ota.OTASenderBean ejbName: OTASenderBean
                        2008-07-21 15:55:46,361 INFO [org.jboss.ejb3.EJBContainer] (main) STARTED EJB: com.pearson.ejb3.session.util.UnitStatusMgrBean ejbName: UnitStatusMgrBean
                        2008-07-21 15:55:47,002 INFO [org.jboss.ejb3.EJBContainer] (main) STARTED EJB: com.pearson.ejb3.session.EventPostProcessorBean ejbName: EventPostProcessorBean
                        2008-07-21 15:55:47,955 ERROR [org.jboss.system.server.profileservice.ProfileServiceBootstrap] (main) Failed to load profile: Summary of incomplete deployments (SEE PREVIOUS ERRORS FOR DETAILS):

                        *** CONTEXTS MISSING DEPENDENCIES: Name -> Dependency{Required State:Actual State}

                        jboss.messaging.connectionfactory:service=ClusterPullConnectionFactory
                        -> jboss.messaging:service=PostOffice{Create:Configured}

                        jboss.messaging.connectionfactory:service=ClusteredConnectionFactory
                        -> jboss.messaging:service=PostOffice{Create:Configured}

                        jboss.messaging.connectionfactory:service=ConnectionFactory
                        -> jboss.messaging:service=PostOffice{Create:Configured}

                        jboss.messaging.destination:name=DLQ,service=Queue
                        -> jboss.messaging:service=PostOffice{Create:Configured}

                        jboss.messaging.destination:name=ExpiryQueue,service=Queue
                        -> jboss.messaging:service=PostOffice{Create:Configured}

                        jboss.messaging:service=PostOffice
                        -> jboss.jgroups:service=ChannelFactory{Create:** NOT FOUND Depends on 'jboss.jgroups:service=ChannelFactory **}


                        *** CONTEXTS IN ERROR: Name -> Error

                        jboss.jgroups:service=ChannelFactory -> ** NOT FOUND Depends on 'jboss.jgroups:service=ChannelFactory **


                        2008-07-21 15:55:48,033 INFO [org.apache.coyote.http11.Http11Protocol] (main) Starting Coyote HTTP/1.1 on http-localhost%2F127.0.0.1-8080
                        2008-07-21 15:55:48,252 INFO [org.apache.coyote.ajp.AjpProtocol] (main) Starting Coyote AJP/1.3 on ajp-localhost%2F127.0.0.1-8009
                        2008-07-21 15:55:48,283 INFO [org.jboss.bootstrap.microcontainer.ServerImpl] (main) JBoss (Microcontainer) [5.0.0.CR1 (build: SVNTag=JBoss_5_0_0_CR1 date=200806301254)] Started in 8m:43s:516ms

                        • 9. Re: Session bean lookup fails JBoss 5, worked in 4.0.5
                          jaikiran

                          lpmon,

                          This looks like the issue with CR1 deployment ordering. Could you please open a new thread in the JBoss AS5 forum http://www.jboss.com/index.html?module=bb&op=viewforum&f=287 and provide these details there. Along with the details, you can even point them to this thread.

                          You will find more audience there, since this looks to be an issue with 5.0 CR1 and might be related to https://jira.jboss.org/jira/browse/JBAS-5190.