Broken renditions for AI, EPS and PDF file extensions

Avatar

Avatar

manojk976698

Avatar

manojk976698

manojk976698

12-09-2019

Hi All,

     We are using Project API in our project. Whenever we upload AI, EPS and PDF files on our custom page and if any of these extension file set as cover then files get      stored in AEM DAM successfully. But the renditions that are getting generated by workflow for these files are broken. Please let me know in case you need more      clarification on this.

1832491_pastedImage_1.png

Thanks and Regards

Manoj Kumar

Replies

Avatar

Avatar

aemmarc

Employee

Avatar

aemmarc

Employee

aemmarc
Employee

12-09-2019

You'll need to leverage either the Image Transcoding Library, Camera RAW, ImageMagick, or PDF Rasterizer packages and make the necessary DAM Update Asset workflow model changes to process these file types.

Avatar

Avatar

manojk976698

Avatar

manojk976698

manojk976698

12-09-2019

ImageMagick is installed on server and PDF/AI Rasterizer  workflow step is modified. After that also i am getting broken renditions for above mentioned files when set as cover.

Avatar

Avatar

Vish_dhaliwal

Employee

Avatar

Vish_dhaliwal

Employee

Vish_dhaliwal
Employee

12-09-2019

Maybe the ImageMagick is not set up properly. Do you see broken images while using on the pages or also in the asset details.(assetdetails.html/content/dam).

Did you see any error in the logs while uploading the EPS/PDF assets to AEM?

Regards,

Vishu

Avatar

Avatar

jbrar

Employee

Avatar

jbrar

Employee

jbrar
Employee

12-09-2019

You can setup a DEBUG logger on the following classes to see which step in the workflow is failing:

com.adobe.granite.workflow.core.WorkflowSessionImpl

com.adobe.granite.workflow.core.job

If you see an error similar to below, imagemagick is failing:

10.04.2019 18:45:21.263 *ERROR* [JobHandler: /var/workflow/instances/server0/2019-04-10/update_asset_6:/content/dam/adobe-test/logo_wday 2.eps/jcr:content/renditions/original] com.day.cq.dam.core.process.CommandLineProcess execute: failed to execute command [[convert, -define, png:size=319x319, logo_wday%202.eps, -thumbnail, 319x319, cq5dam.thumbnail.319.319.png]] for asset [/content/dam/adobe-test/logo_wday 2.eps]:

org.apache.commons.exec.ExecuteException: Process exited with an error: 1 (Exit value: 1)

  at org.apache.commons.exec.DefaultExecutor.executeInternal(DefaultExecutor.java:404) [org.apache.commons.exec:1.3.0]

  at org.apache.commons.exec.DefaultExecutor.execute(DefaultExecutor.java:166) [org.apache.commons.exec:1.3.0]

  at org.apache.commons.exec.DefaultExecutor.execute(DefaultExecutor.java:153) [org.apache.commons.exec:1.3.0]

  at com.day.cq.dam.core.process.CommandLineProcess$2.run(CommandLineProcess.java:275) [com.day.cq.dam.cq-dam-core:5.11.326]

  at com.day.cq.dam.core.impl.jmx.AssetUpdateMonitorImpl$UpdateStep.checkAndRun(AssetUpdateMonitorImpl.java:978) [com.day.cq.dam.cq-dam-core:5.11.326]

  at com.day.cq.dam.core.process.CommandLineProcess.execute(CommandLineProcess.java:193) [com.day.cq.dam.cq-dam-core:5.11.326]

  at com.day.cq.workflow.compatibility.CQWorkflowProcessRunner.execute(CQWorkflowProcessRunner.java:93) [com.day.cq.workflow.cq-workflow-impl:6.2.12]

  at com.adobe.granite.workflow.core.job.HandlerBase.executeProcess(HandlerBase.java:194) [com.adobe.granite.workflow.core:2.0.168.CQ640-B0006]

  at com.adobe.granite.workflow.core.job.JobHandler.process(JobHandler.java:261) [com.adobe.granite.workflow.core:2.0.168.CQ640-B0006]

  at org.apache.sling.event.impl.jobs.JobConsumerManager$JobConsumerWrapper.process(JobConsumerManager.java:502) [org.apache.sling.event:4.2.12]

  at org.apache.sling.event.impl.jobs.queues.JobQueueImpl.startJob(JobQueueImpl.java:293) [org.apache.sling.event:4.2.12]

  at org.apache.sling.event.impl.jobs.queues.JobQueueImpl.access$100(JobQueueImpl.java:60) [org.apache.sling.event:4.2.12]

  at org.apache.sling.event.impl.jobs.queues.JobQueueImpl$1.run(JobQueueImpl.java:229) [org.apache.sling.event:4.2.12]

  at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)

  at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)

  at java.lang.Thread.run(Thread.java:748)

When using third party software like ImageMagick, AEM is just running commands to generate thumbnails, as you saw in the error above where AEM just ran the "convert" command on the VM to get the renditions. As next steps, you can try to run the convert command directly on the VM outside of AEM as below:

  • The user running the AEM is able to run the "convert" command. Make sure the path variable is configured properly.
  • Try ssh using the same user, copy an EPS file and run the following command on the Unix VM:
    • convert -define jpeg:size=319x319 <path to EPS file> -thumbnail 319x319 cq5dam.thumbnail.319.319.png
  • This command should create thumbnails. If not creating, we have an issue with Imagemagick.

Avatar

Avatar

manojk976698

Avatar

manojk976698

manojk976698

12-09-2019

Ques :-  Do you see broken images while using on the pages or also in the asset details.(assetdetails.html/content/dam)?

  ---->   When uploading AI, EPS and PDF on custom page we get broken image But after uploading those to AEM DAM dynamically through codes or manually we are able to see the thumbnails. We are facing problem when we set AI, EPS and PDF as cover.

-------------------------------------------------------------------------------------------------------------------------------------------------------------------------

Ques :- Did you see any error in the logs while uploading the EPS/PDF assets to AEM?

----->   Please have a look on the below logs i found when we make AI extension file as cover.

-------------------------------------------------------------------------------------------------------------------------------------------------------------------------

13.09.2019 06:21:10.866 *INFO* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_806:/content/dam/projects/aicoverwithotherassets/cover/jcr:content/renditions/original] com.adobe.granite.asset.core.impl.metadata.xmpjcr.XmpToJcrVisitor No custom schema available, using default XMP schema service

13.09.2019 06:21:10.900 *INFO* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_806:/content/dam/projects/aicoverwithotherassets/cover/jcr:content/renditions/original] com.day.cq.dam.video.FFMpegThumbnailProcess execute: asset [/content/dam/projects/aicoverwithotherassets/cover] is not of a video mime type, asset ignored.

13.09.2019 06:21:10.901 *INFO* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_806:/content/dam/projects/aicoverwithotherassets/cover/jcr:content/renditions/original] com.day.cq.dam.core.process.CommandLineProcess execute: mime type [image/png] of asset [/content/dam/projects/aicoverwithotherassets/cover] is not in list of accepted mime types [[image/eps, image/x-eps, application/postscript, application/eps, application/x-eps]], ignoring.

13.09.2019 06:21:10.902 *INFO* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_806:/content/dam/projects/aicoverwithotherassets/cover/jcr:content/renditions/original] com.day.cq.dam.video.FFMpegTranscodeProcess execute: asset [/content/dam/projects/aicoverwithotherassets/cover] is not of a video mime type, asset ignored.

13.09.2019 06:21:10.942 *INFO* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_806:/content/dam/projects/aicoverwithotherassets/cover/jcr:content/renditions/original] com.day.cq.dam.scene7.impl.S7ConfigResolverImpl Get S7Config null for resource /content/dam/projects/aicoverwithotherassets/cover

13.09.2019 06:21:10.942 *INFO* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_806:/content/dam/projects/aicoverwithotherassets/cover/jcr:content/renditions/original] com.day.cq.dam.scene7.impl.process.Scene7UploadProcess Skip S7 upload due to cannot find configuration for file: /content/dam/projects/aicoverwithotherassets/cover

13.09.2019 06:21:10.967 *INFO* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_806:/content/dam/projects/aicoverwithotherassets/cover/jcr:content/renditions/original] com.day.cq.dam.core.impl.RenditionMakerImpl generated rendition: /content/dam/projects/aicoverwithotherassets/cover/jcr:content/renditions/cq5dam.thumbnail.140.100.png

13.09.2019 06:21:10.973 *INFO* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_806:/content/dam/projects/aicoverwithotherassets/cover/jcr:content/renditions/original] com.day.cq.dam.core.impl.RenditionMakerImpl generated rendition: /content/dam/projects/aicoverwithotherassets/cover/jcr:content/renditions/cq5dam.thumbnail.48.48.png

13.09.2019 06:21:11.034 *INFO* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_806:/content/dam/projects/aicoverwithotherassets/cover/jcr:content/renditions/original] com.day.cq.dam.core.impl.RenditionMakerImpl generated rendition: /content/dam/projects/aicoverwithotherassets/cover/jcr:content/renditions/cq5dam.thumbnail.319.319.png

13.09.2019 06:21:11.138 *INFO* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_806:/content/dam/projects/aicoverwithotherassets/cover/jcr:content/renditions/original] com.day.cq.dam.core.impl.RenditionMakerImpl generated rendition: /content/dam/projects/aicoverwithotherassets/cover/jcr:content/renditions/cq5dam.web.1280.1280.png

13.09.2019 06:21:11.194 *INFO* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_806:/content/dam/projects/aicoverwithotherassets/cover/jcr:content/renditions/original] com.day.cq.dam.pim.impl.sourcing.upload.process.ProductAssetsUploadProcess payload path :/content/dam/projects/aicoverwithotherassets/cover/jcr:content/renditions/original.

Avatar

Avatar

manojk976698

Avatar

manojk976698

manojk976698

13-09-2019

No error found in workflow. I think ImageMagic is working fine.Please have look on below logs(workflow).

13.09.2019 06:03:14.595 *INFO* [OsgiInstallerImpl] com.adobe.granite.workflow.core.job.TimeoutHandler Deactivate com.adobe.granite.workflow.core.job.TimeoutHandler@7892462a

13.09.2019 06:03:14.643 *INFO* [OsgiInstallerImpl] com.adobe.granite.workflow.core.job.JobHandler Deactivate com.adobe.granite.workflow.core.job.JobHandler@44a48d4f

13.09.2019 06:03:14.660 *INFO* [OsgiInstallerImpl] com.adobe.granite.workflow.core.job.ExternalProcessJobHandler Deactivate com.adobe.granite.workflow.core.job.ExternalProcessJobHandler@7834e411

13.09.2019 06:03:14.983 *INFO* [OsgiInstallerImpl] com.adobe.granite.workflow.core.job.ExternalProcessPollingHandler Deactivate com.adobe.granite.workflow.core.job.ExternalProcessPollingHandler@6c73ed4f

13.09.2019 06:03:19.831 *INFO* [OsgiInstallerImpl] com.adobe.granite.workflow.core.job.TimeoutHandler Activate com.adobe.granite.workflow.core.job.TimeoutHandler@4f0a60dc

13.09.2019 06:03:19.856 *INFO* [OsgiInstallerImpl] com.adobe.granite.workflow.core.job.ExternalProcessJobHandler Activate com.adobe.granite.workflow.core.job.ExternalProcessJobHandler@dedb442

13.09.2019 06:03:19.856 *INFO* [OsgiInstallerImpl] com.adobe.granite.workflow.core.job.JobHandler Activate com.adobe.granite.workflow.core.job.JobHandler@5da09a27

13.09.2019 06:03:20.586 *INFO* [OsgiInstallerImpl] com.adobe.granite.workflow.core.job.ExternalProcessPollingHandler Activate com.adobe.granite.workflow.core.job.ExternalProcessPollingHandler@2ecd4ad0

13.09.2019 06:12:03.666 *INFO* [OsgiInstallerImpl] com.adobe.granite.workflow.core.job.TimeoutHandler Deactivate com.adobe.granite.workflow.core.job.TimeoutHandler@4f0a60dc

13.09.2019 06:12:03.691 *INFO* [OsgiInstallerImpl] com.adobe.granite.workflow.core.job.JobHandler Deactivate com.adobe.granite.workflow.core.job.JobHandler@5da09a27

13.09.2019 06:12:03.714 *INFO* [OsgiInstallerImpl] com.adobe.granite.workflow.core.job.ExternalProcessJobHandler Deactivate com.adobe.granite.workflow.core.job.ExternalProcessJobHandler@dedb442

13.09.2019 06:12:03.975 *INFO* [OsgiInstallerImpl] com.adobe.granite.workflow.core.job.ExternalProcessPollingHandler Deactivate com.adobe.granite.workflow.core.job.ExternalProcessPollingHandler@2ecd4ad0

13.09.2019 06:12:07.776 *INFO* [OsgiInstallerImpl] com.adobe.granite.workflow.core.job.TimeoutHandler Activate com.adobe.granite.workflow.core.job.TimeoutHandler@e74d35c

