From owner-freebsd-net Thu Aug 31 16:41:10 2000 Delivered-To: freebsd-net@freebsd.org Received: from w250.z064001178.sjc-ca.dsl.cnc.net (w250.z064001178.sjc-ca.dsl.cnc.net [64.1.178.250]) by hub.freebsd.org (Postfix) with SMTP id 1246437B43C for ; Thu, 31 Aug 2000 16:39:55 -0700 (PDT) Received: (qmail 93644 invoked by uid 1000); 31 Aug 2000 23:42:26 -0000 Date: Thu, 31 Aug 2000 16:42:26 -0700 From: Jos Backus To: net@freebsd.org Subject: Re: mpd PPTP question Message-ID: <20000831164226.A93633@lizzy.bugworks.com> Reply-To: Jos Backus Mime-Version: 1.0 Content-Type: text/plain; charset=us-ascii Content-Disposition: inline User-Agent: Mutt/1.2.5i Sender: owner-freebsd-net@FreeBSD.ORG Precedence: bulk X-Loop: FreeBSD.org 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