Re: ppp trouble

From: Lew Pitcher (Lew.Pitcher_at_td.com)
Date: 03/03/05 

Date: Thu, 03 Mar 2005 14:59:55 -0500

-----BEGIN PGP SIGNED MESSAGE-----
Hash: SHA1

Joe R wrote:
> I have a 2 year old Toshiba Satellite with XP with the obligatory winmodem,
> as a prelude to installing linux I wanted to see if I could get online with
> Knoppix 3.6. Instead of messing with the winmodem I purchased a ZFM5600 CF
> pcmcia modem which is know to work with linux.
>
> Knoppix beaps twice after I insert the card and puts it on /dev/ttyS0 and
> pppconfig reconizes it. It connects but only for a minute or two, Any ideas?

Your problem, what ever it is, is not with the PPP connection.

- From the log (below) it appears that you have successfully negotiated a full IP
connection with the other end, and sustained it for about two minutes. You sent
300+ bytes of data, and received just under 150 bytes back.

And then, your partner disconnected on you.

What did you do during that almost two minutes that your connection was running?
If you don't know, and can reproduce the problem, you could run the tcpdump
program on the connection, and see what packets are being sent across the wire.
That /might/ help determine why your ISP disconnected and hung up on you after
having successfully connected you to the internet.

> Here's part of /etc/syslog.
>
> Feb 28 21:50:10 Knoppix syslogd 1.4.1#15: restart.
> Feb 28 21:50:30 Knoppix cardmgr[88]: socket 1: Serial or Modem
> Feb 28 21:50:30 Knoppix cardmgr[88]: executing: 'modprobe serial_cs'
> Feb 28 21:50:30 Knoppix cardmgr[88]: executing: './serial start ttyS0'
> Feb 28 21:50:30 Knoppix cardmgr[88]: + Default modem setup
> .....
> Feb 28 22:00:02 Knoppix pppd[1605]: pppd 2.4.2 started by root, uid 0
> Feb 28 22:00:03 Knoppix chat[1607]: abort on (BUSY)
> Feb 28 22:00:03 Knoppix chat[1607]: abort on (NO CARRIER)
> Feb 28 22:00:03 Knoppix chat[1607]: abort on (VOICE)
> Feb 28 22:00:03 Knoppix chat[1607]: abort on (NO DIALTONE)
> Feb 28 22:00:03 Knoppix chat[1607]: abort on (NO DIAL TONE)
> Feb 28 22:00:03 Knoppix chat[1607]: abort on (NO ANSWER)
> Feb 28 22:00:03 Knoppix chat[1607]: abort on (DELAYED)
> Feb 28 22:00:03 Knoppix chat[1607]: send (ATZ^M)
> Feb 28 22:00:03 Knoppix chat[1607]: expect (OK)
> Feb 28 22:00:03 Knoppix chat[1607]: ATZ^M^M
> Feb 28 22:00:03 Knoppix chat[1607]: OK
> Feb 28 22:00:03 Knoppix chat[1607]: -- got it
> Feb 28 22:00:03 Knoppix chat[1607]: send (ATDT4108841220^M)
> Feb 28 22:00:03 Knoppix chat[1607]: expect (CONNECT)
> Feb 28 22:00:03 Knoppix chat[1607]: ^M
> Feb 28 22:00:30 Knoppix chat[1607]: ATDT4108841220^M^M
> Feb 28 22:00:30 Knoppix chat[1607]: CONNECT
> Feb 28 22:00:30 Knoppix chat[1607]: -- got it
> Feb 28 22:00:30 Knoppix chat[1607]: send (\d)
> Feb 28 22:00:31 Knoppix pppd[1605]: Serial connection established.
> Feb 28 22:00:31 Knoppix pppd[1605]: using channel 2
> Feb 28 22:00:31 Knoppix pppd[1605]: Using interface ppp0
> Feb 28 22:00:31 Knoppix pppd[1605]: Connect: ppp0 <--> /dev/ttyS0

OK, the dialer connected with the other end. At least your ISP has a working
phone connection ;-)

