[ONOS-1114] All ONOS instances crashed after restarting mininet (NPE at WAIT_SWITCH_DRIVER_SUB_HANDSHAKE) - Jira

XML

Word

Printable

Details

Type: Bug
Status: Closed (View Workflow)
Priority: Blocker
Resolution: Done
Affects Version/s: 1.1.0
Fix Version/s: 1.1.0
Component/s: None
Labels:
- Test

Epic Link:
Southbound
Sprint:
Blackbird Sprint 5 (Feb 16-27)

Description

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}

{httpContext=org.apache.felix.webconsole.internal.servlet.OsgiManagerHttpContext@5be04012}

Attachments

Options
- Sort By Name
- Sort By Date
- Ascending
- Descending
- Thumbnails
- List
- Download All

Attachments

testlogs_ayaka.txt
323 kB
27/Feb/15 2:25 AM

Options
- Issue Only
- Show All Reviews
- Show Open Reviews
- Show All Issues
- Show Open Issues

Gerrit Reviews

#	Subject	Branch	Project	Status	CR	V
2854,3	ONOS-1114 - NPE for EQUAL switch reconnection	master	onos	Status: MERGED	+2	+1

Activity

People

Assignee:: Ayaka Koshibe

Reporter:: hari

Votes:: 0 Vote for this issue

Watchers:: 1 Start watching this issue

Dates

Created:: 18/Feb/15 5:46 PM

Updated:: 27/Feb/15 6:17 PM

Resolved:: 27/Feb/15 6:17 PM