org.freedesktop.DBus.Error.NoReply messages when trying to interact with syncdaemon via u1sdtool

[Jeff Lane ]

Binary package hint: ubuntuone-client

While trying to add a folder to Ubuntu One using u1sdtool, I noticed that I kept getting these dbus errors... finally I tried

u1sdtool -q

and that failed to actually stop the syncdaemon.

So I killed off the syncdaemon manually and tried restarting but this is what happened:

bladernr@klaatu:~/development/checkbox/jobs$ u1sdtool --start

Oops, an error ocurred:
Traceback (most recent call last):
Failure: dbus.exceptions.DBusException: org.freedesktop.DBus.Error.NoReply: Did not receive a reply. Possible causes include: the remote application did not send a reply, the message bus security policy blocked the reply, the reply timeout expired, or the network connection was broken.

The dbus timeout errors are the same ones I got previously when I tried to do ANYTHING with u1sdtool while syncdaemon was running. Unfortunately, I think the only way to get a working system again is to reboot, which not an optimal fix for whatever is causing this problem.

First, this is what happened when I tried subscribing a folder to be synced:

bladernr@klaatu:~/development/checkbox/jobs$ u1sdtool --subscribe-folder=/home/bladernr/Notes/

Oops, an error ocurred:
Traceback (most recent call last):
  File "/usr/lib/python2.6/dist-packages/twisted/internet/gtk2reactor.py", line 270, in run
    self.__run()
  File "/usr/lib/pymodules/python2.6/ubuntuone/syncdaemon/tools.py", line 92, in parse_reply
    *message.get_args_list()))
  File "/usr/lib/python2.6/dist-packages/twisted/internet/defer.py", line 345, in errback
    self._startRunCallbacks(fail)
  File "/usr/lib/python2.6/dist-packages/twisted/internet/defer.py", line 424, in _startRunCallbacks
    self._runCallbacks()
--- <exception caught here> ---
  File "/usr/lib/python2.6/dist-packages/twisted/internet/defer.py", line 441, in _runCallbacks
    self.result = callback(self.result, *args, **kw)
  File "/usr/bin/u1sdtool", line 124, in <lambda>
    d.addErrback(lambda r: show_error(r, out))
  File "/usr/lib/pymodules/python2.6/ubuntuone/syncdaemon/tools.py", line 794, in show_error
    raise error.value
dbus.exceptions.DBusException: org.freedesktop.DBus.Error.NoReply: Did not receive a reply. Possible causes include: the remote application did not send a reply, the message bus security policy blocked the reply, the reply timeout expired, or the network connection was broken.

Then this:
bladernr@klaatu:~/development/checkbox/jobs$ u1sdtool -s

Oops, an error ocurred:
Traceback (most recent call last):
Failure: dbus.exceptions.DBusException: org.freedesktop.DBus.Error.NoReply: Did not receive a reply. Possible causes include: the remote application did not send a reply, the message bus security policy blocked the reply, the reply timeout expired, or the network connection was broken.

Then this:
bladernr@klaatu:~/development/checkbox/jobs$ u1sdtool -q
ubuntuone-syncdaemon stopped.

Though a ps axf showed that ubuntuone-syncdaemon was still running.

So I killed it using kill -9

then when I tried restarting, I got the error mentioned above at the beginning of this narrative.

ProblemType: Bug
DistroRelease: Ubuntu 10.10
Package: ubuntuone-client 1.4.4.1-0ubuntu1
ProcVersionSignature: Ubuntu 2.6.35-22.35-generic 2.6.35.4
Uname: Linux 2.6.35-22-generic x86_64
NonfreeKernelModules: nvidia
Architecture: amd64
Date: Mon Nov 8 23:10:46 2010
InstallationMedia: Ubuntu 9.10 "Karmic Koala" - Release amd64 (20091027)
PackageArchitecture: all
ProcEnviron:
 LANG=en_US.utf8
 SHELL=/bin/bash
SourcePackage: ubuntuone-client
UbuntuOneClientConfig:
 [ubuntuone]
 bookmarked = True
 connected = False
 connect = 0
 show_applet = 0
