[#BGPCEP-652] Unable to re-establish PCEP session with peers rejected during module restart

[BGPCEP-652] Unable to re-establish PCEP session with peers rejected during module restart Created: 04/Apr/17 Updated: 03/Mar/19 Resolved: 28/Sep/17
Status:	Resolved
Project:	bgpcep
Component/s:	PCEP
Affects Version/s:	Bugzilla Migration
Fix Version/s:	Bugzilla Migration

Type:

Bug

Reporter:

Kevin Wang

Assignee:

Kevin Wang

Resolution:

Done

Votes:

Labels:

None

Remaining Estimate:

Not Specified

Time Spent:

Not Specified

Original Estimate:

Not Specified

Environment:

Operating System: All
Platform: All

External issue ID:

8156

Description

When the PCEP topology provider is loading the configuration initially, the ServerSessionManager will be restarted. Any new PCEP connection coming in during this period will be rejected by the ServerSessionManager. It appears that these rejected PCEP session are not able to properly recovered after the restart.

What we expect is, the rejected session will be re-established later after the session manager restart is finished.

Comments

Comment by Kevin Wang [ 04/Apr/17 ]

{targetType=interface org.opendaylight.yang.gen.v1.urn.tbd.params.xml.ns.yang.network.topology.rev131021.network.topology.topology.Node, path=[org.opendaylight.yang.gen.v1.urn.tbd.params.xml.ns.yang.network.topology.rev131021.NetworkTopology, org.opendaylight.yang.gen.v1.urn.tbd.params.xml.ns.yang.network.topology.rev131021.network.topology.Topology[key=TopologyKey [_topologyId=Uri [_value=pcep-topology-4]]], org.opendaylight.yang.gen.v1.urn.tbd.params.xml.ns.yang.network.topology.rev131021.network.topology.topology.Node[key=NodeKey [_nodeId=Uri [_value=pcc://10.0.0.125]]]]}

shutdown successfully
2017-04-03 21:18:10,202 | ERROR | oupCloseable-2-6 | ServerSessionManager | 291 - org.opendaylight.bgpcep.pcep-topology-provider - 0.5.2.Beryllium-SR2 | Server Session Manager is closed. Unable to create topology node Uri [_value=pcc://10.0.0.207] with listener org.opendaylight.bgpcep.pcep.topology.provider.Stateful07TopologySessionListener@2ff5140a
2017-04-03 21:18:10,203 | WARN | oupCloseable-2-6 | AbstractTopologySessionListener | 291 - org.opendaylight.bgpcep.pcep-topology-provider - 0.5.2.Beryllium-SR2 | Session PCEPSessionImpl

{channel=[id: 0x767ba2d3, /10.0.0.207:20462 => /10.74.1.8:4189], localOpen=Open [_deadTimer=120, _keepalive=30, _sessionId=0, _tlvs=Tlvs [augmentation=[Tlvs1 [_stateful=Stateful [_lspUpdateCapability=true, augmentation=[Stateful1 [_initiation=true], Stateful1 [_deltaLspSyncCapability=true, _includeDbVersion=true, _triggeredInitialSync=true, _triggeredResync=true]]]]]], augmentation=[]], remoteOpen=Open [_deadTimer=120, _keepalive=30, _sessionId=4, _tlvs=Tlvs [augmentation=[Tlvs3 [], Tlvs1 [_stateful=Stateful [_lspUpdateCapability=true, augmentation=[Stateful1 [_initiation=true], Stateful1 []]]]]], _version=ProtocolVersion [_value=1], _ignore=false, _processingRule=false, augmentation=[]]}

went down unexpectedly
java.lang.RuntimeException: Unable to fetch topology node state for PCEP session with /10.0.0.207
at org.opendaylight.bgpcep.pcep.topology.provider.AbstractTopologySessionListener.onSessionUp(AbstractTopologySessionListener.java:150)[291:org.opendaylight.bgpcep.pcep-topology-provider:0.5.2.Beryllium-SR2]
at org.opendaylight.protocol.pcep.impl.PCEPSessionImpl.sessionUp(PCEPSessionImpl.java:357)[278:org.opendaylight.bgpcep.pcep-impl:0.5.2.Beryllium-SR2]
at org.opendaylight.protocol.pcep.impl.PCEPSessionImpl.handlerAdded(PCEPSessionImpl.java:410)[278:org.opendaylight.bgpcep.pcep-impl:0.5.2.Beryllium-SR2]
at io.netty.channel.DefaultChannelPipeline.callHandlerAdded0(DefaultChannelPipeline.java:487)[101:io.netty.transport:4.0.33.Final]
at io.netty.channel.DefaultChannelPipeline.callHandlerAdded(DefaultChannelPipeline.java:482)[101:io.netty.transport:4.0.33.Final]
at io.netty.channel.DefaultChannelPipeline.replace0(DefaultChannelPipeline.java:455)[101:io.netty.transport:4.0.33.Final]
at io.netty.channel.DefaultChannelPipeline.replace(DefaultChannelPipeline.java:405)[101:io.netty.transport:4.0.33.Final]
at io.netty.channel.DefaultChannelPipeline.replace(DefaultChannelPipeline.java:372)[101:io.netty.transport:4.0.33.Final]
at org.opendaylight.protocol.pcep.impl.AbstractSessionNegotiator.negotiationSuccessful(AbstractSessionNegotiator.java:35)[278:org.opendaylight.bgpcep.pcep-impl:0.5.2.Beryllium-SR2]
at org.opendaylight.protocol.pcep.impl.AbstractPCEPSessionNegotiator.handleMessageKeepAlive(AbstractPCEPSessionNegotiator.java:227)[278:org.opendaylight.bgpcep.pcep-impl:0.5.2.Beryllium-SR2]
at org.opendaylight.protocol.pcep.impl.AbstractPCEPSessionNegotiator.handleMessageKeepWait(AbstractPCEPSessionNegotiator.java:216)[278:org.opendaylight.bgpcep.pcep-impl:0.5.2.Beryllium-SR2]
at org.opendaylight.protocol.pcep.impl.AbstractPCEPSessionNegotiator.handleMessage(AbstractPCEPSessionNegotiator.java:344)[278:org.opendaylight.bgpcep.pcep-impl:0.5.2.Beryllium-SR2]
at org.opendaylight.protocol.pcep.impl.AbstractSessionNegotiator.channelRead(AbstractSessionNegotiator.java:78)[278:org.opendaylight.bgpcep.pcep-impl:0.5.2.Beryllium-SR2]
at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:318)[101:io.netty.transport:4.0.33.Final]
at io.netty.channel.AbstractChannelHandlerContext.fireChannelRead(AbstractChannelHandlerContext.java:304)[101:io.netty.transport:4.0.33.Final]
at io.netty.handler.codec.ByteToMessageDecoder.fireChannelRead(ByteToMessageDecoder.java:276)[102:io.netty.codec:4.0.33.Final]
at io.netty.handler.codec.ByteToMessageDecoder.channelRead(ByteToMessageDecoder.java:263)[102:io.netty.codec:4.0.33.Final]
at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:318)[101:io.netty.transport:4.0.33.Final]
at io.netty.channel.AbstractChannelHandlerContext.fireChannelRead(AbstractChannelHandlerContext.java:304)[101:io.netty.transport:4.0.33.Final]
at io.netty.handler.codec.ByteToMessageDecoder.fireChannelRead(ByteToMessageDecoder.java:276)[102:io.netty.codec:4.0.33.Final]
at io.netty.handler.codec.ByteToMessageDecoder.channelRead(ByteToMessageDecoder.java:263)[102:io.netty.codec:4.0.33.Final]
at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:318)[101:io.netty.transport:4.0.33.Final]
at io.netty.channel.AbstractChannelHandlerContext.fireChannelRead(AbstractChannelHandlerContext.java:304)[101:io.netty.transport:4.0.33.Final]
at io.netty.channel.DefaultChannelPipeline.fireChannelRead(DefaultChannelPipeline.java:846)[101:io.netty.transport:4.0.33.Final]
at io.netty.channel.nio.AbstractNioByteChannel$NioByteUnsafe.read(AbstractNioByteChannel.java:131)[101:io.netty.transport:4.0.33.Final]
at io.netty.channel.nio.NioEventLoop.processSelectedKey(NioEventLoop.java:511)[101:io.netty.transport:4.0.33.Final]
at io.netty.channel.nio.NioEventLoop.processSelectedKeysOptimized(NioEventLoop.java:468)[101:io.netty.transport:4.0.33.Final]
at io.netty.channel.nio.NioEventLoop.processSelectedKeys(NioEventLoop.java:382)[101:io.netty.transport:4.0.33.Final]
at io.netty.channel.nio.NioEventLoop.run(NioEventLoop.java:354)[101:io.netty.transport:4.0.33.Final]
at io.netty.util.concurrent.SingleThreadEventExecutor$2.run(SingleThreadEventExecutor.java:112)[100:io.netty.common:4.0.33.Final]
at io.netty.util.concurrent.DefaultThreadFactory$DefaultRunnableDecorator.run(DefaultThreadFactory.java:137)[100:io.netty.common:4.0.33.Final]
at java.lang.Thread.run(Thread.java:745)[:1.7.0_76]
2017-04-03 21:18:10,209 | ERROR | oupCloseable-2-7 | ServerSessionManager | 291 - org.opendaylight.bgpcep.pcep-topology-provider - 0.5.2.Beryllium-SR2 | Server Session Manager is closed. Unable to create topology node Uri [_value=pcc://10.0.0.132] with listener org.opendaylight.bgpcep.pcep.topology.provider.Stateful07TopologySessionListener@20e8bd45
2017-04-03 21:18:10,209 | WARN | oupCloseable-2-7 | AbstractTopologySessionListener | 291 - org.opendaylight.bgpcep.pcep-topology-provider - 0.5.2.Beryllium-SR2 | Session PCEPSessionImpl

{channel=[id: 0x98b5e5b4, /10.0.0.132:52777 => /10.74.1.8:4189], localOpen=Open [_deadTimer=120, _keepalive=30, _sessionId=0, _tlvs=Tlvs [augmentation=[Tlvs1 [_stateful=Stateful [_lspUpdateCapability=true, augmentation=[Stateful1 [_initiation=true], Stateful1 [_deltaLspSyncCapability=true, _includeDbVersion=true, _triggeredInitialSync=true, _triggeredResync=true]]]]]], augmentation=[]], remoteOpen=Open [_deadTimer=120, _keepalive=30, _sessionId=3, _tlvs=Tlvs [augmentation=[Tlvs3 [], Tlvs1 [_stateful=Stateful [_lspUpdateCapability=true, augmentation=[Stateful1 [_initiation=true], Stateful1 []]]]]], _version=ProtocolVersion [_value=1], _ignore=false, _processingRule=false, augmentation=[]]}

went down unexpectedly
java.lang.RuntimeException: Unable to fetch topology node state for PCEP session with /10.0.0.132
at org.opendaylight.bgpcep.pcep.topology.provider.AbstractTopologySessionListener.onSessionUp(AbstractTopologySessionListener.java:150)[291:org.opendaylight.bgpcep.pcep-topology-provider:0.5.2.Beryllium-SR2]
at org.opendaylight.protocol.pcep.impl.PCEPSessionImpl.sessionUp(PCEPSessionImpl.java:357)[278:org.opendaylight.bgpcep.pcep-impl:0.5.2.Beryllium-SR2]
at org.opendaylight.protocol.pcep.impl.PCEPSessionImpl.handlerAdded(PCEPSessionImpl.java:410)[278:org.opendaylight.bgpcep.pcep-impl:0.5.2.Beryllium-SR2]
at io.netty.channel.DefaultChannelPipeline.callHandlerAdded0(DefaultChannelPipeline.java:487)[101:io.netty.transport:4.0.33.Final]
at io.netty.channel.DefaultChannelPipeline.callHandlerAdded(DefaultChannelPipeline.java:482)[101:io.netty.transport:4.0.33.Final]
at io.netty.channel.DefaultChannelPipeline.replace0(DefaultChannelPipeline.java:455)[101:io.netty.transport:4.0.33.Final]
at io.netty.channel.DefaultChannelPipeline.replace(DefaultChannelPipeline.java:405)[101:io.netty.transport:4.0.33.Final]
at io.netty.channel.DefaultChannelPipeline.replace(DefaultChannelPipeline.java:372)[101:io.netty.transport:4.0.33.Final]
at org.opendaylight.protocol.pcep.impl.AbstractSessionNegotiator.negotiationSuccessful(AbstractSessionNegotiator.java:35)[278:org.opendaylight.bgpcep.pcep-impl:0.5.2.Beryllium-SR2]
at org.opendaylight.protocol.pcep.impl.AbstractPCEPSessionNegotiator.handleMessageKeepAlive(AbstractPCEPSessionNegotiator.java:227)[278:org.opendaylight.bgpcep.pcep-impl:0.5.2.Beryllium-SR2]
at org.opendaylight.protocol.pcep.impl.AbstractPCEPSessionNegotiator.handleMessageKeepWait(AbstractPCEPSessionNegotiator.java:216)[278:org.opendaylight.bgpcep.pcep-impl:0.5.2.Beryllium-SR2]
at org.opendaylight.protocol.pcep.impl.AbstractPCEPSessionNegotiator.handleMessage(AbstractPCEPSessionNegotiator.java:344)[278:org.opendaylight.bgpcep.pcep-impl:0.5.2.Beryllium-SR2]
at org.opendaylight.protocol.pcep.impl.AbstractSessionNegotiator.channelRead(AbstractSessionNegotiator.java:78)[278:org.opendaylight.bgpcep.pcep-impl:0.5.2.Beryllium-SR2]
at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:318)[101:io.netty.transport:4.0.33.Final]
at io.netty.channel.AbstractChannelHandlerContext.fireChannelRead(AbstractChannelHandlerContext.java:304)[101:io.netty.transport:4.0.33.Final]
at io.netty.handler.codec.ByteToMessageDecoder.fireChannelRead(ByteToMessageDecoder.java:276)[102:io.netty.codec:4.0.33.Final]
at io.netty.handler.codec.ByteToMessageDecoder.channelRead(ByteToMessageDecoder.java:263)[102:io.netty.codec:4.0.33.Final]
at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:318)[101:io.netty.transport:4.0.33.Final]
at io.netty.channel.AbstractChannelHandlerContext.fireChannelRead(AbstractChannelHandlerContext.java:304)[101:io.netty.transport:4.0.33.Final]
at io.netty.handler.codec.ByteToMessageDecoder.fireChannelRead(ByteToMessageDecoder.java:276)[102:io.netty.codec:4.0.33.Final]
at io.netty.handler.codec.ByteToMessageDecoder.channelRead(ByteToMessageDecoder.java:263)[102:io.netty.codec:4.0.33.Final]
at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:318)[101:io.netty.transport:4.0.33.Final]
at io.netty.channel.AbstractChannelHandlerContext.fireChannelRead(AbstractChannelHandlerContext.java:304)[101:io.netty.transport:4.0.33.Final]
at io.netty.channel.DefaultChannelPipeline.fireChannelRead(DefaultChannelPipeline.java:846)[101:io.netty.transport:4.0.33.Final]
at io.netty.channel.nio.AbstractNioByteChannel$NioByteUnsafe.read(AbstractNioByteChannel.java:131)[101:io.netty.transport:4.0.33.Final]
at io.netty.channel.nio.NioEventLoop.processSelectedKey(NioEventLoop.java:511)[101:io.netty.transport:4.0.33.Final]
at io.netty.channel.nio.NioEventLoop.processSelectedKeysOptimized(NioEventLoop.java:468)[101:io.netty.transport:4.0.33.Final]
at io.netty.channel.nio.NioEventLoop.processSelectedKeys(NioEventLoop.java:382)[101:io.netty.transport:4.0.33.Final]
at io.netty.channel.nio.NioEventLoop.run(NioEventLoop.java:354)[101:io.netty.transport:4.0.33.Final]
at io.netty.util.concurrent.SingleThreadEventExecutor$2.run(SingleThreadEventExecutor.java:112)[100:io.netty.common:4.0.33.Final]
at io.netty.util.concurrent.DefaultThreadFactory$DefaultRunnableDecorator.run(DefaultThreadFactory.java:137)[100:io.netty.common:4.0.33.Final]
at java.lang.Thread.run(Thread.java:745)[:1.7.0_76]
2017-04-03 21:18:10,285 | WARN | oupCloseable-2-7 | DefaultChannelPipeline | 100 - io.netty.common - 4.0.33.Final | An exceptionCaught() event was fired, and it reached at the tail of the pipeline. It usually means the last handler in the pipeline did not handle the exception.
java.lang.NullPointerException
at org.opendaylight.bgpcep.pcep.topology.provider.AbstractTopologySessionListener.onMessage(AbstractTopologySessionListener.java:291)[291:org.opendaylight.bgpcep.pcep-topology-provider:0.5.2.Beryllium-SR2]
at org.opendaylight.protocol.pcep.impl.PCEPSessionImpl.handleMessage(PCEPSessionImpl.java:339)[278:org.opendaylight.bgpcep.pcep-impl:0.5.2.Beryllium-SR2]
at org.opendaylight.protocol.pcep.impl.PCEPSessionImpl.channelRead0(PCEPSessionImpl.java:405)[278:org.opendaylight.bgpcep.pcep-impl:0.5.2.Beryllium-SR2]
at org.opendaylight.protocol.pcep.impl.PCEPSessionImpl.channelRead0(PCEPSessionImpl.java:56)[278:org.opendaylight.bgpcep.pcep-impl:0.5.2.Beryllium-SR2]
at io.netty.channel.SimpleChannelInboundHandler.channelRead(SimpleChannelInboundHandler.java:105)[101:io.netty.transport:4.0.33.Final]
at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:318)[101:io.netty.transport:4.0.33.Final]
at io.netty.channel.AbstractChannelHandlerContext.fireChannelRead(AbstractChannelHandlerContext.java:304)[101:io.netty.transport:4.0.33.Final]
at io.netty.handler.codec.ByteToMessageDecoder.fireChannelRead(ByteToMessageDecoder.java:276)[102:io.netty.codec:4.0.33.Final]
at io.netty.handler.codec.ByteToMessageDecoder.channelRead(ByteToMessageDecoder.java:263)[102:io.netty.codec:4.0.33.Final]
at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:318)[101:io.netty.transport:4.0.33.Final]
at io.netty.channel.AbstractChannelHandlerContext.fireChannelRead(AbstractChannelHandlerContext.java:304)[101:io.netty.transport:4.0.33.Final]
at io.netty.handler.codec.ByteToMessageDecoder.fireChannelRead(ByteToMessageDecoder.java:276)[102:io.netty.codec:4.0.33.Final]
at io.netty.handler.codec.ByteToMessageDecoder.channelRead(ByteToMessageDecoder.java:263)[102:io.netty.codec:4.0.33.Final]
at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:318)[101:io.netty.transport:4.0.33.Final]
at io.netty.channel.AbstractChannelHandlerContext.fireChannelRead(AbstractChannelHandlerContext.java:304)[101:io.netty.transport:4.0.33.Final]
at io.netty.channel.DefaultChannelPipeline.fireChannelRead(DefaultChannelPipeline.java:846)[101:io.netty.transport:4.0.33.Final]
at io.netty.channel.nio.AbstractNioByteChannel$NioByteUnsafe.read(AbstractNioByteChannel.java:131)[101:io.netty.transport:4.0.33.Final]
at io.netty.channel.nio.NioEventLoop.processSelectedKey(NioEventLoop.java:511)[101:io.netty.transport:4.0.33.Final]
at io.netty.channel.nio.NioEventLoop.processSelectedKeysOptimized(NioEventLoop.java:468)[101:io.netty.transport:4.0.33.Final]
at io.netty.channel.nio.NioEventLoop.processSelectedKeys(NioEventLoop.java:382)[101:io.netty.transport:4.0.33.Final]
at io.netty.channel.nio.NioEventLoop.run(NioEventLoop.java:354)[101:io.netty.transport:4.0.33.Final]
at io.netty.util.concurrent.SingleThreadEventExecutor$2.run(SingleThreadEventExecutor.java:112)[100:io.netty.common:4.0.33.Final]
at io.netty.util.concurrent.DefaultThreadFactory$DefaultRunnableDecorator.run(DefaultThreadFactory.java:137)[100:io.netty.common:4.0.33.Final]
at java.lang.Thread.run(Thread.java:745)[:1.7.0_76]
2017-04-03 21:18:10,320 | INFO | config-pusher | ConfigPusherImpl | 94 - org.opendaylight.controller.config-persister-impl - 0.4.2.Beryllium-SR2 | Successfully pushed configuration snapshot 33-pcep-segment-routing.xml(bvc-bgpcep,bvc-bgpcep)
2017-04-03 21:18:10,320 | INFO | config-pusher | ConfigPusherImpl | 94 - org.opendaylight.controller.config-persister-impl - 0.4.2.Beryllium-SR2 | Pushing configuration snapshot 01-netconf.xml(odl-netconf-client,odl-netconf-client)
2017-04-03 21:18:10,483 | WARN | oupCloseable-2-2 | DefaultChannelPipeline | 100 - io.netty.common - 4.0.33.Final | An exceptionCaught() event was fired, and it reached at the tail of the pipeline. It usually means the last handler in the pipeline did not handle the exception.
java.lang.IllegalStateException: Transaction factory was closed. No further operations allowed.
at com.google.common.base.Preconditions.checkState(Preconditions.java:173)[40:com.google.guava:18.0.0]
at org.opendaylight.controller.cluster.databroker.AbstractDOMTransactionFactory.checkNotClosed(AbstractDOMTransactionFactory.java:105)[141:org.opendaylight.controller.sal-distributed-datastore:1.3.2.Beryllium-SR2]
at org.opendaylight.controller.cluster.databroker.AbstractDOMTransactionFactory.newReadWriteTransaction(AbstractDOMTransactionFactory.java:79)[141:org.opendaylight.controller.sal-distributed-datastore:1.3.2.Beryllium-SR2]
at org.opendaylight.controller.md.sal.dom.broker.impl.PingPongTransactionChain.slowAllocateTransaction(PingPongTransactionChain.java:131)[114:org.opendaylight.controller.sal-broker-impl:1.3.2.Beryllium-SR2]
at org.opendaylight.controller.md.sal.dom.broker.impl.PingPongTransactionChain.allocateTransaction(PingPongTransactionChain.java:148)[114:org.opendaylight.controller.sal-broker-impl:1.3.2.Beryllium-SR2]
at org.opendaylight.controller.md.sal.dom.broker.impl.PingPongTransactionChain.newReadWriteTransaction(PingPongTransactionChain.java:347)[114:org.opendaylight.controller.sal-broker-impl:1.3.2.Beryllium-SR2]
at org.opendaylight.controller.md.sal.dom.broker.impl.PingPongTransactionChain.newWriteOnlyTransaction(PingPongTransactionChain.java:378)[114:org.opendaylight.controller.sal-broker-impl:1.3.2.Beryllium-SR2]
at org.opendaylight.controller.md.sal.binding.impl.BindingDOMTransactionChainAdapter.newWriteOnlyTransaction(BindingDOMTransactionChainAdapter.java:75)[116:org.opendaylight.controller.sal-binding-broker-impl:1.3.2.Beryllium-SR2]
at org.opendaylight.bgpcep.pcep.topology.provider.TopologyNodeState.beginTransaction(TopologyNodeState.java:146)[291:org.opendaylight.bgpcep.pcep-topology-provider:0.5.2.Beryllium-SR2]
at org.opendaylight.bgpcep.pcep.topology.provider.AbstractTopologySessionListener.onMessage(AbstractTopologySessionListener.java:291)[291:org.opendaylight.bgpcep.pcep-topology-provider:0.5.2.Beryllium-SR2]
at org.opendaylight.protocol.pcep.impl.PCEPSessionImpl.handleMessage(PCEPSessionImpl.java:339)[278:org.opendaylight.bgpcep.pcep-impl:0.5.2.Beryllium-SR2]
at org.opendaylight.protocol.pcep.impl.PCEPSessionImpl.channelRead0(PCEPSessionImpl.java:405)[278:org.opendaylight.bgpcep.pcep-impl:0.5.2.Beryllium-SR2]
at org.opendaylight.protocol.pcep.impl.PCEPSessionImpl.channelRead0(PCEPSessionImpl.java:56)[278:org.opendaylight.bgpcep.pcep-impl:0.5.2.Beryllium-SR2]
at io.netty.channel.SimpleChannelInboundHandler.channelRead(SimpleChannelInboundHandler.java:105)[101:io.netty.transport:4.0.33.Final]
at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:318)[101:io.netty.transport:4.0.33.Final]
at io.netty.channel.AbstractChannelHandlerContext.fireChannelRead(AbstractChannelHandlerContext.java:304)[101:io.netty.transport:4.0.33.Final]
at io.netty.handler.codec.ByteToMessageDecoder.fireChannelRead(ByteToMessageDecoder.java:276)[102:io.netty.codec:4.0.33.Final]
at io.netty.handler.codec.ByteToMessageDecoder.channelRead(ByteToMessageDecoder.java:263)[102:io.netty.codec:4.0.33.Final]
at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:318)[101:io.netty.transport:4.0.33.Final]
at io.netty.channel.AbstractChannelHandlerContext.fireChannelRead(AbstractChannelHandlerContext.java:304)[101:io.netty.transport:4.0.33.Final]
at io.netty.handler.codec.ByteToMessageDecoder.fireChannelRead(ByteToMessageDecoder.java:276)[102:io.netty.codec:4.0.33.Final]
at io.netty.handler.codec.ByteToMessageDecoder.channelRead(ByteToMessageDecoder.java:263)[102:io.netty.codec:4.0.33.Final]
at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:318)[101:io.netty.transport:4.0.33.Final]
at io.netty.channel.AbstractChannelHandlerContext.fireChannelRead(AbstractChannelHandlerContext.java:304)[101:io.netty.transport:4.0.33.Final]
at io.netty.channel.DefaultChannelPipeline.fireChannelRead(DefaultChannelPipeline.java:846)[101:io.netty.transport:4.0.33.Final]
at io.netty.channel.nio.AbstractNioByteChannel$NioByteUnsafe.read(AbstractNioByteChannel.java:131)[101:io.netty.transport:4.0.33.Final]
at io.netty.channel.nio.NioEventLoop.processSelectedKey(NioEventLoop.java:511)[101:io.netty.transport:4.0.33.Final]
at io.netty.channel.nio.NioEventLoop.processSelectedKeysOptimized(NioEventLoop.java:468)[101:io.netty.transport:4.0.33.Final]
at io.netty.channel.nio.NioEventLoop.processSelectedKeys(NioEventLoop.java:382)[101:io.netty.transport:4.0.33.Final]
at io.netty.channel.nio.NioEventLoop.run(NioEventLoop.java:354)[101:io.netty.transport:4.0.33.Final]
at io.netty.util.concurrent.SingleThreadEventExecutor$2.run(SingleThreadEventExecutor.java:112)[100:io.netty.common:4.0.33.Final]
at io.netty.util.concurrent.DefaultThreadFactory$DefaultRunnableDecorator.run(DefaultThreadFactory.java:137)[100:io.netty.common:4.0.33.Final]
at java.lang.Thread.run(Thread.java:745)[:1.7.0_76]
2017-04-03 21:18:10,569 | WARN | oupCloseable-2-6 | DefaultChannelPipeline | 100 - io.netty.common - 4.0.33.Final | An exceptionCaught() event was fired, and it reached at the tail of the pipeline. It usually means the last handler in the pipeline did not handle the exception.
java.lang.NullPointerException
at org.opendaylight.bgpcep.pcep.topology.provider.AbstractTopologySessionListener.onMessage(AbstractTopologySessionListener.java:291)[291:org.opendaylight.bgpcep.pcep-topology-provider:0.5.2.Beryllium-SR2]
at org.opendaylight.protocol.pcep.impl.PCEPSessionImpl.handleMessage(PCEPSessionImpl.java:339)[278:org.opendaylight.bgpcep.pcep-impl:0.5.2.Beryllium-SR2]
at org.opendaylight.protocol.pcep.impl.PCEPSessionImpl.channelRead0(PCEPSessionImpl.java:405)[278:org.opendaylight.bgpcep.pcep-impl:0.5.2.Beryllium-SR2]
at org.opendaylight.protocol.pcep.impl.PCEPSessionImpl.channelRead0(PCEPSessionImpl.java:56)[278:org.opendaylight.bgpcep.pcep-impl:0.5.2.Beryllium-SR2]
at io.netty.channel.SimpleChannelInboundHandler.channelRead(SimpleChannelInboundHandler.java:105)[101:io.netty.transport:4.0.33.Final]
at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:318)[101:io.netty.transport:4.0.33.Final]
at io.netty.channel.AbstractChannelHandlerContext.fireChannelRead(AbstractChannelHandlerContext.java:304)[101:io.netty.transport:4.0.33.Final]
at io.netty.handler.codec.ByteToMessageDecoder.fireChannelRead(ByteToMessageDecoder.java:276)[102:io.netty.codec:4.0.33.Final]
at io.netty.handler.codec.ByteToMessageDecoder.channelRead(ByteToMessageDecoder.java:263)[102:io.netty.codec:4.0.33.Final]
at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:318)[101:io.netty.transport:4.0.33.Final]
at io.netty.channel.AbstractChannelHandlerContext.fireChannelRead(AbstractChannelHandlerContext.java:304)[101:io.netty.transport:4.0.33.Final]
at io.netty.handler.codec.ByteToMessageDecoder.fireChannelRead(ByteToMessageDecoder.java:276)[102:io.netty.codec:4.0.33.Final]
at io.netty.handler.codec.ByteToMessageDecoder.callDecode(ByteToMessageDecoder.java:354)[102:io.netty.codec:4.0.33.Final]
at io.netty.handler.codec.ByteToMessageDecoder.channelRead(ByteToMessageDecoder.java:244)[102:io.netty.codec:4.0.33.Final]
at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:318)[101:io.netty.transport:4.0.33.Final]
at io.netty.channel.AbstractChannelHandlerContext.fireChannelRead(AbstractChannelHandlerContext.java:304)[101:io.netty.transport:4.0.33.Final]
at io.netty.channel.DefaultChannelPipeline.fireChannelRead(DefaultChannelPipeline.java:846)[101:io.netty.transport:4.0.33.Final]
at io.netty.channel.nio.AbstractNioByteChannel$NioByteUnsafe.read(AbstractNioByteChannel.java:131)[101:io.netty.transport:4.0.33.Final]
at io.netty.channel.nio.NioEventLoop.processSelectedKey(NioEventLoop.java:511)[101:io.netty.transport:4.0.33.Final]
at io.netty.channel.nio.NioEventLoop.processSelectedKeysOptimized(NioEventLoop.java:468)[101:io.netty.transport:4.0.33.Final]
at io.netty.channel.nio.NioEventLoop.processSelectedKeys(NioEventLoop.java:382)[101:io.netty.transport:4.0.33.Final]
at io.netty.channel.nio.NioEventLoop.run(NioEventLoop.java:354)[101:io.netty.transport:4.0.33.Final]
at io.netty.util.concurrent.SingleThreadEventExecutor$2.run(SingleThreadEventExecutor.java:112)[100:io.netty.common:4.0.33.Final]
at io.netty.util.concurrent.DefaultThreadFactory$DefaultRunnableDecorator.run(DefaultThreadFactory.java:137)[100:io.netty.common:4.0.33.Final]
at java.lang.Thread.run(Thread.java:745)[:1.7.0_76]
2017-04-03 21:18:10,677 | INFO | config-pusher | ConfigPusherImpl | 94 - org.opendaylight.controller.config-persister-impl - 0.4.2.Beryllium-SR2 | Successfully pushed configuration snapshot 01-netconf.xml(odl-netconf-client,odl-netconf-client)
2017-04-03 21:18:10,677 | INFO | config-pusher | ConfigPusherImpl | 94 - org.opendaylight.controller.config-persister-impl - 0.4.2.Beryllium-SR2 | Pushing configuration snapshot 10-rest-connector.xml(odl-restconf,odl-restconf)
2017-04-03 21:18:10,697 | WARN | oupCloseable-2-6 | DefaultChannelPipeline | 100 - io.netty.common - 4.0.33.Final | An exceptionCaught() event was fired, and it reached at the tail of the pipeline. It usually means the last handler in the pipeline did not handle the exception.
java.lang.NullPointerException
at org.opendaylight.bgpcep.pcep.topology.provider.AbstractTopologySessionListener.onMessage(AbstractTopologySessionListener.java:291)[291:org.opendaylight.bgpcep.pcep-topology-provider:0.5.2.Beryllium-SR2]
at org.opendaylight.protocol.pcep.impl.PCEPSessionImpl.handleMessage(PCEPSessionImpl.java:339)[278:org.opendaylight.bgpcep.pcep-impl:0.5.2.Beryllium-SR2]
at org.opendaylight.protocol.pcep.impl.PCEPSessionImpl.channelRead0(PCEPSessionImpl.java:405)[278:org.opendaylight.bgpcep.pcep-impl:0.5.2.Beryllium-SR2]
at org.opendaylight.protocol.pcep.impl.PCEPSessionImpl.channelRead0(PCEPSessionImpl.java:56)[278:org.opendaylight.bgpcep.pcep-impl:0.5.2.Beryllium-SR2]
at io.netty.channel.SimpleChannelInboundHandler.channelRead(SimpleChannelInboundHandler.java:105)[101:io.netty.transport:4.0.33.Final]
at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:318)[101:io.netty.transport:4.0.33.Final]
at io.netty.channel.AbstractChannelHandlerContext.fireChannelRead(AbstractChannelHandlerContext.java:304)[101:io.netty.transport:4.0.33.Final]
at io.netty.handler.codec.ByteToMessageDecoder.fireChannelRead(ByteToMessageDecoder.java:276)[102:io.netty.codec:4.0.33.Final]
at io.netty.handler.codec.ByteToMessageDecoder.channelRead(ByteToMessageDecoder.java:263)[102:io.netty.codec:4.0.33.Final]
at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:318)[101:io.netty.transport:4.0.33.Final]
at io.netty.channel.AbstractChannelHandlerContext.fireChannelRead(AbstractChannelHandlerContext.java:304)[101:io.netty.transport:4.0.33.Final]
at io.netty.handler.codec.ByteToMessageDecoder.fireChannelRead(ByteToMessageDecoder.java:276)[102:io.netty.codec:4.0.33.Final]
at io.netty.handler.codec.ByteToMessageDecoder.channelRead(ByteToMessageDecoder.java:263)[102:io.netty.codec:4.0.33.Final]
at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:318)[101:io.netty.transport:4.0.33.Final]
at io.netty.channel.AbstractChannelHandlerContext.fireChannelRead(AbstractChannelHandlerContext.java:304)[101:io.netty.transport:4.0.33.Final]
at io.netty.channel.DefaultChannelPipeline.fireChannelRead(DefaultChannelPipeline.java:846)[101:io.netty.transport:4.0.33.Final]
at io.netty.channel.nio.AbstractNioByteChannel$NioByteUnsafe.read(AbstractNioByteChannel.java:131)[101:io.netty.transport:4.0.33.Final]
at io.netty.channel.nio.NioEventLoop.processSelectedKey(NioEventLoop.java:511)[101:io.netty.transport:4.0.33.Final]
at io.netty.channel.nio.NioEventLoop.processSelectedKeysOptimized(NioEventLoop.java:468)[101:io.netty.transport:4.0.33.Final]
at io.netty.channel.nio.NioEventLoop.processSelectedKeys(NioEventLoop.java:382)[101:io.netty.transport:4.0.33.Final]
at io.netty.channel.nio.NioEventLoop.run(NioEventLoop.java:354)[101:io.netty.transport:4.0.33.Final]
at io.netty.util.concurrent.SingleThreadEventExecutor$2.run(SingleThreadEventExecutor.java:112)[100:io.netty.common:4.0.33.Final]
at io.netty.util.concurrent.DefaultThreadFactory$DefaultRunnableDecorator.run(DefaultThreadFactory.java:137)[100:io.netty.common:4.0.33.Final]
at java.lang.Thread.run(Thread.java:745)[:1.7.0_76]
2017-04-03 21:18:10,778 | INFO | oupCloseable-2-8 | PCEPSessionNegotiator | 278 - org.opendaylight.bgpcep.pcep-impl - 0.5.2.Beryllium-SR2 | Replacing bootstrap negotiator for channel [id: 0x1f8fb4d8, /10.0.0.131:47124 => /10.74.1.8:4189]
2017-04-03 21:18:10,784 | INFO | oupCloseable-2-8 | AbstractPCEPSessionNegotiator | 278 - org.opendaylight.bgpcep.pcep-impl - 0.5.2.Beryllium-SR2 | PCEP session with [id: 0x1f8fb4d8, /10.0.0.131:47124 => /10.74.1.8:4189] started, sent proposal Open [_deadTimer=120, _keepalive=30, _sessionId=0, _tlvs=Tlvs [augmentation=[Tlvs1 [_srPceCapability=SrPceCapability [_msd=0, augmentation=[]]], Tlvs1 [_stateful=Stateful [_lspUpdateCapability=true, augmentation=[Stateful1 [_initiation=true], Stateful1 [_deltaLspSyncCapability=true, _includeDbVersion=true, _triggeredInitialSync=true, _triggeredResync=true]]]]]], augmentation=[]]

Comment by Claudio David Gasparini [ 17/May/17 ]

Hi Kevin, could you try to replicate this bug on boron/Carbon, and push the logs, steps to reproduced.

Thanks

Comment by Claudio David Gasparini [ 17/May/17 ]

Boron SR4

Comment by Claudio David Gasparini [ 01/Jun/17 ]

https://git.opendaylight.org/gerrit/#/q/topic:BUG-8156

Comment by Claudio David Gasparini [ 30/Jun/17 ]

reverting fixes patch since it introduces a bug, pcep releng test fails bgpcep-csit-1node-periodic-throughpcep-only-carbon.

So please push correct patch, well tested, and link to sandbox passing the changes.

Comment by Kevin Wang [ 30/Jun/17 ]

Hi Claudio,

How do I run that test? It seems I do not have enough permission to start a new build.

Comment by Claudio David Gasparini [ 30/Jun/17 ]

you need to use sandbox, http://docs.opendaylight.org/en/stable-carbon/submodules/releng/builder/docs/jenkins.html

regards

Comment by Kevin Wang [ 14/Jul/17 ]

Hi Claudio,

It seems there is something wrong with the job. The job failed due to timeout after 6hrs. I tried it twice. See
1. https://jenkins.opendaylight.org/releng/job/bgpcep-patch-test-pcep-carbon/1/console
2. https://jenkins.opendaylight.org/releng/job/bgpcep-patch-test-pcep-carbon/2/console

However, the tests started by both jobs actually passed. See
1. https://jenkins.opendaylight.org/releng/job/bgpcep-csit-1node-userfeatures-only-carbon/740/
2. https://jenkins.opendaylight.org/releng/job/bgpcep-csit-1node-userfeatures-only-carbon/743/
3. https://jenkins.opendaylight.org/releng/view/bgpcep/job/bgpcep-csit-1node-periodic-throughpcep-only-carbon/340/
4. https://jenkins.opendaylight.org/releng/view/bgpcep/job/bgpcep-csit-1node-periodic-throughpcep-only-carbon/343/

The job was running slow and timed out because it took a extreme long time to assign a VM to run the test.

Meanwhile, I started a new commit here https://git.opendaylight.org/gerrit/#/c/60401/ , which is based on Carbon SR1. Since my change was the last change pushed to stable/carbon, I think there is actually no difference between these two. I just started it to be sure. Feel free to abandon it if you feel like it's not necessary.

Comment by Claudio David Gasparini [ 17/Jul/17 ]

(In reply to Kevin Wang from comment #8)
> Hi Claudio,
>
> It seems there is something wrong with the job. The job failed due to
> timeout after 6hrs. I tried it twice. See
> 1.
> https://jenkins.opendaylight.org/releng/job/bgpcep-patch-test-pcep-carbon/1/
> console
> 2.
> https://jenkins.opendaylight.org/releng/job/bgpcep-patch-test-pcep-carbon/2/
> console
>
> However, the tests started by both jobs actually passed. See
> 1.
> https://jenkins.opendaylight.org/releng/job/bgpcep-csit-1node-userfeatures-
> only-carbon/740/
> 2.
> https://jenkins.opendaylight.org/releng/job/bgpcep-csit-1node-userfeatures-
> only-carbon/743/
> 3.
> https://jenkins.opendaylight.org/releng/view/bgpcep/job/bgpcep-csit-1node-
> periodic-throughpcep-only-carbon/340/
> 4.
> https://jenkins.opendaylight.org/releng/view/bgpcep/job/bgpcep-csit-1node-
> periodic-throughpcep-only-carbon/343/
>
> The job was running slow and timed out because it took a extreme long time
> to assign a VM to run the test.
>
> Meanwhile, I started a new commit here
> https://git.opendaylight.org/gerrit/#/c/60401/ , which is based on Carbon
> SR1. Since my change was the last change pushed to stable/carbon, I think
> there is actually no difference between these two. I just started it to be
> sure. Feel free to abandon it if you feel like it's not necessary.

Jobs are ok, test are no passing because of the changes. You are doing changes under pcep, wheter 1 passes is not related with rour changes. 1 is poiting to
https://jenkins.opendaylight.org/releng/job/bgpcep-csit-1node-periodic-throughpcep-only-carbon/346/. So not sure why you mentioned 3 and 4.

You can see the error here
https://logs.opendaylight.org/releng/jenkins092/bgpcep-csit-1node-periodic-throughpcep-only-carbon/346/karaf_1_threads_after.log.gz

Regards

Comment by Kevin Wang [ 20/Jul/17 ]

Hi Claudio,

The patch for is ready. Please review and merge ASAP. We want the Carbon and Boron patch first.

The carbon patch I have tested twice, both passed(see the last few comment for test result): https://git.opendaylight.org/gerrit/#/c/60401/
The boron patch is having one unrelated BGP failure currently. I have just started a new test for it, but I believe it should pass. https://git.opendaylight.org/gerrit/#/c/60576/

Thanks

Generated at Wed Feb 07 19:13:43 UTC 2024 using Jira 8.20.10#820010-sha1:ace47f9899e9ee25d7157d59aa17ab06aee30d3d.

[BGPCEP-652] Unable to re-establish PCEP session with peers rejected during module restart Created: 04/Apr/17 Updated: 03/Mar/19 Resolved: 28/Sep/17