Upgrade Alfresco MT 4.2c to 5.0b fails

[ebogaard]

Recently upgraded a 'normal' Alfresco 4.2e instance to 5.0b without problems.
Another instance is based on 4.2c AND is in multi tenant mode. This one has some difficulties. I'll post some of the 'highlights' from the logfile below (I won't paste the complete logfile, as the errors are repeated for every instance):

WARN  [admin.patch.AbstractPatch] [AbstractPatch Processor for patch.siteAdministrators1] AbstractPatch Processor for patch.siteAdministrators: Failed to process entry "abc.com".
 org.alfresco.repo.security.authority.UnknownAuthorityException: 09210146 An authority was not found for admin
   at org.alfresco.repo.security.authority.AuthorityDAOImpl.addAuthority(AuthorityDAOImpl.java:346)
   at org.alfresco.repo.security.authority.AuthorityServiceImpl.addAuthority(AuthorityServiceImpl.java:456)
   at org.alfresco.repo.security.authority.AuthorityServiceImpl.addAuthority(AuthorityServiceImpl.java:448)
   at org.alfresco.repo.admin.patch.impl.AddGroupAuthorityPatch.applyInternal(AddGroupAuthorityPatch.java:83)
   at org.alfresco.repo.admin.patch.AbstractPatch$3$1.doWork(AbstractPatch.java:529)
   at org.alfresco.repo.admin.patch.AbstractPatch$3$1.doWork(AbstractPatch.java:526)
   at org.alfresco.repo.security.authentication.AuthenticationUtil.runAs(AuthenticationUtil.java:548)
   at org.alfresco.repo.admin.patch.AbstractPatch$3.process(AbstractPatch.java:525)
   at org.alfresco.repo.admin.patch.AbstractPatch$3.process(AbstractPatch.java:507)
   at org.alfresco.repo.batch.BatchProcessor$TxnCallback.execute(BatchProcessor.java:712)
   at org.alfresco.repo.transaction.RetryingTransactionHelper.doInTransaction(RetryingTransactionHelper.java:454)
   at org.alfresco.repo.batch.BatchProcessor$TxnCallback.run(BatchProcessor.java:756)
   at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145)
   at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
   at java.lang.Thread.run(Thread.java:745)
…
2014-10-21 22:54:24,250  INFO  [admin.patch.PatchExecuter] [localhost-startStop-1]    Applying patch 'patch.alfrescoSearchAdministrators' (Adds the 'GROUP_ALFRESCO_SEARCH_ADMINISTRATORS' group).
 2014-10-21 22:54:25,035  INFO  [admin.patch.AbstractPatch] [localhost-startStop-1] AbstractPatch Processor for patch.alfrescoSearchAdministrators: Commencing batch of 29 entries
 2014-10-21 22:54:25,366  WARN  [admin.patch.AbstractPatch] [AbstractPatch Processor for patch.alfrescoSearchAdministrators1] AbstractPatch Processor for patch.alfrescoSearchAdministrators: Failed to process entry "abc.com".
 org.alfresco.repo.security.authority.UnknownAuthorityException: 09210249 An authority was not found for admin
   at org.alfresco.repo.security.authority.AuthorityDAOImpl.addAuthority(AuthorityDAOImpl.java:346)
   at org.alfresco.repo.security.authority.AuthorityServiceImpl.addAuthority(AuthorityServiceImpl.java:456)
   at org.alfresco.repo.security.authority.AuthorityServiceImpl.addAuthority(AuthorityServiceImpl.java:448)
   at org.alfresco.repo.admin.patch.impl.AddGroupAuthorityPatch.applyInternal(AddGroupAuthorityPatch.java:83)
   at org.alfresco.repo.admin.patch.AbstractPatch$3$1.doWork(AbstractPatch.java:529)
   at org.alfresco.repo.admin.patch.AbstractPatch$3$1.doWork(AbstractPatch.java:526)
   at org.alfresco.repo.security.authentication.AuthenticationUtil.runAs(AuthenticationUtil.java:548)
   at org.alfresco.repo.admin.patch.AbstractPatch$3.process(AbstractPatch.java:525)
   at org.alfresco.repo.admin.patch.AbstractPatch$3.process(AbstractPatch.java:507)
   at org.alfresco.repo.batch.BatchProcessor$TxnCallback.execute(BatchProcessor.java:712)
   at org.alfresco.repo.transaction.RetryingTransactionHelper.doInTransaction(RetryingTransactionHelper.java:454)
   at org.alfresco.repo.batch.BatchProcessor$TxnCallback.run(BatchProcessor.java:756)
   at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145)
   at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
   at java.lang.Thread.run(Thread.java:745)
…
2014-10-21 22:54:37,245  INFO  [admin.patch.PatchExecuter] [localhost-startStop-1]    Applying patch 'patch.surfConfigFolder' (Adds cm:indexControl aspect to surf-config children).
 2014-10-21 22:54:37,391  INFO  [admin.patch.PatchExecuter] [localhost-startStop-1] SurfConfigFolderPatch: Commencing batch of 2496 entries
 2014-10-21 22:54:52,387  WARN  [admin.patch.PatchExecuter] [SurfConfigFolderPatch1] SurfConfigFolderPatch: Failed to process entry "20607".
 org.alfresco.service.cmr.repository.InvalidNodeRefException: Failed invocation: ReflectiveMethodInvocation: public abstract boolean org.alfresco.service.cmr.repository.NodeService.hasAspect(org.alfresco.service.cmr.repository.NodeRef,org.alfresco.service.namespace.QName) throws org.alfresco.service.cmr.repository.InvalidNodeRefException,org.alfresco.service.cmr.dictionary.InvalidAspectException; target is of class [com.sun.proxy.$Proxy14]
   at org.alfresco.repo.tenant.MultiTNodeServiceInterceptor.invoke(MultiTNodeServiceInterceptor.java:144)
   at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172)
   at org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDynamicAopProxy.java:204)
   at com.sun.proxy.$Proxy14.hasAspect(Unknown Source)
   at sun.reflect.GeneratedMethodAccessor229.invoke(Unknown Source)
   at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
   at java.lang.reflect.Method.invoke(Method.java:606)
   at org.alfresco.repo.service.StoreRedirectorProxyFactory$RedirectorInvocationHandler.invoke(StoreRedirectorProxyFactory.java:215)
   at com.sun.proxy.$Proxy35.hasAspect(Unknown Source)
   at org.alfresco.repo.node.MLPropertyInterceptor.getPivotNodeRef(MLPropertyInterceptor.java:323)
   at org.alfresco.repo.node.MLPropertyInterceptor.invoke(MLPropertyInterceptor.java:162)
   at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172)
   at org.alfresco.repo.node.NodeRefPropertyMethodInterceptor.invoke(NodeRefPropertyMethodInterceptor.java:198)
   at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172)
   at org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDynamicAopProxy.java:204)
   at com.sun.proxy.$Proxy14.getProperty(Unknown Source)
   at sun.reflect.GeneratedMethodAccessor299.invoke(Unknown Source)
   at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
   at java.lang.reflect.Method.invoke(Method.java:606)
   at org.springframework.aop.support.AopUtils.invokeJoinpointUsingReflection(AopUtils.java:317)
   at org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDynamicAopProxy.java:198)
   at com.sun.proxy.$Proxy14.getProperty(Unknown Source)
   at org.alfresco.repo.admin.patch.impl.SurfConfigFolderPatch.addIndexControlAspectIfNotExist(SurfConfigFolderPatch.java:333)
   at org.alfresco.repo.admin.patch.impl.SurfConfigFolderPatch.process(SurfConfigFolderPatch.java:267)
   at org.alfresco.repo.admin.patch.impl.SurfConfigFolderPatch.access$300(SurfConfigFolderPatch.java:57)
   at org.alfresco.repo.admin.patch.impl.SurfConfigFolderPatch$1.process(SurfConfigFolderPatch.java:167)
   at org.alfresco.repo.admin.patch.impl.SurfConfigFolderPatch$1.process(SurfConfigFolderPatch.java:150)
   at org.alfresco.repo.batch.BatchProcessor$TxnCallback.execute(BatchProcessor.java:712)
   at org.alfresco.repo.transaction.RetryingTransactionHelper.doInTransaction(RetryingTransactionHelper.java:454)
   at org.alfresco.repo.batch.BatchProcessor$TxnCallback.run(BatchProcessor.java:756)
   at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145)
   at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
   at java.lang.Thread.run(Thread.java:745)
Caused by: org.alfresco.service.cmr.repository.InvalidNodeRefException: Node does not exist: deleted://2a1065eb-d177-4ef4-9d34-d1af76dff948/b5b68168-c81a-45fe-8648-d82cc150bc4d (status:Status[id=51648changeTxnId=96db2397-be52-4302-9e87-92b4352721af, dbTxnId=46825, deleted=true])
   at org.alfresco.repo.node.db.DbNodeServiceImpl.getNodePairNotNull(DbNodeServiceImpl.java:182)
   at org.alfresco.repo.node.db.DbNodeServiceImpl.hasAspect(DbNodeServiceImpl.java:997)
   at sun.reflect.GeneratedMethodAccessor229.invoke(Unknown Source)
   at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
   at java.lang.reflect.Method.invoke(Method.java:606)
   at org.springframework.aop.support.AopUtils.invokeJoinpointUsingReflection(AopUtils.java:317)
   at org.springframework.aop.framework.ReflectiveMethodInvocation.invokeJoinpoint(ReflectiveMethodInvocation.java:183)
   at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:150)
   at org.alfresco.repo.lock.mem.LockableAspectInterceptor.invoke(LockableAspectInterceptor.java:111)
   at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172)
   at org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDynamicAopProxy.java:204)
   at com.sun.proxy.$Proxy14.hasAspect(Unknown Source)
   at sun.reflect.GeneratedMethodAccessor229.invoke(Unknown Source)
   at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
   at java.lang.reflect.Method.invoke(Method.java:606)
   at org.springframework.aop.support.AopUtils.invokeJoinpointUsingReflection(AopUtils.java:317)
   at org.springframework.aop.framework.ReflectiveMethodInvocation.invokeJoinpoint(ReflectiveMethodInvocation.java:183)
   at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:150)
   at org.alfresco.repo.tenant.MultiTNodeServiceInterceptor.invoke(MultiTNodeServiceInterceptor.java:140)
   … 32 more

…
2014-10-21 22:57:19,120  INFO  [patch.impl.SurfConfigFolderPatch] [SurfConfigFolderPatch1] WARNING: unable to find surf-config folder for site: [test]
 2014-10-21 22:59:45,788  ERROR [node.ibatis.NodeDAOImpl$MySQL] [SurfConfigFolderPatch1] ALF-12358: Deleted node - removed child assoc: 47829
 2014-10-21 22:59:46,026  ERROR [node.ibatis.NodeDAOImpl$MySQL] [SurfConfigFolderPatch1] ALF-12358: Deleted node - removed child assoc: 47828
…
 2014-10-21 22:59:59,964  INFO  [admin.patch.PatchExecuter] [localhost-startStop-1] SurfConfigFolderPatch: Processed 208 entries out of 2496. 8% complete. Rate: 0 per second. 20 failures detected.
 2014-10-21 22:59:59,964  INFO  [admin.patch.PatchExecuter] [localhost-startStop-1] SurfConfigFolderPatch: Completed batch of 2496 entries
 2014-10-21 22:59:59,964  ERROR [admin.patch.PatchExecuter] [localhost-startStop-1] SurfConfigFolderPatch: 20 error(s) detected. Last error from entry "49926"
 org.alfresco.service.cmr.repository.InvalidNodeRefException: Failed invocation: ReflectiveMethodInvocation: public abstract boolean org.alfresco.service.cmr.repository.NodeService.hasAspect(org.alfresco.service.cmr.repository.NodeRef,org.alfresco.service.namespace.QName) throws org.alfresco.service.cmr.repository.InvalidNodeRefException,org.alfresco.service.cmr.dictionary.InvalidAspectException; target is of class [com.sun.proxy.$Proxy14]
   at org.alfresco.repo.tenant.MultiTNodeServiceInterceptor.invoke(MultiTNodeServiceInterceptor.java:144)
   at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172)
   at org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDynamicAopProxy.java:204)
   at com.sun.proxy.$Proxy14.hasAspect(Unknown Source)
   at sun.reflect.GeneratedMethodAccessor229.invoke(Unknown Source)
   at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
   at java.lang.reflect.Method.invoke(Method.java:606)
   at org.alfresco.repo.service.StoreRedirectorProxyFactory$RedirectorInvocationHandler.invoke(StoreRedirectorProxyFactory.java:215)
   at com.sun.proxy.$Proxy35.hasAspect(Unknown Source)
   at org.alfresco.repo.node.MLPropertyInterceptor.getPivotNodeRef(MLPropertyInterceptor.java:323)
   at org.alfresco.repo.node.MLPropertyInterceptor.invoke(MLPropertyInterceptor.java:162)
   at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172)
   at org.alfresco.repo.node.NodeRefPropertyMethodInterceptor.invoke(NodeRefPropertyMethodInterceptor.java:198)
   at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172)
   at org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDynamicAopProxy.java:204)
   at com.sun.proxy.$Proxy14.getProperty(Unknown Source)
   at sun.reflect.GeneratedMethodAccessor299.invoke(Unknown Source)
   at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
   at java.lang.reflect.Method.invoke(Method.java:606)
   at org.springframework.aop.support.AopUtils.invokeJoinpointUsingReflection(AopUtils.java:317)
   at org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDynamicAopProxy.java:198)
   at com.sun.proxy.$Proxy14.getProperty(Unknown Source)
   at org.alfresco.repo.admin.patch.impl.SurfConfigFolderPatch.addIndexControlAspectIfNotExist(SurfConfigFolderPatch.java:333)
   at org.alfresco.repo.admin.patch.impl.SurfConfigFolderPatch.process(SurfConfigFolderPatch.java:267)
   at org.alfresco.repo.admin.patch.impl.SurfConfigFolderPatch.access$300(SurfConfigFolderPatch.java:57)
   at org.alfresco.repo.admin.patch.impl.SurfConfigFolderPatch$1.process(SurfConfigFolderPatch.java:167)
   at org.alfresco.repo.admin.patch.impl.SurfConfigFolderPatch$1.process(SurfConfigFolderPatch.java:150)
   at org.alfresco.repo.batch.BatchProcessor$TxnCallback.execute(BatchProcessor.java:712)
   at org.alfresco.repo.transaction.RetryingTransactionHelper.doInTransaction(RetryingTransactionHelper.java:454)
   at org.alfresco.repo.batch.BatchProcessor$TxnCallback.run(BatchProcessor.java:756)
   at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145)
   at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
   at java.lang.Thread.run(Thread.java:745)
Caused by: org.alfresco.service.cmr.repository.InvalidNodeRefException: Node does not exist: deleted://2a1065eb-d177-4ef4-9d34-d1af76dff948/0f2bda4c-9130-49fe-b4a4-c10ca6e15c15 (status:Status[id=51668changeTxnId=96db2397-be52-4302-9e87-92b4352721af, dbTxnId=46825, deleted=true])
   at org.alfresco.repo.node.db.DbNodeServiceImpl.getNodePairNotNull(DbNodeServiceImpl.java:182)
   at org.alfresco.repo.node.db.DbNodeServiceImpl.hasAspect(DbNodeServiceImpl.java:997)
   at sun.reflect.GeneratedMethodAccessor229.invoke(Unknown Source)
   at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
   at java.lang.reflect.Method.invoke(Method.java:606)
   at org.springframework.aop.support.AopUtils.invokeJoinpointUsingReflection(AopUtils.java:317)
   at org.springframework.aop.framework.ReflectiveMethodInvocation.invokeJoinpoint(ReflectiveMethodInvocation.java:183)
   at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:150)
   at org.alfresco.repo.lock.mem.LockableAspectInterceptor.invoke(LockableAspectInterceptor.java:111)
   at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172)
   at org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDynamicAopProxy.java:204)
   at com.sun.proxy.$Proxy14.hasAspect(Unknown Source)
   at sun.reflect.GeneratedMethodAccessor229.invoke(Unknown Source)
   at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
   at java.lang.reflect.Method.invoke(Method.java:606)
   at org.springframework.aop.support.AopUtils.invokeJoinpointUsingReflection(AopUtils.java:317)
   at org.springframework.aop.framework.ReflectiveMethodInvocation.invokeJoinpoint(ReflectiveMethodInvocation.java:183)
   at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:150)
   at org.alfresco.repo.tenant.MultiTNodeServiceInterceptor.invoke(MultiTNodeServiceInterceptor.java:140)
   … 32 more
…
2014-10-21 23:48:54,630  ERROR [node.ibatis.NodeDAOImpl$MySQL] [SurfConfigFolderPatch1] ALF-12358: Deleted node - removed child assoc: 67059
 2014-10-21 23:48:55,037  ERROR [node.ibatis.NodeDAOImpl$MySQL] [SurfConfigFolderPatch1] ALF-12358: Deleted node - removed child assoc: 66661
‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍

And then the process just stop.
Note that this is a test-update without any files in the dir.root-contentfolder, but with
system.bootstrap.config_check.strict=false
index.subsystem.name=noindex

Hope you can help tracing what the problem is!

Also tried updating from 4.2c to 4.2f first, but this wouldn't succeed either, with similar errors.

[stefanotravelli]

I found the same issue and I was able to overcome the problem modifing the failing patch with this change: https://github.com/entaksi/community-edition/commit/d5f170fca4ec2d317d38f3f1d13def4f60546239

Hope this helps.