[VTN-72] Karaf stops responding Created: 12/Jun/15  Updated: 19/Oct/17  Resolved: 10/Feb/16

Status: Resolved
Project: vtn
Component/s: VTN Manager
Affects Version/s: unspecified
Fix Version/s: None

Type: Bug
Reporter: Venkatrangan Govindarajan Assignee: Unassigned
Resolution: Cannot Reproduce Votes: 0
Labels: None
Remaining Estimate: Not Specified
Time Spent: Not Specified
Original Estimate: Not Specified
Environment:

Operating System: All
Platform: All

Attachments: Text File karaf.log    
External issue ID: 3707

 Description   

USed the RC0 distribution

Installed on the feature:install odl-vtn-manager-rest

Executed the CSIT script to test OF10 and OF13 once.
This will bring up mininet, create vtn+vbr+vbrif+port mapping and testig ping+ delete vtn (twice once for OF10 and once for OF13)

Suddenly the karaf stopped responding foir nealry 10 minutes and I see continuous logs being written to the file.

The karaf.log is attached.

The repeating message is

otocol-impl - 0.6.0.Lithium-RC0 | Hello received / branch
2015-06-12 11:47:29,710 | ERROR | TN Flow Thread-0 | FlowRemoveContext | 345 - org.opendaylight.vtn.manager.implementation - 0.3.0.Lithium-RC0 | remove-flow: Caught an exception: canceled=false, input=RemoveFlowInput [_cookie=FlowCookie [_value=9175521290813964298], _cookieMask=FlowCookie [_value=18446744073709551615], _flags=FlowModFlags [_cHECKOVERLAP=false, _rESETCOUNTS=false, _nOPKTCOUNTS=false, _nOBYTCOUNTS=false, _sENDFLOWREM=true], _flowTable=FlowTableRef [_value=KeyedInstanceIdentifier

{targetType=interface org.opendaylight.yang.gen.v1.urn.opendaylight.flow.inventory.rev130819.tables.Table, 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:1]]], org.opendaylight.yang.gen.v1.urn.opendaylight.flow.inventory.rev130819.FlowCapableNode, org.opendaylight.yang.gen.v1.urn.opendaylight.flow.inventory.rev130819.tables.Table[key=TableKey [_id=0]]]}

], _hardTimeout=0, _idleTimeout=0, _instructions=Instructions{getInstruction=[Instruction{getInstruction=ApplyActionsCase{getApplyActions=ApplyActions{getAction=[Action{getAction=OutputActionCase{getOutputAction=OutputAction{getMaxLength=65535, getOutputNodeConnector=Uri [_value=openflow:1:2], augmentations={}}, augmentations={}}, getOrder=0, augmentations={}}], augmentations={}}, augmentations={}}, getOrder=0, augmentations={}}], augmentations={}}, _match=Match{getEthernetMatch=EthernetMatch{getEthernetDestination=EthernetDestination{getAddress=MacAddress [_value=4e:f3:c0:d2:ae:0d], augmentations={}}, getEthernetSource=EthernetSource{getAddress=MacAddress [_value=12:9d:0e:62:af:ca], augmentations={}}, augmentations={}}, getInPort=Uri [_value=openflow:1:1], getVlanMatch=VlanMatch{getVlanId=VlanId{getVlanId=VlanId [_value=0], isVlanIdPresent=false, augmentations={}}, augmentations={}}, augmentations={}}, _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:1]]]]}

], _priority=10, _tableId=0, _transactionUri=Uri [_value=remove-flow:7f5600000000000a-1], _barrier=true, _strict=true, augmentation=[]]
java.util.concurrent.ExecutionException: org.opendaylight.controller.md.sal.dom.api.DOMRpcImplementationNotAvailableException: No implementation of RPC AbsoluteSchemaPath

{path=[(urn:opendaylight:flow:service?revision=2013-08-19)remove-flow]}

available
2015-06-12 11:47:30,231 | WARN | entLoopGroup-6-1 | ConnectionConductorImpl | 213 - org.opendaylight.openflowplugin - 0.1.0.Lithium-RC0 | Closing handshake context failed: null
2015-06-12 11:47:30,231 | WARN | entLoopGroup-6-1 | HandshakeManagerImpl | 213 - org.opendaylight.openflowplugin - 0.1.0.Lithium-RC0 | issuing disconnect during handshake [/127.0.0.1:38701]
2015-06-12 11:47:30,232 | INFO | entLoopGroup-6-1 | ConnectionConductorImpl | 213 - org.opendaylight.openflowplugin - 0.1.0.Lithium-RC0 | OF handshake failed, doing cleanup.
2015-06-12 11:47:30,274 | INFO | entLoopGroup-6-2 | ConnectionConductorImpl | 213 - org.opendaylight.openflowplugin - 0.1.0.Lithium-RC0 | OF handshake failed, doing cleanup.
2015-06-12 11:47:30,289 | WARN | entLoopGroup-6-1 | ConnectionConductorImpl | 213 - org.opendaylight.openflowplugin - 0.1.0.Lithium-RC0 | Closing handshake context failed: null

 Comments   
Comment by Venkatrangan Govindarajan [ 12/Jun/15 ]

Attachment karaf.log has been added with description: Karaf.log

Comment by Venkatrangan Govindarajan [ 12/Jun/15 ]

Basically when the same test is executed twice , we find this error occuring
as explaied above, the test does the following

start mininet
create vtn
create vbr
create vbrif (4)
crearte port map
check ping
delete vtn
stop mininet

( I see problems when executing tests repeatedly)

The karaf.log had these execptions
015-06-12 12:17:28,348 | WARN | pc-oper-thread-0 | StatRpcMsgManagerImpl | 218 - org.opendaylight.openflowplugin.applications.statistics-manager - 0.1.0.Lithium-RC0 | Response Registration for Statistics RPC call fail!
org.opendaylight.controller.md.sal.dom.api.DOMRpcImplementationNotAvailableException: No implementation of RPC AbsoluteSchemaPath

{path=[(urn:opendaylight:flow:statistics?revision=2013-08-19)get-aggregate-flow-statistics-from-flow-table-for-all-flows]}

available
at org.opendaylight.controller.md.sal.dom.broker.impl.DOMRpcRoutingTable.invokeRpc(DOMRpcRoutingTable.java:183)[154:org.opendaylight.controller.sal-broker-impl:1.2.0.Lithium-RC0]
at org.opendaylight.controller.md.sal.dom.broker.impl.DOMRpcRouter.invokeRpc(DOMRpcRouter.java:124)[154:org.opendaylight.controller.sal-broker-impl:1.2.0.Lithium-RC0]
at org.opendaylight.controller.md.sal.binding.impl.RpcServiceAdapter.invoke0(RpcServiceAdapter.java:64)[157:org.opendaylight.controller.sal-binding-broker-impl:1.2.0.Lithium-RC0]
at org.opendaylight.controller.md.sal.binding.impl.RpcServiceAdapter.access$000(RpcServiceAdapter.java:42)[157:org.opendaylight.controller.sal-binding-broker-impl:1.2.0.Lithium-RC0]
at org.opendaylight.controller.md.sal.binding.impl.RpcServiceAdapter$RpcInvocationStrategy.invoke(RpcServiceAdapter.java:156)[157:org.opendaylight.controller.sal-binding-broker-impl:1.2.0.Lithium-RC0]
at org.opendaylight.controller.md.sal.binding.impl.RpcServiceAdapter.invoke(RpcServiceAdapter.java:95)[157:org.opendaylight.controller.sal-binding-broker-impl:1.2.0.Lithium-RC0]
at com.sun.proxy.$Proxy102.getAggregateFlowStatisticsFromFlowTableForAllFlows(Unknown Source)[183:org.opendaylight.openflowplugin.model.flow-statistics:0.1.0.Lithium-RC0]
at org.opendaylight.openflowplugin.applications.statistics.manager.impl.StatRpcMsgManagerImpl$7.call(StatRpcMsgManagerImpl.java:361)[218:org.opendaylight.openflowplugin.applications.statistics-manager:0.1.0.Lithium-RC0]
at org.opendaylight.openflowplugin.applications.statistics.manager.impl.StatRpcMsgManagerImpl$7.call(StatRpcMsgManagerImpl.java:349)[218:org.opendaylight.openflowplugin.applications.statistics-manager:0.1.0.Lithium-RC0]
at org.opendaylight.openflowplugin.applications.statistics.manager.impl.StatRpcMsgManagerImpl.run(StatRpcMsgManagerImpl.java:151)[218:org.opendaylight.openflowplugin.applications.statistics-manager:0.1.0.Lithium-RC0]
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145)[:1.7.0_79]
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoo

Comment by Ananthi Palaniswamy [ 10/Feb/16 ]

Issue does not occur in recent builds .
Hence closing the bug

Generated at Wed Feb 07 20:47:56 UTC 2024 using Jira 8.20.10#820010-sha1:ace47f9899e9ee25d7157d59aa17ab06aee30d3d.