8900AX-1600R2 - intermittent WAN connection failure

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

Re: 8900AX-1600R2 - intermittent WAN connection failure

Post by Pete B » Thu Nov 07, 2019 7:17 pm

Back earlier than expected :)

Another WAN disconnect happened very recently. There was no entry in the DrayTek's logs corresponding to this event, in fact no entries at all. I am posting this latest event syslog in case it yields any further insight into this problem. As ever, thanks again...

Code: Select all

Nov  7 19:00:04 daemon warn radvd[3724]: received RS from fe80::ad1c:c49f:c1c5:ebc0
Nov  7 19:00:04 daemon warn radvd[3724]: received RA from fe80::6203:47ff:fe4b:xxxx
Nov  7 19:03:15 kern crit kernel: eth4 (Int switch port: 3) (Logical Port: 3) Link DOWN.
Nov  7 19:03:15 daemon info radvd[3724]: attempting to reread config file
Nov  7 19:03:15 daemon info radvd[3724]: resuming normal operation
Nov  7 19:03:15 daemon warn radvd[3724]: received RA from fe80::6203:47ff:fe4b:xxxx
Nov  7 19:03:15 daemon info radvd[3724]: attempting to reread config file
Nov  7 19:03:15 daemon info radvd[3724]: resuming normal operation
Nov  7 19:03:15 daemon warn radvd[3724]: received RA from fe80::6203:47ff:fe4b:xxxx
Nov  7 19:03:15 daemon info radvd[3724]: attempting to reread config file
Nov  7 19:03:15 daemon info radvd[3724]: resuming normal operation
Nov  7 19:03:15 daemon warn radvd[3724]: received RA from fe80::6203:47ff:fe4b:xxxx
Nov  7 19:03:16 daemon notice syslog: pppd:Terminating connection due to link down.
Nov  7 19:03:16 daemon crit syslog: Clear IP addresses.  PPP connection DOWN.
Nov  7 19:03:16 daemon crit syslog: Clear IP addresses.  Connection DOWN.
Nov  7 19:03:16 daemon info radvd[3724]: attempting to reread config file
Nov  7 19:03:16 daemon info radvd[3724]: resuming normal operation
Nov  7 19:03:16 daemon warn radvd[3724]: received RA from fe80::6203:47ff:fe4b:xxxx
Nov  7 19:03:16 daemon notice syslog: pppd:Couldn't increase MTU to 1500.
Nov  7 19:03:16 daemon err syslog: pppd:Couldn't increase MRU to 1500
Nov  7 19:03:16 daemon info radvd[3724]: attempting to reread config file
Nov  7 19:03:16 daemon info radvd[3724]: resuming normal operation
Nov  7 19:03:16 daemon warn radvd[3724]: received RA from fe80::6203:47ff:fe4b:xxxx
Nov  7 19:03:16 daemon info radvd[3724]: attempting to reread config file
Nov  7 19:03:16 daemon info radvd[3724]: resuming normal operation
Nov  7 19:03:16 daemon warn radvd[3724]: received RA from fe80::6203:47ff:fe4b:xxxx
Nov  7 19:03:19 daemon notice syslog: pppd:Terminating on signal 20.
Nov  7 19:03:24 daemon info radvd[3724]: attempting to reread config file
Nov  7 19:03:24 daemon info radvd[3724]: resuming normal operation
Nov  7 19:03:24 daemon warn radvd[3724]: received RA from fe80::6203:47ff:fe4b:xxxx
Nov  7 19:03:24 daemon info radvd[3724]: attempting to reread config file
Nov  7 19:03:24 kern info kernel: device eth4 left promiscuous mode
Nov  7 19:03:24 daemon info radvd[3724]: resuming normal operation
Nov  7 19:03:24 daemon warn radvd[3724]: received RA from fe80::6203:47ff:fe4b:xxxx
Nov  7 19:03:24 daemon info radvd[3724]: attempting to reread config file
Nov  7 19:03:24 daemon info radvd[3724]: resuming normal operation
Nov  7 19:03:24 daemon warn radvd[3724]: received RA from fe80::6203:47ff:fe4b:xxxx
Nov  7 19:03:25 kern crit kernel: eth4 (Int switch port: 3) (Logical Port: 3) Link UP 1000 mbps full duplex
Nov  7 19:03:25 daemon info radvd[3724]: attempting to reread config file
Nov  7 19:03:25 daemon info radvd[3724]: resuming normal operation
Nov  7 19:03:25 daemon warn radvd[3724]: received RA from fe80::6203:47ff:fe4b:xxxx
Nov  7 19:03:25 daemon warn radvd[3724]: poll error: Interrupted system call
Nov  7 19:03:25 daemon warn radvd[3724]: Exiting, sigterm received.
Nov  7 19:03:25 daemon info radvd[11966]: version 1.8 started
Nov  7 19:03:25 daemon warn radvd[11966]: received RA from fe80::6203:47ff:fe4b:xxxx
Nov  7 19:03:25 daemon warn radvd[11966]: poll error: Interrupted system call
Nov  7 19:03:25 daemon warn radvd[11966]: Exiting, sigterm received.
Nov  7 19:03:26 daemon info radvd[11969]: version 1.8 started
Nov  7 19:03:26 daemon warn radvd[11969]: received RA from fe80::6203:47ff:fe4b:xxxx
Nov  7 19:03:26 daemon warn radvd[11969]: poll error: Interrupted system call
Nov  7 19:03:26 daemon warn radvd[11969]: Exiting, sigterm received.
Nov  7 19:03:26 daemon info radvd[11972]: version 1.8 started
Nov  7 19:03:26 daemon warn radvd[11972]: received RA from fe80::6203:47ff:fe4b:xxxx
Nov  7 19:03:26 daemon warn radvd[11972]: poll error: Interrupted system call
Nov  7 19:03:26 daemon warn radvd[11972]: Exiting, sigterm received.
Nov  7 19:03:26 daemon info radvd[11973]: version 1.8 started
Nov  7 19:03:26 daemon warn radvd[11973]: received RA from fe80::6203:47ff:fe4b:xxxx
Nov  7 19:03:26 daemon err dhcp6c[3565]: client6_send: transmit failed: Network is unreachable
Nov  7 19:03:26 daemon warn radvd[11973]: poll error: Interrupted system call
Nov  7 19:03:26 daemon warn radvd[11973]: Exiting, sigterm received.
Nov  7 19:03:26 daemon info radvd[11998]: version 1.8 started
Nov  7 19:03:26 daemon warn radvd[11998]: received RA from fe80::6203:47ff:fe4b:xxxx
Nov  7 19:03:27 daemon info radvd[11998]: attempting to reread config file
Nov  7 19:03:27 kern warn kernel: eth4.2 MAC address set to 60:03:47:4B:7C:28
Nov  7 19:03:27 kern warn kernel: netdev path : eth4.2 -> eth4
Nov  7 19:03:27 kern warn kernel: BCMVLAN : eth4 mode was set to RG
Nov  7 19:03:27 kern info kernel: device eth4 entered promiscuous mode
Nov  7 19:03:27 daemon info radvd[11998]: resuming normal operation
Nov  7 19:03:27 daemon warn radvd[11998]: received RA from fe80::6203:47ff:fe4b:xxxx
Nov  7 19:03:27 daemon info radvd[11998]: attempting to reread config file
Nov  7 19:03:27 daemon info radvd[11998]: resuming normal operation
Nov  7 19:03:27 daemon warn radvd[11998]: received RA from fe80::6203:47ff:fe4b:xxxx
Nov  7 19:03:27 daemon info radvd[11998]: attempting to reread config file
Nov  7 19:03:27 daemon info radvd[11998]: resuming normal operation
Nov  7 19:03:27 daemon warn radvd[11998]: received RA from fe80::6203:47ff:fe4b:xxxx
Nov  7 19:03:27 daemon info radvd[11998]: attempting to reread config file
Nov  7 19:03:27 daemon info radvd[11998]: resuming normal operation
Nov  7 19:03:27 daemon warn radvd[11998]: received RA from fe80::6203:47ff:fe4b:xxxx
Nov  7 19:03:30 daemon info email[12277]: eMail: SMTP_SERVER:smtp.fastmail.com SMTP_USER:xxxxxxxx@fastmail.net SMTP_PASS:xxxxx SENDER_MAIL:xxxxxxxx@fastmail.net PORT:587 USE_TLS:true
Nov  7 19:03:30 daemon info email[12277]: eMail: Subject:WAN IP Change (new IP:192.168.2.0)
Nov  7 19:03:31 daemon info radvd[11998]: attempting to reread config file
Nov  7 19:03:31 kern warn kernel: eth4.1 MAC address set to 60:03:47:4B:7C:29
Nov  7 19:03:31 kern warn kernel: netdev path : eth4.1 -> eth4
Nov  7 19:03:31 kern warn kernel: BCMVLAN : eth4 mode was set to RG
Nov  7 19:03:31 daemon notice syslog: pppd:cms logging initialized.
Nov  7 19:03:31 daemon info radvd[11998]: resuming normal operation
Nov  7 19:03:31 daemon warn radvd[11998]: received RA from fe80::6203:47ff:fe4b:xxxx
Nov  7 19:03:31 daemon info radvd[11998]: attempting to reread config file
Nov  7 19:03:31 daemon notice syslog: pppd 2.4.1 started by admin, uid 0
Nov  7 19:03:31 daemon notice syslog: PPP: Start to connect ...
Nov  7 19:03:31 daemon crit syslog: session disconnect pppoe.oldsession=20e09cd90aa1/1259
Nov  7 19:03:31 daemon info radvd[11998]: resuming normal operation
Nov  7 19:03:31 daemon warn radvd[11998]: received RA from fe80::6203:47ff:fe4b:xxxx
Nov  7 19:03:31 daemon info radvd[11998]: attempting to reread config file
Nov  7 19:03:31 daemon info radvd[11998]: resuming normal operation
Nov  7 19:03:31 daemon warn radvd[11998]: received RA from fe80::6203:47ff:fe4b:xxxx
Nov  7 19:03:35 daemon crit syslog: PPP server detected.
Nov  7 19:03:35 daemon crit syslog: PPP session established.
Nov  7 19:03:35 daemon info radvd[11998]: attempting to reread config file
Nov  7 19:03:35 kern warn kernel: netdev path : ppp2.1
Nov  7 19:03:35 kern info kernel:  -> eth4.1 -> 
Nov  7 19:03:35 daemon notice syslog: pppd:Using interface ppp2.1
Nov  7 19:03:35 kern info kernel: eth4
Nov  7 19:03:35 daemon notice syslog: pppd:Connect: ppp2.1 <--> eth4.1
Nov  7 19:03:35 daemon notice syslog: pppd:Couldn't increase MTU to 1500.
Nov  7 19:03:35 daemon err syslog: pppd:Couldn't increase MRU to 1500
Nov  7 19:03:35 daemon info radvd[11998]: resuming normal operation
Nov  7 19:03:35 daemon warn radvd[11998]: received RA from fe80::6203:47ff:fe4b:xxxx
Nov  7 19:03:35 daemon err syslog: pppd:Couldn't increase MRU to 1500
Nov  7 19:03:35 daemon crit syslog: PPP LCP UP.
Nov  7 19:03:36 daemon notice syslog: pppd:Couldn't increase MTU to 1500.
Nov  7 19:03:36 daemon err syslog: pppd:Couldn't increase MRU to 1500
Nov  7 19:03:36 daemon err syslog: pppd:Couldn't increase MRU to 1500
Nov  7 19:03:36 daemon crit syslog: PPP LCP UP.
Nov  7 19:03:36 daemon info radvd[11998]: attempting to reread config file
Nov  7 19:03:36 daemon notice syslog: pppd:local  LL address fe80::684a:540a:25bf:1c73
Nov  7 19:03:36 daemon notice syslog: pppd:remote LL address fe80::02a3:8eff:feca:ae80
Nov  7 19:03:36 daemon notice syslog: pppd:local  IP address 91.135.xxx.xxx
Nov  7 19:03:36 daemon info radvd[11998]: resuming normal operation
Nov  7 19:03:36 daemon warn radvd[11998]: received RA from fe80::6203:47ff:fe4b:xxxx
Nov  7 19:03:36 daemon notice syslog: pppd:remote IP address 212.69.xxx.xxx
Nov  7 19:03:36 daemon notice syslog: pppd:primary   DNS address 212.69.40.23
Nov  7 19:03:36 daemon notice syslog: pppd:secondary DNS address 212.69.36.23
Nov  7 19:03:36 daemon crit syslog: Received valid IP address from server.  Connection UP.
Nov  7 19:03:37 daemon info syslog: Hop limit                : 64
Nov  7 19:03:37 daemon info syslog: Stateful address conf.    : No
Nov  7 19:03:37 daemon info syslog: Stateful other conf.      : No
Nov  7 19:03:37 daemon info syslog: Router preference        : medium
Nov  7 19:03:37 daemon info syslog: Router lifetime           : 1800 seconds
Nov  7 19:03:37 daemon info syslog: Reachable time           : 0 milliseconds
Nov  7 19:03:37 daemon info syslog: Retransmit time          : 0 milliseconds
Nov  7 19:03:37 daemon info syslog: Prefix                   : 2a02:390:xxxx:5e05::/64
Nov  7 19:03:37 daemon info syslog: Valid time               : 2592000 seconds
Nov  7 19:03:37 daemon info syslog: Pref. time               : 604800 seconds
Nov  7 19:03:40 daemon info email[12714]: eMail: SMTP_SERVER:smtp.fastmail.com SMTP_USER:xxxxxxxx@fastmail.net SMTP_PASS:xxxxx SENDER_MAIL:xxxxxxxx@fastmail.net PORT:587 USE_TLS:true
Nov  7 19:03:40 daemon info email[12714]: eMail: Subject:WAN IP Change (new IP:91.135.xxx.xxx)
Nov  7 19:03:44 daemon info syslog: dhcp6c restart..

Pete B
Posts: 24
Joined: Tue Sep 15, 2015 3:15 pm

Re: 8900AX-1600R2 - intermittent WAN connection failure

Post by Pete B » Thu Nov 07, 2019 8:07 pm

:oops: and again. This happened over 3 min period around 19:45. Perhaps it holds clues, there's certainly some different info within. I checked the DrayTek's logs and again, no entries and VDSL2 connection was solid throughout. Between 19:56 and 20:10 this resulted in 5 email warnings from the router informing of WAN disconnect / IP change. This is weird :!:

Code: Select all

Nov  7 19:44:55 daemon info radvd[13042]: resuming normal operation
Nov  7 19:44:55 daemon warn radvd[13042]: received RA from fe80::6203:47ff:fe4b:xxxx
Nov  7 19:44:55 daemon info radvd[13042]: attempting to reread config file
Nov  7 19:44:55 daemon info radvd[13042]: resuming normal operation
Nov  7 19:44:55 daemon warn radvd[13042]: received RA from fe80::6203:47ff:fe4b:xxxx
Nov  7 19:44:55 daemon info radvd[13042]: attempting to reread config file
Nov  7 19:44:55 daemon info radvd[13042]: resuming normal operation
Nov  7 19:44:55 daemon warn radvd[13042]: received RA from fe80::6203:47ff:fe4b:xxxx
Nov  7 19:44:56 daemon notice syslog: pppd:Terminating connection due to link down.
Nov  7 19:44:56 daemon info radvd[13042]: attempting to reread config file
Nov  7 19:44:56 daemon info radvd[13042]: resuming normal operation
Nov  7 19:44:56 daemon warn radvd[13042]: received RA from fe80::6203:47ff:fe4b:xxxx
Nov  7 19:44:56 daemon info radvd[13042]: attempting to reread config file
Nov  7 19:44:56 daemon info radvd[13042]: resuming normal operation
Nov  7 19:44:56 daemon warn radvd[13042]: received RA from fe80::6203:47ff:fe4b:xxxx
Nov  7 19:44:56 daemon crit syslog: Clear IP addresses.  PPP connection DOWN.
Nov  7 19:44:56 daemon crit syslog: Clear IP addresses.  Connection DOWN.
Nov  7 19:44:56 daemon info radvd[13042]: attempting to reread config file
Nov  7 19:44:56 daemon notice syslog: pppd:Couldn't increase MTU to 1500.
Nov  7 19:44:56 daemon err syslog: pppd:Couldn't increase MRU to 1500
Nov  7 19:44:56 daemon info radvd[13042]: resuming normal operation
Nov  7 19:44:56 daemon warn radvd[13042]: received RA from fe80::6203:47ff:fe4b:xxxx
Nov  7 19:44:57 kern crit kernel: eth4 (Int switch port: 3) (Logical Port: 3) Link UP 1000 mbps full duplex
Nov  7 19:44:57 daemon info radvd[13042]: attempting to reread config file
Nov  7 19:44:57 daemon info radvd[13042]: resuming normal operation
Nov  7 19:44:57 daemon warn radvd[13042]: received RA from fe80::6203:47ff:fe4b:xxxx
Nov  7 19:44:57 daemon info radvd[13042]: attempting to reread config file
Nov  7 19:44:57 daemon info radvd[13042]: resuming normal operation
Nov  7 19:44:57 daemon warn radvd[13042]: received RA from fe80::6203:47ff:fe4b:xxxx
Nov  7 19:44:58 kern crit kernel: eth4 (Int switch port: 3) (Logical Port: 3) Link DOWN.
Nov  7 19:44:58 daemon info radvd[13042]: attempting to reread config file
Nov  7 19:44:58 daemon info radvd[13042]: resuming normal operation
Nov  7 19:44:58 daemon warn radvd[13042]: received RA from fe80::6203:47ff:fe4b:xxxx
Nov  7 19:44:58 daemon info radvd[13042]: attempting to reread config file
Nov  7 19:44:58 daemon info radvd[13042]: resuming normal operation
Nov  7 19:44:58 daemon warn radvd[13042]: received RA from fe80::6203:47ff:fe4b:xxxx
Nov  7 19:44:59 daemon notice syslog: pppd:Terminating on signal 20.
Nov  7 19:45:00 kern crit kernel: eth4 (Int switch port: 3) (Logical Port: 3) Link UP 1000 mbps full duplex
Nov  7 19:45:00 daemon info radvd[13042]: attempting to reread config file
Nov  7 19:45:00 daemon info radvd[13042]: resuming normal operation
Nov  7 19:45:00 daemon warn radvd[13042]: received RA from fe80::6203:47ff:fe4b:xxxx
Nov  7 19:45:00 daemon info radvd[13042]: attempting to reread config file
Nov  7 19:45:00 daemon info radvd[13042]: resuming normal operation
Nov  7 19:45:00 daemon warn radvd[13042]: received RA from fe80::6203:47ff:fe4b:xxxx
Nov  7 19:45:02 kern crit kernel: eth4 (Int switch port: 3) (Logical Port: 3) Link DOWN.
Nov  7 19:45:02 daemon info radvd[13042]: attempting to reread config file
Nov  7 19:45:02 daemon info radvd[13042]: resuming normal operation
Nov  7 19:45:02 daemon warn radvd[13042]: received RA from fe80::6203:47ff:fe4b:xxxx
Nov  7 19:45:02 daemon info radvd[13042]: attempting to reread config file
Nov  7 19:45:02 daemon info radvd[13042]: resuming normal operation
Nov  7 19:45:02 daemon warn radvd[13042]: received RA from fe80::6203:47ff:fe4b:xxxx
Nov  7 19:45:04 daemon info radvd[13042]: attempting to reread config file
Nov  7 19:45:04 daemon info radvd[13042]: resuming normal operation
Nov  7 19:45:04 daemon warn radvd[13042]: received RA from fe80::6203:47ff:fe4b:xxxx
Nov  7 19:45:04 kern info kernel: device eth4 left promiscuous mode
Nov  7 19:45:04 daemon info radvd[13042]: attempting to reread config file
Nov  7 19:45:04 daemon info radvd[13042]: resuming normal operation
Nov  7 19:45:04 daemon warn radvd[13042]: received RA from fe80::6203:47ff:fe4b:xxxx
Nov  7 19:45:04 daemon info radvd[13042]: attempting to reread config file
Nov  7 19:45:04 daemon info radvd[13042]: resuming normal operation
Nov  7 19:45:04 daemon warn radvd[13042]: received RA from fe80::6203:47ff:fe4b:xxxx
Nov  7 19:45:05 kern crit kernel: eth4 (Int switch port: 3) (Logical Port: 3) Link UP 1000 mbps full duplex
Nov  7 19:45:05 daemon info radvd[13042]: attempting to reread config file
Nov  7 19:45:05 daemon info radvd[13042]: resuming normal operation
Nov  7 19:45:05 daemon warn radvd[13042]: received RA from fe80::6203:47ff:fe4b:xxxx
Nov  7 19:45:06 daemon warn radvd[13042]: poll error: Interrupted system call
Nov  7 19:45:06 daemon warn radvd[13042]: Exiting, sigterm received.
Nov  7 19:45:06 daemon info radvd[27005]: version 1.8 started
Nov  7 19:45:06 daemon warn radvd[27005]: received RA from fe80::6203:47ff:fe4b:xxxx
Nov  7 19:45:06 daemon warn radvd[27005]: poll error: Interrupted system call
Nov  7 19:45:06 daemon warn radvd[27005]: Exiting, sigterm received.
Nov  7 19:45:06 daemon info radvd[27009]: version 1.8 started
Nov  7 19:45:06 daemon warn radvd[27009]: received RA from fe80::6203:47ff:fe4b:xxxx
Nov  7 19:45:06 daemon warn radvd[27009]: poll error: Interrupted system call
Nov  7 19:45:06 daemon warn radvd[27009]: Exiting, sigterm received.
Nov  7 19:45:06 daemon info radvd[27018]: version 1.8 started
Nov  7 19:45:06 daemon warn radvd[27018]: received RA from fe80::6203:47ff:fe4b:xxxx
Nov  7 19:45:06 daemon err dhcp6c[12809]: client6_send: transmit failed: Network is unreachable
Nov  7 19:45:06 daemon warn radvd[27018]: poll error: Interrupted system call
Nov  7 19:45:06 daemon warn radvd[27018]: Exiting, sigterm received.
Nov  7 19:45:06 daemon info radvd[27038]: version 1.8 started
Nov  7 19:45:06 daemon warn radvd[27038]: received RA from fe80::6203:47ff:fe4b:xxxx
Nov  7 19:45:07 daemon info radvd[27038]: attempting to reread config file
Nov  7 19:45:07 kern warn kernel: eth4.2 MAC address set to 60:03:47:4B:7C:28
Nov  7 19:45:07 kern warn kernel: netdev path : eth4.2 -> 
Nov  7 19:45:07 kern info kernel: eth4
Nov  7 19:45:07 kern warn kernel: BCMVLAN : eth4 mode was set to RG
Nov  7 19:45:07 kern info kernel: device eth4 entered promiscuous mode
Nov  7 19:45:07 daemon info radvd[27038]: resuming normal operation
Nov  7 19:45:07 daemon warn radvd[27038]: received RA from fe80::6203:47ff:fe4b:xxxx
Nov  7 19:45:07 daemon info radvd[27038]: attempting to reread config file
Nov  7 19:45:07 daemon info radvd[27038]: resuming normal operation
Nov  7 19:45:07 daemon warn radvd[27038]: received RA from fe80::6203:47ff:fe4b:xxxx
Nov  7 19:45:07 daemon info radvd[27038]: attempting to reread config file
Nov  7 19:45:07 daemon info radvd[27038]: resuming normal operation
Nov  7 19:45:07 daemon warn radvd[27038]: received RA from fe80::6203:47ff:fe4b:xxxx
Nov  7 19:45:07 daemon info radvd[27038]: attempting to reread config file
Nov  7 19:45:07 daemon info radvd[27038]: resuming normal operation
Nov  7 19:45:07 daemon warn radvd[27038]: received RA from fe80::6203:47ff:fe4b:xxxx
Nov  7 19:45:10 daemon info email[27316]: eMail: SMTP_SERVER:smtp.fastmail.com SMTP_USER:xxxxxxxx@fastmail.net SMTP_PASS:xxxxx SENDER_MAIL:xxxxxxxx@fastmail.net PORT:587 USE_TLS:true
Nov  7 19:45:10 daemon info email[27316]: eMail: Subject:WAN IP Change (new IP:192.168.2.0)
Nov  7 19:45:11 daemon info radvd[27038]: attempting to reread config file
Nov  7 19:45:11 kern warn kernel: eth4.1 MAC address set to 60:03:47:4B:7C:29
Nov  7 19:45:11 kern warn kernel: netdev path : eth4.1 -> eth4
Nov  7 19:45:11 kern warn kernel: BCMVLAN : eth4 mode was set to RG
Nov  7 19:45:11 daemon info radvd[27038]: resuming normal operation
Nov  7 19:45:11 daemon warn radvd[27038]: received RA from fe80::6203:47ff:fe4b:xxxx
Nov  7 19:45:11 daemon info radvd[27038]: attempting to reread config file
Nov  7 19:45:11 daemon info radvd[27038]: resuming normal operation
Nov  7 19:45:11 daemon warn radvd[27038]: received RA from fe80::6203:47ff:fe4b:xxxx
Nov  7 19:45:12 daemon notice syslog: pppd:cms logging initialized.
Nov  7 19:45:12 daemon notice syslog: pppd 2.4.1 started by admin, uid 0
Nov  7 19:45:12 daemon notice syslog: PPP: Start to connect ...
Nov  7 19:45:12 daemon crit syslog: session disconnect pppoe.oldsession=20e09cd90aa1/1358
Nov  7 19:45:16 daemon crit syslog: PPP server detected.
Nov  7 19:45:28 daemon warn radvd[27038]: received RA from fe80::6203:47ff:fe4b:xxxx
Nov  7 19:45:40 daemon err email[27316]: Could not connect to server: smtp.fastmail.com on port: 587
Nov  7 19:45:40 daemon info email[27316]: eMail: Send Fail
Nov  7 19:45:41 daemon err syslog: pppd:Couldn't get channel number: Transport endpoint is not connected
Nov  7 19:45:41 daemon notice syslog: pppd:Couldn't reset tty to normal line discipline: Inappropriate ioctl for device
Nov  7 19:45:41 daemon notice syslog: pppd:Doing disconnect
Nov  7 19:45:44 daemon warn radvd[27038]: received RA from fe80::6203:47ff:fe4b:xxxx
Nov  7 19:45:44 daemon notice syslog: PPP: Start to connect ...
Nov  7 19:45:44 daemon crit syslog: session disconnect pppoe.oldsession=20e09cd90aa1/1358
Nov  7 19:45:53 daemon crit syslog: PPP server detected.
Nov  7 19:46:00 daemon warn radvd[27038]: received RA from fe80::6203:47ff:fe4b:xxxx
Nov  7 19:46:19 daemon err syslog: pppd:Couldn't get channel number: Transport endpoint is not connected
Nov  7 19:46:19 daemon notice syslog: pppd:Couldn't reset tty to normal line discipline: Inappropriate ioctl for device
Nov  7 19:46:19 daemon notice syslog: pppd:Doing disconnect
Nov  7 19:46:22 daemon notice syslog: PPP: Start to connect ...
Nov  7 19:46:22 daemon crit syslog: session disconnect pppoe.oldsession=20e09cd90aa1/1358
Nov  7 19:46:28 daemon crit syslog: PPP server detected.
Nov  7 19:46:28 daemon crit syslog: PPP session established.
Nov  7 19:46:28 daemon info radvd[27038]: attempting to reread config file
Nov  7 19:46:28 kern warn kernel: netdev path : ppp2.1 -> eth4.1
Nov  7 19:46:28 kern info kernel:  -> 
Nov  7 19:46:28 kern info kernel: eth4
Nov  7 19:46:28 daemon notice syslog: pppd:Using interface ppp2.1
Nov  7 19:46:28 daemon notice syslog: pppd:Connect: ppp2.1 <--> eth4.1
Nov  7 19:46:28 daemon notice syslog: pppd:Couldn't increase MTU to 1500.
Nov  7 19:46:28 daemon info radvd[27038]: resuming normal operation
Nov  7 19:46:28 daemon warn radvd[27038]: received RA from fe80::6203:47ff:fe4b:xxxx
Nov  7 19:46:28 daemon err syslog: pppd:Couldn't increase MRU to 1500
Nov  7 19:46:28 daemon err syslog: pppd:Couldn't increase MRU to 1500
Nov  7 19:46:28 daemon crit syslog: PPP LCP UP.
Nov  7 19:46:35 daemon notice syslog: pppd:Couldn't increase MTU to 1500.
Nov  7 19:46:35 daemon err syslog: pppd:Couldn't increase MRU to 1500
Nov  7 19:46:38 daemon err syslog: pppd:Couldn't increase MRU to 1500
Nov  7 19:46:38 daemon crit syslog: PPP LCP UP.
Nov  7 19:46:44 daemon warn radvd[27038]: received RA from fe80::6203:47ff:fe4b:xxxx
Nov  7 19:46:58 daemon notice syslog: pppd:No response to 2 echo-requests
Nov  7 19:46:58 daemon notice syslog: pppd:Serial link appears to be disconnected.
Nov  7 19:46:58 daemon notice syslog: pppd:Couldn't increase MTU to 1500.
Nov  7 19:46:58 daemon err syslog: pppd:Couldn't increase MRU to 1500
Nov  7 19:47:00 daemon warn radvd[27038]: received RA from fe80::6203:47ff:fe4b:xxxx
Nov  7 19:47:01 daemon notice syslog: pppd:Connection terminated.
Nov  7 19:47:01 daemon info radvd[27038]: attempting to reread config file
Nov  7 19:47:01 daemon info radvd[27038]: resuming normal operation
Nov  7 19:47:01 daemon notice syslog: pppd:Doing disconnect
Nov  7 19:47:01 daemon warn radvd[27038]: received RA from fe80::6203:47ff:fe4b:xxxx
Nov  7 19:47:04 daemon notice syslog: PPP: Start to connect ...
Nov  7 19:47:04 daemon crit syslog: session disconnect pppoe.oldsession=20e09cd90aa1/14cc
Nov  7 19:47:08 daemon crit syslog: PPP server detected.
Nov  7 19:47:17 daemon warn radvd[27038]: received RA from fe80::6203:47ff:fe4b:xxxx
Nov  7 19:47:33 daemon warn radvd[27038]: received RA from fe80::6203:47ff:fe4b:xxxx
Nov  7 19:47:33 daemon err syslog: pppd:Couldn't get channel number: Transport endpoint is not connected
Nov  7 19:47:33 daemon notice syslog: pppd:Couldn't reset tty to normal line discipline: Inappropriate ioctl for device
Nov  7 19:47:33 daemon notice syslog: pppd:Doing disconnect
Nov  7 19:47:36 daemon notice syslog: PPP: Start to connect ...
Nov  7 19:47:36 daemon crit syslog: session disconnect pppoe.oldsession=20e09cd90aa1/14cc
Nov  7 19:47:40 daemon crit syslog: PPP server detected.
Nov  7 19:47:49 daemon warn radvd[27038]: received RA from fe80::6203:47ff:fe4b:xxxx
Nov  7 19:48:05 daemon err syslog: pppd:Couldn't get channel number: Transport endpoint is not connected
Nov  7 19:48:05 daemon notice syslog: pppd:Couldn't reset tty to normal line discipline: Inappropriate ioctl for device
Nov  7 19:48:05 daemon notice syslog: pppd:Doing disconnect
Nov  7 19:48:08 daemon notice syslog: PPP: Start to connect ...
Nov  7 19:48:08 daemon crit syslog: session disconnect pppoe.oldsession=20e09cd90aa1/14cc
Nov  7 19:48:14 daemon crit syslog: PPP server detected.

