Failed Deployment to a Group

[Ruslan]

Hello, we are trying to figure out why some deployments fail while others work. 

I have an example of two completely identical applications deployed into the same group. One of them is running fine (newapp) and another one fails to start (test)

The only difference that non working test application was previously deployed into another target (server) and it worked fine there. Then later it was undeployed and redeployed into the new target (group1)

-> Checking deployment-group status Wed May 23 03:22:32 UTC 2018 
List of Deployment Groups: group1

-> Redeploying apps from standalone server to deployment-group

-> Undeploying test from standalone server
/opt/payara/bin/asadmin --user admin --interactive=false --passwordfile /home/jelastic/.ssh/pwdfile --host 192.168.4.180 --port 4848 --secure undeploy --target server test
Command undeploy executed successfully.

-> Deploying test/test.war to test...
/opt/payara/bin/asadmin --user admin --interactive=false --passwordfile /home/jelastic/.ssh/pwdfile --host 192.168.4.180 --port 4848 --secure deploy --availabilityenabled=true --asyncreplication=false --force=true --target group1 --contextroot test test/test.war
Application deployed with name test.
Command deploy executed successfully.

Both applications have the same target at the end.

There is a little bit information about the issue in the logs of a worker instance.

[2018-05-23T03:24:20.235+0000] [Payara 5.181] [INFO] [NCLS-CORE-00022] [javax.enterprise.system.core] [tid: _ThreadID=21 _ThreadName=RunLevelControllerThread-1527045852892] [timeMillis: 1527045860235] [levelValue: 800] [[
  Loading application test done in 3,832 ms]]


[2018-05-23T03:24:24.412+0000] [Payara 5.181] [INFO] [] [fish.payara.nucleus.hazelcast.HazelcastCore] [tid: _ThreadID=21 _ThreadName=RunLevelControllerThread-1527045852892] [timeMillis: 1527045864412] [levelValue: 800] [[
  Hazelcast Instance Bound to JNDI at payara/Hazelcast]]


[2018-05-23T03:24:24.414+0000] [Payara 5.181] [INFO] [] [fish.payara.nucleus.hazelcast.HazelcastCore] [tid: _ThreadID=21 _ThreadName=RunLevelControllerThread-1527045852892] [timeMillis: 1527045864414] [levelValue: 800] [[
  JSR107 Caching Provider Bound to JNDI at payara/CachingProvider]]


[2018-05-23T03:24:24.415+0000] [Payara 5.181] [INFO] [] [fish.payara.nucleus.hazelcast.HazelcastCore] [tid: _ThreadID=21 _ThreadName=RunLevelControllerThread-1527045852892] [timeMillis: 1527045864415] [levelValue: 800] [[
  JSR107 Default Cache Manager Bound to JNDI at payara/CacheManager]]


[2018-05-23T03:24:24.428+0000] [Payara 5.181] [INFO] [AS-WEB-GLUE-00206] [javax.enterprise.web] [tid: _ThreadID=21 _ThreadName=RunLevelControllerThread-1527045852892] [timeMillis: 1527045864428] [levelValue: 800] [[
  Web module test not loaded to any virtual servers]]


[2018-05-23T03:24:24.429+0000] [Payara 5.181] [SEVERE] [] [javax.enterprise.web] [tid: _ThreadID=21 _ThreadName=RunLevelControllerThread-1527045852892] [timeMillis: 1527045864429] [levelValue: 1000] [[
  webApplication.unknownError]]


[2018-05-23T03:24:24.430+0000] [Payara 5.181] [SEVERE] [] [javax.enterprise.system.tools.deployment.common] [tid: _ThreadID=21 _ThreadName=RunLevelControllerThread-1527045852892] [timeMillis: 1527045864430] [levelValue: 1000] [[
  Module not started com.sun.enterprise.web.WebApplication@357e1702]]


