[Module ][WARNING] Error loading the file: /home/john/gnome-bzr/src/conduit/conduit/modules/OSyncEvoModule.py Traceback (most recent call last): File "/home/john/gnome-bzr/src/conduit/conduit/Module.py", line 173, in _load_modules_in_file mod = self._import_file(filename) File "/home/john/gnome-bzr/src/conduit/conduit/Module.py", line 154, in _import_file mods = pydoc.importfile (filename) File "/usr/lib/python2.5/pydoc.py", line 259, in importfile raise ErrorDuringImport(path, sys.exc_info()) ErrorDuringImport: problem in /home/john/gnome-bzr/src/conduit/conduit/modules/OSyncEvoModule.py - : (Module.py:201) [modules.Feed ][INFO ] Module Information: feedparser v4.1 (/var/lib/python-support/python2.5/feedparser.pyc) (FeedModule.py:20) [modules.Feed ][INFO ] Patching feedparser issue #100 (FeedModule.py:26) [modules.Backpack ][INFO ] Module Information: backpack (/home/john/gnome-bzr/src/conduit/conduit/modules/BackpackModule/backpack/backpack.pyc) (BackpackModule.py:22) [dataproviders.SimpleFactory][INFO ] HAL Initialized (VolumeFactory.py:23) [modules.Evolution ][INFO ] Module Information: evolution v(2, 2, 2) (/var/lib/python-support/python2.5/gtk-2.0/evolution/__init__.pyc) (EvolutionModule.py:25) [modules.Flickr ][INFO ] Module Information: flickrapi v1.1 (/home/john/gnome-bzr/src/conduit/conduit/modules/FlickrModule/flickrapi/__init__.pyc) (FlickrModule.py:26) [modules.Network ][INFO ] Network support enabled (NetworkModule.py:30) [modules.Network ][DEBUG ] Starting AvahiAdvertiser server (Server.py:35) [modules.Network ][DEBUG ] Announcing avahi conduit service (Peers.py:159) [modules.Network ][WARNING] Error starting AvahiAdvertiser server: (98, 'Address already in use') (Server.py:52) [modules.Google ][INFO ] Module Information: gdata (/home/john/gnome-bzr/src/conduit/conduit/modules/GoogleModule/gdata/__init__.pyc) (GoogleModule.py:45) [dataproviders.SimpleFactory][INFO ] HAL Initialized (VolumeFactory.py:23) [modules.iPod ][INFO ] Module Information: gpod v(0, 6, 0) (/usr/lib/python2.5/site-packages/gpod/__init__.pyc) (iPodModule.py:35) [dataproviders.SimpleFactory][INFO ] HAL Initialized (VolumeFactory.py:23) [modules.Picasa ][INFO ] Picasa desktop directory detected (PicasaDesktopModule.py:25) [modules.Phone ][INFO ] Module Information: gammu vGammu 1.15.0, python-gammu 0.24 (/usr/lib/python2.5/site-packages/gammu/__init__.pyc) (Gammu.py:16) [modules.Facebook ][INFO ] Module Information: pyfacebook v0.1 (/home/john/gnome-bzr/src/conduit/conduit/modules/FacebookModule/pyfacebook/__init__.pyc) (FacebookModule.py:30) [modules.Phone ][INFO ] Starting Scan Threads (PhoneModule.py:88) [modules.Phone ][INFO ] Beginning Bluetooth Scan (Gammu.py:110) [modules.Phone ][WARNING] Error discovering services (Gammu.py:133) [Conduit ][DEBUG ] Adding twoway dataprovider into source position (Conduit.py:140) [Conduit ][DEBUG ] Adding twoway dataprovider into sink position (Conduit.py:146) [datatypes.File ][DEBUG ] New tempfile created at /tmp/conduitZ7X6L- (File.py:545) [datatypes.File ][DEBUG ] Defering rename till transfer (New name: 66836 UNICODE Ü) (File.py:112) [datatypes.File ][DEBUG ] Using deferred filename in transfer (File.py:293) [datatypes.File ][DEBUG ] Transfering File file:///tmp/conduitZ7X6L- -> file:///home/john/gnome-bzr/src/conduit/test/test-data/source/66836%20UNICODE%20%C3%9C (File.py:302) [datatypes.File ][DEBUG ] Closing file (File.py:58) [datatypes.File ][DEBUG ] Clearing pending rename (File.py:65) [datatypes.File ][DEBUG ] New tempfile created at /tmp/conduitfFT8mP (File.py:545) [datatypes.File ][DEBUG ] Defering rename till transfer (New name: 353104 UNICODE á) (File.py:112) [datatypes.File ][DEBUG ] Using deferred filename in transfer (File.py:293) [datatypes.File ][DEBUG ] Transfering File file:///tmp/conduitfFT8mP -> file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/353104%20UNICODE%20%C3%A1 (File.py:302) [datatypes.File ][DEBUG ] Closing file (File.py:58) [datatypes.File ][DEBUG ] Clearing pending rename (File.py:65) [datatypes.File ][DEBUG ] New tempfile created at /tmp/conduitkAUmki (File.py:545) [datatypes.File ][DEBUG ] Defering rename till transfer (New name: 36354 UNICODE Ü) (File.py:112) [datatypes.File ][DEBUG ] Using deferred filename in transfer (File.py:293) [datatypes.File ][DEBUG ] Transfering File file:///tmp/conduitkAUmki -> file:///home/john/gnome-bzr/src/conduit/test/test-data/source/36354%20UNICODE%20%C3%9C (File.py:302) [datatypes.File ][DEBUG ] Closing file (File.py:58) [datatypes.File ][DEBUG ] Clearing pending rename (File.py:65) [datatypes.File ][DEBUG ] New tempfile created at /tmp/conduitRP2TDW (File.py:545) [datatypes.File ][DEBUG ] Defering rename till transfer (New name: 317110 UNICODE Ä) (File.py:112) [datatypes.File ][DEBUG ] Using deferred filename in transfer (File.py:293) [datatypes.File ][DEBUG ] Transfering File file:///tmp/conduitRP2TDW -> file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/317110%20UNICODE%20%C3%84 (File.py:302) [datatypes.File ][DEBUG ] Closing file (File.py:58) [datatypes.File ][DEBUG ] Clearing pending rename (File.py:65) [datatypes.File ][DEBUG ] New tempfile created at /tmp/conduit3ZCBqK (File.py:545) [datatypes.File ][DEBUG ] Defering rename till transfer (New name: 11070 UNICODE Ü) (File.py:112) [datatypes.File ][DEBUG ] Using deferred filename in transfer (File.py:293) [datatypes.File ][DEBUG ] Transfering File file:///tmp/conduit3ZCBqK -> file:///home/john/gnome-bzr/src/conduit/test/test-data/source/11070%20UNICODE%20%C3%9C (File.py:302) [datatypes.File ][DEBUG ] Closing file (File.py:58) [datatypes.File ][DEBUG ] Clearing pending rename (File.py:65) [datatypes.File ][DEBUG ] New tempfile created at /tmp/conduitYphO0q (File.py:545) [datatypes.File ][DEBUG ] Defering rename till transfer (New name: 411045 UNICODE Ä) (File.py:112) [datatypes.File ][DEBUG ] Using deferred filename in transfer (File.py:293) [datatypes.File ][DEBUG ] Transfering File file:///tmp/conduitYphO0q -> file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/411045%20UNICODE%20%C3%84 (File.py:302) [datatypes.File ][DEBUG ] Closing file (File.py:58) [datatypes.File ][DEBUG ] Clearing pending rename (File.py:65) [datatypes.File ][DEBUG ] New tempfile created at /tmp/conduit6Zf4dP (File.py:545) [datatypes.File ][DEBUG ] Defering rename till transfer (New name: 08357 UNICODE Ë) (File.py:112) [datatypes.File ][DEBUG ] Using deferred filename in transfer (File.py:293) [datatypes.File ][DEBUG ] Transfering File file:///tmp/conduit6Zf4dP -> file:///home/john/gnome-bzr/src/conduit/test/test-data/source/08357%20UNICODE%20%C3%8B (File.py:302) [datatypes.File ][DEBUG ] Closing file (File.py:58) [datatypes.File ][DEBUG ] Clearing pending rename (File.py:65) [datatypes.File ][DEBUG ] New tempfile created at /tmp/conduitthCAy4 (File.py:545) [datatypes.File ][DEBUG ] Defering rename till transfer (New name: 77714 UNICODE Ü) (File.py:112) [datatypes.File ][DEBUG ] Using deferred filename in transfer (File.py:293) [datatypes.File ][DEBUG ] Transfering File file:///tmp/conduitthCAy4 -> file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/77714%20UNICODE%20%C3%9C (File.py:302) [datatypes.File ][DEBUG ] Closing file (File.py:58) [datatypes.File ][DEBUG ] Clearing pending rename (File.py:65) [datatypes.File ][DEBUG ] New tempfile created at /tmp/conduit59hUa6 (File.py:545) [datatypes.File ][DEBUG ] Defering rename till transfer (New name: 71664 UNICODE æ) (File.py:112) [datatypes.File ][DEBUG ] Using deferred filename in transfer (File.py:293) [datatypes.File ][DEBUG ] Transfering File file:///tmp/conduit59hUa6 -> file:///home/john/gnome-bzr/src/conduit/test/test-data/source/71664%20UNICODE%20%C3%A6 (File.py:302) [datatypes.File ][DEBUG ] Closing file (File.py:58) [datatypes.File ][DEBUG ] Clearing pending rename (File.py:65) [datatypes.File ][DEBUG ] New tempfile created at /tmp/conduit9vN7Dh (File.py:545) [datatypes.File ][DEBUG ] Defering rename till transfer (New name: 43737 UNICODE é) (File.py:112) [datatypes.File ][DEBUG ] Using deferred filename in transfer (File.py:293) [datatypes.File ][DEBUG ] Transfering File file:///tmp/conduit9vN7Dh -> file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/43737%20UNICODE%20%C3%A9 (File.py:302) [datatypes.File ][DEBUG ] Closing file (File.py:58) [datatypes.File ][DEBUG ] Clearing pending rename (File.py:65) [Vfs ][DEBUG ] Folder scan 100.0% complete (Vfs.py:508) [Vfs ][DEBUG ] 5 files loaded in 0.00349807739258 seconds (Vfs.py:516) [Vfs ][DEBUG ] Folder scan 100.0% complete (Vfs.py:508) [Vfs ][DEBUG ] 5 files loaded in 0.00281000137329 seconds (Vfs.py:516) [Conduit ][DEBUG ] Disabling Two Way Sync (Conduit.py:249) [Syncronization ][DEBUG ] Created thread (thread: -1210054464) (Synchronization.py:211) [Syncronization ][INFO ] Setting global cancel flag (Synchronization.py:90) [Syncronization ][DEBUG ] Starting worker: [], initial)> (Synchronization.py:93) [Syncronization ][INFO ] Waiting for thread to finish (Synchronization.py:124) [Syncronization ][DEBUG ] Started thread [], started)> (thread: -1263223920) (Synchronization.py:624) [Syncronization ][DEBUG ] Sync [], started)> beginning. Slow: False, Twoway: False (Synchronization.py:629) [Syncronization ][DEBUG ] Syncworker state 0 (Synchronization.py:648) [Syncronization ][DEBUG ] Syncworker state 1 (Synchronization.py:648) [Syncronization ][DEBUG ] Source Status = Synchronizing... (Synchronization.py:677) [Vfs ][DEBUG ] Folder scan 100.0% complete (Vfs.py:508) [Vfs ][DEBUG ] 5 files loaded in 0.00286507606506 seconds (Vfs.py:516) [Vfs ][DEBUG ] Folder scan 100.0% complete (Vfs.py:508) [Vfs ][DEBUG ] 5 files loaded in 0.00330996513367 seconds (Vfs.py:516) [Syncronization ][DEBUG ] Syncworker state 2 (Synchronization.py:648) [Syncronization ][INFO ] Synchronizing Wrapper: source twoway (UID: FolderTwoWay-file:///home/john/gnome-bzr/src/conduit/test/test-data/source) |--> Wrapper: sink twoway (UID: FolderTwoWay-file:///home/john/gnome-bzr/src/conduit/test/test-data/sink) (Synchronization.py:467) [DeltaProvider ][INFO ] Delta: Source (FolderTwoWay-file:///home/john/gnome-bzr/src/conduit/test/test-data/source) does not implement get_changes(). Proxying... (DeltaProvider.py:25) [DeltaProvider ][DEBUG ] Delta: Got 5 items [u'file:///home/john/gnome-bzr/src/conduit/test/test-data/source/66836 UNICODE \xdc', u'file:///home/john/gnome-bzr/src/conduit/test/test-data/source/36354 UNICODE \xdc', u'file:///home/john/gnome-bzr/src/conduit/test/test-data/source/11070 UNICODE \xdc', u'file:///home/john/gnome-bzr/src/conduit/test/test-data/source/08357 UNICODE \xcb', u'file:///home/john/gnome-bzr/src/conduit/test/test-data/source/71664 UNICODE \xe6'] (DeltaProvider.py:39) [DeltaProvider ][DEBUG ] Delta: Expecting 0 items (DeltaProvider.py:49) [Syncronization ][DEBUG ] FolderTwoWay-file:///home/john/gnome-bzr/src/conduit/test/test-data/source Changes: New 5 items [u'file:///home/john/gnome-bzr/src/conduit/test/test-data/source/66836 UNICODE \xdc', u'file:///home/john/gnome-bzr/src/conduit/test/test-data/source/36354 UNICODE \xdc', u'file:///home/john/gnome-bzr/src/conduit/test/test-data/source/11070 UNICODE \xdc', u'file:///home/john/gnome-bzr/src/conduit/test/test-data/source/08357 UNICODE \xcb', u'file:///home/john/gnome-bzr/src/conduit/test/test-data/source/71664 UNICODE \xe6'] (Synchronization.py:241) [Syncronization ][DEBUG ] FolderTwoWay-file:///home/john/gnome-bzr/src/conduit/test/test-data/source Changes: Modified 0 items [] (Synchronization.py:242) [Syncronization ][DEBUG ] FolderTwoWay-file:///home/john/gnome-bzr/src/conduit/test/test-data/source Changes: Deleted 0 items [] (Synchronization.py:243) [Syncronization ][DEBUG ] 1WAY PUT: Folder (file:///home/john/gnome-bzr/src/conduit/test/test-data/source/66836 UNICODE Ü) -----> Folder (Synchronization.py:489) [datatypes.DataType ][DEBUG ] Getting Rid for file:///home/john/gnome-bzr/src/conduit/test/test-data/source/66836 UNICODE Ü (DataType.py:151) [TypeConverter ][DEBUG ] Convert file -> file using [('file', 'file', {})] (TypeConverter.py:210) [TypeConverter ][DEBUG ] Skipping file -> file (TypeConverter.py:169) [datatypes.File ][DEBUG ] Setting mtime of file:///home/john/gnome-bzr/src/conduit/test/test-data/source/66836 UNICODE Ü to 1217731543 () (File.py:141) [datatypes.File ][DEBUG ] Closing file (File.py:58) [Syncronization ][INFO ] Putting data file:///home/john/gnome-bzr/src/conduit/test/test-data/source/66836 UNICODE Ü --> None into FolderTwoWay-file:///home/john/gnome-bzr/src/conduit/test/test-data/sink (Synchronization.py:37) [dataproviders.File ][DEBUG ] Relative path: 66836 UNICODE Ü (File.py:286) [dataproviders.File ][DEBUG ] Found corresponding group (File.py:288) [datatypes.File ][DEBUG ] Transfering File file:///home/john/gnome-bzr/src/conduit/test/test-data/source/66836 UNICODE Ü -> file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/66836 UNICODE Ü (File.py:302) [datatypes.File ][DEBUG ] Closing file (File.py:58) [datatypes.DataType ][DEBUG ] Getting Rid for file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/66836 UNICODE Ü (DataType.py:151) [Syncronization ][DEBUG ] 1WAY PUT: Folder (file:///home/john/gnome-bzr/src/conduit/test/test-data/source/36354 UNICODE Ü) -----> Folder (Synchronization.py:489) [datatypes.DataType ][DEBUG ] Getting Rid for file:///home/john/gnome-bzr/src/conduit/test/test-data/source/36354 UNICODE Ü (DataType.py:151) [TypeConverter ][DEBUG ] Convert file -> file using [('file', 'file', {})] (TypeConverter.py:210) [TypeConverter ][DEBUG ] Skipping file -> file (TypeConverter.py:169) [datatypes.File ][DEBUG ] Setting mtime of file:///home/john/gnome-bzr/src/conduit/test/test-data/source/36354 UNICODE Ü to 1217731543 () (File.py:141) [datatypes.File ][DEBUG ] Closing file (File.py:58) [Syncronization ][INFO ] Putting data file:///home/john/gnome-bzr/src/conduit/test/test-data/source/36354 UNICODE Ü --> None into FolderTwoWay-file:///home/john/gnome-bzr/src/conduit/test/test-data/sink (Synchronization.py:37) [dataproviders.File ][DEBUG ] Relative path: 36354 UNICODE Ü (File.py:286) [dataproviders.File ][DEBUG ] Found corresponding group (File.py:288) [datatypes.File ][DEBUG ] Transfering File file:///home/john/gnome-bzr/src/conduit/test/test-data/source/36354 UNICODE Ü -> file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/36354 UNICODE Ü (File.py:302) [datatypes.File ][DEBUG ] Closing file (File.py:58) [datatypes.DataType ][DEBUG ] Getting Rid for file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/36354 UNICODE Ü (DataType.py:151) [Syncronization ][DEBUG ] 1WAY PUT: Folder (file:///home/john/gnome-bzr/src/conduit/test/test-data/source/11070 UNICODE Ü) -----> Folder (Synchronization.py:489) [datatypes.DataType ][DEBUG ] Getting Rid for file:///home/john/gnome-bzr/src/conduit/test/test-data/source/11070 UNICODE Ü (DataType.py:151) [TypeConverter ][DEBUG ] Convert file -> file using [('file', 'file', {})] (TypeConverter.py:210) [TypeConverter ][DEBUG ] Skipping file -> file (TypeConverter.py:169) [datatypes.File ][DEBUG ] Setting mtime of file:///home/john/gnome-bzr/src/conduit/test/test-data/source/11070 UNICODE Ü to 1217731543 () (File.py:141) [datatypes.File ][DEBUG ] Closing file (File.py:58) [Syncronization ][INFO ] Putting data file:///home/john/gnome-bzr/src/conduit/test/test-data/source/11070 UNICODE Ü --> None into FolderTwoWay-file:///home/john/gnome-bzr/src/conduit/test/test-data/sink (Synchronization.py:37) [dataproviders.File ][DEBUG ] Relative path: 11070 UNICODE Ü (File.py:286) [dataproviders.File ][DEBUG ] Found corresponding group (File.py:288) [datatypes.File ][DEBUG ] Transfering File file:///home/john/gnome-bzr/src/conduit/test/test-data/source/11070 UNICODE Ü -> file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/11070 UNICODE Ü (File.py:302) [datatypes.File ][DEBUG ] Closing file (File.py:58) [datatypes.DataType ][DEBUG ] Getting Rid for file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/11070 UNICODE Ü (DataType.py:151) [Syncronization ][DEBUG ] 1WAY PUT: Folder (file:///home/john/gnome-bzr/src/conduit/test/test-data/source/08357 UNICODE Ë) -----> Folder (Synchronization.py:489) [datatypes.DataType ][DEBUG ] Getting Rid for file:///home/john/gnome-bzr/src/conduit/test/test-data/source/08357 UNICODE Ë (DataType.py:151) [TypeConverter ][DEBUG ] Convert file -> file using [('file', 'file', {})] (TypeConverter.py:210) [TypeConverter ][DEBUG ] Skipping file -> file (TypeConverter.py:169) [datatypes.File ][DEBUG ] Setting mtime of file:///home/john/gnome-bzr/src/conduit/test/test-data/source/08357 UNICODE Ë to 1217731543 () (File.py:141) [datatypes.File ][DEBUG ] Closing file (File.py:58) [Syncronization ][INFO ] Putting data file:///home/john/gnome-bzr/src/conduit/test/test-data/source/08357 UNICODE Ë --> None into FolderTwoWay-file:///home/john/gnome-bzr/src/conduit/test/test-data/sink (Synchronization.py:37) [dataproviders.File ][DEBUG ] Relative path: 08357 UNICODE Ë (File.py:286) [dataproviders.File ][DEBUG ] Found corresponding group (File.py:288) [datatypes.File ][DEBUG ] Transfering File file:///home/john/gnome-bzr/src/conduit/test/test-data/source/08357 UNICODE Ë -> file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/08357 UNICODE Ë (File.py:302) [datatypes.File ][DEBUG ] Closing file (File.py:58) [datatypes.DataType ][DEBUG ] Getting Rid for file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/08357 UNICODE Ë (DataType.py:151) [Syncronization ][DEBUG ] 1WAY PUT: Folder (file:///home/john/gnome-bzr/src/conduit/test/test-data/source/71664 UNICODE æ) -----> Folder (Synchronization.py:489) [datatypes.DataType ][DEBUG ] Getting Rid for file:///home/john/gnome-bzr/src/conduit/test/test-data/source/71664 UNICODE æ (DataType.py:151) [TypeConverter ][DEBUG ] Convert file -> file using [('file', 'file', {})] (TypeConverter.py:210) [TypeConverter ][DEBUG ] Skipping file -> file (TypeConverter.py:169) [datatypes.File ][DEBUG ] Setting mtime of file:///home/john/gnome-bzr/src/conduit/test/test-data/source/71664 UNICODE æ to 1217731543 () (File.py:141) [datatypes.File ][DEBUG ] Closing file (File.py:58) [Syncronization ][INFO ] Putting data file:///home/john/gnome-bzr/src/conduit/test/test-data/source/71664 UNICODE æ --> None into FolderTwoWay-file:///home/john/gnome-bzr/src/conduit/test/test-data/sink (Synchronization.py:37) [dataproviders.File ][DEBUG ] Relative path: 71664 UNICODE æ (File.py:286) [dataproviders.File ][DEBUG ] Found corresponding group (File.py:288) [datatypes.File ][DEBUG ] Transfering File file:///home/john/gnome-bzr/src/conduit/test/test-data/source/71664 UNICODE æ -> file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/71664 UNICODE æ (File.py:302) [datatypes.File ][DEBUG ] Closing file (File.py:58) [datatypes.DataType ][DEBUG ] Getting Rid for file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/71664 UNICODE æ (DataType.py:151) [Syncronization ][DEBUG ] Syncworker state 3 (Synchronization.py:648) [Vfs ][DEBUG ] Folder scan 100.0% complete (Vfs.py:508) [Vfs ][DEBUG ] 5 files loaded in 0.00363492965698 seconds (Vfs.py:516) [Vfs ][DEBUG ] Folder scan 100.0% complete (Vfs.py:508) [Vfs ][DEBUG ] 10 files loaded in 0.00367999076843 seconds (Vfs.py:516) [Vfs ][DEBUG ] Folder scan 100.0% complete (Vfs.py:508) [Vfs ][DEBUG ] 5 files loaded in 0.0028178691864 seconds (Vfs.py:516) [Vfs ][DEBUG ] Folder scan 100.0% complete (Vfs.py:508) [Vfs ][DEBUG ] 10 files loaded in 0.0042929649353 seconds (Vfs.py:516) [Syncronization ][INFO ] Sync already in progress (Synchronization.py:172) [Syncronization ][INFO ] Waiting for thread to finish (Synchronization.py:124) [Syncronization ][DEBUG ] Created thread (thread: -1210054464) (Synchronization.py:211) [Syncronization ][INFO ] Setting global cancel flag (Synchronization.py:90) [Syncronization ][DEBUG ] Starting worker: [], initial)> (Synchronization.py:93) [Syncronization ][INFO ] Waiting for thread to finish (Synchronization.py:124) [Syncronization ][DEBUG ] Started thread [], started)> (thread: -1263223920) (Synchronization.py:624) [Syncronization ][DEBUG ] Sync [], started)> beginning. Slow: False, Twoway: False (Synchronization.py:629) [Syncronization ][DEBUG ] Syncworker state 0 (Synchronization.py:648) [Syncronization ][DEBUG ] Syncworker state 1 (Synchronization.py:648) [Syncronization ][DEBUG ] Source Status = Synchronizing... (Synchronization.py:677) [Vfs ][DEBUG ] Folder scan 100.0% complete (Vfs.py:508) [Vfs ][DEBUG ] 5 files loaded in 0.00346899032593 seconds (Vfs.py:516) [Vfs ][DEBUG ] Folder scan 100.0% complete (Vfs.py:508) [Vfs ][DEBUG ] 10 files loaded in 0.00348997116089 seconds (Vfs.py:516) [Syncronization ][DEBUG ] Syncworker state 2 (Synchronization.py:648) [Syncronization ][INFO ] Synchronizing Wrapper: source twoway (UID: FolderTwoWay-file:///home/john/gnome-bzr/src/conduit/test/test-data/source) |--> Wrapper: sink twoway (UID: FolderTwoWay-file:///home/john/gnome-bzr/src/conduit/test/test-data/sink) (Synchronization.py:467) [DeltaProvider ][INFO ] Delta: Source (FolderTwoWay-file:///home/john/gnome-bzr/src/conduit/test/test-data/source) does not implement get_changes(). Proxying... (DeltaProvider.py:25) [DeltaProvider ][DEBUG ] Delta: Got 5 items [u'file:///home/john/gnome-bzr/src/conduit/test/test-data/source/66836 UNICODE \xdc', u'file:///home/john/gnome-bzr/src/conduit/test/test-data/source/36354 UNICODE \xdc', u'file:///home/john/gnome-bzr/src/conduit/test/test-data/source/11070 UNICODE \xdc', u'file:///home/john/gnome-bzr/src/conduit/test/test-data/source/08357 UNICODE \xcb', u'file:///home/john/gnome-bzr/src/conduit/test/test-data/source/71664 UNICODE \xe6'] (DeltaProvider.py:39) [DeltaProvider ][DEBUG ] Delta: Expecting 5 items (DeltaProvider.py:49) [DeltaProvider ][DEBUG ] file:///home/john/gnome-bzr/src/conduit/test/test-data/source/08357 UNICODE Ë) -- UID:file:///home/john/gnome-bzr/src/conduit/test/test-data/source/08357 UNICODE Ë mtime:2008-08-03 14:45:43 hash:0 (DeltaProvider.py:51) [DeltaProvider ][DEBUG ] file:///home/john/gnome-bzr/src/conduit/test/test-data/source/36354 UNICODE Ü) -- UID:file:///home/john/gnome-bzr/src/conduit/test/test-data/source/36354 UNICODE Ü mtime:2008-08-03 14:45:43 hash:0 (DeltaProvider.py:51) [DeltaProvider ][DEBUG ] file:///home/john/gnome-bzr/src/conduit/test/test-data/source/71664 UNICODE æ) -- UID:file:///home/john/gnome-bzr/src/conduit/test/test-data/source/71664 UNICODE æ mtime:2008-08-03 14:45:43 hash:0 (DeltaProvider.py:51) [DeltaProvider ][DEBUG ] file:///home/john/gnome-bzr/src/conduit/test/test-data/source/11070 UNICODE Ü) -- UID:file:///home/john/gnome-bzr/src/conduit/test/test-data/source/11070 UNICODE Ü mtime:2008-08-03 14:45:43 hash:0 (DeltaProvider.py:51) [DeltaProvider ][DEBUG ] file:///home/john/gnome-bzr/src/conduit/test/test-data/source/66836 UNICODE Ü) -- UID:file:///home/john/gnome-bzr/src/conduit/test/test-data/source/66836 UNICODE Ü mtime:2008-08-03 14:45:43 hash:0 (DeltaProvider.py:51) [datatypes.DataType ][DEBUG ] Getting Rid for file:///home/john/gnome-bzr/src/conduit/test/test-data/source/66836 UNICODE Ü (DataType.py:151) [datatypes.DataType ][DEBUG ] Getting Rid for file:///home/john/gnome-bzr/src/conduit/test/test-data/source/36354 UNICODE Ü (DataType.py:151) [datatypes.DataType ][DEBUG ] Getting Rid for file:///home/john/gnome-bzr/src/conduit/test/test-data/source/11070 UNICODE Ü (DataType.py:151) [datatypes.DataType ][DEBUG ] Getting Rid for file:///home/john/gnome-bzr/src/conduit/test/test-data/source/08357 UNICODE Ë (DataType.py:151) [datatypes.DataType ][DEBUG ] Getting Rid for file:///home/john/gnome-bzr/src/conduit/test/test-data/source/71664 UNICODE æ (DataType.py:151) [Syncronization ][DEBUG ] FolderTwoWay-file:///home/john/gnome-bzr/src/conduit/test/test-data/source Changes: New 0 items [] (Synchronization.py:241) [Syncronization ][DEBUG ] FolderTwoWay-file:///home/john/gnome-bzr/src/conduit/test/test-data/source Changes: Modified 0 items [] (Synchronization.py:242) [Syncronization ][DEBUG ] FolderTwoWay-file:///home/john/gnome-bzr/src/conduit/test/test-data/source Changes: Deleted 0 items [] (Synchronization.py:243) [Syncronization ][DEBUG ] Syncworker state 3 (Synchronization.py:648) [Vfs ][DEBUG ] Folder scan 100.0% complete (Vfs.py:508) [Vfs ][DEBUG ] 5 files loaded in 0.00297689437866 seconds (Vfs.py:516) [Vfs ][DEBUG ] Folder scan 100.0% complete (Vfs.py:508) [Vfs ][DEBUG ] 10 files loaded in 0.00370812416077 seconds (Vfs.py:516) [Vfs ][DEBUG ] Folder scan 100.0% complete (Vfs.py:508) [Vfs ][DEBUG ] 5 files loaded in 0.00343108177185 seconds (Vfs.py:516) [Vfs ][DEBUG ] Folder scan 100.0% complete (Vfs.py:508) [Vfs ][DEBUG ] 10 files loaded in 0.00414681434631 seconds (Vfs.py:516) [Syncronization ][INFO ] Sync already in progress (Synchronization.py:172) [Syncronization ][INFO ] Waiting for thread to finish (Synchronization.py:124) [Syncronization ][DEBUG ] Created thread (thread: -1210054464) (Synchronization.py:211) [Syncronization ][INFO ] Setting global cancel flag (Synchronization.py:90) [Syncronization ][DEBUG ] Starting worker: [], initial)> (Synchronization.py:93) [Syncronization ][INFO ] Waiting for thread to finish (Synchronization.py:124) [Syncronization ][DEBUG ] Started thread [], started)> (thread: -1263223920) (Synchronization.py:624) [Syncronization ][DEBUG ] Sync [], started)> beginning. Slow: False, Twoway: False (Synchronization.py:629) [Syncronization ][DEBUG ] Syncworker state 0 (Synchronization.py:648) [Syncronization ][DEBUG ] Syncworker state 1 (Synchronization.py:648) [Syncronization ][DEBUG ] Source Status = Synchronizing... (Synchronization.py:677) [Vfs ][DEBUG ] Folder scan 100.0% complete (Vfs.py:508) [Vfs ][DEBUG ] 5 files loaded in 0.00278496742249 seconds (Vfs.py:516) [Vfs ][DEBUG ] Folder scan 100.0% complete (Vfs.py:508) [Vfs ][DEBUG ] 10 files loaded in 0.00371503829956 seconds (Vfs.py:516) [Syncronization ][DEBUG ] Syncworker state 2 (Synchronization.py:648) [Syncronization ][INFO ] Synchronizing Wrapper: source twoway (UID: FolderTwoWay-file:///home/john/gnome-bzr/src/conduit/test/test-data/source) |--> Wrapper: sink twoway (UID: FolderTwoWay-file:///home/john/gnome-bzr/src/conduit/test/test-data/sink) (Synchronization.py:467) [DeltaProvider ][INFO ] Delta: Source (FolderTwoWay-file:///home/john/gnome-bzr/src/conduit/test/test-data/source) does not implement get_changes(). Proxying... (DeltaProvider.py:25) [DeltaProvider ][DEBUG ] Delta: Got 5 items [u'file:///home/john/gnome-bzr/src/conduit/test/test-data/source/66836 UNICODE \xdc', u'file:///home/john/gnome-bzr/src/conduit/test/test-data/source/36354 UNICODE \xdc', u'file:///home/john/gnome-bzr/src/conduit/test/test-data/source/11070 UNICODE \xdc', u'file:///home/john/gnome-bzr/src/conduit/test/test-data/source/08357 UNICODE \xcb', u'file:///home/john/gnome-bzr/src/conduit/test/test-data/source/71664 UNICODE \xe6'] (DeltaProvider.py:39) [DeltaProvider ][DEBUG ] Delta: Expecting 5 items (DeltaProvider.py:49) [DeltaProvider ][DEBUG ] file:///home/john/gnome-bzr/src/conduit/test/test-data/source/08357 UNICODE Ë) -- UID:file:///home/john/gnome-bzr/src/conduit/test/test-data/source/08357 UNICODE Ë mtime:2008-08-03 14:45:43 hash:0 (DeltaProvider.py:51) [DeltaProvider ][DEBUG ] file:///home/john/gnome-bzr/src/conduit/test/test-data/source/36354 UNICODE Ü) -- UID:file:///home/john/gnome-bzr/src/conduit/test/test-data/source/36354 UNICODE Ü mtime:2008-08-03 14:45:43 hash:0 (DeltaProvider.py:51) [DeltaProvider ][DEBUG ] file:///home/john/gnome-bzr/src/conduit/test/test-data/source/71664 UNICODE æ) -- UID:file:///home/john/gnome-bzr/src/conduit/test/test-data/source/71664 UNICODE æ mtime:2008-08-03 14:45:43 hash:0 (DeltaProvider.py:51) [DeltaProvider ][DEBUG ] file:///home/john/gnome-bzr/src/conduit/test/test-data/source/11070 UNICODE Ü) -- UID:file:///home/john/gnome-bzr/src/conduit/test/test-data/source/11070 UNICODE Ü mtime:2008-08-03 14:45:43 hash:0 (DeltaProvider.py:51) [DeltaProvider ][DEBUG ] file:///home/john/gnome-bzr/src/conduit/test/test-data/source/66836 UNICODE Ü) -- UID:file:///home/john/gnome-bzr/src/conduit/test/test-data/source/66836 UNICODE Ü mtime:2008-08-03 14:45:43 hash:0 (DeltaProvider.py:51) [datatypes.DataType ][DEBUG ] Getting Rid for file:///home/john/gnome-bzr/src/conduit/test/test-data/source/66836 UNICODE Ü (DataType.py:151) [datatypes.DataType ][DEBUG ] Getting Rid for file:///home/john/gnome-bzr/src/conduit/test/test-data/source/36354 UNICODE Ü (DataType.py:151) [datatypes.DataType ][DEBUG ] Getting Rid for file:///home/john/gnome-bzr/src/conduit/test/test-data/source/11070 UNICODE Ü (DataType.py:151) [datatypes.DataType ][DEBUG ] Getting Rid for file:///home/john/gnome-bzr/src/conduit/test/test-data/source/08357 UNICODE Ë (DataType.py:151) [datatypes.DataType ][DEBUG ] Getting Rid for file:///home/john/gnome-bzr/src/conduit/test/test-data/source/71664 UNICODE æ (DataType.py:151) [Syncronization ][DEBUG ] FolderTwoWay-file:///home/john/gnome-bzr/src/conduit/test/test-data/source Changes: New 0 items [] (Synchronization.py:241) [Syncronization ][DEBUG ] FolderTwoWay-file:///home/john/gnome-bzr/src/conduit/test/test-data/source Changes: Modified 0 items [] (Synchronization.py:242) [Syncronization ][DEBUG ] FolderTwoWay-file:///home/john/gnome-bzr/src/conduit/test/test-data/source Changes: Deleted 0 items [] (Synchronization.py:243) [Syncronization ][DEBUG ] Syncworker state 3 (Synchronization.py:648) [Vfs ][DEBUG ] Folder scan 100.0% complete (Vfs.py:508) [Vfs ][DEBUG ] 5 files loaded in 0.0027871131897 seconds (Vfs.py:516) [Vfs ][DEBUG ] Folder scan 100.0% complete (Vfs.py:508) [Vfs ][DEBUG ] 10 files loaded in 0.00348997116089 seconds (Vfs.py:516) [Vfs ][DEBUG ] Folder scan 100.0% complete (Vfs.py:508) [Vfs ][DEBUG ] 5 files loaded in 0.00422191619873 seconds (Vfs.py:516) [Vfs ][DEBUG ] Folder scan 100.0% complete (Vfs.py:508) [Vfs ][DEBUG ] 10 files loaded in 0.00424385070801 seconds (Vfs.py:516) [Conduit ][DEBUG ] Enabling Two Way Sync (Conduit.py:244) [Syncronization ][INFO ] Sync already in progress (Synchronization.py:172) [Syncronization ][INFO ] Waiting for thread to finish (Synchronization.py:124) [Syncronization ][DEBUG ] Created thread (thread: -1210054464) (Synchronization.py:211) [Syncronization ][INFO ] Setting global cancel flag (Synchronization.py:90) [Syncronization ][DEBUG ] Starting worker: Wrapper: sink twoway (UID: FolderTwoWay-file:///home/john/gnome-bzr/src/conduit/test/test-data/sink), initial)> (Synchronization.py:93) [Syncronization ][INFO ] Waiting for thread to finish (Synchronization.py:124) [Syncronization ][DEBUG ] Started thread Wrapper: sink twoway (UID: FolderTwoWay-file:///home/john/gnome-bzr/src/conduit/test/test-data/sink), started)> (thread: -1263223920) (Synchronization.py:624) [Syncronization ][DEBUG ] Sync Wrapper: sink twoway (UID: FolderTwoWay-file:///home/john/gnome-bzr/src/conduit/test/test-data/sink), started)> beginning. Slow: False, Twoway: True (Synchronization.py:629) [Syncronization ][DEBUG ] Syncworker state 0 (Synchronization.py:648) [Syncronization ][DEBUG ] Syncworker state 1 (Synchronization.py:648) [Syncronization ][DEBUG ] Source Status = Synchronizing... (Synchronization.py:677) [Vfs ][DEBUG ] Folder scan 100.0% complete (Vfs.py:508) [Vfs ][DEBUG ] 5 files loaded in 0.00449991226196 seconds (Vfs.py:516) [Vfs ][DEBUG ] Folder scan 100.0% complete (Vfs.py:508) [Vfs ][DEBUG ] 10 files loaded in 0.00524592399597 seconds (Vfs.py:516) [Syncronization ][DEBUG ] Syncworker state 2 (Synchronization.py:648) [Syncronization ][INFO ] Synchronizing (Two Way) Wrapper: source twoway (UID: FolderTwoWay-file:///home/john/gnome-bzr/src/conduit/test/test-data/source) <--> Wrapper: sink twoway (UID: FolderTwoWay-file:///home/john/gnome-bzr/src/conduit/test/test-data/sink) (Synchronization.py:514) [DeltaProvider ][INFO ] Delta: Source (FolderTwoWay-file:///home/john/gnome-bzr/src/conduit/test/test-data/source) does not implement get_changes(). Proxying... (DeltaProvider.py:25) [DeltaProvider ][DEBUG ] Delta: Got 5 items [u'file:///home/john/gnome-bzr/src/conduit/test/test-data/source/66836 UNICODE \xdc', u'file:///home/john/gnome-bzr/src/conduit/test/test-data/source/36354 UNICODE \xdc', u'file:///home/john/gnome-bzr/src/conduit/test/test-data/source/11070 UNICODE \xdc', u'file:///home/john/gnome-bzr/src/conduit/test/test-data/source/08357 UNICODE \xcb', u'file:///home/john/gnome-bzr/src/conduit/test/test-data/source/71664 UNICODE \xe6'] (DeltaProvider.py:39) [DeltaProvider ][DEBUG ] Delta: Expecting 5 items (DeltaProvider.py:49) [DeltaProvider ][DEBUG ] file:///home/john/gnome-bzr/src/conduit/test/test-data/source/08357 UNICODE Ë) -- UID:file:///home/john/gnome-bzr/src/conduit/test/test-data/source/08357 UNICODE Ë mtime:2008-08-03 14:45:43 hash:0 (DeltaProvider.py:51) [DeltaProvider ][DEBUG ] file:///home/john/gnome-bzr/src/conduit/test/test-data/source/36354 UNICODE Ü) -- UID:file:///home/john/gnome-bzr/src/conduit/test/test-data/source/36354 UNICODE Ü mtime:2008-08-03 14:45:43 hash:0 (DeltaProvider.py:51) [DeltaProvider ][DEBUG ] file:///home/john/gnome-bzr/src/conduit/test/test-data/source/71664 UNICODE æ) -- UID:file:///home/john/gnome-bzr/src/conduit/test/test-data/source/71664 UNICODE æ mtime:2008-08-03 14:45:43 hash:0 (DeltaProvider.py:51) [DeltaProvider ][DEBUG ] file:///home/john/gnome-bzr/src/conduit/test/test-data/source/11070 UNICODE Ü) -- UID:file:///home/john/gnome-bzr/src/conduit/test/test-data/source/11070 UNICODE Ü mtime:2008-08-03 14:45:43 hash:0 (DeltaProvider.py:51) [DeltaProvider ][DEBUG ] file:///home/john/gnome-bzr/src/conduit/test/test-data/source/66836 UNICODE Ü) -- UID:file:///home/john/gnome-bzr/src/conduit/test/test-data/source/66836 UNICODE Ü mtime:2008-08-03 14:45:43 hash:0 (DeltaProvider.py:51) [datatypes.DataType ][DEBUG ] Getting Rid for file:///home/john/gnome-bzr/src/conduit/test/test-data/source/66836 UNICODE Ü (DataType.py:151) [datatypes.DataType ][DEBUG ] Getting Rid for file:///home/john/gnome-bzr/src/conduit/test/test-data/source/36354 UNICODE Ü (DataType.py:151) [datatypes.DataType ][DEBUG ] Getting Rid for file:///home/john/gnome-bzr/src/conduit/test/test-data/source/11070 UNICODE Ü (DataType.py:151) [datatypes.DataType ][DEBUG ] Getting Rid for file:///home/john/gnome-bzr/src/conduit/test/test-data/source/08357 UNICODE Ë (DataType.py:151) [datatypes.DataType ][DEBUG ] Getting Rid for file:///home/john/gnome-bzr/src/conduit/test/test-data/source/71664 UNICODE æ (DataType.py:151) [Syncronization ][DEBUG ] FolderTwoWay-file:///home/john/gnome-bzr/src/conduit/test/test-data/source Changes: New 0 items [] (Synchronization.py:241) [Syncronization ][DEBUG ] FolderTwoWay-file:///home/john/gnome-bzr/src/conduit/test/test-data/source Changes: Modified 0 items [] (Synchronization.py:242) [Syncronization ][DEBUG ] FolderTwoWay-file:///home/john/gnome-bzr/src/conduit/test/test-data/source Changes: Deleted 0 items [] (Synchronization.py:243) [DeltaProvider ][INFO ] Delta: Source (FolderTwoWay-file:///home/john/gnome-bzr/src/conduit/test/test-data/sink) does not implement get_changes(). Proxying... (DeltaProvider.py:25) [DeltaProvider ][DEBUG ] Delta: Got 10 items [u'file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/353104 UNICODE \xe1', u'file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/317110 UNICODE \xc4', u'file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/411045 UNICODE \xc4', u'file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/77714 UNICODE \xdc', u'file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/43737 UNICODE \xe9', u'file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/66836 UNICODE \xdc', u'file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/36354 UNICODE \xdc', u'file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/11070 UNICODE \xdc', u'file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/08357 UNICODE \xcb', u'file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/71664 UNICODE \xe6'] (DeltaProvider.py:39) [DeltaProvider ][DEBUG ] Delta: Expecting 5 items (DeltaProvider.py:49) [DeltaProvider ][DEBUG ] file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/71664 UNICODE æ) -- UID:file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/71664 UNICODE æ mtime:2008-08-03 14:45:43 hash:0 (DeltaProvider.py:51) [DeltaProvider ][DEBUG ] file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/66836 UNICODE Ü) -- UID:file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/66836 UNICODE Ü mtime:2008-08-03 14:45:43 hash:0 (DeltaProvider.py:51) [DeltaProvider ][DEBUG ] file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/08357 UNICODE Ë) -- UID:file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/08357 UNICODE Ë mtime:2008-08-03 14:45:43 hash:0 (DeltaProvider.py:51) [DeltaProvider ][DEBUG ] file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/36354 UNICODE Ü) -- UID:file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/36354 UNICODE Ü mtime:2008-08-03 14:45:43 hash:0 (DeltaProvider.py:51) [DeltaProvider ][DEBUG ] file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/11070 UNICODE Ü) -- UID:file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/11070 UNICODE Ü mtime:2008-08-03 14:45:43 hash:0 (DeltaProvider.py:51) [datatypes.DataType ][DEBUG ] Getting Rid for file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/66836 UNICODE Ü (DataType.py:151) [datatypes.DataType ][DEBUG ] Getting Rid for file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/36354 UNICODE Ü (DataType.py:151) [datatypes.DataType ][DEBUG ] Getting Rid for file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/11070 UNICODE Ü (DataType.py:151) [datatypes.DataType ][DEBUG ] Getting Rid for file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/08357 UNICODE Ë (DataType.py:151) [datatypes.DataType ][DEBUG ] Getting Rid for file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/71664 UNICODE æ (DataType.py:151) [Syncronization ][DEBUG ] FolderTwoWay-file:///home/john/gnome-bzr/src/conduit/test/test-data/sink Changes: New 5 items [u'file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/353104 UNICODE \xe1', u'file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/317110 UNICODE \xc4', u'file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/411045 UNICODE \xc4', u'file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/77714 UNICODE \xdc', u'file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/43737 UNICODE \xe9'] (Synchronization.py:241) [Syncronization ][DEBUG ] FolderTwoWay-file:///home/john/gnome-bzr/src/conduit/test/test-data/sink Changes: Modified 0 items [] (Synchronization.py:242) [Syncronization ][DEBUG ] FolderTwoWay-file:///home/john/gnome-bzr/src/conduit/test/test-data/sink Changes: Deleted 0 items [] (Synchronization.py:243) [Syncronization ][DEBUG ] 2WAY PUT: Folder (file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/353104 UNICODE á) -----> Folder (Synchronization.py:569) [datatypes.DataType ][DEBUG ] Getting Rid for file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/353104 UNICODE á (DataType.py:151) [TypeConverter ][DEBUG ] Convert file -> file using [('file', 'file', {})] (TypeConverter.py:210) [TypeConverter ][DEBUG ] Skipping file -> file (TypeConverter.py:169) [datatypes.File ][DEBUG ] Setting mtime of file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/353104 UNICODE á to 1217731543 () (File.py:141) [datatypes.File ][DEBUG ] Closing file (File.py:58) [Syncronization ][INFO ] Putting data file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/353104 UNICODE á --> None into FolderTwoWay-file:///home/john/gnome-bzr/src/conduit/test/test-data/source (Synchronization.py:37) [dataproviders.File ][DEBUG ] Relative path: 353104 UNICODE á (File.py:286) [dataproviders.File ][DEBUG ] Found corresponding group (File.py:288) [datatypes.File ][DEBUG ] Transfering File file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/353104 UNICODE á -> file:///home/john/gnome-bzr/src/conduit/test/test-data/source/353104 UNICODE á (File.py:302) [datatypes.File ][DEBUG ] Closing file (File.py:58) [datatypes.DataType ][DEBUG ] Getting Rid for file:///home/john/gnome-bzr/src/conduit/test/test-data/source/353104 UNICODE á (DataType.py:151) [Syncronization ][DEBUG ] 2WAY PUT: Folder (file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/317110 UNICODE Ä) -----> Folder (Synchronization.py:569) [datatypes.DataType ][DEBUG ] Getting Rid for file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/317110 UNICODE Ä (DataType.py:151) [TypeConverter ][DEBUG ] Convert file -> file using [('file', 'file', {})] (TypeConverter.py:210) [TypeConverter ][DEBUG ] Skipping file -> file (TypeConverter.py:169) [datatypes.File ][DEBUG ] Setting mtime of file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/317110 UNICODE Ä to 1217731543 () (File.py:141) [datatypes.File ][DEBUG ] Closing file (File.py:58) [Syncronization ][INFO ] Putting data file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/317110 UNICODE Ä --> None into FolderTwoWay-file:///home/john/gnome-bzr/src/conduit/test/test-data/source (Synchronization.py:37) [dataproviders.File ][DEBUG ] Relative path: 317110 UNICODE Ä (File.py:286) [dataproviders.File ][DEBUG ] Found corresponding group (File.py:288) [datatypes.File ][DEBUG ] Transfering File file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/317110 UNICODE Ä -> file:///home/john/gnome-bzr/src/conduit/test/test-data/source/317110 UNICODE Ä (File.py:302) [datatypes.File ][DEBUG ] Closing file (File.py:58) [datatypes.DataType ][DEBUG ] Getting Rid for file:///home/john/gnome-bzr/src/conduit/test/test-data/source/317110 UNICODE Ä (DataType.py:151) [Syncronization ][DEBUG ] 2WAY PUT: Folder (file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/411045 UNICODE Ä) -----> Folder (Synchronization.py:569) [datatypes.DataType ][DEBUG ] Getting Rid for file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/411045 UNICODE Ä (DataType.py:151) [TypeConverter ][DEBUG ] Convert file -> file using [('file', 'file', {})] (TypeConverter.py:210) [TypeConverter ][DEBUG ] Skipping file -> file (TypeConverter.py:169) [datatypes.File ][DEBUG ] Setting mtime of file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/411045 UNICODE Ä to 1217731543 () (File.py:141) [datatypes.File ][DEBUG ] Closing file (File.py:58) [Syncronization ][INFO ] Putting data file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/411045 UNICODE Ä --> None into FolderTwoWay-file:///home/john/gnome-bzr/src/conduit/test/test-data/source (Synchronization.py:37) [dataproviders.File ][DEBUG ] Relative path: 411045 UNICODE Ä (File.py:286) [dataproviders.File ][DEBUG ] Found corresponding group (File.py:288) [datatypes.File ][DEBUG ] Transfering File file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/411045 UNICODE Ä -> file:///home/john/gnome-bzr/src/conduit/test/test-data/source/411045 UNICODE Ä (File.py:302) [datatypes.File ][DEBUG ] Closing file (File.py:58) [datatypes.DataType ][DEBUG ] Getting Rid for file:///home/john/gnome-bzr/src/conduit/test/test-data/source/411045 UNICODE Ä (DataType.py:151) [Syncronization ][DEBUG ] 2WAY PUT: Folder (file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/77714 UNICODE Ü) -----> Folder (Synchronization.py:569) [datatypes.DataType ][DEBUG ] Getting Rid for file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/77714 UNICODE Ü (DataType.py:151) [TypeConverter ][DEBUG ] Convert file -> file using [('file', 'file', {})] (TypeConverter.py:210) [TypeConverter ][DEBUG ] Skipping file -> file (TypeConverter.py:169) [datatypes.File ][DEBUG ] Setting mtime of file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/77714 UNICODE Ü to 1217731543 () (File.py:141) [datatypes.File ][DEBUG ] Closing file (File.py:58) [Syncronization ][INFO ] Putting data file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/77714 UNICODE Ü --> None into FolderTwoWay-file:///home/john/gnome-bzr/src/conduit/test/test-data/source (Synchronization.py:37) [dataproviders.File ][DEBUG ] Relative path: 77714 UNICODE Ü (File.py:286) [dataproviders.File ][DEBUG ] Found corresponding group (File.py:288) [datatypes.File ][DEBUG ] Transfering File file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/77714 UNICODE Ü -> file:///home/john/gnome-bzr/src/conduit/test/test-data/source/77714 UNICODE Ü (File.py:302) [datatypes.File ][DEBUG ] Closing file (File.py:58) [datatypes.DataType ][DEBUG ] Getting Rid for file:///home/john/gnome-bzr/src/conduit/test/test-data/source/77714 UNICODE Ü (DataType.py:151) [Syncronization ][DEBUG ] 2WAY PUT: Folder (file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/43737 UNICODE é) -----> Folder (Synchronization.py:569) [datatypes.DataType ][DEBUG ] Getting Rid for file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/43737 UNICODE é (DataType.py:151) [TypeConverter ][DEBUG ] Convert file -> file using [('file', 'file', {})] (TypeConverter.py:210) [TypeConverter ][DEBUG ] Skipping file -> file (TypeConverter.py:169) [datatypes.File ][DEBUG ] Setting mtime of file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/43737 UNICODE é to 1217731543 () (File.py:141) [datatypes.File ][DEBUG ] Closing file (File.py:58) [Syncronization ][INFO ] Putting data file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/43737 UNICODE é --> None into FolderTwoWay-file:///home/john/gnome-bzr/src/conduit/test/test-data/source (Synchronization.py:37) [dataproviders.File ][DEBUG ] Relative path: 43737 UNICODE é (File.py:286) [dataproviders.File ][DEBUG ] Found corresponding group (File.py:288) [datatypes.File ][DEBUG ] Transfering File file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/43737 UNICODE é -> file:///home/john/gnome-bzr/src/conduit/test/test-data/source/43737 UNICODE é (File.py:302) [datatypes.File ][DEBUG ] Closing file (File.py:58) [datatypes.DataType ][DEBUG ] Getting Rid for file:///home/john/gnome-bzr/src/conduit/test/test-data/source/43737 UNICODE é (DataType.py:151) [Syncronization ][DEBUG ] Syncworker state 3 (Synchronization.py:648) [Vfs ][DEBUG ] Folder scan 100.0% complete (Vfs.py:508) [Vfs ][DEBUG ] 10 files loaded in 0.0043888092041 seconds (Vfs.py:516) [Vfs ][DEBUG ] Folder scan 100.0% complete (Vfs.py:508) [Vfs ][DEBUG ] 10 files loaded in 0.00426888465881 seconds (Vfs.py:516) [Vfs ][DEBUG ] Folder scan 100.0% complete (Vfs.py:508) [Vfs ][DEBUG ] 10 files loaded in 0.00664401054382 seconds (Vfs.py:516) [Vfs ][DEBUG ] Folder scan 100.0% complete (Vfs.py:508) [Vfs ][DEBUG ] 10 files loaded in 0.00725603103638 seconds (Vfs.py:516) [Syncronization ][INFO ] Sync already in progress (Synchronization.py:172) [Syncronization ][INFO ] Waiting for thread to finish (Synchronization.py:124) [Syncronization ][DEBUG ] Created thread (thread: -1210054464) (Synchronization.py:211) [Syncronization ][INFO ] Setting global cancel flag (Synchronization.py:90) [Syncronization ][DEBUG ] Starting worker: Wrapper: sink twoway (UID: FolderTwoWay-file:///home/john/gnome-bzr/src/conduit/test/test-data/sink), initial)> (Synchronization.py:93) [Syncronization ][DEBUG ] Started thread Wrapper: sink twoway (UID: FolderTwoWay-file:///home/john/gnome-bzr/src/conduit/test/test-data/sink), started)> (thread: -1263223920) (Synchronization.py:624) [Syncronization ][DEBUG ] Sync Wrapper: sink twoway (UID: FolderTwoWay-file:///home/john/gnome-bzr/src/conduit/test/test-data/sink), started)> beginning. Slow: False, Twoway: True (Synchronization.py:629) [Syncronization ][DEBUG ] Syncworker state 0 (Synchronization.py:648) [Syncronization ][DEBUG ] Syncworker state 1 (Synchronization.py:648) [Syncronization ][DEBUG ] Source Status = Synchronizing... (Synchronization.py:677) [Vfs ][DEBUG ] Folder scan 100.0% complete (Vfs.py:508) [Vfs ][DEBUG ] 10 files loaded in 0.00501894950867 seconds (Vfs.py:516) [Syncronization ][INFO ] Waiting for thread to finish (Synchronization.py:124) [Vfs ][DEBUG ] Folder scan 100.0% complete (Vfs.py:508) [Vfs ][DEBUG ] 10 files loaded in 0.00705099105835 seconds (Vfs.py:516) [Syncronization ][DEBUG ] Syncworker state 2 (Synchronization.py:648) [Syncronization ][INFO ] Synchronizing (Two Way) Wrapper: source twoway (UID: FolderTwoWay-file:///home/john/gnome-bzr/src/conduit/test/test-data/source) <--> Wrapper: sink twoway (UID: FolderTwoWay-file:///home/john/gnome-bzr/src/conduit/test/test-data/sink) (Synchronization.py:514) [DeltaProvider ][INFO ] Delta: Source (FolderTwoWay-file:///home/john/gnome-bzr/src/conduit/test/test-data/source) does not implement get_changes(). Proxying... (DeltaProvider.py:25) [DeltaProvider ][DEBUG ] Delta: Got 10 items [u'file:///home/john/gnome-bzr/src/conduit/test/test-data/source/66836 UNICODE \xdc', u'file:///home/john/gnome-bzr/src/conduit/test/test-data/source/36354 UNICODE \xdc', u'file:///home/john/gnome-bzr/src/conduit/test/test-data/source/11070 UNICODE \xdc', u'file:///home/john/gnome-bzr/src/conduit/test/test-data/source/08357 UNICODE \xcb', u'file:///home/john/gnome-bzr/src/conduit/test/test-data/source/71664 UNICODE \xe6', u'file:///home/john/gnome-bzr/src/conduit/test/test-data/source/353104 UNICODE \xe1', u'file:///home/john/gnome-bzr/src/conduit/test/test-data/source/317110 UNICODE \xc4', u'file:///home/john/gnome-bzr/src/conduit/test/test-data/source/411045 UNICODE \xc4', u'file:///home/john/gnome-bzr/src/conduit/test/test-data/source/77714 UNICODE \xdc', u'file:///home/john/gnome-bzr/src/conduit/test/test-data/source/43737 UNICODE \xe9'] (DeltaProvider.py:39) [DeltaProvider ][DEBUG ] Delta: Expecting 10 items (DeltaProvider.py:49) [DeltaProvider ][DEBUG ] file:///home/john/gnome-bzr/src/conduit/test/test-data/source/08357 UNICODE Ë) -- UID:file:///home/john/gnome-bzr/src/conduit/test/test-data/source/08357 UNICODE Ë mtime:2008-08-03 14:45:43 hash:0 (DeltaProvider.py:51) [DeltaProvider ][DEBUG ] file:///home/john/gnome-bzr/src/conduit/test/test-data/source/43737 UNICODE é) -- UID:file:///home/john/gnome-bzr/src/conduit/test/test-data/source/43737 UNICODE é mtime:2008-08-03 14:45:43 hash:0 (DeltaProvider.py:51) [DeltaProvider ][DEBUG ] file:///home/john/gnome-bzr/src/conduit/test/test-data/source/411045 UNICODE Ä) -- UID:file:///home/john/gnome-bzr/src/conduit/test/test-data/source/411045 UNICODE Ä mtime:2008-08-03 14:45:43 hash:0 (DeltaProvider.py:51) [DeltaProvider ][DEBUG ] file:///home/john/gnome-bzr/src/conduit/test/test-data/source/317110 UNICODE Ä) -- UID:file:///home/john/gnome-bzr/src/conduit/test/test-data/source/317110 UNICODE Ä mtime:2008-08-03 14:45:43 hash:0 (DeltaProvider.py:51) [DeltaProvider ][DEBUG ] file:///home/john/gnome-bzr/src/conduit/test/test-data/source/71664 UNICODE æ) -- UID:file:///home/john/gnome-bzr/src/conduit/test/test-data/source/71664 UNICODE æ mtime:2008-08-03 14:45:43 hash:0 (DeltaProvider.py:51) [DeltaProvider ][DEBUG ] file:///home/john/gnome-bzr/src/conduit/test/test-data/source/36354 UNICODE Ü) -- UID:file:///home/john/gnome-bzr/src/conduit/test/test-data/source/36354 UNICODE Ü mtime:2008-08-03 14:45:43 hash:0 (DeltaProvider.py:51) [DeltaProvider ][DEBUG ] file:///home/john/gnome-bzr/src/conduit/test/test-data/source/77714 UNICODE Ü) -- UID:file:///home/john/gnome-bzr/src/conduit/test/test-data/source/77714 UNICODE Ü mtime:2008-08-03 14:45:43 hash:0 (DeltaProvider.py:51) [DeltaProvider ][DEBUG ] file:///home/john/gnome-bzr/src/conduit/test/test-data/source/11070 UNICODE Ü) -- UID:file:///home/john/gnome-bzr/src/conduit/test/test-data/source/11070 UNICODE Ü mtime:2008-08-03 14:45:43 hash:0 (DeltaProvider.py:51) [DeltaProvider ][DEBUG ] file:///home/john/gnome-bzr/src/conduit/test/test-data/source/353104 UNICODE á) -- UID:file:///home/john/gnome-bzr/src/conduit/test/test-data/source/353104 UNICODE á mtime:2008-08-03 14:45:43 hash:0 (DeltaProvider.py:51) [DeltaProvider ][DEBUG ] file:///home/john/gnome-bzr/src/conduit/test/test-data/source/66836 UNICODE Ü) -- UID:file:///home/john/gnome-bzr/src/conduit/test/test-data/source/66836 UNICODE Ü mtime:2008-08-03 14:45:43 hash:0 (DeltaProvider.py:51) [datatypes.DataType ][DEBUG ] Getting Rid for file:///home/john/gnome-bzr/src/conduit/test/test-data/source/66836 UNICODE Ü (DataType.py:151) [datatypes.DataType ][DEBUG ] Getting Rid for file:///home/john/gnome-bzr/src/conduit/test/test-data/source/36354 UNICODE Ü (DataType.py:151) [datatypes.DataType ][DEBUG ] Getting Rid for file:///home/john/gnome-bzr/src/conduit/test/test-data/source/11070 UNICODE Ü (DataType.py:151) [datatypes.DataType ][DEBUG ] Getting Rid for file:///home/john/gnome-bzr/src/conduit/test/test-data/source/08357 UNICODE Ë (DataType.py:151) [datatypes.DataType ][DEBUG ] Getting Rid for file:///home/john/gnome-bzr/src/conduit/test/test-data/source/71664 UNICODE æ (DataType.py:151) [datatypes.DataType ][DEBUG ] Getting Rid for file:///home/john/gnome-bzr/src/conduit/test/test-data/source/353104 UNICODE á (DataType.py:151) [datatypes.DataType ][DEBUG ] Getting Rid for file:///home/john/gnome-bzr/src/conduit/test/test-data/source/317110 UNICODE Ä (DataType.py:151) [datatypes.DataType ][DEBUG ] Getting Rid for file:///home/john/gnome-bzr/src/conduit/test/test-data/source/411045 UNICODE Ä (DataType.py:151) [datatypes.DataType ][DEBUG ] Getting Rid for file:///home/john/gnome-bzr/src/conduit/test/test-data/source/77714 UNICODE Ü (DataType.py:151) [datatypes.DataType ][DEBUG ] Getting Rid for file:///home/john/gnome-bzr/src/conduit/test/test-data/source/43737 UNICODE é (DataType.py:151) [Syncronization ][DEBUG ] FolderTwoWay-file:///home/john/gnome-bzr/src/conduit/test/test-data/source Changes: New 0 items [] (Synchronization.py:241) [Syncronization ][DEBUG ] FolderTwoWay-file:///home/john/gnome-bzr/src/conduit/test/test-data/source Changes: Modified 0 items [] (Synchronization.py:242) [Syncronization ][DEBUG ] FolderTwoWay-file:///home/john/gnome-bzr/src/conduit/test/test-data/source Changes: Deleted 0 items [] (Synchronization.py:243) [DeltaProvider ][INFO ] Delta: Source (FolderTwoWay-file:///home/john/gnome-bzr/src/conduit/test/test-data/sink) does not implement get_changes(). Proxying... (DeltaProvider.py:25) [DeltaProvider ][DEBUG ] Delta: Got 10 items [u'file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/353104 UNICODE \xe1', u'file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/317110 UNICODE \xc4', u'file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/411045 UNICODE \xc4', u'file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/77714 UNICODE \xdc', u'file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/43737 UNICODE \xe9', u'file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/66836 UNICODE \xdc', u'file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/36354 UNICODE \xdc', u'file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/11070 UNICODE \xdc', u'file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/08357 UNICODE \xcb', u'file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/71664 UNICODE \xe6'] (DeltaProvider.py:39) [DeltaProvider ][DEBUG ] Delta: Expecting 10 items (DeltaProvider.py:49) [DeltaProvider ][DEBUG ] file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/411045 UNICODE Ä) -- UID:file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/411045 UNICODE Ä mtime:2008-08-03 14:45:43 hash:0 (DeltaProvider.py:51) [DeltaProvider ][DEBUG ] file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/11070 UNICODE Ü) -- UID:file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/11070 UNICODE Ü mtime:2008-08-03 14:45:43 hash:0 (DeltaProvider.py:51) [DeltaProvider ][DEBUG ] file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/08357 UNICODE Ë) -- UID:file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/08357 UNICODE Ë mtime:2008-08-03 14:45:43 hash:0 (DeltaProvider.py:51) [DeltaProvider ][DEBUG ] file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/317110 UNICODE Ä) -- UID:file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/317110 UNICODE Ä mtime:2008-08-03 14:45:43 hash:0 (DeltaProvider.py:51) [DeltaProvider ][DEBUG ] file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/36354 UNICODE Ü) -- UID:file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/36354 UNICODE Ü mtime:2008-08-03 14:45:43 hash:0 (DeltaProvider.py:51) [DeltaProvider ][DEBUG ] file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/43737 UNICODE é) -- UID:file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/43737 UNICODE é mtime:2008-08-03 14:45:43 hash:0 (DeltaProvider.py:51) [DeltaProvider ][DEBUG ] file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/77714 UNICODE Ü) -- UID:file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/77714 UNICODE Ü mtime:2008-08-03 14:45:43 hash:0 (DeltaProvider.py:51) [DeltaProvider ][DEBUG ] file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/353104 UNICODE á) -- UID:file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/353104 UNICODE á mtime:2008-08-03 14:45:43 hash:0 (DeltaProvider.py:51) [DeltaProvider ][DEBUG ] file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/66836 UNICODE Ü) -- UID:file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/66836 UNICODE Ü mtime:2008-08-03 14:45:43 hash:0 (DeltaProvider.py:51) [DeltaProvider ][DEBUG ] file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/71664 UNICODE æ) -- UID:file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/71664 UNICODE æ mtime:2008-08-03 14:45:43 hash:0 (DeltaProvider.py:51) [datatypes.DataType ][DEBUG ] Getting Rid for file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/353104 UNICODE á (DataType.py:151) [datatypes.DataType ][DEBUG ] Getting Rid for file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/317110 UNICODE Ä (DataType.py:151) [datatypes.DataType ][DEBUG ] Getting Rid for file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/411045 UNICODE Ä (DataType.py:151) [datatypes.DataType ][DEBUG ] Getting Rid for file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/77714 UNICODE Ü (DataType.py:151) [datatypes.DataType ][DEBUG ] Getting Rid for file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/43737 UNICODE é (DataType.py:151) [datatypes.DataType ][DEBUG ] Getting Rid for file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/66836 UNICODE Ü (DataType.py:151) [datatypes.DataType ][DEBUG ] Getting Rid for file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/36354 UNICODE Ü (DataType.py:151) [datatypes.DataType ][DEBUG ] Getting Rid for file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/11070 UNICODE Ü (DataType.py:151) [datatypes.DataType ][DEBUG ] Getting Rid for file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/08357 UNICODE Ë (DataType.py:151) [datatypes.DataType ][DEBUG ] Getting Rid for file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/71664 UNICODE æ (DataType.py:151) [Syncronization ][DEBUG ] FolderTwoWay-file:///home/john/gnome-bzr/src/conduit/test/test-data/sink Changes: New 0 items [] (Synchronization.py:241) [Syncronization ][DEBUG ] FolderTwoWay-file:///home/john/gnome-bzr/src/conduit/test/test-data/sink Changes: Modified 0 items [] (Synchronization.py:242) [Syncronization ][DEBUG ] FolderTwoWay-file:///home/john/gnome-bzr/src/conduit/test/test-data/sink Changes: Deleted 0 items [] (Synchronization.py:243) [Syncronization ][DEBUG ] Syncworker state 3 (Synchronization.py:648) [Vfs ][DEBUG ] Folder scan 100.0% complete (Vfs.py:508) [Vfs ][DEBUG ] 10 files loaded in 0.0035080909729 seconds (Vfs.py:516) [Vfs ][DEBUG ] Folder scan 100.0% complete (Vfs.py:508) [Vfs ][DEBUG ] 10 files loaded in 0.00353097915649 seconds (Vfs.py:516) [Vfs ][DEBUG ] Folder scan 100.0% complete (Vfs.py:508) [Vfs ][DEBUG ] 10 files loaded in 0.00353908538818 seconds (Vfs.py:516) [Vfs ][DEBUG ] Folder scan 100.0% complete (Vfs.py:508) [Vfs ][DEBUG ] 10 files loaded in 0.00422787666321 seconds (Vfs.py:516) [Syncronization ][INFO ] Sync already in progress (Synchronization.py:172) [Syncronization ][INFO ] Waiting for thread to finish (Synchronization.py:124) [Syncronization ][DEBUG ] Created thread (thread: -1210054464) (Synchronization.py:211) [Syncronization ][INFO ] Setting global cancel flag (Synchronization.py:90) [Syncronization ][DEBUG ] Starting worker: Wrapper: sink twoway (UID: FolderTwoWay-file:///home/john/gnome-bzr/src/conduit/test/test-data/sink), initial)> (Synchronization.py:93) [Syncronization ][INFO ] Waiting for thread to finish (Synchronization.py:124) [Syncronization ][DEBUG ] Started thread Wrapper: sink twoway (UID: FolderTwoWay-file:///home/john/gnome-bzr/src/conduit/test/test-data/sink), started)> (thread: -1263223920) (Synchronization.py:624) [Syncronization ][DEBUG ] Sync Wrapper: sink twoway (UID: FolderTwoWay-file:///home/john/gnome-bzr/src/conduit/test/test-data/sink), started)> beginning. Slow: False, Twoway: True (Synchronization.py:629) [Syncronization ][DEBUG ] Syncworker state 0 (Synchronization.py:648) [Syncronization ][DEBUG ] Syncworker state 1 (Synchronization.py:648) [Syncronization ][DEBUG ] Source Status = Synchronizing... (Synchronization.py:677) [Vfs ][DEBUG ] Folder scan 100.0% complete (Vfs.py:508) [Vfs ][DEBUG ] 10 files loaded in 0.00650906562805 seconds (Vfs.py:516) [Vfs ][DEBUG ] Folder scan 100.0% complete (Vfs.py:508) [Vfs ][DEBUG ] 10 files loaded in 0.0100419521332 seconds (Vfs.py:516) [Syncronization ][DEBUG ] Syncworker state 2 (Synchronization.py:648) [Syncronization ][INFO ] Synchronizing (Two Way) Wrapper: source twoway (UID: FolderTwoWay-file:///home/john/gnome-bzr/src/conduit/test/test-data/source) <--> Wrapper: sink twoway (UID: FolderTwoWay-file:///home/john/gnome-bzr/src/conduit/test/test-data/sink) (Synchronization.py:514) [DeltaProvider ][INFO ] Delta: Source (FolderTwoWay-file:///home/john/gnome-bzr/src/conduit/test/test-data/source) does not implement get_changes(). Proxying... (DeltaProvider.py:25) [DeltaProvider ][DEBUG ] Delta: Got 10 items [u'file:///home/john/gnome-bzr/src/conduit/test/test-data/source/66836 UNICODE \xdc', u'file:///home/john/gnome-bzr/src/conduit/test/test-data/source/36354 UNICODE \xdc', u'file:///home/john/gnome-bzr/src/conduit/test/test-data/source/11070 UNICODE \xdc', u'file:///home/john/gnome-bzr/src/conduit/test/test-data/source/08357 UNICODE \xcb', u'file:///home/john/gnome-bzr/src/conduit/test/test-data/source/71664 UNICODE \xe6', u'file:///home/john/gnome-bzr/src/conduit/test/test-data/source/353104 UNICODE \xe1', u'file:///home/john/gnome-bzr/src/conduit/test/test-data/source/317110 UNICODE \xc4', u'file:///home/john/gnome-bzr/src/conduit/test/test-data/source/411045 UNICODE \xc4', u'file:///home/john/gnome-bzr/src/conduit/test/test-data/source/77714 UNICODE \xdc', u'file:///home/john/gnome-bzr/src/conduit/test/test-data/source/43737 UNICODE \xe9'] (DeltaProvider.py:39) [DeltaProvider ][DEBUG ] Delta: Expecting 10 items (DeltaProvider.py:49) [DeltaProvider ][DEBUG ] file:///home/john/gnome-bzr/src/conduit/test/test-data/source/08357 UNICODE Ë) -- UID:file:///home/john/gnome-bzr/src/conduit/test/test-data/source/08357 UNICODE Ë mtime:2008-08-03 14:45:43 hash:0 (DeltaProvider.py:51) [DeltaProvider ][DEBUG ] file:///home/john/gnome-bzr/src/conduit/test/test-data/source/43737 UNICODE é) -- UID:file:///home/john/gnome-bzr/src/conduit/test/test-data/source/43737 UNICODE é mtime:2008-08-03 14:45:43 hash:0 (DeltaProvider.py:51) [DeltaProvider ][DEBUG ] file:///home/john/gnome-bzr/src/conduit/test/test-data/source/411045 UNICODE Ä) -- UID:file:///home/john/gnome-bzr/src/conduit/test/test-data/source/411045 UNICODE Ä mtime:2008-08-03 14:45:43 hash:0 (DeltaProvider.py:51) [DeltaProvider ][DEBUG ] file:///home/john/gnome-bzr/src/conduit/test/test-data/source/317110 UNICODE Ä) -- UID:file:///home/john/gnome-bzr/src/conduit/test/test-data/source/317110 UNICODE Ä mtime:2008-08-03 14:45:43 hash:0 (DeltaProvider.py:51) [DeltaProvider ][DEBUG ] file:///home/john/gnome-bzr/src/conduit/test/test-data/source/71664 UNICODE æ) -- UID:file:///home/john/gnome-bzr/src/conduit/test/test-data/source/71664 UNICODE æ mtime:2008-08-03 14:45:43 hash:0 (DeltaProvider.py:51) [DeltaProvider ][DEBUG ] file:///home/john/gnome-bzr/src/conduit/test/test-data/source/36354 UNICODE Ü) -- UID:file:///home/john/gnome-bzr/src/conduit/test/test-data/source/36354 UNICODE Ü mtime:2008-08-03 14:45:43 hash:0 (DeltaProvider.py:51) [DeltaProvider ][DEBUG ] file:///home/john/gnome-bzr/src/conduit/test/test-data/source/77714 UNICODE Ü) -- UID:file:///home/john/gnome-bzr/src/conduit/test/test-data/source/77714 UNICODE Ü mtime:2008-08-03 14:45:43 hash:0 (DeltaProvider.py:51) [DeltaProvider ][DEBUG ] file:///home/john/gnome-bzr/src/conduit/test/test-data/source/11070 UNICODE Ü) -- UID:file:///home/john/gnome-bzr/src/conduit/test/test-data/source/11070 UNICODE Ü mtime:2008-08-03 14:45:43 hash:0 (DeltaProvider.py:51) [DeltaProvider ][DEBUG ] file:///home/john/gnome-bzr/src/conduit/test/test-data/source/353104 UNICODE á) -- UID:file:///home/john/gnome-bzr/src/conduit/test/test-data/source/353104 UNICODE á mtime:2008-08-03 14:45:43 hash:0 (DeltaProvider.py:51) [DeltaProvider ][DEBUG ] file:///home/john/gnome-bzr/src/conduit/test/test-data/source/66836 UNICODE Ü) -- UID:file:///home/john/gnome-bzr/src/conduit/test/test-data/source/66836 UNICODE Ü mtime:2008-08-03 14:45:43 hash:0 (DeltaProvider.py:51) [datatypes.DataType ][DEBUG ] Getting Rid for file:///home/john/gnome-bzr/src/conduit/test/test-data/source/66836 UNICODE Ü (DataType.py:151) [datatypes.DataType ][DEBUG ] Getting Rid for file:///home/john/gnome-bzr/src/conduit/test/test-data/source/36354 UNICODE Ü (DataType.py:151) [datatypes.DataType ][DEBUG ] Getting Rid for file:///home/john/gnome-bzr/src/conduit/test/test-data/source/11070 UNICODE Ü (DataType.py:151) [datatypes.DataType ][DEBUG ] Getting Rid for file:///home/john/gnome-bzr/src/conduit/test/test-data/source/08357 UNICODE Ë (DataType.py:151) [datatypes.DataType ][DEBUG ] Getting Rid for file:///home/john/gnome-bzr/src/conduit/test/test-data/source/71664 UNICODE æ (DataType.py:151) [datatypes.DataType ][DEBUG ] Getting Rid for file:///home/john/gnome-bzr/src/conduit/test/test-data/source/353104 UNICODE á (DataType.py:151) [datatypes.DataType ][DEBUG ] Getting Rid for file:///home/john/gnome-bzr/src/conduit/test/test-data/source/317110 UNICODE Ä (DataType.py:151) [datatypes.DataType ][DEBUG ] Getting Rid for file:///home/john/gnome-bzr/src/conduit/test/test-data/source/411045 UNICODE Ä (DataType.py:151) [datatypes.DataType ][DEBUG ] Getting Rid for file:///home/john/gnome-bzr/src/conduit/test/test-data/source/77714 UNICODE Ü (DataType.py:151) [datatypes.DataType ][DEBUG ] Getting Rid for file:///home/john/gnome-bzr/src/conduit/test/test-data/source/43737 UNICODE é (DataType.py:151) [Syncronization ][DEBUG ] FolderTwoWay-file:///home/john/gnome-bzr/src/conduit/test/test-data/source Changes: New 0 items [] (Synchronization.py:241) [Syncronization ][DEBUG ] FolderTwoWay-file:///home/john/gnome-bzr/src/conduit/test/test-data/source Changes: Modified 0 items [] (Synchronization.py:242) [Syncronization ][DEBUG ] FolderTwoWay-file:///home/john/gnome-bzr/src/conduit/test/test-data/source Changes: Deleted 0 items [] (Synchronization.py:243) [DeltaProvider ][INFO ] Delta: Source (FolderTwoWay-file:///home/john/gnome-bzr/src/conduit/test/test-data/sink) does not implement get_changes(). Proxying... (DeltaProvider.py:25) [DeltaProvider ][DEBUG ] Delta: Got 10 items [u'file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/353104 UNICODE \xe1', u'file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/317110 UNICODE \xc4', u'file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/411045 UNICODE \xc4', u'file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/77714 UNICODE \xdc', u'file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/43737 UNICODE \xe9', u'file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/66836 UNICODE \xdc', u'file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/36354 UNICODE \xdc', u'file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/11070 UNICODE \xdc', u'file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/08357 UNICODE \xcb', u'file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/71664 UNICODE \xe6'] (DeltaProvider.py:39) [DeltaProvider ][DEBUG ] Delta: Expecting 10 items (DeltaProvider.py:49) [DeltaProvider ][DEBUG ] file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/411045 UNICODE Ä) -- UID:file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/411045 UNICODE Ä mtime:2008-08-03 14:45:43 hash:0 (DeltaProvider.py:51) [DeltaProvider ][DEBUG ] file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/11070 UNICODE Ü) -- UID:file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/11070 UNICODE Ü mtime:2008-08-03 14:45:43 hash:0 (DeltaProvider.py:51) [DeltaProvider ][DEBUG ] file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/08357 UNICODE Ë) -- UID:file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/08357 UNICODE Ë mtime:2008-08-03 14:45:43 hash:0 (DeltaProvider.py:51) [DeltaProvider ][DEBUG ] file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/317110 UNICODE Ä) -- UID:file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/317110 UNICODE Ä mtime:2008-08-03 14:45:43 hash:0 (DeltaProvider.py:51) [DeltaProvider ][DEBUG ] file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/36354 UNICODE Ü) -- UID:file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/36354 UNICODE Ü mtime:2008-08-03 14:45:43 hash:0 (DeltaProvider.py:51) [DeltaProvider ][DEBUG ] file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/43737 UNICODE é) -- UID:file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/43737 UNICODE é mtime:2008-08-03 14:45:43 hash:0 (DeltaProvider.py:51) [DeltaProvider ][DEBUG ] file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/77714 UNICODE Ü) -- UID:file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/77714 UNICODE Ü mtime:2008-08-03 14:45:43 hash:0 (DeltaProvider.py:51) [DeltaProvider ][DEBUG ] file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/353104 UNICODE á) -- UID:file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/353104 UNICODE á mtime:2008-08-03 14:45:43 hash:0 (DeltaProvider.py:51) [DeltaProvider ][DEBUG ] file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/66836 UNICODE Ü) -- UID:file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/66836 UNICODE Ü mtime:2008-08-03 14:45:43 hash:0 (DeltaProvider.py:51) [DeltaProvider ][DEBUG ] file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/71664 UNICODE æ) -- UID:file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/71664 UNICODE æ mtime:2008-08-03 14:45:43 hash:0 (DeltaProvider.py:51) [datatypes.DataType ][DEBUG ] Getting Rid for file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/353104 UNICODE á (DataType.py:151) [datatypes.DataType ][DEBUG ] Getting Rid for file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/317110 UNICODE Ä (DataType.py:151) [datatypes.DataType ][DEBUG ] Getting Rid for file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/411045 UNICODE Ä (DataType.py:151) [datatypes.DataType ][DEBUG ] Getting Rid for file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/77714 UNICODE Ü (DataType.py:151) [datatypes.DataType ][DEBUG ] Getting Rid for file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/43737 UNICODE é (DataType.py:151) [datatypes.DataType ][DEBUG ] Getting Rid for file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/66836 UNICODE Ü (DataType.py:151) [datatypes.DataType ][DEBUG ] Getting Rid for file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/36354 UNICODE Ü (DataType.py:151) [datatypes.DataType ][DEBUG ] Getting Rid for file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/11070 UNICODE Ü (DataType.py:151) [datatypes.DataType ][DEBUG ] Getting Rid for file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/08357 UNICODE Ë (DataType.py:151) [datatypes.DataType ][DEBUG ] Getting Rid for file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/71664 UNICODE æ (DataType.py:151) [Syncronization ][DEBUG ] FolderTwoWay-file:///home/john/gnome-bzr/src/conduit/test/test-data/sink Changes: New 0 items [] (Synchronization.py:241) [Syncronization ][DEBUG ] FolderTwoWay-file:///home/john/gnome-bzr/src/conduit/test/test-data/sink Changes: Modified 0 items [] (Synchronization.py:242) [Syncronization ][DEBUG ] FolderTwoWay-file:///home/john/gnome-bzr/src/conduit/test/test-data/sink Changes: Deleted 0 items [] (Synchronization.py:243) [Syncronization ][DEBUG ] Syncworker state 3 (Synchronization.py:648) [Vfs ][DEBUG ] Folder scan 100.0% complete (Vfs.py:508) [Vfs ][DEBUG ] 10 files loaded in 0.00479412078857 seconds (Vfs.py:516) [Vfs ][DEBUG ] Folder scan 100.0% complete (Vfs.py:508) [Vfs ][DEBUG ] 10 files loaded in 0.00350689888 seconds (Vfs.py:516) [Vfs ][DEBUG ] Folder scan 100.0% complete (Vfs.py:508) [Vfs ][DEBUG ] 10 files loaded in 0.00640082359314 seconds (Vfs.py:516) [Vfs ][DEBUG ] Folder scan 100.0% complete (Vfs.py:508) [Vfs ][DEBUG ] 10 files loaded in 0.00351595878601 seconds (Vfs.py:516) [datatypes.File ][DEBUG ] Comparing file:///home/john/gnome-bzr/src/conduit/test/test-data/source/66836 UNICODE Ü (MTIME: 2008-08-03 14:45:43) with file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/66836 UNICODE Ü (MTIME: 2008-08-03 14:45:43) (File.py:472) [datatypes.File ][DEBUG ] Comparing file:///home/john/gnome-bzr/src/conduit/test/test-data/source/353104 UNICODE á (MTIME: 2008-08-03 14:45:43) with file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/353104 UNICODE á (MTIME: 2008-08-03 14:45:43) (File.py:472) [datatypes.File ][DEBUG ] Comparing file:///home/john/gnome-bzr/src/conduit/test/test-data/source/36354 UNICODE Ü (MTIME: 2008-08-03 14:45:43) with file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/36354 UNICODE Ü (MTIME: 2008-08-03 14:45:43) (File.py:472) [datatypes.File ][DEBUG ] Comparing file:///home/john/gnome-bzr/src/conduit/test/test-data/source/317110 UNICODE Ä (MTIME: 2008-08-03 14:45:43) with file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/317110 UNICODE Ä (MTIME: 2008-08-03 14:45:43) (File.py:472) [datatypes.File ][DEBUG ] Comparing file:///home/john/gnome-bzr/src/conduit/test/test-data/source/11070 UNICODE Ü (MTIME: 2008-08-03 14:45:43) with file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/11070 UNICODE Ü (MTIME: 2008-08-03 14:45:43) (File.py:472) [datatypes.File ][DEBUG ] Comparing file:///home/john/gnome-bzr/src/conduit/test/test-data/source/411045 UNICODE Ä (MTIME: 2008-08-03 14:45:43) with file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/411045 UNICODE Ä (MTIME: 2008-08-03 14:45:43) (File.py:472) [datatypes.File ][DEBUG ] Comparing file:///home/john/gnome-bzr/src/conduit/test/test-data/source/08357 UNICODE Ë (MTIME: 2008-08-03 14:45:43) with file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/08357 UNICODE Ë (MTIME: 2008-08-03 14:45:43) (File.py:472) [datatypes.File ][DEBUG ] Comparing file:///home/john/gnome-bzr/src/conduit/test/test-data/source/77714 UNICODE Ü (MTIME: 2008-08-03 14:45:43) with file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/77714 UNICODE Ü (MTIME: 2008-08-03 14:45:43) (File.py:472) [datatypes.File ][DEBUG ] Comparing file:///home/john/gnome-bzr/src/conduit/test/test-data/source/71664 UNICODE æ (MTIME: 2008-08-03 14:45:43) with file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/71664 UNICODE æ (MTIME: 2008-08-03 14:45:43) (File.py:472) [datatypes.File ][DEBUG ] Comparing file:///home/john/gnome-bzr/src/conduit/test/test-data/source/43737 UNICODE é (MTIME: 2008-08-03 14:45:43) with file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/43737 UNICODE é (MTIME: 2008-08-03 14:45:43) (File.py:472) [Syncronization ][INFO ] Sync already in progress (Synchronization.py:172) [Syncronization ][INFO ] Waiting for thread to finish (Synchronization.py:124) [Syncronization ][DEBUG ] Created thread (thread: -1210054464) (Synchronization.py:211) [Syncronization ][INFO ] Setting global cancel flag (Synchronization.py:90) [Syncronization ][DEBUG ] Starting worker: Wrapper: sink twoway (UID: FolderTwoWay-file:///home/john/gnome-bzr/src/conduit/test/test-data/sink), initial)> (Synchronization.py:93) [Syncronization ][INFO ] Waiting for thread to finish (Synchronization.py:124) [Syncronization ][DEBUG ] Started thread Wrapper: sink twoway (UID: FolderTwoWay-file:///home/john/gnome-bzr/src/conduit/test/test-data/sink), started)> (thread: -1263223920) (Synchronization.py:624) [Syncronization ][DEBUG ] Sync Wrapper: sink twoway (UID: FolderTwoWay-file:///home/john/gnome-bzr/src/conduit/test/test-data/sink), started)> beginning. Slow: False, Twoway: True (Synchronization.py:629) [Syncronization ][DEBUG ] Syncworker state 0 (Synchronization.py:648) [Syncronization ][DEBUG ] Syncworker state 1 (Synchronization.py:648) [Syncronization ][DEBUG ] Source Status = Synchronizing... (Synchronization.py:677) [Vfs ][DEBUG ] Folder scan 100.0% complete (Vfs.py:508) [Vfs ][DEBUG ] 7 files loaded in 0.00390100479126 seconds (Vfs.py:516) [Vfs ][DEBUG ] Folder scan 100.0% complete (Vfs.py:508) [Vfs ][DEBUG ] 8 files loaded in 0.00388503074646 seconds (Vfs.py:516) [Syncronization ][DEBUG ] Syncworker state 2 (Synchronization.py:648) [Syncronization ][INFO ] Synchronizing (Two Way) Wrapper: source twoway (UID: FolderTwoWay-file:///home/john/gnome-bzr/src/conduit/test/test-data/source) <--> Wrapper: sink twoway (UID: FolderTwoWay-file:///home/john/gnome-bzr/src/conduit/test/test-data/sink) (Synchronization.py:514) [DeltaProvider ][INFO ] Delta: Source (FolderTwoWay-file:///home/john/gnome-bzr/src/conduit/test/test-data/source) does not implement get_changes(). Proxying... (DeltaProvider.py:25) [DeltaProvider ][DEBUG ] Delta: Got 7 items [u'file:///home/john/gnome-bzr/src/conduit/test/test-data/source/36354 UNICODE \xdc', u'file:///home/john/gnome-bzr/src/conduit/test/test-data/source/08357 UNICODE \xcb', u'file:///home/john/gnome-bzr/src/conduit/test/test-data/source/353104 UNICODE \xe1', u'file:///home/john/gnome-bzr/src/conduit/test/test-data/source/317110 UNICODE \xc4', u'file:///home/john/gnome-bzr/src/conduit/test/test-data/source/411045 UNICODE \xc4', u'file:///home/john/gnome-bzr/src/conduit/test/test-data/source/77714 UNICODE \xdc', u'file:///home/john/gnome-bzr/src/conduit/test/test-data/source/43737 UNICODE \xe9'] (DeltaProvider.py:39) [DeltaProvider ][DEBUG ] Delta: Expecting 10 items (DeltaProvider.py:49) [DeltaProvider ][DEBUG ] file:///home/john/gnome-bzr/src/conduit/test/test-data/source/08357 UNICODE Ë) -- UID:file:///home/john/gnome-bzr/src/conduit/test/test-data/source/08357 UNICODE Ë mtime:2008-08-03 14:45:43 hash:0 (DeltaProvider.py:51) [DeltaProvider ][DEBUG ] file:///home/john/gnome-bzr/src/conduit/test/test-data/source/43737 UNICODE é) -- UID:file:///home/john/gnome-bzr/src/conduit/test/test-data/source/43737 UNICODE é mtime:2008-08-03 14:45:43 hash:0 (DeltaProvider.py:51) [DeltaProvider ][DEBUG ] file:///home/john/gnome-bzr/src/conduit/test/test-data/source/411045 UNICODE Ä) -- UID:file:///home/john/gnome-bzr/src/conduit/test/test-data/source/411045 UNICODE Ä mtime:2008-08-03 14:45:43 hash:0 (DeltaProvider.py:51) [DeltaProvider ][DEBUG ] file:///home/john/gnome-bzr/src/conduit/test/test-data/source/317110 UNICODE Ä) -- UID:file:///home/john/gnome-bzr/src/conduit/test/test-data/source/317110 UNICODE Ä mtime:2008-08-03 14:45:43 hash:0 (DeltaProvider.py:51) [DeltaProvider ][DEBUG ] file:///home/john/gnome-bzr/src/conduit/test/test-data/source/71664 UNICODE æ) -- UID:file:///home/john/gnome-bzr/src/conduit/test/test-data/source/71664 UNICODE æ mtime:2008-08-03 14:45:43 hash:0 (DeltaProvider.py:51) [DeltaProvider ][DEBUG ] file:///home/john/gnome-bzr/src/conduit/test/test-data/source/36354 UNICODE Ü) -- UID:file:///home/john/gnome-bzr/src/conduit/test/test-data/source/36354 UNICODE Ü mtime:2008-08-03 14:45:43 hash:0 (DeltaProvider.py:51) [DeltaProvider ][DEBUG ] file:///home/john/gnome-bzr/src/conduit/test/test-data/source/77714 UNICODE Ü) -- UID:file:///home/john/gnome-bzr/src/conduit/test/test-data/source/77714 UNICODE Ü mtime:2008-08-03 14:45:43 hash:0 (DeltaProvider.py:51) [DeltaProvider ][DEBUG ] file:///home/john/gnome-bzr/src/conduit/test/test-data/source/11070 UNICODE Ü) -- UID:file:///home/john/gnome-bzr/src/conduit/test/test-data/source/11070 UNICODE Ü mtime:2008-08-03 14:45:43 hash:0 (DeltaProvider.py:51) [DeltaProvider ][DEBUG ] file:///home/john/gnome-bzr/src/conduit/test/test-data/source/353104 UNICODE á) -- UID:file:///home/john/gnome-bzr/src/conduit/test/test-data/source/353104 UNICODE á mtime:2008-08-03 14:45:43 hash:0 (DeltaProvider.py:51) [DeltaProvider ][DEBUG ] file:///home/john/gnome-bzr/src/conduit/test/test-data/source/66836 UNICODE Ü) -- UID:file:///home/john/gnome-bzr/src/conduit/test/test-data/source/66836 UNICODE Ü mtime:2008-08-03 14:45:43 hash:0 (DeltaProvider.py:51) [datatypes.DataType ][DEBUG ] Getting Rid for file:///home/john/gnome-bzr/src/conduit/test/test-data/source/36354 UNICODE Ü (DataType.py:151) [datatypes.DataType ][DEBUG ] Getting Rid for file:///home/john/gnome-bzr/src/conduit/test/test-data/source/08357 UNICODE Ë (DataType.py:151) [datatypes.DataType ][DEBUG ] Getting Rid for file:///home/john/gnome-bzr/src/conduit/test/test-data/source/353104 UNICODE á (DataType.py:151) [datatypes.DataType ][DEBUG ] Getting Rid for file:///home/john/gnome-bzr/src/conduit/test/test-data/source/317110 UNICODE Ä (DataType.py:151) [datatypes.DataType ][DEBUG ] Getting Rid for file:///home/john/gnome-bzr/src/conduit/test/test-data/source/411045 UNICODE Ä (DataType.py:151) [datatypes.DataType ][DEBUG ] Getting Rid for file:///home/john/gnome-bzr/src/conduit/test/test-data/source/77714 UNICODE Ü (DataType.py:151) [datatypes.DataType ][DEBUG ] Getting Rid for file:///home/john/gnome-bzr/src/conduit/test/test-data/source/43737 UNICODE é (DataType.py:151) [Syncronization ][DEBUG ] FolderTwoWay-file:///home/john/gnome-bzr/src/conduit/test/test-data/source Changes: New 0 items [] (Synchronization.py:241) [Syncronization ][DEBUG ] FolderTwoWay-file:///home/john/gnome-bzr/src/conduit/test/test-data/source Changes: Modified 0 items [] (Synchronization.py:242) [Syncronization ][DEBUG ] FolderTwoWay-file:///home/john/gnome-bzr/src/conduit/test/test-data/source Changes: Deleted 3 items [u'file:///home/john/gnome-bzr/src/conduit/test/test-data/source/71664 UNICODE \xe6', u'file:///home/john/gnome-bzr/src/conduit/test/test-data/source/11070 UNICODE \xdc', u'file:///home/john/gnome-bzr/src/conduit/test/test-data/source/66836 UNICODE \xdc'] (Synchronization.py:243) [DeltaProvider ][INFO ] Delta: Source (FolderTwoWay-file:///home/john/gnome-bzr/src/conduit/test/test-data/sink) does not implement get_changes(). Proxying... (DeltaProvider.py:25) [DeltaProvider ][DEBUG ] Delta: Got 8 items [u'file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/353104 UNICODE \xe1', u'file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/317110 UNICODE \xc4', u'file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/411045 UNICODE \xc4', u'file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/77714 UNICODE \xdc', u'file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/43737 UNICODE \xe9', u'file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/66836 UNICODE \xdc', u'file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/11070 UNICODE \xdc', u'file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/71664 UNICODE \xe6'] (DeltaProvider.py:39) [DeltaProvider ][DEBUG ] Delta: Expecting 10 items (DeltaProvider.py:49) [DeltaProvider ][DEBUG ] file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/411045 UNICODE Ä) -- UID:file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/411045 UNICODE Ä mtime:2008-08-03 14:45:43 hash:0 (DeltaProvider.py:51) [DeltaProvider ][DEBUG ] file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/11070 UNICODE Ü) -- UID:file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/11070 UNICODE Ü mtime:2008-08-03 14:45:43 hash:0 (DeltaProvider.py:51) [DeltaProvider ][DEBUG ] file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/08357 UNICODE Ë) -- UID:file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/08357 UNICODE Ë mtime:2008-08-03 14:45:43 hash:0 (DeltaProvider.py:51) [DeltaProvider ][DEBUG ] file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/317110 UNICODE Ä) -- UID:file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/317110 UNICODE Ä mtime:2008-08-03 14:45:43 hash:0 (DeltaProvider.py:51) [DeltaProvider ][DEBUG ] file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/36354 UNICODE Ü) -- UID:file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/36354 UNICODE Ü mtime:2008-08-03 14:45:43 hash:0 (DeltaProvider.py:51) [DeltaProvider ][DEBUG ] file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/43737 UNICODE é) -- UID:file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/43737 UNICODE é mtime:2008-08-03 14:45:43 hash:0 (DeltaProvider.py:51) [DeltaProvider ][DEBUG ] file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/77714 UNICODE Ü) -- UID:file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/77714 UNICODE Ü mtime:2008-08-03 14:45:43 hash:0 (DeltaProvider.py:51) [DeltaProvider ][DEBUG ] file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/353104 UNICODE á) -- UID:file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/353104 UNICODE á mtime:2008-08-03 14:45:43 hash:0 (DeltaProvider.py:51) [DeltaProvider ][DEBUG ] file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/66836 UNICODE Ü) -- UID:file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/66836 UNICODE Ü mtime:2008-08-03 14:45:43 hash:0 (DeltaProvider.py:51) [DeltaProvider ][DEBUG ] file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/71664 UNICODE æ) -- UID:file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/71664 UNICODE æ mtime:2008-08-03 14:45:43 hash:0 (DeltaProvider.py:51) [datatypes.DataType ][DEBUG ] Getting Rid for file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/353104 UNICODE á (DataType.py:151) [datatypes.DataType ][DEBUG ] Getting Rid for file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/317110 UNICODE Ä (DataType.py:151) [datatypes.DataType ][DEBUG ] Getting Rid for file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/411045 UNICODE Ä (DataType.py:151) [datatypes.DataType ][DEBUG ] Getting Rid for file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/77714 UNICODE Ü (DataType.py:151) [datatypes.DataType ][DEBUG ] Getting Rid for file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/43737 UNICODE é (DataType.py:151) [datatypes.DataType ][DEBUG ] Getting Rid for file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/66836 UNICODE Ü (DataType.py:151) [datatypes.DataType ][DEBUG ] Getting Rid for file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/11070 UNICODE Ü (DataType.py:151) [datatypes.DataType ][DEBUG ] Getting Rid for file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/71664 UNICODE æ (DataType.py:151) [Syncronization ][DEBUG ] FolderTwoWay-file:///home/john/gnome-bzr/src/conduit/test/test-data/sink Changes: New 0 items [] (Synchronization.py:241) [Syncronization ][DEBUG ] FolderTwoWay-file:///home/john/gnome-bzr/src/conduit/test/test-data/sink Changes: Modified 0 items [] (Synchronization.py:242) [Syncronization ][DEBUG ] FolderTwoWay-file:///home/john/gnome-bzr/src/conduit/test/test-data/sink Changes: Deleted 2 items [u'file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/08357 UNICODE \xcb', u'file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/36354 UNICODE \xdc'] (Synchronization.py:243) [Syncronization ][DEBUG ] 2WAY DEL: Folder (file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/71664 UNICODE æ) (Synchronization.py:558) [Syncronization ][DEBUG ] Deleted Policy: Delete (Synchronization.py:408) [Syncronization ][INFO ] Deleting file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/71664 UNICODE æ from FolderTwoWay-file:///home/john/gnome-bzr/src/conduit/test/test-data/sink (Synchronization.py:52) [datatypes.File ][DEBUG ] Closing file (File.py:58) [datatypes.File ][DEBUG ] Deleting file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/71664 UNICODE æ (File.py:340) [Syncronization ][DEBUG ] 2WAY DEL: Folder (file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/11070 UNICODE Ü) (Synchronization.py:558) [Syncronization ][DEBUG ] Deleted Policy: Delete (Synchronization.py:408) [Syncronization ][INFO ] Deleting file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/11070 UNICODE Ü from FolderTwoWay-file:///home/john/gnome-bzr/src/conduit/test/test-data/sink (Synchronization.py:52) [datatypes.File ][DEBUG ] Closing file (File.py:58) [datatypes.File ][DEBUG ] Deleting file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/11070 UNICODE Ü (File.py:340) [Syncronization ][DEBUG ] 2WAY DEL: Folder (file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/66836 UNICODE Ü) (Synchronization.py:558) [Syncronization ][DEBUG ] Deleted Policy: Delete (Synchronization.py:408) [Syncronization ][INFO ] Deleting file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/66836 UNICODE Ü from FolderTwoWay-file:///home/john/gnome-bzr/src/conduit/test/test-data/sink (Synchronization.py:52) [datatypes.File ][DEBUG ] Closing file (File.py:58) [datatypes.File ][DEBUG ] Deleting file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/66836 UNICODE Ü (File.py:340) [Syncronization ][DEBUG ] 2WAY DEL: Folder (file:///home/john/gnome-bzr/src/conduit/test/test-data/source/08357 UNICODE Ë) (Synchronization.py:558) [Syncronization ][DEBUG ] Deleted Policy: Delete (Synchronization.py:408) [Syncronization ][INFO ] Deleting file:///home/john/gnome-bzr/src/conduit/test/test-data/source/08357 UNICODE Ë from FolderTwoWay-file:///home/john/gnome-bzr/src/conduit/test/test-data/source (Synchronization.py:52) [datatypes.File ][DEBUG ] Closing file (File.py:58) [datatypes.File ][DEBUG ] Deleting file:///home/john/gnome-bzr/src/conduit/test/test-data/source/08357 UNICODE Ë (File.py:340) [Syncronization ][DEBUG ] 2WAY DEL: Folder (file:///home/john/gnome-bzr/src/conduit/test/test-data/source/36354 UNICODE Ü) (Synchronization.py:558) [Syncronization ][DEBUG ] Deleted Policy: Delete (Synchronization.py:408) [Syncronization ][INFO ] Deleting file:///home/john/gnome-bzr/src/conduit/test/test-data/source/36354 UNICODE Ü from FolderTwoWay-file:///home/john/gnome-bzr/src/conduit/test/test-data/source (Synchronization.py:52) [datatypes.File ][DEBUG ] Closing file (File.py:58) [datatypes.File ][DEBUG ] Deleting file:///home/john/gnome-bzr/src/conduit/test/test-data/source/36354 UNICODE Ü (File.py:340) [Syncronization ][DEBUG ] Syncworker state 3 (Synchronization.py:648) [Vfs ][DEBUG ] Folder scan 100.0% complete (Vfs.py:508) [Vfs ][DEBUG ] 5 files loaded in 0.00355792045593 seconds (Vfs.py:516) [Vfs ][DEBUG ] Folder scan 100.0% complete (Vfs.py:508) [Vfs ][DEBUG ] 5 files loaded in 0.00394988059998 seconds (Vfs.py:516) [Vfs ][DEBUG ] Folder scan 100.0% complete (Vfs.py:508) [Vfs ][DEBUG ] 5 files loaded in 0.00297498703003 seconds (Vfs.py:516) [Vfs ][DEBUG ] Folder scan 100.0% complete (Vfs.py:508) [Vfs ][DEBUG ] 5 files loaded in 0.0030460357666 seconds (Vfs.py:516) [Syncronization ][INFO ] Sync already in progress (Synchronization.py:172) [Syncronization ][INFO ] Waiting for thread to finish (Synchronization.py:124) [Syncronization ][DEBUG ] Created thread (thread: -1210054464) (Synchronization.py:211) [Syncronization ][INFO ] Setting global cancel flag (Synchronization.py:90) [Syncronization ][DEBUG ] Starting worker: Wrapper: sink twoway (UID: FolderTwoWay-file:///home/john/gnome-bzr/src/conduit/test/test-data/sink), initial)> (Synchronization.py:93) [Syncronization ][DEBUG ] Started thread Wrapper: sink twoway (UID: FolderTwoWay-file:///home/john/gnome-bzr/src/conduit/test/test-data/sink), started)> (thread: -1263223920) (Synchronization.py:624) [Syncronization ][DEBUG ] Sync Wrapper: sink twoway (UID: FolderTwoWay-file:///home/john/gnome-bzr/src/conduit/test/test-data/sink), started)> beginning. Slow: False, Twoway: True (Synchronization.py:629) [Syncronization ][INFO ] Waiting for thread to finish (Synchronization.py:124) [Syncronization ][DEBUG ] Syncworker state 0 (Synchronization.py:648) [Syncronization ][DEBUG ] Syncworker state 1 (Synchronization.py:648) [Syncronization ][DEBUG ] Source Status = Synchronizing... (Synchronization.py:677) [Vfs ][DEBUG ] Folder scan 100.0% complete (Vfs.py:508) [Vfs ][DEBUG ] 5 files loaded in 0.00512313842773 seconds (Vfs.py:516) [Vfs ][DEBUG ] Folder scan 100.0% complete (Vfs.py:508) [Vfs ][DEBUG ] 5 files loaded in 0.00281691551208 seconds (Vfs.py:516) [Syncronization ][DEBUG ] Syncworker state 2 (Synchronization.py:648) [Syncronization ][INFO ] Synchronizing (Two Way) Wrapper: source twoway (UID: FolderTwoWay-file:///home/john/gnome-bzr/src/conduit/test/test-data/source) <--> Wrapper: sink twoway (UID: FolderTwoWay-file:///home/john/gnome-bzr/src/conduit/test/test-data/sink) (Synchronization.py:514) [DeltaProvider ][INFO ] Delta: Source (FolderTwoWay-file:///home/john/gnome-bzr/src/conduit/test/test-data/source) does not implement get_changes(). Proxying... (DeltaProvider.py:25) [DeltaProvider ][DEBUG ] Delta: Got 5 items [u'file:///home/john/gnome-bzr/src/conduit/test/test-data/source/353104 UNICODE \xe1', u'file:///home/john/gnome-bzr/src/conduit/test/test-data/source/317110 UNICODE \xc4', u'file:///home/john/gnome-bzr/src/conduit/test/test-data/source/411045 UNICODE \xc4', u'file:///home/john/gnome-bzr/src/conduit/test/test-data/source/77714 UNICODE \xdc', u'file:///home/john/gnome-bzr/src/conduit/test/test-data/source/43737 UNICODE \xe9'] (DeltaProvider.py:39) [DeltaProvider ][DEBUG ] Delta: Expecting 5 items (DeltaProvider.py:49) [DeltaProvider ][DEBUG ] file:///home/john/gnome-bzr/src/conduit/test/test-data/source/317110 UNICODE Ä) -- UID:file:///home/john/gnome-bzr/src/conduit/test/test-data/source/317110 UNICODE Ä mtime:2008-08-03 14:45:43 hash:0 (DeltaProvider.py:51) [DeltaProvider ][DEBUG ] file:///home/john/gnome-bzr/src/conduit/test/test-data/source/43737 UNICODE é) -- UID:file:///home/john/gnome-bzr/src/conduit/test/test-data/source/43737 UNICODE é mtime:2008-08-03 14:45:43 hash:0 (DeltaProvider.py:51) [DeltaProvider ][DEBUG ] file:///home/john/gnome-bzr/src/conduit/test/test-data/source/353104 UNICODE á) -- UID:file:///home/john/gnome-bzr/src/conduit/test/test-data/source/353104 UNICODE á mtime:2008-08-03 14:45:43 hash:0 (DeltaProvider.py:51) [DeltaProvider ][DEBUG ] file:///home/john/gnome-bzr/src/conduit/test/test-data/source/411045 UNICODE Ä) -- UID:file:///home/john/gnome-bzr/src/conduit/test/test-data/source/411045 UNICODE Ä mtime:2008-08-03 14:45:43 hash:0 (DeltaProvider.py:51) [DeltaProvider ][DEBUG ] file:///home/john/gnome-bzr/src/conduit/test/test-data/source/77714 UNICODE Ü) -- UID:file:///home/john/gnome-bzr/src/conduit/test/test-data/source/77714 UNICODE Ü mtime:2008-08-03 14:45:43 hash:0 (DeltaProvider.py:51) [datatypes.DataType ][DEBUG ] Getting Rid for file:///home/john/gnome-bzr/src/conduit/test/test-data/source/353104 UNICODE á (DataType.py:151) [datatypes.DataType ][DEBUG ] Getting Rid for file:///home/john/gnome-bzr/src/conduit/test/test-data/source/317110 UNICODE Ä (DataType.py:151) [datatypes.DataType ][DEBUG ] Getting Rid for file:///home/john/gnome-bzr/src/conduit/test/test-data/source/411045 UNICODE Ä (DataType.py:151) [datatypes.DataType ][DEBUG ] Getting Rid for file:///home/john/gnome-bzr/src/conduit/test/test-data/source/77714 UNICODE Ü (DataType.py:151) [datatypes.DataType ][DEBUG ] Getting Rid for file:///home/john/gnome-bzr/src/conduit/test/test-data/source/43737 UNICODE é (DataType.py:151) [Syncronization ][DEBUG ] FolderTwoWay-file:///home/john/gnome-bzr/src/conduit/test/test-data/source Changes: New 0 items [] (Synchronization.py:241) [Syncronization ][DEBUG ] FolderTwoWay-file:///home/john/gnome-bzr/src/conduit/test/test-data/source Changes: Modified 0 items [] (Synchronization.py:242) [Syncronization ][DEBUG ] FolderTwoWay-file:///home/john/gnome-bzr/src/conduit/test/test-data/source Changes: Deleted 0 items [] (Synchronization.py:243) [DeltaProvider ][INFO ] Delta: Source (FolderTwoWay-file:///home/john/gnome-bzr/src/conduit/test/test-data/sink) does not implement get_changes(). Proxying... (DeltaProvider.py:25) [DeltaProvider ][DEBUG ] Delta: Got 5 items [u'file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/353104 UNICODE \xe1', u'file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/317110 UNICODE \xc4', u'file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/411045 UNICODE \xc4', u'file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/77714 UNICODE \xdc', u'file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/43737 UNICODE \xe9'] (DeltaProvider.py:39) [DeltaProvider ][DEBUG ] Delta: Expecting 5 items (DeltaProvider.py:49) [DeltaProvider ][DEBUG ] file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/411045 UNICODE Ä) -- UID:file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/411045 UNICODE Ä mtime:2008-08-03 14:45:43 hash:0 (DeltaProvider.py:51) [DeltaProvider ][DEBUG ] file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/43737 UNICODE é) -- UID:file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/43737 UNICODE é mtime:2008-08-03 14:45:43 hash:0 (DeltaProvider.py:51) [DeltaProvider ][DEBUG ] file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/353104 UNICODE á) -- UID:file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/353104 UNICODE á mtime:2008-08-03 14:45:43 hash:0 (DeltaProvider.py:51) [DeltaProvider ][DEBUG ] file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/317110 UNICODE Ä) -- UID:file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/317110 UNICODE Ä mtime:2008-08-03 14:45:43 hash:0 (DeltaProvider.py:51) [DeltaProvider ][DEBUG ] file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/77714 UNICODE Ü) -- UID:file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/77714 UNICODE Ü mtime:2008-08-03 14:45:43 hash:0 (DeltaProvider.py:51) [datatypes.DataType ][DEBUG ] Getting Rid for file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/353104 UNICODE á (DataType.py:151) [datatypes.DataType ][DEBUG ] Getting Rid for file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/317110 UNICODE Ä (DataType.py:151) [datatypes.DataType ][DEBUG ] Getting Rid for file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/411045 UNICODE Ä (DataType.py:151) [datatypes.DataType ][DEBUG ] Getting Rid for file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/77714 UNICODE Ü (DataType.py:151) [datatypes.DataType ][DEBUG ] Getting Rid for file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/43737 UNICODE é (DataType.py:151) [Syncronization ][DEBUG ] FolderTwoWay-file:///home/john/gnome-bzr/src/conduit/test/test-data/sink Changes: New 0 items [] (Synchronization.py:241) [Syncronization ][DEBUG ] FolderTwoWay-file:///home/john/gnome-bzr/src/conduit/test/test-data/sink Changes: Modified 0 items [] (Synchronization.py:242) [Syncronization ][DEBUG ] FolderTwoWay-file:///home/john/gnome-bzr/src/conduit/test/test-data/sink Changes: Deleted 0 items [] (Synchronization.py:243) [Syncronization ][DEBUG ] Syncworker state 3 (Synchronization.py:648) [Vfs ][DEBUG ] Folder scan 100.0% complete (Vfs.py:508) [Vfs ][DEBUG ] 5 files loaded in 0.00507783889771 seconds (Vfs.py:516) [Vfs ][DEBUG ] Folder scan 100.0% complete (Vfs.py:508) [Vfs ][DEBUG ] 5 files loaded in 0.00515198707581 seconds (Vfs.py:516) [Vfs ][DEBUG ] Folder scan 100.0% complete (Vfs.py:508) [Vfs ][DEBUG ] 5 files loaded in 0.00513291358948 seconds (Vfs.py:516) [Vfs ][DEBUG ] Folder scan 100.0% complete (Vfs.py:508) [Vfs ][DEBUG ] 5 files loaded in 0.00494194030762 seconds (Vfs.py:516) [Syncronization ][INFO ] Sync already in progress (Synchronization.py:172) [Syncronization ][INFO ] Waiting for thread to finish (Synchronization.py:124) [Syncronization ][DEBUG ] Created thread (thread: -1210054464) (Synchronization.py:211) [Syncronization ][INFO ] Setting global cancel flag (Synchronization.py:90) [Syncronization ][DEBUG ] Starting worker: Wrapper: sink twoway (UID: FolderTwoWay-file:///home/john/gnome-bzr/src/conduit/test/test-data/sink), initial)> (Synchronization.py:93) [Syncronization ][DEBUG ] Started thread Wrapper: sink twoway (UID: FolderTwoWay-file:///home/john/gnome-bzr/src/conduit/test/test-data/sink), started)> (thread: -1263223920) (Synchronization.py:624) [Syncronization ][DEBUG ] Sync Wrapper: sink twoway (UID: FolderTwoWay-file:///home/john/gnome-bzr/src/conduit/test/test-data/sink), started)> beginning. Slow: False, Twoway: True (Synchronization.py:629) [Syncronization ][DEBUG ] Syncworker state 0 (Synchronization.py:648) [Syncronization ][DEBUG ] Syncworker state 1 (Synchronization.py:648) [Syncronization ][DEBUG ] Source Status = Synchronizing... (Synchronization.py:677) [Vfs ][DEBUG ] Folder scan 100.0% complete (Vfs.py:508) [Vfs ][DEBUG ] 5 files loaded in 0.00765204429626 seconds (Vfs.py:516) [Syncronization ][INFO ] Waiting for thread to finish (Synchronization.py:124) [Vfs ][DEBUG ] Folder scan 100.0% complete (Vfs.py:508) [Vfs ][DEBUG ] 5 files loaded in 0.00437521934509 seconds (Vfs.py:516) [Syncronization ][DEBUG ] Syncworker state 2 (Synchronization.py:648) [Syncronization ][INFO ] Synchronizing (Two Way) Wrapper: source twoway (UID: FolderTwoWay-file:///home/john/gnome-bzr/src/conduit/test/test-data/source) <--> Wrapper: sink twoway (UID: FolderTwoWay-file:///home/john/gnome-bzr/src/conduit/test/test-data/sink) (Synchronization.py:514) [DeltaProvider ][INFO ] Delta: Source (FolderTwoWay-file:///home/john/gnome-bzr/src/conduit/test/test-data/source) does not implement get_changes(). Proxying... (DeltaProvider.py:25) [DeltaProvider ][DEBUG ] Delta: Got 5 items [u'file:///home/john/gnome-bzr/src/conduit/test/test-data/source/353104 UNICODE \xe1', u'file:///home/john/gnome-bzr/src/conduit/test/test-data/source/317110 UNICODE \xc4', u'file:///home/john/gnome-bzr/src/conduit/test/test-data/source/411045 UNICODE \xc4', u'file:///home/john/gnome-bzr/src/conduit/test/test-data/source/77714 UNICODE \xdc', u'file:///home/john/gnome-bzr/src/conduit/test/test-data/source/43737 UNICODE \xe9'] (DeltaProvider.py:39) [DeltaProvider ][DEBUG ] Delta: Expecting 5 items (DeltaProvider.py:49) [DeltaProvider ][DEBUG ] file:///home/john/gnome-bzr/src/conduit/test/test-data/source/317110 UNICODE Ä) -- UID:file:///home/john/gnome-bzr/src/conduit/test/test-data/source/317110 UNICODE Ä mtime:2008-08-03 14:45:43 hash:0 (DeltaProvider.py:51) [DeltaProvider ][DEBUG ] file:///home/john/gnome-bzr/src/conduit/test/test-data/source/43737 UNICODE é) -- UID:file:///home/john/gnome-bzr/src/conduit/test/test-data/source/43737 UNICODE é mtime:2008-08-03 14:45:43 hash:0 (DeltaProvider.py:51) [DeltaProvider ][DEBUG ] file:///home/john/gnome-bzr/src/conduit/test/test-data/source/353104 UNICODE á) -- UID:file:///home/john/gnome-bzr/src/conduit/test/test-data/source/353104 UNICODE á mtime:2008-08-03 14:45:43 hash:0 (DeltaProvider.py:51) [DeltaProvider ][DEBUG ] file:///home/john/gnome-bzr/src/conduit/test/test-data/source/411045 UNICODE Ä) -- UID:file:///home/john/gnome-bzr/src/conduit/test/test-data/source/411045 UNICODE Ä mtime:2008-08-03 14:45:43 hash:0 (DeltaProvider.py:51) [DeltaProvider ][DEBUG ] file:///home/john/gnome-bzr/src/conduit/test/test-data/source/77714 UNICODE Ü) -- UID:file:///home/john/gnome-bzr/src/conduit/test/test-data/source/77714 UNICODE Ü mtime:2008-08-03 14:45:43 hash:0 (DeltaProvider.py:51) [datatypes.DataType ][DEBUG ] Getting Rid for file:///home/john/gnome-bzr/src/conduit/test/test-data/source/353104 UNICODE á (DataType.py:151) [datatypes.DataType ][DEBUG ] Getting Rid for file:///home/john/gnome-bzr/src/conduit/test/test-data/source/317110 UNICODE Ä (DataType.py:151) [datatypes.DataType ][DEBUG ] Getting Rid for file:///home/john/gnome-bzr/src/conduit/test/test-data/source/411045 UNICODE Ä (DataType.py:151) [datatypes.DataType ][DEBUG ] Getting Rid for file:///home/john/gnome-bzr/src/conduit/test/test-data/source/77714 UNICODE Ü (DataType.py:151) [datatypes.DataType ][DEBUG ] Getting Rid for file:///home/john/gnome-bzr/src/conduit/test/test-data/source/43737 UNICODE é (DataType.py:151) [Syncronization ][DEBUG ] FolderTwoWay-file:///home/john/gnome-bzr/src/conduit/test/test-data/source Changes: New 0 items [] (Synchronization.py:241) [Syncronization ][DEBUG ] FolderTwoWay-file:///home/john/gnome-bzr/src/conduit/test/test-data/source Changes: Modified 0 items [] (Synchronization.py:242) [Syncronization ][DEBUG ] FolderTwoWay-file:///home/john/gnome-bzr/src/conduit/test/test-data/source Changes: Deleted 0 items [] (Synchronization.py:243) [DeltaProvider ][INFO ] Delta: Source (FolderTwoWay-file:///home/john/gnome-bzr/src/conduit/test/test-data/sink) does not implement get_changes(). Proxying... (DeltaProvider.py:25) [DeltaProvider ][DEBUG ] Delta: Got 5 items [u'file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/353104 UNICODE \xe1', u'file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/317110 UNICODE \xc4', u'file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/411045 UNICODE \xc4', u'file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/77714 UNICODE \xdc', u'file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/43737 UNICODE \xe9'] (DeltaProvider.py:39) [DeltaProvider ][DEBUG ] Delta: Expecting 5 items (DeltaProvider.py:49) [DeltaProvider ][DEBUG ] file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/411045 UNICODE Ä) -- UID:file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/411045 UNICODE Ä mtime:2008-08-03 14:45:43 hash:0 (DeltaProvider.py:51) [DeltaProvider ][DEBUG ] file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/43737 UNICODE é) -- UID:file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/43737 UNICODE é mtime:2008-08-03 14:45:43 hash:0 (DeltaProvider.py:51) [DeltaProvider ][DEBUG ] file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/353104 UNICODE á) -- UID:file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/353104 UNICODE á mtime:2008-08-03 14:45:43 hash:0 (DeltaProvider.py:51) [DeltaProvider ][DEBUG ] file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/317110 UNICODE Ä) -- UID:file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/317110 UNICODE Ä mtime:2008-08-03 14:45:43 hash:0 (DeltaProvider.py:51) [DeltaProvider ][DEBUG ] file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/77714 UNICODE Ü) -- UID:file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/77714 UNICODE Ü mtime:2008-08-03 14:45:43 hash:0 (DeltaProvider.py:51) [datatypes.DataType ][DEBUG ] Getting Rid for file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/353104 UNICODE á (DataType.py:151) [datatypes.DataType ][DEBUG ] Getting Rid for file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/317110 UNICODE Ä (DataType.py:151) [datatypes.DataType ][DEBUG ] Getting Rid for file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/411045 UNICODE Ä (DataType.py:151) [datatypes.DataType ][DEBUG ] Getting Rid for file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/77714 UNICODE Ü (DataType.py:151) [datatypes.DataType ][DEBUG ] Getting Rid for file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/43737 UNICODE é (DataType.py:151) [Syncronization ][DEBUG ] FolderTwoWay-file:///home/john/gnome-bzr/src/conduit/test/test-data/sink Changes: New 0 items [] (Synchronization.py:241) [Syncronization ][DEBUG ] FolderTwoWay-file:///home/john/gnome-bzr/src/conduit/test/test-data/sink Changes: Modified 0 items [] (Synchronization.py:242) [Syncronization ][DEBUG ] FolderTwoWay-file:///home/john/gnome-bzr/src/conduit/test/test-data/sink Changes: Deleted 0 items [] (Synchronization.py:243) [Syncronization ][DEBUG ] Syncworker state 3 (Synchronization.py:648) [Vfs ][DEBUG ] Folder scan 100.0% complete (Vfs.py:508) [Vfs ][DEBUG ] 5 files loaded in 0.00282692909241 seconds (Vfs.py:516) [Vfs ][DEBUG ] Folder scan 100.0% complete (Vfs.py:508) [Vfs ][DEBUG ] 5 files loaded in 0.00436997413635 seconds (Vfs.py:516) [Vfs ][DEBUG ] Folder scan 100.0% complete (Vfs.py:508) [Vfs ][DEBUG ] 5 files loaded in 0.00279998779297 seconds (Vfs.py:516) [Vfs ][DEBUG ] Folder scan 100.0% complete (Vfs.py:508) [Vfs ][DEBUG ] 5 files loaded in 0.00278806686401 seconds (Vfs.py:516) [datatypes.File ][DEBUG ] Comparing file:///home/john/gnome-bzr/src/conduit/test/test-data/source/353104 UNICODE á (MTIME: 2008-08-03 14:45:43) with file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/353104 UNICODE á (MTIME: 2008-08-03 14:45:43) (File.py:472) [datatypes.File ][DEBUG ] Comparing file:///home/john/gnome-bzr/src/conduit/test/test-data/source/317110 UNICODE Ä (MTIME: 2008-08-03 14:45:43) with file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/317110 UNICODE Ä (MTIME: 2008-08-03 14:45:43) (File.py:472) [datatypes.File ][DEBUG ] Comparing file:///home/john/gnome-bzr/src/conduit/test/test-data/source/411045 UNICODE Ä (MTIME: 2008-08-03 14:45:43) with file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/411045 UNICODE Ä (MTIME: 2008-08-03 14:45:43) (File.py:472) [datatypes.File ][DEBUG ] Comparing file:///home/john/gnome-bzr/src/conduit/test/test-data/source/77714 UNICODE Ü (MTIME: 2008-08-03 14:45:43) with file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/77714 UNICODE Ü (MTIME: 2008-08-03 14:45:43) (File.py:472) [datatypes.File ][DEBUG ] Comparing file:///home/john/gnome-bzr/src/conduit/test/test-data/source/43737 UNICODE é (MTIME: 2008-08-03 14:45:43) with file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/43737 UNICODE é (MTIME: 2008-08-03 14:45:43) (File.py:472) [datatypes.File ][DEBUG ] New tempfile created at /tmp/conduitPEZVKg (File.py:545) [datatypes.File ][DEBUG ] Defering rename till transfer (New name: .72445) (File.py:112) [datatypes.File ][DEBUG ] Using deferred filename in transfer (File.py:293) [datatypes.File ][DEBUG ] Transfering File file:///tmp/conduitPEZVKg -> file:///home/john/gnome-bzr/src/conduit/test/test-data/source/.21543/.72445 (File.py:302) [datatypes.File ][DEBUG ] Closing file (File.py:58) [datatypes.File ][DEBUG ] Clearing pending rename (File.py:65) [datatypes.File ][DEBUG ] New tempfile created at /tmp/conduitJ5JIs9 (File.py:545) [datatypes.File ][DEBUG ] Defering rename till transfer (New name: .8191010) (File.py:112) [datatypes.File ][DEBUG ] Using deferred filename in transfer (File.py:293) [datatypes.File ][DEBUG ] Transfering File file:///tmp/conduitJ5JIs9 -> file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/.22051/.8191010 (File.py:302) [datatypes.File ][DEBUG ] Closing file (File.py:58) [datatypes.File ][DEBUG ] Clearing pending rename (File.py:65) [datatypes.File ][DEBUG ] New tempfile created at /tmp/conduitRYt5AI (File.py:545) [datatypes.File ][DEBUG ] Defering rename till transfer (New name: .73101010) (File.py:112) [datatypes.File ][DEBUG ] Using deferred filename in transfer (File.py:293) [datatypes.File ][DEBUG ] Transfering File file:///tmp/conduitRYt5AI -> file:///home/john/gnome-bzr/src/conduit/test/test-data/source/.10206/.73101010 (File.py:302) [datatypes.File ][DEBUG ] Closing file (File.py:58) [datatypes.File ][DEBUG ] Clearing pending rename (File.py:65) [datatypes.File ][DEBUG ] New tempfile created at /tmp/conduitJrfxjo (File.py:545) [datatypes.File ][DEBUG ] Defering rename till transfer (New name: .79181) (File.py:112) [datatypes.File ][DEBUG ] Using deferred filename in transfer (File.py:293) [datatypes.File ][DEBUG ] Transfering File file:///tmp/conduitJrfxjo -> file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/.9108910/.79181 (File.py:302) [datatypes.File ][DEBUG ] Closing file (File.py:58) [datatypes.File ][DEBUG ] Clearing pending rename (File.py:65) [datatypes.File ][DEBUG ] New tempfile created at /tmp/conduitxm5HNB (File.py:545) [datatypes.File ][DEBUG ] Defering rename till transfer (New name: .71804) (File.py:112) [datatypes.File ][DEBUG ] Using deferred filename in transfer (File.py:293) [datatypes.File ][DEBUG ] Transfering File file:///tmp/conduitxm5HNB -> file:///home/john/gnome-bzr/src/conduit/test/test-data/source/.53793/.71804 (File.py:302) [datatypes.File ][DEBUG ] Closing file (File.py:58) [datatypes.File ][DEBUG ] Clearing pending rename (File.py:65) [datatypes.File ][DEBUG ] New tempfile created at /tmp/conduit48xjCV (File.py:545) [datatypes.File ][DEBUG ] Defering rename till transfer (New name: .88232) (File.py:112) [datatypes.File ][DEBUG ] Using deferred filename in transfer (File.py:293) [datatypes.File ][DEBUG ] Transfering File file:///tmp/conduit48xjCV -> file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/.108583/.88232 (File.py:302) [datatypes.File ][DEBUG ] Closing file (File.py:58) [datatypes.File ][DEBUG ] Clearing pending rename (File.py:65) [datatypes.File ][DEBUG ] New tempfile created at /tmp/conduitWsdnd6 (File.py:545) [datatypes.File ][DEBUG ] Defering rename till transfer (New name: .105716) (File.py:112) [datatypes.File ][DEBUG ] Using deferred filename in transfer (File.py:293) [datatypes.File ][DEBUG ] Transfering File file:///tmp/conduitWsdnd6 -> file:///home/john/gnome-bzr/src/conduit/test/test-data/source/.78754/.105716 (File.py:302) [datatypes.File ][DEBUG ] Closing file (File.py:58) [datatypes.File ][DEBUG ] Clearing pending rename (File.py:65) [datatypes.File ][DEBUG ] New tempfile created at /tmp/conduit2Uc2fv (File.py:545) [datatypes.File ][DEBUG ] Defering rename till transfer (New name: .731003) (File.py:112) [datatypes.File ][DEBUG ] Using deferred filename in transfer (File.py:293) [datatypes.File ][DEBUG ] Transfering File file:///tmp/conduit2Uc2fv -> file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/.86897/.731003 (File.py:302) [datatypes.File ][DEBUG ] Closing file (File.py:58) [datatypes.File ][DEBUG ] Clearing pending rename (File.py:65) [datatypes.File ][DEBUG ] New tempfile created at /tmp/conduit4V5-MH (File.py:545) [datatypes.File ][DEBUG ] Defering rename till transfer (New name: .931103) (File.py:112) [datatypes.File ][DEBUG ] Using deferred filename in transfer (File.py:293) [datatypes.File ][DEBUG ] Transfering File file:///tmp/conduit4V5-MH -> file:///home/john/gnome-bzr/src/conduit/test/test-data/source/.32629/.931103 (File.py:302) [datatypes.File ][DEBUG ] Closing file (File.py:58) [datatypes.File ][DEBUG ] Clearing pending rename (File.py:65) [datatypes.File ][DEBUG ] New tempfile created at /tmp/conduitwcn22t (File.py:545) [datatypes.File ][DEBUG ] Defering rename till transfer (New name: .4104410) (File.py:112) [datatypes.File ][DEBUG ] Using deferred filename in transfer (File.py:293) [datatypes.File ][DEBUG ] Transfering File file:///tmp/conduitwcn22t -> file:///home/john/gnome-bzr/src/conduit/test/test-data/sink/.75428/.4104410 (File.py:302) [datatypes.File ][DEBUG ] Closing file (File.py:58) [datatypes.File ][DEBUG ] Clearing pending rename (File.py:65) [Syncronization ][INFO ] Sync already in progress (Synchronization.py:172) [Syncronization ][INFO ] Waiting for thread to finish (Synchronization.py:124) [Syncronization ][DEBUG ] Created thread (thread: -1210054464) (Synchronization.py:211) [Syncronization ][INFO ] Setting global cancel flag (Synchronization.py:90) [Syncronization ][DEBUG ] Starting worker: Wrapper: sink twoway (UID: FolderTwoWay-file:///home/john/gnome-bzr/src/conduit/test/test-data/sink), initial)> (Synchronization.py:93) [Syncronization ][INFO ] Waiting for thread to finish (Synchronization.py:124) [Syncronization ][DEBUG ] Started thread Wrapper: sink twoway (UID: FolderTwoWay-file:///home/john/gnome-bzr/src/conduit/test/test-data/sink), started)> (thread: -1263223920) (Synchronization.py:624) [Syncronization ][DEBUG ] Sync Wrapper: sink twoway (UID: FolderTwoWay-file:///home/john/gnome-bzr/src/conduit/test/test-data/sink), started)> beginning. Slow: False, Twoway: True (Synchronization.py:629) [Syncronization ][DEBUG ] Syncworker state 0 (Synchronization.py:648) [Syncronization ][DEBUG ] Syncworker state 1 (Synchronization.py:648) [Syncronization ][DEBUG ] Source Status = Synchronizing... (Synchronization.py:677) [Vfs ][DEBUG ] Folder scan 16.6666666667% complete (Vfs.py:508) [Vfs ][DEBUG ] Folder scan 33.3333333333% complete (Vfs.py:508) [Vfs ][DEBUG ] Folder scan 50.0% complete (Vfs.py:508) [Vfs ][DEBUG ] Folder scan 66.6666666667% complete (Vfs.py:508) [Vfs ][DEBUG ] Folder scan 83.3333333333% complete (Vfs.py:508) [Vfs ][DEBUG ] Folder scan 100.0% complete (Vfs.py:508) [Vfs ][DEBUG ] 10 files loaded in 0.0145790576935 seconds (Vfs.py:516) [Vfs ][DEBUG ] Folder scan 16.6666666667% complete (Vfs.py:508) [Vfs ][DEBUG ] Folder scan 33.3333333333% complete (Vfs.py:508) [Vfs ][DEBUG ] Folder scan 50.0% complete (Vfs.py:508) [Vfs ][DEBUG ] Folder scan 66.6666666667% complete (Vfs.py:508) [Vfs ][DEBUG ] Folder scan 83.3333333333% complete (Vfs.py:508) [Vfs ][DEBUG ] Folder scan 100.0% complete (Vfs.py:508) [Vfs ][DEBUG ] 10 files loaded in 0.0154671669006 seconds (Vfs.py:516) [Syncronization ][DEBUG ] Syncworker state 2 (Synchronization.py:648) [Syncronization ][INFO ] Synchronizing (Two Way) Wrapper: source twoway (UID: FolderTwoWay-file:///home/john/gnome-bzr/src/conduit/test/test-data/source) <--> Wrapper: sink twoway (UID: FolderTwoWay-file:///home/john/gnome-bzr/src/conduit/test/test-data/sink) (Synchronization.py:514) [DeltaProvider ][INFO ] Delta: Source (FolderTwoWay-file:///home/john/gnome-bzr/src/conduit/test/test-data/source) does not implement get_changes(). Proxying... (DeltaProvider.py:25) [DeltaProvider ][DEBUG ] Delta: Got 10 items [u'file:///home/john/gnome-bzr/src/conduit/test/test-data/source/353104 UNICODE \xe1', u'file:///home/john/gnome-bzr/src/conduit/test/test-data/source/317110 UNICODE \xc4', u'file:///home/john/gnome-bzr/src/conduit/test/test-data/source/411045 UNICODE \xc4', u'file:///home/john/gnome-bzr/src/conduit/test/test-data/source/77714 UNICODE \xdc', u'file:///home/john/gnome-bzr/src/conduit/test/test-data/source/43737 UNICODE \xe9', u'file:///home/john/gnome-bzr/src/conduit/test/test-data/source/.21543/.72445', u'file:///home/john/gnome-bzr/src/conduit/test/test-data/source/.10206/.73101010', u'file:///home/john/gnome-bzr/src/conduit/test/test-data/source/.53793/.71804', u'file:///home/john/gnome-bzr/src/conduit/test/test-data/source/.78754/.105716', u'file:///home/john/gnome-bzr/src/conduit/test/test-data/source/.32629/.931103'] (DeltaProvider.py:39) [DeltaProvider ][DEBUG ] Delta: Expecting 5 items (DeltaProvider.py:49) [DeltaProvider ][DEBUG ] file:///home/john/gnome-bzr/src/conduit/test/test-data/