[jboss-user] [Performance Tuning] New message: "Re: JBoss 3.2.6 - pauses for a minute during startup"

Mike Curwen do-not-reply at jboss.com
Fri Feb 26 16:02:44 EST 2010 

User development,

A new message was posted in the thread "JBoss 3.2.6 - pauses for a minute during startup":

http://community.jboss.org/message/528907#528907

Author  : Mike Curwen
Profile : http://community.jboss.org/people/mike.curwen

Message:
--------------------------------------------------------------
I've managed to catch a pause again.  I took 3 thread dumps, each about 10 seconds apart.
 
14:54:02,029 INFO  [Server] Root Deployment Filename: jboss-service.xml
14:54:02,030 INFO  [Server] Starting General Purpose Architecture (GPA)...
14:54:02,263 INFO  [ServerInfo] Java version: 1.4.2_19,Sun Microsystems Inc.
14:54:02,263 INFO  [ServerInfo] Java VM: Java HotSpot(TM) Server VM 1.4.2_19-b04,Sun Microsystems Inc.
14:54:02,263 INFO  [ServerInfo] OS-System: Linux 2.6.24-23-generic,i386
14:54:02,437 INFO  [Server] Core system initialized
Full thread dump Java HotSpot(TM) Server VM (1.4.2_19-b04 mixed mode):

"DestroyJavaVM" prio=1 tid=0x08057c38 nid=0x472e waiting on condition [0x00000000..0xbff722ec]

"main" prio=1 tid=0x080ef038 nid=0x4738 runnable [0x81ad4000..0x81ad51b8]
        at java.io.FileInputStream.readBytes(Native Method)
        at java.io.FileInputStream.read(FileInputStream.java:194)
        at java.io.BufferedInputStream.fill(BufferedInputStream.java:183)
        at java.io.BufferedInputStream.read1(BufferedInputStream.java:222)
        at java.io.BufferedInputStream.read(BufferedInputStream.java:277)
        - locked <0x83238588> (a java.io.BufferedInputStream)
        at sun.security.provider.SeedGenerator$URLSeedGenerator.getSeedByte(SeedGenerator.java:467)
        at sun.security.provider.SeedGenerator.getSeedBytes(SeedGenerator.java:137)
        at sun.security.provider.SeedGenerator.generateSeed(SeedGenerator.java:132)
        at sun.security.provider.SecureRandom.engineGenerateSeed(SecureRandom.java:112)
        at sun.security.provider.SecureRandom.engineNextBytes(SecureRandom.java:169)
        - locked <0x83238048> (a sun.security.provider.SecureRandom)
        at java.security.SecureRandom.nextBytes(SecureRandom.java:381)
        - locked <0x831bffc0> (a java.security.SecureRandom)
        at java.security.SecureRandom.next(SecureRandom.java:403)
        at java.util.Random.nextLong(Random.java:282)
        at java.io.File.generateFile(File.java:1291)
        at java.io.File.createTempFile(File.java:1399)
        at org.jboss.deployment.MainDeployer.makeLocalCopy(MainDeployer.java:990)
        at org.jboss.deployment.MainDeployer.init(MainDeployer.java:673)
        at org.jboss.deployment.MainDeployer.deploy(MainDeployer.java:631)
        at org.jboss.deployment.MainDeployer.deploy(MainDeployer.java:604)
        at org.jboss.deployment.MainDeployer.deploy(MainDeployer.java:588)
        at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
        at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
        at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
        at java.lang.reflect.Method.invoke(Method.java:324)
        at org.jboss.mx.server.ReflectedDispatcher.dispatch(ReflectedDispatcher.java:60)
        at org.jboss.mx.server.Invocation.dispatch(Invocation.java:62)
        at org.jboss.mx.server.Invocation.dispatch(Invocation.java:54)
        at org.jboss.mx.server.Invocation.invoke(Invocation.java:82)
        at org.jboss.mx.server.AbstractMBeanInvoker.invoke(AbstractMBeanInvoker.java:197)
        at org.jboss.mx.server.MBeanServerImpl.invoke(MBeanServerImpl.java:473)
        at org.jboss.mx.util.MBeanProxyExt.invoke(MBeanProxyExt.java:176)
        at $Proxy5.deploy(Unknown Source)
        at org.jboss.system.server.ServerImpl.doStart(ServerImpl.java:407)
        at org.jboss.system.server.ServerImpl.start(ServerImpl.java:311)
        at org.jboss.Main.boot(Main.java:145)
        at org.jboss.Main$1.run(Main.java:399)
        at java.lang.Thread.run(Thread.java:534)

"Signal Dispatcher" daemon prio=1 tid=0x080c09f0 nid=0x4733 waiting on condition [0x00000000..0x00000000]

"Finalizer" daemon prio=1 tid=0x080bb858 nid=0x4731 in Object.wait() [0x82535000..0x825351b8]
        at java.lang.Object.wait(Native Method)
        - waiting on <0x82910490> (a java.lang.ref.ReferenceQueue$Lock)
        at java.lang.ref.ReferenceQueue.remove(ReferenceQueue.java:111)
        - locked <0x82910490> (a java.lang.ref.ReferenceQueue$Lock)
        at java.lang.ref.ReferenceQueue.remove(ReferenceQueue.java:127)
        at java.lang.ref.Finalizer$FinalizerThread.run(Finalizer.java:159)

"Reference Handler" daemon prio=1 tid=0x080bb480 nid=0x4730 in Object.wait() [0x825b6000..0x825b61b8]
        at java.lang.Object.wait(Native Method)
        - waiting on <0x82910380> (a java.lang.ref.Reference$Lock)
        at java.lang.Object.wait(Object.java:429)
        at java.lang.ref.Reference$ReferenceHandler.run(Reference.java:115)
        - locked <0x82910380> (a java.lang.ref.Reference$Lock)

"VM Thread" prio=1 tid=0x080ba9e0 nid=0x472f runnable

"VM Periodic Task Thread" prio=1 tid=0x080c4fe0 nid=0x4737 waiting on condition
"Suspend Checker Thread" prio=1 tid=0x080c0000 nid=0x4732 runnable

me at oldhost:/usr/foo/java/jboss-3.2.6/bin$
me at oldhost:/usr/foo/java/jboss-3.2.6/bin$  (this is me hitting enter a couple times...)
me at oldhost:/usr/foo/java/jboss-3.2.6/bin$
me at oldhost:/usr/foo/java/jboss-3.2.6/bin$ Full thread dump Java HotSpot(TM) Server VM (1.4.2_19-b04 mixed mode):

"DestroyJavaVM" prio=1 tid=0x08057c38 nid=0x472e waiting on condition [0x00000000..0xbff722ec]

"main" prio=1 tid=0x080ef038 nid=0x4738 runnable [0x81ad4000..0x81ad51b8]
        at java.io.FileInputStream.readBytes(Native Method)
        at java.io.FileInputStream.read(FileInputStream.java:194)
        at java.io.BufferedInputStream.fill(BufferedInputStream.java:183)
        at java.io.BufferedInputStream.read1(BufferedInputStream.java:222)
        at java.io.BufferedInputStream.read(BufferedInputStream.java:277)
        - locked <0x83238588> (a java.io.BufferedInputStream)
        at sun.security.provider.SeedGenerator$URLSeedGenerator.getSeedByte(SeedGenerator.java:467)
        at sun.security.provider.SeedGenerator.getSeedBytes(SeedGenerator.java:137)
        at sun.security.provider.SeedGenerator.generateSeed(SeedGenerator.java:132)
        at sun.security.provider.SecureRandom.engineGenerateSeed(SecureRandom.java:112)
        at sun.security.provider.SecureRandom.engineNextBytes(SecureRandom.java:169)
        - locked <0x83238048> (a sun.security.provider.SecureRandom)
        at java.security.SecureRandom.nextBytes(SecureRandom.java:381)
        - locked <0x831bffc0> (a java.security.SecureRandom)
        at java.security.SecureRandom.next(SecureRandom.java:403)
        at java.util.Random.nextLong(Random.java:282)
        at java.io.File.generateFile(File.java:1291)
        at java.io.File.createTempFile(File.java:1399)
        at org.jboss.deployment.MainDeployer.makeLocalCopy(MainDeployer.java:990)
        at org.jboss.deployment.MainDeployer.init(MainDeployer.java:673)
        at org.jboss.deployment.MainDeployer.deploy(MainDeployer.java:631)
        at org.jboss.deployment.MainDeployer.deploy(MainDeployer.java:604)
        at org.jboss.deployment.MainDeployer.deploy(MainDeployer.java:588)
        at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
        at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
        at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
        at java.lang.reflect.Method.invoke(Method.java:324)
        at org.jboss.mx.server.ReflectedDispatcher.dispatch(ReflectedDispatcher.java:60)
        at org.jboss.mx.server.Invocation.dispatch(Invocation.java:62)
        at org.jboss.mx.server.Invocation.dispatch(Invocation.java:54)
        at org.jboss.mx.server.Invocation.invoke(Invocation.java:82)
        at org.jboss.mx.server.AbstractMBeanInvoker.invoke(AbstractMBeanInvoker.java:197)
        at org.jboss.mx.server.MBeanServerImpl.invoke(MBeanServerImpl.java:473)
        at org.jboss.mx.util.MBeanProxyExt.invoke(MBeanProxyExt.java:176)
        at $Proxy5.deploy(Unknown Source)
        at org.jboss.system.server.ServerImpl.doStart(ServerImpl.java:407)
        at org.jboss.system.server.ServerImpl.start(ServerImpl.java:311)
        at org.jboss.Main.boot(Main.java:145)
        at org.jboss.Main$1.run(Main.java:399)
        at java.lang.Thread.run(Thread.java:534)

"Signal Dispatcher" daemon prio=1 tid=0x080c09f0 nid=0x4733 waiting on condition [0x00000000..0x00000000]

"Finalizer" daemon prio=1 tid=0x080bb858 nid=0x4731 in Object.wait() [0x82535000..0x825351b8]
        at java.lang.Object.wait(Native Method)
        - waiting on <0x82910490> (a java.lang.ref.ReferenceQueue$Lock)
        at java.lang.ref.ReferenceQueue.remove(ReferenceQueue.java:111)
        - locked <0x82910490> (a java.lang.ref.ReferenceQueue$Lock)
        at java.lang.ref.ReferenceQueue.remove(ReferenceQueue.java:127)
        at java.lang.ref.Finalizer$FinalizerThread.run(Finalizer.java:159)

"Reference Handler" daemon prio=1 tid=0x080bb480 nid=0x4730 in Object.wait() [0x825b6000..0x825b61b8]
        at java.lang.Object.wait(Native Method)
        - waiting on <0x82910380> (a java.lang.ref.Reference$Lock)
        at java.lang.Object.wait(Object.java:429)
        at java.lang.ref.Reference$ReferenceHandler.run(Reference.java:115)
        - locked <0x82910380> (a java.lang.ref.Reference$Lock)

"VM Thread" prio=1 tid=0x080ba9e0 nid=0x472f runnable

"VM Periodic Task Thread" prio=1 tid=0x080c4fe0 nid=0x4737 waiting on condition
"Suspend Checker Thread" prio=1 tid=0x080c0000 nid=0x4732 runnable

me at oldhost:/usr/foo/java/jboss-3.2.6/bin$
me at oldhost:/usr/foo/java/jboss-3.2.6/bin$  (more of me hitting enter)
me at oldhost:/usr/foo/java/jboss-3.2.6/bin$
me at oldhost:/usr/foo/java/jboss-3.2.6/bin$ Full thread dump Java HotSpot(TM) Server VM (1.4.2_19-b04 mixed mode):

"DestroyJavaVM" prio=1 tid=0x08057c38 nid=0x472e waiting on condition [0x00000000..0xbff722ec]

