I've just upgraded
from JBoss AS 4.2 to 5.1 and have encountered a boot time issue that did not
exist with 4.2. If I start the server while connected to my company's VPN, it
takes 5:32 mins. With 4.2, it took about 1 min. If I start the server
disconnected from the VPN, but still connected to internet (high speed cable),
it takes about 1:40 mins. If I disable my network card, it takes 1:10 mins. I
have not made any modifications to the server configuration that ships in the
jboss-5.1.0.GA-jdk6.zip (also tried the jboss-5.1.0.GA.zip with the same
results).
Any ideas on why the
server startup would be network sensitive?
After turning on
debug level logging for everything, I found these performance
killers:
2010-02-25
12:56:31,003 DEBUG [org.jboss.management.j2ee.deployers.LocalJBossServerDomain]
(main) handleNotification:
javax.management.Notification[source=jboss.system:service=ServiceController][type=org.jboss.system.ServiceMBean.start][message=]
2010-02-25
12:56:43,466 FINE [sun.rmi.transport.tcp] (RMI Scheduler(0)) RMI
Scheduler(0): close connection
2010-02-25 12:56:43,481 FINE
[sun.rmi.transport.tcp] (RMI TCP Connection(2)-3.7.149.84) RMI TCP
Connection(2)-3.7.149.84: (port 1851) connection closed
2010-02-25
12:56:43,481 FINE [sun.rmi.transport.tcp] (RMI TCP
Connection(2)-3.7.149.84) RMI TCP Connection(2)-3.7.149.84: close
connection
2010-02-25 12:56:43,481 FINE [sun.rmi.transport.tcp] (RMI
Scheduler(0)) RMI Scheduler(0): close connection
2010-02-25 12:56:43,481
FINE [sun.rmi.transport.tcp] (RMI TCP Connection(1)-3.7.149.84) RMI TCP
Connection(1)-3.7.149.84: (port 1090) connection closed
2010-02-25
12:56:43,481 FINE [sun.rmi.transport.tcp] (RMI TCP
Connection(1)-3.7.149.84) RMI TCP Connection(1)-3.7.149.84: close
connection
2010-02-25 12:57:56,347 DEBUG [org.jboss.remoting.ServerInvoker]
(main) SocketServerInvoker[HCU-C1WYGC1:4446] added org.jboss.profileservice.management.upload.remoting.DeployHandler@18309b0
for subsystem 'DeploymentManager'
As you can see,
SocketServerInvoker takes over a minute.
Here, ProxyFactory
takes over a minute
2010-02-25
12:59:28,508 DEBUG [org.jboss.aop.microcontainer.beans.GenericBeanAspectFactory]
(main) Creating advice InvocationContextInterceptor with loader
null
2010-02-25 12:59:32,573 DEBUG [com.arjuna.ats.arjuna.logging.arjLogger]
(Thread-14) Periodic recovery - second pass <Thu, 25 Feb 2010
12:59:32>
2010-02-25 12:59:32,589 DEBUG
[com.arjuna.ats.arjuna.logging.arjLogger] (Thread-14)
AtomicActionRecoveryModule: Second pass
2010-02-25 13:00:24,142 DEBUG
[org.apache.catalina.session.ManagerBase]
(ContainerBackgroundProcessor[StandardEngine[jboss.web]]) Start expire sessions
StandardManager at 1267131624142 sessioncount 0
Here, ManagerBase
takes nearly a minute.
Thanks for any / all
help you can throw my way.
Dan