[modules.iPod ][INFO ] Module Information: gpod v(0, 6, 0) (/usr/lib/python2.5/site-packages/gpod/__init__.pyc) (iPodModule.py:45) [modules.AVConverter ][INFO ] Module Information: gst v(0, 10, 13) (/usr/lib/python2.5/site-packages/gst-0.10/gst/__init__.pyc) (AudioVideoConverterModule.py:23) [modules.Google ][INFO ] Module Information: gdata (/home/john/testing/conduit/conduit/modules/GoogleModule/gdata/__init__.pyc) (GoogleModule.py:45) [modules.Feed ][INFO ] Module Information: feedparser v4.1 (/var/lib/python-support/python2.5/feedparser.pyc) (FeedModule.py:21) [modules.Feed ][INFO ] Patching feedparser issue #100 (FeedModule.py:27) [modules.Flickr ][INFO ] Module Information: flickrapi v1.1 (/home/john/testing/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.Backpack ][INFO ] Module Information: backpack (/home/john/testing/conduit/conduit/modules/BackpackModule/backpack/backpack.pyc) (BackpackModule.py:22) [modules.iPod ][INFO ] Module Information: gpod v(0, 6, 0) (/usr/lib/python2.5/site-packages/gpod/__init__.pyc) (iPodModule.py:45) [modules.Facebook ][INFO ] Module Information: pyfacebook v0.1 (/home/john/testing/conduit/conduit/modules/FacebookModule/pyfacebook/__init__.pyc) (FacebookModule.py:30) [modules.Facebook ][INFO ] Facebook parsing using: JSON (simplejson) (FacebookModule.py:31) [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) [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) [Conduit ][DEBUG ] Adding twoway dataprovider into source position (Conduit.py:140) [Conduit ][DEBUG ] Adding twoway dataprovider into sink position (Conduit.py:146) [Conduit ][DEBUG ] Enabling Two Way Sync (Conduit.py:247) [datatypes.DataType ][DEBUG ] COMPARE: 1 <----> 4 (DataType.py:56) [datatypes.DataType ][DEBUG ] Getting Rid for 1 (DataType.py:158) [datatypes.DataType ][DEBUG ] Getting Rid for 4 (DataType.py:158) [datatypes.DataType ][DEBUG ] COMPARE: 4 <----> 1 (DataType.py:56) [datatypes.DataType ][DEBUG ] Getting Rid for 4 (DataType.py:158) [datatypes.DataType ][DEBUG ] Getting Rid for 1 (DataType.py:158) [datatypes.DataType ][DEBUG ] COMPARE: 1 <----> 1 (DataType.py:56) [datatypes.DataType ][DEBUG ] Getting Rid for 1 (DataType.py:158) [datatypes.DataType ][DEBUG ] Getting Rid for 1 (DataType.py:158) [datatypes.DataType ][DEBUG ] COMPARE: 1 <----> 9 (DataType.py:56) [datatypes.DataType ][DEBUG ] Getting Rid for 1 (DataType.py:158) [datatypes.DataType ][DEBUG ] Getting Rid for 9 (DataType.py:158) [Syncronization ][DEBUG ] Created thread (thread: -1209648960) (Synchronization.py:211) [Syncronization ][INFO ] Setting global cancel flag (Synchronization.py:90) [Syncronization ][DEBUG ] Starting worker: Wrapper: twoway (UID: TestShell-B), initial)> (Synchronization.py:93) [Syncronization ][INFO ] Waiting for thread to finish (Synchronization.py:124) [Syncronization ][DEBUG ] Started thread Wrapper: twoway (UID: TestShell-B), started)> (thread: -1261671536) (Synchronization.py:624) [Syncronization ][DEBUG ] Sync Wrapper: twoway (UID: TestShell-B), 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 = Ready (Synchronization.py:677) [Syncronization ][DEBUG ] Syncworker state 2 (Synchronization.py:648) [Syncronization ][INFO ] Synchronizing (Two Way) Wrapper: twoway (UID: TestShell-A) <--> Wrapper: twoway (UID: TestShell-B) (Synchronization.py:514) [Syncronization ][DEBUG ] TestShell-A Changes: New 3 items ['1', '2', '3'] (Synchronization.py:241) [Syncronization ][DEBUG ] TestShell-A Changes: Modified 0 items [] (Synchronization.py:242) [Syncronization ][DEBUG ] TestShell-A Changes: Deleted 0 items [] (Synchronization.py:243) [Syncronization ][DEBUG ] TestShell-B Changes: New 2 items ['4', '5'] (Synchronization.py:241) [Syncronization ][DEBUG ] TestShell-B Changes: Modified 0 items [] (Synchronization.py:242) [Syncronization ][DEBUG ] TestShell-B Changes: Deleted 0 items [] (Synchronization.py:243) [Syncronization ][DEBUG ] 2WAY PUT: (1) -----> (Synchronization.py:569) [datatypes.DataType ][DEBUG ] Getting Rid for 1 (DataType.py:158) [TypeConverter ][DEBUG ] Convert test -> test using [('test', 'test', {})] (TypeConverter.py:209) [TypeConverter ][DEBUG ] Skipping test -> test (TypeConverter.py:168) [Syncronization ][INFO ] Putting data 1 --> None into TestShell-B (Synchronization.py:37) [datatypes.DataType ][DEBUG ] Getting Rid for 1 (DataType.py:158) [Syncronization ][DEBUG ] 2WAY PUT: (2) -----> (Synchronization.py:569) [datatypes.DataType ][DEBUG ] Getting Rid for 2 (DataType.py:158) [TypeConverter ][DEBUG ] Convert test -> test using [('test', 'test', {})] (TypeConverter.py:209) [TypeConverter ][DEBUG ] Skipping test -> test (TypeConverter.py:168) [Syncronization ][INFO ] Putting data 2 --> None into TestShell-B (Synchronization.py:37) [datatypes.DataType ][DEBUG ] Getting Rid for 2 (DataType.py:158) [Syncronization ][DEBUG ] 2WAY PUT: (3) -----> (Synchronization.py:569) [datatypes.DataType ][DEBUG ] Getting Rid for 3 (DataType.py:158) [TypeConverter ][DEBUG ] Convert test -> test using [('test', 'test', {})] (TypeConverter.py:209) [TypeConverter ][DEBUG ] Skipping test -> test (TypeConverter.py:168) [Syncronization ][INFO ] Putting data 3 --> None into TestShell-B (Synchronization.py:37) [datatypes.DataType ][DEBUG ] Getting Rid for 3 (DataType.py:158) [Syncronization ][DEBUG ] 2WAY PUT: (4) -----> (Synchronization.py:569) [datatypes.DataType ][DEBUG ] Getting Rid for 4 (DataType.py:158) [TypeConverter ][DEBUG ] Convert test -> test using [('test', 'test', {})] (TypeConverter.py:209) [TypeConverter ][DEBUG ] Skipping test -> test (TypeConverter.py:168) [Syncronization ][INFO ] Putting data 4 --> None into TestShell-A (Synchronization.py:37) [datatypes.DataType ][DEBUG ] Getting Rid for 4 (DataType.py:158) [Syncronization ][DEBUG ] 2WAY PUT: (5) -----> (Synchronization.py:569) [datatypes.DataType ][DEBUG ] Getting Rid for 5 (DataType.py:158) [TypeConverter ][DEBUG ] Convert test -> test using [('test', 'test', {})] (TypeConverter.py:209) [TypeConverter ][DEBUG ] Skipping test -> test (TypeConverter.py:168) [Syncronization ][INFO ] Putting data 5 --> None into TestShell-A (Synchronization.py:37) [datatypes.DataType ][DEBUG ] Getting Rid for 5 (DataType.py:158) [Syncronization ][DEBUG ] Syncworker state 3 (Synchronization.py:648) [Syncronization ][INFO ] Sync already in progress (Synchronization.py:172) [Syncronization ][INFO ] Waiting for thread to finish (Synchronization.py:124) [Syncronization ][DEBUG ] Created thread (thread: -1209648960) (Synchronization.py:211) [Syncronization ][INFO ] Setting global cancel flag (Synchronization.py:90) [Syncronization ][DEBUG ] Starting worker: Wrapper: twoway (UID: TestShell-B), initial)> (Synchronization.py:93) [Syncronization ][INFO ] Waiting for thread to finish (Synchronization.py:124) [Syncronization ][DEBUG ] Started thread Wrapper: twoway (UID: TestShell-B), started)> (thread: -1270064240) (Synchronization.py:624) [Syncronization ][DEBUG ] Sync Wrapper: twoway (UID: TestShell-B), 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) [Syncronization ][DEBUG ] Syncworker state 2 (Synchronization.py:648) [Syncronization ][INFO ] Synchronizing (Two Way) Wrapper: twoway (UID: TestShell-A) <--> Wrapper: twoway (UID: TestShell-B) (Synchronization.py:514) [Syncronization ][DEBUG ] TestShell-A Changes: New 0 items [] (Synchronization.py:241) [Syncronization ][DEBUG ] TestShell-A Changes: Modified 2 items ['4', '5'] (Synchronization.py:242) [Syncronization ][DEBUG ] TestShell-A Changes: Deleted 0 items [] (Synchronization.py:243) [Syncronization ][DEBUG ] TestShell-B Changes: New 0 items [] (Synchronization.py:241) [Syncronization ][DEBUG ] TestShell-B Changes: Modified 2 items ['1', '2'] (Synchronization.py:242) [Syncronization ][DEBUG ] TestShell-B Changes: Deleted 0 items [] (Synchronization.py:243) [Syncronization ][DEBUG ] 2WAY PUT: (4) -----> (Synchronization.py:569) [datatypes.DataType ][DEBUG ] Getting Rid for 4 (DataType.py:158) [TypeConverter ][DEBUG ] Convert test -> test using [('test', 'test', {})] (TypeConverter.py:209) [TypeConverter ][DEBUG ] Skipping test -> test (TypeConverter.py:168) [Syncronization ][INFO ] Putting data 4 --> 4 into TestShell-B (Synchronization.py:37) [datatypes.DataType ][DEBUG ] Getting Rid for 4 (DataType.py:158) [Syncronization ][DEBUG ] 2WAY PUT: (5) -----> (Synchronization.py:569) [datatypes.DataType ][DEBUG ] Getting Rid for 5 (DataType.py:158) [TypeConverter ][DEBUG ] Convert test -> test using [('test', 'test', {})] (TypeConverter.py:209) [TypeConverter ][DEBUG ] Skipping test -> test (TypeConverter.py:168) [Syncronization ][INFO ] Putting data 5 --> 5 into TestShell-B (Synchronization.py:37) [datatypes.DataType ][DEBUG ] Getting Rid for 5 (DataType.py:158) [Syncronization ][DEBUG ] 2WAY PUT: (1) -----> (Synchronization.py:569) [datatypes.DataType ][DEBUG ] Getting Rid for 1 (DataType.py:158) [TypeConverter ][DEBUG ] Convert test -> test using [('test', 'test', {})] (TypeConverter.py:209) [TypeConverter ][DEBUG ] Skipping test -> test (TypeConverter.py:168) [Syncronization ][INFO ] Putting data 1 --> 1 into TestShell-A (Synchronization.py:37) [datatypes.DataType ][DEBUG ] Getting Rid for 1 (DataType.py:158) [Syncronization ][DEBUG ] 2WAY PUT: (2) -----> (Synchronization.py:569) [datatypes.DataType ][DEBUG ] Getting Rid for 2 (DataType.py:158) [TypeConverter ][DEBUG ] Convert test -> test using [('test', 'test', {})] (TypeConverter.py:209) [TypeConverter ][DEBUG ] Skipping test -> test (TypeConverter.py:168) [Syncronization ][INFO ] Putting data 2 --> 2 into TestShell-A (Synchronization.py:37) [datatypes.DataType ][DEBUG ] Getting Rid for 2 (DataType.py:158) [Syncronization ][DEBUG ] Syncworker state 3 (Synchronization.py:648) [Syncronization ][INFO ] Sync already in progress (Synchronization.py:172) [Syncronization ][INFO ] Waiting for thread to finish (Synchronization.py:124) [Syncronization ][DEBUG ] Created thread (thread: -1209648960) (Synchronization.py:211) [Syncronization ][INFO ] Setting global cancel flag (Synchronization.py:90) [Syncronization ][DEBUG ] Starting worker: Wrapper: twoway (UID: TestShell-B), initial)> (Synchronization.py:93) [Syncronization ][INFO ] Waiting for thread to finish (Synchronization.py:124) [Syncronization ][DEBUG ] Started thread Wrapper: twoway (UID: TestShell-B), started)> (thread: -1270064240) (Synchronization.py:624) [Syncronization ][DEBUG ] Sync Wrapper: twoway (UID: TestShell-B), 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) [Syncronization ][DEBUG ] Syncworker state 2 (Synchronization.py:648) [Syncronization ][INFO ] Synchronizing (Two Way) Wrapper: twoway (UID: TestShell-A) <--> Wrapper: twoway (UID: TestShell-B) (Synchronization.py:514) [Syncronization ][DEBUG ] TestShell-A Changes: New 0 items [] (Synchronization.py:241) [Syncronization ][DEBUG ] TestShell-A Changes: Modified 0 items [] (Synchronization.py:242) [Syncronization ][DEBUG ] TestShell-A Changes: Deleted 1 items ['4'] (Synchronization.py:243) [Syncronization ][DEBUG ] TestShell-B Changes: New 0 items [] (Synchronization.py:241) [Syncronization ][DEBUG ] TestShell-B Changes: Modified 0 items [] (Synchronization.py:242) [Syncronization ][DEBUG ] TestShell-B Changes: Deleted 1 items ['2'] (Synchronization.py:243) [Syncronization ][DEBUG ] 2WAY DEL: (4) (Synchronization.py:558) [Syncronization ][DEBUG ] Deleted Policy: Skipping (Synchronization.py:374) [Syncronization ][DEBUG ] 2WAY DEL: (2) (Synchronization.py:558) [Syncronization ][DEBUG ] Deleted Policy: Skipping (Synchronization.py:374) [Syncronization ][DEBUG ] Syncworker state 3 (Synchronization.py:648) [Syncronization ][INFO ] Sync already in progress (Synchronization.py:172) [Syncronization ][INFO ] Waiting for thread to finish (Synchronization.py:124) [Syncronization ][DEBUG ] Created thread (thread: -1209648960) (Synchronization.py:211) [Syncronization ][INFO ] Setting global cancel flag (Synchronization.py:90) [Syncronization ][DEBUG ] Starting worker: Wrapper: twoway (UID: TestShell-B), initial)> (Synchronization.py:93) [Syncronization ][INFO ] Waiting for thread to finish (Synchronization.py:124) [Syncronization ][DEBUG ] Started thread Wrapper: twoway (UID: TestShell-B), started)> (thread: -1261671536) (Synchronization.py:624) [Syncronization ][DEBUG ] Sync Wrapper: twoway (UID: TestShell-B), 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) [Syncronization ][DEBUG ] Syncworker state 2 (Synchronization.py:648) [Syncronization ][INFO ] Synchronizing (Two Way) Wrapper: twoway (UID: TestShell-A) <--> Wrapper: twoway (UID: TestShell-B) (Synchronization.py:514) [Syncronization ][DEBUG ] TestShell-A Changes: New 0 items [] (Synchronization.py:241) [Syncronization ][DEBUG ] TestShell-A Changes: Modified 0 items [] (Synchronization.py:242) [Syncronization ][DEBUG ] TestShell-A Changes: Deleted 1 items ['4'] (Synchronization.py:243) [Syncronization ][DEBUG ] TestShell-B Changes: New 0 items [] (Synchronization.py:241) [Syncronization ][DEBUG ] TestShell-B Changes: Modified 0 items [] (Synchronization.py:242) [Syncronization ][DEBUG ] TestShell-B Changes: Deleted 1 items ['2'] (Synchronization.py:243) [Syncronization ][DEBUG ] 2WAY DEL: (4) (Synchronization.py:558) [Syncronization ][DEBUG ] Deleted Policy: Ask (Synchronization.py:376) [Syncronization ][DEBUG ] 2WAY DEL: (2) (Synchronization.py:558) [Syncronization ][DEBUG ] Deleted Policy: Ask (Synchronization.py:376) [Syncronization ][DEBUG ] Syncworker state 3 (Synchronization.py:648) [Syncronization ][INFO ] Sync already in progress (Synchronization.py:172) [Syncronization ][INFO ] Waiting for thread to finish (Synchronization.py:124) [Syncronization ][DEBUG ] Created thread (thread: -1209648960) (Synchronization.py:211) [Syncronization ][INFO ] Setting global cancel flag (Synchronization.py:90) [Syncronization ][DEBUG ] Starting worker: Wrapper: twoway (UID: TestShell-B), initial)> (Synchronization.py:93) [Syncronization ][INFO ] Waiting for thread to finish (Synchronization.py:124) [Syncronization ][DEBUG ] Started thread Wrapper: twoway (UID: TestShell-B), started)> (thread: -1270064240) (Synchronization.py:624) [Syncronization ][DEBUG ] Sync Wrapper: twoway (UID: TestShell-B), 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) [Syncronization ][DEBUG ] Syncworker state 2 (Synchronization.py:648) [Syncronization ][INFO ] Synchronizing (Two Way) Wrapper: twoway (UID: TestShell-A) <--> Wrapper: twoway (UID: TestShell-B) (Synchronization.py:514) [Syncronization ][DEBUG ] TestShell-A Changes: New 0 items [] (Synchronization.py:241) [Syncronization ][DEBUG ] TestShell-A Changes: Modified 0 items [] (Synchronization.py:242) [Syncronization ][DEBUG ] TestShell-A Changes: Deleted 1 items ['4'] (Synchronization.py:243) [Syncronization ][DEBUG ] TestShell-B Changes: New 0 items [] (Synchronization.py:241) [Syncronization ][DEBUG ] TestShell-B Changes: Modified 0 items [] (Synchronization.py:242) [Syncronization ][DEBUG ] TestShell-B Changes: Deleted 1 items ['2'] (Synchronization.py:243) [Syncronization ][DEBUG ] 2WAY DEL: (4) (Synchronization.py:558) [Syncronization ][DEBUG ] Deleted Policy: Delete (Synchronization.py:408) [Syncronization ][INFO ] Deleting 4 from TestShell-B (Synchronization.py:52) [Syncronization ][DEBUG ] 2WAY DEL: (2) (Synchronization.py:558) [Syncronization ][DEBUG ] Deleted Policy: Delete (Synchronization.py:408) [Syncronization ][INFO ] Deleting 2 from TestShell-A (Synchronization.py:52) [Syncronization ][DEBUG ] Syncworker state 3 (Synchronization.py:648) [Syncronization ][INFO ] Sync already in progress (Synchronization.py:172) [Syncronization ][INFO ] Waiting for thread to finish (Synchronization.py:124) [Syncronization ][DEBUG ] Created thread (thread: -1209648960) (Synchronization.py:211) [Syncronization ][INFO ] Setting global cancel flag (Synchronization.py:90) [Syncronization ][DEBUG ] Starting worker: Wrapper: twoway (UID: TestShell-B), initial)> (Synchronization.py:93) [Syncronization ][INFO ] Waiting for thread to finish (Synchronization.py:124) [Syncronization ][DEBUG ] Started thread Wrapper: twoway (UID: TestShell-B), started)> (thread: -1270064240) (Synchronization.py:624) [Syncronization ][DEBUG ] Sync Wrapper: twoway (UID: TestShell-B), 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) [Syncronization ][DEBUG ] Syncworker state 2 (Synchronization.py:648) [Syncronization ][INFO ] Synchronizing (Two Way) Wrapper: twoway (UID: TestShell-A) <--> Wrapper: twoway (UID: TestShell-B) (Synchronization.py:514) [Syncronization ][DEBUG ] TestShell-A Changes: New 0 items [] (Synchronization.py:241) [Syncronization ][DEBUG ] TestShell-A Changes: Modified 2 items ['1', '5'] (Synchronization.py:242) [Syncronization ][DEBUG ] TestShell-A Changes: Deleted 0 items [] (Synchronization.py:243) [Syncronization ][DEBUG ] TestShell-B Changes: New 0 items [] (Synchronization.py:241) [Syncronization ][DEBUG ] TestShell-B Changes: Modified 2 items ['1', '5'] (Synchronization.py:242) [Syncronization ][DEBUG ] TestShell-B Changes: Deleted 0 items [] (Synchronization.py:243) [Syncronization ][WARNING ] 2WAY BOTH MODIFIED: 1 v 1 (Synchronization.py:543) [Syncronization ][WARNING ] 2WAY BOTH MODIFIED: 5 v 5 (Synchronization.py:543) [datatypes.DataType ][DEBUG ] Getting Rid for 1 (DataType.py:158) [datatypes.DataType ][DEBUG ] Getting Rid for 1 (DataType.py:158) [TypeConverter ][DEBUG ] Convert test -> test using [('test', 'test', {})] (TypeConverter.py:209) [TypeConverter ][DEBUG ] Skipping test -> test (TypeConverter.py:168) [Syncronization ][DEBUG ] 2WAY CMP: <__main__.TestDataType object at 0xa57426c> v <__main__.TestDataType object at 0xa5742ac> (Synchronization.py:589) [datatypes.DataType ][DEBUG ] COMPARE: 1 <----> 1 (DataType.py:56) [datatypes.DataType ][DEBUG ] Getting Rid for 1 (DataType.py:158) [datatypes.DataType ][DEBUG ] Getting Rid for 1 (DataType.py:158) [Syncronization ][DEBUG ] Conflict Policy: Skipping (Synchronization.py:419) [datatypes.DataType ][DEBUG ] Getting Rid for 5 (DataType.py:158) [datatypes.DataType ][DEBUG ] Getting Rid for 5 (DataType.py:158) [TypeConverter ][DEBUG ] Convert test -> test using [('test', 'test', {})] (TypeConverter.py:209) [TypeConverter ][DEBUG ] Skipping test -> test (TypeConverter.py:168) [Syncronization ][DEBUG ] 2WAY CMP: <__main__.TestDataType object at 0xa57452c> v <__main__.TestDataType object at 0xa57418c> (Synchronization.py:589) [datatypes.DataType ][DEBUG ] COMPARE: 5 <----> 5 (DataType.py:56) [datatypes.DataType ][DEBUG ] Getting Rid for 5 (DataType.py:158) [datatypes.DataType ][DEBUG ] Getting Rid for 5 (DataType.py:158) [Syncronization ][DEBUG ] Conflict Policy: Skipping (Synchronization.py:419) [Syncronization ][DEBUG ] Syncworker state 3 (Synchronization.py:648) [Syncronization ][INFO ] Sync already in progress (Synchronization.py:172) [Syncronization ][INFO ] Waiting for thread to finish (Synchronization.py:124) [Syncronization ][DEBUG ] Created thread (thread: -1209648960) (Synchronization.py:211) [Syncronization ][INFO ] Setting global cancel flag (Synchronization.py:90) [Syncronization ][DEBUG ] Starting worker: Wrapper: twoway (UID: TestShell-B), initial)> (Synchronization.py:93) [Syncronization ][INFO ] Waiting for thread to finish (Synchronization.py:124) [Syncronization ][DEBUG ] Started thread Wrapper: twoway (UID: TestShell-B), started)> (thread: -1270064240) (Synchronization.py:624) [Syncronization ][DEBUG ] Sync Wrapper: twoway (UID: TestShell-B), 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) [Syncronization ][DEBUG ] Syncworker state 2 (Synchronization.py:648) [Syncronization ][INFO ] Synchronizing (Two Way) Wrapper: twoway (UID: TestShell-A) <--> Wrapper: twoway (UID: TestShell-B) (Synchronization.py:514) [Syncronization ][DEBUG ] TestShell-A Changes: New 0 items [] (Synchronization.py:241) [Syncronization ][DEBUG ] TestShell-A Changes: Modified 2 items ['1', '5'] (Synchronization.py:242) [Syncronization ][DEBUG ] TestShell-A Changes: Deleted 0 items [] (Synchronization.py:243) [Syncronization ][DEBUG ] TestShell-B Changes: New 0 items [] (Synchronization.py:241) [Syncronization ][DEBUG ] TestShell-B Changes: Modified 2 items ['1', '5'] (Synchronization.py:242) [Syncronization ][DEBUG ] TestShell-B Changes: Deleted 0 items [] (Synchronization.py:243) [Syncronization ][WARNING ] 2WAY BOTH MODIFIED: 1 v 1 (Synchronization.py:543) [Syncronization ][WARNING ] 2WAY BOTH MODIFIED: 5 v 5 (Synchronization.py:543) [datatypes.DataType ][DEBUG ] Getting Rid for 1 (DataType.py:158) [datatypes.DataType ][DEBUG ] Getting Rid for 1 (DataType.py:158) [TypeConverter ][DEBUG ] Convert test -> test using [('test', 'test', {})] (TypeConverter.py:209) [TypeConverter ][DEBUG ] Skipping test -> test (TypeConverter.py:168) [Syncronization ][DEBUG ] 2WAY CMP: <__main__.TestDataType object at 0xa5748ec> v <__main__.TestDataType object at 0xa5747cc> (Synchronization.py:589) [datatypes.DataType ][DEBUG ] COMPARE: 1 <----> 1 (DataType.py:56) [datatypes.DataType ][DEBUG ] Getting Rid for 1 (DataType.py:158) [datatypes.DataType ][DEBUG ] Getting Rid for 1 (DataType.py:158) [Syncronization ][DEBUG ] Conflict Policy: Ask (Synchronization.py:421) [datatypes.DataType ][DEBUG ] Getting Rid for 5 (DataType.py:158) [datatypes.DataType ][DEBUG ] Getting Rid for 5 (DataType.py:158) [TypeConverter ][DEBUG ] Convert test -> test using [('test', 'test', {})] (TypeConverter.py:209) [TypeConverter ][DEBUG ] Skipping test -> test (TypeConverter.py:168) [Syncronization ][DEBUG ] 2WAY CMP: <__main__.TestDataType object at 0xa574c6c> v <__main__.TestDataType object at 0xa574d0c> (Synchronization.py:589) [datatypes.DataType ][DEBUG ] COMPARE: 5 <----> 5 (DataType.py:56) [datatypes.DataType ][DEBUG ] Getting Rid for 5 (DataType.py:158) [datatypes.DataType ][DEBUG ] Getting Rid for 5 (DataType.py:158) [Syncronization ][DEBUG ] Conflict Policy: Ask (Synchronization.py:421) [Syncronization ][DEBUG ] Syncworker state 3 (Synchronization.py:648) [Syncronization ][INFO ] Sync already in progress (Synchronization.py:172) [Syncronization ][INFO ] Waiting for thread to finish (Synchronization.py:124) [Syncronization ][DEBUG ] Created thread (thread: -1209648960) (Synchronization.py:211) [Syncronization ][INFO ] Setting global cancel flag (Synchronization.py:90) [Syncronization ][DEBUG ] Starting worker: Wrapper: twoway (UID: TestShell-B), initial)> (Synchronization.py:93) [Syncronization ][INFO ] Waiting for thread to finish (Synchronization.py:124) [Syncronization ][DEBUG ] Started thread Wrapper: twoway (UID: TestShell-B), started)> (thread: -1270064240) (Synchronization.py:624) [Syncronization ][DEBUG ] Sync Wrapper: twoway (UID: TestShell-B), 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) [Syncronization ][DEBUG ] Syncworker state 2 (Synchronization.py:648) [Syncronization ][INFO ] Synchronizing (Two Way) Wrapper: twoway (UID: TestShell-A) <--> Wrapper: twoway (UID: TestShell-B) (Synchronization.py:514) [Syncronization ][DEBUG ] TestShell-A Changes: New 0 items [] (Synchronization.py:241) [Syncronization ][DEBUG ] TestShell-A Changes: Modified 2 items ['1', '5'] (Synchronization.py:242) [Syncronization ][DEBUG ] TestShell-A Changes: Deleted 0 items [] (Synchronization.py:243) [Syncronization ][DEBUG ] TestShell-B Changes: New 0 items [] (Synchronization.py:241) [Syncronization ][DEBUG ] TestShell-B Changes: Modified 2 items ['1', '5'] (Synchronization.py:242) [Syncronization ][DEBUG ] TestShell-B Changes: Deleted 0 items [] (Synchronization.py:243) [Syncronization ][WARNING ] 2WAY BOTH MODIFIED: 1 v 1 (Synchronization.py:543) [Syncronization ][WARNING ] 2WAY BOTH MODIFIED: 5 v 5 (Synchronization.py:543) [datatypes.DataType ][DEBUG ] Getting Rid for 1 (DataType.py:158) [datatypes.DataType ][DEBUG ] Getting Rid for 1 (DataType.py:158) [TypeConverter ][DEBUG ] Convert test -> test using [('test', 'test', {})] (TypeConverter.py:209) [TypeConverter ][DEBUG ] Skipping test -> test (TypeConverter.py:168) [Syncronization ][DEBUG ] 2WAY CMP: <__main__.TestDataType object at 0xa57628c> v <__main__.TestDataType object at 0xa57610c> (Synchronization.py:589) [datatypes.DataType ][DEBUG ] COMPARE: 1 <----> 1 (DataType.py:56) [datatypes.DataType ][DEBUG ] Getting Rid for 1 (DataType.py:158) [datatypes.DataType ][DEBUG ] Getting Rid for 1 (DataType.py:158) [Syncronization ][DEBUG ] Conflict Policy: Replace (Synchronization.py:444) [Syncronization ][INFO ] Putting data 1 --> 1 into TestShell-B (Synchronization.py:37) [datatypes.DataType ][DEBUG ] Getting Rid for 1 (DataType.py:158) [datatypes.DataType ][DEBUG ] Getting Rid for 5 (DataType.py:158) [datatypes.DataType ][DEBUG ] Getting Rid for 5 (DataType.py:158) [TypeConverter ][DEBUG ] Convert test -> test using [('test', 'test', {})] (TypeConverter.py:209) [TypeConverter ][DEBUG ] Skipping test -> test (TypeConverter.py:168) [Syncronization ][DEBUG ] 2WAY CMP: <__main__.TestDataType object at 0xa57654c> v <__main__.TestDataType object at 0xa57634c> (Synchronization.py:589) [datatypes.DataType ][DEBUG ] COMPARE: 5 <----> 5 (DataType.py:56) [datatypes.DataType ][DEBUG ] Getting Rid for 5 (DataType.py:158) [datatypes.DataType ][DEBUG ] Getting Rid for 5 (DataType.py:158) [Syncronization ][DEBUG ] Conflict Policy: Replace (Synchronization.py:444) [Syncronization ][INFO ] Putting data 5 --> 5 into TestShell-A (Synchronization.py:37) [datatypes.DataType ][DEBUG ] Getting Rid for 5 (DataType.py:158) [Syncronization ][DEBUG ] Syncworker state 3 (Synchronization.py:648) [Syncronization ][INFO ] Sync already in progress (Synchronization.py:172) [Syncronization ][INFO ] Waiting for thread to finish (Synchronization.py:124) [Syncronization ][DEBUG ] Created thread (thread: -1209648960) (Synchronization.py:211) [Syncronization ][INFO ] Setting global cancel flag (Synchronization.py:90) [Syncronization ][DEBUG ] Starting worker: Wrapper: twoway (UID: TestShell-B), initial)> (Synchronization.py:93) [Syncronization ][DEBUG ] Started thread Wrapper: twoway (UID: TestShell-B), started)> (thread: -1270064240) (Synchronization.py:624) [Syncronization ][DEBUG ] Sync Wrapper: twoway (UID: TestShell-B), 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) [Syncronization ][DEBUG ] Syncworker state 2 (Synchronization.py:648) [Syncronization ][INFO ] Synchronizing (Two Way) Wrapper: twoway (UID: TestShell-A) <--> Wrapper: twoway (UID: TestShell-B) (Synchronization.py:514) [Syncronization ][DEBUG ] TestShell-A Changes: New 0 items [] (Synchronization.py:241) [Syncronization ][DEBUG ] TestShell-A Changes: Modified 1 items ['3'] (Synchronization.py:242) [Syncronization ][DEBUG ] TestShell-A Changes: Deleted 0 items [] (Synchronization.py:243) [Syncronization ][DEBUG ] TestShell-B Changes: New 0 items [] (Synchronization.py:241) [Syncronization ][DEBUG ] TestShell-B Changes: Modified 0 items [] (Synchronization.py:242) [Syncronization ][DEBUG ] TestShell-B Changes: Deleted 1 items ['3'] (Synchronization.py:243) [Syncronization ][INFO ] Waiting for thread to finish (Synchronization.py:124) [Syncronization ][DEBUG ] 2WAY MOD+DEL: 3 v 3 (Synchronization.py:508) [Syncronization ][DEBUG ] 2WAY DEL: (3) (Synchronization.py:558) [Syncronization ][DEBUG ] Deleted Policy: Skipping (Synchronization.py:374) [Syncronization ][DEBUG ] Syncworker state 3 (Synchronization.py:648) [Syncronization ][INFO ] Sync already in progress (Synchronization.py:172) [Syncronization ][INFO ] Waiting for thread to finish (Synchronization.py:124) [Syncronization ][DEBUG ] Created thread (thread: -1209648960) (Synchronization.py:211) [Syncronization ][INFO ] Setting global cancel flag (Synchronization.py:90) [Syncronization ][DEBUG ] Starting worker: Wrapper: twoway (UID: TestShell-B), initial)> (Synchronization.py:93) [Syncronization ][INFO ] Waiting for thread to finish (Synchronization.py:124) [Syncronization ][DEBUG ] Started thread Wrapper: twoway (UID: TestShell-B), started)> (thread: -1270064240) (Synchronization.py:624) [Syncronization ][DEBUG ] Sync Wrapper: twoway (UID: TestShell-B), 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) [Syncronization ][DEBUG ] Syncworker state 2 (Synchronization.py:648) [Syncronization ][INFO ] Synchronizing (Two Way) Wrapper: twoway (UID: TestShell-A) <--> Wrapper: twoway (UID: TestShell-B) (Synchronization.py:514) [Syncronization ][DEBUG ] TestShell-A Changes: New 0 items [] (Synchronization.py:241) [Syncronization ][DEBUG ] TestShell-A Changes: Modified 1 items ['3'] (Synchronization.py:242) [Syncronization ][DEBUG ] TestShell-A Changes: Deleted 0 items [] (Synchronization.py:243) [Syncronization ][DEBUG ] TestShell-B Changes: New 0 items [] (Synchronization.py:241) [Syncronization ][DEBUG ] TestShell-B Changes: Modified 0 items [] (Synchronization.py:242) [Syncronization ][DEBUG ] TestShell-B Changes: Deleted 1 items ['3'] (Synchronization.py:243) [Syncronization ][DEBUG ] 2WAY MOD+DEL: 3 v 3 (Synchronization.py:508) [Syncronization ][DEBUG ] 2WAY DEL: (3) (Synchronization.py:558) [Syncronization ][DEBUG ] Deleted Policy: Ask (Synchronization.py:376) [Syncronization ][DEBUG ] Syncworker state 3 (Synchronization.py:648) [Syncronization ][INFO ] Sync already in progress (Synchronization.py:172) [Syncronization ][INFO ] Waiting for thread to finish (Synchronization.py:124) [Syncronization ][DEBUG ] Created thread (thread: -1209648960) (Synchronization.py:211) [Syncronization ][INFO ] Setting global cancel flag (Synchronization.py:90) [Syncronization ][DEBUG ] Starting worker: Wrapper: twoway (UID: TestShell-B), initial)> (Synchronization.py:93) [Syncronization ][DEBUG ] Started thread Wrapper: twoway (UID: TestShell-B), started)> (thread: -1270064240) (Synchronization.py:624) [Syncronization ][DEBUG ] Sync Wrapper: twoway (UID: TestShell-B), 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) [Syncronization ][DEBUG ] Syncworker state 2 (Synchronization.py:648) [Syncronization ][INFO ] Synchronizing (Two Way) Wrapper: twoway (UID: TestShell-A) <--> Wrapper: twoway (UID: TestShell-B) (Synchronization.py:514) [Syncronization ][DEBUG ] TestShell-A Changes: New 0 items [] (Synchronization.py:241) [Syncronization ][DEBUG ] TestShell-A Changes: Modified 1 items ['3'] (Synchronization.py:242) [Syncronization ][DEBUG ] TestShell-A Changes: Deleted 0 items [] (Synchronization.py:243) [Syncronization ][INFO ] Waiting for thread to finish (Synchronization.py:124) [Syncronization ][DEBUG ] TestShell-B Changes: New 0 items [] (Synchronization.py:241) [Syncronization ][DEBUG ] TestShell-B Changes: Modified 0 items [] (Synchronization.py:242) [Syncronization ][DEBUG ] TestShell-B Changes: Deleted 1 items ['3'] (Synchronization.py:243) [Syncronization ][DEBUG ] 2WAY MOD+DEL: 3 v 3 (Synchronization.py:508) [Syncronization ][DEBUG ] 2WAY DEL: (3) (Synchronization.py:558) [Syncronization ][DEBUG ] Deleted Policy: Delete (Synchronization.py:408) [Syncronization ][INFO ] Deleting 3 from TestShell-A (Synchronization.py:52) [Syncronization ][DEBUG ] Syncworker state 3 (Synchronization.py:648) [Conduit ][DEBUG ] Disabling Two Way Sync (Conduit.py:252) [Syncronization ][DEBUG ] Created thread (thread: -1209648960) (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: -1270064240) (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) [Syncronization ][DEBUG ] Syncworker state 2 (Synchronization.py:648) [Syncronization ][INFO ] Synchronizing Wrapper: source (UID: TestShell-A) |--> Wrapper: sink (UID: TestShell-B) (Synchronization.py:467) [Syncronization ][DEBUG ] TestShell-A Changes: New 5 items ['1', '2', '3', '4', '5'] (Synchronization.py:241) [Syncronization ][DEBUG ] TestShell-A Changes: Modified 0 items [] (Synchronization.py:242) [Syncronization ][DEBUG ] TestShell-A Changes: Deleted 0 items [] (Synchronization.py:243) [Syncronization ][DEBUG ] 1WAY PUT: (1) -----> (Synchronization.py:489) [datatypes.DataType ][DEBUG ] Getting Rid for 1 (DataType.py:158) [TypeConverter ][DEBUG ] Convert test -> test using [('test', 'test', {})] (TypeConverter.py:209) [TypeConverter ][DEBUG ] Skipping test -> test (TypeConverter.py:168) [Syncronization ][INFO ] Putting data 1 --> 1 into TestShell-B (Synchronization.py:37) [datatypes.DataType ][DEBUG ] Getting Rid for 1 (DataType.py:158) [Syncronization ][DEBUG ] 1WAY PUT: (2) -----> (Synchronization.py:489) [datatypes.DataType ][DEBUG ] Getting Rid for 2 (DataType.py:158) [TypeConverter ][DEBUG ] Convert test -> test using [('test', 'test', {})] (TypeConverter.py:209) [TypeConverter ][DEBUG ] Skipping test -> test (TypeConverter.py:168) [Syncronization ][INFO ] Putting data 2 --> None into TestShell-B (Synchronization.py:37) [datatypes.DataType ][DEBUG ] Getting Rid for 2 (DataType.py:158) [Syncronization ][DEBUG ] 1WAY PUT: (3) -----> (Synchronization.py:489) [datatypes.DataType ][DEBUG ] Getting Rid for 3 (DataType.py:158) [TypeConverter ][DEBUG ] Convert test -> test using [('test', 'test', {})] (TypeConverter.py:209) [TypeConverter ][DEBUG ] Skipping test -> test (TypeConverter.py:168) [Syncronization ][INFO ] Putting data 3 --> None into TestShell-B (Synchronization.py:37) [datatypes.DataType ][DEBUG ] Getting Rid for 3 (DataType.py:158) [Syncronization ][DEBUG ] 1WAY PUT: (4) -----> (Synchronization.py:489) [datatypes.DataType ][DEBUG ] Getting Rid for 4 (DataType.py:158) [TypeConverter ][DEBUG ] Convert test -> test using [('test', 'test', {})] (TypeConverter.py:209) [TypeConverter ][DEBUG ] Skipping test -> test (TypeConverter.py:168) [Syncronization ][INFO ] Putting data 4 --> None into TestShell-B (Synchronization.py:37) [datatypes.DataType ][DEBUG ] Getting Rid for 4 (DataType.py:158) [Syncronization ][DEBUG ] 1WAY PUT: (5) -----> (Synchronization.py:489) [datatypes.DataType ][DEBUG ] Getting Rid for 5 (DataType.py:158) [TypeConverter ][DEBUG ] Convert test -> test using [('test', 'test', {})] (TypeConverter.py:209) [TypeConverter ][DEBUG ] Skipping test -> test (TypeConverter.py:168) [Syncronization ][INFO ] Putting data 5 --> 5 into TestShell-B (Synchronization.py:37) [datatypes.DataType ][DEBUG ] Getting Rid for 5 (DataType.py:158) [Syncronization ][DEBUG ] Syncworker state 3 (Synchronization.py:648) [Syncronization ][INFO ] Sync already in progress (Synchronization.py:172) [Syncronization ][INFO ] Waiting for thread to finish (Synchronization.py:124) [Syncronization ][DEBUG ] Created thread (thread: -1209648960) (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: -1261671536) (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) [Syncronization ][DEBUG ] Syncworker state 2 (Synchronization.py:648) [Syncronization ][INFO ] Synchronizing Wrapper: source (UID: TestShell-A) |--> Wrapper: sink (UID: TestShell-B) (Synchronization.py:467) [Syncronization ][DEBUG ] TestShell-A Changes: New 0 items [] (Synchronization.py:241) [Syncronization ][DEBUG ] TestShell-A Changes: Modified 3 items ['1', '2', '3'] (Synchronization.py:242) [Syncronization ][DEBUG ] TestShell-A Changes: Deleted 0 items [] (Synchronization.py:243) [Syncronization ][DEBUG ] 1WAY PUT: (1) -----> (Synchronization.py:489) [datatypes.DataType ][DEBUG ] Getting Rid for 1 (DataType.py:158) [TypeConverter ][DEBUG ] Convert test -> test using [('test', 'test', {})] (TypeConverter.py:209) [TypeConverter ][DEBUG ] Skipping test -> test (TypeConverter.py:168) [Syncronization ][INFO ] Putting data 1 --> 1 into TestShell-B (Synchronization.py:37) [datatypes.DataType ][DEBUG ] Getting Rid for 1 (DataType.py:158) [Syncronization ][DEBUG ] 1WAY PUT: (2) -----> (Synchronization.py:489) [datatypes.DataType ][DEBUG ] Getting Rid for 2 (DataType.py:158) [TypeConverter ][DEBUG ] Convert test -> test using [('test', 'test', {})] (TypeConverter.py:209) [TypeConverter ][DEBUG ] Skipping test -> test (TypeConverter.py:168) [Syncronization ][INFO ] Putting data 2 --> 2 into TestShell-B (Synchronization.py:37) [datatypes.DataType ][DEBUG ] Getting Rid for 2 (DataType.py:158) [Syncronization ][DEBUG ] 1WAY PUT: (3) -----> (Synchronization.py:489) [datatypes.DataType ][DEBUG ] Getting Rid for 3 (DataType.py:158) [TypeConverter ][DEBUG ] Convert test -> test using [('test', 'test', {})] (TypeConverter.py:209) [TypeConverter ][DEBUG ] Skipping test -> test (TypeConverter.py:168) [Syncronization ][INFO ] Putting data 3 --> 3 into TestShell-B (Synchronization.py:37) [datatypes.DataType ][DEBUG ] Getting Rid for 3 (DataType.py:158) [Syncronization ][DEBUG ] Syncworker state 3 (Synchronization.py:648) [Syncronization ][INFO ] Sync already in progress (Synchronization.py:172) [Syncronization ][INFO ] Waiting for thread to finish (Synchronization.py:124) [Syncronization ][DEBUG ] Created thread (thread: -1209648960) (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: -1261671536) (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) [Syncronization ][DEBUG ] Syncworker state 2 (Synchronization.py:648) [Syncronization ][INFO ] Synchronizing Wrapper: source (UID: TestShell-A) |--> Wrapper: sink (UID: TestShell-B) (Synchronization.py:467) [Syncronization ][DEBUG ] TestShell-A Changes: New 0 items [] (Synchronization.py:241) [Syncronization ][DEBUG ] TestShell-A Changes: Modified 0 items [] (Synchronization.py:242) [Syncronization ][DEBUG ] TestShell-A Changes: Deleted 3 items ['1', '2', '3'] (Synchronization.py:243) [Syncronization ][DEBUG ] Deleted Policy: Skipping (Synchronization.py:374) [Syncronization ][DEBUG ] Deleted Policy: Skipping (Synchronization.py:374) [Syncronization ][DEBUG ] Deleted Policy: Skipping (Synchronization.py:374) [Syncronization ][DEBUG ] Syncworker state 3 (Synchronization.py:648) [Syncronization ][INFO ] Sync already in progress (Synchronization.py:172) [Syncronization ][INFO ] Waiting for thread to finish (Synchronization.py:124) [Syncronization ][DEBUG ] Created thread (thread: -1209648960) (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: -1261671536) (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) [Syncronization ][DEBUG ] Syncworker state 2 (Synchronization.py:648) [Syncronization ][INFO ] Synchronizing Wrapper: source (UID: TestShell-A) |--> Wrapper: sink (UID: TestShell-B) (Synchronization.py:467) [Syncronization ][DEBUG ] TestShell-A Changes: New 0 items [] (Synchronization.py:241) [Syncronization ][DEBUG ] TestShell-A Changes: Modified 0 items [] (Synchronization.py:242) [Syncronization ][DEBUG ] TestShell-A Changes: Deleted 3 items ['1', '2', '3'] (Synchronization.py:243) [Syncronization ][DEBUG ] Deleted Policy: Ask (Synchronization.py:376) [Syncronization ][DEBUG ] Deleted Policy: Ask (Synchronization.py:376) [Syncronization ][DEBUG ] Deleted Policy: Ask (Synchronization.py:376) [Syncronization ][DEBUG ] Syncworker state 3 (Synchronization.py:648) [Syncronization ][INFO ] Sync already in progress (Synchronization.py:172) [Syncronization ][INFO ] Waiting for thread to finish (Synchronization.py:124) [Syncronization ][DEBUG ] Created thread (thread: -1209648960) (Synchronization.py:211) [Syncronization ][INFO ] Setting global cancel flag (Synchronization.py:90) [Syncronization ][DEBUG ] Starting worker: [], initial)> (Synchronization.py:93) [Syncronization ][DEBUG ] Started thread [], started)> (thread: -1261671536) (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) [Syncronization ][DEBUG ] Syncworker state 2 (Synchronization.py:648) [Syncronization ][INFO ] Synchronizing Wrapper: source (UID: TestShell-A) |--> Wrapper: sink (UID: TestShell-B) (Synchronization.py:467) [Syncronization ][DEBUG ] TestShell-A Changes: New 0 items [] (Synchronization.py:241) [Syncronization ][DEBUG ] TestShell-A Changes: Modified 0 items [] (Synchronization.py:242) [Syncronization ][DEBUG ] TestShell-A Changes: Deleted 3 items ['1', '2', '3'] (Synchronization.py:243) [Syncronization ][INFO ] Waiting for thread to finish (Synchronization.py:124) [Syncronization ][DEBUG ] Deleted Policy: Delete (Synchronization.py:408) [Syncronization ][INFO ] Deleting 1 from TestShell-B (Synchronization.py:52) [Syncronization ][DEBUG ] Deleted Policy: Delete (Synchronization.py:408) [Syncronization ][INFO ] Deleting 2 from TestShell-B (Synchronization.py:52) [Syncronization ][DEBUG ] Deleted Policy: Delete (Synchronization.py:408) [Syncronization ][INFO ] Deleting 3 from TestShell-B (Synchronization.py:52) [Syncronization ][DEBUG ] Syncworker state 3 (Synchronization.py:648) [Conduit ][DEBUG ] Adding twoway dataprovider into source position (Conduit.py:140) [Conduit ][DEBUG ] Adding twoway dataprovider into sink position (Conduit.py:146) [Syncronization ][DEBUG ] Created thread (thread: -1209648960) (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: -1261671536) (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 = Ready (Synchronization.py:677) [Syncronization ][DEBUG ] Syncworker state 2 (Synchronization.py:648) [Syncronization ][INFO ] Synchronizing Wrapper: twoway (UID: TestShellGetAll-X) |--> Wrapper: twoway (UID: TestShellGetAll-Y) (Synchronization.py:467) [DeltaProvider ][INFO ] Delta: Source (TestShellGetAll-X) does not implement get_changes(). Proxying... (DeltaProvider.py:25) [DeltaProvider ][DEBUG ] Delta: Got 3 items [u'1', u'2', u'3'] (DeltaProvider.py:39) [DeltaProvider ][DEBUG ] Delta: Expecting 0 items (DeltaProvider.py:49) [Syncronization ][DEBUG ] TestShellGetAll-X Changes: New 3 items [u'1', u'2', u'3'] (Synchronization.py:241) [Syncronization ][DEBUG ] TestShellGetAll-X Changes: Modified 0 items [] (Synchronization.py:242) [Syncronization ][DEBUG ] TestShellGetAll-X Changes: Deleted 0 items [] (Synchronization.py:243) [Syncronization ][DEBUG ] 1WAY PUT: (1) -----> (Synchronization.py:489) [datatypes.DataType ][DEBUG ] Getting Rid for 1 (DataType.py:158) [TypeConverter ][DEBUG ] Convert test -> test using [('test', 'test', {})] (TypeConverter.py:209) [TypeConverter ][DEBUG ] Skipping test -> test (TypeConverter.py:168) [Syncronization ][INFO ] Putting data 1 --> None into TestShellGetAll-Y (Synchronization.py:37) [datatypes.DataType ][DEBUG ] Getting Rid for 1 (DataType.py:158) [Syncronization ][DEBUG ] 1WAY PUT: (2) -----> (Synchronization.py:489) [datatypes.DataType ][DEBUG ] Getting Rid for 2 (DataType.py:158) [TypeConverter ][DEBUG ] Convert test -> test using [('test', 'test', {})] (TypeConverter.py:209) [TypeConverter ][DEBUG ] Skipping test -> test (TypeConverter.py:168) [Syncronization ][INFO ] Putting data 2 --> None into TestShellGetAll-Y (Synchronization.py:37) [datatypes.DataType ][DEBUG ] Getting Rid for 2 (DataType.py:158) [Syncronization ][DEBUG ] 1WAY PUT: (3) -----> (Synchronization.py:489) [datatypes.DataType ][DEBUG ] Getting Rid for 3 (DataType.py:158) [TypeConverter ][DEBUG ] Convert test -> test using [('test', 'test', {})] (TypeConverter.py:209) [TypeConverter ][DEBUG ] Skipping test -> test (TypeConverter.py:168) [Syncronization ][INFO ] Putting data 3 --> None into TestShellGetAll-Y (Synchronization.py:37) [datatypes.DataType ][DEBUG ] Getting Rid for 3 (DataType.py:158) [Syncronization ][DEBUG ] Syncworker state 3 (Synchronization.py:648) [Syncronization ][INFO ] Sync already in progress (Synchronization.py:172) [Syncronization ][INFO ] Waiting for thread to finish (Synchronization.py:124) [Syncronization ][DEBUG ] Created thread (thread: -1209648960) (Synchronization.py:211) [Syncronization ][INFO ] Setting global cancel flag (Synchronization.py:90) [Syncronization ][DEBUG ] Starting worker: [], initial)> (Synchronization.py:93) [Syncronization ][DEBUG ] Started thread [], started)> (thread: -1261671536) (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) [Syncronization ][DEBUG ] Syncworker state 2 (Synchronization.py:648) [Syncronization ][INFO ] Synchronizing Wrapper: twoway (UID: TestShellGetAll-X) |--> Wrapper: twoway (UID: TestShellGetAll-Y) (Synchronization.py:467) [DeltaProvider ][INFO ] Delta: Source (TestShellGetAll-X) does not implement get_changes(). Proxying... (DeltaProvider.py:25) [DeltaProvider ][DEBUG ] Delta: Got 3 items [u'1', u'2', u'3'] (DeltaProvider.py:39) [Syncronization ][INFO ] Waiting for thread to finish (Synchronization.py:124) [DeltaProvider ][DEBUG ] Delta: Expecting 3 items (DeltaProvider.py:49) [DeltaProvider ][DEBUG ] 1) -- UID:1 mtime:1983-08-16 00:00:00 hash:1391467368 (DeltaProvider.py:51) [DeltaProvider ][DEBUG ] 3) -- UID:3 mtime:1983-08-16 00:00:00 hash:1391467368 (DeltaProvider.py:51) [DeltaProvider ][DEBUG ] 2) -- UID:2 mtime:1983-08-16 00:00:00 hash:1391467368 (DeltaProvider.py:51) [datatypes.DataType ][DEBUG ] Getting Rid for 1 (DataType.py:158) [datatypes.DataType ][DEBUG ] Getting Rid for 2 (DataType.py:158) [datatypes.DataType ][DEBUG ] Getting Rid for 3 (DataType.py:158) [Syncronization ][DEBUG ] TestShellGetAll-X Changes: New 0 items [] (Synchronization.py:241) [Syncronization ][DEBUG ] TestShellGetAll-X Changes: Modified 0 items [] (Synchronization.py:242) [Syncronization ][DEBUG ] TestShellGetAll-X Changes: Deleted 0 items [] (Synchronization.py:243) [Syncronization ][DEBUG ] Syncworker state 3 (Synchronization.py:648)