[Date Prev][Date Next][Thread Prev][Thread Next][Date Index][Thread Index]

kernel pppoe problem : pppoe0 : timeout



Hello,
(sorry for the long post!)
I used the ppp pppoe (for my dsl connection)  for some while and decided to switch to the kernel implementation.

I'm actually having a problem with kernel pppoe, after a reboot or when I try to connect/reconnect it takes about 
1 minute before it is able to connect. While running "ifconfig pppoe0 debug" (see below) I noticed a few "pppoe0: timeout"

When I used the ppp's pppoe implementation the connection and reconnection were almost instant. 

For information, here is my old ppp.conf:

default:
 set log Phase tun command
 set redial 7 0
 set reconnect 7 10000

provider:
 set device "!/usr/sbin/pppoe -i sis2"
 disable acfcomp iface-alias deflate protocomp vjcomp pred1 ipv6cp
 deny acfcomp
 set mtu max 1454
 set mru max 1454
 set speed sync
 enable lqr
 set lqrperiod 5
 set dial
 set login
 set timeout 0
 set authname "xyz"
 set authkey xyz
 add default HISADDR
 enable mssfixup
 set server /var/run/internet "" 0177


Now, here is my new hostname.pppoe0
pppoedev sis2
!/sbin/ifconfig sis2 up media 10baseT 
!/usr/sbin/spppcontrol \$if myauthproto=pap myauthname=xyz \
        myauthkey=xyz
!/sbin/ifconfig \$if inet 0.0.0.0 0.0.0.1 netmask 0xffffffff link1
!/sbin/route add default 0.0.0.1
!/usr/local/sbin/noip2 -c /etc/no-ip2.conf
up

Here is my dmesg and "ifconfig pppoe0 debug" output:

Oct  6 19:29:10 gate /bsd: syncing disks... done
Oct  6 19:29:10 gate /bsd: OpenBSD 3.8 (GENERIC) #1: Fri Sep 30 21:09:23 CEST 2005
Oct  6 19:29:10 gate /bsd:     root_(_at_)_bonobo_(_dot_)_internal_(_dot_)_local:/home/cvs/OPENBSD_3_8/src/sys/arch/i386/compile/GENERIC
Oct  6 19:29:10 gate /bsd: cpu0: Geode(TM) Integrated Processor by National Semi ("Geode by NSC" 586-class) 267 MHz
Oct  6 19:29:10 gate /bsd: cpu0: FPU,TSC,MSR,CX8,CMOV,MMX
Oct  6 19:29:10 gate /bsd: cpu0: TSC disabled
Oct  6 19:29:10 gate /bsd: real mem  = 133799936 (130664K)
Oct  6 19:29:10 gate /bsd: avail mem = 115470336 (112764K)
Oct  6 19:29:10 gate /bsd: using 1658 buffers containing 6791168 bytes (6632K) of memory
Oct  6 19:29:10 gate /bsd: mainbus0 (root)
Oct  6 19:29:10 gate /bsd: bios0 at mainbus0: AT/286+(00) BIOS, date 20/50/29, BIOS32 rev. 0 @ 0xf7840
Oct  6 19:29:10 gate /bsd: pcibios0 at bios0: rev 2.0 @ 0xf0000/0x10000
Oct  6 19:29:10 gate /bsd: pcibios0: pcibios_get_intr_routing - function not supported
Oct  6 19:29:10 gate /bsd: pcibios0: PCI IRQ Routing information unavailable.
Oct  6 19:29:10 gate /bsd: pcibios0: PCI bus #0 is the last bus
Oct  6 19:29:10 gate /bsd: bios0: ROM list: 0xc8000/0x9000
Oct  6 19:29:10 gate /bsd: cpu0 at mainbus0
Oct  6 19:29:10 gate /bsd: pci0 at mainbus0 bus 0: configuration mode 1 (bios)
Oct  6 19:29:10 gate /bsd: pchb0 at pci0 dev 0 function 0 "Cyrix GXm PCI" rev 0x00
Oct  6 19:29:10 gate /bsd: sis0 at pci0 dev 6 function 0 "NS DP83815 10/100" rev 0x00: DP83816A, irq 10, address 00:00:24:c2:9d:38
Oct  6 19:29:10 gate /bsd: nsphyter0 at sis0 phy 0: DP83815 10/100 PHY, rev. 1
Oct  6 19:29:10 gate /bsd: sis1 at pci0 dev 7 function 0 "NS DP83815 10/100" rev 0x00: DP83816A, irq 10, address 00:00:24:c2:9d:39
Oct  6 19:29:10 gate /bsd: nsphyter1 at sis1 phy 0: DP83815 10/100 PHY, rev. 1
Oct  6 19:29:10 gate /bsd: sis2 at pci0 dev 8 function 0 "NS DP83815 10/100" rev 0x00: DP83816A, irq 10, address 00:00:24:c2:9d:3a
Oct  6 19:29:11 gate /bsd: nsphyter2 at sis2 phy 0: DP83815 10/100 PHY, rev. 1
Oct  6 19:29:11 gate /bsd: hifn0 at pci0 dev 10 function 0 "Hifn 7955/7954" rev 0x00: LZS 3DES ARC4 MD5 SHA1 RNG AES PK, 32KB dram, irq 11
Oct  6 19:29:11 gate /bsd: gscpcib0 at pci0 dev 18 function 0 "NS SC1100 ISA" rev 0x00
Oct  6 19:29:11 gate /bsd: gpio0 at gscpcib0: 64 pins
Oct  6 19:29:11 gate /bsd: "NS SC1100 SMI/ACPI" rev 0x00 at pci0 dev 18 function 1 not configured
Oct  6 19:29:11 gate /bsd: pciide0 at pci0 dev 18 function 2 "NS SCx200 IDE" rev 0x01: DMA, channel 0 wired to compatibility, channel 1 wired to compatibility
Oct  6 19:29:11 gate /bsd: wd0 at pciide0 channel 0 drive 0: <TOSHIBA MK2023GAS>
Oct  6 19:29:11 gate /bsd: wd0: 16-sector PIO, LBA, 19077MB, 39070080 sectors
Oct  6 19:29:11 gate /bsd: wd1 at pciide0 channel 0 drive 1: <SanDisk SDCFB-256>
Oct  6 19:29:11 gate /bsd: wd1: 1-sector PIO, LBA, 245MB, 501760 sectors
Oct  6 19:29:11 gate /bsd: wd0(pciide0:0:0): using PIO mode 4, Ultra-DMA mode 2
Oct  6 19:29:11 gate /bsd: wd1(pciide0:0:1): using PIO mode 4, DMA mode 2
Oct  6 19:29:11 gate /bsd: geodesc0 at pci0 dev 18 function 5 "NS SC1100 X-Bus" rev 0x00: iid 6 revision 3 wdstatus 0
Oct  6 19:29:11 gate /bsd: ohci0 at pci0 dev 19 function 0 "Compaq USB OpenHost" rev 0x08: irq 5, version 1.0, legacy support
Oct  6 19:29:11 gate /bsd: usb0 at ohci0: USB revision 1.0
Oct  6 19:29:11 gate /bsd: uhub0 at usb0
Oct  6 19:29:11 gate /bsd: uhub0: Compaq OHCI root hub, rev 1.00/1.00, addr 1
Oct  6 19:29:11 gate /bsd: uhub0: 3 ports with 3 removable, self powered
Oct  6 19:29:11 gate /bsd: isa0 at gscpcib0
Oct  6 19:29:11 gate /bsd: isadma0 at isa0
Oct  6 19:29:11 gate /bsd: pckbc0 at isa0 port 0x60/5
Oct  6 19:29:11 gate /bsd: pckbd0 at pckbc0 (kbd slot)
Oct  6 19:29:11 gate /bsd: pckbc0: using irq 1 for kbd slot
Oct  6 19:29:11 gate /bsd: wskbd0 at pckbd0: console keyboard
Oct  6 19:29:11 gate /bsd: pcppi0 at isa0 port 0x61
Oct  6 19:29:11 gate /bsd: midi0 at pcppi0: <PC speaker>
Oct  6 19:29:11 gate /bsd: spkr0 at pcppi0
Oct  6 19:29:11 gate /bsd: sysbeep0 at pcppi0
Oct  6 19:29:11 gate /bsd: nsclpcsio0 at isa0 port 0x2e/2: NSC PC87366 rev 9: GPIO VLM TMS
Oct  6 19:29:11 gate /bsd: gpio1 at nsclpcsio0: 29 pins
Oct  6 19:29:11 gate /bsd: gscsio0 at isa0 port 0x15c/2: SC1100 SIO rev 1:
Oct  6 19:29:11 gate /bsd: npx0 at isa0 port 0xf0/16: using exception 16
Oct  6 19:29:11 gate /bsd: pccom0 at isa0 port 0x3f8/8 irq 4: ns16550a, 16 byte fifo
Oct  6 19:29:11 gate /bsd: pccom0: console
Oct  6 19:29:11 gate /bsd: pccom1 at isa0 port 0x2f8/8 irq 3: ns16550a, 16 byte fifo
Oct  6 19:29:11 gate /bsd: biomask f3e5 netmask ffe5 ttymask ffe7
Oct  6 19:29:11 gate /bsd: pctr: no performance counters in CPU
Oct  6 19:29:11 gate /bsd: dkcsum: wd0 matches BIOS drive 0x80
Oct  6 19:29:11 gate /bsd: dkcsum: wd1 matches BIOS drive 0x81
Oct  6 19:29:11 gate /bsd: root on wd0a
Oct  6 19:29:11 gate /bsd: rootdev=0x0 rrootdev=0x300 rawdev=0x302
Oct  6 19:29:11 gate /bsd: pppoe0: lcp close(initial)
Oct  6 19:29:11 gate /bsd: pppoe0: lcp open(initial)
Oct  6 19:29:11 gate /bsd: pppoe0: lcp initial->starting
Oct  6 19:29:11 gate /bsd: pppoe0: phase establish
Oct  6 19:29:11 gate /bsd: pppoe0 (8863) state=1, session=0x0 output -> ff:ff:ff:ff:ff:ff, len=18
Oct  6 19:29:11 gate /bsd: pppoe0 (8863) state=2, session=0x0 output -> 00:90:1a:10:10:87, len=38
Oct  6 19:29:11 gate named[15406]: starting BIND 9.3.1
Oct  6 19:29:12 gate named[15406]: command channel listening on 127.0.0.1#953
Oct  6 19:29:12 gate named[15406]: command channel listening on ::1#953
Oct  6 19:29:12 gate named[15406]: running
Oct  6 19:29:12 gate savecore: no core dump
Oct  6 19:29:13 gate /bsd: pppoe0: timeout
Oct  6 19:29:13 gate /bsd: pppoe0 (8863) state=2, session=0x0 output -> 00:90:1a:10:10:87, len=38
Oct  6 19:29:23 gate /bsd: pppoe0: timeout
Oct  6 19:29:23 gate /bsd: pppoe0 (8863) state=1, session=0x0 output -> ff:ff:ff:ff:ff:ff, len=18
Oct  6 19:29:23 gate /bsd: pppoe0 (8863) state=2, session=0x0 output -> 00:90:1a:10:10:87, len=38
Oct  6 19:29:28 gate /bsd: pppoe0: timeout
Oct  6 19:29:28 gate /bsd: pppoe0 (8863) state=2, session=0x0 output -> 00:90:1a:10:10:87, len=38
Oct  6 19:29:38 gate /bsd: pppoe0: timeout
Oct  6 19:29:38 gate /bsd: pppoe0 (8863) state=1, session=0x0 output -> ff:ff:ff:ff:ff:ff, len=18
Oct  6 19:29:38 gate /bsd: pppoe0 (8863) state=2, session=0x0 output -> 00:90:1a:10:10:87, len=38
Oct  6 19:29:43 gate /bsd: pppoe0: timeout
Oct  6 19:29:43 gate /bsd: pppoe0 (8863) state=2, session=0x0 output -> 00:90:1a:10:10:87, len=38
Oct  6 19:29:53 gate /bsd: pppoe0: timeout
Oct  6 19:29:53 gate /bsd: pppoe0 (8863) state=1, session=0x0 output -> ff:ff:ff:ff:ff:ff, len=18
Oct  6 19:29:53 gate /bsd: pppoe0 (8863) state=2, session=0x0 output -> 00:90:1a:10:10:87, len=38
Oct  6 19:29:58 gate /bsd: pppoe0: timeout
Oct  6 19:29:58 gate /bsd: pppoe0 (8863) state=2, session=0x0 output -> 00:90:1a:10:10:87, len=38
Oct  6 19:30:08 gate /bsd: pppoe0: timeout
Oct  6 19:30:08 gate /bsd: pppoe0 (8863) state=1, session=0x0 output -> ff:ff:ff:ff:ff:ff, len=18
Oct  6 19:30:08 gate /bsd: pppoe0 (8863) state=2, session=0x0 output -> 00:90:1a:10:10:87, len=38
Oct  6 19:30:13 gate /bsd: pppoe0: timeout
Oct  6 19:30:13 gate /bsd: pppoe0 (8863) state=2, session=0x0 output -> 00:90:1a:10:10:87, len=38
Oct  6 19:30:23 gate /bsd: pppoe0: timeout
Oct  6 19:30:23 gate /bsd: pppoe0 (8863) state=1, session=0x0 output -> ff:ff:ff:ff:ff:ff, len=18
Oct  6 19:30:23 gate /bsd: pppoe0 (8863) state=2, session=0x0 output -> 00:90:1a:10:10:87, len=38
Oct  6 19:30:28 gate /bsd: pppoe0: timeout
Oct  6 19:30:28 gate /bsd: pppoe0 (8863) state=2, session=0x0 output -> 00:90:1a:10:10:87, len=38
Oct  6 19:30:28 gate /bsd: pppoe0: session 0xc5 connected
Oct  6 19:30:28 gate /bsd: pppoe0: lcp up(starting)
Oct  6 19:30:28 gate /bsd: pppoe0: lcp starting->req-sent
Oct  6 19:30:28 gate /bsd: pppoe0: lcp output <conf-req id=0x1 len=10 05-06-ff-d4-0f-bf>
Oct  6 19:30:28 gate /bsd: pppoe0 (8864) state=3, session=0xc5 output -> 00:90:1a:10:10:87, len=18
Oct  6 19:30:29 gate /bsd: pppoe0: lcp input(req-sent): <conf-req id=0x3a len=18 01-04-05-d4-03-04-c0-23-05-06-07-be-93-19-00-00-00-00-00-00-00-00-00-00-00-00-00-00-00-00-00-00-00-00-79-65-42-f2>
Oct  6 19:30:29 gate /bsd: pppoe0: lcp parse opts: mru auth-proto magic 
Oct  6 19:30:29 gate /bsd: pppoe0: lcp parse opt values: mru 1492 auth-proto magic 0x7be9319 send conf-ack
Oct  6 19:30:29 gate /bsd: pppoe0: lcp output <conf-ack id=0x3a len=18 01-04-05-d4-03-04-c0-23-05-06-07-be-93-19>
Oct  6 19:30:29 gate /bsd: pppoe0 (8864) state=3, session=0xc5 output -> 00:90:1a:10:10:87, len=26
Oct  6 19:30:29 gate /bsd: pppoe0: lcp req-sent->ack-sent
Oct  6 19:30:29 gate /bsd: pppoe0: lcp input(ack-sent): <conf-ack id=0x1 len=10 05-06-ff-d4-0f-bf-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-29-ce-64-6a>
Oct  6 19:30:29 gate /bsd: pppoe0: lcp ack-sent->opened
Oct  6 19:30:29 gate /bsd: pppoe0: lcp tlu
Oct  6 19:30:29 gate /bsd: pppoe0: phase authenticate
Oct  6 19:30:29 gate /bsd: pppoe0: pap output <req id=0x2 len=27 09-31-33-34-33-33-31-33-34-34-0c-31-33-34-72-65-73-34-36-64-6e-62-75>
Oct  6 19:30:29 gate /bsd: pppoe0 (8864) state=3, session=0xc5 output -> 00:90:1a:10:10:87, len=35
Oct  6 19:30:29 gate /bsd: pppoe0: pap success
Oct  6 19:30:29 gate /bsd: pppoe0: phase network
Oct  6 19:30:29 gate /bsd: pppoe0: ipcp open(initial)
Oct  6 19:30:29 gate /bsd: pppoe0: ipcp initial->starting
Oct  6 19:30:29 gate /bsd: pppoe0: ipcp up(starting)
Oct  6 19:30:29 gate /bsd: pppoe0: ipcp starting->req-sent
Oct  6 19:30:29 gate /bsd: pppoe0: ipcp output <conf-req id=0x3 len=10 03-06-00-00-00-00>
Oct  6 19:30:29 gate /bsd: pppoe0 (8864) state=3, session=0xc5 output -> 00:90:1a:10:10:87, len=18
Oct  6 19:30:29 gate /bsd: pppoe0: ipcp input(req-sent): <conf-nak id=0x3 len=10 03-06-9e-40-7c-2e-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-ae-7c-2b-33>
Oct  6 19:30:29 gate /bsd: pppoe0: ipcp nak opts: address [wantaddr 158.164.24.46] [agree] 
Oct  6 19:30:29 gate /bsd: pppoe0: ipcp output <conf-req id=0x4 len=10 03-06-9e-40-7c-2e>
Oct  6 19:30:29 gate /bsd: pppoe0 (8864) state=3, session=0xc5 output -> 00:90:1a:10:10:87, len=18
Oct  6 19:30:29 gate /bsd: pppoe0: ipcp input(req-sent): <conf-ack id=0x4 len=10 03-06-9e-40-7c-2e-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-93-e8-91-8a>
Oct  6 19:30:29 gate /bsd: pppoe0: ipcp req-sent->ack-rcvd
Oct  6 19:30:29 gate /bsd: pppoe0: ipcp input(ack-rcvd): <conf-req id=0x1 len=10 03-06-9e-40-7c-01-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-79-80-a3>
Oct  6 19:30:29 gate /bsd: pppoe0: ipcp parse opts: address 
Oct  6 19:30:29 gate /bsd: pppoe0: ipcp parse opt values:  address 158.164.24.1 [ack]  send conf-ack
Oct  6 19:30:29 gate /bsd: pppoe0: ipcp output <conf-ack id=0x1 len=10 03-06-9e-40-7c-01>
Oct  6 19:30:29 gate /bsd: pppoe0 (8864) state=3, session=0xc5 output -> 00:90:1a:10:10:87, len=18
Oct  6 19:30:29 gate /bsd: pppoe0: ipcp ack-rcvd->opened
Oct  6 19:30:29 gate /bsd: pppoe0: ipcp tlu
Oct  6 19:30:34 gate /bsd: pppoe0 (8864) state=3, session=0xc5 output -> 00:90:1a:10:10:87, len=64
Oct  6 19:30:56 gate /bsd: pppoe0: lcp output <echo-req id=0x5 len=8 ff-d4-0f-bf>
Oct  6 19:30:56 gate /bsd: pppoe0 (8864) state=3, session=0xc5 output -> 00:90:1a:10:10:87, len=16
Oct  6 19:30:56 gate /bsd: pppoe0: lcp input(opened): <echo-reply id=0x5 len=8 07-be-93-19-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-b6-9a-5b-e4>
Oct  6 19:30:56 gate /bsd: pppoe0: lcp got echo rep