[rbak-nsp] IPv6 Subscriber - IPV6CP ProtoRej

Florian Lohoff f at zz.de
Thu Aug 16 16:19:40 EDT 2012 

Hi,

we are seeing PPP ProtoRej when trying to establish an DualStack PPP
Session - We have full ipv6 routing enabled on the box and
configured an v6 subscriber license. This is 6.5.1.3.

Here is the relevant config - We fail to find the reason of the proto
reject from debugging:


 interface LOOPBACK0 loopback
  description *** primary L2TP interface ***
  ip address 10.178.208.29/32
  ipv6 address 2001:0db8:ffff::8001/128
   ip source-address telnet snmp ssh dhcp-server tftp ftp icmp-dest-unreachable icmp-time-exceed netop flow-ip 

 interface POOL multibind lastresort
  ip unnumbered LOOPBACK0
  ip pool 10.19.64.0/20 name POOL_DEFAULT
  ipv6 pool 2001:0db8:11:1::/64 2001:0db8:11:ffff::/64 name POOLv6ND_DEFAULT 
  ipv6 pool dhcpv6 2001:0db8:1100::/56 2001:0db8:11ff::/56 name POOLv6PD_DEFAULT

 subscriber default
   ip address pool name POOL_DEFAULT
   ip source-validation
   ppp mtu 1492
   dns primary 10.0.208.8
   dns secondary 10.0.208.8
   ipv6 framed-pool POOLv6ND_DEFAULT
   ipv6 nd-profile nd-profile-default
   ipv6 delegated-prefix maximum 2
   dns6 primary  2001:0db8::53
   dns6 secondary 2001:0db8::53
   ipv6 source-validation



Aug 16 20:15:14.768: [1/1:511:63:31/6/2/83925]: %PPP-7-FSM: [283] LCP State is Initial (was Initial)
Aug 16 20:15:14.768: [1/1:511:63:31/6/2/83925]: %PPP-7-FSM: [283] IPCP State is Initial (was Initial)
Aug 16 20:15:14.768: [1/1:511:63:31/6/2/83925]: %PPP-7-NEG: [283] IPCP: wait for ConfReq from peer
Aug 16 20:15:14.768: [1/1:511:63:31/6/2/83925]: %PPP-7-FSM: [283] NLCP State is Initial (was Initial)
Aug 16 20:15:14.768: [1/1:511:63:31/6/2/83925]: %PPP-7-FSM: [283] MPLSCP State is Initial (was Initial)
Aug 16 20:15:14.768: [1/1:511:63:31/6/2/83925]: %PPP-7-FSM: [283] IPV6CP State is Initial (was Initial)
Aug 16 20:15:14.768: [1/1:511:63:31/6/2/83925]: %PPP-7-NEG: [283] IPV6CP: wait for ConfReq from peer
Aug 16 20:15:14.768: [1/1:511:63:31/6/2/83925]: %PPP-7-FSM: [283] LCP State is Closed (was Initial)
Aug 16 20:15:14.768: [1/1:511:63:31/6/2/83925]: %PPP-7-FSM: [283] LCP State is Stopped (was Closed)
Aug 16 20:15:14.782: [1/1:511:63:31/6/2/83925]: %PPP-7-PKT: [283] I [LCP ConfReq id=1 len=14 <mru 1492> <magic 0x815ad369>]
Aug 16 20:15:14.782: [1/1:511:63:31/6/2/83925]: %PPP-7-FSM: [283] Processing LCP ConfReq, id 1
Aug 16 20:15:14.782: [1/1:511:63:31/6/2/83925]: %PPP-7-NEG: [283] LCP: Accepting MRU 1492
Aug 16 20:15:14.782: [1/1:511:63:31/6/2/83925]: %PPP-7-NEG: [283] LCP: Accepting MAGICNUMBER 0x815ad369
Aug 16 20:15:14.782: [1/1:511:63:31/6/2/83925]: %PPP-7-NEG: [283] LCP: Returning ConfAck
Aug 16 20:15:14.782: [1/1:511:63:31/6/2/83925]: %PPP-7-NEG: [283] LCP: peer_mru_max 8992
Aug 16 20:15:14.782: %PPP-7-FSM: [1/1:511:63:31/6/2/83925] [283] cilen 14, peer_mru 8992
Aug 16 20:15:14.782: [1/1:511:63:31/6/2/83925]: %PPP-7-FSM: [283] LCP State is ReqSent (was Stopped)
Aug 16 20:15:14.782: [1/1:511:63:31/6/2/83925]: %PPP-7-FSM: [283] LCP State is AckSent (was ReqSent)
Aug 16 20:15:14.782: [1/1:511:63:31/6/2/83925]: %PPP-7-PKT: [283] O [LCP ConfReq id=207 len=18 <mru 1492> <auth upap> <magic 0x2973e0be>]
Aug 16 20:15:14.782: [1/1:511:63:31/6/2/83925]: %PPP-7-PKT: [283] O [LCP ConfAck id=1 len=14 <mru 1492> <magic 0x815ad369>]
Aug 16 20:15:14.795: [1/1:511:63:31/6/2/83925]: %PPP-7-PKT: [283] I [LCP ConfAck id=207 len=18 <mru 1492> <auth upap> <magic 0x2973e0be>]
Aug 16 20:15:14.795: [1/1:511:63:31/6/2/83925]: %PPP-7-FSM: [283] Processing LCP ConfAck, id 207
Aug 16 20:15:14.795: [1/1:511:63:31/6/2/83925]: %PPP-7-FSM: [283] LCP State is Opened (was AckSent)
Aug 16 20:15:14.795: [1/1:511:63:31/6/2/83925]: %PPP-7-NEG: [283] LCP up: Negotiated MRU 1492
Aug 16 20:15:14.795: [1/1:511:63:31/6/2/83925]: %PPP-7-FSM: [283] IPCP State is Closed (was Initial)
Aug 16 20:15:14.795: [1/1:511:63:31/6/2/83925]: %PPP-7-FSM: [283] IPV6CP State is Closed (was Initial)
Aug 16 20:15:14.795: [1/1:511:63:31/6/2/83925]: %PPP-7-PHASE: [283] Phase is Authen (was Dead)
Aug 16 20:15:14.795: [1/1:511:63:31/6/2/83925]: %PPP-7-PKT: [283] I [LCP DiscReq id=2 len=51 81 5a d3 69 41 56 4d 20 46 52 49 54 5a 21 42 6f 78 20 39 2e 30 35 2e 34 35 20 4f 55 54 20 4e 4f 4e 45 20 41 70 72 20 20 35 20 32 30 31 32 00]
Aug 16 20:15:14.795: [1/1:511:63:31/6/2/83925]: %PPP-7-FSM: [283] Processing LCP DiscReq, id 2
Aug 16 20:15:14.795: [1/1:511:63:31/6/2/83925]: %PPP-7-PKT: [283] I [PAP AuthReq id=1 len=17 user="username" password not shown]
Aug 16 20:15:14.801: %PPP-7-FSM: [1/1:511:63:31/6/2/83925] [283] cilen 6, peer_mru 1492
Aug 16 20:15:14.801: [1/1:511:63:31/6/2/83925]: %PPP-7-FSM: [283] IPCP State is ReqSent (was Closed)
Aug 16 20:15:14.801: [1/1:511:63:31/6/2/83925]: %PPP-7-PHASE: [283] Phase is Net/Passed (was Authen)
Aug 16 20:15:14.802: [1/1:511:63:31/6/2/83925]: %PPP-7-PKT: [283] O [PAP AuthAck id=1 len=5 msg=""]
Aug 16 20:15:14.802: [1/1:511:63:31/6/2/83925]: %PPP-7-PKT: [283] O [IPCP ConfReq id=120 len=10 <addr 10.187.208.29>]
Aug 16 20:15:14.813: [1/1:511:63:31/6/2/83925]: %PPP-7-PKT: [283] I [IPCP ConfReq id=19 len=22 <addr 0.0.0.0> <dns-primary 0.0.0.0> <dns-secondary 0.0.0.0>]
Aug 16 20:15:14.814: [1/1:511:63:31/6/2/83925]: %PPP-7-FSM: [283] Processing IPCP ConfReq, id 19
Aug 16 20:15:14.814: [1/1:511:63:31/6/2/83925]: %PPP-7-NEG: [283] IPCP: NAK-ing ADDR
Aug 16 20:15:14.814: [1/1:511:63:31/6/2/83925]: %PPP-7-NEG: [283] IPCP: NAK-ing DNS-PRI
Aug 16 20:15:14.814: [1/1:511:63:31/6/2/83925]: %PPP-7-NEG: [283] IPCP: NAK-ing DNS-SEC
Aug 16 20:15:14.814: [1/1:511:63:31/6/2/83925]: %PPP-7-NEG: [283] IPCP: Returning ConfNak
Aug 16 20:15:14.814: [1/1:511:63:31/6/2/83925]: %PPP-7-FSM: [283] IPCP State is ReqSent (was ReqSent)
Aug 16 20:15:14.814: [1/1:511:63:31/6/2/83925]: %PPP-7-PKT: [283] O [IPCP ConfNak id=19 len=22 <addr 10.19.79.30> <dns-primary 10.187.208.8> <dns-secondary 10.187.208.8>]
Aug 16 20:15:14.814: [1/1:511:63:31/6/2/83925]: %PPP-7-PKT: [283] I [IPV6CP ConfReq id=12 <Intf-Id c225:06ff:fe90:b4f2>]
Aug 16 20:15:14.815: [1/1:511:63:31/6/2/83925]: %PPP-7-PKT: [283] I [IPCP ConfAck id=120 len=10 <addr 10.187.208.29>]
Aug 16 20:15:14.815: [1/1:511:63:31/6/2/83925]: %PPP-7-FSM: [283] Processing IPCP ConfAck, id 120
Aug 16 20:15:14.815: [1/1:511:63:31/6/2/83925]: %PPP-7-FSM: [283] IPCP State is AckRcvd (was ReqSent)
Aug 16 20:15:14.815: [1/1:511:63:31/6/2/83925]: %PPP-7-PKT: [283] O [LCP ProtRej id=208 len=20 80 57 01 0c 00 0e 01 0a c2 25 06 ff fe 90 b4 f2]
Aug 16 20:15:14.826: [1/1:511:63:31/6/2/83925]: %PPP-7-PKT: [283] I [IPCP ConfReq id=20 len=22 <addr 10.19.79.30> <dns-primary 10.187.208.8> <dns-secondary 10.187.208.8>]
Aug 16 20:15:14.826: [1/1:511:63:31/6/2/83925]: %PPP-7-FSM: [283] Processing IPCP ConfReq, id 20
Aug 16 20:15:14.826: [1/1:511:63:31/6/2/83925]: %PPP-7-NEG: [283] IPCP: Accepting ADDR 10.19.79.30
Aug 16 20:15:14.826: [1/1:511:63:31/6/2/83925]: %PPP-7-NEG: [283] IPCP: Returning ConfAck
Aug 16 20:15:14.826: [1/1:511:63:31/6/2/83925]: %PPP-7-FSM: [283] IPCP State is Opened (was AckRcvd)
Aug 16 20:15:14.826: [1/1:511:63:31/6/2/83925]: %PPP-7-PKT: [283] O [IPCP ConfAck id=20 len=22 <addr 10.19.79.30> <dns-primary 10.187.208.8> <dns-secondary 10.187.208.8>]

Flo
-- 
Florian Lohoff                                                 f at zz.de
-------------- next part --------------
A non-text attachment was scrubbed...
Name: signature.asc
Type: application/pgp-signature
Size: 828 bytes
Desc: Digital signature
URL: <https://puck.nether.net/pipermail/redback-nsp/attachments/20120816/3a614c5d/attachment.sig>

More information about the redback-nsp mailing list