13.09.2019 06:12:07.838 *INFO* [OsgiInstallerImpl] com.adobe.granite.workflow.core.job.ExternalProcessJobHandler Activate com.adobe.granite.workflow.core.job.ExternalProcessJobHandler@2ce97992

13.09.2019 06:12:07.838 *INFO* [OsgiInstallerImpl] com.adobe.granite.workflow.core.job.JobHandler Activate com.adobe.granite.workflow.core.job.JobHandler@5c136325

13.09.2019 06:12:08.692 *INFO* [OsgiInstallerImpl] com.adobe.granite.workflow.core.job.ExternalProcessPollingHandler Activate com.adobe.granite.workflow.core.job.ExternalProcessPollingHandler@1b20261d

13.09.2019 06:13:00.684 *DEBUG* [Workflow Starter Thread] com.adobe.granite.workflow.core.WorkflowSessionImpl created transient job id: 2019/9/13/6/13/17e1ac2a-cf62-4115-8d9f-6469f028699f_809

13.09.2019 06:13:00.713 *DEBUG* [49.249.236.30 [1568355180433] POST /bin/mls/crproject HTTP/1.1] com.adobe.granite.workflow.core.WorkflowSessionImpl getModel for id '/var/workflow/models/ProjectapprovalworkFlow' for mlsCRUD took 1 ms

13.09.2019 06:13:00.734 *DEBUG* [sling-threadpool-befce717-30b3-48e8-b1ca-c8909e0383e8-(apache-sling-job-thread-pool)-13-Granite Transient Workflow Queue(com/adobe/granite/workflow/transient/job/var/workflow/models/dam/update_asset)] com.adobe.granite.workflow.core.job.JobHandler ===== isTransient for com/adobe/granite/workflow/transient/job/var/workflow/models/dam/update_asset - value: true

13.09.2019 06:13:00.737 *DEBUG* [sling-threadpool-befce717-30b3-48e8-b1ca-c8909e0383e8-(apache-sling-job-thread-pool)-13-Granite Transient Workflow Queue(com/adobe/granite/workflow/transient/job/var/workflow/models/dam/update_asset)] com.adobe.granite.workflow.core.job.JobHandler ====== Handle transient

13.09.2019 06:13:00.739 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_803:/content/dam/projects/aicover/cover/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler Start processing: /var/workflow/instances/server0/2019-07-31_1/update_asset_803:/content/dam/projects/aicover/cover/jcr:content/renditions/original

13.09.2019 06:13:00.740 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_803:/content/dam/projects/aicover/cover/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler Start process execution of com.day.cq.dam.core.process.GateKeeperProcess for: VolatileWorkItem_node1_var_workflow_instances_server0_2019-07-31_1_update_asset_803

13.09.2019 06:13:00.745 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_803:/content/dam/projects/aicover/cover/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler Finished process execution of com.day.cq.dam.core.process.GateKeeperProcess for: VolatileWorkItem_node1_var_workflow_instances_server0_2019-07-31_1_update_asset_803

13.09.2019 06:13:00.745 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_803:/content/dam/projects/aicover/cover/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler start archiving

13.09.2019 06:13:00.749 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_803:/content/dam/projects/aicover/cover/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler Start process execution of com.day.cq.dam.core.process.MetadataProcessorProcess for: VolatileWorkItem_node2_var_workflow_instances_server0_2019-07-31_1_update_asset_803

13.09.2019 06:13:00.774 *DEBUG* [49.249.236.30 [1568355180433] POST /bin/mls/crproject HTTP/1.1] com.adobe.granite.workflow.core.job.WorkflowJobUtils calling createTimeoutJob for workitem /var/workflow/instances/server0/2019-07-31_1/ProjectapprovalworkFlow_802/workItems/node1_var_workflow_instances_server0_2019-07-31_1_ProjectapprovalworkFlow_802

13.09.2019 06:13:00.779 *DEBUG* [49.249.236.30 [1568355180433] POST /bin/mls/crproject HTTP/1.1] com.adobe.granite.workflow.core.WorkflowSessionImpl Workflow instance started with model: /var/workflow/models/ProjectapprovalworkFlow and ID: /var/workflow/instances/server0/2019-07-31_1/ProjectapprovalworkFlow_802 for payload: /content/dam/projects/aicover

13.09.2019 06:13:00.811 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_803:/content/dam/projects/aicover/cover/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler Finished process execution of com.day.cq.dam.core.process.MetadataProcessorProcess for: VolatileWorkItem_node2_var_workflow_instances_server0_2019-07-31_1_update_asset_803

13.09.2019 06:13:00.811 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_803:/content/dam/projects/aicover/cover/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler start archiving

13.09.2019 06:13:00.812 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_803:/content/dam/projects/aicover/cover/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler Start process execution of com.day.cq.dam.video.FFMpegThumbnailProcess for: VolatileWorkItem_node3_var_workflow_instances_server0_2019-07-31_1_update_asset_803

13.09.2019 06:13:00.812 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_803:/content/dam/projects/aicover/cover/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler Finished process execution of com.day.cq.dam.video.FFMpegThumbnailProcess for: VolatileWorkItem_node3_var_workflow_instances_server0_2019-07-31_1_update_asset_803

13.09.2019 06:13:00.812 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_803:/content/dam/projects/aicover/cover/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler start archiving

13.09.2019 06:13:00.813 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_803:/content/dam/projects/aicover/cover/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler Start process execution of com.day.cq.dam.core.process.CommandLineProcess for: VolatileWorkItem_node4_var_workflow_instances_server0_2019-07-31_1_update_asset_803

13.09.2019 06:13:00.813 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_803:/content/dam/projects/aicover/cover/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler Finished process execution of com.day.cq.dam.core.process.CommandLineProcess for: VolatileWorkItem_node4_var_workflow_instances_server0_2019-07-31_1_update_asset_803

13.09.2019 06:13:00.813 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_803:/content/dam/projects/aicover/cover/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler start archiving

13.09.2019 06:13:00.814 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_803:/content/dam/projects/aicover/cover/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler Start process execution of com.day.cq.dam.video.FFMpegTranscodeProcess for: VolatileWorkItem_node5_var_workflow_instances_server0_2019-07-31_1_update_asset_803

13.09.2019 06:13:00.814 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_803:/content/dam/projects/aicover/cover/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler Finished process execution of com.day.cq.dam.video.FFMpegTranscodeProcess for: VolatileWorkItem_node5_var_workflow_instances_server0_2019-07-31_1_update_asset_803

13.09.2019 06:13:00.814 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_803:/content/dam/projects/aicover/cover/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler start archiving

13.09.2019 06:13:00.815 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_803:/content/dam/projects/aicover/cover/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler looking for WorkflowExternalProcess Proxy

13.09.2019 06:13:00.815 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_803:/content/dam/projects/aicover/cover/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler executing external process with work item VolatileWorkItem_node6_var_workflow_instances_server0_2019-07-31_1_update_asset_803

13.09.2019 06:13:00.815 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_803:/content/dam/projects/aicover/cover/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler external process created with id null

13.09.2019 06:13:00.815 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_803:/content/dam/projects/aicover/cover/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler start archiving

13.09.2019 06:13:00.816 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_803:/content/dam/projects/aicover/cover/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler Start process execution of com.day.cq.dam.indd.process.INDDPageExtractProcess for: VolatileWorkItem_node7_var_workflow_instances_server0_2019-07-31_1_update_asset_803

13.09.2019 06:13:00.817 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_803:/content/dam/projects/aicover/cover/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler Finished process execution of com.day.cq.dam.indd.process.INDDPageExtractProcess for: VolatileWorkItem_node7_var_workflow_instances_server0_2019-07-31_1_update_asset_803

13.09.2019 06:13:00.817 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_803:/content/dam/projects/aicover/cover/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler start archiving

13.09.2019 06:13:00.817 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_803:/content/dam/projects/aicover/cover/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler Start process execution of com.day.cq.dam.core.process.CreatePdfPreviewProcess for: VolatileWorkItem_node8_var_workflow_instances_server0_2019-07-31_1_update_asset_803

13.09.2019 06:13:00.818 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_803:/content/dam/projects/aicover/cover/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler Finished process execution of com.day.cq.dam.core.process.CreatePdfPreviewProcess for: VolatileWorkItem_node8_var_workflow_instances_server0_2019-07-31_1_update_asset_803

13.09.2019 06:13:00.818 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_803:/content/dam/projects/aicover/cover/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler start archiving

13.09.2019 06:13:00.819 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_803:/content/dam/projects/aicover/cover/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler Start process execution of com.day.cq.dam.core.process.CreateSubAssetsProcess for: VolatileWorkItem_node9_var_workflow_instances_server0_2019-07-31_1_update_asset_803

13.09.2019 06:13:00.819 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_803:/content/dam/projects/aicover/cover/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler Finished process execution of com.day.cq.dam.core.process.CreateSubAssetsProcess for: VolatileWorkItem_node9_var_workflow_instances_server0_2019-07-31_1_update_asset_803

13.09.2019 06:13:00.819 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_803:/content/dam/projects/aicover/cover/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler start archiving

13.09.2019 06:13:00.861 *DEBUG* [Workflow Starter Thread] com.adobe.granite.workflow.core.WorkflowSessionImpl created transient job id: 2019/9/13/6/13/17e1ac2a-cf62-4115-8d9f-6469f028699f_810

13.09.2019 06:13:00.866 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_803:/content/dam/projects/aicover/cover/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler Start process execution of com.adobe.cq.dam.dm.process.workflow.DMImageProcess for: VolatileWorkItem_node10_var_workflow_instances_server0_2019-07-31_1_update_asset_803

13.09.2019 06:13:00.866 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_803:/content/dam/projects/aicover/cover/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler Finished process execution of com.adobe.cq.dam.dm.process.workflow.DMImageProcess for: VolatileWorkItem_node10_var_workflow_instances_server0_2019-07-31_1_update_asset_803

13.09.2019 06:13:00.866 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_803:/content/dam/projects/aicover/cover/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler start archiving

13.09.2019 06:13:00.867 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_803:/content/dam/projects/aicover/cover/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler looking for WorkflowExternalProcess Proxy

13.09.2019 06:13:00.867 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_803:/content/dam/projects/aicover/cover/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler executing external process with work item VolatileWorkItem_node11_var_workflow_instances_server0_2019-07-31_1_update_asset_803

13.09.2019 06:13:00.882 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_803:/content/dam/projects/aicover/cover/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler external process created with id null

13.09.2019 06:13:00.882 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_803:/content/dam/projects/aicover/cover/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler start archiving

13.09.2019 06:13:00.883 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_803:/content/dam/projects/aicover/cover/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler Start process execution of com.day.cq.dam.core.process.ThumbnailProcess for: VolatileWorkItem_node12_var_workflow_instances_server0_2019-07-31_1_update_asset_803

13.09.2019 06:13:00.948 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_803:/content/dam/projects/aicover/cover/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler Finished process execution of com.day.cq.dam.core.process.ThumbnailProcess for: VolatileWorkItem_node12_var_workflow_instances_server0_2019-07-31_1_update_asset_803

13.09.2019 06:13:00.948 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_803:/content/dam/projects/aicover/cover/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler start archiving

13.09.2019 06:13:00.950 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_803:/content/dam/projects/aicover/cover/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler Start process execution of com.day.cq.dam.pim.impl.sourcing.upload.process.ProductAssetsUploadProcess for: VolatileWorkItem_node13_var_workflow_instances_server0_2019-07-31_1_update_asset_803

13.09.2019 06:13:00.952 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_803:/content/dam/projects/aicover/cover/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler Finished process execution of com.day.cq.dam.pim.impl.sourcing.upload.process.ProductAssetsUploadProcess for: VolatileWorkItem_node13_var_workflow_instances_server0_2019-07-31_1_update_asset_803

13.09.2019 06:13:00.952 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_803:/content/dam/projects/aicover/cover/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler start archiving

13.09.2019 06:13:00.965 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_803:/content/dam/projects/aicover/cover/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler Start process execution of com.day.cq.dam.core.impl.process.DamUpdateAssetWorkflowCompletedProcess for: VolatileWorkItem_node14_var_workflow_instances_server0_2019-07-31_1_update_asset_803

13.09.2019 06:13:00.978 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_803:/content/dam/projects/aicover/cover/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler Finished process execution of com.day.cq.dam.core.impl.process.DamUpdateAssetWorkflowCompletedProcess for: VolatileWorkItem_node14_var_workflow_instances_server0_2019-07-31_1_update_asset_803

13.09.2019 06:13:00.978 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_803:/content/dam/projects/aicover/cover/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler start archiving

13.09.2019 06:13:00.979 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_803:/content/dam/projects/aicover/cover/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler Start process execution of com.day.cq.dam.core.impl.process.SendTransientWorkflowCompletedEmailProcess for: VolatileWorkItem_node15_var_workflow_instances_server0_2019-07-31_1_update_asset_803

13.09.2019 06:13:00.979 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_803:/content/dam/projects/aicover/cover/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler Finished process execution of com.day.cq.dam.core.impl.process.SendTransientWorkflowCompletedEmailProcess for: VolatileWorkItem_node15_var_workflow_instances_server0_2019-07-31_1_update_asset_803

13.09.2019 06:13:00.980 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_803:/content/dam/projects/aicover/cover/jcr:content/renditions/original] com.adobe.granite.workflow.core.WorkflowSessionImpl Attempting to complete work item with ID: VolatileWorkItem_node15_var_workflow_instances_server0_2019-07-31_1_update_asset_803

13.09.2019 06:13:00.987 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_803:/content/dam/projects/aicover/cover/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler Finished processing: /var/workflow/instances/server0/2019-07-31_1/update_asset_803:/content/dam/projects/aicover/cover/jcr:content/renditions/original(Complete execution took: 248ms)

13.09.2019 06:13:01.028 *DEBUG* [sling-threadpool-befce717-30b3-48e8-b1ca-c8909e0383e8-(apache-sling-job-thread-pool)-13-Granite Transient Workflow Queue(com/adobe/granite/workflow/transient/job/var/workflow/models/dam/update_asset)] com.adobe.granite.workflow.core.job.JobHandler ===== isTransient for com/adobe/granite/workflow/transient/job/var/workflow/models/dam/update_asset - value: true

13.09.2019 06:13:01.028 *DEBUG* [sling-threadpool-befce717-30b3-48e8-b1ca-c8909e0383e8-(apache-sling-job-thread-pool)-13-Granite Transient Workflow Queue(com/adobe/granite/workflow/transient/job/var/workflow/models/dam/update_asset)] com.adobe.granite.workflow.core.job.JobHandler ====== Handle transient

13.09.2019 06:13:01.030 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_804:/content/dam/projects/aicover/AI_test.ai/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler Start processing: /var/workflow/instances/server0/2019-07-31_1/update_asset_804:/content/dam/projects/aicover/AI_test.ai/jcr:content/renditions/original

13.09.2019 06:13:01.030 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_804:/content/dam/projects/aicover/AI_test.ai/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler Start process execution of com.day.cq.dam.core.process.GateKeeperProcess for: VolatileWorkItem_node1_var_workflow_instances_server0_2019-07-31_1_update_asset_804

13.09.2019 06:13:01.033 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_804:/content/dam/projects/aicover/AI_test.ai/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler Finished process execution of com.day.cq.dam.core.process.GateKeeperProcess for: VolatileWorkItem_node1_var_workflow_instances_server0_2019-07-31_1_update_asset_804

13.09.2019 06:13:01.033 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_804:/content/dam/projects/aicover/AI_test.ai/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler start archiving

13.09.2019 06:13:01.040 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_804:/content/dam/projects/aicover/AI_test.ai/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler Start process execution of com.day.cq.dam.core.process.MetadataProcessorProcess for: VolatileWorkItem_node2_var_workflow_instances_server0_2019-07-31_1_update_asset_804

13.09.2019 06:13:01.134 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_804:/content/dam/projects/aicover/AI_test.ai/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler Finished process execution of com.day.cq.dam.core.process.MetadataProcessorProcess for: VolatileWorkItem_node2_var_workflow_instances_server0_2019-07-31_1_update_asset_804

13.09.2019 06:13:01.134 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_804:/content/dam/projects/aicover/AI_test.ai/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler start archiving

13.09.2019 06:13:01.135 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_804:/content/dam/projects/aicover/AI_test.ai/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler Start process execution of com.day.cq.dam.video.FFMpegThumbnailProcess for: VolatileWorkItem_node3_var_workflow_instances_server0_2019-07-31_1_update_asset_804

13.09.2019 06:13:01.135 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_804:/content/dam/projects/aicover/AI_test.ai/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler Finished process execution of com.day.cq.dam.video.FFMpegThumbnailProcess for: VolatileWorkItem_node3_var_workflow_instances_server0_2019-07-31_1_update_asset_804

13.09.2019 06:13:01.135 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_804:/content/dam/projects/aicover/AI_test.ai/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler start archiving

13.09.2019 06:13:01.136 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_804:/content/dam/projects/aicover/AI_test.ai/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler Start process execution of com.day.cq.dam.core.process.CommandLineProcess for: VolatileWorkItem_node4_var_workflow_instances_server0_2019-07-31_1_update_asset_804

13.09.2019 06:13:02.304 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_804:/content/dam/projects/aicover/AI_test.ai/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler Finished process execution of com.day.cq.dam.core.process.CommandLineProcess for: VolatileWorkItem_node4_var_workflow_instances_server0_2019-07-31_1_update_asset_804

13.09.2019 06:13:02.304 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_804:/content/dam/projects/aicover/AI_test.ai/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler start archiving

13.09.2019 06:13:02.306 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_804:/content/dam/projects/aicover/AI_test.ai/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler Start process execution of com.day.cq.dam.video.FFMpegTranscodeProcess for: VolatileWorkItem_node5_var_workflow_instances_server0_2019-07-31_1_update_asset_804

13.09.2019 06:13:02.306 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_804:/content/dam/projects/aicover/AI_test.ai/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler Finished process execution of com.day.cq.dam.video.FFMpegTranscodeProcess for: VolatileWorkItem_node5_var_workflow_instances_server0_2019-07-31_1_update_asset_804

13.09.2019 06:13:02.306 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_804:/content/dam/projects/aicover/AI_test.ai/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler start archiving

13.09.2019 06:13:02.307 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_804:/content/dam/projects/aicover/AI_test.ai/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler looking for WorkflowExternalProcess Proxy

13.09.2019 06:13:02.307 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_804:/content/dam/projects/aicover/AI_test.ai/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler executing external process with work item VolatileWorkItem_node6_var_workflow_instances_server0_2019-07-31_1_update_asset_804

13.09.2019 06:13:02.321 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_804:/content/dam/projects/aicover/AI_test.ai/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler external process created with id null

13.09.2019 06:13:02.321 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_804:/content/dam/projects/aicover/AI_test.ai/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler start archiving

13.09.2019 06:13:02.322 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_804:/content/dam/projects/aicover/AI_test.ai/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler Start process execution of com.day.cq.dam.indd.process.INDDPageExtractProcess for: VolatileWorkItem_node7_var_workflow_instances_server0_2019-07-31_1_update_asset_804

13.09.2019 06:13:02.322 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_804:/content/dam/projects/aicover/AI_test.ai/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler Finished process execution of com.day.cq.dam.indd.process.INDDPageExtractProcess for: VolatileWorkItem_node7_var_workflow_instances_server0_2019-07-31_1_update_asset_804

13.09.2019 06:13:02.322 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_804:/content/dam/projects/aicover/AI_test.ai/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler start archiving

13.09.2019 06:13:02.323 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_804:/content/dam/projects/aicover/AI_test.ai/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler Start process execution of com.day.cq.dam.core.process.CreatePdfPreviewProcess for: VolatileWorkItem_node8_var_workflow_instances_server0_2019-07-31_1_update_asset_804

13.09.2019 06:13:03.418 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_804:/content/dam/projects/aicover/AI_test.ai/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler Finished process execution of com.day.cq.dam.core.process.CreatePdfPreviewProcess for: VolatileWorkItem_node8_var_workflow_instances_server0_2019-07-31_1_update_asset_804

13.09.2019 06:13:03.419 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_804:/content/dam/projects/aicover/AI_test.ai/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler start archiving

13.09.2019 06:13:03.419 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_804:/content/dam/projects/aicover/AI_test.ai/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler Start process execution of com.day.cq.dam.core.process.CreateSubAssetsProcess for: VolatileWorkItem_node9_var_workflow_instances_server0_2019-07-31_1_update_asset_804

13.09.2019 06:13:04.249 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_804:/content/dam/projects/aicover/AI_test.ai/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler Finished process execution of com.day.cq.dam.core.process.CreateSubAssetsProcess for: VolatileWorkItem_node9_var_workflow_instances_server0_2019-07-31_1_update_asset_804

13.09.2019 06:13:04.249 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_804:/content/dam/projects/aicover/AI_test.ai/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler start archiving

13.09.2019 06:13:04.251 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_804:/content/dam/projects/aicover/AI_test.ai/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler Start process execution of com.adobe.cq.dam.dm.process.workflow.DMImageProcess for: VolatileWorkItem_node10_var_workflow_instances_server0_2019-07-31_1_update_asset_804

13.09.2019 06:13:04.251 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_804:/content/dam/projects/aicover/AI_test.ai/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler Finished process execution of com.adobe.cq.dam.dm.process.workflow.DMImageProcess for: VolatileWorkItem_node10_var_workflow_instances_server0_2019-07-31_1_update_asset_804

13.09.2019 06:13:04.251 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_804:/content/dam/projects/aicover/AI_test.ai/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler start archiving

13.09.2019 06:13:04.252 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_804:/content/dam/projects/aicover/AI_test.ai/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler looking for WorkflowExternalProcess Proxy

13.09.2019 06:13:04.252 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_804:/content/dam/projects/aicover/AI_test.ai/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler executing external process with work item VolatileWorkItem_node11_var_workflow_instances_server0_2019-07-31_1_update_asset_804

13.09.2019 06:13:04.253 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_804:/content/dam/projects/aicover/AI_test.ai/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler external process created with id null

13.09.2019 06:13:04.253 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_804:/content/dam/projects/aicover/AI_test.ai/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler start archiving

13.09.2019 06:13:04.254 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_804:/content/dam/projects/aicover/AI_test.ai/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler Start process execution of com.day.cq.dam.core.process.ThumbnailProcess for: VolatileWorkItem_node12_var_workflow_instances_server0_2019-07-31_1_update_asset_804

13.09.2019 06:13:04.404 *DEBUG* [Workflow Starter Thread] com.adobe.granite.workflow.core.WorkflowSessionImpl created transient job id: 2019/9/13/6/13/17e1ac2a-cf62-4115-8d9f-6469f028699f_811

13.09.2019 06:13:04.440 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_804:/content/dam/projects/aicover/AI_test.ai/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler Finished process execution of com.day.cq.dam.core.process.ThumbnailProcess for: VolatileWorkItem_node12_var_workflow_instances_server0_2019-07-31_1_update_asset_804

13.09.2019 06:13:04.441 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_804:/content/dam/projects/aicover/AI_test.ai/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler start archiving

13.09.2019 06:13:04.442 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_804:/content/dam/projects/aicover/AI_test.ai/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler Start process execution of com.day.cq.dam.pim.impl.sourcing.upload.process.ProductAssetsUploadProcess for: VolatileWorkItem_node13_var_workflow_instances_server0_2019-07-31_1_update_asset_804

13.09.2019 06:13:04.444 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_804:/content/dam/projects/aicover/AI_test.ai/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler Finished process execution of com.day.cq.dam.pim.impl.sourcing.upload.process.ProductAssetsUploadProcess for: VolatileWorkItem_node13_var_workflow_instances_server0_2019-07-31_1_update_asset_804

13.09.2019 06:13:04.444 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_804:/content/dam/projects/aicover/AI_test.ai/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler start archiving

13.09.2019 06:13:04.445 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_804:/content/dam/projects/aicover/AI_test.ai/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler Start process execution of com.day.cq.dam.core.impl.process.DamUpdateAssetWorkflowCompletedProcess for: VolatileWorkItem_node14_var_workflow_instances_server0_2019-07-31_1_update_asset_804

13.09.2019 06:13:04.447 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_804:/content/dam/projects/aicover/AI_test.ai/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler Finished process execution of com.day.cq.dam.core.impl.process.DamUpdateAssetWorkflowCompletedProcess for: VolatileWorkItem_node14_var_workflow_instances_server0_2019-07-31_1_update_asset_804

13.09.2019 06:13:04.447 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_804:/content/dam/projects/aicover/AI_test.ai/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler start archiving

13.09.2019 06:13:04.448 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_804:/content/dam/projects/aicover/AI_test.ai/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler Start process execution of com.day.cq.dam.core.impl.process.SendTransientWorkflowCompletedEmailProcess for: VolatileWorkItem_node15_var_workflow_instances_server0_2019-07-31_1_update_asset_804

13.09.2019 06:13:04.448 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_804:/content/dam/projects/aicover/AI_test.ai/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler Finished process execution of com.day.cq.dam.core.impl.process.SendTransientWorkflowCompletedEmailProcess for: VolatileWorkItem_node15_var_workflow_instances_server0_2019-07-31_1_update_asset_804

13.09.2019 06:13:04.449 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_804:/content/dam/projects/aicover/AI_test.ai/jcr:content/renditions/original] com.adobe.granite.workflow.core.WorkflowSessionImpl Attempting to complete work item with ID: VolatileWorkItem_node15_var_workflow_instances_server0_2019-07-31_1_update_asset_804

13.09.2019 06:13:04.454 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_804:/content/dam/projects/aicover/AI_test.ai/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler Finished processing: /var/workflow/instances/server0/2019-07-31_1/update_asset_804:/content/dam/projects/aicover/AI_test.ai/jcr:content/renditions/original(Complete execution took: 3424ms)

