search

valtech / aem-easy-content-upgrade

AEM Easy Content Upgrade simplifies content migrations in AEM projects
Other
61 stars 26 forks source link

Issue with AecuInstallHook on the local AEM SDK #183

Closed michal-tobiasz-wttech closed 2 years ago

michal-tobiasz-wttech commented 2 years ago

I wanted to use AECU version 6.0.0 of the tool. I have an AEM project created from archetypeVersion=36 with aemVersion=cloud. 2022.3.6698.20220318T233218Z-220400</aem.sdk.api> I have created a module based on the "examples-cloud" (https://github.com/valtech/aem-easy-content-upgrade/tree/develop/examples-cloud). Additionally, the module is added to the "all" module as embedded. When executing the command on the local AEM SDK mvn clean install -PautoInstallSinglePackage -PinstallHook in the AEM logs I see an exception and groovy scripts are not executed.

Could you please suggest how to avoid this exception?

09.05.2022 23:29:33.342 *WARN* [OsgiInstallerImpl] org.apache.jackrabbit.vault.packaging.impl.InstallHookProcessorImpl Hook aecu threw runtime exception.
java.lang.NullPointerException: null
    at de.valtech.aecu.core.installhook.AecuTrackerListener.onMessage(AecuTrackerListener.java:86) [de.valtech.aecu.core:6.0.0]
    at de.valtech.aecu.core.installhook.AecuTrackerListener.logMessage(AecuTrackerListener.java:139) [de.valtech.aecu.core:6.0.0]
    at de.valtech.aecu.core.installhook.AecuTrackerListener.<init>(AecuTrackerListener.java:70) [de.valtech.aecu.core:6.0.0]
    at de.valtech.aecu.core.installhook.AecuInstallHook.execute(AecuInstallHook.java:91) [de.valtech.aecu.core:6.0.0]
    at org.apache.jackrabbit.vault.packaging.impl.InstallHookProcessorImpl.execute(InstallHookProcessorImpl.java:143) [org.apache.jackrabbit.vault:3.4.0]
    at org.apache.jackrabbit.vault.packaging.impl.ZipVaultPackage.extract(ZipVaultPackage.java:226) [org.apache.jackrabbit.vault:3.4.0]
    at org.apache.jackrabbit.vault.packaging.impl.JcrPackageImpl.extract(JcrPackageImpl.java:401) [org.apache.jackrabbit.vault:3.4.0]
    at org.apache.jackrabbit.vault.packaging.impl.JcrPackageImpl.extract(JcrPackageImpl.java:360) [org.apache.jackrabbit.vault:3.4.0]
    at org.apache.jackrabbit.vault.packaging.impl.JcrPackageImpl.install(JcrPackageImpl.java:354) [org.apache.jackrabbit.vault:3.4.0]
    at com.adobe.granite.installer.factory.packages.impl.PackageTransformer$InstallPackageTask.execute(PackageTransformer.java:350) [com.adobe.granite.installer.factory.packages:1.0.20]
    at org.apache.sling.installer.core.impl.OsgiInstallerImpl.doExecuteTasks(OsgiInstallerImpl.java:918) [org.apache.sling.installer.core:3.12.0]
    at org.apache.sling.installer.core.impl.OsgiInstallerImpl.executeTasks(OsgiInstallerImpl.java:755) [org.apache.sling.installer.core:3.12.0]
    at org.apache.sling.installer.core.impl.OsgiInstallerImpl.run(OsgiInstallerImpl.java:304) [org.apache.sling.installer.core:3.12.0]
    at java.base/java.lang.Thread.run(Thread.java:834)
09.05.2022 23:29:33.343 *INFO* [OsgiInstallerImpl] de.valtech.aecu.core.installhook.AecuInstallHook Executing in phase INSTALLED
09.05.2022 23:29:33.344 *WARN* [OsgiInstallerImpl] org.apache.jackrabbit.vault.packaging.impl.InstallHookProcessorImpl Hook aecu threw runtime exception.
java.lang.NullPointerException: null
    at de.valtech.aecu.core.installhook.AecuInstallHook.getScriptsForExecution(AecuInstallHook.java:121) [de.valtech.aecu.core:6.0.0]
    at de.valtech.aecu.core.installhook.AecuInstallHook.execute(AecuInstallHook.java:99) [de.valtech.aecu.core:6.0.0]
    at org.apache.jackrabbit.vault.packaging.impl.InstallHookProcessorImpl.execute(InstallHookProcessorImpl.java:143) [org.apache.jackrabbit.vault:3.4.0]
    at org.apache.jackrabbit.vault.packaging.impl.ZipVaultPackage.extract(ZipVaultPackage.java:240) [org.apache.jackrabbit.vault:3.4.0]
    at org.apache.jackrabbit.vault.packaging.impl.JcrPackageImpl.extract(JcrPackageImpl.java:401) [org.apache.jackrabbit.vault:3.4.0]
    at org.apache.jackrabbit.vault.packaging.impl.JcrPackageImpl.extract(JcrPackageImpl.java:360) [org.apache.jackrabbit.vault:3.4.0]
    at org.apache.jackrabbit.vault.packaging.impl.JcrPackageImpl.install(JcrPackageImpl.java:354) [org.apache.jackrabbit.vault:3.4.0]
gruberrolandvaltech commented 2 years ago

Which version of the cloud SDK is this?

michal-tobiasz-wttech commented 2 years ago
2022.3.6698.20220318T233218Z-220400
gruberrolandvaltech commented 2 years ago

I just tried with 2022.4.7138.20220427T075748Z-220401 and it works. Do you have this issue

michal-tobiasz-wttech commented 2 years ago

It was every time I was executing: mvn clean install -PautoInstallSinglePackage -PinstallHook Today I have executed again deployment with AECU locally

18.05.2022 11:59:46.507 *INFO* [qtp1765150305-108] org.apache.jackrabbit.vault.packaging.impl.ActivityLog com.test:test.all:1.0.0-SNAPSHOT: CREATE
18.05.2022 11:59:46.507 *INFO* [qtp1765150305-108] org.apache.jackrabbit.vault.packaging.impl.ActivityLog com.test:test.all:1.0.0-SNAPSHOT: UPLOAD
18.05.2022 11:59:46.520 *INFO* [qtp1765150305-108] com.day.crx.packaging.impl.SlingInstallerSupportImpl Sling installer node /system/sling/installer/jcr/pauseInstallation/d9558f2e-c68a-4d95-a8b2-2010c0ee75b2/com.day.crx.packaging.impl.SlingInstallerSupportImpl/b01b0d8e-de15-451d-87a7-abc28ac7ea2d created.
18.05.2022 11:59:46.693 *INFO* [qtp1765150305-108] org.apache.jackrabbit.vault.packaging.impl.ActivityLog com.test:test.all:1.0.0-SNAPSHOT: INSTALL
18.05.2022 11:59:46.699 *INFO* [qtp1765150305-108] com.day.crx.packaging.impl.SlingInstallerSupportImpl Sling installer node /system/sling/installer/jcr/pauseInstallation/d9558f2e-c68a-4d95-a8b2-2010c0ee75b2/com.day.crx.packaging.impl.SlingInstallerSupportImpl/b01b0d8e-de15-451d-87a7-abc28ac7ea2d removed.
18.05.2022 11:59:46.829 *INFO* [JcrInstaller.1] org.apache.sling.installer.provider.jcr.impl.JcrInstaller Registering resource with OSGi installer: [InstallableResource, priority=200, id=/apps/test-packages/application/install/test.ui.apps-1.0.0-SNAPSHOT.zip, InstallableResource, priority=200, id=/apps/test-packages/application/install/test.core-1.0.0-SNAPSHOT.jar, InstallableResource, priority=200, id=/apps/test-packages/application/install/test.ui.aecu-1.0.0-SNAPSHOT.zip, InstallableResource, priority=200, id=/apps/test-packages/application/install/test.ui.acl-1.0.0-SNAPSHOT.zip, InstallableResource, priority=200, id=/apps/test-packages/application/install/test.ui.config-1.0.0-SNAPSHOT.zip]
18.05.2022 11:59:46.835 *INFO* [JcrInstaller.1] org.apache.sling.installer.provider.jcr.impl.JcrInstaller Registering resource with OSGi installer: [InstallableResource, priority=200, id=/apps/test-packages/content/install/test.ui.content-1.0.0-SNAPSHOT.zip]
18.05.2022 11:59:46.847 *INFO* [OsgiInstallerImpl] org.apache.jackrabbit.vault.packaging.impl.ActivityLog com.test:test.ui.apps:1.0.0-SNAPSHOT: CREATE
18.05.2022 11:59:46.847 *INFO* [OsgiInstallerImpl] org.apache.jackrabbit.vault.packaging.impl.ActivityLog com.test:test.ui.apps:1.0.0-SNAPSHOT: UPLOAD
18.05.2022 11:59:46.854 *INFO* [OsgiInstallerImpl] org.apache.jackrabbit.vault.packaging.impl.ActivityLog com.test:test.ui.apps:1.0.0-SNAPSHOT: EXTRACT_SUB_PACKAGES ()
18.05.2022 11:59:46.859 *INFO* [OsgiInstallerImpl] org.apache.jackrabbit.vault.packaging.impl.ActivityLog com.test:test.ui.aecu:1.0.0-SNAPSHOT: CREATE
18.05.2022 11:59:46.859 *INFO* [OsgiInstallerImpl] org.apache.jackrabbit.vault.packaging.impl.ActivityLog com.test:test.ui.aecu:1.0.0-SNAPSHOT: UPLOAD
18.05.2022 11:59:46.866 *INFO* [OsgiInstallerImpl] org.apache.jackrabbit.vault.packaging.impl.ActivityLog com.test:test.ui.aecu:1.0.0-SNAPSHOT: EXTRACT_SUB_PACKAGES ()
18.05.2022 11:59:46.869 *INFO* [OsgiInstallerImpl] org.apache.jackrabbit.vault.packaging.impl.ActivityLog com.test:test.ui.acl:1.0.0-SNAPSHOT: CREATE
18.05.2022 11:59:46.869 *INFO* [OsgiInstallerImpl] org.apache.jackrabbit.vault.packaging.impl.ActivityLog com.test:test.ui.acl:1.0.0-SNAPSHOT: UPLOAD
18.05.2022 11:59:46.877 *INFO* [OsgiInstallerImpl] org.apache.jackrabbit.vault.packaging.impl.ActivityLog com.test:test.ui.acl:1.0.0-SNAPSHOT: EXTRACT_SUB_PACKAGES ()
18.05.2022 11:59:46.881 *INFO* [OsgiInstallerImpl] org.apache.jackrabbit.vault.packaging.impl.ActivityLog com.test:test.ui.config:1.0.0-SNAPSHOT: CREATE
18.05.2022 11:59:46.881 *INFO* [OsgiInstallerImpl] org.apache.jackrabbit.vault.packaging.impl.ActivityLog com.test:test.ui.config:1.0.0-SNAPSHOT: UPLOAD
18.05.2022 11:59:46.888 *INFO* [OsgiInstallerImpl] org.apache.jackrabbit.vault.packaging.impl.ActivityLog com.test:test.ui.config:1.0.0-SNAPSHOT: EXTRACT_SUB_PACKAGES ()
18.05.2022 11:59:46.903 *INFO* [OsgiInstallerImpl] org.apache.jackrabbit.vault.packaging.impl.InstallHookProcessorImpl Loading Hook aecu: Main-Class = de.valtech.aecu.core.installhook.AecuInstallHook
18.05.2022 11:59:46.903 *INFO* [OsgiInstallerImpl] org.apache.jackrabbit.vault.packaging.impl.InstallHookProcessorImpl Hook aecu registered.
18.05.2022 11:59:46.903 *INFO* [OsgiInstallerImpl] de.valtech.aecu.core.installhook.AecuInstallHook Executing in phase PREPARE
18.05.2022 11:59:46.903 *WARN* [OsgiInstallerImpl] org.apache.jackrabbit.vault.packaging.impl.InstallHookProcessorImpl Hook aecu threw runtime exception.
java.lang.NullPointerException: null
    at de.valtech.aecu.core.installhook.AecuTrackerListener.onMessage(AecuTrackerListener.java:86) [de.valtech.aecu.core:6.0.0]
    at de.valtech.aecu.core.installhook.AecuTrackerListener.logMessage(AecuTrackerListener.java:139) [de.valtech.aecu.core:6.0.0]
    at de.valtech.aecu.core.installhook.AecuTrackerListener.<init>(AecuTrackerListener.java:70) [de.valtech.aecu.core:6.0.0]
    at de.valtech.aecu.core.installhook.AecuInstallHook.execute(AecuInstallHook.java:91) [de.valtech.aecu.core:6.0.0]
    at org.apache.jackrabbit.vault.packaging.impl.InstallHookProcessorImpl.execute(InstallHookProcessorImpl.java:143) [org.apache.jackrabbit.vault:3.4.0]
    at org.apache.jackrabbit.vault.packaging.impl.ZipVaultPackage.extract(ZipVaultPackage.java:226) [org.apache.jackrabbit.vault:3.4.0]
    at org.apache.jackrabbit.vault.packaging.impl.JcrPackageImpl.extract(JcrPackageImpl.java:401) [org.apache.jackrabbit.vault:3.4.0]
    at org.apache.jackrabbit.vault.packaging.impl.JcrPackageImpl.extract(JcrPackageImpl.java:360) [org.apache.jackrabbit.vault:3.4.0]
    at org.apache.jackrabbit.vault.packaging.impl.JcrPackageImpl.install(JcrPackageImpl.java:354) [org.apache.jackrabbit.vault:3.4.0]
    at com.adobe.granite.installer.factory.packages.impl.PackageTransformer$InstallPackageTask.execute(PackageTransformer.java:350) [com.adobe.granite.installer.factory.packages:1.0.20]
    at org.apache.sling.installer.core.impl.OsgiInstallerImpl.doExecuteTasks(OsgiInstallerImpl.java:918) [org.apache.sling.installer.core:3.12.0]
    at org.apache.sling.installer.core.impl.OsgiInstallerImpl.executeTasks(OsgiInstallerImpl.java:755) [org.apache.sling.installer.core:3.12.0]
    at org.apache.sling.installer.core.impl.OsgiInstallerImpl.run(OsgiInstallerImpl.java:304) [org.apache.sling.installer.core:3.12.0]
    at java.base/java.lang.Thread.run(Thread.java:834)
18.05.2022 11:59:46.904 *INFO* [OsgiInstallerImpl] de.valtech.aecu.core.installhook.AecuInstallHook Executing in phase INSTALLED
18.05.2022 11:59:46.904 *WARN* [OsgiInstallerImpl] org.apache.jackrabbit.vault.packaging.impl.InstallHookProcessorImpl Hook aecu threw runtime exception.
java.lang.NullPointerException: null
    at de.valtech.aecu.core.installhook.AecuInstallHook.getScriptsForExecution(AecuInstallHook.java:121) [de.valtech.aecu.core:6.0.0]
    at de.valtech.aecu.core.installhook.AecuInstallHook.execute(AecuInstallHook.java:99) [de.valtech.aecu.core:6.0.0]
    at org.apache.jackrabbit.vault.packaging.impl.InstallHookProcessorImpl.execute(InstallHookProcessorImpl.java:143) [org.apache.jackrabbit.vault:3.4.0]
    at org.apache.jackrabbit.vault.packaging.impl.ZipVaultPackage.extract(ZipVaultPackage.java:240) [org.apache.jackrabbit.vault:3.4.0]
    at org.apache.jackrabbit.vault.packaging.impl.JcrPackageImpl.extract(JcrPackageImpl.java:401) [org.apache.jackrabbit.vault:3.4.0]
    at org.apache.jackrabbit.vault.packaging.impl.JcrPackageImpl.extract(JcrPackageImpl.java:360) [org.apache.jackrabbit.vault:3.4.0]
    at org.apache.jackrabbit.vault.packaging.impl.JcrPackageImpl.install(JcrPackageImpl.java:354) [org.apache.jackrabbit.vault:3.4.0]
    at com.adobe.granite.installer.factory.packages.impl.PackageTransformer$InstallPackageTask.execute(PackageTransformer.java:350) [com.adobe.granite.installer.factory.packages:1.0.20]
    at org.apache.sling.installer.core.impl.OsgiInstallerImpl.doExecuteTasks(OsgiInstallerImpl.java:918) [org.apache.sling.installer.core:3.12.0]
    at org.apache.sling.installer.core.impl.OsgiInstallerImpl.executeTasks(OsgiInstallerImpl.java:755) [org.apache.sling.installer.core:3.12.0]
    at org.apache.sling.installer.core.impl.OsgiInstallerImpl.run(OsgiInstallerImpl.java:304) [org.apache.sling.installer.core:3.12.0]
    at java.base/java.lang.Thread.run(Thread.java:834)
18.05.2022 11:59:46.904 *INFO* [OsgiInstallerImpl] de.valtech.aecu.core.installhook.AecuInstallHook Executing in phase END
18.05.2022 11:59:46.910 *INFO* [OsgiInstallerImpl] org.apache.jackrabbit.vault.packaging.impl.ActivityLog com.test:test.ui.aecu:1.0.0-SNAPSHOT: INSTALL
18.05.2022 11:59:46.911 *INFO* [OsgiInstallerImpl] org.apache.sling.audit.osgi.installer TaskResource(url=jcrinstall:/apps/test-packages/application/install/test.ui.aecu-1.0.0-SNAPSHOT.zip, entity=content-package:com.test:test.ui.aecu, state=INSTALL, attributes=[org.apache.sling.installer.api.tasks.ResourceTransformer=:50:76:78:29:957:, package-id=com.test:test.ui.aecu:1.0.0-SNAPSHOT, Bundle-Version=1.0.0.SNAPSHOT], digest=1652867986521): content package installed
18.05.2022 11:59:46.959 *INFO* [OsgiInstallerImpl] org.apache.jackrabbit.vault.packaging.impl.ActivityLog com.test:test.ui.apps:1.0.0-SNAPSHOT: INSTALL
18.05.2022 11:59:46.959 *INFO* [OsgiInstallerImpl] org.apache.sling.audit.osgi.installer TaskResource(url=jcrinstall:/apps/test-packages/application/install/test.ui.apps-1.0.0-SNAPSHOT.zip, entity=content-package:com.test:test.ui.apps, state=INSTALL, attributes=[org.apache.sling.installer.api.tasks.ResourceTransformer=:50:76:78:29:957:, package-id=com.test:test.ui.apps:1.0.0-SNAPSHOT, Bundle-Version=1.0.0.SNAPSHOT], digest=1652867986525): content package installed
18.05.2022 11:59:46.967 *INFO* [OsgiInstallerImpl] org.apache.jackrabbit.vault.packaging.impl.ActivityLog com.test:test.ui.config:1.0.0-SNAPSHOT: INSTALL
18.05.2022 11:59:46.967 *INFO* [OsgiInstallerImpl] org.apache.sling.audit.osgi.installer TaskResource(url=jcrinstall:/apps/test-packages/application/install/test.ui.config-1.0.0-SNAPSHOT.zip, entity=content-package:com.test:test.ui.config, state=INSTALL, attributes=[org.apache.sling.installer.api.tasks.ResourceTransformer=:50:76:78:29:957:, package-id=com.test:test.ui.config:1.0.0-SNAPSHOT, Bundle-Version=1.0.0.SNAPSHOT], digest=1652867986521): content package installed

In case when I run below command it executes groovy scripts but have also exception mvn clean install -PautoInstallPackage -PinstallHook

18.05.2022 11:37:00.455 *INFO* [qtp1566870367-246] org.apache.jackrabbit.vault.packaging.impl.ActivityLog com.test:test.ui.apps:1.0.0-SNAPSHOT: CREATE
18.05.2022 11:37:00.455 *INFO* [qtp1566870367-246] org.apache.jackrabbit.vault.packaging.impl.ActivityLog com.test:test.ui.apps:1.0.0-SNAPSHOT: UPLOAD
18.05.2022 11:37:00.492 *INFO* [qtp1566870367-246] com.day.crx.packaging.impl.SlingInstallerSupportImpl Sling installer node /system/sling/installer/jcr/pauseInstallation/32df7f5e-d305-4d7b-a814-1a77b3cb5343/com.day.crx.packaging.impl.SlingInstallerSupportImpl/dd14c28d-6604-4410-85f6-697250d807cc created.
18.05.2022 11:37:00.583 *INFO* [qtp1566870367-246] org.apache.jackrabbit.vault.packaging.impl.ActivityLog com.test:test.ui.apps:1.0.0-SNAPSHOT: INSTALL
18.05.2022 11:37:00.600 *INFO* [qtp1566870367-246] com.day.crx.packaging.impl.SlingInstallerSupportImpl Sling installer node /system/sling/installer/jcr/pauseInstallation/32df7f5e-d305-4d7b-a814-1a77b3cb5343/com.day.crx.packaging.impl.SlingInstallerSupportImpl/dd14c28d-6604-4410-85f6-697250d807cc removed.
18.05.2022 11:37:09.862 *INFO* [qtp1566870367-247] org.apache.jackrabbit.vault.packaging.impl.ActivityLog com.test:test.ui.content:1.0.0-SNAPSHOT: CREATE
18.05.2022 11:37:09.862 *INFO* [qtp1566870367-247] org.apache.jackrabbit.vault.packaging.impl.ActivityLog com.test:test.ui.content:1.0.0-SNAPSHOT: UPLOAD
18.05.2022 11:37:09.892 *INFO* [qtp1566870367-247] com.day.crx.packaging.impl.SlingInstallerSupportImpl Sling installer node /system/sling/installer/jcr/pauseInstallation/32df7f5e-d305-4d7b-a814-1a77b3cb5343/com.day.crx.packaging.impl.SlingInstallerSupportImpl/38702e4d-3091-4e6a-98c0-2c57dc5dd37b created.
18.05.2022 11:37:09.939 *INFO* [qtp1566870367-247] org.apache.jackrabbit.vault.packaging.impl.ActivityLog com.test:test.ui.content:1.0.0-SNAPSHOT: INSTALL
18.05.2022 11:37:09.954 *INFO* [qtp1566870367-247] com.day.crx.packaging.impl.SlingInstallerSupportImpl Sling installer node /system/sling/installer/jcr/pauseInstallation/32df7f5e-d305-4d7b-a814-1a77b3cb5343/com.day.crx.packaging.impl.SlingInstallerSupportImpl/38702e4d-3091-4e6a-98c0-2c57dc5dd37b removed.
18.05.2022 11:37:10.570 *INFO* [qtp1566870367-75] org.apache.jackrabbit.vault.packaging.impl.ActivityLog com.test:test.ui.config:1.0.0-SNAPSHOT: CREATE
18.05.2022 11:37:10.570 *INFO* [qtp1566870367-75] org.apache.jackrabbit.vault.packaging.impl.ActivityLog com.test:test.ui.config:1.0.0-SNAPSHOT: UPLOAD
18.05.2022 11:37:10.608 *INFO* [qtp1566870367-75] com.day.crx.packaging.impl.SlingInstallerSupportImpl Sling installer node /system/sling/installer/jcr/pauseInstallation/32df7f5e-d305-4d7b-a814-1a77b3cb5343/com.day.crx.packaging.impl.SlingInstallerSupportImpl/af0d17f7-9460-49b0-b318-d42df9e167e7 created.
18.05.2022 11:37:10.627 *INFO* [qtp1566870367-75] org.apache.jackrabbit.vault.packaging.impl.ActivityLog com.test:test.ui.config:1.0.0-SNAPSHOT: INSTALL
18.05.2022 11:37:10.647 *INFO* [qtp1566870367-75] com.day.crx.packaging.impl.SlingInstallerSupportImpl Sling installer node /system/sling/installer/jcr/pauseInstallation/32df7f5e-d305-4d7b-a814-1a77b3cb5343/com.day.crx.packaging.impl.SlingInstallerSupportImpl/af0d17f7-9460-49b0-b318-d42df9e167e7 removed.
18.05.2022 11:37:11.518 *INFO* [qtp1566870367-293] org.apache.jackrabbit.vault.packaging.impl.ActivityLog com.test:test.ui.aecu:1.0.0-SNAPSHOT: CREATE
18.05.2022 11:37:11.518 *INFO* [qtp1566870367-293] org.apache.jackrabbit.vault.packaging.impl.ActivityLog com.test:test.ui.aecu:1.0.0-SNAPSHOT: UPLOAD
18.05.2022 11:37:11.554 *INFO* [qtp1566870367-293] com.day.crx.packaging.impl.SlingInstallerSupportImpl Sling installer node /system/sling/installer/jcr/pauseInstallation/32df7f5e-d305-4d7b-a814-1a77b3cb5343/com.day.crx.packaging.impl.SlingInstallerSupportImpl/0fe68dda-4f36-49a0-9782-2a04380f07e4 created.
18.05.2022 11:37:11.554 *INFO* [qtp1566870367-293] org.apache.jackrabbit.vault.packaging.impl.InstallHookProcessorImpl Loading Hook aecu: Main-Class = de.valtech.aecu.core.installhook.AecuInstallHook
18.05.2022 11:37:11.554 *INFO* [qtp1566870367-293] org.apache.jackrabbit.vault.packaging.impl.InstallHookProcessorImpl Hook aecu registered.
18.05.2022 11:37:11.555 *INFO* [qtp1566870367-293] de.valtech.aecu.core.installhook.AecuInstallHook Executing in phase PREPARE
18.05.2022 11:37:11.557 *INFO* [qtp1566870367-293] de.valtech.aecu.core.installhook.AecuInstallHook Executing in phase INSTALLED
18.05.2022 11:37:11.578 *INFO* [qtp1566870367-293] de.valtech.aecu.core.service.AecuServiceImpl Executing script /apps/aecu-scripts/test/content-modification.always.groovy
18.05.2022 11:37:11.578 *INFO* [qtp1566870367-293] de.valtech.aecu.core.service.AecuServiceImpl Executing script /apps/aecu-scripts/test/content-modification.prechecks.groovy
18.05.2022 11:37:11.609 *INFO* [qtp1566870367-293] de.valtech.aecu.core.service.AecuServiceImpl Executed script /apps/aecu-scripts/test/content-modification.prechecks.groovy with status OK
18.05.2022 11:37:11.669 *INFO* [qtp1566870367-293] de.valtech.aecu.core.service.AecuServiceImpl Executed script /apps/aecu-scripts/test/content-modification.always.groovy with status OK
18.05.2022 11:37:11.703 *INFO* [qtp1566870367-293] de.valtech.aecu.core.service.AecuServiceImpl Executing script /apps/aecu-scripts/test/test.author/only-on-author.always.groovy
18.05.2022 11:37:11.735 *INFO* [qtp1566870367-293] de.valtech.aecu.core.service.AecuServiceImpl Executed script /apps/aecu-scripts/test/test.author/only-on-author.always.groovy with status OK
18.05.2022 11:37:11.788 *INFO* [qtp1566870367-293] de.valtech.aecu.core.installhook.AecuInstallHook Executing in phase END
18.05.2022 11:37:11.803 *INFO* [qtp1566870367-293] org.apache.jackrabbit.vault.packaging.impl.ActivityLog com.test:test.ui.aecu:1.0.0-SNAPSHOT: INSTALL
18.05.2022 11:37:11.818 *INFO* [qtp1566870367-293] com.day.crx.packaging.impl.SlingInstallerSupportImpl Sling installer node /system/sling/installer/jcr/pauseInstallation/32df7f5e-d305-4d7b-a814-1a77b3cb5343/com.day.crx.packaging.impl.SlingInstallerSupportImpl/0fe68dda-4f36-49a0-9782-2a04380f07e4 removed.
18.05.2022 11:37:19.969 *INFO* [CommitStats] CommitStats 17 commits by 4 users [35% admin, 23% aecu-service, 23% sling-installer-service, 17% groovy-console-system-user] from location other:java.base/java.lang.Thread.run(Thread.java:834) [CommitStats overhead was 1ms = 59.0us / commit]
18.05.2022 11:37:53.365 *INFO* [qtp1566870367-294] org.apache.jackrabbit.vault.packaging.impl.ActivityLog com.test:test.all:1.0.0-SNAPSHOT: CREATE
18.05.2022 11:37:53.365 *INFO* [qtp1566870367-294] org.apache.jackrabbit.vault.packaging.impl.ActivityLog com.test:test.all:1.0.0-SNAPSHOT: UPLOAD
18.05.2022 11:37:53.394 *INFO* [qtp1566870367-294] com.day.crx.packaging.impl.SlingInstallerSupportImpl Sling installer node /system/sling/installer/jcr/pauseInstallation/32df7f5e-d305-4d7b-a814-1a77b3cb5343/com.day.crx.packaging.impl.SlingInstallerSupportImpl/cedcb3a2-a8a1-4c19-adc0-b4bdc22915c8 created.
18.05.2022 11:37:53.571 *INFO* [qtp1566870367-294] org.apache.jackrabbit.vault.packaging.impl.ActivityLog com.test:test.all:1.0.0-SNAPSHOT: INSTALL
18.05.2022 11:37:53.584 *INFO* [qtp1566870367-294] com.day.crx.packaging.impl.SlingInstallerSupportImpl Sling installer node /system/sling/installer/jcr/pauseInstallation/32df7f5e-d305-4d7b-a814-1a77b3cb5343/com.day.crx.packaging.impl.SlingInstallerSupportImpl/cedcb3a2-a8a1-4c19-adc0-b4bdc22915c8 removed.
18.05.2022 11:37:53.771 *INFO* [JcrInstaller.1] org.apache.sling.installer.provider.jcr.impl.JcrInstaller Registering resource with OSGi installer: [InstallableResource, priority=200, id=/apps/test-packages/application/install/test.ui.apps-1.0.0-SNAPSHOT.zip, InstallableResource, priority=200, id=/apps/test-packages/application/install/test.core-1.0.0-SNAPSHOT.jar, InstallableResource, priority=200, id=/apps/test-packages/application/install/test.ui.aecu-1.0.0-SNAPSHOT.zip, InstallableResource, priority=200, id=/apps/test-packages/application/install/test.ui.acl-1.0.0-SNAPSHOT.zip, InstallableResource, priority=200, id=/apps/test-packages/application/install/test.ui.config-1.0.0-SNAPSHOT.zip]
18.05.2022 11:37:53.774 *INFO* [JcrInstaller.1] org.apache.sling.installer.provider.jcr.impl.JcrInstaller Registering resource with OSGi installer: [InstallableResource, priority=200, id=/apps/test-packages/content/install/test.ui.content-1.0.0-SNAPSHOT.zip]
18.05.2022 11:37:53.789 *INFO* [OsgiInstallerImpl] org.apache.jackrabbit.vault.packaging.impl.ActivityLog com.test:test.ui.apps:1.0.0-SNAPSHOT: CREATE
18.05.2022 11:37:53.789 *INFO* [OsgiInstallerImpl] org.apache.jackrabbit.vault.packaging.impl.ActivityLog com.test:test.ui.apps:1.0.0-SNAPSHOT: UPLOAD
18.05.2022 11:37:53.811 *INFO* [OsgiInstallerImpl] org.apache.jackrabbit.vault.packaging.impl.ActivityLog com.test:test.ui.apps:1.0.0-SNAPSHOT: EXTRACT_SUB_PACKAGES ()
18.05.2022 11:37:53.817 *INFO* [OsgiInstallerImpl] org.apache.jackrabbit.vault.packaging.impl.ActivityLog com.test:test.ui.aecu:1.0.0-SNAPSHOT: CREATE
18.05.2022 11:37:53.817 *INFO* [OsgiInstallerImpl] org.apache.jackrabbit.vault.packaging.impl.ActivityLog com.test:test.ui.aecu:1.0.0-SNAPSHOT: UPLOAD
18.05.2022 11:37:53.834 *INFO* [OsgiInstallerImpl] org.apache.jackrabbit.vault.packaging.impl.ActivityLog com.test:test.ui.aecu:1.0.0-SNAPSHOT: EXTRACT_SUB_PACKAGES ()
18.05.2022 11:37:53.838 *INFO* [OsgiInstallerImpl] org.apache.jackrabbit.vault.packaging.impl.ActivityLog com.test:test.ui.acl:1.0.0-SNAPSHOT: CREATE
18.05.2022 11:37:53.838 *INFO* [OsgiInstallerImpl] org.apache.jackrabbit.vault.packaging.impl.ActivityLog com.test:test.ui.acl:1.0.0-SNAPSHOT: UPLOAD
18.05.2022 11:37:53.855 *INFO* [OsgiInstallerImpl] org.apache.jackrabbit.vault.packaging.impl.ActivityLog com.test:test.ui.acl:1.0.0-SNAPSHOT: EXTRACT_SUB_PACKAGES ()
18.05.2022 11:37:53.860 *INFO* [OsgiInstallerImpl] org.apache.jackrabbit.vault.packaging.impl.ActivityLog com.test:test.ui.config:1.0.0-SNAPSHOT: CREATE
18.05.2022 11:37:53.860 *INFO* [OsgiInstallerImpl] org.apache.jackrabbit.vault.packaging.impl.ActivityLog com.test:test.ui.config:1.0.0-SNAPSHOT: UPLOAD
18.05.2022 11:37:53.881 *INFO* [OsgiInstallerImpl] org.apache.jackrabbit.vault.packaging.impl.ActivityLog com.test:test.ui.config:1.0.0-SNAPSHOT: EXTRACT_SUB_PACKAGES ()
18.05.2022 11:37:53.900 *INFO* [OsgiInstallerImpl] org.apache.jackrabbit.vault.packaging.impl.InstallHookProcessorImpl Loading Hook aecu: Main-Class = de.valtech.aecu.core.installhook.AecuInstallHook
18.05.2022 11:37:53.900 *INFO* [OsgiInstallerImpl] org.apache.jackrabbit.vault.packaging.impl.InstallHookProcessorImpl Hook aecu registered.
18.05.2022 11:37:53.900 *INFO* [OsgiInstallerImpl] de.valtech.aecu.core.installhook.AecuInstallHook Executing in phase PREPARE
18.05.2022 11:37:53.900 *WARN* [OsgiInstallerImpl] org.apache.jackrabbit.vault.packaging.impl.InstallHookProcessorImpl Hook aecu threw runtime exception.
java.lang.NullPointerException: null
    at de.valtech.aecu.core.installhook.AecuTrackerListener.onMessage(AecuTrackerListener.java:86) [de.valtech.aecu.core:6.0.0]
    at de.valtech.aecu.core.installhook.AecuTrackerListener.logMessage(AecuTrackerListener.java:139) [de.valtech.aecu.core:6.0.0]
    at de.valtech.aecu.core.installhook.AecuTrackerListener.<init>(AecuTrackerListener.java:70) [de.valtech.aecu.core:6.0.0]
    at de.valtech.aecu.core.installhook.AecuInstallHook.execute(AecuInstallHook.java:91) [de.valtech.aecu.core:6.0.0]
    at org.apache.jackrabbit.vault.packaging.impl.InstallHookProcessorImpl.execute(InstallHookProcessorImpl.java:143) [org.apache.jackrabbit.vault:3.4.0]
    at org.apache.jackrabbit.vault.packaging.impl.ZipVaultPackage.extract(ZipVaultPackage.java:226) [org.apache.jackrabbit.vault:3.4.0]
    at org.apache.jackrabbit.vault.packaging.impl.JcrPackageImpl.extract(JcrPackageImpl.java:401) [org.apache.jackrabbit.vault:3.4.0]
    at org.apache.jackrabbit.vault.packaging.impl.JcrPackageImpl.extract(JcrPackageImpl.java:360) [org.apache.jackrabbit.vault:3.4.0]
    at org.apache.jackrabbit.vault.packaging.impl.JcrPackageImpl.install(JcrPackageImpl.java:354) [org.apache.jackrabbit.vault:3.4.0]
    at com.adobe.granite.installer.factory.packages.impl.PackageTransformer$InstallPackageTask.execute(PackageTransformer.java:350) [com.adobe.granite.installer.factory.packages:1.0.20]
    at org.apache.sling.installer.core.impl.OsgiInstallerImpl.doExecuteTasks(OsgiInstallerImpl.java:918) [org.apache.sling.installer.core:3.12.0]
    at org.apache.sling.installer.core.impl.OsgiInstallerImpl.executeTasks(OsgiInstallerImpl.java:755) [org.apache.sling.installer.core:3.12.0]
    at org.apache.sling.installer.core.impl.OsgiInstallerImpl.run(OsgiInstallerImpl.java:304) [org.apache.sling.installer.core:3.12.0]
    at java.base/java.lang.Thread.run(Thread.java:834)
18.05.2022 11:37:53.902 *INFO* [OsgiInstallerImpl] de.valtech.aecu.core.installhook.AecuInstallHook Executing in phase INSTALLED
18.05.2022 11:37:53.902 *WARN* [OsgiInstallerImpl] org.apache.jackrabbit.vault.packaging.impl.InstallHookProcessorImpl Hook aecu threw runtime exception.
java.lang.NullPointerException: null
    at de.valtech.aecu.core.installhook.AecuInstallHook.getScriptsForExecution(AecuInstallHook.java:121) [de.valtech.aecu.core:6.0.0]
    at de.valtech.aecu.core.installhook.AecuInstallHook.execute(AecuInstallHook.java:99) [de.valtech.aecu.core:6.0.0]
    at org.apache.jackrabbit.vault.packaging.impl.InstallHookProcessorImpl.execute(InstallHookProcessorImpl.java:143) [org.apache.jackrabbit.vault:3.4.0]
    at org.apache.jackrabbit.vault.packaging.impl.ZipVaultPackage.extract(ZipVaultPackage.java:240) [org.apache.jackrabbit.vault:3.4.0]
    at org.apache.jackrabbit.vault.packaging.impl.JcrPackageImpl.extract(JcrPackageImpl.java:401) [org.apache.jackrabbit.vault:3.4.0]
    at org.apache.jackrabbit.vault.packaging.impl.JcrPackageImpl.extract(JcrPackageImpl.java:360) [org.apache.jackrabbit.vault:3.4.0]
    at org.apache.jackrabbit.vault.packaging.impl.JcrPackageImpl.install(JcrPackageImpl.java:354) [org.apache.jackrabbit.vault:3.4.0]
    at com.adobe.granite.installer.factory.packages.impl.PackageTransformer$InstallPackageTask.execute(PackageTransformer.java:350) [com.adobe.granite.installer.factory.packages:1.0.20]
    at org.apache.sling.installer.core.impl.OsgiInstallerImpl.doExecuteTasks(OsgiInstallerImpl.java:918) [org.apache.sling.installer.core:3.12.0]
    at org.apache.sling.installer.core.impl.OsgiInstallerImpl.executeTasks(OsgiInstallerImpl.java:755) [org.apache.sling.installer.core:3.12.0]
    at org.apache.sling.installer.core.impl.OsgiInstallerImpl.run(OsgiInstallerImpl.java:304) [org.apache.sling.installer.core:3.12.0]
gruberrolandvaltech commented 2 years ago

Does the same thing happen on a new AEM instance (installing only AECU Cloud and Cloud Examples)?

gruberrolandvaltech commented 2 years ago

From the log it seems that package "com.test:test.ui.aecu" installs fine with the scripts. But your package "com.test:test.ui.config" fails. Maybe you added the hook to all your packages? Please only use it in the package that contains the scripts.

michal-tobiasz-wttech commented 2 years ago

The hook installhook.aecu.class is configured in module test.ui.aecu and second hook installhook.actool.class in module ui.acl module test.ui.config doesn't have a hook. For reference pom.xml of test.ui.aecu

<?xml version="1.0" encoding="UTF-8"?>
<project xmlns="http://maven.apache.org/POM/4.0.0" xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance" xsi:schemaLocation="http://maven.apache.org/POM/4.0.0 http://maven.apache.org/xsd/maven-4.0.0.xsd">
    <modelVersion>4.0.0</modelVersion>

    <parent>
        <groupId>com.test</groupId>
        <artifactId>test</artifactId>
        <version>1.0.0-SNAPSHOT</version>
        <relativePath>../pom.xml</relativePath>
    </parent>

    <artifactId>test.ui.aecu</artifactId>
    <packaging>content-package</packaging>
    <name>test - AECU Package</name>
    <description>AECU package for test</description>

    <build>
        <sourceDirectory>src/main/content/jcr_root</sourceDirectory>
        <plugins>
            <plugin>
                <groupId>org.apache.jackrabbit</groupId>
                <artifactId>filevault-package-maven-plugin</artifactId>
                <configuration>
                    <group>com.test</group>
                    <name>test.ui.aecu</name>
                    <properties>
                        <cloudManagerTarget>none</cloudManagerTarget>
                    </properties>
                    <packageType>application</packageType>
                    <dependencies>
                        <dependency>
                            <groupId>de.valtech.aecu</groupId>
                            <artifactId>aecu.complete.cloud</artifactId>
                        </dependency>
                    </dependencies>
                </configuration>
            </plugin>
            <plugin>
                <groupId>com.day.jcr.vault</groupId>
                <artifactId>content-package-maven-plugin</artifactId>
            </plugin>
            <plugin>
                <groupId>org.apache.sling</groupId>
                <artifactId>htl-maven-plugin</artifactId>
            </plugin>
        </plugins>
    </build>

    <dependencies>
        <dependency>
            <groupId>de.valtech.aecu</groupId>
            <artifactId>aecu.complete.cloud</artifactId>
            <type>zip</type>
        </dependency>
    </dependencies>

    <profiles>
        <profile>
            <id>installHook</id>
            <activation>
                <activeByDefault>false</activeByDefault>
            </activation>
            <build>
                <plugins>
                    <plugin>
                        <groupId>org.apache.jackrabbit</groupId>
                        <artifactId>filevault-package-maven-plugin</artifactId>
                        <configuration>
                            <packageType>mixed</packageType>
                            <properties>
                                <installhook.aecu.class>de.valtech.aecu.core.installhook.AecuInstallHook</installhook.aecu.class>
                            </properties>
                        </configuration>
                    </plugin>
                </plugins>
            </build>
        </profile>
    </profiles>

</project>

pom.xml of all module

<project xmlns="http://maven.apache.org/POM/4.0.0"
  xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
  xsi:schemaLocation="http://maven.apache.org/POM/4.0.0 http://maven.apache.org/maven-v4_0_0.xsd">
    <modelVersion>4.0.0</modelVersion>

    <!-- ====================================================================== -->
    <!-- P A R E N T  P R O J E C T  D E S C R I P T I O N                      -->
    <!-- ====================================================================== -->
    <parent>
        <groupId>com.test</groupId>
        <artifactId>test</artifactId>
        <version>1.0.0-SNAPSHOT</version>
        <relativePath>../pom.xml</relativePath>
    </parent>

    <!-- ====================================================================== -->
    <!-- P R O J E C T  D E S C R I P T I O N                                   -->
    <!-- ====================================================================== -->
    <artifactId>test.all</artifactId>
    <packaging>content-package</packaging>
    <name>test - All</name>
    <description>All content package for test</description>

    <!-- ====================================================================== -->
    <!-- B U I L D   D E F I N I T I O N                                        -->
    <!-- ====================================================================== -->
    <build>
        <plugins>
            <!-- ====================================================================== -->
            <!-- V A U L T   P A C K A G E   P L U G I N S                              -->
            <!-- ====================================================================== -->
            <plugin>
                <groupId>org.apache.jackrabbit</groupId>
                <artifactId>filevault-package-maven-plugin</artifactId>
                <extensions>true</extensions>
                <configuration>
                    <group>com.test</group>
                    <packageType>container</packageType>
                    <!-- skip sub package validation for now as some vendor packages like CIF apps will not pass -->
                    <skipSubPackageValidation>true</skipSubPackageValidation>
                    <allowIndexDefinitions>true</allowIndexDefinitions>
                    <embeddeds>
                        <embedded>
                            <groupId>com.test</groupId>
                            <artifactId>test.ui.apps</artifactId>
                            <type>zip</type>
                            <target>/apps/test-packages/application/install</target>
                        </embedded>
                        <embedded>
                            <groupId>com.test</groupId>
                            <artifactId>test.core</artifactId>
                            <target>/apps/test-packages/application/install</target>
                        </embedded>
                        <embedded>
                            <groupId>com.test</groupId>
                            <artifactId>test.ui.content</artifactId>
                            <type>zip</type>
                            <target>/apps/test-packages/content/install</target>
                        </embedded>
                        <embedded>
                            <groupId>com.test</groupId>
                            <artifactId>test.ui.config</artifactId>
                            <type>zip</type>
                            <target>/apps/test-packages/application/install</target>
                        </embedded>
                        <!-- AECU Deployment -->
                        <embedded>
                            <groupId>com.test</groupId>
                            <artifactId>test.ui.aecu</artifactId>
                            <type>zip</type>
                            <target>/apps/test-packages/application/install</target>
                        </embedded>
                        <!-- AECU Deployment -->
                        <embedded>
                            <groupId>de.valtech.aecu</groupId>
                            <artifactId>aecu.complete.cloud</artifactId>
                            <type>zip</type>
                            <target>/apps/test-vendor-packages/application/install</target>
                        </embedded>
                    </embeddeds>
                </configuration>
            </plugin>
            <plugin>
                <groupId>com.day.jcr.vault</groupId>
                <artifactId>content-package-maven-plugin</artifactId>
                <extensions>true</extensions>
                <configuration>
                    <verbose>true</verbose>
                    <failOnError>true</failOnError>
                </configuration>
            </plugin>
            <plugin>
                <artifactId>maven-clean-plugin</artifactId>
                <executions>
                    <execution>
                        <id>auto-clean</id>
                        <phase>initialize</phase>
                        <goals>
                            <goal>clean</goal>
                        </goals>
                    </execution>
                </executions>
            </plugin>
            <plugin>
                <groupId>com.adobe.aem</groupId>
                <artifactId>aemanalyser-maven-plugin</artifactId>
                <executions>
                    <execution>
                        <id>aem-analyser</id>
                        <goals>
                            <goal>project-analyse</goal>
                        </goals>
                    </execution>
                </executions>
            </plugin>
        </plugins>
    </build>

    <!-- ====================================================================== -->
    <!-- P R O F I L E S                                                        -->
    <!-- ====================================================================== -->
    <profiles>
        <profile>
            <id>autoInstallSinglePackage</id>
            <activation>
                <activeByDefault>false</activeByDefault>
            </activation>
            <build>
                <plugins>
                    <plugin>
                        <groupId>com.day.jcr.vault</groupId>
                        <artifactId>content-package-maven-plugin</artifactId>
                        <executions>
                            <execution>
                                <id>install-package</id>
                                <goals>
                                    <goal>install</goal>
                                </goals>
                                <configuration>
                                    <targetURL>http://${aem.host}:${aem.port}/crx/packmgr/service.jsp</targetURL>
                                    <failOnError>true</failOnError>
                                </configuration>
                            </execution>
                        </executions>
                    </plugin>
                </plugins>
            </build>
        </profile>
        <profile>
            <id>autoInstallSinglePackagePublish</id>
            <activation>
                <activeByDefault>false</activeByDefault>
            </activation>
            <build>
                <plugins>
                    <plugin>
                        <groupId>com.day.jcr.vault</groupId>
                        <artifactId>content-package-maven-plugin</artifactId>
                        <executions>
                            <execution>
                                <id>install-package-publish</id>
                                <goals>
                                    <goal>install</goal>
                                </goals>
                                <configuration>
                                    <targetURL>http://${aem.publish.host}:${aem.publish.port}/crx/packmgr/service.jsp</targetURL>
                                    <failOnError>true</failOnError>
                                </configuration>
                            </execution>
                        </executions>
                    </plugin>
                </plugins>
            </build>
        </profile>
    </profiles>

    <!-- ====================================================================== -->
    <!-- D E P E N D E N C I E S                                                -->
    <!-- ====================================================================== -->
    <dependencies>
        <!-- AECU Deployment -->
        <dependency>
            <groupId>de.valtech.aecu</groupId>
            <artifactId>aecu.complete.cloud</artifactId>
            <type>zip</type>
        </dependency>
        <dependency>
            <groupId>com.test</groupId>
            <artifactId>test.ui.apps</artifactId>
            <version>${project.version}</version>
            <type>zip</type>
        </dependency>
        <dependency>
            <groupId>com.test</groupId>
            <artifactId>test.ui.content</artifactId>
            <version>${project.version}</version>
            <type>zip</type>
        </dependency>
        <dependency>
            <groupId>com.test</groupId>
            <artifactId>test.ui.config</artifactId>
            <version>${project.version}</version>
            <type>zip</type>
        </dependency>
        <!-- AECU Deployment -->
        <dependency>
            <groupId>com.test</groupId>
            <artifactId>test.ui.aecu</artifactId>
            <version>${project.version}</version>
            <type>zip</type>
        </dependency>
    </dependencies>
</project>
gruberrolandvaltech commented 2 years ago

I added some sanity checks in https://github.com/valtech/aem-easy-content-upgrade/commit/50639454241622f58e25680d48c719ef4324fec1 Please reopen if the issue is still existing with version 6.0.1 (to be released soon).