Skip site navigation (1)Skip section navigation (2)
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: <http://docs.FreeBSD.org/cgi/mid.cgi?199706061046.UAA24640>