java.lang.NullPointerException

JIRA | Hyunsun Moon | 2 months ago
tip
Do you know that we can give you better hits? Get more relevant results from Samebug’s stack trace search.
  1. 0

    NPE due to the missing group when compute node is rebooted {code} 2017-02-10 21:37:29,409 | ERROR | f-event-stats-23 | OpenFlowControllerImpl | 200 - org.onosproject.onos-protocols-openflow-ctl - 1.9.0.SNAPSHOT | Uncaught exception on onos-of-event-stats-23 java.lang.NullPointerException at org.onosproject.store.group.impl.DistributedGroupStore.groupMissing(DistributedGroupStore.java:1418)[79:org.onosproject.onos-core-dist:1.9.0.SNAPSHOT] at org.onosproject.store.group.impl.DistributedGroupStore.pushGroupMetrics(DistributedGroupStore.java:1352)[79:org.onosproject.onos-core-dist:1.9.0.SNAPSHOT] at org.onosproject.net.group.impl.GroupManager$InternalGroupProviderService.pushGroupMetrics(GroupManager.java:387)[77:org.onosproject.onos-core-net:1.9.0.SNAPSHOT] at org.onosproject.provider.of.group.impl.OpenFlowGroupProvider.pushGroupMetrics(OpenFlowGroupProvider.java:231)[204:org.onosproject.onos-providers-openflow-group:1.9.0.SNAPSHOT] at org.onosproject.provider.of.group.impl.OpenFlowGroupProvider.access$100(OpenFlowGroupProvider.java:86)[204:org.onosproject.onos-providers-openflow-group:1.9.0.SNAPSHOT] at org.onosproject.provider.of.group.impl.OpenFlowGroupProvider$InternalGroupProvider.handleMessage(OpenFlowGroupProvider.java:328)[204:org.onosproject.onos-providers-openflow-group:1.9.0.SNAPSHOT] at org.onosproject.openflow.controller.impl.OpenFlowControllerImpl$OFMessageHandler.run(OpenFlowControllerImpl.java:758)[200:org.onosproject.onos-protocols-openflow-ctl:1.9.0.SNAPSHOT] at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)[:1.8.0_101] at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)[:1.8.0_101] at java.lang.Thread.run(Thread.java:745)[:1.8.0_101] {code}

    JIRA | 2 months ago | Hyunsun Moon
    java.lang.NullPointerException
  2. 0

    After restarting mininet all ONOS instances crashed and lost karaf shell connection to ONOS instances. One Null pointer exceptions at "State WAIT_SWITCH_DRIVER_SUB_HANDSHAKE" and "Error while logging processing event com.esotericsoftware.kryo.KryoException: Buffer underflow." noticed in the logs. ############################################# 2015-02-18 09:36:35,562 | INFO | w I/O worker #13 | OFChannelHandler | 130 - org.onosproject.onos-of-ctl - 1.1.0.SNAPSHOT | OFSwitchImplOVS13 [/10.128.40.50:47139 DPID[00:00:00:00:00:00:00:0a]]:removal called 2015-02-18 09:36:35,562 | INFO | ew I/O worker #9 | DeviceManager | 157 - org.onosproject.onos-core-net - 1.1.0.SNAPSHOT | Device of:0000000000000004 disconnected from this node 2015-02-18 09:36:35,562 | WARN | w I/O worker #13 | ntrollerImpl$OpenFlowSwitchAgent | 130 - org.onosproject.onos-of-ctl - 1.1.0.SNAPSHOT | sw was null for 00:00:00:00:00:00:00:0a 2015-02-18 09:36:35,562 | INFO | w I/O worker #11 | DeviceManager | 157 - org.onosproject.onos-core-net - 1.1.0.SNAPSHOT | Device of:0000000000000001 disconnected from this node 2015-02-18 09:36:35,562 | WARN | w I/O worker #12 | ntrollerImpl$OpenFlowSwitchAgent | 130 - org.onosproject.onos-of-ctl - 1.1.0.SNAPSHOT | removal for 00:00:00:00:00:00:00:11 2015-02-18 09:36:35,562 | INFO | w I/O worker #12 | DeviceManager | 157 - org.onosproject.onos-core-net - 1.1.0.SNAPSHOT | Device of:0000000000000011 disconnected from this node 2015-02-18 09:36:35,562 | WARN | w I/O worker #13 | ntrollerImpl$OpenFlowSwitchAgent | 130 - org.onosproject.onos-of-ctl - 1.1.0.SNAPSHOT | removal for 00:00:00:00:00:00:00:0a 2015-02-18 09:36:35,562 | INFO | w I/O worker #13 | DeviceManager | 157 - org.onosproject.onos-core-net - 1.1.0.SNAPSHOT | Device of:000000000000000a disconnected from this node 2015-02-18 09:36:35,572 | ERROR | w I/O worker #15 | OFChannelHandler | 130 - org.onosproject.onos-of-ctl - 1.1.0.SNAPSHOT | Error while processing message from switch OFSwitchImplOVS13 [/10.128.40.50:47149 DPID[00:00:00:00:00:00:00:09]]state WAIT_SWITCH_DRIVER_SUB_HANDSHAKE java.lang.NullPointerException at org.onosproject.net.device.impl.DeviceManager$InternalDeviceProviderService.updatePorts(DeviceManager.java:396) at org.onosproject.provider.of.device.impl.OpenFlowDeviceProvider$InternalDeviceProvider.switchAdded(OpenFlowDeviceProvider.java:203) at org.onosproject.openflow.controller.impl.OpenFlowControllerImpl$OpenFlowSwitchAgent.addConnectedSwitch(OpenFlowControllerImpl.java:347) at org.onosproject.openflow.controller.driver.AbstractOpenFlowSwitch.connectSwitch(AbstractOpenFlowSwitch.java:210) at org.onosproject.openflow.controller.impl.OFChannelHandler$ChannelState$7.moveToActive(OFChannelHandler.java:537) at org.onosproject.openflow.controller.impl.OFChannelHandler$ChannelState$7.processOFMessage(OFChannelHandler.java:524) at org.onosproject.openflow.controller.impl.OFChannelHandler.messageReceived(OFChannelHandler.java:1150) at org.jboss.netty.handler.timeout.IdleStateAwareChannelHandler.handleUpstream(IdleStateAwareChannelHandler.java:36)[44:org.jboss.netty:3.9.2.Final] at org.jboss.netty.handler.timeout.ReadTimeoutHandler.messageReceived(ReadTimeoutHandler.java:184)[44:org.jboss.netty:3.9.2.Final] at org.jboss.netty.handler.timeout.IdleStateHandler.messageReceived(IdleStateHandler.java:294)[44:org.jboss.netty:3.9.2.Final] at org.jboss.netty.channel.Channels.fireMessageReceived(Channels.java:296)[44:org.jboss.netty:3.9.2.Final] at org.jboss.netty.handler.codec.frame.FrameDecoder.unfoldAndFireMessageReceived(FrameDecoder.java:462)[44:org.jboss.netty:3.9.2.Final] at org.jboss.netty.handler.codec.frame.FrameDecoder.callDecode(FrameDecoder.java:443)[44:org.jboss.netty:3.9.2.Final] at org.jboss.netty.handler.codec.frame.FrameDecoder.messageReceived(FrameDecoder.java:303)[44:org.jboss.netty:3.9.2.Final] at org.jboss.netty.channel.Channels.fireMessageReceived(Channels.java:268)[44:org.jboss.netty:3.9.2.Final] at org.jboss.netty.channel.Channels.fireMessageReceived(Channels.java:255)[44:org.jboss.netty:3.9.2.Final] at org.jboss.netty.channel.socket.nio.NioWorker.read(NioWorker.java:88)[44:org.jboss.netty:3.9.2.Final] at org.jboss.netty.channel.socket.nio.AbstractNioWorker.process(AbstractNioWorker.java:108)[44: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)[44:org.jboss.netty:3.9.2.Final] at org.jboss.netty.channel.socket.nio.NioWorker.run(NioWorker.java:178)[44: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-02-18 09:36:35,583 | ERROR | w I/O worker #16 | OFChannelHandler | 130 - org.onosproject.onos-of-ctl - 1.1.0.SNAPSHOT | Error while processing message from switch OFSwitchImplOVS13 [/10.128.40.50:47150 DPID[00:00:00:00:00:00:00:06]]state WAIT_SWITCH_DRIVER_SUB_HANDSHAKE java.lang.NullPointerException at org.onosproject.net.device.impl.DeviceManager$InternalDeviceProviderService.updatePorts(DeviceManager.java:396) at org.onosproject.provider.of.device.impl.OpenFlowDeviceProvider$InternalDeviceProvider.switchAdded(OpenFlowDeviceProvider.java:203) at org.onosproject.openflow.controller.impl.OpenFlowControllerImpl$OpenFlowSwitchAgent.addConnectedSwitch(OpenFlowControllerImpl.java:347) at org.onosproject.openflow.controller.driver.AbstractOpenFlowSwitch.connectSwitch(AbstractOpenFlowSwitch.java:210) at org.onosproject.openflow.controller.impl.OFChannelHandler$ChannelState$7.moveToActive(OFChannelHandler.java:537) at org.onosproject.openflow.controller.impl.OFChannelHandler$ChannelState$7.processOFMessage(OFChannelHandler.java:524) at org.onosproject.openflow.controller.impl.OFChannelHandler.messageReceived(OFChannelHandler.java:1150) at org.jboss.netty.handler.timeout.IdleStateAwareChannelHandler.handleUpstream(IdleStateAwareChannelHandler.java:36)[44:org.jboss.netty:3.9.2.Final] at org.jboss.netty.handler.timeout.ReadTimeoutHandler.messageReceived(ReadTimeoutHandler.java:184)[44:org.jboss.netty:3.9.2.Final] at org.jboss.netty.handler.timeout.IdleStateHandler.messageReceived(IdleStateHandler.java:294)[44:org.jboss.netty:3.9.2.Final] at org.jboss.netty.channel.Channels.fireMessageReceived(Channels.java:296)[44:org.jboss.netty:3.9.2.Final] at org.jboss.netty.handler.codec.frame.FrameDecoder.unfoldAndFireMessageReceived(FrameDecoder.java:462)[44:org.jboss.netty:3.9.2.Final] at org.jboss.netty.handler.codec.frame.FrameDecoder.callDecode(FrameDecoder.java:443)[44:org.jboss.netty:3.9.2.Final] at org.jboss.netty.handler.codec.frame.FrameDecoder.messageReceived(FrameDecoder.java:303)[44:org.jboss.netty:3.9.2.Final] at org.jboss.netty.channel.Channels.fireMessageReceived(Channels.java:268)[44:org.jboss.netty:3.9.2.Final] at org.jboss.netty.channel.Channels.fireMessageReceived(Channels.java:255)[44:org.jboss.netty:3.9.2.Final] at org.jboss.netty.channel.socket.nio.NioWorker.read(NioWorker.java:88)[44:org.jboss.netty:3.9.2.Final] at org.jboss.netty.channel.socket.nio.AbstractNioWorker.process(AbstractNioWorker.java:108)[44: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)[44:org.jboss.netty:3.9.2.Final] at org.jboss.netty.channel.socket.nio.NioWorker.run(NioWorker.java:178)[44: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-02-18 09:36:35,577 | WARN | w I/O worker #13 | ntrollerImpl$OpenFlowSwitchAgent | 130 - org.onosproject.onos-of-ctl - 1.1.0.SNAPSHOT | removal for 00:00:00:00:00:00:00:0a 2015-02-18 09:36:35,589 | INFO | w I/O worker #16 | OFChannelHandler | 130 - org.onosproject.onos-of-ctl - 1.1.0.SNAPSHOT | Switch disconnected callback for sw:OFSwitchImplOVS13 [/10.128.40.50:47150 DPID[00:00:00:00:00:00:00:06]]. Cleaning up ... 2015-02-18 09:36:35,576 | WARN | w I/O worker #10 | ntrollerImpl$OpenFlowSwitchAgent | 130 - org.onosproject.onos-of-ctl - 1.1.0.SNAPSHOT | removal for 00:00:00:00:00:00:00:03 2015-02-18 09:36:35,592 | INFO | w I/O worker #16 | OFChannelHandler | 130 - org.onosproject.onos-of-ctl - 1.1.0.SNAPSHOT | OFSwitchImplOVS13 [/10.128.40.50:47150 DPID[00:00:00:00:00:00:00:06]]:removal called 2015-02-18 09:36:35,592 | WARN | w I/O worker #16 | ntrollerImpl$OpenFlowSwitchAgent | 130 - org.onosproject.onos-of-ctl - 1.1.0.SNAPSHOT | sw was null for 00:00:00:00:00:00:00:06 2015-02-18 09:36:35,592 | WARN | w I/O worker #16 | ntrollerImpl$OpenFlowSwitchAgent | 130 - org.onosproject.onos-of-ctl - 1.1.0.SNAPSHOT | removal for 00:00:00:00:00:00:00:06 2015-02-18 09:36:35,592 | INFO | w I/O worker #16 | DeviceManager | 157 - org.onosproject.onos-core-net - 1.1.0.SNAPSHOT | Device of:0000000000000006 disconnected from this node 2015-02-18 09:36:35,588 | WARN | w I/O worker #14 | ntrollerImpl$OpenFlowSwitchAgent | 130 - org.onosproject.onos-of-ctl - 1.1.0.SNAPSHOT | removal for 00:00:00:00:00:00:00:02 2015-02-18 09:36:35,592 | WARN | w I/O worker #11 | ntrollerImpl$OpenFlowSwitchAgent | 130 - org.onosproject.onos-of-ctl - 1.1.0.SNAPSHOT | removal for 00:00:00:00:00:00:00:01 2015-02-18 09:36:35,588 | WARN | w I/O worker #12 | ntrollerImpl$OpenFlowSwitchAgent | 130 - org.onosproject.onos-of-ctl - 1.1.0.SNAPSHOT | removal for 00:00:00:00:00:00:00:11 2015-02-18 09:36:35,594 | WARN | ew I/O worker #9 | ntrollerImpl$OpenFlowSwitchAgent | 130 - org.onosproject.onos-of-ctl - 1.1.0.SNAPSHOT | removal for 00:00:00:00:00:00:00:04 2015-02-18 09:36:35,583 | INFO | w I/O worker #15 | OFChannelHandler | 130 - org.onosproject.onos-of-ctl - 1.1.0.SNAPSHOT | Switch disconnected callback for sw:OFSwitchImplOVS13 [/10.128.40.50:47149 DPID[00:00:00:00:00:00:00:09]]. Cleaning up ... 2015-02-18 09:36:35,595 | INFO | w I/O worker #15 | OFChannelHandler | 130 - org.onosproject.onos-of-ctl - 1.1.0.SNAPSHOT | OFSwitchImplOVS13 [/10.128.40.50:47149 DPID[00:00:00:00:00:00:00:09]]:removal called 2015-02-18 09:36:35,596 | WARN | w I/O worker #15 | ntrollerImpl$OpenFlowSwitchAgent | 130 - org.onosproject.onos-of-ctl - 1.1.0.SNAPSHOT | sw was null for 00:00:00:00:00:00:00:09 2015-02-18 09:36:35,596 | WARN | w I/O worker #15 | ntrollerImpl$OpenFlowSwitchAgent | 130 - org.onosproject.onos-of-ctl - 1.1.0.SNAPSHOT | removal for 00:00:00:00:00:00:00:09 2015-02-18 09:36:35,596 | INFO | w I/O worker #15 | DeviceManager | 157 - org.onosproject.onos-core-net - 1.1.0.SNAPSHOT | Device of:0000000000000009 disconnected from this node 2015-02-18 09:36:35,608 | WARN | w I/O worker #16 | ntrollerImpl$OpenFlowSwitchAgent | 130 - org.onosproject.onos-of-ctl - 1.1.0.SNAPSHOT | removal for 00:00:00:00:00:00:00:06 2015-02-18 09:36:35,611 | WARN | w I/O worker #15 | ntrollerImpl$OpenFlowSwitchAgent | 130 - org.onosproject.onos-of-ctl - 1.1.0.SNAPSHOT | removal for 00:00:00:00:00:00:00:09 2015-02-18 09:36:36,434 | INFO | ew I/O worker #1 | OFChannelHandler | 130 - org.onosproject.onos-of-ctl - 1.1.0.SNAPSHOT | New switch connection from /10.128.40.50:47154 2015-02-18 09:36:36,435 | INFO | ew I/O worker #2 | OFChannelHandler | 130 - org.onosproject.onos-of-ctl - 1.1.0.SNAPSHOT | New switch connection from /10.128.40.50:47160 2015-02-18 09:36:36,435 | INFO | ew I/O worker #3 | OFChannelHandler | 130 - org.onosproject.onos-of-ctl - 1.1.0.SNAPSHOT | New switch connection from /10.128.40.50:47161 2015-02-18 09:36:36,435 | INFO | ew I/O worker #1 | OFChannelHandler | 130 - org.onosproject.onos-of-ctl - 1.1.0.SNAPSHOT | Sending OF_13 Hello to /10.128.40.50:47154 2015-02-18 09:36:36,436 | INFO | ew I/O worker #2 | OFChannelHandler | 130 - org.onosproject.onos-of-ctl - 1.1.0.SNAPSHOT | Sending OF_13 Hello to /10.128.40.50:47160 2015-02-18 09:36:36,436 | INFO | ew I/O worker #3 | OFChannelHandler | 130 - org.onosproject.onos-of-ctl - 1.1.0.SNAPSHOT | Sending OF_13 Hello to /10.128.40.50:47161 2015-02-18 09:36:36,438 | INFO | ew I/O worker #1 | OFChannelHandler | 130 - org.onosproject.onos-of-ctl - 1.1.0.SNAPSHOT | Received port desc reply for switch at [/10.128.40.50:47154 DPID[00:00:00:00:00:00:00:15]] 2015-02-18 09:36:36,439 | INFO | ew I/O worker #2 | OFChannelHandler | 130 - org.onosproject.onos-of-ctl - 1.1.0.SNAPSHOT | Received port desc reply for switch at [/10.128.40.50:47160 DPID[00:00:00:00:00:00:00:16]] 2015-02-18 09:36:36,439 | INFO | ew I/O worker #3 | OFChannelHandler | 130 - org.onosproject.onos-of-ctl - 1.1.0.SNAPSHOT | Received port desc reply for switch at [/10.128.40.50:47161 DPID[00:00:00:00:00:00:00:18]] 2015-02-18 09:36:36,442 | INFO | ew I/O worker #1 | OFChannelHandler | 130 - org.onosproject.onos-of-ctl - 1.1.0.SNAPSHOT | Received switch description reply from switch at /10.128.40.50:47154 2015-02-18 09:36:36,443 | INFO | ew I/O worker #3 | OFChannelHandler | 130 - org.onosproject.onos-of-ctl - 1.1.0.SNAPSHOT | Received switch description reply from switch at /10.128.40.50:47161 2015-02-18 09:36:36,443 | INFO | ew I/O worker #2 | OFChannelHandler | 130 - org.onosproject.onos-of-ctl - 1.1.0.SNAPSHOT | Received switch description reply from switch at /10.128.40.50:47160 2015-02-18 09:36:36,443 | INFO | ew I/O worker #1 | ntrollerImpl$OpenFlowSwitchAgent | 130 - org.onosproject.onos-of-ctl - 1.1.0.SNAPSHOT | Added switch 00:00:00:00:00:00:00:15 2015-02-18 09:36:36,443 | INFO | ew I/O worker #1 | DeviceManager | 157 - org.onosproject.onos-core-net - 1.1.0.SNAPSHOT | Device of:0000000000000015 connected 2015-02-18 09:36:36,444 | INFO | ew I/O worker #3 | ntrollerImpl$OpenFlowSwitchAgent | 130 - org.onosproject.onos-of-ctl - 1.1.0.SNAPSHOT | Added switch 00:00:00:00:00:00:00:18 2015-02-18 09:36:36,444 | INFO | ew I/O worker #3 | DeviceManager | 157 - org.onosproject.onos-core-net - 1.1.0.SNAPSHOT | Device of:0000000000000018 connected 2015-02-18 09:36:36,444 | INFO | ew I/O worker #2 | ntrollerImpl$OpenFlowSwitchAgent | 130 - org.onosproject.onos-of-ctl - 1.1.0.SNAPSHOT | Added switch 00:00:00:00:00:00:00:16 2015-02-18 09:36:36,444 | INFO | ew I/O worker #2 | DeviceManager | 157 - org.onosproject.onos-core-net - 1.1.0.SNAPSHOT | Device of:0000000000000016 connected 2015-02-18 09:36:36,452 | INFO | ew I/O worker #2 | DeviceManager | 157 - org.onosproject.onos-core-net - 1.1.0.SNAPSHOT | Role of this node is STANDBY for of:0000000000000016 2015-02-18 09:36:36,452 | INFO | ew I/O worker #2 | OFSwitchImplOVS13 | 128 - org.onosproject.onos-of-api - 1.1.0.SNAPSHOT | Sending role EQUAL to switch 00:00:00:00:00:00:00:16 2015-02-18 09:36:36,452 | INFO | ew I/O worker #2 | OpenFlowDeviceProvider | 133 - org.onosproject.onos-of-provider-device - 1.1.0.SNAPSHOT | Accepting mastership role change for device of:0000000000000016 2015-02-18 09:36:36,459 | ERROR | ew I/O worker #2 | OFChannelHandler | 130 - org.onosproject.onos-of-ctl - 1.1.0.SNAPSHOT | Error while processing message from switch OFSwitchImplOVS13 [/10.128.40.50:47160 DPID[00:00:00:00:00:00:00:16]]state WAIT_SWITCH_DRIVER_SUB_HANDSHAKE java.lang.NullPointerException at org.onosproject.net.device.impl.DeviceManager$InternalDeviceProviderService.updatePorts(DeviceManager.java:396) at org.onosproject.provider.of.device.impl.OpenFlowDeviceProvider$InternalDeviceProvider.switchAdded(OpenFlowDeviceProvider.java:203) at org.onosproject.openflow.controller.impl.OpenFlowControllerImpl$OpenFlowSwitchAgent.addConnectedSwitch(OpenFlowControllerImpl.java:347) at org.onosproject.openflow.controller.driver.AbstractOpenFlowSwitch.connectSwitch(AbstractOpenFlowSwitch.java:210) at org.onosproject.openflow.controller.impl.OFChannelHandler$ChannelState$7.moveToActive(OFChannelHandler.java:537) at org.onosproject.openflow.controller.impl.OFChannelHandler$ChannelState$7.processOFMessage(OFChannelHandler.java:524) at org.onosproject.openflow.controller.impl.OFChannelHandler.messageReceived(OFChannelHandler.java:1150) at org.jboss.netty.handler.timeout.IdleStateAwareChannelHandler.handleUpstream(IdleStateAwareChannelHandler.java:36)[44:org.jboss.netty:3.9.2.Final] at org.jboss.netty.handler.timeout.ReadTimeoutHandler.messageReceived(ReadTimeoutHandler.java:184)[44:org.jboss.netty:3.9.2.Final] at org.jboss.netty.handler.timeout.IdleStateHandler.messageReceived(IdleStateHandler.java:294)[44:org.jboss.netty:3.9.2.Final] at org.jboss.netty.channel.Channels.fireMessageReceived(Channels.java:296)[44:org.jboss.netty:3.9.2.Final] at org.jboss.netty.handler.codec.frame.FrameDecoder.unfoldAndFireMessageReceived(FrameDecoder.java:462)[44:org.jboss.netty:3.9.2.Final] at org.jboss.netty.handler.codec.frame.FrameDecoder.callDecode(FrameDecoder.java:443)[44:org.jboss.netty:3.9.2.Final] at org.jboss.netty.handler.codec.frame.FrameDecoder.messageReceived(FrameDecoder.java:303)[44:org.jboss.netty:3.9.2.Final] at org.jboss.netty.channel.Channels.fireMessageReceived(Channels.java:268)[44:org.jboss.netty:3.9.2.Final] at org.jboss.netty.channel.Channels.fireMessageReceived(Channels.java:255)[44:org.jboss.netty:3.9.2.Final] at org.jboss.netty.channel.socket.nio.NioWorker.read(NioWorker.java:88)[44:org.jboss.netty:3.9.2.Final] at org.jboss.netty.channel.socket.nio.AbstractNioWorker.process(AbstractNioWorker.java:108)[44: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)[44:org.jboss.netty:3.9.2.Final] at org.jboss.netty.channel.socket.nio.NioWorker.run(NioWorker.java:178)[44: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-02-18 09:36:36,462 | INFO | ew I/O worker #3 | DeviceManager | 157 - org.onosproject.onos-core-net - 1.1.0.SNAPSHOT | Role of this node is STANDBY for of:0000000000000018 2015-02-18 09:36:36,462 | INFO | ew I/O worker #1 | DeviceManager | 157 - org.onosproject.onos-core-net - 1.1.0.SNAPSHOT | Role of this node is STANDBY for of:0000000000000015 2015-02-18 09:36:36,467 | INFO | ew I/O worker #2 | OFChannelHandler | 130 - org.onosproject.onos-of-ctl - 1.1.0.SNAPSHOT | Switch disconnected callback for sw:OFSwitchImplOVS13 [/10.128.40.50:47160 DPID[00:00:00:00:00:00:00:16]]. Cleaning up ... 2015-02-18 09:36:36,467 | INFO | ew I/O worker #2 | OFChannelHandler | 130 - org.onosproject.onos-of-ctl - 1.1.0.SNAPSHOT | OFSwitchImplOVS13 [/10.128.40.50:47160 DPID[00:00:00:00:00:00:00:16]]:removal called 2015-02-18 09:36:36,467 | WARN | ew I/O worker #2 | ntrollerImpl$OpenFlowSwitchAgent | 130 - org.onosproject.onos-of-ctl - 1.1.0.SNAPSHOT | sw was null for 00:00:00:00:00:00:00:16 2015-02-18 09:36:36,467 | INFO | ew I/O worker #1 | OFSwitchImplOVS13 | 128 - org.onosproject.onos-of-api - 1.1.0.SNAPSHOT | Sending role EQUAL to switch 00:00:00:00:00:00:00:15 2015-02-18 09:36:36,467 | INFO | ew I/O worker #1 | OpenFlowDeviceProvider | 133 - org.onosproject.onos-of-provider-device - 1.1.0.SNAPSHOT | Accepting mastership role change for device of:0000000000000015 2015-02-18 09:36:36,467 | WARN | ew I/O worker #2 | ntrollerImpl$OpenFlowSwitchAgent | 130 - org.onosproject.onos-of-ctl - 1.1.0.SNAPSHOT | removal for 00:00:00:00:00:00:00:16 2015-02-18 09:36:36,468 | INFO | ew I/O worker #2 | DeviceManager | 157 - org.onosproject.onos-core-net - 1.1.0.SNAPSHOT | Device of:0000000000000016 disconnected from this node 2015-02-18 09:36:36,468 | INFO | ew I/O worker #3 | OFSwitchImplOVS13 | 128 - org.onosproject.onos-of-api - 1.1.0.SNAPSHOT | Sending role EQUAL to switch 00:00:00:00:00:00:00:18 2015-02-18 09:36:36,468 | INFO | ew I/O worker #3 | OpenFlowDeviceProvider | 133 - org.onosproject.onos-of-provider-device - 1.1.0.SNAPSHOT | Accepting mastership role change for device of:0000000000000018 2015-02-18 09:36:36,472 | ERROR | ew I/O worker #1 | OFChannelHandler | 130 - org.onosproject.onos-of-ctl - 1.1.0.SNAPSHOT | Error while processing message from switch OFSwitchImplOVS13 [/10.128.40.50:47154 DPID[00:00:00:00:00:00:00:15]]state WAIT_SWITCH_DRIVER_SUB_HANDSHAKE java.lang.NullPointerException at org.onosproject.net.device.impl.DeviceManager$InternalDeviceProviderService.updatePorts(DeviceManager.java:396) at org.onosproject.provider.of.device.impl.OpenFlowDeviceProvider$InternalDeviceProvider.switchAdded(OpenFlowDeviceProvider.java:203) at org.onosproject.openflow.controller.impl.OpenFlowControllerImpl$OpenFlowSwitchAgent.addConnectedSwitch(OpenFlowControllerImpl.java:347) at org.onosproject.openflow.controller.driver.AbstractOpenFlowSwitch.connectSwitch(AbstractOpenFlowSwitch.java:210) at org.onosproject.openflow.controller.impl.OFChannelHandler$ChannelState$7.moveToActive(OFChannelHandler.java:537) at org.onosproject.openflow.controller.impl.OFChannelHandler$ChannelState$7.processOFMessage(OFChannelHandler.java:524) at org.onosproject.openflow.controller.impl.OFChannelHandler.messageReceived(OFChannelHandler.java:1150) at org.jboss.netty.handler.timeout.IdleStateAwareChannelHandler.handleUpstream(IdleStateAwareChannelHandler.java:36)[44:org.jboss.netty:3.9.2.Final] at org.jboss.netty.handler.timeout.ReadTimeoutHandler.messageReceived(ReadTimeoutHandler.java:184)[44:org.jboss.netty:3.9.2.Final] at org.jboss.netty.handler.timeout.IdleStateHandler.messageReceived(IdleStateHandler.java:294)[44:org.jboss.netty:3.9.2.Final] at org.jboss.netty.channel.Channels.fireMessageReceived(Channels.java:296)[44:org.jboss.netty:3.9.2.Final] at org.jboss.netty.handler.codec.frame.FrameDecoder.unfoldAndFireMessageReceived(FrameDecoder.java:462)[44:org.jboss.netty:3.9.2.Final] at org.jboss.netty.handler.codec.frame.FrameDecoder.callDecode(FrameDecoder.java:443)[44:org.jboss.netty:3.9.2.Final] at org.jboss.netty.handler.codec.frame.FrameDecoder.messageReceived(FrameDecoder.java:303)[44:org.jboss.netty:3.9.2.Final] at org.jboss.netty.channel.Channels.fireMessageReceived(Channels.java:268)[44:org.jboss.netty:3.9.2.Final] at org.jboss.netty.channel.Channels.fireMessageReceived(Channels.java:255)[44:org.jboss.netty:3.9.2.Final] at org.jboss.netty.channel.socket.nio.NioWorker.read(NioWorker.java:88)[44:org.jboss.netty:3.9.2.Final] at org.jboss.netty.channel.socket.nio.AbstractNioWorker.process(AbstractNioWorker.java:108)[44: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)[44:org.jboss.netty:3.9.2.Final] at org.jboss.netty.channel.socket.nio.NioWorker.run(NioWorker.java:178)[44: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-02-18 09:36:36,474 | ERROR | ew I/O worker #3 | OFChannelHandler | 130 - org.onosproject.onos-of-ctl - 1.1.0.SNAPSHOT | Error while processing message from switch OFSwitchImplOVS13 [/10.128.40.50:47161 DPID[00:00:00:00:00:00:00:18]]state WAIT_SWITCH_DRIVER_SUB_HANDSHAKE java.lang.NullPointerException at org.onosproject.net.device.impl.DeviceManager$InternalDeviceProviderService.updatePorts(DeviceManager.java:396) at org.onosproject.provider.of.device.impl.OpenFlowDeviceProvider$InternalDeviceProvider.switchAdded(OpenFlowDeviceProvider.java:203) at org.onosproject.openflow.controller.impl.OpenFlowControllerImpl$OpenFlowSwitchAgent.addConnectedSwitch(OpenFlowControllerImpl.java:347) at org.onosproject.openflow.controller.driver.AbstractOpenFlowSwitch.connectSwitch(AbstractOpenFlowSwitch.java:210) at org.onosproject.openflow.controller.impl.OFChannelHandler$ChannelState$7.moveToActive(OFChannelHandler.java:537) at org.onosproject.openflow.controller.impl.OFChannelHandler$ChannelState$7.processOFMessage(OFChannelHandler.java:524) at org.onosproject.openflow.controller.impl.OFChannelHandler.messageReceived(OFChannelHandler.java:1150) at org.jboss.netty.handler.timeout.IdleStateAwareChannelHandler.handleUpstream(IdleStateAwareChannelHandler.java:36)[44:org.jboss.netty:3.9.2.Final] at org.jboss.netty.handler.timeout.ReadTimeoutHandler.messageReceived(ReadTimeoutHandler.java:184)[44:org.jboss.netty:3.9.2.Final] at org.jboss.netty.handler.timeout.IdleStateHandler.messageReceived(IdleStateHandler.java:294)[44:org.jboss.netty:3.9.2.Final] at org.jboss.netty.channel.Channels.fireMessageReceived(Channels.java:296)[44:org.jboss.netty:3.9.2.Final] at org.jboss.netty.handler.codec.frame.FrameDecoder.unfoldAndFireMessageReceived(FrameDecoder.java:462)[44:org.jboss.netty:3.9.2.Final] at org.jboss.netty.handler.codec.frame.FrameDecoder.callDecode(FrameDecoder.java:443)[44:org.jboss.netty:3.9.2.Final] at org.jboss.netty.handler.codec.frame.FrameDecoder.messageReceived(FrameDecoder.java:303)[44:org.jboss.netty:3.9.2.Final] at org.jboss.netty.channel.Channels.fireMessageReceived(Channels.java:268)[44:org.jboss.netty:3.9.2.Final] at org.jboss.netty.channel.Channels.fireMessageReceived(Channels.java:255)[44:org.jboss.netty:3.9.2.Final] at org.jboss.netty.channel.socket.nio.NioWorker.read(NioWorker.java:88)[44:org.jboss.netty:3.9.2.Final] at org.jboss.netty.channel.socket.nio.AbstractNioWorker.process(AbstractNioWorker.java:108)[44: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)[44:org.jboss.netty:3.9.2.Final] at org.jboss.netty.channel.socket.nio.NioWorker.run(NioWorker.java:178)[44: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-02-18 09:36:36,492 | INFO | ew I/O worker #3 | OFChannelHandler | 130 - org.onosproject.onos-of-ctl - 1.1.0.SNAPSHOT | Switch disconnected callback for sw:OFSwitchImplOVS13 [/10.128.40.50:47161 DPID[00:00:00:00:00:00:00:18]]. Cleaning up ... 2015-02-18 09:36:36,483 | WARN | ew I/O worker #2 | ntrollerImpl$OpenFlowSwitchAgent | 130 - org.onosproject.onos-of-ctl - 1.1.0.SNAPSHOT | removal for 00:00:00:00:00:00:00:16 2015-02-18 09:36:36,480 | INFO | ew I/O worker #1 | OFChannelHandler | 130 - org.onosproject.onos-of-ctl - 1.1.0.SNAPSHOT | Switch disconnected callback for sw:OFSwitchImplOVS13 [/10.128.40.50:47154 DPID[00:00:00:00:00:00:00:15]]. Cleaning up ... 2015-02-18 09:36:36,492 | INFO | ew I/O worker #3 | OFChannelHandler | 130 - org.onosproject.onos-of-ctl - 1.1.0.SNAPSHOT | OFSwitchImplOVS13 [/10.128.40.50:47161 DPID[00:00:00:00:00:00:00:18]]:removal called 2015-02-18 09:36:36,501 | WARN | ew I/O worker #3 | ntrollerImpl$OpenFlowSwitchAgent | 130 - org.onosproject.onos-of-ctl - 1.1.0.SNAPSHOT | sw was null for 00:00:00:00:00:00:00:18 2015-02-18 09:36:36,501 | INFO | ew I/O worker #1 | OFChannelHandler | 130 - org.onosproject.onos-of-ctl - 1.1.0.SNAPSHOT | OFSwitchImplOVS13 [/10.128.40.50:47154 DPID[00:00:00:00:00:00:00:15]]:removal called 2015-02-18 09:36:36,501 | WARN | ew I/O worker #1 | ntrollerImpl$OpenFlowSwitchAgent | 130 - org.onosproject.onos-of-ctl - 1.1.0.SNAPSHOT | sw was null for 00:00:00:00:00:00:00:15 2015-02-18 09:36:36,501 | WARN | ew I/O worker #1 | ntrollerImpl$OpenFlowSwitchAgent | 130 - org.onosproject.onos-of-ctl - 1.1.0.SNAPSHOT | removal for 00:00:00:00:00:00:00:15 2015-02-18 09:36:36,501 | INFO | ew I/O worker #1 | DeviceManager | 157 - org.onosproject.onos-core-net - 1.1.0.SNAPSHOT | Device of:0000000000000015 disconnected from this node 2015-02-18 09:36:36,502 | WARN | ew I/O worker #3 | ntrollerImpl$OpenFlowSwitchAgent | 130 - org.onosproject.onos-of-ctl - 1.1.0.SNAPSHOT | removal for 00:00:00:00:00:00:00:18 2015-02-18 09:36:36,502 | INFO | ew I/O worker #3 | DeviceManager | 157 - org.onosproject.onos-core-net - 1.1.0.SNAPSHOT | Device of:0000000000000018 disconnected from this node 2015-02-18 09:36:36,511 | WARN | ew I/O worker #3 | ntrollerImpl$OpenFlowSwitchAgent | 130 - org.onosproject.onos-of-ctl - 1.1.0.SNAPSHOT | removal for 00:00:00:00:00:00:00:18 2015-02-18 09:36:36,514 | WARN | ew I/O worker #1 | ntrollerImpl$OpenFlowSwitchAgent | 130 - org.onosproject.onos-of-ctl - 1.1.0.SNAPSHOT | removal for 00:00:00:00:00:00:00:15 onos> admin@chONOS1:~$ admin@chONOS1:~$ admin@chONOS1:~$ /opt/onos/bin/onos Logging in as karaf Failed to get the session. admin@chONOS1:~$ admin@chONOS1:~$ /opt/onos/bin/onos Logging in as karaf Failed to get the session. admin@chONOS1:~$ admin@chONOS1:~$ /opt/onos/bin/onos Logging in as karaf Failed to get the session. admin@chONOS1:~$ admin@chONOS1:~$ ps -ef | grep java admin 15719 13758 0 09:38 pts/0 00:00:00 grep --color=auto java admin@chONOS1:~$ admin@chONOS1:~$ ps -ef | grep java admin 15721 13758 0 09:39 pts/0 00:00:00 grep --color=auto java admin@chONOS1:~$ ############################################ KARAF LOGS accessed from bench ################################# admin@chONOSbench:~/ONOS$ admin@chONOSbench:~/ONOS$ onos-log $OC1 ================================================================== at org.jboss.netty.handler.codec.frame.FrameDecoder.messageReceived(FrameDecoder.java:303)[44:org.jboss.netty:3.9.2.Final] at org.jboss.netty.channel.Channels.fireMessageReceived(Channels.java:268)[44:org.jboss.netty:3.9.2.Final] at org.jboss.netty.channel.Channels.fireMessageReceived(Channels.java:255)[44:org.jboss.netty:3.9.2.Final] at org.jboss.netty.channel.socket.nio.NioWorker.read(NioWorker.java:88)[44:org.jboss.netty:3.9.2.Final] at org.jboss.netty.channel.socket.nio.AbstractNioWorker.process(AbstractNioWorker.java:108)[44: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)[44:org.jboss.netty:3.9.2.Final] at org.jboss.netty.channel.socket.nio.NioWorker.run(NioWorker.java:178)[44: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-02-18 09:36:43,521 | INFO | ew I/O worker #4 | DeviceManager | 157 - org.onosproject.onos-core-net - 1.1.0.SNAPSHOT | Role of this node is STANDBY for of:0000000000000004 2015-02-18 09:36:43,520 | INFO | w I/O worker #10 | DeviceManager | 157 - org.onosproject.onos-core-net - 1.1.0.SNAPSHOT | Role of this node is STANDBY for of:0000000000000009 2015-02-18 09:36:43,534 | INFO | ew I/O worker #6 | OFChannelHandler | 130 - org.onosproject.onos-of-ctl - 1.1.0.SNAPSHOT | Switch disconnected callback for sw:OFSwitchImplOVS13 [/10.128.40.50:47170 DPID[00:00:00:00:00:00:00:01]]. Cleaning up ... 2015-02-18 09:36:43,534 | INFO | ew I/O worker #6 | OFChannelHandler | 130 - org.onosproject.onos-of-ctl - 1.1.0.SNAPSHOT | OFSwitchImplOVS13 [/10.128.40.50:47170 DPID[00:00:00:00:00:00:00:01]]:removal called 2015-02-18 09:36:43,524 | WARN | ew I/O worker #8 | ntrollerImpl$OpenFlowSwitchAgent | 130 - org.onosproject.onos-of-ctl - 1.1.0.SNAPSHOT | removal for 00:00:00:00:00:00:00:0a 2015-02-18 09:36:43,523 | INFO | w I/O worker #11 | OpenFlowDeviceProvider | 133 - org.onosproject.onos-of-provider-device - 1.1.0.SNAPSHOT | Accepting mastership role change for device of:0000000000000006 2015-02-18 09:36:43,535 | INFO | ew I/O worker #8 | DeviceManager | 157 - org.onosproject.onos-core-net - 1.1.0.SNAPSHOT | Device of:000000000000000a disconnected from this node 2015-02-18 09:36:43,535 | INFO | ew I/O worker #4 | OFSwitchImplOVS13 | 128 - org.onosproject.onos-of-api - 1.1.0.SNAPSHOT | Sending role EQUAL to switch 00:00:00:00:00:00:00:04 2015-02-18 09:36:43,535 | INFO | ew I/O worker #4 | OpenFlowDeviceProvider | 133 - org.onosproject.onos-of-provider-device - 1.1.0.SNAPSHOT | Accepting mastership role change for device of:0000000000000004 2015-02-18 09:36:43,534 | WARN | ew I/O worker #6 | ntrollerImpl$OpenFlowSwitchAgent | 130 - org.onosproject.onos-of-ctl - 1.1.0.SNAPSHOT | sw was null for 00:00:00:00:00:00:00:01 2015-02-18 09:36:43,534 | INFO | w I/O worker #10 | OFSwitchImplOVS13 | 128 - org.onosproject.onos-of-api - 1.1.0.SNAPSHOT | Sending role EQUAL to switch 00:00:00:00:00:00:00:09 2015-02-18 09:36:43,535 | INFO | w I/O worker #10 | OpenFlowDeviceProvider | 133 - org.onosproject.onos-of-provider-device - 1.1.0.SNAPSHOT | Accepting mastership role change for device of:0000000000000009 2015-02-18 09:36:43,536 | WARN | ew I/O worker #6 | ntrollerImpl$OpenFlowSwitchAgent | 130 - org.onosproject.onos-of-ctl - 1.1.0.SNAPSHOT | removal for 00:00:00:00:00:00:00:01 2015-02-18 09:36:43,536 | INFO | ew I/O worker #6 | DeviceManager | 157 - org.onosproject.onos-core-net - 1.1.0.SNAPSHOT | Device of:0000000000000001 disconnected from this node 2015-02-18 09:36:43,536 | WARN | ew I/O worker #5 | ntrollerImpl$OpenFlowSwitchAgent | 130 - org.onosproject.onos-of-ctl - 1.1.0.SNAPSHOT | removal for 00:00:00:00:00:00:00:03 2015-02-18 09:36:43,540 | ERROR | w I/O worker #10 | OFChannelHandler | 130 - org.onosproject.onos-of-ctl - 1.1.0.SNAPSHOT | Error while processing message from switch OFSwitchImplOVS13 [/10.128.40.50:47186 DPID[00:00:00:00:00:00:00:09]]state WAIT_SWITCH_DRIVER_SUB_HANDSHAKE java.lang.NullPointerException at org.onosproject.net.device.impl.DeviceManager$InternalDeviceProviderService.updatePorts(DeviceManager.java:396) at org.onosproject.provider.of.device.impl.OpenFlowDeviceProvider$InternalDeviceProvider.switchAdded(OpenFlowDeviceProvider.java:203) at org.onosproject.openflow.controller.impl.OpenFlowControllerImpl$OpenFlowSwitchAgent.addConnectedSwitch(OpenFlowControllerImpl.java:347) at org.onosproject.openflow.controller.driver.AbstractOpenFlowSwitch.connectSwitch(AbstractOpenFlowSwitch.java:210) at org.onosproject.openflow.controller.impl.OFChannelHandler$ChannelState$7.moveToActive(OFChannelHandler.java:537) at org.onosproject.openflow.controller.impl.OFChannelHandler$ChannelState$7.processOFMessage(OFChannelHandler.java:524) at org.onosproject.openflow.controller.impl.OFChannelHandler.messageReceived(OFChannelHandler.java:1150) at org.jboss.netty.handler.timeout.IdleStateAwareChannelHandler.handleUpstream(IdleStateAwareChannelHandler.java:36)[44:org.jboss.netty:3.9.2.Final] at org.jboss.netty.handler.timeout.ReadTimeoutHandler.messageReceived(ReadTimeoutHandler.java:184)[44:org.jboss.netty:3.9.2.Final] at org.jboss.netty.handler.timeout.IdleStateHandler.messageReceived(IdleStateHandler.java:294)[44:org.jboss.netty:3.9.2.Final] at org.jboss.netty.channel.Channels.fireMessageReceived(Channels.java:296)[44:org.jboss.netty:3.9.2.Final] at org.jboss.netty.handler.codec.frame.FrameDecoder.unfoldAndFireMessageReceived(FrameDecoder.java:462)[44:org.jboss.netty:3.9.2.Final] at org.jboss.netty.handler.codec.frame.FrameDecoder.callDecode(FrameDecoder.java:443)[44:org.jboss.netty:3.9.2.Final] at org.jboss.netty.handler.codec.frame.FrameDecoder.messageReceived(FrameDecoder.java:303)[44:org.jboss.netty:3.9.2.Final] at org.jboss.netty.channel.Channels.fireMessageReceived(Channels.java:268)[44:org.jboss.netty:3.9.2.Final] at org.jboss.netty.channel.Channels.fireMessageReceived(Channels.java:255)[44:org.jboss.netty:3.9.2.Final] at org.jboss.netty.channel.socket.nio.NioWorker.read(NioWorker.java:88)[44:org.jboss.netty:3.9.2.Final] at org.jboss.netty.channel.socket.nio.AbstractNioWorker.process(AbstractNioWorker.java:108)[44: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)[44:org.jboss.netty:3.9.2.Final] at org.jboss.netty.channel.socket.nio.NioWorker.run(NioWorker.java:178)[44: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-02-18 09:36:43,544 | INFO | ew I/O worker #7 | DeviceManager | 157 - org.onosproject.onos-core-net - 1.1.0.SNAPSHOT | Role of this node is STANDBY for of:0000000000000011 2015-02-18 09:36:43,540 | ERROR | ew I/O worker #4 | OFChannelHandler | 130 - org.onosproject.onos-of-ctl - 1.1.0.SNAPSHOT | Error while processing message from switch OFSwitchImplOVS13 [/10.128.40.50:47164 DPID[00:00:00:00:00:00:00:04]]state WAIT_SWITCH_DRIVER_SUB_HANDSHAKE java.lang.NullPointerException at org.onosproject.net.device.impl.DeviceManager$InternalDeviceProviderService.updatePorts(DeviceManager.java:396) at org.onosproject.provider.of.device.impl.OpenFlowDeviceProvider$InternalDeviceProvider.switchAdded(OpenFlowDeviceProvider.java:203) at org.onosproject.openflow.controller.impl.OpenFlowControllerImpl$OpenFlowSwitchAgent.addConnectedSwitch(OpenFlowControllerImpl.java:347) at org.onosproject.openflow.controller.driver.AbstractOpenFlowSwitch.connectSwitch(AbstractOpenFlowSwitch.java:210) at org.onosproject.openflow.controller.impl.OFChannelHandler$ChannelState$7.moveToActive(OFChannelHandler.java:537) at org.onosproject.openflow.controller.impl.OFChannelHandler$ChannelState$7.processOFMessage(OFChannelHandler.java:524) at org.onosproject.openflow.controller.impl.OFChannelHandler.messageReceived(OFChannelHandler.java:1150) at org.jboss.netty.handler.timeout.IdleStateAwareChannelHandler.handleUpstream(IdleStateAwareChannelHandler.java:36)[44:org.jboss.netty:3.9.2.Final] at org.jboss.netty.handler.timeout.ReadTimeoutHandler.messageReceived(ReadTimeoutHandler.java:184)[44:org.jboss.netty:3.9.2.Final] at org.jboss.netty.handler.timeout.IdleStateHandler.messageReceived(IdleStateHandler.java:294)[44:org.jboss.netty:3.9.2.Final] at org.jboss.netty.channel.Channels.fireMessageReceived(Channels.java:296)[44:org.jboss.netty:3.9.2.Final] at org.jboss.netty.handler.codec.frame.FrameDecoder.unfoldAndFireMessageReceived(FrameDecoder.java:462)[44:org.jboss.netty:3.9.2.Final] at org.jboss.netty.handler.codec.frame.FrameDecoder.callDecode(FrameDecoder.java:443)[44:org.jboss.netty:3.9.2.Final] at org.jboss.netty.handler.codec.frame.FrameDecoder.messageReceived(FrameDecoder.java:303)[44:org.jboss.netty:3.9.2.Final] at org.jboss.netty.channel.Channels.fireMessageReceived(Channels.java:268)[44:org.jboss.netty:3.9.2.Final] at org.jboss.netty.channel.Channels.fireMessageReceived(Channels.java:255)[44:org.jboss.netty:3.9.2.Final] at org.jboss.netty.channel.socket.nio.NioWorker.read(NioWorker.java:88)[44:org.jboss.netty:3.9.2.Final] at org.jboss.netty.channel.socket.nio.AbstractNioWorker.process(AbstractNioWorker.java:108)[44: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)[44:org.jboss.netty:3.9.2.Final] at org.jboss.netty.channel.socket.nio.NioWorker.run(NioWorker.java:178)[44: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-02-18 09:36:43,552 | WARN | ew I/O worker #8 | ntrollerImpl$OpenFlowSwitchAgent | 130 - org.onosproject.onos-of-ctl - 1.1.0.SNAPSHOT | removal for 00:00:00:00:00:00:00:0a 2015-02-18 09:36:43,547 | INFO | ew I/O worker #7 | OFSwitchImplOVS13 | 128 - org.onosproject.onos-of-api - 1.1.0.SNAPSHOT | Sending role EQUAL to switch 00:00:00:00:00:00:00:11 2015-02-18 09:36:43,547 | INFO | w I/O worker #10 | OFChannelHandler | 130 - org.onosproject.onos-of-ctl - 1.1.0.SNAPSHOT | Switch disconnected callback for sw:OFSwitchImplOVS13 [/10.128.40.50:47186 DPID[00:00:00:00:00:00:00:09]]. Cleaning up ... 2015-02-18 09:36:43,555 | INFO | ew I/O worker #7 | OpenFlowDeviceProvider | 133 - org.onosproject.onos-of-provider-device - 1.1.0.SNAPSHOT | Accepting mastership role change for device of:0000000000000011 2015-02-18 09:36:43,555 | INFO | w I/O worker #10 | OFChannelHandler | 130 - org.onosproject.onos-of-ctl - 1.1.0.SNAPSHOT | OFSwitchImplOVS13 [/10.128.40.50:47186 DPID[00:00:00:00:00:00:00:09]]:removal called 2015-02-18 09:36:43,555 | INFO | ew I/O worker #4 | OFChannelHandler | 130 - org.onosproject.onos-of-ctl - 1.1.0.SNAPSHOT | Switch disconnected callback for sw:OFSwitchImplOVS13 [/10.128.40.50:47164 DPID[00:00:00:00:00:00:00:04]]. Cleaning up ... 2015-02-18 09:36:43,555 | WARN | w I/O worker #10 | ntrollerImpl$OpenFlowSwitchAgent | 130 - org.onosproject.onos-of-ctl - 1.1.0.SNAPSHOT | sw was null for 00:00:00:00:00:00:00:09 2015-02-18 09:36:43,555 | INFO | ew I/O worker #4 | OFChannelHandler | 130 - org.onosproject.onos-of-ctl - 1.1.0.SNAPSHOT | OFSwitchImplOVS13 [/10.128.40.50:47164 DPID[00:00:00:00:00:00:00:04]]:removal called 2015-02-18 09:36:43,555 | WARN | w I/O worker #10 | ntrollerImpl$OpenFlowSwitchAgent | 130 - org.onosproject.onos-of-ctl - 1.1.0.SNAPSHOT | removal for 00:00:00:00:00:00:00:09 2015-02-18 09:36:43,556 | WARN | ew I/O worker #4 | ntrollerImpl$OpenFlowSwitchAgent | 130 - org.onosproject.onos-of-ctl - 1.1.0.SNAPSHOT | sw was null for 00:00:00:00:00:00:00:04 2015-02-18 09:36:43,556 | INFO | w I/O worker #10 | DeviceManager | 157 - org.onosproject.onos-core-net - 1.1.0.SNAPSHOT | Device of:0000000000000009 disconnected from this node 2015-02-18 09:36:43,556 | WARN | ew I/O worker #4 | ntrollerImpl$OpenFlowSwitchAgent | 130 - org.onosproject.onos-of-ctl - 1.1.0.SNAPSHOT | removal for 00:00:00:00:00:00:00:04 2015-02-18 09:36:43,556 | INFO | ew I/O worker #4 | DeviceManager | 157 - org.onosproject.onos-core-net - 1.1.0.SNAPSHOT | Device of:0000000000000004 disconnected from this node 2015-02-18 09:36:43,561 | WARN | ew I/O worker #6 | ntrollerImpl$OpenFlowSwitchAgent | 130 - org.onosproject.onos-of-ctl - 1.1.0.SNAPSHOT | removal for 00:00:00:00:00:00:00:01 2015-02-18 09:36:43,588 | ERROR | w I/O worker #11 | OFChannelHandler | 130 - org.onosproject.onos-of-ctl - 1.1.0.SNAPSHOT | Error while processing message from switch OFSwitchImplOVS13 [/10.128.40.50:47187 DPID[00:00:00:00:00:00:00:06]]state WAIT_SWITCH_DRIVER_SUB_HANDSHAKE java.lang.NullPointerException at org.onosproject.net.device.impl.DeviceManager$InternalDeviceProviderService.updatePorts(DeviceManager.java:396) at org.onosproject.provider.of.device.impl.OpenFlowDeviceProvider$InternalDeviceProvider.switchAdded(OpenFlowDeviceProvider.java:203) at org.onosproject.openflow.controller.impl.OpenFlowControllerImpl$OpenFlowSwitchAgent.addConnectedSwitch(OpenFlowControllerImpl.java:347) at org.onosproject.openflow.controller.driver.AbstractOpenFlowSwitch.connectSwitch(AbstractOpenFlowSwitch.java:210) at org.onosproject.openflow.controller.impl.OFChannelHandler$ChannelState$7.moveToActive(OFChannelHandler.java:537) at org.onosproject.openflow.controller.impl.OFChannelHandler$ChannelState$7.processOFMessage(OFChannelHandler.java:524) at org.onosproject.openflow.controller.impl.OFChannelHandler.messageReceived(OFChannelHandler.java:1150) at org.jboss.netty.handler.timeout.IdleStateAwareChannelHandler.handleUpstream(IdleStateAwareChannelHandler.java:36)[44:org.jboss.netty:3.9.2.Final] at org.jboss.netty.handler.timeout.ReadTimeoutHandler.messageReceived(ReadTimeoutHandler.java:184)[44:org.jboss.netty:3.9.2.Final] at org.jboss.netty.handler.timeout.IdleStateHandler.messageReceived(IdleStateHandler.java:294)[44:org.jboss.netty:3.9.2.Final] at org.jboss.netty.channel.Channels.fireMessageReceived(Channels.java:296)[44:org.jboss.netty:3.9.2.Final] at org.jboss.netty.handler.codec.frame.FrameDecoder.unfoldAndFireMessageReceived(FrameDecoder.java:462)[44:org.jboss.netty:3.9.2.Final] at org.jboss.netty.handler.codec.frame.FrameDecoder.callDecode(FrameDecoder.java:443)[44:org.jboss.netty:3.9.2.Final] at org.jboss.netty.handler.codec.frame.FrameDecoder.messageReceived(FrameDecoder.java:303)[44:org.jboss.netty:3.9.2.Final] at org.jboss.netty.channel.Channels.fireMessageReceived(Channels.java:268)[44:org.jboss.netty:3.9.2.Final] at org.jboss.netty.channel.Channels.fireMessageReceived(Channels.java:255)[44:org.jboss.netty:3.9.2.Final] at org.jboss.netty.channel.socket.nio.NioWorker.read(NioWorker.java:88)[44:org.jboss.netty:3.9.2.Final] at org.jboss.netty.channel.socket.nio.AbstractNioWorker.process(AbstractNioWorker.java:108)[44: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)[44:org.jboss.netty:3.9.2.Final] at org.jboss.netty.channel.socket.nio.NioWorker.run(NioWorker.java:178)[44: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-02-18 09:36:43,594 | WARN | ew I/O worker #4 | ntrollerImpl$OpenFlowSwitchAgent | 130 - org.onosproject.onos-of-ctl - 1.1.0.SNAPSHOT | removal for 00:00:00:00:00:00:00:04 2015-02-18 09:36:43,595 | INFO | w I/O worker #11 | OFChannelHandler | 130 - org.onosproject.onos-of-ctl - 1.1.0.SNAPSHOT | Switch disconnected callback for sw:OFSwitchImplOVS13 [/10.128.40.50:47187 DPID[00:00:00:00:00:00:00:06]]. Cleaning up ... 2015-02-18 09:36:43,595 | INFO | w I/O worker #11 | OFChannelHandler | 130 - org.onosproject.onos-of-ctl - 1.1.0.SNAPSHOT | OFSwitchImplOVS13 [/10.128.40.50:47187 DPID[00:00:00:00:00:00:00:06]]:removal called 2015-02-18 09:36:43,595 | WARN | w I/O worker #11 | ntrollerImpl$OpenFlowSwitchAgent | 130 - org.onosproject.onos-of-ctl - 1.1.0.SNAPSHOT | sw was null for 00:00:00:00:00:00:00:06 2015-02-18 09:36:43,595 | WARN | w I/O worker #11 | ntrollerImpl$OpenFlowSwitchAgent | 130 - org.onosproject.onos-of-ctl - 1.1.0.SNAPSHOT | removal for 00:00:00:00:00:00:00:06 2015-02-18 09:36:43,595 | INFO | w I/O worker #11 | DeviceManager | 157 - org.onosproject.onos-core-net - 1.1.0.SNAPSHOT | Device of:0000000000000006 disconnected from this node 2015-02-18 09:36:43,596 | ERROR | ew I/O worker #7 | OFChannelHandler | 130 - org.onosproject.onos-of-ctl - 1.1.0.SNAPSHOT | Error while processing message from switch OFSwitchImplOVS13 [/10.128.40.50:47173 DPID[00:00:00:00:00:00:00:11]]state WAIT_SWITCH_DRIVER_SUB_HANDSHAKE java.lang.NullPointerException at org.onosproject.net.device.impl.DeviceManager$InternalDeviceProviderService.updatePorts(DeviceManager.java:396) at org.onosproject.provider.of.device.impl.OpenFlowDeviceProvider$InternalDeviceProvider.switchAdded(OpenFlowDeviceProvider.java:203) at org.onosproject.openflow.controller.impl.OpenFlowControllerImpl$OpenFlowSwitchAgent.addConnectedSwitch(OpenFlowControllerImpl.java:347) at org.onosproject.openflow.controller.driver.AbstractOpenFlowSwitch.connectSwitch(AbstractOpenFlowSwitch.java:210) at org.onosproject.openflow.controller.impl.OFChannelHandler$ChannelState$7.moveToActive(OFChannelHandler.java:537) at org.onosproject.openflow.controller.impl.OFChannelHandler$ChannelState$7.processOFMessage(OFChannelHandler.java:524) at org.onosproject.openflow.controller.impl.OFChannelHandler.messageReceived(OFChannelHandler.java:1150) at org.jboss.netty.handler.timeout.IdleStateAwareChannelHandler.handleUpstream(IdleStateAwareChannelHandler.java:36)[44:org.jboss.netty:3.9.2.Final] at org.jboss.netty.handler.timeout.ReadTimeoutHandler.messageReceived(ReadTimeoutHandler.java:184)[44:org.jboss.netty:3.9.2.Final] at org.jboss.netty.handler.timeout.IdleStateHandler.messageReceived(IdleStateHandler.java:294)[44:org.jboss.netty:3.9.2.Final] at org.jboss.netty.channel.Channels.fireMessageReceived(Channels.java:296)[44:org.jboss.netty:3.9.2.Final] at org.jboss.netty.handler.codec.frame.FrameDecoder.unfoldAndFireMessageReceived(FrameDecoder.java:462)[44:org.jboss.netty:3.9.2.Final] at org.jboss.netty.handler.codec.frame.FrameDecoder.callDecode(FrameDecoder.java:443)[44:org.jboss.netty:3.9.2.Final] at org.jboss.netty.handler.codec.frame.FrameDecoder.messageReceived(FrameDecoder.java:303)[44:org.jboss.netty:3.9.2.Final] at org.jboss.netty.channel.Channels.fireMessageReceived(Channels.java:268)[44:org.jboss.netty:3.9.2.Final] at org.jboss.netty.channel.Channels.fireMessageReceived(Channels.java:255)[44:org.jboss.netty:3.9.2.Final] at org.jboss.netty.channel.socket.nio.NioWorker.read(NioWorker.java:88)[44:org.jboss.netty:3.9.2.Final] at org.jboss.netty.channel.socket.nio.AbstractNioWorker.process(AbstractNioWorker.java:108)[44: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)[44:org.jboss.netty:3.9.2.Final] at org.jboss.netty.channel.socket.nio.NioWorker.run(NioWorker.java:178)[44: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-02-18 09:36:43,607 | INFO | ew I/O worker #7 | OFChannelHandler | 130 - org.onosproject.onos-of-ctl - 1.1.0.SNAPSHOT | Switch disconnected callback for sw:OFSwitchImplOVS13 [/10.128.40.50:47173 DPID[00:00:00:00:00:00:00:11]]. Cleaning up ... 2015-02-18 09:36:43,607 | INFO | ew I/O worker #7 | OFChannelHandler | 130 - org.onosproject.onos-of-ctl - 1.1.0.SNAPSHOT | OFSwitchImplOVS13 [/10.128.40.50:47173 DPID[00:00:00:00:00:00:00:11]]:removal called 2015-02-18 09:36:43,607 | WARN | ew I/O worker #7 | ntrollerImpl$OpenFlowSwitchAgent | 130 - org.onosproject.onos-of-ctl - 1.1.0.SNAPSHOT | sw was null for 00:00:00:00:00:00:00:11 2015-02-18 09:36:43,607 | WARN | ew I/O worker #7 | ntrollerImpl$OpenFlowSwitchAgent | 130 - org.onosproject.onos-of-ctl - 1.1.0.SNAPSHOT | removal for 00:00:00:00:00:00:00:11 2015-02-18 09:36:43,607 | INFO | ew I/O worker #7 | DeviceManager | 157 - org.onosproject.onos-core-net - 1.1.0.SNAPSHOT | Device of:0000000000000011 disconnected from this node 2015-02-18 09:36:43,620 | WARN | w I/O worker #10 | ntrollerImpl$OpenFlowSwitchAgent | 130 - org.onosproject.onos-of-ctl - 1.1.0.SNAPSHOT | removal for 00:00:00:00:00:00:00:09 2015-02-18 09:36:43,630 | WARN | w I/O worker #11 | ntrollerImpl$OpenFlowSwitchAgent | 130 - org.onosproject.onos-of-ctl - 1.1.0.SNAPSHOT | removal for 00:00:00:00:00:00:00:06 2015-02-18 09:36:43,630 | WARN | ew I/O worker #7 | ntrollerImpl$OpenFlowSwitchAgent | 130 - org.onosproject.onos-of-ctl - 1.1.0.SNAPSHOT | removal for 00:00:00:00:00:00:00:11 2015-02-18 09:36:43,689 | WARN | choTest5.event-3 | EventService | 53 - com.hazelcast - 3.4.0 | [10.128.40.41]:5701 [choTest5] [3.4] Error while logging processing event com.esotericsoftware.kryo.KryoException: Buffer underflow. at com.esotericsoftware.kryo.io.Input.require(Input.java:181)[57:com.esotericsoftware.kryo:3.0.0] at com.esotericsoftware.kryo.io.Input.readAscii_slow(Input.java:598)[57:com.esotericsoftware.kryo:3.0.0] at com.esotericsoftware.kryo.io.Input.readAscii(Input.java:576)[57:com.esotericsoftware.kryo:3.0.0] at com.esotericsoftware.kryo.io.Input.readString(Input.java:454)[57:com.esotericsoftware.kryo:3.0.0] at org.onosproject.store.serializers.DeviceIdSerializer.read(DeviceIdSerializer.java:45)[160:org.onosproject.onos-core-serializers:1.1.0.SNAPSHOT] at org.onosproject.store.serializers.DeviceIdSerializer.read(DeviceIdSerializer.java:28)[160:org.onosproject.onos-core-serializers:1.1.0.SNAPSHOT] at com.esotericsoftware.kryo.Kryo.readClassAndObject(Kryo.java:786)[57:com.esotericsoftware.kryo:3.0.0] at org.onlab.util.KryoNamespace.deserialize(KryoNamespace.java:303)[72:org.onosproject.onlab-misc:1.1.0.SNAPSHOT] at org.onosproject.store.serializers.KryoSerializer.decode(KryoSerializer.java:57)[160:org.onosproject.onos-core-serializers:1.1.0.SNAPSHOT] at org.onosproject.store.hz.SMap.deserializeKey(SMap.java:615)[159:org.onosproject.onos-core-dist:1.1.0.SNAPSHOT] at org.onosproject.store.hz.SMap.access$100(SMap.java:57)[159:org.onosproject.onos-core-dist:1.1.0.SNAPSHOT] at org.onosproject.store.hz.SMap$BaseEntryListener.entryUpdated(SMap.java:689)[159:org.onosproject.onos-core-dist:1.1.0.SNAPSHOT] at com.hazelcast.map.impl.MapEventPublishingService.dispatch0(MapEventPublishingService.java:91)[53:com.hazelcast:3.4.0] at com.hazelcast.map.impl.MapEventPublishingService.dispatchEntryEventData(MapEventPublishingService.java:64)[53:com.hazelcast:3.4.0] at com.hazelcast.map.impl.MapEventPublishingService.dispatchEvent(MapEventPublishingService.java:39)[53:com.hazelcast:3.4.0] at com.hazelcast.map.impl.MapEventPublishingService.dispatchEvent(MapEventPublishingService.java:17)[53:com.hazelcast:3.4.0] at com.hazelcast.map.impl.MapService.dispatchEvent(MapService.java:76)[53:com.hazelcast:3.4.0] at com.hazelcast.map.impl.MapService.dispatchEvent(MapService.java:51)[53:com.hazelcast:3.4.0] at com.hazelcast.spi.impl.EventServiceImpl$EventPacketProcessor.process(EventServiceImpl.java:549)[53:com.hazelcast:3.4.0] at com.hazelcast.spi.impl.EventServiceImpl$RemoteEventPacketProcessor.run(EventServiceImpl.java:630)[53:com.hazelcast:3.4.0] at com.hazelcast.util.executor.StripedExecutor$Worker.process(StripedExecutor.java:190)[53:com.hazelcast:3.4.0] at com.hazelcast.util.executor.StripedExecutor$Worker.run(StripedExecutor.java:174)[53:com.hazelcast:3.4.0] 2015-02-18 09:36:44,435 | INFO | w I/O worker #12 | OFChannelHandler | 130 - org.onosproject.onos-of-ctl - 1.1.0.SNAPSHOT | New switch connection from /10.128.40.50:47191 2015-02-18 09:36:44,436 | INFO | w I/O worker #12 | OFChannelHandler | 130 - org.onosproject.onos-of-ctl - 1.1.0.SNAPSHOT | Sending OF_13 Hello to /10.128.40.50:47191 2015-02-18 09:36:44,439 | INFO | w I/O worker #13 | OFChannelHandler | 130 - org.onosproject.onos-of-ctl - 1.1.0.SNAPSHOT | New switch connection from /10.128.40.50:47197 2015-02-18 09:36:44,440 | INFO | w I/O worker #13 | OFChannelHandler | 130 - org.onosproject.onos-of-ctl - 1.1.0.SNAPSHOT | Sending OF_13 Hello to /10.128.40.50:47197 2015-02-18 09:36:44,440 | INFO | w I/O worker #14 | OFChannelHandler | 130 - org.onosproject.onos-of-ctl - 1.1.0.SNAPSHOT | New switch connection from /10.128.40.50:47198 2015-02-18 09:36:44,444 | INFO | w I/O worker #14 | OFChannelHandler | 130 - org.onosproject.onos-of-ctl - 1.1.0.SNAPSHOT | Sending OF_13 Hello to /10.128.40.50:47198 2015-02-18 09:36:44,446 | INFO | w I/O worker #12 | OFChannelHandler | 130 - org.onosproject.onos-of-ctl - 1.1.0.SNAPSHOT | Received port desc reply for switch at [/10.128.40.50:47191 DPID[00:00:00:00:00:00:00:15]] 2015-02-18 09:36:44,448 | INFO | w I/O worker #14 | OFChannelHandler | 130 - org.onosproject.onos-of-ctl - 1.1.0.SNAPSHOT | Received port desc reply for switch at [/10.128.40.50:47198 DPID[00:00:00:00:00:00:00:18]] 2015-02-18 09:36:44,448 | INFO | w I/O worker #13 | OFChannelHandler | 130 - org.onosproject.onos-of-ctl - 1.1.0.SNAPSHOT | Received port desc reply for switch at [/10.128.40.50:47197 DPID[00:00:00:00:00:00:00:16]] 2015-02-18 09:36:44,452 | INFO | w I/O worker #12 | OFChannelHandler | 130 - org.onosproject.onos-of-ctl - 1.1.0.SNAPSHOT | Received switch description reply from switch at /10.128.40.50:47191 2015-02-18 09:36:44,454 | INFO | w I/O worker #13 | OFChannelHandler | 130 - org.onosproject.onos-of-ctl - 1.1.0.SNAPSHOT | Received switch description reply from switch at /10.128.40.50:47197 2015-02-18 09:36:44,454 | INFO | w I/O worker #14 | OFChannelHandler | 130 - org.onosproject.onos-of-ctl - 1.1.0.SNAPSHOT | Received switch description reply from switch at /10.128.40.50:47198 2015-02-18 09:36:44,455 | INFO | w I/O worker #12 | ntrollerImpl$OpenFlowSwitchAgent | 130 - org.onosproject.onos-of-ctl - 1.1.0.SNAPSHOT | Added switch 00:00:00:00:00:00:00:15 2015-02-18 09:36:44,455 | INFO | w I/O worker #12 | DeviceManager | 157 - org.onosproject.onos-core-net - 1.1.0.SNAPSHOT | Device of:0000000000000015 connected 2015-02-18 09:36:44,456 | INFO | w I/O worker #14 | ntrollerImpl$OpenFlowSwitchAgent | 130 - org.onosproject.onos-of-ctl - 1.1.0.SNAPSHOT | Added switch 00:00:00:00:00:00:00:18 2015-02-18 09:36:44,456 | INFO | w I/O worker #13 | ntrollerImpl$OpenFlowSwitchAgent | 130 - org.onosproject.onos-of-ctl - 1.1.0.SNAPSHOT | Added switch 00:00:00:00:00:00:00:16 2015-02-18 09:36:44,456 | INFO | w I/O worker #14 | DeviceManager | 157 - org.onosproject.onos-core-net - 1.1.0.SNAPSHOT | Device of:0000000000000018 connected 2015-02-18 09:36:44,456 | INFO | w I/O worker #13 | DeviceManager | 157 - org.onosproject.onos-core-net - 1.1.0.SNAPSHOT | Device of:0000000000000016 connected 2015-02-18 09:36:44,467 | INFO | w I/O worker #14 | DeviceManager | 157 - org.onosproject.onos-core-net - 1.1.0.SNAPSHOT | Role of this node is STANDBY for of:0000000000000018 2015-02-18 09:36:44,467 | INFO | w I/O worker #14 | OFSwitchImplOVS13 | 128 - org.onosproject.onos-of-api - 1.1.0.SNAPSHOT | Sending role EQUAL to switch 00:00:00:00:00:00:00:18 2015-02-18 09:36:44,468 | INFO | w I/O worker #14 | OpenFlowDeviceProvider | 133 - org.onosproject.onos-of-provider-device - 1.1.0.SNAPSHOT | Accepting mastership role change for device of:0000000000000018 2015-02-18 09:36:44,471 | ERROR | w I/O worker #14 | OFChannelHandler | 130 - org.onosproject.onos-of-ctl - 1.1.0.SNAPSHOT | Error while processing message from switch OFSwitchImplOVS13 [/10.128.40.50:47198 DPID[00:00:00:00:00:00:00:18]]state WAIT_SWITCH_DRIVER_SUB_HANDSHAKE java.lang.NullPointerException at org.onosproject.net.device.impl.DeviceManager$InternalDeviceProviderService.updatePorts(DeviceManager.java:396) at org.onosproject.provider.of.device.impl.OpenFlowDeviceProvider$InternalDeviceProvider.switchAdded(OpenFlowDeviceProvider.java:203) at org.onosproject.openflow.controller.impl.OpenFlowControllerImpl$OpenFlowSwitchAgent.addConnectedSwitch(OpenFlowControllerImpl.java:347) at org.onosproject.openflow.controller.driver.AbstractOpenFlowSwitch.connectSwitch(AbstractOpenFlowSwitch.java:210) at org.onosproject.openflow.controller.impl.OFChannelHandler$ChannelState$7.moveToActive(OFChannelHandler.java:537) at org.onosproject.openflow.controller.impl.OFChannelHandler$ChannelState$7.processOFMessage(OFChannelHandler.java:524) at org.onosproject.openflow.controller.impl.OFChannelHandler.messageReceived(OFChannelHandler.java:1150) at org.jboss.netty.handler.timeout.IdleStateAwareChannelHandler.handleUpstream(IdleStateAwareChannelHandler.java:36)[44:org.jboss.netty:3.9.2.Final] at org.jboss.netty.handler.timeout.ReadTimeoutHandler.messageReceived(ReadTimeoutHandler.java:184)[44:org.jboss.netty:3.9.2.Final] at org.jboss.netty.handler.timeout.IdleStateHandler.messageReceived(IdleStateHandler.java:294)[44:org.jboss.netty:3.9.2.Final] at org.jboss.netty.channel.Channels.fireMessageReceived(Channels.java:296)[44:org.jboss.netty:3.9.2.Final] at org.jboss.netty.handler.codec.frame.FrameDecoder.unfoldAndFireMessageReceived(FrameDecoder.java:462)[44:org.jboss.netty:3.9.2.Final] at org.jboss.netty.handler.codec.frame.FrameDecoder.callDecode(FrameDecoder.java:443)[44:org.jboss.netty:3.9.2.Final] at org.jboss.netty.handler.codec.frame.FrameDecoder.messageReceived(FrameDecoder.java:303)[44:org.jboss.netty:3.9.2.Final] at org.jboss.netty.channel.Channels.fireMessageReceived(Channels.java:268)[44:org.jboss.netty:3.9.2.Final] at org.jboss.netty.channel.Channels.fireMessageReceived(Channels.java:255)[44:org.jboss.netty:3.9.2.Final] at org.jboss.netty.channel.socket.nio.NioWorker.read(NioWorker.java:88)[44:org.jboss.netty:3.9.2.Final] at org.jboss.netty.channel.socket.nio.AbstractNioWorker.process(AbstractNioWorker.java:108)[44: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)[44:org.jboss.netty:3.9.2.Final] at org.jboss.netty.channel.socket.nio.NioWorker.run(NioWorker.java:178)[44: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-02-18 09:36:44,475 | INFO | w I/O worker #13 | DeviceManager | 157 - org.onosproject.onos-core-net - 1.1.0.SNAPSHOT | Role of this node is STANDBY for of:0000000000000016 2015-02-18 09:36:44,473 | INFO | w I/O worker #12 | DeviceManager | 157 - org.onosproject.onos-core-net - 1.1.0.SNAPSHOT | Role of this node is STANDBY for of:0000000000000015 2015-02-18 09:36:44,482 | INFO | w I/O worker #12 | OFSwitchImplOVS13 | 128 - org.onosproject.onos-of-api - 1.1.0.SNAPSHOT | Sending role EQUAL to switch 00:00:00:00:00:00:00:15 2015-02-18 09:36:44,482 | INFO | w I/O worker #13 | OFSwitchImplOVS13 | 128 - org.onosproject.onos-of-api - 1.1.0.SNAPSHOT | Sending role EQUAL to switch 00:00:00:00:00:00:00:16 2015-02-18 09:36:44,482 | INFO | w I/O worker #12 | OpenFlowDeviceProvider | 133 - org.onosproject.onos-of-provider-device - 1.1.0.SNAPSHOT | Accepting mastership role change for device of:0000000000000015 2015-02-18 09:36:44,482 | INFO | w I/O worker #13 | OpenFlowDeviceProvider | 133 - org.onosproject.onos-of-provider-device - 1.1.0.SNAPSHOT | Accepting mastership role change for device of:0000000000000016 2015-02-18 09:36:44,483 | INFO | w I/O worker #14 | OFChannelHandler | 130 - org.onosproject.onos-of-ctl - 1.1.0.SNAPSHOT | Switch disconnected callback for sw:OFSwitchImplOVS13 [/10.128.40.50:47198 DPID[00:00:00:00:00:00:00:18]]. Cleaning up ... 2015-02-18 09:36:44,483 | INFO | w I/O worker #14 | OFChannelHandler | 130 - org.onosproject.onos-of-ctl - 1.1.0.SNAPSHOT | OFSwitchImplOVS13 [/10.128.40.50:47198 DPID[00:00:00:00:00:00:00:18]]:removal called 2015-02-18 09:36:44,483 | WARN | w I/O worker #14 | ntrollerImpl$OpenFlowSwitchAgent | 130 - org.onosproject.onos-of-ctl - 1.1.0.SNAPSHOT | sw was null for 00:00:00:00:00:00:00:18 2015-02-18 09:36:44,483 | WARN | w I/O worker #14 | ntrollerImpl$OpenFlowSwitchAgent | 130 - org.onosproject.onos-of-ctl - 1.1.0.SNAPSHOT | removal for 00:00:00:00:00:00:00:18 2015-02-18 09:36:44,483 | INFO | w I/O worker #14 | DeviceManager | 157 - org.onosproject.onos-core-net - 1.1.0.SNAPSHOT | Device of:0000000000000018 disconnected from this node 2015-02-18 09:36:44,487 | ERROR | w I/O worker #13 | OFChannelHandler | 130 - org.onosproject.onos-of-ctl - 1.1.0.SNAPSHOT | Error while processing message from switch OFSwitchImplOVS13 [/10.128.40.50:47197 DPID[00:00:00:00:00:00:00:16]]state WAIT_SWITCH_DRIVER_SUB_HANDSHAKE java.lang.NullPointerException at org.onosproject.net.device.impl.DeviceManager$InternalDeviceProviderService.updatePorts(DeviceManager.java:396) at org.onosproject.provider.of.device.impl.OpenFlowDeviceProvider$InternalDeviceProvider.switchAdded(OpenFlowDeviceProvider.java:203) at org.onosproject.openflow.controller.impl.OpenFlowControllerImpl$OpenFlowSwitchAgent.addConnectedSwitch(OpenFlowControllerImpl.java:347) at org.onosproject.openflow.controller.driver.AbstractOpenFlowSwitch.connectSwitch(AbstractOpenFlowSwitch.java:210) at org.onosproject.openflow.controller.impl.OFChannelHandler$ChannelState$7.moveToActive(OFChannelHandler.java:537) at org.onosproject.openflow.controller.impl.OFChannelHandler$ChannelState$7.processOFMessage(OFChannelHandler.java:524) at org.onosproject.openflow.controller.impl.OFChannelHandler.messageReceived(OFChannelHandler.java:1150) at org.jboss.netty.handler.timeout.IdleStateAwareChannelHandler.handleUpstream(IdleStateAwareChannelHandler.java:36)[44:org.jboss.netty:3.9.2.Final] at org.jboss.netty.handler.timeout.ReadTimeoutHandler.messageReceived(ReadTimeoutHandler.java:184)[44:org.jboss.netty:3.9.2.Final] at org.jboss.netty.handler.timeout.IdleStateHandler.messageReceived(IdleStateHandler.java:294)[44:org.jboss.netty:3.9.2.Final] at org.jboss.netty.channel.Channels.fireMessageReceived(Channels.java:296)[44:org.jboss.netty:3.9.2.Final] at org.jboss.netty.handler.codec.frame.FrameDecoder.unfoldAndFireMessageReceived(FrameDecoder.java:462)[44:org.jboss.netty:3.9.2.Final] at org.jboss.netty.handler.codec.frame.FrameDecoder.callDecode(FrameDecoder.java:443)[44:org.jboss.netty:3.9.2.Final] at org.jboss.netty.handler.codec.frame.FrameDecoder.messageReceived(FrameDecoder.java:303)[44:org.jboss.netty:3.9.2.Final] at org.jboss.netty.channel.Channels.fireMessageReceived(Channels.java:268)[44:org.jboss.netty:3.9.2.Final] at org.jboss.netty.channel.Channels.fireMessageReceived(Channels.java:255)[44:org.jboss.netty:3.9.2.Final] at org.jboss.netty.channel.socket.nio.NioWorker.read(NioWorker.java:88)[44:org.jboss.netty:3.9.2.Final] at org.jboss.netty.channel.socket.nio.AbstractNioWorker.process(AbstractNioWorker.java:108)[44: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)[44:org.jboss.netty:3.9.2.Final] at org.jboss.netty.channel.socket.nio.NioWorker.run(NioWorker.java:178)[44: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-02-18 09:36:44,495 | INFO | w I/O worker #13 | OFChannelHandler | 130 - org.onosproject.onos-of-ctl - 1.1.0.SNAPSHOT | Switch disconnected callback for sw:OFSwitchImplOVS13 [/10.128.40.50:47197 DPID[00:00:00:00:00:00:00:16]]. Cleaning up ... 2015-02-18 09:36:44,495 | INFO | w I/O worker #13 | OFChannelHandler | 130 - org.onosproject.onos-of-ctl - 1.1.0.SNAPSHOT | OFSwitchImplOVS13 [/10.128.40.50:47197 DPID[00:00:00:00:00:00:00:16]]:removal called 2015-02-18 09:36:44,495 | WARN | w I/O worker #13 | ntrollerImpl$OpenFlowSwitchAgent | 130 - org.onosproject.onos-of-ctl - 1.1.0.SNAPSHOT | sw was null for 00:00:00:00:00:00:00:16 2015-02-18 09:36:44,495 | WARN | w I/O worker #13 | ntrollerImpl$OpenFlowSwitchAgent | 130 - org.onosproject.onos-of-ctl - 1.1.0.SNAPSHOT | removal for 00:00:00:00:00:00:00:16 2015-02-18 09:36:44,495 | WARN | w I/O worker #14 | ntrollerImpl$OpenFlowSwitchAgent | 130 - org.onosproject.onos-of-ctl - 1.1.0.SNAPSHOT | removal for 00:00:00:00:00:00:00:18 2015-02-18 09:36:44,495 | INFO | w I/O worker #13 | DeviceManager | 157 - org.onosproject.onos-core-net - 1.1.0.SNAPSHOT | Device of:0000000000000016 disconnected from this node 2015-02-18 09:36:44,499 | ERROR | w I/O worker #12 | OFChannelHandler | 130 - org.onosproject.onos-of-ctl - 1.1.0.SNAPSHOT | Error while processing message from switch OFSwitchImplOVS13 [/10.128.40.50:47191 DPID[00:00:00:00:00:00:00:15]]state WAIT_SWITCH_DRIVER_SUB_HANDSHAKE java.lang.NullPointerException at org.onosproject.net.device.impl.DeviceManager$InternalDeviceProviderService.updatePorts(DeviceManager.java:396) at org.onosproject.provider.of.device.impl.OpenFlowDeviceProvider$InternalDeviceProvider.switchAdded(OpenFlowDeviceProvider.java:203) at org.onosproject.openflow.controller.impl.OpenFlowControllerImpl$OpenFlowSwitchAgent.addConnectedSwitch(OpenFlowControllerImpl.java:347) at org.onosproject.openflow.controller.driver.AbstractOpenFlowSwitch.connectSwitch(AbstractOpenFlowSwitch.java:210) at org.onosproject.openflow.controller.impl.OFChannelHandler$ChannelState$7.moveToActive(OFChannelHandler.java:537) at org.onosproject.openflow.controller.impl.OFChannelHandler$ChannelState$7.processOFMessage(OFChannelHandler.java:524) at org.onosproject.openflow.controller.impl.OFChannelHandler.messageReceived(OFChannelHandler.java:1150) at org.jboss.netty.handler.timeout.IdleStateAwareChannelHandler.handleUpstream(IdleStateAwareChannelHandler.java:36)[44:org.jboss.netty:3.9.2.Final] at org.jboss.netty.handler.timeout.ReadTimeoutHandler.messageReceived(ReadTimeoutHandler.java:184)[44:org.jboss.netty:3.9.2.Final] at org.jboss.netty.handler.timeout.IdleStateHandler.messageReceived(IdleStateHandler.java:294)[44:org.jboss.netty:3.9.2.Final] at org.jboss.netty.channel.Channels.fireMessageReceived(Channels.java:296)[44:org.jboss.netty:3.9.2.Final] at org.jboss.netty.handler.codec.frame.FrameDecoder.unfoldAndFireMessageReceived(FrameDecoder.java:462)[44:org.jboss.netty:3.9.2.Final] at org.jboss.netty.handler.codec.frame.FrameDecoder.callDecode(FrameDecoder.java:443)[44:org.jboss.netty:3.9.2.Final] at org.jboss.netty.handler.codec.frame.FrameDecoder.messageReceived(FrameDecoder.java:303)[44:org.jboss.netty:3.9.2.Final] at org.jboss.netty.channel.Channels.fireMessageReceived(Channels.java:268)[44:org.jboss.netty:3.9.2.Final] at org.jboss.netty.channel.Channels.fireMessageReceived(Channels.java:255)[44:org.jboss.netty:3.9.2.Final] at org.jboss.netty.channel.socket.nio.NioWorker.read(NioWorker.java:88)[44:org.jboss.netty:3.9.2.Final] at org.jboss.netty.channel.socket.nio.AbstractNioWorker.process(AbstractNioWorker.java:108)[44: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)[44:org.jboss.netty:3.9.2.Final] at org.jboss.netty.channel.socket.nio.NioWorker.run(NioWorker.java:178)[44: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-02-18 09:36:44,505 | WARN | w I/O worker #13 | ntrollerImpl$OpenFlowSwitchAgent | 130 - org.onosproject.onos-of-ctl - 1.1.0.SNAPSHOT | removal for 00:00:00:00:00:00:00:16 2015-02-18 09:36:44,508 | INFO | w I/O worker #12 | OFChannelHandler | 130 - org.onosproject.onos-of-ctl - 1.1.0.SNAPSHOT | Switch disconnected callback for sw:OFSwitchImplOVS13 [/10.128.40.50:47191 DPID[00:00:00:00:00:00:00:15]]. Cleaning up ... 2015-02-18 09:36:44,508 | INFO | w I/O worker #12 | OFChannelHandler | 130 - org.onosproject.onos-of-ctl - 1.1.0.SNAPSHOT | OFSwitchImplOVS13 [/10.128.40.50:47191 DPID[00:00:00:00:00:00:00:15]]:removal called 2015-02-18 09:36:44,508 | WARN | w I/O worker #12 | ntrollerImpl$OpenFlowSwitchAgent | 130 - org.onosproject.onos-of-ctl - 1.1.0.SNAPSHOT | sw was null for 00:00:00:00:00:00:00:15 2015-02-18 09:36:44,508 | WARN | w I/O worker #12 | ntrollerImpl$OpenFlowSwitchAgent | 130 - org.onosproject.onos-of-ctl - 1.1.0.SNAPSHOT | removal for 00:00:00:00:00:00:00:15 2015-02-18 09:36:44,508 | INFO | w I/O worker #12 | DeviceManager | 157 - org.onosproject.onos-core-net - 1.1.0.SNAPSHOT | Device of:0000000000000015 disconnected from this node 2015-02-18 09:36:44,516 | WARN | w I/O worker #12 | ntrollerImpl$OpenFlowSwitchAgent | 130 - org.onosproject.onos-of-ctl - 1.1.0.SNAPSHOT | removal for 00:00:00:00:00:00:00:15 2015-02-18 09:36:48,040 | INFO | FelixShutdown | BlueprintExtender | 15 - org.apache.aries.blueprint.core - 1.4.1 | Destroying BlueprintContainer for bundle org.apache.karaf.service.command 2015-02-18 09:36:48,072 | INFO | FelixShutdown | BlueprintExtender | 15 - org.apache.aries.blueprint.core - 1.4.1 | Destroying BlueprintContainer for bundle org.apache.karaf.log.command 2015-02-18 09:36:48,133 | INFO | FelixShutdown | BlueprintExtender | 15 - org.apache.aries.blueprint.core - 1.4.1 | Destroying BlueprintContainer for bundle org.apache.karaf.diagnostic.command 2015-02-18 09:36:48,142 | INFO | FelixShutdown | BlueprintExtender | 15 - org.apache.aries.blueprint.core - 1.4.1 | Destroying BlueprintContainer for bundle org.apache.karaf.jaas.command 2015-02-18 09:36:48,214 | INFO | FelixShutdown | BlueprintExtender | 15 - org.apache.aries.blueprint.core - 1.4.1 | Destroying BlueprintContainer for bundle org.apache.karaf.instance.command 2015-02-18 09:36:48,277 | INFO | FelixShutdown | BlueprintExtender | 15 - org.apache.aries.blueprint.core - 1.4.1 | Destroying BlueprintContainer for bundle org.apache.karaf.package.command 2015-02-18 09:36:48,287 | INFO | FelixShutdown | BlueprintExtender | 15 - org.apache.aries.blueprint.core - 1.4.1 | Destroying BlueprintContainer for bundle org.apache.karaf.kar.command 2015-02-18 09:36:48,307 | INFO | FelixShutdown | BlueprintExtender | 15 - org.apache.aries.blueprint.core - 1.4.1 | Destroying BlueprintContainer for bundle org.apache.karaf.shell.ssh 2015-02-18 09:36:48,332 | INFO | FelixShutdown | BlueprintExtender | 15 - org.apache.aries.blueprint.core - 1.4.1 | Destroying BlueprintContainer for bundle org.onosproject.onos-cli 2015-02-18 09:36:48,496 | INFO | FelixShutdown | BlueprintExtender | 15 - org.apache.aries.blueprint.core - 1.4.1 | Destroying BlueprintContainer for bundle org.apache.karaf.region.command 2015-02-18 09:36:48,509 | INFO | FelixShutdown | BlueprintExtender | 15 - org.apache.aries.blueprint.core - 1.4.1 | Destroying BlueprintContainer for bundle org.apache.karaf.web.command 2015-02-18 09:36:48,521 | INFO | FelixShutdown | BlueprintExtender | 15 - org.apache.aries.blueprint.core - 1.4.1 | Destroying BlueprintContainer for bundle org.apache.karaf.http.command 2015-02-18 09:36:48,527 | INFO | FelixShutdown | BlueprintExtender | 15 - org.apache.aries.blueprint.core - 1.4.1 | Destroying BlueprintContainer for bundle org.apache.karaf.shell.commands 2015-02-18 09:36:48,630 | INFO | FelixShutdown | BlueprintExtender | 15 - org.apache.aries.blueprint.core - 1.4.1 | Destroying BlueprintContainer for bundle org.apache.karaf.system.command 2015-02-18 09:36:48,654 | INFO | FelixShutdown | BlueprintExtender | 15 - org.apache.aries.blueprint.core - 1.4.1 | Destroying BlueprintContainer for bundle org.apache.karaf.shell.help 2015-02-18 09:36:48,667 | INFO | FelixShutdown | BlueprintExtender | 15 - org.apache.aries.blueprint.core - 1.4.1 | Destroying BlueprintContainer for bundle org.apache.karaf.bundle.command 2015-02-18 09:36:48,743 | INFO | FelixShutdown | BlueprintExtender | 15 - org.apache.aries.blueprint.core - 1.4.1 | Destroying BlueprintContainer for bundle org.apache.karaf.features.command 2015-02-18 09:36:48,773 | INFO | FelixShutdown | BlueprintExtender | 15 - org.apache.aries.blueprint.core - 1.4.1 | Destroying BlueprintContainer for bundle org.apache.karaf.deployer.wrap 2015-02-18 09:36:48,776 | INFO | FelixShutdown | BlueprintExtender | 15 - org.apache.aries.blueprint.core - 1.4.1 | Destroying BlueprintContainer for bundle org.apache.karaf.system.core 2015-02-18 09:36:48,797 | INFO | FelixShutdown | BlueprintExtender | 15 - org.apache.aries.blueprint.core - 1.4.1 | Destroying BlueprintContainer for bundle org.apache.karaf.bundle.core 2015-02-18 09:36:48,804 | INFO | FelixShutdown | BlueprintExtender | 15 - org.apache.aries.blueprint.core - 1.4.1 | Destroying BlueprintContainer for bundle org.apache.karaf.deployer.features 2015-02-18 09:36:48,808 | INFO | FelixShutdown | BlueprintExtender | 15 - org.apache.aries.blueprint.core - 1.4.1 | Destroying BlueprintContainer for bundle org.apache.karaf.service.core 2015-02-18 09:36:48,811 | INFO | FelixShutdown | BlueprintExtender | 15 - org.apache.aries.blueprint.core - 1.4.1 | Destroying BlueprintContainer for bundle org.apache.karaf.log.core 2015-02-18 09:36:48,818 | INFO | FelixShutdown | BlueprintExtender | 15 - org.apache.aries.blueprint.core - 1.4.1 | Destroying BlueprintContainer for bundle org.apache.karaf.diagnostic.core 2015-02-18 09:36:48,825 | INFO | FelixShutdown | BlueprintExtender | 15 - org.apache.aries.blueprint.core - 1.4.1 | Destroying BlueprintContainer for bundle org.apache.karaf.instance.core 2015-02-18 09:36:48,828 | INFO | FelixShutdown | BlueprintExtender | 15 - org.apache.aries.blueprint.core - 1.4.1 | Destroying BlueprintContainer for bundle org.apache.karaf.package.core 2015-02-18 09:36:48,831 | INFO | FelixShutdown | BlueprintExtender | 15 - org.apache.aries.blueprint.core - 1.4.1 | Destroying BlueprintContainer for bundle org.apache.karaf.webconsole.console 2015-02-18 09:36:48,835 | INFO | FelixShutdown | BlueprintExtender | 15 - org.apache.aries.blueprint.core - 1.4.1 | Destroying BlueprintContainer for bundle org.apache.karaf.deployer.kar 2015-02-18 09:36:48,838 | INFO | FelixShutdown | BlueprintExtender | 15 - org.apache.aries.blueprint.core - 1.4.1 | Destroying BlueprintContainer for bundle org.apache.karaf.kar.core 2015-02-18 09:36:48,843 | INFO | FelixShutdown | BlueprintExtender | 15 - org.apache.aries.blueprint.core - 1.4.1 | Destroying BlueprintContainer for bundle org.apache.karaf.features.core 2015-02-18 09:36:48,847 | INFO | FelixShutdown | ApplicationManager | 157 - org.onosproject.onos-core-net - 1.1.0.SNAPSHOT | Stopped 2015-02-18 09:36:48,853 | INFO | FelixShutdown | BlueprintExtender | 15 - org.apache.aries.blueprint.core - 1.4.1 | Destroying BlueprintContainer for bundle org.apache.karaf.management.server 2015-02-18 09:36:48,858 | INFO | FelixShutdown | core | 123 - org.apache.aries.jmx.core - 1.1.2 | Unregistering org.osgi.jmx.framework.BundleStateMBean to MBeanServer com.sun.jmx.mbeanserver.JmxMBeanServer@7e818355 with name osgi.core:type=bundleState,version=1.7,framework=org.apache.felix.framework,uuid=c32b4bd6-32cd-4c44-b637-c4e643c08203 2015-02-18 09:36:48,858 | INFO | FelixShutdown | core | 123 - org.apache.aries.jmx.core - 1.1.2 | Unregistering org.osgi.jmx.framework.wiring.BundleWiringStateMBean to MBeanServer com.sun.jmx.mbeanserver.JmxMBeanServer@7e818355 with name osgi.core:type=wiringState,version=1.1,framework=org.apache.felix.framework,uuid=c32b4bd6-32cd-4c44-b637-c4e643c08203 2015-02-18 09:36:48,858 | INFO | FelixShutdown | core | 123 - org.apache.aries.jmx.core - 1.1.2 | Unregistering org.osgi.jmx.framework.ServiceStateMBean to MBeanServer com.sun.jmx.mbeanserver.JmxMBeanServer@7e818355 with name osgi.core:type=serviceState,version=1.7,framework=org.apache.felix.framework,uuid=c32b4bd6-32cd-4c44-b637-c4e643c08203 2015-02-18 09:36:48,858 | INFO | FelixShutdown | core | 123 - org.apache.aries.jmx.core - 1.1.2 | Unregistering org.osgi.jmx.framework.FrameworkMBean to MBeanServer com.sun.jmx.mbeanserver.JmxMBeanServer@7e818355 with name osgi.core:type=framework,version=1.7,framework=org.apache.felix.framework,uuid=c32b4bd6-32cd-4c44-b637-c4e643c08203 2015-02-18 09:36:48,859 | INFO | FelixShutdown | core | 123 - org.apache.aries.jmx.core - 1.1.2 | Unregistering org.osgi.jmx.service.cm.ConfigurationAdminMBean to MBeanServer com.sun.jmx.mbeanserver.JmxMBeanServer@7e818355 with name osgi.compendium:service=cm,version=1.3,framework=org.apache.felix.framework,uuid=c32b4bd6-32cd-4c44-b637-c4e643c08203 2015-02-18 09:36:48,859 | INFO | FelixShutdown | core | 123 - org.apache.aries.jmx.core - 1.1.2 | Unregistering org.osgi.jmx.framework.PackageStateMBean to MBeanServer com.sun.jmx.mbeanserver.JmxMBeanServer@7e818355 with name osgi.core:type=packageState,version=1.5,framework=org.apache.felix.framework,uuid=c32b4bd6-32cd-4c44-b637-c4e643c08203 2015-02-18 09:36:48,860 | WARN | FelixShutdown | core | 123 - org.apache.aries.jmx.core - 1.1.2 | Task rejected for JMX Notification dispatch of event [org.osgi.framework.ServiceEvent[source=[javax.management.MBeanServer]]] - Dispatcher may have been shutdown 2015-02-18 09:36:48,862 | INFO | FelixShutdown | ScrServiceMBeanImpl | 127 - org.apache.karaf.scr.management - 3.0.2 | Deactivating the Apache Karaf SCR Service MBean 2015-02-18 09:36:48,878 | INFO | FelixShutdown | BlueprintExtender | 15 - org.apache.aries.blueprint.core - 1.4.1 | Destroying BlueprintContainer for bundle org.apache.karaf.web.core 2015-02-18 09:36:48,880 | INFO | FelixShutdown | BlueprintExtender | 15 - org.apache.aries.blueprint.core - 1.4.1 | Destroying BlueprintContainer for bundle org.apache.karaf.http.core 2015-02-18 09:36:48,882 | INFO | FelixShutdown | BlueprintExtender | 15 - org.apache.aries.blueprint.core - 1.4.1 | Destroying BlueprintContainer for bundle org.apache.karaf.config.command 2015-02-18 09:36:48,903 | INFO | FelixShutdown | BlueprintExtender | 15 - org.apache.aries.blueprint.core - 1.4.1 | Destroying BlueprintContainer for bundle org.apache.karaf.config.core 2015-02-18 09:36:48,904 | INFO | FelixShutdown | BlueprintExtender | 15 - org.apache.aries.blueprint.core - 1.4.1 | Destroying BlueprintContainer for bundle org.apache.karaf.jaas.modules 2015-02-18 09:36:48,907 | INFO | FelixShutdown | BlueprintExtender | 15 - org.apache.aries.blueprint.core - 1.4.1 | Destroying BlueprintContainer for bundle org.apache.karaf.jaas.config 2015-02-18 09:36:48,909 | INFO | FelixShutdown | BlueprintExtender | 15 - org.apache.aries.blueprint.core - 1.4.1 | Destroying BlueprintContainer for bundle org.apache.karaf.shell.console 2015-02-18 09:36:48,933 | INFO | FelixShutdown | BlueprintExtender | 15 - org.apache.aries.blueprint.core - 1.4.1 | Destroying BlueprintContainer for bundle org.apache.karaf.deployer.blueprint 2015-02-18 09:36:48,935 | INFO | FelixShutdown | BlueprintExtender | 15 - org.apache.aries.blueprint.core - 1.4.1 | Destroying BlueprintContainer for bundle org.apache.karaf.deployer.spring 2015-02-18 09:36:48,937 | INFO | FelixShutdown | BlueprintExtender | 15 - org.apache.aries.blueprint.core - 1.4.1 | Destroying BlueprintContainer for bundle org.apache.aries.blueprint.core 2015-02-18 09:36:48,939 | INFO | FelixShutdown | BlueprintExtender | 15 - org.apache.aries.blueprint.core - 1.4.1 | Destroying BlueprintContainer for bundle org.apache.aries.blueprint.cm 2015-02-18 09:36:48,950 | INFO | FelixStartLevel | ProxyArp | 162 - org.onosproject.onos-app-proxyarp - 1.1.0.SNAPSHOT | Stopped 2015-02-18 09:36:48,992 | INFO | FelixStartLevel | ReactiveForwarding | 142 - org.onosproject.onos-app-fwd - 1.1.0.SNAPSHOT | Stopped 2015-02-18 09:36:48,996 | INFO | FelixStartLevel | HostLocationProvider | 132 - org.onosproject.onos-host-provider - 1.1.0.SNAPSHOT | Stopped 2015-02-18 09:36:49,004 | INFO | FelixStartLevel | DefaultTopologyProvider | 157 - org.onosproject.onos-core-net - 1.1.0.SNAPSHOT | Stopped 2015-02-18 09:36:49,026 | INFO | FelixStartLevel | PathManager | 157 - org.onosproject.onos-core-net - 1.1.0.SNAPSHOT | Stopped 2015-02-18 09:36:49,036 | INFO | FelixStartLevel | IntentManager | 157 - org.onosproject.onos-core-net - 1.1.0.SNAPSHOT | Stopped 2015-02-18 09:36:49,038 | INFO | FelixStartLevel | GossipIntentStore | 159 - org.onosproject.onos-core-dist - 1.1.0.SNAPSHOT | Stopped 2015-02-18 09:36:49,040 | INFO | FelixStartLevel | ObjectiveTracker | 157 - org.onosproject.onos-core-net - 1.1.0.SNAPSHOT | Stopped 2015-02-18 09:36:49,041 | INFO | FelixStartLevel | TopologyManager | 157 - org.onosproject.onos-core-net - 1.1.0.SNAPSHOT | Stopped 2015-02-18 09:36:49,042 | INFO | FelixStartLevel | DistributedTopologyStore | 159 - org.onosproject.onos-core-dist - 1.1.0.SNAPSHOT | Stopped 2015-02-18 09:36:49,048 | INFO | opycat-default-1 | CopycatStateContext | 70 - org.onosproject.onlab-thirdparty - 1.1.0.SNAPSHOT | tcp://10.128.40.41:7238 - Transitioning to START 2015-02-18 09:36:49,057 | INFO | ycat-coordinator | CopycatStateContext | 70 - org.onosproject.onlab-thirdparty - 1.1.0.SNAPSHOT | tcp://10.128.40.41:7238 - Transitioning to START 2015-02-18 09:36:49,061 | INFO | FelixStartLevel | DatabaseManager | 159 - org.onosproject.onos-core-dist - 1.1.0.SNAPSHOT | Successfully closed database. 2015-02-18 09:36:49,061 | INFO | FelixStartLevel | DatabaseManager | 159 - org.onosproject.onos-core-dist - 1.1.0.SNAPSHOT | Stopped 2015-02-18 09:36:49,073 | INFO | FelixStartLevel | LLDPLinkProvider | 131 - org.onosproject.onos-lldp-provider - 1.1.0.SNAPSHOT | Stopped 2015-02-18 09:36:49,075 | INFO | FelixStartLevel | OpenFlowPacketProvider | 134 - org.onosproject.onos-of-provider-packet - 1.1.0.SNAPSHOT | Stopped 2015-02-18 09:36:49,077 | INFO | FelixStartLevel | ProxyArpManager | 157 - org.onosproject.onos-core-net - 1.1.0.SNAPSHOT | Stopped 2015-02-18 09:36:49,080 | INFO | FelixStartLevel | HostManager | 157 - org.onosproject.onos-core-net - 1.1.0.SNAPSHOT | Stopped 2015-02-18 09:36:49,082 | INFO | FelixStartLevel | PacketManager | 157 - org.onosproject.onos-core-net - 1.1.0.SNAPSHOT | Stopped 2015-02-18 09:36:49,083 | INFO | FelixStartLevel | StatisticManager | 157 - org.onosproject.onos-core-net - 1.1.0.SNAPSHOT | Stopped 2015-02-18 09:36:49,084 | INFO | FelixStartLevel | OpenFlowRuleProvider | 135 - org.onosproject.onos-of-provider-flow - 1.1.0.SNAPSHOT | Stopped 2015-02-18 09:36:49,089 | INFO | FelixStartLevel | FlowRuleManager | 157 - org.onosproject.onos-core-net - 1.1.0.SNAPSHOT | Stopped 2015-02-18 09:36:49,091 | INFO | FelixStartLevel | DistributedFlowRuleStore | 159 - org.onosproject.onos-core-dist - 1.1.0.SNAPSHOT | Stopped 2015-02-18 09:36:49,093 | INFO | FelixStartLevel | DistributedStatisticStore | 159 - org.onosproject.onos-core-dist - 1.1.0.SNAPSHOT | Stopped 2015-02-18 09:36:49,094 | INFO | FelixStartLevel | ReplicaInfoManager | 159 - org.onosproject.onos-core-dist - 1.1.0.SNAPSHOT | Stopped 2015-02-18 09:36:49,096 | INFO | FelixStartLevel | GossipApplicationStore | 159 - org.onosproject.onos-core-dist - 1.1.0.SNAPSHOT | Stopped 2015-02-18 09:36:49,100 | INFO | FelixStartLevel | DistributedApplicationIdStore | 159 - org.onosproject.onos-core-dist - 1.1.0.SNAPSHOT | Stopped 2015-02-18 09:36:49,103 | INFO | FelixStartLevel | LinkResourceManager | 157 - org.onosproject.onos-core-net - 1.1.0.SNAPSHOT | Stopped 2015-02-18 09:36:49,104 | INFO | FelixStartLevel | HazelcastLinkResourceStore | 159 - org.onosproject.onos-core-dist - 1.1.0.SNAPSHOT | Stopped 2015-02-18 09:36:49,106 | INFO | FelixStartLevel | LinkManager | 157 - org.onosproject.onos-core-net - 1.1.0.SNAPSHOT | Stopped 2015-02-18 09:36:49,107 | INFO | FelixStartLevel | GossipLinkStore | 159 - org.onosproject.onos-core-dist - 1.1.0.SNAPSHOT | Stopped 2015-02-18 09:36:49,111 | INFO | FelixStartLevel | GossipHostStore | 159 - org.onosproject.onos-core-dist - 1.1.0.SNAPSHOT | Stopped 2015-02-18 09:36:49,112 | INFO | FelixStartLevel | HostClockManager | 159 - org.onosproject.onos-core-dist - 1.1.0.SNAPSHOT | Stopped 2015-02-18 09:36:49,116 | INFO | FelixStartLevel | OpenFlowDeviceProvider | 133 - org.onosproject.onos-of-provider-device - 1.1.0.SNAPSHOT | Stopped 2015-02-18 09:36:49,118 | INFO | FelixStartLevel | DeviceManager | 157 - org.onosproject.onos-core-net - 1.1.0.SNAPSHOT | Stopped 2015-02-18 09:36:49,121 | INFO | FelixStartLevel | GossipDeviceStore | 159 - org.onosproject.onos-core-dist - 1.1.0.SNAPSHOT | Stopped 2015-02-18 09:36:49,122 | INFO | FelixStartLevel | DistributedPacketStore | 159 - org.onosproject.onos-core-dist - 1.1.0.SNAPSHOT | Stopped 2015-02-18 09:36:49,124 | INFO | FelixStartLevel | MastershipManager | 157 - org.onosproject.onos-core-net - 1.1.0.SNAPSHOT | Stopped 2015-02-18 09:36:49,126 | INFO | FelixStartLevel | DistributedMastershipStore | 159 - org.onosproject.onos-core-dist - 1.1.0.SNAPSHOT | Stopped 2015-02-18 09:36:49,131 | INFO | FelixStartLevel | ClusterCommunicationManager | 159 - org.onosproject.onos-core-dist - 1.1.0.SNAPSHOT | Stopped 2015-02-18 09:36:49,146 | INFO | FelixStartLevel | HazelcastLeadershipService | 159 - org.onosproject.onos-core-dist - 1.1.0.SNAPSHOT | Hazelcast Leadership Service stopped 2015-02-18 09:36:49,148 | INFO | FelixStartLevel | ClusterManager | 157 - org.onosproject.onos-core-net - 1.1.0.SNAPSHOT | Stopped 2015-02-18 09:36:49,150 | INFO | FelixStartLevel | DistributedClusterStore | 159 - org.onosproject.onos-core-dist - 1.1.0.SNAPSHOT | Stopped 2015-02-18 09:36:49,150 | INFO | nt-partition-4-1 | HazelcastLeadershipService | 159 - org.onosproject.onos-core-dist - 1.1.0.SNAPSHOT | Leader Elected for topic intent-partition-4 2015-02-18 09:36:49,153 | INFO | t-partition-19-1 | HazelcastLeadershipService | 159 - org.onosproject.onos-core-dist - 1.1.0.SNAPSHOT | Leader Elected for topic intent-partition-19 2015-02-18 09:36:49,154 | INFO | FelixStartLevel | LifecycleService | 53 - com.hazelcast - 3.4.0 | [10.128.40.41]:5701 [choTest5] [3.4] Address[10.128.40.41]:5701 is SHUTTING_DOWN 2015-02-18 09:36:49,155 | INFO | nt-partition-6-1 | HazelcastLeadershipService | 159 - org.onosproject.onos-core-dist - 1.1.0.SNAPSHOT | Leader Elected for topic intent-partition-6 2015-02-18 09:36:49,159 | INFO | nt-partition-7-1 | HazelcastLeadershipService | 159 - org.onosproject.onos-core-dist - 1.1.0.SNAPSHOT | Leader Elected for topic intent-partition-7 2015-02-18 09:36:49,164 | INFO | nt-partition-1-1 | HazelcastLeadershipService | 159 - org.onosproject.onos-core-dist - 1.1.0.SNAPSHOT | Leader Elected for topic intent-partition-1 2015-02-18 09:36:49,180 | INFO | t-partition-17-1 | HazelcastLeadershipService | 159 - org.onosproject.onos-core-dist - 1.1.0.SNAPSHOT | Leader Elected for topic intent-partition-17 2015-02-18 09:36:49,182 | INFO | t-partition-18-1 | HazelcastLeadershipService | 159 - org.onosproject.onos-core-dist - 1.1.0.SNAPSHOT | Leader Elected for topic intent-partition-18 2015-02-18 09:36:49,182 | INFO | FelixStartLevel | Node | 53 - com.hazelcast - 3.4.0 | [10.128.40.41]:5701 [choTest5] [3.4] Shutting down multicast service... 2015-02-18 09:36:49,186 | INFO | FelixStartLevel | Node | 53 - com.hazelcast - 3.4.0 | [10.128.40.41]:5701 [choTest5] [3.4] Shutting down connection manager... 2015-02-18 09:36:49,191 | INFO | 5.IO.thread-in-2 | TcpIpConnection | 53 - com.hazelcast - 3.4.0 | [10.128.40.41]:5701 [choTest5] [3.4] Connection [Address[10.128.40.44]:5701] lost. Reason: java.io.IOException[Connection reset by peer] 2015-02-18 09:36:49,191 | INFO | 5.IO.thread-in-1 | TcpIpConnection | 53 - com.hazelcast - 3.4.0 | [10.128.40.41]:5701 [choTest5] [3.4] Connection [Address[10.128.40.43]:5701] lost. Reason: java.io.EOFException[Remote socket closed!] 2015-02-18 09:36:49,192 | INFO | 5.IO.thread-in-0 | TcpIpConnection | 53 - com.hazelcast - 3.4.0 | [10.128.40.41]:5701 [choTest5] [3.4] Connection [Address[10.128.40.42]:5701] lost. Reason: java.io.EOFException[Remote socket closed!] 2015-02-18 09:36:49,193 | INFO | FelixStartLevel | TcpIpConnection | 53 - com.hazelcast - 3.4.0 | [10.128.40.41]:5701 [choTest5] [3.4] Connection [Address[10.128.40.45]:5701] lost. Reason: Socket explicitly closed 2015-02-18 09:36:49,194 | INFO | FelixStartLevel | Node | 53 - com.hazelcast - 3.4.0 | [10.128.40.41]:5701 [choTest5] [3.4] Shutting down node engine... 2015-02-18 09:36:49,249 | INFO | FelixStartLevel | NodeExtension | 53 - com.hazelcast - 3.4.0 | [10.128.40.41]:5701 [choTest5] [3.4] Destroying node NodeExtension. 2015-02-18 09:36:49,250 | INFO | FelixStartLevel | Node | 53 - com.hazelcast - 3.4.0 | [10.128.40.41]:5701 [choTest5] [3.4] Hazelcast Shutdown is completed in 93 ms. 2015-02-18 09:36:49,251 | INFO | FelixStartLevel | LifecycleService | 53 - com.hazelcast - 3.4.0 | [10.128.40.41]:5701 [choTest5] [3.4] Address[10.128.40.41]:5701 is SHUTDOWN 2015-02-18 09:36:49,251 | INFO | FelixStartLevel | StoreManager | 159 - org.onosproject.onos-core-dist - 1.1.0.SNAPSHOT | Stopped 2015-02-18 09:36:49,252 | INFO | FelixStartLevel | IntentClockManager | 159 - org.onosproject.onos-core-dist - 1.1.0.SNAPSHOT | Stopped 2015-02-18 09:36:49,253 | INFO | FelixStartLevel | DeviceClockManager | 159 - org.onosproject.onos-core-dist - 1.1.0.SNAPSHOT | Stopped 2015-02-18 09:36:49,256 | INFO | event-dispatch-0 | CoreEventDispatcher | 157 - org.onosproject.onos-core-net - 1.1.0.SNAPSHOT | Dispatch loop terminated 2015-02-18 09:36:49,256 | INFO | FelixStartLevel | CoreEventDispatcher | 157 - org.onosproject.onos-core-net - 1.1.0.SNAPSHOT | Stopped 2015-02-18 09:36:49,259 | INFO | FelixStartLevel | Controller | 130 - org.onosproject.onos-of-ctl - 1.1.0.SNAPSHOT | Stopping OpenFlow IO 2015-02-18 09:36:49,261 | INFO | ew I/O worker #1 | OFChannelHandler | 130 - org.onosproject.onos-of-ctl - 1.1.0.SNAPSHOT | Switch disconnected callback for sw:OFSwitchImplOVS13 [/10.128.40.50:46960 DPID[00:00:00:00:00:00:00:08]]. Cleaning up ... 2015-02-18 09:36:49,261 | INFO | ew I/O worker #1 | OFChannelHandler | 130 - org.onosproject.onos-of-ctl - 1.1.0.SNAPSHOT | OFSwitchImplOVS13 [/10.128.40.50:46960 DPID[00:00:00:00:00:00:00:08]]:removal called 2015-02-18 09:36:49,261 | WARN | ew I/O worker #1 | ntrollerImpl$OpenFlowSwitchAgent | 130 - org.onosproject.onos-of-ctl - 1.1.0.SNAPSHOT | sw was null for 00:00:00:00:00:00:00:08 2015-02-18 09:36:49,261 | WARN | ew I/O worker #1 | ntrollerImpl$OpenFlowSwitchAgent | 130 - org.onosproject.onos-of-ctl - 1.1.0.SNAPSHOT | removal for 00:00:00:00:00:00:00:08 2015-02-18 09:36:49,261 | INFO | ew I/O worker #1 | FlowStatsCollector | 135 - org.onosproject.onos-of-provider-flow - 1.1.0.SNAPSHOT | Stopping Stats collection thread for 00:00:00:00:00:00:00:08 2015-02-18 09:36:49,263 | INFO | ew I/O worker #4 | OFChannelHandler | 130 - org.onosproject.onos-of-ctl - 1.1.0.SNAPSHOT | Switch disconnected callback for sw:OFSwitchImplOVS13 [/10.128.40.50:47045 DPID[00:00:00:00:00:00:00:13]]. Cleaning up ... 2015-02-18 09:36:49,263 | INFO | ew I/O worker #4 | OFChannelHandler | 130 - org.onosproject.onos-of-ctl - 1.1.0.SNAPSHOT | OFSwitchImplOVS13 [/10.128.40.50:47045 DPID[00:00:00:00:00:00:00:13]]:removal called 2015-02-18 09:36:49,263 | WARN | ew I/O worker #4 | ntrollerImpl$OpenFlowSwitchAgent | 130 - org.onosproject.onos-of-ctl - 1.1.0.SNAPSHOT | sw was null for 00:00:00:00:00:00:00:13 2015-02-18 09:36:49,263 | WARN | ew I/O worker #4 | ntrollerImpl$OpenFlowSwitchAgent | 130 - org.onosproject.onos-of-ctl - 1.1.0.SNAPSHOT | removal for 00:00:00:00:00:00:00:13 2015-02-18 09:36:49,263 | INFO | ew I/O worker #4 | FlowStatsCollector | 135 - org.onosproject.onos-of-provider-flow - 1.1.0.SNAPSHOT | Stopping Stats collection thread for 00:00:00:00:00:00:00:13 2015-02-18 09:36:49,263 | INFO | ew I/O worker #4 | OFChannelHandler | 130 - org.onosproject.onos-of-ctl - 1.1.0.SNAPSHOT | Switch disconnected callback for sw:OFSwitchImplOVS13 [/10.128.40.50:46974 DPID[00:00:00:00:00:00:00:0b]]. Cleaning up ... 2015-02-18 09:36:49,263 | INFO | ew I/O worker #4 | OFChannelHandler | 130 - org.onosproject.onos-of-ctl - 1.1.0.SNAPSHOT | OFSwitchImplOVS13 [/10.128.40.50:46974 DPID[00:00:00:00:00:00:00:0b]]:removal called 2015-02-18 09:36:49,263 | WARN | ew I/O worker #4 | ntrollerImpl$OpenFlowSwitchAgent | 130 - org.onosproject.onos-of-ctl - 1.1.0.SNAPSHOT | sw was null for 00:00:00:00:00:00:00:0b 2015-02-18 09:36:49,263 | WARN | ew I/O worker #4 | ntrollerImpl$OpenFlowSwitchAgent | 130 - org.onosproject.onos-of-ctl - 1.1.0.SNAPSHOT | removal for 00:00:00:00:00:00:00:0b 2015-02-18 09:36:49,264 | INFO | ew I/O worker #4 | FlowStatsCollector | 135 - org.onosproject.onos-of-provider-flow - 1.1.0.SNAPSHOT | Stopping Stats collection thread for 00:00:00:00:00:00:00:0b 2015-02-18 09:36:49,264 | INFO | ew I/O worker #5 | OFChannelHandler | 130 - org.onosproject.onos-of-ctl - 1.1.0.SNAPSHOT | Switch disconnected callback for sw:OFSwitchImplOVS13 [/10.128.40.50:47049 DPID[00:00:00:00:00:00:00:14]]. Cleaning up ... 2015-02-18 09:36:49,264 | INFO | ew I/O worker #5 | OFChannelHandler | 130 - org.onosproject.onos-of-ctl - 1.1.0.SNAPSHOT | OFSwitchImplOVS13 [/10.128.40.50:47049 DPID[00:00:00:00:00:00:00:14]]:removal called 2015-02-18 09:36:49,264 | WARN | ew I/O worker #5 | ntrollerImpl$OpenFlowSwitchAgent | 130 - org.onosproject.onos-of-ctl - 1.1.0.SNAPSHOT | sw was null for 00:00:00:00:00:00:00:14 2015-02-18 09:36:49,264 | WARN | ew I/O worker #5 | ntrollerImpl$OpenFlowSwitchAgent | 130 - org.onosproject.onos-of-ctl - 1.1.0.SNAPSHOT | removal for 00:00:00:00:00:00:00:14 2015-02-18 09:36:49,264 | INFO | ew I/O worker #5 | FlowStatsCollector | 135 - org.onosproject.onos-of-provider-flow - 1.1.0.SNAPSHOT | Stopping Stats collection thread for 00:00:00:00:00:00:00:14 2015-02-18 09:36:49,264 | INFO | ew I/O worker #5 | OFChannelHandler | 130 - org.onosproject.onos-of-ctl - 1.1.0.SNAPSHOT | Switch disconnected callback for sw:OFSwitchImplOVS13 [/10.128.40.50:46978 DPID[00:00:00:00:00:00:00:0c]]. Cleaning up ... 2015-02-18 09:36:49,264 | INFO | ew I/O worker #5 | OFChannelHandler | 130 - org.onosproject.onos-of-ctl - 1.1.0.SNAPSHOT | OFSwitchImplOVS13 [/10.128.40.50:46978 DPID[00:00:00:00:00:00:00:0c]]:removal called 2015-02-18 09:36:49,264 | WARN | ew I/O worker #5 | ntrollerImpl$OpenFlowSwitchAgent | 130 - org.onosproject.onos-of-ctl - 1.1.0.SNAPSHOT | sw was null for 00:00:00:00:00:00:00:0c 2015-02-18 09:36:49,264 | WARN | ew I/O worker #5 | ntrollerImpl$OpenFlowSwitchAgent | 130 - org.onosproject.onos-of-ctl - 1.1.0.SNAPSHOT | removal for 00:00:00:00:00:00:00:0c 2015-02-18 09:36:49,264 | INFO | ew I/O worker #5 | FlowStatsCollector | 135 - org.onosproject.onos-of-provider-flow - 1.1.0.SNAPSHOT | Stopping Stats collection thread for 00:00:00:00:00:00:00:0c 2015-02-18 09:36:49,264 | INFO | ew I/O worker #6 | OFChannelHandler | 130 - org.onosproject.onos-of-ctl - 1.1.0.SNAPSHOT | Switch disconnected callback for sw:OFSwitchImplOVS13 [/10.128.40.50:46986 DPID[00:00:00:00:00:00:00:0d]]. Cleaning up ... 2015-02-18 09:36:49,264 | INFO | ew I/O worker #6 | OFChannelHandler | 130 - org.onosproject.onos-of-ctl - 1.1.0.SNAPSHOT | OFSwitchImplOVS13 [/10.128.40.50:46986 DPID[00:00:00:00:00:00:00:0d]]:removal called 2015-02-18 09:36:49,264 | WARN | ew I/O worker #6 | ntrollerImpl$OpenFlowSwitchAgent | 130 - org.onosproject.onos-of-ctl - 1.1.0.SNAPSHOT | sw was null for 00:00:00:00:00:00:00:0d 2015-02-18 09:36:49,264 | WARN | ew I/O worker #6 | ntrollerImpl$OpenFlowSwitchAgent | 130 - org.onosproject.onos-of-ctl - 1.1.0.SNAPSHOT | removal for 00:00:00:00:00:00:00:0d 2015-02-18 09:36:49,264 | INFO | ew I/O worker #6 | FlowStatsCollector | 135 - org.onosproject.onos-of-provider-flow - 1.1.0.SNAPSHOT | Stopping Stats collection thread for 00:00:00:00:00:00:00:0d 2015-02-18 09:36:49,265 | INFO | ew I/O worker #7 | OFChannelHandler | 130 - org.onosproject.onos-of-ctl - 1.1.0.SNAPSHOT | Switch disconnected callback for sw:OFSwitchImplOVS13 [/10.128.40.50:46988 DPID[00:00:00:00:00:00:00:0e]]. Cleaning up ... 2015-02-18 09:36:49,265 | INFO | ew I/O worker #7 | OFChannelHandler | 130 - org.onosproject.onos-of-ctl - 1.1.0.SNAPSHOT | OFSwitchImplOVS13 [/10.128.40.50:46988 DPID[00:00:00:00:00:00:00:0e]]:removal called 2015-02-18 09:36:49,265 | WARN | ew I/O worker #7 | ntrollerImpl$OpenFlowSwitchAgent | 130 - org.onosproject.onos-of-ctl - 1.1.0.SNAPSHOT | sw was null for 00:00:00:00:00:00:00:0e 2015-02-18 09:36:49,265 | WARN | ew I/O worker #7 | ntrollerImpl$OpenFlowSwitchAgent | 130 - org.onosproject.onos-of-ctl - 1.1.0.SNAPSHOT | removal for 00:00:00:00:00:00:00:0e 2015-02-18 09:36:49,265 | INFO | ew I/O worker #7 | FlowStatsCollector | 135 - org.onosproject.onos-of-provider-flow - 1.1.0.SNAPSHOT | Stopping Stats collection thread for 00:00:00:00:00:00:00:0e 2015-02-18 09:36:49,265 | INFO | ew I/O worker #8 | OFChannelHandler | 130 - org.onosproject.onos-of-ctl - 1.1.0.SNAPSHOT | Switch disconnected callback for sw:OFSwitchImplOVS13 [/10.128.40.50:46995 DPID[00:00:00:00:00:00:00:0f]]. Cleaning up ... 2015-02-18 09:36:49,265 | INFO | ew I/O worker #8 | OFChannelHandler | 130 - org.onosproject.onos-of-ctl - 1.1.0.SNAPSHOT | OFSwitchImplOVS13 [/10.128.40.50:46995 DPID[00:00:00:00:00:00:00:0f]]:removal called 2015-02-18 09:36:49,265 | WARN | ew I/O worker #8 | ntrollerImpl$OpenFlowSwitchAgent | 130 - org.onosproject.onos-of-ctl - 1.1.0.SNAPSHOT | sw was null for 00:00:00:00:00:00:00:0f 2015-02-18 09:36:49,265 | WARN | ew I/O worker #8 | ntrollerImpl$OpenFlowSwitchAgent | 130 - org.onosproject.onos-of-ctl - 1.1.0.SNAPSHOT | removal for 00:00:00:00:00:00:00:0f 2015-02-18 09:36:49,265 | INFO | ew I/O worker #8 | FlowStatsCollector | 135 - org.onosproject.onos-of-provider-flow - 1.1.0.SNAPSHOT | Stopping Stats collection thread for 00:00:00:00:00:00:00:0f 2015-02-18 09:36:49,265 | INFO | ew I/O worker #8 | OFChannelHandler | 130 - org.onosproject.onos-of-ctl - 1.1.0.SNAPSHOT | Switch disconnected callback for sw:OFSwitchImplOVS13 [/10.128.40.50:47063 DPID[00:00:00:00:00:00:00:17]]. Cleaning up ... 2015-02-18 09:36:49,265 | INFO | ew I/O worker #8 | OFChannelHandler | 130 - org.onosproject.onos-of-ctl - 1.1.0.SNAPSHOT | OFSwitchImplOVS13 [/10.128.40.50:47063 DPID[00:00:00:00:00:00:00:17]]:removal called 2015-02-18 09:36:49,265 | WARN | ew I/O worker #8 | ntrollerImpl$OpenFlowSwitchAgent | 130 - org.onosproject.onos-of-ctl - 1.1.0.SNAPSHOT | sw was null for 00:00:00:00:00:00:00:17 2015-02-18 09:36:49,265 | WARN | ew I/O worker #8 | ntrollerImpl$OpenFlowSwitchAgent | 130 - org.onosproject.onos-of-ctl - 1.1.0.SNAPSHOT | removal for 00:00:00:00:00:00:00:17 2015-02-18 09:36:49,266 | INFO | ew I/O worker #8 | FlowStatsCollector | 135 - org.onosproject.onos-of-provider-flow - 1.1.0.SNAPSHOT | Stopping Stats collection thread for 00:00:00:00:00:00:00:17 2015-02-18 09:36:49,266 | INFO | ew I/O worker #9 | OFChannelHandler | 130 - org.onosproject.onos-of-ctl - 1.1.0.SNAPSHOT | Switch disconnected callback for sw:OFSwitchImplOVS13 [/10.128.40.50:47001 DPID[00:00:00:00:00:00:00:10]]. Cleaning up ... 2015-02-18 09:36:49,266 | INFO | ew I/O worker #9 | OFChannelHandler | 130 - org.onosproject.onos-of-ctl - 1.1.0.SNAPSHOT | OFSwitchImplOVS13 [/10.128.40.50:47001 DPID[00:00:00:00:00:00:00:10]]:removal called 2015-02-18 09:36:49,266 | WARN | ew I/O worker #9 | ntrollerImpl$OpenFlowSwitchAgent | 130 - org.onosproject.onos-of-ctl - 1.1.0.SNAPSHOT | sw was null for 00:00:00:00:00:00:00:10 2015-02-18 09:36:49,266 | WARN | ew I/O worker #9 | ntrollerImpl$OpenFlowSwitchAgent | 130 - org.onosproject.onos-of-ctl - 1.1.0.SNAPSHOT | removal for 00:00:00:00:00:00:00:10 2015-02-18 09:36:49,266 | INFO | ew I/O worker #9 | FlowStatsCollector | 135 - org.onosproject.onos-of-provider-flow - 1.1.0.SNAPSHOT | Stopping Stats collection thread for 00:00:00:00:00:00:00:10 2015-02-18 09:36:49,266 | INFO | w I/O worker #10 | OFChannelHandler | 130 - org.onosproject.onos-of-ctl - 1.1.0.SNAPSHOT | Switch disconnected callback for sw:OFSwitchImplOVS13 [/10.128.40.50:47074 DPID[00:00:00:00:00:00:00:19]]. Cleaning up ... 2015-02-18 09:36:49,266 | INFO | w I/O worker #10 | OFChannelHandler | 130 - org.onosproject.onos-of-ctl - 1.1.0.SNAPSHOT | OFSwitchImplOVS13 [/10.128.40.50:47074 DPID[00:00:00:00:00:00:00:19]]:removal called 2015-02-18 09:36:49,266 | WARN | w I/O worker #10 | ntrollerImpl$OpenFlowSwitchAgent | 130 - org.onosproject.onos-of-ctl - 1.1.0.SNAPSHOT | sw was null for 00:00:00:00:00:00:00:19 2015-02-18 09:36:49,266 | WARN | w I/O worker #10 | ntrollerImpl$OpenFlowSwitchAgent | 130 - org.onosproject.onos-of-ctl - 1.1.0.SNAPSHOT | removal for 00:00:00:00:00:00:00:19 2015-02-18 09:36:49,266 | INFO | w I/O worker #10 | FlowStatsCollector | 135 - org.onosproject.onos-of-provider-flow - 1.1.0.SNAPSHOT | Stopping Stats collection thread for 00:00:00:00:00:00:00:19 2015-02-18 09:36:49,267 | INFO | w I/O worker #11 | OFChannelHandler | 130 - org.onosproject.onos-of-ctl - 1.1.0.SNAPSHOT | Switch disconnected callback for sw:OFSwitchImplOVS13 [/10.128.40.50:47011 DPID[00:00:00:00:00:00:00:12]]. Cleaning up ... 2015-02-18 09:36:49,267 | INFO | w I/O worker #11 | OFChannelHandler | 130 - org.onosproject.onos-of-ctl - 1.1.0.SNAPSHOT | OFSwitchImplOVS13 [/10.128.40.50:47011 DPID[00:00:00:00:00:00:00:12]]:removal called 2015-02-18 09:36:49,267 | WARN | w I/O worker #11 | ntrollerImpl$OpenFlowSwitchAgent | 130 - org.onosproject.onos-of-ctl - 1.1.0.SNAPSHOT | sw was null for 00:00:00:00:00:00:00:12 2015-02-18 09:36:49,267 | WARN | w I/O worker #11 | ntrollerImpl$OpenFlowSwitchAgent | 130 - org.onosproject.onos-of-ctl - 1.1.0.SNAPSHOT | removal for 00:00:00:00:00:00:00:12 2015-02-18 09:36:49,267 | INFO | w I/O worker #11 | FlowStatsCollector | 135 - org.onosproject.onos-of-provider-flow - 1.1.0.SNAPSHOT | Stopping Stats collection thread for 00:00:00:00:00:00:00:12 2015-02-18 09:36:49,267 | INFO | w I/O worker #14 | OFChannelHandler | 130 - org.onosproject.onos-of-ctl - 1.1.0.SNAPSHOT | Switch disconnected callback for sw:OFSwitchImplOVS13 [/10.128.40.50:46943 DPID[00:00:00:00:00:00:00:05]]. Cleaning up ... 2015-02-18 09:36:49,267 | INFO | w I/O worker #14 | OFChannelHandler | 130 - org.onosproject.onos-of-ctl - 1.1.0.SNAPSHOT | OFSwitchImplOVS13 [/10.128.40.50:46943 DPID[00:00:00:00:00:00:00:05]]:removal called 2015-02-18 09:36:49,267 | WARN | w I/O worker #14 | ntrollerImpl$OpenFlowSwitchAgent | 130 - org.onosproject.onos-of-ctl - 1.1.0.SNAPSHOT | sw was null for 00:00:00:00:00:00:00:05 2015-02-18 09:36:49,267 | WARN | w I/O worker #14 | ntrollerImpl$OpenFlowSwitchAgent | 130 - org.onosproject.onos-of-ctl - 1.1.0.SNAPSHOT | removal for 00:00:00:00:00:00:00:05 2015-02-18 09:36:49,267 | INFO | w I/O worker #14 | FlowStatsCollector | 135 - org.onosproject.onos-of-provider-flow - 1.1.0.SNAPSHOT | Stopping Stats collection thread for 00:00:00:00:00:00:00:05 2015-02-18 09:36:49,268 | INFO | w I/O worker #16 | OFChannelHandler | 130 - org.onosproject.onos-of-ctl - 1.1.0.SNAPSHOT | Switch disconnected callback for sw:OFSwitchImplOVS13 [/10.128.40.50:46955 DPID[00:00:00:00:00:00:00:07]]. Cleaning up ... 2015-02-18 09:36:49,268 | INFO | w I/O worker #16 | OFChannelHandler | 130 - org.onosproject.onos-of-ctl - 1.1.0.SNAPSHOT | OFSwitchImplOVS13 [/10.128.40.50:46955 DPID[00:00:00:00:00:00:00:07]]:removal called 2015-02-18 09:36:49,268 | WARN | w I/O worker #16 | ntrollerImpl$OpenFlowSwitchAgent | 130 - org.onosproject.onos-of-ctl - 1.1.0.SNAPSHOT | sw was null for 00:00:00:00:00:00:00:07 2015-02-18 09:36:49,268 | WARN | w I/O worker #16 | ntrollerImpl$OpenFlowSwitchAgent | 130 - org.onosproject.onos-of-ctl - 1.1.0.SNAPSHOT | removal for 00:00:00:00:00:00:00:07 2015-02-18 09:36:49,268 | INFO | w I/O worker #16 | FlowStatsCollector | 135 - org.onosproject.onos-of-provider-flow - 1.1.0.SNAPSHOT | Stopping Stats collection thread for 00:00:00:00:00:00:00:07 2015-02-18 09:36:49,272 | INFO | FelixStartLevel | Activator | 98 - org.ops4j.pax.web.pax-web-extender-war - 3.1.2 | Destroying extension for bundle org.onosproject.onos-rest 2015-02-18 09:36:49,279 | INFO | FelixStartLevel | ContextHandler | 84 - org.eclipse.jetty.aggregate.jetty-all-server - 8.1.15.v20140411 | stopped HttpServiceContext{httpContext=WebAppHttpContext{org.onosproject.onos-rest - 120}} 2015-02-18 09:36:49,281 | INFO | FelixStartLevel | HttpServiceFactoryImpl | 92 - org.ops4j.pax.web.pax-web-runtime - 3.1.2 | Unbinding bundle: [org.onosproject.onos-rest [120]] 2015-02-18 09:36:49,282 | INFO | FelixStartLevel | CodecManager | 120 - org.onosproject.onos-rest - 1.1.0.SNAPSHOT | Stopped 2015-02-18 09:36:49,283 | INFO | FelixStartLevel | Activator | 98 - org.ops4j.pax.web.pax-web-extender-war - 3.1.2 | Destroying extension for bundle org.onosproject.onos-gui 2015-02-18 09:36:49,286 | INFO | FelixStartLevel | TopologyViewMessages | 115 - org.onosproject.onos-gui - 1.1.0.SNAPSHOT | GUI client disconnected 2015-02-18 09:36:49,287 | INFO | FelixStartLevel | ContextHandler | 84 - org.eclipse.jetty.aggregate.jetty-all-server - 8.1.15.v20140411 | stopped HttpServiceContext{httpContext=WebAppHttpContext{org.onosproject.onos-gui - 115}} 2015-02-18 09:36:49,288 | INFO | FelixStartLevel | HttpServiceFactoryImpl | 92 - org.ops4j.pax.web.pax-web-runtime - 3.1.2 | Unbinding bundle: [org.onosproject.onos-gui [115]] 2015-02-18 09:36:49,303 | INFO | FelixStartLevel | HttpServiceFactoryImpl | 92 - org.ops4j.pax.web.pax-web-runtime - 3.1.2 | Unbinding bundle: [org.apache.karaf.webconsole.console [144]] 2015-02-18 09:36:49,307 | INFO | FelixStartLevel | core | 123 - org.apache.aries.jmx.core - 1.1.2 | Stopping JMX OSGi agent 2015-02-18 09:36:49,307 | INFO | FelixStartLevel | core | 123 - org.apache.aries.jmx.core - 1.1.2 | Unregistering MBean with ObjectName [osgi.compendium:service=cm,version=1.3,framework=org.apache.felix.framework,uuid=c32b4bd6-32cd-4c44-b637-c4e643c08203] for service with service.id [13] 2015-02-18 09:36:49,350 | INFO | pool-3-thread-2 | ContextHandler | 84 - org.eclipse.jetty.aggregate.jetty-all-server - 8.1.15.v20140411 | stopped HttpServiceContext{httpContext=org.apache.felix.webconsole.internal.servlet.OsgiManagerHttpContext@26756539} 2015-02-18 09:36:49,351 | INFO | pool-3-thread-2 | ContextHandler | 84 - org.eclipse.jetty.aggregate.jetty-all-server - 8.1.15.v20140411 | stopped HttpServiceContext{httpContext=org.apache.felix.webconsole.internal.servlet.OsgiManagerHttpContext@5be04012} 2015-02-18 09:36:49,406 | INFO | FelixStartLevel | Activator | 92 - org.ops4j.pax.web.pax-web-runtime - 3.1.2 | Pax Web stopped 2015-02-18 09:36:49,411 | INFO | FelixStartLevel | DetailsCommandComponent | 39 - org.apache.karaf.scr.command - 3.0.2 | Deactivating the Apache Karaf SCR Details Command 2015-02-18 09:36:49,412 | INFO | FelixStartLevel | DeactivateCommandComponent | 39 - org.apache.karaf.scr.command - 3.0.2 | Deactivating the Apache Karaf SCR Deactivate Command 2015-02-18 09:36:49,413 | INFO | FelixStartLevel | ActivateCommandComponent | 39 - org.apache.karaf.scr.command - 3.0.2 | Deactivating the Apache Karaf SCR Activate Command 2015-02-18 09:36:49,414 | INFO | FelixStartLevel | ListCommandComponent | 39 - org.apache.karaf.scr.command - 3.0.2 | Deactivating the Apache Karaf SCR List Command

    JIRA | 2 years ago | hari krishna [X]
    java.lang.NullPointerException
  3. 0

    When ovs device is repeatedly connected and disconnected from onos controller, null pointer exception was happening in the FlowStatsCollector . I followed the below steps to reproduce the issue. 1. Started onos 1.5 and executed log:tail command for viewing the real time logs. 2. Run a python script to start and stop mininet continuously (script is attached). 3. The below exception was visible in onos logs. 6-07-14 15:41:37,551 | INFO | ew I/O worker #8 | PortStatsCollector | 168 - org.onosproject.onos-of-provider-device - 1.5.2.SNAPSHOT | Stopping Port Stats collection thread for 00:00:00:00:00:00:00:01 2016-07-14 15:41:37,551 | ERROR | ew I/O worker #8 | OFChannelHandler | 167 - org.onosproject.onos-of-ctl - 1.5.2.SNAPSHOT | Error while processing message from switch org.onosproject.driver.handshaker.NiciraSwitchHandshaker [/192.168.56.108:41576 DPID[00:00:00:00:00:00:00:01]]state WAIT_DESCRIPTION_STAT_REPLY java.lang.NullPointerException at org.onosproject.provider.of.flow.impl.FlowStatsCollector.start(FlowStatsCollector.java:88)[170:org.onosproject.onos-of-provider-flow:1.5.2.SNAPSHOT] at org.onosproject.provider.of.flow.impl.OpenFlowRuleProvider.createCollector(OpenFlowRuleProvider.java:228)[170:org.onosproject.onos-of-provider-flow:1.5.2.SNAPSHOT] at org.onosproject.provider.of.flow.impl.OpenFlowRuleProvider.access$100(OpenFlowRuleProvider.java:95)[170:org.onosproject.onos-of-provider-flow:1.5.2.SNAPSHOT] at org.onosproject.provider.of.flow.impl.OpenFlowRuleProvider$InternalFlowProvider.switchAdded(OpenFlowRuleProvider.java:395)[170:org.onosproject.onos-of-provider-flow:1.5.2.SNAPSHOT] at org.onosproject.openflow.controller.impl.OpenFlowControllerImpl$OpenFlowSwitchAgent.addConnectedSwitch(OpenFlowControllerImpl.java:537)[167:org.onosproject.onos-of-ctl:1.5.2.SNAPSHOT] at org.onosproject.openflow.controller.driver.AbstractOpenFlowSwitch.connectSwitch(AbstractOpenFlowSwitch.java:294)[164:o

    JIRA | 10 months ago | Prince Pereira
    java.lang.NullPointerException
  4. Speed up your debug routine!

    Automated exception search integrated into your IDE

  5. 0

    Occasionally the events that trigger loading of required applications for the openflow meta app get delivered out of order, and apps that are required are loaded after the meta app. Here is an example stack trace: 2016-08-10 22:21:44,178 | WARN | -message-handler | ApplicationManager | 76 - org.onosproject.onos-core-net - 1.7.0.SNAPSHOT | Unable to perform operation on application org.onosproject.openflow-base java.lang.IllegalStateException: Can't install feature onos-providers-openflow-base/0.0.0: Could not start bundle mvn:org.onosproject/onos-providers-openflow-device/1.7.0-SNAPSHOT in feature(s) onos-providers-openflow-base-1.7.0-SNAPSHOT: Unresolved constraint in bundle org.onosproject.onos-providers-openflow-device [163]: Unable to resolve 163.0: missing requirement [163.0] osgi.wiring.package; (&(osgi.wiring.package=org.onosproject.net.optical.device)(version>=1.7.0)(!(version>=2.0.0))) at org.apache.karaf.features.internal.FeaturesServiceImpl.installFeature(FeaturesServiceImpl.java:405) at org.apache.karaf.features.internal.FeaturesServiceImpl.installFeature(FeaturesServiceImpl.java:373) at org.apache.karaf.features.internal.FeaturesServiceImpl.installFeature(FeaturesServiceImpl.java:351) at Proxy2f5c7ac0_3d59_4dae_bba3_203c1f78f885.installFeature(Unknown Source) at org.onosproject.app.impl.ApplicationManager.installAppFeatures(ApplicationManager.java:283) at org.onosproject.app.impl.ApplicationManager.access$200(ApplicationManager.java:66) at org.onosproject.app.impl.ApplicationManager$InternalStoreDelegate.notify(ApplicationManager.java:210) at org.onosproject.app.impl.ApplicationManager$InternalStoreDelegate.notify(ApplicationManager.java:202) at org.onosproject.store.app.DistributedApplicationStore.setupApplicationAndNotify(DistributedApplicationStore.java:484)[78:org.onosproject.onos-core-dist:1.7.0.SNAPSHOT] at org.onosproject.store.app.DistributedApplicationStore.access$500(DistributedApplicationStore.java:94)[78:org.onosproject.onos-core-dist:1.7.0.SNAPSHOT] at org.onosproject.store.app.DistributedApplicationStore$InternalAppsListener.event(DistributedApplicationStore.java:467) at org.onosproject.store.primitives.impl.MeteredAsyncConsistentMap$InternalMeteredMapEventListener.event(MeteredAsyncConsistentMap.java:291) at org.onosproject.store.primitives.impl.TranscodingAsyncConsistentMap$InternalBackingMapEventListener.event(TranscodingAsyncConsistentMap.java:311) at org.onosproject.store.primitives.resources.impl.AtomixConsistentMap.lambda$null$2(AtomixConsistentMap.java:102) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)[:1.8.0_101] at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)[:1.8.0_101] at java.lang.Thread.run(Thread.java:745)[:1.8.0_101]

    JIRA | 9 months ago | Ray Milkey
    java.lang.IllegalStateException: Can't install feature onos-providers-openflow-base/0.0.0: Could not start bundle mvn:org.onosproject/onos-providers-openflow-device/1.7.0-SNAPSHOT in feature(s) onos-providers-openflow-base-1.7.0-SNAPSHOT: Unresolved constraint in bundle org.onosproject.onos-providers-openflow-device [163]: Unable to resolve 163.0: missing requirement [163.0] osgi.wiring.package; (&(osgi.wiring.package=org.onosproject.net.optical.device)(version>=1.7.0)(!(version>=2.0.0)))
  6. 0

    Hello, ONOS: 1.3.0-SNAPSHOT (commit 3f1b128) Test topology: tools/test/topos/opticalTest.py Activated apps: proxyarp, drivers, optical, openflow, calendar (from onos-app-samples.git repo) During the attempt to create HostToHost or P2P intent the following exception appears and intent is not created. The intent is created if I avoid passing both bandwidth and latency constraints in source code (just comment them). If at least one of them is passed to HostToHostIntent or TwoWayP2PIntent exception appears. onos> hosts id=00:00:00:00:00:01/-1, mac=00:00:00:00:00:01, location=of:0000ffffffff0001/1, vlan=-1, ip(s)=[10.0.0.1] id=00:00:00:00:00:02/-1, mac=00:00:00:00:00:02, location=of:0000ffffffff0002/1, vlan=-1, ip(s)=[10.0.0.2] REST request: curl -XPOST "http://192.168.1.101:8181/onos/calendar/intent/0000ffffffff0001/0000ffffffff0002/1/1/10/30" Exception: 2015-07-04 23:17:42,748 | INFO | tp1969339732-463 | BandwidthCalendarResource | 174 - org.onosproject.onos-app-calendar - 1.3.0.SNAPSHOT | Submitted Calendar App intent and waiting: TwoWayP2PIntent{id=0x0, key=0x0, appId=DefaultApplicationId{id=31, name=org.onosproject.calendar}, priority=100, resources=[], selector=DefaultTrafficSelector{criteria=[ETH_TYPE{ethType=ipv4}]}, treatment=DefaultTrafficTreatment{immediate=[], deferred=[], transition=None, cleared=false, metadata=null}, constraints=[BandwidthConstraint{bandwidth=1.0E7}, LatencyConstraint{latency=PT0.00003S}], one=ConnectPoint{elementId=of:0000ffffffff0001, portNumber=1}, two=ConnectPoint{elementId=of:0000ffffffff0002, portNumber=1}} 2015-07-04 23:17:42,906 | WARN | nos-intent-batch | IntentManager | 76 - org.onosproject.onos-core-net - 1.3.0.SNAPSHOT | Future failed: {} java.util.concurrent.ExecutionException: java.lang.NullPointerException at java.util.concurrent.FutureTask.report(FutureTask.java:122)[:1.8.0_45] at java.util.concurrent.FutureTask.get(FutureTask.java:192)[:1.8.0_45] at org.onosproject.net.intent.impl.IntentManager$IntentBatchProcess.waitForFutures(IntentManager.java:364)[76:org.onosproject.onos-core-net:1.3.0.SNAPSHOT] at org.onosproject.net.intent.impl.IntentManager$IntentBatchProcess.run(IntentManager.java:339)[76:org.onosproject.onos-core-net:1.3.0.SNAPSHOT] at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)[:1.8.0_45] at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)[:1.8.0_45] at java.lang.Thread.run(Thread.java:745)[:1.8.0_45] Caused by: java.lang.NullPointerException at sun.misc.FloatingDecimal.readJavaFormatString(FloatingDecimal.java:1838)[:1.8.0_45] at sun.misc.FloatingDecimal.parseDouble(FloatingDecimal.java:110)[:1.8.0_45] at java.lang.Double.parseDouble(Double.java:538)[:1.8.0_45] at org.onosproject.net.AnnotationKeys.getAnnotatedValue(AnnotationKeys.java:104)[74:org.onosproject.onos-api:1.3.0.SNAPSHOT] at org.onosproject.net.intent.constraint.LatencyConstraint.cost(LatencyConstraint.java:59)[74:org.onosproject.onos-api:1.3.0.SNAPSHOT] at org.onosproject.net.intent.impl.compiler.ConnectivityIntentCompiler$ConstraintBasedLinkWeight.weight(ConnectivityIntentCompiler.java:148)[76:org.onosproject.onos-core-net:1.3.0.SNAPSHOT] at org.onosproject.net.intent.impl.compiler.ConnectivityIntentCompiler$ConstraintBasedLinkWeight.weight(ConnectivityIntentCompiler.java:118)[76:org.onosproject.onos-core-net:1.3.0.SNAPSHOT] at org.onlab.graph.AbstractGraphPathSearch$DefaultResult.relaxEdge(AbstractGraphPathSearch.java:194)[72:org.onosproject.onlab-misc:1.3.0.SNAPSHOT] at org.onlab.graph.DijkstraGraphSearch.search(DijkstraGraphSearch.java:63)[72:org.onosproject.onlab-misc:1.3.0.SNAPSHOT] at org.onosproject.store.topology.impl.DefaultTopology.getPaths(DefaultTopology.java:300)[78:org.onosproject.onos-core-dist:1.3.0.SNAPSHOT] at org.onosproject.store.topology.impl.DistributedTopologyStore.getPaths(DistributedTopologyStore.java:122)[78:org.onosproject.onos-core-dist:1.3.0.SNAPSHOT] at org.onosproject.net.topology.impl.TopologyManager.getPaths(TopologyManager.java:176)[76:org.onosproject.onos-core-net:1.3.0.SNAPSHOT] at org.onosproject.net.topology.impl.PathManager.getPaths(PathManager.java:126)[76:org.onosproject.onos-core-net:1.3.0.SNAPSHOT] at org.onosproject.net.intent.impl.compiler.ConnectivityIntentCompiler.getPath(ConnectivityIntentCompiler.java:99)[76:org.onosproject.onos-core-net:1.3.0.SNAPSHOT] at org.onosproject.net.intent.impl.compiler.PointToPointIntentCompiler.compile(PointToPointIntentCompiler.java:74)[76:org.onosproject.onos-core-net:1.3.0.SNAPSHOT] at org.onosproject.net.intent.impl.compiler.PointToPointIntentCompiler.compile(PointToPointIntentCompiler.java:41)[76:org.onosproject.onos-core-net:1.3.0.SNAPSHOT] at org.onosproject.net.intent.impl.CompilerRegistry.compile(CompilerRegistry.java:82)[76:org.onosproject.onos-core-net:1.3.0.SNAPSHOT] at org.onosproject.net.intent.impl.CompilerRegistry.compile(CompilerRegistry.java:83)[76:org.onosproject.onos-core-net:1.3.0.SNAPSHOT] at org.onosproject.net.intent.impl.IntentManager$InternalIntentProcessor.compile(IntentManager.java:394)[76:org.onosproject.onos-core-net:1.3.0.SNAPSHOT] at org.onosproject.net.intent.impl.phase.Compiling.execute(Compiling.java:57)[76:org.onosproject.onos-core-net:1.3.0.SNAPSHOT] at org.onosproject.net.intent.impl.phase.IntentWorker.call(IntentWorker.java:48)[76:org.onosproject.onos-core-net:1.3.0.SNAPSHOT] at org.onosproject.net.intent.impl.phase.IntentWorker.call(IntentWorker.java:27)[76:org.onosproject.onos-core-net:1.3.0.SNAPSHOT] at java.util.concurrent.FutureTask.run(FutureTask.java:266)[:1.8.0_45] ... 3 more Please let me know if any additional info is required from my side. Thanks in advanced.

    JIRA | 2 years ago | Maxim Kondratenko
    java.util.concurrent.ExecutionException: java.lang.NullPointerException

    Not finding the right solution?
    Take a tour to get the most out of Samebug.

    Tired of useless tips?

    Automated exception search integrated into your IDE

    Root Cause Analysis

    1. java.lang.NullPointerException

      No message provided

      at org.onosproject.store.group.impl.DistributedGroupStore.groupMissing()
    2. org.onosproject.store
      DistributedGroupStore.pushGroupMetrics
      1. org.onosproject.store.group.impl.DistributedGroupStore.groupMissing(DistributedGroupStore.java:1418)[79:org.onosproject.onos-core-dist:1.9.0.SNAPSHOT]
      2. org.onosproject.store.group.impl.DistributedGroupStore.pushGroupMetrics(DistributedGroupStore.java:1352)[79:org.onosproject.onos-core-dist:1.9.0.SNAPSHOT]
      2 frames
    3. org.onosproject.net
      GroupManager$InternalGroupProviderService.pushGroupMetrics
      1. org.onosproject.net.group.impl.GroupManager$InternalGroupProviderService.pushGroupMetrics(GroupManager.java:387)[77:org.onosproject.onos-core-net:1.9.0.SNAPSHOT]
      1 frame
    4. org.onosproject.provider
      OpenFlowGroupProvider$InternalGroupProvider.handleMessage
      1. org.onosproject.provider.of.group.impl.OpenFlowGroupProvider.pushGroupMetrics(OpenFlowGroupProvider.java:231)[204:org.onosproject.onos-providers-openflow-group:1.9.0.SNAPSHOT]
      2. org.onosproject.provider.of.group.impl.OpenFlowGroupProvider.access$100(OpenFlowGroupProvider.java:86)[204:org.onosproject.onos-providers-openflow-group:1.9.0.SNAPSHOT]
      3. org.onosproject.provider.of.group.impl.OpenFlowGroupProvider$InternalGroupProvider.handleMessage(OpenFlowGroupProvider.java:328)[204:org.onosproject.onos-providers-openflow-group:1.9.0.SNAPSHOT]
      3 frames
    5. org.onosproject.openflow
      OpenFlowControllerImpl$OFMessageHandler.run
      1. org.onosproject.openflow.controller.impl.OpenFlowControllerImpl$OFMessageHandler.run(OpenFlowControllerImpl.java:758)[200:org.onosproject.onos-protocols-openflow-ctl:1.9.0.SNAPSHOT]
      1 frame
    6. Java RT
      Thread.run
      1. java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)[:1.8.0_101]
      2. java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)[:1.8.0_101]
      3. java.lang.Thread.run(Thread.java:745)[:1.8.0_101]
      3 frames