[modules.iPod        ][INFO              ]  Module Information: gpod v(0, 7, 2) (/usr/lib/python2.6/dist-packages/gpod/__init__.pyc) (iPodModule.py:45)
[modules.AVConverter ][INFO              ]  Module Information: gst v(0, 10, 17) (/usr/lib/python2.6/dist-packages/gst-0.10/gst/__init__.pyc) (AudioVideoConverterModule.py:23)
[modules.Google      ][INFO              ]  Module Information: gdata (/usr/lib/pymodules/python2.6/gdata/__init__.pyc) (GoogleModule.py:46)
[modules.Feed        ][INFO              ]  Module Information: feedparser v4.1 (/usr/lib/pymodules/python2.6/feedparser.pyc) (FeedModule.py:21)
[modules.Feed        ][INFO              ]  Patching feedparser issue #100 (FeedModule.py:27)
[modules.Flickr      ][INFO              ]  Module Information: flickrapi v1.2 (/usr/lib/pymodules/python2.6/flickrapi/__init__.pyc) (FlickrModule.py:24)
[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.Backpack    ][INFO              ]  Module Information: backpack (/home/john/Programming/conduit.git/conduit/modules/BackpackModule/backpack/backpack.pyc) (BackpackModule.py:22)
[modules.iPod        ][INFO              ]  Module Information: gpod v(0, 7, 2) (/usr/lib/python2.6/dist-packages/gpod/__init__.pyc) (iPodModule.py:45)
[modules.Facebook    ][INFO              ]  Module Information: pyfacebook v0.1 (/home/john/Programming/conduit.git/conduit/modules/FacebookModule/pyfacebook/__init__.pyc) (FacebookModule.py:30)
[modules.Facebook    ][INFO              ]  Facebook parsing using: JSON (json) (FacebookModule.py:31)
[modules.Evolution   ][INFO              ]  Module Information: evolution v(2, 28, 0) (/usr/lib/pymodules/python2.6/gtk-2.0/evolution/__init__.pyc) (EvolutionModule.py:25)
[dataproviders.VolumeFactory][INFO              ]  Waiting for HAL to attempt mount (VolumeFactory.py:38)
[dataproviders.VolumeFactory][INFO              ]  Waiting for HAL to attempt mount (VolumeFactory.py:38)
[dataproviders.VolumeFactory][INFO              ]  Waiting for HAL to attempt mount (VolumeFactory.py:38)
[dataproviders.VolumeFactory][INFO              ]  Waiting for HAL to attempt mount (VolumeFactory.py:38)
[dataproviders.VolumeFactory][INFO              ]  Waiting for HAL to attempt mount (VolumeFactory.py:38)
[dataproviders.VolumeFactory][INFO              ]  Waiting for HAL to attempt mount (VolumeFactory.py:38)
[DBus                ][DEBUG             ]  DBus Exported: / (DBus.py:127)
[DBus                ][DEBUG             ]  DBus Exported: /syncset/gui (DBus.py:127)
[DBus                ][DEBUG             ]  DBus Message from /: GetAllDataProviders (DBus.py:134)
[DBus                ][DEBUG             ]  DBus Message from /: GetDataProvider: TestSource (DBus.py:134)
[DBus                ][DEBUG             ]  DBus Exported: /dataprovider/141f6f3d8924470d865ce54fad94c4eb (DBus.py:127)
[DBus                ][DEBUG             ]  DBus Message from /dataprovider/141f6f3d8924470d865ce54fad94c4eb: GetConfigurationXml (DBus.py:134)
[DBus                ][DEBUG             ]  DBus Message from /dataprovider/141f6f3d8924470d865ce54fad94c4eb: GetInformation (DBus.py:134)
[DBus                ][DEBUG             ]  DBus Message from /dataprovider/141f6f3d8924470d865ce54fad94c4eb: SetConfigurationXml: <?xml version="1.0" ?><configuration><aInt type="int">0</aInt><count type="int">0</count><slow type="bool">False</slow><UID type="str">031038</UID><newMtime type="bool">False</newMtime><aList type="list"/><errorAfter type="int">999</errorAfter><numData type="int">10</numData><newHash type="bool">False</newHash><aBool type="bool">False</aBool><errorFatal type="bool">False</errorFatal><aString type="str"></aString></configuration> (DBus.py:134)
[DBus                ][DEBUG             ]  DBus Message from /dataprovider/141f6f3d8924470d865ce54fad94c4eb: AddData: Foo (DBus.py:134)
[DBus                ][DEBUG             ]  DBus Message from /: GetDataProvider: TestSink (DBus.py:134)
[DBus                ][DEBUG             ]  DBus Exported: /dataprovider/cf45bfd9a0dd403fb6591d68cc676e51 (DBus.py:127)
[DBus                ][DEBUG             ]  DBus Message from /dataprovider/cf45bfd9a0dd403fb6591d68cc676e51: IsPending (DBus.py:134)
[DBus                ][DEBUG             ]  DBus Message from /: BuildExporter (sender: TestDBus.py:) --> TestSink (DBus.py:134)
[DBus                ][DEBUG             ]  DBus Exported: /dataprovider/de94d1be24924a3fb2e6a76675a3bad2 (DBus.py:127)
[DBus                ][DEBUG             ]  DBus Exported: /dataprovider/7a6738f58f624d53841e0a0bfcbbe1e3 (DBus.py:127)
[DBus                ][DEBUG             ]  DBus Exported: /conduit/e14aa964e078411e9808a9261a738da6 (DBus.py:127)
[DBus                ][DEBUG             ]  DBus Message from /conduit/e14aa964e078411e9808a9261a738da6: SinkGetConfigurationXml (DBus.py:134)
[DBus                ][DEBUG             ]  DBus Message from /conduit/e14aa964e078411e9808a9261a738da6: SinkSetConfigurationXml: <?xml version="1.0" ?><configuration><aInt type="int">0</aInt><count type="int">0</count><slow type="bool">False</slow><UID type="str">60084</UID><newMtime type="bool">False</newMtime><aList type="list"/><errorAfter type="int">999</errorAfter><numData type="int">5</numData><newHash type="bool">False</newHash><aBool type="bool">False</aBool><errorFatal type="bool">False</errorFatal><aString type="str"></aString></configuration> (DBus.py:134)
[DBus                ][DEBUG             ]  DBus Message from /conduit/e14aa964e078411e9808a9261a738da6: SinkGetInformation (DBus.py:134)
[DBus                ][DEBUG             ]  DBus Message from /conduit/e14aa964e078411e9808a9261a738da6: AddData: Foo (DBus.py:134)
[dataproviders.File  ][WARNING           ]  Could not add: Foo (File.py:176)
[DBus                ][DEBUG             ]  DBus Message from /: BuildConduit (sender: TestDBus.py:) /dataprovider/141f6f3d8924470d865ce54fad94c4eb --> /dataprovider/cf45bfd9a0dd403fb6591d68cc676e51 (DBus.py:134)
[DBus                ][DEBUG             ]  DBus Exported: /conduit/f5b236756a8443d88884ca7fbed2185a (DBus.py:127)
[DBus                ][DEBUG             ]  DBus Message from /: GetDataProvider: TestSink (DBus.py:134)
[DBus                ][DEBUG             ]  DBus Exported: /dataprovider/ab759fa52a4f4b3e9512bb0a770d0262 (DBus.py:127)
[DBus                ][DEBUG             ]  DBus Message from /conduit/f5b236756a8443d88884ca7fbed2185a: AddDataprovider: /dataprovider/ab759fa52a4f4b3e9512bb0a770d0262 (DBus.py:134)
[DBus                ][DEBUG             ]  DBus Message from /: GetDataProvider: TestTwoWay (DBus.py:134)
[DBus                ][DEBUG             ]  DBus Exported: /dataprovider/0fe40cf9daad43118224d09f1ff485ee (DBus.py:127)
[DBus                ][DEBUG             ]  DBus Message from /conduit/f5b236756a8443d88884ca7fbed2185a: AddDataprovider: /dataprovider/0fe40cf9daad43118224d09f1ff485ee (DBus.py:134)
[Conduit             ][DEBUG             ]  Adding twoway dataprovider into sink position (Conduit.py:146)
[DBus                ][DEBUG             ]  DBus Message from /conduit/f5b236756a8443d88884ca7fbed2185a: DeleteDataprovider: /dataprovider/0fe40cf9daad43118224d09f1ff485ee (DBus.py:134)
[DBus                ][DEBUG             ]  DBus Message from /: GetDataProvider: Foobar (DBus.py:134)
[Module              ][WARNING           ]  Could not find module wrapper: Foobar (Module.py:267)
[DBus                ][DEBUG             ]  DBus Exported: /dataprovider/68d04dd3f358465d9ac6b1bc5258ab78 (DBus.py:127)
[DBus                ][DEBUG             ]  DBus Message from /dataprovider/68d04dd3f358465d9ac6b1bc5258ab78: IsPending (DBus.py:134)
[DBus                ][DEBUG             ]  DBus Message from /conduit/f5b236756a8443d88884ca7fbed2185a: Refresh (DBus.py:134)
[Syncronization      ][DEBUG             ]  Created thread <SyncWorker(Thread-4, initial)> (thread: -1215932736) (Synchronization.py:217)
[Syncronization      ][INFO              ]  Setting global cancel flag (Synchronization.py:90)
[Syncronization      ][DEBUG             ]  Starting worker: <SyncWorker(Wrapper: Test Source source (UID: TestSource-031038) |--> [<conduit.ModuleWrapper.ModuleWrapper instance at 0xad6ecec>, <conduit.ModuleWrapper.ModuleWrapper instance at 0xad78b4c>], initial)> (Synchronization.py:93)
[Syncronization      ][DEBUG             ]  Started thread <SyncWorker(Wrapper: Test Source source (UID: TestSource-031038) |--> [<conduit.ModuleWrapper.ModuleWrapper instance at 0xad6ecec>, <conduit.ModuleWrapper.ModuleWrapper instance at 0xad78b4c>], started -1262486672)> (thread: -1262486672) (Synchronization.py:630)
[DBus                ][DEBUG             ]  DBus Message from /conduit/f5b236756a8443d88884ca7fbed2185a: Sync (DBus.py:134)
[Syncronization      ][DEBUG             ]  Sync <SyncWorker(Wrapper: Test Source source (UID: TestSource-031038) |--> [<conduit.ModuleWrapper.ModuleWrapper instance at 0xad6ecec>, <conduit.ModuleWrapper.ModuleWrapper instance at 0xad78b4c>], started -1262486672)> beginning. Slow: False, Twoway: False (Synchronization.py:635)
[Syncronization      ][INFO              ]  Sync already in progress (Synchronization.py:178)
[Syncronization      ][DEBUG             ]  Syncworker state 0 (Synchronization.py:654)
[Syncronization      ][INFO              ]  Waiting for thread to finish (Synchronization.py:124)
[Syncronization      ][DEBUG             ]  Syncworker state 1 (Synchronization.py:654)
[Syncronization      ][DEBUG             ]  Source Status = Ready (Synchronization.py:683)
[Syncronization      ][DEBUG             ]  Syncworker state 3 (Synchronization.py:654)
[Syncronization      ][DEBUG             ]  Created thread <SyncWorker(Thread-5, initial)> (thread: -1215932736) (Synchronization.py:217)
[Syncronization      ][INFO              ]  Setting global cancel flag (Synchronization.py:90)
[Syncronization      ][DEBUG             ]  Starting worker: <SyncWorker(Wrapper: Test Source source (UID: TestSource-031038) |--> [<conduit.ModuleWrapper.ModuleWrapper instance at 0xad6ecec>, <conduit.ModuleWrapper.ModuleWrapper instance at 0xad78b4c>], initial)> (Synchronization.py:93)
[Syncronization      ][DEBUG             ]  Started thread <SyncWorker(Wrapper: Test Source source (UID: TestSource-031038) |--> [<conduit.ModuleWrapper.ModuleWrapper instance at 0xad6ecec>, <conduit.ModuleWrapper.ModuleWrapper instance at 0xad78b4c>], started -1262486672)> (thread: -1262486672) (Synchronization.py:630)
[DBus                ][DEBUG             ]  DBus Message from /syncset/gui: AddConduit: /conduit/f5b236756a8443d88884ca7fbed2185a (DBus.py:134)
[Syncronization      ][DEBUG             ]  Sync <SyncWorker(Wrapper: Test Source source (UID: TestSource-031038) |--> [<conduit.ModuleWrapper.ModuleWrapper instance at 0xad6ecec>, <conduit.ModuleWrapper.ModuleWrapper instance at 0xad78b4c>], started -1262486672)> beginning. Slow: False, Twoway: False (Synchronization.py:635)
[DBus                ][DEBUG             ]  DBus Message from /syncset/gui: DeleteConduit: /conduit/f5b236756a8443d88884ca7fbed2185a (DBus.py:134)
[Syncronization      ][DEBUG             ]  Syncworker state 0 (Synchronization.py:654)
[Syncronization      ][DEBUG             ]  Syncworker state 1 (Synchronization.py:654)
[Syncronization      ][DEBUG             ]  Source Status = Refreshed OK (Synchronization.py:683)
[Syncronization      ][DEBUG             ]  Syncworker state 2 (Synchronization.py:654)
[Syncronization      ][INFO              ]  Synchronizing Wrapper: Test Source source (UID: TestSource-031038) |--> Wrapper: Test Sink sink (UID: TestSink-1010087)  (Synchronization.py:473)
[DeltaProvider       ][INFO              ]  Delta: Source (TestSource-031038) does not implement get_changes(). Proxying... (DeltaProvider.py:25)
[DBus                ][DEBUG             ]  DBus Message from /: NewSyncSet (DBus.py:134)
[DeltaProvider       ][DEBUG             ]  Delta: Got 10 items
[u'0', u'1', u'2', u'3', u'4', u'5', u'6', u'7', u'8', u'9'] (DeltaProvider.py:39)
[DBus                ][DEBUG             ]  DBus Exported: /syncset/8f979687c97f4423b3ae0eb9069e0c0a (DBus.py:127)
[DBus                ][DEBUG             ]  DBus Message from /syncset/8f979687c97f4423b3ae0eb9069e0c0a: AddConduit: /conduit/f5b236756a8443d88884ca7fbed2185a (DBus.py:134)
[DBus                ][DEBUG             ]  DBus Message from /syncset/8f979687c97f4423b3ae0eb9069e0c0a: SaveToXml: /tmp/conduit2kimlv (DBus.py:134)
[SyncSet             ][INFO              ]  Saving Sync Set to settings.xml (SyncSet.py:137)
[DeltaProvider       ][DEBUG             ]  Delta: Expecting 0 items (DeltaProvider.py:49)
[DBus                ][DEBUG             ]  DBus Message from /: NewSyncSet (DBus.py:134)
[Syncronization      ][DEBUG             ]  TestSource-031038 Changes: New 10 items
[u'0', u'1', u'2', u'3', u'4', u'5', u'6', u'7', u'8', u'9'] (Synchronization.py:247)
[Syncronization      ][DEBUG             ]  TestSource-031038 Changes: Modified 0 items
[] (Synchronization.py:248)
[DBus                ][DEBUG             ]  DBus Exported: /syncset/c2aec2efb869411793096046771b25cd (DBus.py:127)
[Syncronization      ][DEBUG             ]  TestSource-031038 Changes: Deleted 0 items
[] (Synchronization.py:249)
[DBus                ][DEBUG             ]  DBus Message from /syncset/c2aec2efb869411793096046771b25cd: RestoreFromXml: /tmp/conduit2kimlv (DBus.py:134)
[Syncronization      ][DEBUG             ]  1WAY PUT: Test Source (0) -----> Test Sink (Synchronization.py:495)
[SyncSet             ][INFO              ]  Restoring Sync Set from /tmp/conduit2kimlv (SyncSet.py:197)
[datatypes.DataType  ][DEBUG             ]  Getting Rid for 0 (DataType.py:158)
[TypeConverter       ][DEBUG             ]  Convert test_type -> test_type using [('test_type', 'test_type', {})] (TypeConverter.py:209)
[TypeConverter       ][DEBUG             ]  Skipping test_type -> test_type (TypeConverter.py:168)
[SyncSet             ][DEBUG             ]  Restoring TestSource to (source=True) (SyncSet.py:62)
[SyncSet             ][DEBUG             ]  Restoring TestSink to (source=False) (SyncSet.py:62)
[SyncSet             ][DEBUG             ]  Restoring TestSink to (source=False) (SyncSet.py:62)
[Syncronization      ][INFO              ]  Putting data 0 --> None into TestSink-1010087 (Synchronization.py:37)
[datatypes.DataType  ][DEBUG             ]  Getting Rid for 0 (DataType.py:158)