[NETCONF-76] Netconf Flooding logging system during initialization Created: 02/Oct/15  Updated: 15/Mar/19  Resolved: 27/Oct/15

Status: Resolved
Project: netconf
Component/s: netconf
Affects Version/s: None
Fix Version/s: None

Type: Bug
Reporter: Reinaldo Penno Assignee: Tony Tkacik
Resolution: Done 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: 4397

 Description   

How to reproduce:

1 - Start SFC master
2 - Wait until everything is initialized. This might take a long time.
3 - Check logs, at some point the messages below will start to show up non-stop

at com.google.common.util.concurrent.Futures.addCallback(Futures.java:1322)[97:com.google.guava:18.0.0]
at com.google.common.util.concurrent.Futures.addCallback(Futures.java:1258)[97:com.google.guava:18.0.0]
at org.opendaylight.netconf.sal.connect.netconf.NetconfDevice$RecursiveSchemaSetup.setUpSchema(NetconfDevice.java:460)[335:org.opendaylight.netconf.sal-netconf-connector:1.3.0.SNAPSHOT]
at org.opendaylight.netconf.sal.connect.netconf.NetconfDevice$RecursiveSchemaSetup.access$1400(NetconfDevice.java:390)[335:org.opendaylight.netconf.sal-netconf-connector:1.3.0.SNAPSHOT]
at org.opendaylight.netconf.sal.connect.netconf.NetconfDevice$RecursiveSchemaSetup$1.onFailure(NetconfDevice.java:443)[335:org.opendaylight.netconf.sal-netconf-connector:1.3.0.SNAPSHOT]
at com.google.common.util.concurrent.Futures$6.run(Futures.java:1310)[97:com.google.guava:18.0.0]
at com.google.common.util.concurrent.MoreExecutors$DirectExecutor.execute(MoreExecutors.java:457)[97:com.google.guava:18.0.0]
at com.google.common.util.concurrent.ExecutionList.executeListener(ExecutionList.java:156)[97:com.google.guava:18.0.0]
at com.google.common.util.concurrent.ExecutionList.add(ExecutionList.java:101)[97:com.google.guava:18.0.0]
at com.google.common.util.concurrent.AbstractFuture.addListener(AbstractFuture.java:170)[97:com.google.guava:18.0.0]
at com.google.common.util.concurrent.ForwardingListenableFuture.addListener(ForwardingListenableFuture.java:47)[97:com.google.guava:18.0.0]
at com.google.common.util.concurrent.Futures.addCallback(Futures.java:1322)[97:com.google.guava:18.0.0]
at com.google.common.util.concurrent.Futures.addCallback(Futures.java:1258)[97:com.google.guava:18.0.0]
at org.opendaylight.netconf.sal.connect.netconf.NetconfDevice$RecursiveSchemaSetup.setUpSchema(NetconfDevice.java:460)[335:org.opendaylight.netconf.sal-netconf-connector:1.3.0.SNAPSHOT]
at org.opendaylight.netconf.sal.connect.netconf.NetconfDevice$RecursiveSchemaSetup.access$1400(NetconfDevice.java:390)[335:org.opendaylight.netconf.sal-netconf-connector:1.3.0.SNAPSHOT]
at org.opendaylight.netconf.sal.connect.netconf.NetconfDevice$RecursiveSchemaSetup$1.onFailure(NetconfDevice.java:443)[335:org.opendaylight.netconf.sal-netconf-connector:1.3.0.SNAPSHOT]
at com.google.common.util.concurrent.Futures$6.run(Futures.java:1310)[97:com.google.guava:18.0.0]
at com.google.common.util.concurrent.MoreExecutors$DirectExecutor.execute(MoreExecutors.java:457)[97:com.google.guava:18.0.0]
at com.google.common.util.concurrent.ExecutionList.executeListener(ExecutionList.java:156)[97:com.google.guava:18.0.0]
at com.google.common.util.concurrent.ExecutionList.add(ExecutionList.java:101)[97:com.google.guava:18.0.0]
at com.google.common.util.concurrent.AbstractFuture.addListener(AbstractFuture.java:170)[97:com.google.guava:18.0.0]
at com.google.common.util.concurrent.ForwardingListenableFuture.addListener(ForwardingListenableFuture.java:47)[97:com.google.guava:18.0.0]
at com.google.common.util.concurrent.Futures.addCallback(Futures.java:1322)[97:com.google.guava:18.0.0]
at com.google.common.util.concurrent.Futures.addCallback(Futures.java:1258)[97:com.google.guava:18.0.0]
at org.opendaylight.netconf.sal.connect.netconf.NetconfDevice$RecursiveSchemaSetup.setUpSchema(NetconfDevice.java:460)[335:org.opendaylight.netconf.sal-netconf-connector:1.3.0.SNAPSHOT]
at org.opendaylight.netconf.sal.connect.netconf.NetconfDevice$RecursiveSchemaSetup.access$1400(NetconfDevice.java:390)[335:org.opendaylight.netconf.sal-netconf-connector:1.3.0.SNAPSHOT]
at org.opendaylight.netconf.sal.connect.netconf.NetconfDevice$RecursiveSchemaSetup$1.onFailure(NetconfDevice.java:443)[335:org.opendaylight.netconf.sal-netconf-connector:1.3.0.SNAPSHOT]
at com.google.common.util.concurrent.Futures$6.run(Futures.java:1310)[97:com.google.guava:18.0.0]
at com.google.common.util.concurrent.MoreExecutors$DirectExecutor.execute(MoreExecutors.java:457)[97:com.google.guava:18.0.0]
at com.google.common.util.concurrent.ExecutionList.executeListener(ExecutionList.java:156)[97:com.google.guava:18.0.0]
at com.google.common.util.concurrent.ExecutionList.add(ExecutionList.java:101)[97:com.google.guava:18.0.0]
at com.google.common.util.concurrent.AbstractFuture.addListener(AbstractFuture.java:170)[97:com.google.guava:18.0.0]
at com.google.common.util.concurrent.ForwardingListenableFuture.addListener(ForwardingListenableFuture.java:47)[97:com.google.guava:18.0.0]



 Comments   
