Following is the result of dumping the threads in the JVM at the point of the hang-up.
I could use some help interpreting this.
The stack trace associated with Thread-2 appears to indicate it's the one allocated to initialize the ExternalContext.
It's not obvious to me though why it's hung.
Could it be some undesirable interaction between object web's monolog (used by the NamingContextFactory class I've specified in the ExternalContext service config file) and log4j?
C:\jboss_6.0.0\bin>run
Calling C:\jboss_6.0.0\bin\run.conf.bat
===============================================================================
JBoss Bootstrap Environment
JBOSS_HOME: C:\jboss_6.0.0
JAVA: C:\Program Files\Java\jdk1.6.0_26\bin\java
JAVA_OPTS: -Dprogram.name=run.bat -Xms512m -Xmx1024m -server
CLASSPATH: C:\Program Files\Java\jdk1.6.0_26\lib\tools.jar;C:\jboss_6.0.0\bin\
run.jar
===============================================================================
07:32:41,328 INFO [AbstractJBossASServerBase] Server Configuration:
JBOSS_HOME URL: file:/C:/jboss_6.0.0/
Bootstrap: $JBOSS_HOME\server/default/conf/bootstrap.xml
Common Base: $JBOSS_HOME\common/
Common Library: $JBOSS_HOME\common/lib/
Server Name: default
Server Base: $JBOSS_HOME\server/
Server Library: $JBOSS_HOME\server/default/lib/
Server Config: $JBOSS_HOME\server/default/conf/
Server Home: $JBOSS_HOME\server/default/
Server Data: $JBOSS_HOME\server/default/data/
Server Log: $JBOSS_HOME\server/default/log/
Server Temp: $JBOSS_HOME\server/default/tmp/
07:32:41,359 INFO [AbstractServer] Starting: JBossAS [6.0.0.Final "Neo"]
07:32:49,188 INFO [ServerInfo] Java version: 1.6.0_26,Sun Microsystems Inc.
07:32:49,188 INFO [ServerInfo] Java Runtime: Java(TM) SE Runtime Environment (b
uild 1.6.0_26-b03)
07:32:49,188 INFO [ServerInfo] Java VM: Java HotSpot(TM) Server VM 20.1-b02,Sun
Microsystems Inc.
07:32:49,188 INFO [ServerInfo] OS-System: Windows Server 2008 6.0,x86
07:32:49,188 INFO [ServerInfo] VM arguments: -Dprogram.name=run.bat -Xms512m -X
mx1024m -Djava.endorsed.dirs=C:\jboss_6.0.0\lib\endorsed
07:32:49,484 INFO [JMXKernel] Legacy JMX core initialized
07:33:12,781 INFO [AbstractServerConfig] JBoss Web Services - Stack CXF Server
3.4.1.GA
07:33:16,969 INFO [JSFImplManagementDeployer] Initialized 3 JSF configurations:
[Mojarra-1.2, MyFaces-2.0, Mojarra-2.0]
07:33:28,594 WARNING [FileConfigurationParser] AIO wasn't located on this platfo
rm, it will fall back to using pure Java NIO. If your platform is Linux, install
LibAIO to enable the AIO journal
2011-08-09 08:00:49
Full thread dump Java HotSpot(TM) Server VM (20.1-b02 mixed mode):
"GC Daemon" daemon prio=2 tid=0x4bdd9400 nid=0xa48 in Object.wait() [0x49eaf000]
java.lang.Thread.State: TIMED_WAITING (on object monitor)
at java.lang.Object.wait(Native Method)
- waiting on <0x0a3a0158> (a sun.misc.GC$LatencyLock)
at sun.misc.GC$Daemon.run(GC.java:100)
- locked <0x0a3a0158> (a sun.misc.GC$LatencyLock)
"AuthenticationCacheFlushThread" prio=6 tid=0x4a722400 nid=0x10c waiting on cond
ition [0x49d8f000]
java.lang.Thread.State: TIMED_WAITING (sleeping)
at java.lang.Thread.sleep(Native Method)
at org.jboss.security.auth.AuthenticationCacheFlushThread.run(Authentica
tionCacheFlushThread.java:83)
"Thread-2" prio=6 tid=0x4a420000 nid=0xdb8 runnable [0x49b7d000]
java.lang.Thread.State: RUNNABLE
at java.lang.Object.<init>(Object.java:20)
at org.jboss.logmanager.LoggerNode.<init>(LoggerNode.java:125)
at org.jboss.logmanager.LoggerNode.getOrCreate(LoggerNode.java:155)
at org.jboss.logmanager.LogContext.getLogger(LogContext.java:122)
at org.jboss.logmanager.log4j.BridgeRepository.getLogger(BridgeRepositor
y.java:69)
at org.jboss.logmanager.log4j.BridgeRepository.getLogger(BridgeRepositor
y.java:78)
at org.apache.log4j.LogManager.getLogger(LogManager.java:209)
at org.apache.log4j.Logger.getLogger(Logger.java:155)
at org.objectweb.util.monolog.wrapper.log4j.MonologLoggerFactory.<clinit
>(MonologLoggerFactory.java:97)
at java.lang.Class.forName0(Native Method)
at java.lang.Class.forName(Class.java:169)
at org.objectweb.util.monolog.Monolog.instanciateMonologFactory(Monolog.
java:308)
at org.objectweb.util.monolog.Monolog.getMonologFactory(Monolog.java:202
)
at org.objectweb.util.monolog.Monolog.initialize(Monolog.java:139)
at org.objectweb.util.monolog.Monolog.init(Monolog.java:148)
at fr.dyade.aaa.util.Debug.initialize(Debug.java:146)
at fr.dyade.aaa.util.Debug.init(Debug.java:79)
at fr.dyade.aaa.util.Debug.getLogger(Debug.java:161)
at fr.dyade.aaa.jndi2.client.Trace.<clinit>(Trace.java:34)
at fr.dyade.aaa.jndi2.client.NamingContextFactory.getInitialContext(Nami
ngContextFactory.java:74)
at javax.naming.spi.NamingManager.getInitialContext(NamingManager.java:6
67)
at javax.naming.InitialContext.getDefaultInitCtx(InitialContext.java:288
)
at javax.naming.InitialContext.init(InitialContext.java:223)
at javax.naming.InitialContext.<init>(InitialContext.java:197)
at sun.reflect.NativeConstructorAccessorImpl.newInstance0(Native Method)
at sun.reflect.NativeConstructorAccessorImpl.newInstance(NativeConstruct
orAccessorImpl.java:39)
at sun.reflect.DelegatingConstructorAccessorImpl.newInstance(DelegatingC
onstructorAccessorImpl.java:27)
at java.lang.reflect.Constructor.newInstance(Constructor.java:513)
at org.jboss.naming.ExternalContext$SerializableInitialContext.newDefaul
tContext(ExternalContext.java:466)
at org.jboss.naming.ExternalContext$SerializableInitialContext.newContex
t(ExternalContext.java:450)
at org.jboss.naming.ExternalContext$SerializableInitialContext.newContex
t(ExternalContext.java:440)
at org.jboss.naming.ExternalContext.rebind(ExternalContext.java:261)
at org.jboss.naming.ExternalContext.startService(ExternalContext.java:22
5)
at org.jboss.system.ServiceMBeanSupport.jbossInternalStart(ServiceMBeanS
upport.java:355)
at org.jboss.system.ServiceMBeanSupport.jbossInternalLifecycle(ServiceMB
eanSupport.java:301)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.
java:39)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAcces
sorImpl.java:25)
at java.lang.reflect.Method.invoke(Method.java:597)
at org.jboss.mx.interceptor.ReflectedDispatcher.invoke(ReflectedDispatch
er.java:157)
at org.jboss.mx.server.Invocation.dispatch(Invocation.java:96)
at org.jboss.mx.server.Invocation.invoke(Invocation.java:88)
at org.jboss.mx.server.AbstractMBeanInvoker.invoke(AbstractMBeanInvoker.
java:271)
at org.jboss.mx.server.MBeanServerImpl.invoke(MBeanServerImpl.java:670)
at org.jboss.system.microcontainer.ServiceProxy.invoke(ServiceProxy.java
:189)
at $Proxy41.start(Unknown Source)
at org.jboss.system.microcontainer.StartStopLifecycleAction.installActio
n(StartStopLifecycleAction.java:53)
at org.jboss.system.microcontainer.StartStopLifecycleAction.installActio
n(StartStopLifecycleAction.java:41)
at org.jboss.dependency.plugins.action.SimpleControllerContextAction.sim
pleInstallAction(SimpleControllerContextAction.java:62)
at org.jboss.dependency.plugins.action.AccessControllerContextAction.ins
tall(AccessControllerContextAction.java:71)
at org.jboss.dependency.plugins.AbstractControllerContextActions.install
(AbstractControllerContextActions.java:51)
at org.jboss.dependency.plugins.AbstractControllerContext.install(Abstra
ctControllerContext.java:379)
at org.jboss.system.microcontainer.ServiceControllerContext.install(Serv
iceControllerContext.java:301)
at org.jboss.dependency.plugins.AbstractController.install(AbstractContr
oller.java:2044)
at org.jboss.dependency.plugins.AbstractController.incrementState(Abstra
ctController.java:1083)
at org.jboss.dependency.plugins.AbstractController.executeOrIncrementSta
teDirectly(AbstractController.java:1322)
at org.jboss.dependency.plugins.AbstractController.resolveContexts(Abstr
actController.java:1246)
at org.jboss.dependency.plugins.AbstractController.resolveContexts(Abstr
actController.java:1139)
at org.jboss.dependency.plugins.AbstractController.change(AbstractContro
ller.java:939)
at org.jboss.dependency.plugins.AbstractController.change(AbstractContro
ller.java:654)
at org.jboss.system.ServiceController.doChange(ServiceController.java:67
1)
at org.jboss.system.ServiceController.start(ServiceController.java:443)
at org.jboss.system.deployers.ServiceDeployer.start(ServiceDeployer.java
:189)
at org.jboss.system.deployers.ServiceDeployer.deploy(ServiceDeployer.jav
a:102)
at org.jboss.system.deployers.ServiceDeployer.deploy(ServiceDeployer.jav
a:49)
at org.jboss.deployers.spi.deployer.helpers.AbstractSimpleRealDeployer.i
nternalDeploy(AbstractSimpleRealDeployer.java:62)
at org.jboss.deployers.spi.deployer.helpers.AbstractRealDeployer.deploy(
AbstractRealDeployer.java:55)
at org.jboss.deployers.plugins.deployers.DeployerWrapper.deploy(Deployer
Wrapper.java:179)
at org.jboss.deployers.plugins.deployers.DeployersImpl.doDeploy(Deployer
sImpl.java:1832)
at org.jboss.deployers.plugins.deployers.DeployersImpl.doInstallParentFi
rst(DeployersImpl.java:1550)
at org.jboss.deployers.plugins.deployers.DeployersImpl.doInstallParentFi
rst(DeployersImpl.java:1571)
at org.jboss.deployers.plugins.deployers.DeployersImpl.install(Deployers
Impl.java:1491)
at org.jboss.dependency.plugins.AbstractControllerContext.install(Abstra
ctControllerContext.java:379)
at org.jboss.dependency.plugins.AbstractController.install(AbstractContr
oller.java:2044)
at org.jboss.dependency.plugins.AbstractController.incrementState(Abstra
ctController.java:1083)
at org.jboss.dependency.plugins.AbstractController.executeOrIncrementSta
teDirectly(AbstractController.java:1322)
at org.jboss.dependency.plugins.AbstractController.resolveContexts(Abstr
actController.java:1246)
at org.jboss.dependency.plugins.AbstractController.resolveContexts(Abstr
actController.java:1139)
at org.jboss.dependency.plugins.AbstractController.change(AbstractContro
ller.java:939)
at org.jboss.dependency.plugins.AbstractController.change(AbstractContro
ller.java:654)
at org.jboss.deployers.plugins.deployers.DeployersImpl.change(DeployersI
mpl.java:1983)
at org.jboss.deployers.plugins.deployers.DeployersImpl.process(Deployers
Impl.java:1076)
at org.jboss.deployers.plugins.main.MainDeployerImpl.process(MainDeploye
rImpl.java:679)
at org.jboss.system.server.profileservice.deployers.MainDeployerPlugin.p
rocess(MainDeployerPlugin.java:106)
at org.jboss.profileservice.dependency.ProfileControllerContext$Delegate
Deployer.process(ProfileControllerContext.java:143)
at org.jboss.profileservice.dependency.ProfileDeployAction.deploy(Profil
eDeployAction.java:151)
at org.jboss.profileservice.dependency.ProfileDeployAction.installAction
Internal(ProfileDeployAction.java:94)
at org.jboss.kernel.plugins.dependency.InstallsAwareAction.installAction
(InstallsAwareAction.java:54)
at org.jboss.kernel.plugins.dependency.InstallsAwareAction.installAction
(InstallsAwareAction.java:42)
at org.jboss.dependency.plugins.action.SimpleControllerContextAction.sim
pleInstallAction(SimpleControllerContextAction.java:62)
at org.jboss.dependency.plugins.action.AccessControllerContextAction.ins
tall(AccessControllerContextAction.java:71)
at org.jboss.dependency.plugins.AbstractControllerContextActions.install
(AbstractControllerContextActions.java:51)
at org.jboss.dependency.plugins.AbstractControllerContext.install(Abstra
ctControllerContext.java:379)
at org.jboss.dependency.plugins.AbstractController.install(AbstractContr
oller.java:2044)
at org.jboss.dependency.plugins.AbstractController.incrementState(Abstra
ctController.java:1083)
at org.jboss.dependency.plugins.AbstractController.executeOrIncrementSta
teDirectly(AbstractController.java:1322)
at org.jboss.dependency.plugins.AbstractController.resolveContexts(Abstr
actController.java:1246)
at org.jboss.dependency.plugins.AbstractController.resolveContexts(Abstr
actController.java:1139)
at org.jboss.dependency.plugins.AbstractController.change(AbstractContro
ller.java:939)
at org.jboss.dependency.plugins.AbstractController.change(AbstractContro
ller.java:654)
at org.jboss.profileservice.dependency.ProfileActivationWrapper$BasicPro
fileActivation.start(ProfileActivationWrapper.java:190)
at org.jboss.profileservice.dependency.ProfileActivationWrapper.start(Pr
ofileActivationWrapper.java:87)
at org.jboss.profileservice.dependency.ProfileActivationService.activate
Profile(ProfileActivationService.java:215)
at org.jboss.profileservice.dependency.ProfileActivationService.activate
(ProfileActivationService.java:159)
at org.jboss.profileservice.bootstrap.AbstractProfileServiceBootstrap.ac
tivate(AbstractProfileServiceBootstrap.java:112)
at org.jboss.profileservice.resolver.BasicResolverFactory$ProfileResolve
rFacade.deploy(BasicResolverFactory.java:87)
at org.jboss.profileservice.bootstrap.AbstractProfileServiceBootstrap.st
art(AbstractProfileServiceBootstrap.java:91)
at org.jboss.system.server.profileservice.bootstrap.BasicProfileServiceB
ootstrap.start(BasicProfileServiceBootstrap.java:132)
at org.jboss.system.server.profileservice.bootstrap.BasicProfileServiceB
ootstrap.start(BasicProfileServiceBootstrap.java:56)
at org.jboss.bootstrap.impl.base.server.AbstractServer.startBootstraps(A
bstractServer.java:827)
at org.jboss.bootstrap.impl.base.server.AbstractServer$StartServerTask.r
un(AbstractServer.java:417)
- locked <0x0a511c28> (a org.jboss.bootstrap.impl.as.server.JBossASServe
rImpl)
at java.lang.Thread.run(Thread.java:662)
"DestroyJavaVM" prio=6 tid=0x000f9c00 nid=0x894 waiting on condition [0x00000000
]
java.lang.Thread.State: RUNNABLE
"main" prio=6 tid=0x49774400 nid=0x178 in Object.wait() [0x49aef000]
java.lang.Thread.State: WAITING (on object monitor)
at java.lang.Object.wait(Native Method)
- waiting on <0x0a511b88> (a java.lang.Thread)
at java.lang.Thread.join(Thread.java:1186)
- locked <0x0a511b88> (a java.lang.Thread)
at java.lang.Thread.join(Thread.java:1239)
at org.jboss.bootstrap.impl.base.server.AbstractServer.start(AbstractSer
ver.java:348)
at org.jboss.bootstrap.impl.mc.server.AbstractMCServerBase.start(Abstrac
tMCServerBase.java:248)
at org.jboss.bootstrap.impl.as.server.AbstractJBossASServerBase.start(Ab
stractJBossASServerBase.java:402)
at org.jboss.Main.boot(Main.java:461)
at org.jboss.Main$1.run(Main.java:805)
at java.lang.Thread.run(Thread.java:662)
"Low Memory Detector" daemon prio=6 tid=0x496fe000 nid=0x1a8 runnable [0x0000000
0]
java.lang.Thread.State: RUNNABLE
"C2 CompilerThread1" daemon prio=10 tid=0x496f5400 nid=0xd64 waiting on conditio
n [0x00000000]
java.lang.Thread.State: RUNNABLE
"C2 CompilerThread0" daemon prio=10 tid=0x496f0c00 nid=0xfb0 waiting on conditio
n [0x00000000]
java.lang.Thread.State: RUNNABLE
"Attach Listener" daemon prio=10 tid=0x496ee400 nid=0x9e4 runnable [0x00000000]
java.lang.Thread.State: RUNNABLE
"Signal Dispatcher" daemon prio=10 tid=0x021efc00 nid=0x458 waiting on condition
[0x00000000]
java.lang.Thread.State: RUNNABLE
"Finalizer" daemon prio=8 tid=0x021e5000 nid=0xea4 in Object.wait() [0x496bf000]
java.lang.Thread.State: WAITING (on object monitor)
at java.lang.Object.wait(Native Method)
- waiting on <0x0b162d00> (a java.lang.ref.ReferenceQueue$Lock)
at java.lang.ref.ReferenceQueue.remove(ReferenceQueue.java:118)
- locked <0x0b162d00> (a java.lang.ref.ReferenceQueue$Lock)
at java.lang.ref.ReferenceQueue.remove(ReferenceQueue.java:134)
at java.lang.ref.Finalizer$FinalizerThread.run(Finalizer.java:159)
"Reference Handler" daemon prio=10 tid=0x021e4000 nid=0xa68 in Object.wait() [0x
4966f000]
java.lang.Thread.State: WAITING (on object monitor)
at java.lang.Object.wait(Native Method)
- waiting on <0x0b162cb8> (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 <0x0b162cb8> (a java.lang.ref.Reference$Lock)
"VM Thread" prio=10 tid=0x021e0c00 nid=0xa24 runnable
"GC task thread#0 (ParallelGC)" prio=6 tid=0x02112000 nid=0x45c runnable
"GC task thread#1 (ParallelGC)" prio=6 tid=0x02113000 nid=0xe4 runnable
"GC task thread#2 (ParallelGC)" prio=6 tid=0x02114400 nid=0x774 runnable
"GC task thread#3 (ParallelGC)" prio=6 tid=0x02115800 nid=0xcc8 runnable
"VM Periodic Task Thread" prio=10 tid=0x496ff000 nid=0x9f0 waiting on condition
JNI global references: 1170
Heap
PSYoungGen total 348416K, used 15979K [0x33ca0000, 0x491f0000, 0x491f0000)
eden space 347456K, 5% used [0x33ca0000,0x34e6ae70,0x48ff0000)
from space 960K, 86% used [0x490e0000,0x491b0010,0x491d0000)
to space 960K, 0% used [0x48ff0000,0x48ff0000,0x490e0000)
PSOldGen total 349568K, used 163205K [0x091f0000, 0x1e750000, 0x33ca0000
)
object space 349568K, 46% used [0x091f0000,0x13151648,0x1e750000)
PSPermGen total 47488K, used 34005K [0x051f0000, 0x08050000, 0x091f0000)
object space 47488K, 71% used [0x051f0000,0x073256e0,0x08050000)
