Skip to content

Instantly share code, notes, and snippets.

@sridhargaddam

sridhargaddam/Karaf Logs during reconciliation

Created August 1, 2017 15:26
Show Gist options
  • Star 0 You must be signed in to star a gist
  • Fork 0 You must be signed in to fork a gist
  • Save sridhargaddam/3761ef080e11f2dd2429c8d7016ae6d0 to your computer and use it in GitHub Desktop.
Save sridhargaddam/3761ef080e11f2dd2429c8d7016ae6d0 to your computer and use it in GitHub Desktop.
Download ZIP
Karaf Logs during reconciliation
Raw
Karaf Logs during reconciliation
2017-08-01 07:47:48,533 | INFO | ofppool-0 | SystemNotificationsListenerImpl | 307 - org.opendaylight.openflowplugin.impl - 0.4.1.Carbon-redhat-1 | Switch Idle state occurred, node=/172.17.1.21:60424|auxId=0
2017-08-01 07:47:50,534 | WARN | ofppool-0 | SystemNotificationsListenerImpl | 307 - org.opendaylight.openflowplugin.impl - 0.4.1.Carbon-redhat-1 | Exception while waiting for echoReply from [/172.17.1.21:60424] in TIMEOUTING state: Timeout waiting for task.
2017-08-01 07:47:50,535 | INFO | ofppool-0 | SystemNotificationsListenerImpl | 307 - org.opendaylight.openflowplugin.impl - 0.4.1.Carbon-redhat-1 | ConnectionEvent:Closing connection as device is idle. Echo sent at Tue Aug 01 07:47:48 EDT 2017. Device:/172.17.1.21:60424, NodeId:openflow:255747680747988
2017-08-01 07:47:50,536 | INFO | ofppool-0 | ContextChainHolderImpl | 307 - org.opendaylight.openflowplugin.impl - 0.4.1.Carbon-redhat-1 | Device openflow:255747680747988 disconnected.
2017-08-01 07:47:50,536 | INFO | ofppool-0 | StatisticsContextImpl | 307 - org.opendaylight.openflowplugin.impl - 0.4.1.Carbon-redhat-1 | Stopping running statistics gathering for node openflow:255747680747988
2017-08-01 07:47:50,536 | INFO | ofppool-0 | StatisticsManagerImpl | 307 - org.opendaylight.openflowplugin.impl - 0.4.1.Carbon-redhat-1 | Statistics gathering for device openflow:255747680747988 was cancelled.
2017-08-01 07:47:50,539 | INFO | ofppool-0 | ContextChainImpl | 307 - org.opendaylight.openflowplugin.impl - 0.4.1.Carbon-redhat-1 | Closed clustering MASTER services for node openflow:255747680747988
2017-08-01 07:47:50,539 | INFO | ofppool-0 | DeviceManagerImpl | 307 - org.opendaylight.openflowplugin.impl - 0.4.1.Carbon-redhat-1 | Publishing node removed notification for Uri [_value=openflow:255747680747988]
2017-08-01 07:47:50,539 | INFO | ofppool-0 | LifecycleServiceImpl | 307 - org.opendaylight.openflowplugin.impl - 0.4.1.Carbon-redhat-1 | Closing clustering services registration for node openflow:255747680747988
2017-08-01 07:47:50,539 | INFO | pool-24-thread-1 | DeviceMastershipManager | 342 - org.opendaylight.openflowplugin.applications.forwardingrules-manager - 0.4.1.Carbon-redhat-1 | Unregistered FRM cluster singleton service for service id : openflow:255747680747988
2017-08-01 07:47:50,541 | INFO | ofppool-0 | StatisticsContextImpl | 307 - org.opendaylight.openflowplugin.impl - 0.4.1.Carbon-redhat-1 | Stopping running statistics gathering for node openflow:255747680747988
2017-08-01 07:47:50,543 | INFO | lt-dispatcher-20 | LifecycleServiceImpl | 307 - org.opendaylight.openflowplugin.impl - 0.4.1.Carbon-redhat-1 | Closing clustering services for node openflow:255747680747988
2017-08-01 07:47:50,544 | INFO | ult-dispatcher-4 | ContextChainHolderImpl | 307 - org.opendaylight.openflowplugin.impl - 0.4.1.Carbon-redhat-1 | Removing device Uri [_value=openflow:255747680747988] from operational DS
2017-08-01 07:47:50,547 | INFO | eChangeHandler-0 | InterfaceStateChangeListener | 360 - org.opendaylight.netvirt.vpnmanager-impl - 0.4.1.Carbon-redhat-1 | VPN Interface update event - intfName tun75cb379b7c8 from InterfaceStateChangeListener
2017-08-01 07:47:50,547 | ERROR | eChangeHandler-0 | SubnetOpDpnManager | 360 - org.opendaylight.netvirt.vpnmanager-impl - 0.4.1.Carbon-redhat-1 | Cannot get, portOp for port 255747680747988:eth2:trunk is not available in datastore
2017-08-01 07:47:50,547 | INFO | eChangeHandler-0 | InterfaceStateChangeListener | 360 - org.opendaylight.netvirt.vpnmanager-impl - 0.4.1.Carbon-redhat-1 | VPN Interface update event - intfName 255747680747988:eth2:trunk from InterfaceStateChangeListener
2017-08-01 07:47:50,553 | INFO | atchingManager-0 | ResourceBatchingManager | 319 - org.opendaylight.genius.mdsalutil-api - 0.2.1.Carbon-redhat-1 | Total taken ##time = 1ms for resourceList of size 1 for resourceType ITM-DEFAULT-OPERATIONAL
2017-08-01 07:47:59,479 | WARN | pool-24-thread-1 | MDSALManager | 348 - org.opendaylight.genius.mdsalutil-impl - 0.2.1.Carbon-redhat-1 | Flow FlowKey [_id=Uri [_value=515001226450915260028883821153350852:54:00:69:C9:70]] does not exist for dpn 22645091526002
2017-08-01 07:47:59,479 | WARN | pool-24-thread-1 | MDSALManager | 348 - org.opendaylight.genius.mdsalutil-impl - 0.2.1.Carbon-redhat-1 | Flow FlowKey [_id=Uri [_value=5150012557476807479888883821153350852:54:00:69:C9:70]] does not exist for dpn 255747680747988
2017-08-01 07:48:04,981 | INFO | ool-146-thread-5 | Ipv6PktHandler | 373 - org.opendaylight.netvirt.ipv6service-impl - 0.4.1.Carbon-redhat-1 | Received Neighbor Solicitation request
2017-08-01 07:48:06,644 | INFO | entLoopGroup-7-4 | ConnectionAdapterImpl | 296 - org.opendaylight.openflowjava.openflow-protocol-impl - 0.9.1.Carbon-redhat-1 | Hello received
2017-08-01 07:48:06,648 | INFO | entLoopGroup-7-4 | ContextChainHolderImpl | 307 - org.opendaylight.openflowplugin.impl - 0.4.1.Carbon-redhat-1 | Device openflow:255747680747988 connected.
2017-08-01 07:48:06,648 | INFO | entLoopGroup-7-4 | DeviceManagerImpl | 307 - org.opendaylight.openflowplugin.impl - 0.4.1.Carbon-redhat-1 | ConnectionEvent: Device connected to controller, Device:/172.17.1.21:45322, NodeId:Uri [_value=openflow:255747680747988]
2017-08-01 07:48:06,648 | INFO | entLoopGroup-7-4 | ContextChainHolderImpl | 307 - org.opendaylight.openflowplugin.impl - 0.4.1.Carbon-redhat-1 | Starting timer for setting SLAVE role on node openflow:255747680747988 if no role will be set in 20s.
2017-08-01 07:48:06,648 | INFO | entLoopGroup-7-4 | LifecycleServiceImpl | 307 - org.opendaylight.openflowplugin.impl - 0.4.1.Carbon-redhat-1 | Registered clustering services for node openflow:255747680747988
2017-08-01 07:48:06,649 | INFO | entLoopGroup-4-1 | LoggingHandler | 145 - io.netty.common - 4.1.8.Final | [id: 0x010012d7, L:/0.0.0.0:6640] RECEIVED: [id: 0x98358f39, L:/172.17.1.19:6640 - R:/172.17.1.21:33286]
2017-08-01 07:48:06,652 | INFO | ofppool-0 | LifecycleServiceImpl | 307 - org.opendaylight.openflowplugin.impl - 0.4.1.Carbon-redhat-1 | Starting clustering services for node openflow:255747680747988
2017-08-01 07:48:06,652 | INFO | ofppool-0 | DeviceContextImpl | 307 - org.opendaylight.openflowplugin.impl - 0.4.1.Carbon-redhat-1 | Starting device context cluster services for node openflow:255747680747988
2017-08-01 07:48:06,653 | INFO | entLoopGroup-7-4 | DeviceInitializationUtil | 307 - org.opendaylight.openflowplugin.impl - 0.4.1.Carbon-redhat-1 | IP address of the node openflow:255747680747988 is: IpAddress [_ipv4Address=Ipv4Address [_value=172.17.1.21]]
2017-08-01 07:48:06,653 | INFO | entLoopGroup-7-4 | DeviceInitializationUtil | 307 - org.opendaylight.openflowplugin.impl - 0.4.1.Carbon-redhat-1 | Port number of the node openflow:255747680747988 is: 45322
2017-08-01 07:48:06,661 | INFO | entLoopGroup-7-4 | OF13DeviceInitializer | 307 - org.opendaylight.openflowplugin.impl - 0.4.1.Carbon-redhat-1 | Static node openflow:255747680747988 info: OFPMPMETERFEATURES collected
2017-08-01 07:48:06,661 | INFO | entLoopGroup-7-4 | OF13DeviceInitializer | 307 - org.opendaylight.openflowplugin.impl - 0.4.1.Carbon-redhat-1 | Static node openflow:255747680747988 info: OFPMPGROUPFEATURES collected
2017-08-01 07:48:06,661 | INFO | entLoopGroup-7-4 | OF13DeviceInitializer | 307 - org.opendaylight.openflowplugin.impl - 0.4.1.Carbon-redhat-1 | Static node openflow:255747680747988 info: OFPMPPORTDESC collected
2017-08-01 07:48:06,662 | INFO | entLoopGroup-7-4 | OF13DeviceInitializer | 307 - org.opendaylight.openflowplugin.impl - 0.4.1.Carbon-redhat-1 | Static node openflow:255747680747988 successfully finished collecting
2017-08-01 07:48:06,662 | INFO | ofppool-0 | SalRoleServiceImpl | 307 - org.opendaylight.openflowplugin.impl - 0.4.1.Carbon-redhat-1 | SetRole called with input:SetRoleInput [_controllerRole=BECOMEMASTER, _node=NodeRef [_value=KeyedInstanceIdentifier{targetType=interface org.opendaylight.yang.gen.v1.urn.opendaylight.inventory.rev130819.nodes.Node, path=[org.opendaylight.yang.gen.v1.urn.opendaylight.inventory.rev130819.Nodes, org.opendaylight.yang.gen.v1.urn.opendaylight.inventory.rev130819.nodes.Node[key=NodeKey [_id=Uri [_value=openflow:255747680747988]]]]}], augmentation=[]]
2017-08-01 07:48:06,662 | INFO | ofppool-0 | SalRoleServiceImpl | 307 - org.opendaylight.openflowplugin.impl - 0.4.1.Carbon-redhat-1 | Requesting state change to BECOMEMASTER
2017-08-01 07:48:06,662 | INFO | ofppool-0 | SalRoleServiceImpl | 307 - org.opendaylight.openflowplugin.impl - 0.4.1.Carbon-redhat-1 | RoleChangeTask called on device:openflow:255747680747988 OFPRole:BECOMEMASTER
2017-08-01 07:48:06,662 | INFO | ofppool-0 | RoleService | 307 - org.opendaylight.openflowplugin.impl - 0.4.1.Carbon-redhat-1 | getGenerationIdFromDevice called for device: openflow:255747680747988
2017-08-01 07:48:06,662 | INFO | entLoopGroup-7-4 | RoleService | 307 - org.opendaylight.openflowplugin.impl - 0.4.1.Carbon-redhat-1 | submitRoleChange called for device:Uri [_value=openflow:255747680747988], role:BECOMEMASTER
2017-08-01 07:48:06,662 | INFO | entLoopGroup-7-4 | RoleService | 307 - org.opendaylight.openflowplugin.impl - 0.4.1.Carbon-redhat-1 | submitRoleChange onSuccess for device:Uri [_value=openflow:255747680747988], role:BECOMEMASTER
2017-08-01 07:48:06,664 | INFO | ofppool-0 | StatisticsContextImpl | 307 - org.opendaylight.openflowplugin.impl - 0.4.1.Carbon-redhat-1 | Starting statistics context cluster services for node openflow:255747680747988
2017-08-01 07:48:06,664 | INFO | ofppool-0 | RpcContextImpl | 307 - org.opendaylight.openflowplugin.impl - 0.4.1.Carbon-redhat-1 | Starting rpc context cluster services for node openflow:255747680747988
2017-08-01 07:48:06,676 | INFO | entLoopGroup-7-4 | ContextChainImpl | 307 - org.opendaylight.openflowplugin.impl - 0.4.1.Carbon-redhat-1 | Device openflow:255747680747988 is able to work as master.
2017-08-01 07:48:06,676 | INFO | entLoopGroup-7-4 | ContextChainHolderImpl | 307 - org.opendaylight.openflowplugin.impl - 0.4.1.Carbon-redhat-1 | Role MASTER was granted to device openflow:255747680747988
2017-08-01 07:48:06,676 | INFO | entLoopGroup-7-4 | DeviceManagerImpl | 307 - org.opendaylight.openflowplugin.impl - 0.4.1.Carbon-redhat-1 | Publishing node added notification for Uri [_value=openflow:255747680747988]
2017-08-01 07:48:06,676 | INFO | entLoopGroup-7-4 | StatisticsManagerImpl | 307 - org.opendaylight.openflowplugin.impl - 0.4.1.Carbon-redhat-1 | Scheduling statistics poll for device: Uri [_value=openflow:255747680747988]
2017-08-01 07:48:06,676 | INFO | pool-24-thread-1 | DeviceMastership | 342 - org.opendaylight.openflowplugin.applications.forwardingrules-manager - 0.4.1.Carbon-redhat-1 | Triggering reconciliation for device openflow:255747680747988
2017-08-01 07:48:06,676 | INFO | pool-24-thread-1 | FlowNodeReconciliationImpl | 342 - org.opendaylight.openflowplugin.applications.forwardingrules-manager - 0.4.1.Carbon-redhat-1 | Triggering reconciliation for device NodeKey [_id=Uri [_value=openflow:255747680747988]]
2017-08-01 07:48:06,677 | INFO | eChangeHandler-0 | AclNodeListener | 370 - org.opendaylight.netvirt.aclservice-impl - 0.4.1.Carbon-redhat-1 | Adding default ACL entries for mode: Stateful
2017-08-01 07:48:06,679 | WARN | pool-45-thread-3 | FlowNodeReconciliationImpl | 342 - org.opendaylight.openflowplugin.applications.forwardingrules-manager - 0.4.1.Carbon-redhat-1 | Installing the group for node connector CONTROLLER
2017-08-01 07:48:06,679 | ERROR | pool-45-thread-3 | FlowNodeReconciliationImpl | 342 - org.opendaylight.openflowplugin.applications.forwardingrules-manager - 0.4.1.Carbon-redhat-1 | Not yet received the node-connector updated for CONTROLLER for the group with id GroupId [_value=5000]
2017-08-01 07:48:06,680 | INFO | eChangeHandler-0 | IdManager | 346 - org.opendaylight.genius.idmanager-impl - 0.2.1.Carbon-redhat-1 | createIdPool called with input CreateIdPoolInput [_high=30000, _low=1000, _poolName=acl.flow.priorities.pool.255747680747988.213.PERMIT, augmentation=[]]
2017-08-01 07:48:06,682 | INFO | nPool-1-worker-1 | OvsInterfaceStateAddHelper | 352 - org.opendaylight.genius.interfacemanager-impl - 0.2.1.Carbon-redhat-1 | Adding Interface State to Oper DS for interface: tun75cb379b7c8
2017-08-01 07:48:06,683 | INFO | eChangeHandler-0 | IdManager | 346 - org.opendaylight.genius.idmanager-impl - 0.2.1.Carbon-redhat-1 | createIdPool called with input CreateIdPoolInput [_high=60000, _low=30001, _poolName=acl.flow.priorities.pool.255747680747988.213.DENY, augmentation=[]]
2017-08-01 07:48:06,683 | INFO | eChangeHandler-0 | IdPoolListener | 346 - org.opendaylight.genius.idmanager-impl - 0.2.1.Carbon-redhat-1 | Received add for name acl.flow.priorities.pool.255747680747988.213.PERMIT : IdPool{getAvailableIdsHolder=AvailableIdsHolder{getCursor=3899, getEnd=30000, getStart=1000, augmentations={}}, getBlockSize=2900, getChildPools=[ChildPools{getChildPoolName=acl.flow.priorities.pool.255747680747988.213.PERMIT.-1062725626, getLastAccessTime=1501588086, augmentations={}}], getPoolName=acl.flow.priorities.pool.255747680747988.213.PERMIT, getReleasedIdsHolder=ReleasedIdsHolder{getAvailableIdCount=0, getDelayedTimeSec=0, augmentations={}}, augmentations={}}
2017-08-01 07:48:06,683 | INFO | nPool-1-worker-1 | IfmUtil | 352 - org.opendaylight.genius.interfacemanager-impl - 0.2.1.Carbon-redhat-1 | Binding Service default.tun75cb379b7c8 for : tun75cb379b7c8
2017-08-01 07:48:06,683 | INFO | atchingManager-0 | ResourceBatchingManager | 319 - org.opendaylight.genius.mdsalutil-api - 0.2.1.Carbon-redhat-1 | Total taken ##time = 0ms for resourceList of size 1 for resourceType INTERFACEMGR-DEFAULT-OPERATIONAL
2017-08-01 07:48:06,685 | INFO | nPool-1-worker-3 | OvsInterfaceStateAddHelper | 352 - org.opendaylight.genius.interfacemanager-impl - 0.2.1.Carbon-redhat-1 | Adding Interface State to Oper DS for interface: 255747680747988:eth2
2017-08-01 07:48:06,686 | INFO | eChangeHandler-0 | InterfaceStateChangeListener | 360 - org.opendaylight.netvirt.vpnmanager-impl - 0.4.1.Carbon-redhat-1 | VPN Interface update event - intfName 255747680747988:eth2 from InterfaceStateChangeListener
2017-08-01 07:48:06,686 | INFO | nPool-1-worker-3 | OvsInterfaceStateAddHelper | 352 - org.opendaylight.genius.interfacemanager-impl - 0.2.1.Carbon-redhat-1 | Adding Interface State to Oper DS for interface: 255747680747988:eth2:trunk
2017-08-01 07:48:06,687 | INFO | nPool-1-worker-3 | IfmUtil | 352 - org.opendaylight.genius.interfacemanager-impl - 0.2.1.Carbon-redhat-1 | Binding Service default.255747680747988:eth2:trunk for : 255747680747988:eth2:trunk
2017-08-01 07:48:06,687 | ERROR | eChangeHandler-0 | SubnetOpDpnManager | 360 - org.opendaylight.netvirt.vpnmanager-impl - 0.4.1.Carbon-redhat-1 | Cannot get, portOp for port 255747680747988:eth2:trunk is not available in datastore
2017-08-01 07:48:06,687 | INFO | eChangeHandler-0 | InterfaceStateChangeListener | 360 - org.opendaylight.netvirt.vpnmanager-impl - 0.4.1.Carbon-redhat-1 | VPN Interface update event - intfName 255747680747988:eth2:trunk from InterfaceStateChangeListener
2017-08-01 07:48:06,691 | INFO | atchingManager-0 | ResourceBatchingManager | 319 - org.opendaylight.genius.mdsalutil-api - 0.2.1.Carbon-redhat-1 | Total taken ##time = 7ms for resourceList of size 1 for resourceType MDSALUTIL-INVENTORY-CONFIG
2017-08-01 07:48:06,691 | INFO | eChangeHandler-0 | IdPoolListener | 346 - org.opendaylight.genius.idmanager-impl - 0.2.1.Carbon-redhat-1 | Received add for name acl.flow.priorities.pool.255747680747988.213.PERMIT.-1062725626 : IdPool{getAvailableIdsHolder=AvailableIdsHolder{getCursor=999, getEnd=3899, getStart=1000, augmentations={}}, getBlockSize=2900, getParentPoolName=acl.flow.priorities.pool.255747680747988.213.PERMIT, getPoolName=acl.flow.priorities.pool.255747680747988.213.PERMIT.-1062725626, getReleasedIdsHolder=ReleasedIdsHolder{getAvailableIdCount=0, getDelayedIdEntries=[], getDelayedTimeSec=30, augmentations={}}, augmentations={}}
2017-08-01 07:48:06,691 | INFO | eChangeHandler-0 | IdPoolListener | 346 - org.opendaylight.genius.idmanager-impl - 0.2.1.Carbon-redhat-1 | Received add for name acl.flow.priorities.pool.255747680747988.213.DENY : IdPool{getAvailableIdsHolder=AvailableIdsHolder{getCursor=32999, getEnd=60000, getStart=30001, augmentations={}}, getBlockSize=2999, getChildPools=[ChildPools{getChildPoolName=acl.flow.priorities.pool.255747680747988.213.DENY.-1062725626, getLastAccessTime=1501588086, augmentations={}}], getPoolName=acl.flow.priorities.pool.255747680747988.213.DENY, getReleasedIdsHolder=ReleasedIdsHolder{getAvailableIdCount=0, getDelayedTimeSec=0, augmentations={}}, augmentations={}}
2017-08-01 07:48:06,691 | INFO | eChangeHandler-0 | IdPoolListener | 346 - org.opendaylight.genius.idmanager-impl - 0.2.1.Carbon-redhat-1 | Received add for name acl.flow.priorities.pool.255747680747988.213.DENY.-1062725626 : IdPool{getAvailableIdsHolder=AvailableIdsHolder{getCursor=30000, getEnd=32999, getStart=30001, augmentations={}}, getBlockSize=2999, getParentPoolName=acl.flow.priorities.pool.255747680747988.213.DENY, getPoolName=acl.flow.priorities.pool.255747680747988.213.DENY.-1062725626, getReleasedIdsHolder=ReleasedIdsHolder{getAvailableIdCount=0, getDelayedIdEntries=[], getDelayedTimeSec=30, augmentations={}}, augmentations={}}
2017-08-01 07:48:06,691 | INFO | eChangeHandler-0 | IdManager | 346 - org.opendaylight.genius.idmanager-impl - 0.2.1.Carbon-redhat-1 | createIdPool called with input CreateIdPoolInput [_high=30000, _low=1000, _poolName=acl.flow.priorities.pool.255747680747988.243.PERMIT, augmentation=[]]
2017-08-01 07:48:06,694 | INFO | eChangeHandler-0 | IdPoolListener | 346 - org.opendaylight.genius.idmanager-impl - 0.2.1.Carbon-redhat-1 | Received add for name acl.flow.priorities.pool.255747680747988.243.PERMIT : IdPool{getAvailableIdsHolder=AvailableIdsHolder{getCursor=3899, getEnd=30000, getStart=1000, augmentations={}}, getBlockSize=2900, getChildPools=[ChildPools{getChildPoolName=acl.flow.priorities.pool.255747680747988.243.PERMIT.-1062725626, getLastAccessTime=1501588086, augmentations={}}], getPoolName=acl.flow.priorities.pool.255747680747988.243.PERMIT, getReleasedIdsHolder=ReleasedIdsHolder{getAvailableIdCount=0, getDelayedTimeSec=0, augmentations={}}, augmentations={}}
2017-08-01 07:48:06,694 | INFO | eChangeHandler-0 | IdManager | 346 - org.opendaylight.genius.idmanager-impl - 0.2.1.Carbon-redhat-1 | createIdPool called with input CreateIdPoolInput [_high=60000, _low=30001, _poolName=acl.flow.priorities.pool.255747680747988.243.DENY, augmentation=[]]
2017-08-01 07:48:06,695 | INFO | eChangeHandler-0 | IdPoolListener | 346 - org.opendaylight.genius.idmanager-impl - 0.2.1.Carbon-redhat-1 | Received add for name acl.flow.priorities.pool.255747680747988.243.DENY : IdPool{getAvailableIdsHolder=AvailableIdsHolder{getCursor=32999, getEnd=60000, getStart=30001, augmentations={}}, getBlockSize=2999, getChildPools=[ChildPools{getChildPoolName=acl.flow.priorities.pool.255747680747988.243.DENY.-1062725626, getLastAccessTime=1501588086, augmentations={}}], getPoolName=acl.flow.priorities.pool.255747680747988.243.DENY, getReleasedIdsHolder=ReleasedIdsHolder{getAvailableIdCount=0, getDelayedTimeSec=0, augmentations={}}, augmentations={}}
2017-08-01 07:48:06,696 | INFO | eChangeHandler-0 | IdPoolListener | 346 - org.opendaylight.genius.idmanager-impl - 0.2.1.Carbon-redhat-1 | Received add for name acl.flow.priorities.pool.255747680747988.243.PERMIT.-1062725626 : IdPool{getAvailableIdsHolder=AvailableIdsHolder{getCursor=999, getEnd=3899, getStart=1000, augmentations={}}, getBlockSize=2900, getParentPoolName=acl.flow.priorities.pool.255747680747988.243.PERMIT, getPoolName=acl.flow.priorities.pool.255747680747988.243.PERMIT.-1062725626, getReleasedIdsHolder=ReleasedIdsHolder{getAvailableIdCount=0, getDelayedIdEntries=[], getDelayedTimeSec=30, augmentations={}}, augmentations={}}
2017-08-01 07:48:06,696 | INFO | eChangeHandler-0 | IdPoolListener | 346 - org.opendaylight.genius.idmanager-impl - 0.2.1.Carbon-redhat-1 | Received add for name acl.flow.priorities.pool.255747680747988.243.DENY.-1062725626 : IdPool{getAvailableIdsHolder=AvailableIdsHolder{getCursor=30000, getEnd=32999, getStart=30001, augmentations={}}, getBlockSize=2999, getParentPoolName=acl.flow.priorities.pool.255747680747988.243.DENY, getPoolName=acl.flow.priorities.pool.255747680747988.243.DENY.-1062725626, getReleasedIdsHolder=ReleasedIdsHolder{getAvailableIdCount=0, getDelayedIdEntries=[], getDelayedTimeSec=30, augmentations={}}, augmentations={}}
2017-08-01 07:48:07,150 | ERROR | pool-45-thread-3 | FlowNodeReconciliationImpl | 342 - org.opendaylight.openflowplugin.applications.forwardingrules-manager - 0.4.1.Carbon-redhat-1 | These Groups are pointing to node-connectors that are not up yet [Group{getBuckets=Buckets{getBucket=[Bucket{getAction=[Action{getAction=OutputActionCase{getOutputAction=OutputAction{getMaxLength=65535, getOutputNodeConnector=Uri [_value=CONTROLLER], augmentations={}}, augmentations={}}, getOrder=0, augmentations={}}], getBucketId=BucketId [_value=0], getWatchGroup=4294967295, getWatchPort=4294967295, getWeight=0, augmentations={}}, Bucket{getAction=[Action{getAction=NxActionResubmitNodesNodeGroupBucketsBucketActionsCase{getNxResubmit=NxResubmit{getInPort=65528, getTable=81, augmentations={}}, augmentations={}}, getOrder=0, augmentations={}}], getBucketId=BucketId [_value=2], getWatchGroup=4294967295, getWatchPort=4294967295, getWeight=0, augmentations={}}, Bucket{getAction=[Action{getAction=NxActionResubmitNodesNodeGroupBucketsBucketActionsCase{getNxResubmit=NxResubmit{getInPort=65528, getTable=17, augmentations={}}, augmentations={}}, getOrder=0, augmentations={}}], getBucketId=BucketId [_value=1], getWatchGroup=4294967295, getWatchPort=4294967295, getWeight=0, augmentations={}}], augmentations={}}, getGroupId=GroupId [_value=5000], getGroupName=Arp_Responder_Group_Flow, getGroupType=GroupAll, augmentations={}}]
2017-08-01 07:48:07,150 | ERROR | pool-45-thread-3 | FlowNodeReconciliationImpl | 342 - org.opendaylight.openflowplugin.applications.forwardingrules-manager - 0.4.1.Carbon-redhat-1 | Installing the group GroupId [_value=5000] finally although the port is not up after checking for 5 times
2017-08-01 07:48:07,184 | INFO | atchingManager-0 | ResourceBatchingManager | 319 - org.opendaylight.genius.mdsalutil-api - 0.2.1.Carbon-redhat-1 | Total taken ##time = 1ms for resourceList of size 5 for resourceType INTERFACEMGR-DEFAULT-OPERATIONAL
2017-08-01 07:48:07,184 | INFO | eChangeHandler-0 | InterfaceStateChangeListener | 360 - org.opendaylight.netvirt.vpnmanager-impl - 0.4.1.Carbon-redhat-1 | VPN Interface update event - intfName tun75cb379b7c8 from InterfaceStateChangeListener
2017-08-01 07:48:07,185 | INFO | atchingManager-0 | ResourceBatchingManager | 319 - org.opendaylight.genius.mdsalutil-api - 0.2.1.Carbon-redhat-1 | Total taken ##time = 1ms for resourceList of size 1 for resourceType ITM-DEFAULT-OPERATIONAL
2017-08-01 07:48:07,654 | ERROR | -Future-Reaper-0 | StalePassiveConnectionService | 285 - org.opendaylight.ovsdb.library - 1.4.1.Carbon-redhat-1 | Error in checking stale connections)
java.util.concurrent.CancellationException: Task was cancelled.
at com.google.common.util.concurrent.AbstractFuture.cancellationExceptionWithCause(AbstractFuture.java:392)[65:com.google.guava:18.0.0]
at com.google.common.util.concurrent.AbstractFuture$Sync.getValue(AbstractFuture.java:306)[65:com.google.guava:18.0.0]
at com.google.common.util.concurrent.AbstractFuture$Sync.get(AbstractFuture.java:286)[65:com.google.guava:18.0.0]
at com.google.common.util.concurrent.AbstractFuture.get(AbstractFuture.java:116)[65:com.google.guava:18.0.0]
at com.google.common.util.concurrent.Uninterruptibles.getUninterruptibly(Uninterruptibles.java:137)[65:com.google.guava:18.0.0]
at com.google.common.util.concurrent.Futures$6.run(Futures.java:1308)[65:com.google.guava:18.0.0]
at com.google.common.util.concurrent.MoreExecutors$DirectExecutor.execute(MoreExecutors.java:457)[65:com.google.guava:18.0.0]
at com.google.common.util.concurrent.ExecutionList.executeListener(ExecutionList.java:156)[65:com.google.guava:18.0.0]
at com.google.common.util.concurrent.ExecutionList.execute(ExecutionList.java:145)[65:com.google.guava:18.0.0]
at com.google.common.util.concurrent.AbstractFuture.cancel(AbstractFuture.java:134)[65:com.google.guava:18.0.0]
at org.opendaylight.ovsdb.lib.jsonrpc.JsonRpcEndpoint.lambda$null$0(JsonRpcEndpoint.java:129)[285:org.opendaylight.ovsdb.library:1.4.1.Carbon-redhat-1]
at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)[:1.8.0_131]
at java.util.concurrent.FutureTask.run(FutureTask.java:266)[:1.8.0_131]
at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$201(ScheduledThreadPoolExecutor.java:180)[:1.8.0_131]
at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:293)[:1.8.0_131]
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)[:1.8.0_131]
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)[:1.8.0_131]
at java.lang.Thread.run(Thread.java:748)[:1.8.0_131]
Caused by: java.util.concurrent.CancellationException: Future.cancel() was called.
at com.google.common.util.concurrent.AbstractFuture$Sync.complete(AbstractFuture.java:378)[65:com.google.guava:18.0.0]
at com.google.common.util.concurrent.AbstractFuture$Sync.cancel(AbstractFuture.java:355)[65:com.google.guava:18.0.0]
at com.google.common.util.concurrent.AbstractFuture.cancel(AbstractFuture.java:131)[65:com.google.guava:18.0.0]
... 8 more
2017-08-01 07:48:08,042 | WARN | ssionScavenger-4 | teInvalidatingHashSessionManager | 229 - org.ops4j.pax.web.pax-web-jetty - 3.2.9 | Timing out for 1 session(s) with id 1tpq70tsnjpp35u3m4a942dpy
2017-08-01 07:48:10,130 | INFO | eChangeHandler-0 | InterfaceStateChangeListener | 360 - org.opendaylight.netvirt.vpnmanager-impl - 0.4.1.Carbon-redhat-1 | VPN Interface update event - intfName tun81e67ac5138 from InterfaceStateChangeListener
2017-08-01 07:48:10,130 | INFO | atchingManager-0 | ResourceBatchingManager | 319 - org.opendaylight.genius.mdsalutil-api - 0.2.1.Carbon-redhat-1 | Total taken ##time = 0ms for resourceList of size 1 for resourceType ITM-DEFAULT-OPERATIONAL
2017-08-01 07:48:16,654 | ERROR | assiveConnServ-9 | StalePassiveConnectionService | 285 - org.opendaylight.ovsdb.library - 1.4.1.Carbon-redhat-1 | Error in checking stale connections)
java.util.concurrent.CancellationException: Task was cancelled.
at com.google.common.util.concurrent.AbstractFuture.cancellationExceptionWithCause(AbstractFuture.java:392)[65:com.google.guava:18.0.0]
at com.google.common.util.concurrent.AbstractFuture$Sync.getValue(AbstractFuture.java:306)[65:com.google.guava:18.0.0]
at com.google.common.util.concurrent.AbstractFuture$Sync.get(AbstractFuture.java:286)[65:com.google.guava:18.0.0]
at com.google.common.util.concurrent.AbstractFuture.get(AbstractFuture.java:116)[65:com.google.guava:18.0.0]
at com.google.common.util.concurrent.Uninterruptibles.getUninterruptibly(Uninterruptibles.java:137)[65:com.google.guava:18.0.0]
at com.google.common.util.concurrent.Futures$6.run(Futures.java:1308)[65:com.google.guava:18.0.0]
at com.google.common.util.concurrent.MoreExecutors$DirectExecutor.execute(MoreExecutors.java:457)[65:com.google.guava:18.0.0]
at com.google.common.util.concurrent.ExecutionList.executeListener(ExecutionList.java:156)[65:com.google.guava:18.0.0]
at com.google.common.util.concurrent.ExecutionList.execute(ExecutionList.java:145)[65:com.google.guava:18.0.0]
at com.google.common.util.concurrent.AbstractFuture.cancel(AbstractFuture.java:134)[65:com.google.guava:18.0.0]
at org.opendaylight.ovsdb.lib.impl.StalePassiveConnectionService.lambda$handleNewPassiveConnection$0(StalePassiveConnectionService.java:67)[285:org.opendaylight.ovsdb.library:1.4.1.Carbon-redhat-1]
at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)[:1.8.0_131]
at java.util.concurrent.FutureTask.run(FutureTask.java:266)[:1.8.0_131]
at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$201(ScheduledThreadPoolExecutor.java:180)[:1.8.0_131]
at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:293)[:1.8.0_131]
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)[:1.8.0_131]
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)[:1.8.0_131]
at java.lang.Thread.run(Thread.java:748)[:1.8.0_131]
Caused by: java.util.concurrent.CancellationException: Future.cancel() was called.
at com.google.common.util.concurrent.AbstractFuture$Sync.complete(AbstractFuture.java:378)[65:com.google.guava:18.0.0]
at com.google.common.util.concurrent.AbstractFuture$Sync.cancel(AbstractFuture.java:355)[65:com.google.guava:18.0.0]
at com.google.common.util.concurrent.AbstractFuture.cancel(AbstractFuture.java:131)[65:com.google.guava:18.0.0]
... 8 more
2017-08-01 07:48:16,659 | INFO | entLoopGroup-5-1 | OvsdbConnectionService | 285 - org.opendaylight.ovsdb.library - 1.4.1.Carbon-redhat-1 | Connection closed ConnectionInfo [Remote-address=172.17.1.21, Remote-port=51192, Local-address172.17.1.19, Local-port=6640, type=PASSIVE]
2017-08-01 07:48:16,659 | INFO | entLoopGroup-5-1 | HwvtepConnectionManager | 293 - org.opendaylight.ovsdb.hwvtepsouthbound-impl - 1.4.1.Carbon-redhat-1 | Library disconnected PASSIVE from /172.17.1.21:51192 to /172.17.1.19:6640. Cleaning up the operational data store
2017-08-01 07:48:16,659 | WARN | entLoopGroup-5-1 | HwvtepConnectionManager | 293 - org.opendaylight.ovsdb.hwvtepsouthbound-impl - 1.4.1.Carbon-redhat-1 | HWVTEP disconnected event did not find connection instance for ConnectionInfo [_localIp=IpAddress [_ipv4Address=Ipv4Address [_value=172.17.1.19]], _localPort=PortNumber [_value=6640], _remoteIp=IpAddress [_ipv4Address=Ipv4Address [_value=172.17.1.21]], _remotePort=PortNumber [_value=51192], augmentation=[]]
2017-08-01 07:48:16,659 | INFO | entLoopGroup-5-1 | OvsdbConnectionManager | 289 - org.opendaylight.ovsdb.southbound-impl - 1.4.1.Carbon-redhat-1 | Library disconnected PASSIVE from /172.17.1.21:51192 to /172.17.1.19:6640. Cleaning up the operational data store
2017-08-01 07:48:16,660 | INFO | lt-dispatcher-49 | OvsdbConnectionManager | 289 - org.opendaylight.ovsdb.southbound-impl - 1.4.1.Carbon-redhat-1 | Entity{type='ovsdb', id=/(urn:TBD:params:xml:ns:yang:network-topology?revision=2013-10-21)network-topology/topology/topology[{(urn:TBD:params:xml:ns:yang:network-topology?revision=2013-10-21)topology-id=ovsdb:1}]/node/node[{(urn:TBD:params:xml:ns:yang:network-topology?revision=2013-10-21)node-id=ovsdb://uuid/e9806896-8dc2-4f17-83ea-c1c957608915}]} has no owner, cleaning up the operational data store
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment