Prev: FLASH Write Error #5 on Aironet 350
Next: Client 4.7 installation error with DNE
From: Dan Lanciani on 27 Nov 2006 00:52
In article <20e8a568b9076b3f0adff25b0c09bd85.115602(a)mygate.mailgate.org>, il.maggiore(a)inwind.it (Elia Spadoni) writes:

| I have to resolve this issue. If I connect with Win XP SP2, or a Windows
| 2003 server SP1 as a PPTP client
| the connection is fine and never disconnects. With the cisco, it
| disconnects randomly after 60secs or more.

Can you find out what the server thinks is happening? If not you might
try some more debugging (vpdn events and errors). At this point I'd be
tempted to use a sniffer to compare the Windows & Cisco clinets' behaviors.
Or you could move up to packet debugging for ppp & vpdn; it will be rather
verbose.

There aren't many pptp parameters to adjust, but you could experiment with
the echo interval.

| > | I put an mty of 1500, because i received this warning:
| > |
| > | 25-11-2006 04:28:05 Syslog.Warning 192.168.1.254 32: 000028: Nov 25
| > | 05:27:43.791 CET: %DIALER-4-MTU_WARNING: Current MTU setting of 1500 on
| > | Virtual-Access1 is being overwritten by setting of 1450 defined by Dialer0.
| >
| > The warning is normal. I would not increase the MTU until you are sure
| > it is not causing a problem.
| >
|
| With MTU, please suggest what I have to set on the dialer vpn interface.

As i said, I suggest 1450 until you know something bigger works.

Dan Lanciani
ddl(a)danlan.*com
From: E. S. on 27 Nov 2006 08:23
OK

I activated now this debug:

gateway#show debug
VPN:
VPDN errors debugging is on
VPN disconnect debugging is on
PPP:
PPP protocol errors debugging is on
PPP protocol negotiation debugging is on


27-11-2006 14:15:45 Syslog.Notice 192.168.1.254 2251: 002244: Nov 27
15:15:24.812 CET: %SYS-5-CONFIG_I: Configured from console by maggiore on
vty0 (192.168.1.11)
27-11-2006 14:16:05 Syslog.Warning 192.168.1.254 2252: 002245: Nov 27
15:15:45.310 CET: %DIALER-4-MTU_WARNING: Current MTU setting of 1500 on
Virtual-Access1 is being overwritten by setting of 1450 defined by Dialer0.
27-11-2006 14:16:05 Syslog.Debug 192.168.1.254 2253: 002246: Nov 27
15:15:45.318 CET: Vi1 PPP: Phase is DOWN, Setup
27-11-2006 14:16:05 Syslog.Error 192.168.1.254 2254: 002247: Nov 27
15:15:45.554 CET: %LINK-3-UPDOWN: Interface Virtual-Access1, changed state
to up
27-11-2006 14:16:05 Syslog.Debug 192.168.1.254 2255: 002248: Nov 27
15:15:45.582 CET: Vi1 PPP: Using dialer call direction
27-11-2006 14:16:05 Syslog.Debug 192.168.1.254 2256: 002249: Nov 27
15:15:45.582 CET: Vi1 PPP: Treating connection as a callout
27-11-2006 14:16:05 Syslog.Debug 192.168.1.254 2257: 002250: Nov 27
15:15:45.582 CET: Vi1 PPP: Session handle[7000002C] Session id[17]
27-11-2006 14:16:05 Syslog.Debug 192.168.1.254 2258: 002251: Nov 27
15:15:45.582 CET: Vi1 PPP: Phase is ESTABLISHING, Active Open
27-11-2006 14:16:05 Syslog.Debug 192.168.1.254 2259: 002252: Nov 27
15:15:45.582 CET: Vi1 PPP: No remote authentication for call-out
27-11-2006 14:16:05 Syslog.Debug 192.168.1.254 2260: 002253: Nov 27
15:15:45.582 CET: Vi1 LCP: O CONFREQ [Closed] id 1 len 16
27-11-2006 14:16:05 Syslog.Debug 192.168.1.254 2261: 002254: Nov 27
15:15:45.582 CET: Vi1 LCP: MRU 1450 (0x010405AA)
27-11-2006 14:16:05 Syslog.Debug 192.168.1.254 2262: 002255: Nov 27
15:15:45.586 CET: Vi1 LCP: MagicNumber 0x1414D961 (0x05061414D961)
27-11-2006 14:16:05 Syslog.Debug 192.168.1.254 2263: 002256: Nov 27
15:15:45.586 CET: Vi1 LCP: PFC (0x0702)
27-11-2006 14:16:06 Syslog.Debug 192.168.1.254 2264: 002257: Nov 27
15:15:45.642 CET: Vi1 LCP: I CONFACK [REQsent] id 1 len 16
27-11-2006 14:16:06 Syslog.Debug 192.168.1.254 2265: 002258: Nov 27
15:15:45.642 CET: Vi1 LCP: MRU 1450 (0x010405AA)
27-11-2006 14:16:06 Syslog.Debug 192.168.1.254 2266: 002259: Nov 27
15:15:45.646 CET: Vi1 LCP: MagicNumber 0x1414D961 (0x05061414D961)
27-11-2006 14:16:06 Syslog.Debug 192.168.1.254 2267: 002260: Nov 27
15:15:45.646 CET: Vi1 LCP: PFC (0x0702)
27-11-2006 14:16:07 Syslog.Error 192.168.1.254 2268: 002261: Nov 27
15:15:47.306 CET: %LINK-3-UPDOWN: Interface Dialer0, changed state to up
27-11-2006 14:16:08 Syslog.Debug 192.168.1.254 2269: 002262: Nov 27
15:15:47.542 CET: Vi1 LCP: Timeout: State ACKrcvd
27-11-2006 14:16:08 Syslog.Debug 192.168.1.254 2270: 002263: Nov 27
15:15:47.542 CET: Vi1 LCP: O CONFREQ [ACKrcvd] id 2 len 16
27-11-2006 14:16:08 Syslog.Debug 192.168.1.254 2271: 002264: Nov 27
15:15:47.542 CET: Vi1 LCP: MRU 1450 (0x010405AA)
27-11-2006 14:16:08 Syslog.Debug 192.168.1.254 2272: 002265: Nov 27
15:15:47.542 CET: Vi1 LCP: MagicNumber 0x1414D961 (0x05061414D961)
27-11-2006 14:16:08 Syslog.Debug 192.168.1.254 2273: 002266: Nov 27
15:15:47.542 CET: Vi1 LCP: PFC (0x0702)
27-11-2006 14:16:08 Syslog.Debug 192.168.1.254 2274: 002267: Nov 27
15:15:47.602 CET: Vi1 LCP: I CONFACK [REQsent] id 2 len 16
27-11-2006 14:16:08 Syslog.Debug 192.168.1.254 2275: 002268: Nov 27
15:15:47.602 CET: Vi1 LCP: MRU 1450 (0x010405AA)
27-11-2006 14:16:08 Syslog.Debug 192.168.1.254 2276: 002269: Nov 27
15:15:47.602 CET: Vi1 LCP: MagicNumber 0x1414D961 (0x05061414D961)
27-11-2006 14:16:08 Syslog.Debug 192.168.1.254 2277: 002270: Nov 27
15:15:47.602 CET: Vi1 LCP: PFC (0x0702)
27-11-2006 14:16:08 Syslog.Debug 192.168.1.254 2279: 002272: Nov 27
15:15:48.554 CET: Vi1 LCP: ACCM 0x00000000 (0x020600000000)
27-11-2006 14:16:08 Syslog.Debug 192.168.1.254 2280: 002273: Nov 27
15:15:48.554 CET: Vi1 LCP: AuthProto MS-CHAP-V2 (0x0305C22381)
27-11-2006 14:16:08 Syslog.Debug 192.168.1.254 2281: 002274: Nov 27
15:15:48.554 CET: Vi1 LCP: MagicNumber 0xFC6965F2 (0x0506FC6965F2)
27-11-2006 14:16:08 Syslog.Debug 192.168.1.254 2282: 002275: Nov 27
15:15:48.554 CET: Vi1 LCP: PFC (0x0702)
27-11-2006 14:16:08 Syslog.Debug 192.168.1.254 2283: 002276: Nov 27
15:15:48.554 CET: Vi1 LCP: ACFC (0x0802)
27-11-2006 14:16:08 Syslog.Debug 192.168.1.254 2284: 002277: Nov 27
15:15:48.554 CET: Vi1 LCP: O CONFACK [ACKrcvd] id 1 len 25
27-11-2006 14:16:08 Syslog.Debug 192.168.1.254 2285: 002278: Nov 27
15:15:48.554 CET: Vi1 LCP: ACCM 0x00000000 (0x020600000000)
27-11-2006 14:16:08 Syslog.Debug 192.168.1.254 2286: 002279: Nov 27
15:15:48.558 CET: Vi1 LCP: AuthProto MS-CHAP-V2 (0x0305C22381)
27-11-2006 14:16:08 Syslog.Debug 192.168.1.254 2287: 002280: Nov 27
15:15:48.558 CET: Vi1 LCP: MagicNumber 0xFC6965F2 (0x0506FC6965F2)
27-11-2006 14:16:08 Syslog.Debug 192.168.1.254 2288: 002281: Nov 27
15:15:48.558 CET: Vi1 LCP: PFC (0x0702)
27-11-2006 14:16:08 Syslog.Debug 192.168.1.254 2289: 002282: Nov 27
15:15:48.558 CET: Vi1 LCP: ACFC (0x0802)
27-11-2006 14:16:08 Syslog.Debug 192.168.1.254 2290: 002283: Nov 27
15:15:48.558 CET: Vi1 LCP: State is Open
27-11-2006 14:16:08 Syslog.Debug 192.168.1.254 2291: 002284: Nov 27
15:15:48.558 CET: Vi1 PPP: Phase is AUTHENTICATING, by the peer
27-11-2006 14:16:08 Syslog.Debug 192.168.1.254 2292: 002285: Nov 27
15:15:48.618 CET: Vi1 MS-CHAP-V2: I CHALLENGE id 74 len 26 from "pptpd"
27-11-2006 14:16:08 Syslog.Debug 192.168.1.254 2293: 002286: Nov 27
15:15:48.658 CET: Vi1 MS CHAP V2: Using hostname from interface CHAP
27-11-2006 14:16:08 Syslog.Debug 192.168.1.254 2294: 002287: Nov 27
15:15:48.658 CET: Vi1 MS CHAP V2: Using password from interface CHAP
27-11-2006 14:16:08 Syslog.Debug 192.168.1.254 2295: 002288: Nov 27
15:15:48.658 CET: Vi1 MS-CHAP-V2: O RESPONSE id 74 len 62 from "maggiore"
27-11-2006 14:16:08 Syslog.Debug 192.168.1.254 2296: 002289: Nov 27
15:15:48.722 CET: Vi1 MS-CHAP-V2: I SUCCESS id 74 len 46 msg is
"S=1EAE4574AAB745DFED3A26A21F888DB8303A6346"
27-11-2006 14:16:08 Syslog.Debug 192.168.1.254 2297: 002290: Nov 27
15:15:48.722 CET: Vi1 MS CHAP V2 No Password found for : pptpd
27-11-2006 14:16:08 Syslog.Debug 192.168.1.254 2298: 002291: Nov 27
15:15:48.722 CET: Vi1 MS CHAP V2 No Password found for : pptpd
27-11-2006 14:16:08 Syslog.Debug 192.168.1.254 2299: 002292: Nov 27
15:15:48.726 CET: Vi1 MS CHAP V2 Check AuthenticatorResponse Success for :
maggiore
27-11-2006 14:16:08 Syslog.Debug 192.168.1.254 2300: 002293: Nov 27
15:15:48.726 CET: Vi1 PPP: Phase is FORWARDING, Attempting Forward
27-11-2006 14:16:08 Syslog.Debug 192.168.1.254 2301: 002294: Nov 27
15:15:48.726 CET: Vi1 PPP: Queue CCP code[1] id[1]
27-11-2006 14:16:08 Syslog.Debug 192.168.1.254 2302: 002295: Nov 27
15:15:48.730 CET: Vi1 PPP SSS: Receive SSS-Mgr Connect-Local
27-11-2006 14:16:08 Syslog.Debug 192.168.1.254 2303: 002296: Nov 27
15:15:48.730 CET: Vi1 PPP: Phase is ESTABLISHING, Finish LCP
27-11-2006 14:16:08 Syslog.Debug 192.168.1.254 2304: 002297: Nov 27
15:15:48.730 CET: Vi1 PPP: Phase is UP
27-11-2006 14:16:08 Syslog.Debug 192.168.1.254 2305: 002298: Nov 27
15:15:48.730 CET: Vi1 IPCP: O CONFREQ [Closed] id 1 len 10
27-11-2006 14:16:08 Syslog.Debug 192.168.1.254 2306: 002299: Nov 27
15:15:48.734 CET: Vi1 IPCP: Address 0.0.0.0 (0x030600000000)
27-11-2006 14:16:08 Syslog.Debug 192.168.1.254 2307: 002300: Nov 27
15:15:48.734 CET: Vi1 CCP: O CONFREQ [Closed] id 1 len 10
27-11-2006 14:16:08 Syslog.Debug 192.168.1.254 2308: 002301: Nov 27
15:15:48.734 CET: Vi1 CCP: MS-PPC supported bits 0x01000060
(0x120601000060)
27-11-2006 14:16:08 Syslog.Debug 192.168.1.254 2309: 002302: Nov 27
15:15:48.734 CET: Vi1 PPP: Process pending ncp packets
27-11-2006 14:16:08 Syslog.Debug 192.168.1.254 2310: 002303: Nov 27
15:15:48.734 CET: Vi1 CCP: Redirect packet to Vi1
27-11-2006 14:16:08 Syslog.Debug 192.168.1.254 2311: 002304: Nov 27
15:15:48.734 CET: Vi1 CCP: I CONFREQ [REQsent] id 1 len 10
27-11-2006 14:16:08 Syslog.Debug 192.168.1.254 2312: 002305: Nov 27
15:15:48.734 CET: Vi1 CCP: MS-PPC supported bits 0x01000040
(0x120601000040)
27-11-2006 14:16:08 Syslog.Debug 192.168.1.254 2313: 002306: Nov 27
15:15:48.738 CET: Vi1 CCP: O CONFACK [REQsent] id 1 len 10
27-11-2006 14:16:08 Syslog.Debug 192.168.1.254 2314: 002307: Nov 27
15:15:48.738 CET: Vi1 CCP: MS-PPC supported bits 0x01000040
(0x120601000040)
27-11-2006 14:16:08 Syslog.Debug 192.168.1.254 2315: 002308: Nov 27
15:15:48.798 CET: Vi1 IPCP: I TERMACK [REQsent] id 1 len 4
27-11-2006 14:16:08 Syslog.Debug 192.168.1.254 2316: 002309: Nov 27
15:15:48.798 CET: Vi1 CCP: I CONFNAK [ACKsent] id 1 len 10
27-11-2006 14:16:08 Syslog.Debug 192.168.1.254 2317: 002310: Nov 27
15:15:48.798 CET: Vi1 CCP: MS-PPC supported bits 0x01000040
(0x120601000040)
27-11-2006 14:16:08 Syslog.Debug 192.168.1.254 2318: 002311: Nov 27
15:15:48.798 CET: Vi1 CCP: O CONFREQ [ACKsent] id 2 len 10
27-11-2006 14:16:08 Syslog.Debug 192.168.1.254 2319: 002312: Nov 27
15:15:48.802 CET: Vi1 CCP: MS-PPC supported bits 0x01000040
(0x120601000040)
27-11-2006 14:16:08 Syslog.Debug 192.168.1.254 2320: 002313: Nov 27
15:15:48.858 CET: Vi1 CCP: I CONFACK [ACKsent] id 2 len 10
27-11-2006 14:16:09 Syslog.Debug 192.168.1.254 2321: 002314: Nov 27
15:15:48.858 CET: Vi1 CCP: MS-PPC supported bits 0x01000040
(0x120601000040)
27-11-2006 14:16:09 Syslog.Debug 192.168.1.254 2322: 002315: Nov 27
15:15:48.858 CET: Vi1 CCP: State is Open
27-11-2006 14:16:09 Syslog.Debug 192.168.1.254 2323: 002316: Nov 27
15:15:48.866 CET: Vi1 IPCP: I CONFREQ [REQsent] id 1 len 10
27-11-2006 14:16:09 Syslog.Debug 192.168.1.254 2324: 002317: Nov 27
15:15:48.866 CET: Vi1 IPCP: Address 83.233.168.2 (0x030653E9A802)
27-11-2006 14:16:09 Syslog.Debug 192.168.1.254 2325: 002318: Nov 27
15:15:48.866 CET: Vi1 IPCP: O CONFACK [REQsent] id 1 len 10
27-11-2006 14:16:09 Syslog.Debug 192.168.1.254 2326: 002319: Nov 27
15:15:48.866 CET: Vi1 IPCP: Address 83.233.168.2 (0x030653E9A802)
27-11-2006 14:16:10 Syslog.Notice 192.168.1.254 2327: 002320: Nov 27
15:15:49.730 CET: %LINEPROTO-5-UPDOWN: Line protocol on Interface
Virtual-Access1, changed state to up
27-11-2006 14:16:10 Syslog.Debug 192.168.1.254 2328: 002321: Nov 27
15:15:50.742 CET: Vi1 IPCP: Timeout: State ACKsent
27-11-2006 14:16:10 Syslog.Debug 192.168.1.254 2329: 002322: Nov 27
15:15:50.742 CET: Vi1 IPCP: O CONFREQ [ACKsent] id 2 len 10
27-11-2006 14:16:10 Syslog.Debug 192.168.1.254 2330: 002323: Nov 27
15:15:50.742 CET: Vi1 IPCP: Address 0.0.0.0 (0x030600000000)
27-11-2006 14:16:10 Syslog.Debug 192.168.1.254 2331: 002324: Nov 27
15:15:50.798 CET: Vi1 IPCP: I CONFNAK [ACKsent] id 2 len 10
27-11-2006 14:16:10 Syslog.Debug 192.168.1.254 2332: 002325: Nov 27
15:15:50.798 CET: Vi1 IPCP: Address 83.233.168.16 (0x030653E9A810)
27-11-2006 14:16:10 Syslog.Debug 192.168.1.254 2333: 002326: Nov 27
15:15:50.802 CET: Vi1 IPCP: O CONFREQ [ACKsent] id 3 len 10
27-11-2006 14:16:10 Syslog.Debug 192.168.1.254 2334: 002327: Nov 27
15:15:50.802 CET: Vi1 IPCP: Address 83.233.168.16 (0x030653E9A810)
27-11-2006 14:16:10 Syslog.Debug 192.168.1.254 2335: 002328: Nov 27
15:15:50.858 CET: Vi1 IPCP: I CONFACK [ACKsent] id 3 len 10
27-11-2006 14:16:11 Syslog.Debug 192.168.1.254 2336: 002329: Nov 27
15:15:50.858 CET: Vi1 IPCP: Address 83.233.168.16 (0x030653E9A810)
27-11-2006 14:16:11 Syslog.Debug 192.168.1.254 2337: 002330: Nov 27
15:15:50.858 CET: Vi1 IPCP: State is Open
27-11-2006 14:16:11 Syslog.Debug 192.168.1.254 2338: 002331: Nov 27
15:15:50.862 CET: Di0 IPCP: Install negotiated IP interface address
83.233.168.16
27-11-2006 14:16:11 Syslog.Debug 192.168.1.254 2339: 002332: Nov 27
15:15:50.870 CET: Di0 IPCP: Install route to 83.233.168.2
27-11-2006 14:16:11 Syslog.Debug 192.168.1.254 2340: 002333: Nov 27
15:15:50.870 CET: Vi1 IPCP: Add link info for cef entry 83.233.168.2
27-11-2006 14:17:10 Syslog.Notice 192.168.1.254 2341: 002334: Nov 27
15:16:49.351 CET: %SYS-5-CONFIG_I: Configured from console by maggiore on
vty0 (192.168.1.11)
27-11-2006 14:17:11 Syslog.Debug 192.168.1.254 2342: 002335: Nov 27
15:16:51.191 CET: Vi1 PPP: Missed 5 keepalives, taking LCP down
27-11-2006 14:17:11 Syslog.Debug 192.168.1.254 2343: 002336: Nov 27
15:16:51.191 CET: Vi1 PPP: Sending Acct Event[Down] id[58]
27-11-2006 14:17:11 Syslog.Debug 192.168.1.254 2344: 002337: Nov 27
15:16:51.191 CET: Vi1 LCP: State is Closed
27-11-2006 14:17:11 Syslog.Debug 192.168.1.254 2345: 002338: Nov 27
15:16:51.191 CET: Vi1 PPP: Phase is DOWN
27-11-2006 14:17:11 Syslog.Debug 192.168.1.254 2346: 002339: Nov 27
15:16:51.191 CET: Vi1 CCP: State is Closed
27-11-2006 14:17:11 Syslog.Debug 192.168.1.254 2347: 002340: Nov 27
15:16:51.195 CET: Vi1 IPCP: State is Closed
27-11-2006 14:17:11 Syslog.Debug 192.168.1.254 2348: 002341: Nov 27
15:16:51.195 CET: Vi1 IPCP: Remove link info for cef entry 83.233.168.2
27-11-2006 14:17:11 Syslog.Debug 192.168.1.254 2349: 002342: Nov 27
15:16:51.195 CET: Vi1 PPP SSS: Send DISCONNECT to mgr_hdl[300001D]
27-11-2006 14:17:11 Syslog.Debug 192.168.1.254 2350: 002343: Nov 27
15:16:51.199 CET: Vi1 PPP: Phase is ESTABLISHING, Passive Open
27-11-2006 14:17:12 Syslog.Debug 192.168.1.254 2351: 002344: Nov 27
15:16:51.199 CET: Vi1 LCP: State is Listen
27-11-2006 14:17:12 Syslog.Debug 192.168.1.254 2352: 002345: Nov 27
15:16:51.211 CET: Di0 IPCP: Remove route to 83.233.168.2
27-11-2006 14:17:13 Syslog.Notice 192.168.1.254 2353: 002346: Nov 27
15:16:52.191 CET: %LINEPROTO-5-UPDOWN: Line protocol on Interface
Virtual-Access1, changed state to down
27-11-2006 14:17:13 Syslog.Debug 192.168.1.254 2354: 002347: Nov 27
15:16:53.207 CET: Vi1 LCP: Timeout: State Listen
27-11-2006 14:17:13 Syslog.Debug 192.168.1.254 2355: 002348: Nov 27
15:16:53.207 CET: Vi1 PPP: No remote authentication for call-out
27-11-2006 14:17:13 Syslog.Debug 192.168.1.254 2356: 002349: Nov 27
15:16:53.207 CET: Vi1 LCP: O CONFREQ [Listen] id 3 len 16
27-11-2006 14:17:13 Syslog.Debug 192.168.1.254 2357: 002350: Nov 27
15:16:53.207 CET: Vi1 LCP: MRU 1450 (0x010405AA)
27-11-2006 14:17:13 Syslog.Debug 192.168.1.254 2358: 002351: Nov 27
15:16:53.207 CET: Vi1 LCP: MagicNumber 0x1415E1B5 (0x05061415E1B5)
27-11-2006 14:17:13 Syslog.Debug 192.168.1.254 2359: 002352: Nov 27
15:16:53.207 CET: Vi1 LCP: PFC (0x0702)
27-11-2006 14:17:13 Syslog.Debug 192.168.1.254 2360: 002353: Nov 27
15:16:53.315 CET: Vi1 LCP: I TERMREQ [REQsent] id 2 len 17
(0x4D5050452064697361626C6564)
27-11-2006 14:17:13 Syslog.Debug 192.168.1.254 2361: 002354: Nov 27
15:16:53.319 CET: Vi1 LCP: O TERMACK [REQsent] id 2 len 4
27-11-2006 14:17:13 Syslog.Debug 192.168.1.254 2362: 002355: Nov 27
15:16:53.319 CET: Vi1 LCP: I CONFREQ [REQsent] id 3 len 25
27-11-2006 14:17:13 Syslog.Debug 192.168.1.254 2363: 002356: Nov 27
15:16:53.319 CET: Vi1 LCP: ACCM 0x00000000 (0x020600000000)
27-11-2006 14:17:13 Syslog.Debug 192.168.1.254 2364: 002357: Nov 27
15:16:53.319 CET: Vi1 LCP: AuthProto MS-CHAP-V2 (0x0305C22381)
27-11-2006 14:17:13 Syslog.Debug 192.168.1.254 2365: 002358: Nov 27
15:16:53.319 CET: Vi1 LCP: MagicNumber 0xF4ECC6C3 (0x0506F4ECC6C3)
27-11-2006 14:17:13 Syslog.Debug 192.168.1.254 2366: 002359: Nov 27
15:16:53.319 CET: Vi1 LCP: PFC (0x0702)
27-11-2006 14:17:13 Syslog.Debug 192.168.1.254 2367: 002360: Nov 27
15:16:53.319 CET: Vi1 LCP: ACFC (0x0802)
27-11-2006 14:17:13 Syslog.Debug 192.168.1.254 2368: 002361: Nov 27
15:16:53.323 CET: Vi1 LCP: O CONFACK [REQsent] id 3 len 25
27-11-2006 14:17:13 Syslog.Debug 192.168.1.254 2369: 002362: Nov 27
15:16:53.323 CET: Vi1 LCP: ACCM 0x00000000 (0x020600000000)
27-11-2006 14:17:13 Syslog.Debug 192.168.1.254 2370: 002363: Nov 27
15:16:53.323 CET: Vi1 LCP: AuthProto MS-CHAP-V2 (0x0305C22381)
27-11-2006 14:17:13 Syslog.Debug 192.168.1.254 2371: 002364: Nov 27
15:16:53.323 CET: Vi1 LCP: MagicNumber 0xF4ECC6C3 (0x0506F4ECC6C3)
27-11-2006 14:17:13 Syslog.Debug 192.168.1.254 2372: 002365: Nov 27
15:16:53.323 CET: Vi1 LCP: PFC (0x0702)
27-11-2006 14:17:13 Syslog.Debug 192.168.1.254 2373: 002366: Nov 27
15:16:53.323 CET: Vi1 LCP: ACFC (0x0802)
27-11-2006 14:17:13 Syslog.Debug 192.168.1.254 2374: 002367: Nov 27
15:16:53.323 CET: Vi1 LCP: I CONFACK [ACKsent] id 3 len 16
27-11-2006 14:17:13 Syslog.Debug 192.168.1.254 2375: 002368: Nov 27
15:16:53.323 CET: Vi1 LCP: MRU 1450 (0x010405AA)
27-11-2006 14:17:13 Syslog.Debug 192.168.1.254 2376: 002369: Nov 27
15:16:53.327 CET: Vi1 LCP: MagicNumber 0x1415E1B5 (0x05061415E1B5)
27-11-2006 14:17:13 Syslog.Debug 192.168.1.254 2377: 002370: Nov 27
15:16:53.327 CET: Vi1 LCP: PFC (0x0702)
27-11-2006 14:17:13 Syslog.Debug 192.168.1.254 2378: 002371: Nov 27
15:16:53.327 CET: Vi1 LCP: State is Open
27-11-2006 14:17:13 Syslog.Debug 192.168.1.254 2379: 002372: Nov 27
15:16:53.327 CET: Vi1 PPP: Phase is AUTHENTICATING, by the peer
27-11-2006 14:17:13 Syslog.Debug 192.168.1.254 2380: 002373: Nov 27
15:16:53.391 CET: Vi1 MS-CHAP-V2: I CHALLENGE id 53 len 26 from "pptpd"
27-11-2006 14:17:13 Syslog.Debug 192.168.1.254 2381: 002374: Nov 27
15:16:53.427 CET: Vi1 MS CHAP V2: Using hostname from interface CHAP
27-11-2006 14:17:13 Syslog.Debug 192.168.1.254 2382: 002375: Nov 27
15:16:53.427 CET: Vi1 MS CHAP V2: Using password from interface CHAP
27-11-2006 14:17:13 Syslog.Debug 192.168.1.254 2383: 002376: Nov 27
15:16:53.427 CET: Vi1 MS-CHAP-V2: O RESPONSE id 53 len 62 from "maggiore"
27-11-2006 14:17:13 Syslog.Debug 192.168.1.254 2384: 002377: Nov 27
15:16:53.491 CET: Vi1 MS-CHAP-V2: I SUCCESS id 53 len 4
27-11-2006 14:17:13 Syslog.Debug 192.168.1.254 2385: 002378: Nov 27
15:16:53.491 CET: Vi1 MS CHAP V2 No Password found for : pptpd
27-11-2006 14:17:13 Syslog.Debug 192.168.1.254 2386: 002379: Nov 27
15:16:53.495 CET: Vi1 MS CHAP V2 No Password found for : pptpd
27-11-2006 14:17:13 Syslog.Debug 192.168.1.254 2387: 002380: Nov 27
15:16:53.495 CET: Vi1 MS CHAP V2 CheckAuthenticatorResponse Failed for :
maggiore
27-11-2006 14:17:13 Syslog.Debug 192.168.1.254 2388: 002381: Nov 27
15:16:53.495 CET: Vi1 PPP: Phase is FORWARDING, Attempting Forward
27-11-2006 14:17:13 Syslog.Debug 192.168.1.254 2389: 002382: Nov 27
15:16:53.495 CET: Vi1 PPP: Sending Acct Event[Down] id[59]
27-11-2006 14:17:13 Syslog.Debug 192.168.1.254 2390: 002383: Nov 27
15:16:53.499 CET: Vi1 PPP: Phase is TERMINATING
27-11-2006 14:17:13 Syslog.Debug 192.168.1.254 2391: 002384: Nov 27
15:16:53.499 CET: Vi1 LCP: O TERMREQ [Open] id 4 len 4
27-11-2006 14:17:13 Syslog.Debug 192.168.1.254 2392: 002385: Nov 27
15:16:53.503 CET: Vi1 PPP SSS: Receive SSS-Mgr Connect-Local
27-11-2006 14:17:13 Syslog.Debug 192.168.1.254 2393: 002386: Nov 27
15:16:53.503 CET: Vi1 PPP: Phase is ESTABLISHING, Finish LCP
27-11-2006 14:17:13 Syslog.Debug 192.168.1.254 2394: 002387: Nov 27
15:16:53.563 CET: Vi1 LCP: I TERMACK [TERMsent] id 4 len 4
27-11-2006 14:17:13 Syslog.Debug 192.168.1.254 2395: 002388: Nov 27
15:16:53.563 CET: Vi1 LCP: State is Closed
27-11-2006 14:17:13 Syslog.Debug 192.168.1.254 2396: 002389: Nov 27
15:16:53.563 CET: Vi1 PPP: Phase is DOWN
27-11-2006 14:17:13 Syslog.Debug 192.168.1.254 2397: 002390: Nov 27
15:16:53.563 CET: Vi1 PPP SSS: Send DISCONNECT to mgr_hdl[3200001F]
27-11-2006 14:17:14 Syslog.Debug 192.168.1.254 2398: 002391: Nov 27
15:16:53.563 CET: Vi1 PPP: Phase is ESTABLISHING, Passive Open
27-11-2006 14:17:14 Syslog.Debug 192.168.1.254 2399: 002392: Nov 27
15:16:53.563 CET: Vi1 LCP: State is Listen
27-11-2006 14:17:16 Syslog.Debug 192.168.1.254 2400: 002393: Nov 27
15:16:55.575 CET: Vi1 LCP: Timeout: State Listen
27-11-2006 14:17:16 Syslog.Debug 192.168.1.254 2401: 002394: Nov 27
15:16:55.575 CET: Vi1 PPP: No remote authentication for call-out
27-11-2006 14:17:16 Syslog.Debug 192.168.1.254 2402: 002395: Nov 27
15:16:55.575 CET: Vi1 LCP: O CONFREQ [Listen] id 5 len 16
27-11-2006 14:17:16 Syslog.Debug 192.168.1.254 2403: 002396: Nov 27
15:16:55.575 CET: Vi1 LCP: MRU 1450 (0x010405AA)
27-11-2006 14:17:16 Syslog.Debug 192.168.1.254 2404: 002397: Nov 27
15:16:55.575 CET: Vi1 LCP: MagicNumber 0x1415EAF7 (0x05061415EAF7)
27-11-2006 14:17:16 Syslog.Debug 192.168.1.254 2405: 002398: Nov 27
15:16:55.575 CET: Vi1 LCP: PFC (0x0702)
27-11-2006 14:17:16 Syslog.Debug 192.168.1.254 2406: 002399: Nov 27
15:16:56.339 CET: Vi1 Tnl/Sn 16/14 PPTP: Destroying session, informational
traceback below: -Traceback= 0x81B3D494 0x81B1C25C 0x81B1C338 0x81B39C50
0x81B3C008 0x81B3AA90 0x81B3B51C 0x81B3B980 0x805FE638 0x80601C18
27-11-2006 14:17:16 Syslog.Debug 192.168.1.254 2407: 002400: Nov 27
15:16:56.343 CET: Vi1 Tnl/Sn 16/14 PPTP: Unbinding session from idb,
informational traceback: -Traceback= 0x81AFBF54 0x81B3D4C8 0x81B1C25C
0x81B1C338 0x81B39C50 0x81B3C008 0x81B3AA90 0x81B3B51C 0x81B3B980 0x805FE638
0x80601C18
27-11-2006 14:17:16 Syslog.Debug 192.168.1.254 2408: 002401: Nov 27
15:16:56.347 CET: Vi1 VPDN: Resetting interface, informational traceback
below: -Traceback= 0x81AFC870 0x81AFC974 0x81AFCB7C 0x81B3D4D4 0x81B1C25C
0x81B1C338 0x81B39C50 0x81B3C008 0x81B3AA90 0x81B3B51C 0x81B3B980 0x805FE638
0x80601C18
27-11-2006 14:17:16 Syslog.Error 192.168.1.254 2409: 002402: Nov 27
15:16:56.351 CET: %LINK-3-UPDOWN: Interface Virtual-Access1, changed state
to down
27-11-2006 14:17:17 Syslog.Debug 192.168.1.254 2410: 002403: Nov 27
15:16:56.355 CET: Vi1 PPP: Sending Acct Event[Down] id[5A]
27-11-2006 14:17:17 Syslog.Debug 192.168.1.254 2411: 002404: Nov 27
15:16:56.355 CET: Vi1 LCP: State is Closed
27-11-2006 14:17:17 Syslog.Debug 192.168.1.254 2412: 002405: Nov 27
15:16:56.355 CET: Vi1 PPP: Phase is DOWN
27-11-2006 14:17:27 Syslog.Notice 192.168.1.254 2413: 002406: Nov 27
15:17:06.811 CET: %LINK-5-CHANGED: Interface Dialer0, changed state to
administratively down
27-11-2006 14:17:28 Syslog.Notice 192.168.1.254 2414: 002407: Nov 27
15:17:07.835 CET: %SYS-5-CONFIG_I: Configured from console by maggiore on
vty0 (192.168.1.11)


