Package: ppp Version: 2.4.4rel-9 Severity: normal I am connecting to the Swedish mobile ISP Tele2 with PPP using a Huawei 3G modem. The PPP link is started, but about half the time or more it ends up assigning bogus DNS addresses 10.11.12.13 and 10.11.12.14 (which cannot in fact be reached). The remaining sessions give me name servers with public IPs that do work.
Log of failure case: Oct 2 08:08:57 melech pppd[11052]: Serial connection established. Oct 2 08:08:57 melech pppd[11052]: using channel 3 Oct 2 08:08:57 melech pppd[11052]: Using interface ppp0 Oct 2 08:08:57 melech pppd[11052]: Connect: ppp0 <--> /dev/3gmodem Oct 2 08:08:58 melech pppd[11052]: sent [LCP ConfReq id=0x1 <asyncmap 0x0> <magic 0x260e3aa4> <pcomp> <accomp>] Oct 2 08:08:58 melech pppd[11052]: rcvd [LCP ConfReq id=0x0 <asyncmap 0x0> <auth chap MD5> <magic 0x4fafaf6> <pcomp> <accomp>] Oct 2 08:08:58 melech pppd[11052]: sent [LCP ConfNak id=0x0 <auth pap>] Oct 2 08:08:58 melech pppd[11052]: rcvd [LCP ConfAck id=0x1 <asyncmap 0x0> <magic 0x260e3aa4> <pcomp> <accomp>] Oct 2 08:08:58 melech pppd[11052]: rcvd [LCP ConfReq id=0x1 <asyncmap 0x0> <auth pap> <magic 0x4fafaf6> <pcomp> <accomp>] Oct 2 08:08:58 melech pppd[11052]: sent [LCP ConfAck id=0x1 <asyncmap 0x0> <auth pap> <magic 0x4fafaf6> <pcomp> <accomp>] Oct 2 08:08:58 melech pppd[11052]: sent [PAP AuthReq id=0x1 user="melech" password=<hidden>] Oct 2 08:08:58 melech pppd[11052]: rcvd [LCP DiscReq id=0x2 magic=0x4fafaf6] Oct 2 08:08:58 melech pppd[11052]: rcvd [PAP AuthAck id=0x1 ""] Oct 2 08:08:58 melech pppd[11052]: PAP authentication succeeded Oct 2 08:08:58 melech pppd[11052]: sent [IPCP ConfReq id=0x1 <addr 0.0.0.0> <ms-dns1 0.0.0.0> <ms-dns3 0.0.0.0>] Oct 2 08:08:59 melech pppd[11052]: rcvd [IPCP ConfNak id=0x1 <ms-dns1 10.11.12.13> <ms-dns3 10.11.12.14> <ms-wins 10.11.12.13> <ms-wins 10.11.12.14>] Oct 2 08:08:59 melech pppd[11052]: sent [IPCP ConfReq id=0x2 <addr 0.0.0.0> <ms-dns1 10.11.12.13> <ms-dns3 10.11.12.14>] Oct 2 08:09:00 melech pppd[11052]: rcvd [IPCP ConfNak id=0x2 <ms-dns1 10.11.12.13> <ms-dns3 10.11.12.14> <ms-wins 10.11.12.13> <ms-wins 10.11.12.14>] Oct 2 08:09:00 melech pppd[11052]: sent [IPCP ConfReq id=0x3 <addr 0.0.0.0> <ms-dns1 10.11.12.13> <ms-dns3 10.11.12.14>] Oct 2 08:09:01 melech pppd[11052]: rcvd [IPCP ConfNak id=0x3 <ms-dns1 10.11.12.13> <ms-dns3 10.11.12.14> <ms-wins 10.11.12.13> <ms-wins 10.11.12.14>] Oct 2 08:09:01 melech pppd[11052]: sent [IPCP ConfReq id=0x4 <addr 0.0.0.0> <ms-dns1 10.11.12.13> <ms-dns3 10.11.12.14>] Oct 2 08:09:02 melech pppd[11052]: rcvd [IPCP ConfNak id=0x4 <ms-dns1 10.11.12.13> <ms-dns3 10.11.12.14> <ms-wins 10.11.12.13> <ms-wins 10.11.12.14>] Oct 2 08:09:02 melech pppd[11052]: sent [IPCP ConfReq id=0x5 <addr 0.0.0.0> <ms-dns1 10.11.12.13> <ms-dns3 10.11.12.14>] Oct 2 08:09:03 melech pppd[11052]: rcvd [IPCP ConfNak id=0x5 <ms-dns1 10.11.12.13> <ms-dns3 10.11.12.14> <ms-wins 10.11.12.13> <ms-wins 10.11.12.14>] Oct 2 08:09:03 melech pppd[11052]: sent [IPCP ConfReq id=0x6 <addr 0.0.0.0>] Oct 2 08:09:03 melech pppd[11052]: rcvd [IPCP ConfReq id=0x0] Oct 2 08:09:03 melech pppd[11052]: sent [IPCP ConfNak id=0x0 <addr 0.0.0.0>] Oct 2 08:09:03 melech pppd[11052]: rcvd [IPCP ConfNak id=0x6 <addr 83.188.169.123>] Oct 2 08:09:03 melech pppd[11052]: sent [IPCP ConfReq id=0x7] Oct 2 08:09:03 melech pppd[11052]: rcvd [IPCP ConfNak id=0x7 <addr 83.188.169.123>] Oct 2 08:09:03 melech pppd[11052]: sent [IPCP ConfReq id=0x8 <addr 83.188.169.123>] Oct 2 08:09:03 melech pppd[11052]: rcvd [IPCP ConfAck id=0x8 <addr 83.188.169.123>] Oct 2 08:09:04 melech pppd[11052]: rcvd [IPCP ConfReq id=0x1] Oct 2 08:09:04 melech pppd[11052]: sent [IPCP ConfAck id=0x1] Oct 2 08:09:04 melech pppd[11052]: Could not determine remote IP address: defaulting to 10.64.64.64 Oct 2 08:09:04 melech pppd[11052]: Cannot determine ethernet address for proxy ARP Oct 2 08:09:04 melech pppd[11052]: local IP address 83.188.169.123 Oct 2 08:09:04 melech pppd[11052]: remote IP address 10.64.64.64 Oct 2 08:09:04 melech pppd[11052]: primary DNS address 10.11.12.13 Oct 2 08:09:04 melech pppd[11052]: secondary DNS address 10.11.12.14 Oct 2 08:09:04 melech pppd[11052]: Script /etc/ppp/ip-up started (pid 11065) Log of success case: Oct 2 08:12:00 melech pppd[11589]: Serial connection established. Oct 2 08:12:00 melech pppd[11589]: using channel 4 Oct 2 08:12:00 melech pppd[11589]: Using interface ppp0 Oct 2 08:12:00 melech pppd[11589]: Connect: ppp0 <--> /dev/3gmodem Oct 2 08:12:01 melech pppd[11589]: sent [LCP ConfReq id=0x1 <asyncmap 0x0> <magic 0x8d65e535> <pcomp> <accomp>] Oct 2 08:12:01 melech pppd[11589]: rcvd [LCP ConfReq id=0x3 <asyncmap 0x0> <auth chap MD5> <magic 0x4fdc5be> <pcomp> <accomp>] Oct 2 08:12:01 melech pppd[11589]: sent [LCP ConfNak id=0x3 <auth pap>] Oct 2 08:12:01 melech pppd[11589]: rcvd [LCP ConfAck id=0x1 <asyncmap 0x0> <magic 0x8d65e535> <pcomp> <accomp>] Oct 2 08:12:01 melech pppd[11589]: rcvd [LCP ConfReq id=0x4 <asyncmap 0x0> <auth pap> <magic 0x4fdc5be> <pcomp> <accomp>] Oct 2 08:12:01 melech pppd[11589]: sent [LCP ConfAck id=0x4 <asyncmap 0x0> <auth pap> <magic 0x4fdc5be> <pcomp> <accomp>] Oct 2 08:12:01 melech pppd[11589]: sent [PAP AuthReq id=0x1 user="melech" password=<hidden>] Oct 2 08:12:01 melech pppd[11589]: rcvd [LCP DiscReq id=0x5 magic=0x4fdc5be] Oct 2 08:12:01 melech pppd[11589]: rcvd [PAP AuthAck id=0x1 ""] Oct 2 08:12:01 melech pppd[11589]: PAP authentication succeeded Oct 2 08:12:01 melech pppd[11589]: sent [IPCP ConfReq id=0x1 <addr 0.0.0.0> <ms-dns1 0.0.0.0> <ms-dns3 0.0.0.0>] Oct 2 08:12:02 melech pppd[11589]: rcvd [IPCP ConfNak id=0x1 <ms-dns1 10.11.12.13> <ms-dns3 10.11.12.14> <ms-wins 10.11.12.13> <ms-wins 10.11.12.14>] Oct 2 08:12:02 melech pppd[11589]: sent [IPCP ConfReq id=0x2 <addr 0.0.0.0> <ms-dns1 10.11.12.13> <ms-dns3 10.11.12.14>] Oct 2 08:12:03 melech pppd[11589]: rcvd [IPCP ConfNak id=0x2 <ms-dns1 10.11.12.13> <ms-dns3 10.11.12.14> <ms-wins 10.11.12.13> <ms-wins 10.11.12.14>] Oct 2 08:12:03 melech pppd[11589]: sent [IPCP ConfReq id=0x3 <addr 0.0.0.0> <ms-dns1 10.11.12.13> <ms-dns3 10.11.12.14>] Oct 2 08:12:04 melech pppd[11589]: rcvd [IPCP ConfReq id=0x2] Oct 2 08:12:04 melech pppd[11589]: sent [IPCP ConfNak id=0x2 <addr 0.0.0.0>] Oct 2 08:12:04 melech pppd[11589]: rcvd [IPCP ConfNak id=0x3 <addr 83.188.169.214> <ms-dns1 130.244.127.161> <ms-dns3 130.244.127.169>] Oct 2 08:12:04 melech pppd[11589]: sent [IPCP ConfReq id=0x4 <addr 83.188.169.214> <ms-dns1 130.244.127.161> <ms-dns3 130.244.127.169>] Oct 2 08:12:04 melech pppd[11589]: rcvd [IPCP ConfAck id=0x4 <addr 83.188.169.214> <ms-dns1 130.244.127.161> <ms-dns3 130.244.127.169>] Oct 2 08:12:05 melech pppd[11589]: rcvd [IPCP ConfReq id=0x3] Oct 2 08:12:05 melech pppd[11589]: sent [IPCP ConfAck id=0x3] Oct 2 08:12:05 melech pppd[11589]: Could not determine remote IP address: defaulting to 10.64.64.64 Oct 2 08:12:05 melech pppd[11589]: Cannot determine ethernet address for proxy ARP Oct 2 08:12:05 melech pppd[11589]: local IP address 83.188.169.214 Oct 2 08:12:05 melech pppd[11589]: remote IP address 10.64.64.64 Oct 2 08:12:05 melech pppd[11589]: primary DNS address 130.244.127.161 Oct 2 08:12:05 melech pppd[11589]: secondary DNS address 130.244.127.169 /etc/ppp/peers/tele2-3g: ----------------------------------- 3gmodem 921600 connect '/usr/sbin/chat -v -f /etc/ppp/tele2-3g.chat' noipdefault novj noccp noauth local defaultroute usepeerdns debug ---------------------------------- /etc/ppp/tele2-3g.chat: ---------------------------------- TIMEOUT 5 ABORT "NO CARRIER" ABORT "NO DIALTONE" ABORT "NO ANSWER" ABORT "BUSY" "" ATZ OK AT+CPIN=1729 TIMEOUT 2 OK-AT-OK AT+CGDCONT=1,"IP","internet.tele2.se" TIMEOUT 5 OK ATDT*99***1# CONNECT ---------------------------------- /etc/ppp/options is the default, with the following appended: ------------- deflate 12 bsdcomp 12 predictor1 ------------- By checking logs from a number of attempts, I have found the following pattern: pppd on my side sends up to five IPCP ConfReqs, the first with blank info and the remaining like this: Oct 2 08:08:59 melech pppd[11052]: sent [IPCP ConfReq id=0x2 <addr 0.0.0.0> <ms-dns1 10.11.12.13> <ms-dns3 10.11.12.14>] If it receives a ConfReq from the other side before that point, everything works as in the success case. If not, then pppd starts sending a different ConfReq from id=0x6: Oct 2 08:09:03 melech pppd[11052]: sent [IPCP ConfReq id=0x6 <addr 0.0.0.0>] Note that the ms-dns1 and ms-dns3 parameters are missing. That makes the other end send only an IP address at the end: Oct 2 08:09:03 melech pppd[11052]: rcvd [IPCP ConfAck id=0x8 <addr 83.188.169.123>] In the success case, it sends both IP address and DNS settings: Oct 2 08:12:04 melech pppd[11589]: rcvd [IPCP ConfAck id=0x4 <addr 83.188.169.214> <ms-dns1 130.244.127.161> <ms-dns3 130.244.127.169>] I don't know enough about the protocol to understand why pppd changes the ConfReqs. Also, I'm not sure why there are so many of these ConfNak messages. Perhaps the server is not happy that pppd ignores the ms-wins parameters? (It's quite possible that the other end is misbehaving. I'll try to contact the ISP, but suspect they won't be very helpful.) -- System Information: Debian Release: lenny/sid APT prefers testing APT policy: (990, 'testing'), (500, 'unstable'), (500, 'stable'), (1, 'experimental') Architecture: amd64 (x86_64) Kernel: Linux 2.6.23-rc8-melech (SMP w/2 CPU cores; PREEMPT) Locale: LANG=sv_SE.UTF-8, LC_CTYPE=sv_SE.UTF-8 (charmap=UTF-8) Shell: /bin/sh linked to /bin/bash Versions of packages ppp depends on: ii libc6 2.6.1-1 GNU C Library: Shared libraries ii libpam-modules 0.99.7.1-4 Pluggable Authentication Modules f ii libpam-runtime 0.99.7.1-4 Runtime support for the PAM librar ii libpam0g 0.99.7.1-4 Pluggable Authentication Modules l ii libpcap0.8 0.9.7-1 System interface for user-level pa ii netbase 4.30 Basic TCP/IP networking system ii procps 1:3.2.7-4.1 /proc file system utilities ppp recommends no packages. -- no debconf information -- To UNSUBSCRIBE, email to [EMAIL PROTECTED] with a subject of "unsubscribe". Trouble? Contact [EMAIL PROTECTED]