> Feb 28 22:00:32 Knoppix pppd[1605]: sent [LCP ConfReq id=0x1 <asyncmap 0x0> <magic 0xee7dc105> <pcomp> <accomp>]
> Feb 28 22:00:32 Knoppix pppd[1605]: rcvd [LCP ConfReq id=0x1 < 00 04 00 00> <mru 1524> <asyncmap 0xa0000> <auth pap> <pcomp> <accomp> <mrru 1524> <endpoint [MAC:00:c0:7b:8b:fc:71]>]
> Feb 28 22:00:32 Knoppix pppd[1605]: sent [LCP ConfRej id=0x1 < 00 04 00 00> <mrru 1524>]
> Feb 28 22:00:32 Knoppix pppd[1605]: rcvd [LCP ConfAck id=0x1 <asyncmap 0x0> <magic 0xee7dc105> <pcomp> <accomp>]
> Feb 28 22:00:32 Knoppix pppd[1605]: rcvd [LCP ConfReq id=0x2 <mru 1524> <asyncmap 0xa0000> <auth pap> <pcomp> <accomp> <endpoint [MAC:00:c0:7b:8b:fc:71]>]
> Feb 28 22:00:32 Knoppix pppd[1605]: sent [LCP ConfAck id=0x2 <mru 1524> <asyncmap 0xa0000> <auth pap> <pcomp> <accomp> <endpoint [MAC:00:c0:7b:8b:fc:71]>]

You and your ISP negotiate some parameters, including the async map, and the
decision that you will use pap to authenticate yourself.

> Feb 28 22:00:32 Knoppix pppd[1605]: sent [LCP EchoReq id=0x0 magic=0xee7dc105]
> Feb 28 22:00:32 Knoppix pppd[1605]: sent [PAP AuthReq id=0x1 user="jringer3" password=<hidden>]
> Feb 28 22:00:32 Knoppix pppd[1605]: rcvd [LCP EchoRep id=0x0 magic=0x0]
> Feb 28 22:00:32 Knoppix pppd[1605]: rcvd [PAP AuthAck id=0x1 ""]
> Feb 28 22:00:32 Knoppix pppd[1605]: PAP authentication succeeded

You successfully authenticated yourself to your ISP using the PAP protocol.