As it is clear, it missed the keepalives.
Now here is the log without keepalives

27-11-2006 14:19:52 Syslog.Debug 192.168.1.254 2416: 002409: Nov 27
15:19:32.598 CET: Vi1 PPP: Phase is DOWN, Setup
27-11-2006 14:19:52 Syslog.Error 192.168.1.254 2417: 002410: Nov 27
15:19:32.834 CET: %LINK-3-UPDOWN: Interface Virtual-Access1, changed state
to up
27-11-2006 14:19:52 Syslog.Debug 192.168.1.254 2418: 002411: Nov 27
15:19:32.862 CET: Vi1 PPP: Using dialer call direction
27-11-2006 14:19:52 Syslog.Debug 192.168.1.254 2419: 002412: Nov 27
15:19:32.862 CET: Vi1 PPP: Treating connection as a callout
27-11-2006 14:19:52 Syslog.Debug 192.168.1.254 2420: 002413: Nov 27
15:19:32.862 CET: Vi1 PPP: Session handle[14000031] Session id[20]
27-11-2006 14:19:52 Syslog.Debug 192.168.1.254 2421: 002414: Nov 27
15:19:32.862 CET: Vi1 PPP: Phase is ESTABLISHING, Active Open
27-11-2006 14:19:52 Syslog.Debug 192.168.1.254 2422: 002415: Nov 27
15:19:32.862 CET: Vi1 PPP: No remote authentication for call-out
27-11-2006 14:19:52 Syslog.Debug 192.168.1.254 2423: 002416: Nov 27
15:19:32.862 CET: Vi1 LCP: O CONFREQ [Closed] id 1 len 16
27-11-2006 14:19:52 Syslog.Debug 192.168.1.254 2424: 002417: Nov 27
15:19:32.862 CET: Vi1 LCP: MRU 1450 (0x010405AA)
27-11-2006 14:19:52 Syslog.Debug 192.168.1.254 2425: 002418: Nov 27
15:19:32.862 CET: Vi1 LCP: MagicNumber 0x14185229 (0x050614185229)
27-11-2006 14:19:52 Syslog.Debug 192.168.1.254 2426: 002419: Nov 27
15:19:32.866 CET: Vi1 LCP: PFC (0x0702)
27-11-2006 14:19:53 Syslog.Debug 192.168.1.254 2427: 002420: Nov 27
15:19:32.922 CET: Vi1 LCP: I CONFACK [REQsent] id 1 len 16
27-11-2006 14:19:53 Syslog.Debug 192.168.1.254 2428: 002421: Nov 27
15:19:32.922 CET: Vi1 LCP: MRU 1450 (0x010405AA)
27-11-2006 14:19:53 Syslog.Debug 192.168.1.254 2429: 002422: Nov 27
15:19:32.922 CET: Vi1 LCP: MagicNumber 0x14185229 (0x050614185229)
27-11-2006 14:19:53 Syslog.Debug 192.168.1.254 2430: 002423: Nov 27
15:19:32.926 CET: Vi1 LCP: PFC (0x0702)
27-11-2006 14:19:54 Syslog.Error 192.168.1.254 2431: 002424: Nov 27
15:19:34.586 CET: %LINK-3-UPDOWN: Interface Dialer0, changed state to up
27-11-2006 14:19:55 Syslog.Debug 192.168.1.254 2432: 002425: Nov 27
15:19:34.834 CET: Vi1 LCP: Timeout: State ACKrcvd
27-11-2006 14:19:55 Syslog.Debug 192.168.1.254 2433: 002426: Nov 27
15:19:34.834 CET: Vi1 LCP: O CONFREQ [ACKrcvd] id 2 len 16
27-11-2006 14:19:55 Syslog.Debug 192.168.1.254 2434: 002427: Nov 27
15:19:34.834 CET: Vi1 LCP: MRU 1450 (0x010405AA)
27-11-2006 14:19:55 Syslog.Debug 192.168.1.254 2435: 002428: Nov 27
15:19:34.834 CET: Vi1 LCP: MagicNumber 0x14185229 (0x050614185229)
27-11-2006 14:19:55 Syslog.Debug 192.168.1.254 2436: 002429: Nov 27
15:19:34.834 CET: Vi1 LCP: PFC (0x0702)
27-11-2006 14:19:55 Syslog.Debug 192.168.1.254 2437: 002430: Nov 27
15:19:34.894 CET: Vi1 LCP: I CONFACK [REQsent] id 2 len 16
27-11-2006 14:19:55 Syslog.Debug 192.168.1.254 2438: 002431: Nov 27
15:19:34.894 CET: Vi1 LCP: MRU 1450 (0x010405AA)
27-11-2006 14:19:55 Syslog.Debug 192.168.1.254 2439: 002432: Nov 27
15:19:34.894 CET: Vi1 LCP: MagicNumber 0x14185229 (0x050614185229)
27-11-2006 14:19:55 Syslog.Debug 192.168.1.254 2440: 002433: Nov 27
15:19:34.894 CET: Vi1 LCP: PFC (0x0702)
27-11-2006 14:19:55 Syslog.Debug 192.168.1.254 2441: 002434: Nov 27
15:19:35.826 CET: Vi1 LCP: I CONFREQ [ACKrcvd] id 1 len 25
27-11-2006 14:19:55 Syslog.Debug 192.168.1.254 2442: 002435: Nov 27
15:19:35.826 CET: Vi1 LCP: ACCM 0x00000000 (0x020600000000)
27-11-2006 14:19:55 Syslog.Debug 192.168.1.254 2443: 002436: Nov 27
15:19:35.826 CET: Vi1 LCP: AuthProto MS-CHAP-V2 (0x0305C22381)
27-11-2006 14:19:55 Syslog.Debug 192.168.1.254 2444: 002437: Nov 27
15:19:35.830 CET: Vi1 LCP: MagicNumber 0xB17FBAED (0x0506B17FBAED)
27-11-2006 14:19:55 Syslog.Debug 192.168.1.254 2445: 002438: Nov 27
15:19:35.830 CET: Vi1 LCP: PFC (0x0702)
27-11-2006 14:19:55 Syslog.Debug 192.168.1.254 2446: 002439: Nov 27
15:19:35.830 CET: Vi1 LCP: ACFC (0x0802)
27-11-2006 14:19:55 Syslog.Debug 192.168.1.254 2447: 002440: Nov 27
15:19:35.830 CET: Vi1 LCP: O CONFACK [ACKrcvd] id 1 len 25
27-11-2006 14:19:55 Syslog.Debug 192.168.1.254 2448: 002441: Nov 27
15:19:35.830 CET: Vi1 LCP: ACCM 0x00000000 (0x020600000000)
27-11-2006 14:19:55 Syslog.Debug 192.168.1.254 2449: 002442: Nov 27
15:19:35.830 CET: Vi1 LCP: AuthProto MS-CHAP-V2 (0x0305C22381)
27-11-2006 14:19:55 Syslog.Debug 192.168.1.254 2450: 002443: Nov 27
15:19:35.830 CET: Vi1 LCP: MagicNumber 0xB17FBAED (0x0506B17FBAED)
27-11-2006 14:19:55 Syslog.Debug 192.168.1.254 2451: 002444: Nov 27
15:19:35.834 CET: Vi1 LCP: PFC (0x0702)
27-11-2006 14:19:55 Syslog.Debug 192.168.1.254 2452: 002445: Nov 27
15:19:35.834 CET: Vi1 LCP: ACFC (0x0802)
27-11-2006 14:19:55 Syslog.Debug 192.168.1.254 2453: 002446: Nov 27
15:19:35.834 CET: Vi1 LCP: State is Open
27-11-2006 14:19:55 Syslog.Debug 192.168.1.254 2454: 002447: Nov 27
15:19:35.834 CET: Vi1 PPP: Phase is AUTHENTICATING, by the peer
27-11-2006 14:19:55 Syslog.Debug 192.168.1.254 2455: 002448: Nov 27
15:19:35.894 CET: Vi1 MS-CHAP-V2: I CHALLENGE id 118 len 26 from "pptpd"
27-11-2006 14:19:55 Syslog.Debug 192.168.1.254 2456: 002449: Nov 27
15:19:35.930 CET: Vi1 MS CHAP V2: Using hostname from interface CHAP
27-11-2006 14:19:55 Syslog.Debug 192.168.1.254 2457: 002450: Nov 27
15:19:35.934 CET: Vi1 MS CHAP V2: Using password from interface CHAP
27-11-2006 14:19:55 Syslog.Debug 192.168.1.254 2458: 002451: Nov 27
15:19:35.934 CET: Vi1 MS-CHAP-V2: O RESPONSE id 118 len 62 from "maggiore"
27-11-2006 14:19:55 Syslog.Debug 192.168.1.254 2459: 002452: Nov 27
15:19:35.998 CET: Vi1 MS-CHAP-V2: I SUCCESS id 118 len 46 msg is
"S=F4B814ED6FC49CEEC89EA9878D589FD64AC5C459"
27-11-2006 14:19:56 Syslog.Debug 192.168.1.254 2460: 002453: Nov 27
15:19:36.002 CET: Vi1 MS CHAP V2 No Password found for : pptpd
27-11-2006 14:19:56 Syslog.Debug 192.168.1.254 2461: 002454: Nov 27
15:19:36.002 CET: Vi1 MS CHAP V2 No Password found for : pptpd
27-11-2006 14:19:56 Syslog.Debug 192.168.1.254 2462: 002455: Nov 27
15:19:36.002 CET: Vi1 MS CHAP V2 Check AuthenticatorResponse Success for :
maggiore
27-11-2006 14:19:56 Syslog.Debug 192.168.1.254 2463: 002456: Nov 27
15:19:36.002 CET: Vi1 PPP: Phase is FORWARDING, Attempting Forward
27-11-2006 14:19:56 Syslog.Debug 192.168.1.254 2464: 002457: Nov 27
15:19:36.002 CET: Vi1 PPP: Queue CCP code[1] id[1]
27-11-2006 14:19:56 Syslog.Debug 192.168.1.254 2465: 002458: Nov 27
15:19:36.006 CET: Vi1 PPP SSS: Receive SSS-Mgr Connect-Local
27-11-2006 14:19:56 Syslog.Debug 192.168.1.254 2466: 002459: Nov 27
15:19:36.006 CET: Vi1 PPP: Phase is ESTABLISHING, Finish LCP
27-11-2006 14:19:56 Syslog.Debug 192.168.1.254 2467: 002460: Nov 27
15:19:36.010 CET: Vi1 PPP: Phase is UP
27-11-2006 14:19:56 Syslog.Debug 192.168.1.254 2468: 002461: Nov 27
15:19:36.010 CET: Vi1 IPCP: O CONFREQ [Closed] id 1 len 10
27-11-2006 14:19:56 Syslog.Debug 192.168.1.254 2469: 002462: Nov 27
15:19:36.010 CET: Vi1 IPCP: Address 0.0.0.0 (0x030600000000)
27-11-2006 14:19:56 Syslog.Debug 192.168.1.254 2470: 002463: Nov 27
15:19:36.010 CET: Vi1 CCP: O CONFREQ [Closed] id 1 len 10
27-11-2006 14:19:56 Syslog.Debug 192.168.1.254 2471: 002464: Nov 27
15:19:36.010 CET: Vi1 CCP: MS-PPC supported bits 0x01000060
(0x120601000060)
27-11-2006 14:19:56 Syslog.Debug 192.168.1.254 2472: 002465: Nov 27
15:19:36.010 CET: Vi1 PPP: Process pending ncp packets
27-11-2006 14:19:56 Syslog.Debug 192.168.1.254 2473: 002466: Nov 27
15:19:36.014 CET: Vi1 CCP: Redirect packet to Vi1
27-11-2006 14:19:56 Syslog.Debug 192.168.1.254 2474: 002467: Nov 27
15:19:36.014 CET: Vi1 CCP: I CONFREQ [REQsent] id 1 len 10
27-11-2006 14:19:56 Syslog.Debug 192.168.1.254 2475: 002468: Nov 27
15:19:36.014 CET: Vi1 CCP: MS-PPC supported bits 0x01000040
(0x120601000040)
27-11-2006 14:19:56 Syslog.Debug 192.168.1.254 2476: 002469: Nov 27
15:19:36.014 CET: Vi1 CCP: O CONFACK [REQsent] id 1 len 10
27-11-2006 14:19:56 Syslog.Debug 192.168.1.254 2477: 002470: Nov 27
15:19:36.014 CET: Vi1 CCP: MS-PPC supported bits 0x01000040
(0x120601000040)
27-11-2006 14:19:56 Syslog.Debug 192.168.1.254 2478: 002471: Nov 27
15:19:36.074 CET: Vi1 IPCP: I TERMACK [REQsent] id 1 len 4
27-11-2006 14:19:56 Syslog.Debug 192.168.1.254 2479: 002472: Nov 27
15:19:36.074 CET: Vi1 CCP: I CONFNAK [ACKsent] id 1 len 10
27-11-2006 14:19:56 Syslog.Debug 192.168.1.254 2480: 002473: Nov 27
15:19:36.078 CET: Vi1 CCP: MS-PPC supported bits 0x01000040
(0x120601000040)
27-11-2006 14:19:56 Syslog.Debug 192.168.1.254 2481: 002474: Nov 27
15:19:36.078 CET: Vi1 CCP: O CONFREQ [ACKsent] id 2 len 10
27-11-2006 14:19:56 Syslog.Debug 192.168.1.254 2482: 002475: Nov 27
15:19:36.078 CET: Vi1 CCP: MS-PPC supported bits 0x01000040
(0x120601000040)
27-11-2006 14:19:56 Syslog.Debug 192.168.1.254 2483: 002476: Nov 27
15:19:36.138 CET: Vi1 CCP: I CONFACK [ACKsent] id 2 len 10
27-11-2006 14:19:56 Syslog.Debug 192.168.1.254 2484: 002477: Nov 27
15:19:36.138 CET: Vi1 CCP: MS-PPC supported bits 0x01000040
(0x120601000040)
27-11-2006 14:19:56 Syslog.Debug 192.168.1.254 2485: 002478: Nov 27
15:19:36.138 CET: Vi1 CCP: State is Open
27-11-2006 14:19:56 Syslog.Debug 192.168.1.254 2486: 002479: Nov 27
15:19:36.142 CET: Vi1 IPCP: I CONFREQ [REQsent] id 1 len 10
27-11-2006 14:19:56 Syslog.Debug 192.168.1.254 2487: 002480: Nov 27
15:19:36.146 CET: Vi1 IPCP: Address 83.233.168.2 (0x030653E9A802)
27-11-2006 14:19:56 Syslog.Debug 192.168.1.254 2488: 002481: Nov 27
15:19:36.146 CET: Vi1 IPCP: O CONFACK [REQsent] id 1 len 10
27-11-2006 14:19:56 Syslog.Debug 192.168.1.254 2489: 002482: Nov 27
15:19:36.146 CET: Vi1 IPCP: Address 83.233.168.2 (0x030653E9A802)
27-11-2006 14:19:57 Syslog.Notice 192.168.1.254 2490: 002483: Nov 27
15:19:37.006 CET: %LINEPROTO-5-UPDOWN: Line protocol on Interface
Virtual-Access1, changed state to up
27-11-2006 14:19:58 Syslog.Debug 192.168.1.254 2491: 002484: Nov 27
15:19:38.002 CET: Vi1 IPCP: Timeout: State ACKsent
27-11-2006 14:19:58 Syslog.Debug 192.168.1.254 2492: 002485: Nov 27
15:19:38.002 CET: Vi1 IPCP: O CONFREQ [ACKsent] id 2 len 10
27-11-2006 14:19:58 Syslog.Debug 192.168.1.254 2493: 002486: Nov 27
15:19:38.002 CET: Vi1 IPCP: Address 0.0.0.0 (0x030600000000)
27-11-2006 14:19:58 Syslog.Debug 192.168.1.254 2494: 002487: Nov 27
15:19:38.062 CET: Vi1 IPCP: I CONFNAK [ACKsent] id 2 len 10
27-11-2006 14:19:58 Syslog.Debug 192.168.1.254 2495: 002488: Nov 27
15:19:38.062 CET: Vi1 IPCP: Address 83.233.168.52 (0x030653E9A834)
27-11-2006 14:19:58 Syslog.Debug 192.168.1.254 2496: 002489: Nov 27
15:19:38.062 CET: Vi1 IPCP: O CONFREQ [ACKsent] id 3 len 10
27-11-2006 14:19:58 Syslog.Debug 192.168.1.254 2497: 002490: Nov 27
15:19:38.062 CET: Vi1 IPCP: Address 83.233.168.52 (0x030653E9A834)
27-11-2006 14:19:58 Syslog.Debug 192.168.1.254 2498: 002491: Nov 27
15:19:38.126 CET: Vi1 IPCP: I CONFACK [ACKsent] id 3 len 10
27-11-2006 14:19:59 Syslog.Debug 192.168.1.254 2499: 002492: Nov 27
15:19:38.126 CET: Vi1 IPCP: Address 83.233.168.52 (0x030653E9A834)
27-11-2006 14:19:59 Syslog.Debug 192.168.1.254 2500: 002493: Nov 27
15:19:38.126 CET: Vi1 IPCP: State is Open
27-11-2006 14:19:59 Syslog.Debug 192.168.1.254 2501: 002494: Nov 27
15:19:38.126 CET: Di0 IPCP: Install negotiated IP interface address
83.233.168.52
27-11-2006 14:19:59 Syslog.Debug 192.168.1.254 2502: 002495: Nov 27
15:19:38.134 CET: Di0 IPCP: Install route to 83.233.168.2
27-11-2006 14:19:59 Syslog.Debug 192.168.1.254 2503: 002496: Nov 27
15:19:38.138 CET: Vi1 IPCP: Add link info for cef entry 83.233.168.2
27-11-2006 14:21:30 Syslog.Notice 192.168.1.254 2504: 002497: Nov 27
15:21:09.427 CET: %SYS-5-CONFIG_I: Configured from console by maggiore on
vty0 (192.168.1.11)
27-11-2006 14:22:31 Syslog.Debug 192.168.1.254 2505: 002498: Nov 27
15:22:10.977 CET: Vi1 LCP: I TERMREQ [Open] id 3 len 17
(0x4D5050452064697361626C6564)
27-11-2006 14:22:31 Syslog.Debug 192.168.1.254 2506: 002499: Nov 27
15:22:10.977 CET: Vi1 LCP: O TERMACK [Open] id 3 len 4
27-11-2006 14:22:31 Syslog.Debug 192.168.1.254 2507: 002500: Nov 27
15:22:10.981 CET: Vi1 PPP: Sending Acct Event[Down] id[5B]
27-11-2006 14:22:31 Syslog.Debug 192.168.1.254 2508: 002501: Nov 27
15:22:10.981 CET: Vi1 PPP: Phase is TERMINATING
27-11-2006 14:22:33 Syslog.Debug 192.168.1.254 2509: 002502: Nov 27
15:22:12.985 CET: Vi1 LCP: Timeout: State TERMsent
27-11-2006 14:22:33 Syslog.Debug 192.168.1.254 2510: 002503: Nov 27
15:22:12.985 CET: Vi1 LCP: State is Closed
27-11-2006 14:22:33 Syslog.Debug 192.168.1.254 2511: 002504: Nov 27
15:22:12.985 CET: Vi1 PPP: Phase is DOWN
27-11-2006 14:22:33 Syslog.Debug 192.168.1.254 2512: 002505: Nov 27
15:22:12.985 CET: Vi1 CCP: State is Closed
27-11-2006 14:22:33 Syslog.Debug 192.168.1.254 2513: 002506: Nov 27
15:22:12.989 CET: Vi1 IPCP: State is Closed
27-11-2006 14:22:33 Syslog.Debug 192.168.1.254 2514: 002507: Nov 27
15:22:12.989 CET: Vi1 IPCP: Remove link info for cef entry 83.233.168.2
27-11-2006 14:22:33 Syslog.Debug 192.168.1.254 2515: 002508: Nov 27
15:22:12.989 CET: Vi1 PPP SSS: Send DISCONNECT to mgr_hdl[D3000021]
27-11-2006 14:22:33 Syslog.Debug 192.168.1.254 2516: 002509: Nov 27
15:22:12.989 CET: Vi1 PPP: Phase is ESTABLISHING, Passive Open
27-11-2006 14:22:33 Syslog.Debug 192.168.1.254 2517: 002510: Nov 27
15:22:12.989 CET: Vi1 LCP: State is Listen
27-11-2006 14:22:33 Syslog.Debug 192.168.1.254 2518: 002511: Nov 27
15:22:13.001 CET: Di0 IPCP: Remove route to 83.233.168.2
27-11-2006 14:22:33 Syslog.Debug 192.168.1.254 2519: 002512: Nov 27
15:22:13.977 CET: Vi1 LCP: I TERMREQ [Listen] id 4 len 17
(0x4D5050452064697361626C6564)
27-11-2006 14:22:33 Syslog.Debug 192.168.1.254 2520: 002513: Nov 27
15:22:13.977 CET: Vi1 LCP: O TERMACK [Listen] id 4 len 4
27-11-2006 14:22:33 Syslog.Notice 192.168.1.254 2521: 002514: Nov 27
15:22:13.993 CET: %LINEPROTO-5-UPDOWN: Line protocol on Interface
Virtual-Access1, changed state to down
27-11-2006 14:22:33 Syslog.Debug 192.168.1.254 2522: 002515: Nov 27
15:22:14.005 CET: Vi1 Tnl/Sn 17/15 PPTP: Destroying session, informational
traceback below: -Traceback= 0x81B3D494 0x81B1C25C 0x81B1C338 0x81B39C50
0x81B3C008 0x81B3AA90 0x81B3B51C 0x81B3B980 0x805FE638 0x80601C18
27-11-2006 14:22:33 Syslog.Debug 192.168.1.254 2523: 002516: Nov 27
15:22:14.005 CET: Vi1 Tnl/Sn 17/15 PPTP: Unbinding session from idb,
informational traceback: -Traceback= 0x81AFBF54 0x81B3D4C8 0x81B1C25C
0x81B1C338 0x81B39C50 0x81B3C008 0x81B3AA90 0x81B3B51C 0x81B3B980 0x805FE638
0x80601C18
27-11-2006 14:22:33 Syslog.Debug 192.168.1.254 2524: 002517: Nov 27
15:22:14.009 CET: Vi1 VPDN: Resetting interface, informational traceback
below:
27-11-2006 14:22:33 Syslog.Debug 192.168.1.254 2525: -Traceback= 0x81AFC870
0x81AFC974 0x81AFCB7C 0x81B3D4D4 0x81B1C25C 0x81B1C338 0x81B39C50 0x81B3C008
0x81B3AA90 0x81B3B51C 0x81B3B980 0x805FE638 0x80601C18
27-11-2006 14:22:33 Syslog.Error 192.168.1.254 2526: 002518: Nov 27
15:22:14.013 CET: %LINK-3-UPDOWN: Interface Virtual-Access1, changed state
to down
27-11-2006 14:22:34 Syslog.Debug 192.168.1.254 2527: 002519: Nov 27
15:22:14.017 CET: Vi1 LCP: State is Closed
27-11-2006 14:22:34 Syslog.Debug 192.168.1.254 2528: 002520: Nov 27
15:22:14.017 CET: Vi1 PPP: Phase is DOWN
27-11-2006 14:22:45 Syslog.Notice 192.168.1.254 2529: 002521: Nov 27
15:22:24.325 CET: %LINK-5-CHANGED: Interface Dialer0, changed state to
administratively down





