Expand my Community achievements bar.

Several Resolved / Installed Bundles will not change to Active State

Avatar

Level 1

I have 2 instances of AEM 6.2 running on Linux VM's. One of the instance starts and processing documents fine. The other instance is having problems with bundles changing from Resolved / Installed to Active.

Here is a snippet from the error.log and this is consistently repreated:

03.08.2017 07:37:01.273 *WARN* [sling-oak-observation-567] org.apache.jackrabbit.oak.jcr.observation.ChangeProcessor Error while dispatching observation events for /etc/workflow//*[10101b, cq:Workflow, cq:Payload, no local]@com.adobe.granite.workflow.core.eventsupport.JcrToWorkflowEventService

java.lang.NullPointerException: null

        at com.adobe.granite.workflow.core.eventsupport.JcrToWorkflowEventService.onEvent(JcrToWorkflowEventService.java:209)

        at org.apache.jackrabbit.commons.observation.ListenerTracker$1.onEvent(ListenerTracker.java:164)

        at org.apache.jackrabbit.oak.jcr.observation.ChangeProcessor.contentChanged(ChangeProcessor.java:317)

        at org.apache.jackrabbit.oak.spi.commit.BackgroundObserver$1$1.call(BackgroundObserver.java:131)

        at org.apache.jackrabbit.oak.spi.commit.BackgroundObserver$1$1.call(BackgroundObserver.java:125)

        at java.util.concurrent.FutureTask.run(FutureTask.java:266)

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

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

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

2 Replies

Avatar

Level 1

Additional information from the log:

03.08.2017 07:36:20.877 *ERROR* [discovery.connectors.common.runner.bc0bd855-d54a-4961-b299-d4ce0cf499e6.connectorPinger] org.apache.sling.discovery.base.commons.PeriodicBackgroundJob safelyRun: got a RuntimeException executing 'discovery.connectors.common.runner.bc0bd855-d54a-4961-b299-d4ce0cf499e6.connectorPinger': java.lang.RuntimeException: Exception while talking to repository (org.apache.sling.api.resource.PersistenceException: Unable to commit changes to session.)

java.lang.RuntimeException: Exception while talking to repository (org.apache.sling.api.resource.PersistenceException: Unable to commit changes to session.)

        at org.apache.sling.discovery.oak.OakDiscoveryService.doUpdateProperties(OakDiscoveryService.java:542)

        at org.apache.sling.discovery.oak.OakDiscoveryService.updateProperties(OakDiscoveryService.java:562)

        at org.apache.sling.discovery.oak.pinger.OakViewChecker.updateProperties(OakViewChecker.java:330)

        at org.apache.sling.discovery.base.commons.BaseViewChecker.issueHeartbeat(BaseViewChecker.java:213)

        at org.apache.sling.discovery.base.commons.BaseViewChecker.heartbeatAndCheckView(BaseViewChecker.java:181)

        at org.apache.sling.discovery.base.commons.BaseViewChecker.run(BaseViewChecker.java:167)

        at org.apache.sling.discovery.base.commons.PeriodicBackgroundJob.safelyRun(PeriodicBackgroundJob.java:86)

        at org.apache.sling.discovery.base.commons.PeriodicBackgroundJob.run(PeriodicBackgroundJob.java:77)

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

Caused by: org.apache.sling.api.resource.PersistenceException: Unable to commit changes to session.

        at org.apache.sling.jcr.resource.internal.helper.jcr.JcrResourceProvider.commit(JcrResourceProvider.java:482)

        at org.apache.sling.resourceresolver.impl.providers.stateful.AuthenticatedResourceProvider.commit(AuthenticatedResourceProvider.java:215)

        at org.apache.sling.resourceresolver.impl.helper.ResourceResolverControl.commit(ResourceResolverControl.java:411)

        at org.apache.sling.resourceresolver.impl.ResourceResolverImpl.commit(ResourceResolverImpl.java:1224)

        at org.apache.sling.discovery.oak.OakDiscoveryService.doUpdateProperties(OakDiscoveryService.java:533)

        ... 8 common frames omitted

Caused by: javax.jcr.InvalidItemStateException: OakMerge0004: OakMerge0004: Following exceptions occurred during the bulk update operations: [org.apache.jackrabbit.oak.plugins.document.ConflictException: The node 6:/var/discovery/oak/clusterInstances/bc0bd855-d54a-4961-b299-d4ce0cf499e6/properties was changed in revision

r15d8c14cf6f-0-1 (not yet visible), which was applied after the base revision

r15d8c14cf32-0-1,r15d8c16d48a-0-2,r15cc66cd95d-0-3,r15d74dc0e76-0-4,r15da8184e51-0-5,r15da595db9a-0-6, before

r15da818c0f7-0-6] (retries 5, 300191 ms)

        at org.apache.jackrabbit.oak.api.CommitFailedException.asRepositoryException(CommitFailedException.java:239)

        at org.apache.jackrabbit.oak.api.CommitFailedException.asRepositoryException(CommitFailedException.java:212)

        at org.apache.jackrabbit.oak.jcr.delegate.SessionDelegate.newRepositoryException(SessionDelegate.java:670)

        at org.apache.jackrabbit.oak.jcr.delegate.SessionDelegate.save(SessionDelegate.java:496)

        at org.apache.jackrabbit.oak.jcr.session.SessionImpl$8.performVoid(SessionImpl.java:419)

        at org.apache.jackrabbit.oak.jcr.delegate.SessionDelegate.performVoid(SessionDelegate.java:274)

        at org.apache.jackrabbit.oak.jcr.session.SessionImpl.save(SessionImpl.java:416)

        at sun.reflect.GeneratedMethodAccessor63.invoke(Unknown Source)

        at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)

        at java.lang.reflect.Method.invoke(Method.java:498)

        at org.apache.sling.jcr.base.SessionProxyHandler$SessionProxyInvocationHandler.invoke(SessionProxyHandler.java:116)

        at com.sun.proxy.$Proxy11.save(Unknown Source)

        at org.apache.sling.jcr.resource.internal.helper.jcr.JcrResourceProvider.commit(JcrResourceProvider.java:480)

        ... 12 common frames omitted

Caused by: org.apache.jackrabbit.oak.api.CommitFailedException: OakMerge0004: OakMerge0004: Following exceptions occurred during the bulk update operations: [org.apache.jackrabbit.oak.plugins.document.ConflictException: The node 6:/var/discovery/oak/clusterInstances/bc0bd855-d54a-4961-b299-d4ce0cf499e6/properties was changed in revision

r15d8c14cf6f-0-1 (not yet visible), which was applied after the base revision

r15d8c14cf32-0-1,r15d8c16d48a-0-2,r15cc66cd95d-0-3,r15d74dc0e76-0-4,r15da8184e51-0-5,r15da595db9a-0-6, before

r15da818c0f7-0-6] (retries 5, 300191 ms)

        at org.apache.jackrabbit.oak.plugins.document.DocumentNodeStoreBranch.merge0(DocumentNodeStoreBranch.java:202)

        at org.apache.jackrabbit.oak.plugins.document.DocumentNodeStoreBranch.merge(DocumentNodeStoreBranch.java:126)

        at org.apache.jackrabbit.oak.plugins.document.DocumentRootBuilder.merge(DocumentRootBuilder.java:158)

        at org.apache.jackrabbit.oak.plugins.document.DocumentNodeStore.merge(DocumentNodeStore.java:1508)

        at org.apache.jackrabbit.oak.core.MutableRoot.commit(MutableRoot.java:247)

        at org.apache.jackrabbit.oak.jcr.delegate.SessionDelegate.commit(SessionDelegate.java:347)

        at org.apache.jackrabbit.oak.jcr.delegate.SessionDelegate.save(SessionDelegate.java:494)

        ... 21 common frames omitted

Caused by: org.apache.jackrabbit.oak.plugins.document.ConflictException: Following exceptions occurred during the bulk update operations: [org.apache.jackrabbit.oak.plugins.document.ConflictException: The node 6:/var/discovery/oak/clusterInstances/bc0bd855-d54a-4961-b299-d4ce0cf499e6/properties was changed in revision

r15d8c14cf6f-0-1 (not yet visible), which was applied after the base revision

r15d8c14cf32-0-1,r15d8c16d48a-0-2,r15cc66cd95d-0-3,r15d74dc0e76-0-4,r15da8184e51-0-5,r15da595db9a-0-6, before

r15da818c0f7-0-6]

        at org.apache.jackrabbit.oak.plugins.document.Commit.checkConflicts(Commit.java:561)

        at org.apache.jackrabbit.oak.plugins.document.Commit.applyToDocumentStore(Commit.java:326)

        at org.apache.jackrabbit.oak.plugins.document.Commit.applyToDocumentStore(Commit.java:262)

        at org.apache.jackrabbit.oak.plugins.document.Commit.applyInternal(Commit.java:231)

        at org.apache.jackrabbit.oak.plugins.document.Commit.apply(Commit.java:220)

        at org.apache.jackrabbit.oak.plugins.document.DocumentNodeStoreBranch.persist(DocumentNodeStoreBranch.java:292)

        at org.apache.jackrabbit.oak.plugins.document.DocumentNodeStoreBranch.persist(DocumentNodeStoreBranch.java:262)

        at org.apache.jackrabbit.oak.plugins.document.DocumentNodeStoreBranch.access$300(DocumentNodeStoreBranch.java:57)

        at org.apache.jackrabbit.oak.plugins.document.DocumentNodeStoreBranch$InMemory.merge(DocumentNodeStoreBranch.java:499)

        at org.apache.jackrabbit.oak.plugins.document.DocumentNodeStoreBranch.merge0(DocumentNodeStoreBranch.java:182)

        ... 27 common frames omitted

03.08.2017 07:36:21.185 *WARN* [sling-oak-observation-559] org.apache.jackrabbit.oak.jcr.observation.ChangeProcessor Error while dispatching observation events for /etc/workflow//*[10101b, cq:Workflow, cq:Payload, no local]@com.adobe.granite.workflow.core.eventsupport.JcrToWorkflowEventService

java.lang.NullPointerException: null

        at com.adobe.granite.workflow.core.eventsupport.JcrToWorkflowEventService.onEvent(JcrToWorkflowEventService.java:209)

        at org.apache.jackrabbit.commons.observation.ListenerTracker$1.onEvent(ListenerTracker.java:164)

        at org.apache.jackrabbit.oak.jcr.observation.ChangeProcessor.contentChanged(ChangeProcessor.java:317)

        at org.apache.jackrabbit.oak.spi.commit.BackgroundObserver$1$1.call(BackgroundObserver.java:131)

        at org.apache.jackrabbit.oak.spi.commit.BackgroundObserver$1$1.call(BackgroundObserver.java:125)

        at java.util.concurrent.FutureTask.run(FutureTask.java:266)

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

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

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

03.08.2017 07:36:22.196 *WARN* [sling-oak-observation-562] org.apache.jackrabbit.oak.jcr.observation.ChangeProcessor Error while dispatching observation events for /etc/workflow//*[10101b, cq:Workflow, cq:Payload, no local]@com.adobe.granite.workflow.core.eventsupport.JcrToWorkflowEventService

java.lang.NullPointerException: null

        at com.adobe.granite.workflow.core.eventsupport.JcrToWorkflowEventService.onEvent(JcrToWorkflowEventService.java:209)

        at org.apache.jackrabbit.commons.observation.ListenerTracker$1.onEvent(ListenerTracker.java:164)

        at org.apache.jackrabbit.oak.jcr.observation.ChangeProcessor.contentChanged(ChangeProcessor.java:317)

        at org.apache.jackrabbit.oak.spi.commit.BackgroundObserver$1$1.call(BackgroundObserver.java:131)

        at org.apache.jackrabbit.oak.spi.commit.BackgroundObserver$1$1.call(BackgroundObserver.java:125)

        at java.util.concurrent.FutureTask.run(FutureTask.java:266)

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

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

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

03.08.2017 07:36:42.222 *INFO* [qtp1702366321-3409] org.apache.sling.auth.core.impl.SlingAuthenticator getAnonymousResolver: Anonymous access not allowed by configuration - requesting credentials

03.08.2017 07:37:01.273 *WARN* [sling-oak-observation-567] org.apache.jackrabbit.oak.jcr.observation.ChangeProcessor Error while dispatching observation events for /etc/workflow//*[10101b, cq:Workflow, cq:Payload, no local]@com.adobe.granite.workflow.core.eventsupport.JcrToWorkflowEventService

java.lang.NullPointerException: null

        at com.adobe.granite.workflow.core.eventsupport.JcrToWorkflowEventService.onEvent(JcrToWorkflowEventService.java:209)

        at org.apache.jackrabbit.commons.observation.ListenerTracker$1.onEvent(ListenerTracker.java:164)

        at org.apache.jackrabbit.oak.jcr.observation.ChangeProcessor.contentChanged(ChangeProcessor.java:317)

        at org.apache.jackrabbit.oak.spi.commit.BackgroundObserver$1$1.call(BackgroundObserver.java:131)

        at org.apache.jackrabbit.oak.spi.commit.BackgroundObserver$1$1.call(BackgroundObserver.java:125)

        at java.util.concurrent.FutureTask.run(FutureTask.java:266)

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

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

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

03.08.2017 07:37:03.292 *WARN* [sling-oak-observation-554] org.apache.jackrabbit.oak.jcr.observation.ChangeProcessor Error while dispatching observation events for /etc/workflow//*[10101b, cq:Workflow, cq:Payload, no local]@com.adobe.granite.workflow.core.eventsupport.JcrToWorkflowEventService

java.lang.NullPointerException: null

        at com.adobe.granite.workflow.core.eventsupport.JcrToWorkflowEventService.onEvent(JcrToWorkflowEventService.java:209)

        at org.apache.jackrabbit.commons.observation.ListenerTracker$1.onEvent(ListenerTracker.java:164)

        at org.apache.jackrabbit.oak.jcr.observation.ChangeProcessor.contentChanged(ChangeProcessor.java:317)

        at org.apache.jackrabbit.oak.spi.commit.BackgroundObserver$1$1.call(BackgroundObserver.java:131)

        at org.apache.jackrabbit.oak.spi.commit.BackgroundObserver$1$1.call(BackgroundObserver.java:125)

        at java.util.concurrent.FutureTask.run(FutureTask.java:266)

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

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

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