topic Re: problème d'arrêt d'Alfresco 4.0.d in Alfresco Archive

problème d'arrêt d'Alfresco 4.0.d

jeanjot — Thu, 29 Mar 2012 18:28:40 GMT

BonsoirJ'ai un soucis pour stopper Alfresco.Version linux 4.0.d avec utilisation de solr.quand je stoppe Alfresco : ./alfresco/sh stop‍Celui-ci ne me rends pas la main.Les derniers messages enregistrés dans catalina.out sont :29 mars 2012 20:16:46 org.apache.coyote.http11.Http11Protocol destroyINFO:

Re: problème d'arrêt d'Alfresco 4.0.d

rguinot — Fri, 30 Mar 2012 09:04:13 GMT

Bonjour Jeanjot,

Des problèmes de ce type (thread non-daemon probablement) ont existé par le passé.
Peux tu attacher un thread dump (kill -3 ou jstack sur le process) executé pendant qu'alfresco.sh stop ne te rend pas la main ?

En fonction de ce qui reste indument actif, tu peux sans doute ajouter un kill dans le script d'arret après un petit délai d'attente.

Re: problème d'arrêt d'Alfresco 4.0.d

jeanjot — Tue, 03 Apr 2012 16:23:26 GMT

Salut Romain

Je n'avais pas vu ta réponse car je n'avais pas coché la case "M'avertir …"

Je suis ok pour la réponse des thread non-daemon qui trainent.

Voici le thread dump demandé après le lancement de la commande : ./alfresco.sh stop
J'espère que cela va te parler …

2012-04-03 18:05:49
Full thread dump Java HotSpot(TM) 64-Bit Server VM (17.1-b03 mixed mode):

"DestroyJavaVM" prio=10 tid=0x00007fdec0006800 nid=0x4f99 waiting on condition [0x0000000000000000]
   java.lang.Thread.State: RUNNABLE

"TimeLimitedCollector timer thread" daemon prio=10 tid=0x00007fdec078a000 nid=0x515f waiting on condition [0x00007fde4e9ef000]
   java.lang.Thread.State: TIMED_WAITING (sleeping)
        at java.lang.Thread.sleep(Native Method)
        at org.apache.lucene.search.TimeLimitingCollector$TimerThread.run(TimeLimitingCollector.java:78)

"RMI TCP Connection(idle)" daemon prio=10 tid=0x00007fde4800b000 nid=0x5148 waiting on condition [0x00007fde4cd4c000]
   java.lang.Thread.State: TIMED_WAITING (parking)
        at sun.misc.Unsafe.park(Native Method)
        - parking to wait for  <0x00007fde7cfc4288> (a java.util.concurrent.SynchronousQueue$TransferStack)
        at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:198)
        at java.util.concurrent.SynchronousQueue$TransferStack.awaitFulfill(SynchronousQueue.java:424)
        at java.util.concurrent.SynchronousQueue$TransferStack.transfer(SynchronousQueue.java:323)
        at java.util.concurrent.SynchronousQueue.poll(SynchronousQueue.java:874)
        at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:945)
        at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:907)
        at java.lang.Thread.run(Thread.java:662)

"RMI TCP Connection(8)-127.0.0.1" daemon prio=10 tid=0x00007fde48008800 nid=0x5143 runnable [0x00007fde1bfbe000]
   java.lang.Thread.State: RUNNABLE
        at java.net.SocketInputStream.socketRead0(Native Method)
        at java.net.SocketInputStream.read(SocketInputStream.java:129)
        at java.io.BufferedInputStream.fill(BufferedInputStream.java:218)
        at java.io.BufferedInputStream.read(BufferedInputStream.java:237)
        - locked <0x00007fdea94a7908> (a java.io.BufferedInputStream)
        at java.io.FilterInputStream.read(FilterInputStream.java:66)
        at sun.rmi.transport.tcp.TCPTransport.handleMessages(TCPTransport.java:517)
        at sun.rmi.transport.tcp.TCPTransport$ConnectionHandler.run0(TCPTransport.java:790)
        at sun.rmi.transport.tcp.TCPTransport$ConnectionHandler.run(TCPTransport.java:649)
        at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:886)
        at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:908)
        at java.lang.Thread.run(Thread.java:662)

"http-8443-5" daemon prio=10 tid=0x00007fde2c00c000 nid=0x50a0 in Object.wait() [0x00007fde4dbc0000]
   java.lang.Thread.State: WAITING (on object monitor)
        at java.lang.Object.wait(Native Method)
        - waiting on <0x00007fdeb38c9478> (a org.apache.tomcat.util.net.JIoEndpoint$Worker)
        at java.lang.Object.wait(Object.java:485)
        at org.apache.tomcat.util.net.JIoEndpoint$Worker.await(JIoEndpoint.java:458)
        - locked <0x00007fdeb38c9478> (a org.apache.tomcat.util.net.JIoEndpoint$Worker)
        at org.apache.tomcat.util.net.JIoEndpoint$Worker.run(JIoEndpoint.java:484)
        at java.lang.Thread.run(Thread.java:662)

"http-8080-2" daemon prio=10 tid=0x000000004101f800 nid=0x5097 in Object.wait() [0x00007fde1abf7000]
   java.lang.Thread.State: WAITING (on object monitor)
        at java.lang.Object.wait(Native Method)
        - waiting on <0x00007fde901d70e0> (a org.apache.tomcat.util.net.JIoEndpoint$Worker)
        at java.lang.Object.wait(Object.java:485)
        at org.apache.tomcat.util.net.JIoEndpoint$Worker.await(JIoEndpoint.java:458)
        - locked <0x00007fde901d70e0> (a org.apache.tomcat.util.net.JIoEndpoint$Worker)
        at org.apache.tomcat.util.net.JIoEndpoint$Worker.run(JIoEndpoint.java:484)
        at java.lang.Thread.run(Thread.java:662)

"http-8443-4" daemon prio=10 tid=0x00007fde2c00a000 nid=0x5092 runnable [0x00007fde1acf8000]
   java.lang.Thread.State: RUNNABLE
        at java.net.SocketInputStream.socketRead0(Native Method)
        at java.net.SocketInputStream.read(SocketInputStream.java:129)
        at com.sun.net.ssl.internal.ssl.InputRecord.readFully(InputRecord.java:293)
        at com.sun.net.ssl.internal.ssl.InputRecord.read(InputRecord.java:331)
        at com.sun.net.ssl.internal.ssl.SSLSocketImpl.readRecord(SSLSocketImpl.java:798)
        - locked <0x00007fde9012f088> (a java.lang.Object)
        at com.sun.net.ssl.internal.ssl.SSLSocketImpl.readDataRecord(SSLSocketImpl.java:755)
        at com.sun.net.ssl.internal.ssl.AppInputStream.read(AppInputStream.java:75)
        - locked <0x00007fde90130258> (a com.sun.net.ssl.internal.ssl.AppInputStream)
        at org.apache.coyote.http11.InternalInputBuffer.fill(InternalInputBuffer.java:735)
        at org.apache.coyote.http11.InternalInputBuffer.parseRequestLine(InternalInputBuffer.java:366)
        at org.apache.coyote.http11.Http11Processor.process(Http11Processor.java:814)
        at org.apache.coyote.http11.Http11Protocol$Http11ConnectionHandler.process(Http11Protocol.java:588)
        at org.apache.tomcat.util.net.JIoEndpoint$Worker.run(JIoEndpoint.java:489)
        at java.lang.Thread.run(Thread.java:662)

"http-8443-3" daemon prio=10 tid=0x00007fde2c008000 nid=0x5091 runnable [0x00007fde1adf9000]
   java.lang.Thread.State: RUNNABLE
        at java.net.SocketInputStream.socketRead0(Native Method)
        at java.net.SocketInputStream.read(SocketInputStream.java:129)
        at com.sun.net.ssl.internal.ssl.InputRecord.readFully(InputRecord.java:293)
        at com.sun.net.ssl.internal.ssl.InputRecord.read(InputRecord.java:331)
        at com.sun.net.ssl.internal.ssl.SSLSocketImpl.readRecord(SSLSocketImpl.java:798)
        - locked <0x00007fde9012d698> (a java.lang.Object)
        at com.sun.net.ssl.internal.ssl.SSLSocketImpl.readDataRecord(SSLSocketImpl.java:755)
        at com.sun.net.ssl.internal.ssl.AppInputStream.read(AppInputStream.java:75)
        - locked <0x00007fde9012e868> (a com.sun.net.ssl.internal.ssl.AppInputStream)
        at org.apache.coyote.http11.InternalInputBuffer.fill(InternalInputBuffer.java:735)
        at org.apache.coyote.http11.InternalInputBuffer.parseRequestLine(InternalInputBuffer.java:366)
        at org.apache.coyote.http11.Http11Processor.process(Http11Processor.java:814)
        at org.apache.coyote.http11.Http11Protocol$Http11ConnectionHandler.process(Http11Protocol.java:588)
        at org.apache.tomcat.util.net.JIoEndpoint$Worker.run(JIoEndpoint.java:489)
        at java.lang.Thread.run(Thread.java:662)

"http-8443-2" daemon prio=10 tid=0x00007fde2c006000 nid=0x5090 runnable [0x00007fde1aefa000]
   java.lang.Thread.State: RUNNABLE
        at java.net.SocketInputStream.socketRead0(Native Method)
        at java.net.SocketInputStream.read(SocketInputStream.java:129)
        at com.sun.net.ssl.internal.ssl.InputRecord.readFully(InputRecord.java:293)
        at com.sun.net.ssl.internal.ssl.InputRecord.read(InputRecord.java:331)
        at com.sun.net.ssl.internal.ssl.SSLSocketImpl.readRecord(SSLSocketImpl.java:798)
        - locked <0x00007fdeb37b7048> (a java.lang.Object)
        at com.sun.net.ssl.internal.ssl.SSLSocketImpl.readDataRecord(SSLSocketImpl.java:755)
        at com.sun.net.ssl.internal.ssl.AppInputStream.read(AppInputStream.java:75)
        - locked <0x00007fdeb37b8200> (a com.sun.net.ssl.internal.ssl.AppInputStream)
        at org.apache.coyote.http11.InternalInputBuffer.fill(InternalInputBuffer.java:735)
        at org.apache.coyote.http11.InternalInputBuffer.parseRequestLine(InternalInputBuffer.java:366)
        at org.apache.coyote.http11.Http11Processor.process(Http11Processor.java:814)
        at org.apache.coyote.http11.Http11Protocol$Http11ConnectionHandler.process(Http11Protocol.java:588)
        at org.apache.tomcat.util.net.JIoEndpoint$Worker.run(JIoEndpoint.java:489)
        at java.lang.Thread.run(Thread.java:662)

"http-8443-1" daemon prio=10 tid=0x00007fde2c004000 nid=0x508f runnable [0x00007fde1affb000]
   java.lang.Thread.State: RUNNABLE
        at java.net.SocketInputStream.socketRead0(Native Method)
        at java.net.SocketInputStream.read(SocketInputStream.java:129)
        at com.sun.net.ssl.internal.ssl.InputRecord.readFully(InputRecord.java:293)
        at com.sun.net.ssl.internal.ssl.InputRecord.read(InputRecord.java:331)
        at com.sun.net.ssl.internal.ssl.SSLSocketImpl.readRecord(SSLSocketImpl.java:798)
        - locked <0x00007fdeb3b5c4c0> (a java.lang.Object)
        at com.sun.net.ssl.internal.ssl.SSLSocketImpl.readDataRecord(SSLSocketImpl.java:755)
        at com.sun.net.ssl.internal.ssl.AppInputStream.read(AppInputStream.java:75)
        - locked <0x00007fdeb3b5d678> (a com.sun.net.ssl.internal.ssl.AppInputStream)
        at org.apache.coyote.http11.InternalInputBuffer.fill(InternalInputBuffer.java:735)
        at org.apache.coyote.http11.InternalInputBuffer.parseRequestLine(InternalInputBuffer.java:366)
        at org.apache.coyote.http11.Http11Processor.process(Http11Processor.java:814)
        at org.apache.coyote.http11.Http11Protocol$Http11ConnectionHandler.process(Http11Protocol.java:588)
        at org.apache.tomcat.util.net.JIoEndpoint$Worker.run(JIoEndpoint.java:489)
        at java.lang.Thread.run(Thread.java:662)

"http-8443-Acceptor-0" daemon prio=10 tid=0x00007fdec0951000 nid=0x508e waiting on condition [0x00007fde1b0fc000]
   java.lang.Thread.State: TIMED_WAITING (sleeping)
        at java.lang.Thread.sleep(Native Method)
        at org.apache.tomcat.util.net.JIoEndpoint$Acceptor.run(JIoEndpoint.java:344)
        at java.lang.Thread.run(Thread.java:662)

"TP-Processor4" daemon prio=10 tid=0x00007fdec08b8000 nid=0x508c in Object.wait() [0x00007fde1b2fe000]
   java.lang.Thread.State: WAITING (on object monitor)
        at java.lang.Object.wait(Native Method)
        - waiting on <0x00007fde9010df38> (a org.apache.jk.common.ChannelSocket)
        at java.lang.Object.wait(Object.java:485)
        at org.apache.jk.common.ChannelSocket.accept(ChannelSocket.java:306)
        - locked <0x00007fde9010df38> (a org.apache.jk.common.ChannelSocket)
        at org.apache.jk.common.ChannelSocket.acceptConnections(ChannelSocket.java:666)
        at org.apache.jk.common.ChannelSocket$SocketAcceptor.runIt(ChannelSocket.java:877)
        at org.apache.tomcat.util.threads.ThreadPool$ControlRunnable.run(ThreadPool.java:690)
        at java.lang.Thread.run(Thread.java:662)

"http-8080-1" daemon prio=10 tid=0x0000000041006000 nid=0x5088 in Object.wait() [0x00007fde1b702000]
   java.lang.Thread.State: WAITING (on object monitor)
        at java.lang.Object.wait(Native Method)
        - waiting on <0x00007fde90279750> (a org.apache.tomcat.util.net.JIoEndpoint$Worker)
        at java.lang.Object.wait(Object.java:485)
        at org.apache.tomcat.util.net.JIoEndpoint$Worker.await(JIoEndpoint.java:458)
        - locked <0x00007fde90279750> (a org.apache.tomcat.util.net.JIoEndpoint$Worker)
        at org.apache.tomcat.util.net.JIoEndpoint$Worker.run(JIoEndpoint.java:484)
        at java.lang.Thread.run(Thread.java:662)

"http-8080-Acceptor-0" daemon prio=10 tid=0x00007fdec0ea4800 nid=0x5087 waiting on condition [0x00007fde4c746000]
   java.lang.Thread.State: TIMED_WAITING (sleeping)
        at java.lang.Thread.sleep(Native Method)
        at org.apache.tomcat.util.net.JIoEndpoint$Acceptor.run(JIoEndpoint.java:344)
        at java.lang.Thread.run(Thread.java:662)

"MultiThreadedHttpConnectionManager cleanup" daemon prio=10 tid=0x00007fde44008000 nid=0x507d in Object.wait() [0x00007fde4d9be000]
   java.lang.Thread.State: WAITING (on object monitor)
        at java.lang.Object.wait(Native Method)
        - waiting on <0x00007fde8cacc988> (a java.lang.ref.ReferenceQueue$Lock)
        at java.lang.ref.ReferenceQueue.remove(ReferenceQueue.java:118)
        - locked <0x00007fde8cacc988> (a java.lang.ref.ReferenceQueue$Lock)
        at java.lang.ref.ReferenceQueue.remove(ReferenceQueue.java:134)
        at org.apache.commons.httpclient.MultiThreadedHttpConnectionManager$ReferenceQueueThread.run(MultiThreadedHttpConnectionManager.java:1122)

"MultiThreadedHttpConnectionManager cleanup" daemon prio=10 tid=0x00007fde30003800 nid=0x505e in Object.wait() [0x00007fde1c2c1000]
   java.lang.Thread.State: WAITING (on object monitor)
        at java.lang.Object.wait(Native Method)
        - waiting on <0x00007fde8353baf8> (a java.lang.ref.ReferenceQueue$Lock)
        at java.lang.ref.ReferenceQueue.remove(ReferenceQueue.java:118)
        - locked <0x00007fde8353baf8> (a java.lang.ref.ReferenceQueue$Lock)
        at java.lang.ref.ReferenceQueue.remove(ReferenceQueue.java:134)
        at org.apache.commons.httpclient.MultiThreadedHttpConnectionManager$ReferenceQueueThread.run(MultiThreadedHttpConnectionManager.java:1122)

"RMI RenewClean-[127.0.0.1:45001]" daemon prio=10 tid=0x00007fde2002d800 nid=0x505d in Object.wait() [0x00007fde1c3c2000]
   java.lang.Thread.State: TIMED_WAITING (on object monitor)
        at java.lang.Object.wait(Native Method)
        - waiting on <0x00007fde89dfbcb0> (a java.lang.ref.ReferenceQueue$Lock)
        at java.lang.ref.ReferenceQueue.remove(ReferenceQueue.java:118)
        - locked <0x00007fde89dfbcb0> (a java.lang.ref.ReferenceQueue$Lock)
        at sun.rmi.transport.DGCClient$EndpointEntry$RenewCleanThread.run(DGCClient.java:516)
        at java.lang.Thread.run(Thread.java:662)

"RMI TCP Accept-0" daemon prio=10 tid=0x00007fdec0eb8800 nid=0x505c runnable [0x00007fde1c4c3000]
   java.lang.Thread.State: RUNNABLE
        at java.net.PlainSocketImpl.socketAccept(Native Method)
        at java.net.PlainSocketImpl.accept(PlainSocketImpl.java:390)
        - locked <0x00007fde8a7387d0> (a java.net.SocksSocketImpl)
        at java.net.ServerSocket.implAccept(ServerSocket.java:453)
        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:662)

"Timer-7" prio=10 tid=0x00007fdec0e16000 nid=0x5031 in Object.wait() [0x00007fde1ebea000]
   java.lang.Thread.State: WAITING (on object monitor)
        at java.lang.Object.wait(Native Method)
        - waiting on <0x00007fde8a7d4050> (a java.util.TaskQueue)
        at java.lang.Object.wait(Object.java:485)
        at java.util.TimerThread.mainLoop(Timer.java:483)
        - locked <0x00007fde8a7d4050> (a java.util.TaskQueue)
        at java.util.TimerThread.run(Timer.java:462)

"Timer-6" prio=10 tid=0x00007fdec1084000 nid=0x502c in Object.wait() [0x00007fde1edec000]
   java.lang.Thread.State: WAITING (on object monitor)
        at java.lang.Object.wait(Native Method)
        - waiting on <0x00007fde892d4968> (a java.util.TaskQueue)
        at java.lang.Object.wait(Object.java:485)
        at java.util.TimerThread.mainLoop(Timer.java:483)
        - locked <0x00007fde892d4968> (a java.util.TaskQueue)
        at java.util.TimerThread.run(Timer.java:462)

"Timer-5" prio=10 tid=0x00007fdec163c000 nid=0x5021 in Object.wait() [0x00007fde1eeed000]
   java.lang.Thread.State: WAITING (on object monitor)
        at java.lang.Object.wait(Native Method)
        - waiting on <0x00007fde88f407f8> (a java.util.TaskQueue)
        at java.lang.Object.wait(Object.java:485)
        at java.util.TimerThread.mainLoop(Timer.java:483)
        - locked <0x00007fde88f407f8> (a java.util.TaskQueue)
        at java.util.TimerThread.run(Timer.java:462)

