Jon Martin
2018-03-20 10:10:44 UTC
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 '***@teksavvy.com' 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, Peer-Id=***@teksavvy.com, 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, Peer-Id=***@teksavvy.com, 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.
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 '***@teksavvy.com' 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, Peer-Id=***@teksavvy.com, 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, Peer-Id=***@teksavvy.com, 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.