UbuntuOneOAuthLoginLog: 2010-06-09 12:52:38,627:627.223968506 ubuntuone-login Starting Ubuntu One login manager version 1.2.1
UbuntuOneSyncdaemonExceptionsLog:

UbuntuOneUserSyncdaemonConfig:
 [bandwidth_throttling]
 read_limit = 2097152
 write_limit = 2048
 on = False

[Jeff Lane  (bladernr)]

Just rebooted to see if that helped. Immediately after reboot, this is what I get:

ubladernr@klaatu:~$ u1sdtool -s

Oops, an error ocurred:
Traceback (most recent call last):
Failure: dbus.exceptions.DBusException: org.freedesktop.DBus.Error.NoReply: Did not receive a reply. Possible causes include: the remote application did not send a reply, the message bus security policy blocked the reply, the reply timeout expired, or the network connection was broken.
bladernr@klaatu:~$ u1sdtool -s

Oops, an error ocurred:
Traceback (most recent call last):
Failure: dbus.exceptions.DBusException: org.freedesktop.DBus.Error.TimedOut: Activation of com.ubuntuone.SyncDaemon timed out
bladernr@klaatu:~$ u1sdtool -s

Oops, an error ocurred:
Traceback (most recent call last):
Failure: dbus.exceptions.DBusException: org.freedesktop.DBus.Error.NoReply: Did not receive a reply. Possible causes include: the remote application did not send a reply, the message bus security policy blocked the reply, the reply timeout expired, or the network connection was broken.
bladernr@klaatu:~$ u1sdtool -c

Oops, an error ocurred:
Traceback (most recent call last):
Failure: dbus.exceptions.DBusException: org.freedesktop.DBus.Error.NoReply: Did not receive a reply. Possible causes include: the remote application did not send a reply, the message bus security policy blocked the reply, the reply timeout expired, or the network connection was broken.

So even after a fresh reboot I am unable to use u1sdtool to do anything... and since the "Sync with Ubuntu One" option has disappeared from the left click menu when in File Manager, I have NO way at all of adding new folders other than moving them into the Ubuntu One folder, which I really do not wish to do.

[Jarek Sobiecki (harijari)]

I can confirm this problem. Is there any workaround, or solution how to force ubuntu one to work again?

[Laszlo (ltorokjr)]

I can also confirm the issue. I'm running an Ubuntu 10.10 32bit Desktop Edition on my laptop as a virtual machine. (virtualcomputer.com)

[Duane Hinnen (duanedesign)]

Jeff,
This happens because of a less then optimal way metadata is handled in versions prior to what is now in nightlies and Natty Narwhal. Basically that's bug LP:436612. Anyone who has a fairly large number of files in their Ubuntu One directory I believe would want to try the nightlies PPA (please note that downgrade to older metadata storage is not possible).

If you are interested in testing the new improved metadata storage (which takes syncdaemon about a second or two to start) then please have a look at https://launchpad.net/~ubuntuone/+archive/nightlies. Please note that the nightlies releases are only automatically tested and there is a possibility that not all features are working at all times.

The commands to add the PPA:

sudo add-apt-repository ppa:ubuntuone/nightlies

sudo apt-get update; sudo apt-get upgrade

thank you,
duanedesign

[Launchpad Janitor (janitor)]

[Expired for ubuntuone-client (Ubuntu) because there has been no activity for 60 days.]

[Andrei Sosnin (deemoowoor)]

I'd like to add, that this bug seems to also affect normal nautilus functionality. I discovered this issue after failing to run Nautilus normally. When I run the Ubuntu One client and try to check the 'Cloud Folders' and 'Devices' tabs, it shows me the same error in red. If I can get you any kind of logs, apart from the above mentioned stuff, I will gladly help.

I don't have many files in my Ubuntu One account and only two shared directories are specified.

This issue appeared suddenly without any explicit changes on my part.

[Andrei Sosnin (deemoowoor)]

Here's my ~/.cache/ubuntuone/log/syncdaemon.log:

2011-09-16 15:30:14,915 - ubuntuone.SyncDaemon.Main - INFO - Starting Ubuntu One client version 1.6.2
2011-09-16 15:30:14,915 - ubuntuone.SyncDaemon.tritcask - INFO - Initializing Tritcask on: /home/asosnin/.local/share/ubuntuone/syncdaemon/tritcask
2011-09-16 15:30:14,915 - ubuntuone.SyncDaemon.tritcask - INFO - found 3 data files and 0 dead files
2011-09-16 15:30:14,918 - ubuntuone.SyncDaemon.tritcask - INFO - keydir ready! (keys: 52)
2011-09-16 15:30:14,919 - ubuntuone.SyncDaemon.fsm - INFO - loading updated metadata
2011-09-16 15:30:14,924 - ubuntuone.SyncDaemon.fsm - INFO - initialized: idx_path: 50, idx_node_id: 50, shares: 2
2011-09-16 15:30:14,992 - ubuntuone.SyncDaemon.filesystem_notifications.GeneralProcessor - INFO - Ignoring files: ['\\A#.*\\Z', '\\A.*~\\Z', '\\A.*\\.py[oc]\\Z', '\\A.*\\.sw[nopx]\\Z', '\\A.*\\.swpx\\Z', '\\A\\..*\\.tmp\\Z', '\\A\\.~lock\\..*#\\Z']
2011-09-16 15:30:15,055 - ubuntuone.SyncDaemon.HQ - INFO - HashQueue: _hasher started

I also have some seemingly strange behavior in controlpanel.log:

2011-09-16 15:33:21,356 - ubuntuone.controlpanel.dbus_service - DEBUG - FileSyncStatusChanged: args (<ubuntuone.controlpanel.dbus_service.ControlPanelBackend at /preferences at 0x22ee050>, 'file-sync-starting'), kwargs {}.
2011-09-16 15:33:21,357 - ubuntuone.controlpanel.gtk.gui - INFO - on_file_sync_status_starting: args (<FileSyncStatus object at 0x28457d0 (GtkHBox at 0x29c14e0)>, dbus.String(u'doing local rescan (LOCAL_RESCAN)')), kwargs {}.
2011-09-16 15:33:21,358 - ubuntuone.controlpanel.gtk.gui - DEBUG - on_file_sync_status_changed: args (<FileSyncService object at 0x29fd460 (GtkVBox at 0x29f8e70)>, dbus.String(u'file-sync-starting')), kwargs {}.
2011-09-16 15:33:21,358 - ubuntuone.controlpanel.gtk.gui - INFO - FileSyncService: on_file_sync_status_changed: status dbus.String(u'file-sync-starting'), enabled? True
2011-09-16 15:33:22,423 - ubuntuone.controlpanel.dbus_service - INFO - process_status: new status received {'status': 'file-sync-starting', 'message': u'doing local rescan (LOCAL_RESCAN)'}
2011-09-16 15:33:22,423 - ubuntuone.controlpanel.dbus_service - DEBUG - FileSyncStatusStarting: args (<ubuntuone.controlpanel.dbus_service.ControlPanelBackend at /preferences at 0x22ee050>, u'doing local rescan (LOCAL_RESCAN)'), kwargs {}.
2011-09-16 15:33:22,424 - ubuntuone.controlpanel.dbus_service - DEBUG - FileSyncStatusChanged: args (<ubuntuone.controlpanel.dbus_service.ControlPanelBackend at /preferences at 0x22ee050>, 'file-sync-starting'), kwargs {}.
2011-09-16 15:33:22,425 - ubuntuone.controlpanel.gtk.gui - INFO - on_file_sync_status_starting: args (<FileSyncStatus object at 0x28457d0 (GtkHBox at 0x29c14e0)>, dbus.String(u'doing local rescan (LOCAL_RESCAN)')), kwargs {}.
2011-09-16 15:33:22,430 - ubuntuone.controlpanel.gtk.gui - DEBUG - on_file_sync_status_changed: args (<FileSyncService object at 0x29fd460 (GtkVBox at 0x29f8e70)>, dbus.String(u'file-sync-starting')), kwargs {}.
2011-09-16 15:33:22,430 - ubuntuone.controlpanel.gtk.gui - INFO - FileSyncService: on_file_sync_status_changed: status dbus.String(u'file-sync-starting'), enabled? True
2011-09-16 15:33:23...

[Andrei Sosnin (deemoowoor)]

This is what it looks when I called

$ cat syncdaemon-exceptions.log*
[...]
2011-09-16 15:36:06,047 - ubuntuone.SyncDaemon - ERROR - Unexpected error
Traceback (most recent call last):
  File "/usr/lib/ubuntuone-client/ubuntuone-syncdaemon", line 190, in <module>
    main(sys.argv)
  File "/usr/lib/ubuntuone-client/ubuntuone-syncdaemon", line 175, in main
    main.start()
  File "/usr/lib/pymodules/python2.7/ubuntuone-client/ubuntuone/syncdaemon/main.py", line 200, in start
    self.event_q.add_watch(self.root_dir)
  File "/usr/lib/pymodules/python2.7/ubuntuone-client/ubuntuone/syncdaemon/event_queue.py", line 215, in add_watch
    self.monitor.add_watch(dirpath)
  File "/usr/lib/pymodules/python2.7/ubuntuone-client/ubuntuone/platform/linux/filesystem_notifications.py", line 459, in add_watch
    w_dict[dirpath] = result[dirpath]
KeyError: '/home/asosnin/Ubuntu One'
2011-09-16 15:36:07,087 - ubuntuone.SyncDaemon - ERROR - Unexpected error
Traceback (most recent call last):
  File "/usr/lib/ubuntuone-client/ubuntuone-syncdaemon", line 190, in <module>
    main(sys.argv)
  File "/usr/lib/ubuntuone-client/ubuntuone-syncdaemon", line 175, in main
    main.start()
  File "/usr/lib/pymodules/python2.7/ubuntuone-client/ubuntuone/syncdaemon/main.py", line 200, in start
    self.event_q.add_watch(self.root_dir)
  File "/usr/lib/pymodules/python2.7/ubuntuone-client/ubuntuone/syncdaemon/event_queue.py", line 215, in add_watch
    self.monitor.add_watch(dirpath)
  File "/usr/lib/pymodules/python2.7/ubuntuone-client/ubuntuone/platform/linux/filesystem_notifications.py", line 459, in add_watch
    w_dict[dirpath] = result[dirpath]
KeyError: '/home/asosnin/Ubuntu One'
2011-09-16 15:36:08,167 - ubuntuone.SyncDaemon - ERROR - Unexpected error
Traceback (most recent call last):
  File "/usr/lib/ubuntuone-client/ubuntuone-syncdaemon", line 190, in <module>
    main(sys.argv)
  File "/usr/lib/ubuntuone-client/ubuntuone-syncdaemon", line 175, in main
    main.start()
  File "/usr/lib/pymodules/python2.7/ubuntuone-client/ubuntuone/syncdaemon/main.py", line 200, in start
    self.event_q.add_watch(self.root_dir)
  File "/usr/lib/pymodules/python2.7/ubuntuone-client/ubuntuone/syncdaemon/event_queue.py", line 215, in add_watch
    self.monitor.add_watch(dirpath)
  File "/usr/lib/pymodules/python2.7/ubuntuone-client/ubuntuone/platform/linux/filesystem_notifications.py", line 459, in add_watch
    w_dict[dirpath] = result[dirpath]
KeyError: '/home/asosnin/Ubuntu One'

This is repeating every second or even more often.

[Andrei Sosnin (deemoowoor)]

$ rm -rf '~/Ubuntu One'
didn't help.

[dobey (dobey)]

Morgus, please DO NOT change bugs from expired to confirmed.

Andrei, please file a new bug by running 'ubuntu-bug ubuntuone-client' from the terminal, and attaching your logs to it instead.

[Andrei Sosnin (deemoowoor)]

Filed it as Bug #851932 "File synchronization fails with DBusException NoReply"

[Launchpad Janitor (janitor)]

[Expired for ubuntuone-client (Ubuntu) because there has been no activity for 60 days.]