* Re: Starting dhcpcd terminates the established PPPoE connection
2016-12-27 23:26 Starting dhcpcd terminates the established PPPoE connection Sven Kirmess
@ 2016-12-28 17:20 ` James Carlson
2016-12-29 11:35 ` Sven Kirmess
` (3 subsequent siblings)
4 siblings, 0 replies; 6+ messages in thread
From: James Carlson @ 2016-12-28 17:20 UTC (permalink / raw)
To: linux-ppp
On 12/27/2016 6:26 PM, Sven Kirmess wrote:
>>From /var/log/ppp.log:
>
> Dec 25 02:26:56 kaldra pppd[8185]: rcvd [LCP TermReq id=0x2]
> Dec 25 02:26:56 kaldra pppd[8185]: LCP terminated by peer
> Dec 25 02:26:56 kaldra pppd[8185]: Connect time 1.0 minutes.
It wouldn't be a bad thing to see what led up to this point, but I
suspect there isn't much more information available on this side. A
good implementation of PPP uses the optional disconnection message in
LCP Terminate-Request to report the reason why the connection is being
dropped. Unfortunately, the PPP implementation used by your peer isn't
good, and it doesn't do that.
It's remotely possible that the disconnection is unrelated to DHCP and
that it's just coincidence. That's why I suggested posting more of the
logs. But I think it's just a very remote possibility.
I'm afraid that most likely means that you need to talk with your ISP
and find out if they have logs or error messages to share with you. My
experience with most of these ISPs, though, is that there's nobody home
at the other end, and the only thing you can really do is quit and find
a better one.
Your mileage may vary.
> Dec 25 02:26:57 kaldra pppd[8185]: Failed to disconnect PPPoE socket:
> 114 Operation already in progress
Besides this apparently spurious error message, did the PPPoE client log
any sort of message about the disconnection?
--
James Carlson 42.703N 71.076W <carlsonj@workingcode.com>
^ permalink raw reply [flat|nested] 6+ messages in thread* Re: Starting dhcpcd terminates the established PPPoE connection
2016-12-27 23:26 Starting dhcpcd terminates the established PPPoE connection Sven Kirmess
2016-12-28 17:20 ` James Carlson
@ 2016-12-29 11:35 ` Sven Kirmess
2016-12-29 20:37 ` James Carlson
` (2 subsequent siblings)
4 siblings, 0 replies; 6+ messages in thread
From: Sven Kirmess @ 2016-12-29 11:35 UTC (permalink / raw)
To: linux-ppp
Hi James,
Thanks for looking into this.
On Wed, Dec 28, 2016 at 6:20 PM, James Carlson <carlsonj@workingcode.com> wrote:
> It's remotely possible that the disconnection is unrelated to DHCP and
> that it's just coincidence. That's why I suggested posting more of the
> logs. But I think it's just a very remote possibility.
I've added the log files to dropbox. I've rebooted the system at 01:39
and at 01:59.
<https://www.dropbox.com/s/k6s70fzl7ee4o42/daemon.txt?dl=0>
<https://www.dropbox.com/s/1h6mqvcxvstvf4y/debug.txt?dl=0>
<https://www.dropbox.com/s/msg66lt19f95772/kern.txt?dl=0>
<https://www.dropbox.com/s/lnm6aw5fzskq0d4/ppp.txt?dl=0>
<https://www.dropbox.com/s/qxcx7wm4yj7qhr5/syslog.txt?dl=0>
I was able to bring the line back up after I've removed the ip-up
script that starts/stops dhcpcd.
> I'm afraid that most likely means that you need to talk with your ISP
> and find out if they have logs or error messages to share with you. My
> experience with most of these ISPs, though, is that there's nobody home
> at the other end, and the only thing you can really do is quit and find
> a better one.
>
> Your mileage may vary.
>
>> Dec 25 02:26:57 kaldra pppd[8185]: Failed to disconnect PPPoE socket:
>> 114 Operation already in progress
>
> Besides this apparently spurious error message, did the PPPoE client log
> any sort of message about the disconnection?
I can't see anything.
Sven
^ permalink raw reply [flat|nested] 6+ messages in thread* Re: Starting dhcpcd terminates the established PPPoE connection
2016-12-27 23:26 Starting dhcpcd terminates the established PPPoE connection Sven Kirmess
2016-12-28 17:20 ` James Carlson
2016-12-29 11:35 ` Sven Kirmess
@ 2016-12-29 20:37 ` James Carlson
2016-12-31 13:45 ` Sven Kirmess
2016-12-31 19:24 ` James Carlson
4 siblings, 0 replies; 6+ messages in thread
From: James Carlson @ 2016-12-29 20:37 UTC (permalink / raw)
To: linux-ppp
On 12/29/16 06:35, Sven Kirmess wrote:
> Hi James,
>
> Thanks for looking into this.
>
> On Wed, Dec 28, 2016 at 6:20 PM, James Carlson <carlsonj@workingcode.com> wrote:
>
>> It's remotely possible that the disconnection is unrelated to DHCP and
>> that it's just coincidence. That's why I suggested posting more of the
>> logs. But I think it's just a very remote possibility.
>
> I've added the log files to dropbox. I've rebooted the system at 01:39
> and at 01:59.
>
> <https://www.dropbox.com/s/k6s70fzl7ee4o42/daemon.txt?dl=0>
> <https://www.dropbox.com/s/1h6mqvcxvstvf4y/debug.txt?dl=0>
> <https://www.dropbox.com/s/msg66lt19f95772/kern.txt?dl=0>
> <https://www.dropbox.com/s/lnm6aw5fzskq0d4/ppp.txt?dl=0>
> <https://www.dropbox.com/s/qxcx7wm4yj7qhr5/syslog.txt?dl=0>
I don't see much that's special going on there. I still think the
problem is on the peer's side, not yours.
Does /libexec/dhcpcd-run-hooks do anything crazy, such as pkill?
--
James Carlson 42.703N 71.076W <carlsonj@workingcode.com>
^ permalink raw reply [flat|nested] 6+ messages in thread
* Re: Starting dhcpcd terminates the established PPPoE connection
2016-12-27 23:26 Starting dhcpcd terminates the established PPPoE connection Sven Kirmess
` (2 preceding siblings ...)
2016-12-29 20:37 ` James Carlson
@ 2016-12-31 13:45 ` Sven Kirmess
2016-12-31 19:24 ` James Carlson
4 siblings, 0 replies; 6+ messages in thread
From: Sven Kirmess @ 2016-12-31 13:45 UTC (permalink / raw)
To: linux-ppp
On Thu, Dec 29, 2016 at 9:37 PM, James Carlson <carlsonj@workingcode.com> wrote:
> I don't see much that's special going on there. I still think the
> problem is on the peer's side, not yours.
That's going to be much more difficult to get fixed.
> Does /libexec/dhcpcd-run-hooks do anything crazy, such as pkill?
I can't find anything.
Besides that, I've restarted dhcpcd and for now know it's working
again. Without a change on my side.
Thanks for your confirmation that it's probably not on my side.
^ permalink raw reply [flat|nested] 6+ messages in thread* Re: Starting dhcpcd terminates the established PPPoE connection
2016-12-27 23:26 Starting dhcpcd terminates the established PPPoE connection Sven Kirmess
` (3 preceding siblings ...)
2016-12-31 13:45 ` Sven Kirmess
@ 2016-12-31 19:24 ` James Carlson
4 siblings, 0 replies; 6+ messages in thread
From: James Carlson @ 2016-12-31 19:24 UTC (permalink / raw)
To: linux-ppp
On 12/31/2016 8:45 AM, Sven Kirmess wrote:
> On Thu, Dec 29, 2016 at 9:37 PM, James Carlson <carlsonj@workingcode.com> wrote:
>
>> I don't see much that's special going on there. I still think the
>> problem is on the peer's side, not yours.
>
> That's going to be much more difficult to get fixed.
>
>> Does /libexec/dhcpcd-run-hooks do anything crazy, such as pkill?
>
> I can't find anything.
>
> Besides that, I've restarted dhcpcd and for now know it's working
> again. Without a change on my side.
>
> Thanks for your confirmation that it's probably not on my side.
>
One possibly interesting note is that I see LCP being renegotiated after
starting authentication. That's a dead give-away that the other side
has a front-end device that tunnels the PPP traffic back to back-end
servers.
The reason it works sometimes and not at other times could be that you
sometimes hit a broken (or misconfigured) back-end server, and other
times you hit one that's ok.
It could also be that someone on their side noticed the errors and fixed
something. You could be just that lucky. :-/
--
James Carlson 42.703N 71.076W <carlsonj@workingcode.com>
^ permalink raw reply [flat|nested] 6+ messages in thread