PPPoE
Julian Elischer
julian at elischer.org
Mon Jun 21 16:06:15 GMT 2004
a tcpdump of the ethernet interface can be useful too..
On Mon, 21 Jun 2004, Yohan wrote:
> Gleb,
>
> I have enclosed another copy of ppp.log with slightly
> different results. Thanks for the help.
>
> regards
>
> Yohann
>
> ppp.log
>
> Jun 21 14:00:53 chennai ppp[353]: Phase: Using
> interface: tun0
> Jun 21 14:00:53 chennai ppp[353]: Phase: deflink:
> Created in closed state
> Jun 21 14:00:53 chennai ppp[353]: tun0: Command: bsnl:
> ident user-ppp VERSION (built COMPILATIONDATE)
> Jun 21 14:00:53 chennai ppp[353]: tun0: Command: bsnl:
> set device PPPoE:rl1
> Jun 21 14:00:53 chennai ppp[353]: tun0: Command: bsnl:
> set mru 1492
> Jun 21 14:00:53 chennai ppp[353]: tun0: Command: bsnl:
> set mtu 1492
> Jun 21 14:00:53 chennai ppp[353]: tun0: Command: bsnl:
> set speed sync
> Jun 21 14:00:53 chennai ppp[353]: tun0: Command: bsnl:
> enable lqr
> Jun 21 14:00:53 chennai ppp[353]: tun0: Command: bsnl:
> set cd off
> Jun 21 14:00:53 chennai ppp[353]: tun0: Command: bsnl:
> set dial
> Jun 21 14:00:53 chennai ppp[353]: tun0: Command: bsnl:
> set login
> Jun 21 14:00:53 chennai ppp[353]: tun0: Command: bsnl:
> set timeout 0
> Jun 21 14:00:53 chennai ppp[353]: tun0: Command: bsnl:
> set authname hddias33
> Jun 21 14:00:53 chennai ppp[353]: tun0: Command: bsnl:
> set authkey ********
> Jun 21 14:00:53 chennai ppp[353]: tun0: Command: bsnl:
> set ifaddr 10.0.0.1/0 10.0.0.2/0 255.255.255.0 0.0.0.0
>
> Jun 21 14:00:53 chennai ppp[353]: tun0: Command: bsnl:
> delete all
> Jun 21 14:00:53 chennai ppp[353]: tun0: Debug:
> route_IfDelete (8)
> Jun 21 14:00:53 chennai ppp[353]: tun0: Command: bsnl:
> add default HISADDR
> Jun 21 14:00:53 chennai ppp[353]: tun0: ID0: 9 =
> socket(17, 3, 0)
> Jun 21 14:00:53 chennai ppp[353]: tun0: ID0: 140 =
> write(9, data, 140)
> Jun 21 14:00:53 chennai ppp[353]: tun0: Debug: wrote
> 140: cmd = Add, dst = 0.0.0.0/0, gateway = 10.0.0.2
> Jun 21 14:00:53 chennai ppp[353]: tun0: Command: bsnl:
> enable dns
> Jun 21 14:00:53 chennai ppp[354]: tun0: ID0:
> 0x282948a0 = fopen("/var/run/tun0.pid", "w")
> Jun 21 14:00:53 chennai ppp[354]: tun0: Phase: PPP
> Started (ddial mode).
> Jun 21 14:00:53 chennai ppp[354]: tun0: Phase: bundle:
> Establish
> Jun 21 14:00:53 chennai ppp[354]: tun0: Phase:
> deflink: closed -> opening
> Jun 21 14:00:53 chennai ppp[354]: tun0: ID0: 0 =
> NgMkSockNode("", &cs, &ds)
> Jun 21 14:00:53 chennai ppp[354]: tun0: Debug: List of
> netgraph node ``rl1:'' (id 2) hooks:
> Jun 21 14:00:53 chennai ppp[354]: tun0: Debug:
> Creating PPPoE netgraph node [2]:orphans -> ethernet
> Jun 21 14:00:53 chennai ppp[354]: tun0: Debug:
> Connecting netgraph socket .:tun0 -> rl1:orphans:tun0
> Jun 21 14:00:53 chennai ppp[354]: tun0: ID0: 2 =
> socket(2, 2, 0)
> Jun 21 14:00:53 chennai ppp[354]: tun0: ID0: 0 =
> ioctl(2, 3223349521, 0xbfbfed50)
> Jun 21 14:00:53 chennai ppp[354]: tun0: ID0: 0 =
> ioctl(2, 2149607696, 0xbfbfed50)
> Jun 21 14:00:53 chennai ppp[354]: tun0: Debug: Sending
> PPPOE_CONNECT to .:tun0
> Jun 21 14:00:53 chennai ppp[354]: tun0: Warning:
> deflink: Carrier must be set, using ``set cd 5!''
> Jun 21 14:00:53 chennai ppp[354]: tun0: Debug: Found
> the following interfaces:
> Jun 21 14:00:53 chennai ppp[354]: tun0: Debug: Index
> 1, name "rl0"
> Jun 21 14:00:53 chennai ppp[354]: tun0: Debug: Index
> 2, name "rl1"
> Jun 21 14:00:53 chennai ppp[354]: tun0: Debug: Index
> 3, name "lp0"
> Jun 21 14:00:53 chennai ppp[354]: tun0: Debug: Index
> 4, name "lo0"
> Jun 21 14:00:53 chennai ppp[354]: tun0: Debug: Index
> 5, name "ppp0"
> Jun 21 14:00:53 chennai ppp[354]: tun0: Debug: Index
> 6, name "sl0"
> Jun 21 14:00:53 chennai ppp[354]: tun0: Debug: Index
> 7, name "faith0"
> Jun 21 14:00:53 chennai ppp[354]: tun0: Debug: Index
> 8, name "tun0"
> Jun 21 14:00:53 chennai ppp[354]: tun0: Timer:
> timer_Start: Inserting physical throughput
> timer[0x80ba068]
> Jun 21 14:00:53 chennai ppp[354]: tun0: Phase:
> deflink: Connected!
> Jun 21 14:00:53 chennai ppp[354]: tun0: Phase:
> deflink: opening -> dial
> Jun 21 14:00:53 chennai ppp[354]: tun0: Phase:
> deflink: dial -> carrier
> Jun 21 14:00:53 chennai ppp[354]: tun0: Debug: Waiting
> for carrier
> Jun 21 14:00:54 chennai ppp[354]: tun0: Timer: Select
> returns -1
> Jun 21 14:00:54 chennai ppp[354]: tun0: Timer: ----
> Begin of Timer Service List---
> Jun 21 14:00:54 chennai ppp[354]: tun0: Timer:
> physical throughput timer[0x80ba068]: freq = 1.00s,
> next = 0.00s, state = running
> Jun 21 14:00:54 chennai ppp[354]: tun0: Timer: ----
> End of Timer Service List ---
> Jun 21 14:00:54 chennai ppp[354]: tun0: Timer:
> timer_Start: Inserting physical throughput
> timer[0x80ba068]
> Jun 21 14:00:54 chennai ppp[354]: tun0: Phase:
> Received NGM_PPPOE_ACNAME (hook "BANYAN")
> Jun 21 14:00:54 chennai ppp[354]: tun0: Debug: Waiting
> for carrier
> Jun 21 14:00:55 chennai ppp[354]: tun0: Timer: Select
> returns -1
> Jun 21 14:00:55 chennai ppp[354]: tun0: Timer: ----
> Begin of Timer Service List---
> Jun 21 14:00:55 chennai ppp[354]: tun0: Timer:
> physical throughput timer[0x80ba068]: freq = 1.00s,
> next = 0.00s, state = running
> Jun 21 14:00:55 chennai ppp[354]: tun0: Timer: ----
> End of Timer Service List ---
> Jun 21 14:00:55 chennai ppp[354]: tun0: Timer:
> timer_Start: Inserting physical throughput
> timer[0x80ba068]
> Jun 21 14:00:55 chennai ppp[354]: tun0: Debug: Waiting
> for carrier
> Jun 21 14:00:56 chennai ppp[354]: tun0: Timer: Select
> returns -1
> Jun 21 14:00:56 chennai ppp[354]: tun0: Timer: ----
> Begin of Timer Service List---
> Jun 21 14:00:56 chennai ppp[354]: tun0: Timer:
> physical throughput timer[0x80ba068]: freq = 1.00s,
> next = 0.00s, state = running
> Jun 21 14:00:56 chennai ppp[354]: tun0: Timer: ----
> End of Timer Service List ---
> Jun 21 14:00:56 chennai ppp[354]: tun0: Timer:
> timer_Start: Inserting physical throughput
> timer[0x80ba068]
> Jun 21 14:00:56 chennai ppp[354]: tun0: Debug: Waiting
> for carrier
> Jun 21 14:00:57 chennai ppp[354]: tun0: Timer: Select
> returns -1
> Jun 21 14:00:57 chennai ppp[354]: tun0: Timer: ----
> Begin of Timer Service List---
> Jun 21 14:00:57 chennai ppp[354]: tun0: Timer:
> physical throughput timer[0x80ba068]: freq = 1.00s,
> next = 0.00s, state = running
> Jun 21 14:00:57 chennai ppp[354]: tun0: Timer: ----
> End of Timer Service List ---
> Jun 21 14:00:57 chennai ppp[354]: tun0: Timer:
> timer_Start: Inserting physical throughput
> timer[0x80ba068]
> Jun 21 14:00:57 chennai ppp[354]: tun0: Debug: Waiting
> for carrier
> Jun 21 14:00:58 chennai ppp[354]: tun0: Timer: Select
> returns -1
> Jun 21 14:00:58 chennai ppp[354]: tun0: Timer: ----
> Begin of Timer Service List---
> Jun 21 14:00:58 chennai ppp[354]: tun0: Timer:
> physical throughput timer[0x80ba068]: freq = 1.00s,
> next = 0.00s, state = running
> Jun 21 14:00:58 chennai ppp[354]: tun0: Timer: ----
> End of Timer Service List ---
> Jun 21 14:00:58 chennai ppp[354]: tun0: Timer:
> timer_Start: Inserting physical throughput
> timer[0x80ba068]
> Jun 21 14:00:58 chennai ppp[354]: tun0: Phase:
> deflink: Disconnected!
> Jun 21 14:00:58 chennai ppp[354]: tun0: Phase:
> deflink: carrier -> hangup
> Jun 21 14:00:58 chennai ppp[354]: tun0: Debug:
> deflink: Close
> Jun 21 14:00:58 chennai ppp[354]: tun0: Phase:
> deflink: Connect time: 5 secs: 0 octets in, 0 octets
> out
> Jun 21 14:00:58 chennai ppp[354]: tun0: Phase:
> deflink: 0 packets in, 0 packets out
> Jun 21 14:00:58 chennai ppp[354]: tun0: Phase: total
> 0 bytes/sec, peak 0 bytes/sec on Mon Jun 21 14:00:53
> 2004
> Jun 21 14:00:58 chennai ppp[354]: tun0: Phase:
> deflink: hangup -> opening
> Jun 21 14:00:58 chennai ppp[354]: tun0: Timer:
> timer_Start: Inserting dial timer[0x80b7d44]
> Jun 21 14:00:58 chennai ppp[354]: tun0: Phase:
> deflink: Enter pause (30) for redialing.
> Jun 21 14:01:28 chennai ppp[354]: tun0: Timer: Select
> returns -1
> Jun 21 14:01:28 chennai ppp[354]: tun0: Timer: ----
> Begin of Timer Service List---
> Jun 21 14:01:28 chennai ppp[354]: tun0: Timer: dial
> timer[0x80b7d44]: freq = 30.00s, next = 0.00s, state =
> running
> Jun 21 14:01:28 chennai ppp[354]: tun0: Timer: ----
> End of Timer Service List ---
> Jun 21 14:01:28 chennai ppp[354]: tun0: Chat: deflink:
> Redial timer expired.
> Jun 21 14:01:28 chennai ppp[354]: tun0: ID0: 0 =
> NgMkSockNode("", &cs, &ds)
> Jun 21 14:01:28 chennai ppp[354]: tun0: Debug: List of
> netgraph node ``rl1:'' (id 2) hooks:
> Jun 21 14:01:28 chennai ppp[354]: tun0: Debug: Found
> orphans -> ethernet
> Jun 21 14:01:28 chennai ppp[354]: tun0: Debug:
> Connecting netgraph socket .:tun0 -> [4]::tun0
> Jun 21 14:01:28 chennai ppp[354]: tun0: ID0: 2 =
> socket(2, 2, 0)
> Jun 21 14:01:28 chennai ppp[354]: tun0: ID0: 0 =
> ioctl(2, 3223349521, 0xbfbfed50)
> Jun 21 14:01:28 chennai ppp[354]: tun0: ID0: 0 =
> ioctl(2, 2149607696, 0xbfbfed50)
> Jun 21 14:01:28 chennai ppp[354]: tun0: Debug: Sending
> PPPOE_CONNECT to .:tun0
> Jun 21 14:01:28 chennai ppp[354]: tun0: Warning:
> deflink: Carrier must be set, using ``set cd 5!''
> Jun 21 14:01:28 chennai ppp[354]: tun0: Timer:
> timer_Start: Inserting physical throughput
> timer[0x80ba068]
> Jun 21 14:01:28 chennai ppp[354]: tun0: Phase:
> deflink: Connected!
> Jun 21 14:01:28 chennai ppp[354]: tun0: Phase:
> deflink: opening -> dial
> Jun 21 14:01:28 chennai ppp[354]: tun0: Phase:
> deflink: dial -> carrier
> Jun 21 14:01:28 chennai ppp[354]: tun0: Debug: Waiting
> for carrier
> Jun 21 14:01:29 chennai ppp[354]: tun0: Timer: Select
> returns -1
> Jun 21 14:01:29 chennai ppp[354]: tun0: Timer: ----
> Begin of Timer Service List---
> Jun 21 14:01:29 chennai ppp[354]: tun0: Timer:
> physical throughput timer[0x80ba068]: freq = 1.00s,
> next = 0.00s, state = running
> Jun 21 14:01:29 chennai ppp[354]: tun0: Timer: ----
> End of Timer Service List ---
> Jun 21 14:01:29 chennai ppp[354]: tun0: Timer:
> timer_Start: Inserting physical throughput
> timer[0x80ba068]
> Jun 21 14:01:29 chennai ppp[354]: tun0: Phase:
> Received NGM_PPPOE_ACNAME (hook "BANYAN")
> Jun 21 14:01:29 chennai ppp[354]: tun0: Debug: Waiting
> for carrier
> Jun 21 14:01:30 chennai ppp[354]: tun0: Timer: Select
> returns -1
> Jun 21 14:01:30 chennai ppp[354]: tun0: Timer: ----
> Begin of Timer Service List---
> Jun 21 14:01:30 chennai ppp[354]: tun0: Timer:
> physical throughput timer[0x80ba068]: freq = 1.00s,
> next = 0.00s, state = running
> Jun 21 14:01:30 chennai ppp[354]: tun0: Timer: ----
> End of Timer Service List ---
> Jun 21 14:01:30 chennai ppp[354]: tun0: Timer:
> timer_Start: Inserting physical throughput
> timer[0x80ba068]
> Jun 21 14:01:30 chennai ppp[354]: tun0: Debug: Waiting
> for carrier
> Jun 21 14:01:31 chennai ppp[354]: tun0: Timer: Select
> returns -1
> Jun 21 14:01:31 chennai ppp[354]: tun0: Timer: ----
> Begin of Timer Service List---
> Jun 21 14:01:31 chennai ppp[354]: tun0: Timer:
> physical throughput timer[0x80ba068]: freq = 1.00s,
> next = 0.00s, state = running
> Jun 21 14:01:31 chennai ppp[354]: tun0: Timer: ----
> End of Timer Service List ---
> Jun 21 14:01:31 chennai ppp[354]: tun0: Timer:
> timer_Start: Inserting physical throughput
> timer[0x80ba068]
> Jun 21 14:01:31 chennai ppp[354]: tun0: Debug: Waiting
> for carrier
> Jun 21 14:01:32 chennai ppp[354]: tun0: Timer: Select
> returns -1
> Jun 21 14:01:32 chennai ppp[354]: tun0: Timer: ----
> Begin of Timer Service List---
> Jun 21 14:01:32 chennai ppp[354]: tun0: Timer:
> physical throughput timer[0x80ba068]: freq = 1.00s,
> next = 0.00s, state = running
> Jun 21 14:01:32 chennai ppp[354]: tun0: Timer: ----
> End of Timer Service List ---
> Jun 21 14:01:32 chennai ppp[354]: tun0: Timer:
> timer_Start: Inserting physical throughput
> timer[0x80ba068]
> Jun 21 14:01:32 chennai ppp[354]: tun0: Debug: Waiting
> for carrier
> Jun 21 14:01:33 chennai ppp[354]: tun0: Timer: Select
> returns -1
> Jun 21 14:01:33 chennai ppp[354]: tun0: Timer: ----
> Begin of Timer Service List---
> Jun 21 14:01:33 chennai ppp[354]: tun0: Timer:
> physical throughput timer[0x80ba068]: freq = 1.00s,
> next = 0.00s, state = running
> Jun 21 14:01:33 chennai ppp[354]: tun0: Timer: ----
> End of Timer Service List ---
> Jun 21 14:01:33 chennai ppp[354]: tun0: Timer:
> timer_Start: Inserting physical throughput
> timer[0x80ba068]
> Jun 21 14:01:33 chennai ppp[354]: tun0: Phase:
> deflink: Disconnected!
> Jun 21 14:01:33 chennai ppp[354]: tun0: Phase:
> deflink: carrier -> hangup
> Jun 21 14:01:33 chennai ppp[354]: tun0: Debug:
> deflink: Close
> Jun 21 14:01:33 chennai ppp[354]: tun0: Phase:
> deflink: Connect time: 5 secs: 0 octets in, 0 octets
> out
> Jun 21 14:01:33 chennai ppp[354]: tun0: Phase:
> deflink: 0 packets in, 0 packets out
> Jun 21 14:01:33 chennai ppp[354]: tun0: Phase: total
> 0 bytes/sec, peak 0 bytes/sec on Mon Jun 21 14:01:28
> 2004
> Jun 21 14:01:33 chennai ppp[354]: tun0: Phase:
> deflink: hangup -> opening
> Jun 21 14:01:33 chennai ppp[354]: tun0: Timer:
> timer_Start: Inserting dial timer[0x80b7d44]
> Jun 21 14:01:33 chennai ppp[354]: tun0: Phase:
> deflink: Enter pause (30) for redialing.
> Jun 21 14:02:03 chennai ppp[354]: tun0: Timer: Select
> returns -1
> Jun 21 14:02:03 chennai ppp[354]: tun0: Timer: ----
> Begin of Timer Service List---
> Jun 21 14:02:03 chennai ppp[354]: tun0: Timer: dial
> timer[0x80b7d44]: freq = 30.00s, next = 0.00s, state =
> running
> Jun 21 14:02:03 chennai ppp[354]: tun0: Timer: ----
> End of Timer Service List ---
> Jun 21 14:02:03 chennai ppp[354]: tun0: Chat: deflink:
> Redial timer expired.
> Jun 21 14:02:03 chennai ppp[354]: tun0: ID0: 0 =
> NgMkSockNode("", &cs, &ds)
> Jun 21 14:02:03 chennai ppp[354]: tun0: Debug: List of
> netgraph node ``rl1:'' (id 2) hooks:
> Jun 21 14:02:03 chennai ppp[354]: tun0: Debug: Found
> orphans -> ethernet
> Jun 21 14:02:03 chennai ppp[354]: tun0: Debug:
> Connecting netgraph socket .:tun0 -> [4]::tun0
> Jun 21 14:02:03 chennai ppp[354]: tun0: ID0: 2 =
> socket(2, 2, 0)
> Jun 21 14:02:03 chennai ppp[354]: tun0: ID0: 0 =
> ioctl(2, 3223349521, 0xbfbfed50)
> Jun 21 14:02:03 chennai ppp[354]: tun0: ID0: 0 =
> ioctl(2, 2149607696, 0xbfbfed50)
> Jun 21 14:02:03 chennai ppp[354]: tun0: Debug: Sending
> PPPOE_CONNECT to .:tun0
> Jun 21 14:02:03 chennai ppp[354]: tun0: Warning:
> deflink: Carrier must be set, using ``set cd 5!''
> Jun 21 14:02:03 chennai ppp[354]: tun0: Timer:
> timer_Start: Inserting physical throughput
> timer[0x80ba068]
> Jun 21 14:02:03 chennai ppp[354]: tun0: Phase:
> deflink: Connected!
> Jun 21 14:02:03 chennai ppp[354]: tun0: Phase:
> deflink: opening -> dial
> Jun 21 14:02:03 chennai ppp[354]: tun0: Phase:
> deflink: dial -> carrier
> Jun 21 14:02:03 chennai ppp[354]: tun0: Debug: Waiting
> for carrier
> Jun 21 14:02:04 chennai ppp[354]: tun0: Timer: Select
> returns -1
> Jun 21 14:02:04 chennai ppp[354]: tun0: Timer: ----
> Begin of Timer Service List---
> Jun 21 14:02:04 chennai ppp[354]: tun0: Timer:
> physical throughput timer[0x80ba068]: freq = 1.00s,
> next = 0.00s, state = running
> Jun 21 14:02:04 chennai ppp[354]: tun0: Timer: ----
> End of Timer Service List ---
> Jun 21 14:02:04 chennai ppp[354]: tun0: Timer:
> timer_Start: Inserting physical throughput
> timer[0x80ba068]
> Jun 21 14:02:04 chennai ppp[354]: tun0: Phase:
> Received NGM_PPPOE_ACNAME (hook "BANYAN")
> Jun 21 14:02:04 chennai ppp[354]: tun0: Debug: Waiting
> for carrier
> Jun 21 14:02:05 chennai ppp[354]: tun0: Timer: Select
> returns -1
> Jun 21 14:02:05 chennai ppp[354]: tun0: Timer: ----
> Begin of Timer Service List---
> Jun 21 14:02:05 chennai ppp[354]: tun0: Timer:
> physical throughput timer[0x80ba068]: freq = 1.00s,
> next = 0.00s, state = running
> Jun 21 14:02:05 chennai ppp[354]: tun0: Timer: ----
> End of Timer Service List ---
> Jun 21 14:02:05 chennai ppp[354]: tun0: Timer:
> timer_Start: Inserting physical throughput
> timer[0x80ba068]
> Jun 21 14:02:05 chennai ppp[354]: tun0: Debug: Waiting
> for carrier
> Jun 21 14:02:06 chennai ppp[354]: tun0: Timer: Select
> returns -1
> Jun 21 14:02:06 chennai ppp[354]: tun0: Timer: ----
> Begin of Timer Service List---
> Jun 21 14:02:06 chennai ppp[354]: tun0: Timer:
> physical throughput timer[0x80ba068]: freq = 1.00s,
> next = 0.00s, state = running
> Jun 21 14:02:06 chennai ppp[354]: tun0: Timer: ----
> End of Timer Service List ---
> Jun 21 14:02:06 chennai ppp[354]: tun0: Timer:
> timer_Start: Inserting physical throughput
> timer[0x80ba068]
> Jun 21 14:02:06 chennai ppp[354]: tun0: Debug: Waiting
> for carrier
> Jun 21 14:02:07 chennai ppp[354]: tun0: Timer: Select
> returns -1
> Jun 21 14:02:07 chennai ppp[354]: tun0: Timer: ----
> Begin of Timer Service List---
> Jun 21 14:02:07 chennai ppp[354]: tun0: Timer:
> physical throughput timer[0x80ba068]: freq = 1.00s,
> next = 0.00s, state = running
> Jun 21 14:02:07 chennai ppp[354]: tun0: Timer: ----
> End of Timer Service List ---
> Jun 21 14:02:07 chennai ppp[354]: tun0: Timer:
> timer_Start: Inserting physical throughput
> timer[0x80ba068]
> Jun 21 14:02:07 chennai ppp[354]: tun0: Debug: Waiting
> for carrier
> Jun 21 14:02:08 chennai ppp[354]: tun0: Timer: Select
> returns -1
> Jun 21 14:02:08 chennai ppp[354]: tun0: Timer: ----
> Begin of Timer Service List---
> Jun 21 14:02:08 chennai ppp[354]: tun0: Timer:
> physical throughput timer[0x80ba068]: freq = 1.00s,
> next = 0.00s, state = running
> Jun 21 14:02:08 chennai ppp[354]: tun0: Timer: ----
> End of Timer Service List ---
> Jun 21 14:02:08 chennai ppp[354]: tun0: Timer:
> timer_Start: Inserting physical throughput
> timer[0x80ba068]
> Jun 21 14:02:08 chennai ppp[354]: tun0: Phase:
> deflink: Disconnected!
> Jun 21 14:02:08 chennai ppp[354]: tun0: Phase:
> deflink: carrier -> hangup
> Jun 21 14:02:08 chennai ppp[354]: tun0: Debug:
> deflink: Close
> Jun 21 14:02:08 chennai ppp[354]: tun0: Phase:
> deflink: Connect time: 5 secs: 0 octets in, 0 octets
> out
> Jun 21 14:02:08 chennai ppp[354]: tun0: Phase:
> deflink: 0 packets in, 0 packets out
> Jun 21 14:02:08 chennai ppp[354]: tun0: Phase: total
> 0 bytes/sec, peak 0 bytes/sec on Mon Jun 21 14:02:03
> 2004
> Jun 21 14:02:08 chennai ppp[354]: tun0: Phase:
> deflink: hangup -> opening
> Jun 21 14:02:08 chennai ppp[354]: tun0: Timer:
> timer_Start: Inserting dial timer[0x80b7d44]
> Jun 21 14:02:08 chennai ppp[354]: tun0: Phase:
> deflink: Enter pause (30) for redialing.
> Jun 21 14:02:38 chennai ppp[354]: tun0: Timer: Select
> returns -1
> Jun 21 14:02:38 chennai ppp[354]: tun0: Timer: ----
> Begin of Timer Service List---
> Jun 21 14:02:38 chennai ppp[354]: tun0: Timer: dial
> timer[0x80b7d44]: freq = 30.00s, next = 0.00s, state =
> running
> Jun 21 14:02:38 chennai ppp[354]: tun0: Timer: ----
> End of Timer Service List ---
> Jun 21 14:02:38 chennai ppp[354]: tun0: Chat: deflink:
> Redial timer expired.
> Jun 21 14:02:38 chennai ppp[354]: tun0: ID0: 0 =
> NgMkSockNode("", &cs, &ds)
> Jun 21 14:02:38 chennai ppp[354]: tun0: Debug: List of
> netgraph node ``rl1:'' (id 2) hooks:
> Jun 21 14:02:38 chennai ppp[354]: tun0: Debug: Found
> orphans -> ethernet
> Jun 21 14:02:38 chennai ppp[354]: tun0: Debug:
> Connecting netgraph socket .:tun0 -> [4]::tun0
> Jun 21 14:02:38 chennai ppp[354]: tun0: ID0: 2 =
> socket(2, 2, 0)
> Jun 21 14:02:38 chennai ppp[354]: tun0: ID0: 0 =
> ioctl(2, 3223349521, 0xbfbfed50)
> Jun 21 14:02:38 chennai ppp[354]: tun0: ID0: 0 =
> ioctl(2, 2149607696, 0xbfbfed50)
> Jun 21 14:02:38 chennai ppp[354]: tun0: Debug: Sending
> PPPOE_CONNECT to .:tun0
> Jun 21 14:02:38 chennai ppp[354]: tun0: Warning:
> deflink: Carrier must be set, using ``set cd 5!''
> Jun 21 14:02:38 chennai ppp[354]: tun0: Timer:
> timer_Start: Inserting physical throughput
> timer[0x80ba068]
> Jun 21 14:02:38 chennai ppp[354]: tun0: Phase:
> deflink: Connected!
> Jun 21 14:02:38 chennai ppp[354]: tun0: Phase:
> deflink: opening -> dial
> Jun 21 14:02:38 chennai ppp[354]: tun0: Phase:
> deflink: dial -> carrier
> Jun 21 14:02:38 chennai ppp[354]: tun0: Debug: Waiting
> for carrier
> Jun 21 14:02:39 chennai ppp[354]: tun0: Timer: Select
> returns -1
> Jun 21 14:02:39 chennai ppp[354]: tun0: Timer: ----
> Begin of Timer Service List---
> Jun 21 14:02:39 chennai ppp[354]: tun0: Timer:
> physical throughput timer[0x80ba068]: freq = 1.00s,
> next = 0.00s, state = running
> Jun 21 14:02:39 chennai ppp[354]: tun0: Timer: ----
> End of Timer Service List ---
> Jun 21 14:02:39 chennai ppp[354]: tun0: Timer:
> timer_Start: Inserting physical throughput
> timer[0x80ba068]
> Jun 21 14:02:39 chennai ppp[354]: tun0: Phase:
> Received NGM_PPPOE_ACNAME (hook "BANYAN")
> Jun 21 14:02:39 chennai ppp[354]: tun0: Debug: Waiting
> for carrier
> Jun 21 14:02:40 chennai ppp[354]: tun0: Timer: Select
> returns -1
> Jun 21 14:02:40 chennai ppp[354]: tun0: Timer: ----
> Begin of Timer Service List---
> Jun 21 14:02:40 chennai ppp[354]: tun0: Timer:
> physical throughput timer[0x80ba068]: freq = 1.00s,
> next = 0.00s, state = running
> Jun 21 14:02:40 chennai ppp[354]: tun0: Timer: ----
> End of Timer Service List ---
> Jun 21 14:02:40 chennai ppp[354]: tun0: Timer:
> timer_Start: Inserting physical throughput
> timer[0x80ba068]
> Jun 21 14:02:40 chennai ppp[354]: tun0: Debug: Waiting
> for carrier
> Jun 21 14:02:41 chennai ppp[354]: tun0: Timer: Select
> returns -1
> Jun 21 14:02:41 chennai ppp[354]: tun0: Timer: ----
> Begin of Timer Service List---
> Jun 21 14:02:41 chennai ppp[354]: tun0: Timer:
> physical throughput timer[0x80ba068]: freq = 1.00s,
> next = 0.00s, state = running
> Jun 21 14:02:41 chennai ppp[354]: tun0: Timer: ----
> End of Timer Service List ---
> Jun 21 14:02:41 chennai ppp[354]: tun0: Timer:
> timer_Start: Inserting physical throughput
> timer[0x80ba068]
> Jun 21 14:02:41 chennai ppp[354]: tun0: Debug: Waiting
> for carrier
> Jun 21 14:02:42 chennai ppp[354]: tun0: Timer: Select
> returns -1
> Jun 21 14:02:42 chennai ppp[354]: tun0: Timer: ----
> Begin of Timer Service List---
> Jun 21 14:02:42 chennai ppp[354]: tun0: Timer:
> physical throughput timer[0x80ba068]: freq = 1.00s,
> next = 0.00s, state = running
> Jun 21 14:02:42 chennai ppp[354]: tun0: Timer: ----
> End of Timer Service List ---
> Jun 21 14:02:42 chennai ppp[354]: tun0: Timer:
> timer_Start: Inserting physical throughput
> timer[0x80ba068]
> Jun 21 14:02:42 chennai ppp[354]: tun0: Debug: Waiting
> for carrier
> Jun 21 14:02:43 chennai ppp[354]: tun0: Timer: Select
> returns -1
> Jun 21 14:02:43 chennai ppp[354]: tun0: Timer: ----
> Begin of Timer Service List---
> Jun 21 14:02:43 chennai ppp[354]: tun0: Timer:
> physical throughput timer[0x80ba068]: freq = 1.00s,
> next = 0.00s, state = running
> Jun 21 14:02:43 chennai ppp[354]: tun0: Timer: ----
> End of Timer Service List ---
> Jun 21 14:02:43 chennai ppp[354]: tun0: Timer:
> timer_Start: Inserting physical throughput
> timer[0x80ba068]
> Jun 21 14:02:43 chennai ppp[354]: tun0: Phase:
> deflink: Disconnected!
> Jun 21 14:02:43 chennai ppp[354]: tun0: Phase:
> deflink: carrier -> hangup
> Jun 21 14:02:43 chennai ppp[354]: tun0: Debug:
> deflink: Close
> Jun 21 14:02:43 chennai ppp[354]: tun0: Phase:
> deflink: Connect time: 5 secs: 0 octets in, 0 octets
> out
> Jun 21 14:02:43 chennai ppp[354]: tun0: Phase:
> deflink: 0 packets in, 0 packets out
> Jun 21 14:02:43 chennai ppp[354]: tun0: Phase: total
> 0 bytes/sec, peak 0 bytes/sec on Mon Jun 21 14:02:38
> 2004
> Jun 21 14:02:43 chennai ppp[354]: tun0: Phase:
> deflink: hangup -> opening
> Jun 21 14:02:43 chennai ppp[354]: tun0: Timer:
> timer_Start: Inserting dial timer[0x80b7d44]
> Jun 21 14:02:43 chennai ppp[354]: tun0: Phase:
> deflink: Enter pause (30) for redialing.
> Jun 21 14:03:13 chennai ppp[354]: tun0: Timer: Select
> returns -1
> Jun 21 14:03:13 chennai ppp[354]: tun0: Timer: ----
> Begin of Timer Service List---
> Jun 21 14:03:13 chennai ppp[354]: tun0: Timer: dial
> timer[0x80b7d44]: freq = 30.00s, next = 0.00s, state =
> running
> Jun 21 14:03:13 chennai ppp[354]: tun0: Timer: ----
> End of Timer Service List ---
> Jun 21 14:03:13 chennai ppp[354]: tun0: Chat: deflink:
> Redial timer expired.
> Jun 21 14:03:13 chennai ppp[354]: tun0: ID0: 0 =
> NgMkSockNode("", &cs, &ds)
> Jun 21 14:03:13 chennai ppp[354]: tun0: Debug: List of
> netgraph node ``rl1:'' (id 2) hooks:
> Jun 21 14:03:13 chennai ppp[354]: tun0: Debug: Found
> orphans -> ethernet
> Jun 21 14:03:13 chennai ppp[354]: tun0: Debug:
> Connecting netgraph socket .:tun0 -> [4]::tun0
> Jun 21 14:03:13 chennai ppp[354]: tun0: ID0: 2 =
> socket(2, 2, 0)
> Jun 21 14:03:13 chennai ppp[354]: tun0: ID0: 0 =
> ioctl(2, 3223349521, 0xbfbfed50)
> Jun 21 14:03:13 chennai ppp[354]: tun0: ID0: 0 =
> ioctl(2, 2149607696, 0xbfbfed50)
> Jun 21 14:03:13 chennai ppp[354]: tun0: Debug: Sending
> PPPOE_CONNECT to .:tun0
> Jun 21 14:03:13 chennai ppp[354]: tun0: Warning:
> deflink: Carrier must be set, using ``set cd 5!''
> Jun 21 14:03:13 chennai ppp[354]: tun0: Timer:
> timer_Start: Inserting physical throughput
> timer[0x80ba068]
> Jun 21 14:03:13 chennai ppp[354]: tun0: Phase:
> deflink: Connected!
> Jun 21 14:03:13 chennai ppp[354]: tun0: Phase:
> deflink: opening -> dial
> Jun 21 14:03:13 chennai ppp[354]: tun0: Phase:
> deflink: dial -> carrier
> Jun 21 14:03:13 chennai ppp[354]: tun0: Debug: Waiting
> for carrier
> Jun 21 14:03:14 chennai ppp[354]: tun0: Timer: Select
> returns -1
> Jun 21 14:03:14 chennai ppp[354]: tun0: Timer: ----
> Begin of Timer Service List---
> Jun 21 14:03:14 chennai ppp[354]: tun0: Timer:
> physical throughput timer[0x80ba068]: freq = 1.00s,
> next = 0.00s, state = running
> Jun 21 14:03:14 chennai ppp[354]: tun0: Timer: ----
> End of Timer Service List ---
> Jun 21 14:03:14 chennai ppp[354]: tun0: Timer:
> timer_Start: Inserting physical throughput
> timer[0x80ba068]
> Jun 21 14:03:14 chennai ppp[354]: tun0: Phase:
> Received NGM_PPPOE_ACNAME (hook "BANYAN")
> Jun 21 14:03:14 chennai ppp[354]: tun0: Debug: Waiting
> for carrier
> Jun 21 14:03:15 chennai ppp[354]: tun0: Timer: Select
> returns -1
> Jun 21 14:03:15 chennai ppp[354]: tun0: Timer: ----
> Begin of Timer Service List---
> Jun 21 14:03:15 chennai ppp[354]: tun0: Timer:
> physical throughput timer[0x80ba068]: freq = 1.00s,
> next = 0.00s, state = running
> Jun 21 14:03:15 chennai ppp[354]: tun0: Timer: ----
> End of Timer Service List ---
> Jun 21 14:03:15 chennai ppp[354]: tun0: Timer:
> timer_Start: Inserting physical throughput
> timer[0x80ba068]
> Jun 21 14:03:15 chennai ppp[354]: tun0: Debug: Waiting
> for carrier
> Jun 21 14:03:16 chennai ppp[354]: tun0: Timer: Select
> returns -1
> Jun 21 14:03:16 chennai ppp[354]: tun0: Timer: ----
> Begin of Timer Service List---
> Jun 21 14:03:16 chennai ppp[354]: tun0: Timer:
> physical throughput timer[0x80ba068]: freq = 1.00s,
> next = 0.00s, state = running
> Jun 21 14:03:16 chennai ppp[354]: tun0: Timer: ----
> End of Timer Service List ---
> Jun 21 14:03:16 chennai ppp[354]: tun0: Timer:
> timer_Start: Inserting physical throughput
> timer[0x80ba068]
> Jun 21 14:03:16 chennai ppp[354]: tun0: Debug: Waiting
> for carrier
> Jun 21 14:03:17 chennai ppp[354]: tun0: Timer: Select
> returns -1
> Jun 21 14:03:17 chennai ppp[354]: tun0: Timer: ----
> Begin of Timer Service List---
> Jun 21 14:03:17 chennai ppp[354]: tun0: Timer:
> physical throughput timer[0x80ba068]: freq = 1.00s,
> next = 0.00s, state = running
> Jun 21 14:03:17 chennai ppp[354]: tun0: Timer: ----
> End of Timer Service List ---
> Jun 21 14:03:17 chennai ppp[354]: tun0: Timer:
> timer_Start: Inserting physical throughput
> timer[0x80ba068]
> Jun 21 14:03:17 chennai ppp[354]: tun0: Debug: Waiting
> for carrier
> Jun 21 14:03:18 chennai ppp[354]: tun0: Timer: Select
> returns -1
> Jun 21 14:03:18 chennai ppp[354]: tun0: Timer: ----
> Begin of Timer Service List---
> Jun 21 14:03:18 chennai ppp[354]: tun0: Timer:
> physical throughput timer[0x80ba068]: freq = 1.00s,
> next = 0.00s, state = running
> Jun 21 14:03:18 chennai ppp[354]: tun0: Timer: ----
> End of Timer Service List ---
> Jun 21 14:03:18 chennai ppp[354]: tun0: Timer:
> timer_Start: Inserting physical throughput
> timer[0x80ba068]
> Jun 21 14:03:18 chennai ppp[354]: tun0: Phase:
> deflink: Disconnected!
> Jun 21 14:03:18 chennai ppp[354]: tun0: Phase:
> deflink: carrier -> hangup
> Jun 21 14:03:18 chennai ppp[354]: tun0: Debug:
> deflink: Close
> Jun 21 14:03:18 chennai ppp[354]: tun0: Phase:
> deflink: Connect time: 5 secs: 0 octets in, 0 octets
> out
> Jun 21 14:03:18 chennai ppp[354]: tun0: Phase:
> deflink: 0 packets in, 0 packets out
> Jun 21 14:03:18 chennai ppp[354]: tun0: Phase: total
> 0 bytes/sec, peak 0 bytes/sec on Mon Jun 21 14:03:13
> 2004
> Jun 21 14:03:18 chennai ppp[354]: tun0: Phase:
> deflink: hangup -> opening
> Jun 21 14:03:18 chennai ppp[354]: tun0: Timer:
> timer_Start: Inserting dial timer[0x80b7d44]
> Jun 21 14:03:18 chennai ppp[354]: tun0: Phase:
> deflink: Enter pause (30) for redialing.
> Jun 21 14:03:48 chennai ppp[354]: tun0: Timer: Select
> returns -1
> Jun 21 14:03:48 chennai ppp[354]: tun0: Timer: ----
> Begin of Timer Service List---
> Jun 21 14:03:48 chennai ppp[354]: tun0: Timer: dial
> timer[0x80b7d44]: freq = 30.00s, next = 0.00s, state =
> running
> Jun 21 14:03:48 chennai ppp[354]: tun0: Timer: ----
> End of Timer Service List ---
> Jun 21 14:03:48 chennai ppp[354]: tun0: Chat: deflink:
> Redial timer expired.
> Jun 21 14:03:48 chennai ppp[354]: tun0: ID0: 0 =
> NgMkSockNode("", &cs, &ds)
> Jun 21 14:03:48 chennai ppp[354]: tun0: Debug: List of
> netgraph node ``rl1:'' (id 2) hooks:
> Jun 21 14:03:48 chennai ppp[354]: tun0: Debug: Found
> orphans -> ethernet
> Jun 21 14:03:48 chennai ppp[354]: tun0: Debug:
> Connecting netgraph socket .:tun0 -> [4]::tun0
> Jun 21 14:03:48 chennai ppp[354]: tun0: ID0: 2 =
> socket(2, 2, 0)
> Jun 21 14:03:48 chennai ppp[354]: tun0: ID0: 0 =
> ioctl(2, 3223349521, 0xbfbfed50)
> Jun 21 14:03:48 chennai ppp[354]: tun0: ID0: 0 =
> ioctl(2, 2149607696, 0xbfbfed50)
> Jun 21 14:03:48 chennai ppp[354]: tun0: Debug: Sending
> PPPOE_CONNECT to .:tun0
> Jun 21 14:03:48 chennai ppp[354]: tun0: Warning:
> deflink: Carrier must be set, using ``set cd 5!''
> Jun 21 14:03:48 chennai ppp[354]: tun0: Timer:
> timer_Start: Inserting physical throughput
> timer[0x80ba068]
> Jun 21 14:03:48 chennai ppp[354]: tun0: Phase:
> deflink: Connected!
> Jun 21 14:03:48 chennai ppp[354]: tun0: Phase:
> deflink: opening -> dial
> Jun 21 14:03:48 chennai ppp[354]: tun0: Phase:
> deflink: dial -> carrier
> Jun 21 14:03:48 chennai ppp[354]: tun0: Debug: Waiting
> for carrier
> Jun 21 14:03:49 chennai ppp[354]: tun0: Timer: Select
> returns -1
> Jun 21 14:03:49 chennai ppp[354]: tun0: Timer: ----
> Begin of Timer Service List---
> Jun 21 14:03:49 chennai ppp[354]: tun0: Timer:
> physical throughput timer[0x80ba068]: freq = 1.00s,
> next = 0.00s, state = running
> Jun 21 14:03:49 chennai ppp[354]: tun0: Timer: ----
> End of Timer Service List ---
> Jun 21 14:03:49 chennai ppp[354]: tun0: Timer:
> timer_Start: Inserting physical throughput
> timer[0x80ba068]
> Jun 21 14:03:49 chennai ppp[354]: tun0: Phase:
> Received NGM_PPPOE_ACNAME (hook "BANYAN")
> Jun 21 14:03:49 chennai ppp[354]: tun0: Debug: Waiting
> for carrier
> Jun 21 14:03:50 chennai ppp[354]: tun0: Timer: Select
> returns -1
> Jun 21 14:03:50 chennai ppp[354]: tun0: Timer: ----
> Begin of Timer Service List---
> Jun 21 14:03:50 chennai ppp[354]: tun0: Timer:
> physical throughput timer[0x80ba068]: freq = 1.00s,
> next = 0.00s, state = running
> Jun 21 14:03:50 chennai ppp[354]: tun0: Timer: ----
> End of Timer Service List ---
> Jun 21 14:03:50 chennai ppp[354]: tun0: Timer:
> timer_Start: Inserting physical throughput
> timer[0x80ba068]
> Jun 21 14:03:50 chennai ppp[354]: tun0: Debug: Waiting
> for carrier
> Jun 21 14:03:51 chennai ppp[354]: tun0: Timer: Select
> returns -1
> Jun 21 14:03:51 chennai ppp[354]: tun0: Timer: ----
> Begin of Timer Service List---
> Jun 21 14:03:51 chennai ppp[354]: tun0: Timer:
> physical throughput timer[0x80ba068]: freq = 1.00s,
> next = 0.00s, state = running
> Jun 21 14:03:51 chennai ppp[354]: tun0: Timer: ----
> End of Timer Service List ---
> Jun 21 14:03:51 chennai ppp[354]: tun0: Timer:
> timer_Start: Inserting physical throughput
> timer[0x80ba068]
> Jun 21 14:03:51 chennai ppp[354]: tun0: Debug: Waiting
> for carrier
> Jun 21 14:03:52 chennai ppp[354]: tun0: Timer: Select
> returns -1
> Jun 21 14:03:52 chennai ppp[354]: tun0: Timer: ----
> Begin of Timer Service List---
> Jun 21 14:03:52 chennai ppp[354]: tun0: Timer:
> physical throughput timer[0x80ba068]: freq = 1.00s,
> next = 0.00s, state = running
> Jun 21 14:03:52 chennai ppp[354]: tun0: Timer: ----
> End of Timer Service List ---
> Jun 21 14:03:52 chennai ppp[354]: tun0: Timer:
> timer_Start: Inserting physical throughput
> timer[0x80ba068]
> Jun 21 14:03:52 chennai ppp[354]: tun0: Debug: Waiting
> for carrier
> Jun 21 14:03:53 chennai ppp[354]: tun0: Timer: Select
> returns -1
> Jun 21 14:03:53 chennai ppp[354]: tun0: Timer: ----
> Begin of Timer Service List---
> Jun 21 14:03:53 chennai ppp[354]: tun0: Timer:
> physical throughput timer[0x80ba068]: freq = 1.00s,
> next = 0.00s, state = running
> Jun 21 14:03:53 chennai ppp[354]: tun0: Timer: ----
> End of Timer Service List ---
> Jun 21 14:03:53 chennai ppp[354]: tun0: Timer:
> timer_Start: Inserting physical throughput
> timer[0x80ba068]
> Jun 21 14:03:53 chennai ppp[354]: tun0: Phase:
> deflink: Disconnected!
> Jun 21 14:03:53 chennai ppp[354]: tun0: Phase:
> deflink: carrier -> hangup
> Jun 21 14:03:53 chennai ppp[354]: tun0: Debug:
> deflink: Close
> Jun 21 14:03:53 chennai ppp[354]: tun0: Phase:
> deflink: Connect time: 5 secs: 0 octets in, 0 octets
> out
> Jun 21 14:03:53 chennai ppp[354]: tun0: Phase:
> deflink: 0 packets in, 0 packets out
> Jun 21 14:03:53 chennai ppp[354]: tun0: Phase: total
> 0 bytes/sec, peak 0 bytes/sec on Mon Jun 21 14:03:48
> 2004
> Jun 21 14:03:53 chennai ppp[354]: tun0: Phase:
> deflink: hangup -> opening
> Jun 21 14:03:53 chennai ppp[354]: tun0: Timer:
> timer_Start: Inserting dial timer[0x80b7d44]
> Jun 21 14:03:53 chennai ppp[354]: tun0: Phase:
> deflink: Enter pause (30) for redialing.
>
> --- Gleb Smirnoff <glebius at cell.sick.ru> wrote:
> > On Sun, Jun 20, 2004 at 09:05:19AM -0700, Yohan
> > wrote:
> > Y> Im using PPPoE / FreeBSD 4.9 with a DSL line
> > provided by my ISP. The same modem / line works fine
> > / connects to the internet on a Windows 2000
> > machine. But when i use it with my FreeBSD machine
> > using ppp i get the following message in my ppp.log
> > "-> Waiting for carrier" - "Last message repeated n
> > times". I have followed the FreeBSD handbook
> > instructions for setting up PPPoE. Have also tried
> > various other articles related to PPPoE setup on
> > FreeBSD. I have tried various other options like
> > disabling carrier and others but i am unable to get
> > past the "no carrier" problem. Any help will be
> > greatly apreciated.
> >
> > Please show full ppp.log starting from the moment
> > when you started dailing.
> > Please also show your ppp.conf
> >
> > --
> > Totus tuus, Glebius.
> > GLEBIUS-RIPN GLEB-RIPE
> >
>
>
>
>
> __________________________________
> Do you Yahoo!?
> Yahoo! Mail - 50x more storage than other providers!
> http://promotions.yahoo.com/new_mail
> _______________________________________________
> freebsd-net at freebsd.org mailing list
> http://lists.freebsd.org/mailman/listinfo/freebsd-net
> To unsubscribe, send any mail to "freebsd-net-unsubscribe at freebsd.org"
>
More information about the freebsd-net
mailing list