"Timer-4" prio=10 tid=0x00007fdec0f27000 nid=0x5020 in Object.wait() [0x00007fde1eceb000]
   java.lang.Thread.State: WAITING (on object monitor)
        at java.lang.Object.wait(Native Method)
        - waiting on <0x00007fde88f3fa60> (a java.util.TaskQueue)
        at java.lang.Object.wait(Object.java:485)
        at java.util.TimerThread.mainLoop(Timer.java:483)
        - locked <0x00007fde88f3fa60> (a java.util.TaskQueue)
        at java.util.TimerThread.run(Timer.java:462)

"Timer-3" prio=10 tid=0x00007fdec0d85000 nid=0x5011 in Object.wait() [0x00007fde1efee000]
   java.lang.Thread.State: WAITING (on object monitor)
        at java.lang.Object.wait(Native Method)
        - waiting on <0x00007fde88f43310> (a java.util.TaskQueue)
        at java.lang.Object.wait(Object.java:485)
        at java.util.TimerThread.mainLoop(Timer.java:483)
        - locked <0x00007fde88f43310> (a java.util.TaskQueue)
        at java.util.TimerThread.run(Timer.java:462)

"Timer-2" prio=10 tid=0x00007fdec10c3000 nid=0x5010 in Object.wait() [0x00007fde1f0ef000]
   java.lang.Thread.State: WAITING (on object monitor)
        at java.lang.Object.wait(Native Method)
        - waiting on <0x00007fde88f42a58> (a java.util.TaskQueue)
        at java.lang.Object.wait(Object.java:485)
        at java.util.TimerThread.mainLoop(Timer.java:483)
        - locked <0x00007fde88f42a58> (a java.util.TaskQueue)
        at java.util.TimerThread.run(Timer.java:462)

"RMI RenewClean-[127.0.0.1:48414,org.alfresco.util.remote.server.socket.HostConfigurableSocketFactory@0]" daemon prio=10 tid=0x00007fde20014800 nid=0x5002 in Object.wait() [0x00007fde1f2f1000]
   java.lang.Thread.State: TIMED_WAITING (on object monitor)
        at java.lang.Object.wait(Native Method)
        - waiting on <0x00007fde84987150> (a java.lang.ref.ReferenceQueue$Lock)
        at java.lang.ref.ReferenceQueue.remove(ReferenceQueue.java:118)
        - locked <0x00007fde84987150> (a java.lang.ref.ReferenceQueue$Lock)
        at sun.rmi.transport.DGCClient$EndpointEntry$RenewCleanThread.run(DGCClient.java:516)
        at java.lang.Thread.run(Thread.java:662)

"RMI TCP Accept-0" daemon prio=10 tid=0x00007fdec0e7e800 nid=0x5001 runnable [0x00007fde1f3f2000]
   java.lang.Thread.State: RUNNABLE
        at java.net.PlainSocketImpl.socketAccept(Native Method)
        at java.net.PlainSocketImpl.accept(PlainSocketImpl.java:390)
        - locked <0x00007fde851824f8> (a java.net.SocksSocketImpl)
        at java.net.ServerSocket.implAccept(ServerSocket.java:453)
        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:662)

"RMI RenewClean-[127.0.0.1:50508]" daemon prio=10 tid=0x00007fde5001a800 nid=0x4fe0 in Object.wait() [0x00007fde4c847000]
   java.lang.Thread.State: TIMED_WAITING (on object monitor)
        at java.lang.Object.wait(Native Method)
        - waiting on <0x00007fde81da4710> (a java.lang.ref.ReferenceQueue$Lock)
        at java.lang.ref.ReferenceQueue.remove(ReferenceQueue.java:118)
        - locked <0x00007fde81da4710> (a java.lang.ref.ReferenceQueue$Lock)
        at sun.rmi.transport.DGCClient$EndpointEntry$RenewCleanThread.run(DGCClient.java:516)
        at java.lang.Thread.run(Thread.java:662)

"RMI Scheduler(0)" daemon prio=10 tid=0x00007fdec0b59800 nid=0x4fdf waiting on condition [0x00007fde4c948000]
   java.lang.Thread.State: TIMED_WAITING (parking)
        at sun.misc.Unsafe.park(Native Method)
        - parking to wait for  <0x00007fde7cfd8788> (a java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject)
        at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:198)
        at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2025)
        at java.util.concurrent.DelayQueue.take(DelayQueue.java:164)
        at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:609)
        at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:602)
        at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:947)
        at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:907)
        at java.lang.Thread.run(Thread.java:662)

"MultiThreadedHttpConnectionManager cleanup" daemon prio=10 tid=0x00007fde24015000 nid=0x4fd9 in Object.wait() [0x00007fde4cf4e000]
   java.lang.Thread.State: WAITING (on object monitor)
        at java.lang.Object.wait(Native Method)
        - waiting on <0x00007fde80ea80a8> (a java.lang.ref.ReferenceQueue$Lock)
        at java.lang.ref.ReferenceQueue.remove(ReferenceQueue.java:118)
        - locked <0x00007fde80ea80a8> (a java.lang.ref.ReferenceQueue$Lock)
        at java.lang.ref.ReferenceQueue.remove(ReferenceQueue.java:134)
        at org.apache.commons.httpclient.MultiThreadedHttpConnectionManager$ReferenceQueueThread.run(MultiThreadedHttpConnectionManager.java:1122)

"GC Daemon" daemon prio=10 tid=0x00007fdec030c800 nid=0x4fa5 in Object.wait() [0x00007fde4eea7000]
   java.lang.Thread.State: TIMED_WAITING (on object monitor)
        at java.lang.Object.wait(Native Method)
        - waiting on <0x00007fde7cee01d8> (a sun.misc.GC$LatencyLock)
        at sun.misc.GC$Daemon.run(GC.java:100)
        - locked <0x00007fde7cee01d8> (a sun.misc.GC$LatencyLock)

"RMI TCP Accept-0" daemon prio=10 tid=0x00007fdec00e2800 nid=0x4fa3 runnable [0x00007fde4f0a9000]
   java.lang.Thread.State: RUNNABLE
        at java.net.PlainSocketImpl.socketAccept(Native Method)
        at java.net.PlainSocketImpl.accept(PlainSocketImpl.java:390)
        - locked <0x00007fde7cee02b0> (a java.net.SocksSocketImpl)
        at java.net.ServerSocket.implAccept(ServerSocket.java:453)
        at java.net.ServerSocket.accept(ServerSocket.java:421)
        at sun.management.jmxremote.LocalRMIServerSocketFactory$1.accept(LocalRMIServerSocketFactory.java:34)
        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:662)

"Low Memory Detector" daemon prio=10 tid=0x00007fdec0092800 nid=0x4fa2 runnable [0x0000000000000000]
   java.lang.Thread.State: RUNNABLE

"CompilerThread1" daemon prio=10 tid=0x00007fdec0090800 nid=0x4fa1 waiting on condition [0x0000000000000000]
   java.lang.Thread.State: RUNNABLE

"CompilerThread0" daemon prio=10 tid=0x00007fdec008d800 nid=0x4fa0 waiting on condition [0x0000000000000000]
   java.lang.Thread.State: RUNNABLE

"Signal Dispatcher" daemon prio=10 tid=0x00007fdec008b800 nid=0x4f9f waiting on condition [0x0000000000000000]
   java.lang.Thread.State: RUNNABLE

