Date: Fri, 6 Jun 1997 20:16:36 +0930 (CST) From: grog@FreeBSD.ORG To: questions@FreeBSD.ORG (FreeBSD Questions) Subject: Has anybody seen this PPP "protocol"? Message-ID: <199706061046.UAA24640@freebie.lemis.com>
next in thread | raw e-mail | index | archive | help
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
Want to link to this message? Use this URL: <https://mail-archive.FreeBSD.org/cgi/mid.cgi?199706061046.UAA24640>