Drive: investigate on randomly failing test_changes_root_registrations

22:24:59 ======================================================================
22:24:59 FAIL: test_changes_root_registrations (nxdrive.tests.test_integration_remote_changes.TestIntegrationRemoteChanges)
22:24:59 ----------------------------------------------------------------------
22:24:59 Traceback (most recent call last):
22:24:59   File "/Users/hudson/tmp/workspace/IT-nuxeo-drive-master-osx/nuxeo-drive-client/nxdrive/tests/test_integration_remote_changes.py", line 66, in test_changes_root_registrations
22:24:59     self.assertEquals(len(summary['fileSystemChanges']), 1)
22:24:59 AssertionError: 0 != 1
22:24:59 -------------------- >> begin captured logging << --------------------
22:24:59 nxdrive.controller: DEBUG: nxdrive installed in '/Users/hudson/tmp/workspace/IT-nuxeo-drive-master-osx/nuxeo-drive-client/nxdrive'
22:24:59 nxdrive.controller: DEBUG: nxdrive configured in '/private/var/folders/z2/0qcpf6l97tv6_pz56r7h67gm0000zd/T/tmp6dhFxZ-nxdrive-tests-user-1/nuxeo-drive-conf'
22:24:59 alembic.migration: INFO: Context impl SQLiteImpl.
22:24:59 alembic.migration: INFO: Will assume non-transactional DDL.
22:24:59 nxdrive.migration: DEBUG: Checking if SQLite database migration is needed.
22:24:59 nxdrive.migration: DEBUG: Current Alembic revision: None
22:24:59 nxdrive.migration: DEBUG: Head Alembic revision: None
22:24:59 nxdrive.migration: DEBUG: No migration to process as current Alembic revision in SQLite database is already the head revision.
22:24:59 nxdrive.model: DEBUG: Set client version to 1.3-dev
22:24:59 nxdrive.controller: DEBUG: nxdrive installed in '/Users/hudson/tmp/workspace/IT-nuxeo-drive-master-osx/nuxeo-drive-client/nxdrive'
22:24:59 nxdrive.controller: DEBUG: nxdrive configured in '/private/var/folders/z2/0qcpf6l97tv6_pz56r7h67gm0000zd/T/tmpC0YDQg-nxdrive-tests-user-2/nuxeo-drive-conf'
22:24:59 alembic.migration: INFO: Context impl SQLiteImpl.
22:24:59 alembic.migration: INFO: Will assume non-transactional DDL.
22:24:59 nxdrive.migration: DEBUG: Checking if SQLite database migration is needed.
22:24:59 nxdrive.migration: DEBUG: Current Alembic revision: None
22:24:59 nxdrive.migration: DEBUG: Head Alembic revision: None
22:24:59 nxdrive.migration: DEBUG: No migration to process as current Alembic revision in SQLite database is already the head revision.
22:24:59 nxdrive.model: DEBUG: Set client version to 1.3-dev
22:24:59 nxdrive.client.base_automation_client: TRACE: Calling http://localhost:8080/nuxeo/site/automation/ with headers {'X-Client-Version': '1.3-dev', 'X-Device-Id': u'nxdrive-test-administrator-device', 'User-Agent': 'Nuxeo Drive/1.3-dev', 'X-User-Id': 'Administrator', 'Cache-Control': 'no-cache', 'X-Application-Name': 'Nuxeo Drive', 'Authorization': 'Basic QWRtaW5pc3RyYXRvcjpBZG1pbmlzdHJhdG9y'} and cookies []
22:24:59 nxdrive.client.base_automation_client: TRACE: Dumping JSON structure: {'params': {'value': u'/'}}
22:24:59 nxdrive.client.base_automation_client: TRACE: Calling http://localhost:8080/nuxeo/site/automation/Document.Fetch with headers {'X-Client-Version': '1.3-dev', 'X-NXDocumentProperties': '*', 'X-Device-Id': u'nxdrive-test-administrator-device', 'Accept': 'application/json+nxentity, */*', 'User-Agent': 'Nuxeo Drive/1.3-dev', 'X-User-Id': 'Administrator', 'Cache-Control': 'no-cache', 'Content-Type': 'application/json+nxrequest', 'X-Application-Name': 'Nuxeo Drive', 'Authorization': 'Basic QWRtaW5pc3RyYXRvcjpBZG1pbmlzdHJhdG9y'}, cookies [] and JSON payload '{"params": {"value": "/"}}'
22:24:59 nxdrive.client.base_automation_client: TRACE: Response for 'http://localhost:8080/nuxeo/site/automation/Document.Fetch' with cookies [] and JSON payload: '{"entity-type":"document","repository":"default","uid":"8822d8a0-022d-4743-9e02-8196cd721119","path":"/","type":"Root","state":null,"versionLabel":"","isCheckedOut":true,"title":"8822d8a0-022d-4743-9e02-8196cd721119","properties":{"dc:creator":null,"dc:source":null,"dc:contributors":[],"dc:nature":null,"dc:created":null,"dc:description":null,"dc:rights":null,"dc:subjects":[],"dc:publisher":null,"dc:valid":null,"dc:format":null,"dc:issued":null,"dc:modified":null,"dc:expired":null,"dc:coverage":null,"dc:language":null,"dc:title":null,"dc:lastContributor":null,"common:icon":null,"common:icon-expanded":null,"common:size":null},"facets":["Folderish"],"changeToken":null,"contextParameters":{}}'
22:24:59 nxdrive.client.base_automation_client: TRACE: Dumping JSON structure: {'params': {'userNames': 'user_1, user_2', 'permission': 'ReadWrite'}}
22:24:59 nxdrive.client.base_automation_client: TRACE: Calling http://localhost:8080/nuxeo/site/automation/NuxeoDrive.SetupIntegrationTests with headers {'X-Client-Version': '1.3-dev', 'X-NXDocumentProperties': '*', 'X-Device-Id': u'nxdrive-test-administrator-device', 'Accept': 'application/json+nxentity, */*', 'User-Agent': 'Nuxeo Drive/1.3-dev', 'X-User-Id': 'Administrator', 'Cache-Control': 'no-cache', 'Content-Type': 'application/json+nxrequest', 'X-Application-Name': 'Nuxeo Drive', 'Authorization': 'Basic QWRtaW5pc3RyYXRvcjpBZG1pbmlzdHJhdG9y'}, cookies [] and JSON payload '{"params": {"userNames": "user_1, user_2", "permission": "ReadWrite"}}'
22:24:59 nxdrive.client.base_automation_client: TRACE: Response for 'http://localhost:8080/nuxeo/site/automation/NuxeoDrive.SetupIntegrationTests' with cookies [] and content-type: 'text/plain'
22:24:59 nxdrive.client.base_automation_client: TRACE: Dumping JSON structure: {'params': {'value': u'/default-domain/workspaces/nuxeo-drive-test-workspace'}}
22:24:59 nxdrive.client.base_automation_client: TRACE: Calling http://localhost:8080/nuxeo/site/automation/Document.Fetch with headers {'X-Client-Version': '1.3-dev', 'X-NXDocumentProperties': '*', 'X-Device-Id': u'nxdrive-test-administrator-device', 'Accept': 'application/json+nxentity, */*', 'User-Agent': 'Nuxeo Drive/1.3-dev', 'X-User-Id': 'Administrator', 'Cache-Control': 'no-cache', 'Content-Type': 'application/json+nxrequest', 'X-Application-Name': 'Nuxeo Drive', 'Authorization': 'Basic QWRtaW5pc3RyYXRvcjpBZG1pbmlzdHJhdG9y'}, cookies [] and JSON payload '{"params": {"value": "/default-domain/workspaces/nuxeo-drive-test-workspace"}}'
22:24:59 nxdrive.client.base_automation_client: TRACE: Response for 'http://localhost:8080/nuxeo/site/automation/Document.Fetch' with cookies [] and JSON payload: '{"entity-type":"document","repository":"default","uid":"45609c50-3d48-4a45-bae0-e81e248d735f","path":"/default-domain/workspaces/nuxeo-drive-test-workspace","type":"Workspace","state":"project","versionLabel":"","isCheckedOut":true,"title":"Nuxeo Drive Test Workspace","lastModified":"2014-02-12T21:20:51.53Z","properties":{"dc:creator":"Administrator","dc:source":null,"dc:contributors":["Administrator"],"dc:nature":null,"dc:created":"2014-02-12T21:20:51.53Z","dc:description":null,"dc:rights":null,"dc:subjects":[],"dc:publisher":null,"dc:valid":null,"dc:format":null,"dc:issued":null,"dc:modified":"2014-02-12T21:20:51.53Z","dc:expired":null,"dc:coverage":null,"dc:language":null,"dc:title":"Nuxeo Drive Test Workspace","dc:lastContributor":"Administrator","files:files":[],"common:icon":"/icons/workspace.gif","common:icon-expanded":null,"common:size":null,"file:content":null,"file:filename":null,"webc:useCaptcha":null,"webc:welcomeText":null,"webc:welcomeMedia":null,"webc:theme":"sites","webc:isWebContainer":null,"webc:baseline":null,"webc:template":null,"webc:logo":null,"webc:themePerspective":null,"webc:themePage":"workspace","webc:name":null,"webc:moderationType":"aposteriori","webc:url":null,"webc:email":null,"publish:sections":[]},"facets":["SuperSpace","Folderish"],"changeToken":"1392240051533","contextParameters":{}}'
22:24:59 nxdrive.client.base_automation_client: TRACE: Calling http://localhost:8080/nuxeo/site/automation/ with headers {'X-Client-Version': '1.3-dev', 'X-Device-Id': u'nxdrive-test-device-1', 'User-Agent': 'Nuxeo Drive/1.3-dev', 'X-User-Id': u'nuxeoDriveTestUser_user_1', 'Cache-Control': 'no-cache', 'X-Application-Name': 'Nuxeo Drive', 'Authorization': 'Basic bnV4ZW9Ecml2ZVRlc3RVc2VyX3VzZXJfMTo0NzljYWY='} and cookies []
22:24:59 nxdrive.client.base_automation_client: TRACE: Dumping JSON structure: {'params': {'value': u'45609c50-3d48-4a45-bae0-e81e248d735f'}}
22:24:59 nxdrive.client.base_automation_client: TRACE: Calling http://localhost:8080/nuxeo/site/automation/Document.Fetch with headers {'X-Client-Version': '1.3-dev', 'X-NXDocumentProperties': '*', 'X-Device-Id': u'nxdrive-test-device-1', 'Accept': 'application/json+nxentity, */*', 'User-Agent': 'Nuxeo Drive/1.3-dev', 'X-User-Id': u'nuxeoDriveTestUser_user_1', 'Cache-Control': 'no-cache', 'Content-Type': 'application/json+nxrequest', 'X-Application-Name': 'Nuxeo Drive', 'Authorization': 'Basic bnV4ZW9Ecml2ZVRlc3RVc2VyX3VzZXJfMTo0NzljYWY='}, cookies [] and JSON payload '{"params": {"value": "45609c50-3d48-4a45-bae0-e81e248d735f"}}'
22:24:59 nxdrive.client.base_automation_client: TRACE: Response for 'http://localhost:8080/nuxeo/site/automation/Document.Fetch' with cookies [] and JSON payload: '{"entity-type":"document","repository":"default","uid":"45609c50-3d48-4a45-bae0-e81e248d735f","path":"/default-domain/workspaces/nuxeo-drive-test-workspace","type":"Workspace","state":"project","versionLabel":"","isCheckedOut":true,"title":"Nuxeo Drive Test Workspace","lastModified":"2014-02-12T21:20:51.53Z","properties":{"dc:creator":"Administrator","dc:source":null,"dc:contributors":["Administrator"],"dc:nature":null,"dc:created":"2014-02-12T21:20:51.53Z","dc:description":null,"dc:rights":null,"dc:subjects":[],"dc:publisher":null,"dc:valid":null,"dc:format":null,"dc:issued":null,"dc:modified":"2014-02-12T21:20:51.53Z","dc:expired":null,"dc:coverage":null,"dc:language":null,"dc:title":"Nuxeo Drive Test Workspace","dc:lastContributor":"Administrator","files:files":[],"common:icon":"/icons/workspace.gif","common:icon-expanded":null,"common:size":null,"file:content":null,"file:filename":null,"webc:useCaptcha":null,"webc:welcomeText":null,"webc:welcomeMedia":null,"webc:theme":"sites","webc:isWebContainer":null,"webc:baseline":null,"webc:template":null,"webc:logo":null,"webc:themePerspective":null,"webc:themePage":"workspace","webc:name":null,"webc:moderationType":"aposteriori","webc:url":null,"webc:email":null,"publish:sections":[]},"facets":["SuperSpace","Folderish"],"changeToken":"1392240051533","contextParameters":{}}'
22:24:59 nxdrive.client.base_automation_client: TRACE: Calling http://localhost:8080/nuxeo/site/automation/ with headers {'X-Client-Version': '1.3-dev', 'X-Device-Id': u'nxdrive-test-device-2', 'User-Agent': 'Nuxeo Drive/1.3-dev', 'X-User-Id': u'nuxeoDriveTestUser_user_2', 'Cache-Control': 'no-cache', 'X-Application-Name': 'Nuxeo Drive', 'Authorization': 'Basic bnV4ZW9Ecml2ZVRlc3RVc2VyX3VzZXJfMjozMDZiNTc='} and cookies []
22:24:59 nxdrive.client.base_automation_client: TRACE: Dumping JSON structure: {'params': {'value': u'45609c50-3d48-4a45-bae0-e81e248d735f'}}
22:24:59 nxdrive.client.base_automation_client: TRACE: Calling http://localhost:8080/nuxeo/site/automation/Document.Fetch with headers {'X-Client-Version': '1.3-dev', 'X-NXDocumentProperties': '*', 'X-Device-Id': u'nxdrive-test-device-2', 'Accept': 'application/json+nxentity, */*', 'User-Agent': 'Nuxeo Drive/1.3-dev', 'X-User-Id': u'nuxeoDriveTestUser_user_2', 'Cache-Control': 'no-cache', 'Content-Type': 'application/json+nxrequest', 'X-Application-Name': 'Nuxeo Drive', 'Authorization': 'Basic bnV4ZW9Ecml2ZVRlc3RVc2VyX3VzZXJfMjozMDZiNTc='}, cookies [] and JSON payload '{"params": {"value": "45609c50-3d48-4a45-bae0-e81e248d735f"}}'
22:24:59 nxdrive.client.base_automation_client: TRACE: Response for 'http://localhost:8080/nuxeo/site/automation/Document.Fetch' with cookies [] and JSON payload: '{"entity-type":"document","repository":"default","uid":"45609c50-3d48-4a45-bae0-e81e248d735f","path":"/default-domain/workspaces/nuxeo-drive-test-workspace","type":"Workspace","state":"project","versionLabel":"","isCheckedOut":true,"title":"Nuxeo Drive Test Workspace","lastModified":"2014-02-12T21:20:51.53Z","properties":{"dc:creator":"Administrator","dc:source":null,"dc:contributors":["Administrator"],"dc:nature":null,"dc:created":"2014-02-12T21:20:51.53Z","dc:description":null,"dc:rights":null,"dc:subjects":[],"dc:publisher":null,"dc:valid":null,"dc:format":null,"dc:issued":null,"dc:modified":"2014-02-12T21:20:51.53Z","dc:expired":null,"dc:coverage":null,"dc:language":null,"dc:title":"Nuxeo Drive Test Workspace","dc:lastContributor":"Administrator","files:files":[],"common:icon":"/icons/workspace.gif","common:icon-expanded":null,"common:size":null,"file:content":null,"file:filename":null,"webc:useCaptcha":null,"webc:welcomeText":null,"webc:welcomeMedia":null,"webc:theme":"sites","webc:isWebContainer":null,"webc:baseline":null,"webc:template":null,"webc:logo":null,"webc:themePerspective":null,"webc:themePage":"workspace","webc:name":null,"webc:moderationType":"aposteriori","webc:url":null,"webc:email":null,"publish:sections":[]},"facets":["SuperSpace","Folderish"],"changeToken":"1392240051533","contextParameters":{}}'
22:24:59 nxdrive.client.base_automation_client: TRACE: Calling http://localhost:8080/nuxeo/site/automation/ with headers {'X-Client-Version': '1.3-dev', 'X-Device-Id': u'nxdrive-test-device-1', 'User-Agent': 'Nuxeo Drive/1.3-dev', 'X-User-Id': u'nuxeoDriveTestUser_user_1', 'Cache-Control': 'no-cache', 'X-Application-Name': 'Nuxeo Drive', 'Authorization': 'Basic bnV4ZW9Ecml2ZVRlc3RVc2VyX3VzZXJfMTo0NzljYWY='} and cookies []
22:24:59 nxdrive.client.base_automation_client: TRACE: Calling http://localhost:8080/nuxeo/site/automation/ with headers {'X-Client-Version': '1.3-dev', 'X-Device-Id': u'nxdrive-test-device-2', 'User-Agent': 'Nuxeo Drive/1.3-dev', 'X-User-Id': u'nuxeoDriveTestUser_user_2', 'Cache-Control': 'no-cache', 'X-Application-Name': 'Nuxeo Drive', 'Authorization': 'Basic bnV4ZW9Ecml2ZVRlc3RVc2VyX3VzZXJfMjozMDZiNTc='} and cookies []
22:24:59 nxdrive.client.base_automation_client: TRACE: Dumping JSON structure: {'input': u'doc:45609c50-3d48-4a45-bae0-e81e248d735f', 'params': {'type': 'Folder', 'name': 'Folder 1', 'properties': 'dc:title=Folder 1'}}
22:24:59 nxdrive.client.base_automation_client: TRACE: Calling http://localhost:8080/nuxeo/site/automation/Document.Create with headers {'X-Client-Version': '1.3-dev', 'X-NXDocumentProperties': '*', 'X-Device-Id': u'nxdrive-test-device-1', 'Accept': 'application/json+nxentity, */*', 'User-Agent': 'Nuxeo Drive/1.3-dev', 'X-User-Id': u'nuxeoDriveTestUser_user_1', 'Cache-Control': 'no-cache', 'Content-Type': 'application/json+nxrequest', 'X-Application-Name': 'Nuxeo Drive', 'Authorization': 'Basic bnV4ZW9Ecml2ZVRlc3RVc2VyX3VzZXJfMTo0NzljYWY='}, cookies [] and JSON payload '{"input": "doc:45609c50-3d48-4a45-bae0-e81e248d735f", "params": {"type": "Folder", "name": "Folder 1", "properties": "dc:title=Folder 1"}}'
22:24:59 nxdrive.client.base_automation_client: TRACE: Response for 'http://localhost:8080/nuxeo/site/automation/Document.Create' with cookies [] and JSON payload: '{"entity-type":"document","repository":"default","uid":"080f84d1-10ef-4bc8-a632-b444a2f413ec","path":"/default-domain/workspaces/nuxeo-drive-test-workspace/Folder 1","type":"Folder","state":"project","versionLabel":"","isCheckedOut":true,"title":"Folder 1","lastModified":"2014-02-12T21:20:51.73Z","properties":{"dc:creator":"nuxeoDriveTestUser_user_1","dc:source":null,"dc:contributors":["nuxeoDriveTestUser_user_1"],"dc:nature":null,"dc:created":"2014-02-12T21:20:51.73Z","dc:description":null,"dc:rights":null,"dc:subjects":[],"dc:publisher":null,"dc:valid":null,"dc:format":null,"dc:issued":null,"dc:modified":"2014-02-12T21:20:51.73Z","dc:expired":null,"dc:coverage":null,"dc:language":null,"dc:title":"Folder 1","dc:lastContributor":"nuxeoDriveTestUser_user_1","common:icon":"/icons/folder.gif","common:icon-expanded":null,"common:size":null},"facets":["Folderish"],"changeToken":"1392240051735","contextParameters":{}}'
22:24:59 nxdrive.client.base_automation_client: TRACE: Dumping JSON structure: {'input': u'doc:45609c50-3d48-4a45-bae0-e81e248d735f', 'params': {'type': 'Folder', 'name': 'Folder 2', 'properties': 'dc:title=Folder 2'}}
22:24:59 nxdrive.client.base_automation_client: TRACE: Calling http://localhost:8080/nuxeo/site/automation/Document.Create with headers {'X-Client-Version': '1.3-dev', 'X-NXDocumentProperties': '*', 'X-Device-Id': u'nxdrive-test-device-1', 'Accept': 'application/json+nxentity, */*', 'User-Agent': 'Nuxeo Drive/1.3-dev', 'X-User-Id': u'nuxeoDriveTestUser_user_1', 'Cache-Control': 'no-cache', 'Content-Type': 'application/json+nxrequest', 'X-Application-Name': 'Nuxeo Drive', 'Authorization': 'Basic bnV4ZW9Ecml2ZVRlc3RVc2VyX3VzZXJfMTo0NzljYWY='}, cookies [] and JSON payload '{"input": "doc:45609c50-3d48-4a45-bae0-e81e248d735f", "params": {"type": "Folder", "name": "Folder 2", "properties": "dc:title=Folder 2"}}'
22:24:59 nxdrive.client.base_automation_client: TRACE: Response for 'http://localhost:8080/nuxeo/site/automation/Document.Create' with cookies [] and JSON payload: '{"entity-type":"document","repository":"default","uid":"43862bb5-f673-4a64-b9ee-0d02327abe68","path":"/default-domain/workspaces/nuxeo-drive-test-workspace/Folder 2","type":"Folder","state":"project","versionLabel":"","isCheckedOut":true,"title":"Folder 2","lastModified":"2014-02-12T21:20:51.75Z","properties":{"dc:creator":"nuxeoDriveTestUser_user_1","dc:source":null,"dc:contributors":["nuxeoDriveTestUser_user_1"],"dc:nature":null,"dc:created":"2014-02-12T21:20:51.75Z","dc:description":null,"dc:rights":null,"dc:subjects":[],"dc:publisher":null,"dc:valid":null,"dc:format":null,"dc:issued":null,"dc:modified":"2014-02-12T21:20:51.75Z","dc:expired":null,"dc:coverage":null,"dc:language":null,"dc:title":"Folder 2","dc:lastContributor":"nuxeoDriveTestUser_user_1","common:icon":"/icons/folder.gif","common:icon-expanded":null,"common:size":null},"facets":["Folderish"],"changeToken":"1392240051759","contextParameters":{}}'
22:24:59 nxdrive.client.base_automation_client: TRACE: Dumping JSON structure: {'input': u'doc:43862bb5-f673-4a64-b9ee-0d02327abe68', 'params': {'type': 'Folder', 'name': 'Folder 2.2', 'properties': 'dc:title=Folder 2.2'}}
22:24:59 nxdrive.client.base_automation_client: TRACE: Calling http://localhost:8080/nuxeo/site/automation/Document.Create with headers {'X-Client-Version': '1.3-dev', 'X-NXDocumentProperties': '*', 'X-Device-Id': u'nxdrive-test-device-1', 'Accept': 'application/json+nxentity, */*', 'User-Agent': 'Nuxeo Drive/1.3-dev', 'X-User-Id': u'nuxeoDriveTestUser_user_1', 'Cache-Control': 'no-cache', 'Content-Type': 'application/json+nxrequest', 'X-Application-Name': 'Nuxeo Drive', 'Authorization': 'Basic bnV4ZW9Ecml2ZVRlc3RVc2VyX3VzZXJfMTo0NzljYWY='}, cookies [] and JSON payload '{"input": "doc:43862bb5-f673-4a64-b9ee-0d02327abe68", "params": {"type": "Folder", "name": "Folder 2.2", "properties": "dc:title=Folder 2.2"}}'
22:24:59 nxdrive.client.base_automation_client: TRACE: Response for 'http://localhost:8080/nuxeo/site/automation/Document.Create' with cookies [] and JSON payload: '{"entity-type":"document","repository":"default","uid":"a7bfe9c9-1452-44f2-9a34-223ca5582183","path":"/default-domain/workspaces/nuxeo-drive-test-workspace/Folder 2/Folder 2.2","type":"Folder","state":"project","versionLabel":"","isCheckedOut":true,"title":"Folder 2.2","lastModified":"2014-02-12T21:20:51.77Z","properties":{"dc:creator":"nuxeoDriveTestUser_user_1","dc:source":null,"dc:contributors":["nuxeoDriveTestUser_user_1"],"dc:nature":null,"dc:created":"2014-02-12T21:20:51.77Z","dc:description":null,"dc:rights":null,"dc:subjects":[],"dc:publisher":null,"dc:valid":null,"dc:format":null,"dc:issued":null,"dc:modified":"2014-02-12T21:20:51.77Z","dc:expired":null,"dc:coverage":null,"dc:language":null,"dc:title":"Folder 2.2","dc:lastContributor":"nuxeoDriveTestUser_user_1","common:icon":"/icons/folder.gif","common:icon-expanded":null,"common:size":null},"facets":["Folderish"],"changeToken":"1392240051776","contextParameters":{}}'
22:24:59 nxdrive.client.base_automation_client: TRACE: Dumping JSON structure: {'params': {}}
22:24:59 nxdrive.client.base_automation_client: TRACE: Calling http://localhost:8080/nuxeo/site/automation/NuxeoDrive.GetChangeSummary with headers {'X-Client-Version': '1.3-dev', 'X-NXDocumentProperties': '*', 'X-Device-Id': u'nxdrive-test-device-1', 'Accept': 'application/json+nxentity, */*', 'User-Agent': 'Nuxeo Drive/1.3-dev', 'X-User-Id': u'nuxeoDriveTestUser_user_1', 'Cache-Control': 'no-cache', 'Content-Type': 'application/json+nxrequest', 'X-Application-Name': 'Nuxeo Drive', 'Authorization': 'Basic bnV4ZW9Ecml2ZVRlc3RVc2VyX3VzZXJfMTo0NzljYWY='}, cookies [] and JSON payload '{"params": {}}'
22:24:59 nxdrive.client.base_automation_client: TRACE: Response for 'http://localhost:8080/nuxeo/site/automation/NuxeoDrive.GetChangeSummary' with cookies [] and JSON payload: '{"fileSystemChanges":[],"syncDate":1392240052000,"activeSynchronizationRootDefinitions":"","hasTooManyChanges":false}'
22:24:59 nxdrive.client.base_automation_client: TRACE: Calling http://localhost:8080/nuxeo/site/automation/ with headers {'X-Client-Version': '1.3-dev', 'X-Device-Id': u'8c91363d942b11e39ff4002500d3a152', 'User-Agent': 'Nuxeo Drive/1.3-dev', 'X-User-Id': u'nuxeoDriveTestUser_user_1', 'Cache-Control': 'no-cache', 'X-Application-Name': 'Nuxeo Drive', 'Authorization': 'Basic bnV4ZW9Ecml2ZVRlc3RVc2VyX3VzZXJfMTo0NzljYWY='} and cookies []
22:24:59 nxdrive.client.base_automation_client: TRACE: Calling http://localhost:8080/nuxeo/authentication/token?applicationName=Nuxeo+Drive&deviceDescription=Mac+OSX+Desktop&revoke=false&deviceId=8c91363d942b11e39ff4002500d3a152&permission=ReadWrite with headers {'X-Client-Version': '1.3-dev', 'X-Device-Id': u'8c91363d942b11e39ff4002500d3a152', 'User-Agent': 'Nuxeo Drive/1.3-dev', 'X-User-Id': u'nuxeoDriveTestUser_user_1', 'Cache-Control': 'no-cache', 'X-Application-Name': 'Nuxeo Drive', 'Authorization': 'Basic bnV4ZW9Ecml2ZVRlc3RVc2VyX3VzZXJfMTo0NzljYWY='} and cookies []
22:24:59 nxdrive.client.base_automation_client: TRACE: Got token 'a4d9f014-9aca-47da-b7c9-6e171ca48b77' with cookies []
22:24:59 nxdrive.controller: INFO: Binding '/var/folders/z2/0qcpf6l97tv6_pz56r7h67gm0000zd/T/tmp6dhFxZ-nxdrive-tests-user-1/Nuxeo Drive' to 'http://localhost:8080/nuxeo/' with account 'nuxeoDriveTestUser_user_1'
22:24:59 nxdrive.client.base_automation_client: TRACE: Calling http://localhost:8080/nuxeo/site/automation/ with headers {'X-Authentication-Token': 'a4d9f014-9aca-47da-b7c9-6e171ca48b77', 'X-Device-Id': u'8c91363d942b11e39ff4002500d3a152', 'User-Agent': 'Nuxeo Drive/1.3-dev', 'X-User-Id': u'nuxeoDriveTestUser_user_1', 'Cache-Control': 'no-cache', 'X-Application-Name': 'Nuxeo Drive', 'X-Client-Version': '1.3-dev'} and cookies []
22:24:59 nxdrive.client.base_automation_client: TRACE: Dumping JSON structure: {'params': {}}
22:24:59 nxdrive.client.base_automation_client: TRACE: Calling http://localhost:8080/nuxeo/site/automation/NuxeoDrive.GetTopLevelFolder with headers {'X-Authentication-Token': 'a4d9f014-9aca-47da-b7c9-6e171ca48b77', 'X-NXDocumentProperties': '*', 'X-Device-Id': u'8c91363d942b11e39ff4002500d3a152', 'Accept': 'application/json+nxentity, */*', 'User-Agent': 'Nuxeo Drive/1.3-dev', 'X-User-Id': u'nuxeoDriveTestUser_user_1', 'Cache-Control': 'no-cache', 'Content-Type': 'application/json+nxrequest', 'X-Application-Name': 'Nuxeo Drive', 'X-Client-Version': '1.3-dev'}, cookies [] and JSON payload '{"params": {}}'
22:24:59 nxdrive.client.base_automation_client: TRACE: Response for 'http://localhost:8080/nuxeo/site/automation/NuxeoDrive.GetTopLevelFolder' with cookies [] and JSON payload: '{"canCreateChild":false,"name":"Nuxeo Drive","id":"org.nuxeo.drive.service.impl.DefaultTopLevelFolderItemFactory#","path":"/org.nuxeo.drive.service.impl.DefaultTopLevelFolderItemFactory#","creationDate":878,"parentId":null,"folder":true,"canDelete":false,"creator":"system","lastModificationDate":878,"canRename":false,"userName":"nuxeoDriveTestUser_user_1"}'
22:24:59 nxdrive.model: TRACE: Updated state for LastKnownState<local_folder=u'/var/folders/z2/0qcpf6l97tv6_pz56r7h67gm0000zd/T/tmp6dhFxZ-nxdrive-tests-user-1/Nuxeo Drive', local_path=u'/', remote_name=None, local_state=None, remote_state=None, pair_state='unknown'>
22:24:59 nxdrive.model: TRACE: Use last known modification time to detect updates: local DB, server = 'None', '1970-01-01 01:00:00'
22:24:59 nxdrive.model: TRACE: last_remote_updated is None for doc None, set it to 1970-01-01 01:00:00 and updated last_sync_date to 2014-02-12 22:20:52.881377
22:24:59 nxdrive.model: TRACE: Updated state for LastKnownState<local_folder=u'/var/folders/z2/0qcpf6l97tv6_pz56r7h67gm0000zd/T/tmp6dhFxZ-nxdrive-tests-user-1/Nuxeo Drive', local_path=u'/', remote_name=u'Nuxeo Drive', local_state='synchronized', remote_state='synchronized', pair_state='synchronized'>
22:24:59 nxdrive.client.base_automation_client: TRACE: Calling http://localhost:8080/nuxeo/site/automation/ with headers {'X-Authentication-Token': u'a4d9f014-9aca-47da-b7c9-6e171ca48b77', 'X-Device-Id': u'8c91363d942b11e39ff4002500d3a152', 'User-Agent': 'Nuxeo Drive/1.3-dev', 'X-User-Id': u'nuxeoDriveTestUser_user_1', 'Cache-Control': 'no-cache', 'X-Application-Name': 'Nuxeo Drive', 'X-Client-Version': '1.3-dev'} and cookies []
22:24:59 nxdrive.client.base_automation_client: TRACE: Dumping JSON structure: {'input': u'doc:080f84d1-10ef-4bc8-a632-b444a2f413ec', 'params': {'enable': True}}
22:24:59 nxdrive.client.base_automation_client: TRACE: Calling http://localhost:8080/nuxeo/site/automation/NuxeoDrive.SetSynchronization with headers {'X-Authentication-Token': u'a4d9f014-9aca-47da-b7c9-6e171ca48b77', 'X-NXDocumentProperties': '*', 'X-Device-Id': u'8c91363d942b11e39ff4002500d3a152', 'Accept': 'application/json+nxentity, */*', 'User-Agent': 'Nuxeo Drive/1.3-dev', 'X-User-Id': u'nuxeoDriveTestUser_user_1', 'Cache-Control': 'no-cache', 'Content-Type': 'application/json+nxrequest', 'X-Application-Name': 'Nuxeo Drive', 'X-Client-Version': '1.3-dev'}, cookies [] and JSON payload '{"input": "doc:080f84d1-10ef-4bc8-a632-b444a2f413ec", "params": {"enable": true}}'
22:24:59 nxdrive.client.base_automation_client: TRACE: Response for 'http://localhost:8080/nuxeo/site/automation/NuxeoDrive.SetSynchronization' with cookies [] and content-type: ''
22:24:59 nxdrive.client.base_automation_client: TRACE: Dumping JSON structure: {'params': {'lastSyncDate': 1392240052000, 'lastSyncActiveRootDefinitions': u''}}
22:24:59 nxdrive.client.base_automation_client: TRACE: Calling http://localhost:8080/nuxeo/site/automation/NuxeoDrive.GetChangeSummary with headers {'X-Client-Version': '1.3-dev', 'X-NXDocumentProperties': '*', 'X-Device-Id': u'nxdrive-test-device-1', 'Accept': 'application/json+nxentity, */*', 'User-Agent': 'Nuxeo Drive/1.3-dev', 'X-User-Id': u'nuxeoDriveTestUser_user_1', 'Cache-Control': 'no-cache', 'Content-Type': 'application/json+nxrequest', 'X-Application-Name': 'Nuxeo Drive', 'Authorization': 'Basic bnV4ZW9Ecml2ZVRlc3RVc2VyX3VzZXJfMTo0NzljYWY='}, cookies [] and JSON payload '{"params": {"lastSyncDate": 1392240052000, "lastSyncActiveRootDefinitions": ""}}'
22:24:59 nxdrive.client.base_automation_client: TRACE: Response for 'http://localhost:8080/nuxeo/site/automation/NuxeoDrive.GetChangeSummary' with cookies [] and JSON payload: '{"fileSystemChanges":[],"syncDate":1392240053000,"activeSynchronizationRootDefinitions":"default:080f84d1-10ef-4bc8-a632-b444a2f413ec","hasTooManyChanges":false}'
22:24:59 --------------------- >> end captured logging << ---------------------
22:24:59