0 Replies Latest reply on Oct 25, 2012 3:11 AM by Matti Linnanvuori

    Deadlock when starting managed JBossAS 6.1.0.Final "Neo"

    Matti Linnanvuori Newbie

      Apparently 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