RM Installation causes failed start and 404 error

[ofrxnz]

So, i am was testing the upgrade path from 3.0 to 3.2r and after I applied this fix https://issues.alfresco.com/jira/browse/ALFCOM-3611 I was successfully able to migrate/upgrade to 3.2r with 100% the functionality. 

I went to install the Records Management amps to give them a test drive and was careful to install the share amp into share and the alfresco amp into alfresco.

If I use a basic configuration and a blank repo, RM installs and starts properly. 

Using our backup data, when I start alfresco after I install the RM amps i get the error at the bottom of this post. 

I think it is caused by a conflict I made many versions ago when i renamed to root node bootstrap-spaces.properties and bootstrap-spaces_en_us.properties.  this was the property "spaces.company_home.name=ActualCompanyNameHere"  I also made similar changes in the webclient.properties files. 

By changing this I can cause a blank repository to also exibit the same error (unlike when its set to it's default)

This change has never posed an issue before and has upgraded several times with no issue. 

Any help will be greatly appreciated.  I have preliminarily played with editing it back in the db and config but it didn't help though i don't know what im doing. 

Thanks in advance,

Adam

Here is the log.  You will see the bit about the VTI module.  It installs properly and works.  I didn't install it for this example to reduce complexity since RM was causing trouble.

16:10:33,060 INFO  [org.alfresco.repo.management.subsystems.ChildApplicationContextFactory] Startup of 'thirdparty' subsystem, ID: [default] complete
16:10:35,306 INFO  [org.alfresco.repo.admin.ConfigurationChecker] The Alfresco root data directory ('dir.root') is: /opt/Alfresco/alf_data
16:10:36,176 INFO  [org.alfresco.repo.admin.patch.PatchExecuter] Checking for patches to apply …
16:10:36,695 INFO  [org.alfresco.repo.admin.patch.PatchExecuter] No patches were required.
16:10:36,804 INFO  [org.alfresco.repo.module.ModuleServiceImpl] Found 1 module(s).
16:10:37,687 INFO  [org.alfresco.repo.module.ModuleServiceImpl] Installing module 'org_alfresco_module_dod5015' version 1.0.
16:10:37,752 DEBUG [org.alfresco.repo.importer.ImporterBootstrap] Importing alfresco/module/org_alfresco_module_dod5015/bootstrap/RMDataDictionaryBootstrap.xml
16:10:37,787 DEBUG [org.alfresco.repo.importer.ImporterBootstrap] Import started at Tue Nov 17 16:10:37 EST 2009 (1258492237787)
16:10:39,890 DEBUG [org.alfresco.repo.importer.ImporterBootstrap] Import completed at Tue Nov 17 16:10:39 EST 2009 (1258492239881)
16:10:39,910 DEBUG [org.alfresco.repo.importer.ImporterBootstrap] Import duration: 2094 ms (Note: excluding commit time)
16:10:39,910 DEBUG [org.alfresco.repo.importer.ImporterBootstrap]  Nodes created: 9
16:10:39,910 DEBUG [org.alfresco.repo.importer.ImporterBootstrap]  Nodes linked: 0
16:10:39,910 DEBUG [org.alfresco.repo.importer.ImporterBootstrap]  Aspects Added: 0
16:10:39,910 DEBUG [org.alfresco.repo.importer.ImporterBootstrap]  Properties set: 56
16:10:39,910 DEBUG [org.alfresco.repo.importer.ImporterBootstrap]  Content set: 6
16:10:39,910 DEBUG [org.alfresco.repo.importer.ImporterBootstrap]  Permissions set: 0
16:10:40,663 WARN  [org.alfresco.repo.module.ModuleServiceImpl] A previously-installed module 'org.alfresco.module.vti' (version 1.0) is not present in your distribution.
16:10:41,502 ERROR [org.springframework.web.context.ContextLoader] Context initialization failed
net.sf.acegisecurity.AuthenticationCredentialsNotFoundException: A valid SecureContext was not provided in the RequestContext
        at net.sf.acegisecurity.intercept.AbstractSecurityInterceptor.credentialsNotFound(AbstractSecurityInterceptor.java:477)
        at net.sf.acegisecurity.intercept.AbstractSecurityInterceptor.beforeInvocation(AbstractSecurityInterceptor.java:355)
        at net.sf.acegisecurity.intercept.method.aopalliance.MethodSecurityInterceptor.invoke(MethodSecurityInterceptor.java:77)
        at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:171)
        at org.alfresco.repo.security.permissions.impl.ExceptionTranslatorMethodInterceptor.invoke(ExceptionTranslatorMethodInterceptor.java:49)
        at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:171)
        at org.alfresco.repo.audit.AuditMethodInterceptor.proceed(AuditMethodInterceptor.java:198)
        at org.alfresco.repo.audit.AuditMethodInterceptor.invoke(AuditMethodInterceptor.java:152)
        at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:171)
        at org.springframework.transaction.interceptor.TransactionInterceptor.invoke(TransactionInterceptor.java:106)
        at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:171)
        at org.alfresco.repo.security.permissions.impl.ExceptionTranslatorMethodInterceptor.invoke(ExceptionTranslatorMethodInterceptor.java:49)
        at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:171)
        at org.alfresco.repo.audit.AuditMethodInterceptor.proceedWithAudit(AuditMethodInterceptor.java:237)
        at org.alfresco.repo.audit.AuditMethodInterceptor.proceed(AuditMethodInterceptor.java:204)
        at org.alfresco.repo.audit.AuditMethodInterceptor.invoke(AuditMethodInterceptor.java:152)
        at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:171)
        at org.springframework.transaction.interceptor.TransactionInterceptor.invoke(TransactionInterceptor.java:106)
        at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:171)
        at org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDynamicAopProxy.java:204)
        at $Proxy9.exists(Unknown Source)
        at org.alfresco.repo.rule.RuleServiceImpl.checkForCopy(RuleServiceImpl.java:986)
        at org.alfresco.repo.rule.RuleServiceImpl.canExecuteRule(RuleServiceImpl.java:961)
        at org.alfresco.repo.rule.RuleServiceImpl.executePendingRule(RuleServiceImpl.java:894)
        at org.alfresco.repo.rule.RuleServiceImpl.executePendingRulesImpl(RuleServiceImpl.java:867)
        at org.alfresco.repo.rule.RuleServiceImpl.executePendingRules(RuleServiceImpl.java:840)
        at org.alfresco.repo.rule.RuleTransactionListener.beforeCommit(RuleTransactionListener.java:63)
        at org.alfresco.repo.transaction.AlfrescoTransactionSupport$TransactionSynchronizationImpl.doBeforeCommit(AlfrescoTransactionSupport.java:739)
        at org.alfresco.repo.transaction.AlfrescoTransactionSupport$TransactionSynchronizationImpl.doBeforeCommit(AlfrescoTransactionSupport.java:719)
        at org.alfresco.repo.transaction.AlfrescoTransactionSupport$TransactionSynchronizationImpl.beforeCommit(AlfrescoTransactionSupport.java:679)
        at org.springframework.transaction.support.TransactionSynchronizationUtils.triggerBeforeCommit(TransactionSynchronizationUtils.java:48)
        at org.springframework.transaction.support.AbstractPlatformTransactionManager.triggerBeforeCommit(AbstractPlatformTransactionManager.java:835)
        at org.springframework.transaction.support.AbstractPlatformTransactionManager.processCommit(AbstractPlatformTransactionManager.java:645)
        at org.springframework.transaction.support.AbstractPlatformTransactionManager.commit(AbstractPlatformTransactionManager.java:632)
        at org.springframework.transaction.interceptor.TransactionAspectSupport.commitTransactionAfterReturning(TransactionAspectSupport.java:314)
        at org.alfresco.util.transaction.SpringAwareUserTransaction.commit(SpringAwareUserTransaction.java:467)
        at org.alfresco.repo.transaction.RetryingTransactionHelper.doInTransaction(RetryingTransactionHelper.java:340)
        at org.alfresco.repo.transaction.RetryingTransactionHelper.doInTransaction(RetryingTransactionHelper.java:252)
        at org.alfresco.repo.module.ModuleStarter.onBootstrap(ModuleStarter.java:74)
        at org.alfresco.util.AbstractLifecycleBean.onApplicationEvent(AbstractLifecycleBean.java:62)
        at org.springframework.context.event.SimpleApplicationEventMulticaster$1.run(SimpleApplicationEventMulticaster.java:77)
        at org.springframework.core.task.SyncTaskExecutor.execute(SyncTaskExecutor.java:49)
        at org.springframework.context.event.SimpleApplicationEventMulticaster.multicastEvent(SimpleApplicationEventMulticaster.java:75)
        at org.springframework.context.support.AbstractApplicationContext.publishEvent(AbstractApplicationContext.java:246)
        at org.springframework.context.support.AbstractApplicationContext.finishRefresh(AbstractApplicationContext.java:617)
        at org.springframework.context.support.AbstractApplicationContext.refresh(AbstractApplicationContext.java:355)
        at org.springframework.web.context.ContextLoader.createWebApplicationContext(ContextLoader.java:246)
        at org.springframework.web.context.ContextLoader.initWebApplicationContext(ContextLoader.java:189)
        at org.springframework.web.context.ContextLoaderListener.contextInitialized(ContextLoaderListener.java:49)
        at org.alfresco.web.app.ContextLoaderListener.contextInitialized(ContextLoaderListener.java:69)
        at org.apache.catalina.core.StandardContext.listenerStart(StandardContext.java:3843)
        at org.apache.catalina.core.StandardContext.start(StandardContext.java:4342)
        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:525)
        at org.apache.catalina.startup.HostConfig.deployDescriptor(HostConfig.java:627)
        at org.apache.catalina.startup.HostConfig.deployDescriptors(HostConfig.java:553)
        at org.apache.catalina.startup.HostConfig.deployApps(HostConfig.java:488)
        at org.apache.catalina.startup.HostConfig.start(HostConfig.java:1149)
        at org.apache.catalina.startup.HostConfig.lifecycleEvent(HostConfig.java:311)
        at org.apache.catalina.util.LifecycleSupport.fireLifecycleEvent(LifecycleSupport.java:117)
        at org.apache.catalina.core.ContainerBase.start(ContainerBase.java:1053)
        at org.apache.catalina.core.StandardHost.start(StandardHost.java:719)
        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:516)
        at org.apache.catalina.core.StandardServer.start(StandardServer.java:710)
        at org.apache.catalina.startup.Catalina.start(Catalina.java:578)
        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:288)
        at org.apache.catalina.startup.Bootstrap.main(Bootstrap.java:413)
