Uploaded image for project: 'ONOS'
  1. ONOS
  2. ONOS-1409

Ping through host intent fails and log show PathNotFoundException

    XMLWordPrintable

    Details

    • Type: Bug
    • Status: Closed (View Workflow)
    • Priority: Major
    • Resolution: Done
    • Affects Version/s: 1.2.0
    • Fix Version/s: 1.2.0
    • Component/s: None
    • Labels:
    • Epic Link:
    • Sprint:
      Cardinal Sprint 1 (3/23-4/3)

      Description

      Steps used:
      1) Bring up 5 ONOS nodes (no reactive fwd enabled)
      2) Install a host intent between h1 and h2 (verify it is moved to FAILED state)
      3) Start a simple 2 switch, 2 host topology from mininet, assign both switches to all 5 nodes.
      4) Verify on ONOS intent is moved to INSTALLED state
      5) Verify that ping between h1 and h2 works as expected.
      6) Now restart the Mininet topology (to create a device disconnect scenario)

      7) Note that intent is still in INSTALLED state even when hosts are gone (Intent should have switched to FAILED state) - This may be another issue.

      8) Now try ping between h1 and h2 only the first ping packet go through and then it stops.

      9) Only ONOS-5 instance logs an exception (see the below logs).
      **************************************************
      ONOS-1 logs
      **********************
      ____ _ ______ ____
      / __ \/ |/ / __ \/ __/
      / // / / // /\ \
      ___//|/__/__/

      Hit '<tab>' for a list of available commands
      and '[cmd] --help' for help on a specific command.
      Hit '<ctrl-d>' or type 'system:shutdown' or 'logout' to shutdown ONOS.

      onos>
      onos>
      onos>
      onos> summary
      node=10.128.40.41, version=1.2.0.admin~2015/03/27@10:56
      nodes=5, devices=0, links=0, hosts=0, SCC(s)=0, flows=0, intents=0
      onos>
      onos> apps | grep openflow

      • id=10, name=org.onosproject.openflow, version=1.2.0, origin=ON.Lab, description=ONOS OpenFlow protocol southbound providers, features=[onos-openflow], featuresRepo=, permissions=[]
        onos>
        onos>
        onos> summary
        node=10.128.40.41, version=1.2.0.admin~2015/03/27@10:56
        nodes=5, devices=2, links=2, hosts=0, SCC(s)=1, flows=6, intents=0
        onos>
        onos> add
        add-flows add-host-intent add-mpls-intent
        add-multi-to-single-intent add-optical-intent add-point-intent
        add-single-to-multi-intent addbundle addfilter
        addregion address-bindings
        onos> masters
        10.128.40.41: 0 devices
        10.128.40.42: 0 devices
        10.128.40.43: 2 devices
        of:0000000000000001
        of:0000000000000002
        10.128.40.44: 0 devices
        10.128.40.45: 0 devices
        onos> add-host-intent 00:00:00:00:00:01/-1 00:00:00:00:00:02/-1
        Host to Host intent submitted:
        HostToHostIntent{id=0x400000, key=0x400000, appId=DefaultApplicationId {id=3, name=org.onosproject.cli}

        , priority=100, resources=[00:00:00:00:00:01/-1, 00:00:00:00:00:02/-1], selector=DefaultTrafficSelector

        {criteria=[]}

        , treatment=DefaultTrafficTreatment

        {immediate=[], deferred=[], transition=None, cleared=false}

        , constraints=[LinkTypeConstraint

        {inclusive=false, types=[OPTICAL]}], one=00:00:00:00:00:01/-1, two=00:00:00:00:00:02/-1}
        onos> intents
        id=0x400000, state=FAILED, key=0x400000, type=HostToHostIntent, appId=org.onosproject.cli
        resources=[00:00:00:00:00:01/-1, 00:00:00:00:00:02/-1]
        constraints=[LinkTypeConstraint{inclusive=false, types=[OPTICAL]}

        ]
        host1=00:00:00:00:00:01/-1, host2=00:00:00:00:00:02/-1
        onos>
        onos> intents
        id=0x400000, state=INSTALLING, key=0x400000, type=HostToHostIntent, appId=org.onosproject.cli
        resources=[00:00:00:00:00:01/-1, 00:00:00:00:00:02/-1]
        constraints=[LinkTypeConstraint

        {inclusive=false, types=[OPTICAL]}]
        host1=00:00:00:00:00:01/-1, host2=00:00:00:00:00:02/-1
        onos>
        onos> masters
        10.128.40.41: 0 devices
        10.128.40.42: 0 devices
        10.128.40.43: 2 devices
        of:0000000000000001
        of:0000000000000002
        10.128.40.44: 0 devices
        10.128.40.45: 0 devices
        onos> summary
        node=10.128.40.41, version=1.2.0.admin~2015/03/27@10:56
        nodes=5, devices=2, links=2, hosts=2, SCC(s)=1, flows=10, intents=1
        onos>
        onos> summary
        node=10.128.40.41, version=1.2.0.admin~2015/03/27@10:56
        nodes=5, devices=2, links=2, hosts=2, SCC(s)=1, flows=10, intents=1
        onos>
        onos>
        onos>
        onos> summary
        node=10.128.40.41, version=1.2.0.admin~2015/03/27@10:56
        nodes=5, devices=2, links=0, hosts=1, SCC(s)=1, flows=5, intents=1
        onos>
        onos> masters
        10.128.40.41: 0 devices
        10.128.40.42: 0 devices
        10.128.40.43: 1 devices
        of:0000000000000002
        10.128.40.44: 0 devices
        10.128.40.45: 0 devices
        onos>
        onos> intents
        id=0x400000, state=INSTALLED, key=0x400000, type=HostToHostIntent, appId=org.onosproject.cli
        resources=[00:00:00:00:00:01/-1, 00:00:00:00:00:02/-1]
        constraints=[LinkTypeConstraint{inclusive=false, types=[OPTICAL]}

        ]
        host1=00:00:00:00:00:01/-1, host2=00:00:00:00:00:02/-1
        onos> masters
        10.128.40.41: 0 devices
        10.128.40.42: 0 devices
        10.128.40.43: 0 devices
        10.128.40.44: 0 devices
        10.128.40.45: 0 devices
        onos> intents
        id=0x400000, state=INSTALLED, key=0x400000, type=HostToHostIntent, appId=org.onosproject.cli
        resources=[00:00:00:00:00:01/-1, 00:00:00:00:00:02/-1]
        constraints=[LinkTypeConstraint

        {inclusive=false, types=[OPTICAL]}]
        host1=00:00:00:00:00:01/-1, host2=00:00:00:00:00:02/-1
        onos>
        onos> intents
        id=0x400000, state=INSTALLED, key=0x400000, type=HostToHostIntent, appId=org.onosproject.cli
        resources=[00:00:00:00:00:01/-1, 00:00:00:00:00:02/-1]
        constraints=[LinkTypeConstraint{inclusive=false, types=[OPTICAL]}

        ]
        host1=00:00:00:00:00:01/-1, host2=00:00:00:00:00:02/-1
        onos> masters
        10.128.40.41: 1 devices
        of:0000000000000002
        10.128.40.42: 0 devices
        10.128.40.43: 0 devices
        10.128.40.44: 1 devices
        of:0000000000000001
        10.128.40.45: 0 devices
        onos>
        onos> intents
        id=0x400000, state=INSTALLED, key=0x400000, type=HostToHostIntent, appId=org.onosproject.cli
        resources=[00:00:00:00:00:01/-1, 00:00:00:00:00:02/-1]
        constraints=[LinkTypeConstraint

        {inclusive=false, types=[OPTICAL]}]
        host1=00:00:00:00:00:01/-1, host2=00:00:00:00:00:02/-1
        onos>
        onos> summary
        node=10.128.40.41, version=1.2.0.admin~2015/03/27@10:56
        nodes=5, devices=2, links=2, hosts=0, SCC(s)=1, flows=10, intents=1
        onos>
        onos> summary
        node=10.128.40.41, version=1.2.0.admin~2015/03/27@10:56
        nodes=5, devices=2, links=0, hosts=1, SCC(s)=1, flows=5, intents=1
        onos> summary
        node=10.128.40.41, version=1.2.0.admin~2015/03/27@10:56
        nodes=5, devices=2, links=0, hosts=0, SCC(s)=0, flows=0, intents=1
        onos> summary
        node=10.128.40.41, version=1.2.0.admin~2015/03/27@10:56
        nodes=5, devices=2, links=0, hosts=0, SCC(s)=0, flows=0, intents=1
        onos> intents
        id=0x400000, state=INSTALLED, key=0x400000, type=HostToHostIntent, appId=org.onosproject.cli
        resources=[00:00:00:00:00:01/-1, 00:00:00:00:00:02/-1]
        constraints=[LinkTypeConstraint{inclusive=false, types=[OPTICAL]}

        ]
        host1=00:00:00:00:00:01/-1, host2=00:00:00:00:00:02/-1
        onos>
        onos>
        onos> summary
        node=10.128.40.41, version=1.2.0.admin~2015/03/27@10:56
        nodes=5, devices=2, links=0, hosts=0, SCC(s)=0, flows=0, intents=1
        onos>
        onos> masters
        10.128.40.41: 0 devices
        10.128.40.42: 0 devices
        10.128.40.43: 0 devices
        10.128.40.44: 0 devices
        10.128.40.45: 0 devices
        onos> masters
        10.128.40.41: 0 devices
        10.128.40.42: 0 devices
        10.128.40.43: 2 devices
        of:0000000000000001
        of:0000000000000002
        10.128.40.44: 0 devices
        10.128.40.45: 0 devices
        onos> summary
        node=10.128.40.41, version=1.2.0.admin~2015/03/27@10:56
        nodes=5, devices=2, links=2, hosts=0, SCC(s)=1, flows=10, intents=1
        onos>
        onos> intents
        id=0x400000, state=INSTALLED, key=0x400000, type=HostToHostIntent, appId=org.onosproject.cli
        resources=[00:00:00:00:00:01/-1, 00:00:00:00:00:02/-1]
        constraints=[LinkTypeConstraint

        {inclusive=false, types=[OPTICAL]}

        ]
        host1=00:00:00:00:00:01/-1, host2=00:00:00:00:00:02/-1
        onos>
        onos> log:display | grep Exception
        2015-03-27 10:58:04,135 | WARN | FelixStartLevel | OSGiScriptEngineManager | 54 - com.hazelcast - 3.4.0 | Found ScriptEngineFactory candidate for com.sun.script.javascript.RhinoScriptEngineFactory, but cannot load class! -> java.lang.ClassNotFoundException: com.sun.script.javascript.RhinoScriptEngineFactory not found by com.hazelcast [54]
        onos>

      ##################ONOS-5 LOGS################
      2015-03-27 11:08:06,517 | WARN | ending-publish-6 | BoundedThreadPool | 74 - org.onosproject.onlab-misc - 1.2.0.SNAPSHOT | queue size: 0 jobs, submitted: 0.025565639780135496 jobs/s, taken: 0.012782819890067748 jobs/s
      2015-03-27 11:08:06,517 | WARN | nos-intent-batch | IntentManager | 77 - org.onosproject.onos-core-net - 1.2.0.SNAPSHOT | Future failed: {}
      java.util.concurrent.ExecutionException: PathNotFoundException

      {source=00:00:00:00:00:01/-1, destination=00:00:00:00:00:02/-1}

      at java.util.concurrent.FutureTask.report(FutureTask.java:122)[:1.8.0_25]
      at java.util.concurrent.FutureTask.get(FutureTask.java:192)[:1.8.0_25]
      at org.onosproject.net.intent.impl.IntentManager$IntentBatchProcess.waitForFutures(IntentManager.java:329)[77:org.onosproject.onos-core-net:1.2.0.SNAPSHOT]
      at org.onosproject.net.intent.impl.IntentManager$IntentBatchProcess.run(IntentManager.java:304)[77:org.onosproject.onos-core-net:1.2.0.SNAPSHOT]
      at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)[:1.8.0_25]
      at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)[:1.8.0_25]
      at java.lang.Thread.run(Thread.java:745)[:1.8.0_25]
      Caused by: PathNotFoundException

      {source=00:00:00:00:00:01/-1, destination=00:00:00:00:00:02/-1}

      at org.onosproject.net.intent.impl.compiler.ConnectivityIntentCompiler.getPath(ConnectivityIntentCompiler.java:109)[77:org.onosproject.onos-core-net:1.2.0.SNAPSHOT]
      at org.onosproject.net.intent.impl.compiler.HostToHostIntentCompiler.compile(HostToHostIntentCompiler.java:67)[77:org.onosproject.onos-core-net:1.2.0.SNAPSHOT]
      at org.onosproject.net.intent.impl.compiler.HostToHostIntentCompiler.compile(HostToHostIntentCompiler.java:46)[77:org.onosproject.onos-core-net:1.2.0.SNAPSHOT]
      at org.onosproject.net.intent.impl.CompilerRegistry.compile(CompilerRegistry.java:81)[77:org.onosproject.onos-core-net:1.2.0.SNAPSHOT]
      at org.onosproject.net.intent.impl.IntentManager$InternalIntentProcessor.compile(IntentManager.java:358)[77:org.onosproject.onos-core-net:1.2.0.SNAPSHOT]
      at org.onosproject.net.intent.impl.phase.Recompiling.execute(Recompiling.java:51)[77:org.onosproject.onos-core-net:1.2.0.SNAPSHOT]
      at org.onosproject.net.intent.impl.phase.IntentWorker.call(IntentWorker.java:48)[77:org.onosproject.onos-core-net:1.2.0.SNAPSHOT]
      at org.onosproject.net.intent.impl.phase.IntentWorker.call(IntentWorker.java:27)[77:org.onosproject.onos-core-net:1.2.0.SNAPSHOT]
      at java.util.concurrent.FutureTask.run(FutureTask.java:266)[:1.8.0_25]
      ... 3 more
      2015-03-27 11:08:06,543 | WARN | urrent-publish-1 | BoundedThreadPool | 74 - org.onosproject.onlab-misc - 1.2.0.SNAPSHOT | queue size: 0 jobs, submitted: 0.016914177463549947 jobs/s, taken: 0.008457088731774974 jobs/s
      2015-03-27 11:08:13,290 | WARN | urrent-publish-3 | BoundedThreadPool | 74 - org.onosproject.onlab-misc - 1.2.0.SNAPSHOT | queue size: 0 jobs, submitted: 0.592856084185564 jobs/s, taken: 0.296428042092782 jobs/s

      onos>

        Attachments

        # Subject Branch Project Status CR V

          Activity

            People

            Assignee:
            hari hari
            Reporter:
            hari hari
            Votes:
            0 Vote for this issue
            Watchers:
            1 Start watching this issue

              Dates

              Created:
              Updated:
              Resolved: