No IP address on WAN after reboot - N66U & 276.44

[CaptnDanLKW]

After my upgrade to 276.44, when I reboot my RT-N66U the device won't get an IP address from my ISP. I've tried the usual reboot of the cable modem, and the 'Normal' and 'Aggressive' settings in the WAN configuration screen. I reset the config after updating and manually reconfigured. I've was running .43 previously.

Oddly, it will fetch an address if I disconnect and reconnect the wan Ethernet cable. The act of the physical interface going down and back up solves the issues, but its not a good long term solution.

I've captured the output from the serial port - You'll see towards the bottom that the router is having problems getting an IP address. I've annotated where i disconnect/reconnect the WAN cable in the log below.

I haven't seen anyone else with this issue, so I'm looking for some ideas.

Thanks

____


CFE version 5.100.127 based on BBP 1.0.37 for BCM947XX (32bit,SP,LE)
Build Date: ? 9? 14 17:47:35 CST 2012 (boyau@localhost)
Copyright (C) 2000-2008 Broadcom Corporation.

Init Arena
Init Devs.
Boot partition size = 262144(0x40000)
et0: Broadcom BCM47XX 10/100/1000 Mbps Ethernet Controller 5.100.127
CPU type 0x19749: 600MHz
Tot mem: 131072 KBytes

CFE mem: 0x80700000 - 0x80839350 (1282896)
Data: 0x80733120 - 0x807363E0 (12992)
BSS: 0x807363E0 - 0x80737350 (3952)
Heap: 0x80737350 - 0x80837350 (1048576)
Stack: 0x80837350 - 0x80839350 (8192)
Text: 0x80700000 - 0x80733118 (209176)

Device eth0: hwaddr , ipaddr 192.168.1.1, mask 255.255.255.0
gateway not set, nameserver not set
Null Rescue Flag.
Loader:raw Filesys:tftp Dev:eth0 File:: Optionsnull)
Loading: TFTP Server.
Failed.
Could not load :: Timeout occured
Loader:raw Filesys:raw Dev:flash0.os File: Optionsnull)
Loading: ...... 3363708 bytes read
Entry at 0x80001000
Closing network.
Starting program at 0x80001000
start_kernel
Linux version 2.6.22.19 (root@asus) (gcc version 4.2.4) #1 Sun Aug 3 19:36:35 EDT 2014

(boot sequence output truncated)


start_wan: start_wan_if(0)!
start_wan_if(0)
update_wan_state(wan0_, 0, 0)
convert_wan_nvram(wan0_)
_ifconfig: name=eth0 flags=0000 addr=(null) netmask=(null)
Attempt to kill tasklet from interrupt
** wan_ifname: eth0 is NOT UP
_ifconfig: name=eth0 flags=1043 IFUP addr=(null) netmask=(null)
restart_nasapps 431: waiting after 6/10.
_ifconfig: name=eth0 flags=1043 IFUP addr=(null) netmask=(null)
start_auth:: done
update_wan_state(wan0_, 1, 0)
start_wan_if(): End.
start_wan: start_wan_if(1)!
start_wan_if(1)
update_wan_state(wan1_, 0, 0)
start_wan_if(): Cound't find the type(0) of unit(1)!!!
start_wan_if(): End.
vlan1: cmd=14: Operation not supported
udhcpc_wan:: deconfig
_ifconfig: name=eth0 flags=1043 IFUP addr=0.0.0.0 netmask=(null)
wan_down(eth0)
wan_down(eth0): .
stop_auth:: done
route_manip: cmd=DEL name=eth0 addr=0.0.0.0 netmask=0.0.0.0 gateway=(null) metric=0
update_wan_state(wan0_, 3, 0)
update_wan_state(wan0_, 4, 3)
udhcpc:: deconfig done
httpd 349:notify_rc: restart_wan_if 0
[1 init:init_main +99] main loop signal/state=16
cmd[0]=restart_wan_if
cmd[1]=0
[1 init:handle_notifications +99] running: 3 wan_if
handle_notifications: wan_if: 0.
update_wan_state(wan0_, 6, 0)
stop_auth:: done
_ifconfig: name=eth0 flags=0000 addr=(null) netmask=(null)
Attempt to kill tasklet from interrupt
_ifconfig: name=eth0 flags=1043 IFUP addr=0.0.0.0 netmask=(null)
update_wan_state(wan0_, 4, 0)
stop_wan_if wan0_proto=dhcp
start_wan_if(0)
update_wan_state(wan0_, 0, 0)
convert_wan_nvram(wan0_)
_ifconfig: name=eth0 flags=1043 IFUP addr=(null) netmask=(null)
start_auth:: done
update_wan_state(wan0_, 1, 0)
start_wan_if(): End.
handle_notifications() end
udhcpc_wan:: deconfig
_ifconfig: name=eth0 flags=1043 IFUP addr=0.0.0.0 netmask=(null)
wan_down(eth0)
wan_down(eth0): .
stop_auth:: done
route_manip: cmd=DEL name=eth0 addr=0.0.0.0 netmask=0.0.0.0 gateway=(null) metric=0
update_wan_state(wan0_, 3, 0)
update_wan_state(wan0_, 4, 3)
udhcpc:: deconfig done
wanduck(0): Try to restart_wan_if.
wanduck 330:notify_rc: restart_wan_if 0
restart_wan_if 0 330: waiting after 9/10.
[1 init:init_main +145] main loop signal/state=16
cmd[0]=restart_wan_if
cmd[1]=0
[1 init:handle_notifications +146] running: 3 wan_if
handle_notifications: wan_if: 0.
update_wan_state(wan0_, 6, 0)
stop_auth:: done
_ifconfig: name=eth0 flags=0000 addr=(null) netmask=(null)
Attempt to kill tasklet from interrupt
_ifconfig: name=eth0 flags=1043 IFUP addr=0.0.0.0 netmask=(null)
update_wan_state(wan0_, 4, 0)
restart_wan_if 0 330: waiting after 8/10.
restart_wan_if 0 330: waiting after 7/10.
stop_wan_if wan0_proto=dhcp
start_wan_if(0)
update_wan_state(wan0_, 0, 0)
convert_wan_nvram(wan0_)
_ifconfig: name=eth0 flags=1043 IFUP addr=(null) netmask=(null)
start_auth:: done
update_wan_state(wan0_, 1, 0)
start_wan_if(): End.
handle_notifications() end
[1 init:init_main +148] main loop signal/state=14
udhcpc_wan:: deconfig
_ifconfig: name=eth0 flags=1043 IFUP addr=0.0.0.0 netmask=(null)
wan_down(eth0)


*********I disconnected and reconnected the WAN cable, you can see I get my IP address now


wan_down(eth0): .
stop_auth:: done
route_manip: cmd=DEL name=eth0 addr=0.0.0.0 netmask=0.0.0.0 gateway=(null) metric=0
update_wan_state(wan0_, 3, 0)
update_wan_state(wan0_, 4, 3)

udhcpc:: deconfig done
udhcpc_wan:: bound
_ifconfig: name=eth0 flags=1043 IFUP addr=0.0.0.0 netmask=(null)
_ifconfig: name=eth0 flags=1043 IFUP addr=67.197.239.9 netmask=255.255.248.0
wan_up(eth0)
route_manip: cmd=ADD name=eth0 addr=67.197.232.1 netmask=255.255.255.255 gateway=(null) metric=0
update_wan_state(wan0_, 2, 0)
writting prerouting eth0 67.197.239.9 eth0 0.0.0.0 br0 192.168.1.1
wanport_status(0) 1
start_nat_rules: apply the nat_rules(/tmp/nat_rules_eth0_eth0)!
[552 udhcpc:start_dnsmasq +148] begin
[552 udhcpc:stop_dnsmasq +148] begin
[1 init:init_main +148] main loop signal/state=14
[552 udhcpc:stop_dnsmasq +148] end
[1 init:init_main +148] main loop signal/state=14
[552 udhcpc:start_dnsmasq +148] end
[552 udhcpc:filter_setting +148] writing vts_enable_x
[552 udhcpc:filter_setting +148] write porttrigger
[552 udhcpc:filter_setting +148] write wl filter
[552 udhcpc:filter_setting +148] write url filter
nf_conntrack_rtsp v0.6.21 loading
nf_nat_rtsp v0.6.21 loading
start_auth:: done
HTB: quantum of class 10002 is big. Consider r2q change.
HTB: quantum of class 10060 is big. Consider r2q change.
udhcpc 552:notify_rc: stop_upnp
[1 init:init_main +149] main loop signal/state=16
cmd[0]=stop_upnp
[1 init:handle_notifications +149] running: 1 upnp
handle_notifications() end
[1 init:init_main +149] main loop signal/state=14
udhcpc 552:notify_rc: start_upnp
[1 init:init_main +149] main loop signal/state=16
cmd[0]=start_upnp
[1 init:handle_notifications +149] running: 2 upnp
handle_notifications() end
[1 init:init_main +149] main loop signal/state=14
udhcpc 552:notify_rc: stop_ntpc
udhcpc 552:notify_rc: start_ntpc
552: wait for previous script(29/30): stop_ntpc 552 udhcpc.
[1 init:init_main +150] main loop signal/state=16
cmd[0]=stop_ntpc
[1 init:handle_notifications +150] running: 1 ntpc
handle_notifications() end
wan_up(eth0): done.
udhcpc:: bound done
[1 init:init_main +151] main loop signal/state=16
cmd[0]=start_ntpc
[1 init:handle_notifications +151] running: 2 ntpc
starting ntp...
handle_notifications() end
[1 init:init_main +151] main loop signal/state=14

# Disable direct rule if not tunnelled (D2C)

/ #

 

[Deleted member 27741]

Did you use mac address cloning before and are you using the same mac address for the router now?

 

[CaptnDanLKW]

No, didn't use MAC cloning before and not using now. The only MAC address ever exposed to the cable modem is the one from the WAN side of the router.

It is odd...

As I said, I tried toggling aggressive and normal mode, and I could see in the console the frequency of the dhcp requests change, but only the down/up state of the physical WAN port results in getting an address.

The only difference in the logs where I'm not getting an address and where I do is the event:

udhcpc_wan:: bound

soon as I see that, I know I'm getting an address.

I also disabled STP in the switch configuration section, but I'm not sure if the STP control is for just the LAN ports. Didn't make a difference.

 

[RMerlin]

Don't try just power cycling the modem - leave it turned off for about 10 mins to ensure the connection fully gets reset at the ISP's end.

 

[CaptnDanLKW]

I've done that too, but this isn't a case of the ISP equipment not handing out an address due to many different MAC addresses. The MAC address of the router is the only thing my ISP ever sees.

It doesn't matter how long the cable modem is off, or not. It could be up for 2 minutes, 2 hours or 2 days. When I reboot the router the WAN remains 'disconnected' until I DOWN/UP the physical state of the port on the WAN side. The logs coming of the serial side will spit out that same messages forever (see below) and never get an address until I simply down/up the physical link.

Like my original post said, I realize this is odd and no one else is reporting it, yet its happening to me now. I will be going back to fresh .43 as a test.

Dan
____

_ifconfig: name=eth0 flags=0000 addr=(null) netmask=(null)
Attempt to kill tasklet from interrupt
_ifconfig: name=eth0 flags=1043 IFUP addr=0.0.0.0 netmask=(null)
update_wan_state(wan0_, 4, 0)
stop_wan_if wan0_proto=dhcp
start_wan_if(0)
update_wan_state(wan0_, 0, 0)
convert_wan_nvram(wan0_)
_ifconfig: name=eth0 flags=1043 IFUP addr=(null) netmask=(null)
start_auth:: done
update_wan_state(wan0_, 1, 0)
start_wan_if(): End.
handle_notifications() end
udhcpc_wan:: deconfig
_ifconfig: name=eth0 flags=1043 IFUP addr=0.0.0.0 netmask=(null)
wan_down(eth0)
wan_down(eth0): .
stop_auth:: done
route_manip: cmd=DEL name=eth0 addr=0.0.0.0 netmask=0.0.0.0 gateway=(null) metric=0
update_wan_state(wan0_, 3, 0)
update_wan_state(wan0_, 4, 3)
udhcpc:: deconfig done
wanduck(0): Try to restart_wan_if.
wanduck 330:notify_rc: restart_wan_if 0
restart_wan_if 0 330: waiting after 9/10.
[1 init:init_main +145] main loop signal/state=16
cmd[0]=restart_wan_if
cmd[1]=0
[1 init:handle_notifications +146] running: 3 wan_if
handle_notifications: wan_if: 0.
update_wan_state(wan0_, 6, 0)
stop_auth:: done
_ifconfig: name=eth0 flags=0000 addr=(null) netmask=(null)
Attempt to kill tasklet from interrupt
_ifconfig: name=eth0 flags=1043 IFUP addr=0.0.0.0 netmask=(null)
update_wan_state(wan0_, 4, 0)
restart_wan_if 0 330: waiting after 8/10.
restart_wan_if 0 330: waiting after 7/10.
stop_wan_if wan0_proto=dhcp
start_wan_if(0)
update_wan_state(wan0_, 0, 0)
convert_wan_nvram(wan0_)
_ifconfig: name=eth0 flags=1043 IFUP addr=(null) netmask=(null)
start_auth:: done
update_wan_state(wan0_, 1, 0)
start_wan_if(): End.
handle_notifications() end
[1 init:init_main +148] main loop signal/state=14
udhcpc_wan:: deconfig
_ifconfig: name=eth0 flags=1043 IFUP addr=0.0.0.0 netmask=(null)
wan_down(eth0)