GPRS Problem

[Leachim]

When I use this Script:

#!/bin/sh

NAMESERVER1="139.7.30.125" 
NAMESERVER2="139.7.30.126"
echo -e "nameserver $NAMESERVER1\nnameserver $NAMESERVER1" > /etc/resolv.conf

APN="web.vodafone.de" 
USERNAME="x"
PASSWORD="x"
BUSNAME="org.freesmartphone.frameworkd"
OBJECTPATH="/org/freesmartphone/GSM/Device"
METHODNAME="org.freesmartphone.GSM.PDP.ActivateContext"
mdbus -s $BUSNAME $OBJECTPATH $METHODNAME $APN "$USERNAME" "$PASSWORD"

to connect GPRS, the Network connection of a connected windows xp computer disconect four a very short time.

In FSO I had never any problem with this script but in SHR it diden´t work. In the Frameworkd Debug log I have red:

"2008.11.27 17:09:21 ogsmd.pdp DEBUG got output from ppp: '/usr/sbin/pppd: pppd is unable to open the /dev/ppp device.\nYou need to create the /dev/ppp device node by\nexecuting the following command as root:\n\tmknod /dev/ppp c 108 0\n\n'"

Execute "mknod /dev/ppp c 108 0" as root has no effect to the GPRS connection Problem.

At the moment the Debug log says:

2008.11.27 17:09:44 frameworkd.controller DEBUG    alive and kicking
2008.11.27 17:10:00 otimed   DEBUG    Minute 17:10
2008.11.27 17:10:29 odeviced DEBUG    (read '1' from '/sys/class/power_supply/bat/present')
2008.11.27 17:10:29 odeviced DEBUG    (read '1' from '/sys/class/power_supply/bat/present')
2008.11.27 17:10:29 odeviced DEBUG    (read '100' from '/sys/class/power_supply/bat/capacity')
2008.11.27 17:10:44 frameworkd.controller DEBUG    alive and kicking
2008.11.27 17:11:00 otimed   DEBUG    Minute 17:11
2008.11.27 17:11:44 frameworkd.controller DEBUG    alive and kicking
2008.11.27 17:12:00 otimed   DEBUG    Minute 17:12
2008.11.27 17:12:44 frameworkd.controller DEBUG    alive and kicking
2008.11.27 17:13:00 otimed   DEBUG    Minute 17:13
2008.11.27 17:13:40 ogsmd    DEBUG    |...|...|...|...|...|...|...|...|...> PdpActivateContext.__init__: ENTER (<framework.subsystems.ogsmd.objects.Device at /org/freesmartphone/GSM/Device at 0x409f1370>, <function <lambda> at 0x5bcfb0>, <function <lambda> at 0x56e970>),{'password': dbus.String(u'x'), 'apn': dbus.String(u'web.vodafone.de'), 'user': dbus.String(u'x')}
2008.11.27 17:13:40 ogsmd    DEBUG    |...|...|...|...|...|...|...|...|...> PdpActivateContext.__init__: LEAVE
2008.11.27 17:13:40 ogsmd.pdp INFO     configuring ppp for user 'x' w/ password 'x'
2008.11.27 17:13:40 ogsmd.pdp DEBUG    activate got port /dev/pts/5
2008.11.27 17:13:40 ogsmd.pdp INFO     launching ppp with commandline ['/usr/sbin/pppd', '/dev/pts/5', 'connect', '/var/tmp/ogsmd/gprs-connect-chat', 'disconnect', '/var/tmp/ogsmd/gprs-disconnect-chat', '115200', 'nodetach', 'crtscts', 'defaultroute', 'debug', 'hide-password', 'holdoff', '3', 'ipcp-accept-local', 'ktune', 'lcp-echo-failure', '10', 'lcp-echo-interval', '20', 'ipcp-max-configure', '4', 'lock', 'noauth', 'noipdefault', 'novj', 'novjccomp', 'proxyarp', 'replacedefaultroute', 'usepeerdns', 'user', 'x']
2008.11.27 17:13:40 ogsmd.pdp DEBUG    preparing file /etc/ppp/ip-up.d/08setupdns
2008.11.27 17:13:40 ogsmd.pdp DEBUG    preparing file /etc/ppp/ip-down.d/92removedns
2008.11.27 17:13:40 ogsmd.pdp DEBUG    preparing file /var/tmp/ogsmd/gprs-disconnect-chat
2008.11.27 17:13:40 ogsmd.pdp DEBUG    preparing file /etc/ppp/pap-secrets
2008.11.27 17:13:40 ogsmd.pdp DEBUG    preparing file /etc/ppp/chap-secrets
2008.11.27 17:13:40 ogsmd.pdp DEBUG    preparing file /var/tmp/ogsmd/gprs-connect-chat
2008.11.27 17:13:40 ogsmd.pdp INFO     pppd launched, pid 1376. See logread -f for output.
2008.11.27 17:13:40 ogsmd.objects INFO     org.freesmartphone.GSM.PDP.ContextStatus: 1 outgoing {}
2008.11.27 17:13:41 ogsmd    DEBUG    |...|...|...|...|...|...|...|...> PdpActivateContext.__del__: ENTER (),{}
2008.11.27 17:13:41 ogsmd    DEBUG    |...|...|...|...|...|...|...|...> PdpActivateContext.__del__: LEAVE
2008.11.27 17:13:41 odeviced.kernel26 DEBUG    got data from uevent socket: 'add@/module/slhc\x00ACTION=add\x00DEVPATH=/module/slhc\x00SUBSYSTEM=module\x00SEQNUM=507\x00'
2008.11.27 17:13:41 odeviced.kernel26 DEBUG    got data from uevent socket: 'add@/module/ppp_generic\x00ACTION=add\x00DEVPATH=/module/ppp_generic\x00SUBSYSTEM=module\x00SEQNUM=508\x00'
2008.11.27 17:13:41 odeviced.kernel26 DEBUG    got data from uevent socket: 'add@/class/ppp\x00ACTION=add\x00DEVPATH=/class/ppp\x00SUBSYSTEM=class\x00SEQNUM=509\x00'
2008.11.27 17:13:41 odeviced.kernel26 DEBUG    got data from uevent socket: 'add@/devices/virtual/ppp/ppp\x00ACTION=add\x00DEVPATH=/devices/virtual/ppp/ppp\x00SUBSYSTEM=ppp\x00MAJOR=108\x00MINOR=0\x00SEQNUM=510\x00'
2008.11.27 17:13:43 ogsmd.pdp DEBUG    route status. old=usb0, last=usb0, current=usb0
2008.11.27 17:13:44 frameworkd.controller DEBUG    alive and kicking
2008.11.27 17:13:45 ogsmd.pdp DEBUG    route status. old=usb0, last=usb0, current=usb0
2008.11.27 17:13:47 ogsmd.pdp DEBUG    route status. old=usb0, last=usb0, current=usb0
2008.11.27 17:13:49 ogsmd.pdp DEBUG    route status. old=usb0, last=usb0, current=usb0
2008.11.27 17:13:51 ogsmd.pdp DEBUG    route status. old=usb0, last=usb0, current=usb0
2008.11.27 17:13:53 ogsmd.pdp DEBUG    route status. old=usb0, last=usb0, current=usb0
2008.11.27 17:13:53 ogsmd.pdp DEBUG    got output from ppp: 'Connect script failed\n'
2008.11.27 17:13:53 ogsmd.pdp INFO     pppd exited with code 8 and signal 0
2008.11.27 17:13:53 ogsmd.objects INFO     org.freesmartphone.GSM.PDP.ContextStatus: 1 release {}
2008.11.27 17:13:53 ogsmd.pdp DEBUG    recovering file /etc/ppp/ip-up.d/08setupdns
2008.11.27 17:13:53 ogsmd.pdp DEBUG    recovering file /etc/ppp/ip-down.d/92removedns
2008.11.27 17:13:53 ogsmd.pdp DEBUG    recovering file /var/tmp/ogsmd/gprs-disconnect-chat
2008.11.27 17:13:53 ogsmd.pdp DEBUG    recovering file /etc/ppp/pap-secrets
2008.11.27 17:13:53 ogsmd.pdp DEBUG    recovering file /etc/ppp/chap-secrets
2008.11.27 17:13:53 ogsmd.pdp DEBUG    recovering file /var/tmp/ogsmd/gprs-connect-chat
2008.11.27 17:14:00 otimed   DEBUG    Minute 17:14

