[c-nsp] ADSL connection not getting an IP

[kris at amy.id.au]

Hi Folks,

I have a vpdn session that comes across an l2tp tunnel. The user  
authenticates fine and
radius returns an ip for them. But it never get's assigned. I see  
logging about IPCP
timing out. The only bug I can see similar to this is CSCdr92864.  
Anyone got any ideas?

Nov  7 18:57:16: ppp1196 PPP: Phase is ESTABLISHING
Nov  7 18:57:16: ppp1196 LCP: O CONFREQ [Closed] id 1 len 43
Nov  7 18:57:16: ppp1196 LCP:    MRU 1464 (0x010405B8)
Nov  7 18:57:16: ppp1196 LCP:    AuthProto PAP (0x0304C023)
Nov  7 18:57:16: ppp1196 LCP:    MagicNumber 0x46C561C9 (0x050646C561C9)
Nov  7 18:57:16: ppp1196 LCP:    MRRU 1524 (0x110405F4)
Nov  7 18:57:16: ppp1196 LCP:    EndpointDisc 1 XXX
Nov  7 18:57:16: ppp1196 LCP:     (0x131501333434515545454E2D4C4E5331)
Nov  7 18:57:16: ppp1196 LCP:     (0x2D37323030)
Nov  7 18:57:16: ppp1196 LCP: I CONFREQ [REQsent] id 4 len 4
Nov  7 18:57:16: ppp1196 LCP: O CONFACK [REQsent] id 4 len 4
Nov  7 18:57:16: ppp1196 LCP: I CONFREJ [ACKsent] id 1 len 29
Nov  7 18:57:16: ppp1196 LCP:    MRRU 1524 (0x110405F4)
Nov  7 18:57:16: ppp1196 LCP:    EndpointDisc 1 XXXX
Nov  7 18:57:16: ppp1196 LCP:     (0x131501333434515545454E2D4C4E5331)
Nov  7 18:57:16: ppp1196 LCP:     (0x2D37323030)
Nov  7 18:57:16: ppp1196 LCP: O CONFREQ [ACKsent] id 2 len 18
Nov  7 18:57:16: ppp1196 LCP:    MRU 1464 (0x010405B8)
Nov  7 18:57:16: ppp1196 LCP:    AuthProto PAP (0x0304C023)
Nov  7 18:57:16: ppp1196 LCP:    MagicNumber 0x46C561C9 (0x050646C561C9)
Nov  7 18:57:16: ppp1196 LCP: I CONFACK [ACKsent] id 2 len 18
Nov  7 18:57:16: ppp1196 LCP:    MRU 1464 (0x010405B8)
Nov  7 18:57:16: ppp1196 LCP:    AuthProto PAP (0x0304C023)
Nov  7 18:57:16: ppp1196 LCP:    MagicNumber 0x46C561C9 (0x050646C561C9)
Nov  7 18:57:16: ppp1196 LCP: State is Open
Nov  7 18:57:16: ppp1196 PPP: Phase is AUTHENTICATING, by this end
Nov  7 18:57:16: ppp1196 PAP: I AUTH-REQ id 59 len 32 from "XXX at XXX"
Nov  7 18:57:16: ppp1196 PAP: Authenticating peer jinghaili at bit.net.au
Nov  7 18:57:16: ppp1196 PPP: Phase is FORWARDING, Attempting Forward
Nov  7 18:57:16: ppp1196 PPP: Phase is AUTHENTICATING, Unauthenticated User
Nov  7 18:57:16: RADIUS(0000241E): Storing nasport 1196 in rad_db
Nov  7 18:57:16: RADIUS(0000241E): Config NAS IP: X.X.X.X
Nov  7 18:57:16: RADIUS/ENCODE(0000241E): acct_session_id: 17919
Nov  7 18:57:16: RADIUS(0000241E): sending
Nov  7 18:57:16: RADIUS:  NAS-Port            [5]   6   1196
Nov  7 18:57:16: RADIUS:  Calling-Station-Id  [31]  17  "nky609100200993"
Nov  7 18:57:16: RADIUS:  Service-Type        [6]   6   Framed          
            [2]
Nov  7 18:57:16: RADIUS:  NAS-IP-Address      [4]   6   X.X.X.X

Nov  7 18:57:18: RADIUS: Received from id 21670/183 X.X.X.X:1645,  
Access-Accept, len 86
Nov  7 18:57:18: RADIUS:  authenticator BE 82 30 FB D3 29 17 B9 - D9  
EC 03 98 38 9B 2D 65
Nov  7 18:57:18: RADIUS:  Framed-Protocol     [7]   6   PPP             
            [1]
Nov  7 18:57:18: RADIUS:  Framed-IP-Netmask   [9]   6   255.255.255.255
Nov  7 18:57:18: RADIUS:  Framed-IP-Address   [8]   6   X.X.X.X
Nov  7 18:57:18: RADIUS:  Vendor, Cisco       [26]  48
Nov  7 18:57:18: RADIUS:   Cisco AVpair       [1]   42   
"ip:dns-servers=X.X.X.X X.X.X.X"
Nov  7 18:57:18: RADIUS(0000241E): Received from id 21670/183
Nov  7 18:57:18: ppp1196 PPP: Phase is FORWARDING, Attempting Forward
Nov  7 18:57:18: %LINK-3-UPDOWN: Interface Virtual-Access35, changed  
state to up
Nov  7 18:57:18: Vi35 PPP: Phase is AUTHENTICATING, Authenticated User
Nov  7 18:57:18: Vi35 PAP: O AUTH-ACK id 59 len 5
Nov  7 18:57:18: Vi35 PPP: Phase is UP
Nov  7 18:57:18: Vi35 IPCP: O CONFREQ [Closed] id 1 len 10
Nov  7 18:57:18: Vi35 IPCP:    Address X.X.X.X (0x0306CB844079)
Nov  7 18:57:18: Vi35 PPP: Process pending packets
Nov  7 18:57:19: %LINEPROTO-5-UPDOWN: Line protocol on Interface  
Virtual-Access35,
changed state to up
Nov  7 18:57:20: Vi35 IPCP: TIMEout: State REQsent
Nov  7 18:57:20: Vi35 IPCP: O CONFREQ [REQsent] id 2 len 10
Nov  7 18:57:20: Vi35 IPCP:    Address X.X.X.X (0x0306CB844079)

Cheers,
Kris