[730] in linux-net channel archive
ppp problem
daemon@ATHENA.MIT.EDU (Vern Smith)
Wed Jul 19 16:37:11 1995
From: Vern Smith <VLS@admin.unitec.ac.nz>
To: linux-net@vger.rutgers.edu
Date: Wed, 19 Jul 1995 14:57:33 GMT+1200
Reply-to: vsmith@unitec.ac.nz
X-Confirm-Reading-To: vsmith@unitec.ac.nz
I have a problem getting ppp to operate correctly between a PC
running Trumpet winsock and a Linux machine on a network. I would
appreciate help and/or advice in getting the link working correctly.
I am setting up a ppp dialup link from a pc running trumpet winsock
version 2.1e to a Linux machine on a network in an academic
institution. I can dial up from the pc and login to the Linux machine
no problem. Serial communications is fine. I have set up a ppp
userid in the recommended manner and that login invokes a script
containing:
exec /usr/sbin/pppd -detach -ip passive modem crtscts
202.2.4.210:202.2.4.221
The script mode is -rwsr-xr-x. There are 'ip-up' and 'ip-down' files
with the same mode.
I have followed (I hope) all the recommended installs and setups
from Howtos, FAQs, books etc. The problem is that after logging in
as user ppp from the PC (trumpet winsock), the PC and Linux box
initially communicate, but the session is terminated for unknown (to
me) reasons. The 'debug' and 'messages' files are listed below
together with the output from trumpet winsock.
Other particulars are: Linux kernel 1.2.1
pppd: version 2.1.2
The output on the Linux startup screen appears normal:
ie
ppp: version 0.2.7 (4 channels) NEW_TTY_DRIVERS OPTIMIZE_FLAGS
ppp line discipline registered
messages log
Jul 17 14:51:21 edtech pppd[2323]: pppd 2.1.2 started by ppp, uid 504
Jul 17 14:51:21 edtech pppd[2323]: Using interface ppp0
Jul 17 14:51:21 edtech pppd[2323]: Connect: ppp0 <--> /dev/ttyS1
Jul 17 14:51:21 edtech pppd[2323]: fsm_sdata(LCP): Sent code 1, id 1.
Jul 17 14:51:21 edtech pppd[2323]: LCP: sending Configure-Request, id
1
Jul 17 14:51:21 edtech pppd[2323]: fsm_rconfreq(LCP): Rcvd id 1.
Jul 17 14:51:21 edtech pppd[2323]: lcp_reqci: rcvd MRU
Jul 17 14:51:21 edtech pppd[2323]: (1500)
Jul 17 14:51:21 edtech pppd[2323]: (ACK)
Jul 17 14:51:21 edtech pppd[2323]: lcp_reqci: rcvd ASYNCMAP
Jul 17 14:51:21 edtech pppd[2323]: (0)
Jul 17 14:51:21 edtech pppd[2323]: (ACK)
Jul 17 14:51:21 edtech pppd[2323]: lcp_reqci: rcvd MAGICNUMBER
Jul 17 14:51:21 edtech pppd[2323]: (88f35)
Jul 17 14:51:21 edtech pppd[2323]: (ACK)
Jul 17 14:51:21 edtech pppd[2323]: lcp_reqci: rcvd PCOMPRESSION
Jul 17 14:51:21 edtech pppd[2323]: (ACK)
Jul 17 14:51:21 edtech pppd[2323]: lcp_reqci: rcvd ACCOMPRESSION
Jul 17 14:51:21 edtech pppd[2323]: (ACK)
Jul 17 14:51:21 edtech pppd[2323]: lcp_reqci: returning CONFACK.
Jul 17 14:51:21 edtech pppd[2323]: fsm_sdata(LCP): Sent code 2, id 1.
Jul 17 14:51:21 edtech pppd[2323]: fsm_rconfack(LCP): Rcvd id 1.
Jul 17 14:51:21 edtech pppd[2323]: fsm_sdata(IPCP): Sent code 1, id 1.
Jul 17 14:51:21 edtech pppd[2323]: IPCP: sending Configure-Request,
id 1
Jul 17 14:51:22 edtech pppd[2323]: fsm_rconfreq(IPCP): Rcvd id 1.
Jul 17 14:51:22 edtech pppd[2323]: ipcp: received COMPRESSTYPE
Jul 17 14:51:22 edtech pppd[2323]: (REJ)
Jul 17 14:51:22 edtech pppd[2323]: ipcp: received ADDR
Jul 17 14:51:22 edtech pppd[2323]: (REJ)
Jul 17 14:51:22 edtech pppd[2323]: ipcp: returning Configure-REJ
Jul 17 14:51:22 edtech pppd[2323]: fsm_sdata(IPCP): Sent code 4, id 1.
Jul 17 14:51:22 edtech pppd[2323]: fsm_rconfack(IPCP): Rcvd id 1.
Jul 17 14:51:22 edtech pppd[2323]: fsm_rconfreq(IPCP): Rcvd id 2.
Jul 17 14:51:22 edtech pppd[2323]: ipcp: returning Configure-ACK
Jul 17 14:51:22 edtech pppd[2323]: fsm_sdata(IPCP): Sent code 2, id 2.
Jul 17 14:51:22 edtech pppd[2323]: ipcp: up
Jul 17 14:51:22 edtech pppd[2323]: local IP address 202.2.4.210
Jul 17 14:51:22 edtech pppd[2323]: remote IP address 202.2.4.221
Jul 17 14:51:47 edtech pppd[2323]: fsm_rtermreq(IPCP): Rcvd id 2.
Jul 17 14:51:47 edtech pppd[2323]: IPCP terminated at peer's request
Jul 17 14:51:47 edtech pppd[2323]: ipcp: down
Jul 17 14:51:47 edtech pppd[2323]: fsm_sdata(IPCP): Sent code 6, id 2.
Jul 17 14:51:47 edtech pppd[2323]: fsm_rtermreq(LCP): Rcvd id 1.
Jul 17 14:51:47 edtech pppd[2323]: LCP terminated at peer's request
Jul 17 14:51:47 edtech pppd[2323]: fsm_sdata(LCP): Sent code 6, id 1.
Jul 17 14:51:50 edtech pppd[2323]: Connection terminated.
Jul 17 14:51:50 edtech pppd[2323]: Exit.
debug log
Jul 17 14:51:21 edtech pppd[2323]: set_xaccm: 00000000 00000000
00000000 60000000
Jul 17 14:51:21 edtech pppd[2323]: send_config: mtu = 1500
Jul 17 14:51:21 edtech pppd[2323]: send_config: asyncmap = ffffffff
Jul 17 14:51:21 edtech pppd[2323]: send_config: flags = f000000
Jul 17 14:51:21 edtech pppd[2323]: recv_config: mru = 1500
Jul 17 14:51:21 edtech pppd[2323]: recv_config: asyncmap = 0
Jul 17 14:51:21 edtech pppd[2323]: recv_config: flags = f000010
Jul 17 14:51:21 edtech pppd[2323]: sent [LCP ConfReq id=0x1 <mru
1500> <magic 0x32c7d82e> <pcomp> <accomp>]
Jul 17 14:51:21 edtech pppd[2323]: Timeout 2244:10920 in 3 seconds.
Jul 17 14:51:21 edtech pppd[2323]: Setting itimer for 3 seconds in
timeout.
Jul 17 14:51:21 edtech pppd[2323]: IO signal received
Jul 17 14:51:21 edtech pppd[2323]: rcvd [LCP ConfReq id=0x1 <mru
1500> <asyncmap 0x0> <magic 0x88f35> <pcomp> <accomp>] 5c 1b
Jul 17 14:51:21 edtech pppd[2323]: sent [LCP ConfAck id=0x1 <mru
1500> <asyncmap 0x0> <magic 0x88f35> <pcomp> <accomp>]
Jul 17 14:51:21 edtech pppd[2323]: IO signal received
Jul 17 14:51:21 edtech pppd[2323]: rcvd [LCP ConfAck id=0x1 <mru
1500> <magic 0x32c7d82e> <pcomp> <accomp>] 7c f3
Jul 17 14:51:21 edtech pppd[2323]: Untimeout 2244:10920.
Jul 17 14:51:21 edtech pppd[2323]: Setting itimer for 0 seconds in
untimeout.
Jul 17 14:51:21 edtech pppd[2323]: send_config: mtu = 1500
Jul 17 14:51:21 edtech pppd[2323]: send_config: asyncmap = 0
Jul 17 14:51:21 edtech pppd[2323]: send_config: flags = f000013
Jul 17 14:51:21 edtech pppd[2323]: recv_config: mru = 1500
Jul 17 14:51:21 edtech pppd[2323]: recv_config: asyncmap = 0
Jul 17 14:51:21 edtech pppd[2323]: recv_config: flags = f000003
Jul 17 14:51:21 edtech pppd[2323]: sent [IPCP ConfReq id=0x1]
Jul 17 14:51:21 edtech pppd[2323]: Timeout 2244:114f0 in 3 seconds.
Jul 17 14:51:21 edtech pppd[2323]: Setting itimer for 3 seconds in
timeout.
Jul 17 14:51:22 edtech pppd[2323]: IO signal received
Jul 17 14:51:22 edtech pppd[2323]: rcvd [IPCP ConfReq id=0x1
<compress VJ 0f 01> <addr 0.0.0.0>] 2d 99
Jul 17 14:51:22 edtech pppd[2323]: sent [IPCP ConfRej id=0x1
<compress VJ 0f 01> <addr 0.0.0.0>]
Jul 17 14:51:22 edtech pppd[2323]: IO signal received
Jul 17 14:51:22 edtech pppd[2323]: rcvd [IPCP ConfAck id=0x1] cd 92
Jul 17 14:51:22 edtech pppd[2323]: IO signal received
Jul 17 14:51:22 edtech pppd[2323]: rcvd [IPCP ConfReq id=0x2] 64 58
Jul 17 14:51:22 edtech pppd[2323]: sent [IPCP ConfAck id=0x2]
Jul 17 14:51:22 edtech pppd[2323]: Untimeout 2244:114f0.
Jul 17 14:51:22 edtech pppd[2323]: Setting itimer for 0 seconds in
untimeout.
Jul 17 14:51:22 edtech pppd[2323]: Script /etc/ppp/ip-up started; pid
= 2331
Jul 17 14:51:47 edtech pppd[2323]: IO signal received
Jul 17 14:51:47 edtech pppd[2323]: rcvd [IPCP TermReq id=0x2] 88 2a
Jul 17 14:51:47 edtech pppd[2323]: Script /etc/ppp/ip-down started;
pid = 2332
Jul 17 14:51:47 edtech pppd[2323]: Timeout 2244:114f0 in 3 seconds.
Jul 17 14:51:47 edtech pppd[2323]: Setting itimer for 3 seconds in
timeout.
Jul 17 14:51:47 edtech pppd[2323]: sent [IPCP TermAck id=0x2]
Jul 17 14:51:47 edtech pppd[2323]: IO signal received
Jul 17 14:51:47 edtech pppd[2323]: rcvd [LCP TermReq id=0x1] 3d c7
Jul 17 14:51:47 edtech pppd[2323]: Untimeout 2244:114f0.
Jul 17 14:51:47 edtech pppd[2323]: Setting itimer for 0 seconds in
untimeout.
Jul 17 14:51:47 edtech pppd[2323]: send_config: mtu = 1500
Jul 17 14:51:47 edtech pppd[2323]: send_config: asyncmap = ffffffff
Jul 17 14:51:47 edtech pppd[2323]: send_config: flags = f000008
Jul 17 14:51:47 edtech pppd[2323]: recv_config: mru = 1500
Jul 17 14:51:47 edtech pppd[2323]: recv_config: asyncmap = 0
Jul 17 14:51:47 edtech pppd[2323]: recv_config: flags = f000018
Jul 17 14:51:47 edtech pppd[2323]: Timeout 2244:10920 in 3 seconds.
Jul 17 14:51:47 edtech pppd[2323]: Setting itimer for 3 seconds in
timeout.
Jul 17 14:51:47 edtech pppd[2323]: sent [LCP TermAck id=0x1]
Jul 17 14:51:50 edtech pppd[2323]: Alarm
Winsock log
PPP[C021] state = starting
PPP[C023] state = starting
PPP[8021] state = starting
PPP ENABLED
Internal SLIP driver COM1 Baud rate = 19200 Hardware handshaking
Compression enabled
IP buffers = 32 Packet buffers = 16
My IP = 0.0.0.0 netmask = 0.0.0.0 gateway = 202.2.4.254
Executing script c:\internet\winsock\ppplogin.cmd.
PPP DISABLED
AT&F &C1 &D2 S95=0 S11=75 \J1 F0 &W
OK
atdt7435
CONNECT 19200
Welcome to Linux 1.2.1.
edtech -->:
Script completed
PPP ENABLED
PPP[C021] SND CONFREQ ID=01 LEN=24 MRU(05DC) ACCM(00000000)
MAGIC(00088F35) PFC ACFC
PPP[C021] state = reqsent
PPP[C021] RCV CONFREQ ID=01 LEN=18 MRU(05DC) MAGIC(32C7D82E) PFC ACFC
PPP[C021] SND CONFACK ID=01 LEN=18 MRU(05DC) MAGIC(32C7D82E) PFC ACFC
PPP[C021] state = acksent
PPP[C021] RCV CONFACK ID=01 LEN=24 MRU(05DC) ACCM(00000000)
MAGIC(00088F35) PFC ACFC
PPP[C021] state = opened
PPP[8021] SND CONFREQ ID=01 LEN=16 IPCP(002D0F01) IPADDR(00000000)
PPP[8021] state = reqsent
PPP[8021] RCV CONFREQ ID=01 LEN=4
PPP[8021] SND CONFACK ID=01 LEN=4
PPP[8021] state = acksent
PPP[8021] RCV CONFREJ ID=01 LEN=16 IPCP(002D0F01) IPADDR(00000000)
PPP[8021] SND CONFREQ ID=02 LEN=4
PPP[8021] RCV CONFACK ID=02 LEN=4
PPP[8021] state = opened
then follows cleanup with trumpet winsock terminating.
Many thanks to anybody who is able to throw light on the problem.
Vern Smith.
UNITEC Institute of Technology
Private Bag 92025 Email: vsmith@unitec.ac.nz
Auckland Phone: +64-9-815 4325
New Zealand Fax: +64-9-849 4312
