Buy or Renew
Buy or Renew
Cisco + Splunk: It’s a new day for your data. Learn more.
 
cancel
Turn on suggestions
Auto-suggest helps you quickly narrow down your search results by suggesting possible matches as you type.
Showing results for 
Search instead for 
Did you mean: 
cancel
Start a conversation
5729
Views
20
Helpful
8
Replies

Cisco 881 PPOE client troubleshooting

Go to solution
incomf_lankey
Level 1
Level 1

‎04-15-2011 01:03 AM - edited ‎03-04-2019 12:04 PM

Good day!

I have some problems with establishing pppoe connection from Cisco to ISP. Please could you help me!

show ver

Cisco IOS Software, C880 Software (C880DATA-UNIVERSALK9_NPE-M), Version 15.1(1)T1, RELEASE SOFTWARE (fc2)

Technical Support: http://www.cisco.com/techsupport

Copyright (c) 1986-2010 by Cisco Systems, Inc.

Compiled Mon 19-Jul-10 07:17 by prod_rel_team


ROM: System Bootstrap, Version 12.4(22r)YB5, RELEASE SOFTWARE (fc1)


cisco_vlc uptime is 23 hours, 58 minutes

System returned to ROM by power-on

System image file is "flash:c880data-universalk9_npe-mz.151-1.T1.bin"

Last reload type: Normal Reload

show run (partial)


version 15.1


no aaa new-model

clock timezone Moscow 3

ip source-route

!

ip cef

no ip domain lookup

ip domain name vlc.local

ip name-server -----------ISP DNS--------

ip name-server -----------ISP DNS--------

no ipv6 cef

!

!

vpdn enable

!

bba-group pppoe global

!

interface FastEthernet0

!

interface FastEthernet1

!

interface FastEthernet2

!

interface FastEthernet3

!

interface FastEthernet4

no ip address

duplex auto

speed auto

pppoe-client dial-pool-number 1

         interface Vlan1

description $ETH-SW-LAUNCH$$INTF-INFO-HWIC 4ESW$

ip address 192.168.10.1 255.255.255.0

ip nat inside

ip virtual-reassembly

ip tcp adjust-mss 1452

!

interface Dialer1

ip address negotiated

ip mtu 1492

ip nat outside

ip virtual-reassembly

encapsulation ppp

dialer pool 1

dialer-group 1

ppp authentication chap callin

ppp chap hostname lizing

ppp chap password 0 PASSWORD

ppp ipcp route default

no cdp enable

!

ip forward-protocol nd


ip nat inside source list NAT interface Dialer1 overload

ip route 0.0.0.0 0.0.0.0 Dialer1

!

ip access-list extended NAT

permit ip host 192.168.10.254 any

!

dialer-list 1 protocol ip permit

I trying to debug:

sh debug

PPP:

  PPP authentication debugging is on

  PPP protocol errors debugging is on

  PPP protocol negotiation debugging is on

PPPoE:

  PPPoE protocol events debugging is on

  PPPoE control packets debugging is on

And i can see some problems, that i can't understand:
*Apr 15 07:39:45.810: PPPoE 42506: I PADS  R:0015.178a.be16 L:c471.fea7.fce8 Fa4
contiguous pak, size 66
C4 71 FE A7 FC E8 00 15 17 8A BE 16 88 63 11 65
A6 0A 00 2E 01 02 00 0E 6E 61 73 31 5F 70 70 70
6F 65 5F 76 6C 73 01 01 00 00 01 03 00 08 14 00
00 01 00 00 0A C1 01 04 00 08 C0 3D C0 4B 00 FF
FF FF
*Apr 15 07:39:45.810: IN PADS from PPPoE Session
*Apr 15 07:39:45.810: %DIALER-6-BIND: Interface Vi1 bound to profile Di1
*Apr 15 07:39:45.810: PPPoE: Virtual Access interface obtained.
*Apr 15 07:39:45.810: PPPoE : encap string prepared
*Apr 15 07:39:45.810: [0]PPPoE 42506: data path set to PPPoE Client
*Apr 15 07:39:45.814: %LINK-3-UPDOWN: Interface Virtual-Access1, changed state to up
*Apr 15 07:39:45.814: Vi1 PPP: Sending cstate UP notification
*Apr 15 07:39:45.814: Vi1 PPP: Processing CstateUp message
*Apr 15 07:39:45.814: PPP: Alloc Context [849EEEC8]
*Apr 15 07:39:45.814: ppp74 PPP: Phase is ESTABLISHING
*Apr 15 07:39:45.814: Vi1 PPP: Using dialer call direction
*Apr 15 07:39:45.814: Vi1 PPP: Treating connection as a callout
*Apr 15 07:39:45.814: Vi1 PPP: Session handle[EF00000A] Session id[74]
*Apr 15 07:39:45.814: Vi1 LCP: Event[OPEN] State[Initial to Starting]
*Apr 15 07:39:45.814: Vi1 PPP: No remote authentication for call-out
*Apr 15 07:39:45.814: Vi1 LCP: O CONFREQ [Starting] id 1 len 10
*Apr 15 07:39:45.814: Vi1 LCP:    MagicNumber 0x77186541 (0x050677186541)
*Apr 15 07:39:45.814: Vi1 LCP: Event[UP] State[Starting to REQsent]
*Apr 15 07:39:45.818: Vi1 LCP: I CONFACK [REQsent] id 1 len 10
*Apr 15 07:39:45.818: Vi1 LCP:    MagicNumber 0x77186541 (0x050677186541)
*Apr 15 07:39:45.818: Vi1 LCP: Event[Receive ConfAck] State[REQsent to ACKrcvd]
*Apr 15 07:39:47.810: Vi1 LCP: I CONFREQ [ACKrcvd] id 2 len 36
*Apr 15 07:39:47.810: Vi1 LCP:    PFC (0x0702)
*Apr 15 07:39:47.810: Vi1 LCP:    MRU 1492 (0x010405D4)
*Apr 15 07:39:47.810: Vi1 LCP:    MagicNumber 0xB4CCFF6A (0x0506B4CCFF6A)
*Apr 15 07:39:47.810: Vi1 LCP:    AuthProto CHAP (0x0305C22305)
*Apr 15 07:39:47.810: Vi1 LCP:    MRRU 2048 (0x11040800)
*Apr 15 07:39:47.810: Vi1 LCP:    MultilinkShortSeq (0x1202)
*Apr 15 07:39:47.810: Vi1 LCP:    EndpointDisc 3 0015.179d.7227(0x1309030015179D7227)
*Apr 15 07:39:47.810: Vi1 LCP: O CONFREJ [ACKrcvd] id 2 len 10
*Apr 15 07:39:47.810: Vi1 LCP:    MRRU 2048 (0x11040800)
*Apr 15 07:39:47.810: Vi1 LCP:    MultilinkShortSeq (0x1202)
*Apr 15 07:39:47.810: Vi1 LCP: Event[Receive ConfReq-] State[ACKrcvd to ACKrcvd]
*Apr 15 07:39:47.810: Vi1 LCP: I CONFREQ [ACKrcvd] id 3 len 21
*Apr 15 07:39:47.810: Vi1 LCP:    PFC (0x0702)
*Apr 15 07:39:47.810: Vi1 LCP:    MRU 1492 (0x010405D4)
*Apr 15 07:39:47.810: Vi1 LCP:    MagicNumber 0xB4CCFF6A (0x0506B4CCFF6A)
*Apr 15 07:39:47.810: Vi1 LCP:    AuthProto CHAP (0x0305C22305)
*Apr 15 07:39:47.810: Vi1 LCP: O CONFNAK [ACKrcvd] id 3 len 8
*Apr 15 07:39:47.810: Vi1 LCP:    MRU 1500 (0x010405DC)
*Apr 15 07:39:47.810: Vi1 LCP: Event[Receive ConfReq-] State[ACKrcvd to ACKrcvd]
*Apr 15 07:39:47.814: Vi1 LCP: O CONFREQ [ACKrcvd] id 2 len 10
*Apr 15 07:39:47.814: Vi1 LCP:    MagicNumber 0x77186541 (0x050677186541)
*Apr 15 07:39:47.814: Vi1 LCP: Event[Timeout+] State[ACKrcvd to REQsent]
*Apr 15 07:39:47.814: Vi1 LCP: I CONFREQ [REQsent] id 4 len 21
*Apr 15 07:39:47.814: Vi1 LCP:    PFC (0x0702)
*Apr 15 07:39:47.814: Vi1 LCP:    MRU 1492 (0x010405D4)
*Apr 15 07:39:47.814: Vi1 LCP:    MagicNumber 0xB4CCFF6A (0x0506B4CCFF6A)
*Apr 15 07:39:47.814: Vi1 LCP:    AuthProto CHAP (0x0305C22305)
*Apr 15 07:39:47.814: Vi1 LCP: O CONFNAK [REQsent] id 4 len 8
*Apr 15 07:39:47.814: Vi1 LCP:    MRU 1500 (0x010405DC)
*Apr 15 07:39:47.814: Vi1 LCP: Event[Receive ConfReq-] State[REQsent to REQsent]
*Apr 15 07:39:47.814: Vi1 LCP: I CONFACK [REQsent] id 2 len 10
*Apr 15 07:39:47.814: Vi1 LCP:    MagicNumber 0x77186541 (0x050677186541)
*Apr 15 07:39:47.814: Vi1 LCP: Event[Receive ConfAck] State[REQsent to ACKrcvd]
*Apr 15 07:39:47.814: Vi1 LCP: I CONFREQ [ACKrcvd] id 5 len 21
*Apr 15 07:39:47.814: Vi1 LCP:    PFC (0x0702)
*Apr 15 07:39:47.814: Vi1 LCP:    MRU 1492 (0x010405D4)
*Apr 15 07:39:47.814: Vi1 LCP:    MagicNumber 0xB4CCFF6A (0x0506B4CCFF6A)
*Apr 15 07:39:47.814: Vi1 LCP:    AuthProto CHAP (0x0305C22305)
*Apr 15 07:39:47.814: Vi1 LCP: O CONFNAK [ACKrcvd] id 5 len 8
*Apr 15 07:39:47.814: Vi1 LCP:    MRU 1500 (0x010405DC)
*Apr 15 07:39:47.814: Vi1 LCP: Event[Receive ConfReq-] State[ACKrcvd to ACKrcvd]
*Apr 15 07:39:47.814: Vi1 LCP: I CONFREQ [ACKrcvd] id 6 len 21
*Apr 15 07:39:47.814: Vi1 LCP:    PFC (0x0702)
*Apr 15 07:39:47.814: Vi1 LCP:    MRU 1492 (0x010405D4)
*Apr 15 07:39:47.814: Vi1 LCP:    MagicNumber 0xB4CCFF6A (0x0506B4CCFF6A)
*Apr 15 07:39:47.814: Vi1 LCP:    AuthProto CHAP (0x0305C22305)
*Apr 15 07:39:47.814: Vi1 LCP: O CONFNAK [ACKrcvd] id 6 len 8
*Apr 15 07:39:47.814: Vi1 LCP:    MRU 1500 (0x010405DC)
*Apr 15 07:39:47.814: Vi1 LCP: Event[Receive ConfReq-] State[ACKrcvd to ACKrcvd]
*Apr 15 07:39:47.818: Vi1 LCP: I CONFREQ [ACKrcvd] id 7 len 21
*Apr 15 07:39:47.818: Vi1 LCP:    PFC (0x0702)
*Apr 15 07:39:47.818: Vi1 LCP:    MRU 1492 (0x010405D4)
*Apr 15 07:39:47.818: Vi1 LCP:    MagicNumber 0xB4CCFF6A (0x0506B4CCFF6A)
*Apr 15 07:39:47.818: Vi1 LCP:    AuthProto CHAP (0x0305C22305)
*Apr 15 07:39:47.818: Vi1 LCP: O CONFNAK [ACKrcvd] id 7 len 8
*Apr 15 07:39:47.818: Vi1 LCP:    MRU 1500 (0x010405DC)
*Apr 15 07:39:47.818: Vi1 LCP: Event[Receive ConfReq-] State[ACKrcvd to ACKrcvd]
*Apr 15 07:39:47.818: Vi1 LCP: I CONFREQ [ACKrcvd] id 8 len 21
*Apr 15 07:39:47.818: Vi1 LCP:    PFC (0x0702)
*Apr 15 07:39:47.818: Vi1 LCP:    MRU 1492 (0x010405D4)
*Apr 15 07:39:47.818: Vi1 LCP:    MagicNumber 0xB4CCFF6A (0x0506B4CCFF6A)
*Apr 15 07:39:47.818: Vi1 LCP:    AuthProto CHAP (0x0305C22305)
*Apr 15 07:39:47.818: Vi1 LCP: Sent too many CONFNAKs.  Switch to CONFREJ
*Apr 15 07:39:47.818: Vi1 LCP: O CONFREJ [ACKrcvd] id 8 len 8
*Apr 15 07:39:47.818: Vi1 LCP:    MRU 1492 (0x010405D4)
*Apr 15 07:39:47.818: Vi1 LCP: Event[Receive ConfReq-] State[ACKrcvd to ACKrcvd]
*Apr 15 07:39:47.818: Vi1 LCP: I CONFREQ [ACKrcvd] id 9 len 17
*Apr 15 07:39:47.818: Vi1 LCP:    PFC (0x0702)
*Apr 15 07:39:47.818: Vi1 LCP:    MagicNumber 0xB4CCFF6A (0x0506B4CCFF6A)
*Apr 15 07:39:47.818: Vi1 LCP:    AuthProto CHAP (0x0305C22305)
*Apr 15 07:39:47.818: Vi1 LCP: O CONFACK [ACKrcvd] id 9 len 17
*Apr 15 07:39:47.818: Vi1 LCP:    PFC (0x0702)
*Apr 15 07:39:47.818: Vi1 LCP:    MagicNumber 0xB4CCFF6A (0x0506B4CCFF6A)
*Apr 15 07:39:47.822: Vi1 LCP:    AuthProto CHAP (0x0305C22305)
*Apr 15 07:39:47.822: Vi1 LCP: Event[Receive ConfReq+] State[ACKrcvd to Open]
*Apr 15 07:39:47.822: Vi1 PPP: Queue CHAP code[1] id[1]
*Apr 15 07:39:47.846: Vi1 PPP: No authorization without authentication
*Apr 15 07:39:47.846: Vi1 PPP: Phase is AUTHENTICATING, by the peer
*Apr 15 07:39:47.846: Vi1 CHAP: Redirect packet to Vi1
*Apr 15 07:39:47.846: Vi1 CHAP: I CHALLENGE id 1 len 40
*Apr 15 07:39:47.846: Vi1 CHAP: No name received from peer
*Apr 15 07:39:47.846: Vi1 CHAP: Using hostname from interface CHAP
*Apr 15 07:39:47.846: Vi1 CHAP: Using password from interface CHAP
*Apr 15 07:39:47.846: Vi1 CHAP: O RESPONSE id 1 len 27 from "lizing"
*Apr 15 07:39:47.846: Vi1 LCP: State is Open
*Apr 15 07:39:47.846: Vi1 CHAP: I SUCCESS id 1 len 11 msg is "Welcome"
*Apr 15 07:39:47.846: Vi1 PPP: Phase is FORWARDING, Attempting Forward
*Apr 15 07:39:47.850: Vi1 PPP DISC: Lower Layer disconnected
*Apr 15 07:39:47.850: Vi1 LCP: O TERMREQ [Open] id 3 len 4
*Apr 15 07:39:47.850: Vi1 LCP: Event[CLOSE] State[Open to Closing]
*Apr 15 07:39:47.850: Vi1 PPP: Phase is TERMINATING
*Apr 15 07:39:47.850:  PPPoE : Shutting down client session
*Apr 15 07:39:47.850: [0]PPPoE 42506: O PADT  R:0015.178a.be16 L:c471.fea7.fce8 Fa4
contiguous pak, size 60
00 15 17 8A BE 16 C4 71 FE A7 FC E8 88 63 11 A7
A6 0A 00 00 00 00 00 00 00 00 00 00 00 00 00 00
00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
00 00 00 00 00 00 00 00 00 00 00 00
*Apr 15 07:39:47.850: %DIALER-6-UNBIND: Interface Vi1 unbound from profile Di1
*Apr 15 07:39:47.850: Vi1 PPP: Block vaccess from being freed [0x10]
*Apr 15 07:39:47.850: Vi1 IPCP: LCP not open, discarding packet
*Apr 15 07:39:47.850: Vi1 LCP: Event[DOWN] State[Closing to Initial]
*Apr 15 07:39:47.850: Vi1 PPP: Unlocked by [0x10] Still Locked by [0x0]
*Apr 15 07:39:47.850: Vi1 PPP: Free previously blocked vaccess
*Apr 15 07:39:47.850: Vi1 PPP: Phase is DOWN
*Apr 15 07:39:47.850: PPPoE 42506: I PADT  R:0015.178a.be16 L:c471.fea7.fce8 Fa4
contiguous pak, size 60
C4 71 FE A7 FC E8 00 15 17 8A BE 16 88 63 11 A7
A6 0A 00 12 02 03 00 0E 73 65 73 73 69 6F 6E 20
63 6C 6F 73 65 64 00 00 00 00 00 00 00 00 00 00
00 00 00 00 00 00 00 00 34 73 86 D6
*Apr 15 07:39:47.854: %LINK-3-UPDOWN: Interface Virtual-Access1, changed state to down
*Apr 15 07:40:07.826:  Sending PADI: Interface = FastEthernet4
*Apr 15 07:40:07.826: pppoe_send_padi:
contiguous pak, size 60
FF FF FF FF FF FF C4 71 FE A7 FC E8 88 63 11 09
00 00 00 10 01 01 00 00 01 03 00 08 14 00 00 01
00 00 0A C1 00 00 00 00 00 00 00 00 00 00 00 00
00 00 00 00 00 00 00 00 00 00 00 00
*Apr 15 07:40:07.826: PPPoE 0: I PADO  R:0015.178a.be16 L:c471.fea7.fce8 Fa4
contiguous pak, size 71
C4 71 FE A7 FC E8 00 15 17 8A BE 16 88 63 11 07
00 00 00 33 01 02 00 0E 6E 61 73 31 5F 70 70 70
6F 65 5F 76 6C 73 01 01 00 00 01 01 00 01 2A 01
03 00 08 14 00 00 01 00 00 0A C1 01 04 00 08 40
5B 25 7B 00 FF FF FF
*Apr 15 07:40:09.874:  PPPOE: we've got our pado and the pado timer went off
*Apr 15 07:40:09.874: OUT PADR from PPPoE Session
contiguous pak, size 71
00 15 17 8A BE 16 C4 71 FE A7 FC E8 88 63 11 19
00 00 00 33 01 02 00 0E 6E 61 73 31 5F 70 70 70
6F 65 5F 76 6C 73 01 01 00 00 01 01 00 01 2A 01
03 00 08 14 00 00 01 00 00 0A C1 01 04 00 08 40
5B 25 7B 00 FF FF FF
*Apr 15 07:40:09.874: PPPoE 42511: I PADS  R:0015.178a.be16 L:c471.fea7.fce8 Fa4
contiguous pak, size 66
C4 71 FE A7 FC E8 00 15 17 8A BE 16 88 63 11 65
A6 0F 00 2E 01 02 00 0E 6E 61 73 31 5F 70 70 70
6F 65 5F 76 6C 73 01 01 00 00 01 03 00 08 14 00
00 01 00 00 0A C1 01 04 00 08 40 5B 25 7B 00 FF
FF FF
Labels:
0 Helpful
1 Accepted Solution

