User:JohnSullivan/Backtraces
From Openmoko
JohnSullivan (Talk | contribs) (→Hanging while trying to send SMS: with debug on) |
JohnSullivan (Talk | contribs) (→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 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