16:10:58,093 INFO  [org.alfresco.web.site.FrameworkHelper] Successfully Initialized Web Framework
16:11:09,353 INFO  [org.alfresco.web.site.FrameworkHelper] Successfully Initialized Web Framework

[kbonnet]

Thanks for posting this and pointing me to it from my own post /en/viewtopic.php?f=51&t=23237&p=76180. i have the same problem, but i never named CompanyHome different from the default.

Any Alfrescians on the rescue?

Koen

[ofrxnz]

May or may not be related but, this seems to go deeper into the boot strap process now. 

The new IMAP related files did not and do not appear in Data Dictionary as well….

When i tested 3.2 (not 3.2r) a while back, IMAP worked great but, in 3.2r it seems to be half working……

Ill keep digging and post what i find

[ofrxnz]

Well, it looks like there is a version 3.2r2 emphasis on the second 2. i found it on sourceforge now and it seems to be giving me better behavior.  will post more when i have put it through its paces

The version that was giving me the issues IDs as v3.2.0 (r 2384)

This version which holds promise is v3.2.0 (r2 2440)

Should the front page of the Wiki be updated to reflect this is an update?

[kbonnet]

Yes, i agree, R2 is a rather important improvement. There are also improvements on the external authentication subsystem

[ofrxnz]

