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@lists.openwrt.org https://lists.openwrt.org/cgi-bin/mailman/listinfo/openwrt-devel
