Uploaded image for project: 'Hippo CMS'
  1. Hippo CMS
  2. CMS-5006

Deadlock during startup

    XMLWordPrintable

Details

    • Bug
    • Status: Closed
    • Top
    • Resolution: Probably fixed, check next version
    • 2.18.00
    • 2.18.03, 2.19.01
    • repository-core
    • None
    • linux, tomcat, mysql, clustered

    Description

      The repository fails to startup.

      Feb 25, 2011 9:00:47 AM org.apache.coyote.http11.Http11Protocol init
      INFO: Initializing Coyote HTTP/1.1 on http-8180
      Feb 25, 2011 9:00:47 AM org.apache.catalina.startup.Catalina load
      INFO: Initialization processed in 3248 ms
      Feb 25, 2011 9:00:47 AM org.apache.catalina.realm.JAASRealm setContainer
      INFO: Set JAAS app name Catalina
      Feb 25, 2011 9:00:47 AM org.apache.catalina.core.StandardService start
      INFO: Starting service Catalina
      Feb 25, 2011 9:00:47 AM org.apache.catalina.core.StandardEngine start
      INFO: Starting Servlet Engine: Apache Tomcat/6.0.29
      Feb 25, 2011 9:00:51 AM org.apache.catalina.startup.HostConfig deployDescriptor
      INFO: Deploying configuration descriptor site.xml
      Feb 25, 2011 9:00:53 AM org.apache.catalina.realm.JAASRealm setUseContextClassLoader
      INFO: Setting useContextClassLoader = true
      2011-02-25 10:32:59
      Full thread dump Java HotSpot(TM) Server VM (17.1-b03 mixed mode):

      "Transient File Reaper" daemon prio=10 tid=0xdc932000 nid=0xdac in Object.wait() [0xdd4ae000]
      java.lang.Thread.State: WAITING (on object monitor)
      at java.lang.Object.wait(Native Method)

      • waiting on <0xe1da1c40> (a java.lang.ref.ReferenceQueue$Lock)
        at java.lang.ref.ReferenceQueue.remove(Unknown Source)
      • locked <0xe1da1c40> (a java.lang.ref.ReferenceQueue$Lock)
        at java.lang.ref.ReferenceQueue.remove(Unknown Source)
        at org.apache.jackrabbit.util.TransientFileFactory$ReaperThread.run(TransientFileFactory.java:192)

      "jackrabbit-pool-1" prio=10 tid=0xdc9ca800 nid=0xdaa waiting on condition [0xdd4fe000]
      java.lang.Thread.State: TIMED_WAITING (parking)
      at sun.misc.Unsafe.park(Native Method)

      • parking to wait for <0xe1b209a8> (a java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject)
        at java.util.concurrent.locks.LockSupport.parkNanos(Unknown Source)
        at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(Unknown Source)
        at java.util.concurrent.DelayQueue.take(Unknown Source)
        at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(Unknown Source)
        at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(Unknown Source)
        at java.util.concurrent.ThreadPoolExecutor.getTask(Unknown Source)
        at java.util.concurrent.ThreadPoolExecutor$Worker.run(Unknown Source)
        at java.lang.Thread.run(Unknown Source)

      "ClusterNode-site-test" daemon prio=10 tid=0xdd936400 nid=0xda8 waiting for monitor entry [0xdd65e000]
      java.lang.Thread.State: BLOCKED (on object monitor)
      at org.apache.jackrabbit.core.nodetype.NodeTypeRegistry.unregisterNodeTypes(NodeTypeRegistry.java:281)

      • waiting to lock <0xe19c94e8> (a org.apache.jackrabbit.core.nodetype.NodeTypeRegistry)
        at org.apache.jackrabbit.core.nodetype.NodeTypeRegistry.externalUnregistered(NodeTypeRegistry.java:691)
        at org.apache.jackrabbit.core.cluster.ClusterNode.process(ClusterNode.java:888)
        at org.apache.jackrabbit.core.cluster.NodeTypeRecord.process(NodeTypeRecord.java:202)
        at org.apache.jackrabbit.core.cluster.ClusterNode.consume(ClusterNode.java:751)
        at org.apache.jackrabbit.core.journal.AbstractJournal.doSync(AbstractJournal.java:212)
        at org.apache.jackrabbit.core.journal.AbstractJournal.sync(AbstractJournal.java:186)
        at org.apache.jackrabbit.core.cluster.ClusterNode.sync(ClusterNode.java:303)
        at org.apache.jackrabbit.core.cluster.ClusterNode.run(ClusterNode.java:269)
        at java.lang.Thread.run(Unknown Source)

      "Timer-1" daemon prio=10 tid=0xdd55ac00 nid=0xda7 in Object.wait() [0xdd6ae000]
      java.lang.Thread.State: TIMED_WAITING (on object monitor)
      at java.lang.Object.wait(Native Method)
      at java.util.TimerThread.mainLoop(Unknown Source)

      • locked <0xe1da1eb8> (a java.util.TaskQueue)
        at java.util.TimerThread.run(Unknown Source)

      "ObservationManager" daemon prio=10 tid=0xdc926400 nid=0xda3 in Object.wait() [0xdd6fe000]
      java.lang.Thread.State: WAITING (on object monitor)
      at java.lang.Object.wait(Native Method)

      • waiting on <0xe1a4e7d8> (a org.apache.commons.collections.buffer.BlockingBuffer)
        at java.lang.Object.wait(Object.java:485)
        at org.apache.commons.collections.buffer.BlockingBuffer.remove(BlockingBuffer.java:192)
      • locked <0xe1a4e7d8> (a org.apache.commons.collections.buffer.BlockingBuffer)
        at org.apache.jackrabbit.core.observation.ObservationDispatcher.run(ObservationDispatcher.java:153)
        at java.lang.Thread.run(Unknown Source)

      "Timer-0" daemon prio=10 tid=0xdd928c00 nid=0xda0 in Object.wait() [0xddaa5000]
      java.lang.Thread.State: TIMED_WAITING (on object monitor)
      at java.lang.Object.wait(Native Method)
      at java.util.TimerThread.mainLoop(Unknown Source)

      • locked <0xe18d0630> (a java.util.TaskQueue)
        at java.util.TimerThread.run(Unknown Source)

      "GC Daemon" daemon prio=10 tid=0xddf2d000 nid=0xd9e in Object.wait() [0xde071000]
      java.lang.Thread.State: TIMED_WAITING (on object monitor)
      at java.lang.Object.wait(Native Method)

      • waiting on <0xe18457a8> (a sun.misc.GC$LatencyLock)
        at sun.misc.GC$Daemon.run(Unknown Source)
      • locked <0xe18457a8> (a sun.misc.GC$LatencyLock)

      "RMI TCP Accept-0" daemon prio=10 tid=0x08376000 nid=0xd9a runnable [0xde177000]
      java.lang.Thread.State: RUNNABLE
      at java.net.PlainSocketImpl.socketAccept(Native Method)
      at java.net.PlainSocketImpl.accept(Unknown Source)

      • locked <0xe1779f30> (a java.net.SocksSocketImpl)
        at java.net.ServerSocket.implAccept(Unknown Source)
        at java.net.ServerSocket.accept(Unknown Source)
        at sun.management.jmxremote.LocalRMIServerSocketFactory$1.accept(Unknown Source)
        at sun.rmi.transport.tcp.TCPTransport$AcceptLoop.executeAcceptLoop(Unknown Source)
        at sun.rmi.transport.tcp.TCPTransport$AcceptLoop.run(Unknown Source)
        at java.lang.Thread.run(Unknown Source)

      "RMI TCP Accept-9103" daemon prio=10 tid=0x08370c00 nid=0xd97 runnable [0xde1c7000]
      java.lang.Thread.State: RUNNABLE
      at java.net.PlainSocketImpl.socketAccept(Native Method)
      at java.net.PlainSocketImpl.accept(Unknown Source)

      • locked <0xe1777510> (a java.net.SocksSocketImpl)
        at java.net.ServerSocket.implAccept(Unknown Source)
        at java.net.ServerSocket.accept(Unknown Source)
        at sun.rmi.transport.tcp.TCPTransport$AcceptLoop.executeAcceptLoop(Unknown Source)
        at sun.rmi.transport.tcp.TCPTransport$AcceptLoop.run(Unknown Source)
        at java.lang.Thread.run(Unknown Source)

      "RMI TCP Accept-0" daemon prio=10 tid=0x08325800 nid=0xd96 runnable [0xde217000]
      java.lang.Thread.State: RUNNABLE
      at java.net.PlainSocketImpl.socketAccept(Native Method)
      at java.net.PlainSocketImpl.accept(Unknown Source)

      • locked <0xe1771750> (a java.net.SocksSocketImpl)
        at java.net.ServerSocket.implAccept(Unknown Source)
        at java.net.ServerSocket.accept(Unknown Source)
        at sun.rmi.transport.tcp.TCPTransport$AcceptLoop.executeAcceptLoop(Unknown Source)
        at sun.rmi.transport.tcp.TCPTransport$AcceptLoop.run(Unknown Source)
        at java.lang.Thread.run(Unknown Source)

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

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

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

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

      "Surrogate Locker Thread (CMS)" daemon prio=10 tid=0x08225800 nid=0xd50 waiting on condition [0x00000000]
      java.lang.Thread.State: RUNNABLE

      "Finalizer" daemon prio=10 tid=0x08214000 nid=0xd42 in Object.wait() [0xde587000]
      java.lang.Thread.State: WAITING (on object monitor)
      at java.lang.Object.wait(Native Method)

      • waiting on <0xe1720a80> (a java.lang.ref.ReferenceQueue$Lock)
        at java.lang.ref.ReferenceQueue.remove(Unknown Source)
      • locked <0xe1720a80> (a java.lang.ref.ReferenceQueue$Lock)
        at java.lang.ref.ReferenceQueue.remove(Unknown Source)
        at java.lang.ref.Finalizer$FinalizerThread.run(Unknown Source)

      "Reference Handler" daemon prio=10 tid=0x08212800 nid=0xd40 in Object.wait() [0xde5d7000]
      java.lang.Thread.State: WAITING (on object monitor)
      at java.lang.Object.wait(Native Method)

      • waiting on <0xe1720990> (a java.lang.ref.Reference$Lock)
        at java.lang.Object.wait(Object.java:485)
        at java.lang.ref.Reference$ReferenceHandler.run(Unknown Source)
      • locked <0xe1720990> (a java.lang.ref.Reference$Lock)

      "main" prio=10 tid=0x08147c00 nid=0xd39 in Object.wait() [0xf69bf000]
      java.lang.Thread.State: WAITING (on object monitor)
      at java.lang.Object.wait(Native Method)

      • waiting on <0xe1a4e688> (a EDU.oswego.cs.dl.util.concurrent.WriterPreferenceReadWriteLock$WriterLock)
        at java.lang.Object.wait(Object.java:485)
        at EDU.oswego.cs.dl.util.concurrent.WriterPreferenceReadWriteLock$WriterLock.acquire(Unknown Source)
      • locked <0xe1a4e688> (a EDU.oswego.cs.dl.util.concurrent.WriterPreferenceReadWriteLock$WriterLock)
        at org.apache.jackrabbit.core.journal.AbstractJournal.lockAndSync(AbstractJournal.java:241)
        at org.apache.jackrabbit.core.journal.DefaultRecordProducer.append(DefaultRecordProducer.java:51)
        at org.apache.jackrabbit.core.cluster.ClusterNode.unregistered(ClusterNode.java:480)
        at org.apache.jackrabbit.core.nodetype.NodeTypeRegistry.unregisterNodeTypes(NodeTypeRegistry.java:314)
      • locked <0xe19c94e8> (a org.apache.jackrabbit.core.nodetype.NodeTypeRegistry)
        at org.apache.jackrabbit.core.nodetype.NodeTypeRegistry.unregisterNodeTypes(NodeTypeRegistry.java:263)
        at org.apache.jackrabbit.core.nodetype.NodeTypeRegistry.unregisterNodeType(NodeTypeRegistry.java:347)
        at org.hippoecm.repository.LocalHippoRepository.initializeNodetypes(LocalHippoRepository.java:606)
        at org.hippoecm.repository.LocalHippoRepository.initializeStartup(LocalHippoRepository.java:398)
        at org.hippoecm.repository.LocalHippoRepository.initialize(LocalHippoRepository.java:278)
        at org.hippoecm.repository.LocalHippoRepository.<init>(LocalHippoRepository.java:155)
        at org.hippoecm.repository.LocalHippoRepository.create(LocalHippoRepository.java:163)
        at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
        at sun.reflect.NativeMethodAccessorImpl.invoke(Unknown Source)
        at sun.reflect.DelegatingMethodAccessorImpl.invoke(Unknown Source)
        at java.lang.reflect.Method.invoke(Unknown Source)
        at org.hippoecm.repository.HippoRepositoryFactory.getHippoRepository(HippoRepositoryFactory.java:181)
        at org.hippoecm.repository.RepositoryServlet.init(RepositoryServlet.java:153)
        at org.apache.catalina.core.StandardWrapper.loadServlet(StandardWrapper.java:1173)
      • locked <0xe18b2da0> (a org.apache.catalina.core.StandardWrapper)
        at org.apache.catalina.core.StandardWrapper.load(StandardWrapper.java:993)
      • locked <0xe18b2da0> (a org.apache.catalina.core.StandardWrapper)
        at org.apache.catalina.core.StandardContext.loadOnStartup(StandardContext.java:4350)
        at org.apache.catalina.core.StandardContext.start(StandardContext.java:4659)
      • locked <0xe1846188> (a org.apache.catalina.core.StandardContext)
        at org.apache.catalina.core.ContainerBase.addChildInternal(ContainerBase.java:791)
      • locked <0xe18341f8> (a java.util.HashMap)
        at org.apache.catalina.core.ContainerBase.addChild(ContainerBase.java:771)
        at org.apache.catalina.core.StandardHost.addChild(StandardHost.java:546)
        at org.apache.catalina.startup.HostConfig.deployDescriptor(HostConfig.java:637)
        at org.apache.catalina.startup.HostConfig.deployDescriptors(HostConfig.java:563)
        at org.apache.catalina.startup.HostConfig.deployApps(HostConfig.java:498)
        at org.apache.catalina.startup.HostConfig.start(HostConfig.java:1277)
        at org.apache.catalina.startup.HostConfig.lifecycleEvent(HostConfig.java:321)
        at org.apache.catalina.util.LifecycleSupport.fireLifecycleEvent(LifecycleSupport.java:119)
        at org.apache.catalina.core.ContainerBase.start(ContainerBase.java:1053)
      • locked <0xe1834160> (a org.apache.catalina.core.StandardHost)
        at org.apache.catalina.core.StandardHost.start(StandardHost.java:785)
      • locked <0xe1834160> (a org.apache.catalina.core.StandardHost)
        at org.apache.catalina.core.ContainerBase.start(ContainerBase.java:1045)
      • locked <0xe182b008> (a org.apache.catalina.core.StandardEngine)
        at org.apache.catalina.core.StandardEngine.start(StandardEngine.java:445)
        at org.apache.catalina.core.StandardService.start(StandardService.java:519)
      • locked <0xe182b008> (a org.apache.catalina.core.StandardEngine)
        at org.apache.catalina.core.StandardServer.start(StandardServer.java:710)
      • locked <0xe1838270> (a [Lorg.apache.catalina.Service
        at org.apache.catalina.startup.Catalina.start(Catalina.java:581)
        at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
        at sun.reflect.NativeMethodAccessorImpl.invoke(Unknown Source)
        at sun.reflect.DelegatingMethodAccessorImpl.invoke(Unknown Source)
        at java.lang.reflect.Method.invoke(Unknown Source)
        at org.apache.catalina.startup.Bootstrap.start(Bootstrap.java:289)
        at org.apache.catalina.startup.Bootstrap.main(Bootstrap.java:414)

      "VM Thread" prio=10 tid=0x0820fc00 nid=0xd3f runnable

      "Gang worker#0 (Parallel GC Threads)" prio=10 tid=0x0814c400 nid=0xd3b runnable

      "Gang worker#1 (Parallel GC Threads)" prio=10 tid=0x0814dc00 nid=0xd3c runnable

      "Concurrent Mark-Sweep GC Thread" prio=10 tid=0x081c1000 nid=0xd3d runnable
      "VM Periodic Task Thread" prio=10 tid=0x08378000 nid=0xd9c waiting on condition

      JNI global references: 1544

      Heap
      par new generation total 14848K, used 697K [0xdf720000, 0xe0730000, 0xe1720000)
      eden space 13248K, 5% used [0xdf720000, 0xdf7ce4b8, 0xe0410000)
      from space 1600K, 0% used [0xe0410000, 0xe0410000, 0xe05a0000)
      to space 1600K, 0% used [0xe05a0000, 0xe05a0000, 0xe0730000)
      concurrent mark-sweep generation total 114688K, used 8044K [0xe1720000, 0xe8720000, 0xef720000)
      concurrent-mark-sweep perm gen total 33192K, used 19915K [0xef720000, 0xf178a000, 0xf3720000)

      Attachments

        Issue Links

          Activity

            People

              bvdschans Bart van der Schans (Inactive)
              bvdschans Bart van der Schans (Inactive)
              Votes:
              0 Vote for this issue
              Watchers:
              0 Start watching this issue

              Dates

                Created:
                Updated:
                Resolved: