Installation & Restore issue

[lamoureux]

Hi,

I post here because I'm stuck with an Alfresco installation.

I am trying to install an Alfresco portal configured by a partner during a project but this partner gave me a very weak installation procedure and he doesn't want to be more involved in the project .

Here my configuration:
- Apache2
- Tomcat 6.0
- PostgreSQL 9.1
- Alfresco 3.4

To install Alfresco, I've used the official documentation (http://wiki.alfresco.com/wiki/Install_Tomcat6 and http://www.alfresco.com/help/34/community/all/)

I've then added the two following modules with java -jar command:
- alfresco-community-wcmqs-3.4.d.amp
- alfresco-community-wcmqs-share-3.4.d.amp

Then I launch Tomcat : all goes fine.
When I check http://myserver:8080/manager/html url, everything is OK :
- /alfresco application is functional
- /share application is functional
- /wcmqs application is functional

The PostgreSQL database have been populated by the default alfresco data.

Then occurs the last step : restoring the original portal.

I followed the standard procedure :
- stop tomcat
- copy the dir.root to a temp location
- restore the dir.root folder provided by our dear partner
- restore the postgresql database
- start tomcat again

And now, /alfresco application won't start.

You can find attached the log file but the message seems to be "Context initialization failed".

Because I'm not used to Alfresco, I really don't know what to do with that.

If someone have an idea or just a track to find a solution I'll be very greatful !

Thierry.

[lamoureux]

Hello,

Since last time, situation change a bit, but I'm always stuck.

My previous problems was due to a version issue : our partner send us a documentation for installing version 3.4 of Alfresco but, in fact, the database and files they provides us was for Alfresco 4.0 …

Once this problem detected, I've performed a new installation/restoration and here I am :
- Alfresco seems to be correctly installed
- WQMS also
- I've restored files and database
- I've deleted the lucune index folders (I had issues with nodes that can't be found)
- I've performed a full reindex
- Tomcat seems to hang during the its startup.

Here are the log message from catalina.out:

3 sept. 2013 16:38:12 org.apache.catalina.startup.ClassLoaderFactory validateFile
ATTENTION: Problem with directory [/usr/share/tomcat6/server/classes], exists: [false], isDirectory: [false], canRead: [false]
23 sept. 2013 16:38:12 org.apache.catalina.startup.ClassLoaderFactory validateFile
ATTENTION: Problem with directory [/usr/share/tomcat6/server], exists: [false], isDirectory: [false], canRead: [false]
23 sept. 2013 16:38:12 org.apache.catalina.startup.ClassLoaderFactory validateFile
ATTENTION: Problem with directory [/usr/share/tomcat6/shared/classes], exists: [false], isDirectory: [false], canRead: [false]
23 sept. 2013 16:38:12 org.apache.catalina.startup.ClassLoaderFactory validateFile
ATTENTION: Problem with directory [/usr/share/tomcat6/shared], exists: [false], isDirectory: [false], canRead: [false]
23 sept. 2013 16:38:13 org.apache.coyote.http11.Http11Protocol init
INFO: Initialisation de Coyote HTTP/1.1 sur http-8080
23 sept. 2013 16:38:13 org.apache.catalina.startup.Catalina load
INFO: Initialization processed in 818 ms
23 sept. 2013 16:38:13 org.apache.catalina.core.StandardService start
INFO: Démarrage du service Catalina
23 sept. 2013 16:38:13 org.apache.catalina.core.StandardEngine start
INFO: Starting Servlet Engine: Apache Tomcat/6.0.35
23 sept. 2013 16:38:13 org.apache.catalina.startup.HostConfig deployDescriptor
INFO: Déploiement du descripteur de configuration manager.xml
23 sept. 2013 16:38:14 org.apache.catalina.startup.HostConfig deployDescriptor
INFO: Déploiement du descripteur de configuration host-manager.xml
23 sept. 2013 16:38:14 org.apache.catalina.startup.HostConfig deployDescriptor
INFO: Déploiement du descripteur de configuration ROOT.xml
23 sept. 2013 16:38:14 org.apache.catalina.startup.HostConfig deployDescriptor
INFO: Déploiement du descripteur de configuration alfresco.xml
23 sept. 2013 16:38:16 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.
2013-09-23 16:38:38,807  INFO  [management.subsystems.ChildApplicationContextFactory] [main] Starting 'sysAdmin' subsystem, ID: [sysAdmin, default]
 2013-09-23 16:38:38,948  INFO  [management.subsystems.ChildApplicationContextFactory] [main] Startup of 'sysAdmin' subsystem, ID: [sysAdmin, default] complete
 23 sept. 2013 16:38:49 org.activiti.engine.impl.ProcessEngineImpl <init>
