PPP problem on Red Hat 9.0 Kernel 2.4.21
 

I am trying to connect to a Windows 2000 machine over a serial modem. I have the scripts dialing and the Windows machine picks up and authintactes the call, but when it goes into the handshake on protocols something happens.

Log from Linux
sent [LCP EchoReq id=0x0 magic=0xbd32ba76]
rcvd [CHAP Challenge id=0x0 <b462502035f4f534>, name = "****"]
sent [CHAP Response id=0x0 <000000000000000000000000000000000000000000000000c025b26e91be698a053ac825d432a7a40c75fddbc9910d6f01> , name = "****"]
rcvd [LCP EchoRep id=0x0 magic=0x19850673]
rcvd [CHAP Success id=0x0 ""]
Couldn't set MRRU: Inappropriate ioctl for device
Using interface ppp0
New bundle ppp0 created
sent [IPCP ConfReq id=0x1 <addr 192.168.1.254> <compress VJ 0f 01>]
rcvd [CCP ConfReq id=0x5 < 12 06 00 00 00 f1>]
Unsupported protocol 'Compression Control Protocol' (0x80fd) received
sent [LCP ProtRej id=0x3 80 fd 01 05 00 0a 12 06 00 00 00 f1]
rcvd [IPCP ConfReq id=0x6 <compress VJ 0f 01> <addr 192.168.3.254>]
sent [IPCP ConfAck id=0x6 <compress VJ 0f 01> <addr 192.168.3.254>]
rcvd [LCP TermReq id=0x7 19 85 06 73 00 3c cd 74 00 00 02 e6]
LCP terminated by peer (^YM-^E^Fs^@<M-Mt^@^@^BM-f)
sent [LCP TermAck id=0x7]
Hangup (SIGHUP)
Modem hangup
Connection terminated.

At this point the connection is closed. This used to work. I have all the PPP modules compiled for the kernel, but it doesn't seem to want to make the connection.

Here is a Log from the windows side of things
[1156] 11:10:33:507: MS-CHAP-MPPE-Keys set
[1156] 11:10:33:507: Auth Attribute Domain = MCC2
[1156] 11:10:33:507: Auth Attribute Idle Timeout Seconds = 0
[1156] 11:10:33:507: AuthAttribute MaxChannelsAllowed = -1
[1156] 11:10:33:507: FsmThisLayerUp called for protocol = c223, port = 8
[1156] 11:10:33:507: NotifyCaller(hPort=8, dwMsgId=17)
[1156] 11:10:33:507: NotifyCaller(hPort=8, dwMsgId=20)
[1304] 11:10:33:507: PPPEMSG_DdmInterfaceInfo recvd,IPXif=0,IPif=1b22f08,Type=0

[1156] 11:10:33:507: Will not initialize CP 8029
[1156] 11:10:33:507: Will not initialize CP 803f
[1156] 11:10:33:507: Will not initialize CP 802b
[1156] 11:10:33:507: FsmInit called for protocol = 80fd, port = 8
[1156] 11:10:33:507: FsmReset called for protocol = 80fd, port = 8
[1156] 11:10:33:507: FsmInit called for protocol = 8021, port = 8
[1156] 11:10:33:507: FsmReset called for protocol = 8021, port = 8
[1156] 11:10:33:507: FsmOpen event received for protocol 80fd on port 8
[1156] 11:10:33:507: FsmThisLayerStarted called for protocol = 80fd, port = 8
[1156] 11:10:33:507: FsmUp event received for protocol 80fd on port 8
[1156] 11:10:33:507: <PPP packet sent at 09/29/2004 15:10:33:507
[1156] 11:10:33:507: <Protocol = CCP, Type = Configure-Req, Length = 0xc, Id = 0x8, Port = 8
[1156] 11:10:33:507: <80 FD 01 08 00 0A 12 06 00 00 00 F1 00 00 00 00 |................|
[1156] 11:10:33:507:
[1156] 11:10:33:507: InsertInTimerQ called portid=6,Id=8,Protocol=80fd,EventType=0,fAuth=0
[1156] 11:10:33:507: FsmOpen event received for protocol 8021 on port 8
[1156] 11:10:33:507: FsmThisLayerStarted called for protocol = 8021, port = 8
[1156] 11:10:33:507: FsmUp event received for protocol 8021 on port 8
[1156] 11:10:33:507: <PPP packet sent at 09/29/2004 15:10:33:507
[1156] 11:10:33:507: <Protocol = IPCP, Type = Configure-Req, Length = 0x12, Id = 0x9, Port = 8
[1156] 11:10:33:507: <80 21 01 09 00 10 02 06 00 2D 0F 01 03 06 C0 A8 |.!.......-......|
[1156] 11:10:33:507: <03 FE 00 00 00 00 00 00 00 00 00 00 00 00 00 00 |................|
[1156] 11:10:33:507:
[1156] 11:10:33:507: InsertInTimerQ called portid=6,Id=9,Protocol=8021,EventType=0,fAuth=0
[672] 11:10:35:660: Packet received (18 bytes) for hPort 8
[1156] 11:10:35:660: >PPP packet received at 09/29/2004 15:10:35:660
[1156] 11:10:35:660: >Protocol = LCP, Type = Protocol-Reject, Length = 0x12, Id = 0x2, Port = 8
[1156] 11:10:35:660: >C0 21 08 02 00 10 80 FD 01 08 00 0A 12 06 00 00 |.!..............|
[1156] 11:10:35:660: >00 F1 00 00 00 00 00 00 00 00 00 00 00 00 00 00 |................|
[1156] 11:10:35:660:
[1156] 11:10:35:660: PPP Protocol Reject, Protocol = 80fd
[1156] 11:10:35:660: RemoveFromTimerQ called portid=6,Id=8,Protocol=80fd,EventType=0,fAuth=0
[1156] 11:10:35:660: FsmThisLayerFinished called for protocol = 80fd, port = 8
[1156] 11:10:35:660: NotifyCaller(hPort=8, dwMsgId=14)
[1304] 11:10:35:660: PppDdmStop

