[OPNFLWPLUG-148] OSGi errors after connecting mininet to the controller Created: 02/May/14  Updated: 27/Sep/21  Due: 24/Oct/14  Resolved: 08/Sep/14

Status: Resolved
Project: OpenFlowPlugin
Component/s: General
Affects Version/s: None
Fix Version/s: None

Type: Bug
Reporter: Madhusudhan Ananderi Assignee: Luis Gomez
Resolution: Done Votes: 0
Labels: None
Remaining Estimate: Not Specified
Time Spent: Not Specified
Original Estimate: Not Specified
Environment:

Operating System: Windows
Platform: PC


External issue ID: 947

 Description   

1. Download the latest controller base edition:

https://jenkins.opendaylight.org/integration/job/integration-project-centralized-integration/lastSuccessfulBuild/artifact/distributions/base/target/distributions-base-0.1.2-SNAPSHOT-osgipackage.zip

2. Run the controller - ./run.sh -of13 -Xms1G

3. Start the mininet (of10): sudo mn --topo tree,2 --controller 'remote,ip=127.0.0.1,port=6633' --switch ovsk,protocols=OpenFlow10

2014-05-02 13:58:56.209 PDT [pool-13-thread-1] WARN o.o.c.m.s.manager.StatisticsProvider - Attempted to start already-existing handler for Uri [_value=openflow:1], very strange
2014-05-02 13:58:58.031 PDT [pool-14-thread-1] ERROR o.o.c.m.s.b.i.ForwardedBackwardsCompatibleDataBroker - Error during invoking transaction listener org.opendaylight.controller.md.statistics.manager.StatisticsRequestScheduler@16bbc7d5
java.lang.IllegalStateException: Address not found for route [RouteIdentifierImpl{context=null, type=(urn:opendaylight:group:statistics?revision=2013-11-11)get-all-group-statistics, route=/(urn:opendaylight:inventory?revision=2013-08-19)nodes/(urn:opendaylight:inventory?revision=2013-08-19)node[

{(urn:opendaylight:inventory?revision=2013-08-19)id=controller-config}

]}]
at com.google.common.base.Preconditions.checkState(Preconditions.java:176) ~[bundlefile:na]
at org.opendaylight.controller.sal.connector.remoterpc.ClientImpl.lookupRemoteAddressForRpc(ClientImpl.java:212) ~[na:na]
at org.opendaylight.controller.sal.connector.remoterpc.ClientImpl.invokeRpc(ClientImpl.java:127) ~[na:na]
at org.opendaylight.controller.sal.connector.remoterpc.RemoteRpcProvider.invokeRpc(RemoteRpcProvider.java:107) ~[na:na]
at org.opendaylight.controller.sal.dom.broker.impl.SchemaAwareRpcBroker.invokeRpc(SchemaAwareRpcBroker.java:241) ~[na:na]
at org.opendaylight.controller.sal.dom.broker.impl.SchemaAwareRpcBroker$RoutedRpcSelector.invokeRpc(SchemaAwareRpcBroker.java:338) ~[na:na]
at org.opendaylight.controller.sal.dom.broker.impl.SchemaAwareRpcBroker.invokeRpc(SchemaAwareRpcBroker.java:168) ~[na:na]
at org.opendaylight.controller.sal.dom.broker.osgi.RpcProvisionRegistryProxy.invokeRpc(RpcProvisionRegistryProxy.java:63) ~[na:na]
at org.opendaylight.controller.sal.binding.impl.connect.dom.BindingIndependentConnector$DefaultInvocationStrategy.forwardToDomBroker(BindingIndependentConnector.java:827) ~[bundlefile:na]
at org.opendaylight.controller.sal.binding.impl.connect.dom.BindingIndependentConnector$DefaultInvocationStrategy.forwardToDomBroker(BindingIndependentConnector.java:785) ~[bundlefile:na]
at org.opendaylight.controller.sal.binding.impl.connect.dom.BindingIndependentConnector$DomToBindingRpcForwarder.invoke(BindingIndependentConnector.java:670) ~[bundlefile:na]
at com.sun.proxy.$Proxy106.getAllGroupStatistics(Unknown Source) ~[na:na]
at org.opendaylight.yang.gen.v1.urn.opendaylight.group.statistics.rev131111.OpendaylightGroupStatisticsService$$Broker$Router.getAllGroupStatistics(OpendaylightGroupStatisticsService$$Broker$Router.java) ~[na:na]
at org.opendaylight.yang.gen.v1.urn.opendaylight.group.statistics.rev131111.OpendaylightGroupStatisticsService$$Broker$DirectProxy.getAllGroupStatistics(OpendaylightGroupStatisticsService$$Broker$DirectProxy.java) ~[na:na]
at org.opendaylight.controller.md.statistics.manager.GroupStatsTracker.request(GroupStatsTracker.java:80) ~[na:na]
at org.opendaylight.controller.md.statistics.manager.StatisticsRequestScheduler.onStatusUpdated(StatisticsRequestScheduler.java:110) ~[na:na]
at org.opendaylight.controller.md.sal.binding.impl.ForwardedBackwardsCompatibleDataBroker$ForwardedBackwardsCompatibleTransacion.changeStatus(ForwardedBackwardsCompatibleDataBroker.java:329) [bundlefile:na]
at org.opendaylight.controller.md.sal.binding.impl.ForwardedBackwardsCompatibleDataBroker$ForwardedBackwardsCompatibleTransacion.access$200(ForwardedBackwardsCompatibleDataBroker.java:195) [bundlefile:na]
at org.opendaylight.controller.md.sal.binding.impl.ForwardedBackwardsCompatibleDataBroker$ForwardedBackwardsCompatibleTransacion$1.onSuccess(ForwardedBackwardsCompatibleDataBroker.java:354) [bundlefile:na]
at org.opendaylight.controller.md.sal.binding.impl.ForwardedBackwardsCompatibleDataBroker$ForwardedBackwardsCompatibleTransacion$1.onSuccess(ForwardedBackwardsCompatibleDataBroker.java:351) [bundlefile:na]
at com.google.common.util.concurrent.Futures$4.run(Futures.java:1149) [bundlefile:na]
at com.google.common.util.concurrent.MoreExecutors$SameThreadExecutorService.execute(MoreExecutors.java:293) [bundlefile:na]
at com.google.common.util.concurrent.ExecutionList$RunnableExecutorPair.execute(ExecutionList.java:150) [bundlefile:na]
at com.google.common.util.concurrent.ExecutionList.execute(ExecutionList.java:135) [bundlefile:na]
at com.google.common.util.concurrent.AbstractFuture.set(AbstractFuture.java:185) [bundlefile:na]
at com.google.common.util.concurrent.Futures$ChainingListenableFuture$1.run(Futures.java:873) [bundlefile:na]
at com.google.common.util.concurrent.MoreExecutors$SameThreadExecutorService.execute(MoreExecutors.java:293) [bundlefile:na]
at com.google.common.util.concurrent.Futures$ImmediateFuture.addListener(Futures.java:99) [bundlefile:na]
at com.google.common.util.concurrent.Futures$ChainingListenableFuture.run(Futures.java:866) [bundlefile:na]
at com.google.common.util.concurrent.MoreExecutors$SameThreadExecutorService.execute(MoreExecutors.java:293) [bundlefile:na]
at com.google.common.util.concurrent.ExecutionList$RunnableExecutorPair.execute(ExecutionList.java:150) [bundlefile:na]
at com.google.common.util.concurrent.ExecutionList.execute(ExecutionList.java:135) [bundlefile:na]
at com.google.common.util.concurrent.AbstractFuture.set(AbstractFuture.java:185) [bundlefile:na]
at com.google.common.util.concurrent.Futures$ChainingListenableFuture$1.run(Futures.java:873) [bundlefile:na]
at com.google.common.util.concurrent.MoreExecutors$SameThreadExecutorService.execute(MoreExecutors.java:293) [bundlefile:na]
at com.google.common.util.concurrent.ExecutionList$RunnableExecutorPair.execute(ExecutionList.java:150) [bundlefile:na]
at com.google.common.util.concurrent.ExecutionList.execute(ExecutionList.java:135) [bundlefile:na]
at com.google.common.util.concurrent.ListenableFutureTask.done(ListenableFutureTask.java:91) [bundlefile:na]
at java.util.concurrent.FutureTask.finishCompletion(FutureTask.java:380) [na:1.7.0_51]
at java.util.concurrent.FutureTask.set(FutureTask.java:229) [na:1.7.0_51]
at java.util.concurrent.FutureTask.run(FutureTask.java:270) [na:1.7.0_51]
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145) [na:1.7.0_51]
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615) [na:1.7.0_51]
at java.lang.Thread.run(Thread.java:744) [na:1.7.0_51]

--------------------------------------------------------------------------------

1. Download the latest controller base edition:

https://jenkins.opendaylight.org/integration/job/integration-project-centralized-integration/lastSuccessfulBuild/artifact/distributions/base/target/distributions-base-0.1.2-SNAPSHOT-osgipackage.zip

2. Run the controller - ./run.sh -of13 -Xms1G

3. Start the mininet (of10): sudo mn --topo tree,2 --controller 'remote,ip=127.0.0.1,port=6633'

[Note: This can be reproduced one time after you start the fresh controller and mininet(of10). If you restart the mininet(of10) again without shutting the controller, the error cannot be seen]

2014-05-02 12:06:01.452 PDT [nioEventLoopGroup-12-4] INFO o.o.o.p.i.c.PublishingChannelInitializer - Incoming connection accepted - building pipeline
2014-05-02 12:06:01.483 PDT [nioEventLoopGroup-12-4] INFO o.o.o.p.i.c.ConnectionAdapterImpl - Hello received / branch
2014-05-02 12:06:01.722 PDT [pool-29-thread-1] ERROR o.o.o.o.m.core.HandshakeManagerImpl - issuing disconnect during handshake, reason: org.opendaylight.yang.gen.v1.urn.opendaylight.openflow.protocol.rev130731.MultipartRequestInputBuilder
2014-05-02 12:06:01.723 PDT [pool-30-thread-1] ERROR o.o.o.o.m.core.HandshakeManagerImpl - issuing disconnect during handshake, reason: org/opendaylight/yang/gen/v1/urn/opendaylight/openflow/protocol/rev130731/MultipartRequestInputBuilder
2014-05-02 12:06:01.725 PDT [pool-29-thread-1] ERROR o.o.o.o.m.c.ErrorHandlerQueueImpl - null
java.lang.InterruptedException: null
at java.util.concurrent.locks.AbstractQueuedSynchronizer.acquireInterruptibly(AbstractQueuedSynchronizer.java:1219) ~[na:1.7.0_51]
at java.util.concurrent.locks.ReentrantLock.lockInterruptibly(ReentrantLock.java:340) ~[na:1.7.0_51]
at java.util.concurrent.LinkedBlockingQueue.put(LinkedBlockingQueue.java:338) ~[na:1.7.0_51]
at org.opendaylight.openflowplugin.openflow.md.core.ErrorHandlerQueueImpl.handleException(ErrorHandlerQueueImpl.java:64) ~[bundlefile:na]
at org.opendaylight.openflowplugin.openflow.md.core.HandshakeManagerImpl.shake(HandshakeManagerImpl.java:106) [bundlefile:na]
at org.opendaylight.openflowplugin.openflow.md.core.HandshakeStepWrapper.run(HandshakeStepWrapper.java:47) [bundlefile:na]
at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:471) [na:1.7.0_51]
at java.util.concurrent.FutureTask.run(FutureTask.java:262) [na:1.7.0_51]
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145) [na:1.7.0_51]
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615) [na:1.7.0_51]
at java.lang.Thread.run(Thread.java:744) [na:1.7.0_51]
2014-05-02 12:06:01.726 PDT [pool-30-thread-1] ERROR o.o.o.o.m.c.ErrorHandlerQueueImpl - null
java.lang.InterruptedException: null
at java.util.concurrent.locks.AbstractQueuedSynchronizer.acquireInterruptibly(AbstractQueuedSynchronizer.java:1219) ~[na:1.7.0_51]
at java.util.concurrent.locks.ReentrantLock.lockInterruptibly(ReentrantLock.java:340) ~[na:1.7.0_51]
at java.util.concurrent.LinkedBlockingQueue.put(LinkedBlockingQueue.java:338) ~[na:1.7.0_51]
at org.opendaylight.openflowplugin.openflow.md.core.ErrorHandlerQueueImpl.handleException(ErrorHandlerQueueImpl.java:64) ~[bundlefile:na]
at org.opendaylight.openflowplugin.openflow.md.core.HandshakeManagerImpl.shake(HandshakeManagerImpl.java:106) [bundlefile:na]
at org.opendaylight.openflowplugin.openflow.md.core.HandshakeStepWrapper.run(HandshakeStepWrapper.java:47) [bundlefile:na]
at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:471) [na:1.7.0_51]
at java.util.concurrent.FutureTask.run(FutureTask.java:262) [na:1.7.0_51]
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145) [na:1.7.0_51]
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615) [na:1.7.0_51]
at java.lang.Thread.run(Thread.java:744) [na:1.7.0_51]
2014-05-02 12:06:01.887 PDT [pool-13-thread-1] WARN o.o.c.m.s.m.StatisticsProvider - Attempted to start already-existing handler for Uri [_value=openflow:3], very strange
2014-05-02 12:06:01.887 PDT [pool-13-thread-1] WARN o.o.c.m.s.m.StatisticsProvider - Attempted to start already-existing handler for Uri [_value=openflow:3], very strange
2014-05-02 12:06:01.891 PDT [Thread-21] ERROR o.o.o.o.md.queue.TicketFinisher - null
java.lang.NullPointerException: null
at org.opendaylight.openflowplugin.openflow.md.lldp.LLDPSpeaker.addNodeConnector(LLDPSpeaker.java:85) ~[na:na]
at org.opendaylight.openflowplugin.openflow.md.lldp.LLDPSpeakerPopListener.onPop(LLDPSpeakerPopListener.java:37) ~[na:na]
at org.opendaylight.openflowplugin.openflow.md.queue.TicketFinisher.run(TicketFinisher.java:65) ~[na:na]
at java.lang.Thread.run(Thread.java:744) [na:1.7.0_51]
2014-05-02 12:06:01.973 PDT [pool-13-thread-1] WARN o.o.c.m.s.m.StatisticsProvider - Attempted to start already-existing handler for Uri [_value=openflow:1], very strange
2014-05-02 12:06:01.974 PDT [pool-13-thread-1] WARN o.o.c.m.s.m.StatisticsProvider - Attempted to start already-existing handler for Uri [_value=openflow:1], very strange
2014-05-02 12:06:01.982 PDT [pool-13-thread-1] WARN o.o.c.m.s.m.StatisticsProvider - Attempted to start already-existing handler for Uri [_value=openflow:1], very strange
2014-05-02 12:06:01.997 PDT [pool-13-thread-2] WARN o.o.c.m.s.m.StatisticsProvider - Attempted to start already-existing handler for Uri [_value=openflow:1], very strange
2014-05-02 12:06:02.005 PDT [pool-13-thread-1] WARN o.o.c.m.s.m.StatisticsProvider - Attempted to start already-existing handler for Uri [_value=openflow:2], very strange
2014-05-02 12:06:02.007 PDT [pool-13-thread-1] WARN o.o.c.m.s.m.StatisticsProvider - Attempted to start already-existing handler for Uri [_value=openflow:2], very strange
2014-05-02 12:06:02.011 PDT [pool-13-thread-1] WARN o.o.c.m.s.m.StatisticsProvider - Attempted to start already-existing handler for Uri [_value=openflow:3], very strange
2014-05-02 12:06:02.024 PDT [pool-13-thread-1] WARN o.o.c.m.s.m.StatisticsProvider - Attempted to start already-existing handler for Uri [_value=openflow:3], very strange
2014-05-02 12:06:02.019 PDT [nioEventLoopGroup-12-6] INFO o.o.o.p.i.c.PublishingChannelInitializer - Incoming connection from (remote address): /127.0.0.1:42460 --> :6633
2014-05-02 12:06:02.019 PDT [nioEventLoopGroup-12-5] INFO o.o.o.p.i.c.PublishingChannelInitializer - Incoming connection from (remote address): /127.0.0.1:42459 --> :6633
2014-05-02 12:06:02.027 PDT [nioEventLoopGroup-12-6] INFO o.o.o.p.i.c.PublishingChannelInitializer - Incoming connection accepted - building pipeline
2014-05-02 12:06:02.027 PDT [nioEventLoopGroup-12-5] INFO o.o.o.p.i.c.PublishingChannelInitializer - Incoming connection accepted - building pipeline
2014-05-02 12:06:02.038 PDT [nioEventLoopGroup-12-6] INFO o.o.o.p.i.c.ConnectionAdapterImpl - Hello received / branch
2014-05-02 12:06:02.041 PDT [nioEventLoopGroup-12-5] INFO o.o.o.p.i.c.ConnectionAdapterImpl - Hello received / branch
2014-05-02 12:06:02.065 PDT [pool-13-thread-1] WARN o.o.c.m.s.m.StatisticsProvider - Attempted to start already-existing handler for Uri [_value=openflow:3], very strange
2014-05-02 12:06:02.076 PDT [pool-31-thread-1] WARN o.o.o.o.m.c.session.OFSessionUtil - duplicate datapathId occured while registering new switch session: 3
2014-05-02 12:06:02.149 PDT [pool-13-thread-2] WARN o.o.c.m.s.m.StatisticsProvider - Attempted to start already-existing handler for Uri [_value=openflow:2], very strange
2014-05-02 12:06:02.172 PDT [pool-13-thread-1] WARN o.o.c.m.s.m.StatisticsProvider - Attempted to start already-existing handler for Uri [_value=openflow:3], very strange
2014-05-02 12:06:02.172 PDT [pool-13-thread-1] WARN o.o.c.m.s.m.StatisticsProvider - Attempted to start already-existing handler for Uri [_value=openflow:3], very strange
2014-05-02 12:06:02.178 PDT [pool-13-thread-2] WARN o.o.c.m.s.m.StatisticsProvider - Attempted to start already-existing handler for Uri [_value=openflow:3], very strange
2014-05-02 12:06:02.188 PDT [pool-13-thread-2] WARN o.o.c.m.s.m.StatisticsProvider - Attempted to start already-existing handler for Uri [_value=openflow:3], very strange
2014-05-02 12:06:02.191 PDT [pool-13-thread-1] WARN o.o.c.m.s.m.StatisticsProvider - Attempted to start already-existing handler for Uri [_value=openflow:3], very strange
2014-05-02 12:06:02.204 PDT [pool-13-thread-1] WARN o.o.c.m.s.m.StatisticsProvider - Attempted to start already-existing handler for Uri [_value=openflow:2], very strange
2014-05-02 12:06:02.209 PDT [pool-13-thread-2] WARN o.o.c.m.s.m.StatisticsProvider - Attempted to start already-existing handler for Uri [_value=openflow:2], very strange
2014-05-02 12:06:02.292 PDT [md-sal-binding-notification-1] WARN o.o.y.s.b.g.i.LazyGeneratedCodecRegistry - Could not find generated type for augmentation AugmentationSchemaImpl[targetPath=SchemaPath [path=[(urn:TBD:params:xml:ns:yang:network-topology?revision=2013-10-21)network-topology, (urn:TBD:params:xml:ns:yang:network-topology?revision=2013-10-21)topology, (urn:TBD:params:xml:ns:yang:network-topology?revision=2013-10-21)node], absolute=true], when=null] with childs [].
2014-05-02 12:06:02.295 PDT [md-sal-binding-notification-1] WARN o.o.y.s.b.g.i.LazyGeneratedCodecRegistry - Could not find generated type for augmentation AugmentationSchemaImpl[targetPath=SchemaPath [path=[(urn:TBD:params:xml:ns:yang:network-topology?revision=2013-10-21)network-topology, (urn:TBD:params:xml:ns:yang:network-topology?revision=2013-10-21)topology], absolute=true], when=null] with childs [].



 Comments   
Comment by Luis Gomez [ 03/May/14 ]

The second ERROR I always see the first time you start mininet after controller startup. It is happening for both of10 and of13 simulation:

sudo mn --topo tree,2 --controller 'remote,ip=127.0.0.1,port=6633'

sudo mn --topo tree,2 --controller 'remote,ip=127.0.0.1,port=6633' --switch ovsk,protocols=OpenFlow13

Comment by Michal Rehak [ 05/May/14 ]

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

Comment by Luis Gomez [ 07/May/14 ]

Today, it is not that is not fixed, it gives even more errors:

osgi> 2014-05-06 04:22:24.171 PDT [nioEventLoopGroup-11-1] INFO o.o.o.p.i.c.PublishingChannelInitializer - Incoming connection from (remote address): /127.0.0.1:38005 --> :6633
2014-05-06 04:22:24.171 PDT [nioEventLoopGroup-11-1] INFO o.o.o.p.i.c.PublishingChannelInitializer - Incoming connection accepted - building pipeline
2014-05-06 04:22:24.185 PDT [nioEventLoopGroup-11-1] WARN o.o.o.o.m.c.s.SessionManagerOFImpl - context for invalidation not found
2014-05-06 04:22:24.533 PDT [nioEventLoopGroup-11-2] INFO o.o.o.p.i.c.PublishingChannelInitializer - Incoming connection from (remote address): /127.0.0.1:38006 --> :6633
2014-05-06 04:22:24.533 PDT [nioEventLoopGroup-11-2] INFO o.o.o.p.i.c.PublishingChannelInitializer - Incoming connection accepted - building pipeline
2014-05-06 04:22:24.538 PDT [nioEventLoopGroup-11-2] INFO o.o.o.p.i.c.ConnectionAdapterImpl - Hello received / branch
2014-05-06 04:22:24.595 PDT [nioEventLoopGroup-11-3] INFO o.o.o.p.i.c.PublishingChannelInitializer - Incoming connection from (remote address): /127.0.0.1:38007 --> :6633
2014-05-06 04:22:24.595 PDT [nioEventLoopGroup-11-3] INFO o.o.o.p.i.c.PublishingChannelInitializer - Incoming connection accepted - building pipeline
2014-05-06 04:22:24.597 PDT [nioEventLoopGroup-11-3] INFO o.o.o.p.i.c.ConnectionAdapterImpl - Hello received / branch
2014-05-06 04:22:24.701 PDT [nioEventLoopGroup-11-4] INFO o.o.o.p.i.c.PublishingChannelInitializer - Incoming connection from (remote address): /127.0.0.1:38008 --> :6633
2014-05-06 04:22:24.701 PDT [nioEventLoopGroup-11-4] INFO o.o.o.p.i.c.PublishingChannelInitializer - Incoming connection accepted - building pipeline
2014-05-06 04:22:24.705 PDT [nioEventLoopGroup-11-4] INFO o.o.o.p.i.c.ConnectionAdapterImpl - Hello received / branch
2014-05-06 04:22:24.813 PDT [pool-26-thread-1] ERROR o.o.o.o.md.core.HandshakeManagerImpl - issuing disconnect during handshake, reason: org.opendaylight.yang.gen.v1.urn.opendaylight.openflow.protocol.rev130731.multipart.request.multipart.request.body.MultipartRequestGroupFeaturesCaseBuilder$MultipartRequestGroupFeaturesCaseImpl
2014-05-06 04:22:24.813 PDT [pool-28-thread-1] ERROR o.o.o.o.md.core.HandshakeManagerImpl - issuing disconnect during handshake, reason: org/opendaylight/yang/gen/v1/urn/opendaylight/openflow/protocol/rev130731/multipart/request/multipart/request/body/MultipartRequestGroupFeaturesCaseBuilder$MultipartRequestGroupFeaturesCaseImpl
2014-05-06 04:22:24.839 PDT [pool-26-thread-1] ERROR o.o.o.o.m.core.ErrorHandlerQueueImpl - null
java.lang.InterruptedException: null
at java.util.concurrent.locks.AbstractQueuedSynchronizer.acquireInterruptibly(AbstractQueuedSynchronizer.java:1219) ~[na:1.7.0_51]
at java.util.concurrent.locks.ReentrantLock.lockInterruptibly(ReentrantLock.java:340) ~[na:1.7.0_51]
at java.util.concurrent.LinkedBlockingQueue.put(LinkedBlockingQueue.java:338) ~[na:1.7.0_51]
at org.opendaylight.openflowplugin.openflow.md.core.ErrorHandlerQueueImpl.handleException(ErrorHandlerQueueImpl.java:64) ~[bundlefile:na]
at org.opendaylight.openflowplugin.openflow.md.core.HandshakeManagerImpl.shake(HandshakeManagerImpl.java:106) [bundlefile:na]
at org.opendaylight.openflowplugin.openflow.md.core.HandshakeStepWrapper.run(HandshakeStepWrapper.java:47) [bundlefile:na]
at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:471) [na:1.7.0_51]
at java.util.concurrent.FutureTask.run(FutureTask.java:262) [na:1.7.0_51]
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145) [na:1.7.0_51]
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615) [na:1.7.0_51]
at java.lang.Thread.run(Thread.java:744) [na:1.7.0_51]
2014-05-06 04:22:24.839 PDT [pool-28-thread-1] ERROR o.o.o.o.m.core.ErrorHandlerQueueImpl - null
java.lang.InterruptedException: null
at java.util.concurrent.locks.AbstractQueuedSynchronizer.acquireInterruptibly(AbstractQueuedSynchronizer.java:1219) ~[na:1.7.0_51]
at java.util.concurrent.locks.ReentrantLock.lockInterruptibly(ReentrantLock.java:340) ~[na:1.7.0_51]
at java.util.concurrent.LinkedBlockingQueue.put(LinkedBlockingQueue.java:338) ~[na:1.7.0_51]
at org.opendaylight.openflowplugin.openflow.md.core.ErrorHandlerQueueImpl.handleException(ErrorHandlerQueueImpl.java:64) ~[bundlefile:na]
at org.opendaylight.openflowplugin.openflow.md.core.HandshakeManagerImpl.shake(HandshakeManagerImpl.java:106) [bundlefile:na]
at org.opendaylight.openflowplugin.openflow.md.core.HandshakeStepWrapper.run(HandshakeStepWrapper.java:47) [bundlefile:na]
at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:471) [na:1.7.0_51]
at java.util.concurrent.FutureTask.run(FutureTask.java:262) [na:1.7.0_51]
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145) [na:1.7.0_51]
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615) [na:1.7.0_51]
at java.lang.Thread.run(Thread.java:744) [na:1.7.0_51]
2014-05-06 04:22:24.874 PDT [md-sal-binding-notification-16] INFO o.o.c.m.s.manager.StatisticsProvider - Attempted to get non-existing handler for Uri [_value=openflow:2]
2014-05-06 04:22:25.634 PDT [nioEventLoopGroup-11-5] INFO o.o.o.p.i.c.PublishingChannelInitializer - Incoming connection from (remote address): /127.0.0.1:38009 --> :6633
2014-05-06 04:22:25.635 PDT [nioEventLoopGroup-11-6] INFO o.o.o.p.i.c.PublishingChannelInitializer - Incoming connection from (remote address): /127.0.0.1:38010 --> :6633
2014-05-06 04:22:25.635 PDT [nioEventLoopGroup-11-5] INFO o.o.o.p.i.c.PublishingChannelInitializer - Incoming connection accepted - building pipeline
2014-05-06 04:22:25.635 PDT [nioEventLoopGroup-11-6] INFO o.o.o.p.i.c.PublishingChannelInitializer - Incoming connection accepted - building pipeline
2014-05-06 04:22:25.637 PDT [nioEventLoopGroup-11-6] INFO o.o.o.p.i.c.ConnectionAdapterImpl - Hello received / branch
2014-05-06 04:22:25.641 PDT [nioEventLoopGroup-11-5] INFO o.o.o.p.i.c.ConnectionAdapterImpl - Hello received / branch
2014-05-06 04:22:25.648 PDT [pool-29-thread-1] WARN o.o.o.o.m.core.session.OFSessionUtil - duplicate datapathId occured while registering new switch session: 3
2014-05-06 04:22:25.648 PDT [pool-30-thread-1] WARN o.o.o.o.m.core.session.OFSessionUtil - duplicate datapathId occured while registering new switch session: 1
2014-05-06 04:22:25.658 PDT [md-sal-binding-notification-6] WARN o.o.y.s.b.g.i.LazyGeneratedCodecRegistry - Could not find generated type for augmentation AugmentationSchemaImpl[targetPath=SchemaPath [path=[(urn:TBD:params:xml:ns:yang:network-topology?revision=2013-10-21)network-topology, (urn:TBD:params:xml:ns:yang:network-topology?revision=2013-10-21)topology, (urn:TBD:params:xml:ns:yang:network-topology?revision=2013-10-21)node], absolute=true], when=null] with childs [].
2014-05-06 04:22:25.660 PDT [md-sal-binding-notification-6] WARN o.o.y.s.b.g.i.LazyGeneratedCodecRegistry - Could not find generated type for augmentation AugmentationSchemaImpl[targetPath=SchemaPath [path=[(urn:TBD:params:xml:ns:yang:network-topology?revision=2013-10-21)network-topology, (urn:TBD:params:xml:ns:yang:network-topology?revision=2013-10-21)topology], absolute=true], when=null] with childs [].
2014-05-06 04:22:25.669 PDT [pool-15-thread-1] INFO o.o.c.m.s.d.b.i.DOMDataBrokerImpl$CommitCoordination - Tx: DOM-31 Did not pass canCommit phase.
2014-05-06 04:22:25.670 PDT [pool-15-thread-1] ERROR o.o.c.m.s.b.i.ForwardedBackwardsCompatibleDataBroker - Tx: DOM-31 Rollback - Datastore commit failed.
2014-05-06 04:22:25.671 PDT [pool-15-thread-1] ERROR o.o.c.m.i.manager.NodeChangeCommiter - Action node update [KeyedInstanceIdentifier

{targetType=interface org.opendaylight.yang.gen.v1.urn.opendaylight.inventory.rev130819.nodes.Node, path=[org.opendaylight.yang.gen.v1.urn.opendaylight.inventory.rev130819.Nodes, org.opendaylight.yang.gen.v1.urn.opendaylight.inventory.rev130819.nodes.Node[key=NodeKey [_id=Uri [_value=openflow:1]]]]}

] failed for Tx:DOM-31
2014-05-06 04:22:25.677 PDT [pool-15-thread-1] INFO o.o.c.m.s.d.b.i.DOMDataBrokerImpl$CommitCoordination - Tx: DOM-35 Did not pass canCommit phase.
2014-05-06 04:22:25.677 PDT [pool-15-thread-1] ERROR o.o.c.m.s.b.i.ForwardedBackwardsCompatibleDataBroker - Tx: DOM-35 Rollback - Datastore commit failed.
2014-05-06 04:22:25.677 PDT [pool-15-thread-1] ERROR o.o.c.m.i.manager.NodeChangeCommiter - Action node update [KeyedInstanceIdentifier

{targetType=interface org.opendaylight.yang.gen.v1.urn.opendaylight.inventory.rev130819.nodes.Node, path=[org.opendaylight.yang.gen.v1.urn.opendaylight.inventory.rev130819.Nodes, org.opendaylight.yang.gen.v1.urn.opendaylight.inventory.rev130819.nodes.Node[key=NodeKey [_id=Uri [_value=openflow:3]]]]}

] failed for Tx:DOM-35
2014-05-06 04:22:25.742 PDT [pool-15-thread-1] INFO o.o.c.m.s.d.b.i.DOMDataBrokerImpl$CommitCoordination - Tx: DOM-60 Did not pass canCommit phase.
2014-05-06 04:22:25.742 PDT [pool-15-thread-1] ERROR o.o.c.m.s.b.i.ForwardedBackwardsCompatibleDataBroker - Tx: DOM-60 Rollback - Datastore commit failed.
2014-05-06 04:22:25.742 PDT [pool-15-thread-1] ERROR o.o.m.c.t.m.FlowCapableTopologyExporter - Topology export failed for Tx:DOM-60
2014-05-06 04:22:25.742 PDT [pool-15-thread-1] INFO o.o.c.m.s.d.b.i.DOMDataBrokerImpl$CommitCoordination - Tx: DOM-61 Did not pass canCommit phase.
2014-05-06 04:22:25.742 PDT [pool-15-thread-1] ERROR o.o.c.m.s.b.i.ForwardedBackwardsCompatibleDataBroker - Tx: DOM-61 Rollback - Datastore commit failed.
2014-05-06 04:22:25.743 PDT [pool-15-thread-1] ERROR o.o.m.c.t.m.FlowCapableTopologyExporter - Topology export failed for Tx:DOM-61
2014-05-06 04:22:25.743 PDT [pool-15-thread-1] INFO o.o.c.m.s.d.b.i.DOMDataBrokerImpl$CommitCoordination - Tx: DOM-62 Did not pass canCommit phase.
2014-05-06 04:22:25.743 PDT [pool-15-thread-1] ERROR o.o.c.m.s.b.i.ForwardedBackwardsCompatibleDataBroker - Tx: DOM-62 Rollback - Datastore commit failed.
2014-05-06 04:22:25.743 PDT [pool-15-thread-1] ERROR o.o.m.c.t.m.FlowCapableTopologyExporter - Topology export failed for Tx:DOM-62
2014-05-06 04:22:25.743 PDT [pool-15-thread-1] INFO o.o.c.m.s.d.b.i.DOMDataBrokerImpl$CommitCoordination - Tx: DOM-63 Did not pass canCommit phase.
2014-05-06 04:22:25.744 PDT [pool-15-thread-1] ERROR o.o.c.m.s.b.i.ForwardedBackwardsCompatibleDataBroker - Tx: DOM-63 Rollback - Datastore commit failed.
2014-05-06 04:22:25.744 PDT [pool-15-thread-1] ERROR o.o.m.c.t.m.FlowCapableTopologyExporter - Topology export failed for Tx:DOM-63
2014-05-06 04:22:33.965 PDT [pool-14-thread-1] WARN o.o.c.m.s.manager.StatisticsProvider - Attempted to start already-existing handler for Uri [_value=openflow:2], very strange
2014-05-06 04:22:45.121 PDT [pool-14-thread-1] WARN o.o.c.m.s.manager.StatisticsProvider - Attempted to start already-existing handler for Uri [_value=openflow:1], very strange
2014-05-06 04:22:47.622 PDT [pool-14-thread-1] WARN o.o.c.m.s.manager.StatisticsProvider - Attempted to start already-existing handler for Uri [_value=openflow:3], very strange

Comment by Michal Rehak [ 13/May/14 ]

please verify

Comment by Luis Gomez [ 13/May/14 ]

I am still getting a lot of warnings and errors when starting mininet OF13 simulation.

Using latest build for base edition:

wget https://jenkins.opendaylight.org/integration/job/integration-project-centralized-integration/lastSuccessfulBuild/artifact/distributions/base/target/distributions-base-0.1.2-SNAPSHOT-osgipackage.zip

1) After controller starts, first time I do: sudo mn --controller 'remote,ip=127.0.0.1,port=6633' --topo tree,2 --switch ovsk,protocols=OpenFlow13

