• Zarejestrowany: 2025-09-27
  • Posty: 5

Temat: [OpenWrt] Problem z autentykacją PPPoE

Cześć

To już chyba 3 forum na którym szukam pomocy. Temat nieco szerzej opisany na oficjalnym forum OpenWrt:
https://forum.openwrt.org/t/wan-pppoe-n … /240996/21

Główny problem:
Niepowodzenie autentykacji PPPoE a co za tym idzie brak połączenia z internetem.

ISP:
Województwo śląskie, lokalny dostawca, sieć światłowodowa zakończona urządzeniem Nokia G-010G-Q (GPON -> RJ45)

Sprzęt i oprogramowanie użyte do testów:
- Banana Pi BPI-R4; OpenWrt OpenWrt 24.10.0, 24.10.2, 24.10.3
- TP-Link WR1034ND v3.0; OpenWrt 24.10.3
- TP-Link WR1034ND v4.0; oryginalne oprogramowanie 3.16.9 Build 20160607 Rel.58297n

Konfiguracja:
Światłowód wchodzi do domu na urządzenie Nokia G-010G-Q, z którego przewód RJ-45 podpięty jest do głównego routera (jedno z urządzeń wymienione powyżej).
ISP na urządzeniu klienta wymaga konfiguracji bridge na porcie WAN i połączenia za pomocą PPPoE.
ISP nie wymaga konfiguracji VLAN (dzwoniłem do nich, ogólnie działają na id 100 untagged ale z uwagi na w/w, nie powinno mieć to znaczenia)
MAC routera nie jest blokowany - nie ma znaczenia.

Na OpenWrt, zarówno na BPI-R4 jak i TP-Linku połączenie PPPoE nie działa. Za poradą na w/w forum dodałem opcję debug do loga.

