From owner-freebsd-bluetooth@FreeBSD.ORG Fri Mar 23 21:13:47 2007 Return-Path: X-Original-To: freebsd-bluetooth@freebsd.org Delivered-To: freebsd-bluetooth@freebsd.org Received: from mx1.freebsd.org (mx1.freebsd.org [69.147.83.52]) by hub.freebsd.org (Postfix) with ESMTP id C3B7116A405 for ; Fri, 23 Mar 2007 21:13:47 +0000 (UTC) (envelope-from ml-vic@de-martino.it) Received: from smtp5.aruba.it (smtpd3.aruba.it [62.149.128.208]) by mx1.freebsd.org (Postfix) with SMTP id 7D28313C458 for ; Fri, 23 Mar 2007 21:13:46 +0000 (UTC) (envelope-from ml-vic@de-martino.it) Received: (qmail 32562 invoked by uid 89); 23 Mar 2007 21:13:44 -0000 Received: by simscan 1.1.0 ppid: 32549, pid: 32554, t: 0.9558s scanners: clamav: 0.88.4/m:40/d:1722 Received: from unknown (HELO ?10.155.100.8?) (ml-vic@de-martino.it@82.53.153.72) by smtp5.aruba.it with SMTP; 23 Mar 2007 21:13:43 -0000 From: ml-vic To: freebsd-bluetooth@freebsd.org Date: Fri, 23 Mar 2007 22:13:29 +0000 User-Agent: KMail/1.9.5 MIME-Version: 1.0 Content-Type: text/plain; charset="us-ascii" Content-Transfer-Encoding: 7bit Content-Disposition: inline Message-Id: <200703232213.30138.ml-vic@de-martino.it> X-Spam-Rating: smtp5.aruba.it 1.6.2 0/1000/N Subject: ppp connection drops because of a flood of LCP Req X-BeenThere: freebsd-bluetooth@freebsd.org X-Mailman-Version: 2.1.5 Precedence: list List-Id: Using Bluetooth in FreeBSD environments List-Unsubscribe: , List-Archive: List-Post: List-Help: List-Subscribe: , X-List-Received-Date: Fri, 23 Mar 2007 21:13:47 -0000 For a very long time I've being using a umts pcmcia card so it was easy to me modify a bit ppp.conf file as below to connect my pc thru bluetooth and my cell phone used as modem: ################################################################# # PPP Sample Configuration File ################################################################# default: set log Phase Chat LCP IPCP CCP tun command ident user-ppp VERSION (built COMPILATIONDATE) rfcomm-dialup: set device /dev/ubt0 enable force-scripts set phone *99\# set mtu 300 set mru 300 enable dns disable mppe enable lqr set dial "ABORT BUSY ABORT NO\sCARRIER TIMEOUT 5 \ \"\" ATZ OK-AT-OK ATE1Q0 OK \ AT+CGDCONT=1,\\\"IP\\\",\\\"web.omnitel.it\\\",\\\"0.0.0.0\\\" OK \ \\dATDT\\T TIMEOUT 40 CONNECT" set authname # those can be usually chosen at will set authkey disable vjcomp disable deflate disable protocomp set dns 83.224.66.134 83.224.65.134 set ifaddr 10.0.0.1/0 10.0.0.2/0 255.255.255.0 0.0.0.0 add default HISADDR ################################## And then launch # rfcomm_pppd -a 00:12:d2:65:c2:03 -c -C dun -l rfcomm-dialup ppp starts, chats, tries to connect to the provider but drops after a while issuing a flood of LCP requests (see below ppp.log) Any idea? Ciao Vittorio Mar 23 14:06:24 NbBSD ppp[1093]: Phase: Using interface: tun0 Mar 23 14:06:24 NbBSD ppp[1093]: Phase: deflink: Created in closed state Mar 23 14:06:24 NbBSD ppp[1093]: tun0: Command: default: ident user-ppp VERSION (built COMPILATIONDATE) Mar 23 14:06:24 NbBSD ppp[1093]: tun0: Command: default: set device /dev/cuad0 Mar 23 14:06:24 NbBSD ppp[1093]: tun0: Command: default: set speed 115200 Mar 23 14:06:24 NbBSD ppp[1093]: tun0: Command: default: set dial ABORT BUSY ABORT NO\sCARRIER TIMEOUT 5 "" AT OK-AT-OK ATE1Q0 OK \dATDT\T TIMEOUT 40 CONNECT Mar 23 14:06:24 NbBSD ppp[1093]: tun0: Command: default: set timeout 180 Mar 23 14:06:24 NbBSD ppp[1093]: tun0: Command: default: enable dns Mar 23 14:06:24 NbBSD ppp[1093]: tun0: Command: default: set log Phase Chat LCP IPCP CCP tun command Mar 23 14:06:24 NbBSD ppp[1093]: tun0: Command: default: ident user-ppp VERSION (built COMPILATIONDATE) Mar 23 14:06:24 NbBSD ppp[1093]: tun0: Command: rfcomm-dialup: set device /dev/ubt0 Mar 23 14:06:24 NbBSD ppp[1093]: tun0: Command: rfcomm-dialup: enable force-scripts Mar 23 14:06:24 NbBSD ppp[1093]: tun0: Command: rfcomm-dialup: set phone *99# Mar 23 14:06:24 NbBSD ppp[1093]: tun0: Command: rfcomm-dialup: set mtu 300 Mar 23 14:06:24 NbBSD ppp[1093]: tun0: Command: rfcomm-dialup: set mru 300 Mar 23 14:06:24 NbBSD ppp[1093]: tun0: Command: rfcomm-dialup: enable dns Mar 23 14:06:24 NbBSD ppp[1093]: tun0: Command: rfcomm-dialup: disable mppe Mar 23 14:06:24 NbBSD ppp[1093]: tun0: Command: rfcomm-dialup: enable lqr Mar 23 14:06:24 NbBSD ppp[1093]: tun0: Command: rfcomm-dialup: set dial ABORT BUSY ABORT NOsCARRIER TIMEOUT 5 "" ATZ OK-AT-OK ATE1Q0 OK AT+CGDCONT=1, \"IP\",\"web.omnitel.it\",\"0.0.0.0\" OK \dATDT\T TIMEOUT 40 CONNECT Mar 23 14:06:24 NbBSD ppp[1093]: tun0: Command: rfcomm-dialup: set authname Mar 23 14:06:24 NbBSD ppp[1093]: tun0: Command: rfcomm-dialup: set authkey Mar 23 14:06:24 NbBSD ppp[1093]: tun0: Command: rfcomm-dialup: disable vjcomp Mar 23 14:06:24 NbBSD ppp[1093]: tun0: Command: rfcomm-dialup: disable deflate Mar 23 14:06:24 NbBSD ppp[1093]: tun0: Command: rfcomm-dialup: disable protocomp Mar 23 14:06:24 NbBSD ppp[1093]: tun0: Command: rfcomm-dialup: set dns 83.224.66.134 83.224.65.134 Mar 23 14:06:24 NbBSD ppp[1093]: tun0: Command: rfcomm-dialup: set ifaddr 10.0.0.1/0 10.0.0.2/0 255.255.255.0 0.0.0.0 Mar 23 14:06:24 NbBSD ppp[1093]: tun0: Command: rfcomm-dialup: add default HISADDR Mar 23 14:06:24 NbBSD ppp[1093]: tun0: Phase: PPP Started (direct mode). Mar 23 14:06:24 NbBSD ppp[1093]: tun0: Phase: bundle: Establish Mar 23 14:06:24 NbBSD ppp[1093]: tun0: Phase: deflink: closed -> opening Mar 23 14:06:24 NbBSD ppp[1093]: tun0: Phase: deflink: Connected! Mar 23 14:06:24 NbBSD ppp[1093]: tun0: Phase: deflink: opening -> dial Mar 23 14:06:24 NbBSD ppp[1093]: tun0: Chat: Phone: *99# Mar 23 14:06:24 NbBSD ppp[1093]: tun0: Chat: deflink: Dial attempt 1 of 1 Mar 23 14:06:24 NbBSD ppp[1093]: tun0: Chat: Send: ATZ^M Mar 23 14:06:24 NbBSD ppp[1093]: tun0: Chat: Expect(5): OK Mar 23 14:06:24 NbBSD ppp[1093]: tun0: Chat: Received: ATZ^M^M Mar 23 14:06:24 NbBSD ppp[1093]: tun0: Chat: Received: OK^M Mar 23 14:06:24 NbBSD ppp[1093]: tun0: Chat: Send: ATE1Q0^M Mar 23 14:06:24 NbBSD ppp[1093]: tun0: Chat: Expect(5): OK Mar 23 14:06:24 NbBSD ppp[1093]: tun0: Chat: Received: ATE1Q0^M^M Mar 23 14:06:24 NbBSD ppp[1093]: tun0: Chat: Received: OK^M Mar 23 14:06:24 NbBSD ppp[1093]: tun0: Chat: Send: AT+CGDCONT=1,"IP","web.omnitel.it","0.0.0.0"^M Mar 23 14:06:24 NbBSD ppp[1093]: tun0: Chat: Expect(5): OK Mar 23 14:06:24 NbBSD ppp[1093]: tun0: Chat: Received: AT+CGDCONT=1,"IP","web.omnitel.it","0.0.0.0"^M^M Mar 23 14:06:24 NbBSD ppp[1093]: tun0: Chat: Received: OK^M Mar 23 14:06:24 NbBSD ppp[1093]: tun0: Chat: Send: ATDT*99#^M Mar 23 14:06:26 NbBSD ppp[1093]: tun0: Chat: Expect(40): CONNECT Mar 23 14:06:26 NbBSD ppp[1093]: tun0: Chat: Received: ATDT*99#^M^M Mar 23 14:06:26 NbBSD ppp[1093]: tun0: Chat: Received: CONNECT^M Mar 23 14:06:26 NbBSD ppp[1093]: tun0: Phase: deflink: dial -> carrier Mar 23 14:06:26 NbBSD ppp[1093]: tun0: Phase: deflink: carrier -> login Mar 23 14:06:26 NbBSD ppp[1093]: tun0: Phase: deflink: login -> lcp Mar 23 14:06:26 NbBSD ppp[1093]: tun0: LCP: FSM: Using "deflink" as a transport Mar 23 14:06:26 NbBSD ppp[1093]: tun0: LCP: deflink: State change Initial --> Closed Mar 23 14:06:26 NbBSD ppp[1093]: tun0: LCP: deflink: State change Closed --> Stopped Mar 23 14:06:27 NbBSD ppp[1093]: tun0: LCP: deflink: LayerStart Mar 23 14:06:27 NbBSD ppp[1093]: tun0: LCP: deflink: SendConfigReq(1) state = Stopped Mar 23 14:06:27 NbBSD ppp[1093]: tun0: LCP: ACFCOMP[2] Mar 23 14:06:27 NbBSD ppp[1093]: tun0: LCP: ACCMAP[6] 0x00000000 Mar 23 14:06:27 NbBSD ppp[1093]: tun0: LCP: MRU[4] 300 Mar 23 14:06:27 NbBSD ppp[1093]: tun0: LCP: MAGICNUM[6] 0xc9837379 Mar 23 14:06:27 NbBSD ppp[1093]: tun0: LCP: QUALPROTO[8] proto c025, interval 30000ms Mar 23 14:06:27 NbBSD ppp[1093]: tun0: LCP: deflink: State change Stopped --> Req-Sent Mar 23 14:06:27 NbBSD ppp[1093]: tun0: LCP: deflink: RecvConfigRej(1) state = Req-Sent Mar 23 14:06:27 NbBSD ppp[1093]: tun0: LCP: deflink: SendIdent(0) state = Req-Sent Mar 23 14:06:27 NbBSD ppp[1093]: tun0: LCP: MAGICNUM c9837379 Mar 23 14:06:27 NbBSD ppp[1093]: tun0: LCP: TEXT user-ppp 3.4.2 (built Jan 15 2007) Mar 23 14:06:27 NbBSD ppp[1093]: tun0: LCP: ACFCOMP[2] Mar 23 14:06:27 NbBSD ppp[1093]: tun0: LCP: MAGICNUM[6] 0xc9837379 Mar 23 14:06:27 NbBSD ppp[1093]: tun0: LCP: Magic 0xc9837379 is REJected! Mar 23 14:06:27 NbBSD ppp[1093]: tun0: LCP: QUALPROTO[8] proto c025, interval 30000ms Mar 23 14:06:27 NbBSD ppp[1093]: tun0: LCP: deflink: SendConfigReq(2) state = Req-Sent Mar 23 14:06:27 NbBSD ppp[1093]: tun0: LCP: ACCMAP[6] 0x00000000 Mar 23 14:06:27 NbBSD ppp[1093]: tun0: LCP: MRU[4] 300 Mar 23 14:06:27 NbBSD ppp[1093]: tun0: LCP: deflink: RecvCodeRej(0) state = Req-Sent Mar 23 14:06:27 NbBSD ppp[1093]: tun0: LCP: deflink: RecvConfigNak(2) state = Req-Sent Mar 23 14:06:27 NbBSD ppp[1093]: tun0: LCP: MRU[4] 1500 Mar 23 14:06:27 NbBSD ppp[1093]: tun0: LCP: deflink: SendConfigReq(3) state = Req-Sent Mar 23 14:06:27 NbBSD ppp[1093]: tun0: LCP: ACCMAP[6] 0x00000000 Mar 23 14:06:27 NbBSD ppp[1093]: tun0: LCP: MRU[4] 300 Mar 23 14:06:27 NbBSD ppp[1093]: tun0: LCP: deflink: RecvConfigNak(3) state = Req-Sent Mar 23 14:06:27 NbBSD ppp[1093]: tun0: LCP: MRU[4] 1500 Mar 23 14:06:27 NbBSD ppp[1093]: tun0: LCP: deflink: SendConfigReq(4) state = Req-Sent Mar 23 14:06:27 NbBSD ppp[1093]: tun0: LCP: ACCMAP[6] 0x00000000 Mar 23 14:06:27 NbBSD ppp[1093]: tun0: LCP: MRU[4] 300 Mar 23 14:06:27 NbBSD ppp[1093]: tun0: LCP: deflink: RecvConfigNak(4) state = Req-Sent Mar 23 14:06:27 NbBSD ppp[1093]: tun0: LCP: MRU[4] 1500 Mar 23 14:06:27 NbBSD ppp[1093]: tun0: LCP: deflink: SendConfigReq(5) state = Req-Sent Mar 23 14:06:27 NbBSD ppp[1093]: tun0: LCP: ACCMAP[6] 0x00000000 Mar 23 14:06:27 NbBSD ppp[1093]: tun0: LCP: MRU[4] 300 Mar 23 14:06:27 NbBSD ppp[1093]: tun0: LCP: deflink: RecvConfigNak(5) state = Req-Sent Mar 23 14:06:27 NbBSD ppp[1093]: tun0: LCP: MRU[4] 1500 Mar 23 14:06:27 NbBSD ppp[1093]: tun0: LCP: deflink: SendConfigReq(6) state = Req-Sent Mar 23 14:06:27 NbBSD ppp[1093]: tun0: LCP: ACCMAP[6] 0x00000000 Mar 23 14:06:27 NbBSD ppp[1093]: tun0: LCP: MRU[4] 300 Mar 23 14:06:27 NbBSD ppp[1093]: tun0: LCP: deflink: RecvConfigNak(6) state = Req-Sent Mar 23 14:06:27 NbBSD ppp[1093]: tun0: LCP: MRU[4] 1500 Mar 23 14:06:27 NbBSD ppp[1093]: tun0: LCP: deflink: SendConfigReq(7) state = Req-Sent Mar 23 14:06:27 NbBSD ppp[1093]: tun0: LCP: ACCMAP[6] 0x00000000 Mar 23 14:06:27 NbBSD ppp[1093]: tun0: LCP: MRU[4] 300 Mar 23 14:06:27 NbBSD ppp[1093]: tun0: LCP: deflink: RecvConfigNak(7) state = Req-Sent Mar 23 14:06:27 NbBSD ppp[1093]: tun0: LCP: MRU[4] 1500 Mar 23 14:06:27 NbBSD ppp[1093]: tun0: LCP: deflink: SendConfigReq(8) state = Req-Sent Mar 23 14:06:27 NbBSD ppp[1093]: tun0: LCP: ACCMAP[6] 0x00000000 Mar 23 14:06:27 NbBSD ppp[1093]: tun0: LCP: MRU[4] 300 Mar 23 14:06:27 NbBSD ppp[1093]: tun0: LCP: deflink: RecvConfigNak(8) state = Req-Sent Mar 23 14:06:27 NbBSD ppp[1093]: tun0: LCP: MRU[4] 1500 Mar 23 14:06:27 NbBSD ppp[1093]: tun0: LCP: deflink: SendConfigReq(9) state = Req-Sent Mar 23 14:06:27 NbBSD ppp[1093]: tun0: LCP: ACCMAP[6] 0x00000000 Mar 23 14:06:27 NbBSD ppp[1093]: tun0: LCP: MRU[4] 300 Mar 23 14:06:27 NbBSD ppp[1093]: tun0: LCP: deflink: RecvConfigNak(9) state = Req-Sent Mar 23 14:06:27 NbBSD ppp[1093]: tun0: LCP: MRU[4] 1500 Mar 23 14:06:27 NbBSD ppp[1093]: tun0: LCP: deflink: SendConfigReq(10) state = Req-Sent Mar 23 14:06:27 NbBSD ppp[1093]: tun0: LCP: ACCMAP[6] 0x00000000 Mar 23 14:06:27 NbBSD ppp[1093]: tun0: LCP: MRU[4] 300 Mar 23 14:06:27 NbBSD ppp[1093]: tun0: LCP: deflink: RecvConfigNak(10) state = Req-Sent Mar 23 14:06:27 NbBSD ppp[1093]: tun0: LCP: MRU[4] 1500 Mar 23 14:06:27 NbBSD ppp[1093]: tun0: LCP: deflink: SendConfigReq(11) state = Req-Sent Mar 23 14:06:27 NbBSD ppp[1093]: tun0: LCP: ACCMAP[6] 0x00000000 Mar 23 14:06:27 NbBSD ppp[1093]: tun0: LCP: MRU[4] 300 Mar 23 14:06:27 NbBSD ppp[1093]: tun0: LCP: deflink: RecvConfigNak(11) state = Req-Sent Mar 23 14:06:27 NbBSD ppp[1093]: tun0: LCP: MRU[4] 1500 Mar 23 14:06:27 NbBSD ppp[1093]: tun0: LCP: deflink: SendConfigReq(12) state = Req-Sent Mar 23 14:06:27 NbBSD ppp[1093]: tun0: LCP: ACCMAP[6] 0x00000000 Mar 23 14:06:27 NbBSD ppp[1093]: tun0: LCP: MRU[4] 300 Mar 23 14:06:27 NbBSD ppp[1093]: tun0: LCP: deflink: RecvConfigNak(12) state = Req-Sent Mar 23 14:06:27 NbBSD ppp[1093]: tun0: LCP: MRU[4] 1500 Mar 23 14:06:27 NbBSD ppp[1093]: tun0: LCP: deflink: SendConfigReq(13) state = Req-Sent Mar 23 14:06:27 NbBSD ppp[1093]: tun0: LCP: ACCMAP[6] 0x00000000 Mar 23 14:06:27 NbBSD ppp[1093]: tun0: LCP: MRU[4] 300 Mar 23 14:06:27 NbBSD ppp[1093]: tun0: LCP: deflink: RecvConfigNak(13) state = Req-Sent Mar 23 14:06:27 NbBSD ppp[1093]: tun0: LCP: MRU[4] 1500 Mar 23 14:06:27 NbBSD ppp[1093]: tun0: LCP: deflink: SendConfigReq(14) state = Req-Sent Mar 23 14:06:27 NbBSD ppp[1093]: tun0: LCP: ACCMAP[6] 0x00000000 Mar 23 14:06:27 NbBSD ppp[1093]: tun0: LCP: MRU[4] 300 Mar 23 14:06:27 NbBSD ppp[1093]: tun0: LCP: deflink: RecvConfigNak(14) state = Req-Sent Mar 23 14:06:27 NbBSD ppp[1093]: tun0: LCP: MRU[4] 1500 Mar 23 14:06:27 NbBSD ppp[1093]: tun0: LCP: deflink: SendConfigReq(15) state = Req-Sent Mar 23 14:06:27 NbBSD ppp[1093]: tun0: LCP: ACCMAP[6] 0x00000000 Mar 23 14:06:27 NbBSD ppp[1093]: tun0: LCP: MRU[4] 300 Mar 23 14:06:27 NbBSD ppp[1093]: tun0: LCP: deflink: RecvConfigNak(15) state = Req-Sent Mar 23 14:06:27 NbBSD ppp[1093]: tun0: LCP: MRU[4] 1500 Mar 23 14:06:27 NbBSD ppp[1093]: tun0: Phase: deflink: Too many LCP REQs sent - abandoning negotiation Mar 23 14:06:27 NbBSD ppp[1093]: tun0: LCP: deflink: SendIdent(1) state = Req-Sent Mar 23 14:06:27 NbBSD ppp[1093]: tun0: LCP: MAGICNUM 00000000 Mar 23 14:06:27 NbBSD ppp[1093]: tun0: LCP: TEXT user-ppp 3.4.2 (built Jan 15 2007) Mar 23 14:06:27 NbBSD ppp[1093]: tun0: LCP: deflink: SendTerminateReq(16) state = Req-Sent Mar 23 14:06:27 NbBSD ppp[1093]: tun0: LCP: deflink: State change Req-Sent --> Closing Mar 23 14:06:27 NbBSD ppp[1093]: tun0: LCP: deflink: RecvCodeRej(1) state = Closing Mar 23 14:06:27 NbBSD ppp[1093]: tun0: LCP: deflink: RecvTerminateAck(16) state = Closing Mar 23 14:06:27 NbBSD ppp[1093]: tun0: LCP: deflink: LayerFinish Mar 23 14:06:27 NbBSD ppp[1093]: tun0: LCP: deflink: State change Closing --> Closed Mar 23 14:06:27 NbBSD ppp[1093]: tun0: LCP: deflink: State change Closed --> Initial Mar 23 14:06:27 NbBSD ppp[1093]: tun0: Phase: deflink: Disconnected! Mar 23 14:06:27 NbBSD ppp[1093]: tun0: Phase: deflink: lcp -> logout Mar 23 14:06:27 NbBSD ppp[1093]: tun0: Phase: deflink: logout -> hangup Mar 23 14:06:27 NbBSD ppp[1093]: tun0: Phase: deflink: Disconnected! Mar 23 14:06:27 NbBSD ppp[1093]: tun0: Phase: deflink: Connect time: 3 secs: 525 octets in, 699 octets out Mar 23 14:06:27 NbBSD ppp[1093]: tun0: Phase: deflink: 16 packets in, 18 packets out Mar 23 14:06:27 NbBSD ppp[1093]: tun0: Phase: total 408 bytes/sec, peak 0 bytes/sec on Fri Mar 23 14:06:24 2007 Mar 23 14:06:27 NbBSD ppp[1093]: tun0: Phase: deflink: hangup -> closed Mar 23 14:06:27 NbBSD ppp[1093]: tun0: Phase: bundle: Dead Mar 23 14:06:27 NbBSD ppp[1093]: tun0: Phase: PPP Terminated (normal). Mar 23 17:08:29 NbBSD ppp[1465]: Phase: Using interface: tun0 Mar 23 17:08:29 NbBSD ppp[1465]: Phase: deflink: Created in closed state Mar 23 17:08:29 NbBSD ppp[1465]: tun0: Command: default: ident user-ppp VERSION (built COMPILATIONDATE) Mar 23 17:08:29 NbBSD ppp[1465]: tun0: Command: default: set device /dev/cuad0 Mar 23 17:08:29 NbBSD ppp[1465]: tun0: Command: default: set speed 115200 Mar 23 17:08:29 NbBSD ppp[1465]: tun0: Command: default: set dial ABORT BUSY ABORT NO\sCARRIER TIMEOUT 5 "" AT OK-AT-OK ATE1Q0 OK \dATDT\T TIMEOUT 40 CONNECT Mar 23 17:08:29 NbBSD ppp[1465]: tun0: Command: default: set timeout 180 Mar 23 17:08:29 NbBSD ppp[1465]: tun0: Command: default: enable dns Mar 23 17:08:29 NbBSD ppp[1465]: tun0: Command: default: set log Phase Chat LCP IPCP CCP tun command Mar 23 17:08:29 NbBSD ppp[1465]: tun0: Command: default: ident user-ppp VERSION (built COMPILATIONDATE) Mar 23 17:08:29 NbBSD ppp[1465]: tun0: Command: rfcomm-dialup: set device /dev/ubt0 Mar 23 17:08:29 NbBSD ppp[1465]: tun0: Command: rfcomm-dialup: enable force-scripts Mar 23 17:08:29 NbBSD ppp[1465]: tun0: Command: rfcomm-dialup: set phone *99# Mar 23 17:08:29 NbBSD ppp[1465]: tun0: Command: rfcomm-dialup: set mtu 300 Mar 23 17:08:29 NbBSD ppp[1465]: tun0: Command: rfcomm-dialup: set mru 300 Mar 23 17:08:29 NbBSD ppp[1465]: tun0: Command: rfcomm-dialup: enable dns Mar 23 17:08:29 NbBSD ppp[1465]: tun0: Command: rfcomm-dialup: disable mppe Mar 23 17:08:29 NbBSD ppp[1465]: tun0: Command: rfcomm-dialup: enable lqr Mar 23 17:08:29 NbBSD ppp[1465]: tun0: Command: rfcomm-dialup: set dial ABORT BUSY ABORT NOsCARRIER TIMEOUT 5 "" ATZ OK-AT-OK ATE1Q0 OK AT+CGDCONT=1, \"IP\",\"web.omnitel.it\",\"0.0.0.0\" OK \dATDT\T TIMEOUT 40 CONNECT Mar 23 17:08:29 NbBSD ppp[1465]: tun0: Command: rfcomm-dialup: set authname Mar 23 17:08:29 NbBSD ppp[1465]: tun0: Command: rfcomm-dialup: set authkey Mar 23 17:08:29 NbBSD ppp[1465]: tun0: Command: rfcomm-dialup: disable vjcomp Mar 23 17:08:29 NbBSD ppp[1465]: tun0: Command: rfcomm-dialup: disable deflate Mar 23 17:08:29 NbBSD ppp[1465]: tun0: Command: rfcomm-dialup: disable protocomp Mar 23 17:08:29 NbBSD ppp[1465]: tun0: Command: rfcomm-dialup: set dns 83.224.66.134 83.224.65.134 Mar 23 17:08:29 NbBSD ppp[1465]: tun0: Command: rfcomm-dialup: set ifaddr 10.0.0.1/0 10.0.0.2/0 255.255.255.0 0.0.0.0 Mar 23 17:08:29 NbBSD ppp[1465]: tun0: Command: rfcomm-dialup: add default HISADDR Mar 23 17:08:29 NbBSD ppp[1465]: tun0: Warning: Add route failed: 0.0.0.0/0 already exists Mar 23 17:08:29 NbBSD ppp[1465]: tun0: Phase: PPP Started (direct mode). Mar 23 17:08:29 NbBSD ppp[1465]: tun0: Phase: bundle: Establish Mar 23 17:08:29 NbBSD ppp[1465]: tun0: Phase: deflink: closed -> opening Mar 23 17:08:29 NbBSD ppp[1465]: tun0: Phase: deflink: Connected! Mar 23 17:08:29 NbBSD ppp[1465]: tun0: Phase: deflink: opening -> dial Mar 23 17:08:29 NbBSD ppp[1465]: tun0: Chat: Phone: *99# Mar 23 17:08:29 NbBSD ppp[1465]: tun0: Chat: deflink: Dial attempt 1 of 1 Mar 23 17:08:29 NbBSD ppp[1465]: tun0: Chat: Send: ATZ^M Mar 23 17:08:29 NbBSD ppp[1465]: tun0: Chat: Expect(5): OK Mar 23 17:08:29 NbBSD ppp[1465]: tun0: Chat: Received: ATZ^M^M Mar 23 17:08:29 NbBSD ppp[1465]: tun0: Chat: Received: OK^M Mar 23 17:08:29 NbBSD ppp[1465]: tun0: Chat: Send: ATE1Q0^M Mar 23 17:08:29 NbBSD ppp[1465]: tun0: Chat: Expect(5): OK Mar 23 17:08:29 NbBSD ppp[1465]: tun0: Chat: Received: ATE1Q0^M^M Mar 23 17:08:29 NbBSD ppp[1465]: tun0: Chat: Received: OK^M Mar 23 17:08:29 NbBSD ppp[1465]: tun0: Chat: Send: AT+CGDCONT=1,"IP","web.omnitel.it","0.0.0.0"^M Mar 23 17:08:29 NbBSD ppp[1465]: tun0: Chat: Expect(5): OK Mar 23 17:08:29 NbBSD ppp[1465]: tun0: Chat: Received: AT+CGDCONT=1,"IP","web.omnitel.it","0.0.0.0"^M^M Mar 23 17:08:29 NbBSD ppp[1465]: tun0: Chat: Received: OK^M Mar 23 17:08:29 NbBSD ppp[1465]: tun0: Chat: Send: ATDT*99#^M Mar 23 17:08:31 NbBSD ppp[1465]: tun0: Chat: Expect(40): CONNECT Mar 23 17:08:31 NbBSD ppp[1465]: tun0: Chat: Received: ATDT*99#^M^M Mar 23 17:08:31 NbBSD ppp[1465]: tun0: Chat: Received: CONNECT^M Mar 23 17:08:31 NbBSD ppp[1465]: tun0: Phase: deflink: dial -> carrier Mar 23 17:08:31 NbBSD ppp[1465]: tun0: Phase: deflink: carrier -> login Mar 23 17:08:31 NbBSD ppp[1465]: tun0: Phase: deflink: login -> lcp Mar 23 17:08:31 NbBSD ppp[1465]: tun0: LCP: FSM: Using "deflink" as a transport Mar 23 17:08:31 NbBSD ppp[1465]: tun0: LCP: deflink: State change Initial --> Closed Mar 23 17:08:31 NbBSD ppp[1465]: tun0: LCP: deflink: State change Closed --> Stopped Mar 23 17:08:32 NbBSD ppp[1465]: tun0: LCP: deflink: LayerStart Mar 23 17:08:32 NbBSD ppp[1465]: tun0: LCP: deflink: SendConfigReq(1) state = Stopped Mar 23 17:08:32 NbBSD ppp[1465]: tun0: LCP: ACFCOMP[2] Mar 23 17:08:32 NbBSD ppp[1465]: tun0: LCP: ACCMAP[6] 0x00000000 Mar 23 17:08:32 NbBSD ppp[1465]: tun0: LCP: MRU[4] 300 Mar 23 17:08:32 NbBSD ppp[1465]: tun0: LCP: MAGICNUM[6] 0x40377ea4 Mar 23 17:08:32 NbBSD ppp[1465]: tun0: LCP: QUALPROTO[8] proto c025, interval 30000ms Mar 23 17:08:32 NbBSD ppp[1465]: tun0: LCP: deflink: State change Stopped --> Req-Sent Mar 23 17:08:32 NbBSD ppp[1465]: tun0: LCP: deflink: RecvConfigRej(1) state = Req-Sent Mar 23 17:08:32 NbBSD ppp[1465]: tun0: LCP: deflink: SendIdent(0) state = Req-Sent Mar 23 17:08:32 NbBSD ppp[1465]: tun0: LCP: MAGICNUM 40377ea4 Mar 23 17:08:32 NbBSD ppp[1465]: tun0: LCP: TEXT user-ppp 3.4.2 (built Jan 15 2007) Mar 23 17:08:32 NbBSD ppp[1465]: tun0: LCP: ACFCOMP[2] Mar 23 17:08:32 NbBSD ppp[1465]: tun0: LCP: MAGICNUM[6] 0x40377ea4 Mar 23 17:08:32 NbBSD ppp[1465]: tun0: LCP: Magic 0x40377ea4 is REJected! Mar 23 17:08:32 NbBSD ppp[1465]: tun0: LCP: QUALPROTO[8] proto c025, interval 30000ms Mar 23 17:08:32 NbBSD ppp[1465]: tun0: LCP: deflink: SendConfigReq(2) state = Req-Sent Mar 23 17:08:32 NbBSD ppp[1465]: tun0: LCP: ACCMAP[6] 0x00000000 Mar 23 17:08:32 NbBSD ppp[1465]: tun0: LCP: MRU[4] 300 Mar 23 17:08:32 NbBSD ppp[1465]: tun0: LCP: deflink: RecvCodeRej(0) state = Req-Sent Mar 23 17:08:32 NbBSD ppp[1465]: tun0: LCP: deflink: RecvConfigNak(2) state = Req-Sent Mar 23 17:08:32 NbBSD ppp[1465]: tun0: LCP: MRU[4] 1500 Mar 23 17:08:32 NbBSD ppp[1465]: tun0: LCP: deflink: SendConfigReq(3) state = Req-Sent Mar 23 17:08:32 NbBSD ppp[1465]: tun0: LCP: ACCMAP[6] 0x00000000 Mar 23 17:08:32 NbBSD ppp[1465]: tun0: LCP: MRU[4] 300 Mar 23 17:08:32 NbBSD ppp[1465]: tun0: LCP: deflink: RecvConfigNak(3) state = Req-Sent Mar 23 17:08:32 NbBSD ppp[1465]: tun0: LCP: MRU[4] 1500 Mar 23 17:08:32 NbBSD ppp[1465]: tun0: LCP: deflink: SendConfigReq(4) state = Req-Sent Mar 23 17:08:32 NbBSD ppp[1465]: tun0: LCP: ACCMAP[6] 0x00000000 Mar 23 17:08:32 NbBSD ppp[1465]: tun0: LCP: MRU[4] 300 Mar 23 17:08:32 NbBSD ppp[1465]: tun0: LCP: deflink: RecvConfigNak(4) state = Req-Sent Mar 23 17:08:32 NbBSD ppp[1465]: tun0: LCP: MRU[4] 1500 Mar 23 17:08:32 NbBSD ppp[1465]: tun0: LCP: deflink: SendConfigReq(5) state = Req-Sent Mar 23 17:08:32 NbBSD ppp[1465]: tun0: LCP: ACCMAP[6] 0x00000000 Mar 23 17:08:32 NbBSD ppp[1465]: tun0: LCP: MRU[4] 300 Mar 23 17:08:32 NbBSD ppp[1465]: tun0: LCP: deflink: RecvConfigNak(5) state = Req-Sent Mar 23 17:08:32 NbBSD ppp[1465]: tun0: LCP: MRU[4] 1500 Mar 23 17:08:32 NbBSD ppp[1465]: tun0: LCP: deflink: SendConfigReq(6) state = Req-Sent Mar 23 17:08:32 NbBSD ppp[1465]: tun0: LCP: ACCMAP[6] 0x00000000 Mar 23 17:08:32 NbBSD ppp[1465]: tun0: LCP: MRU[4] 300 Mar 23 17:08:32 NbBSD ppp[1465]: tun0: LCP: deflink: RecvConfigNak(6) state = Req-Sent Mar 23 17:08:32 NbBSD ppp[1465]: tun0: LCP: MRU[4] 1500 Mar 23 17:08:32 NbBSD ppp[1465]: tun0: LCP: deflink: SendConfigReq(7) state = Req-Sent Mar 23 17:08:32 NbBSD ppp[1465]: tun0: LCP: ACCMAP[6] 0x00000000 Mar 23 17:08:32 NbBSD ppp[1465]: tun0: LCP: MRU[4] 300 Mar 23 17:08:32 NbBSD ppp[1465]: tun0: LCP: deflink: RecvConfigNak(7) state = Req-Sent Mar 23 17:08:32 NbBSD ppp[1465]: tun0: LCP: MRU[4] 1500 Mar 23 17:08:32 NbBSD ppp[1465]: tun0: LCP: deflink: SendConfigReq(8) state = Req-Sent Mar 23 17:08:32 NbBSD ppp[1465]: tun0: LCP: ACCMAP[6] 0x00000000 Mar 23 17:08:32 NbBSD ppp[1465]: tun0: LCP: MRU[4] 300 Mar 23 17:08:32 NbBSD ppp[1465]: tun0: LCP: deflink: RecvConfigNak(8) state = Req-Sent Mar 23 17:08:32 NbBSD ppp[1465]: tun0: LCP: MRU[4] 1500 Mar 23 17:08:32 NbBSD ppp[1465]: tun0: LCP: deflink: SendConfigReq(9) state = Req-Sent Mar 23 17:08:32 NbBSD ppp[1465]: tun0: LCP: ACCMAP[6] 0x00000000 Mar 23 17:08:32 NbBSD ppp[1465]: tun0: LCP: MRU[4] 300 Mar 23 17:08:32 NbBSD ppp[1465]: tun0: LCP: deflink: RecvConfigNak(9) state = Req-Sent Mar 23 17:08:32 NbBSD ppp[1465]: tun0: LCP: MRU[4] 1500 Mar 23 17:08:32 NbBSD ppp[1465]: tun0: LCP: deflink: SendConfigReq(10) state = Req-Sent Mar 23 17:08:32 NbBSD ppp[1465]: tun0: LCP: ACCMAP[6] 0x00000000 Mar 23 17:08:32 NbBSD ppp[1465]: tun0: LCP: MRU[4] 300 Mar 23 17:08:32 NbBSD ppp[1465]: tun0: LCP: deflink: RecvConfigNak(10) state = Req-Sent Mar 23 17:08:32 NbBSD ppp[1465]: tun0: LCP: MRU[4] 1500 Mar 23 17:08:32 NbBSD ppp[1465]: tun0: LCP: deflink: SendConfigReq(11) state = Req-Sent Mar 23 17:08:32 NbBSD ppp[1465]: tun0: LCP: ACCMAP[6] 0x00000000 Mar 23 17:08:32 NbBSD ppp[1465]: tun0: LCP: MRU[4] 300 Mar 23 17:08:32 NbBSD ppp[1465]: tun0: LCP: deflink: RecvConfigNak(11) state = Req-Sent Mar 23 17:08:32 NbBSD ppp[1465]: tun0: LCP: MRU[4] 1500 Mar 23 17:08:32 NbBSD ppp[1465]: tun0: LCP: deflink: SendConfigReq(12) state = Req-Sent Mar 23 17:08:32 NbBSD ppp[1465]: tun0: LCP: ACCMAP[6] 0x00000000 Mar 23 17:08:32 NbBSD ppp[1465]: tun0: LCP: MRU[4] 300 Mar 23 17:08:32 NbBSD ppp[1465]: tun0: LCP: deflink: RecvConfigNak(12) state = Req-Sent Mar 23 17:08:32 NbBSD ppp[1465]: tun0: LCP: MRU[4] 1500 Mar 23 17:08:32 NbBSD ppp[1465]: tun0: LCP: deflink: SendConfigReq(13) state = Req-Sent Mar 23 17:08:32 NbBSD ppp[1465]: tun0: LCP: ACCMAP[6] 0x00000000 Mar 23 17:08:32 NbBSD ppp[1465]: tun0: LCP: MRU[4] 300 Mar 23 17:08:32 NbBSD ppp[1465]: tun0: LCP: deflink: RecvConfigNak(13) state = Req-Sent Mar 23 17:08:32 NbBSD ppp[1465]: tun0: LCP: MRU[4] 1500 Mar 23 17:08:32 NbBSD ppp[1465]: tun0: LCP: deflink: SendConfigReq(14) state = Req-Sent Mar 23 17:08:32 NbBSD ppp[1465]: tun0: LCP: ACCMAP[6] 0x00000000 Mar 23 17:08:32 NbBSD ppp[1465]: tun0: LCP: MRU[4] 300 Mar 23 17:08:32 NbBSD ppp[1465]: tun0: LCP: deflink: RecvConfigNak(14) state = Req-Sent Mar 23 17:08:32 NbBSD ppp[1465]: tun0: LCP: MRU[4] 1500 Mar 23 17:08:32 NbBSD ppp[1465]: tun0: LCP: deflink: SendConfigReq(15) state = Req-Sent Mar 23 17:08:32 NbBSD ppp[1465]: tun0: LCP: ACCMAP[6] 0x00000000 Mar 23 17:08:32 NbBSD ppp[1465]: tun0: LCP: MRU[4] 300 Mar 23 17:08:32 NbBSD ppp[1465]: tun0: LCP: deflink: RecvConfigNak(15) state = Req-Sent Mar 23 17:08:32 NbBSD ppp[1465]: tun0: LCP: MRU[4] 1500 Mar 23 17:08:32 NbBSD ppp[1465]: tun0: Phase: deflink: Too many LCP REQs sent - abandoning negotiation Mar 23 17:08:32 NbBSD ppp[1465]: tun0: LCP: deflink: SendIdent(1) state = Req-Sent Mar 23 17:08:32 NbBSD ppp[1465]: tun0: LCP: MAGICNUM 00000000 Mar 23 17:08:32 NbBSD ppp[1465]: tun0: LCP: TEXT user-ppp 3.4.2 (built Jan 15 2007) Mar 23 17:08:32 NbBSD ppp[1465]: tun0: LCP: deflink: SendTerminateReq(16) state = Req-Sent Mar 23 17:08:32 NbBSD ppp[1465]: tun0: LCP: deflink: State change Req-Sent --> Closing Mar 23 17:08:32 NbBSD ppp[1465]: tun0: LCP: deflink: RecvCodeRej(1) state = Closing Mar 23 17:08:32 NbBSD ppp[1465]: tun0: LCP: deflink: RecvTerminateAck(16) state = Closing Mar 23 17:08:32 NbBSD ppp[1465]: tun0: LCP: deflink: LayerFinish Mar 23 17:08:32 NbBSD ppp[1465]: tun0: LCP: deflink: State change Closing --> Closed Mar 23 17:08:32 NbBSD ppp[1465]: tun0: LCP: deflink: State change Closed --> Initial Mar 23 17:08:32 NbBSD ppp[1465]: tun0: Phase: deflink: Disconnected! Mar 23 17:08:32 NbBSD ppp[1465]: tun0: Phase: deflink: lcp -> logout Mar 23 17:08:32 NbBSD ppp[1465]: tun0: Phase: deflink: logout -> hangup Mar 23 17:08:32 NbBSD ppp[1465]: tun0: Phase: deflink: Disconnected! Mar 23 17:08:32 NbBSD ppp[1465]: tun0: Phase: deflink: Connect time: 3 secs: 527 octets in, 700 octets out Mar 23 17:08:32 NbBSD ppp[1465]: tun0: Phase: deflink: 17 packets in, 18 packets out Mar 23 17:08:32 NbBSD ppp[1465]: tun0: Phase: total 409 bytes/sec, peak 0 bytes/sec on Fri Mar 23 17:08:29 2007 Mar 23 17:08:32 NbBSD ppp[1465]: tun0: Phase: deflink: hangup -> closed Mar 23 17:08:32 NbBSD ppp[1465]: tun0: Phase: bundle: Dead Mar 23 17:08:32 NbBSD ppp[1465]: tun0: Phase: PPP Terminated (normal).