[OpenWrt-Devel] WAN bouncing at boot
Weedy
weedy2887 at gmail.com
Fri Aug 8 22:13:22 EDT 2014
On Sun, Jul 27, 2014 at 3:31 PM, Weedy <weedy2887 at 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 at lists.openwrt.org
https://lists.openwrt.org/cgi-bin/mailman/listinfo/openwrt-devel
More information about the openwrt-devel
mailing list