7800DXL needs manual recovery from dropped connection

Post Reply
judge63
Posts: 6
Joined: Fri Nov 17, 2017 7:27 pm

7800DXL needs manual recovery from dropped connection

Post by judge63 »

My 7800DXL was rock solid for about 18months, but over last few months it periodically loses the ADSL connection - PPP goes down, keeps trying to bring LCP back up unsuccessfully. ADSL is still in sync. Need to manually pull the cable from the wall, change the ADSL config, or reboot the router to recover.

Maybe a coincidence - around the time it started happening, there was some change in the exchange - now getting slightly higher upstream sync speeds than before - 780 now, was around half that before. Wonder if I am now connected to some different equipment which is not quite as compatible?

Not able to get any useful info out of BT support - sorry not a home hub - must be your router :(

Any ideas? Any config tweaks I can do to "force" an ADSL re-sync after PPP goes down?

Thanks.
billion_fan
Posts: 5374
Joined: Tue Jul 19, 2011 4:30 pm

Re: 7800DXL needs manual recovery from dropped connection

Post by billion_fan »

judge63 wrote: Fri Nov 17, 2017 7:37 pm My 7800DXL was rock solid for about 18months, but over last few months it periodically loses the ADSL connection - PPP goes down, keeps trying to bring LCP back up unsuccessfully. ADSL is still in sync. Need to manually pull the cable from the wall, change the ADSL config, or reboot the router to recover.

Maybe a coincidence - around the time it started happening, there was some change in the exchange - now getting slightly higher upstream sync speeds than before - 780 now, was around half that before. Wonder if I am now connected to some different equipment which is not quite as compatible?

Not able to get any useful info out of BT support - sorry not a home hub - must be your router :(

Any ideas? Any config tweaks I can do to "force" an ADSL re-sync after PPP goes down?

Thanks.
Can you post a copy of your system log once the issue occurs??
judge63
Posts: 6
Joined: Fri Nov 17, 2017 7:27 pm

Re: 7800DXL needs manual recovery from dropped connection

Post by judge63 »

will do - when it occurs. Could be day, could be a week or more...

Meantime I have got a script running. It is nice that there is shell access. The script should toggle the adsl connection if it sees the pppoa interface staying down for at least a couple of minutes (that's the theory anyway ;)
judge63
Posts: 6
Joined: Fri Nov 17, 2017 7:27 pm

Re: 7800DXL needs manual recovery from dropped connection

Post by judge63 »

Problem has happened - and my script recovered it :)
Here is the log (the user.notice entries are from the script).
Does it provide anything useful? Thx.

Code: Select all

Nov 21 22:04:05 (none) daemon.info syslog: sending ACK to IP:192.168.1.111, MAC:6c:4d:73:c5:2b:44 103
Nov 21 22:08:54 (none) daemon.notice syslog: pppd:No response to 2 echo-requests  86
Nov 21 22:08:54 (none) daemon.notice syslog: pppd:Serial link appears to be disconnected.  95
Nov 21 22:08:54 (none) daemon.crit syslog: Clear IP addresses.  PPP connection DOWN.  90
Nov 21 22:08:54 (none) daemon.crit syslog: Clear IP addresses.  Connection DOWN.  86
Nov 21 22:09:00 (none) daemon.notice syslog: pppd:Connection terminated.  78
Nov 21 22:09:00 (none) daemon.notice syslog: pppd:Connect time 3063.4 minutes.  84
Nov 21 22:09:00 (none) daemon.notice syslog: pppd:Sent 87813633 bytes, received 1065631183 bytes. 103
Nov 21 22:09:03 (none) daemon.notice syslog: PPP: Start to connect ...  76
Nov 21 22:09:04 (none) daemon.err dhcp6c[17789]: client6_send: transmit failed: Network is unreachable 108
Nov 21 22:09:05 (none) daemon.warn kernel: netdev path : pppoa0 -> atm0  77
Nov 21 22:09:05 (none) daemon.notice syslog: pppd:Using interface pppoa0  78
Nov 21 22:09:05 (none) daemon.notice syslog: pppd:Connect: pppoa0 <-->   77
Nov 21 22:09:13 (none) daemon.info syslog: sending OFFER to IP:192.168.1.111, MAC:6c:4d:73:c5:2b:44 105
Nov 21 22:09:14 (none) daemon.notice syslog: pppd:LCP: timeout sending Config-Requests  92
Nov 21 22:09:14 (none) daemon.notice syslog: pppd:Connection terminated.  78
Nov 21 22:09:14 (none) daemon.info syslog: sending OFFER to IP:192.168.1.111, MAC:6c:4d:73:c5:2b:44 105
Nov 21 22:09:15 (none) daemon.info syslog: sending ACK to IP:192.168.1.111, MAC:6c:4d:73:c5:2b:44 103
Nov 21 22:09:17 (none) daemon.notice syslog: PPP: Start to connect ...  76
Nov 21 22:09:18 (none) daemon.warn kernel: netdev path : pppoa0 -> atm0  77
Nov 21 22:09:18 (none) daemon.notice syslog: pppd:Using interface pppoa0  78
Nov 21 22:09:18 (none) daemon.notice syslog: pppd:Connect: pppoa0 <-->   77
Nov 21 22:09:28 (none) daemon.notice syslog: pppd:LCP: timeout sending Config-Requests  92
Nov 21 22:09:28 (none) daemon.notice syslog: pppd:Connection terminated.  78
Nov 21 22:09:31 (none) daemon.notice syslog: PPP: Start to connect ...  76
Nov 21 22:09:32 (none) daemon.warn kernel: netdev path : pppoa0 -> atm0  77
Nov 21 22:09:32 (none) daemon.notice syslog: pppd:Using interface pppoa0  78
Nov 21 22:09:32 (none) daemon.notice syslog: pppd:Connect: pppoa0 <-->   77
Nov 21 22:09:34 (none) user.notice admin: PPP is DOWN  59
Nov 21 22:09:41 (none) daemon.notice syslog: pppd:LCP: timeout sending Config-Requests  92
Nov 21 22:09:41 (none) daemon.notice syslog: pppd:Connection terminated.  78
Nov 21 22:09:44 (none) daemon.notice syslog: PPP: Start to connect ...  76
Nov 21 22:09:46 (none) daemon.warn kernel: netdev path : pppoa0 -> atm0  77
Nov 21 22:09:46 (none) daemon.notice syslog: pppd:Using interface pppoa0  78
Nov 21 22:09:46 (none) daemon.notice syslog: pppd:Connect: pppoa0 <-->   77
Nov 21 22:09:55 (none) daemon.notice syslog: pppd:LCP: timeout sending Config-Requests  92
Nov 21 22:09:55 (none) daemon.notice syslog: pppd:Connection terminated.  78
Nov 21 22:09:58 (none) daemon.notice syslog: PPP: Start to connect ...  76
Nov 21 22:09:59 (none) daemon.warn kernel: netdev path : pppoa0 -> atm0  77
Nov 21 22:09:59 (none) daemon.notice syslog: pppd:Using interface pppoa0  78
Nov 21 22:09:59 (none) daemon.notice syslog: pppd:Connect: pppoa0 <-->   77
Nov 21 22:10:09 (none) daemon.notice syslog: pppd:LCP: timeout sending Config-Requests  92
Nov 21 22:10:09 (none) daemon.notice syslog: pppd:Connection terminated.  78
Nov 21 22:10:12 (none) daemon.notice syslog: PPP: Start to connect ...  76
Nov 21 22:10:13 (none) daemon.warn kernel: netdev path : pppoa0 -> atm0  77
Nov 21 22:10:13 (none) daemon.notice syslog: pppd:Using interface pppoa0  78
Nov 21 22:10:13 (none) daemon.notice syslog: pppd:Connect: pppoa0 <-->   77
Nov 21 22:10:22 (none) daemon.notice syslog: pppd:LCP: timeout sending Config-Requests  92
Nov 21 22:10:22 (none) daemon.notice syslog: pppd:Connection terminated.  78
Nov 21 22:10:25 (none) daemon.notice syslog: PPP: Start to connect ...  76
Nov 21 22:10:27 (none) daemon.warn kernel: netdev path : pppoa0 -> atm0  77
Nov 21 22:10:27 (none) daemon.notice syslog: pppd:Using interface pppoa0  78
Nov 21 22:10:27 (none) daemon.notice syslog: pppd:Connect: pppoa0 <-->   77
Nov 21 22:10:36 (none) daemon.notice syslog: pppd:LCP: timeout sending Config-Requests  92
Nov 21 22:10:36 (none) daemon.notice syslog: pppd:Connection terminated.  78
Nov 21 22:10:39 (none) daemon.notice syslog: PPP: Start to connect ...  76
Nov 21 22:10:40 (none) daemon.warn kernel: netdev path : pppoa0 -> atm0  77
Nov 21 22:10:40 (none) daemon.notice syslog: pppd:Using interface pppoa0  78
Nov 21 22:10:40 (none) daemon.notice syslog: pppd:Connect: pppoa0 <-->   77
Nov 21 22:10:50 (none) daemon.notice syslog: pppd:LCP: timeout sending Config-Requests  92
Nov 21 22:10:50 (none) daemon.notice syslog: pppd:Connection terminated.  78
Nov 21 22:10:53 (none) daemon.notice syslog: PPP: Start to connect ...  76
Nov 21 22:10:54 (none) daemon.warn kernel: netdev path : pppoa0 -> atm0  77
Nov 21 22:10:54 (none) daemon.notice syslog: pppd:Using interface pppoa0  78
Nov 21 22:10:54 (none) daemon.notice syslog: pppd:Connect: pppoa0 <-->   77
Nov 21 22:11:03 (none) daemon.notice syslog: pppd:LCP: timeout sending Config-Requests  92
Nov 21 22:11:03 (none) daemon.notice syslog: pppd:Connection terminated.  78
Nov 21 22:11:06 (none) daemon.notice syslog: PPP: Start to connect ...  76
Nov 21 22:11:08 (none) daemon.warn kernel: netdev path : pppoa0 -> atm0  77
Nov 21 22:11:08 (none) daemon.notice syslog: pppd:Using interface pppoa0  78
Nov 21 22:11:08 (none) daemon.notice syslog: pppd:Connect: pppoa0 <-->   77
Nov 21 22:11:17 (none) daemon.notice syslog: pppd:LCP: timeout sending Config-Requests  92
Nov 21 22:11:17 (none) daemon.notice syslog: pppd:Connection terminated.  78
Nov 21 22:11:20 (none) daemon.notice syslog: PPP: Start to connect ...  76
Nov 21 22:11:21 (none) daemon.warn kernel: netdev path : pppoa0 -> atm0  77
Nov 21 22:11:21 (none) daemon.notice syslog: pppd:Using interface pppoa0  78
Nov 21 22:11:21 (none) daemon.notice syslog: pppd:Connect: pppoa0 <-->   77
Nov 21 22:11:30 (none) daemon.notice syslog: pppd:LCP: timeout sending Config-Requests  92
Nov 21 22:11:30 (none) daemon.notice syslog: pppd:Connection terminated.  78
Nov 21 22:11:34 (none) daemon.notice syslog: PPP: Start to connect ...  76
Nov 21 22:11:34 (none) user.notice admin: PPP still down - kicking ADSL  77
Nov 21 22:11:34 (none) daemon.crit kernel: Line 0: ADSL link down  71
Nov 21 22:11:34 (none) daemon.warn kernel: bcmxtmcfg: XTM Link Information, port = 0, State = DOWN, Service Support = ATM  128
Nov 21 22:11:34 (none) daemon.warn kernel: bcmxtmrt: DSD(890b151c) - 128   79
Nov 21 22:11:34 (none) daemon.warn kernel: HOST XTM tx ch 0 disabled.  75
Nov 21 22:11:34 (none) daemon.warn kernel: bcmxtmcfg: Connection DOWN, LinkActiveStatus=0x0   98
Nov 21 22:11:34 (none) daemon.notice syslog: pppd:Terminating on signal 20.  81
Nov 21 22:11:35 (none) daemon.warn kernel: netdev path : pppoa0 -> atm0  77
Nov 21 22:11:35 (none) daemon.notice syslog: pppd:Using interface pppoa0  78
Nov 21 22:11:35 (none) daemon.notice syslog: pppd:Connect: pppoa0 <-->   77
Nov 21 22:11:57 (none) daemon.crit kernel: Line 0: ADSL G.992 started  75
Nov 21 22:12:04 (none) daemon.crit kernel: Line 0: ADSL G.992 channel analysis  84
Nov 21 22:12:10 (none) daemon.crit kernel: Line 0: ADSL G.992 message exchange  84
Nov 21 22:12:10 (none) daemon.crit kernel: Line 0: ADSL link up, Bearer 0, us=756, ds=6478  96
Nov 21 22:12:10 (none) daemon.warn kernel: bcmxtmcfg: XTM Link Information, port = 0, State = UP, Service Support = ATM  126
Nov 21 22:12:10 (none) daemon.warn kernel: bcmxtmrt: MAC address: 60 03 47 27 a8 1d  89
Nov 21 22:12:10 (none) daemon.warn kernel: [DoCreateDeviceReq.3269]: register_netdev  90
Nov 21 22:12:11 (none) daemon.warn kernel: [DoCreateDeviceReq.3271]: register_netdev done  95
Nov 21 22:12:11 (none) daemon.warn kernel: [FAP0] xtmCreateDevice : devId 0, encapType 1, headerLen 0 107
Nov 21 22:12:11 (none) daemon.warn kernel: bcmxtmrt: DSD(890bacdc) - 128   79
Nov 21 22:12:11 (none) daemon.warn kernel: XTM Init: 400 tx BDs at 0xaa70c000  83
Nov 21 22:12:11 (none) daemon.warn kernel: bcmxtmcfg: Connection UP, LinkActiveStatus=0x1, US=756000, DS=6478000  119
Nov 21 22:12:11 (none) daemon.warn kernel: [FAP0] xtmLinkUp : devId 0, matchId 0  86
Nov 21 22:12:11 (none) daemon.notice syslog: pppd:cms logging initialized.  80
Nov 21 22:12:11 (none) daemon.notice syslog: pppd:PPPoATM setdevname_pppoatm  82
Nov 21 22:12:11 (none) daemon.notice syslog: pppd:PPPoATM setdevname_pppoatm - SUCCESS  92
Nov 21 22:12:11 (none) daemon.notice syslog: pppd 2.4.1 started by admin, uid 0  85
Nov 21 22:12:11 (none) daemon.notice syslog: PPP: Start to connect ...  76
Nov 21 22:12:11 (none) daemon.warn kernel: netdev path : pppoa0  69
Nov 21 22:12:11 (none) daemon.info kernel:  -> atm0  57
Nov 21 22:12:11 (none) daemon.notice syslog: pppd:Using interface pppoa0  78
Nov 21 22:12:11 (none) daemon.notice syslog: pppd:Connect: pppoa0 <-->   77
Nov 21 22:12:19 (none) daemon.crit syslog: PPP LCP UP.  60
Nov 21 22:12:20 (none) daemon.notice syslog: pppd:Remote message: CHAP authentication success  99
Nov 21 22:12:20 (none) daemon.notice syslog: pppd:local  LL address fe80::c4ea:55cc:a8e1:ec04  99
Nov 21 22:12:20 (none) daemon.notice syslog: pppd:remote LL address fe80::12e8:78ff:fe86:7f51  99
Nov 21 22:12:20 (none) daemon.notice syslog: pppd:local  IP address 86.184.151.220  88
Nov 21 22:12:20 (none) daemon.notice syslog: pppd:remote IP address 172.16.19.48  86
Nov 21 22:12:20 (none) daemon.notice syslog: pppd:primary   DNS address 81.139.57.100  91
Nov 21 22:12:20 (none) daemon.notice syslog: pppd:secondary DNS address 81.139.56.100  91
Nov 21 22:12:20 (none) daemon.crit syslog: Received valid IP address from server.  Connection UP. 103
Nov 21 22:12:21 (none) daemon.info syslog: Hop limit                : 64  78
Nov 21 22:12:21 (none) daemon.info syslog: Stateful address conf.    : Yes  80
Nov 21 22:12:21 (none) daemon.info syslog: Stateful other conf.      : No  79
Nov 21 22:12:21 (none) daemon.info syslog: Router preference        : medium  82
Nov 21 22:12:21 (none) daemon.info syslog: Router lifetime           : 4500 seconds  89
Nov 21 22:12:21 (none) daemon.info syslog: Reachable time           : 0 milliseconds  90
Nov 21 22:12:21 (none) daemon.info syslog: Retransmit time          : 0 milliseconds  90
Nov 21 22:12:27 (none) daemon.warn kernel: ^[[0;36;44mBroadcom Packet Flow Cache flushing the flows^[[0m 110
Nov 21 22:12:27 (none) daemon.info syslog: dhcp6c restart..  65
Nov 21 22:49:37 (none) daemon.info syslog: sending OFFER to IP:192.168.1.106, MAC:0c:89:10:03:9d:51 105
Nov 21 22:49:37 (none) daemon.info syslog: sending ACK to IP:192.168.1.106, MAC:0c:89:10:03:9d:51 103
Nov 21 23:10:16 (none) daemon.info syslog: sending ACK to IP:192.168.1.104, MAC:f8:1e:df:ee:15:5c 103

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

Re: 7800DXL needs manual recovery from dropped connection

Post by billion_fan »

judge63 wrote: Tue Nov 21, 2017 11:19 pm Problem has happened - and my script recovered it :)
Here is the log (the user.notice entries are from the script).
Does it provide anything useful? Thx.

Code: Select all

Nov 21 22:04:05 (none) daemon.info syslog: sending ACK to IP:192.168.1.111, MAC:6c:4d:73:c5:2b:44 103
Nov 21 22:08:54 (none) daemon.notice syslog: pppd:No response to 2 echo-requests  86
Nov 21 22:08:54 (none) daemon.notice syslog: pppd:Serial link appears to be disconnected.  95
Nov 21 22:08:54 (none) daemon.crit syslog: Clear IP addresses.  PPP connection DOWN.  90
Nov 21 22:08:54 (none) daemon.crit syslog: Clear IP addresses.  Connection DOWN.  86
Nov 21 22:09:00 (none) daemon.notice syslog: pppd:Connection terminated.  78
Nov 21 22:09:00 (none) daemon.notice syslog: pppd:Connect time 3063.4 minutes.  84
Nov 21 22:09:00 (none) daemon.notice syslog: pppd:Sent 87813633 bytes, received 1065631183 bytes. 103
Nov 21 22:09:03 (none) daemon.notice syslog: PPP: Start to connect ...  76
Nov 21 22:09:04 (none) daemon.err dhcp6c[17789]: client6_send: transmit failed: Network is unreachable 108
Nov 21 22:09:05 (none) daemon.warn kernel: netdev path : pppoa0 -> atm0  77
Nov 21 22:09:05 (none) daemon.notice syslog: pppd:Using interface pppoa0  78
Nov 21 22:09:05 (none) daemon.notice syslog: pppd:Connect: pppoa0 <-->   77
Nov 21 22:09:13 (none) daemon.info syslog: sending OFFER to IP:192.168.1.111, MAC:6c:4d:73:c5:2b:44 105
Nov 21 22:09:14 (none) daemon.notice syslog: pppd:LCP: timeout sending Config-Requests  92
Nov 21 22:09:14 (none) daemon.notice syslog: pppd:Connection terminated.  78
Nov 21 22:09:14 (none) daemon.info syslog: sending OFFER to IP:192.168.1.111, MAC:6c:4d:73:c5:2b:44 105
Nov 21 22:09:15 (none) daemon.info syslog: sending ACK to IP:192.168.1.111, MAC:6c:4d:73:c5:2b:44 103
Nov 21 22:09:17 (none) daemon.notice syslog: PPP: Start to connect ...  76
Nov 21 22:09:18 (none) daemon.warn kernel: netdev path : pppoa0 -> atm0  77
Nov 21 22:09:18 (none) daemon.notice syslog: pppd:Using interface pppoa0  78
Nov 21 22:09:18 (none) daemon.notice syslog: pppd:Connect: pppoa0 <-->   77
Nov 21 22:09:28 (none) daemon.notice syslog: pppd:LCP: timeout sending Config-Requests  92
Nov 21 22:09:28 (none) daemon.notice syslog: pppd:Connection terminated.  78
Nov 21 22:09:31 (none) daemon.notice syslog: PPP: Start to connect ...  76
Nov 21 22:09:32 (none) daemon.warn kernel: netdev path : pppoa0 -> atm0  77
Nov 21 22:09:32 (none) daemon.notice syslog: pppd:Using interface pppoa0  78
Nov 21 22:09:32 (none) daemon.notice syslog: pppd:Connect: pppoa0 <-->   77
Nov 21 22:09:34 (none) user.notice admin: PPP is DOWN  59
Nov 21 22:09:41 (none) daemon.notice syslog: pppd:LCP: timeout sending Config-Requests  92
Nov 21 22:09:41 (none) daemon.notice syslog: pppd:Connection terminated.  78
Nov 21 22:09:44 (none) daemon.notice syslog: PPP: Start to connect ...  76
Nov 21 22:09:46 (none) daemon.warn kernel: netdev path : pppoa0 -> atm0  77
Nov 21 22:09:46 (none) daemon.notice syslog: pppd:Using interface pppoa0  78
Nov 21 22:09:46 (none) daemon.notice syslog: pppd:Connect: pppoa0 <-->   77
Nov 21 22:09:55 (none) daemon.notice syslog: pppd:LCP: timeout sending Config-Requests  92
Nov 21 22:09:55 (none) daemon.notice syslog: pppd:Connection terminated.  78
Nov 21 22:09:58 (none) daemon.notice syslog: PPP: Start to connect ...  76
Nov 21 22:09:59 (none) daemon.warn kernel: netdev path : pppoa0 -> atm0  77
Nov 21 22:09:59 (none) daemon.notice syslog: pppd:Using interface pppoa0  78
Nov 21 22:09:59 (none) daemon.notice syslog: pppd:Connect: pppoa0 <-->   77
Nov 21 22:10:09 (none) daemon.notice syslog: pppd:LCP: timeout sending Config-Requests  92
Nov 21 22:10:09 (none) daemon.notice syslog: pppd:Connection terminated.  78
Nov 21 22:10:12 (none) daemon.notice syslog: PPP: Start to connect ...  76
Nov 21 22:10:13 (none) daemon.warn kernel: netdev path : pppoa0 -> atm0  77
Nov 21 22:10:13 (none) daemon.notice syslog: pppd:Using interface pppoa0  78
Nov 21 22:10:13 (none) daemon.notice syslog: pppd:Connect: pppoa0 <-->   77
Nov 21 22:10:22 (none) daemon.notice syslog: pppd:LCP: timeout sending Config-Requests  92
Nov 21 22:10:22 (none) daemon.notice syslog: pppd:Connection terminated.  78
Nov 21 22:10:25 (none) daemon.notice syslog: PPP: Start to connect ...  76
Nov 21 22:10:27 (none) daemon.warn kernel: netdev path : pppoa0 -> atm0  77
Nov 21 22:10:27 (none) daemon.notice syslog: pppd:Using interface pppoa0  78
Nov 21 22:10:27 (none) daemon.notice syslog: pppd:Connect: pppoa0 <-->   77
Nov 21 22:10:36 (none) daemon.notice syslog: pppd:LCP: timeout sending Config-Requests  92
Nov 21 22:10:36 (none) daemon.notice syslog: pppd:Connection terminated.  78
Nov 21 22:10:39 (none) daemon.notice syslog: PPP: Start to connect ...  76
Nov 21 22:10:40 (none) daemon.warn kernel: netdev path : pppoa0 -> atm0  77
Nov 21 22:10:40 (none) daemon.notice syslog: pppd:Using interface pppoa0  78
Nov 21 22:10:40 (none) daemon.notice syslog: pppd:Connect: pppoa0 <-->   77
Nov 21 22:10:50 (none) daemon.notice syslog: pppd:LCP: timeout sending Config-Requests  92
Nov 21 22:10:50 (none) daemon.notice syslog: pppd:Connection terminated.  78
Nov 21 22:10:53 (none) daemon.notice syslog: PPP: Start to connect ...  76
Nov 21 22:10:54 (none) daemon.warn kernel: netdev path : pppoa0 -> atm0  77
Nov 21 22:10:54 (none) daemon.notice syslog: pppd:Using interface pppoa0  78
Nov 21 22:10:54 (none) daemon.notice syslog: pppd:Connect: pppoa0 <-->   77
Nov 21 22:11:03 (none) daemon.notice syslog: pppd:LCP: timeout sending Config-Requests  92
Nov 21 22:11:03 (none) daemon.notice syslog: pppd:Connection terminated.  78
Nov 21 22:11:06 (none) daemon.notice syslog: PPP: Start to connect ...  76
Nov 21 22:11:08 (none) daemon.warn kernel: netdev path : pppoa0 -> atm0  77
Nov 21 22:11:08 (none) daemon.notice syslog: pppd:Using interface pppoa0  78
Nov 21 22:11:08 (none) daemon.notice syslog: pppd:Connect: pppoa0 <-->   77
Nov 21 22:11:17 (none) daemon.notice syslog: pppd:LCP: timeout sending Config-Requests  92
Nov 21 22:11:17 (none) daemon.notice syslog: pppd:Connection terminated.  78
Nov 21 22:11:20 (none) daemon.notice syslog: PPP: Start to connect ...  76
Nov 21 22:11:21 (none) daemon.warn kernel: netdev path : pppoa0 -> atm0  77
Nov 21 22:11:21 (none) daemon.notice syslog: pppd:Using interface pppoa0  78
Nov 21 22:11:21 (none) daemon.notice syslog: pppd:Connect: pppoa0 <-->   77
Nov 21 22:11:30 (none) daemon.notice syslog: pppd:LCP: timeout sending Config-Requests  92
Nov 21 22:11:30 (none) daemon.notice syslog: pppd:Connection terminated.  78
Nov 21 22:11:34 (none) daemon.notice syslog: PPP: Start to connect ...  76
Nov 21 22:11:34 (none) user.notice admin: PPP still down - kicking ADSL  77
Nov 21 22:11:34 (none) daemon.crit kernel: Line 0: ADSL link down  71
Nov 21 22:11:34 (none) daemon.warn kernel: bcmxtmcfg: XTM Link Information, port = 0, State = DOWN, Service Support = ATM  128
Nov 21 22:11:34 (none) daemon.warn kernel: bcmxtmrt: DSD(890b151c) - 128   79
Nov 21 22:11:34 (none) daemon.warn kernel: HOST XTM tx ch 0 disabled.  75
Nov 21 22:11:34 (none) daemon.warn kernel: bcmxtmcfg: Connection DOWN, LinkActiveStatus=0x0   98
Nov 21 22:11:34 (none) daemon.notice syslog: pppd:Terminating on signal 20.  81
Nov 21 22:11:35 (none) daemon.warn kernel: netdev path : pppoa0 -> atm0  77
Nov 21 22:11:35 (none) daemon.notice syslog: pppd:Using interface pppoa0  78
Nov 21 22:11:35 (none) daemon.notice syslog: pppd:Connect: pppoa0 <-->   77
Nov 21 22:11:57 (none) daemon.crit kernel: Line 0: ADSL G.992 started  75
Nov 21 22:12:04 (none) daemon.crit kernel: Line 0: ADSL G.992 channel analysis  84
Nov 21 22:12:10 (none) daemon.crit kernel: Line 0: ADSL G.992 message exchange  84
Nov 21 22:12:10 (none) daemon.crit kernel: Line 0: ADSL link up, Bearer 0, us=756, ds=6478  96
Nov 21 22:12:10 (none) daemon.warn kernel: bcmxtmcfg: XTM Link Information, port = 0, State = UP, Service Support = ATM  126
Nov 21 22:12:10 (none) daemon.warn kernel: bcmxtmrt: MAC address: 60 03 47 27 a8 1d  89
Nov 21 22:12:10 (none) daemon.warn kernel: [DoCreateDeviceReq.3269]: register_netdev  90
Nov 21 22:12:11 (none) daemon.warn kernel: [DoCreateDeviceReq.3271]: register_netdev done  95
Nov 21 22:12:11 (none) daemon.warn kernel: [FAP0] xtmCreateDevice : devId 0, encapType 1, headerLen 0 107
Nov 21 22:12:11 (none) daemon.warn kernel: bcmxtmrt: DSD(890bacdc) - 128   79
Nov 21 22:12:11 (none) daemon.warn kernel: XTM Init: 400 tx BDs at 0xaa70c000  83
Nov 21 22:12:11 (none) daemon.warn kernel: bcmxtmcfg: Connection UP, LinkActiveStatus=0x1, US=756000, DS=6478000  119
Nov 21 22:12:11 (none) daemon.warn kernel: [FAP0] xtmLinkUp : devId 0, matchId 0  86
Nov 21 22:12:11 (none) daemon.notice syslog: pppd:cms logging initialized.  80
Nov 21 22:12:11 (none) daemon.notice syslog: pppd:PPPoATM setdevname_pppoatm  82
Nov 21 22:12:11 (none) daemon.notice syslog: pppd:PPPoATM setdevname_pppoatm - SUCCESS  92
Nov 21 22:12:11 (none) daemon.notice syslog: pppd 2.4.1 started by admin, uid 0  85
Nov 21 22:12:11 (none) daemon.notice syslog: PPP: Start to connect ...  76
Nov 21 22:12:11 (none) daemon.warn kernel: netdev path : pppoa0  69
Nov 21 22:12:11 (none) daemon.info kernel:  -> atm0  57
Nov 21 22:12:11 (none) daemon.notice syslog: pppd:Using interface pppoa0  78
Nov 21 22:12:11 (none) daemon.notice syslog: pppd:Connect: pppoa0 <-->   77
Nov 21 22:12:19 (none) daemon.crit syslog: PPP LCP UP.  60
Nov 21 22:12:20 (none) daemon.notice syslog: pppd:Remote message: CHAP authentication success  99
Nov 21 22:12:20 (none) daemon.notice syslog: pppd:local  LL address fe80::c4ea:55cc:a8e1:ec04  99
Nov 21 22:12:20 (none) daemon.notice syslog: pppd:remote LL address fe80::12e8:78ff:fe86:7f51  99
Nov 21 22:12:20 (none) daemon.notice syslog: pppd:local  IP address 86.184.151.220  88
Nov 21 22:12:20 (none) daemon.notice syslog: pppd:remote IP address 172.16.19.48  86
Nov 21 22:12:20 (none) daemon.notice syslog: pppd:primary   DNS address 81.139.57.100  91
Nov 21 22:12:20 (none) daemon.notice syslog: pppd:secondary DNS address 81.139.56.100  91
Nov 21 22:12:20 (none) daemon.crit syslog: Received valid IP address from server.  Connection UP. 103
Nov 21 22:12:21 (none) daemon.info syslog: Hop limit                : 64  78
Nov 21 22:12:21 (none) daemon.info syslog: Stateful address conf.    : Yes  80
Nov 21 22:12:21 (none) daemon.info syslog: Stateful other conf.      : No  79
Nov 21 22:12:21 (none) daemon.info syslog: Router preference        : medium  82
Nov 21 22:12:21 (none) daemon.info syslog: Router lifetime           : 4500 seconds  89
Nov 21 22:12:21 (none) daemon.info syslog: Reachable time           : 0 milliseconds  90
Nov 21 22:12:21 (none) daemon.info syslog: Retransmit time          : 0 milliseconds  90
Nov 21 22:12:27 (none) daemon.warn kernel: ^[[0;36;44mBroadcom Packet Flow Cache flushing the flows^[[0m 110
Nov 21 22:12:27 (none) daemon.info syslog: dhcp6c restart..  65
Nov 21 22:49:37 (none) daemon.info syslog: sending OFFER to IP:192.168.1.106, MAC:0c:89:10:03:9d:51 105
Nov 21 22:49:37 (none) daemon.info syslog: sending ACK to IP:192.168.1.106, MAC:0c:89:10:03:9d:51 103
Nov 21 23:10:16 (none) daemon.info syslog: sending ACK to IP:192.168.1.104, MAC:f8:1e:df:ee:15:5c 103

pppd:LCP: timeout sending Config-Requests , means the router is sending LCP config requests to the exchange and the exchange is not replying.

Try setting your WAN connection to PPPoE
judge63
Posts: 6
Joined: Fri Nov 17, 2017 7:27 pm

Re: 7800DXL needs manual recovery from dropped connection

Post by judge63 »

Gave that a quick try, but didn't come up. PPP complaining about some channel problem. I'll look into it more at the weekend.

Is there a known issue with BT exchange equipment which causes LCP to fail with PPPoA encap?

Thx.
billion_fan
Posts: 5374
Joined: Tue Jul 19, 2011 4:30 pm

Re: 7800DXL needs manual recovery from dropped connection

Post by billion_fan »

judge63 wrote: Thu Nov 23, 2017 8:29 am Gave that a quick try, but didn't come up. PPP complaining about some channel problem. I'll look into it more at the weekend.

Is there a known issue with BT exchange equipment which causes LCP to fail with PPPoA encap?

Thx.
Not that I am aware of, it something must of changed when they upgraded your exchange
judge63
Posts: 6
Joined: Fri Nov 17, 2017 7:27 pm

Re: 7800DXL needs manual recovery from dropped connection

Post by judge63 »

connected using pppoe now. Will report back on whether it solves the problem...
judge63
Posts: 6
Joined: Fri Nov 17, 2017 7:27 pm

Re: 7800DXL needs manual recovery from dropped connection

Post by judge63 »

Unfortunately pppoe did not help. Connection has gone down twice and has required manual recovery. Log has lost when it first went down, but can see it is looping trying to unsuccessfully restart, before finally I pulled the cable from the wall to recover.

Code: Select all

Nov 27 22:34:16 (none) daemon.notice syslog: PPP: Start to connect ...  76
Nov 27 22:34:43 (none) daemon.err syslog: pppd:Couldn't get channel number: Transport endpoint is not connected 117
Nov 27 22:34:43 (none) daemon.notice syslog: pppd:Couldn't reset tty to normal line discipline: Inappropriate ioctl for device 132
Nov 27 22:34:43 (none) daemon.notice syslog: pppd:Doing disconnect  72
Nov 27 22:34:46 (none) daemon.notice syslog: PPP: Start to connect ...  76
Nov 27 22:35:13 (none) daemon.err syslog: pppd:Couldn't get channel number: Transport endpoint is not connected 117
Nov 27 22:35:13 (none) daemon.notice syslog: pppd:Couldn't reset tty to normal line discipline: Inappropriate ioctl for device 132
Nov 27 22:35:13 (none) daemon.notice syslog: pppd:Doing disconnect  72
Nov 27 22:35:16 (none) daemon.notice syslog: PPP: Start to connect ...  76
Nov 27 22:35:20 (none) daemon.info syslog: sending OFFER to IP:192.168.1.107, MAC:f8:1e:df:ee:15:5c 105
Nov 27 22:35:21 (none) daemon.info syslog: sending ACK to IP:192.168.1.107, MAC:f8:1e:df:ee:15:5c 103
Nov 27 22:35:43 (none) daemon.err syslog: pppd:Couldn't get channel number: Transport endpoint is not connected 117
Nov 27 22:35:43 (none) daemon.notice syslog: pppd:Couldn't reset tty to normal line discipline: Inappropriate ioctl for device 132
Nov 27 22:35:43 (none) daemon.notice syslog: pppd:Doing disconnect  72
Nov 27 22:35:46 (none) daemon.notice syslog: PPP: Start to connect ...  76
Nov 27 22:36:13 (none) daemon.err syslog: pppd:Couldn't get channel number: Transport endpoint is not connected 117
Nov 27 22:36:13 (none) daemon.notice syslog: pppd:Couldn't reset tty to normal line discipline: Inappropriate ioctl for device 132
Nov 27 22:36:13 (none) daemon.notice syslog: pppd:Doing disconnect  72
Nov 27 22:36:16 (none) daemon.notice syslog: PPP: Start to connect ...  76
Nov 27 22:36:44 (none) daemon.err syslog: pppd:Couldn't get channel number: Transport endpoint is not connected 117
Nov 27 22:36:44 (none) daemon.notice syslog: pppd:Couldn't reset tty to normal line discipline: Inappropriate ioctl for device 132
Nov 27 22:36:44 (none) daemon.notice syslog: pppd:Doing disconnect  72
Nov 27 22:36:47 (none) daemon.notice syslog: PPP: Start to connect ...  76
Nov 27 22:37:00 (none) daemon.crit kernel: Line 0: ADSL link down  71
Nov 27 22:37:00 (none) daemon.warn kernel: bcmxtmcfg: XTM Link Information, port = 0, State = DOWN, Service Support = ATM  128
Nov 27 22:37:00 (none) daemon.warn kernel: bcmxtmrt: DSD(8907ccdc) - 128   79
Nov 27 22:37:00 (none) daemon.warn kernel: HOST XTM tx ch 0 disabled.  75
Nov 27 22:37:00 (none) daemon.warn kernel: bcmxtmcfg: Connection DOWN, LinkActiveStatus=0x0   98
Nov 27 22:37:01 (none) daemon.notice syslog: pppd:Terminating on signal 20.  81
Nov 27 22:37:06 (none) daemon.info kernel: device atm0 left promiscuous mode  82
Nov 27 22:37:06 (none) daemon.info kernel: device atm0 entered promiscuous mode  85
Nov 27 22:37:06 (none) daemon.warn kernel: ^[[0;33;41m[ERROR vlan] bcmVlan_devHardStartXmit,589: Internal Error: Could not find Real Device atm0 on Transmission^[[0m 171
Nov 27 22:37:06 (none) daemon.info kernel: device atm0 left promiscuous mode  82
Nov 27 22:37:13 (none) daemon.crit kernel: Line 0: ADSL G.992 started  75
Nov 27 22:37:20 (none) daemon.crit kernel: Line 0: ADSL G.992 channel analysis  84
Nov 27 22:37:26 (none) daemon.crit kernel: Line 0: ADSL G.992 message exchange  84
Nov 27 22:37:27 (none) daemon.crit kernel: Line 0: ADSL link up, Bearer 0, us=756, ds=6670  96
Nov 27 22:37:27 (none) daemon.warn kernel: bcmxtmcfg: XTM Link Information, port = 0, State = UP, Service Support = ATM  126
Nov 27 22:37:27 (none) daemon.warn kernel: bcmxtmrt: MAC address: 60 03 47 27 a8 1d  89
Nov 27 22:37:27 (none) daemon.warn kernel: [DoCreateDeviceReq.3269]: register_netdev  90
Nov 27 22:37:27 (none) daemon.warn kernel: [DoCreateDeviceReq.3271]: register_netdev done  95
Nov 27 22:37:27 (none) daemon.warn kernel: [FAP0] xtmCreateDevice : devId 0, encapType 0, headerLen 10 108
Nov 27 22:37:27 (none) daemon.warn kernel: bcmxtmrt: DSD(8a7d0d3c) - 128   79
Nov 27 22:37:27 (none) daemon.warn kernel: XTM Init: 400 tx BDs at 0xa9104000  83
Nov 27 22:37:27 (none) daemon.warn kernel: bcmxtmcfg: Connection UP, LinkActiveStatus=0x1, US=756000, DS=6670000  119
Nov 27 22:37:27 (none) daemon.warn kernel: [FAP0] xtmLinkUp : devId 0, matchId 0  86
Nov 27 22:37:27 (none) daemon.warn kernel: atm0.1 MAC address set to 60:03:47:27:A8:1E  92
Nov 27 22:37:27 (none) daemon.warn kernel: netdev path :   63
Nov 27 22:37:27 (none) daemon.info kernel: atm0.1 -> atm0  63
Nov 27 22:37:27 (none) daemon.warn kernel: BCMVLAN : atm0 mode was set to RG  82
Nov 27 22:37:27 (none) daemon.info kernel: device atm0 entered promiscuous mode  85
Nov 27 22:37:27 (none) daemon.notice syslog: pppd:cms logging initialized.  80
Nov 27 22:37:27 (none) daemon.notice syslog: pppd 2.4.1 started by admin, uid 0  85
Nov 27 22:37:27 (none) daemon.notice syslog: PPP: Start to connect ...  76
Nov 27 22:37:32 (none) daemon.crit syslog: PPP server detected.  69
Nov 27 22:37:32 (none) daemon.crit syslog: PPP session established.  73
Nov 27 22:37:32 (none) daemon.warn kernel: netdev path : ppp0.1 -> atm0.1 -> atm0  87
Nov 27 22:37:32 (none) daemon.notice syslog: pppd:Using interface ppp0.1  78
Nov 27 22:37:32 (none) daemon.notice syslog: pppd:Connect: ppp0.1 <--> atm0.1  83
Nov 27 22:37:32 (none) daemon.notice syslog: pppd:Couldn't increase MTU to 1500.  86
Nov 27 22:37:32 (none) daemon.err syslog: pppd:Couldn't increase MRU to 1500  82
Nov 27 22:37:32 (none) daemon.err syslog: pppd:Couldn't increase MRU to 1500  82
Nov 27 22:37:32 (none) daemon.crit syslog: PPP LCP UP.  60
Nov 27 22:37:32 (none) daemon.notice syslog: pppd:Warning - secret file /etc/ppp/chap-secrets has world and/or group access 129
Nov 27 22:37:32 (none) daemon.notice syslog: pppd:No CHAP secret found for authenticating us to acc-aln18.eh-don 118
Nov 27 22:37:32 (none) daemon.notice syslog: pppd:Remote message: CHAP authentication success  99
Nov 27 22:37:32 (none) daemon.notice syslog: pppd:local  LL address fe80::f01d:9dc6:20da:2d76  99
Nov 27 22:37:32 (none) daemon.notice syslog: pppd:remote LL address fe80::12e8:78ff:fe86:7f51  99
Nov 27 22:37:32 (none) daemon.notice syslog: pppd:local  IP address 86.131.75.78  86
Nov 27 22:37:32 (none) daemon.notice syslog: pppd:remote IP address 172.16.19.48  86
Nov 27 22:37:32 (none) daemon.notice syslog: pppd:primary   DNS address 81.139.57.100  91
Nov 27 22:37:32 (none) daemon.notice syslog: pppd:secondary DNS address 81.139.56.100  91
Nov 27 22:37:32 (none) daemon.crit syslog: Received valid IP address from server.  Connection UP. 103
Nov 27 22:37:33 (none) daemon.info syslog: Hop limit                : 64  78
Nov 27 22:37:33 (none) daemon.info syslog: Stateful address conf.    : Yes  80
Nov 27 22:37:33 (none) daemon.info syslog: Stateful other conf.      : No  79
Nov 27 22:37:33 (none) daemon.info syslog: Router preference        : medium  82
Nov 27 22:37:33 (none) daemon.info syslog: Router lifetime           : 4500 seconds  89
Nov 27 22:37:33 (none) daemon.info syslog: Reachable time           : 0 milliseconds  90
Nov 27 22:37:33 (none) daemon.info syslog: Retransmit time          : 0 milliseconds  90
Nov 27 22:37:40 (none) daemon.warn kernel: ^[[0;36;44mBroadcom Packet Flow Cache flushing the flows^[[0m 110
Nov 27 22:37:41 (none) daemon.info syslog: dhcp6c restart..  65
I guess will go back to pppoa + the background script...
Post Reply