Buy or Renew
Buy or Renew
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
2837
Views
0
Helpful
1
Replies

Missed keepalives - drops LCP

richard-hale
Level 1
Level 1

‎08-17-2011 06:34 AM - edited ‎03-04-2019 01:18 PM

Hi All,

I'm in need of some advice, a few of my links (all BT ADSL on Cisco IAD887s) suffer from drops in PPP, the physical circuit doesn't drop out, just the PPP and LCP

000489: Aug 12 12:13:22.085: Vi2 PPP: Missed 5 keepalives, taking LCP down

000490: Aug 12 12:13:22.085: Vi2 PPP DISC: Missed too many keepalives

000491: Aug 12 12:13:22.085: Vi2 PPP: Sending Acct Event[Down] id[19]

000492: Aug 12 12:13:22.085: Vi2 IPCP: Event[DOWN] State[Open to Starting]

000493: Aug 12 12:13:22.085: Vi2 IPCP: Event[CLOSE] State[Starting to Initial]

000494: Aug 12 12:13:22.085: Vi2 LCP: O TERMREQ [Open] id 4 len 4

000495: Aug 12 12:13:22.085: Vi2 LCP: Event[CLOSE] State[Open to Closing]

000496: Aug 12 12:13:22.085: Vi2 PPP: Phase is TERMINATING

000497: Aug 12 12:13:22.085: Vi2 LCP: Event[DOWN] State[Closing to Initial]

000498: Aug 12 12:13:22.085: Vi2 PPP: Phase is DOWN

000499: Aug 12 12:13:22.089: Di1 IPCP: Remove route to 212.xx.xx.xx

000500: Aug 12 12:13:23.092: PPP: Alloc Context [86BA7CD4]

000501: Aug 12 12:13:23.092: ppp8 PPP: Phase is ESTABLISHING

000502: Aug 12 12:13:23.092: Vi2 PPP: Using dialer call direction

000503: Aug 12 12:13:23.092: Vi2 PPP: Treating connection as a callout

000504: Aug 12 12:13:23.092: Vi2 PPP: Session handle[E8000009] Session id[8]

000505: Aug 12 12:13:23.092: Vi2 LCP: Event[OPEN] State[Initial to Starting]

000506: Aug 12 12:13:23.092: Vi2 PPP: No remote authentication for call-out

000507: Aug 12 12:13:23.096: Vi2 LCP: O CONFREQ [Starting] id 1 len 10

000508: Aug 12 12:13:23.096: Vi2 LCP:    MagicNumber 0xE51D17E4 (0x0506E51D17E4)

000509: Aug 12 12:13:23.096: Vi2 LCP: Event[UP] State[Starting to REQsent]

000510: Aug 12 12:13:25.092: Vi2 LCP: O CONFREQ [REQsent] id 2 len 10

000511: Aug 12 12:13:25.092: Vi2 LCP:    MagicNumber 0xE51D17E4 (0x0506E51D17E4)

000512: Aug 12 12:13:25.092: Vi2 LCP: Event[Timeout+] State[REQsent to REQsent]

000513: Aug 12 12:13:27.108: Vi2 LCP: O CONFREQ [REQsent] id 3 len 10

000514: Aug 12 12:13:27.108: Vi2 LCP:    MagicNumber 0xE51D17E4 (0x0506E51D17E4)

000515: Aug 12 12:13:27.108: Vi2 LCP: Event[Timeout+] State[REQsent to REQsent]

000516: Aug 12 12:13:28.404: Vi2 LCP: I CONFREQ [REQsent] id 167 len 19

000517: Aug 12 12:13:28.404: Vi2 LCP:    MRU 1500 (0x010405DC)

000518: Aug 12 12:13:28.404: Vi2 LCP:    AuthProto CHAP (0x0305C22305)

000519: Aug 12 12:13:28.404: Vi2 LCP:    MagicNumber 0x540CC896 (0x0506540CC896)

000520: Aug 12 12:13:28.404: Vi2 LCP: O CONFACK [REQsent] id 167 len 19

000521: Aug 12 12:13:28.404: Vi2 LCP:    MRU 1500 (0x010405DC)

000522: Aug 12 12:13:28.404: Vi2 LCP:    AuthProto CHAP (0x0305C22305)

000523: Aug 12 12:13:28.404: Vi2 LCP:    MagicNumber 0x540CC896 (0x0506540CC896)

000524: Aug 12 12:13:28.404: Vi2 LCP: Event[Receive ConfReq+] State[REQsent to ACKsent]

000525: Aug 12 12:13:28.404: Vi2 LCP: I CONFACK [ACKsent] id 3 len 10

000526: Aug 12 12:13:28.404: Vi2 LCP:    MagicNumber 0xE51D17E4 (0x0506E51D17E4)

000527: Aug 12 12:13:28.404: Vi2 LCP: Event[Receive ConfAck] State[ACKsent to Open]

000528: Aug 12 12:13:28.420: Vi2 PPP: Phase is AUTHENTICATING, by the peer

000529: Aug 12 12:13:28.420: Vi2 LCP: State is Open

000530: Aug 12 12:13:28.432: Vi2 CHAP: I CHALLENGE id 1 len 35 from "bras-red1.gi-b"

000531: Aug 12 12:13:28.432: Vi2 PPP: Sent CHAP SENDAUTH Request

000532: Aug 12 12:13:28.432: Vi2 PPP: Received SENDAUTH Response FAIL

000533: Aug 12 12:13:28.432: Vi2 CHAP: Using hostname from interface CHAP

000534: Aug 12 12:13:28.432: Vi2 CHAP: Using password from interface CHAP

000535: Aug 12 12:13:28.432: Vi2 CHAP: O RESPONSE id 1 len 48 from bbeuxxxxxxxx@xxxxxxxx.co.uk

000536: Aug 12 12:13:28.872: Vi2 CHAP: I SUCCESS id 1 len 4

000537: Aug 12 12:13:28.872: Vi2 PPP: Phase is FORWARDING, Attempting Forward

000538: Aug 12 12:13:28.872: Vi2 PPP: Queue IPCP code[1] id[1]

000539: Aug 12 12:13:28.872: Vi2 PPP: Phase is ESTABLISHING, Finish LCP

000540: Aug 12 12:13:28.872: Vi2 PPP: Phase is UP

000541: Aug 12 12:13:28.876: Vi2 IPCP: Protocol configured, start CP. state[Initial]

000542: Aug 12 12:13:28.876: Vi2 IPCP: Event[OPEN] State[Initial to Starting]

000543: Aug 12 12:13:28.876: Vi2 IPCP: O CONFREQ [Starting] id 1 len 22

000544: Aug 12 12:13:28.876: Vi2 IPCP:    Address 0.0.0.0 (0x030600000000)

000545: Aug 12 12:13:28.876: Vi2 IPCP:    PrimaryDNS 0.0.0.0 (0x810600000000)

000546: Aug 12 12:13:28.876: Vi2 IPCP:    SecondaryDNS 0.0.0.0 (0x830600000000)

000547: Aug 12 12:13:28.876: Vi2 IPCP: Event[UP] State[Starting to REQsent]

000548: Aug 12 12:13:28.876: Vi2 PPP: Process pending ncp packets

000549: Aug 12 12:13:28.876: Vi2 IPCP: Redirect packet to Vi2

000550: Aug 12 12:13:28.876: Vi2 IPCP: I CONFREQ [REQsent] id 1 len 10

000551: Aug 12 12:13:28.876: Vi2 IPCP:    Address 212.xxx.xxx.xxx (0x0306D4B79D6E)

000552: Aug 12 12:13:28.876: Vi2 IPCP: O CONFACK [REQsent] id 1 len 10

000553: Aug 12 12:13:28.876: Vi2 IPCP:    Address 212.xxx.xxx.xxx(0x0306D4B79D6E)

000554: Aug 12 12:13:28.876: Vi2 IPCP: Event[Receive ConfReq+] State[REQsent to ACKsent]

000555: Aug 12 12:13:28.908: Vi2 IPCP: I CONFNAK [ACKsent] id 1 len 22

000556: Aug 12 12:13:28.908: Vi2 IPCP:    Address 88.82.xx.xx (0x030658521932)

000557: Aug 12 12:13:28.908: Vi2 IPCP:    PrimaryDNS 88.82.xx.xx 0x8106585211FE)

000558: Aug 12 12:13:28.908: Vi2 IPCP:    SecondaryDNS 88.82.xx.xx (0x8306585210FE)

000559: Aug 12 12:13:28.908: Vi2 IPCP: O CONFREQ [ACKsent] id 2 len 22

000560: Aug 12 12:13:28.908: Vi2 IPCP:    Address 88.82.xx.xx (0x030658521932)

000561: Aug 12 12:13:28.908: Vi2 IPCP:    PrimaryDNS 88.82.xx.xx (0x8106585211FE)

000562: Aug 12 12:13:28.908: Vi2 IPCP:    SecondaryDNS 88.82.xx.xx (0x8306585210FE)

000563: Aug 12 12:13:28.908: Vi2 IPCP: Event[Receive ConfNak/Rej] State[ACKsent to ACKsent]

000564: Aug 12 12:13:28.940: Vi2 IPCP: I CONFACK [ACKsent] id 2 len 22

000565: Aug 12 12:13:28.944: Vi2 IPCP:    Address 88.82.xx.xx (0x030658521932)

000566: Aug 12 12:13:28.944: Vi2 IPCP:    PrimaryDNS 88.82.xx.xx (0x8106585211FE)

000567: Aug 12 12:13:28.944: Vi2 IPCP:    SecondaryDNS 88.82.xx.xx (0x8306585210FE)

000568: Aug 12 12:13:28.944: Vi2 IPCP: Event[Receive ConfAck] State[ACKsent to Open]

000569: Aug 12 12:13:28.964: Vi2 IPCP: State is Open

000570: Aug 12 12:13:28.964: Di1 IPCP: Install negotiated IP interface address 88.xx.xx.xx

000571: Aug 12 12:13:28.964: Di1 IPCP: Install route to 212.xxx.xxx.xxx

000572: Aug 12 12:39:21.348: Vi2 PPP: Missed 5 keepalives, taking LCP down

000573: Aug 12 12:39:21.348: Vi2 PPP DISC: Missed too many keepalives

000574: Aug 12 12:39:21.348: Vi2 PPP: Sending Acct Event[Down] id[1A]

000575: Aug 12 12:39:21.348: Vi2 IPCP: Event[DOWN] State[Open to Starting]

000576: Aug 12 12:39:21.348: Vi2 IPCP: Event[CLOSE] State[Starting to Initial]

000577: Aug 12 12:39:21.348: Vi2 LCP: O TERMREQ [Open] id 4 len 4

000578: Aug 12 12:39:21.348: Vi2 LCP: Event[CLOSE] State[Open to Closing]

000579: Aug 12 12:39:21.348: Vi2 PPP: Phase is TERMINATING

000580: Aug 12 12:39:21.348: Vi2 LCP: Event[DOWN] State[Closing to Initial]

000581: Aug 12 12:39:21.348: Vi2 PPP: Phase is DOWN

000582: Aug 12 12:39:21.352: Di1 IPCP: Remove route to 212.xxx.xxx.xxx

000583: Aug 12 12:39:22.356: PPP: Alloc Context [86BA7CD4]

000584: Aug 12 12:39:22.356: ppp9 PPP: Phase is ESTABLISHING

000585: Aug 12 12:39:22.356: Vi2 PPP: Using dialer call direction

000586: Aug 12 12:39:22.356: Vi2 PPP: Treating connection as a callout

000587: Aug 12 12:39:22.356: Vi2 PPP: Session handle[7D00000A] Session id[9]

000588: Aug 12 12:39:22.356: Vi2 LCP: Event[OPEN] State[Initial to Starting]

000589: Aug 12 12:39:22.360: Vi2 PPP: No remote authentication for call-out

000590: Aug 12 12:39:22.360: Vi2 LCP: O CONFREQ [Starting] id 1 len 10

000591: Aug 12 12:39:22.360: Vi2 LCP:    MagicNumber 0xE534E2C5 (0x0506E534E2C5)

000592: Aug 12 12:39:22.360: Vi2 LCP: Event[UP] State[Starting to REQsent]

000593: Aug 12 12:39:24.356: Vi2 LCP: O CONFREQ [REQsent] id 2 len 10

000594: Aug 12 12:39:24.356: Vi2 LCP:    MagicNumber 0xE534E2C5 (0x0506E534E2C5)

000595: Aug 12 12:39:24.356: Vi2 LCP: Event[Timeout+] State[REQsent to REQsent]

000596: Aug 12 12:39:26.372: Vi2 LCP: O CONFREQ [REQsent] id 3 len 10

000597: Aug 12 12:39:26.372: Vi2 LCP:    MagicNumber 0xE534E2C5 (0x0506E534E2C5)

000598: Aug 12 12:39:26.372: Vi2 LCP: Event[Timeout+] State[REQsent to REQsent]

000599: Aug 12 12:39:27.464: Vi2 LCP: I CONFREQ [REQsent] id 60 len 19

000600: Aug 12 12:39:27.464: Vi2 LCP:    MRU 1500 (0x010405DC)

000601: Aug 12 12:39:27.464: Vi2 LCP:    AuthProto CHAP (0x0305C22305)

000602: Aug 12 12:39:27.464: Vi2 LCP:    MagicNumber 0x78FA0D23 (0x050678FA0D23)

000603: Aug 12 12:39:27.464: Vi2 LCP: O CONFACK [REQsent] id 60 len 19

000604: Aug 12 12:39:27.464: Vi2 LCP:    MRU 1500 (0x010405DC)

000605: Aug 12 12:39:27.464: Vi2 LCP:    AuthProto CHAP (0x0305C22305)

000606: Aug 12 12:39:27.464: Vi2 LCP:    MagicNumber 0x78FA0D23 (0x050678FA0D23)

000607: Aug 12 12:39:27.464: Vi2 LCP: Event[Receive ConfReq+] State[REQsent to ACKsent]

000608: Aug 12 12:39:27.468: Vi2 LCP: I CONFACK [ACKsent] id 3 len 10

000609: Aug 12 12:39:27.468: Vi2 LCP:    MagicNumber 0xE534E2C5 (0x0506E534E2C5)

000610: Aug 12 12:39:27.468: Vi2 LCP: Event[Receive ConfAck] State[ACKsent to Open]

000611: Aug 12 12:39:27.492: Vi2 PPP: Phase is AUTHENTICATING, by the peer

000612: Aug 12 12:39:27.492: Vi2 LCP: State is Open

000613: Aug 12 12:39:27.492: Vi2 CHAP: I CHALLENGE id 1 len 35 from "bras-red1.gi-b"

000614: Aug 12 12:39:27.492: Vi2 PPP: Sent CHAP SENDAUTH Request

000615: Aug 12 12:39:27.492: Vi2 PPP: Received SENDAUTH Response FAIL

000616: Aug 12 12:39:27.492: Vi2 CHAP: Using hostname from interface CHAP

000617: Aug 12 12:39:27.492: Vi2 CHAP: Using password from interface CHAP

000618: Aug 12 12:39:27.492: Vi2 CHAP: O RESPONSE id 1 len 48 from bbeuxxxxxxxx@xxxxxxxx.co.uk

000619: Aug 12 12:39:27.860: Vi2 CHAP: I SUCCESS id 1 len 4

000620: Aug 12 12:39:27.860: Vi2 PPP: Phase is FORWARDING, Attempting Forward

000621: Aug 12 12:39:27.860: Vi2 PPP: Queue IPCP code[1] id[1]

000622: Aug 12 12:39:27.864: Vi2 PPP: Phase is ESTABLISHING, Finish LCP

000623: Aug 12 12:39:27.864: Vi2 PPP: Phase is UP

000624: Aug 12 12:39:27.864: Vi2 IPCP: Protocol configured, start CP. state[Initial]

