[CONTROLLER-324] Exception logged when shutting down config-manager Created: 15/Apr/14  Updated: 25/Jul/23  Resolved: 12/May/15

Status: Resolved
Project: controller
Component/s: mdsal
Affects Version/s: None
Fix Version/s: None

Type: Bug
Reporter: Tomas Olvecky Assignee: Unassigned
Resolution: Cannot Reproduce Votes: 0
Labels: None
Remaining Estimate: Not Specified
Time Spent: Not Specified
Original Estimate: Not Specified
Environment:

Operating System: All
Platform: All

External issue ID: 744

 Description   

Steps to reproduce: run controller with default configuration
stop config-manager.

Following exception is logged:
2014-04-15 12:22:42.861 CEST [Gogo shell] ERROR o.o.c.m.s.c.i.s.AbstractDataBroker - Unexpected exception in listener org.opendaylight.controller.sal.binding.impl.connect.dom.BindingIndependentConnector$DomToBindingCommitHandler@593414c during invoking onRegister
java.lang.NullPointerException: null
at org.opendaylight.controller.config.yang.md.sal.binding.impl.RuntimeMappingModule$RuntimeGeneratedMappingServiceProxy.toDataDom(RuntimeMappingModule.java:129) ~[na:na]
at org.opendaylight.controller.sal.binding.impl.connect.dom.BindingIndependentConnector$DomToBindingCommitHandler.onRegister(BindingIndependentConnector.java:493) ~[na:na]
at org.opendaylight.controller.sal.binding.impl.connect.dom.BindingIndependentConnector$DomToBindingCommitHandler.onRegister(BindingIndependentConnector.java:486) ~[na:na]
at org.opendaylight.controller.md.sal.common.impl.service.AbstractDataBroker.registerCommitHandler(AbstractDataBroker.java:245) ~[na:na]
at org.opendaylight.controller.md.sal.binding.util.AbstractBindingSalProviderInstance.registerCommitHandler(AbstractBindingSalProviderInstance.java:53) [bundlefile:na]
at org.opendaylight.controller.md.sal.binding.util.AbstractBindingSalProviderInstance.registerCommitHandler(AbstractBindingSalProviderInstance.java:30) [bundlefile:na]
at org.opendaylight.controller.sal.compatibility.topology.TopologyProvider.startAdapter(TopologyProvider.java:79) [bundlefile:na]
at org.opendaylight.controller.sal.compatibility.SalCompatibilityProvider.onSessionInitiated(SalCompatibilityProvider.java:95) [bundlefile:na]
at org.opendaylight.controller.md.sal.binding.util.BindingContextUtils.createProviderContextAndInitialize(BindingContextUtils.java:57) [bundlefile:na]
at org.opendaylight.controller.sal.binding.impl.RootBindingAwareBroker.registerProvider(RootBindingAwareBroker.java:140) [bundlefile:na]
at org.opendaylight.controller.sal.compatibility.ComponentActivator.setBroker(ComponentActivator.java:186) [bundlefile:na]
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) ~[na:1.7.0_51]
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57) ~[na:1.7.0_51]
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) ~[na:1.7.0_51]
at java.lang.reflect.Method.invoke(Method.java:606) ~[na:1.7.0_51]
at org.apache.felix.dm.InvocationUtil.invokeMethod(InvocationUtil.java:111) [bundlefile:na]
at org.apache.felix.dm.InvocationUtil.invokeCallbackMethod(InvocationUtil.java:66) [bundlefile:na]
at org.apache.felix.dm.impl.ComponentImpl.invokeCallbackMethod(ComponentImpl.java:688) [bundlefile:na]
at org.apache.felix.dm.impl.dependencies.ServiceDependencyImpl.invoke(ServiceDependencyImpl.java:704) [bundlefile:na]
at org.apache.felix.dm.impl.dependencies.ServiceDependencyImpl.invokeRemoved(ServiceDependencyImpl.java:666) [bundlefile:na]
at org.apache.felix.dm.impl.dependencies.ServiceDependencyImpl.invokeRemoved(ServiceDependencyImpl.java:1089) [bundlefile:na]
at org.apache.felix.dm.impl.ComponentImpl.unconfigureServices(ComponentImpl.java:1020) [bundlefile:na]
at org.apache.felix.dm.impl.ComponentImpl.destroyService(ComponentImpl.java:843) [bundlefile:na]
at org.apache.felix.dm.impl.ComponentImpl.deactivateService(ComponentImpl.java:671) [bundlefile:na]
at org.apache.felix.dm.impl.ComponentImpl.access$300(ComponentImpl.java:52) [bundlefile:na]
at org.apache.felix.dm.impl.ComponentImpl$13.run(ComponentImpl.java:220) [bundlefile:na]
at org.apache.felix.dm.impl.SerialExecutor$1.run(SerialExecutor.java:47) [bundlefile:na]
at org.apache.felix.dm.impl.SerialExecutor.scheduleNext(SerialExecutor.java:84) [bundlefile:na]
at org.apache.felix.dm.impl.SerialExecutor.execute(SerialExecutor.java:68) [bundlefile:na]
at org.apache.felix.dm.impl.ComponentImpl.calculateStateChanges(ComponentImpl.java:231) [bundlefile:na]
at org.apache.felix.dm.impl.ComponentImpl.dependencyUnavailable(ComponentImpl.java:381) [bundlefile:na]
at org.apache.felix.dm.impl.dependencies.ServiceDependencyImpl.removedService(ServiceDependencyImpl.java:513) [bundlefile:na]
at org.apache.felix.dm.tracker.ServiceTracker$Tracked.customizerRemoved(ServiceTracker.java:1351) [bundlefile:na]
at org.apache.felix.dm.tracker.AbstractTracked.untrack(AbstractTracked.java:359) [bundlefile:na]
at org.apache.felix.dm.tracker.ServiceTracker$Tracked.serviceChangedHideAspects(ServiceTracker.java:1285) [bundlefile:na]
at org.apache.felix.dm.tracker.ServiceTracker$Tracked.serviceChanged(ServiceTracker.java:1101) [bundlefile:na]
at org.eclipse.osgi.internal.serviceregistry.FilteredServiceListener.serviceChanged(FilteredServiceListener.java:107) [org.eclipse.osgi-3.8.1.v20120830-144521.jar:na]
at org.eclipse.osgi.framework.internal.core.BundleContextImpl.dispatchEvent(BundleContextImpl.java:861) [org.eclipse.osgi-3.8.1.v20120830-144521.jar:na]
at org.eclipse.osgi.framework.eventmgr.EventManager.dispatchEvent(EventManager.java:230) [org.eclipse.osgi-3.8.1.v20120830-144521.jar:na]
at org.eclipse.osgi.framework.eventmgr.ListenerQueue.dispatchEventSynchronous(ListenerQueue.java:148) [org.eclipse.osgi-3.8.1.v20120830-144521.jar:na]
at org.eclipse.osgi.internal.serviceregistry.ServiceRegistry.publishServiceEventPrivileged(ServiceRegistry.java:819) [org.eclipse.osgi-3.8.1.v20120830-144521.jar:na]
at org.eclipse.osgi.internal.serviceregistry.ServiceRegistry.publishServiceEvent(ServiceRegistry.java:771) [org.eclipse.osgi-3.8.1.v20120830-144521.jar:na]
at org.eclipse.osgi.internal.serviceregistry.ServiceRegistrationImpl.unregister(ServiceRegistrationImpl.java:225) [org.eclipse.osgi-3.8.1.v20120830-144521.jar:na]
at org.opendaylight.controller.config.manager.impl.osgi.BeanToOsgiServiceManager$OsgiRegistration.close(BeanToOsgiServiceManager.java:110) [bundlefile:na]
at org.opendaylight.controller.config.manager.impl.dependencyresolver.DestroyedModule.close(DestroyedModule.java:58) [bundlefile:na]
at org.opendaylight.controller.config.manager.impl.ConfigRegistryImpl.close(ConfigRegistryImpl.java:434) [bundlefile:na]
at org.opendaylight.controller.config.manager.impl.util.OsgiRegistrationUtil$4.close(OsgiRegistrationUtil.java:82) [bundlefile:na]
at org.opendaylight.controller.config.manager.impl.osgi.ConfigManagerActivator.stop(ConfigManagerActivator.java:88) [bundlefile:na]
at org.eclipse.osgi.framework.internal.core.BundleContextImpl$2.run(BundleContextImpl.java:771) [org.eclipse.osgi-3.8.1.v20120830-144521.jar:na]
at java.security.AccessController.doPrivileged(Native Method) [na:1.7.0_51]
at org.eclipse.osgi.framework.internal.core.BundleContextImpl.stop(BundleContextImpl.java:764) [org.eclipse.osgi-3.8.1.v20120830-144521.jar:na]
at org.eclipse.osgi.framework.internal.core.BundleHost.stopWorker(BundleHost.java:510) [org.eclipse.osgi-3.8.1.v20120830-144521.jar:na]
at org.eclipse.osgi.framework.internal.core.AbstractBundle.stop(AbstractBundle.java:465) [org.eclipse.osgi-3.8.1.v20120830-144521.jar:na]
at org.eclipse.osgi.framework.internal.core.AbstractBundle.stop(AbstractBundle.java:457) [org.eclipse.osgi-3.8.1.v20120830-144521.jar:na]
at org.eclipse.equinox.console.commands.EquinoxCommandProvider.stop(EquinoxCommandProvider.java:267) [org.eclipse.equinox.console-1.0.0.v20120522-1841.jar:na]
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) ~[na:1.7.0_51]
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57) ~[na:1.7.0_51]
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) ~[na:1.7.0_51]
at java.lang.reflect.Method.invoke(Method.java:606) ~[na:1.7.0_51]
at org.apache.felix.gogo.runtime.Reflective.method(Reflective.java:136) [bundlefile:na]
at org.apache.felix.gogo.runtime.CommandProxy.execute(CommandProxy.java:82) [bundlefile:na]
at org.apache.felix.gogo.runtime.Closure.executeCmd(Closure.java:469) [bundlefile:na]
at org.apache.felix.gogo.runtime.Closure.executeStatement(Closure.java:395) [bundlefile:na]
at org.apache.felix.gogo.runtime.Pipe.run(Pipe.java:108) [bundlefile:na]
at org.apache.felix.gogo.runtime.Closure.execute(Closure.java:183) [bundlefile:na]
at org.apache.felix.gogo.runtime.Closure.execute(Closure.java:120) [bundlefile:na]
at org.apache.felix.gogo.runtime.CommandSessionImpl.execute(CommandSessionImpl.java:89) [bundlefile:na]
at org.apache.felix.gogo.shell.Console.run(Console.java:62) [bundlefile:na]
at org.apache.felix.gogo.shell.Shell.console(Shell.java:203) [bundlefile:na]
at org.apache.felix.gogo.shell.Shell.gosh(Shell.java:128) [bundlefile:na]
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) ~[na:1.7.0_51]
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57) ~[na:1.7.0_51]
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) ~[na:1.7.0_51]
at java.lang.reflect.Method.invoke(Method.java:606) ~[na:1.7.0_51]
at org.apache.felix.gogo.runtime.Reflective.method(Reflective.java:136) [bundlefile:na]
at org.apache.felix.gogo.runtime.CommandProxy.execute(CommandProxy.java:82) [bundlefile:na]
at org.apache.felix.gogo.runtime.Closure.executeCmd(Closure.java:469) [bundlefile:na]
at org.apache.felix.gogo.runtime.Closure.executeStatement(Closure.java:395) [bundlefile:na]
at org.apache.felix.gogo.runtime.Pipe.run(Pipe.java:108) [bundlefile:na]
at org.apache.felix.gogo.runtime.Closure.execute(Closure.java:183) [bundlefile:na]
at org.apache.felix.gogo.runtime.Closure.execute(Closure.java:120) [bundlefile:na]
at org.apache.felix.gogo.runtime.CommandSessionImpl.execute(CommandSessionImpl.java:89) [bundlefile:na]
at org.apache.felix.gogo.shell.Activator.run(Activator.java:75) [bundlefile:na]
at java.lang.Thread.run(Thread.java:744) [na:1.7.0_51]

 Comments   
Comment by Tony Tkacik [ 14/Jul/14 ]

2014-07-14 13:06:50.887 CEST [remote-connector-processing-executor-2] ERROR o.o.c.s.c.netconf.NetconfDevice - RemoteDevice

{controller-config}: Initialization failed
java.lang.IllegalStateException: RemoteDevice{controller-config}

: Sal provider was not initialized by sal. Cannot publish notification
at com.google.common.base.Preconditions.checkState(Preconditions.java:176) ~[na:na]
at org.opendaylight.controller.sal.connect.netconf.sal.NetconfDeviceSalProvider.getMountInstance(NetconfDeviceSalProvider.java:43) ~[na:na]
at org.opendaylight.controller.sal.connect.netconf.sal.NetconfDeviceSalFacade.onDeviceConnected(NetconfDeviceSalFacade.java:61) ~[na:na]
at org.opendaylight.controller.sal.connect.netconf.sal.NetconfDeviceSalFacade.onDeviceConnected(NetconfDeviceSalFacade.java:32) ~[na:na]
at org.opendaylight.controller.sal.connect.netconf.NetconfDevice$2.run(NetconfDevice.java:101) ~[na:na]
at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:471) ~[na:1.7.0_55]
at java.util.concurrent.FutureTask.run(FutureTask.java:262) ~[na:1.7.0_55]
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145) [na:1.7.0_55]
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615) [na:1.7.0_55]
at java.lang.Thread.run(Thread.java:744) [na:1.7.0_55]
2014-07-14 13:06:50 CEST [com.google.common.util.concurrent.ExecutionList] SEVERE com.google.common.util.concurrent.ExecutionList$RunnableExecutorPair execute RuntimeException while executing runnable com.google.common.util.concurrent.Futures$4@56790ea6 with executor com.google.common.util.concurrent.MoreExecutors$SameThreadExecutorService@25b0e0a4
java.util.concurrent.RejectedExecutionException: Task com.google.common.util.concurrent.ListenableFutureTask@7d0ae5dc rejected from java.util.concurrent.ThreadPoolExecutor@4d5f158f[Shutting down, pool size = 2, active threads = 2, queued tasks = 0, completed tasks = 2]
at java.util.concurrent.ThreadPoolExecutor$AbortPolicy.rejectedExecution(ThreadPoolExecutor.java:2048)
at java.util.concurrent.ThreadPoolExecutor.reject(ThreadPoolExecutor.java:821)
at java.util.concurrent.ThreadPoolExecutor.execute(ThreadPoolExecutor.java:1372)
at java.util.concurrent.Executors$DelegatedExecutorService.execute(Executors.java:628)
at com.google.common.util.concurrent.MoreExecutors$ListeningDecorator.execute(MoreExecutors.java:480)
at com.google.common.util.concurrent.AbstractListeningExecutorService.submit(AbstractListeningExecutorService.java:49)
at org.opendaylight.controller.sal.connect.netconf.sal.NetconfDeviceDatastoreAdapter.submitDataChangeToExecutor(NetconfDeviceDatastoreAdapter.java:149)
at org.opendaylight.controller.sal.connect.netconf.sal.NetconfDeviceDatastoreAdapter.updateDeviceState(NetconfDeviceDatastoreAdapter.java:64)
at org.opendaylight.controller.sal.connect.netconf.sal.NetconfDeviceSalFacade.onDeviceDisconnected(NetconfDeviceSalFacade.java:69)
at org.opendaylight.controller.sal.connect.netconf.NetconfDevice$3.onFailure(NetconfDevice.java:116)
at com.google.common.util.concurrent.Futures$4.run(Futures.java:1140)
at com.google.common.util.concurrent.MoreExecutors$SameThreadExecutorService.execute(MoreExecutors.java:293)
at com.google.common.util.concurrent.ExecutionList$RunnableExecutorPair.execute(ExecutionList.java:150)
at com.google.common.util.concurrent.ExecutionList.execute(ExecutionList.java:135)
at com.google.common.util.concurrent.ListenableFutureTask.done(ListenableFutureTask.java:91)
at java.util.concurrent.FutureTask.finishCompletion(FutureTask.java:380)
at java.util.concurrent.FutureTask.setException(FutureTask.java:247)
at java.util.concurrent.FutureTask.run(FutureTask.java:267)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
at java.lang.Thread.run(Thread.java:744)

2014-07-14 13:06:50.894 CEST [remote-connector-processing-executor-1] ERROR o.o.c.s.c.netconf.NetconfDevice - RemoteDevice

{controller-config}: Initialization failed
java.lang.IllegalStateException: RemoteDevice{controller-config}

: Sal provider was not initialized by sal. Cannot publish notification
at com.google.common.base.Preconditions.checkState(Preconditions.java:176) ~[na:na]
at org.opendaylight.controller.sal.connect.netconf.sal.NetconfDeviceSalProvider.getMountInstance(NetconfDeviceSalProvider.java:43) ~[na:na]
at org.opendaylight.controller.sal.connect.netconf.sal.NetconfDeviceSalFacade.onDeviceConnected(NetconfDeviceSalFacade.java:61) ~[na:na]
at org.opendaylight.controller.sal.connect.netconf.sal.NetconfDeviceSalFacade.onDeviceConnected(NetconfDeviceSalFacade.java:32) ~[na:na]
at org.opendaylight.controller.sal.connect.netconf.NetconfDevice$2.run(NetconfDevice.java:101) ~[na:na]
at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:471) ~[na:1.7.0_55]
at java.util.concurrent.FutureTask.run(FutureTask.java:262) ~[na:1.7.0_55]
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145) [na:1.7.0_55]
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615) [na:1.7.0_55]
at java.lang.Thread.run(Thread.java:744) [na:1.7.0_55]
2014-07-14 13:06:50 CEST [com.google.common.util.concurrent.ExecutionList] SEVERE com.google.common.util.concurrent.ExecutionList$RunnableExecutorPair execute RuntimeException while executing runnable com.google.common.util.concurrent.Futures$4@6200de10 with executor com.google.common.util.concurrent.MoreExecutors$SameThreadExecutorService@26f31eb4
java.util.concurrent.RejectedExecutionException: Task com.google.common.util.concurrent.ListenableFutureTask@43cf598f rejected from java.util.concurrent.ThreadPoolExecutor@4d5f158f[Shutting down, pool size = 1, active threads = 1, queued tasks = 0, completed tasks = 3]
at java.util.concurrent.ThreadPoolExecutor$AbortPolicy.rejectedExecution(ThreadPoolExecutor.java:2048)
at java.util.concurrent.ThreadPoolExecutor.reject(ThreadPoolExecutor.java:821)
at java.util.concurrent.ThreadPoolExecutor.execute(ThreadPoolExecutor.java:1372)
at java.util.concurrent.Executors$DelegatedExecutorService.execute(Executors.java:628)
at com.google.common.util.concurrent.MoreExecutors$ListeningDecorator.execute(MoreExecutors.java:480)
at com.google.common.util.concurrent.AbstractListeningExecutorService.submit(AbstractListeningExecutorService.java:49)
at org.opendaylight.controller.sal.connect.netconf.sal.NetconfDeviceDatastoreAdapter.submitDataChangeToExecutor(NetconfDeviceDatastoreAdapter.java:149)
at org.opendaylight.controller.sal.connect.netconf.sal.NetconfDeviceDatastoreAdapter.updateDeviceState(NetconfDeviceDatastoreAdapter.java:64)
at org.opendaylight.controller.sal.connect.netconf.sal.NetconfDeviceSalFacade.onDeviceDisconnected(NetconfDeviceSalFacade.java:69)
at org.opendaylight.controller.sal.connect.netconf.NetconfDevice$3.onFailure(NetconfDevice.java:116)
at com.google.common.util.concurrent.Futures$4.run(Futures.java:1140)
at com.google.common.util.concurrent.MoreExecutors$SameThreadExecutorService.execute(MoreExecutors.java:293)
at com.google.common.util.concurrent.ExecutionList$RunnableExecutorPair.execute(ExecutionList.java:150)
at com.google.common.util.concurrent.ExecutionList.execute(ExecutionList.java:135)
at com.google.common.util.concurrent.ListenableFutureTask.done(ListenableFutureTask.java:91)
at java.util.concurrent.FutureTask.finishCompletion(FutureTask.java:380)
at java.util.concurrent.FutureTask.setException(FutureTask.java:247)
at java.util.concurrent.FutureTask.run(FutureTask.java:267)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
at java.lang.Thread.run(Thread.java:744)

Comment by Tony Tkacik [ 12/May/15 ]

Offending component does not exists anymore.

Generated at Wed Feb 07 19:52:45 UTC 2024 using Jira 8.20.10#820010-sha1:ace47f9899e9ee25d7157d59aa17ab06aee30d3d.