AnsweredAssumed Answered

Upgrade Alfresco MT 4.2c to 5.0b fails

Question asked by ebogaard on Oct 25, 2014
Latest reply on Dec 13, 2014 by stefanotravelli
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.

Outcomes