AnsweredAssumed Answered

Alfresco 3.4.b nach Neustart kein Zugriff mehr

Question asked by turbotoi on Dec 17, 2010
Latest reply on Jan 8, 2011 by turbotoi
Hallo zusammen,

ich habe vor Kurzem Alfresco 3.4.b CE auf einem VMware (ESX4i) - CentOS 5.5 installiert und konnte es einige Zeit fehlerfrei nutzen.

Als ich gestern den VMWare - Host zu Wartungszwecken heruntergefahren  (alfresco wurde vorher über das "/etc/init.d/alfresco stop" beendet, auch die VM habe ich sauber heruntergefahren) und danach wieder hochgefahren habe, hatte ich keinen Zugriff mehr auf die /alfresco Seite mehr

(Fehlermeldung im Browser: HTTP Status 404 - type Status report message description The requested resource () is not available. Apache Tomcat/6.0.26)

Ich habe erstmal die komplette VMWare per Snapshot gesichert, heruntergefahren, auf dem Host in ein anderes Verzeichnis kopiert und versucht, der Sache auf den Grund zu gehen.

Mittlerweile habe ich auch verstanden, dass dieses Symptom die unterschiedlichsten Ursachen haben kann und ich konnte in den Alfresco - Foren leider keinen Ansatz finden, der mir weiterhalf.

Was im catalina.out - Log (folgt) auffällig ist, sind die JAVA - OutOfMemory Fehler.
An der Stelle habe ich schon versucht dem java über die setenv.sh mehr Speicher mitzugeben –> ohne Erfolg (Standardeinstellungen sind bei mir: -XX:MaxPermSize=512m -Xms128m -Xmx768m)

Die VMware hat 4,2Gig Swap (ungenutzt) sowie 2,2Gig RAM

Zuletzt habe ich eine neue virtuelle Maschine mit Centos 5.5 aufgesetzt, Alfresco installiert, zum Laufen gebracht und sowohl das /mysql und das /alf_data - Verzeichnis mit denen aus dem alten System ausgetauscht. Nach meinem Verständnis wird dies ja auch bei einem Backup weggesichert und neu eingespielt, auch die lucene-indexes - Ordner habe ich testweise gelöscht, da ich gelesen habe, dass sonst die Neuindizierung ggf. nicht funktioniert.
Das Ergebnis ist identisch zu dem der alten Virtuellen Maschine. Die tomcat und mysql - Prozesse laufen soweit, auf die mysql-Daten kann ich mit phpmyadmin auch zugreifen.

So, ich denke, man sieht, dass ich mich nicht sonderlich mit Alfresco auskenne, aber ab diesem Punkt ist mein Latein so langsam am Ende. Ein komplettes Neueinspielen der Daten ist noch nicht einmal so schlimm, da es ja nicht so viele waren. Viel mehr beunruhigt mich die Tatsache, dass wenn wirklich mal viele Daten in Alfresco liegen, ich vielleicht genau wieder an demselben Punkt stehen könnte.

Wäre echt schön, wenn mir jemand einen hilfreichen Tipp geben könnte, wie ich entweder das alte System wiederbeleben kann oder zumindest die Daten retten, vielleicht mache ich aber auch insbesondere beim Wiedereinspielen der Daten einen Denkfehler.

Falls es von Belang ist: mein alf_data ist 77M groß und mysql 540M.

Gruß

Tobias

Anbei noch das catalina.out - Log ab dem Punkt, wo es anfängt, Fehler auszuspucken.


20:51:42,224  INFO  [management.subsystems.ChildApplicationContextFactory] Startup of 'OOoDirect' subsystem, ID: [OOoDirect, default] complete

==> Bis zu diesem Punkt sieht das LOG genauso aus, wenn es funktioniert.

[b]Exception in thread "Store org.alfresco.cache.ticketsCache Spool Thread" Exception in thread "Timer-1" java.lang.OutOfMemoryError: Java heap space
[/b]        at java.util.concurrent.ConcurrentHashMap.size(ConcurrentHashMap.java:718)
        at net.sf.ehcache.store.DiskStore.throwableSafeFlushSpoolIfRequired(DiskStore.java:702)
        at net.sf.ehcache.store.DiskStore.spoolAndExpiryThreadMain(DiskStore.java:675)
        at net.sf.ehcache.store.DiskStore.access$900(DiskStore.java:74)
        at net.sf.ehcache.store.DiskStore$SpoolAndExpiryThread.run(DiskStore.java:1170)
[b]java.lang.OutOfMemoryError: Java heap space[/b]
        at java.util.concurrent.ThreadPoolExecutor.addThread(ThreadPoolExecutor.java:671)
        at java.util.concurrent.ThreadPoolExecutor.addIfUnderCorePoolSize(ThreadPoolExecutor.java:697)
        at java.util.concurrent.ThreadPoolExecutor.execute(ThreadPoolExecutor.java:652)
        at org.alfresco.repo.search.impl.lucene.index.IndexInfo$AbstractSchedulable.schedule(IndexInfo.java:3122)
        at org.alfresco.repo.search.impl.lucene.index.IndexInfo$4.run(IndexInfo.java:792)
        at java.util.TimerThread.mainLoop(Timer.java:512)
        at java.util.TimerThread.run(Timer.java:462)
[b]20:53:07,434  ERROR [web.context.ContextLoader] Context initialization failed[/b]
org.alfresco.error.AlfrescoRuntimeException: 11170002 Reindex failure for org.alfresco.repo.node.index.FullIndexRecoveryComponent
        at org.alfresco.repo.node.index.AbstractReindexComponent.reindex(AbstractReindexComponent.java:315)
        at org.alfresco.repo.node.index.IndexRecoveryBootstrapBean.onBootstrap(IndexRecoveryBootstrapBean.java:37)
        at org.springframework.extensions.surf.util.AbstractLifecycleBean.onApplicationEvent(AbstractLifecycleBean.java:56)
        at org.springframework.context.event.SimpleApplicationEventMulticaster.multicastEvent(SimpleApplicationEventMulticaster.java:97)
        at org.alfresco.repo.management.SafeApplicationEventMulticaster.multicastEvent(SafeApplicationEventMulticaster.java:78)
        at org.springframework.context.support.AbstractApplicationContext.publishEvent(AbstractApplicationContext.java:294)
        at org.springframework.context.support.AbstractApplicationContext.finishRefresh(AbstractApplicationContext.java:858)
        at org.springframework.context.support.AbstractApplicationContext.refresh(AbstractApplicationContext.java:419)
        at org.springframework.web.context.ContextLoader.createWebApplicationContext(ContextLoader.java:261)
        at org.springframework.web.context.ContextLoader.initWebApplicationContext(ContextLoader.java:192)
        at org.springframework.web.context.ContextLoaderListener.contextInitialized(ContextLoaderListener.java:47)
        at org.alfresco.web.app.ContextLoaderListener.contextInitialized(ContextLoaderListener.java:63)
        at org.apache.catalina.core.StandardContext.listenerStart(StandardContext.java:3972)
        at org.apache.catalina.core.StandardContext.start(StandardContext.java:4467)
        at org.apache.catalina.core.ContainerBase.addChildInternal(ContainerBase.java:791)
        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)
        at org.apache.catalina.core.StandardHost.start(StandardHost.java:785)
        at org.apache.catalina.core.ContainerBase.start(ContainerBase.java:1045)
        at org.apache.catalina.core.StandardEngine.start(StandardEngine.java:443)
        at org.apache.catalina.core.StandardService.start(StandardService.java:519)
        at org.apache.catalina.core.StandardServer.start(StandardServer.java:710)
        at org.apache.catalina.startup.Catalina.start(Catalina.java:581)
        at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
        at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
        at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
        at java.lang.reflect.Method.invoke(Method.java:597)
        at org.apache.catalina.startup.Bootstrap.start(Bootstrap.java:289)
        at org.apache.catalina.startup.Bootstrap.main(Bootstrap.java:414)
Caused by: org.alfresco.error.AlfrescoRuntimeException: 11170001 Exception in Transaction.
        at org.alfresco.repo.transaction.RetryingTransactionHelper.doInTransaction(RetryingTransactionHelper.java:462)
        at org.alfresco.repo.transaction.RetryingTransactionHelper.doInTransaction(RetryingTransactionHelper.java:270)
        at org.alfresco.repo.node.index.AbstractReindexComponent.reindex(AbstractReindexComponent.java:306)
        … 35 more
