Не восстанавливается PPTP соединение

Обсуждение ПО и его настройки
seregaelcin
Сообщения: 176
Зарегистрирован: 27 фев 2016, 17:12

параметр keepalive на интерфейсе какой выставлен ?
PPPoE 30 сек
PPTP 60 сек

PPPoE раньше понимает что с ним проблемы


Обладатель Mikrotik RB2011UAS-2HnD-IN
bnsott
Сообщения: 18
Зарегистрирован: 28 фев 2019, 19:01

Проверил вчера, если вручную выключить и включить PPPoE соединение с Ростелеком, то PPTP соединение с VPN может само восстановиться, а может не восстановиться.
Если не восстанавливается, то не помогает ничего: ни выключение включение PPTP соединения, ни выключение включение соединения с Ростелеком.
После перезагрузки роутера всё работает.
Vlad-2 писал(а): 02 мар 2019, 14:22 3) Мне кажется надо более детально или "тонко" настроить РРТР, может наоборот,
именно РРТР сделать keepalive меньше, скажем сек 10, чтобы он быстрее понимал,
что сессии нету, надо перестартовывать?!...
Не знаю, тестировать и ещё раз тестировать.
keepalive пробовал поставить 10 секунд, всё также
seregaelcin писал(а): 03 мар 2019, 13:07 У ростелекома если была поднята старая сессия, новая не обрывает старую, в результате возникает ошибка 691, пока брас не грохнет сессию
У эртелекома новая сессия грохает старую. Это легко проверить авторизовавшись с 2х одинаковых учеток - при авторизации от браса прилетит терминейшн реквест на вторую авторизованную учетку
В этом может быть причина?



Еще странно то, что у меня такой же роутер, точно так же настроен, подключается к тому же VPN через PPTP и всё работает без проблем. Единственное отличие в том, что у меня Билайн через L2TP, а не Ростелеком.


hAP ac²
Аватара пользователя
Vlad-2
Модератор
Сообщения: 2531
Зарегистрирован: 08 апр 2016, 19:19
Откуда: Петропавловск-Камчатский (п-ов Камчатка)
Контактная информация:

1) так как РРРоЕ восстанавливается, и делается это самим роутером и оперативно, то не в этом причина,
и не важны уже другие тонкости, связь с провайдером после разрыва/дисконнекта поднимается.
Это норма работы сеансовых протоколов. Интервал тут считаю должен быть не большим, чтобы если нет связи,
чтобы сам роутер мог это понять и сессию "зависшую" сам убить, и заново сделать переконнект.

2) Не важно если у провайдера старая или новая сессия, для нас (в данной ситуации это
не важно), ТС сказал что связь с провайдером восстанавливается сама.
Значит тут дело чисто логическое, я так подумал, может в настройках РРТР используется
для подключение доменное имя, если адрес ВПН один, постоянный, не проще его сразу
прописать, обычно у меня так и сделано, поэтому возможно у меня таких проблем не было
и ранее и сейчас.

Возможно когда падает связь с провайдером, и даже когда она восстанавливается, не сразу
роутеру (профилю РРТР) в этот момент удаётся сделать резолв имени. Поэтому надо в профиле
подключение к ВПНу по РРТР задать адрес подключения по IP-адресу
(если конечно это возможно, удалённый сервер не имеет динамическую адресацию и так далее).

2.1) в настройках микротика, в настройках DNS - убрать большое значение TTL, вроде
там стоит 7 дней, уменьшить в разы, на одном объекте ДНС-микротика слишком долго
кешировал многие динамические записи, это в данном случаи не очень полезно было.
Поэтому если ДНС-кеширование не нужно, мало используется, попробуйте поставить
2-3 часа (НО это тонкая настройка, будьте аккуратны).



На работе(ах): 2xCCR1016-12G, RB3011UiAS и hAP lite (RB941)
Дома: CCR1016-12G, RBcAP2n (standalone), RB wAP LTE kit
Для тестов(под рукой): RB3011UiAS, hAP mini (RB931) и что-то ещё по мелочи
MTCNA
MTCRE
bnsott
Сообщения: 18
Зарегистрирован: 28 фев 2019, 19:01

Vlad-2 писал(а): 03 мар 2019, 17:35 1) так как РРРоЕ восстанавливается, и делается это самим роутером и оперативно, то не в этом причина,
и не важны уже другие тонкости, связь с провайдером после разрыва/дисконнекта поднимается.
Это норма работы сеансовых протоколов. Интервал тут считаю должен быть не большим, чтобы если нет связи,
чтобы сам роутер мог это понять и сессию "зависшую" сам убить, и заново сделать переконнект.

2) Не важно если у провайдера старая или новая сессия, для нас (в данной ситуации это
не важно), ТС сказал что связь с провайдером восстанавливается сама.
Значит тут дело чисто логическое, я так подумал, может в настройках РРТР используется
для подключение доменное имя, если адрес ВПН один, постоянный, не проще его сразу
прописать, обычно у меня так и сделано, поэтому возможно у меня таких проблем не было
и ранее и сейчас.