billion_fan
Posts: 5211
Joined: Tue Jul 19, 2011 4:30 pm

Re: 8900AX-1600R2 - intermittent WAN connection failure

Post by billion_fan » Fri Nov 08, 2019 9:21 am

Pete B wrote:
Thu Nov 07, 2019 8:07 pm
:oops: and again. This happened over 3 min period around 19:45. Perhaps it holds clues, there's certainly some different info within. I checked the DrayTek's logs and again, no entries and VDSL2 connection was solid throughout. Between 19:56 and 20:10 this resulted in 5 email warnings from the router informing of WAN disconnect / IP change. This is weird :!:

Code: Select all

Nov  7 19:44:55 daemon info radvd[13042]: resuming normal operation
Nov  7 19:44:55 daemon warn radvd[13042]: received RA from fe80::6203:47ff:fe4b:xxxx
Nov  7 19:44:55 daemon info radvd[13042]: attempting to reread config file
Nov  7 19:44:55 daemon info radvd[13042]: resuming normal operation
Nov  7 19:44:55 daemon warn radvd[13042]: received RA from fe80::6203:47ff:fe4b:xxxx
Nov  7 19:44:55 daemon info radvd[13042]: attempting to reread config file
Nov  7 19:44:55 daemon info radvd[13042]: resuming normal operation
Nov  7 19:44:55 daemon warn radvd[13042]: received RA from fe80::6203:47ff:fe4b:xxxx
Nov  7 19:44:56 daemon notice syslog: pppd:Terminating connection due to link down.
Nov  7 19:44:56 daemon info radvd[13042]: attempting to reread config file
Nov  7 19:44:56 daemon info radvd[13042]: resuming normal operation
Nov  7 19:44:56 daemon warn radvd[13042]: received RA from fe80::6203:47ff:fe4b:xxxx
Nov  7 19:44:56 daemon info radvd[13042]: attempting to reread config file
Nov  7 19:44:56 daemon info radvd[13042]: resuming normal operation
Nov  7 19:44:56 daemon warn radvd[13042]: received RA from fe80::6203:47ff:fe4b:xxxx
Nov  7 19:44:56 daemon crit syslog: Clear IP addresses.  PPP connection DOWN.
Nov  7 19:44:56 daemon crit syslog: Clear IP addresses.  Connection DOWN.
Nov  7 19:44:56 daemon info radvd[13042]: attempting to reread config file
Nov  7 19:44:56 daemon notice syslog: pppd:Couldn't increase MTU to 1500.
Nov  7 19:44:56 daemon err syslog: pppd:Couldn't increase MRU to 1500
Nov  7 19:44:56 daemon info radvd[13042]: resuming normal operation
Nov  7 19:44:56 daemon warn radvd[13042]: received RA from fe80::6203:47ff:fe4b:xxxx
Nov  7 19:44:57 kern crit kernel: eth4 (Int switch port: 3) (Logical Port: 3) Link UP 1000 mbps full duplex
Nov  7 19:44:57 daemon info radvd[13042]: attempting to reread config file
Nov  7 19:44:57 daemon info radvd[13042]: resuming normal operation
Nov  7 19:44:57 daemon warn radvd[13042]: received RA from fe80::6203:47ff:fe4b:xxxx
Nov  7 19:44:57 daemon info radvd[13042]: attempting to reread config file
Nov  7 19:44:57 daemon info radvd[13042]: resuming normal operation
Nov  7 19:44:57 daemon warn radvd[13042]: received RA from fe80::6203:47ff:fe4b:xxxx
Nov  7 19:44:58 kern crit kernel: eth4 (Int switch port: 3) (Logical Port: 3) Link DOWN.
Nov  7 19:44:58 daemon info radvd[13042]: attempting to reread config file
Nov  7 19:44:58 daemon info radvd[13042]: resuming normal operation
Nov  7 19:44:58 daemon warn radvd[13042]: received RA from fe80::6203:47ff:fe4b:xxxx
Nov  7 19:44:58 daemon info radvd[13042]: attempting to reread config file
Nov  7 19:44:58 daemon info radvd[13042]: resuming normal operation
Nov  7 19:44:58 daemon warn radvd[13042]: received RA from fe80::6203:47ff:fe4b:xxxx
Nov  7 19:44:59 daemon notice syslog: pppd:Terminating on signal 20.
Nov  7 19:45:00 kern crit kernel: eth4 (Int switch port: 3) (Logical Port: 3) Link UP 1000 mbps full duplex
Nov  7 19:45:00 daemon info radvd[13042]: attempting to reread config file
Nov  7 19:45:00 daemon info radvd[13042]: resuming normal operation
Nov  7 19:45:00 daemon warn radvd[13042]: received RA from fe80::6203:47ff:fe4b:xxxx
Nov  7 19:45:00 daemon info radvd[13042]: attempting to reread config file
Nov  7 19:45:00 daemon info radvd[13042]: resuming normal operation
Nov  7 19:45:00 daemon warn radvd[13042]: received RA from fe80::6203:47ff:fe4b:xxxx
Nov  7 19:45:02 kern crit kernel: eth4 (Int switch port: 3) (Logical Port: 3) Link DOWN.
Nov  7 19:45:02 daemon info radvd[13042]: attempting to reread config file
Nov  7 19:45:02 daemon info radvd[13042]: resuming normal operation
Nov  7 19:45:02 daemon warn radvd[13042]: received RA from fe80::6203:47ff:fe4b:xxxx
Nov  7 19:45:02 daemon info radvd[13042]: attempting to reread config file
Nov  7 19:45:02 daemon info radvd[13042]: resuming normal operation
Nov  7 19:45:02 daemon warn radvd[13042]: received RA from fe80::6203:47ff:fe4b:xxxx
Nov  7 19:45:04 daemon info radvd[13042]: attempting to reread config file
Nov  7 19:45:04 daemon info radvd[13042]: resuming normal operation
Nov  7 19:45:04 daemon warn radvd[13042]: received RA from fe80::6203:47ff:fe4b:xxxx
Nov  7 19:45:04 kern info kernel: device eth4 left promiscuous mode
Nov  7 19:45:04 daemon info radvd[13042]: attempting to reread config file
Nov  7 19:45:04 daemon info radvd[13042]: resuming normal operation
Nov  7 19:45:04 daemon warn radvd[13042]: received RA from fe80::6203:47ff:fe4b:xxxx
Nov  7 19:45:04 daemon info radvd[13042]: attempting to reread config file
Nov  7 19:45:04 daemon info radvd[13042]: resuming normal operation
Nov  7 19:45:04 daemon warn radvd[13042]: received RA from fe80::6203:47ff:fe4b:xxxx
Nov  7 19:45:05 kern crit kernel: eth4 (Int switch port: 3) (Logical Port: 3) Link UP 1000 mbps full duplex
Nov  7 19:45:05 daemon info radvd[13042]: attempting to reread config file
Nov  7 19:45:05 daemon info radvd[13042]: resuming normal operation
Nov  7 19:45:05 daemon warn radvd[13042]: received RA from fe80::6203:47ff:fe4b:xxxx
Nov  7 19:45:06 daemon warn radvd[13042]: poll error: Interrupted system call
Nov  7 19:45:06 daemon warn radvd[13042]: Exiting, sigterm received.
Nov  7 19:45:06 daemon info radvd[27005]: version 1.8 started
Nov  7 19:45:06 daemon warn radvd[27005]: received RA from fe80::6203:47ff:fe4b:xxxx
Nov  7 19:45:06 daemon warn radvd[27005]: poll error: Interrupted system call
Nov  7 19:45:06 daemon warn radvd[27005]: Exiting, sigterm received.
Nov  7 19:45:06 daemon info radvd[27009]: version 1.8 started
Nov  7 19:45:06 daemon warn radvd[27009]: received RA from fe80::6203:47ff:fe4b:xxxx
Nov  7 19:45:06 daemon warn radvd[27009]: poll error: Interrupted system call
Nov  7 19:45:06 daemon warn radvd[27009]: Exiting, sigterm received.
Nov  7 19:45:06 daemon info radvd[27018]: version 1.8 started
Nov  7 19:45:06 daemon warn radvd[27018]: received RA from fe80::6203:47ff:fe4b:xxxx
Nov  7 19:45:06 daemon err dhcp6c[12809]: client6_send: transmit failed: Network is unreachable
Nov  7 19:45:06 daemon warn radvd[27018]: poll error: Interrupted system call
Nov  7 19:45:06 daemon warn radvd[27018]: Exiting, sigterm received.
Nov  7 19:45:06 daemon info radvd[27038]: version 1.8 started
Nov  7 19:45:06 daemon warn radvd[27038]: received RA from fe80::6203:47ff:fe4b:xxxx
Nov  7 19:45:07 daemon info radvd[27038]: attempting to reread config file
Nov  7 19:45:07 kern warn kernel: eth4.2 MAC address set to 60:03:47:4B:7C:28
Nov  7 19:45:07 kern warn kernel: netdev path : eth4.2 -> 
Nov  7 19:45:07 kern info kernel: eth4
Nov  7 19:45:07 kern warn kernel: BCMVLAN : eth4 mode was set to RG
Nov  7 19:45:07 kern info kernel: device eth4 entered promiscuous mode
Nov  7 19:45:07 daemon info radvd[27038]: resuming normal operation
Nov  7 19:45:07 daemon warn radvd[27038]: received RA from fe80::6203:47ff:fe4b:xxxx
Nov  7 19:45:07 daemon info radvd[27038]: attempting to reread config file
Nov  7 19:45:07 daemon info radvd[27038]: resuming normal operation
Nov  7 19:45:07 daemon warn radvd[27038]: received RA from fe80::6203:47ff:fe4b:xxxx
Nov  7 19:45:07 daemon info radvd[27038]: attempting to reread config file
Nov  7 19:45:07 daemon info radvd[27038]: resuming normal operation
Nov  7 19:45:07 daemon warn radvd[27038]: received RA from fe80::6203:47ff:fe4b:xxxx
Nov  7 19:45:07 daemon info radvd[27038]: attempting to reread config file
Nov  7 19:45:07 daemon info radvd[27038]: resuming normal operation
Nov  7 19:45:07 daemon warn radvd[27038]: received RA from fe80::6203:47ff:fe4b:xxxx
Nov  7 19:45:10 daemon info email[27316]: eMail: SMTP_SERVER:smtp.fastmail.com SMTP_USER:xxxxxxxx@fastmail.net SMTP_PASS:xxxxx SENDER_MAIL:xxxxxxxx@fastmail.net PORT:587 USE_TLS:true
Nov  7 19:45:10 daemon info email[27316]: eMail: Subject:WAN IP Change (new IP:192.168.2.0)
Nov  7 19:45:11 daemon info radvd[27038]: attempting to reread config file
Nov  7 19:45:11 kern warn kernel: eth4.1 MAC address set to 60:03:47:4B:7C:29
Nov  7 19:45:11 kern warn kernel: netdev path : eth4.1 -> eth4
Nov  7 19:45:11 kern warn kernel: BCMVLAN : eth4 mode was set to RG
Nov  7 19:45:11 daemon info radvd[27038]: resuming normal operation
Nov  7 19:45:11 daemon warn radvd[27038]: received RA from fe80::6203:47ff:fe4b:xxxx
Nov  7 19:45:11 daemon info radvd[27038]: attempting to reread config file
Nov  7 19:45:11 daemon info radvd[27038]: resuming normal operation
Nov  7 19:45:11 daemon warn radvd[27038]: received RA from fe80::6203:47ff:fe4b:xxxx
Nov  7 19:45:12 daemon notice syslog: pppd:cms logging initialized.
Nov  7 19:45:12 daemon notice syslog: pppd 2.4.1 started by admin, uid 0
Nov  7 19:45:12 daemon notice syslog: PPP: Start to connect ...
Nov  7 19:45:12 daemon crit syslog: session disconnect pppoe.oldsession=20e09cd90aa1/1358
Nov  7 19:45:16 daemon crit syslog: PPP server detected.
Nov  7 19:45:28 daemon warn radvd[27038]: received RA from fe80::6203:47ff:fe4b:xxxx
Nov  7 19:45:40 daemon err email[27316]: Could not connect to server: smtp.fastmail.com on port: 587
Nov  7 19:45:40 daemon info email[27316]: eMail: Send Fail
Nov  7 19:45:41 daemon err syslog: pppd:Couldn't get channel number: Transport endpoint is not connected
Nov  7 19:45:41 daemon notice syslog: pppd:Couldn't reset tty to normal line discipline: Inappropriate ioctl for device
Nov  7 19:45:41 daemon notice syslog: pppd:Doing disconnect
Nov  7 19:45:44 daemon warn radvd[27038]: received RA from fe80::6203:47ff:fe4b:xxxx
Nov  7 19:45:44 daemon notice syslog: PPP: Start to connect ...
Nov  7 19:45:44 daemon crit syslog: session disconnect pppoe.oldsession=20e09cd90aa1/1358
Nov  7 19:45:53 daemon crit syslog: PPP server detected.
Nov  7 19:46:00 daemon warn radvd[27038]: received RA from fe80::6203:47ff:fe4b:xxxx
Nov  7 19:46:19 daemon err syslog: pppd:Couldn't get channel number: Transport endpoint is not connected
Nov  7 19:46:19 daemon notice syslog: pppd:Couldn't reset tty to normal line discipline: Inappropriate ioctl for device
Nov  7 19:46:19 daemon notice syslog: pppd:Doing disconnect
Nov  7 19:46:22 daemon notice syslog: PPP: Start to connect ...
Nov  7 19:46:22 daemon crit syslog: session disconnect pppoe.oldsession=20e09cd90aa1/1358
Nov  7 19:46:28 daemon crit syslog: PPP server detected.
Nov  7 19:46:28 daemon crit syslog: PPP session established.
Nov  7 19:46:28 daemon info radvd[27038]: attempting to reread config file
Nov  7 19:46:28 kern warn kernel: netdev path : ppp2.1 -> eth4.1
Nov  7 19:46:28 kern info kernel:  -> 
Nov  7 19:46:28 kern info kernel: eth4
Nov  7 19:46:28 daemon notice syslog: pppd:Using interface ppp2.1
Nov  7 19:46:28 daemon notice syslog: pppd:Connect: ppp2.1 <--> eth4.1
Nov  7 19:46:28 daemon notice syslog: pppd:Couldn't increase MTU to 1500.
Nov  7 19:46:28 daemon info radvd[27038]: resuming normal operation
Nov  7 19:46:28 daemon warn radvd[27038]: received RA from fe80::6203:47ff:fe4b:xxxx
Nov  7 19:46:28 daemon err syslog: pppd:Couldn't increase MRU to 1500
Nov  7 19:46:28 daemon err syslog: pppd:Couldn't increase MRU to 1500
Nov  7 19:46:28 daemon crit syslog: PPP LCP UP.
Nov  7 19:46:35 daemon notice syslog: pppd:Couldn't increase MTU to 1500.
Nov  7 19:46:35 daemon err syslog: pppd:Couldn't increase MRU to 1500
Nov  7 19:46:38 daemon err syslog: pppd:Couldn't increase MRU to 1500
Nov  7 19:46:38 daemon crit syslog: PPP LCP UP.
Nov  7 19:46:44 daemon warn radvd[27038]: received RA from fe80::6203:47ff:fe4b:xxxx
Nov  7 19:46:58 daemon notice syslog: pppd:No response to 2 echo-requests
Nov  7 19:46:58 daemon notice syslog: pppd:Serial link appears to be disconnected.
Nov  7 19:46:58 daemon notice syslog: pppd:Couldn't increase MTU to 1500.
Nov  7 19:46:58 daemon err syslog: pppd:Couldn't increase MRU to 1500
Nov  7 19:47:00 daemon warn radvd[27038]: received RA from fe80::6203:47ff:fe4b:xxxx
Nov  7 19:47:01 daemon notice syslog: pppd:Connection terminated.
Nov  7 19:47:01 daemon info radvd[27038]: attempting to reread config file
Nov  7 19:47:01 daemon info radvd[27038]: resuming normal operation
Nov  7 19:47:01 daemon notice syslog: pppd:Doing disconnect
Nov  7 19:47:01 daemon warn radvd[27038]: received RA from fe80::6203:47ff:fe4b:xxxx
Nov  7 19:47:04 daemon notice syslog: PPP: Start to connect ...
Nov  7 19:47:04 daemon crit syslog: session disconnect pppoe.oldsession=20e09cd90aa1/14cc
Nov  7 19:47:08 daemon crit syslog: PPP server detected.
Nov  7 19:47:17 daemon warn radvd[27038]: received RA from fe80::6203:47ff:fe4b:xxxx
Nov  7 19:47:33 daemon warn radvd[27038]: received RA from fe80::6203:47ff:fe4b:xxxx
Nov  7 19:47:33 daemon err syslog: pppd:Couldn't get channel number: Transport endpoint is not connected
Nov  7 19:47:33 daemon notice syslog: pppd:Couldn't reset tty to normal line discipline: Inappropriate ioctl for device
Nov  7 19:47:33 daemon notice syslog: pppd:Doing disconnect
Nov  7 19:47:36 daemon notice syslog: PPP: Start to connect ...
Nov  7 19:47:36 daemon crit syslog: session disconnect pppoe.oldsession=20e09cd90aa1/14cc
Nov  7 19:47:40 daemon crit syslog: PPP server detected.
Nov  7 19:47:49 daemon warn radvd[27038]: received RA from fe80::6203:47ff:fe4b:xxxx
Nov  7 19:48:05 daemon err syslog: pppd:Couldn't get channel number: Transport endpoint is not connected
Nov  7 19:48:05 daemon notice syslog: pppd:Couldn't reset tty to normal line discipline: Inappropriate ioctl for device
Nov  7 19:48:05 daemon notice syslog: pppd:Doing disconnect
Nov  7 19:48:08 daemon notice syslog: PPP: Start to connect ...
Nov  7 19:48:08 daemon crit syslog: session disconnect pppoe.oldsession=20e09cd90aa1/14cc
Nov  7 19:48:14 daemon crit syslog: PPP server detected.
It looks like it might be a ISP issue, the PPP session is disconnecting but DSL is holding sync, (I have seen that before)

