Date: Tue, 22 Jun 1999 18:51:57 +0200 From: Andre Albsmeier <andre.albsmeier@mchp.siemens.de> To: freebsd-questions@freebsd.org Subject: What is wrong with this ppp connection? Message-ID: <19990622185157.A7554@internal>
next in thread | raw e-mail | index | archive | help
Hi,
I want to connect to a 3.2-STABLE machine (called messfix) which
is attached to a mobile phone GSM 900 modem. On this machine I am using
the pp=/usr/local/bin/ppplogin feature. Here I have also got a 3.2-STABLE
machine, it is called server.
When I had "messfix" here in the lab, everything worked very well.
Now it is 500km away and I get the above attached output in the log.
Luckily, since I use the pp=/usr/local/bin/ppplogin feature, I can
still connect to it via a standard terminal progam (minicom).
Here is my ppp.conf file on "server":
------------------------- snip ------------------------------------
default:
set log Phase Chat LCP IPCP CCP tun command
set device /dev/modem
set speed 115200
allow users xxxx
set dial "ABORT BUSY ABORT NO\\sCARRIER TIMEOUT 5 \"\" AT \
OK-AT-OK ATE1Q0 OK \\dATDT\\T TIMEOUT 60 CONNECT \\p\\p\\p\\p\\r"
messfix:
set phone xxxxxx
set AuthName xxx
set AuthKey xxx
set timeout 600
set ifaddr 192.168.19.1 192.168.19.130 255.255.255.255
------------------------- snap ------------------------------------
Here is my ppp.conf file on "messfix":
------------------------- snip ------------------------------------
default:
set log Phase Chat LCP IPCP CCP tun command
set device /dev/modem
set speed 19200
set dial "ABORT BUSY ABORT NO\\sCARRIER TIMEOUT 5 \"\" AT \
OK-AT-OK ATE1Q0 OK \\dATDT\\T TIMEOUT 40 CONNECT"
server:
enable chap
set ifaddr 192.168.19.130 192.168.19.1 255.255.255.255
------------------------- snap ------------------------------------
It is quite an urgent problem because the remote machine is doing
measurements and we have to get the results here :-)
Any hints what I am doing wrong? I am a little bit confused by the
lines that contain things like "Magic 0x47b99d4a is NAKed!"....
Thanks a lot in advance,
-Andre
Here the log output on server:
Jun 22 18:34:07 <daemon.info> server ppp[8385]: tun0: Command: /dev/tty: dial messfix
Jun 22 18:34:07 <daemon.info> server ppp[8385]: tun0: Command: default: allow users xxxxx
Jun 22 18:34:07 <daemon.info> server ppp[8385]: tun0: Command: messfix: set phone xxxxxxx
Jun 22 18:34:07 <daemon.info> server ppp[8385]: tun0: Command: messfix: set AuthName ppp
Jun 22 18:34:07 <daemon.info> server ppp[8385]: tun0: Command: messfix: set AuthKey ********
Jun 22 18:34:07 <daemon.info> server ppp[8385]: tun0: Command: messfix: set timeout 600
Jun 22 18:34:07 <daemon.info> server ppp[8385]: tun0: Command: messfix: set ifaddr 192.168.19.1 192.168.19.130 255.255.255.255
Jun 22 18:34:07 <daemon.info> server ppp[8385]: tun0: Phase: bundle: Establish
Jun 22 18:34:07 <daemon.info> server ppp[8385]: tun0: Phase: deflink: closed -> opening
Jun 22 18:34:07 <daemon.info> server ppp[8385]: tun0: Phase: deflink: Connected!
Jun 22 18:34:07 <daemon.info> server ppp[8385]: tun0: Phase: deflink: opening -> dial
Jun 22 18:34:07 <daemon.info> server ppp[8385]: tun0: Phase: Phone: xxxxxxx
Jun 22 18:34:07 <daemon.info> server ppp[8385]: tun0: Chat: deflink: Dial attempt 1 of 1
Jun 22 18:34:07 <daemon.info> server ppp[8385]: tun0: Chat: Send: AT^M
Jun 22 18:34:07 <daemon.info> server ppp[8385]: tun0: Chat: Expect(5): OK
Jun 22 18:34:07 <daemon.info> server ppp[8385]: tun0: Chat: Received: AT^M^M
Jun 22 18:34:07 <daemon.info> server ppp[8385]: tun0: Chat: Received: OK^M
Jun 22 18:34:07 <daemon.info> server ppp[8385]: tun0: Chat: Send: ATE1Q0^M
Jun 22 18:34:07 <daemon.info> server ppp[8385]: tun0: Chat: Expect(5): OK
Jun 22 18:34:07 <daemon.info> server ppp[8385]: tun0: Chat: Received: ATE1Q0^M^M
Jun 22 18:34:07 <daemon.info> server ppp[8385]: tun0: Chat: Received: OK^M
Jun 22 18:34:07 <daemon.info> server ppp[8385]: tun0: Chat: Send: ATDTxxxxxxxx^M
Jun 22 18:34:09 <daemon.info> server ppp[8385]: tun0: Chat: Expect(60): CONNECT
Jun 22 18:34:40 <daemon.info> server ppp[8385]: tun0: Chat: Received: ATDTxxxxxxxxxxxx^M^M
Jun 22 18:34:40 <daemon.info> server ppp[8385]: tun0: Chat: Received: CONNECT 9600/ARQ/V32/LAPM^M
Jun 22 18:34:40 <daemon.info> server ppp[8385]: tun0: Chat: Send: ^M^M
Jun 22 18:34:41 <daemon.info> server ppp[8385]: tun0: Phase: deflink: dial -> login
Jun 22 18:34:41 <daemon.info> server ppp[8385]: tun0: Phase: deflink: login -> lcp
Jun 22 18:34:41 <daemon.info> server ppp[8385]: tun0: LCP: FSM: Using "deflink" as a transport
Jun 22 18:34:41 <daemon.info> server ppp[8385]: tun0: LCP: deflink: State change Initial --> Closed
Jun 22 18:34:41 <daemon.info> server ppp[8385]: tun0: LCP: deflink: State change Closed --> Stopped
Jun 22 18:34:42 <daemon.info> server ppp[8385]: tun0: LCP: deflink: LayerStart
Jun 22 18:34:42 <daemon.info> server ppp[8385]: tun0: LCP: deflink: SendConfigReq(1) state = Stopped
Jun 22 18:34:42 <daemon.info> server ppp[8385]: tun0: LCP: ACFCOMP[2]
Jun 22 18:34:42 <daemon.info> server ppp[8385]: tun0: LCP: PROTOCOMP[2]
Jun 22 18:34:42 <daemon.info> server ppp[8385]: tun0: LCP: ACCMAP[6] 0x00000000
Jun 22 18:34:42 <daemon.info> server ppp[8385]: tun0: LCP: MRU[4] 1500
Jun 22 18:34:42 <daemon.info> server ppp[8385]: tun0: LCP: MAGICNUM[6] 0x47b99d4a
Jun 22 18:34:42 <daemon.info> server ppp[8385]: tun0: LCP: deflink: State change Stopped --> Req-Sent
Jun 22 18:34:42 <daemon.info> server ppp[8385]: tun0: LCP: deflink: RecvConfigReq(1) state = Req-Sent
Jun 22 18:34:42 <daemon.info> server ppp[8385]: tun0: LCP: ACFCOMP[2]
Jun 22 18:34:42 <daemon.info> server ppp[8385]: tun0: LCP: PROTOCOMP[2]
Jun 22 18:34:42 <daemon.info> server ppp[8385]: tun0: LCP: ACCMAP[6] 0x00000000
Jun 22 18:34:42 <daemon.info> server ppp[8385]: tun0: LCP: MRU[4] 1500
Jun 22 18:34:42 <daemon.info> server ppp[8385]: tun0: LCP: MAGICNUM[6] 0x47b99d4a
Jun 22 18:34:42 <daemon.info> server ppp[8385]: tun0: LCP: Magic is same (47b99d4a) - 1 times
Jun 22 18:34:43 <daemon.info> server ppp[8385]: tun0: LCP: deflink: SendConfigNak(1) state = Req-Sent
Jun 22 18:34:43 <daemon.info> server ppp[8385]: tun0: LCP: MAGICNUM[6] 0x47b99d4a
Jun 22 18:34:44 <daemon.info> server ppp[8385]: tun0: LCP: deflink: RecvConfigNak(1) state = Req-Sent
Jun 22 18:34:44 <daemon.info> server ppp[8385]: tun0: LCP: MAGICNUM[6] 0x47b99d4a
Jun 22 18:34:44 <daemon.info> server ppp[8385]: tun0: LCP: Magic 0x47b99d4a is NAKed!
Jun 22 18:34:44 <daemon.info> server ppp[8385]: tun0: LCP: deflink: SendConfigReq(2) state = Req-Sent
Jun 22 18:34:44 <daemon.info> server ppp[8385]: tun0: LCP: ACFCOMP[2]
Jun 22 18:34:44 <daemon.info> server ppp[8385]: tun0: LCP: PROTOCOMP[2]
Jun 22 18:34:44 <daemon.info> server ppp[8385]: tun0: LCP: ACCMAP[6] 0x00000000
Jun 22 18:34:44 <daemon.info> server ppp[8385]: tun0: LCP: MRU[4] 1500
Jun 22 18:34:44 <daemon.info> server ppp[8385]: tun0: LCP: MAGICNUM[6] 0x45ef93ee
Jun 22 18:34:44 <daemon.info> server ppp[8385]: tun0: LCP: deflink: RecvConfigReq(2) state = Req-Sent
Jun 22 18:34:44 <daemon.info> server ppp[8385]: tun0: LCP: ACFCOMP[2]
Jun 22 18:34:44 <daemon.info> server ppp[8385]: tun0: LCP: PROTOCOMP[2]
Jun 22 18:34:44 <daemon.info> server ppp[8385]: tun0: LCP: ACCMAP[6] 0x00000000
Jun 22 18:34:44 <daemon.info> server ppp[8385]: tun0: LCP: MRU[4] 1500
Jun 22 18:34:44 <daemon.info> server ppp[8385]: tun0: LCP: MAGICNUM[6] 0x45ef93ee
Jun 22 18:34:44 <daemon.info> server ppp[8385]: tun0: LCP: Magic is same (45ef93ee) - 2 times
Jun 22 18:34:46 <daemon.info> server ppp[8385]: tun0: LCP: deflink: SendConfigNak(2) state = Req-Sent
Jun 22 18:34:46 <daemon.info> server ppp[8385]: tun0: LCP: MAGICNUM[6] 0x45ef93ee
Jun 22 18:34:46 <daemon.info> server ppp[8385]: tun0: LCP: deflink: RecvConfigNak(2) state = Req-Sent
Jun 22 18:34:46 <daemon.info> server ppp[8385]: tun0: LCP: MAGICNUM[6] 0x45ef93ee
Jun 22 18:34:46 <daemon.info> server ppp[8385]: tun0: LCP: Magic 0x45ef93ee is NAKed!
Jun 22 18:34:46 <daemon.info> server ppp[8385]: tun0: LCP: deflink: SendConfigReq(3) state = Req-Sent
Jun 22 18:34:46 <daemon.info> server ppp[8385]: tun0: LCP: ACFCOMP[2]
Jun 22 18:34:46 <daemon.info> server ppp[8385]: tun0: LCP: PROTOCOMP[2]
Jun 22 18:34:46 <daemon.info> server ppp[8385]: tun0: LCP: ACCMAP[6] 0x00000000
Jun 22 18:34:46 <daemon.info> server ppp[8385]: tun0: LCP: MRU[4] 1500
Jun 22 18:34:46 <daemon.info> server ppp[8385]: tun0: LCP: MAGICNUM[6] 0x78288e08
Jun 22 18:34:47 <daemon.info> server ppp[8385]: tun0: LCP: deflink: RecvConfigReq(3) state = Req-Sent
Jun 22 18:34:47 <daemon.info> server ppp[8385]: tun0: LCP: ACFCOMP[2]
Jun 22 18:34:47 <daemon.info> server ppp[8385]: tun0: LCP: PROTOCOMP[2]
Jun 22 18:34:47 <daemon.info> server ppp[8385]: tun0: LCP: ACCMAP[6] 0x00000000
Jun 22 18:34:47 <daemon.info> server ppp[8385]: tun0: LCP: MRU[4] 1500
Jun 22 18:34:47 <daemon.info> server ppp[8385]: tun0: LCP: MAGICNUM[6] 0x78288e08
Jun 22 18:34:47 <daemon.info> server ppp[8385]: tun0: LCP: Magic is same (78288e08) - 3 times
Jun 22 18:34:49 <daemon.info> server ppp[8385]: tun0: LCP: deflink: SendConfigNak(3) state = Req-Sent
Jun 22 18:34:49 <daemon.info> server ppp[8385]: tun0: LCP: MAGICNUM[6] 0x78288e08
Jun 22 18:34:49 <daemon.info> server ppp[8385]: tun0: LCP: deflink: RecvConfigNak(3) state = Req-Sent
Jun 22 18:34:49 <daemon.info> server ppp[8385]: tun0: LCP: MAGICNUM[6] 0x78288e08
Jun 22 18:34:49 <daemon.info> server ppp[8385]: tun0: LCP: Magic 0x78288e08 is NAKed!
Jun 22 18:34:49 <daemon.info> server ppp[8385]: tun0: LCP: deflink: SendConfigReq(4) state = Req-Sent
Jun 22 18:34:49 <daemon.info> server ppp[8385]: tun0: LCP: ACFCOMP[2]
Jun 22 18:34:49 <daemon.info> server ppp[8385]: tun0: LCP: PROTOCOMP[2]
Jun 22 18:34:49 <daemon.info> server ppp[8385]: tun0: LCP: ACCMAP[6] 0x00000000
Jun 22 18:34:49 <daemon.info> server ppp[8385]: tun0: LCP: MRU[4] 1500
Jun 22 18:34:49 <daemon.info> server ppp[8385]: tun0: LCP: MAGICNUM[6] 0x74f480c0
Jun 22 18:34:50 <daemon.info> server ppp[8385]: tun0: LCP: deflink: RecvConfigReq(4) state = Req-Sent
Jun 22 18:34:50 <daemon.info> server ppp[8385]: tun0: LCP: ACFCOMP[2]
Jun 22 18:34:50 <daemon.info> server ppp[8385]: tun0: LCP: PROTOCOMP[2]
Jun 22 18:34:50 <daemon.info> server ppp[8385]: tun0: LCP: ACCMAP[6] 0x00000000
Jun 22 18:34:50 <daemon.info> server ppp[8385]: tun0: LCP: MRU[4] 1500
Jun 22 18:34:50 <daemon.info> server ppp[8385]: tun0: LCP: MAGICNUM[6] 0x74f480c0
Jun 22 18:34:50 <daemon.info> server ppp[8385]: tun0: LCP: Magic is same (74f480c0) - 4 times
Jun 22 18:34:52 <daemon.info> server ppp[8385]: tun0: LCP: deflink: SendConfigNak(4) state = Req-Sent
Jun 22 18:34:52 <daemon.info> server ppp[8385]: tun0: LCP: MAGICNUM[6] 0x74f480c0
Jun 22 18:34:53 <daemon.info> server ppp[8385]: tun0: LCP: deflink: RecvConfigNak(4) state = Req-Sent
Jun 22 18:34:53 <daemon.info> server ppp[8385]: tun0: LCP: MAGICNUM[6] 0x74f480c0
Jun 22 18:34:53 <daemon.info> server ppp[8385]: tun0: LCP: Magic 0x74f480c0 is NAKed!
Jun 22 18:34:53 <daemon.info> server ppp[8385]: tun0: LCP: deflink: SendConfigReq(5) state = Req-Sent
Jun 22 18:34:53 <daemon.info> server ppp[8385]: tun0: LCP: ACFCOMP[2]
Jun 22 18:34:53 <daemon.info> server ppp[8385]: tun0: LCP: PROTOCOMP[2]
Jun 22 18:34:53 <daemon.info> server ppp[8385]: tun0: LCP: ACCMAP[6] 0x00000000
Jun 22 18:34:53 <daemon.info> server ppp[8385]: tun0: LCP: MRU[4] 1500
Jun 22 18:34:53 <daemon.info> server ppp[8385]: tun0: LCP: MAGICNUM[6] 0x36c83fa9
Jun 22 18:34:55 <daemon.info> server ppp[8385]: tun0: LCP: deflink: SendConfigReq(5) state = Req-Sent
Jun 22 18:34:55 <daemon.info> server ppp[8385]: tun0: LCP: ACFCOMP[2]
Jun 22 18:34:55 <daemon.info> server ppp[8385]: tun0: LCP: PROTOCOMP[2]
Jun 22 18:34:55 <daemon.info> server ppp[8385]: tun0: LCP: ACCMAP[6] 0x00000000
Jun 22 18:34:55 <daemon.info> server ppp[8385]: tun0: LCP: MRU[4] 1500
Jun 22 18:34:55 <daemon.info> server ppp[8385]: tun0: LCP: MAGICNUM[6] 0x36c83fa9
Jun 22 18:34:58 <daemon.info> server ppp[8385]: tun0: LCP: deflink: SendConfigReq(5) state = Req-Sent
Jun 22 18:34:58 <daemon.info> server ppp[8385]: tun0: LCP: ACFCOMP[2]
Jun 22 18:34:58 <daemon.info> server ppp[8385]: tun0: LCP: PROTOCOMP[2]
Jun 22 18:34:58 <daemon.info> server ppp[8385]: tun0: LCP: ACCMAP[6] 0x00000000
Jun 22 18:34:58 <daemon.info> server ppp[8385]: tun0: LCP: MRU[4] 1500
Jun 22 18:34:58 <daemon.info> server ppp[8385]: tun0: LCP: MAGICNUM[6] 0x36c83fa9
Jun 22 18:35:01 <daemon.info> server ppp[8385]: tun0: LCP: deflink: SendConfigReq(5) state = Req-Sent
Jun 22 18:35:01 <daemon.info> server ppp[8385]: tun0: LCP: ACFCOMP[2]
Jun 22 18:35:01 <daemon.info> server ppp[8385]: tun0: LCP: PROTOCOMP[2]
Jun 22 18:35:01 <daemon.info> server ppp[8385]: tun0: LCP: ACCMAP[6] 0x00000000
Jun 22 18:35:01 <daemon.info> server ppp[8385]: tun0: LCP: MRU[4] 1500
Jun 22 18:35:01 <daemon.info> server ppp[8385]: tun0: LCP: MAGICNUM[6] 0x36c83fa9
Jun 22 18:35:04 <daemon.info> server ppp[8385]: tun0: LCP: deflink: SendConfigReq(5) state = Req-Sent
Jun 22 18:35:04 <daemon.info> server ppp[8385]: tun0: LCP: ACFCOMP[2]
Jun 22 18:35:04 <daemon.info> server ppp[8385]: tun0: LCP: PROTOCOMP[2]
Jun 22 18:35:04 <daemon.info> server ppp[8385]: tun0: LCP: ACCMAP[6] 0x00000000
Jun 22 18:35:04 <daemon.info> server ppp[8385]: tun0: LCP: MRU[4] 1500
Jun 22 18:35:04 <daemon.info> server ppp[8385]: tun0: LCP: MAGICNUM[6] 0x36c83fa9
Jun 22 18:35:07 <daemon.info> server ppp[8385]: tun0: LCP: deflink: LayerFinish
Jun 22 18:35:07 <daemon.info> server ppp[8385]: tun0: LCP: deflink: State change Req-Sent --> Stopped
Jun 22 18:35:07 <daemon.info> server ppp[8385]: tun0: LCP: deflink: State change Stopped --> Closed
Jun 22 18:35:07 <daemon.info> server ppp[8385]: tun0: LCP: deflink: State change Closed --> Initial
Jun 22 18:35:07 <daemon.info> server ppp[8385]: tun0: Phase: deflink: Disconnected!
Jun 22 18:35:07 <daemon.info> server ppp[8385]: tun0: Phase: deflink: lcp -> hangup
Jun 22 18:35:07 <daemon.info> server ppp[8385]: tun0: Phase: deflink: Connect time: 60 secs: 689 octets in, 572 octets out
Jun 22 18:35:07 <daemon.info> server ppp[8385]: tun0: Phase: total 21 bytes/sec, peak 78 bytes/sec on Tue Jun 22 18:35:07 1999
Jun 22 18:35:07 <daemon.info> server ppp[8385]: tun0: Phase: deflink: hangup -> closed
Jun 22 18:35:07 <daemon.info> server ppp[8385]: tun0: Phase: bundle: Dead
Jun 22 18:35:13 <daemon.info> server ppp[8385]: tun0: Command: /dev/tty: close
Jun 22 18:35:17 <daemon.info> server ppp[8385]: tun0: Phase: /dev/tty: Client connection closed.
Jun 22 18:35:17 <daemon.info> server ppp[8385]: tun0: Phase: PPP Terminated (normal).
--
A kernel a day + has anything changed?
keeps sanity away | is Linus deranged?
by time you have untarred one | I state with much glee
another has come + I use FreeBSD
To Unsubscribe: send mail to majordomo@FreeBSD.org
with "unsubscribe freebsd-questions" in the body of the message
Want to link to this message? Use this URL: <https://mail-archive.FreeBSD.org/cgi/mid.cgi?19990622185157.A7554>