dev@glassfish.java.net

V3 - exceptions when deploy commands sent immediately after server start.

From: Peter Williams <Pete.Williams_at_Sun.COM>
Date: Wed, 12 Mar 2008 18:52:30 -0700

Deployments to V3 performed immediately after server startup have been
working.

However, in current builds, if I start the server and immediately deploy
an application, I get the NPE shown in the server log below. (Typical
use case that does this -- <Run> action on web project in NetBeans when
server is not currently running.)
 
The deploy command itself is failed and NetBeans fails the <Run>
action. If I add a 1 second wait after to the code that is checking
server readiness, it works. Someone broke something recently...

On at least one occurrence of this problem, domain.xml was corrupted by
the addition of a second entry for the application.

-Peter

V3 NetBeans Plugin Command Log

INFO [glassfish-hk2]: command: http://localhost:8080/__asadmin/version
INFO [glassfish-hk2]: command result: SUCCESS, GlassFish 10.0-SNAPSHOT
(build b001)
INFO [glassfish-hk2]: command: http://localhost:8080/__asadmin/version
INFO [glassfish-hk2]: command result: SUCCESS, GlassFish 10.0-SNAPSHOT
(build b001)
INFO [glassfish-hk2]: command:
http://localhost:8080/__asadmin/list-applications
INFO [glassfish-hk2]: command result: SUCCESS, List of deployed
applications per container
INFO [glassfish-hk2]: command:
http://localhost:8080/__asadmin/deploy?path=/tmp/Foo/build/web?name=Foo?force=true
INFO [glassfish-hk2]: command: http://localhost:8080/__asadmin/version
INFO [glassfish-hk2]: command result: SUCCESS, GlassFish 10.0-SNAPSHOT
(build b001)
INFO [glassfish-hk2]: command result: FAILURE, Exception while deploying
the app : java.lang.NullPointerException

Glassfish Log:

Mar 12, 2008 6:34:57 PM com.sun.enterprise.v3.server.AppServerStartup run
INFO: HK2 initialized in 292 ms
Mar 12, 2008 6:34:57 PM com.sun.enterprise.v3.server.AppServerStartup run
INFO: com.sun.enterprise.naming.impl.ServicesHookup_at_1b48197 Init done in
311 ms
Mar 12, 2008 6:34:57 PM com.sun.enterprise.v3.server.AppServerStartup run
INFO: com.sun.enterprise.v3.services.impl.CmdLineParamProcessor_at_1c0ec97
Init done in 314 ms
Mar 12, 2008 6:34:57 PM com.sun.enterprise.v3.server.AppServerStartup run
INFO: com.sun.enterprise.v3.server.SystemTasks_at_145f0e3 Init done in 397 ms
Mar 12, 2008 6:34:57 PM com.sun.enterprise.v3.server.AppServerStartup run
INFO: com.sun.enterprise.v3.server.Globals_at_170bea5 Init done in 398 ms
Mar 12, 2008 6:34:57 PM com.sun.enterprise.v3.server.AppServerStartup run
INFO: com.sun.enterprise.v3.services.impl.LogManagerService_at_210b5b Init
done in 426 ms
Mar 12, 2008 6:34:57 PM com.sun.enterprise.v3.server.AppServerStartup run
INFO: com.sun.enterprise.v3.services.impl.HouseKeeper_at_1581593 Init done
in 428 ms
Mar 12, 2008 6:34:57 PM com.sun.enterprise.v3.services.impl.GrizzlyProxy
start
INFO: Listening on port 8080
Mar 12, 2008 6:34:57 PM com.sun.enterprise.v3.services.impl.GrizzlyProxy
start
INFO: Listening on port 8181
Mar 12, 2008 6:34:57 PM com.sun.enterprise.v3.services.impl.GrizzlyProxy
start
INFO: Listening on port 4848
Mar 12, 2008 6:34:57 PM com.sun.enterprise.v3.server.AppServerStartup run
INFO: com.sun.enterprise.v3.services.impl.GrizzlyService_at_1c50507 startup
done in 801 ms
Mar 12, 2008 6:34:57 PM com.sun.enterprise.v3.server.AppServerStartup run
INFO: Glassfish v3 started in 805 ms
Mar 12, 2008 6:34:57 PM com.sun.enterprise.security.SecurityLifecycle <init>
INFO: SEC1002: Security Manager is OFF.
Mar 12, 2008 6:34:58 PM com.sun.enterprise.security.SecurityLifecycle
onInitialization
INFO: Security startup service called
Mar 12, 2008 6:34:58 PM com.sun.enterprise.v3.admin.CommandRunner doCommand
SEVERE: Exception in command execution : java.lang.NullPointerException
java.lang.NullPointerException
        at
com.sun.enterprise.v3.deployment.UndeployCommand.execute(UndeployCommand.java:100)
        at
com.sun.enterprise.v3.admin.CommandRunner.doCommand(CommandRunner.java:172)
    at
com.sun.enterprise.v3.admin.CommandRunner.doCommand(CommandRunner.java:79)
        at
com.sun.enterprise.v3.deployment.DeployCommand.handleRedeploy(DeployCommand.java:354)
        at
com.sun.enterprise.v3.deployment.DeployCommand.execute(DeployCommand.java:208)
        at
