Alfresco Community 5.0.c -> 5.1 Migration-Problems

[chribu]

Hello together,

i have tried to upgrade my Alfresco Community 5.0.c-Instance to the new 5.1-GA-Release by working with the following guides:
http://docs.alfresco.com/community/tasks/upgrade-process.html
After restoring the contentstore, contentstore.deleted, solr4/solr4/index/archive, solr4/solr4/index/archive/workspace and the MySQL-Database i have started the new Installation with the old contents and got the following errors:

catalina.out

2016-03-09 13:20:35,840  ERROR [repo.action.AsynchronousActionExecutionQueueImpl] [defaultAsyncAction2] Failed to execute asynchronous action: Action[ id=d11304ab-258f-4938-8c8f-69ef12fa98cf, node=workspace://SpacesStore/d11304ab-258f-4938-8c8f-69ef12fa98cf ]: 02090033 Transformer for 'text/plain' source mime type and 'image/png' target mime type was not found. Operation can't be performed org.alfresco.service.cmr.rendition.RenditionServiceException: 02090033 Transformer for 'text/plain' source mime type and 'image/png' target mime type was not found. Operation can't be performed at org.alfresco.repo.rendition.executer.AbstractTransformationRenderingEngine.render(AbstractTransformationRenderingEngine.java:186)
‍‍‍

The errors repeats 37 times.


Can you give me a hint?

Thanks
Chris

[kumar_mullur_08]

Hi,

I think an action is being executed asynchronously which is trying to transform a file from text/plain to image/png. While doing that, it is not able to find the content transformer. Because of which exception is being thrown (RenditionServiceException).

RenditionService is mainly used for,
1. reformatted content (a transformation from one MIME-type to another)
2. rescaled images (including thumbnails)
3. the output of a Freemarker or XSLT template.

For this it uses complex transformation(text/plain to application/pdf to image/png). It does that using Direct Open Office Connection(to transform into pdf) and imagemagic(to transform into png).
Using action id and noderef you can find out what action is being executed on which node and also check the transformers configured correctly. I hope this info is useful.


Regards
Kumar

[briant]

After upgrading from 5.0.c to 5.1 (community 201605-GA) I received the exact same (or rather near identical) experience.  In my case, the GUIDs are different but the error messages are otherwise so similar that I believe the root cause to be identical.

Note: I have not solved the root cause because it has not re-occurred - my belief (unverified) is that it is an issue when the upgrade scripts have yet to be run, but the new wars have been deployed and the system is starting up the very first time with these new wars.

Just to repeat that: these errors only occurred the once on the first startup after the new wars were put in place.

In my case, the "template" for the errors was:

2016-11-15 17:31:20,054 ERROR [repo.action.AsynchronousActionExecutionQueueImpl] [defaultAsyncAction1] Failed to execute asynchronous action: Action[ id=_THE_GUID_, node=workspace://SpacesStore/_THE_GUID_ ]: 10150001 Transformer for 'text/plain' source mime type and 'image/png' target mime type was not found. Operation can't be performed

This error repeated 37 times (identical to OP experience), with the same action id/node _THE_GUID_ in every message.  My value for _THE_GUID_ was vastly different from the OP's (which is to be expected).

A search for that GUID turned up only 1 initial result - which is the parent to the other 20 actions.

The timestamps and "defaultAsyncAction1" were the only variation in these log lines - with the 'action number' ranging from 1-20 in no particular order: 1,15,10,9,11,8,7,5,6,13,17,12,1,2,8,5,11,9,7,15,20,4,3,10,19,18,16,14,5,11,13,8,1,17,12,2,6

Interestingly, some were repeatedly tried, others were not. ie: defaultAsyncAction1 was tried 3 times, but defaultAsyncAction20 was only tried the once.  Not sure of the logic driving that, perhaps the async nature of the implementation.

At the end of these 37 error messages, the upgrade scripts ran and made changes to the system.  Restarting alfresco did not produce these errors again; and it has not re-occurred since.

ie: immediately after the last of the 37 errors, the upgrade scripts ran (only included below for reference):

2016-11-15 17:31:25,176 INFO [patch.impl.AddUnmovableAspectToSitesPatch] [localhost-startStop-1] AddUnmovableAspectToSitesPatch: Commencing batch of 5 entries
2016-11-15 17:31:25,617 INFO [patch.impl.AddUnmovableAspectToSitesPatch] [localhost-startStop-1] AddUnmovableAspectToSitesPatch: Processed 5 entries out of 5. 100% complete. Rate: 11 per second. 0 failures detected.
2016-11-15 17:31:25,618 INFO [patch.impl.AddUnmovableAspectToSitesPatch] [localhost-startStop-1] AddUnmovableAspectToSitesPatch: Completed batch of 5 entries
2016-11-15 17:31:25,639 INFO [admin.patch.PatchExecuter] [localhost-startStop-1] Applying patch 'patch.deleteClassifibleAspectForFailedThumbnail' (Deletes 'cm:generalclassifiable' aspect and associated properties for
nodes of 'cm:failedThumbnail' type).
2016-11-15 17:31:25,667 INFO [admin.patch.PatchExecuter] [localhost-startStop-1] Applying patch 'patch.downloadsFolder' (Ensures the Syste Downloads folder exists.).
2016-11-15 17:31:25,699 INFO [admin.patch.PatchExecuter] [localhost-startStop-1] Applying patch 'patch.fixPersonSizeCurrentType' (Fix type of cm:sizeCurrent property.).
2016-11-15 17:31:25,731 INFO [admin.patch.PatchExecuter] [localhost-startStop-1] Applying patch 'patch.alfrescoModelAdministrators' (Adds the 'GROUP_ALFRESCO_MODEL_ADMINISTRATORS' group).
2016-11-15 17:31:26,006 INFO [admin.patch.PatchExecuter] [localhost-startStop-1] Applying patch 'patch.addInviteAddDirectEmailTemplates' (Adds the email templates for the add-direct invite flow).
2016-11-15 17:31:26,395 INFO [admin.patch.PatchExecuter] [localhost-startStop-1] Applying patch 'patch.imapSpacesTemplates4' (Replaces content templates for IMAP clients).
2016-11-15 17:31:28,114 INFO [admin.patch.PatchExecuter] [localhost-startStop-1] Applying patch 'patch.addSurfConfigFolders' (Adds 'cm:extensions' and 'cm:module-deployments' folders into surf-config folder.).
2016-11-15 17:31:28,441 INFO [admin.patch.PatchExecuter] [localhost-startStop-1] Applying patch 'patch.spacesBootstrapSmartTemplatesFolder' (Adds Smart Templates Folder in Data Dictionary.).
2016-11-15 17:31:28,604 INFO [admin.patch.PatchExecuter] [localhost-startStop-1] Applying patch 'patch.spacesBootstrapSmartFolderExample' (Adds smartFoldersExample.json file in Smart Templates Folder.).
2016-11-15 17:31:28,816 INFO [admin.patch.PatchExecuter] [localhost-startStop-1] Applying patch 'patch.spacesBootstrapSmartDownloadFolder' (Adds Smart Download Folder in Data Dictionary.).
2016-11-15 17:31:28,968 INFO [admin.patch.PatchExecuter] [localhost-startStop-1]
=== Applied patch ===
ID: patch.db-V4.2-migrate-locale-multilingual
RESULT:
Script completed
=====================================
2016-11-15 17:31:28,968 INFO [admin.patch.PatchExecuter] [localhost-startStop-1]
=== Applied patch ===
ID: patch.db-V4.1-AuthorizationTables

RESULT:
Script completed
=====================================
2016-11-15 17:31:28,968 INFO [admin.patch.PatchExecuter] [localhost-startStop-1]
=== Applied patch ===
ID: patch.db-v4.2-migrate-activiti-workflows
RESULT:
Script completed
=====================================
2016-11-15 17:31:28,968 INFO [admin.patch.PatchExecuter] [localhost-startStop-1]
=== Applied patch ===
ID: patch.db-V5.0-update-activiti-default-timestamp-column
RESULT:
Script completed
=====================================
2016-11-15 17:31:28,969 INFO [admin.patch.PatchExecuter] [localhost-startStop-1]
=== Applied patch ===
ID: patch.db-V5.0-activiti-correct-tenant-id-MSSQL
RESULT:
Script completed
=====================================
2016-11-15 17:31:28,969 INFO [admin.patch.PatchExecuter] [localhost-startStop-1]
=== Applied patch ===
ID: patch.fixWebscriptTemplate
RESULT:
Fixed template was updated.
=====================================
2016-11-15 17:31:28,970 INFO [admin.patch.PatchExecuter] [localhost-startStop-1]
=== Applied patch ===
ID: patch.imapSpacesTemplates3
RESULT:
Imported view into bootstrap location: /app:company_home/app:dictionary/app:imap_configs/app:imap_templates (workspace://SpacesStore/8cf8b3a8-215c-44d0-8e17-3c9b66d2c2ef)
=====================================

2016-11-15 17:31:28,970 INFO [admin.patch.PatchExecuter] [localhost-startStop-1]
=== Applied patch ===
ID: patch.db-V5.0-remove-columns-after-upgrade-to-activiti-5.16.2
RESULT:
Script completed
=====================================
2016-11-15 17:31:28,971 INFO [admin.patch.PatchExecuter] [localhost-startStop-1]
=== Applied patch ===
ID: patch.addUnmovableAspect
RESULT:
Unmovable aspect has been added to sites.
=====================================
2016-11-15 17:31:28,971 INFO [admin.patch.PatchExecuter] [localhost-startStop-1]
=== Applied patch ===
ID: patch.deleteClassifibleAspectForFailedThumbnail
RESULT:
Successfully deleted the '{http://www.alfresco.org/model/content/1.0}generalclassifiable' aspect for the nodes of '{http://www.alfresco.org/model/content/1.0}failedThumbnail' type.
=====================================
2016-11-15 17:31:28,971 INFO [admin.patch.PatchExecuter] [localhost-startStop-1]
=== Applied patch ===
ID: patch.downloadsFolder
RESULT:
Bootstrap location already exists: /sys:system/sys:downloads
=====================================
2016-11-15 17:31:28,972 INFO [admin.patch.PatchExecuter] [localhost-startStop-1]
=== Applied patch ===
ID: patch.fixPersonSizeCurrentType
RESULT:
Fixing sizeCurrent type.
Fixed 0 sizeCurrent properties.
=====================================

2016-11-15 17:31:28,972 INFO [admin.patch.PatchExecuter] [localhost-startStop-1]
=== Applied patch ===
ID: patch.alfrescoModelAdministrators
RESULT:
Adding group authority.
Successfully added group authority: GROUP_ALFRESCO_MODEL_ADMINISTRATORS
=====================================
2016-11-15 17:31:28,972 INFO [admin.patch.PatchExecuter] [localhost-startStop-1]
=== Applied patch ===
ID: patch.addInviteAddDirectEmailTemplates
RESULT:
Imported view into bootstrap location: /app:company_home/app:dictionary/app:email_templates/cm:invite (workspace://SpacesStore/8cf8b3a8-215c-44d0-8e17-3c9b66d2c2ef)
=====================================
2016-11-15 17:31:28,972 INFO [admin.patch.PatchExecuter] [localhost-startStop-1]
=== Applied patch ===
ID: patch.db-V5.1-upgrade-to-activiti-5.19.0
RESULT:
Script completed
=====================================
2016-11-15 17:31:28,972 INFO [admin.patch.PatchExecuter] [localhost-startStop-1]
=== Applied patch ===
ID: patch.imapSpacesTemplates4
RESULT:
Imported view into bootstrap location: /app:company_home/app:dictionary/app:imap_configs/app:imap_templates (workspace://SpacesStore/8cf8b3a8-215c-44d0-8e17-3c9b66d2c2ef)
=====================================
2016-11-15 17:31:28,973 INFO [admin.patch.PatchExecuter] [localhost-startStop-1]
=== Applied patch ===
ID: patch.addSurfConfigFolders
RESULT:
Adding surf-config children.
Successfully added 'extensions' folders and set the required permissions for 'extensions' folders.
Skipped 'module-deployments' folders as they already exist, but set the required permissions for 'module-deployments' folders.
=====================================

2016-11-15 17:31:28,973 INFO [admin.patch.PatchExecuter] [localhost-startStop-1]
=== Applied patch ===
ID: patch.spacesBootstrapSmartDownloadFolder
RESULT:
Imported view into bootstrap location: /app:company_home/app:dictionary (workspace://SpacesStore/8cf8b3a8-215c-44d0-8e17-3c9b66d2c2ef)
=====================================
2016-11-15 17:31:28,973 INFO [admin.patch.PatchExecuter] [localhost-startStop-1]
=== Applied patch ===
ID: patch.spacesBootstrapSmartTemplatesFolder
RESULT:
Imported view into bootstrap location: /app:company_home/app:dictionary (workspace://SpacesStore/8cf8b3a8-215c-44d0-8e17-3c9b66d2c2ef)
=====================================
2016-11-15 17:31:28,973 INFO [admin.patch.PatchExecuter] [localhost-startStop-1]
=== Applied patch ===
ID: patch.spacesBootstrapSmartFolderExample
RESULT:
Imported view into bootstrap location: /app:company_home/app:dictionary/app:smart_folders (workspace://SpacesStore/8cf8b3a8-215c-44d0-8e17-3c9b66d2c2ef)
=====================================
2016-11-15 17:31:28,997 INFO [repo.module.ModuleServiceImpl] [localhost-startStop-1] Found 0 module package(s).
2016-11-15 17:31:29,005 INFO [management.subsystems.ChildApplicationContextFactory] [localhost-startStop-1] Starting 'fileServers' subsystem, ID: [fileServers, default]

{normal startup continues from this point onwards - the error does not occur again}

Edits: typos

[p_bodnar]

Thanks for sharing, I have probably the very same experience with upgrade to Alfresco 5.2. So hopefully this is really just a one-time issue which occurs only on the very 1st Alfresco run.