From: Dan Lanciani on 28 Nov 2006 04:04
In article <r_ednX02W88he_fYRVnygA(a)kpnqwest.it>, admin(a)NOSPAMspadhausen.com (E. S.) writes:
| OK
|
| I activated now this debug:
|
| gateway#show debug
| VPN:
| VPDN errors debugging is on
| VPN disconnect debugging is on
| PPP:
| PPP protocol errors debugging is on
| PPP protocol negotiation debugging is on

[...]

It might be time to hook up a sniffer or look at the logs from the
other end. (Or try packet level debugging, but it will be verbose.)
Again, just to be sure, 88.233.168.2 is not an address you use for
"initiate-to ip" in the vpdn definition, right?


Dan Lanciani
ddl(a)danlan.*com
From: Elia Spadoni on 28 Nov 2006 11:59

>
> It might be time to hook up a sniffer or look at the logs from the
> other end. (Or try packet level debugging, but it will be verbose.)
> Again, just to be sure, 88.233.168.2 is not an address you use for
> "initiate-to ip" in the vpdn definition, right?


initiate-to ip 83.233.168.2
initiate-to ip 83.233.168.3
initiate-to ip 83.233.168.4
initiate-to ip 83.233.168.5
initiate-to ip 83.233.168.6
initiate-to ip 83.233.168.7

