Package: ppp Version: 2.4.5-4 Severity: normal File: /usr/sbin/pppd I am using pppd to connect via a Nokia E70 phone. This has worked well until recently, where "recently" means it didn't work yesterday, and was definitely working a month or two ago when I last used it. I haven't altered any relevant configuration files between when it was working and now.
The command used to invoke it is: pppd call gprs-optus.peer When /etc/ppp/peers/gprs-optus.peer contains this: asyncmap 0 noauth /dev/ttyACM0 115200 connect "/usr/sbin/chat -v -T internet -f /etc/ppp/peers/gprs.chat" debug crtscts noipdefault defaultroute usepeerdns updetach The connection is made successfully, but then is terminated immediately. Here is the contents of /var/log/syslog: Sep 30 10:32:28 russell-laptop pppd[11019]: pppd 2.4.5 started by rstuart, uid 0 Sep 30 10:32:29 russell-laptop chat[11020]: send (ATZ^M) Sep 30 10:32:29 russell-laptop chat[11020]: expect (OK) Sep 30 10:32:29 russell-laptop chat[11020]: ATZ^M^M Sep 30 10:32:29 russell-laptop chat[11020]: OK Sep 30 10:32:29 russell-laptop chat[11020]: -- got it Sep 30 10:32:29 russell-laptop chat[11020]: send (AT+CGDCONT=1,"IP","internet"^M) Sep 30 10:32:29 russell-laptop chat[11020]: expect (OK) Sep 30 10:32:29 russell-laptop chat[11020]: ^M Sep 30 10:32:29 russell-laptop chat[11020]: AT+CGDCONT=1,"IP","internet"^M^M Sep 30 10:32:29 russell-laptop chat[11020]: OK Sep 30 10:32:29 russell-laptop chat[11020]: -- got it Sep 30 10:32:29 russell-laptop chat[11020]: send (ATD*99***1#^M) Sep 30 10:32:29 russell-laptop chat[11020]: expect (CONNECT) Sep 30 10:32:29 russell-laptop chat[11020]: ^M Sep 30 10:32:34 russell-laptop chat[11020]: ATD*99***1#^M^M Sep 30 10:32:34 russell-laptop chat[11020]: CONNECT Sep 30 10:32:34 russell-laptop chat[11020]: -- got it Sep 30 10:32:34 russell-laptop pppd[11019]: Script /usr/sbin/chat -v -T internet -f /etc/ppp/peers/gprs.chat finished (pid 11020), status = 0x0 Sep 30 10:32:34 russell-laptop pppd[11019]: Serial connection established. Sep 30 10:32:34 russell-laptop pppd[11019]: using channel 2 Sep 30 10:32:34 russell-laptop pppd[11019]: Using interface ppp0 Sep 30 10:32:34 russell-laptop pppd[11019]: Connect: ppp0 <--> /dev/ttyACM0 Sep 30 10:32:34 russell-laptop pppd[11019]: rcvd [LCP ConfReq id=0x0 <auth pap> <mru 1500> <asyncmap 0xa0000>] Sep 30 10:32:34 russell-laptop pppd[11019]: sent [LCP ConfReq id=0x1 <asyncmap 0x0> <magic 0xcfd0a8b2> <pcomp> <accomp>] Sep 30 10:32:34 russell-laptop pppd[11019]: sent [LCP ConfAck id=0x0 <auth pap> <mru 1500> <asyncmap 0xa0000>] Sep 30 10:32:34 russell-laptop pppd[11019]: rcvd [LCP ConfRej id=0x1 <magic 0xcfd0a8b2> <pcomp> <accomp>] Sep 30 10:32:34 russell-laptop pppd[11019]: sent [LCP ConfReq id=0x2 <asyncmap 0x0>] Sep 30 10:32:34 russell-laptop pppd[11019]: rcvd [LCP ConfAck id=0x2 <asyncmap 0x0>] Sep 30 10:32:34 russell-laptop pppd[11019]: sent [LCP EchoReq id=0x0 magic=0x0] Sep 30 10:32:34 russell-laptop pppd[11019]: sent [PAP AuthReq id=0x1 user="russell-laptop" password=<hidden>] Sep 30 10:32:34 russell-laptop pppd[11019]: rcvd [LCP EchoRep id=0x0 magic=0x0] Sep 30 10:32:34 russell-laptop pppd[11019]: rcvd [PAP AuthAck id=0x1 ""] Sep 30 10:32:34 russell-laptop pppd[11019]: PAP authentication succeeded Sep 30 10:32:34 russell-laptop pppd[11019]: sent [CCP ConfReq id=0x1 <deflate 15> <deflate(old#) 15> <bsd v1 15>] Sep 30 10:32:34 russell-laptop pppd[11019]: sent [IPCP ConfReq id=0x1 <compress VJ 0f 01> <addr 0.0.0.0> <ms-dns1 0.0.0.0> <ms-dns2 0.0.0.0>] Sep 30 10:32:34 russell-laptop pppd[11019]: rcvd [IPCP ConfReq id=0x0 <addr 10.6.6.6>] Sep 30 10:32:34 russell-laptop pppd[11019]: sent [IPCP ConfAck id=0x0 <addr 10.6.6.6>] Sep 30 10:32:34 russell-laptop pppd[11019]: rcvd [LCP ProtRej id=0x0 80 fd 01 01 00 0f 1a 04 78 00 18 04 78 00 15 03 2f] Sep 30 10:32:34 russell-laptop pppd[11019]: Protocol-Reject for 'Compression Control Protocol' (0x80fd) received Sep 30 10:32:35 russell-laptop pppd[11019]: rcvd [IPCP ConfRej id=0x1 <compress VJ 0f 01>] Sep 30 10:32:35 russell-laptop pppd[11019]: sent [IPCP ConfReq id=0x2 <addr 0.0.0.0> <ms-dns1 0.0.0.0> <ms-dns2 0.0.0.0>] Sep 30 10:32:35 russell-laptop pppd[11019]: rcvd [IPCP ConfNak id=0x2 <addr 122.110.246.41> <ms-dns1 211.29.132.12> <ms-dns2 61.88.88.88>] Sep 30 10:32:35 russell-laptop pppd[11019]: sent [IPCP ConfReq id=0x3 <addr 122.110.246.41> <ms-dns1 211.29.132.12> <ms-dns2 61.88.88.88>] Sep 30 10:32:35 russell-laptop pppd[11019]: rcvd [IPCP ConfAck id=0x3 <addr 122.110.246.41> <ms-dns1 211.29.132.12> <ms-dns2 61.88.88.88>] Sep 30 10:32:35 russell-laptop pppd[11019]: not replacing existing default route via 10.7.0.2 Sep 30 10:32:35 russell-laptop pppd[11019]: local IP address 122.110.246.41 Sep 30 10:32:35 russell-laptop pppd[11019]: remote IP address 10.6.6.6 Sep 30 10:32:35 russell-laptop pppd[11019]: primary DNS address 211.29.132.12 Sep 30 10:32:35 russell-laptop pppd[11019]: secondary DNS address 61.88.88.88 Sep 30 10:32:35 russell-laptop pppd[11029]: Script /etc/ppp/ip-up started (pid 11030) Sep 30 10:32:35 russell-laptop pppd[11029]: Modem hangup Sep 30 10:32:35 russell-laptop pppd[11029]: Connect time 0.0 minutes. Sep 30 10:32:35 russell-laptop pppd[11029]: Sent 0 bytes, received 0 bytes. Sep 30 10:32:35 russell-laptop pppd[11029]: Connection terminated. However if I alter the /etc/ppp/peers/gprs-optus.peer, commenting out the updetach option so it the file now looks like this: asyncmap 0 noauth /dev/ttyACM0 115200 connect "/usr/sbin/chat -v -T internet -f /etc/ppp/peers/gprs.chat" debug crtscts noipdefault defaultroute usepeerdns #updetach Then it all works as it should: Sep 30 10:31:33 russell-laptop pppd[10888]: pppd 2.4.5 started by root, uid 0 Sep 30 10:31:34 russell-laptop chat[10889]: send (ATZ^M) Sep 30 10:31:34 russell-laptop chat[10889]: expect (OK) Sep 30 10:31:34 russell-laptop chat[10889]: ATZ^M^M Sep 30 10:31:34 russell-laptop chat[10889]: OK Sep 30 10:31:34 russell-laptop chat[10889]: -- got it Sep 30 10:31:34 russell-laptop chat[10889]: send (AT+CGDCONT=1,"IP","internet"^M) Sep 30 10:31:34 russell-laptop chat[10889]: expect (OK) Sep 30 10:31:34 russell-laptop chat[10889]: ^M Sep 30 10:31:34 russell-laptop chat[10889]: AT+CGDCONT=1,"IP","internet"^M^M Sep 30 10:31:34 russell-laptop chat[10889]: OK Sep 30 10:31:34 russell-laptop chat[10889]: -- got it Sep 30 10:31:34 russell-laptop chat[10889]: send (ATD*99***1#^M) Sep 30 10:31:34 russell-laptop chat[10889]: expect (CONNECT) Sep 30 10:31:34 russell-laptop chat[10889]: ^M Sep 30 10:31:36 russell-laptop dhclient: DHCPDISCOVER on eth0 to 255.255.255.255 port 67 interval 14 Sep 30 10:31:38 russell-laptop chat[10889]: ATD*99***1#^M^M Sep 30 10:31:38 russell-laptop chat[10889]: CONNECT Sep 30 10:31:38 russell-laptop chat[10889]: -- got it Sep 30 10:31:38 russell-laptop pppd[10888]: Script /usr/sbin/chat -v -T internet -f /etc/ppp/peers/gprs.chat finished (pid 10889), status = 0x0 Sep 30 10:31:38 russell-laptop pppd[10888]: Serial connection established. Sep 30 10:31:38 russell-laptop pppd[10888]: using channel 1 Sep 30 10:31:38 russell-laptop pppd[10888]: Using interface ppp0 Sep 30 10:31:38 russell-laptop pppd[10888]: Connect: ppp0 <--> /dev/ttyACM0 Sep 30 10:31:38 russell-laptop pppd[10888]: rcvd [LCP ConfReq id=0x0 <auth pap> <mru 1500> <asyncmap 0xa0000>] Sep 30 10:31:38 russell-laptop pppd[10888]: sent [LCP ConfReq id=0x1 <asyncmap 0x0> <magic 0x4db9078f> <pcomp> <accomp>] Sep 30 10:31:38 russell-laptop pppd[10888]: sent [LCP ConfAck id=0x0 <auth pap> <mru 1500> <asyncmap 0xa0000>] Sep 30 10:31:38 russell-laptop pppd[10888]: rcvd [LCP ConfRej id=0x1 <magic 0x4db9078f> <pcomp> <accomp>] Sep 30 10:31:38 russell-laptop pppd[10888]: sent [LCP ConfReq id=0x2 <asyncmap 0x0>] Sep 30 10:31:38 russell-laptop pppd[10888]: rcvd [LCP ConfAck id=0x2 <asyncmap 0x0>] Sep 30 10:31:38 russell-laptop pppd[10888]: sent [LCP EchoReq id=0x0 magic=0x0] Sep 30 10:31:38 russell-laptop pppd[10888]: sent [PAP AuthReq id=0x1 user="russell-laptop" password=<hidden>] Sep 30 10:31:38 russell-laptop pppd[10888]: rcvd [LCP EchoRep id=0x0 magic=0x0] Sep 30 10:31:38 russell-laptop pppd[10888]: rcvd [PAP AuthAck id=0x1 ""] Sep 30 10:31:38 russell-laptop pppd[10888]: PAP authentication succeeded Sep 30 10:31:38 russell-laptop kernel: [ 5966.707967] PPP BSD Compression module registered Sep 30 10:31:38 russell-laptop pppd[10888]: sent [CCP ConfReq id=0x1 <deflate 15> <deflate(old#) 15> <bsd v1 15>] Sep 30 10:31:38 russell-laptop pppd[10888]: sent [IPCP ConfReq id=0x1 <compress VJ 0f 01> <addr 0.0.0.0> <ms-dns1 0.0.0.0> <ms-dns2 0.0.0.0>] Sep 30 10:31:38 russell-laptop pppd[10888]: rcvd [IPCP ConfReq id=0x0 <addr 10.6.6.6>] Sep 30 10:31:38 russell-laptop pppd[10888]: sent [IPCP ConfAck id=0x0 <addr 10.6.6.6>] Sep 30 10:31:38 russell-laptop kernel: [ 5966.735317] PPP Deflate Compression module registered Sep 30 10:31:38 russell-laptop pppd[10888]: rcvd [LCP ProtRej id=0x0 80 fd 01 01 00 0f 1a 04 78 00 18 04 78 00 15 03 2f] Sep 30 10:31:38 russell-laptop pppd[10888]: Protocol-Reject for 'Compression Control Protocol' (0x80fd) received Sep 30 10:31:39 russell-laptop pppd[10888]: rcvd [IPCP ConfRej id=0x1 <compress VJ 0f 01>] Sep 30 10:31:39 russell-laptop pppd[10888]: sent [IPCP ConfReq id=0x2 <addr 0.0.0.0> <ms-dns1 0.0.0.0> <ms-dns2 0.0.0.0>] Sep 30 10:31:39 russell-laptop pppd[10888]: rcvd [IPCP ConfNak id=0x2 <addr 122.110.100.198> <ms-dns1 211.29.132.12> <ms-dns2 61.88.88.88>] Sep 30 10:31:39 russell-laptop pppd[10888]: sent [IPCP ConfReq id=0x3 <addr 122.110.100.198> <ms-dns1 211.29.132.12> <ms-dns2 61.88.88.88>] Sep 30 10:31:39 russell-laptop pppd[10888]: rcvd [IPCP ConfAck id=0x3 <addr 122.110.100.198> <ms-dns1 211.29.132.12> <ms-dns2 61.88.88.88>] Sep 30 10:31:39 russell-laptop pppd[10888]: not replacing existing default route via 10.7.0.2 Sep 30 10:31:39 russell-laptop pppd[10888]: local IP address 122.110.100.198 Sep 30 10:31:39 russell-laptop pppd[10888]: remote IP address 10.6.6.6 Sep 30 10:31:39 russell-laptop pppd[10888]: primary DNS address 211.29.132.12 Sep 30 10:31:39 russell-laptop pppd[10888]: secondary DNS address 61.88.88.88 Sep 30 10:31:39 russell-laptop pppd[10888]: Script /etc/ppp/ip-up started (pid 10904) Sep 30 10:31:40 russell-laptop named[1976]: received control channel command 'reconfig' Sep 30 10:31:40 russell-laptop named[1976]: loading configuration from '/etc/bind/named.conf' Sep 30 10:31:40 russell-laptop named[1976]: reading built-in trusted keys from file '/etc/bind/bind.keys' Sep 30 10:31:40 russell-laptop named[1976]: using default UDP/IPv4 port range: [1024, 65535] Sep 30 10:31:40 russell-laptop named[1976]: using default UDP/IPv6 port range: [1024, 65535] Sep 30 10:31:40 russell-laptop named[1976]: listening on IPv4 interface ppp0, 122.110.100.198#53 Sep 30 10:31:40 russell-laptop named[1976]: set up managed keys zone for view _default, file 'managed-keys.bind' Sep 30 10:31:40 russell-laptop named[1976]: reloading configuration succeeded Sep 30 10:31:40 russell-laptop named[1976]: any newly configured zones are now loaded Sep 30 10:31:41 russell-laptop pppd[10888]: Script /etc/ppp/ip-up finished (pid 10904), status = 0x0 I have not modified /etc/ppp/options, /etc/ppp/ip-up or anything under /etc/ppp/ip-up.d. -- System Information: Debian Release: squeeze/sid APT prefers testing APT policy: (500, 'testing') Architecture: amd64 (x86_64) Kernel: Linux 2.6.32-5-amd64 (SMP w/2 CPU cores) Locale: LANG=en_AU.UTF-8, LC_CTYPE=en_AU.UTF-8 (charmap=UTF-8) Shell: /bin/sh linked to /bin/bash Versions of packages ppp depends on: ii libc6 2.11.2-6 Embedded GNU C Library: Shared lib ii libpam-modules 1.1.1-6 Pluggable Authentication Modules f ii libpam-runtime 1.1.1-6 Runtime support for the PAM librar ii libpam0g 1.1.1-6 Pluggable Authentication Modules l ii libpcap0.8 1.1.1-2 system interface for user-level pa ii procps 1:3.2.8-9 /proc file system utilities ppp recommends no packages. ppp suggests no packages. -- Configuration Files: /etc/chatscripts/gprs [Errno 13] Permission denied: u'/etc/chatscripts/gprs' /etc/chatscripts/pap [Errno 13] Permission denied: u'/etc/chatscripts/pap' -- no debconf information -- To UNSUBSCRIBE, email to debian-bugs-dist-requ...@lists.debian.org with a subject of "unsubscribe". Trouble? Contact listmas...@lists.debian.org
