Unable to create PCE Initiated LSPs with IOS-XR


Hariharan Ananthakrishnan
 

Hi,

 

I tried the following ODL releases Nitrogen-SR2, Boron-SR4 & Beryllium-SR4. None of them are able to successfully create a LSP in IOS-XR / 6.2.3. The tunnels are shown as “down” state in the XR.

This is a single-hop tunnel. I can successfully create the tunnel with same parameters from CLI in IOS-XR.

 

Name: tunnel-te1006  Destination: 10.66.4.220  Ifhandle:0x54 (auto-tunnel pcc)

  Signalled-Name: test-hari-8

  Status:

    Admin:    up Oper: down   Path: not valid   Signalling: Down

 

    path option 10, (verbatim) type explicit (autopcc_te1006)

    Last PCALC Error: Tue Mar 13 18:13:19 2018

      Info: Path-option is skipped because it is held down

    G-PID: 0x0800 (derived from egress interface properties)

    Bandwidth Requested: 0 kbps  CT0

    Creation Time: Tue Mar 13 18:01:04 2018 (00:15:28 ago)

  Config Parameters:

    Bandwidth:   100000 kbps (CT0) Priority:  7  7 Affinity: 0x0/0xffff

    Metric Type: TE (global)

    Path Selection:

      Tiebreaker: Min-fill (default)

    Hop-limit: disabled

    Cost-limit: disabled

    Path-invalidation timeout: 10000 msec (default), Action: Tear (default)

    AutoRoute: disabled  LockDown: disabled   Policy class: not set

    Forward class: 0 (default)

    Forwarding-Adjacency: disabled

    Autoroute Destinations: 0

    Loadshare:          0 equal loadshares

    Auto-bw: disabled

    Fast Reroute: Disabled, Protection Desired: None

    Path Protection: Not Enabled

    BFD Fast Detection: Disabled

    Reoptimization after affinity failure: Enabled

    Soft Preemption: Disabled

  Auto PCC:

    Symbolic name: test-hari-8

    PCEP ID: 1007

    Delegated to: 10.64.4.209

    Created by: 10.64.4.209

 

RP/0/RP0/CPU0:PE-240#show running-config mpls traffic-eng pce

Tue Mar 13 18:18:00.995 UTC

mpls traffic-eng

pce

  peer source ipv4 10.66.4.240

  peer ipv4 10.64.4.209

  !

  segment-routing

  stateful-client

   instantiation

   report

   timers redelegation-timeout 0

   timers state-timeout 0

  !

  speaker-entity-id PE-240

!

!

 

show mpls traffic-eng pce trace tailf

 

Mar 13 18:01:04.279 mpls/te/pcestateful 0/RP0/CPU0 t7760 :9130: (PCE  T) Processing Initiate from 10.64.4.209 - LSP: Symbolic name: test-hari-8 PLSP-ID: 0 SRP-ID: 1

Mar 13 18:01:04.279 mpls/te/pcestateful 0/RP0/CPU0 t7760 :4404: (PCE  T) Initiate received: Peer: 10.64.4.209 LSP Symbolic name: test-hari-8 PLSP-ID: 0 Flags: R-0

Mar 13 18:01:04.279 mpls/te/pcectrl 0/RP0/CPU0 t7760 TCP:9144: (PCE  T) Processed Initiate from 10.64.4.209: containing 1 lsps

Mar 13 18:01:04.365 mpls/te/pceperf 0/RP0/CPU0 t7760 :502: REQ: Creation: (Peer 0x0 : request id 191)

Mar 13 18:01:04.365 mpls/te/pcetun 0/RP0/CPU0 t7602 TUN:6276: (Main T) Primary path req: tunnel-te1006, src 10.66.4.240, dest 10.66.4.220, bw 100000

Mar 13 18:01:12.506 mpls/te/pceperf 0/RP0/CPU0 t7760 :517: REQ: Deletion: (Peer 0x0 : request id 183)

Mar 13 18:01:12.506 mpls/te/pceperf 0/RP0/CPU0 t7760 :517: REQ: Deletion: (Peer 0x0 : request id 185)

Mar 13 18:01:12.506 mpls/te/pceperf 0/RP0/CPU0 t7760 :517: REQ: Deletion: (Peer 0x0 : request id 187)

Mar 13 18:01:12.506 mpls/te/pceperf 0/RP0/CPU0 t7760 :517: REQ: Deletion: (Peer 0x0 : request id 189)

Mar 13 18:02:04.282 mpls/te/pceerror 0/RP0/CPU0 t7602 :5488: tunnel-te1006 is not eligible for report

Mar 13 18:02:04.282 mpls/te/pceerror 0/RP0/CPU0 t7760 :5977: pce_lspmgr_error_cb: Timer for processing of LSP 0 (test-hari-8) request (srp-id 1) expired

Mar 13 18:02:04.282 mpls/te/pcectrl 0/RP0/CPU0 t7760 TCP:9695: (PCE  T) Enqueued Error to 10.64.4.209: Type: 22 Value: 3

Mar 13 18:02:04.282 mpls/te/pcectrl 0/RP0/CPU0 7527# t7760 TCP:246: (PCE  T) Closing peer  10.64.4.209

Mar 13 18:02:04.282 mpls/te/pcestateful 0/RP0/CPU0 t7760 :300: (PCE  T) LSP removed: Symbolic name: test-hari-8 PLSP-ID: 0

Mar 13 18:02:04.384 mpls/te/pcectrl 0/RP0/CPU0 t7760 TCP:1286: (PCE  T) Peer (10.64.4.209 Speaker entity ID: Not set) state changed from Up to Idle

Mar 13 18:02:12.512 mpls/te/pceperf 0/RP0/CPU0 t7760 :517: REQ: Deletion: (Peer 0x0 : request id 211)

Mar 13 18:02:12.512 mpls/te/pceperf 0/RP0/CPU0 t7760 :517: REQ: Deletion: (Peer 0x0 : request id 213)

Mar 13 18:02:12.512 mpls/te/pceperf 0/RP0/CPU0 t7760 :517: REQ: Deletion: (Peer 0x0 : request id 215)

Mar 13 18:02:12.512 mpls/te/pceperf 0/RP0/CPU0 t7760 :517: REQ: Deletion: (Peer 0x0 : request id 217)

Mar 13 18:02:14.386 mpls/te/pcectrl 0/RP0/CPU0 t7760 TCP:1286: (PCE  T) Peer (10.64.4.209 Speaker entity ID: Not set) state changed from Idle to TCP Pending

Mar 13 18:02:14.453 mpls/te/pcectrl 0/RP0/CPU0 t7760 TCP:1286: (PCE  T) Peer (10.64.4.209 Speaker entity ID: Not set) state changed from TCP Pending to Keep Wait

Mar 13 18:02:14.453 mpls/te/pcectrl 0/RP0/CPU0 t7760 TCP:523: (PCE  T) Transitioning to PCE_STATE_OPEN_WAIT, peer  10.64.4.209

Mar 13 18:02:14.453 mpls/te/pcectrl 0/RP0/CPU0 t7760 TCP:10157: (PCE  T) Enqueue OPEN msg to 10.64.4.209, ka = 30 dead = 120, priority = 0

Mar 13 18:02:14.453 mpls/te/pcectrl 0/RP0/CPU0 t7760 TCP:3800: (PCE  T) Process OPEN from 10.64.4.209 ka = 30 dead = 120

Mar 13 18:02:14.455 mpls/te/pcectrl 0/RP0/CPU0 t7760 TCP:1286: (PCE  T) Peer (10.64.4.209 Speaker entity ID: Not set) state changed from Keep Wait to Up

Mar 13 18:02:14.455 mpls/te/pcectrl 0/RP0/CPU0 t7760 TCP:12432: (PCE  T) Enqueue Report msg to 10.64.4.209 (size 12)

Mar 13 18:02:14.455 mpls/te/pcestateful 0/RP0/CPU0 t7760 :5392: (PCE  T) Enqueued Report to 10.64.4.209 - LSP: Symbolic name:  PLSP-ID: 0 SRP-ID: 0

Mar 13 18:02:14.455 mpls/te/pcestateful 0/RP0/CPU0 t7760 :5349: (PCE  T) StateSync to 10.64.4.209 - batch of 1 LSPs (size 8)

Mar 13 18:02:14.457 mpls/te/pcectrl 0/RP0/CPU0 t7760 TCP:12432: (PCE  T) Enqueue Report msg to 10.64.4.209 (size 108)

Mar 13 18:02:14.457 mpls/te/pcestateful 0/RP0/CPU0 t7760 :5559: (PCE  T) Enqueued Report to 10.64.4.209 - LSP: Symbolic name: PE-240_t100 PLSP-ID: 101 SRP-ID: 0

Mar 13 18:02:22.514 mpls/te/pcetun 0/RP0/CPU0 t7602 TUN:6276: (Main T) Primary path req: tunnel-te1004, src 10.66.4.240, dest 10.66.4.220, bw 100000

Mar 13 18:02:22.514 mpls/te/pcetun 0/RP0/CPU0 t7602 TUN:6276: (Main T) Primary path req: tunnel-te1002, src 10.66.4.240, dest 10.66.4.230, bw 100000

Mar 13 18:02:22.514 mpls/te/pcetun 0/RP0/CPU0 t7602 TUN:6276: (Main T) Primary path req: tunnel-te1005, src 10.66.4.240, dest 10.66.4.220, bw 100000

Mar 13 18:02:22.514 mpls/te/pcetun 0/RP0/CPU0 t7602 TUN:6276: (Main T) Primary path req: tunnel-te1001, src 10.66.4.240, dest 10.66.4.210, bw 100000

 

 

Karaf.log

2018-03-13 14:02:02,044 | INFO  | Timer-0          | AbstractTopologySessionListener  | 327 - org.opendaylight.bgpcep.pcep-topology-provider - 0.8.2 | Request SrpIdNumber [_value=1] timed-out waiting for response

2018-03-13 14:02:32,050 | WARN  | entLoopGroup-5-2 | tateful07TopologySessionListener | 327 - org.opendaylight.bgpcep.pcep-topology-provider - 0.8.2 | Request ID SrpIdNumber [_value=1] not found in outstanding DB

2018-03-13 14:02:32,141 | ERROR | entLoopGroup-5-2 | PCEPSessionImpl                  | 323 - org.opendaylight.bgpcep.pcep-impl - 0.8.2 | Exception captured for session PCEPSessionImpl{channel=[id: 0x8c639dcc, L:/10.64.4.209:4189 - R:/10.66.4.240:17991], localOpen=Open [_deadTimer=120, _keepalive=30, _sessionId=0, _tlvs=Tlvs [augmentation=[Tlvs1 [_srPceCapability=SrPceCapability [_msd=0, augmentation=[]]], Tlvs1 [_stateful=Stateful [_lspUpdateCapability=true, augmentation=[Stateful1 [_deltaLspSyncCapability=true, _includeDbVersion=true, _triggeredInitialSync=true, _triggeredResync=true], Stateful1 [_initiation=true]]]]]], augmentation=[]], remoteOpen=Open [_deadTimer=120, _keepalive=30, _sessionId=82, _tlvs=Tlvs [augmentation=[Tlvs1 [_srPceCapability=SrPceCapability [_msd=10, augmentation=[]]], Tlvs1 [_stateful=Stateful [_lspUpdateCapability=true, augmentation=[Stateful1 [], Stateful1 [_initiation=true]]]], Tlvs3 [_speakerEntityId=SpeakerEntityId [_speakerEntityIdValue=[80, 69, 45, 50, 52, 48], augmentation=[]]]]], _version=ProtocolVersion [_value=1], _ignore=false, _processingRule=false, augmentation=[]]}, closing session.

io.netty.channel.unix.Errors$NativeIoException: syscall:read(...)() failed: Connection reset by peer

        at io.netty.channel.unix.FileDescriptor.readAddress(...)(Unknown Source)

2018-03-13 14:02:32,143 | INFO  | entLoopGroup-5-2 | PCEPSessionImpl                  | 323 - org.opendaylight.bgpcep.pcep-impl - 0.8.2 | Closing PCEP session with reason UNKNOWN: PCEPSessionImpl{channel=[id: 0x8c639dcc, L:/10.64.4.209:4189 - R:/10.66.4.240:17991], localOpen=Open [_deadTimer=120, _keepalive=30, _sessionId=0, _tlvs=Tlvs [augmentation=[Tlvs1 [_srPceCapability=SrPceCapability [_msd=0, augmentation=[]]], Tlvs1 [_stateful=Stateful [_lspUpdateCapability=true, augmentation=[Stateful1 [_deltaLspSyncCapability=true, _includeDbVersion=true, _triggeredInitialSync=true, _triggeredResync=true], Stateful1 [_initiation=true]]]]]], augmentation=[]], remoteOpen=Open [_deadTimer=120, _keepalive=30, _sessionId=82, _tlvs=Tlvs [augmentation=[Tlvs1 [_srPceCapability=SrPceCapability [_msd=10, augmentation=[]]], Tlvs1 [_stateful=Stateful [_lspUpdateCapability=true, augmentation=[Stateful1 [], Stateful1 [_initiation=true]]]], Tlvs3 [_speakerEntityId=SpeakerEntityId [_speakerEntityIdValue=[80, 69, 45, 50, 52, 48], augmentation=[]]]]], _version=ProtocolVersion [_value=1], _ignore=false, _processingRule=false, augmentation=[]]}

2018-03-13 14:02:32,148 | INFO  | entLoopGroup-5-2 | PCEPSessionImpl                  | 323 - org.opendaylight.bgpcep.pcep-impl - 0.8.2 | Closing PCEP session channel: [id: 0x8c639dcc, L:/10.64.4.209:4189 ! R:/10.66.4.240:17991]

 

RESTConf:

 

curl -X POST -H "Content-Type: application/json"  -d @test-lsp-simple.json http://admin:admin@....209:8181/restconf/operations/network-topology-pcep:add-lsp

 

{"output":{"failure":"no-ack"}}

 

 

test-lsp-simple.json

 

{

            "input": {

                        "arguments": {

                                    "bandwidth": {

                                                "bandwidth": "Sz68IA=="

                                    },

                                    "endpoints-obj": {

                                                "ipv4": {

                                                            "destination-ipv4-address": "10.66.4.220",

                                                            "source-ipv4-address": "10.66.4.240"

                                                }

                                    },

                                    "ero": {

                                                "subobject": [

                                                            {

                                                                        "ip-prefix": {

                                                                                    "ip-prefix": "10.10.33.240/32"

                                                                        },

                                                                        "loose": false

                                                            }

                                                ]

                                    },

                                    "lsp": {

                                                "administrative": true,

                                                "delegate": true

                                    }

                        },

                        "name": "test-hari-8",

                        "network-topology-ref": "/network-topology:network-topology/network-topology:topology[network-topology:topology-id='pcep-topology']",

                        "node": "pcc://10.66.4.240"

            }

}

 

- Hari