"main" prio=1 tid=0x080ef038 nid=0x4738 runnable [0x81ad4000..0x81ad51b8]
        at java.io.FileInputStream.readBytes(Native Method)
        at java.io.FileInputStream.read(FileInputStream.java:194)
        at java.io.BufferedInputStream.fill(BufferedInputStream.java:183)
        at java.io.BufferedInputStream.read1(BufferedInputStream.java:222)
        at java.io.BufferedInputStream.read(BufferedInputStream.java:277)
        - locked <0x83238588> (a java.io.BufferedInputStream)
        at sun.security.provider.SeedGenerator$URLSeedGenerator.getSeedByte(SeedGenerator.java:467)
        at sun.security.provider.SeedGenerator.getSeedBytes(SeedGenerator.java:137)
        at sun.security.provider.SeedGenerator.generateSeed(SeedGenerator.java:132)
        at sun.security.provider.SecureRandom.engineGenerateSeed(SecureRandom.java:112)
        at sun.security.provider.SecureRandom.engineNextBytes(SecureRandom.java:169)
        - locked <0x83238048> (a sun.security.provider.SecureRandom)
        at java.security.SecureRandom.nextBytes(SecureRandom.java:381)
        - locked <0x831bffc0> (a java.security.SecureRandom)
        at java.security.SecureRandom.next(SecureRandom.java:403)
        at java.util.Random.nextLong(Random.java:282)
        at java.io.File.generateFile(File.java:1291)
        at java.io.File.createTempFile(File.java:1399)
        at org.jboss.deployment.MainDeployer.makeLocalCopy(MainDeployer.java:990)
        at org.jboss.deployment.MainDeployer.init(MainDeployer.java:673)
        at org.jboss.deployment.MainDeployer.deploy(MainDeployer.java:631)
        at org.jboss.deployment.MainDeployer.deploy(MainDeployer.java:604)
        at org.jboss.deployment.MainDeployer.deploy(MainDeployer.java:588)
        at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
        at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
        at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
        at java.lang.reflect.Method.invoke(Method.java:324)
        at org.jboss.mx.server.ReflectedDispatcher.dispatch(ReflectedDispatcher.java:60)
        at org.jboss.mx.server.Invocation.dispatch(Invocation.java:62)
        at org.jboss.mx.server.Invocation.dispatch(Invocation.java:54)
        at org.jboss.mx.server.Invocation.invoke(Invocation.java:82)
        at org.jboss.mx.server.AbstractMBeanInvoker.invoke(AbstractMBeanInvoker.java:197)
        at org.jboss.mx.server.MBeanServerImpl.invoke(MBeanServerImpl.java:473)
        at org.jboss.mx.util.MBeanProxyExt.invoke(MBeanProxyExt.java:176)
        at $Proxy5.deploy(Unknown Source)
        at org.jboss.system.server.ServerImpl.doStart(ServerImpl.java:407)
        at org.jboss.system.server.ServerImpl.start(ServerImpl.java:311)
        at org.jboss.Main.boot(Main.java:145)
        at org.jboss.Main$1.run(Main.java:399)
        at java.lang.Thread.run(Thread.java:534)

"Signal Dispatcher" daemon prio=1 tid=0x080c09f0 nid=0x4733 waiting on condition [0x00000000..0x00000000]

"Finalizer" daemon prio=1 tid=0x080bb858 nid=0x4731 in Object.wait() [0x82535000..0x825351b8]
        at java.lang.Object.wait(Native Method)
        - waiting on <0x82910490> (a java.lang.ref.ReferenceQueue$Lock)
        at java.lang.ref.ReferenceQueue.remove(ReferenceQueue.java:111)
        - locked <0x82910490> (a java.lang.ref.ReferenceQueue$Lock)
        at java.lang.ref.ReferenceQueue.remove(ReferenceQueue.java:127)
        at java.lang.ref.Finalizer$FinalizerThread.run(Finalizer.java:159)

"Reference Handler" daemon prio=1 tid=0x080bb480 nid=0x4730 in Object.wait() [0x825b6000..0x825b61b8]
        at java.lang.Object.wait(Native Method)
        - waiting on <0x82910380> (a java.lang.ref.Reference$Lock)
        at java.lang.Object.wait(Object.java:429)
        at java.lang.ref.Reference$ReferenceHandler.run(Reference.java:115)
        - locked <0x82910380> (a java.lang.ref.Reference$Lock)

"VM Thread" prio=1 tid=0x080ba9e0 nid=0x472f runnable

"VM Periodic Task Thread" prio=1 tid=0x080c4fe0 nid=0x4737 waiting on condition
"Suspend Checker Thread" prio=1 tid=0x080c0000 nid=0x4732 runnable


me at oldhost:/usr/foo/java/jboss-3.2.6/bin$ 14:54:46,561 INFO  [Log4jService$URLWatchTimerTask] Configuring from URL: resource:log4j.xml

pause is now over....



 
so I read that as "it's waiting for i/o", except vmstat within the host didn't who this wait.    Would this be evidence of resource contention in the server that is hosting the VM?

--------------------------------------------------------------

To reply to this message visit the message page: http://community.jboss.org/message/528907#528907

More information about the jboss-user mailing list