Date: Thu, 31 Aug 2000 16:42:26 -0700 From: Jos Backus <josb@corp.webtv.net> To: net@freebsd.org Subject: Re: mpd PPTP question Message-ID: <20000831164226.A93633@lizzy.bugworks.com>
next in thread | raw e-mail | index | archive | help
Hi Archie,
>Probably a bug in the NT server (or mpd, but I just checked
>and didn't see it).
>In any case this is not a big deal and you can ignore it.
OK (Wouldn't surprise me.)
>Let's see the log and I'll take a look..
Here it is. It's big, sorry about that.
Thanks for looking into this.
Jos
Aug 31 16:36:14 lizzy mpd: mpd: pid 93631, version 3.0 (root@lizzy.bugworks.com 22:49 24-Aug-2000)
Aug 31 16:36:14 lizzy mpd: [ms-pptp] ppp node is "mpd93631-ms-ppt"
Aug 31 16:36:14 lizzy mpd: [ms-pptp] using interface ng0
Aug 31 16:36:14 lizzy mpd: [ms-pptp] IFACE: Open event
Aug 31 16:36:14 lizzy mpd: [ms-pptp] IPCP: Open event
Aug 31 16:36:14 lizzy mpd: [ms-pptp] IPCP: state change Initial --> Starting
Aug 31 16:36:15 lizzy mpd: [ms-pptp] IPCP: LayerStart
Aug 31 16:36:15 lizzy mpd: [ms-pptp] bundle: OPEN event in state CLOSED
Aug 31 16:36:15 lizzy mpd: [ms-pptp] opening link "work"...
Aug 31 16:36:15 lizzy mpd: [work] link: OPEN event
Aug 31 16:36:15 lizzy mpd: [work] LCP: Open event
Aug 31 16:36:15 lizzy mpd: [work] LCP: state change Initial --> Starting
Aug 31 16:36:15 lizzy mpd: [work] LCP: LayerStart
Aug 31 16:36:15 lizzy mpd: [work] device: OPEN event in state DOWN
Aug 31 16:36:15 lizzy mpd: pptp0: connecting to 209.240.195.15:1723
Aug 31 16:36:15 lizzy mpd: [work] device is now in state OPENING
Aug 31 16:36:15 lizzy mpd: pptp0: connected to 209.240.195.15:1723
Aug 31 16:36:15 lizzy mpd: pptp0: attached to connection with 209.240.195.15:1723
Aug 31 16:36:15 lizzy mpd: pptp0-0: outgoing call connected at -2137614336 bps
Aug 31 16:36:15 lizzy mpd: [work] PPTP call successful
Aug 31 16:36:15 lizzy mpd: [work] device: UP event in state OPENING
Aug 31 16:36:15 lizzy mpd: [work] device is now in state UP
Aug 31 16:36:15 lizzy mpd: [work] link: UP event
Aug 31 16:36:15 lizzy mpd: [work] link: origination is local
Aug 31 16:36:15 lizzy mpd: [work] LCP: Up event
Aug 31 16:36:15 lizzy mpd: [work] LCP: state change Starting --> Req-Sent
Aug 31 16:36:15 lizzy mpd: [work] LCP: phase shift DEAD --> ESTABLISH
Aug 31 16:36:15 lizzy mpd: [work] LCP: SendConfigReq #1
Aug 31 16:36:15 lizzy mpd: ACFCOMP
Aug 31 16:36:15 lizzy mpd: PROTOCOMP
Aug 31 16:36:15 lizzy mpd: MRU 1500
Aug 31 16:36:15 lizzy mpd: MAGICNUM 46c5df8e
Aug 31 16:36:15 lizzy mpd: AUTHPROTO CHAP MSOFT
Aug 31 16:36:15 lizzy mpd: [work] LCP: rec'd Configure Request #0 link 0 (Req-Sent)
Aug 31 16:36:15 lizzy mpd: AUTHPROTO CHAP 0x81
Aug 31 16:36:15 lizzy mpd: MAGICNUM 5813148c
Aug 31 16:36:15 lizzy mpd: PROTOCOMP
Aug 31 16:36:15 lizzy mpd: ACFCOMP
Aug 31 16:36:15 lizzy mpd: CALLBACK
Aug 31 16:36:15 lizzy mpd: Not supported
Aug 31 16:36:15 lizzy mpd: [work] LCP: SendConfigRej #0
Aug 31 16:36:15 lizzy mpd: CALLBACK
Aug 31 16:36:15 lizzy mpd: [work] LCP: rec'd Configure Ack #1 link 0 (Req-Sent)
Aug 31 16:36:15 lizzy mpd: ACFCOMP
Aug 31 16:36:15 lizzy mpd: PROTOCOMP
Aug 31 16:36:15 lizzy mpd: MRU 1500
Aug 31 16:36:15 lizzy mpd: MAGICNUM 46c5df8e
Aug 31 16:36:15 lizzy mpd: AUTHPROTO CHAP MSOFT
Aug 31 16:36:15 lizzy mpd: [work] LCP: state change Req-Sent --> Ack-Rcvd
Aug 31 16:36:15 lizzy mpd: [work] LCP: rec'd Configure Request #1 link 0 (Ack-Rcvd)
Aug 31 16:36:15 lizzy mpd: AUTHPROTO CHAP 0x81
Aug 31 16:36:15 lizzy mpd: MAGICNUM 5813148c
Aug 31 16:36:15 lizzy mpd: PROTOCOMP
Aug 31 16:36:15 lizzy mpd: ACFCOMP
Aug 31 16:36:15 lizzy mpd: [work] LCP: SendConfigNak #1
Aug 31 16:36:15 lizzy mpd: AUTHPROTO CHAP MD5
Aug 31 16:36:15 lizzy mpd: [work] LCP: rec'd Configure Request #2 link 0 (Ack-Rcvd)
Aug 31 16:36:15 lizzy mpd: AUTHPROTO CHAP MSOFT
Aug 31 16:36:15 lizzy mpd: MAGICNUM 5813148c
Aug 31 16:36:15 lizzy mpd: PROTOCOMP
Aug 31 16:36:15 lizzy mpd: ACFCOMP
Aug 31 16:36:15 lizzy mpd: [work] LCP: SendConfigAck #2
Aug 31 16:36:15 lizzy mpd: AUTHPROTO CHAP MSOFT
Aug 31 16:36:15 lizzy mpd: MAGICNUM 5813148c
Aug 31 16:36:15 lizzy mpd: PROTOCOMP
Aug 31 16:36:15 lizzy mpd: ACFCOMP
Aug 31 16:36:15 lizzy mpd: [work] LCP: state change Ack-Rcvd --> Opened
Aug 31 16:36:15 lizzy mpd: [work] LCP: phase shift ESTABLISH --> AUTHENTICATE
Aug 31 16:36:15 lizzy mpd: [work] LCP: auth: peer wants CHAP, I want CHAP
Aug 31 16:36:15 lizzy mpd: [work] CHAP: sending CHALLENGE
Aug 31 16:36:15 lizzy mpd: [work] LCP: LayerUp
Aug 31 16:36:15 lizzy mpd: pptp0: CID 0x5c10 in SetLinkInfo not found
Aug 31 16:36:15 lizzy mpd: [work] CHAP: rec'd CHALLENGE #0
Aug 31 16:36:15 lizzy mpd: Name: "PPTPSERVER"
Aug 31 16:36:15 lizzy mpd: Using authname "user"
Aug 31 16:36:15 lizzy mpd: [work] CHAP: sending RESPONSE
Aug 31 16:36:15 lizzy mpd: [work] CHAP: rec'd SUCCESS #0
Aug 31 16:36:15 lizzy mpd: [work] LCP: rec'd Configure Request #4 link 0 (Opened)
Aug 31 16:36:15 lizzy mpd: AUTHPROTO CHAP 0x81
Aug 31 16:36:15 lizzy mpd: MAGICNUM 5a7969e8
Aug 31 16:36:15 lizzy mpd: PROTOCOMP
Aug 31 16:36:15 lizzy mpd: ACFCOMP
Aug 31 16:36:15 lizzy mpd: CALLBACK
Aug 31 16:36:15 lizzy mpd: Not supported
Aug 31 16:36:15 lizzy mpd: [work] LCP: LayerDown
Aug 31 16:36:15 lizzy mpd: [work] LCP: SendConfigReq #2
Aug 31 16:36:15 lizzy mpd: ACFCOMP
Aug 31 16:36:15 lizzy mpd: PROTOCOMP
Aug 31 16:36:15 lizzy mpd: MRU 1500
Aug 31 16:36:15 lizzy mpd: MAGICNUM 46c5df8e
Aug 31 16:36:15 lizzy mpd: AUTHPROTO CHAP MSOFT
Aug 31 16:36:15 lizzy mpd: [work] LCP: SendConfigRej #4
Aug 31 16:36:15 lizzy mpd: CALLBACK
Aug 31 16:36:15 lizzy mpd: [work] LCP: state change Opened --> Req-Sent
Aug 31 16:36:15 lizzy mpd: [work] LCP: phase shift AUTHENTICATE --> ESTABLISH
Aug 31 16:36:15 lizzy mpd: pptp0: CID 0x5c10 in SetLinkInfo not found
Aug 31 16:36:15 lizzy mpd: [work] LCP: rec'd Configure Reject #2 link 0 (Req-Sent)
Aug 31 16:36:15 lizzy mpd: AUTHPROTO CHAP MSOFT
Aug 31 16:36:15 lizzy mpd: [work] LCP: SendConfigReq #3
Aug 31 16:36:15 lizzy mpd: ACFCOMP
Aug 31 16:36:15 lizzy mpd: PROTOCOMP
Aug 31 16:36:15 lizzy mpd: MRU 1500
Aug 31 16:36:15 lizzy mpd: MAGICNUM 46c5df8e
Aug 31 16:36:15 lizzy mpd: AUTHPROTO CHAP MSOFT
Aug 31 16:36:15 lizzy mpd: [work] LCP: rec'd Configure Request #5 link 0 (Req-Sent)
Aug 31 16:36:15 lizzy mpd: AUTHPROTO CHAP 0x81
Aug 31 16:36:15 lizzy mpd: MAGICNUM 5a7969e8
Aug 31 16:36:15 lizzy mpd: PROTOCOMP
Aug 31 16:36:15 lizzy mpd: ACFCOMP
Aug 31 16:36:15 lizzy mpd: [work] LCP: SendConfigNak #5
Aug 31 16:36:15 lizzy mpd: AUTHPROTO CHAP MD5
Aug 31 16:36:15 lizzy mpd: [work] LCP: rec'd Configure Reject #3 link 0 (Req-Sent)
Aug 31 16:36:15 lizzy mpd: AUTHPROTO CHAP MSOFT
Aug 31 16:36:15 lizzy mpd: [work] LCP: SendConfigReq #4
Aug 31 16:36:15 lizzy mpd: ACFCOMP
Aug 31 16:36:15 lizzy mpd: PROTOCOMP
Aug 31 16:36:15 lizzy mpd: MRU 1500
Aug 31 16:36:15 lizzy mpd: MAGICNUM 46c5df8e
Aug 31 16:36:15 lizzy mpd: AUTHPROTO CHAP MSOFT
Aug 31 16:36:15 lizzy mpd: [work] LCP: rec'd Configure Request #6 link 0 (Req-Sent)
Aug 31 16:36:15 lizzy mpd: AUTHPROTO CHAP MSOFT
Aug 31 16:36:15 lizzy mpd: MAGICNUM 5a7969e8
Aug 31 16:36:15 lizzy mpd: PROTOCOMP
Aug 31 16:36:15 lizzy mpd: ACFCOMP
Aug 31 16:36:15 lizzy mpd: [work] LCP: SendConfigAck #6
Aug 31 16:36:15 lizzy mpd: AUTHPROTO CHAP MSOFT
Aug 31 16:36:15 lizzy mpd: MAGICNUM 5a7969e8
Aug 31 16:36:15 lizzy mpd: PROTOCOMP
Aug 31 16:36:15 lizzy mpd: ACFCOMP
Aug 31 16:36:15 lizzy mpd: [work] LCP: state change Req-Sent --> Ack-Sent
Aug 31 16:36:15 lizzy mpd: [work] LCP: rec'd Configure Reject #4 link 0 (Ack-Sent)
Aug 31 16:36:15 lizzy mpd: AUTHPROTO CHAP MSOFT
Aug 31 16:36:15 lizzy mpd: [work] LCP: SendConfigReq #5
Aug 31 16:36:15 lizzy mpd: ACFCOMP
Aug 31 16:36:15 lizzy mpd: PROTOCOMP
Aug 31 16:36:15 lizzy mpd: MRU 1500
Aug 31 16:36:15 lizzy mpd: MAGICNUM 46c5df8e
Aug 31 16:36:15 lizzy mpd: AUTHPROTO CHAP MSOFT
Aug 31 16:36:15 lizzy mpd: [work] LCP: rec'd Configure Reject #5 link 0 (Ack-Sent)
Aug 31 16:36:15 lizzy mpd: AUTHPROTO CHAP MSOFT
Aug 31 16:36:15 lizzy mpd: [work] LCP: SendConfigReq #6
Aug 31 16:36:15 lizzy mpd: ACFCOMP
Aug 31 16:36:15 lizzy mpd: PROTOCOMP
Aug 31 16:36:15 lizzy mpd: MRU 1500
Aug 31 16:36:15 lizzy mpd: MAGICNUM 46c5df8e
Aug 31 16:36:15 lizzy mpd: AUTHPROTO CHAP MSOFT
Aug 31 16:36:15 lizzy mpd: [work] LCP: rec'd Configure Reject #6 link 0 (Ack-Sent)
Aug 31 16:36:15 lizzy mpd: AUTHPROTO CHAP MSOFT
Aug 31 16:36:15 lizzy mpd: [work] LCP: SendConfigReq #7
Aug 31 16:36:15 lizzy mpd: ACFCOMP
Aug 31 16:36:15 lizzy mpd: PROTOCOMP
Aug 31 16:36:15 lizzy mpd: MRU 1500
Aug 31 16:36:15 lizzy mpd: MAGICNUM 46c5df8e
Aug 31 16:36:15 lizzy mpd: AUTHPROTO CHAP MSOFT
Aug 31 16:36:15 lizzy mpd: [work] LCP: rec'd Terminate Request #7 link 0 (Ack-Sent)
Aug 31 16:36:15 lizzy mpd: [work] LCP: state change Ack-Sent --> Req-Sent
Aug 31 16:36:15 lizzy mpd: [work] LCP: SendTerminateAck #8
Aug 31 16:36:17 lizzy mpd: [work] LCP: rec'd Terminate Request #8 link 0 (Req-Sent)
Aug 31 16:36:17 lizzy mpd: [work] LCP: SendTerminateAck #9
Aug 31 16:36:17 lizzy mpd: [work] LCP: SendConfigReq #10
Aug 31 16:36:17 lizzy mpd: ACFCOMP
Aug 31 16:36:17 lizzy mpd: PROTOCOMP
Aug 31 16:36:17 lizzy mpd: MRU 1500
Aug 31 16:36:17 lizzy mpd: MAGICNUM 46c5df8e
Aug 31 16:36:17 lizzy mpd: AUTHPROTO CHAP MSOFT
Aug 31 16:36:19 lizzy mpd: pptp0: CID 0x83cb in CallClearRequest not found
Aug 31 16:36:19 lizzy mpd: [work] LCP: SendConfigReq #11
Aug 31 16:36:19 lizzy mpd: ACFCOMP
Aug 31 16:36:19 lizzy mpd: PROTOCOMP
Aug 31 16:36:19 lizzy mpd: MRU 1500
Aug 31 16:36:19 lizzy mpd: MAGICNUM 46c5df8e
Aug 31 16:36:19 lizzy mpd: AUTHPROTO CHAP MSOFT
Aug 31 16:36:21 lizzy mpd: [work] LCP: SendConfigReq #12
Aug 31 16:36:21 lizzy mpd: ACFCOMP
Aug 31 16:36:21 lizzy mpd: PROTOCOMP
Aug 31 16:36:21 lizzy mpd: MRU 1500
Aug 31 16:36:21 lizzy mpd: MAGICNUM 46c5df8e
Aug 31 16:36:21 lizzy mpd: AUTHPROTO CHAP MSOFT
Aug 31 16:36:23 lizzy mpd: [work] LCP: SendConfigReq #13
Aug 31 16:36:23 lizzy mpd: ACFCOMP
Aug 31 16:36:23 lizzy mpd: PROTOCOMP
Aug 31 16:36:23 lizzy mpd: MRU 1500
Aug 31 16:36:23 lizzy mpd: MAGICNUM 46c5df8e
Aug 31 16:36:23 lizzy mpd: AUTHPROTO CHAP MSOFT
Aug 31 16:36:25 lizzy mpd: [work] LCP: SendConfigReq #14
Aug 31 16:36:25 lizzy mpd: ACFCOMP
Aug 31 16:36:25 lizzy mpd: PROTOCOMP
Aug 31 16:36:25 lizzy mpd: MRU 1500
Aug 31 16:36:25 lizzy mpd: MAGICNUM 46c5df8e
Aug 31 16:36:25 lizzy mpd: AUTHPROTO CHAP MSOFT
Aug 31 16:36:27 lizzy mpd: [work] LCP: SendConfigReq #15
Aug 31 16:36:27 lizzy mpd: ACFCOMP
Aug 31 16:36:27 lizzy mpd: PROTOCOMP
Aug 31 16:36:27 lizzy mpd: MRU 1500
Aug 31 16:36:27 lizzy mpd: MAGICNUM 46c5df8e
Aug 31 16:36:27 lizzy mpd: AUTHPROTO CHAP MSOFT
Aug 31 16:36:29 lizzy mpd: [work] LCP: SendConfigReq #16
Aug 31 16:36:29 lizzy mpd: ACFCOMP
Aug 31 16:36:29 lizzy mpd: PROTOCOMP
Aug 31 16:36:29 lizzy mpd: MRU 1500
Aug 31 16:36:29 lizzy mpd: MAGICNUM 46c5df8e
Aug 31 16:36:29 lizzy mpd: AUTHPROTO CHAP MSOFT
Aug 31 16:36:29 lizzy mpd: [work] error writing len 27 frame to bypass: No buffer space available
Aug 31 16:36:31 lizzy mpd: [work] LCP: SendConfigReq #17
Aug 31 16:36:31 lizzy mpd: ACFCOMP
Aug 31 16:36:31 lizzy mpd: PROTOCOMP
Aug 31 16:36:31 lizzy mpd: MRU 1500
Aug 31 16:36:31 lizzy mpd: MAGICNUM 46c5df8e
Aug 31 16:36:31 lizzy mpd: AUTHPROTO CHAP MSOFT
Aug 31 16:36:33 lizzy mpd: [work] LCP: SendConfigReq #18
Aug 31 16:36:33 lizzy mpd: ACFCOMP
Aug 31 16:36:33 lizzy mpd: PROTOCOMP
Aug 31 16:36:33 lizzy mpd: MRU 1500
Aug 31 16:36:33 lizzy mpd: MAGICNUM 46c5df8e
Aug 31 16:36:33 lizzy mpd: AUTHPROTO CHAP MSOFT
Aug 31 16:36:33 lizzy mpd: [work] error writing len 27 frame to bypass: No buffer space available
Aug 31 16:36:35 lizzy mpd: [work] LCP: state change Req-Sent --> Stopped
Aug 31 16:36:35 lizzy mpd: [work] LCP: LayerFinish
Aug 31 16:36:35 lizzy mpd: [work] LCP: parameter negotiation failed
Aug 31 16:36:35 lizzy mpd: [work] LCP: LayerFinish
Aug 31 16:36:35 lizzy mpd: [work] device: CLOSE event in state UP
Aug 31 16:36:35 lizzy mpd: pptp0-0: clearing call
Aug 31 16:36:35 lizzy mpd: [work] device is now in state CLOSING
Aug 31 16:36:35 lizzy mpd: [work] device: CLOSE event in state CLOSING
Aug 31 16:36:35 lizzy mpd: [work] device is now in state CLOSING
Aug 31 16:36:35 lizzy mpd: [work] device: DOWN event in state CLOSING
Aug 31 16:36:35 lizzy mpd: [work] device is now in state DOWN
Aug 31 16:36:35 lizzy mpd: [work] link: DOWN event
Aug 31 16:36:35 lizzy mpd: [work] LCP: Down event
Aug 31 16:36:35 lizzy mpd: [work] LCP: state change Stopped --> Starting
Aug 31 16:36:35 lizzy mpd: [work] LCP: phase shift ESTABLISH --> DEAD
Aug 31 16:36:35 lizzy mpd: [work] LCP: LayerStart
Aug 31 16:36:35 lizzy mpd: [work] device: OPEN event in state DOWN
Aug 31 16:36:35 lizzy mpd: [work] pausing 8 seconds before open
Aug 31 16:36:35 lizzy mpd: [work] device is now in state DOWN
Aug 31 16:36:35 lizzy mpd: [work] device: OPEN event in state DOWN
Aug 31 16:36:35 lizzy mpd: [work] device is now in state DOWN
Aug 31 16:36:42 lizzy mpd: mpd: caught fatal signal int
Aug 31 16:36:42 lizzy mpd: mpd: fatal error, exiting
Aug 31 16:36:42 lizzy mpd: [ms-pptp] IPCP: Down event
Aug 31 16:36:42 lizzy mpd: [ms-pptp] IFACE: Close event
Aug 31 16:36:42 lizzy mpd: [ms-pptp] IPCP: Close event
Aug 31 16:36:42 lizzy mpd: [ms-pptp] IPCP: state change Starting --> Initial
Aug 31 16:36:42 lizzy mpd: [ms-pptp] IPCP: LayerFinish
Aug 31 16:36:42 lizzy mpd: mpd: process 93631 terminated
Groetjes,
--
Jos Backus _/ _/_/_/ "Modularity is not a hack."
_/ _/ _/ -- D. J. Bernstein
_/ _/_/_/
_/ _/ _/ _/
josb@cncdsl.com _/_/ _/_/_/ use Std::Disclaimer;
To Unsubscribe: send mail to majordomo@FreeBSD.org
with "unsubscribe freebsd-net" in the body of the message
Want to link to this message? Use this URL: <https://mail-archive.FreeBSD.org/cgi/mid.cgi?20000831164226.A93633>