Caused by: java.lang.OutOfMemoryError: Java heap space
Dec 17, 2010 8:53:07 PM org.apache.catalina.core.StandardContext start
SEVERE: Error listenerStart
Dec 17, 2010 8:53:07 PM org.apache.catalina.core.StandardContext start
SEVERE: Context [/alfresco] startup failed due to previous errors
Dec 17, 2010 8:53:07 PM org.apache.catalina.loader.WebappClassLoader clearReferencesJdbc
SEVERE: A web application registered the JBDC driver [org.postgresql.Driver] but failed to unregister it when the web application was stopped. To prevent a memory leak,  Driver has been forcibly unregistered.
Dec 17, 2010 8:53:07 PM org.apache.catalina.loader.WebappClassLoader clearReferencesStopTimerThread
SEVERE: A web application appears to have started a TimerThread named [MySQL Statement Cancellation Timer] via the java.util.Timer API but has failed to stop it. To prevmory leak, the timer (and hence the associated thread) has been forcibly cancelled.
Dec 17, 2010 8:53:07 PM org.apache.catalina.loader.WebappClassLoader clearReferencesThreads
SEVERE: A web application appears to have started a thread named [DefaultScheduler_Worker-1] but has failed to stop it. This is very likely to create a memory leak.
Dec 17, 2010 8:53:07 PM org.apache.catalina.loader.WebappClassLoader clearReferencesThreads
SEVERE: A web application appears to have started a thread named [DefaultScheduler_Worker-2] but has failed to stop it. This is very likely to create a memory leak.
Dec 17, 2010 8:53:07 PM org.apache.catalina.loader.WebappClassLoader clearReferencesThreads
SEVERE: A web application appears to have started a thread named [DefaultScheduler_Worker-3] but has failed to stop it. This is very likely to create a memory leak.
Dec 17, 2010 8:53:07 PM org.apache.catalina.loader.WebappClassLoader clearReferencesThreads
SEVERE: A web application appears to have started a thread named [DefaultScheduler_Worker-4] but has failed to stop it. This is very likely to create a memory leak.
Dec 17, 2010 8:53:07 PM org.apache.catalina.loader.WebappClassLoader clearReferencesThreads
SEVERE: A web application appears to have started a thread named [DefaultScheduler_Worker-5] but has failed to stop it. This is very likely to create a memory leak.
Dec 17, 2010 8:53:07 PM org.apache.catalina.loader.WebappClassLoader clearReferencesThreads
SEVERE: A web application appears to have started a thread named [DefaultScheduler_Worker-6] but has failed to stop it. This is very likely to create a memory leak.
Dec 17, 2010 8:53:07 PM org.apache.catalina.loader.WebappClassLoader clearReferencesThreads
SEVERE: A web application appears to have started a thread named [DefaultScheduler_Worker-7] but has failed to stop it. This is very likely to create a memory leak.
Dec 17, 2010 8:53:07 PM org.apache.catalina.loader.WebappClassLoader clearReferencesThreads
SEVERE: A web application appears to have started a thread named [DefaultScheduler_Worker-8] but has failed to stop it. This is very likely to create a memory leak.
Dec 17, 2010 8:53:07 PM org.apache.catalina.loader.WebappClassLoader clearReferencesThreads
SEVERE: A web application appears to have started a thread named [DefaultScheduler_Worker-9] but has failed to stop it. This is very likely to create a memory leak.
Dec 17, 2010 8:53:07 PM org.apache.catalina.loader.WebappClassLoader clearReferencesThreads
SEVERE: A web application appears to have started a thread named [DefaultScheduler_Worker-10] but has failed to stop it. This is very likely to create a memory leak.
Dec 17, 2010 8:53:07 PM org.apache.catalina.loader.WebappClassLoader clearReferencesStopTimerThread
SEVERE: A web application appears to have started a TimerThread named [Timer-0] via the java.util.Timer API but has failed to stop it. To prevent a memory leak, the timeence the associated thread) has been forcibly cancelled.
Dec 17, 2010 8:53:07 PM org.apache.catalina.loader.WebappClassLoader clearReferencesThreads
SEVERE: A web application appears to have started a thread named [Thread-15] but has failed to stop it. This is very likely to create a memory leak.
Dec 17, 2010 8:53:07 PM org.apache.catalina.loader.WebappClassLoader clearReferencesThreads
SEVERE: A web application appears to have started a thread named [MessageDispatcher] but has failed to stop it. This is very likely to create a memory leak.
Dec 17, 2010 8:53:07 PM org.apache.catalina.loader.WebappClassLoader clearReferencesThreads
SEVERE: A web application appears to have started a thread named [indexThread1] but has failed to stop it. This is very likely to create a memory leak.
Dec 17, 2010 8:53:07 PM org.apache.catalina.loader.WebappClassLoader clearReferencesThreads
SEVERE: A web application appears to have started a thread named [indexThread2] but has failed to stop it. This is very likely to create a memory leak.
Dec 17, 2010 8:53:07 PM org.apache.catalina.loader.WebappClassLoader clearReferencesThreads
SEVERE: A web application appears to have started a thread named [indexThread3] but has failed to stop it. This is very likely to create a memory leak.
Dec 17, 2010 8:53:07 PM org.apache.catalina.loader.WebappClassLoader clearReferencesThreads
SEVERE: A web application appears to have started a thread named [indexThread4] but has failed to stop it. This is very likely to create a memory leak.
Dec 17, 2010 8:53:07 PM org.apache.catalina.loader.WebappClassLoader clearReferencesThreads
SEVERE: A web application appears to have started a thread named [DefaultScheduler_QuartzSchedulerThread] but has failed to stop it. This is very likely to create a memo
Dec 17, 2010 8:53:07 PM org.apache.catalina.loader.WebappClassLoader clearThreadLocalMap
SEVERE: A web application created a ThreadLocal with key of type [org.alfresco.repo.security.authentication.AuthenticationUtil.ThreadLocalStack] (value [org.alfresco.repty.authentication.AuthenticationUtil$ThreadLocalStack@68c7300f]) and a value of type [java.util.Stack] (value [[]]) but failed to remove it when the web application was  To prevent a memory leak, the ThreadLocal has been forcibly removed.
Dec 17, 2010 8:53:07 PM org.apache.catalina.loader.WebappClassLoader clearThreadLocalMap
SEVERE: A web application created a ThreadLocal with key of type [org.alfresco.repo.security.authentication.AuthenticationUtil.ThreadLocalStack] (value [org.alfresco.repty.authentication.AuthenticationUtil$ThreadLocalStack@6d245e86]) and a value of type [java.util.Stack] (value [[]]) but failed to remove it when the web application was  To prevent a memory leak, the ThreadLocal has been forcibly removed.
Dec 17, 2010 8:53:07 PM org.apache.catalina.loader.WebappClassLoader clearThreadLocalMap
SEVERE: A web application created a ThreadLocal with key of type [java.lang.ThreadLocal] (value [java.lang.ThreadLocal@6eb9e764]) and a value of type [org.alfresco.util.ateFormat] (value [yyyy-MM-dd]) but failed to remove it when the web application was stopped. To prevent a memory leak, the ThreadLocal has been forcibly removed.
Dec 17, 2010 8:53:07 PM org.apache.catalina.loader.WebappClassLoader clearThreadLocalMap
SEVERE: A web application created a ThreadLocal with key of type [null] (value [com.sun.xml.bind.v2.runtime.Coordinator$1@6f068f06]) and a value of type [com.sun.xml    time.Coordinator[]] (value [[Lcom.sun.xml.bind.v2.runtime.Coordinator;@17980bdc]) but failed to remove it when the web application was stopped.                          eadLocal has been forcibly removed.
Dec 17, 2010 8:53:07 PM org.apache.catalina.loader.WebappClassLoader clearThreadLocalMap
SEVERE: A web application created a ThreadLocal with key of type [java.lang.ThreadLocal] (value [java.lang.ThreadLocal@33f1c19                                           mpl] (value [org.apache.cxf.bus.CXFBusImpl@44de86b6]) but failed to remove it when the web application was stopped. To prevent                                           emoved.
log4j:ERROR LogMananger.repositorySelector was null likely due to error in class reloading, using NOPLoggerRepository.
Dec 17, 2010 8:53:10 PM org.apache.catalina.startup.HostConfig deployDescriptor
INFO: Deploying configuration descriptor manager.xml
Dec 17, 2010 8:53:10 PM org.apache.catalina.startup.HostConfig deployDescriptor
INFO: Deploying configuration descriptor host-manager.xml
Dec 17, 2010 8:53:10 PM org.apache.catalina.startup.HostConfig deployWAR
INFO: Deploying web application archive wcmqs.war
WARN : org.springframework.beans.GenericTypeAwarePropertyDescriptor - Invalid JavaBean property 'baseUrl' being accessed! Ambi                                           blic void org.alfresco.wcm.client.impl.WebScriptCallerImpl.setBaseUrl(java.lang.String) throws java.net.URISyntaxException]: [                                           allerImpl.setBaseUrl(java.net.URI)]
Dec 17, 2010 8:53:13 PM org.apache.catalina.startup.HostConfig deployWAR
INFO: Deploying web application archive awe.war
Dec 17, 2010 8:53:16 PM org.apache.catalina.startup.HostConfig deployWAR
INFO: Deploying web application archive share.war
20:53:19,410  INFO  [extensions.webscripts.DeclarativeRegistry] Registered 302 Web Scripts (+0 failed), 310 URLs
20:53:19,411  INFO  [extensions.webscripts.DeclarativeRegistry] Registered 8 Package Description Documents (+0 failed)
20:53:19,411  INFO  [extensions.webscripts.DeclarativeRegistry] Registered 0 Schema Description Documents (+0 failed)
20:53:19,588  INFO  [extensions.webscripts.AbstractRuntimeContainer] Initialised Spring Surf Container Web Script Container (i
20:53:19,639  INFO  [extensions.webscripts.TemplateProcessorRegistry] Registered template processor freemarker for extension f
20:53:19,702  INFO  [extensions.webscripts.ScriptProcessorRegistry] Registered script processor javascript for extension js
20:53:19,779  INFO  [extensions.webscripts.TemplateProcessorRegistry] Registered template processor freemarker for extension f
20:53:19,782  INFO  [extensions.webscripts.ScriptProcessorRegistry] Registered script processor javascript for extension js
20:53:19,850  INFO  [extensions.webscripts.TemplateProcessorRegistry] Registered template processor freemarker for extension f
20:53:19,854  INFO  [extensions.webscripts.ScriptProcessorRegistry] Registered script processor javascript for extension js
Dec 17, 2010 8:53:20 PM org.apache.catalina.startup.HostConfig deployDirectory
INFO: Deploying web application directory ROOT
Dec 17, 2010 8:53:20 PM org.apache.coyote.http11.Http11Protocol start
INFO: Starting Coyote HTTP/1.1 on http-8080
Dec 17, 2010 8:53:20 PM org.apache.jk.common.ChannelSocket init
INFO: JK: ajp13 listening on /0.0.0.0:8009
Dec 17, 2010 8:53:20 PM org.apache.jk.server.JkMain start
INFO: Jk running ID=0 time=0/20  config=null
Dec 17, 2010 8:53:20 PM org.apache.catalina.startup.Catalina start
INFO: Server startup in 126518 ms
Exception in thread "Thread-26" org.apache.chemistry.opencmis.commons.exceptions.CmisObjectNotFoundException: Not Found
        at org.apache.chemistry.opencmis.client.bindings.spi.atompub.AbstractAtomPubService.convertStatusCode(AbstractAtomPubS
        at org.apache.chemistry.opencmis.client.bindings.spi.atompub.AbstractAtomPubService.read(AbstractAtomPubService.java:4
        at org.apache.chemistry.opencmis.client.bindings.spi.atompub.AbstractAtomPubService.getRepositoriesInternal(AbstractAt
        at org.apache.chemistry.opencmis.client.bindings.spi.atompub.RepositoryServiceImpl.getRepositoryInfos(RepositoryServic
        at org.apache.chemistry.opencmis.client.bindings.impl.RepositoryServiceImpl.getRepositoryInfos(RepositoryServiceImpl.j
        at org.apache.chemistry.opencmis.client.runtime.SessionFactoryImpl.getRepositories(SessionFactoryImpl.java:108)
        at org.alfresco.wcm.client.util.impl.GuestSessionFactoryImpl.getRepository(GuestSessionFactoryImpl.java:104)
        at org.alfresco.wcm.client.util.impl.GuestSessionFactoryImpl.run(GuestSessionFactoryImpl.java:114)
        at java.lang.Thread.run(Thread.java:619)

Outcomes