Разрыв соединения ppp при пуле из одного адреса

PPTP related questions
Post Reply
alexeit
Posts: 2
Joined: 09 May 2018, 15:54

Разрыв соединения ppp при пуле из одного адреса

Post by alexeit »

Здравствуйте!

Работаю по pptp: сервер accel-ppp, клиент pppd->pptp.

Если на accel сконфигурирован пул из одного адреса:

Code: Select all

[ip-pool]
gw-ip-address=1.1.1.1
1.1.1.10/32
то клиент подключается, и через время таймаута отключается, в логах сообщение

Code: Select all

fsm timeout 9
.
Поскольку тайм-аут по умолчанию равен 3, разрыв происходит практически мгновенно. Если поставить в конфигурационном файле

Code: Select all

[ppp]
timeout=120
то разрыв через 2 минуты. Если в пуле хотя бы 2 адреса, разрыва не происходит.
Dmitry
Администратор
Posts: 954
Joined: 09 Oct 2014, 10:06

Re: Разрыв соединения ppp при пуле из одного адреса

Post by Dmitry »

привет
надо больше логов
все verbose=1
[log]
level=5
alexeit
Posts: 2
Joined: 09 May 2018, 15:54

Re: Разрыв соединения ppp при пуле из одного адреса

Post by alexeit »

Уточнение: при первом подключении соединение устанавливается. Вот лог:

Code: Select all

[2018-05-16 15:37:34]:  info: pptp: new connection from 20.20.20.2
[2018-05-16 15:37:35]: debug: : lcp_layer_init
[2018-05-16 15:37:35]: debug: : auth_layer_init
[2018-05-16 15:37:35]: debug: : ccp_layer_init
[2018-05-16 15:37:35]: debug: : ipcp_layer_init
[2018-05-16 15:37:35]: debug: : ipv6cp_layer_init
[2018-05-16 15:37:35]: debug: : ppp establishing
[2018-05-16 15:37:35]: debug: : lcp_layer_start
[2018-05-16 15:37:35]:  info: : send [LCP ConfReq id=1 <auth CHAP-md5> <mru 1400> <magic 74b0dc51>]
[2018-05-16 15:37:35]:  info: : recv [LCP ConfReq id=1 < 2 6 0 0 0 0 > <magic 01d22020> <pcomp> <accomp>]
[2018-05-16 15:37:35]:  info: : send [LCP ConfRej id=1  < 2 6 0 0 0 0 > <pcomp> <accomp>]
[2018-05-16 15:37:35]:  info: : recv [LCP ConfAck id=1 <auth CHAP-md5> <mru 1400> <magic 74b0dc51>]
[2018-05-16 15:37:35]:  info: : recv [LCP ConfReq id=2 <magic 01d22020>]
[2018-05-16 15:37:35]:  info: : send [LCP ConfAck id=2 ]
[2018-05-16 15:37:35]: debug: : lcp_layer_started
[2018-05-16 15:37:35]: debug: : auth_layer_start
[2018-05-16 15:37:35]:  info: : send [CHAP Challenge id=1 <5f8a6ae7b795d7827d898367814521a8>]
[2018-05-16 15:37:35]: debug: : recv [LCP EchoReq id=0 <magic 01d22020>]
[2018-05-16 15:37:35]: debug: : send [LCP EchoRep id=0 <magic 74b0dc51>]
[2018-05-16 15:37:35]:  info: : recv [CHAP Response id=1 <cb80252ddcd4b444b4bb8ea7c2196eb>, name="user1"]
[2018-05-16 15:37:35]:  info: ppp1000: connect: ppp1000 <--> pptp(20.20.20.2)
[2018-05-16 15:37:35]: debug: ppp1000: ppp connected
[2018-05-16 15:37:35]:  info: ppp1000: send [CHAP Success id=1 "Authentication succeeded"]
[2018-05-16 15:37:35]: debug: ppp1000: auth_layer_started
[2018-05-16 15:37:35]: debug: ppp1000: ccp_layer_start
[2018-05-16 15:37:35]: debug: ppp1000: ipcp_layer_start
[2018-05-16 15:37:35]:  info: ppp1000: send [IPCP ConfReq id=1 <addr 1.1.1.1>]
[2018-05-16 15:37:35]: debug: ppp1000: ipv6cp_layer_start
[2018-05-16 15:37:35]:  info: ppp1000: user1: authentication succeeded
[2018-05-16 15:37:35]:  info: ppp1000: recv [CCP ConfReq id=1 < 1a 4 78 0 > < 18 4 78 0 > < 15 3 2f >]
[2018-05-16 15:37:35]:  info: ppp1000: send [CCP ConfReq id=1]
[2018-05-16 15:37:35]:  info: ppp1000: send [CCP ConfRej id=1 < 1a 4 78 0 > < 18 4 78 0 > < 15 3 2f >]
[2018-05-16 15:37:35]:  info: ppp1000: recv [IPCP ConfReq id=1 < 2 6 0 2d f 1 > <addr 0.0.0.0>]
[2018-05-16 15:37:35]:  info: ppp1000: send [IPCP ConfRej id=1 < 2 6 0 2d f 1 >]
[2018-05-16 15:37:35]:  info: ppp1000: recv [IPCP ConfAck id=1 <addr 1.1.1.1>]
[2018-05-16 15:37:35]:  info: ppp1000: recv [CCP ConfAck id=1]
[2018-05-16 15:37:35]:  info: ppp1000: recv [CCP ConfReq id=2]
[2018-05-16 15:37:35]:  info: ppp1000: send [CCP ConfAck id=2]
[2018-05-16 15:37:35]: debug: ppp1000: ccp_layer_started
[2018-05-16 15:37:35]:  info: ppp1000: recv [IPCP ConfReq id=2 <addr 0.0.0.0>]
[2018-05-16 15:37:35]:  info: ppp1000: send [IPCP ConfNak id=2 <addr 1.1.1.10>]
[2018-05-16 15:37:35]:  info: ppp1000: recv [IPCP ConfReq id=3 <addr 1.1.1.10>]
[2018-05-16 15:37:35]:  info: ppp1000: send [IPCP ConfAck id=3]
[2018-05-16 15:37:35]: debug: ppp1000: ipcp_layer_started
[2018-05-16 15:37:35]: debug: ppp1000: pptp: ppp started
[2018-05-16 15:37:35]:  info: ppp1000: pppd_compat: ip-up started (pid 3815)
[2018-05-16 15:37:35]:  info: ppp1000: pppd_compat: ip-up finished (0)
[2018-05-16 15:37:40]: debug: ppp1000: recv [LCP EchoReq id=1 <magic 01d22020>]
[2018-05-16 15:37:40]: debug: ppp1000: send [LCP EchoRep id=1 <magic 74b0dc51>]
[2018-05-16 15:37:45]: debug: ppp1000: recv [LCP EchoReq id=2 <magic 01d22020>]
[2018-05-16 15:37:45]: debug: ppp1000: send [LCP EchoRep id=2 <magic 74b0dc51>]
[2018-05-16 15:37:50]: debug: ppp1000: recv [LCP EchoReq id=3 <magic 01d22020>]
[2018-05-16 15:37:50]: debug: ppp1000: send [LCP EchoRep id=3 <magic 74b0dc51>]
[2018-05-16 15:37:55]: debug: ppp1000: send [LCP EchoReq id=2 <magic 74b0dc51>]
[2018-05-16 15:37:55]: debug: ppp1000: recv [LCP EchoRep id=2 <magic 01d22020>]
[2018-05-16 15:37:55]: debug: ppp1000: recv [LCP EchoReq id=4 <magic 01d22020>]
[2018-05-16 15:37:55]: debug: ppp1000: send [LCP EchoRep id=4 <magic 74b0dc51>]
При повторном (роняем физический интерфейс клиента и поднимаем) разрыв соединения. Тайм-аут PPP 5 сек. Лог:

