View Single Post
epage's Avatar
Posts: 1,684 | Thanked: 1,562 times | Joined on Jun 2008 @ Austin, TX
#319
Good
Code:
(07:48:33) INFO:connection:Kicking off disconnect
(07:48:33) INFO:connection:Disconnecting
(07:48:34) INFO:autogv:Stopping voicemail refresh
(07:48:34) INFO:autogv:Cancelling auto-log off
(07:48:36) INFO:gvoice.state_machine:Addressbook Stopping State Machine
(07:48:36) INFO:gvoice.state_machine:Voicemail Stopping State Machine
(07:48:36) INFO:gvoice.state_machine:Texting Stopping State Machine
(07:48:36) INFO:connection:Disconnected
(07:48:39) INFO:connection_manager:Connection manager quitting
(07:48:54) INFO:root:telepathy-theonering 0.8.1-0
(07:48:54) DEBUG:root:OS: Linux
(07:48:54) DEBUG:root:Kernel: 2.6.28-omap1 (#1 PREEMPT Thu Dec 17 09:40:52 EET 2009) for armv7l
(07:48:54) DEBUG:root:Hostname: Nokia-N900-42-11
(07:48:54) INFO:connection_manager:Connection manager created
(07:48:54) DEBUG:handle:Created Handle: <ConnectionHandle id=1 name='USERNAME'> (Is New!)
(07:48:54) INFO:connection:Connection to the account USERNAME created
(07:48:54) INFO:connection:Kicking off connect
(07:48:54) INFO:connection:Connecting...
Bad
Code:
(11:18:50) INFO:connection:Kicking off disconnect
(11:18:50) INFO:connection:Disconnecting
(11:18:50) INFO:autogv:Stopping voicemail refresh
(11:18:50) INFO:autogv:Cancelling auto-log off
(11:18:56) INFO:gvoice.state_machine:Addressbook Stopping State Machine
(11:18:56) INFO:gvoice.state_machine:Voicemail Stopping State Machine
(11:18:56) INFO:gvoice.state_machine:Texting Stopping State Machine
(11:18:56) INFO:connection:Disconnected
(11:18:56) INFO:connection_manager:Connection manager quitting
(11:19:25) INFO:root:telepathy-theonering 0.8.1-0
(11:19:25) DEBUG:root:OS: Linux
(11:19:25) DEBUG:root:Kernel: 2.6.28-omap1 (#1 PREEMPT Thu Dec 17 09:40:52 EET 2009) for armv7l
(11:19:25) DEBUG:root:Hostname: Nokia-N900-42-11
(11:19:25) INFO:connection_manager:Connection manager created
(11:19:26) DEBUG:handle:Created Handle: <ConnectionHandle id=1 name='USERNAME'> (Is New!)
(11:19:26) INFO:connection:Connection to the account USERNAME created
(12:59:29) ERROR:connection:__init__
Traceback (most recent call last):
  File "/usr/lib/theonering/util/misc.py", line 48, in wrapper
    return func(*args, **kwds)
  File "/usr/lib/theonering/connection.py", line 111, in __init__
    constants._telepathy_implementation_name_
  File "/usr/lib/theonering/tp/conn.py", line 82, in __init__
    _Connection.__init__(self, bus_name, object_path)
  File "/usr/lib/pymodules/python2.5/dbus/service.py", line 480, in __init__
    self.add_to_connection(conn, object_path)
  File "/usr/lib/pymodules/python2.5/dbus/service.py", line 571, in add_to_connection
    self._fallback)
KeyError: "Can't register the object-path handler for '/org/freedesktop/Telepathy/Connection/theonering/sip/USERNAME': there is already a handler"
Between
Code:
INFO:autogv:Cancelling auto-log off
and
Code:
INFO:gvoice.state_machine:Addressbook Stopping State Machine
In my logs I've seen it take from 2-4 seconds on good disconnects on both my n810 and n900. On my desktop I've never seen it take more than a second. On a bad disconnect it took 6 seconds.

Code:
(11:18:56) INFO:connection_manager:Connection manager quitting
My service shutdown. I assume anytime my service shutsdown, all of the resources are cleaned up regardless of how sloppy I am.

The service is then started 30 seconds later

Code:
(11:19:26) INFO:connection:Connection to the account USERNAME created
The first phase of initialization is ran.

Code:
(12:59:29) ERROR:connection:__init__
1 hour 30 minutes later the first phase of initialization happens again but fails because the (11:19:26) occurrence was never cleaned up.

It seems very strange for the second phase of initialization to have never completed
__________________
770, n810, n900, Ideapad S10-3t
TheOneRing, DialCentral, Gonvert, Quicknote, Multilist, ejpi, nQa, Waters of Shiloah
Programming Blog
 

The Following User Says Thank You to epage For This Useful Post: