"IPCP: timeout sending Config-Requests" - vodafone mobile connect 3g card

[Didier Wiroth]

Hi,

I got a vodafone pcmcia mobile connect 3g/gprs datacard today. I tried it on my laptop running 3.9-stable.
Previously, I used a siemens connect 2 air cf card to connect via gprs without problems and I used almost identical pppd scripts.

With the vodafone card (actually from www.OPTION.com) I'm not able to make a gprs connection as I get the following error after +/- 20 seconds:
Apr 4 21:08:14 nc6000 pppd[22150]: pppd 2.3.5 started by didier, uid 0
Apr 4 21:08:18 nc6000 pppd[22150]: Connect: ppp0 <--> /dev/ttyU0
Apr 4 21:08:48 nc6000 pppd[22150]: IPCP: timeout sending Config-Requests
Apr 4 21:08:54 nc6000 pppd[22150]: Connection terminated.

Here is the (partial) output of "usbdevs -v":
Controller /dev/usb4:
addr 1: full speed, self powered, config 1, OHCI root hub(0x0000), Opti(0x1045), rev 1.00
port 1 addr 2: full speed, power 100 mA, config 1, Vodafone Mobile Connect Card - 3G(0x5000), Vodafone(0x0af0), rev 0.01
port 2 powered

Here is my pppd gprs script (this script works with the siemens card):
/dev/ttyU0
115200
defaultroute
lock
noauth
debug
connect '/usr/sbin/chat -f /etc/ppp/peers/gprs.chat'

Here is the gprs chat script (it works with the siemens card).
ABORT BUSY
ABORT 'NO CARRIER'
ABORT VOICE
ABORT "NO DIALTONE"
"" ATZ
OK AT+CPIN="xxxx"
OK AT+CGDCONT=1,"IP","web.pt.lu"
OK ATDT*99***1#
'CONNECT' '\c'
'TIMEOUT' '5'

I had a look at the pppd man and tried the following 2 options:
ipcp-accept-local and
ipcp-accept-remote, without success.

I must admit that I'm not very comfortable with pppd, so any help is welcome.

Thanks a lot
Didier

[Paul de Weerd]

On Tue, Apr 04, 2006 at 07:50:15PM +0000, Didier Wiroth wrote:
| Hi,
|
| I got a vodafone pcmcia mobile connect 3g/gprs datacard today. I tried it on
my laptop running 3.9-stable.
| Previously, I used a siemens connect 2 air cf card to connect via gprs
without problems and I used almost identical pppd scripts.
|
| With the vodafone card (actually from www.OPTION.com) I'm not able to make a
gprs connection as I get the following error after +/- 20 seconds:
| Apr 4 21:08:14 nc6000 pppd[22150]: pppd 2.3.5 started by didier, uid 0
| Apr 4 21:08:18 nc6000 pppd[22150]: Connect: ppp0 <--> /dev/ttyU0
| Apr 4 21:08:48 nc6000 pppd[22150]: IPCP: timeout sending Config-Requests
| Apr 4 21:08:54 nc6000 pppd[22150]: Connection terminated.
|
| Here is the (partial) output of "usbdevs -v":
| Controller /dev/usb4:
| addr 1: full speed, self powered, config 1, OHCI root hub(0x0000),
Opti(0x1045), rev 1.00
| port 1 addr 2: full speed, power 100 mA, config 1, Vodafone Mobile Connect
Card - 3G(0x5000), Vodafone(0x0af0), rev 0.01
| port 2 powered
|
| Here is my pppd gprs script (this script works with the siemens card):
| /dev/ttyU0
| 115200
| defaultroute
| lock
| noauth
| debug
| connect '/usr/sbin/chat -f /etc/ppp/peers/gprs.chat'
|
| Here is the gprs chat script (it works with the siemens card).
| ABORT BUSY
| ABORT 'NO CARRIER'
| ABORT VOICE
| ABORT "NO DIALTONE"
| "" ATZ
| OK AT+CPIN="xxxx"
| OK AT+CGDCONT=1,"IP","web.pt.lu"
| OK ATDT*99***1#
| 'CONNECT' '\c'
| 'TIMEOUT' '5'

Try some more debugging on the chatscript. Change the pppd gprs script
to use the following :

connect '/usr/sbin/chat -v -f /etc/ppp/peers/gprs.chat'

Also try using

kdebug 7

This greatly increases pppd debugging, both from the in-kernel ppp
driver and from the chatscript. Closely watch your logfiles and see
what you can find from there.

Cheers,

Paul 'WEiRD' de Weerd

PS: Please wrap your lines at 72 chars.

--

