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)
 
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

Latest revision as of 21: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
Retrieved from "http://wiki.openmoko.org/index.php?title=User:JohnSullivan/Backtraces&oldid=67421"
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