2006 / february / 05
Workaround for 'LCP ConfReq errors' in mac dialup networking
It seems that there is a nasty error in the PPP implementation that Mac OS X uses, as well as some other BSD-derived operating systems. There are some accounts on the web, but the info was hard to find when I was trying to fix it, so I'm posting it here for google and the greater good.
The problem
With broadband at home and wifi everywhere, I've never had to use the internal modem of my ibook until I was in Italy in december, and I had great trouble in getting it to work. It should have been so simple. There was an old machine running win95, with a working phone line and account. Plugging the phone line into my mac and using PPP with similar settings resulted in an audible modem handshake, but after that, it failed. The PPP error log (pasted in full below) had these lines which seemed to indicate the software PPP handshake was where it was going wrong:
sent [LCP ConfReq id=0x1] LCP: timeout sending Config-Requests
The workaround
In the end, I found a workaround after much googling - there are many phrases in the error log that pop up in various packages, so it wasn't easy. A big hand to Lonesome Amoeba where I finally found a good description, and a workaround.
Fixed; sort of
In short: turn 'Connect using a terminal window' on. This leads to an empty dialog with a single 'ok' button when the modems have done their hardware handshake, but PPP is yet to begin. After clicking ok, the dialog disappears, and PPP succesfully connects. Weird, very un-maclike, but at least it gave me connectivity up there in the mountains.
(and here I am, typing this comfortably on broadband at home, and my providers monthly email has just come in - they've doubled the speed, again. 8Mb/2Mb now. Dialup is beginning to look really, really old.)
The full PPP error log
As promised, the log in full. First, two unsuccesfull tries.
Thu Dec 29 14:50:42 2005 : Version 2.0 Thu Dec 29 14:50:42 2005 : CCLWrite : AT\13 Thu Dec 29 14:50:42 2005 : CCLMatched : OK\13\10 Thu Dec 29 14:50:42 2005 : CCLWrite : ATI4\13 Thu Dec 29 14:50:42 2005 : CCLMatched : Apple Internal Modem\13\10 Thu Dec 29 14:50:42 2005 : CCLWrite : ATI12\13 Thu Dec 29 14:50:42 2005 : CCLMatched : D1\13\10 Thu Dec 29 14:50:42 2005 : CCLWrite : AT&F92L3E0S95=45&D2S7=75W2\13 Thu Dec 29 14:50:42 2005 : CCLMatched : OK\13\10 Thu Dec 29 14:50:42 2005 : Dialing 7020005017 Thu Dec 29 14:50:42 2005 : CCLWrite : ATDT7020005017\13 Thu Dec 29 14:51:12 2005 : CCLMatched : +ER: Thu Dec 29 14:51:12 2005 : CCLMatched : LAPM Thu Dec 29 14:51:12 2005 : Modem Reliable Link Established. Thu Dec 29 14:51:12 2005 : CCLMatched : +DR: Thu Dec 29 14:51:12 2005 : CCLMatched : V42B\13 Thu Dec 29 14:51:12 2005 : Modem Compression Established. Thu Dec 29 14:51:12 2005 : CCLMatched : CONNECT Thu Dec 29 14:51:12 2005 : CCLMatched : 48000 Thu Dec 29 14:51:15 2005 : Serial connection established. Thu Dec 29 14:51:15 2005 : using link 0 Thu Dec 29 14:51:15 2005 : Using interface ppp0 Thu Dec 29 14:51:15 2005 : Connect: ppp0 <--> /dev/cu.modem Thu Dec 29 14:51:16 2005 : sent [LCP ConfReq id=0x1] Thu Dec 29 14:51:19 2005 : sent [LCP ConfReq id=0x1 ] Thu Dec 29 14:51:22 2005 : sent [LCP ConfReq id=0x1 ] Thu Dec 29 14:51:25 2005 : sent [LCP ConfReq id=0x1 ] Thu Dec 29 14:51:28 2005 : sent [LCP ConfReq id=0x1 ] Thu Dec 29 14:51:31 2005 : sent [LCP ConfReq id=0x1 ] Thu Dec 29 14:51:34 2005 : sent [LCP ConfReq id=0x1 ] Thu Dec 29 14:51:37 2005 : sent [LCP ConfReq id=0x1 ] Thu Dec 29 14:51:40 2005 : sent [LCP ConfReq id=0x1 ] Thu Dec 29 14:51:43 2005 : sent [LCP ConfReq id=0x1 ] Thu Dec 29 14:51:46 2005 : LCP: timeout sending Config-Requests Thu Dec 29 14:51:46 2005 : Connection terminated. Thu Dec 29 14:51:46 2005 : CCLWrite : +++ Thu Dec 29 14:51:50 2005 : CCLWrite : ATH\13 Thu Dec 29 14:51:53 2005 : CCLWrite : +++ Thu Dec 29 14:51:56 2005 : CCLWrite : ATH\13 Thu Dec 29 14:51:59 2005 : CCLWrite : +++ Thu Dec 29 14:52:02 2005 : CCLWrite : ATH\13 Thu Dec 29 14:52:06 2005 : CCLWrite : AT&F\13 Thu Dec 29 14:52:07 2005 : disconnect script failed
Thu Dec 29 16:32:33 2005 : Version 2.0 Thu Dec 29 16:32:33 2005 : CCLWrite : AT\13 Thu Dec 29 16:32:33 2005 : CCLMatched : OK\13\10 Thu Dec 29 16:32:33 2005 : CCLWrite : ATI4\13 Thu Dec 29 16:32:33 2005 : CCLMatched : Apple Internal Modem\13\10 Thu Dec 29 16:32:34 2005 : CCLWrite : ATI12\13 Thu Dec 29 16:32:34 2005 : CCLMatched : D1\13\10 Thu Dec 29 16:32:34 2005 : CCLWrite : AT&F92L3E0S95=45&D2S7=75W2\13 Thu Dec 29 16:32:34 2005 : CCLMatched : OK\13\10 Thu Dec 29 16:32:34 2005 : Dialing 7020005017 Thu Dec 29 16:32:34 2005 : CCLWrite : ATDT7020005017\13 Thu Dec 29 16:33:03 2005 : CCLMatched : +ER: Thu Dec 29 16:33:03 2005 : CCLMatched : LAPM Thu Dec 29 16:33:03 2005 : Modem Reliable Link Established. Thu Dec 29 16:33:03 2005 : CCLMatched : +DR: Thu Dec 29 16:33:03 2005 : CCLMatched : V42B\13 Thu Dec 29 16:33:03 2005 : Modem Compression Established. Thu Dec 29 16:33:03 2005 : CCLMatched : CONNECT Thu Dec 29 16:33:03 2005 : CCLMatched : 48000 Thu Dec 29 16:33:06 2005 : Serial connection established. Thu Dec 29 16:33:06 2005 : using link 0 Thu Dec 29 16:33:06 2005 : Using interface ppp0 Thu Dec 29 16:33:06 2005 : Connect: ppp0 <--> /dev/cu.modem Thu Dec 29 16:33:07 2005 : sent [LCP ConfReq id=0x1] Thu Dec 29 16:33:10 2005 : sent [LCP ConfReq id=0x1 ] Thu Dec 29 16:33:13 2005 : sent [LCP ConfReq id=0x1 ] Thu Dec 29 16:33:16 2005 : sent [LCP ConfReq id=0x1 ] Thu Dec 29 16:33:19 2005 : sent [LCP ConfReq id=0x1 ] Thu Dec 29 16:33:22 2005 : sent [LCP ConfReq id=0x1 ] Thu Dec 29 16:33:25 2005 : sent [LCP ConfReq id=0x1 ] Thu Dec 29 16:33:28 2005 : sent [LCP ConfReq id=0x1 ] Thu Dec 29 16:33:30 2005 : Hangup (SIGHUP) Thu Dec 29 16:33:30 2005 : Modem hangup Thu Dec 29 16:33:30 2005 : Connection terminated. Thu Dec 29 16:33:43 2005 : Terminating on signal 15.
Finally, the log with the connect dialog fix.
Thu Dec 29 16:33:56 2005 : Version 2.0 Thu Dec 29 16:33:56 2005 : CCLWrite : AT\13 Thu Dec 29 16:33:56 2005 : CCLMatched : OK\13\10 Thu Dec 29 16:33:56 2005 : CCLWrite : ATI4\13 Thu Dec 29 16:33:56 2005 : CCLMatched : Apple Internal Modem\13\10 Thu Dec 29 16:33:56 2005 : CCLWrite : ATI12\13 Thu Dec 29 16:33:56 2005 : CCLMatched : D1\13\10 Thu Dec 29 16:33:56 2005 : CCLWrite : AT&F92L3E0S95=45&D2S7=75W2\13 Thu Dec 29 16:33:57 2005 : CCLMatched : OK\13\10 Thu Dec 29 16:33:57 2005 : Dialing 7020005017 Thu Dec 29 16:33:57 2005 : CCLWrite : ATDT7020005017\13 Thu Dec 29 16:34:26 2005 : CCLMatched : +ER: Thu Dec 29 16:34:26 2005 : CCLMatched : LAPM Thu Dec 29 16:34:26 2005 : Modem Reliable Link Established. Thu Dec 29 16:34:26 2005 : CCLMatched : +DR: Thu Dec 29 16:34:26 2005 : CCLMatched : V42B\13 Thu Dec 29 16:34:26 2005 : Modem Compression Established. Thu Dec 29 16:34:26 2005 : CCLMatched : CONNECT Thu Dec 29 16:34:26 2005 : CCLMatched : 48000 Thu Dec 29 16:34:29 2005 : Serial connection established. Thu Dec 29 16:34:44 2005 : Terminal connection established. Thu Dec 29 16:34:44 2005 : using link 0 Thu Dec 29 16:34:44 2005 : Using interface ppp0 Thu Dec 29 16:34:44 2005 : Connect: ppp0 <--> /dev/cu.modem Thu Dec 29 16:34:46 2005 : sent [LCP ConfReq id=0x1] Thu Dec 29 16:34:46 2005 : rcvd [LCP ConfReq id=0xd ] Thu Dec 29 16:34:46 2005 : lcp_reqci: returning CONFACK. Thu Dec 29 16:34:46 2005 : sent [LCP ConfAck id=0xd ] Thu Dec 29 16:34:46 2005 : rcvd [LCP ConfAck id=0x1 ] Thu Dec 29 16:34:46 2005 : sent [LCP EchoReq id=0x0 magic=0x649e03c6] Thu Dec 29 16:34:46 2005 : sent [PAP AuthReq id=0x1 user="xxx@xxx.net" password= ] Thu Dec 29 16:34:46 2005 : rcvd [LCP EchoRep id=0x0 magic=0xb9a481af] Thu Dec 29 16:34:46 2005 : rcvd [PAP AuthAck id=0x1 ""] Thu Dec 29 16:34:46 2005 : sent [IPCP ConfReq id=0x1 ] Thu Dec 29 16:34:46 2005 : sent [IPV6CP ConfReq id=0x1 ] Thu Dec 29 16:34:46 2005 : rcvd [IPCP ConfReq id=0x1 ] Thu Dec 29 16:34:46 2005 : ipcp: returning Configure-ACK Thu Dec 29 16:34:46 2005 : sent [IPCP ConfAck id=0x1 ] Thu Dec 29 16:34:46 2005 : rcvd [IPCP ConfRej id=0x1 ] Thu Dec 29 16:34:46 2005 : sent [IPCP ConfReq id=0x2 ] Thu Dec 29 16:34:46 2005 : rcvd [LCP ProtRej id=0x1 80 57 01 01 00 0e 01 0a 02 0a 95 ff fe ab fe c8] Thu Dec 29 16:34:46 2005 : rcvd [IPCP ConfNak id=0x2 ] Thu Dec 29 16:34:46 2005 : sent [IPCP ConfReq id=0x3 ] Thu Dec 29 16:34:47 2005 : rcvd [IPCP ConfAck id=0x3 ] Thu Dec 29 16:34:47 2005 : ipcp: up Thu Dec 29 16:34:47 2005 : local IP address 62.94.66.205 Thu Dec 29 16:34:47 2005 : remote IP address 62.94.66.1 Thu Dec 29 16:34:47 2005 : primary DNS address 62.94.0.1 Thu Dec 29 16:34:47 2005 : secondary DNS address 62.94.0.2 Thu Dec 29 16:35:12 2005 : Terminating on signal 15. Thu Dec 29 16:35:12 2005 : ipcp: down Thu Dec 29 16:35:13 2005 : sent [LCP TermReq id=0x2 "User request"] Thu Dec 29 16:35:13 2005 : rcvd [LCP TermAck id=0x2] Thu Dec 29 16:35:13 2005 : Connection terminated. Thu Dec 29 16:35:13 2005 : Connect time 0.5 minutes. Thu Dec 29 16:35:13 2005 : Sent 2523 bytes, received 16669 bytes. Thu Dec 29 16:35:14 2005 : CCLWrite : +++ Thu Dec 29 16:35:15 2005 : CCLMatched : OK\13\10 Thu Dec 29 16:35:15 2005 : CCLWrite : ATH\13 Thu Dec 29 16:35:17 2005 : CCLMatched : NO CARRIER\13\10 Thu Dec 29 16:35:18 2005 : CCLWrite : AT&F\13 Thu Dec 29 16:35:18 2005 : CCLMatched : OK\13\10 Thu Dec 29 16:35:18 2005 : Serial link disconnected.
Reflections on 'My life in Orange' by Tim Guest | main | Amnesty International: breng wapens onder controle!