Accepted Solutions

Go to solution
Juan Perez
Level 1
Level 1
In response to incomf_lankey

‎04-15-2011 09:55 AM

Sorry, just noticed you are running 15.1(1)T1.

I would recommend upgrading it to 15.1(1)T2 or latest 15.1(3)T1. There is a bug on these boxes running 15.1(1)T1 on which we get a "Vi1 PPP DISC: Lower Layer disconnected" message, it is related to some garbage being sent out.

View solution in original post

0 Helpful
8 Replies 8

Go to solution
mavespig
Level 3
Level 3

‎04-15-2011 01:55 AM

Hi Mikhail,

*Apr 15 07:39:47.810: Vi1 LCP: I CONFREQ [ACKrcvd] id 3 len 21
*Apr 15 07:39:47.810: Vi1 LCP:    MRU 1492 (0x010405D4)
*Apr 15 07:39:47.810: Vi1 LCP: O CONFNAK [ACKrcvd] id 3 len 8
*Apr 15 07:39:47.810: Vi1 LCP:    MRU 1500 (0x010405DC)

Your ISP wants to negotiate MRU 1492, while you reply back with 1500 (it's a NAK, so you don't agree on the CONFREQ).
Can you try "mtu 1492" instead of "ip mtu 1492"?

Hope this helps
Marco

** Remember to rate useful posts **

Go to solution
incomf_lankey
Level 1
Level 1
In response to mavespig

‎04-15-2011 04:38 AM

Marco Vespignani, thank you for your answer.

New config looks like this:

vpdn enable

bba-group pppoe global

!

interface FastEthernet4

no ip address

duplex auto

speed auto

pppoe-client dial-pool-number 1

!

interface Vlan1

description $ETH-SW-LAUNCH$$INTF-INFO-HWIC 4ESW$

ip address 192.168.10.1 255.255.255.0

ip nat inside

ip virtual-reassembly

ip tcp adjust-mss 1452

!

interface Dialer1

mtu 1492

ip address negotiated

ip nat outside

ip virtual-reassembly

encapsulation ppp

dialer pool 1

dialer-group 1

ppp authentication chap callin

ppp chap hostname lizing

ppp chap password 0 PASSWORD

ppp ipcp route default

no cdp enable

!
ip nat inside source list NAT interface Dialer1 overload
ip route 0.0.0.0 0.0.0.0 Dialer1
!
ip access-list extended NAT
permit ip host 192.168.10.254 any
!
dialer-list 1 protocol ip permit
And DEBUG:
contiguous pak, size 71
C4 71 FE A7 FC E8 00 15 17 8A BE 16 88 63 11 07
00 00 00 33 01 02 00 0E 6E 61 73 31 5F 70 70 70
6F 65 5F 76 6C 73 01 01 00 00 01 01 00 01 2A 01
03 00 08 14 00 00 01 00 00 0A C1 01 04 00 08 40
B3 9E 30 00 FF FF FF
*Apr 15 09:13:27.717:  PPPOE: we've got our pado and the pado timer went off
*Apr 15 09:13:27.717: OUT PADR from PPPoE Session
contiguous pak, size 71
00 15 17 8A BE 16 C4 71 FE A7 FC E8 88 63 11 19
00 00 00 33 01 02 00 0E 6E 61 73 31 5F 70 70 70
6F 65 5F 76 6C 73 01 01 00 00 01 01 00 01 2A 01
03 00 08 14 00 00 01 00 00 0A C1 01 04 00 08 40
B3 9E 30 00 FF FF FF
*Apr 15 09:13:27.717: PPPoE 43679: I PADS  R:0015.178a.be16 L:c471.fea7.fce8 Fa4
contiguous pak, size 66
C4 71 FE A7 FC E8 00 15 17 8A BE 16 88 63 11 65
AA 9F 00 2E 01 02 00 0E 6E 61 73 31 5F 70 70 70
6F 65 5F 76 6C 73 01 01 00 00 01 03 00 08 14 00
00 01 00 00 0A C1 01 04 00 08 40 B3 9E 30 00 FF
FF FF
*Apr 15 09:13:27.717: IN PADS from PPPoE Session
*Apr 15 09:13:27.717: %DIALER-6-BIND: Interface Vi1 bound to profile Di1
*Apr 15 09:13:27.717: PPPoE: Virtual Access interface obtained.
*Apr 15 09:13:27.717: PPPoE : encap string prepared
*Apr 15 09:13:27.717: [0]PPPoE 43679: data path set to PPPoE Client
*Apr 15 09:13:27.721: %LINK-3-UPDOWN: Interface Virtual-Access1, changed state to up
*Apr 15 09:13:27.721: Vi1 PPP: Sending cstate UP notification
*Apr 15 09:13:27.721: Vi1 PPP: Processing CstateUp message
*Apr 15 09:13:27.721: PPP: Alloc Context [849EEEC8]
*Apr 15 09:13:27.721: ppp108 PPP: Phase is ESTABLISHING
*Apr 15 09:13:27.721: Vi1 PPP: Using dialer call direction
*Apr 15 09:13:27.721: Vi1 PPP: Treating connection as a callout
*Apr 15 09:13:27.721: Vi1 PPP: Session handle[5100002C] Session id[108]
*Apr 15 09:13:27.721: Vi1 LCP: Event[OPEN] State[Initial to Starting]
*Apr 15 09:13:27.721: Vi1 PPP: No remote authentication for call-out
*Apr 15 09:13:27.721: Vi1 LCP: O CONFREQ [Starting] id 1 len 14
*Apr 15 09:13:27.721: Vi1 LCP:    MRU 1492 (0x010405D4)
*Apr 15 09:13:27.721: Vi1 LCP:    MagicNumber 0x776E320D (0x0506776E320D)
*Apr 15 09:13:27.721: Vi1 LCP: Event[UP] State[Starting to REQsent]
*Apr 15 09:13:27.721: Vi1 LCP: I CONFACK [REQsent] id 1 len 14
*Apr 15 09:13:27.721: Vi1 LCP:    MRU 1492 (0x010405D4)
*Apr 15 09:13:27.721: Vi1 LCP:    MagicNumber 0x776E320D (0x0506776E320D)
*Apr 15 09:13:27.721: Vi1 LCP: Event[Receive ConfAck] State[REQsent to ACKrcvd]
*Apr 15 09:13:29.717: Vi1 LCP: I CONFREQ [ACKrcvd] id 2 len 36
*Apr 15 09:13:29.717: Vi1 LCP:    PFC (0x0702)
*Apr 15 09:13:29.717: Vi1 LCP:    MRU 1492 (0x010405D4)
*Apr 15 09:13:29.717: Vi1 LCP:    MagicNumber 0x2163349C (0x05062163349C)
*Apr 15 09:13:29.717: Vi1 LCP:    AuthProto CHAP (0x0305C22305)
*Apr 15 09:13:29.717: Vi1 LCP:    MRRU 2048 (0x11040800)
*Apr 15 09:13:29.717: Vi1 LCP:    MultilinkShortSeq (0x1202)
*Apr 15 09:13:29.717: Vi1 LCP:    EndpointDisc 3 0015.179d.7227(0x1309030015179D7227)
*Apr 15 09:13:29.717: Vi1 LCP: O CONFREJ [ACKrcvd] id 2 len 10
*Apr 15 09:13:29.717: Vi1 LCP:    MRRU 2048 (0x11040800)
*Apr 15 09:13:29.717: Vi1 LCP:    MultilinkShortSeq (0x1202)
*Apr 15 09:13:29.717: Vi1 LCP: Event[Receive ConfReq-] State[ACKrcvd to ACKrcvd]
*Apr 15 09:13:29.717: Vi1 LCP: I CONFREQ [ACKrcvd] id 3 len 21
*Apr 15 09:13:29.717: Vi1 LCP:    PFC (0x0702)
*Apr 15 09:13:29.717: Vi1 LCP:    MRU 1492 (0x010405D4)
*Apr 15 09:13:29.717: Vi1 LCP:    MagicNumber 0x2163349C (0x05062163349C)
*Apr 15 09:13:29.717: Vi1 LCP:    AuthProto CHAP (0x0305C22305)
*Apr 15 09:13:29.717: Vi1 LCP: O CONFACK [ACKrcvd] id 3 len 21
*Apr 15 09:13:29.717: Vi1 LCP:    PFC (0x0702)
*Apr 15 09:13:29.717: Vi1 LCP:    MRU 1492 (0x010405D4)
*Apr 15 09:13:29.717: Vi1 LCP:    MagicNumber 0x2163349C (0x05062163349C)
*Apr 15 09:13:29.717: Vi1 LCP:    AuthProto CHAP (0x0305C22305)
*Apr 15 09:13:29.717: Vi1 LCP: Event[Receive ConfReq+] State[ACKrcvd to Open]
*Apr 15 09:13:29.721: Vi1 PPP: Queue CHAP code[1] id[1]
*Apr 15 09:13:29.733: Vi1 PPP: No authorization without authentication
*Apr 15 09:13:29.733: Vi1 PPP: Phase is AUTHENTICATING, by the peer
*Apr 15 09:13:29.733: Vi1 CHAP: Redirect packet to Vi1
*Apr 15 09:13:29.733: Vi1 CHAP: I CHALLENGE id 1 len 29
*Apr 15 09:13:29.733: Vi1 CHAP: No name received from peer
*Apr 15 09:13:29.733: Vi1 CHAP: Using hostname from interface CHAP
*Apr 15 09:13:29.733: Vi1 CHAP: Using password from interface CHAP
*Apr 15 09:13:29.733: Vi1 CHAP: O RESPONSE id 1 len 27 from "lizing"
*Apr 15 09:13:29.733: Vi1 LCP: State is Open
*Apr 15 09:13:29.733: Vi1 CHAP: I SUCCESS id 1 len 11 msg is "Welcome"
*Apr 15 09:13:29.737: Vi1 PPP: Phase is FORWARDING, Attempting Forward
*Apr 15 09:13:29.737: Vi1 PPP: Queue IPCP code[1] id[1]
*Apr 15 09:13:29.737: Vi1 PPP DISC: Lower Layer disconnected
*Apr 15 09:13:29.737: Vi1 LCP: O TERMREQ [Open] id 2 len 4
*Apr 15 09:13:29.737: Vi1 LCP: Event[CLOSE] State[Open to Closing]
*Apr 15 09:13:29.737: Vi1 PPP: Phase is TERMINATING
*Apr 15 09:13:29.737:  PPPoE : Shutting down client session
*Apr 15 09:13:29.737: [0]PPPoE 43679: O PADT  R:0015.178a.be16 L:c471.fea7.fce8 Fa4
contiguous pak, size 60
00 15 17 8A BE 16 C4 71 FE A7 FC E8 88 63 11 A7
AA 9F 00 00 00 00 00 00 00 00 00 00 00 00 00 00
00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
00 00 00 00 00 00 00 00 00 00 00 00
*Apr 15 09:13:29.737: %DIALER-6-UNBIND: Interface Vi1 unbound from profile Di1
*Apr 15 09:13:29.737: Vi1 PPP: Block vaccess from being freed [0x10]
*Apr 15 09:13:29.741: Vi1 LCP: Event[DOWN] State[Closing to Initial]
*Apr 15 09:13:29.741: Vi1 PPP: Unlocked by [0x10] Still Locked by [0x0]
*Apr 15 09:13:29.741: Vi1 PPP: Free previously blocked vaccess
*Apr 15 09:13:29.741: Vi1 PPP: Phase is DOWN
*Apr 15 09:13:29.741: PPPoE 43679: I PADT  R:0015.178a.be16 L:c471.fea7.fce8 Fa4
contiguous pak, size 60
C4 71 FE A7 FC E8 00 15 17 8A BE 16 88 63 11 A7
AA 9F 00 12 02 03 00 0E 73 65 73 73 69 6F 6E 20
63 6C 6F 73 65 64 00 00 00 00 00 00 00 00 00 00
00 00 00 00 00 00 00 00 BF 43 DA A6
*Apr 15 09:13:29.741: %LINK-3-UPDOWN: Interface Virtual-Access1, changed state to down
*Apr 15 09:13:41.689: %LINEPROTO-5-UPDOWN: Line protocol on Interface FastEthernet4, changed state to down
*Apr 15 09:13:49.733:  Sending PADI: Interface = FastEthernet4
*Apr 15 09:13:49.733: pppoe_send_padi:
contiguous pak, size 60
Could you, please, clarify - what should i change, to function this schema normaly.

0 Helpful

Go to solution
Juan Perez
Level 1
Level 1
In response to incomf_lankey

‎04-15-2011 06:05 AM

Hello Mikhail,

Please try by adding "ppp authentication chap callin" then collect same debugs. Are you sure you should be using CHAP? Why don't you try using both PAP and CHAP at the same time.

I hope this helps!

Go to solution
incomf_lankey
Level 1
Level 1
In response to Juan Perez

‎04-15-2011 08:32 AM

ISP said, that only CHAP authentication should be used. As i know, using CHAP and PAP all together - is not correct configugation. Сorrect me if I'm wrong.

"ppp authentication chap callin" i already use

0 Helpful

Go to solution
Juan Perez
Level 1
Level 1
In response to incomf_lankey

‎04-15-2011 09:44 AM

Mikhail,

You can have both PAP and CHAP configured under Dialer interface, that should not be a problem. IOS and router will know what to use based on the I CONFREQ messages received from DSL Agg device, based on that CPE will know what to use.

I agree with Marco, issue is due to a lower layer problem, see below:

Vi1 PPP DISC: Lower Layer disconnected

What is the IOS you are running on this device?

Go to solution
Juan Perez
Level 1
Level 1
In response to incomf_lankey

‎04-15-2011 09:55 AM

Sorry, just noticed you are running 15.1(1)T1.

I would recommend upgrading it to 15.1(1)T2 or latest 15.1(3)T1. There is a bug on these boxes running 15.1(1)T1 on which we get a "Vi1 PPP DISC: Lower Layer disconnected" message, it is related to some garbage being sent out.

0 Helpful

Go to solution
incomf_lankey
Level 1
Level 1
In response to Juan Perez

‎04-21-2011 03:39 AM

Marco Vespignani, Juan Perez - thank you for your help!

Problem solved via changing IOS to c880data-universalk9_npe-mz.151-4.M.bin

No working config looks like this:

no aaa new-model

memory-size iomem 10

ip source-route

!

no ipv6 cef

!

!

vpdn enable

!

!

interface FastEthernet0

no ip address

no cdp enable

!

interface FastEthernet1

no ip address

no cdp enable

!

interface FastEthernet2

no ip address

no cdp enable

!

interface FastEthernet3

no ip address

no cdp enable

!

interface FastEthernet4

no ip address

duplex auto

speed auto

pppoe-client dial-pool-number 1

no cdp enable

!

interface Vlan1

description "LAN"

ip address 192.168.10.1 255.255.255.0

ip nat inside

ip virtual-reassembly in

!

interface Dialer1

mtu 1452

ip address negotiated

ip nat outside

ip virtual-reassembly in

