#19988: pptp connected but don't works. LCP ProtRej Protocol-Reject for
unsupported
protocol
------------------------------+-----------------------------------
Reporter: pavel.gumenyuk@… | Owner: developers
Type: defect | Status: new
Priority: normal | Milestone: Barrier Breaker 14.07
Component: packages | Version: Barrier Breaker 14.07
Keywords: pptp, mppe, pppd |
------------------------------+-----------------------------------
Unable to get pptp connection working although it is established. LCP
ProtRej Protocol-Reject for unsupported protocol
PC with Win7 connects smoothly (ms-chapv2,mppe-128).
Another router with 14.07 openwrt (wr1043nd) has same symptoms as x86 and
pptp tunnel not working.
'''/etc/openwrt_release'''
{{{
DISTRIB_ID="OpenWrt"
DISTRIB_RELEASE="14.07"
DISTRIB_REVISION="r42625"
DISTRIB_CODENAME="barrier_breaker"
DISTRIB_TARGET="x86/generic"
DISTRIB_DESCRIPTION="OpenWrt Barrier Breaker 14.07"
}}}
'''/etc/config/network'''
{{{
config interface 'xxx'
option proto 'pptp'
option delegate '0'
option server 'xxx.xxx.xxx.xxx'
option username '[email protected]'
option password 'xxx>'
option defaultroute '0'
option pppd_options 'noipdefault refuse-chap refuse-pap refuse-
mschap refuse-eap debug'
option mtu '1000'
}}}
'''lastlog'''
{{{
Wed Jun 24 18:01:56 2015 daemon.info pppd[26786]: Plugin pptp.so loaded.
Wed Jun 24 18:01:56 2015 daemon.info pppd[26786]: PPTP plugin version 1.00
Wed Jun 24 18:01:56 2015 daemon.notice pppd[26786]: pppd 2.4.7 started by
root, uid 0
Wed Jun 24 18:01:56 2015 daemon.debug pppd[26787]: pptp: call manager for
xxx.xxx.xxx.xxx
Wed Jun 24 18:01:56 2015 daemon.debug pppd[26787]: window size: 50
Wed Jun 24 18:01:56 2015 daemon.debug pppd[26787]: call id: 4
Wed Jun 24 18:01:56 2015 daemon.debug pppd[26787]: control connection
Wed Jun 24 18:01:56 2015 daemon.debug pppd[26787]: unix_sock
Wed Jun 24 18:01:56 2015 daemon.debug pppd[26788]: Sent control packet
type is 1 'Start-Control-Connection-Request'
Wed Jun 24 18:01:56 2015 daemon.debug pppd[26788]: Received Start Control
Connection Reply
Wed Jun 24 18:01:56 2015 daemon.debug pppd[26788]: Client connection
established.
Wed Jun 24 18:01:57 2015 daemon.debug pppd[26788]: Sent control packet
type is 7 'Outgoing-Call-Request'
Wed Jun 24 18:01:57 2015 daemon.debug pppd[26788]: Received Outgoing Call
Reply.
Wed Jun 24 18:01:57 2015 daemon.debug pppd[26788]: Outgoing call
established (call ID 4, peer's call ID 60032).
Wed Jun 24 18:01:57 2015 daemon.debug pppd[26786]: using channel 4
Wed Jun 24 18:01:57 2015 daemon.info pppd[26786]: Using interface pptp-xxx
Wed Jun 24 18:01:57 2015 daemon.notice pppd[26786]: Connect: pptp-xxx <-->
pptp (xxx.xxx.xxx.xxx)
Wed Jun 24 18:01:57 2015 daemon.debug pppd[26786]: sent [LCP ConfReq
id=0x1 <mru 1000> <asyncmap 0x0> <magic 0x7098ad8>]
Wed Jun 24 18:01:59 2015 daemon.debug pppd[26786]: rcvd [LCP ConfReq
id=0x1 <accomp> <pcomp> <asyncmap 0x0> <mru 1500> <magic 0xfc89ef78> <auth
chap MS-v2>]
Wed Jun 24 18:01:59 2015 daemon.debug pppd[26786]: sent [LCP ConfRej
id=0x1 <accomp> <pcomp>]
Wed Jun 24 18:01:59 2015 daemon.debug pppd[26786]: rcvd [LCP ConfAck
id=0x1 <mru 1000> <asyncmap 0x0> <magic 0x7098ad8>]
Wed Jun 24 18:01:59 2015 daemon.debug pppd[26786]: rcvd [LCP ConfReq
id=0x2 <asyncmap 0x0> <mru 1500> <magic 0xfc89ef78> <auth chap MS-v2>]
Wed Jun 24 18:01:59 2015 daemon.debug pppd[26786]: sent [LCP ConfAck
id=0x2 <asyncmap 0x0> <mru 1500> <magic 0xfc89ef78> <auth chap MS-v2>]
Wed Jun 24 18:01:59 2015 daemon.debug pppd[26786]: sent [LCP EchoReq
id=0x0 magic=0x7098ad8]
Wed Jun 24 18:01:59 2015 daemon.debug pppd[26786]: rcvd [CHAP Challenge
id=0x1 <31323334363431303233343838333630>, name = ""]
Wed Jun 24 18:01:59 2015 daemon.debug pppd[26786]: added response cache
entry 0
Wed Jun 24 18:01:59 2015 daemon.debug pppd[26786]: sent [CHAP Response
id=0x1
<259ca7b93c907b36b09d1f90027b19fd0000000000000000d8a8a03339757d5bbadb4ae65e1ec4160310d13b77a73e9b00>,
name = "[email protected]"]
Wed Jun 24 18:01:59 2015 daemon.debug pppd[26786]: rcvd [LCP EchoRep
id=0x0 magic=0xfc89ef78]
Wed Jun 24 18:01:59 2015 daemon.debug pppd[26786]: rcvd [CHAP Success
id=0x1 "S=60B32E8F2C6EA7653FE63734D55F2D41F6E92922"]
Wed Jun 24 18:01:59 2015 daemon.debug pppd[26786]: response found in cache
(entry 0)
Wed Jun 24 18:01:59 2015 daemon.notice pppd[26786]: CHAP authentication
succeeded
Wed Jun 24 18:01:59 2015 daemon.debug pppd[26786]: sent [CCP ConfReq
id=0x1 <mppe +H +M +S +L -D -C>]
Wed Jun 24 18:01:59 2015 daemon.debug pppd[26786]: rcvd [CCP ConfReq
id=0x1 <deflate 15> <predictor 1> <mppe -H +M +S +L -D -C>]
Wed Jun 24 18:01:59 2015 daemon.debug pppd[26786]: sent [CCP ConfRej
id=0x1 <deflate 15> <predictor 1>]
Wed Jun 24 18:01:59 2015 daemon.debug pppd[26786]: rcvd [IPCP ConfReq
id=0x1 <addr 192.168.161.1> <compress VJ 0f 01> <ms-dns1 192.168.1.7> <ms-
dns2 255.255.255.255>]
Wed Jun 24 18:01:59 2015 daemon.debug pppd[26786]: sent [IPCP TermAck
id=0x1]
Wed Jun 24 18:01:59 2015 daemon.debug pppd[26786]: rcvd [IPV6CP ConfReq
id=0x1 <addr fe80::0215:17ff:fe26:a616>]
Wed Jun 24 18:01:59 2015 daemon.debug pppd[26786]: sent [IPV6CP TermAck
id=0x1]
Wed Jun 24 18:01:59 2015 daemon.debug pppd[26786]: rcvd [CCP ConfNak
id=0x1 <mppe +H -M +S -L -D -C>]
Wed Jun 24 18:01:59 2015 daemon.debug pppd[26786]: sent [CCP ConfReq
id=0x2 <mppe +H -M +S -L -D -C>]
Wed Jun 24 18:01:59 2015 daemon.debug pppd[26786]: rcvd [CCP ConfReq
id=0x2 <mppe -H +M +S +L -D -C>]
Wed Jun 24 18:01:59 2015 daemon.debug pppd[26786]: sent [CCP ConfNak
id=0x2 <mppe -H -M +S -L -D -C>]
Wed Jun 24 18:01:59 2015 daemon.debug pppd[26786]: rcvd [CCP ConfAck
id=0x2 <mppe +H -M +S -L -D -C>]
Wed Jun 24 18:01:59 2015 daemon.debug pppd[26786]: rcvd [CCP ConfReq
id=0x3 <mppe -H -M +S -L -D -C>]
Wed Jun 24 18:01:59 2015 daemon.debug pppd[26786]: sent [CCP ConfAck
id=0x3 <mppe -H -M +S -L -D -C>]
Wed Jun 24 18:01:59 2015 daemon.notice pppd[26786]: MPPE 128-bit stateless
compression enabled
Wed Jun 24 18:01:59 2015 daemon.debug pppd[26786]: sent [IPCP ConfReq
id=0x1 <compress VJ 0f 01> <addr 0.0.0.0> <ms-dns1 0.0.0.0> <ms-dns2
0.0.0.0>]
Wed Jun 24 18:01:59 2015 daemon.debug pppd[26786]: sent [IPV6CP ConfReq
id=0x1 <addr fe80::a080:8dd0:9948:fbb0>]
Wed Jun 24 18:01:59 2015 daemon.debug pppd[26786]: rcvd [IPCP ConfNak
id=0x1 <addr 192.168.161.167> <ms-dns1 192.168.1.7> <ms-dns2
255.255.255.255>]
Wed Jun 24 18:01:59 2015 daemon.debug pppd[26786]: sent [IPCP ConfReq
id=0x2 <compress VJ 0f 01> <addr 192.168.161.167> <ms-dns1 192.168.1.7>
<ms-dns2 255.255.255.255>]
Wed Jun 24 18:01:59 2015 daemon.debug pppd[26786]: rcvd [IPV6CP ConfAck
id=0x1 <addr fe80::a080:8dd0:9948:fbb0>]
Wed Jun 24 18:01:59 2015 daemon.debug pppd[26786]: rcvd [IPCP ConfAck
id=0x2 <compress VJ 0f 01> <addr 192.168.161.167> <ms-dns1 192.168.1.7>
<ms-dns2 255.255.255.255>]
Wed Jun 24 18:02:00 2015 daemon.debug pppd[26786]: sent [LCP EchoReq
id=0x1 magic=0x7098ad8]
Wed Jun 24 18:02:00 2015 daemon.debug pppd[26786]: rcvd [LCP EchoRep
id=0x1 magic=0xfc89ef78]
Wed Jun 24 18:02:01 2015 daemon.debug pppd[26786]: sent [LCP EchoReq
id=0x2 magic=0x7098ad8]
Wed Jun 24 18:02:01 2015 daemon.debug pppd[26786]: rcvd [LCP EchoRep
id=0x2 magic=0xfc89ef78]
Wed Jun 24 18:02:02 2015 daemon.debug pppd[26786]: sent [LCP EchoReq
id=0x3 magic=0x7098ad8]
Wed Jun 24 18:02:02 2015 daemon.debug pppd[26786]: rcvd [LCP EchoRep
id=0x3 magic=0xfc89ef78]
Wed Jun 24 18:02:02 2015 daemon.debug pppd[26786]: sent [IPV6CP ConfReq
id=0x1 <addr fe80::a080:8dd0:9948:fbb0>]
Wed Jun 24 18:02:02 2015 daemon.debug pppd[26786]: rcvd [IPCP ConfReq
id=0x2 <addr 192.168.161.1> <compress VJ 0f 01> <ms-dns1 192.168.1.7> <ms-
dns2 255.255.255.255>]
Wed Jun 24 18:02:02 2015 daemon.debug pppd[26786]: sent [IPCP ConfRej
id=0x2 <ms-dns1 192.168.1.7> <ms-dns2 255.255.255.255>]
Wed Jun 24 18:02:02 2015 daemon.debug pppd[26786]: rcvd [IPV6CP ConfReq
id=0x2 <addr fe80::0215:17ff:fe26:a616>]
Wed Jun 24 18:02:02 2015 daemon.debug pppd[26786]: sent [IPV6CP ConfAck
id=0x2 <addr fe80::0215:17ff:fe26:a616>]
Wed Jun 24 18:02:02 2015 daemon.debug pppd[26786]: sent [IPCP ConfReq
id=0x2 <compress VJ 0f 01> <addr 192.168.161.167> <ms-dns1 192.168.1.7>
<ms-dns2 255.255.255.255>]
Wed Jun 24 18:02:02 2015 daemon.debug pppd[26786]: rcvd [IPV6CP ConfAck
id=0x1 <addr fe80::a080:8dd0:9948:fbb0>]
Wed Jun 24 18:02:02 2015 daemon.err pppd[26786]: sif6addr:
ioctl(SIOCSIFADDR): No buffer space available (line 2645)
Wed Jun 24 18:02:02 2015 daemon.warn pppd[26786]: sif6addr failed
Wed Jun 24 18:02:02 2015 daemon.err pppd[26786]: cif6addr:
ioctl(SIOCDIFADDR): No such device or address (line 2695)
Wed Jun 24 18:02:02 2015 daemon.debug pppd[26786]: sent [IPV6CP TermReq
id=0x2 "Interface configuration failed"]
Wed Jun 24 18:02:02 2015 daemon.debug pppd[26786]: rcvd [IPCP ConfReq
id=0x3 <addr 192.168.161.1> <compress VJ 0f 01>]
Wed Jun 24 18:02:02 2015 daemon.debug pppd[26786]: sent [IPCP ConfAck
id=0x3 <addr 192.168.161.1> <compress VJ 0f 01>]
Wed Jun 24 18:02:02 2015 daemon.debug pppd[26786]: rcvd [Compressed data]
10 00 86 e5 79 a3 8d 41 ...
Wed Jun 24 18:02:02 2015 daemon.debug pppd[26786]: sent [CCP ResetReq
id=0x3]
Wed Jun 24 18:02:02 2015 daemon.debug pppd[26786]: rcvd [Compressed data]
10 01 f2 2d 45 57 a9 f3 ...
Wed Jun 24 18:02:02 2015 daemon.debug pppd[26786]: sent [CCP ResetReq
id=0x4]
Wed Jun 24 18:02:02 2015 kern.debug kernel: [ 4073.046793]
mppe_decompress[0]: FLUSHED bit not set in stateless mode!
Wed Jun 24 18:02:02 2015 daemon.debug pppd[26786]: rcvd [IPCP ConfAck
id=0x2 <compress VJ 0f 01> <addr 192.168.161.167> <ms-dns1 192.168.1.7>
<ms-dns2 255.255.255.255>]
Wed Jun 24 18:02:02 2015 daemon.notice pppd[26786]: local IP address
192.168.161.167
Wed Jun 24 18:02:02 2015 daemon.notice pppd[26786]: remote IP address
192.168.161.1
Wed Jun 24 18:02:02 2015 daemon.notice pppd[26786]: primary DNS address
192.168.1.7
Wed Jun 24 18:02:02 2015 daemon.notice pppd[26786]: secondary DNS address
255.255.255.255
Wed Jun 24 18:02:02 2015 daemon.debug pppd[26786]: Script /lib/netifd/ppp-
up started (pid 26815)
Wed Jun 24 18:02:02 2015 daemon.debug pppd[26786]: rcvd [IPV6CP TermAck
id=0x2]
Wed Jun 24 18:02:02 2015 daemon.notice netifd: Network device 'pptp-xxx'
link is up
Wed Jun 24 18:02:02 2015 daemon.notice netifd: Interface 'xxx' is now up
Wed Jun 24 18:02:02 2015 daemon.debug pppd[26786]: Script /lib/netifd/ppp-
up finished (pid 26815), status = 0x1
Wed Jun 24 18:02:02 2015 user.notice firewall: Reloading firewall due to
ifup of xxx (pptp-xxx)
Wed Jun 24 18:02:05 2015 daemon.info dnsmasq[1376]: reading
/tmp/resolv.conf.auto
Wed Jun 24 18:02:05 2015 daemon.info dnsmasq[1376]: using local addresses
only for domain lan
Wed Jun 24 18:02:05 2015 daemon.info dnsmasq[1376]: using nameserver
192.168.1.7#53
Wed Jun 24 18:02:05 2015 daemon.info dnsmasq[1376]: using nameserver
8.8.8.8#53
Wed Jun 24 18:02:57 2015 daemon.debug pppd[26788]: Echo Reply received.
Wed Jun 24 18:03:39 2015 daemon.debug pppd[26786]: rcvd [LCP ProtRej
id=0x3 ee 3a d5 d4 a4 4d 7c f8 c6 41 d4 26 c8 64 c9 d6 07 f2 e0 59 e8 f0
51 d7 13 83 73 b7 db 66 90 33 ...]
Wed Jun 24 18:03:39 2015 daemon.warn pppd[26786]: Protocol-Reject for
unsupported protocol 0xee3a
Wed Jun 24 18:03:40 2015 daemon.debug pppd[26786]: rcvd [LCP ProtRej
id=0x3 b9 c0 ec a1 22 43 c4 02 b7 80 8c c7 62 c1 06 0e 56 96 00 22 5f 99
55 01 f4 6c 55 2f ee be 83 7a ...]
Wed Jun 24 18:03:40 2015 daemon.warn pppd[26786]: Protocol-Reject for
unsupported protocol 0xb9c0
Wed Jun 24 18:03:57 2015 daemon.debug pppd[26788]: Echo Reply received.
Wed Jun 24 18:04:07 2015 daemon.err pppd[27209]: unrecognized option '-V'
Wed Jun 24 18:04:27 2015 daemon.debug pppd[26786]: rcvd [LCP ProtRej
id=0x3 2b 69 f4 83 2f 15 3d 2c a9 35 27 ac 47 a8 84 e3 3f b3 ab ec d1 aa
71 cd cc ab 41 94 97 a9 1d cd ...]
Wed Jun 24 18:04:27 2015 daemon.warn pppd[26786]: Protocol-Reject for
unsupported protocol 0x2b69
Wed Jun 24 18:04:57 2015 daemon.debug pppd[26788]: Echo Reply received.
Wed Jun 24 18:05:27 2015 daemon.debug pppd[26786]: rcvd [LCP ProtRej
id=0x3 e6 27 8f 85 d0 fe 13 38 93 a2 8b d0 f7 7c 3f 80 16 30 53 41 c4 ba
7e 86 6e a9 f4 c4 86 4a bb f0 ...]
Wed Jun 24 18:05:27 2015 daemon.warn pppd[26786]: Protocol-Reject for
unsupported protocol 0xe627
Wed Jun 24 18:05:58 2015 daemon.debug pppd[26788]: Echo Reply received.
Wed Jun 24 18:06:58 2015 daemon.debug pppd[26788]: Echo Reply received.
Wed Jun 24 18:07:58 2015 daemon.debug pppd[26788]: Echo Reply received.
Wed Jun 24 18:08:58 2015 daemon.debug pppd[26788]: Echo Reply received.
Wed Jun 24 18:09:58 2015 daemon.debug pppd[26788]: Echo Reply received.
Wed Jun 24 18:10:28 2015 daemon.debug pppd[26786]: rcvd [LCP ProtRej
id=0x3 a1 c3 8e fd b3 37 a3 0b 75 8a ef 62 34 62 12 ea 07 cd c0 44 00 f1
bc e8 d3 2a 99 71 0f 27 37 72 ...]
Wed Jun 24 18:10:28 2015 daemon.warn pppd[26786]: Protocol-Reject for
unsupported protocol 0xa1c3
Wed Jun 24 18:10:58 2015 daemon.debug pppd[26788]: Echo Reply received.
Wed Jun 24 18:11:58 2015 daemon.debug pppd[26788]: Echo Reply received.
Wed Jun 24 18:11:58 2015 daemon.debug pppd[26788]: no more Echo
Reply/Request packets will be reported.
Wed Jun 24 18:12:39 2015 daemon.debug pppd[26786]: rcvd [LCP ProtRej
id=0x3 2a df bb cb 69 2f ce f3 e8 d4 f4 db 99 02 0e b8 3b 1f 88 6d 47 7e
0e ea 18 31 69 eb b8 c9 8f 5d ...]
Wed Jun 24 18:12:39 2015 daemon.warn pppd[26786]: Protocol-Reject for
unsupported protocol 0x2adf
Wed Jun 24 18:14:07 2015 daemon.debug pppd[26786]: rcvd [LCP ProtRej
id=0x3 29 52 6a 11 0e d3 2e 6e 01 1a 35 67 f9 2f dc e6 e3 de 93 78 e0 1f
f1 bb 5e f9 91 b8 d9 18 fa 7e ...]
Wed Jun 24 18:14:07 2015 daemon.warn pppd[26786]: Protocol-Reject for
unsupported protocol 0x2952
}}}
--
Ticket URL: <https://dev.openwrt.org/ticket/19988>
OpenWrt <http://openwrt.org>
Opensource Wireless Router Technology
_______________________________________________
openwrt-tickets mailing list
[email protected]
https://lists.openwrt.org/cgi-bin/mailman/listinfo/openwrt-tickets
