[OPNFLWPLUG-815] [Li Plugin]A little switches were not found in the topology after the odl controller connecting 300 switches(mininet) Created: 09/Nov/16  Updated: 27/Sep/21  Resolved: 03/Jul/17

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

Type: Bug
Reporter: yunyunhan Assignee: yunyunhan
Resolution: Done Votes: 0
Labels: None
Remaining Estimate: Not Specified
Time Spent: Not Specified
Original Estimate: Not Specified
Environment:

Operating System: All
Platform: All


External issue ID: 7130

 Description   

A little switches were not found in the topology after the odl controller connecting 300 switches(mininet).
Print all the time in the log:
2016-11-07 19:03:55,483 | INFO | entLoopGroup-7-4 | DeviceManagerImpl | 284 - org.opendaylight.openflowplugin.impl - 0.2.4.SNAPSHOT | ConnectionEvent: Device connected to controller, Device:/10.42.94.101:45358, NodeId:Uri [_value=openflow:187]
2016-11-07 19:03:55,483 | INFO | entLoopGroup-7-4 | StatisticsManagerImpl | 284 - org.opendaylight.openflowplugin.impl - 0.2.4.SNAPSHOT | Schedule Statistics poll for node:Uri [_value=openflow:187]
2016-11-07 19:03:55,484 | INFO | entLoopGroup-7-4 | SalRoleServiceImpl | 284 - org.opendaylight.openflowplugin.impl - 0.2.4.SNAPSHOT | SetRole called with input:SetRoleInput [_controllerRole=BECOMESLAVE, _node=NodeRef [_value=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:187]]]]}

], augmentation=[]]
2016-11-07 19:03:55,484 | INFO | entLoopGroup-7-4 | SalRoleServiceImpl | 284 - org.opendaylight.openflowplugin.impl - 0.2.4.SNAPSHOT | Requesting state change to BECOMESLAVE
2016-11-07 19:03:55,484 | INFO | entLoopGroup-7-4 | SalRoleServiceImpl | 284 - org.opendaylight.openflowplugin.impl - 0.2.4.SNAPSHOT | RoleChangeTask called on device:openflow:187 OFPRole:BECOMESLAVE
2016-11-07 19:03:55,484 | INFO | entLoopGroup-7-4 | RoleService | 284 - org.opendaylight.openflowplugin.impl - 0.2.4.SNAPSHOT | getGenerationIdFromDevice called for device:openflow:187
2016-11-07 19:03:55,484 | INFO | entLoopGroup-7-4 | RoleContextImpl | 284 - org.opendaylight.openflowplugin.impl - 0.2.4.SNAPSHOT | Initialization main candidate for node Uri [_value=openflow:187]
2016-11-07 19:03:55,484 | WARN | entLoopGroup-7-4 | RoleContextImpl | 284 - org.opendaylight.openflowplugin.impl - 0.2.4.SNAPSHOT | Candidate for entity openflow is already registered.
2016-11-07 19:03:55,484 | WARN | entLoopGroup-7-4 | LifecycleConductorImpl | 284 - org.opendaylight.openflowplugin.impl - 0.2.4.SNAPSHOT | Initialization phase for node Uri [_value=openflow:187] in role context was NOT successful, closing connection.
2016-11-07 19:03:55,484 | INFO | entLoopGroup-7-4 | ConnectionContextImpl | 284 - org.opendaylight.openflowplugin.impl - 0.2.4.SNAPSHOT | Unregistering outbound queue successful.
2016-11-07 19:03:55,484 | INFO | entLoopGroup-7-4 | RpcManagerImpl | 284 - org.opendaylight.openflowplugin.impl - 0.2.4.SNAPSHOT | Unregister RPCs services for device context closure
2016-11-07 19:03:55,484 | INFO | entLoopGroup-7-4 | RoleContextImpl | 284 - org.opendaylight.openflowplugin.impl - 0.2.4.SNAPSHOT | Role context closed, unregistering all candidates for ownership for node Uri [_value=openflow:187]
2016-11-07 19:03:55,485 | INFO | entLoopGroup-7-4 | HandshakeListenerImpl | 284 - org.opendaylight.openflowplugin.impl - 0.2.4.SNAPSHOT | ConnectionContext initial processing failed: null
2016-11-07 19:03:55,485 | WARN | pool-34-thread-1 | RoleManagerImpl | 284 - org.opendaylight.openflowplugin.impl - 0.2.4.SNAPSHOT | New role BECOMESLAVE was not propagated to device Uri [_value=openflow:187] during 10 sec
2016-11-07 19:03:55,485 | INFO | entLoopGroup-7-4 | ConnectionContextImpl | 284 - org.opendaylight.openflowplugin.impl - 0.2.4.SNAPSHOT | Unregistering outbound queue successful.
2016-11-07 19:03:55,485 | INFO | entLoopGroup-7-4 | DeviceManagerImpl | 284 - org.opendaylight.openflowplugin.impl - 0.2.4.SNAPSHOT | DeviceContext for Node Uri [_value=openflow:187] was not found. Connection is terminated without OFP context suite.
2016-11-07 19:03:55,485 | WARN | pool-34-thread-1 | SalRoleServiceImpl | 284 - org.opendaylight.openflowplugin.impl - 0.2.4.SNAPSHOT | SetRoleService set Role BECOMESLAVE for Node: 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:187]]]]}

fail.
java.util.concurrent.CancellationException: Task was cancelled.
at com.google.common.util.concurrent.AbstractFuture.cancellationExceptionWithCause(AbstractFuture.java:392)[63:com.google.guava:18.0.0]
at com.google.common.util.concurrent.AbstractFuture$Sync.getValue(AbstractFuture.java:306)[63:com.google.guava:18.0.0]
at com.google.common.util.concurrent.AbstractFuture$Sync.get(AbstractFuture.java:286)[63:com.google.guava:18.0.0]
at com.google.common.util.concurrent.AbstractFuture.get(AbstractFuture.java:116)[63:com.google.guava:18.0.0]
at com.google.common.util.concurrent.Uninterruptibles.getUninterruptibly(Uninterruptibles.java:137)[63:com.google.guava:18.0.0]
at com.google.common.util.concurrent.Futures$6.run(Futures.java:1308)[63:com.google.guava:18.0.0]
at com.google.common.util.concurrent.MoreExecutors$DirectExecutor.execute(MoreExecutors.java:457)[63:com.google.guava:18.0.0]
at com.google.common.util.concurrent.ExecutionList.executeListener(ExecutionList.java:156)[63:com.google.guava:18.0.0]
at com.google.common.util.concurrent.ExecutionList.execute(ExecutionList.java:145)[63:com.google.guava:18.0.0]
at com.google.common.util.concurrent.AbstractFuture.cancel(AbstractFuture.java:134)[63:com.google.guava:18.0.0]
at org.opendaylight.openflowplugin.impl.role.RoleManagerImpl$2.run(RoleManagerImpl.java:308)[284:org.opendaylight.openflowplugin.impl:0.2.4.SNAPSHOT]
at io.netty.util.HashedWheelTimer$HashedWheelTimeout.expire(HashedWheelTimer.java:581)[123:io.netty.common:4.0.37.Final]
at io.netty.util.HashedWheelTimer$HashedWheelBucket.expireTimeouts(HashedWheelTimer.java:655)[123:io.netty.common:4.0.37.Final]
at io.netty.util.HashedWheelTimer$Worker.run(HashedWheelTimer.java:367)[123:io.netty.common:4.0.37.Final]
at java.lang.Thread.run(Thread.java:745)[:1.8.0_91]
Caused by: java.util.concurrent.CancellationException: Future.cancel() was called.
at com.google.common.util.concurrent.AbstractFuture$Sync.complete(AbstractFuture.java:378)[63:com.google.guava:18.0.0]
at com.google.common.util.concurrent.AbstractFuture$Sync.cancel(AbstractFuture.java:355)[63:com.google.guava:18.0.0]
at com.google.common.util.concurrent.AbstractFuture.cancel(AbstractFuture.java:131)[63:com.google.guava:18.0.0]
... 5 more
2016-11-07 19:03:55,485 | WARN | pool-34-thread-1 | RoleManagerImpl | 284 - org.opendaylight.openflowplugin.impl - 0.2.4.SNAPSHOT | Unable to set role BECOMESLAVE on device Uri [_value=openflow:187]
2016-11-07 19:03:55,486 | WARN | pool-34-thread-1 | LifecycleConductorImpl | 284 - org.opendaylight.openflowplugin.impl - 0.2.4.SNAPSHOT | Something went wrong, device context for nodeId: {} doesn't exists



 Comments   
Comment by Tomas Slusny [ 28/Jun/17 ]

Can you try this again after this patch was merged: https://git.opendaylight.org/gerrit/#/c/57814/?

Comment by yunyunhan [ 29/Jun/17 ]

(In reply to Tomas Slusny from comment #1)
> Can you try this again after this patch was merged:
> https://git.opendaylight.org/gerrit/#/c/57814/?

I've been busy with my work recently. I will give you an answer this weekend.

Comment by yunyunhan [ 01/Jul/17 ]

(In reply to Tomas Slusny from comment #1)
> Can you try this again after this patch was merged:
> https://git.opendaylight.org/gerrit/#/c/57814/?

I have tested that for hours, and the result has always been fine.

Comment by Tomas Slusny [ 03/Jul/17 ]

Okay then, looks like this issue is resolved. Closing

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