Uploaded image for project: 'OpenFlowPlugin'
  1. OpenFlowPlugin
  2. OPNFLWPLUG-383

OFFrameDecoder | 314 - org.opendaylight.openflowjava.openflow-protocol-impl - 0.6.0.SNAPSHOT | Unexpected exception from downstream.

    XMLWordPrintable

Details

    • Bug
    • Status: Resolved
    • Resolution: Cannot Reproduce
    • None
    • None
    • General
    • None
    • Operating System: All
      Platform: All

    • 2879

    Description

      Using build from Fri, Mar/20/15

      When trying to connect to OF node, I hit the following exception:

      https://gist.github.com/e2a8301c8b2c49188c41

      2015-03-20 08:40:31,673 | INFO | entLoopGroup-8-1 | ConnectionAdapterImpl | 314 - org.opendaylight.openflowjava.openflow-protocol-impl - 0.6.0.SNAPSHOT | Hello received / branch
      2015-03-20 08:40:31,677 | WARN | entLoopGroup-8-1 | OFFrameDecoder | 314 - org.opendaylight.openflowjava.openflow-protocol-impl - 0.6.0.SNAPSHOT | Unexpected exception from downstream.
      java.io.IOException: Connection reset by peer
      at sun.nio.ch.FileDispatcherImpl.read0(Native Method)[:1.7.0_67]
      at sun.nio.ch.SocketDispatcher.read(SocketDispatcher.java:39)[:1.7.0_67]
      at sun.nio.ch.IOUtil.readIntoNativeBuffer(IOUtil.java:223)[:1.7.0_67]
      at sun.nio.ch.IOUtil.read(IOUtil.java:192)[:1.7.0_67]
      at sun.nio.ch.SocketChannelImpl.read(SocketChannelImpl.java:379)[:1.7.0_67]
      at io.netty.buffer.PooledUnsafeDirectByteBuf.setBytes(PooledUnsafeDirectByteBuf.java:311)[170:io.netty.buffer:4.0.24.Final]
      at io.netty.buffer.AbstractByteBuf.writeBytes(AbstractByteBuf.java:881)[170:io.netty.buffer:4.0.24.Final]
      at io.netty.channel.socket.nio.NioSocketChannel.doReadBytes(NioSocketChannel.java:225)[168:io.netty.transport:4.0.24.Final]
      at io.netty.channel.nio.AbstractNioByteChannel$NioByteUnsafe.read(AbstractNioByteChannel.java:119)[168:io.netty.transport:4.0.24.Final]
      at io.netty.channel.nio.NioEventLoop.processSelectedKey(NioEventLoop.java:511)[168:io.netty.transport:4.0.24.Final]
      at io.netty.channel.nio.NioEventLoop.processSelectedKeysOptimized(NioEventLoop.java:468)[168:io.netty.transport:4.0.24.Final]
      at io.netty.channel.nio.NioEventLoop.processSelectedKeys(NioEventLoop.java:382)[168:io.netty.transport:4.0.24.Final]
      at io.netty.channel.nio.NioEventLoop.run(NioEventLoop.java:349)[168:io.netty.transport:4.0.24.Final]
      at io.netty.util.concurrent.SingleThreadEventExecutor$2.run(SingleThreadEventExecutor.java:116)[169:io.netty.common:4.0.24.Final]
      at io.netty.util.concurrent.DefaultThreadFactory$DefaultRunnableDecorator.run(DefaultThreadFactory.java:137)[169:io.netty.common:4.0.24.Final]
      at java.lang.Thread.run(Thread.java:745)[:1.7.0_67]
      2015-03-20 08:40:31,678 | WARN | entLoopGroup-8-1 | OFFrameDecoder | 314 - org.opendaylight.openflowjava.openflow-protocol-impl - 0.6.0.SNAPSHOT | Closing connection.
      2015-03-20 08:40:31,683 | WARN | entLoopGroup-8-1 | SessionManagerOFImpl | 316 - org.opendaylight.openflowplugin - 0.1.0.SNAPSHOT | context for invalidation not found
      2015-03-20 08:40:32,667 | INFO | entLoopGroup-8-2 | ConnectionAdapterImpl | 314 - org.opendaylight.openflowjava.openflow-protocol-impl - 0.6.0.SNAPSHOT | Hello received / branch
      2015-03-20 08:40:32,688 | WARN | OFHandshake-0-0 | HandshakeManagerImpl | 316 - org.opendaylight.openflowplugin - 0.1.0.SNAPSHOT | issuing disconnect during handshake, reason - RPC: rpcError:Channel disconnected;
      java.lang.Exception: rpcError:Channel disconnected;
      at org.opendaylight.openflowplugin.openflow.md.core.RpcUtil.smokeRpc(RpcUtil.java:34)[316:org.opendaylight.openflowplugin:0.1.0.SNAPSHOT]
      at org.opendaylight.openflowplugin.openflow.md.core.HandshakeManagerImpl.postHandshake(HandshakeManagerImpl.java:326)[316:org.opendaylight.openflowplugin:0.1.0.SNAPSHOT]
      at org.opendaylight.openflowplugin.openflow.md.core.HandshakeManagerImpl.handleVersionBitmapNegotiation(HandshakeManagerImpl.java:189)[316:org.opendaylight.openflowplugin:0.1.0.SNAPSHOT]
      at org.opendaylight.openflowplugin.openflow.md.core.HandshakeManagerImpl.shake(HandshakeManagerImpl.java:115)[316:org.opendaylight.openflowplugin:0.1.0.SNAPSHOT]
      at org.opendaylight.openflowplugin.openflow.md.core.HandshakeStepWrapper.run(HandshakeStepWrapper.java:48)[316:org.opendaylight.openflowplugin:0.1.0.SNAPSHOT]
      at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:471)[:1.7.0_67]
      at java.util.concurrent.FutureTask.run(FutureTask.java:262)[:1.7.0_67]
      at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145)[:1.7.0_67]
      at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)[:1.7.0_67]
      at java.lang.Thread.run(Thread.java:745)[:1.7.0_67]
      2015-03-20 08:40:32,689 | INFO | OFHandshake-0-0 | ConnectionConductorImpl | 316 - org.opendaylight.openflowplugin - 0.1.0.SNAPSHOT | OF handshake failed, doing cleanup.
      2015-03-20 08:40:32,812 | INFO | DOM-OPER-DCL-0 | lowCapableNodeDataChangeListener | 355 - org.opendaylight.ovsdb.openstack.net-virt-providers - 1.1.0.SNAPSHOT | >>>>> created iiD: InstanceIdentifier

      {targetType=interface org.opendaylight.yang.gen.v1.urn.opendaylight.flow.inventory.rev130819.FlowCapableNode, 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:38340636234561]]], org.opendaylight.yang.gen.v1.urn.opendaylight.flow.inventory.rev130819.FlowCapableNode]}

      - first: 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:38340636234561]]]]}

      - NodeKey: openflow:38340636234561
      2015-03-20 08:40:32,813 | INFO | DOM-OPER-DCL-0 | lowCapableNodeDataChangeListener | 355 - org.opendaylight.ovsdb.openstack.net-virt-providers - 1.1.0.SNAPSHOT | notifyNodeCreated: Node openflow:38340636234561 from Controller's inventory Service
      2015-03-20 08:40:32,813 | INFO | DOM-OPER-DCL-0 | PipelineOrchestratorImpl | 355 - org.opendaylight.ovsdb.openstack.net-virt-providers - 1.1.0.SNAPSHOT | >>>>> enqueue: openflow:38340636234561
      2015-03-20 08:40:32,813 | INFO | ol-1049-thread-1 | PipelineOrchestratorImpl | 355 - org.opendaylight.ovsdb.openstack.net-virt-providers - 1.1.0.SNAPSHOT | >>>>> dequeue: openflow:38340636234561
      2015-03-20 08:40:32,815 | INFO | ol-1035-thread-1 | SouthboundHandler | 354 - org.opendaylight.ovsdb.openstack.net-virt - 1.1.0.SNAPSHOT | notifyNode: Node Node [_id=Uri [_value=openflow:38340636234561], _key=NodeKey [_id=Uri [_value=openflow:38340636234561]], augmentation=[]] update ADD from Controller's inventory Service
      2015-03-20 08:40:32,816 | ERROR | ol-1035-thread-1 | OF13Provider | 355 - org.opendaylight.ovsdb.openstack.net-virt-providers - 1.1.0.SNAPSHOT | Unable to spot Bridge Identifier for br-ex in Node [_id=Uri [_value=OVS|192.168.50.21:51445], _key=NodeKey [_id=Uri [_value=OVS|192.168.50.21:51445]], augmentation=[]]
      2015-03-20 08:40:33,693 | ERROR | OFHandshake-0-0 | ErrorHandlerSimpleImpl | 316 - org.opendaylight.openflowplugin - 0.1.0.SNAPSHOT | exception -> rpcError:Channel disconnected;, session -> null
      java.lang.Exception: rpcError:Channel disconnected;
      at org.opendaylight.openflowplugin.openflow.md.core.RpcUtil.smokeRpc(RpcUtil.java:34)[316:org.opendaylight.openflowplugin:0.1.0.SNAPSHOT]
      at org.opendaylight.openflowplugin.openflow.md.core.HandshakeManagerImpl.postHandshake(HandshakeManagerImpl.java:326)[316:org.opendaylight.openflowplugin:0.1.0.SNAPSHOT]
      at org.opendaylight.openflowplugin.openflow.md.core.HandshakeManagerImpl.handleVersionBitmapNegotiation(HandshakeManagerImpl.java:189)[316:org.opendaylight.openflowplugin:0.1.0.SNAPSHOT]
      at org.opendaylight.openflowplugin.openflow.md.core.HandshakeManagerImpl.shake(HandshakeManagerImpl.java:115)[316:org.opendaylight.openflowplugin:0.1.0.SNAPSHOT]
      at org.opendaylight.openflowplugin.openflow.md.core.HandshakeStepWrapper.run(HandshakeStepWrapper.java:48)[316:org.opendaylight.openflowplugin:0.1.0.SNAPSHOT]
      at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:471)[:1.7.0_67]
      at java.util.concurrent.FutureTask.run(FutureTask.java:262)[:1.7.0_67]
      at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145)[:1.7.0_67]
      at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)[:1.7.0_67]
      at java.lang.Thread.run(Thread.java:745)[:1.7.0_67]
      2015-03-20 08:40:33,694 | INFO | OFHandshake-0-0 | ConnectionConductorImpl | 316 - org.opendaylight.openflowplugin - 0.1.0.SNAPSHOT | OF handshake failed, doing cleanup.

      Attachments

        No reviews matched the request. Check your Options in the drop-down menu of this sections header.

        Activity

          People

            mbobak@cisco.com Martin Bobak
            flavio@flaviof.com Flavio Fernandes
            Votes:
            0 Vote for this issue
            Watchers:
            0 Start watching this issue

            Dates

              Created:
              Updated:
              Resolved: