ASR1001-X and ASR920-4SZ: PPPoE doesnt install route

[spadhausen]

Hello:

The very same issue on ASR1001-X with 16.9.8 IPBASE and ASR920-4SZ-A with 17.6.7 ADVMETROACCESS

This is the conf:

!
aaa new-model
!
!
aaa authentication password-prompt Password:
aaa authentication username-prompt Username:
aaa authentication login default local
aaa authentication ppp default group radius
aaa authorization exec default local
aaa authorization network default group radius
aaa accounting exec default start-stop group radius
aaa accounting network default start-stop group radius
!
bba-group pppoe global
virtual-template 1
!
!
!
interface Loopback0
ip address 100.80.2.102 255.255.255.255
no ip redirects
no ip unreachables
no ip proxy-arp
!
interface GigabitEthernet0/0/0
ip address 100.100.100.254 255.255.255.0 (fictional public ip address)
no ip redirects
no ip proxy-arp
media-type rj45
negotiation auto
pppoe enable group global
cdp enable
!

!
interface Virtual-Template1
ip unnumbered Loopback0
no ip redirects
peer default ip address pool DUMMY
ppp mtu adaptive
ppp mtu pppoe unlimited
ppp authentication chap ms-chap ms-chap-v2 pap callin
ppp ipcp dns 185.83.172.10 185.83.172.14
ppp ipcp route default
subscriber-interface

ip local pool DUMMY 10.254.254.1 10.254.254.2sh log

radius server MT_USERMANAGER
address ipv4 100.127.251.200 auth-port 1812 acct-port 1813
key ***
!

The PPPoE client connects fine, receives an IP address but the is no route to the /32 installed on the Router (ASR).

Made a lot of tests with no avail. I cannot even ping from the client, the ip unnumbered Ip address

the IP I want to assign to the client is a public IP 154.62.195.222/32

Here the logs:

Apr 18 12:07:59.473: RADIUS: NAS-IP-Address [4] 6 100.80.2.102
Apr 18 12:07:59.473: RADIUS(00000038): Sending a IPv4 Radius Packet
Apr 18 12:07:59.474: RADIUS(00000038): Started 5 sec timeout
Apr 18 12:07:59.475: RADIUS: Received from id 1645/39 100.127.251.200:1812, Access-Accept, len 94
RADIUS: authenticator 66 70 9C 32 FB 5D 77 16 - 0F 02 50 2F D7 82 4F 62
Apr 18 12:07:59.475: RADIUS: Framed-IP-Address [8] 6 154.62.195.222
Apr 18 12:07:59.475: RADIUS: Framed-IP-Netmask [9] 6 255.255.255.255
Apr 18 12:07:59.475: RADIUS: Framed-Route [22] 34 "154.62.195.222/32 154.62.195.222"
Apr 18 12:07:59.476: RADIUS: Class [25] 10
RADIUS: 15 4B FD CC 9E F1 D7 68 [ Kh]
Apr 18 12:07:59.476: RADIUS: Message-Authenticato[80] 18
RADIUS: 9F 04 9B 7A FD 44 0D F9 00 B0 EF C4 C4 1F 05 E3 [ zD]
Apr 18 12:07:59.476: RADIUS(00000038): Received from id 1645/39
Apr 18 12:07:59.477: ppp40 PPP: Phase is FORWARDING, Attempting Forward
Apr 18 12:07:59.501: %SYS-5-CONFIG_P: Configured programmatically by process VTEMPLATE Background Mgr from console as console
Apr 18 12:07:59.506: [40]PPPoE 40: State LCP_NEGOTIATION Event SSS CONNECT LOCAL
Apr 18 12:07:59.508: %LINK-3-UPDOWN: Interface Virtual-Access3, changed state to up
Apr 18 12:07:59.514: [40]PPPoE 40: Segment (SSS class): UPDATED
Apr 18 12:07:59.514: [40]PPPoE 40: Segment (SSS class): BOUND
Apr 18 12:07:59.514: [40]PPPoE 40: data path set to Virtual Acess
Apr 18 12:07:59.514: [40]PPPoE 40: State LCP_NEGOTIATION Event SSM UPDATED
Apr 18 12:07:59.515: Vi3 PPP: Phase is AUTHENTICATING, Authenticated User
Apr 18 12:07:59.515: Vi3 CHAP: O SUCCESS id 1 len 4
Apr 18 12:07:59.516: Vi3 PPP: Reducing MTU to peer's MRU
Apr 18 12:07:59.522: dyn_attrs->xmit_rate: 100000000 dyn_attrs->rcv_rate: 100000000
Apr 18 12:07:59.523: [40]PPPoE 40: AAA get dynamic attrs
Apr 18 12:07:59.524: Vi3 PPP: Phase is UP
Apr 18 12:07:59.524: Vi3 IPCP: Protocol configured, start CP. state[Initial]
Apr 18 12:07:59.524: Vi3 IPCP: Event[OPEN] State[Initial to Starting]
Apr 18 12:07:59.525: Vi3 IPCP: O CONFREQ [Starting] id 1 len 10
Apr 18 12:07:59.525: Vi3 IPCP: Address 100.80.2.102 (0x030664500266)
Apr 18 12:07:59.525: Vi3 IPCP: Event[UP] State[Starting to REQsent]
Apr 18 12:07:59.525: Vi3 IPCP: I CONFREQ [REQsent] id 3 len 22
Apr 18 12:07:59.526: Vi3 IPCP: Address 0.0.0.0 (0x030600000000)
Apr 18 12:07:59.526: Vi3 IPCP: PrimaryDNS 0.0.0.0 (0x810600000000)
Apr 18 12:07:59.526: Vi3 IPCP: SecondaryDNS 0.0.0.0 (0x830600000000)
Apr 18 12:07:59.526: Vi3 IPCP AUTHOR: Start. Her address 0.0.0.0, we want 0.0.0.0
Apr 18 12:07:59.527: Vi3 IPCP AUTHOR: Done. Her address 0.0.0.0, we want 154.62.195.222
Apr 18 12:07:59.527: Vi3 IPCP: O CONFNAK [REQsent] id 3 len 22
Apr 18 12:07:59.527: Vi3 IPCP: Address 154.62.195.222 (0x03069A3EC3DE)
Apr 18 12:07:59.527: Vi3 IPCP: PrimaryDNS 185.83.172.10 (0x8106B953AC0A)
Apr 18 12:07:59.527: Vi3 IPCP: SecondaryDNS 185.83.172.14 (0x8306B953AC0E)
Apr 18 12:07:59.528: Vi3 IPCP: Event[Receive ConfReq-] State[REQsent to REQsent]
Apr 18 12:07:59.528: Vi3 TAGCP: I CONFREQ [UNKNOWN] id 2 len 4
Apr 18 12:07:59.528: Vi3 LCP: O PROTREJ [Open] id 2 len 10 protocol TAGCP (0x01020004)
Apr 18 12:07:59.528: Vi3 IPCP: I CONFACK [REQsent] id 1 len 10
Apr 18 12:07:59.528: Vi3 IPCP: Address 100.80.2.102 (0x030664500266)
Apr 18 12:07:59.529: Vi3 IPCP: Event[Receive ConfAck] State[REQsent to ACKrcvd]
Apr 18 12:07:59.529: RADIUS/ENCODE(00000038):Orig. component type = PPPoE
Apr 18 12:07:59.530: RADIUS(00000038): Config NAS IP: 100.80.2.102
Apr 18 12:07:59.530: vrfid: [65535] ipv6 tableid : [0]
Apr 18 12:07:59.530: idb is NULL
Apr 18 12:07:59.530: RADIUS(00000038): Config NAS IPv6: ::
Apr 18 12:07:59.530: RADIUS(00000038): sending
Apr 18 12:07:59.530: [40]PPPoE 40: State PTA_BINDING Event STATIC BIND RESPONSE
Apr 18 12:07:59.530: [40]PPPoE 40: Connected PTA
Apr 18 12:07:59.532: RADIUS(00000038): Send Accounting-Request to 100.127.251.200:1813 id 1646/76, len 179
RADIUS: authenticator 2F CF 2F F7 2A 65 BA 38 - AB C0 33 0E AA 93 A3 F3
Apr 18 12:07:59.532: RADIUS: Acct-Session-Id [44] 10 "0000002E"
Apr 18 12:07:59.532: RADIUS: Framed-Protocol [7] 6 PPP [1]
Apr 18 12:07:59.532: RADIUS: User-Name [1] 9 "utente1"
Apr 18 12:07:59.532: RADIUS: Vendor, Cisco [26] 32
Apr 18 12:07:59.532: RADIUS: Cisco AVpair [1] 26 "connect-progress=Call Up"
Apr 18 12:07:59.533: RADIUS: Acct-Authentic [45] 6 RADIUS [1]
Apr 18 12:07:59.533: RADIUS: Acct-Status-Type [40] 6 Start [1]
Apr 18 12:07:59.533: RADIUS: NAS-Port-Type [61] 6 Virtual [5]
Apr 18 12:07:59.534: RADIUS: NAS-Port [5] 6 0
Apr 18 12:07:59.534: RADIUS: NAS-Port-Id [87] 9 "0/0/0/0"
Apr 18 12:07:59.534: RADIUS: Vendor, Cisco [26] 41
Apr 18 12:07:59.534: RADIUS: Cisco AVpair [1] 35 "client-mac-address=c4ad.34bb.dbfa"
Apr 18 12:07:59.534: RADIUS: Class [25] 10
RADIUS: 15 4B FD CC 9E F1 D7 68 [ Kh]
Apr 18 12:07:59.534: RADIUS: Service-Type [6] 6 Framed [2]
Apr 18 12:07:59.534: RADIUS: NAS-IP-Address [4] 6 100.80.2.102
Apr 18 12:07:59.535: RADIUS: Acct-Delay-Time [41] 6 0
Apr 18 12:07:59.535: RADIUS(00000038): Sending a IPv4 Radius Packet
Apr 18 12:07:59.535: RADIUS(00000038): Started 5 sec timeout
Apr 18 12:07:59.536: Vi3 IPCP: I CONFREQ [ACKrcvd] id 4 len 22
Apr 18 12:07:59.536: Vi3 IPCP: Address 154.62.195.222 (0x03069A3EC3DE)
Apr 18 12:07:59.536: Vi3 IPCP: PrimaryDNS 185.83.172.10 (0x8106B953AC0A)
Apr 18 12:07:59.536: Vi3 IPCP: SecondaryDNS 185.83.172.14 (0x8306B953AC0E)
Apr 18 12:07:59.536: Vi3 IPCP: O CONFACK [ACKrcvd] id 4 len 22
Apr 18 12:07:59.536: Vi3 IPCP: Address 154.62.195.222 (0x03069A3EC3DE)
Apr 18 12:07:59.536: Vi3 IPCP: PrimaryDNS 185.83.172.10 (0x8106B953AC0A)
Apr 18 12:07:59.536: Vi3 IPCP: SecondaryDNS 185.83.172.14 (0x8306B953AC0E)
Apr 18 12:07:59.537: Vi3 IPCP: Event[Receive ConfReq+] State[ACKrcvd to Open]
Apr 18 12:07:59.543: RADIUS: Received from id 1646/76 100.127.251.200:1813, Accounting-response, len 60
RADIUS: authenticator 89 B1 F1 90 75 8E EA A1 - 44 8C 82 75 84 57 F7 F1
Apr 18 12:07:59.543: RADIUS: User-Name [1] 9 "utente1"
Apr 18 12:07:59.543: RADIUS: NAS-Port-Type [61] 6 Virtual [5]
Apr 18 12:07:59.543: RADIUS: NAS-Port-Id [87] 9 "0/0/0/0"
Apr 18 12:07:59.544: RADIUS: NAS-IP-Address [4] 6 100.80.2.102
Apr 18 12:07:59.544: RADIUS: Acct-Session-Id [44] 10 "0000002E"
Apr 18 12:07:59.562: Vi3 IPCP: State is Open
Apr 18 12:07:59.562: ppp_session_ntfy, topswidb Vi3, va Vi3, platform notify 0
Apr 18 12:07:59.566: Vi3 IPCP: Install default route thru 154.62.195.222
Apr 18 12:07:59.566: Vi3 Added to neighbor route AVL tree: topoid 185404173, address 154.62.195.222
Apr 18 12:07:59.566: Vi3 IPCP: Route not installed to 154.62.195.222
Apr 18 12:08:00.216: %LINEPROTO-5-UPDOWN: Line protocol on Interface Virtual-Access3, changed state to up
Apr 18 12:08:00.221: PPPoE : ipfib_encapstr prepared

