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

NPE and devices reset on ONOS after a DHCP discover from host

    XMLWordPrintable

    Details

    • Type: Bug
    • Status: Closed (View Workflow)
    • Priority: Major
    • Resolution: Done
    • Affects Version/s: None
    • Fix Version/s: 1.3.0
    • Component/s: None
    • Labels:

      Description

      Not sure if this will fall under Core or NB. Setting this to Core for now.
      **********************************************
      Logging in as karaf
      398 [sshd-SshClient[55a1c291]-nio2-thread-2] WARN org.apache.sshd.client.keyverifier.AcceptAllServerKeyVerifier - Server at [localhost/127.0.0.1:8101, DSA, 24:4f:43:d0:3c:49:e8:35:5d:2d:50:87:bf:70:bc:07] presented unverified {} key: {}
      Welcome to Open Network Operating System (ONOS)!
      ____ _ ______ ____
      / __ \/ |/ / __ \/ __/
      / // / / // /\ \
      ___//|/__/__/

      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> masters
      10.128.20.11: 0 devices
      10.128.20.12: 0 devices
      10.128.20.13: 2 devices
      of:0000000000000003
      of:0000000000000004
      onos>
      onos> ^C
      onos> ^D

      sdn@suibin-test-dev-onos01:~$
      sdn@suibin-test-dev-onos01:~$ exit
      logout
      There are stopped jobs.
      sdn@suibin-test-dev-onos01:~$ exit
      logout
      Connection to 10.128.20.11 closed.
      haris-mbp-2:~ harikrishna$ ssh sdn@10.128.20.13
      Welcome to Ubuntu 14.04.1 LTS (GNU/Linux 2.6.32-39-pve x86_64)

      • Documentation: https://help.ubuntu.com/
        Last login: Thu Sep 10 16:39:22 2015 from 10.128.10.11
        sdn@Suibin-TestDevOnos3:~$
        sdn@Suibin-TestDevOnos3:~$ /opt/onos/bin/onos
        Logging in as karaf
        384 [sshd-SshClient[55a1c291]-nio2-thread-2] WARN org.apache.sshd.client.keyverifier.AcceptAllServerKeyVerifier - Server at [localhost/127.0.0.1:8101, DSA, 35:1d:f4:9e:93:e7:ba:17:5a:ea:9b:78:d2:ab:46:67] presented unverified {} key: {}
        Welcome to Open Network Operating System (ONOS)!
        ____ _ ______ ____
        / __ \/ |/ / __ \/ __/
        / // / / // /\ \
        ___//|/__/__/

      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> summary
      node=10.128.20.13, version=1.3.0.admin~2015/09/10@13:38
      nodes=3, devices=2, links=2, hosts=0, SCC(s)=1, flows=6, intents=0
      onos> app activate org.onosproject.
      org.onosproject.aaa org.onosproject.acl org.onosproject.bgprouter
      org.onosproject.cip org.onosproject.config org.onosproject.cordfabric
      org.onosproject.demo org.onosproject.dhcp org.onosproject.distributedprimitives
      org.onosproject.election org.onosproject.flowanalyzer org.onosproject.fwd
      org.onosproject.intentperf org.onosproject.messagingperf org.onosproject.metrics
      org.onosproject.netconf org.onosproject.null org.onosproject.olt
      org.onosproject.optical org.onosproject.ovsdb org.onosproject.pcep
      org.onosproject.reactive.routing org.onosproject.sdnip org.onosproject.segmentrouting
      org.onosproject.virtualbng org.onosproject.vtn org.onosproject.vtnrsc
      org.onosproject.vtnweb org.onosproject.xosintegration
      onos> app activate org.onosproject.fwd
      onos> app activate org.onosproject.dhcp
      onos> summary
      node=10.128.20.13, version=1.3.0.admin~2015/09/10@13:38
      nodes=3, devices=2, links=2, hosts=0, SCC(s)=1, flows=12, intents=0
      onos>
      onos>
      onos> flows
      deviceId=of:0000000000000003, flowRuleCount=6
      id=200004643c1d4, state=ADDED, bytes=4212, packets=52, duration=160, priority=40000, tableId=0 appId=org.onosproject.core, payLoad=null
      selector=[ETH_TYPE

      {ethType=lldp}]
      treatment=DefaultTrafficTreatment{immediate=[OUTPUT{port=CONTROLLER}], deferred=[], transition=None, cleared=false, metadata=null}
      id=200004643d01e, state=ADDED, bytes=4212, packets=52, duration=160, priority=40000, tableId=0 appId=org.onosproject.core, payLoad=null
      selector=[ETH_TYPE{ethType=bddp}]
      treatment=DefaultTrafficTreatment{immediate=[OUTPUT{port=CONTROLLER}]
      , deferred=[], transition=None, cleared=false, metadata=null}
      id=2000046532920, state=ADDED, bytes=0, packets=0, duration=5, priority=5, tableId=0 appId=org.onosproject.core, payLoad=null
      selector=[ETH_TYPE{ethType=ipv4}]
      treatment=DefaultTrafficTreatment{immediate=[OUTPUT{port=CONTROLLER}], deferred=[], transition=None, cleared=false, metadata=null}
      id=20000465329da, state=ADDED, bytes=0, packets=0, duration=5, priority=5, tableId=0 appId=org.onosproject.core, payLoad=null
      selector=[ETH_TYPE{ethType=arp}]
      treatment=DefaultTrafficTreatment{immediate=[OUTPUT{port=CONTROLLER}]
      , deferred=[], transition=None, cleared=false, metadata=null}
      id=20000465329da, state=ADDED, bytes=0, packets=0, duration=1, priority=40000, tableId=0 appId=org.onosproject.core, payLoad=null
      selector=[ETH_TYPE{ethType=arp}]
      treatment=DefaultTrafficTreatment{immediate=[OUTPUT{port=CONTROLLER}], deferred=[], transition=None, cleared=false, metadata=null}
      id=200004655328e, state=ADDED, bytes=0, packets=0, duration=1, priority=40000, tableId=0 appId=org.onosproject.core, payLoad=null
      selector=[ETH_TYPE{ethType=ipv4}, IP_PROTO{protocol=17}, UDP_DST{udpPort=67}, UDP_SRC{udpPort=68}]

      treatment=DefaultTrafficTreatment{immediate=[OUTPUT{port=CONTROLLER}], deferred=[], transition=None, cleared=false, metadata=null}
      deviceId=of:0000000000000004, flowRuleCount=6
      id=2000046443633, state=ADDED, bytes=4212, packets=52, duration=160, priority=40000, tableId=0 appId=org.onosproject.core, payLoad=null
      selector=[ETH_TYPE{ethType=lldp}

      ]
      treatment=DefaultTrafficTreatment{immediate=[OUTPUT

      {port=CONTROLLER}], deferred=[], transition=None, cleared=false, metadata=null}
      id=200004644447d, state=ADDED, bytes=4212, packets=52, duration=160, priority=40000, tableId=0 appId=org.onosproject.core, payLoad=null
      selector=[ETH_TYPE{ethType=bddp}]
      treatment=DefaultTrafficTreatment{immediate=[OUTPUT{port=CONTROLLER}

      ], deferred=[], transition=None, cleared=false, metadata=null}
      id=2000046539d7f, state=ADDED, bytes=0, packets=0, duration=5, priority=5, tableId=0 appId=org.onosproject.core, payLoad=null
      selector=[ETH_TYPE

      {ethType=ipv4}]
      treatment=DefaultTrafficTreatment{immediate=[OUTPUT{port=CONTROLLER}], deferred=[], transition=None, cleared=false, metadata=null}
      id=2000046539e39, state=ADDED, bytes=0, packets=0, duration=5, priority=5, tableId=0 appId=org.onosproject.core, payLoad=null
      selector=[ETH_TYPE{ethType=arp}]
      treatment=DefaultTrafficTreatment{immediate=[OUTPUT{port=CONTROLLER}]
      , deferred=[], transition=None, cleared=false, metadata=null}
      id=2000046539e39, state=ADDED, bytes=0, packets=0, duration=1, priority=40000, tableId=0 appId=org.onosproject.core, payLoad=null
      selector=[ETH_TYPE{ethType=arp}]
      treatment=DefaultTrafficTreatment{immediate=[OUTPUT{port=CONTROLLER}], deferred=[], transition=None, cleared=false, metadata=null}
      id=200004655a6ed, state=ADDED, bytes=0, packets=0, duration=1, priority=40000, tableId=0 appId=org.onosproject.core, payLoad=null
      selector=[ETH_TYPE{ethType=ipv4}

      , IP_PROTO

      {protocol=17}

      , UDP_DST

      {udpPort=67}

      , UDP_SRC

      {udpPort=68}

      ]
      treatment=DefaultTrafficTreatment{immediate=[OUTPUT

      {port=CONTROLLER}

      ], deferred=[], transition=None, cleared=false, metadata=null}
      onos>
      onos> nodes
      id=10.128.20.11, address=10.128.20.11:9876, state=ACTIVE, updated=3m ago
      id=10.128.20.12, address=10.128.20.12:9876, state=ACTIVE, updated=3m ago
      id=10.128.20.13, address=10.128.20.13:9876, state=ACTIVE, updated=3m ago *
      onos> masters
      10.128.20.11: 0 devices
      10.128.20.12: 0 devices
      10.128.20.13: 2 devices
      of:0000000000000003
      of:0000000000000004
      onos>
      onos> roles
      of:0000000000000003: master=10.128.20.13, standbys=[ 10.128.20.11 10.128.20.12 ]
      of:0000000000000004: master=10.128.20.13, standbys=[ 10.128.20.12 10.128.20.11 ]
      onos>
      onos> devices
      id=of:0000000000000003, available=true, role=MASTER, type=SWITCH, mfr=Nicira, Inc., hw=Open vSwitch, sw=2.3.0, serial=None, protocol=OF_13, channelId=10.128.10.11:40308
      id=of:0000000000000004, available=true, role=MASTER, type=SWITCH, mfr=Nicira, Inc., hw=Open vSwitch, sw=2.3.0, serial=None, protocol=OF_13, channelId=10.128.10.11:40311
      onos> log:exception-display
      onos>
      onos>
      onos> log:exception-display
      java.lang.NullPointerException: IP Address must be specified
      at com.google.common.base.Preconditions.checkNotNull(Preconditions.java:226)
      at org.onosproject.dhcp.IpAssignment$Builder.validateInputs(IpAssignment.java:191)
      at org.onosproject.dhcp.IpAssignment$Builder.build(IpAssignment.java:163)
      at org.onosproject.dhcp.impl.DistributedDhcpStore.suggestIP(DistributedDhcpStore.java:164)
      at org.onosproject.dhcp.impl.DhcpManager$DHCPPacketProcessor.processDHCPPacket(DhcpManager.java:445)
      at org.onosproject.dhcp.impl.DhcpManager$DHCPPacketProcessor.process(DhcpManager.java:560)
      at org.onosproject.net.packet.impl.PacketManager$InternalPacketProviderService.processPacket(PacketManager.java:284)
      at org.onosproject.provider.of.packet.impl.OpenFlowPacketProvider$InternalPacketProvider.handlePacket(OpenFlowPacketProvider.java:170)
      at org.onosproject.openflow.controller.impl.OpenFlowControllerImpl.processPacket(OpenFlowControllerImpl.java:282)
      at org.onosproject.openflow.controller.impl.OpenFlowControllerImpl$OpenFlowSwitchAgent.processMessage(OpenFlowControllerImpl.java:606)
      at org.onosproject.openflow.controller.driver.AbstractOpenFlowSwitch.handleMessage(AbstractOpenFlowSwitch.java:203)
      at org.onosproject.openflow.controller.impl.OFChannelHandler.dispatchMessage(OFChannelHandler.java:1185)
      at org.onosproject.openflow.controller.impl.OFChannelHandler.access$1800(OFChannelHandler.java:81)
      at org.onosproject.openflow.controller.impl.OFChannelHandler$ChannelState$8.processOFPacketIn(OFChannelHandler.java:636)
      at org.onosproject.openflow.controller.impl.OFChannelHandler$ChannelState.processOFMessage(OFChannelHandler.java:884)
      at org.onosproject.openflow.controller.impl.OFChannelHandler.messageReceived(OFChannelHandler.java:1166)
      at org.jboss.netty.handler.timeout.IdleStateAwareChannelHandler.handleUpstream(IdleStateAwareChannelHandler.java:36)[45:org.jboss.netty:3.9.2.Final]
      at org.jboss.netty.handler.timeout.ReadTimeoutHandler.messageReceived(ReadTimeoutHandler.java:184)[45:org.jboss.netty:3.9.2.Final]
      at org.jboss.netty.handler.timeout.IdleStateHandler.messageReceived(IdleStateHandler.java:294)[45:org.jboss.netty:3.9.2.Final]
      at org.jboss.netty.channel.Channels.fireMessageReceived(Channels.java:296)[45:org.jboss.netty:3.9.2.Final]
      at org.jboss.netty.handler.codec.frame.FrameDecoder.unfoldAndFireMessageReceived(FrameDecoder.java:462)[45:org.jboss.netty:3.9.2.Final]
      at org.jboss.netty.handler.codec.frame.FrameDecoder.callDecode(FrameDecoder.java:443)[45:org.jboss.netty:3.9.2.Final]
      at org.jboss.netty.handler.codec.frame.FrameDecoder.messageReceived(FrameDecoder.java:303)[45:org.jboss.netty:3.9.2.Final]
      at org.jboss.netty.channel.Channels.fireMessageReceived(Channels.java:268)[45:org.jboss.netty:3.9.2.Final]
      at org.jboss.netty.channel.Channels.fireMessageReceived(Channels.java:255)[45:org.jboss.netty:3.9.2.Final]
      at org.jboss.netty.channel.socket.nio.NioWorker.read(NioWorker.java:88)[45:org.jboss.netty:3.9.2.Final]
      at org.jboss.netty.channel.socket.nio.AbstractNioWorker.process(AbstractNioWorker.java:108)[45:org.jboss.netty:3.9.2.Final]
      at org.jboss.netty.channel.socket.nio.AbstractNioSelector.run(AbstractNioSelector.java:318)
      at org.jboss.netty.channel.socket.nio.AbstractNioWorker.run(AbstractNioWorker.java:89)[45:org.jboss.netty:3.9.2.Final]
      at org.jboss.netty.channel.socket.nio.NioWorker.run(NioWorker.java:178)[45:org.jboss.netty:3.9.2.Final]
      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]

      onos> masters
      10.128.20.11: 1 devices
      of:0000000000000003
      10.128.20.12: 0 devices
      10.128.20.13: 1 devices
      of:0000000000000004
      onos> summary
      node=10.128.20.13, version=1.3.0.admin~2015/09/10@13:38
      nodes=3, devices=2, links=2, hosts=0, SCC(s)=1, flows=12, intents=0
      onos> log:display
      2015-09-10 16:38:49,827 | INFO | event-dispatch-0 | CoreEventDispatcher | 77 - org.onosproject.onos-core-net - 1.3.0.SNAPSHOT | Dispatch loop initiated
      2015-09-10 16:38:49,828 | INFO | FelixStartLevel | CoreEventDispatcher | 77 - org.onosproject.onos-core-net - 1.3.0.SNAPSHOT | Started
      2015-09-10 16:38:49,938 | INFO | FelixStartLevel | CodecManager | 78 - org.onosproject.onos-core-common - 1.3.0.SNAPSHOT | Started
      2015-09-10 16:38:50,205 | INFO | FelixStartLevel | ClusterDefinitionManager | 79 - org.onosproject.onos-core-dist - 1.3.0.SNAPSHOT | Started
      2015-09-10 16:38:50,228 | INFO | FelixStartLevel | NettyMessagingManager | 79 - org.onosproject.onos-core-dist - 1.3.0.SNAPSHOT | enableNettyTLS = false
      2015-09-10 16:38:50,322 | INFO | FelixStartLevel | NettyMessagingManager | 79 - org.onosproject.onos-core-dist - 1.3.0.SNAPSHOT | Started
      2015-09-10 16:38:50,326 | INFO | entLoopGroup-4-1 | NettyMessagingManager | 81 - org.onosproject.onlab-netty - 1.3.0.SNAPSHOT | 10.128.20.13 accepting incoming connections on port 9876
      2015-09-10 16:38:50,539 | INFO | FelixStartLevel | DistributedClusterStore | 79 - org.onosproject.onos-core-dist - 1.3.0.SNAPSHOT | Started
      2015-09-10 16:38:50,971 | INFO | FelixStartLevel | ApiDocManager | 140 - org.onosproject.onos-rest - 1.3.0.SNAPSHOT | Started
      2015-09-10 16:38:51,139 | INFO | FelixStartLevel | HttpServiceFactoryImpl | 115 - org.ops4j.pax.web.pax-web-runtime - 3.1.4 | Binding bundle: [org.onosproject.onos-rest [140]] to http service
      2015-09-10 16:38:51,363 | INFO | FelixStartLevel | HttpServiceFactoryImpl | 115 - org.ops4j.pax.web.pax-web-runtime - 3.1.4 | Binding bundle: [org.onosproject.onos-gui [158]] to http service
      2015-09-10 16:38:51,515 | INFO | pool-8-thread-1 | WebApplicationImpl | 134 - com.sun.jersey.jersey-server - 1.19.0 | Initiating Jersey application, version 'Jersey: 1.19 02/11/2015 03:25 AM'
      2015-09-10 16:38:51,517 | INFO | rint Extender: 2 | BlueprintContainerImpl | 15 - org.apache.aries.blueprint.core - 1.4.2 | Bundle org.apache.karaf.kar.command is waiting for dependencies [(objectClass=org.apache.karaf.kar.KarService)]
      2015-09-10 16:38:51,519 | INFO | pool-8-thread-2 | WebApplicationImpl | 134 - com.sun.jersey.jersey-server - 1.19.0 | Initiating Jersey application, version 'Jersey: 1.19 02/11/2015 03:25 AM'
      2015-09-10 16:38:51,951 | INFO | FelixStartLevel | ClusterManager | 77 - org.onosproject.onos-core-net - 1.3.0.SNAPSHOT | Started
      2015-09-10 16:38:51,954 | INFO | FelixStartLevel | ClusterCommunicationManager | 79 - org.onosproject.onos-core-dist - 1.3.0.SNAPSHOT | Started
      2015-09-10 16:38:51,970 | INFO | FelixStartLevel | DatabaseManager | 79 - org.onosproject.onos-core-dist - 1.3.0.SNAPSHOT | Loading database definition: /opt/onos/apache-karaf-3.0.3/../config/tablets.json
      2015-09-10 16:38:52,659 | INFO | ycat-coordinator | RaftContext | 71 - org.onosproject.onlab-thirdparty - 1.3.0.SNAPSHOT | onos://10.128.20.13:9876 - Transitioning to FOLLOWER
      2015-09-10 16:38:52,698 | INFO | pool-8-thread-2 | WebApplicationImpl | 134 - com.sun.jersey.jersey-server - 1.19.0 | Initiating Jersey application, version 'Jersey: 1.19 02/11/2015 03:25 AM'
      2015-09-10 16:38:52,755 | WARN | pool-8-thread-1 | Errors | 133 - com.sun.jersey.core - 1.19.0 | The following warnings have been detected with resource and/or provider classes:
      WARNING: A sub-resource method, public javax.ws.rs.core.Response org.onosproject.rest.resources.ApiDocResource.getDefault() throws java.io.IOException,java.net.URISyntaxException, with URI template, "/", is treated as a resource method
      2015-09-10 16:38:52,924 | INFO | pool-8-thread-2 | WebApplicationImpl | 134 - com.sun.jersey.jersey-server - 1.19.0 | Initiating Jersey application, version 'Jersey: 1.19 02/11/2015 03:25 AM'
      2015-09-10 16:38:53,066 | INFO | pool-8-thread-2 | WebApplicationImpl | 134 - com.sun.jersey.jersey-server - 1.19.0 | Initiating Jersey application, version 'Jersey: 1.19 02/11/2015 03:25 AM'
      2015-09-10 16:38:53,208 | INFO | pool-8-thread-2 | WebApplicationImpl | 134 - com.sun.jersey.jersey-server - 1.19.0 | Initiating Jersey application, version 'Jersey: 1.19 02/11/2015 03:25 AM'
      2015-09-10 16:38:56,946 | INFO | copycat-p0-1 | RaftContext | 71 - org.onosproject.onlab-thirdparty - 1.3.0.SNAPSHOT | onos://10.128.20.13:9876 - Transitioning to FOLLOWER
      2015-09-10 16:39:01,198 | INFO | copycat-p1-1 | RaftContext | 71 - org.onosproject.onlab-thirdparty - 1.3.0.SNAPSHOT | onos://10.128.20.13:9876 - Transitioning to FOLLOWER
      2015-09-10 16:39:06,082 | INFO | copycat-p1-1 | FollowerState | 71 - org.onosproject.onlab-thirdparty - 1.3.0.SNAPSHOT | onos://10.128.20.13:9876 - Heartbeat timed out in 4884 milliseconds
      2015-09-10 16:39:06,084 | INFO | copycat-p1-1 | FollowerState | 71 - org.onosproject.onlab-thirdparty - 1.3.0.SNAPSHOT | onos://10.128.20.13:9876 - Polling members [onos://10.128.20.12:9876, onos://10.128.20.11:9876, onos://10.128.20.13:9876]
      2015-09-10 16:39:06,100 | INFO | copycat-p1-1 | FollowerState | 71 - org.onosproject.onlab-thirdparty - 1.3.0.SNAPSHOT | onos://10.128.20.13:9876 - Received accepted poll from onos://10.128.20.13:9876
      2015-09-10 16:39:06,106 | INFO | copycat-p1-1 | FollowerState | 71 - org.onosproject.onlab-thirdparty - 1.3.0.SNAPSHOT | onos://10.128.20.13:9876 - Received accepted poll from onos://10.128.20.12:9876
      2015-09-10 16:39:06,106 | INFO | copycat-p1-1 | RaftContext | 71 - org.onosproject.onlab-thirdparty - 1.3.0.SNAPSHOT | onos://10.128.20.13:9876 - Transitioning to CANDIDATE
      2015-09-10 16:39:06,109 | INFO | copycat-p1-1 | CandidateState | 71 - org.onosproject.onlab-thirdparty - 1.3.0.SNAPSHOT | onos://10.128.20.13:9876 - Starting election
      2015-09-10 16:39:06,112 | INFO | copycat-p1-1 | CandidateState | 71 - org.onosproject.onlab-thirdparty - 1.3.0.SNAPSHOT | onos://10.128.20.13:9876 - Requesting votes from [onos://10.128.20.12:9876, onos://10.128.20.11:9876, onos://10.128.20.13:9876]
      2015-09-10 16:39:06,116 | INFO | copycat-p1-1 | CandidateState | 71 - org.onosproject.onlab-thirdparty - 1.3.0.SNAPSHOT | onos://10.128.20.13:9876 - Received successful vote from onos://10.128.20.13:9876
      2015-09-10 16:39:06,116 | INFO | copycat-p1-1 | CandidateState | 71 - org.onosproject.onlab-thirdparty - 1.3.0.SNAPSHOT | onos://10.128.20.13:9876 - Received successful vote from onos://10.128.20.12:9876
      2015-09-10 16:39:06,116 | INFO | copycat-p1-1 | RaftContext | 71 - org.onosproject.onlab-thirdparty - 1.3.0.SNAPSHOT | onos://10.128.20.13:9876 - Transitioning to LEADER
      2015-09-10 16:39:06,126 | INFO | copycat-p2-1 | RaftContext | 71 - org.onosproject.onlab-thirdparty - 1.3.0.SNAPSHOT | onos://10.128.20.13:9876 - Transitioning to FOLLOWER
      2015-09-10 16:39:09,202 | INFO | copycat-p3-1 | RaftContext | 71 - org.onosproject.onlab-thirdparty - 1.3.0.SNAPSHOT | onos://10.128.20.13:9876 - Transitioning to FOLLOWER
      2015-09-10 16:39:14,250 | INFO | copycat-p3-1 | FollowerState | 71 - org.onosproject.onlab-thirdparty - 1.3.0.SNAPSHOT | onos://10.128.20.13:9876 - Heartbeat timed out in 5048 milliseconds
      2015-09-10 16:39:14,251 | INFO | copycat-p3-1 | FollowerState | 71 - org.onosproject.onlab-thirdparty - 1.3.0.SNAPSHOT | onos://10.128.20.13:9876 - Polling members [onos://10.128.20.12:9876, onos://10.128.20.11:9876, onos://10.128.20.13:9876]
      2015-09-10 16:39:14,260 | INFO | copycat-p3-1 | FollowerState | 71 - org.onosproject.onlab-thirdparty - 1.3.0.SNAPSHOT | onos://10.128.20.13:9876 - Received accepted poll from onos://10.128.20.13:9876
      2015-09-10 16:39:14,270 | INFO | copycat-p3-1 | FollowerState | 71 - org.onosproject.onlab-thirdparty - 1.3.0.SNAPSHOT | onos://10.128.20.13:9876 - Received accepted poll from onos://10.128.20.12:9876
      2015-09-10 16:39:14,270 | INFO | copycat-p3-1 | RaftContext | 71 - org.onosproject.onlab-thirdparty - 1.3.0.SNAPSHOT | onos://10.128.20.13:9876 - Transitioning to CANDIDATE
      2015-09-10 16:39:14,270 | INFO | copycat-p3-1 | CandidateState | 71 - org.onosproject.onlab-thirdparty - 1.3.0.SNAPSHOT | onos://10.128.20.13:9876 - Starting election
      2015-09-10 16:39:14,271 | INFO | copycat-p3-1 | CandidateState | 71 - org.onosproject.onlab-thirdparty - 1.3.0.SNAPSHOT | onos://10.128.20.13:9876 - Requesting votes from [onos://10.128.20.12:9876, onos://10.128.20.11:9876, onos://10.128.20.13:9876]
      2015-09-10 16:39:14,271 | INFO | copycat-p3-1 | CandidateState | 71 - org.onosproject.onlab-thirdparty - 1.3.0.SNAPSHOT | onos://10.128.20.13:9876 - Received successful vote from onos://10.128.20.13:9876
      2015-09-10 16:39:14,272 | INFO | copycat-p3-1 | CandidateState | 71 - org.onosproject.onlab-thirdparty - 1.3.0.SNAPSHOT | onos://10.128.20.13:9876 - Received successful vote from onos://10.128.20.12:9876
      2015-09-10 16:39:14,272 | INFO | copycat-p3-1 | RaftContext | 71 - org.onosproject.onlab-thirdparty - 1.3.0.SNAPSHOT | onos://10.128.20.13:9876 - Transitioning to LEADER
      2015-09-10 16:39:14,273 | INFO | at-coordinator-1 | DatabaseManager | 79 - org.onosproject.onos-core-dist - 1.3.0.SNAPSHOT | Successfully initialized database.
      2015-09-10 16:39:14,300 | INFO | FelixStartLevel | DatabaseManager | 79 - org.onosproject.onos-core-dist - 1.3.0.SNAPSHOT | Started
      2015-09-10 16:39:14,303 | INFO | FelixStartLevel | LogicalClockManager | 79 - org.onosproject.onos-core-dist - 1.3.0.SNAPSHOT | Started
      2015-09-10 16:39:14,340 | INFO | FelixStartLevel | ConsistentApplicationIdStore | 79 - org.onosproject.onos-core-dist - 1.3.0.SNAPSHOT | Started
      2015-09-10 16:39:14,346 | INFO | FelixStartLevel | DistributedFlowObjectiveStore | 79 - org.onosproject.onos-core-dist - 1.3.0.SNAPSHOT | Started
      2015-09-10 16:39:14,385 | INFO | FelixStartLevel | DistributedNetworkConfigStore | 79 - org.onosproject.onos-core-dist - 1.3.0.SNAPSHOT | Started
      2015-09-10 16:39:14,387 | INFO | FelixStartLevel | NetworkConfigManager | 77 - org.onosproject.onos-core-net - 1.3.0.SNAPSHOT | Started
      2015-09-10 16:39:14,391 | INFO | FelixStartLevel | ExtraNetworkConfigs | 82 - org.onosproject.onos-incubator-net - 1.3.0.SNAPSHOT | Started
      2015-09-10 16:39:14,404 | INFO | FelixStartLevel | InterfaceManager | 82 - org.onosproject.onos-incubator-net - 1.3.0.SNAPSHOT | Started
      2015-09-10 16:39:14,437 | INFO | FelixStartLevel | GossipComponentConfigStore | 79 - org.onosproject.onos-core-dist - 1.3.0.SNAPSHOT | Started
      2015-09-10 16:39:14,439 | INFO | FelixStartLevel | ComponentConfigManager | 77 - org.onosproject.onos-core-net - 1.3.0.SNAPSHOT | Started
      2015-09-10 16:39:14,455 | INFO | FelixStartLevel | ECHostStore | 79 - org.onosproject.onos-core-dist - 1.3.0.SNAPSHOT | Started
      2015-09-10 16:39:14,461 | INFO | FelixStartLevel | ConsistentIdBlockStore | 79 - org.onosproject.onos-core-dist - 1.3.0.SNAPSHOT | Started
      2015-09-10 16:39:14,820 | WARN | copycat-p0-1 | FollowerState | 71 - org.onosproject.onlab-thirdparty - 1.3.0.SNAPSHOT | onos://10.128.20.13:9876 - Rejected AppendRequest[term=1, leader=onos://10.128.20.11:9876, logIndex=1, logTerm=1, entries=[0], firstIndex=false, commitIndex=null]: Previous index (1) is greater than the local log's last index (null)
      2015-09-10 16:39:14,923 | INFO | FelixStartLevel | IntentDomainManager | 82 - org.onosproject.onos-incubator-net - 1.3.0.SNAPSHOT | Started
      2015-09-10 16:39:14,929 | INFO | FelixStartLevel | BasicNetworkConfigs | 77 - org.onosproject.onos-core-net - 1.3.0.SNAPSHOT | Started
      2015-09-10 16:39:14,975 | INFO | FelixStartLevel | GossipApplicationStore | 79 - org.onosproject.onos-core-dist - 1.3.0.SNAPSHOT | Started
      2015-09-10 16:39:15,370 | INFO | -app-states-fg-0 | ApplicationManager | 77 - org.onosproject.onos-core-net - 1.3.0.SNAPSHOT | Application org.onosproject.mobility has been installed
      2015-09-10 16:39:15,382 | INFO | FelixStartLevel | FeaturesServiceImpl | 20 - org.apache.karaf.features.core - 3.0.3 | Installing feature onos-app-mobility 1.3.0-SNAPSHOT
      2015-09-10 16:39:15,382 | INFO | FelixStartLevel | FeaturesServiceImpl | 20 - org.apache.karaf.features.core - 3.0.3 | Installing feature onos-api 1.3.0-SNAPSHOT
      2015-09-10 16:39:15,382 | INFO | FelixStartLevel | FeaturesServiceImpl | 20 - org.apache.karaf.features.core - 3.0.3 | Installing feature scr 3.0.3
      2015-09-10 16:39:15,399 | INFO | FelixStartLevel | FeaturesServiceImpl | 20 - org.apache.karaf.features.core - 3.0.3 | Installing feature onos-thirdparty-base 1.3.0-SNAPSHOT
      2015-09-10 16:39:15,527 | INFO | FelixStartLevel | FeaturesServiceImpl | 20 - org.apache.karaf.features.core - 3.0.3 | Installing feature scr-condition-management_0_0_0 3.0.3
      2015-09-10 16:39:15,529 | INFO | FelixStartLevel | FeaturesServiceImpl | 20 - org.apache.karaf.features.core - 3.0.3 | Installing feature scr-condition-webconsole_0_0_0 3.0.3
      2015-09-10 16:39:15,531 | INFO | FelixStartLevel | FeaturesServiceImpl | 20 - org.apache.karaf.features.core - 3.0.3 | Installing feature webconsole-condition-scr_0_0_0 3.0.3
      2015-09-10 16:39:15,533 | INFO | FelixStartLevel | FeaturesServiceImpl | 20 - org.apache.karaf.features.core - 3.0.3 | Installing feature standard-condition-webconsole_0_0_0 3.0.3
      2015-09-10 16:39:15,577 | INFO | FelixStartLevel | InstancePlugin | 160 - org.apache.karaf.webconsole.instance - 3.0.3 | Instance plugin activated
      2015-09-10 16:39:15,584 | INFO | FelixStartLevel | HttpServiceFactoryImpl | 115 - org.ops4j.pax.web.pax-web-runtime - 3.1.4 | Binding bundle: [org.apache.karaf.webconsole.instance [160]] to http service
      2015-09-10 16:39:15,606 | INFO | FelixStartLevel | FeaturesPlugin | 161 - org.apache.karaf.webconsole.features - 3.0.3 | Features plugin activated
      2015-09-10 16:39:15,610 | INFO | FelixStartLevel | HttpServiceFactoryImpl | 115 - org.ops4j.pax.web.pax-web-runtime - 3.1.4 | Binding bundle: [org.apache.karaf.webconsole.features [161]] to http service
      2015-09-10 16:39:15,639 | INFO | FelixStartLevel | GogoPlugin | 162 - org.apache.karaf.webconsole.gogo - 3.0.3 | Gogo plugin activated
      2015-09-10 16:39:15,643 | INFO | FelixStartLevel | HttpServiceFactoryImpl | 115 - org.ops4j.pax.web.pax-web-runtime - 3.1.4 | Binding bundle: [org.apache.karaf.webconsole.gogo [162]] to http service
      2015-09-10 16:39:15,663 | INFO | FelixStartLevel | HttpPlugin | 163 - org.apache.karaf.webconsole.http - 3.0.3 | Http plugin activated
      2015-09-10 16:39:15,665 | WARN | FelixStartLevel | ServletTracker | 120 - org.ops4j.pax.web.pax-web-extender-whiteboard - 3.1.4 | Registered servlet [org.apache.karaf.webconsole.http.HttpPlugin@81b4158] did not contain a valid alias or url patterns property
      2015-09-10 16:39:15,679 | INFO | FelixStartLevel | ApplicationManager | 77 - org.onosproject.onos-core-net - 1.3.0.SNAPSHOT | Application org.onosproject.mobility has been activated
      2015-09-10 16:39:15,692 | INFO | -app-states-fg-7 | ApplicationManager | 77 - org.onosproject.onos-core-net - 1.3.0.SNAPSHOT | Application org.onosproject.xosintegration has been installed
      2015-09-10 16:39:15,694 | INFO | -app-states-fg-1 | ApplicationManager | 77 - org.onosproject.onos-core-net - 1.3.0.SNAPSHOT | Application org.onosproject.vtn has been installed
      2015-09-10 16:39:15,704 | INFO | -app-states-fg-1 | ApplicationManager | 77 - org.onosproject.onos-core-net - 1.3.0.SNAPSHOT | Application org.onosproject.distributedprimitives has been installed
      2015-09-10 16:39:15,710 | INFO | -app-states-fg-6 | ApplicationManager | 77 - org.onosproject.onos-core-net - 1.3.0.SNAPSHOT | Application org.onosproject.messagingperf has been installed
      2015-09-10 16:39:15,718 | INFO | -app-states-fg-7 | ApplicationManager | 77 - org.onosproject.onos-core-net - 1.3.0.SNAPSHOT | Application org.onosproject.vtnrsc has been installed
      2015-09-10 16:39:15,779 | INFO | -app-states-fg-2 | ApplicationManager | 77 - org.onosproject.onos-core-net - 1.3.0.SNAPSHOT | Application org.onosproject.proxyarp has been installed
      2015-09-10 16:39:15,780 | INFO | -app-states-fg-0 | FeaturesServiceImpl | 20 - org.apache.karaf.features.core - 3.0.3 | Installing feature onos-app-proxyarp 1.3.0-SNAPSHOT
      2015-09-10 16:39:15,780 | INFO | -app-states-fg-0 | FeaturesServiceImpl | 20 - org.apache.karaf.features.core - 3.0.3 | Installing feature onos-api 1.3.0-SNAPSHOT
      2015-09-10 16:39:15,780 | INFO | -app-states-fg-0 | FeaturesServiceImpl | 20 - org.apache.karaf.features.core - 3.0.3 | Installing feature scr 3.0.3
      2015-09-10 16:39:15,784 | INFO | -app-states-fg-0 | FeaturesServiceImpl | 20 - org.apache.karaf.features.core - 3.0.3 | Installing feature onos-thirdparty-base 1.3.0-SNAPSHOT
      2015-09-10 16:39:15,840 | INFO | -app-states-fg-0 | FeaturesServiceImpl | 20 - org.apache.karaf.features.core - 3.0.3 | Installing feature scr-condition-management_0_0_0 3.0.3
      2015-09-10 16:39:15,842 | INFO | -app-states-fg-0 | FeaturesServiceImpl | 20 - org.apache.karaf.features.core - 3.0.3 | Installing feature scr-condition-webconsole_0_0_0 3.0.3
      2015-09-10 16:39:15,843 | INFO | -app-states-fg-0 | FeaturesServiceImpl | 20 - org.apache.karaf.features.core - 3.0.3 | Installing feature webconsole-condition-scr_0_0_0 3.0.3
      2015-09-10 16:39:15,845 | INFO | -app-states-fg-0 | FeaturesServiceImpl | 20 - org.apache.karaf.features.core - 3.0.3 | Installing feature standard-condition-webconsole_0_0_0 3.0.3
      2015-09-10 16:39:15,853 | INFO | -app-states-fg-0 | ApplicationManager | 77 - org.onosproject.onos-core-net - 1.3.0.SNAPSHOT | Application org.onosproject.proxyarp has been activated
      2015-09-10 16:39:15,940 | INFO | -app-states-fg-2 | ApplicationManager | 77 - org.onosproject.onos-core-net - 1.3.0.SNAPSHOT | Application org.onosproject.election has been installed
      2015-09-10 16:39:15,988 | INFO | -app-states-fg-1 | ApplicationManager | 77 - org.onosproject.onos-core-net - 1.3.0.SNAPSHOT | Application org.onosproject.bgprouter has been installed
      2015-09-10 16:39:16,059 | INFO | -app-states-fg-7 | ApplicationManager | 77 - org.onosproject.onos-core-net - 1.3.0.SNAPSHOT | Application org.onosproject.fwd has been installed
      2015-09-10 16:39:16,107 | INFO | -app-states-fg-1 | ApplicationManager | 77 - org.onosproject.onos-core-net - 1.3.0.SNAPSHOT | Application org.onosproject.dhcp has been installed
      2015-09-10 16:39:16,143 | INFO | -app-states-fg-5 | ApplicationManager | 77 - org.onosproject.onos-core-net - 1.3.0.SNAPSHOT | Application org.onosproject.virtualbng has been installed
      2015-09-10 16:39:16,199 | INFO | -app-states-fg-6 | ApplicationManager | 77 - org.onosproject.onos-core-net - 1.3.0.SNAPSHOT | Application org.onosproject.intentperf has been installed
      2015-09-10 16:39:16,260 | INFO | -app-states-fg-0 | ApplicationManager | 77 - org.onosproject.onos-core-net - 1.3.0.SNAPSHOT | Application org.onosproject.ovsdb has been installed
      2015-09-10 16:39:16,339 | INFO | -app-states-fg-3 | ApplicationManager | 77 - org.onosproject.onos-core-net - 1.3.0.SNAPSHOT | Application org.onosproject.drivers has been installed
      2015-09-10 16:39:16,341 | INFO | -app-states-fg-0 | FeaturesServiceImpl | 20 - org.apache.karaf.features.core - 3.0.3 | Installing feature onos-drivers 1.3.0-SNAPSHOT
      2015-09-10 16:39:16,341 | INFO | -app-states-fg-0 | FeaturesServiceImpl | 20 - org.apache.karaf.features.core - 3.0.3 | Installing feature onos-api 1.3.0-SNAPSHOT
      2015-09-10 16:39:16,341 | INFO | -app-states-fg-0 | FeaturesServiceImpl | 20 - org.apache.karaf.features.core - 3.0.3 | Installing feature scr 3.0.3
      2015-09-10 16:39:16,346 | INFO | -app-states-fg-0 | FeaturesServiceImpl | 20 - org.apache.karaf.features.core - 3.0.3 | Installing feature onos-thirdparty-base 1.3.0-SNAPSHOT
      2015-09-10 16:39:16,468 | INFO | -app-states-fg-0 | FeaturesServiceImpl | 20 - org.apache.karaf.features.core - 3.0.3 | Installing feature scr-condition-management_0_0_0 3.0.3
      2015-09-10 16:39:16,470 | INFO | -app-states-fg-0 | FeaturesServiceImpl | 20 - org.apache.karaf.features.core - 3.0.3 | Installing feature scr-condition-webconsole_0_0_0 3.0.3
      2015-09-10 16:39:16,471 | INFO | -app-states-fg-0 | FeaturesServiceImpl | 20 - org.apache.karaf.features.core - 3.0.3 | Installing feature webconsole-condition-scr_0_0_0 3.0.3
      2015-09-10 16:39:16,473 | INFO | -app-states-fg-0 | FeaturesServiceImpl | 20 - org.apache.karaf.features.core - 3.0.3 | Installing feature standard-condition-webconsole_0_0_0 3.0.3
      2015-09-10 16:39:16,481 | INFO | -app-states-fg-0 | ApplicationManager | 77 - org.onosproject.onos-core-net - 1.3.0.SNAPSHOT | Application org.onosproject.drivers has been activated
      2015-09-10 16:39:16,653 | INFO | -app-states-fg-6 | ApplicationManager | 77 - org.onosproject.onos-core-net - 1.3.0.SNAPSHOT | Application org.onosproject.optical has been installed
      2015-09-10 16:39:16,716 | INFO | -app-states-fg-4 | ApplicationManager | 77 - org.onosproject.onos-core-net - 1.3.0.SNAPSHOT | Application org.onosproject.flowanalyzer has been installed
      2015-09-10 16:39:16,762 | INFO | -app-states-fg-2 | ApplicationManager | 77 - org.onosproject.onos-core-net - 1.3.0.SNAPSHOT | Application org.onosproject.vtnweb has been installed
      2015-09-10 16:39:16,809 | INFO | -app-states-fg-6 | ApplicationManager | 77 - org.onosproject.onos-core-net - 1.3.0.SNAPSHOT | Application org.onosproject.cip has been installed
      2015-09-10 16:39:16,839 | INFO | -app-states-fg-4 | ApplicationManager | 77 - org.onosproject.onos-core-net - 1.3.0.SNAPSHOT | Application org.onosproject.acl has been installed
      2015-09-10 16:39:16,878 | INFO | -app-states-fg-3 | ApplicationManager | 77 - org.onosproject.onos-core-net - 1.3.0.SNAPSHOT | Application org.onosproject.olt has been installed
      2015-09-10 16:39:16,943 | INFO | -app-states-fg-2 | ApplicationManager | 77 - org.onosproject.onos-core-net - 1.3.0.SNAPSHOT | Application org.onosproject.sdnip has been installed
      2015-09-10 16:39:16,964 | INFO | -app-states-fg-7 | ApplicationManager | 77 - org.onosproject.onos-core-net - 1.3.0.SNAPSHOT | Application org.onosproject.openflow has been installed
      2015-09-10 16:39:16,972 | INFO | -app-states-fg-1 | FeaturesServiceImpl | 20 - org.apache.karaf.features.core - 3.0.3 | Installing feature onos-openflow 1.3.0-SNAPSHOT
      2015-09-10 16:39:16,972 | INFO | -app-states-fg-1 | FeaturesServiceImpl | 20 - org.apache.karaf.features.core - 3.0.3 | Installing feature onos-api 1.3.0-SNAPSHOT
      2015-09-10 16:39:16,972 | INFO | -app-states-fg-1 | FeaturesServiceImpl | 20 - org.apache.karaf.features.core - 3.0.3 | Installing feature scr 3.0.3
      2015-09-10 16:39:16,984 | INFO | -app-states-fg-1 | FeaturesServiceImpl | 20 - org.apache.karaf.features.core - 3.0.3 | Installing feature onos-thirdparty-base 1.3.0-SNAPSHOT
      2015-09-10 16:39:17,077 | INFO | -app-states-fg-1 | FeaturesServiceImpl | 20 - org.apache.karaf.features.core - 3.0.3 | Installing feature scr-condition-management_0_0_0 3.0.3
      2015-09-10 16:39:17,079 | INFO | -app-states-fg-1 | FeaturesServiceImpl | 20 - org.apache.karaf.features.core - 3.0.3 | Installing feature scr-condition-webconsole_0_0_0 3.0.3
      2015-09-10 16:39:17,081 | INFO | -app-states-fg-1 | FeaturesServiceImpl | 20 - org.apache.karaf.features.core - 3.0.3 | Installing feature webconsole-condition-scr_0_0_0 3.0.3
      2015-09-10 16:39:17,082 | INFO | -app-states-fg-1 | FeaturesServiceImpl | 20 - org.apache.karaf.features.core - 3.0.3 | Installing feature standard-condition-webconsole_0_0_0 3.0.3
      2015-09-10 16:39:17,091 | INFO | -app-states-fg-1 | ApplicationManager | 77 - org.onosproject.onos-core-net - 1.3.0.SNAPSHOT | Application org.onosproject.openflow has been activated
      2015-09-10 16:39:17,214 | INFO | -app-states-fg-5 | ApplicationManager | 77 - org.onosproject.onos-core-net - 1.3.0.SNAPSHOT | Application org.onosproject.metrics has been installed
      2015-09-10 16:39:17,270 | INFO | -app-states-fg-3 | ApplicationManager | 77 - org.onosproject.onos-core-net - 1.3.0.SNAPSHOT | Application org.onosproject.config has been installed
      2015-09-10 16:39:17,277 | INFO | -app-states-fg-0 | ApplicationManager | 77 - org.onosproject.onos-core-net - 1.3.0.SNAPSHOT | Application org.onosproject.reactive.routing has been installed
      2015-09-10 16:39:17,330 | INFO | -app-states-fg-6 | ApplicationManager | 77 - org.onosproject.onos-core-net - 1.3.0.SNAPSHOT | Application org.onosproject.cordfabric has been installed
      2015-09-10 16:39:17,345 | INFO | -app-states-fg-2 | ApplicationManager | 77 - org.onosproject.onos-core-net - 1.3.0.SNAPSHOT | Application org.onosproject.null has been installed
      2015-09-10 16:39:17,384 | INFO | -app-states-fg-1 | ApplicationManager | 77 - org.onosproject.onos-core-net - 1.3.0.SNAPSHOT | Application org.onosproject.pcep has been installed
      2015-09-10 16:39:17,424 | INFO | -app-states-fg-5 | ApplicationManager | 77 - org.onosproject.onos-core-net - 1.3.0.SNAPSHOT | Application org.onosproject.demo has been installed
      2015-09-10 16:39:17,609 | INFO | FelixStartLevel | ApplicationManager | 77 - org.onosproject.onos-core-net - 1.3.0.SNAPSHOT | Application org.onosproject.segmentrouting has been installed
      2015-09-10 16:39:17,698 | INFO | FelixStartLevel | ApplicationManager | 77 - org.onosproject.onos-core-net - 1.3.0.SNAPSHOT | Application org.onosproject.netconf has been installed
      2015-09-10 16:39:17,744 | INFO | FelixStartLevel | ApplicationManager | 77 - org.onosproject.onos-core-net - 1.3.0.SNAPSHOT | Application org.onosproject.aaa has been installed
      2015-09-10 16:39:17,769 | INFO | FelixStartLevel | ApplicationManager | 77 - org.onosproject.onos-core-net - 1.3.0.SNAPSHOT | Started
      2015-09-10 16:39:17,795 | INFO | FelixStartLevel | DistributedLeadershipManager | 79 - org.onosproject.onos-core-dist - 1.3.0.SNAPSHOT | Started
      2015-09-10 16:39:17,810 | INFO | FelixStartLevel | ConsistentDeviceMastershipStore | 79 - org.onosproject.onos-core-dist - 1.3.0.SNAPSHOT | Started
      2015-09-10 16:39:18,020 | INFO | FelixStartLevel | GossipIntentStore | 79 - org.onosproject.onos-core-dist - 1.3.0.SNAPSHOT | Started
      2015-09-10 16:39:18,035 | INFO | FelixStartLevel | MastershipManager | 77 - org.onosproject.onos-core-net - 1.3.0.SNAPSHOT | Started
      2015-09-10 16:39:18,050 | INFO | FelixStartLevel | DistributedTopologyStore | 79 - org.onosproject.onos-core-dist - 1.3.0.SNAPSHOT | Started
      2015-09-10 16:39:18,052 | INFO | FelixStartLevel | TopologyManager | 77 - org.onosproject.onos-core-net - 1.3.0.SNAPSHOT | Started
      2015-09-10 16:39:18,056 | INFO | FelixStartLevel | ReplicaInfoManager | 79 - org.onosproject.onos-core-dist - 1.3.0.SNAPSHOT | Started
      2015-09-10 16:39:18,083 | INFO | FelixStartLevel | DistributedGroupStore | 79 - org.onosproject.onos-core-dist - 1.3.0.SNAPSHOT | Started
      2015-09-10 16:39:18,093 | INFO | FelixStartLevel | DeviceClockManager | 79 - org.onosproject.onos-core-dist - 1.3.0.SNAPSHOT | Started
      2015-09-10 16:39:18,129 | INFO | FelixStartLevel | GossipDeviceStore | 79 - org.onosproject.onos-core-dist - 1.3.0.SNAPSHOT | Started
      2015-09-10 16:39:18,150 | INFO | FelixStartLevel | ECLinkStore | 79 - org.onosproject.onos-core-dist - 1.3.0.SNAPSHOT | Started
      2015-09-10 16:39:18,162 | INFO | FelixStartLevel | DistributedStatisticStore | 79 - org.onosproject.onos-core-dist - 1.3.0.SNAPSHOT | Started
      2015-09-10 16:39:18,180 | INFO | FelixStartLevel | DistributedPacketStore | 79 - org.onosproject.onos-core-dist - 1.3.0.SNAPSHOT | Started
      2015-09-10 16:39:18,187 | INFO | FelixStartLevel | UiExtensionManager | 158 - org.onosproject.onos-gui - 1.3.0.SNAPSHOT | Started
      2015-09-10 16:39:18,196 | INFO | FelixStartLevel | DeviceManager | 77 - org.onosproject.onos-core-net - 1.3.0.SNAPSHOT | Started
      2015-09-10 16:39:18,197 | INFO | FelixStartLevel | PortStatisticsManager | 82 - org.onosproject.onos-incubator-net - 1.3.0.SNAPSHOT | Started
      2015-09-10 16:39:18,222 | INFO | FelixStartLevel | NewDistributedFlowRuleStore | 79 - org.onosproject.onos-core-dist - 1.3.0.SNAPSHOT | Started with msgHandlerPoolSize = 8; backupEnabled = true, backupPeriod = 2000
      2015-09-10 16:39:18,236 | INFO | FelixStartLevel | ConsistentDeviceResourceStore | 79 - org.onosproject.onos-core-dist - 1.3.0.SNAPSHOT | Started
      2015-09-10 16:39:18,236 | INFO | FelixStartLevel | DeviceResourceManager | 77 - org.onosproject.onos-core-net - 1.3.0.SNAPSHOT | Started
      2015-09-10 16:39:18,249 | INFO | FelixStartLevel | FlowRuleManager | 77 - org.onosproject.onos-core-net - 1.3.0.SNAPSHOT | Started
      2015-09-10 16:39:18,250 | INFO | FelixStartLevel | StatisticManager | 77 - org.onosproject.onos-core-net - 1.3.0.SNAPSHOT | Started
      2015-09-10 16:39:18,263 | INFO | FelixStartLevel | LinkManager | 77 - org.onosproject.onos-core-net - 1.3.0.SNAPSHOT | Started
      2015-09-10 16:39:18,271 | INFO | FelixStartLevel | ConsistentLinkResourceStore | 79 - org.onosproject.onos-core-dist - 1.3.0.SNAPSHOT | Started
      2015-09-10 16:39:18,272 | INFO | FelixStartLevel | LinkResourceManager | 77 - org.onosproject.onos-core-net - 1.3.0.SNAPSHOT | Started
      2015-09-10 16:39:18,281 | INFO | FelixStartLevel | DefaultTopologyProvider | 77 - org.onosproject.onos-core-net - 1.3.0.SNAPSHOT | Configured with maxEvents = 1000; maxBatchMs = 50; maxIdleMs = 10; accumulator=true
      2015-09-10 16:39:18,282 | INFO | FelixStartLevel | DefaultTopologyProvider | 77 - org.onosproject.onos-core-net - 1.3.0.SNAPSHOT | Started
      2015-09-10 16:39:18,288 | INFO | FelixStartLevel | GroupManager | 77 - org.onosproject.onos-core-net - 1.3.0.SNAPSHOT | Started
      2015-09-10 16:39:18,292 | INFO | FelixStartLevel | DriverManager | 77 - org.onosproject.onos-core-net - 1.3.0.SNAPSHOT | Started
      2015-09-10 16:39:18,303 | INFO | nos-topo-build-0 | TopologyManager | 77 - org.onosproject.onos-core-net - 1.3.0.SNAPSHOT | Topology DefaultTopology

      {time=2591325758733773, creationTime=1441917558292, computeCost=3296101, clusters=0, devices=0, links=0}

      changed
      2015-09-10 16:39:18,311 | INFO | FelixStartLevel | DistributedMeterStore | 83 - org.onosproject.onos-incubator-store - 1.3.0.SNAPSHOT | Started
      2015-09-10 16:39:18,313 | INFO | FelixStartLevel | MeterManager | 82 - org.onosproject.onos-incubator-net - 1.3.0.SNAPSHOT | Started
      2015-09-10 16:39:18,358 | INFO | FelixStartLevel | DistributedTunnelStore | 83 - org.onosproject.onos-incubator-store - 1.3.0.SNAPSHOT | Started
      2015-09-10 16:39:18,360 | INFO | FelixStartLevel | TunnelManager | 82 - org.onosproject.onos-incubator-net - 1.3.0.SNAPSHOT | Started
      2015-09-10 16:39:18,384 | INFO | FelixStartLevel | DistributedLabelResourceStore | 83 - org.onosproject.onos-incubator-store - 1.3.0.SNAPSHOT | Started
      2015-09-10 16:39:18,385 | INFO | FelixStartLevel | LabelResourceManager | 82 - org.onosproject.onos-incubator-net - 1.3.0.SNAPSHOT | Started
      2015-09-10 16:39:18,630 | INFO | FelixStartLevel | DefaultDrivers | 165 - org.onosproject.onos-drivers - 1.3.0.SNAPSHOT | Started
      2015-09-10 16:39:18,636 | INFO | FelixStartLevel | FlowObjectiveManager | 77 - org.onosproject.onos-core-net - 1.3.0.SNAPSHOT | Started
      2015-09-10 16:39:18,637 | INFO | FelixStartLevel | PacketManager | 77 - org.onosproject.onos-core-net - 1.3.0.SNAPSHOT | Started
      2015-09-10 16:39:18,639 | INFO | FelixStartLevel | EdgeManager | 77 - org.onosproject.onos-core-net - 1.3.0.SNAPSHOT | Started
      2015-09-10 16:39:18,642 | INFO | -karaf-3.0.3/etc | fileinstall | 7 - org.apache.felix.fileinstall - 3.4.2 | Started bundle: wrap:jardir:/opt/onos/apache-karaf-3.0.3/etc/samples$Bundle-SymbolicName=samples&Bundle-Version=0.0.0
      2015-09-10 16:39:18,656 | INFO | FelixStartLevel | HostManager | 77 - org.onosproject.onos-core-net - 1.3.0.SNAPSHOT | Started
      2015-09-10 16:39:18,667 | INFO | FelixStartLevel | DistributedProxyArpStore | 79 - org.onosproject.onos-core-dist - 1.3.0.SNAPSHOT | Started
      2015-09-10 16:39:18,674 | INFO | FelixStartLevel | PathManager | 77 - org.onosproject.onos-core-net - 1.3.0.SNAPSHOT | Started
      2015-09-10 16:39:18,688 | INFO | FelixStartLevel | ObjectiveTracker | 77 - org.onosproject.onos-core-net - 1.3.0.SNAPSHOT | Started
      2015-09-10 16:39:18,691 | INFO | FelixStartLevel | IntentManager | 77 - org.onosproject.onos-core-net - 1.3.0.SNAPSHOT | Started
      2015-09-10 16:39:18,722 | INFO | FelixStartLevel | IntentCleanup | 77 - org.onosproject.onos-core-net - 1.3.0.SNAPSHOT | Started
      2015-09-10 16:39:18,733 | INFO | FelixStartLevel | ProxyArpManager | 77 - org.onosproject.onos-core-net - 1.3.0.SNAPSHOT | Started
      2015-09-10 16:39:18,736 | INFO | FelixStartLevel | ProxyArp | 164 - org.onosproject.onos-app-proxyarp - 1.3.0.SNAPSHOT | IPv6 Neighbor Discovery is not configured, using current value of false
      2015-09-10 16:39:18,771 | INFO | FelixStartLevel | ProxyArp | 164 - org.onosproject.onos-app-proxyarp - 1.3.0.SNAPSHOT | Started with Application ID 11
      2015-09-10 16:39:18,774 | INFO | FelixStartLevel | HostMobility | 159 - org.onosproject.onos-app-mobility - 1.3.0.SNAPSHOT | Started with Application ID 5
      2015-09-10 16:39:18,858 | INFO | FelixStartLevel | Controller | 169 - org.onosproject.onos-of-ctl - 1.3.0.SNAPSHOT | Starting OpenFlow IO
      2015-09-10 16:39:18,899 | INFO | FelixStartLevel | Controller | 169 - org.onosproject.onos-of-ctl - 1.3.0.SNAPSHOT | Listening for switch connections on 0.0.0.0/0.0.0.0:6633
      2015-09-10 16:39:18,922 | INFO | FelixStartLevel | LLDPLinkProvider | 170 - org.onosproject.onos-lldp-provider - 1.3.0.SNAPSHOT | Reading suppression rules from ../config/lldp_suppression.json
      2015-09-10 16:39:18,922 | INFO | FelixStartLevel | LLDPLinkProvider | 170 - org.onosproject.onos-lldp-provider - 1.3.0.SNAPSHOT | Failed to load ../config/lldp_suppression.json, using built-in rules
      2015-09-10 16:39:18,925 | INFO | FelixStartLevel | LLDPLinkProvider | 170 - org.onosproject.onos-lldp-provider - 1.3.0.SNAPSHOT | Settings: enabled=true, useBDDP=true, probeRate=3000, staleLinkAge=10000, lldpSuppression=../config/lldp_suppression.json
      2015-09-10 16:39:18,925 | INFO | FelixStartLevel | LLDPLinkProvider | 170 - org.onosproject.onos-lldp-provider - 1.3.0.SNAPSHOT | Started
      2015-09-10 16:39:18,944 | INFO | FelixStartLevel | HostLocationProvider | 171 - org.onosproject.onos-host-provider - 1.3.0.SNAPSHOT | Host removal on port/device down events is not configured, using current value of true
      2015-09-10 16:39:18,944 | INFO | FelixStartLevel | HostLocationProvider | 171 - org.onosproject.onos-host-provider - 1.3.0.SNAPSHOT | Using IPv6 Neighbor Discovery is not configured, using current value of false
      2015-09-10 16:39:18,947 | INFO | FelixStartLevel | HostLocationProvider | 171 - org.onosproject.onos-host-provider - 1.3.0.SNAPSHOT | Started with Application ID 38
      2015-09-10 16:39:18,962 | INFO | FelixStartLevel | OpenFlowDeviceProvider | 172 - org.onosproject.onos-of-provider-device - 1.3.0.SNAPSHOT | Started
      2015-09-10 16:39:18,970 | INFO | FelixStartLevel | OpenFlowPacketProvider | 173 - org.onosproject.onos-of-provider-packet - 1.3.0.SNAPSHOT | Started
      2015-09-10 16:39:18,991 | INFO | FelixStartLevel | OpenFlowRuleProvider | 174 - org.onosproject.onos-of-provider-flow - 1.3.0.SNAPSHOT | Started
      2015-09-10 16:39:19,000 | INFO | FelixStartLevel | OpenFlowGroupProvider | 175 - org.onosproject.onos-of-provider-group - 1.3.0.SNAPSHOT | Started
      2015-09-10 16:39:24,928 | INFO | ew I/O worker #1 | OFChannelHandler | 169 - org.onosproject.onos-of-ctl - 1.3.0.SNAPSHOT | New switch connection from /10.128.10.11:42016
      2015-09-10 16:39:24,929 | INFO | ew I/O worker #2 | OFChannelHandler | 169 - org.onosproject.onos-of-ctl - 1.3.0.SNAPSHOT | New switch connection from /10.128.10.11:42019
      2015-09-10 16:39:24,946 | INFO | ew I/O worker #2 | OFChannelHandler | 169 - org.onosproject.onos-of-ctl - 1.3.0.SNAPSHOT | Sending OF_13 Hello to /10.128.10.11:42019
      2015-09-10 16:39:24,946 | INFO | ew I/O worker #1 | OFChannelHandler | 169 - org.onosproject.onos-of-ctl - 1.3.0.SNAPSHOT | Sending OF_13 Hello to /10.128.10.11:42016
      2015-09-10 16:39:24,967 | INFO | ew I/O worker #2 | OFChannelHandler | 169 - org.onosproject.onos-of-ctl - 1.3.0.SNAPSHOT | Received port desc reply for switch at [/10.128.10.11:42019 DPID[00:00:00:00:00:00:00:04]]
      2015-09-10 16:39:24,967 | INFO | ew I/O worker #1 | OFChannelHandler | 169 - org.onosproject.onos-of-ctl - 1.3.0.SNAPSHOT | Received port desc reply for switch at [/10.128.10.11:42016 DPID[00:00:00:00:00:00:00:03]]
      2015-09-10 16:39:24,982 | INFO | ew I/O worker #2 | OFChannelHandler | 169 - org.onosproject.onos-of-ctl - 1.3.0.SNAPSHOT | Received switch description reply OFDescStatsReplyVer13(xid=4294967289, flags=[], mfrDesc=Nicira, Inc., hwDesc=Open vSwitch, swDesc=2.3.0, serialNum=None, dpDesc=None) from switch at /10.128.10.11:42019
      2015-09-10 16:39:24,982 | INFO | ew I/O worker #1 | OFChannelHandler | 169 - org.onosproject.onos-of-ctl - 1.3.0.SNAPSHOT | Received switch description reply OFDescStatsReplyVer13(xid=4294967289, flags=[], mfrDesc=Nicira, Inc., hwDesc=Open vSwitch, swDesc=2.3.0, serialNum=None, dpDesc=None) from switch at /10.128.10.11:42016
      2015-09-10 16:39:24,988 | INFO | ew I/O worker #2 | Controller | 169 - org.onosproject.onos-of-ctl - 1.3.0.SNAPSHOT | OpenFlow handshaker found for device 4: org.onosproject.driver.handshaker.NiciraSwitchHandshaker [? DPID[00:00:00:00:00:00:00:04]]
      2015-09-10 16:39:24,989 | INFO | ew I/O worker #2 | ntrollerImpl$OpenFlowSwitchAgent | 169 - org.onosproject.onos-of-ctl - 1.3.0.SNAPSHOT | Added switch 00:00:00:00:00:00:00:04
      2015-09-10 16:39:24,989 | INFO | ew I/O worker #1 | Controller | 169 - org.onosproject.onos-of-ctl - 1.3.0.SNAPSHOT | OpenFlow handshaker found for device 3: org.onosproject.driver.handshaker.NiciraSwitchHandshaker [? DPID[00:00:00:00:00:00:00:03]]
      2015-09-10 16:39:24,989 | INFO | ew I/O worker #1 | ntrollerImpl$OpenFlowSwitchAgent | 169 - org.onosproject.onos-of-ctl - 1.3.0.SNAPSHOT | Added switch 00:00:00:00:00:00:00:03
      2015-09-10 16:39:25,019 | INFO | ew I/O worker #1 | DeviceManager | 77 - org.onosproject.onos-core-net - 1.3.0.SNAPSHOT | Local role is MASTER for of:0000000000000003
      2015-09-10 16:39:25,022 | INFO | ew I/O worker #2 | DeviceManager | 77 - org.onosproject.onos-core-net - 1.3.0.SNAPSHOT | Local role is MASTER for of:0000000000000004
      2015-09-10 16:39:25,031 | INFO | ew I/O worker #1 | DeviceManager | 77 - org.onosproject.onos-core-net - 1.3.0.SNAPSHOT | Device of:0000000000000003 connected
      2015-09-10 16:39:25,032 | INFO | ew I/O worker #2 | DeviceManager | 77 - org.onosproject.onos-core-net - 1.3.0.SNAPSHOT | Device of:0000000000000004 connected
      2015-09-10 16:39:25,033 | INFO | event-dispatch-0 | FlowObjectiveManager | 77 - org.onosproject.onos-core-net - 1.3.0.SNAPSHOT | Driver ovs bound to device of:0000000000000003 ... initializing driver
      2015-09-10 16:39:25,042 | INFO | ew I/O worker #2 | PortStatsCollector | 172 - org.onosproject.onos-of-provider-device - 1.3.0.SNAPSHOT | Starting Port Stats collection thread for 00:00:00:00:00:00:00:04
      2015-09-10 16:39:25,042 | INFO | ew I/O worker #1 | PortStatsCollector | 172 - org.onosproject.onos-of-provider-device - 1.3.0.SNAPSHOT | Starting Port Stats collection thread for 00:00:00:00:00:00:00:03
      2015-09-10 16:39:25,060 | INFO | ew I/O worker #2 | GroupStatsCollector | 175 - org.onosproject.onos-of-provider-group - 1.3.0.SNAPSHOT | Starting Group Stats collection thread for 00:00:00:00:00:00:00:04
      2015-09-10 16:39:25,061 | INFO | ew I/O worker #1 | GroupStatsCollector | 175 - org.onosproject.onos-of-provider-group - 1.3.0.SNAPSHOT | Starting Group Stats collection thread for 00:00:00:00:00:00:00:03
      2015-09-10 16:39:25,065 | INFO | ew I/O worker #2 | MeterStatsCollector | 176 - org.onosproject.onos-of-provider-meter - 1.3.0.SNAPSHOT | Starting Meter Stats collection thread for 00:00:00:00:00:00:00:04
      2015-09-10 16:39:25,065 | INFO | ew I/O worker #2 | OFChannelHandler | 169 - org.onosproject.onos-of-ctl - 1.3.0.SNAPSHOT | Processing 0 pending port status messages for 00:00:00:00:00:00:00:04
      2015-09-10 16:39:25,066 | INFO | ew I/O worker #1 | MeterStatsCollector | 176 - org.onosproject.onos-of-provider-meter - 1.3.0.SNAPSHOT | Starting Meter Stats collection thread for 00:00:00:00:00:00:00:03
      2015-09-10 16:39:25,066 | INFO | ew I/O worker #1 | OFChannelHandler | 169 - org.onosproject.onos-of-ctl - 1.3.0.SNAPSHOT | Processing 0 pending port status messages for 00:00:00:00:00:00:00:03
      2015-09-10 16:39:25,067 | INFO | event-dispatch-0 | LinkDiscovery | 170 - org.onosproject.onos-lldp-provider - 1.3.0.SNAPSHOT | Using BDDP to discover network
      2015-09-10 16:39:25,072 | INFO | ew I/O worker #1 | ntrollerImpl$OpenFlowSwitchAgent | 169 - org.onosproject.onos-of-ctl - 1.3.0.SNAPSHOT | Transitioned switch 00:00:00:00:00:00:00:03 to MASTER
      2015-09-10 16:39:25,072 | INFO | event-dispatch-0 | FlowObjectiveManager | 77 - org.onosproject.onos-core-net - 1.3.0.SNAPSHOT | Driver ovs bound to device of:0000000000000004 ... initializing driver
      2015-09-10 16:39:25,072 | INFO | ew I/O worker #2 | ntrollerImpl$OpenFlowSwitchAgent | 169 - org.onosproject.onos-of-ctl - 1.3.0.SNAPSHOT | Transitioned switch 00:00:00:00:00:00:00:04 to MASTER
      2015-09-10 16:39:25,073 | INFO | event-dispatch-0 | LinkDiscovery | 170 - org.onosproject.onos-lldp-provider - 1.3.0.SNAPSHOT | Using BDDP to discover network
      2015-09-10 16:39:25,091 | INFO | nos-topo-build-1 | TopologyManager | 77 - org.onosproject.onos-core-net - 1.3.0.SNAPSHOT | Topology DefaultTopology

      {time=2591332526692281, creationTime=1441917565060, computeCost=1019929, clusters=2, devices=2, links=0}

      changed
      2015-09-10 16:39:25,095 | INFO | nos-topo-build-2 | TopologyManager | 77 - org.onosproject.onos-core-net - 1.3.0.SNAPSHOT | Topology DefaultTopology

      {time=2591332549985523, creationTime=1441917565083, computeCost=109349, clusters=2, devices=2, links=0}

      changed
      2015-09-10 16:39:25,096 | WARN | copycat-p2-1 | FollowerState | 71 - org.onosproject.onlab-thirdparty - 1.3.0.SNAPSHOT | onos://10.128.20.13:9876 - Rejected AppendRequest[term=1, leader=onos://10.128.20.12:9876, logIndex=1, logTerm=1, entries=[0], firstIndex=false, commitIndex=null]: Previous index (1) is greater than the local log's last index (null)
      2015-09-10 16:39:28,293 | INFO | nos-topo-build-3 | DistributedTopologyStore | 79 - org.onosproject.onos-core-dist - 1.3.0.SNAPSHOT | Cluster rooted at of:0000000000000003 has 2 broadcast-points; #-489164262
      2015-09-10 16:39:28,298 | INFO | nos-topo-build-3 | TopologyManager | 77 - org.onosproject.onos-core-net - 1.3.0.SNAPSHOT | Topology DefaultTopology

      {time=2591335752998100, creationTime=1441917568286, computeCost=2855782, clusters=1, devices=2, links=2}

      changed
      2015-09-10 16:42:00,461 | INFO | h for user karaf | FeaturesServiceImpl | 20 - org.apache.karaf.features.core - 3.0.3 | Installing feature onos-app-fwd 1.3.0-SNAPSHOT
      2015-09-10 16:42:00,461 | INFO | h for user karaf | FeaturesServiceImpl | 20 - org.apache.karaf.features.core - 3.0.3 | Installing feature onos-api 1.3.0-SNAPSHOT
      2015-09-10 16:42:00,461 | INFO | h for user karaf | FeaturesServiceImpl | 20 - org.apache.karaf.features.core - 3.0.3 | Installing feature scr 3.0.3
      2015-09-10 16:42:00,466 | INFO | h for user karaf | FeaturesServiceImpl | 20 - org.apache.karaf.features.core - 3.0.3 | Installing feature onos-thirdparty-base 1.3.0-SNAPSHOT
      2015-09-10 16:42:00,513 | INFO | h for user karaf | FeaturesServiceImpl | 20 - org.apache.karaf.features.core - 3.0.3 | Installing feature scr-condition-management_0_0_0 3.0.3
      2015-09-10 16:42:00,515 | INFO | h for user karaf | FeaturesServiceImpl | 20 - org.apache.karaf.features.core - 3.0.3 | Installing feature scr-condition-webconsole_0_0_0 3.0.3
      2015-09-10 16:42:00,518 | INFO | h for user karaf | FeaturesServiceImpl | 20 - org.apache.karaf.features.core - 3.0.3 | Installing feature webconsole-condition-scr_0_0_0 3.0.3
      2015-09-10 16:42:00,520 | INFO | h for user karaf | FeaturesServiceImpl | 20 - org.apache.karaf.features.core - 3.0.3 | Installing feature standard-condition-webconsole_0_0_0 3.0.3
      2015-09-10 16:42:00,550 | INFO | h for user karaf | ReactiveForwarding | 177 - org.onosproject.onos-app-fwd - 1.3.0.SNAPSHOT | Flow Timeout is not configured, default value is 10
      2015-09-10 16:42:00,550 | INFO | h for user karaf | ReactiveForwarding | 177 - org.onosproject.onos-app-fwd - 1.3.0.SNAPSHOT | Flow Priority is not configured, default value is 10
      2015-09-10 16:42:00,558 | INFO | h for user karaf | ReactiveForwarding | 177 - org.onosproject.onos-app-fwd - 1.3.0.SNAPSHOT | Started
      2015-09-10 16:42:00,559 | INFO | h for user karaf | ApplicationManager | 77 - org.onosproject.onos-core-net - 1.3.0.SNAPSHOT | Application org.onosproject.fwd has been activated
      2015-09-10 16:42:03,964 | INFO | h for user karaf | FeaturesServiceImpl | 20 - org.apache.karaf.features.core - 3.0.3 | Installing feature onos-app-dhcp 1.3.0-SNAPSHOT
      2015-09-10 16:42:03,964 | INFO | h for user karaf | FeaturesServiceImpl | 20 - org.apache.karaf.features.core - 3.0.3 | Installing feature onos-api 1.3.0-SNAPSHOT
      2015-09-10 16:42:03,964 | INFO | h for user karaf | FeaturesServiceImpl | 20 - org.apache.karaf.features.core - 3.0.3 | Installing feature scr 3.0.3
      2015-09-10 16:42:03,969 | INFO | h for user karaf | FeaturesServiceImpl | 20 - org.apache.karaf.features.core - 3.0.3 | Installing feature onos-thirdparty-base 1.3.0-SNAPSHOT
      2015-09-10 16:42:04,033 | INFO | h for user karaf | FeaturesServiceImpl | 20 - org.apache.karaf.features.core - 3.0.3 | Installing feature scr-condition-management_0_0_0 3.0.3
      2015-09-10 16:42:04,036 | INFO | h for user karaf | FeaturesServiceImpl | 20 - org.apache.karaf.features.core - 3.0.3 | Installing feature scr-condition-webconsole_0_0_0 3.0.3
      2015-09-10 16:42:04,038 | INFO | h for user karaf | FeaturesServiceImpl | 20 - org.apache.karaf.features.core - 3.0.3 | Installing feature webconsole-condition-scr_0_0_0 3.0.3
      2015-09-10 16:42:04,039 | INFO | h for user karaf | FeaturesServiceImpl | 20 - org.apache.karaf.features.core - 3.0.3 | Installing feature standard-condition-webconsole_0_0_0 3.0.3
      2015-09-10 16:42:04,124 | INFO | h for user karaf | DistributedDhcpStore | 178 - org.onosproject.onos-app-dhcp - 1.3.0.SNAPSHOT | Started
      2015-09-10 16:42:04,136 | INFO | h for user karaf | DhcpManager | 178 - org.onosproject.onos-app-dhcp - 1.3.0.SNAPSHOT | Started
      2015-09-10 16:42:04,142 | INFO | h for user karaf | DhcpUi | 178 - org.onosproject.onos-app-dhcp - 1.3.0.SNAPSHOT | Started
      2015-09-10 16:42:04,388 | INFO | h for user karaf | HttpServiceFactoryImpl | 115 - org.ops4j.pax.web.pax-web-runtime - 3.1.4 | Binding bundle: [org.onosproject.onos-app-dhcp [178]] to http service
      2015-09-10 16:42:04,392 | INFO | h for user karaf | ApplicationManager | 77 - org.onosproject.onos-core-net - 1.3.0.SNAPSHOT | Application org.onosproject.dhcp has been activated
      2015-09-10 16:42:04,408 | INFO | pool-8-thread-1 | WebApplicationImpl | 134 - com.sun.jersey.jersey-server - 1.19.0 | Initiating Jersey application, version 'Jersey: 1.19 02/11/2015 03:25 AM'
      2015-09-10 16:42:47,968 | ERROR | ew I/O worker #1 | OFChannelHandler | 169 - org.onosproject.onos-of-ctl - 1.3.0.SNAPSHOT | Error while processing message from switch org.onosproject.driver.handshaker.NiciraSwitchHandshaker [/10.128.10.11:42016 DPID[00:00:00:00:00:00:00:03]]state ACTIVE
      java.lang.NullPointerException: IP Address must be specified
      at com.google.common.base.Preconditions.checkNotNull(Preconditions.java:226)
      at org.onosproject.dhcp.IpAssignment$Builder.validateInputs(IpAssignment.java:191)
      at org.onosproject.dhcp.IpAssignment$Builder.build(IpAssignment.java:163)
      at org.onosproject.dhcp.impl.DistributedDhcpStore.suggestIP(DistributedDhcpStore.java:164)
      at org.onosproject.dhcp.impl.DhcpManager$DHCPPacketProcessor.processDHCPPacket(DhcpManager.java:445)
      at org.onosproject.dhcp.impl.DhcpManager$DHCPPacketProcessor.process(DhcpManager.java:560)
      at org.onosproject.net.packet.impl.PacketManager$InternalPacketProviderService.processPacket(PacketManager.java:284)
      at org.onosproject.provider.of.packet.impl.OpenFlowPacketProvider$InternalPacketProvider.handlePacket(OpenFlowPacketProvider.java:170)
      at org.onosproject.openflow.controller.impl.OpenFlowControllerImpl.processPacket(OpenFlowControllerImpl.java:282)
      at org.onosproject.openflow.controller.impl.OpenFlowControllerImpl$OpenFlowSwitchAgent.processMessage(OpenFlowControllerImpl.java:606)
      at org.onosproject.openflow.controller.driver.AbstractOpenFlowSwitch.handleMessage(AbstractOpenFlowSwitch.java:203)
      at org.onosproject.openflow.controller.impl.OFChannelHandler.dispatchMessage(OFChannelHandler.java:1185)
      at org.onosproject.openflow.controller.impl.OFChannelHandler.access$1800(OFChannelHandler.java:81)
      at org.onosproject.openflow.controller.impl.OFChannelHandler$ChannelState$8.processOFPacketIn(OFChannelHandler.java:636)
      at org.onosproject.openflow.controller.impl.OFChannelHandler$ChannelState.processOFMessage(OFChannelHandler.java:884)
      at org.onosproject.openflow.controller.impl.OFChannelHandler.messageReceived(OFChannelHandler.java:1166)
      at org.jboss.netty.handler.timeout.IdleStateAwareChannelHandler.handleUpstream(IdleStateAwareChannelHandler.java:36)[45:org.jboss.netty:3.9.2.Final]
      at org.jboss.netty.handler.timeout.ReadTimeoutHandler.messageReceived(ReadTimeoutHandler.java:184)[45:org.jboss.netty:3.9.2.Final]
      at org.jboss.netty.handler.timeout.IdleStateHandler.messageReceived(IdleStateHandler.java:294)[45:org.jboss.netty:3.9.2.Final]
      at org.jboss.netty.channel.Channels.fireMessageReceived(Channels.java:296)[45:org.jboss.netty:3.9.2.Final]
      at org.jboss.netty.handler.codec.frame.FrameDecoder.unfoldAndFireMessageReceived(FrameDecoder.java:462)[45:org.jboss.netty:3.9.2.Final]
      at org.jboss.netty.handler.codec.frame.FrameDecoder.callDecode(FrameDecoder.java:443)[45:org.jboss.netty:3.9.2.Final]
      at org.jboss.netty.handler.codec.frame.FrameDecoder.messageReceived(FrameDecoder.java:303)[45:org.jboss.netty:3.9.2.Final]
      at org.jboss.netty.channel.Channels.fireMessageReceived(Channels.java:268)[45:org.jboss.netty:3.9.2.Final]
      at org.jboss.netty.channel.Channels.fireMessageReceived(Channels.java:255)[45:org.jboss.netty:3.9.2.Final]
      at org.jboss.netty.channel.socket.nio.NioWorker.read(NioWorker.java:88)[45:org.jboss.netty:3.9.2.Final]
      at org.jboss.netty.channel.socket.nio.AbstractNioWorker.process(AbstractNioWorker.java:108)[45:org.jboss.netty:3.9.2.Final]
      at org.jboss.netty.channel.socket.nio.AbstractNioSelector.run(AbstractNioSelector.java:318)
      at org.jboss.netty.channel.socket.nio.AbstractNioWorker.run(AbstractNioWorker.java:89)[45:org.jboss.netty:3.9.2.Final]
      at org.jboss.netty.channel.socket.nio.NioWorker.run(NioWorker.java:178)[45:org.jboss.netty:3.9.2.Final]
      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]
      2015-09-10 16:42:47,981 | INFO | ew I/O worker #1 | OFChannelHandler | 169 - org.onosproject.onos-of-ctl - 1.3.0.SNAPSHOT | Switch disconnected callback for sw:org.onosproject.driver.handshaker.NiciraSwitchHandshaker [/10.128.10.11:42016 DPID[00:00:00:00:00:00:00:03]]. Cleaning up ...
      2015-09-10 16:42:47,981 | INFO | ew I/O worker #1 | OFChannelHandler | 169 - org.onosproject.onos-of-ctl - 1.3.0.SNAPSHOT | org.onosproject.driver.handshaker.NiciraSwitchHandshaker [/10.128.10.11:42016 DPID[00:00:00:00:00:00:00:03]]:removal called
      2015-09-10 16:42:47,981 | INFO | ew I/O worker #1 | DeviceManager | 77 - org.onosproject.onos-core-net - 1.3.0.SNAPSHOT | Device of:0000000000000003 disconnected from this node
      2015-09-10 16:42:47,999 | INFO | ew I/O worker #1 | PortStatsCollector | 172 - org.onosproject.onos-of-provider-device - 1.3.0.SNAPSHOT | Stopping Port Stats collection thread for 00:00:00:00:00:00:00:03
      2015-09-10 16:42:47,999 | INFO | ew I/O worker #1 | GroupStatsCollector | 175 - org.onosproject.onos-of-provider-group - 1.3.0.SNAPSHOT | Stopping Group Stats collection thread for 00:00:00:00:00:00:00:03
      2015-09-10 16:42:47,999 | INFO | ew I/O worker #1 | MeterStatsCollector | 176 - org.onosproject.onos-of-provider-meter - 1.3.0.SNAPSHOT | Stopping Meter Stats collection thread for 00:00:00:00:00:00:00:03
      2015-09-10 16:42:48,013 | WARN | nager-background | DeviceManager | 77 - org.onosproject.onos-core-net - 1.3.0.SNAPSHOT | Node was instructed to be STANDBY role for of:0000000000000003, but this node cannot reach the device. Relinquishing role.
      2015-09-10 16:42:48,018 | WARN | nager-background | DeviceManager | 77 - org.onosproject.onos-core-net - 1.3.0.SNAPSHOT | Node was instructed to be STANDBY role for of:0000000000000003, but this node cannot reach the device. Relinquishing role.
      2015-09-10 16:42:48,021 | INFO | nos-topo-build-4 | TopologyManager | 77 - org.onosproject.onos-core-net - 1.3.0.SNAPSHOT | Topology DefaultTopology

      {time=2591535468124400, creationTime=1441917768001, computeCost=212679, clusters=1, devices=1, links=0}

      changed
      2015-09-10 16:42:48,032 | INFO | nos-topo-build-5 | TopologyManager | 77 - org.onosproject.onos-core-net - 1.3.0.SNAPSHOT | Topology DefaultTopology

      {time=2591535490199195, creationTime=1441917768023, computeCost=100947, clusters=1, devices=1, links=0}

      changed
      2015-09-10 16:42:48,911 | INFO | ew I/O worker #3 | OFChannelHandler | 169 - org.onosproject.onos-of-ctl - 1.3.0.SNAPSHOT | New switch connection from /10.128.10.11:42020
      2015-09-10 16:42:48,911 | INFO | ew I/O worker #3 | OFChannelHandler | 169 - org.onosproject.onos-of-ctl - 1.3.0.SNAPSHOT | Sending OF_13 Hello to /10.128.10.11:42020
      2015-09-10 16:42:48,912 | INFO | ew I/O worker #3 | OFChannelHandler | 169 - org.onosproject.onos-of-ctl - 1.3.0.SNAPSHOT | Received port desc reply for switch at [/10.128.10.11:42020 DPID[00:00:00:00:00:00:00:03]]
      2015-09-10 16:42:48,913 | INFO | ew I/O worker #3 | OFChannelHandler | 169 - org.onosproject.onos-of-ctl - 1.3.0.SNAPSHOT | Received switch description reply OFDescStatsReplyVer13(xid=4294967289, flags=[], mfrDesc=Nicira, Inc., hwDesc=Open vSwitch, swDesc=2.3.0, serialNum=None, dpDesc=None) from switch at /10.128.10.11:42020
      2015-09-10 16:42:48,914 | INFO | ew I/O worker #3 | Controller | 169 - org.onosproject.onos-of-ctl - 1.3.0.SNAPSHOT | OpenFlow handshaker found for device 3: org.onosproject.driver.handshaker.NiciraSwitchHandshaker [? DPID[00:00:00:00:00:00:00:03]]
      2015-09-10 16:42:48,914 | INFO | ew I/O worker #3 | ntrollerImpl$OpenFlowSwitchAgent | 169 - org.onosproject.onos-of-ctl - 1.3.0.SNAPSHOT | Added switch 00:00:00:00:00:00:00:03
      2015-09-10 16:42:50,058 | INFO | lection-thread-0 | DeviceManager | 77 - org.onosproject.onos-core-net - 1.3.0.SNAPSHOT | Local role is STANDBY for of:0000000000000003
      2015-09-10 16:42:50,060 | INFO | ew I/O worker #3 | DeviceManager | 77 - org.onosproject.onos-core-net - 1.3.0.SNAPSHOT | Device of:0000000000000003 connected
      2015-09-10 16:42:50,060 | INFO | ew I/O worker #3 | PortStatsCollector | 172 - org.onosproject.onos-of-provider-device - 1.3.0.SNAPSHOT | Starting Port Stats collection thread for 00:00:00:00:00:00:00:03
      2015-09-10 16:42:50,060 | INFO | ew I/O worker #3 | GroupStatsCollector | 175 - org.onosproject.onos-of-provider-group - 1.3.0.SNAPSHOT | Starting Group Stats collection thread for 00:00:00:00:00:00:00:03
      2015-09-10 16:42:50,060 | INFO | ew I/O worker #3 | MeterStatsCollector | 176 - org.onosproject.onos-of-provider-meter - 1.3.0.SNAPSHOT | Starting Meter Stats collection thread for 00:00:00:00:00:00:00:03
      2015-09-10 16:42:50,061 | INFO | ew I/O worker #3 | OFChannelHandler | 169 - org.onosproject.onos-of-ctl - 1.3.0.SNAPSHOT | Processing 0 pending port status messages for 00:00:00:00:00:00:00:03
      2015-09-10 16:42:50,062 | INFO | ew I/O worker #3 | ntrollerImpl$OpenFlowSwitchAgent | 169 - org.onosproject.onos-of-ctl - 1.3.0.SNAPSHOT | Transitioned switch 00:00:00:00:00:00:00:03 to EQUAL
      2015-09-10 16:42:50,066 | INFO | event-dispatch-0 | FlowObjectiveManager | 77 - org.onosproject.onos-core-net - 1.3.0.SNAPSHOT | Driver ovs bound to device of:0000000000000003 ... initializing driver
      2015-09-10 16:42:50,089 | INFO | nos-topo-build-6 | TopologyManager | 77 - org.onosproject.onos-core-net - 1.3.0.SNAPSHOT | Topology DefaultTopology

      {time=2591537543667330, creationTime=1441917770077, computeCost=85010, clusters=2, devices=2, links=0}

      changed
      2015-09-10 16:42:50,190 | INFO | nos-topo-build-7 | TopologyManager | 77 - org.onosproject.onos-core-net - 1.3.0.SNAPSHOT | Topology DefaultTopology

      {time=2591537649048531, creationTime=1441917770182, computeCost=262127, clusters=2, devices=2, links=1}

      changed
      2015-09-10 16:42:52,894 | INFO | dcast-trees-fg-2 | DistributedTopologyStore | 79 - org.onosproject.onos-core-dist - 1.3.0.SNAPSHOT | Cluster rooted at of:0000000000000003 has 2 broadcast-points; #-489164262
      2015-09-10 16:42:52,897 | INFO | nos-topo-build-0 | TopologyManager | 77 - org.onosproject.onos-core-net - 1.3.0.SNAPSHOT | Topology DefaultTopology

      {time=2591540361988843, creationTime=1441917772895, computeCost=299903, clusters=1, devices=2, links=2}

      changed

      onos>

        Attachments

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

          Activity

            People

            Assignee:
            sam Samanwita Pal
            Reporter:
            hari hari
            Votes:
            0 Vote for this issue
            Watchers:
            5 Start watching this issue

              Dates

              Created:
              Updated:
              Resolved: