Wednesday, 7 September 2011

Draytek 2750n and profile drop

After a month of using the 2750N, I discovered that my profile had been capped at 31750Kbit/s. http://speedtester.bt.com/ Looking through the logs, I could see a lot of disconnections not caused by anything at my end (no changes made and nothing odd happening on the LAN), but the PPP link kept being terminated:

Aug 19 11:13:22 192.168.1.1 pppd[10704]: No response to 5 echo-requests
Aug 19 11:13:22 192.168.1.1 pppd[10704]: Serial link appears to be disconnected.
Aug 19 11:13:22 192.168.1.1 pppd[10704]: Connect time 0.7 minutes.
Aug 19 11:13:22 192.168.1.1 pppd[10704]: Sent 666 bytes, received 328 bytes.
Aug 19 11:13:23 192.168.1.1 root: revert uci network state in ip-down...
Aug 19 11:13:25 192.168.1.1 root: [WAN] check default route failed:(0), revert WAN state and ifup wan...
Aug 19 11:13:26 192.168.1.1 root: stopping ntpclient
Aug 19 11:13:27 192.168.1.1 pppd[10704]: Terminating on signal 15
Aug 19 11:13:29 192.168.1.1 pppd[12063]: Plugin rp-pppoe.so loaded.
Aug 19 11:13:29 192.168.1.1 pppd[12064]: pppd 2.4.4 started by root, uid 0
Aug 19 11:13:30 192.168.1.1 : IPv6 WAN up
Aug 19 11:13:30 192.168.1.1 dnsmasq[2044]: reading /tmp/resolv.conf.auto
Aug 19 11:13:30 192.168.1.1 dnsmasq[2044]: using nameserver 168.95.1.1#53
Aug 19 11:13:30 192.168.1.1 dnsmasq[2044]: using nameserver 4.2.2.1#53
Aug 19 11:13:30 192.168.1.1 dnsmasq[2044]: using local addresses only for domain lan
Aug 19 11:13:32 192.168.1.1 root: udp-broadcast-relay not start: can't find interface ...
Aug 19 11:13:45 192.168.1.1 pppd[12064]: Timeout waiting for PADS packets
Aug 19 11:13:45 192.168.1.1 pppd[12064]: Unable to complete PPPoE Discovery
Aug 19 11:14:17 192.168.1.1 root: [WAN] check default route failed:(0), revert WAN state and ifup wan...
Aug 19 11:14:19 192.168.1.1 : 12064 root         0 Z    [pppd]
Aug 19 11:14:19 192.168.1.1 ifdown: kill all pppd...
Aug 19 11:14:20 192.168.1.1 pppd[13050]: Plugin rp-pppoe.so loaded.
Aug 19 11:14:20 192.168.1.1 pppd[13051]: pppd 2.4.4 started by root, uid 0
Aug 19 11:14:20 192.168.1.1 : IPv6 WAN up
Aug 19 11:14:22 192.168.1.1 dnsmasq[2044]: reading /tmp/resolv.conf.auto
Aug 19 11:14:22 192.168.1.1 dnsmasq[2044]: using nameserver 168.95.1.1#53
Aug 19 11:14:22 192.168.1.1 dnsmasq[2044]: using nameserver 4.2.2.1#53
Aug 19 11:14:22 192.168.1.1 dnsmasq[2044]: using local addresses only for domain lan
Aug 19 11:14:35 192.168.1.1 pppd[13051]: Timeout waiting for PADS packets
Aug 19 11:14:35 192.168.1.1 pppd[13051]: Unable to complete PPPoE Discovery
Aug 19 11:15:10 192.168.1.1 root: [WAN] check default route failed:(0), revert WAN state and ifup wan...
Aug 19 11:15:10 192.168.1.1 pppd[13051]: PPP session is 1159
Aug 19 11:15:10 192.168.1.1 pppd[13051]: hnat_patch_fn /tmp/pppoe.info/eth1.101
Aug 19 11:15:10 192.168.1.1 pppd[13051]: Using interface ppp0
Aug 19 11:15:10 192.168.1.1 pppd[13051]: Connect: ppp0 <--> eth1.101
Aug 19 11:15:10 192.168.1.1 pppd[13051]: Terminating on signal 15
Aug 19 11:15:10 192.168.1.1 kernel: register netdev : ppp0#015
Aug 19 11:15:12 192.168.1.1 : 13051 root         0 Z    [pppd]
Aug 19 11:15:12 192.168.1.1 ifdown: kill all pppd...
Aug 19 11:15:13 192.168.1.1 pppd[14092]: Plugin rp-pppoe.so loaded.
Aug 19 11:15:13 192.168.1.1 pppd[14093]: pppd 2.4.4 started by root, uid 0
Aug 19 11:15:13 192.168.1.1 : IPv6 WAN up
Aug 19 11:15:14 192.168.1.1 dnsmasq[2044]: reading /tmp/resolv.conf.auto
Aug 19 11:15:14 192.168.1.1 dnsmasq[2044]: using nameserver 168.95.1.1#53
Aug 19 11:15:14 192.168.1.1 dnsmasq[2044]: using nameserver 4.2.2.1#53
Aug 19 11:15:14 192.168.1.1 dnsmasq[2044]: using local addresses only for domain lan
Aug 19 11:15:28 192.168.1.1 pppd[14093]: Timeout waiting for PADS packets
Aug 19 11:15:28 192.168.1.1 pppd[14093]: Unable to complete PPPoE Discovery
Aug 19 11:16:03 192.168.1.1 root: [WAN] check default route failed:(0), revert WAN state and ifup wan...
Aug 19 11:16:05 192.168.1.1 : 14093 root         0 Z    [pppd]
Aug 19 11:16:05 192.168.1.1 ifdown: kill all pppd...
Aug 19 11:16:06 192.168.1.1 pppd[15129]: Plugin rp-pppoe.so loaded.
Aug 19 11:16:06 192.168.1.1 pppd[15130]: pppd 2.4.4 started by root, uid 0
Aug 19 11:16:06 192.168.1.1 : IPv6 WAN up
Aug 19 11:16:08 192.168.1.1 dnsmasq[2044]: reading /tmp/resolv.conf.auto
Aug 19 11:16:08 192.168.1.1 dnsmasq[2044]: using nameserver 168.95.1.1#53
Aug 19 11:16:08 192.168.1.1 dnsmasq[2044]: using nameserver 4.2.2.1#53
Aug 19 11:16:08 192.168.1.1 dnsmasq[2044]: using local addresses only for domain lan
Aug 19 11:16:21 192.168.1.1 pppd[15130]: Timeout waiting for PADO packets
Aug 19 11:16:21 192.168.1.1 pppd[15130]: Unable to complete PPPoE Discovery
Aug 19 11:16:56 192.168.1.1 root: [WAN] check default route failed:(0), revert WAN state and ifup wan...
Aug 19 11:16:58 192.168.1.1 : 15130 root         0 Z    [pppd]
Aug 19 11:16:58 192.168.1.1 ifdown: kill all pppd...
Aug 19 11:16:59 192.168.1.1 pppd[16174]: Plugin rp-pppoe.so loaded.
Aug 19 11:16:59 192.168.1.1 pppd[16175]: pppd 2.4.4 started by root, uid 0
Aug 19 11:16:59 192.168.1.1 : IPv6 WAN up
Aug 19 11:16:59 192.168.1.1 pppd[16175]: PPP session is 1166
Aug 19 11:16:59 192.168.1.1 pppd[16175]: hnat_patch_fn /tmp/pppoe.info/eth1.101
Aug 19 11:16:59 192.168.1.1 pppd[16175]: Using interface ppp0
Aug 19 11:16:59 192.168.1.1 pppd[16175]: Connect: ppp0 <--> eth1.101
Aug 19 11:16:59 192.168.1.1 kernel: register netdev : ppp0#015
Aug 19 11:16:59 192.168.1.1 pppd[16175]: CHAP authentication succeeded: CHAP authentication success, unit 12646
Aug 19 11:16:59 192.168.1.1 pppd[16175]: CHAP authentication succeeded
Aug 19 11:16:59 192.168.1.1 pppd[16175]: peer from calling number aa:bb:cc:dd:ee:ff:gg:hh authorized
Aug 19 11:16:59 192.168.1.1 pppd[16175]: local  IP address 1.2.3.4
Aug 19 11:16:59 192.168.1.1 pppd[16175]: remote IP address 217.32.142.3
Aug 19 11:16:59 192.168.1.1 pppd[16175]: primary   DNS address 194.72.9.38
Aug 19 11:16:59 192.168.1.1 pppd[16175]: secondary DNS address 62.6.40.162
Aug 19 11:16:59 192.168.1.1 dnsmasq[2044]: reading /tmp/resolv.conf.auto
Aug 19 11:16:59 192.168.1.1 dnsmasq[2044]: using nameserver 62.6.40.162#53
Aug 19 11:16:59 192.168.1.1 dnsmasq[2044]: using nameserver 194.72.9.38#53
Aug 19 11:16:59 192.168.1.1 dnsmasq[2044]: using local addresses only for domain lan
Aug 19 11:17:01 192.168.1.1 firewall: Restart user's custom iptables rules
Aug 19 11:17:05 192.168.1.1 root: udp-broadcast-relay not start: can't find interface ...
Aug 19 11:17:08 192.168.1.1 kernel: gvid = 2 0x1 gvid 16= 40000
Aug 19 11:17:08 192.168.1.1 kernel: gvid = 2 (gvid&0xFF)  16= 20000
Aug 19 11:17:09 192.168.1.1 syslog: Required parameters missing, make sure that username and password are given
Aug 19 11:17:13 192.168.1.1 miniupnpd: removing firewall rules for ppp0 from zone wan
Aug 19 11:17:13 192.168.1.1 miniupnpd: adding firewall rules for ppp0 to zone wan
Aug 19 11:17:23 192.168.1.1 root: starting ntpclient
Aug 19 11:17:28 192.168.1.1 root: udp-broadcast-relay not start: can't find interface ...
Aug 19 11:19:28 192.168.1.1 pppd[16175]: No response to 5 echo-requests
Aug 19 11:19:28 192.168.1.1 pppd[16175]: Serial link appears to be disconnected.
Aug 19 11:19:28 192.168.1.1 pppd[16175]: Connect time 2.5 minutes.
Aug 19 11:19:28 192.168.1.1 pppd[16175]: Sent 1946 bytes, received 872 bytes.
Aug 19 11:19:28 192.168.1.1 root: revert uci network state in ip-down...
Aug 19 11:19:30 192.168.1.1 syslog: [WAN] WAN detection success...ifup wan
Aug 19 11:19:31 192.168.1.1 pppd[16175]: Connection terminated.
Aug 19 11:19:32 192.168.1.1 : 16175 root      1308 S    /usr/sbin/pppd plugin rp-pppoe.so mtu 1442 mru 1442 n
Aug 19 11:19:32 192.168.1.1 root: stopping ntpclient
Aug 19 11:19:32 192.168.1.1 pppd[16175]: Terminating on signal 15
Aug 19 11:19:32 192.168.1.1 ifdown: kill all pppd...
Aug 19 11:19:33 192.168.1.1 pppd[19613]: Plugin rp-pppoe.so loaded.
Aug 19 11:19:33 192.168.1.1 pppd[19614]: pppd 2.4.4 started by root, uid 0
Aug 19 11:19:33 192.168.1.1 : IPv6 WAN up
Aug 19 11:19:33 192.168.1.1 pppd[19614]: PPP session is 1170
Aug 19 11:19:33 192.168.1.1 pppd[19614]: hnat_patch_fn /tmp/pppoe.info/eth1.101
Aug 19 11:19:33 192.168.1.1 kernel: register netdev : ppp0#015
Aug 19 11:19:33 192.168.1.1 pppd[19614]: Using interface ppp0
Aug 19 11:19:33 192.168.1.1 pppd[19614]: Connect: ppp0 <--> eth1.101
Aug 19 11:19:34 192.168.1.1 pppd[19614]: CHAP authentication succeeded: CHAP authentication success, unit 2731
Aug 19 11:19:34 192.168.1.1 pppd[19614]: CHAP authentication succeeded
Aug 19 11:19:34 192.168.1.1 pppd[19614]: peer from calling number aa:bb:cc:dd:ee:ff:gg:hh authorized
Aug 19 11:19:34 192.168.1.1 pppd[19614]: local  IP address 1.2.3.4
Aug 19 11:19:34 192.168.1.1 pppd[19614]: remote IP address 217.32.142.3
Aug 19 11:19:34 192.168.1.1 pppd[19614]: primary   DNS address 194.72.9.38
Aug 19 11:19:34 192.168.1.1 pppd[19614]: secondary DNS address 62.6.40.162
Aug 19 11:19:34 192.168.1.1 dnsmasq[2044]: reading /tmp/resolv.conf.auto
Aug 19 11:19:34 192.168.1.1 dnsmasq[2044]: using nameserver 62.6.40.162#53
Aug 19 11:19:34 192.168.1.1 dnsmasq[2044]: using nameserver 194.72.9.38#53
Aug 19 11:19:34 192.168.1.1 dnsmasq[2044]: using local addresses only for domain lan
Aug 19 11:19:36 192.168.1.1 firewall: Restart user's custom iptables rules
Aug 19 11:19:37 192.168.1.1 root: udp-broadcast-relay not start: can't find interface ...
Aug 19 11:19:38 192.168.1.1 pppd[16175]: Exit.
Aug 19 11:19:40 192.168.1.1 root: udp-broadcast-relay not start: can't find interface ...
Aug 19 11:19:42 192.168.1.1 kernel: gvid = 2 0x1 gvid 16= 40000
Aug 19 11:19:42 192.168.1.1 kernel: gvid = 2 (gvid&0xFF)  16= 20000
Aug 19 11:19:44 192.168.1.1 syslog: Required parameters missing, make sure that username and password are given
Aug 19 11:19:47 192.168.1.1 miniupnpd: removing firewall rules for ppp0 from zone wan
Aug 19 11:19:48 192.168.1.1 miniupnpd: adding firewall rules for ppp0 to zone wan
Aug 19 11:20:30 192.168.1.1 pppd[19614]: No response to 5 echo-requests
Aug 19 11:20:30 192.168.1.1 pppd[19614]: Serial link appears to be disconnected.
Aug 19 11:20:30 192.168.1.1 pppd[19614]: Connect time 1.0 minutes.
Aug 19 11:20:30 192.168.1.1 pppd[19614]: Sent 14187 bytes, received 31492 bytes.
Aug 19 11:20:31 192.168.1.1 root: [WAN] check default route failed:(0), revert WAN state and ifup wan...
Aug 19 11:20:32 192.168.1.1 pppd[19614]: Terminating on signal 15
Aug 19 11:20:35 192.168.1.1 pppd[21238]: Plugin rp-pppoe.so loaded.
Aug 19 11:20:35 192.168.1.1 pppd[21242]: pppd 2.4.4 started by root, uid 0
Aug 19 11:20:35 192.168.1.1 : IPv6 WAN up
Aug 19 11:20:37 192.168.1.1 dnsmasq[2044]: reading /tmp/resolv.conf.auto
Aug 19 11:20:37 192.168.1.1 dnsmasq[2044]: using nameserver 168.95.1.1#53
Aug 19 11:20:37 192.168.1.1 dnsmasq[2044]: using nameserver 4.2.2.1#53
Aug 19 11:20:37 192.168.1.1 dnsmasq[2044]: using local addresses only for domain lan
Aug 19 11:20:50 192.168.1.1 pppd[21242]: Timeout waiting for PADO packets
Aug 19 11:20:50 192.168.1.1 pppd[21242]: Unable to complete PPPoE Discovery
Aug 19 11:21:20 192.168.1.1 root: [WAN] check default route failed:(0), revert WAN state and ifup wan...
Aug 19 11:21:22 192.168.1.1 : 21242 root         0 Z    [pppd]
Aug 19 11:21:22 192.168.1.1 ifdown: kill all pppd...
Aug 19 11:21:23 192.168.1.1 pppd[22217]: Plugin rp-pppoe.so loaded.
Aug 19 11:21:23 192.168.1.1 pppd[22218]: pppd 2.4.4 started by root, uid 0
Aug 19 11:21:23 192.168.1.1 : IPv6 WAN up
Aug 19 11:21:24 192.168.1.1 dnsmasq[2044]: reading /tmp/resolv.conf.auto
Aug 19 11:21:24 192.168.1.1 dnsmasq[2044]: using nameserver 168.95.1.1#53
Aug 19 11:21:24 192.168.1.1 dnsmasq[2044]: using nameserver 4.2.2.1#53
Aug 19 11:21:24 192.168.1.1 dnsmasq[2044]: using local addresses only for domain lan
Aug 19 11:21:29 192.168.1.1 pppd[22218]: PPP session is 1195
Aug 19 11:21:29 192.168.1.1 pppd[22218]: hnat_patch_fn /tmp/pppoe.info/eth1.101
Aug 19 11:21:29 192.168.1.1 pppd[22218]: Using interface ppp0
Aug 19 11:21:29 192.168.1.1 kernel: register netdev : ppp0#015
Aug 19 11:21:29 192.168.1.1 pppd[22218]: Connect: ppp0 <--> eth1.101
Aug 19 11:21:29 192.168.1.1 pppd[22218]: CHAP authentication succeeded: CHAP authentication success, unit 15087
Aug 19 11:21:29 192.168.1.1 pppd[22218]: CHAP authentication succeeded
Aug 19 11:21:29 192.168.1.1 pppd[22218]: peer from calling number aa:bb:cc:dd:ee:ff:gg:hh authorized
Aug 19 11:21:29 192.168.1.1 pppd[22218]: local  IP address 1.2.3.4
Aug 19 11:21:29 192.168.1.1 pppd[22218]: remote IP address 217.32.142.3
Aug 19 11:21:29 192.168.1.1 dnsmasq[2044]: reading /tmp/resolv.conf.auto
Aug 19 11:21:29 192.168.1.1 dnsmasq[2044]: using nameserver 62.6.40.162#53
Aug 19 11:21:29 192.168.1.1 dnsmasq[2044]: using nameserver 194.72.9.38#53
Aug 19 11:21:29 192.168.1.1 dnsmasq[2044]: using local addresses only for domain lan
Aug 19 11:21:29 192.168.1.1 pppd[22218]: primary   DNS address 194.72.9.38
Aug 19 11:21:29 192.168.1.1 pppd[22218]: secondary DNS address 62.6.40.162
Aug 19 11:21:30 192.168.1.1 firewall: Restart user's custom iptables rules
Aug 19 11:21:34 192.168.1.1 root: udp-broadcast-relay not start: can't find interface ...
Aug 19 11:21:37 192.168.1.1 kernel: gvid = 2 0x1 gvid 16= 40000
Aug 19 11:21:37 192.168.1.1 kernel: gvid = 2 (gvid&0xFF)  16= 20000
Aug 19 11:21:38 192.168.1.1 syslog: Required parameters missing, make sure that username and password are given
Aug 19 11:21:42 192.168.1.1 miniupnpd: removing firewall rules for ppp0 from zone wan
Aug 19 11:21:43 192.168.1.1 miniupnpd: adding firewall rules for ppp0 to zone wan
Aug 19 11:21:47 192.168.1.1 root: udp-broadcast-relay not start: can't find interface ...
Aug 19 11:23:12 192.168.1.1 pppd[22218]: No response to 5 echo-requests
Aug 19 11:23:12 192.168.1.1 pppd[22218]: Serial link appears to be disconnected.
Aug 19 11:23:12 192.168.1.1 pppd[22218]: Connect time 1.8 minutes.
Aug 19 11:23:12 192.168.1.1 pppd[22218]: Sent 3031 bytes, received 3743 bytes.
Aug 19 11:23:13 192.168.1.1 root: revert uci network state in ip-down...
Aug 19 11:23:15 192.168.1.1 root: stopping ntpclient
Aug 19 11:23:20 192.168.1.1 root: udp-broadcast-relay not start: can't find interface ...
Aug 19 11:23:24 192.168.1.1 pppd[22218]: Connection terminated.
Aug 19 11:23:24 192.168.1.1 pppd[22218]: Modem hangup
Aug 19 11:23:31 192.168.1.1 root: [WAN] check default route failed:(0), revert WAN state and ifup wan...
Aug 19 11:23:32 192.168.1.1 : 22218 root      1308 S    /usr/sbin/pppd plugin rp-pppoe.so mtu 1442 mru 1442 n
Aug 19 11:23:32 192.168.1.1 pppd[22218]: Terminating on signal 15
Aug 19 11:23:32 192.168.1.1 pppd[22218]: Exit.
Aug 19 11:23:32 192.168.1.1 ifdown: kill all pppd...
Aug 19 11:23:34 192.168.1.1 pppd[25189]: Plugin rp-pppoe.so loaded.
Aug 19 11:23:34 192.168.1.1 pppd[25190]: pppd 2.4.4 started by root, uid 0
Aug 19 11:23:34 192.168.1.1 : IPv6 WAN up
Aug 19 11:23:34 192.168.1.1 dnsmasq[2044]: reading /tmp/resolv.conf.auto
Aug 19 11:23:34 192.168.1.1 dnsmasq[2044]: using nameserver 168.95.1.1#53
Aug 19 11:23:34 192.168.1.1 dnsmasq[2044]: using nameserver 4.2.2.1#53
Aug 19 11:23:34 192.168.1.1 dnsmasq[2044]: using local addresses only for domain lan
Aug 19 11:23:49 192.168.1.1 pppd[25190]: Timeout waiting for PADS packets
Aug 19 11:23:49 192.168.1.1 pppd[25190]: Unable to complete PPPoE Discovery
Aug 19 11:24:25 192.168.1.1 root: [WAN] check default route failed:(0), revert WAN state and ifup wan...
Aug 19 11:24:26 192.168.1.1 : 25190 root         0 Z    [pppd]
Aug 19 11:24:27 192.168.1.1 ifdown: kill all pppd...
Aug 19 11:24:28 192.168.1.1 pppd[26244]: Plugin rp-pppoe.so loaded.
Aug 19 11:24:28 192.168.1.1 pppd[26245]: pppd 2.4.4 started by root, uid 0
Aug 19 11:24:28 192.168.1.1 : IPv6 WAN up
Aug 19 11:24:29 192.168.1.1 dnsmasq[2044]: reading /tmp/resolv.conf.auto
Aug 19 11:24:29 192.168.1.1 dnsmasq[2044]: using nameserver 168.95.1.1#53
Aug 19 11:24:29 192.168.1.1 dnsmasq[2044]: using nameserver 4.2.2.1#53
Aug 19 11:24:29 192.168.1.1 dnsmasq[2044]: using local addresses only for domain lan
Aug 19 11:24:43 192.168.1.1 pppd[26245]: Timeout waiting for PADO packets
Aug 19 11:24:43 192.168.1.1 pppd[26245]: Unable to complete PPPoE Discovery
Aug 19 11:25:17 192.168.1.1 root: [WAN] check default route failed:(0), revert WAN state and ifup wan...
Aug 19 11:25:19 192.168.1.1 : 26245 root         0 Z    [pppd]
Aug 19 11:25:19 192.168.1.1 ifdown: kill all pppd...
Aug 19 11:25:21 192.168.1.1 pppd[27285]: Plugin rp-pppoe.so loaded.
Aug 19 11:25:21 192.168.1.1 pppd[27286]: pppd 2.4.4 started by root, uid 0
Aug 19 11:25:21 192.168.1.1 : IPv6 WAN up
Aug 19 11:25:21 192.168.1.1 pppd[27286]: PPP session is 1214
Aug 19 11:25:21 192.168.1.1 pppd[27286]: hnat_patch_fn /tmp/pppoe.info/eth1.101
Aug 19 11:25:21 192.168.1.1 pppd[27286]: Using interface ppp0
Aug 19 11:25:21 192.168.1.1 pppd[27286]: Connect: ppp0 <--> eth1.101
Aug 19 11:25:21 192.168.1.1 kernel: register netdev : ppp0#015
Aug 19 11:25:21 192.168.1.1 pppd[27286]: CHAP authentication succeeded: CHAP authentication success, unit 2685
Aug 19 11:25:21 192.168.1.1 pppd[27286]: CHAP authentication succeeded
Aug 19 11:25:21 192.168.1.1 pppd[27286]: peer from calling number aa:bb:cc:dd:ee:ff:gg:hh authorized
Aug 19 11:25:21 192.168.1.1 pppd[27286]: local  IP address 1.2.3.4
Aug 19 11:25:21 192.168.1.1 pppd[27286]: remote IP address 217.32.142.3
Aug 19 11:25:21 192.168.1.1 pppd[27286]: primary   DNS address 194.72.9.38
Aug 19 11:25:21 192.168.1.1 pppd[27286]: secondary DNS address 62.6.40.162
Aug 19 11:25:21 192.168.1.1 dnsmasq[2044]: reading /tmp/resolv.conf.auto
Aug 19 11:25:21 192.168.1.1 dnsmasq[2044]: using nameserver 62.6.40.162#53
Aug 19 11:25:21 192.168.1.1 dnsmasq[2044]: using nameserver 194.72.9.38#53
Aug 19 11:25:21 192.168.1.1 dnsmasq[2044]: using local addresses only for domain lan
Aug 19 11:25:23 192.168.1.1 firewall: Restart user's custom iptables rules
Aug 19 11:25:27 192.168.1.1 root: udp-broadcast-relay not start: can't find interface ...
Aug 19 11:25:30 192.168.1.1 kernel: gvid = 2 0x1 gvid 16= 40000
Aug 19 11:25:30 192.168.1.1 kernel: gvid = 2 (gvid&0xFF)  16= 20000
Aug 19 11:25:31 192.168.1.1 syslog: Required parameters missing, make sure that username and password are given
Aug 19 11:25:34 192.168.1.1 miniupnpd: removing firewall rules for ppp0 from zone wan
Aug 19 11:25:35 192.168.1.1 miniupnpd: adding firewall rules for ppp0 to zone wan
Aug 19 11:25:44 192.168.1.1 root: starting ntpclient
Aug 19 11:25:50 192.168.1.1 root: udp-broadcast-relay not start: can't find interface ...
Aug 19 11:27:25 192.168.1.1 pppd[27286]: No response to 5 echo-requests
Aug 19 11:27:25 192.168.1.1 pppd[27286]: Serial link appears to be disconnected.
Aug 19 11:27:25 192.168.1.1 pppd[27286]: Connect time 2.1 minutes.
Aug 19 11:27:25 192.168.1.1 pppd[27286]: Sent 12694 bytes, received 13432 bytes.
Aug 19 11:27:25 192.168.1.1 pppd[27286]: Connection terminated.
Aug 19 11:27:26 192.168.1.1 root: [WAN] check default route failed:(0), revert WAN state and ifup wan...
Aug 19 11:27:26 192.168.1.1 root: revert uci network state in ip-down...
Aug 19 11:27:28 192.168.1.1 : 27286 root      1308 S    /usr/sbin/pppd plugin rp-pppoe.so mtu 1442 mru 1442 n
Aug 19 11:27:28 192.168.1.1 pppd[27286]: Terminating on signal 15
Aug 19 11:27:28 192.168.1.1 ifdown: kill all pppd...
Aug 19 11:27:30 192.168.1.1 dnsmasq[2044]: no servers found in /tmp/resolv.conf.auto, will retry
Aug 19 11:27:30 192.168.1.1 pppd[30215]: Plugin rp-pppoe.so loaded.
Aug 19 11:27:31 192.168.1.1 pppd[30223]: pppd 2.4.4 started by root, uid 0
Aug 19 11:27:31 192.168.1.1 root: stopping ntpclient
Aug 19 11:27:31 192.168.1.1 : IPv6 WAN up
Aug 19 11:27:31 192.168.1.1 pppd[30223]: PPP session is 186
Aug 19 11:27:31 192.168.1.1 pppd[30223]: hnat_patch_fn /tmp/pppoe.info/eth1.101
Aug 19 11:27:31 192.168.1.1 kernel: register netdev : ppp0#015
Aug 19 11:27:31 192.168.1.1 pppd[30223]: Using interface ppp0
Aug 19 11:27:31 192.168.1.1 pppd[30223]: Connect: ppp0 <--> eth1.101
Aug 19 11:27:31 192.168.1.1 pppd[30223]: CHAP authentication succeeded: CHAP authentication success, unit 14295
Aug 19 11:27:31 192.168.1.1 pppd[30223]: CHAP authentication succeeded
Aug 19 11:27:31 192.168.1.1 pppd[30223]: peer from calling number aa:bb:cc:dd:ee:ff:gg:hh authorized
Aug 19 11:27:31 192.168.1.1 dnsmasq[2044]: reading /tmp/resolv.conf.auto
Aug 19 11:27:31 192.168.1.1 dnsmasq[2044]: using nameserver 62.6.40.162#53
Aug 19 11:27:31 192.168.1.1 dnsmasq[2044]: using nameserver 194.72.9.38#53
Aug 19 11:27:31 192.168.1.1 dnsmasq[2044]: using local addresses only for domain lan
Aug 19 11:27:31 192.168.1.1 pppd[30223]: local  IP address 1.2.3.4
Aug 19 11:27:31 192.168.1.1 pppd[30223]: remote IP address 217.32.142.3
Aug 19 11:27:31 192.168.1.1 pppd[30223]: primary   DNS address 194.72.9.38
Aug 19 11:27:31 192.168.1.1 pppd[30223]: secondary DNS address 62.6.40.162
Aug 19 11:27:33 192.168.1.1 firewall: Restart user's custom iptables rules
Aug 19 11:27:37 192.168.1.1 root: udp-broadcast-relay not start: can't find interface ...
Aug 19 11:27:37 192.168.1.1 pppd[27286]: Exit.
Aug 19 11:27:37 192.168.1.1 root: udp-broadcast-relay not start: can't find interface ...
Aug 19 11:27:40 192.168.1.1 kernel: gvid = 2 0x1 gvid 16= 40000
Aug 19 11:27:40 192.168.1.1 kernel: gvid = 2 (gvid&0xFF)  16= 20000
Aug 19 11:27:42 192.168.1.1 syslog: Required parameters missing, make sure that username and password are given
Aug 19 11:27:45 192.168.1.1 miniupnpd: removing firewall rules for ppp0 from zone wan
Aug 19 11:27:46 192.168.1.1 miniupnpd: adding firewall rules for ppp0 to zone wan
Aug 19 11:28:27 192.168.1.1 pppd[30223]: No response to 5 echo-requests
Aug 19 11:28:27 192.168.1.1 pppd[30223]: Serial link appears to be disconnected.
Aug 19 11:28:27 192.168.1.1 pppd[30223]: Connect time 1.0 minutes.
Aug 19 11:28:27 192.168.1.1 pppd[30223]: Sent 716 bytes, received 7857 bytes.
Aug 19 11:28:33 192.168.1.1 pppd[30223]: Connection terminated.
Aug 19 11:28:33 192.168.1.1 pppd[30223]: Modem hangup
Aug 19 11:28:53 192.168.1.1 root: [WAN] check default route failed:(0), revert WAN state and ifup wan...
Aug 19 11:28:55 192.168.1.1 : 30223 root      1308 S    /usr/sbin/pppd plugin rp-pppoe.so mtu 1442 mru 1442 n
Aug 19 11:28:55 192.168.1.1 pppd[30223]: Terminating on signal 15
Aug 19 11:28:55 192.168.1.1 ifdown: kill all pppd...
Aug 19 11:28:56 192.168.1.1 pppd[32274]: Plugin rp-pppoe.so loaded.
Aug 19 11:28:56 192.168.1.1 pppd[32275]: pppd 2.4.4 started by root, uid 0
Aug 19 11:28:56 192.168.1.1 : IPv6 WAN up
Aug 19 11:28:57 192.168.1.1 dnsmasq[2044]: reading /tmp/resolv.conf.auto
Aug 19 11:28:57 192.168.1.1 dnsmasq[2044]: using nameserver 168.95.1.1#53
Aug 19 11:28:57 192.168.1.1 dnsmasq[2044]: using nameserver 4.2.2.1#53
Aug 19 11:28:57 192.168.1.1 dnsmasq[2044]: using local addresses only for domain lan
Aug 19 11:29:00 192.168.1.1 pppd[30223]: Exit.
Aug 19 11:29:08 192.168.1.1 syslog: [WAN] WAN detection success...ifup wan
Aug 19 11:29:08 192.168.1.1 root: ifup is running.......skip ifup wan
Aug 19 11:29:11 192.168.1.1 pppd[32275]: Timeout waiting for PADO packets
Aug 19 11:29:11 192.168.1.1 pppd[32275]: Unable to complete PPPoE Discovery
Aug 19 11:29:40 192.168.1.1 root: [WAN] check default route failed:(0), revert WAN state and ifup wan...
Aug 19 11:29:43 192.168.1.1 : 32275 root         0 Z    [pppd]
Aug 19 11:29:43 192.168.1.1 ifdown: kill all pppd...
Aug 19 11:29:44 192.168.1.1 pppd[772]: Plugin rp-pppoe.so loaded.
Aug 19 11:29:44 192.168.1.1 pppd[773]: pppd 2.4.4 started by root, uid 0
Aug 19 11:29:44 192.168.1.1 : IPv6 WAN up
Aug 19 11:29:44 192.168.1.1 pppd[773]: PPP session is 596
Aug 19 11:29:44 192.168.1.1 pppd[773]: hnat_patch_fn /tmp/pppoe.info/eth1.101
Aug 19 11:29:44 192.168.1.1 pppd[773]: Using interface ppp0
Aug 19 11:29:44 192.168.1.1 kernel: register netdev : ppp0#015
Aug 19 11:29:44 192.168.1.1 pppd[773]: Connect: ppp0 <--> eth1.101
Aug 19 11:29:44 192.168.1.1 pppd[773]: CHAP authentication succeeded: CHAP authentication success, unit 7675
Aug 19 11:29:44 192.168.1.1 pppd[773]: CHAP authentication succeeded
Aug 19 11:29:44 192.168.1.1 pppd[773]: peer from calling number aa:bb:cc:dd:ee:ff:gg:hh authorized
Aug 19 11:29:44 192.168.1.1 pppd[773]: local  IP address 1.2.3.4
Aug 19 11:29:44 192.168.1.1 pppd[773]: remote IP address 217.32.142.3
Aug 19 11:29:44 192.168.1.1 pppd[773]: primary   DNS address 194.72.9.38
Aug 19 11:29:44 192.168.1.1 pppd[773]: secondary DNS address 62.6.40.162
Aug 19 11:29:44 192.168.1.1 dnsmasq[2044]: reading /tmp/resolv.conf.auto
Aug 19 11:29:44 192.168.1.1 dnsmasq[2044]: using nameserver 62.6.40.162#53
Aug 19 11:29:44 192.168.1.1 dnsmasq[2044]: using nameserver 194.72.9.38#53
Aug 19 11:29:44 192.168.1.1 dnsmasq[2044]: using local addresses only for domain lan
Aug 19 11:29:46 192.168.1.1 firewall: Restart user's custom iptables rules
Aug 19 11:29:50 192.168.1.1 root: udp-broadcast-relay not start: can't find interface ...
Aug 19 11:29:53 192.168.1.1 kernel: gvid = 2 0x1 gvid 16= 40000
Aug 19 11:29:53 192.168.1.1 kernel: gvid = 2 (gvid&0xFF)  16= 20000
Aug 19 11:29:54 192.168.1.1 syslog: Required parameters missing, make sure that username and password are given
Aug 19 11:29:58 192.168.1.1 miniupnpd: removing firewall rules for ppp0 from zone wan
Aug 19 11:29:59 192.168.1.1 miniupnpd: adding firewall rules for ppp0 to zone wan