000625: Aug 12 12:39:27.864: Vi2 IPCP: Event[OPEN] State[Initial to Starting]

000626: Aug 12 12:39:27.864: Vi2 IPCP: O CONFREQ [Starting] id 1 len 22

000627: Aug 12 12:39:27.864: Vi2 IPCP:    Address 0.0.0.0 (0x030600000000)

000628: Aug 12 12:39:27.864: Vi2 IPCP:    PrimaryDNS 0.0.0.0 (0x810600000000)

000629: Aug 12 12:39:27.864: Vi2 IPCP:    SecondaryDNS 0.0.0.0 (0x830600000000)

000630: Aug 12 12:39:27.864: Vi2 IPCP: Event[UP] State[Starting to REQsent]

000631: Aug 12 12:39:27.864: Vi2 PPP: Process pending ncp packets

000632: Aug 12 12:39:27.864: Vi2 IPCP: Redirect packet to Vi2

000633: Aug 12 12:39:27.864: Vi2 IPCP: I CONFREQ [REQsent] id 1 len 10

000634: Aug 12 12:39:27.864: Vi2 IPCP:    Address 212.xx.xx.xx (0x0306D4B79D6E)

000635: Aug 12 12:39:27.864: Vi2 IPCP: O CONFACK [REQsent] id 1 len 10

000636: Aug 12 12:39:27.864: Vi2 IPCP:    Address 212.xx.xx.xx (0x0306D4B79D6E)

000637: Aug 12 12:39:27.864: Vi2 IPCP: Event[Receive ConfReq+] State[REQsent to ACKsent]

000638: Aug 12 12:39:27.900: Vi2 IPCP: I CONFNAK [ACKsent] id 1 len 22

000639: Aug 12 12:39:27.900: Vi2 IPCP:    Address 88.82.xx.xx (0x030658521932)

000640: Aug 12 12:39:27.900: Vi2 IPCP:    PrimaryDNS 88.82.xx.xx (0x8106585211FE)

000641: Aug 12 12:39:27.900: Vi2 IPCP:    SecondaryDNS 88.82.xx.xx(0x8306585210FE)

000642: Aug 12 12:39:27.900: Vi2 IPCP: O CONFREQ [ACKsent] id 2 len 22

000643: Aug 12 12:39:27.900: Vi2 IPCP:    Address 88.82.xx.xx (0x030658521932)

000644: Aug 12 12:39:27.900: Vi2 IPCP:    PrimaryDNS 88.82.xx.xx (0x8106585211FE)

000645: Aug 12 12:39:27.900: Vi2 IPCP:    SecondaryDNS 88.82.xx.xx (0x8306585210FE)

000646: Aug 12 12:39:27.900: Vi2 IPCP: Event[Receive ConfNak/Rej] State[ACKsent to ACKsent]

000647: Aug 12 12:39:27.936: Vi2 IPCP: I CONFACK [ACKsent] id 2 len 22

000648: Aug 12 12:39:27.936: Vi2 IPCP:    Address 88.82.xx.xx (0x030658521932)

000649: Aug 12 12:39:27.936: Vi2 IPCP:    PrimaryDNS 88.82.xx.xx (0x8106585211FE)

000650: Aug 12 12:39:27.936: Vi2 IPCP:    SecondaryDNS 88.82.xx.xx (0x8306585210FE)

000651: Aug 12 12:39:27.936: Vi2 IPCP: Event[Receive ConfAck] State[ACKsent to Open]

000652: Aug 12 12:39:27.940: Vi2 IPCP: State is Open

000653: Aug 12 12:39:27.940: Di1 IPCP: Install negotiated IP interface address 88.xx.xx.xx

000654: Aug 12 12:39:27.940: Di1 IPCP: Install route to 212.xxx.xxx.xxx

1 person had this problem
Labels:
0 Helpful
1 Reply 1

richard-hale
Level 1
Level 1

‎08-17-2011 06:42 AM

Sorry, I should mention that Dialer 1 and ATM0 are clean of errors

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