++++++++[<++++++++++>-]<+++++++.>+++[<------>-]<.>+++[<+

+++++++++++>-]<.>++[<------------>-]<+.--------------.[-]
http://www.weirdnet.nl/

[demime 1.01d removed an attachment of type application/pgp-signature]

[Felix Kronlage]

I've noticed that the options I had to use with the Siemens Connect2Air
card differed from what I had to use with the 3G cards I used.
For use with the german telco e-plus I had to add various options to
the pppd option file that change the behaviour in ip negotiation.

Post by Didier Wiroth
Here is the gprs chat script (it works with the siemens card).

that it works with the siemens card does not mean anything.
While most of the cards have generally a set of commands that
is the same across all of them (AT+CPIN, AT+CGDCONT), some of them
have a subset to their own.

I see you've added "debug" to the pppd option file, have you
enabled the debugging to the logfile in syslog as well (pppd(8)
explains that). Your syslogd.conf should be configured to log
daemon.debug as well, otherwise the output that "debug" triggers
is not logged to /var/log/messages. (if this would be the case,
there would be lots more debugging output).

felix

[Didier Wiroth]

Oups yes ... sorry for that .... here it is. This chinese for me so I hope you can help me on this
issue.

Post by Felix Kronlage
I see you've added "debug" to the pppd option file, have you
enabled the debugging to the logfile in syslog as well (pppd(8)
explains that). Your syslogd.conf should be configured to log
daemon.debug as well, otherwise the output that "debug" triggers
is not logged to /var/log/messages. (if this would be the case,
there would be lots more debugging output).

Here is the output of a connection:
pppd[29026]: pppd 2.3.5 started by didier, uid 1000
Apr 5 10:56:33 nc6000 pppd[29026]: Serial connection established.
Apr 5 10:56:34 nc6000 pppd[29026]: Using interface ppp0
Apr 5 10:56:34 nc6000 pppd[29026]: Connect: ppp0 <--> /dev/ttyU0
Apr 5 10:56:34 nc6000 pppd[29026]: sent [LCP ConfReq id=0x1 <magic 0xa7a71966> <pcomp> <accomp>]
Apr 5 10:56:34 nc6000 pppd[29026]: rcvd [LCP ConfReq id=0x0 <asyncmap 0x0> <auth chap 05> <magic 0x40210ca> <pcomp> <accomp>]
Apr 5 10:56:34 nc6000 pppd[29026]: sent [LCP ConfRej id=0x0 <auth chap 05>]
Apr 5 10:56:34 nc6000 pppd[29026]: rcvd [LCP ConfAck id=0x1 <magic 0xa7a71966> <pcomp> <accomp>]
Apr 5 10:56:34 nc6000 pppd[29026]: rcvd [LCP ConfReq id=0x1 <asyncmap 0x0> <magic 0x40210ca> <pcomp> <accomp>]
Apr 5 10:56:34 nc6000 pppd[29026]: sent [LCP ConfAck id=0x1 <asyncmap 0x0> <magic 0x40210ca> <pcomp> <accomp>]
Apr 5 10:56:34 nc6000 pppd[29026]: sent [IPCP ConfReq id=0x1 <addr 0.0.0.0> <compress VJ 0f 01>]
Apr 5 10:56:34 nc6000 pppd[29026]: sent [CCP ConfReq id=0x1 <deflate 15> <deflate(old#) 15> <bsd v1 15>]
Apr 5 10:56:34 nc6000 pppd[29026]: rcvd [LCP DiscReq id=0x2 magic=0x40210ca]
Apr 5 10:56:37 nc6000 pppd[29026]: sent [CCP ConfReq id=0x1 <deflate 15> <deflate(old#) 15> <bsd v1 15>]
Apr 5 10:56:37 nc6000 pppd[29026]: sent [IPCP ConfReq id=0x1 <addr 0.0.0.0> <compress VJ 0f 01>]
Apr 5 10:56:40 nc6000 pppd[29026]: sent [IPCP ConfReq id=0x1 <addr 0.0.0.0> <compress VJ 0f 01>]
Apr 5 10:56:40 nc6000 pppd[29026]: sent [CCP ConfReq id=0x1 <deflate 15> <deflate(old#) 15> <bsd v1 15>]
Apr 5 10:56:43 nc6000 pppd[29026]: sent [CCP ConfReq id=0x1 <deflate 15> <deflate(old#) 15> <bsd v1 15>]
Apr 5 10:56:43 nc6000 pppd[29026]: sent [IPCP ConfReq id=0x1 <addr 0.0.0.0> <compress VJ 0f 01>]
Apr 5 10:56:46 nc6000 pppd[29026]: sent [IPCP ConfReq id=0x1 <addr 0.0.0.0> <compress VJ 0f 01>]
Apr 5 10:56:46 nc6000 pppd[29026]: sent [CCP ConfReq id=0x1 <deflate 15> <deflate(old#) 15> <bsd v1 15>]
Apr 5 10:56:49 nc6000 pppd[29026]: sent [CCP ConfReq id=0x1 <deflate 15> <deflate(old#) 15> <bsd v1 15>]
Apr 5 10:56:49 nc6000 pppd[29026]: sent [IPCP ConfReq id=0x1 <addr 0.0.0.0> <compress VJ 0f 01>]
Apr 5 10:56:52 nc6000 pppd[29026]: sent [IPCP ConfReq id=0x1 <addr 0.0.0.0> <compress VJ 0f 01>]
Apr 5 10:56:52 nc6000 pppd[29026]: sent [CCP ConfReq id=0x1 <deflate 15> <deflate(old#) 15> <bsd v1 15>]
Apr 5 10:56:55 nc6000 pppd[29026]: sent [CCP ConfReq id=0x1 <deflate 15> <deflate(old#) 15> <bsd v1 15>]
Apr 5 10:56:55 nc6000 pppd[29026]: sent [IPCP ConfReq id=0x1 <addr 0.0.0.0> <compress VJ 0f 01>]
Apr 5 10:56:58 nc6000 pppd[29026]: sent [IPCP ConfReq id=0x1 <addr 0.0.0.0> <compress VJ 0f 01>]
Apr 5 10:56:58 nc6000 pppd[29026]: sent [CCP ConfReq id=0x1 <deflate 15> <deflate(old#) 15> <bsd v1 15>]
Apr 5 10:57:01 nc6000 pppd[29026]: sent [CCP ConfReq id=0x1 <deflate 15> <deflate(old#) 15> <bsd v1 15>]
Apr 5 10:57:01 nc6000 pppd[29026]: sent [IPCP ConfReq id=0x1 <addr 0.0.0.0> <compress VJ 0f 01>]
Apr 5 10:57:04 nc6000 pppd[29026]: IPCP: timeout sending Config-Requests
Apr 5 10:57:04 nc6000 pppd[29026]: sent [LCP TermReq id=0x2 "No network protocols running"]
Apr 5 10:57:07 nc6000 pppd[29026]: sent [LCP TermReq id=0x3 "No network protocols running"]
Apr 5 10:57:10 nc6000 pppd[29026]: Connection terminated.
Apr 5 10:57:13 nc6000 pppd: Exit.

[Felix Kronlage]

Post by Didier Wiroth
Apr 5 10:57:04 nc6000 pppd[29026]: IPCP: timeout sending Config-Requests
Apr 5 10:57:04 nc6000 pppd[29026]: sent [LCP TermReq id=0x2 "No network protocols running"]
Apr 5 10:57:07 nc6000 pppd[29026]: sent [LCP TermReq id=0x3 "No network protocols running"]
Apr 5 10:57:10 nc6000 pppd[29026]: Connection terminated.
Apr 5 10:57:13 nc6000 pppd: Exit.

what I did when I had that, was to increase the ipcp restart interval:

ipcp-restart 10

(it defaults to '3').

also, I have in my config:

0.0.0.0:10.64.64.64
noipdefault

the first option wires down the remote address. I used my mac with OS X
to connect to the ISP to see what kind of remote address comes up. I did
that because my ISP has some whacked ppp session setup, this might help
you too.

felix

[Didier Wiroth]

Sorry ... for previous post

Here it is:
Apr 5 09:30:12 nc6000 pppd[32382]: pppd 2.3.5 started by didier, uid 1000
Apr 5 09:30:15 nc6000 pppd[32382]: Serial connection established.
Apr 5 09:30:16 nc6000 pppd[32382]: Using interface ppp0
Apr 5 09:30:16 nc6000 pppd[32382]: Connect: ppp0 <--> /dev/ttyU0
Apr 5 09:30:16 nc6000 pppd[32382]: sent [LCP ConfReq id=0x1 <magic 0xae071c2b> <pcomp> <accomp>]
Apr 5 09:30:16 nc6000 pppd[32382]: rcvd [LCP ConfReq id=0x0 <asyncmap 0x0> <auth chap 05> <magic 0x4030a4f> <pcomp> <accomp>]
Apr 5 09:30:16 nc6000 pppd[32382]: sent [LCP ConfRej id=0x0 <auth chap 05>]
Apr 5 09:30:16 nc6000 pppd[32382]: rcvd [LCP ConfAck id=0x1 <magic 0xae071c2b> <pcomp> <accomp>]
Apr 5 09:30:16 nc6000 pppd[32382]: rcvd [LCP ConfReq id=0x1 <asyncmap 0x0> <magic 0x4030a4f> <pcomp> <accomp>]
Apr 5 09:30:16 nc6000 pppd[32382]: sent [LCP ConfAck id=0x1 <asyncmap 0x0> <magic 0x4030a4f> <pcomp> <accomp>]
Apr 5 09:30:16 nc6000 pppd[32382]: sent [IPCP ConfReq id=0x1 <addr 0.0.0.0> <compress VJ 0f 01>]
Apr 5 09:30:16 nc6000 pppd[32382]: sent [CCP ConfReq id=0x1 <deflate 15> <deflate(old#) 15> <bsd v1 15>]
Apr 5 09:30:16 nc6000 pppd[32382]: rcvd [LCP DiscReq id=0x2 magic=0x4030a4f]
Apr 5 09:30:19 nc6000 pppd[32382]: sent [CCP ConfReq id=0x1 <deflate 15> <deflate(old#) 15> <bsd v1 15>]
Apr 5 09:30:19 nc6000 pppd[32382]: sent [IPCP ConfReq id=0x1 <addr 0.0.0.0> <compress VJ 0f 01>]
Apr 5 09:30:22 nc6000 pppd[32382]: sent [IPCP ConfReq id=0x1 <addr 0.0.0.0> <compress VJ 0f 01>]
Apr 5 09:30:22 nc6000 pppd[32382]: sent [CCP ConfReq id=0x1 <deflate 15> <deflate(old#) 15> <bsd v1 15>]
Apr 5 09:30:25 nc6000 pppd[32382]: sent [CCP ConfReq id=0x1 <deflate 15> <deflate(old#) 15> <bsd v1 15>]
Apr 5 09:30:25 nc6000 pppd[32382]: sent [IPCP ConfReq id=0x1 <addr 0.0.0.0> <compress VJ 0f 01>]
Apr 5 09:30:28 nc6000 pppd[32382]: sent [IPCP ConfReq id=0x1 <addr 0.0.0.0> <compress VJ 0f 01>]
Apr 5 09:30:28 nc6000 pppd[32382]: sent [CCP ConfReq id=0x1 <deflate 15> <deflate(old#) 15> <bsd v1 15>]
Apr 5 09:30:31 nc6000 pppd[32382]: sent [CCP ConfReq id=0x1 <deflate 15> <deflate(old#) 15> <bsd v1 15>]
Apr 5 09:30:31 nc6000 pppd[32382]: sent [IPCP ConfReq id=0x1 <addr 0.0.0.0> <compress VJ 0f 01>]
Apr 5 09:30:34 nc6000 pppd[32382]: sent [IPCP ConfReq id=0x1 <addr 0.0.0.0> <compress VJ 0f 01>]
Apr 5 09:30:34 nc6000 pppd[32382]: sent [CCP ConfReq id=0x1 <deflate 15> <deflate(old#) 15> <bsd v1 15>]
Apr 5 09:30:37 nc6000 pppd[32382]: sent [CCP ConfReq id=0x1 <deflate 15> <deflate(old#) 15> <bsd v1 15>]
Apr 5 09:30:37 nc6000 pppd[32382]: sent [IPCP ConfReq id=0x1 <addr 0.0.0.0> <compress VJ 0f 01>]
Apr 5 09:30:40 nc6000 pppd[32382]: sent [IPCP ConfReq id=0x1 <addr 0.0.0.0> <compress VJ 0f 01>]
Apr 5 09:30:40 nc6000 pppd[32382]: sent [CCP ConfReq id=0x1 <deflate 15> <deflate(old#) 15> <bsd v1 15>]
Apr 5 09:30:43 nc6000 pppd[32382]: sent [CCP ConfReq id=0x1 <deflate 15> <deflate(old#) 15> <bsd v1 15>]
Apr 5 09:30:43 nc6000 pppd[32382]: sent [IPCP ConfReq id=0x1 <addr 0.0.0.0> <compress VJ 0f 01>]
Apr 5 09:30:46 nc6000 pppd[32382]: IPCP: timeout sending Config-Requests
Apr 5 09:30:46 nc6000 pppd[32382]: sent [LCP TermReq id=0x2 "No network protocols running"]
Apr 5 09:30:49 nc6000 pppd[32382]: sent [LCP TermReq id=0x3 "No network protocols running"]
Apr 5 09:30:52 nc6000 pppd[32382]: Connection terminated.
Apr 5 09:30:54 nc6000 pppd: Exit.