Comment by Vratko Polak [ 02/Oct/15 ]

Confirmed, this also happened in Bgpcep CSIT job.
https://jenkins.opendaylight.org/releng/view/bgpcep/job/bgpcep-csit-1node-userfeatures-only-beryllium/593

Re-assigning to upstream project, which I believe is netconf (component netconf-connector).

The test suite managed to start, but attempt to access controller-config device has lead to:
{"errors":{"error":[

{"error-type":"protocol","error-tag":"data-missing","error-message":"Mount point does not exist."}

]}}

Karaf log rotates at 20MB, and there are repeated exceptions for different Yang modules, but here is how the above-mentioned exception text starts:
2015-10-02 01:12:37,353 | ERROR | sing-executor-11 | Futures$CombinedFuture | 39 - com.google.guava - 18.0.0 | input future failed.
org.opendaylight.yangtools.yang.model.repo.api.MissingSchemaSourceException: No providers registered for sourceSourceIdentifier [name=odl-bgp-topology-provider-cfg@2013-11-15]
at org.opendaylight.yangtools.yang.model.repo.util.AbstractSchemaRepository.getSchemaSource(AbstractSchemaRepository.java:92)
at org.opendaylight.yangtools.yang.parser.repo.SharedSchemaContextFactory$1.apply(SharedSchemaContextFactory.java:59)
at org.opendaylight.yangtools.yang.parser.repo.SharedSchemaContextFactory$1.apply(SharedSchemaContextFactory.java:56)
at com.google.common.collect.Iterators$8.transform(Iterators.java:799)
at com.google.common.collect.TransformedIterator.next(TransformedIterator.java:48)
at java.util.AbstractCollection.toArray(AbstractCollection.java:141)[:1.7.0_85]
at com.google.common.collect.ImmutableList.copyOf(ImmutableList.java:258)
at com.google.common.collect.ImmutableList.copyOf(ImmutableList.java:226)
at com.google.common.util.concurrent.Futures.allAsList(Futures.java:1027)
at org.opendaylight.yangtools.yang.parser.repo.SharedSchemaContextFactory.createSchemaContext(SharedSchemaContextFactory.java:128)
at org.opendaylight.netconf.sal.connect.netconf.NetconfDevice$RecursiveSchemaSetup.setUpSchema(NetconfDevice.java:423)
at org.opendaylight.netconf.sal.connect.netconf.NetconfDevice$RecursiveSchemaSetup.access$1400(NetconfDevice.java:390)
at org.opendaylight.netconf.sal.connect.netconf.NetconfDevice$RecursiveSchemaSetup$1.onFailure(NetconfDevice.java:443)

