Hyland Connect

varsenault · ‎07-17-2012

Hi guys,

I'm updrading from a 3.2r installation with 500GB (the MySQL database dump is approximately 500MB) of documents and workflow definitions (I copied the configuration files, MySQL database, alf_data directory from 3.2r to 4.0d).

A "naked" default 4.0d installation runs perfectly fine, but with the 3.2r data and configuration I get this when starting alfresco "sh alfresco.sh start". And in the web browser, I get a 404 error.

This is on an isolated Ubuntu LTS 12.04 machine.

We have been using Alfresco since version 2 here, and upgrades have never been out of the box…

Based on the log below, can someone help us? Regards,

Vincent

11:35:57,995 INFO  [org.alfresco.repo.management.subsystems.ChildApplicationContextFactory] Starting 'sysAdmin' subsystem, ID: [sysAdmin, default]
11:35:58,086 INFO  [org.alfresco.repo.management.subsystems.ChildApplicationContextFactory] Startup of 'sysAdmin' subsystem, ID: [sysAdmin, default] complete
11:36:04,906 WARN  [org.alfresco.util.AbstractTriggerBean] Job ehCacheTracerJob is not active/enabled
11:36:08,229 INFO  [org.springframework.extensions.webscripts.TemplateProcessorRegistry] Registered template processor Repository Template Processor for extension ftl
11:36:08,231 INFO  [org.springframework.extensions.webscripts.ScriptProcessorRegistry] Registered script processor Repository Script Processor for extension js
11:36:15,163 INFO  [org.alfresco.repo.domain.schema.SchemaBootstrap] Schema managed by database dialect org.hibernate.dialect.MySQLInnoDBDialect.
11:36:16,395 ERROR [org.alfresco.repo.domain.schema.SchemaBootstrap] Schema auto-update failed
java.lang.RuntimeException: Unable to execute export.
   at org.alfresco.util.schemacomp.ExportDb.execute(ExportDb.java:163)
   at org.alfresco.repo.domain.schema.SchemaBootstrap.validateSchema(SchemaBootstrap.java:1671)
   at org.alfresco.repo.domain.schema.SchemaBootstrap.executeScriptFile(SchemaBootstrap.java:1038)
   at org.alfresco.repo.domain.schema.SchemaBootstrap.executeScriptUrl(SchemaBootstrap.java:969)
   at org.alfresco.repo.domain.schema.SchemaBootstrap.checkSchemaPatchScripts(SchemaBootstrap.java:940)
   at org.alfresco.repo.domain.schema.SchemaBootstrap.updateSchema(SchemaBootstrap.java:806)
   at org.alfresco.repo.domain.schema.SchemaBootstrap.onBootstrap(SchemaBootstrap.java:1479)
   at org.springframework.extensions.surf.util.AbstractLifecycleBean.onApplicationEvent(AbstractLifecycleBean.java:56)
   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)
Caused by: java.lang.NullPointerException
   at org.alfresco.util.schemacomp.ExportDb.execute(ExportDb.java:295)
   at org.alfresco.util.schemacomp.ExportDb.execute(ExportDb.java:159)
   … 40 more
11:36:16,398 ERROR [org.springframework.web.context.ContextLoader] Context initialization failed
org.alfresco.error.AlfrescoRuntimeException: 06170000 Schema auto-update failed
   at org.alfresco.repo.domain.schema.SchemaBootstrap.onBootstrap(SchemaBootstrap.java:1612)
   at org.springframework.extensions.surf.util.AbstractLifecycleBean.onApplicationEvent(AbstractLifecycleBean.java:56)
   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)
Caused by: java.lang.RuntimeException: Unable to execute export.
   at org.alfresco.util.schemacomp.ExportDb.execute(ExportDb.java:163)
   at org.alfresco.repo.domain.schema.SchemaBootstrap.validateSchema(SchemaBootstrap.java:1671)
   at org.alfresco.repo.domain.schema.SchemaBootstrap.executeScriptFile(SchemaBootstrap.java:1038)
   at org.alfresco.repo.domain.schema.SchemaBootstrap.executeScriptUrl(SchemaBootstrap.java:969)
   at org.alfresco.repo.domain.schema.SchemaBootstrap.checkSchemaPatchScripts(SchemaBootstrap.java:940)
   at org.alfresco.repo.domain.schema.SchemaBootstrap.updateSchema(SchemaBootstrap.java:806)
   at org.alfresco.repo.domain.schema.SchemaBootstrap.onBootstrap(SchemaBootstrap.java:1479)
   … 34 more
Caused by: java.lang.NullPointerException
   at org.alfresco.util.schemacomp.ExportDb.execute(ExportDb.java:295)
   at org.alfresco.util.schemacomp.ExportDb.execute(ExportDb.java:159)
   … 40 more
11:36:25,206 INFO  [org.springframework.extensions.webscripts.TemplateProcessorRegistry] Registered template processor freemarker for extension ftl
11:36:25,258 INFO  [org.springframework.extensions.webscripts.ScriptProcessorRegistry] Registered script processor javascript for extension js
11:36:25,258 INFO  [org.springframework.extensions.webscripts.TemplateProcessorRegistry] Registered template processor freemarker for extension ftl
11:36:25,261 INFO  [org.springframework.extensions.webscripts.ScriptProcessorRegistry] Registered script processor javascript for extension js
11:36:26,579 INFO  [org.springframework.extensions.webscripts.DeclarativeRegistry] Registered 312 Web Scripts (+0 failed), 322 URLs
11:36:26,579 INFO  [org.springframework.extensions.webscripts.DeclarativeRegistry] Registered 8 Package Description Documents (+0 failed) 
11:36:26,579 INFO  [org.springframework.extensions.webscripts.DeclarativeRegistry] Registered 0 Schema Description Documents (+0 failed) 
11:36:26,746 INFO  [org.springframework.extensions.webscripts.AbstractRuntimeContainer] Initialised Spring Surf Container Web Script Container (in 1481.6385ms)
11:36:26,751 INFO  [org.springframework.extensions.webscripts.TemplateProcessorRegistry] Registered template processor freemarker for extension ftl
11:36:26,754 INFO  [org.springframework.extensions.webscripts.ScriptProcessorRegistry] Registered script processor javascript for extension js‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍

wepwjwcv · ‎07-27-2012

Hi Vincent,

I was facing the same problem and could work around like this:
Install and setup A4.0.d, test the naked default installation.
Then remove the alfresco.war and the exploded application from the webapps directory.
Download Alfresco 4.0.b, extract alfresco.war and place it in the webapps directory.
Start Alfresco. Migration happens. You might want to disable SOLR during the update.
As soon as everythin is finished, stop alfresco again.
Remove alfresco.war and exploded dir again, replace it with the alfresco.war from 4.0.d.
Start, let it do the rest of the migration and you should be good to go.
At least this worked for me.
Good luck!

varsenault · ‎08-07-2012

Hi! I can confirm I could upgrade to 4.0b. Trying 4.0d now. If I face another problem, I will post here, otherwise, that solution made it for me. Thanks!

varsenault · ‎08-08-2012

So I ran into this bug: https://issues.alfresco.com/jira/browse/ALF-12337

Here is the log.

Trying now without the said patch by commenting it out from patch-services-context.xml.

16:33:42,344 INFO  [org.alfresco.repo.management.subsystems.ChildApplicationContextFactory] Starting 'sysAdmin' subsystem, ID: [sysAdmin, default]
16:33:42,438 INFO  [org.alfresco.repo.management.subsystems.ChildApplicationContextFactory] Startup of 'sysAdmin' subsystem, ID: [sysAdmin, default] complete
16:33:49,222 WARN  [org.alfresco.util.AbstractTriggerBean] Job ehCacheTracerJob is not active/enabled
16:33:52,715 INFO  [org.springframework.extensions.webscripts.TemplateProcessorRegistry] Registered template processor Repository Template Processor for extension ftl
16:33:52,719 INFO  [org.springframework.extensions.webscripts.ScriptProcessorRegistry] Registered script processor Repository Script Processor for extension js
16:33:57,988 INFO  [org.alfresco.repo.domain.schema.SchemaBootstrap] Schema managed by database dialect org.hibernate.dialect.MySQLInnoDBDialect.
16:34:00,187 INFO  [org.alfresco.repo.domain.schema.SchemaBootstrap] No changes were made to the schema.
16:34:00,562 INFO  [org.alfresco.repo.management.subsystems.ChildApplicationContextFactory] Starting 'Search' subsystem, ID: [Search, managed, lucene]
16:34:14,099 INFO  [org.alfresco.repo.management.subsystems.ChildApplicationContextFactory] Startup of 'Search' subsystem, ID: [Search, managed, lucene] complete
16:34:15,741 INFO  [org.alfresco.repo.management.subsystems.ChildApplicationContextFactory] Starting 'thirdparty' subsystem, ID: [thirdparty, default]
16:34:16,379 INFO  [org.alfresco.repo.management.subsystems.ChildApplicationContextFactory] Startup of 'thirdparty' subsystem, ID: [thirdparty, default] complete
16:34:16,380 INFO  [org.alfresco.repo.management.subsystems.ChildApplicationContextFactory] Starting 'OOoDirect' subsystem, ID: [OOoDirect, default]
16:34:17,647 INFO  [org.alfresco.repo.management.subsystems.ChildApplicationContextFactory] Startup of 'OOoDirect' subsystem, ID: [OOoDirect, default] complete
16:34:20,140 INFO  [org.alfresco.repo.node.index.FullIndexRecoveryComponent] Index recovery started: 215,751 transactions.
16:34:57,526 WARN  [org.alfresco.repo.cache.TransactionalCache.org.alfresco.cache.contentDataTransactionalCache] Transactional update cache 'org.alfresco.cache.contentDataTransactionalCache' is full (1000).
16:35:52,459 WARN  [org.alfresco.repo.cache.TransactionalCache.org.alfresco.cache.node.nodesTransactionalCache] Transactional update cache 'org.alfresco.cache.node.nodesTransactionalCache' is full (125000).
16:35:54,473 WARN  [org.alfresco.repo.cache.TransactionalCache.org.alfresco.cache.node.propertiesTransactionalCache] Transactional update cache 'org.alfresco.cache.node.propertiesTransactionalCache' is full (65000).
16:35:54,545 WARN  [org.alfresco.repo.cache.TransactionalCache.org.alfresco.cache.node.aspectsTransactionalCache] Transactional update cache 'org.alfresco.cache.node.aspectsTransactionalCache' is full (65000).
16:37:08,016 INFO  [org.alfresco.repo.node.index.FullIndexRecoveryComponent]    10 % complete.
16:38:35,969 INFO  [org.alfresco.repo.node.index.FullIndexRecoveryComponent]    20 % complete.
16:40:59,672 INFO  [org.alfresco.repo.node.index.FullIndexRecoveryComponent]    30 % complete.
16:43:37,597 INFO  [org.alfresco.repo.node.index.FullIndexRecoveryComponent]    40 % complete.
16:48:41,995 INFO  [org.alfresco.repo.node.index.FullIndexRecoveryComponent]    50 % complete.
16:52:44,799 INFO  [org.alfresco.repo.node.index.FullIndexRecoveryComponent]    60 % complete.
16:55:13,257 WARN  [org.alfresco.repo.cache.TransactionalCache.org.alfresco.cache.contentDataTransactionalCache] Transactional update cache 'org.alfresco.cache.contentDataTransactionalCache' is full (1000).
16:55:13,264 WARN  [org.alfresco.repo.cache.TransactionalCache.org.alfresco.cache.contentDataTransactionalCache] Transactional update cache 'org.alfresco.cache.contentDataTransactionalCache' is full (1000).
16:55:15,490 WARN  [org.alfresco.repo.cache.TransactionalCache.org.alfresco.cache.contentDataTransactionalCache] Transactional update cache 'org.alfresco.cache.contentDataTransactionalCache' is full (1000).
16:57:41,277 INFO  [org.alfresco.repo.node.index.FullIndexRecoveryComponent]    70 % complete.
17:01:26,869 INFO  [org.alfresco.repo.node.index.FullIndexRecoveryComponent]    80 % complete.
17:07:11,932 INFO  [org.alfresco.repo.node.index.FullIndexRecoveryComponent]    90 % complete.
17:11:08,601 INFO  [org.alfresco.repo.node.index.FullIndexRecoveryComponent]    100 % complete.
17:11:12,219 INFO  [org.alfresco.repo.node.index.FullIndexRecoveryComponent] Index recovery completed.
17:11:13,067 INFO  [org.alfresco.repo.node.index.AVMFullIndexRecoveryComponent] Rebuilding indexes for 2 AVM Stores
17:11:13,067 INFO  [org.alfresco.repo.node.index.AVMFullIndexRecoveryComponent]   Reindex   0% complete
17:11:13,068 INFO  [org.alfresco.repo.node.index.AVMFullIndexRecoveryComponent]     Rebuilding index for investissementresponsable
17:11:13,793 INFO  [org.alfresco.repo.node.index.AVMFullIndexRecoveryComponent]         Rebuilding index for snapshots 0 to 4
17:11:19,984 INFO  [org.alfresco.repo.node.index.AVMFullIndexRecoveryComponent]   Reindex   50% complete
17:11:19,984 INFO  [org.alfresco.repo.node.index.AVMFullIndexRecoveryComponent]     Rebuilding index for sitestore
17:11:20,113 INFO  [org.alfresco.repo.node.index.AVMFullIndexRecoveryComponent]         Rebuilding index for snapshots 0 to 2
17:11:20,507 INFO  [org.alfresco.repo.node.index.AVMFullIndexRecoveryComponent]   Reindex   100% complete
17:11:20,507 INFO  [org.alfresco.repo.node.index.AVMFullIndexRecoveryComponent] Finished rebuilding indexes for AVM Stores
17:11:22,062 INFO  [org.alfresco.repo.admin.ConfigurationChecker] The Alfresco root data directory ('dir.root') is: /backup/alf_data
17:11:22,100 INFO  [org.alfresco.repo.admin.patch.PatchExecuter] Checking for patches to apply …
17:11:23,527 INFO  [org.alfresco.repo.admin.patch.PatchExecuter]    Applying patch 'patch.thumbnailsAssocQName' (Update the 'cm:thumbnails' association QName to 'rn:rendition'.).
17:11:23,806 INFO  [org.alfresco.repo.admin.patch.PatchExecuter]    Applying patch 'patch.fixUserQNames' (Fixes user store qnames to improve native authentication performance).
17:11:24,155 INFO  [org.alfresco.repo.admin.patch.impl.FixUserQNamesPatch] FixUserQNamesPatch: Commencing batch of 55 entries
17:11:26,747 INFO  [org.alfresco.repo.admin.patch.impl.FixUserQNamesPatch] FixUserQNamesPatch: Processed 55 entries out of 55. 100% complete. Rate: 21 per second. 0 failures detected.
17:11:26,747 INFO  [org.alfresco.repo.admin.patch.impl.FixUserQNamesPatch] FixUserQNamesPatch: Completed batch of 55 entries
17:11:26,833 INFO  [org.alfresco.repo.admin.patch.PatchExecuter]    Applying patch 'patch.redeployNominatedInvitationProcessWithPropsForShare' (Redeploy nominated invitation workflow).
17:11:28,357 INFO  [org.alfresco.repo.admin.patch.PatchExecuter]    Applying patch 'patch.rendition.rendering_actions' (Creates the Rendering Actions folder.).
17:11:29,133 INFO  [org.alfresco.repo.admin.patch.PatchExecuter]    Applying patch 'patch.emailInviteAndNotifyTemplatesFolder' (Ensures the existence of the 'Email Invite Templates' and 'Email Notify Templates' folders.).
17:11:29,983 INFO  [org.alfresco.repo.admin.patch.PatchExecuter]    Applying patch 'patch.convertContentUrls' (Converts pre-3.2 content URLs to use the alf_content_data table.  The conversion work can also be done on a schedule; please contact Alfresco Support for further details.).
17:11:30,204 INFO  [org.alfresco.repo.admin.patch.PatchExecuter] Content URL conversion progress:
17:11:30,204 INFO  [org.alfresco.repo.admin.patch.PatchExecuter]    Processing ADM Content URLs.
17:12:18,887 INFO  [org.alfresco.repo.admin.patch.PatchExecuter]       Patch patch.convertContentUrls 10% complete, estimated complete at 07/08/12 5:19 PM.
17:12:39,437 INFO  [org.alfresco.repo.admin.patch.PatchExecuter]       Patch patch.convertContentUrls 20% complete, estimated complete at 07/08/12 5:17 PM.
17:12:48,422 INFO  [org.alfresco.repo.admin.patch.PatchExecuter]       Patch patch.convertContentUrls 30% complete, estimated complete at 07/08/12 5:15 PM.
17:12:57,504 INFO  [org.alfresco.repo.admin.patch.PatchExecuter]       Patch patch.convertContentUrls 40% complete, estimated complete at 07/08/12 5:15 PM.
17:13:15,907 INFO  [org.alfresco.repo.admin.patch.PatchExecuter]       Patch patch.convertContentUrls 50% complete, estimated complete at 07/08/12 5:15 PM.
17:13:51,730 INFO  [org.alfresco.repo.admin.patch.PatchExecuter]       Patch patch.convertContentUrls 60% complete, estimated complete at 07/08/12 5:15 PM.
17:14:49,686 INFO  [org.alfresco.repo.admin.patch.PatchExecuter]       Patch patch.convertContentUrls 70% complete, estimated complete at 07/08/12 5:16 PM.
17:15:02,843 INFO  [org.alfresco.repo.admin.patch.PatchExecuter]    Finished processing ADM nodes up to ID 1,006,086.
17:15:02,928 INFO  [org.alfresco.repo.admin.patch.PatchExecuter]    Processing AVM Content URLs.
17:15:03,623 INFO  [org.alfresco.repo.admin.patch.PatchExecuter]    Finished processing AVM nodes up to ID 390.
17:15:03,703 INFO  [org.alfresco.repo.admin.patch.PatchExecuter]    Reading content URLs from store FileContentStore[ root=/backup/alf_data/contentstore, allowRandomAccess=true, readOnly=false].
17:45:49,774 INFO  [org.alfresco.repo.admin.patch.PatchExecuter]    Scheduled 5,746 content URLs for deletion from store: FileContentStore[ root=/backup/alf_data/contentstore, allowRandomAccess=true, readOnly=false]
17:45:51,498 INFO  [org.alfresco.repo.admin.patch.PatchExecuter]    Applying patch 'patch.transferServiceFolder' (Add transfer definitions folder to data dictionary.).
17:45:58,752 INFO  [org.alfresco.repo.admin.patch.PatchExecuter]    Applying patch 'patch.fixAuthoritiesCrcValues' (Fixes authority CRC32 values to match UTF-8 encoding.).
17:46:00,243 INFO  [org.alfresco.repo.admin.patch.impl.FixAuthoritiesCrcValuesPatch] FixAuthorityCrcValuesPatch: Commencing batch of 55 entries
17:46:01,885 INFO  [org.alfresco.repo.admin.patch.impl.FixAuthoritiesCrcValuesPatch] FixAuthorityCrcValuesPatch: Processed 55 entries out of 55. 100% complete. Rate: 33 per second. 0 failures detected.
17:46:01,885 INFO  [org.alfresco.repo.admin.patch.impl.FixAuthoritiesCrcValuesPatch] FixAuthorityCrcValuesPatch: Completed batch of 55 entries
17:46:01,945 INFO  [org.alfresco.repo.admin.patch.PatchExecuter]    Applying patch 'patch.updateMimetypes1' (Fix mimetypes for Excel and Powerpoint.).
17:46:19,161 WARN  [org.alfresco.repo.cache.TransactionalCache.org.alfresco.cache.contentDataTransactionalCache] Transactional update cache 'org.alfresco.cache.contentDataTransactionalCache' is full (1000).
18:00:37,855 INFO  [org.alfresco.repo.admin.patch.PatchExecuter]    Applying patch 'patch.migrateAttrAVMLocks' (Migrate old AVM Lock attributes).
18:00:38,156 INFO  [org.alfresco.repo.admin.patch.PatchExecuter]    Applying patch 'patch.migrateAttrPropBackedBeans' (Migrate old Property-Backed Bean component attributes).
18:00:38,243 INFO  [org.alfresco.repo.admin.patch.PatchExecuter]    Applying patch 'patch.migrateAttrChainingURS' (Migrate old Chaining User Registry Synchronizer attributes).
18:00:38,323 INFO  [org.alfresco.repo.admin.patch.PatchExecuter]    Applying patch 'patch.migrateAttrDelete' (null).
18:00:38,325 WARN  [org.alfresco.repo.admin.patch.PatchExecuter] Patch description is not available: Patch[ id=patch.migrateAttrDelete, description=patch.migrateAttrDelete.description, fixesFromSchema=0, fixesToSchema=4106, targetSchema=4107]
18:00:38,410 INFO  [org.alfresco.repo.admin.patch.PatchExecuter]    Applying patch 'patch.replication.replication_actions' (Creates the Replication Actions folder.).
18:01:53,831 INFO  [org.alfresco.repo.admin.patch.PatchExecuter]    Applying patch 'patch.transfer.targetrulefolder' (Creates the transfer target rule folder for the default transfer group.).
18:01:54,309 INFO  [org.alfresco.repo.admin.patch.PatchExecuter]    Applying patch 'patch.transfer.targetrule' (Creates the transfer target rule for the default transfer group.).
18:01:54,777 INFO  [org.alfresco.repo.admin.patch.PatchExecuter]    Applying patch 'patch.actions.scheduledfolder' (Creates the scheduled actions folder in the Data Dictionary.).
18:01:55,142 INFO  [org.alfresco.repo.admin.patch.PatchExecuter]    Applying patch 'patch.updateMimetypes2' (Fix mimetypes for Excel and Powerpoint.).
18:01:55,361 INFO  [org.alfresco.repo.admin.patch.PatchExecuter]    Applying patch 'patch.redeployJbpmAdhocWorkflow' (Redeploy JBPM adhoc workflow).
18:01:56,218 INFO  [org.alfresco.repo.admin.patch.PatchExecuter]    Applying patch 'patch.mtFixAdminExistingTenants' (Fix bootstrapped creator/modifier).
18:01:56,559 INFO  [org.alfresco.repo.admin.patch.PatchExecuter]    Applying patch 'patch.activitiesEmailTemplate' (null).
18:01:59,400 WARN  [org.alfresco.repo.admin.patch.PatchExecuter] Patch description is not available: Patch[ id=patch.activitiesEmailTemplate, description=patch.activitiesEmailTemplate.description, fixesFromSchema=0, fixesToSchema=5005, targetSchema=5006]
18:01:59,470 INFO  [org.alfresco.repo.admin.patch.PatchExecuter]    Applying patch 'patch.newUserEmailTemplates' (Adds the email templates for notifying new users of their accounts).
18:02:00,646 INFO  [org.alfresco.repo.admin.patch.PatchExecuter]    Applying patch 'patch.inviteEmailTemplates' (Adds the email templates for inviting users to a Site).
18:02:01,646 INFO  [org.alfresco.repo.admin.patch.PatchExecuter]    Applying patch 'patch.htmlNotificationMailTemplates' (Adds HTML email templates for notifying users of new content).
18:02:48,943 INFO  [org.alfresco.repo.admin.patch.PatchExecuter]    Applying patch 'patch.imapSpacesLocaleTemplates' (Adds content templates for IMAP clients (Non-default locales only)).
18:02:52,918 INFO  [org.alfresco.repo.admin.patch.PatchExecuter]    Applying patch 'patch.fixAclInheritance' (Fixes any ACL inheritance issues.).
18:03:20,252 INFO  [org.alfresco.repo.admin.patch.PatchExecuter]    Applying patch 'patch.imap.clear.old.messages' (Remove old IMAP message templates).
18:03:54,422 INFO  [org.alfresco.repo.admin.patch.PatchExecuter]    Applying patch 'patch.migrateAttrDropOldTables' (Drops old alf_*attribute* tables and sequence).
18:03:55,322 INFO  [org.alfresco.repo.admin.patch.PatchExecuter]    Applying patch 'patch.activitiesTemplatesUpdate' (Updates activities email templates.).
18:03:58,908 INFO  [org.alfresco.repo.admin.patch.PatchExecuter]    Applying patch 'patch.followingMailTemplates' (Adds email templates for following notifications).
18:03:59,369 INFO  [org.alfresco.repo.admin.patch.PatchExecuter]    Applying patch 'patch.copiedFromAspect' (Adds peer associations for cm:copiedfrom and cm:workingcopy (new model) and removes cm:source property).
18:04:01,513 INFO  [org.alfresco.repo.admin.patch.PatchExecuter] CopiedFromAspectPatch: Commencing batch of 120741 entries
18:04:59,088 WARN  [org.alfresco.repo.cache.TransactionalCache.org.alfresco.cache.contentDataTransactionalCache] Transactional update cache 'org.alfresco.cache.contentDataTransactionalCache' is full (1000).
18:06:14,663 WARN  [org.alfresco.repo.cache.TransactionalCache.org.alfresco.cache.node.nodesTransactionalCache] Transactional update cache 'org.alfresco.cache.node.nodesTransactionalCache' is full (125000).
18:07:16,589 INFO  [org.alfresco.repo.admin.patch.PatchExecuter] CopiedFromAspectPatch: Processed 1000 entries out of 120741. 1% complete. Rate: 5 per second. 0 failures detected.
18:08:09,425 INFO  [org.alfresco.repo.admin.patch.PatchExecuter] CopiedFromAspectPatch: Processed 2000 entries out of 120741. 2% complete. Rate: 8 per second. 0 failures detected.
18:08:59,519 INFO  [org.alfresco.repo.admin.patch.PatchExecuter] CopiedFromAspectPatch: Processed 3000 entries out of 120741. 2% complete. Rate: 10 per second. 0 failures detected.
18:09:55,638 INFO  [org.alfresco.repo.admin.patch.PatchExecuter] CopiedFromAspectPatch: Processed 4000 entries out of 120741. 3% complete. Rate: 11 per second. 0 failures detected.
18:10:48,802 INFO  [org.alfresco.repo.admin.patch.PatchExecuter] CopiedFromAspectPatch: Processed 5000 entries out of 120741. 4% complete. Rate: 12 per second. 0 failures detected.
18:11:41,988 INFO  [org.alfresco.repo.admin.patch.PatchExecuter] CopiedFromAspectPatch: Processed 6000 entries out of 120741. 5% complete. Rate: 13 per second. 0 failures detected.
18:12:36,278 INFO  [org.alfresco.repo.admin.patch.PatchExecuter] CopiedFromAspectPatch: Processed 7000 entries out of 120741. 6% complete. Rate: 13 per second. 0 failures detected.
18:13:27,765 WARN  [org.alfresco.repo.admin.patch.PatchExecuter] CopiedFromAspectPatch: Failed to process entry "(694499, workspace://version2Store/830adc50-3c72-41dc-8e46-e03b45b67740)".
org.alfresco.service.cmr.repository.AssociationExistsException
   at org.alfresco.repo.domain.node.AbstractNodeDAOImpl.newNodeAssoc(AbstractNodeDAOImpl.java:2528)
   at org.alfresco.repo.node.db.DbNodeServiceImpl.createAssociation(DbNodeServiceImpl.java:1904)
   at sun.reflect.GeneratedMethodAccessor604.invoke(Unknown Source)
   at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
   at java.lang.reflect.Method.invoke(Method.java:597)
   at org.springframework.aop.support.AopUtils.invokeJoinpointUsingReflection(AopUtils.java:309)
   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:104)
   at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172)
   at org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDynamicAopProxy.java:202)
   at $Proxy8.createAssociation(Unknown Source)
   at sun.reflect.GeneratedMethodAccessor604.invoke(Unknown Source)
   at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
   at java.lang.reflect.Method.invoke(Method.java:597)
   at org.alfresco.repo.service.StoreRedirectorProxyFactory$RedirectorInvocationHandler.invoke(StoreRedirectorProxyFactory.java:215)
   at $Proxy9.createAssociation(Unknown Source)
   at sun.reflect.GeneratedMethodAccessor604.invoke(Unknown Source)
   at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
   at java.lang.reflect.Method.invoke(Method.java:597)
   at org.springframework.aop.support.AopUtils.invokeJoinpointUsingReflection(AopUtils.java:309)
   at org.springframework.aop.framework.ReflectiveMethodInvocation.invokeJoinpoint(ReflectiveMethodInvocation.java:183)
   at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:150)
   at org.alfresco.repo.tagging.TagScopePropertyMethodInterceptor.invoke(TagScopePropertyMethodInterceptor.java:129)
   at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172)
   at org.alfresco.repo.node.MLPropertyInterceptor.invoke(MLPropertyInterceptor.java:304)
   at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172)
   at org.alfresco.repo.tagging.TagScopePropertyMethodInterceptor.invoke(TagScopePropertyMethodInterceptor.java:129)
   at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172)
   at org.alfresco.repo.tagging.TagScopePropertyMethodInterceptor.invoke(TagScopePropertyMethodInterceptor.java:129)
   at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172)
   at org.alfresco.repo.node.MLPropertyInterceptor.invoke(MLPropertyInterceptor.java:304)
   at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172)
   at org.alfresco.repo.node.NodeRefPropertyMethodInterceptor.invoke(NodeRefPropertyMethodInterceptor.java:269)
   at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172)
   at org.alfresco.repo.node.MLPropertyInterceptor.invoke(MLPropertyInterceptor.java:304)
   at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172)
   at org.alfresco.repo.node.NodeRefPropertyMethodInterceptor.invoke(NodeRefPropertyMethodInterceptor.java:269)
   at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172)
   at org.alfresco.repo.node.NodeRefPropertyMethodInterceptor.invoke(NodeRefPropertyMethodInterceptor.java:269)
   at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172)
   at org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDynamicAopProxy.java:202)
   at $Proxy8.createAssociation(Unknown Source)
   at org.alfresco.repo.admin.patch.impl.CopiedFromAspectPatch.process(CopiedFromAspectPatch.java:454)
   at org.alfresco.repo.admin.patch.impl.CopiedFromAspectPatch.access$300(CopiedFromAspectPatch.java:85)
   at org.alfresco.repo.admin.patch.impl.CopiedFromAspectPatch$1.process(CopiedFromAspectPatch.java:270)
   at org.alfresco.repo.admin.patch.impl.CopiedFromAspectPatch$1.process(CopiedFromAspectPatch.java:254)
   at org.alfresco.repo.batch.BatchProcessor$TxnCallback.execute(BatchProcessor.java:712)
   at org.alfresco.repo.transaction.RetryingTransactionHelper.doInTransaction(RetryingTransactionHelper.java:388)
   at org.alfresco.repo.batch.BatchProcessor$TxnCallback.run(BatchProcessor.java:756)
   at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:886)
   at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:908)
   at java.lang.Thread.run(Thread.java:662)
18:13:30,213 INFO  [org.alfresco.repo.admin.patch.PatchExecuter] CopiedFromAspectPatch: Processed 8000 entries out of 120741. 7% complete. Rate: 14 per second. 0 failures detected.
18:14:21,718 WARN  [org.alfresco.repo.admin.patch.PatchExecuter] CopiedFromAspectPatch: Failed to process entry "(731806, workspace://version2Store/8193797d-03e0-482f-ac9f-9258a1e92181)".
org.alfresco.service.cmr.repository.AssociationExistsException
   at org.alfresco.repo.domain.node.AbstractNodeDAOImpl.newNodeAssoc(AbstractNodeDAOImpl.java:2528)
   at org.alfresco.repo.node.db.DbNodeServiceImpl.createAssociation(DbNodeServiceImpl.java:1904)
   at sun.reflect.GeneratedMethodAccessor604.invoke(Unknown Source)
   at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
   at java.lang.reflect.Method.invoke(Method.java:597)
   at org.springframework.aop.support.AopUtils.invokeJoinpointUsingReflection(AopUtils.java:309)
   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:104)
   at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172)
   at org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDynamicAopProxy.java:202)
   at $Proxy8.createAssociation(Unknown Source)
   at sun.reflect.GeneratedMethodAccessor604.invoke(Unknown Source)
   at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
   at java.lang.reflect.Method.invoke(Method.java:597)
   at org.alfresco.repo.service.StoreRedirectorProxyFactory$RedirectorInvocationHandler.invoke(StoreRedirectorProxyFactory.java:215)
   at $Proxy9.createAssociation(Unknown Source)
   at sun.reflect.GeneratedMethodAccessor604.invoke(Unknown Source)
   at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
   at java.lang.reflect.Method.invoke(Method.java:597)
   at org.springframework.aop.support.AopUtils.invokeJoinpointUsingReflection(AopUtils.java:309)
   at org.springframework.aop.framework.ReflectiveMethodInvocation.invokeJoinpoint(ReflectiveMethodInvocation.java:183)
   at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:150)
   at org.alfresco.repo.tagging.TagScopePropertyMethodInterceptor.invoke(TagScopePropertyMethodInterceptor.java:129)
   at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172)
   at org.alfresco.repo.node.MLPropertyInterceptor.invoke(MLPropertyInterceptor.java:304)
   at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172)
   at org.alfresco.repo.tagging.TagScopePropertyMethodInterceptor.invoke(TagScopePropertyMethodInterceptor.java:129)
   at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172)
   at org.alfresco.repo.tagging.TagScopePropertyMethodInterceptor.invoke(TagScopePropertyMethodInterceptor.java:129)
   at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172)
   at org.alfresco.repo.node.MLPropertyInterceptor.invoke(MLPropertyInterceptor.java:304)
   at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172)
   at org.alfresco.repo.node.NodeRefPropertyMethodInterceptor.invoke(NodeRefPropertyMethodInterceptor.java:269)
   at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172)
   at org.alfresco.repo.node.MLPropertyInterceptor.invoke(MLPropertyInterceptor.java:304)
   at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172)
   at org.alfresco.repo.node.NodeRefPropertyMethodInterceptor.invoke(NodeRefPropertyMethodInterceptor.java:269)
   at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172)
   at org.alfresco.repo.node.NodeRefPropertyMethodInterceptor.invoke(NodeRefPropertyMethodInterceptor.java:269)
   at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172)
   at org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDynamicAopProxy.java:202)
   at $Proxy8.createAssociation(Unknown Source)
   at org.alfresco.repo.admin.patch.impl.CopiedFromAspectPatch.process(CopiedFromAspectPatch.java:454)
   at org.alfresco.repo.admin.patch.impl.CopiedFromAspectPatch.access$300(CopiedFromAspectPatch.java:85)
   at org.alfresco.repo.admin.patch.impl.CopiedFromAspectPatch$1.process(CopiedFromAspectPatch.java:270)
   at org.alfresco.repo.admin.patch.impl.CopiedFromAspectPatch$1.process(CopiedFromAspectPatch.java:254)
   at org.alfresco.repo.batch.BatchProcessor$TxnCallback.execute(BatchProcessor.java:712)
   at org.alfresco.repo.transaction.RetryingTransactionHelper.doInTransaction(RetryingTransactionHelper.java:388)
   at org.alfresco.repo.batch.BatchProcessor$TxnCallback.run(BatchProcessor.java:756)
   at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:886)
   at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:908)
   at java.lang.Thread.run(Thread.java:662)
18:14:24,929 INFO  [org.alfresco.repo.admin.patch.PatchExecuter] CopiedFromAspectPatch: Processed 9000 entries out of 120741. 7% complete. Rate: 14 per second. 1 failures detected.
18:15:19,772 INFO  [org.alfresco.repo.admin.patch.PatchExecuter] CopiedFromAspectPatch: Processed 10000 entries out of 120741. 8% complete. Rate: 14 per second. 2 failures detected.
18:16:13,962 INFO  [org.alfresco.repo.admin.patch.PatchExecuter] CopiedFromAspectPatch: Processed 11000 entries out of 120741. 9% complete. Rate: 15 per second. 2 failures detected.
18:17:07,696 INFO  [org.alfresco.repo.admin.patch.PatchExecuter] CopiedFromAspectPatch: Processed 12000 entries out of 120741. 10% complete. Rate: 15 per second. 2 failures detected.
18:18:02,004 INFO  [org.alfresco.repo.admin.patch.PatchExecuter] CopiedFromAspectPatch: Processed 13000 entries out of 120741. 11% complete. Rate: 15 per second. 2 failures detected.
18:18:56,826 INFO  [org.alfresco.repo.admin.patch.PatchExecuter] CopiedFromAspectPatch: Processed 14000 entries out of 120741. 12% complete. Rate: 15 per second. 2 failures detected.
18:19:53,391 INFO  [org.alfresco.repo.admin.patch.PatchExecuter] CopiedFromAspectPatch: Processed 15000 entries out of 120741. 12% complete. Rate: 15 per second. 2 failures detected.
18:20:49,791 INFO  [org.alfresco.repo.admin.patch.PatchExecuter] CopiedFromAspectPatch: Processed 16000 entries out of 120741. 13% complete. Rate: 15 per second. 2 failures detected.
18:21:44,718 INFO  [org.alfresco.repo.admin.patch.PatchExecuter] CopiedFromAspectPatch: Processed 17000 entries out of 120741. 14% complete. Rate: 15 per second. 2 failures detected.
18:22:40,652 INFO  [org.alfresco.repo.admin.patch.PatchExecuter] CopiedFromAspectPatch: Processed 18000 entries out of 120741. 15% complete. Rate: 16 per second. 2 failures detected.
18:23:35,292 INFO  [org.alfresco.repo.admin.patch.PatchExecuter] CopiedFromAspectPatch: Processed 19000 entries out of 120741. 16% complete. Rate: 16 per second. 2 failures detected.
18:24:31,039 INFO  [org.alfresco.repo.admin.patch.PatchExecuter] CopiedFromAspectPatch: Processed 20000 entries out of 120741. 17% complete. Rate: 16 per second. 2 failures detected.
18:25:30,203 INFO  [org.alfresco.repo.admin.patch.PatchExecuter] CopiedFromAspectPatch: Processed 21000 entries out of 120741. 17% complete. Rate: 16 per second. 2 failures detected.
18:26:27,249 INFO  [org.alfresco.repo.admin.patch.PatchExecuter] CopiedFromAspectPatch: Processed 22000 entries out of 120741. 18% complete. Rate: 16 per second. 2 failures detected.
18:27:27,960 INFO  [org.alfresco.repo.admin.patch.PatchExecuter] CopiedFromAspectPatch: Processed 23000 entries out of 120741. 19% complete. Rate: 16 per second. 2 failures detected.
18:28:26,519 INFO  [org.alfresco.repo.admin.patch.PatchExecuter] CopiedFromAspectPatch: Processed 24000 entries out of 120741. 20% complete. Rate: 16 per second. 2 failures detected.
18:29:26,583 INFO  [org.alfresco.repo.admin.patch.PatchExecuter] CopiedFromAspectPatch: Processed 25000 entries out of 120741. 21% complete. Rate: 16 per second. 2 failures detected.
18:30:26,381 INFO  [org.alfresco.repo.admin.patch.PatchExecuter] CopiedFromAspectPatch: Processed 26000 entries out of 120741. 22% complete. Rate: 16 per second. 2 failures detected.
18:31:25,563 INFO  [org.alfresco.repo.admin.patch.PatchExecuter] CopiedFromAspectPatch: Processed 27000 entries out of 120741. 22% complete. Rate: 16 per second. 2 failures detected.
18:32:24,102 INFO  [org.alfresco.repo.admin.patch.PatchExecuter] CopiedFromAspectPatch: Processed 28000 entries out of 120741. 23% complete. Rate: 16 per second. 2 failures detected.
18:33:22,085 INFO  [org.alfresco.repo.admin.patch.PatchExecuter] CopiedFromAspectPatch: Processed 29000 entries out of 120741. 24% complete. Rate: 16 per second. 2 failures detected.
18:34:22,010 INFO  [org.alfresco.repo.admin.patch.PatchExecuter] CopiedFromAspectPatch: Processed 30000 entries out of 120741. 25% complete. Rate: 16 per second. 2 failures detected.
18:35:22,232 INFO  [org.alfresco.repo.admin.patch.PatchExecuter] CopiedFromAspectPatch: Processed 31000 entries out of 120741. 26% complete. Rate: 16 per second. 2 failures detected.
18:36:22,351 INFO  [org.alfresco.repo.admin.patch.PatchExecuter] CopiedFromAspectPatch: Processed 32000 entries out of 120741. 27% complete. Rate: 16 per second. 2 failures detected.
18:37:24,563 INFO  [org.alfresco.repo.admin.patch.PatchExecuter] CopiedFromAspectPatch: Processed 33000 entries out of 120741. 27% complete. Rate: 16 per second. 2 failures detected.
18:38:22,557 INFO  [org.alfresco.repo.admin.patch.PatchExecuter] CopiedFromAspectPatch: Processed 34000 entries out of 120741. 28% complete. Rate: 16 per second. 2 failures detected.
18:39:26,641 INFO  [org.alfresco.repo.admin.patch.PatchExecuter] CopiedFromAspectPatch: Processed 35000 entries out of 120741. 29% complete. Rate: 16 per second. 2 failures detected.
18:40:28,004 INFO  [org.alfresco.repo.admin.patch.PatchExecuter] CopiedFromAspectPatch: Processed 36000 entries out of 120741. 30% complete. Rate: 16 per second. 2 failures detected.
18:41:30,255 INFO  [org.alfresco.repo.admin.patch.PatchExecuter] CopiedFromAspectPatch: Processed 37000 entries out of 120741. 31% complete. Rate: 16 per second. 2 failures detected.
18:42:31,224 INFO  [org.alfresco.repo.admin.patch.PatchExecuter] CopiedFromAspectPatch: Processed 38000 entries out of 120741. 31% complete. Rate: 16 per second. 2 failures detected.
18:43:28,485 INFO  [org.alfresco.repo.admin.patch.PatchExecuter] CopiedFromAspectPatch: Processed 39000 entries out of 120741. 32% complete. Rate: 16 per second. 2 failures detected.
18:44:28,327 INFO  [org.alfresco.repo.admin.patch.PatchExecuter] CopiedFromAspectPatch: Processed 40000 entries out of 120741. 33% complete. Rate: 16 per second. 2 failures detected.
18:45:27,736 INFO  [org.alfresco.repo.admin.patch.PatchExecuter] CopiedFromAspectPatch: Processed 41000 entries out of 120741. 34% complete. Rate: 16 per second. 2 failures detected.
18:46:26,160 INFO  [org.alfresco.repo.admin.patch.PatchExecuter] CopiedFromAspectPatch: Processed 42000 entries out of 120741. 35% complete. Rate: 16 per second. 2 failures detected.
18:47:26,214 INFO  [org.alfresco.repo.admin.patch.PatchExecuter] CopiedFromAspectPatch: Processed 43000 entries out of 120741. 36% complete. Rate: 16 per second. 2 failures detected.
18:48:24,297 INFO  [org.alfresco.repo.admin.patch.PatchExecuter] CopiedFromAspectPatch: Processed 44000 entries out of 120741. 36% complete. Rate: 16 per second. 2 failures detected.
18:49:21,654 INFO  [org.alfresco.repo.admin.patch.PatchExecuter] CopiedFromAspectPatch: Processed 45000 entries out of 120741. 37% complete. Rate: 16 per second. 2 failures detected.
18:50:18,381 INFO  [org.alfresco.repo.admin.patch.PatchExecuter] CopiedFromAspectPatch: Processed 46000 entries out of 120741. 38% complete. Rate: 16 per second. 2 failures detected.
18:51:17,243 INFO  [org.alfresco.repo.admin.patch.PatchExecuter] CopiedFromAspectPatch: Processed 47000 entries out of 120741. 39% complete. Rate: 16 per second. 2 failures detected.
18:52:16,581 INFO  [org.alfresco.repo.admin.patch.PatchExecuter] CopiedFromAspectPatch: Processed 48000 entries out of 120741. 40% complete. Rate: 16 per second. 2 failures detected.
18:53:17,138 INFO  [org.alfresco.repo.admin.patch.PatchExecuter] CopiedFromAspectPatch: Processed 49000 entries out of 120741. 41% complete. Rate: 16 per second. 2 failures detected.
18:54:18,303 INFO  [org.alfresco.repo.admin.patch.PatchExecuter] CopiedFromAspectPatch: Processed 50000 entries out of 120741. 41% complete. Rate: 16 per second. 2 failures detected.
18:55:19,510 INFO  [org.alfresco.repo.admin.patch.PatchExecuter] CopiedFromAspectPatch: Processed 51000 entries out of 120741. 42% complete. Rate: 16 per second. 2 failures detected.
18:56:22,236 INFO  [org.alfresco.repo.admin.patch.PatchExecuter] CopiedFromAspectPatch: Processed 52000 entries out of 120741. 43% complete. Rate: 16 per second. 2 failures detected.
18:57:24,811 INFO  [org.alfresco.repo.admin.patch.PatchExecuter] CopiedFromAspectPatch: Processed 53000 entries out of 120741. 44% complete. Rate: 16 per second. 2 failures detected.
18:58:28,425 INFO  [org.alfresco.repo.admin.patch.PatchExecuter] CopiedFromAspectPatch: Processed 54000 entries out of 120741. 45% complete. Rate: 16 per second. 2 failures detected.
18:59:29,418 INFO  [org.alfresco.repo.admin.patch.PatchExecuter] CopiedFromAspectPatch: Processed 55000 entries out of 120741. 46% complete. Rate: 16 per second. 2 failures detected.
19:02:22,474 INFO  [org.alfresco.repo.admin.patch.PatchExecuter] CopiedFromAspectPatch: Processed 56000 entries out of 120741. 46% complete. Rate: 15 per second. 2 failures detected.
19:03:35,789 INFO  [org.alfresco.repo.admin.patch.PatchExecuter] CopiedFromAspectPatch: Processed 57000 entries out of 120741. 47% complete. Rate: 15 per second. 2 failures detected.
19:04:42,937 INFO  [org.alfresco.repo.admin.patch.PatchExecuter] CopiedFromAspectPatch: Processed 58000 entries out of 120741. 48% complete. Rate: 15 per second. 2 failures detected.
19:05:50,452 INFO  [org.alfresco.repo.admin.patch.PatchExecuter] CopiedFromAspectPatch: Processed 59000 entries out of 120741. 49% complete. Rate: 15 per second. 2 failures detected.
19:06:57,642 INFO  [org.alfresco.repo.admin.patch.PatchExecuter] CopiedFromAspectPatch: Processed 60000 entries out of 120741. 50% complete. Rate: 15 per second. 2 failures detected.
19:08:02,266 INFO  [org.alfresco.repo.admin.patch.PatchExecuter] CopiedFromAspectPatch: Processed 61000 entries out of 120741. 51% complete. Rate: 15 per second. 2 failures detected.
19:09:07,235 INFO  [org.alfresco.repo.admin.patch.PatchExecuter] CopiedFromAspectPatch: Processed 62000 entries out of 120741. 51% complete. Rate: 15 per second. 2 failures detected.
19:10:15,950 INFO  [org.alfresco.repo.admin.patch.PatchExecuter] CopiedFromAspectPatch: Processed 63000 entries out of 120741. 52% complete. Rate: 15 per second. 2 failures detected.
19:11:19,516 INFO  [org.alfresco.repo.admin.patch.PatchExecuter] CopiedFromAspectPatch: Processed 64000 entries out of 120741. 53% complete. Rate: 15 per second. 2 failures detected.
19:12:26,491 INFO  [org.alfresco.repo.admin.patch.PatchExecuter] CopiedFromAspectPatch: Processed 65000 entries out of 120741. 54% complete. Rate: 15 per second. 2 failures detected.
19:13:31,950 INFO  [org.alfresco.repo.admin.patch.PatchExecuter] CopiedFromAspectPatch: Processed 66000 entries out of 120741. 55% complete. Rate: 15 per second. 2 failures detected.
19:14:39,502 INFO  [org.alfresco.repo.admin.patch.PatchExecuter] CopiedFromAspectPatch: Processed 67000 entries out of 120741. 55% complete. Rate: 15 per second. 2 failures detected.
19:15:48,229 INFO  [org.alfresco.repo.admin.patch.PatchExecuter] CopiedFromAspectPatch: Processed 68000 entries out of 120741. 56% complete. Rate: 15 per second. 2 failures detected.
19:16:54,331 INFO  [org.alfresco.repo.admin.patch.PatchExecuter] CopiedFromAspectPatch: Processed 69000 entries out of 120741. 57% complete. Rate: 15 per second. 2 failures detected.
19:18:00,950 INFO  [org.alfresco.repo.admin.patch.PatchExecuter] CopiedFromAspectPatch: Processed 70000 entries out of 120741. 58% complete. Rate: 15 per second. 2 failures detected.
19:19:07,272 INFO  [org.alfresco.repo.admin.patch.PatchExecuter] CopiedFromAspectPatch: Processed 71000 entries out of 120741. 59% complete. Rate: 15 per second. 2 failures detected.
19:20:15,973 INFO  [org.alfresco.repo.admin.patch.PatchExecuter] CopiedFromAspectPatch: Processed 72000 entries out of 120741. 60% complete. Rate: 15 per second. 2 failures detected.
19:21:26,838 INFO  [org.alfresco.repo.admin.patch.PatchExecuter] CopiedFromAspectPatch: Processed 73000 entries out of 120741. 60% complete. Rate: 15 per second. 2 failures detected.
19:22:35,205 INFO  [org.alfresco.repo.admin.patch.PatchExecuter] CopiedFromAspectPatch: Processed 74000 entries out of 120741. 61% complete. Rate: 15 per second. 2 failures detected.
19:23:42,775 INFO  [org.alfresco.repo.admin.patch.PatchExecuter] CopiedFromAspectPatch: Processed 75000 entries out of 120741. 62% complete. Rate: 15 per second. 2 failures detected.
19:24:50,269 INFO  [org.alfresco.repo.admin.patch.PatchExecuter] CopiedFromAspectPatch: Processed 76000 entries out of 120741. 63% complete. Rate: 15 per second. 2 failures detected.
19:25:56,251 INFO  [org.alfresco.repo.admin.patch.PatchExecuter] CopiedFromAspectPatch: Processed 77000 entries out of 120741. 64% complete. Rate: 15 per second. 2 failures detected.
19:27:02,497 INFO  [org.alfresco.repo.admin.patch.PatchExecuter] CopiedFromAspectPatch: Processed 78000 entries out of 120741. 65% complete. Rate: 15 per second. 2 failures detected.
19:28:11,017 INFO  [org.alfresco.repo.admin.patch.PatchExecuter] CopiedFromAspectPatch: Processed 79000 entries out of 120741. 65% complete. Rate: 15 per second. 2 failures detected.
19:29:20,465 INFO  [org.alfresco.repo.admin.patch.PatchExecuter] CopiedFromAspectPatch: Processed 80000 entries out of 120741. 66% complete. Rate: 15 per second. 2 failures detected.
19:30:25,651 INFO  [org.alfresco.repo.admin.patch.PatchExecuter] CopiedFromAspectPatch: Processed 81000 entries out of 120741. 67% complete. Rate: 15 per second. 2 failures detected.
19:31:31,205 INFO  [org.alfresco.repo.admin.patch.PatchExecuter] CopiedFromAspectPatch: Processed 82000 entries out of 120741. 68% complete. Rate: 15 per second. 2 failures detected.
19:32:37,884 INFO  [org.alfresco.repo.admin.patch.PatchExecuter] CopiedFromAspectPatch: Processed 83000 entries out of 120741. 69% complete. Rate: 15 per second. 2 failures detected.
19:33:47,040 INFO  [org.alfresco.repo.admin.patch.PatchExecuter] CopiedFromAspectPatch: Processed 84000 entries out of 120741. 70% complete. Rate: 15 per second. 2 failures detected.
19:34:56,755 INFO  [org.alfresco.repo.admin.patch.PatchExecuter] CopiedFromAspectPatch: Processed 85000 entries out of 120741. 70% complete. Rate: 15 per second. 2 failures detected.
19:36:02,786 INFO  [org.alfresco.repo.admin.patch.PatchExecuter] CopiedFromAspectPatch: Processed 86000 entries out of 120741. 71% complete. Rate: 15 per second. 2 failures detected.
19:37:15,716 INFO  [org.alfresco.repo.admin.patch.PatchExecuter] CopiedFromAspectPatch: Processed 87000 entries out of 120741. 72% complete. Rate: 15 per second. 2 failures detected.
19:38:26,616 INFO  [org.alfresco.repo.admin.patch.PatchExecuter] CopiedFromAspectPatch: Processed 88000 entries out of 120741. 73% complete. Rate: 15 per second. 2 failures detected.
19:39:38,984 INFO  [org.alfresco.repo.admin.patch.PatchExecuter] CopiedFromAspectPatch: Processed 89000 entries out of 120741. 74% complete. Rate: 15 per second. 2 failures detected.
19:40:46,161 INFO  [org.alfresco.repo.admin.patch.PatchExecuter] CopiedFromAspectPatch: Processed 90000 entries out of 120741. 75% complete. Rate: 15 per second. 2 failures detected.
19:41:49,688 INFO  [org.alfresco.repo.admin.patch.PatchExecuter] CopiedFromAspectPatch: Processed 91000 entries out of 120741. 75% complete. Rate: 15 per second. 2 failures detected.
19:42:58,287 INFO  [org.alfresco.repo.admin.patch.PatchExecuter] CopiedFromAspectPatch: Processed 92000 entries out of 120741. 76% complete. Rate: 15 per second. 2 failures detected.
19:44:05,312 INFO  [org.alfresco.repo.admin.patch.PatchExecuter] CopiedFromAspectPatch: Processed 93000 entries out of 120741. 77% complete. Rate: 15 per second. 2 failures detected.
19:45:11,745 INFO  [org.alfresco.repo.admin.patch.PatchExecuter] CopiedFromAspectPatch: Processed 94000 entries out of 120741. 78% complete. Rate: 15 per second. 2 failures detected.
19:46:17,576 INFO  [org.alfresco.repo.admin.patch.PatchExecuter] CopiedFromAspectPatch: Processed 95000 entries out of 120741. 79% complete. Rate: 15 per second. 2 failures detected.
19:47:24,169 INFO  [org.alfresco.repo.admin.patch.PatchExecuter] CopiedFromAspectPatch: Processed 96000 entries out of 120741. 80% complete. Rate: 15 per second. 2 failures detected.
19:48:31,297 INFO  [org.alfresco.repo.admin.patch.PatchExecuter] CopiedFromAspectPatch: Processed 97000 entries out of 120741. 80% complete. Rate: 15 per second. 2 failures detected.
19:49:39,084 INFO  [org.alfresco.repo.admin.patch.PatchExecuter] CopiedFromAspectPatch: Processed 98000 entries out of 120741. 81% complete. Rate: 15 per second. 2 failures detected.
19:50:48,678 INFO  [org.alfresco.repo.admin.patch.PatchExecuter] CopiedFromAspectPatch: Processed 99000 entries out of 120741. 82% complete. Rate: 15 per second. 2 failures detected.
19:51:55,603 INFO  [org.alfresco.repo.admin.patch.PatchExecuter] CopiedFromAspectPatch: Processed 100000 entries out of 120741. 83% complete. Rate: 15 per second. 2 failures detected.
19:53:05,150 INFO  [org.alfresco.repo.admin.patch.PatchExecuter] CopiedFromAspectPatch: Processed 101000 entries out of 120741. 84% complete. Rate: 15 per second. 2 failures detected.
19:54:14,300 INFO  [org.alfresco.repo.admin.patch.PatchExecuter] CopiedFromAspectPatch: Processed 102000 entries out of 120741. 84% complete. Rate: 15 per second. 2 failures detected.
19:55:19,226 INFO  [org.alfresco.repo.admin.patch.PatchExecuter] CopiedFromAspectPatch: Processed 103000 entries out of 120741. 85% complete. Rate: 15 per second. 2 failures detected.
19:56:28,165 INFO  [org.alfresco.repo.admin.patch.PatchExecuter] CopiedFromAspectPatch: Processed 104000 entries out of 120741. 86% complete. Rate: 15 per second. 2 failures detected.
19:57:36,844 INFO  [org.alfresco.repo.admin.patch.PatchExecuter] CopiedFromAspectPatch: Processed 105000 entries out of 120741. 87% complete. Rate: 15 per second. 2 failures detected.
19:58:46,149 INFO  [org.alfresco.repo.admin.patch.PatchExecuter] CopiedFromAspectPatch: Processed 106000 entries out of 120741. 88% complete. Rate: 15 per second. 2 failures detected.
19:59:56,001 INFO  [org.alfresco.repo.admin.patch.PatchExecuter] CopiedFromAspectPatch: Processed 107000 entries out of 120741. 89% complete. Rate: 15 per second. 2 failures detected.
20:01:06,796 INFO  [org.alfresco.repo.admin.patch.PatchExecuter] CopiedFromAspectPatch: Processed 108000 entries out of 120741. 89% complete. Rate: 15 per second. 2 failures detected.
20:03:38,807 INFO  [org.alfresco.repo.admin.patch.PatchExecuter] CopiedFromAspectPatch: Processed 109000 entries out of 120741. 90% complete. Rate: 15 per second. 2 failures detected.
20:04:54,780 INFO  [org.alfresco.repo.admin.patch.PatchExecuter] CopiedFromAspectPatch: Processed 110000 entries out of 120741. 91% complete. Rate: 15 per second. 2 failures detected.
20:06:10,003 INFO  [org.alfresco.repo.admin.patch.PatchExecuter] CopiedFromAspectPatch: Processed 111000 entries out of 120741. 92% complete. Rate: 15 per second. 2 failures detected.
20:07:22,349 INFO  [org.alfresco.repo.admin.patch.PatchExecuter] CopiedFromAspectPatch: Processed 112000 entries out of 120741. 93% complete. Rate: 15 per second. 2 failures detected.
20:08:34,110 INFO  [org.alfresco.repo.admin.patch.PatchExecuter] CopiedFromAspectPatch: Processed 113000 entries out of 120741. 94% complete. Rate: 15 per second. 2 failures detected.
20:09:46,589 INFO  [org.alfresco.repo.admin.patch.PatchExecuter] CopiedFromAspectPatch: Processed 114000 entries out of 120741. 94% complete. Rate: 15 per second. 2 failures detected.
20:11:02,948 INFO  [org.alfresco.repo.admin.patch.PatchExecuter] CopiedFromAspectPatch: Processed 115000 entries out of 120741. 95% complete. Rate: 15 per second. 2 failures detected.
20:12:14,954 INFO  [org.alfresco.repo.admin.patch.PatchExecuter] CopiedFromAspectPatch: Processed 116000 entries out of 120741. 96% complete. Rate: 15 per second. 2 failures detected.
20:13:28,917 INFO  [org.alfresco.repo.admin.patch.PatchExecuter] CopiedFromAspectPatch: Processed 117000 entries out of 120741. 97% complete. Rate: 15 per second. 2 failures detected.
20:14:41,071 INFO  [org.alfresco.repo.admin.patch.PatchExecuter] CopiedFromAspectPatch: Processed 118000 entries out of 120741. 98% complete. Rate: 15 per second. 2 failures detected.
20:15:53,353 INFO  [org.alfresco.repo.admin.patch.PatchExecuter] CopiedFromAspectPatch: Processed 119000 entries out of 120741. 99% complete. Rate: 15 per second. 2 failures detected.
20:17:49,177 INFO  [org.alfresco.repo.admin.patch.PatchExecuter] CopiedFromAspectPatch: Processed 120000 entries out of 120741. 99% complete. Rate: 14 per second. 2 failures detected.
20:17:49,304 INFO  [org.alfresco.repo.admin.patch.PatchExecuter] CopiedFromAspectPatch: Processed 120704 entries out of 120741. 100% complete. Rate: 15 per second. 2 failures detected.
20:17:49,304 INFO  [org.alfresco.repo.admin.patch.PatchExecuter] CopiedFromAspectPatch: Completed batch of 120741 entries
20:17:49,350 ERROR [org.alfresco.repo.admin.patch.PatchExecuter] CopiedFromAspectPatch: 2 error(s) detected. Last error from entry "(731806, workspace://version2Store/8193797d-03e0-482f-ac9f-9258a1e92181)"
org.alfresco.service.cmr.repository.AssociationExistsException
   at org.alfresco.repo.domain.node.AbstractNodeDAOImpl.newNodeAssoc(AbstractNodeDAOImpl.java:2528)
   at org.alfresco.repo.node.db.DbNodeServiceImpl.createAssociation(DbNodeServiceImpl.java:1904)
   at sun.reflect.GeneratedMethodAccessor604.invoke(Unknown Source)
   at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
   at java.lang.reflect.Method.invoke(Method.java:597)
   at org.springframework.aop.support.AopUtils.invokeJoinpointUsingReflection(AopUtils.java:309)
   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:104)
   at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172)
   at org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDynamicAopProxy.java:202)
   at $Proxy8.createAssociation(Unknown Source)
   at sun.reflect.GeneratedMethodAccessor604.invoke(Unknown Source)
   at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
   at java.lang.reflect.Method.invoke(Method.java:597)
   at org.alfresco.repo.service.StoreRedirectorProxyFactory$RedirectorInvocationHandler.invoke(StoreRedirectorProxyFactory.java:215)
   at $Proxy9.createAssociation(Unknown Source)
   at sun.reflect.GeneratedMethodAccessor604.invoke(Unknown Source)
   at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
   at java.lang.reflect.Method.invoke(Method.java:597)
   at org.springframework.aop.support.AopUtils.invokeJoinpointUsingReflection(AopUtils.java:309)
   at org.springframework.aop.framework.ReflectiveMethodInvocation.invokeJoinpoint(ReflectiveMethodInvocation.java:183)
   at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:150)
   at org.alfresco.repo.tagging.TagScopePropertyMethodInterceptor.invoke(TagScopePropertyMethodInterceptor.java:129)
   at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172)
   at org.alfresco.repo.node.MLPropertyInterceptor.invoke(MLPropertyInterceptor.java:304)
   at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172)
   at org.alfresco.repo.tagging.TagScopePropertyMethodInterceptor.invoke(TagScopePropertyMethodInterceptor.java:129)
   at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172)
   at org.alfresco.repo.tagging.TagScopePropertyMethodInterceptor.invoke(TagScopePropertyMethodInterceptor.java:129)
   at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172)
   at org.alfresco.repo.node.MLPropertyInterceptor.invoke(MLPropertyInterceptor.java:304)
   at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172)
   at org.alfresco.repo.node.NodeRefPropertyMethodInterceptor.invoke(NodeRefPropertyMethodInterceptor.java:269)
   at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172)
   at org.alfresco.repo.node.MLPropertyInterceptor.invoke(MLPropertyInterceptor.java:304)
   at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172)
   at org.alfresco.repo.node.NodeRefPropertyMethodInterceptor.invoke(NodeRefPropertyMethodInterceptor.java:269)
   at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172)
   at org.alfresco.repo.node.NodeRefPropertyMethodInterceptor.invoke(NodeRefPropertyMethodInterceptor.java:269)
   at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172)
   at org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDynamicAopProxy.java:202)
   at $Proxy8.createAssociation(Unknown Source)
   at org.alfresco.repo.admin.patch.impl.CopiedFromAspectPatch.process(CopiedFromAspectPatch.java:454)
   at org.alfresco.repo.admin.patch.impl.CopiedFromAspectPatch.access$300(CopiedFromAspectPatch.java:85)
   at org.alfresco.repo.admin.patch.impl.CopiedFromAspectPatch$1.process(CopiedFromAspectPatch.java:270)
   at org.alfresco.repo.admin.patch.impl.CopiedFromAspectPatch$1.process(CopiedFromAspectPatch.java:254)
   at org.alfresco.repo.batch.BatchProcessor$TxnCallback.execute(BatchProcessor.java:712)
   at org.alfresco.repo.transaction.RetryingTransactionHelper.doInTransaction(RetryingTransactionHelper.java:388)
   at org.alfresco.repo.batch.BatchProcessor$TxnCallback.run(BatchProcessor.java:756)
   at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:886)
   at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:908)
   at java.lang.Thread.run(Thread.java:662)
