[c-nsp] Multilink problems

Wed Dec 14 02:18:36 EST 2005

James,

your debug (which is a bit hard to read) shows that your peer (the
DSLAM?) is not configured to use Multilink. It neither sends MRRU, nor
does it ACK your router's MRRU.

	oli

james edwards <> wrote on Tuesday, December 13, 2005 11:41 PM:

> I'm turning up a mlppp link to Payadyne IP DSLAM at very remote field
> connection point, starting with one T-1, but I have configed mlppp so
> it will be hitless to add another T-1. The T-1 controller 2/0 and 
> Serial2/0:0 are up but Multilink1 will not come up. T-1 passes BERT
> 0's, 1's, and quasi tests, from the router's PA-8T1 card to the NIU.
> I suspect the problem is on the DSLAM config; could y'all take a look
> at the config and debug and give me so ideas ?
> Paradyne suggested the MTU 1600.
> 
> 
> 
> !
> interface Multilink1
>  mtu 1600
>  no ip address
>  no cdp enable
>  ppp bridge ip
>  ppp multilink
>  ppp multilink links minimum 1
>  no ppp multilink fragmentation
>  multilink-group 1
>  bridge-group 10
> !
> !
> interface Serial2/0:0
>  mtu 1524
>  no ip address
>  encapsulation ppp
>  tx-ring-limit 16384
>  no fair-queue
>  no cdp enable
>  ppp multilink
>  multilink-group 1
> !
> !
> interface BVI10
>  mtu 1600
>  ip address 10.0.0.1 255.255.255.0
>  ip helper-address 10.0.1.1
> !
> bridge 10 protocol ieee
> bridge 10 route ip
> !
> 
> Debug of ppp/multilink events & errors:
> 
> Dec 13 14:58:42 65.19.28.1 609: Dec 13 14:58:42.266 MST: Se2/0:0 LCP:
> I CONFREQ [Open] id 31 len 18
> Dec 13 14:58:42 65.19.28.1 610: Dec 13 14:58:42.266 MST: Se2/0:0 LCP:
> ACFC (0x0802)
> Dec 13 14:58:42 65.19.28.1 611: Dec 13 14:58:42.266 MST: Se2/0:0 LCP:
> PFC (0x0702)
> Dec 13 14:58:42 65.19.28.1 612: Dec 13 14:58:42.266 MST: Se2/0:0 LCP:
> MRU 1524 (0x010405F4)
> Dec 13 14:58:42 65.19.28.1 613: Dec 13 14:58:42.266 MST: Se2/0:0 LCP:
> MagicNumber 0x745CDE00 (0x0506745CDE00)
> Dec 13 14:58:42 65.19.28.1 614: Dec 13 14:58:42.266 MST: Se2/0:0
> BNCP: State is Closed
> Dec 13 14:58:42 65.19.28.1 615: Dec 13 14:58:42.266 MST: Se2/0:0
> BACP: State is Closed
> Dec 13 14:58:42 65.19.28.1 616: Dec 13 14:58:42.266 MST: Se2/0:0 MLP:
> BAP down, link not multilink capable
> Dec 13 14:58:42 65.19.28.1 617: Dec 13 14:58:42.266 MST: Se2/0:0 PPP:
> Phase is TERMINATING
> Dec 13 14:58:42 65.19.28.1 618: Dec 13 14:58:42.266 MST: Se2/0:0 PPP:
> Phase is ESTABLISHING
> Dec 13 14:58:42 65.19.28.1 619: Dec 13 14:58:42.266 MST: Se2/0:0 LCP:
> O CONFREQ [Open] id 129 len 31
> Dec 13 14:58:42 65.19.28.1 620: Dec 13 14:58:42.266 MST: Se2/0:0 LCP:
> MRU 1524 (0x010405F4)
> Dec 13 14:58:42 65.19.28.1 621: Dec 13 14:58:42.266 MST: Se2/0:0 LCP:
> MagicNumber 0x39CEB0DC (0x050639CEB0DC)
> Dec 13 14:58:42 65.19.28.1 622: Dec 13 14:58:42.266 MST: Se2/0:0 LCP:
> MRRU 1524 (0x110405F4)
> Dec 13 14:58:42 65.19.28.1 623: Dec 13 14:58:42.266 MST: Se2/0:0 LCP:
> EndpointDisc 1 SFcolo-dsl (0x130D015346636F6C6F2D64736C)
> Dec 13 14:58:42 65.19.28.1 624: Dec 13 14:58:42.266 MST: Se2/0:0 LCP:
> O CONFACK [Open] id 31 len 18
> Dec 13 14:58:42 65.19.28.1 625: Dec 13 14:58:42.266 MST: Se2/0:0 LCP:
> ACFC (0x0802)
> Dec 13 14:58:42 65.19.28.1 626: Dec 13 14:58:42.266 MST: Se2/0:0 LCP:
> PFC (0x0702)
> Dec 13 14:58:42 65.19.28.1 627: Dec 13 14:58:42.266 MST: Se2/0:0 LCP:
> MRU 1524 (0x010405F4)
> Dec 13 14:58:42 65.19.28.1 628: Dec 13 14:58:42.266 MST: Se2/0:0 LCP:
> MagicNumber 0x745CDE00 (0x0506745CDE00)
> Dec 13 14:58:42 65.19.28.1 629: Dec 13 14:58:42.270 MST: Se2/0:0 LCP:
> I CONFREJ [ACKsent] id 129 len 8
> Dec 13 14:58:42 65.19.28.1 630: Dec 13 14:58:42.270 MST: Se2/0:0 LCP:
> MRRU 1524 (0x110405F4)
> Dec 13 14:58:42 65.19.28.1 631: Dec 13 14:58:42.270 MST: Se2/0:0 LCP:
> O CONFREQ [ACKsent] id 130 len 27
> Dec 13 14:58:42 65.19.28.1 632: Dec 13 14:58:42.270 MST: Se2/0:0 LCP:
> MRU 1524 (0x010405F4)
> Dec 13 14:58:42 65.19.28.1 633: Dec 13 14:58:42.270 MST: Se2/0:0 LCP:
> MagicNumber 0x39CEB0DC (0x050639CEB0DC)
> Dec 13 14:58:42 65.19.28.1 634: Dec 13 14:58:42.270 MST: Se2/0:0 LCP:
> EndpointDisc 1 SFcolo-dsl (0x130D015346636F6C6F2D64736C)
> Dec 13 14:58:42 65.19.28.1 635: Dec 13 14:58:42.270 MST: Se2/0:0 LCP:
> I CONFACK [ACKsent] id 130 len 27
> Dec 13 14:58:42 65.19.28.1 636: Dec 13 14:58:42.270 MST: Se2/0:0 LCP:
> MRU 1524 (0x010405F4)
> Dec 13 14:58:42 65.19.28.1 637: Dec 13 14:58:42.270 MST: Se2/0:0 LCP:
> MagicNumber 0x39CEB0DC (0x050639CEB0DC)
> Dec 13 14:58:42 65.19.28.1 638: Dec 13 14:58:42.270 MST: Se2/0:0 LCP:
> EndpointDisc 1 SFcolo-dsl (0x130D015346636F6C6F2D64736C)
> Dec 13 14:58:42 65.19.28.1 639: Dec 13 14:58:42.270 MST: Se2/0:0 LCP:
> State is Open
> Dec 13 14:58:42 65.19.28.1 640: Dec 13 14:58:42.270 MST: Se2/0:0 PPP:
> Phase is FORWARDING, Attempting Forward
> Dec 13 14:58:42 65.19.28.1 641: Dec 13 14:58:42.270 MST: Se2/0:0 PPP:
> Queue BNCP code[1] id[140]
> Dec 13 14:58:42 65.19.28.1 642: Dec 13 14:58:42.274 MST: Se2/0:0 PPP:
> Phase is ESTABLISHING, Finish LCP
> Dec 13 14:58:42 65.19.28.1 643: Dec 13 14:58:42.274 MST: Se2/0:0 PPP:
> Phase is UP
> Dec 13 14:58:42 65.19.28.1 644: Dec 13 14:58:42.274 MST: Se2/0:0 PPP:
> Process pending packets
> Dec 13 14:58:42 65.19.28.1 645: Dec 13 14:58:42.274 MST: Se2/0:0 BNCP:
> Redirect packet to Se2/0:0
> Dec 13 14:58:43 65.19.28.1 646: Dec 13 14:58:42.274 MST: Se2/0:0
> BNCP: I CONFREQ [Not negotiated] id 140 len 7
> Dec 13 14:58:43 65.19.28.1 647: Dec 13 14:58:42.274 MST: Se2/0:0 BNCP:
> Type8 (0x080301)
> Dec 13 14:58:43 65.19.28.1 648: Dec 13 14:58:42.274 MST: Se2/0:0 LCP:
> O PROTREJ [Open] id 131 len 13 protocol BNCP (0x8031018C0007080
> 301)
> Dec 13 14:58:48 65.19.28.1 649: Dec 13 14:58:48.278 MST: Se2/0:0 LCP:
> I CONFREQ [Open] id 32 len 18
> Dec 13 14:58:48 65.19.28.1 650: Dec 13 14:58:48.278 MST: Se2/0:0 LCP:
> ACFC (0x0802)
> Dec 13 14:58:48 65.19.28.1 651: Dec 13 14:58:48.278 MST: Se2/0:0 LCP:
> PFC (0x0702)
> Dec 13 14:58:48 65.19.28.1 652: Dec 13 14:58:48.278 MST: Se2/0:0 LCP:
> MRU 1524 (0x010405F4)
> Dec 13 14:58:48 65.19.28.1 653: Dec 13 14:58:48.278 MST: Se2/0:0 LCP:
> MagicNumber 0xAB1C2A00 (0x0506AB1C2A00)
> Dec 13 14:58:48 65.19.28.1 654: Dec 13 14:58:48.278 MST: Se2/0:0
> BNCP: State is Closed
> Dec 13 14:58:48 65.19.28.1 655: Dec 13 14:58:48.278 MST: Se2/0:0
> BACP: State is Closed
> Dec 13 14:58:48 65.19.28.1 656: Dec 13 14:58:48.278 MST: Se2/0:0 MLP:
> BAP down, link not multilink capable
> Dec 13 14:58:48 65.19.28.1 657: Dec 13 14:58:48.278 MST: Se2/0:0 PPP:
> Phase is TERMINATING
> Dec 13 14:58:48 65.19.28.1 658: Dec 13 14:58:48.278 MST: Se2/0:0 PPP:
> Phase is ESTABLISHING
> Dec 13 14:58:48 65.19.28.1 659: Dec 13 14:58:48.278 MST: Se2/0:0 LCP:
> O CONFREQ [Open] id 132 len 31
> Dec 13 14:58:48 65.19.28.1 660: Dec 13 14:58:48.278 MST: Se2/0:0 LCP:
> MRU 1524 (0x010405F4)
> Dec 13 14:58:48 65.19.28.1 661: Dec 13 14:58:48.278 MST: Se2/0:0 LCP:
> MagicNumber 0x39CEC859 (0x050639CEC859)
> Dec 13 14:58:48 65.19.28.1 662: Dec 13 14:58:48.278 MST: Se2/0:0 LCP:
> MRRU 1524 (0x110405F4)
> Dec 13 14:58:48 65.19.28.1 663: Dec 13 14:58:48.278 MST: Se2/0:0 LCP:
> EndpointDisc 1 SFcolo-dsl (0x130D015346636F6C6F2D64736C)
> Dec 13 14:58:48 65.19.28.1 664: Dec 13 14:58:48.278 MST: Se2/0:0 LCP:
> O CONFACK [Open] id 32 len 18
> Dec 13 14:58:48 65.19.28.1 665: Dec 13 14:58:48.278 MST: Se2/0:0 LCP:
> ACFC (0x0802)
> Dec 13 14:58:48 65.19.28.1 666: Dec 13 14:58:48.278 MST: Se2/0:0 LCP:
> PFC (0x0702)
> Dec 13 14:58:48 65.19.28.1 667: Dec 13 14:58:48.278 MST: Se2/0:0 LCP:
> MRU 1524 (0x010405F4)
> Dec 13 14:58:48 65.19.28.1 668: Dec 13 14:58:48.278 MST: Se2/0:0 LCP:
> MagicNumber 0xAB1C2A00 (0x0506AB1C2A00)
> Dec 13 14:58:48 65.19.28.1 669: Dec 13 14:58:48.278 MST: Se2/0:0 LCP:
> I CONFREJ [ACKsent] id 132 len 8
> Dec 13 14:58:48 65.19.28.1 670: Dec 13 14:58:48.278 MST: Se2/0:0 LCP:
> MRRU 1524 (0x110405F4)
> Dec 13 14:58:48 65.19.28.1 671: Dec 13 14:58:48.278 MST: Se2/0:0 LCP:
> O CONFREQ [ACKsent] id 133 len 27
> Dec 13 14:58:48 65.19.28.1 672: Dec 13 14:58:48.278 MST: Se2/0:0 LCP:
> MRU 1524 (0x010405F4)
> Dec 13 14:58:48 65.19.28.1 673: Dec 13 14:58:48.278 MST: Se2/0:0 LCP:
> MagicNumber 0x39CEC859 (0x050639CEC859)
> Dec 13 14:58:48 65.19.28.1 674: Dec 13 14:58:48.278 MST: Se2/0:0 LCP:
> EndpointDisc 1 SFcolo-dsl (0x130D015346636F6C6F2D64736C)
> Dec 13 14:58:48 65.19.28.1 675: Dec 13 14:58:48.282 MST: Se2/0:0 LCP:
> I CONFACK [ACKsent] id 133 len 27
> Dec 13 14:58:48 65.19.28.1 676: Dec 13 14:58:48.282 MST: Se2/0:0 LCP:
> MRU 1524 (0x010405F4)
> Dec 13 14:58:48 65.19.28.1 677: Dec 13 14:58:48.282 MST: Se2/0:0 LCP:
> MagicNumber 0x39CEC859 (0x050639CEC859)
> Dec 13 14:58:48 65.19.28.1 678: Dec 13 14:58:48.282 MST: Se2/0:0 LCP:
> EndpointDisc 1 SFcolo-dsl (0x130D015346636F6C6F2D64736C)
> Dec 13 14:58:48 65.19.28.1 679: Dec 13 14:58:48.282 MST: Se2/0:0 LCP:
> State is Open
> Dec 13 14:58:48 65.19.28.1 680: Dec 13 14:58:48.282 MST: Se2/0:0 PPP:
> Phase is FORWARDING, Attempting Forward
> Dec 13 14:58:48 65.19.28.1 681: Dec 13 14:58:48.282 MST: Se2/0:0 PPP:
> Queue BNCP code[1] id[141]
> Dec 13 14:58:48 65.19.28.1 682: Dec 13 14:58:48.282 MST: Se2/0:0 PPP:
> Phase is ESTABLISHING, Finish LCP
> Dec 13 14:58:48 65.19.28.1 683: Dec 13 14:58:48.282 MST: Se2/0:0 PPP:
> Phase is UP
> Dec 13 14:58:48 65.19.28.1 684: Dec 13 14:58:48.282 MST: Se2/0:0 PPP:
> Process pending packets
> Dec 13 14:58:48 65.19.28.1 685: Dec 13 14:58:48.282 MST: Se2/0:0 BNCP:
> Redirect packet to Se2/0:0
> Dec 13 14:58:49 65.19.28.1 686: Dec 13 14:58:48.282 MST: Se2/0:0
> BNCP: I CONFREQ [Not negotiated] id 141 len 7
> Dec 13 14:58:49 65.19.28.1 687: Dec 13 14:58:48.282 MST: Se2/0:0 BNCP:
> Type8 (0x080301)
> Dec 13 14:58:49 65.19.28.1 688: Dec 13 14:58:48.282 MST: Se2/0:0 LCP:
> O PROTREJ [Open] id 134 len 13 protocol BNCP (0x8031018D0007080
> 301)
> 
> 
> 
> James
> Routing and Security Administrator
> At the Santa Fe Office: Cyber Mesa Telecom
> jamesh at cybermesa.com  noc at cybermesa.com
> 
> 
> 
> _______________________________________________
> cisco-nsp mailing list  cisco-nsp at puck.nether.net
> https://puck.nether.net/mailman/listinfo/cisco-nsp
> archive at http://puck.nether.net/pipermail/cisco-nsp/