They are in my config.!!



--
Posted via Mailgate.ORG Server - http://www.Mailgate.ORG
From: Dan Lanciani on 28 Nov 2006 15:00
In article <a3f9da0490c4d0f6afa295df7f910d97.115602(a)mygate.mailgate.org>, il.maggiore(a)inwind.it (Elia Spadoni) writes:
|
| >
| > It might be time to hook up a sniffer or look at the logs from the
| > other end. (Or try packet level debugging, but it will be verbose.)
| > Again, just to be sure, 88.233.168.2 is not an address you use for
| > "initiate-to ip" in the vpdn definition, right?
|
|
| initiate-to ip 83.233.168.2

Then you have exactly the problem I described: the PPTP server is using
for its end of the PPP link the same address at which you contact it
initially for the PPTP vpn. Your configuration cannot be working as
you describned while the connection is up. You must not be routing
through the vpn at all. Note that *incoming* traffic to the address
you are assigned will work fine until the connection kills itself,
explaining why you think the connection is working.

In any case, you need to use policy based routing to force the encapsulated
vpn traffic through the correct interface in spite of the /32 that is
installed when the link comes up. Try something like this:


interface Ethernet0
ip policy route-map pptp
!
interface FastEthernet0
ip policy route-map pptp
!
interface Dialer0
ip policy route-map pptp
!
interface ATM0.35 point-to-point
ip policy route-map pptp
!
ip local policy route-map pptp
!
access-list 112 permit ip any host 88.233.168.2
access-list 112 permit ip any host 88.233.168.3
access-list 112 permit ip any host 88.233.168.4
access-list 112 permit ip any host 88.233.168.5
access-list 112 permit ip any host 88.233.168.6
access-list 112 permit ip any host 88.233.168.7
access-list 112 deny ip any any

route-map pptp permit 10
match ip address 112
set interface ATM0.35

Dan Lanciani
ddl(a)danlan.*com
First  |  Prev  |  Next  |  Last
Pages: 1 2 3 4 5
Prev: FLASH Write Error #5 on Aironet 350
Next: Client 4.7 installation error with DNE