[OpenWrt-Devel] Oops while shutting down WAN

Weedy weedy2887 at gmail.com
Sun Jul 27 15:34:27 EDT 2014


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.
_______________________________________________
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