User:JohnSullivan/Backtraces

From Openmoko

< User:JohnSullivan(Difference between revisions)
Jump to: navigation, search
(Hanging while trying to send SMS: with debug on)
(Hanging while trying to send SMS: more info)
 
(One intermediate revision by one user not shown)
Line 3: Line 3:
 
== Hanging while trying to send SMS ==
 
== Hanging while trying to send SMS ==
  
Trying to send using StoreMessage and SendStoredMessage. Firmware moko11-beta1. frameworkd from git at c431182670962eff5ceeec485a9418a0f55aa2da.
+
Trying to send using StoreMessage and SendStoredMessage. Firmware moko11-beta1. frameworkd from git at c431182670962eff5ceeec485a9418a0f55aa2da. Eventually, there is a dbus timeout error, and the message remains stored on the card with status "unsent".
  
 
Mar  8 14:14:35 calvino ogsmd.modems.abstract.channel WARNING  UNHANDLED INTERMEDIATE: +CMGS: 4
 
Mar  8 14:14:35 calvino ogsmd.modems.abstract.channel WARNING  UNHANDLED INTERMEDIATE: +CMGS: 4
Line 22: Line 22:
 
With DEBUG on:
 
With DEBUG on:
  
Mar  8 14:51:20 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/0>: _readyToSend: watch timeout = None
+
 
Mar  8 14:51:20 calvino ogsmd    DEBUG    (<MiscChannel via /dev/pts/0>: last communication with modem was 112 seconds ago. Sending EOF to wakeup)
+
    Mar  8 14:51:20 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/0>: _readyToSend: watch timeout = None
Mar  8 14:51:20 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/0>: sending 10 bytes: 'AT+CPIN?\r\n'
+
    Mar  8 14:51:20 calvino ogsmd    DEBUG    (<MiscChannel via /dev/pts/0>: last communication with modem was 112 seconds ago. Sending EOF to wakeup)
Mar  8 14:51:20 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/0>: _readyToRead: watch timeout = 495
+
    Mar  8 14:51:20 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/0>: sending 10 bytes: 'AT+CPIN?\r\n'
Mar  8 14:51:20 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/0>: got 22 bytes: '\r\n+CPIN: READY\r\n\r\nOK\r\n'
+
    Mar  8 14:51:20 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/0>: _readyToRead: watch timeout = 495
Mar  8 14:51:20 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/0>: COMPLETED 'AT+CPIN?' => ['+CPIN: READY', 'OK']
+
    Mar  8 14:51:20 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/0>: got 22 bytes: '\r\n+CPIN: READY\r\n\r\nOK\r\n'
Mar  8 14:51:20 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/0>: _readyToSend: watch timeout = None
+
    Mar  8 14:51:20 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/0>: COMPLETED 'AT+CPIN?' => ['+CPIN: READY', 'OK']
Mar  8 14:51:20 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/0>: sending 11 bytes: 'AT+CFUN=1\r\n'
+
    Mar  8 14:51:20 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/0>: _readyToSend: watch timeout = None
Mar  8 14:51:20 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/0>: _readyToRead: watch timeout = 498
+
    Mar  8 14:51:20 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/0>: sending 11 bytes: 'AT+CFUN=1\r\n'
Mar  8 14:51:20 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/0>: got 6 bytes: '\r\nOK\r\n'
+
    Mar  8 14:51:20 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/0>: _readyToRead: watch timeout = 498
Mar  8 14:51:20 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/0>: COMPLETED 'AT+CFUN=1' => ['OK']
+
    Mar  8 14:51:20 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/0>: got 6 bytes: '\r\nOK\r\n'
Mar  8 14:51:20 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/0>: _readyToSend: watch timeout = None
+
    Mar  8 14:51:20 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/0>: COMPLETED 'AT+CFUN=1' => ['OK']
Mar  8 14:51:20 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/0>: sending 10 bytes: 'AT+CPIN?\r\n'
+
    Mar  8 14:51:20 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/0>: _readyToSend: watch timeout = None
Mar  8 14:51:20 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/0>: _readyToRead: watch timeout = 501
+
    Mar  8 14:51:20 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/0>: sending 10 bytes: 'AT+CPIN?\r\n'
Mar  8 14:51:20 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/0>: got 22 bytes: '\r\n+CPIN: READY\r\n\r\nOK\r\n'
+
    Mar  8 14:51:20 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/0>: _readyToRead: watch timeout = 501
Mar  8 14:51:20 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/0>: COMPLETED 'AT+CPIN?' => ['+CPIN: READY', 'OK']
+
    Mar  8 14:51:20 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/0>: got 22 bytes: '\r\n+CPIN: READY\r\n\r\nOK\r\n'
Mar  8 14:51:20 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/0>: _readyToSend: watch timeout = None
+
    Mar  8 14:51:20 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/0>: COMPLETED 'AT+CPIN?' => ['+CPIN: READY', 'OK']
Mar  8 14:51:20 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/0>: sending 10 bytes: 'AT+CPIN?\r\n'
+
    Mar  8 14:51:20 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/0>: _readyToSend: watch timeout = None
Mar  8 14:51:20 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/0>: _readyToRead: watch timeout = 504
+
    Mar  8 14:51:20 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/0>: sending 10 bytes: 'AT+CPIN?\r\n'
Mar  8 14:51:20 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/0>: got 22 bytes: '\r\n+CPIN: READY\r\n\r\nOK\r\n'
+
    Mar  8 14:51:20 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/0>: _readyToRead: watch timeout = 504
Mar  8 14:51:20 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/0>: COMPLETED 'AT+CPIN?' => ['+CPIN: READY', 'OK']
+
    Mar  8 14:51:20 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/0>: got 22 bytes: '\r\n+CPIN: READY\r\n\r\nOK\r\n'
Mar  8 14:51:20 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/0>: _readyToSend: watch timeout = None
+
    Mar  8 14:51:20 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/0>: COMPLETED 'AT+CPIN?' => ['+CPIN: READY', 'OK']
Mar  8 14:51:20 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/0>: sending 11 bytes: 'AT+CFUN=1\r\n'
+
    Mar  8 14:51:20 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/0>: _readyToSend: watch timeout = None
Mar  8 14:51:20 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/0>: _readyToRead: watch timeout = 507
+
    Mar  8 14:51:20 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/0>: sending 11 bytes: 'AT+CFUN=1\r\n'
Mar  8 14:51:20 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/0>: got 6 bytes: '\r\nOK\r\n'
+
    Mar  8 14:51:20 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/0>: _readyToRead: watch timeout = 507
Mar  8 14:51:20 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/0>: COMPLETED 'AT+CFUN=1' => ['OK']
+
    Mar  8 14:51:20 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/0>: got 6 bytes: '\r\nOK\r\n'
Mar  8 14:51:20 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/0>: _readyToSend: watch timeout = None
+
    Mar  8 14:51:20 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/0>: COMPLETED 'AT+CFUN=1' => ['OK']
Mar  8 14:51:20 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/0>: sending 10 bytes: 'AT+CPIN?\r\n'
+
    Mar  8 14:51:20 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/0>: _readyToSend: watch timeout = None
Mar  8 14:51:20 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/0>: Autogenerated prefixes for command 'AT+CMGW=24\r': set(['+CMGW'])
+
    Mar  8 14:51:20 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/0>: sending 10 bytes: 'AT+CPIN?\r\n'
Mar  8 14:51:20 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/0>: Autogenerated prefixes for command '0001000B818175535081F300000CD4F29CCE02CDDF7279DE05\x1a': set([])
+
    Mar  8 14:51:20 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/0>: Autogenerated prefixes for command 'AT+CMGW=24\r': set(['+CMGW'])
Mar  8 14:51:20 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/0>: _readyToRead: watch timeout = 510
+
    Mar  8 14:51:20 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/0>: Autogenerated prefixes for command '0001000B818175535081F300000CD4F29CCE02CDDF7279DE05\x1a': set([])
Mar  8 14:51:20 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/0>: got 22 bytes: '\r\n+CPIN: READY\r\n\r\nOK\r\n'
+
    Mar  8 14:51:20 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/0>: _readyToRead: watch timeout = 510
Mar  8 14:51:20 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/0>: COMPLETED 'AT+CPIN?' => ['+CPIN: READY', 'OK']
+
    Mar  8 14:51:20 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/0>: got 22 bytes: '\r\n+CPIN: READY\r\n\r\nOK\r\n'
Mar  8 14:51:20 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/0>: _readyToSend: watch timeout = None
+
    Mar  8 14:51:20 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/0>: COMPLETED 'AT+CPIN?' => ['+CPIN: READY', 'OK']
Mar  8 14:51:20 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/0>: sending 11 bytes: 'AT+CMGW=24\r'
+
    Mar  8 14:51:20 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/0>: _readyToSend: watch timeout = None
Mar  8 14:51:20 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/0>: _readyToRead: watch timeout = 513
+
    Mar  8 14:51:20 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/0>: sending 11 bytes: 'AT+CMGW=24\r'
Mar  8 14:51:20 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/0>: got 4 bytes: '\r\n> '
+
    Mar  8 14:51:20 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/0>: _readyToRead: watch timeout = 513
Mar  8 14:51:20 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/0>: COMPLETED 'AT+CMGW=24' => []
+
    Mar  8 14:51:20 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/0>: got 4 bytes: '\r\n> '
Mar  8 14:51:20 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/0>: _readyToSend: watch timeout = None
+
    Mar  8 14:51:20 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/0>: COMPLETED 'AT+CMGW=24' => []
Mar  8 14:51:20 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/0>: sending 51 bytes: '0001000B818175535081F300000CD4F29CCE02CDDF7279DE05\x1a'
+
    Mar  8 14:51:20 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/0>: _readyToSend: watch timeout = None
Mar  8 14:51:21 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/0>: _readyToRead: watch timeout = 516
+
    Mar  8 14:51:20 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/0>: sending 51 bytes: '0001000B818175535081F300000CD4F29CCE02CDDF7279DE05\x1a'
Mar  8 14:51:21 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/0>: got 18 bytes: '\r\n+CMGW: 5\r\n\r\nOK\r\n'
+
    Mar  8 14:51:21 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/0>: _readyToRead: watch timeout = 516
Mar  8 14:51:21 calvino ogsmd.modems.abstract.channel WARNING  UNHANDLED INTERMEDIATE: +CMGW: 5
+
    Mar  8 14:51:21 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/0>: got 18 bytes: '\r\n+CMGW: 5\r\n\r\nOK\r\n'
Mar  8 14:51:21 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/0>: COMPLETED '0001000B818175535081F300000CD4F29CCE02CDDF7279DE05' => ['OK']
+
    Mar  8 14:51:21 calvino ogsmd.modems.abstract.channel WARNING  UNHANDLED INTERMEDIATE: +CMGW: 5
Mar  8 14:51:21 calvino ogsmd.channel ERROR    (ignoring) unhandled exception in response callback: invalid literal for int() with base 10: 'OK' Traceback (most recent call last):  File "/usr/lib/python2.5/site-packages/framework/subsystems/ogsmd/gsm/channel.py", line 414, in handleResponseToRequest    ok_cb( reqstring.strip(), response )  File "/usr/lib/python2.5/site-packages/framework/subsystems/ogsmd/modems/abstract/mediator.py", line 1004, in responseFromChannel    self._ok( int(self._rightHandSide(response[0])) ) ValueError: invalid literal for int() with base 10: 'OK'
+
    Mar  8 14:51:21 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/0>: COMPLETED '0001000B818175535081F300000CD4F29CCE02CDDF7279DE05' => ['OK']
Mar  8 14:51:21 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/0>: _readyToSend: watch timeout = None
+
    Mar  8 14:51:21 calvino ogsmd.channel ERROR    (ignoring) unhandled exception in response callback: invalid literal for int() with base 10: 'OK' Traceback (most recent call last):  File "/usr/lib/python2.5/site-packages/framework/subsystems/ogsmd/gsm/channel.py", line 414, in handleResponseToRequest    ok_cb( reqstring.strip(), response )  File "/usr/lib/python2.5/site-packages/framework/subsystems/ogsmd/modems/abstract/mediator.py", line 1004, in responseFromChannel    self._ok( int(self._rightHandSide(response[0])) ) ValueError: invalid literal for int() with base 10: 'OK'
 +
    Mar  8 14:51:21 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/0>: _readyToSend: watch timeout = None

Latest revision as of 20:04, 8 March 2009

[edit] Backtraces

[edit] Hanging while trying to send SMS

Trying to send using StoreMessage and SendStoredMessage. Firmware moko11-beta1. frameworkd from git at c431182670962eff5ceeec485a9418a0f55aa2da. Eventually, there is a dbus timeout error, and the message remains stored on the card with status "unsent".

Mar 8 14:14:35 calvino ogsmd.modems.abstract.channel WARNING UNHANDLED INTERMEDIATE: +CMGS: 4 Mar 8 14:14:35 calvino ogsmd.channel ERROR (ignoring) unhandled exception in response callback: invalid literal for int() with base 10: 'OK' Traceback (most recent call last): File "/usr/lib/python2.5/site-packages/framework/subsystems/ogsmd/gsm/channel.py", line 414, in handleResponseToRequest ok_cb( reqstring.strip(), response ) File "/usr/lib/python2.5/site-packages/framework/subsystems/ogsmd/modems/abstract/mediator.py", line 1056, in responseFromChannel self._ok( int(mr), timestamp ) ValueError: invalid literal for int() with base 10: 'OK'

...

Mar 8 14:17:39 calvino ogsmd.modems.abstract.channel WARNING UNHANDLED INTERMEDIATE: +CMGW: 3 Mar 8 14:17:39 calvino ogsmd.channel ERROR (ignoring) unhandled exception in response callback: invalid literal for int() with base 10: 'OK' Traceback (most recent call last): File "/usr/lib/python2.5/site-packages/framework/subsystems/ogsmd/gsm/channel.py", line 414, in handleResponseToRequest ok_cb( reqstring.strip(), response ) File "/usr/lib/python2.5/site-packages/framework/subsystems/ogsmd/modems/abstract/mediator.py", line 1004, in responseFromChannel self._ok( int(self._rightHandSide(response[0])) ) ValueError: invalid literal for int() with base 10: 'OK'

...

Mar 8 14:20:23 calvino ogsmd.modems.abstract.channel WARNING UNHANDLED INTERMEDIATE: +CMGS: 5 Mar 8 14:20:23 calvino ogsmd.channel ERROR (ignoring) unhandled exception in response callback: invalid literal for int() with base 10: 'OK' Traceback (most recent call last): File "/usr/lib/python2.5/site-packages/framework/subsystems/ogsmd/gsm/channel.py", line 414, in handleResponseToRequest ok_cb( reqstring.strip(), response ) File "/usr/lib/python2.5/site-packages/framework/subsystems/ogsmd/modems/abstract/mediator.py", line 1056, in responseFromChannel self._ok( int(mr), timestamp ) ValueError: invalid literal for int() with base 10: 'OK'

...

With DEBUG on:


   Mar  8 14:51:20 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/0>: _readyToSend: watch timeout = None
   Mar  8 14:51:20 calvino ogsmd    DEBUG    (<MiscChannel via /dev/pts/0>: last communication with modem was 112 seconds ago. Sending EOF to wakeup)
   Mar  8 14:51:20 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/0>: sending 10 bytes: 'AT+CPIN?\r\n'
   Mar  8 14:51:20 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/0>: _readyToRead: watch timeout = 495
   Mar  8 14:51:20 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/0>: got 22 bytes: '\r\n+CPIN: READY\r\n\r\nOK\r\n'
   Mar  8 14:51:20 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/0>: COMPLETED 'AT+CPIN?' => ['+CPIN: READY', 'OK']
   Mar  8 14:51:20 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/0>: _readyToSend: watch timeout = None
   Mar  8 14:51:20 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/0>: sending 11 bytes: 'AT+CFUN=1\r\n'
   Mar  8 14:51:20 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/0>: _readyToRead: watch timeout = 498
   Mar  8 14:51:20 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/0>: got 6 bytes: '\r\nOK\r\n'
   Mar  8 14:51:20 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/0>: COMPLETED 'AT+CFUN=1' => ['OK']
   Mar  8 14:51:20 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/0>: _readyToSend: watch timeout = None
   Mar  8 14:51:20 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/0>: sending 10 bytes: 'AT+CPIN?\r\n'
   Mar  8 14:51:20 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/0>: _readyToRead: watch timeout = 501
   Mar  8 14:51:20 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/0>: got 22 bytes: '\r\n+CPIN: READY\r\n\r\nOK\r\n'
   Mar  8 14:51:20 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/0>: COMPLETED 'AT+CPIN?' => ['+CPIN: READY', 'OK']
   Mar  8 14:51:20 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/0>: _readyToSend: watch timeout = None
   Mar  8 14:51:20 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/0>: sending 10 bytes: 'AT+CPIN?\r\n'
   Mar  8 14:51:20 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/0>: _readyToRead: watch timeout = 504
   Mar  8 14:51:20 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/0>: got 22 bytes: '\r\n+CPIN: READY\r\n\r\nOK\r\n'
   Mar  8 14:51:20 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/0>: COMPLETED 'AT+CPIN?' => ['+CPIN: READY', 'OK']
   Mar  8 14:51:20 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/0>: _readyToSend: watch timeout = None
   Mar  8 14:51:20 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/0>: sending 11 bytes: 'AT+CFUN=1\r\n'
   Mar  8 14:51:20 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/0>: _readyToRead: watch timeout = 507
   Mar  8 14:51:20 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/0>: got 6 bytes: '\r\nOK\r\n'
   Mar  8 14:51:20 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/0>: COMPLETED 'AT+CFUN=1' => ['OK']
   Mar  8 14:51:20 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/0>: _readyToSend: watch timeout = None
   Mar  8 14:51:20 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/0>: sending 10 bytes: 'AT+CPIN?\r\n'
   Mar  8 14:51:20 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/0>: Autogenerated prefixes for command 'AT+CMGW=24\r': set(['+CMGW'])
   Mar  8 14:51:20 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/0>: Autogenerated prefixes for command '0001000B818175535081F300000CD4F29CCE02CDDF7279DE05\x1a': set([])
   Mar  8 14:51:20 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/0>: _readyToRead: watch timeout = 510
   Mar  8 14:51:20 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/0>: got 22 bytes: '\r\n+CPIN: READY\r\n\r\nOK\r\n'
   Mar  8 14:51:20 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/0>: COMPLETED 'AT+CPIN?' => ['+CPIN: READY', 'OK']
   Mar  8 14:51:20 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/0>: _readyToSend: watch timeout = None
   Mar  8 14:51:20 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/0>: sending 11 bytes: 'AT+CMGW=24\r'
   Mar  8 14:51:20 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/0>: _readyToRead: watch timeout = 513
   Mar  8 14:51:20 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/0>: got 4 bytes: '\r\n> '
   Mar  8 14:51:20 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/0>: COMPLETED 'AT+CMGW=24' => []
   Mar  8 14:51:20 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/0>: _readyToSend: watch timeout = None
   Mar  8 14:51:20 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/0>: sending 51 bytes: '0001000B818175535081F300000CD4F29CCE02CDDF7279DE05\x1a'
   Mar  8 14:51:21 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/0>: _readyToRead: watch timeout = 516
   Mar  8 14:51:21 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/0>: got 18 bytes: '\r\n+CMGW: 5\r\n\r\nOK\r\n'
   Mar  8 14:51:21 calvino ogsmd.modems.abstract.channel WARNING  UNHANDLED INTERMEDIATE: +CMGW: 5
   Mar  8 14:51:21 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/0>: COMPLETED '0001000B818175535081F300000CD4F29CCE02CDDF7279DE05' => ['OK']
   Mar  8 14:51:21 calvino ogsmd.channel ERROR    (ignoring) unhandled exception in response callback: invalid literal for int() with base 10: 'OK' Traceback (most recent call last):   File "/usr/lib/python2.5/site-packages/framework/subsystems/ogsmd/gsm/channel.py", line 414, in handleResponseToRequest     ok_cb( reqstring.strip(), response )   File "/usr/lib/python2.5/site-packages/framework/subsystems/ogsmd/modems/abstract/mediator.py", line 1004, in responseFromChannel     self._ok( int(self._rightHandSide(response[0])) ) ValueError: invalid literal for int() with base 10: 'OK'
   Mar  8 14:51:21 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/0>: _readyToSend: watch timeout = None
Personal tools

Backtraces

Hanging while trying to send SMS

Trying to send using StoreMessage and SendStoredMessage. Firmware moko11-beta1. frameworkd from git at c431182670962eff5ceeec485a9418a0f55aa2da.

Mar 8 14:14:35 calvino ogsmd.modems.abstract.channel WARNING UNHANDLED INTERMEDIATE: +CMGS: 4 Mar 8 14:14:35 calvino ogsmd.channel ERROR (ignoring) unhandled exception in response callback: invalid literal for int() with base 10: 'OK' Traceback (most recent call last): File "/usr/lib/python2.5/site-packages/framework/subsystems/ogsmd/gsm/channel.py", line 414, in handleResponseToRequest ok_cb( reqstring.strip(), response ) File "/usr/lib/python2.5/site-packages/framework/subsystems/ogsmd/modems/abstract/mediator.py", line 1056, in responseFromChannel self._ok( int(mr), timestamp ) ValueError: invalid literal for int() with base 10: 'OK'

...

Mar 8 14:17:39 calvino ogsmd.modems.abstract.channel WARNING UNHANDLED INTERMEDIATE: +CMGW: 3 Mar 8 14:17:39 calvino ogsmd.channel ERROR (ignoring) unhandled exception in response callback: invalid literal for int() with base 10: 'OK' Traceback (most recent call last): File "/usr/lib/python2.5/site-packages/framework/subsystems/ogsmd/gsm/channel.py", line 414, in handleResponseToRequest ok_cb( reqstring.strip(), response ) File "/usr/lib/python2.5/site-packages/framework/subsystems/ogsmd/modems/abstract/mediator.py", line 1004, in responseFromChannel self._ok( int(self._rightHandSide(response[0])) ) ValueError: invalid literal for int() with base 10: 'OK'

...

Mar 8 14:20:23 calvino ogsmd.modems.abstract.channel WARNING UNHANDLED INTERMEDIATE: +CMGS: 5 Mar 8 14:20:23 calvino ogsmd.channel ERROR (ignoring) unhandled exception in response callback: invalid literal for int() with base 10: 'OK' Traceback (most recent call last): File "/usr/lib/python2.5/site-packages/framework/subsystems/ogsmd/gsm/channel.py", line 414, in handleResponseToRequest ok_cb( reqstring.strip(), response ) File "/usr/lib/python2.5/site-packages/framework/subsystems/ogsmd/modems/abstract/mediator.py", line 1056, in responseFromChannel self._ok( int(mr), timestamp ) ValueError: invalid literal for int() with base 10: 'OK'

...

With DEBUG on:

