Cisco 1841 with PPP Internet link down

khanhnguyenquoc · ‎10-01-2014

Hi brothers, please help.

We have Cisco 1841 with ATM card connect to Internet . Some days one Dialer interface of ISP is down and only restart router to get Dialer interface up again. I check debug PPP negotiation & see that no inbound packets from ISP.

I really need to fix this issue permanently, not need to restart router to recover Internet.

Thanks!

---------------

All line protocol from ATM0/0/0 & Dialer interfaces are up, but Dialer interface cannot get allocated IP addresses.

-------------------------------

interface ATM0/0/0
description ISP Internet
no ip address
no atm ilmi-keepalive
dsl operating-mode itu-dmt
pvc 0/38
encapsulation aal5mux ppp dialer
dialer pool-member 2

interface Dialer2
description ISP Internet 1
mtu 1492
bandwidth 8000
ip address negotiated
ip nat outside
no ip virtual-reassembly in
encapsulation ppp
dialer pool 2
dialer-group 2
ppp authentication chap callin
ppp chap hostname xxx
ppp chap password 7 xxx
no cdp enable

wr1#show ip int br
Interface                  IP-Address      OK? Method Status                Protocol

ATM0/0/0                   unassigned      YES NVRAM up                    up
ATM0/1/0                   unassigned      YES NVRAM up                    up
Dialer2                    unassigned      YES IPCP   up                    up

wr1#show int atm0/0/0
ATM0/0/0 is up, line protocol is up
Hardware is HWIC-DSLSAR (with Alcatel ADSL Module), address is 001f.9e87.xxx(bia 001f.9e87.xxx)
Description: ISP Internet
MTU 4470 bytes, sub MTU 4470, BW 832 Kbit/sec, DLY 610 usec,
     reliability 255/255, txload 1/255, rxload 1/255
Encapsulation ATM, loopback not set
Keepalive not supported
Encapsulation(s): AAL5
23 maximum active VCs, 256 VCs per VP, 1 current VCCs
VC Auto Creation Disabled.
VC idle disconnect time: 300 seconds
Last input never, output 23:48:07, output hang never
Last clearing of "show interface" counters never
Input queue: 0/75/0/0 (size/max/drops/flushes); Total output drops: 1084
Queueing strategy: Per VC Queueing
5 minute input rate 0 bits/sec, 0 packets/sec
5 minute output rate 0 bits/sec, 0 packets/sec
     58124 packets input, 30790858 bytes, 0 no buffer
     Received 0 broadcasts (0 IP multicasts)
     0 runts, 0 giants, 0 throttles
     0 input errors, 348 CRC, 0 frame, 0 overrun, 0 ignored, 0 abort
     51848 packets output, 16773091 bytes, 0 underruns
     6 output errors, 0 collisions, 0 interface resets
     0 unknown protocol drops
     0 output buffer failures, 0 output buffers swapped out
wr1#show int dia2
Dialer2 is up, line protocol is up (spoofing)
Hardware is Unknown
Description: ISP Internet
Internet address will be negotiated using IPCP
MTU 1492 bytes, BW 8000 Kbit/sec, DLY 20000 usec,
     reliability 255/255, txload 1/255, rxload 1/255
Encapsulation PPP, LCP Closed, loopback not set
Keepalive set (10 sec)
DTR is pulsed for 1 seconds on reset
Interface is bound to Vi2
Last input never, output never, output hang never
Last clearing of "show interface" counters 1d00h
Input queue: 0/75/0/0 (size/max/drops/flushes); Total output drops: 0
Queueing strategy: Class-based queueing
Output queue: 0/1000/0 (size/max total/drops)
5 minute input rate 0 bits/sec, 0 packets/sec
5 minute output rate 0 bits/sec, 0 packets/sec
     52937 packets input, 30470954 bytes
     52931 packets output, 17287959 bytes
Bound to:
Virtual-Access2 is up, line protocol is down
Hardware is Virtual Access interface
MTU 1492 bytes, BW 8000 Kbit/sec, DLY 20000 usec,
     reliability 255/255, txload 1/255, rxload 1/255
Encapsulation PPP, LCP REQsent
PPPoATM vaccess, cloned from Dialer2
Vaccess status 0x44
Bound to ATM0/0/0 VCD: 1, VPI: 0, VCI: 38, loopback not set
Keepalive set (10 sec)
DTR is pulsed for 5 seconds on reset
Interface is bound to Di2 (Encapsulation PPP)
Last input 00:00:09, output never, output hang never
Last clearing of "show interface" counters 1d00h
Input queue: 0/75/0/0 (size/max/drops/flushes); Total output drops: 40403
Queueing strategy: Class-based queueing
Output queue: 65/1000/0 (size/max total/drops)
5 minute input rate 0 bits/sec, 0 packets/sec
5 minute output rate 0 bits/sec, 0 packets/sec
     58124 packets input, 30591958 bytes, 0 no buffer
     Received 0 broadcasts (0 IP multicasts)
     0 runts, 0 giants, 0 throttles
     0 input errors, 0 CRC, 0 frame, 0 overrun, 0 ignored, 0 abort
     93405 packets output, 17935575 bytes, 0 underruns
     0 output errors, 0 collisions, 0 interface resets
     0 unknown protocol drops
     0 output buffer failures, 0 output buffers swapped out
     0 carrier transitions

syslog for debug ppp negotiation:

