From owner-freebsd-questions@FreeBSD.ORG Wed Dec 4 23:23:11 2013 Return-Path: Delivered-To: freebsd-questions@freebsd.org Received: from mx1.freebsd.org (mx1.freebsd.org [8.8.178.115]) (using TLSv1 with cipher ADH-AES256-SHA (256/256 bits)) (No client certificate requested) by hub.freebsd.org (Postfix) with ESMTPS id 704D46E0 for ; Wed, 4 Dec 2013 23:23:11 +0000 (UTC) Received: from relais.videotron.ca (relais.videotron.ca [24.201.245.36]) by mx1.freebsd.org (Postfix) with ESMTP id 45F04196D for ; Wed, 4 Dec 2013 23:23:10 +0000 (UTC) MIME-version: 1.0 Content-type: text/plain; charset=iso-8859-1 Received: from elsewhere.tellme3times ([24.37.212.120]) by VL-VM-MR002.ip.videotron.ca (Oracle Communications Messaging Exchange Server 7u4-22.01 64bit (built Apr 21 2011)) with ESMTP id <0MXB001IB2AME600@VL-VM-MR002.ip.videotron.ca> for freebsd-questions@freebsd.org; Wed, 04 Dec 2013 18:23:10 -0500 (EST) Received: from [192.168.7.61] (unknown [192.168.7.61]) by elsewhere.tellme3times (Postfix) with ESMTP id 025DB3981A; Wed, 04 Dec 2013 18:23:10 -0500 (EST) Subject: Re: PPPoE help From: Chris In-reply-to: <529FA253.2000900@sentex.net> Date: Wed, 04 Dec 2013 18:23:09 -0500 Content-transfer-encoding: quoted-printable Message-id: References: <529FA253.2000900@sentex.net> To: Mike Tancsa X-Mailer: Apple Mail (2.1822) Cc: freebsd-questions X-BeenThere: freebsd-questions@freebsd.org X-Mailman-Version: 2.1.17 Precedence: list List-Id: User questions List-Unsubscribe: , List-Archive: List-Post: List-Help: List-Subscribe: , X-List-Received-Date: Wed, 04 Dec 2013 23:23:11 -0000 I have a workaround that I've been using for a long time which works = great for me and some clients who can only connect with PPPoE It can be found here; http://logicsquad.net/freebsd/pingmonitor-how-to.html You don't have to reinvent your own solution. It would be great if ppp = would reset when the connection gets "jammed" Chris chris@lrckinfo.com --------------------------------------------------------------- 514.884.8185 320, rue de la Fenaison Varennes, Qu=E9bec, J3X 2H2 --------------------------------------------------------------- http://www.lrckinfo.com On Dec 4, 2013, at 4:44 PM, Mike Tancsa wrote: > Googling around this problem occasionally comes up, but I have yet to > find a definitive answer. An ISP is changing out their LACs and when > they are doing a hot cut, this sometimes messes up the pppoe ppp = process > so it gets stuck in a loop and never recovers. Killing off the ppp > process and restarts it works and all is fixed. >=20 > But this still on rare occasion will come up. Does anyone know the > cause or work around ? I did manage to catch one and up the debug = logs. >=20 > None debug looks like >=20 > Nov 26 15:30:01 s0332 ppp[1620]: tun1: IPCP: deflink: = RecvConfigReq(10) > state =3D Initial > Nov 26 15:30:01 s0332 ppp[1620]: tun1: IPCP: IPADDR[6] xx.yy.128.14 > Nov 26 15:30:01 s0332 ppp[1620]: tun1: IPCP: deflink: Oops, RCR in = Initial. > Nov 26 15:30:03 s0332 ppp[1620]: tun1: IPCP: deflink: = RecvConfigReq(11) > state =3D Initial > Nov 26 15:30:03 s0332 ppp[1620]: tun1: IPCP: IPADDR[6] xx.yy.128.14 > Nov 26 15:30:03 s0332 ppp[1620]: tun1: IPCP: deflink: Oops, RCR in = Initial. > Nov 26 15:30:04 s0332 ppp[1620]: tun1: LCP: deflink: > RecvEchoRequest(174) state =3D Opened > Nov 26 15:30:04 s0332 ppp[1620]: tun1: LCP: deflink: = SendEchoReply(174) > state =3D Opened > Nov 26 15:30:05 s0332 ppp[1620]: tun1: IPCP: deflink: = RecvConfigReq(12) > state =3D Initial >=20 >=20 > Config is simple >=20 >=20 > pppoe: > add 10.6.153.2 HISADDR > add default HISADDR > set device PPPoE:vr0 > set server /var/run/spdsl-internet "" 0177 > set speed sync > enable echo > disable ipv6cp > disable vjcomp > set cd 15 > set dial > set login > set timeout 0 > set lqrperiod 10 > set authname s0332@realm > set authkey xxxxxxxx > set ifaddr 10.0.0.1/0 10.0.0.2/0 255.255.255.0 255.255.255.0 >=20 > With debugging >=20 > Nov 26 16:53:20 s0332 ppp[1620]: tun1: Timer: Select returns 1 > Nov 26 16:53:20 s0332 ppp[1620]: tun1: Physical: read > Nov 26 16:53:20 s0332 ppp[1620]: tun1: Physical: 80 21 01 97 00 0a 03 > 06 43 2b 80 0e .!......C+.. > Nov 26 16:53:20 s0332 ppp[1620]: tun1: Debug: deflink: DescriptorRead: > read 12/2048 from 1 > Nov 26 16:53:20 s0332 ppp[1620]: tun1: Sync: Read > Nov 26 16:53:20 s0332 ppp[1620]: tun1: Sync: 80 21 01 97 00 0a 03 06 = 43 > 2b 80 0e .!......C+.. > Nov 26 16:53:20 s0332 ppp[1620]: tun1: Debug: proto_LayerPull: unknown > -> 0x8021 > Nov 26 16:53:20 s0332 ppp[1620]: tun1: Debug: link_PullPacket: = Despatch > proto 0x8021 > Nov 26 16:53:20 s0332 ppp[1620]: tun1: IPCP: deflink: = RecvConfigReq(151) > state =3D Initial > Nov 26 16:53:20 s0332 ppp[1620]: tun1: IPCP: IPADDR[6] xx.yy.128.14 > Nov 26 16:53:20 s0332 ppp[1620]: tun1: IPCP: deflink: Oops, RCR in = Initial. > Nov 26 16:53:20 s0332 ppp[1620]: tun1: Timer: tun: fdset(r) 6 > Nov 26 16:53:20 s0332 ppp[1620]: tun1: Timer: deflink(ctrl): fdset(r) = 0 > Nov 26 16:53:20 s0332 ppp[1620]: tun1: Timer: deflink: fdset(r) 1 > Nov 26 16:53:20 s0332 ppp[1620]: tun1: Timer: deflink: fdset(e) 1 > Nov 26 16:53:20 s0332 ppp[1620]: tun1: Timer: server: fdset(r) 9 > Nov 26 16:53:20 s0332 ppp[1620]: tun1: Timer: prompt > /var/run/spdsl-internet: fdset(r) 2 > Nov 26 16:53:21 s0332 ppp[1620]: tun1: Timer: Select returns -1 > Nov 26 16:53:21 s0332 ppp[1620]: tun1: Timer: ---- Begin of Timer > Service List--- > Nov 26 16:53:21 s0332 ppp[1620]: tun1: Timer: physical throughput > timer[0x28411068]: freq =3D 1.00s, next =3D 0.00s, state =3D running > Nov 26 16:53:21 s0332 ppp[1620]: tun1: Timer: lqm timer[0x28413df4]: > freq =3D 10.00s, next =3D 2.40s, state =3D running > Nov 26 16:53:21 s0332 ppp[1620]: tun1: Timer: hdlc timer[0x28413db0]: > freq =3D 60.00s, next =3D 52.40s, state =3D running > Nov 26 16:53:21 s0332 ppp[1620]: tun1: Timer: ---- End of Timer = Service > List --- > Nov 26 16:53:21 s0332 ppp[1620]: tun1: Timer: timer_Start: Inserting > physical throughput timer[0x28411068] before lqm timer[0x28413df4], > delta =3D 10 > Nov 26 16:53:21 s0332 ppp[1620]: tun1: Timer: tun: fdset(r) 6 > Nov 26 16:53:21 s0332 ppp[1620]: tun1: Timer: deflink(ctrl): fdset(r) = 0 > Nov 26 16:53:21 s0332 ppp[1620]: tun1: Timer: deflink: fdset(r) 1 > Nov 26 16:53:21 s0332 ppp[1620]: tun1: Timer: deflink: fdset(e) 1 > Nov 26 16:53:21 s0332 ppp[1620]: tun1: Timer: server: fdset(r) 9 > Nov 26 16:53:21 s0332 ppp[1620]: tun1: Timer: prompt > /var/run/spdsl-internet: fdset(r) 2 > Nov 26 16:53:22 s0332 ppp[1620]: tun1: Timer: Select returns -1 > Nov 26 16:53:22 s0332 ppp[1620]: tun1: Timer: ---- Begin of Timer > Service List--- > Nov 26 16:53:22 s0332 ppp[1620]: tun1: Timer: physical throughput > timer[0x28411068]: freq =3D 1.00s, next =3D 0.00s, state =3D running > Nov 26 16:53:22 s0332 ppp[1620]: tun1: Timer: lqm timer[0x28413df4]: > freq =3D 10.00s, next =3D 1.40s, state =3D running > Nov 26 16:53:22 s0332 ppp[1620]: tun1: Timer: hdlc timer[0x28413db0]: > freq =3D 60.00s, next =3D 51.40s, state =3D running > Nov 26 16:53:22 s0332 ppp[1620]: tun1: Timer: ---- End of Timer = Service > List --- > Nov 26 16:53:22 s0332 ppp[1620]: tun1: Timer: timer_Start: Inserting > physical throughput timer[0x28411068] before lqm timer[0x28413df4], > delta =3D 10 > Nov 26 16:53:22 s0332 ppp[1620]: tun1: Timer: tun: fdset(r) 6 > Nov 26 16:53:22 s0332 ppp[1620]: tun1: Timer: deflink(ctrl): fdset(r) = 0 > Nov 26 16:53:22 s0332 ppp[1620]: tun1: Timer: deflink: fdset(r) 1 > Nov 26 16:53:22 s0332 ppp[1620]: tun1: Timer: deflink: fdset(e) 1 > Nov 26 16:53:22 s0332 ppp[1620]: tun1: Timer: server: fdset(r) 9 > Nov 26 16:53:22 s0332 ppp[1620]: tun1: Timer: prompt > /var/run/spdsl-internet: fdset(r) 2 > Nov 26 16:53:22 s0332 ppp[1620]: tun1: Timer: Select returns 1 > Nov 26 16:53:22 s0332 ppp[1620]: tun1: Physical: read > Nov 26 16:53:22 s0332 ppp[1620]: tun1: Physical: 80 21 01 98 00 0a 03 > 06 43 2b 80 0e .!......C+.. > Nov 26 16:53:22 s0332 ppp[1620]: tun1: Debug: deflink: DescriptorRead: > read 12/2048 from 1 > Nov 26 16:53:22 s0332 ppp[1620]: tun1: Sync: Read > Nov 26 16:53:22 s0332 ppp[1620]: tun1: Sync: 80 21 01 98 00 0a 03 06 = 43 > 2b 80 0e .!......C+.. > Nov 26 16:53:22 s0332 ppp[1620]: tun1: Debug: proto_LayerPull: unknown > -> 0x8021 > Nov 26 16:53:22 s0332 ppp[1620]: tun1: Debug: link_PullPacket: = Despatch > proto 0x8021 > Nov 26 16:53:22 s0332 ppp[1620]: tun1: IPCP: deflink: = RecvConfigReq(152) > state =3D Initial > Nov 26 16:53:22 s0332 ppp[1620]: tun1: IPCP: IPADDR[6] xx.yy.128.14 > Nov 26 16:53:22 s0332 ppp[1620]: tun1: IPCP: deflink: Oops, RCR in = Initial. > Nov 26 16:53:22 s0332 ppp[1620]: tun1: Timer: tun: fdset(r) 6 > Nov 26 16:53:22 s0332 ppp[1620]: tun1: Timer: deflink(ctrl): fdset(r) = 0 >=20 >=20 >=20 >=20 > --=20 > ------------------- > Mike Tancsa, tel +1 519 651 3400 > Sentex Communications, mike@sentex.net > Providing Internet services since 1994 www.sentex.net > Cambridge, Ontario Canada http://www.tancsa.com/ > _______________________________________________ > freebsd-questions@freebsd.org mailing list > http://lists.freebsd.org/mailman/listinfo/freebsd-questions > To unsubscribe, send any mail to = "freebsd-questions-unsubscribe@freebsd.org"