SMX4 startup stuck

classic Classic list List threaded Threaded
5 messages Options
Reply | Threaded
Open this post in threaded view
|

SMX4 startup stuck

Jean-Baptiste Onofré
 Hi all,

 using ServiceMix 4.0-M1, most of the time (not always), when I start
ServiceMix using the servicemix script in the bin directory, it takes
100% of my CPU and do nothing while I haven't kill the process.

 My environment is :
 - Linux Debian
 - Kernel : 2.6.25
 - JVM : 1.6.0_01 (Sun)

 I have took a ThreadDump :

 [FelixStartLevel] INFO org.ops4j.pax.logging.internal.Activator -
Enabling SLF4J API support.
 [FelixDispatchQueue] INFO org.ops4j.pax.logging.pax-logging-service
- BundleEvent STARTED
 [FelixStartLevel] INFO org.ops4j.pax.logging.internal.Activator -
Enabling Jakarta Commons Logging API support.
 [FelixStartLevel] INFO org.ops4j.pax.logging.internal.Activator -
Enabling Log4J API support.
 2008-11-03 17:41:59
 Full thread dump Java HotSpot(TM) Server VM (1.6.0_01-b06 mixed
mode):

 "Timer-0" daemon prio=10 tid=0x8fd98c00 nid=0x3f83 in Object.wait()
[0x8fbf9000..0x8fbf91c0]
 java.lang.Thread.State: TIMED_WAITING (on object monitor)
 at java.lang.Object.wait(Native Method)
 - waiting on <0x94836228> (a java.util.TaskQueue)
 at java.util.TimerThread.mainLoop(Timer.java:509)
 - locked <0x94836228> (a java.util.TaskQueue)
 at java.util.TimerThread.run(Timer.java:462)

 "Configuration Updater" daemon prio=10 tid=0x8fffa800 nid=0x3f82 in
Object.wait() [0x8fe5a000..0x8fe5a140]
 java.lang.Thread.State: WAITING (on object monitor)
 at java.lang.Object.wait(Native Method)
 - waiting on <0x948362e0> (a java.util.LinkedList)
 at java.lang.Object.wait(Object.java:485)
 at org.apache.felix.cm.impl.UpdateThread.run(UpdateThread.java:69)
 - locked <0x948362e0> (a java.util.LinkedList)

 "FelixPackageAdmin" daemon prio=10 tid=0x082a3000 nid=0x3f81 in
Object.wait() [0x902cf000..0x902cfec0]
 java.lang.Thread.State: WAITING (on object monitor)
 at java.lang.Object.wait(Native Method)
 - waiting on <0x94570118> (a
org.apache.felix.framework.PackageAdminImpl)
 at java.lang.Object.wait(Object.java:485)
 at
org.apache.felix.framework.PackageAdminImpl.run(PackageAdminImpl.java:247)
 - locked <0x94570118> (a
org.apache.felix.framework.PackageAdminImpl)
 at java.lang.Thread.run(Thread.java:619)

 "FelixStartLevel" daemon prio=10 tid=0x0827ec00 nid=0x3f80 runnable
[0x90320000..0x90320e40]
 java.lang.Thread.State: RUNNABLE
 at
org.apache.felix.framework.searchpolicy.R4SearchPolicyCore.calculateUsesConstraints(R4SearchPolicyCore.java:1432)
 at
org.apache.felix.framework.searchpolicy.R4SearchPolicyCore.calculateUsesConstraints(R4SearchPolicyCore.java:1424)
 at
org.apache.felix.framework.searchpolicy.R4SearchPolicyCore.calculateUsesConstraints(R4SearchPolicyCore.java:1424)
 at
org.apache.felix.framework.searchpolicy.R4SearchPolicyCore.calculateUsesConstraints(R4SearchPolicyCore.java:1424)
 at
org.apache.felix.framework.searchpolicy.R4SearchPolicyCore.calculateUsesConstraints(R4SearchPolicyCore.java:1376)
 at
org.apache.felix.framework.searchpolicy.R4SearchPolicyCore.isClassSpaceConsistent(R4SearchPolicyCore.java:1278)
 at
org.apache.felix.framework.searchpolicy.R4SearchPolicyCore.isClassSpaceConsistent(R4SearchPolicyCore.java:1266)
 at
org.apache.felix.framework.searchpolicy.R4SearchPolicyCore.isClassSpaceConsistent(R4SearchPolicyCore.java:1266)
 at
org.apache.felix.framework.searchpolicy.R4SearchPolicyCore.findConsistentClassSpace(R4SearchPolicyCore.java:1198)
 at
org.apache.felix.framework.searchpolicy.R4SearchPolicyCore.resolve(R4SearchPolicyCore.java:1028)
 - locked <0x94577718> (a
org.apache.felix.moduleloader.ModuleFactoryImpl)
 at org.apache.felix.framework.Felix._resolveBundle(Felix.java:1705)
 at org.apache.felix.framework.Felix._startBundle(Felix.java:1579)
 at org.apache.felix.framework.Felix.startBundle(Felix.java:1532)
 at
org.apache.felix.framework.Felix.setFrameworkStartLevel(Felix.java:1116)

 at
org.apache.felix.framework.StartLevelImpl.run(StartLevelImpl.java:258)

 at java.lang.Thread.run(Thread.java:619)

 "FelixDispatchQueue" prio=10 tid=0x081a3800 nid=0x3f7f in
Object.wait() [0x90371000..0x90371fc0]
 java.lang.Thread.State: WAITING (on object monitor)
 at java.lang.Object.wait(Native Method)
 - waiting on <0x94570248> (a java.util.ArrayList)
 at java.lang.Object.wait(Object.java:485)
 at
org.apache.felix.framework.util.EventDispatcher.run(EventDispatcher.java:794)

 - locked <0x94570248> (a java.util.ArrayList)
 at
org.apache.felix.framework.util.EventDispatcher.access$000(EventDispatcher.java:42)

 at
org.apache.felix.framework.util.EventDispatcher$1.run(EventDispatcher.java:94)

 at java.lang.Thread.run(Thread.java:619)

 "RMI TCP Accept-0" daemon prio=10 tid=0x08187000 nid=0x3f7d runnable
[0x90443000..0x904440c0]
 java.lang.Thread.State: RUNNABLE
 at java.net.PlainSocketImpl.socketAccept(Native Method)
 at java.net.PlainSocketImpl.accept(PlainSocketImpl.java:384)
 - locked <0x94570300> (a java.net.SocksSocketImpl)
 at java.net.ServerSocket.implAccept(ServerSocket.java:450)
 at java.net.ServerSocket.accept(ServerSocket.java:421)
 at
sun.rmi.transport.tcp.TCPTransport$AcceptLoop.executeAcceptLoop(TCPTransport.java:369)

 at
sun.rmi.transport.tcp.TCPTransport$AcceptLoop.run(TCPTransport.java:341)

 at java.lang.Thread.run(Thread.java:619)

 "Low Memory Detector" daemon prio=10 tid=0x08102000 nid=0x3f7c
runnable [0x00000000..0x00000000]
 java.lang.Thread.State: RUNNABLE

 "CompilerThread1" daemon prio=10 tid=0x08100800 nid=0x3f7b waiting
on condition [0x00000000..0x9052f808]
 java.lang.Thread.State: RUNNABLE

 "CompilerThread0" daemon prio=10 tid=0x080ff400 nid=0x3f7a waiting
on condition [0x00000000..0x905b0758]
 java.lang.Thread.State: RUNNABLE

 "Signal Dispatcher" daemon prio=10 tid=0x080fe000 nid=0x3f79 waiting
on condition [0x00000000..0x00000000]
 java.lang.Thread.State: RUNNABLE

 "Finalizer" daemon prio=10 tid=0x080eb000 nid=0x3f78 in
Object.wait() [0x907eb000..0x907ebe40]
 java.lang.Thread.State: WAITING (on object monitor)
 at java.lang.Object.wait(Native Method)
 - waiting on <0x94570670> (a java.lang.ref.ReferenceQueue$Lock)
 at java.lang.ref.ReferenceQueue.remove(ReferenceQueue.java:116)
 - locked <0x94570670> (a java.lang.ref.ReferenceQueue$Lock)
 at java.lang.ref.ReferenceQueue.remove(ReferenceQueue.java:132)
 at java.lang.ref.Finalizer$FinalizerThread.run(Finalizer.java:159)

 "Reference Handler" daemon prio=10 tid=0x080ea800 nid=0x3f77 in
Object.wait() [0x9083c000..0x9083cfc0]
 java.lang.Thread.State: WAITING (on object monitor)
 at java.lang.Object.wait(Native Method)
 - waiting on <0x94570700> (a java.lang.ref.Reference$Lock)
 at java.lang.Object.wait(Object.java:485)
 at java.lang.ref.Reference$ReferenceHandler.run(Reference.java:116)
 - locked <0x94570700> (a java.lang.ref.Reference$Lock)

 "main" prio=10 tid=0x08058c00 nid=0x3f75 in Object.wait()
[0xb7e25000..0xb7e26298]
 java.lang.Thread.State: WAITING (on object monitor)
 at java.lang.Object.wait(Native Method)
 - waiting on <0x94836528> (a java.lang.Integer)
 at java.lang.Object.wait(Object.java:485)
 at
org.apache.felix.framework.StartLevelImpl.setStartLevelAndWait(StartLevelImpl.java:136)

 - locked <0x94836528> (a java.lang.Integer)
 at org.apache.felix.framework.Felix.start(Felix.java:865)
 - locked <0x94570738> (a org.apache.felix.framework.Felix)
 at org.apache.servicemix.kernel.main.Main.main(Main.java:257)

 "VM Thread" prio=10 tid=0x080e7c00 nid=0x3f76 runnable

 "VM Periodic Task Thread" prio=10 tid=0x08191800 nid=0x3f7e waiting
on condition

 JNI global references: 1127

 Heap
 def new generation total 13120K, used 11535K [0x90c90000,
0x91ac0000, 0x94570000)
 eden space 11712K, 96% used [0x90c90000, 0x9179f4f8, 0x91800000)
 from space 1408K, 14% used [0x91960000, 0x91994758, 0x91ac0000)
 to space 1408K, 0% used [0x91800000, 0x91800000, 0x91960000)
 tenured generation total 116544K, used 3215K [0x94570000,
0x9b740000, 0xb0c90000)
 the space 116544K, 2% used [0x94570000, 0x94893fa8, 0x94894000,
0x9b740000)
 compacting perm gen total 16384K, used 6454K [0xb0c90000,
0xb1c90000, 0xb4c90000)
 the space 16384K, 39% used [0xb0c90000, 0xb12dd9a8, 0xb12dda00,
0xb1c90000)
 No shared spaces configured.

 I have tried to increase the heap size to 1024m (-Xmx1024m) and
define the MaxPermSize to 128m (-XX:MaxPermSize=128m) but the behavior
is quite the same.

 Do you have an idea of the cause of the problem ?

 Thanks,
 JB
 --
 Jean-Baptiste Onofré
 [hidden email]
 BuildProcess/AutoDeploy Project Leader
 http://buildprocess.sourceforge.net

Reply | Threaded
Open this post in threaded view
|

Re: SMX4 startup stuck

Chris Custine (Apache)
I recall this happening at times with JDK 1.6 on Linux, so you might try 1.5
until we get a newer release out there.

Thanks,
Chris

--
Chris Custine
My Blog :: http://blog.organicelement.com
Apache ServiceMix :: http://servicemix.apache.org
Apache Directory Server :: http://directory.apache.org


On Mon, Nov 3, 2008 at 9:59 AM, Jean-Baptiste Onofré <[hidden email]>wrote:

>  Hi all,
>
>  using ServiceMix 4.0-M1, most of the time (not always), when I start
> ServiceMix using the servicemix script in the bin directory, it takes
> 100% of my CPU and do nothing while I haven't kill the process.
>
>  My environment is :
>  - Linux Debian
>  - Kernel : 2.6.25
>  - JVM : 1.6.0_01 (Sun)
>
>  I have took a ThreadDump :
>
>  [FelixStartLevel] INFO org.ops4j.pax.logging.internal.Activator -
> Enabling SLF4J API support.
>  [FelixDispatchQueue] INFO org.ops4j.pax.logging.pax-logging-service
> - BundleEvent STARTED
>  [FelixStartLevel] INFO org.ops4j.pax.logging.internal.Activator -
> Enabling Jakarta Commons Logging API support.
>  [FelixStartLevel] INFO org.ops4j.pax.logging.internal.Activator -
> Enabling Log4J API support.
>  2008-11-03 17:41:59
>  Full thread dump Java HotSpot(TM) Server VM (1.6.0_01-b06 mixed
> mode):
>
>  "Timer-0" daemon prio=10 tid=0x8fd98c00 nid=0x3f83 in Object.wait()
> [0x8fbf9000..0x8fbf91c0]
>  java.lang.Thread.State: TIMED_WAITING (on object monitor)
>  at java.lang.Object.wait(Native Method)
>  - waiting on <0x94836228> (a java.util.TaskQueue)
>  at java.util.TimerThread.mainLoop(Timer.java:509)
>  - locked <0x94836228> (a java.util.TaskQueue)
>  at java.util.TimerThread.run(Timer.java:462)
>
>  "Configuration Updater" daemon prio=10 tid=0x8fffa800 nid=0x3f82 in
> Object.wait() [0x8fe5a000..0x8fe5a140]
>  java.lang.Thread.State: WAITING (on object monitor)
>  at java.lang.Object.wait(Native Method)
>  - waiting on <0x948362e0> (a java.util.LinkedList)
>  at java.lang.Object.wait(Object.java:485)
>  at org.apache.felix.cm.impl.UpdateThread.run(UpdateThread.java:69)
>  - locked <0x948362e0> (a java.util.LinkedList)
>
>  "FelixPackageAdmin" daemon prio=10 tid=0x082a3000 nid=0x3f81 in
> Object.wait() [0x902cf000..0x902cfec0]
>  java.lang.Thread.State: WAITING (on object monitor)
>  at java.lang.Object.wait(Native Method)
>  - waiting on <0x94570118> (a
> org.apache.felix.framework.PackageAdminImpl)
>  at java.lang.Object.wait(Object.java:485)
>  at
> org.apache.felix.framework.PackageAdminImpl.run(PackageAdminImpl.java:247)
>  - locked <0x94570118> (a
> org.apache.felix.framework.PackageAdminImpl)
>  at java.lang.Thread.run(Thread.java:619)
>
>  "FelixStartLevel" daemon prio=10 tid=0x0827ec00 nid=0x3f80 runnable
> [0x90320000..0x90320e40]
>  java.lang.Thread.State: RUNNABLE
>  at
>
> org.apache.felix.framework.searchpolicy.R4SearchPolicyCore.calculateUsesConstraints(R4SearchPolicyCore.java:1432)
>  at
>
> org.apache.felix.framework.searchpolicy.R4SearchPolicyCore.calculateUsesConstraints(R4SearchPolicyCore.java:1424)
>  at
>
> org.apache.felix.framework.searchpolicy.R4SearchPolicyCore.calculateUsesConstraints(R4SearchPolicyCore.java:1424)
>  at
>
> org.apache.felix.framework.searchpolicy.R4SearchPolicyCore.calculateUsesConstraints(R4SearchPolicyCore.java:1424)
>  at
>
> org.apache.felix.framework.searchpolicy.R4SearchPolicyCore.calculateUsesConstraints(R4SearchPolicyCore.java:1376)
>  at
>
> org.apache.felix.framework.searchpolicy.R4SearchPolicyCore.isClassSpaceConsistent(R4SearchPolicyCore.java:1278)
>  at
>
> org.apache.felix.framework.searchpolicy.R4SearchPolicyCore.isClassSpaceConsistent(R4SearchPolicyCore.java:1266)
>  at
>
> org.apache.felix.framework.searchpolicy.R4SearchPolicyCore.isClassSpaceConsistent(R4SearchPolicyCore.java:1266)
>  at
>
> org.apache.felix.framework.searchpolicy.R4SearchPolicyCore.findConsistentClassSpace(R4SearchPolicyCore.java:1198)
>  at
>
> org.apache.felix.framework.searchpolicy.R4SearchPolicyCore.resolve(R4SearchPolicyCore.java:1028)
>  - locked <0x94577718> (a
> org.apache.felix.moduleloader.ModuleFactoryImpl)
>  at org.apache.felix.framework.Felix._resolveBundle(Felix.java:1705)
>  at org.apache.felix.framework.Felix._startBundle(Felix.java:1579)
>  at org.apache.felix.framework.Felix.startBundle(Felix.java:1532)
>  at
> org.apache.felix.framework.Felix.setFrameworkStartLevel(Felix.java:1116)
>
>  at
> org.apache.felix.framework.StartLevelImpl.run(StartLevelImpl.java:258)
>
>  at java.lang.Thread.run(Thread.java:619)
>
>  "FelixDispatchQueue" prio=10 tid=0x081a3800 nid=0x3f7f in
> Object.wait() [0x90371000..0x90371fc0]
>  java.lang.Thread.State: WAITING (on object monitor)
>  at java.lang.Object.wait(Native Method)
>  - waiting on <0x94570248> (a java.util.ArrayList)
>  at java.lang.Object.wait(Object.java:485)
>  at
>
> org.apache.felix.framework.util.EventDispatcher.run(EventDispatcher.java:794)
>
>  - locked <0x94570248> (a java.util.ArrayList)
>  at
>
> org.apache.felix.framework.util.EventDispatcher.access$000(EventDispatcher.java:42)
>
>  at
>
> org.apache.felix.framework.util.EventDispatcher$1.run(EventDispatcher.java:94)
>
>  at java.lang.Thread.run(Thread.java:619)
>
>  "RMI TCP Accept-0" daemon prio=10 tid=0x08187000 nid=0x3f7d runnable
> [0x90443000..0x904440c0]
>  java.lang.Thread.State: RUNNABLE
>  at java.net.PlainSocketImpl.socketAccept(Native Method)
>  at java.net.PlainSocketImpl.accept(PlainSocketImpl.java:384)
>  - locked <0x94570300> (a java.net.SocksSocketImpl)
>  at java.net.ServerSocket.implAccept(ServerSocket.java:450)
>  at java.net.ServerSocket.accept(ServerSocket.java:421)
>  at
>
> sun.rmi.transport.tcp.TCPTransport$AcceptLoop.executeAcceptLoop(TCPTransport.java:369)
>
>  at
> sun.rmi.transport.tcp.TCPTransport$AcceptLoop.run(TCPTransport.java:341)
>
>  at java.lang.Thread.run(Thread.java:619)
>
>  "Low Memory Detector" daemon prio=10 tid=0x08102000 nid=0x3f7c
> runnable [0x00000000..0x00000000]
>  java.lang.Thread.State: RUNNABLE
>
>  "CompilerThread1" daemon prio=10 tid=0x08100800 nid=0x3f7b waiting
> on condition [0x00000000..0x9052f808]
>  java.lang.Thread.State: RUNNABLE
>
>  "CompilerThread0" daemon prio=10 tid=0x080ff400 nid=0x3f7a waiting
> on condition [0x00000000..0x905b0758]
>  java.lang.Thread.State: RUNNABLE
>
>  "Signal Dispatcher" daemon prio=10 tid=0x080fe000 nid=0x3f79 waiting
> on condition [0x00000000..0x00000000]
>  java.lang.Thread.State: RUNNABLE
>
>  "Finalizer" daemon prio=10 tid=0x080eb000 nid=0x3f78 in
> Object.wait() [0x907eb000..0x907ebe40]
>  java.lang.Thread.State: WAITING (on object monitor)
>  at java.lang.Object.wait(Native Method)
>  - waiting on <0x94570670> (a java.lang.ref.ReferenceQueue$Lock)
>  at java.lang.ref.ReferenceQueue.remove(ReferenceQueue.java:116)
>  - locked <0x94570670> (a java.lang.ref.ReferenceQueue$Lock)
>  at java.lang.ref.ReferenceQueue.remove(ReferenceQueue.java:132)
>  at java.lang.ref.Finalizer$FinalizerThread.run(Finalizer.java:159)
>
>  "Reference Handler" daemon prio=10 tid=0x080ea800 nid=0x3f77 in
> Object.wait() [0x9083c000..0x9083cfc0]
>  java.lang.Thread.State: WAITING (on object monitor)
>  at java.lang.Object.wait(Native Method)
>  - waiting on <0x94570700> (a java.lang.ref.Reference$Lock)
>  at java.lang.Object.wait(Object.java:485)
>  at java.lang.ref.Reference$ReferenceHandler.run(Reference.java:116)
>  - locked <0x94570700> (a java.lang.ref.Reference$Lock)
>
>  "main" prio=10 tid=0x08058c00 nid=0x3f75 in Object.wait()
> [0xb7e25000..0xb7e26298]
>  java.lang.Thread.State: WAITING (on object monitor)
>  at java.lang.Object.wait(Native Method)
>  - waiting on <0x94836528> (a java.lang.Integer)
>  at java.lang.Object.wait(Object.java:485)
>  at
>
> org.apache.felix.framework.StartLevelImpl.setStartLevelAndWait(StartLevelImpl.java:136)
>
>  - locked <0x94836528> (a java.lang.Integer)
>  at org.apache.felix.framework.Felix.start(Felix.java:865)
>  - locked <0x94570738> (a org.apache.felix.framework.Felix)
>  at org.apache.servicemix.kernel.main.Main.main(Main.java:257)
>
>  "VM Thread" prio=10 tid=0x080e7c00 nid=0x3f76 runnable
>
>  "VM Periodic Task Thread" prio=10 tid=0x08191800 nid=0x3f7e waiting
> on condition
>
>  JNI global references: 1127
>
>  Heap
>  def new generation total 13120K, used 11535K [0x90c90000,
> 0x91ac0000, 0x94570000)
>  eden space 11712K, 96% used [0x90c90000, 0x9179f4f8, 0x91800000)
>  from space 1408K, 14% used [0x91960000, 0x91994758, 0x91ac0000)
>  to space 1408K, 0% used [0x91800000, 0x91800000, 0x91960000)
>  tenured generation total 116544K, used 3215K [0x94570000,
> 0x9b740000, 0xb0c90000)
>  the space 116544K, 2% used [0x94570000, 0x94893fa8, 0x94894000,
> 0x9b740000)
>  compacting perm gen total 16384K, used 6454K [0xb0c90000,
> 0xb1c90000, 0xb4c90000)
>  the space 16384K, 39% used [0xb0c90000, 0xb12dd9a8, 0xb12dda00,
> 0xb1c90000)
>  No shared spaces configured.
>
>  I have tried to increase the heap size to 1024m (-Xmx1024m) and
> define the MaxPermSize to 128m (-XX:MaxPermSize=128m) but the behavior
> is quite the same.
>
>  Do you have an idea of the cause of the problem ?
>
>  Thanks,
>  JB
>  --
>  Jean-Baptiste Onofré
>  [hidden email]
>  BuildProcess/AutoDeploy Project Leader
>  http://buildprocess.sourceforge.net
>
>
Reply | Threaded
Open this post in threaded view
|

Re: SMX4 startup stuck

Jean-Baptiste Onofré
Thanks Chris,

I downgrade to JDK 1.5.

Sorry I haven't found any JIRA about this (my filter is maybe not correct).

Regards
JB

On Monday 03 November 2008 - 10:25, Chris Custine wrote:

> I recall this happening at times with JDK 1.6 on Linux, so you might try 1.5
> until we get a newer release out there.
>
> Thanks,
> Chris
>
> --
> Chris Custine
> My Blog :: http://blog.organicelement.com
> Apache ServiceMix :: http://servicemix.apache.org
> Apache Directory Server :: http://directory.apache.org
>
>
> On Mon, Nov 3, 2008 at 9:59 AM, Jean-Baptiste Onofré <[hidden email]>wrote:
>
> >  Hi all,
> >
> >  using ServiceMix 4.0-M1, most of the time (not always), when I start
> > ServiceMix using the servicemix script in the bin directory, it takes
> > 100% of my CPU and do nothing while I haven't kill the process.
> >
> >  My environment is :
> >  - Linux Debian
> >  - Kernel : 2.6.25
> >  - JVM : 1.6.0_01 (Sun)
> >
> >  I have took a ThreadDump :
> >
> >  [FelixStartLevel] INFO org.ops4j.pax.logging.internal.Activator -
> > Enabling SLF4J API support.
> >  [FelixDispatchQueue] INFO org.ops4j.pax.logging.pax-logging-service
> > - BundleEvent STARTED
> >  [FelixStartLevel] INFO org.ops4j.pax.logging.internal.Activator -
> > Enabling Jakarta Commons Logging API support.
> >  [FelixStartLevel] INFO org.ops4j.pax.logging.internal.Activator -
> > Enabling Log4J API support.
> >  2008-11-03 17:41:59
> >  Full thread dump Java HotSpot(TM) Server VM (1.6.0_01-b06 mixed
> > mode):
> >
> >  "Timer-0" daemon prio=10 tid=0x8fd98c00 nid=0x3f83 in Object.wait()
> > [0x8fbf9000..0x8fbf91c0]
> >  java.lang.Thread.State: TIMED_WAITING (on object monitor)
> >  at java.lang.Object.wait(Native Method)
> >  - waiting on <0x94836228> (a java.util.TaskQueue)
> >  at java.util.TimerThread.mainLoop(Timer.java:509)
> >  - locked <0x94836228> (a java.util.TaskQueue)
> >  at java.util.TimerThread.run(Timer.java:462)
> >
> >  "Configuration Updater" daemon prio=10 tid=0x8fffa800 nid=0x3f82 in
> > Object.wait() [0x8fe5a000..0x8fe5a140]
> >  java.lang.Thread.State: WAITING (on object monitor)
> >  at java.lang.Object.wait(Native Method)
> >  - waiting on <0x948362e0> (a java.util.LinkedList)
> >  at java.lang.Object.wait(Object.java:485)
> >  at org.apache.felix.cm.impl.UpdateThread.run(UpdateThread.java:69)
> >  - locked <0x948362e0> (a java.util.LinkedList)
> >
> >  "FelixPackageAdmin" daemon prio=10 tid=0x082a3000 nid=0x3f81 in
> > Object.wait() [0x902cf000..0x902cfec0]
> >  java.lang.Thread.State: WAITING (on object monitor)
> >  at java.lang.Object.wait(Native Method)
> >  - waiting on <0x94570118> (a
> > org.apache.felix.framework.PackageAdminImpl)
> >  at java.lang.Object.wait(Object.java:485)
> >  at
> > org.apache.felix.framework.PackageAdminImpl.run(PackageAdminImpl.java:247)
> >  - locked <0x94570118> (a
> > org.apache.felix.framework.PackageAdminImpl)
> >  at java.lang.Thread.run(Thread.java:619)
> >
> >  "FelixStartLevel" daemon prio=10 tid=0x0827ec00 nid=0x3f80 runnable
> > [0x90320000..0x90320e40]
> >  java.lang.Thread.State: RUNNABLE
> >  at
> >
> > org.apache.felix.framework.searchpolicy.R4SearchPolicyCore.calculateUsesConstraints(R4SearchPolicyCore.java:1432)
> >  at
> >
> > org.apache.felix.framework.searchpolicy.R4SearchPolicyCore.calculateUsesConstraints(R4SearchPolicyCore.java:1424)
> >  at
> >
> > org.apache.felix.framework.searchpolicy.R4SearchPolicyCore.calculateUsesConstraints(R4SearchPolicyCore.java:1424)
> >  at
> >
> > org.apache.felix.framework.searchpolicy.R4SearchPolicyCore.calculateUsesConstraints(R4SearchPolicyCore.java:1424)
> >  at
> >
> > org.apache.felix.framework.searchpolicy.R4SearchPolicyCore.calculateUsesConstraints(R4SearchPolicyCore.java:1376)
> >  at
> >
> > org.apache.felix.framework.searchpolicy.R4SearchPolicyCore.isClassSpaceConsistent(R4SearchPolicyCore.java:1278)
> >  at
> >
> > org.apache.felix.framework.searchpolicy.R4SearchPolicyCore.isClassSpaceConsistent(R4SearchPolicyCore.java:1266)
> >  at
> >
> > org.apache.felix.framework.searchpolicy.R4SearchPolicyCore.isClassSpaceConsistent(R4SearchPolicyCore.java:1266)
> >  at
> >
> > org.apache.felix.framework.searchpolicy.R4SearchPolicyCore.findConsistentClassSpace(R4SearchPolicyCore.java:1198)
> >  at
> >
> > org.apache.felix.framework.searchpolicy.R4SearchPolicyCore.resolve(R4SearchPolicyCore.java:1028)
> >  - locked <0x94577718> (a
> > org.apache.felix.moduleloader.ModuleFactoryImpl)
> >  at org.apache.felix.framework.Felix._resolveBundle(Felix.java:1705)
> >  at org.apache.felix.framework.Felix._startBundle(Felix.java:1579)
> >  at org.apache.felix.framework.Felix.startBundle(Felix.java:1532)
> >  at
> > org.apache.felix.framework.Felix.setFrameworkStartLevel(Felix.java:1116)
> >
> >  at
> > org.apache.felix.framework.StartLevelImpl.run(StartLevelImpl.java:258)
> >
> >  at java.lang.Thread.run(Thread.java:619)
> >
> >  "FelixDispatchQueue" prio=10 tid=0x081a3800 nid=0x3f7f in
> > Object.wait() [0x90371000..0x90371fc0]
> >  java.lang.Thread.State: WAITING (on object monitor)
> >  at java.lang.Object.wait(Native Method)
> >  - waiting on <0x94570248> (a java.util.ArrayList)
> >  at java.lang.Object.wait(Object.java:485)
> >  at
> >
> > org.apache.felix.framework.util.EventDispatcher.run(EventDispatcher.java:794)
> >
> >  - locked <0x94570248> (a java.util.ArrayList)
> >  at
> >
> > org.apache.felix.framework.util.EventDispatcher.access$000(EventDispatcher.java:42)
> >
> >  at
> >
> > org.apache.felix.framework.util.EventDispatcher$1.run(EventDispatcher.java:94)
> >
> >  at java.lang.Thread.run(Thread.java:619)
> >
> >  "RMI TCP Accept-0" daemon prio=10 tid=0x08187000 nid=0x3f7d runnable
> > [0x90443000..0x904440c0]
> >  java.lang.Thread.State: RUNNABLE
> >  at java.net.PlainSocketImpl.socketAccept(Native Method)
> >  at java.net.PlainSocketImpl.accept(PlainSocketImpl.java:384)
> >  - locked <0x94570300> (a java.net.SocksSocketImpl)
> >  at java.net.ServerSocket.implAccept(ServerSocket.java:450)
> >  at java.net.ServerSocket.accept(ServerSocket.java:421)
> >  at
> >
> > sun.rmi.transport.tcp.TCPTransport$AcceptLoop.executeAcceptLoop(TCPTransport.java:369)
> >
> >  at
> > sun.rmi.transport.tcp.TCPTransport$AcceptLoop.run(TCPTransport.java:341)
> >
> >  at java.lang.Thread.run(Thread.java:619)
> >
> >  "Low Memory Detector" daemon prio=10 tid=0x08102000 nid=0x3f7c
> > runnable [0x00000000..0x00000000]
> >  java.lang.Thread.State: RUNNABLE
> >
> >  "CompilerThread1" daemon prio=10 tid=0x08100800 nid=0x3f7b waiting
> > on condition [0x00000000..0x9052f808]
> >  java.lang.Thread.State: RUNNABLE
> >
> >  "CompilerThread0" daemon prio=10 tid=0x080ff400 nid=0x3f7a waiting
> > on condition [0x00000000..0x905b0758]
> >  java.lang.Thread.State: RUNNABLE
> >
> >  "Signal Dispatcher" daemon prio=10 tid=0x080fe000 nid=0x3f79 waiting
> > on condition [0x00000000..0x00000000]
> >  java.lang.Thread.State: RUNNABLE
> >
> >  "Finalizer" daemon prio=10 tid=0x080eb000 nid=0x3f78 in
> > Object.wait() [0x907eb000..0x907ebe40]
> >  java.lang.Thread.State: WAITING (on object monitor)
> >  at java.lang.Object.wait(Native Method)
> >  - waiting on <0x94570670> (a java.lang.ref.ReferenceQueue$Lock)
> >  at java.lang.ref.ReferenceQueue.remove(ReferenceQueue.java:116)
> >  - locked <0x94570670> (a java.lang.ref.ReferenceQueue$Lock)
> >  at java.lang.ref.ReferenceQueue.remove(ReferenceQueue.java:132)
> >  at java.lang.ref.Finalizer$FinalizerThread.run(Finalizer.java:159)
> >
> >  "Reference Handler" daemon prio=10 tid=0x080ea800 nid=0x3f77 in
> > Object.wait() [0x9083c000..0x9083cfc0]
> >  java.lang.Thread.State: WAITING (on object monitor)
> >  at java.lang.Object.wait(Native Method)
> >  - waiting on <0x94570700> (a java.lang.ref.Reference$Lock)
> >  at java.lang.Object.wait(Object.java:485)
> >  at java.lang.ref.Reference$ReferenceHandler.run(Reference.java:116)
> >  - locked <0x94570700> (a java.lang.ref.Reference$Lock)
> >
> >  "main" prio=10 tid=0x08058c00 nid=0x3f75 in Object.wait()
> > [0xb7e25000..0xb7e26298]
> >  java.lang.Thread.State: WAITING (on object monitor)
> >  at java.lang.Object.wait(Native Method)
> >  - waiting on <0x94836528> (a java.lang.Integer)
> >  at java.lang.Object.wait(Object.java:485)
> >  at
> >
> > org.apache.felix.framework.StartLevelImpl.setStartLevelAndWait(StartLevelImpl.java:136)
> >
> >  - locked <0x94836528> (a java.lang.Integer)
> >  at org.apache.felix.framework.Felix.start(Felix.java:865)
> >  - locked <0x94570738> (a org.apache.felix.framework.Felix)
> >  at org.apache.servicemix.kernel.main.Main.main(Main.java:257)
> >
> >  "VM Thread" prio=10 tid=0x080e7c00 nid=0x3f76 runnable
> >
> >  "VM Periodic Task Thread" prio=10 tid=0x08191800 nid=0x3f7e waiting
> > on condition
> >
> >  JNI global references: 1127
> >
> >  Heap
> >  def new generation total 13120K, used 11535K [0x90c90000,
> > 0x91ac0000, 0x94570000)
> >  eden space 11712K, 96% used [0x90c90000, 0x9179f4f8, 0x91800000)
> >  from space 1408K, 14% used [0x91960000, 0x91994758, 0x91ac0000)
> >  to space 1408K, 0% used [0x91800000, 0x91800000, 0x91960000)
> >  tenured generation total 116544K, used 3215K [0x94570000,
> > 0x9b740000, 0xb0c90000)
> >  the space 116544K, 2% used [0x94570000, 0x94893fa8, 0x94894000,
> > 0x9b740000)
> >  compacting perm gen total 16384K, used 6454K [0xb0c90000,
> > 0xb1c90000, 0xb4c90000)
> >  the space 16384K, 39% used [0xb0c90000, 0xb12dd9a8, 0xb12dda00,
> > 0xb1c90000)
> >  No shared spaces configured.
> >
> >  I have tried to increase the heap size to 1024m (-Xmx1024m) and
> > define the MaxPermSize to 128m (-XX:MaxPermSize=128m) but the behavior
> > is quite the same.
> >
> >  Do you have an idea of the cause of the problem ?
> >
> >  Thanks,
> >  JB
> >  --
> >  Jean-Baptiste Onofré
> >  [hidden email]
> >  BuildProcess/AutoDeploy Project Leader
> >  http://buildprocess.sourceforge.net
> >
> >

--
Jean-Baptiste Onofré (Nanthrax)
BuildProcess/AutoDeploy Project Leader
http://buildprocess.sourceforge.net
[hidden email]
PGP : 17D4F086
Reply | Threaded
Open this post in threaded view
|

Re: SMX4 startup stuck

Guillaume Nodet
Administrator
IIRC, there was a problem with the servicemix osgi enhanced spec that
caused a deadlock at some point.
Can you try with a m2-SNAPSHOT to see if you can reproduce the problem ?

On Mon, Nov 3, 2008 at 7:24 PM, Jean-Baptiste Onofré <[hidden email]> wrote:

> Thanks Chris,
>
> I downgrade to JDK 1.5.
>
> Sorry I haven't found any JIRA about this (my filter is maybe not correct).
>
> Regards
> JB
>
> On Monday 03 November 2008 - 10:25, Chris Custine wrote:
>> I recall this happening at times with JDK 1.6 on Linux, so you might try 1.5
>> until we get a newer release out there.
>>
>> Thanks,
>> Chris
>>
>> --
>> Chris Custine
>> My Blog :: http://blog.organicelement.com
>> Apache ServiceMix :: http://servicemix.apache.org
>> Apache Directory Server :: http://directory.apache.org
>>
>>
>> On Mon, Nov 3, 2008 at 9:59 AM, Jean-Baptiste Onofré <[hidden email]>wrote:
>>
>> >  Hi all,
>> >
>> >  using ServiceMix 4.0-M1, most of the time (not always), when I start
>> > ServiceMix using the servicemix script in the bin directory, it takes
>> > 100% of my CPU and do nothing while I haven't kill the process.
>> >
>> >  My environment is :
>> >  - Linux Debian
>> >  - Kernel : 2.6.25
>> >  - JVM : 1.6.0_01 (Sun)
>> >
>> >  I have took a ThreadDump :
>> >
>> >  [FelixStartLevel] INFO org.ops4j.pax.logging.internal.Activator -
>> > Enabling SLF4J API support.
>> >  [FelixDispatchQueue] INFO org.ops4j.pax.logging.pax-logging-service
>> > - BundleEvent STARTED
>> >  [FelixStartLevel] INFO org.ops4j.pax.logging.internal.Activator -
>> > Enabling Jakarta Commons Logging API support.
>> >  [FelixStartLevel] INFO org.ops4j.pax.logging.internal.Activator -
>> > Enabling Log4J API support.
>> >  2008-11-03 17:41:59
>> >  Full thread dump Java HotSpot(TM) Server VM (1.6.0_01-b06 mixed
>> > mode):
>> >
>> >  "Timer-0" daemon prio=10 tid=0x8fd98c00 nid=0x3f83 in Object.wait()
>> > [0x8fbf9000..0x8fbf91c0]
>> >  java.lang.Thread.State: TIMED_WAITING (on object monitor)
>> >  at java.lang.Object.wait(Native Method)
>> >  - waiting on <0x94836228> (a java.util.TaskQueue)
>> >  at java.util.TimerThread.mainLoop(Timer.java:509)
>> >  - locked <0x94836228> (a java.util.TaskQueue)
>> >  at java.util.TimerThread.run(Timer.java:462)
>> >
>> >  "Configuration Updater" daemon prio=10 tid=0x8fffa800 nid=0x3f82 in
>> > Object.wait() [0x8fe5a000..0x8fe5a140]
>> >  java.lang.Thread.State: WAITING (on object monitor)
>> >  at java.lang.Object.wait(Native Method)
>> >  - waiting on <0x948362e0> (a java.util.LinkedList)
>> >  at java.lang.Object.wait(Object.java:485)
>> >  at org.apache.felix.cm.impl.UpdateThread.run(UpdateThread.java:69)
>> >  - locked <0x948362e0> (a java.util.LinkedList)
>> >
>> >  "FelixPackageAdmin" daemon prio=10 tid=0x082a3000 nid=0x3f81 in
>> > Object.wait() [0x902cf000..0x902cfec0]
>> >  java.lang.Thread.State: WAITING (on object monitor)
>> >  at java.lang.Object.wait(Native Method)
>> >  - waiting on <0x94570118> (a
>> > org.apache.felix.framework.PackageAdminImpl)
>> >  at java.lang.Object.wait(Object.java:485)
>> >  at
>> > org.apache.felix.framework.PackageAdminImpl.run(PackageAdminImpl.java:247)
>> >  - locked <0x94570118> (a
>> > org.apache.felix.framework.PackageAdminImpl)
>> >  at java.lang.Thread.run(Thread.java:619)
>> >
>> >  "FelixStartLevel" daemon prio=10 tid=0x0827ec00 nid=0x3f80 runnable
>> > [0x90320000..0x90320e40]
>> >  java.lang.Thread.State: RUNNABLE
>> >  at
>> >
>> > org.apache.felix.framework.searchpolicy.R4SearchPolicyCore.calculateUsesConstraints(R4SearchPolicyCore.java:1432)
>> >  at
>> >
>> > org.apache.felix.framework.searchpolicy.R4SearchPolicyCore.calculateUsesConstraints(R4SearchPolicyCore.java:1424)
>> >  at
>> >
>> > org.apache.felix.framework.searchpolicy.R4SearchPolicyCore.calculateUsesConstraints(R4SearchPolicyCore.java:1424)
>> >  at
>> >
>> > org.apache.felix.framework.searchpolicy.R4SearchPolicyCore.calculateUsesConstraints(R4SearchPolicyCore.java:1424)
>> >  at
>> >
>> > org.apache.felix.framework.searchpolicy.R4SearchPolicyCore.calculateUsesConstraints(R4SearchPolicyCore.java:1376)
>> >  at
>> >
>> > org.apache.felix.framework.searchpolicy.R4SearchPolicyCore.isClassSpaceConsistent(R4SearchPolicyCore.java:1278)
>> >  at
>> >
>> > org.apache.felix.framework.searchpolicy.R4SearchPolicyCore.isClassSpaceConsistent(R4SearchPolicyCore.java:1266)
>> >  at
>> >
>> > org.apache.felix.framework.searchpolicy.R4SearchPolicyCore.isClassSpaceConsistent(R4SearchPolicyCore.java:1266)
>> >  at
>> >
>> > org.apache.felix.framework.searchpolicy.R4SearchPolicyCore.findConsistentClassSpace(R4SearchPolicyCore.java:1198)
>> >  at
>> >
>> > org.apache.felix.framework.searchpolicy.R4SearchPolicyCore.resolve(R4SearchPolicyCore.java:1028)
>> >  - locked <0x94577718> (a
>> > org.apache.felix.moduleloader.ModuleFactoryImpl)
>> >  at org.apache.felix.framework.Felix._resolveBundle(Felix.java:1705)
>> >  at org.apache.felix.framework.Felix._startBundle(Felix.java:1579)
>> >  at org.apache.felix.framework.Felix.startBundle(Felix.java:1532)
>> >  at
>> > org.apache.felix.framework.Felix.setFrameworkStartLevel(Felix.java:1116)
>> >
>> >  at
>> > org.apache.felix.framework.StartLevelImpl.run(StartLevelImpl.java:258)
>> >
>> >  at java.lang.Thread.run(Thread.java:619)
>> >
>> >  "FelixDispatchQueue" prio=10 tid=0x081a3800 nid=0x3f7f in
>> > Object.wait() [0x90371000..0x90371fc0]
>> >  java.lang.Thread.State: WAITING (on object monitor)
>> >  at java.lang.Object.wait(Native Method)
>> >  - waiting on <0x94570248> (a java.util.ArrayList)
>> >  at java.lang.Object.wait(Object.java:485)
>> >  at
>> >
>> > org.apache.felix.framework.util.EventDispatcher.run(EventDispatcher.java:794)
>> >
>> >  - locked <0x94570248> (a java.util.ArrayList)
>> >  at
>> >
>> > org.apache.felix.framework.util.EventDispatcher.access$000(EventDispatcher.java:42)
>> >
>> >  at
>> >
>> > org.apache.felix.framework.util.EventDispatcher$1.run(EventDispatcher.java:94)
>> >
>> >  at java.lang.Thread.run(Thread.java:619)
>> >
>> >  "RMI TCP Accept-0" daemon prio=10 tid=0x08187000 nid=0x3f7d runnable
>> > [0x90443000..0x904440c0]
>> >  java.lang.Thread.State: RUNNABLE
>> >  at java.net.PlainSocketImpl.socketAccept(Native Method)
>> >  at java.net.PlainSocketImpl.accept(PlainSocketImpl.java:384)
>> >  - locked <0x94570300> (a java.net.SocksSocketImpl)
>> >  at java.net.ServerSocket.implAccept(ServerSocket.java:450)
>> >  at java.net.ServerSocket.accept(ServerSocket.java:421)
>> >  at
>> >
>> > sun.rmi.transport.tcp.TCPTransport$AcceptLoop.executeAcceptLoop(TCPTransport.java:369)
>> >
>> >  at
>> > sun.rmi.transport.tcp.TCPTransport$AcceptLoop.run(TCPTransport.java:341)
>> >
>> >  at java.lang.Thread.run(Thread.java:619)
>> >
>> >  "Low Memory Detector" daemon prio=10 tid=0x08102000 nid=0x3f7c
>> > runnable [0x00000000..0x00000000]
>> >  java.lang.Thread.State: RUNNABLE
>> >
>> >  "CompilerThread1" daemon prio=10 tid=0x08100800 nid=0x3f7b waiting
>> > on condition [0x00000000..0x9052f808]
>> >  java.lang.Thread.State: RUNNABLE
>> >
>> >  "CompilerThread0" daemon prio=10 tid=0x080ff400 nid=0x3f7a waiting
>> > on condition [0x00000000..0x905b0758]
>> >  java.lang.Thread.State: RUNNABLE
>> >
>> >  "Signal Dispatcher" daemon prio=10 tid=0x080fe000 nid=0x3f79 waiting
>> > on condition [0x00000000..0x00000000]
>> >  java.lang.Thread.State: RUNNABLE
>> >
>> >  "Finalizer" daemon prio=10 tid=0x080eb000 nid=0x3f78 in
>> > Object.wait() [0x907eb000..0x907ebe40]
>> >  java.lang.Thread.State: WAITING (on object monitor)
>> >  at java.lang.Object.wait(Native Method)
>> >  - waiting on <0x94570670> (a java.lang.ref.ReferenceQueue$Lock)
>> >  at java.lang.ref.ReferenceQueue.remove(ReferenceQueue.java:116)
>> >  - locked <0x94570670> (a java.lang.ref.ReferenceQueue$Lock)
>> >  at java.lang.ref.ReferenceQueue.remove(ReferenceQueue.java:132)
>> >  at java.lang.ref.Finalizer$FinalizerThread.run(Finalizer.java:159)
>> >
>> >  "Reference Handler" daemon prio=10 tid=0x080ea800 nid=0x3f77 in
>> > Object.wait() [0x9083c000..0x9083cfc0]
>> >  java.lang.Thread.State: WAITING (on object monitor)
>> >  at java.lang.Object.wait(Native Method)
>> >  - waiting on <0x94570700> (a java.lang.ref.Reference$Lock)
>> >  at java.lang.Object.wait(Object.java:485)
>> >  at java.lang.ref.Reference$ReferenceHandler.run(Reference.java:116)
>> >  - locked <0x94570700> (a java.lang.ref.Reference$Lock)
>> >
>> >  "main" prio=10 tid=0x08058c00 nid=0x3f75 in Object.wait()
>> > [0xb7e25000..0xb7e26298]
>> >  java.lang.Thread.State: WAITING (on object monitor)
>> >  at java.lang.Object.wait(Native Method)
>> >  - waiting on <0x94836528> (a java.lang.Integer)
>> >  at java.lang.Object.wait(Object.java:485)
>> >  at
>> >
>> > org.apache.felix.framework.StartLevelImpl.setStartLevelAndWait(StartLevelImpl.java:136)
>> >
>> >  - locked <0x94836528> (a java.lang.Integer)
>> >  at org.apache.felix.framework.Felix.start(Felix.java:865)
>> >  - locked <0x94570738> (a org.apache.felix.framework.Felix)
>> >  at org.apache.servicemix.kernel.main.Main.main(Main.java:257)
>> >
>> >  "VM Thread" prio=10 tid=0x080e7c00 nid=0x3f76 runnable
>> >
>> >  "VM Periodic Task Thread" prio=10 tid=0x08191800 nid=0x3f7e waiting
>> > on condition
>> >
>> >  JNI global references: 1127
>> >
>> >  Heap
>> >  def new generation total 13120K, used 11535K [0x90c90000,
>> > 0x91ac0000, 0x94570000)
>> >  eden space 11712K, 96% used [0x90c90000, 0x9179f4f8, 0x91800000)
>> >  from space 1408K, 14% used [0x91960000, 0x91994758, 0x91ac0000)
>> >  to space 1408K, 0% used [0x91800000, 0x91800000, 0x91960000)
>> >  tenured generation total 116544K, used 3215K [0x94570000,
>> > 0x9b740000, 0xb0c90000)
>> >  the space 116544K, 2% used [0x94570000, 0x94893fa8, 0x94894000,
>> > 0x9b740000)
>> >  compacting perm gen total 16384K, used 6454K [0xb0c90000,
>> > 0xb1c90000, 0xb4c90000)
>> >  the space 16384K, 39% used [0xb0c90000, 0xb12dd9a8, 0xb12dda00,
>> > 0xb1c90000)
>> >  No shared spaces configured.
>> >
>> >  I have tried to increase the heap size to 1024m (-Xmx1024m) and
>> > define the MaxPermSize to 128m (-XX:MaxPermSize=128m) but the behavior
>> > is quite the same.
>> >
>> >  Do you have an idea of the cause of the problem ?
>> >
>> >  Thanks,
>> >  JB
>> >  --
>> >  Jean-Baptiste Onofré
>> >  [hidden email]
>> >  BuildProcess/AutoDeploy Project Leader
>> >  http://buildprocess.sourceforge.net
>> >
>> >
>
> --
> Jean-Baptiste Onofré (Nanthrax)
> BuildProcess/AutoDeploy Project Leader
> http://buildprocess.sourceforge.net
> [hidden email]
> PGP : 17D4F086
>



--
Cheers,
Guillaume Nodet
------------------------
Blog: http://gnodet.blogspot.com/
------------------------
Open Source SOA
http://fusesource.com
Reply | Threaded
Open this post in threaded view
|

Re: SMX4 startup stuck

Jean-Baptiste Onofré
Ok Guillaume

I make a new test using m2-snapshot release.

Keep you posted.

Regards

On Monday 03 November 2008 - 20:37, Guillaume Nodet wrote:

> IIRC, there was a problem with the servicemix osgi enhanced spec that
> caused a deadlock at some point.
> Can you try with a m2-SNAPSHOT to see if you can reproduce the problem ?
>
> On Mon, Nov 3, 2008 at 7:24 PM, Jean-Baptiste Onofré <[hidden email]> wrote:
> > Thanks Chris,
> >
> > I downgrade to JDK 1.5.
> >
> > Sorry I haven't found any JIRA about this (my filter is maybe not correct).
> >
> > Regards
> > JB
> >
> > On Monday 03 November 2008 - 10:25, Chris Custine wrote:
> >> I recall this happening at times with JDK 1.6 on Linux, so you might try 1.5
> >> until we get a newer release out there.
> >>
> >> Thanks,
> >> Chris
> >>
> >> --
> >> Chris Custine
> >> My Blog :: http://blog.organicelement.com
> >> Apache ServiceMix :: http://servicemix.apache.org
> >> Apache Directory Server :: http://directory.apache.org
> >>
> >>
> >> On Mon, Nov 3, 2008 at 9:59 AM, Jean-Baptiste Onofré <[hidden email]>wrote:
> >>
> >> >  Hi all,
> >> >
> >> >  using ServiceMix 4.0-M1, most of the time (not always), when I start
> >> > ServiceMix using the servicemix script in the bin directory, it takes
> >> > 100% of my CPU and do nothing while I haven't kill the process.
> >> >
> >> >  My environment is :
> >> >  - Linux Debian
> >> >  - Kernel : 2.6.25
> >> >  - JVM : 1.6.0_01 (Sun)
> >> >
> >> >  I have took a ThreadDump :
> >> >
> >> >  [FelixStartLevel] INFO org.ops4j.pax.logging.internal.Activator -
> >> > Enabling SLF4J API support.
> >> >  [FelixDispatchQueue] INFO org.ops4j.pax.logging.pax-logging-service
> >> > - BundleEvent STARTED
> >> >  [FelixStartLevel] INFO org.ops4j.pax.logging.internal.Activator -
> >> > Enabling Jakarta Commons Logging API support.
> >> >  [FelixStartLevel] INFO org.ops4j.pax.logging.internal.Activator -
> >> > Enabling Log4J API support.
> >> >  2008-11-03 17:41:59
> >> >  Full thread dump Java HotSpot(TM) Server VM (1.6.0_01-b06 mixed
> >> > mode):
> >> >
> >> >  "Timer-0" daemon prio=10 tid=0x8fd98c00 nid=0x3f83 in Object.wait()
> >> > [0x8fbf9000..0x8fbf91c0]
> >> >  java.lang.Thread.State: TIMED_WAITING (on object monitor)
> >> >  at java.lang.Object.wait(Native Method)
> >> >  - waiting on <0x94836228> (a java.util.TaskQueue)
> >> >  at java.util.TimerThread.mainLoop(Timer.java:509)
> >> >  - locked <0x94836228> (a java.util.TaskQueue)
> >> >  at java.util.TimerThread.run(Timer.java:462)
> >> >
> >> >  "Configuration Updater" daemon prio=10 tid=0x8fffa800 nid=0x3f82 in
> >> > Object.wait() [0x8fe5a000..0x8fe5a140]
> >> >  java.lang.Thread.State: WAITING (on object monitor)
> >> >  at java.lang.Object.wait(Native Method)
> >> >  - waiting on <0x948362e0> (a java.util.LinkedList)
> >> >  at java.lang.Object.wait(Object.java:485)
> >> >  at org.apache.felix.cm.impl.UpdateThread.run(UpdateThread.java:69)
> >> >  - locked <0x948362e0> (a java.util.LinkedList)
> >> >
> >> >  "FelixPackageAdmin" daemon prio=10 tid=0x082a3000 nid=0x3f81 in
> >> > Object.wait() [0x902cf000..0x902cfec0]
> >> >  java.lang.Thread.State: WAITING (on object monitor)
> >> >  at java.lang.Object.wait(Native Method)
> >> >  - waiting on <0x94570118> (a
> >> > org.apache.felix.framework.PackageAdminImpl)
> >> >  at java.lang.Object.wait(Object.java:485)
> >> >  at
> >> > org.apache.felix.framework.PackageAdminImpl.run(PackageAdminImpl.java:247)
> >> >  - locked <0x94570118> (a
> >> > org.apache.felix.framework.PackageAdminImpl)
> >> >  at java.lang.Thread.run(Thread.java:619)
> >> >
> >> >  "FelixStartLevel" daemon prio=10 tid=0x0827ec00 nid=0x3f80 runnable
> >> > [0x90320000..0x90320e40]
> >> >  java.lang.Thread.State: RUNNABLE
> >> >  at
> >> >
> >> > org.apache.felix.framework.searchpolicy.R4SearchPolicyCore.calculateUsesConstraints(R4SearchPolicyCore.java:1432)
> >> >  at
> >> >
> >> > org.apache.felix.framework.searchpolicy.R4SearchPolicyCore.calculateUsesConstraints(R4SearchPolicyCore.java:1424)
> >> >  at
> >> >
> >> > org.apache.felix.framework.searchpolicy.R4SearchPolicyCore.calculateUsesConstraints(R4SearchPolicyCore.java:1424)
> >> >  at
> >> >
> >> > org.apache.felix.framework.searchpolicy.R4SearchPolicyCore.calculateUsesConstraints(R4SearchPolicyCore.java:1424)
> >> >  at
> >> >
> >> > org.apache.felix.framework.searchpolicy.R4SearchPolicyCore.calculateUsesConstraints(R4SearchPolicyCore.java:1376)
> >> >  at
> >> >
> >> > org.apache.felix.framework.searchpolicy.R4SearchPolicyCore.isClassSpaceConsistent(R4SearchPolicyCore.java:1278)
> >> >  at
> >> >
> >> > org.apache.felix.framework.searchpolicy.R4SearchPolicyCore.isClassSpaceConsistent(R4SearchPolicyCore.java:1266)
> >> >  at
> >> >
> >> > org.apache.felix.framework.searchpolicy.R4SearchPolicyCore.isClassSpaceConsistent(R4SearchPolicyCore.java:1266)
> >> >  at
> >> >
> >> > org.apache.felix.framework.searchpolicy.R4SearchPolicyCore.findConsistentClassSpace(R4SearchPolicyCore.java:1198)
> >> >  at
> >> >
> >> > org.apache.felix.framework.searchpolicy.R4SearchPolicyCore.resolve(R4SearchPolicyCore.java:1028)
> >> >  - locked <0x94577718> (a
> >> > org.apache.felix.moduleloader.ModuleFactoryImpl)
> >> >  at org.apache.felix.framework.Felix._resolveBundle(Felix.java:1705)
> >> >  at org.apache.felix.framework.Felix._startBundle(Felix.java:1579)
> >> >  at org.apache.felix.framework.Felix.startBundle(Felix.java:1532)
> >> >  at
> >> > org.apache.felix.framework.Felix.setFrameworkStartLevel(Felix.java:1116)
> >> >
> >> >  at
> >> > org.apache.felix.framework.StartLevelImpl.run(StartLevelImpl.java:258)
> >> >
> >> >  at java.lang.Thread.run(Thread.java:619)
> >> >
> >> >  "FelixDispatchQueue" prio=10 tid=0x081a3800 nid=0x3f7f in
> >> > Object.wait() [0x90371000..0x90371fc0]
> >> >  java.lang.Thread.State: WAITING (on object monitor)
> >> >  at java.lang.Object.wait(Native Method)
> >> >  - waiting on <0x94570248> (a java.util.ArrayList)
> >> >  at java.lang.Object.wait(Object.java:485)
> >> >  at
> >> >
> >> > org.apache.felix.framework.util.EventDispatcher.run(EventDispatcher.java:794)
> >> >
> >> >  - locked <0x94570248> (a java.util.ArrayList)
> >> >  at
> >> >
> >> > org.apache.felix.framework.util.EventDispatcher.access$000(EventDispatcher.java:42)
> >> >
> >> >  at
> >> >
> >> > org.apache.felix.framework.util.EventDispatcher$1.run(EventDispatcher.java:94)
> >> >
> >> >  at java.lang.Thread.run(Thread.java:619)
> >> >
> >> >  "RMI TCP Accept-0" daemon prio=10 tid=0x08187000 nid=0x3f7d runnable
> >> > [0x90443000..0x904440c0]
> >> >  java.lang.Thread.State: RUNNABLE
> >> >  at java.net.PlainSocketImpl.socketAccept(Native Method)
> >> >  at java.net.PlainSocketImpl.accept(PlainSocketImpl.java:384)
> >> >  - locked <0x94570300> (a java.net.SocksSocketImpl)
> >> >  at java.net.ServerSocket.implAccept(ServerSocket.java:450)
> >> >  at java.net.ServerSocket.accept(ServerSocket.java:421)
> >> >  at
> >> >
> >> > sun.rmi.transport.tcp.TCPTransport$AcceptLoop.executeAcceptLoop(TCPTransport.java:369)
> >> >
> >> >  at
> >> > sun.rmi.transport.tcp.TCPTransport$AcceptLoop.run(TCPTransport.java:341)
> >> >
> >> >  at java.lang.Thread.run(Thread.java:619)
> >> >
> >> >  "Low Memory Detector" daemon prio=10 tid=0x08102000 nid=0x3f7c
> >> > runnable [0x00000000..0x00000000]
> >> >  java.lang.Thread.State: RUNNABLE
> >> >
> >> >  "CompilerThread1" daemon prio=10 tid=0x08100800 nid=0x3f7b waiting
> >> > on condition [0x00000000..0x9052f808]
> >> >  java.lang.Thread.State: RUNNABLE
> >> >
> >> >  "CompilerThread0" daemon prio=10 tid=0x080ff400 nid=0x3f7a waiting
> >> > on condition [0x00000000..0x905b0758]
> >> >  java.lang.Thread.State: RUNNABLE
> >> >
> >> >  "Signal Dispatcher" daemon prio=10 tid=0x080fe000 nid=0x3f79 waiting
> >> > on condition [0x00000000..0x00000000]
> >> >  java.lang.Thread.State: RUNNABLE
> >> >
> >> >  "Finalizer" daemon prio=10 tid=0x080eb000 nid=0x3f78 in
> >> > Object.wait() [0x907eb000..0x907ebe40]
> >> >  java.lang.Thread.State: WAITING (on object monitor)
> >> >  at java.lang.Object.wait(Native Method)
> >> >  - waiting on <0x94570670> (a java.lang.ref.ReferenceQueue$Lock)
> >> >  at java.lang.ref.ReferenceQueue.remove(ReferenceQueue.java:116)
> >> >  - locked <0x94570670> (a java.lang.ref.ReferenceQueue$Lock)
> >> >  at java.lang.ref.ReferenceQueue.remove(ReferenceQueue.java:132)
> >> >  at java.lang.ref.Finalizer$FinalizerThread.run(Finalizer.java:159)
> >> >
> >> >  "Reference Handler" daemon prio=10 tid=0x080ea800 nid=0x3f77 in
> >> > Object.wait() [0x9083c000..0x9083cfc0]
> >> >  java.lang.Thread.State: WAITING (on object monitor)
> >> >  at java.lang.Object.wait(Native Method)
> >> >  - waiting on <0x94570700> (a java.lang.ref.Reference$Lock)
> >> >  at java.lang.Object.wait(Object.java:485)
> >> >  at java.lang.ref.Reference$ReferenceHandler.run(Reference.java:116)
> >> >  - locked <0x94570700> (a java.lang.ref.Reference$Lock)
> >> >
> >> >  "main" prio=10 tid=0x08058c00 nid=0x3f75 in Object.wait()
> >> > [0xb7e25000..0xb7e26298]
> >> >  java.lang.Thread.State: WAITING (on object monitor)
> >> >  at java.lang.Object.wait(Native Method)
> >> >  - waiting on <0x94836528> (a java.lang.Integer)
> >> >  at java.lang.Object.wait(Object.java:485)
> >> >  at
> >> >
> >> > org.apache.felix.framework.StartLevelImpl.setStartLevelAndWait(StartLevelImpl.java:136)
> >> >
> >> >  - locked <0x94836528> (a java.lang.Integer)
> >> >  at org.apache.felix.framework.Felix.start(Felix.java:865)
> >> >  - locked <0x94570738> (a org.apache.felix.framework.Felix)
> >> >  at org.apache.servicemix.kernel.main.Main.main(Main.java:257)
> >> >
> >> >  "VM Thread" prio=10 tid=0x080e7c00 nid=0x3f76 runnable
> >> >
> >> >  "VM Periodic Task Thread" prio=10 tid=0x08191800 nid=0x3f7e waiting
> >> > on condition
> >> >
> >> >  JNI global references: 1127
> >> >
> >> >  Heap
> >> >  def new generation total 13120K, used 11535K [0x90c90000,
> >> > 0x91ac0000, 0x94570000)
> >> >  eden space 11712K, 96% used [0x90c90000, 0x9179f4f8, 0x91800000)
> >> >  from space 1408K, 14% used [0x91960000, 0x91994758, 0x91ac0000)
> >> >  to space 1408K, 0% used [0x91800000, 0x91800000, 0x91960000)
> >> >  tenured generation total 116544K, used 3215K [0x94570000,
> >> > 0x9b740000, 0xb0c90000)
> >> >  the space 116544K, 2% used [0x94570000, 0x94893fa8, 0x94894000,
> >> > 0x9b740000)
> >> >  compacting perm gen total 16384K, used 6454K [0xb0c90000,
> >> > 0xb1c90000, 0xb4c90000)
> >> >  the space 16384K, 39% used [0xb0c90000, 0xb12dd9a8, 0xb12dda00,
> >> > 0xb1c90000)
> >> >  No shared spaces configured.
> >> >
> >> >  I have tried to increase the heap size to 1024m (-Xmx1024m) and
> >> > define the MaxPermSize to 128m (-XX:MaxPermSize=128m) but the behavior
> >> > is quite the same.
> >> >
> >> >  Do you have an idea of the cause of the problem ?
> >> >
> >> >  Thanks,
> >> >  JB
> >> >  --
> >> >  Jean-Baptiste Onofré
> >> >  [hidden email]
> >> >  BuildProcess/AutoDeploy Project Leader
> >> >  http://buildprocess.sourceforge.net
> >> >
> >> >
> >
> > --
> > Jean-Baptiste Onofré (Nanthrax)
> > BuildProcess/AutoDeploy Project Leader
> > http://buildprocess.sourceforge.net
> > [hidden email]
> > PGP : 17D4F086
> >
>
>
>
> --
> Cheers,
> Guillaume Nodet
> ------------------------
> Blog: http://gnodet.blogspot.com/
> ------------------------
> Open Source SOA
> http://fusesource.com

--
Jean-Baptiste Onofré (Nanthrax)
BuildProcess/AutoDeploy Project Leader
http://buildprocess.sourceforge.net
[hidden email]
PGP : 17D4F086