So, in 3.2r2 if i delete every user, disable external authentication, and wait about 24 hours, i have about a 60% success rate at installing RM.

Could the authentication component be taking too long to start and causing the RM install to explode?

Does that make any sense?

[bnordgren]

I suspect it's not the authentication component causing the fail.  I have a brand new 3.2r2 system on ArchLinux with no data and no authentication configured (meaning admin/admin is in the local store; no other users, no other user stores).  My failure is somewhat different: installing RM appears to have reset the "dir.root" property.  I can't figure out how, since my alfresco-global.properties is intact. 

Note I am not using the installer.  My process was : 1] stop tomcat; 2] install RM amp in alfresco war; 3] install RM-share amp in share war; 4] remove the alfresco and share directories from /srv/tomcat/webapps; 5] restart tomcat; 6] notice failure.  I did install the MS Sharepoint support at the same time, so that could be it.

In the following, note the line which declares that: "The Alfresco 'dir.root' property is set to a relative path './alf_data'.  'dir.root' should be overridden to point to a specific folder."  My alfresco-global.properties file still has dir.root as "/mnt/infomaster/alfresco".  But since dir.root is being ignored, it's being set to a directory which doesn't exist, which puts it out of sync with the database (which it can still contact). 

Should we enter this into Jira, or is it already there?

