[c-nsp] L2TP errors on LNS and no PPP sessions from CPE

Zahid Hassan zhassan at gmx.net
Tue Nov 11 19:06:31 EST 2008 

Dear All,



I manage a LNS on which there are multiple L2TP tunnels.

>From one of the L2TP tunnels, I am not getting any PPP sessions.

Unfortunately, I do not have access to the LAC.

Below is what I am seeing on the LNS and the CPE :



LNS# debug vpdn l2x-errors 

Nov 11 23:51:53.998 GMT: L2TP tnl   0BE86:000041EC: Control connection
authentication skipped/passed.
Nov 11 23:51:54.618 GMT: L2TP tnl   05E82:0000C4DC: Control connection
authentication skipped/passed.
Nov 11 23:51:54.618 GMT: L2TP _____:_____:________: Create session
Nov 11 23:51:54.618 GMT: L2TP _____:_____:________:   Using ICRQ FSM
Nov 11 23:51:54.618 GMT: L2TP _____:_____:________:     remote ip set to
22.7.101.23
Nov 11 23:51:54.622 GMT: L2TP _____:_____:________:     local ip set to
22.7.114.212
Nov 11 23:51:54.622 GMT: L2TP tnl   05E82:0000C4DC: FSM-CC ev Session-Conn
Nov 11 23:51:54.622 GMT: L2TP tnl   05E82:0000C4DC: FSM-CC    in established
Nov 11 23:51:54.622 GMT: L2TP tnl   05E82:0000C4DC: FSM-CC do
Session-Conn-Est
Nov 11 23:51:54.622 GMT: L2TP tnl   05E82:0000C4DC:   Session count now 2
Nov 11 23:51:54.622 GMT: L2TP _____:05E82:0000A327: FSM-Sn ev CC-Up
Nov 11 23:51:54.622 GMT: L2TP _____:05E82:0000A327: FSM-Sn    in Idle
Nov 11 23:51:54.622 GMT: L2TP _____:05E82:0000A327: FSM-Sn do
CC-Up-Ignore0-1
Nov 11 23:51:54.622 GMT: L2TP _____:05E82:0000A327: Session attached
Nov 11 23:51:54.622 GMT: L2TP _____:05E82:0000A327: no cookies enabled
Nov 11 23:51:54.622 GMT: L2TP _____:05E82:0000A327: FSM-Sn ev Rx-ICRQ
Nov 11 23:51:54.622 GMT: L2TP _____:05E82:0000A327: FSM-Sn
Idle->Proc-ICRQ
Nov 11 23:51:54.622 GMT: L2TP _____:05E82:0000A327: FSM-Sn do Rx-ICRQ
Nov 11 23:51:54.622 GMT: L2TP _____:05E82:0000A327:   Chose application VPDN
Nov 11 23:51:54.622 GMT: L2TP _____:05E82:0000A327:   App type set to VPDN
Nov 11 23:51:54.622 GMT: L2TP tnl   05E82:0000C4DC:   VPDN Session count now
2
Nov 11 23:51:54.622 GMT: L2TP _____:05E82:0000A327: VPDN: process AVPs
Nov 11 23:51:54.622 GMT: L2TP _____:05E82:0000A327: Local AC is now UP
Nov 11 23:51:54.622 GMT: L2TP _____:05E82:0000A327: Remote AC is now UP
Nov 11 23:51:54.622 GMT: L2TP _____:05E82:0000A327:  
Nov 11 23:51:54.622 GMT: L2TP _____:05E82:0000A327: Shutting down session
Nov 11 23:51:54.622 GMT: L2TP _____:05E82:0000A327:   Result Code
Nov 11 23:51:54.622 GMT: L2TP _____:05E82:0000A327:     Reserved (0)
Nov 11 23:51:54.622 GMT: L2TP _____:05E82:0000A327:   Error Code
Nov 11 23:51:54.622 GMT: L2TP _____:05E82:0000A327:     No error (0)
Nov 11 23:51:54.622 GMT: L2TP _____:05E82:0000A327:   Vendor Error
Nov 11 23:51:54.622 GMT: L2TP _____:05E82:0000A327:     None (0)
Nov 11 23:51:54.622 GMT: L2TP _____:05E82:0000A327:   Optional Message
Nov 11 23:51:54.622 GMT: L2TP _____:05E82:0000A327:     "No disconnect
reason given"


