1 Temat przez Bieniu

  • Bieniu
  • Użytkownik
  • Nieaktywny
  • Zarejestrowany: 2014-10-28
  • Posty: 69

Temat: Prośba o interpretację logów

Witam
TP-Link WDR4300 z OpenWrt CC od Cezarego. Dwa razy przy większej ilości danych przesyłanych po WiFi zdarzyło się, że nagle klienci WiFi nie mieli dostępu do routera ani innych urządzeń w sieci, ani internetu (zarówno po nazwach jak i po IP). Po zalogowaniu z komputera podpiętego po kablu w logu było coś takiego:

Log systemowy:
Tue Jul  7 21:00:54 2015 kern.info kernel: [781603.380000] Atheros AR8216/AR8236/AR8316 ag71xx-mdio.0:00: Port 1 is down
Tue Jul  7 21:01:20 2015 kern.info kernel: [781629.390000] Atheros AR8216/AR8236/AR8316 ag71xx-mdio.0:00: Port 1 is up
Tue Jul  7 21:01:25 2015 daemon.info dnsmasq-dhcp[19773]: DHCPREQUEST(br-lan) 192.168.1.130 xx:xx:xx:xx:xx:xx 
Tue Jul  7 21:01:25 2015 daemon.info dnsmasq-dhcp[19773]: DHCPACK(br-lan) 192.168.1.130 xx:xx:xx:xx:xx:xx print-server
Tue Jul  7 21:01:42 2015 kern.info kernel: [781651.390000] Atheros AR8216/AR8236/AR8316 ag71xx-mdio.0:00: Port 1 is down
Tue Jul  7 21:01:56 2015 kern.info kernel: [781665.390000] Atheros AR8216/AR8236/AR8316 ag71xx-mdio.0:00: Port 1 is up
Tue Jul  7 21:02:43 2015 user.err ddns-scripts[2912]: dnsomatic: BusyBox nslookup error: '1'
Tue Jul  7 21:02:43 2015 user.warn ddns-scripts[2912]: dnsomatic: Get registered/public IP for 'xxxxx' failed - retry 1/0 in 60 seconds
Tue Jul  7 21:03:32 2015 daemon.info hostapd: wlan0: STA xx:xx:xx:xx:xx:xx WPA: group key handshake completed (RSN)
Tue Jul  7 21:03:35 2015 daemon.info hostapd: wlan0: STA xx:xx:xx:xx:xx:xx IEEE 802.11: authenticated
Tue Jul  7 21:03:35 2015 daemon.info hostapd: wlan0: STA xx:xx:xx:xx:xx:xx IEEE 802.11: associated (aid 1)
Tue Jul  7 21:03:35 2015 daemon.info hostapd: wlan0: STA xx:xx:xx:xx:xx:xx WPA: pairwise key handshake completed (RSN)
Tue Jul  7 21:04:23 2015 user.err ddns-scripts[2912]: dnsomatic: BusyBox nslookup error: '1'
Tue Jul  7 21:04:23 2015 user.warn ddns-scripts[2912]: dnsomatic: Get registered/public IP for 'xxxxx' failed - retry 2/0 in 60 seconds
Tue Jul  7 21:04:51 2015 daemon.info hostapd: wlan1: STA xx:xx:xx:xx:xx:xx WPA: group key handshake completed (RSN)
Tue Jul  7 21:05:04 2015 kern.info kernel: [781853.390000] Atheros AR8216/AR8236/AR8316 ag71xx-mdio.0:00: Port 4 is up
Tue Jul  7 21:05:15 2015 daemon.info dnsmasq[19773]: read /etc/hosts - 1 addresses
Tue Jul  7 21:05:15 2015 daemon.info dnsmasq[19773]: read /tmp/hosts/dhcp - 1 addresses
Tue Jul  7 21:05:15 2015 daemon.info dnsmasq[19773]: read /tmp/hosts/odhcpd - 1 addresses
Tue Jul  7 21:05:16 2015 daemon.info dnsmasq[19773]: read /etc/hosts.deny - 70811 addresses
Tue Jul  7 21:05:16 2015 daemon.info dnsmasq-dhcp[19773]: read /etc/ethers - 0 addresses
Tue Jul  7 21:05:19 2015 daemon.info dnsmasq-dhcp[19773]: DHCPREQUEST(br-lan) 192.168.1.110 xx:xx:xx:xx:xx:xx 
Tue Jul  7 21:05:19 2015 daemon.info dnsmasq-dhcp[19773]: DHCPACK(br-lan) 192.168.1.110 xx:xx:xx:xx:xx:xx laptop
Tue Jul  7 21:05:26 2015 daemon.info dnsmasq[19773]: read /etc/hosts - 1 addresses
Tue Jul  7 21:05:26 2015 daemon.info dnsmasq[19773]: read /tmp/hosts/dhcp - 1 addresses
Tue Jul  7 21:05:26 2015 daemon.info dnsmasq[19773]: read /tmp/hosts/odhcpd - 2 addresses
Tue Jul  7 21:05:28 2015 daemon.info dnsmasq[19773]: read /etc/hosts.deny - 70811 addresses
Tue Jul  7 21:05:28 2015 daemon.info dnsmasq-dhcp[19773]: read /etc/ethers - 0 addresses
Log jądra:
[548619.360000] Atheros AR8216/AR8236/AR8316 ag71xx-mdio.0:00: Port 5 is down
[548623.360000] Atheros AR8216/AR8236/AR8316 ag71xx-mdio.0:00: Port 5 is up
[548779.360000] Atheros AR8216/AR8236/AR8316 ag71xx-mdio.0:00: Port 5 is down
[548781.360000] Atheros AR8216/AR8236/AR8316 ag71xx-mdio.0:00: Port 5 is up
[781603.380000] Atheros AR8216/AR8236/AR8316 ag71xx-mdio.0:00: Port 1 is down
[781629.390000] Atheros AR8216/AR8236/AR8316 ag71xx-mdio.0:00: Port 1 is up
[781651.390000] Atheros AR8216/AR8236/AR8316 ag71xx-mdio.0:00: Port 1 is down
[781665.390000] Atheros AR8216/AR8236/AR8316 ag71xx-mdio.0:00: Port 1 is up
[781853.390000] Atheros AR8216/AR8236/AR8316 ag71xx-mdio.0:00: Port 4 is up

Ktoś doradzi co się może dziać?

2 Odpowiedź przez pepe2k

  • pepe2k
  • pepe2k
  • Użytkownik
  • Nieaktywny
  • Zarejestrowany: 2012-10-29
  • Posty: 5,028

Odp: Prośba o interpretację logów

Co masz podłączone do routera na kablu? Czy te urządzenia działają prawidłowo? Te logi to jedynie informacja o tym, że kabel Ethernet został "odłączony", na dwóch portach, w tym WAN (i stąd pewnie brak dostępu do sieci).

Albo to jest bug, albo masz jakiś problem sprzętowy z okablowaniem, sprzętem podłączonym do routera lub samym routerem. Bez głębszej analizy ciężko cokolwiek powiedzieć.

Podobny problem ktoś już zgłaszał: https://dev.openwrt.org/ticket/19775

3 Odpowiedź przez build000 (edytowany przez build000 2015-07-07 22:27:40)

  • Zarejestrowany: 2013-06-25
  • Posty: 2,058

Odp: Prośba o interpretację logów

Ten log w zasadzie nic nie mówi. Natomiast dla przykładu ten powtarzający się fragment:

 Jul  7 21:02:43 2015 user.err ddns-scripts[2912]: dnsomatic: BusyBox nslookup error: '1'
Tue Jul  7 21:02:43 2015 user.warn ddns-scripts[2912]: dnsomatic: Get registered/public IP for 'xxxxx' failed - retry 1(2,3,4,i.t.d.)/0 in 60 seconds

u mnie występuje wtedy jak de facto nie ma internetu, jakieś zakłócenia na łączach wan, i.t.d.
Poza tym dla samego testu, gdy to występuje, wyłączyłbym chwilowo ten plik:

Tue Jul  7 21:05:16 2015 daemon.info dnsmasq[19773]: read /etc/hosts.deny - 70811 addresses

i tym samym skorzystał z domyślnego mechanizmu "allow all".

4 Odpowiedź przez Bieniu (edytowany przez Bieniu 2015-07-08 12:54:31)

  • Bieniu
  • Użytkownik
  • Nieaktywny
  • Zarejestrowany: 2014-10-28
  • Posty: 69

Odp: Prośba o interpretację logów

Po kablu podłączony modem od Multimedii jako WAN, a po stronie LANu, serwer NAS od Synology, print server, komputer stacjonarny i poprzez switcha Xbox360, odtwarzacz Dune i dekoder nC+. Urządzenia jak dotąd działały bezproblemowo. Po przejściu na CC dopiero coś takiego ujrzałem. Muszę sprawdzić, na którym porcie jakie urządzenie siedzi i je przetestować.

Co do wpisów dnsomatic to Dynamic DNS nie miał prawa działać bo nie było dostępu do WAN. Adblocka (hosts.deny) wyłączałem - to nie jego wina.

Dodam jeszcze, że restart routera rozwiązuje problem.

5 Odpowiedź przez pepe2k

  • pepe2k
  • pepe2k
  • Użytkownik
  • Nieaktywny
  • Zarejestrowany: 2012-10-29
  • Posty: 5,028

Odp: Prośba o interpretację logów

Bieniu napisał/a:

Po kablu podłączony modem od Multimedii jako WAN, a po stronie LANu, serwer NAS od Synology, print server, komputer stacjonarny i poprzez switcha Xbox360, odtwarzacz Dune i dekoder nC+. Urządzenia jak dotąd działały bezproblemowo. Po przejściu na CC dopiero coś takiego ujrzałem. Muszę sprawdzić, na którym porcie jakie urządzenie siedzi i je przetestować.

Co do wpisów dnsomatic to Dynamic DNS nie miał prawa działać bo nie było dostępu do WAN. Adblocka (hosts.deny) wyłączałem - to nie jego wina.

Dodam jeszcze, że restart routera rozwiązuje problem.

To może być jakiś problem w CC, ale nie widać żeby był powszechny, dlatego jak masz czas, to wróć do BB i przetestuj tam - wykluczysz wpływ oprogramowania, a trochę przy switchach grzebali pomiędzy BB i CC. Jeżeli na BB złapiesz podobny problem, to sprawdź sprzęt - np. czy Ci się nie przegrzewa (ostatnio aura nie sprzyja elektronice), czy kable i wtyczki są OK, no i czy druga strona działa prawidłowo (niektóre modemy Cisco jak miały za ciepło to się restartowały, co skutkowało takim "odłączaniem" kabla sieciowego).