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
08-17-2011 06:42 AM
Sorry, I should mention that Dialer 1 and ATM0 are clean of errors
Discover and save your favorite ideas. Come back to expert answers, step-by-step guides, recent topics, and more.
New here? Get started with these tips. How to use Community New member guide