Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

kernel: 5.4.68 编译的固件PPPoE频繁断连 #5599

Closed
1 task done
Ljzkirito opened this issue Oct 4, 2020 · 22 comments · Fixed by #5605
Closed
1 task done

kernel: 5.4.68 编译的固件PPPoE频繁断连 #5599

Ljzkirito opened this issue Oct 4, 2020 · 22 comments · Fixed by #5605

Comments

@Ljzkirito
Copy link
Contributor

Ljzkirito commented Oct 4, 2020

Q:是否搜索了issue (使用 "x" 选择)

  • 没有类似的issue

2. 详细叙述

(1) 具体问题

A:昨天更新源码后,保留配置升级,PPPoE频繁断连,关闭Flow Offloading没有效果,自己Fork到自己仓库,Revert "kernel: bump 5.4 to 5.4.68 (#5555)"后正常。

(2) 路由器型号和固件版本

A:XY-C5,最新源码,Github Actions编译

(3) 详细日志

A:在18:00时,概览虽显示PPPoE已连接,但此时已经断网

Sun Oct 4 12:26:38 2020 kern.notice kernel: [ 0.000000] Linux version 5.4.68 (Ljzkirito@GitHub Actions) (gcc version 8.4.0 (OpenWrt GCC 8.4.0 r0-71e335c)) #0 SMP Fri Oct 2 16:41:31 2020

Sun Oct 4 12:26:44 2020 daemon.notice netifd: Network device 'wan' link is up
Sun Oct 4 12:26:44 2020 daemon.notice netifd: Interface 'wan' has link connectivity
Sun Oct 4 12:26:44 2020 daemon.notice netifd: Interface 'wan' is setting up now
Sun Oct 4 12:26:44 2020 daemon.err insmod: module is already loaded - slhc
Sun Oct 4 12:26:44 2020 daemon.err insmod: module is already loaded - ppp_generic
Sun Oct 4 12:26:44 2020 daemon.err insmod: module is already loaded - pppox
Sun Oct 4 12:26:44 2020 daemon.err insmod: module is already loaded - pppoe
Sun Oct 4 12:26:44 2020 daemon.notice netifd: wan (2556): uci: Entry not found
Sun Oct 4 12:26:44 2020 daemon.notice netifd: wan (2556): sh: out of range
Sun Oct 4 12:26:44 2020 daemon.info pppd[2566]: Plugin rp-pppoe.so loaded.
Sun Oct 4 12:26:44 2020 daemon.info pppd[2566]: RP-PPPoE plugin version 3.8p compiled against pppd 2.4.8
Sun Oct 4 12:26:44 2020 daemon.notice pppd[2566]: pppd 2.4.8 started by root, uid 0
Sun Oct 4 12:26:45 2020 kern.info kernel: [ 66.689886] mt7530 mdio-bus:1f lan3: Link is Up - 1Gbps/Full - flow control rx/tx
Sun Oct 4 12:26:45 2020 kern.info kernel: [ 66.704855] br-lan: port 3(lan3) entered blocking state
Sun Oct 4 12:26:45 2020 kern.info kernel: [ 66.715279] br-lan: port 3(lan3) entered forwarding state
Sun Oct 4 12:26:45 2020 daemon.notice netifd: Network device 'lan3' link is up
Sun Oct 4 12:26:45 2020 kern.info kernel: [ 66.726935] IPv6: ADDRCONF(NETDEV_CHANGE): br-lan: link becomes ready
Sun Oct 4 12:26:45 2020 daemon.notice netifd: bridge 'br-lan' link is up
Sun Oct 4 12:26:45 2020 daemon.notice netifd: Interface 'lan' has link connectivity
Sun Oct 4 12:26:45 2020 kern.info kernel: [ 67.521894] mt7530 mdio-bus:1f lan1: Link is Up - 1Gbps/Full - flow control off
Sun Oct 4 12:26:45 2020 kern.info kernel: [ 67.536569] br-lan: port 1(lan1) entered blocking state
Sun Oct 4 12:26:45 2020 kern.info kernel: [ 67.546997] br-lan: port 1(lan1) entered forwarding state
Sun Oct 4 12:26:45 2020 daemon.notice netifd: Network device 'lan1' link is up
Sun Oct 4 12:26:46 2020 daemon.err odhcpd[1605]: Failed to send to ff02::1%lan@br-lan (Address not available)
Sun Oct 4 12:26:46 2020 daemon.info dnsmasq[2596]: Connected to system UBus
Sun Oct 4 12:26:46 2020 daemon.notice procd: /etc/rc.d/S60flowoffload: udhcpc: no lease, failing
Sun Oct 4 12:26:46 2020 daemon.notice procd: /etc/rc.d/S60flowoffload: DNSMASQ change
Sun Oct 4 12:26:47 2020 daemon.err dnscache[2715]: * 10/04 12:26:47| pdnsd: info: pdnsd-1.2.9b-par starting.
Sun Oct 4 12:26:47 2020 daemon.notice procd: /etc/rc.d/S90kms: KMS Server has started.
Sun Oct 4 12:26:49 2020 daemon.info pppd[2566]: PPP session is 17311
Sun Oct 4 12:26:49 2020 daemon.warn pppd[2566]: Connected to 00:00:5e:00:01:46 via interface wan
Sun Oct 4 12:26:49 2020 kern.info kernel: [ 71.114017] pppoe-wan: renamed from ppp0
Sun Oct 4 12:26:49 2020 daemon.info pppd[2566]: Renamed interface ppp0 to pppoe-wan
Sun Oct 4 12:26:49 2020 daemon.info pppd[2566]: Using interface pppoe-wan
Sun Oct 4 12:26:49 2020 daemon.notice pppd[2566]: Connect: pppoe-wan <--> wan
Sun Oct 4 12:26:52 2020 daemon.info pppd[2566]: syncppp not active
Sun Oct 4 12:26:57 2020 user.notice firewall: Reloading firewall due to ifup of lan (br-lan)
Sun Oct 4 12:26:57 2020 user.notice upnp daemon: external interface not found, not starting
Sun Oct 4 12:27:18 2020 cron.err crond[5617]: crond (busybox 1.31.1) started, log level 5
Sun Oct 4 12:27:19 2020 daemon.notice procd: /etc/rc.d/S97unblockmusic: ipset v7.4: Missing second mandatory argument to command add
Sun Oct 4 12:27:19 2020 daemon.notice procd: /etc/rc.d/S97unblockmusic: Try `ipset help' for more information.
Sun Oct 4 12:27:30 2020 daemon.info procd: - init complete -
Sun Oct 4 12:27:30 2020 daemon.info urandom_seed[6280]: Seed saved (/etc/urandom.seed)
Sun Oct 4 12:27:40 2020 daemon.info dnsmasq[6558]: Connected to system UBus
Sun Oct 4 12:27:52 2020 daemon.info pppd[2566]: LCP terminated by peer
Sun Oct 4 12:27:52 2020 daemon.notice pppd[2566]: Modem hangup
Sun Oct 4 12:27:52 2020 daemon.notice pppd[2566]: Connection terminated.
Sun Oct 4 12:27:52 2020 daemon.info pppd[2566]: Sent PADT
Sun Oct 4 12:27:52 2020 daemon.info pppd[2566]: Exit.
Sun Oct 4 12:27:52 2020 daemon.notice netifd: Interface 'wan' is now down
Sun Oct 4 12:27:52 2020 kern.info kernel: [ 134.319294] mt7530 mdio-bus:1f wan: Link is Down
Sun Oct 4 12:27:52 2020 daemon.notice netifd: Interface 'wan' is disabled
Sun Oct 4 12:27:52 2020 kern.info kernel: [ 134.355683] mt7530 mdio-bus:1f wan: configuring for phy/gmii link mode
Sun Oct 4 12:27:52 2020 kern.info kernel: [ 134.369341] 8021q: adding VLAN 0 to HW filter on device wan
Sun Oct 4 12:27:52 2020 daemon.notice netifd: Interface 'wan' is enabled
Sun Oct 4 12:27:52 2020 daemon.notice netifd: Interface 'wan' is setting up now
Sun Oct 4 12:27:52 2020 daemon.notice netifd: Network device 'wan' link is down
Sun Oct 4 12:27:52 2020 daemon.notice netifd: Interface 'wan' has link connectivity loss
Sun Oct 4 12:27:52 2020 daemon.notice netifd: Interface 'wan' is now down
Sun Oct 4 12:27:52 2020 daemon.notice netifd: Interface 'wan' is disabled
Sun Oct 4 12:27:52 2020 kern.info kernel: [ 134.506292] mt7530 mdio-bus:1f wan: configuring for phy/gmii link mode
Sun Oct 4 12:27:52 2020 kern.info kernel: [ 134.519836] 8021q: adding VLAN 0 to HW filter on device wan
Sun Oct 4 12:27:52 2020 daemon.notice netifd: Interface 'wan' is enabled
Sun Oct 4 12:27:54 2020 cron.err crond[7251]: crond (busybox 1.31.1) started, log level 5
Sun Oct 4 12:27:55 2020 cron.err crond[7385]: crond (busybox 1.31.1) started, log level 5
Sun Oct 4 12:27:56 2020 kern.info kernel: [ 137.602071] mt7530 mdio-bus:1f wan: Link is Up - 1Gbps/Full - flow control off
Sun Oct 4 12:27:56 2020 kern.info kernel: [ 137.624450] IPv6: ADDRCONF(NETDEV_CHANGE): wan: link becomes ready
Sun Oct 4 12:27:56 2020 daemon.notice netifd: Network device 'wan' link is up
Sun Oct 4 12:27:56 2020 daemon.notice netifd: Interface 'wan' has link connectivity
Sun Oct 4 12:27:56 2020 daemon.notice netifd: Interface 'wan' is setting up now
Sun Oct 4 12:27:56 2020 daemon.err insmod: module is already loaded - slhc
Sun Oct 4 12:27:56 2020 daemon.err insmod: module is already loaded - ppp_generic
Sun Oct 4 12:27:56 2020 daemon.err insmod: module is already loaded - pppox
Sun Oct 4 12:27:56 2020 daemon.err insmod: module is already loaded - pppoe
Sun Oct 4 12:27:56 2020 daemon.notice netifd: wan (7401): uci: Entry not found
Sun Oct 4 12:27:56 2020 daemon.notice netifd: wan (7401): sh: out of range
Sun Oct 4 12:27:56 2020 daemon.info pppd[7451]: Plugin rp-pppoe.so loaded.
Sun Oct 4 12:27:56 2020 daemon.info pppd[7451]: RP-PPPoE plugin version 3.8p compiled against pppd 2.4.8
Sun Oct 4 12:27:56 2020 daemon.notice pppd[7451]: pppd 2.4.8 started by root, uid 0
Sun Oct 4 12:27:56 2020 daemon.info pppd[7451]: PPP session is 10144
Sun Oct 4 12:27:56 2020 daemon.warn pppd[7451]: Connected to 00:00:5e:00:01:46 via interface wan
Sun Oct 4 12:27:56 2020 kern.info kernel: [ 137.971740] pppoe-wan: renamed from ppp0
Sun Oct 4 12:27:56 2020 daemon.info pppd[7451]: Renamed interface ppp0 to pppoe-wan
Sun Oct 4 12:27:56 2020 daemon.info pppd[7451]: Using interface pppoe-wan
Sun Oct 4 12:27:56 2020 daemon.notice pppd[7451]: Connect: pppoe-wan <--> wan
Sun Oct 4 12:27:59 2020 daemon.info pppd[7451]: syncppp not active
Sun Oct 4 12:27:59 2020 daemon.info pppd[7451]: CHAP authentication succeeded: Authentication success,Welcome!
Sun Oct 4 12:27:59 2020 daemon.notice pppd[7451]: CHAP authentication succeeded
Sun Oct 4 12:27:59 2020 daemon.notice pppd[7451]: peer from calling number 00:00:5E:00:01:46 authorized
Sun Oct 4 12:27:59 2020 daemon.notice pppd[7451]: local LL address fe80::ed80:d46a:1b64:e9c2
Sun Oct 4 12:27:59 2020 daemon.notice pppd[7451]: remote LL address fe80::0200:5eff:fe00:0146
Sun Oct 4 12:27:59 2020 daemon.notice pppd[7451]: local IP address 100.69.73.11
Sun Oct 4 12:27:59 2020 daemon.notice pppd[7451]: remote IP address 100.69.64.1
Sun Oct 4 12:27:59 2020 daemon.notice pppd[7451]: primary DNS address 211.137.191.26
Sun Oct 4 12:27:59 2020 daemon.notice pppd[7451]: secondary DNS address 218.201.96.130
Sun Oct 4 12:27:59 2020 daemon.notice netifd: Network device 'pppoe-wan' link is up
Sun Oct 4 12:27:59 2020 daemon.notice netifd: Interface 'wan' is now up
Sun Oct 4 12:27:59 2020 daemon.notice netifd: Network alias 'pppoe-wan' link is up
Sun Oct 4 12:27:59 2020 daemon.notice netifd: Interface 'wan_6' is enabled
Sun Oct 4 12:27:59 2020 daemon.notice netifd: Interface 'wan_6' has link connectivity
Sun Oct 4 12:27:59 2020 daemon.notice netifd: Interface 'wan_6' is setting up now
Sun Oct 4 12:28:01 2020 daemon.notice netifd: Interface 'wan_6' is now up
Sun Oct 4 12:28:09 2020 user.notice firewall: Reloading firewall due to ifup of wan (pppoe-wan)
Sun Oct 4 12:28:11 2020 cron.err crond[8133]: crond (busybox 1.31.1) started, log level 5
Sun Oct 4 12:28:11 2020 daemon.info dnsmasq[8098]: Connected to system UBus
Sun Oct 4 12:28:12 2020 cron.err crond[8261]: crond (busybox 1.31.1) started, log level 5
Sun Oct 4 12:28:13 2020 daemon.err miniupnpd[8457]: could not open lease file: /var/upnp.leases
Sun Oct 4 12:28:13 2020 daemon.notice miniupnpd[8457]: HTTP listening on port 5000
Sun Oct 4 12:28:13 2020 daemon.notice miniupnpd[8457]: Listening for NAT-PMP/PCP traffic on port 5351
Sun Oct 4 12:28:14 2020 user.notice firewall: Reloading firewall due to ifupdate of wan (pppoe-wan)
Sun Oct 4 12:28:15 2020 user.notice ddns-scripts[8486]: openwrt_ipv6: PID '8486' started at 2020-10-04 12:28
Sun Oct 4 12:28:15 2020 cron.err crond[9096]: crond (busybox 1.31.1) started, log level 5
Sun Oct 4 17:55:53 2020 cron.err crond[9454]: crond (busybox 1.31.1) started, log level 5
Sun Oct 4 17:55:55 2020 daemon.info pppd[7451]: System time change detected.
Sun Oct 4 17:56:05 2020 user.notice firewall: Reloading firewall due to ifup of wan_6 (pppoe-wan)
Sun Oct 4 17:56:06 2020 cron.err crond[12047]: crond (busybox 1.31.1) started, log level 5
Sun Oct 4 17:56:07 2020 cron.err crond[12219]: crond (busybox 1.31.1) started, log level 5

@ruichen-git
Copy link

我的XY-C5也遇到这个问题,启动后大概10多分钟就丢失了pppoe-wan连接,看日志是peer发了LCP reset

@Myoko
Copy link
Contributor

Myoko commented Oct 4, 2020

旧内核升级到新的内核 还是建议别保存配置升级!

@ruichen-git
Copy link

不保存配置也是一样的,下面是系统日志

Sun Oct 4 13:45:54 2020 daemon.notice pppd[2855]: PAP authentication succeeded
Sun Oct 4 13:45:54 2020 daemon.notice pppd[2855]: peer from calling number 70:7B:E8:C2:E7:05 authorized
Sun Oct 4 13:45:54 2020 daemon.notice pppd[2855]: local LL address fe80::2091:b59c:d21b:4f5d
Sun Oct 4 13:45:54 2020 daemon.notice pppd[2855]: remote LL address fe80::727b:e8ff:fec2:e705
Sun Oct 4 13:45:54 2020 daemon.notice pppd[2855]: local IP address 10.70.76.81
Sun Oct 4 13:45:54 2020 daemon.notice pppd[2855]: remote IP address 10.70.0.1
Sun Oct 4 13:45:54 2020 daemon.notice pppd[2855]: primary DNS address 202.106.195.68
Sun Oct 4 13:45:54 2020 daemon.notice pppd[2855]: secondary DNS address 202.106.46.151
Sun Oct 4 13:45:54 2020 daemon.notice netifd: Network device 'pppoe-wan' link is up
Sun Oct 4 13:45:54 2020 daemon.notice netifd: Interface 'wan' is now up
Sun Oct 4 13:45:54 2020 daemon.notice netifd: Network alias 'pppoe-wan' link is up
Sun Oct 4 13:45:54 2020 daemon.notice netifd: Interface 'wan_6' is enabled
Sun Oct 4 13:45:54 2020 daemon.notice netifd: Interface 'wan_6' has link connectivity
Sun Oct 4 13:45:54 2020 daemon.notice netifd: Interface 'wan_6' is setting up now
Sun Oct 4 13:45:54 2020 daemon.notice netifd: Interface 'wan_6' is now up
Sun Oct 4 13:45:54 2020 user.notice firewall: Reloading firewall due to ifup of wan (pppoe-wan)
Sun Oct 4 13:45:55 2020 user.notice firewall: Reloading firewall due to ifupdate of wan (pppoe-wan)
Sun Oct 4 13:49:28 2020 daemon.info pppd[2855]: System time change detected.
Sun Oct 4 13:57:08 2020 daemon.info pppd[2855]: LCP terminated by peer
Sun Oct 4 13:57:08 2020 daemon.info pppd[2855]: Connect time 7.9 minutes.
Sun Oct 4 13:57:08 2020 daemon.info pppd[2855]: Sent 1917609 bytes, received 76544677 bytes.
Sun Oct 4 13:57:08 2020 daemon.notice netifd: Network device 'pppoe-wan' link is down
Sun Oct 4 13:57:08 2020 daemon.notice netifd: Network alias 'pppoe-wan' link is down
Sun Oct 4 13:57:08 2020 daemon.notice netifd: Interface 'wan_6' has link connectivity loss
Sun Oct 4 13:57:08 2020 daemon.notice netifd: Interface 'wan_6' is now down
Sun Oct 4 13:57:08 2020 daemon.notice netifd: Interface 'wan_6' is disabled
Sun Oct 4 13:57:08 2020 daemon.notice netifd: Interface 'wan' has lost the connection
Sun Oct 4 13:57:11 2020 daemon.notice pppd[2855]: Connection terminated.
Sun Oct 4 13:57:11 2020 daemon.info pppd[2855]: Connect time 7.9 minutes.
Sun Oct 4 13:57:11 2020 daemon.info pppd[2855]: Sent 1917609 bytes, received 76544677 bytes.
Sun Oct 4 13:57:11 2020 daemon.info pppd[2855]: Sent PADT
Sun Oct 4 13:57:11 2020 daemon.notice pppd[2855]: Modem hangup
Sun Oct 4 13:57:11 2020 daemon.info pppd[2855]: Exit.
Sun Oct 4 13:57:11 2020 daemon.notice netifd: Interface 'wan' is now down
Sun Oct 4 13:57:11 2020 daemon.notice netifd: Interface 'wan' is setting up now
Sun Oct 4 13:57:11 2020 daemon.err insmod: module is already loaded - slhc
Sun Oct 4 13:57:11 2020 daemon.err insmod: module is already loaded - ppp_generic
Sun Oct 4 13:57:11 2020 daemon.err insmod: module is already loaded - pppox
Sun Oct 4 13:57:11 2020 daemon.err insmod: module is already loaded - pppoe
Sun Oct 4 13:57:11 2020 daemon.notice netifd: wan (3433): uci: Entry not found
Sun Oct 4 13:57:11 2020 daemon.notice netifd: wan (3433): sh: out of range
Sun Oct 4 13:57:12 2020 daemon.info pppd[3448]: Plugin rp-pppoe.so loaded.
Sun Oct 4 13:57:12 2020 daemon.info pppd[3448]: RP-PPPoE plugin version 3.8p compiled against pppd 2.4.8
Sun Oct 4 13:57:12 2020 daemon.notice pppd[3448]: pppd 2.4.8 started by root, uid 0
Sun Oct 4 13:57:17 2020 daemon.info pppd[3448]: PPP session is 32914
Sun Oct 4 13:57:17 2020 daemon.warn pppd[3448]: Connected to 70:7b:e8:c2:e7:05 via interface wan
Sun Oct 4 13:57:17 2020 kern.info kernel: [ 558.608642] pppoe-wan: renamed from ppp0
Sun Oct 4 13:57:17 2020 daemon.info pppd[3448]: Renamed interface ppp0 to pppoe-wan
Sun Oct 4 13:57:17 2020 daemon.info pppd[3448]: Using interface pppoe-wan
Sun Oct 4 13:57:17 2020 daemon.notice pppd[3448]: Connect: pppoe-wan <--> wan
Sun Oct 4 13:57:17 2020 daemon.info pppd[3448]: syncppp not active
Sun Oct 4 13:57:20 2020 daemon.info pppd[3448]: syncppp not active
Sun Oct 4 13:57:20 2020 daemon.notice pppd[3448]: PAP authentication succeeded
Sun Oct 4 13:57:20 2020 daemon.notice pppd[3448]: peer from calling number 70:7B:E8:C2:E7:05 authorized
Sun Oct 4 13:57:27 2020 daemon.notice pppd[3448]: local IP address 10.70.87.116
Sun Oct 4 13:57:27 2020 daemon.notice pppd[3448]: remote IP address 10.70.0.1
Sun Oct 4 13:57:27 2020 daemon.notice pppd[3448]: primary DNS address 202.106.195.68
Sun Oct 4 13:57:27 2020 daemon.notice pppd[3448]: secondary DNS address 202.106.46.151
Sun Oct 4 13:57:27 2020 daemon.notice netifd: Network device 'pppoe-wan' link is up
Sun Oct 4 13:57:27 2020 daemon.notice netifd: Interface 'wan' is now up
Sun Oct 4 13:57:27 2020 user.notice firewall: Reloading firewall due to ifup of wan (pppoe-wan)
Sun Oct 4 13:57:38 2020 daemon.notice pppd[3448]: local LL address fe80::318b:573a:350e:c489
Sun Oct 4 13:57:38 2020 daemon.notice pppd[3448]: remote LL address fe80::727b:e8ff:fec2:e705
Sun Oct 4 13:57:38 2020 daemon.notice netifd: Network alias 'pppoe-wan' link is up
Sun Oct 4 13:57:38 2020 daemon.notice netifd: Interface 'wan_6' is enabled
Sun Oct 4 13:57:38 2020 daemon.notice netifd: Interface 'wan_6' has link connectivity
Sun Oct 4 13:57:38 2020 daemon.notice netifd: Interface 'wan_6' is setting up now
Sun Oct 4 13:57:38 2020 daemon.notice netifd: Interface 'wan_6' is now up
Sun Oct 4 13:57:38 2020 user.notice firewall: Reloading firewall due to ifupdate of wan (pppoe-wan)

@Myoko
Copy link
Contributor

Myoko commented Oct 4, 2020

物理机 纯路由 没有遇到这个问题!开机3个多小时都没有断过

@blueveryday
Copy link

不保存配置也是一样的,下面是系统日志

Sun Oct 4 13:45:54 2020 daemon.notice pppd[2855]: PAP authentication succeeded
Sun Oct 4 13:45:54 2020 daemon.notice pppd[2855]: peer from calling number 70:7B:E8:C2:E7:05 authorized
Sun Oct 4 13:45:54 2020 daemon.notice pppd[2855]: local LL address fe80::2091:b59c:d21b:4f5d
Sun Oct 4 13:45:54 2020 daemon.notice pppd[2855]: remote LL address fe80::727b:e8ff:fec2:e705
Sun Oct 4 13:45:54 2020 daemon.notice pppd[2855]: local IP address 10.70.76.81
Sun Oct 4 13:45:54 2020 daemon.notice pppd[2855]: remote IP address 10.70.0.1
Sun Oct 4 13:45:54 2020 daemon.notice pppd[2855]: primary DNS address 202.106.195.68
Sun Oct 4 13:45:54 2020 daemon.notice pppd[2855]: secondary DNS address 202.106.46.151
Sun Oct 4 13:45:54 2020 daemon.notice netifd: Network device 'pppoe-wan' link is up
Sun Oct 4 13:45:54 2020 daemon.notice netifd: Interface 'wan' is now up
Sun Oct 4 13:45:54 2020 daemon.notice netifd: Network alias 'pppoe-wan' link is up
Sun Oct 4 13:45:54 2020 daemon.notice netifd: Interface 'wan_6' is enabled
Sun Oct 4 13:45:54 2020 daemon.notice netifd: Interface 'wan_6' has link connectivity
Sun Oct 4 13:45:54 2020 daemon.notice netifd: Interface 'wan_6' is setting up now
Sun Oct 4 13:45:54 2020 daemon.notice netifd: Interface 'wan_6' is now up
Sun Oct 4 13:45:54 2020 user.notice firewall: Reloading firewall due to ifup of wan (pppoe-wan)
Sun Oct 4 13:45:55 2020 user.notice firewall: Reloading firewall due to ifupdate of wan (pppoe-wan)
Sun Oct 4 13:49:28 2020 daemon.info pppd[2855]: System time change detected.
Sun Oct 4 13:57:08 2020 daemon.info pppd[2855]: LCP terminated by peer
Sun Oct 4 13:57:08 2020 daemon.info pppd[2855]: Connect time 7.9 minutes.
Sun Oct 4 13:57:08 2020 daemon.info pppd[2855]: Sent 1917609 bytes, received 76544677 bytes.
Sun Oct 4 13:57:08 2020 daemon.notice netifd: Network device 'pppoe-wan' link is down
Sun Oct 4 13:57:08 2020 daemon.notice netifd: Network alias 'pppoe-wan' link is down
Sun Oct 4 13:57:08 2020 daemon.notice netifd: Interface 'wan_6' has link connectivity loss
Sun Oct 4 13:57:08 2020 daemon.notice netifd: Interface 'wan_6' is now down
Sun Oct 4 13:57:08 2020 daemon.notice netifd: Interface 'wan_6' is disabled
Sun Oct 4 13:57:08 2020 daemon.notice netifd: Interface 'wan' has lost the connection
Sun Oct 4 13:57:11 2020 daemon.notice pppd[2855]: Connection terminated.
Sun Oct 4 13:57:11 2020 daemon.info pppd[2855]: Connect time 7.9 minutes.
Sun Oct 4 13:57:11 2020 daemon.info pppd[2855]: Sent 1917609 bytes, received 76544677 bytes.
Sun Oct 4 13:57:11 2020 daemon.info pppd[2855]: Sent PADT
Sun Oct 4 13:57:11 2020 daemon.notice pppd[2855]: Modem hangup
Sun Oct 4 13:57:11 2020 daemon.info pppd[2855]: Exit.
Sun Oct 4 13:57:11 2020 daemon.notice netifd: Interface 'wan' is now down
Sun Oct 4 13:57:11 2020 daemon.notice netifd: Interface 'wan' is setting up now
Sun Oct 4 13:57:11 2020 daemon.err insmod: module is already loaded - slhc
Sun Oct 4 13:57:11 2020 daemon.err insmod: module is already loaded - ppp_generic
Sun Oct 4 13:57:11 2020 daemon.err insmod: module is already loaded - pppox
Sun Oct 4 13:57:11 2020 daemon.err insmod: module is already loaded - pppoe
Sun Oct 4 13:57:11 2020 daemon.notice netifd: wan (3433): uci: Entry not found
Sun Oct 4 13:57:11 2020 daemon.notice netifd: wan (3433): sh: out of range
Sun Oct 4 13:57:12 2020 daemon.info pppd[3448]: Plugin rp-pppoe.so loaded.
Sun Oct 4 13:57:12 2020 daemon.info pppd[3448]: RP-PPPoE plugin version 3.8p compiled against pppd 2.4.8
Sun Oct 4 13:57:12 2020 daemon.notice pppd[3448]: pppd 2.4.8 started by root, uid 0
Sun Oct 4 13:57:17 2020 daemon.info pppd[3448]: PPP session is 32914
Sun Oct 4 13:57:17 2020 daemon.warn pppd[3448]: Connected to 70:7b:e8:c2:e7:05 via interface wan
Sun Oct 4 13:57:17 2020 kern.info kernel: [ 558.608642] pppoe-wan: renamed from ppp0
Sun Oct 4 13:57:17 2020 daemon.info pppd[3448]: Renamed interface ppp0 to pppoe-wan
Sun Oct 4 13:57:17 2020 daemon.info pppd[3448]: Using interface pppoe-wan
Sun Oct 4 13:57:17 2020 daemon.notice pppd[3448]: Connect: pppoe-wan <--> wan
Sun Oct 4 13:57:17 2020 daemon.info pppd[3448]: syncppp not active
Sun Oct 4 13:57:20 2020 daemon.info pppd[3448]: syncppp not active
Sun Oct 4 13:57:20 2020 daemon.notice pppd[3448]: PAP authentication succeeded
Sun Oct 4 13:57:20 2020 daemon.notice pppd[3448]: peer from calling number 70:7B:E8:C2:E7:05 authorized
Sun Oct 4 13:57:27 2020 daemon.notice pppd[3448]: local IP address 10.70.87.116
Sun Oct 4 13:57:27 2020 daemon.notice pppd[3448]: remote IP address 10.70.0.1
Sun Oct 4 13:57:27 2020 daemon.notice pppd[3448]: primary DNS address 202.106.195.68
Sun Oct 4 13:57:27 2020 daemon.notice pppd[3448]: secondary DNS address 202.106.46.151
Sun Oct 4 13:57:27 2020 daemon.notice netifd: Network device 'pppoe-wan' link is up
Sun Oct 4 13:57:27 2020 daemon.notice netifd: Interface 'wan' is now up
Sun Oct 4 13:57:27 2020 user.notice firewall: Reloading firewall due to ifup of wan (pppoe-wan)
Sun Oct 4 13:57:38 2020 daemon.notice pppd[3448]: local LL address fe80::318b:573a:350e:c489
Sun Oct 4 13:57:38 2020 daemon.notice pppd[3448]: remote LL address fe80::727b:e8ff:fec2:e705
Sun Oct 4 13:57:38 2020 daemon.notice netifd: Network alias 'pppoe-wan' link is up
Sun Oct 4 13:57:38 2020 daemon.notice netifd: Interface 'wan_6' is enabled
Sun Oct 4 13:57:38 2020 daemon.notice netifd: Interface 'wan_6' has link connectivity
Sun Oct 4 13:57:38 2020 daemon.notice netifd: Interface 'wan_6' is setting up now
Sun Oct 4 13:57:38 2020 daemon.notice netifd: Interface 'wan_6' is now up
Sun Oct 4 13:57:38 2020 user.notice firewall: Reloading firewall due to ifupdate of wan (pppoe-wan)

HC5962,跟你问题类似,wan+man方式,man有问题,关掉man就正常了。
我现在准备恢复到5.4内核了。

@ruichen-git
Copy link

试试这个 commit ( AmadeusGhost@2e74f09) 能不能解决问题

不知道怎么去抓你这个commit去编译,git玩的不熟 ^_^

@blueveryday
Copy link

pppoe报错rename,然后拨号链接显示连上的,但是网络实际是断了,MAN口的iptv还可以使用。

@Ljzkirito
Copy link
Contributor Author

Ljzkirito commented Oct 5, 2020

试试这个 commit ( AmadeusGhost@2e74f09) 能不能解决问题

昨天git clone --depth 1 https://github.com/AmadeusGhost/lede -b modified openwrt编译
今天不保留配置升级,仍然出现PPPoE显示连上,但网络实际断连的情况

@Ljzkirito

This comment has been minimized.

@Ljzkirito
Copy link
Contributor Author

试试 https://github.com/AmadeusGhost/lede/tree/ramips ,另外就是在 AmadeusGhost@34d9c51 上把小娱 C5 的有线驱动改成 4.14 上的了(可以开 hwnat 了)。

开机一小时,PPPoE网络连接正常,未发现断网。

@blueveryday
Copy link

开机一小时,PPPoE网络连接正常,未发现断网。

已发 pr

HC5962的这个问题能不能一并解决一下?同样存在相同的问题。

@Ljzkirito
Copy link
Contributor Author

Ljzkirito commented Oct 7, 2020

HC5962的这个问题能不能一并解决一下?同样存在相同的问题。

HC5962也是mt7621设备吧,是的话此pr应该一并修复了

@blueveryday
Copy link

开机一小时,PPPoE网络连接正常,未发现断网。

已发 pr

HC5962的这个问题能不能一并解决一下?同样存在相同的问题。

HC5962也是mt7621设备吧,是的话应该一并修复了

对,就是mt7621的,现在再用5.4的内核。

@Ljzkirito
Copy link
Contributor Author

Ljzkirito commented Oct 7, 2020

对,就是mt7621的,现在再用5.4的内核。

pr是针对mediatek芯片修复的,HC5962也已经修复了,等待合并即可

@blueveryday
Copy link

合并了我测试一哈。

@Myoko
Copy link
Contributor

Myoko commented Oct 7, 2020

有没有试过4.19.138 内核?

@Ljzkirito
Copy link
Contributor Author

Ljzkirito commented Oct 7, 2020

有没有试过4.19.138 内核?

没有试过,也不知道怎么更换lean的内核版本

@Myoko
Copy link
Contributor

Myoko commented Oct 7, 2020

有没有试过4.19.138 内核?

没有试过,也不知道怎么更换lean的内核版本

云端编一个试试看!

@Ljzkirito
Copy link
Contributor Author

Ljzkirito commented Oct 7, 2020

有没有试过4.19.138 内核?

没有试过,也不知道怎么更换lean的内核版本

云端编一个试试看!

所以怎么编译4.19.138 内核的版本?不是编程出身的,不会呀。😂
或者你的意思是用 https://github.com/coolsnowwolf/openwrt.git 编一个吗?

P3TERXActions-OpenWrt中文教程,才会的Fork
Revert是用GitHub Desktop做的😂,也不会git命令😂

@Myoko
Copy link
Contributor

Myoko commented Oct 7, 2020

P3TERXActions-OpenWrt

用P3TERX的Actions-OpenWrt 在云端编译就可以了!

@Myoko
Copy link
Contributor

Myoko commented Oct 7, 2020

有没有试过4.19.138 内核?

ramips 只有 4.14 和 5.4,而且驱动都同步官方了,回去意义不大

ramips 那就没办法了!

@blueveryday
Copy link

blueveryday commented Oct 8, 2020

[ 16.225452] procd: - init -
[ 16.483968] random: crng init done
[ 16.967217] kmodloader: loading kernel modules from /etc/modules.d/*
[ 17.031080] NET: Registered protocol family 38
[ 17.047622] zram: Added device: zram0
[ 17.057092] ntfs: driver 2.1.32 [Flags: R/O MODULE].
[ 17.112434] cryptodev: driver 1.10 loaded.
[ 17.122225] Loading modules backported from Linux version v5.8-0-gbcf876870b95
[ 17.129463] Backport generated by backports.git v5.8-1-0-g79400d9e
[ 17.158367] usbcore: registered new interface driver ums-alauda
[ 17.165858] usbcore: registered new interface driver ums-cypress
[ 17.173623] usbcore: registered new interface driver ums-datafab
[ 17.181455] usbcore: registered new interface driver ums-freecom
[ 17.189025] usbcore: registered new interface driver ums-isd200
[ 17.196554] usbcore: registered new interface driver ums-jumpshot
[ 17.201452] urngd: v1.0.2 started.
[ 17.204073] usbcore: registered new interface driver ums-karma
[ 17.213764] usbcore: registered new interface driver ums-sddr09
[ 17.221305] usbcore: registered new interface driver ums-sddr55
[ 17.228983] usbcore: registered new interface driver ums-usbat
[ 17.239027] usblp 1-2.3:1.1: usblp0: USB Bidirectional printer dev 3 if 1 alt 0 proto 2 vid 0x03F0 pid 0x3112
[ 17.249358] usbcore: registered new interface driver usblp
[ 17.268093] xt_time: kernel timezone is -0000
[ 17.351373] mt7621-pci 1e140000.pcie: bus=1 slot=0 irq=23
[ 17.356835] pci 0000:00:00.0: enabling device (0006 -> 0007)
[ 17.362566] mt7603e 0000:01:00.0: enabling device (0000 -> 0002)
[ 17.368845] mt7603e 0000:01:00.0: ASIC revision: 76030010
[ 17.996026] mt7603e 0000:01:00.0: Firmware Version: ap_pcie
[ 18.001619] mt7603e 0000:01:00.0: Build Time: 20160107100755
[ 18.034666] mt7603e 0000:01:00.0: firmware init done
[ 18.214524] ieee80211 phy0: Selected rate control algorithm 'minstrel_ht'
[ 18.227272] mt7621-pci 1e140000.pcie: bus=2 slot=1 irq=24
[ 18.232718] pci 0000:00:01.0: enabling device (0006 -> 0007)
[ 18.238377] mt76x2e 0000:02:00.0: enabling device (0000 -> 0002)
[ 18.244616] mt76x2e 0000:02:00.0: ASIC revision: 76120044
[ 18.647306] mt76x2e 0000:02:00.0: ROM patch build: 20141115060606a
[ 18.657071] mt76x2e 0000:02:00.0: Firmware Version: 0.0.00
[ 18.662576] mt76x2e 0000:02:00.0: Build: 1
[ 18.666668] mt76x2e 0000:02:00.0: Build Time: 201507311614____
[ 18.686678] mt76x2e 0000:02:00.0: Firmware running!
[ 18.693374] ieee80211 phy1: Selected rate control algorithm 'minstrel_ht'
[ 18.732784] PPP generic driver version 2.4.2
[ 18.738395] PPP MPPE Compression module registered
[ 18.744348] NET: Registered protocol family 24
[ 18.759357] kmodloader: done loading kernel modules from /etc/modules.d/*
[ 22.257590] zram0: detected capacity change from 0 to 127926272
[ 22.841735] EXT4-fs (sda1): recovery complete
[ 22.852173] EXT4-fs (sda1): mounted filesystem with ordered data mode. Opts:
[ 23.086697] Adding 524284k swap on /mnt/sda1/opt/.swap. Priority:-2 extents:5 across:1040380k
[ 27.368977] mtk_soc_eth 1e100000.ethernet eth0: Link is Down
[ 27.381988] mtk_soc_eth 1e100000.ethernet eth0: configuring for fixed/rgmii link mode
[ 27.390425] mtk_soc_eth 1e100000.ethernet eth0: Link is Up - 1Gbps/Full - flow control rx/tx
[ 27.392838] device eth0 entered promiscuous mode
[ 27.404434] mt7530 mdio-bus:1f lan1: configuring for phy/gmii link mode
[ 27.411668] 8021q: adding VLAN 0 to HW filter on device lan1
[ 27.420933] br-lan: port 1(lan1) entered blocking state
[ 27.426310] br-lan: port 1(lan1) entered disabled state
[ 27.432525] device lan1 entered promiscuous mode
[ 27.457416] mt7530 mdio-bus:1f lan2: configuring for phy/gmii link mode
[ 27.465575] 8021q: adding VLAN 0 to HW filter on device lan2
[ 27.475750] br-lan: port 2(lan2) entered blocking state
[ 27.481086] br-lan: port 2(lan2) entered disabled state
[ 27.487814] device lan2 entered promiscuous mode
[ 27.499886] mt7530 mdio-bus:1f lan3: configuring for phy/gmii link mode
[ 27.507269] 8021q: adding VLAN 0 to HW filter on device lan3
[ 27.516409] br-lan: port 3(lan3) entered blocking state
[ 27.521697] br-lan: port 3(lan3) entered disabled state
[ 27.528409] device lan3 entered promiscuous mode
[ 27.539475] mt7530 mdio-bus:1f wan: configuring for phy/gmii link mode
[ 27.547420] 8021q: adding VLAN 0 to HW filter on device wan
[ 29.595261] mt7530 mdio-bus:1f wan: Link is Up - 100Mbps/Full - flow control off
[ 29.602755] IPv6: ADDRCONF(NETDEV_CHANGE): wan: link becomes ready
[ 29.865009] br-lan: port 4(wlan0) entered blocking state
[ 29.870487] br-lan: port 4(wlan0) entered disabled state
[ 29.876634] device wlan0 entered promiscuous mode
[ 29.881804] br-lan: port 4(wlan0) entered blocking state
[ 29.887198] br-lan: port 4(wlan0) entered forwarding state
[ 29.893112] IPv6: ADDRCONF(NETDEV_CHANGE): br-lan: link becomes ready
[ 30.403285] IPv6: ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready
[ 30.874401] br-lan: port 5(wlan1) entered blocking state
[ 30.879883] br-lan: port 5(wlan1) entered disabled state
[ 30.885924] device wlan1 entered promiscuous mode
[ 31.925760] IPv6: ADDRCONF(NETDEV_CHANGE): wlan1: link becomes ready
[ 31.932411] br-lan: port 5(wlan1) entered blocking state
[ 31.937795] br-lan: port 5(wlan1) entered forwarding state
[ 50.873061] pppoe-wan: renamed from ppp0
[ 541.156600] pppoe-wan: renamed from ppp0
[ 936.985130] pppoe-wan: renamed from ppp0
[ 1073.141372] pppoe-wan: renamed from ppp0
[ 1095.783329] pppoe-wan: renamed from ppp0

重新编译,刷了,问题依旧。
我的网络:HC5962+wan+man(iptv),单线复用。
故障复现:
上网正常,收视IPTV组播,一切正常,用了半个小时。
然后关掉IPTV播放器,路由器就断网了,wan口显示获取的pppoe拨号信息正常,但是整个网络不能上网了。
log没有错误信息。保持上面1095.783329的内核记录不变。
虽然不能上网了,IPTV可以正常使用。
WAN口跃点是1,MAN口跃点是100
问题原因不明。

目前回退到commit 0ecfa0d4fa08c72595dae904f8f326e5ad94fb89编译的固件,安装上面的操作,使用一切正常。
并且内核日志没有renamed的报错,截取如下:

[ 27.139386] br-lan: port 3(lan3) entered disabled state
[ 27.146118] device lan3 entered promiscuous mode
[ 27.157283] mt7530 mdio-bus:1f wan: configuring for phy/gmii link mode
[ 27.164414] 8021q: adding VLAN 0 to HW filter on device wan
[ 29.219882] mt7530 mdio-bus:1f wan: Link is Up - 100Mbps/Full - flow control off
[ 29.227348] IPv6: ADDRCONF(NETDEV_CHANGE): wan: link becomes ready
[ 29.843423] pppoe-wan: renamed from ppp0
[ 29.869968] br-lan: port 4(wlan0) entered blocking state
[ 29.875529] br-lan: port 4(wlan0) entered disabled state
[ 29.881744] device wlan0 entered promiscuous mode
[ 29.887014] br-lan: port 4(wlan0) entered blocking state
[ 29.892485] br-lan: port 4(wlan0) entered forwarding state
[ 29.899747] IPv6: ADDRCONF(NETDEV_CHANGE): br-lan: link becomes ready
[ 30.254433] br-lan: port 4(wlan0) entered disabled state
[ 30.426730] IPv6: ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready
[ 30.433668] br-lan: port 4(wlan0) entered blocking state
[ 30.439155] br-lan: port 4(wlan0) entered forwarding state
[ 30.868422] br-lan: port 5(wlan1) entered blocking state
[ 30.873790] br-lan: port 5(wlan1) entered disabled state
[ 30.880125] device wlan1 entered promiscuous mode
[ 30.885229] br-lan: port 5(wlan1) entered blocking state
[ 30.890618] br-lan: port 5(wlan1) entered forwarding state
[ 31.513447] br-lan: port 5(wlan1) entered disabled state
[ 31.977735] IPv6: ADDRCONF(NETDEV_CHANGE): wlan1: link becomes ready
[ 31.984449] br-lan: port 5(wlan1) entered blocking state
[ 31.989853] br-lan: port 5(wlan1) entered forwarding state

Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Labels
None yet
Projects
None yet
Development

Successfully merging a pull request may close this issue.

4 participants