warn: pptp: iprange module disabled, improper IP configuration of PPP interfaces may cause kernel soft lockup

[Colombiano]

Hi

I try to make a pptp connection but when I start accel-ppp I get this message and the pptp connection is not made

warn: pptp: iprange module disabled, improper IP configuration of PPP interfaces may cause kernel soft lockup

This is my configuration

[modules]
log_file
pppoe
auth_pap
client-ip-range
chap-secrets
ippool
shaper
pptp
auth_mschap_v2
#auth_mschap_v1
auth_chap_md5
#auth_pap
#ipv6pool
#ipv6_nd
#ipv6_dhcp

[core]
log-error=/var/log/accel-ppp/core.log
thread-count=4

[ppp]
verbose=1
min-mtu=1280
mtu=1480
mru=1480
ipv4=require
ipv6=deny
lcp-echo-interval=20
#lcp-echo-failure=10
lcp-echo-timeout=10

[pppoe]
verbose=1
ac-name=CXC
vlan-mon=eth2,2-4094
vlan-name=%I.%N
auth_chap_md5
auth_pap
ip-pool=pool1
ip-pool=bloqueada
vlan-timeout=60
interface=eth2
interface=re:eth2\.[\d]+

[pptp]
verbose=1
#echo-interval=30
ip-pool=pool_pptp
#ipv6-pool=pptp
#ipv6-pool-delegate=pptp
ifname=pptp%d
#port=n
#mppe=prefer

[dns]
dns1=10.0.99.252
dns2=10.0.99.1

[wins]
wins1=10.0.99.252
wins2=10.0.99.1

[client-ip-range]
0.0.0.0/0

[ip-pool]
gw-ip-address=xx.xx.xx.xx
101.165.100.2-101.165.100.254,name=pool1
10.165.100.2-10.165.100.254,name=pool_pptp
10.0.254.2-10.0.254.254,name=bloqueada

[log]
log-file=/var/log/accel-ppp/accel-ppp.log
log-emerg=/var/log/accel-ppp/emerg.log
log-fail-file=/var/log/accel-ppp/auth-fail.log
copy=1
level=5

[chap-secrets]
gw-ip-address=xx.xx.xx.xx
chap-secrets=/etc/chap-secrets

[shaper]
vendor=Mikrotik
attr=Mikrotik-Rate-Limit
#attr=Filter-Id
#down-burst-factor=0.1
#up-burst-factor=1.0
#latency=50
#mpu=0
#mtu=0
#r2q=10
#quantum=1500
#moderate-quantum=1
#cburst=1534
#ifb=ifb0
up-limiter=police
down-limiter=tbf
#leaf-qdisc=sfq perturb 10
#leaf-qdisc=fq_codel [limit PACKETS] [flows NUMBER] [target TIME] [interval TIME] [quantum BYTES] [[no]ecn]
#rate-multiplier=1
#fwmark=1
#rate-limit=2048/1024
verbose=1

[cli]
verbose=1
telnet=127.0.0.1:2000
tcp=127.0.0.1:2001

LOG

root@accel:/etc#
root@accel:/etc# tail -f /var/log/accel-ppp/accel-ppp.log
[2023-05-26 00:25:05]: debug: ppp0: recv [LCP EchoReq id=20 <magic 58d257f8>]
[2023-05-26 00:25:05]: debug: ppp0: send [LCP EchoRep id=20 <magic 78c85dbf>]
[2023-05-26 00:25:25]: debug: ppp0: recv [LCP EchoReq id=21 <magic 58d257f8>]
[2023-05-26 00:25:25]: debug: ppp0: send [LCP EchoRep id=21 <magic 78c85dbf>]
[2023-05-26 00:25:45]: debug: ppp0: send [LCP EchoReq id=84 <magic 78c85dbf>]
[2023-05-26 00:25:45]: debug: ppp0: recv [LCP EchoRep id=84 <magic 58d257f8>]
[2023-05-26 00:25:45]: debug: ppp0: recv [LCP EchoReq id=22 <magic 58d257f8>]
[2023-05-26 00:25:45]: debug: ppp0: send [LCP EchoRep id=22 <magic 78c85dbf>]
[2023-05-26 00:26:05]: debug: ppp0: recv [LCP EchoReq id=23 <magic 58d257f8>]
[2023-05-26 00:26:05]: debug: ppp0: send [LCP EchoRep id=23 <magic 78c85dbf>]
[2023-05-26 00:26:16]: info: cli: tcp: new connection from 127.0.0.1
[2023-05-26 00:26:16]: debug: cli: disconnect
[2023-05-26 00:26:16]: info: terminate, sig = 15
[2023-05-26 00:26:16]: debug: ppp0: terminate
[2023-05-26 00:26:16]: debug: ppp0: lcp_layer_finish
[2023-05-26 00:26:16]: info: ppp0: send [LCP TermReq id=134]
[2023-05-26 00:26:16]: debug: ppp0: auth_layer_finish
[2023-05-26 00:26:16]: debug: ppp0: auth_layer_finished
[2023-05-26 00:26:16]: debug: ppp0: ccp_layer_finish
[2023-05-26 00:26:16]: debug: ppp0: ccp_layer_finished
[2023-05-26 00:26:16]: debug: ppp0: ipcp_layer_finish
[2023-05-26 00:26:16]: debug: ppp0: ipcp_layer_finished
[2023-05-26 00:26:16]: debug: ppp0: ipv6cp_layer_finish
[2023-05-26 00:26:16]: debug: ppp0: ipv6cp_layer_finished
[2023-05-26 00:26:16]: info: ppp0: recv [LCP TermAck id=86]
[2023-05-26 00:26:16]: debug: ppp0: lcp_layer_finished
[2023-05-26 00:26:16]: debug: ppp0: pppoe: ppp finished
[2023-05-26 00:26:16]: debug: ppp0: lcp_layer_free
[2023-05-26 00:26:16]: debug: ppp0: auth_layer_free
[2023-05-26 00:26:16]: debug: ppp0: ccp_layer_free
[2023-05-26 00:26:16]: debug: ppp0: ipcp_layer_free
[2023-05-26 00:26:16]: debug: ppp0: ipv6cp_layer_free
[2023-05-26 00:26:16]: debug: ppp0: ppp destablished
[2023-05-26 00:26:16]: info: eth2.1001: send [PPPoE PADT 90:e2:ba:37:4c:bd => 68:58:11:6c:2a:e3 sid=0001 <AC-Name CXC> <Service-Name >]
[2023-05-26 00:26:16]: info: ppp0: disconnected
[2023-05-26 00:26:16]: info: pppoe: remove vlan eth2.1001
[2023-05-26 00:26:17]: warn: pptp: iprange module disabled, improper IP configuration of PPP interfaces may cause kernel soft lockup
[2023-05-26 00:26:24]: debug: vlan-mon: notify 4 1001 8863 0
[2023-05-26 00:26:24]: warn: pppoe: discard PADT (destination address is broadcast)
[2023-05-26 00:26:24]: info: pppoe: create vlan eth2.1001 parent eth2
[2023-05-26 00:26:25]: info: eth2.1001: recv [PPPoE PADI 68:58:11:6c:2a:e3 => ff:ff:ff:ff:ff:ff sid=0000 <Service-Name > <Host-Uniq 34640000>]
[2023-05-26 00:26:25]: info: eth2.1001: send [PPPoE PADO 90:e2:ba:37:4c:bd => 68:58:11:6c:2a:e3 sid=0000 <AC-Name CXC> <Service-Name > <AC-Cookie d41299bc83c0adb19c0f9d2fa6d0804ebaf76de4d1cd03b5> <Host-Uniq 34640000>]
[2023-05-26 00:26:25]: info: eth2.1001: recv [PPPoE PADR 68:58:11:6c:2a:e3 => 90:e2:ba:37:4c:bd sid=0000 <Service-Name > <Host-Uniq 34640000> <AC-Cookie d41299bc83c0adb19c0f9d2fa6d0804ebaf76de4d1cd03b5>]
[2023-05-26 00:26:25]: info: eth2.1001: send [PPPoE PADS 90:e2:ba:37:4c:bd => 68:58:11:6c:2a:e3 sid=0001 <AC-Name CXC> <Service-Name > <Host-Uniq 34640000>]
[2023-05-26 00:26:25]: debug: eth2.1001: lcp_layer_init
[2023-05-26 00:26:25]: debug: eth2.1001: auth_layer_init
[2023-05-26 00:26:25]: debug: eth2.1001: ccp_layer_init
[2023-05-26 00:26:25]: debug: eth2.1001: ipcp_layer_init
[2023-05-26 00:26:25]: debug: eth2.1001: ipv6cp_layer_init
[2023-05-26 00:26:25]: debug: eth2.1001: ppp establishing
[2023-05-26 00:26:25]: debug: eth2.1001: lcp_layer_start
[2023-05-26 00:26:25]: info: eth2.1001: send [LCP ConfReq id=8 <auth PAP> <mru 1480> <magic 5f1cf8e1>]
[2023-05-26 00:26:25]: info: eth2.1001: recv [LCP ConfReq id=1 <mru 1492> <magic 5a5c5691>]
[2023-05-26 00:26:25]: info: eth2.1001: send [LCP ConfAck id=1]
[2023-05-26 00:26:28]: debug: eth2.1001: fsm timeout 9
[2023-05-26 00:26:28]: info: eth2.1001: send [LCP ConfReq id=8 <auth PAP> <mru 1480> <magic 5f1cf8e1>]
[2023-05-26 00:26:28]: info: eth2.1001: recv [LCP ConfAck id=8 <auth PAP> <mru 1480> <magic 5f1cf8e1>]
[2023-05-26 00:26:28]: debug: eth2.1001: lcp_layer_started
[2023-05-26 00:26:28]: debug: eth2.1001: auth_layer_start
[2023-05-26 00:26:28]: debug: eth2.1001: recv [LCP EchoReq id=0 <magic 5a5c5691>]
[2023-05-26 00:26:28]: debug: eth2.1001: send [LCP EchoRep id=0 <magic 5f1cf8e1>]
[2023-05-26 00:26:28]: info: eth2.1001: recv [PAP AuthReq id=1]
[2023-05-26 00:26:28]: info: ppp0: connect: ppp0 <--> pppoe(68:58:11:6c:2a:e3)
[2023-05-26 00:26:28]: debug: ppp0: ppp connected
[2023-05-26 00:26:28]: info: ppp0: send [PAP AuthAck id=1 "Authentication succeeded"]
[2023-05-26 00:26:28]: info: ppp0: LuisE5: authentication succeeded
[2023-05-26 00:26:28]: debug: ppp0: auth_layer_started
[2023-05-26 00:26:28]: debug: ppp0: ccp_layer_start
[2023-05-26 00:26:28]: debug: ppp0: ipcp_layer_start
[2023-05-26 00:26:28]: info: ppp0: send [IPCP ConfReq id=ca <addr 190.131.219.93>]
[2023-05-26 00:26:28]: debug: ppp0: ipv6cp_layer_start
[2023-05-26 00:26:28]: info: ppp0: recv [IPCP ConfReq id=1 <addr 0.0.0.0> <dns1 0.0.0.0> <dns2 0.0.0.0>]
[2023-05-26 00:26:28]: info: ppp0: send [IPCP ConfNak id=1 <addr 101.165.100.2> <dns1 10.0.99.252> <dns2 10.0.99.1>]
[2023-05-26 00:26:28]: info: ppp0: recv [IPCP ConfAck id=ca <addr 190.131.219.93>]
[2023-05-26 00:26:28]: info: ppp0: recv [IPCP ConfReq id=2 <addr 101.165.100.2> <dns1 10.0.99.252> <dns2 10.0.99.1>]
[2023-05-26 00:26:28]: info: ppp0: send [IPCP ConfAck id=2]
[2023-05-26 00:26:28]: debug: ppp0: ipcp_layer_started
[2023-05-26 00:26:28]: info: ppp0: shaper: installed shaper 362400/322400 (Kbit)
[2023-05-26 00:26:28]: debug: libnetlink: RTNETLINK answers: File exists
[2023-05-26 00:26:28]: debug: libnetlink: RTNETLINK answers: File exists
[2023-05-26 00:26:28]: debug: ppp0: pppoe: ppp started
[2023-05-26 00:26:38]: info: pptp: new connection from 114.150.128.172
[2023-05-26 00:26:38]: info: : recv [PPTP Start-Ctrl-Conn-Request <Version 1> <Framing 1> <Bearer 1> <Max-Chan 0>]
[2023-05-26 00:26:38]: info: : send [PPTP Start-Ctrl-Conn-Reply <Version 1> <Result 1> <Error 0> <Framing 3> <Bearer 3> <Max-Chan 1>]
[2023-05-26 00:26:38]: info: : recv [PPTP Outgoing-Call-Request <Call-ID d9fa> <Call-Serial e> <Min-BPS 300> <Max-BPS 100000000> <Bearer 3> <Framing 3> <Window-Size 64> <Delay 0>]
[2023-05-26 00:26:38]: info: : send [PPTP Outgoing-Call-Reply <Call-ID 7> <Peer-Call-ID d9fa> <Result 1> <Error 0> <Cause 0> <Speed 100000000> <Window-Size 64> <Delay 0> <Channel 0>]
[2023-05-26 00:26:38]: debug: : lcp_layer_init
[2023-05-26 00:26:38]: debug: : auth_layer_init
[2023-05-26 00:26:38]: debug: : ccp_layer_init
[2023-05-26 00:26:38]: debug: : ipcp_layer_init
[2023-05-26 00:26:38]: debug: : ipv6cp_layer_init
[2023-05-26 00:26:38]: debug: : ppp establishing
[2023-05-26 00:26:38]: debug: : lcp_layer_start
[2023-05-26 00:26:38]: info: : send [LCP ConfReq id=8d <auth PAP> <mru 1436> <magic 1edab1a8>]
[2023-05-26 00:26:38]: info: : recv [PPTP Set-Link-Info]
[2023-05-26 00:26:41]: debug: : fsm timeout 9
[2023-05-26 00:26:41]: info: : send [LCP ConfReq id=8d <auth PAP> <mru 1436> <magic 1edab1a8>]
[2023-05-26 00:26:44]: debug: : fsm timeout 8
[2023-05-26 00:26:44]: info: : send [LCP ConfReq id=8d <auth PAP> <mru 1436> <magic 1edab1a8>]
[2023-05-26 00:26:47]: debug: : fsm timeout 7
[2023-05-26 00:26:47]: info: : send [LCP ConfReq id=8d <auth PAP> <mru 1436> <magic 1edab1a8>]
[2023-05-26 00:26:48]: debug: ppp0: recv [LCP EchoReq id=1 <magic 5a5c5691>]
[2023-05-26 00:26:48]: debug: ppp0: send [LCP EchoRep id=1 <magic 5f1cf8e1>]
[2023-05-26 00:26:50]: debug: : fsm timeout 6
[2023-05-26 00:26:50]: info: : send [LCP ConfReq id=8d <auth PAP> <mru 1436> <magic 1edab1a8>]
[2023-05-26 00:26:53]: debug: : fsm timeout 5
[2023-05-26 00:26:53]: info: : send [LCP ConfReq id=8d <auth PAP> <mru 1436> <magic 1edab1a8>]
[2023-05-26 00:26:56]: debug: : fsm timeout 4
[2023-05-26 00:26:56]: info: : send [LCP ConfReq id=8d <auth PAP> <mru 1436> <magic 1edab1a8>]
[2023-05-26 00:26:59]: debug: : fsm timeout 3
[2023-05-26 00:26:59]: info: : send [LCP ConfReq id=8d <auth PAP> <mru 1436> <magic 1edab1a8>]
[2023-05-26 00:27:02]: debug: : fsm timeout 2
[2023-05-26 00:27:02]: info: : send [LCP ConfReq id=8d <auth PAP> <mru 1436> <magic 1edab1a8>]
[2023-05-26 00:27:05]: debug: : fsm timeout 1
[2023-05-26 00:27:05]: info: : send [LCP ConfReq id=8d <auth PAP> <mru 1436> <magic 1edab1a8>]
[2023-05-26 00:27:08]: debug: : fsm timeout 0
[2023-05-26 00:27:08]: debug: : lcp_layer_finished
[2023-05-26 00:27:08]: debug: : terminate
[2023-05-26 00:27:08]: debug: : lcp_layer_finish
[2023-05-26 00:27:08]: debug: : pptp: ppp finished
[2023-05-26 00:27:08]: info: : send [PPTP Call-Disconnect-Notify <Call-ID fad9> <Result 3> <Error 0> <Cause 0>]
[2023-05-26 00:27:08]: info: : send [PPTP Stop-Ctrl-Conn-Request <Reason 0>]
[2023-05-26 00:27:08]: debug: : lcp_layer_free
[2023-05-26 00:27:08]: debug: : auth_layer_free
[2023-05-26 00:27:08]: debug: : ccp_layer_free
[2023-05-26 00:27:08]: debug: : ipcp_layer_free
[2023-05-26 00:27:08]: debug: : ipv6cp_layer_free
[2023-05-26 00:27:08]: debug: ppp0: recv [LCP EchoReq id=2 <magic 5a5c5691>]
[2023-05-26 00:27:08]: debug: ppp0: send [LCP EchoRep id=2 <magic 5f1cf8e1>]
[2023-05-26 00:27:08]: info: : recv [PPTP Stop-Ctrl-Conn-Reply <Result 1> <Error 0>]
[2023-05-26 00:27:08]: debug: : pptp: disconnect
[2023-05-26 00:27:08]: info: : disconnected

[dimka88]

Hi, it is just warning message that notify "to be safe, just declare from which networks pptp clints will connect to preevent overlaped routes"

warn: pptp: iprange module disabled, improper IP configuration of PPP interfaces may cause kernel soft lockup

[Colombiano]

Hi, it is just warning message that notify "to be safe, just declare from which networks pptp clints will connect to preevent overlaped routes"

warn: pptp: iprange module disabled, improper IP configuration of PPP interfaces may cause kernel soft lockup

Thanks for your answer, but even though it's just a warning message, I still can't connect, I immediately start it and it stays waiting and never makes a connection... any suggestions please

info: pptp: new connection from 191.232.2.94
[2023-06-05 19:46:51]: info: : recv [PPTP Start-Ctrl-Conn-Request <Version 1> <Framing 1> <Bearer 1> <Max-Chan 0>]
[2023-06-05 19:46:51]: info: : send [PPTP Start-Ctrl-Conn-Reply <Version 1> <Result 1> <Error 0> <Framing 3> <Bearer 3> <Max-Chan 1>]
[2023-06-05 19:46:51]: info: : recv [PPTP Outgoing-Call-Request <Call-ID c326> <Call-Serial 7> <Min-BPS 300> <Max-BPS 100000000> <Bearer 3> <Framing 3> <Window-Size 64> <Delay 0>]
[2023-06-05 19:46:51]: info: : send [PPTP Outgoing-Call-Reply <Call-ID 7> <Peer-Call-ID c326> <Result 1> <Error 0> <Cause 0> <Speed 100000000> <Window-Size 64> <Delay 0> <Channel 0>]
[2023-06-05 19:46:51]: debug: : lcp_layer_init
[2023-06-05 19:46:51]: debug: : auth_layer_init
[2023-06-05 19:46:51]: debug: : ccp_layer_init
[2023-06-05 19:46:51]: debug: : ipcp_layer_init
[2023-06-05 19:46:51]: debug: : ipv6cp_layer_init
[2023-06-05 19:46:51]: debug: : ppp establishing
[2023-06-05 19:46:51]: debug: : lcp_layer_start
[2023-06-05 19:46:51]: info: : send [LCP ConfReq id=64 <auth MSCHAP-v2> <mru 1436> <magic 45f739f7>]
[2023-06-05 19:46:51]: info: : recv [PPTP Set-Link-Info]
[2023-06-05 19:46:54]: debug: : fsm timeout 9
[2023-06-05 19:46:54]: info: : send [LCP ConfReq id=64 <auth MSCHAP-v2> <mru 1436> <magic 45f739f7>]
[2023-06-05 19:46:57]: debug: : fsm timeout 8
[2023-06-05 19:46:57]: info: : send [LCP ConfReq id=64 <auth MSCHAP-v2> <mru 1436> <magic 45f739f7>]
[2023-06-05 19:47:00]: debug: : fsm timeout 7
[2023-06-05 19:47:00]: info: : send [LCP ConfReq id=64 <auth MSCHAP-v2> <mru 1436> <magic 45f739f7>]
[2023-06-05 19:47:03]: debug: : fsm timeout 6
[2023-06-05 19:47:03]: info: : send [LCP ConfReq id=64 <auth MSCHAP-v2> <mru 1436> <magic 45f739f7>]
[2023-06-05 19:47:04]: info: : recv [PPTP Call-Clear-Request <Call-ID c326>]
[2023-06-05 19:47:04]: debug: : terminate
[2023-06-05 19:47:04]: debug: : lcp_layer_free
[2023-06-05 19:47:04]: debug: : auth_layer_free
[2023-06-05 19:47:04]: debug: : ccp_layer_free
[2023-06-05 19:47:04]: debug: : ipcp_layer_free
[2023-06-05 19:47:04]: debug: : ipv6cp_layer_free
[2023-06-05 19:47:04]: info: : send [PPTP Call-Disconnect-Notify <Call-ID 26c3> <Result 4> <Error 0> <Cause 0>]
[2023-06-05 19:47:05]: info: : recv [PPTP Stop-Ctrl-Conn-Request <Reason 1>]
[2023-06-05 19:47:05]: info: : send [PPTP Stop-Ctrl-Conn-Reply <Result 1> <Error 0>]
[2023-06-05 19:47:05]: debug: : pptp: disconnect
[2023-06-05 19:47:05]: info: : disconnected

[pptp]
verbose=1
#bind=0.0.0.0
port=1723
echo-interval=0
echo-failure=3
timeout=5
mppe=deny
ifname=ifname
ppp-max-mtu=n
#By default ppp-max-mtu=1436
ip-pool=pool_pptp

[client-ip-range]
0.0.0.0/0

[ip-pool]
gw-ip-address=190.131.219.93
101.165.100.0/24,name=pool1
10.165.100.0/24,name=pool_pptp
10.0.254.0/24,name=bloqueada

[dimka88]

Hi, it looks like your ppptp client behind NAT without special NAT PPTP features, like nf_pptp_nat modules. It is PPTP protocol specific, try l2tp instead, it has not the similar issue