[hiciu]

I got this issue too. After mknod, I checked in /var/tmp/ogsmd (or smth like that) and there was file named ',var,tmp,ogsmd', maybe its misspelled somewhere. Quickfix: copy /usr/lib/python2.5/site-packages/frameworkd/ from fso testing image. Cons: gprs will works but many of frameworkd features will not.

(does opkg upgrade fix this issue? I got old image and I can't update it right now..)

[zub]

Experiencing the same issue.

After creating the ppp device (mknod /dev/ppp c 108 0), I discovered the following in the output of logread:

Dec 13 01:25:29 om-gta02 local0.info /usr/sbin/gsm0710muxd[1233]: gsm0710muxd.c:693:c_alloc_channel(): Connecting /dev/pts/10 to virtual channel 7 for ogsmd.gprs on /dev/ttySAC0
Dec 13 01:25:29 om-gta02 local0.info /usr/sbin/gsm0710muxd[1233]: gsm0710muxd.c:1429:extract_frames(): Logical channel 7 for ogsmd.gprs couldn't be opened
Dec 13 01:25:29 om-gta02 user.info kernel: PPP generic driver version 2.4.2
Dec 13 01:25:29 om-gta02 daemon.notice pppd[1500]: pppd 2.4.3 started by root, uid 0
Dec 13 01:25:30 om-gta02 local2.info chat[1510]: abort on (BUSY)
Dec 13 01:25:30 om-gta02 local2.info chat[1510]: abort on (DELAYED)
Dec 13 01:25:30 om-gta02 local2.info chat[1510]: abort on (ERROR)
Dec 13 01:25:30 om-gta02 local2.info chat[1510]: abort on (NO ANSWER)
Dec 13 01:25:30 om-gta02 local2.info chat[1510]: abort on (NO CARRIER)
Dec 13 01:25:30 om-gta02 local2.info chat[1510]: abort on (NO DIALTONE)
Dec 13 01:25:30 om-gta02 local2.info chat[1510]: abort on (RINGING)
Dec 13 01:25:30 om-gta02 local2.info chat[1510]: abort on (VOICE)
Dec 13 01:25:30 om-gta02 local2.info chat[1510]: timeout set to 5 seconds
Dec 13 01:25:30 om-gta02 local2.info chat[1510]: send (+++ATZ^M)
Dec 13 01:25:31 om-gta02 local0.warn /usr/sbin/gsm0710muxd[1233]: gsm0710muxd.c:569:pseudo_device_read(): Write to a channel which wasn't acked to be open.
Dec 13 01:25:31 om-gta02 local0.info /usr/sbin/gsm0710muxd[1233]: gsm0710muxd.c:1429:extract_frames(): Logical channel 7 for ogsmd.gprs couldn't be opened
Dec 13 01:25:31 om-gta02 local0.warn /usr/sbin/gsm0710muxd[1233]: gsm0710muxd.c:569:pseudo_device_read(): Write to a channel which wasn't acked to be open.
...
Dec 13 01:25:31 om-gta02 local0.warn /usr/sbin/gsm0710muxd[1233]: gsm0710muxd.c:569:pseudo_device_read(): Write to a channel which wasn't acked to be open.
Dec 13 01:25:31 om-gta02 local2.info chat[1510]: expect (OK)
Dec 13 01:25:31 om-gta02 local0.info /usr/sbin/gsm0710muxd[1233]: gsm0710muxd.c:1429:extract_frames(): Logical channel 7 for ogsmd.gprs couldn't be opened
Dec 13 01:25:36 om-gta02 local2.info chat[1510]: alarm
Dec 13 01:25:36 om-gta02 local2.info chat[1510]: send (\k\k\k\d+++ATH^M)
Dec 13 01:25:36 om-gta02 local0.warn /usr/sbin/gsm0710muxd[1233]: gsm0710muxd.c:569:pseudo_device_read(): Write to a channel which wasn't acked to be open.
Dec 13 01:25:36 om-gta02 local0.info /usr/sbin/gsm0710muxd[1233]: gsm0710muxd.c:1429:extract_frames(): Logical channel 7 for ogsmd.gprs couldn't be opened
...
Dec 13 01:25:37 om-gta02 local0.warn /usr/sbin/gsm0710muxd[1233]: gsm0710muxd.c:569:pseudo_device_read(): Write to a channel which wasn't acked to be open.
Dec 13 01:25:37 om-gta02 local0.info /usr/sbin/gsm0710muxd[1233]: gsm0710muxd.c:1429:extract_frames(): Logical channel 7 for ogsmd.gprs couldn't be opened
Dec 13 01:25:37 om-gta02 local2.info chat[1510]: expect (OK)
Dec 13 01:25:37 om-gta02 local0.warn /usr/sbin/gsm0710muxd[1233]: gsm0710muxd.c:569:pseudo_device_read(): Write to a channel which wasn't acked to be open.
Dec 13 01:25:37 om-gta02 local0.info /usr/sbin/gsm0710muxd[1233]: gsm0710muxd.c:1429:extract_frames(): Logical channel 7 for ogsmd.gprs couldn't be opened
Dec 13 01:25:42 om-gta02 local2.info chat[1510]: alarm
Dec 13 01:25:42 om-gta02 local2.info chat[1510]: Failed
Dec 13 01:25:42 om-gta02 daemon.err pppd[1500]: Connect script failed
Dec 13 01:25:42 om-gta02 local0.info /usr/sbin/gsm0710muxd[1233]: gsm0710muxd.c:609:pseudo_device_read(): Logical channel 7 for (null) closed
Dec 13 01:25:42 om-gta02 daemon.info pppd[1500]: Exit.

The second line:

Dec 13 01:25:29 om-gta02 local0.info /usr/sbin/gsm0710muxd[1233]: gsm0710muxd.c:1429:extract_frames(): Logical channel 7 for ogsmd.gprs couldn't be opened

seems to be the root of the evil. (?)

[zub]

Restarting gsm0710muxd seems to fix the problem.

[hiciu]

echo ppp-generic > /etc/modutils/ppp-generic
update-modules

fix the problem permamently, at least for me. Could /etc/modutils/ppp-generic file be added to shr images?

[leachim]

Thank you very mutch, it works very well.

[Zem]

BUSNAME="org.freesmartphone.frameworkd" is deprecated, and finaly removed in the current FSO unstable. BUSNAME="org.freesmartphone.ogsmd" is the right busname. just for the case someone is using this Bug to get his gprs working on SHR-Unstable. ;-)

[dos]

Please contact me there are still problems.