Sergey
January 28, 2020, 5:18pm
1
Hello. I can’t connect to internet provider with PPPoE. I flashed last medkit, but it not helped. Phisically port fine, I tried DHCP connection on this port, and it works. Providers line also fine, raspberry pi and notebook can connect internet with this line. I cannot find out what’s the problem. Is there some tool like pppoeconf or something similar to debug connection ?
the system log prints related messages as start point for debugging, e.g. from the ssh cli logread | grep ppp
.
Advanced verbosity can be achieved with enabling the debug
flag in /etc/ppp/options
Sergey
January 28, 2020, 8:36pm
3
Thank you for answer. I enabled debug flag. Seems router can’t recieve PADO packets, like link is not connected. I checked connecton. What else I should check ?
Jan 28 20:21:06 turris pppd[11131]: Plugin rp-pppoe.so loaded.
Jan 28 20:21:06 turris pppd[11131]: RP-PPPoE plugin version 3.8p compiled against pppd 2.4.7
Jan 28 20:21:06 turris pppd[11131]: pppd 2.4.7 started by root, uid 0
Jan 28 20:21:06 turris pppd[11131]: Send PPPOE Discovery V1T1 PADI session 0x0 length 4
Jan 28 20:21:06 turris pppd[11131]: dst ff:ff:ff:ff:ff:ff src d8:58:d7:00:1d:3b
Jan 28 20:21:06 turris pppd[11131]: [service-name]
Jan 28 20:21:11 turris pppd[11131]: Send PPPOE Discovery V1T1 PADI session 0x0 length 4
Jan 28 20:21:11 turris pppd[11131]: dst ff:ff:ff:ff:ff:ff src d8:58:d7:00:1d:3b
Jan 28 20:21:11 turris pppd[11131]: [service-name]
Jan 28 20:21:12 turris kresd[3553]: [priming] cannot resolve ‘.’ NS, next priming query in 10 seconds
Jan 28 20:21:16 turris pppd[11131]: Send PPPOE Discovery V1T1 PADI session 0x0 length 4
Jan 28 20:21:16 turris pppd[11131]: dst ff:ff:ff:ff:ff:ff src d8:58:d7:00:1d:3b
Jan 28 20:21:16 turris pppd[11131]: [service-name]
Jan 28 20:21:21 turris pppd[11131]: Timeout waiting for PADO packets
Jan 28 20:21:21 turris pppd[11131]: Unable to complete PPPoE Discovery
Jan 28 20:21:21 turris pppd[11131]: Exit.
Jan 28 20:21:21 turris netifd: Interface ‘wan’ is now down
Jan 28 20:21:21 turris netifd: Interface ‘wan’ is disabled
Would not reckon this is an issue of not being able to receive PADO packets but rather the packets going upstream not being received by the ISP in the first place and subsequent no PADO packets returned.
What is the setup for upstream connectivity, e.g.
ISP -> modem > TO?
which modem?
does the ISP require VLAN ID tagging?
What is the output from (redact/obfuscate whatever you feel necessary):
ifstatus wan
(ipv4)
ifstatus wan_6
(ipv6 if applciable)
ifstatus wan_6_4
(ds-lite if applicable)
Sergey
January 31, 2020, 12:33pm
5
I contacted my ISP support once again. Technician came and we tried to plug router beside line, that come to apartment. Connection established only with 8-wired cable. Technitian upgraded line to apartment, and now connection works.
Some glitches are still present. Sometimes router not establish connection like before, despite wan led is flasing.
Jan 31 12:04:15 turris netifd: Network device ‘eth2’ link is up
Jan 31 12:04:15 turris netifd: Interface ‘wan’ has link connectivity
Jan 31 12:04:15 turris netifd: Interface ‘wan’ is setting up now
Jan 31 15:04:15 turris kernel: [ 301.578763] mvneta f1034000.ethernet eth2: Link is Up - 1Gbps/Full - flow control off
Jan 31 12:04:17 turris pppd[9735]: Send PPPOE Discovery V1T1 PADI session 0x0 length 4
Jan 31 12:04:17 turris pppd[9735]: dst ff:ff:ff:ff:ff:ff src d8:58:d7:00:1d:3b
Jan 31 12:04:17 turris pppd[9735]: [service-name]
Jan 31 12:04:18 turris netifd: Interface ‘wan’ is now down
Jan 31 15:04:18 turris kernel: [ 304.514152] mvneta f1034000.ethernet eth2: Link is Down
Jan 31 15:04:18 turris kernel: [ 304.527163] IPv6: ADDRCONF(NETDEV_UP): eth2: link is not ready
Jan 31 12:04:18 turris netifd: Interface ‘wan’ is disabled
Jan 31 15:04:18 turris kernel: [ 304.629011] mvneta f1034000.ethernet eth2: PHY [f1072004.mdio-mii:01] driver [Marvell 88E1510]
Jan 31 15:04:18 turris kernel: [ 304.637965] mvneta f1034000.ethernet eth2: configuring for phy/sgmii link mode
Jan 31 15:04:18 turris kernel: [ 304.645385] mvneta f1034000.ethernet eth2: Link is Up - 1Gbps/Full - flow control off
Jan 31 12:04:18 turris netifd: Interface ‘wan’ is enabled
Jan 31 12:04:18 turris netifd: Interface ‘wan’ is setting up now
Jan 31 12:04:18 turris insmod: module is already loaded - slhc
Jan 31 12:04:18 turris insmod: module is already loaded - ppp_generic
Jan 31 12:04:18 turris insmod: module is already loaded - pppox
Jan 31 12:04:18 turris insmod: module is already loaded - pppoe
Jan 31 12:04:18 turris pppd[9945]: Plugin rp-pppoe.so loaded.
Jan 31 12:04:18 turris pppd[9945]: RP-PPPoE plugin version 3.8p compiled against pppd 2.4.7
Jan 31 12:04:18 turris pppd[9945]: pppd 2.4.7 started by root, uid 0
Jan 31 12:04:18 turris pppd[9945]: Send PPPOE Discovery V1T1 PADI session 0x0 length 4
Jan 31 12:04:18 turris pppd[9945]: dst ff:ff:ff:ff:ff:ff src d8:58:d7:00:1d:3b
Jan 31 12:04:18 turris pppd[9945]: [service-name]
Jan 31 15:04:19 turris kernel: [ 305.821882] mvneta f1034000.ethernet eth2: Link is Down
Jan 31 12:04:19 turris netifd: Network device ‘eth2’ link is down
Jan 31 12:04:19 turris netifd: Interface ‘wan’ has link connectivity loss
Jan 31 12:04:21 turris netifd: Network device ‘eth2’ link is up
Jan 31 12:04:21 turris netifd: Interface ‘wan’ has link connectivity
Jan 31 12:04:21 turris netifd: Interface ‘wan’ is setting up now
Jan 31 15:04:21 turris kernel: [ 307.898501] mvneta f1034000.ethernet eth2: Link is Up - 1Gbps/Full - flow control off
Jan 31 12:04:23 turris pppd[9945]: Send PPPOE Discovery V1T1 PADI session 0x0 length 4
Jan 31 12:04:23 turris pppd[9945]: dst ff:ff:ff:ff:ff:ff src d8:58:d7:00:1d:3b
Jan 31 12:04:23 turris pppd[9945]: [service-name]
But mostly connection successful
Jan 31 12:09:10 turris pppd[7408]: Timeout waiting for PADO packets
Jan 31 12:09:10 turris pppd[7408]: Unable to complete PPPoE Discovery
Jan 31 12:09:10 turris pppd[7408]: Exit.
Jan 31 12:09:10 turris netifd: Interface ‘wan’ is now down
Jan 31 15:09:10 turris kernel: [ 69.819955] mvneta f1034000.ethernet eth2: Link is Down
Jan 31 12:09:10 turris netifd: Interface ‘wan’ is disabled
Jan 31 15:09:10 turris kernel: [ 69.833155] IPv6: ADDRCONF(NETDEV_UP): eth2: link is not ready
Jan 31 15:09:10 turris kernel: [ 69.932090] mvneta f1034000.ethernet eth2: PHY [f1072004.mdio-mii:01] driver [Marvell 88E1510]
Jan 31 15:09:10 turris kernel: [ 69.941535] mvneta f1034000.ethernet eth2: configuring for phy/sgmii link mode
Jan 31 15:09:10 turris kernel: [ 69.948878] mvneta f1034000.ethernet eth2: Link is Up - 1Gbps/Full - flow control off
Jan 31 12:09:10 turris netifd: Interface ‘wan’ is enabled
Jan 31 12:09:10 turris netifd: Interface ‘wan’ is setting up now
Jan 31 12:09:10 turris insmod: module is already loaded - slhc
Jan 31 12:09:10 turris insmod: module is already loaded - ppp_generic
Jan 31 12:09:10 turris insmod: module is already loaded - pppox
Jan 31 12:09:10 turris insmod: module is already loaded - pppoe
Jan 31 12:09:10 turris pppd[7626]: Plugin rp-pppoe.so loaded.
Jan 31 12:09:10 turris pppd[7626]: RP-PPPoE plugin version 3.8p compiled against pppd 2.4.7
Jan 31 12:09:10 turris pppd[7626]: pppd 2.4.7 started by root, uid 0
Jan 31 12:09:10 turris pppd[7626]: Send PPPOE Discovery V1T1 PADI session 0x0 length 4
Jan 31 12:09:10 turris pppd[7626]: dst ff:ff:ff:ff:ff:ff src d8:58:d7:00:1d:3b
Jan 31 12:09:10 turris pppd[7626]: [service-name]
Jan 31 12:09:10 turris kresd[7637]: [priming] cannot resolve ‘.’ NS, next priming query in 10 seconds
Jan 31 12:09:10 turris kresd[7637]: [detect_time_skew] cannot resolve ‘.’ NS
Jan 31 15:09:11 turris kernel: [ 71.114860] mvneta f1034000.ethernet eth2: Link is Down
Jan 31 12:09:11 turris netifd: Network device ‘eth2’ link is down
Jan 31 12:09:11 turris netifd: Interface ‘wan’ has link connectivity loss
Jan 31 12:09:13 turris netifd: Network device ‘eth2’ link is up
Jan 31 12:09:13 turris netifd: Interface ‘wan’ has link connectivity
Jan 31 15:09:13 turris kernel: [ 73.191676] mvneta f1034000.ethernet eth2: Link is Up - 1Gbps/Full - flow control off
Jan 31 12:09:15 turris pppd[7626]: Send PPPOE Discovery V1T1 PADI session 0x0 length 4
Jan 31 12:09:15 turris pppd[7626]: dst ff:ff:ff:ff:ff:ff src d8:58:d7:00:1d:3b
Jan 31 12:09:15 turris pppd[7626]: [service-name]
Jan 31 12:09:20 turris kresd[7637]: [ ta ] active refresh failed for . with rcode: 2
Jan 31 12:09:20 turris kresd[7637]: [ ta ] next refresh for . in 1 hours
Jan 31 12:09:20 turris pppd[7626]: Send PPPOE Discovery V1T1 PADI session 0x0 length 4
Jan 31 12:09:20 turris pppd[7626]: dst ff:ff:ff:ff:ff:ff src d8:58:d7:00:1d:3b
Jan 31 12:09:20 turris pppd[7626]: [service-name]
Jan 31 12:09:20 turris kresd[7637]: [priming] cannot resolve ‘.’ NS, next priming query in 10 seconds
Jan 31 12:09:25 turris pppd[7626]: Timeout waiting for PADO packets
Jan 31 12:09:25 turris pppd[7626]: Unable to complete PPPoE Discovery
Jan 31 12:09:25 turris pppd[7626]: Exit.
Jan 31 12:09:25 turris netifd: Interface ‘wan’ is now down
Jan 31 15:09:25 turris kernel: [ 85.219363] mvneta f1034000.ethernet eth2: Link is Down
Jan 31 12:09:25 turris netifd: Interface ‘wan’ is disabled
Jan 31 15:09:25 turris kernel: [ 85.232784] IPv6: ADDRCONF(NETDEV_UP): eth2: link is not ready
Jan 31 15:09:25 turris kernel: [ 85.332033] mvneta f1034000.ethernet eth2: PHY [f1072004.mdio-mii:01] driver [Marvell 88E1510]
Jan 31 15:09:25 turris kernel: [ 85.341644] mvneta f1034000.ethernet eth2: configuring for phy/sgmii link mode
Jan 31 15:09:25 turris kernel: [ 85.351359] mvneta f1034000.ethernet eth2: Link is Up - 1Gbps/Full - flow control off
Jan 31 12:09:25 turris netifd: Interface ‘wan’ is enabled
Jan 31 12:09:25 turris netifd: Interface ‘wan’ is setting up now
Jan 31 12:09:25 turris insmod: module is already loaded - slhc
Jan 31 12:09:25 turris insmod: module is already loaded - ppp_generic
Jan 31 12:09:25 turris insmod: module is already loaded - pppox
Jan 31 12:09:25 turris insmod: module is already loaded - pppoe
Jan 31 12:09:25 turris pppd[7853]: Plugin rp-pppoe.so loaded.
Jan 31 12:09:25 turris pppd[7853]: RP-PPPoE plugin version 3.8p compiled against pppd 2.4.7
Jan 31 12:09:25 turris pppd[7853]: pppd 2.4.7 started by root, uid 0
Jan 31 12:09:25 turris pppd[7853]: Send PPPOE Discovery V1T1 PADI session 0x0 length 4
Jan 31 12:09:25 turris pppd[7853]: dst ff:ff:ff:ff:ff:ff src d8:58:d7:00:1d:3b
Jan 31 12:09:25 turris pppd[7853]: [service-name]
Jan 31 12:09:25 turris kresd[7858]: [priming] cannot resolve ‘.’ NS, next priming query in 10 seconds
Jan 31 12:09:25 turris kresd[7858]: [detect_time_skew] cannot resolve ‘.’ NS
Jan 31 15:09:26 turris kernel: [ 86.474855] mvneta f1034000.ethernet eth2: Link is Down
Jan 31 12:09:26 turris netifd: Network device ‘eth2’ link is down
Jan 31 12:09:26 turris netifd: Interface ‘wan’ has link connectivity loss
Jan 31 12:09:28 turris netifd: Network device ‘eth2’ link is up
Jan 31 12:09:28 turris netifd: Interface ‘wan’ has link connectivity
Jan 31 15:09:28 turris kernel: [ 88.551664] mvneta f1034000.ethernet eth2: Link is Up - 1Gbps/Full - flow control off
Jan 31 12:09:30 turris pppd[7853]: Send PPPOE Discovery V1T1 PADI session 0x0 length 4
Jan 31 12:09:30 turris pppd[7853]: dst ff:ff:ff:ff:ff:ff src d8:58:d7:00:1d:3b
Jan 31 12:09:30 turris pppd[7853]: [service-name]
Jan 31 12:09:35 turris kresd[7858]: [ ta ] active refresh failed for . with rcode: 2
Jan 31 12:09:35 turris kresd[7858]: [ ta ] next refresh for . in 1 hours
Jan 31 12:09:35 turris pppd[7853]: Send PPPOE Discovery V1T1 PADI session 0x0 length 4
Jan 31 12:09:35 turris pppd[7853]: dst ff:ff:ff:ff:ff:ff src d8:58:d7:00:1d:3b
Jan 31 12:09:35 turris pppd[7853]: [service-name]
Jan 31 12:09:35 turris kresd[7858]: [priming] cannot resolve ‘.’ NS, next priming query in 10 seconds
Jan 31 12:09:36 turris pppd[7853]: Recv PPPOE Discovery V1T1 PADO session 0x0 length 34
Jan 31 12:09:36 turris pppd[7853]: dst d8:58:d7:00:1d:3b src 00:00:5e:00:01:82
Jan 31 12:09:36 turris pppd[7853]: [service-name] [AC-name DOM.RU ] [AC-cookie f8 6f bd d0 79 da 63 f4 48 22 dc 6c c3 26 3b c3]
Jan 31 12:09:36 turris pppd[7853]: Send PPPOE Discovery V1T1 PADR session 0x0 length 24
Jan 31 12:09:36 turris pppd[7853]: dst 00:00:5e:00:01:82 src d8:58:d7:00:1d:3b
Jan 31 12:09:36 turris pppd[7853]: [service-name] [AC-cookie f8 6f bd d0 79 da 63 f4 48 22 dc 6c c3 26 3b c3]
Jan 31 12:09:36 turris pppd[7853]: Recv PPPOE Discovery V1T1 PADS session 0x1 length 4
Jan 31 12:09:36 turris pppd[7853]: dst d8:58:d7:00:1d:3b src 00:00:5e:00:01:82
Jan 31 12:09:36 turris pppd[7853]: [service-name]
Jan 31 12:09:36 turris pppd[7853]: PADS: Service-Name: ‘’
Jan 31 12:09:36 turris pppd[7853]: PPP session is 1
Jan 31 12:09:36 turris pppd[7853]: Connected to 00:00:5e:00:01:82 via interface eth2
Jan 31 12:09:36 turris pppd[7853]: using channel 1
Jan 31 15:09:36 turris kernel: [ 95.804141] pppoe-wan: renamed from ppp0
Jan 31 12:09:36 turris pppd[7853]: Using interface pppoe-wan
Jan 31 12:09:36 turris pppd[7853]: Connect: pppoe-wan ↔ eth2
Jan 31 12:09:36 turris pppd[7853]: sent [LCP ConfReq id=0x1 <mru 1492> <magic 0x18ac235c>]
Jan 31 12:09:36 turris pppd[7853]: rcvd [LCP ConfReq id=0xf9 <mru 1492> <magic 0x38ea83cd>]
Jan 31 12:09:36 turris pppd[7853]: sent [LCP ConfAck id=0xf9 <mru 1492> <magic 0x38ea83cd>]
Jan 31 12:09:36 turris pppd[7853]: rcvd [LCP ConfAck id=0x1 <mru 1492> <magic 0x18ac235c>]
Jan 31 12:09:36 turris pppd[7853]: rcvd [CHAP Challenge id=0x1 <739b8de8d466b3e9c9d4407a5cc35ca896b90f8ad40d8de2fbdcf5dcdb23664fbff3b714da6cfea441be1f9d83fac699b5d5>, name = “bsr01-nn”]
Jan 31 12:09:36 turris pppd[7853]: sent [CHAP Response id=0x1 , name = “v03458135”]
Jan 31 12:09:36 turris pppd[7853]: rcvd [CHAP Success id=0x1 “(balance=3027.42) Ok, \320\275\320\270\321\207\320\265\320\263\320\276 \321\201 \320\275\320\270\320\274 \320\264\320\265\320\273\320\260\321\202\321\214 \320\275\320\265 \320\275\320\260\320\264\320\276.”]
Jan 31 12:09:36 turris pppd[7853]: CHAP authentication succeeded: (balance=3027.42) Ok, M-PM-=M-PM-8M-QM-^GM-PM-5M-PM-3M-PM-> M-QM-^A M-PM-=M-PM-8M-PM-< M-PM-4M-PM-5M-PM-;M-PM-0M-QM-^BM-QM-^L M-PM-=M-PM-5 M-PM-=M-PM-0M-PM-4M-PM->.
Jan 31 12:09:36 turris pppd[7853]: CHAP authentication succeeded
Jan 31 12:09:36 turris pppd[7853]: peer from calling number 00:00:5E:00:01:82 authorized
Jan 31 12:09:36 turris pppd[7853]: sent [IPCP ConfReq id=0x1 <addr 0.0.0.0> <ms-dns1 0.0.0.0> <ms-dns2 0.0.0.0>]
Jan 31 12:09:36 turris pppd[7853]: rcvd [IPCP ConfReq id=0x3 <addr 100.76.191.254>]
Jan 31 12:09:36 turris pppd[7853]: sent [IPCP ConfAck id=0x3 <addr 100.76.191.254>]
Jan 31 12:09:36 turris pppd[7853]: rcvd [IPCP ConfNak id=0x1 <addr 5.164.251.190> <ms-dns1 91.144.186.3> <ms-dns2 5.3.3.3>]
Jan 31 12:09:36 turris pppd[7853]: sent [IPCP ConfReq id=0x2 <addr 5.164.251.190> <ms-dns1 91.144.186.3> <ms-dns2 5.3.3.3>]
Jan 31 12:09:36 turris pppd[7853]: rcvd [IPCP ConfAck id=0x2 <addr 5.164.251.190> <ms-dns1 91.144.186.3> <ms-dns2 5.3.3.3>]
Jan 31 12:09:36 turris pppd[7853]: local IP address 5.164.251.190
Jan 31 12:09:36 turris pppd[7853]: remote IP address 100.76.191.254
Jan 31 12:09:36 turris pppd[7853]: primary DNS address 91.144.186.3
Jan 31 12:09:36 turris pppd[7853]: secondary DNS address 5.3.3.3
Jan 31 12:09:36 turris pppd[7853]: Script /lib/netifd/ppp-up started (pid 8189)
Jan 31 12:09:36 turris netifd: Network device ‘pppoe-wan’ link is up
Jan 31 12:09:36 turris netifd: Interface ‘wan’ is now up
Jan 31 12:09:36 turris pppd[7853]: Script /lib/netifd/ppp-up finished (pid 8189), status = 0x1
Jan 31 12:09:36 turris firewall: Reloading firewall due to ifup of wan (pppoe-wan)
Jan 31 12:09:48 turris kresd[8313]: [ ta ] key: 20326 state: Valid
Jan 31 12:09:48 turris kresd[8313]: [ ta ] next refresh for . in 1.4718055555556 hours
If option foce link is enabled connection more often succeed. Glitches not big problem.