INFO: Starting Servlet Engine: Apache Tomcat/6.0.20
09:23:27,593  INFO  [alfresco.config.JBossEnabledWebApplicationContext] Refreshi
ng org.alfresco.config.JBossEnabledWebApplicationContext@3eb68e0e: display name
[Root WebApplicationContext]; startup date [Fri Dec 18 09:23:27 GMT 2009]; root
of context hierarchy
09:23:27,773  INFO  [alfresco.config.JBossEnabledWebApplicationContext] Bean fac
tory for application context [org.alfresco.config.JBossEnabledWebApplicationCont
ext@3eb68e0e]: org.springframework.beans.factory.support.DefaultListableBeanFact
ory@709a1411
09:23:28,444  INFO  [web.scripts.DeclarativeRegistry] Registered 22 Web Scripts
(+0 failed), 24 URLs
09:23:28,456  INFO  [web.scripts.AbstractRuntimeContainer] Initialised Presentat
ion Web Script Container (in 143.1033ms)
09:23:28,661  INFO  [web.scripts.DeclarativeRegistry] Registered 40 Web Scripts
(+0 failed), 42 URLs
09:23:28,673  INFO  [web.scripts.AbstractRuntimeContainer] Initialised WebFramew
ork Web Script Container (in 214.60793ms)
Dec 18, 2009 9:23:29 AM org.apache.catalina.core.StandardContext addApplicationL
istener
INFO: The listener "org.apache.myfaces.webapp.StartupServletContextListener" is
already configured for this context. The duplicate definition has been ignored.
09:23:31,849  INFO  [alfresco.config.JndiPropertiesFactoryBean] Loading properti
es file from class path resource [alfresco/repository.properties]
09:23:31,851  INFO  [alfresco.config.JndiPropertiesFactoryBean] Loading properti
es file from class path resource [alfresco/domain/transaction.properties]
09:23:31,851  INFO  [alfresco.config.JndiPropertiesFactoryBean] Loading properti
es file from file [/srv/tomcat/webapps/alfresco/WEB-INF/classes/alfresco/module/
org_alfresco_module_dod5015/alfresco-global.properties]
09:23:31,918  INFO  [alfresco.config.JndiPropertyPlaceholderConfigurer] Loading
properties file from class path resource [alfresco/alfresco-shared.properties]
09:23:47,818  INFO  [domain.schema.SchemaBootstrap] Schema managed by database d
ialect org.hibernate.dialect.MySQLInnoDBDialect.
09:23:48,387  INFO  [domain.schema.SchemaBootstrap] No changes were made to the
schema.
09:23:48,635  INFO  [management.subsystems.ChildApplicationContextFactory] Start
ing 'sysAdmin' subsystem, ID: [sysAdmin, default]
09:23:48,646  INFO  [alfresco.config.JndiPropertyPlaceholderConfigurer] Loading
properties file from class path resource [alfresco/alfresco-shared.properties]
09:23:48,650  INFO  [management.subsystems.ChildApplicationContextFactory] Start
up of 'sysAdmin' subsystem, ID: [sysAdmin, default] complete
09:23:49,715  WARN  [node.index.FullIndexRecoveryComponent] The indexes are not
synchronized with the database.
09:23:49,815  WARN  [node.index.AVMFullIndexRecoveryComponent]     Index for avm
 store sitestore is out of date
