3 seconds pause

PPPoE related questions
Post Reply
alister
Posts: 3
Joined: 09 Nov 2016, 15:23

3 seconds pause

Post by alister »

Something strange with LCP connection.
I've tried different versions - the same.
When disable CCP=0
I see fsm timeout 9

I have no idea what's wrong....
[2016-11-09 17:21:35.620] recv [PPPoE PADI f4:6d:04:f8:5c:c1 => ff:ff:ff:ff:ff:ff sid=0000 <Service-Name > <Host-Uniq 050000000000000008000000>]
[2016-11-09 17:21:35.620] send [PPPoE PADO 28:92:4a:37:09:bb => f4:6d:04:f8:5c:c1 sid=0000 <AC-Name accel-ppp> <Service-Name > <AC-Cookie 4924d07553d4723325675a610ee4d52a383ac0437000c285> <Host-Uniq 050000000000000008000000>]
[2016-11-09 17:21:35.621] recv [PPPoE PADR f4:6d:04:f8:5c:c1 => 28:92:4a:37:09:bb sid=0000 <Service-Name > <Host-Uniq 050000000000000009000000> <AC-Cookie 4924d07553d4723325675a610ee4d52a383ac0437000c285>]
[2016-11-09 17:21:35.621] send [PPPoE PADS 28:92:4a:37:09:bb => f4:6d:04:f8:5c:c1 sid=0080 <AC-Name accel-ppp> <Service-Name > <Host-Uniq 050000000000000009000000>]
[2016-11-09 17:21:35.621] ppp0: : connect: ppp0 <--> pppoe(f4:6d:04:f8:5c:c1)
[2016-11-09 17:21:35.621] ppp0: a4c8ec8f7d90a7e8: send [LCP ConfReq id=1 <auth MSCHAP-v2> <mru 1400> <magic 643c9869>]
[2016-11-09 17:21:35.629] ppp0: a4c8ec8f7d90a7e8: recv [LCP ConfReq id=0 <mru 1480> <magic 50163e72> <pcomp> <accomp> < d 3 6 >]
[2016-11-09 17:21:35.629] ppp0: a4c8ec8f7d90a7e8: send [LCP ConfRej id=0 <pcomp> <accomp> < d 3 6 >]
[2016-11-09 17:21:35.629] ppp0: a4c8ec8f7d90a7e8: recv [LCP ConfReq id=1 <mru 1480> <magic 50163e72>]
[2016-11-09 17:21:35.630] ppp0: a4c8ec8f7d90a7e8: send [LCP ConfAck id=1 ]
[2016-11-09 17:21:38.621] ppp0: a4c8ec8f7d90a7e8: send [LCP ConfReq id=1 <auth MSCHAP-v2> <mru 1400> <magic 643c9869>]

[2016-11-09 17:21:38.622] ppp0: a4c8ec8f7d90a7e8: recv [LCP ConfAck id=1 <auth MSCHAP-v2> <mru 1400> <magic 643c9869>]
[2016-11-09 17:21:38.622] ppp0: a4c8ec8f7d90a7e8: send [MSCHAP-v2 Challenge id=1 <4cf0b794a770818c279fb863a8e3138b>]
[2016-11-09 17:21:38.622] ppp0: a4c8ec8f7d90a7e8: recv [LCP Ident id=2 <MSRASV5.20>]
[2016-11-09 17:21:38.622] ppp0: a4c8ec8f7d90a7e8: recv [LCP Ident id=3 <MSRAS-0-ASUS1600-PC>]
[2016-11-09 17:21:38.622] ppp0: a4c8ec8f7d90a7e8: recv [LCP Ident id=4 <▒▒▒▒;▒G▒▒▒
q▒d▒>]
[2016-11-09 17:21:38.624] ppp0: a4c8ec8f7d90a7e8: recv [MSCHAP-v2 Response id=1 <209a57648c7179a6f7612e319a4ea95>, <d8b3ad60f25c3d7c2c84daf1c4f55ea4d0e6be435a962b>, F=0, name="test"]
[2016-11-09 17:21:38.624] ppp0: a4c8ec8f7d90a7e8: send [MSCHAP-v2 Success id=1 "S=EDDD9F0367E8D6B809051480DF70AE78D67D95C0 M=Authentication succeeded"]
[2016-11-09 17:21:38.624] ppp0: a4c8ec8f7d90a7e8: send [IPCP ConfReq id=1 <addr 192.168.100.1>]
[2016-11-09 17:21:38.624] ppp0: a4c8ec8f7d90a7e8: test: authentication succeeded
[2016-11-09 17:21:38.626] ppp0: a4c8ec8f7d90a7e8: recv [CCP ConfReq id=5 <mppe -H -M -S -L -D -C>]
[2016-11-09 17:21:38.626] ppp0: a4c8ec8f7d90a7e8: send [CCP ConfReq id=1 <mppe -H -M -S -L -D -C>]
[2016-11-09 17:21:38.626] ppp0: a4c8ec8f7d90a7e8: send [CCP ConfAck id=5]
[2016-11-09 17:21:38.626] ppp0: a4c8ec8f7d90a7e8: recv [IPCP ConfReq id=6 <addr 0.0.0.0> <dns1 0.0.0.0> <wins1 0.0.0.0> <dns2 0.0.0.0> <wins2 0.0.0.0>]
[2016-11-09 17:21:38.626] ppp0: a4c8ec8f7d90a7e8: send [IPCP ConfNak id=6 <addr 10.0.10.2>]
[2016-11-09 17:21:38.626] ppp0: a4c8ec8f7d90a7e8: recv [IPCP ConfAck id=1 <addr 192.168.100.1>]
[2016-11-09 17:21:38.628] ppp0: a4c8ec8f7d90a7e8: recv [CCP ConfAck id=1 <mppe -H -M -S -L -D -C>]
[2016-11-09 17:21:38.628] ppp0: a4c8ec8f7d90a7e8: recv [IPCP ConfReq id=7 <addr 10.0.10.2> <dns1 0.0.0.0> <wins1 0.0.0.0> <dns2 0.0.0.0> <wins2 0.0.0.0>]
[2016-11-09 17:21:38.628] ppp0: a4c8ec8f7d90a7e8: send [IPCP ConfAck id=7]
[2016-11-09 17:21:38.628] ppp0: a4c8ec8f7d90a7e8: pppd_compat: ip-up started (pid 14212)
[2016-11-09 17:21:38.695] ppp0: a4c8ec8f7d90a7e8: pppd_compat: ip-up finished (0)
dimka88
Posts: 866
Joined: 13 Oct 2014, 05:51
Contact:

Re: 3 seconds pause

Post by dimka88 »

hi, show ppp section in accel-ppp.conf
alister
Posts: 3
Joined: 09 Nov 2016, 15:23

Re: 3 seconds pause

Post by alister »

[2016-11-09 19:21:10.341] eth1: a4c8ec8f7d90dad0: send [LCP ConfReq id=1 <auth MSCHAP-v2> <mru 1400> <magic 327b23c6>]
[2016-11-09 19:21:10.346] eth1: a4c8ec8f7d90dad0: recv [LCP ConfReq id=0 <mru 1480> <magic 3a270322> <pcomp> <accomp>]
[2016-11-09 19:21:10.346] eth1: a4c8ec8f7d90dad0: send [LCP ConfRej id=0 <pcomp> <accomp>]
[2016-11-09 19:21:10.348] eth1: a4c8ec8f7d90dad0: recv [LCP ConfReq id=1 <mru 1480> <magic 3a270322>]
[2016-11-09 19:21:10.348] eth1: a4c8ec8f7d90dad0: send [LCP ConfAck id=1 ]

here 3 seconds before auth mschap

[2016-11-09 19:21:13.342] eth1: a4c8ec8f7d90dad0: send [LCP ConfReq id=1 <auth MSCHAP-v2> <mru 1400> <magic 327b23c6>]
[2016-11-09 19:21:13.342] eth1: a4c8ec8f7d90dad0: recv [LCP ConfAck id=1 <auth MSCHAP-v2> <mru 1400> <magic 327b23c6>]

Code: Select all

[ppp]
verbose=4
min-mtu=1280
mtu=1400
mru=1400
#accomp=deny
#pcomp=deny
#ccp=0
#check-ip=0
#mppe=require
ipv4=require
ipv6=deny
ipv6-intf-id=0:0:0:1
ipv6-peer-intf-id=0:0:0:2
ipv6-accept-peer-intf-id=1
lcp-echo-interval=20
#lcp-echo-failure=3
lcp-echo-timeout=120
unit-cache=1
#unit-preallocate=1
dimka88
Posts: 866
Joined: 13 Oct 2014, 05:51
Contact:

Re: 3 seconds pause

Post by dimka88 »

ppp: discard packets with invalid id before passing it to fsm

ppp: default fsm timeout increased to 3 sec.
ppp: accept LCP TermReq/TermAck packets in terminating phase
https://sourceforge.net/p/accel-ppp/cod ... 51894bd58/

You may change this value, but I think this is no best practices.
accel-ppp-code/accel-pppd/ppp/ppp_fsm.c

Code: Select all

static int conf_timeout = 3;
alister
Posts: 3
Joined: 09 Nov 2016, 15:23

Re: 3 seconds pause

Post by alister »

i'll try thx, but i'm not sure why does it wait for timeout?
FSM is broken?
Post Reply