[jboss-dev] AS is hanging at startup

[Adrian Brock]

On Wed, 2008-09-03 at 16:20 +0200, Sacha Labourey wrote:
> Damned.... that's a LOT, is this what Adrian is supposed to optimize?
> 

At least find out what is wrong and report it to whoever
is responsible.
Find out what is wrong at least and report it to whoever
is responsible. :-)

hprof sampling (not very accurate but useful for finding 
"braindeaths"/hotspots) shows the time spent in;

* vfs ZIP handling (and some other vfs calls such isLeaf())
* aop point cut matching
* annotation scanning
* ClassLoader.defineClass1()

The last one is probably spurious since hprof does
byte code manipulation to add its hooks during class definition
and it would a JDK issue anyway. :-)

It needs more detailed analysis (and better numbers). 
To determine where/why these are slow and whether they can 
be optimised further.

I did some anaylsis last month which cut the "default"
config bootstrap from 1 minute to 45 seconds on my laptop.

Marko has already done some fixes to the VFS based
on this earlier analysis, but they don't look to
have made a major difference - or maybe it got
slower elsewhere to compensate. :-)

Although this is misleading since the
> > -----Original Message-----
> > From: jboss-development-bounces at lists.jboss.org [mailto:jboss-
> > development-bounces at lists.jboss.org] On Behalf Of Dimitris Andreadis
> > Sent: mercredi, 3 septembre 2008 16:17
> > To: JBoss.org development list
> > Subject: Re: [jboss-dev] AS is hanging at startup
> > 
> > The boot delays are so far due to annotation/vfs processing. This
> > particular one on my
> > machine appears to be a 4sec:
> > 
> > 2008-09-03 17:10:56,046 DEBUG
> > [org.jboss.deployment.MappedReferenceMetaDataResolverDeployer]
> > (main) Processing unit=invoker.war, structure:
> > http-invoker.sar
> > 
> > +invoker.war
> > 
> > 
> > 2008-09-03 17:11:00,937 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-09-03 17:11:00,968 DEBUG
> > [org.jboss.deployment.MappedReferenceMetaDataResolverDeployer]
> > (main)
> > vfsfile:/X:/cvs/jboss-public/jboss-head/build/output/jboss-
> > 5.0.0.CR2/server/default/deploy/jbossws.sar/
> > endpoint mappings:
> > 
> > 
> > 
> > 
> > Sacha Labourey wrote:
> > > 30 seconds:
> > >
> > > ...
> > > 15:09:27,671 INFO  [JMXKernel] Legacy JMX core initialized
> > > 15:09:31,406 INFO  [MetaDataAwareProfile] Using profile
> > > root:C:\SVN_HOME\trunk\b
> > > uild\output\jboss-5.0.0.CR2\server\web-profile
> > > 15:10:02,062 INFO  [TransactionManagerService] JBossTS Transaction
> > Service
> > > (JTA
> > > version) - JBoss Inc.
> > > 15:10:02,062 INFO  [TransactionManagerService] Setting up property
> > manager
> > > MBean
> > >  and JMX layer
> > > ...
> > >
> > > Any idea why? I am connected to the Internet.
> > >
> > > Actually, it doesn't really hang, but doing things pretty slowly and
> > then
> > > there is this:
> > >
> > > 2008-09-03 15:09:48,562 DEBUG
> > > [org.jboss.classloader.spi.base.BaseClassLoaderDomain] (main)
> > >
> > ClassLoaderDomain at faaa93{vfsfile:/C:/SVN_HOME/trunk/build/output/jboss-
> > 5.0.0
> > > .CR2/server/web-profile/deploy/jmx-console.war/} registerClassLoader
> > > BaseClassLoader at d4b411{vfsfile:/C:/SVN_HOME/trunk/build/output/jboss-
> > 5.0.0.C
> > > R2/server/web-profile/deploy/jmx-console.war/}
> > > 2008-09-03 15:09:49,390 DEBUG
> > > [org.jboss.deployment.OptAnnotationMetaDataDeployer] (main)
> > Deployment is
> > > metadata-complete, skipping annotation processing,
> > ejbJarMetaData=null,
> > > jbossWebMetaData=org.jboss.metadata.web.spec.Web23MetaData at 1f,
> > > jbossClientMetaData=null, metaDataCompleteIsDefault=false
> > > 2008-09-03 15:09:49,406 DEBUG
> > > [org.jboss.deployment.MappedReferenceMetaDataResolverDeployer] (main)
> > > vfsfile:/C:/SVN_HOME/trunk/build/output/jboss-5.0.0.CR2/server/web-
> > profile/d
> > > eploy/http-invoker.sar/ endpoint mappings:
> > >
> > > 2008-09-03 15:09:49,406 DEBUG
> > > [org.jboss.deployment.MappedReferenceMetaDataResolverDeployer] (main)
> > > Processing unit=invoker.war, structure:
> > > http-invoker.sar
> > >
> > > +invoker.war
> > >
> > >
> > > 2008-09-03 15:09:58,984 DEBUG
> > > [org.jboss.deployment.OptAnnotationMetaDataDeployer] (main)
> > Deployment is
> > > metadata-complete, skipping annotation processing,
> > ejbJarMetaData=null,
> > > jbossWebMetaData=org.jboss.metadata.web.spec.Web23MetaData at 1f,
> > > jbossClientMetaData=null, metaDataCompleteIsDefault=false
> > > 2008-09-03 15:09:58,984 DEBUG
> > > [org.jboss.deployment.MappedReferenceMetaDataResolverDeployer] (main)
> > > vfsfile:/C:/SVN_HOME/trunk/build/output/jboss-5.0.0.CR2/server/web-
> > profile/d
> > > eploy/ROOT.war/ endpoint mappings:
> > >
> > > 2008-09-03 15:09:58,984 DEBUG
> > > [org.jboss.deployment.MappedReferenceMetaDataResolverDeployer] (main)
> > > Processing unit=ROOT.war, structure:
> > > ROOT.war
> > >
> > >
> > > 2008-09-03 15:09:59,046 DEBUG
> > > [org.jboss.deployment.OptAnnotationMetaDataDeployer] (main)
> > Deployment is
> > > metadata-complete, skipping annotation processing,
> > ejbJarMetaData=null,
> > > jbossWebMetaData=org.jboss.metadata.web.spec.Web23MetaData at 1f,
> > > jbossClientMetaData=null, metaDataCompleteIsDefault=false
> > >
> > >
> > > _______________________________________________
> > > 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
> 
> _______________________________________________
> 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