[1304] 11:10:35:660: PPPEMSG_Stop recvd

[1156] 11:10:35:660: FsmClose event received for protocol c021 on port 8
[1156] 11:10:35:660: RemoveFromTimerQ called portid=6,Id=6,Protocol=c021,EventType=0,fAuth=0
[1156] 11:10:35:660: FsmThisLayerDown called for protocol = c021, port = 8
[1156] 11:10:35:660: FsmDown event received for protocol 80fd on port 8
[1156] 11:10:35:660: RemoveFromTimerQ called portid=6,Id=8,Protocol=80fd,EventType=0,fAuth=0
[1156] 11:10:35:660: FsmReset called for protocol = 80fd, port = 8
[1156] 11:10:35:660: FsmDown event received for protocol 8021 on port 8
[1156] 11:10:35:660: RemoveFromTimerQ called portid=6,Id=9,Protocol=8021,EventType=0,fAuth=0
[1156] 11:10:35:660: FsmReset called for protocol = 8021, port = 8
[1156] 11:10:35:660: RemoveFromTimerQ called portid=6,Id=0,Protocol=c029,EventType=0,fAuth=0
[1156] 11:10:35:660: <PPP packet sent at 09/29/2004 15:10:35:660
[1156] 11:10:35:660: <Protocol = LCP, Type = Terminate-Req, Length = 0x12, Id = 0xa, Port = 8
[1156] 11:10:35:660: <C0 21 05 0A 00 10 32 FA 4C 1F 00 3C CD 74 00 00 |.!....2.L..<.t..|
[1156] 11:10:35:660: <02 E6 00 00 00 00 00 00 00 00 00 00 00 00 00 00 |................|
[1156] 11:10:35:660:
[1156] 11:10:35:660: InsertInTimerQ called portid=6,Id=10,Protocol=c021,EventType=0,fAuth=0
[672] 11:10:37:823: Packet received (6 bytes) for hPort 8
[1156] 11:10:37:823: >PPP packet received at 09/29/2004 15:10:37:823
[1156] 11:10:37:823: >Protocol = LCP, Type = Terminate-Ack, Length = 0x6, Id = 0xa, Port = 8
[1156] 11:10:37:823: >C0 21 06 0A 00 04 00 00 00 00 00 00 00 00 00 00 |.!..............|
[1156] 11:10:37:823:
[1156] 11:10:37:823: RemoveFromTimerQ called portid=6,Id=10,Protocol=c021,EventType=0,fAuth=0
[1156] 11:10:37:823: FsmThisLayerFinished called for protocol = c021, port = 8
[1156] 11:10:37:823: FsmThisLayerFinished called for protocol = 80fd, port = 8: 0
[1156] 11:10:37:823: FsmThisLayerFinished called for protocol = 8021, port = 8: 0
[1156] 11:10:37:823: NotifyCaller(hPort=8, dwMsgId=18)
[672] 11:10:39:736: PPPEMSG_LineDown recvd, hPort=8