2014-09-10 08:49:31   Local7.Notice   local IP address   801: wr1.lon: .Sep 10 01:51:33.803: %LINEPROTO-5-UPDOWN: Line protocol on Interface ATM0/0/0, changed state to down
2014-09-10 08:49:32   Local7.Notice   local IP address   804: wr1.lon: .Sep 10 01:51:35.247: %TRACKING-5-STATE: 3 ip sla 3 state Up->Down
...
2014-09-10 09:13:40   Local7.Error   local IP address   808: wr1.lon: Sep 10 02:15:42.646: %LINK-3-UPDOWN: Interface ATM0/0/0, changed state to up
2014-09-10 09:13:40   Local7.Notice   local IP address   809: wr1.lon: Sep 10 02:15:43.646: %LINEPROTO-5-UPDOWN: Line protocol on Interface ATM0/0/0, changed state to up
2014-09-10 09:13:45   Local7.Error   local IP address   810: wr1.lon: Sep 10 02:15:48.957: %LINK-3-UPDOWN: Interface Virtual-Access2, changed state to up
2014-09-10 09:13:45   Local7.Debug   local IP address   811: wr1.lon: Sep 10 02:15:48.961: Interface Virtual-Access2 max_reserved_bandwidth config will not
2014-09-10 09:13:45   Local7.Debug   local IP address   812: wr1.lon: take effect on the queueing features configured via service-policy
2014-09-10 09:13:45   Local7.Info   local IP address   813: wr1.lon: Sep 10 02:15:48.965: %DIALER-6-BIND: Interface Vi2 bound to profile Di2
2014-09-10 09:13:45   Local7.Debug   local IP address   814: wr1.lon: Sep 10 02:15:48.965: Vi2 PPP: Sending cstate UP notification
2014-09-10 09:13:45   Local7.Debug   local IP address   815: wr1.lon: Sep 10 02:15:48.969: Vi2 PPP: Processing CstateUp message
2014-09-10 09:13:45   Local7.Debug   local IP address   816: wr1.lon: Sep 10 02:15:48.973: PPP: Alloc Context [662C56A4]
2014-09-10 09:13:45   Local7.Debug   local IP address   817: wr1.lon: Sep 10 02:15:48.973: ppp3 PPP: Phase is ESTABLISHING
2014-09-10 09:13:45   Local7.Debug   local IP address   818: wr1.lon: Sep 10 02:15:48.973: Vi2 PPP: Using dialer call direction
2014-09-10 09:13:45   Local7.Debug   local IP address   819: wr1.lon: Sep 10 02:15:48.973: Vi2 PPP: Treating connection as a callout
2014-09-10 09:13:45   Local7.Debug   local IP address   820: wr1.lon: Sep 10 02:15:48.973: Vi2 PPP: Session handle[14000004] Session id[3]
2014-09-10 09:13:45   Local7.Debug   local IP address   821: wr1.lon: Sep 10 02:15:48.973: Vi2 LCP: Event[OPEN] State[Initial to Starting]
2014-09-10 09:13:45   Local7.Debug   local IP address   822: wr1.lon: Sep 10 02:15:48.973: Vi2 PPP: No remote authentication for call-out
2014-09-10 09:13:45   Local7.Debug   local IP address   823: wr1.lon: Sep 10 02:15:48.973: Vi2 LCP: O CONFREQ [Starting] id 1 len 14
2014-09-10 09:13:45   Local7.Debug   local IP address   824: wr1.lon: Sep 10 02:15:48.973: Vi2 LCP:    MRU 1492 (0x010405D4)
2014-09-10 09:13:46   Local7.Debug   local IP address   825: wr1.lon: Sep 10 02:15:48.973: Vi2 LCP:    MagicNumber 0x24FF1F46 (0x050624FF1F46)
2014-09-10 09:13:46   Local7.Debug   local IP address   826: wr1.lon: Sep 10 02:15:48.973: Vi2 LCP: Event[UP] State[Starting to REQsent]
2014-09-10 09:13:48   Local7.Debug   local IP address   827: wr1.lon: Sep 10 02:15:50.965: Vi2 LCP: O CONFREQ [REQsent] id 2 len 14
2014-09-10 09:13:48   Local7.Debug   local IP address   828: wr1.lon: Sep 10 02:15:50.965: Vi2 LCP:    MRU 1492 (0x010405D4)
2014-09-10 09:13:48   Local7.Debug   local IP address   829: wr1.lon: Sep 10 02:15:50.965: Vi2 LCP:    MagicNumber 0x24FF1F46 (0x050624FF1F46)
2014-09-10 09:13:48   Local7.Debug   local IP address   830: wr1.lon: Sep 10 02:15:50.965: Vi2 LCP: Event[Timeout+] State[REQsent to REQsent]
2014-09-10 09:13:50   Local7.Debug   local IP address   831: wr1.lon: Sep 10 02:15:52.981: Vi2 LCP: O CONFREQ [REQsent] id 3 len 14
2014-09-10 09:13:50   Local7.Debug   local IP address   832: wr1.lon: Sep 10 02:15:52.981: Vi2 LCP:    MRU 1492 (0x010405D4)
2014-09-10 09:13:50   Local7.Debug   local IP address   833: wr1.lon: Sep 10 02:15:52.981: Vi2 LCP:    MagicNumber 0x24FF1F46 (0x050624FF1F46)
2014-09-10 09:13:50   Local7.Debug   local IP address   834: wr1.lon: Sep 10 02:15:52.981: Vi2 LCP: Event[Timeout+] State[REQsent to REQsent]
2014-09-10 09:13:52   Local7.Debug   local IP address   835: wr1.lon: Sep 10 02:15:54.996: Vi2 LCP: O CONFREQ [REQsent] id 4 len 14
2014-09-10 09:13:52   Local7.Debug   local IP address   836: wr1.lon: Sep 10 02:15:54.996: Vi2 LCP:    MRU 1492 (0x010405D4)
2014-09-10 09:13:52   Local7.Debug   local IP address   837: wr1.lon: Sep 10 02:15:54.996: Vi2 LCP:    MagicNumber 0x24FF1F46 (0x050624FF1F46)
2014-09-10 09:13:52   Local7.Debug   local IP address   838: wr1.lon: Sep 10 02:15:55.000: Vi2 LCP: Event[Timeout+] State[REQsent to REQsent]
2014-09-10 09:13:54   Local7.Debug   local IP address   839: wr1.lon: Sep 10 02:15:57.012: Vi2 LCP: O CONFREQ [REQsent] id 5 len 14
2014-09-10 09:13:54   Local7.Debug   local IP address   840: wr1.lon: Sep 10 02:15:57.012: Vi2 LCP:    MRU 1492 (0x010405D4)
2014-09-10 09:13:54   Local7.Debug   local IP address   841: wr1.lon: Sep 10 02:15:57.012: Vi2 LCP:    MagicNumber 0x24FF1F46 (0x050624FF1F46)
2014-09-10 09:13:54   Local7.Debug   local IP address   842: wr1.lon: Sep 10 02:15:57.012: Vi2 LCP: Event[Timeout+] State[REQsent to REQsent]
2014-09-10 09:13:56   Local7.Debug   local IP address   843: wr1.lon: Sep 10 02:15:59.028: Vi2 LCP: O CONFREQ [REQsent] id 6 len 14
2014-09-10 09:13:56   Local7.Debug   local IP address   844: wr1.lon: Sep 10 02:15:59.028: Vi2 LCP:    MRU 1492 (0x010405D4)
2014-09-10 09:13:56   Local7.Debug   local IP address   845: wr1.lon: Sep 10 02:15:59.028: Vi2 LCP:    MagicNumber 0x24FF1F46 (0x050624FF1F46)
2014-09-10 09:13:56   Local7.Debug   local IP address   846: wr1.lon: Sep 10 02:15:59.028: Vi2 LCP: Event[Timeout+] State[REQsent to REQsent]
2014-09-10 09:13:58   Local7.Debug   local IP address   847: wr1.lon: Sep 10 02:16:01.044: Vi2 LCP: O CONFREQ [REQsent] id 7 len 14
2014-09-10 09:13:58   Local7.Debug   local IP address   848: wr1.lon: Sep 10 02:16:01.044: Vi2 LCP:    MRU 1492 (0x010405D4)
2014-09-10 09:13:58   Local7.Debug   local IP address   849: wr1.lon: Sep 10 02:16:01.044: Vi2 LCP:    MagicNumber 0x24FF1F46 (0x050624FF1F46)
2014-09-10 09:13:58   Local7.Debug   local IP address   850: wr1.lon: Sep 10 02:16:01.044: Vi2 LCP: Event[Timeout+] State[REQsent to REQsent]
2014-09-10 09:14:00   Local7.Debug   local IP address   851: wr1.lon: Sep 10 02:16:03.060: Vi2 LCP: O CONFREQ [REQsent] id 8 len 14
2014-09-10 09:14:00   Local7.Debug   local IP address   852: wr1.lon: Sep 10 02:16:03.060: Vi2 LCP:    MRU 1492 (0x010405D4)
2014-09-10 09:14:00   Local7.Debug   local IP address   853: wr1.lon: Sep 10 02:16:03.060: Vi2 LCP:    MagicNumber 0x24FF1F46 (0x050624FF1F46)
2014-09-10 09:14:00   Local7.Debug   local IP address   854: wr1.lon: Sep 10 02:16:03.060: Vi2 LCP: Event[Timeout+] State[REQsent to REQsent]
2014-09-10 09:14:02   Local7.Debug   local IP address   855: wr1.lon: Sep 10 02:16:05.075: Vi2 LCP: O CONFREQ [REQsent] id 9 len 14
2014-09-10 09:14:02   Local7.Debug   local IP address   856: wr1.lon: Sep 10 02:16:05.075: Vi2 LCP:    MRU 1492 (0x010405D4)
2014-09-10 09:14:02   Local7.Debug   local IP address   857: wr1.lon: Sep 10 02:16:05.075: Vi2 LCP:    MagicNumber 0x24FF1F46 (0x050624FF1F46)
2014-09-10 09:14:02   Local7.Debug   local IP address   858: wr1.lon: Sep 10 02:16:05.075: Vi2 LCP: Event[Timeout+] State[REQsent to REQsent]
2014-09-10 09:14:04   Local7.Debug   local IP address   859: wr1.lon: Sep 10 02:16:07.091: Vi2 LCP: O CONFREQ [REQsent] id 10 len 14
2014-09-10 09:14:04   Local7.Debug   local IP address   860: wr1.lon: Sep 10 02:16:07.091: Vi2 LCP:    MRU 1492 (0x010405D4)
2014-09-10 09:14:04   Local7.Debug   local IP address   861: wr1.lon: Sep 10 02:16:07.091: Vi2 LCP:    MagicNumber 0x24FF1F46 (0x050624FF1F46)
2014-09-10 09:14:04   Local7.Debug   local IP address   862: wr1.lon: Sep 10 02:16:07.091: Vi2 LCP: Event[Timeout+] State[REQsent to REQsent]
2014-09-10 09:14:06   Local7.Debug   local IP address   863: wr1.lon: Sep 10 02:16:09.107: Vi2 PPP DISC: LCP failed to negotiate
2014-09-10 09:14:06   Local7.Debug   local IP address   864: wr1.lon: Sep 10 02:16:09.107: PPP: NET STOP send to AAA.
2014-09-10 09:14:06   Local7.Debug   local IP address   865: wr1.lon: Sep 10 02:16:09.107: Vi2 PPP: No remote authentication for call-out
2014-09-10 09:14:06   Local7.Debug   local IP address   866: wr1.lon: Sep 10 02:16:09.107: Vi2 LCP: Event[Timeout-] State[REQsent to Stopped]
2014-09-10 09:14:06   Local7.Debug   local IP address   867: wr1.lon: Sep 10 02:16:09.107: Vi2 LCP: Event[DOWN] State[Stopped to Starting]
2014-09-10 09:14:06   Local7.Debug   local IP address   868: wr1.lon: Sep 10 02:16:09.107: Vi2 PPP: Phase is DOWN

Sunil Bhadauria · ‎10-01-2014

Hello ,

As line comes up after reloading the device this does not looks like configuration issue but hardware .

Either connection between ATM card and modem is getting idle or some issue with ATM card .

Did you try replacing ATM card with a spare one to see if issue persists .

HTH

Sunil Bhadauria

! Kindly rate all helpful posts and accordingly mark correct answers to help forum !

khanhnguyenquoc · ‎10-01-2014

Hi Sunil Bhadauria

Thank for your recommendation. what a pity that we do not have a spare one, and this device is in remote office.

Should I check any firmware update for ATM card, Router is up-to-date newest version of IOS

Thanks!

Regards!

----------------------------

Nguyen Quoc Khanh