[Yunus Emre DEV]

Hi

Send full configs in asr1k and asr9k let's examine in detail

[MHM Cisco World]

aaa authorization network default group radius <<- remove this 

And add under interface in both server and clinet 
encap ppp 

then check 

MHM

Ppp encap

[spadhausen]

Ok:

I removed aaa authorization network default group radius

Now the IP ADDR is taken from the local pool, not from the radius anymore, I mean it ignored the radius framed ip address and took an IP from the local pool.

however no route installed.

Apr 19 15:28:33.280: ppp47 PPP: Phase is ESTABLISHING
Apr 19 15:28:33.282: ppp47 PPP: Using vpn set call direction
Apr 19 15:28:33.282: ppp47 PPP: Treating connection as a callin
Apr 19 15:28:33.282: ppp47 PPP: Session handle[300002F] Session id[47]
Apr 19 15:28:33.282: ppp47 LCP: Event[OPEN] State[Initial to Starting]
Apr 19 15:28:33.282: ppp47 PPP LCP: Enter passive mode, state[Stopped]
Apr 19 15:28:33.377: ppp47 LCP: I CONFREQ [Stopped] id 7 len 14
Apr 19 15:28:33.377: ppp47 LCP: MRU 1480 (0x010405C8)
Apr 19 15:28:33.377: ppp47 LCP: MagicNumber 0x25E29196 (0x050625E29196)
Apr 19 15:28:33.378: ppp47 LCP: O CONFREQ [Stopped] id 1 len 15
Apr 19 15:28:33.378: ppp47 LCP: AuthProto CHAP (0x0305C22305)
Apr 19 15:28:33.378: ppp47 LCP: MagicNumber 0x347CE6DB (0x0506347CE6DB)
Apr 19 15:28:33.379: ppp47 LCP: O CONFACK [Stopped] id 7 len 14
Apr 19 15:28:33.379: ppp47 LCP: MRU 1480 (0x010405C8)
Apr 19 15:28:33.379: ppp47 LCP: MagicNumber 0x25E29196 (0x050625E29196)
Apr 19 15:28:33.380: ppp47 LCP: Event[Receive ConfReq+] State[Stopped to ACKsent]
Apr 19 15:28:33.380: ppp47 LCP: I CONFACK [ACKsent] id 1 len 15
Apr 19 15:28:33.380: ppp47 LCP: AuthProto CHAP (0x0305C22305)
Apr 19 15:28:33.381: ppp47 LCP: MagicNumber 0x347CE6DB (0x0506347CE6DB)
Apr 19 15:28:33.381: ppp47 LCP: Event[Receive ConfAck] State[ACKsent to Open]
Apr 19 15:28:33.386: ppp47 PPP: Phase is AUTHENTICATING, by this end
Apr 19 15:28:33.387: ppp47 CHAP: O CHALLENGE id 1 len 30 from "ASR920_RA"
Apr 19 15:28:33.387: ppp47 LCP: State is Open
Apr 19 15:28:33.390: ppp47 CHAP: I RESPONSE id 1 len 28 from "utente1"
Apr 19 15:28:33.391: ppp47 PPP: Phase is FORWARDING, Attempting Forward
Apr 19 15:28:33.392: ppp47 PPP: Phase is AUTHENTICATING, Unauthenticated User
Apr 19 15:28:33.396: ppp47 PPP: Phase is FORWARDING, Attempting Forward
Apr 19 15:28:33.406: %SYS-5-CONFIG_P: Configured programmatically by process VTEMPLATE Background Mgr from console as console
Apr 19 15:28:33.413: %LINK-3-UPDOWN: Interface Virtual-Access3, changed state to up
Apr 19 15:28:33.420: Vi3 PPP: Phase is AUTHENTICATING, Authenticated User
Apr 19 15:28:33.420: Vi3 CHAP: O SUCCESS id 1 len 4
Apr 19 15:28:33.421: Vi3 PPP: Reducing MTU to peer's MRU
Apr 19 15:28:33.428: Vi3 PPP: Phase is UP
Apr 19 15:28:33.428: Vi3 IPCP: Protocol configured, start CP. state[Initial]
Apr 19 15:28:33.428: Vi3 IPCP: Event[OPEN] State[Initial to Starting]
Apr 19 15:28:33.429: Vi3 IPCP: O CONFREQ [Starting] id 1 len 10
Apr 19 15:28:33.429: Vi3 IPCP: Address 0.0.0.0 (0x030600000000)
Apr 19 15:28:33.430: Vi3 IPCP: Event[UP] State[Starting to REQsent]
Apr 19 15:28:33.431: Vi3 IPCP: I CONFREQ [REQsent] id 7 len 22
Apr 19 15:28:33.431: Vi3 IPCP: Address 0.0.0.0 (0x030600000000)
Apr 19 15:28:33.431: Vi3 IPCP: PrimaryDNS 0.0.0.0 (0x810600000000)
Apr 19 15:28:33.431: Vi3 IPCP: SecondaryDNS 0.0.0.0 (0x830600000000)
Apr 19 15:28:33.432: Vi3 IPCP AUTHOR: Done. Her address 0.0.0.0, we want 0.0.0.0
Apr 19 15:28:33.432: Vi3 IPCP: Pool returned 10.254.254.1
Apr 19 15:28:33.432: Vi3 IPCP: O CONFNAK [REQsent] id 7 len 22
Apr 19 15:28:33.432: Vi3 IPCP: Address 10.254.254.1 (0x03060AFEFE01)
Apr 19 15:28:33.433: Vi3 IPCP: PrimaryDNS 185.83.172.10 (0x8106B953AC0A)
Apr 19 15:28:33.433: Vi3 IPCP: SecondaryDNS 185.83.172.14 (0x8306B953AC0E)
Apr 19 15:28:33.433: Vi3 IPCP: Event[Receive ConfReq-] State[REQsent to REQsent]
Apr 19 15:28:33.434: Vi3 TAGCP: I CONFREQ [UNKNOWN] id 4 len 4
Apr 19 15:28:33.434: Vi3 LCP: O PROTREJ [Open] id 2 len 10 protocol TAGCP (0x01040004)
Apr 19 15:28:33.435: Vi3 IPCP: I CONFREJ [REQsent] id 1 len 10
Apr 19 15:28:33.435: Vi3 IPCP: Address 0.0.0.0 (0x030600000000)
Apr 19 15:28:33.436: Vi3 IPCP: O CONFREQ [REQsent] id 2 len 4
Apr 19 15:28:33.436: Vi3 IPCP: Event[Receive ConfNak/Rej] State[REQsent to REQsent]
Apr 19 15:28:33.437: Vi3 IPCP: I CONFREQ [REQsent] id 8 len 22
Apr 19 15:28:33.437: Vi3 IPCP: Address 10.254.254.1 (0x03060AFEFE01)
Apr 19 15:28:33.437: Vi3 IPCP: PrimaryDNS 185.83.172.10 (0x8106B953AC0A)
Apr 19 15:28:33.437: Vi3 IPCP: SecondaryDNS 185.83.172.14 (0x8306B953AC0E)
Apr 19 15:28:33.438: Vi3 IPCP: O CONFACK [REQsent] id 8 len 22
Apr 19 15:28:33.438: Vi3 IPCP: Address 10.254.254.1 (0x03060AFEFE01)
Apr 19 15:28:33.438: Vi3 IPCP: PrimaryDNS 185.83.172.10 (0x8106B953AC0A)
Apr 19 15:28:33.438: Vi3 IPCP: SecondaryDNS 185.83.172.14 (0x8306B953AC0E)
Apr 19 15:28:33.439: Vi3 IPCP: Event[Receive ConfReq+] State[REQsent to ACKsent]
Apr 19 15:28:33.439: Vi3 IPCP: I CONFACK [ACKsent] id 2 len 4
Apr 19 15:28:33.439: Vi3 IPCP: Event[Receive ConfAck] State[ACKsent to Open]
Apr 19 15:28:33.441: Vi3 IPCP: State is Open
Apr 19 15:28:33.444: Vi3 Added to neighbor route AVL tree: topoid 185404173, address 10.254.254.1
Apr 19 15:28:33.444: Vi3 IPCP: Route not installed to 10.254.254.1
Apr 19 15:28:34.120: %LINEPROTO-5-UPDOWN: Line protocol on Interface Virtual-Access3, changed state to up

[MHM Cisco World]

Did you add 

Encapsulation ppp <<- under virtual and dial interface?

MHM

[spadhausen]

Yes, even if I added it, it didnt appear in the configuration, maybe was an implicit setting.

[MHM Cisco World]

In pppoe client route-table' you will see 

1- IP client get from pool server 

2- IP of LO of server 

3- defualt route with next-hop IP of LO of server

So can you confirm which of these three appear and which not appear.

MHM

[spadhausen]

Hello, Happy Easter.

On the client I get:

the client ip address: 154.62.195.222/32

the "remote address" 100.80.2.102 (only if I set ip unnumbered loop0; if I set ip address negotiated I dont get any remote address IP)

on the client is installed a default route via pppoe.

That is correct. The issue I assume is on the ASR that doesnt have a route to 154.62.195.222

[spadhausen]

I made further tests:

I startup the connection

I remove the ip unnumbered loopback and add it again, it installs the route!

SR920_RA(config-if)#
Apr 20 15:55:46.585: Vi3 Deleted neighbor route from AVL tree: topoid 185404173, address 154.62.195.222
Apr 20 15:55:46.586: Vi3 IPCP: Remove route to 154.62.195.222
Apr 20 15:55:46.669: %SYS-5-CONFIG_P: Configured programmatically by process VTEMPLATE Background Mgr from console as console
Apr 20 15:55:46.673: %SYS-5-CONFIG_P: Configured programmatically by process VTEMPLATE Background Mgr from console as console
Apr 20 15:55:46.676: %SYS-5-CONFIG_P: Configured programmatically by process VTEMPLATE Background Mgr from console as console
Apr 20 15:55:46.680: %SYS-5-CONFIG_P: Configured programmatically by process VTEMPLATE Background Mgr from console as console
Apr 20 15:55:46.684: %SYS-5-CONFIG_P: Configured programmatically by process VTEMPLATE Background Mgr from console as console
Apr 20 15:55:46.687: %SYS-5-CONFIG_P: Configured programmatically by process VTEMPLATE Background Mgr from console as console
Apr 20 15:55:46.690: %SYS-5-CONFIG_P: Configured programmatically by process VTEMPLATE Background Mgr from console as console
Apr 20 15:55:46.698: %SYS-5-CONFIG_P: Configured programmatically by process VTEMPLATE Background Mgr from console as console
Apr 20 15:56:01.566: Vi3 LCP: I TERMREQ [Open] id 2 len 25
Apr 20 15:56:01.567: Vi3 LCP: I TERMREQ [Open] id 2 len 25
Apr 20 15:56:01.567: Vi3 LCP: (0x61646D696E6973747261746F72207265)
Apr 20 15:56:01.567: Vi3 LCP: (0x7175657374)
Apr 20 15:56:01.567: Vi3 PPP DISC: Received LCP TERMREQ from peer
Apr 20 15:56:01.568: Vi3 PPP: Sending Acct Event[Down] id[5C]
Apr 20 15:56:01.569: PPP: NET STOP send to AAA.
Apr 20 15:56:01.569: Vi3 PPP: Phase is TERMINATING
Apr 20 15:56:01.569: ppp_session_ntfy delete, topswidb Vi3, va Vi3, platform notify 0
Apr 20 15:56:01.571: Vi3 IPCP: Event[DOWN] State[Open to Starting]
Apr 20 15:56:01.572: Vi3 IPCP: Event[CLOSE] State[Starting to Initial]
Apr 20 15:56:01.573: Vi3 LCP: O TERMACK [Open] id 2 len 4
Apr 20 15:56:01.574: Vi3 LCP: Event[Receive TermReq] State[Open to Stopping]
Apr 20 15:56:01.580: Vi3 PPP: Block vaccess from being freed [0x10]
Apr 20 15:56:01.581: Vi3 LCP: Event[DOWN] State[Stopping to Starting]
Apr 20 15:56:01.581: ppp_session_ntfy delete, topswidb Vi3, va Vi3, platform notify 0
Apr 20 15:56:01.582: Vi3 PPP: Unlocked by [0x10] Still Locked by [0x0]
Apr 20 15:56:01.582: Vi3 PPP: Free previously blocked vaccess
Apr 20 15:56:01.582: Vi3 PPP: Phase is DOWN
Apr 20 15:56:01.583: %LINK-3-UPDOWN: Interface Virtual-Access3, changed state to down
Apr 20 15:56:02.280: %LINEPROTO-5-UPDOWN: Line protocol on Interface Virtual-Access3, changed state to down
Apr 20 15:56:02.288: %SYS-5-CONFIG_P: Configured programmatically by process VTEMPLATE Background Mgr from console as console
Apr 20 15:56:03.811: PPP: Alloc Context [6F1EF3E4]
Apr 20 15:56:03.812: ppp68 PPP: Phase is ESTABLISHING
Apr 20 15:56:03.813: ppp68 PPP: Using vpn set call direction
Apr 20 15:56:03.813: ppp68 PPP: Treating connection as a callin
Apr 20 15:56:03.813: ppp68 PPP: Session handle[34000044] Session id[68]
Apr 20 15:56:03.813: ppp68 LCP: Event[OPEN] State[Initial to Starting]
Apr 20 15:56:03.813: ppp68 PPP LCP: Enter passive mode, state[Stopped]
Apr 20 15:56:03.897: ppp68 LCP: I CONFREQ [Stopped] id 3 len 14
Apr 20 15:56:03.897: ppp68 LCP: MRU 1480 (0x010405C8)
Apr 20 15:56:03.897: ppp68 LCP: MagicNumber 0x16849561 (0x050616849561)
Apr 20 15:56:03.897: ppp68 LCP: O CONFREQ [Stopped] id 1 len 15
Apr 20 15:56:03.897: ppp68 LCP: AuthProto CHAP (0x0305C22305)
Apr 20 15:56:03.897: ppp68 LCP: MagicNumber 0x39BD4966 (0x050639BD4966)
Apr 20 15:56:03.898: ppp68 LCP: O CONFACK [Stopped] id 3 len 14
Apr 20 15:56:03.898: ppp68 LCP: MRU 1480 (0x010405C8)
Apr 20 15:56:03.898: ppp68 LCP: MagicNumber 0x16849561 (0x050616849561)
Apr 20 15:56:03.898: ppp68 LCP: Event[Receive ConfReq+] State[Stopped to ACKsent]
Apr 20 15:56:03.900: ppp68 LCP: I CONFACK [ACKsent] id 1 len 15
Apr 20 15:56:03.900: ppp68 LCP: AuthProto CHAP (0x0305C22305)
Apr 20 15:56:03.900: ppp68 LCP: MagicNumber 0x39BD4966 (0x050639BD4966)
Apr 20 15:56:03.900: ppp68 LCP: Event[Receive ConfAck] State[ACKsent to Open]
Apr 20 15:56:03.913: ppp68 PPP: Phase is AUTHENTICATING, by this end
Apr 20 15:56:03.913: ppp68 CHAP: O CHALLENGE id 1 len 30 from "ASR920_RA"
Apr 20 15:56:03.914: ppp68 LCP: State is Open
Apr 20 15:56:03.915: ppp68 CHAP: I RESPONSE id 1 len 28 from "utente1"
Apr 20 15:56:03.915: ppp68 PPP: Phase is FORWARDING, Attempting Forward
Apr 20 15:56:03.917: ppp68 PPP: Phase is AUTHENTICATING, Unauthenticated User
Apr 20 15:56:03.921: ppp68 PPP: Phase is FORWARDING, Attempting Forward
Apr 20 15:56:03.945: %SYS-5-CONFIG_P: Configured programmatically by process VTEMPLATE Background Mgr from console as console
Apr 20 15:56:03.953: %LINK-3-UPDOWN: Interface Virtual-Access4, changed state to up
Apr 20 15:56:03.960: Vi4 PPP: Phase is AUTHENTICATING, Authenticated User
Apr 20 15:56:03.960: Vi4 CHAP: O SUCCESS id 1 len 4
Apr 20 15:56:03.961: Vi4 PPP: Reducing MTU to peer's MRU
Apr 20 15:56:03.969: Vi4 PPP: Phase is UP
Apr 20 15:56:03.969: Vi4 IPCP: Protocol configured, start CP. state[Initial]
Apr 20 15:56:03.969: Vi4 IPCP: Event[OPEN] State[Initial to Starting]
Apr 20 15:56:03.970: Vi4 IPCP: O CONFREQ [Starting] id 1 len 10
Apr 20 15:56:03.970: Vi4 IPCP: Address 100.80.2.102 (0x030664500266)
Apr 20 15:56:03.970: Vi4 IPCP: Event[UP] State[Starting to REQsent]
Apr 20 15:56:03.970: Vi4 IPCP: I CONFREQ [REQsent] id 3 len 22
Apr 20 15:56:03.971: Vi4 IPCP: Address 0.0.0.0 (0x030600000000)
Apr 20 15:56:03.971: Vi4 IPCP: PrimaryDNS 0.0.0.0 (0x810600000000)
Apr 20 15:56:03.971: Vi4 IPCP: SecondaryDNS 0.0.0.0 (0x830600000000)
Apr 20 15:56:03.971: Vi4 IPCP AUTHOR: Start. Her address 0.0.0.0, we want 0.0.0.0
Apr 20 15:56:03.971: Vi4 IPCP AUTHOR: Done. Her address 0.0.0.0, we want 154.62.195.222
Apr 20 15:56:03.972: Vi4 IPCP: O CONFNAK [REQsent] id 3 len 22
Apr 20 15:56:03.972: Vi4 IPCP: Address 154.62.195.222 (0x03069A3EC3DE)
Apr 20 15:56:03.972: Vi4 IPCP: PrimaryDNS 185.83.172.10 (0x8106B953AC0A)
Apr 20 15:56:03.972: Vi4 IPCP: SecondaryDNS 185.83.172.14 (0x8306B953AC0E)
Apr 20 15:56:03.972: Vi4 IPCP: Event[Receive ConfReq-] State[REQsent to REQsent]
Apr 20 15:56:03.973: Vi4 IPV6CP: I CONFREQ [UNKNOWN] id 2 len 14
Apr 20 15:56:03.973: Vi4 IPV6CP: Interface-Id 0000:0000:0000:0008 (0x010A0000000000000008)
Apr 20 15:56:03.973: Vi4 LCP: O PROTREJ [Open] id 2 len 20 protocol IPV6CP (0x0102000E010A0000000000000008)
Apr 20 15:56:03.973: Vi4 TAGCP: I CONFREQ [UNKNOWN] id 2 len 4
Apr 20 15:56:03.974: Vi4 LCP: O PROTREJ [Open] id 3 len 10 protocol TAGCP (0x01020004)
Apr 20 15:56:03.974: Vi4 IPCP: I CONFACK [REQsent] id 1 len 10
Apr 20 15:56:03.974: Vi4 IPCP: Address 100.80.2.102 (0x030664500266)
Apr 20 15:56:03.974: Vi4 IPCP: Event[Receive ConfAck] State[REQsent to ACKrcvd]
Apr 20 15:56:03.975: Vi4 IPCP: I CONFREQ [ACKrcvd] id 4 len 22
Apr 20 15:56:03.975: Vi4 IPCP: Address 154.62.195.222 (0x03069A3EC3DE)
Apr 20 15:56:03.975: Vi4 IPCP: PrimaryDNS 185.83.172.10 (0x8106B953AC0A)
Apr 20 15:56:03.975: Vi4 IPCP: SecondaryDNS 185.83.172.14 (0x8306B953AC0E)
Apr 20 15:56:03.975: Vi4 IPCP: O CONFACK [ACKrcvd] id 4 len 22
Apr 20 15:56:03.975: Vi4 IPCP: Address 154.62.195.222 (0x03069A3EC3DE)
Apr 20 15:56:03.975: Vi4 IPCP: PrimaryDNS 185.83.172.10 (0x8106B953AC0A)
Apr 20 15:56:03.975: Vi4 IPCP: SecondaryDNS 185.83.172.14 (0x8306B953AC0E)
Apr 20 15:56:03.976: Vi4 IPCP: Event[Receive ConfReq+] State[ACKrcvd to Open]
Apr 20 15:56:03.979: Vi4 IPCP: State is Open
Apr 20 15:56:03.979: ppp_session_ntfy, topswidb Vi4, va Vi4, platform notify 0
Apr 20 15:56:03.983: Vi4 Added to neighbor route AVL tree: topoid 185404173, address 154.62.195.222
Apr 20 15:56:03.983: Vi4 IPCP: Route not installed to 154.62.195.222
Apr 20 15:56:04.660: %LINEPROTO-5-UPDOWN: Line protocol on Interface Virtual-Access4, changed state to upip unn
ASR920_RA(config-if)#ip unnumbered loop0
ASR920_RA(config-if)#
Apr 20 15:56:18.518: %SYS-5-CONFIG_P: Configured programmatically by process VTEMPLATE Background Mgr from console as console
Apr 20 15:56:18.521: %SYS-5-CONFIG_P: Configured programmatically by process VTEMPLATE Background Mgr from console as console
Apr 20 15:56:18.525: %SYS-5-CONFIG_P: Configured programmatically by process VTEMPLATE Background Mgr from console as console
Apr 20 15:56:18.529: %SYS-5-CONFIG_P: Configured programmatically by process VTEMPLATE Background Mgr from console as console
Apr 20 15:56:18.532: %SYS-5-CONFIG_P: Configured programmatically by process VTEMPLATE Background Mgr from console as console
Apr 20 15:56:18.536: %SYS-5-CONFIG_P: Configured programmatically by process VTEMPLATE Background Mgr from console as console
Apr 20 15:56:18.538: %SYS-5-CONFIG_P: Configured programmatically by process VTEMPLATE Background Mgr from console as console
Apr 20 15:56:18.549: %SYS-5-CONFIG_P: Configured programmatically by process VTEMPLATE Background Mgr from console as consoleip unnumbered bdi10
ASR920_RA(config-if)#
Apr 20 15:56:25.010: ppp_session_ntfy delete, topswidb Vi4, va Vi4, platform notify 0
Apr 20 15:56:25.034: ppp_session_ntfy delete, topswidb Vi4, va Vi4, platform notify 0
Apr 20 15:56:25.038: Vi4 IPCP: Event[DOWN] State[Open to Starting]
Apr 20 15:56:25.038: Vi4 IPCP: Event[CLOSE] State[Starting to Initial]
Apr 20 15:56:25.038: Vi4 IPCP: Event[OPEN] State[Initial to Starting]
Apr 20 15:56:25.041: Vi4 IPCP: O CONFREQ [Starting] id 2 len 10
Apr 20 15:56:25.041: Vi4 IPCP: Address 100.80.7.254 (0x0306645007FE)
Apr 20 15:56:25.042: Vi4 IPCP: Event[UP] State[Starting to REQsent]
Apr 20 15:56:25.043: Vi4 Deleted neighbor route from AVL tree: topoid 185404173, address 154.62.195.222
Apr 20 15:56:25.044: Vi4 IPCP: Remove route to 154.62.195.222
Apr 20 15:56:25.057: Vi4 IPCP: I CONFREQ [REQsent] id 5 len 22
Apr 20 15:56:25.057: Vi4 IPCP: Address 154.62.195.222 (0x03069A3EC3DE)
Apr 20 15:56:25.057: Vi4 IPCP: PrimaryDNS 0.0.0.0 (0x810600000000)
Apr 20 15:56:25.057: Vi4 IPCP: SecondaryDNS 0.0.0.0 (0x830600000000)
Apr 20 15:56:25.057: Vi4 IPCP AUTHOR: Start. Her address 154.62.195.222, we want 0.0.0.0
Apr 20 15:56:25.058: Vi4 IPCP AUTHOR: Done. Her address 154.62.195.222, we want 154.62.195.222
Apr 20 15:56:25.058: Vi4 IPCP: O CONFNAK [REQsent] id 5 len 16
Apr 20 15:56:25.058: Vi4 IPCP: PrimaryDNS 185.83.172.10 (0x8106B953AC0A)
Apr 20 15:56:25.058: Vi4 IPCP: SecondaryDNS 185.83.172.14 (0x8306B953AC0E)
Apr 20 15:56:25.059: Vi4 IPCP: Event[Receive ConfReq-] State[REQsent to REQsent]
Apr 20 15:56:25.059: Vi4 IPCP: I CONFNAK [REQsent] id 2 len 10
Apr 20 15:56:25.059: Vi4 IPCP: Address 100.80.2.102 (0x030664500266)
Apr 20 15:56:25.059: Vi4 IPCP: O CONFREQ [REQsent] id 3 len 4
Apr 20 15:56:25.060: Vi4 IPCP: Event[Receive ConfNak/Rej] State[REQsent to REQsent]
Apr 20 15:56:25.061: Vi4 IPCP: I CONFREQ [REQsent] id 6 len 22
Apr 20 15:56:25.061: Vi4 IPCP: Address 154.62.195.222 (0x03069A3EC3DE)
Apr 20 15:56:25.061: Vi4 IPCP: PrimaryDNS 185.83.172.10 (0x8106B953AC0A)
Apr 20 15:56:25.061: Vi4 IPCP: SecondaryDNS 185.83.172.14 (0x8306B953AC0E)
Apr 20 15:56:25.062: Vi4 IPCP: O CONFACK [REQsent] id 6 len 22
Apr 20 15:56:25.062: Vi4 IPCP: Address 154.62.195.222 (0x03069A3EC3DE)
Apr 20 15:56:25.062: Vi4 IPCP: PrimaryDNS 185.83.172.10 (0x8106B953AC0A)
Apr 20 15:56:25.062: Vi4 IPCP: SecondaryDNS 185.83.172.14 (0x8306B953AC0E)
Apr 20 15:56:25.063: Vi4 IPCP: Event[Receive ConfReq+] State[REQsent to ACKsent]
Apr 20 15:56:25.063: Vi4 IPCP: I CONFACK [ACKsent] id 3 len 4
Apr 20 15:56:25.063: Vi4 IPCP: Event[Receive ConfAck] State[ACKsent to Open]
Apr 20 15:56:25.065: Vi4 IPCP: State is Open
Apr 20 15:56:25.066: ppp_session_ntfy, topswidb Vi4, va Vi4, platform notify 0
Apr 20 15:56:25.072: Vi4 Added to neighbor route AVL tree: topoid 185404173, address 154.62.195.222
Apr 20 15:56:25.072: Vi4 IPCP: Install route to 154.62.195.222

I startup the ocnnection with ip unnumbered loop0

then I change to ip unnumbered bdi10 and it installs the route.

I can have another interface and apply ip unnumbered when the connection is already up and it installs the route.

I mean I start with ip unnumbered bdi10 and then change to loop0 and it installs the route on a unnumbered change!

[Yunus Emre DEV]

mate, I don't think you understand what's written,
send the full configuration here, including the route tables in full asr1k and asr9k, you can delete or change ip's and private information such as password.

now you want the engineers here to walk down the dark road without stumbling, it's ridiculous.

no one can help you in this way, please do not take people's time and time in this way.!!!

[spadhausen]

Cool down man!

You are not neither polite nor nice. If this thread is not interesting for you, you are free not to answer or reply.

[Yunus Emre DEV]

I speak for everyone,
people respect you and try to help you here, this is out of respect for you, you have to do what those who write respectful answers to you say to help you, if you want support.
This is called respect.

Cisco engineers are also writing answers here and other people are writing answers here, everyone respects each other in this community, but people who ask for help should respect the people who support the community.

people cannot know your topology, your system, where you run what you run, your designs, even if you open a case to cisco tac engineers, they first want to see your tech support files and debug files and your short custom topology to analyse your topology and system.

why I say this, because I work at a different vendor and these are the human factor problems that we constantly encounter

[spadhausen]

Hello,

I have posted all the relevant configuration here of my units to reproduce the conf in a lab. As I am trying to to, both on a lab, and on a live network.

I cannot post the whole routing table since I have a lot of routes installed and the very full configuration.

I posted all the necessary sections to reproduce the config and the issue. I didnt desrespected anyone here. Also I have posted further results of the tests.

I have followed all the advices and posted the results here, to show what happens and hoping to find a solution.

I hope you understand.

[spadhausen · Accepted solution]

Hello, I have found the issue.

With the very same conf, I activated trial mode the ADVIPSERVICES on 16.9.8 and it worked flawlessy (1001-X)

The ASR920 with the ADVMETRO IOS dont work, maybe it is not supported.