AS boots slowly (was: [jboss-dev] tomcat-sso)

Mon Oct 20 10:58:41 EDT 2008

Before I posted some stats on the deployment and controller stages
timings.
Besides aop I haven't seen much work done on this?

You can run this yourself now since it is a part of trunk.
So if you have to two different checkouts you can compare the stats.

For the deployers you can get the stats from
jboss.deployment:service=DeployersImpl

To see the controller stages, you need to expose the KernelController
via JMX and turn on stats collection which you can do by adding the
following to the bottom of conf/jmx.xml

   <!-- The kernel controller -->
   <bean name="KernelController">

<annotation>@org.jboss.aop.microcontainer.aspects.jmx.JMX(name="jboss.kernel:service=Controller", exposedInterface=org.jboss.dependency.plugins.AbstractControllerMBean.class)</annotation>
      <constructor factoryMethod="getController">
         <factory bean="jboss.kernel:service=Kernel"/>
      </constructor>
      <property name="collectStats">true</property>
   </bean>
   <bean name="KernelControllerMBean"
class="org.jboss.system.microcontainer.jmx.ServiceControllerLifecycleCallback">
      <property name="serviceController"><inject bean="JMXKernel"
property="serviceController"/></property>
      <install method="install">
         <parameter><inject bean="KernelController"
fromContext="context"/></parameter>
      </install>
      <uninstall method="uninstall">
         <parameter><inject bean="KernelController"
fromContext="context"/></parameter>
      </uninstall>
   </bean>

On Fri, 2008-10-17 at 13:50 +0300, Dimitris Andreadis wrote:
> I looked a bit more into that and it seems the tests times out because the server boots very 
> slowly in the tomcat-sso config (takes more than 120 secs).
> 
> And locally my default & all configs now take 60% more time to boot.
> 
> The usually culprit is AOP, so we need to investigate what changed:
> 
> https://jira.jboss.org/jira/browse/JBAS-6105
> 
> 2008-10-17 03:58:26,791 DEBUG [org.jboss.deployment.OptAnnotationMetaDataDeployer] (main) 
> Deployment is metadata-complete, skipping annotation processing, ejbJarMetaData=null, 
> jbossWebMetaData=org.jboss.metadata.web.spec.Web24MetaData at 1f, jbossClientMetaData=null, 
> metaDataCompleteIsDefault=false
> 2008-10-17 03:58:26,823 DEBUG [org.jboss.deployment.MappedReferenceMetaDataResolverDeployer] 
> (main) 
> vfsfile:/qa/services/hudson/hudson_workspace/workspace/JBoss-AS-5.0.x-TestSuite-sun15/trunk/build/output/jboss-5.0.0.GA/server/tomcat-sso/deploy/jbossws.sar/ 
> endpoint mappings:
> 
> 2008-10-17 03:58:26,823 DEBUG [org.jboss.deployment.MappedReferenceMetaDataResolverDeployer] 
> (main) Processing unit=jbossws-management.war, structure:
> jbossws.sar
> 
> +FastInfoset.jar
> 
> +jboss-jaxb-intros.jar
> 
> +jbossws-management.war
> 
> +jbossws-native-core.jar
> 
> +jettison.jar
> 
> +policy.jar
> 
> +wsdl4j.jar
> 
> +xmlsec.jar
> 
> 
> 2008-10-17 03:59:47,704 INFO  [org.jboss.bootstrap.microcontainer.ServerImpl] (JBoss 
> Shutdown Hook) Runtime shutdown hook called, forceHalt: true
> 2008-10-17 03:59:47,705 DEBUG [org.jboss.deployers.plugins.main.MainDeployerImpl] (JBoss 
> Shutdown Hook) Remove deployment context: 
> vfsfile:/qa/services/hudson/hudson_workspace/workspace/JBoss-AS-5.0.x-TestSuite-sun15/trunk/build/output/jboss-5.0.0.GA/server/tomcat-sso/deploy/management/console-mgr.sar/
> 2008-10-17 03:59:47,705 DEBUG [org.jboss.deployers.plugins.main.MainDeployerImpl] (JBoss 
> Shutdown Hook) Scheduling undeployment: 
> vfsfile:/qa/services/hudson/hudson_workspace/workspace/JBoss-AS-5.0.x-TestSuite-sun15/trunk/build/output/jboss-5.0.0.GA/server/tomcat-sso/deploy/management/console-mgr.sar/ 
> parent=null
> 
> 
> Dimitris Andreadis wrote:
> > It's been a few runs now that the tomcat-sso configuration doesn't start 
> > properly:
> > 
> > tomcat-sso-tests:
> >     [echo] creating tomcat-sso config
> >     [copy] Copying 374 files to 
> > /qa/services/hudson/hudson_workspace/workspace/JBoss-AS-5.0.x-TestSuite-sun15/trunk/build/output/jboss-5.0.0.GA/server/tomcat-sso 
> > 
> >     [copy] Copied 73 empty directories to 2 empty directories under 
> > /qa/services/hudson/hudson_workspace/workspace/JBoss-AS-5.0.x-TestSuite-sun15/trunk/build/output/jboss-5.0.0.GA/server/tomcat-sso 
> > 
> >     [echo] Overwriting config descriptors
> >     [copy] Copying 4 files to 
> > /qa/services/hudson/hudson_workspace/workspace/JBoss-AS-5.0.x-TestSuite-sun15/trunk/build/output/jboss-5.0.0.GA/server/tomcat-sso 
> > 
> > [server:start] Starting server "tomcat-sso", with command (start timeout 
> > is 120 seconds ): [server:start] /qa/tools/opt/jdk1.5.0_15/bin/java -cp 
> > /qa/services/hudson/hudson_workspace/workspace/JBoss-AS-5.0.x-TestSuite-sun15/trunk/build/output/jboss-5.0.0.GA/bin/run.jar:/qa/tools/opt/jdk1.5.0_15/lib/tools.jar 
> > -Xmx128m -XX:MaxPermSize=512m  -Djava.net.preferIPv4Stack=true 
> > -Djava.endorsed.dirs=/qa/services/hudson/hudson_workspace/workspace/JBoss-AS-5.0.x-TestSuite-sun15/trunk/build/output/jboss-5.0.0.GA/lib/endorsed  
> > org.jboss.Main -c tomcat-sso -b 10.18.94.175 -u 227.43.0.134 
> > [server:start] Failed to start server "tomcat-sso" before timeout. 
> > Destroying the process.
> > 
> >> From the log, I can't tell what's wrong:
> > http://hudson.qa.jboss.com/hudson/view/JBoss%20AS/job/JBoss-AS-5.0.x-TestSuite-sun15/996/console 
> > 
> > 
> > Whoever is changing the tomcat configs can you please make sure the 
> > tomcat testsuite configs are in synch?
> > 
> > Cheers
> > /D
> > _______________________________________________
> > jboss-development mailing list
> > jboss-development at lists.jboss.org
> > https://lists.jboss.org/mailman/listinfo/jboss-development
> _______________________________________________
> jboss-development mailing list
> jboss-development at lists.jboss.org
> https://lists.jboss.org/mailman/listinfo/jboss-development
-- 
xxxxxxxxxxxxxxxxxxxxxxxxxxxx
Adrian Brock
Chief Scientist
JBoss, a division of Red Hat
xxxxxxxxxxxxxxxxxxxxxxxxxxxx