encapsulation ppp

load-interval 30

dialer pool 1

dialer-group 1

ppp authentication chap callin

ppp chap hostname LOGIN

ppp chap password 0 PASSWORD

ppp pap sent-username LOGIN password 0 PASSWORD

ppp ipcp dns accept

ppp ipcp route default

no cdp enable

!

ip forward-protocol nd

no ip http server

no ip http secure-server

!

ip nat inside source list NAT interface Dialer1 overload

!

ip access-list extended NAT

permit ip host 192.168.10.254 any

!

logging esm config

dialer-list 1 protocol ip permit

no cdp run

!

cisco#sh ver

Cisco IOS Software, C880 Software (C880DATA-UNIVERSALK9_NPE-M), Version 15.1(4)M, RELEASE SOFTWARE (fc1)

Technical Support: http://www.cisco.com/techsupport

Copyright (c) 1986-2011 by Cisco Systems, Inc.

Compiled Thu 24-Mar-11 20:33 by prod_rel_team

ROM: System Bootstrap, Version 12.4(22r)YB5, RELEASE SOFTWARE (fc1)

cisco_vlc uptime is 59 minutes

System returned to ROM by power-on

System image file is "flash:c880data-universalk9_npe-mz.151-4.M.bin"

Last reload type: Normal Reload

