From owner-freebsd-questions Sun Jun 10 5:24:38 2001 Delivered-To: freebsd-questions@freebsd.org Received: from mail.XtremeDev.com (xtremedev.com [216.241.38.65]) by hub.freebsd.org (Postfix) with ESMTP id 9EC5B37B403 for ; Sun, 10 Jun 2001 05:24:24 -0700 (PDT) (envelope-from freebsd@XtremeDev.com) Received: from xtremedev.com (xtremedev.com [216.241.38.65]) by mail.XtremeDev.com (Postfix) with ESMTP id 732D313636 for ; Sun, 10 Jun 2001 06:01:33 -0600 (MDT) Date: Sun, 10 Jun 2001 06:01:33 -0600 (MDT) From: FreeBSD To: Subject: pppd dialin server using mgetty and pppd Message-ID: <20010610053816.W45631-100000@Amber.XtremeDev.com> MIME-Version: 1.0 Content-Type: TEXT/PLAIN; charset=US-ASCII Sender: owner-freebsd-questions@FreeBSD.ORG Precedence: bulk List-ID: List-Archive: (Web Archive) List-Help: (List Instructions) List-Subscribe: List-Unsubscribe: X-Loop: FreeBSD.ORG Hello all, I am trying to setup a dialin server for my Windows 2000 machine in FreeBSD, using pppd 2.3.5 and mgetty-1.1.25.02.01 compiled fresh from a recently cvsup'd ports tree. The FreeBSD machine has a USR Courier modem and the Win2k machine has an external Sportster 56k. I can get mgetty to recognize on AutoPPP and call pppd, but for some reason pppd doesn't get any response from an lcp (?) request. Here's the log from pppd: Jun 10 05:50:12 pppdserver pppd[45652]: pppd 2.3.5 started by root, uid 0 Jun 10 05:50:12 pppdserver pppd[45652]: Using interface ppp0 Jun 10 05:50:12 pppdserver pppd[45652]: Connect: ppp0 <--> /dev/cuaa0 Jun 10 05:50:12 pppdserver pppd[45652]: sent [LCP ConfReq id=0x1 ] Jun 10 05:50:15 pppdserver /kernel: ppp0: bad protocol 2666 Jun 10 05:50:15 pppdserver pppd[45652]: sent [LCP ConfReq id=0x1 ] Jun 10 05:50:15 pppdserver pppd[45652]: rcvd [LCP ConfReq id=0x2 < 11 04 06 4e> < 13 17 01 69 6d ac 7c 32 7b 4e b5 88 0c d4 fc 68 06 6 9 c7 00 00 00 00>] Jun 10 05:50:15 pppdserver pppd[45652]: sent [LCP ConfRej id=0x2 < 11 04 06 4e> < 13 17 01 69 6d ac 7c 32 7b 4e b5 88 0c d4 fc 68 06 69 c7 00 00 00 00>] Jun 10 05:50:18 pppdserver pppd[45652]: sent [LCP ConfReq id=0x1 ] Jun 10 05:50:19 pppdserver pppd[45652]: rcvd [LCP ConfReq id=0x3 < 11 04 06 4e> < 13 17 01 69 6d ac 7c 32 7b 4e b5 88 0c d4 fc 68 06 6 9 c7 00 00 00 00>] Jun 10 05:50:19 pppdserver pppd[45652]: sent [LCP ConfRej id=0x3 < 11 04 06 4e> < 13 17 01 69 6d ac 7c 32 7b 4e b5 88 0c d4 fc 68 06 69 c7 00 00 00 00>] Jun 10 05:50:20 pppdserver sudo: dave : TTY=ttyph ; PWD=/usr/home/dave ; USER=root ; COMMAND=/usr/bin/less /var/tmp/.tmpsc/.tmpsc Jun 10 05:50:21 pppdserver pppd[45652]: sent [LCP ConfReq id=0x1 ] Jun 10 05:50:23 pppdserver pppd[45652]: rcvd [LCP ConfReq id=0x4 < 11 04 06 4e> < 13 17 01 69 6d ac 7c 32 7b 4e b5 88 0c d4 fc 68 06 69 c7 00 00 00 00>] Jun 10 05:50:23 pppdserver pppd[45652]: sent [LCP ConfRej id=0x4 < 11 04 06 4e> < 13 17 01 69 6d ac 7c 32 7b 4e b5 88 0c d4 fc 68 06 69 c7 00 00 00 00>] Jun 10 05:50:24 pppdserver pppd[45652]: sent [LCP ConfReq id=0x1 ] Jun 10 05:50:27 pppdserver pppd[45652]: rcvd [LCP ConfReq id=0x5 < 11 04 06 4e> < 13 17 01 69 6d ac 7c 32 7b 4e b5 88 0c d4 fc 68 06 69 c7 00 00 00 00>] Jun 10 05:50:27 pppdserver pppd[45652]: sent [LCP ConfRej id=0x5 < 11 04 06 4e> < 13 17 01 69 6d ac 7c 32 7b 4e b5 88 0c d4 fc 68 06 69 c7 00 00 00 00>] Jun 10 05:50:27 pppdserver pppd[45652]: sent [LCP ConfReq id=0x1 ] Jun 10 05:50:30 pppdserver pppd[45652]: sent [LCP ConfReq id=0x1 ] Jun 10 05:50:31 pppdserver pppd[45652]: rcvd [LCP ConfReq id=0x6 < 11 04 06 4e> < 13 17 01 69 6d ac 7c 32 7b 4e b5 88 0c d4 fc 68 06 69 c7 00 00 00 00>] Jun 10 05:50:31 pppdserver pppd[45652]: sent [LCP ConfRej id=0x6 < 11 04 06 4e> < 13 17 01 69 6d ac 7c 32 7b 4e b5 88 0c d4 fc 68 06 69 c7 00 00 00 00>] Jun 10 05:50:33 pppdserver pppd[45652]: sent [LCP ConfReq id=0x1 ] Jun 10 05:50:35 pppdserver pppd[45652]: rcvd [LCP ConfReq id=0x7 < 11 04 06 4e> < 13 17 01 69 6d ac 7c 32 7b 4e b5 88 0c d4 fc 68 06 69 c7 00 00 00 00>] Jun 10 05:50:35 pppdserver pppd[45652]: sent [LCP ConfRej id=0x7 < 11 04 06 4e> < 13 17 01 69 6d ac 7c 32 7b 4e b5 88 0c d4 fc 68 06 69 c7 00 00 00 00>] Jun 10 05:50:36 pppdserver pppd[45652]: sent [LCP ConfReq id=0x1 ] Jun 10 05:50:39 pppdserver pppd[45652]: rcvd [LCP ConfReq id=0x8 < 11 04 06 4e> < 13 17 01 69 6d ac 7c 32 7b 4e b5 88 0c d4 fc 68 06 69 c7 00 00 00 00>] Jun 10 05:50:39 pppdserver pppd[45652]: sent [LCP ConfRej id=0x8 < 11 04 06 4e> < 13 17 01 69 6d ac 7c 32 7b 4e b5 88 0c d4 fc 68 06 69 c7 00 00 00 00>] Jun 10 05:50:39 pppdserver pppd[45652]: sent [LCP ConfReq id=0x1 ] Jun 10 05:50:42 pppdserver pppd[45652]: LCP: timeout sending Config-Requests Jun 10 05:50:42 pppdserver pppd[45652]: Connection terminated, connected for 1 minutes Jun 10 05:50:42 pppdserver pppd[45652]: Hangup (SIGHUP) Jun 10 05:50:42 pppdserver pppd[45652]: Exit. I don't know what the "bad protocol 2666" error means. But from what I gather from reading google.com and groups.google.com pppd keeps sending LCP requests but doesn't receive anything back. I don't see anything refering to logins. mgetty works appearantely, since from the logs it sees autoppp fine and calls pppd with file options.server in /etc/ppp: -detach noauth asyncmap 0 modem crtscts lock refuse-chap require-pap proxyarp debug kdebug 1 mru 576 mtu 576 bsdcomp 15,15 deflate 15,15 vj-max-slots 16 idle 500 I don't know if this will help, here's the portion of the mgetty.log file pertaining to the connection: 06/10 05:49:56 aa0 checking lockfiles, locking the line 06/10 05:49:56 aa0 makelock(cuaa0) called 06/10 05:49:56 aa0 do_makelock: lock='/var/spool/lock/LCK..cuaa0' 06/10 05:49:56 aa0 lock made 06/10 05:49:56 aa0 wfr: waiting for ``RING'' 06/10 05:49:56 aa0 got: [0a][0d][0a]RING[0d] 06/10 05:49:56 aa0 wfr: rc=0, drn=0 06/10 05:49:56 aa0 send: ATA[0d] 06/10 05:49:56 aa0 waiting for ``CONNECT'' 06/10 05:49:56 aa0 got: ATA[0d][0d][0a]+FDM[0d][0a][0d][0a]CONNECT ** found ** 06/10 05:50:10 aa0 send: 06/10 05:50:10 aa0 waiting for ``_'' 06/10 05:50:10 aa0 got: 31200/ARQ[0d] ** found ** 06/10 05:50:10 aa0 waiting for line to clear (VTIME), read: [0a]~[ff]}#[c0]!}!} } /}"}&} } } } }%}&f0};?}'}"}(}"}1}$}&N}3}7}!im[ac]|2{N[b5][88]},[d4][fc]h}&i[c7]} } } } Z[db]~ 06/10 05:50:10 aa0 utmp + wtmp entry made 06/10 05:50:10 aa0 tio_set_flow_control( HARD ) 06/10 05:50:10 aa0 print welcome banner (/etc/issue) 06/10 05:50:10 aa0 getlogname (AUTO_PPP), read:~[ff]}#[c0]! 06/10 05:50:12 aa0 input finished with '\r', setting ICRNL ONLCR 06/10 05:50:12 aa0 tio_get_rs232_lines: status: RTS CTS DSR DTR DCD 06/10 05:50:12 aa0 match: user='/AutoPPP/', key='' 06/10 05:50:12 aa0 match: user='/AutoPPP/', key='' 06/10 05:50:12 aa0 match: user='/AutoPPP/', key='/AutoPPP/'*** hit! 06/10 05:50:12 aa0 calling login: cmd='/usr/sbin/pppd', argv[]='pppd cuaa0 file /etc/ppp/options.server' 06/10 05:50:12 ##### data dev=cuaa0, pid=45652, caller='none', conn='31200/ARQ', name='', cmd='/usr/sbin/pppd', user='/AutoPPP/' 06/10 05:50:12 aa0 setenv: 'CALLER_ID=none' 06/10 05:50:12 aa0 setenv: 'CALLED_ID=' 06/10 05:50:12 aa0 setenv: 'CONNECT=31200/ARQ' -- 06/10 05:50:42 aa0 mgetty: experimental test release 1.1.25-Feb01 06/10 05:50:42 aa0 check for lockfiles 06/10 05:50:42 aa0 checklock: no active process has lock, will remove 06/10 05:50:42 aa0 locking the line 06/10 05:50:42 aa0 makelock(cuaa0) called 06/10 05:50:42 aa0 do_makelock: lock='/var/spool/lock/LCK..cuaa0' 06/10 05:50:42 aa0 lock made 06/10 05:50:45 aa0 tio_get_rs232_lines: status: RTS CTS DSR DTR 06/10 05:50:45 aa0 lowering DTR to reset Modem 06/10 05:50:46 aa0 tss: set speed to 38400 (113000) 06/10 05:50:46 aa0 tio_set_flow_control( HARD ) 06/10 05:50:46 aa0 waiting for line to clear (VTIME), read: 06/10 05:50:46 aa0 send: ATS0=0Q0&D3&C1[0d] 06/10 05:50:46 aa0 waiting for ``OK'' 06/10 05:50:46 aa0 got: ATS0=0Q0&D3&C1[0d][0d][0a]OK ** found ** 06/10 05:50:46 aa0 mdm_send: 'AT+FCLASS=2.0' 06/10 05:50:46 aa0 mdm_command: string 'AT+FCLASS=2.0' 06/10 05:50:46 aa0 mdm_command: string 'OK' -> OK 06/10 05:50:46 aa0 mdm_send: 'AT+FAA=1;+FCR=1' 06/10 05:50:46 aa0 mdm_command: string 'AT+FAA=1;+FCR=1' 06/10 05:50:46 aa0 mdm_command: string 'OK' -> OK 06/10 05:50:46 aa0 mdm_send: 'AT+FBO=1' 06/10 05:50:46 aa0 mdm_command: string 'AT+FBO=1' 06/10 05:50:46 aa0 mdm_command: string 'OK' -> OK 06/10 05:50:46 aa0 mdm_send: 'AT+FNR=1,1,1,0' 06/10 05:50:46 aa0 mdm_command: string 'AT+FNR=1,1,1,0' 06/10 05:50:46 aa0 mdm_command: string 'OK' -> OK 06/10 05:50:46 aa0 mdm_send: 'AT+FLI="4179421"' 06/10 05:50:46 aa0 mdm_command: string 'AT+FLI="4179421"' 06/10 05:50:47 aa0 mdm_command: string 'OK' -> OK 06/10 05:50:47 aa0 mdm_send: 'AT+FCC=1,5,0,2,0,0,0,0' 06/10 05:50:47 aa0 mdm_command: string 'AT+FCC=1,5,0,2,0' 06/10 05:50:47 aa0 mdm_command: string 'OK' -> OK 06/10 05:50:47 aa0 waiting for line to clear (VTIME), read: 06/10 05:50:47 aa0 removing lock file 06/10 05:50:47 aa0 waiting... I have tried in Win2k having the dialer bring up the terminal after connect and calling pppd from the command line, but it gave me the same error. Please, if anyone has had FreeBSD working as a dialup server with mgetty+pppd for Windows dialers, please point out to me what I have done wrong. I know it's got to be something very obvious, but I'm at a lost as to what it might possibly be. Thanks in advance. Dave To Unsubscribe: send mail to majordomo@FreeBSD.org with "unsubscribe freebsd-questions" in the body of the message