Suse10.3 Modem Connects Ok, Lcp Error When Ondemand Enabled

Good evening,

this is my first post, nevertheless i hope all required information will be here.

Problem: everything works good with pppd, and it fails with lcp error when on demand is enabled in yast2.

I use opensuse 10.3 and i connect to the internet using a nokia phone, it works perfectly both with bluetooth /dev/rfcomm0 and usb /dev/ttyACM0.

this phone reboots every some days, and so i decided to setup a connection on demand on opensuse.

i logged, both the sucessful connections(without ondemand) and the unsuccessful ones (with ondemand) and one thing that was different was the following line:

WITHOUT ondemand:
[code]
pppd[0]: sent [IPCP ConfReq id=0x1 <compress VJ 0f 01> <addr 0.0.0.0> <ms-dns1 0.0.0.0> <ms-dns3 0.0.0.0>]
[code]

WITH ondemand the same line is:
[code]
pppd[0]: sent [IPCP ConfReq id=0x1 <compress VJ 0f 01> <addr 192.168.99.1> <ms-dns1 0.0.0.0> <ms-dns3 0.0.0.0>]
[code]

I believe the problem could be on this addr 192.168.99.1, which misteriously takes place on the on demand connections, but i couldn't determine where it comes from. i tried in PPPD_options, in the interface file, to force it somehow, but nothing would change.

the versions of my system are as follows:
Opensuse 10.3 kernel 2.6.22.17-0.1-default / 64bits architecture
pppd version 2.4.4
smpppd 1.59

to make the connections without on demand enabled i use kinternet or rcnetwork restart modem1
with the on demand enabled i just ping google.com and it starts as expected, failing after..

i include my configuration files next, as well as the log files of successful / unsuccessful connection.

yast changes the option ondemand of my /etc/sysconfig/network/providers/provider0 file and required the dns which i copied from the ones modem usually gets on connection. this file is as follows:

[code]
ASKPASSWORD='no'
AUTODNS='yes'
AUTO_RECONNECT='yes'
DEMAND='yes'
DSLSUPPORTED='no'
IDLETIME='0'
ISDNSUPPORTED='no'
MODEMSUPPORTED='yes'
MODIFYDNS='yes'
PASSWORD='mypassword'
PHONE='*99#'
PROVIDER='PTPRIME'
STUPIDMODE='no'
USERNAME='myispusername'
DEFAULTROUTE='yes'
IPADDR='0.0.0.0'
MODIFYIP='yes'
REMOTE_IPADDR=''
DNS1='62.48.131.10'
DNS2='62.48.131.11'
[code]

my modem files (usb bluetooth) are identical, with device changed. for all this post i used bluetooth to avoid any extra problems, so the file /etc/sysconfig/network/ifcfg-modem1 is as follows:

[code]
BOOTPROTO='none'
DIALCOMMAND='ATDT'
DIALPREFIX=''
DIALPREFIXREGEX=''
INIT1='ATZ'
INIT2='ATQ0 V1 E1 S0=0 &C1 &D2 +FCLASS=0'
INIT3='AT+CGDCONT=1,"IP","internet.ptprime.pt","", 0,0'
INIT8='ATM0'
INIT9='ATX3'
MODEM_DEVICE='/dev/ttyACM0'
NAME='Nokia Mobile Phones Nokia 6110 Navigator'
PPPD_OPTIONS='debug'
PROVIDER='provider0'
SPEED='460800'
STARTMODE='auto'
UDI='/org/freedesktop/Hal/devices/usb_device_421_46e_noserial_if8_serial_unknown_0'
USERCONTROL='yes'
[code]
note: the debug option i added myself, yast2 doesnt fill anything pppd_options

LOG of a successful conection (Without ondemand)
[code]
pppd[0]: Connect: modem1 <--> /dev/ttyACM0
pppd[0]: sent [LCP ConfReq id=0x1 <asyncmap 0x0> <magic 0x664e0083> <pcomp> <accomp>]
pppd[0]: rcvd [LCP ConfRej id=0x1 <magic 0x664e0083> <pcomp> <accomp>]
pppd[0]: sent [LCP ConfReq id=0x2 <asyncmap 0x0>]
pppd[0]: rcvd [LCP ConfAck id=0x2 <asyncmap 0x0>]
pppd[0]: rcvd [LCP ConfReq id=0x0 <auth pap> <mru 1500> <asyncmap 0xa0000>]
pppd[0]: sent [LCP ConfAck id=0x0 <auth pap> <mru 1500> <asyncmap 0xa0000>]
pppd[0]: sent [LCP EchoReq id=0x0 magic=0x0]
pppd[0]: sent [PAP AuthReq id=0x1 user="MYUSERNAME" password=<hidden>]
pppd[0]: rcvd [LCP EchoRep id=0x0 magic=0x0]
pppd[0]: rcvd [PAP AuthAck id=0x1 ""]
pppd[0]: PAP authentication succeeded
pppd[0]: sent [CCP ConfReq id=0x1 <deflate 15> <deflate(old#) 15> <bsd v1 15>]
pppd[0]: sent [IPCP ConfReq id=0x1 <compress VJ 0f 01> <addr 0.0.0.0> <ms-dns1 0.0.0.0> <ms-dns3 0.0.0.0>]
pppd[0]: rcvd [IPCP ConfReq id=0x0 <addr 10.6.6.6>]
pppd[0]: sent [IPCP ConfAck id=0x0 <addr 10.6.6.6>]
pppd[0]: rcvd [LCP ProtRej id=0x0 80 fd 01 01 00 0f 1a 04 78 00 18 04 78 00 15 03 2f]
pppd[0]: Protocol-Reject for 'Compression Control Protocol' (0x80fd) received
pppd[0]: rcvd [IPCP ConfRej id=0x1 <compress VJ 0f 01>]
pppd[0]: sent [IPCP ConfReq id=0x2 <addr 0.0.0.0> <ms-dns1 0.0.0.0> <ms-dns3 0.0.0.0>]
pppd[0]: rcvd [IPCP ConfNak id=0x2 <addr 62.28.59.203> <ms-dns1 62.48.131.10> <ms-dns3 62.48.131.11>]
pppd[0]: sent [IPCP ConfReq id=0x3 <addr 62.28.59.203> <ms-dns1 62.48.131.10> <ms-dns3 62.48.131.11>]
pppd[0]: rcvd [IPCP ConfAck id=0x3 <addr 62.28.59.203> <ms-dns1 62.48.131.10> <ms-dns3 62.48.131.11>]
pppd[0]: local IP address 62.28.59.203
pppd[0]: remote IP address 10.6.6.6
pppd[0]: primary DNS address 62.48.131.10
pppd[0]: secondary DNS address 62.48.131.11
pppd[0]: Script /etc/ppp/ip-up started (pid 6936)
pppd[0]: Script /etc/ppp/ip-up finished (pid 6936), status = 0x0
Status is: connected
[code]

and now the log of unsuccessful connections when ondemand is enabled:
[code]
pppd[0]: Connect: modem1 <--> /dev/ttyACM0
pppd[0]: sent [LCP ConfReq id=0x1 <asyncmap 0x0> <magic 0x670bd725> <pcomp> <accomp>]
pppd[0]: rcvd [LCP ConfRej id=0x1 <magic 0x670bd725> <pcomp> <accomp>]
pppd[0]: sent [LCP ConfReq id=0x2 <asyncmap 0x0>]
pppd[0]: rcvd [LCP ConfAck id=0x2 <asyncmap 0x0>]
pppd[0]: rcvd [LCP ConfReq id=0x0 <auth pap> <mru 1500> <asyncmap 0xa0000>]
pppd[0]: sent [LCP ConfAck id=0x0 <auth pap> <mru 1500> <asyncmap 0xa0000>]
pppd[0]: sent [LCP EchoReq id=0x0 magic=0x0]
pppd[0]: sent [PAP AuthReq id=0x1 user="MYUSERNAME" password=<hidden>]
pppd[0]: rcvd [LCP EchoRep id=0x0 magic=0x0]
pppd[0]: rcvd [PAP AuthAck id=0x1 ""]
pppd[0]: PAP authentication succeeded
pppd[0]: sent [CCP ConfReq id=0x1 <deflate 15> <deflate(old#) 15> <bsd v1 15>]
pppd[0]: sent [IPCP ConfReq id=0x1 <compress VJ 0f 01> <addr 192.168.99.1> <ms-dns1 0.0.0.0> <ms-dns3 0.0.0.0>]
pppd[0]: rcvd [IPCP ConfReq id=0x0 <addr 10.6.6.6>]
pppd[0]: sent [IPCP ConfAck id=0x0 <addr 10.6.6.6>]
pppd[0]: rcvd [LCP ProtRej id=0x0 80 fd 01 01 00 0f 1a 04 78 00 18 04 78 00 15 03 2f]
pppd[0]: Protocol-Reject for 'Compression Control Protocol' (0x80fd) received
pppd[0]: rcvd [LCP TermReq id=0x1]
pppd[0]: LCP terminated by peer
pppd[0]: sent [LCP TermAck id=0x1]
pppd[0]: Connection terminated.
[code]


I dont know if i should add more information...
I dont find a solution so i'm hoping maybe you could help me with your experience.

thanks in advance for any suggestions and support

Qsp

no replies so far, maybe at least someone could point me out at what could cause this? smpppd or pppd itself maybe, so that i could maybe ask in the projects webpage.. i'm really puzzled with this..

thank you for any info,
qsp.

Edit: After examining differences between logs:

I don't have detailed knowledge on this subject, so I'm not sure what I can offer, but as you've already noticed, the negotiation between your ISP and your linux box without the 'ondemand' negotiation chat after involves this string being sent:

pppd[0]: sent [IPCP ConfReq id=0x1 <compress VJ 0f 01> <addr 0.0.0.0> <ms-dns1 0.0.0.0> <ms-dns3 0.0.0.0>]

The 'ondemand' log shows:

pppd[0]: sent [IPCP ConfReq id=0x1 <compress VJ 0f 01> <addr 192.168.99.1> <ms-dns1 0.0.0.0> <ms-dns3 0.0.0.0>]

I think your greatest chance of solving this is with google, and examining other distro forum threads, as it will not be familiar to most of us. I will try to help if I can.

Shot in the dark: Have a look at /etc/ppp/options for 'noipdefault' entry (ie make sure it is present).

Network gurus - w r u?

Thanks, as a matter of fact the noipdefault option is there, uncommented, in the options file.

i try to understand how this works, and i believe that the smpppd deamon is the one who always calls pppd, but i fail to find options for it, or to know, for instance, how the pppd command is launched (ie, which args) because maybe knowing this arguments i could figure out if its smpppd or pppd related. if anyone more experienced as an idea i'd be very thankful.

i use linux for some time, but i'm more aquainted to ethernet networks.. so i'm a bit lost with ppp.

any more info please reply, meanwhile i'll post other tests that may help understanding what could be going on.



thanks.

Edit: After examining differences between logs:

I don't have detailed knowledge on this subject, so I'm not sure what I can offer, but as you've already noticed, the negotiation between your ISP and your linux box without the 'ondemand' negotiation chat after involves this string being sent:

pppd[0]: sent [IPCP ConfReq id=0x1 <compress VJ 0f 01> <addr 0.0.0.0> <ms-dns1 0.0.0.0> <ms-dns3 0.0.0.0>]

The 'ondemand' log shows:

pppd[0]: sent [IPCP ConfReq id=0x1 <compress VJ 0f 01> <addr 192.168.99.1> <ms-dns1 0.0.0.0> <ms-dns3 0.0.0.0>]

I think your greatest chance of solving this is with google, and examining other distro forum threads, as it will not be familiar to most of us. I will try to help if I can.

Shot in the dark: Have a look at /etc/ppp/options for 'noipdefault' entry (ie make sure it is present).

Network gurus - w r u?
[/b]

Hello all,

in case this could be an issue to anyone else, i found a fix that is working well for 2 days now.
first, this ip address i had initially - 192.168.99.1 was the ip that would be assigned to my modem device, while it was waiting for any dns lookup to come, so that it can detect network traffic and handle it.

As i expected, it was this string with this ip that was blocking my modem, its most probably due to a poor implementation of ipcp in my nokia phone:
pppd[0]: sent [IPCP ConfReq id=0x1 <compress VJ 0f 01> <addr 192.168.99.1> <ms-dns1 0.0.0.0> <ms-dns3 0.0.0.0>]

so, in order to send the string as it should, with addr 0.0.0.0 , i had to set the modem device to have this device always as 0.0.0.0. this way the default while waiting became 10.64.64.64 (no idea why) but the string while connecting became:
pppd[0]: sent [IPCP ConfReq id=0x1 <compress VJ 0f 01> <addr 0.0.0.0> <ms-dns1 0.0.0.0> <ms-dns3 0.0.0.0>]

so, for this i changed the provider configuration as follows (/etc/sysconfig/network/providers/provider0)
ASKPASSWORD='no'
AUTODNS='yes'
AUTO_RECONNECT='yes'
DEMAND='yes'
DSLSUPPORTED='no'
IDLETIME='0'
ISDNSUPPORTED='no'
MODEMSUPPORTED='yes'
MODIFYDNS='yes'
PASSWORD='pass'
PHONE='*99#'
PROVIDER='PTPRIME'
STUPIDMODE='no'
USERNAME='user'
DEFAULTROUTE='yes'
IPADDR='0.0.0.0'
MODIFYIP='no'
REMOTE_IPADDR=''
DNS1='62.48.131.10'
DNS2='62.48.131.11'

so, as you can see, with this configuration my ip is 0.0.0.0 and its configured with modifyip=no, which means theoretically it wouldnt change ip, nevertheless i need one, so i changed in /etc/ppp/options, which Dean kindly directed me to, and i enabled the options:

# With this option, pppd will accept the peer's idea of our local IP
# address, even if the local IP address was specified in an option.
ipcp-accept-local

# With this option, pppd will accept the peer's idea of its (remote) IP
# address, even if the remote IP address was specified in an option.
ipcp-accept-remote

I'm not sure this is 100% needed, but it seemed wrong to me to have a fixed ip, when i need my ip to be assigned by my isp, so i changed it.

and from now on it all connected successfully: smpppd starts, sets up the bluetooth or usb modem device with this ip 10.64.64.64 and first connection attempt triggers pppd and everything goes fine, if modem reboots (happens every two days with this phone) connection is also reestablished automatically.


Now i'll wait for opensuse 11 to come and start out a new quest, i'll give network manager again a try, hoping it will handle my perhaps complex connection needs, i use wireless on the weekend, wired at the office, outside the office i need my 3g connection and a vpn that lets me access the work exchange and samba shares. I didnt manage to do it with network manager, so i disabled and used scpm, nevertheless this time i'll be trying it better, as the dbus solution seems to have more potential.

Thanks for the views, and of course for the reply to my post.
Qsp.

Great work!