0 Helpful

Go to solution
mavespig
Level 3
Level 3
In response to incomf_lankey

‎04-15-2011 09:19 AM

Hi, 2 interesting things in the debugs:

Now we agree on MTU 1492, good.

*Apr 15 09:13:27.721: Vi1 LCP: O CONFREQ [Starting] id 1 len 14
*Apr 15 09:13:27.721: Vi1 LCP:    MRU 1492 (0x010405D4)
*Apr 15 09:13:27.721: Vi1 LCP: I CONFACK [REQsent] id 1 len 14
*Apr 15 09:13:27.721: Vi1 LCP:    MRU 1492 (0x010405D4)
This is interesting, ISP proposes a MRRU, that is the MTU for multilink bundles:
*Apr 15 09:13:29.717: Vi1 LCP: I CONFREQ [ACKrcvd] id 2 len 36
*Apr 15 09:13:29.717: Vi1 LCP:    MRRU 2048 (0x11040800)
*Apr 15 09:13:29.717: Vi1 LCP:    MultilinkShortSeq (0x1202)
09:13:29.717: Vi1 LCP: O CONFREJ [ACKrcvd] id 2 len 10
We send a CONFREJ because the dialer is not configured for PPP multilink. If you wish you can configured it under the dialer and test it.
The disconnection is actually starting from your side: LCP and authentication are completed, we should start IPCP, but suddenly there is the PPP DISC
*Apr 15 09:13:29.733: Vi1 CHAP: I SUCCESS id 1 len 11 msg is "Welcome"   <<
*Apr 15 09:13:29.737: Vi1 PPP: Phase is FORWARDING, Attempting Forward
*Apr 15 09:13:29.737: Vi1 PPP: Queue IPCP code[1] id[1]
*Apr 15 09:13:29.737: Vi1 PPP DISC: Lower Layer disconnected
I don't see anything unusual in the config, except "ppp ipcp route default", which is not needed.
Can you try to remove it and test?
Marco

Getting Started

Find answers to your questions by entering keywords or phrases in the Search bar above. New here? Use these resources to familiarize yourself with the community:

Customers Also Viewed These Support Documents
Review Cisco Networking products for a $25 gift card