-
Bug
-
Resolution: Fixed
-
Medium
-
5.8.6
-
None
Currently, the /status endpoint uses pluginFrameworkStartedEvent to determine when to transition from STARTING to RUNNING.
However we should wait until all upgrade tasks and plugin upgrade tasks are done - it would be better to listen to one of the application started type events.
Right now Confluence nodes look like they are ready to serve before they are, causing issues with load balancers etc.
- included in
-
![[JIRA]](/images/icons/generic_link_16.png "[JIRA]")
CPU-64
Confluence 5.9.1-OD-2015.47.1-0002
[CONFSERVER-38673] /status endpoint should not transition from "Starting" to "Running" before the application is fully ready
Seems fine to me apart from those exceptions - I think those are to do with master rather than your branch.
I merged master into your branch before deploying to pull in the latest. It's quite possible it's just problems on master.
That's really odd. None of that seems related to my changes at first glance, but it might be. Also my branch is off 5.8.x - does that matter for OnDemand?
I merged your branch into master and attempted to deploy to an OnDemand instance. I've hit a number of errors during startup, I'm not sure how many are related to your branch and how many are just connect plugin being broke in DEV.
2015-10-29 15:12:53.558330500 2015-10-29 15:12:53,551 WARN [ThreadPoolAsyncTaskExecutor::Thread 9] [connect.plugin.util.ConfigurationUtils] getIntSystemProperty System property 'com.atlassian.plugin.connect.add_on_properties.max_properties' does not exist or has a null value. Returning default value 100. 2015-10-29 15:12:54.218158500 2015-10-29 15:12:54,211 ERROR [ThreadPoolAsyncTaskExecutor::Thread 9] [plugin.osgi.factory.OsgiPlugin] onPluginContainerFailed Unable to start the plugin container for plugin 'com.atlassian.plugins.atlassian-connect-plugin' 2015-10-29 15:12:54.218160500 org.springframework.beans.factory.BeanInitializationException: Failed to process @EventListener annotation on bean with name 'licenseService'; nested exception is java.lang.LinkageError: loader constraint violation: loader (instance of org/eclipse/gemini/blueprint/context/support/internal/classloader/ChainedClassLoader) previously initiated loading for a different type with name "com/atlassian/extras/api/confluence/ConfluenceLicense" 2015-10-29 15:12:54.218173500 at org.springframework.context.event.EventListenerMethodProcessor.afterSingletonsInstantiated(EventListenerMethodProcessor.java:83) 2015-10-29 15:12:54.218174500 at org.springframework.beans.factory.support.DefaultListableBeanFactory.preInstantiateSingletons(DefaultListableBeanFactory.java:792) 2015-10-29 15:12:54.218175500 at org.springframework.context.support.AbstractApplicationContext.finishBeanFactoryInitialization(AbstractApplicationContext.java:835) 2015-10-29 15:12:54.218178500 at org.eclipse.gemini.blueprint.context.support.AbstractDelegatedExecutionApplicationContext.access$1600(AbstractDelegatedExecutionApplicationContext.java:60) 2015-10-29 15:12:54.218179500 at org.eclipse.gemini.blueprint.context.support.AbstractDelegatedExecutionApplicationContext$4.run(AbstractDelegatedExecutionApplicationContext.java:325) 2015-10-29 15:12:54.218181500 at org.eclipse.gemini.blueprint.util.internal.PrivilegedUtils.executeWithCustomTCCL(PrivilegedUtils.java:85) 2015-10-29 15:12:54.218182500 at org.eclipse.gemini.blueprint.context.support.AbstractDelegatedExecutionApplicationContext.completeRefresh(AbstractDelegatedExecutionApplicationContext.java:290) 2015-10-29 15:12:54.218183500 at org.eclipse.gemini.blueprint.extender.internal.dependencies.startup.DependencyWaiterApplicationContextExecutor$CompleteRefreshTask.run(DependencyWaiterApplicationContextExecutor.java:137) 2015-10-29 15:12:54.218185500 at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142) 2015-10-29 15:12:54.218185500 at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617) 2015-10-29 15:12:54.218186500 at java.lang.Thread.run(Thread.java:745) 2015-10-29 15:12:54.218188500 Caused by: java.lang.LinkageError: loader constraint violation: loader (instance of org/eclipse/gemini/blueprint/context/support/internal/classloader/ChainedClassLoader) previously initiated loading for a different type with name "com/atlassian/extras/api/confluence/ConfluenceLicense" 2015-10-29 15:12:54.218189500 at java.lang.Class.getDeclaredMethods0(Native Method) 2015-10-29 15:12:54.218189500 at java.lang.Class.privateGetDeclaredMethods(Class.java:2688) 2015-10-29 15:12:54.218191500 at java.lang.Class.getDeclaredMethods(Class.java:1962) 2015-10-29 15:12:54.218191500 at org.springframework.util.ReflectionUtils.getDeclaredMethods(ReflectionUtils.java:606) 2015-10-29 15:12:54.218192500 at org.springframework.util.ReflectionUtils.doWithMethods(ReflectionUtils.java:518) 2015-10-29 15:12:54.218192500 at org.springframework.util.ReflectionUtils.doWithMethods(ReflectionUtils.java:504) 2015-10-29 15:12:54.218193500 at org.springframework.util.ReflectionUtils.getUniqueDeclaredMethods(ReflectionUtils.java:564) 2015-10-29 15:12:54.218201500 at org.springframework.context.event.EventListenerMethodProcessor.processBean(EventListenerMethodProcessor.java:105) 2015-10-29 15:12:54.218201500 at org.springframework.context.event.EventListenerMethodProcessor.afterSingletonsInstantiated(EventListenerMethodProcessor.java:80) 2015-10-29 15:12:54.218204500 ... 10 more 2015-10-29 15:13:01.383918500 2015-10-29 15:13:01,382 INFO [localhost-startStop-1] [atlassian.plugin.manager.DefaultPluginManager] lateStartup Plugin system lateStartup finished in 0:00:49.950 2015-10-29 15:13:01.452142500 2015-10-29 15:13:01,450 ERROR [localhost-startStop-1] [atlassian.event.internal.AsynchronousAbleEventDispatcher] run There was an exception thrown trying to dispatch event [com.atlassian.plugin.event.events.PluginFrameworkStartedEvent@12b9ea8] from the invoker [com.atlassian.confluence.event.ConfluenceListenerHandlersConfiguration$TimingListenerHandler$1$1@3be8f2] 2015-10-29 15:13:01.452145500 java.lang.RuntimeException: java.lang.NullPointerException 2015-10-29 15:13:01.452146500 at com.atlassian.event.internal.SingleParameterMethodListenerInvoker.invoke(SingleParameterMethodListenerInvoker.java:50) 2015-10-29 15:13:01.452157500 at com.atlassian.confluence.event.ConfluenceListenerHandlersConfiguration$TimingListenerHandler$1$1.invoke(ConfluenceListenerHandlersConfiguration.java:85) 2015-10-29 15:13:01.452158500 at com.atlassian.event.internal.AsynchronousAbleEventDispatcher$1$1.run(AsynchronousAbleEventDispatcher.java:46) 2015-10-29 15:13:01.452158500 at com.google.common.util.concurrent.MoreExecutors$SameThreadExecutorService.execute(MoreExecutors.java:253) 2015-10-29 15:13:01.452160500 at com.atlassian.event.internal.AsynchronousAbleEventDispatcher.dispatch(AsynchronousAbleEventDispatcher.java:105) 2015-10-29 15:13:01.452163500 at com.atlassian.event.internal.EventPublisherImpl.invokeListeners(EventPublisherImpl.java:160) 2015-10-29 15:13:01.452164500 at com.atlassian.event.internal.EventPublisherImpl.publish(EventPublisherImpl.java:79) 2015-10-29 15:13:01.452164500 at com.atlassian.confluence.event.TimingEventPublisher.publish(TimingEventPublisher.java:74) 2015-10-29 15:13:01.452165500 at com.atlassian.plugin.event.impl.DefaultPluginEventManager.broadcast(DefaultPluginEventManager.java:73) 2015-10-29 15:13:01.452166500 at com.atlassian.plugin.manager.DefaultPluginManager.broadcastIgnoreError(DefaultPluginManager.java:1973) 2015-10-29 15:13:01.452167500 at com.atlassian.plugin.manager.DefaultPluginManager.lateStartup(DefaultPluginManager.java:557) 2015-10-29 15:13:01.452168500 at com.atlassian.confluence.plugin.ConfluencePluginManager.lateStartup(ConfluencePluginManager.java:157) 2015-10-29 15:13:01.452169500 at com.atlassian.confluence.plugin.PluginFrameworkContextListener.contextInitialized(PluginFrameworkContextListener.java:88) 2015-10-29 15:13:01.452170500 at org.apache.catalina.core.StandardContext.listenerStart(StandardContext.java:5016) 2015-10-29 15:13:01.452170500 at org.apache.catalina.core.StandardContext.startInternal(StandardContext.java:5528) 2015-10-29 15:13:01.452171500 at org.apache.catalina.util.LifecycleBase.start(LifecycleBase.java:150) 2015-10-29 15:13:01.452173500 at org.apache.catalina.core.ContainerBase.addChildInternal(ContainerBase.java:901) 2015-10-29 15:13:01.452173500 at org.apache.catalina.core.ContainerBase.addChild(ContainerBase.java:877) 2015-10-29 15:13:01.452174500 at org.apache.catalina.core.StandardHost.addChild(StandardHost.java:652) 2015-10-29 15:13:01.452174500 at org.apache.catalina.startup.HostConfig.deployDescriptor(HostConfig.java:672) 2015-10-29 15:13:01.452176500 at org.apache.catalina.startup.HostConfig$DeployDescriptor.run(HostConfig.java:1882) 2015-10-29 15:13:01.452176500 at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511) 2015-10-29 15:13:01.452177500 at java.util.concurrent.FutureTask.run(FutureTask.java:266) 2015-10-29 15:13:01.452177500 at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142) 2015-10-29 15:13:01.452179500 at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617) 2015-10-29 15:13:01.452179500 at java.lang.Thread.run(Thread.java:745) 2015-10-29 15:13:01.452179500 Caused by: java.lang.NullPointerException 2015-10-29 15:13:01.452180500 at com.atlassian.sal.confluence.lifecycle.SpringManagedModuleReferenceParser.apply(SpringManagedModuleReferenceParser.java:22) 2015-10-29 15:13:01.452181500 at com.atlassian.sal.confluence.lifecycle.SpringManagedModuleReferenceParser.apply(SpringManagedModuleReferenceParser.java:17) 2015-10-29 15:13:01.452189500 at com.atlassian.sal.confluence.lifecycle.ModuleExecutionFilter.add(ModuleExecutionFilter.java:38) 2015-10-29 15:13:01.452189500 at com.atlassian.sal.confluence.lifecycle.ServiceExecutionStrategyComposite.add(ServiceExecutionStrategyComposite.java:28) 2015-10-29 15:13:01.452190500 at com.atlassian.sal.confluence.lifecycle.TenantAwareLifecycleManager$PerTenantLifecycleExecution.addLifecycles(TenantAwareLifecycleManager.java:202) 2015-10-29 15:13:01.452192500 at com.atlassian.sal.confluence.lifecycle.TenantAwareLifecycleManager$PerTenantLifecycleExecution.run(TenantAwareLifecycleManager.java:189) 2015-10-29 15:13:01.452192500 ... 5 more 2015-10-29 15:13:03.818118500 2015-10-29 15:13:03,815 INFO [localhost-startStop-1] [confluence.upgrade.impl.DefaultUpgradeGate] waitForBooleanValue Waiting to find if plugin dependent upgrades are required. Maximum wait time will be 90 seconds. 2015-10-29 15:13:03.818121500 2015-10-29 15:13:03,816 INFO [localhost-startStop-1] [confluence.upgrade.impl.DefaultUpgradeGate] waitForBooleanValue plugin dependent upgrades are required : false. 2015-10-29 15:13:05.000620500 2015-10-29 15:13:04,995 ERROR [ThreadPoolAsyncTaskExecutor::Thread 9] [internal.util.concurrent.RunnableTimedExecution] execute Closing runnable for context NonValidatingOsgiBundleXmlApplicationContext(bundle=com.atlassian.plugins.atlassian-connect-plugin, config=osgibundle:/META-INF/spring/*.xml) did not finish in 10000ms; consider taking a snapshot and then shutdown the VM in case the thread still hangs 2015-10-29 15:13:05.000624500 2015-10-29 15:13:04,997 ERROR [ThreadPoolAsyncTaskExecutor::Thread 9] [extender.internal.support.ExtenderConfiguration] onOsgiApplicationEvent Application context refresh failed (NonValidatingOsgiBundleXmlApplicationContext(bundle=com.atlassian.plugins.atlassian-connect-plugin, config=osgibundle:/META-INF/spring/*.xml)) 2015-10-29 15:13:05.000635500 org.springframework.beans.factory.BeanInitializationException: Failed to process @EventListener annotation on bean with name 'licenseService'; nested exception is java.lang.LinkageError: loader constraint violation: loader (instance of org/eclipse/gemini/blueprint/context/support/internal/classloader/ChainedClassLoader) previously initiated loading for a different type with name "com/atlassian/extras/api/confluence/ConfluenceLicense" 2015-10-29 15:13:05.000641500 at org.springframework.context.event.EventListenerMethodProcessor.afterSingletonsInstantiated(EventListenerMethodProcessor.java:83) 2015-10-29 15:13:05.000642500 at org.springframework.beans.factory.support.DefaultListableBeanFactory.preInstantiateSingletons(DefaultListableBeanFactory.java:792) 2015-10-29 15:13:05.000643500 at org.springframework.context.support.AbstractApplicationContext.finishBeanFactoryInitialization(AbstractApplicationContext.java:835) 2015-10-29 15:13:05.000644500 at org.eclipse.gemini.blueprint.context.support.AbstractDelegatedExecutionApplicationContext.access$1600(AbstractDelegatedExecutionApplicationContext.java:60) 2015-10-29 15:13:05.000645500 at org.eclipse.gemini.blueprint.context.support.AbstractDelegatedExecutionApplicationContext$4.run(AbstractDelegatedExecutionApplicationContext.java:325) 2015-10-29 15:13:05.000646500 at org.eclipse.gemini.blueprint.util.internal.PrivilegedUtils.executeWithCustomTCCL(PrivilegedUtils.java:85) 2015-10-29 15:13:05.000647500 at org.eclipse.gemini.blueprint.context.support.AbstractDelegatedExecutionApplicationContext.completeRefresh(AbstractDelegatedExecutionApplicationContext.java:290) 2015-10-29 15:13:05.000650500 at org.eclipse.gemini.blueprint.extender.internal.dependencies.startup.DependencyWaiterApplicationContextExecutor$CompleteRefreshTask.run(DependencyWaiterApplicationContextExecutor.java:137) 2015-10-29 15:13:05.000651500 at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142) 2015-10-29 15:13:05.000657500 at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617) 2015-10-29 15:13:05.000658500 at java.lang.Thread.run(Thread.java:745) 2015-10-29 15:13:05.000658500 Caused by: java.lang.LinkageError: loader constraint violation: loader (instance of org/eclipse/gemini/blueprint/context/support/internal/classloader/ChainedClassLoader) previously initiated loading for a different type with name "com/atlassian/extras/api/confluence/ConfluenceLicense" 2015-10-29 15:13:05.000660500 at java.lang.Class.getDeclaredMethods0(Native Method) 2015-10-29 15:13:05.000661500 at java.lang.Class.privateGetDeclaredMethods(Class.java:2688) 2015-10-29 15:13:05.000661500 at java.lang.Class.getDeclaredMethods(Class.java:1962) 2015-10-29 15:13:05.000662500 at org.springframework.util.ReflectionUtils.getDeclaredMethods(ReflectionUtils.java:606) 2015-10-29 15:13:05.000666500 at org.springframework.util.ReflectionUtils.doWithMethods(ReflectionUtils.java:518) 2015-10-29 15:13:05.000666500 at org.springframework.util.ReflectionUtils.doWithMethods(ReflectionUtils.java:504) 2015-10-29 15:13:05.000667500 at org.springframework.util.ReflectionUtils.getUniqueDeclaredMethods(ReflectionUtils.java:564) 2015-10-29 15:13:05.000667500 at org.springframework.context.event.EventListenerMethodProcessor.processBean(EventListenerMethodProcessor.java:105) 2015-10-29 15:13:05.005848500 at org.springframework.context.event.EventListenerMethodProcessor.afterSingletonsInstantiated(EventListenerMethodProcessor.java:80) 2015-10-29 15:13:05.005849500 ... 10 more 2015-10-29 15:13:05.005849500 2015-10-29 15:13:04,998 ERROR [ThreadPoolAsyncTaskExecutor::Thread 9] [internal.dependencies.startup.DependencyWaiterApplicationContextExecutor] fail Unable to create application context for [com.atlassian.plugins.atlassian-connect-plugin], unsatisfied dependencies: none 2015-10-29 15:13:05.005858500 org.springframework.beans.factory.BeanInitializationException: Failed to process @EventListener annotation on bean with name 'licenseService'; nested exception is java.lang.LinkageError: loader constraint violation: loader (instance of org/eclipse/gemini/blueprint/context/support/internal/classloader/ChainedClassLoader) previously initiated loading for a different type with name "com/atlassian/extras/api/confluence/ConfluenceLicense" 2015-10-29 15:13:05.005868500 at org.springframework.context.event.EventListenerMethodProcessor.afterSingletonsInstantiated(EventListenerMethodProcessor.java:83) 2015-10-29 15:13:05.005870500 at org.springframework.beans.factory.support.DefaultListableBeanFactory.preInstantiateSingletons(DefaultListableBeanFactory.java:792) 2015-10-29 15:13:05.005871500 at org.springframework.context.support.AbstractApplicationContext.finishBeanFactoryInitialization(AbstractApplicationContext.java:835) 2015-10-29 15:13:05.005871500 at org.eclipse.gemini.blueprint.context.support.AbstractDelegatedExecutionApplicationContext.access$1600(AbstractDelegatedExecutionApplicationContext.java:60) 2015-10-29 15:13:05.005873500 at org.eclipse.gemini.blueprint.context.support.AbstractDelegatedExecutionApplicationContext$4.run(AbstractDelegatedExecutionApplicationContext.java:325) 2015-10-29 15:13:05.005874500 at org.eclipse.gemini.blueprint.util.internal.PrivilegedUtils.executeWithCustomTCCL(PrivilegedUtils.java:85) 2015-10-29 15:13:05.005876500 at org.eclipse.gemini.blueprint.context.support.AbstractDelegatedExecutionApplicationContext.completeRefresh(AbstractDelegatedExecutionApplicationContext.java:290) 2015-10-29 15:13:05.005877500 at org.eclipse.gemini.blueprint.extender.internal.dependencies.startup.DependencyWaiterApplicationContextExecutor$CompleteRefreshTask.run(DependencyWaiterApplicationContextExecutor.java:137) 2015-10-29 15:13:05.005879500 at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142) 2015-10-29 15:13:05.005880500 at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617) 2015-10-29 15:13:05.005880500 at java.lang.Thread.run(Thread.java:745) 2015-10-29 15:13:05.005881500 Caused by: java.lang.LinkageError: loader constraint violation: loader (instance of org/eclipse/gemini/blueprint/context/support/internal/classloader/ChainedClassLoader) previously initiated loading for a different type with name "com/atlassian/extras/api/confluence/ConfluenceLicense" 2015-10-29 15:13:05.005889500 at java.lang.Class.getDeclaredMethods0(Native Method) 2015-10-29 15:13:05.005889500 at java.lang.Class.privateGetDeclaredMethods(Class.java:2688) 2015-10-29 15:13:05.005893500 at java.lang.Class.getDeclaredMethods(Class.java:1962) 2015-10-29 15:13:05.005893500 at org.springframework.util.ReflectionUtils.getDeclaredMethods(ReflectionUtils.java:606) 2015-10-29 15:13:05.005894500 at org.springframework.util.ReflectionUtils.doWithMethods(ReflectionUtils.java:518) 2015-10-29 15:13:05.005895500 at org.springframework.util.ReflectionUtils.doWithMethods(ReflectionUtils.java:504) 2015-10-29 15:13:05.005895500 at org.springframework.util.ReflectionUtils.getUniqueDeclaredMethods(ReflectionUtils.java:564) 2015-10-29 15:13:05.005897500 at org.springframework.context.event.EventListenerMethodProcessor.processBean(EventListenerMethodProcessor.java:105) 2015-10-29 15:13:05.005898500 at org.springframework.context.event.EventListenerMethodProcessor.afterSingletonsInstantiated(EventListenerMethodProcessor.java:80) 2015-10-29 15:13:05.005898500 ... 10 more 2015-10-29 15:13:05.160129500 2015-10-29 15:13:05,153 INFO [localhost-startStop-1] [confluence.common.listener.LicenseChangeConfluenceFlavourListener] handleStartup Confluence started. Changing Confluence Flavour if required. 2015-10-29 15:13:05.610963500 2015-10-29 15:13:05,608 INFO [localhost-startStop-1] [atlassian.ondemand.license.LicenseFileAccessor] readLicense License read successfully 2015-10-29 15:13:05.716288500 2015-10-29 15:13:05,703 INFO [localhost-startStop-1] [atlassian.ondemand.license.DefaultLicenseManager] broadcastLicenseChangedEvent License loaded successfully. Broadcasting LicenseChangedEvent to plugins. 2015-10-29 15:13:05.716290500 2015-10-29 15:13:05,705 INFO [localhost-startStop-1] [studio.common.appstatus.AppStatusServiceImpl] refreshAppStatuses Refresh of Application Status requested 2015-10-29 15:13:05.734865500 2015-10-29 15:13:05,731 INFO [localhost-startStop-1] [studio.common.appstatus.AppStatusServiceImpl] refreshAppStatus Updating AppStatus for JIRA from AppStatus{application=interface com.atlassian.applinks.api.application.jira.JiraApplicationType, enabled=true} to true. 2015-10-29 15:13:05.734867500 2015-10-29 15:13:05,733 INFO [localhost-startStop-1] [studio.common.appstatus.AppStatusServiceImpl] refreshAppStatus Updating status for application JIRA, broadcasting AppStatusChangeEvent. This will trigger AppLink creation/deletion. 2015-10-29 15:13:05.736010500 2015-10-29 15:13:05,735 INFO [localhost-startStop-1] [studio.common.appstatus.AppStatusServiceImpl] refreshAppStatus Updating AppStatus for CONFLUENCE from AppStatus{application=interface com.atlassian.applinks.api.application.confluence.ConfluenceApplicationType, enabled=true} to true. 2015-10-29 15:13:05.736948500 2015-10-29 15:13:05,735 INFO [localhost-startStop-1] [studio.common.appstatus.AppStatusServiceImpl] refreshAppStatus Updating status for application CONFLUENCE, broadcasting AppStatusChangeEvent. This will trigger AppLink creation/deletion. 2015-10-29 15:13:05.736949500 2015-10-29 15:13:05,736 INFO [localhost-startStop-1] [studio.common.appstatus.AppStatusServiceImpl] refreshAppStatus Updating AppStatus for FECRU from AppStatus{application=interface com.atlassian.applinks.api.application.fecru.FishEyeCrucibleApplicationType, enabled=false} to false. 2015-10-29 15:13:05.737998500 2015-10-29 15:13:05,736 INFO [localhost-startStop-1] [studio.common.appstatus.AppStatusServiceImpl] refreshAppStatus Updating status for application FECRU, broadcasting AppStatusChangeEvent. This will trigger AppLink creation/deletion. 2015-10-29 15:13:05.738040500 2015-10-29 15:13:05,737 INFO [localhost-startStop-1] [studio.common.appstatus.AppStatusServiceImpl] refreshAppStatus Updating AppStatus for BAMBOO from AppStatus{application=interface com.atlassian.applinks.api.application.bamboo.BambooApplicationType, enabled=false} to false. 2015-10-29 15:13:05.738877500 2015-10-29 15:13:05,738 INFO [localhost-startStop-1] [studio.common.appstatus.AppStatusServiceImpl] refreshAppStatus Updating status for application BAMBOO, broadcasting AppStatusChangeEvent. This will trigger AppLink creation/deletion. 2015-10-29 15:13:05.740758500 2015-10-29 15:13:05,740 INFO [localhost-startStop-1] [confluence.common.listener.LicenseChangeConfluenceFlavourListener] handleLicenseChange License read during startup. Setting Confluence flavour to initial value. 2015-10-29 15:13:05.793016500 2015-10-29 15:13:05,791 INFO [localhost-startStop-1] [confluence.common.services.ConfluenceFlavourServiceImpl] initialiseFlavourFromLicense Initialising Confluence Flavour to ALACARTE. 2015-10-29 15:13:05.793019500 2015-10-29 15:13:05,791 INFO [localhost-startStop-1] [confluence.common.services.ConfluenceFlavourServiceImpl] setFlavourFromLicense Setting Confluence Flavour to ALACARTE (was: ALACARTE). 2015-10-29 15:13:05.800993500 2015-10-29 15:13:05,800 INFO [localhost-startStop-1] [confluence.common.services.ConfluenceFlavourServiceImpl] togglePluginsForFlavour Enabling the following set of plugins to meet the Confluence Flavour ALACARTE: [com.atlassian.studio.confluence] 2015-10-29 15:13:06.183949500 2015-10-29 15:13:06,168 INFO [scheduler_Worker-10] [atlassian.crowd.directory.DbCachingRemoteDirectory] synchroniseCache INCREMENTAL synchronisation for directory [ 65537 ] starting 2015-10-29 15:13:06.191857500 2015-10-29 15:13:06,185 INFO [scheduler_Worker-10] [atlassian.crowd.directory.DbCachingRemoteDirectory] synchroniseCache Attempting INCREMENTAL synchronisation for directory [ 65537 ] 2015-10-29 15:13:06.191858500 2015-10-29 15:13:06,186 INFO [scheduler_Worker-10] [atlassian.crowd.directory.DbCachingRemoteDirectory] synchroniseCache Incremental synchronisation for directory [ 65537 ] was not completed, falling back to a full synchronisation 2015-10-29 15:13:06.191859500 2015-10-29 15:13:06,186 INFO [scheduler_Worker-10] [atlassian.crowd.directory.DbCachingRemoteDirectory] synchroniseCache INCREMENTAL synchronisation for directory [ 65537 ] was not successful, attempting FULL 2015-10-29 15:13:06.487145500 2015-10-29 15:13:06,460 WARN [ThreadPoolAsyncTaskExecutor::Thread 10] [config.internal.adapter.OsgiServiceLifecycleListenerAdapter] invokeCustomServiceReferenceMethod custom method [public void com.atlassian.sal.confluence.lifecycle.TenantAwareLifecycleManager.onLifecycleExport(org.osgi.framework.ServiceReference)] threw exception when passing service [com.sun.proxy.$Proxy527@1ed72a] 2015-10-29 15:13:06.487148500 java.lang.NullPointerException 2015-10-29 15:13:06.487149500 at com.atlassian.sal.confluence.lifecycle.SpringManagedModuleReferenceParser.apply(SpringManagedModuleReferenceParser.java:22) 2015-10-29 15:13:06.487160500 at com.atlassian.sal.confluence.lifecycle.SpringManagedModuleReferenceParser.apply(SpringManagedModuleReferenceParser.java:17) 2015-10-29 15:13:06.487161500 at com.atlassian.sal.confluence.lifecycle.ModuleExecutionFilter.add(ModuleExecutionFilter.java:38) 2015-10-29 15:13:06.487162500 at com.atlassian.sal.confluence.lifecycle.ServiceExecutionStrategyComposite.add(ServiceExecutionStrategyComposite.java:28) 2015-10-29 15:13:06.487164500 at com.atlassian.sal.confluence.lifecycle.TenantAwareLifecycleManager$PerTenantLifecycleExecution.addLifecycles(TenantAwareLifecycleManager.java:202) 2015-10-29 15:13:06.487167500 at com.atlassian.sal.confluence.lifecycle.TenantAwareLifecycleManager$PerTenantLifecycleExecution.run(TenantAwareLifecycleManager.java:189) 2015-10-29 15:13:06.487168500 at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511) 2015-10-29 15:13:06.487168500 at java.util.concurrent.FutureTask.run(FutureTask.java:266) 2015-10-29 15:13:06.487170500 at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142) 2015-10-29 15:13:06.487170500 at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617) 2015-10-29 15:13:06.487171500 at java.lang.Thread.run(Thread.java:745) 2015-10-29 15:13:06.569923500 2015-10-29 15:13:06,539 INFO [scheduler_Worker-10] [directory.ldap.cache.RemoteDirectoryCacheRefresher] findAllRemoteUsers found [ 2 ] remote users in [ 353 ms ] 2015-10-29 15:13:06.600097500 2015-10-29 15:13:06,597 WARN [ThreadPoolAsyncTaskExecutor::Thread 10] [config.internal.adapter.OsgiServiceLifecycleListenerAdapter] invokeCustomServiceReferenceMethod custom method [public void com.atlassian.sal.confluence.lifecycle.TenantAwareLifecycleManager.onLifecycleExport(org.osgi.framework.ServiceReference)] threw exception when passing service [com.sun.proxy.$Proxy527@bc0aa6] 2015-10-29 15:13:06.600100500 java.lang.NullPointerException 2015-10-29 15:13:06.600100500 at com.atlassian.sal.confluence.lifecycle.SpringManagedModuleReferenceParser.apply(SpringManagedModuleReferenceParser.java:22) 2015-10-29 15:13:06.600112500 at com.atlassian.sal.confluence.lifecycle.SpringManagedModuleReferenceParser.apply(SpringManagedModuleReferenceParser.java:17) 2015-10-29 15:13:06.600113500 at com.atlassian.sal.confluence.lifecycle.ModuleExecutionFilter.add(ModuleExecutionFilter.java:38) 2015-10-29 15:13:06.600113500 at com.atlassian.sal.confluence.lifecycle.ServiceExecutionStrategyComposite.add(ServiceExecutionStrategyComposite.java:28) 2015-10-29 15:13:06.600115500 at com.atlassian.sal.confluence.lifecycle.TenantAwareLifecycleManager$PerTenantLifecycleExecution.addLifecycles(TenantAwareLifecycleManager.java:202) 2015-10-29 15:13:06.600119500 at com.atlassian.sal.confluence.lifecycle.TenantAwareLifecycleManager$PerTenantLifecycleExecution.run(TenantAwareLifecycleManager.java:189) 2015-10-29 15:13:06.600120500 at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511) 2015-10-29 15:13:06.600120500 at java.util.concurrent.FutureTask.run(FutureTask.java:266) 2015-10-29 15:13:06.600122500 at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142) 2015-10-29 15:13:06.600122500 at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617) 2015-10-29 15:13:06.600123500 at java.lang.Thread.run(Thread.java:745)
drizzuto this issue is mentioned in commits included in the Confluence 5.9.1-OD-2015.47.1-0002 release being approved for production: CPU-64