The next steps will be, upgrade the firmware and see how it goes, then the final test will be using the Billion in modem/router mode.

Then if the same issue occurs when using the device in modem/router mode, (DSL holds sync but drops the PPP session) then you will have to contact IDnet to investigate further

Pete B
Posts: 24
Joined: Tue Sep 15, 2015 3:15 pm

Re: 8900AX-1600R2 - intermittent WAN connection failure

Post by Pete B » Fri Nov 08, 2019 10:09 am

Ok... and again, many thanks for the reply. You've laid out a strategy which I will follow in due course. I'm determined to get to the bottom of this. Ultimately, if it's not an issue purely derived at this end, it could well provide information to help others. Bear with me while I work through this and rest assured, I will definitely post back on all outcomes... cheers

billion_fan
Posts: 5211
Joined: Tue Jul 19, 2011 4:30 pm

Re: 8900AX-1600R2 - intermittent WAN connection failure

Post by billion_fan » Fri Nov 08, 2019 12:27 pm

Pete B wrote:
Fri Nov 08, 2019 10:09 am
Ok... and again, many thanks for the reply. You've laid out a strategy which I will follow in due course. I'm determined to get to the bottom of this. Ultimately, if it's not an issue purely derived at this end, it could well provide information to help others. Bear with me while I work through this and rest assured, I will definitely post back on all outcomes... cheers
Also I was just looking through the logs again and found this

