pppoe(4) constantly disconnected/reconnecting on 3.8/sparc64

classic Classic list List threaded Threaded
2 messages Options
Reply | Threaded
Open this post in threaded view
|

pppoe(4) constantly disconnected/reconnecting on 3.8/sparc64

Jim Faulkner
When I follow the instructions on the pppoe(4) manpage to create
/etc/hostname.pppoe0, pppoe will connected and obtain an IP address, but
then disconnect again within 1 minute, and start reconnecting and
disconnecting in an endless loop, each time obtaining a different IP
address.

The file attached to this e-mail as "pppoe1.txt" is the output from
/var/log/messages after a few disconnects/reconnects.  The file attached
as "pppoe2.txt" is similar output, except that I ran "ifconfig pppoe0
debug" immediately after boot.

I also had this problem with OpenBSD 3.7:
http://groups.google.com/group/lucky.openbsd.misc/browse_thread/thread/c17bde3ca5533645/30e7b12f3c4719ef
but I never filed an official bug report.

My hardware is a Sun Ultra-1.  I am running OpenBSD 3.8/sparc64.  The
pppoedev is hme1 (an sbus card).  I have SBC Yahoo DSL with a Speedstream
5260 ADSL modem.

I have noticed that if I ping an external host, the link seems to stay up
indefinitely.  It stayed up for more than an hour today while pinging an
external host.  When there is no activity on the line, it goes back to its
cycle of disconnecting/reconnecting every minute or so.

Please let me know if you need any other information.

thanks,
Jim Faulkner
Nov  9 15:13:00 noname /bsd: OpenBSD 3.8 (GENERIC) #607: Sat Sep 10 16:03:59 MDT 2005

Nov  9 15:13:00 noname /bsd:     [hidden email]:/usr/src/sys/arch/sparc64/compile/GENERIC

Nov  9 15:13:00 noname /bsd: total memory = 469762048

Nov  9 15:13:00 noname /bsd: avail memory = 418807808

Nov  9 15:13:00 noname /bsd: using 2867 buffers containing 23486464 bytes of memory

Nov  9 15:13:00 noname /bsd: bootpath: /sbus@1f,0/SUNW,hme@e,8c00000

Nov  9 15:13:00 noname /bsd: mainbus0 (root): Sun Ultra 1 UPA/SBus (UltraSPARC 167MHz)

Nov  9 15:13:00 noname /bsd: cpu0 at mainbus0: SUNW,UltraSPARC @ 166.995 MHz, version 0 FPU

Nov  9 15:13:00 noname /bsd: cpu0: physical 32K instruction (32 b/l), 16K data (32 b/l), 512K external (64 b/l)

Nov  9 15:13:00 noname /bsd: timer0 at mainbus0 addr 0xfffc7c00 irq vectors 7f0 and 7f1

Nov  9 15:13:00 noname /bsd: sbus0 at mainbus0 addr 0xfffcc000: clock = 25 MHz

Nov  9 15:13:00 noname /bsd: STC0 on /mainbus enabled

Nov  9 15:13:00 noname /bsd: DVMA map: ff800000 to ffffe000

Nov  9 15:13:00 noname /bsd: IOTDB: 21fa000 to 21fc000

Nov  9 15:13:00 noname /bsd: audiocs0 at sbus0 slot 13 offset 0xc000000 vector 24 ipl 8

Nov  9 15:13:00 noname /bsd: audio0 at audiocs0

Nov  9 15:13:00 noname /bsd: auxio0 at sbus0 slot 15 offset 0x1900000

Nov  9 15:13:00 noname /bsd: flashprom at sbus0 slot 15 offset 0x0 not configured

Nov  9 15:13:00 noname /bsd: fdc0 at sbus0 slot 15 offset 0x1400000 vector 29 ipl 11: no drives attached

Nov  9 15:13:00 noname /bsd: clock1 at sbus0 slot 15 offset 0x1200000: mk48t59: hostid 8088ebd5

Nov  9 15:13:00 noname /bsd: zs0 at sbus0 slot 15 offset 0x1100000 vector 28 ipl 12 softpri 6

Nov  9 15:13:00 noname /bsd: zstty0 at zs0 channel 0

Nov  9 15:13:00 noname /bsd: zstty1 at zs0 channel 1

Nov  9 15:13:00 noname /bsd: zs1 at sbus0 slot 15 offset 0x1000000 vector 28 ipl 12 softpri 6

Nov  9 15:13:00 noname /bsd: zskbd0 at zs1 channel 0: layout 33

Nov  9 15:13:00 noname /bsd: wskbd0 at zskbd0: console keyboard

Nov  9 15:13:00 noname /bsd: zstty2 at zs1 channel 1: mouse

Nov  9 15:13:00 noname /bsd: uperf0 at sbus0 slot 15 offset 0x1300000: model SUNW,sc-up (0/0) ports 3

Nov  9 15:13:00 noname /bsd: SUNW,pll at sbus0 slot 15 offset 0x1304000 not configured

Nov  9 15:13:00 noname /bsd: esp0 at sbus0 slot 14 offset 0x8800000 vector 20 ipl 3: dma rev fas

Nov  9 15:13:00 noname /bsd: esp0: FAS366/HME, 40MHz, SCSI ID 7

Nov  9 15:13:00 noname /bsd: scsibus0 at esp0: 8 targets

Nov  9 15:13:00 noname /bsd: hme0 at sbus0 slot 14 offset 0x8c00000 vector 21 ipl 6: address 08:00:20:88:eb:d5

Nov  9 15:13:00 noname /bsd: nsphy0 at hme0 phy 1: DP83840 10/100 PHY, rev. 0

Nov  9 15:13:00 noname /bsd: SUNW,bpp at sbus0 slot 14 offset 0xc800000 vector 22 ipl 2 not configured

Nov  9 15:13:00 noname /bsd: hme1 at sbus0 slot 0 offset 0x8c00000 vector 4 ipl 6: address 08:00:20:88:eb:d5

Nov  9 15:13:00 noname /bsd: nsphy1 at hme1 phy 1: DP83840 10/100 PHY, rev. 1

Nov  9 15:13:00 noname /bsd: stp0 at sbus0 slot 1 offset 0x0 vector 4 ipl 2 vector 7 ipl 2: rev 1

Nov  9 15:13:00 noname /bsd: pcmcia0 at stp0 socket 0

Nov  9 15:13:00 noname /bsd: "3Com Corporation, 3C589D, TP/BNC LAN Card Ver. 2a, 000002" (manufacturer 0x101, product 0x589) at pcmcia0 function 0 not configured

Nov  9 15:13:00 noname /bsd: pcmcia1 at stp0 socket 1

Nov  9 15:13:00 noname /bsd: wi0 at pcmcia1 function 0 "NETGEAR MA401RA Wireless PC, Card, ISL37300P" port 0x0/64

Nov  9 15:13:00 noname /bsd: wi0: PRISM2.5 ISL3873 (0x800c), Firmware 1.1.1 (primary), 1.8.0 (station), address 00:09:5b:0f:03:40

Nov  9 15:13:00 noname /bsd: creator0 at mainbus0 addr 0xfec00000: Creator3D, model SUNW,501-2633, dac 10

Nov  9 15:13:00 noname /bsd: wsdisplay0 at creator0: console (std, sun emulation), using wskbd0

Nov  9 15:13:00 noname /bsd: pcons at mainbus0 not configured

Nov  9 15:13:00 noname /bsd: nfs_boot: using network interface 'hme0'

Nov  9 15:13:00 noname /bsd: nfs_boot: client_addr=192.168.0.2

Nov  9 15:13:00 noname /bsd: nfs_boot: server_addr=192.168.0.83 hostname=leech

Nov  9 15:13:00 noname /bsd: root on 192.168.0.83:/home/leech

Nov  9 15:13:00 noname /bsd: nfs_boot: bootparam get swap: 60

Nov  9 15:13:00 noname /bsd: WARNING: no swap

Nov  9 15:13:00 noname /bsd: swapmount: no device

Nov  9 15:13:00 noname /bsd: pppoe0: phase establish

Nov  9 15:13:00 noname /bsd: pppoe0: phase authenticate

Nov  9 15:13:00 noname /bsd: pppoe0: phase network

Nov  9 15:13:01 noname savecore: no core dump (no dumpdev)

Nov  9 15:13:16 noname /bsd: pppoe0: phase terminate

Nov  9 15:13:26 noname /bsd: pppoe0: phase dead

Nov  9 15:13:26 noname /bsd: pppoe0: phase establish

Nov  9 15:13:26 noname /bsd: pppoe0: phase dead

Nov  9 15:13:26 noname /bsd: pppoe0: phase establish

Nov  9 15:13:26 noname /bsd: pppoe0: up

Nov  9 15:13:26 noname /bsd: pppoe0: phase authenticate

Nov  9 15:13:26 noname /bsd: pppoe0: phase network

Nov  9 15:13:56 noname /bsd: pppoe0: phase terminate

Nov  9 15:14:06 noname /bsd: pppoe0: phase dead

Nov  9 15:14:06 noname /bsd: pppoe0: phase establish

Nov  9 15:14:06 noname /bsd: pppoe0: phase dead

Nov  9 15:14:06 noname /bsd: pppoe0: phase establish

Nov  9 15:14:06 noname /bsd: pppoe0: up

Nov  9 15:14:06 noname /bsd: pppoe0: phase authenticate

Nov  9 15:14:06 noname /bsd: pppoe0: phase network

Nov  9 15:14:26 noname /bsd: pppoe0: phase terminate

Nov  9 15:14:36 noname /bsd: pppoe0: phase dead

Nov  9 15:14:36 noname /bsd: pppoe0: phase establish

Nov  9 15:14:36 noname /bsd: pppoe0: phase dead

Nov  9 15:14:36 noname /bsd: pppoe0: phase establish

Nov  9 15:14:36 noname /bsd: pppoe0: up

Nov  9 15:14:36 noname /bsd: pppoe0: phase authenticate

Nov  9 15:14:36 noname /bsd: pppoe0: phase network

Nov  9 15:19:27 noname /bsd: OpenBSD 3.8 (GENERIC) #607: Sat Sep 10 16:03:59 MDT 2005

Nov  9 15:19:27 noname /bsd:     [hidden email]:/usr/src/sys/arch/sparc64/compile/GENERIC

Nov  9 15:19:27 noname /bsd: total memory = 469762048

Nov  9 15:19:27 noname /bsd: avail memory = 418807808

Nov  9 15:19:27 noname /bsd: using 2867 buffers containing 23486464 bytes of memory

Nov  9 15:19:27 noname /bsd: bootpath: /sbus@1f,0/SUNW,hme@e,8c00000

Nov  9 15:19:27 noname /bsd: mainbus0 (root): Sun Ultra 1 UPA/SBus (UltraSPARC 167MHz)

Nov  9 15:19:27 noname /bsd: cpu0 at mainbus0: SUNW,UltraSPARC @ 166.997 MHz, version 0 FPU

Nov  9 15:19:27 noname /bsd: cpu0: physical 32K instruction (32 b/l), 16K data (32 b/l), 512K external (64 b/l)

Nov  9 15:19:27 noname /bsd: timer0 at mainbus0 addr 0xfffc7c00 irq vectors 7f0 and 7f1

Nov  9 15:19:27 noname /bsd: sbus0 at mainbus0 addr 0xfffcc000: clock = 25 MHz

Nov  9 15:19:27 noname /bsd: STC0 on /mainbus enabled

Nov  9 15:19:27 noname /bsd: DVMA map: ff800000 to ffffe000

Nov  9 15:19:27 noname /bsd: IOTDB: 21fa000 to 21fc000

Nov  9 15:19:27 noname /bsd: audiocs0 at sbus0 slot 13 offset 0xc000000 vector 24 ipl 8

Nov  9 15:19:27 noname /bsd: audio0 at audiocs0

Nov  9 15:19:27 noname /bsd: auxio0 at sbus0 slot 15 offset 0x1900000

Nov  9 15:19:27 noname /bsd: flashprom at sbus0 slot 15 offset 0x0 not configured

Nov  9 15:19:27 noname /bsd: fdc0 at sbus0 slot 15 offset 0x1400000 vector 29 ipl 11: no drives attached

Nov  9 15:19:27 noname /bsd: clock1 at sbus0 slot 15 offset 0x1200000: mk48t59: hostid 8088ebd5

Nov  9 15:19:27 noname /bsd: zs0 at sbus0 slot 15 offset 0x1100000 vector 28 ipl 12 softpri 6

Nov  9 15:19:27 noname /bsd: zstty0 at zs0 channel 0

Nov  9 15:19:27 noname /bsd: zstty1 at zs0 channel 1

Nov  9 15:19:27 noname /bsd: zs1 at sbus0 slot 15 offset 0x1000000 vector 28 ipl 12 softpri 6

Nov  9 15:19:27 noname /bsd: zskbd0 at zs1 channel 0: layout 33

Nov  9 15:19:27 noname /bsd: wskbd0 at zskbd0: console keyboard

Nov  9 15:19:27 noname /bsd: zstty2 at zs1 channel 1: mouse

Nov  9 15:19:27 noname /bsd: uperf0 at sbus0 slot 15 offset 0x1300000: model SUNW,sc-up (0/0) ports 3

Nov  9 15:19:27 noname /bsd: SUNW,pll at sbus0 slot 15 offset 0x1304000 not configured

Nov  9 15:19:27 noname /bsd: esp0 at sbus0 slot 14 offset 0x8800000 vector 20 ipl 3: dma rev fas

Nov  9 15:19:27 noname /bsd: esp0: FAS366/HME, 40MHz, SCSI ID 7

Nov  9 15:19:27 noname /bsd: scsibus0 at esp0: 8 targets

Nov  9 15:19:27 noname /bsd: hme0 at sbus0 slot 14 offset 0x8c00000 vector 21 ipl 6: address 08:00:20:88:eb:d5

Nov  9 15:19:27 noname /bsd: nsphy0 at hme0 phy 1: DP83840 10/100 PHY, rev. 0

Nov  9 15:19:27 noname /bsd: SUNW,bpp at sbus0 slot 14 offset 0xc800000 vector 22 ipl 2 not configured

Nov  9 15:19:27 noname /bsd: hme1 at sbus0 slot 0 offset 0x8c00000 vector 4 ipl 6: address 08:00:20:88:eb:d5

Nov  9 15:19:27 noname /bsd: nsphy1 at hme1 phy 1: DP83840 10/100 PHY, rev. 1

Nov  9 15:19:27 noname /bsd: stp0 at sbus0 slot 1 offset 0x0 vector 4 ipl 2 vector 7 ipl 2: rev 1

Nov  9 15:19:27 noname /bsd: pcmcia0 at stp0 socket 0

Nov  9 15:19:27 noname /bsd: "3Com Corporation, 3C589D, TP/BNC LAN Card Ver. 2a, 000002" (manufacturer 0x101, product 0x589) at pcmcia0 function 0 not configured

Nov  9 15:19:27 noname /bsd: pcmcia1 at stp0 socket 1

Nov  9 15:19:27 noname /bsd: wi0 at pcmcia1 function 0 "NETGEAR MA401RA Wireless PC, Card, ISL37300P" port 0x0/64

Nov  9 15:19:27 noname /bsd: wi0: PRISM2.5 ISL3873 (0x800c), Firmware 1.1.1 (primary), 1.8.0 (station), address 00:09:5b:0f:03:40

Nov  9 15:19:27 noname /bsd: creator0 at mainbus0 addr 0xfec00000: Creator3D, model SUNW,501-2633, dac 10

Nov  9 15:19:27 noname /bsd: wsdisplay0 at creator0: console (std, sun emulation), using wskbd0

Nov  9 15:19:27 noname /bsd: pcons at mainbus0 not configured

Nov  9 15:19:27 noname /bsd: nfs_boot: using network interface 'hme0'

Nov  9 15:19:27 noname /bsd: nfs_boot: client_addr=192.168.0.2

Nov  9 15:19:27 noname /bsd: nfs_boot: server_addr=192.168.0.83 hostname=leech

Nov  9 15:19:27 noname /bsd: root on 192.168.0.83:/home/leech

Nov  9 15:19:27 noname /bsd: nfs_boot: bootparam get swap: 60

Nov  9 15:19:27 noname /bsd: WARNING: no swap

Nov  9 15:19:27 noname /bsd: swapmount: no device

Nov  9 15:19:27 noname /bsd: pppoe0: phase establish

Nov  9 15:19:27 noname /bsd: pppoe0: phase authenticate

Nov  9 15:19:27 noname /bsd: pppoe0: phase network

Nov  9 15:19:28 noname savecore: no core dump (no dumpdev)

Nov  9 15:19:43 noname /bsd: pppoe0: phase terminate

Nov  9 15:19:53 noname /bsd: pppoe0: lcp TO(stopping) rst_counter = 0

Nov  9 15:19:53 noname /bsd: pppoe0: lcp stopping->stopped

Nov  9 15:19:53 noname /bsd: pppoe0: phase dead

Nov  9 15:19:53 noname /bsd: pppoe0: timeout

Nov  9 15:19:53 noname /bsd: pppoe0: disconnecting

Nov  9 15:19:53 noname /bsd: pppoe0: lcp down(stopped)

Nov  9 15:19:53 noname /bsd: pppoe0: lcp stopped->starting

Nov  9 15:19:53 noname /bsd: pppoe0: phase establish

Nov  9 15:19:53 noname /bsd: pppoe0 (8863) state=1, session=0x0 output -> ff:ff:ff:ff:ff:ff, len=22

Nov  9 15:19:53 noname /bsd: pppoe0: Down event (carrier loss), taking interface down.<7>pppoe0: lcp close(starting)

Nov  9 15:19:53 noname /bsd: pppoe0: lcp starting->initial

Nov  9 15:19:53 noname /bsd: pppoe0: phase dead

Nov  9 15:19:53 noname /bsd: pppoe0 (8863) state=2, session=0x0 output -> 00:90:1a:40:cd:28, len=42

Nov  9 15:19:53 noname /bsd: pppoe0: session 0x1656 connected

Nov  9 15:19:53 noname /bsd: pppoe0: lcp open(initial)

Nov  9 15:19:53 noname /bsd: pppoe0: lcp initial->starting

Nov  9 15:19:53 noname /bsd: pppoe0: phase establish

Nov  9 15:19:53 noname /bsd: pppoe0: lcp up(starting)

Nov  9 15:19:53 noname /bsd: pppoe0: lcp starting->req-sent

Nov  9 15:19:53 noname /bsd: pppoe0: lcp output <conf-req id=0x6 len=10 05-06-c7-12-3d-dc>

Nov  9 15:19:53 noname /bsd: pppoe0 (8864) state=3, session=0x1656 output -> 00:90:1a:40:cd:28, len=18

Nov  9 15:19:53 noname /bsd: pppoe0: lcp input(req-sent): <conf-req id=0xe8 len=18 01-04-05-d4-03-04-c0-23-05-06-62-a5-2c-2b-00-00-00-00-00-00-00-00-00-00-00-00-00-00-00-00-00-00-00-00>

Nov  9 15:19:53 noname /bsd: pppoe0: lcp parse opts: mru auth-proto magic

Nov  9 15:19:53 noname /bsd: pppoe0: lcp parse opt values: mru 1492 auth-proto magic 0x62a52c2b send conf-ack

Nov  9 15:19:53 noname /bsd: pppoe0: lcp output <conf-ack id=0xe8 len=18 01-04-05-d4-03-04-c0-23-05-06-62-a5-2c-2b>

Nov  9 15:19:53 noname /bsd: pppoe0 (8864) state=3, session=0x1656 output -> 00:90:1a:40:cd:28, len=26

Nov  9 15:19:53 noname /bsd: pppoe0: lcp req-sent->ack-sent

Nov  9 15:19:53 noname /bsd: pppoe0: lcp input(ack-sent): <conf-ack id=0x6 len=10 05-06-c7-12-3d-dc-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>

Nov  9 15:19:53 noname /bsd: pppoe0: lcp ack-sent->opened

Nov  9 15:19:53 noname /bsd: pppoe0: lcp tlu

Nov  9 15:19:53 noname /bsd: pppoe0: up

Nov  9 15:19:53 noname /bsd: pppoe0: phase authenticate

Nov  9 15:19:53 noname /bsd: pppoe0: pap output <req id=0x7 len=41 19-6a-69-6d-66-61-75-6c-6b-6e-65-72-40-73-62-63-67-6c-6f-62-61-6c-2e-6e-65-74-0a-31-63-66-64-64-39-66-61-66-62>

Nov  9 15:19:53 noname /bsd: pppoe0 (8864) state=3, session=0x1656 output -> 00:90:1a:40:cd:28, len=49

