Opened 6 years ago

Closed 6 years ago

Last modified 4 years ago

#354 closed defect (fixed)

gprs org.freesmartphone.GSM.PDP.ActivateContext connection failed

Reported by: max_posedon Owned by: mickey
Priority: critical Milestone:
Component: framework/ogsmd Version:
Keywords: Cc:

Description

pppd don't start properly, when I connect via gprs

# connecting to gprs

$ cli-framework
failed to connect to bluez
freesmartphone.org interactive command line
>>> gsmpdp.ActivateContext("mts", "mts", "mts")

# pppd log (logread -f)

Feb  9 22:14:31 om-gta02 local0.info /usr/sbin/gsm0710muxd[1496]: gsm0710muxd.c:729:c_alloc_channel(): Connecting /dev/pts/8 to virtual channel 4 for ogsmd.gprs on /dev/ttySAC0                                                                        
Feb  9 22:14:31 om-gta02 local0.info /usr/sbin/gsm0710muxd[1496]: gsm0710muxd.c:1474:extract_frames(): Logical channel 4 opened                                                                                                                         
Feb  9 22:14:32 om-gta02 daemon.notice pppd[2010]: pppd 2.4.3 started by root, uid 0                                        
Feb  9 22:14:33 om-gta02 local2.info chat[2013]: abort on (BUSY)                                                            
Feb  9 22:14:33 om-gta02 local2.info chat[2013]: abort on (DELAYED)                                                         
Feb  9 22:14:33 om-gta02 local2.info chat[2013]: abort on (ERROR)                                                           
Feb  9 22:14:33 om-gta02 local2.info chat[2013]: abort on (NO ANSWER)                                                       
Feb  9 22:14:33 om-gta02 local2.info chat[2013]: abort on (NO CARRIER)                                                      
Feb  9 22:14:33 om-gta02 local2.info chat[2013]: abort on (NO DIALTONE)                                                     
Feb  9 22:14:33 om-gta02 local2.info chat[2013]: abort on (RINGING)                                                         
Feb  9 22:14:33 om-gta02 local2.info chat[2013]: abort on (VOICE)                                                           
Feb  9 22:14:33 om-gta02 local2.info chat[2013]: timeout set to 5 seconds                                                   
Feb  9 22:14:33 om-gta02 local2.info chat[2013]: send (+++AT^M)                                                             
Feb  9 22:14:33 om-gta02 local2.info chat[2013]: expect (OK)                                                                
Feb  9 22:14:33 om-gta02 local2.info chat[2013]: AT^M                                                                       
Feb  9 22:14:33 om-gta02 local2.info chat[2013]: OK                                                                         
Feb  9 22:14:33 om-gta02 local2.info chat[2013]:  -- got it
Feb  9 22:14:33 om-gta02 local2.info chat[2013]: send (ATE0Q0V1^M)
Feb  9 22:14:33 om-gta02 local2.info chat[2013]: expect (OK)
Feb  9 22:14:33 om-gta02 local2.info chat[2013]: ^M
Feb  9 22:14:33 om-gta02 local2.info chat[2013]: ATE0Q0V1^M^M
Feb  9 22:14:33 om-gta02 local2.info chat[2013]: OK
Feb  9 22:14:33 om-gta02 local2.info chat[2013]:  -- got it
Feb  9 22:14:33 om-gta02 local2.info chat[2013]: send (AT+CMEE=2^M)
Feb  9 22:14:33 om-gta02 local2.info chat[2013]: expect (OK)
Feb  9 22:14:33 om-gta02 local2.info chat[2013]: ^M
Feb  9 22:14:33 om-gta02 local2.info chat[2013]: ^M
Feb  9 22:14:33 om-gta02 local2.info chat[2013]: OK
Feb  9 22:14:33 om-gta02 local2.info chat[2013]:  -- got it
Feb  9 22:14:33 om-gta02 local2.info chat[2013]: send (AT+CGDCONT=1,"IP","mts"^M)
Feb  9 22:14:34 om-gta02 local2.info chat[2013]: timeout set to 180 seconds
Feb  9 22:14:34 om-gta02 local2.info chat[2013]: expect (OK)
Feb  9 22:14:34 om-gta02 local2.info chat[2013]: ^M
Feb  9 22:14:34 om-gta02 local2.info chat[2013]: ^M
Feb  9 22:14:34 om-gta02 local2.info chat[2013]: OK
Feb  9 22:14:34 om-gta02 local2.info chat[2013]:  -- got it
Feb  9 22:14:34 om-gta02 local2.info chat[2013]: send (ATD*99#^M)
Feb  9 22:14:34 om-gta02 local2.info chat[2013]: expect (CONNECT)
Feb  9 22:14:34 om-gta02 local2.info chat[2013]: ^M
Feb  9 22:17:34 om-gta02 local2.info chat[2013]: alarm
Feb  9 22:17:34 om-gta02 local2.info chat[2013]: Failed
Feb  9 22:17:34 om-gta02 daemon.err pppd[2010]: Connect script failed
Feb  9 22:17:34 om-gta02 local0.info /usr/sbin/gsm0710muxd[1496]: gsm0710muxd.c:542:logical_channel_close(): Logical channel 4 for ogsmd.gprs closing
Feb  9 22:17:34 om-gta02 daemon.info pppd[2010]: Exit.
Feb  9 22:17:34 om-gta02 local0.info /usr/sbin/gsm0710muxd[1496]: gsm0710muxd.c:1460:extract_frames(): Logical channel 4 for ogsmd.gprs closed

# framework.log

2009.02.09 22:14:31 ogsmd.modems.abstract.pdp INFO     configuring ppp for user 'mts' w/ password 'mts'
2009.02.09 22:14:31 ogsmd.modems.ti_calypso INFO     Requesting new channel from 'gsm0710muxd'
2009.02.09 22:14:31 ogsmd.modems.abstract.pdp INFO     launching ppp as commandline ['/usr/sbin/pppd', '/dev/pts/8', 'connec
2009.02.09 22:14:31 ogsmd.modems.abstract.pdp INFO     pppd launched. See syslog (e.g. logread -f) for output.
2009.02.09 22:14:31 ogsmd.device INFO     org.freesmartphone.GSM.PDP.ContextStatus: 1 outgoing {}
2009.02.09 22:14:32 ogsmd.server INFO     online status now offline
2009.02.09 22:14:34 ogsmd.device INFO     org.freesmartphone.GSM.PDP.NetworkStatus: {'act': 'GSM', 'registration': 'busy'}
2009.02.09 22:14:41 ogsmd.device INFO     org.freesmartphone.GSM.Network.SignalStrength: 100
2009.02.09 22:15:01 ogsmd.device INFO     org.freesmartphone.GSM.Network.SignalStrength: 98
2009.02.09 22:15:33 odeviced.kernel26 INFO     capacity now 83
2009.02.09 22:17:34 ogsmd.modems.abstract.pdp INFO     pppd exited with code 8, signal 0
2009.02.09 22:17:34 ogsmd.device INFO     org.freesmartphone.GSM.PDP.ContextStatus: 1 release {}
2009.02.09 22:17:34 ogsmd.device INFO     org.freesmartphone.GSM.PDP.NetworkStatus: {'act': 'GSM', 'registration': 'unregist
2009.02.09 22:17:34 ogsmd.modems.abstract.pdp INFO     shutting down pppd
2009.02.09 22:17:34 mppl.processguard INFO     shutdown: process already vanished

Change History (5)

comment:1 Changed 6 years ago by max_posedon

Oh... framework wasn't pasted fully, now correct:

# framework.log

2009.02.09 22:14:31 ogsmd.modems.abstract.pdp INFO     configuring ppp for user 'mts' w/ password 'mts'
2009.02.09 22:14:31 ogsmd.modems.ti_calypso INFO     Requesting new channel from 'gsm0710muxd'
2009.02.09 22:14:31 ogsmd.modems.abstract.pdp INFO     launching ppp as commandline ['/usr/sbin/pppd', '/dev/pts/8', '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', 'ipcp-max-configure', '4', 'lock', 'noauth', 'noipdefault', 'novj', 'novjccomp', 'proxyarp', 'replacedefaultroute', 'usepeerdns', 'user', 'mts']
2009.02.09 22:14:31 ogsmd.modems.abstract.pdp INFO     pppd launched. See syslog (e.g. logread -f) for output.
2009.02.09 22:14:31 ogsmd.device INFO     org.freesmartphone.GSM.PDP.ContextStatus: 1 outgoing {}
2009.02.09 22:14:32 ogsmd.server INFO     online status now offline
2009.02.09 22:14:34 ogsmd.device INFO     org.freesmartphone.GSM.PDP.NetworkStatus: {'act': 'GSM', 'registration': 'busy'}
2009.02.09 22:14:41 ogsmd.device INFO     org.freesmartphone.GSM.Network.SignalStrength: 100
2009.02.09 22:15:01 ogsmd.device INFO     org.freesmartphone.GSM.Network.SignalStrength: 98
2009.02.09 22:15:33 odeviced.kernel26 INFO     capacity now 83
2009.02.09 22:17:34 ogsmd.modems.abstract.pdp INFO     pppd exited with code 8, signal 0
2009.02.09 22:17:34 ogsmd.device INFO     org.freesmartphone.GSM.PDP.ContextStatus: 1 release {}
2009.02.09 22:17:34 ogsmd.device INFO     org.freesmartphone.GSM.PDP.NetworkStatus: {'act': 'GSM', 'registration': 'unregistered'}
2009.02.09 22:17:34 ogsmd.modems.abstract.pdp INFO     shutting down pppd

comment:2 Changed 6 years ago by max_posedon

  • Resolution set to invalid
  • Status changed from new to closed

Oh... Sorry, looks it wasn't software problem, because from 20th try I connected successfully.

comment:3 Changed 6 years ago by max_posedon

  • Resolution invalid deleted
  • Status changed from closed to reopened

But SHR (and should be FSO, contains bug with framework.

>>> gsmpdp.ActivateContext("eplus","eplus","eplus")                                                                            
Traceback (most recent call last):                                                                                             
  File "<console>", line 1, in <module>                                                                                        
  File "/usr/lib/python2.6/site-packages/dbus/proxies.py", line 140, in __call__
    **keywords)
  File "/usr/lib/python2.6/site-packages/dbus/connection.py", line 622, in call_blocking
    message, timeout)
DBusException: org.freedesktop.DBus.Python.TypeError: Traceback (most recent call last):
  File "/usr/lib/python2.6/site-packages/dbus/service.py", line 702, in _message_cb
    retval = candidate_method(self, *args, **keywords)
  File "<string>", line 1, in <lambda>
  File "/usr/lib/python2.6/site-packages/framework/resource.py", line 41, in checkedmethod
    return f(*args, **kw)
  File "/usr/lib/python2.6/site-packages/framework/subsystems/ogsmd/device.py", line 587, in ActivateContext
    mediator.PdpActivateContext( self, dbus_ok, dbus_error, apn=apn, user=user, password=password )
  File "/usr/lib/python2.6/site-packages/framework/subsystems/ogsmd/modems/abstract/mediator.py", line 243, in __init__
    AbstractYieldSupport.__init__( self, *args, **kwargs )
  File "/usr/lib/python2.6/site-packages/framework/subsystems/ogsmd/modems/abstract/mediator.py", line 165, in __init__
    self.generator = self.trigger()
  File "/usr/lib/python2.6/site-packages/framework/subsystems/ogsmd/modems/abstract/mediator.py", line 1493, in trigger
    pdpConnection.activate()
  File "/usr/lib/python2.6/site-packages/framework/subsystems/ogsmd/modems/abstract/pdp.py", line 86, in activate
    self._activate()
  File "/usr/lib/python2.6/site-packages/framework/subsystems/ogsmd/modems/abstract/pdp.py", line 114, in _activate
    self.port = str( self._object.modem.dataPort() )
  File "/usr/lib/python2.6/site-packages/framework/subsystems/ogsmd/modems/ti_calypso/modem.py", line 180, in dataPort
    return self.pathfactory( self, "ogsmd.gprs" )
TypeError: pathfactory() takes exactly 2 arguments (3 given)

Patch proposed by PaulFertser?

$ diff -Nur modem.py.old modem.p
y
--- modem.py.old        Mon Feb  9 23:00:24 2009
+++ modem.py    Mon Feb  9 15:11:26 2009
@@ -177,7 +177,7 @@
             return str(pts)

     def dataPort( self ):
-        return self.pathfactory( self, "ogsmd.gprs" )
+        return self.pathfactory( "ogsmd.gprs" )

     def prepareForSuspend( self, ok_callback, error_callback ):
         """overridden for internal purposes"""

comment:4 Changed 6 years ago by mickey

  • Resolution set to fixed
  • Status changed from reopened to closed
Note: See TracTickets for help on using tickets.