I'm hoping someone can do a sanity check for me. I'm trying to get an OpenBSD 6.2 router working with Teksavvy DSL. Teksavvy uses PPPoE over Telus DSL. It seems to authenticate just fine, but then my box immediately terminates the connection?
My hostname.pppoe0, pretty much straight out of the man pages: inet 0.0.0.0 255.255.255.255 NONE \ pppoedev em0 \ authproto pap authname '[email protected]' authkey 'HiThere' \ up debug dest 0.0.0.1 !/sbin/route add default -ifp pppoe0 0.0.0.1 Explicity turning off the dial-on-demand link1 flag does not change the behaviour I'm seeing. I have put the logs created by the debug flag at the bottom of this message. A tcpdump of what goes across em0, with some of my observations and suspicions inline: 03:42:32.481632 :MY_ROUTER: Broadcast 8863 32: PPPoE-Discovery code Initiation, version 1, type 1, id 0x0000, length 12 tag Service-Name, length 0 tag Host-Uniq, length 4 K\200H\214 03:42:32.481828 :MY_ROUTER: Broadcast 8863 32: PPPoE-Discovery code Initiation, version 1, type 1, id 0x0000, length 12 tag Service-Name, length 0 tag Host-Uniq, length 4 K\200H\214 03:42:32.482017 :MY_ROUTER: Broadcast 8863 32: PPPoE-Discovery code Initiation, version 1, type 1, id 0x0000, length 12 tag Service-Name, length 0 tag Host-Uniq, length 4 K\200H\214 03:42:32.496674 00:90:1a:a0:91:66 :MY_ROUTER: 8863 68: PPPoE-Discovery code Offer, version 1, type 1, id 0x0000, length 48 tag AC-Name, length 12 EDTNABXTAR03 tag Host-Uniq, length 4 K\200H\214 tag Service-Name, length 0 tag AC-Cookie, length 16 \222\377q-,p\230I\037:t\250\251\322\031h 03:42:32.500635 00:90:1a:a0:91:66 :MY_ROUTER: 8863 68: PPPoE-Discovery code Offer, version 1, type 1, id 0x0000, length 48 tag AC-Name, length 12 EDTNABXTAR03 tag Host-Uniq, length 4 K\200H\214 tag Service-Name, length 0 tag AC-Cookie, length 16 \222\377q-,p\230I\037:t\250\251\322\031h 03:42:32.504625 00:90:1a:a0:91:66 :MY_ROUTER: 8863 68: PPPoE-Discovery code Offer, version 1, type 1, id 0x0000, length 48 tag AC-Name, length 12 EDTNABXTAR03 tag Host-Uniq, length 4 K\200H\214 tag Service-Name, length 0 tag AC-Cookie, length 16 \222\377q-,p\230I\037:t\250\251\322\031h 03:42:32.506573 :MY_ROUTER: 00:90:1a:a0:91:66 8863 52: PPPoE-Discovery code Request, version 1, type 1, id 0x0000, length 32 tag Service-Name, length 0 tag AC-Cookie, length 16 \222\377q-,p\230I\037:t\250\251\322\031h tag Host-Uniq, length 4 K\200H\214 03:42:32.631874 00:90:1a:a0:91:66 :MY_ROUTER: 8863 60: PPPoE-Discovery code Confirm, version 1, type 1, id 0x17a6, length 12 tag Service-Name, length 0 tag Host-Uniq, length 4 K\200H\214 03:42:32.650084 :MY_ROUTER: 00:90:1a:a0:91:66 8864 36: PPPoE-Session code Session, version 1, type 1, id 0x17a6, length 16 LCP: Configure-Request, Magic-Number=-617813364, Max-Rx-Unit=1492[|lcp] 03:42:32.838573 00:90:1a:a0:91:66 :MY_ROUTER: 8864 60: PPPoE-Session code Session, version 1, type 1, id 0x17a6, length 21 LCP: Configure-Request, Max-Rx-Unit=1460, Auth-Prot CHAP/MD5, Magic-Number=306492429, Vendor-Ext 03:42:32.848645 :MY_ROUTER: 00:90:1a:a0:91:66 8864 31: PPPoE-Session code Session, version 1, type 1, id 0x17a6, length 11 LCP: Configure-Nak, Auth-Prot PAP[|lcp] *** ^ This might be significant. The only clue I have found online, from over five years ago, is that Telus sends a CHAP challenge first, then when there is a valid response to that they start PAP authentication. *** 03:42:32.848654 00:90:1a:a0:91:66 :MY_ROUTER: 8864 60: PPPoE-Session code Session, version 1, type 1, id 0x17a6, length 16 LCP: Configure-Ack, Magic-Number=-617813364, Max-Rx-Unit=1492, Vendor-Ext 03:42:33.641445 :MY_ROUTER: 00:90:1a:a0:91:66 8864 36: PPPoE-Session code Session, version 1, type 1, id 0x17a6, length 16 LCP: Configure-Request, Magic-Number=-617813364, Max-Rx-Unit=1492[|lcp] 03:42:33.650921 00:90:1a:a0:91:66 :MY_ROUTER: 8864 60: PPPoE-Session code Session, version 1, type 1, id 0x17a6, length 16 LCP: Configure-Ack, Magic-Number=-617813364, Max-Rx-Unit=1492, Vendor-Ext 03:42:34.641457 :MY_ROUTER: 00:90:1a:a0:91:66 8864 36: PPPoE-Session code Session, version 1, type 1, id 0x17a6, length 16 LCP: Configure-Request, Magic-Number=-617813364, Max-Rx-Unit=1492[|lcp] 03:42:34.650908 00:90:1a:a0:91:66 :MY_ROUTER: 8864 60: PPPoE-Session code Session, version 1, type 1, id 0x17a6, length 16 LCP: Configure-Ack, Magic-Number=-617813364, Max-Rx-Unit=1492, Vendor-Ext 03:42:35.641449 :MY_ROUTER: 00:90:1a:a0:91:66 8864 36: PPPoE-Session code Session, version 1, type 1, id 0x17a6, length 16 LCP: Configure-Request, Magic-Number=-617813364, Max-Rx-Unit=1492[|lcp] 03:42:35.651171 00:90:1a:a0:91:66 :MY_ROUTER: 8864 60: PPPoE-Session code Session, version 1, type 1, id 0x17a6, length 16 LCP: Configure-Ack, Magic-Number=-617813364, Max-Rx-Unit=1492, Vendor-Ext 03:42:35.834563 00:90:1a:a0:91:66 :MY_ROUTER: 8864 60: PPPoE-Session code Session, version 1, type 1, id 0x17a6, length 20 LCP: Configure-Request, Max-Rx-Unit=1460, Auth-Prot PAP, Magic-Number=306492429, Vendor-Ext 03:42:35.844618 :MY_ROUTER: 00:90:1a:a0:91:66 8864 40: PPPoE-Session code Session, version 1, type 1, id 0x17a6, length 20 LCP: Configure-Ack, Max-Rx-Unit=1460, Auth-Prot PAP, Magic-Number=306492429[|lcp] 03:42:35.854660 :MY_ROUTER: 00:90:1a:a0:91:66 8864 56: PPPoE-Session code Session, version 1, type 1, id 0x17a6, length 36 PAP: Authenticate-Request, [email protected], Passwd=HiThere 03:42:36.434842 00:90:1a:a0:91:66 :MY_ROUTER: 8864 60: PPPoE-Session code Session, version 1, type 1, id 0x17a6, length 20 LCP: Configure-Request, Max-Rx-Unit=1452, Auth-Prot PAP, Magic-Number=1462616641, Vendor-Ext 03:42:36.444922 :MY_ROUTER: 00:90:1a:a0:91:66 8864 40: PPPoE-Session code Session, version 1, type 1, id 0x17a6, length 20 LCP: Configure-Ack, Max-Rx-Unit=1452, Auth-Prot PAP, Magic-Number=1462616641[|lcp] 03:42:36.454959 :MY_ROUTER: 00:90:1a:a0:91:66 8864 36: PPPoE-Session code Session, version 1, type 1, id 0x17a6, length 16 LCP: Configure-Request, Magic-Number=-617813364, Max-Rx-Unit=1492[|lcp] 03:42:36.476793 00:90:1a:a0:91:66 :MY_ROUTER: 8864 60: PPPoE-Session code Session, version 1, type 1, id 0x17a6, length 16 LCP: Configure-Ack, Magic-Number=-617813364, Max-Rx-Unit=1492, Vendor-Ext 03:42:36.486873 :MY_ROUTER: 00:90:1a:a0:91:66 8864 56: PPPoE-Session code Session, version 1, type 1, id 0x17a6, length 36 PAP: Authenticate-Request, [email protected], Passwd=HiThere 03:42:36.806113 00:90:1a:a0:91:66 :MY_ROUTER: 8864 60: PPPoE-Session code Session, version 1, type 1, id 0x17a6, length 7 PAP: Authenticate-Ack 03:42:36.816167 :MY_ROUTER: 00:90:1a:a0:91:66 8864 26: PPPoE-Session code Session, version 1, type 1, id 0x17a6, length 6 LCP: Terminate-Request *** ^ I'm sending a Terminate-Request right after I get the Auth-Ack. *** 03:42:36.836379 00:90:1a:a0:91:66 :MY_ROUTER: 8864 60: PPPoE-Session code Session, version 1, type 1, id 0x17a6, length 6 LCP: Terminate-Ack 03:42:36.866533 :MY_ROUTER: 00:90:1a:a0:91:66 8863 20: PPPoE-Discovery code Terminate, version 1, type 1, id 0x17a6, length 0 So is me sending a Terminate-Request the problem, or a symptom of a problem? If what I read from 2012 is correct and I need to respond to both CHAP and PAP, what is the syntax for that? /var/log/messages: Mar 20 03:42:32 sork /bsd: pppoe0 (8863) state=2, session=0x0 output -> 00:90:1a:a0:91:66, len=38 Mar 20 03:42:32 sork /bsd: pppoe0: received unexpected PADO Mar 20 03:42:32 sork /bsd: pppoe0: received unexpected PADO Mar 20 03:42:32 sork /bsd: pppoe0: session 0x17a6 connected Mar 20 03:42:32 sork /bsd: pppoe0: lcp up(starting) Mar 20 03:42:32 sork /bsd: pppoe0: lcp starting->req-sent Mar 20 03:42:32 sork /bsd: pppoe0: lcp output <conf-req id=0x1 len=14 05-06-db-2c-ea-8c-01-04-05-d4> Mar 20 03:42:32 sork /bsd: pppoe0 (8864) state=3, session=0x17a6 output -> 00:90:1a:a0:91:66, len=22 Mar 20 03:42:32 sork /bsd: pppoe0: lcp input(req-sent): <conf-req id=0x54 len=19 01-04-05-b4-03-05-c2-23-05-05-06-12-44-b4-0d-00-00-00-00-00-00-00-00-00-00-00-00-00-00-00-00-00-00-00> Mar 20 03:42:32 sork /bsd: pppoe0: lcp parse opts: mru auth-proto magic Mar 20 03:42:32 sork /bsd: pppoe0: lcp parse opt values: mru 1460 auth-proto [mine 0x0 != his chap] magic 0x1244b40d send conf-nak Mar 20 03:42:32 sork /bsd: pppoe0: lcp output <conf-nak id=0x54 len=9 03-05-c0-23-05> Mar 20 03:42:32 sork /bsd: pppoe0 (8864) state=3, session=0x17a6 output -> 00:90:1a:a0:91:66, len=17 Mar 20 03:42:32 sork /bsd: pppoe0: lcp input(req-sent): <conf-ack id=0x1 len=14 05-06-db-2c-ea-8c-01-04-05-d4-00-00-00-00-00-00-00-00-00-00-00-00-00-00-00-00-00-00-00-00-00-00-00-00> Mar 20 03:42:32 sork /bsd: pppoe0: lcp req-sent->ack-rcvd Mar 20 03:42:33 sork /bsd: pppoe0: lcp TO(ack-rcvd) rst_counter = 10 Mar 20 03:42:33 sork /bsd: pppoe0: lcp ack-rcvd->req-sent Mar 20 03:42:33 sork /bsd: pppoe0: lcp output <conf-req id=0x2 len=14 05-06-db-2c-ea-8c-01-04-05-d4> Mar 20 03:42:33 sork /bsd: pppoe0 (8864) state=3, session=0x17a6 output -> 00:90:1a:a0:91:66, len=22 Mar 20 03:42:33 sork /bsd: pppoe0: lcp input(req-sent): <conf-ack id=0x2 len=14 05-06-db-2c-ea-8c-01-04-05-d4-00-00-00-00-00-00-00-00-00-00-00-00-00-00-00-00-00-00-00-00-00-00-00-00> Mar 20 03:42:33 sork /bsd: pppoe0: lcp req-sent->ack-rcvd Mar 20 03:42:34 sork /bsd: pppoe0: lcp TO(ack-rcvd) rst_counter = 10 Mar 20 03:42:34 sork /bsd: pppoe0: lcp ack-rcvd->req-sent Mar 20 03:42:34 sork /bsd: pppoe0: lcp output <conf-req id=0x3 len=14 05-06-db-2c-ea-8c-01-04-05-d4> Mar 20 03:42:34 sork /bsd: pppoe0 (8864) state=3, session=0x17a6 output -> 00:90:1a:a0:91:66, len=22 Mar 20 03:42:34 sork /bsd: pppoe0: lcp input(req-sent): <conf-ack id=0x3 len=14 05-06-db-2c-ea-8c-01-04-05-d4-00-00-00-00-00-00-00-00-00-00-00-00-00-00-00-00-00-00-00-00-00-00-00-00> Mar 20 03:42:34 sork /bsd: pppoe0: lcp req-sent->ack-rcvd Mar 20 03:42:35 sork /bsd: pppoe0: lcp TO(ack-rcvd) rst_counter = 10 Mar 20 03:42:35 sork /bsd: pppoe0: lcp ack-rcvd->req-sent Mar 20 03:42:35 sork /bsd: pppoe0: lcp output <conf-req id=0x4 len=14 05-06-db-2c-ea-8c-01-04-05-d4> Mar 20 03:42:35 sork /bsd: pppoe0 (8864) state=3, session=0x17a6 output -> 00:90:1a:a0:91:66, len=22 Mar 20 03:42:35 sork /bsd: pppoe0: lcp input(req-sent): <conf-ack id=0x4 len=14 05-06-db-2c-ea-8c-01-04-05-d4-00-00-00-00-00-00-00-00-00-00-00-00-00-00-00-00-00-00-00-00-00-00-00-00> Mar 20 03:42:35 sork /bsd: pppoe0: lcp req-sent->ack-rcvd Mar 20 03:42:35 sork /bsd: pppoe0: lcp input(ack-rcvd): <conf-req id=0x55 len=18 01-04-05-b4-03-04-c0-23-05-06-12-44-b4-0d-00-00-00-00-00-00-00-00-00-00-00-00-00-00-00-00-00-00-00-00> Mar 20 03:42:35 sork /bsd: pppoe0: lcp parse opts: mru auth-proto magic Mar 20 03:42:35 sork /bsd: pppoe0: lcp parse opt values: mru 1460 auth-proto magic 0x1244b40d send conf-ack Mar 20 03:42:35 sork /bsd: pppoe0: lcp output <conf-ack id=0x55 len=18 01-04-05-b4-03-04-c0-23-05-06-12-44-b4-0d> Mar 20 03:42:35 sork /bsd: pppoe0 (8864) state=3, session=0x17a6 output -> 00:90:1a:a0:91:66, len=26 Mar 20 03:42:35 sork /bsd: pppoe0: lcp ack-rcvd->opened Mar 20 03:42:35 sork /bsd: pppoe0: lcp tlu Mar 20 03:42:35 sork /bsd: pppoe0: phase authenticate Mar 20 03:42:35 sork /bsd: pppoe0: pap output <req id=0x5 len=34 16-MyTekAcct-40-74-65-6b-73-61-76-76-79-2e-63-6f-6d-06-HiThere> Mar 20 03:42:35 sork /bsd: pppoe0 (8864) state=3, session=0x17a6 output -> 00:90:1a:a0:91:66, len=42 Mar 20 03:42:36 sork /bsd: pppoe0: lcp input(opened): <conf-req id=0x81 len=18 01-04-05-ac-03-04-c0-23-05-06-57-2d-c2-41-00-00-00-00-00-00-00-00-00-00-00-00-00-00-00-00-00-00-00-00> Mar 20 03:42:36 sork /bsd: pppoe0: lcp parse opts: mru auth-proto magic Mar 20 03:42:36 sork /bsd: pppoe0: lcp parse opt values: mru 1452 auth-proto magic 0x572dc241 send conf-ack Mar 20 03:42:36 sork /bsd: pppoe0: lcp output <conf-ack id=0x81 len=18 01-04-05-ac-03-04-c0-23-05-06-57-2d-c2-41> Mar 20 03:42:36 sork /bsd: pppoe0 (8864) state=3, session=0x17a6 output -> 00:90:1a:a0:91:66, len=26 Mar 20 03:42:36 sork /bsd: pppoe0: lcp opened->ack-sent Mar 20 03:42:36 sork /bsd: pppoe0: phase terminate Mar 20 03:42:36 sork /bsd: pppoe0: lcp output <conf-req id=0x6 len=14 05-06-db-2c-ea-8c-01-04-05-d4> Mar 20 03:42:36 sork /bsd: pppoe0 (8864) state=3, session=0x17a6 output -> 00:90:1a:a0:91:66, len=22 Mar 20 03:42:36 sork /bsd: pppoe0: lcp input(ack-sent): <conf-ack id=0x6 len=14 05-06-db-2c-ea-8c-01-04-05-d4-00-00-00-00-00-00-00-00-00-00-00-00-00-00-00-00-00-00-00-00-00-00-00-00> Mar 20 03:42:36 sork /bsd: pppoe0: lcp ack-sent->opened Mar 20 03:42:36 sork /bsd: pppoe0: lcp tlu Mar 20 03:42:36 sork /bsd: pppoe0: phase authenticate Mar 20 03:42:36 sork /bsd: pppoe0: pap output <req id=0x7 len=34 16-MyTekAcct-40-74-65-6b-73-61-76-76-79-2e-63-6f-6d-06-HiThere> Mar 20 03:42:36 sork /bsd: pppoe0 (8864) state=3, session=0x17a6 output -> 00:90:1a:a0:91:66, len=42 Mar 20 03:42:36 sork /bsd: pppoe0: pap success Mar 20 03:42:36 sork /bsd: pppoe0: phase network Mar 20 03:42:36 sork /bsd: pppoe0: ipcp open(initial) Mar 20 03:42:36 sork /bsd: pppoe0: ipcp initial->starting Mar 20 03:42:36 sork /bsd: pppoe0: ipcp_open(): no IP interface Mar 20 03:42:36 sork /bsd: pppoe0: ipv6cp_open(): no IPv6 interface Mar 20 03:42:36 sork /bsd: pppoe0: lcp close(opened) Mar 20 03:42:36 sork /bsd: pppoe0: lcp opened->closing Mar 20 03:42:36 sork /bsd: pppoe0: lcp output <term-req id=0x8 len=4> Mar 20 03:42:36 sork /bsd: pppoe0 (8864) state=3, session=0x17a6 output -> 00:90:1a:a0:91:66, len=12 Mar 20 03:42:36 sork /bsd: pppoe0: phase terminate Mar 20 03:42:36 sork /bsd: pppoe0: lcp input(closing): <term-ack id=0x8 len=4 00-00-00-00-00-00-00-00-00-00-00-00-00-00-00-00-00-00-00-00-00-00-00-00-00-00-00-00-00-00-00-00-00-00> Mar 20 03:42:36 sork /bsd: pppoe0: lcp closing->closed Mar 20 03:42:36 sork /bsd: pppoe0: phase dead Mar 20 03:42:36 sork /bsd: pppoe0: timeout Mar 20 03:42:36 sork /bsd: pppoe0: disconnecting Mar 20 03:42:36 sork /bsd: pppoe0: lcp down(closed) Mar 20 03:42:36 sork /bsd: pppoe0: lcp closed->initial Mar 20 03:42:36 sork /bsd: pppoe0: Down event (carrier loss), taking interface down.