Mar 8 14:51:20 calvino ogsmd.channel DEBUG <MiscChannel via /dev/pts/0>: _readyToSend: watch timeout = None Mar 8 14:51:20 calvino ogsmd DEBUG (<MiscChannel via /dev/pts/0>: last communication with modem was 112 seconds ago. Sending EOF to wakeup) Mar 8 14:51:20 calvino ogsmd.channel DEBUG <MiscChannel via /dev/pts/0>: sending 10 bytes: 'AT+CPIN?\r\n' Mar 8 14:51:20 calvino ogsmd.channel DEBUG <MiscChannel via /dev/pts/0>: _readyToRead: watch timeout = 495 Mar 8 14:51:20 calvino ogsmd.channel DEBUG <MiscChannel via /dev/pts/0>: got 22 bytes: '\r\n+CPIN: READY\r\n\r\nOK\r\n' Mar 8 14:51:20 calvino ogsmd.channel DEBUG <MiscChannel via /dev/pts/0>: COMPLETED 'AT+CPIN?' => ['+CPIN: READY', 'OK'] Mar 8 14:51:20 calvino ogsmd.channel DEBUG <MiscChannel via /dev/pts/0>: _readyToSend: watch timeout = None Mar 8 14:51:20 calvino ogsmd.channel DEBUG <MiscChannel via /dev/pts/0>: sending 11 bytes: 'AT+CFUN=1\r\n' Mar 8 14:51:20 calvino ogsmd.channel DEBUG <MiscChannel via /dev/pts/0>: _readyToRead: watch timeout = 498 Mar 8 14:51:20 calvino ogsmd.channel DEBUG <MiscChannel via /dev/pts/0>: got 6 bytes: '\r\nOK\r\n' Mar 8 14:51:20 calvino ogsmd.channel DEBUG <MiscChannel via /dev/pts/0>: COMPLETED 'AT+CFUN=1' => ['OK'] Mar 8 14:51:20 calvino ogsmd.channel DEBUG <MiscChannel via /dev/pts/0>: _readyToSend: watch timeout = None Mar 8 14:51:20 calvino ogsmd.channel DEBUG <MiscChannel via /dev/pts/0>: sending 10 bytes: 'AT+CPIN?\r\n' Mar 8 14:51:20 calvino ogsmd.channel DEBUG <MiscChannel via /dev/pts/0>: _readyToRead: watch timeout = 501 Mar 8 14:51:20 calvino ogsmd.channel DEBUG <MiscChannel via /dev/pts/0>: got 22 bytes: '\r\n+CPIN: READY\r\n\r\nOK\r\n' Mar 8 14:51:20 calvino ogsmd.channel DEBUG <MiscChannel via /dev/pts/0>: COMPLETED 'AT+CPIN?' => ['+CPIN: READY', 'OK'] Mar 8 14:51:20 calvino ogsmd.channel DEBUG <MiscChannel via /dev/pts/0>: _readyToSend: watch timeout = None Mar 8 14:51:20 calvino ogsmd.channel DEBUG <MiscChannel via /dev/pts/0>: sending 10 bytes: 'AT+CPIN?\r\n' Mar 8 14:51:20 calvino ogsmd.channel DEBUG <MiscChannel via /dev/pts/0>: _readyToRead: watch timeout = 504 Mar 8 14:51:20 calvino ogsmd.channel DEBUG <MiscChannel via /dev/pts/0>: got 22 bytes: '\r\n+CPIN: READY\r\n\r\nOK\r\n' Mar 8 14:51:20 calvino ogsmd.channel DEBUG <MiscChannel via /dev/pts/0>: COMPLETED 'AT+CPIN?' => ['+CPIN: READY', 'OK'] Mar 8 14:51:20 calvino ogsmd.channel DEBUG <MiscChannel via /dev/pts/0>: _readyToSend: watch timeout = None Mar 8 14:51:20 calvino ogsmd.channel DEBUG <MiscChannel via /dev/pts/0>: sending 11 bytes: 'AT+CFUN=1\r\n' Mar 8 14:51:20 calvino ogsmd.channel DEBUG <MiscChannel via /dev/pts/0>: _readyToRead: watch timeout = 507 Mar 8 14:51:20 calvino ogsmd.channel DEBUG <MiscChannel via /dev/pts/0>: got 6 bytes: '\r\nOK\r\n' Mar 8 14:51:20 calvino ogsmd.channel DEBUG <MiscChannel via /dev/pts/0>: COMPLETED 'AT+CFUN=1' => ['OK'] Mar 8 14:51:20 calvino ogsmd.channel DEBUG <MiscChannel via /dev/pts/0>: _readyToSend: watch timeout = None Mar 8 14:51:20 calvino ogsmd.channel DEBUG <MiscChannel via /dev/pts/0>: sending 10 bytes: 'AT+CPIN?\r\n' Mar 8 14:51:20 calvino ogsmd.channel DEBUG <MiscChannel via /dev/pts/0>: Autogenerated prefixes for command 'AT+CMGW=24\r': set(['+CMGW']) Mar 8 14:51:20 calvino ogsmd.channel DEBUG <MiscChannel via /dev/pts/0>: Autogenerated prefixes for command '0001000B818175535081F300000CD4F29CCE02CDDF7279DE05\x1a': set([]) Mar 8 14:51:20 calvino ogsmd.channel DEBUG <MiscChannel via /dev/pts/0>: _readyToRead: watch timeout = 510 Mar 8 14:51:20 calvino ogsmd.channel DEBUG <MiscChannel via /dev/pts/0>: got 22 bytes: '\r\n+CPIN: READY\r\n\r\nOK\r\n' Mar 8 14:51:20 calvino ogsmd.channel DEBUG <MiscChannel via /dev/pts/0>: COMPLETED 'AT+CPIN?' => ['+CPIN: READY', 'OK'] Mar 8 14:51:20 calvino ogsmd.channel DEBUG <MiscChannel via /dev/pts/0>: _readyToSend: watch timeout = None Mar 8 14:51:20 calvino ogsmd.channel DEBUG <MiscChannel via /dev/pts/0>: sending 11 bytes: 'AT+CMGW=24\r' Mar 8 14:51:20 calvino ogsmd.channel DEBUG <MiscChannel via /dev/pts/0>: _readyToRead: watch timeout = 513 Mar 8 14:51:20 calvino ogsmd.channel DEBUG <MiscChannel via /dev/pts/0>: got 4 bytes: '\r\n> ' Mar 8 14:51:20 calvino ogsmd.channel DEBUG <MiscChannel via /dev/pts/0>: COMPLETED 'AT+CMGW=24' => [] Mar 8 14:51:20 calvino ogsmd.channel DEBUG <MiscChannel via /dev/pts/0>: _readyToSend: watch timeout = None Mar 8 14:51:20 calvino ogsmd.channel DEBUG <MiscChannel via /dev/pts/0>: sending 51 bytes: '0001000B818175535081F300000CD4F29CCE02CDDF7279DE05\x1a' Mar 8 14:51:21 calvino ogsmd.channel DEBUG <MiscChannel via /dev/pts/0>: _readyToRead: watch timeout = 516 Mar 8 14:51:21 calvino ogsmd.channel DEBUG <MiscChannel via /dev/pts/0>: got 18 bytes: '\r\n+CMGW: 5\r\n\r\nOK\r\n' Mar 8 14:51:21 calvino ogsmd.modems.abstract.channel WARNING UNHANDLED INTERMEDIATE: +CMGW: 5 Mar 8 14:51:21 calvino ogsmd.channel DEBUG <MiscChannel via /dev/pts/0>: COMPLETED '0001000B818175535081F300000CD4F29CCE02CDDF7279DE05' => ['OK'] Mar 8 14:51:21 calvino ogsmd.channel ERROR (ignoring) unhandled exception in response callback: invalid literal for int() with base 10: 'OK' Traceback (most recent call last): File "/usr/lib/python2.5/site-packages/framework/subsystems/ogsmd/gsm/channel.py", line 414, in handleResponseToRequest ok_cb( reqstring.strip(), response ) File "/usr/lib/python2.5/site-packages/framework/subsystems/ogsmd/modems/abstract/mediator.py", line 1004, in responseFromChannel self._ok( int(self._rightHandSide(response[0])) ) ValueError: invalid literal for int() with base 10: 'OK' Mar 8 14:51:21 calvino ogsmd.channel DEBUG <MiscChannel via /dev/pts/0>: _readyToSend: watch timeout = None