LNS# debug vpdn l2x-events


Nov 11 23:54:54.971 GMT: L2TP tnl   0BE86:000041EC: FSM-CC ev Session-Conn
Nov 11 23:54:54.971 GMT: L2TP tnl   0BE86:000041EC: FSM-CC    in established
Nov 11 23:54:54.971 GMT: L2TP tnl   0BE86:000041EC: FSM-CC do
Session-Conn-Est
Nov 11 23:54:54.971 GMT: L2TP tnl   0BE86:000041EC:   Session count now 3
Nov 11 23:54:54.971 GMT: L2TP _____:0BE86:0000A33A: FSM-Sn ev CC-Up
Nov 11 23:54:54.971 GMT: L2TP _____:0BE86:0000A33A: FSM-Sn    in Idle
Nov 11 23:54:54.971 GMT: L2TP _____:0BE86:0000A33A: FSM-Sn do
CC-Up-Ignore0-1
Nov 11 23:54:54.971 GMT: L2TP _____:0BE86:0000A33A: Session attached
Nov 11 23:54:54.971 GMT: L2TP _____:0BE86:0000A33A: no cookies enabled
Nov 11 23:54:54.971 GMT: L2TP _____:0BE86:0000A33A: FSM-Sn ev Rx-ICRQ
Nov 11 23:54:54.971 GMT: L2TP _____:0BE86:0000A33A: FSM-Sn
Idle->Proc-ICRQ
Nov 11 23:54:54.975 GMT: L2TP _____:0BE86:0000A33A: FSM-Sn do Rx-ICRQ
Nov 11 23:54:54.975 GMT: L2TP _____:0BE86:0000A33A:   Chose application VPDN
Nov 11 23:54:54.975 GMT: L2TP _____:0BE86:0000A33A:   App type set to VPDN
Nov 11 23:54:54.975 GMT: L2TP tnl   0BE86:000041EC:   VPDN Session count now
3
Nov 11 23:54:54.975 GMT: L2TP _____:0BE86:0000A33A: VPDN: process AVPs
Nov 11 23:54:54.975 GMT: L2TP _____:0BE86:0000A33A: Local AC is now UP
Nov 11 23:54:54.975 GMT: L2TP _____:0BE86:0000A33A: Remote AC is now UP
Nov 11 23:54:54.975 GMT: L2TP _____:0BE86:0000A33A:  
Nov 11 23:54:54.975 GMT: L2TUN APP: handle/451345shutdown app session
Nov 11 23:54:54.975 GMT: L2TP _____:0BE86:0000A33A: Shutting down session
Nov 11 23:54:54.975 GMT: L2TP _____:0BE86:0000A33A:   Result Code
Nov 11 23:54:54.975 GMT: L2TP _____:0BE86:0000A33A:     Reserved (0)
Nov 11 23:54:54.975 GMT: L2TP _____:0BE86:0000A33A:   Error Code
Nov 11 23:54:54.975 GMT: L2TP _____:0BE86:0000A33A:     No error (0)
Nov 11 23:54:54.975 GMT: L2TP _____:0BE86:0000A33A:   Vendor Error
Nov 11 23:54:54.975 GMT: L2TP _____:0BE86:0000A33A:     None (0)
Nov 11 23:54:54.975 GMT: L2TP _____:0BE86:0000A33A:   Optional Message
Nov 11 23:54:54.975 GMT: L2TP _____:0BE86:0000A33A:     "No disconnect
reason given"
Nov 11 23:54:54.975 GMT: L2TP _____:0BE86:0000A33A:  
Nov 11 23:54:54.975 GMT: L2TP _____:0BE86:0000A33A: FSM-Sn ev ICRQ-ERR
Nov 11 23:54:54.975 GMT: L2TP _____:0BE86:0000A33A: FSM-Sn
Proc-ICRQ->Idle
Nov 11 23:54:54.975 GMT: L2TP _____:0BE86:0000A33A: FSM-Sn do Tx-CDN
Nov 11 23:54:54.975 GMT: L2TP _____:0BE86:0000A33A: No L2TUN socket VPDN
Nov 11 23:54:54.975 GMT: L2TP _____:0BE86:0000A33A: Open sock
22.4.14.22:1701->22.4.1.4:1701
Nov 11 23:54:54.975 GMT: L2TP _____:0BE86:0000A33A: FSM-Sn ev Sock-Ready
Nov 11 23:54:54.975 GMT: L2TP _____:0BE86:0000A33A: FSM-Sn    in Idle
Nov 11 23:54:54.975 GMT: L2TP _____:0BE86:0000A33A: FSM-Sn do Ignore-Sock-Up
Nov 11 23:54:54.975 GMT: L2TP _____:0BE86:0000A33A: Session down
Nov 11 23:54:54.975 GMT: L2TP _____:0BE86:0000A33A:
22.4.14.22:1701->22.4.1.4:1701
Nov 11 23:54:54.975 GMT: L2TP _____:0BE86:0000A33A: Destroying session
Nov 11 23:54:54.975 GMT: L2TP tnl   0BE86:000041EC: FSM-CC ev Session-Disc
Nov 11 23:54:54.975 GMT: L2TP tnl   0BE86:000041EC: FSM-CC    in established
Nov 11 23:54:54.975 GMT: L2TP tnl   0BE86:000041EC: FSM-CC do
Session-Disc-Est
Nov 11 23:54:54.975 GMT: L2TP tnl   0BE86:000041EC:   Session count now 2
Nov 11 23:54:54.975 GMT: L2TP tnl   0BE86:000041EC:   VPDN Session count now
2
Nov 11 23:54:54.975 GMT: L2TP _____:_____:________: Session detached
Nov 11 23:54:54.975 GMT: L2X  _____:_____:________: Destroying logical
session


CPE#debug ppp authentication

*Mar 12 22:37:39.500: Vi2 CHAP: I CHALLENGE id 1 len 30 from "lon-0-dsl"
*Mar 12 22:37:39.500: Vi2 CHAP: Using hostname from interface CHAP
*Mar 12 22:37:39.500: Vi2 CHAP: Using password from interface CHAP
*Mar 12 22:37:39.500: Vi2 CHAP: O RESPONSE id 1 len 48 from
"testuser at bis-internet.co.uk"
*Mar 12 22:37:39.540: Vi2 CHAP: I FAILURE id 1 len 26 msg is "Authentication
failure"
*Mar 12 22:37:39.540: Vi2 LCP: I TERMREQ [Open] id 3 len 4
*Mar 12 22:37:39.544: Vi2 LCP: O TERMACK [Open] id 3 len 4
*Mar 12 22:37:39.544: Vi2 PPP: Sending Acct Event[Down] id[99C]
*Mar 12 22:37:39.548: Vi2 PPP: Phase is TERMINATING
*Mar 12 22:37:39.576: Vi2 LCP: I CONFREQ [TERMsent] id 1 len 15
*Mar 12 22:37:39.576: Vi2 LCP:    AuthProto CHAP (0x0305C22305)
*Mar 12 22:37:39.576: Vi2 LCP:    MagicNumber 0xDE87DF9D (0x0506DE87DF9D)
*Mar 12 22:37:39.580: Vi2 LCP: Dropping packet, state is TERMsent
*Mar 12 22:37:41.532: Vi2 LCP: TIMEout: State TERMsent
*Mar 12 22:37:41.532: Vi2 LCP: State is Closed
*Mar 12 22:37:41.532: Vi2 PPP: Phase is DOWN
*Mar 12 22:37:41.532: Vi2 PPP: Phase is ESTABLISHING, Passive Open
*Mar 12 22:37:41.536: Vi2 LCP: State is Listen
*Mar 12 22:37:43.544: Vi2 LCP: TIMEout: State Listen



Is it possible to tell where the problem could be ?

I am not even seeing the CPE session hitting the LNS.


Thanks in advance.



Regards,


Zahid

More information about the cisco-nsp mailing list