Deadlock when starting managed JBossAS 6.1.0.Final "Neo"
mattil Oct 25, 2012 3:11 AMApparently I got a deadlock when starting Arquillian managed on JBossAS 6.1.0.Final "Neo".
The server is not started correctly and only a thread dump prints something to output.log of any JBoss log files.
Before that I got a stack overflow error in logging, so the problem might be in logging.
[junit] 2012-10-25 09:54:56
[junit] Full thread dump Java HotSpot(TM) 64-Bit Server VM (20.4-b02 mixed mode):
[junit] "Thread-3" prio=10 tid=0x00007fc9fa28a000 nid=0x2ffc runnable [0x00007fc9c7a3a000]
[junit] java.lang.Thread.State: RUNNABLE
[junit] at java.io.FileInputStream.readBytes(Native Method)
[junit] at java.io.FileInputStream.read(FileInputStream.java:220)
[junit] at sun.nio.cs.StreamDecoder.readBytes(StreamDecoder.java:264)
[junit] at sun.nio.cs.StreamDecoder.implRead(StreamDecoder.java:306)
[junit] at sun.nio.cs.StreamDecoder.read(StreamDecoder.java:158)
[junit] - locked <0x00000007d7c98ee8> (a java.io.InputStreamReader)
[junit] at java.io.InputStreamReader.read(InputStreamReader.java:167)
[junit] at java.io.BufferedReader.fill(BufferedReader.java:136)
[junit] at java.io.BufferedReader.readLine(BufferedReader.java:299)
[junit] - locked <0x00000007d7c98ee8> (a java.io.InputStreamReader)
[junit] at java.io.BufferedReader.readLine(BufferedReader.java:362)
[junit] at org.jboss.jbossas.servermanager.ServerController$OutputPumper.run(ServerController.java:627)
[junit] "Thread-2" prio=10 tid=0x00007fc9f9c39800 nid=0x2ffb runnable [0x00007fc9c7b3b000]
[junit] java.lang.Thread.State: RUNNABLE
[junit] at java.io.FileInputStream.readBytes(Native Method)
[junit] at java.io.FileInputStream.read(FileInputStream.java:220)
[junit] at java.io.BufferedInputStream.read1(BufferedInputStream.java:256)
[junit] at java.io.BufferedInputStream.read(BufferedInputStream.java:317)
[junit] - locked <0x00000007d7ce6ae0> (a java.io.BufferedInputStream)
[junit] at sun.nio.cs.StreamDecoder.readBytes(StreamDecoder.java:264)
[junit] at sun.nio.cs.StreamDecoder.implRead(StreamDecoder.java:306)
[junit] at sun.nio.cs.StreamDecoder.read(StreamDecoder.java:158)
[junit] - locked <0x00000007d7c9f138> (a java.io.InputStreamReader)
[junit] at java.io.InputStreamReader.read(InputStreamReader.java:167)
[junit] at java.io.BufferedReader.fill(BufferedReader.java:136)
[junit] at java.io.BufferedReader.readLine(BufferedReader.java:299)
[junit] - locked <0x00000007d7c9f138> (a java.io.InputStreamReader)
[junit] at java.io.BufferedReader.readLine(BufferedReader.java:362)
[junit] at org.jboss.jbossas.servermanager.ServerController$OutputPumper.run(ServerController.java:627)
[junit] "process reaper" daemon prio=10 tid=0x00007fc9fa524800 nid=0x2ff9 runnable [0x00007fc9c7c3c000]
[junit] java.lang.Thread.State: RUNNABLE
[junit] at java.lang.UNIXProcess.waitForProcessExit(Native Method)
[junit] at java.lang.UNIXProcess.access$900(UNIXProcess.java:20)
[junit] at java.lang.UNIXProcess$1$1.run(UNIXProcess.java:132)
[junit] "Low Memory Detector" daemon prio=10 tid=0x00007fc9f80b2000 nid=0x2ff7 runnable [0x0000000000000000]
[junit] java.lang.Thread.State: RUNNABLE
[junit] "C2 CompilerThread1" daemon prio=10 tid=0x00007fc9f80af800 nid=0x2ff6 waiting on condition [0x0000000000000000]
[junit] java.lang.Thread.State: RUNNABLE
[junit] "C2 CompilerThread0" daemon prio=10 tid=0x00007fc9f80ac800 nid=0x2ff5 waiting on condition [0x0000000000000000]
[junit] java.lang.Thread.State: RUNNABLE
[junit] "Signal Dispatcher" daemon prio=10 tid=0x00007fc9f80aa800 nid=0x2ff4 waiting on condition [0x0000000000000000]
[junit] java.lang.Thread.State: RUNNABLE
[junit] "Finalizer" daemon prio=10 tid=0x00007fc9f808e800 nid=0x2ff3 in Object.wait() [0x00007fc9fc754000]
[junit] java.lang.Thread.State: WAITING (on object monitor)
[junit] at java.lang.Object.wait(Native Method)
[junit] - waiting on <0x00000007d8d90150> (a java.lang.ref.ReferenceQueue$Lock)
[junit] at java.lang.ref.ReferenceQueue.remove(ReferenceQueue.java:118)
[junit] - locked <0x00000007d8d90150> (a java.lang.ref.ReferenceQueue$Lock)
[junit] at java.lang.ref.ReferenceQueue.remove(ReferenceQueue.java:134)
[junit] at java.lang.ref.Finalizer$FinalizerThread.run(Finalizer.java:159)
[junit] "Reference Handler" daemon prio=10 tid=0x00007fc9f808c800 nid=0x2ff2 in Object.wait() [0x00007fc9fc855000]
[junit] java.lang.Thread.State: WAITING (on object monitor)
[junit] at java.lang.Object.wait(Native Method)
[junit] - waiting on <0x00000007d8d80288> (a java.lang.ref.Reference$Lock)
[junit] at java.lang.Object.wait(Object.java:485)
[junit] at java.lang.ref.Reference$ReferenceHandler.run(Reference.java:116)
[junit] - locked <0x00000007d8d80288> (a java.lang.ref.Reference$Lock)
[junit] "main" prio=10 tid=0x00007fc9f8028000 nid=0x2fec runnable [0x00007fc9fe8a4000]
[junit] java.lang.Thread.State: RUNNABLE
[junit] at java.net.SocketInputStream.socketRead0(Native Method)
[junit] at java.net.SocketInputStream.read(SocketInputStream.java:129)
[junit] at java.io.BufferedInputStream.fill(BufferedInputStream.java:218)
[junit] at java.io.BufferedInputStream.read1(BufferedInputStream.java:258)
[junit] at java.io.BufferedInputStream.read(BufferedInputStream.java:317)
[junit] - locked <0x00000007d7ccd128> (a java.io.BufferedInputStream)
[junit] at sun.net.www.http.HttpClient.parseHTTPHeader(HttpClient.java:695)
[junit] at sun.net.www.http.HttpClient.parseHTTP(HttpClient.java:640)
[junit] at sun.net.www.protocol.http.HttpURLConnection.getInputStream(HttpURLConnection.java:1195)
[junit] - locked <0x00000007d7cc2b08> (a sun.net.www.protocol.http.HttpURLConnection)
[junit] at java.net.HttpURLConnection.getResponseCode(HttpURLConnection.java:379)
[junit] at org.jboss.jbossas.servermanager.ServerController.isServerStarted(ServerController.java:371)
[junit] at org.jboss.jbossas.servermanager.ServerController.waitForServer(ServerController.java:331)
[junit] at org.jboss.jbossas.servermanager.ServerController.startServer(ServerController.java:140)
[junit] at org.jboss.jbossas.servermanager.ServerManager.startServer(ServerManager.java:163)
[junit] at org.jboss.arquillian.container.jbossas.managed_6.JBossASLocalContainer.start(JBossASLocalContainer.java:110)
[junit] at org.jboss.arquillian.container.impl.ContainerImpl.start(ContainerImpl.java:198)
[junit] at org.jboss.arquillian.container.impl.client.container.ContainerLifecycleController$8.perform(ContainerLifecycleController.java:163)
[junit] at org.jboss.arquillian.container.impl.client.container.ContainerLifecycleController$8.perform(ContainerLifecycleController.java:157)
[junit] at org.jboss.arquillian.container.impl.client.container.ContainerLifecycleController.forContainer(ContainerLifecycleController.java:255)
[junit] at org.jboss.arquillian.container.impl.client.container.ContainerLifecycleController.startContainer(ContainerLifecycleController.java:156)
[junit] at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
[junit] at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
[junit] at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
[junit] at java.lang.reflect.Method.invoke(Method.java:597)
[junit] at org.jboss.arquillian.core.impl.ObserverImpl.invoke(ObserverImpl.java:94)
[junit] at org.jboss.arquillian.core.impl.EventContextImpl.invokeObservers(EventContextImpl.java:99)
[junit] at org.jboss.arquillian.core.impl.EventContextImpl.proceed(EventContextImpl.java:81)
[junit] at org.jboss.arquillian.container.impl.client.ContainerDeploymentContextHandler.createContainerContext(ContainerDeploymentContextHandler.java:57)
[junit] at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
[junit] at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
[junit] at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
[junit] at java.lang.reflect.Method.invoke(Method.java:597)
[junit] at org.jboss.arquillian.core.impl.ObserverImpl.invoke(ObserverImpl.java:94)
[junit] at org.jboss.arquillian.core.impl.EventContextImpl.proceed(EventContextImpl.java:88)
[junit] at org.jboss.arquillian.core.impl.ManagerImpl.fire(ManagerImpl.java:135)
[junit] at org.jboss.arquillian.core.impl.ManagerImpl.fire(ManagerImpl.java:115)
[junit] at org.jboss.arquillian.core.impl.EventImpl.fire(EventImpl.java:67)
[junit] at org.jboss.arquillian.container.impl.client.container.ContainerLifecycleController$2.perform(ContainerLifecycleController.java:77)
[junit] at org.jboss.arquillian.container.impl.client.container.ContainerLifecycleController$2.perform(ContainerLifecycleController.java:70)
[junit] at org.jboss.arquillian.container.impl.client.container.ContainerLifecycleController.forEachSuiteContainer(ContainerLifecycleController.java:221)
[junit] at org.jboss.arquillian.container.impl.client.container.ContainerLifecycleController.startSuiteContainers(ContainerLifecycleController.java:69)
[junit] at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
[junit] at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
[junit] at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
[junit] at java.lang.reflect.Method.invoke(Method.java:597)
[junit] at org.jboss.arquillian.core.impl.ObserverImpl.invoke(ObserverImpl.java:94)
[junit] at org.jboss.arquillian.core.impl.EventContextImpl.invokeObservers(EventContextImpl.java:99)
[junit] at org.jboss.arquillian.core.impl.EventContextImpl.proceed(EventContextImpl.java:81)
[junit] at org.jboss.arquillian.core.impl.ManagerImpl.fire(ManagerImpl.java:135)
[junit] at org.jboss.arquillian.core.impl.ManagerImpl.fire(ManagerImpl.java:115)
[junit] at org.jboss.arquillian.core.impl.EventImpl.fire(EventImpl.java:67)
[junit] at org.jboss.arquillian.container.test.impl.client.ContainerEventController.execute(ContainerEventController.java:86)
[junit] at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
[junit] at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
[junit] at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
[junit] at java.lang.reflect.Method.invoke(Method.java:597)
[junit] at org.jboss.arquillian.core.impl.ObserverImpl.invoke(ObserverImpl.java:94)
[junit] at org.jboss.arquillian.core.impl.EventContextImpl.invokeObservers(EventContextImpl.java:99)
[junit] at org.jboss.arquillian.core.impl.EventContextImpl.proceed(EventContextImpl.java:81)
[junit] at org.jboss.arquillian.test.impl.TestContextHandler.createSuiteContext(TestContextHandler.java:60)
[junit] at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
[junit] at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
[junit] at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
[junit] at java.lang.reflect.Method.invoke(Method.java:597)
[junit] at org.jboss.arquillian.core.impl.ObserverImpl.invoke(ObserverImpl.java:94)
[junit] at org.jboss.arquillian.core.impl.EventContextImpl.proceed(EventContextImpl.java:88)
[junit] at org.jboss.arquillian.core.impl.ManagerImpl.fire(ManagerImpl.java:135)
[junit] at org.jboss.arquillian.core.impl.ManagerImpl.fire(ManagerImpl.java:115)
[junit] at org.jboss.arquillian.test.impl.EventTestRunnerAdaptor.beforeSuite(EventTestRunnerAdaptor.java:68)
[junit] at org.jboss.arquillian.junit.Arquillian.run(Arquillian.java:97)
[junit] at junit.framework.JUnit4TestAdapter.run(JUnit4TestAdapter.java:39)
[junit] at org.apache.tools.ant.taskdefs.optional.junit.JUnitTestRunner.run(JUnitTestRunner.java:421)
[junit] at org.apache.tools.ant.taskdefs.optional.junit.JUnitTestRunner.launch(JUnitTestRunner.java:912)
[junit] at org.apache.tools.ant.taskdefs.optional.junit.JUnitTestRunner.main(JUnitTestRunner.java:766)
[junit] "VM Thread" prio=10 tid=0x00007fc9f8085800 nid=0x2ff1 runnable
[junit] "GC task thread#0 (ParallelGC)" prio=10 tid=0x00007fc9f803b000 nid=0x2fed runnable
[junit] "GC task thread#1 (ParallelGC)" prio=10 tid=0x00007fc9f803d000 nid=0x2fee runnable
[junit] "GC task thread#2 (ParallelGC)" prio=10 tid=0x00007fc9f803e800 nid=0x2fef runnable
[junit] "GC task thread#3 (ParallelGC)" prio=10 tid=0x00007fc9f8040800 nid=0x2ff0 runnable
[junit] "VM Periodic Task Thread" prio=10 tid=0x00007fc9f80bc000 nid=0x2ff8 waiting on condition
[junit] JNI global references: 1643
[junit] Heap
[junit] PSYoungGen total 36800K, used 18090K [0x00000007d6eb0000, 0x00000007d97c0000, 0x0000000800000000)
[junit] eden space 31552K, 48% used [0x00000007d6eb0000,0x00000007d7d826e8,0x00000007d8d80000)
[junit] from space 5248K, 55% used [0x00000007d8d80000,0x00000007d90583f8,0x00000007d92a0000)
[junit] to space 5248K, 0% used [0x00000007d92a0000,0x00000007d92a0000,0x00000007d97c0000)
[junit] PSOldGen total 84160K, used 0K [0x0000000784c00000, 0x0000000789e30000, 0x00000007d6eb0000)
[junit] object space 84160K, 0% used [0x0000000784c00000,0x0000000784c00000,0x0000000789e30000)
[junit] PSPermGen total 21248K, used 11205K [0x000000077fa00000, 0x0000000780ec0000, 0x0000000784c00000)
[junit] object space 21248K, 52% used [0x000000077fa00000,0x00000007804f1588,0x0000000780ec0000)
matti 12142 2736 63 822686 923956 0 09:49 pts/0 00:02:14 /opt/ecolane/jav
matti 12265 12142 2 822902 122244 3 09:51 pts/0 00:00:01 /opt/ecolane/jdk
matti 12282 12265 99 889305 1105204 0 09:51 pts/0 00:01:23 /opt/ecolane/jdk
matti@matti:~/projects/k3$ sudo strace -p 12142
Process 12142 attached - interrupt to quit
futex(0x7f844b0109d0, FUTEX_WAIT, 12144, NULL^C <unfinished ...>
Process 12142 detached
matti@matti:~/projects/k3$ sudo strace -p 12265
Process 12265 attached - interrupt to quit
futex(0x7fc9fe8a99d0, FUTEX_WAIT, 12268, NULL^C <unfinished ...>
Process 12265 detached
matti@matti:~/projects/k3$ sudo strace -p 12282
Process 12282 attached - interrupt to quit
futex(0x7f721d9cb9d0, FUTEX_WAIT, 12285, NULL^C <unfinished ...>
Process 12282 detached
-
ivy.xml 10.1 KB
-
output.log.zip 4.3 KB