Fri Sep 19 21:37:52 2025 daemon.notice netifd: Interface 'wan' is now down
Fri Sep 19 21:37:52 2025 daemon.notice netifd: Interface 'wan' is setting up now
Fri Sep 19 21:37:52 2025 daemon.info pppd[18348]: Plugin pppoe.so loaded.
Fri Sep 19 21:37:52 2025 daemon.info pppd[18348]: PPPoE plugin from pppd 2.5.1
Fri Sep 19 21:37:53 2025 daemon.notice pppd[18348]: pppd 2.5.1 started by root, uid 0
Fri Sep 19 21:37:53 2025 daemon.debug pppd[18348]: Send PPPOE Discovery V1T1 PADI session 0x0 length 12
Fri Sep 19 21:37:53 2025 daemon.debug pppd[18348]:  dst ff:ff:ff:ff:ff:ff  src XX:XX:XX:XX:XX:XX
Fri Sep 19 21:37:53 2025 daemon.debug pppd[18348]:  [service-name] [host-uniq ac 47 00 00]
Fri Sep 19 21:37:53 2025 daemon.debug pppd[18348]: Recv PPPOE Discovery V1T1 PADO session 0x0 length 47
Fri Sep 19 21:37:53 2025 daemon.debug pppd[18348]:  dst XX:XX:XX:XX:XX:XX  src 60:26:aa:27:b7:c0
Fri Sep 19 21:37:53 2025 daemon.debug pppd[18348]:  [service-name] [host-uniq ac 47 00 00] [AC-name KonASR-HX-2] [AC-cookie 95 91 0e 4e 12 d0 61 d6 a2 66 c7 28 48 e4 5a a2]
Fri Sep 19 21:37:53 2025 daemon.debug pppd[18348]: Send PPPOE Discovery V1T1 PADR session 0x0 length 32
Fri Sep 19 21:37:53 2025 daemon.debug pppd[18348]:  dst 60:26:aa:27:b7:c0  src XX:XX:XX:XX:XX:XX
Fri Sep 19 21:37:53 2025 daemon.debug pppd[18348]:  [service-name] [host-uniq ac 47 00 00] [AC-cookie 95 91 0e 4e 12 d0 61 d6 a2 66 c7 28 48 e4 5a a2]
Fri Sep 19 21:37:53 2025 daemon.debug pppd[18348]: Recv PPPOE Discovery V1T1 PADO session 0x0 length 47
Fri Sep 19 21:37:53 2025 daemon.debug pppd[18348]:  dst XX:XX:XX:XX:XX:XX  src 60:26:aa:27:dd:c0
Fri Sep 19 21:37:53 2025 daemon.debug pppd[18348]:  [service-name] [host-uniq ac 47 00 00] [AC-name KonASR-HX-1] [AC-cookie 12 ed 4b ab 06 95 bc c7 f1 56 e1 6e 2d b7 aa af]
Fri Sep 19 21:37:53 2025 daemon.debug pppd[18348]: Recv PPPOE Discovery V1T1 PADS session 0xa375 length 32
Fri Sep 19 21:37:53 2025 daemon.debug pppd[18348]:  dst XX:XX:XX:XX:XX:XX  src 60:26:aa:27:b7:c0
Fri Sep 19 21:37:53 2025 daemon.debug pppd[18348]:  [service-name] [host-uniq ac 47 00 00] [AC-cookie 95 91 0e 4e 12 d0 61 d6 a2 66 c7 28 48 e4 5a a2]
Fri Sep 19 21:37:53 2025 daemon.info pppd[18348]: PPP session is 41845
Fri Sep 19 21:37:53 2025 daemon.warn pppd[18348]: Connected to 60:26:AA:27:B7:C0 via interface br-wan
Fri Sep 19 21:37:53 2025 daemon.debug pppd[18348]: using channel 53
Fri Sep 19 21:37:53 2025 daemon.info pppd[18348]: Using interface pppoe-wan
Fri Sep 19 21:37:53 2025 daemon.notice pppd[18348]: Connect: pppoe-wan <--> br-wan
Fri Sep 19 21:37:53 2025 daemon.debug pppd[18348]: sent [LCP ConfReq id=0x1 <mru 1492> <magic 0x7b05afda>]
Fri Sep 19 21:37:53 2025 daemon.debug pppd[18348]: rcvd [LCP ConfReq id=0x1 <mru 1492> <auth chap MD5> <magic 0xe75fde3c>]
Fri Sep 19 21:37:53 2025 daemon.debug pppd[18348]: sent [LCP ConfAck id=0x1 <mru 1492> <auth chap MD5> <magic 0xe75fde3c>]
Fri Sep 19 21:37:53 2025 daemon.debug pppd[18348]: rcvd [LCP ConfAck id=0x1 <mru 1492> <magic 0x7b05afda>]
Fri Sep 19 21:37:53 2025 daemon.debug pppd[18348]: sent [LCP EchoReq id=0x0 magic=0x7b05afda]
Fri Sep 19 21:37:53 2025 daemon.debug pppd[18348]: rcvd [LCP EchoRep id=0x0 magic=0xe75fde3c]
Fri Sep 19 21:37:53 2025 daemon.debug pppd[18348]: rcvd [CHAP Challenge id=0x1 <9078261dba7b44be56bf7d9e1ef95c6b>, name = "KonASR-HX-2"]
Fri Sep 19 21:37:53 2025 daemon.debug pppd[18348]: sent [CHAP Response id=0x1 <e0fda31ba84d2960ec3c342d75a31ee0>, name = "XXXXX"]
Fri Sep 19 21:37:53 2025 daemon.warn odhcpd[1992]: No default route present, overriding ra_lifetime to 0!
Fri Sep 19 21:37:54 2025 daemon.debug pppd[18348]: sent [LCP EchoReq id=0x1 magic=0x7b05afda]
Fri Sep 19 21:37:54 2025 daemon.debug pppd[18348]: rcvd [LCP EchoRep id=0x1 magic=0xe75fde3c]
Fri Sep 19 21:37:54 2025 daemon.info dnsmasq[1]: read /etc/hosts - 12 names
Fri Sep 19 21:37:54 2025 daemon.info dnsmasq[1]: read /tmp/hosts/dhcp.cfg01411c - 4 names
Fri Sep 19 21:37:54 2025 daemon.info dnsmasq[1]: read /tmp/hosts/odhcpd - 2 names
Fri Sep 19 21:37:54 2025 daemon.info dnsmasq-dhcp[1]: read /etc/ethers - 0 addresses
Fri Sep 19 21:37:54 2025 daemon.debug pppd[18348]: rcvd [CHAP Failure id=0x1 "Authentication failed"]
Fri Sep 19 21:37:54 2025 daemon.info pppd[18348]: CHAP authentication failed: Authentication failed
Fri Sep 19 21:37:54 2025 daemon.err pppd[18348]: CHAP authentication failed
Fri Sep 19 21:37:54 2025 daemon.debug pppd[18348]: sent [LCP TermReq id=0x2 "Failed to authenticate ourselves to peer"]
Fri Sep 19 21:37:54 2025 daemon.debug pppd[18348]: rcvd [LCP TermReq id=0x2]
Fri Sep 19 21:37:54 2025 daemon.debug pppd[18348]: sent [LCP TermAck id=0x2]
Fri Sep 19 21:37:54 2025 daemon.debug pppd[18348]: rcvd [LCP TermAck id=0x2]
Fri Sep 19 21:37:54 2025 daemon.notice pppd[18348]: Connection terminated.
Fri Sep 19 21:37:54 2025 daemon.debug pppd[18348]: Send PPPOE Discovery V1T1 PADT session 0xa375 length 28
Fri Sep 19 21:37:54 2025 daemon.debug pppd[18348]:  dst 60:26:aa:27:b7:c0  src XX:XX:XX:XX:XX:XX
Fri Sep 19 21:37:54 2025 daemon.debug pppd[18348]:  [host-uniq ac 47 00 00] [AC-cookie 95 91 0e 4e 12 d0 61 d6 a2 66 c7 28 48 e4 5a a2]
Fri Sep 19 21:37:54 2025 daemon.info pppd[18348]: Sent PADT
Fri Sep 19 21:37:54 2025 daemon.info pppd[18348]: Exit.

Widać, że sesja negocjacyjna PADx przechodzi i połączenie wywala się na samej autentykacji. Skoro dzieje się to na obu urządzeniach z OpenWrt wykluczam przyczynę sprzętową.

Macie jakieś pomysły co może być przyczyną i jak to dokładniej zdebugować?

2 Odpowiedź przez Cezary

  • Skąd: Warszawa
  • Zarejestrowany: 2006-02-25
  • Posty: 115,935

Odp: [OpenWrt] Problem z autentykacją PPPoE

Na 100% masz dobre username i password? Sprawdzałeś na innym sprzęcie czy w ogóle sie daje na te dane zalogować?

Masz niepotrzebny router, uszkodzony czy nie - chętnie przygarnę go.

Cezary's Strona

3 Odpowiedź przez MikeOct (edytowany przez MikeOct 2025-09-27 11:53:44)

  • Zarejestrowany: 2025-09-27
  • Posty: 5

Odp: [OpenWrt] Problem z autentykacją PPPoE

W tym momencie działam dzięki TP-Linkowi (v4) z oryginalnym oprogramowaniem i wbitymi loginem/hasłem smile
Na tym samym modelu TP-Linka (v3) z OpenWrt, PPPoE nie działa. Na BPI-R4 to samo.

Login i hasło w postaci cyfr, sprawdzone w pliku /etc/config/network. Nie ma pomyłki.

4 Odpowiedź przez Cezary

  • Skąd: Warszawa
  • Zarejestrowany: 2006-02-25
  • Posty: 115,935

Odp: [OpenWrt] Problem z autentykacją PPPoE

Tu masz chap'a z md5/ W tplinku coś innego wybierałeś?

Masz niepotrzebny router, uszkodzony czy nie - chętnie przygarnę go.

Cezary's Strona

5 Odpowiedź przez MikeOct

  • Zarejestrowany: 2025-09-27
  • Posty: 5

Odp: [OpenWrt] Problem z autentykacją PPPoE

W TP-Linku nie ma nigdzie opcji wyboru.
https://forum.openwrt.org/uploads/default/original/3X/9/2/928ca5f8a9b60d014dc15f6a32fa3af1a13958ba.jpeg

Log z działającego TP-Linka też za wiele nie mówi w tym temacie.

1st day 00:00:27     PPP           NOTICE     call pppoeFormatCmd.
1st day 00:00:27     PPP           NOTICE     Standard authentication.
1st day 00:00:28     PPP           INFO       send_phase 2091 pppd_phase = 0x2, ipv6 = 0, ipv4 = 0^M 
1st day 00:00:28     PPP           INFO       In pppd the httpd-id is 475, set link phase is 0x2 
1st day 00:00:28     PPP           INFO       sent [PADI Host-Uniq(0x00000206)]
1st day 00:00:28     PPP           INFO       rcvd [PADO AC-Name:KonASR-HX-2 AC-MAC:60:26:aa:27:b7:c0]
1st day 00:00:28     PPP           INFO       sent [PADR Host-Uniq(0x00000206)]
1st day 00:00:28     PPP           INFO       rcvd [PADS sess-id(59774)]
1st day 00:00:28     PPP           INFO       send_phase 2091 pppd_phase = 0x4, ipv6 = 0, ipv4 = 0^M 
1st day 00:00:28     PPP           INFO       In pppd the httpd-id is 475, set link phase is 0x4 
1st day 00:00:28     PPP           INFO       sent [LCP Req mru=1480 magic=0xc7d8428f]
1st day 00:00:28     PPP           INFO       rcvd [LCP Req mru=1492 auth=chap-MD5 magic=0xec3b760c]
1st day 00:00:28     PPP           INFO       sent [LCP Ack mru=1492 auth=chap-MD5 magic=0xec3b760c]
1st day 00:00:28     PPP           INFO       rcvd [LCP Nak mru=1492]
1st day 00:00:28     PPP           INFO       sent [LCP Req mru=1492 magic=0xc7d8428f]
1st day 00:00:28     PPP           INFO       rcvd [LCP Ack mru=1492 magic=0xc7d8428f]
1st day 00:00:28     PPP           INFO       send_phase 2091 pppd_phase = 0x5, ipv6 = 0, ipv4 = 0^M 
1st day 00:00:28     PPP           INFO       In pppd the httpd-id is 475, set link phase is 0x5 
1st day 00:00:28     PPP           INFO       rcvd [CHAP Challenge (15368b71091278cb56bf7d9e28decd72), name = "KonASR-HX-2"]
1st day 00:00:28     PPP           INFO       sent [CHAP Response (8d45ca977bf8340f6ca42a2fb6d715c3), name = "XXXXX"]
1st day 00:00:28     PPP           INFO       rcvd [CHAP Success ""]
1st day 00:00:28     PPP           INFO       send_phase 2091 pppd_phase = 0x7, ipv6 = 0, ipv4 = 0^M 
1st day 00:00:28     PPP           INFO       In pppd the httpd-id is 475, set link phase is 0x7

6 Odpowiedź przez nicefile

  • Zarejestrowany: 2018-12-05
  • Posty: 58

Odp: [OpenWrt] Problem z autentykacją PPPoE

jestem ciekawy czy to nie temat z https://github.com/ppp-project/ppp/issues/496
pppd jest juz w wersji 2.5.1 na 24.10.x ale moze potrzeba by przebudować obraz by jechał tylko z OpenSSL?
Dodatkowo (i to najpierw) zawsze warto wrzucić SNAPSHOT na testy.

7 Odpowiedź przez MikeOct (edytowany przez MikeOct 2025-09-30 11:01:35)

  • Zarejestrowany: 2025-09-27
  • Posty: 5

Odp: [OpenWrt] Problem z autentykacją PPPoE

SNAPSHOT'a ściągałem parę dni przed pojawieniem się wersji 24.10.3 - nie pomogło. Na 24.10.3 dalej nie działa.
Z ciekawości ściągnąłem jeszcze obraz z linku podanego tutaj:
https://eko.one.pl/forum/viewtopic.php? … 38#p321138
Dalej nie ma poprawy.

Przejrzałem pobieżnie wątek z linku @nicefile i tam od razu w oczy rzucał się długi hash. W moim przypadku wszystko wygląda poprawnie ale moje podejrzenia też padają na kodowanie, stąd pomysł na forum OpenWrt, żeby wymusić konkretne kodowania chap i sprawdzić czy to pomoże.
Nie wiem czy można ustawić jeszcze głębszy poziom debugowania - mogłoby to być przydatne.

Opcja ostateczna: wpięcie się switchem i laptopem pomiędzy router a Nokię, spoofowanie adresu MAC routera i próba odczytania komunikacji sieciowej i porównanie. Chociaż tu nie wiem czy switch przepuści mi ruch sieciowy na ten sam MAC obecny na 2 portach.

8 Odpowiedź przez nicefile

  • Zarejestrowany: 2018-12-05
  • Posty: 58

Odp: [OpenWrt] Problem z autentykacją PPPoE

@MikeOct mi chodzi aby przez firmware selector zbudowac fw bez embedtls ,jedynie openssl. Musisz wskazac  libustream-openssl  i wpad-basic-openssl a usunac mbedtls
dla 24.10.3 i wr1043v3

base-files ca-bundle dnsmasq dropbear firewall4 fstools kmod-ath9k kmod-gpio-button-hotplug kmod-nft-offload libc libgcc libustream-openssl logd mtd netifd nftables odhcp6c odhcpd-ipv6only opkg ppp ppp-mod-pppoe procd-ujail swconfig uboot-envtools uci uclient-fetch urandom-seed urngd wpad-basic-openssl kmod-usb2 kmod-usb-ledtrig-usbport luci

9 Odpowiedź przez MikeOct (edytowany przez MikeOct 2025-10-13 17:06:33)

  • Zarejestrowany: 2025-09-27
  • Posty: 5

Odp: [OpenWrt] Problem z autentykacją PPPoE

Zbudowałem obraz dla BPI-R4 według tego co napisałeś @nicefile
Jeśli to jedyne zmiany jakie należało wprowadzić... to nie pomogło. Dalej ten sam błąd autentykacji.

Próbowałem też wymusić autentykację chap poprzez

options pppd-options "debug require-chap

Tu zachowanie jest ciekawe bo dostaję komunikat "The remote system is required to identify itself but i couldnt find any suitable secret password for it to use"

Fri Sep 19 21:27:26 2025 daemon.notice netifd: Interface 'wan' is now down
Fri Sep 19 21:27:26 2025 daemon.notice netifd: Interface 'wan' is setting up now
Fri Sep 19 21:27:26 2025 daemon.info pppd[6888]: Plugin pppoe.so loaded.
Fri Sep 19 21:27:26 2025 daemon.info pppd[6888]: PPPoE plugin from pppd 2.5.1
Fri Sep 19 21:27:26 2025 daemon.err pppd[6888]: The remote system is required to authenticate itself
Fri Sep 19 21:27:26 2025 daemon.notice netifd: wan (6888): /usr/sbin/pppd: The remote system is required to authenticate itself
Fri Sep 19 21:27:26 2025 daemon.err pppd[6888]: but I couldn't find any suitable secret (password) for it to use to do so.
Fri Sep 19 21:27:26 2025 daemon.notice netifd: wan (6888): /usr/sbin/pppd: but I couldn't find any suitable secret (password) for it to use to do so.
Fri Sep 19 21:27:27 2025 daemon.notice netifd: Interface 'wan' is now down

Pogadałem trochę z chatem gpt i podrzucił mi ścieżkę do pliku gdzie należy podać hasło i login

etc/ppp/chap-secrets

Nic to nie dało, oprócz tego, że komunikat zniknął z logów ale wygląda na to, jakby komunikacja zawieszała się na pakietach typu echo (nie pamiętam dokładnie).

Fri Sep 19 21:27:28 2025 daemon.notice netifd: Interface 'wan' is setting up now
Fri Sep 19 21:27:28 2025 daemon.notice netifd: Network device 'wan' link is down
Fri Sep 19 21:27:28 2025 daemon.notice netifd: Interface 'wan' has link connectivity loss
Fri Sep 19 21:27:28 2025 kern.info kernel: [  476.008231] mt7530-mmio 15020000.switch wan: Link is Down
Fri Sep 19 21:27:28 2025 daemon.notice netifd: Interface 'wan' is now down
Fri Sep 19 21:27:29 2025 daemon.warn odhcpd[2005]: No default route present, overriding ra_lifetime to 0!
Fri Sep 19 21:27:29 2025 daemon.info dnsmasq[1]: read /etc/hosts - 12 names
Fri Sep 19 21:27:29 2025 daemon.info dnsmasq[1]: read /tmp/hosts/dhcp.cfg01411c - 4 names
Fri Sep 19 21:27:29 2025 daemon.info dnsmasq-dhcp[1]: read /etc/ethers - 0 addresses
Fri Sep 19 21:30:48 2025 daemon.notice netifd: Interface 'wan' is disabled
Fri Sep 19 21:30:48 2025 daemon.notice netifd: Interface 'wan' is enabled
Fri Sep 19 21:30:48 2025 kern.info kernel: [  676.256819] mt7530-mmio 15020000.switch wan: configuring for phy/internal link mode
Fri Sep 19 21:31:05 2025 daemon.notice netifd: Network device 'wan' link is up
Fri Sep 19 21:31:05 2025 daemon.notice netifd: Interface 'wan' has link connectivity
Fri Sep 19 21:31:05 2025 daemon.notice netifd: Interface 'wan' is setting up now
Fri Sep 19 21:31:05 2025 kern.info kernel: [  693.526649] mt7530-mmio 15020000.switch wan: Link is Up - 1Gbps/Full - flow control off
Fri Sep 19 21:31:05 2025 daemon.info pppd[7410]: Plugin pppoe.so loaded.
Fri Sep 19 21:31:05 2025 daemon.info pppd[7410]: PPPoE plugin from pppd 2.5.1
Fri Sep 19 21:31:05 2025 daemon.notice pppd[7410]: pppd 2.5.1 started by root, uid 0
Fri Sep 19 21:31:06 2025 daemon.debug pppd[7410]: Send PPPOE Discovery V1T1 PADI session 0x0 length 12
Fri Sep 19 21:31:06 2025 daemon.debug pppd[7410]:  dst ff:ff:ff:ff:ff:ff  src b2:18:f8:23:8c:d5
Fri Sep 19 21:31:06 2025 daemon.debug pppd[7410]:  [service-name] [host-uniq f2 1c 00 00]
Fri Sep 19 21:31:06 2025 daemon.debug pppd[7410]: Recv PPPOE Discovery V1T1 PADO session 0x0 length 47
Fri Sep 19 21:31:06 2025 daemon.debug pppd[7410]:  dst b2:18:f8:23:8c:d5  src 60:26:aa:27:b7:c0
Fri Sep 19 21:31:06 2025 daemon.debug pppd[7410]:  [service-name] [host-uniq f2 1c 00 00] [AC-name KonASR-HX-2] [AC-cookie ad 7d de a1 50 d7 ef 85 c6 bf 63 39 2f 54 0d 02]
Fri Sep 19 21:31:06 2025 daemon.debug pppd[7410]: Send PPPOE Discovery V1T1 PADR session 0x0 length 32
Fri Sep 19 21:31:06 2025 daemon.debug pppd[7410]:  dst 60:26:aa:27:b7:c0  src b2:18:f8:23:8c:d5
Fri Sep 19 21:31:06 2025 daemon.debug pppd[7410]:  [service-name] [host-uniq f2 1c 00 00] [AC-cookie ad 7d de a1 50 d7 ef 85 c6 bf 63 39 2f 54 0d 02]
Fri Sep 19 21:31:06 2025 daemon.debug pppd[7410]: Recv PPPOE Discovery V1T1 PADO session 0x0 length 47
Fri Sep 19 21:31:06 2025 daemon.debug pppd[7410]:  dst b2:18:f8:23:8c:d5  src 60:26:aa:27:dd:c0
Fri Sep 19 21:31:06 2025 daemon.debug pppd[7410]:  [service-name] [host-uniq f2 1c 00 00] [AC-name KonASR-HX-1] [AC-cookie a2 d2 0b d0 40 69 de 41 6a db 46 26 4d 4e 98 f3]
Fri Sep 19 21:31:06 2025 daemon.debug pppd[7410]: Recv PPPOE Discovery V1T1 PADS session 0x93b3 length 32
Fri Sep 19 21:31:06 2025 daemon.debug pppd[7410]:  dst b2:18:f8:23:8c:d5  src 60:26:aa:27:b7:c0
Fri Sep 19 21:31:06 2025 daemon.debug pppd[7410]:  [service-name] [host-uniq f2 1c 00 00] [AC-cookie ad 7d de a1 50 d7 ef 85 c6 bf 63 39 2f 54 0d 02]
Fri Sep 19 21:31:06 2025 daemon.info pppd[7410]: PPP session is 37811
Fri Sep 19 21:31:06 2025 daemon.warn pppd[7410]: Connected to 60:26:AA:27:B7:C0 via interface wan
Fri Sep 19 21:31:06 2025 daemon.debug pppd[7410]: using channel 117
Fri Sep 19 21:31:06 2025 daemon.info pppd[7410]: Using interface pppoe-wan
Fri Sep 19 21:31:06 2025 daemon.notice pppd[7410]: Connect: pppoe-wan <--> wan
Fri Sep 19 21:31:06 2025 daemon.debug pppd[7410]: sent [LCP ConfReq id=0x1 <mru 1492> <auth chap MD5> <magic 0xc12e9ed8>]
Fri Sep 19 21:31:06 2025 daemon.debug pppd[7410]: rcvd [LCP ConfReq id=0x1 <mru 1492> <auth chap MD5> <magic 0x2585f696>]
Fri Sep 19 21:31:06 2025 daemon.debug pppd[7410]: sent [LCP ConfAck id=0x1 <mru 1492> <auth chap MD5> <magic 0x2585f696>]
Fri Sep 19 21:31:06 2025 daemon.debug pppd[7410]: rcvd [LCP ConfAck id=0x1 <mru 1492> <auth chap MD5> <magic 0xc12e9ed8>]
Fri Sep 19 21:31:06 2025 daemon.debug pppd[7410]: sent [LCP EchoReq id=0x0 magic=0xc12e9ed8]
Fri Sep 19 21:31:06 2025 daemon.debug pppd[7410]: sent [CHAP Challenge id=0x28 <6f49da8312213031fbb27421f89b9586f54c33b6ba91fd>, name = "OpenWrt"]
Fri Sep 19 21:31:06 2025 daemon.debug pppd[7410]: rcvd [LCP EchoRep id=0x0 magic=0x2585f696]
Fri Sep 19 21:31:06 2025 daemon.debug pppd[7410]: rcvd [CHAP Challenge id=0x1 <042971f67a2945b356bf7d9e4afa0a8d>, name = "KonASR-HX-2"]
Fri Sep 19 21:31:06 2025 daemon.debug pppd[7410]: sent [CHAP Response id=0x1 <e6567c3e45f784c05daadd102485a7d8>, name = "32752"]
Fri Sep 19 21:31:07 2025 daemon.debug pppd[7410]: sent [LCP EchoReq id=0x1 magic=0xc12e9ed8]
Fri Sep 19 21:31:07 2025 daemon.debug pppd[7410]: rcvd [LCP EchoRep id=0x1 magic=0x2585f696]
Fri Sep 19 21:31:08 2025 daemon.debug pppd[7410]: sent [LCP EchoReq id=0x2 magic=0xc12e9ed8]
Fri Sep 19 21:31:08 2025 daemon.debug pppd[7410]: rcvd [LCP EchoRep id=0x2 magic=0x2585f696]
Fri Sep 19 21:31:09 2025 daemon.debug pppd[7410]: sent [CHAP Challenge id=0x28 <6f49da8312213031fbb27421f89b9586f54c33b6ba91fd>, name = "OpenWrt"]
Fri Sep 19 21:31:09 2025 daemon.debug pppd[7410]: sent [LCP EchoReq id=0x3 magic=0xc12e9ed8]
Fri Sep 19 21:31:09 2025 daemon.debug pppd[7410]: rcvd [LCP EchoRep id=0x3 magic=0x2585f696]
Fri Sep 19 21:31:10 2025 daemon.debug pppd[7410]: sent [LCP EchoReq id=0x4 magic=0xc12e9ed8]
Fri Sep 19 21:31:10 2025 daemon.debug pppd[7410]: rcvd [LCP EchoRep id=0x4 magic=0x2585f696]
Fri Sep 19 21:31:11 2025 daemon.debug pppd[7410]: sent [LCP EchoReq id=0x5 magic=0xc12e9ed8]
Fri Sep 19 21:31:11 2025 daemon.debug pppd[7410]: rcvd [LCP EchoRep id=0x5 magic=0x2585f696]
Fri Sep 19 21:31:12 2025 daemon.debug pppd[7410]: sent [CHAP Challenge id=0x28 <6f49da8312213031fbb27421f89b9586f54c33b6ba91fd>, name = "OpenWrt"]
Fri Sep 19 21:31:12 2025 daemon.debug pppd[7410]: sent [LCP EchoReq id=0x6 magic=0xc12e9ed8]
Fri Sep 19 21:31:12 2025 daemon.debug pppd[7410]: rcvd [LCP EchoRep id=0x6 magic=0x2585f696]
Fri Sep 19 21:31:13 2025 daemon.debug pppd[7410]: sent [LCP EchoReq id=0x7 magic=0xc12e9ed8]
Fri Sep 19 21:31:13 2025 daemon.debug pppd[7410]: rcvd [LCP EchoRep id=0x7 magic=0x2585f696]
Fri Sep 19 21:31:14 2025 daemon.debug pppd[7410]: sent [LCP EchoReq id=0x8 magic=0xc12e9ed8]
Fri Sep 19 21:31:14 2025 daemon.debug pppd[7410]: rcvd [LCP EchoRep id=0x8 magic=0x2585f696]
Fri Sep 19 21:31:15 2025 daemon.debug pppd[7410]: sent [CHAP Challenge id=0x28 <6f49da8312213031fbb27421f89b9586f54c33b6ba91fd>, name = "OpenWrt"]
Fri Sep 19 21:31:15 2025 daemon.debug pppd[7410]: sent [LCP EchoReq id=0x9 magic=0xc12e9ed8]
Fri Sep 19 21:31:15 2025 daemon.debug pppd[7410]: rcvd [LCP EchoRep id=0x9 magic=0x2585f696]
Fri Sep 19 21:31:16 2025 daemon.debug pppd[7410]: sent [LCP EchoReq id=0xa magic=0xc12e9ed8]
Fri Sep 19 21:31:16 2025 daemon.debug pppd[7410]: rcvd [LCP EchoRep id=0xa magic=0x2585f696]
Fri Sep 19 21:31:16 2025 daemon.debug pppd[7410]: rcvd [CHAP Challenge id=0x2 <e230b76d3ac2f82156bf7d9eec087184>, name = "KonASR-HX-2"]
Fri Sep 19 21:31:16 2025 daemon.debug pppd[7410]: sent [CHAP Response id=0x2 <aa0c1311b8dc2390be7375b0fe4284d6>, name = "32752"]
Fri Sep 19 21:31:17 2025 daemon.debug pppd[7410]: sent [LCP EchoReq id=0xb magic=0xc12e9ed8]
Fri Sep 19 21:31:17 2025 daemon.debug pppd[7410]: rcvd [LCP EchoRep id=0xb magic=0x2585f696]
Fri Sep 19 21:31:18 2025 daemon.debug pppd[7410]: sent [CHAP Challenge id=0x28 <6f49da8312213031fbb27421f89b9586f54c33b6ba91fd>, name = "OpenWrt"]
Fri Sep 19 21:31:18 2025 daemon.debug pppd[7410]: sent [LCP EchoReq id=0xc magic=0xc12e9ed8]
Fri Sep 19 21:31:18 2025 daemon.debug pppd[7410]: rcvd [LCP EchoRep id=0xc magic=0x2585f696]
Fri Sep 19 21:31:19 2025 daemon.debug pppd[7410]: sent [LCP EchoReq id=0xd magic=0xc12e9ed8]
Fri Sep 19 21:31:19 2025 daemon.debug pppd[7410]: rcvd [LCP EchoRep id=0xd magic=0x2585f696]
Fri Sep 19 21:31:20 2025 daemon.debug pppd[7410]: sent [LCP EchoReq id=0xe magic=0xc12e9ed8]
Fri Sep 19 21:31:20 2025 daemon.debug pppd[7410]: rcvd [LCP EchoRep id=0xe magic=0x2585f696]
Fri Sep 19 21:31:21 2025 daemon.debug pppd[7410]: sent [CHAP Challenge id=0x28 <6f49da8312213031fbb27421f89b9586f54c33b6ba91fd>, name = "OpenWrt"]
Fri Sep 19 21:31:21 2025 daemon.debug pppd[7410]: sent [LCP EchoReq id=0xf magic=0xc12e9ed8]
Fri Sep 19 21:31:21 2025 daemon.debug pppd[7410]: rcvd [LCP EchoRep id=0xf magic=0x2585f696]
Fri Sep 19 21:31:22 2025 daemon.debug pppd[7410]: sent [LCP EchoReq id=0x10 magic=0xc12e9ed8]
Fri Sep 19 21:31:22 2025 daemon.debug pppd[7410]: rcvd [LCP EchoRep id=0x10 magic=0x2585f696]
Fri Sep 19 21:31:23 2025 daemon.debug pppd[7410]: sent [LCP EchoReq id=0x11 magic=0xc12e9ed8]
Fri Sep 19 21:31:23 2025 daemon.debug pppd[7410]: rcvd [LCP EchoRep id=0x11 magic=0x2585f696]
Fri Sep 19 21:31:24 2025 daemon.debug pppd[7410]: sent [CHAP Challenge id=0x28 <6f49da8312213031fbb27421f89b9586f54c33b6ba91fd>, name = "OpenWrt"]
Fri Sep 19 21:31:24 2025 daemon.debug pppd[7410]: sent [LCP EchoReq id=0x12 magic=0xc12e9ed8]
Fri Sep 19 21:31:24 2025 daemon.debug pppd[7410]: rcvd [LCP EchoRep id=0x12 magic=0x2585f696]
Fri Sep 19 21:31:25 2025 daemon.debug pppd[7410]: sent [LCP EchoReq id=0x13 magic=0xc12e9ed8]
Fri Sep 19 21:31:25 2025 daemon.debug pppd[7410]: rcvd [LCP EchoRep id=0x13 magic=0x2585f696]
Fri Sep 19 21:31:26 2025 daemon.debug pppd[7410]: sent [LCP EchoReq id=0x14 magic=0xc12e9ed8]
Fri Sep 19 21:31:26 2025 daemon.debug pppd[7410]: rcvd [LCP EchoRep id=0x14 magic=0x2585f696]
Fri Sep 19 21:31:26 2025 daemon.debug pppd[7410]: rcvd [CHAP Challenge id=0x3 <c69e970c8b12883956bf7d9e797621fd>, name = "KonASR-HX-2"]
Fri Sep 19 21:31:26 2025 daemon.debug pppd[7410]: sent [CHAP Response id=0x3 <0cb32669d072a27041fbf8d7adc823e6>, name = "XXXXX"]
Fri Sep 19 21:31:27 2025 daemon.debug pppd[7410]: sent [CHAP Challenge id=0x28 <6f49da8312213031fbb27421f89b9586f54c33b6ba91fd>, name = "OpenWrt"]
Fri Sep 19 21:31:27 2025 daemon.debug pppd[7410]: sent [LCP EchoReq id=0x15 magic=0xc12e9ed8]
Fri Sep 19 21:31:27 2025 daemon.debug pppd[7410]: rcvd [LCP EchoRep id=0x15 magic=0x2585f696]
Fri Sep 19 21:31:28 2025 daemon.debug pppd[7410]: sent [LCP EchoReq id=0x16 magic=0xc12e9ed8]
Fri Sep 19 21:31:28 2025 daemon.debug pppd[7410]: rcvd [LCP EchoRep id=0x16 magic=0x2585f696]
Fri Sep 19 21:31:29 2025 daemon.debug pppd[7410]: sent [LCP EchoReq id=0x17 magic=0xc12e9ed8]
Fri Sep 19 21:31:29 2025 daemon.debug pppd[7410]: rcvd [LCP EchoRep id=0x17 magic=0x2585f696]
Fri Sep 19 21:31:30 2025 daemon.debug pppd[7410]: sent [CHAP Challenge id=0x28 <6f49da8312213031fbb27421f89b9586f54c33b6ba91fd>, name = "OpenWrt"]
Fri Sep 19 21:31:30 2025 daemon.debug pppd[7410]: sent [LCP EchoReq id=0x18 magic=0xc12e9ed8]
Fri Sep 19 21:31:30 2025 daemon.debug pppd[7410]: rcvd [LCP EchoRep id=0x18 magic=0x2585f696]
Fri Sep 19 21:31:31 2025 daemon.debug pppd[7410]: sent [LCP EchoReq id=0x19 magic=0xc12e9ed8]
Fri Sep 19 21:31:31 2025 daemon.debug pppd[7410]: rcvd [LCP EchoRep id=0x19 magic=0x2585f696]
Fri Sep 19 21:31:32 2025 daemon.debug pppd[7410]: sent [LCP EchoReq id=0x1a magic=0xc12e9ed8]
Fri Sep 19 21:31:32 2025 daemon.debug pppd[7410]: rcvd [LCP EchoRep id=0x1a magic=0x2585f696]
Fri Sep 19 21:31:33 2025 daemon.debug pppd[7410]: sent [CHAP Challenge id=0x28 <6f49da8312213031fbb27421f89b9586f54c33b6ba91fd>, name = "OpenWrt"]
Fri Sep 19 21:31:33 2025 daemon.debug pppd[7410]: sent [LCP EchoReq id=0x1b magic=0xc12e9ed8]
Fri Sep 19 21:31:33 2025 daemon.debug pppd[7410]: rcvd [LCP EchoRep id=0x1b magic=0x2585f696]
Fri Sep 19 21:31:34 2025 daemon.debug pppd[7410]: sent [LCP EchoReq id=0x1c magic=0xc12e9ed8]
Fri Sep 19 21:31:34 2025 daemon.debug pppd[7410]: rcvd [LCP EchoRep id=0x1c magic=0x2585f696]
Fri Sep 19 21:31:35 2025 daemon.debug pppd[7410]: sent [LCP EchoReq id=0x1d magic=0xc12e9ed8]
Fri Sep 19 21:31:35 2025 daemon.debug pppd[7410]: rcvd [LCP EchoRep id=0x1d magic=0x2585f696]
Fri Sep 19 21:31:36 2025 daemon.debug pppd[7410]: sent [LCP TermReq id=0x2 "Authentication failed"]
Fri Sep 19 21:31:36 2025 daemon.debug pppd[7410]: rcvd [LCP TermAck id=0x2]
Fri Sep 19 21:31:36 2025 daemon.notice pppd[7410]: Connection terminated.
Fri Sep 19 21:31:36 2025 daemon.debug pppd[7410]: Send PPPOE Discovery V1T1 PADT session 0x93b3 length 28
Fri Sep 19 21:31:36 2025 daemon.debug pppd[7410]:  dst 60:26:aa:27:b7:c0  src b2:18:f8:23:8c:d5
Fri Sep 19 21:31:36 2025 daemon.debug pppd[7410]:  [host-uniq f2 1c 00 00] [AC-cookie ad 7d de a1 50 d7 ef 85 c6 bf 63 39 2f 54 0d 02]
Fri Sep 19 21:31:36 2025 daemon.info pppd[7410]: Sent PADT
Fri Sep 19 21:31:36 2025 daemon.info pppd[7410]: Exit.
Fri Sep 19 21:31:36 2025 daemon.notice netifd: Interface 'wan' is now down

Przeanalizuję logi i wrzucę po pracy.

Edit: Dorzuciłem logi.

10 Odpowiedź przez nicefile

  • Zarejestrowany: 2018-12-05
  • Posty: 58

Odp: [OpenWrt] Problem z autentykacją PPPoE

to inaczej. Podsłuchaj cały ruch przez tcpdump między ONT a WR1034ND v4.0 w trakcie spinania sesji pppoe. Potem  to samo na OpenWrt. Wyślij to tutaj i na forum OpenWrt.
Podsłuchanie powinno być łatwe przy użyciu portu LAN4 i LAN3 na BPI-R4 bo to oddzielne karty sieciowe których ruch musi iść przez CPU. Ew. port mirroring lub nawet hub (nie switch) 10Mbit/100Mbit.