20:17:49,698 INFO  [org.alfresco.repo.admin.patch.PatchExecuter]    Applying patch 'patch.publishing.root' (null).
20:17:51,766 WARN  [org.alfresco.repo.admin.patch.PatchExecuter] Patch description is not available: Patch[ id=patch.publishing.root, description=patch.publishing.root.description, fixesFromSchema=0, fixesToSchema=5013, targetSchema=5014]
20:17:51,818 INFO  [org.alfresco.repo.admin.patch.PatchExecuter]    Applying patch 'patch.workflowNotification' (Patch to add workflow email notification email folder and template.).
20:17:53,597 INFO  [org.alfresco.repo.admin.patch.PatchExecuter]    Applying patch 'patch.nodeTemplatesFolder' (Patch to create new Data Dictionary folder for Share - Create Node by Template).
20:17:54,256 INFO  [org.alfresco.repo.admin.patch.PatchExecuter]    Applying patch 'patch.updateMimetypes3' (Fix mimetype for MPEG Audio).
20:17:59,199 INFO  [org.alfresco.repo.admin.patch.PatchExecuter]    Applying patch 'patch.updateWorkflowNotificationTemplates' (Patch to update the workflow notification templates.).
20:18:07,735 INFO  [org.alfresco.repo.admin.patch.PatchExecuter]    Applying patch 'patch.siteLoadPatch.swsdp' (Loads a sample site into the repository.).
20:18:08,822 ERROR [org.alfresco.repo.admin.patch.PatchExecuter] 07070001 java.lang.NullPointerException
   at org.alfresco.repo.domain.permissions.AclDAOImpl.getAcl(AclDAOImpl.java:1044)
   at org.alfresco.repo.domain.permissions.ADMAccessControlListDAO.getAccessControlList(ADMAccessControlListDAO.java:84)
   at org.alfresco.repo.domain.permissions.AbstractPermissionsDaoComponentImpl.getAccessControlListProperties(AbstractPermissionsDaoComponentImpl.java:622)
   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.alfresco.repo.service.StoreRedirectorProxyFactory$RedirectorInvocationHandler.invoke(StoreRedirectorProxyFactory.java:215)
   at $Proxy10.getAccessControlListProperties(Unknown Source)
   at org.alfresco.repo.security.permissions.impl.PermissionServiceImpl.hasPermission(PermissionServiceImpl.java:493)
   at org.alfresco.repo.security.permissions.impl.PermissionServiceImpl.hasPermission(PermissionServiceImpl.java:1006)
   at sun.reflect.GeneratedMethodAccessor568.invoke(Unknown Source)
   at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
   at java.lang.reflect.Method.invoke(Method.java:597)
   at org.springframework.aop.support.AopUtils.invokeJoinpointUsingReflection(AopUtils.java:309)
   at org.springframework.aop.framework.ReflectiveMethodInvocation.invokeJoinpoint(ReflectiveMethodInvocation.java:183)
   at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:150)
   at org.springframework.transaction.interceptor.TransactionInterceptor.invoke(TransactionInterceptor.java:110)
   at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172)
   at org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDynamicAopProxy.java:202)
   at $Proxy24.hasPermission(Unknown Source)
   at org.alfresco.repo.security.permissions.impl.acegi.ACLEntryVoter.vote(ACLEntryVoter.java:437)
   at net.sf.acegisecurity.vote.AffirmativeBased.decide(AffirmativeBased.java:69)
   at net.sf.acegisecurity.intercept.AbstractSecurityInterceptor.beforeInvocation(AbstractSecurityInterceptor.java:394)
   at net.sf.acegisecurity.intercept.method.aopalliance.MethodSecurityInterceptor.invoke(MethodSecurityInterceptor.java:77)
   at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172)
   at org.alfresco.repo.security.permissions.impl.ExceptionTranslatorMethodInterceptor.invoke(ExceptionTranslatorMethodInterceptor.java:44)
   at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172)
   at org.alfresco.repo.audit.AuditMethodInterceptor.invoke(AuditMethodInterceptor.java:146)
   at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172)
   at org.springframework.transaction.interceptor.TransactionInterceptor.invoke(TransactionInterceptor.java:110)
   at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172)
   at org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDynamicAopProxy.java:202)
   at $Proxy8.hasAspect(Unknown Source)
   at org.alfresco.repo.tagging.TaggingServiceImpl.addTagScope(TaggingServiceImpl.java:783)
   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.springframework.aop.support.AopUtils.invokeJoinpointUsingReflection(AopUtils.java:309)
   at org.springframework.aop.framework.ReflectiveMethodInvocation.invokeJoinpoint(ReflectiveMethodInvocation.java:183)
   at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:150)
   at org.alfresco.repo.security.permissions.impl.AlwaysProceedMethodInterceptor.invoke(AlwaysProceedMethodInterceptor.java:34)
   at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172)
   at org.alfresco.repo.security.permissions.impl.ExceptionTranslatorMethodInterceptor.invoke(ExceptionTranslatorMethodInterceptor.java:44)
   at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172)
   at org.alfresco.repo.audit.AuditMethodInterceptor.invoke(AuditMethodInterceptor.java:146)
   at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172)
   at org.springframework.transaction.interceptor.TransactionInterceptor.invoke(TransactionInterceptor.java:110)
   at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172)
   at org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDynamicAopProxy.java:202)
   at $Proxy83.addTagScope(Unknown Source)
   at org.alfresco.repo.site.SiteServiceImpl.createSite(SiteServiceImpl.java:483)
   at org.alfresco.repo.site.SiteServiceImpl.createSite(SiteServiceImpl.java:426)
   at org.alfresco.repo.admin.patch.impl.SiteLoadPatch.applyInternalImpl(SiteLoadPatch.java:208)
   at org.alfresco.repo.admin.patch.impl.SiteLoadPatch.applyInternal(SiteLoadPatch.java:166)
   at org.alfresco.repo.admin.patch.AbstractPatch.applyImpl(AbstractPatch.java:407)
   at org.alfresco.repo.admin.patch.AbstractPatch.access$000(AbstractPatch.java:52)
   at org.alfresco.repo.admin.patch.AbstractPatch$2$1.execute(AbstractPatch.java:466)
   at org.alfresco.repo.admin.patch.AbstractPatch$2$1.execute(AbstractPatch.java:463)
   at org.alfresco.repo.transaction.RetryingTransactionHelper.doInTransaction(RetryingTransactionHelper.java:388)
   at org.alfresco.repo.admin.patch.AbstractPatch$2.doWork(AbstractPatch.java:469)
   at org.alfresco.repo.admin.patch.AbstractPatch$2.doWork(AbstractPatch.java:456)
   at org.alfresco.repo.security.authentication.AuthenticationUtil.runAs(AuthenticationUtil.java:519)
   at org.alfresco.repo.admin.patch.AbstractPatch.apply(AbstractPatch.java:478)
   at org.alfresco.repo.admin.patch.PatchServiceImpl$PatchWork.applyPatch(PatchServiceImpl.java:491)
   at org.alfresco.repo.admin.patch.PatchServiceImpl$PatchWork.execute(PatchServiceImpl.java:403)
   at org.alfresco.repo.admin.patch.PatchServiceImpl.applyPatch(PatchServiceImpl.java:258)
   at org.alfresco.repo.admin.patch.PatchServiceImpl.applyPatchAndDependencies(PatchServiceImpl.java:235)
   at org.alfresco.repo.admin.patch.PatchServiceImpl.applyOutstandingPatches(PatchServiceImpl.java:171)
   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.springframework.aop.support.AopUtils.invokeJoinpointUsingReflection(AopUtils.java:309)
   at org.springframework.aop.framework.ReflectiveMethodInvocation.invokeJoinpoint(ReflectiveMethodInvocation.java:183)
   at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:150)
   at org.springframework.transaction.interceptor.TransactionInterceptor.invoke(TransactionInterceptor.java:110)
   at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172)
   at org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDynamicAopProxy.java:202)
   at $Proxy92.applyOutstandingPatches(Unknown Source)
   at org.alfresco.repo.admin.patch.PatchExecuter.applyOutstandingPatches(PatchExecuter.java:76)
   at org.alfresco.repo.admin.patch.PatchExecuter$1.doWork(PatchExecuter.java:124)
   at org.alfresco.repo.admin.patch.PatchExecuter$1.doWork(PatchExecuter.java:120)
   at org.alfresco.repo.security.authentication.AuthenticationUtil.runAs(AuthenticationUtil.java:519)
   at org.alfresco.repo.admin.patch.PatchExecuter.onBootstrap(PatchExecuter.java:128)
   at org.springframework.extensions.surf.util.AbstractLifecycleBean.onApplicationEvent(AbstractLifecycleBean.java:56)
   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)