> Feb 28 22:00:33 Knoppix pppd[1605]: sent [CCP ConfReq id=0x1 <deflate 15> <deflate(old#) 15> <bsd v1 15>]
> Feb 28 22:00:33 Knoppix pppd[1605]: sent [IPCP ConfReq id=0x1 <compress VJ 0f 01> <addr 0.0.0.0> <ms-dns1 0.0.0.0> <ms-dns3 0.0.0.0>]
> Feb 28 22:00:33 Knoppix pppd[1605]: rcvd [IPCP ConfReq id=0x1 <compress VJ 0f 01> <addr 208.59.216.12>]
> Feb 28 22:00:33 Knoppix pppd[1605]: sent [IPCP ConfAck id=0x1 <compress VJ 0f 01> <addr 208.59.216.12>]
> Feb 28 22:00:33 Knoppix pppd[1605]: rcvd [CCP ConfReq id=0x1 < 11 06 00 01 01 03>]
> Feb 28 22:00:33 Knoppix pppd[1605]: sent [CCP ConfRej id=0x1 < 11 06 00 01 01 03>]
> Feb 28 22:00:33 Knoppix pppd[1605]: rcvd [CCP ConfRej id=0x1 <deflate 15> <deflate(old#) 15> <bsd v1 15>]
> Feb 28 22:00:33 Knoppix pppd[1605]: sent [CCP ConfReq id=0x2]
> Feb 28 22:00:33 Knoppix pppd[1605]: rcvd [IPCP ConfNak id=0x1 <addr 208.58.224.203> <ms-dns1 207.172.3.10> <ms-dns3 207.172.3.11>]
> Feb 28 22:00:33 Knoppix pppd[1605]: sent [IPCP ConfReq id=0x2 <compress VJ 0f 01> <addr 208.58.224.203> <ms-dns1 207.172.3.10> <ms-dns3 207.172.3.11>]
> Feb 28 22:00:33 Knoppix pppd[1605]: rcvd [CCP ConfReq id=0x2 <mppe -H -M -S -L -D +C>]
> Feb 28 22:00:33 Knoppix pppd[1605]: sent [CCP ConfRej id=0x2 <mppe -H -M -S -L -D +C>]
> Feb 28 22:00:33 Knoppix pppd[1605]: rcvd [CCP ConfRej id=0x2]
> Feb 28 22:00:33 Knoppix pppd[1605]: rcvd [IPCP ConfAck id=0x2 <compress VJ 0f 01> <addr 208.58.224.203> <ms-dns1 207.172.3.10> <ms-dns3 207.172.3.11>]

You and your ISP negotiate data compression algorithms, and they give you your
IP address.

> Feb 28 22:00:33 Knoppix pppd[1605]: Cannot determine ethernet address for proxy ARP
> Feb 28 22:00:33 Knoppix pppd[1605]: local IP address 208.58.224.203
> Feb 28 22:00:33 Knoppix pppd[1605]: remote IP address 208.59.216.12
> Feb 28 22:00:33 Knoppix pppd[1605]: primary DNS address 207.172.3.10
> Feb 28 22:00:33 Knoppix pppd[1605]: secondary DNS address 207.172.3.11

PPPD announces that you have IP connectivity to your ISP

> Feb 28 22:00:33 Knoppix pppd[1605]: Script /etc/ppp/ip-up started (pid 1653)
> Feb 28 22:00:33 Knoppix pppd[1605]: rcvd [CCP ConfReq id=0x3 < 11 05 00 01 04>]
> Feb 28 22:00:33 Knoppix pppd[1605]: sent [CCP ConfReq id=0x3 <deflate 15> <deflate(old#) 15> <bsd v1 15>]
> Feb 28 22:00:33 Knoppix pppd[1605]: sent [CCP ConfRej id=0x3 < 11 05 00 01 04>]
> Feb 28 22:00:33 Knoppix pppd[1605]: rcvd [CCP ConfRej id=0x3 <deflate 15> <deflate(old#) 15> <bsd v1 15>]
> Feb 28 22:00:33 Knoppix pppd[1605]: sent [CCP ConfReq id=0x4]
> Feb 28 22:00:33 Knoppix pppd[1605]: rcvd [CCP ConfReq id=0x4 < 11 05 00 01 03>]
> Feb 28 22:00:33 Knoppix pppd[1605]: sent [CCP ConfRej id=0x4 < 11 05 00 01 03>]
> Feb 28 22:00:33 Knoppix pppd[1605]: rcvd [CCP ConfRej id=0x4]
> Feb 28 22:00:33 Knoppix pppd[1605]: Script /etc/ppp/ip-up finished (pid 1653), status = 0x0

PPP has now run the ip-up script. You are officially "on the internet".

> Feb 28 22:02:13 Knoppix pppd[1605]: Hangup (SIGHUP)
> Feb 28 22:02:13 Knoppix pppd[1605]: Modem hangup

One minute, 40 seconds later, your ISP hangs up the phone
PPPD will now run the ip-down script

> Feb 28 22:02:13 Knoppix pppd[1605]: Script /etc/ppp/ip-down started (pid 1795)
> Feb 28 22:02:13 Knoppix pppd[1605]: Connection terminated.
> Feb 28 22:02:13 Knoppix pppd[1605]: Connect time 1.7 minutes.
> Feb 28 22:02:13 Knoppix pppd[1605]: Sent 313 bytes, received 142 bytes.

PPPD gives some stats
You sent 313 bytes out from your IP address
You received 142 bytes from the internet

> Feb 28 22:02:13 Knoppix pppd[1605]: Waiting for 1 child processes...
> Feb 28 22:02:13 Knoppix pppd[1605]: script /etc/ppp/ip-down, pid 1795
> Feb 28 22:02:13 Knoppix pppd[1605]: Script /etc/ppp/ip-down finished (pid 1795), status = 0x0

It's official, you are completely disconnected now.

> Feb 28 22:02:13 Knoppix pppd[1605]: Exit.

- --
Lew Pitcher
IT Specialist, Enterprise Data Systems,
Enterprise Technology Solutions, TD Bank Financial Group

(Opinions expressed are my own, not my employers')
-----BEGIN PGP SIGNATURE-----
Version: GnuPG v1.2.4 (MingW32)

iD8DBQFCJ2y7agVFX4UWr64RAg1GAKDZswu+hdgpVsqlQ/t3OWTiJJpRmwCg2BBS
NAjcL+7/bSVwrKRRmdlso2Y=
=ClV7
-----END PGP SIGNATURE-----

Relevant Pages

  • Re: wvdial problems
    ... Support people) is dropping the connection before the default (? ... an effort to delay the 3-second interval, but it causes the Modem hangup ... ISP terminating the connection for some arcane reason. ... pppd option required? ...
    (comp.os.linux.misc)
  • Re: Cyberterrorism [was: Re: NSA wiretap, Friday night]
    ... uniform rule to disconnect compromised machines. ... Wouldn't someone move from one ISP to another if it was really bad at ... but only obeying the terms of a statute ... needs the connection open, the ISP can open it through the proxy server. ...
    (comp.os.linux.security)
  • Re: Preventing default route being wrongly attached
    ... Do I need to get diald and pppd working together if I want to support ... I use the pon script to start a connection to my isp and poff to ...
    (Debian-User)
  • Re: how to save the visitors ip addresses
    ... through the same proxy. ... In the case of the IP address identifying the server. ... The only IP visible in the header is the one assigned to your site by your ISP. ... With none having lost a connection ...
    (comp.lang.php)
  • Re: Is there a minimum dialup speed that Vista can cope with?
    ... I no longer ring Eircom ... internet/phone bundle with another ISP, UTV (Eircom just rakes in the line ... I'm keeping modem logs because the line quality fluctuates many times ... hardware/software to fail with this slow connection. ...
    (microsoft.public.windows.vista.networking_sharing)