On Sun, Jul 27, 2014 at 3:31 PM, Weedy <weedy2...@gmail.com> wrote: > 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
bump. _______________________________________________ openwrt-devel mailing list openwrt-devel@lists.openwrt.org https://lists.openwrt.org/cgi-bin/mailman/listinfo/openwrt-devel