13.09.2019 06:13:04.467 *DEBUG* [sling-threadpool-befce717-30b3-48e8-b1ca-c8909e0383e8-(apache-sling-job-thread-pool)-13-Granite Transient Workflow Queue(com/adobe/granite/workflow/transient/job/var/workflow/models/dam/process_subasset)] com.adobe.granite.workflow.core.job.JobHandler ===== isTransient for com/adobe/granite/workflow/transient/job/var/workflow/models/dam/process_subasset - value: true

13.09.2019 06:13:04.467 *DEBUG* [sling-threadpool-befce717-30b3-48e8-b1ca-c8909e0383e8-(apache-sling-job-thread-pool)-13-Granite Transient Workflow Queue(com/adobe/granite/workflow/transient/job/var/workflow/models/dam/process_subasset)] com.adobe.granite.workflow.core.job.JobHandler ====== Handle transient

13.09.2019 06:13:04.470 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/process_subasset_805:/content/dam/projects/aicover/AI_test.ai/subassets/layer1.png/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler Start processing: /var/workflow/instances/server0/2019-07-31_1/process_subasset_805:/content/dam/projects/aicover/AI_test.ai/subassets/layer1.png/jcr:content/renditions/original

13.09.2019 06:13:04.470 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/process_subasset_805:/content/dam/projects/aicover/AI_test.ai/subassets/layer1.png/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler Start process execution of com.day.cq.dam.core.process.CreateWebEnabledImageProcess for: VolatileWorkItem_node1_var_workflow_instances_server0_2019-07-31_1_process_subasset_805

13.09.2019 06:13:04.995 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/process_subasset_805:/content/dam/projects/aicover/AI_test.ai/subassets/layer1.png/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler Finished process execution of com.day.cq.dam.core.process.CreateWebEnabledImageProcess for: VolatileWorkItem_node1_var_workflow_instances_server0_2019-07-31_1_process_subasset_805

13.09.2019 06:13:04.997 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/process_subasset_805:/content/dam/projects/aicover/AI_test.ai/subassets/layer1.png/jcr:content/renditions/original] com.adobe.granite.workflow.core.WorkflowSessionImpl Attempting to complete work item with ID: VolatileWorkItem_node1_var_workflow_instances_server0_2019-07-31_1_process_subasset_805

13.09.2019 06:13:05.040 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/process_subasset_805:/content/dam/projects/aicover/AI_test.ai/subassets/layer1.png/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler Finished processing: /var/workflow/instances/server0/2019-07-31_1/process_subasset_805:/content/dam/projects/aicover/AI_test.ai/subassets/layer1.png/jcr:content/renditions/original(Complete execution took: 570ms)

13.09.2019 06:21:10.765 *DEBUG* [Workflow Starter Thread] com.adobe.granite.workflow.core.WorkflowSessionImpl created transient job id: 2019/9/13/6/21/17e1ac2a-cf62-4115-8d9f-6469f028699f_812

13.09.2019 06:21:10.802 *DEBUG* [sling-threadpool-befce717-30b3-48e8-b1ca-c8909e0383e8-(apache-sling-job-thread-pool)-27-Granite Transient Workflow Queue(com/adobe/granite/workflow/transient/job/var/workflow/models/dam/update_asset)] com.adobe.granite.workflow.core.job.JobHandler ===== isTransient for com/adobe/granite/workflow/transient/job/var/workflow/models/dam/update_asset - value: true

13.09.2019 06:21:10.802 *DEBUG* [sling-threadpool-befce717-30b3-48e8-b1ca-c8909e0383e8-(apache-sling-job-thread-pool)-27-Granite Transient Workflow Queue(com/adobe/granite/workflow/transient/job/var/workflow/models/dam/update_asset)] com.adobe.granite.workflow.core.job.JobHandler ====== Handle transient

13.09.2019 06:21:10.806 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_806:/content/dam/projects/aicoverwithotherassets/cover/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler Start processing: /var/workflow/instances/server0/2019-07-31_1/update_asset_806:/content/dam/projects/aicoverwithotherassets/cover/jcr:content/renditions/original

13.09.2019 06:21:10.806 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_806:/content/dam/projects/aicoverwithotherassets/cover/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler Start process execution of com.day.cq.dam.core.process.GateKeeperProcess for: VolatileWorkItem_node1_var_workflow_instances_server0_2019-07-31_1_update_asset_806

13.09.2019 06:21:10.816 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_806:/content/dam/projects/aicoverwithotherassets/cover/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler Finished process execution of com.day.cq.dam.core.process.GateKeeperProcess for: VolatileWorkItem_node1_var_workflow_instances_server0_2019-07-31_1_update_asset_806

13.09.2019 06:21:10.816 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_806:/content/dam/projects/aicoverwithotherassets/cover/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler start archiving

13.09.2019 06:21:10.832 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_806:/content/dam/projects/aicoverwithotherassets/cover/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler Start process execution of com.day.cq.dam.core.process.MetadataProcessorProcess for: VolatileWorkItem_node2_var_workflow_instances_server0_2019-07-31_1_update_asset_806

13.09.2019 06:21:10.858 *DEBUG* [49.249.236.30 [1568355670540] POST /bin/mls/crproject HTTP/1.1] com.adobe.granite.workflow.core.WorkflowSessionImpl getModel for id '/var/workflow/models/ProjectapprovalworkFlow' for mlsCRUD took 1 ms

13.09.2019 06:21:10.899 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_806:/content/dam/projects/aicoverwithotherassets/cover/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler Finished process execution of com.day.cq.dam.core.process.MetadataProcessorProcess for: VolatileWorkItem_node2_var_workflow_instances_server0_2019-07-31_1_update_asset_806

13.09.2019 06:21:10.899 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_806:/content/dam/projects/aicoverwithotherassets/cover/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler start archiving

13.09.2019 06:21:10.900 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_806:/content/dam/projects/aicoverwithotherassets/cover/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler Start process execution of com.day.cq.dam.video.FFMpegThumbnailProcess for: VolatileWorkItem_node3_var_workflow_instances_server0_2019-07-31_1_update_asset_806

13.09.2019 06:21:10.900 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_806:/content/dam/projects/aicoverwithotherassets/cover/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler Finished process execution of com.day.cq.dam.video.FFMpegThumbnailProcess for: VolatileWorkItem_node3_var_workflow_instances_server0_2019-07-31_1_update_asset_806

13.09.2019 06:21:10.900 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_806:/content/dam/projects/aicoverwithotherassets/cover/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler start archiving

13.09.2019 06:21:10.901 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_806:/content/dam/projects/aicoverwithotherassets/cover/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler Start process execution of com.day.cq.dam.core.process.CommandLineProcess for: VolatileWorkItem_node4_var_workflow_instances_server0_2019-07-31_1_update_asset_806

13.09.2019 06:21:10.901 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_806:/content/dam/projects/aicoverwithotherassets/cover/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler Finished process execution of com.day.cq.dam.core.process.CommandLineProcess for: VolatileWorkItem_node4_var_workflow_instances_server0_2019-07-31_1_update_asset_806

13.09.2019 06:21:10.901 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_806:/content/dam/projects/aicoverwithotherassets/cover/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler start archiving

13.09.2019 06:21:10.902 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_806:/content/dam/projects/aicoverwithotherassets/cover/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler Start process execution of com.day.cq.dam.video.FFMpegTranscodeProcess for: VolatileWorkItem_node5_var_workflow_instances_server0_2019-07-31_1_update_asset_806

13.09.2019 06:21:10.902 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_806:/content/dam/projects/aicoverwithotherassets/cover/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler Finished process execution of com.day.cq.dam.video.FFMpegTranscodeProcess for: VolatileWorkItem_node5_var_workflow_instances_server0_2019-07-31_1_update_asset_806

13.09.2019 06:21:10.902 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_806:/content/dam/projects/aicoverwithotherassets/cover/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler start archiving

13.09.2019 06:21:10.903 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_806:/content/dam/projects/aicoverwithotherassets/cover/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler looking for WorkflowExternalProcess Proxy

13.09.2019 06:21:10.903 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_806:/content/dam/projects/aicoverwithotherassets/cover/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler executing external process with work item VolatileWorkItem_node6_var_workflow_instances_server0_2019-07-31_1_update_asset_806

13.09.2019 06:21:10.903 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_806:/content/dam/projects/aicoverwithotherassets/cover/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler external process created with id null

13.09.2019 06:21:10.903 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_806:/content/dam/projects/aicoverwithotherassets/cover/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler start archiving

13.09.2019 06:21:10.904 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_806:/content/dam/projects/aicoverwithotherassets/cover/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler Start process execution of com.day.cq.dam.indd.process.INDDPageExtractProcess for: VolatileWorkItem_node7_var_workflow_instances_server0_2019-07-31_1_update_asset_806

13.09.2019 06:21:10.905 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_806:/content/dam/projects/aicoverwithotherassets/cover/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler Finished process execution of com.day.cq.dam.indd.process.INDDPageExtractProcess for: VolatileWorkItem_node7_var_workflow_instances_server0_2019-07-31_1_update_asset_806

13.09.2019 06:21:10.905 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_806:/content/dam/projects/aicoverwithotherassets/cover/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler start archiving

13.09.2019 06:21:10.905 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_806:/content/dam/projects/aicoverwithotherassets/cover/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler Start process execution of com.day.cq.dam.core.process.CreatePdfPreviewProcess for: VolatileWorkItem_node8_var_workflow_instances_server0_2019-07-31_1_update_asset_806

13.09.2019 06:21:10.906 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_806:/content/dam/projects/aicoverwithotherassets/cover/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler Finished process execution of com.day.cq.dam.core.process.CreatePdfPreviewProcess for: VolatileWorkItem_node8_var_workflow_instances_server0_2019-07-31_1_update_asset_806

13.09.2019 06:21:10.906 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_806:/content/dam/projects/aicoverwithotherassets/cover/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler start archiving

13.09.2019 06:21:10.913 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_806:/content/dam/projects/aicoverwithotherassets/cover/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler Start process execution of com.day.cq.dam.core.process.CreateSubAssetsProcess for: VolatileWorkItem_node9_var_workflow_instances_server0_2019-07-31_1_update_asset_806

13.09.2019 06:21:10.913 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_806:/content/dam/projects/aicoverwithotherassets/cover/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler Finished process execution of com.day.cq.dam.core.process.CreateSubAssetsProcess for: VolatileWorkItem_node9_var_workflow_instances_server0_2019-07-31_1_update_asset_806

13.09.2019 06:21:10.913 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_806:/content/dam/projects/aicoverwithotherassets/cover/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler start archiving

13.09.2019 06:21:10.913 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_806:/content/dam/projects/aicoverwithotherassets/cover/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler Start process execution of com.adobe.cq.dam.dm.process.workflow.DMImageProcess for: VolatileWorkItem_node10_var_workflow_instances_server0_2019-07-31_1_update_asset_806

13.09.2019 06:21:10.914 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_806:/content/dam/projects/aicoverwithotherassets/cover/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler Finished process execution of com.adobe.cq.dam.dm.process.workflow.DMImageProcess for: VolatileWorkItem_node10_var_workflow_instances_server0_2019-07-31_1_update_asset_806

13.09.2019 06:21:10.914 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_806:/content/dam/projects/aicoverwithotherassets/cover/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler start archiving

13.09.2019 06:21:10.914 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_806:/content/dam/projects/aicoverwithotherassets/cover/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler looking for WorkflowExternalProcess Proxy

13.09.2019 06:21:10.914 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_806:/content/dam/projects/aicoverwithotherassets/cover/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler executing external process with work item VolatileWorkItem_node11_var_workflow_instances_server0_2019-07-31_1_update_asset_806

13.09.2019 06:21:10.924 *DEBUG* [49.249.236.30 [1568355670540] POST /bin/mls/crproject HTTP/1.1] com.adobe.granite.workflow.core.job.WorkflowJobUtils calling createTimeoutJob for workitem /var/workflow/instances/server0/2019-07-31_1/ProjectapprovalworkFlow_807/workItems/node1_var_workflow_instances_server0_2019-07-31_1_ProjectapprovalworkFlow_807

13.09.2019 06:21:10.933 *DEBUG* [49.249.236.30 [1568355670540] POST /bin/mls/crproject HTTP/1.1] com.adobe.granite.workflow.core.WorkflowSessionImpl Workflow instance started with model: /var/workflow/models/ProjectapprovalworkFlow and ID: /var/workflow/instances/server0/2019-07-31_1/ProjectapprovalworkFlow_807 for payload: /content/dam/projects/aicoverwithotherassets

13.09.2019 06:21:10.942 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_806:/content/dam/projects/aicoverwithotherassets/cover/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler external process created with id null

13.09.2019 06:21:10.942 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_806:/content/dam/projects/aicoverwithotherassets/cover/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler start archiving

13.09.2019 06:21:10.943 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_806:/content/dam/projects/aicoverwithotherassets/cover/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler Start process execution of com.day.cq.dam.core.process.ThumbnailProcess for: VolatileWorkItem_node12_var_workflow_instances_server0_2019-07-31_1_update_asset_806

13.09.2019 06:21:11.000 *DEBUG* [Workflow Starter Thread] com.adobe.granite.workflow.core.WorkflowSessionImpl created transient job id: 2019/9/13/6/21/17e1ac2a-cf62-4115-8d9f-6469f028699f_813

13.09.2019 06:21:11.165 *DEBUG* [Workflow Starter Thread] com.adobe.granite.workflow.core.WorkflowSessionImpl created transient job id: 2019/9/13/6/21/17e1ac2a-cf62-4115-8d9f-6469f028699f_814

13.09.2019 06:21:11.189 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_806:/content/dam/projects/aicoverwithotherassets/cover/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler Finished process execution of com.day.cq.dam.core.process.ThumbnailProcess for: VolatileWorkItem_node12_var_workflow_instances_server0_2019-07-31_1_update_asset_806

13.09.2019 06:21:11.189 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_806:/content/dam/projects/aicoverwithotherassets/cover/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler start archiving

13.09.2019 06:21:11.194 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_806:/content/dam/projects/aicoverwithotherassets/cover/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler Start process execution of com.day.cq.dam.pim.impl.sourcing.upload.process.ProductAssetsUploadProcess for: VolatileWorkItem_node13_var_workflow_instances_server0_2019-07-31_1_update_asset_806

13.09.2019 06:21:11.222 *DEBUG* [Workflow Starter Thread] com.adobe.granite.workflow.core.WorkflowSessionImpl created transient job id: 2019/9/13/6/21/17e1ac2a-cf62-4115-8d9f-6469f028699f_815

13.09.2019 06:21:11.230 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_806:/content/dam/projects/aicoverwithotherassets/cover/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler Finished process execution of com.day.cq.dam.pim.impl.sourcing.upload.process.ProductAssetsUploadProcess for: VolatileWorkItem_node13_var_workflow_instances_server0_2019-07-31_1_update_asset_806

13.09.2019 06:21:11.230 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_806:/content/dam/projects/aicoverwithotherassets/cover/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler start archiving

13.09.2019 06:21:11.231 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_806:/content/dam/projects/aicoverwithotherassets/cover/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler Start process execution of com.day.cq.dam.core.impl.process.DamUpdateAssetWorkflowCompletedProcess for: VolatileWorkItem_node14_var_workflow_instances_server0_2019-07-31_1_update_asset_806

13.09.2019 06:21:11.231 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_806:/content/dam/projects/aicoverwithotherassets/cover/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler Finished process execution of com.day.cq.dam.core.impl.process.DamUpdateAssetWorkflowCompletedProcess for: VolatileWorkItem_node14_var_workflow_instances_server0_2019-07-31_1_update_asset_806

13.09.2019 06:21:11.232 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_806:/content/dam/projects/aicoverwithotherassets/cover/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler start archiving

13.09.2019 06:21:11.232 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_806:/content/dam/projects/aicoverwithotherassets/cover/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler Start process execution of com.day.cq.dam.core.impl.process.SendTransientWorkflowCompletedEmailProcess for: VolatileWorkItem_node15_var_workflow_instances_server0_2019-07-31_1_update_asset_806

13.09.2019 06:21:11.233 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_806:/content/dam/projects/aicoverwithotherassets/cover/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler Finished process execution of com.day.cq.dam.core.impl.process.SendTransientWorkflowCompletedEmailProcess for: VolatileWorkItem_node15_var_workflow_instances_server0_2019-07-31_1_update_asset_806

13.09.2019 06:21:11.233 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_806:/content/dam/projects/aicoverwithotherassets/cover/jcr:content/renditions/original] com.adobe.granite.workflow.core.WorkflowSessionImpl Attempting to complete work item with ID: VolatileWorkItem_node15_var_workflow_instances_server0_2019-07-31_1_update_asset_806

13.09.2019 06:21:11.252 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_806:/content/dam/projects/aicoverwithotherassets/cover/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler Finished processing: /var/workflow/instances/server0/2019-07-31_1/update_asset_806:/content/dam/projects/aicoverwithotherassets/cover/jcr:content/renditions/original(Complete execution took: 446ms)

13.09.2019 06:21:11.295 *DEBUG* [sling-threadpool-befce717-30b3-48e8-b1ca-c8909e0383e8-(apache-sling-job-thread-pool)-27-Granite Transient Workflow Queue(com/adobe/granite/workflow/transient/job/var/workflow/models/dam/update_asset)] com.adobe.granite.workflow.core.job.JobHandler ===== isTransient for com/adobe/granite/workflow/transient/job/var/workflow/models/dam/update_asset - value: true

13.09.2019 06:21:11.295 *DEBUG* [sling-threadpool-befce717-30b3-48e8-b1ca-c8909e0383e8-(apache-sling-job-thread-pool)-27-Granite Transient Workflow Queue(com/adobe/granite/workflow/transient/job/var/workflow/models/dam/update_asset)] com.adobe.granite.workflow.core.job.JobHandler ====== Handle transient

13.09.2019 06:21:11.297 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_808:/content/dam/projects/aicoverwithotherassets/7.jpg/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler Start processing: /var/workflow/instances/server0/2019-07-31_1/update_asset_808:/content/dam/projects/aicoverwithotherassets/7.jpg/jcr:content/renditions/original

13.09.2019 06:21:11.297 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_808:/content/dam/projects/aicoverwithotherassets/7.jpg/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler Start process execution of com.day.cq.dam.core.process.GateKeeperProcess for: VolatileWorkItem_node1_var_workflow_instances_server0_2019-07-31_1_update_asset_808

13.09.2019 06:21:11.300 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_808:/content/dam/projects/aicoverwithotherassets/7.jpg/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler Finished process execution of com.day.cq.dam.core.process.GateKeeperProcess for: VolatileWorkItem_node1_var_workflow_instances_server0_2019-07-31_1_update_asset_808

13.09.2019 06:21:11.300 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_808:/content/dam/projects/aicoverwithotherassets/7.jpg/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler start archiving

13.09.2019 06:21:11.302 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_808:/content/dam/projects/aicoverwithotherassets/7.jpg/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler Start process execution of com.day.cq.dam.core.process.MetadataProcessorProcess for: VolatileWorkItem_node2_var_workflow_instances_server0_2019-07-31_1_update_asset_808

13.09.2019 06:21:11.503 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_808:/content/dam/projects/aicoverwithotherassets/7.jpg/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler Finished process execution of com.day.cq.dam.core.process.MetadataProcessorProcess for: VolatileWorkItem_node2_var_workflow_instances_server0_2019-07-31_1_update_asset_808

13.09.2019 06:21:11.503 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_808:/content/dam/projects/aicoverwithotherassets/7.jpg/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler start archiving

13.09.2019 06:21:11.504 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_808:/content/dam/projects/aicoverwithotherassets/7.jpg/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler Start process execution of com.day.cq.dam.video.FFMpegThumbnailProcess for: VolatileWorkItem_node3_var_workflow_instances_server0_2019-07-31_1_update_asset_808

13.09.2019 06:21:11.505 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_808:/content/dam/projects/aicoverwithotherassets/7.jpg/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler Finished process execution of com.day.cq.dam.video.FFMpegThumbnailProcess for: VolatileWorkItem_node3_var_workflow_instances_server0_2019-07-31_1_update_asset_808

13.09.2019 06:21:11.505 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_808:/content/dam/projects/aicoverwithotherassets/7.jpg/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler start archiving

13.09.2019 06:21:11.506 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_808:/content/dam/projects/aicoverwithotherassets/7.jpg/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler Start process execution of com.day.cq.dam.core.process.CommandLineProcess for: VolatileWorkItem_node4_var_workflow_instances_server0_2019-07-31_1_update_asset_808

13.09.2019 06:21:11.506 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_808:/content/dam/projects/aicoverwithotherassets/7.jpg/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler Finished process execution of com.day.cq.dam.core.process.CommandLineProcess for: VolatileWorkItem_node4_var_workflow_instances_server0_2019-07-31_1_update_asset_808

13.09.2019 06:21:11.506 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_808:/content/dam/projects/aicoverwithotherassets/7.jpg/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler start archiving

13.09.2019 06:21:11.507 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_808:/content/dam/projects/aicoverwithotherassets/7.jpg/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler Start process execution of com.day.cq.dam.video.FFMpegTranscodeProcess for: VolatileWorkItem_node5_var_workflow_instances_server0_2019-07-31_1_update_asset_808

13.09.2019 06:21:11.507 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_808:/content/dam/projects/aicoverwithotherassets/7.jpg/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler Finished process execution of com.day.cq.dam.video.FFMpegTranscodeProcess for: VolatileWorkItem_node5_var_workflow_instances_server0_2019-07-31_1_update_asset_808

13.09.2019 06:21:11.507 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_808:/content/dam/projects/aicoverwithotherassets/7.jpg/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler start archiving

13.09.2019 06:21:11.508 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_808:/content/dam/projects/aicoverwithotherassets/7.jpg/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler looking for WorkflowExternalProcess Proxy

13.09.2019 06:21:11.508 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_808:/content/dam/projects/aicoverwithotherassets/7.jpg/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler executing external process with work item VolatileWorkItem_node6_var_workflow_instances_server0_2019-07-31_1_update_asset_808

13.09.2019 06:21:11.509 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_808:/content/dam/projects/aicoverwithotherassets/7.jpg/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler external process created with id null

13.09.2019 06:21:11.509 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_808:/content/dam/projects/aicoverwithotherassets/7.jpg/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler start archiving

13.09.2019 06:21:11.510 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_808:/content/dam/projects/aicoverwithotherassets/7.jpg/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler Start process execution of com.day.cq.dam.indd.process.INDDPageExtractProcess for: VolatileWorkItem_node7_var_workflow_instances_server0_2019-07-31_1_update_asset_808

13.09.2019 06:21:11.510 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_808:/content/dam/projects/aicoverwithotherassets/7.jpg/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler Finished process execution of com.day.cq.dam.indd.process.INDDPageExtractProcess for: VolatileWorkItem_node7_var_workflow_instances_server0_2019-07-31_1_update_asset_808

13.09.2019 06:21:11.510 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_808:/content/dam/projects/aicoverwithotherassets/7.jpg/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler start archiving

13.09.2019 06:21:11.511 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_808:/content/dam/projects/aicoverwithotherassets/7.jpg/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler Start process execution of com.day.cq.dam.core.process.CreatePdfPreviewProcess for: VolatileWorkItem_node8_var_workflow_instances_server0_2019-07-31_1_update_asset_808

13.09.2019 06:21:11.511 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_808:/content/dam/projects/aicoverwithotherassets/7.jpg/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler Finished process execution of com.day.cq.dam.core.process.CreatePdfPreviewProcess for: VolatileWorkItem_node8_var_workflow_instances_server0_2019-07-31_1_update_asset_808

13.09.2019 06:21:11.511 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_808:/content/dam/projects/aicoverwithotherassets/7.jpg/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler start archiving

13.09.2019 06:21:11.512 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_808:/content/dam/projects/aicoverwithotherassets/7.jpg/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler Start process execution of com.day.cq.dam.core.process.CreateSubAssetsProcess for: VolatileWorkItem_node9_var_workflow_instances_server0_2019-07-31_1_update_asset_808

13.09.2019 06:21:11.512 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_808:/content/dam/projects/aicoverwithotherassets/7.jpg/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler Finished process execution of com.day.cq.dam.core.process.CreateSubAssetsProcess for: VolatileWorkItem_node9_var_workflow_instances_server0_2019-07-31_1_update_asset_808

13.09.2019 06:21:11.512 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_808:/content/dam/projects/aicoverwithotherassets/7.jpg/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler start archiving

13.09.2019 06:21:11.513 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_808:/content/dam/projects/aicoverwithotherassets/7.jpg/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler Start process execution of com.adobe.cq.dam.dm.process.workflow.DMImageProcess for: VolatileWorkItem_node10_var_workflow_instances_server0_2019-07-31_1_update_asset_808

13.09.2019 06:21:11.513 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_808:/content/dam/projects/aicoverwithotherassets/7.jpg/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler Finished process execution of com.adobe.cq.dam.dm.process.workflow.DMImageProcess for: VolatileWorkItem_node10_var_workflow_instances_server0_2019-07-31_1_update_asset_808

13.09.2019 06:21:11.513 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_808:/content/dam/projects/aicoverwithotherassets/7.jpg/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler start archiving

13.09.2019 06:21:11.514 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_808:/content/dam/projects/aicoverwithotherassets/7.jpg/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler looking for WorkflowExternalProcess Proxy

13.09.2019 06:21:11.514 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_808:/content/dam/projects/aicoverwithotherassets/7.jpg/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler executing external process with work item VolatileWorkItem_node11_var_workflow_instances_server0_2019-07-31_1_update_asset_808

13.09.2019 06:21:11.516 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_808:/content/dam/projects/aicoverwithotherassets/7.jpg/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler external process created with id null

13.09.2019 06:21:11.516 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_808:/content/dam/projects/aicoverwithotherassets/7.jpg/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler start archiving