I knew from my rudimentary understanding of ADSL profiling, that if I left the connection 24h/10d, then my profile should improve.  Sure enough after 7 days, there was one final disconnection and I was re-profiled back to 37.5Mb. From the Stats chart below, it is clear there were 10M of FEC errors (Thanks Father Hackett) - I saw these coming in quickly during the period of instability, but they are in the past. My prime suspects for the instability:

  1. WAN connection detection (see below).
  2. Engineering work at the cabinet or on the copper.
  3. Router firmware is iffy (it was only released on 8-Aug-2011).

System Uptime: 9d 04:38:12

Up time: 1d 08:18:00


VDSL Status

VDSL
Link Status
Firmware Version 1411f0
VDSL2 Profile 8c
Basic StatusUpstreamDownstreamUnit
Actual Data Rate 9998 34987 Kb/s
SNR 137 79 0.1dB
Advance Status UpstreamDownstreamUnit
Actual delay 0 0 ms
Actual INP 0 0 0.1 symbols
15M CV 0 1 counter
1Day CV 21 278 counter
15M FEC 016 counter
1Day FEC 0 2708 counter
Total FEC 0 10020411 counter
Previous Data Rate 9998 31823 Kbps
Attainable Rate 14650 37510 Kbps
Electrical Length 40 40 0.1 dB
SNR Margin 136 N/A (US0,--) 0.1 dB
SNR Margin 137 79 (US1,DS1) 0.1 dB
SNR Margin N/A 78 (US2,DS2) 0.1 dB
SNR Margin N/A N/A (US3,DS3) 0.1 dB
SNR Margin N/A N/A (US4,DS4) 0.1 dB
15M Elapsed time 247 247 secs
15M FECS 0 4 counter
15M ES 0 1 counter
15M SES 0 0 counter
15M LOSS 0 0 counter
15M UAS 0 0 counter
1Day Elapsed time 29947 29947 secs
1Day FECS 0 317 counter
1Day ES 18 245 counter
1Day SES 0 0 counter
1Day LOSS 0 0 counter
1Day UAS 0 0 counter
Total FECS 0 38261 counter
Total ES 31696 833 counter
Total SES 968 18 counter
Total LOSS 0 20 counter
Total UAS 79750 84 counter

Where local IP address has been changed to 1.2.3.4 and Calling number modified to aa:bb:cc:dd:ee:ff:gg:hh

I disabled temporarily(or maybe not?) the WAN->Internet Access->WAN Connection Detection->Disabled.

3 comments:

The great pretender of stuff! said...

would you be able to assist me on my situation at the moment in relation to my set up with the vigor 2750n on bt infinity? Since i switched over the hub and modem to the vigor my profile seems to also have been capped to 33 my upload has stayed the same but i can never get upload above 5 and a half.I contacted BT to ask for a reset which they did and where as before tests using the speedtest.net i was getting eratic ups and downs on the graph where as now it seems like steady line,but no improvement on the down and upload and ping times.im at a loss and would really appreciate some detailed advice?

tng said...

Hello,

Well I can't really offer any detailed advice as above, I would remove the WAN connection detection.

I would leave the 2750 on 24/7 and never disconnect it.

I would also enable syslog and check the logs every day to find out if you have any restarts.

I would run a speedtest at speedtester.bt.com and find out if you have been set a lower profile and what your upload speed is.

Finally, if you are really worried about a 7Mb degredation, then plug in the Openreach HG612 and HH3 and leave for 10 days. Check regularly at speedtest.net and speedtester.bt.com. If the profile changes and you get 40Mb again then raise a support ticket with Draytek.

The great pretender of stuff! said...

Yeah i did the speedtester at btspeedtester and thats where i found my profile had been reduced to the 33 getting 31 wired. Could you give me some idea as to what the actual delay (mine8)and actual INR(mine 30) as i noticed this is different to what you had on your vdsl status,is this interleaving? what i havn't done is turn on syslog mainly because i don't really know what to look for.Ive been researching as much as i can on all router terminology to gather asmuch knowledge as i can but there is so much to learn. Ive resisted not switching off the router and giving it as much time as possible as you posted in your blog, maybe i just need to give it a bit longer.im on day 6 with all settings as you suggested.