KN-3910 Периодически пропадает соединение с провайдером(PPPoE0)

[Lostpro]

Всем добрый день, 

сегодня обновил свой роутер с Keenetic AIR KN-1613 На Keenetic Challenger (KN-3910). 

На старом роутере проблем с соединением не возникало. 

Версия прошивни последняя 4.3.5
Все настроил, интернет заработал. Интернет стал Шустрее работать особенно 5 ГГц !
Соединение через Ethernet по PPPoE0

Но уже 4 раза с промежутками в 1 час и меньше пропадал интернет. 
Причем я не могу понять это соединение само отваливается или роутер перезапускается или перезапускает Порт на котором соединение.
 

Есть время подключения по Ethernet и время в сети в разделе Система и различие в 1 минут только , это говорит о перезагрузке роутера? 
в логах не нашел ключевых слов reboot

Помогите разобраться , что с этим делать. 

Заранее спасибо

Спойлер

Июл 23 23:12:45

ndm

Core::Server: started Session /var/run/ndm.core.socket.

Июл 23 23:12:45

ndm

Core::Session: client disconnected.

Июл 23 23:12:45

ndm

Http::Manager: updated configuration.

Июл 23 23:12:45

ndm

Core::Server: started Session /var/run/ndm.core.socket.

Июл 23 23:12:45

ndm

Core::Session: client disconnected.

Июл 23 23:12:48

kernel

NOHZ: local_softirq_pending 08

Июл 23 23:12:48

kernel

NOHZ: local_softirq_pending 08

Июл 23 23:13:46

ndm

Core::System::StartupConfig: saving (http/rci).

Июл 23 23:13:46

ndhcps

NDM DHCP server stopped (exit status: 0).

Июл 23 23:13:46

ndhcps

NDM DHCP server stopped (exit status: 0).

Июл 23 23:13:48

ndhcps

NDM DHCP Server, v3.2.57.

Июл 23 23:13:48

ndhcps

NDM DHCP Server, v3.2.57.

Июл 23 23:13:48

ndm

Core::Server: started Session /var/run/ndm.core.socket.

Июл 23 23:13:48

ndm

Core::Session: client disconnected.

Июл 23 23:13:48

ndm

Core::Server: started Session /var/run/ndm.core.socket.

Июл 23 23:13:48

ndm

Core::Session: client disconnected.

Июл 23 23:13:50

ndm

Core::System::StartupConfig: configuration saved.
 

Июл 23 23:22:26

ndm

Network::Interface::Ethernet: unable to find Switch0 in "Network::Interface::Ethernet".

Июл 23 23:23:28

ndm

Network::Interface::Ethernet: unable to find Switch0 in "Network::Interface::Ethernet".

 [E] Jul 23 22:17:47 ndm: Network::Interface::Ethernet: unable to find Switch0 in "Network::Interface::Ethernet".
Warning is лога:
Jul 23 21:16:37 kernel: NOHZ: local_softirq_pending 08
Jul 23 21:16:37 kernel: NOHZ: local_softirq_pending 08
Jul 23 21:16:47 kernel: NOHZ: local_softirq_pending 08
Jul 23 21:16:47 kernel: NOHZ: local_softirq_pending 08
Jul 23 21:16:59 kernel: NOHZ: local_softirq_pending 08
Jul 23 21:16:59 kernel: NOHZ: local_softirq_pending 08
[W] Jul 23 22:13:32 kernel: NOHZ: local_softirq_pending 08
[W] Jul 23 22:13:32 kernel: NOHZ: local_softirq_pending 08
[W] Jul 23 22:13:33 kernel: NOHZ: local_softirq_pending 08
[W] Jul 23 22:13:33 kernel: NOHZ: local_softirq_pending 08
[W] Jul 23 22:13:36 kernel: NOHZ: local_softirq_pending 08
[W] Jul 23 22:13:36 kernel: NOHZ: local_softirq_pending 08

Jul 23 23:12:48 kernel: NOHZ: local_softirq_pending 08
Jul 23 23:12:48 kernel: NOHZ: local_softirq_pending 08
 

 

 

[Илья Картавенко]

7 часов назад, Lostpro сказал:

Всем добрый день, 

сегодня обновил свой роутер с Keenetic AIR KN-1613 На Keenetic Challenger (KN-3910). 

На старом роутере проблем с соединением не возникало. 

Версия прошивни последняя 4.3.5
Все настроил, интернет заработал. Интернет стал Шустрее работать особенно 5 ГГц !
Соединение через Ethernet по PPPoE0

Но уже 4 раза с промежутками в 1 час и меньше пропадал интернет. 
Причем я не могу понять это соединение само отваливается или роутер перезапускается или перезапускает Порт на котором соединение.
 

Есть время подключения по Ethernet и время в сети в разделе Система и различие в 1 минут только , это говорит о перезагрузке роутера? 
в логах не нашел ключевых слов reboot

Помогите разобраться , что с этим делать. 

Заранее спасибо

  Скрыть контент

Июл 23 23:12:45

ndm

Core::Server: started Session /var/run/ndm.core.socket.

Июл 23 23:12:45

ndm

Core::Session: client disconnected.

Июл 23 23:12:45

ndm

Http::Manager: updated configuration.

Июл 23 23:12:45

ndm

Core::Server: started Session /var/run/ndm.core.socket.

Июл 23 23:12:45

ndm

Core::Session: client disconnected.

Июл 23 23:12:48

kernel

NOHZ: local_softirq_pending 08

Июл 23 23:12:48

kernel

NOHZ: local_softirq_pending 08

Июл 23 23:13:46

ndm

Core::System::StartupConfig: saving (http/rci).

Июл 23 23:13:46

ndhcps

NDM DHCP server stopped (exit status: 0).

Июл 23 23:13:46

ndhcps

NDM DHCP server stopped (exit status: 0).

Июл 23 23:13:48

ndhcps

NDM DHCP Server, v3.2.57.

Июл 23 23:13:48

ndhcps

NDM DHCP Server, v3.2.57.

Июл 23 23:13:48

ndm

Core::Server: started Session /var/run/ndm.core.socket.

Июл 23 23:13:48

ndm

Core::Session: client disconnected.

Июл 23 23:13:48

ndm

Core::Server: started Session /var/run/ndm.core.socket.

Июл 23 23:13:48

ndm

Core::Session: client disconnected.

Июл 23 23:13:50

ndm

Core::System::StartupConfig: configuration saved.
 

Июл 23 23:22:26

ndm

Network::Interface::Ethernet: unable to find Switch0 in "Network::Interface::Ethernet".

Июл 23 23:23:28

ndm

Network::Interface::Ethernet: unable to find Switch0 in "Network::Interface::Ethernet".

 [E] Jul 23 22:17:47 ndm: Network::Interface::Ethernet: unable to find Switch0 in "Network::Interface::Ethernet".
Warning is лога:
Jul 23 21:16:37 kernel: NOHZ: local_softirq_pending 08
Jul 23 21:16:37 kernel: NOHZ: local_softirq_pending 08
Jul 23 21:16:47 kernel: NOHZ: local_softirq_pending 08
Jul 23 21:16:47 kernel: NOHZ: local_softirq_pending 08
Jul 23 21:16:59 kernel: NOHZ: local_softirq_pending 08
Jul 23 21:16:59 kernel: NOHZ: local_softirq_pending 08
[W] Jul 23 22:13:32 kernel: NOHZ: local_softirq_pending 08
[W] Jul 23 22:13:32 kernel: NOHZ: local_softirq_pending 08
[W] Jul 23 22:13:33 kernel: NOHZ: local_softirq_pending 08
[W] Jul 23 22:13:33 kernel: NOHZ: local_softirq_pending 08
[W] Jul 23 22:13:36 kernel: NOHZ: local_softirq_pending 08
[W] Jul 23 22:13:36 kernel: NOHZ: local_softirq_pending 08

Jul 23 23:12:48 kernel: NOHZ: local_softirq_pending 08
Jul 23 23:12:48 kernel: NOHZ: local_softirq_pending 08
 

 

 

О перезагрузки роутера вам скажет время его работы в системном мониторе. Если роутер используется один, уберите компонент контроллер wifi система или выключите протокол stp на всех портов https://help.keenetic.com/hc/ru/articles/4405876929682-Отключение-поддержки-STP-на-порту-коммутатора-роутера. Выставьте побольше время аренды ip адреса для сегмента домашняя сеть.Проверьте не обрывается ли pppoe соединение.

[Lostpro]

3 часа назад, Илья Картавенко сказал:

О перезагрузки роутера вам скажет время его работы в системном мониторе. Если роутер используется один, уберите компонент контроллер wifi система или выключите протокол stp на всех портов https://help.keenetic.com/hc/ru/articles/4405876929682-Отключение-поддержки-STP-на-порту-коммутатора-роутера. Выставьте побольше время аренды ip адреса для сегмента домашняя сеть.Проверьте не обрывается ли pppoe соединение.

Доброе утро Илья, спасибо за ответ.
Спасибо
1) если судить по работе в системном мониторе, тогда получается почему-то получаю перезагрузку роутера. 
Но причин не нахожу, в self-test будет информация об этом?

2) Контроллер убрать не могу, используется mesh wi-fi система вместе с buddy.

3) Время аренды увеличил до 3 дней(это максимум) , до этого стояло 7 часов, но перезагрузки были явно раньше этого срока.
 

в части STP . не нашел в логах падения порта link down.

4) также может это быть причиной, но не уверен:  у меня есть старый роутер ZyXEL Keenetic 4G III (NDMS 2.15.C.6.0-1) (со старой прошивкой,которая для Mesh не годится, обновить выше нельзя) , я его сделал давно как репитер для камеры 
Так почему-то он отображался вчера как подключенный по проводу , а не по WI-fi, причем изначально даже имя не отображал, только макадрес.

Скриншота нет, но у меня сохранились логи: (нет информации что он подключен к WI-fi точке <ap>WifiMaster1/AccessPoint0</ap>

Спойлер

        <hostname>Keenetic-4G</hostname>
        <name/>
        <system-mode>repeater</system-mode>
        <interface>
            <id>Bridge0</id>
            <name>Home</name>
            <description>Home network</description>
        </interface>
        <dhcp>
            <static>yes</static>
        </dhcp>
        <registered>no</registered>
        <access>permit</access>
        <schedule/>
        <priority>6</priority>
        <active>yes</active>
        <rxbytes>0</rxbytes>
        <txbytes>0</txbytes>
        <uptime>80</uptime>
        <first-seen>603</first-seen>
        <last-seen>3</last-seen>
        <http-port>80</http-port>
        <description>ZyXEL Keenetic 4G III (NDMS 2.15.C.6.0-1): kg_rh</description>
        <firmware>2.15.C.6.0-1</firmware>
        <traffic-shape>
            <rx>0</rx>
            <tx>0</tx>
            <mode>mac</mode>
            <schedule/>
        </traffic-shape>
        

а вот из сегодняшнего лога: (отображается нормально)

Спойлер

<hostname>Keenetic-4G</hostname>
        <name/>
        <system-mode>repeater</system-mode>
        <interface>
            <id>Bridge0</id>
            <name>Home</name>
            <description>Home network</description>
        </interface>
        <dhcp>
            <expires>14281</expires>
        </dhcp>
        <registered>no</registered>
        <access>permit</access>
        <schedule/>
        <priority>6</priority>
        <active>yes</active>
        <rxbytes>0</rxbytes>
        <txbytes>0</txbytes>
        <link>up</link>
        <ssid>Masked</ssid>
        <ap>WifiMaster0/AccessPoint0</ap>
        <psm>no</psm>
        <mld>no</mld>
        <authenticated>yes</authenticated>
        <txrate>28</txrate>
        <ht>20</ht>
        <mode>11n</mode>
        <gi>400</gi>
        <rssi>-83</rssi>
        <mcs>9</mcs>
        <txss>2</txss>
        <ebf>no</ebf>
        <security>wpa2-psk</security>
        <uptime>10625</uptime>
        <first-seen>10914</first-seen>
        <last-seen>3</last-seen>
        <http-port>80</http-port>
        <description>ZyXEL Keenetic 4G III (NDMS 2.15.C.6.0-1): kg_rh</description>
        <firmware>2.15.C.6.0-1</firmware>
        <traffic-shape>
            <rx>0</rx>
            <tx>0</tx>
            <mode>mac</mode>
            <schedule/>
        </traffic-shape>

5) Также наблюдал, что периодически возникал 1 клиент без имени в списках клиентах, только с mac.

6) в части обрывов PPPoE записей в логе не нашел
 

Изменено Четверг в 08:48 пользователем Lostpro
добавили информацию

[Padavan]

Lostpro

У вас устройство перезагружается. После перезагрузки там все с чистого листа, поэтому понять причину невозможно.

Из self-test у вас выключен дамп на NDSS
ndss dump-report disable

Без дампа выяснить причину не представляется возможным.

[Lostpro]

4 часа назад, Padavan сказал:

Lostpro

У вас устройство перезагружается. После перезагрузки там все с чистого листа, поэтому понять причину невозможно.

Из self-test у вас выключен дамп на NDSS
ndss dump-report disable

Без дампа выяснить причину не представляется возможным.

Добрый вечер, Padavan, 
спасибо за ответ, 
1) Сбор дампов включил, я правильно понимаю они автоматически потом вам на сервера отправляться, и мне дополнительно посылать не нужно будет?
проверил что теперь в логах ndss dump-report no disable
2) Сегодня не было нагрузки на роутер в клиентах только репитер и камера.
но увидел что Роутер работал 14 часов, а вот отключение EIthernet было, хотя провайдер не сообщал об аварии, self-test приложу следующим сообщением.
3) смутила строка из лога про ip6 , 
 [W] Jul 24 11:12:32 ndm: Network::Interface::Ip6: "Bridge0": reinstall address 

точно помню, что в настройках EIthernet стояла автоматически определять для ip6 и он его действительно получал , а сейчас стоит , не использовать, скорее всего думаю роутер умный и сам отключил.

Прошу проанализировать. 
По Логам странная активность в 11:12 была

Спойлер

Начало:

[I] Jul 24 11:12:32 kernel: device ra0 left promiscuous mode
[I] Jul 24 11:12:32 kernel: br0: port 2(ra0) entered disabled state
[I] Jul 24 11:12:32 ndm: Network::Interface::Bridge: "Bridge0": removed WifiMaster0/AccessPoint0. 
[I] Jul 24 11:12:32 kernel: device ra8 left promiscuous mode
[I] Jul 24 11:12:32 kernel: br0: port 3(ra8) entered disabled state
[I] Jul 24 11:12:32 ndm: Network::Interface::Bridge: "Bridge0": removed WifiMaster1/AccessPoint0. 


 

похоже на STP проблему? 

Спойлер

[I] Jul 24 11:12:32 kernel: br0: port 2(ra0) entered blocking state
[I] Jul 24 11:12:32 kernel: br0: port 2(ra0) entered disabled state
[I] Jul 24 11:12:32 kernel: device ra0 entered promiscuous mode
[I] Jul 24 11:12:32 kernel: br0: port 2(ra0) entered blocking state
[I] Jul 24 11:12:32 kernel: br0: port 2(ra0) entered listening state
[I] Jul 24 11:12:32 kernel: br0: disabled STP choke for port 2(ra0)
[I] Jul 24 11:12:32 kernel: br0: port 2(ra0) entered disabled state
[I] Jul 24 11:12:32 kernel: br0: port 5(ra15.1) entered disabled state
[I] Jul 24 11:12:32 kernel: br0: port 4(ra7.1) entered disabled state
[I] Jul 24 11:12:32 kernel: br0: port 1(eth2) entered disabled state
[I] Jul 24 11:12:32 wind: socket 6 interface went down. 
[I] Jul 24 11:12:32 ndhcps: the interface went down. 
[I] Jul 24 11:12:32 ndm: Network::Interface::Base: "Bridge0": interface is down. 
[I] Jul 24 11:12:32 kernel: br0: port 2(ra0) entered blocking state
[I] Jul 24 11:12:32 kernel: br0: port 2(ra0) entered listening state
[I] Jul 24 11:12:32 kernel: br0: port 5(ra15.1) entered blocking state
[I] Jul 24 11:12:32 kernel: br0: port 5(ra15.1) entered listening state
[I] Jul 24 11:12:32 kernel: br0: port 4(ra7.1) entered blocking state
[I] Jul 24 11:12:32 kernel: br0: port 4(ra7.1) entered listening state
[I] Jul 24 11:12:32 kernel: br0: port 1(eth2) entered blocking state
[I] Jul 24 11:12:32 kernel: br0: port 1(eth2) entered listening state
[I] Jul 24 11:12:32 kernel: IPv6: ADDRCONF(NETDEV_UP): br0: link is not ready
[I] Jul 24 11:12:32 ndm: Network::Interface::Base: "Bridge0": interface is up. 
[I] Jul 24 11:12:32 ndm: Network::Interface::Bridge: "Bridge0": WifiMaster0/AccessPoint0 included. 

только не понял когда именно отключился сам интернет, возможно только в 22:07 , но могу ошибаться

Спойлер

[I] Jul 24 21:17:57 ndm: Core::Syslog: last message repeated 9 times.
[I] Jul 24 22:07:07 pppd[619]: No response to 3 echo-requests 
[I] Jul 24 22:07:07 pppd[619]: Serial link appears to be disconnected. 
[I] Jul 24 22:07:07 pppd[619]: Connect time 831.1 minutes. 
[I] Jul 24 22:07:07 pppd[619]: Sent 80658823 bytes, received 51624604 bytes. 
[I] Jul 24 22:07:07 ndm: Network::Interface::Base: "PPPoE0": "ip" changed "ipv4" layer state "running" to "pending". 
[I] Jul 24 22:07:07 ndm: Network::Interface::Ip: "PPPoE0": IP address cleared. 
[I] Jul 24 22:07:07 ndm: Network::Interface::Base: "PPPoE0": "ppp" changed "link" layer state "running" to "connecting". 
[I] Jul 24 22:07:07 ndm: Network::InternetChecker: Internet access lost (status: 0x0000). 

 

Изменено Четверг в 20:33 пользователем Lostpro
добавил информацию по ip6

[Padavan]

Lostpro

Да, вам специально ничего не нужно присылать.

[I] Jul 24 22:07:07 pppd[619]: No response to 3 echo-requests 
[I] Jul 24 22:07:07 pppd[619]: Serial link appears to be disconnected. 
[I] Jul 24 22:07:07 pppd[619]: Connect time 831.1 minutes. 
[I] Jul 24 22:07:07 pppd[619]: Sent 80658823 bytes, received 51624604 bytes. 

Здесь действительно было отключение PPPoE, BRAS провайдера перестал отвечать на эхи. Это часто встречается, проблема обычно на трассе до BRAS у ISP. Иногда бывают ложные отключения, в настройках PPPoE есть  адаптивный режим:

interface PPPoE0 lcp echo adaptive

 

Цитата

похоже на STP проблему? 

Да, возможно петля. Это распространенная ситуация, когда например PC подключен и кабелем и wifi.

Но все же нужно дождаться самопроизвольных перезагрузок.

[Le ecureuil]

Цитата

3) смутила строка из лога про ip6 , 
 [W] Jul 24 11:12:32 ndm: Network::Interface::Ip6: "Bridge0": reinstall address 

Это нормально, можете не обращать внимания.

[sergeyk]

@Lostpro включите ещё отладку на интерфейсе PPPoE0:

interface PPPoE0 debug
system configuration save

 

[Lostpro]

10 часов назад, Padavan сказал:

Lostpro

Да, вам специально ничего не нужно присылать.

[I] Jul 24 22:07:07 pppd[619]: No response to 3 echo-requests 
[I] Jul 24 22:07:07 pppd[619]: Serial link appears to be disconnected. 
[I] Jul 24 22:07:07 pppd[619]: Connect time 831.1 minutes. 
[I] Jul 24 22:07:07 pppd[619]: Sent 80658823 bytes, received 51624604 bytes. 

Здесь действительно было отключение PPPoE, BRAS провайдера перестал отвечать на эхи. Это часто встречается, проблема обычно на трассе до BRAS у ISP. Иногда бывают ложные отключения, в настройках PPPoE есть  адаптивный режим:

interface PPPoE0 lcp echo adaptive

 

Да, возможно петля. Это распространенная ситуация, когда например PC подключен и кабелем и wifi.

Но все же нужно дождаться самопроизвольных перезагрузок.

@Padavan Спасибо, адаптивный режим включил, пришлось правда поискать корректную команду на вашем форуме.

Ввёл такую: interface pppoe0 lcp echo 30 3 adaptive

в роутере 1 свободный порт оказался только, я не обратил на это внимание перед покупкой. но он пока никак не используется. Вообще телевизор у меня шел через проводное соединение. но пока не ставил. 
Напишу отдельно , когда снова будет перезагрузка. 
 

Сегодня кстати снова падал вечером pppoe по логам, посмотрим завтра как будет с адаптивном режимом вести.

Спасибо

Спойлер

Строка 2080: [I] Jul 25 21:17:57 pppd[28115]: No response to 3 echo-requests 
    Строка 2081: [I] Jul 25 21:17:57 pppd[28115]: Serial link appears to be disconnected. 
    Строка 2082: [I] Jul 25 21:17:57 pppd[28115]: Connect time 1201.6 minutes. 
    Строка 2083: [I] Jul 25 21:17:57 pppd[28115]: Sent 37770151 bytes, received 12141086 bytes. 
    Строка 2098: [I] Jul 25 21:18:00 pppd[28115]: Connection terminated. 
    Строка 2099: [E] Jul 25 21:18:00 pppd[28115]: write: Bad file descriptor (9) 
    Строка 2100: [I] Jul 25 21:18:00 pppd[28115]: Sent PADT 
    Строка 2101: [I] Jul 25 21:18:00 pppd[28115]: Exit. 

5 часов назад, sergeyk сказал:

@Lostpro включите ещё отладку на интерфейсе PPPoE0:

interface PPPoE0 debug
system configuration save

 

@sergeyk Выполнил команду, спасибо .

 

10 часов назад, Le ecureuil сказал:

Это нормально, можете не обращать внимания.

@Le ecureuil Спасибо

я правда для перестраховки  в настройках IPv6 выставил не использовать в Eithernet.

сейчас настройки такие, так корректно, или вернуть ipv6 в режим автоматически определять?
какие правильно настройки ставить для pppoe для ipv6? 

Всем еще раз спасибо за отзыв и комментарии.

Настройка Eithernet соединения:

 

Настройка :Аутентификация у провайдера (PPPoE / PPTP / L2TP)

Изменено Пятница в 20:15 пользователем Lostpro
добавлена информация к скринам