Modify

Opened 3 years ago

Last modified 3 years ago

#19988 new defect

pptp connected but don't works. LCP ProtRej Protocol-Reject for unsupported protocol

Reported by: pavel.gumenyuk@… Owned by: developers
Priority: normal Milestone: Barrier Breaker 14.07
Component: packages Version: Barrier Breaker 14.07
Keywords: pptp, mppe, pppd Cc:

Description

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 'xxx@mxxx.xxx'
        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 = "xxx@xxx.xxx"]
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

Attachments (0)

Change History (1)

comment:1 Changed 3 years ago by finas

same with me.

Add Comment

Modify Ticket

Action
as new .
Author


E-mail address and user name can be saved in the Preferences.

 
Note: See TracTickets for help on using tickets.