Is there anything I can do to stop this? It started sometime in the last 6months of trunk. Right after this and couple minutes after boot my healing script fires and detects that WAN is broken and calls ifdown; sleep; ifup at which point I get an IP and keep it. But why it the WAN goinig up and down during boot?
Sun Jul 27 15:08:33 2014 user.info syslog: module is already loaded - cls_u32 Sun Jul 27 15:08:33 2014 kern.emerg already loaded - cls_u32 Sun Jul 27 15:08:33 2014 user.info syslog: module is already loaded - em_u32 Sun Jul 27 15:08:33 2014 kern.emerg already loaded - em_u32 Sun Jul 27 15:08:33 2014 user.info syslog: module is already loaded - act_connmark Sun Jul 27 15:08:33 2014 kern.emerg already loaded - act_connmark Sun Jul 27 15:08:33 2014 user.info syslog: module is already loaded - act_mirred Sun Jul 27 15:08:33 2014 kern.emerg already loaded - act_mirred Sun Jul 27 15:08:33 2014 user.info syslog: module is already loaded - sch_ingress Sun Jul 27 15:08:33 2014 kern.emerg already loaded - sch_ingress Sun Jul 27 15:08:33 2014 user.info syslog: module is already loaded - cls_fw Sun Jul 27 15:08:33 2014 kern.emerg already loaded - cls_fw Sun Jul 27 15:08:33 2014 user.info syslog: module is already loaded - sch_hfsc Sun Jul 27 15:08:33 2014 kern.emerg already loaded - sch_hfsc Sun Jul 27 15:08:33 2014 user.emerg syslog: Cannot find device "eth0.2" Sun Jul 27 15:08:33 2014 user.emerg syslog: Cannot find device "eth0.2" Sun Jul 27 15:08:33 2014 user.emerg syslog: Cannot find device "eth0.2" Sun Jul 27 15:08:33 2014 user.emerg syslog: Cannot find device "eth0.2" Sun Jul 27 15:08:33 2014 user.emerg syslog: Cannot find device "eth0.2" Sun Jul 27 15:08:33 2014 user.emerg syslog: Cannot find device "eth0.2" Sun Jul 27 15:08:33 2014 user.emerg syslog: Cannot find device "eth0.2" Sun Jul 27 15:08:33 2014 user.emerg syslog: Cannot find device "eth0.2" Sun Jul 27 15:08:33 2014 user.emerg syslog: Cannot find device "eth0.2" Sun Jul 27 15:08:33 2014 user.emerg syslog: Cannot find device "eth0.2" Sun Jul 27 15:08:33 2014 user.emerg syslog: Cannot find device "eth0.2" Sun Jul 27 15:08:33 2014 user.emerg syslog: Cannot find device "eth0.2" Sun Jul 27 15:08:33 2014 user.emerg syslog: Cannot find device "eth0.2" Sun Jul 27 15:08:33 2014 user.emerg syslog: Cannot find device "eth0.2" Sun Jul 27 15:08:33 2014 user.emerg syslog: Cannot find device "eth0.2" Sun Jul 27 15:08:33 2014 user.emerg syslog: Cannot find device "eth0.2" Sun Jul 27 15:08:33 2014 user.emerg syslog: Cannot find device "eth0.2" Sun Jul 27 15:08:34 2014 user.emerg syslog: Cannot find device "eth0.2" Sun Jul 27 15:08:34 2014 user.emerg syslog: Cannot find device "eth0.2" Sun Jul 27 15:08:34 2014 user.emerg syslog: Cannot find device "eth0.2" Sun Jul 27 15:08:34 2014 kern.info kernel: [ 20.720000] IPv6: ADDRCONF(NETDEV_UP): eth0: link is not ready Sun Jul 27 15:08:34 2014 kern.info kernel: [ 20.720000] IPv6: ADDRCONF(NETDEV_UP): eth0.1: link is not ready Sun Jul 27 15:08:34 2014 kern.info kernel: [ 20.740000] device eth0.1 entered promiscuous mode Sun Jul 27 15:08:34 2014 kern.info kernel: [ 20.740000] device eth0 entered promiscuous mode Sun Jul 27 15:08:34 2014 daemon.notice netifd: Interface 'lan' is enabled Sun Jul 27 15:08:34 2014 daemon.notice netifd: Interface 'loopback' is enabled Sun Jul 27 15:08:34 2014 kern.info kernel: [ 20.770000] IPv6: ADDRCONF(NETDEV_UP): br-lan: link is not ready Sun Jul 27 15:08:34 2014 daemon.notice netifd: Interface 'wan' is enabled Sun Jul 27 15:08:34 2014 kern.info kernel: [ 20.800000] IPv6: ADDRCONF(NETDEV_UP): eth0.2: link is not ready Sun Jul 27 15:08:34 2014 daemon.notice netifd: Network device 'lo' link is up Sun Jul 27 15:08:34 2014 daemon.notice netifd: Interface 'loopback' has link connectivity Sun Jul 27 15:08:34 2014 daemon.notice netifd: Interface 'loopback' is setting up now Sun Jul 27 15:08:34 2014 daemon.notice netifd: Interface 'loopback' is now up Sun Jul 27 15:08:34 2014 kern.info kernel: [ 20.880000] eth0: link up (1000Mbps/Full duplex) Sun Jul 27 15:08:34 2014 daemon.notice netifd: Network device 'eth0' link is up Sun Jul 27 15:08:34 2014 daemon.notice netifd: VLAN 'eth0.2' link is up Sun Jul 27 15:08:34 2014 daemon.notice netifd: Interface 'wan' has link connectivity Sun Jul 27 15:08:34 2014 daemon.notice netifd: Interface 'wan' is setting up now Sun Jul 27 15:08:34 2014 daemon.notice netifd: VLAN 'eth0.1' link is up Sun Jul 27 15:08:34 2014 kern.info kernel: [ 20.920000] IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready Sun Jul 27 15:08:35 2014 kern.info kernel: [ 20.920000] br-lan: port 1(eth0.1) entered forwarding state Sun Jul 27 15:08:35 2014 kern.info kernel: [ 20.930000] br-lan: port 1(eth0.1) entered forwarding state Sun Jul 27 15:08:35 2014 kern.info kernel: [ 20.940000] IPv6: ADDRCONF(NETDEV_CHANGE): eth0.1: link becomes ready Sun Jul 27 15:08:35 2014 kern.info kernel: [ 20.940000] IPv6: ADDRCONF(NETDEV_CHANGE): eth0.2: link becomes ready Sun Jul 27 15:08:35 2014 kern.info kernel: [ 21.000000] IPv6: ADDRCONF(NETDEV_CHANGE): br-lan: link becomes ready Sun Jul 27 15:08:35 2014 daemon.notice netifd: Bridge 'br-lan' link is up Sun Jul 27 15:08:35 2014 daemon.notice netifd: Interface 'lan' has link connectivity Sun Jul 27 15:08:35 2014 daemon.notice netifd: Interface 'lan' is setting up now Sun Jul 27 15:08:35 2014 daemon.notice netifd: Interface 'lan' is now up Sun Jul 27 15:08:35 2014 daemon.warn odhcpd[926]: Failed to send to ff02::1%br-lan (Cannot assign requested address) Sun Jul 27 15:08:36 2014 daemon.info pppd[1361]: Plugin rp-pppoe.so loaded. Sun Jul 27 15:08:36 2014 daemon.info pppd[1361]: RP-PPPoE plugin version 3.8p compiled against pppd 2.4.6 Sun Jul 27 15:08:36 2014 daemon.notice pppd[1361]: pppd 2.4.6 started by root, uid 0 Sun Jul 27 15:08:36 2014 daemon.debug pppd[1361]: Send PPPOE Discovery V1T1 PADI session 0x0 length 12 Sun Jul 27 15:08:36 2014 daemon.debug pppd[1361]: dst ff:ff:ff:ff:ff:ff src 64:70:02:e2:a4:28 Sun Jul 27 15:08:36 2014 daemon.debug pppd[1361]: [service-name] [host-uniq 00 00 05 51] Sun Jul 27 15:08:36 2014 daemon.debug pppd[1361]: Recv PPPOE Discovery V1T1 PADO session 0x0 length 53 Sun Jul 27 15:08:36 2014 daemon.debug pppd[1361]: dst 64:70:02:e2:a4:28 src 00:90:1a:a3:b7:45 Sun Jul 27 15:08:36 2014 daemon.debug pppd[1361]: [AC-name bas1-woodbridge48] [host-uniq 00 00 05 51] [service-name] [AC-cookie c3 69 03 d2 09 e3 af a5 dc 7a 62 b4 c1 8f d5 6e] Sun Jul 27 15:08:36 2014 daemon.debug pppd[1361]: Send PPPOE Discovery V1T1 PADR session 0x0 length 32 Sun Jul 27 15:08:36 2014 daemon.debug pppd[1361]: dst 00:90:1a:a3:b7:45 src 64:70:02:e2:a4:28 Sun Jul 27 15:08:36 2014 daemon.debug pppd[1361]: [service-name] [host-uniq 00 00 05 51] [AC-cookie c3 69 03 d2 09 e3 af a5 dc 7a 62 b4 c1 8f d5 6e] Sun Jul 27 15:08:36 2014 daemon.debug pppd[1361]: Recv PPPOE Discovery V1T1 PADS session 0x3242 length 12 Sun Jul 27 15:08:36 2014 daemon.debug pppd[1361]: dst 64:70:02:e2:a4:28 src 00:90:1a:a3:b7:45 Sun Jul 27 15:08:36 2014 daemon.debug pppd[1361]: [service-name] [host-uniq 00 00 05 51] Sun Jul 27 15:08:36 2014 daemon.debug pppd[1361]: PADS: Service-Name: '' Sun Jul 27 15:08:36 2014 daemon.info pppd[1361]: PPP session is 12866 Sun Jul 27 15:08:36 2014 daemon.warn pppd[1361]: Connected to 00:90:1a:a3:b7:45 via interface eth0.2 Sun Jul 27 15:08:36 2014 daemon.debug pppd[1361]: using channel 1 Sun Jul 27 15:08:36 2014 daemon.info pppd[1361]: Using interface pppoe-wan Sun Jul 27 15:08:36 2014 daemon.notice pppd[1361]: Connect: pppoe-wan <--> eth0.2 Sun Jul 27 15:08:36 2014 daemon.debug pppd[1361]: sent [LCP ConfReq id=0x1 <mru 1492> <magic 0xf8eb7ed9>] Sun Jul 27 15:08:36 2014 kern.info kernel: [ 22.930000] br-lan: port 1(eth0.1) entered forwarding state Sun Jul 27 15:08:36 2014 daemon.debug pppd[1361]: rcvd [LCP ConfReq id=0x7d <mru 1492> <auth pap> <magic 0x4c957146>] Sun Jul 27 15:08:36 2014 daemon.debug pppd[1361]: sent [LCP ConfAck id=0x7d <mru 1492> <auth pap> <magic 0x4c957146>] Sun Jul 27 15:08:36 2014 daemon.debug pppd[1361]: rcvd [LCP ConfAck id=0x1 <mru 1492> <magic 0xf8eb7ed9>] Sun Jul 27 15:08:36 2014 daemon.debug pppd[1361]: sent [LCP EchoReq id=0x0 magic=0xf8eb7ed9] Sun Jul 27 15:08:37 2014 daemon.debug pppd[1361]: rcvd [PAP AuthAck id=0x1 ""] Sun Jul 27 15:08:37 2014 daemon.notice pppd[1361]: PAP authentication succeeded Sun Jul 27 15:08:37 2014 daemon.notice pppd[1361]: peer from calling number 00:90:1A:A3:B7:45 authorized Sun Jul 27 15:08:37 2014 daemon.debug pppd[1361]: sent [IPCP ConfReq id=0x1 <addr 0.0.0.0> <ms-dns1 0.0.0.0> <ms-dns2 0.0.0.0>] Sun Jul 27 15:08:38 2014 kern.info kernel: [ 24.040000] IPv6: ADDRCONF(NETDEV_UP): wlan1: link is not ready Sun Jul 27 15:08:38 2014 kern.info kernel: [ 24.040000] device wlan1 entered promiscuous mode Sun Jul 27 15:08:38 2014 daemon.debug pppd[1361]: rcvd [IPCP ConfReq id=0x34 <addr 206.248.154.122>] Sun Jul 27 15:08:38 2014 daemon.debug pppd[1361]: sent [IPCP ConfAck id=0x34 <addr 206.248.154.122>] Sun Jul 27 15:08:38 2014 daemon.debug pppd[1361]: rcvd [IPCP ConfNak id=0x1 <addr 75.119.250.208> <ms-dns1 206.248.154.22> <ms-dns2 206.248.154.170>] Sun Jul 27 15:08:38 2014 daemon.debug pppd[1361]: sent [IPCP ConfReq id=0x2 <addr 75.119.250.208> <ms-dns1 206.248.154.22> <ms-dns2 206.248.154.170>] Sun Jul 27 15:08:38 2014 daemon.debug pppd[1361]: rcvd [IPCP ConfAck id=0x2 <addr 75.119.250.208> <ms-dns1 206.248.154.22> <ms-dns2 206.248.154.170>] Sun Jul 27 15:08:38 2014 daemon.notice pppd[1361]: local IP address 75.119.250.208 Sun Jul 27 15:08:38 2014 daemon.notice pppd[1361]: remote IP address 206.248.154.122 Sun Jul 27 15:08:38 2014 daemon.notice pppd[1361]: primary DNS address 206.248.154.22 Sun Jul 27 15:08:38 2014 daemon.notice pppd[1361]: secondary DNS address 206.248.154.170 Sun Jul 27 15:08:38 2014 daemon.debug pppd[1361]: Script /lib/netifd/ppp-up started (pid 1560) Sun Jul 27 15:08:38 2014 kern.info kernel: [ 24.440000] IPv6: ADDRCONF(NETDEV_UP): wlan0: link is not ready Sun Jul 27 15:08:38 2014 kern.info kernel: [ 24.480000] device wlan0 entered promiscuous mode Sun Jul 27 15:08:38 2014 kern.info kernel: [ 24.480000] br-lan: port 3(wlan0) entered forwarding state Sun Jul 27 15:08:38 2014 kern.info kernel: [ 24.490000] br-lan: port 3(wlan0) entered forwarding state Sun Jul 27 15:08:38 2014 daemon.notice netifd: Network device 'pppoe-wan' link is up Sun Jul 27 15:08:38 2014 daemon.notice netifd: Interface 'wan' is now up Sun Jul 27 15:08:38 2014 daemon.debug pppd[1361]: Script /lib/netifd/ppp-up finished (pid 1560), status = 0x1 Sun Jul 27 15:08:39 2014 kern.info kernel: [ 25.040000] br-lan: port 3(wlan0) entered disabled state Sun Jul 27 15:08:39 2014 kern.info kernel: [ 25.790000] br-lan: port 2(wlan1) entered forwarding state Sun Jul 27 15:08:39 2014 kern.info kernel: [ 25.800000] br-lan: port 2(wlan1) entered forwarding state Sun Jul 27 15:08:39 2014 kern.info kernel: [ 25.800000] IPv6: ADDRCONF(NETDEV_CHANGE): wlan1: link becomes ready Sun Jul 27 15:08:40 2014 kern.info kernel: [ 26.010000] br-lan: port 3(wlan0) entered forwarding state Sun Jul 27 15:08:40 2014 kern.info kernel: [ 26.020000] br-lan: port 3(wlan0) entered forwarding state Sun Jul 27 15:08:40 2014 kern.info kernel: [ 26.020000] IPv6: ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready Sun Jul 27 15:08:40 2014 daemon.notice netifd: Network device 'wlan1' link is up Sun Jul 27 15:08:40 2014 daemon.notice netifd: Network device 'wlan0' link is up Sun Jul 27 15:08:40 2014 user.info syslog: module is already loaded - cls_u32 Sun Jul 27 15:08:40 2014 kern.emerg already loaded - cls_u32 Sun Jul 27 15:08:40 2014 user.info syslog: module is already loaded - em_u32 Sun Jul 27 15:08:40 2014 kern.emerg already loaded - em_u32 Sun Jul 27 15:08:40 2014 user.info syslog: module is already loaded - act_connmark Sun Jul 27 15:08:40 2014 kern.emerg already loaded - act_connmark Sun Jul 27 15:08:40 2014 user.info syslog: module is already loaded - act_mirred Sun Jul 27 15:08:40 2014 kern.emerg already loaded - act_mirred Sun Jul 27 15:08:40 2014 user.info syslog: module is already loaded - sch_ingress Sun Jul 27 15:08:40 2014 kern.emerg already loaded - sch_ingress Sun Jul 27 15:08:40 2014 user.info syslog: module is already loaded - cls_fw Sun Jul 27 15:08:40 2014 kern.emerg already loaded - cls_fw Sun Jul 27 15:08:40 2014 user.info syslog: module is already loaded - sch_hfsc Sun Jul 27 15:08:40 2014 kern.emerg already loaded - sch_hfsc Sun Jul 27 15:08:41 2014 kern.info kernel: [ 27.800000] br-lan: port 2(wlan1) entered forwarding state Sun Jul 27 15:08:42 2014 kern.info kernel: [ 28.020000] br-lan: port 3(wlan0) entered forwarding state Sun Jul 27 15:08:42 2014 kern.info kernel: [ 28.400000] device br-lan entered promiscuous mode Sun Jul 27 15:08:42 2014 user.emerg syslog: jffs2 is not ready - marker found Sun Jul 27 15:08:42 2014 user.emerg syslog: jffs2 is not ready - marker found Sun Jul 27 15:08:42 2014 kern.warn kernel: [ 28.660000] jffs2_scan_eraseblock(): End of filesystem marker found at 0x0 Sun Jul 27 15:08:42 2014 kern.warn kernel: [ 28.680000] jffs2_build_filesystem(): unlocking the mtd device... done. Sun Jul 27 15:08:43 2014 daemon.info dnsmasq[1955]: started, version 2.71 cachesize 4096 Sun Jul 27 15:08:43 2014 daemon.info dnsmasq[1955]: 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 Sun Jul 27 15:08:43 2014 daemon.info dnsmasq-dhcp[1955]: DHCP, IP range 192.168.8.150 -- 192.168.8.199, lease time 3h Sun Jul 27 15:08:43 2014 daemon.info dnsmasq[1955]: using local addresses only for domain lan Sun Jul 27 15:08:43 2014 daemon.info dnsmasq[1955]: reading /etc/resolv.conf.opendns Sun Jul 27 15:08:43 2014 daemon.info dnsmasq[1955]: using local addresses only for domain lan Sun Jul 27 15:08:43 2014 daemon.info dnsmasq[1955]: using nameserver 66.163.0.161#53 Sun Jul 27 15:08:43 2014 daemon.info dnsmasq[1955]: using nameserver 206.248.154.170#53 Sun Jul 27 15:08:43 2014 daemon.info dnsmasq[1955]: using nameserver 206.248.154.22#53 Sun Jul 27 15:08:43 2014 daemon.info dnsmasq[1955]: using nameserver 142.46.128.130#53 Sun Jul 27 15:08:43 2014 daemon.info dnsmasq[1955]: using nameserver 8.8.4.4#53 Sun Jul 27 15:08:43 2014 daemon.info dnsmasq[1955]: read /etc/hosts - 5 addresses Sun Jul 27 15:08:43 2014 daemon.info dnsmasq-dhcp[1955]: read /etc/ethers - 13 addresses Sun Jul 27 15:08:45 2014 kern.info kernel: [ 31.860000] nf_conntrack: automatic helper assignment is deprecated and it will be removed soon. Use the iptables CT target to attach helpers instead. Sun Jul 27 15:08:46 2014 user.notice firewall: Reloading firewall due to ifup of lan (br-lan) Sun Jul 27 15:08:50 2014 daemon.notice miniupnpd[2387]: HTTP listening on port 5000 Sun Jul 27 15:08:50 2014 daemon.warn miniupnpd[2387]: no HTTP IPv6 address, disabling IPv6 Sun Jul 27 15:08:52 2014 user.info syslog: module is already loaded - cls_u32 Sun Jul 27 15:08:52 2014 kern.emerg already loaded - cls_u32 Sun Jul 27 15:08:52 2014 user.info syslog: module is already loaded - em_u32 Sun Jul 27 15:08:52 2014 kern.emerg already loaded - em_u32 Sun Jul 27 15:08:52 2014 user.info syslog: module is already loaded - sch_ingress Sun Jul 27 15:08:52 2014 kern.emerg already loaded - sch_ingress Sun Jul 27 15:08:52 2014 user.info syslog: module is already loaded - cls_fw Sun Jul 27 15:08:52 2014 kern.emerg already loaded - cls_fw Sun Jul 27 15:08:52 2014 user.info syslog: module is already loaded - sch_hfsc Sun Jul 27 15:08:52 2014 kern.emerg already loaded - sch_hfsc Sun Jul 27 15:08:53 2014 user.notice firewall: Reloading firewall due to ifup of wan (pppoe-wan) Sun Jul 27 15:08:56 2014 daemon.notice miniupnpd[2387]: shutting down MiniUPnPd Sun Jul 27 15:08:56 2014 daemon.err miniupnpd[2387]: sendto(udp_shutdown=-1): Bad file descriptor Sun Jul 27 15:08:56 2014 daemon.err miniupnpd[2387]: sendto(udp_shutdown=-1): Bad file descriptor Sun Jul 27 15:08:56 2014 daemon.err miniupnpd[2387]: sendto(udp_shutdown=-1): Bad file descriptor Sun Jul 27 15:08:56 2014 daemon.err miniupnpd[2387]: sendto(udp_shutdown=-1): Bad file descriptor Sun Jul 27 15:08:56 2014 daemon.err miniupnpd[2387]: sendto(udp_shutdown=-1): Bad file descriptor Sun Jul 27 15:08:56 2014 daemon.err miniupnpd[2387]: sendto(udp_shutdown=-1): Bad file descriptor Sun Jul 27 15:08:56 2014 daemon.err miniupnpd[2387]: sendto(udp_shutdown=-1): Bad file descriptor Sun Jul 27 15:08:56 2014 daemon.err miniupnpd[2387]: sendto(udp_shutdown=-1): Bad file descriptor Sun Jul 27 15:08:56 2014 daemon.err miniupnpd[2387]: sendto(udp_shutdown=-1): Bad file descriptor Sun Jul 27 15:08:56 2014 daemon.err miniupnpd[2387]: sendto(udp_shutdown=-1): Bad file descriptor Sun Jul 27 15:08:56 2014 daemon.notice miniupnpd[2830]: HTTP listening on port 5000 Sun Jul 27 15:08:56 2014 daemon.warn miniupnpd[2830]: no HTTP IPv6 address, disabling IPv6 Sun Jul 27 15:09:00 2014 kern.warn kernel: [ 28.680000] jffs2_build_filesystem(): erasing all blocks after the end marker... done. Sun Jul 27 15:09:00 2014 kern.notice kernel: [ 46.950000] jffs2: notice: (1847) jffs2_build_xattr_subsystem: complete building xattr subsystem, 0 of xdatum (0 unchecked, 0 orphan) and 0 of xref (0 dead, 0 orphan) found. Sun Jul 27 15:09:01 2014 user.emerg syslog: doing fo cleanup Sun Jul 27 15:09:01 2014 kern.info kernel: [ 47.320000] eth0: link down Sun Jul 27 15:09:01 2014 daemon.notice netifd: Network device 'eth0' link is down Sun Jul 27 15:09:01 2014 daemon.notice netifd: VLAN 'eth0.2' link is down Sun Jul 27 15:09:01 2014 daemon.notice netifd: Interface 'wan' has link connectivity loss Sun Jul 27 15:09:01 2014 kern.info kernel: [ 47.350000] br-lan: port 1(eth0.1) entered disabled state Sun Jul 27 15:09:01 2014 daemon.notice netifd: VLAN 'eth0.1' link is down Sun Jul 27 15:09:01 2014 kern.info kernel: [ 47.490000] eth0: link up (1000Mbps/Full duplex) Sun Jul 27 15:09:01 2014 kern.info kernel: [ 47.490000] br-lan: port 1(eth0.1) entered forwarding state Sun Jul 27 15:09:01 2014 kern.info kernel: [ 47.500000] br-lan: port 1(eth0.1) entered forwarding state Sun Jul 27 15:09:01 2014 daemon.notice netifd: Network device 'eth0' link is up Sun Jul 27 15:09:01 2014 daemon.notice netifd: VLAN 'eth0.2' link is up Sun Jul 27 15:09:01 2014 daemon.notice netifd: Interface 'wan' has link connectivity Sun Jul 27 15:09:01 2014 daemon.notice netifd: Interface 'wan' is setting up now Sun Jul 27 15:09:01 2014 daemon.notice netifd: VLAN 'eth0.1' link is up Sun Jul 27 15:09:01 2014 daemon.notice netifd: Network device 'pppoe-wan' link is down Sun Jul 27 15:09:01 2014 daemon.info pppd[3001]: Plugin rp-pppoe.so loaded. Sun Jul 27 15:09:01 2014 daemon.info pppd[3001]: RP-PPPoE plugin version 3.8p compiled against pppd 2.4.6 Sun Jul 27 15:09:01 2014 daemon.notice pppd[3001]: pppd 2.4.6 started by root, uid 0 Sun Jul 27 15:09:01 2014 daemon.debug pppd[3001]: Send PPPOE Discovery V1T1 PADI session 0x0 length 12 Sun Jul 27 15:09:01 2014 daemon.debug pppd[3001]: dst ff:ff:ff:ff:ff:ff src 64:70:02:e2:a4:28 Sun Jul 27 15:09:01 2014 daemon.debug pppd[3001]: [service-name] [host-uniq 00 00 0b b9] Sun Jul 27 15:09:01 2014 daemon.debug pppd[3001]: Recv PPPOE Discovery V1T1 PADO session 0x0 length 53 Sun Jul 27 15:09:01 2014 daemon.debug pppd[3001]: dst 64:70:02:e2:a4:28 src 00:90:1a:a3:b7:45 Sun Jul 27 15:09:01 2014 daemon.debug pppd[3001]: [AC-name bas1-woodbridge48] [host-uniq 00 00 0b b9] [service-name] [AC-cookie c3 69 03 d2 09 e3 af a5 dc 7a 62 b4 c1 8f d5 6e] Sun Jul 27 15:09:01 2014 daemon.debug pppd[3001]: Send PPPOE Discovery V1T1 PADR session 0x0 length 32 Sun Jul 27 15:09:01 2014 daemon.debug pppd[3001]: dst 00:90:1a:a3:b7:45 src 64:70:02:e2:a4:28 Sun Jul 27 15:09:01 2014 daemon.debug pppd[3001]: [service-name] [host-uniq 00 00 0b b9] [AC-cookie c3 69 03 d2 09 e3 af a5 dc 7a 62 b4 c1 8f d5 6e] Sun Jul 27 15:09:01 2014 daemon.debug pppd[3001]: Recv PPPOE Discovery V1T1 PADS session 0x3249 length 12 Sun Jul 27 15:09:01 2014 daemon.debug pppd[3001]: dst 64:70:02:e2:a4:28 src 00:90:1a:a3:b7:45 Sun Jul 27 15:09:01 2014 daemon.debug pppd[3001]: [service-name] [host-uniq 00 00 0b b9] Sun Jul 27 15:09:01 2014 daemon.debug pppd[3001]: PADS: Service-Name: '' Sun Jul 27 15:09:01 2014 daemon.info pppd[3001]: PPP session is 12873 Sun Jul 27 15:09:01 2014 daemon.warn pppd[3001]: Connected to 00:90:1a:a3:b7:45 via interface eth0.2 Sun Jul 27 15:09:01 2014 daemon.debug pppd[3001]: using channel 2 Sun Jul 27 15:09:01 2014 daemon.info pppd[3001]: Using interface pppoe-wan Sun Jul 27 15:09:01 2014 daemon.notice pppd[3001]: Connect: pppoe-wan <--> eth0.2 Sun Jul 27 15:09:01 2014 daemon.debug pppd[3001]: sent [LCP ConfReq id=0x1 <mru 1492> <magic 0x4f01f3c2>] Sun Jul 27 15:09:01 2014 daemon.debug pppd[3001]: rcvd [LCP ConfReq id=0x53 <mru 1492> <auth pap> <magic 0x261a8e92>] Sun Jul 27 15:09:01 2014 daemon.debug pppd[3001]: sent [LCP ConfAck id=0x53 <mru 1492> <auth pap> <magic 0x261a8e92>] Sun Jul 27 15:09:01 2014 daemon.debug pppd[3001]: rcvd [LCP ConfAck id=0x1 <mru 1492> <magic 0x4f01f3c2>] Sun Jul 27 15:09:01 2014 daemon.debug pppd[3001]: rcvd [LCP EchoRep id=0x0 magic=0x261a8e92] Sun Jul 27 15:09:02 2014 user.emerg syslog: setting up led USB1 Sun Jul 27 15:09:02 2014 user.emerg syslog: setting up led USB2 Sun Jul 27 15:09:02 2014 user.emerg syslog: setting up led WLAN2G Sun Jul 27 15:09:02 2014 user.emerg syslog: - init complete - Sun Jul 27 15:09:02 2014 daemon.debug pppd[3001]: rcvd [PAP AuthAck id=0x1 ""] Sun Jul 27 15:09:02 2014 daemon.notice pppd[3001]: PAP authentication succeeded Sun Jul 27 15:09:02 2014 daemon.notice pppd[3001]: peer from calling number 00:90:1A:A3:B7:45 authorized Sun Jul 27 15:09:02 2014 daemon.debug pppd[3001]: sent [IPCP ConfReq id=0x1 <addr 0.0.0.0> <ms-dns1 0.0.0.0> <ms-dns2 0.0.0.0>] Sun Jul 27 15:09:02 2014 daemon.debug pppd[3001]: rcvd [IPCP ConfReq id=0x7 <addr 206.248.154.121>] Sun Jul 27 15:09:02 2014 daemon.debug pppd[3001]: sent [IPCP ConfAck id=0x7 <addr 206.248.154.121>] Sun Jul 27 15:09:02 2014 daemon.debug pppd[3001]: rcvd [IPCP ConfNak id=0x1 <addr 184.175.2.49> <ms-dns1 206.248.154.22> <ms-dns2 206.248.154.170>] Sun Jul 27 15:09:02 2014 daemon.debug pppd[3001]: sent [IPCP ConfReq id=0x2 <addr 184.175.2.49> <ms-dns1 206.248.154.22> <ms-dns2 206.248.154.170>] Sun Jul 27 15:09:02 2014 daemon.debug pppd[3001]: rcvd [IPCP ConfAck id=0x2 <addr 184.175.2.49> <ms-dns1 206.248.154.22> <ms-dns2 206.248.154.170>] Sun Jul 27 15:09:02 2014 daemon.notice pppd[3001]: local IP address 184.175.2.49 Sun Jul 27 15:09:02 2014 daemon.notice pppd[3001]: remote IP address 206.248.154.121 Sun Jul 27 15:09:02 2014 daemon.notice pppd[3001]: primary DNS address 206.248.154.22 Sun Jul 27 15:09:02 2014 daemon.notice pppd[3001]: secondary DNS address 206.248.154.170 Sun Jul 27 15:09:02 2014 daemon.notice netifd: Network device 'pppoe-wan' link is up Sun Jul 27 15:09:02 2014 daemon.debug pppd[3001]: Script /lib/netifd/ppp-up started (pid 3081) Sun Jul 27 15:09:02 2014 daemon.notice netifd: Interface 'wan' is now up Sun Jul 27 15:09:02 2014 daemon.debug pppd[3001]: Script /lib/netifd/ppp-up finished (pid 3081), status = 0x1 Sun Jul 27 15:09:03 2014 user.info syslog: module is already loaded - cls_u32 Sun Jul 27 15:09:03 2014 kern.emerg already loaded - cls_u32 Sun Jul 27 15:09:03 2014 user.info syslog: module is already loaded - em_u32 Sun Jul 27 15:09:03 2014 kern.emerg already loaded - em_u32 Sun Jul 27 15:09:03 2014 kern.info kernel: [ 49.500000] br-lan: port 1(eth0.1) entered forwarding state Sun Jul 27 15:09:03 2014 user.info syslog: module is already loaded - act_connmark Sun Jul 27 15:09:03 2014 kern.emerg already loaded - act_connmark Sun Jul 27 15:09:03 2014 user.info syslog: module is already loaded - act_mirred Sun Jul 27 15:09:03 2014 kern.emerg already loaded - act_mirred Sun Jul 27 15:09:03 2014 user.info syslog: module is already loaded - sch_ingress Sun Jul 27 15:09:03 2014 kern.emerg already loaded - sch_ingress Sun Jul 27 15:09:03 2014 user.info syslog: module is already loaded - cls_fw Sun Jul 27 15:09:03 2014 kern.emerg already loaded - cls_fw Sun Jul 27 15:09:03 2014 user.info syslog: module is already loaded - sch_hfsc Sun Jul 27 15:09:03 2014 kern.emerg already loaded - sch_hfsc Sun Jul 27 15:09:03 2014 user.notice firewall: Reloading firewall due to ifup of wan (pppoe-wan) Sun Jul 27 15:09:05 2014 daemon.notice miniupnpd[2830]: shutting down MiniUPnPd Sun Jul 27 15:09:05 2014 daemon.err miniupnpd[2830]: sendto(udp_shutdown=-1): Bad file descriptor Sun Jul 27 15:09:05 2014 daemon.err miniupnpd[2830]: sendto(udp_shutdown=-1): Bad file descriptor Sun Jul 27 15:09:05 2014 daemon.err miniupnpd[2830]: sendto(udp_shutdown=-1): Bad file descriptor Sun Jul 27 15:09:05 2014 daemon.err miniupnpd[2830]: sendto(udp_shutdown=-1): Bad file descriptor Sun Jul 27 15:09:05 2014 daemon.err miniupnpd[2830]: sendto(udp_shutdown=-1): Bad file descriptor Sun Jul 27 15:09:05 2014 daemon.err miniupnpd[2830]: sendto(udp_shutdown=-1): Bad file descriptor Sun Jul 27 15:09:05 2014 daemon.err miniupnpd[2830]: sendto(udp_shutdown=-1): Bad file descriptor Sun Jul 27 15:09:05 2014 daemon.err miniupnpd[2830]: sendto(udp_shutdown=-1): Bad file descriptor Sun Jul 27 15:09:05 2014 daemon.err miniupnpd[2830]: sendto(udp_shutdown=-1): Bad file descriptor Sun Jul 27 15:09:05 2014 daemon.err miniupnpd[2830]: sendto(udp_shutdown=-1): Bad file descriptor Sun Jul 27 15:09:05 2014 daemon.err miniupnpd[2830]: sendto(udp_shutdown=-1): Bad file descriptor Sun Jul 27 15:09:05 2014 daemon.err miniupnpd[2830]: Failed to broadcast good-bye notifications Sun Jul 27 15:09:05 2014 daemon.notice miniupnpd[3449]: HTTP listening on port 5000 Sun Jul 27 15:09:05 2014 daemon.warn miniupnpd[3449]: no HTTP IPv6 address, disabling IPv6 Sun Jul 27 15:09:06 2014 daemon.notice netifd: Network device 'pppoe-wan' link is down Sun Jul 27 15:12:31 2014 user.notice hotplug.d: Calling netCheck.sh to make sure everything is sane... Sun Jul 27 15:12:32 2014 daemon.err miniupnpd[3449]: addnatrule() : iptc_commit() error : Resource temporarily unavailable Sun Jul 27 15:12:33 2014 daemon.err miniupnpd[3449]: Failed to get ip address for interface pppoe-wan Sun Jul 27 15:12:35 2014 daemon.err miniupnpd[3449]: Failed to get ip address for interface pppoe-wan Sun Jul 27 15:12:39 2014 daemon.err miniupnpd[3449]: Failed to get ip address for interface pppoe-wan _______________________________________________ openwrt-devel mailing list [email protected] https://lists.openwrt.org/cgi-bin/mailman/listinfo/openwrt-devel