osgi> 2014-05-10 21:27:35.020 PDT [nioEventLoopGroup-10-1] INFO o.o.o.p.i.c.PublishingChannelInitializer - Incoming connection from (remote address): /127.0.0.1:43093 --> :6633
2014-05-10 21:27:35.020 PDT [nioEventLoopGroup-10-1] INFO o.o.o.p.i.c.PublishingChannelInitializer - Incoming connection accepted - building pipeline
2014-05-10 21:27:35.034 PDT [nioEventLoopGroup-10-1] WARN o.o.o.o.m.c.s.SessionManagerOFImpl - context for invalidation not found
2014-05-10 21:27:35.369 PDT [nioEventLoopGroup-10-2] INFO o.o.o.p.i.c.PublishingChannelInitializer - Incoming connection from (remote address): /127.0.0.1:43094 --> :6633
2014-05-10 21:27:35.369 PDT [nioEventLoopGroup-10-2] INFO o.o.o.p.i.c.PublishingChannelInitializer - Incoming connection accepted - building pipeline
2014-05-10 21:27:35.373 PDT [nioEventLoopGroup-10-2] INFO o.o.o.p.i.c.ConnectionAdapterImpl - Hello received / branch
2014-05-10 21:27:35.420 PDT [nioEventLoopGroup-10-3] INFO o.o.o.p.i.c.PublishingChannelInitializer - Incoming connection from (remote address): /127.0.0.1:43095 --> :6633
2014-05-10 21:27:35.420 PDT [nioEventLoopGroup-10-3] INFO o.o.o.p.i.c.PublishingChannelInitializer - Incoming connection accepted - building pipeline
2014-05-10 21:27:35.426 PDT [nioEventLoopGroup-10-3] INFO o.o.o.p.i.c.ConnectionAdapterImpl - Hello received / branch
2014-05-10 21:27:35.480 PDT [nioEventLoopGroup-10-4] INFO o.o.o.p.i.c.PublishingChannelInitializer - Incoming connection from (remote address): /127.0.0.1:43096 --> :6633
2014-05-10 21:27:35.480 PDT [nioEventLoopGroup-10-4] INFO o.o.o.p.i.c.PublishingChannelInitializer - Incoming connection accepted - building pipeline
2014-05-10 21:27:35.485 PDT [nioEventLoopGroup-10-4] INFO o.o.o.p.i.c.ConnectionAdapterImpl - Hello received / branch
2014-05-10 21:27:35.663 PDT [pool-14-thread-1] WARN o.o.y.s.b.g.i.RuntimeGeneratedMappingServiceImpl - Failed to deserialize entry (urn:opendaylight:inventory?revision=2013-08-19)nodes/(urn:opendaylight:inventory?revision=2013-08-19)node[

{(urn:opendaylight:inventory?revision=2013-08-19)id=openflow:3}

]/(urn:opendaylight:flow:inventory?revision=2013-08-19)description
2014-05-10 21:27:35.663 PDT [pool-14-thread-1] WARN o.o.y.s.b.g.i.RuntimeGeneratedMappingServiceImpl - Failed to deserialize entry (urn:opendaylight:inventory?revision=2013-08-19)nodes/(urn:opendaylight:inventory?revision=2013-08-19)node[

{(urn:opendaylight:inventory?revision=2013-08-19)id=openflow:3}

]/(urn:opendaylight:flow:inventory?revision=2013-08-19)hardware
2014-05-10 21:27:35.663 PDT [pool-14-thread-1] WARN o.o.y.s.b.g.i.RuntimeGeneratedMappingServiceImpl - Failed to deserialize entry (urn:opendaylight:inventory?revision=2013-08-19)nodes/(urn:opendaylight:inventory?revision=2013-08-19)node[

{(urn:opendaylight:inventory?revision=2013-08-19)id=openflow:3}

]/(urn:opendaylight:flow:inventory?revision=2013-08-19)manufacturer
2014-05-10 21:27:35.664 PDT [pool-14-thread-1] WARN o.o.y.s.b.g.i.RuntimeGeneratedMappingServiceImpl - Failed to deserialize entry (urn:opendaylight:inventory?revision=2013-08-19)nodes/(urn:opendaylight:inventory?revision=2013-08-19)node[

{(urn:opendaylight:inventory?revision=2013-08-19)id=openflow:3}

]/(urn:opendaylight:flow:inventory?revision=2013-08-19)serial-number
2014-05-10 21:27:35.664 PDT [pool-14-thread-1] WARN o.o.y.s.b.g.i.RuntimeGeneratedMappingServiceImpl - Failed to deserialize entry (urn:opendaylight:inventory?revision=2013-08-19)nodes/(urn:opendaylight:inventory?revision=2013-08-19)node[

{(urn:opendaylight:inventory?revision=2013-08-19)id=openflow:3}

]/(urn:opendaylight:flow:inventory?revision=2013-08-19)software
2014-05-10 21:27:35.664 PDT [pool-14-thread-1] WARN o.o.y.s.b.g.i.RuntimeGeneratedMappingServiceImpl - Failed to deserialize entry (urn:opendaylight:inventory?revision=2013-08-19)nodes/(urn:opendaylight:inventory?revision=2013-08-19)node[

{(urn:opendaylight:inventory?revision=2013-08-19)id=openflow:3}

]/(urn:opendaylight:flow:inventory?revision=2013-08-19)supported-actions
2014-05-10 21:27:35.693 PDT [md-sal-binding-notification-20] INFO o.o.c.m.s.manager.StatisticsProvider - Attempted to get non-existing handler for Uri [_value=openflow:1]
2014-05-10 21:27:35.711 PDT [pool-14-thread-2] WARN o.o.y.s.b.g.i.RuntimeGeneratedMappingServiceImpl - Failed to deserialize entry (urn:opendaylight:inventory?revision=2013-08-19)nodes/(urn:opendaylight:inventory?revision=2013-08-19)node[

{(urn:opendaylight:inventory?revision=2013-08-19)id=openflow:2}

]/(urn:opendaylight:flow:inventory?revision=2013-08-19)description
2014-05-10 21:27:35.711 PDT [pool-14-thread-2] WARN o.o.y.s.b.g.i.RuntimeGeneratedMappingServiceImpl - Failed to deserialize entry (urn:opendaylight:inventory?revision=2013-08-19)nodes/(urn:opendaylight:inventory?revision=2013-08-19)node[

{(urn:opendaylight:inventory?revision=2013-08-19)id=openflow:2}

]/(urn:opendaylight:flow:inventory?revision=2013-08-19)hardware
2014-05-10 21:27:35.711 PDT [pool-14-thread-2] WARN o.o.y.s.b.g.i.RuntimeGeneratedMappingServiceImpl - Failed to deserialize entry (urn:opendaylight:inventory?revision=2013-08-19)nodes/(urn:opendaylight:inventory?revision=2013-08-19)node[

{(urn:opendaylight:inventory?revision=2013-08-19)id=openflow:2}

]/(urn:opendaylight:flow:inventory?revision=2013-08-19)manufacturer
2014-05-10 21:27:35.711 PDT [pool-14-thread-2] WARN o.o.y.s.b.g.i.RuntimeGeneratedMappingServiceImpl - Failed to deserialize entry (urn:opendaylight:inventory?revision=2013-08-19)nodes/(urn:opendaylight:inventory?revision=2013-08-19)node[

{(urn:opendaylight:inventory?revision=2013-08-19)id=openflow:2}

]/(urn:opendaylight:flow:inventory?revision=2013-08-19)serial-number
2014-05-10 21:27:35.712 PDT [pool-14-thread-2] WARN o.o.y.s.b.g.i.RuntimeGeneratedMappingServiceImpl - Failed to deserialize entry (urn:opendaylight:inventory?revision=2013-08-19)nodes/(urn:opendaylight:inventory?revision=2013-08-19)node[

{(urn:opendaylight:inventory?revision=2013-08-19)id=openflow:2}

]/(urn:opendaylight:flow:inventory?revision=2013-08-19)software
2014-05-10 21:27:35.712 PDT [pool-14-thread-2] WARN o.o.y.s.b.g.i.RuntimeGeneratedMappingServiceImpl - Failed to deserialize entry (urn:opendaylight:inventory?revision=2013-08-19)nodes/(urn:opendaylight:inventory?revision=2013-08-19)node[

{(urn:opendaylight:inventory?revision=2013-08-19)id=openflow:2}

]/(urn:opendaylight:flow:inventory?revision=2013-08-19)supported-actions
2014-05-10 21:27:35.717 PDT [pool-14-thread-1] WARN o.o.y.s.b.g.i.RuntimeGeneratedMappingServiceImpl - Failed to deserialize entry (urn:opendaylight:inventory?revision=2013-08-19)nodes/(urn:opendaylight:inventory?revision=2013-08-19)node[

{(urn:opendaylight:inventory?revision=2013-08-19)id=openflow:1}

]/(urn:opendaylight:flow:inventory?revision=2013-08-19)description
2014-05-10 21:27:35.717 PDT [pool-14-thread-1] WARN o.o.y.s.b.g.i.RuntimeGeneratedMappingServiceImpl - Failed to deserialize entry (urn:opendaylight:inventory?revision=2013-08-19)nodes/(urn:opendaylight:inventory?revision=2013-08-19)node[

{(urn:opendaylight:inventory?revision=2013-08-19)id=openflow:1}

]/(urn:opendaylight:flow:inventory?revision=2013-08-19)hardware
2014-05-10 21:27:35.718 PDT [pool-14-thread-1] WARN o.o.y.s.b.g.i.RuntimeGeneratedMappingServiceImpl - Failed to deserialize entry (urn:opendaylight:inventory?revision=2013-08-19)nodes/(urn:opendaylight:inventory?revision=2013-08-19)node[

{(urn:opendaylight:inventory?revision=2013-08-19)id=openflow:1}

]/(urn:opendaylight:flow:inventory?revision=2013-08-19)manufacturer
2014-05-10 21:27:35.718 PDT [pool-14-thread-1] WARN o.o.y.s.b.g.i.RuntimeGeneratedMappingServiceImpl - Failed to deserialize entry (urn:opendaylight:inventory?revision=2013-08-19)nodes/(urn:opendaylight:inventory?revision=2013-08-19)node[

{(urn:opendaylight:inventory?revision=2013-08-19)id=openflow:1}

]/(urn:opendaylight:flow:inventory?revision=2013-08-19)serial-number
2014-05-10 21:27:35.718 PDT [pool-14-thread-1] WARN o.o.y.s.b.g.i.RuntimeGeneratedMappingServiceImpl - Failed to deserialize entry (urn:opendaylight:inventory?revision=2013-08-19)nodes/(urn:opendaylight:inventory?revision=2013-08-19)node[

{(urn:opendaylight:inventory?revision=2013-08-19)id=openflow:1}

]/(urn:opendaylight:flow:inventory?revision=2013-08-19)software
2014-05-10 21:27:35.718 PDT [pool-14-thread-1] WARN o.o.y.s.b.g.i.RuntimeGeneratedMappingServiceImpl - Failed to deserialize entry (urn:opendaylight:inventory?revision=2013-08-19)nodes/(urn:opendaylight:inventory?revision=2013-08-19)node[

{(urn:opendaylight:inventory?revision=2013-08-19)id=openflow:1}

]/(urn:opendaylight:flow:inventory?revision=2013-08-19)supported-actions
2014-05-10 21:27:35.766 PDT [pool-14-thread-2] WARN o.o.y.s.b.g.i.LazyGeneratedCodecRegistry - Could not find generated type for augmentation AugmentationSchemaImpl[targetPath=SchemaPath [path=[(urn:TBD:params:xml:ns:yang:network-topology?revision=2013-10-21)network-topology, (urn:TBD:params:xml:ns:yang:network-topology?revision=2013-10-21)topology, (urn:TBD:params:xml:ns:yang:network-topology?revision=2013-10-21)node], absolute=true], when=null] with childs [].
2014-05-10 21:27:35.767 PDT [pool-14-thread-2] WARN o.o.y.s.b.g.i.LazyGeneratedCodecRegistry - Could not find generated type for augmentation AugmentationSchemaImpl[targetPath=SchemaPath [path=[(urn:TBD:params:xml:ns:yang:network-topology?revision=2013-10-21)network-topology, (urn:TBD:params:xml:ns:yang:network-topology?revision=2013-10-21)topology], absolute=true], when=null] with childs [].
2014-05-10 21:27:35.786 PDT [pool-14-thread-1] WARN o.o.c.t.internal.TopologyManagerImpl - Ignore edge that contains invalid node connector: (MD_SAL|openflow:1:2@MD_SAL|openflow:1->MD_SAL|openflow:3:3@MD_SAL|openflow:3)
2014-05-10 21:27:35.789 PDT [pool-14-thread-2] WARN o.o.c.t.internal.TopologyManagerImpl - Ignore edge that contains invalid node connector: (MD_SAL|openflow:1:1@MD_SAL|openflow:1->MD_SAL|openflow:2:3@MD_SAL|openflow:2)
2014-05-10 21:27:35.834 PDT [pool-14-thread-2] ERROR o.o.c.s.c.t.TopologyCommitHandler - Exception caught
java.lang.NullPointerException: null
at org.opendaylight.controller.topologymanager.internal.TopologyManagerImpl.edgeUpdate(TopologyManagerImpl.java:655) ~[na:na]
at org.opendaylight.controller.topologymanager.internal.TopologyManagerImpl.edgeUpdate(TopologyManagerImpl.java:706) ~[na:na]
at org.opendaylight.controller.sal.implementation.internal.Topology.edgeUpdate(Topology.java:95) ~[na:na]
at org.opendaylight.controller.sal.compatibility.topology.TopologyCommitHandler.onDataChanged(TopologyCommitHandler.java:77) ~[na:na]
at org.opendaylight.controller.md.sal.binding.impl.ForwardedBackwardsCompatibleDataBroker$BackwardsCompatibleOperationalDataChangeInvoker.onDataChanged(ForwardedBackwardsCompatibleDataBroker.java:434) [bundlefile:na]
at org.opendaylight.controller.md.sal.binding.impl.AbstractForwardedDataBroker$TranslatingDataChangeInvoker.onDataChanged(AbstractForwardedDataBroker.java:158) [bundlefile:na]
at org.opendaylight.controller.md.sal.dom.store.impl.ChangeListenerNotifyTask.run(ChangeListenerNotifyTask.java:33) [bundlefile:na]
at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:471) [na:1.7.0_51]
at java.util.concurrent.FutureTask.run(FutureTask.java:262) [na:1.7.0_51]
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145) [na:1.7.0_51]
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615) [na:1.7.0_51]
at java.lang.Thread.run(Thread.java:744) [na:1.7.0_51]
2014-05-10 21:27:35.835 PDT [pool-14-thread-1] ERROR o.o.c.s.c.t.TopologyCommitHandler - Exception caught
java.lang.NullPointerException: null
at org.opendaylight.controller.topologymanager.internal.TopologyManagerImpl.edgeUpdate(TopologyManagerImpl.java:655) ~[na:na]
at org.opendaylight.controller.topologymanager.internal.TopologyManagerImpl.edgeUpdate(TopologyManagerImpl.java:706) ~[na:na]
at org.opendaylight.controller.sal.implementation.internal.Topology.edgeUpdate(Topology.java:95) ~[na:na]
at org.opendaylight.controller.sal.compatibility.topology.TopologyCommitHandler.onDataChanged(TopologyCommitHandler.java:77) ~[na:na]
at org.opendaylight.controller.md.sal.binding.impl.ForwardedBackwardsCompatibleDataBroker$BackwardsCompatibleOperationalDataChangeInvoker.onDataChanged(ForwardedBackwardsCompatibleDataBroker.java:434) [bundlefile:na]
at org.opendaylight.controller.md.sal.binding.impl.AbstractForwardedDataBroker$TranslatingDataChangeInvoker.onDataChanged(AbstractForwardedDataBroker.java:158) [bundlefile:na]
at org.opendaylight.controller.md.sal.dom.store.impl.ChangeListenerNotifyTask.run(ChangeListenerNotifyTask.java:33) [bundlefile:na]
at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:471) [na:1.7.0_51]
at java.util.concurrent.FutureTask.run(FutureTask.java:262) [na:1.7.0_51]
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145) [na:1.7.0_51]
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615) [na:1.7.0_51]
at java.lang.Thread.run(Thread.java:744) [na:1.7.0_51]
2014-05-10 21:27:36.362 PDT [pool-14-thread-2] WARN o.o.y.s.b.g.i.RuntimeGeneratedMappingServiceImpl - Failed to deserialize entry (urn:opendaylight:inventory?revision=2013-08-19)nodes/(urn:opendaylight:inventory?revision=2013-08-19)node[

{(urn:opendaylight:inventory?revision=2013-08-19)id=openflow:3}

]/(urn:opendaylight:flow:inventory?revision=2013-08-19)table[

{(urn:opendaylight:flow:inventory?revision=2013-08-19)id=72}

]/(urn:opendaylight:flow:table:statistics?revision=2013-12-15)flow-table-statistics
2014-05-10 21:27:36.364 PDT [pool-14-thread-2] WARN o.o.c.m.s.b.i.AbstractForwardedDataBroker - Failed to transform (urn:opendaylight:inventory?revision=2013-08-19)nodes/(urn:opendaylight:inventory?revision=2013-08-19)node/(urn:opendaylight:inventory?revision=2013-08-19)node[

{(urn:opendaylight:inventory?revision=2013-08-19)id=openflow:3}

]/AugmentationIdentifier

{childNames=[(urn:opendaylight:flow:inventory?revision=2013-08-19)description, (urn:opendaylight:flow:inventory?revision=2013-08-19)group, (urn:opendaylight:flow:inventory?revision=2013-08-19)hardware, (urn:opendaylight:flow:inventory?revision=2013-08-19)manufacturer, (urn:opendaylight:flow:inventory?revision=2013-08-19)meter, (urn:opendaylight:flow:inventory?revision=2013-08-19)serial-number, (urn:opendaylight:flow:inventory?revision=2013-08-19)software, (urn:opendaylight:flow:inventory?revision=2013-08-19)supported-actions, (urn:opendaylight:flow:inventory?revision=2013-08-19)supported-instructions, (urn:opendaylight:flow:inventory?revision=2013-08-19)supported-match-types, (urn:opendaylight:flow:inventory?revision=2013-08-19)switch-features, (urn:opendaylight:flow:inventory?revision=2013-08-19)table]}