13.09.2019 06:21:11.517 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_808:/content/dam/projects/aicoverwithotherassets/7.jpg/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler Start process execution of com.day.cq.dam.core.process.ThumbnailProcess for: VolatileWorkItem_node12_var_workflow_instances_server0_2019-07-31_1_update_asset_808

13.09.2019 06:21:11.941 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_808:/content/dam/projects/aicoverwithotherassets/7.jpg/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler Finished process execution of com.day.cq.dam.core.process.ThumbnailProcess for: VolatileWorkItem_node12_var_workflow_instances_server0_2019-07-31_1_update_asset_808

13.09.2019 06:21:11.941 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_808:/content/dam/projects/aicoverwithotherassets/7.jpg/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler start archiving

13.09.2019 06:21:11.943 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_808:/content/dam/projects/aicoverwithotherassets/7.jpg/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler Start process execution of com.day.cq.dam.pim.impl.sourcing.upload.process.ProductAssetsUploadProcess for: VolatileWorkItem_node13_var_workflow_instances_server0_2019-07-31_1_update_asset_808

13.09.2019 06:21:11.945 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_808:/content/dam/projects/aicoverwithotherassets/7.jpg/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler Finished process execution of com.day.cq.dam.pim.impl.sourcing.upload.process.ProductAssetsUploadProcess for: VolatileWorkItem_node13_var_workflow_instances_server0_2019-07-31_1_update_asset_808

13.09.2019 06:21:11.945 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_808:/content/dam/projects/aicoverwithotherassets/7.jpg/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler start archiving

13.09.2019 06:21:11.946 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_808:/content/dam/projects/aicoverwithotherassets/7.jpg/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler Start process execution of com.day.cq.dam.core.impl.process.DamUpdateAssetWorkflowCompletedProcess for: VolatileWorkItem_node14_var_workflow_instances_server0_2019-07-31_1_update_asset_808

13.09.2019 06:21:11.964 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_808:/content/dam/projects/aicoverwithotherassets/7.jpg/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler Finished process execution of com.day.cq.dam.core.impl.process.DamUpdateAssetWorkflowCompletedProcess for: VolatileWorkItem_node14_var_workflow_instances_server0_2019-07-31_1_update_asset_808

13.09.2019 06:21:11.964 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_808:/content/dam/projects/aicoverwithotherassets/7.jpg/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler start archiving

13.09.2019 06:21:11.965 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_808:/content/dam/projects/aicoverwithotherassets/7.jpg/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler Start process execution of com.day.cq.dam.core.impl.process.SendTransientWorkflowCompletedEmailProcess for: VolatileWorkItem_node15_var_workflow_instances_server0_2019-07-31_1_update_asset_808

13.09.2019 06:21:11.965 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_808:/content/dam/projects/aicoverwithotherassets/7.jpg/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler Finished process execution of com.day.cq.dam.core.impl.process.SendTransientWorkflowCompletedEmailProcess for: VolatileWorkItem_node15_var_workflow_instances_server0_2019-07-31_1_update_asset_808

13.09.2019 06:21:11.965 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_808:/content/dam/projects/aicoverwithotherassets/7.jpg/jcr:content/renditions/original] com.adobe.granite.workflow.core.WorkflowSessionImpl Attempting to complete work item with ID: VolatileWorkItem_node15_var_workflow_instances_server0_2019-07-31_1_update_asset_808

13.09.2019 06:21:11.970 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_808:/content/dam/projects/aicoverwithotherassets/7.jpg/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler Finished processing: /var/workflow/instances/server0/2019-07-31_1/update_asset_808:/content/dam/projects/aicoverwithotherassets/7.jpg/jcr:content/renditions/original(Complete execution took: 673ms)

13.09.2019 06:21:11.997 *DEBUG* [sling-threadpool-befce717-30b3-48e8-b1ca-c8909e0383e8-(apache-sling-job-thread-pool)-27-Granite Transient Workflow Queue(com/adobe/granite/workflow/transient/job/var/workflow/models/dam/update_asset)] com.adobe.granite.workflow.core.job.JobHandler ===== isTransient for com/adobe/granite/workflow/transient/job/var/workflow/models/dam/update_asset - value: true

13.09.2019 06:21:11.997 *DEBUG* [sling-threadpool-befce717-30b3-48e8-b1ca-c8909e0383e8-(apache-sling-job-thread-pool)-27-Granite Transient Workflow Queue(com/adobe/granite/workflow/transient/job/var/workflow/models/dam/update_asset)] com.adobe.granite.workflow.core.job.JobHandler ====== Handle transient

13.09.2019 06:21:12.001 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_809:/content/dam/projects/aicoverwithotherassets/8---Copy---Copy.jpg/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler Start processing: /var/workflow/instances/server0/2019-07-31_1/update_asset_809:/content/dam/projects/aicoverwithotherassets/8---Copy---Copy.jpg/jcr:content/renditions/original

13.09.2019 06:21:12.001 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_809:/content/dam/projects/aicoverwithotherassets/8---Copy---Copy.jpg/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler Start process execution of com.day.cq.dam.core.process.GateKeeperProcess for: VolatileWorkItem_node1_var_workflow_instances_server0_2019-07-31_1_update_asset_809

13.09.2019 06:21:12.004 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_809:/content/dam/projects/aicoverwithotherassets/8---Copy---Copy.jpg/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler Finished process execution of com.day.cq.dam.core.process.GateKeeperProcess for: VolatileWorkItem_node1_var_workflow_instances_server0_2019-07-31_1_update_asset_809

13.09.2019 06:21:12.004 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_809:/content/dam/projects/aicoverwithotherassets/8---Copy---Copy.jpg/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler start archiving

13.09.2019 06:21:12.006 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_809:/content/dam/projects/aicoverwithotherassets/8---Copy---Copy.jpg/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler Start process execution of com.day.cq.dam.core.process.MetadataProcessorProcess for: VolatileWorkItem_node2_var_workflow_instances_server0_2019-07-31_1_update_asset_809

13.09.2019 06:21:12.043 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_809:/content/dam/projects/aicoverwithotherassets/8---Copy---Copy.jpg/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler Finished process execution of com.day.cq.dam.core.process.MetadataProcessorProcess for: VolatileWorkItem_node2_var_workflow_instances_server0_2019-07-31_1_update_asset_809

13.09.2019 06:21:12.043 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_809:/content/dam/projects/aicoverwithotherassets/8---Copy---Copy.jpg/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler start archiving

13.09.2019 06:21:12.044 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_809:/content/dam/projects/aicoverwithotherassets/8---Copy---Copy.jpg/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler Start process execution of com.day.cq.dam.video.FFMpegThumbnailProcess for: VolatileWorkItem_node3_var_workflow_instances_server0_2019-07-31_1_update_asset_809

13.09.2019 06:21:12.045 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_809:/content/dam/projects/aicoverwithotherassets/8---Copy---Copy.jpg/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler Finished process execution of com.day.cq.dam.video.FFMpegThumbnailProcess for: VolatileWorkItem_node3_var_workflow_instances_server0_2019-07-31_1_update_asset_809

13.09.2019 06:21:12.045 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_809:/content/dam/projects/aicoverwithotherassets/8---Copy---Copy.jpg/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler start archiving

13.09.2019 06:21:12.045 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_809:/content/dam/projects/aicoverwithotherassets/8---Copy---Copy.jpg/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler Start process execution of com.day.cq.dam.core.process.CommandLineProcess for: VolatileWorkItem_node4_var_workflow_instances_server0_2019-07-31_1_update_asset_809

13.09.2019 06:21:12.046 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_809:/content/dam/projects/aicoverwithotherassets/8---Copy---Copy.jpg/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler Finished process execution of com.day.cq.dam.core.process.CommandLineProcess for: VolatileWorkItem_node4_var_workflow_instances_server0_2019-07-31_1_update_asset_809

13.09.2019 06:21:12.046 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_809:/content/dam/projects/aicoverwithotherassets/8---Copy---Copy.jpg/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler start archiving

13.09.2019 06:21:12.047 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_809:/content/dam/projects/aicoverwithotherassets/8---Copy---Copy.jpg/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler Start process execution of com.day.cq.dam.video.FFMpegTranscodeProcess for: VolatileWorkItem_node5_var_workflow_instances_server0_2019-07-31_1_update_asset_809

13.09.2019 06:21:12.047 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_809:/content/dam/projects/aicoverwithotherassets/8---Copy---Copy.jpg/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler Finished process execution of com.day.cq.dam.video.FFMpegTranscodeProcess for: VolatileWorkItem_node5_var_workflow_instances_server0_2019-07-31_1_update_asset_809

13.09.2019 06:21:12.047 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_809:/content/dam/projects/aicoverwithotherassets/8---Copy---Copy.jpg/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler start archiving

13.09.2019 06:21:12.048 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_809:/content/dam/projects/aicoverwithotherassets/8---Copy---Copy.jpg/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler looking for WorkflowExternalProcess Proxy

13.09.2019 06:21:12.048 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_809:/content/dam/projects/aicoverwithotherassets/8---Copy---Copy.jpg/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler executing external process with work item VolatileWorkItem_node6_var_workflow_instances_server0_2019-07-31_1_update_asset_809

13.09.2019 06:21:12.048 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_809:/content/dam/projects/aicoverwithotherassets/8---Copy---Copy.jpg/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler external process created with id null

13.09.2019 06:21:12.048 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_809:/content/dam/projects/aicoverwithotherassets/8---Copy---Copy.jpg/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler start archiving

13.09.2019 06:21:12.049 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_809:/content/dam/projects/aicoverwithotherassets/8---Copy---Copy.jpg/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler Start process execution of com.day.cq.dam.indd.process.INDDPageExtractProcess for: VolatileWorkItem_node7_var_workflow_instances_server0_2019-07-31_1_update_asset_809

13.09.2019 06:21:12.049 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_809:/content/dam/projects/aicoverwithotherassets/8---Copy---Copy.jpg/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler Finished process execution of com.day.cq.dam.indd.process.INDDPageExtractProcess for: VolatileWorkItem_node7_var_workflow_instances_server0_2019-07-31_1_update_asset_809

13.09.2019 06:21:12.049 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_809:/content/dam/projects/aicoverwithotherassets/8---Copy---Copy.jpg/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler start archiving

13.09.2019 06:21:12.050 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_809:/content/dam/projects/aicoverwithotherassets/8---Copy---Copy.jpg/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler Start process execution of com.day.cq.dam.core.process.CreatePdfPreviewProcess for: VolatileWorkItem_node8_var_workflow_instances_server0_2019-07-31_1_update_asset_809

13.09.2019 06:21:12.050 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_809:/content/dam/projects/aicoverwithotherassets/8---Copy---Copy.jpg/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler Finished process execution of com.day.cq.dam.core.process.CreatePdfPreviewProcess for: VolatileWorkItem_node8_var_workflow_instances_server0_2019-07-31_1_update_asset_809

13.09.2019 06:21:12.050 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_809:/content/dam/projects/aicoverwithotherassets/8---Copy---Copy.jpg/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler start archiving

13.09.2019 06:21:12.051 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_809:/content/dam/projects/aicoverwithotherassets/8---Copy---Copy.jpg/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler Start process execution of com.day.cq.dam.core.process.CreateSubAssetsProcess for: VolatileWorkItem_node9_var_workflow_instances_server0_2019-07-31_1_update_asset_809

13.09.2019 06:21:12.052 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_809:/content/dam/projects/aicoverwithotherassets/8---Copy---Copy.jpg/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler Finished process execution of com.day.cq.dam.core.process.CreateSubAssetsProcess for: VolatileWorkItem_node9_var_workflow_instances_server0_2019-07-31_1_update_asset_809

13.09.2019 06:21:12.052 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_809:/content/dam/projects/aicoverwithotherassets/8---Copy---Copy.jpg/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler start archiving

13.09.2019 06:21:12.053 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_809:/content/dam/projects/aicoverwithotherassets/8---Copy---Copy.jpg/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler Start process execution of com.adobe.cq.dam.dm.process.workflow.DMImageProcess for: VolatileWorkItem_node10_var_workflow_instances_server0_2019-07-31_1_update_asset_809

13.09.2019 06:21:12.053 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_809:/content/dam/projects/aicoverwithotherassets/8---Copy---Copy.jpg/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler Finished process execution of com.adobe.cq.dam.dm.process.workflow.DMImageProcess for: VolatileWorkItem_node10_var_workflow_instances_server0_2019-07-31_1_update_asset_809

13.09.2019 06:21:12.053 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_809:/content/dam/projects/aicoverwithotherassets/8---Copy---Copy.jpg/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler start archiving

13.09.2019 06:21:12.054 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_809:/content/dam/projects/aicoverwithotherassets/8---Copy---Copy.jpg/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler looking for WorkflowExternalProcess Proxy

13.09.2019 06:21:12.054 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_809:/content/dam/projects/aicoverwithotherassets/8---Copy---Copy.jpg/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler executing external process with work item VolatileWorkItem_node11_var_workflow_instances_server0_2019-07-31_1_update_asset_809

13.09.2019 06:21:12.055 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_809:/content/dam/projects/aicoverwithotherassets/8---Copy---Copy.jpg/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler external process created with id null

13.09.2019 06:21:12.055 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_809:/content/dam/projects/aicoverwithotherassets/8---Copy---Copy.jpg/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler start archiving

13.09.2019 06:21:12.056 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_809:/content/dam/projects/aicoverwithotherassets/8---Copy---Copy.jpg/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler Start process execution of com.day.cq.dam.core.process.ThumbnailProcess for: VolatileWorkItem_node12_var_workflow_instances_server0_2019-07-31_1_update_asset_809

13.09.2019 06:21:12.573 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_809:/content/dam/projects/aicoverwithotherassets/8---Copy---Copy.jpg/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler Finished process execution of com.day.cq.dam.core.process.ThumbnailProcess for: VolatileWorkItem_node12_var_workflow_instances_server0_2019-07-31_1_update_asset_809

13.09.2019 06:21:12.573 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_809:/content/dam/projects/aicoverwithotherassets/8---Copy---Copy.jpg/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler start archiving

13.09.2019 06:21:12.575 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_809:/content/dam/projects/aicoverwithotherassets/8---Copy---Copy.jpg/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler Start process execution of com.day.cq.dam.pim.impl.sourcing.upload.process.ProductAssetsUploadProcess for: VolatileWorkItem_node13_var_workflow_instances_server0_2019-07-31_1_update_asset_809

13.09.2019 06:21:12.577 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_809:/content/dam/projects/aicoverwithotherassets/8---Copy---Copy.jpg/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler Finished process execution of com.day.cq.dam.pim.impl.sourcing.upload.process.ProductAssetsUploadProcess for: VolatileWorkItem_node13_var_workflow_instances_server0_2019-07-31_1_update_asset_809

13.09.2019 06:21:12.577 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_809:/content/dam/projects/aicoverwithotherassets/8---Copy---Copy.jpg/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler start archiving

13.09.2019 06:21:12.578 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_809:/content/dam/projects/aicoverwithotherassets/8---Copy---Copy.jpg/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler Start process execution of com.day.cq.dam.core.impl.process.DamUpdateAssetWorkflowCompletedProcess for: VolatileWorkItem_node14_var_workflow_instances_server0_2019-07-31_1_update_asset_809

13.09.2019 06:21:12.594 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_809:/content/dam/projects/aicoverwithotherassets/8---Copy---Copy.jpg/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler Finished process execution of com.day.cq.dam.core.impl.process.DamUpdateAssetWorkflowCompletedProcess for: VolatileWorkItem_node14_var_workflow_instances_server0_2019-07-31_1_update_asset_809

13.09.2019 06:21:12.594 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_809:/content/dam/projects/aicoverwithotherassets/8---Copy---Copy.jpg/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler start archiving

13.09.2019 06:21:12.595 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_809:/content/dam/projects/aicoverwithotherassets/8---Copy---Copy.jpg/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler Start process execution of com.day.cq.dam.core.impl.process.SendTransientWorkflowCompletedEmailProcess for: VolatileWorkItem_node15_var_workflow_instances_server0_2019-07-31_1_update_asset_809

13.09.2019 06:21:12.595 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_809:/content/dam/projects/aicoverwithotherassets/8---Copy---Copy.jpg/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler Finished process execution of com.day.cq.dam.core.impl.process.SendTransientWorkflowCompletedEmailProcess for: VolatileWorkItem_node15_var_workflow_instances_server0_2019-07-31_1_update_asset_809

13.09.2019 06:21:12.595 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_809:/content/dam/projects/aicoverwithotherassets/8---Copy---Copy.jpg/jcr:content/renditions/original] com.adobe.granite.workflow.core.WorkflowSessionImpl Attempting to complete work item with ID: VolatileWorkItem_node15_var_workflow_instances_server0_2019-07-31_1_update_asset_809

13.09.2019 06:21:12.600 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_809:/content/dam/projects/aicoverwithotherassets/8---Copy---Copy.jpg/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler Finished processing: /var/workflow/instances/server0/2019-07-31_1/update_asset_809:/content/dam/projects/aicoverwithotherassets/8---Copy---Copy.jpg/jcr:content/renditions/original(Complete execution took: 599ms)

13.09.2019 06:21:12.618 *DEBUG* [sling-threadpool-befce717-30b3-48e8-b1ca-c8909e0383e8-(apache-sling-job-thread-pool)-27-Granite Transient Workflow Queue(com/adobe/granite/workflow/transient/job/var/workflow/models/dam/update_asset)] com.adobe.granite.workflow.core.job.JobHandler ===== isTransient for com/adobe/granite/workflow/transient/job/var/workflow/models/dam/update_asset - value: true

13.09.2019 06:21:12.618 *DEBUG* [sling-threadpool-befce717-30b3-48e8-b1ca-c8909e0383e8-(apache-sling-job-thread-pool)-27-Granite Transient Workflow Queue(com/adobe/granite/workflow/transient/job/var/workflow/models/dam/update_asset)] com.adobe.granite.workflow.core.job.JobHandler ====== Handle transient

13.09.2019 06:21:12.621 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_810:/content/dam/projects/aicoverwithotherassets/AI_test.ai/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler Start processing: /var/workflow/instances/server0/2019-07-31_1/update_asset_810:/content/dam/projects/aicoverwithotherassets/AI_test.ai/jcr:content/renditions/original

13.09.2019 06:21:12.621 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_810:/content/dam/projects/aicoverwithotherassets/AI_test.ai/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler Start process execution of com.day.cq.dam.core.process.GateKeeperProcess for: VolatileWorkItem_node1_var_workflow_instances_server0_2019-07-31_1_update_asset_810

13.09.2019 06:21:12.625 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_810:/content/dam/projects/aicoverwithotherassets/AI_test.ai/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler Finished process execution of com.day.cq.dam.core.process.GateKeeperProcess for: VolatileWorkItem_node1_var_workflow_instances_server0_2019-07-31_1_update_asset_810

13.09.2019 06:21:12.625 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_810:/content/dam/projects/aicoverwithotherassets/AI_test.ai/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler start archiving

13.09.2019 06:21:12.633 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_810:/content/dam/projects/aicoverwithotherassets/AI_test.ai/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler Start process execution of com.day.cq.dam.core.process.MetadataProcessorProcess for: VolatileWorkItem_node2_var_workflow_instances_server0_2019-07-31_1_update_asset_810

13.09.2019 06:21:12.724 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_810:/content/dam/projects/aicoverwithotherassets/AI_test.ai/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler Finished process execution of com.day.cq.dam.core.process.MetadataProcessorProcess for: VolatileWorkItem_node2_var_workflow_instances_server0_2019-07-31_1_update_asset_810

13.09.2019 06:21:12.724 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_810:/content/dam/projects/aicoverwithotherassets/AI_test.ai/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler start archiving

13.09.2019 06:21:12.725 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_810:/content/dam/projects/aicoverwithotherassets/AI_test.ai/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler Start process execution of com.day.cq.dam.video.FFMpegThumbnailProcess for: VolatileWorkItem_node3_var_workflow_instances_server0_2019-07-31_1_update_asset_810

13.09.2019 06:21:12.726 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_810:/content/dam/projects/aicoverwithotherassets/AI_test.ai/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler Finished process execution of com.day.cq.dam.video.FFMpegThumbnailProcess for: VolatileWorkItem_node3_var_workflow_instances_server0_2019-07-31_1_update_asset_810

13.09.2019 06:21:12.726 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_810:/content/dam/projects/aicoverwithotherassets/AI_test.ai/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler start archiving

13.09.2019 06:21:12.726 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_810:/content/dam/projects/aicoverwithotherassets/AI_test.ai/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler Start process execution of com.day.cq.dam.core.process.CommandLineProcess for: VolatileWorkItem_node4_var_workflow_instances_server0_2019-07-31_1_update_asset_810

13.09.2019 06:21:13.704 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_810:/content/dam/projects/aicoverwithotherassets/AI_test.ai/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler Finished process execution of com.day.cq.dam.core.process.CommandLineProcess for: VolatileWorkItem_node4_var_workflow_instances_server0_2019-07-31_1_update_asset_810

13.09.2019 06:21:13.704 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_810:/content/dam/projects/aicoverwithotherassets/AI_test.ai/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler start archiving

13.09.2019 06:21:13.706 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_810:/content/dam/projects/aicoverwithotherassets/AI_test.ai/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler Start process execution of com.day.cq.dam.video.FFMpegTranscodeProcess for: VolatileWorkItem_node5_var_workflow_instances_server0_2019-07-31_1_update_asset_810

13.09.2019 06:21:13.706 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_810:/content/dam/projects/aicoverwithotherassets/AI_test.ai/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler Finished process execution of com.day.cq.dam.video.FFMpegTranscodeProcess for: VolatileWorkItem_node5_var_workflow_instances_server0_2019-07-31_1_update_asset_810

13.09.2019 06:21:13.706 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_810:/content/dam/projects/aicoverwithotherassets/AI_test.ai/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler start archiving

13.09.2019 06:21:13.707 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_810:/content/dam/projects/aicoverwithotherassets/AI_test.ai/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler looking for WorkflowExternalProcess Proxy

13.09.2019 06:21:13.707 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_810:/content/dam/projects/aicoverwithotherassets/AI_test.ai/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler executing external process with work item VolatileWorkItem_node6_var_workflow_instances_server0_2019-07-31_1_update_asset_810

13.09.2019 06:21:13.708 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_810:/content/dam/projects/aicoverwithotherassets/AI_test.ai/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler external process created with id null

13.09.2019 06:21:13.708 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_810:/content/dam/projects/aicoverwithotherassets/AI_test.ai/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler start archiving

13.09.2019 06:21:13.708 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_810:/content/dam/projects/aicoverwithotherassets/AI_test.ai/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler Start process execution of com.day.cq.dam.indd.process.INDDPageExtractProcess for: VolatileWorkItem_node7_var_workflow_instances_server0_2019-07-31_1_update_asset_810

13.09.2019 06:21:13.709 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_810:/content/dam/projects/aicoverwithotherassets/AI_test.ai/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler Finished process execution of com.day.cq.dam.indd.process.INDDPageExtractProcess for: VolatileWorkItem_node7_var_workflow_instances_server0_2019-07-31_1_update_asset_810

13.09.2019 06:21:13.709 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_810:/content/dam/projects/aicoverwithotherassets/AI_test.ai/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler start archiving

13.09.2019 06:21:13.710 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_810:/content/dam/projects/aicoverwithotherassets/AI_test.ai/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler Start process execution of com.day.cq.dam.core.process.CreatePdfPreviewProcess for: VolatileWorkItem_node8_var_workflow_instances_server0_2019-07-31_1_update_asset_810

13.09.2019 06:21:16.177 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_810:/content/dam/projects/aicoverwithotherassets/AI_test.ai/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler Finished process execution of com.day.cq.dam.core.process.CreatePdfPreviewProcess for: VolatileWorkItem_node8_var_workflow_instances_server0_2019-07-31_1_update_asset_810

13.09.2019 06:21:16.177 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_810:/content/dam/projects/aicoverwithotherassets/AI_test.ai/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler start archiving

13.09.2019 06:21:16.177 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_810:/content/dam/projects/aicoverwithotherassets/AI_test.ai/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler Start process execution of com.day.cq.dam.core.process.CreateSubAssetsProcess for: VolatileWorkItem_node9_var_workflow_instances_server0_2019-07-31_1_update_asset_810

13.09.2019 06:21:17.077 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_810:/content/dam/projects/aicoverwithotherassets/AI_test.ai/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler Finished process execution of com.day.cq.dam.core.process.CreateSubAssetsProcess for: VolatileWorkItem_node9_var_workflow_instances_server0_2019-07-31_1_update_asset_810

13.09.2019 06:21:17.077 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_810:/content/dam/projects/aicoverwithotherassets/AI_test.ai/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler start archiving

13.09.2019 06:21:17.097 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_810:/content/dam/projects/aicoverwithotherassets/AI_test.ai/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler Start process execution of com.adobe.cq.dam.dm.process.workflow.DMImageProcess for: VolatileWorkItem_node10_var_workflow_instances_server0_2019-07-31_1_update_asset_810

13.09.2019 06:21:17.097 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_810:/content/dam/projects/aicoverwithotherassets/AI_test.ai/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler Finished process execution of com.adobe.cq.dam.dm.process.workflow.DMImageProcess for: VolatileWorkItem_node10_var_workflow_instances_server0_2019-07-31_1_update_asset_810

13.09.2019 06:21:17.097 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_810:/content/dam/projects/aicoverwithotherassets/AI_test.ai/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler start archiving

13.09.2019 06:21:17.097 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_810:/content/dam/projects/aicoverwithotherassets/AI_test.ai/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler looking for WorkflowExternalProcess Proxy

13.09.2019 06:21:17.098 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_810:/content/dam/projects/aicoverwithotherassets/AI_test.ai/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler executing external process with work item VolatileWorkItem_node11_var_workflow_instances_server0_2019-07-31_1_update_asset_810

13.09.2019 06:21:17.101 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_810:/content/dam/projects/aicoverwithotherassets/AI_test.ai/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler external process created with id null

13.09.2019 06:21:17.101 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_810:/content/dam/projects/aicoverwithotherassets/AI_test.ai/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler start archiving

13.09.2019 06:21:17.102 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_810:/content/dam/projects/aicoverwithotherassets/AI_test.ai/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler Start process execution of com.day.cq.dam.core.process.ThumbnailProcess for: VolatileWorkItem_node12_var_workflow_instances_server0_2019-07-31_1_update_asset_810

13.09.2019 06:21:17.140 *DEBUG* [Workflow Starter Thread] com.adobe.granite.workflow.core.WorkflowSessionImpl created transient job id: 2019/9/13/6/21/17e1ac2a-cf62-4115-8d9f-6469f028699f_816

13.09.2019 06:21:17.216 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_810:/content/dam/projects/aicoverwithotherassets/AI_test.ai/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler Finished process execution of com.day.cq.dam.core.process.ThumbnailProcess for: VolatileWorkItem_node12_var_workflow_instances_server0_2019-07-31_1_update_asset_810

13.09.2019 06:21:17.216 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_810:/content/dam/projects/aicoverwithotherassets/AI_test.ai/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler start archiving

13.09.2019 06:21:17.218 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_810:/content/dam/projects/aicoverwithotherassets/AI_test.ai/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler Start process execution of com.day.cq.dam.pim.impl.sourcing.upload.process.ProductAssetsUploadProcess for: VolatileWorkItem_node13_var_workflow_instances_server0_2019-07-31_1_update_asset_810

13.09.2019 06:21:17.220 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_810:/content/dam/projects/aicoverwithotherassets/AI_test.ai/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler Finished process execution of com.day.cq.dam.pim.impl.sourcing.upload.process.ProductAssetsUploadProcess for: VolatileWorkItem_node13_var_workflow_instances_server0_2019-07-31_1_update_asset_810

13.09.2019 06:21:17.220 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_810:/content/dam/projects/aicoverwithotherassets/AI_test.ai/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler start archiving

13.09.2019 06:21:17.221 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_810:/content/dam/projects/aicoverwithotherassets/AI_test.ai/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler Start process execution of com.day.cq.dam.core.impl.process.DamUpdateAssetWorkflowCompletedProcess for: VolatileWorkItem_node14_var_workflow_instances_server0_2019-07-31_1_update_asset_810

13.09.2019 06:21:17.222 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_810:/content/dam/projects/aicoverwithotherassets/AI_test.ai/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler Finished process execution of com.day.cq.dam.core.impl.process.DamUpdateAssetWorkflowCompletedProcess for: VolatileWorkItem_node14_var_workflow_instances_server0_2019-07-31_1_update_asset_810

13.09.2019 06:21:17.222 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_810:/content/dam/projects/aicoverwithotherassets/AI_test.ai/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler start archiving

13.09.2019 06:21:17.223 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_810:/content/dam/projects/aicoverwithotherassets/AI_test.ai/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler Start process execution of com.day.cq.dam.core.impl.process.SendTransientWorkflowCompletedEmailProcess for: VolatileWorkItem_node15_var_workflow_instances_server0_2019-07-31_1_update_asset_810

13.09.2019 06:21:17.223 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_810:/content/dam/projects/aicoverwithotherassets/AI_test.ai/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler Finished process execution of com.day.cq.dam.core.impl.process.SendTransientWorkflowCompletedEmailProcess for: VolatileWorkItem_node15_var_workflow_instances_server0_2019-07-31_1_update_asset_810

13.09.2019 06:21:17.223 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_810:/content/dam/projects/aicoverwithotherassets/AI_test.ai/jcr:content/renditions/original] com.adobe.granite.workflow.core.WorkflowSessionImpl Attempting to complete work item with ID: VolatileWorkItem_node15_var_workflow_instances_server0_2019-07-31_1_update_asset_810

13.09.2019 06:21:17.227 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_810:/content/dam/projects/aicoverwithotherassets/AI_test.ai/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler Finished processing: /var/workflow/instances/server0/2019-07-31_1/update_asset_810:/content/dam/projects/aicoverwithotherassets/AI_test.ai/jcr:content/renditions/original(Complete execution took: 4606ms)

13.09.2019 06:21:17.251 *DEBUG* [sling-threadpool-befce717-30b3-48e8-b1ca-c8909e0383e8-(apache-sling-job-thread-pool)-27-Granite Transient Workflow Queue(com/adobe/granite/workflow/transient/job/var/workflow/models/dam/process_subasset)] com.adobe.granite.workflow.core.job.JobHandler ===== isTransient for com/adobe/granite/workflow/transient/job/var/workflow/models/dam/process_subasset - value: true

13.09.2019 06:21:17.251 *DEBUG* [sling-threadpool-befce717-30b3-48e8-b1ca-c8909e0383e8-(apache-sling-job-thread-pool)-27-Granite Transient Workflow Queue(com/adobe/granite/workflow/transient/job/var/workflow/models/dam/process_subasset)] com.adobe.granite.workflow.core.job.JobHandler ====== Handle transient

13.09.2019 06:21:17.253 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/process_subasset_811:/content/dam/projects/aicoverwithotherassets/AI_test.ai/subassets/layer1.png/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler Start processing: /var/workflow/instances/server0/2019-07-31_1/process_subasset_811:/content/dam/projects/aicoverwithotherassets/AI_test.ai/subassets/layer1.png/jcr:content/renditions/original

13.09.2019 06:21:17.253 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/process_subasset_811:/content/dam/projects/aicoverwithotherassets/AI_test.ai/subassets/layer1.png/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler Start process execution of com.day.cq.dam.core.process.CreateWebEnabledImageProcess for: VolatileWorkItem_node1_var_workflow_instances_server0_2019-07-31_1_process_subasset_811

13.09.2019 06:21:17.410 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/process_subasset_811:/content/dam/projects/aicoverwithotherassets/AI_test.ai/subassets/layer1.png/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler Finished process execution of com.day.cq.dam.core.process.CreateWebEnabledImageProcess for: VolatileWorkItem_node1_var_workflow_instances_server0_2019-07-31_1_process_subasset_811

13.09.2019 06:21:17.411 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/process_subasset_811:/content/dam/projects/aicoverwithotherassets/AI_test.ai/subassets/layer1.png/jcr:content/renditions/original] com.adobe.granite.workflow.core.WorkflowSessionImpl Attempting to complete work item with ID: VolatileWorkItem_node1_var_workflow_instances_server0_2019-07-31_1_process_subasset_811

13.09.2019 06:21:17.415 *DEBUG* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/process_subasset_811:/content/dam/projects/aicoverwithotherassets/AI_test.ai/subassets/layer1.png/jcr:content/renditions/original] com.adobe.granite.workflow.core.job.JobHandler Finished processing: /var/workflow/instances/server0/2019-07-31_1/process_subasset_811:/content/dam/projects/aicoverwithotherassets/AI_test.ai/subassets/layer1.png/jcr:content/renditions/original(Complete execution took: 162ms)

Avatar

Avatar

jbrar

Employee

Avatar

jbrar

Employee

jbrar
Employee

15-09-2019

Looks like AEM is not triggering the "convert" command. Can you check if you have configured the DAM Update Asset workflow properly to work with ImageMagick as per [1]?

[1] Install and configure ImageMagick to work with AEM Assets

Avatar

Avatar

manojk976698

Avatar

manojk976698

manojk976698

16-09-2019

I am getting this in error.log when i make AI file as cover :-

16.09.2019 06:11:27.404 *INFO* [sling-oak-observation-15] org.apache.sling.event.impl.jobs.queues.JobQueueImpl.Granite Transient Workflow Queue Starting job queue Granite Transient Workflow Queue

16.09.2019 06:11:27.407 *INFO* [sling-oak-observation-15] org.apache.sling.event Service [QueueMBean for queue Granite Transient Workflow Queue,110713, [org.apache.sling.event.jobs.jmx.StatisticsMBean]] ServiceEvent REGISTERED

16.09.2019 06:11:27.433 *INFO* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_952:/content/dam/projects/checkaiepscover/cover/jcr:content/renditions/original] com.adobe.xmp.worker.files.ncomm.XMPFilesNComm [PERF][EXECUTE_START] | /mnt2/java_temp/cq-dam-wf-file3937067595363153570.tmp | XMP extraction

16.09.2019 06:11:27.449 *INFO* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_952:/content/dam/projects/checkaiepscover/cover/jcr:content/renditions/original] com.adobe.xmp.worker.files.ncomm.XMPFilesNComm [XMPFilesProcessor-4787] PROC: Executing request extractXMP

16.09.2019 06:11:27.449 *INFO* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_952:/content/dam/projects/checkaiepscover/cover/jcr:content/renditions/original] com.adobe.xmp.worker.files.ncomm.XMPFilesNComm [PERF][EXECUTE_END] | /mnt2/java_temp/cq-dam-wf-file3937067595363153570.tmp | XMP extraction

16.09.2019 06:11:27.451 *INFO* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_952:/content/dam/projects/checkaiepscover/cover/jcr:content/renditions/original] com.adobe.granite.asset.core.impl.metadata.xmpjcr.XmpToJcrVisitor No custom schema available, using default XMP schema service

16.09.2019 06:11:27.474 *INFO* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_952:/content/dam/projects/checkaiepscover/cover/jcr:content/renditions/original] com.day.cq.dam.core.process.CommandLineProcess execute: mime type [image/png] of asset [/content/dam/projects/checkaiepscover/cover] is not in list of accepted mime types [[image/eps, image/x-eps, application/postscript, application/eps, application/x-eps]], ignoring.

16.09.2019 06:11:27.485 *INFO* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_952:/content/dam/projects/checkaiepscover/cover/jcr:content/renditions/original] com.day.cq.dam.scene7.impl.S7ConfigResolverImpl Get S7Config null for resource /content/dam/projects/checkaiepscover/cover

16.09.2019 06:11:27.485 *INFO* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_952:/content/dam/projects/checkaiepscover/cover/jcr:content/renditions/original] com.day.cq.dam.scene7.impl.process.Scene7UploadProcess Skip S7 upload due to cannot find configuration for file: /content/dam/projects/checkaiepscover/cover

16.09.2019 06:11:27.528 *INFO* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_952:/content/dam/projects/checkaiepscover/cover/jcr:content/renditions/original] com.day.cq.dam.core.impl.RenditionMakerImpl generated rendition: /content/dam/projects/checkaiepscover/cover/jcr:content/renditions/cq5dam.thumbnail.140.100.png

16.09.2019 06:11:27.534 *INFO* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_952:/content/dam/projects/checkaiepscover/cover/jcr:content/renditions/original] com.day.cq.dam.core.impl.RenditionMakerImpl generated rendition: /content/dam/projects/checkaiepscover/cover/jcr:content/renditions/cq5dam.thumbnail.48.48.png

16.09.2019 06:11:27.556 *INFO* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_952:/content/dam/projects/checkaiepscover/cover/jcr:content/renditions/original] com.day.cq.dam.core.impl.RenditionMakerImpl generated rendition: /content/dam/projects/checkaiepscover/cover/jcr:content/renditions/cq5dam.thumbnail.319.319.png

16.09.2019 06:11:27.556 *INFO* [49.249.236.30 [1568614287124] POST /bin/mls/crproject HTTP/1.1] com.adobe.granite.workflow.core.advance.DynamicParticipantNodeHandler Activate com.adobe.granite.workflow.core.advance.DynamicParticipantNodeHandler@2b7bbac6

16.09.2019 06:11:27.592 *INFO* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_952:/content/dam/projects/checkaiepscover/cover/jcr:content/renditions/original] com.day.cq.dam.core.impl.RenditionMakerImpl generated rendition: /content/dam/projects/checkaiepscover/cover/jcr:content/renditions/cq5dam.web.1280.1280.png

16.09.2019 06:11:27.614 *INFO* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_952:/content/dam/projects/checkaiepscover/cover/jcr:content/renditions/original] com.day.cq.dam.pim.impl.sourcing.upload.process.ProductAssetsUploadProcess payload path :/content/dam/projects/checkaiepscover/cover/jcr:content/renditions/original

16.09.2019 06:11:27.668 *INFO* [JobHandler: /var/workflow/instances/server0/2019-07-31_1/update_asset_952:/content/dam/projects/checkaiepscover/cover/jcr:content/renditions/original] com.day.cq.dam.core.impl.process.SendTransientWorkflowCompletedEmailProcess Transient workflow complete email notification is disabled

Avatar

Avatar

VikramGaur

Employee

Avatar

VikramGaur

Employee

VikramGaur
Employee

02-10-2019

Hi Manoj,

I hope you are good.

Do you have Dynamic Media configuration on your AEM instance?

If yes, could you please check the run mode used for Dynamic Media ?

Also, what is the exact version of your AEM instance?

Thanks,

Vikram Gaur