Nov 7 19:46:58 daemon notice syslog: pppd:No response to 2 echo-requests
Nov 7 19:46:58 daemon notice syslog: pppd:Serial link appears to be disconnected.

Pete B
Posts: 24
Joined: Tue Sep 15, 2015 3:15 pm

Re: 8900AX-1600R2 - intermittent WAN connection failure

Post by Pete B » Fri Nov 08, 2019 4:22 pm

billion_fan wrote:Nov 7 19:46:58 daemon notice syslog: pppd:No response to 2 echo-requests
Nov 7 19:46:58 daemon notice syslog: pppd:Serial link appears to be disconnected.
I probably understand the first line but why it's there is another matter. I do have an IPv6 rule set up for incoming to allow ICMP because of the nature of IPv6.

The second line, I really don't know what that means. Serial ? is that like RS232 and back in the days ? :)

BTW I'm starting to wonder if your insight into the log regarding the potential for an ISP issue is getting towards the truth. I haven't had a single PPP drop within the last (almost) 24 hours ! When you consider that I was getting sometimes 5 within 30 mins, maybe I was connecting at a point where there was a 'flurry of remedial activity' at their end, just by coincidence. I'm watching this closely and if I maintain PPP connection stability, I'll drop them a line to ask if they've been doing anything untoward with their IPv6 provision.

For the time being I need to just monitor it all :)

billion_fan
Posts: 5211
Joined: Tue Jul 19, 2011 4:30 pm

Re: 8900AX-1600R2 - intermittent WAN connection failure

Post by billion_fan » Fri Nov 08, 2019 4:26 pm

Pete B wrote:
Fri Nov 08, 2019 4:22 pm
billion_fan wrote:Nov 7 19:46:58 daemon notice syslog: pppd:No response to 2 echo-requests
Nov 7 19:46:58 daemon notice syslog: pppd:Serial link appears to be disconnected.
I probably understand the first line but why it's there is another matter. I do have an IPv6 rule set up for incoming to allow ICMP because of the nature of IPv6.

The second line, I really don't know what that means. Serial ? is that like RS232 and back in the days ? :)

BTW I'm starting to wonder if your insight into the log regarding the potential for an ISP issue is getting towards the truth. I haven't had a single PPP drop within the last (almost) 24 hours ! When you consider that I was getting sometimes 5 within 30 mins, maybe I was connecting at a point where there was a 'flurry of remedial activity' at their end, just by coincidence. I'm watching this closely and if I maintain PPP connection stability, I'll drop them a line to ask if they've been doing anything untoward with their IPv6 provision.

For the time being I need to just monitor it all :)
Nov 7 19:46:58 daemon notice syslog: pppd:Serial link appears to be disconnected, just means it can't see a link to connect to after the 2 echo requests fail.