INFO: ProcessEngine default created
2013-09-23 16:38:50,840  WARN  [alfresco.util.AbstractTriggerBean] [main] Job ehCacheTracerJob is not active/enabled
 2013-09-23 16:38:55,208  INFO  [extensions.webscripts.TemplateProcessorRegistry] [main] Registered template processor Repository Template Processor for extension ftl
 2013-09-23 16:38:55,219  INFO  [extensions.webscripts.ScriptProcessorRegistry] [main] Registered script processor Repository Script Processor for extension js
 2013-09-23 16:39:04,720  INFO  [domain.schema.SchemaBootstrap] [main] Schema managed by database dialect org.hibernate.dialect.PostgreSQLDialect.
 23 sept. 2013 16:39:08 org.activiti.engine.impl.ProcessEngineImpl <init>
INFO: ProcessEngine activitiBootstrapEngine created
2013-09-23 16:39:08,464  INFO  [domain.schema.SchemaBootstrap] [main] No changes were made to the schema.
 23 sept. 2013 16:39:08 org.activiti.engine.impl.jobexecutor.JobAcquisitionThread run
INFO: JobAcquisitionThread starting to acquire jobs
2013-09-23 16:39:08,765  INFO  [management.subsystems.ChildApplicationContextFactory] [main] Starting 'Search' subsystem, ID: [Search, managed, lucene]
 2013-09-23 16:39:09,201  INFO  [management.subsystems.ChildApplicationContextFactory] [main] Startup of 'Search' subsystem, ID: [Search, managed, lucene] complete
 2013-09-23 16:39:11,204  INFO  [management.subsystems.ChildApplicationContextFactory] [main] Starting 'thirdparty' subsystem, ID: [thirdparty, default]
 2013-09-23 16:39:12,506  INFO  [management.subsystems.ChildApplicationContextFactory] [main] Startup of 'thirdparty' subsystem, ID: [thirdparty, default] complete
 2013-09-23 16:39:12,517  INFO  [management.subsystems.ChildApplicationContextFactory] [main] Starting 'OOoDirect' subsystem, ID: [OOoDirect, default]
 2013-09-23 16:39:14,045  WARN  [alfresco.util.OpenOfficeConnectionTester] [main] An initial OpenOffice connection could not be established.
 2013-09-23 16:39:14,086  INFO  [management.subsystems.ChildApplicationContextFactory] [main] Startup of 'OOoDirect' subsystem, ID: [OOoDirect, default] complete
 2013-09-23 16:39:25,123  INFO  [repo.admin.ConfigurationChecker] [main] The Alfresco root data directory ('dir.root') is: /var/lib/alfresco/alf_data
 2013-09-23 16:39:25,224  INFO  [admin.patch.PatchExecuter] [main] Checking for patches to apply …
 2013-09-23 16:39:26,830  INFO  [admin.patch.PatchExecuter] [main] No patches were required.
 2013-09-23 16:39:26,861  INFO  [repo.module.ModuleServiceImpl] [main] Found 1 module(s).
 2013-09-23 16:39:26,984  INFO  [repo.module.ModuleServiceImpl] [main] Starting module 'org_alfresco_module_wcmquickstart' version 1.0.
 2013-09-23 16:39:27,011  DEBUG [repo.module.ModuleComponentHelper] [main] Started module 'ModuleDetails[{module.version=1.0, module.description=Web Quick Start, module.id=org_alfresco_module_wcmquickstart, module.repo.version.max=999, module.title=Web Quick Start, module.repo.version.min=3.3, module.installState=INSTALLED, module.installDate=2013-09-16T16:26:45.695+02:00}]' including 0components.
 2013-09-23 16:39:27,029  DEBUG [repo.module.ModuleComponentHelper] [main] Installed module found in distribution: org_alfresco_module_wcmquickstart
 2013-09-23 16:39:27,031  INFO  [management.subsystems.ChildApplicationContextFactory] [main] Starting 'fileServers' subsystem, ID: [fileServers, default]
 2013-09-23 16:39:27,593  INFO  [management.subsystems.ChildApplicationContextFactory] [main] Startup of 'fileServers' subsystem, ID: [fileServers, default] complete
 2013-09-23 16:39:27,594  INFO  [management.subsystems.ChildApplicationContextFactory] [main] Starting 'imap' subsystem, ID: [imap, default]
 2013-09-23 16:39:27,683  INFO  [management.subsystems.ChildApplicationContextFactory] [main] Startup of 'imap' subsystem, ID: [imap, default] complete
 2013-09-23 16:39:27,684  INFO  [management.subsystems.ChildApplicationContextFactory] [main] Starting 'email' subsystem, ID: [email, outbound]
 2013-09-23 16:39:27,768  INFO  [management.subsystems.ChildApplicationContextFactory] [main] Startup of 'email' subsystem, ID: [email, outbound] complete
 2013-09-23 16:39:27,768  INFO  [management.subsystems.ChildApplicationContextFactory] [main] Starting 'email' subsystem, ID: [email, inbound]
 2013-09-23 16:39:27,874  INFO  [management.subsystems.ChildApplicationContextFactory] [main] Startup of 'email' subsystem, ID: [email, inbound] complete
 2013-09-23 16:39:27,875  INFO  [management.subsystems.ChildApplicationContextFactory] [main] Starting 'googledocs' subsystem, ID: [googledocs, default]
 2013-09-23 16:39:27,960  INFO  [management.subsystems.ChildApplicationContextFactory] [main] Startup of 'googledocs' subsystem, ID: [googledocs, default] complete
 2013-09-23 16:39:27,960  INFO  [management.subsystems.ChildApplicationContextFactory] [main] Starting 'Subscriptions' subsystem, ID: [Subscriptions, default]
 2013-09-23 16:39:28,005  INFO  [management.subsystems.ChildApplicationContextFactory] [main] Startup of 'Subscriptions' subsystem, ID: [Subscriptions, default] complete
 2013-09-23 16:39:28,006  INFO  [repo.usage.UserUsageTrackingComponent] [main] Disabled - clear non-missing user usages …
 2013-09-23 16:39:28,039  INFO  [repo.usage.UserUsageTrackingComponent] [main] Found 0 users to clear
 2013-09-23 16:39:28,039  INFO  [repo.usage.UserUsageTrackingComponent] [main] … cleared non-missing usages for 0 users
 2013-09-23 16:39:28,039  INFO  [management.subsystems.ChildApplicationContextFactory] [main] Starting 'Synchronization' subsystem, ID: [Synchronization, default]
 2013-09-23 16:39:28,250  INFO  [management.subsystems.ChildApplicationContextFactory] [main] Starting 'Authentication' subsystem, ID: [Authentication, managed, alfrescoNtlm1]
 2013-09-23 16:39:28,704  INFO  [management.subsystems.ChildApplicationContextFactory] [main] Startup of 'Authentication' subsystem, ID: [Authentication, managed, alfrescoNtlm1] complete
 2013-09-23 16:39:28,730  INFO  [management.subsystems.ChildApplicationContextFactory] [main] Startup of 'Synchronization' subsystem, ID: [Synchronization, default] complete
 2013-09-23 16:39:28,972  INFO  [service.descriptor.DescriptorService] [main] Alfresco JVM - v1.6.0_27-b27; maximum heap size 1019,875MB
 2013-09-23 16:39:28,985  INFO  [service.descriptor.DescriptorService] [main] Alfresco started (Community). Current version: 4.0.0 (b 3835) schema 5 019. Originally installed version: 4.0.0 (b 3835) schema 5 019.
 2013-09-23 16:39:28,986  INFO  [management.subsystems.ChildApplicationContextFactory] [main] Starting 'ActivitiesFeed' subsystem, ID: [ActivitiesFeed, default]
 2013-09-23 16:39:29,251  INFO  [management.subsystems.ChildApplicationContextFactory] [main] Startup of 'ActivitiesFeed' subsystem, ID: [ActivitiesFeed, default] complete
 2013-09-23 16:39:29,251  INFO  [management.subsystems.ChildApplicationContextFactory] [main] Starting 'Replication' subsystem, ID: [Replication, default]
 2013-09-23 16:39:29,308  INFO  [management.subsystems.ChildApplicationContextFactory] [main] Startup of 'Replication' subsystem, ID: [Replication, default] complete
 2013-09-23 16:40:00,306  INFO  [alfresco.util.OpenOfficeConnectionTester] [DefaultScheduler_Worker-10] The OpenOffice connection was re-established.
 2013-09-23 16:44:30,731  ERROR [repo.lock.JobLockServiceImpl] [JobLockService1] Callback to lockReleased failed.
 java.lang.NullPointerException
        at org.alfresco.service.cmr.repository.NodeRef.<init>(NodeRef.java:102)
        at org.alfresco.repo.transfer.RepoTransferProgressMonitorImpl.getTransferRecord(RepoTransferProgressMonitorImpl.java:122)
        at org.alfresco.repo.transfer.RepoTransferProgressMonitorImpl.getProgressInternal(RepoTransferProgressMonitorImpl.java:53)
        at org.alfresco.repo.transfer.AbstractTransferProgressMonitor$1.execute(AbstractTransferProgressMonitor.java:65)
        at org.alfresco.repo.transfer.AbstractTransferProgressMonitor$1.execute(AbstractTransferProgressMonitor.java:62)
        at org.alfresco.repo.transaction.RetryingTransactionHelper.doInTransaction(RetryingTransactionHelper.java:388)
        at org.alfresco.repo.transfer.AbstractTransferProgressMonitor.getProgress(AbstractTransferProgressMonitor.java:60)
        at org.alfresco.repo.transfer.LoggingTransferProgressMonitorImpl.getProgress(LoggingTransferProgressMonitorImpl.java:55)
        at org.alfresco.repo.transfer.ChangeCapturingProgressMonitor.getProgress(ChangeCapturingProgressMonitor.java:100)
        at org.alfresco.repo.transfer.RepoTransferReceiverImpl$2.execute(RepoTransferReceiverImpl.java:563)
        at org.alfresco.repo.transfer.RepoTransferReceiverImpl$2.execute(RepoTransferReceiverImpl.java:558)
        at org.alfresco.repo.transaction.RetryingTransactionHelper.doInTransaction(RetryingTransactionHelper.java:388)
        at org.alfresco.repo.transfer.RepoTransferReceiverImpl$3.doWork(RepoTransferReceiverImpl.java:593)
        at org.alfresco.repo.transfer.RepoTransferReceiverImpl$3.doWork(RepoTransferReceiverImpl.java:590)
        at org.alfresco.repo.security.authentication.AuthenticationUtil.runAs(AuthenticationUtil.java:519)
        at org.alfresco.repo.transfer.RepoTransferReceiverImpl.timeout(RepoTransferReceiverImpl.java:589)
        at org.alfresco.repo.transfer.RepoTransferReceiverImpl.access$1300(RepoTransferReceiverImpl.java:108)
        at org.alfresco.repo.transfer.RepoTransferReceiverImpl$Lock.lockReleased(RepoTransferReceiverImpl.java:1601)
        at org.alfresco.repo.lock.JobLockServiceImpl.callLockReleased(JobLockServiceImpl.java:411)
        at org.alfresco.repo.lock.JobLockServiceImpl.access$400(JobLockServiceImpl.java:46)
        at org.alfresco.repo.lock.JobLockServiceImpl$2.run(JobLockServiceImpl.java:354)
        at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:471)
        at java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:334)
        at java.util.concurrent.FutureTask.run(FutureTask.java:166)
        at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$101(ScheduledThreadPoolExecutor.java:165)
        at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:266)
        at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1146)
        at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
        at java.lang.Thread.run(Thread.java:679)
‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍

Does it talk to someone of you? It would be great because I'm beginning to pulling out my hairs …

Many thanks (in advance !)

Thierry.

[lamoureux]

Hello,

Noboby has a start of answer about this issue?

The error "Callback to lockReleased failed" persists, even if I rebuild the lucenes-index.

Thanks,

Thierry.

[lamoureux]

For your information, solved by deleting all webapps folders and relaunching Alfresco.

I'm now facing another issue : Node does not exist: workspace://SpacesStore/200dbae5-ad80-4c91-b33c-d0d404860baP

And a doesn't have this worskpace in the database (alf_node table)