Guest User!

You are not Sophos Staff.

Thread Info
  • State Not Answered
  • Replies 4 replies
  • Subscribers 0 subscribers
  • Views 1380 views
  • Users 0 members are here
Options
Suggested

Strange DSL Issue

BrucekConvergent
Running 7.075, I've noted that my DSL connection has quit working a couple of times per day.  There's no problem with the line (sync is good, etc.), and connecting the DSL Modem directly to a Computer and using PPOE there works fine... the Astaro for some reason can't establish a PPOE connection.  This happens fairly randomly... on 7.011, never had a problem.  Pretty strange... rebooting the whole unit fixes it.  Manual reconnects do not work.  Here's an excerpt from the PPOE log:

2007:11:13-14:33:36 (none) pppd-pppoe[3710]: Script /etc/ppp/ip-down started (pid 20059)
2007:11:13-14:33:36 (none) pppd-pppoe[3710]: sent [LCP TermReq id=0x3 "Peer not responding"]
2007:11:13-14:33:37 (none) pppd-pppoe[3710]: Script /etc/ppp/ip-down finished (pid 20059), status = 0x0
2007:11:13-14:33:39 (none) pppd-pppoe[3710]: sent [LCP TermReq id=0x4 "Peer not responding"]
2007:11:13-14:33:42 (none) pppd-pppoe[3710]: Connection terminated.
2007:11:13-14:33:42 (none) pppd-pppoe[3710]: Modem hangup
2007:11:13-14:33:42 (none) pppd-pppoe[3710]: sending SIGHUP to process 3718
2007:11:13-14:33:42 (none) pppd-pppoe[3710]: Waiting for 1 child processes...
2007:11:13-14:33:42 (none) pppd-pppoe[3710]:   script /usr/sbin/pppoe -I eth1 -m 1452, pid 3718
2007:11:13-14:33:42 (none) pppoe[3718]: Received signal 1 on session 4131.
2007:11:13-14:33:42 (none) pppoe[3718]: Sent PADT
2007:11:13-14:33:42 (none) pppd-pppoe[3710]: Script /usr/sbin/pppoe -I eth1 -m 1452 finished (pid 3718), status = 0x0
2007:11:13-14:33:42 (none) pppd-pppoe[3710]: Exit.
2007:11:13-14:34:57 (none) pppoe[20340]: Timeout waiting for PADO packets
2007:11:13-14:34:57 (none) pppoe-sh: Can not connect DSL AC - retry in 60 seconds
2007:11:13-14:36:12 (none) pppoe[20391]: Timeout waiting for PADO packets
2007:11:13-14:36:12 (none) pppoe-sh: Can not connect DSL AC - retry in 60 seconds
2007:11:13-14:36:51 (none) pppoe[20493]: Timeout waiting for PADO packets
2007:11:13-14:36:51 (none) pppoe-sh: Can not connect DSL AC - retry in 60 seconds
2007:11:13-14:38:06 (none) pppoe[20577]: Timeout waiting for PADO packets
2007:11:13-14:38:06 (none) pppoe-sh: Can not connect DSL AC - retry in 60 seconds
2007:11:13-14:39:21 (none) pppoe[20693]: Timeout waiting for PADO packets
2007:11:13-14:39:21 (none) pppoe-sh: Can not connect DSL AC - retry in 60 seconds
2007:11:13-14:42:23 (none) pppoe[3581]: Timeout waiting for PADO packets
2007:11:13-14:42:24 (none) pppoe-sh: Can not connect DSL AC - retry in 60 seconds
Parents
  • 0
    Hi,


    Running 7.075, I've noted that my DSL connection has quit working a couple of times per day.  There's no problem with the line (sync is good, etc.), and connecting the DSL Modem directly to a Computer and using PPOE there works fine... the Astaro for some reason can't establish a PPOE connection.  This happens fairly randomly... on 7.011, never had a problem.  Pretty strange... rebooting the whole unit fixes it.  Manual reconnects do not work.


    Thanks for the report. Unfortunately, it is not quite possible to diagnose the issue properly with the provided information:

    Here's an excerpt from the PPOE log:

    2007:11:13-14:33:36 (none) pppd-pppoe[3710]: Script /etc/ppp/ip-down started (pid 20059)
    2007:11:13-14:33:36 (none) pppd-pppoe[3710]: sent [LCP TermReq id=0x3 "Peer not responding"]
    2007:11:13-14:33:37 (none) pppd-pppoe[3710]: Script /etc/ppp/ip-down finished (pid 20059), status = 0x0
    2007:11:13-14:33:39 (none) pppd-pppoe[3710]: sent [LCP TermReq id=0x4 "Peer not responding"]
    2007:11:13-14:33:42 (none) pppd-pppoe[3710]: Connection terminated.
    2007:11:13-14:33:42 (none) pppd-pppoe[3710]: Modem hangup
    2007:11:13-14:33:42 (none) pppd-pppoe[3710]: sending SIGHUP to process 3718
    2007:11:13-14:33:42 (none) pppd-pppoe[3710]: Waiting for 1 child processes...
    2007:11:13-14:33:42 (none) pppd-pppoe[3710]:   script /usr/sbin/pppoe -I eth1 -m 1452, pid 3718
    2007:11:13-14:33:42 (none) pppoe[3718]: Received signal 1 on session 4131.
    2007:11:13-14:33:42 (none) pppoe[3718]: Sent PADT
    2007:11:13-14:33:42 (none) pppd-pppoe[3710]: Script /usr/sbin/pppoe -I eth1 -m 1452 finished (pid 3718), status = 0x0
    2007:11:13-14:33:42 (none) pppd-pppoe[3710]: Exit.


    Here, the previous connection was considered dead for some unknown reason, and the session cleanly shut down.

    2007:11:13-14:34:57 (none) pppoe[20340]: Timeout waiting for PADO packets
    2007:11:13-14:34:57 (none) pppoe-sh: Can not connect DSL AC - retry in 60 seconds
    2007:11:13-14:36:12 (none) pppoe[20391]: Timeout waiting for PADO packets
    2007:11:13-14:36:12 (none) pppoe-sh: Can not connect DSL AC - retry in 60 seconds
    2007:11:13-14:36:51 (none) pppoe[20493]: Timeout waiting for PADO packets
    2007:11:13-14:36:51 (none) pppoe-sh: Can not connect DSL AC - retry in 60 seconds
    2007:11:13-14:38:06 (none) pppoe[20577]: Timeout waiting for PADO packets
    2007:11:13-14:38:06 (none) pppoe-sh: Can not connect DSL AC - retry in 60 seconds
    2007:11:13-14:39:21 (none) pppoe[20693]: Timeout waiting for PADO packets
    2007:11:13-14:39:21 (none) pppoe-sh: Can not connect DSL AC - retry in 60 seconds
    2007:11:13-14:42:23 (none) pppoe[3581]: Timeout waiting for PADO packets
    2007:11:13-14:42:24 (none) pppoe-sh: Can not connect DSL AC - retry in 60 seconds


    Some time later (longer than the ~25 seconds that it takes for a PAD0 timeout to occur), a new login attempt is made and fails. So, it's fairly certain that it's not two PPPoE sessions fighting over resources. At this point, it could be anything/coincidence, including loose cabling and issues at the carrier.

    When the regular PC is connected to the DSL line, does it work long-term as well (and reconnect quickly, if necessary?)

    If this issue continues to look like a problem with the ASG, could you please mail me (mgrimm at astaro dot com) login credentials for this system? I would enable debug logging, restart the Middleware and check back once the problem can be observed again.


    Moritz
  • 0 in reply to mgrimm
    My PPPoE works without problem on this version.

    Bruce, does your DSL modem have any onboard diagnostics? It would be interesting to find out what your SNR, and ATTEN are, because it should not take that long for PAD0 requests.

    If you know what kind of DSL modem you have, I can possibly help you get access to the diagnostics (if it has any).

    This would rule out any problems on the ASG.
  • 0 in reply to ReD-MaN
    This is a system that I've had setup (at home) for a couple of years now, no problems until now... Signal looks good on the DSL modem... I'll just see if it happens anymore, may be just some bizarre coincidence ... running from the PC it ran for about an hour with no trouble, so I don't think it was the line, rather, a PPPOE issue... I'll just keep an eye on it, may just be coincidence.  Did anything change with PPPOE between 7.011 and 7.100?

    CTO, Convergent Information Security Solutions, LLC

    https://www.convergesecurity.com

    Advice given as posted on this forum does not construe a support relationship or other relationship with Convergent Information Security Solutions, LLC or its subsidiaries.  Use the advice given at your own risk.

  • 0 in reply to BrucekConvergent
    Hi all,

    there are no changes in ppp/pppoe in 7.1.

    PADO packets (PPPoE Active Discovery Offer) are send from the Access Concentrator (AC) as an answer for PADI (PPPoE Active Discovery Initiation) packets. The PADO packet contains the MAC address of the AC, its name and a session cookie - which is visible in the pppoe.log:

    2007:11:15-00:33:43 (none) pppoe[5181]: Timeout waiting for PADO packets
    2007:11:15-00:33:43 (none) pppoe-sh: Can not connect DSL AC - retry in 900 seconds
    2007:11:15-00:38:05 (none) pppoe-sh: Access-Concentrator: FRAC02-10k Got a cookie: 3b 84 2a 3b 0f 3d 7b 3e e5 ae 11 1e 67 21 c1 32 AC-Ethernet-Address: 00:19:e8:35:36:49 

    Missing PADO packets are always an indicator, that the AC is down/broken/unreachable if the DSL line is (physical) up.
    With tcpdump on the ethernet interface used for PPPoE you should be able to see the outgoing PADI packets.
Reply
  • 0 in reply to BrucekConvergent
    Hi all,

    there are no changes in ppp/pppoe in 7.1.

    PADO packets (PPPoE Active Discovery Offer) are send from the Access Concentrator (AC) as an answer for PADI (PPPoE Active Discovery Initiation) packets. The PADO packet contains the MAC address of the AC, its name and a session cookie - which is visible in the pppoe.log:

    2007:11:15-00:33:43 (none) pppoe[5181]: Timeout waiting for PADO packets
    2007:11:15-00:33:43 (none) pppoe-sh: Can not connect DSL AC - retry in 900 seconds
    2007:11:15-00:38:05 (none) pppoe-sh: Access-Concentrator: FRAC02-10k Got a cookie: 3b 84 2a 3b 0f 3d 7b 3e e5 ae 11 1e 67 21 c1 32 AC-Ethernet-Address: 00:19:e8:35:36:49 

    Missing PADO packets are always an indicator, that the AC is down/broken/unreachable if the DSL line is (physical) up.
    With tcpdump on the ethernet interface used for PPPoE you should be able to see the outgoing PADI packets.
Children
No Data
Share Feedback
×

Submitted a Tech Support Case lately from the Support Portal?