[OpenWrt-Devel] WAN bouncing at boot

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

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

More information about the openwrt-devel mailing list