Also, there seem to be block of slightly different exceptions of this type:
2015-10-02 01:10:37,802 | ERROR | sing-executor-11 | Futures$CombinedFuture | 39 - com.google.guava - 18.0.0 | input future failed.
org.opendaylight.yangtools.yang.model.repo.api.MissingSchemaSourceException: No providers registered for sourceSourceIdentifier [name=ted@2013-10-21]
at org.opendaylight.yangtools.yang.model.repo.util.AbstractSchemaRepository.getSchemaSource(AbstractSchemaRepository.java:92)[51:org.opendaylight.yangtools.yang-model-util:0.8.0.SNAPSHOT]
at org.opendaylight.yangtools.yang.parser.repo.SharedSchemaContextFactory$1.apply(SharedSchemaContextFactory.java:59)[53:org.opendaylight.yangtools.yang-parser-impl:0.8.0.SNAPSHOT]
at org.opendaylight.yangtools.yang.parser.repo.SharedSchemaContextFactory$1.apply(SharedSchemaContextFactory.java:56)[53:org.opendaylight.yangtools.yang-parser-impl:0.8.0.SNAPSHOT]
at com.google.common.collect.Iterators$8.transform(Iterators.java:799)[39:com.google.guava:18.0.0]
at com.google.common.collect.TransformedIterator.next(TransformedIterator.java:48)[39:com.google.guava:18.0.0]
at java.util.AbstractCollection.toArray(AbstractCollection.java:141)[:1.7.0_85]
at com.google.common.collect.ImmutableList.copyOf(ImmutableList.java:258)[39:com.google.guava:18.0.0]
at com.google.common.collect.ImmutableList.copyOf(ImmutableList.java:226)[39:com.google.guava:18.0.0]
at com.google.common.util.concurrent.Futures.allAsList(Futures.java:1027)[39:com.google.guava:18.0.0]
at org.opendaylight.yangtools.yang.parser.repo.SharedSchemaContextFactory.createSchemaContext(SharedSchemaContextFactory.java:128)[53:org.opendaylight.yangtools.yang-parser-impl:0.8.0.SNAPSHOT]
at org.opendaylight.netconf.sal.connect.netconf.NetconfDevice$RecursiveSchemaSetup.setUpSchema(NetconfDevice.java:423)[268:org.opendaylight.netconf.sal-netconf-connector:1.3.0.SNAPSHOT]
at org.opendaylight.netconf.sal.connect.netconf.NetconfDevice$RecursiveSchemaSetup.access$1400(NetconfDevice.java:390)[268:org.opendaylight.netconf.sal-netconf-connector:1.3.0.SNAPSHOT]
at org.opendaylight.netconf.sal.connect.netconf.NetconfDevice$RecursiveSchemaSetup$1.onFailure(NetconfDevice.java:443)[268:org.opendaylight.netconf.sal-netconf-connector:1.3.0.SNAPSHOT]
at com.google.common.util.concurrent.Futures$6.run(Futures.java:1310)[39:com.google.guava:18.0.0]
at com.google.common.util.concurrent.MoreExecutors$DirectExecutor.execute(MoreExecutors.java:457)[39:com.google.guava:18.0.0]
at com.google.common.util.concurrent.ExecutionList.executeListener(ExecutionList.java:156)[39:com.google.guava:18.0.0]
at com.google.common.util.concurrent.ExecutionList.add(ExecutionList.java:101)[39:com.google.guava:18.0.0]
at com.google.common.util.concurrent.AbstractFuture.addListener(AbstractFuture.java:170)[39:com.google.guava:18.0.0]
at com.google.common.util.concurrent.ForwardingListenableFuture.addListener(ForwardingListenableFuture.java:47)[39:com.google.guava:18.0.0]
at com.google.common.util.concurrent.Futures.addCallback(Futures.java:1322)[39:com.google.guava:18.0.0]
at com.google.common.util.concurrent.Futures.addCallback(Futures.java:1258)[39:com.google.guava:18.0.0]
at org.opendaylight.netconf.sal.connect.netconf.NetconfDevice$RecursiveSchemaSetup.setUpSchema(NetconfDevice.java:460)[268:org.opendaylight.netconf.sal-netconf-connector:1.3.0.SNAPSHOT]
at org.opendaylight.netconf.sal.connect.netconf.NetconfDevice$RecursiveSchemaSetup.access$1400(NetconfDevice.java:390)[268:org.opendaylight.netconf.sal-netconf-connector:1.3.0.SNAPSHOT]
at org.opendaylight.netconf.sal.connect.netconf.NetconfDevice$RecursiveSchemaSetup$1.onFailure(NetconfDevice.java:443)[268:org.opendaylight.netconf.sal-netconf-connector:1.3.0.SNAPSHOT]

Comment by Tony Tkacik [ 07/Oct/15 ]

https://git.opendaylight.org/gerrit/#/c/27975/

Comment by Vratko Polak [ 27/Oct/15 ]

> https://git.opendaylight.org/gerrit/#/c/27975/

Merged. I do not see the original symptoms anymore.

Generated at Wed Feb 07 20:14:07 UTC 2024 using Jira 8.20.10#820010-sha1:ace47f9899e9ee25d7157d59aa17ab06aee30d3d.