Code: Select all

[2018-05-16 15:35:26]:  info: pptp: new connection from 20.20.20.2
[2018-05-16 15:35:27]: debug: : lcp_layer_init
[2018-05-16 15:35:27]: debug: : auth_layer_init
[2018-05-16 15:35:27]: debug: : ccp_layer_init
[2018-05-16 15:35:27]: debug: : ipcp_layer_init
[2018-05-16 15:35:27]: debug: : ipv6cp_layer_init
[2018-05-16 15:35:27]: debug: : ppp establishing
[2018-05-16 15:35:27]: debug: : lcp_layer_start
[2018-05-16 15:35:27]:  info: : send [LCP ConfReq id=1 <auth CHAP-md5> <mru 1400> <magic 6b8b4567>]
[2018-05-16 15:35:27]:  info: : recv [LCP ConfReq id=3 < 2 6 0 0 0 0 > <magic 9a9fb899> <pcomp> <accomp>]
[2018-05-16 15:35:27]:  info: : send [LCP ConfRej id=3  < 2 6 0 0 0 0 > <pcomp> <accomp>]
[2018-05-16 15:35:27]:  info: : recv [LCP ConfAck id=1 <auth CHAP-md5> <mru 1400> <magic 6b8b4567>]
[2018-05-16 15:35:27]:  info: : recv [LCP ConfReq id=4 <magic 9a9fb899>]
[2018-05-16 15:35:27]:  info: : send [LCP ConfAck id=4 ]
[2018-05-16 15:35:27]: debug: : lcp_layer_started
[2018-05-16 15:35:27]: debug: : auth_layer_start
[2018-05-16 15:35:27]:  info: : send [CHAP Challenge id=1 <ae739eb566b8de5438f56dfb29d67c8>]
[2018-05-16 15:35:27]: debug: : recv [LCP EchoReq id=0 <magic 9a9fb899>]
[2018-05-16 15:35:27]: debug: : send [LCP EchoRep id=0 <magic 6b8b4567>]
[2018-05-16 15:35:27]:  info: : recv [CHAP Response id=1 <f8246ac8b977221fd344f7638eb8f567>, name="user1"]
[2018-05-16 15:35:27]:  info: ppp1000: connect: ppp1000 <--> pptp(20.20.20.2)
[2018-05-16 15:35:27]: debug: ppp1000: ppp connected
[2018-05-16 15:35:27]:  info: ppp1000: send [CHAP Success id=1 "Authentication succeeded"]
[2018-05-16 15:35:27]: debug: ppp1000: auth_layer_started
[2018-05-16 15:35:27]: debug: ppp1000: ccp_layer_start
[2018-05-16 15:35:27]: debug: ppp1000: ipcp_layer_start
[2018-05-16 15:35:27]:  info: ppp1000: send [IPCP ConfReq id=1 <addr 1.1.1.1>]
[2018-05-16 15:35:27]: debug: ppp1000: ipv6cp_layer_start
[2018-05-16 15:35:27]:  info: ppp1000: user1: authentication succeeded
[2018-05-16 15:35:27]:  info: ppp1000: recv [CCP ConfReq id=3 < 1a 4 78 0 > < 18 4 78 0 > < 15 3 2f >]
[2018-05-16 15:35:27]:  info: ppp1000: send [CCP ConfReq id=1]
[2018-05-16 15:35:27]:  info: ppp1000: send [CCP ConfRej id=3 < 1a 4 78 0 > < 18 4 78 0 > < 15 3 2f >]
[2018-05-16 15:35:27]:  info: ppp1000: recv [IPCP ConfReq id=4 < 2 6 0 2d f 1 > <addr 1.1.1.10>]
[2018-05-16 15:35:27]:  info: ppp1000: send [IPCP ConfRej id=4 < 2 6 0 2d f 1 >]
[2018-05-16 15:35:27]:  info: ppp1000: recv [IPCP ConfAck id=1 <addr 1.1.1.1>]
[2018-05-16 15:35:27]:  info: ppp1000: recv [CCP ConfAck id=1]
[2018-05-16 15:35:27]:  info: ppp1000: recv [CCP ConfReq id=4]
[2018-05-16 15:35:27]:  info: ppp1000: send [CCP ConfAck id=4]
[2018-05-16 15:35:27]: debug: ppp1000: ccp_layer_started
[2018-05-16 15:35:27]:  info: ppp1000: recv [IPCP ConfReq id=5 <addr 1.1.1.10>]
[2018-05-16 15:35:27]: debug: ppp1000: ipcp_layer_started
[2018-05-16 15:35:27]: debug: ppp1000: pptp: ppp started
[2018-05-16 15:35:27]:  info: ppp1000: pppd_compat: ip-up started (pid 3620)
[2018-05-16 15:35:27]:  info: ppp1000: send [IPCP ConfAck id=5]
[2018-05-16 15:35:27]:  info: ppp1000: pppd_compat: ip-up finished (0)
[2018-05-16 15:35:32]: debug: ppp1000: recv [LCP EchoReq id=1 <magic 9a9fb899>]
[2018-05-16 15:35:32]: debug: ppp1000: send [LCP EchoRep id=1 <magic 6b8b4567>]
[2018-05-16 15:35:32]: debug: ppp1000: fsm timeout 9
[2018-05-16 15:35:32]:  info: ppp1000: send [IPCP ConfReq id=2 <addr 1.1.1.1>]
[2018-05-16 15:35:32]:  info: ppp1000: recv [IPCP ConfReq id=6 < 2 6 0 2d f 1 > <addr 1.1.1.10>]
[2018-05-16 15:35:32]: debug: ppp1000: terminate
[2018-05-16 15:35:32]: debug: ppp1000: lcp_layer_finish
[2018-05-16 15:35:32]:  info: ppp1000: send [LCP TermReq id=3]
[2018-05-16 15:35:32]: debug: ppp1000: auth_layer_finish
[2018-05-16 15:35:32]: debug: ppp1000: auth_layer_finished
[2018-05-16 15:35:32]: debug: ppp1000: ccp_layer_finish
[2018-05-16 15:35:32]: debug: ppp1000: ccp_layer_finished
[2018-05-16 15:35:32]: debug: ppp1000: ipcp_layer_finish
[2018-05-16 15:35:32]: debug: ppp1000: ipcp_layer_finished
[2018-05-16 15:35:32]: debug: ppp1000: ipv6cp_layer_finish
[2018-05-16 15:35:32]: debug: ppp1000: ipv6cp_layer_finished
[2018-05-16 15:35:32]:  warn: ppp1000: IPCP: discarding packet
[2018-05-16 15:35:32]:  info: ppp1000: recv [LCP TermAck id=3]
[2018-05-16 15:35:32]: debug: ppp1000: lcp_layer_finished
[2018-05-16 15:35:32]:  info: ppp1000: pppd_compat: ip-down started (pid 3640)
[2018-05-16 15:35:32]:  info: ppp1000: pppd_compat: ip-down finished (1)
[2018-05-16 15:35:32]: debug: ppp1000: pptp: ppp finished
[2018-05-16 15:35:32]: debug: ppp1000: lcp_layer_free
[2018-05-16 15:35:32]: debug: ppp1000: auth_layer_free
[2018-05-16 15:35:32]: debug: ppp1000: ccp_layer_free
[2018-05-16 15:35:32]: debug: ppp1000: ipcp_layer_free
[2018-05-16 15:35:32]: debug: ppp1000: ipv6cp_layer_free
[2018-05-16 15:35:32]: debug: ppp1000: ppp destablished
[2018-05-16 15:35:32]: debug: ppp1000: pptp: disconnect
[2018-05-16 15:35:32]:  info: ppp1000: disconnected
Post Reply