Description
Hi, we are an ISP and we are thinking of migration from static RSVP tunnels to Segment Routing with an external controller. Our network is build mostly on Juniper MX devices, we cretead a lab with few routers and with an ODL Neon controller + Pathman-SR. Unfortunatelly we can't deploy any path.
So, what we have:
ODL Neon Controller
Pathman-SR
Juniper vMX devices running Junos 17.2R1.13
All the routers are seen in Pathman-SR, we have following topologies in our ODL controller:
example-linkstate-topology(all the routers/links are seen there)
example-pcep-topology(all the routers are connected to PCEP controller and session is UP, configured with Stateful, Active).
example-netconf-topology(the status is connected with all the routers)
I've attached a file, with the result of "/restconf/operational/network-topology:network-topology/"
When I try to deploy a path, I see the following error:
"Response indicated the error in frontend-backend communication."
This the error in karaf.log:
"2019-04-19T18:56:12,806 | INFO | remote-connector-processing-executor-19 | NetconfDevice | 407 - org.opendaylight.netconf.sal-netconf-connector - 1.9.0 | RemoteDevice{EQUINIX}: Netconf connector initialized successfully
2019-04-19T18:56:13,589 | WARN | epollEventLoopGroup-5-2 | Stateful07TopologySessionListener | 381 - org.opendaylight.bgpcep.pcep-topology-provider - 0.11.0 | Unhandled PCErr message PcerrMessage{_errors=[Errors{_errorObject=ErrorObject{_type=6, _value=10, _ignore=false, _processingRule=false, augmentation=[]}, augmentation=[]}], augmentation=[]}.
2019-04-19T18:56:13,590 | WARN | epollEventLoopGroup-5-2 | AbstractTopologySessionListener | 381 - org.opendaylight.bgpcep.pcep-topology-provider - 0.11.0 | Unhandled message Pcerr{_pcerrMessage=PcerrMessage{_errors=[Errors{_errorObject=ErrorObject{_type=6, _value=10, _ignore=false, _processingRule=false, augmentation=[]}, augmentation=[]}], augmentation=[]}, augmentation=[]} on session PCEPSessionImpl{channel=[id: 0x35c28cba, L:/10.10.1.134:4189 - R:/10.100.100.11:62749], localOpen=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=[]}, remoteOpen=Open{_deadTimer=120, _keepalive=30, _sessionId=49, _tlvs=Tlvs{augmentation=[Tlvs1{_srPceCapability=SrPceCapability{_msd=5, augmentation=[]}}, Tlvs1{_stateful=Stateful{_lspUpdateCapability=true, augmentation=[Stateful1{_initiation=true}, Stateful1{}]}}, Tlvs3{}]}, _version=ProtocolVersion{_value=1}, _ignore=false, _processingRule=false, augmentation=[]}}"
This is the log in /tmp/pathman.log:
"6591 2019-04-19 15:56:13.511 UTC root:initialize INFO: Init 2 done - debug saved
6591 2019-04-19 15:56:13.512 UTC root:rest_interface_parser INFO: Commands Relieved: {u'path': [u'CORE0', u'EQUINIX'], u'option': u'create', u'name': u'CORE0 -> EQUINIX'}
6591 2019-04-19 15:56:13.512 UTC root:get_loop_list INFO: Path: [u'CORE0', u'EQUINIX']
6591 2019-04-19 15:56:13.512 UTC root:get_loop_list INFO: Loop list: [u'10.100.100.20']
6591 2019-04-19 15:56:13.512 UTC root:get_sid_list INFO: Path: [u'CORE0', u'EQUINIX']
6591 2019-04-19 15:56:13.513 UTC root:get_sid_list INFO: SID list: [2020]
6591 2019-04-19 15:56:13.592 UTC root:createSRtunnel INFO: Create SR Tunnel response: {u'output': {u'failure': u'failed', u'error': [{u'error-object': {u'ignore': True, u'processing-rule': True, u'type': 10, u'value': 5}}]}}
6591 2019-04-19 15:56:15.595 UTC root:post INFO: {"response": [{"cause": {"failure": "failed", "error": [{"error-object": {"ignore": true, "processing-rule": true, "type": 10, "value": 5}}]}, "option": "create", "success": false}]}
6591 2019-04-19 15:56:15.665 UTC root:initialize INFO: Init 2 done - debug saved
6591 2019-04-19 15:56:15.665 UTC root:rest_interface_parser INFO: Commands Relieved: {u'option': u'list_all'}
6591 2019-04-19 15:56:15.679 UTC root:get_url INFO: Url get Status: 200
6591 2019-04-19 15:56:15.679 UTC root:listAllLsp INFO: list: [], formatted: []
6591 2019-04-19 15:56:15.680 UTC root:post INFO: {"response": [{"list": [], "option": "list_all", "success": true}]}"
This is the error which I see on Juniper MX in the log file of PCEP controller:
"Apr 19 18:19:10 [4829] pcep_msg_parse: Received PCEP message type: PCCreate, version: 1 length: 80, flags: 0x0
Apr 19 18:19:10 [4829] pcep_obj_trace: SRP object: remove: 0 srp_id: 2 lsp_setup_type: 0
Apr 19 18:19:10 [4829] pcep_tlv_trace: TLV path setup setup: 1
Apr 19 18:19:10 [4829] pcep_obj_trace: LSP object: plsp ID: 0, remove: 0, operational: 0, last_update: 0, delegated: 1 administrative: 1 is_p2mp 0, PCE Created: 0
Apr 19 18:19:10 [4829] pcep_tlv_trace: TLV LSP Symbolic Name: 'EQUINIX -> CORE0'
Apr 19 18:19:10 [4829] pcep_obj_trace: END-POINTS IPv4 object: source: 10.100.100.20, destination: 10.100.100.11
Apr 19 18:19:10 [4829] pcep_obj_trace: ERO object: subobjects: 1
Apr 19 18:19:10 [4829] pcep_msg_parse: PCCreate message received:
Apr 19 18:19:10 [4829] pcep_msg_parse: ...P!........... 20 0C 00 50 21 12 00 14 00 00 00 00 00 00 00 02
Apr 19 18:19:10 [4829] pcep_msg_parse: ................ 00 1C 00 04 00 00 00 01 20 10 00 1C 00 00 00 09
Apr 19 18:19:10 [4829] pcep_msg_parse: ....EQUINIX.->.C 00 11 00 10 45 51 55 49 4E 49 58 20 2D 3E 20 43
Apr 19 18:19:10 [4829] pcep_msg_parse: ORE0.....dd..dd. 4F 52 45 30 04 12 00 0C 0A 64 64 14 0A 64 64 0B
Apr 19 18:19:10 [4829] pcep_msg_parse: .........}...dd. 07 10 00 10 05 0C 10 01 00 7D B0 00 0A 64 64 0B
Apr 19 18:19:10 [4829] pcep_msg_parse:
Apr 19 18:19:10 [4829] process_message: Deadtimer has been touched
Apr 19 18:19:10 [4829] pccd_pcep_onmessage_cb() -->
Apr 19 18:19:10 [4829] pccd_pcep_onmessage_cb(): onmessage objp: 0x886e000 objc: 4 msg_type:12 pce:'odl'
Apr 19 18:19:10 [4829] pccd_core_received_pccreate() -->
Apr 19 18:19:10 [4829] pccd_core_received_pccreate(): Copying LSP name: 'EQUINIX -> CORE0' from TLV
Apr 19 18:19:10 [4829] pccd_core_parse_lsp_instantion() -->
Apr 19 18:19:10 [4829] pccd_core_parse_spring_ero() -->
Apr 19 18:19:10 [4829] pcep_send_message: Sending PCEP message 'Error'
Apr 19 18:19:10 [4829] pcep_obj_trace: SRP object: remove: 48 srp_id: 2 lsp_setup_type: 48
Apr 19 18:19:10 [4829] pcep_obj_trace: ERROR object: type: 10, value: 5
Apr 19 18:19:10 [4829] pcep_obj_trace: TLV Missing Request ID: 808464432
Apr 19 18:19:10 [4829] pccd_core_parse_spring_ero() <--
Apr 19 18:19:10 [4829] pccd_core_parse_lsp_instantion() <--
Apr 19 18:19:10 [4829] pccd_pcep_onerror_cb() -->
Apr 19 18:19:10 [4829] pccd_pcep_onerror_cb(): onerror_cb pcep 0x880b060 ctx 0x882c000 error 0 (No error: 0)
Apr 19 18:19:10 [4829] pccd_pcep_onerror_cb(): Outgoing PCErr msg with TYPE:6 and VALUE:10
Apr 19 18:19:10 [4829] pccd_pcep_onerror_cb() <--
Apr 19 18:19:10 [4829] pcep_send_message: Sending PCEP message 'Error'
Apr 19 18:19:10 [4829] pcep_obj_trace: ERROR object: type: 6, value: 10
"
Can anybody advice what to do to solve the problem ?
Any help would be very appreciated!!!