20:18:10,366 INFO  [org.alfresco.repo.admin.patch.PatchExecuter] 
=== Applied patch                 === 
ID: patch.db-V3.4-UsageTables
RESULT: 
Script completed
=====================================
20:18:10,366 INFO  [org.alfresco.repo.admin.patch.PatchExecuter] 
=== Applied patch                 === 
ID: patch.db-V3.0-ActivityTables
RESULT: 
Preceded by alternative patch 'patch.db-V3.0-0-CreateActivitiesExtras'.
=====================================
20:18:10,366 INFO  [org.alfresco.repo.admin.patch.PatchExecuter] 
=== Applied patch                 === 
ID: patch.db-V3.2-ContentTables2
RESULT: 
Script completed
=====================================
20:18:10,366 INFO  [org.alfresco.repo.admin.patch.PatchExecuter] 
=== Applied patch                 === 
ID: patch.redeployNominatedInvitationProcessWithPropsForShare
RESULT: 
Re-deployed 1 workflows. 
=====================================
20:18:10,366 INFO  [org.alfresco.repo.admin.patch.PatchExecuter] 
=== Applied patch                 === 
ID: patch.db-V3.3-Remove-VersionCount
RESULT: 
Script completed
=====================================
20:18:10,366 INFO  [org.alfresco.repo.admin.patch.PatchExecuter] 
=== Applied patch                 === 
ID: patch.rendition.rendering_actions
RESULT: 
Imported view into bootstrap location: /app:company_home/app:dictionary (workspace://SpacesStore/0094cf97-3f9d-11dc-a403-cbe9c48b606f)
=====================================
20:18:10,366 INFO  [org.alfresco.repo.admin.patch.PatchExecuter] 
=== Applied patch                 === 
ID: patch.thumbnailsAssocQName
RESULT: 
Successfully updated the '{http://www.alfresco.org/model/content/1.0}thumbnails' QName to '{http://www.alfresco.org/model/rendition/1.0}rendition'.
=====================================
20:18:10,366 INFO  [org.alfresco.repo.admin.patch.PatchExecuter] 
=== Applied patch                 === 
ID: patch.emailInviteAndNotifyTemplatesFolder
RESULT: 
The Email Notify Templates folder was successfully created: workspace://SpacesStore/7d13f3f0-4fe9-46cc-a153-030ba41fb618; The Email Invite Templates folder was successfully created: workspace://SpacesStore/7d13f3f0-4fe9-46cc-a153-030ba41fb618
=====================================
20:18:10,366 INFO  [org.alfresco.repo.admin.patch.PatchExecuter] 
=== Applied patch                 === 
ID: patch.convertContentUrls
RESULT: 
Content URL conversion completed.
=====================================
20:18:10,366 INFO  [org.alfresco.repo.admin.patch.PatchExecuter] 
=== Applied patch                 === 
ID: patch.transferServiceFolder
RESULT: 
Imported view into bootstrap location: /app:company_home/app:dictionary (workspace://SpacesStore/0094cf97-3f9d-11dc-a403-cbe9c48b606f)
=====================================
20:18:10,367 INFO  [org.alfresco.repo.admin.patch.PatchExecuter] 
=== Applied patch                 === 
ID: patch.db-V3.4-authority-unique-idx
RESULT: 
Script completed
=====================================
20:18:10,367 INFO  [org.alfresco.repo.admin.patch.PatchExecuter] 
=== Applied patch                 === 
ID: patch.fixAuthoritiesCrcValues
RESULT: 
Fixed CRC32 values for UTF-8 encoding for 55 authorities.  See file /opt/alfresco-4.0.d/tomcat/temp/Alfresco/longLife_patches/FixAuthorityCrcValuesPatch.log for details.
=====================================
20:18:10,367 INFO  [org.alfresco.repo.admin.patch.PatchExecuter] 
=== Applied patch                 === 
ID: patch.updateMimetypes1
RESULT: 
Updating mimetypes.
   Updated 1 content references with mimetype 'application/vnd.excel' to use 'application/vnd.ms-excel'.
   Indexed 21,523 nodes in store 'workspace://SpacesStore'.
   Indexed 36,342 nodes in store 'archive://SpacesStore'.
   Updated 1 content references with mimetype 'application/vnd.powerpoint' to use 'application/vnd.ms-powerpoint'.
   Indexed 176 nodes in store 'workspace://SpacesStore'.
   Indexed 359 nodes in store 'archive://SpacesStore'.
   Finished updating mimetypes.
=====================================
20:18:10,367 INFO  [org.alfresco.repo.admin.patch.PatchExecuter] 
=== Applied patch                 === 
ID: patch.db-V3.3-modify-index-permission_id
RESULT: 
Script completed
=====================================
20:18:10,367 INFO  [org.alfresco.repo.admin.patch.PatchExecuter] 
=== Applied patch                 === 
ID: patch.db-V3.3-Fix-AVM-Seqs
RESULT: 
Script completed
=====================================
20:18:10,367 INFO  [org.alfresco.repo.admin.patch.PatchExecuter] 
=== Applied patch                 === 
ID: patch.db-V3.3-Fix-Repo-Seqs
RESULT: 
Script completed
=====================================
20:18:10,367 INFO  [org.alfresco.repo.admin.patch.PatchExecuter] 
=== Applied patch                 === 
ID: patch.db-V3.4-child-assoc-indexes
RESULT: 
Script completed
=====================================
20:18:10,367 INFO  [org.alfresco.repo.admin.patch.PatchExecuter] 
=== Applied patch                 === 
ID: patch.db-V3.4-property-unique-ctx-idx
RESULT: 
Script completed
=====================================
20:18:10,367 INFO  [org.alfresco.repo.admin.patch.PatchExecuter] 
=== Applied patch                 === 
ID: patch.db-V3.4-property-unique-ctx-value
RESULT: 
Script completed
=====================================
20:18:10,367 INFO  [org.alfresco.repo.admin.patch.PatchExecuter] 
=== Applied patch                 === 
ID: patch.db-V3.3-JBPM-Extra
RESULT: 
Script completed
=====================================
20:18:10,367 INFO  [org.alfresco.repo.admin.patch.PatchExecuter] 
=== Applied patch                 === 
ID: patch.db-V3.3-Node-Prop-Serializable
RESULT: 
Script completed
=====================================
20:18:10,368 INFO  [org.alfresco.repo.admin.patch.PatchExecuter] 
=== Applied patch                 === 
ID: patch.migrateAttrDelete
RESULT: 
No-op patch
=====================================
20:18:10,368 INFO  [org.alfresco.repo.admin.patch.PatchExecuter] 
=== Applied patch                 === 
ID: patch.migrateAttrAVMLocks
RESULT: 
Processed 0 attributes
=====================================
20:18:10,368 INFO  [org.alfresco.repo.admin.patch.PatchExecuter] 
=== Applied patch                 === 
ID: patch.migrateAttrChainingURS
RESULT: 
Processed 0 attributes
=====================================
20:18:10,368 INFO  [org.alfresco.repo.admin.patch.PatchExecuter] 
=== Applied patch                 === 
ID: patch.migrateAttrPropBackedBeans
RESULT: 
Processed 0 attributes (0 properties)
=====================================
20:18:10,368 INFO  [org.alfresco.repo.admin.patch.PatchExecuter] 
=== Applied patch                 === 
ID: patch.replication.replication_actions
RESULT: 
Imported view into bootstrap location: /app:company_home/app:dictionary (workspace://SpacesStore/0094cf97-3f9d-11dc-a403-cbe9c48b606f)
=====================================
20:18:10,368 INFO  [org.alfresco.repo.admin.patch.PatchExecuter] 
=== Applied patch                 === 
ID: patch.transfer.targetrule
RESULT: 
Imported view into bootstrap location: /app:company_home/app:dictionary/app:transfers/app:transfer_groups/cm:default/rule:ruleFolder (workspace://SpacesStore/0094cf97-3f9d-11dc-a403-cbe9c48b606f)
=====================================
20:18:10,368 INFO  [org.alfresco.repo.admin.patch.PatchExecuter] 
=== Applied patch                 === 
ID: patch.transfer.targetrulefolder
RESULT: 
Imported view into bootstrap location: /app:company_home/app:dictionary/app:transfers/app:transfer_groups/cm:default (workspace://SpacesStore/0094cf97-3f9d-11dc-a403-cbe9c48b606f)
=====================================
20:18:10,368 INFO  [org.alfresco.repo.admin.patch.PatchExecuter] 
=== Applied patch                 === 
ID: patch.actions.scheduledfolder
RESULT: 
Imported view into bootstrap location: /app:company_home/app:dictionary (workspace://SpacesStore/0094cf97-3f9d-11dc-a403-cbe9c48b606f)
=====================================
20:18:10,368 INFO  [org.alfresco.repo.admin.patch.PatchExecuter] 
=== Applied patch                 === 
ID: patch.updateMimetypes2
RESULT: 
Updating mimetypes.
   Finished updating mimetypes.
=====================================
20:18:10,368 INFO  [org.alfresco.repo.admin.patch.PatchExecuter] 
=== Applied patch                 === 
ID: patch.db-V3.2-AddFKIndexes-2
RESULT: 
Script completed
=====================================
20:18:10,368 INFO  [org.alfresco.repo.admin.patch.PatchExecuter] 
=== Applied patch                 === 
ID: patch.eliminateDuplicates
RESULT: 
Preceded by alternative patch 'patch.db-V3.4-AVM-rename-dupes'.
=====================================
20:18:10,369 INFO  [org.alfresco.repo.admin.patch.PatchExecuter] 
=== Applied patch                 === 
ID: patch.db-V3.4-RemovingLinkValidationMetadata
RESULT: 
Script completed
=====================================
20:18:10,369 INFO  [org.alfresco.repo.admin.patch.PatchExecuter] 
=== Applied patch                 === 
ID: patch.db-V3.4-AVM-rename-dupes
RESULT: 
Script completed: rows updated = 0
=====================================
20:18:10,369 INFO  [org.alfresco.repo.admin.patch.PatchExecuter] 
=== Applied patch                 === 
ID: patch.redeployJbpmAdhocWorkflow
RESULT: 
Re-deployed 1 workflows. 
=====================================
20:18:10,369 INFO  [org.alfresco.repo.admin.patch.PatchExecuter] 
=== Applied patch                 === 
ID: patch.db-V3.4-VarcharFieldSizesQuadrupleIncreasing
RESULT: 
Script completed
=====================================
20:18:10,369 INFO  [org.alfresco.repo.admin.patch.PatchExecuter] 
=== Applied patch                 === 
ID: patch.db-V3.4-JBPM-FK-indexes
RESULT: 
Script completed
=====================================
20:18:10,369 INFO  [org.alfresco.repo.admin.patch.PatchExecuter] 
=== Applied patch                 === 
ID: patch.mtFixAdminExistingTenants
RESULT: 
Fix bootstrapped creator/modifier
=====================================
20:18:10,369 INFO  [org.alfresco.repo.admin.patch.PatchExecuter] 
=== Applied patch                 === 
ID: patch.fixUserQNames
RESULT: 
Generated qnames for 55 users.
=====================================
20:18:10,369 INFO  [org.alfresco.repo.admin.patch.PatchExecuter] 
=== Applied patch                 === 
ID: patch.fixAclInheritance
RESULT: 
Fixed 0 ACLs.
=====================================
20:18:10,369 INFO  [org.alfresco.repo.admin.patch.PatchExecuter] 
=== Applied patch                 === 
ID: patch.inviteEmailTemplates
RESULT: 
Imported view into bootstrap location: /app:company_home/app:dictionary/app:email_templates/cm:invite (workspace://SpacesStore/0094cf97-3f9d-11dc-a403-cbe9c48b606f)
=====================================
20:18:10,369 INFO  [org.alfresco.repo.admin.patch.PatchExecuter] 
=== Applied patch                 === 
ID: patch.newUserEmailTemplates
RESULT: 
Imported view into bootstrap location: /app:company_home/app:dictionary/app:email_templates/cm:invite (workspace://SpacesStore/0094cf97-3f9d-11dc-a403-cbe9c48b606f)
=====================================
20:18:10,369 INFO  [org.alfresco.repo.admin.patch.PatchExecuter] 
=== Applied patch                 === 
ID: patch.activitiesEmailTemplate
RESULT: 
Imported view into bootstrap location: /app:company_home/app:dictionary/app:email_templates (workspace://SpacesStore/0094cf97-3f9d-11dc-a403-cbe9c48b606f)
=====================================
20:18:10,369 INFO  [org.alfresco.repo.admin.patch.PatchExecuter] 
=== Applied patch                 === 
ID: patch.imap.clear.old.messages
RESULT: 
Old IMAP message templates have been removed from the repository
=====================================
20:18:10,370 INFO  [org.alfresco.repo.admin.patch.PatchExecuter] 
=== Applied patch                 === 
ID: patch.imapSpacesLocaleTemplates
RESULT: 
Imported view into bootstrap location: /app:company_home/app:dictionary/app:imap_configs/app:imap_templates (workspace://SpacesStore/0094cf97-3f9d-11dc-a403-cbe9c48b606f)
=====================================
20:18:10,370 INFO  [org.alfresco.repo.admin.patch.PatchExecuter] 
=== Applied patch                 === 
ID: patch.htmlNotificationMailTemplates
RESULT: 
Imported view into bootstrap location: /app:company_home/app:dictionary/app:email_templates/app:notify_email_templates (workspace://SpacesStore/0094cf97-3f9d-11dc-a403-cbe9c48b606f)
=====================================
20:18:10,370 INFO  [org.alfresco.repo.admin.patch.PatchExecuter] 
=== Applied patch                 === 
ID: patch.migrateAttrDropOldTables
RESULT: 
Drop tables alf_attributes, alf_global_attributes, alf_list_attribute_entries, alf_map_attribute_entries and sequence alf_attributes_seq
=====================================
20:18:10,370 INFO  [org.alfresco.repo.admin.patch.PatchExecuter] 
=== Applied patch                 === 
ID: patch.db-V4.0-AclChangeSet
RESULT: 
Script completed
=====================================
20:18:10,370 INFO  [org.alfresco.repo.admin.patch.PatchExecuter] 
=== Applied patch                 === 
ID: patch.db-V4.0-NodeAssoc-Ordering
RESULT: 
Script completed
=====================================
20:18:10,370 INFO  [org.alfresco.repo.admin.patch.PatchExecuter] 
=== Applied patch                 === 
ID: patch.db-V4.0-Node-Locale
RESULT: 
Script completed
=====================================
20:18:10,370 INFO  [org.alfresco.repo.admin.patch.PatchExecuter] 
=== Applied patch                 === 
ID: patch.followingMailTemplates
RESULT: 
Imported view into bootstrap location: /app:company_home/app:dictionary/app:email_templates (workspace://SpacesStore/0094cf97-3f9d-11dc-a403-cbe9c48b606f)
=====================================
20:18:10,370 INFO  [org.alfresco.repo.admin.patch.PatchExecuter] 
=== Applied patch                 === 
ID: patch.activitiesTemplatesUpdate
RESULT: 
Updated 6 activities email templates.
=====================================
20:18:10,370 INFO  [org.alfresco.repo.admin.patch.PatchExecuter] 
=== Applied patch                 === 
ID: patch.db-V4.0-SubscriptionTables
RESULT: 
Script completed
=====================================
20:18:10,370 INFO  [org.alfresco.repo.admin.patch.PatchExecuter] 
=== Applied patch                 === 
ID: patch.avmToAdmRemoteStore
RESULT: 
Preceded by alternative patch 'patch.sitesFolder'.
=====================================
20:18:10,370 INFO  [org.alfresco.repo.admin.patch.PatchExecuter] 
=== Applied patch                 === 
ID: patch.copiedFromAspect
RESULT: 
Fixed cm:copiedfrom model for 120,741 nodes.  See file /opt/alfresco-4.0.d/tomcat/temp/Alfresco/longLife_patches/CopiedFromAspectPatch.log for details.
=====================================
20:18:10,371 INFO  [org.alfresco.repo.admin.patch.PatchExecuter] 
=== Applied patch                 === 
ID: patch.publishing.root
RESULT: 
Imported view into bootstrap location: /app:company_home/app:dictionary (workspace://SpacesStore/0094cf97-3f9d-11dc-a403-cbe9c48b606f)
=====================================
20:18:10,371 INFO  [org.alfresco.repo.admin.patch.PatchExecuter] 
=== Applied patch                 === 
ID: patch.workflowNotification
RESULT: 
Imported view into bootstrap location: /app:company_home/app:dictionary/app:email_templates (workspace://SpacesStore/0094cf97-3f9d-11dc-a403-cbe9c48b606f)
=====================================
20:18:10,371 INFO  [org.alfresco.repo.admin.patch.PatchExecuter] 
=== Applied patch                 === 
ID: patch.nodeTemplatesFolder
RESULT: 
Imported view into bootstrap location: /app:company_home/app:dictionary (workspace://SpacesStore/0094cf97-3f9d-11dc-a403-cbe9c48b606f)
=====================================
20:18:10,371 INFO  [org.alfresco.repo.admin.patch.PatchExecuter] 
=== Applied patch                 === 
ID: patch.updateMimetypes3
RESULT: 
Updating mimetypes.
   Updated 1 content references with mimetype 'audio/x-mpeg' to use 'audio/mpeg'.
   Indexed 34 nodes in store 'workspace://SpacesStore'.
   Indexed 41 nodes in store 'archive://SpacesStore'.
   Finished updating mimetypes.
=====================================
20:18:10,371 INFO  [org.alfresco.repo.admin.patch.PatchExecuter] 
=== Applied patch                 === 
ID: patch.sitesSpacePermissions
RESULT: 
Preceded by alternative patch 'patch.sitesFolder'.
=====================================
20:18:10,371 INFO  [org.alfresco.repo.admin.patch.PatchExecuter] 
=== Applied patch                 === 
ID: patch.updateWorkflowNotificationTemplates
RESULT: 
Workflow Notification Templates successfully updated.
=====================================
20:18:10,371 ERROR [org.alfresco.repo.admin.patch.PatchExecuter] 
=== Failed to apply patch         === 
ID: patch.siteLoadPatch.swsdp
RESULT: 
07070001 java.lang.NullPointerException
   at org.alfresco.repo.domain.permissions.AclDAOImpl.getAcl(AclDAOImpl.java:1044)
   at org.alfresco.repo.domain.permissions.ADMAccessControlListDAO.getAccessControlList(ADMAccessControlListDAO.java:84)
   at org.alfresco.repo.domain.permissions.AbstractPermissionsDaoComponentImpl.getAccessControlListProperties(AbstractPermissionsDaoComponentImpl.java:622)
   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.alfresco.repo.service.StoreRedirectorProxyFactory$RedirectorInvocationHandler.invoke(StoreRedirectorProxyFactory.java:215)
   at $Proxy10.getAccessControlListProperties(Unknown Source)
   at org.alfresco.repo.security.permissions.impl.PermissionServiceImpl.hasPermission(PermissionServiceImpl.java:493)
   at org.alfresco.repo.security.permissions.impl.PermissionServiceImpl.hasPermission(PermissionServiceImpl.java:1006)
   at sun.reflect.GeneratedMethodAccessor568.invoke(Unknown Source)
   at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
   at java.lang.reflect.Method.invoke(Method.java:597)
   at org.springframework.aop.support.AopUtils.invokeJoinpointUsingReflection(AopUtils.java:309)
   at org.springframework.aop.framework.ReflectiveMethodInvocation.invokeJoinpoint(ReflectiveMethodInvocation.java:183)
   at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:150)
   at org.springframework.transaction.interceptor.TransactionInterceptor.invoke(TransactionInterceptor.java:110)
   at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172)
   at org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDynamicAopProxy.java:202)
   at $Proxy24.hasPermission(Unknown Source)
   at org.alfresco.repo.security.permissions.impl.acegi.ACLEntryVoter.vote(ACLEntryVoter.java:437)
   at net.sf.acegisecurity.vote.AffirmativeBased.decide(AffirmativeBased.java:69)
   at net.sf.acegisecurity.intercept.AbstractSecurityInterceptor.beforeInvocation(AbstractSecurityInterceptor.java:394)
   at net.sf.acegisecurity.intercept.method.aopalliance.MethodSecurityInterceptor.invoke(MethodSecurityInterceptor.java:77)
   at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172)
   at org.alfresco.repo.security.permissions.impl.ExceptionTranslatorMethodInterceptor.invoke(ExceptionTranslatorMethodInterceptor.java:44)
   at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172)
   at org.alfresco.repo.audit.AuditMethodInterceptor.invoke(AuditMethodInterceptor.java:146)
   at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172)
   at org.springframework.transaction.interceptor.TransactionInterceptor.invoke(TransactionInterceptor.java:110)
   at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172)
   at org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDynamicAopProxy.java:202)
   at $Proxy8.hasAspect(Unknown Source)
   at org.alfresco.repo.tagging.TaggingServiceImpl.addTagScope(TaggingServiceImpl.java:783)
   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.springframework.aop.support.AopUtils.invokeJoinpointUsingReflection(AopUtils.java:309)
   at org.springframework.aop.framework.ReflectiveMethodInvocation.invokeJoinpoint(ReflectiveMethodInvocation.java:183)
   at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:150)
   at org.alfresco.repo.security.permissions.impl.AlwaysProceedMethodInterceptor.invoke(AlwaysProceedMethodInterceptor.java:34)
   at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172)
   at org.alfresco.repo.security.permissions.impl.ExceptionTranslatorMethodInterceptor.invoke(ExceptionTranslatorMethodInterceptor.java:44)
   at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172)
   at org.alfresco.repo.audit.AuditMethodInterceptor.invoke(AuditMethodInterceptor.java:146)
   at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172)
   at org.springframework.transaction.interceptor.TransactionInterceptor.invoke(TransactionInterceptor.java:110)
   at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172)
   at org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDynamicAopProxy.java:202)
   at $Proxy83.addTagScope(Unknown Source)
   at org.alfresco.repo.site.SiteServiceImpl.createSite(SiteServiceImpl.java:483)
   at org.alfresco.repo.site.SiteServiceImpl.createSite(SiteServiceImpl.java:426)
   at org.alfresco.repo.admin.patch.impl.SiteLoadPatch.applyInternalImpl(SiteLoadPatch.java:208)
   at org.alfresco.repo.admin.patch.impl.SiteLoadPatch.applyInternal(SiteLoadPatch.java:166)
   at org.alfresco.repo.admin.patch.AbstractPatch.applyImpl(AbstractPatch.java:407)
   at org.alfresco.repo.admin.patch.AbstractPatch.access$000(AbstractPatch.java:52)
   at org.alfresco.repo.admin.patch.AbstractPatch$2$1.execute(AbstractPatch.java:466)
   at org.alfresco.repo.admin.patch.AbstractPatch$2$1.execute(AbstractPatch.java:463)
   at org.alfresco.repo.transaction.RetryingTransactionHelper.doInTransaction(RetryingTransactionHelper.java:388)
   at org.alfresco.repo.admin.patch.AbstractPatch$2.doWork(AbstractPatch.java:469)
   at org.alfresco.repo.admin.patch.AbstractPatch$2.doWork(AbstractPatch.java:456)
   at org.alfresco.repo.security.authentication.AuthenticationUtil.runAs(AuthenticationUtil.java:519)
   at org.alfresco.repo.admin.patch.AbstractPatch.apply(AbstractPatch.java:478)
   at org.alfresco.repo.admin.patch.PatchServiceImpl$PatchWork.applyPatch(PatchServiceImpl.java:491)
   at org.alfresco.repo.admin.patch.PatchServiceImpl$PatchWork.execute(PatchServiceImpl.java:403)
   at org.alfresco.repo.admin.patch.PatchServiceImpl.applyPatch(PatchServiceImpl.java:258)
   at org.alfresco.repo.admin.patch.PatchServiceImpl.applyPatchAndDependencies(PatchServiceImpl.java:235)
   at org.alfresco.repo.admin.patch.PatchServiceImpl.applyOutstandingPatches(PatchServiceImpl.java:171)
   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.springframework.aop.support.AopUtils.invokeJoinpointUsingReflection(AopUtils.java:309)
   at org.springframework.aop.framework.ReflectiveMethodInvocation.invokeJoinpoint(ReflectiveMethodInvocation.java:183)
   at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:150)
   at org.springframework.transaction.interceptor.TransactionInterceptor.invoke(TransactionInterceptor.java:110)
   at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172)
   at org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDynamicAopProxy.java:202)
   at $Proxy92.applyOutstandingPatches(Unknown Source)
   at org.alfresco.repo.admin.patch.PatchExecuter.applyOutstandingPatches(PatchExecuter.java:76)
   at org.alfresco.repo.admin.patch.PatchExecuter$1.doWork(PatchExecuter.java:124)
   at org.alfresco.repo.admin.patch.PatchExecuter$1.doWork(PatchExecuter.java:120)
   at org.alfresco.repo.security.authentication.AuthenticationUtil.runAs(AuthenticationUtil.java:519)
   at org.alfresco.repo.admin.patch.PatchExecuter.onBootstrap(PatchExecuter.java:128)
   at org.springframework.extensions.surf.util.AbstractLifecycleBean.onApplicationEvent(AbstractLifecycleBean.java:56)
   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)