09:23:50,645  WARN  [repo.admin.ConfigurationChecker] The Alfresco 'dir.root' pr
operty is set to a relative path './alf_data'.  'dir.root' should be overridden
to point to a specific folder.
09:23:50,645  INFO  [repo.admin.ConfigurationChecker] The Alfresco root data dir
ectory ('dir.root') is: ./alf_data
09:23:50,681  ERROR [repo.admin.ConfigurationChecker] CONTENT INTEGRITY ERROR: I
ndexes not found for 7 stores.
09:23:50,681  INFO  [repo.admin.ConfigurationChecker] You may set 'index.recover
y.mode=FULL' if you need to rebuild the indexes.
09:23:50,681  ERROR [repo.admin.ConfigurationChecker] CONTENT INTEGRITY ERROR: S
ystem content not found in content store.
09:23:50,681  ERROR [repo.admin.ConfigurationChecker] Ensure that the 'dir.root'
 property is pointing to the correct data location.
09:23:50,683  ERROR [web.context.ContextLoader] Context initialization failed
org.alfresco.error.AlfrescoRuntimeException: 11180000 Ensure that the 'dir.root'
 property is pointing to the correct data location.
        at org.alfresco.repo.admin.ConfigurationChecker.check(ConfigurationCheck
er.java:338)
‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍

[bnordgren]

Ok, while all the above observations are "true", subsequent investigation renders unlikely the conclusion to which I jumped.

At some point in time yesterday, prior to alfresco booting at all, it stopped reading my alfresco-global.properties file.  Hence, the "./alf_data" directory was the one in use prior to adding any AMPs to the warfile.  So I withdraw any assertion that installing the RM AMP affected my dir.root.

…but adding the AMPs yesterday did cause the indices to get out of sync, which caused the configuration checker to abort all attempts to start.

…and yet starting from scratch (removing ./alf_data; dropping the alfresco database and re-creating it) and adding AMPs one by one, restarting the server in between each addition, everything seems to work as expected.  No out-of-sync indices.  No errors on startup. 

The conclusion to which I now jump is that Alfresco is like DOS, or windows 95:  If you install something, you better reboot.  If you're installing lots of somethings, you do it one at a time and reboot in between each one.  If you fail to do this, it "might" work, but if you want to be sure, it's reboot city.

Now to find out why tomcat suddenly stopped locating my alfresco-global.properties file…

[bnordgren]

Just a quick follow-up…after all my jumping to conclusions, it turns out that my problem is that my installation of Tomcat 6.0.20 does not respect the shared.loader directive in the configuration file.  Once I moved the shared directory path to the common.loader directive in the same file I stopped seeing these weird problems.  My issues were mostly due to starting Tomcat in whatever random directory I happened to be at, causing a new filestore to be created which was no longer in sync with the database.  I wasn't debugging what I thought I was.  Sorry.