Nov  9 15:19:53 noname /bsd: pppoe0: pap success

Nov  9 15:19:53 noname /bsd: pppoe0: phase network

Nov  9 15:19:53 noname /bsd: pppoe0: ipcp open(initial)

Nov  9 15:19:53 noname /bsd: pppoe0: ipcp initial->starting

Nov  9 15:19:53 noname /bsd: pppoe0: ipcp up(starting)

Nov  9 15:19:53 noname /bsd: pppoe0: ipcp starting->req-sent

Nov  9 15:19:53 noname /bsd: pppoe0: ipcp output <conf-req id=0x8 len=10 03-06-00-00-00-00>

Nov  9 15:19:53 noname /bsd: pppoe0 (8864) state=3, session=0x1656 output -> 00:90:1a:40:cd:28, len=18

Nov  9 15:19:53 noname /bsd: pppoe0: ipcp input(req-sent): <conf-req id=0x7d len=10 03-06-cc-3c-04-29-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>

Nov  9 15:19:53 noname /bsd: pppoe0: ipcp parse opts: address

Nov  9 15:19:53 noname /bsd: pppoe0: ipcp parse opt values:  address 204.60.4.41 [ack]  send conf-ack

Nov  9 15:19:53 noname /bsd: pppoe0: ipcp output <conf-ack id=0x7d len=10 03-06-cc-3c-04-29>

Nov  9 15:19:53 noname /bsd: pppoe0 (8864) state=3, session=0x1656 output -> 00:90:1a:40:cd:28, len=18

Nov  9 15:19:53 noname /bsd: pppoe0: ipcp req-sent->ack-sent

Nov  9 15:19:53 noname /bsd: pppoe0: ipcp input(ack-sent): <conf-nak id=0x8 len=10 03-06-45-b7-82-f2-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>

Nov  9 15:19:53 noname /bsd: pppoe0: ipcp nak opts: address [wantaddr 69.183.130.242] [agree]

Nov  9 15:19:53 noname /bsd: pppoe0: ipcp output <conf-req id=0x9 len=10 03-06-45-b7-82-f2>

Nov  9 15:19:53 noname /bsd: pppoe0 (8864) state=3, session=0x1656 output -> 00:90:1a:40:cd:28, len=18

Nov  9 15:19:53 noname /bsd: pppoe0: ipcp input(ack-sent): <conf-ack id=0x9 len=10 03-06-45-b7-82-f2-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>

Nov  9 15:19:53 noname /bsd: pppoe0: ipcp ack-sent->opened

Nov  9 15:19:53 noname /bsd: pppoe0: ipcp tlu

Nov  9 15:20:13 noname /bsd: pppoe0: lcp output <echo-req id=0xa len=8 00-00-00-00>

Nov  9 15:20:13 noname /bsd: pppoe0 (8864) state=3, session=0x1656 output -> 00:90:1a:40:cd:28, len=16

Nov  9 15:20:13 noname /bsd: pppoe0: lcp input(opened): <term-req id=0xe9 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>

Nov  9 15:20:13 noname /bsd: pppoe0: lcp opened->stopping

Nov  9 15:20:13 noname /bsd: pppoe0: phase terminate

Nov  9 15:20:13 noname /bsd: pppoe0: ipcp down(opened)

Nov  9 15:20:13 noname /bsd: pppoe0: ipcp opened->starting

Nov  9 15:20:13 noname /bsd: pppoe0: ipcp close(starting)

Nov  9 15:20:13 noname /bsd: pppoe0: ipcp starting->initial

Nov  9 15:20:13 noname /bsd: pppoe0: lcp send terminate-ack

Nov  9 15:20:13 noname /bsd: pppoe0: lcp output <term-ack id=0xe9 len=4>

Nov  9 15:20:13 noname /bsd: pppoe0 (8864) state=3, session=0x1656 output -> 00:90:1a:40:cd:28, len=12

Nov  9 15:20:23 noname /bsd: pppoe0: lcp TO(stopping) rst_counter = 0

Nov  9 15:20:23 noname /bsd: pppoe0: lcp stopping->stopped

Nov  9 15:20:23 noname /bsd: pppoe0: phase dead

Nov  9 15:20:23 noname /bsd: pppoe0: timeout

Nov  9 15:20:23 noname /bsd: pppoe0: disconnecting

Nov  9 15:20:23 noname /bsd: pppoe0: lcp down(stopped)

Nov  9 15:20:23 noname /bsd: pppoe0: lcp stopped->starting

Nov  9 15:20:23 noname /bsd: pppoe0: phase establish

Nov  9 15:20:23 noname /bsd: pppoe0 (8863) state=1, session=0x0 output -> ff:ff:ff:ff:ff:ff, len=22

Nov  9 15:20:23 noname /bsd: pppoe0: Down event (carrier loss), taking interface down.<7>pppoe0: lcp close(starting)

Nov  9 15:20:23 noname /bsd: pppoe0: lcp starting->initial

Nov  9 15:20:23 noname /bsd: pppoe0: phase dead

Nov  9 15:20:23 noname /bsd: pppoe0 (8863) state=2, session=0x0 output -> 00:90:1a:40:cd:28, len=42

Nov  9 15:20:23 noname /bsd: pppoe0: session 0x1689 connected

Nov  9 15:20:23 noname /bsd: pppoe0: lcp open(initial)

Nov  9 15:20:23 noname /bsd: pppoe0: lcp initial->starting

Nov  9 15:20:23 noname /bsd: pppoe0: phase establish

Nov  9 15:20:23 noname /bsd: pppoe0: lcp up(starting)

Nov  9 15:20:23 noname /bsd: pppoe0: lcp starting->req-sent

Nov  9 15:20:23 noname /bsd: pppoe0: lcp output <conf-req id=0xb len=10 05-06-ad-a6-24-c6>

Nov  9 15:20:23 noname /bsd: pppoe0 (8864) state=3, session=0x1689 output -> 00:90:1a:40:cd:28, len=18

Nov  9 15:20:23 noname /bsd: pppoe0: lcp input(req-sent): <conf-req id=0xea len=18 01-04-05-d4-03-04-c0-23-05-06-5d-95-26-48-00-00-00-00-00-00-00-00-00-00-00-00-00-00-00-00-00-00-00-00>

Nov  9 15:20:23 noname /bsd: pppoe0: lcp parse opts: mru auth-proto magic

Nov  9 15:20:23 noname /bsd: pppoe0: lcp parse opt values: mru 1492 auth-proto magic 0x5d952648 send conf-ack

Nov  9 15:20:23 noname /bsd: pppoe0: lcp output <conf-ack id=0xea len=18 01-04-05-d4-03-04-c0-23-05-06-5d-95-26-48>

Nov  9 15:20:23 noname /bsd: pppoe0 (8864) state=3, session=0x1689 output -> 00:90:1a:40:cd:28, len=26

Nov  9 15:20:23 noname /bsd: pppoe0: lcp req-sent->ack-sent

Nov  9 15:20:23 noname /bsd: pppoe0: lcp input(ack-sent): <conf-ack id=0xb len=10 05-06-ad-a6-24-c6-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>

Nov  9 15:20:23 noname /bsd: pppoe0: lcp ack-sent->opened

Nov  9 15:20:23 noname /bsd: pppoe0: lcp tlu

Nov  9 15:20:23 noname /bsd: pppoe0: up

Nov  9 15:20:23 noname /bsd: pppoe0: phase authenticate

Nov  9 15:20:23 noname /bsd: pppoe0: pap output <req id=0xc len=41 19-6a-69-6d-66-61-75-6c-6b-6e-65-72-40-73-62-63-67-6c-6f-62-61-6c-2e-6e-65-74-0a-31-63-66-64-64-39-66-61-66-62>

Nov  9 15:20:23 noname /bsd: pppoe0 (8864) state=3, session=0x1689 output -> 00:90:1a:40:cd:28, len=49

Nov  9 15:20:24 noname /bsd: pppoe0: pap success

Nov  9 15:20:24 noname /bsd: pppoe0: phase network

Nov  9 15:20:24 noname /bsd: pppoe0: ipcp open(initial)

Nov  9 15:20:24 noname /bsd: pppoe0: ipcp initial->starting

Nov  9 15:20:24 noname /bsd: pppoe0: ipcp up(starting)

