[OpenWrt-Devel] Oops while shutting down WAN

Weedy weedy2887 at gmail.com
Fri Aug 8 22:13:49 EDT 2014 

On Sun, Jul 27, 2014 at 3:34 PM, Weedy <weedy2887 at gmail.com> wrote:
> Something I discovered while dealing with the WAN bouncing problem.
> If you ifdown while pppd is setting up it pukes.
>
> Sat Jul 26 02:41:52 2014 kern.info kernel: [   23.080000] IPv6:
> ADDRCONF(NETDEV_UP): wlan0: link is not ready
> Sat Jul 26 02:41:53 2014 kern.info kernel: [   23.110000] device wlan0
> entered promiscuous mode
> Sat Jul 26 02:41:53 2014 kern.info kernel: [   23.110000] br-lan: port
> 3(wlan0) entered forwarding state
> Sat Jul 26 02:41:53 2014 kern.info kernel: [   23.120000] br-lan: port
> 3(wlan0) entered forwarding state
> Sat Jul 26 02:41:53 2014 kern.info kernel: [   23.780000] eth0: link down
> Sat Jul 26 02:41:53 2014 kern.info kernel: [   23.810000] br-lan: port
> 3(wlan0) entered disabled state
> Sat Jul 26 02:41:53 2014 kern.info kernel: [   23.810000] br-lan: port
> 1(eth0.1) entered disabled state
> Sat Jul 26 02:41:53 2014 daemon.notice netifd: Network device 'eth0'
> link is down
> Sat Jul 26 02:41:53 2014 daemon.notice netifd: VLAN 'eth0.2' link is down
> Sat Jul 26 02:41:53 2014 daemon.notice netifd: Interface 'wan' has
> link connectivity loss
> Sat Jul 26 02:41:53 2014 daemon.notice netifd: VLAN 'eth0.1' link is down
> Sat Jul 26 02:41:54 2014 kern.info kernel: [   24.650000] eth0: link
> up (1000Mbps/Full duplex)
> Sat Jul 26 02:41:54 2014 kern.info kernel: [   24.650000] br-lan: port
> 1(eth0.1) entered forwarding state
> Sat Jul 26 02:41:54 2014 kern.info kernel: [   24.660000] br-lan: port
> 1(eth0.1) entered forwarding state
> Sat Jul 26 02:41:54 2014 daemon.notice netifd: Network device 'eth0' link is up
> Sat Jul 26 02:41:54 2014 daemon.notice netifd: VLAN 'eth0.2' link is up
> Sat Jul 26 02:41:54 2014 daemon.notice netifd: Interface 'wan' has
> link connectivity
> Sat Jul 26 02:41:54 2014 daemon.notice netifd: VLAN 'eth0.1' link is up
> Sat Jul 26 02:41:55 2014 kern.info kernel: [   25.260000] br-lan: port
> 2(wlan1) entered forwarding state
> Sat Jul 26 02:41:55 2014 kern.info kernel: [   25.260000] br-lan: port
> 2(wlan1) entered forwarding state
> Sat Jul 26 02:41:55 2014 kern.info kernel: [   25.270000] IPv6:
> ADDRCONF(NETDEV_CHANGE): wlan1: link becomes ready
> Sat Jul 26 02:41:55 2014 kern.info kernel: [   25.300000] br-lan: port
> 3(wlan0) entered forwarding state
> Sat Jul 26 02:41:55 2014 kern.info kernel: [   25.310000] br-lan: port
> 3(wlan0) entered forwarding state
> Sat Jul 26 02:41:55 2014 kern.info kernel: [   25.310000] IPv6:
> ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready
> Sat Jul 26 02:41:55 2014 user.emerg syslog: setting up led USB1
> Sat Jul 26 02:41:55 2014 user.emerg syslog: setting up led USB2
> Sat Jul 26 02:41:55 2014 user.emerg syslog: setting up led WLAN2G
> Sat Jul 26 02:41:55 2014 daemon.notice netifd: Network device 'wlan1' link is up
> Sat Jul 26 02:41:55 2014 daemon.notice netifd: Network device 'wlan0' link is up
> Sat Jul 26 02:41:56 2014 user.emerg syslog: - init complete -
> Sat Jul 26 02:41:56 2014 kern.info kernel: [   26.660000] br-lan: port
> 1(eth0.1) entered forwarding state
> Sat Jul 26 02:41:57 2014 kern.info kernel: [   27.260000] br-lan: port
> 2(wlan1) entered forwarding state
> Sat Jul 26 02:41:57 2014 kern.info kernel: [   27.310000] br-lan: port
> 3(wlan0) entered forwarding state
> Sat Jul 26 02:41:58 2014 daemon.info dnsmasq[1696]: started, version
> 2.71 cachesize 4096
> Sat Jul 26 02:41:58 2014 daemon.info dnsmasq[1696]: compile time
> options: IPv6 GNU-getopt no-DBus no-i18n no-IDN DHCP no-DHCPv6 no-Lua
> TFTP no-conntrack no-ipset no-auth no-DNSSEC
> Sat Jul 26 02:41:58 2014 daemon.info dnsmasq-dhcp[1696]: DHCP, IP
> range 192.168.8.150 -- 192.168.8.199, lease time 3h
> Sat Jul 26 02:41:58 2014 daemon.info dnsmasq[1696]: using local
> addresses only for domain lan
> Sat Jul 26 02:41:58 2014 daemon.info dnsmasq[1696]: reading
> /etc/resolv.conf.opendns
> Sat Jul 26 02:41:58 2014 daemon.info dnsmasq[1696]: using local
> addresses only for domain lan
> Sat Jul 26 02:41:58 2014 daemon.info dnsmasq[1696]: using nameserver
> 66.163.0.161#53
> Sat Jul 26 02:41:58 2014 daemon.info dnsmasq[1696]: using nameserver
> 206.248.154.170#53
> Sat Jul 26 02:41:58 2014 daemon.info dnsmasq[1696]: using nameserver
> 206.248.154.22#53
> Sat Jul 26 02:41:58 2014 daemon.info dnsmasq[1696]: using nameserver 8.8.4.4#53
> Sat Jul 26 02:41:58 2014 daemon.info dnsmasq[1696]: read /etc/hosts -
> 5 addresses
> Sat Jul 26 02:41:58 2014 daemon.info dnsmasq-dhcp[1696]: read
> /etc/ethers - 13 addresses
> Sat Jul 26 02:41:59 2014 user.notice firewall: Reloading firewall due
> to ifup of lan (br-lan)
> Sat Jul 26 02:42:00 2014 user.notice upnp daemon: external interface
> not found, not starting
> Sat Jul 26 02:42:01 2014 cron.info crond[861]: crond: USER root pid
> 1857 cmd /bin/sh /etc/netCheck.sh
> Sat Jul 26 02:42:01 2014 daemon.info pppd[1107]: PPP session is 5594
> Sat Jul 26 02:42:01 2014 daemon.warn pppd[1107]: Connected to
> 00:90:1a:a3:b7:45 via interface eth0.2
> Sat Jul 26 02:42:01 2014 daemon.info pppd[1107]: Using interface pppoe-wan
> Sat Jul 26 02:42:01 2014 daemon.notice pppd[1107]: Connect: pppoe-wan
> <--> eth0.2
> Sat Jul 26 02:42:02 2014 daemon.info pppd[1107]: Remote message: lcp
> tunnel failed
> Sat Jul 26 02:42:02 2014 daemon.err pppd[1107]: PAP authentication failed
> Sat Jul 26 02:42:02 2014 daemon.notice pppd[1107]: Connection terminated.
> Sat Jul 26 02:42:02 2014 daemon.info pppd[1107]: Exit.
> Sat Jul 26 02:42:02 2014 daemon.notice netifd: Interface 'wan' is now down
> Sat Jul 26 02:42:02 2014 kern.info kernel: [   32.490000] IPv6:
> ADDRCONF(NETDEV_UP): eth0.2: link is not ready
> Sat Jul 26 02:42:02 2014 daemon.notice netifd: Interface 'wan' is disabled
> Sat Jul 26 02:42:02 2014 daemon.notice netifd: Interface 'wan' is enabled
> Sat Jul 26 02:42:02 2014 daemon.notice netifd: Interface 'wan' is setting up now
> Sat Jul 26 02:42:02 2014 daemon.notice netifd: VLAN 'eth0.2' link is down
> Sat Jul 26 02:42:02 2014 daemon.notice netifd: Interface 'wan' has
> link connectivity loss
> Sat Jul 26 02:42:02 2014 daemon.notice netifd: VLAN 'eth0.2' link is up
> Sat Jul 26 02:42:02 2014 daemon.notice netifd: Interface 'wan' has
> link connectivity
> Sat Jul 26 02:42:02 2014 daemon.info pppd[1942]: Plugin rp-pppoe.so loaded.
> Sat Jul 26 02:42:02 2014 daemon.info pppd[1942]: RP-PPPoE plugin
> version 3.8p compiled against pppd 2.4.6
> Sat Jul 26 02:42:02 2014 daemon.notice pppd[1942]: pppd 2.4.6 started
> by root, uid 0
> Sat Jul 26 02:42:03 2014 user.notice netCheck: Something is funky with
> the firewall, resetting it.
> Sat Jul 26 02:42:07 2014 daemon.info pppd[1942]: PPP session is 5605
> Sat Jul 26 02:42:07 2014 daemon.warn pppd[1942]: Connected to
> 00:90:1a:a3:b7:45 via interface eth0.2
> Sat Jul 26 02:42:07 2014 daemon.info pppd[1942]: Using interface pppoe-wan
> Sat Jul 26 02:42:07 2014 daemon.notice pppd[1942]: Connect: pppoe-wan
> <--> eth0.2
> Sat Jul 26 02:42:08 2014 daemon.info pppd[1942]: Remote message: lcp
> tunnel failed
> Sat Jul 26 02:42:08 2014 daemon.err pppd[1942]: PAP authentication failed
> Sat Jul 26 02:42:08 2014 daemon.notice pppd[1942]: Connection terminated.
> Sat Jul 26 02:42:08 2014 daemon.info pppd[1942]: Exit.
> Sat Jul 26 02:42:08 2014 daemon.notice netifd: Interface 'wan' is now down
> Sat Jul 26 02:42:08 2014 daemon.notice netifd: Interface 'wan' is disabled
> Sat Jul 26 02:42:08 2014 daemon.notice netifd: Interface 'wan' is enabled
> Sat Jul 26 02:42:08 2014 daemon.notice netifd: Interface 'wan' is setting up now
> Sat Jul 26 02:42:08 2014 daemon.notice netifd: VLAN 'eth0.2' link is down
> Sat Jul 26 02:42:08 2014 daemon.notice netifd: Interface 'wan' has
> link connectivity loss
> Sat Jul 26 02:42:08 2014 daemon.notice netifd: VLAN 'eth0.2' link is up
> Sat Jul 26 02:42:08 2014 daemon.notice netifd: Interface 'wan' has
> link connectivity
> Sat Jul 26 02:42:08 2014 kern.info kernel: [   38.700000] IPv6:
> ADDRCONF(NETDEV_UP): eth0.2: link is not ready
> Sat Jul 26 02:42:08 2014 daemon.info pppd[2069]: Plugin rp-pppoe.so loaded.
> Sat Jul 26 02:42:08 2014 daemon.info pppd[2069]: RP-PPPoE plugin
> version 3.8p compiled against pppd 2.4.6
> Sat Jul 26 02:42:08 2014 daemon.notice pppd[2069]: pppd 2.4.6 started
> by root, uid 0
> Sat Jul 26 02:48:11 2014 user.notice netCheck: Number of external
> hosts unavalible: 4
> Sat Jul 26 02:48:11 2014 user.notice netCheck: Restarting WAN
> Sat Jul 26 02:48:14 2014 daemon.info pppd[2069]: PPP session is 5609
> Sat Jul 26 02:48:14 2014 daemon.warn pppd[2069]: Connected to
> 00:90:1a:a3:b7:45 via interface eth0.2
> Sat Jul 26 02:48:14 2014 daemon.info pppd[2069]: Using interface pppoe-wan
> Sat Jul 26 02:48:14 2014 daemon.notice pppd[2069]: Connect: pppoe-wan
> <--> eth0.2
> Sat Jul 26 02:48:14 2014 daemon.info pppd[2069]: System time change detected.
> Sat Jul 26 02:48:14 2014 daemon.info pppd[2069]: Terminating on signal 15
> Sat Jul 26 02:48:17 2014 daemon.notice netifd: Interface 'wan' is now down
> Sat Jul 26 02:48:17 2014 kern.info kernel: [   47.160000] IPv6:
> ADDRCONF(NETDEV_UP): eth0.2: link is not ready
> Sat Jul 26 02:48:17 2014 kern.alert kernel: [   47.170000] CPU 0
> Unable to handle kernel paging request at virtual address 00000204,
> epc == 871277e4, ra == 871277a4
> Sat Jul 26 02:48:17 2014 kern.warn kernel: [   47.170000] Oops[#1]:
> Sat Jul 26 02:48:17 2014 kern.warn kernel: [   47.170000] CPU: 0 PID:
> 2069 Comm: pppd Not tainted 3.10.44 #2
> Sat Jul 26 02:48:17 2014 kern.warn kernel: [   47.170000] task:
> 86e125b0 ti: 86df2000 task.ti: 86df2000
> Sat Jul 26 02:48:17 2014 kern.warn kernel: [   47.170000] $ 0   :
> 00000000 00000001 00000001 00000000
> Sat Jul 26 02:48:17 2014 kern.warn kernel: [   47.170000] $ 4   :
> 864dac00 00000000 86e448c8 00000001
> Sat Jul 26 02:48:17 2014 kern.warn kernel: [   47.170000] $ 8   :
> 0010003d 0010003d 00000001 87548550
> Sat Jul 26 02:48:17 2014 kern.warn kernel: [   47.170000] $12   :
> 00000000 00000001 000f003d 8704cf74
> Sat Jul 26 02:48:17 2014 kern.warn kernel: [   47.170000] $16   :
> 864dac00 00000000 87543580 875435a0
> Sat Jul 26 02:48:17 2014 kern.warn kernel: [   47.170000] $20   :
> 86e448c8 875435a0 87807330 80310000
> Sat Jul 26 02:48:17 2014 kern.warn kernel: [   47.170000] $24   :
> 00000000 801ef6f0
> Sat Jul 26 02:48:17 2014 kern.warn kernel: [   47.170000] $28   :
> 86df2000 86df3cf0 802c6fd8 871277a4
> Sat Jul 26 02:48:17 2014 kern.warn kernel: [   47.170000] Hi    : 00000015
> Sat Jul 26 02:48:17 2014 kern.warn kernel: [   47.170000] Lo    : 00000006
> Sat Jul 26 02:48:17 2014 kern.warn kernel: [   47.170000] epc   :
> 871277e4 0x871277e4
> Sat Jul 26 02:48:17 2014 kern.warn kernel: [   47.170000]     Not tainted
> Sat Jul 26 02:48:17 2014 kern.warn kernel: [   47.170000] ra    :
> 871277a4 0x871277a4
> Sat Jul 26 02:48:17 2014 kern.warn kernel: [   47.170000] Status:
> 1100dc02    KERNEL EXL
> Sat Jul 26 02:48:17 2014 kern.warn kernel: [   47.170000] Cause : 00800008
> Sat Jul 26 02:48:17 2014 kern.warn kernel: [   47.170000] BadVA : 00000204
> Sat Jul 26 02:48:17 2014 kern.warn kernel: [   47.170000] PrId  :
> 0001974c (MIPS 74Kc)
> Sat Jul 26 02:48:17 2014 kern.warn kernel: [   47.170000] Modules
> linked in: ath9k ath9k_common pppoe ppp_async iptable_nat ath9k_hw ath
> pppox ppp_generic nf_nat_ipv4 nf_conntrack_ipv4 mac80211
> ipt_MASQUERADE cfg80211 xt_time xt_tcpudp xt_tcpmss xt_string
> xt_statistic xt_state xt_recent xt_nat xt_multiport xt_mark xt_mac
> xt_limit xt_length xt_hl xt_helper xt_ecn xt_dscp xt_conntrack
> xt_connmark xt_connlimit xt_connbytes xt_comment xt_TCPMSS xt_REDIRECT
> xt_LOG xt_HL xt_DSCP xt_CT xt_CLASSIFY ts_kmp ts_fsm tSat Jul 26
> 02:48:17 2014 kern.warn kernel: [   47.170000] Process pppd (pid:
> 2069, threadinfo=86df2000, task=86e125b0, tls=77c51440)
> Sat Jul 26 02:48:17 2014 kern.warn kernel: [   47.170000] Stack :
> 87543440 800fa754 87543420 8025ef94 875485d8 87543580 87127cc4
> 875484c8
> Sat Jul 26 02:48:17 2014 kern.warn kernel: [   47.170000]
> 875435a0 86e448c8 875435a0 801ef85c 86d84480 86539c80 875485d8
> 00000000
> Sat Jul 26 02:48:17 2014 kern.warn kernel: [   47.170000]
> 86e448c0 86e448c0 00000008 801ef908 86e448c8 875435a0 86e448c0
> 00000008
> Sat Jul 26 02:48:17 2014 kern.warn kernel: [   47.170000]
> 875484c8 800e66cc 810d3b80 86e440a0 00000000 800e30f4 00000000
> 00000000
> Sat Jul 26 02:48:17 2014 kern.warn kernel: [   47.170000]
> 86e125b0 86e44820 80380000 86d844bc 802c6fc8 00000001 80320000
> 800902a8
> Sat Jul 26 02:48:17 2014 kern.warn kernel: [   47.170000]       ...
> Sat Jul 26 02:48:17 2014 kern.warn kernel: [   47.170000] Call Trace:
> Sat Jul 26 02:48:17 2014 kern.warn kernel: [   47.170000] [<871277e4>]
> 0x871277e4
> Sat Jul 26 02:48:17 2014 kern.warn kernel: [   47.170000]
> Sat Jul 26 02:48:17 2014 kern.warn kernel: [   47.170000]
> Sat Jul 26 02:48:17 2014 kern.warn kernel: [   47.170000] Code:
> 41626000  30420001  000000c0 <8c630204> 8c640000  2484ffff  ac640000
> 10400002  41606000
> Sat Jul 26 02:48:17 2014 kern.warn kernel: [   47.420000] ---[ end
> trace f3d54a54d90270c9 ]---
> Sat Jul 26 02:48:17 2014 kern.alert kernel: [   47.430000] Fixing
> recursive fault but reboot is needed!
> Sat Jul 26 02:48:17 2014 daemon.notice netifd: Interface 'wan' is disabled
> Sat Jul 26 02:48:17 2014 daemon.notice netifd: Interface 'wan' is enabled
> Sat Jul 26 02:48:17 2014 daemon.notice netifd: Interface 'wan' is setting up now
> Sat Jul 26 02:48:17 2014 daemon.notice netifd: VLAN 'eth0.2' link is down
> Sat Jul 26 02:48:17 2014 daemon.notice netifd: Interface 'wan' has
> link connectivity loss
> Sat Jul 26 02:48:17 2014 daemon.notice netifd: VLAN 'eth0.2' link is up
> Sat Jul 26 02:48:17 2014 daemon.notice netifd: Interface 'wan' has
> link connectivity
> Sat Jul 26 02:48:17 2014 daemon.info pppd[2174]: Plugin rp-pppoe.so loaded.
> Sat Jul 26 02:48:17 2014 daemon.info pppd[2174]: RP-PPPoE plugin
> version 3.8p compiled against pppd 2.4.6
> Sat Jul 26 02:48:17 2014 daemon.notice pppd[2174]: pppd 2.4.6 started
> by root, uid 0
> Sat Jul 26 02:48:17 2014 daemon.info pppd[2174]: PPP session is 5611
> Sat Jul 26 02:48:17 2014 daemon.warn pppd[2174]: Connected to
> 00:90:1a:a3:b7:45 via interface eth0.2
> Sat Jul 26 02:48:17 2014 daemon.err pppd[2174]: Couldn't rename ppp1
> to pppoe-wan
> Sat Jul 26 02:48:17 2014 daemon.info pppd[2174]: Exit.

bump
_______________________________________________
openwrt-devel mailing list
openwrt-devel at lists.openwrt.org
https://lists.openwrt.org/cgi-bin/mailman/listinfo/openwrt-devel

More information about the openwrt-devel mailing list