[c-nsp] PPPoE Session Limit?

[Graham Wooden]

Hi all,

I am running into what appears is some sort of session limit with providing
pppoe DSL service.  We are not running any session-limit commands, and the
local Bell doesn't have any limits set on their side, but we can only get 5
sessions up. This means that if I clear all the sessions, the first 5 will
get in. Or if someone drops off, a user that couldn't get in, will now.

We have a 2620, running the latest IP Plus, 12.3.18.

While doing a show vpdn, the 6th comes up in-a-split-second as Interface
"SSS Circuit" with a State "os wt-sss", and then goes away until the modem
tries again.  Running sessions are getting the virtual interface, Vi2.x with
their state being established.

Is there some sort of limit that we are overlooking, either with the IOS
running on the 2600 or with the ATM card?

Below is a debug output showing a session coming up and then going away.
Any ideas?  Thanks all,

-graham




*Apr 19 19:45:55.207: ppp303 PPP: Phase is ESTABLISHING
*Apr 19 19:45:55.211: ppp303 LCP: I FORCED rcvd CONFACK len 14
*Apr 19 19:45:55.211: ppp303 LCP:    MagicNumber 0x3664BAF7 (0x05063664BAF7)
*Apr 19 19:45:55.211: ppp303 LCP:    MRU 1500 (0x010405DC)
*Apr 19 19:45:55.211: ppp303 LCP:    AuthProto PAP (0x0304C023)
*Apr 19 19:45:55.211: ppp303 LCP: I FORCED sent CONFACK len 10
*Apr 19 19:45:55.211: ppp303 LCP:    MagicNumber 0xB4A05257 (0x0506B4A05257)
*Apr 19 19:45:55.211: ppp303 LCP:    MRU 1492 (0x010405D4)
*Apr 19 19:45:55.211: ppp303 PPP LCP not accepting sent CONFACK
*Apr 19 19:45:55.215: ppp303 LCP: O CONFREQ [Closed] id 1 len 19
*Apr 19 19:45:55.215: ppp303 LCP:    MRU 9180 (0x010423DC)
*Apr 19 19:45:55.215: ppp303 LCP:    AuthProto CHAP (0x0305C22305)
*Apr 19 19:45:55.215: ppp303 LCP:    MagicNumber 0x08B85FB5 (0x050608B85FB5)
*Apr 19 19:45:55.231: ppp303 LCP: I CONFREQ [REQsent] id 2 len 10
*Apr 19 19:45:55.235: ppp303 LCP:    MagicNumber 0x5D884DAC (0x05065D884DAC)
*Apr 19 19:45:55.235: ppp303 LCP: O CONFACK [REQsent] id 2 len 10
*Apr 19 19:45:55.235: ppp303 LCP:    MagicNumber 0x5D884DAC (0x05065D884DAC)
*Apr 19 19:45:55.239: ppp303 LCP: I CONFNAK [ACKsent] id 1 len 8
*Apr 19 19:45:55.239: ppp303 LCP:    AuthProto PAP (0x0304C023)
*Apr 19 19:45:55.239: ppp303 LCP: O CONFREQ [ACKsent] id 2 len 18
*Apr 19 19:45:55.239: ppp303 LCP:    MRU 9180 (0x010423DC)
*Apr 19 19:45:55.239: ppp303 LCP:    AuthProto PAP (0x0304C023)
*Apr 19 19:45:55.239: ppp303 LCP:    MagicNumber 0x08B85FB5 (0x050608B85FB5)
*Apr 19 19:45:55.259: ppp303 LCP: I CONFACK [ACKsent] id 2 len 18
*Apr 19 19:45:55.259: ppp303 LCP:    MRU 9180 (0x010423DC)
*Apr 19 19:45:55.259: ppp303 LCP:    AuthProto PAP (0x0304C023)
*Apr 19 19:45:55.259: ppp303 LCP:    MagicNumber 0x08B85FB5 (0x050608B85FB5)
*Apr 19 19:45:55.259: ppp303 LCP: State is Open
*Apr 19 19:45:55.259: ppp303 PPP: Phase is AUTHENTICATING, by this end
*Apr 19 19:45:55.263: ppp303 PAP: I AUTH-REQ id 2 len 40 from
"user at dsl.local"
*Apr 19 19:45:55.263: ppp303 PAP: Authenticating peer
"user at dsl.local"
*Apr 19 19:45:55.263: ppp303 PPP: Phase is FORWARDING, Attempting Forward
*Apr 19 19:45:55.263: ppp303 PPP: Phase is AUTHENTICATING,
Unauthenticated User
*Apr 19 19:45:55.267: ppp303 PPP: Phase is FORWARDING, Attempting Forward
*Apr 19 19:45:55.343: Vi2.6 PPP: Phase is AUTHENTICATING, Authenticated User
*Apr 19 19:45:55.343: Vi2.6 PAP: O AUTH-ACK id 2 len 5
*Apr 19 19:45:55.347: Vi2.6 PPP: Phase is UP
*Apr 19 19:45:55.347: Vi2.6 PPP: Process pending ncp packets
*Apr 19 19:45:55.363: Vi2.6 IPCP: I CONFREQ [Not negotiated] id 1 len 34
*Apr 19 19:45:55.367: Vi2.6 IPCP:    Address 0.0.0.0 (0x030600000000)
*Apr 19 19:45:55.367: Vi2.6 IPCP:    PrimaryDNS 0.0.0.0 (0x810600000000)
*Apr 19 19:45:55.367: Vi2.6 IPCP:    SecondaryDNS 0.0.0.0 (0x830600000000)
*Apr 19 19:45:55.367: Vi2.6 IPCP:    PrimaryWINS 0.0.0.0 (0x820600000000)
*Apr 19 19:45:55.367: Vi2.6 IPCP:    SecondaryWINS 0.0.0.0 (0x840600000000)
*Apr 19 19:45:55.367: Vi2.6 LCP: O PROTREJ [Open] id 3 len 40 protocol IPCP
*Apr 19 19:45:55.371: Vi2.6 LCP:  (0x80210101002203060000000081060000)
*Apr 19 19:45:55.371: Vi2.6 LCP:  (0x00008306000000008206000000008406)no de
*Apr 19 19:45:55.371: Vi2.6 LCP:  (0x00000000)
*Apr 19 19:45:55.387: Vi2.6 LCP: I TERMREQ [Open] id 3 len 4
*Apr 19 19:45:55.387: Vi2.6 LCP: O TERMACK [Open] id 3 len 4
*Apr 19 19:45:55.387: Vi2.6 PPP: Sending Acct Event[Down] id[D091]
*Apr 19 19:45:55.391: Vi2.6 PPP: Phase is TERMINATING
*Apr 19 19:45:55.415: Vi2.6 PPP: Block vaccess from being freed [0x18]
*Apr 19 19:45:56.375: Vi2.6 PPP: Missed link down notification
*Apr 19 19:45:56.379: Vi2.6 LCP: State is Closed
*Apr 19 19:45:56.379: Vi2.6 PPP: Phase is DOWN
*Apr 19 19:45:56.379: Vi2.6 PPP: Unlocked by [0x10] Still Locked by [0x8]
*Apr 19 19:45:56.379: Vi2.6 PPP: Unlocked by [0x8] Still Locked by [0x0]
*Apr 19 19:45:56.379: Vi2.6 PPP: Free previously blocked vaccessbug all