Nov  9 15:20:24 noname /bsd: pppoe0: ipcp starting->req-sent

Nov  9 15:20:24 noname /bsd: pppoe0: ipcp output <conf-req id=0xd len=10 03-06-00-00-00-00>

Nov  9 15:20:24 noname /bsd: pppoe0 (8864) state=3, session=0x1689 output -> 00:90:1a:40:cd:28, len=18

Nov  9 15:20:24 noname /bsd: pppoe0: ipcp input(req-sent): <conf-req id=0x7e len=10 03-06-cc-3c-04-29-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>

Nov  9 15:20:24 noname /bsd: pppoe0: ipcp parse opts: address

Nov  9 15:20:24 noname /bsd: pppoe0: ipcp parse opt values:  address 204.60.4.41 [ack]  send conf-ack

Nov  9 15:20:24 noname /bsd: pppoe0: ipcp output <conf-ack id=0x7e len=10 03-06-cc-3c-04-29>

Nov  9 15:20:24 noname /bsd: pppoe0 (8864) state=3, session=0x1689 output -> 00:90:1a:40:cd:28, len=18

Nov  9 15:20:24 noname /bsd: pppoe0: ipcp req-sent->ack-sent

Nov  9 15:20:24 noname /bsd: pppoe0: ipcp input(ack-sent): <conf-nak id=0xd len=10 03-06-45-b6-2c-56-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>

Nov  9 15:20:24 noname /bsd: pppoe0: ipcp nak opts: address [wantaddr 69.182.44.86] [agree]

Nov  9 15:20:24 noname /bsd: pppoe0: ipcp output <conf-req id=0xe len=10 03-06-45-b6-2c-56>

Nov  9 15:20:24 noname /bsd: pppoe0 (8864) state=3, session=0x1689 output -> 00:90:1a:40:cd:28, len=18

Nov  9 15:20:24 noname /bsd: pppoe0: ipcp input(ack-sent): <conf-ack id=0xe len=10 03-06-45-b6-2c-56-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>

Nov  9 15:20:24 noname /bsd: pppoe0: ipcp ack-sent->opened

Nov  9 15:20:24 noname /bsd: pppoe0: ipcp tlu

Nov  9 15:20:43 noname /bsd: pppoe0: lcp output <echo-req id=0xf len=8 00-00-00-00>

Nov  9 15:20:43 noname /bsd: pppoe0 (8864) state=3, session=0x1689 output -> 00:90:1a:40:cd:28, len=16

Nov  9 15:20:43 noname /bsd: pppoe0: lcp input(opened): <term-req id=0xeb 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>

Nov  9 15:20:43 noname /bsd: pppoe0: lcp opened->stopping

Nov  9 15:20:43 noname /bsd: pppoe0: phase terminate

Nov  9 15:20:43 noname /bsd: pppoe0: ipcp down(opened)

Nov  9 15:20:43 noname /bsd: pppoe0: ipcp opened->starting

Nov  9 15:20:43 noname /bsd: pppoe0: ipcp close(starting)

Nov  9 15:20:43 noname /bsd: pppoe0: ipcp starting->initial

Nov  9 15:20:43 noname /bsd: pppoe0: lcp send terminate-ack

Nov  9 15:20:43 noname /bsd: pppoe0: lcp output <term-ack id=0xeb len=4>

Nov  9 15:20:43 noname /bsd: pppoe0 (8864) state=3, session=0x1689 output -> 00:90:1a:40:cd:28, len=12


Reply | Threaded
Open this post in threaded view
|

Re: pppoe(4) constantly disconnected/reconnecting on 3.8/sparc64

Can Erkin Acar
On Wed, Nov 09, 2005 at 06:06:38PM -0500, Jim Faulkner wrote:
> When I follow the instructions on the pppoe(4) manpage to create
> /etc/hostname.pppoe0, pppoe will connected and obtain an IP address, but
> then disconnect again within 1 minute, and start reconnecting and
> disconnecting in an endless loop, each time obtaining a different IP
> address.

It appears that your isp does not reply to lcp echo requests.  pppoe
thinks that the link is broken and re-establishes the link.  These
'keepalive' packets  are sent only when the link is idle.
The obvious workaround is to keep sending (and receiving) traffic.

I will look into providing an option to disable the keepalives.

Can