NVG 510 Dropping Connection Even After DNS Fix

[jesterloops]

Hi,

A few weeks ago my internet starting dropping the reconnecting over a span of around a minute.  It seems to happen more frequently at night. I thought this might be the Dns issue i kept reading about (i have a NVG510). I decided to turn off wireless and bridge my old router using the google dns servers, as sugested in many guides. Tonight it has begun again, I want to pinpoint if it is in fact the dns issue. Here is my Diagnostics log. If someone could point me in the right direction it would be greatly appreciated this issue is getting quite frustrating.

P0000-00-00T00:00:04 L6 sdb[306]: log buffer size set to 8192
P0000-00-00T00:00:04 L7 sdb[306]: starting process /sbin/klogd (pid 313)
P0000-00-00T00:00:10 L7 sdb[306]: libmotopia: Closing /dev/motopia
P0000-00-00T00:00:10 L7 sdb[306]: Loading platform module bcm_enet
P0000-00-00T00:00:10 L6 sdb[306]: SSL CA-root-cert directory is ready.
P0000-00-00T00:00:10 L6 sdb[306]: Hardware is 'NVG510'
P0000-00-00T00:00:11 L6 sdb[306]: S/N 159090766129552, SKU 64
P0000-00-00T00:00:13 L3 sdb[306]: Wireless subsystem found
P0000-00-00T00:00:16 L5 sdb[306]: VOIP subsystem found
P0000-00-00T00:00:16 L7 sdb[306]: netfilter: redirect object not found. skip prerouting rules
P0000-00-00T00:00:18 L3 sdb[306]: scheduler: no ntp object
P0000-00-00T00:00:18 L6 sdb[306]: ip6.route[1]: Setting state from 'unset' to 'down'
P0000-00-00T00:00:19 L3 sdb[306]: Wi-Fi: Adding interface wl0
P0000-00-00T00:00:19 L3 sdb[306]: Wi-Fi: Adding interface wl0.1
P0000-00-00T00:00:19 L3 sdb[306]: Wi-Fi: Adding interface wl0.2
P0000-00-00T00:00:19 L3 sdb[306]: Wi-Fi: Adding interface wl0.3
P0000-00-00T00:00:19 L3 sdb[306]: DSL: EOC version 90b1349a2990 NVG510 906221-64
P0000-00-00T00:00:19 L7 sdb[306]: enabling vc[1]
P0000-00-00T00:00:20 L7 sdb[306]: starting process /bin/voipexe (pid 997)
P0000-00-00T00:00:20 L5 sdb[306]: voipexe start returned ret=0 
P0000-00-00T00:00:20 L5 sdb[306]: login authorization-delay timer set for 300 seconds
P0000-00-00T00:00:20 L4 sdb[306]: Configured for IPDSLAM mode
P0000-00-00T00:00:20 L5 sdb[306]: SYS: loading saved configuration
P0000-00-00T00:00:20 L6 sdb[306]: ip6_set_proc: setting  to '0'
P0000-00-00T00:00:20 L6 sdb[306]: ip6_set_proc: setting  to '1'
P0000-00-00T00:00:20 L6 sdb[306]: ip6_set_proc: setting  to '1'
P0000-00-00T00:00:24 L7 sdb[306]: Ensw QoS: p-bit map: 0 0 0 0 1 1 2 2
P0000-00-00T00:00:24 L7 sdb[306]: Ensw: QoS configured
P0000-00-00T00:00:24 L7 sdb[306]: Ensw: max_age is 300
P0000-00-00T00:00:24 L3 sdb[306]: Wi-Fi: Intializing the subsystem
P0000-00-00T00:00:24 L3 sdb[306]: Wi-Fi: Setting country code to US
P0000-00-00T00:00:24 L3 sdb[306]: Wi-Fi: Country code set to US/2139404244
P0000-00-00T00:00:24 L3 sdb[306]: DSL: TPS-TC encoding set to PTM.
P0000-00-00T00:00:25 L6 sdb[306]: DSL: NLNM threshold value set to 60
P0000-00-00T00:00:25 L3 sdb[306]: DSL: Bitswap is ON, SRA is ON
P0000-00-00T00:00:25 L3 sdb[306]: DSL: Dying Gasp is OFF
P0000-00-00T00:00:25 L3 sdb[306]: DSL: DSP version - A2pD035b.d23i
P0000-00-00T00:00:25 L7 sdb[306]: DSL: Created net device ptm0, code = 0
P0000-00-00T00:00:26 L6 sdb[306]: conn[1]: linkchange_handler got event 19
P0000-00-00T00:00:26 L7 sdb[306]: conn[1]: new object ignoring event 19 from link[1]
P0000-00-00T00:00:26 L6 sdb[306]: conn[2]: linkchange_handler got event 19
P0000-00-00T00:00:26 L7 sdb[306]: conn[2]: new object ignoring event 19 from link[1]
P0000-00-00T00:00:26 L7 sdb[306]: link[1] sending DOWN event
P0000-00-00T00:00:26 L6 sdb[306]: conn[1]: linkchange_handler got event 17
P0000-00-00T00:00:26 L7 sdb[306]: conn[1]: new object ignoring event 17 from link[1]
P0000-00-00T00:00:26 L6 sdb[306]: conn[2]: linkchange_handler got event 17
P0000-00-00T00:00:26 L7 sdb[306]: conn[2]: new object ignoring event 17 from link[1]
P0000-00-00T00:00:26 L5 sdb[306]: 
 voice_event_handler: voice_get_wan_conn_obj returned null ..Not posting event:17
P0000-00-00T00:00:26 L6 sdb[306]: link[1]: Setting state from 'unset' to 'down'
P0000-00-00T00:00:26 L6 sdb[306]: conn[1]: linkchange_handler got event 19
P0000-00-00T00:00:26 L7 sdb[306]: conn[1]: new object ignoring event 19 from link[2]
P0000-00-00T00:00:26 L6 sdb[306]: conn[2]: linkchange_handler got event 19
P0000-00-00T00:00:26 L7 sdb[306]: conn[2]: new object ignoring event 19 from link[2]
P0000-00-00T00:00:26 L7 sdb[306]: link[2] sending DOWN event
P0000-00-00T00:00:26 L6 sdb[306]: conn[1]: linkchange_handler got event 17
P0000-00-00T00:00:26 L7 sdb[306]: conn[1]: new object ignoring event 17 from link[2]
P0000-00-00T00:00:26 L6 sdb[306]: conn[2]: linkchange_handler got event 17
P0000-00-00T00:00:26 L7 sdb[306]: conn[2]: new object ignoring event 17 from link[2]
P0000-00-00T00:00:26 L5 sdb[306]: 
 voice_event_handler: voice_get_wan_conn_obj returned null ..Not posting event:17
P0000-00-00T00:00:26 L6 sdb[306]: link[2]: Setting state from 'unset' to 'down'
P0000-00-00T00:00:26 L7 sdb[306]: starting process /bin/eap_tls_peer (pid 1308)
P0000-00-00T00:00:26 L6 sdb[306]: conn_apply conn[1]
P0000-00-00T00:00:26 L6 sdb[306]: conn[1]: conn_state_mc_controller got event 31 subevent 302
P0000-00-00T00:00:26 L7 sdb[306]: conn[1]: static_state_mc_controller got event 302
P0000-00-00T00:00:26 L7 sdb[306]: conn[1]: starting, current state is 'down'
P0000-00-00T00:00:26 L7 sdb[306]: priv_data: conn[1]/link[1]: static
P0000-00-00T00:00:26 L6 sdb[306]: starting conn[1] on link[1], interface=br1
P0000-00-00T00:00:26 L6 sdb[306]: conn[1]: config (0.0.0.0)->(192.168.1.254) flags=[1, 2, 0]
P0000-00-00T00:00:26 L5 sdb[306]: conn[1]: interface configured for 192.168.1.254
P0000-00-00T00:00:27 L7 sdb[306]: dhcps: invoke on interface br1
P0000-00-00T00:00:27 L7 sdb[306]: dhcps: adding interface string "br1"
P0000-00-00T00:00:27 L7 sdb[306]: starting process /bin/dhcpd (pid 1313)
P0000-00-00T00:00:27 L6 sdb[306]: conn_apply conn[2]
P0000-00-00T00:00:27 L6 sdb[306]: conn[2]: conn_state_mc_controller got event 30 subevent 254
P0000-00-00T00:00:27 L7 sdb[306]: conn[2]: dhcpc_state_mc_controller got event 254
P0000-00-00T00:00:27 L3 sdb[306]: Wi-Fi: Setting country code to US
P0000-00-00T00:00:27 L6 sdb[306]: log buffer size set to 32768
P0000-00-00T00:00:27 L6 sdb[306]: setting log level to 5
P0000-00-00T00:00:28 L3 sdb[306]: lan_redirect_status_get: no conn
P0000-00-00T00:00:29 L3 sdb[306]: 
voice_send_event: error 2:No such file or directory message size 100
P0000-00-00T00:00:31 L5 sdb[306]: database initialized
P0000-00-00T00:00:31 L3 vosdb[997]: SDB "isapplying" failed: Connection timed out
P0000-00-00T00:00:31 L5 sdb[306]: ELAN: Port lan-3 (eth2) going up
P0000-00-00T00:00:31 L5 sdb[306]: ELAN: Port lan-3 (eth2) speed 100 duplex full
P0000-00-00T00:00:31 L3 vosdb[997]: SDB "isapplying" failed: Connection timed out
P0000-00-00T00:00:31 L5 sdb[306]: ELAN: Port lan-2 (eth1) going up
P0000-00-00T00:00:31 L5 sdb[306]: ELAN: Port lan-2 (eth1) speed 100 duplex full
P0000-00-00T00:00:31 L5 sdb[306]: ELAN: Port lan-1 (eth0) going up
P0000-00-00T00:00:31 L5 sdb[306]: ELAN: Port lan-1 (eth0) speed 100 duplex full
P0000-00-00T00:00:32 L3 vosdb[997]: **********Starting VOIP Tasks *************
P0000-00-00T00:00:43 L3 sdb[306]: DSL: Filters may be missing: No
P0000-00-00T00:00:43 L3 sdb[306]: DSL: Echo level: 0 dB
P0000-00-00T00:00:43 L3 sdb[306]: DSL: Affected tones: 39 
P0000-00-00T00:00:49 L3 sdb[306]: DSL: Last Retrain Reason: 0x8000 Reason Unknown 2 
P0000-00-00T00:01:07 L3 sdb[306]: DSL: Filters may be missing: No
P0000-00-00T00:01:07 L3 sdb[306]: DSL: Echo level: 0 dB
P0000-00-00T00:01:07 L3 sdb[306]: DSL: Affected tones: 29 
P0000-00-00T00:01:13 L3 sdb[306]: DSL: Mode ADSL2+ (PTM)
P0000-00-00T00:01:13 L3 sdb[306]: DSL: Sync Rate in kbps D/S 3006 U/S 509
P0000-00-00T00:01:13 L3 sdb[306]: DSL: Latency path Interleaved
P0000-00-00T00:01:13 L3 sdb[306]: DSL: Trellis D/SN U/SN
P0000-00-00T00:01:13 L3 sdb[306]: DSL: CO Vendor BDCM
P0000-00-00T00:01:13 L3 sdb[306]: DSL: Link showtime counter since boot 1
P0000-00-00T00:01:17 L5 supp1x[1308]: Sending START Packet
P0000-00-00T00:01:19 L5 supp1x[1308]: Supplicant event = SUCCESS
P0000-00-00T00:01:21 L5 sdb[306]: conn[2]: interface configured for 107.194.108.140
P0000-00-00T00:01:29 L5 cwmp[1366]: DSLF_WanMgmtChg path[conn[2].ipaddr] val[107.194.108.140]
P0000-00-00T00:01:29 L3 vosdb[997]:  Sdb Failed to get ip.ntp.time-qcquired
P0000-00-00T00:01:30 L1 sdb[306]: nm_ipv6_add_firewall:  (4)
2012-11-12T22:16:08-05:00 L5 sdb[306]: authorization-delay timeout cleared
2012-11-12T22:16:08-05:00 L5 ntph[2202]: update system date & time from NTP server 68.94.156.17
2012-11-12T22:16:13-05:00 L5 cwmp[1366]: Next Periodic Inform after 85692 s
2012-11-12T22:16:13-05:00 L4 cwmp[1366]: Resolving ACS URL - Retry 0
2012-11-12T22:16:13-05:00 L5 cwmp[1366]: Connect to https://cwmp.c01.sbcglobal.net/cwmp/services/CWMP [] Retry 0
2012-11-12T22:16:13-05:00 L5 cwmp[1366]: (SSL) Connect Success: cwmp.c01.sbcglobal.net
2012-11-12T22:16:13-05:00 L5 cwmp[1366]: (SSL) Certificate Verify Success: cwmp.c01.sbcglobal.net
2012-11-12T22:16:14-05:00 L5 cwmp[1366]: Post Inform - reason 1 BOOT 
2012-11-12T22:16:14-05:00 L5 cwmp[1366]: Server auth challenge received
2012-11-12T22:16:14-05:00 L5 cwmp[1366]: Receive InformResponse
2012-11-12T22:16:14-05:00 L5 cwmp[1366]: Post 0
2012-11-12T22:16:14-05:00 L5 cwmp[1366]: Receive GetParameterValues
2012-11-12T22:16:14-05:00 L5 cwmp[1366]: Post GetParameterValuesResponse (chunk-length - 2683)
2012-11-12T22:16:14-05:00 L4 cwmp[1366]: Continue GetParameterValuesResponse (chunk-length - 0)
2012-11-12T22:16:15-05:00 L5 cwmp[1366]: Receive SetParameterValues
2012-11-12T22:16:15-05:00 L1 sdb[306]: nm_ipv6_add_firewall:  (4)
2012-11-12T22:16:15-05:00 L5 cwmp[1366]: Post SetParameterValuesResponse
2012-11-12T22:16:15-05:00 L5 cwmp[1366]: Closing connection on HTTP 204
2012-11-12T22:16:15-05:00 L5 cwmp[1366]: (SSL) Closing Connection: cwmp.c01.sbcglobal.net
2012-11-12T22:17:15-05:00 L5 sdb[306]: ELAN: Port lan-1 (eth0) going down
2012-11-12T22:17:17-05:00 L5 sdb[306]: ELAN: Port lan-1 (eth0) going up
2012-11-12T22:17:17-05:00 L5 sdb[306]: ELAN: Port lan-1 (eth0) speed 100 duplex full
2012-11-12T22:17:46-05:00 L5 sdb[306]: ELAN: Port lan-1 (eth0) going down
2012-11-12T22:17:48-05:00 L5 sdb[306]: ELAN: Port lan-1 (eth0) going up
2012-11-12T22:17:48-05:00 L5 sdb[306]: ELAN: Port lan-1 (eth0) speed 100 duplex full
2012-11-12T22:18:00-05:00 L1 sdb[306]: nm_ipv6_add_firewall:  (4)
The last message was repeated 2 times.
2012-11-12T22:21:24-05:00 L5 sdb[306]: ELAN: Port lan-1 (eth0) going down
2012-11-12T22:21:25-05:00 L5 sdb[306]: ELAN: Port lan-1 (eth0) going up
2012-11-12T22:21:25-05:00 L5 sdb[306]: ELAN: Port lan-1 (eth0) speed 100 duplex full
2012-11-12T23:26:56-05:00 L3 dnsmasq[2405]: no responses from nameserver '68.94.157.1'
2012-11-12T23:26:58-05:00 L3 sdb[306]: DSL: Last Retrain Reason: 0x1 RDI Detector 
2012-11-12T23:26:59-05:00 L3 sdb[306]: DSL: Link drop counter since boot 1
2012-11-12T23:26:59-05:00 L1 sdb[306]: nm_ipv6_add_firewall:  (4)
2012-11-12T23:27:00-05:00 L3 sdb[306]: dns_lan_redirect_notify. DOWN. restart proxy
2012-11-12T23:27:00-05:00 L1 sdb[306]: nm_ipv6_add_firewall:  (4)
The last message was repeated 2 times.
2012-11-12T23:27:00-05:00 L3 sdb[306]: dns_lan_redirect_notify. DOWN. restart proxy
2012-11-12T23:27:00-05:00 L1 sdb[306]: nm_ipv6_add_firewall:  (4)
The last message was repeated 3 times.
2012-11-12T23:27:02-05:00 L3 sdb[306]: dns_lan_redirect_notify. DOWN. restart proxy
2012-11-12T23:27:17-05:00 L3 sdb[306]: DSL: Filters may be missing: No
2012-11-12T23:27:17-05:00 L3 sdb[306]: DSL: Echo level: 0 dB
2012-11-12T23:27:17-05:00 L3 sdb[306]: DSL: Affected tones: 28 
2012-11-12T23:27:23-05:00 L3 sdb[306]: DSL: Last Retrain Reason: 0x8000 Reason Unknown 2 
2012-11-12T23:27:41-05:00 L3 sdb[306]: DSL: Filters may be missing: No
2012-11-12T23:27:41-05:00 L3 sdb[306]: DSL: Echo level: 0 dB
2012-11-12T23:27:41-05:00 L3 sdb[306]: DSL: Affected tones: 25 
2012-11-12T23:27:47-05:00 L1 sdb[306]: nm_ipv6_add_firewall:  (4)
2012-11-12T23:27:47-05:00 L3 sdb[306]: DSL: Mode ADSL2+ (PTM)
2012-11-12T23:27:47-05:00 L3 sdb[306]: DSL: Sync Rate in kbps D/S 3006 U/S 509
2012-11-12T23:27:47-05:00 L3 sdb[306]: DSL: Latency path Interleaved
2012-11-12T23:27:47-05:00 L3 sdb[306]: DSL: Trellis D/SN U/SN
2012-11-12T23:27:47-05:00 L3 sdb[306]: DSL: CO Vendor BDCM
2012-11-12T23:27:47-05:00 L3 sdb[306]: DSL: Link showtime counter since boot 2
2012-11-12T23:27:48-05:00 L1 sdb[306]: nm_ipv6_add_firewall:  (4)
2012-11-12T23:27:48-05:00 L3 sdb[306]: dns_lan_redirect_notify. DOWN. restart proxy
2012-11-12T23:27:48-05:00 L1 sdb[306]: nm_ipv6_add_firewall:  (4)
The last message was repeated 2 times.
2012-11-12T23:27:52-05:00 L5 supp1x[1308]: Sending START Packet
2012-11-12T23:27:54-05:00 L5 supp1x[1308]: Supplicant event = SUCCESS
2012-11-12T23:27:54-05:00 L1 sdb[306]: nm_ipv6_add_firewall:  (4)
The last message was repeated 2 times.
2012-11-13T00:20:08-05:00 L3 sdb[306]: DSL: Last Retrain Reason: 0x1 RDI Detector 
2012-11-13T00:20:09-05:00 L3 sdb[306]: DSL: Link drop counter since boot 2
2012-11-13T00:20:09-05:00 L1 sdb[306]: nm_ipv6_add_firewall:  (4)
2012-11-13T00:20:10-05:00 L3 sdb[306]: dns_lan_redirect_notify. DOWN. restart proxy
2012-11-13T00:20:10-05:00 L1 sdb[306]: nm_ipv6_add_firewall:  (4)
The last message was repeated 2 times.

[JefferMC ACE - Wizard]

I see two things going on in your logs:

1) Your LAN connection dropped a few times about an hour after power up.  Did you disconnect the LAN cable?

2012-11-12T22:17:15-05:00 L5 sdb[306]: ELAN: Port lan-1 (eth0) going down
2012-11-12T22:17:17-05:00 L5 sdb[306]: ELAN: Port lan-1 (eth0) going up
2012-11-12T22:17:17-05:00 L5 sdb[306]: ELAN: Port lan-1 (eth0) speed 100 duplex full
2012-11-12T22:17:46-05:00 L5 sdb[306]: ELAN: Port lan-1 (eth0) going down
2012-11-12T22:17:48-05:00 L5 sdb[306]: ELAN: Port lan-1 (eth0) going up
2012-11-12T22:17:48-05:00 L5 sdb[306]: ELAN: Port lan-1 (eth0) speed 100 duplex full
..
2012-11-12T22:21:24-05:00 L5 sdb[306]: ELAN: Port lan-1 (eth0) going down
2012-11-12T22:21:25-05:00 L5 sdb[306]: ELAN: Port lan-1 (eth0) going up
2012-11-12T22:21:25-05:00 L5 sdb[306]: ELAN: Port lan-1 (eth0) speed 100 duplex full

2) Your NVG 510 lost its connection to the remote AT&T device.  This could be caused by a bad line, or interference with the signal inside or outside your home.

2012-11-12T23:26:58-05:00 L3 sdb[306]: DSL: Last Retrain Reason: 0x1 RDI Detector 
2012-11-12T23:26:59-05:00 L3 sdb[306]: DSL: Link drop counter since boot 1

2012-11-13T00:20:08-05:00 L3 sdb[306]: DSL: Last Retrain Reason: 0x1 RDI Detector 
2012-11-13T00:20:09-05:00 L3 sdb[306]: DSL: Link drop counter since boot 2

About the only option you have regarding this second issue is call AT&T Technical Support at 800-288-2020 and tell them you're having issues with your RG staying connected and ask them to send someone out to check on the signal it's getting.