/(urn:opendaylight:flow:inventory?revision=2013-08-19)table/(urn:opendaylight:flow:inventory?revision=2013-08-19)table[

{(urn:opendaylight:flow:inventory?revision=2013-08-19)id=72}

]/(urn:opendaylight:flow:table:statistics?revision=2013-12-15)flow-table-statistics=ImmutableContainerNode{nodeIdentifier=(urn:opendaylight:flow:table:statistics?revision=2013-12-15)flow-table-statistics, value=[ImmutableLeafNode{nodeIdentifier=(urn:opendaylight:flow:table:statistics?revision=2013-12-15)active-flows, value=0, attributes={}}, ImmutableLeafNode{nodeIdentifier=(urn:opendaylight:flow:table:statistics?revision=2013-12-15)packets-matched, value=0, attributes={}}, ImmutableLeafNode{nodeIdentifier=(urn:opendaylight:flow:table:statistics?revision=2013-12-15)packets-looked-up, value=0, attributes={}}], attributes={}}, omitting it
org.opendaylight.yangtools.yang.data.impl.codec.DeserializationException: Entry deserialization failed
at org.opendaylight.yangtools.sal.binding.generator.impl.RuntimeGeneratedMappingServiceImpl.fromDataDom(RuntimeGeneratedMappingServiceImpl.java:332) ~[bundlefile:na]
at org.opendaylight.controller.config.yang.md.sal.binding.impl.RuntimeMappingModule$RuntimeGeneratedMappingServiceProxy.fromDataDom(RuntimeMappingModule.java:147) ~[bundlefile:na]
at org.opendaylight.controller.md.sal.binding.impl.BindingToNormalizedNodeCodec.toBindingImpl(BindingToNormalizedNodeCodec.java:183) ~[bundlefile:na]
at org.opendaylight.controller.md.sal.binding.impl.BindingToNormalizedNodeCodec.toBinding(BindingToNormalizedNodeCodec.java:132) ~[bundlefile:na]
at org.opendaylight.controller.md.sal.binding.impl.BindingToNormalizedNodeCodec.toBinding(BindingToNormalizedNodeCodec.java:233) ~[bundlefile:na]
at org.opendaylight.controller.md.sal.binding.impl.AbstractForwardedDataBroker.toBinding(AbstractForwardedDataBroker.java:99) ~[bundlefile:na]
at org.opendaylight.controller.md.sal.binding.impl.AbstractForwardedDataBroker$TranslatedDataChangeEvent.getCreatedData(AbstractForwardedDataBroker.java:183) [bundlefile:na]
at org.opendaylight.controller.md.sal.binding.impl.LegacyDataChangeEvent$OperationalChangeEvent.getCreatedOperationalData(LegacyDataChangeEvent.java:108) [bundlefile:na]
at org.opendaylight.controller.md.statistics.manager.FlowCapableTracker.onDataChanged(FlowCapableTracker.java:93) [bundlefile:na]
at org.opendaylight.controller.md.sal.binding.impl.ForwardedBackwardsCompatibleDataBroker$BackwardsCompatibleOperationalDataChangeInvoker.onDataChanged(ForwardedBackwardsCompatibleDataBroker.java:434) [bundlefile:na]
at org.opendaylight.controller.md.sal.binding.impl.AbstractForwardedDataBroker$TranslatingDataChangeInvoker.onDataChanged(AbstractForwardedDataBroker.java:158) [bundlefile:na]
at org.opendaylight.controller.md.sal.dom.store.impl.ChangeListenerNotifyTask.run(ChangeListenerNotifyTask.java:33) [bundlefile:na]
at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:471) [na:1.7.0_51]
at java.util.concurrent.FutureTask.run(FutureTask.java:262) [na:1.7.0_51]
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145) [na:1.7.0_51]
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615) [na:1.7.0_51]
at java.lang.Thread.run(Thread.java:744) [na:1.7.0_51]
Caused by: java.lang.IllegalStateException: Failed to lookup instantiated type for path SchemaPath [path=[(urn:opendaylight:inventory?revision=2013-08-19)nodes, (urn:opendaylight:inventory?revision=2013-08-19)node, (urn:opendaylight:flow:inventory?revision=2013-08-19)table, (urn:opendaylight:flow:table:statistics?revision=2013-12-15)flow-table-statistics], absolute=true]
at com.google.common.base.Preconditions.checkState(Preconditions.java:176) ~[bundlefile:na]
at org.opendaylight.yangtools.sal.binding.generator.impl.LazyGeneratedCodecRegistry.getClassForPath(LazyGeneratedCodecRegistry.java:208) ~[bundlefile:na]
at org.opendaylight.yangtools.sal.binding.generator.impl.InstanceIdentifierCodecImpl._deserializePathArgument(InstanceIdentifierCodecImpl.java:94) ~[bundlefile:na]
at org.opendaylight.yangtools.sal.binding.generator.impl.InstanceIdentifierCodecImpl.deserializePathArgument(InstanceIdentifierCodecImpl.java:193) ~[bundlefile:na]
at org.opendaylight.yangtools.sal.binding.generator.impl.InstanceIdentifierCodecImpl.deserialize(InstanceIdentifierCodecImpl.java:66) ~[bundlefile:na]
at org.opendaylight.yangtools.sal.binding.generator.impl.RuntimeGeneratedMappingServiceImpl.fromDataDom(RuntimeGeneratedMappingServiceImpl.java:329) ~[bundlefile:na]
... 16 common frames omitted

2) Next time I do: sudo mn --controller 'remote,ip=127.0.0.1,port=6633' --topo tree,2 --switch ovsk,protocols=OpenFlow13

osgi> 2014-05-10 21:30:46.986 PDT [nioEventLoopGroup-10-5] INFO o.o.o.p.i.c.PublishingChannelInitializer - Incoming connection from (remote address): /127.0.0.1:43120 --> :6633
2014-05-10 21:30:46.986 PDT [nioEventLoopGroup-10-5] INFO o.o.o.p.i.c.PublishingChannelInitializer - Incoming connection accepted - building pipeline
2014-05-10 21:30:46.987 PDT [nioEventLoopGroup-10-5] WARN o.o.o.o.m.c.s.SessionManagerOFImpl - context for invalidation not found
2014-05-10 21:30:47.357 PDT [nioEventLoopGroup-10-6] INFO o.o.o.p.i.c.PublishingChannelInitializer - Incoming connection from (remote address): /127.0.0.1:43121 --> :6633
2014-05-10 21:30:47.357 PDT [nioEventLoopGroup-10-6] INFO o.o.o.p.i.c.PublishingChannelInitializer - Incoming connection accepted - building pipeline
2014-05-10 21:30:47.367 PDT [nioEventLoopGroup-10-6] INFO o.o.o.p.i.c.ConnectionAdapterImpl - Hello received / branch
2014-05-10 21:30:47.380 PDT [md-sal-binding-notification-30] INFO o.o.c.m.s.manager.StatisticsProvider - Attempted to get non-existing handler for Uri [_value=openflow:1]
2014-05-10 21:30:47.380 PDT [pool-14-thread-2] WARN o.o.y.s.b.g.i.RuntimeGeneratedMappingServiceImpl - Failed to deserialize entry (urn:opendaylight:inventory?revision=2013-08-19)nodes/(urn:opendaylight:inventory?revision=2013-08-19)node[

{(urn:opendaylight:inventory?revision=2013-08-19)id=openflow:1}

]/(urn:opendaylight:flow:inventory?revision=2013-08-19)description
2014-05-10 21:30:47.380 PDT [pool-14-thread-2] WARN o.o.y.s.b.g.i.RuntimeGeneratedMappingServiceImpl - Failed to deserialize entry (urn:opendaylight:inventory?revision=2013-08-19)nodes/(urn:opendaylight:inventory?revision=2013-08-19)node[

{(urn:opendaylight:inventory?revision=2013-08-19)id=openflow:1}

]/(urn:opendaylight:flow:inventory?revision=2013-08-19)hardware
2014-05-10 21:30:47.381 PDT [pool-14-thread-2] WARN o.o.y.s.b.g.i.RuntimeGeneratedMappingServiceImpl - Failed to deserialize entry (urn:opendaylight:inventory?revision=2013-08-19)nodes/(urn:opendaylight:inventory?revision=2013-08-19)node[

{(urn:opendaylight:inventory?revision=2013-08-19)id=openflow:1}

]/(urn:opendaylight:flow:inventory?revision=2013-08-19)manufacturer
2014-05-10 21:30:47.382 PDT [pool-14-thread-2] WARN o.o.y.s.b.g.i.RuntimeGeneratedMappingServiceImpl - Failed to deserialize entry (urn:opendaylight:inventory?revision=2013-08-19)nodes/(urn:opendaylight:inventory?revision=2013-08-19)node[

{(urn:opendaylight:inventory?revision=2013-08-19)id=openflow:1}

]/(urn:opendaylight:flow:inventory?revision=2013-08-19)serial-number
2014-05-10 21:30:47.382 PDT [pool-14-thread-2] WARN o.o.y.s.b.g.i.RuntimeGeneratedMappingServiceImpl - Failed to deserialize entry (urn:opendaylight:inventory?revision=2013-08-19)nodes/(urn:opendaylight:inventory?revision=2013-08-19)node[

{(urn:opendaylight:inventory?revision=2013-08-19)id=openflow:1}

]/(urn:opendaylight:flow:inventory?revision=2013-08-19)software
2014-05-10 21:30:47.384 PDT [pool-14-thread-2] WARN o.o.y.s.b.g.i.RuntimeGeneratedMappingServiceImpl - Failed to deserialize entry (urn:opendaylight:inventory?revision=2013-08-19)nodes/(urn:opendaylight:inventory?revision=2013-08-19)node[

{(urn:opendaylight:inventory?revision=2013-08-19)id=openflow:1}

]/(urn:opendaylight:flow:inventory?revision=2013-08-19)supported-actions
2014-05-10 21:30:47.414 PDT [nioEventLoopGroup-10-7] INFO o.o.o.p.i.c.PublishingChannelInitializer - Incoming connection from (remote address): /127.0.0.1:43122 --> :6633
2014-05-10 21:30:47.414 PDT [nioEventLoopGroup-10-7] INFO o.o.o.p.i.c.PublishingChannelInitializer - Incoming connection accepted - building pipeline
2014-05-10 21:30:47.414 PDT [nioEventLoopGroup-10-7] INFO o.o.o.p.i.c.ConnectionAdapterImpl - Hello received / branch
2014-05-10 21:30:47.423 PDT [pool-14-thread-1] WARN o.o.y.s.b.g.i.RuntimeGeneratedMappingServiceImpl - Failed to deserialize entry (urn:opendaylight:inventory?revision=2013-08-19)nodes/(urn:opendaylight:inventory?revision=2013-08-19)node[

{(urn:opendaylight:inventory?revision=2013-08-19)id=openflow:2}

]/(urn:opendaylight:flow:inventory?revision=2013-08-19)description
2014-05-10 21:30:47.424 PDT [pool-14-thread-1] WARN o.o.y.s.b.g.i.RuntimeGeneratedMappingServiceImpl - Failed to deserialize entry (urn:opendaylight:inventory?revision=2013-08-19)nodes/(urn:opendaylight:inventory?revision=2013-08-19)node[

{(urn:opendaylight:inventory?revision=2013-08-19)id=openflow:2}

]/(urn:opendaylight:flow:inventory?revision=2013-08-19)hardware
2014-05-10 21:30:47.425 PDT [md-sal-binding-notification-25] INFO o.o.c.m.s.manager.StatisticsProvider - Attempted to get non-existing handler for Uri [_value=openflow:2]
2014-05-10 21:30:47.425 PDT [pool-14-thread-1] WARN o.o.y.s.b.g.i.RuntimeGeneratedMappingServiceImpl - Failed to deserialize entry (urn:opendaylight:inventory?revision=2013-08-19)nodes/(urn:opendaylight:inventory?revision=2013-08-19)node[

{(urn:opendaylight:inventory?revision=2013-08-19)id=openflow:2}

]/(urn:opendaylight:flow:inventory?revision=2013-08-19)manufacturer
2014-05-10 21:30:47.426 PDT [pool-14-thread-1] WARN o.o.y.s.b.g.i.RuntimeGeneratedMappingServiceImpl - Failed to deserialize entry (urn:opendaylight:inventory?revision=2013-08-19)nodes/(urn:opendaylight:inventory?revision=2013-08-19)node[

{(urn:opendaylight:inventory?revision=2013-08-19)id=openflow:2}

]/(urn:opendaylight:flow:inventory?revision=2013-08-19)serial-number
2014-05-10 21:30:47.426 PDT [pool-14-thread-1] WARN o.o.y.s.b.g.i.RuntimeGeneratedMappingServiceImpl - Failed to deserialize entry (urn:opendaylight:inventory?revision=2013-08-19)nodes/(urn:opendaylight:inventory?revision=2013-08-19)node[

{(urn:opendaylight:inventory?revision=2013-08-19)id=openflow:2}

]/(urn:opendaylight:flow:inventory?revision=2013-08-19)software
2014-05-10 21:30:47.426 PDT [pool-14-thread-1] WARN o.o.y.s.b.g.i.RuntimeGeneratedMappingServiceImpl - Failed to deserialize entry (urn:opendaylight:inventory?revision=2013-08-19)nodes/(urn:opendaylight:inventory?revision=2013-08-19)node[

{(urn:opendaylight:inventory?revision=2013-08-19)id=openflow:2}

]/(urn:opendaylight:flow:inventory?revision=2013-08-19)supported-actions
2014-05-10 21:30:47.462 PDT [nioEventLoopGroup-10-8] INFO o.o.o.p.i.c.PublishingChannelInitializer - Incoming connection from (remote address): /127.0.0.1:43123 --> :6633
2014-05-10 21:30:47.462 PDT [nioEventLoopGroup-10-8] INFO o.o.o.p.i.c.PublishingChannelInitializer - Incoming connection accepted - building pipeline
2014-05-10 21:30:47.463 PDT [nioEventLoopGroup-10-8] INFO o.o.o.p.i.c.ConnectionAdapterImpl - Hello received / branch
2014-05-10 21:30:47.468 PDT [pool-14-thread-1] WARN o.o.y.s.b.g.i.RuntimeGeneratedMappingServiceImpl - Failed to deserialize entry (urn:opendaylight:inventory?revision=2013-08-19)nodes/(urn:opendaylight:inventory?revision=2013-08-19)node[

{(urn:opendaylight:inventory?revision=2013-08-19)id=openflow:3}

]/(urn:opendaylight:flow:inventory?revision=2013-08-19)description
2014-05-10 21:30:47.469 PDT [pool-14-thread-1] WARN o.o.y.s.b.g.i.RuntimeGeneratedMappingServiceImpl - Failed to deserialize entry (urn:opendaylight:inventory?revision=2013-08-19)nodes/(urn:opendaylight:inventory?revision=2013-08-19)node[

{(urn:opendaylight:inventory?revision=2013-08-19)id=openflow:3}

]/(urn:opendaylight:flow:inventory?revision=2013-08-19)hardware
2014-05-10 21:30:47.469 PDT [pool-14-thread-1] WARN o.o.y.s.b.g.i.RuntimeGeneratedMappingServiceImpl - Failed to deserialize entry (urn:opendaylight:inventory?revision=2013-08-19)nodes/(urn:opendaylight:inventory?revision=2013-08-19)node[

{(urn:opendaylight:inventory?revision=2013-08-19)id=openflow:3}

]/(urn:opendaylight:flow:inventory?revision=2013-08-19)manufacturer
2014-05-10 21:30:47.469 PDT [pool-14-thread-1] WARN o.o.y.s.b.g.i.RuntimeGeneratedMappingServiceImpl - Failed to deserialize entry (urn:opendaylight:inventory?revision=2013-08-19)nodes/(urn:opendaylight:inventory?revision=2013-08-19)node[

{(urn:opendaylight:inventory?revision=2013-08-19)id=openflow:3}

]/(urn:opendaylight:flow:inventory?revision=2013-08-19)serial-number
2014-05-10 21:30:47.469 PDT [pool-14-thread-1] WARN o.o.y.s.b.g.i.RuntimeGeneratedMappingServiceImpl - Failed to deserialize entry (urn:opendaylight:inventory?revision=2013-08-19)nodes/(urn:opendaylight:inventory?revision=2013-08-19)node[

{(urn:opendaylight:inventory?revision=2013-08-19)id=openflow:3}

]/(urn:opendaylight:flow:inventory?revision=2013-08-19)software
2014-05-10 21:30:47.469 PDT [pool-14-thread-1] WARN o.o.y.s.b.g.i.RuntimeGeneratedMappingServiceImpl - Failed to deserialize entry (urn:opendaylight:inventory?revision=2013-08-19)nodes/(urn:opendaylight:inventory?revision=2013-08-19)node[

{(urn:opendaylight:inventory?revision=2013-08-19)id=openflow:3}

]/(urn:opendaylight:flow:inventory?revision=2013-08-19)supported-actions

Comment by Luis Gomez [ 13/May/14 ]

And even more errors when starting OF10 mininet simulation:

1) After restart first time I do: sudo mn --controller 'remote,ip=127.0.0.1,port=6633' --topo tree,2

