多次手动重连 WAN 口，问题复现，本次重连日志：
Sun Dec 11 02:39:27 2022 daemon.info pppd[9396]: Terminating on signal 15
Sun Dec 11 02:39:27 2022 daemon.info pppd[9396]: Connect time 1.4 minutes.
Sun Dec 11 02:39:27 2022 daemon.info pppd[9396]: Sent 1684299 bytes, received 4001750 bytes.
Sun Dec 11 02:39:27 2022 daemon.err miniupnpd[4180]: ioctl(s, SIOCGIFADDR, ...): Address not available
Sun Dec 11 02:39:27 2022 daemon.err miniupnpd[4180]: Failed to get IP for interface pppoe-wan
Sun Dec 11 02:39:27 2022 daemon.warn miniupnpd[4180]: SendNATPMPPublicAddressChangeNotification: cannot get public IP address, stopping
Sun Dec 11 02:39:27 2022 daemon.notice netifd: Network device 'pppoe-wan' link is down
Sun Dec 11 02:39:27 2022 daemon.notice pppd[9396]: Connection terminated.
Sun Dec 11 02:39:27 2022 daemon.info pppd[9396]: Connect time 1.4 minutes.
Sun Dec 11 02:39:27 2022 daemon.info pppd[9396]: Sent 1684299 bytes, received 4001750 bytes.
Sun Dec 11 02:39:27 2022 daemon.info pppd[9396]: Exit.
Sun Dec 11 02:39:27 2022 daemon.notice netifd: Interface 'wan' is now down
Sun Dec 11 02:39:27 2022 daemon.notice netifd: Interface 'wan' is disabled
Sun Dec 11 02:39:27 2022 daemon.notice netifd: Interface 'wan' is enabled
Sun Dec 11 02:39:27 2022 daemon.notice netifd: Interface 'wan' is setting up now
Sun Dec 11 02:39:27 2022 kern.info kernel: [ 1747.236734] 8021q: adding VLAN 0 to HW filter on device eth5
Sun Dec 11 02:39:27 2022 daemon.notice netifd: Network device 'eth5' link is down
Sun Dec 11 02:39:27 2022 daemon.notice netifd: Interface 'wan' has link connectivity loss
Sun Dec 11 02:39:27 2022 daemon.notice netifd: wan (13405): uci: Entry not found
Sun Dec 11 02:39:27 2022 daemon.notice netifd: wan (13405): sh: out of range
Sun Dec 11 02:39:27 2022 daemon.notice netifd: wan (13405): Command failed: ubus call network.interface notify_proto { "action": 1, "command": [ "\/usr\/sbin\/pppd", "nodetach", "ipparam", "wan", "ifname", "pppoe-wan", "nodefaultroute", "usepeerdns", "maxfail", "1", "user", "", "password", "", "ip-up-script", "\/lib\/netifd\/ppp-up", "ip-down-script", "\/lib\/netifd\/ppp-down", "mtu", "1492", "mru", "1492", "plugin", "pppoe.so", "nic-eth5" ], "interface": "wan" } (Permission denied)
Sun Dec 11 02:39:27 2022 daemon.notice netifd: Interface 'wan' is now down
Sun Dec 11 02:39:27 2022 daemon.notice netifd: Interface 'wan' is disabled
Sun Dec 11 02:39:27 2022 daemon.notice netifd: Interface 'wan' is enabled
Sun Dec 11 02:39:27 2022 kern.info kernel: [ 1747.324730] 8021q: adding VLAN 0 to HW filter on device eth5
Sun Dec 11 02:39:27 2022 user.notice ddns-scripts[9897]: DNSPOD: PID '9897' terminated by 'SIGTERM' at 2022-12-11 02:39
Sun Dec 11 02:39:30 2022 kern.info kernel: [ 1750.208588] igb 0000:06:00.0 eth5: igb: eth5 NIC Link is Up 1000 Mbps Full Duplex, Flow Control: RX
Sun Dec 11 02:39:30 2022 daemon.notice netifd: Network device 'eth5' link is up
Sun Dec 11 02:39:30 2022 daemon.notice netifd: Interface 'wan' has link connectivity
Sun Dec 11 02:39:30 2022 daemon.notice netifd: Interface 'wan' is setting up now
Sun Dec 11 02:39:30 2022 daemon.notice netifd: wan (13683): uci: Entry not found
Sun Dec 11 02:39:30 2022 daemon.notice netifd: wan (13683): sh: out of range
Sun Dec 11 02:39:30 2022 daemon.info pppd[13691]: Plugin pppoe.so loaded.
Sun Dec 11 02:39:30 2022 daemon.info pppd[13691]: PPPoE plugin from pppd 2.4.9
Sun Dec 11 02:39:30 2022 daemon.notice pppd[13691]: pppd 2.4.9 started by root, uid 0
Sun Dec 11 02:39:35 2022 daemon.info pppd[13691]: PPP session is 19144
Sun Dec 11 02:39:35 2022 daemon.warn pppd[13691]: Connected to via interface eth5
Sun Dec 11 02:39:35 2022 kern.info kernel: [ 1755.528499] pppoe-wan: renamed from ppp0
Sun Dec 11 02:39:35 2022 daemon.info pppd[13691]: Renamed interface ppp0 to pppoe-wan
Sun Dec 11 02:39:35 2022 daemon.info pppd[13691]: Using interface pppoe-wan
Sun Dec 11 02:39:35 2022 daemon.notice pppd[13691]: Connect: pppoe-wan <--> eth5
Sun Dec 11 02:39:38 2022 daemon.info pppd[13691]: syncppp not active
Sun Dec 11 02:39:38 2022 daemon.info pppd[13691]: Remote message: Authentication success,Welcome!
Sun Dec 11 02:39:38 2022 daemon.notice pppd[13691]: PAP authentication succeeded
Sun Dec 11 02:39:38 2022 daemon.notice pppd[13691]: peer from calling number authorized
Sun Dec 11 02:39:38 2022 daemon.notice pppd[13691]: local LL address
Sun Dec 11 02:39:38 2022 daemon.notice pppd[13691]: remote LL address
Sun Dec 11 02:39:38 2022 daemon.notice pppd[13691]: local IP address
Sun Dec 11 02:39:38 2022 daemon.notice pppd[13691]: remote IP address
Sun Dec 11 02:39:38 2022 daemon.notice pppd[13691]: primary DNS address
Sun Dec 11 02:39:38 2022 daemon.notice pppd[13691]: secondary DNS address
Sun Dec 11 02:39:38 2022 daemon.notice netifd: Network device 'pppoe-wan' link is up
Sun Dec 11 02:39:38 2022 daemon.notice netifd: Interface 'wan' is now up
Sun Dec 11 02:39:38 2022 user.notice firewall: Reloading firewall due to ifup of wan (pppoe-wan)
Sun Dec 11 02:39:39 2022 user.notice ddns-scripts[14236]: DNSPOD: PID '14236' started at 2022-12-11 02:39
本次复现问题后，再次重连 WAN 口，问题解决，本次重连日志：
Sun Dec 11 03:13:18 2022 daemon.info pppd[13691]: Terminating on signal 15
Sun Dec 11 03:13:18 2022 daemon.info pppd[13691]: Connect time 33.7 minutes.
Sun Dec 11 03:13:18 2022 daemon.info pppd[13691]: Sent 13846980 bytes, received 82062707 bytes.
Sun Dec 11 03:13:18 2022 daemon.err miniupnpd[14833]: ioctl(s, SIOCGIFADDR, ...): Address not available
Sun Dec 11 03:13:18 2022 daemon.err miniupnpd[14833]: Failed to get IP for interface pppoe-wan
Sun Dec 11 03:13:18 2022 daemon.warn miniupnpd[14833]: SendNATPMPPublicAddressChangeNotification: cannot get public IP address, stopping
Sun Dec 11 03:13:18 2022 daemon.notice netifd: Network device 'pppoe-wan' link is down
Sun Dec 11 03:13:18 2022 daemon.notice pppd[13691]: Connection terminated.
Sun Dec 11 03:13:18 2022 daemon.info pppd[13691]: Connect time 33.7 minutes.
Sun Dec 11 03:13:18 2022 daemon.info pppd[13691]: Sent 13846980 bytes, received 82062707 bytes.
Sun Dec 11 03:13:18 2022 daemon.info pppd[13691]: Exit.
Sun Dec 11 03:13:18 2022 daemon.notice netifd: Interface 'wan' is now down
Sun Dec 11 03:13:18 2022 daemon.notice netifd: Interface 'wan' is disabled
Sun Dec 11 03:13:18 2022 daemon.notice netifd: Interface 'wan' is enabled
Sun Dec 11 03:13:18 2022 daemon.notice netifd: Interface 'wan' is setting up now
Sun Dec 11 03:13:18 2022 daemon.notice netifd: Network device 'eth5' link is down
Sun Dec 11 03:13:18 2022 daemon.notice netifd: Interface 'wan' has link connectivity loss
Sun Dec 11 03:13:18 2022 kern.info kernel: [ 3779.035875] 8021q: adding VLAN 0 to HW filter on device eth5
Sun Dec 11 03:13:18 2022 daemon.notice netifd: wan (16966): uci: Entry not found
Sun Dec 11 03:13:18 2022 daemon.notice netifd: wan (16966): sh: out of range
Sun Dec 11 03:13:18 2022 daemon.notice netifd: wan (16966): Command failed: ubus call network.interface notify_proto { "action": 1, "command": [ "\/usr\/sbin\/pppd", "nodetach", "ipparam", "wan", "ifname", "pppoe-wan", "nodefaultroute", "usepeerdns", "maxfail", "1", "user", "", "password", "", "ip-up-script", "\/lib\/netifd\/ppp-up", "ip-down-script", "\/lib\/netifd\/ppp-down", "mtu", "1492", "mru", "1492", "plugin", "pppoe.so", "nic-eth5" ], "interface": "wan" } (Permission denied)
Sun Dec 11 03:13:18 2022 daemon.notice netifd: Interface 'wan' is now down
Sun Dec 11 03:13:18 2022 daemon.notice netifd: Interface 'wan' is disabled
Sun Dec 11 03:13:19 2022 daemon.notice netifd: Interface 'wan' is enabled
Sun Dec 11 03:13:19 2022 kern.info kernel: [ 3779.123868] 8021q: adding VLAN 0 to HW filter on device eth5
Sun Dec 11 03:13:19 2022 user.notice ddns-scripts[14968]: DNSPOD: PID '14968' terminated by 'SIGTERM' at 2022-12-11 03:13
Sun Dec 11 03:13:21 2022 kern.info kernel: [ 3782.043773] igb 0000:06:00.0 eth5: igb: eth5 NIC Link is Up 1000 Mbps Full Duplex, Flow Control: RX
Sun Dec 11 03:13:22 2022 daemon.notice netifd: Network device 'eth5' link is up
Sun Dec 11 03:13:22 2022 daemon.notice netifd: Interface 'wan' has link connectivity
Sun Dec 11 03:13:22 2022 daemon.notice netifd: Interface 'wan' is setting up now
Sun Dec 11 03:13:22 2022 daemon.notice netifd: wan (17235): uci: Entry not found
Sun Dec 11 03:13:22 2022 daemon.notice netifd: wan (17235): sh: out of range
Sun Dec 11 03:13:22 2022 daemon.info pppd[17243]: Plugin pppoe.so loaded.
Sun Dec 11 03:13:22 2022 daemon.info pppd[17243]: PPPoE plugin from pppd 2.4.9
Sun Dec 11 03:13:22 2022 daemon.notice pppd[17243]: pppd 2.4.9 started by root, uid 0
Sun Dec 11 03:13:27 2022 daemon.info pppd[17243]: PPP session is 24620
Sun Dec 11 03:13:27 2022 daemon.warn pppd[17243]: Connected to via interface eth5
Sun Dec 11 03:13:27 2022 kern.info kernel: [ 3787.387677] pppoe-wan: renamed from ppp0
Sun Dec 11 03:13:27 2022 daemon.info pppd[17243]: Renamed interface ppp0 to pppoe-wan
Sun Dec 11 03:13:27 2022 daemon.info pppd[17243]: Using interface pppoe-wan
Sun Dec 11 03:13:27 2022 daemon.notice pppd[17243]: Connect: pppoe-wan <--> eth5
Sun Dec 11 03:13:30 2022 daemon.info pppd[17243]: syncppp not active
Sun Dec 11 03:13:30 2022 daemon.info pppd[17243]: Remote message: Authentication success,Welcome!
Sun Dec 11 03:13:30 2022 daemon.notice pppd[17243]: PAP authentication succeeded
Sun Dec 11 03:13:30 2022 daemon.notice pppd[17243]: peer from calling number authorized
Sun Dec 11 03:13:33 2022 daemon.notice pppd[17243]: local IP address
Sun Dec 11 03:13:33 2022 daemon.notice pppd[17243]: remote IP address
Sun Dec 11 03:13:33 2022 daemon.notice pppd[17243]: primary DNS address
Sun Dec 11 03:13:33 2022 daemon.notice pppd[17243]: secondary DNS address
Sun Dec 11 03:13:33 2022 daemon.notice pppd[17243]: local LL address
Sun Dec 11 03:13:33 2022 daemon.notice pppd[17243]: remote LL address
Sun Dec 11 03:13:33 2022 daemon.notice netifd: Network device 'pppoe-wan' link is up
Sun Dec 11 03:13:33 2022 daemon.notice netifd: Interface 'wan' is now up
Sun Dec 11 03:13:33 2022 user.notice firewall: Reloading firewall due to ifup of wan (pppoe-wan)
Sun Dec 11 03:13:33 2022 user.notice ddns-scripts[17627]: DNSPOD: PID '17627' started at 2022-12-11 03:13
关闭科学插件后，再次进行多次手动重连 WAN 口，问题仍然可以复现，本次重连日志：
Sun Dec 11 03:15:02 2022 daemon.info pppd[21407]: Terminating on signal 15
Sun Dec 11 03:15:02 2022 daemon.info pppd[21407]: Connect time 0.4 minutes.
Sun Dec 11 03:15:02 2022 daemon.info pppd[21407]: Sent 1080449 bytes, received 1422034 bytes.
Sun Dec 11 03:15:02 2022 daemon.err miniupnpd[14833]: ioctl(s, SIOCGIFADDR, ...): Address not available
Sun Dec 11 03:15:02 2022 daemon.err miniupnpd[14833]: Failed to get IP for interface pppoe-wan
Sun Dec 11 03:15:02 2022 daemon.warn miniupnpd[14833]: SendNATPMPPublicAddressChangeNotification: cannot get public IP address, stopping
Sun Dec 11 03:15:02 2022 daemon.notice netifd: Network device 'pppoe-wan' link is down
Sun Dec 11 03:15:02 2022 daemon.notice pppd[21407]: Connection terminated.
Sun Dec 11 03:15:02 2022 daemon.info pppd[21407]: Connect time 0.4 minutes.
Sun Dec 11 03:15:02 2022 daemon.info pppd[21407]: Sent 1080449 bytes, received 1422034 bytes.
Sun Dec 11 03:15:02 2022 daemon.info pppd[21407]: Exit.
Sun Dec 11 03:15:02 2022 daemon.notice netifd: Interface 'wan' is now down
Sun Dec 11 03:15:02 2022 daemon.notice netifd: Interface 'wan' is disabled
Sun Dec 11 03:15:02 2022 kern.info kernel: [ 3882.409493] 8021q: adding VLAN 0 to HW filter on device eth5
Sun Dec 11 03:15:02 2022 daemon.notice netifd: Interface 'wan' is enabled
Sun Dec 11 03:15:02 2022 daemon.notice netifd: Interface 'wan' is setting up now
Sun Dec 11 03:15:02 2022 daemon.notice netifd: Network device 'eth5' link is down
Sun Dec 11 03:15:02 2022 daemon.notice netifd: Interface 'wan' has link connectivity loss
Sun Dec 11 03:15:02 2022 daemon.notice netifd: wan (25243): uci: Entry not found
Sun Dec 11 03:15:02 2022 daemon.notice netifd: wan (25243): sh: out of range
Sun Dec 11 03:15:02 2022 daemon.notice netifd: wan (25243): Command failed: ubus call network.interface notify_proto { "action": 1, "command": [ "\/usr\/sbin\/pppd", "nodetach", "ipparam", "wan", "ifname", "pppoe-wan", "nodefaultroute", "usepeerdns", "maxfail", "1", "user", "", "password", "", "ip-up-script", "\/lib\/netifd\/ppp-up", "ip-down-script", "\/lib\/netifd\/ppp-down", "mtu", "1492", "mru", "1492", "plugin", "pppoe.so", "nic-eth5" ], "interface": "wan" } (Permission denied)
Sun Dec 11 03:15:02 2022 daemon.notice netifd: Interface 'wan' is now down
Sun Dec 11 03:15:02 2022 daemon.notice netifd: Interface 'wan' is disabled
Sun Dec 11 03:15:02 2022 daemon.notice netifd: Interface 'wan' is enabled
Sun Dec 11 03:15:02 2022 kern.info kernel: [ 3882.497482] 8021q: adding VLAN 0 to HW filter on device eth5
Sun Dec 11 03:15:02 2022 user.notice ddns-scripts[21789]: DNSPOD: PID '21789' terminated by 'SIGTERM' at 2022-12-11 03:15
Sun Dec 11 03:15:05 2022 kern.info kernel: [ 3885.397392] igb 0000:06:00.0 eth5: igb: eth5 NIC Link is Up 1000 Mbps Full Duplex, Flow Control: RX
Sun Dec 11 03:15:05 2022 daemon.notice netifd: Network device 'eth5' link is up
Sun Dec 11 03:15:05 2022 daemon.notice netifd: Interface 'wan' has link connectivity
Sun Dec 11 03:15:05 2022 daemon.notice netifd: Interface 'wan' is setting up now
Sun Dec 11 03:15:05 2022 daemon.notice netifd: wan (25509): uci: Entry not found
Sun Dec 11 03:15:05 2022 daemon.notice netifd: wan (25509): sh: out of range
Sun Dec 11 03:15:05 2022 daemon.info pppd[25517]: Plugin pppoe.so loaded.
Sun Dec 11 03:15:05 2022 daemon.info pppd[25517]: PPPoE plugin from pppd 2.4.9
Sun Dec 11 03:15:05 2022 daemon.notice pppd[25517]: pppd 2.4.9 started by root, uid 0
Sun Dec 11 03:15:10 2022 daemon.info pppd[25517]: PPP session is 2517
Sun Dec 11 03:15:10 2022 daemon.warn pppd[25517]: Connected to via interface eth5
Sun Dec 11 03:15:10 2022 kern.info kernel: [ 3890.717352] pppoe-wan: renamed from ppp0
Sun Dec 11 03:15:10 2022 daemon.info pppd[25517]: Renamed interface ppp0 to pppoe-wan
Sun Dec 11 03:15:10 2022 daemon.info pppd[25517]: Using interface pppoe-wan
Sun Dec 11 03:15:10 2022 daemon.notice pppd[25517]: Connect: pppoe-wan <--> eth5
Sun Dec 11 03:15:13 2022 daemon.info pppd[25517]: syncppp not active
Sun Dec 11 03:15:16 2022 daemon.info pppd[25517]: syncppp not active
Sun Dec 11 03:15:19 2022 daemon.info pppd[25517]: syncppp not active
Sun Dec 11 03:15:22 2022 daemon.info pppd[25517]: syncppp not active
Sun Dec 11 03:15:25 2022 daemon.info pppd[25517]: syncppp not active
Sun Dec 11 03:15:28 2022 daemon.info pppd[25517]: syncppp not active
Sun Dec 11 03:15:28 2022 daemon.info pppd[25517]: Remote message: Authentication success,Welcome!
Sun Dec 11 03:15:28 2022 daemon.notice pppd[25517]: PAP authentication succeeded
Sun Dec 11 03:15:28 2022 daemon.notice pppd[25517]: peer from calling number authorized
Sun Dec 11 03:15:28 2022 daemon.notice pppd[25517]: local LL address
Sun Dec 11 03:15:28 2022 daemon.notice pppd[25517]: remote LL address
Sun Dec 11 03:15:28 2022 daemon.notice pppd[25517]: local IP address
Sun Dec 11 03:15:28 2022 daemon.notice pppd[25517]: remote IP address
Sun Dec 11 03:15:28 2022 daemon.notice pppd[25517]: primary DNS address
Sun Dec 11 03:15:28 2022 daemon.notice pppd[25517]: secondary DNS address
Sun Dec 11 03:15:28 2022 daemon.notice netifd: Network device 'pppoe-wan' link is up
Sun Dec 11 03:15:28 2022 daemon.notice netifd: Interface 'wan' is now up
Sun Dec 11 03:15:28 2022 user.notice firewall: Reloading firewall due to ifup of wan (pppoe-wan)
Sun Dec 11 03:15:29 2022 user.notice ddns-scripts[25898]: DNSPOD: PID '25898' started at 2022-12-11 03:15
本次复现问题后，再次重连 WAN 口，问题解决，本次重连日志：
Sun Dec 11 03:23:55 2022 daemon.info pppd[25517]: Terminating on signal 15
Sun Dec 11 03:23:55 2022 daemon.info pppd[25517]: Connect time 8.5 minutes.
Sun Dec 11 03:23:55 2022 daemon.info pppd[25517]: Sent 8495665 bytes, received 24744729 bytes.
Sun Dec 11 03:23:55 2022 daemon.err miniupnpd[14833]: ioctl(s, SIOCGIFADDR, ...): Address not available
Sun Dec 11 03:23:55 2022 daemon.err miniupnpd[14833]: Failed to get IP for interface pppoe-wan
Sun Dec 11 03:23:55 2022 daemon.warn miniupnpd[14833]: SendNATPMPPublicAddressChangeNotification: cannot get public IP address, stopping
Sun Dec 11 03:23:55 2022 daemon.notice netifd: Network device 'pppoe-wan' link is down
Sun Dec 11 03:23:55 2022 daemon.notice pppd[25517]: Connection terminated.
Sun Dec 11 03:23:55 2022 daemon.info pppd[25517]: Connect time 8.5 minutes.
Sun Dec 11 03:23:55 2022 daemon.info pppd[25517]: Sent 8495665 bytes, received 24744729 bytes.
Sun Dec 11 03:23:55 2022 daemon.info pppd[25517]: Exit.
Sun Dec 11 03:23:55 2022 daemon.notice netifd: Interface 'wan' is now down
Sun Dec 11 03:23:55 2022 daemon.notice netifd: Interface 'wan' is disabled
Sun Dec 11 03:23:55 2022 daemon.notice netifd: Interface 'wan' is enabled
Sun Dec 11 03:23:55 2022 daemon.notice netifd: Interface 'wan' is setting up now
Sun Dec 11 03:23:55 2022 kern.info kernel: [ 4415.555868] 8021q: adding VLAN 0 to HW filter on device eth5
Sun Dec 11 03:23:55 2022 daemon.notice netifd: Network device 'eth5' link is down
Sun Dec 11 03:23:55 2022 daemon.notice netifd: Interface 'wan' has link connectivity loss
Sun Dec 11 03:23:55 2022 daemon.notice netifd: wan (29506): uci: Entry not found
Sun Dec 11 03:23:55 2022 daemon.notice netifd: wan (29506): sh: out of range
Sun Dec 11 03:23:55 2022 daemon.notice netifd: wan (29506): Command failed: ubus call network.interface notify_proto { "action": 1, "command": [ "\/usr\/sbin\/pppd", "nodetach", "ipparam", "wan", "ifname", "pppoe-wan", "nodefaultroute", "usepeerdns", "maxfail", "1", "user", "", "password", "", "ip-up-script", "\/lib\/netifd\/ppp-up", "ip-down-script", "\/lib\/netifd\/ppp-down", "mtu", "1492", "mru", "1492", "plugin", "pppoe.so", "nic-eth5" ], "interface": "wan" } (Permission denied)
Sun Dec 11 03:23:55 2022 daemon.notice netifd: Interface 'wan' is now down
Sun Dec 11 03:23:55 2022 daemon.notice netifd: Interface 'wan' is disabled
Sun Dec 11 03:23:55 2022 daemon.notice netifd: Interface 'wan' is enabled
Sun Dec 11 03:23:55 2022 kern.info kernel: [ 4415.647835] 8021q: adding VLAN 0 to HW filter on device eth5
Sun Dec 11 03:23:55 2022 user.notice ddns-scripts[25898]: DNSPOD: PID '25898' terminated by 'SIGTERM' at 2022-12-11 03:23
Sun Dec 11 03:23:58 2022 kern.info kernel: [ 4418.527724] igb 0000:06:00.0 eth5: igb: eth5 NIC Link is Up 1000 Mbps Full Duplex, Flow Control: RX
Sun Dec 11 03:23:58 2022 daemon.notice netifd: Network device 'eth5' link is up
Sun Dec 11 03:23:58 2022 daemon.notice netifd: Interface 'wan' has link connectivity
Sun Dec 11 03:23:58 2022 daemon.notice netifd: Interface 'wan' is setting up now
Sun Dec 11 03:23:58 2022 daemon.notice netifd: wan (29787): uci: Entry not found
Sun Dec 11 03:23:58 2022 daemon.notice netifd: wan (29787): sh: out of range
Sun Dec 11 03:23:58 2022 daemon.info pppd[29795]: Plugin pppoe.so loaded.
Sun Dec 11 03:23:58 2022 daemon.info pppd[29795]: PPPoE plugin from pppd 2.4.9
Sun Dec 11 03:23:58 2022 daemon.notice pppd[29795]: pppd 2.4.9 started by root, uid 0
Sun Dec 11 03:23:58 2022 daemon.info pppd[29795]: PPP session is 5076
Sun Dec 11 03:23:58 2022 daemon.warn pppd[29795]: Connected to via interface eth5
Sun Dec 11 03:23:58 2022 kern.info kernel: [ 4418.972397] pppoe-wan: renamed from ppp0
Sun Dec 11 03:23:58 2022 daemon.info pppd[29795]: Renamed interface ppp0 to pppoe-wan
Sun Dec 11 03:23:58 2022 daemon.info pppd[29795]: Using interface pppoe-wan
Sun Dec 11 03:23:58 2022 daemon.notice pppd[29795]: Connect: pppoe-wan <--> eth5
Sun Dec 11 03:24:01 2022 daemon.info pppd[29795]: syncppp not active
Sun Dec 11 03:24:01 2022 daemon.info pppd[29795]: Remote message: Authentication success,Welcome!
Sun Dec 11 03:24:01 2022 daemon.notice pppd[29795]: PAP authentication succeeded
Sun Dec 11 03:24:01 2022 daemon.notice pppd[29795]: peer from calling number authorized
Sun Dec 11 03:24:01 2022 daemon.notice pppd[29795]: local LL address
Sun Dec 11 03:24:01 2022 daemon.notice pppd[29795]: remote LL address
Sun Dec 11 03:24:01 2022 daemon.notice pppd[29795]: local IP address
Sun Dec 11 03:24:01 2022 daemon.notice pppd[29795]: remote IP address
Sun Dec 11 03:24:01 2022 daemon.notice pppd[29795]: primary DNS address
Sun Dec 11 03:24:01 2022 daemon.notice pppd[29795]: secondary DNS address
Sun Dec 11 03:24:01 2022 daemon.notice netifd: Network device 'pppoe-wan' link is up
Sun Dec 11 03:24:01 2022 daemon.notice netifd: Interface 'wan' is now up
Sun Dec 11 03:24:02 2022 user.notice firewall: Reloading firewall due to ifup of wan (pppoe-wan)
Sun Dec 11 03:24:02 2022 user.notice ddns-scripts[30167]: DNSPOD: PID '30167' started at 2022-12-11 03:24
GreatMichaelLee
commented
1 year ago
1.关于你要提交的问题
Q：是否搜索了issue (使用 "x" 选择)
2. 详细叙述
(1) 具体问题
WAN 口重连后有概率导致路由器后台登录页面/管理页面加载缓慢，再次重连 WAN 口才能恢复。问题复现概率比较低，想要复现问题需要多次重连 WAN 口。
手动重连 WAN 口、运营商定时重新拨号都有可能导致该问题，两种情况我都遇到了，近三个月的源码都有遇到。
具体表现： 浏览器刷新登录页面会发现加载缓慢或转圈时间过长，F12 查看后发现部分请求耗时过长，同时 SSH 查看 CPU 占用并无异常。测试重启部分插件、重启防火墙均不能解决问题，再次重连 WAN 口后恢复正常。
截图举例两个加载缓慢的请求： http://192.168.1.1/cgi-bin/luci/ https://192.168.1.1/luci-static/resources/cbi/up.gif?0.6254013145419357
第一个请求在正常情况下，会在 100 ms 左右加载完成 第二个请求可能是验证是否配置了有效的证书，正常情况下这个请求会在 20 ms 以内完成
(2) 路由器型号和固件版本
X86 | 3865U R22.12.1 | K5.15.82
仅编译了常用插件：Passwall、AdGuard Home、动态 DNS、UPnP，其他例如 Turbo ACC 等插件均未编译进固件 由于关闭了 Passwall 仍然可以手动复现该问题，所以先在这边提 Issues。
(3) 详细日志
日志中部分 IP 地址、Mac 地址信息已删除或用星号代替
多次手动重连 WAN 口，问题复现，本次重连日志： Sun Dec 11 02:39:27 2022 daemon.info pppd[9396]: Terminating on signal 15 Sun Dec 11 02:39:27 2022 daemon.info pppd[9396]: Connect time 1.4 minutes. Sun Dec 11 02:39:27 2022 daemon.info pppd[9396]: Sent 1684299 bytes, received 4001750 bytes. Sun Dec 11 02:39:27 2022 daemon.err miniupnpd[4180]: ioctl(s, SIOCGIFADDR, ...): Address not available Sun Dec 11 02:39:27 2022 daemon.err miniupnpd[4180]: Failed to get IP for interface pppoe-wan Sun Dec 11 02:39:27 2022 daemon.warn miniupnpd[4180]: SendNATPMPPublicAddressChangeNotification: cannot get public IP address, stopping Sun Dec 11 02:39:27 2022 daemon.notice netifd: Network device 'pppoe-wan' link is down Sun Dec 11 02:39:27 2022 daemon.notice pppd[9396]: Connection terminated. Sun Dec 11 02:39:27 2022 daemon.info pppd[9396]: Connect time 1.4 minutes. Sun Dec 11 02:39:27 2022 daemon.info pppd[9396]: Sent 1684299 bytes, received 4001750 bytes. Sun Dec 11 02:39:27 2022 daemon.info pppd[9396]: Exit. Sun Dec 11 02:39:27 2022 daemon.notice netifd: Interface 'wan' is now down Sun Dec 11 02:39:27 2022 daemon.notice netifd: Interface 'wan' is disabled Sun Dec 11 02:39:27 2022 daemon.notice netifd: Interface 'wan' is enabled Sun Dec 11 02:39:27 2022 daemon.notice netifd: Interface 'wan' is setting up now Sun Dec 11 02:39:27 2022 kern.info kernel: [ 1747.236734] 8021q: adding VLAN 0 to HW filter on device eth5 Sun Dec 11 02:39:27 2022 daemon.notice netifd: Network device 'eth5' link is down Sun Dec 11 02:39:27 2022 daemon.notice netifd: Interface 'wan' has link connectivity loss Sun Dec 11 02:39:27 2022 daemon.notice netifd: wan (13405): uci: Entry not found Sun Dec 11 02:39:27 2022 daemon.notice netifd: wan (13405): sh: out of range Sun Dec 11 02:39:27 2022 daemon.notice netifd: wan (13405): Command failed: ubus call network.interface notify_proto { "action": 1, "command": [ "\/usr\/sbin\/pppd", "nodetach", "ipparam", "wan", "ifname", "pppoe-wan", "nodefaultroute", "usepeerdns", "maxfail", "1", "user", "", "password", "", "ip-up-script", "\/lib\/netifd\/ppp-up", "ip-down-script", "\/lib\/netifd\/ppp-down", "mtu", "1492", "mru", "1492", "plugin", "pppoe.so", "nic-eth5" ], "interface": "wan" } (Permission denied) Sun Dec 11 02:39:27 2022 daemon.notice netifd: Interface 'wan' is now down Sun Dec 11 02:39:27 2022 daemon.notice netifd: Interface 'wan' is disabled Sun Dec 11 02:39:27 2022 daemon.notice netifd: Interface 'wan' is enabled Sun Dec 11 02:39:27 2022 kern.info kernel: [ 1747.324730] 8021q: adding VLAN 0 to HW filter on device eth5 Sun Dec 11 02:39:27 2022 user.notice ddns-scripts[9897]: DNSPOD: PID '9897' terminated by 'SIGTERM' at 2022-12-11 02:39 Sun Dec 11 02:39:30 2022 kern.info kernel: [ 1750.208588] igb 0000:06:00.0 eth5: igb: eth5 NIC Link is Up 1000 Mbps Full Duplex, Flow Control: RX Sun Dec 11 02:39:30 2022 daemon.notice netifd: Network device 'eth5' link is up Sun Dec 11 02:39:30 2022 daemon.notice netifd: Interface 'wan' has link connectivity Sun Dec 11 02:39:30 2022 daemon.notice netifd: Interface 'wan' is setting up now Sun Dec 11 02:39:30 2022 daemon.notice netifd: wan (13683): uci: Entry not found Sun Dec 11 02:39:30 2022 daemon.notice netifd: wan (13683): sh: out of range Sun Dec 11 02:39:30 2022 daemon.info pppd[13691]: Plugin pppoe.so loaded. Sun Dec 11 02:39:30 2022 daemon.info pppd[13691]: PPPoE plugin from pppd 2.4.9 Sun Dec 11 02:39:30 2022 daemon.notice pppd[13691]: pppd 2.4.9 started by root, uid 0 Sun Dec 11 02:39:35 2022 daemon.info pppd[13691]: PPP session is 19144 Sun Dec 11 02:39:35 2022 daemon.warn pppd[13691]: Connected to via interface eth5 Sun Dec 11 02:39:35 2022 kern.info kernel: [ 1755.528499] pppoe-wan: renamed from ppp0 Sun Dec 11 02:39:35 2022 daemon.info pppd[13691]: Renamed interface ppp0 to pppoe-wan Sun Dec 11 02:39:35 2022 daemon.info pppd[13691]: Using interface pppoe-wan Sun Dec 11 02:39:35 2022 daemon.notice pppd[13691]: Connect: pppoe-wan <--> eth5 Sun Dec 11 02:39:38 2022 daemon.info pppd[13691]: syncppp not active Sun Dec 11 02:39:38 2022 daemon.info pppd[13691]: Remote message: Authentication success,Welcome! Sun Dec 11 02:39:38 2022 daemon.notice pppd[13691]: PAP authentication succeeded Sun Dec 11 02:39:38 2022 daemon.notice pppd[13691]: peer from calling number authorized Sun Dec 11 02:39:38 2022 daemon.notice pppd[13691]: local LL address Sun Dec 11 02:39:38 2022 daemon.notice pppd[13691]: remote LL address Sun Dec 11 02:39:38 2022 daemon.notice pppd[13691]: local IP address Sun Dec 11 02:39:38 2022 daemon.notice pppd[13691]: remote IP address Sun Dec 11 02:39:38 2022 daemon.notice pppd[13691]: primary DNS address Sun Dec 11 02:39:38 2022 daemon.notice pppd[13691]: secondary DNS address Sun Dec 11 02:39:38 2022 daemon.notice netifd: Network device 'pppoe-wan' link is up Sun Dec 11 02:39:38 2022 daemon.notice netifd: Interface 'wan' is now up Sun Dec 11 02:39:38 2022 user.notice firewall: Reloading firewall due to ifup of wan (pppoe-wan) Sun Dec 11 02:39:39 2022 user.notice ddns-scripts[14236]: DNSPOD: PID '14236' started at 2022-12-11 02:39
本次复现问题后，再次重连 WAN 口，问题解决，本次重连日志： Sun Dec 11 03:13:18 2022 daemon.info pppd[13691]: Terminating on signal 15 Sun Dec 11 03:13:18 2022 daemon.info pppd[13691]: Connect time 33.7 minutes. Sun Dec 11 03:13:18 2022 daemon.info pppd[13691]: Sent 13846980 bytes, received 82062707 bytes. Sun Dec 11 03:13:18 2022 daemon.err miniupnpd[14833]: ioctl(s, SIOCGIFADDR, ...): Address not available Sun Dec 11 03:13:18 2022 daemon.err miniupnpd[14833]: Failed to get IP for interface pppoe-wan Sun Dec 11 03:13:18 2022 daemon.warn miniupnpd[14833]: SendNATPMPPublicAddressChangeNotification: cannot get public IP address, stopping Sun Dec 11 03:13:18 2022 daemon.notice netifd: Network device 'pppoe-wan' link is down Sun Dec 11 03:13:18 2022 daemon.notice pppd[13691]: Connection terminated. Sun Dec 11 03:13:18 2022 daemon.info pppd[13691]: Connect time 33.7 minutes. Sun Dec 11 03:13:18 2022 daemon.info pppd[13691]: Sent 13846980 bytes, received 82062707 bytes. Sun Dec 11 03:13:18 2022 daemon.info pppd[13691]: Exit. Sun Dec 11 03:13:18 2022 daemon.notice netifd: Interface 'wan' is now down Sun Dec 11 03:13:18 2022 daemon.notice netifd: Interface 'wan' is disabled Sun Dec 11 03:13:18 2022 daemon.notice netifd: Interface 'wan' is enabled Sun Dec 11 03:13:18 2022 daemon.notice netifd: Interface 'wan' is setting up now Sun Dec 11 03:13:18 2022 daemon.notice netifd: Network device 'eth5' link is down Sun Dec 11 03:13:18 2022 daemon.notice netifd: Interface 'wan' has link connectivity loss Sun Dec 11 03:13:18 2022 kern.info kernel: [ 3779.035875] 8021q: adding VLAN 0 to HW filter on device eth5 Sun Dec 11 03:13:18 2022 daemon.notice netifd: wan (16966): uci: Entry not found Sun Dec 11 03:13:18 2022 daemon.notice netifd: wan (16966): sh: out of range Sun Dec 11 03:13:18 2022 daemon.notice netifd: wan (16966): Command failed: ubus call network.interface notify_proto { "action": 1, "command": [ "\/usr\/sbin\/pppd", "nodetach", "ipparam", "wan", "ifname", "pppoe-wan", "nodefaultroute", "usepeerdns", "maxfail", "1", "user", "", "password", "", "ip-up-script", "\/lib\/netifd\/ppp-up", "ip-down-script", "\/lib\/netifd\/ppp-down", "mtu", "1492", "mru", "1492", "plugin", "pppoe.so", "nic-eth5" ], "interface": "wan" } (Permission denied) Sun Dec 11 03:13:18 2022 daemon.notice netifd: Interface 'wan' is now down Sun Dec 11 03:13:18 2022 daemon.notice netifd: Interface 'wan' is disabled Sun Dec 11 03:13:19 2022 daemon.notice netifd: Interface 'wan' is enabled Sun Dec 11 03:13:19 2022 kern.info kernel: [ 3779.123868] 8021q: adding VLAN 0 to HW filter on device eth5 Sun Dec 11 03:13:19 2022 user.notice ddns-scripts[14968]: DNSPOD: PID '14968' terminated by 'SIGTERM' at 2022-12-11 03:13 Sun Dec 11 03:13:21 2022 kern.info kernel: [ 3782.043773] igb 0000:06:00.0 eth5: igb: eth5 NIC Link is Up 1000 Mbps Full Duplex, Flow Control: RX Sun Dec 11 03:13:22 2022 daemon.notice netifd: Network device 'eth5' link is up Sun Dec 11 03:13:22 2022 daemon.notice netifd: Interface 'wan' has link connectivity Sun Dec 11 03:13:22 2022 daemon.notice netifd: Interface 'wan' is setting up now Sun Dec 11 03:13:22 2022 daemon.notice netifd: wan (17235): uci: Entry not found Sun Dec 11 03:13:22 2022 daemon.notice netifd: wan (17235): sh: out of range Sun Dec 11 03:13:22 2022 daemon.info pppd[17243]: Plugin pppoe.so loaded. Sun Dec 11 03:13:22 2022 daemon.info pppd[17243]: PPPoE plugin from pppd 2.4.9 Sun Dec 11 03:13:22 2022 daemon.notice pppd[17243]: pppd 2.4.9 started by root, uid 0 Sun Dec 11 03:13:27 2022 daemon.info pppd[17243]: PPP session is 24620 Sun Dec 11 03:13:27 2022 daemon.warn pppd[17243]: Connected to via interface eth5 Sun Dec 11 03:13:27 2022 kern.info kernel: [ 3787.387677] pppoe-wan: renamed from ppp0 Sun Dec 11 03:13:27 2022 daemon.info pppd[17243]: Renamed interface ppp0 to pppoe-wan Sun Dec 11 03:13:27 2022 daemon.info pppd[17243]: Using interface pppoe-wan Sun Dec 11 03:13:27 2022 daemon.notice pppd[17243]: Connect: pppoe-wan <--> eth5 Sun Dec 11 03:13:30 2022 daemon.info pppd[17243]: syncppp not active Sun Dec 11 03:13:30 2022 daemon.info pppd[17243]: Remote message: Authentication success,Welcome! Sun Dec 11 03:13:30 2022 daemon.notice pppd[17243]: PAP authentication succeeded Sun Dec 11 03:13:30 2022 daemon.notice pppd[17243]: peer from calling number authorized Sun Dec 11 03:13:33 2022 daemon.notice pppd[17243]: local IP address Sun Dec 11 03:13:33 2022 daemon.notice pppd[17243]: remote IP address Sun Dec 11 03:13:33 2022 daemon.notice pppd[17243]: primary DNS address Sun Dec 11 03:13:33 2022 daemon.notice pppd[17243]: secondary DNS address Sun Dec 11 03:13:33 2022 daemon.notice pppd[17243]: local LL address Sun Dec 11 03:13:33 2022 daemon.notice pppd[17243]: remote LL address Sun Dec 11 03:13:33 2022 daemon.notice netifd: Network device 'pppoe-wan' link is up Sun Dec 11 03:13:33 2022 daemon.notice netifd: Interface 'wan' is now up Sun Dec 11 03:13:33 2022 user.notice firewall: Reloading firewall due to ifup of wan (pppoe-wan) Sun Dec 11 03:13:33 2022 user.notice ddns-scripts[17627]: DNSPOD: PID '17627' started at 2022-12-11 03:13
关闭科学插件后，再次进行多次手动重连 WAN 口，问题仍然可以复现，本次重连日志： Sun Dec 11 03:15:02 2022 daemon.info pppd[21407]: Terminating on signal 15 Sun Dec 11 03:15:02 2022 daemon.info pppd[21407]: Connect time 0.4 minutes. Sun Dec 11 03:15:02 2022 daemon.info pppd[21407]: Sent 1080449 bytes, received 1422034 bytes. Sun Dec 11 03:15:02 2022 daemon.err miniupnpd[14833]: ioctl(s, SIOCGIFADDR, ...): Address not available Sun Dec 11 03:15:02 2022 daemon.err miniupnpd[14833]: Failed to get IP for interface pppoe-wan Sun Dec 11 03:15:02 2022 daemon.warn miniupnpd[14833]: SendNATPMPPublicAddressChangeNotification: cannot get public IP address, stopping Sun Dec 11 03:15:02 2022 daemon.notice netifd: Network device 'pppoe-wan' link is down Sun Dec 11 03:15:02 2022 daemon.notice pppd[21407]: Connection terminated. Sun Dec 11 03:15:02 2022 daemon.info pppd[21407]: Connect time 0.4 minutes. Sun Dec 11 03:15:02 2022 daemon.info pppd[21407]: Sent 1080449 bytes, received 1422034 bytes. Sun Dec 11 03:15:02 2022 daemon.info pppd[21407]: Exit. Sun Dec 11 03:15:02 2022 daemon.notice netifd: Interface 'wan' is now down Sun Dec 11 03:15:02 2022 daemon.notice netifd: Interface 'wan' is disabled Sun Dec 11 03:15:02 2022 kern.info kernel: [ 3882.409493] 8021q: adding VLAN 0 to HW filter on device eth5 Sun Dec 11 03:15:02 2022 daemon.notice netifd: Interface 'wan' is enabled Sun Dec 11 03:15:02 2022 daemon.notice netifd: Interface 'wan' is setting up now Sun Dec 11 03:15:02 2022 daemon.notice netifd: Network device 'eth5' link is down Sun Dec 11 03:15:02 2022 daemon.notice netifd: Interface 'wan' has link connectivity loss Sun Dec 11 03:15:02 2022 daemon.notice netifd: wan (25243): uci: Entry not found Sun Dec 11 03:15:02 2022 daemon.notice netifd: wan (25243): sh: out of range Sun Dec 11 03:15:02 2022 daemon.notice netifd: wan (25243): Command failed: ubus call network.interface notify_proto { "action": 1, "command": [ "\/usr\/sbin\/pppd", "nodetach", "ipparam", "wan", "ifname", "pppoe-wan", "nodefaultroute", "usepeerdns", "maxfail", "1", "user", "", "password", "", "ip-up-script", "\/lib\/netifd\/ppp-up", "ip-down-script", "\/lib\/netifd\/ppp-down", "mtu", "1492", "mru", "1492", "plugin", "pppoe.so", "nic-eth5" ], "interface": "wan" } (Permission denied) Sun Dec 11 03:15:02 2022 daemon.notice netifd: Interface 'wan' is now down Sun Dec 11 03:15:02 2022 daemon.notice netifd: Interface 'wan' is disabled Sun Dec 11 03:15:02 2022 daemon.notice netifd: Interface 'wan' is enabled Sun Dec 11 03:15:02 2022 kern.info kernel: [ 3882.497482] 8021q: adding VLAN 0 to HW filter on device eth5 Sun Dec 11 03:15:02 2022 user.notice ddns-scripts[21789]: DNSPOD: PID '21789' terminated by 'SIGTERM' at 2022-12-11 03:15 Sun Dec 11 03:15:05 2022 kern.info kernel: [ 3885.397392] igb 0000:06:00.0 eth5: igb: eth5 NIC Link is Up 1000 Mbps Full Duplex, Flow Control: RX Sun Dec 11 03:15:05 2022 daemon.notice netifd: Network device 'eth5' link is up Sun Dec 11 03:15:05 2022 daemon.notice netifd: Interface 'wan' has link connectivity Sun Dec 11 03:15:05 2022 daemon.notice netifd: Interface 'wan' is setting up now Sun Dec 11 03:15:05 2022 daemon.notice netifd: wan (25509): uci: Entry not found Sun Dec 11 03:15:05 2022 daemon.notice netifd: wan (25509): sh: out of range Sun Dec 11 03:15:05 2022 daemon.info pppd[25517]: Plugin pppoe.so loaded. Sun Dec 11 03:15:05 2022 daemon.info pppd[25517]: PPPoE plugin from pppd 2.4.9 Sun Dec 11 03:15:05 2022 daemon.notice pppd[25517]: pppd 2.4.9 started by root, uid 0 Sun Dec 11 03:15:10 2022 daemon.info pppd[25517]: PPP session is 2517 Sun Dec 11 03:15:10 2022 daemon.warn pppd[25517]: Connected to via interface eth5 Sun Dec 11 03:15:10 2022 kern.info kernel: [ 3890.717352] pppoe-wan: renamed from ppp0 Sun Dec 11 03:15:10 2022 daemon.info pppd[25517]: Renamed interface ppp0 to pppoe-wan Sun Dec 11 03:15:10 2022 daemon.info pppd[25517]: Using interface pppoe-wan Sun Dec 11 03:15:10 2022 daemon.notice pppd[25517]: Connect: pppoe-wan <--> eth5 Sun Dec 11 03:15:13 2022 daemon.info pppd[25517]: syncppp not active Sun Dec 11 03:15:16 2022 daemon.info pppd[25517]: syncppp not active Sun Dec 11 03:15:19 2022 daemon.info pppd[25517]: syncppp not active Sun Dec 11 03:15:22 2022 daemon.info pppd[25517]: syncppp not active Sun Dec 11 03:15:25 2022 daemon.info pppd[25517]: syncppp not active Sun Dec 11 03:15:28 2022 daemon.info pppd[25517]: syncppp not active Sun Dec 11 03:15:28 2022 daemon.info pppd[25517]: Remote message: Authentication success,Welcome! Sun Dec 11 03:15:28 2022 daemon.notice pppd[25517]: PAP authentication succeeded Sun Dec 11 03:15:28 2022 daemon.notice pppd[25517]: peer from calling number authorized Sun Dec 11 03:15:28 2022 daemon.notice pppd[25517]: local LL address Sun Dec 11 03:15:28 2022 daemon.notice pppd[25517]: remote LL address Sun Dec 11 03:15:28 2022 daemon.notice pppd[25517]: local IP address Sun Dec 11 03:15:28 2022 daemon.notice pppd[25517]: remote IP address Sun Dec 11 03:15:28 2022 daemon.notice pppd[25517]: primary DNS address Sun Dec 11 03:15:28 2022 daemon.notice pppd[25517]: secondary DNS address Sun Dec 11 03:15:28 2022 daemon.notice netifd: Network device 'pppoe-wan' link is up Sun Dec 11 03:15:28 2022 daemon.notice netifd: Interface 'wan' is now up Sun Dec 11 03:15:28 2022 user.notice firewall: Reloading firewall due to ifup of wan (pppoe-wan) Sun Dec 11 03:15:29 2022 user.notice ddns-scripts[25898]: DNSPOD: PID '25898' started at 2022-12-11 03:15
本次复现问题后，再次重连 WAN 口，问题解决，本次重连日志： Sun Dec 11 03:23:55 2022 daemon.info pppd[25517]: Terminating on signal 15 Sun Dec 11 03:23:55 2022 daemon.info pppd[25517]: Connect time 8.5 minutes. Sun Dec 11 03:23:55 2022 daemon.info pppd[25517]: Sent 8495665 bytes, received 24744729 bytes. Sun Dec 11 03:23:55 2022 daemon.err miniupnpd[14833]: ioctl(s, SIOCGIFADDR, ...): Address not available Sun Dec 11 03:23:55 2022 daemon.err miniupnpd[14833]: Failed to get IP for interface pppoe-wan Sun Dec 11 03:23:55 2022 daemon.warn miniupnpd[14833]: SendNATPMPPublicAddressChangeNotification: cannot get public IP address, stopping Sun Dec 11 03:23:55 2022 daemon.notice netifd: Network device 'pppoe-wan' link is down Sun Dec 11 03:23:55 2022 daemon.notice pppd[25517]: Connection terminated. Sun Dec 11 03:23:55 2022 daemon.info pppd[25517]: Connect time 8.5 minutes. Sun Dec 11 03:23:55 2022 daemon.info pppd[25517]: Sent 8495665 bytes, received 24744729 bytes. Sun Dec 11 03:23:55 2022 daemon.info pppd[25517]: Exit. Sun Dec 11 03:23:55 2022 daemon.notice netifd: Interface 'wan' is now down Sun Dec 11 03:23:55 2022 daemon.notice netifd: Interface 'wan' is disabled Sun Dec 11 03:23:55 2022 daemon.notice netifd: Interface 'wan' is enabled Sun Dec 11 03:23:55 2022 daemon.notice netifd: Interface 'wan' is setting up now Sun Dec 11 03:23:55 2022 kern.info kernel: [ 4415.555868] 8021q: adding VLAN 0 to HW filter on device eth5 Sun Dec 11 03:23:55 2022 daemon.notice netifd: Network device 'eth5' link is down Sun Dec 11 03:23:55 2022 daemon.notice netifd: Interface 'wan' has link connectivity loss Sun Dec 11 03:23:55 2022 daemon.notice netifd: wan (29506): uci: Entry not found Sun Dec 11 03:23:55 2022 daemon.notice netifd: wan (29506): sh: out of range Sun Dec 11 03:23:55 2022 daemon.notice netifd: wan (29506): Command failed: ubus call network.interface notify_proto { "action": 1, "command": [ "\/usr\/sbin\/pppd", "nodetach", "ipparam", "wan", "ifname", "pppoe-wan", "nodefaultroute", "usepeerdns", "maxfail", "1", "user", "", "password", "", "ip-up-script", "\/lib\/netifd\/ppp-up", "ip-down-script", "\/lib\/netifd\/ppp-down", "mtu", "1492", "mru", "1492", "plugin", "pppoe.so", "nic-eth5" ], "interface": "wan" } (Permission denied) Sun Dec 11 03:23:55 2022 daemon.notice netifd: Interface 'wan' is now down Sun Dec 11 03:23:55 2022 daemon.notice netifd: Interface 'wan' is disabled Sun Dec 11 03:23:55 2022 daemon.notice netifd: Interface 'wan' is enabled Sun Dec 11 03:23:55 2022 kern.info kernel: [ 4415.647835] 8021q: adding VLAN 0 to HW filter on device eth5 Sun Dec 11 03:23:55 2022 user.notice ddns-scripts[25898]: DNSPOD: PID '25898' terminated by 'SIGTERM' at 2022-12-11 03:23 Sun Dec 11 03:23:58 2022 kern.info kernel: [ 4418.527724] igb 0000:06:00.0 eth5: igb: eth5 NIC Link is Up 1000 Mbps Full Duplex, Flow Control: RX Sun Dec 11 03:23:58 2022 daemon.notice netifd: Network device 'eth5' link is up Sun Dec 11 03:23:58 2022 daemon.notice netifd: Interface 'wan' has link connectivity Sun Dec 11 03:23:58 2022 daemon.notice netifd: Interface 'wan' is setting up now Sun Dec 11 03:23:58 2022 daemon.notice netifd: wan (29787): uci: Entry not found Sun Dec 11 03:23:58 2022 daemon.notice netifd: wan (29787): sh: out of range Sun Dec 11 03:23:58 2022 daemon.info pppd[29795]: Plugin pppoe.so loaded. Sun Dec 11 03:23:58 2022 daemon.info pppd[29795]: PPPoE plugin from pppd 2.4.9 Sun Dec 11 03:23:58 2022 daemon.notice pppd[29795]: pppd 2.4.9 started by root, uid 0 Sun Dec 11 03:23:58 2022 daemon.info pppd[29795]: PPP session is 5076 Sun Dec 11 03:23:58 2022 daemon.warn pppd[29795]: Connected to via interface eth5 Sun Dec 11 03:23:58 2022 kern.info kernel: [ 4418.972397] pppoe-wan: renamed from ppp0 Sun Dec 11 03:23:58 2022 daemon.info pppd[29795]: Renamed interface ppp0 to pppoe-wan Sun Dec 11 03:23:58 2022 daemon.info pppd[29795]: Using interface pppoe-wan Sun Dec 11 03:23:58 2022 daemon.notice pppd[29795]: Connect: pppoe-wan <--> eth5 Sun Dec 11 03:24:01 2022 daemon.info pppd[29795]: syncppp not active Sun Dec 11 03:24:01 2022 daemon.info pppd[29795]: Remote message: Authentication success,Welcome! Sun Dec 11 03:24:01 2022 daemon.notice pppd[29795]: PAP authentication succeeded Sun Dec 11 03:24:01 2022 daemon.notice pppd[29795]: peer from calling number authorized Sun Dec 11 03:24:01 2022 daemon.notice pppd[29795]: local LL address Sun Dec 11 03:24:01 2022 daemon.notice pppd[29795]: remote LL address Sun Dec 11 03:24:01 2022 daemon.notice pppd[29795]: local IP address Sun Dec 11 03:24:01 2022 daemon.notice pppd[29795]: remote IP address Sun Dec 11 03:24:01 2022 daemon.notice pppd[29795]: primary DNS address Sun Dec 11 03:24:01 2022 daemon.notice pppd[29795]: secondary DNS address Sun Dec 11 03:24:01 2022 daemon.notice netifd: Network device 'pppoe-wan' link is up Sun Dec 11 03:24:01 2022 daemon.notice netifd: Interface 'wan' is now up Sun Dec 11 03:24:02 2022 user.notice firewall: Reloading firewall due to ifup of wan (pppoe-wan) Sun Dec 11 03:24:02 2022 user.notice ddns-scripts[30167]: DNSPOD: PID '30167' started at 2022-12-11 03:24