From owner-freebsd-net@FreeBSD.ORG Mon Jun 21 09:11:13 2004 Return-Path: Delivered-To: freebsd-net@freebsd.org Received: from mx1.FreeBSD.org (mx1.freebsd.org [216.136.204.125]) by hub.freebsd.org (Postfix) with ESMTP id EE10116A4D1 for ; Mon, 21 Jun 2004 09:11:13 +0000 (GMT) Received: from web60810.mail.yahoo.com (web60810.mail.yahoo.com [216.155.196.73]) by mx1.FreeBSD.org (Postfix) with SMTP id 7B8BD43D31 for ; Mon, 21 Jun 2004 09:11:13 +0000 (GMT) (envelope-from yohanphilip@yahoo.com) Message-ID: <20040621091112.87515.qmail@web60810.mail.yahoo.com> Received: from [220.226.41.229] by web60810.mail.yahoo.com via HTTP; Mon, 21 Jun 2004 02:11:12 PDT Date: Mon, 21 Jun 2004 02:11:12 -0700 (PDT) From: Yohan To: freebsd-net@freebsd.org In-Reply-To: <20040620184858.GA68020@cell.sick.ru> MIME-Version: 1.0 Content-Type: text/plain; charset=us-ascii Subject: Re: PPPoE X-BeenThere: freebsd-net@freebsd.org X-Mailman-Version: 2.1.1 Precedence: list List-Id: Networking and TCP/IP with FreeBSD List-Unsubscribe: , List-Archive: List-Post: List-Help: List-Subscribe: , X-List-Received-Date: Mon, 21 Jun 2004 09:11:14 -0000 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 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