=====================================
20:18:10,371 ERROR [org.springframework.web.context.ContextLoader] Context initialization failed
org.alfresco.error.AlfrescoRuntimeException: 07070002 Not all patches could be applied
   at org.alfresco.repo.admin.patch.PatchExecuter.applyOutstandingPatches(PatchExecuter.java:111)
   at org.alfresco.repo.admin.patch.PatchExecuter$1.doWork(PatchExecuter.java:124)
   at org.alfresco.repo.admin.patch.PatchExecuter$1.doWork(PatchExecuter.java:120)
   at org.alfresco.repo.security.authentication.AuthenticationUtil.runAs(AuthenticationUtil.java:519)
   at org.alfresco.repo.admin.patch.PatchExecuter.onBootstrap(PatchExecuter.java:128)
   at org.springframework.extensions.surf.util.AbstractLifecycleBean.onApplicationEvent(AbstractLifecycleBean.java:56)
   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)
‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍

varsenault · ‎08-14-2012

So, now, the alfresco webapp seems to work fine, but I have a problem with the "share" webapp.

When I access the share url directly on the server : http://XXX.XXX.XXX.XXX:8080/share, everything is fine.

However, whenever I try to map an external port (http://YYY.YYY.YYY.YYY:8080 –> http://XXX.XXX.XXX.XXX:8080) and access it through the router/firewall, I get the following error…

Please not that the alfresco webapp doesn't seem to display the same problem…

Vincent

javax.servlet.ServletException: org.alfresco.error.AlfrescoRuntimeException: 07140010 Unable to retrieve object: site-index of type: page
   org.alfresco.web.site.servlet.DispatcherServlet.service(DispatcherServlet.java:146)
   javax.servlet.http.HttpServlet.service(HttpServlet.java:717)
root cause

org.alfresco.error.AlfrescoRuntimeException: 07140010 Unable to retrieve object: site-index of type: page
   org.alfresco.web.framework.ModelObjectManager.getObject(ModelObjectManager.java:140)
   org.alfresco.web.site.Model.getObject(Model.java:513)
   org.alfresco.web.site.Model.getPage(Model.java:165)
   org.alfresco.web.site.SlingshotPageMapper.executeMapper(SlingshotPageMapper.java:188)
   org.alfresco.web.site.AbstractPageMapper.execute(AbstractPageMapper.java:62)
   org.alfresco.web.site.DefaultRequestContextFactory.newInstance(DefaultRequestContextFactory.java:109)
   org.alfresco.web.site.FrameworkHelper.initRequestContext(FrameworkHelper.java:202)
   org.alfresco.web.site.servlet.DispatcherServlet.service(DispatcherServlet.java:142)
   javax.servlet.http.HttpServlet.service(HttpServlet.java:717)
root cause

org.alfresco.web.framework.exception.ModelObjectPersisterException: Error loading object id: site-index from persister id: RemoteStore_alfresco/site-data/pages_page
   org.alfresco.web.framework.MultiModelObjectPersister.getObject(MultiModelObjectPersister.java:110)
   org.alfresco.web.framework.ModelObjectManager.getObject(ModelObjectManager.java:136)
   org.alfresco.web.site.Model.getObject(Model.java:513)
   org.alfresco.web.site.Model.getPage(Model.java:165)
   org.alfresco.web.site.SlingshotPageMapper.executeMapper(SlingshotPageMapper.java:188)
   org.alfresco.web.site.AbstractPageMapper.execute(AbstractPageMapper.java:62)
   org.alfresco.web.site.DefaultRequestContextFactory.newInstance(DefaultRequestContextFactory.java:109)
   org.alfresco.web.site.FrameworkHelper.initRequestContext(FrameworkHelper.java:202)
   org.alfresco.web.site.servlet.DispatcherServlet.service(DispatcherServlet.java:142)
   javax.servlet.http.HttpServlet.service(HttpServlet.java:717)
root cause

org.alfresco.web.framework.exception.ModelObjectPersisterException: Failure to load model object for path: site-index.xml
   org.alfresco.web.framework.StoreModelObjectPersister.getObjectByPath(StoreModelObjectPersister.java:170)
   org.alfresco.web.framework.StoreModelObjectPersister.getObject(StoreModelObjectPersister.java:108)
   org.alfresco.web.framework.MultiModelObjectPersister.getObject(MultiModelObjectPersister.java:106)
   org.alfresco.web.framework.ModelObjectManager.getObject(ModelObjectManager.java:136)
   org.alfresco.web.site.Model.getObject(Model.java:513)
   org.alfresco.web.site.Model.getPage(Model.java:165)
   org.alfresco.web.site.SlingshotPageMapper.executeMapper(SlingshotPageMapper.java:188)
   org.alfresco.web.site.AbstractPageMapper.execute(AbstractPageMapper.java:62)
   org.alfresco.web.site.DefaultRequestContextFactory.newInstance(DefaultRequestContextFactory.java:109)
   org.alfresco.web.site.FrameworkHelper.initRequestContext(FrameworkHelper.java:202)
   org.alfresco.web.site.servlet.DispatcherServlet.service(DispatcherServlet.java:142)
   javax.servlet.http.HttpServlet.service(HttpServlet.java:717)
root cause

java.io.IOException: Unable to test document path: site-index.xml in remote store: alfresco due to error: 498 Connection refused
   org.alfresco.web.scripts.RemoteStore.hasDocument(RemoteStore.java:351)
   org.alfresco.web.framework.StoreModelObjectPersister.getObjectByPath(StoreModelObjectPersister.java:136)
   org.alfresco.web.framework.StoreModelObjectPersister.getObject(StoreModelObjectPersister.java:108)
   org.alfresco.web.framework.MultiModelObjectPersister.getObject(MultiModelObjectPersister.java:106)
   org.alfresco.web.framework.ModelObjectManager.getObject(ModelObjectManager.java:136)
   org.alfresco.web.site.Model.getObject(Model.java:513)
   org.alfresco.web.site.Model.getPage(Model.java:165)
   org.alfresco.web.site.SlingshotPageMapper.executeMapper(SlingshotPageMapper.java:188)
   org.alfresco.web.site.AbstractPageMapper.execute(AbstractPageMapper.java:62)
   org.alfresco.web.site.DefaultRequestContextFactory.newInstance(DefaultRequestContextFactory.java:109)
   org.alfresco.web.site.FrameworkHelper.initRequestContext(FrameworkHelper.java:202)
   org.alfresco.web.site.servlet.DispatcherServlet.service(DispatcherServlet.java:142)
   javax.servlet.http.HttpServlet.service(HttpServlet.java:717)‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍

varsenault · ‎08-14-2012

My bad. The redirect seem to redirect to the old alfresco instance…

varsenault · ‎11-22-2012

OK,

So I thought everything was cool. I successfully updated the 15 October backup I had of the currently in-production 3.2r2 instance to 4.2b (and not just the initially planned 4.0d). And we did some testing in an almost identical hardware sandbox on 4.2b (same OS, just 16GB ram in sandbox instead of 8GB ram in production).

But then, during the 3.2r2 to 4.0b upgrade (see messages above for reference), the thing crashed on patch.updateMimetypes1 (please see below for full relevant log excerpt).

The same happened upon updating from 3.2r2 to 3.4d. With the same error on the same patch.

I could find an issue that seemed related here: https://issues.alfresco.com/jira/browse/ALF-2719

Especially because there is mention of a SQL query that returns a list of faulty entries in the document table that would return about 50 results among which I could find the ID of the document that fired the "No ContentData"" error:


   select * from alf_node_properties np 
   left join alf_content_data cd on (cd.id = np.long_value) 
   where np.actual_type_n = 21 
   and cd.id is null‍‍‍‍‍

What puzzles me is that a backup of the state of the production instance on October 15 would work and not the one on November 1 does not. What happened between those 2 dates????!!!

I had backups still, so I just reverted to 3.2r2 for the production instance, but still. I would really like to upgrade to 4.2b and can't.

And what worries me most is that the bug reports for the seemingly related issue ends with: contact Alfresco support who will help to fix the specific database entries.

Any help would be greatly appreciated.

I am on Ubuntu 12.10.

Please help,

Vincent

8:33:25,056 INFO  [org.alfresco.repo.admin.patch.PatchExecuter]    Applying patch 'patch.updateMimetypes1' (Fix mimetypes for Excel and Powerpoint.).
18:33:29,292 WARN  [org.alfresco.repo.cache.TransactionalCache.org.alfresco.cache.contentDataTransactionalCache] Transactional update cache 'org.alfresco.cache.contentDataTransactionalCache' is full (1000).
18:33:39,167 WARN  [org.alfresco.repo.cache.TransactionalCache.org.alfresco.cache.contentDataTransactionalCache] Transactional update cache 'org.alfresco.cache.contentDataTransactionalCache' is full (1000).
18:33:45,230 WARN  [org.alfresco.repo.cache.TransactionalCache.org.alfresco.cache.contentDataTransactionalCache] Transactional update cache 'org.alfresco.cache.contentDataTransactionalCache' is full (1000).
18:33:51,308 WARN  [org.alfresco.repo.cache.TransactionalCache.org.alfresco.cache.contentDataTransactionalCache] Transactional update cache 'org.alfresco.cache.contentDataTransactionalCache' is full (1000).
18:33:57,373 WARN  [org.alfresco.repo.cache.TransactionalCache.org.alfresco.cache.contentDataTransactionalCache] Transactional update cache 'org.alfresco.cache.contentDataTransactionalCache' is full (1000).
18:34:03,378 WARN  [org.alfresco.repo.cache.TransactionalCache.org.alfresco.cache.contentDataTransactionalCache] Transactional update cache 'org.alfresco.cache.contentDataTransactionalCache' is full (1000).
18:34:09,649 WARN  [org.alfresco.repo.cache.TransactionalCache.org.alfresco.cache.contentDataTransactionalCache] Transactional update cache 'org.alfresco.cache.contentDataTransactionalCache' is full (1000).
18:34:15,647 WARN  [org.alfresco.repo.cache.TransactionalCache.org.alfresco.cache.contentDataTransactionalCache] Transactional update cache 'org.alfresco.cache.contentDataTransactionalCache' is full (1000).
18:34:21,766 WARN  [org.alfresco.repo.cache.TransactionalCache.org.alfresco.cache.contentDataTransactionalCache] Transactional update cache 'org.alfresco.cache.contentDataTransactionalCache' is full (1000).
18:34:28,116 WARN  [org.alfresco.repo.cache.TransactionalCache.org.alfresco.cache.contentDataTransactionalCache] Transactional update cache 'org.alfresco.cache.contentDataTransactionalCache' is full (1000).
18:34:34,523 WARN  [org.alfresco.repo.cache.TransactionalCache.org.alfresco.cache.contentDataTransactionalCache] Transactional update cache 'org.alfresco.cache.contentDataTransactionalCache' is full (1000).
18:34:40,883 WARN  [org.alfresco.repo.cache.TransactionalCache.org.alfresco.cache.contentDataTransactionalCache] Transactional update cache 'org.alfresco.cache.contentDataTransactionalCache' is full (1000).
18:34:47,215 WARN  [org.alfresco.repo.cache.TransactionalCache.org.alfresco.cache.contentDataTransactionalCache] Transactional update cache 'org.alfresco.cache.contentDataTransactionalCache' is full (1000).
18:34:53,771 WARN  [org.alfresco.repo.cache.TransactionalCache.org.alfresco.cache.contentDataTransactionalCache] Transactional update cache 'org.alfresco.cache.contentDataTransactionalCache' is full (1000).
18:34:59,887 WARN  [org.alfresco.repo.cache.TransactionalCache.org.alfresco.cache.contentDataTransactionalCache] Transactional update cache 'org.alfresco.cache.contentDataTransactionalCache' is full (1000).
18:35:05,964 WARN  [org.alfresco.repo.cache.TransactionalCache.org.alfresco.cache.contentDataTransactionalCache] Transactional update cache 'org.alfresco.cache.contentDataTransactionalCache' is full (1000).
18:35:12,398 WARN  [org.alfresco.repo.cache.TransactionalCache.org.alfresco.cache.contentDataTransactionalCache] Transactional update cache 'org.alfresco.cache.contentDataTransactionalCache' is full (1000).
18:35:19,521 WARN  [org.alfresco.repo.cache.TransactionalCache.org.alfresco.cache.contentDataTransactionalCache] Transactional update cache 'org.alfresco.cache.contentDataTransactionalCache' is full (1000).
18:35:26,718 WARN  [org.alfresco.repo.cache.TransactionalCache.org.alfresco.cache.contentDataTransactionalCache] Transactional update cache 'org.alfresco.cache.contentDataTransactionalCache' is full (1000).
18:35:32,802 WARN  [org.alfresco.repo.cache.TransactionalCache.org.alfresco.cache.contentDataTransactionalCache] Transactional update cache 'org.alfresco.cache.contentDataTransactionalCache' is full (1000).
18:35:39,287 WARN  [org.alfresco.repo.cache.TransactionalCache.org.alfresco.cache.contentDataTransactionalCache] Transactional update cache 'org.alfresco.cache.contentDataTransactionalCache' is full (1000).
18:35:45,582 WARN  [org.alfresco.repo.cache.TransactionalCache.org.alfresco.cache.contentDataTransactionalCache] Transactional update cache 'org.alfresco.cache.contentDataTransactionalCache' is full (1000).
18:35:53,291 WARN  [org.alfresco.repo.cache.TransactionalCache.org.alfresco.cache.contentDataTransactionalCache] Transactional update cache 'org.alfresco.cache.contentDataTransactionalCache' is full (1000).
18:35:59,470 WARN  [org.alfresco.repo.cache.TransactionalCache.org.alfresco.cache.contentDataTransactionalCache] Transactional update cache 'org.alfresco.cache.contentDataTransactionalCache' is full (1000).
18:36:06,615 WARN  [org.alfresco.repo.cache.TransactionalCache.org.alfresco.cache.contentDataTransactionalCache] Transactional update cache 'org.alfresco.cache.contentDataTransactionalCache' is full (1000).
18:36:13,863 WARN  [org.alfresco.repo.cache.TransactionalCache.org.alfresco.cache.contentDataTransactionalCache] Transactional update cache 'org.alfresco.cache.contentDataTransactionalCache' is full (1000).
18:36:21,731 WARN  [org.alfresco.repo.cache.TransactionalCache.org.alfresco.cache.contentDataTransactionalCache] Transactional update cache 'org.alfresco.cache.contentDataTransactionalCache' is full (1000).
18:36:28,691 WARN  [org.alfresco.repo.cache.TransactionalCache.org.alfresco.cache.contentDataTransactionalCache] Transactional update cache 'org.alfresco.cache.contentDataTransactionalCache' is full (1000).
18:36:36,122 WARN  [org.alfresco.repo.cache.TransactionalCache.org.alfresco.cache.contentDataTransactionalCache] Transactional update cache 'org.alfresco.cache.contentDataTransactionalCache' is full (1000).
18:36:43,472 WARN  [org.alfresco.repo.cache.TransactionalCache.org.alfresco.cache.contentDataTransactionalCache] Transactional update cache 'org.alfresco.cache.contentDataTransactionalCache' is full (1000).
18:36:50,623 WARN  [org.alfresco.repo.cache.TransactionalCache.org.alfresco.cache.contentDataTransactionalCache] Transactional update cache 'org.alfresco.cache.contentDataTransactionalCache' is full (1000).
18:36:58,278 WARN  [org.alfresco.repo.cache.TransactionalCache.org.alfresco.cache.contentDataTransactionalCache] Transactional update cache 'org.alfresco.cache.contentDataTransactionalCache' is full (1000).
18:37:05,250 WARN  [org.alfresco.repo.cache.TransactionalCache.org.alfresco.cache.contentDataTransactionalCache] Transactional update cache 'org.alfresco.cache.contentDataTransactionalCache' is full (1000).
18:37:12,300 WARN  [org.alfresco.repo.cache.TransactionalCache.org.alfresco.cache.contentDataTransactionalCache] Transactional update cache 'org.alfresco.cache.contentDataTransactionalCache' is full (1000).
18:37:19,366 WARN  [org.alfresco.repo.cache.TransactionalCache.org.alfresco.cache.contentDataTransactionalCache] Transactional update cache 'org.alfresco.cache.contentDataTransactionalCache' is full (1000).
18:37:27,203 WARN  [org.alfresco.repo.cache.TransactionalCache.org.alfresco.cache.contentDataTransactionalCache] Transactional update cache 'org.alfresco.cache.contentDataTransactionalCache' is full (1000).
18:37:34,964 WARN  [org.alfresco.repo.cache.TransactionalCache.org.alfresco.cache.contentDataTransactionalCache] Transactional update cache 'org.alfresco.cache.contentDataTransactionalCache' is full (1000).
18:37:42,069 WARN  [org.alfresco.repo.cache.TransactionalCache.org.alfresco.cache.contentDataTransactionalCache] Transactional update cache 'org.alfresco.cache.contentDataTransactionalCache' is full (1000).
18:37:48,123 WARN  [org.alfresco.repo.cache.TransactionalCache.org.alfresco.cache.contentDataTransactionalCache] Transactional update cache 'org.alfresco.cache.contentDataTransactionalCache' is full (1000).
18:37:56,171 WARN  [org.alfresco.repo.cache.TransactionalCache.org.alfresco.cache.contentDataTransactionalCache] Transactional update cache 'org.alfresco.cache.contentDataTransactionalCache' is full (1000).
18:38:03,499 ERROR [org.alfresco.repo.admin.patch.PatchExecuter] 10100001 org.springframework.dao.DataIntegrityViolationException: No ContentData value exists for ID 511159
   at org.alfresco.repo.domain.contentdata.AbstractContentDataDAOImpl.getContentData(AbstractContentDataDAOImpl.java:177)
   at org.alfresco.repo.domain.node.NodePropertyHelper.makeSerializableValue(NodePropertyHelper.java:671)
   at org.alfresco.repo.domain.node.NodePropertyHelper.collapsePropertiesWithSameQNameAndListIndex(NodePropertyHelper.java:579)
   at org.alfresco.repo.domain.node.NodePropertyHelper.convertToPublicProperties(NodePropertyHelper.java:407)
   at org.alfresco.repo.domain.node.AbstractNodeDAOImpl.cacheNodesNoBatch(AbstractNodeDAOImpl.java:3841)
   at org.alfresco.repo.domain.node.AbstractNodeDAOImpl.cacheNodes(AbstractNodeDAOImpl.java:3765)
   at org.alfresco.repo.domain.node.AbstractNodeDAOImpl.cacheNodes(AbstractNodeDAOImpl.java:3734)
   at org.alfresco.repo.search.impl.lucene.LuceneResultSet.prefetch(LuceneResultSet.java:213)
   at org.alfresco.repo.search.impl.lucene.LuceneResultSet.getNodeRef(LuceneResultSet.java:114)
   at org.alfresco.repo.search.AbstractResultSetRow.getNodeRef(AbstractResultSetRow.java:81)
   at org.alfresco.repo.admin.patch.impl.GenericMimetypeRenamePatch.reindex(GenericMimetypeRenamePatch.java:187)
   at org.alfresco.repo.admin.patch.impl.GenericMimetypeRenamePatch.applyInternal(GenericMimetypeRenamePatch.java:153)
   at org.alfresco.repo.admin.patch.AbstractPatch.applyImpl(AbstractPatch.java:407)
   at org.alfresco.repo.admin.patch.AbstractPatch.access$000(AbstractPatch.java:52)
   at org.alfresco.repo.admin.patch.AbstractPatch$2$1.execute(AbstractPatch.java:466)
   at org.alfresco.repo.admin.patch.AbstractPatch$2$1.execute(AbstractPatch.java:463)
   at org.alfresco.repo.transaction.RetryingTransactionHelper.doInTransaction(RetryingTransactionHelper.java:388)
   at org.alfresco.repo.admin.patch.AbstractPatch$2.doWork(AbstractPatch.java:469)
   at org.alfresco.repo.admin.patch.AbstractPatch$2.doWork(AbstractPatch.java:456)
   at org.alfresco.repo.security.authentication.AuthenticationUtil.runAs(AuthenticationUtil.java:519)
   at org.alfresco.repo.admin.patch.AbstractPatch.apply(AbstractPatch.java:478)
   at org.alfresco.repo.admin.patch.PatchServiceImpl$PatchWork.applyPatch(PatchServiceImpl.java:491)
   at org.alfresco.repo.admin.patch.PatchServiceImpl$PatchWork.execute(PatchServiceImpl.java:403)
   at org.alfresco.repo.admin.patch.PatchServiceImpl.applyPatch(PatchServiceImpl.java:258)
   at org.alfresco.repo.admin.patch.PatchServiceImpl.applyPatchAndDependencies(PatchServiceImpl.java:235)
   at org.alfresco.repo.admin.patch.PatchServiceImpl.applyOutstandingPatches(PatchServiceImpl.java:171)
   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.springframework.aop.support.AopUtils.invokeJoinpointUsingReflection(AopUtils.java:309)
   at org.springframework.aop.framework.ReflectiveMethodInvocation.invokeJoinpoint(ReflectiveMethodInvocation.java:183)
   at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:150)
   at org.springframework.transaction.interceptor.TransactionInterceptor.invoke(TransactionInterceptor.java:110)
   at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172)
   at org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDynamicAopProxy.java:202)
   at $Proxy92.applyOutstandingPatches(Unknown Source)
   at org.alfresco.repo.admin.patch.PatchExecuter.applyOutstandingPatches(PatchExecuter.java:76)
   at org.alfresco.repo.admin.patch.PatchExecuter$1.doWork(PatchExecuter.java:124)
   at org.alfresco.repo.admin.patch.PatchExecuter$1.doWork(PatchExecuter.java:120)
   at org.alfresco.repo.security.authentication.AuthenticationUtil.runAs(AuthenticationUtil.java:519)
   at org.alfresco.repo.admin.patch.PatchExecuter.onBootstrap(PatchExecuter.java:128)
   at org.springframework.extensions.surf.util.AbstractLifecycleBean.onApplicationEvent(AbstractLifecycleBean.java:56)
   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:3972)
   at org.apache.catalina.core.StandardContext.start(StandardContext.java:4467)
   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:443)
   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)
‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍

bopolissimus · ‎12-11-2012

Hello Vincent,

Were you able to resolve this (successfully upgrade from 3.2r (or 3.2r2) to 4.0.d or 4.2.b?

I'm working through the same process and am currently stuck at 4.0.b. 3.2r2->4.0.e doesn't work for me (11:30:26,453 ERROR [org.alfresco.repo.domain.schema.SchemaBootstrap] Schema auto-update failed java.lang.RuntimeException: Unable to execute export) so I'm upgrading to 4.0.b first. It gets further (I don't get unable to execute export) but gets stuck at a different point and I can't tell why. I'll crank up the loglevel and if that helps identify the issue.

If you've already cracked this though it'd be great to know and would save me much time.

Thanks for any pointers,

Gerald Quimpo

Hyland Connect

Upgrading from 3.2r to 4.0d