com.sun.enterprise.v3.admin.CommandRunner.doCommand(CommandRunner.java:172)
        at
com.sun.enterprise.v3.admin.CommandRunner.doCommand(CommandRunner.java:79)
        at
com.sun.enterprise.v3.admin.AdminAdapter.doCommand(AdminAdapter.java:144)
        at
com.sun.enterprise.v3.admin.AdminAdapter.service(AdminAdapter.java:109)
        at
com.sun.grizzly.http.DefaultProcessorTask.invokeAdapter(DefaultProcessorTask.java:605)
        at
com.sun.grizzly.http.DefaultProcessorTask.doProcess(DefaultProcessorTask.java:536)
        at
com.sun.grizzly.http.DefaultProcessorTask.process(DefaultProcessorTask.java:785)
        at
com.sun.grizzly.http.DefaultProtocolFilter.execute(DefaultProtocolFilter.java:130)
        at
com.sun.enterprise.v3.services.impl.HttpProtocolFilter.execute(HttpProtocolFilter.java:111)
    at
com.sun.enterprise.v3.services.impl.GlassfishProtocolChain.executeProtocolFilter(GlassfishProtocolChain.java:61)
        at
com.sun.grizzly.DefaultProtocolChain.execute(DefaultProtocolChain.java:78)
        at
com.sun.grizzly.ProtocolChainContextTask.doCall(ProtocolChainContextTask.java:54)
        at
com.sun.grizzly.SelectionKeyContextTask.call(SelectionKeyContextTask.java:57)
        at
com.sun.grizzly.util.WorkerThreadImpl.run(WorkerThreadImpl.java:179)
Mar 12, 2008 6:34:58 PM com.sun.enterprise.security.SecurityLifecycle
onInitialization
INFO: Security service(s) started successfully....
Mar 12, 2008 6:34:58 PM
com.sun.enterprise.v3.server.ApplicationLifecycle failure
SEVERE: Exception while deploying the app
java.lang.NullPointerException
        at
com.sun.enterprise.v3.server.ApplicationLifecycle.setupContainerInfos(ApplicationLifecycle.java:423)
        at
com.sun.enterprise.v3.server.ApplicationLifecycle.deploy(ApplicationLifecycle.java:260)
        at
com.sun.enterprise.v3.deployment.DeployCommand.execute(DeployCommand.java:289)
        at
com.sun.enterprise.v3.admin.CommandRunner.doCommand(CommandRunner.java:172)
    at
com.sun.enterprise.v3.admin.CommandRunner.doCommand(CommandRunner.java:79)
        at
com.sun.enterprise.v3.admin.AdminAdapter.doCommand(AdminAdapter.java:144)
        at
com.sun.enterprise.v3.admin.AdminAdapter.service(AdminAdapter.java:109)
        at
com.sun.grizzly.http.DefaultProcessorTask.invokeAdapter(DefaultProcessorTask.java:605)
        at
com.sun.grizzly.http.DefaultProcessorTask.doProcess(DefaultProcessorTask.java:536)
        at
com.sun.grizzly.http.DefaultProcessorTask.process(DefaultProcessorTask.java:785)
        at
com.sun.grizzly.http.DefaultProtocolFilter.execute(DefaultProtocolFilter.java:130)
        at
com.sun.enterprise.v3.services.impl.HttpProtocolFilter.execute(HttpProtocolFilter.java:111)
        at
com.sun.enterprise.v3.services.impl.GlassfishProtocolChain.executeProtocolFilter(GlassfishProtocolChain.java:61)
        at
com.sun.grizzly.DefaultProtocolChain.execute(DefaultProtocolChain.java:78)
        at
com.sun.grizzly.ProtocolChainContextTask.doCall(ProtocolChainContextTask.java:54)
    at
com.sun.grizzly.SelectionKeyContextTask.call(SelectionKeyContextTask.java:57)
        at
com.sun.grizzly.util.WorkerThreadImpl.run(WorkerThreadImpl.java:179)
Mar 12, 2008 6:34:58 PM com.sun.enterprise.web.WebContainer postConstruct
INFO: Using default-web.xml
/space/tools/v3Mar11/config/../domains/domain1/config/default-web.xml
Mar 12, 2008 6:34:59 PM org.apache.coyote.tomcat5.MapperListener
registerHost
WARNING: HTTP listener with port 8080 ignoring registration of host with
object name com.sun.appserv:type=Host,host=__asadmin, because none of
the host's associated HTTP listeners matches this port
Mar 12, 2008 6:34:59 PM org.apache.coyote.tomcat5.MapperListener
registerHost
WARNING: HTTP listener with port 8181 ignoring registration of host with
object name com.sun.appserv:type=Host,host=__asadmin, because none of
the host's associated HTTP listeners matches this port
Mar 12, 2008 6:34:59 PM com.sun.enterprise.security.PolicyLoader loadPolicy
INFO: SEC1143: Loading policy provider
com.sun.enterprise.security.provider.PolicyWrapper.
Mar 12, 2008 6:35:00 PM com.sun.enterprise.web.WebDeployer load
INFO: Loading application Foo at /Foo
Mar 12, 2008 6:35:00 PM
com.sun.enterprise.v3.services.impl.ApplicationLoaderService
processApplication
INFO: Loading Foo Application done is 2658 ms