"Finalizer" daemon prio=10 tid=0x00007fdec006c800 nid=0x4f9e in Object.wait() [0x00007fde4fffe000]
   java.lang.Thread.State: WAITING (on object monitor)
        at java.lang.Object.wait(Native Method)
        - waiting on <0x00007fde7cee10f0> (a java.lang.ref.ReferenceQueue$Lock)
        at java.lang.ref.ReferenceQueue.remove(ReferenceQueue.java:118)
        - locked <0x00007fde7cee10f0> (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=0x00007fdec006a800 nid=0x4f9d in Object.wait() [0x00007fde5c1e9000]
   java.lang.Thread.State: WAITING (on object monitor)
        at java.lang.Object.wait(Native Method)
        - waiting on <0x00007fde7cee01b8> (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 <0x00007fde7cee01b8> (a java.lang.ref.Reference$Lock)

"VM Thread" prio=10 tid=0x00007fdec0066000 nid=0x4f9c runnable

"GC task thread#0 (ParallelGC)" prio=10 tid=0x00007fdec001a000 nid=0x4f9a runnable

"GC task thread#1 (ParallelGC)" prio=10 tid=0x00007fdec001b800 nid=0x4f9b runnable

"VM Periodic Task Thread" prio=10 tid=0x00007fdec0157800 nid=0x4fa4 waiting on condition

JNI global references: 1123

Heap
 PSYoungGen      total 269184K, used 55954K [0x00007fdea7990000, 0x00007fdebcc60000, 0x00007fdebcee0000)
  eden space 191552K, 15% used [0x00007fdea7990000,0x00007fdea95a4c50,0x00007fdeb34a0000)
  from space 77632K, 35% used [0x00007fdeb34a0000,0x00007fdeb4f2fc08,0x00007fdeb8070000)
  to   space 75392K, 0% used [0x00007fdeb82c0000,0x00007fdeb82c0000,0x00007fdebcc60000)
 PSOldGen        total 398208K, used 322838K [0x00007fde7cee0000, 0x00007fde953c0000, 0x00007fdea7990000)
  object space 398208K, 81% used [0x00007fde7cee0000,0x00007fde90a25808,0x00007fde953c0000)
 PSPermGen       total 222144K, used 198498K [0x00007fde5cee0000, 0x00007fde6a7d0000, 0x00007fde7cee0000)
  object space 222144K, 89% used [0x00007fde5cee0000,0x00007fde690b8a58,0x00007fde6a7d0000)

‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍

Par contre je veux bien mettre un kill -9 du processus quelque part dans le fichier : tomcat/scripts/ctl.sh, mais je vois pas bien ou le mettre ????

Mais juste pour information, il faut plus de 5 minutes (montre en main et c'est long !!!!) pour que qu'Alfresco soit stoppé proprement.

Re: problème d'arrêt d'Alfresco 4.0.d

rguinot — Tue, 03 Apr 2012 17:12:46 GMT

On voit dans ton thread dump que les domain threads qui restent sont des threads relatives au tracking solr (e.g MultiThreadedHttpConnectionManager, etc..)

Ces pbs de non-daemon threads et associés ont apparemment été corrigés pour la 4.0.1 , voir https://issues.alfresco.com/jira/browse/ALF-13432 et https://issues.alfresco.com/jira/browse/ALF-12801.

En attendant, pas la peine de faire un kill -9 , un kill suffit après avoir initié le shutdown et attendu qq secondes. Exemple, dans le script alfresco.sh :

….
elif [ "$1" = "stop" ]; then
  "${APPSERVER}/bin/shutdown.sh"
  sleep 3
  PIDS_TO_KILL=$(ps -ef | grep java | grep tomcat | sed -e 's/[a-z]\+[ ]\+//g' | cut -d ' ' -f 1 | xargs)
  if [ "x${PIDS_TO_KILL}" != "x" ]
  then
    for pid in ${PIDS_TO_KILL}
    do
        echo "killing PID : $pid"
        kill $pid
    done
  fi
…‍‍‍‍‍‍‍‍‍‍‍‍‍‍

A adapter en fonction de tes besoins et de ton infra…

Re: problème d'arrêt d'Alfresco 4.0.d

jeanjot — Tue, 03 Apr 2012 17:23:44 GMT

Merci pour la réponse rapide.

Je regarde et te tiens au courant.

Re: problème d'arrêt d'Alfresco 4.0.d

joreun — Thu, 26 Apr 2012 15:00:52 GMT

Bonjour,
Je relance ce sujet car nous rencontrons exactement le meme problème pour arreter alfresco, cependant sur la version 3.4.8 Entreprise.
La démarche est simple :
-installer avec toutes les options par défaut
-exécuter alfresco.sh start
-se connecter : ok tout va bien
-exécuter alfresco.sh stop
Le process prend systématiquement un temps aberrant pour s'arreter. Seul le kill -9 nous libère.
Ce soucis n'existait pas avec la dernière version utilisée 3.4.2 (bien qu'il y en avait un autre répertorié lié ou pas sur cette version : Cf remarque du 21/07/2011 sur https://issues.alfresco.com/jira/browse/ALF-7570?page=com.atlassian.jira.plugin.system.issuetabpanels%253Aall-tabpanel#issue-tabs)

Du coup, cela impacte notamment nos procédures de déploiement en intégration continue.
Serait-ce le meme problème en version 3.4.8 et 4.0.d ?

Ci-joint le catalina.out si çà peut éclairer :

Apr 26, 2012 3:35:27 PM org.apache.catalina.core.AprLifecycleListener init
INFO: The APR based Apache Tomcat Native library which allows optimal performance in production environments was not found on the java.library.path: /projet/alfresco/3.4.8/app/java/jre/lib/amd64/server:/projet/alfresco/3.4.8/app/java/jre/lib/amd64:/projet/alfresco/3.4.8/app/java/jre/../lib/amd64:/projet/alfresco/3.4.8/app/postgresql/lib:/projet/alfresco/3.4.8/app/common/lib::/usr/java/packages/lib/amd64:/usr/lib64:/lib64:/lib:/usr/lib
Apr 26, 2012 3:35:27 PM org.apache.coyote.http11.Http11Protocol init
INFO: Initializing Coyote HTTP/1.1 on http-8080
Apr 26, 2012 3:35:27 PM org.apache.catalina.startup.Catalina load
INFO: Initialization processed in 1335 ms
Apr 26, 2012 3:35:27 PM org.apache.catalina.core.StandardService start
INFO: Starting service Catalina
Apr 26, 2012 3:35:27 PM org.apache.catalina.core.StandardEngine start
INFO: Starting Servlet Engine: Apache Tomcat/6.0.29
Apr 26, 2012 3:35:27 PM org.apache.catalina.startup.HostConfig deployWAR
INFO: Deploying web application archive share.war
15:35:54,524  INFO  [extensions.webscripts.DeclarativeRegistry] Registered 314 Web Scripts (+0 failed), 324 URLs
15:35:54,525  INFO  [extensions.webscripts.DeclarativeRegistry] Registered 8 Package Description Documents (+0 failed) 
15:35:54,526  INFO  [extensions.webscripts.DeclarativeRegistry] Registered 0 Schema Description Documents (+0 failed) 
15:35:54,922  INFO  [extensions.webscripts.AbstractRuntimeContainer] Initialised Spring Surf Container Web Script Container (in 2835.168ms)
15:35:54,968  INFO  [extensions.webscripts.TemplateProcessorRegistry] Registered template processor freemarker for extension ftl
15:35:55,048  INFO  [extensions.webscripts.ScriptProcessorRegistry] Registered script processor javascript for extension js
15:35:55,067  INFO  [extensions.webscripts.TemplateProcessorRegistry] Registered template processor freemarker for extension ftl
15:35:55,071  INFO  [extensions.webscripts.ScriptProcessorRegistry] Registered script processor javascript for extension js
15:35:55,090  INFO  [extensions.webscripts.TemplateProcessorRegistry] Registered template processor freemarker for extension ftl
15:35:55,094  INFO  [extensions.webscripts.ScriptProcessorRegistry] Registered script processor javascript for extension js
Apr 26, 2012 3:35:57 PM org.apache.catalina.startup.HostConfig deployWAR
INFO: Deploying web application archive awe.war
Apr 26, 2012 3:36:01 PM org.apache.catalina.startup.HostConfig deployWAR
INFO: Deploying web application archive wcmqs.war
15:36:04,695 WARN  [org.apache.myfaces.shared_impl.util.LocaleUtils] Locale name in faces-config.xml null or empty, setting locale to default locale : en_US
15:36:07,839 WARN  [org.springframework.beans.GenericTypeAwarePropertyDescriptor] Invalid JavaBean property 'baseUrl' being accessed! Ambiguous write methods found next to actually used [public void org.alfresco.wcm.client.impl.WebScriptCallerImpl.setBaseUrl(java.lang.String) throws java.net.URISyntaxException]: [public void org.alfresco.wcm.client.impl.WebScriptCallerImpl.setBaseUrl(java.net.URI)]
Apr 26, 2012 3:36:11 PM org.apache.catalina.startup.HostConfig deployWAR
INFO: Deploying web application archive alfresco.war
Apr 26, 2012 3:36:20 PM org.apache.catalina.core.StandardContext addApplicationListener
INFO: The listener "org.apache.myfaces.webapp.StartupServletContextListener" is already configured for this context. The duplicate definition has been ignored.
15:36:27,841  INFO  [alfresco.config.JndiPropertiesFactoryBean] Loading properties file from class path resource [alfresco/repository.properties]
15:36:27,843  INFO  [alfresco.config.JndiPropertiesFactoryBean] Loading properties file from class path resource [alfresco/domain/transaction.properties]
15:36:27,844  INFO  [alfresco.config.JndiPropertiesFactoryBean] Loading properties file from file [/projet/alfresco/3.4.8/app/tomcat/webapps/alfresco/WEB-INF/classes/alfresco/module/org_alfresco_module_dod5015/alfresco-global.properties]
15:36:27,844  INFO  [alfresco.config.JndiPropertiesFactoryBean] Loading properties file from file [/projet/alfresco/3.4.8/app/tomcat/webapps/alfresco/WEB-INF/classes/alfresco/module/test/alfresco-global.properties]
15:36:27,844  INFO  [alfresco.config.JndiPropertiesFactoryBean] Loading properties file from file [/projet/alfresco/3.4.8/app/tomcat/webapps/alfresco/WEB-INF/classes/alfresco/module/org_alfresco_module_wcmquickstart/alfresco-global.properties]
15:36:27,844  INFO  [alfresco.config.JndiPropertiesFactoryBean] Loading properties file from URL [file:/projet/alfresco/3.4.8/app/tomcat/shared/classes/alfresco-global.properties]
15:36:28,122  INFO  [alfresco.config.FixedPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/version.properties]
15:36:28,228  INFO  [alfresco.config.JndiPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/alfresco-shared.properties]
15:36:28,321  INFO  [alfresco.config.FixedPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/domain/cache-strategies.properties]
15:36:28,358  INFO  [alfresco.config.FixedPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/module/org.alfresco.module.quickr/context/lotus.properties]
15:36:28,358  INFO  [alfresco.config.FixedPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/extension/custom-lotus.properties]
15:36:28,383  INFO  [alfresco.config.FixedPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/module/org.alfresco.module.vti/context/vti.properties]
15:36:28,383  INFO  [alfresco.config.FixedPropertyPlaceholderConfigurer] Loading properties file from URL [file:/projet/alfresco/3.4.8/app/tomcat/shared/classes/alfresco/extension/custom-vti.properties]
15:36:29,605  INFO  [management.subsystems.ChildApplicationContextFactory] Starting 'sysAdmin' subsystem, ID: [sysAdmin, default]
15:36:29,670  INFO  [alfresco.config.FixedPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/version.properties]
15:36:29,670  INFO  [alfresco.config.JndiPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/alfresco-shared.properties]
15:36:29,670  INFO  [alfresco.config.FixedPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/domain/cache-strategies.properties]
15:36:29,671  INFO  [alfresco.config.FixedPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/module/org.alfresco.module.quickr/context/lotus.properties]
15:36:29,671  INFO  [alfresco.config.FixedPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/extension/custom-lotus.properties]
15:36:29,671  INFO  [alfresco.config.FixedPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/module/org.alfresco.module.vti/context/vti.properties]
15:36:29,671  INFO  [alfresco.config.FixedPropertyPlaceholderConfigurer] Loading properties file from URL [file:/projet/alfresco/3.4.8/app/tomcat/shared/classes/alfresco/extension/custom-vti.properties]
15:36:29,680  INFO  [management.subsystems.ChildApplicationContextFactory] Startup of 'sysAdmin' subsystem, ID: [sysAdmin, default] complete
15:37:03,949  WARN  [springframework.beans.GenericTypeAwarePropertyDescriptor] Invalid JavaBean property 'serviceBeans' being accessed! Ambiguous write methods found next to actually used [public void org.apache.cxf.jaxrs.JAXRSServerFactoryBean.setServiceBeans(java.util.List)]: [public void org.apache.cxf.jaxrs.JAXRSServerFactoryBean.setServiceBeans(java.lang.Object[])]
15:37:05,713  INFO  [extensions.webscripts.TemplateProcessorRegistry] Registered template processor Repository Template Processor for extension ftl
15:37:05,717  INFO  [extensions.webscripts.ScriptProcessorRegistry] Registered script processor Repository Script Processor for extension js
15:37:12,023  INFO  [domain.schema.SchemaBootstrap] Schema managed by database dialect org.hibernate.dialect.PostgreSQLDialect.
15:37:27,273  INFO  [domain.schema.SchemaBootstrap] Executing database script /projet/alfresco/3.4.8/app/tomcat/temp/Alfresco/AlfrescoSchema-PostgreSQLDialect-Update-8000236462591011888.sql (Copied from classpath:alfresco/dbscripts/create/org.hibernate.dialect.PostgreSQLDialect/AlfrescoCreate-RepoTables.sql).
15:37:47,714  INFO  [domain.schema.SchemaBootstrap] Executing database script /projet/alfresco/3.4.8/app/tomcat/temp/Alfresco/AlfrescoSchema-PostgreSQLDialect-Update-7699407487540407537.sql (Copied from classpath:alfresco/dbscripts/create/org.hibernate.dialect.PostgreSQLDialect/AlfrescoCreate-LockTables.sql).
15:37:48,359  INFO  [domain.schema.SchemaBootstrap] Executing database script /projet/alfresco/3.4.8/app/tomcat/temp/Alfresco/AlfrescoSchema-PostgreSQLDialect-Update-4886301430495725053.sql (Copied from classpath:alfresco/dbscripts/create/org.hibernate.dialect.PostgreSQLDialect/AlfrescoCreate-ContentTables.sql).
15:37:49,783  INFO  [domain.schema.SchemaBootstrap] Executing database script /projet/alfresco/3.4.8/app/tomcat/temp/Alfresco/AlfrescoSchema-PostgreSQLDialect-Update-2784878777509850493.sql (Copied from classpath:alfresco/dbscripts/create/org.hibernate.dialect.PostgreSQLDialect/AlfrescoCreate-PropertyValueTables.sql).
15:37:52,657  INFO  [domain.schema.SchemaBootstrap] Executing database script /projet/alfresco/3.4.8/app/tomcat/temp/Alfresco/AlfrescoSchema-PostgreSQLDialect-Update-8450885883971014037.sql (Copied from classpath:alfresco/dbscripts/create/org.hibernate.dialect.PostgreSQLDialect/AlfrescoCreate-AuditTables.sql).
15:37:54,033  INFO  [domain.schema.SchemaBootstrap] Executing database script /projet/alfresco/3.4.8/app/tomcat/temp/Alfresco/AlfrescoSchema-PostgreSQLDialect-Update-177884342665093248.sql (Copied from classpath:alfresco/dbscripts/create/org.hibernate.dialect.PostgreSQLDialect/AlfrescoCreate-AvmTables.sql).
15:37:58,560  INFO  [domain.schema.SchemaBootstrap] Executing database script /projet/alfresco/3.4.8/app/tomcat/temp/Alfresco/AlfrescoSchema-PostgreSQLDialect-Update-1158834114555927077.sql (Copied from classpath:alfresco/dbscripts/create/org.hibernate.dialect.PostgreSQLDialect/AlfrescoCreate-ActivityTables.sql).
15:38:00,006  INFO  [domain.schema.SchemaBootstrap] Executing database script /projet/alfresco/3.4.8/app/tomcat/temp/Alfresco/AlfrescoSchema-PostgreSQLDialect-Update-4118852774007505826.sql (Copied from classpath:alfresco/dbscripts/create/org.hibernate.dialect.PostgreSQLDialect/AlfrescoCreate-UsageTables.sql).
15:38:00,510  INFO  [domain.schema.SchemaBootstrap] Executing database script /projet/alfresco/3.4.8/app/tomcat/temp/Alfresco/AlfrescoSchema-PostgreSQLDialect-Update-5006124001389223498.sql (Generated).
15:38:07,397  INFO  [domain.schema.SchemaBootstrap] Executing database script /projet/alfresco/3.4.8/app/tomcat/temp/Alfresco/AlfrescoSchema-PostgreSQLDialect-Update-9191948431722496024.sql (Copied from classpath:alfresco/dbscripts/create/org.hibernate.dialect.PostgreSQLDialect/AlfrescoPostCreate-JBPM-Extra.sql).
15:38:07,414  INFO  [domain.schema.SchemaBootstrap] Executing database script /projet/alfresco/3.4.8/app/tomcat/temp/Alfresco/AlfrescoSchema-PostgreSQLDialect-Update-1826740709212991139.sql (Copied from classpath:alfresco/dbscripts/create/org.hibernate.dialect.PostgreSQLDialect/AlfrescoPostCreate-JBPM-FK-indexes.sql).
15:38:15,669  INFO  [domain.schema.SchemaBootstrap] All executed statements:  /projet/alfresco/3.4.8/app/tomcat/temp/Alfresco/AlfrescoSchema-PostgreSQLDialect-All_Statements-7493564417497012917.sql.
15:38:27,529  INFO  [domain.schema.SchemaBootstrap] Normalized schema dumped to file /projet/alfresco/3.4.8/app/tomcat/temp/Alfresco/AlfrescoSchema-PostgreSQLDialect-5902243327905936406.xml.
15:38:27,900  WARN  [repo.dictionary.DictionaryRepositoryBootstrap] StoreRef 'workspace://SpacesStore' does not exist
15:38:30,307  INFO  [management.subsystems.ChildApplicationContextFactory] Starting 'thirdparty' subsystem, ID: [thirdparty, default]
15:38:30,327  INFO  [alfresco.config.FixedPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/version.properties]
15:38:30,328  INFO  [alfresco.config.JndiPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/alfresco-shared.properties]
15:38:30,329  INFO  [alfresco.config.FixedPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/domain/cache-strategies.properties]
15:38:30,329  INFO  [alfresco.config.FixedPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/module/org.alfresco.module.quickr/context/lotus.properties]
15:38:30,329  INFO  [alfresco.config.FixedPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/extension/custom-lotus.properties]
15:38:30,330  INFO  [alfresco.config.FixedPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/module/org.alfresco.module.vti/context/vti.properties]
15:38:30,330  INFO  [alfresco.config.FixedPropertyPlaceholderConfigurer] Loading properties file from URL [file:/projet/alfresco/3.4.8/app/tomcat/shared/classes/alfresco/extension/custom-vti.properties]
15:38:32,008  INFO  [management.subsystems.ChildApplicationContextFactory] Startup of 'thirdparty' subsystem, ID: [thirdparty, default] complete
15:38:32,015  INFO  [management.subsystems.ChildApplicationContextFactory] Starting 'OOoDirect' subsystem, ID: [OOoDirect, default]
15:38:32,068  INFO  [alfresco.config.FixedPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/version.properties]
15:38:32,069  INFO  [alfresco.config.JndiPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/alfresco-shared.properties]
15:38:32,069  INFO  [alfresco.config.FixedPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/domain/cache-strategies.properties]
15:38:32,069  INFO  [alfresco.config.FixedPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/module/org.alfresco.module.quickr/context/lotus.properties]
15:38:32,070  INFO  [alfresco.config.FixedPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/extension/custom-lotus.properties]
15:38:32,071  INFO  [alfresco.config.FixedPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/module/org.alfresco.module.vti/context/vti.properties]
15:38:32,071  INFO  [alfresco.config.FixedPropertyPlaceholderConfigurer] Loading properties file from URL [file:/projet/alfresco/3.4.8/app/tomcat/shared/classes/alfresco/extension/custom-vti.properties]
15:38:32,509  WARN  [alfresco.util.OpenOfficeConnectionTester] An initial OpenOffice connection could not be established.
15:38:32,520  INFO  [management.subsystems.ChildApplicationContextFactory] Startup of 'OOoDirect' subsystem, ID: [OOoDirect, default] complete
15:38:32,577  INFO  [management.subsystems.ChildApplicationContextFactory] Starting 'OOoJodconverter' subsystem, ID: [OOoJodconverter, default]
15:38:32,602  INFO  [alfresco.config.FixedPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/version.properties]
15:38:32,603  INFO  [alfresco.config.JndiPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/alfresco-shared.properties]
15:38:32,603  INFO  [alfresco.config.FixedPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/domain/cache-strategies.properties]
15:38:32,603  INFO  [alfresco.config.FixedPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/module/org.alfresco.module.quickr/context/lotus.properties]
15:38:32,603  INFO  [alfresco.config.FixedPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/extension/custom-lotus.properties]
15:38:32,604  INFO  [alfresco.config.FixedPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/module/org.alfresco.module.vti/context/vti.properties]
15:38:32,604  INFO  [alfresco.config.FixedPropertyPlaceholderConfigurer] Loading properties file from URL [file:/projet/alfresco/3.4.8/app/tomcat/shared/classes/alfresco/extension/custom-vti.properties]
15:38:42,542  INFO  [management.subsystems.ChildApplicationContextFactory] Startup of 'OOoJodconverter' subsystem, ID: [OOoJodconverter, default] complete
15:39:10,057  INFO  [service.descriptor.DescriptorService] Alfresco license: Creating time limited trial license
15:39:11,569  INFO  [repo.admin.ConfigurationChecker] The Alfresco root data directory ('dir.root') is: /projet/alfresco/3.4.8/app/alf_data
15:39:11,608  INFO  [admin.patch.PatchExecuter] Checking for patches to apply …
15:39:14,255  INFO  [admin.patch.PatchExecuter]    Applying patch 'patch.wcmFolders' (Ensures the existance of the WCM specific 'Web Projects' and 'Web Forms' folders.).
15:39:15,975  INFO  [admin.patch.PatchExecuter]    Applying patch 'patch.wcmDeployed' (null).
15:39:17,937  WARN  [admin.patch.PatchExecuter] Patch description is not available: Patch[ id=patch.wcmDeployed, description=patch.wcmDeployed.description, fixesFromSchema=0, fixesToSchema=4208, targetSchema=10000]
15:39:17,985  INFO  [admin.patch.PatchExecuter] 
=== Applied patch                 === 
ID: patch.db-V3.4-UsageTables
RESULT: 
Script completed
=====================================
15:39:17,985  INFO  [admin.patch.PatchExecuter] 
=== Applied patch                 === 
ID: patch.db-V3.0-ActivityTables
RESULT: 
Script completed
=====================================
15:39:17,985  INFO  [admin.patch.PatchExecuter] 
=== Applied patch                 === 
ID: patch.db-V3.2-LockTables
RESULT: 
Script completed
=====================================
15:39:17,985  INFO  [admin.patch.PatchExecuter] 
=== Applied patch                 === 
ID: patch.db-V3.2-ContentTables
RESULT: 
Script completed
=====================================
15:39:17,986  INFO  [admin.patch.PatchExecuter] 
=== Applied patch                 === 
ID: patch.db-V3.2-PropertyValueTables
RESULT: 
Script completed
=====================================
15:39:17,986  INFO  [admin.patch.PatchExecuter] 
=== Applied patch                 === 
ID: patch.db-V3.2-AuditTables
RESULT: 
Script completed
=====================================
15:39:17,986  INFO  [admin.patch.PatchExecuter] 
=== Applied patch                 === 
ID: patch.db-V3.2-AvmTables
RESULT: 
Script completed
=====================================
15:39:17,986  INFO  [admin.patch.PatchExecuter] 
=== Applied patch                 === 
ID: patch.db-V3.3-JBPM-Extra
RESULT: 
Script completed
=====================================
15:39:17,986  INFO  [admin.patch.PatchExecuter] 
=== Applied patch                 === 
ID: patch.db-V3.4-JBPM-FK-indexes
RESULT: 
Script completed
=====================================
15:39:17,986  INFO  [admin.patch.PatchExecuter] 
=== Applied patch                 === 
ID: patch.wcmFolders
RESULT: 
The Web Projects folder was successfully created: workspace://SpacesStore/b05f0bb2-37ca-439b-b8f3-a62351c5041a
The Web Forms folder was successfully created: workspace://SpacesStore/b05f0bb2-37ca-439b-b8f3-a62351c5041a
=====================================
15:39:17,986  INFO  [admin.patch.PatchExecuter] 
=== Applied patch                 === 
ID: patch.wcmDeployed
RESULT: 
Imported view into bootstrap location: /app:company_home (workspace://SpacesStore/15f46d5e-e467-4fc2-8c17-48a6a50267ed)
=====================================
15:39:18,431  INFO  [repo.module.ModuleServiceImpl] Found 4 module(s).
15:39:18,588  INFO  [repo.module.ModuleServiceImpl] Installing module 'org_alfresco_module_wcmquickstart' version 1.0.
15:39:18,695  INFO  [repo.module.ModuleServiceImpl] Installing module 'org_alfresco_module_dod5015' version 1.0.
15:39:19,590  INFO  [repo.module.ModuleServiceImpl] Installing module 'org.alfresco.module.vti' version 1.2.
15:39:19,631  INFO  [repo.module.ModuleServiceImpl] Installing module 'org.alfresco.module.quickr' version 1.0.
15:39:21,552  INFO  [management.subsystems.ChildApplicationContextFactory] Starting 'fileServers' subsystem, ID: [fileServers, default]
15:39:21,588  INFO  [alfresco.config.FixedPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/version.properties]
15:39:21,589  INFO  [alfresco.config.JndiPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/alfresco-shared.properties]
15:39:21,590  INFO  [alfresco.config.FixedPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/domain/cache-strategies.properties]
15:39:21,590  INFO  [alfresco.config.FixedPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/module/org.alfresco.module.quickr/context/lotus.properties]
15:39:21,590  INFO  [alfresco.config.FixedPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/extension/custom-lotus.properties]
15:39:21,591  INFO  [alfresco.config.FixedPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/module/org.alfresco.module.vti/context/vti.properties]
15:39:21,591  INFO  [alfresco.config.FixedPropertyPlaceholderConfigurer] Loading properties file from URL [file:/projet/alfresco/3.4.8/app/tomcat/shared/classes/alfresco/extension/custom-vti.properties]
15:39:22,210  INFO  [management.subsystems.ChildApplicationContextFactory] Starting 'Authentication' subsystem, ID: [Authentication, managed, alfrescoNtlm1]
15:39:22,231  INFO  [alfresco.config.FixedPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/version.properties]
15:39:22,236  INFO  [alfresco.config.JndiPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/alfresco-shared.properties]
15:39:22,236  INFO  [alfresco.config.FixedPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/domain/cache-strategies.properties]
15:39:22,236  INFO  [alfresco.config.FixedPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/module/org.alfresco.module.quickr/context/lotus.properties]
15:39:22,237  INFO  [alfresco.config.FixedPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/extension/custom-lotus.properties]
15:39:22,237  INFO  [alfresco.config.FixedPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/module/org.alfresco.module.vti/context/vti.properties]
15:39:22,237  INFO  [alfresco.config.FixedPropertyPlaceholderConfigurer] Loading properties file from URL [file:/projet/alfresco/3.4.8/app/tomcat/shared/classes/alfresco/extension/custom-vti.properties]
15:39:22,363  INFO  [management.subsystems.ChildApplicationContextFactory] Startup of 'Authentication' subsystem, ID: [Authentication, managed, alfrescoNtlm1] complete
15:39:27,402  ERROR [org.alfresco.fileserver] Failed to get local domain/workgroup name, using default of WORKGROUP
15:39:27,402  ERROR [org.alfresco.fileserver] (This may be due to firewall settings or incorrect <broadcast> setting)
15:39:27,535  INFO  [management.subsystems.ChildApplicationContextFactory] Startup of 'fileServers' subsystem, ID: [fileServers, default] complete
15:39:27,535  INFO  [management.subsystems.ChildApplicationContextFactory] Starting 'imap' subsystem, ID: [imap, default]
15:39:27,556  ERROR [org.alfresco.fileserver] [SMB] Server error : org.alfresco.jlan.server.config.InvalidConfigurationException: Error initializing TCP-IP SMB session handler, Permission denied
15:39:27,647  ERROR [org.alfresco.fileserver] org.alfresco.jlan.server.config.InvalidConfigurationException: Error initializing TCP-IP SMB session handler, Permission denied
org.alfresco.jlan.server.config.InvalidConfigurationException: Error initializing TCP-IP SMB session handler, Permission denied
   at org.alfresco.jlan.smb.server.nio.NIOCifsConnectionsHandler.initializeHandler(NIOCifsConnectionsHandler.java:259)
   at org.alfresco.jlan.smb.server.SMBServer.run(SMBServer.java:475)
   at java.lang.Thread.run(Thread.java:662)
15:39:27,648  INFO  [alfresco.config.FixedPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/version.properties]
15:39:27,659  INFO  [alfresco.config.JndiPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/alfresco-shared.properties]
15:39:27,659  INFO  [alfresco.config.FixedPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/domain/cache-strategies.properties]
15:39:27,659  INFO  [alfresco.config.FixedPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/module/org.alfresco.module.quickr/context/lotus.properties]
15:39:27,659  INFO  [alfresco.config.FixedPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/extension/custom-lotus.properties]
15:39:27,660  INFO  [alfresco.config.FixedPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/module/org.alfresco.module.vti/context/vti.properties]
15:39:27,660  INFO  [alfresco.config.FixedPropertyPlaceholderConfigurer] Loading properties file from URL [file:/projet/alfresco/3.4.8/app/tomcat/shared/classes/alfresco/extension/custom-vti.properties]
15:39:27,764  INFO  [management.subsystems.ChildApplicationContextFactory] Startup of 'imap' subsystem, ID: [imap, default] complete
15:39:27,764  INFO  [management.subsystems.ChildApplicationContextFactory] Starting 'email' subsystem, ID: [email, outbound]
15:39:27,783  INFO  [alfresco.config.FixedPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/version.properties]
15:39:27,783  INFO  [alfresco.config.JndiPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/alfresco-shared.properties]
15:39:27,784  INFO  [alfresco.config.FixedPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/domain/cache-strategies.properties]
15:39:27,784  INFO  [alfresco.config.FixedPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/module/org.alfresco.module.quickr/context/lotus.properties]
15:39:27,784  INFO  [alfresco.config.FixedPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/extension/custom-lotus.properties]
15:39:27,784  INFO  [alfresco.config.FixedPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/module/org.alfresco.module.vti/context/vti.properties]
15:39:27,785  INFO  [alfresco.config.FixedPropertyPlaceholderConfigurer] Loading properties file from URL [file:/projet/alfresco/3.4.8/app/tomcat/shared/classes/alfresco/extension/custom-vti.properties]
15:39:27,866  INFO  [management.subsystems.ChildApplicationContextFactory] Startup of 'email' subsystem, ID: [email, outbound] complete
15:39:27,867  INFO  [management.subsystems.ChildApplicationContextFactory] Starting 'email' subsystem, ID: [email, inbound]
15:39:27,881  INFO  [alfresco.config.FixedPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/version.properties]
15:39:27,881  INFO  [alfresco.config.JndiPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/alfresco-shared.properties]
15:39:27,883  INFO  [alfresco.config.FixedPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/domain/cache-strategies.properties]
15:39:27,883  INFO  [alfresco.config.FixedPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/module/org.alfresco.module.quickr/context/lotus.properties]
15:39:27,883  INFO  [alfresco.config.FixedPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/extension/custom-lotus.properties]
15:39:27,884  INFO  [alfresco.config.FixedPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/module/org.alfresco.module.vti/context/vti.properties]
15:39:27,884  INFO  [alfresco.config.FixedPropertyPlaceholderConfigurer] Loading properties file from URL [file:/projet/alfresco/3.4.8/app/tomcat/shared/classes/alfresco/extension/custom-vti.properties]
15:39:27,962  INFO  [management.subsystems.ChildApplicationContextFactory] Startup of 'email' subsystem, ID: [email, inbound] complete
15:39:27,962  INFO  [management.subsystems.ChildApplicationContextFactory] Starting 'googledocs' subsystem, ID: [googledocs, default]
15:39:27,993  INFO  [alfresco.config.FixedPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/version.properties]
15:39:27,994  INFO  [alfresco.config.JndiPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/alfresco-shared.properties]
15:39:27,999  INFO  [alfresco.config.FixedPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/domain/cache-strategies.properties]
15:39:27,999  INFO  [alfresco.config.FixedPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/module/org.alfresco.module.quickr/context/lotus.properties]
15:39:27,999  INFO  [alfresco.config.FixedPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/extension/custom-lotus.properties]
15:39:28,000  INFO  [alfresco.config.FixedPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/module/org.alfresco.module.vti/context/vti.properties]
15:39:28,000  INFO  [alfresco.config.FixedPropertyPlaceholderConfigurer] Loading properties file from URL [file:/projet/alfresco/3.4.8/app/tomcat/shared/classes/alfresco/extension/custom-vti.properties]
15:39:28,472  INFO  [management.subsystems.ChildApplicationContextFactory] Startup of 'googledocs' subsystem, ID: [googledocs, default] complete
15:39:28,472  INFO  [repo.usage.UserUsageTrackingComponent] Enabled - calculate missing user usages …
15:39:28,513  INFO  [repo.usage.UserUsageTrackingComponent] Found 0 users to recalculate
15:39:28,514  INFO  [repo.usage.UserUsageTrackingComponent] … calculated missing usages for 0 users
15:39:28,514  INFO  [management.subsystems.ChildApplicationContextFactory] Starting 'Synchronization' subsystem, ID: [Synchronization, default]
15:39:28,536  INFO  [alfresco.config.FixedPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/version.properties]
15:39:28,537  INFO  [alfresco.config.JndiPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/alfresco-shared.properties]
15:39:28,537  INFO  [alfresco.config.FixedPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/domain/cache-strategies.properties]
15:39:28,537  INFO  [alfresco.config.FixedPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/module/org.alfresco.module.quickr/context/lotus.properties]
15:39:28,537  INFO  [alfresco.config.FixedPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/extension/custom-lotus.properties]
15:39:28,537  INFO  [alfresco.config.FixedPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/module/org.alfresco.module.vti/context/vti.properties]
15:39:28,537  INFO  [alfresco.config.FixedPropertyPlaceholderConfigurer] Loading properties file from URL [file:/projet/alfresco/3.4.8/app/tomcat/shared/classes/alfresco/extension/custom-vti.properties]
15:39:28,972  INFO  [management.subsystems.ChildApplicationContextFactory] Startup of 'Synchronization' subsystem, ID: [Synchronization, default] complete
15:39:28,999  INFO  [alfresco.util.OpenOfficeConnectionTester] The OpenOffice connection was re-established.
15:39:29,083  INFO  [service.descriptor.DescriptorService] Alfresco JVM - v1.6.0_22-b04; maximum heap size 910.250MB
15:39:31,490  INFO  [service.descriptor.DescriptorService] Alfresco license: Enterprise - v3.4 granted to Trial User limited to 30 days expiring Sat May 26 00:00:00 CEST 2012 (30 days remaining)
15:39:31,491  INFO  [service.descriptor.DescriptorService] Alfresco started (Enterprise): Current version 3.4.8 (638) schema 4208 - Originally installed version 3.4.8 (638) schema 4208
15:39:31,491  INFO  [management.subsystems.ChildApplicationContextFactory] Starting 'Replication' subsystem, ID: [Replication, default]
15:39:31,513  INFO  [alfresco.config.FixedPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/version.properties]
15:39:31,514  INFO  [alfresco.config.JndiPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/alfresco-shared.properties]
15:39:31,514  INFO  [alfresco.config.FixedPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/domain/cache-strategies.properties]
15:39:31,514  INFO  [alfresco.config.FixedPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/module/org.alfresco.module.quickr/context/lotus.properties]
15:39:31,514  INFO  [alfresco.config.FixedPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/extension/custom-lotus.properties]
15:39:31,514  INFO  [alfresco.config.FixedPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/module/org.alfresco.module.vti/context/vti.properties]
15:39:31,515  INFO  [alfresco.config.FixedPropertyPlaceholderConfigurer] Loading properties file from URL [file:/projet/alfresco/3.4.8/app/tomcat/shared/classes/alfresco/extension/custom-vti.properties]
15:39:31,518  INFO  [management.subsystems.ChildApplicationContextFactory] Startup of 'Replication' subsystem, ID: [Replication, default] complete
15:39:35,787  INFO  [module.vti.VtiServer] Vti server started successfully on port: 7070
15:39:35,788  INFO  [module.vti.VtiServer] Vti server SessionIdManagerWorkerName: jetty1
15:39:54,992  INFO  [extensions.webscripts.DeclarativeRegistry] Registered 463 Web Scripts (+0 failed), 715 URLs
15:39:54,993  INFO  [extensions.webscripts.DeclarativeRegistry] Registered 2 Package Description Documents (+0 failed) 
15:39:54,993  INFO  [extensions.webscripts.DeclarativeRegistry] Registered 1 Schema Description Documents (+0 failed) 
15:39:54,995  INFO  [extensions.webscripts.AbstractRuntimeContainer] Initialised Repository Web Script Container (in 13690.728ms)
15:39:55,011  INFO  [extensions.webscripts.TemplateProcessorRegistry] Registered template processor freemarker for extension ftl
15:39:55,015  INFO  [extensions.webscripts.ScriptProcessorRegistry] Registered script processor javascript for extension js
15:39:55,499  INFO  [management.subsystems.ChildApplicationContextFactory] Starting 'wcm_deployment_receiver' subsystem, ID: [wcm_deployment_receiver, default]
15:39:55,547  INFO  [alfresco.config.FixedPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/version.properties]
15:39:55,548  INFO  [alfresco.config.JndiPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/alfresco-shared.properties]
15:39:55,548  INFO  [alfresco.config.FixedPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/domain/cache-strategies.properties]
15:39:55,548  INFO  [alfresco.config.FixedPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/module/org.alfresco.module.quickr/context/lotus.properties]
15:39:55,548  INFO  [alfresco.config.FixedPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/extension/custom-lotus.properties]
15:39:55,549  INFO  [alfresco.config.FixedPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/module/org.alfresco.module.vti/context/vti.properties]
15:39:55,549  INFO  [alfresco.config.FixedPropertyPlaceholderConfigurer] Loading properties file from URL [file:/projet/alfresco/3.4.8/app/tomcat/shared/classes/alfresco/extension/custom-vti.properties]
15:39:55,790  INFO  [management.subsystems.ChildApplicationContextFactory] Startup of 'wcm_deployment_receiver' subsystem, ID: [wcm_deployment_receiver, default] complete
Apr 26, 2012 3:39:57 PM org.apache.catalina.startup.HostConfig deployDirectory
INFO: Deploying web application directory ROOT
Apr 26, 2012 3:39:57 PM org.apache.catalina.startup.HostConfig deployDirectory
INFO: Deploying web application directory manager
Apr 26, 2012 3:39:57 PM org.apache.catalina.startup.HostConfig deployDirectory
INFO: Deploying web application directory host-manager
Apr 26, 2012 3:39:57 PM org.apache.coyote.http11.Http11Protocol start
INFO: Starting Coyote HTTP/1.1 on http-8080
Apr 26, 2012 3:39:57 PM org.apache.jk.common.ChannelSocket init
INFO: JK: ajp13 listening on /0.0.0.0:8009
Apr 26, 2012 3:39:57 PM org.apache.jk.server.JkMain start
INFO: Jk running ID=0 time=0/87  config=null
Apr 26, 2012 3:39:57 PM org.apache.catalina.startup.Catalina start
INFO: Server startup in 270107 ms
Apr 26, 2012 3:41:15 PM org.apache.coyote.http11.Http11Protocol pause
INFO: Pausing Coyote HTTP/1.1 on http-8080
Apr 26, 2012 3:41:16 PM org.apache.catalina.core.StandardService stop
INFO: Stopping service Catalina
Apr 26, 2012 3:41:16 PM org.apache.catalina.loader.WebappClassLoader clearThreadLocalMap
SEVERE: The web application [/share] created a ThreadLocal with key of type [null] (value [org.springframework.extensions.webscripts.processor.FTLTemplateProcessor$NonBlockingObjectWrapper$1@1dbf9510]) and a value of type [freemarker.template.DefaultObjectWrapper] (value [freemarker.template.DefaultObjectWrapper@cd35cb2]) but failed to remove it when the web application was stopped. This is very likely to create a memory leak.
Apr 26, 2012 3:41:16 PM org.apache.catalina.loader.WebappClassLoader clearThreadLocalMap
SEVERE: The web application [/share] created a ThreadLocal with key of type [null] (value [org.springframework.extensions.webscripts.processor.FTLTemplateProcessor$NonBlockingObjectWrapper$1@86ab0b2]) and a value of type [freemarker.template.DefaultObjectWrapper] (value [freemarker.template.DefaultObjectWrapper@14767a6f]) but failed to remove it when the web application was stopped. This is very likely to create a memory leak.
Apr 26, 2012 3:41:16 PM org.apache.catalina.loader.WebappClassLoader clearThreadLocalMap

…

Apr 26, 2012 3:41:17 PM org.apache.catalina.loader.WebappClassLoader clearThreadLocalMap
SEVERE: The web application [/wcmqs] created a ThreadLocal with key of type [null] (value [org.springframework.extensions.webscripts.processor.FTLTemplateProcessor$NonBlockingObjectWrapper$1@67ac602]) and a value of type [freemarker.template.DefaultObjectWrapper] (value [freemarker.template.DefaultObjectWrapper@665db0a0]) but failed to remove it when the web application was stopped. This is very likely to create a memory leak.
log4j:ERROR LogMananger.repositorySelector was null likely due to error in class reloading, using NOPLoggerRepository.
15:41:19,178  INFO  [management.subsystems.ChildApplicationContextFactory] Stopping 'wcm_deployment_receiver' subsystem, ID: [wcm_deployment_receiver, default]
15:41:19,180  INFO  [management.subsystems.ChildApplicationContextFactory] Stopped 'wcm_deployment_receiver' subsystem, ID: [wcm_deployment_receiver, default]
15:41:19,429  INFO  [management.subsystems.ChildApplicationContextFactory] Stopping 'googledocs' subsystem, ID: [googledocs, default]
15:41:19,429  INFO  [management.subsystems.ChildApplicationContextFactory] Stopped 'googledocs' subsystem, ID: [googledocs, default]
15:41:19,429  INFO  [management.subsystems.ChildApplicationContextFactory] Stopping 'email' subsystem, ID: [email, inbound]
15:41:19,430  INFO  [management.subsystems.ChildApplicationContextFactory] Stopped 'email' subsystem, ID: [email, inbound]
15:41:19,496  INFO  [management.subsystems.ChildApplicationContextFactory] Stopping 'OOoJodconverter' subsystem, ID: [OOoJodconverter, default]
15:41:19,815  INFO  [management.subsystems.ChildApplicationContextFactory] Stopped 'OOoJodconverter' subsystem, ID: [OOoJodconverter, default]
15:41:19,823  INFO  [management.subsystems.ChildApplicationContextFactory] Stopping 'Replication' subsystem, ID: [Replication, default]
15:41:19,823  INFO  [management.subsystems.ChildApplicationContextFactory] Stopped 'Replication' subsystem, ID: [Replication, default]
15:41:19,834  INFO  [management.subsystems.ChildApplicationContextFactory] Stopping 'Synchronization' subsystem, ID: [Synchronization, default]
15:41:19,834  INFO  [management.subsystems.ChildApplicationContextFactory] Stopped 'Synchronization' subsystem, ID: [Synchronization, default]
15:41:19,835  INFO  [management.subsystems.ChildApplicationContextFactory] Stopping 'fileServers' subsystem, ID: [fileServers, default]
15:41:19,840  INFO  [management.subsystems.ChildApplicationContextFactory] Stopped 'fileServers' subsystem, ID: [fileServers, default]
15:41:19,848  INFO  [management.subsystems.ChildApplicationContextFactory] Stopping 'OOoDirect' subsystem, ID: [OOoDirect, default]
15:41:19,848  INFO  [management.subsystems.ChildApplicationContextFactory] Stopped 'OOoDirect' subsystem, ID: [OOoDirect, default]
15:41:19,849  INFO  [management.subsystems.ChildApplicationContextFactory] Stopping 'email' subsystem, ID: [email, outbound]
15:41:19,849  INFO  [management.subsystems.ChildApplicationContextFactory] Stopped 'email' subsystem, ID: [email, outbound]
15:41:19,849  INFO  [management.subsystems.ChildApplicationContextFactory] Stopping 'imap' subsystem, ID: [imap, default]
15:41:19,851  INFO  [management.subsystems.ChildApplicationContextFactory] Stopped 'imap' subsystem, ID: [imap, default]
15:41:20,167  INFO  [management.subsystems.ChildApplicationContextFactory] Stopping 'thirdparty' subsystem, ID: [thirdparty, default]
15:41:20,167  INFO  [management.subsystems.ChildApplicationContextFactory] Stopped 'thirdparty' subsystem, ID: [thirdparty, default]
15:41:20,190  INFO  [management.subsystems.ChildApplicationContextFactory] Stopping 'Authentication' subsystem, ID: [Authentication, managed, alfrescoNtlm1]
15:41:20,190  INFO  [management.subsystems.ChildApplicationContextFactory] Stopped 'Authentication' subsystem, ID: [Authentication, managed, alfrescoNtlm1]
15:41:20,275  INFO  [management.subsystems.ChildApplicationContextFactory] Stopping 'sysAdmin' subsystem, ID: [sysAdmin, default]
15:41:20,275  INFO  [management.subsystems.ChildApplicationContextFactory] Stopped 'sysAdmin' subsystem, ID: [sysAdmin, default]
Apr 26, 2012 3:41:20 PM org.apache.catalina.loader.WebappClassLoader clearReferencesThreads
SEVERE: The web application [/alfresco] appears to have started a thread named [IndexInfo Cleaner Deamon] but has failed to stop it. This is very likely to create a memory leak.

…

Apr 26, 2012 3:41:20 PM org.apache.catalina.loader.WebappClassLoader clearThreadLocalMap
SEVERE: The web application [/alfresco] created a ThreadLocal with key of type [java.lang.ThreadLocal] (value [java.lang.ThreadLocal@4c888ab4]) and a value of type [null] (value [org.alfresco.repo.web.scripts.RepositoryImageResolver$1@5292fb8e]) but failed to remove it when the web application was stopped. This is very likely to create a memory leak.
log4j:ERROR LogMananger.repositorySelector was null likely due to error in class reloading, using NOPLoggerRepository.
Exception in thread "IndexInfo Cleaner Deamon" java.lang.NullPointerException
   at org.alfresco.util.DynamicallySizedThreadPoolExecutor.execute(DynamicallySizedThreadPoolExecutor.java:88)
   at org.alfresco.repo.search.impl.lucene.index.IndexInfo$AbstractSchedulable.schedule(IndexInfo.java:3262)
   at org.alfresco.repo.search.impl.lucene.index.IndexInfo$4.run(IndexInfo.java:802)
   at java.util.TimerThread.mainLoop(Timer.java:512)
   at java.util.TimerThread.run(Timer.java:462)
Apr 26, 2012 3:41:24 PM org.apache.catalina.loader.WebappClassLoader clearThreadLocalMap
SEVERE: The web application [/awe] created a ThreadLocal with key of type [null] (value [org.springframework.extensions.webscripts.processor.FTLTemplateProcessor$NonBlockingObjectWrapper$1@15436088]) and a value of type [freemarker.template.DefaultObjectWrapper] (value [freemarker.template.DefaultObjectWrapper@7a768b9f]) but failed to remove it when the web application was stopped. This is very likely to create a memory leak.
Apr 26, 2012 3:41:24 PM org.apache.catalina.loader.WebappClassLoader clearThreadLocalMap
SEVERE: The web application [/awe] created a ThreadLocal with key of type [null] (value [org.springframework.extensions.webscripts.processor.FTLTemplateProcessor$NonBlockingObjectWrapper$1@7cc8b288]) and a value of type [freemarker.template.DefaultObjectWrapper] (value [freemarker.template.DefaultObjectWrapper@5c413eb8]) but failed to remove it when the web application was stopped. This is very likely to create a memory leak.
Apr 26, 2012 3:41:24 PM org.apache.catalina.loader.WebappClassLoader clearThreadLocalMap
SEVERE: The web application [/awe] created a ThreadLocal with key of type [null] (value [org.springframework.extensions.webscripts.processor.FTLTemplateProcessor$NonBlockingObjectWrapper$1@3f7cd159]) and a value of type [freemarker.template.DefaultObjectWrapper] (value [freemarker.template.DefaultObjectWrapper@1b944a9f]) but failed to remove it when the web application was stopped. This is very likely to create a memory leak.
log4j:ERROR LogMananger.repositorySelector was null likely due to error in class reloading, using NOPLoggerRepository.
Apr 26, 2012 3:41:24 PM org.apache.coyote.http11.Http11Protocol destroy
INFO: Stopping Coyote HTTP/1.1 on http-8080‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍

A noter lors de chaque arret Tomcat, les "SEVERE" systématiques en 3.4.x (que tout le monde a je suppose), mais surtout le

log4j:ERROR LogMananger.repositorySelector was null likely due to error in class reloading, using NOPLoggerRepository.
Exception in thread "IndexInfo Cleaner Deamon" java.lang.NullPointerException‍‍

qui n'existait pas en 3.4.2