IPv6 ICMP is just the ping rule it should not matter.

Lets see how you get on tonight,

Pete B
Posts: 24
Joined: Tue Sep 15, 2015 3:15 pm

Re: 8900AX-1600R2 - intermittent WAN connection failure

Post by Pete B » Wed Nov 13, 2019 7:59 pm

Back as promised, good news, this issue now appears to be fully resolved :D ... not absolutely certain how or why but the upgrade to firmware 2.52.d2 was cathartic and undoubtedly instrumental in achieving it, so thank you for that. Naturally I got 'Quick Access' to setup the WAN connections for IPv4 & IPv6 at the time. On comparing a screenshot of the old installation with the new, there was a difference in configuration on one of the two PPPoE connections. The connection which is default labelled after what appears to be the VLAN tag (in this case 101), on the old installation, had static IP address selected (this is either on or off for both IPv4 and IPv6, they obviously can't be separated), my static IPv4 which I have from my ISP was in the address field, (always was from when I was setup for only IPv4)... but with no IPv6 address, as this needs to be set dynamically using DHCPv6 and is required to be like this by my ISP. The other PPPoE connection had no static IP selection at all and this is how both of them should be... even if you do have a static IPv4 address.

Really, I think there were 2 problems, firstly a misconfigured PPPoE connection resulting from coming originally from a previously configured static IPv4 only setup and secondly the router was not issuing router advertisements, which you picked up on and we fixed earlier on in this thread. Hope that all makes sense :lol: Firmware 2.52.d2 is running flawlessly, no WAN connection failures in the last 3 days (used to be up to 10 a day). What can I say...

Thanks a billion :roll:

billion_fan
Posts: 5211
Joined: Tue Jul 19, 2011 4:30 pm

Re: 8900AX-1600R2 - intermittent WAN connection failure

Post by billion_fan » Thu Nov 14, 2019 9:20 am

Pete B wrote:
Wed Nov 13, 2019 7:59 pm
Back as promised, good news, this issue now appears to be fully resolved :D ... not absolutely certain how or why but the upgrade to firmware 2.52.d2 was cathartic and undoubtedly instrumental in achieving it, so thank you for that. Naturally I got 'Quick Access' to setup the WAN connections for IPv4 & IPv6 at the time. On comparing a screenshot of the old installation with the new, there was a difference in configuration on one of the two PPPoE connections. The connection which is default labelled after what appears to be the VLAN tag (in this case 101), on the old installation, had static IP address selected (this is either on or off for both IPv4 and IPv6, they obviously can't be separated), my static IPv4 which I have from my ISP was in the address field, (always was from when I was setup for only IPv4)... but with no IPv6 address, as this needs to be set dynamically using DHCPv6 and is required to be like this by my ISP. The other PPPoE connection had no static IP selection at all and this is how both of them should be... even if you do have a static IPv4 address.

Really, I think there were 2 problems, firstly a misconfigured PPPoE connection resulting from coming originally from a previously configured static IPv4 only setup and secondly the router was not issuing router advertisements, which you picked up on and we fixed earlier on in this thread. Hope that all makes sense :lol: Firmware 2.52.d2 is running flawlessly, no WAN connection failures in the last 3 days (used to be up to 10 a day). What can I say...

Thanks a billion :roll:
That's great news mate, I will keep you posted with further firmware updates for your model :D

Post Reply