osgi> 2014-05-10 21:39:15.118 PDT [nioEventLoopGroup-12-1] INFO o.o.o.p.i.c.PublishingChannelInitializer - Incoming connection from (remote address): /127.0.0.1:43168 --> :6633
2014-05-10 21:39:15.118 PDT [nioEventLoopGroup-12-1] INFO o.o.o.p.i.c.PublishingChannelInitializer - Incoming connection accepted - building pipeline
2014-05-10 21:39:15.132 PDT [nioEventLoopGroup-12-1] WARN o.o.o.o.m.c.s.SessionManagerOFImpl - context for invalidation not found
2014-05-10 21:39:15.472 PDT [nioEventLoopGroup-12-2] INFO o.o.o.p.i.c.PublishingChannelInitializer - Incoming connection from (remote address): /127.0.0.1:43169 --> :6633
2014-05-10 21:39:15.473 PDT [nioEventLoopGroup-12-2] INFO o.o.o.p.i.c.PublishingChannelInitializer - Incoming connection accepted - building pipeline
2014-05-10 21:39:15.487 PDT [nioEventLoopGroup-12-2] INFO o.o.o.p.i.c.ConnectionAdapterImpl - Hello received / branch
2014-05-10 21:39:15.568 PDT [nioEventLoopGroup-12-3] INFO o.o.o.p.i.c.PublishingChannelInitializer - Incoming connection from (remote address): /127.0.0.1:43170 --> :6633
2014-05-10 21:39:15.569 PDT [nioEventLoopGroup-12-3] INFO o.o.o.p.i.c.PublishingChannelInitializer - Incoming connection accepted - building pipeline
2014-05-10 21:39:15.581 PDT [nioEventLoopGroup-12-3] INFO o.o.o.p.i.c.ConnectionAdapterImpl - Hello received / branch
2014-05-10 21:39:15.605 PDT [nioEventLoopGroup-12-4] INFO o.o.o.p.i.c.PublishingChannelInitializer - Incoming connection from (remote address): /127.0.0.1:43171 --> :6633
2014-05-10 21:39:15.606 PDT [nioEventLoopGroup-12-4] INFO o.o.o.p.i.c.PublishingChannelInitializer - Incoming connection accepted - building pipeline
2014-05-10 21:39:15.609 PDT [nioEventLoopGroup-12-4] INFO o.o.o.p.i.c.ConnectionAdapterImpl - Hello received / branch
2014-05-10 21:39:15.718 PDT [pool-28-thread-1] WARN o.o.o.o.m.queue.QueueKeeperLightImpl - message enqueing interrupted
java.lang.InterruptedException: null
at java.util.concurrent.locks.AbstractQueuedSynchronizer.acquireInterruptibly(AbstractQueuedSynchronizer.java:1219) ~[na:1.7.0_51]
at java.util.concurrent.locks.ReentrantLock.lockInterruptibly(ReentrantLock.java:340) ~[na:1.7.0_51]
at java.util.concurrent.LinkedBlockingQueue.put(LinkedBlockingQueue.java:338) ~[na:1.7.0_51]
at org.opendaylight.openflowplugin.openflow.md.queue.QueueKeeperLightImpl.push(QueueKeeperLightImpl.java:109) [bundlefile:na]
at org.opendaylight.openflowplugin.openflow.md.queue.QueueKeeperLightImpl.push(QueueKeeperLightImpl.java:97) [bundlefile:na]
at org.opendaylight.openflowplugin.openflow.md.queue.QueueKeeperLightImpl.push(QueueKeeperLightImpl.java:30) [bundlefile:na]
at org.opendaylight.openflowplugin.openflow.md.core.ConnectionConductorImpl.onHandshakeSuccessfull(ConnectionConductorImpl.java:387) [bundlefile:na]
at org.opendaylight.openflowplugin.openflow.md.core.HandshakeManagerImpl.postHandshake(HandshakeManagerImpl.java:320) [bundlefile:na]
at org.opendaylight.openflowplugin.openflow.md.core.HandshakeManagerImpl.handleStepByStepVersionNegotiation(HandshakeManagerImpl.java:129) [bundlefile:na]
at org.opendaylight.openflowplugin.openflow.md.core.HandshakeManagerImpl.shake(HandshakeManagerImpl.java:104) [bundlefile:na]
at org.opendaylight.openflowplugin.openflow.md.core.HandshakeStepWrapper.run(HandshakeStepWrapper.java:47) [bundlefile:na]
at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:471) [na:1.7.0_51]
at java.util.concurrent.FutureTask.run(FutureTask.java:262) [na:1.7.0_51]
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145) [na:1.7.0_51]
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615) [na:1.7.0_51]
at java.lang.Thread.run(Thread.java:744) [na:1.7.0_51]
2014-05-10 21:39:15.718 PDT [pool-29-thread-1] WARN o.o.o.o.m.queue.QueueKeeperLightImpl - message enqueing interrupted
java.lang.InterruptedException: null
at java.util.concurrent.locks.AbstractQueuedSynchronizer.acquireInterruptibly(AbstractQueuedSynchronizer.java:1219) ~[na:1.7.0_51]
at java.util.concurrent.locks.ReentrantLock.lockInterruptibly(ReentrantLock.java:340) ~[na:1.7.0_51]
at java.util.concurrent.LinkedBlockingQueue.put(LinkedBlockingQueue.java:338) ~[na:1.7.0_51]
at org.opendaylight.openflowplugin.openflow.md.queue.QueueKeeperLightImpl.push(QueueKeeperLightImpl.java:109) [bundlefile:na]
at org.opendaylight.openflowplugin.openflow.md.queue.QueueKeeperLightImpl.push(QueueKeeperLightImpl.java:97) [bundlefile:na]
at org.opendaylight.openflowplugin.openflow.md.queue.QueueKeeperLightImpl.push(QueueKeeperLightImpl.java:30) [bundlefile:na]
at org.opendaylight.openflowplugin.openflow.md.core.ConnectionConductorImpl.onHandshakeSuccessfull(ConnectionConductorImpl.java:387) [bundlefile:na]
at org.opendaylight.openflowplugin.openflow.md.core.HandshakeManagerImpl.postHandshake(HandshakeManagerImpl.java:320) [bundlefile:na]
at org.opendaylight.openflowplugin.openflow.md.core.HandshakeManagerImpl.handleStepByStepVersionNegotiation(HandshakeManagerImpl.java:129) [bundlefile:na]
at org.opendaylight.openflowplugin.openflow.md.core.HandshakeManagerImpl.shake(HandshakeManagerImpl.java:104) [bundlefile:na]
at org.opendaylight.openflowplugin.openflow.md.core.HandshakeStepWrapper.run(HandshakeStepWrapper.java:47) [bundlefile:na]
at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:471) [na:1.7.0_51]
at java.util.concurrent.FutureTask.run(FutureTask.java:262) [na:1.7.0_51]
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145) [na:1.7.0_51]
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615) [na:1.7.0_51]
at java.lang.Thread.run(Thread.java:744) [na:1.7.0_51]
2014-05-10 21:39:15.718 PDT [pool-27-thread-1] WARN o.o.o.o.m.queue.QueueKeeperLightImpl - message enqueing interrupted
java.lang.InterruptedException: null
at java.util.concurrent.locks.AbstractQueuedSynchronizer.acquireInterruptibly(AbstractQueuedSynchronizer.java:1219) ~[na:1.7.0_51]
at java.util.concurrent.locks.ReentrantLock.lockInterruptibly(ReentrantLock.java:340) ~[na:1.7.0_51]
at java.util.concurrent.LinkedBlockingQueue.put(LinkedBlockingQueue.java:338) ~[na:1.7.0_51]
at org.opendaylight.openflowplugin.openflow.md.queue.QueueKeeperLightImpl.push(QueueKeeperLightImpl.java:109) [bundlefile:na]
at org.opendaylight.openflowplugin.openflow.md.queue.QueueKeeperLightImpl.push(QueueKeeperLightImpl.java:97) [bundlefile:na]
at org.opendaylight.openflowplugin.openflow.md.queue.QueueKeeperLightImpl.push(QueueKeeperLightImpl.java:30) [bundlefile:na]
at org.opendaylight.openflowplugin.openflow.md.core.ConnectionConductorImpl.onHandshakeSuccessfull(ConnectionConductorImpl.java:387) [bundlefile:na]
at org.opendaylight.openflowplugin.openflow.md.core.HandshakeManagerImpl.postHandshake(HandshakeManagerImpl.java:320) [bundlefile:na]
at org.opendaylight.openflowplugin.openflow.md.core.HandshakeManagerImpl.handleLowerVersionProposal(HandshakeManagerImpl.java:159) [bundlefile:na]
at org.opendaylight.openflowplugin.openflow.md.core.HandshakeManagerImpl.handleStepByStepVersionNegotiation(HandshakeManagerImpl.java:139) [bundlefile:na]
at org.opendaylight.openflowplugin.openflow.md.core.HandshakeManagerImpl.shake(HandshakeManagerImpl.java:104) [bundlefile:na]
at org.opendaylight.openflowplugin.openflow.md.core.HandshakeStepWrapper.run(HandshakeStepWrapper.java:47) [bundlefile:na]
at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:471) [na:1.7.0_51]
at java.util.concurrent.FutureTask.run(FutureTask.java:262) [na:1.7.0_51]
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145) [na:1.7.0_51]
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615) [na:1.7.0_51]
at java.lang.Thread.run(Thread.java:744) [na:1.7.0_51]
2014-05-10 21:39:15.771 PDT [pool-14-thread-1] WARN o.o.y.s.b.g.i.RuntimeGeneratedMappingServiceImpl - Failed to deserialize entry (urn:opendaylight:inventory?revision=2013-08-19)nodes/(urn:opendaylight:inventory?revision=2013-08-19)node[

{(urn:opendaylight:inventory?revision=2013-08-19)id=openflow:2}

]/(urn:opendaylight:flow:inventory?revision=2013-08-19)description
2014-05-10 21:39:15.772 PDT [pool-14-thread-1] WARN o.o.y.s.b.g.i.RuntimeGeneratedMappingServiceImpl - Failed to deserialize entry (urn:opendaylight:inventory?revision=2013-08-19)nodes/(urn:opendaylight:inventory?revision=2013-08-19)node[

{(urn:opendaylight:inventory?revision=2013-08-19)id=openflow:2}

]/(urn:opendaylight:flow:inventory?revision=2013-08-19)hardware
2014-05-10 21:39:15.772 PDT [pool-14-thread-1] WARN o.o.y.s.b.g.i.RuntimeGeneratedMappingServiceImpl - Failed to deserialize entry (urn:opendaylight:inventory?revision=2013-08-19)nodes/(urn:opendaylight:inventory?revision=2013-08-19)node[

{(urn:opendaylight:inventory?revision=2013-08-19)id=openflow:2}

]/(urn:opendaylight:flow:inventory?revision=2013-08-19)manufacturer
2014-05-10 21:39:15.772 PDT [pool-14-thread-1] WARN o.o.y.s.b.g.i.RuntimeGeneratedMappingServiceImpl - Failed to deserialize entry (urn:opendaylight:inventory?revision=2013-08-19)nodes/(urn:opendaylight:inventory?revision=2013-08-19)node[

{(urn:opendaylight:inventory?revision=2013-08-19)id=openflow:2}

]/(urn:opendaylight:flow:inventory?revision=2013-08-19)serial-number
2014-05-10 21:39:15.773 PDT [pool-14-thread-1] WARN o.o.y.s.b.g.i.RuntimeGeneratedMappingServiceImpl - Failed to deserialize entry (urn:opendaylight:inventory?revision=2013-08-19)nodes/(urn:opendaylight:inventory?revision=2013-08-19)node[

{(urn:opendaylight:inventory?revision=2013-08-19)id=openflow:2}

]/(urn:opendaylight:flow:inventory?revision=2013-08-19)software
2014-05-10 21:39:15.773 PDT [pool-14-thread-1] WARN o.o.y.s.b.g.i.RuntimeGeneratedMappingServiceImpl - Failed to deserialize entry (urn:opendaylight:inventory?revision=2013-08-19)nodes/(urn:opendaylight:inventory?revision=2013-08-19)node[

{(urn:opendaylight:inventory?revision=2013-08-19)id=openflow:2}

]/(urn:opendaylight:flow:inventory?revision=2013-08-19)supported-actions
2014-05-10 21:39:15.773 PDT [pool-14-thread-1] WARN o.o.y.s.b.g.i.RuntimeGeneratedMappingServiceImpl - Failed to deserialize entry (urn:opendaylight:inventory?revision=2013-08-19)nodes/(urn:opendaylight:inventory?revision=2013-08-19)node[

{(urn:opendaylight:inventory?revision=2013-08-19)id=openflow:2}

]/(urn:opendaylight:flow:inventory?revision=2013-08-19)supported-instructions
2014-05-10 21:39:15.773 PDT [pool-14-thread-1] WARN o.o.y.s.b.g.i.RuntimeGeneratedMappingServiceImpl - Failed to deserialize entry (urn:opendaylight:inventory?revision=2013-08-19)nodes/(urn:opendaylight:inventory?revision=2013-08-19)node[

{(urn:opendaylight:inventory?revision=2013-08-19)id=openflow:2}

]/(urn:opendaylight:flow:inventory?revision=2013-08-19)supported-match-types
2014-05-10 21:39:15.801 PDT [pool-14-thread-2] WARN o.o.y.s.b.g.i.RuntimeGeneratedMappingServiceImpl - Failed to deserialize entry (urn:opendaylight:inventory?revision=2013-08-19)nodes/(urn:opendaylight:inventory?revision=2013-08-19)node[

{(urn:opendaylight:inventory?revision=2013-08-19)id=openflow:3}

]/(urn:opendaylight:flow:inventory?revision=2013-08-19)description
2014-05-10 21:39:15.801 PDT [pool-14-thread-2] WARN o.o.y.s.b.g.i.RuntimeGeneratedMappingServiceImpl - Failed to deserialize entry (urn:opendaylight:inventory?revision=2013-08-19)nodes/(urn:opendaylight:inventory?revision=2013-08-19)node[

{(urn:opendaylight:inventory?revision=2013-08-19)id=openflow:3}

]/(urn:opendaylight:flow:inventory?revision=2013-08-19)hardware
2014-05-10 21:39:15.802 PDT [pool-14-thread-2] WARN o.o.y.s.b.g.i.RuntimeGeneratedMappingServiceImpl - Failed to deserialize entry (urn:opendaylight:inventory?revision=2013-08-19)nodes/(urn:opendaylight:inventory?revision=2013-08-19)node[

{(urn:opendaylight:inventory?revision=2013-08-19)id=openflow:3}

]/(urn:opendaylight:flow:inventory?revision=2013-08-19)manufacturer
2014-05-10 21:39:15.802 PDT [pool-14-thread-2] WARN o.o.y.s.b.g.i.RuntimeGeneratedMappingServiceImpl - Failed to deserialize entry (urn:opendaylight:inventory?revision=2013-08-19)nodes/(urn:opendaylight:inventory?revision=2013-08-19)node[

{(urn:opendaylight:inventory?revision=2013-08-19)id=openflow:3}

]/(urn:opendaylight:flow:inventory?revision=2013-08-19)serial-number
2014-05-10 21:39:15.802 PDT [pool-14-thread-2] WARN o.o.y.s.b.g.i.RuntimeGeneratedMappingServiceImpl - Failed to deserialize entry (urn:opendaylight:inventory?revision=2013-08-19)nodes/(urn:opendaylight:inventory?revision=2013-08-19)node[

{(urn:opendaylight:inventory?revision=2013-08-19)id=openflow:3}

]/(urn:opendaylight:flow:inventory?revision=2013-08-19)software
2014-05-10 21:39:15.802 PDT [pool-14-thread-2] WARN o.o.y.s.b.g.i.RuntimeGeneratedMappingServiceImpl - Failed to deserialize entry (urn:opendaylight:inventory?revision=2013-08-19)nodes/(urn:opendaylight:inventory?revision=2013-08-19)node[

{(urn:opendaylight:inventory?revision=2013-08-19)id=openflow:3}

]/(urn:opendaylight:flow:inventory?revision=2013-08-19)supported-actions
2014-05-10 21:39:15.803 PDT [pool-14-thread-2] WARN o.o.y.s.b.g.i.RuntimeGeneratedMappingServiceImpl - Failed to deserialize entry (urn:opendaylight:inventory?revision=2013-08-19)nodes/(urn:opendaylight:inventory?revision=2013-08-19)node[

{(urn:opendaylight:inventory?revision=2013-08-19)id=openflow:3}

]/(urn:opendaylight:flow:inventory?revision=2013-08-19)supported-instructions
2014-05-10 21:39:15.803 PDT [pool-14-thread-2] WARN o.o.y.s.b.g.i.RuntimeGeneratedMappingServiceImpl - Failed to deserialize entry (urn:opendaylight:inventory?revision=2013-08-19)nodes/(urn:opendaylight:inventory?revision=2013-08-19)node[

{(urn:opendaylight:inventory?revision=2013-08-19)id=openflow:3}

]/(urn:opendaylight:flow:inventory?revision=2013-08-19)supported-match-types
2014-05-10 21:39:15.814 PDT [pool-14-thread-1] WARN o.o.y.s.b.g.i.RuntimeGeneratedMappingServiceImpl - Failed to deserialize entry (urn:opendaylight:inventory?revision=2013-08-19)nodes/(urn:opendaylight:inventory?revision=2013-08-19)node[

{(urn:opendaylight:inventory?revision=2013-08-19)id=openflow:1}

]/(urn:opendaylight:flow:inventory?revision=2013-08-19)description
2014-05-10 21:39:15.814 PDT [pool-14-thread-1] WARN o.o.y.s.b.g.i.RuntimeGeneratedMappingServiceImpl - Failed to deserialize entry (urn:opendaylight:inventory?revision=2013-08-19)nodes/(urn:opendaylight:inventory?revision=2013-08-19)node[

{(urn:opendaylight:inventory?revision=2013-08-19)id=openflow:1}

]/(urn:opendaylight:flow:inventory?revision=2013-08-19)hardware
2014-05-10 21:39:15.815 PDT [pool-14-thread-1] WARN o.o.y.s.b.g.i.RuntimeGeneratedMappingServiceImpl - Failed to deserialize entry (urn:opendaylight:inventory?revision=2013-08-19)nodes/(urn:opendaylight:inventory?revision=2013-08-19)node[

{(urn:opendaylight:inventory?revision=2013-08-19)id=openflow:1}

]/(urn:opendaylight:flow:inventory?revision=2013-08-19)manufacturer
2014-05-10 21:39:15.815 PDT [pool-14-thread-1] WARN o.o.y.s.b.g.i.RuntimeGeneratedMappingServiceImpl - Failed to deserialize entry (urn:opendaylight:inventory?revision=2013-08-19)nodes/(urn:opendaylight:inventory?revision=2013-08-19)node[

{(urn:opendaylight:inventory?revision=2013-08-19)id=openflow:1}

]/(urn:opendaylight:flow:inventory?revision=2013-08-19)serial-number
2014-05-10 21:39:15.815 PDT [pool-14-thread-1] WARN o.o.y.s.b.g.i.RuntimeGeneratedMappingServiceImpl - Failed to deserialize entry (urn:opendaylight:inventory?revision=2013-08-19)nodes/(urn:opendaylight:inventory?revision=2013-08-19)node[

{(urn:opendaylight:inventory?revision=2013-08-19)id=openflow:1}

]/(urn:opendaylight:flow:inventory?revision=2013-08-19)software
2014-05-10 21:39:15.815 PDT [pool-14-thread-1] WARN o.o.y.s.b.g.i.RuntimeGeneratedMappingServiceImpl - Failed to deserialize entry (urn:opendaylight:inventory?revision=2013-08-19)nodes/(urn:opendaylight:inventory?revision=2013-08-19)node[

{(urn:opendaylight:inventory?revision=2013-08-19)id=openflow:1}

]/(urn:opendaylight:flow:inventory?revision=2013-08-19)supported-actions
2014-05-10 21:39:15.816 PDT [pool-14-thread-1] WARN o.o.y.s.b.g.i.RuntimeGeneratedMappingServiceImpl - Failed to deserialize entry (urn:opendaylight:inventory?revision=2013-08-19)nodes/(urn:opendaylight:inventory?revision=2013-08-19)node[

{(urn:opendaylight:inventory?revision=2013-08-19)id=openflow:1}

]/(urn:opendaylight:flow:inventory?revision=2013-08-19)supported-instructions
2014-05-10 21:39:15.817 PDT [pool-14-thread-1] WARN o.o.y.s.b.g.i.RuntimeGeneratedMappingServiceImpl - Failed to deserialize entry (urn:opendaylight:inventory?revision=2013-08-19)nodes/(urn:opendaylight:inventory?revision=2013-08-19)node[

{(urn:opendaylight:inventory?revision=2013-08-19)id=openflow:1}

]/(urn:opendaylight:flow:inventory?revision=2013-08-19)supported-match-types
2014-05-10 21:39:16.839 PDT [pool-14-thread-2] WARN o.o.y.s.b.g.i.RuntimeGeneratedMappingServiceImpl - Failed to deserialize entry (urn:opendaylight:inventory?revision=2013-08-19)nodes/(urn:opendaylight:inventory?revision=2013-08-19)node[

{(urn:opendaylight:inventory?revision=2013-08-19)id=openflow:2}

]/(urn:opendaylight:flow:inventory?revision=2013-08-19)table[

{(urn:opendaylight:flow:inventory?revision=2013-08-19)id=3}

]/(urn:opendaylight:flow:table:statistics?revision=2013-12-15)flow-table-statistics
2014-05-10 21:39:16.841 PDT [pool-14-thread-2] WARN o.o.c.m.s.b.i.AbstractForwardedDataBroker - Failed to transform (urn:opendaylight:inventory?revision=2013-08-19)nodes/(urn:opendaylight:inventory?revision=2013-08-19)node/(urn:opendaylight:inventory?revision=2013-08-19)node[

{(urn:opendaylight:inventory?revision=2013-08-19)id=openflow:2}

]/AugmentationIdentifier

{childNames=[(urn:opendaylight:flow:inventory?revision=2013-08-19)description, (urn:opendaylight:flow:inventory?revision=2013-08-19)group, (urn:opendaylight:flow:inventory?revision=2013-08-19)hardware, (urn:opendaylight:flow:inventory?revision=2013-08-19)manufacturer, (urn:opendaylight:flow:inventory?revision=2013-08-19)meter, (urn:opendaylight:flow:inventory?revision=2013-08-19)serial-number, (urn:opendaylight:flow:inventory?revision=2013-08-19)software, (urn:opendaylight:flow:inventory?revision=2013-08-19)supported-actions, (urn:opendaylight:flow:inventory?revision=2013-08-19)supported-instructions, (urn:opendaylight:flow:inventory?revision=2013-08-19)supported-match-types, (urn:opendaylight:flow:inventory?revision=2013-08-19)switch-features, (urn:opendaylight:flow:inventory?revision=2013-08-19)table]}

/(urn:opendaylight:flow:inventory?revision=2013-08-19)table/(urn:opendaylight:flow:inventory?revision=2013-08-19)table[

{(urn:opendaylight:flow:inventory?revision=2013-08-19)id=3}

]/(urn:opendaylight:flow:table:statistics?revision=2013-12-15)flow-table-statistics=ImmutableContainerNode{nodeIdentifier=(urn:opendaylight:flow:table:statistics?revision=2013-12-15)flow-table-statistics, value=[ImmutableLeafNode{nodeIdentifier=(urn:opendaylight:flow:table:statistics?revision=2013-12-15)active-flows, value=0, attributes={}}, ImmutableLeafNode{nodeIdentifier=(urn:opendaylight:flow:table:statistics?revision=2013-12-15)packets-matched, value=0, attributes={}}, ImmutableLeafNode{nodeIdentifier=(urn:opendaylight:flow:table:statistics?revision=2013-12-15)packets-looked-up, value=0, attributes={}}], attributes={}}, omitting it
org.opendaylight.yangtools.yang.data.impl.codec.DeserializationException: Entry deserialization failed
at org.opendaylight.yangtools.sal.binding.generator.impl.RuntimeGeneratedMappingServiceImpl.fromDataDom(RuntimeGeneratedMappingServiceImpl.java:332) ~[bundlefile:na]
at org.opendaylight.controller.config.yang.md.sal.binding.impl.RuntimeMappingModule$RuntimeGeneratedMappingServiceProxy.fromDataDom(RuntimeMappingModule.java:147) ~[bundlefile:na]
at org.opendaylight.controller.md.sal.binding.impl.BindingToNormalizedNodeCodec.toBindingImpl(BindingToNormalizedNodeCodec.java:183) ~[bundlefile:na]
at org.opendaylight.controller.md.sal.binding.impl.BindingToNormalizedNodeCodec.toBinding(BindingToNormalizedNodeCodec.java:132) ~[bundlefile:na]
at org.opendaylight.controller.md.sal.binding.impl.BindingToNormalizedNodeCodec.toBinding(BindingToNormalizedNodeCodec.java:233) ~[bundlefile:na]
at org.opendaylight.controller.md.sal.binding.impl.AbstractForwardedDataBroker.toBinding(AbstractForwardedDataBroker.java:99) ~[bundlefile:na]
at org.opendaylight.controller.md.sal.binding.impl.AbstractForwardedDataBroker$TranslatedDataChangeEvent.getCreatedData(AbstractForwardedDataBroker.java:183) [bundlefile:na]
at org.opendaylight.controller.md.sal.binding.impl.LegacyDataChangeEvent$OperationalChangeEvent.getCreatedOperationalData(LegacyDataChangeEvent.java:108) [bundlefile:na]
at org.opendaylight.controller.md.statistics.manager.FlowCapableTracker.onDataChanged(FlowCapableTracker.java:93) [bundlefile:na]
at org.opendaylight.controller.md.sal.binding.impl.ForwardedBackwardsCompatibleDataBroker$BackwardsCompatibleOperationalDataChangeInvoker.onDataChanged(ForwardedBackwardsCompatibleDataBroker.java:434) [bundlefile:na]
at org.opendaylight.controller.md.sal.binding.impl.AbstractForwardedDataBroker$TranslatingDataChangeInvoker.onDataChanged(AbstractForwardedDataBroker.java:158) [bundlefile:na]
at org.opendaylight.controller.md.sal.dom.store.impl.ChangeListenerNotifyTask.run(ChangeListenerNotifyTask.java:33) [bundlefile:na]
at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:471) [na:1.7.0_51]
at java.util.concurrent.FutureTask.run(FutureTask.java:262) [na:1.7.0_51]
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145) [na:1.7.0_51]
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615) [na:1.7.0_51]
at java.lang.Thread.run(Thread.java:744) [na:1.7.0_51]
Caused by: java.lang.IllegalStateException: Failed to lookup instantiated type for path SchemaPath [path=[(urn:opendaylight:inventory?revision=2013-08-19)nodes, (urn:opendaylight:inventory?revision=2013-08-19)node, (urn:opendaylight:flow:inventory?revision=2013-08-19)table, (urn:opendaylight:flow:table:statistics?revision=2013-12-15)flow-table-statistics], absolute=true]
at com.google.common.base.Preconditions.checkState(Preconditions.java:176) ~[bundlefile:na]
at org.opendaylight.yangtools.sal.binding.generator.impl.LazyGeneratedCodecRegistry.getClassForPath(LazyGeneratedCodecRegistry.java:208) ~[bundlefile:na]
at org.opendaylight.yangtools.sal.binding.generator.impl.InstanceIdentifierCodecImpl._deserializePathArgument(InstanceIdentifierCodecImpl.java:94) ~[bundlefile:na]
at org.opendaylight.yangtools.sal.binding.generator.impl.InstanceIdentifierCodecImpl.deserializePathArgument(InstanceIdentifierCodecImpl.java:193) ~[bundlefile:na]
at org.opendaylight.yangtools.sal.binding.generator.impl.InstanceIdentifierCodecImpl.deserialize(InstanceIdentifierCodecImpl.java:66) ~[bundlefile:na]
at org.opendaylight.yangtools.sal.binding.generator.impl.RuntimeGeneratedMappingServiceImpl.fromDataDom(RuntimeGeneratedMappingServiceImpl.java:329) ~[bundlefile:na]
... 16 common frames omitted

2) Next time I do: sudo mn --controller 'remote,ip=127.0.0.1,port=6633' --topo tree,2

osgi> 2014-05-10 21:45:24.076 PDT [nioEventLoopGroup-12-5] INFO o.o.o.p.i.c.PublishingChannelInitializer - Incoming connection from (remote address): /127.0.0.1:43208 --> :6633
2014-05-10 21:45:24.076 PDT [nioEventLoopGroup-12-5] INFO o.o.o.p.i.c.PublishingChannelInitializer - Incoming connection accepted - building pipeline
2014-05-10 21:45:24.077 PDT [nioEventLoopGroup-12-5] WARN o.o.o.o.m.c.s.SessionManagerOFImpl - context for invalidation not found
2014-05-10 21:45:24.423 PDT [nioEventLoopGroup-12-6] INFO o.o.o.p.i.c.PublishingChannelInitializer - Incoming connection from (remote address): /127.0.0.1:43209 --> :6633
2014-05-10 21:45:24.423 PDT [nioEventLoopGroup-12-6] INFO o.o.o.p.i.c.PublishingChannelInitializer - Incoming connection accepted - building pipeline
2014-05-10 21:45:24.426 PDT [nioEventLoopGroup-12-6] INFO o.o.o.p.i.c.ConnectionAdapterImpl - Hello received / branch
2014-05-10 21:45:24.432 PDT [pool-14-thread-2] WARN o.o.y.s.b.g.i.RuntimeGeneratedMappingServiceImpl - Failed to deserialize entry (urn:opendaylight:inventory?revision=2013-08-19)nodes/(urn:opendaylight:inventory?revision=2013-08-19)node[

{(urn:opendaylight:inventory?revision=2013-08-19)id=openflow:1}

]/(urn:opendaylight:flow:inventory?revision=2013-08-19)description
2014-05-10 21:45:24.433 PDT [pool-14-thread-2] WARN o.o.y.s.b.g.i.RuntimeGeneratedMappingServiceImpl - Failed to deserialize entry (urn:opendaylight:inventory?revision=2013-08-19)nodes/(urn:opendaylight:inventory?revision=2013-08-19)node[

{(urn:opendaylight:inventory?revision=2013-08-19)id=openflow:1}

]/(urn:opendaylight:flow:inventory?revision=2013-08-19)hardware
2014-05-10 21:45:24.433 PDT [pool-14-thread-2] WARN o.o.y.s.b.g.i.RuntimeGeneratedMappingServiceImpl - Failed to deserialize entry (urn:opendaylight:inventory?revision=2013-08-19)nodes/(urn:opendaylight:inventory?revision=2013-08-19)node[

{(urn:opendaylight:inventory?revision=2013-08-19)id=openflow:1}

]/(urn:opendaylight:flow:inventory?revision=2013-08-19)manufacturer
2014-05-10 21:45:24.433 PDT [pool-14-thread-2] WARN o.o.y.s.b.g.i.RuntimeGeneratedMappingServiceImpl - Failed to deserialize entry (urn:opendaylight:inventory?revision=2013-08-19)nodes/(urn:opendaylight:inventory?revision=2013-08-19)node[

{(urn:opendaylight:inventory?revision=2013-08-19)id=openflow:1}

]/(urn:opendaylight:flow:inventory?revision=2013-08-19)serial-number
2014-05-10 21:45:24.433 PDT [pool-14-thread-2] WARN o.o.y.s.b.g.i.RuntimeGeneratedMappingServiceImpl - Failed to deserialize entry (urn:opendaylight:inventory?revision=2013-08-19)nodes/(urn:opendaylight:inventory?revision=2013-08-19)node[

{(urn:opendaylight:inventory?revision=2013-08-19)id=openflow:1}

]/(urn:opendaylight:flow:inventory?revision=2013-08-19)software
2014-05-10 21:45:24.433 PDT [pool-14-thread-2] WARN o.o.y.s.b.g.i.RuntimeGeneratedMappingServiceImpl - Failed to deserialize entry (urn:opendaylight:inventory?revision=2013-08-19)nodes/(urn:opendaylight:inventory?revision=2013-08-19)node[

{(urn:opendaylight:inventory?revision=2013-08-19)id=openflow:1}

]/(urn:opendaylight:flow:inventory?revision=2013-08-19)supported-actions
2014-05-10 21:45:24.434 PDT [pool-14-thread-2] WARN o.o.y.s.b.g.i.RuntimeGeneratedMappingServiceImpl - Failed to deserialize entry (urn:opendaylight:inventory?revision=2013-08-19)nodes/(urn:opendaylight:inventory?revision=2013-08-19)node[

{(urn:opendaylight:inventory?revision=2013-08-19)id=openflow:1}

]/(urn:opendaylight:flow:inventory?revision=2013-08-19)supported-instructions
2014-05-10 21:45:24.434 PDT [pool-14-thread-2] WARN o.o.y.s.b.g.i.RuntimeGeneratedMappingServiceImpl - Failed to deserialize entry (urn:opendaylight:inventory?revision=2013-08-19)nodes/(urn:opendaylight:inventory?revision=2013-08-19)node[

{(urn:opendaylight:inventory?revision=2013-08-19)id=openflow:1}

]/(urn:opendaylight:flow:inventory?revision=2013-08-19)supported-match-types
2014-05-10 21:45:24.437 PDT [pool-31-thread-1] WARN o.o.o.o.m.queue.QueueKeeperLightImpl - message enqueing interrupted
java.lang.InterruptedException: null
at java.util.concurrent.locks.AbstractQueuedSynchronizer.acquireInterruptibly(AbstractQueuedSynchronizer.java:1219) ~[na:1.7.0_51]
at java.util.concurrent.locks.ReentrantLock.lockInterruptibly(ReentrantLock.java:340) ~[na:1.7.0_51]
at java.util.concurrent.LinkedBlockingQueue.put(LinkedBlockingQueue.java:338) ~[na:1.7.0_51]
at org.opendaylight.openflowplugin.openflow.md.queue.QueueKeeperLightImpl.push(QueueKeeperLightImpl.java:109) [bundlefile:na]
at org.opendaylight.openflowplugin.openflow.md.queue.QueueKeeperLightImpl.push(QueueKeeperLightImpl.java:97) [bundlefile:na]
at org.opendaylight.openflowplugin.openflow.md.queue.QueueKeeperLightImpl.push(QueueKeeperLightImpl.java:30) [bundlefile:na]
at org.opendaylight.openflowplugin.openflow.md.core.ConnectionConductorImpl.onHandshakeSuccessfull(ConnectionConductorImpl.java:387) [bundlefile:na]
at org.opendaylight.openflowplugin.openflow.md.core.HandshakeManagerImpl.postHandshake(HandshakeManagerImpl.java:320) [bundlefile:na]
at org.opendaylight.openflowplugin.openflow.md.core.HandshakeManagerImpl.handleLowerVersionProposal(HandshakeManagerImpl.java:159) [bundlefile:na]
at org.opendaylight.openflowplugin.openflow.md.core.HandshakeManagerImpl.handleStepByStepVersionNegotiation(HandshakeManagerImpl.java:139) [bundlefile:na]
at org.opendaylight.openflowplugin.openflow.md.core.HandshakeManagerImpl.shake(HandshakeManagerImpl.java:104) [bundlefile:na]
at org.opendaylight.openflowplugin.openflow.md.core.HandshakeStepWrapper.run(HandshakeStepWrapper.java:47) [bundlefile:na]
at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:471) [na:1.7.0_51]
at java.util.concurrent.FutureTask.run(FutureTask.java:262) [na:1.7.0_51]
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145) [na:1.7.0_51]
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615) [na:1.7.0_51]
at java.lang.Thread.run(Thread.java:744) [na:1.7.0_51]
2014-05-10 21:45:24.485 PDT [nioEventLoopGroup-12-7] INFO o.o.o.p.i.c.PublishingChannelInitializer - Incoming connection from (remote address): /127.0.0.1:43210 --> :6633
2014-05-10 21:45:24.485 PDT [nioEventLoopGroup-12-7] INFO o.o.o.p.i.c.PublishingChannelInitializer - Incoming connection accepted - building pipeline
2014-05-10 21:45:24.485 PDT [nioEventLoopGroup-12-7] INFO o.o.o.p.i.c.ConnectionAdapterImpl - Hello received / branch
2014-05-10 21:45:24.494 PDT [pool-32-thread-1] WARN o.o.o.o.m.queue.QueueKeeperLightImpl - message enqueing interrupted
java.lang.InterruptedException: null
at java.util.concurrent.locks.AbstractQueuedSynchronizer.acquireInterruptibly(AbstractQueuedSynchronizer.java:1219) ~[na:1.7.0_51]
at java.util.concurrent.locks.ReentrantLock.lockInterruptibly(ReentrantLock.java:340) ~[na:1.7.0_51]
at java.util.concurrent.LinkedBlockingQueue.put(LinkedBlockingQueue.java:338) ~[na:1.7.0_51]
at org.opendaylight.openflowplugin.openflow.md.queue.QueueKeeperLightImpl.push(QueueKeeperLightImpl.java:109) [bundlefile:na]
at org.opendaylight.openflowplugin.openflow.md.queue.QueueKeeperLightImpl.push(QueueKeeperLightImpl.java:97) [bundlefile:na]
at org.opendaylight.openflowplugin.openflow.md.queue.QueueKeeperLightImpl.push(QueueKeeperLightImpl.java:30) [bundlefile:na]
at org.opendaylight.openflowplugin.openflow.md.core.ConnectionConductorImpl.onHandshakeSuccessfull(ConnectionConductorImpl.java:387) [bundlefile:na]
at org.opendaylight.openflowplugin.openflow.md.core.HandshakeManagerImpl.postHandshake(HandshakeManagerImpl.java:320) [bundlefile:na]
at org.opendaylight.openflowplugin.openflow.md.core.HandshakeManagerImpl.handleStepByStepVersionNegotiation(HandshakeManagerImpl.java:129) [bundlefile:na]
at org.opendaylight.openflowplugin.openflow.md.core.HandshakeManagerImpl.shake(HandshakeManagerImpl.java:104) [bundlefile:na]
at org.opendaylight.openflowplugin.openflow.md.core.HandshakeStepWrapper.run(HandshakeStepWrapper.java:47) [bundlefile:na]
at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:471) [na:1.7.0_51]
at java.util.concurrent.FutureTask.run(FutureTask.java:262) [na:1.7.0_51]
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145) [na:1.7.0_51]
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615) [na:1.7.0_51]
at java.lang.Thread.run(Thread.java:744) [na:1.7.0_51]
2014-05-10 21:45:24.495 PDT [pool-14-thread-2] WARN o.o.y.s.b.g.i.RuntimeGeneratedMappingServiceImpl - Failed to deserialize entry (urn:opendaylight:inventory?revision=2013-08-19)nodes/(urn:opendaylight:inventory?revision=2013-08-19)node[

{(urn:opendaylight:inventory?revision=2013-08-19)id=openflow:2}

]/(urn:opendaylight:flow:inventory?revision=2013-08-19)description
2014-05-10 21:45:24.496 PDT [pool-14-thread-2] WARN o.o.y.s.b.g.i.RuntimeGeneratedMappingServiceImpl - Failed to deserialize entry (urn:opendaylight:inventory?revision=2013-08-19)nodes/(urn:opendaylight:inventory?revision=2013-08-19)node[

{(urn:opendaylight:inventory?revision=2013-08-19)id=openflow:2}

]/(urn:opendaylight:flow:inventory?revision=2013-08-19)hardware
2014-05-10 21:45:24.496 PDT [pool-14-thread-2] WARN o.o.y.s.b.g.i.RuntimeGeneratedMappingServiceImpl - Failed to deserialize entry (urn:opendaylight:inventory?revision=2013-08-19)nodes/(urn:opendaylight:inventory?revision=2013-08-19)node[

{(urn:opendaylight:inventory?revision=2013-08-19)id=openflow:2}

]/...

Comment by Abhijit Kumbhare [ 14/May/14 ]

Michal's fix has been merged yesterday:

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

Please verify again.

Comment by Luis Gomez [ 15/May/14 ]

This is not fixed yet, I still get many warnings and errors when I connect mininet. Users do not like to see errors when they try our controller even if these errors are not impacting the functionality.

BR/Luis

Comment by Michal Rehak [ 20/May/14 ]

Please retest. The last exception referring to InterruptedException was fixed in BUG-995.

Comment by Luis Gomez [ 30/May/14 ]

This is the latest and yes, I do not see InterruptException anymore:

osgi> 2014-05-30 01:11:07.419 PDT [nioEventLoopGroup-9-1] INFO o.o.o.p.i.c.PublishingChannelInitializer - Incoming connection from (remote address): /127.0.0.1:42578 --> :6633
2014-05-30 01:11:07.419 PDT [nioEventLoopGroup-9-1] INFO o.o.o.p.i.c.PublishingChannelInitializer - Incoming connection accepted - building pipeline
2014-05-30 01:11:07.435 PDT [nioEventLoopGroup-9-1] WARN o.o.o.o.m.c.s.SessionManagerOFImpl - context for invalidation not found
2014-05-30 01:11:07.849 PDT [nioEventLoopGroup-9-2] INFO o.o.o.p.i.c.PublishingChannelInitializer - Incoming connection from (remote address): /127.0.0.1:42579 --> :6633
2014-05-30 01:11:07.849 PDT [nioEventLoopGroup-9-2] INFO o.o.o.p.i.c.PublishingChannelInitializer - Incoming connection accepted - building pipeline
2014-05-30 01:11:07.868 PDT [nioEventLoopGroup-9-2] INFO o.o.o.p.i.c.ConnectionAdapterImpl - Hello received / branch
2014-05-30 01:11:07.879 PDT [nioEventLoopGroup-9-2] WARN o.o.o.p.i.c.ResponseExpectedRpcListener - Request for RpcResultKey [xid=2, outputClazz=org.opendaylight.yang.gen.v1.urn.opendaylight.openflow.protocol.rev130731.GetFeaturesOutput] did not receive a response
2014-05-30 01:11:07.905 PDT [nioEventLoopGroup-9-3] INFO o.o.o.p.i.c.PublishingChannelInitializer - Incoming connection from (remote address): /127.0.0.1:42580 --> :6633
2014-05-30 01:11:07.905 PDT [nioEventLoopGroup-9-3] INFO o.o.o.p.i.c.PublishingChannelInitializer - Incoming connection accepted - building pipeline
2014-05-30 01:11:07.930 PDT [nioEventLoopGroup-9-3] INFO o.o.o.p.i.c.ConnectionAdapterImpl - Hello received / branch
2014-05-30 01:11:07.959 PDT [nioEventLoopGroup-9-4] INFO o.o.o.p.i.c.PublishingChannelInitializer - Incoming connection from (remote address): /127.0.0.1:42581 --> :6633
2014-05-30 01:11:07.959 PDT [nioEventLoopGroup-9-4] INFO o.o.o.p.i.c.PublishingChannelInitializer - Incoming connection accepted - building pipeline
2014-05-30 01:11:07.960 PDT [nioEventLoopGroup-9-3] WARN o.o.o.p.i.c.ResponseExpectedRpcListener - Request for RpcResultKey [xid=4, outputClazz=org.opendaylight.yang.gen.v1.urn.opendaylight.openflow.protocol.rev130731.GetFeaturesOutput] did not receive a response
2014-05-30 01:11:07.960 PDT [nioEventLoopGroup-9-4] INFO o.o.o.p.i.c.ConnectionAdapterImpl - Hello received / branch
2014-05-30 01:11:07.962 PDT [nioEventLoopGroup-9-4] WARN o.o.o.p.i.c.ConnectionAdapterImpl - received unexpected rpc response: RpcResultKey [xid=5, outputClazz=org.opendaylight.yang.gen.v1.urn.opendaylight.openflow.protocol.rev130731.GetFeaturesOutput]
2014-05-30 01:11:08.111 PDT [md-sal-binding-notification-10] INFO o.o.c.m.s.manager.StatisticsProvider - Attempted to get non-existing handler for Uri [_value=openflow:2]
2014-05-30 01:11:08.111 PDT [md-sal-binding-notification-11] INFO o.o.c.m.s.manager.StatisticsProvider - Attempted to get non-existing handler for Uri [_value=openflow:1]
2014-05-30 01:11:09.373 PDT [pool-12-thread-2] WARN o.o.y.s.b.g.i.RuntimeGeneratedMappingServiceImpl - Failed to deserialize entry (urn:opendaylight:inventory?revision=2013-08-19)nodes/(urn:opendaylight:inventory?revision=2013-08-19)node[

{(urn:opendaylight:inventory?revision=2013-08-19)id=openflow:2}

]/(urn:opendaylight:flow:inventory?revision=2013-08-19)table[

{(urn:opendaylight:flow:inventory?revision=2013-08-19)id=242}

]/(urn:opendaylight:flow:table:statistics?revision=2013-12-15)flow-table-statistics
2014-05-30 01:11:09.383 PDT [pool-12-thread-2] WARN o.o.c.m.s.b.i.AbstractForwardedDataBroker - Failed to transform (urn:opendaylight:inventory?revision=2013-08-19)nodes/(urn:opendaylight:inventory?revision=2013-08-19)node/(urn:opendaylight:inventory?revision=2013-08-19)node[

{(urn:opendaylight:inventory?revision=2013-08-19)id=openflow:2}

]/AugmentationIdentifier

{childNames=[(urn:opendaylight:flow:inventory?revision=2013-08-19)description, (urn:opendaylight:flow:inventory?revision=2013-08-19)group, (urn:opendaylight:flow:inventory?revision=2013-08-19)hardware, (urn:opendaylight:flow:inventory?revision=2013-08-19)manufacturer, (urn:opendaylight:flow:inventory?revision=2013-08-19)meter, (urn:opendaylight:flow:inventory?revision=2013-08-19)serial-number, (urn:opendaylight:flow:inventory?revision=2013-08-19)software, (urn:opendaylight:flow:inventory?revision=2013-08-19)supported-actions, (urn:opendaylight:flow:inventory?revision=2013-08-19)supported-instructions, (urn:opendaylight:flow:inventory?revision=2013-08-19)supported-match-types, (urn:opendaylight:flow:inventory?revision=2013-08-19)switch-features, (urn:opendaylight:flow:inventory?revision=2013-08-19)table]}

/(urn:opendaylight:flow:inventory?revision=2013-08-19)table/(urn:opendaylight:flow:inventory?revision=2013-08-19)table[

{(urn:opendaylight:flow:inventory?revision=2013-08-19)id=242}

]/AugmentationIdentifier

{childNames=[(urn:opendaylight:flow:table:statistics?revision=2013-12-15)flow-table-statistics]}

=ImmutableAugmentationNode{nodeIdentifier=AugmentationIdentifier

{childNames=[(urn:opendaylight:flow:table:statistics?revision=2013-12-15)flow-table-statistics]}

, value=[ImmutableContainerNode{nodeIdentifier=(urn:opendaylight:flow:table:statistics?revision=2013-12-15)flow-table-statistics, value=[ImmutableLeafNode{nodeIdentifier=(urn:opendaylight:flow:table:statistics?revision=2013-12-15)active-flows, value=0, attributes={}}, ImmutableLeafNode{nodeIdentifier=(urn:opendaylight:flow:table:statistics?revision=2013-12-15)packets-matched, value=0, attributes={}}, ImmutableLeafNode{nodeIdentifier=(urn:opendaylight:flow:table:statistics?revision=2013-12-15)packets-looked-up, value=0, attributes={}}], attributes={}}]}, omitting it
org.opendaylight.yangtools.yang.data.impl.codec.DeserializationException: Entry deserialization failed
at org.opendaylight.yangtools.sal.binding.generator.impl.RuntimeGeneratedMappingServiceImpl.fromDataDom(RuntimeGeneratedMappingServiceImpl.java:311) ~[bundlefile:na]
at org.opendaylight.controller.config.yang.md.sal.binding.impl.RuntimeMappingModule$RuntimeGeneratedMappingServiceProxy.fromDataDom(RuntimeMappingModule.java:151) ~[bundlefile:na]
at org.opendaylight.controller.md.sal.binding.impl.BindingToNormalizedNodeCodec.toBindingImpl(BindingToNormalizedNodeCodec.java:202) ~[bundlefile:na]
at org.opendaylight.controller.md.sal.binding.impl.BindingToNormalizedNodeCodec.toBindingAugmented(BindingToNormalizedNodeCodec.java:167) ~[bundlefile:na]
at org.opendaylight.controller.md.sal.binding.impl.BindingToNormalizedNodeCodec.toBinding(BindingToNormalizedNodeCodec.java:130) ~[bundlefile:na]
at org.opendaylight.controller.md.sal.binding.impl.BindingToNormalizedNodeCodec.toBinding(BindingToNormalizedNodeCodec.java:258) ~[bundlefile:na]
at org.opendaylight.controller.md.sal.binding.impl.AbstractForwardedDataBroker.toBinding(AbstractForwardedDataBroker.java:99) ~[bundlefile:na]
at org.opendaylight.controller.md.sal.binding.impl.AbstractForwardedDataBroker$TranslatedDataChangeEvent.getCreatedData(AbstractForwardedDataBroker.java:183) [bundlefile:na]
at org.opendaylight.controller.md.sal.binding.impl.LegacyDataChangeEvent$OperationalChangeEvent.getCreatedOperationalData(LegacyDataChangeEvent.java:108) [bundlefile:na]
at org.opendaylight.controller.md.statistics.manager.FlowCapableTracker.onDataChanged(FlowCapableTracker.java:93) [bundlefile:na]
at org.opendaylight.controller.md.sal.binding.impl.ForwardedBackwardsCompatibleDataBroker$BackwardsCompatibleOperationalDataChangeInvoker.onDataChanged(ForwardedBackwardsCompatibleDataBroker.java:434) [bundlefile:na]
at org.opendaylight.controller.md.sal.binding.impl.AbstractForwardedDataBroker$TranslatingDataChangeInvoker.onDataChanged(AbstractForwardedDataBroker.java:158) [bundlefile:na]
at org.opendaylight.controller.md.sal.dom.store.impl.ChangeListenerNotifyTask.run(ChangeListenerNotifyTask.java:33) [bundlefile:na]
at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:471) [na:1.7.0_51]
at java.util.concurrent.FutureTask.run(FutureTask.java:262) [na:1.7.0_51]
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145) [na:1.7.0_51]
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615) [na:1.7.0_51]
at java.lang.Thread.run(Thread.java:744) [na:1.7.0_51]
Caused by: java.lang.IllegalStateException: Failed to lookup instantiated type for path SchemaPath [path=[(urn:opendaylight:inventory?revision=2013-08-19)nodes, (urn:opendaylight:inventory?revision=2013-08-19)node, (urn:opendaylight:flow:inventory?revision=2013-08-19)table, (urn:opendaylight:flow:table:statistics?revision=2013-12-15)flow-table-statistics], absolute=true]
at com.google.common.base.Preconditions.checkState(Preconditions.java:176) ~[bundlefile:na]
at org.opendaylight.yangtools.sal.binding.generator.impl.LazyGeneratedCodecRegistry.getClassForPath(LazyGeneratedCodecRegistry.java:205) ~[bundlefile:na]
at org.opendaylight.yangtools.sal.binding.generator.impl.InstanceIdentifierCodecImpl.deserializeNodeIdentifier(InstanceIdentifierCodecImpl.java:99) ~[bundlefile:na]
at org.opendaylight.yangtools.sal.binding.generator.impl.InstanceIdentifierCodecImpl.deserializePathArgument(InstanceIdentifierCodecImpl.java:219) ~[bundlefile:na]
at org.opendaylight.yangtools.sal.binding.generator.impl.InstanceIdentifierCodecImpl.deserialize(InstanceIdentifierCodecImpl.java:69) ~[bundlefile:na]
at org.opendaylight.yangtools.sal.binding.generator.impl.RuntimeGeneratedMappingServiceImpl.fromDataDom(RuntimeGeneratedMappingServiceImpl.java:308) ~[bundlefile:na]
... 17 common frames omitted
2014-05-30 01:11:09.384 PDT [pool-12-thread-2] WARN o.o.y.s.b.g.i.RuntimeGeneratedMappingServiceImpl - Failed to deserialize entry (urn:opendaylight:inventory?revision=2013-08-19)nodes/(urn:opendaylight:inventory?revision=2013-08-19)node[

{(urn:opendaylight:inventory?revision=2013-08-19)id=openflow:2}

]/(urn:opendaylight:flow:inventory?revision=2013-08-19)table[

{(urn:opendaylight:flow:inventory?revision=2013-08-19)id=41}

]/(urn:opendaylight:flow:table:statistics?revision=2013-12-15)flow-table-statistics
2014-05-30 01:11:09.385 PDT [pool-12-thread-2] WARN o.o.c.m.s.b.i.AbstractForwardedDataBroker - Failed to transform (urn:opendaylight:inventory?revision=2013-08-19)nodes/(urn:opendaylight:inventory?revision=2013-08-19)node/(urn:opendaylight:inventory?revision=2013-08-19)node[

{(urn:opendaylight:inventory?revision=2013-08-19)id=openflow:2}

]/AugmentationIdentifier

{childNames=[(urn:opendaylight:flow:inventory?revision=2013-08-19)description, (urn:opendaylight:flow:inventory?revision=2013-08-19)group, (urn:opendaylight:flow:inventory?revision=2013-08-19)hardware, (urn:opendaylight:flow:inventory?revision=2013-08-19)manufacturer, (urn:opendaylight:flow:inventory?revision=2013-08-19)meter, (urn:opendaylight:flow:inventory?revision=2013-08-19)serial-number, (urn:opendaylight:flow:inventory?revision=2013-08-19)software, (urn:opendaylight:flow:inventory?revision=2013-08-19)supported-actions, (urn:opendaylight:flow:inventory?revision=2013-08-19)supported-instructions, (urn:opendaylight:flow:inventory?revision=2013-08-19)supported-match-types, (urn:opendaylight:flow:inventory?revision=2013-08-19)switch-features, (urn:opendaylight:flow:inventory?revision=2013-08-19)table]}

/(urn:opendaylight:flow:inventory?revision=2013-08-19)table/(urn:opendaylight:flow:inventory?revision=2013-08-19)table[

{(urn:opendaylight:flow:inventory?revision=2013-08-19)id=41}

]/AugmentationIdentifier

{childNames=[(urn:opendaylight:flow:table:statistics?revision=2013-12-15)flow-table-statistics]}

=ImmutableAugmentationNode{nodeIdentifier=AugmentationIdentifier

{childNames=[(urn:opendaylight:flow:table:statistics?revision=2013-12-15)flow-table-statistics]}

, value=[ImmutableContainerNode{nodeIdentifier=(urn:opendaylight:flow:table:statistics?revision=2013-12-15)flow-table-statistics, value=[ImmutableLeafNode{nodeIdentifier=(urn:opendaylight:flow:table:statistics?revision=2013-12-15)active-flows, value=0, attributes={}}, ImmutableLeafNode{nodeIdentifier=(urn:opendaylight:flow:table:statistics?revision=2013-12-15)packets-matched, value=0, attributes={}}, ImmutableLeafNode{nodeIdentifier=(urn:opendaylight:flow:table:statistics?revision=2013-12-15)packets-looked-up, value=0, attributes={}}], attributes={}}]}, omitting it
org.opendaylight.yangtools.yang.data.impl.codec.DeserializationException: Entry deserialization failed
at org.opendaylight.yangtools.sal.binding.generator.impl.RuntimeGeneratedMappingServiceImpl.fromDataDom(RuntimeGeneratedMappingServiceImpl.java:311) ~[bundlefile:na]
at org.opendaylight.controller.config.yang.md.sal.binding.impl.RuntimeMappingModule$RuntimeGeneratedMappingServiceProxy.fromDataDom(RuntimeMappingModule.java:151) ~[bundlefile:na]
at org.opendaylight.controller.md.sal.binding.impl.BindingToNormalizedNodeCodec.toBindingImpl(BindingToNormalizedNodeCodec.java:202) ~[bundlefile:na]
at org.opendaylight.controller.md.sal.binding.impl.BindingToNormalizedNodeCodec.toBindingAugmented(BindingToNormalizedNodeCodec.java:167) ~[bundlefile:na]
at org.opendaylight.controller.md.sal.binding.impl.BindingToNormalizedNodeCodec.toBinding(BindingToNormalizedNodeCodec.java:130) ~[bundlefile:na]
at org.opendaylight.controller.md.sal.binding.impl.BindingToNormalizedNodeCodec.toBinding(BindingToNormalizedNodeCodec.java:258) ~[bundlefile:na]
at org.opendaylight.controller.md.sal.binding.impl.AbstractForwardedDataBroker.toBinding(AbstractForwardedDataBroker.java:99) ~[bundlefile:na]
at org.opendaylight.controller.md.sal.binding.impl.AbstractForwardedDataBroker$TranslatedDataChangeEvent.getCreatedData(AbstractForwardedDataBroker.java:183) [bundlefile:na]
at org.opendaylight.controller.md.sal.binding.impl.LegacyDataChangeEvent$OperationalChangeEvent.getCreatedOperationalData(LegacyDataChangeEvent.java:108) [bundlefile:na]
at org.opendaylight.controller.md.statistics.manager.FlowCapableTracker.onDataChanged(FlowCapableTracker.java:93) [bundlefile:na]
at org.opendaylight.controller.md.sal.binding.impl.ForwardedBackwardsCompatibleDataBroker$BackwardsCompatibleOperationalDataChangeInvoker.onDataChanged(ForwardedBackwardsCompatibleDataBroker.java:434) [bundlefile:na]
at org.opendaylight.controller.md.sal.binding.impl.AbstractForwardedDataBroker$TranslatingDataChangeInvoker.onDataChanged(AbstractForwardedDataBroker.java:158) [bundlefile:na]
at org.opendaylight.controller.md.sal.dom.store.impl.ChangeListenerNotifyTask.run(ChangeListenerNotifyTask.java:33) [bundlefile:na]
at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:471) [na:1.7.0_51]
at java.util.concurrent.FutureTask.run(FutureTask.java:262) [na:1.7.0_51]
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145) [na:1.7.0_51]
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615) [na:1.7.0_51]
at java.lang.Thread.run(Thread.java:744) [na:1.7.0_51]
Caused by: java.lang.IllegalStateException: Failed to lookup instantiated type for path SchemaPath [path=[(urn:opendaylight:inventory?revision=2013-08-19)nodes, (urn:opendaylight:inventory?revision=2013-08-19)node, (urn:opendaylight:flow:inventory?revision=2013-08-19)table, (urn:opendaylight:flow:table:statistics?revision=2013-12-15)flow-table-statistics], absolute=true]
at com.google.common.base.Preconditions.checkState(Preconditions.java:176) ~[bundlefile:na]
at org.opendaylight.yangtools.sal.binding.generator.impl.LazyGeneratedCodecRegistry.getClassForPath(LazyGeneratedCodecRegistry.java:205) ~[bundlefile:na]
at org.opendaylight.yangtools.sal.binding.generator.impl.InstanceIdentifierCodecImpl.deserializeNodeIdentifier(InstanceIdentifierCodecImpl.java:99) ~[bundlefile:na]
at org.opendaylight.yangtools.sal.binding.generator.impl.InstanceIdentifierCodecImpl.deserializePathArgument(InstanceIdentifierCodecImpl.java:219) ~[bundlefile:na]
at org.opendaylight.yangtools.sal.binding.generator.impl.InstanceIdentifierCodecImpl.deserialize(InstanceIdentifierCodecImpl.java:69) ~[bundlefile:na]
at org.opendaylight.yangtools.sal.binding.generator.impl.RuntimeGeneratedMappingServiceImpl.fromDataDom(RuntimeGeneratedMappingServiceImpl.java:308) ~[bundlefile:na]
... 17 common frames omitted
2014-05-30 01:11:09.385 PDT [pool-12-thread-2] WARN o.o.y.s.b.g.i.RuntimeGeneratedMappingServiceImpl - Failed to deserialize entry (urn:opendaylight:inventory?revision=2013-08-19)nodes/(urn:opendaylight:inventory?revision=2013-08-19)node[

{(urn:opendaylight:inventory?revision=2013-08-19)id=openflow:2}

]/(urn:opendaylight:flow:inventory?revision=2013-08-19)table[

{(urn:opendaylight:flow:inventory?revision=2013-08-19)id=46}

]/(urn:opendaylight:flow:table:statistics?revision=2013-12-15)flow-table-statistics
2014-05-30 01:11:09.386 PDT [pool-12-thread-2] WARN o.o.c.m.s.b.i.AbstractForwardedDataBroker - Failed to transform (urn:opendaylight:inventory?revision=2013-08-19)nodes/(urn:opendaylight:inventory?revision=2013-08-19)node/(urn:opendaylight:inventory?revision=2013-08-19)node[

{(urn:opendaylight:inventory?revision=2013-08-19)id=openflow:2}

]/AugmentationIdentifier

{childNames=[(urn:opendaylight:flow:inventory?revision=2013-08-19)description, (urn:opendaylight:flow:inventory?revision=2013-08-19)group, (urn:opendaylight:flow:inventory?revision=2013-08-19)hardware, (urn:opendaylight:flow:inventory?revision=2013-08-19)manufacturer, (urn:opendaylight:flow:inventory?revision=2013-08-19)meter, (urn:opendaylight:flow:inventory?revision=2013-08-19)serial-number, (urn:opendaylight:flow:inventory?revision=2013-08-19)software, (urn:opendaylight:flow:inventory?revision=2013-08-19)supported-actions, (urn:opendaylight:flow:inventory?revision=2013-08-19)supported-instructions, (urn:opendaylight:flow:inventory?revision=2013-08-19)supported-match-types, (urn:opendaylight:flow:inventory?revision=2013-08-19)switch-features, (urn:opendaylight:flow:inventory?revision=2013-08-19)table]}

/(urn:opendaylight:flow:inventory?revision=2013-08-19)table/(urn:opendaylight:flow:inventory?revision=2013-08-19)table[

{(urn:opendaylight:flow:inventory?revision=2013-08-19)id=46}

]/AugmentationIdentifier

{childNames=[(urn:opendaylight:flow:table:statistics?revision=2013-12-15)flow-table-statistics]}

=ImmutableAugmentationNode{nodeIdentifier=AugmentationIdentifier

{childNames=[(urn:opendaylight:flow:table:statistics?revision=2013-12-15)flow-table-statistics]}

, value=[ImmutableContainerNode{nodeIdentifier=(urn:opendaylight:flow:table:statistics?revision=2013-12-15)flow-table-statistics, value=[ImmutableLeafNode{nodeIdentifier=(urn:opendaylight:flow:table:statistics?revision=2013-12-15)active-flows, value=0, attributes={}}, ImmutableLeafNode{nodeIdentifier=(urn:opendaylight:flow:table:statistics?revision=2013-12-15)packets-matched, value=0, attributes={}}, ImmutableLeafNode{nodeIdentifier=(urn:opendaylight:flow:table:statistics?revision=2013-12-15)packets-looked-up, value=0, attributes={}}], attributes={}}]}, omitting it
org.opendaylight.yangtools.yang.data.impl.codec.DeserializationException: Entry deserialization failed
at org.opendaylight.yangtools.sal.binding.generator.impl.RuntimeGeneratedMappingServiceImpl.fromDataDom(RuntimeGeneratedMappingServiceImpl.java:311) ~[bundlefile:na]
at org.opendaylight.controller.config.yang.md.sal.binding.impl.RuntimeMappingModule$RuntimeGeneratedMappingServiceProxy.fromDataDom(RuntimeMappingModule.java:151) ~[bundlefile:na]
at org.opendaylight.controller.md.sal.binding.impl.BindingToNormalizedNodeCodec.toBindingImpl(BindingToNormalizedNodeCodec.java:202) ~[bundlefile:na]
at org.opendaylight.controller.md.sal.binding.impl.BindingToNormalizedNodeCodec.toBindingAugmented(BindingToNormalizedNodeCodec.java:167) ~[bundlefile:na]
at org.opendaylight.controller.md.sal.binding.impl.BindingToNormalizedNodeCodec.toBinding(BindingToNormalizedNodeCodec.java:130) ~[bundlefile:na]
at org.opendaylight.controller.md.sal.binding.impl.BindingToNormalizedNodeCodec.toBinding(BindingToNormalizedNodeCodec.java:258) ~[bundlefile:na]
at org.opendaylight.controller.md.sal.binding.impl.AbstractForwardedDataBroker.toBinding(AbstractForwardedDataBroker.java:99) ~[bundlefile:na]
at org.opendaylight.controller.md.sal.binding.impl.AbstractForwardedDataBroker$TranslatedDataChangeEvent.getCreatedData(AbstractForwardedDataBroker.java:183) [bundlefile:na]
at org.opendaylight.controller.md.sal.binding.impl.LegacyDataChangeEvent$OperationalChangeEvent.getCreatedOperationalData(LegacyDataChangeEvent.java:108) [bundlefile:na]
at org.opendaylight.controller.md.statistics.manager.FlowCapableTracker.onDataChanged(FlowCapableTracker.java:93) [bundlefile:na]
at org.opendaylight.controller.md.sal.binding.impl.ForwardedBackwardsCompatibleDataBroker$BackwardsCompatibleOperationalDataChangeInvoker.onDataChanged(ForwardedBackwardsCompatibleDataBroker.java:434) [bundlefile:na]
at org.opendaylight.controller.md.sal.binding.impl.AbstractForwardedDataBroker$TranslatingDataChangeInvoker.onDataChanged(AbstractForwardedDataBroker.java:158) [bundlefile:na]
at org.opendaylight.controller.md.sal.dom.store.impl.ChangeListenerNotifyTask.run(ChangeListenerNotifyTask.java:33) [bundlefile:na]
at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:471) [na:1.7.0_51]
at java.util.concurrent.FutureTask.run(FutureTask.java:262) [na:1.7.0_51]
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145) [na:1.7.0_51]
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615) [na:1.7.0_51]
at java.lang.Thread.run(Thread.java:744) [na:1.7.0_51]
Caused by: java.lang.IllegalStateException: Failed to lookup instantiated type for path SchemaPath [path=[(urn:opendaylight:inventory?revision=2013-08-19)nodes, (urn:opendaylight:inventory?revision=2013-08-19)node, (urn:opendaylight:flow:inventory?revision=2013-08-19)table, (urn:opendaylight:flow:table:statistics?revision=2013-12-15)flow-table-statistics], absolute=true]
at com.google.common.base.Preconditions.checkState(Preconditions.java:176) ~[bundlefile:na]
at org.opendaylight.yangtools.sal.binding.generator.impl.LazyGeneratedCodecRegistry.getClassForPath(LazyGeneratedCodecRegistry.java:205) ~[bundlefile:na]
at org.opendaylight.yangtools.sal.binding.generator.impl.InstanceIdentifierCodecImpl.deserializeNodeIdentifier(InstanceIdentifierCodecImpl.java:99) ~[bundlefile:na]
at org.opendaylight.yangtools.sal.binding.generator.impl.InstanceIdentifierCodecImpl.deserializePathArgument(InstanceIdentifierCodecImpl.java:219) ~[bundlefile:na]
at org.opendaylight.yangtools.sal.binding.generator.impl.InstanceIdentifierCodecImpl.deserialize(InstanceIdentifierCodecImpl.java:69) ~[bundlefile:na]
at org.opendaylight.yangtools.sal.binding.generator.impl.RuntimeGeneratedMappingServiceImpl.fromDataDom(RuntimeGeneratedMappingServiceImpl.java:308) ~[bundlefile:na]
... 17 common frames omitted
2014-05-30 01:11:10.963 PDT [pool-29-thread-1] WARN o.o.o.o.md.core.HandshakeManagerImpl - issuing disconnect during handshake, reason: future expired
java.util.concurrent.TimeoutException: Timeout waiting for task.
at com.google.common.util.concurrent.AbstractFuture$Sync.get(AbstractFuture.java:276) ~[bundlefile:na]
at com.google.common.util.concurrent.AbstractFuture.get(AbstractFuture.java:96) ~[bundlefile:na]
at org.opendaylight.openflowplugin.openflow.md.core.HandshakeManagerImpl.postHandshake(HandshakeManagerImpl.java:307) [bundlefile:na]
at org.opendaylight.openflowplugin.openflow.md.core.HandshakeManagerImpl.handleVersionBitmapNegotiation(HandshakeManagerImpl.java:174) [bundlefile:na]
at org.opendaylight.openflowplugin.openflow.md.core.HandshakeManagerImpl.shake(HandshakeManagerImpl.java:101) [bundlefile:na]
at org.opendaylight.openflowplugin.openflow.md.core.HandshakeStepWrapper.run(HandshakeStepWrapper.java:47) [bundlefile:na]
at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:471) [na:1.7.0_51]
at java.util.concurrent.FutureTask.run(FutureTask.java:262) [na:1.7.0_51]
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145) [na:1.7.0_51]
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615) [na:1.7.0_51]
at java.lang.Thread.run(Thread.java:744) [na:1.7.0_51]
2014-05-30 01:11:10.964 PDT [pool-29-thread-1] WARN o.o.o.p.i.c.ResponseExpectedRpcListener - Request for RpcResultKey [xid=5, outputClazz=org.opendaylight.yang.gen.v1.urn.opendaylight.openflow.protocol.rev130731.GetFeaturesOutput] did not receive a response
2014-05-30 01:11:10.964 PDT [pool-29-thread-1] ERROR o.o.o.o.m.c.ErrorHandlerSimpleImpl - exception -> Timeout waiting for task., session -> null
java.util.concurrent.TimeoutException: Timeout waiting for task.
at com.google.common.util.concurrent.AbstractFuture$Sync.get(AbstractFuture.java:276) ~[bundlefile:na]
at com.google.common.util.concurrent.AbstractFuture.get(AbstractFuture.java:96) ~[bundlefile:na]
at org.opendaylight.openflowplugin.openflow.md.core.HandshakeManagerImpl.postHandshake(HandshakeManagerImpl.java:307) ~[bundlefile:na]
at org.opendaylight.openflowplugin.openflow.md.core.HandshakeManagerImpl.handleVersionBitmapNegotiation(HandshakeManagerImpl.java:174) ~[bundlefile:na]
at org.opendaylight.openflowplugin.openflow.md.core.HandshakeManagerImpl.shake(HandshakeManagerImpl.java:101) ~[bundlefile:na]
at org.opendaylight.openflowplugin.openflow.md.core.HandshakeStepWrapper.run(HandshakeStepWrapper.java:47) [bundlefile:na]
at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:471) [na:1.7.0_51]
at java.util.concurrent.FutureTask.run(FutureTask.java:262) [na:1.7.0_51]
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145) [na:1.7.0_51]
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615) [na:1.7.0_51]
at java.lang.Thread.run(Thread.java:744) [na:1.7.0_51]
2014-05-30 01:11:11.107 PDT [nioEventLoopGroup-9-5] INFO o.o.o.p.i.c.PublishingChannelInitializer - Incoming connection from (remote address): /127.0.0.1:42582 --> :6633
2014-05-30 01:11:11.108 PDT [nioEventLoopGroup-9-5] INFO o.o.o.p.i.c.PublishingChannelInitializer - Incoming connection accepted - building pipeline
2014-05-30 01:11:11.110 PDT [nioEventLoopGroup-9-5] INFO o.o.o.p.i.c.ConnectionAdapterImpl - Hello received / branch
2014-05-30 01:11:11.112 PDT [nioEventLoopGroup-9-5] WARN o.o.o.p.i.c.ResponseExpectedRpcListener - Request for RpcResultKey [xid=5, outputClazz=org.opendaylight.yang.gen.v1.urn.opendaylight.openflow.protocol.rev130731.GetFeaturesOutput] did not receive a response

Comment by Abhijit Kumbhare [ 12/Aug/14 ]

Luis,

Changed the bug title to be more general "OSGI errors" rather than the earlier more specific "Two different OSGI errors" - as a catch-all. If you do not like this bug title - you can close this bug about specific errors - and create one for general console errors which prevent the user from understanding what's happening.

I am adding a Trello action item under "To do - day to day actions". For now assigning it to Michal - but we can talk during the meeting and reassign it.

Abhijit

Comment by Luis Gomez [ 13/Aug/14 ]

This one has already improved a lot, I only see one RPC error in the beginning:

osgi> 2014-08-08 09:02:05.862 PDT [nioEventLoopGroup-12-1] INFO o.o.o.p.i.c.PublishingChannelInitializer - Incoming connection from (remote address): /127.0.0.1:38531 --> :6633
2014-08-08 09:02:05.863 PDT [nioEventLoopGroup-12-1] INFO o.o.o.p.i.c.PublishingChannelInitializer - Incoming connection accepted - building pipeline
2014-08-08 09:02:05.891 PDT [nioEventLoopGroup-12-1] WARN o.o.o.o.m.c.s.SessionManagerOFImpl - context for invalidation not found
2014-08-08 09:02:05.898 PDT [OFHandshake-0-0] ERROR o.o.o.o.m.c.ErrorHandlerSimpleImpl - exception -> rpcError:Channel disconnected;, session -> null
java.lang.Exception: rpcError:Channel disconnected;
at org.opendaylight.openflowplugin.openflow.md.core.RpcUtil.smokeRpc(RpcUtil.java:34) ~[bundlefile:na]
at org.opendaylight.openflowplugin.openflow.md.core.HandshakeManagerImpl.sendHelloMessage(HandshakeManagerImpl.java:287) ~[bundlefile:na]
at org.opendaylight.openflowplugin.openflow.md.core.HandshakeManagerImpl.shake(HandshakeManagerImpl.java:94) ~[bundlefile:na]
at org.opendaylight.openflowplugin.openflow.md.core.HandshakeStepWrapper.run(HandshakeStepWrapper.java:47) [bundlefile:na]
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145) [na:1.7.0_51]
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615) [na:1.7.0_51]
at java.lang.Thread.run(Thread.java:744) [na:1.7.0_51]
2014-08-08 09:02:06.197 PDT [nioEventLoopGroup-12-2] INFO o.o.o.p.i.c.PublishingChannelInitializer - Incoming connection from (remote address): /127.0.0.1:38532 --> :6633
2014-08-08 09:02:06.198 PDT [nioEventLoopGroup-12-2] INFO o.o.o.p.i.c.PublishingChannelInitializer - Incoming connection accepted - building pipeline
2014-08-08 09:02:06.212 PDT [nioEventLoopGroup-12-2] INFO o.o.o.p.i.c.ConnectionAdapterImpl - Hello received / branch
2014-08-08 09:02:06.238 PDT [nioEventLoopGroup-12-2] WARN o.o.o.p.i.c.ResponseExpectedRpcListener - Request for RpcResultKey [xid=232, outputClazz=org.opendaylight.yang.gen.v1.urn.opendaylight.openflow.protocol.rev130731.GetFeaturesOutput] did not receive a response
2014-08-08 09:02:06.273 PDT [nioEventLoopGroup-12-3] INFO o.o.o.p.i.c.PublishingChannelInitializer - Incoming connection from (remote address): /127.0.0.1:38533 --> :6633
2014-08-08 09:02:06.274 PDT [nioEventLoopGroup-12-3] INFO o.o.o.p.i.c.PublishingChannelInitializer - Incoming connection accepted - building pipeline
2014-08-08 09:02:06.294 PDT [nioEventLoopGroup-12-3] INFO o.o.o.p.i.c.ConnectionAdapterImpl - Hello received / branch
2014-08-08 09:02:06.322 PDT [nioEventLoopGroup-12-3] WARN o.o.o.p.i.c.ResponseExpectedRpcListener - Request for RpcResultKey [xid=234, outputClazz=org.opendaylight.yang.gen.v1.urn.opendaylight.openflow.protocol.rev130731.GetFeaturesOutput] did not receive a response
2014-08-08 09:02:06.348 PDT [nioEventLoopGroup-12-4] INFO o.o.o.p.i.c.PublishingChannelInitializer - Incoming connection from (remote address): /127.0.0.1:38534 --> :6633
2014-08-08 09:02:06.348 PDT [nioEventLoopGroup-12-4] INFO o.o.o.p.i.c.PublishingChannelInitializer - Incoming connection accepted - building pipeline
2014-08-08 09:02:06.414 PDT [nioEventLoopGroup-12-4] INFO o.o.o.p.i.c.ConnectionAdapterImpl - Hello received / branch
2014-08-08 09:02:06.438 PDT [nioEventLoopGroup-12-4] WARN o.o.o.p.i.c.ResponseExpectedRpcListener - Request for RpcResultKey [xid=234, outputClazz=org.opendaylight.yang.gen.v1.urn.opendaylight.openflow.protocol.rev130731.GetFeaturesOutput] did not receive a response
2014-08-08 09:02:06.680 PDT [md-sal-binding-notification-27] INFO o.o.c.m.s.manager.StatisticsProvider - Attempted to get non-existing handler for Uri [_value=openflow:2]
2014-08-08 09:02:06.680 PDT [md-sal-binding-notification-28] INFO o.o.c.m.s.manager.StatisticsProvider - Attempted to get non-existing handler for Uri [_value=openflow:1]
2014-08-08 09:02:06.731 PDT [md-sal-binding-notification-22] INFO o.o.c.m.s.manager.StatisticsProvider - Attempted to get non-existing handler for Uri [_value=openflow:3]

Comment by Michal Rehak [ 25/Aug/14 ]

The rpc related exception has been changed to warning with explanation that sending hello during handshake failed because of disconnection. Could you retest, please?

OPNFLWPLUG-242

Comment by Luis Gomez [ 08/Sep/14 ]

Yes, this is much better now. I can close this bug.

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