Возможно когда падает связь с провайдером, и даже когда она восстанавливается, не сразу
роутеру (профилю РРТР) в этот момент удаётся сделать резолв имени. Поэтому надо в профиле
подключение к ВПНу по РРТР задать адрес подключения по IP-адресу
(если конечно это возможно, удалённый сервер не имеет динамическую адресацию и так далее).

2.1) в настройках микротика, в настройках DNS - убрать большое значение TTL, вроде
там стоит 7 дней, уменьшить в разы, на одном объекте ДНС-микротика слишком долго
кешировал многие динамические записи, это в данном случаи не очень полезно было.
Поэтому если ДНС-кеширование не нужно, мало используется, попробуйте поставить
2-3 часа (НО это тонкая настройка, будьте аккуратны).
В подключении к VPN уже задан IP-адрес. Значит TTL не имеет смысла изменять?


hAP ac²
Аватара пользователя
Vlad-2
Модератор
Сообщения: 2531
Зарегистрирован: 08 апр 2016, 19:19
Откуда: Петропавловск-Камчатский (п-ов Камчатка)
Контактная информация:

bnsott писал(а): 03 мар 2019, 18:13 В подключении к VPN уже задан IP-адрес. Значит TTL не имеет смысла изменять?
(рассуждения)
Ну 7 дней кеширования в наш мир динамического Интернета, когда
всё меняется = много. Я оставляю 2-3 дня, а где сильно критично,
и/или есть внутренний ДНС-сервер, то оставляю 2-4 часа, для кеширования
хватает более чем. Да и для туннелей с вызовом DynDNS - долго кешировать,
как я уже говорил = плохо.

Опять же, если отталкиваться от параметров таблицы Connections - то там
таймаут по TCP сессии = сутки (24 часа). Так что больше 1-2 дней бесполезно
хранить даже.

P.S.
Кстати, а чё мы тормозим, включите дебаг для РРТР и всё, смотрите что
происходит.....

Keepalive я бы для РРРоЕ сделал бы 11-13 сек, а для РРТР - 25 (на время хотя бы)
(специально не ровно, чтобы цифры не были относительно друг друга кратными)

P.P.S.
Вопрос покажется странным, но у Вас как сделан НАТ, то есть сделан:
а) через правило MASQUARED
б) через правило SRC-NAT
???



На работе(ах): 2xCCR1016-12G, RB3011UiAS и hAP lite (RB941)
Дома: CCR1016-12G, RBcAP2n (standalone), RB wAP LTE kit
Для тестов(под рукой): RB3011UiAS, hAP mini (RB931) и что-то ещё по мелочи
MTCNA
MTCRE
bnsott
Сообщения: 18
Зарегистрирован: 28 фев 2019, 19:01

Vlad-2 писал(а): 03 мар 2019, 18:26 Кстати, а чё мы тормозим, включите дебаг для РРТР и всё, смотрите что
происходит.....
если для сохранения в файл, то так?
/system logging add topics=pptp,debug action=disk
Вопрос покажется странным, но у Вас как сделан НАТ, то есть сделан:
а) через правило MASQUARED
б) через правило SRC-NAT
???
это имеется в виду?
/ip firewall nat add chain=srcnat out-interface=VPN action=masquerade


hAP ac²
Аватара пользователя
Vlad-2
Модератор
Сообщения: 2531
Зарегистрирован: 08 апр 2016, 19:19
Откуда: Петропавловск-Камчатский (п-ов Камчатка)
Контактная информация:

bnsott писал(а): 03 мар 2019, 19:34 если для сохранения в файл, то так?
/system logging add topics=pptp,debug action=disk
Можно просто включить, необязательно с сохранением.
И уже смотреть логи и передёрнуть кабель провайдерский
и понаблюдать....



На работе(ах): 2xCCR1016-12G, RB3011UiAS и hAP lite (RB941)
Дома: CCR1016-12G, RBcAP2n (standalone), RB wAP LTE kit
Для тестов(под рукой): RB3011UiAS, hAP mini (RB931) и что-то ещё по мелочи
MTCNA
MTCRE
bnsott
Сообщения: 18
Зарегистрирован: 28 фев 2019, 19:01

Оказывается дело вовсе не в PPTP соединении.
Если вручную отключить и сразу включить соединение с ростелеком, статус соединения отображается как "connected", но на самом деле интернета нет.
Видимо также происходит когда ростелеком сам рвет сессию.
Если же отключить и подождать перед включением, то подключится нормально. Странно то, что иногда хватает подождать и 10 секунд, иногда и через 30 не подключается, иногда подключается даже если вообще не ждать.
Перезагрузка наверно поэтому и помогала, что роутер перезагружается около минуты.

Дебаг логи в этот момент:
 
Mar/08/2019 16:40:05 pppoe,debug,packet ether1: sent PADI to FF:FF:FF:FF:FF:FF
Mar/08/2019 16:40:05 pppoe,debug,packet session-id=0x0000
Mar/08/2019 16:40:05 pppoe,debug,packet host-uniq=0x500b600
Mar/08/2019 16:40:05 pppoe,debug,packet service-name=
Mar/08/2019 16:40:05 pppoe,debug,packet ether1: rcvd PADO from 44:6A:2E:48:E9:9D
Mar/08/2019 16:40:05 pppoe,debug,packet session-id=0x0000
Mar/08/2019 16:40:05 pppoe,debug,packet host-uniq=0x500b600
Mar/08/2019 16:40:05 pppoe,debug,packet service-name=
Mar/08/2019 16:40:05 pppoe,debug,packet ac-name=BRAS
Mar/08/2019 16:40:05 pppoe,debug,packet ether1: sent PADR to 44:6A:2E:48:E9:9D
Mar/08/2019 16:40:05 pppoe,debug,packet session-id=0x0000
Mar/08/2019 16:40:05 pppoe,debug,packet host-uniq=0x600b600
Mar/08/2019 16:40:05 pppoe,debug,packet service-name=
Mar/08/2019 16:40:05 pppoe,debug,packet ether1: rcvd PADS from 44:6A:2E:48:E9:9D
Mar/08/2019 16:40:05 pppoe,debug,packet session-id=0x7f8b
Mar/08/2019 16:40:05 pppoe,debug,packet host-uniq=0x600b600
Mar/08/2019 16:40:05 pppoe,debug,packet service-name=
Mar/08/2019 16:40:05 pppoe,ppp,debug Rostelecom: LCP lowerup
Mar/08/2019 16:40:05 pppoe,ppp,debug Rostelecom: LCP open
Mar/08/2019 16:40:05 pppoe,ppp,debug Rostelecom: LCP timer
Mar/08/2019 16:40:05 pppoe,ppp,debug,packet Rostelecom: sent LCP ConfReq id=0x3
Mar/08/2019 16:40:05 pppoe,ppp,debug,packet <mru 1480>
Mar/08/2019 16:40:05 pppoe,ppp,debug,packet <magic 0x42f7f74d>
Mar/08/2019 16:40:05 pppoe,ppp,debug,packet Rostelecom: rcvd LCP ConfReq id=0x2
Mar/08/2019 16:40:05 pppoe,ppp,debug,packet <mru 1492>
Mar/08/2019 16:40:05 pppoe,ppp,debug,packet <magic 0xb9c6ac05>
Mar/08/2019 16:40:05 pppoe,ppp,debug,packet <auth pap>
Mar/08/2019 16:40:05 pppoe,ppp,debug,packet Rostelecom: sent LCP ConfAck id=0x2
Mar/08/2019 16:40:05 pppoe,ppp,debug,packet <mru 1492>
Mar/08/2019 16:40:05 pppoe,ppp,debug,packet <magic 0xb9c6ac05>
Mar/08/2019 16:40:05 pppoe,ppp,debug,packet <auth pap>
Mar/08/2019 16:40:05 pppoe,ppp,debug,packet Rostelecom: rcvd LCP ConfAck id=0x3
Mar/08/2019 16:40:05 pppoe,ppp,debug,packet <mru 1480>
Mar/08/2019 16:40:05 pppoe,ppp,debug,packet <magic 0x42f7f74d>
Mar/08/2019 16:40:05 pppoe,ppp,debug Rostelecom: LCP opened
Mar/08/2019 16:40:05 pppoe,ppp,debug,packet Rostelecom: sent PAP AuthReq id=0x2
Mar/08/2019 16:40:05 pppoe,ppp,debug,packet <user szt>
Mar/08/2019 16:40:05 pppoe,ppp,debug,packet <password szt>
Mar/08/2019 16:40:05 pppoe,ppp,debug,packet Rostelecom: rcvd PAP AuthAck id=0x2
Mar/08/2019 16:40:05 pppoe,ppp,debug,packet Authentication success,Welcome!
Mar/08/2019 16:40:05 pppoe,ppp,debug Rostelecom: IPCP lowerup
Mar/08/2019 16:40:05 pppoe,ppp,debug Rostelecom: IPCP open
Mar/08/2019 16:40:05 pppoe,ppp,debug,packet Rostelecom: sent IPCP ConfReq id=0x3
Mar/08/2019 16:40:05 pppoe,ppp,debug,packet <addr 0.0.0.0>
Mar/08/2019 16:40:05 pppoe,ppp,debug Rostelecom: IPV6CP open
Mar/08/2019 16:40:05 pppoe,ppp,debug Rostelecom: MPLSCP lowerup
Mar/08/2019 16:40:05 pppoe,ppp,debug Rostelecom: MPLSCP open
Mar/08/2019 16:40:05 pppoe,ppp,debug,packet Rostelecom: sent MPLSCP ConfReq id=0x2
Mar/08/2019 16:40:05 pppoe,ppp,debug Rostelecom: BCP open
Mar/08/2019 16:40:05 pppoe,ppp,debug Rostelecom: CCP lowerup
Mar/08/2019 16:40:05 pppoe,ppp,debug Rostelecom: CCP open
Mar/08/2019 16:40:05 pppoe,ppp,debug,packet Rostelecom: rcvd IPCP ConfReq id=0x1
Mar/08/2019 16:40:05 pppoe,ppp,debug,packet <addr 212.48.195.118>
Mar/08/2019 16:40:05 pppoe,ppp,debug,packet Rostelecom: sent IPCP ConfAck id=0x1
Mar/08/2019 16:40:05 pppoe,ppp,debug,packet <addr 212.48.195.118>
Mar/08/2019 16:40:05 pppoe,ppp,debug,packet Rostelecom: rcvd IPCP ConfNak id=0x3
Mar/08/2019 16:40:05 pppoe,ppp,debug,packet <addr 178.64.133.19>
Mar/08/2019 16:40:05 pppoe,ppp,debug,packet Rostelecom: sent IPCP ConfReq id=0x4
Mar/08/2019 16:40:05 pppoe,ppp,debug,packet <addr 178.64.133.19>
Mar/08/2019 16:40:05 pppoe,ppp,debug,packet Rostelecom: rcvd LCP ProtRej id=0x3
Mar/08/2019 16:40:05 pppoe,ppp,debug,packet 82 81
Mar/08/2019 16:40:05 pppoe,ppp,debug,packet Rostelecom: rcvd IPCP ConfAck id=0x4
Mar/08/2019 16:40:05 pppoe,ppp,debug,packet <addr 178.64.133.19>
Mar/08/2019 16:40:05 pppoe,ppp,debug Rostelecom: IPCP opened
Mar/08/2019 16:40:13 pppoe,ppp,debug Rostelecom: LCP close
Mar/08/2019 16:40:13 pppoe,ppp,debug Rostelecom: LCP closed
Mar/08/2019 16:40:13 pppoe,ppp,debug Rostelecom: CCP lowerdown
Mar/08/2019 16:40:13 pppoe,ppp,debug Rostelecom: BCP lowerdown
Mar/08/2019 16:40:13 pppoe,ppp,debug Rostelecom: BCP down event in starting state
Mar/08/2019 16:40:13 pppoe,ppp,debug Rostelecom: IPCP lowerdown
Mar/08/2019 16:40:13 pppoe,ppp,debug Rostelecom: IPCP closed
Mar/08/2019 16:40:13 pppoe,ppp,debug Rostelecom: IPV6CP lowerdown
Mar/08/2019 16:40:13 pppoe,ppp,debug Rostelecom: IPV6CP down event in starting state
Mar/08/2019 16:40:13 pppoe,ppp,debug Rostelecom: MPLSCP lowerdown
Mar/08/2019 16:40:13 pppoe,ppp,debug,packet Rostelecom: sent LCP TermReq id=0x4
Mar/08/2019 16:40:13 pppoe,ppp,debug,packet administrator request
Mar/08/2019 16:40:13 pppoe,ppp,debug,packet Rostelecom: rcvd LCP TermAck id=0x4
Mar/08/2019 16:40:13 pppoe,ppp,debug Rostelecom: LCP lowerdown
Mar/08/2019 16:40:13 pppoe,ppp,debug Rostelecom: CCP close
Mar/08/2019 16:40:13 pppoe,ppp,debug Rostelecom: BCP close
Mar/08/2019 16:40:13 pppoe,ppp,debug Rostelecom: IPCP close
Mar/08/2019 16:40:13 pppoe,ppp,debug Rostelecom: IPV6CP close
Mar/08/2019 16:40:13 pppoe,ppp,debug Rostelecom: MPLSCP close
Mar/08/2019 16:40:13 pppoe,debug,packet ether1: sent PADT to 44:6A:2E:48:E9:9D
Mar/08/2019 16:40:13 pppoe,debug,packet session-id=0x7f8b
Mar/08/2019 16:40:13 pppoe,ppp,debug Rostelecom: LCP lowerdown
Mar/08/2019 16:40:13 pppoe,ppp,debug Rostelecom: LCP down event in initial state
Mar/08/2019 16:40:13 pppoe,debug,packet ether1: rcvd PADT from 44:6A:2E:48:E9:9D
Mar/08/2019 16:40:13 pppoe,debug,packet session-id=0x7f8b
Mar/08/2019 16:40:17 pppoe,debug,packet ether1: sent PADI to FF:FF:FF:FF:FF:FF
Mar/08/2019 16:40:17 pppoe,debug,packet session-id=0x0000
Mar/08/2019 16:40:17 pppoe,debug,packet host-uniq=0x700b600
Mar/08/2019 16:40:17 pppoe,debug,packet service-name=
Mar/08/2019 16:40:17 pppoe,debug,packet ether1: rcvd PADO from 44:6A:2E:48:E9:9D
Mar/08/2019 16:40:17 pppoe,debug,packet session-id=0x0000
Mar/08/2019 16:40:17 pppoe,debug,packet host-uniq=0x700b600
Mar/08/2019 16:40:17 pppoe,debug,packet service-name=
Mar/08/2019 16:40:17 pppoe,debug,packet ac-name=BRAS
Mar/08/2019 16:40:17 pppoe,debug,packet ether1: sent PADR to 44:6A:2E:48:E9:9D
Mar/08/2019 16:40:17 pppoe,debug,packet session-id=0x0000
Mar/08/2019 16:40:17 pppoe,debug,packet host-uniq=0x800b600
Mar/08/2019 16:40:17 pppoe,debug,packet service-name=
Mar/08/2019 16:40:17 pppoe,debug,packet ether1: rcvd PADS from 44:6A:2E:48:E9:9D
Mar/08/2019 16:40:17 pppoe,debug,packet session-id=0xc667
Mar/08/2019 16:40:17 pppoe,debug,packet host-uniq=0x800b600
Mar/08/2019 16:40:17 pppoe,debug,packet service-name=
Mar/08/2019 16:40:17 pppoe,ppp,debug Rostelecom: LCP lowerup
Mar/08/2019 16:40:17 pppoe,ppp,debug Rostelecom: LCP open
Mar/08/2019 16:40:17 pppoe,ppp,debug Rostelecom: LCP timer
Mar/08/2019 16:40:17 pppoe,ppp,debug,packet Rostelecom: sent LCP ConfReq id=0x5
Mar/08/2019 16:40:17 pppoe,ppp,debug,packet <mru 1480>
Mar/08/2019 16:40:17 pppoe,ppp,debug,packet <magic 0x26ea6835>
Mar/08/2019 16:40:17 pppoe,ppp,debug,packet Rostelecom: rcvd LCP ConfReq id=0x2
Mar/08/2019 16:40:17 pppoe,ppp,debug,packet <mru 1492>
Mar/08/2019 16:40:17 pppoe,ppp,debug,packet <magic 0xc3aaac05>
Mar/08/2019 16:40:17 pppoe,ppp,debug,packet <auth pap>
Mar/08/2019 16:40:17 pppoe,ppp,debug,packet Rostelecom: sent LCP ConfAck id=0x2
Mar/08/2019 16:40:17 pppoe,ppp,debug,packet <mru 1492>
Mar/08/2019 16:40:17 pppoe,ppp,debug,packet <magic 0xc3aaac05>
Mar/08/2019 16:40:17 pppoe,ppp,debug,packet <auth pap>
Mar/08/2019 16:40:17 pppoe,ppp,debug,packet Rostelecom: rcvd LCP ConfAck id=0x5
Mar/08/2019 16:40:17 pppoe,ppp,debug,packet <mru 1480>
Mar/08/2019 16:40:17 pppoe,ppp,debug,packet <magic 0x26ea6835>
Mar/08/2019 16:40:17 pppoe,ppp,debug Rostelecom: LCP opened
Mar/08/2019 16:40:17 pppoe,ppp,debug,packet Rostelecom: sent PAP AuthReq id=0x3
Mar/08/2019 16:40:17 pppoe,ppp,debug,packet <user szt>
Mar/08/2019 16:40:17 pppoe,ppp,debug,packet <password szt>
Mar/08/2019 16:40:17 pppoe,ppp,debug,packet Rostelecom: rcvd PAP AuthAck id=0x3
Mar/08/2019 16:40:17 pppoe,ppp,debug,packet Authentication success,Welcome!
Mar/08/2019 16:40:17 pppoe,ppp,debug Rostelecom: IPCP lowerup
Mar/08/2019 16:40:17 pppoe,ppp,debug Rostelecom: IPCP open
Mar/08/2019 16:40:17 pppoe,ppp,debug,packet Rostelecom: sent IPCP ConfReq id=0x5
Mar/08/2019 16:40:17 pppoe,ppp,debug,packet <addr 0.0.0.0>
Mar/08/2019 16:40:17 pppoe,ppp,debug Rostelecom: IPV6CP open
Mar/08/2019 16:40:17 pppoe,ppp,debug Rostelecom: MPLSCP lowerup
Mar/08/2019 16:40:17 pppoe,ppp,debug Rostelecom: MPLSCP open
Mar/08/2019 16:40:17 pppoe,ppp,debug,packet Rostelecom: sent MPLSCP ConfReq id=0x3
Mar/08/2019 16:40:17 pppoe,ppp,debug Rostelecom: BCP open
Mar/08/2019 16:40:17 pppoe,ppp,debug Rostelecom: CCP lowerup
Mar/08/2019 16:40:17 pppoe,ppp,debug Rostelecom: CCP open
Mar/08/2019 16:40:17 pppoe,ppp,debug,packet Rostelecom: rcvd IPCP ConfReq id=0x1
Mar/08/2019 16:40:17 pppoe,ppp,debug,packet <addr 212.48.195.118>
Mar/08/2019 16:40:17 pppoe,ppp,debug,packet Rostelecom: sent IPCP ConfAck id=0x1
Mar/08/2019 16:40:17 pppoe,ppp,debug,packet <addr 212.48.195.118>
Mar/08/2019 16:40:17 pppoe,ppp,debug,packet Rostelecom: rcvd IPCP ConfNak id=0x5
Mar/08/2019 16:40:17 pppoe,ppp,debug,packet <addr 178.64.146.229>
Mar/08/2019 16:40:17 pppoe,ppp,debug,packet Rostelecom: sent IPCP ConfReq id=0x6
Mar/08/2019 16:40:17 pppoe,ppp,debug,packet <addr 178.64.146.229>
Mar/08/2019 16:40:17 pppoe,ppp,debug,packet Rostelecom: rcvd LCP ProtRej id=0x3
Mar/08/2019 16:40:17 pppoe,ppp,debug,packet 82 81
Mar/08/2019 16:40:17 pppoe,ppp,debug,packet Rostelecom: rcvd IPCP ConfAck id=0x6
Mar/08/2019 16:40:17 pppoe,ppp,debug,packet <addr 178.64.146.229>
Mar/08/2019 16:40:17 pppoe,ppp,debug Rostelecom: IPCP opened
Mar/08/2019 16:40:47 pppoe,ppp,debug,packet Rostelecom: rcvd LCP EchoReq id=0x1
Mar/08/2019 16:40:47 pppoe,ppp,debug,packet <magic 0xc3aaac05>
Mar/08/2019 16:40:47 pppoe,ppp,debug,packet Rostelecom: sent LCP EchoRep id=0x1
Mar/08/2019 16:40:47 pppoe,ppp,debug,packet <magic 0x26ea6835>
Mar/08/2019 16:41:16 pppoe,ppp,debug,packet Rostelecom: rcvd LCP EchoReq id=0x2
Mar/08/2019 16:41:16 pppoe,ppp,debug,packet <magic 0xc3aaac05>
Mar/08/2019 16:41:16 pppoe,ppp,debug,packet Rostelecom: sent LCP EchoRep id=0x2
Mar/08/2019 16:41:16 pppoe,ppp,debug,packet <magic 0x26ea6835>
Mar/08/2019 16:41:45 pppoe,ppp,debug,packet Rostelecom: rcvd LCP EchoReq id=0x3
Mar/08/2019 16:41:45 pppoe,ppp,debug,packet <magic 0xc3aaac05>
Mar/08/2019 16:41:45 pppoe,ppp,debug,packet Rostelecom: sent LCP EchoRep id=0x3
Mar/08/2019 16:41:45 pppoe,ppp,debug,packet <magic 0x26ea6835>
Mar/08/2019 16:42:13 pppoe,ppp,debug Rostelecom: LCP close
Mar/08/2019 16:42:13 pppoe,ppp,debug Rostelecom: LCP closed
Mar/08/2019 16:42:13 pppoe,ppp,debug Rostelecom: CCP lowerdown
Mar/08/2019 16:42:13 pppoe,ppp,debug Rostelecom: BCP lowerdown
Mar/08/2019 16:42:13 pppoe,ppp,debug Rostelecom: BCP down event in starting state
Mar/08/2019 16:42:13 pppoe,ppp,debug Rostelecom: IPCP lowerdown
Mar/08/2019 16:42:13 pppoe,ppp,debug Rostelecom: IPCP closed
Mar/08/2019 16:42:13 pppoe,ppp,debug Rostelecom: IPV6CP lowerdown
Mar/08/2019 16:42:13 pppoe,ppp,debug Rostelecom: IPV6CP down event in starting state
Mar/08/2019 16:42:13 pppoe,ppp,debug Rostelecom: MPLSCP lowerdown
Mar/08/2019 16:42:13 pppoe,ppp,debug,packet Rostelecom: sent LCP TermReq id=0x6
Mar/08/2019 16:42:13 pppoe,ppp,debug,packet administrator request
Mar/08/2019 16:42:13 pppoe,ppp,debug,packet Rostelecom: rcvd LCP TermAck id=0x6
Mar/08/2019 16:42:13 pppoe,ppp,debug Rostelecom: LCP lowerdown
Mar/08/2019 16:42:13 pppoe,ppp,debug Rostelecom: CCP close
Mar/08/2019 16:42:13 pppoe,ppp,debug Rostelecom: BCP close
Mar/08/2019 16:42:13 pppoe,ppp,debug Rostelecom: IPCP close
Mar/08/2019 16:42:13 pppoe,ppp,debug Rostelecom: IPV6CP close
Mar/08/2019 16:42:13 pppoe,ppp,debug Rostelecom: MPLSCP close
Mar/08/2019 16:42:13 pppoe,debug,packet ether1: sent PADT to 44:6A:2E:48:E9:9D
Mar/08/2019 16:42:13 pppoe,debug,packet session-id=0xc667
Mar/08/2019 16:42:13 pppoe,ppp,debug Rostelecom: LCP lowerdown
Mar/08/2019 16:42:13 pppoe,ppp,debug Rostelecom: LCP down event in initial state
Mar/08/2019 16:42:13 pppoe,debug,packet ether1: rcvd PADT from 44:6A:2E:48:E9:9D
Mar/08/2019 16:42:13 pppoe,debug,packet session-id=0xc667
Mar/08/2019 16:42:15 pppoe,debug,packet ether1: sent PADI to FF:FF:FF:FF:FF:FF
Mar/08/2019 16:42:15 pppoe,debug,packet session-id=0x0000
Mar/08/2019 16:42:15 pppoe,debug,packet host-uniq=0x900b600
Mar/08/2019 16:42:15 pppoe,debug,packet service-name=
Mar/08/2019 16:42:15 pppoe,debug,packet ether1: rcvd PADO from 44:6A:2E:48:E9:9D
Mar/08/2019 16:42:15 pppoe,debug,packet session-id=0x0000
Mar/08/2019 16:42:15 pppoe,debug,packet host-uniq=0x900b600
Mar/08/2019 16:42:15 pppoe,debug,packet service-name=
Mar/08/2019 16:42:15 pppoe,debug,packet ac-name=BRAS
Mar/08/2019 16:42:15 pppoe,debug,packet ether1: sent PADR to 44:6A:2E:48:E9:9D
Mar/08/2019 16:42:15 pppoe,debug,packet session-id=0x0000
Mar/08/2019 16:42:15 pppoe,debug,packet host-uniq=0xa00b600
Mar/08/2019 16:42:15 pppoe,debug,packet service-name=
Mar/08/2019 16:42:15 pppoe,debug,packet ether1: rcvd PADS from 44:6A:2E:48:E9:9D
Mar/08/2019 16:42:15 pppoe,debug,packet session-id=0x2183
Mar/08/2019 16:42:15 pppoe,debug,packet host-uniq=0xa00b600
Mar/08/2019 16:42:15 pppoe,debug,packet service-name=
Mar/08/2019 16:42:15 pppoe,ppp,debug Rostelecom: LCP lowerup
Mar/08/2019 16:42:15 pppoe,ppp,debug Rostelecom: LCP open
Mar/08/2019 16:42:15 pppoe,ppp,debug Rostelecom: LCP timer
Mar/08/2019 16:42:15 pppoe,ppp,debug,packet Rostelecom: sent LCP ConfReq id=0x7
Mar/08/2019 16:42:15 pppoe,ppp,debug,packet <mru 1480>
Mar/08/2019 16:42:15 pppoe,ppp,debug,packet <magic 0x5ba67554>
Mar/08/2019 16:42:15 pppoe,ppp,debug,packet Rostelecom: rcvd LCP ConfReq id=0x2
Mar/08/2019 16:42:15 pppoe,ppp,debug,packet <mru 1492>
Mar/08/2019 16:42:15 pppoe,ppp,debug,packet <magic 0xffc4ac05>
Mar/08/2019 16:42:15 pppoe,ppp,debug,packet <auth pap>
Mar/08/2019 16:42:15 pppoe,ppp,debug,packet Rostelecom: sent LCP ConfAck id=0x2
Mar/08/2019 16:42:15 pppoe,ppp,debug,packet <mru 1492>
Mar/08/2019 16:42:15 pppoe,ppp,debug,packet <magic 0xffc4ac05>
Mar/08/2019 16:42:15 pppoe,ppp,debug,packet <auth pap>
Mar/08/2019 16:42:15 pppoe,ppp,debug,packet Rostelecom: rcvd LCP ConfAck id=0x7
Mar/08/2019 16:42:15 pppoe,ppp,debug,packet <mru 1480>
Mar/08/2019 16:42:15 pppoe,ppp,debug,packet <magic 0x5ba67554>
Mar/08/2019 16:42:15 pppoe,ppp,debug Rostelecom: LCP opened
Mar/08/2019 16:42:15 pppoe,ppp,debug,packet Rostelecom: sent PAP AuthReq id=0x4
Mar/08/2019 16:42:15 pppoe,ppp,debug,packet <user szt>
Mar/08/2019 16:42:15 pppoe,ppp,debug,packet <password szt>
Mar/08/2019 16:42:15 pppoe,ppp,debug,packet Rostelecom: rcvd PAP AuthAck id=0x4
Mar/08/2019 16:42:15 pppoe,ppp,debug,packet Authentication success,Welcome!
Mar/08/2019 16:42:15 pppoe,ppp,debug Rostelecom: IPCP lowerup
Mar/08/2019 16:42:15 pppoe,ppp,debug Rostelecom: IPCP open
Mar/08/2019 16:42:15 pppoe,ppp,debug,packet Rostelecom: sent IPCP ConfReq id=0x7
Mar/08/2019 16:42:15 pppoe,ppp,debug,packet <addr 0.0.0.0>
Mar/08/2019 16:42:15 pppoe,ppp,debug Rostelecom: IPV6CP open
Mar/08/2019 16:42:15 pppoe,ppp,debug Rostelecom: MPLSCP lowerup
Mar/08/2019 16:42:15 pppoe,ppp,debug Rostelecom: MPLSCP open
Mar/08/2019 16:42:15 pppoe,ppp,debug,packet Rostelecom: sent MPLSCP ConfReq id=0x4
Mar/08/2019 16:42:15 pppoe,ppp,debug Rostelecom: BCP open
Mar/08/2019 16:42:15 pppoe,ppp,debug Rostelecom: CCP lowerup
Mar/08/2019 16:42:15 pppoe,ppp,debug Rostelecom: CCP open
Mar/08/2019 16:42:15 pppoe,ppp,debug,packet Rostelecom: rcvd IPCP ConfReq id=0x1
Mar/08/2019 16:42:15 pppoe,ppp,debug,packet <addr 212.48.195.118>
Mar/08/2019 16:42:15 pppoe,ppp,debug,packet Rostelecom: sent IPCP ConfAck id=0x1
Mar/08/2019 16:42:15 pppoe,ppp,debug,packet <addr 212.48.195.118>
Mar/08/2019 16:42:15 pppoe,ppp,debug,packet Rostelecom: rcvd IPCP ConfNak id=0x7
Mar/08/2019 16:42:15 pppoe,ppp,debug,packet <addr 178.64.239.199>
Mar/08/2019 16:42:15 pppoe,ppp,debug,packet Rostelecom: sent IPCP ConfReq id=0x8
Mar/08/2019 16:42:15 pppoe,ppp,debug,packet <addr 178.64.239.199>
Mar/08/2019 16:42:15 pppoe,ppp,debug,packet Rostelecom: rcvd LCP ProtRej id=0x3
Mar/08/2019 16:42:15 pppoe,ppp,debug,packet 82 81
Mar/08/2019 16:42:15 pppoe,ppp,debug,packet Rostelecom: rcvd IPCP ConfAck id=0x8
Mar/08/2019 16:42:15 pppoe,ppp,debug,packet <addr 178.64.239.199>
Mar/08/2019 16:42:15 pppoe,ppp,debug Rostelecom: IPCP opened


hAP ac²
seregaelcin
Сообщения: 176
Зарегистрирован: 27 фев 2016, 17:12

Mar/08/2019 16:40:47 pppoe,ppp,debug,packet Rostelecom: rcvd LCP EchoReq id=0x1
Mar/08/2019 16:40:47 pppoe,ppp,debug,packet <magic 0xc3aaac05>
Mar/08/2019 16:40:47 pppoe,ppp,debug,packet Rostelecom: sent LCP EchoRep id=0x1
Mar/08/2019 16:40:47 pppoe,ppp,debug,packet <magic 0x26ea6835>
Mar/08/2019 16:41:16 pppoe,ppp,debug,packet Rostelecom: rcvd LCP EchoReq id=0x2
Mar/08/2019 16:41:16 pppoe,ppp,debug,packet <magic 0xc3aaac05>
Mar/08/2019 16:41:16 pppoe,ppp,debug,packet Rostelecom: sent LCP EchoRep id=0x2
Mar/08/2019 16:41:16 pppoe,ppp,debug,packet <magic 0x26ea6835>
Mar/08/2019 16:41:45 pppoe,ppp,debug,packet Rostelecom: rcvd LCP EchoReq id=0x3
Mar/08/2019 16:41:45 pppoe,ppp,debug,packet <magic 0xc3aaac05>
Mar/08/2019 16:41:45 pppoe,ppp,debug,packet Rostelecom: sent LCP EchoRep id=0x3
Mar/08/2019 16:41:45 pppoe,ppp,debug,packet <magic 0x26ea6835>

Не дошел rcvd LCP EchoReq от браса, микротик посчитал сессию мертвой после 30 секунд
Либо до браса не дошел sent LCP EchoRep

Такое ощущение что пакеты дропаются
Вот пример моего дебага

Mar/11/2019 14:55:13 pppoe,ppp,debug pppoe-out1: IPCP opened
Mar/11/2019 14:55:13 pppoe,ppp,info pppoe-out1: connected
Mar/11/2019 14:55:54 pppoe,ppp,debug pppoe-out1: MPLSCP timer
Mar/11/2019 14:55:54 pppoe,ppp,debug,packet pppoe-out1: sent MPLSCP ConfReq id=0x15
Mar/11/2019 14:56:43 pppoe,ppp,debug,packet pppoe-out1: rcvd LCP EchoReq id=0x2a

У автора даже не начался обмен в первом случае EchoReq и EchoRep, сессия сразу отлегла - прошло 8 секунд
Mar/08/2019 16:40:05 pppoe,ppp,debug Rostelecom: IPCP opened
Mar/08/2019 16:40:13 pppoe,ppp,debug Rostelecom: LCP close

Т.к. через PON я понимаю включено все это хозяйство пусть проверят уровни сигнала/sfp-модули, либо оптика где-то загнулась
Либо смотрите ошибки между микротиком и терминалом по езернету на интерфейсе ether1
Либо проблема в самом терминале


Обладатель Mikrotik RB2011UAS-2HnD-IN
bnsott
Сообщения: 18
Зарегистрирован: 28 фев 2019, 19:01

seregaelcin писал(а): 11 мар 2019, 13:52 Т.к. через PON я понимаю включено все это хозяйство
Да, подключено через PON
пусть проверят уровни сигнала/sfp-модули
Это нужно проверять на стороне провайдера?
Либо смотрите ошибки между микротиком и терминалом по езернету на интерфейсе ether1
Подскажите как это посмотреть?


hAP ac²
Ответить