Hi,

My friend is trying to connect his PowerBook to Vodafone UMTS, but
without much success. I was hoping someone can help me to understand
the logfile and to solve the problem. Here is the relevant part from
the logfile:


Fri Dec 10 16:02:35 2004 : Serial connection established.
Fri Dec 10 16:02:35 2004 : using link 0
Fri Dec 10 16:02:35 2004 : Using interface ppp0
Fri Dec 10 16:02:35 2004 : Connect: ppp0 <--> /dev/cu.VMCC-3G
Fri Dec 10 16:02:36 2004 : sent [LCP ConfReq id=0x1 <asyncmap 0x0>
<magic 0x81a9494> <pcomp> <accomp>]
Fri Dec 10 16:02:36 2004 : rcvd [LCP ConfReq id=0x9 <asyncmap 0x0>
<auth chap MD5> <magic 0x49c13f4> <pcomp> <accomp>]
Fri Dec 10 16:02:36 2004 : lcp_reqci: returning CONFACK.
Fri Dec 10 16:02:36 2004 : sent [LCP ConfAck id=0x9 <asyncmap 0x0>
<auth chap MD5> <magic 0x49c13f4> <pcomp> <accomp>]
Fri Dec 10 16:02:36 2004 : rcvd [LCP ConfAck id=0x1 <asyncmap 0x0>
<magic 0x81a9494> <pcomp> <accomp>]
Fri Dec 10 16:02:36 2004 : rcvd [LCP DiscReq id=0xa magic=0x49c13f4]
Fri Dec 10 16:02:36 2004 : rcvd [CHAP Challenge id=0x1
<825368cd28e5427cd4e4d20b5c20ad42>, name = "UMTS_CHAP_SRVR"]
Fri Dec 10 16:02:36 2004 : sent [CHAP Response id=0x1
<7d6c1846de8aa64653ec692b5cc2bccb>, name = "vodafone"]
Fri Dec 10 16:02:37 2004 : rcvd [CHAP Success id=0x1 ""]
Fri Dec 10 16:02:37 2004 : sent [IPCP ConfReq id=0x1 <addr 0.0.0.0>
<ms-dns1 0.0.0.0> <ms-dns3 0.0.0.0>]
Fri Dec 10 16:02:38 2004 : rcvd [IPCP ConfNak id=0x1 <ms-dns1
10.11.12.13> <ms-dns3 10.11.12.14>]
Fri Dec 10 16:02:38 2004 : sent [IPCP ConfReq id=0x2 <addr 0.0.0.0>
<ms-dns1 10.11.12.13> <ms-dns3 10.11.12.14>]
Fri Dec 10 16:02:39 2004 : rcvd [IPCP ConfNak id=0x2 <ms-dns1
10.11.12.13> <ms-dns3 10.11.12.14>]
Fri Dec 10 16:02:39 2004 : sent [IPCP ConfReq id=0x3 <addr 0.0.0.0>
<ms-dns1 10.11.12.13> <ms-dns3 10.11.12.14>]
Fri Dec 10 16:02:42 2004 : sent [IPCP ConfReq id=0x3 <addr 0.0.0.0>
<ms-dns1 10.11.12.13> <ms-dns3 10.11.12.14>]
Fri Dec 10 16:02:45 2004 : sent [IPCP ConfReq id=0x3 <addr 0.0.0.0>
<ms-dns1 10.11.12.13> <ms-dns3 10.11.12.14>]
Fri Dec 10 16:02:48 2004 : sent [IPCP ConfReq id=0x3 <addr 0.0.0.0>
<ms-dns1 10.11.12.13> <ms-dns3 10.11.12.14>]
Fri Dec 10 16:02:51 2004 : sent [IPCP ConfReq id=0x3 <addr 0.0.0.0>
<ms-dns1 10.11.12.13> <ms-dns3 10.11.12.14>]
Fri Dec 10 16:02:54 2004 : sent [IPCP ConfReq id=0x3 <addr 0.0.0.0>
<ms-dns1 10.11.12.13> <ms-dns3 10.11.12.14>]
Fri Dec 10 16:02:57 2004 : sent [IPCP ConfReq id=0x3 <addr 0.0.0.0>
<ms-dns1 10.11.12.13> <ms-dns3 10.11.12.14>]
Fri Dec 10 16:03:00 2004 : sent [IPCP ConfReq id=0x3 <addr 0.0.0.0>
<ms-dns1 10.11.12.13> <ms-dns3 10.11.12.14>]
Fri Dec 10 16:03:03 2004 : sent [IPCP ConfReq id=0x3 <addr 0.0.0.0>
<ms-dns1 10.11.12.13> <ms-dns3 10.11.12.14>]
Fri Dec 10 16:03:06 2004 : sent [IPCP ConfReq id=0x3 <addr 0.0.0.0>
<ms-dns1 10.11.12.13> <ms-dns3 10.11.12.14>]
Fri Dec 10 16:03:09 2004 : IPCP: timeout sending Config-Requests
Fri Dec 10 16:03:09 2004 : sent [LCP TermReq id=0x2 "No network
protocols running"]
Fri Dec 10 16:03:12 2004 : sent [LCP TermReq id=0x3 "No network
protocols running"]
Fri Dec 10 16:03:15 2004 : Connection terminated.


In particular I don't understand why server sends ConfNak here:
Fri Dec 10 16:02:39 2004 : rcvd [IPCP ConfNak id=0x2 <ms-dns1
10.11.12.13> <ms-dns3 10.11.12.14>]
Isn't it the same request the client sends?!
Thanks in advance,

Ksenia.

[ Post a follow-up to this message ]

"ksenia.marasanova@gmail.com" <ksenia.marasanova@gmail.com> writes:
> Fri Dec 10 16:02:36 2004 : sent [LCP ConfReq id=0x1 <asyncmap 0x0>
> <magic 0x81a9494> <pcomp> <accomp>]
> Fri Dec 10 16:02:36 2004 : rcvd [LCP ConfReq id=0x9 <asyncmap 0x0>
> <auth chap MD5> <magic 0x49c13f4> <pcomp> <accomp>]
> Fri Dec 10 16:02:36 2004 : lcp_reqci: returning CONFACK.

It looks like this instance of pppd was compiled with "DEBUG"
enabled.  You probably don't want to do that.

> Fri Dec 10 16:02:39 2004 : sent [IPCP ConfReq id=0x3 <addr 0.0.0.0>
> <ms-dns1 10.11.12.13> <ms-dns3 10.11.12.14>]
> Fri Dec 10 16:02:42 2004 : sent [IPCP ConfReq id=0x3 <addr 0.0.0.0>
> <ms-dns1 10.11.12.13> <ms-dns3 10.11.12.14>]

It looks like the peer is just plain broken.  It should respond with
Configure-Nak (providing an address), Configure-Reject (to say that it
doesn't know how to negotiate the IP-Address option), or LCP
Protocol-Reject (to shut down IP).  It isn't responding at all.

Possible issues at this point include:

- Control-character transparency or flow control
misconfiguration.  Are you sure that the set-up of this UMTS
device is correct for use with PPP?

- Weird implementation artifacts in peer.  Some cellular
services seem to _lie_ to the user during authentication,
and perform the link authentication only when IPCP is
negotiated.  They exhibit failures like you're seeing when
the shared secret ("password") is wrong or the username is
incorrect.

- Just plain broken peer; find another.

> In particular I don't understand why server sends ConfNak here:
> Fri Dec 10 16:02:39 2004 : rcvd [IPCP ConfNak id=0x2 <ms-dns1
> 10.11.12.13> <ms-dns3 10.11.12.14>]
> Isn't it the same request the client sends?!

Yes.  It's clearly a bug-ridden implementation of PPP.

--
James Carlson, IP Systems Group                <james.d.carlson@sun.com>
Sun Microsystems / 1 Network Drive         71.234W   Vox +1 781 442 2084
MS UBUR02-212 / Burlington MA 01803-2757   42.497N   Fax +1 781 442 1677

[ Post a follow-up to this message ]