Re: xl(4) autonegotiation trouble - ng_pppoe related?

From: Maxim Maximov <mcsi_at_mcsi.pp.ru>
Date: Wed, 28 Jul 2004 23:41:29 +0400
Matthias Andree wrote:
> "Bjoern A. Zeeb" <bzeeb-lists_at_lists.zabbadoz.net> writes:
> 
> 
>>On Fri, 23 Jul 2004, Matthias Andree wrote:
>>
>>
>>>>It's quite obvious that you can't do pppoEthernet w/o a working
>>>>ethernet link.
>>>
>>>The link IS working: if I use rp-pppoe, a user-space PPPoE driver, it
>>>works on the same interface where ppp (with ng_pppoe) fails.
>>
>>can you please check if rp-pppoe does put the interface into promisc
>>mode ?
> 
> 
> I have checked, zero hints to promiscuous mode:
> ifconfig has no "PROMISC" tag,
> nothing in dmesg or /var/log/messages.
> 
> 
>>and - do you have s.th. like
>>	ifconfig_xl1="up"
>>in your /etc/rc.conf ? Else I think it won't work with ppp/ng_pppoe
> 
> 
> Yes, I do. ppp/ng_pppoe has worked a few weeks ago and I hadn't changed
> my configuration for months.
> 

Well, now ng_pppoe stops working with sk(4) driver. The breakage was 
introduced in the last week.

Here's ppp logs:

Jul 28 23:19:58 ultra ppp[288]: tun0: Timer: Select returns 1
Jul 28 23:19:58 ultra ppp[288]: tun0: DNS: DIALbound query IN AAAA 
ntp.psn.ru.
Jul 28 23:19:58 ultra ppp[288]: tun0: Phase: bundle: Establish
Jul 28 23:19:58 ultra ppp[288]: tun0: Phase: deflink: closed -> opening
Jul 28 23:19:58 ultra ppp[288]: tun0: TCP/IP: OUT UDP: 172.16.0.1:63737 
---> 213.85.31.229:53 (28/56)
Jul 28 23:19:58 ultra ppp[288]: tun0: DNS: OUTbound query IN AAAA 
ntp.psn.ru.
Jul 28 23:19:58 ultra ppp[288]: tun0: Debug: m_enqueue: len = 1
Jul 28 23:19:58 ultra ppp[288]: tun0: Timer: tun: fdset(r) 9
Jul 28 23:19:58 ultra ppp[288]: tun0: ID0: 4 = kldload("netgraph")
Jul 28 23:19:58 ultra ppp[288]: tun0: ID0: 5 = kldload("ng_ether")
Jul 28 23:19:58 ultra ppp[288]: tun0: ID0: 6 = kldload("ng_pppoe")
Jul 28 23:19:58 ultra ppp[288]: tun0: ID0: 7 = kldload("ng_socket")
Jul 28 23:19:58 ultra ppp[288]: tun0: ID0: 0 = NgMkSockNode("", &cs, &ds)
Jul 28 23:19:58 ultra ppp[288]: tun0: Debug: List of netgraph node 
``sk0:'' (id 1) hooks:
Jul 28 23:19:58 ultra ppp[288]: tun0: Debug: Creating PPPoE netgraph 
node [1]:orphans -> ethernet
Jul 28 23:19:58 ultra ppp[288]: tun0: Debug: Connecting netgraph socket 
.:tun0 -> sk0:orphans:tun0
Jul 28 23:19:58 ultra ppp[288]: tun0: ID0: 6 = socket(2, 2, 0)
Jul 28 23:19:58 ultra ppp[288]: tun0: ID0: 0 = ioctl(6, 3223349521, 
0xbfbfddb0)
Jul 28 23:19:58 ultra ppp[288]: tun0: ID0: 0 = ioctl(6, 2149607696, 
0xbfbfddb0)
Jul 28 23:19:58 ultra ppp[288]: tun0: Debug: Sending PPPOE_CONNECT to .:tun0
Jul 28 23:19:58 ultra ppp[288]: tun0: Debug: Found the following interfaces:
Jul 28 23:19:58 ultra ppp[288]: tun0: Debug:  Index 1, name "sk0"
Jul 28 23:19:58 ultra ppp[288]: tun0: Debug:  Index 2, name "fwe0"
Jul 28 23:19:58 ultra ppp[288]: tun0: Debug:  Index 3, name "plip0"
Jul 28 23:19:58 ultra ppp[288]: tun0: Debug:  Index 4, name "pflog0"
Jul 28 23:19:58 ultra ppp[288]: tun0: Debug:  Index 5, name "lo0"
Jul 28 23:19:58 ultra ppp[288]: tun0: Debug:  Index 6, name "tun0"
Jul 28 23:19:58 ultra ppp[288]: tun0: Timer: timer_Start: Inserting 
physical throughput timer[0x80be068]
Jul 28 23:19:58 ultra ppp[288]: tun0: Phase: deflink: Connected!
Jul 28 23:19:58 ultra ppp[288]: tun0: Phase: deflink: opening -> dial
Jul 28 23:19:58 ultra ppp[288]: tun0: Chat: deflink: Dial attempt 1 of 1
Jul 28 23:19:58 ultra ppp[288]: tun0: Phase: deflink: dial -> carrier
Jul 28 23:19:58 ultra ppp[288]: tun0: Debug: Waiting for carrier
Jul 28 23:19:59 ultra ppp[288]: tun0: Timer: Select returns -1
Jul 28 23:19:59 ultra ppp[288]: tun0: Timer: ---- Begin of Timer Service 
List---
Jul 28 23:19:59 ultra ppp[288]: tun0: Timer: physical throughput 
timer[0x80be068]: freq = 1.00s, next = 0.00s, state = running
Jul 28 23:19:59 ultra ppp[288]: tun0: Timer: ---- End of Timer Service 
List ---
Jul 28 23:19:59 ultra ppp[288]: tun0: Timer: timer_Start: Inserting 
physical throughput timer[0x80be068]
Jul 28 23:19:59 ultra ppp[288]: tun0: Timer: tun: fdset(r) 9
Jul 28 23:19:59 ultra ppp[288]: tun0: Debug: Waiting for carrier
Jul 28 23:20:00 ultra ppp[288]: tun0: Timer: Select returns -1
Jul 28 23:20:00 ultra ppp[288]: tun0: Timer: ---- Begin of Timer Service 
List---
Jul 28 23:20:00 ultra ppp[288]: tun0: Timer: physical throughput 
timer[0x80be068]: freq = 1.00s, next = 0.00s, state = running
Jul 28 23:20:00 ultra ppp[288]: tun0: Timer: ---- End of Timer Service 
List ---
Jul 28 23:20:00 ultra ppp[288]: tun0: Timer: timer_Start: Inserting 
physical throughput timer[0x80be068]
Jul 28 23:20:00 ultra ppp[288]: tun0: Timer: tun: fdset(r) 9
Jul 28 23:20:00 ultra ppp[288]: tun0: Debug: Waiting for carrier
Jul 28 23:20:01 ultra ppp[288]: tun0: Timer: Select returns -1
Jul 28 23:20:01 ultra ppp[288]: tun0: Timer: ---- Begin of Timer Service 
List---
Jul 28 23:20:01 ultra ppp[288]: tun0: Timer: physical throughput 
timer[0x80be068]: freq = 1.00s, next = 0.00s, state = running
Jul 28 23:20:01 ultra ppp[288]: tun0: Timer: ---- End of Timer Service 
List ---
Jul 28 23:20:01 ultra ppp[288]: tun0: Timer: timer_Start: Inserting 
physical throughput timer[0x80be068]
Jul 28 23:20:01 ultra ppp[288]: tun0: Timer: tun: fdset(r) 9
Jul 28 23:20:01 ultra ppp[288]: tun0: Debug: Waiting for carrier
Jul 28 23:20:02 ultra ppp[288]: tun0: Timer: Select returns -1
Jul 28 23:20:02 ultra ppp[288]: tun0: Timer: ---- Begin of Timer Service 
List---
Jul 28 23:20:02 ultra ppp[288]: tun0: Timer: physical throughput 
timer[0x80be068]: freq = 1.00s, next = 0.00s, state = running
Jul 28 23:20:02 ultra ppp[288]: tun0: Timer: ---- End of Timer Service 
List ---
Jul 28 23:20:02 ultra ppp[288]: tun0: Timer: timer_Start: Inserting 
physical throughput timer[0x80be068]
Jul 28 23:20:02 ultra ppp[288]: tun0: Timer: tun: fdset(r) 9
Jul 28 23:20:02 ultra ppp[288]: tun0: Debug: Waiting for carrier
Jul 28 23:20:03 ultra ppp[288]: tun0: Timer: Select returns 1
Jul 28 23:20:03 ultra ppp[288]: tun0: TCP/IP: OUT UDP: 172.16.0.1:61436 
---> 213.85.31.229:53 (28/56)
Jul 28 23:20:03 ultra ppp[288]: tun0: DNS: OUTbound query IN AAAA 
ntp.psn.ru.
Jul 28 23:20:03 ultra ppp[288]: tun0: Debug: m_enqueue: len = 2
Jul 28 23:20:03 ultra ppp[288]: tun0: Timer: tun: fdset(r) 9
Jul 28 23:20:03 ultra ppp[288]: tun0: Phase: deflink: Disconnected!
Jul 28 23:20:03 ultra ppp[288]: tun0: Phase: deflink: carrier -> hangup
Jul 28 23:20:03 ultra ppp[288]: tun0: Debug: deflink: Close
Jul 28 23:20:03 ultra ppp[288]: tun0: Phase: deflink: Connect time: 5 
secs: 0 octets in, 0 octets out
Jul 28 23:20:03 ultra ppp[288]: tun0: Phase: deflink: 0 packets in, 0 
packets out
Jul 28 23:20:03 ultra ppp[288]: tun0: Phase:  total 0 bytes/sec, peak 0 
bytes/sec on Wed Jul 28 23:19:58 2004
Jul 28 23:20:03 ultra ppp[288]: tun0: Phase: deflink: hangup -> closed
Jul 28 23:20:03 ultra ppp[288]: tun0: Phase: bundle: Dead


-- 
Maxim Maximov
Received on Wed Jul 28 2004 - 17:41:53 UTC

This archive was generated by hypermail 2.4.0 : Wed May 19 2021 - 11:38:03 UTC