From owner-freebsd-questions Fri Jun 6 03:47:47 1997 Return-Path: Received: (from root@localhost) by hub.freebsd.org (8.8.5/8.8.5) id DAA12665 for questions-outgoing; Fri, 6 Jun 1997 03:47:47 -0700 (PDT) Received: from Teapot06.onaustralia.com.au (mail.onaustralia.com.au [139.134.5.225]) by hub.freebsd.org (8.8.5/8.8.5) with SMTP id DAA12658 for ; Fri, 6 Jun 1997 03:47:36 -0700 (PDT) Received: from Teapot06.onaustralia.com.au by Teapot06.onaustralia.com.au (NTMail 3.02.10) with ESMTP id ea315644 for ; Fri, 6 Jun 1997 20:47:45 +1100 Received: (grog@localhost) by freebie.lemis.com (8.8.4/8.6.12) id UAA24640 for questions@FreeBSD.org; Fri, 6 Jun 1997 20:16:36 +0930 (CST) From: grog@FreeBSD.ORG Message-Id: <199706061046.UAA24640@freebie.lemis.com> Subject: Has anybody seen this PPP "protocol"? To: questions@FreeBSD.ORG (FreeBSD Questions) Date: Fri, 6 Jun 1997 20:16:36 +0930 (CST) Organisation: LEMIS, PO Box 460, Echunga SA 5153, Australia Phone: +61-8-8388-8250 Fax: +61-8-8388-8250 Mobile: +61-41-739-7062 Reply-to: grog@FreeBSD.ORG (Greg Lehey) WWW-Home-Page: http://www.FreeBSD.org/~grog X-Mailer: ELM [version 2.4ME+ PL28 (25)] MIME-Version: 1.0 Content-Type: text/plain; charset=US-ASCII Content-Transfer-Encoding: 8bit X-MIME-Autoconverted: from quoted-printable to 8bit by hub.freebsd.org id DAA12661 Sender: owner-questions@FreeBSD.ORG X-Loop: FreeBSD.org Precedence: bulk I'm currently trying to dial into a system in China using ppp, and it's not succeeding. The login succeeds, but then each end rejects the packets from the other end with the message "invalid protocol". Here's an excerpt from the debug log: 06-06 18:54:52 [24506] Using interface: tun0 06-06 18:54:52 [24506] PPP Started. 06-06 18:55:02 [24506] Expecting 06-06 18:55:02 [24506] sending: ATE1Q0 06-06 18:55:02 [24506] Expecting OK-AT-OK 06-06 18:55:02 [24506] Wait for (5): OK --> OK 06-06 18:55:02 [24506] Chat: ATE1Q0 06-06 18:55:02 [24506] Chat: OK 06-06 18:55:03 [24506] Phone: XXX 06-06 18:55:03 [24506] sending: ATDT00118621XXX 06-06 18:55:03 [24506] Expecting CONNECT 06-06 18:55:03 [24506] Wait for (40): CONNECT --> CONNECT 06-06 18:55:03 [24506] Chat: 06-06 18:55:33 [24506] Chat: ATDT00118621xxx 06-06 18:55:33 [24506] Chat: CONNECT 06-06 18:55:33 [24506] *Connected! 06-06 18:55:33 [24506] LCP: state change Initial --> Closed 06-06 18:55:33 [24506] LCP: SendConfigReq 06-06 18:55:33 [24506] ACFCOMP 06-06 18:55:33 [24506] PROTOCOMP 06-06 18:55:33 [24506] ACCMAP [6] 00000000 06-06 18:55:33 [24506] MRU [4] 1500 06-06 18:55:33 [24506] MAGICNUM [6] 75827c4e 06-06 18:55:33 [24506] LCP: state change Closed --> Req-Sent 06-06 18:55:33 [24506] LCP: Received Configure Request (1) state = Req-Sent (6) 06-06 18:55:33 [24506] MRU 1522 06-06 18:55:33 [24506] ACCMAP 000a0000 06-06 18:55:33 [24506] AUTHPROTO proto = c027 06-06 18:55:33 [24506] AUTHPROTO not implemented, NAK. 06-06 18:55:33 [24506] MAGICNUM 2538d01d 06-06 18:55:33 [24506] ACFCOMP 06-06 18:55:33 [24506] LCP: SendConfigNak(Req-Sent) 06-06 18:55:33 [24506] AUTHPROTO proto = c027 06-06 18:55:33 [24506] LCP: Received Configure Ack (1) state = Req-Sent (6) 06-06 18:55:33 [24506] LCP: state change Req-Sent --> Ack-Rcvd 06-06 18:55:34 [24506] LCP: Received Configure Request (2) state = Ack-Rcvd (7) 06-06 18:55:34 [24506] MRU 1522 06-06 18:55:34 [24506] ACCMAP 000a0000 06-06 18:55:34 [24506] AUTHPROTO proto = c123 06-06 18:55:34 [24506] AUTHPROTO not implemented, NAK. 06-06 18:55:34 [24506] MAGICNUM 2538d01d 06-06 18:55:34 [24506] ACFCOMP 06-06 18:55:34 [24506] LCP: SendConfigNak(Ack-Rcvd) 06-06 18:55:34 [24506] AUTHPROTO proto = c123 06-06 18:55:34 [24506] LCP: Received Configure Request (3) state = Ack-Rcvd (7) 06-06 18:55:34 [24506] MRU 1522 06-06 18:55:34 [24506] ACCMAP 000a0000 06-06 18:55:34 [24506] AUTHPROTO proto = c223 06-06 18:55:34 [24506] MAGICNUM 2538d01d 06-06 18:55:34 [24506] ACFCOMP 06-06 18:55:34 [24506] LCP: SendConfigNak(Ack-Rcvd) 06-06 18:55:34 [24506] AUTHPROTO proto = c023 06-06 18:55:35 [24506] LCP: Received Configure Request (4) state = Ack-Rcvd (7) 06-06 18:55:35 [24506] MRU 1522 06-06 18:55:35 [24506] ACCMAP 000a0000 06-06 18:55:35 [24506] AUTHPROTO proto = c023 06-06 18:55:35 [24506] MAGICNUM 2538d01d 06-06 18:55:35 [24506] ACFCOMP 06-06 18:55:35 [24506] LCP: SendConfigAck(Ack-Rcvd) 06-06 18:55:35 [24506] MRU 1522 06-06 18:55:35 [24506] ACCMAP 000a0000 06-06 18:55:35 [24506] AUTHPROTO proto = c023 06-06 18:55:35 [24506] MAGICNUM 2538d01d 06-06 18:55:35 [24506] ACFCOMP 06-06 18:55:35 [24506] LCP: state change Ack-Rcvd --> Opend 06-06 18:55:35 [24506] LCP: LayerUp 06-06 18:55:35 [24506] Phase: Authenticate 06-06 18:55:35 [24506] his = c023, mine = 0 06-06 18:55:35 [24506] PAP: foo (bar) 06-06 18:55:35 [24506] LQM method = 2 06-06 18:55:35 [24506] LQR is not activated. 06-06 18:55:35 [24506] PapInput: ACK 06-06 18:55:35 [24506] Received PAP_ACK (Welcome) 06-06 18:55:35 [24506] Phase: Network 06-06 18:55:35 [24506] IPCP: state change Initial --> Closed 06-06 18:55:35 [24506] IPCP Up event!! 06-06 18:55:35 [24506] IPCP: SendConfigReq 06-06 18:55:35 [24506] IPADDR [6] 0.0.0.0 06-06 18:55:35 [24506] COMPPROTO [6] 002d0f00 06-06 18:55:35 [24506] IPCP: state change Closed --> Req-Sent 06-06 18:55:35 [24506] CCP: state change Initial --> Closed 06-06 18:55:35 [24506] CCP Up event!! 06-06 18:55:35 [24506] IPCP: Received Configure Request (1) state = Req-Sent (6) 06-06 18:55:35 [24506] IPADDR[6] 130.252.88.217 06-06 18:55:35 [24506] COMPPROTO[6] 002d0f00 06-06 18:55:35 [24506] IPCP: SendConfigAck(Req-Sent) 06-06 18:55:35 [24506] IPADDR[6] 130.252.88.217 06-06 18:55:35 [24506] COMPPROTO[6] 002d0f00 06-06 18:55:35 [24506] IPCP: state change Req-Sent --> Ack-Sent 06-06 18:55:35 [24506] Unknown protocol 0x802b 06-06 18:55:35 [24506] LCP: SendProtoRej 06-06 18:55:35 [24506] CCP: Received Configure Request (1) state = Closed (2) 06-06 18:55:35 [24506] CCP: SendTerminateAck 06-06 18:55:35 [24506] Unknown protocol 0x803f 06-06 18:55:35 [24506] LCP: SendProtoRej 06-06 18:55:35 [24506] Unknown protocol 0x00cf 06-06 18:55:35 [24506] LCP: SendProtoRej 06-06 18:55:36 [24506] IPCP: Received Configure Nak (1) state = Ack-Sent (8) 06-06 18:55:36 [24506] IPADDR[6] 130.252.88.220 06-06 18:55:36 [24506] IPADDR[6] changing address: 0.0.0.0 --> 130.252.88.220 06-06 18:55:36 [24506] IPCP: SendConfigReq 06-06 18:55:36 [24506] IPADDR [6] 130.252.88.220 06-06 18:55:36 [24506] COMPPROTO [6] 002d0f00 06-06 18:55:36 [24506] IPCP: Received Configure Ack (2) state = Ack-Sent (8) 06-06 18:55:36 [24506] IPCP: state change Ack-Sent --> Opend 06-06 18:55:36 [24506] IPCP: LayerUp. 06-06 18:55:36 [24506] myaddr = 130.252.88.220 hisaddr = 130.252.88.217 06-06 18:55:36 [24506] OsLinkup: 130.252.88.217 *** At this point, I presume the connection to have been made. What *** comes below should be traffic. As you can see, it doesn't work. 06-06 18:55:40 [24506] Unknown protocol 0x00cf 06-06 18:55:40 [24506] LCP: SendProtoRej 06-06 18:55:40 [24506] CCP: Received Configure Request (2) state = Closed (2) 06-06 18:55:40 [24506] CCP: SendTerminateAck 06-06 18:55:45 [24506] CCP: Received Configure Request (3) state = Closed (2) 06-06 18:55:45 [24506] CCP: SendTerminateAck 06-06 18:55:45 [24506] Unknown protocol 0x00cf 06-06 18:55:45 [24506] LCP: SendProtoRej 06-06 18:55:50 [24506] Unknown protocol 0x00cf 06-06 18:55:50 [24506] LCP: SendProtoRej 06-06 18:55:50 [24506] CCP: Received Configure Request (4) state = Closed (2) 06-06 18:55:50 [24506] CCP: SendTerminateAck 06-06 18:55:54 [24506] LCP: LayerDown 06-06 18:55:54 [24506] OsLinkdown: 130.252.88.217 06-06 18:55:54 [24506] Already set route addr dst=fc82, gateway=d958fc82 06-06 18:55:54 [24506] Phase: Terminate 06-06 18:55:54 [24506] LCP: SendTerminateReq. 06-06 18:55:54 [24506] LCP: state change Opend --> Closing 06-06 18:55:55 [24506] LCP: Received Terminate Ack (2) state = Closing (4) 06-06 18:55:55 [24506] LCP: state change Closing --> Closed 06-06 18:55:55 [24506] LCP: LayerFinish 06-06 18:55:55 [24506] Phase: Dead 06-06 18:56:36 [24506] PPP Terminated. As far as I can tell from this log, it looks as if the other end is sending a packet of type '0xcf', which may be related to configuration. Unfortunately, user mode ppp (and, from what I've seen of the code, kernel ppp) doesn't mention this stuff at all. Does anybody recognize what the other end's trying to do? FWIW, I suspect it's a Microsoft box. Here's my ppp.config entry: default: set device /dev/cuaa0 set speed 38400 disable lqr deny lqr set dial "ABORT BUSY ABORT NO\\sCARRIER TIMEOUT 5 \"\" ATE1Q0 OK-AT-OK \\dATDT\\T TIMEOUT 40 CONNECT" china: set phone 00118621xxx set speed 38400 deny chap accept pap disable pred1 # enable pap set authname foo set authkey baz set timeout 600 2 set debug Phase Chat Connect Carrier LQM LCP Link TCP/IP hdlc set ifaddr 0.0.0.0 0.0.0.0 set openmode active I'd be grateful if anybody could shed some light on this. Greg