Date: Sun, 10 Jun 2001 13:58:00 -0600 (MDT) From: FreeBSD <freebsd@XtremeDev.com> To: <freebsd-net@freebsd.org> Subject: pppd dialin server using mgetty and pppd Message-ID: <20010610135618.A65409-100000@Amber.XtremeDev.com>
next in thread | raw e-mail | index | archive | help
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 <mru 576>
<asyncmap 0x0> <authpap> <magic 0x6713d059> <pcomp> <accomp>]
Jun 10 05:50:15 pppdserver /kernel: ppp0: bad protocol 2666
Jun 10 05:50:15 pppdserver pppd[45652]: sent [LCP ConfReq id=0x1 <mru 576>
<asyncmap 0x0> <authpap> <magic 0x6713d059> <pcomp> <accomp>]
Jun 10 05:50:15 pppdserver pppd[45652]: rcvd [LCP ConfReq id=0x2 <asyncmap
0x0> <magic 0x66301b3f> <pcomp> <accomp> < 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 <mru 576>
<asyncmap 0x0> <authpap> <magic 0x6713d059> <pcomp> <accomp>]
Jun 10 05:50:19 pppdserver pppd[45652]: rcvd [LCP ConfReq id=0x3 <asyncmap
0x0> <magic 0x66301b3f> <pcomp> <accomp> < 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 <mru 576>
<asyncmap 0x0> <authpap> <magic 0x6713d059> <pcomp> <accomp>]
Jun 10 05:50:23 pppdserver pppd[45652]: rcvd [LCP ConfReq id=0x4 <asyncmap
0x0> <magic 0x66301b3f> <pcomp> <accomp> < 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 <mru 576>
<asyncmap 0x0> <authpap> <magic 0x6713d059> <pcomp> <accomp>]
Jun 10 05:50:27 pppdserver pppd[45652]: rcvd [LCP ConfReq id=0x5 <asyncmap
0x0> <magic 0x66301b3f> <pcomp> <accomp> < 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 <mru 576>
<asyncmap 0x0> <authpap> <magic 0x6713d059> <pcomp> <accomp>]
Jun 10 05:50:30 pppdserver pppd[45652]: sent [LCP ConfReq id=0x1 <mru 576>
<asyncmap 0x0> <authpap> <magic 0x6713d059> <pcomp> <accomp>]
Jun 10 05:50:31 pppdserver pppd[45652]: rcvd [LCP ConfReq id=0x6 <asyncmap
0x0> <magic 0x66301b3f> <pcomp> <accomp> < 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 <mru 576>
<asyncmap 0x0> <authpap> <magic 0x6713d059> <pcomp> <accomp>]
Jun 10 05:50:35 pppdserver pppd[45652]: rcvd [LCP ConfReq id=0x7 <asyncmap
0x0> <magic 0x66301b3f> <pcomp> <accomp> < 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 <mru 576>
<asyncmap 0x0> <authpap> <magic 0x6713d059> <pcomp> <accomp>]
Jun 10 05:50:39 pppdserver pppd[45652]: rcvd [LCP ConfReq id=0x8 <asyncmap
0x0> <magic 0x66301b3f> <pcomp> <accomp> < 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 <mru 576>
<asyncmap 0x0> <authpap> <magic 0x6713d059> <pcomp> <accomp>]
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-net" in the body of the message
Want to link to this message? Use this URL: <https://mail-archive.FreeBSD.org/cgi/mid.cgi?20010610135618.A65409-100000>