4.0.b to 4.0.d upgrade failure

[davidgreiner]

So I have sucessfully upgraded from 4.0.b to 4.0.d with no problems by just installing 4.0.d and pointing it to the 4.0.b alf_data and mysql database. But I have a second installation that I am trying to upgrade, and even though it is also 4.0.b it fails, here is the output of catalina.out:

May 1, 2012 12:46:12 PM org.apache.catalina.core.StandardService start
INFO: Starting service Catalina
May 1, 2012 12:46:12 PM org.apache.catalina.core.StandardEngine start
INFO: Starting Servlet Engine: Apache Tomcat/6.0.29
May 1, 2012 12:46:12 PM org.apache.catalina.startup.HostConfig deployDescriptor
INFO: Deploying configuration descriptor alfresco.xml
May 1, 2012 12:46:13 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.
2012-05-01 12:46:27,056  INFO  [management.subsystems.ChildApplicationContextFactory] [main] Starting 'sysAdmin' subsystem, ID: [sysAdmin, default]
 2012-05-01 12:46:27,131  INFO  [management.subsystems.ChildApplicationContextFactory] [main] Startup of 'sysAdmin' subsystem, ID: [sysAdmin, default] complete
 2012-05-01 12:46:35,230  WARN  [alfresco.util.AbstractTriggerBean] [main] Job ehCacheTracerJob is not active/enabled
 2012-05-01 12:46:38,645  INFO  [extensions.webscripts.TemplateProcessorRegistry] [main] Registered template processor Repository Template Processor for extension ftl
 2012-05-01 12:46:38,648  INFO  [extensions.webscripts.ScriptProcessorRegistry] [main] Registered script processor Repository Script Processor for extension js
 2012-05-01 12:46:46,249  INFO  [domain.schema.SchemaBootstrap] [main] Schema managed by database dialect org.hibernate.dialect.MySQLInnoDBDialect.
 2012-05-01 12:46:47,119  INFO  [domain.schema.SchemaBootstrap] [main] No changes were made to the schema.
 2012-05-01 12:46:47,528  INFO  [management.subsystems.ChildApplicationContextFactory] [main] Starting 'Search' subsystem, ID: [Search, managed, solr]
 2012-05-01 12:46:47,651  INFO  [management.subsystems.ChildApplicationContextFactory] [main] Startup of 'Search' subsystem, ID: [Search, managed, solr] complete
 2012-05-01 12:46:48,031  INFO  [management.subsystems.ChildApplicationContextFactory] [main] Starting 'thirdparty' subsystem, ID: [thirdparty, default]
 2012-05-01 12:46:48,480  INFO  [management.subsystems.ChildApplicationContextFactory] [main] Startup of 'thirdparty' subsystem, ID: [thirdparty, default] complete
 2012-05-01 12:46:48,481  INFO  [management.subsystems.ChildApplicationContextFactory] [main] Starting 'OOoDirect' subsystem, ID: [OOoDirect, default]
 2012-05-01 12:46:49,116  WARN  [alfresco.util.OpenOfficeConnectionTester] [main] An initial OpenOffice connection could not be established.
 2012-05-01 12:46:49,124  INFO  [management.subsystems.ChildApplicationContextFactory] [main] Startup of 'OOoDirect' subsystem, ID: [OOoDirect, default] complete
 2012-05-01 12:46:49,266  INFO  [repo.admin.ConfigurationChecker] [main] The Alfresco root data directory ('dir.root') is: /data/alf_data.4.d
 2012-05-01 12:46:49,287  INFO  [admin.patch.PatchExecuter] [main] Checking for patches to apply …
 2012-05-01 12:46:51,078  INFO  [admin.patch.PatchExecuter] [main] No patches were required.
 2012-05-01 12:46:51,093  INFO  [repo.module.ModuleServiceImpl] [main] Found 1 module(s).
 2012-05-01 12:46:51,154  INFO  [repo.module.ModuleServiceImpl] [main] Starting module 'org.alfresco.module.vti' version 1.2.
 2012-05-01 12:46:51,164  DEBUG [repo.module.ModuleComponentHelper] [main] Started module 'ModuleDetails[{module.version=1.2, module.description=Alfresco Vti Extension, module.id=org.alfresco.module.vti, module.repo.version.max=999, module.title=Vti, module.repo.version.min=0, module.installState=INSTALLED, module.installDate=2012-05-01T10:50:27.156-07:00}]' including 0components.
 2012-05-01 12:46:51,187  WARN  [repo.module.ModuleServiceImpl] [main] A previously-installed module 'org_alfresco_module_wcmquickstart' (version 1.0) is not present in your distribution.
 2012-05-01 12:46:51,187  DEBUG [repo.module.ModuleComponentHelper] [main] Installed module found in distribution: org.alfresco.module.vti
 2012-05-01 12:46:51,189  INFO  [management.subsystems.ChildApplicationContextFactory] [main] Starting 'fileServers' subsystem, ID: [fileServers, default]
 2012-05-01 12:46:51,772  INFO  [management.subsystems.ChildApplicationContextFactory] [main] Starting 'Authentication' subsystem, ID: [Authentication, managed, alfrescoNtlm1]
 2012-05-01 12:46:51,905  INFO  [management.subsystems.ChildApplicationContextFactory] [main] Startup of 'Authentication' subsystem, ID: [Authentication, managed, alfrescoNtlm1] complete
 2012-05-01 12:46:51,905  INFO  [management.subsystems.ChildApplicationContextFactory] [main] Starting 'Authentication' subsystem, ID: [Authentication, managed, ldap1]
 2012-05-01 12:46:52,107  INFO  [management.subsystems.ChildApplicationContextFactory] [main] Startup of 'Authentication' subsystem, ID: [Authentication, managed, ldap1] complete
 2012-05-01 12:46:57,126  WARN  [org.alfresco.fileserver] [main] CIFS, Unable to get local domain/workgroup name, using default of WORKGROUP. This may be due to firewall settings or incorrect <broadcast> setting)
 2012-05-01 12:46:57,393  INFO  [management.subsystems.ChildApplicationContextFactory] [main] Startup of 'fileServers' subsystem, ID: [fileServers, default] complete
 2012-05-01 12:46:57,393  INFO  [management.subsystems.ChildApplicationContextFactory] [main] Starting 'imap' subsystem, ID: [imap, default]
 2012-05-01 12:46:57,486  INFO  [management.subsystems.ChildApplicationContextFactory] [main] Startup of 'imap' subsystem, ID: [imap, default] complete
 2012-05-01 12:46:57,486  INFO  [management.subsystems.ChildApplicationContextFactory] [main] Starting 'email' subsystem, ID: [email, outbound]
 2012-05-01 12:46:57,543  INFO  [management.subsystems.ChildApplicationContextFactory] [main] Startup of 'email' subsystem, ID: [email, outbound] complete
 2012-05-01 12:46:57,543  INFO  [management.subsystems.ChildApplicationContextFactory] [main] Starting 'email' subsystem, ID: [email, inbound]
 2012-05-01 12:46:57,677  INFO  [management.subsystems.ChildApplicationContextFactory] [main] Startup of 'email' subsystem, ID: [email, inbound] complete
 2012-05-01 12:46:57,678  INFO  [management.subsystems.ChildApplicationContextFactory] [main] Starting 'googledocs' subsystem, ID: [googledocs, default]
 2012-05-01 12:46:57,780  INFO  [management.subsystems.ChildApplicationContextFactory] [main] Startup of 'googledocs' subsystem, ID: [googledocs, default] complete
 2012-05-01 12:46:57,780  INFO  [management.subsystems.ChildApplicationContextFactory] [main] Starting 'Subscriptions' subsystem, ID: [Subscriptions, default]
 2012-05-01 12:46:57,805  INFO  [management.subsystems.ChildApplicationContextFactory] [main] Startup of 'Subscriptions' subsystem, ID: [Subscriptions, default] complete
 2012-05-01 12:46:57,805  INFO  [repo.usage.UserUsageTrackingComponent] [main] Disabled - clear non-missing user usages …
 2012-05-01 12:46:57,836  INFO  [repo.usage.UserUsageTrackingComponent] [main] Found 0 users to clear
 2012-05-01 12:46:57,836  INFO  [repo.usage.UserUsageTrackingComponent] [main] … cleared non-missing usages for 0 users
 2012-05-01 12:46:57,837  INFO  [management.subsystems.ChildApplicationContextFactory] [main] Starting 'Synchronization' subsystem, ID: [Synchronization, default]
 2012-05-01 12:46:57,951  INFO  [management.subsystems.ChildApplicationContextFactory] [main] Startup of 'Synchronization' subsystem, ID: [Synchronization, default] complete
 2012-05-01 12:46:58,487  INFO  [service.descriptor.DescriptorService] [main] Alfresco JVM - v1.6.0_22-b04; maximum heap size 910.250MB
 2012-05-01 12:46:58,492  INFO  [service.descriptor.DescriptorService] [main] Alfresco started (Community). Current version: 4.0.0 (4003) schema 5,025. Originally installed version: 4.0.0 (b 3835) schema 5,019.
 2012-05-01 12:46:58,494  INFO  [management.subsystems.ChildApplicationContextFactory] [main] Starting 'ActivitiesFeed' subsystem, ID: [ActivitiesFeed, default]
 2012-05-01 12:46:58,589  INFO  [management.subsystems.ChildApplicationContextFactory] [main] Startup of 'ActivitiesFeed' subsystem, ID: [ActivitiesFeed, default] complete
 2012-05-01 12:46:58,589  INFO  [management.subsystems.ChildApplicationContextFactory] [main] Starting 'Replication' subsystem, ID: [Replication, default]
 2012-05-01 12:46:58,622  INFO  [management.subsystems.ChildApplicationContextFactory] [main] Startup of 'Replication' subsystem, ID: [Replication, default] complete
 2012-05-01 12:47:00,335  INFO  [alfresco.util.OpenOfficeConnectionTester] [DefaultScheduler_Worker-3] The OpenOffice connection was re-established.
 2012-05-01 12:47:01,728  INFO  [module.vti.VtiServer] [main] Vti server started successfully on port: 7070
 2012-05-01 12:47:01,736  INFO  [module.vti.VtiServer] [main] Vti server SessionIdManagerWorkerName: jetty1
 2012-05-01 12:47:02,016  INFO  [extensions.webscripts.AbstractRuntimeContainer] [main] Initialised Repository Web Script Container (in 239.749ms)
 2012-05-01 12:47:02,019  ERROR [web.context.ContextLoader] [main] Context initialization failed
 java.lang.NullPointerException
        at org.alfresco.repo.web.scripts.RepoStore.findNodeRef(RepoStore.java:315)
        at org.alfresco.repo.web.scripts.RepoStore$7$1.execute(RepoStore.java:685)
        at org.alfresco.repo.web.scripts.RepoStore$7$1.execute(RepoStore.java:682)
        at org.alfresco.repo.transaction.RetryingTransactionHelper.doInTransaction(RetryingTransactionHelper.java:388)
        at org.alfresco.repo.web.scripts.RepoStore$7.doWork(RepoStore.java:681)
        at org.alfresco.repo.web.scripts.RepoStore$7.doWork(RepoStore.java:678)
        at org.alfresco.repo.security.authentication.AuthenticationUtil.runAs(AuthenticationUtil.java:519)
        at org.alfresco.repo.web.scripts.RepoStore.getDocument(RepoStore.java:677)
        at org.springframework.extensions.webscripts.DeclarativeRegistry.initWebScripts(DeclarativeRegistry.java:367)
        at org.springframework.extensions.webscripts.DeclarativeRegistry.reset(DeclarativeRegistry.java:178)
        at org.springframework.extensions.webscripts.AbstractRuntimeContainer.reset(AbstractRuntimeContainer.java:259)
        at org.alfresco.repo.web.scripts.RepositoryContainer.init(RepositoryContainer.java:633)
        at org.alfresco.repo.web.scripts.RepositoryContainer$5.execute(RepositoryContainer.java:603)
        at org.alfresco.repo.transaction.RetryingTransactionHelper.doInTransaction(RetryingTransactionHelper.java:388)
        at org.alfresco.repo.web.scripts.RepositoryContainer.reset(RepositoryContainer.java:598)
        at org.alfresco.repo.web.scripts.RepositoryContainer$4.doWork(RepositoryContainer.java:553)
        at org.alfresco.repo.security.authentication.AuthenticationUtil.runAs(AuthenticationUtil.java:519)
        at org.alfresco.repo.web.scripts.RepositoryContainer.onApplicationEvent(RepositoryContainer.java:557)
        at org.alfresco.repo.management.SafeApplicationEventMulticaster.multicastEventInternal(SafeApplicationEventMulticaster.java:209)
        at org.alfresco.repo.management.SafeApplicationEventMulticaster.multicastEvent(SafeApplicationEventMulticaster.java:180)
        at org.springframework.context.support.AbstractApplicationContext.publishEvent(AbstractApplicationContext.java:303)
        at org.springframework.context.support.AbstractApplicationContext.finishRefresh(AbstractApplicationContext.java:911)
        at org.springframework.context.support.AbstractApplicationContext.refresh(AbstractApplicationContext.java:428)
        at org.springframework.web.context.ContextLoader.createWebApplicationContext(ContextLoader.java:276)
        at org.springframework.web.context.ContextLoader.initWebApplicationContext(ContextLoader.java:197)
        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:4135)
        at org.apache.catalina.core.StandardContext.start(StandardContext.java:4630)
        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:445)
        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)
‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍

I have tried switching out the keystores, wiping the config, re-installing, dumping the database and then using a new one as well as  a new alf_data (with the contents copied in) but I always get the same result as above.

Any ideas?

[davidgreiner]

I know it is old but it looks related to this issue:

https://issues.alfresco.com/jira/browse/ALF-3557

How would I fix it though? And why is the above supposedly "fixed"

[supta]

I got the same error when upgrading 4.0.b to 4.0.d. In my case, I use postgresql.

Have you found the solution for this yet?