[2018-05-23T03:24:24.432+0000] [Payara 5.181] [SEVERE] [] [javax.enterprise.system.tools.deployment.common] [tid: _ThreadID=21 _ThreadName=RunLevelControllerThread-1527045852892] [timeMillis: 1527045864432] [levelValue: 1000] [[
  Exception while invoking class com.sun.enterprise.web.WebApplication start method
java.lang.Exception: Module not started com.sun.enterprise.web.WebApplication@357e1702
 at org.glassfish.internal.data.ModuleInfo.start(ModuleInfo.java:296)
 at org.glassfish.internal.data.ApplicationInfo.start(ApplicationInfo.java:357)
 at com.sun.enterprise.v3.server.ApplicationLifecycle.initialize(ApplicationLifecycle.java:538)
 at com.sun.enterprise.v3.server.ApplicationLoaderService.postConstruct(ApplicationLoaderService.java:334)
 at org.jvnet.hk2.internal.ClazzCreator.postConstructMe(ClazzCreator.java:327)
 at org.jvnet.hk2.internal.ClazzCreator.create(ClazzCreator.java:375)
 at org.jvnet.hk2.internal.SystemDescriptor.create(SystemDescriptor.java:487)
 at org.glassfish.hk2.runlevel.internal.AsyncRunLevelContext.findOrCreate(AsyncRunLevelContext.java:305)
 at org.glassfish.hk2.runlevel.RunLevelContext.findOrCreate(RunLevelContext.java:89)
 at org.jvnet.hk2.internal.Utilities.createService(Utilities.java:2126)
 at org.jvnet.hk2.internal.ServiceHandleImpl.getService(ServiceHandleImpl.java:116)
 at org.jvnet.hk2.internal.ServiceHandleImpl.getService(ServiceHandleImpl.java:90)
 at org.glassfish.hk2.runlevel.internal.CurrentTaskFuture$QueueRunner.oneJob(CurrentTaskFuture.java:1237)
 at org.glassfish.hk2.runlevel.internal.CurrentTaskFuture$QueueRunner.run(CurrentTaskFuture.java:1168)
 at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
 at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
 at java.lang.Thread.run(Thread.java:748)
]]


[2018-05-23T03:24:24.436+0000] [Payara 5.181] [SEVERE] [NCLS-CORE-00026] [javax.enterprise.system.core] [tid: _ThreadID=21 _ThreadName=RunLevelControllerThread-1527045852892] [timeMillis: 1527045864436] [levelValue: 1000] [[
  Exception during lifecycle processing
java.lang.Exception: Module not started com.sun.enterprise.web.WebApplication@357e1702
 at org.glassfish.internal.data.ModuleInfo.start(ModuleInfo.java:296)
 at org.glassfish.internal.data.ApplicationInfo.start(ApplicationInfo.java:357)
 at com.sun.enterprise.v3.server.ApplicationLifecycle.initialize(ApplicationLifecycle.java:538)
 at com.sun.enterprise.v3.server.ApplicationLoaderService.postConstruct(ApplicationLoaderService.java:334)
 at org.jvnet.hk2.internal.ClazzCreator.postConstructMe(ClazzCreator.java:327)
 at org.jvnet.hk2.internal.ClazzCreator.create(ClazzCreator.java:375)
 at org.jvnet.hk2.internal.SystemDescriptor.create(SystemDescriptor.java:487)
 at org.glassfish.hk2.runlevel.internal.AsyncRunLevelContext.findOrCreate(AsyncRunLevelContext.java:305)
 at org.glassfish.hk2.runlevel.RunLevelContext.findOrCreate(RunLevelContext.java:89)
 at org.jvnet.hk2.internal.Utilities.createService(Utilities.java:2126)
 at org.jvnet.hk2.internal.ServiceHandleImpl.getService(ServiceHandleImpl.java:116)
 at org.jvnet.hk2.internal.ServiceHandleImpl.getService(ServiceHandleImpl.java:90)
 at org.glassfish.hk2.runlevel.internal.CurrentTaskFuture$QueueRunner.oneJob(CurrentTaskFuture.java:1237)
 at org.glassfish.hk2.runlevel.internal.CurrentTaskFuture$QueueRunner.run(CurrentTaskFuture.java:1168)
 at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
 at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
 at java.lang.Thread.run(Thread.java:748)
]]


[2018-05-23T03:24:24.438+0000] [Payara 5.181] [SEVERE] [] [javax.enterprise.system.core] [tid: _ThreadID=21 _ThreadName=RunLevelControllerThread-1527045852892] [timeMillis: 1527045864438] [levelValue: 1000] [[
  Exception while loading the app]]


[2018-05-23T03:24:24.439+0000] [Payara 5.181] [SEVERE] [AS-WEB-GLUE-00192] [javax.enterprise.web] [tid: _ThreadID=21 _ThreadName=RunLevelControllerThread-1527045852892] [timeMillis: 1527045864439] [levelValue: 1000] [[
  Undeployment failed for context /test]]

The issue appears only at Payara, the same deployment flow works fine with GlassFish. Any hint will be appreciated. 

Ruslan

[Ondro Mihályi]

Hi Ruslan,

This seems like a bug. I'm not sure what's happenning. We're very busy with preparing a new Payara release right now, could you please raise an issue on our github so that we don't forget to come back to it later?

Ondro

[Ruslan]

Hi Ondro, sure. 

https://github.com/payara/Payara/issues/2765

Thanks