8900AX-1600R2 (2.52.d2) - WAN connection instability ?

Discussions for BiPAC 8900 series: 8900AX-1600, 8900AX-2400, 8900X
Post Reply
Pete B
Posts: 26
Joined: Tue Sep 15, 2015 3:15 pm

8900AX-1600R2 (2.52.d2) - WAN connection instability ?

Post by Pete B »

Hi again

You'll doubtless recall the recent thread about intermittent WAN connection failure, which we basically resolved through correcting IPv6 setup parameters. Does seem that I still have a 'less than stable' setup though. It's currently much better than before, I don't for example get warning email anymore informing of 'new' WAN IP addresses.

However what does happen is that the WAN connection time still periodically resets. No set pattern to this, it might go a couple of days, might be twice in one day. What I do see though is a change from green to amber (or vice versa) of the EWAN/P5 indicator. My understanding of these indications has always been that green is > 100 Mbit/s (e.g. for gigabit LAN speeds) and amber is < 100 Mbit/s (actually perhaps what I might expect to see as this port is being served by the ethernet output of my modem ? ... not sure about this... perhaps the modem / EWAN port link should be regarded as gigabit ? Needless to say my indicator light colour varies all the time :?

I access the modem using an IPoE connection through the router but I don't think this would cause any issue.

I recently 'caught' a change from green to amber indication and have extracted the relevant syslog portion for that time. I would genuinely appreciate any advice as to what might cause this 'instability'. It's no big deal and it isn't disruptive but it's not correct and would be good to fix. Furthermore, from using ThinkBroadband's check it typically reports very low 'quality' scores e.g. band F. Maybe there is an issue here too. I have attached the syslog extract and a screenshot of the ThinkBroadband check.

Code: Select all

 
Nov 30 14:12:36 kern crit kernel: eth4 (switch port: 5) Link UP 1000 mbps full duplex
Nov 30 14:12:36 daemon info radvd[24686]: resuming normal operation
Nov 30 14:12:36 daemon warn radvd[24686]: received RA from fe80::6203:47ff:fe4b:xxxx
Nov 30 14:12:36 daemon info radvd[24686]: attempting to reread config file
Nov 30 14:12:36 daemon info radvd[24686]: resuming normal operation
Nov 30 14:12:36 daemon warn radvd[24686]: received RA from fe80::6203:47ff:fe4b:xxxx
Nov 30 14:12:37 daemon notice syslog: pppd:Terminating on signal 20.
Nov 30 14:12:38 kern crit kernel: eth4 (switch port: 5)  Link DOWN.
Nov 30 14:12:38 daemon info radvd[24686]: attempting to reread config file
Nov 30 14:12:38 daemon info radvd[24686]: resuming normal operation
Nov 30 14:12:38 daemon warn radvd[24686]: received RA from fe80::6203:47ff:fe4b:xxxx
Nov 30 14:12:38 daemon info radvd[24686]: attempting to reread config file
Nov 30 14:12:38 daemon info radvd[24686]: resuming normal operation
Nov 30 14:12:38 daemon warn radvd[24686]: received RA from fe80::6203:47ff:fe4b:xxxx
Nov 30 14:12:40 kern crit kernel: eth4 (switch port: 5) Link UP 1000 mbps full duplex
Nov 30 14:12:40 daemon info radvd[24686]: attempting to reread config file
Nov 30 14:12:40 daemon info radvd[24686]: resuming normal operation
Nov 30 14:12:40 daemon warn radvd[24686]: received RA from fe80::6203:47ff:fe4b:xxxx
Nov 30 14:12:40 daemon info radvd[24686]: attempting to reread config file
Nov 30 14:12:40 daemon info radvd[24686]: resuming normal operation
Nov 30 14:12:40 daemon warn radvd[24686]: received RA from fe80::6203:47ff:fe4b:xxxx
Nov 30 14:12:41 kern crit kernel: eth4 (switch port: 5)  Link DOWN.
Nov 30 14:12:41 daemon info radvd[24686]: attempting to reread config file
Nov 30 14:12:41 daemon info radvd[24686]: resuming normal operation
Nov 30 14:12:41 daemon warn radvd[24686]: received RA from fe80::6203:47ff:fe4b:xxxx
Nov 30 14:12:41 daemon info radvd[24686]: attempting to reread config file
Nov 30 14:12:41 daemon info radvd[24686]: resuming normal operation
Nov 30 14:12:41 daemon warn radvd[24686]: received RA from fe80::6203:47ff:fe4b:xxxx
Nov 30 14:12:42 daemon notice syslog: pppd:Connection terminated.
Nov 30 14:12:42 daemon notice syslog: pppd:Connect time 7.8 minutes.
Nov 30 14:12:42 daemon notice syslog: pppd:Sent 101788 bytes, received 219510 bytes.
Nov 30 14:12:42 daemon info radvd[24686]: attempting to reread config file
Nov 30 14:12:42 daemon info radvd[24686]: resuming normal operation
Nov 30 14:12:42 daemon warn radvd[24686]: received RA from fe80::6203:47ff:fe4b:xxxx
Nov 30 14:12:42 daemon notice syslog: pppd:Doing disconnect
Nov 30 14:12:42 daemon notice syslog: send_disc - 469 - Sent packet - Code: 0xa7 ...
Nov 30 14:12:42 daemon info radvd[24686]: attempting to reread config file
Nov 30 14:12:42 kern info kernel: device eth4 left promiscuous mode
Nov 30 14:12:42 daemon info radvd[24686]: resuming normal operation
Nov 30 14:12:42 daemon warn radvd[24686]: received RA from fe80::6203:47ff:fe4b:xxxx
Nov 30 14:12:42 daemon info radvd[24686]: attempting to reread config file
Nov 30 14:12:42 daemon info radvd[24686]: resuming normal operation
Nov 30 14:12:42 daemon warn radvd[24686]: received RA from fe80::6203:47ff:fe4b:xxxx
Nov 30 14:12:44 kern crit kernel: eth4 (switch port: 5) Link UP 1000 mbps full duplex
Nov 30 14:12:44 daemon info radvd[24686]: attempting to reread config file
Nov 30 14:12:44 daemon info radvd[24686]: resuming normal operation
Nov 30 14:12:44 daemon warn radvd[24686]: received RA from fe80::6203:47ff:fe4b:xxxx
Nov 30 14:12:44 daemon warn radvd[24686]: poll error: Interrupted system call
Nov 30 14:12:44 daemon warn radvd[24686]: Exiting, sigterm received.
Nov 30 14:12:45 kern crit kernel: eth4 (switch port: 5)  Link DOWN.
Nov 30 14:12:45 daemon info radvd[27520]: version 1.8 started
Nov 30 14:12:45 daemon warn radvd[27520]: received RA from fe80::6203:47ff:fe4b:xxxx
Nov 30 14:12:45 daemon warn radvd[27520]: poll error: Interrupted system call
Nov 30 14:12:45 daemon warn radvd[27520]: Exiting, sigterm received.
Nov 30 14:12:46 kern warn kernel: ETH Init: Ch:1 - 200 tx BDs at 0xb0820650
Nov 30 14:12:46 kern warn kernel: ETH Init: Ch:1 - 600 rx BDs at 0xb0a21910
Nov 30 14:12:46 kern warn kernel: [FAP1] enetRxChannel 1
Nov 30 14:12:46 kern crit kernel: eth4 (switch port: 5) Link UP 100 mbps full duplex
Nov 30 14:12:46 daemon info radvd[27529]: version 1.8 started
Nov 30 14:12:46 daemon warn radvd[27529]: received RA from fe80::6203:47ff:fe4b:xxxx
Nov 30 14:12:46 daemon warn radvd[27529]: poll error: Interrupted system call
Nov 30 14:12:46 daemon warn radvd[27529]: Exiting, sigterm received.
Nov 30 14:12:47 daemon info radvd[27538]: version 1.8 started
Nov 30 14:12:47 daemon warn radvd[27538]: received RA from fe80::6203:47ff:fe4b:xxxx
Nov 30 14:12:47 daemon warn radvd[27538]: poll error: Interrupted system call
Nov 30 14:12:47 daemon warn radvd[27538]: Exiting, sigterm received.
Nov 30 14:12:48 daemon info radvd[27545]: version 1.8 started
Nov 30 14:12:48 daemon warn radvd[27545]: received RA from fe80::6203:47ff:fe4b:xxxx
Nov 30 14:12:49 daemon err dhcp6c[24525]: client6_send: transmit failed: Network is unreachable
Nov 30 14:12:49 daemon warn radvd[27545]: poll error: Interrupted system call
Nov 30 14:12:49 daemon warn radvd[27545]: Exiting, sigterm received.
Nov 30 14:12:50 daemon info radvd[27576]: version 1.8 started
Nov 30 14:12:50 daemon warn radvd[27576]: received RA from fe80::6203:47ff:fe4b:xxxx
Nov 30 14:12:50 daemon info radvd[27576]: attempting to reread config file
Nov 30 14:12:50 kern warn kernel: eth4.2 MAC address set to 60:03:47:4B:7C:xx
Nov 30 14:12:50 kern warn kernel: netdev path : eth4.2 -> eth4
Nov 30 14:12:50 kern warn kernel: BCMVLAN : eth4 mode was set to RG
Nov 30 14:12:50 kern info kernel: device eth4 entered promiscuous mode
Nov 30 14:12:50 daemon info radvd[27576]: resuming normal operation
Nov 30 14:12:50 daemon warn radvd[27576]: received RA from fe80::6203:47ff:fe4b:xxxx
Nov 30 14:12:50 daemon info radvd[27576]: attempting to reread config file
Nov 30 14:12:50 daemon info radvd[27576]: resuming normal operation
Nov 30 14:12:50 daemon warn radvd[27576]: received RA from fe80::6203:47ff:fe4b:xxxx
Nov 30 14:12:50 daemon info radvd[27576]: attempting to reread config file
Nov 30 14:12:50 daemon info radvd[27576]: resuming normal operation
Nov 30 14:12:50 daemon warn radvd[27576]: received RA from fe80::6203:47ff:fe4b:xxxx
Nov 30 14:12:53 daemon info radvd[27576]: attempting to reread config file
Nov 30 14:12:53 kern warn kernel: eth4.1 MAC address set to 60:03:47:4B:7C:xx
Nov 30 14:12:53 kern warn kernel: netdev path : eth4.1 -> eth4
Nov 30 14:12:53 kern warn kernel: BCMVLAN : eth4 mode was set to RG
Nov 30 14:12:53 daemon info radvd[27576]: resuming normal operation
Nov 30 14:12:53 daemon warn radvd[27576]: received RA from fe80::6203:47ff:fe4b:xxxx
Nov 30 14:12:53 daemon info radvd[27576]: attempting to reread config file
Nov 30 14:12:53 daemon info radvd[27576]: resuming normal operation
Nov 30 14:12:53 daemon warn radvd[27576]: received RA from fe80::6203:47ff:fe4b:xxxx
Nov 30 14:12:53 daemon notice syslog: pppd:cms logging initialized.
Nov 30 14:12:53 daemon notice syslog: pppd 2.4.1 started by admin, uid 0
Nov 30 14:12:53 daemon notice syslog: PPP: Start to connect ...
Nov 30 14:12:53 daemon crit syslog: session disconnect pppoe.oldsession=20e09cd90aa1/0e4e, pppConnectCount=0
Nov 30 14:12:53 daemon notice syslog: send_disc - 469 - Sent packet - Code: 0xa7 ...
Nov 30 14:12:54 daemon notice syslog: std_init_disc - 115 - ...
Nov 30 14:12:54 daemon notice syslog: std_init_disc - 131 - pppoe: Sending PADI ...
Nov 30 14:12:54 daemon notice syslog: std_init_disc - 148 - going to send_disc...
Nov 30 14:12:54 daemon notice syslog: send_disc - 469 - Sent packet - Code: 0x9 ...
Nov 30 14:12:54 daemon notice syslog: session_connect - 664 - ================================...
Nov 30 14:12:54 daemon notice syslog: session_connect - 668 - retransmits = 0, retries = 8...
Nov 30 14:12:54 daemon notice syslog: Recv'd packet from: [ 20:e0:9c:d9:0a:xx ] ....
Nov 30 14:12:54 daemon notice syslog: session_connect - 731 - ses->retransmits = 1...
Nov 30 14:12:54 daemon notice syslog: session_connect - 795 - case PADT_CODE ...
Nov 30 14:12:54 daemon notice syslog: rcv_packet.hdr->sid = 3662, ses->sp.sa_addr.pppoe.sid = 0
Nov 30 14:12:54 daemon notice syslog: session_connect - 668 - retransmits = 0, retries = 8...
Nov 30 14:12:56 daemon notice syslog: session_connect - 714 - going to send_disc...
Nov 30 14:12:56 daemon notice syslog: send_disc - 469 - Sent packet - Code: 0x9 ...
Nov 30 14:12:56 daemon notice syslog: session_connect - 668 - retransmits = 1, retries = 8...
Nov 30 14:12:56 daemon notice syslog: Recv'd packet from: [ 20:e0:9c:d9:0a:xx ] ....
Nov 30 14:12:56 daemon notice syslog: session_connect - 731 - ses->retransmits = 2...
Nov 30 14:12:56 daemon notice syslog: session_connect - 752 - case PADO_CODE ...
Nov 30 14:12:56 daemon crit syslog: PPP server detected.
Nov 30 14:12:56 daemon notice syslog: send_disc - 469 - Sent packet - Code: 0x19 ...
Nov 30 14:12:56 daemon notice syslog: Recv'd packet from: [ 20:e0:9c:d9:0a:xx ] ....
Nov 30 14:12:56 daemon notice syslog: session_connect - 731 - ses->retransmits = 0...
Nov 30 14:12:56 daemon notice syslog: session_connect - 779 - case PADS_CODE ...
Nov 30 14:12:56 daemon crit syslog: PPP session established.
Nov 30 14:12:56 daemon info radvd[27576]: attempting to reread config file
Nov 30 14:12:56 daemon notice syslog: pppd:Using interface ppp2.1
Nov 30 14:12:56 kern warn kernel: netdev path : ppp2.1 -> eth4.1 -> eth4
Nov 30 14:12:56 daemon notice syslog: pppd:Connect: ppp2.1 <--> eth4.1
Nov 30 14:12:56 daemon info radvd[27576]: resuming normal operation
Nov 30 14:12:56 daemon warn radvd[27576]: received RA from fe80::6203:47ff:fe4b:xxxx
Nov 30 14:12:56 daemon crit syslog: PPP LCP UP.
Nov 30 14:12:56 daemon crit syslog: PPP LCP UP.
Nov 30 14:12:56 daemon info radvd[27576]: attempting to reread config file
Nov 30 14:12:56 daemon notice syslog: pppd:local  LL address fe80::49c8:8b68:42e3:2379
Nov 30 14:12:56 daemon notice syslog: pppd:remote LL address fe80::0227:e3ff:fe77:b100
Nov 30 14:12:56 daemon notice syslog: pppd:local  IP address 91.135.xxx.xxx
Nov 30 14:12:56 daemon info radvd[27576]: resuming normal operation
Nov 30 14:12:56 daemon warn radvd[27576]: received RA from fe80::6203:47ff:fe4b:xxxx
Nov 30 14:12:56 daemon notice syslog: pppd:remote IP address 212.69.xxx.xxx
Nov 30 14:12:56 daemon notice syslog: pppd:primary   DNS address 212.69.40.23
Nov 30 14:12:56 daemon notice syslog: pppd:secondary DNS address 212.69.36.23
Nov 30 14:12:56 daemon crit syslog: Received valid IP address from server.  Connection UP.
Nov 30 14:12:57 daemon info syslog: Hop limit                : 64
Nov 30 14:12:57 daemon info syslog: Stateful address conf.    : No
Nov 30 14:12:57 daemon info syslog: Stateful other conf.      : No
Nov 30 14:12:57 daemon info syslog: Router preference        : medium
Nov 30 14:12:57 daemon info syslog: Router lifetime           : 1800 seconds
Nov 30 14:12:57 daemon info syslog: Reachable time           : 0 milliseconds
Nov 30 14:12:57 daemon info syslog: Retransmit time          : 0 milliseconds
Nov 30 14:12:57 daemon info syslog: Prefix                   : 2a02:390:feed:5e05::/64
Nov 30 14:12:57 daemon info syslog: Valid time               : 2592000 seconds
Nov 30 14:12:57 daemon info syslog: Pref. time               : 604800 seconds
Nov 30 14:13:04 daemon info syslog: dhcp6c restart..
Nov 30 14:13:12 daemon warn radvd[27576]: received RA from fe80::6203:47ff:fe4b:xxxx
Nov 30 14:13:13 daemon warn radvd[27576]: poll error: Interrupted system call
Nov 30 14:13:13 daemon warn radvd[27576]: Exiting, sigterm received.
Nov 30 14:13:14 daemon info radvd[28227]: version 1.8 started
Nov 30 14:13:14 daemon warn radvd[28227]: received RA from fe80::6203:47ff:fe4b:xxxx
Nov 30 14:13:14 daemon warn radvd[28227]: poll error: Interrupted system call
Nov 30 14:13:15 daemon warn radvd[28227]: Exiting, sigterm received.
Nov 30 14:13:16 daemon info radvd[28278]: version 1.8 started
Nov 30 14:13:16 daemon warn radvd[28278]: received RA from fe80::6203:47ff:fe4b:xxxx
Nov 30 14:13:16 daemon warn radvd[28278]: poll error: Interrupted system call
Nov 30 14:13:16 daemon warn radvd[28278]: Exiting, sigterm received.
Nov 30 14:13:17 daemon info radvd[28293]: version 1.8 started
Nov 30 14:13:17 daemon warn radvd[28293]: received RA from fe80::6203:47ff:fe4b:xxxx
Nov 30 14:13:17 daemon warn radvd[28293]: poll error: Interrupted system call
Nov 30 14:13:17 daemon warn radvd[28293]: Exiting, sigterm received.
Nov 30 14:13:18 daemon info radvd[28318]: version 1.8 started
Nov 30 14:13:18 daemon warn radvd[28318]: received RA from fe80::6203:47ff:fe4b:xxxx
Nov 30 14:13:18 daemon warn radvd[28318]: poll error: Interrupted system call
Nov 30 14:13:18 daemon warn radvd[28318]: Exiting, sigterm received.
Nov 30 14:13:19 daemon info radvd[28337]: version 1.8 started
Nov 30 14:13:19 daemon warn radvd[28337]: received RA from fe80::6203:47ff:fe4b:xxxx
Nov 30 14:13:35 daemon warn radvd[28337]: received RA from fe80::6203:47ff:fe4b:xxxx
Nov 30 14:13:51 daemon warn radvd[28337]: received RA from fe80::6203:47ff:fe4b:xxxx
Nov 30 14:14:07 daemon warn radvd[28337]: received RA from fe80::6203:47ff:fe4b:xxxx 
 
thinkbroadband Speed Test - Mozilla Firefox 30_11_19 15_18_27.png
As ever, my thanks.
You do not have the required permissions to view the files attached to this post.
billion_fan
Posts: 5374
Joined: Tue Jul 19, 2011 4:30 pm

Re: 8900AX-1600R2 (2.52.d2) - WAN connection instability ?

Post by billion_fan »

Pete B wrote: Sat Nov 30, 2019 3:36 pm Hi again

You'll doubtless recall the recent thread about intermittent WAN connection failure, which we basically resolved through correcting IPv6 setup parameters. Does seem that I still have a 'less than stable' setup though. It's currently much better than before, I don't for example get warning email anymore informing of 'new' WAN IP addresses.

However what does happen is that the WAN connection time still periodically resets. No set pattern to this, it might go a couple of days, might be twice in one day. What I do see though is a change from green to amber (or vice versa) of the EWAN/P5 indicator. My understanding of these indications has always been that green is > 100 Mbit/s (e.g. for gigabit LAN speeds) and amber is < 100 Mbit/s (actually perhaps what I might expect to see as this port is being served by the ethernet output of my modem ? ... not sure about this... perhaps the modem / EWAN port link should be regarded as gigabit ? Needless to say my indicator light colour varies all the time :?

I access the modem using an IPoE connection through the router but I don't think this would cause any issue.

I recently 'caught' a change from green to amber indication and have extracted the relevant syslog portion for that time. I would genuinely appreciate any advice as to what might cause this 'instability'. It's no big deal and it isn't disruptive but it's not correct and would be good to fix. Furthermore, from using ThinkBroadband's check it typically reports very low 'quality' scores e.g. band F. Maybe there is an issue here too. I have attached the syslog extract and a screenshot of the ThinkBroadband check.

Code: Select all

 
Nov 30 14:12:36 kern crit kernel: eth4 (switch port: 5) Link UP 1000 mbps full duplex
Nov 30 14:12:36 daemon info radvd[24686]: resuming normal operation
Nov 30 14:12:36 daemon warn radvd[24686]: received RA from fe80::6203:47ff:fe4b:xxxx
Nov 30 14:12:36 daemon info radvd[24686]: attempting to reread config file
Nov 30 14:12:36 daemon info radvd[24686]: resuming normal operation
Nov 30 14:12:36 daemon warn radvd[24686]: received RA from fe80::6203:47ff:fe4b:xxxx
Nov 30 14:12:37 daemon notice syslog: pppd:Terminating on signal 20.
Nov 30 14:12:38 kern crit kernel: eth4 (switch port: 5)  Link DOWN.
Nov 30 14:12:38 daemon info radvd[24686]: attempting to reread config file
Nov 30 14:12:38 daemon info radvd[24686]: resuming normal operation
Nov 30 14:12:38 daemon warn radvd[24686]: received RA from fe80::6203:47ff:fe4b:xxxx
Nov 30 14:12:38 daemon info radvd[24686]: attempting to reread config file
Nov 30 14:12:38 daemon info radvd[24686]: resuming normal operation
Nov 30 14:12:38 daemon warn radvd[24686]: received RA from fe80::6203:47ff:fe4b:xxxx
Nov 30 14:12:40 kern crit kernel: eth4 (switch port: 5) Link UP 1000 mbps full duplex
Nov 30 14:12:40 daemon info radvd[24686]: attempting to reread config file
Nov 30 14:12:40 daemon info radvd[24686]: resuming normal operation
Nov 30 14:12:40 daemon warn radvd[24686]: received RA from fe80::6203:47ff:fe4b:xxxx
Nov 30 14:12:40 daemon info radvd[24686]: attempting to reread config file
Nov 30 14:12:40 daemon info radvd[24686]: resuming normal operation
Nov 30 14:12:40 daemon warn radvd[24686]: received RA from fe80::6203:47ff:fe4b:xxxx
Nov 30 14:12:41 kern crit kernel: eth4 (switch port: 5)  Link DOWN.
Nov 30 14:12:41 daemon info radvd[24686]: attempting to reread config file
Nov 30 14:12:41 daemon info radvd[24686]: resuming normal operation
Nov 30 14:12:41 daemon warn radvd[24686]: received RA from fe80::6203:47ff:fe4b:xxxx
Nov 30 14:12:41 daemon info radvd[24686]: attempting to reread config file
Nov 30 14:12:41 daemon info radvd[24686]: resuming normal operation
Nov 30 14:12:41 daemon warn radvd[24686]: received RA from fe80::6203:47ff:fe4b:xxxx
Nov 30 14:12:42 daemon notice syslog: pppd:Connection terminated.
Nov 30 14:12:42 daemon notice syslog: pppd:Connect time 7.8 minutes.
Nov 30 14:12:42 daemon notice syslog: pppd:Sent 101788 bytes, received 219510 bytes.
Nov 30 14:12:42 daemon info radvd[24686]: attempting to reread config file
Nov 30 14:12:42 daemon info radvd[24686]: resuming normal operation
Nov 30 14:12:42 daemon warn radvd[24686]: received RA from fe80::6203:47ff:fe4b:xxxx
Nov 30 14:12:42 daemon notice syslog: pppd:Doing disconnect
Nov 30 14:12:42 daemon notice syslog: send_disc - 469 - Sent packet - Code: 0xa7 ...
Nov 30 14:12:42 daemon info radvd[24686]: attempting to reread config file
Nov 30 14:12:42 kern info kernel: device eth4 left promiscuous mode
Nov 30 14:12:42 daemon info radvd[24686]: resuming normal operation
Nov 30 14:12:42 daemon warn radvd[24686]: received RA from fe80::6203:47ff:fe4b:xxxx
Nov 30 14:12:42 daemon info radvd[24686]: attempting to reread config file
Nov 30 14:12:42 daemon info radvd[24686]: resuming normal operation
Nov 30 14:12:42 daemon warn radvd[24686]: received RA from fe80::6203:47ff:fe4b:xxxx
Nov 30 14:12:44 kern crit kernel: eth4 (switch port: 5) Link UP 1000 mbps full duplex
Nov 30 14:12:44 daemon info radvd[24686]: attempting to reread config file
Nov 30 14:12:44 daemon info radvd[24686]: resuming normal operation
Nov 30 14:12:44 daemon warn radvd[24686]: received RA from fe80::6203:47ff:fe4b:xxxx
Nov 30 14:12:44 daemon warn radvd[24686]: poll error: Interrupted system call
Nov 30 14:12:44 daemon warn radvd[24686]: Exiting, sigterm received.
Nov 30 14:12:45 kern crit kernel: eth4 (switch port: 5)  Link DOWN.
Nov 30 14:12:45 daemon info radvd[27520]: version 1.8 started
Nov 30 14:12:45 daemon warn radvd[27520]: received RA from fe80::6203:47ff:fe4b:xxxx
Nov 30 14:12:45 daemon warn radvd[27520]: poll error: Interrupted system call
Nov 30 14:12:45 daemon warn radvd[27520]: Exiting, sigterm received.
Nov 30 14:12:46 kern warn kernel: ETH Init: Ch:1 - 200 tx BDs at 0xb0820650
Nov 30 14:12:46 kern warn kernel: ETH Init: Ch:1 - 600 rx BDs at 0xb0a21910
Nov 30 14:12:46 kern warn kernel: [FAP1] enetRxChannel 1
Nov 30 14:12:46 kern crit kernel: eth4 (switch port: 5) Link UP 100 mbps full duplex
Nov 30 14:12:46 daemon info radvd[27529]: version 1.8 started
Nov 30 14:12:46 daemon warn radvd[27529]: received RA from fe80::6203:47ff:fe4b:xxxx
Nov 30 14:12:46 daemon warn radvd[27529]: poll error: Interrupted system call
Nov 30 14:12:46 daemon warn radvd[27529]: Exiting, sigterm received.
Nov 30 14:12:47 daemon info radvd[27538]: version 1.8 started
Nov 30 14:12:47 daemon warn radvd[27538]: received RA from fe80::6203:47ff:fe4b:xxxx
Nov 30 14:12:47 daemon warn radvd[27538]: poll error: Interrupted system call
Nov 30 14:12:47 daemon warn radvd[27538]: Exiting, sigterm received.
Nov 30 14:12:48 daemon info radvd[27545]: version 1.8 started
Nov 30 14:12:48 daemon warn radvd[27545]: received RA from fe80::6203:47ff:fe4b:xxxx
Nov 30 14:12:49 daemon err dhcp6c[24525]: client6_send: transmit failed: Network is unreachable
Nov 30 14:12:49 daemon warn radvd[27545]: poll error: Interrupted system call
Nov 30 14:12:49 daemon warn radvd[27545]: Exiting, sigterm received.
Nov 30 14:12:50 daemon info radvd[27576]: version 1.8 started
Nov 30 14:12:50 daemon warn radvd[27576]: received RA from fe80::6203:47ff:fe4b:xxxx
Nov 30 14:12:50 daemon info radvd[27576]: attempting to reread config file
Nov 30 14:12:50 kern warn kernel: eth4.2 MAC address set to 60:03:47:4B:7C:xx
Nov 30 14:12:50 kern warn kernel: netdev path : eth4.2 -> eth4
Nov 30 14:12:50 kern warn kernel: BCMVLAN : eth4 mode was set to RG
Nov 30 14:12:50 kern info kernel: device eth4 entered promiscuous mode
Nov 30 14:12:50 daemon info radvd[27576]: resuming normal operation
Nov 30 14:12:50 daemon warn radvd[27576]: received RA from fe80::6203:47ff:fe4b:xxxx
Nov 30 14:12:50 daemon info radvd[27576]: attempting to reread config file
Nov 30 14:12:50 daemon info radvd[27576]: resuming normal operation
Nov 30 14:12:50 daemon warn radvd[27576]: received RA from fe80::6203:47ff:fe4b:xxxx
Nov 30 14:12:50 daemon info radvd[27576]: attempting to reread config file
Nov 30 14:12:50 daemon info radvd[27576]: resuming normal operation
Nov 30 14:12:50 daemon warn radvd[27576]: received RA from fe80::6203:47ff:fe4b:xxxx
Nov 30 14:12:53 daemon info radvd[27576]: attempting to reread config file
Nov 30 14:12:53 kern warn kernel: eth4.1 MAC address set to 60:03:47:4B:7C:xx
Nov 30 14:12:53 kern warn kernel: netdev path : eth4.1 -> eth4
Nov 30 14:12:53 kern warn kernel: BCMVLAN : eth4 mode was set to RG
Nov 30 14:12:53 daemon info radvd[27576]: resuming normal operation
Nov 30 14:12:53 daemon warn radvd[27576]: received RA from fe80::6203:47ff:fe4b:xxxx
Nov 30 14:12:53 daemon info radvd[27576]: attempting to reread config file
Nov 30 14:12:53 daemon info radvd[27576]: resuming normal operation
Nov 30 14:12:53 daemon warn radvd[27576]: received RA from fe80::6203:47ff:fe4b:xxxx
Nov 30 14:12:53 daemon notice syslog: pppd:cms logging initialized.
Nov 30 14:12:53 daemon notice syslog: pppd 2.4.1 started by admin, uid 0
Nov 30 14:12:53 daemon notice syslog: PPP: Start to connect ...
Nov 30 14:12:53 daemon crit syslog: session disconnect pppoe.oldsession=20e09cd90aa1/0e4e, pppConnectCount=0
Nov 30 14:12:53 daemon notice syslog: send_disc - 469 - Sent packet - Code: 0xa7 ...
Nov 30 14:12:54 daemon notice syslog: std_init_disc - 115 - ...
Nov 30 14:12:54 daemon notice syslog: std_init_disc - 131 - pppoe: Sending PADI ...
Nov 30 14:12:54 daemon notice syslog: std_init_disc - 148 - going to send_disc...
Nov 30 14:12:54 daemon notice syslog: send_disc - 469 - Sent packet - Code: 0x9 ...
Nov 30 14:12:54 daemon notice syslog: session_connect - 664 - ================================...
Nov 30 14:12:54 daemon notice syslog: session_connect - 668 - retransmits = 0, retries = 8...
Nov 30 14:12:54 daemon notice syslog: Recv'd packet from: [ 20:e0:9c:d9:0a:xx ] ....
Nov 30 14:12:54 daemon notice syslog: session_connect - 731 - ses->retransmits = 1...
Nov 30 14:12:54 daemon notice syslog: session_connect - 795 - case PADT_CODE ...
Nov 30 14:12:54 daemon notice syslog: rcv_packet.hdr->sid = 3662, ses->sp.sa_addr.pppoe.sid = 0
Nov 30 14:12:54 daemon notice syslog: session_connect - 668 - retransmits = 0, retries = 8...
Nov 30 14:12:56 daemon notice syslog: session_connect - 714 - going to send_disc...
Nov 30 14:12:56 daemon notice syslog: send_disc - 469 - Sent packet - Code: 0x9 ...
Nov 30 14:12:56 daemon notice syslog: session_connect - 668 - retransmits = 1, retries = 8...
Nov 30 14:12:56 daemon notice syslog: Recv'd packet from: [ 20:e0:9c:d9:0a:xx ] ....
Nov 30 14:12:56 daemon notice syslog: session_connect - 731 - ses->retransmits = 2...
Nov 30 14:12:56 daemon notice syslog: session_connect - 752 - case PADO_CODE ...
Nov 30 14:12:56 daemon crit syslog: PPP server detected.
Nov 30 14:12:56 daemon notice syslog: send_disc - 469 - Sent packet - Code: 0x19 ...
Nov 30 14:12:56 daemon notice syslog: Recv'd packet from: [ 20:e0:9c:d9:0a:xx ] ....
Nov 30 14:12:56 daemon notice syslog: session_connect - 731 - ses->retransmits = 0...
Nov 30 14:12:56 daemon notice syslog: session_connect - 779 - case PADS_CODE ...
Nov 30 14:12:56 daemon crit syslog: PPP session established.
Nov 30 14:12:56 daemon info radvd[27576]: attempting to reread config file
Nov 30 14:12:56 daemon notice syslog: pppd:Using interface ppp2.1
Nov 30 14:12:56 kern warn kernel: netdev path : ppp2.1 -> eth4.1 -> eth4
Nov 30 14:12:56 daemon notice syslog: pppd:Connect: ppp2.1 <--> eth4.1
Nov 30 14:12:56 daemon info radvd[27576]: resuming normal operation
Nov 30 14:12:56 daemon warn radvd[27576]: received RA from fe80::6203:47ff:fe4b:xxxx
Nov 30 14:12:56 daemon crit syslog: PPP LCP UP.
Nov 30 14:12:56 daemon crit syslog: PPP LCP UP.
Nov 30 14:12:56 daemon info radvd[27576]: attempting to reread config file
Nov 30 14:12:56 daemon notice syslog: pppd:local  LL address fe80::49c8:8b68:42e3:2379
Nov 30 14:12:56 daemon notice syslog: pppd:remote LL address fe80::0227:e3ff:fe77:b100
Nov 30 14:12:56 daemon notice syslog: pppd:local  IP address 91.135.xxx.xxx
Nov 30 14:12:56 daemon info radvd[27576]: resuming normal operation
Nov 30 14:12:56 daemon warn radvd[27576]: received RA from fe80::6203:47ff:fe4b:xxxx
Nov 30 14:12:56 daemon notice syslog: pppd:remote IP address 212.69.xxx.xxx
Nov 30 14:12:56 daemon notice syslog: pppd:primary   DNS address 212.69.40.23
Nov 30 14:12:56 daemon notice syslog: pppd:secondary DNS address 212.69.36.23
Nov 30 14:12:56 daemon crit syslog: Received valid IP address from server.  Connection UP.
Nov 30 14:12:57 daemon info syslog: Hop limit                : 64
Nov 30 14:12:57 daemon info syslog: Stateful address conf.    : No
Nov 30 14:12:57 daemon info syslog: Stateful other conf.      : No
Nov 30 14:12:57 daemon info syslog: Router preference        : medium
Nov 30 14:12:57 daemon info syslog: Router lifetime           : 1800 seconds
Nov 30 14:12:57 daemon info syslog: Reachable time           : 0 milliseconds
Nov 30 14:12:57 daemon info syslog: Retransmit time          : 0 milliseconds
Nov 30 14:12:57 daemon info syslog: Prefix                   : 2a02:390:feed:5e05::/64
Nov 30 14:12:57 daemon info syslog: Valid time               : 2592000 seconds
Nov 30 14:12:57 daemon info syslog: Pref. time               : 604800 seconds
Nov 30 14:13:04 daemon info syslog: dhcp6c restart..
Nov 30 14:13:12 daemon warn radvd[27576]: received RA from fe80::6203:47ff:fe4b:xxxx
Nov 30 14:13:13 daemon warn radvd[27576]: poll error: Interrupted system call
Nov 30 14:13:13 daemon warn radvd[27576]: Exiting, sigterm received.
Nov 30 14:13:14 daemon info radvd[28227]: version 1.8 started
Nov 30 14:13:14 daemon warn radvd[28227]: received RA from fe80::6203:47ff:fe4b:xxxx
Nov 30 14:13:14 daemon warn radvd[28227]: poll error: Interrupted system call
Nov 30 14:13:15 daemon warn radvd[28227]: Exiting, sigterm received.
Nov 30 14:13:16 daemon info radvd[28278]: version 1.8 started
Nov 30 14:13:16 daemon warn radvd[28278]: received RA from fe80::6203:47ff:fe4b:xxxx
Nov 30 14:13:16 daemon warn radvd[28278]: poll error: Interrupted system call
Nov 30 14:13:16 daemon warn radvd[28278]: Exiting, sigterm received.
Nov 30 14:13:17 daemon info radvd[28293]: version 1.8 started
Nov 30 14:13:17 daemon warn radvd[28293]: received RA from fe80::6203:47ff:fe4b:xxxx
Nov 30 14:13:17 daemon warn radvd[28293]: poll error: Interrupted system call
Nov 30 14:13:17 daemon warn radvd[28293]: Exiting, sigterm received.
Nov 30 14:13:18 daemon info radvd[28318]: version 1.8 started
Nov 30 14:13:18 daemon warn radvd[28318]: received RA from fe80::6203:47ff:fe4b:xxxx
Nov 30 14:13:18 daemon warn radvd[28318]: poll error: Interrupted system call
Nov 30 14:13:18 daemon warn radvd[28318]: Exiting, sigterm received.
Nov 30 14:13:19 daemon info radvd[28337]: version 1.8 started
Nov 30 14:13:19 daemon warn radvd[28337]: received RA from fe80::6203:47ff:fe4b:xxxx
Nov 30 14:13:35 daemon warn radvd[28337]: received RA from fe80::6203:47ff:fe4b:xxxx
Nov 30 14:13:51 daemon warn radvd[28337]: received RA from fe80::6203:47ff:fe4b:xxxx
Nov 30 14:14:07 daemon warn radvd[28337]: received RA from fe80::6203:47ff:fe4b:xxxx 
 
thinkbroadband Speed Test - Mozilla Firefox 30_11_19 15_18_27.png

As ever, my thanks.
From the logs it seems the Ethernet connection between the Draytek and Billion is dropping.

I would advise first trying to replace the Ethernet connection between the two and see if it improves.
Pete B
Posts: 26
Joined: Tue Sep 15, 2015 3:15 pm

Re: 8900AX-1600R2 (2.52.d2) - WAN connection instability ?

Post by Pete B »

Hmmm... well. the bottom line here is never trust a cable, always try a replacement !

I took Billion Fan's advice and checked the cable between modem and router. I have a network cable tester and the cable checked absolutely fine, or so it seemed, but problem not resolved. On checking again I noticed that the first pair of conductors were taking an absolutely miniscule time longer to pass the test than the other 3 pairs. You'd never see it without looking hard and certainly not with just a simple continuity test. It was enough to 'throw' the router and all is now correct.

It's now of course apparent that I had compound problems with dropping the WAN connection because this is on top of what was going on in this recent thread I started, for anyone who has been looking at this stuff...

intermittent WAN connection failure

...so brand new cable, now running for many days with no drops, that's it, all fixed 8-) and as ever, genuine thanks for all the help.
billion_fan
Posts: 5374
Joined: Tue Jul 19, 2011 4:30 pm

Re: 8900AX-1600R2 (2.52.d2) - WAN connection instability ?

Post by billion_fan »

Pete B wrote: Mon Dec 09, 2019 12:37 pm Hmmm... well. the bottom line here is never trust a cable, always try a replacement !

I took Billion Fan's advice and checked the cable between modem and router. I have a network cable tester and the cable checked absolutely fine, or so it seemed, but problem not resolved. On checking again I noticed that the first pair of conductors were taking an absolutely miniscule time longer to pass the test than the other 3 pairs. You'd never see it without looking hard and certainly not with just a simple continuity test. It was enough to 'throw' the router and all is now correct.

It's now of course apparent that I had compound problems with dropping the WAN connection because this is on top of what was going on in this recent thread I started, for anyone who has been looking at this stuff...

intermittent WAN connection failure

...so brand new cable, now running for many days with no drops, that's it, all fixed 8-) and as ever, genuine thanks for all the help.
That's great news Pete, and thanks for the update :D
Post Reply