[1156] 11:10:39:736: Line down event occurred on port 8
[1156] 11:10:39:736: FsmDown event received for protocol c021 on port 8
[1156] 11:10:39:736: RemoveFromTimerQ called portid=6,Id=10,Protocol=c021,EventType=0,fAuth=0
[1156] 11:10:39:736: FsmReset called for protocol = c021, port = 8
[1156] 11:10:39:736: RemoveFromTimerQ called portid=6,Id=0,Protocol=0,EventType=3,fAuth=0
[1156] 11:10:39:736: RemoveFromTimerQ called portid=6,Id=0,Protocol=0,EventType=1,fAuth=0
[1156] 11:10:39:736: RemoveFromTimerQ called portid=6,Id=0,Protocol=0,EventType=4,fAuth=0
[1156] 11:10:39:736: RemoveFromTimerQ called portid=6,Id=0,Protocol=0,EventType=6,fAuth=0
[1156] 11:10:39:766: RemoveFromTimerQ called portid=6,Id=0,Protocol=c029,EventType=0,fAuth=0
[1156] 11:10:39:766: LcpEnd
[1156] 11:10:39:776: NotifyCaller(hPort=8, dwMsgId=23)

This is starting to drive me a littl nuts, and i am sure it something simple that i am forgetting. Any help would be great thanks.

Ok, i have figured out the authication problem, and the problem with dropping the call, but now i have a problem with IPCP timeout. Once the PPP session goes into trying to handshake on a protocol to use it seems to time out on the IPCP protocol. I get a "No Network Protocols Running" and the connection is closed. Anyway have any ideas what might be causeing this?

I am trying to dial in from a Linux box into a Windows 2000 Pro machine. So far i have not be able to get the two to talk to each other. The Linux box is running the 2.4.21 kernel with all the PPP options compiled as modules. The Windows 2000 Pro machine is a standard install.

Any ideas??

I am still getting the error. I am using ppp-2.4.1-10 and i have even dropped back to ppp-2.4.1-3 but i still have this problem. I have comiled in all the PPP opetions in the kernel and i don't know why i have getting this error.

If someone could help me out it would be great. thanks.

Well i am no longer getting the No Protocols Running error. I am not getting a LCP Terminate by Peer error. I am trying to connect to a Windows 2000 box over a standard 19200 modem. I have played a little bit with my LCP timeout variables, but is there something on Windows i might have to do to get this to work? (or should i just run windows on both machines and be done with it?)

Hi FLLinux,

Looks like you've been at this one for a while. Wish I could help. Have you tried any newsgroups? I remember getting help from the good fellow who wrote this doc:
http://axion.physics.ubc.ca/ppp-linux.html
from... hmm... maybe comp.os.linux.networking . Darn. Why did google have to mess with the nice way they used to have http://groups.google.com set up.

Also, maybe this might be of use:
http://www.linux.org/docs/ldp/howto/PPP-HOWTO/

Finally, I just googled and came up with these
http://www.van-dijk.net/PPP-NT-HOWTO/PPP-NT-HOWTO.html
http://www.handhelds.org/howto/win2kppp/win2kppp.html
http://www.jacco2.dds.nl/networking/...-freeswan.html
but have no idea if they will be directly helpful to your specific issue.

Thanks for the post JohnMG. The links helped me out.

Now i am stuck somewhere else. here is a listing of the log from the modem on the linux side
Serial connection established.
using channel 4
Connect: <--> /dev/ttyUSB0
sent [LCP ConfReq id=0x1 <asyncmap 0x0> <magic 0xa8251736> <pcomp> <accomp> <mrru 1500> <endpoint [MAC:00:80:17:cc:64:3e]>]
rcvd [LCP ConfReq id=0x0 <asyncmap 0x0> <auth chap 81> <magic 0x65046545> <pcomp> <accomp> <callback CBCP> <mrru 1614> <endpoint [local:e4.81.63.62.15.72.4e.e8.bf.7e.78.7d.75.dc.41.ad.00.00.00.00]> < 17 04 00 03>]
sent [LCP ConfRej id=0x0 <callback CBCP> < 17 04 00 03>]
rcvd [LCP ConfAck id=0x1 <asyncmap 0x0> <magic 0xa8251736> <pcomp> <accomp> <mrru 1500> <endpoint [MAC:00:80:17:cc:64:3e]>]
rcvd [LCP ConfReq id=0x1 <asyncmap 0x0> <auth chap 81> <magic 0x65046545> <pcomp> <accomp> <mrru 1614> <endpoint [local:e4.81.63.62.15.72.4e.e8.bf.7e.78.7d.75.dc.41.ad.00.00.00.00]>]
sent [LCP ConfNak id=0x1 <auth chap MD5>]
rcvd [LCP ConfReq id=0x2 <asyncmap 0x0> <auth chap 81> <magic 0x65046545> <pcomp> <accomp> <mrru 1614> <endpoint [local:e4.81.63.62.15.72.4e.e8.bf.7e.78.7d.75.dc.41.ad.00.00.00.00]>]
sent [LCP ConfNak id=0x2 <auth chap MD5>]
rcvd [LCP ConfReq id=0x3 <asyncmap 0x0> <auth chap m$oft> <magic 0x65046545> <pcomp> <accomp> <mrru 1614> <endpoint [local:e4.81.63.62.15.72.4e.e8.bf.7e.78.7d.75.dc.41.ad.00.00.00.00]>]
sent [LCP ConfAck id=0x3 <asyncmap 0x0> <auth chap m$oft> <magic 0x65046545> <pcomp> <accomp> <mrru 1614> <endpoint [local:e4.81.63.62.15.72.4e.e8.bf.7e.78.7d.75.dc.41.ad.00.00.00.00]>]
rcvd [CHAP Challenge id=0x0 <ade06e55127c9ff0>, name = "*****"]
sent [CHAP Response id=0x0 <00000000000000000000000000000000000000000000000011ae33942945ec042a84c6a5b9c16290d387a70e76c27a6201> , name = "*****"]
rcvd [CHAP Success id=0x0 ""]
Couldn't set MRRU: Inappropriate ioctl for device
Using interface ppp0
New bundle ppp0 created
sent [IPCP ConfReq id=0x1 <addr 192.168.1.254> <compress VJ 0f 01>]
rcvd [CCP ConfReq id=0x5 < 12 06 00 00 00 01>]
Unsupported protocol 'Compression Control Protocol' (0x80fd) received
sent [LCP ProtRej id=0x2 80 fd 01 05 00 0a 12 06 00 00 00 01]
rcvd [IPCP ConfReq id=0x6 <compress VJ 0f 01> <addr 192.168.3.254>]
sent [IPCP ConfAck id=0x6 <compress VJ 0f 01> <addr 192.168.3.254>]
rcvd [IPCP ConfReq id=0x7 <compress VJ 0f 01> <addr 192.168.3.254>]
sent [IPCP ConfAck id=0x7 <compress VJ 0f 01> <addr 192.168.3.254>]
rcvd [IPCP ConfReq id=0x8 <compress VJ 0f 01> <addr 192.168.3.254>]
sent [IPCP ConfAck id=0x8 <compress VJ 0f 01> <addr 192.168.3.254>]
sent [IPCP ConfReq id=0x1 <addr 192.168.1.254> <compress VJ 0f 01>]
rcvd [IPCP ConfReq id=0x9 <compress VJ 0f 01> <addr 192.168.3.254>]
sent [IPCP ConfAck id=0x9 <compress VJ 0f 01> <addr 192.168.3.254>]
rcvd [IPCP ConfReq id=0xa <compress VJ 0f 01> <addr 192.168.3.254>]
sent [IPCP ConfAck id=0xa <compress VJ 0f 01> <addr 192.168.3.254>]
sent [IPCP ConfReq id=0x1 <addr 192.168.1.254> <compress VJ 0f 01>]
rcvd [IPCP ConfReq id=0xb <compress VJ 0f 01> <addr 192.168.3.254>]
sent [IPCP ConfAck id=0xb <compress VJ 0f 01> <addr 192.168.3.254>]
sent [IPCP ConfReq id=0x1 <addr 192.168.1.254> <compress VJ 0f 01>]
rcvd [IPCP ConfReq id=0xc <compress VJ 0f 01> <addr 192.168.3.254>]
sent [IPCP ConfAck id=0xc <compress VJ 0f 01> <addr 192.168.3.254>]
sent [IPCP ConfReq id=0x1 <addr 192.168.1.254> <compress VJ 0f 01>]
rcvd [IPCP ConfReq id=0xd <compress VJ 0f 01> <addr 192.168.3.254>]
sent [IPCP ConfAck id=0xd <compress VJ 0f 01> <addr 192.168.3.254>]
sent [IPCP ConfReq id=0x1 <addr 192.168.1.254> <compress VJ 0f 01>]
rcvd [IPCP ConfReq id=0xe <compress VJ 0f 01> <addr 192.168.3.254>]
sent [IPCP ConfAck id=0xe <compress VJ 0f 01> <addr 192.168.3.254>]
sent [IPCP ConfReq id=0x1 <addr 192.168.1.254> <compress VJ 0f 01>]
rcvd [IPCP ConfReq id=0xf <compress VJ 0f 01> <addr 192.168.3.254>]
sent [IPCP ConfAck id=0xf <compress VJ 0f 01> <addr 192.168.3.254>]
sent [IPCP ConfReq id=0x1 <addr 192.168.1.254> <compress VJ 0f 01>]
rcvd [IPCP ConfReq id=0x10 <compress VJ 0f 01> <addr 192.168.3.254>]
sent [IPCP ConfAck id=0x10 <compress VJ 0f 01> <addr 192.168.3.254>]
sent [IPCP ConfReq id=0x1 <addr 192.168.1.254> <compress VJ 0f 01>]
rcvd [IPCP ConfReq id=0x11 <compress VJ 0f 01> <addr 192.168.3.254>]
sent [IPCP ConfAck id=0x11 <compress VJ 0f 01> <addr 192.168.3.254>]
sent [IPCP ConfReq id=0x1 <addr 192.168.1.254> <compress VJ 0f 01>]
sent [IPCP ConfReq id=0x1 <addr 192.168.1.254> <compress VJ 0f 01>]
rcvd [IPCP ConfReq id=0x12 <compress VJ 0f 01> <addr 192.168.3.254>]
sent [IPCP ConfAck id=0x12 <compress VJ 0f 01> <addr 192.168.3.254>]
sent [IPCP ConfReq id=0x1 <addr 192.168.1.254> <compress VJ 0f 01>]
rcvd [IPCP ConfReq id=0x13 <compress VJ 0f 01> <addr 192.168.3.254>]
sent [IPCP ConfAck id=0x13 <compress VJ 0f 01> <addr 192.168.3.254>]
sent [IPCP ConfReq id=0x1 <addr 192.168.1.254> <compress VJ 0f 01>]
sent [IPCP ConfReq id=0x1 <addr 192.168.1.254> <compress VJ 0f 01>]
rcvd [IPCP ConfReq id=0x14 <compress VJ 0f 01> <addr 192.168.3.254>]
sent [IPCP ConfAck id=0x14 <compress VJ 0f 01> <addr 192.168.3.254>]
sent [IPCP ConfReq id=0x1 <addr 192.168.1.254> <compress VJ 0f 01>]
rcvd [IPCP ConfReq id=0x15 <compress VJ 0f 01> <addr 192.168.3.254>]
sent [IPCP ConfAck id=0x15 <compress VJ 0f 01> <addr 192.168.3.254>]
sent [IPCP ConfReq id=0x1 <addr 192.168.1.254> <compress VJ 0f 01>]
sent [IPCP ConfReq id=0x1 <addr 192.168.1.254> <compress VJ 0f 01>]
rcvd [LCP TermReq id=0x16 "e\004eE\000<\37777777715t\000\000\002\37777777716"]
LCP terminated by peer (e^DeE^@<M-Mt^@^@^BM-N)
sent [LCP TermAck id=0x16]
Hangup (SIGHUP)
Modem hangup
Connection terminated.

Here is a listing of the Linux ppp file
/dev/ttyUSB0
debug
38400
crtscts
192.168.1.254:192.168.3.254
defaultroute
pap-restart 10
lcp-max-configure 20
lcp-restart 10
ipcp-max-configure 40
ipcp-restart 10
user *****
password *****
logfile /etc/ppp/modem1log
noccp
passive
connect-delay 5000
connect '/usr/sbin/chat -v -f /etc/ppp/chat-modem1'

I have checked my password and everything on the Linux box and the windows box and they are correct. I am even tried creating a different account to log-int to the windows 2000 box. The call is still being dropped at some point. I added in the username and password to the connection file even though it goes through CHAP. My chap file has the same username and password as the connect script. I am completely out of ideas on this one. Please someone help.