Uploaded image for project: 'bgpcep'
  1. bgpcep
  2. BGPCEP-1008

ODL Sulfur : PCEP Session terminates when a PCReq is received . Log shows "Compute path from Source Null"

XMLWordPrintable

    • Icon: Bug Bug
    • Resolution: Unresolved
    • Icon: Highest Highest
    • None
    • 0.17.2
    • None

      Description

      Initially , the BGPLS topology ( bgp-example rib ) and the Graph topology ( mapped using example-link-state-topology) is correctly populated ( GET response of linkstate routes and Graph topology attached) .  The PCEP session between this PCE and our PCC is also successfully established ( GET response of pcep-topology attached ). 

      When i send a first PCRequest ( either for RSVP TE tunnel or Segment Routing MPLS tunnel) , the PCEP session is terminated by the PCE . 

      In the log file, i see the message "Compute path from Source Null" after which an exception is raised. 

      After this, the PCEP sessions keeps flapping.

      Log: 

      13:17:05.079 DEBUG [epollEventLoopGroup-11-2] Message was received: Pcreq{pcreqMessage=PcreqMessage{requests=[Requests{rp=Rp{biDirectional=false, eroCompression=false, fragmentation=false, ignore=false, loose=true, makeBeforeBreak=false, order=false, p2mp=false, pathKey=false, processingRule=true, reoptimization=false, requestId=RequestId{_value=2}, supplyOf=false, tlvs=Tlvs{pathSetupType=PathSetupType{pst=1}}}, segmentComputation=SegmentComputation{p2p=P2p{endpointsObj=EndpointsObj{addressFamily=Ipv4Case{ipv4=Ipv4{destinationIpv4Address=Ipv4Address{_value=5.5.5.5}, sourceIpv4Address=Ipv4Address{_value=1.1.1.1}}}, ignore=false, processingRule=true}}}}]}} from /1.1.1.1:58811
      13:17:05.079 DEBUG [epollEventLoopGroup-11-2] PCEP message Pcreq{pcreqMessage=PcreqMessage{requests=[Requests{rp=Rp{biDirectional=false, eroCompression=false, fragmentation=false, ignore=false, loose=true, makeBeforeBreak=false, order=false, p2mp=false, pathKey=false, processingRule=true, reoptimization=false, requestId=RequestId{_value=2}, supplyOf=false, tlvs=Tlvs{pathSetupType=PathSetupType{pst=1}}}, segmentComputation=SegmentComputation{p2p=P2p{endpointsObj=EndpointsObj{addressFamily=Ipv4Case{ipv4=Ipv4{destinationIpv4Address=Ipv4Address{_value=5.5.5.5}, sourceIpv4Address=Ipv4Address{_value=1.1.1.1}}}, ignore=false, processingRule=true}}}}]}} received.
      13:17:05.080 INFO [epollEventLoopGroup-11-2] PcReq detected. Start Request Message handler
      13:17:05.080 INFO [epollEventLoopGroup-11-2] Start PcRequest Message handler
      13:17:05.080 DEBUG [epollEventLoopGroup-11-2] Process request Requests{rp=Rp{biDirectional=false, eroCompression=false, fragmentation=false, ignore=false, loose=true, makeBeforeBreak=false, order=false, p2mp=false, pathKey=false, processingRule=true, reoptimization=false, requestId=RequestId{_value=2}, supplyOf=false, tlvs=Tlvs{pathSetupType=PathSetupType{pst=1}}}, segmentComputation=SegmentComputation{p2p=P2p{endpointsObj=EndpointsObj{addressFamily=Ipv4Case{ipv4=Ipv4{destinationIpv4Address=Ipv4Address{_value=5.5.5.5}, sourceIpv4Address=Ipv4Address{_value=1.1.1.1}}}, ignore=false, processingRule=true}}}}
      13:17:05.080 INFO [epollEventLoopGroup-11-2] Received Compute Path request
      13:17:05.080 DEBUG [epollEventLoopGroup-11-2] Request for path computation Requests{rp=Rp{biDirectional=false, eroCompression=false, fragmentation=false, ignore=false, loose=true, makeBeforeBreak=false, order=false, p2mp=false, pathKey=false, processingRule=true, reoptimization=false, requestId=RequestId{_value=2}, supplyOf=false, tlvs=Tlvs{pathSetupType=PathSetupType{pst=1}}}, segmentComputation=SegmentComputation{p2p=P2p{endpointsObj=EndpointsObj{addressFamily=Ipv4Case{ipv4=Ipv4{destinationIpv4Address=Ipv4Address{_value=5.5.5.5}, sourceIpv4Address=Ipv4Address{_value=1.1.1.1}}}, ignore=false, processingRule=true}}}}
      13:17:05.080 DEBUG [epollEventLoopGroup-11-2] Compute path from Source Null
      13:17:05.080 ERROR [epollEventLoopGroup-11-2] Exception captured for session PCEPSessionImpl{channel=[id: 0xa2925209, L:/201.1.1.252:4189 - R:/1.1.1.1:58811], localOpen=Open{deadTimer=120, keepalive=30, sessionId=1, tlvs=Tlvs{augmentation=[Tlvs1{srPceCapability=SrPceCapability{msd=0, nFlag=false, xFlag=false}}, Tlvs1{stateful=Stateful{lspUpdateCapability=true, augmentation=[Stateful1{deltaLspSyncCapability=true, includeDbVersion=true, triggeredInitialSync=true, triggeredResync=true}, Stateful1{initiation=true}]}}]}}, remoteOpen=Open{deadTimer=120, ignore=false, keepalive=30, processingRule=false, sessionId=10, tlvs=Tlvs{augmentation=[Tlvs1{srPceCapability=SrPceCapability{msd=6, nFlag=false, xFlag=false}}, Tlvs3{}, Tlvs1{stateful=Stateful{lspUpdateCapability=true, augmentation=[Stateful1{}, Stateful1{initiation=true}]}}]}, version=ProtocolVersion{_value=1}}}, closing session.
      java.lang.NullPointerException: null<snip>13:17:05.082 INFO [epollEventLoopGroup-11-2] Closing PCEP session with reason UNKNOWN: PCEPSessionImpl{channel=[id: 0xa2925209, L:/201.1.1.252:4189 - R:/1.1.1.1:58811], localOpen=Open{deadTimer=120, keepalive=30, sessionId=1, tlvs=Tlvs{augmentation=[Tlvs1{srPceCapability=SrPceCapability{msd=0, nFlag=false, xFlag=false}}, Tlvs1{stateful=Stateful{lspUpdateCapability=true, augmentation=[Stateful1{deltaLspSyncCapability=true, includeDbVersion=true, triggeredInitialSync=true, triggeredResync=true}, Stateful1{initiation=true}]}}]}}, remoteOpen=Open{deadTimer=120, ignore=false, keepalive=30, processingRule=false, sessionId=10, tlvs=Tlvs{augmentation=[Tlvs1{srPceCapability=SrPceCapability{msd=6, nFlag=false, xFlag=false}}, Tlvs3null, Tlvs1{stateful=Stateful{lspUpdateCapability=true, augmentation=[Stateful1null, Stateful1{initiation=true}]}}]}, version=ProtocolVersion{_value=1}}}
      13:17:05.083 DEBUG [epollEventLoopGroup-11-2] PCEP Message enqueued: Close{cCloseMessage=CCloseMessage{cClose=CClose{reason=1}}}
      13:17:05.083 INFO [epollEventLoopGroup-11-2] Closing PCEP session channel: [id: 0xa2925209, L:/201.1.1.252:4189 - R:/1.1.1.1:58811]
      13:17:05.083 INFO [epollEventLoopGroup-11-2] Session PCEPSessionImpl{channel=[id: 0xa2925209, L:/201.1.1.252:4189 ! R:/1.1.1.1:58811], localOpen=Open{deadTimer=120, keepalive=30, sessionId=1, tlvs=Tlvs{augmentation=[Tlvs1{srPceCapability=SrPceCapability{msd=0, nFlag=false, xFlag=false}}, Tlvs1{stateful=Stateful{lspUpdateCapability=true, augmentation=[Stateful1{deltaLspSyncCapability=true, includeDbVersion=true, triggeredInitialSync=true, triggeredResync=true}, Stateful1{initiation=true}]}}]}}, remoteOpen=Open{deadTimer=120, ignore=false, keepalive=30, processingRule=false, sessionId=10, tlvs=Tlvs{augmentation=[Tlvs1{srPceCapability=SrPceCapability{msd=6, nFlag=false, xFlag=false}}, Tlvs3null, Tlvs1{stateful=Stateful{lspUpdateCapability=true, augmentation=[Stateful1null, Stateful1{initiation=true}]}}]}, version=ProtocolVersion{_value=1}}} terminated by peer with reason PCEPCloseTermination{reason=UNKNOWN}
      13:17:05.083 DEBUG [epollEventLoopGroup-11-2] Node KeyedInstanceIdentifier{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, o 

      Full log file attached ( please see the last part of the log file ) . 

      All the graph and linkstate routes responses are attached . Also, pcep-topology response is attached too. 

       

       

            Unassigned Unassigned
            raiaseem Aseem Rai
            Votes:
            0 Vote for this issue
            Watchers:
            2 Start watching this issue

              Created:
              Updated: