[MDSAL-689] Multiple issues during global context update Created: 20/Sep/21 Updated: 29/Sep/21 Resolved: 20/Sep/21 |
|
| Status: | Resolved |
| Project: | mdsal |
| Component/s: | DOM runtime |
| Affects Version/s: | 6.0.0, 7.0.0, 8.0.0, 6.0.11, 7.0.9, 8.0.5 |
| Fix Version/s: | 7.0.10, 8.0.6, 6.0.12 |
| Type: | Bug | Priority: | High |
| Reporter: | Robert Varga | Assignee: | Robert Varga |
| Resolution: | Done | Votes: | 0 |
| Labels: | pt | ||
| Remaining Estimate: | Not Specified | ||
| Time Spent: | Not Specified | ||
| Original Estimate: | Not Specified | ||
| Description |
|
Performing a late install operation like this:
opendaylight-user@root>feature:install odl-restconf-nb-rfc8040 WARNING: An illegal reflective access operation has occurred WARNING: Illegal reflective access by com.esotericsoftware.kryo.util.UnsafeUtil (file:/home/nite/odl/netconf/karaf/netconf-karaf-2.0.6-SNAPSHOT/data/cache/org.eclipse.osgi/207/0/bundleFile) to constructor java.nio.DirectByteBuffer(long,int,java.lang.Object) WARNING: Please consider reporting this to the maintainers of com.esotericsoftware.kryo.util.UnsafeUtil WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations WARNING: All illegal access operations will be denied in a future release opendaylight-user@root>feature:install odl-mdsal-apidocs leads to a reactor rewiring via rabbitmq/dropwizard metricts. This is not a problem as such, but we also end up refreshing the global runtime context: 2021-09-20T16:13:12,340 | INFO | features-3-thread-1 | OSGiModuleInfoSnapshotImpl | 242 - org.opendaylight.mdsal.dom-schema-osgi - 8.0.5 | EffectiveModelContext generation 2 activated 2021-09-20T16:13:12,498 | INFO | features-3-thread-1 | OSGiBindingRuntimeContextImpl | 226 - org.opendaylight.mdsal.binding-runtime-osgi - 8.0.5 | BindingRuntimeContext generation 2 activated 2021-09-20T16:13:12,501 | INFO | features-3-thread-1 | OSGiBindingDOMCodecServicesImpl | 220 - org.opendaylight.mdsal.binding-dom-codec-osgi - 8.0.5 | Binding/DOM Codec generation 2 activated but we then proceed to restart components: 2021-09-20T16:13:12,507 | INFO | features-3-thread-1 | AbstractAdaptedService | 217 - org.opendaylight.mdsal.binding-dom-adapter - 8.0.5 | Binding/DOM adapter for ActionProviderService deactivated (reason 5)
2021-09-20T16:13:12,508 | INFO | features-3-thread-1 | OSGiRemoteOpsProvider | 213 - org.opendaylight.controller.sal-remoterpc-connector - 4.0.3 | Remote Operations service stopping
2021-09-20T16:13:12,509 | INFO | features-3-thread-1 | OSGiRemoteOpsProvider | 213 - org.opendaylight.controller.sal-remoterpc-connector - 4.0.3 | Remote Operations services stopped
2021-09-20T16:13:12,514 | INFO | features-3-thread-1 | AbstractAdaptedService | 217 - org.opendaylight.mdsal.binding-dom-adapter - 8.0.5 | Binding/DOM adapter for ActionService deactivated (reason 5)
2021-09-20T16:13:12,531 | INFO | features-3-thread-1 | OSGiEntityOwnershipService | 244 - org.opendaylight.mdsal.eos-binding-adapter - 8.0.5 | Entity Ownership Service adapter stopping
2021-09-20T16:13:12,541 | INFO | features-3-thread-1 | OSGiEntityOwnershipService | 244 - org.opendaylight.mdsal.eos-binding-adapter - 8.0.5 | Entity Ownership Service adapter stopped
2021-09-20T16:13:12,543 | INFO | features-3-thread-1 | OSGiClusterAdmin | 209 - org.opendaylight.controller.sal-cluster-admin-impl - 4.0.3 | Cluster Admin services stopped
2021-09-20T16:13:12,545 | INFO | features-3-thread-1 | OSGiClusterSingletonServiceProvider | 253 - org.opendaylight.mdsal.singleton-dom-impl - 8.0.5 | Cluster Singleton Service stopping
2021-09-20T16:13:12,550 | INFO | features-3-thread-1 | OSGiClusterSingletonServiceProvider | 253 - org.opendaylight.mdsal.singleton-dom-impl - 8.0.5 | Cluster Singleton Service stopped
2021-09-20T16:13:12,558 | INFO | features-3-thread-1 | AbstractAdaptedService | 217 - org.opendaylight.mdsal.binding-dom-adapter - 8.0.5 | Binding/DOM adapter for RpcProviderService deactivated (reason 5)
2021-09-20T16:13:12,568 | INFO | features-3-thread-1 | AbstractAdaptedService | 217 - org.opendaylight.mdsal.binding-dom-adapter - 8.0.5 | Binding/DOM adapter for RpcConsumerRegistry deactivated (reason 5)
2021-09-20T16:13:12,570 | INFO | opendaylight-cluster-data-akka.actor.default-dispatcher-15 | LocalActorRef | 205 - org.opendaylight.controller.repackaged-akka - 4.0.3 | Message [akka.dispatch.sysmsg.Suspend] from Actor[akka://opendaylight-cluster-data/user/EOSBootstrap/ListenerRegistry/TypeList
ener:type=org.opendaylight.mdsal.AsyncServiceCloseEntityType-5c0e0f2c-6c58-4e3f-ac85-a8d57c4a1d66#-807810114] to Actor[akka://opendaylight-cluster-data/user/EOSBootstrap/ListenerRegistry/TypeListener:type=org.opendaylight.mdsal.AsyncServiceCloseEntityType-5c0e0f2c-6c58-4e3f-ac85-a8d57c4a1d66#-807810114] was not del
ivered. [1] dead letters encountered. If this is not an expected behavior then Actor[akka://opendaylight-cluster-data/user/EOSBootstrap/ListenerRegistry/TypeListener:type=org.opendaylight.mdsal.AsyncServiceCloseEntityType-5c0e0f2c-6c58-4e3f-ac85-a8d57c4a1d66#-807810114] may have terminated unexpectedly. This loggin
g can be turned off or adjusted with configuration settings 'akka.log-dead-letters' and 'akka.log-dead-letters-during-shutdown'.
2021-09-20T16:13:12,581 | INFO | features-3-thread-1 | OSGiDOMRpcRouter | 241 - org.opendaylight.mdsal.dom-broker - 8.0.5 | DOM RPC/Action router stopped
2021-09-20T16:13:12,583 | INFO | features-3-thread-1 | OSGiDistributedDataStore | 212 - org.opendaylight.controller.sal-distributed-datastore - 4.0.3 | Distributed Datastore type OPERATIONAL stopping
2021-09-20T16:13:12,594 | INFO | features-3-thread-1 | OSGiH2Store | 185 - org.opendaylight.aaa.idm-store-h2 - 0.14.3 | H2 IDMStore deactivated
2021-09-20T16:13:12,606 | INFO | features-3-thread-1 | OSGiPasswordServiceConfigBootstrap | 187 - org.opendaylight.aaa.password-service-impl - 0.14.3 | No longer listening for password service configuration
2021-09-20T16:13:12,611 | INFO | features-3-thread-1 | AbstractAdaptedService | 217 - org.opendaylight.mdsal.binding-dom-adapter - 8.0.5 | Binding/DOM adapter for DataBroker deactivated (reason 5)
2021-09-20T16:13:12,611 | INFO | features-3-thread-1 | OSGiDOMDataBroker | 212 - org.opendaylight.controller.sal-distributed-datastore - 4.0.3 | DOM Data Broker stopping
2021-09-20T16:13:12,612 | INFO | features-3-thread-1 | OSGiDOMDataBroker | 212 - org.opendaylight.controller.sal-distributed-datastore - 4.0.3 | DOM Data Broker stopped
2021-09-20T16:13:12,619 | INFO | features-3-thread-1 | OSGiDOMStore | 212 - org.opendaylight.controller.sal-distributed-datastore - 4.0.3 | Datastore service type OPERATIONAL deactivated
2021-09-20T16:13:12,620 | INFO | features-3-thread-1 | AbstractDataStore | 212 - org.opendaylight.controller.sal-distributed-datastore - 4.0.3 | Closing data store ClientIdentifier{frontend=member-1-frontend-datastore-operational, generation=0}
2021-09-20T16:13:12,626 | INFO | opendaylight-cluster-data-shard-dispatcher-20 | ShardManager | 212 - org.opendaylight.controller.sal-distributed-datastore - 4.0.3 | Shutting down ShardManager shard-manager-operational - waiting on 3 shards
2021-09-20T16:13:12,629 | INFO | opendaylight-cluster-data-shard-dispatcher-29 | Shard | 210 - org.opendaylight.controller.sal-clustering-commons - 4.0.3 | Stopping Shard member-1-shard-inventory-operational
2021-09-20T16:13:12,634 | INFO | opendaylight-cluster-data-shard-dispatcher-24 | Shard | 210 - org.opendaylight.controller.sal-clustering-commons - 4.0.3 | Stopping Shard member-1-shard-topology-operational
2021-09-20T16:13:12,636 | INFO | opendaylight-cluster-data-shard-dispatcher-23 | Shard | 210 - org.opendaylight.controller.sal-clustering-commons - 4.0.3 | Stopping Shard member-1-shard-default-operational
2021-09-20T16:13:12,638 | INFO | opendaylight-cluster-data-shard-dispatcher-17 | ShardManager | 212 - org.opendaylight.controller.sal-distributed-datastore - 4.0.3 | Stopping ShardManager shard-manager-operational
2021-09-20T16:13:12,647 | INFO | features-3-thread-1 | OSGiDistributedDataStore | 212 - org.opendaylight.controller.sal-distributed-datastore - 4.0.3 | Distributed Datastore type OPERATIONAL stopped
2021-09-20T16:13:12,647 | INFO | features-3-thread-1 | OSGiDistributedDataStore | 212 - org.opendaylight.controller.sal-distributed-datastore - 4.0.3 | Distributed Datastore type CONFIGURATION stopping
2021-09-20T16:13:12,654 | INFO | opendaylight-cluster-data-akka.actor.default-dispatcher-31 | RepointableActorRef | 205 - org.opendaylight.controller.repackaged-akka - 4.0.3 | Message [org.opendaylight.controller.cluster.common.actor.ExecuteInSelfMessage] to Actor[akka://opendaylight-cluster-data/user
/shardmanager-operational#-1293111216] was not delivered. [2] dead letters encountered. If this is not an expected behavior then Actor[akka://opendaylight-cluster-data/user/shardmanager-operational#-1293111216] may have terminated unexpectedly. This logging can be turned off or adjusted with configuration settings
'akka.log-dead-letters' and 'akka.log-dead-letters-during-shutdown'.
2021-09-20T16:13:12,657 | INFO | features-3-thread-1 | OSGiDOMStore | 212 - org.opendaylight.controller.sal-distributed-datastore - 4.0.3 | Datastore service type CONFIGURATION deactivated
2021-09-20T16:13:12,657 | INFO | opendaylight-cluster-data-akka.actor.default-dispatcher-30 | RepointableActorRef | 205 - org.opendaylight.controller.repackaged-akka - 4.0.3 | Message [org.opendaylight.controller.cluster.common.actor.ExecuteInSelfMessage] to Actor[akka://opendaylight-cluster-data/user
/shardmanager-operational#-1293111216] was not delivered. [3] dead letters encountered. If this is not an expected behavior then Actor[akka://opendaylight-cluster-data/user/shardmanager-operational#-1293111216] may have terminated unexpectedly. This logging can be turned off or adjusted with configuration settings
'akka.log-dead-letters' and 'akka.log-dead-letters-during-shutdown'.
2021-09-20T16:13:12,659 | INFO | opendaylight-cluster-data-akka.actor.default-dispatcher-30 | RepointableActorRef | 205 - org.opendaylight.controller.repackaged-akka - 4.0.3 | Message [org.opendaylight.controller.cluster.databroker.actors.dds.AbstractDataStoreClientBehavior$$Lambda$2370/0x00000007c166
ec40] to Actor[akka://opendaylight-cluster-data/user/$b#1333458643] was not delivered. [4] dead letters encountered. If this is not an expected behavior then Actor[akka://opendaylight-cluster-data/user/$b#1333458643] may have terminated unexpectedly. This logging can be turned off or adjusted with configuration set
tings 'akka.log-dead-letters' and 'akka.log-dead-letters-during-shutdown'.
2021-09-20T16:13:12,661 | INFO | features-3-thread-1 | AbstractDataStore | 212 - org.opendaylight.controller.sal-distributed-datastore - 4.0.3 | Closing data store ClientIdentifier{frontend=member-1-frontend-datastore-config, generation=0}
2021-09-20T16:13:12,674 | INFO | opendaylight-cluster-data-shard-dispatcher-17 | ShardManager | 212 - org.opendaylight.controller.sal-distributed-datastore - 4.0.3 | Shutting down ShardManager shard-manager-config - waiting on 3 shards
2021-09-20T16:13:12,677 | INFO | opendaylight-cluster-data-shard-dispatcher-24 | Shard | 210 - org.opendaylight.controller.sal-clustering-commons - 4.0.3 | Stopping Shard member-1-shard-topology-config
2021-09-20T16:13:12,678 | INFO | opendaylight-cluster-data-shard-dispatcher-26 | Shard | 210 - org.opendaylight.controller.sal-clustering-commons - 4.0.3 | Stopping Shard member-1-shard-inventory-config
2021-09-20T16:13:12,679 | INFO | opendaylight-cluster-data-shard-dispatcher-17 | Shard | 210 - org.opendaylight.controller.sal-clustering-commons - 4.0.3 | Stopping Shard member-1-shard-default-config
2021-09-20T16:13:12,687 | INFO | opendaylight-cluster-data-shard-dispatcher-17 | ShardManager | 212 - org.opendaylight.controller.sal-distributed-datastore - 4.0.3 | Stopping ShardManager shard-manager-config
2021-09-20T16:13:12,690 | INFO | features-3-thread-1 | OSGiDistributedDataStore | 212 - org.opendaylight.controller.sal-distributed-datastore - 4.0.3 | Distributed Datastore type CONFIGURATION stopped
2021-09-20T16:13:12,693 | INFO | opendaylight-cluster-data-akka.actor.default-dispatcher-4 | RepointableActorRef | 205 - org.opendaylight.controller.repackaged-akka - 4.0.3 | Message [org.opendaylight.controller.cluster.common.actor.ExecuteInSelfMessage] to Actor[akka://opendaylight-cluster-data/user/
shardmanager-config#795186601] was not delivered. [5] dead letters encountered. If this is not an expected behavior then Actor[akka://opendaylight-cluster-data/user/shardmanager-config#795186601] may have terminated unexpectedly. This logging can be turned off or adjusted with configuration settings 'akka.log-dead-
letters' and 'akka.log-dead-letters-during-shutdown'.
2021-09-20T16:13:12,693 | INFO | features-3-thread-1 | OSGiDOMSchemaService | 242 - org.opendaylight.mdsal.dom-schema-osgi - 8.0.5 | DOM Schema services deactivated
2021-09-20T16:13:12,695 | INFO | opendaylight-cluster-data-akka.actor.default-dispatcher-4 | RepointableActorRef | 205 - org.opendaylight.controller.repackaged-akka - 4.0.3 | Message [org.opendaylight.controller.cluster.common.actor.ExecuteInSelfMessage] to Actor[akka://opendaylight-cluster-data/user/
shardmanager-config#795186601] was not delivered. [6] dead letters encountered. If this is not an expected behavior then Actor[akka://opendaylight-cluster-data/user/shardmanager-config#795186601] may have terminated unexpectedly. This logging can be turned off or adjusted with configuration settings 'akka.log-dead-
letters' and 'akka.log-dead-letters-during-shutdown'.
2021-09-20T16:13:12,696 | INFO | opendaylight-cluster-data-akka.actor.default-dispatcher-34 | RepointableActorRef | 205 - org.opendaylight.controller.repackaged-akka - 4.0.3 | Message [org.opendaylight.controller.cluster.databroker.actors.dds.AbstractDataStoreClientBehavior$$Lambda$2370/0x00000007c166
ec40] to Actor[akka://opendaylight-cluster-data/user/$a#2016588676] was not delivered. [7] dead letters encountered. If this is not an expected behavior then Actor[akka://opendaylight-cluster-data/user/$a#2016588676] may have terminated unexpectedly. This logging can be turned off or adjusted with configuration set
tings 'akka.log-dead-letters' and 'akka.log-dead-letters-during-shutdown'.
The key issue seems to be that we are not cleanly handing of BindingRuntimeContexts – we should be just adopting gen=2 and not deactivate services. The 'reason 5' deactivations are okay, as those are explicit disposals due to the old runtime context going away. |
| Comments |
| Comment by Robert Varga [ 20/Sep/21 ] |
|
What certainly should NOT be going away is OSGiDOMRpcRouter, as it has only DOMSchemaService as its dependency. There seems to be a problem there, as well, though, as eventually we are getting this NPE: 2021-09-20T16:13:12,708 | ERROR | features-3-thread-1 | OSGiDOMSchemaService | 242 - org.opendaylight.mdsal.dom-schema-osgi - 8.0.5 | bundle org.opendaylight.mdsal.dom-schema-osgi:8.0.5 (242)[org.opendaylight.mdsal.dom.schema.osgi.impl.OSGiDOMSchemaService(29)] : The addListener method has thrown an exc eption java.lang.NullPointerException: null at org.opendaylight.mdsal.dom.schema.osgi.impl.OSGiDOMSchemaService.getGlobalContext(OSGiDOMSchemaService.java:54) ~[?:?] at org.opendaylight.mdsal.dom.schema.osgi.impl.OSGiDOMSchemaService.addListener(OSGiDOMSchemaService.java:82) ~[?:?] at jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method) ~[?:?] at jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) ~[?:?] at jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) ~[?:?] at java.lang.reflect.Method.invoke(Method.java:566) ~[?:?] at org.apache.felix.scr.impl.inject.methods.BaseMethod.invokeMethod(BaseMethod.java:244) ~[?:?] at org.apache.felix.scr.impl.inject.methods.BaseMethod.access$500(BaseMethod.java:41) ~[?:?] at org.apache.felix.scr.impl.inject.methods.BaseMethod$Resolved.invoke(BaseMethod.java:685) ~[?:?] at org.apache.felix.scr.impl.inject.methods.BaseMethod.invoke(BaseMethod.java:529) ~[?:?] at org.apache.felix.scr.impl.inject.methods.BindMethod.invoke(BindMethod.java:42) ~[?:?] at org.apache.felix.scr.impl.manager.DependencyManager.doInvokeBindMethod(DependencyManager.java:2083) ~[?:?] at org.apache.felix.scr.impl.manager.DependencyManager.bindDependency(DependencyManager.java:1900) ~[?:?] at org.apache.felix.scr.impl.manager.DependencyManager.bind(DependencyManager.java:1887) ~[?:?] at org.apache.felix.scr.impl.manager.SingleComponentManager.createImplementationObject(SingleComponentManager.java:320) ~[?:?] at org.apache.felix.scr.impl.manager.SingleComponentManager.createComponent(SingleComponentManager.java:115) ~[?:?] at org.apache.felix.scr.impl.manager.SingleComponentManager.getService(SingleComponentManager.java:1000) ~[?:?] at org.apache.felix.scr.impl.manager.SingleComponentManager.getServiceInternal(SingleComponentManager.java:973) ~[?:?] at org.apache.felix.scr.impl.manager.SingleComponentManager.getService(SingleComponentManager.java:918) ~[?:?] at org.eclipse.osgi.internal.serviceregistry.ServiceFactoryUse$1.run(ServiceFactoryUse.java:216) ~[?:?] at java.security.AccessController.doPrivileged(Native Method) ~[?:?] at org.eclipse.osgi.internal.serviceregistry.ServiceFactoryUse.factoryGetService(ServiceFactoryUse.java:213) ~[?:?] at org.eclipse.osgi.internal.serviceregistry.ServiceFactoryUse.getService(ServiceFactoryUse.java:114) ~[?:?] at org.eclipse.osgi.internal.serviceregistry.ServiceConsumer$2.getService(ServiceConsumer.java:48) ~[?:?] at org.eclipse.osgi.internal.serviceregistry.ServiceRegistrationImpl.getService(ServiceRegistrationImpl.java:547) ~[?:?] at org.eclipse.osgi.internal.serviceregistry.ServiceRegistry.getService(ServiceRegistry.java:533) ~[?:?] at org.eclipse.osgi.internal.framework.BundleContextImpl.getService(BundleContextImpl.java:656) ~[?:?] at org.apache.felix.scr.impl.manager.SingleRefPair.getServiceObject(SingleRefPair.java:88) ~[?:?] at org.apache.felix.scr.impl.inject.field.FieldHandler$ReferenceMethodImpl.getServiceObject(FieldHandler.java:525) ~[?:?] at org.apache.felix.scr.impl.manager.DependencyManager.getServiceObject(DependencyManager.java:2556) ~[?:?] at org.apache.felix.scr.impl.manager.DependencyManager$SingleStaticCustomizer.prebind(DependencyManager.java:1398) ~[?:?] at org.apache.felix.scr.impl.manager.DependencyManager.prebind(DependencyManager.java:1827) ~[?:?] at org.apache.felix.scr.impl.manager.AbstractComponentManager.collectDependencies(AbstractComponentManager.java:1057) ~[?:?] at org.apache.felix.scr.impl.manager.SingleComponentManager.getServiceInternal(SingleComponentManager.java:953) ~[?:?] at org.apache.felix.scr.impl.manager.SingleComponentManager.getService(SingleComponentManager.java:918) ~[?:?] at org.eclipse.osgi.internal.serviceregistry.ServiceFactoryUse$1.run(ServiceFactoryUse.java:216) ~[?:?] at java.security.AccessController.doPrivileged(Native Method) ~[?:?] at org.eclipse.osgi.internal.serviceregistry.ServiceFactoryUse.factoryGetService(ServiceFactoryUse.java:213) ~[?:?] at org.eclipse.osgi.internal.serviceregistry.ServiceFactoryUse.getService(ServiceFactoryUse.java:114) ~[?:?] at org.eclipse.osgi.internal.serviceregistry.ServiceConsumer$2.getService(ServiceConsumer.java:48) ~[?:?] at org.eclipse.osgi.internal.serviceregistry.ServiceRegistrationImpl.getService(ServiceRegistrationImpl.java:547) ~[?:?] at org.eclipse.osgi.internal.serviceregistry.ServiceRegistry.getService(ServiceRegistry.java:533) ~[?:?] at org.eclipse.osgi.internal.framework.BundleContextImpl.getService(BundleContextImpl.java:656) ~[?:?] at org.apache.felix.scr.impl.manager.SingleRefPair.getServiceObject(SingleRefPair.java:88) ~[?:?] at org.apache.felix.scr.impl.inject.methods.BindMethod.getServiceObject(BindMethod.java:675) ~[?:?] at org.apache.felix.scr.impl.manager.DependencyManager.getServiceObject(DependencyManager.java:2556) ~[?:?] at org.apache.felix.scr.impl.manager.DependencyManager$SingleStaticCustomizer.prebind(DependencyManager.java:1398) ~[?:?] at org.apache.felix.scr.impl.manager.DependencyManager.prebind(DependencyManager.java:1827) ~[?:?] at org.apache.felix.scr.impl.manager.AbstractComponentManager.collectDependencies(AbstractComponentManager.java:1057) ~[?:?] at org.apache.felix.scr.impl.manager.SingleComponentManager.getServiceInternal(SingleComponentManager.java:953) ~[?:?] at org.apache.felix.scr.impl.manager.SingleComponentManager.getService(SingleComponentManager.java:918) ~[?:?] at org.eclipse.osgi.internal.serviceregistry.ServiceFactoryUse$1.run(ServiceFactoryUse.java:216) ~[?:?] at java.security.AccessController.doPrivileged(Native Method) ~[?:?] at org.eclipse.osgi.internal.serviceregistry.ServiceFactoryUse.factoryGetService(ServiceFactoryUse.java:213) ~[?:?] at org.eclipse.osgi.internal.serviceregistry.ServiceFactoryUse.getService(ServiceFactoryUse.java:114) ~[?:?] at org.eclipse.osgi.internal.serviceregistry.ServiceConsumer$2.getService(ServiceConsumer.java:48) ~[?:?] at org.eclipse.osgi.internal.serviceregistry.ServiceRegistrationImpl.getService(ServiceRegistrationImpl.java:547) ~[?:?] at org.eclipse.osgi.internal.serviceregistry.ServiceRegistry.getService(ServiceRegistry.java:533) ~[?:?] at org.eclipse.osgi.internal.framework.BundleContextImpl.getService(BundleContextImpl.java:656) ~[?:?] at org.opendaylight.mdsal.binding.dom.adapter.osgi.AdaptingTracker.addingService(AdaptingTracker.java:75) ~[?:?] at org.opendaylight.mdsal.binding.dom.adapter.osgi.AdaptingTracker.addingService(AdaptingTracker.java:31) ~[?:?] at org.osgi.util.tracker.ServiceTracker$Tracked.customizerAdding(ServiceTracker.java:943) ~[osgi.core-7.0.0.jar:?] at org.osgi.util.tracker.ServiceTracker$Tracked.customizerAdding(ServiceTracker.java:871) ~[osgi.core-7.0.0.jar:?] at org.osgi.util.tracker.AbstractTracked.trackAdding(AbstractTracked.java:256) ~[osgi.core-7.0.0.jar:?] at org.osgi.util.tracker.AbstractTracked.track(AbstractTracked.java:229) ~[osgi.core-7.0.0.jar:?] at org.osgi.util.tracker.ServiceTracker$Tracked.serviceChanged(ServiceTracker.java:903) ~[osgi.core-7.0.0.jar:?] at org.eclipse.osgi.internal.serviceregistry.FilteredServiceListener.serviceChanged(FilteredServiceListener.java:120) ~[?:?] at org.eclipse.osgi.internal.framework.BundleContextImpl.dispatchEvent(BundleContextImpl.java:957) ~[?:?] at org.eclipse.osgi.framework.eventmgr.EventManager.dispatchEvent(EventManager.java:234) ~[?:?] at org.eclipse.osgi.framework.eventmgr.ListenerQueue.dispatchEventSynchronous(ListenerQueue.java:151) ~[?:?] at org.eclipse.osgi.internal.serviceregistry.ServiceRegistry.publishServiceEventPrivileged(ServiceRegistry.java:936) ~[?:?] at org.eclipse.osgi.internal.serviceregistry.ServiceRegistry.publishServiceEvent(ServiceRegistry.java:873) ~[?:?] at org.eclipse.osgi.internal.serviceregistry.ServiceRegistrationImpl.register(ServiceRegistrationImpl.java:141) ~[?:?] at org.eclipse.osgi.internal.serviceregistry.ServiceRegistry.registerService(ServiceRegistry.java:261) ~[?:?] at org.eclipse.osgi.internal.framework.BundleContextImpl.registerService(BundleContextImpl.java:496) ~[?:?] at org.apache.felix.scr.impl.manager.AbstractComponentManager$3.register(AbstractComponentManager.java:929) ~[?:?] at org.apache.felix.scr.impl.manager.AbstractComponentManager$3.register(AbstractComponentManager.java:915) ~[?:?] at org.apache.felix.scr.impl.manager.RegistrationManager.changeRegistration(RegistrationManager.java:133) ~[?:?] at org.apache.felix.scr.impl.manager.AbstractComponentManager.registerService(AbstractComponentManager.java:984) ~[?:?] at org.apache.felix.scr.impl.manager.AbstractComponentManager.activateInternal(AbstractComponentManager.java:752) ~[?:?] at org.apache.felix.scr.impl.manager.DependencyManager$SingleStaticCustomizer.addedService(DependencyManager.java:1271) ~[?:?] at org.apache.felix.scr.impl.manager.DependencyManager$SingleStaticCustomizer.addedService(DependencyManager.java:1222) ~[?:?] at org.apache.felix.scr.impl.manager.ServiceTracker$Tracked.customizerAdded(ServiceTracker.java:1200) ~[?:?] at org.apache.felix.scr.impl.manager.ServiceTracker$Tracked.customizerAdded(ServiceTracker.java:1121) ~[?:?] at org.apache.felix.scr.impl.manager.ServiceTracker$AbstractTracked.trackAdding(ServiceTracker.java:928) ~[?:?] at org.apache.felix.scr.impl.manager.ServiceTracker$AbstractTracked.track(ServiceTracker.java:864) ~[?:?] at org.apache.felix.scr.impl.manager.ServiceTracker$Tracked.serviceChanged(ServiceTracker.java:1152) ~[?:?] at org.apache.felix.scr.impl.BundleComponentActivator$ListenerInfo.serviceChanged(BundleComponentActivator.java:114) ~[?:?] at org.eclipse.osgi.internal.serviceregistry.FilteredServiceListener.serviceChanged(FilteredServiceListener.java:120) ~[?:?] at org.eclipse.osgi.internal.framework.BundleContextImpl.dispatchEvent(BundleContextImpl.java:957) ~[?:?] at org.eclipse.osgi.framework.eventmgr.EventManager.dispatchEvent(EventManager.java:234) ~[?:?] at org.eclipse.osgi.framework.eventmgr.ListenerQueue.dispatchEventSynchronous(ListenerQueue.java:151) ~[?:?] at org.eclipse.osgi.internal.serviceregistry.ServiceRegistry.publishServiceEventPrivileged(ServiceRegistry.java:936) ~[?:?] at org.eclipse.osgi.internal.serviceregistry.ServiceRegistry.publishServiceEvent(ServiceRegistry.java:873) ~[?:?] at org.eclipse.osgi.internal.serviceregistry.ServiceRegistrationImpl.register(ServiceRegistrationImpl.java:141) ~[?:?] at org.eclipse.osgi.internal.serviceregistry.ServiceRegistry.registerService(ServiceRegistry.java:261) ~[?:?] at org.eclipse.osgi.internal.framework.BundleContextImpl.registerService(BundleContextImpl.java:496) ~[?:?] at org.apache.felix.scr.impl.manager.AbstractComponentManager$3.register(AbstractComponentManager.java:929) ~[?:?] at org.apache.felix.scr.impl.manager.AbstractComponentManager$3.register(AbstractComponentManager.java:915) ~[?:?] at org.apache.felix.scr.impl.manager.RegistrationManager.changeRegistration(RegistrationManager.java:133) ~[?:?] at org.apache.felix.scr.impl.manager.AbstractComponentManager.registerService(AbstractComponentManager.java:984) ~[?:?] at org.apache.felix.scr.impl.manager.AbstractComponentManager.activateInternal(AbstractComponentManager.java:752) ~[?:?] at org.apache.felix.scr.impl.manager.DependencyManager$SingleStaticCustomizer.addedService(DependencyManager.java:1271) ~[?:?] at org.apache.felix.scr.impl.manager.DependencyManager$SingleStaticCustomizer.addedService(DependencyManager.java:1222) ~[?:?] at org.apache.felix.scr.impl.manager.ServiceTracker$Tracked.customizerAdded(ServiceTracker.java:1200) ~[?:?] at org.apache.felix.scr.impl.manager.ServiceTracker$Tracked.customizerAdded(ServiceTracker.java:1121) ~[?:?] at org.apache.felix.scr.impl.manager.ServiceTracker$AbstractTracked.trackAdding(ServiceTracker.java:928) ~[?:?] at org.apache.felix.scr.impl.manager.ServiceTracker$AbstractTracked.track(ServiceTracker.java:864) ~[?:?] at org.apache.felix.scr.impl.manager.ServiceTracker$Tracked.serviceChanged(ServiceTracker.java:1152) ~[?:?] at org.apache.felix.scr.impl.BundleComponentActivator$ListenerInfo.serviceChanged(BundleComponentActivator.java:114) ~[?:?] at org.eclipse.osgi.internal.serviceregistry.FilteredServiceListener.serviceChanged(FilteredServiceListener.java:120) ~[?:?] at org.eclipse.osgi.internal.framework.BundleContextImpl.dispatchEvent(BundleContextImpl.java:957) ~[?:?] at org.eclipse.osgi.framework.eventmgr.EventManager.dispatchEvent(EventManager.java:234) ~[?:?] at org.eclipse.osgi.framework.eventmgr.ListenerQueue.dispatchEventSynchronous(ListenerQueue.java:151) ~[?:?] at org.eclipse.osgi.internal.serviceregistry.ServiceRegistry.publishServiceEventPrivileged(ServiceRegistry.java:936) ~[?:?] at org.eclipse.osgi.internal.serviceregistry.ServiceRegistry.publishServiceEvent(ServiceRegistry.java:873) ~[?:?] at org.eclipse.osgi.internal.serviceregistry.ServiceRegistrationImpl.register(ServiceRegistrationImpl.java:141) ~[?:?] at org.eclipse.osgi.internal.serviceregistry.ServiceRegistry.registerService(ServiceRegistry.java:261) ~[?:?] at org.eclipse.osgi.internal.framework.BundleContextImpl.registerService(BundleContextImpl.java:496) ~[?:?] at org.apache.felix.scr.impl.manager.AbstractComponentManager$3.register(AbstractComponentManager.java:929) ~[?:?] at org.apache.felix.scr.impl.manager.AbstractComponentManager$3.register(AbstractComponentManager.java:915) ~[?:?] at org.apache.felix.scr.impl.manager.RegistrationManager.changeRegistration(RegistrationManager.java:133) ~[?:?] at org.apache.felix.scr.impl.manager.AbstractComponentManager.registerService(AbstractComponentManager.java:984) ~[?:?] at org.apache.felix.scr.impl.manager.AbstractComponentManager.activateInternal(AbstractComponentManager.java:752) ~[?:?] at org.apache.felix.scr.impl.manager.ExtendedServiceEvent.activateManagers(ExtendedServiceEvent.java:59) ~[?:?] at org.apache.felix.scr.impl.BundleComponentActivator$ListenerInfo.serviceChanged(BundleComponentActivator.java:119) ~[?:?] at org.eclipse.osgi.internal.serviceregistry.FilteredServiceListener.serviceChanged(FilteredServiceListener.java:120) ~[?:?] at org.eclipse.osgi.internal.framework.BundleContextImpl.dispatchEvent(BundleContextImpl.java:957) ~[?:?] at org.eclipse.osgi.framework.eventmgr.EventManager.dispatchEvent(EventManager.java:234) ~[?:?] at org.eclipse.osgi.framework.eventmgr.ListenerQueue.dispatchEventSynchronous(ListenerQueue.java:151) ~[?:?] at org.eclipse.osgi.internal.serviceregistry.ServiceRegistry.publishServiceEventPrivileged(ServiceRegistry.java:936) ~[?:?] at org.eclipse.osgi.internal.serviceregistry.ServiceRegistry.publishServiceEvent(ServiceRegistry.java:873) ~[?:?] at org.eclipse.osgi.internal.serviceregistry.ServiceRegistrationImpl.unregister(ServiceRegistrationImpl.java:241) ~[?:?] at org.apache.felix.scr.impl.manager.AbstractComponentManager$3.unregister(AbstractComponentManager.java:952) ~[?:?] at org.apache.felix.scr.impl.manager.AbstractComponentManager$3.unregister(AbstractComponentManager.java:915) ~[?:?] at org.apache.felix.scr.impl.manager.RegistrationManager.changeRegistration(RegistrationManager.java:140) ~[?:?] at org.apache.felix.scr.impl.manager.AbstractComponentManager.unregisterService(AbstractComponentManager.java:994) ~[?:?] at org.apache.felix.scr.impl.manager.AbstractComponentManager.doDeactivate(AbstractComponentManager.java:844) ~[?:?] at org.apache.felix.scr.impl.manager.AbstractComponentManager.deactivateInternal(AbstractComponentManager.java:825) ~[?:?] at org.apache.felix.scr.impl.manager.AbstractComponentManager.dispose(AbstractComponentManager.java:589) ~[?:?] at org.apache.felix.scr.impl.manager.AbstractComponentManager.dispose(AbstractComponentManager.java:575) ~[?:?] at org.apache.felix.scr.impl.manager.ComponentContextImpl$ComponentInstanceImpl.dispose(ComponentContextImpl.java:308) ~[?:?] at org.opendaylight.mdsal.dom.schema.osgi.impl.RegularYangModuleInfoRegistry.updateService(RegularYangModuleInfoRegistry.java:102) ~[?:?] at org.opendaylight.mdsal.dom.schema.osgi.impl.RegularYangModuleInfoRegistry.enableScannerAndUpdate(RegularYangModuleInfoRegistry.java:67) ~[?:?] at org.opendaylight.mdsal.dom.schema.osgi.impl.KarafYangModuleInfoRegistry.deploymentEvent(KarafYangModuleInfoRegistry.java:56) ~[?:?] at org.apache.karaf.features.internal.service.FeaturesServiceImpl.callListeners(FeaturesServiceImpl.java:322) ~[?:?] at org.apache.karaf.features.internal.service.Deployer.deploy(Deployer.java:1028) ~[?:?] at org.apache.karaf.features.internal.service.FeaturesServiceImpl.doProvision(FeaturesServiceImpl.java:1069) ~[?:?] at org.apache.karaf.features.internal.service.FeaturesServiceImpl.lambda$doProvisionInThread$13(FeaturesServiceImpl.java:1004) ~[?:?] at java.util.concurrent.FutureTask.run(FutureTask.java:264) [?:?] at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128) [?:?] at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628) [?:?] at java.lang.Thread.run(Thread.java:829) [?:?] |
| Comment by Robert Varga [ 20/Sep/21 ] |
|
This turns out to be a problem in how the snapshot reference is defined to affect OSGiDOMSchemaService |