[NXP-13394] Drive: investigate on randomly failing test_remote_rename_sync_root_folder - Nuxeo Issue Tracker

XML

Word

Printable

Details

Type: Bug
Status: Resolved
Priority: Minor
Resolution: Duplicate
Affects Version/s: None
Fix Version/s: 6.0
Component/s: Nuxeo Drive

Tags:
- QAEmergency
Backlog priority:
500

Description

For example: http://qa.nuxeo.org/jenkins/view/Drive/job/IT-nuxeo-drive-5.6.0-linux/782/console

FAIL: test_remote_rename_sync_root_folder (nxdrive.tests.test_integration_remote_move_and_rename.TestIntegrationRemoteMoveAndRename)
21:20:12 ----------------------------------------------------------------------
21:20:12 Traceback (most recent call last):
21:20:12   File "/jenkins/tmp/workspace/IT-nuxeo-drive-5.6.0-linux/nuxeo-drive-client/nxdrive/tests/test_integration_remote_move_and_rename.py", line 466, in test_remote_rename_sync_root_folder
21:20:12     self.assertEquals(ctl.synchronizer.update_synchronize_server(sb), 1)
21:20:12 AssertionError: 0 != 1
21:20:12 -------------------- >> begin captured logging << --------------------
21:20:12 nxdrive.controller: DEBUG: nxdrive installed in '/jenkins/tmp/workspace/IT-nuxeo-drive-5.6.0-linux/nuxeo-drive-client/nxdrive'
21:20:12 nxdrive.controller: DEBUG: nxdrive configured in '/tmp/tmpogT0Sd-nxdrive-tests-user-1/nuxeo-drive-conf'
21:20:12 nxdrive.model: DEBUG: Set client version to 1.2-dev
21:20:12 nxdrive.controller: DEBUG: nxdrive installed in '/jenkins/tmp/workspace/IT-nuxeo-drive-5.6.0-linux/nuxeo-drive-client/nxdrive'
21:20:12 nxdrive.controller: DEBUG: nxdrive configured in '/tmp/tmpahUFgP-nxdrive-tests-user-2/nuxeo-drive-conf'
21:20:12 nxdrive.model: DEBUG: Set client version to 1.2-dev
21:20:12 nxdrive.client.base_automation_client: TRACE: Calling http://localhost:8080/nuxeo/site/automation/ with headers {'X-Client-Version': '1.2-dev', 'X-Device-Id': u'nxdrive-test-administrator-device', 'X-User-Id': 'Administrator', 'Cache-Control': 'no-cache', 'X-Application-Name': 'Nuxeo Drive', 'Authorization': 'Basic QWRtaW5pc3RyYXRvcjpBZG1pbmlzdHJhdG9y'} and cookies []
21:20:12 nxdrive.client.base_automation_client: TRACE: Dumping JSON structure: {'params': {'value': u'/'}}
21:20:12 nxdrive.client.base_automation_client: TRACE: Calling http://localhost:8080/nuxeo/site/automation/Document.Fetch with headers {'X-Client-Version': '1.2-dev', 'X-NXDocumentProperties': '*', 'X-Device-Id': u'nxdrive-test-administrator-device', 'Accept': 'application/json+nxentity, */*', '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": "/"}}'
21:20:12 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":"9f48335d-8057-4af0-8992-fa8db6d3be4f","path":"/","type":"Root","state":null,"versionLabel":"","title":"9f48335d-8057-4af0-8992-fa8db6d3be4f","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":{}}'
21:20:12 nxdrive.client.base_automation_client: TRACE: Dumping JSON structure: {'params': {'userNames': 'user_1, user_2', 'permission': 'ReadWrite'}}
21:20:12 nxdrive.client.base_automation_client: TRACE: Calling http://localhost:8080/nuxeo/site/automation/NuxeoDrive.SetupIntegrationTests with headers {'X-Client-Version': '1.2-dev', 'X-NXDocumentProperties': '*', 'X-Device-Id': u'nxdrive-test-administrator-device', 'Accept': 'application/json+nxentity, */*', '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"}}'
21:20:12 nxdrive.client.base_automation_client: TRACE: Response for 'http://localhost:8080/nuxeo/site/automation/NuxeoDrive.SetupIntegrationTests' with cookies [] and content-type: 'text/plain'
21:20:12 nxdrive.client.base_automation_client: TRACE: Dumping JSON structure: {'params': {'value': u'/default-domain/workspaces/nuxeo-drive-test-workspace'}}
21:20:12 nxdrive.client.base_automation_client: TRACE: Calling http://localhost:8080/nuxeo/site/automation/Document.Fetch with headers {'X-Client-Version': '1.2-dev', 'X-NXDocumentProperties': '*', 'X-Device-Id': u'nxdrive-test-administrator-device', 'Accept': 'application/json+nxentity, */*', '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"}}'
21:20:12 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":"ddcea5a8-fbb7-422b-8392-fb2e5283738f","path":"/default-domain/workspaces/nuxeo-drive-test-workspace","type":"Workspace","state":"project","versionLabel":"","title":"Nuxeo Drive Test Workspace","lastModified":"2013-12-02T20:15:29.19Z","properties":{"dc:creator":"Administrator","dc:source":null,"dc:contributors":["Administrator"],"dc:nature":null,"dc:created":"2013-12-02T20:15:29.19Z","dc:description":null,"dc:rights":null,"dc:subjects":[],"dc:publisher":null,"dc:valid":null,"dc:format":null,"dc:issued":null,"dc:modified":"2013-12-02T20:15:29.19Z","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":["WebView","SuperSpace","Folderish"],"changeToken":"1386015329199","contextParameters":{}}'
21:20:12 nxdrive.client.base_automation_client: TRACE: Calling http://localhost:8080/nuxeo/site/automation/ with headers {'X-Client-Version': '1.2-dev', 'X-Device-Id': u'nxdrive-test-device-1', 'X-User-Id': u'nuxeoDriveTestUser_user_1', 'Cache-Control': 'no-cache', 'X-Application-Name': 'Nuxeo Drive', 'Authorization': 'Basic bnV4ZW9Ecml2ZVRlc3RVc2VyX3VzZXJfMTphM2I1MmQ='} and cookies []
21:20:12 nxdrive.client.base_automation_client: TRACE: Dumping JSON structure: {'params': {'value': u'ddcea5a8-fbb7-422b-8392-fb2e5283738f'}}
21:20:12 nxdrive.client.base_automation_client: TRACE: Calling http://localhost:8080/nuxeo/site/automation/Document.Fetch with headers {'X-Client-Version': '1.2-dev', 'X-NXDocumentProperties': '*', 'X-Device-Id': u'nxdrive-test-device-1', 'Accept': 'application/json+nxentity, */*', 'X-User-Id': u'nuxeoDriveTestUser_user_1', 'Cache-Control': 'no-cache', 'Content-Type': 'application/json+nxrequest', 'X-Application-Name': 'Nuxeo Drive', 'Authorization': 'Basic bnV4ZW9Ecml2ZVRlc3RVc2VyX3VzZXJfMTphM2I1MmQ='}, cookies [] and JSON payload '{"params": {"value": "ddcea5a8-fbb7-422b-8392-fb2e5283738f"}}'
21:20:12 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":"ddcea5a8-fbb7-422b-8392-fb2e5283738f","path":"/default-domain/workspaces/nuxeo-drive-test-workspace","type":"Workspace","state":"project","versionLabel":"","title":"Nuxeo Drive Test Workspace","lastModified":"2013-12-02T20:15:29.19Z","properties":{"dc:creator":"Administrator","dc:source":null,"dc:contributors":["Administrator"],"dc:nature":null,"dc:created":"2013-12-02T20:15:29.19Z","dc:description":null,"dc:rights":null,"dc:subjects":[],"dc:publisher":null,"dc:valid":null,"dc:format":null,"dc:issued":null,"dc:modified":"2013-12-02T20:15:29.19Z","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":["WebView","SuperSpace","Folderish"],"changeToken":"1386015329199","contextParameters":{}}'
21:20:12 nxdrive.client.base_automation_client: TRACE: Calling http://localhost:8080/nuxeo/site/automation/ with headers {'X-Client-Version': '1.2-dev', 'X-Device-Id': u'nxdrive-test-device-2', 'X-User-Id': u'nuxeoDriveTestUser_user_2', 'Cache-Control': 'no-cache', 'X-Application-Name': 'Nuxeo Drive', 'Authorization': 'Basic bnV4ZW9Ecml2ZVRlc3RVc2VyX3VzZXJfMjo5NzFkNzY='} and cookies []
21:20:12 nxdrive.client.base_automation_client: TRACE: Dumping JSON structure: {'params': {'value': u'ddcea5a8-fbb7-422b-8392-fb2e5283738f'}}
21:20:12 nxdrive.client.base_automation_client: TRACE: Calling http://localhost:8080/nuxeo/site/automation/Document.Fetch with headers {'X-Client-Version': '1.2-dev', 'X-NXDocumentProperties': '*', 'X-Device-Id': u'nxdrive-test-device-2', 'Accept': 'application/json+nxentity, */*', 'X-User-Id': u'nuxeoDriveTestUser_user_2', 'Cache-Control': 'no-cache', 'Content-Type': 'application/json+nxrequest', 'X-Application-Name': 'Nuxeo Drive', 'Authorization': 'Basic bnV4ZW9Ecml2ZVRlc3RVc2VyX3VzZXJfMjo5NzFkNzY='}, cookies [] and JSON payload '{"params": {"value": "ddcea5a8-fbb7-422b-8392-fb2e5283738f"}}'
21:20:12 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":"ddcea5a8-fbb7-422b-8392-fb2e5283738f","path":"/default-domain/workspaces/nuxeo-drive-test-workspace","type":"Workspace","state":"project","versionLabel":"","title":"Nuxeo Drive Test Workspace","lastModified":"2013-12-02T20:15:29.19Z","properties":{"dc:creator":"Administrator","dc:source":null,"dc:contributors":["Administrator"],"dc:nature":null,"dc:created":"2013-12-02T20:15:29.19Z","dc:description":null,"dc:rights":null,"dc:subjects":[],"dc:publisher":null,"dc:valid":null,"dc:format":null,"dc:issued":null,"dc:modified":"2013-12-02T20:15:29.19Z","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":["WebView","SuperSpace","Folderish"],"changeToken":"1386015329199","contextParameters":{}}'
21:20:12 nxdrive.client.base_automation_client: TRACE: Calling http://localhost:8080/nuxeo/site/automation/ with headers {'X-Client-Version': '1.2-dev', 'X-Device-Id': u'nxdrive-test-device-1', 'X-User-Id': u'nuxeoDriveTestUser_user_1', 'Cache-Control': 'no-cache', 'X-Application-Name': 'Nuxeo Drive', 'Authorization': 'Basic bnV4ZW9Ecml2ZVRlc3RVc2VyX3VzZXJfMTphM2I1MmQ='} and cookies []
21:20:12 nxdrive.client.base_automation_client: TRACE: Calling http://localhost:8080/nuxeo/site/automation/ with headers {'X-Client-Version': '1.2-dev', 'X-Device-Id': u'nxdrive-test-device-2', 'X-User-Id': u'nuxeoDriveTestUser_user_2', 'Cache-Control': 'no-cache', 'X-Application-Name': 'Nuxeo Drive', 'Authorization': 'Basic bnV4ZW9Ecml2ZVRlc3RVc2VyX3VzZXJfMjo5NzFkNzY='} and cookies []
21:20:12 nxdrive.client.base_automation_client: TRACE: Calling http://localhost:8080/nuxeo/site/automation/ with headers {'X-Client-Version': '1.2-dev', 'X-Device-Id': u'7c01c7825b8e11e3a2c70024e805f008', 'X-User-Id': u'nuxeoDriveTestUser_user_1', 'Cache-Control': 'no-cache', 'X-Application-Name': 'Nuxeo Drive', 'Authorization': 'Basic bnV4ZW9Ecml2ZVRlc3RVc2VyX3VzZXJfMTphM2I1MmQ='} and cookies []
21:20:12 nxdrive.client.base_automation_client: TRACE: Calling http://localhost:8080/nuxeo/authentication/token?applicationName=Nuxeo+Drive&deviceDescription=Linux+Desktop&revoke=false&deviceId=7c01c7825b8e11e3a2c70024e805f008&permission=ReadWrite with headers {'X-Client-Version': '1.2-dev', 'X-Device-Id': u'7c01c7825b8e11e3a2c70024e805f008', 'X-User-Id': u'nuxeoDriveTestUser_user_1', 'Cache-Control': 'no-cache', 'X-Application-Name': 'Nuxeo Drive', 'Authorization': 'Basic bnV4ZW9Ecml2ZVRlc3RVc2VyX3VzZXJfMTphM2I1MmQ='} and cookies []
21:20:12 nxdrive.client.base_automation_client: TRACE: Got token 'd332461b-6623-486d-8818-2f8277c15b6d' with cookies []
21:20:12 nxdrive.controller: INFO: Binding '/tmp/tmpogT0Sd-nxdrive-tests-user-1/Nuxeo Drive' to 'http://localhost:8080/nuxeo/' with account 'nuxeoDriveTestUser_user_1'
21:20:12 nxdrive.client.base_automation_client: TRACE: Calling http://localhost:8080/nuxeo/site/automation/ with headers {'X-Authentication-Token': 'd332461b-6623-486d-8818-2f8277c15b6d', 'X-Device-Id': u'7c01c7825b8e11e3a2c70024e805f008', 'X-User-Id': u'nuxeoDriveTestUser_user_1', 'Cache-Control': 'no-cache', 'X-Application-Name': 'Nuxeo Drive', 'X-Client-Version': '1.2-dev'} and cookies []
21:20:12 nxdrive.client.base_automation_client: TRACE: Dumping JSON structure: {'params': {}}
21:20:12 nxdrive.client.base_automation_client: TRACE: Calling http://localhost:8080/nuxeo/site/automation/NuxeoDrive.GetTopLevelFolder with headers {'X-Authentication-Token': 'd332461b-6623-486d-8818-2f8277c15b6d', 'X-NXDocumentProperties': '*', 'X-Device-Id': u'7c01c7825b8e11e3a2c70024e805f008', 'Accept': 'application/json+nxentity, */*', '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.2-dev'}, cookies [] and JSON payload '{"params": {}}'
21:20:12 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":521,"folder":true,"creator":"system","parentId":null,"lastModificationDate":521,"canRename":false,"canDelete":false,"userName":"nuxeoDriveTestUser_user_1"}'
21:20:12 nxdrive.model: TRACE: Updated state for LastKnownState<local_folder=u'/tmp/tmpogT0Sd-nxdrive-tests-user-1/Nuxeo Drive', local_path=u'/', remote_name=None, local_state=None, remote_state=None, pair_state='unknown'>
21:20:12 nxdrive.model: TRACE: Use last known modification time to detect updates: local DB, server = 'None', '1970-01-01 01:00:00'
21:20:12 nxdrive.model: TRACE: last_remote_updated is None for doc None, set it to 1970-01-01 01:00:00
21:20:12 nxdrive.model: TRACE: Updated state for LastKnownState<local_folder=u'/tmp/tmpogT0Sd-nxdrive-tests-user-1/Nuxeo Drive', local_path=u'/', remote_name=u'Nuxeo Drive', local_state='synchronized', remote_state='synchronized', pair_state='synchronized'>
21:20:12 nxdrive.client.base_automation_client: TRACE: Calling http://localhost:8080/nuxeo/site/automation/ with headers {'X-Authentication-Token': u'd332461b-6623-486d-8818-2f8277c15b6d', 'X-Device-Id': u'7c01c7825b8e11e3a2c70024e805f008', 'X-User-Id': u'nuxeoDriveTestUser_user_1', 'Cache-Control': 'no-cache', 'X-Application-Name': 'Nuxeo Drive', 'X-Client-Version': '1.2-dev'} and cookies []
21:20:12 nxdrive.client.base_automation_client: TRACE: Dumping JSON structure: {'input': u'doc:ddcea5a8-fbb7-422b-8392-fb2e5283738f', 'params': {'enable': True}}
21:20:12 nxdrive.client.base_automation_client: TRACE: Calling http://localhost:8080/nuxeo/site/automation/NuxeoDrive.SetSynchronization with headers {'X-Authentication-Token': u'd332461b-6623-486d-8818-2f8277c15b6d', 'X-NXDocumentProperties': '*', 'X-Device-Id': u'7c01c7825b8e11e3a2c70024e805f008', 'Accept': 'application/json+nxentity, */*', '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.2-dev'}, cookies [] and JSON payload '{"input": "doc:ddcea5a8-fbb7-422b-8392-fb2e5283738f", "params": {"enable": true}}'
21:20:12 nxdrive.client.base_automation_client: TRACE: Response for 'http://localhost:8080/nuxeo/site/automation/NuxeoDrive.SetSynchronization' with cookies [] and content-type: ''
21:20:12 nxdrive.client.base_automation_client: TRACE: Dumping JSON structure: {'params': {}}
21:20:12 nxdrive.client.base_automation_client: TRACE: Calling http://localhost:8080/nuxeo/site/automation/NuxeoDrive.GetChangeSummary with headers {'X-Authentication-Token': 'd332461b-6623-486d-8818-2f8277c15b6d', 'X-NXDocumentProperties': '*', 'X-Device-Id': u'7c01c7825b8e11e3a2c70024e805f008', 'Accept': 'application/json+nxentity, */*', '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.2-dev'}, cookies [] and JSON payload '{"params": {}}'
21:20:12 nxdrive.client.base_automation_client: TRACE: Response for 'http://localhost:8080/nuxeo/site/automation/NuxeoDrive.GetChangeSummary' with cookies [] and JSON payload: '{"fileSystemChanges":[],"syncDate":1386015329000,"activeSynchronizationRootDefinitions":"default:ddcea5a8-fbb7-422b-8392-fb2e5283738f","hasTooManyChanges":false}'
21:20:12 nxdrive.synchronizer: DEBUG: Remote full scan of /tmp/tmpogT0Sd-nxdrive-tests-user-1/Nuxeo Drive. Reasons: forced: False, too many changes: False, first pass: True
21:20:12 nxdrive.client.base_automation_client: TRACE: Dumping JSON structure: {'params': {'id': u'org.nuxeo.drive.service.impl.DefaultTopLevelFolderItemFactory#'}}
21:20:12 nxdrive.client.base_automation_client: TRACE: Calling http://localhost:8080/nuxeo/site/automation/NuxeoDrive.GetFileSystemItem with headers {'X-Authentication-Token': 'd332461b-6623-486d-8818-2f8277c15b6d', 'X-NXDocumentProperties': '*', 'X-Device-Id': u'7c01c7825b8e11e3a2c70024e805f008', 'Accept': 'application/json+nxentity, */*', '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.2-dev'}, cookies [] and JSON payload '{"params": {"id": "org.nuxeo.drive.service.impl.DefaultTopLevelFolderItemFactory#"}}'
21:20:12 nxdrive.client.base_automation_client: TRACE: Response for 'http://localhost:8080/nuxeo/site/automation/NuxeoDrive.GetFileSystemItem' 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":720,"folder":true,"creator":"system","parentId":null,"lastModificationDate":720,"canRename":false,"canDelete":false,"userName":"nuxeoDriveTestUser_user_1"}'
21:20:12 nxdrive.model: TRACE: Use last known modification time to detect updates: local DB, server = '1970-01-01 01:00:00', '1970-01-01 01:00:00'
21:20:12 nxdrive.client.base_automation_client: TRACE: Dumping JSON structure: {'params': {'id': u'org.nuxeo.drive.service.impl.DefaultTopLevelFolderItemFactory#'}}
21:20:12 nxdrive.client.base_automation_client: TRACE: Calling http://localhost:8080/nuxeo/site/automation/NuxeoDrive.GetChildren with headers {'X-Authentication-Token': 'd332461b-6623-486d-8818-2f8277c15b6d', 'X-NXDocumentProperties': '*', 'X-Device-Id': u'7c01c7825b8e11e3a2c70024e805f008', 'Accept': 'application/json+nxentity, */*', '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.2-dev'}, cookies [] and JSON payload '{"params": {"id": "org.nuxeo.drive.service.impl.DefaultTopLevelFolderItemFactory#"}}'
21:20:12 nxdrive.client.base_automation_client: TRACE: Response for 'http://localhost:8080/nuxeo/site/automation/NuxeoDrive.GetChildren' with cookies [] and JSON payload: '[{"canCreateChild":true,"name":"Nuxeo Drive Test Workspace","id":"defaultSyncRootFolderItemFactory#default#ddcea5a8-fbb7-422b-8392-fb2e5283738f","path":"/org.nuxeo.drive.service.impl.DefaultTopLevelFolderItemFactory#/defaultSyncRootFolderItemFactory#default#ddcea5a8-fbb7-422b-8392-fb2e5283738f","creationDate":1386015329199,"folder":true,"creator":"Administrator","parentId":"org.nuxeo.drive.service.impl.DefaultTopLevelFolderItemFactory#","lastModificationDate":1386015329689,"canRename":true,"canDelete":true,"userName":"nuxeoDriveTestUser_user_1"}]'
21:20:12 nxdrive.model: TRACE: Selecting refs set([u'defaultSyncRootFolderItemFactory#default#ddcea5a8-fbb7-422b-8392-fb2e5283738f'])
21:20:12 nxdrive.model: TRACE: Use last known modification time to detect updates: local DB, server = 'None', '2013-12-02 21:15:29'
21:20:12 nxdrive.model: TRACE: last_remote_updated is None for doc None, set it to 2013-12-02 21:15:29
21:20:12 nxdrive.model: TRACE: Updated state for LastKnownState<local_folder=u'/tmp/tmpogT0Sd-nxdrive-tests-user-1/Nuxeo Drive', local_path=None, remote_name=u'Nuxeo Drive Test Workspace', local_state=None, remote_state=None, pair_state='unknown'>
21:20:12 nxdrive.synchronizer: TRACE: Created new pair LastKnownState<local_folder=u'Nuxeo Drive', local_path=None, remote_name=u'Nuxeo Drive Test Workspace', local_state='unknown', remote_state='unknown', pair_state='unknown'>
21:20:12 nxdrive.model: TRACE: Use last known modification time to detect updates: local DB, server = '2013-12-02 21:15:29', '2013-12-02 21:15:29'
21:20:12 nxdrive.client.base_automation_client: TRACE: Dumping JSON structure: {'params': {'id': u'defaultSyncRootFolderItemFactory#default#ddcea5a8-fbb7-422b-8392-fb2e5283738f'}}
21:20:12 nxdrive.client.base_automation_client: TRACE: Calling http://localhost:8080/nuxeo/site/automation/NuxeoDrive.GetChildren with headers {'X-Authentication-Token': 'd332461b-6623-486d-8818-2f8277c15b6d', 'X-NXDocumentProperties': '*', 'X-Device-Id': u'7c01c7825b8e11e3a2c70024e805f008', 'Accept': 'application/json+nxentity, */*', '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.2-dev'}, cookies [] and JSON payload '{"params": {"id": "defaultSyncRootFolderItemFactory#default#ddcea5a8-fbb7-422b-8392-fb2e5283738f"}}'
21:20:12 nxdrive.client.base_automation_client: TRACE: Response for 'http://localhost:8080/nuxeo/site/automation/NuxeoDrive.GetChildren' with cookies [] and JSON payload: '[]'
21:20:12 nxdrive.model: TRACE: Selecting refs set([])
21:20:12 nxdrive.model: TRACE: Selecting paths set([])
21:20:12 nxdrive.client.base_automation_client: TRACE: Dumping JSON structure: {'params': {'id': u'defaultSyncRootFolderItemFactory#default#ddcea5a8-fbb7-422b-8392-fb2e5283738f'}}
21:20:12 nxdrive.client.base_automation_client: TRACE: Calling http://localhost:8080/nuxeo/site/automation/NuxeoDrive.GetFileSystemItem with headers {'X-Authentication-Token': 'd332461b-6623-486d-8818-2f8277c15b6d', 'X-NXDocumentProperties': '*', 'X-Device-Id': u'7c01c7825b8e11e3a2c70024e805f008', 'Accept': 'application/json+nxentity, */*', '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.2-dev'}, cookies [] and JSON payload '{"params": {"id": "defaultSyncRootFolderItemFactory#default#ddcea5a8-fbb7-422b-8392-fb2e5283738f"}}'
21:20:12 nxdrive.client.base_automation_client: TRACE: Response for 'http://localhost:8080/nuxeo/site/automation/NuxeoDrive.GetFileSystemItem' with cookies [] and JSON payload: '{"canCreateChild":true,"name":"Nuxeo Drive Test Workspace","id":"defaultSyncRootFolderItemFactory#default#ddcea5a8-fbb7-422b-8392-fb2e5283738f","path":"/org.nuxeo.drive.service.impl.DefaultTopLevelFolderItemFactory#/defaultSyncRootFolderItemFactory#default#ddcea5a8-fbb7-422b-8392-fb2e5283738f","creationDate":1386015329199,"folder":true,"creator":"Administrator","parentId":"org.nuxeo.drive.service.impl.DefaultTopLevelFolderItemFactory#","lastModificationDate":1386015329689,"canRename":true,"canDelete":true,"userName":"nuxeoDriveTestUser_user_1"}'
21:20:12 nxdrive.model: TRACE: Use last known modification time to detect updates: local DB, server = '2013-12-02 21:15:29', '2013-12-02 21:15:29'
21:20:12 nxdrive.model: TRACE: Updated state for LastKnownState<local_folder=u'/tmp/tmpogT0Sd-nxdrive-tests-user-1/Nuxeo Drive', local_path=None, remote_name=u'Nuxeo Drive Test Workspace', local_state=u'unknown', remote_state='created', pair_state='remotely_created'>
21:20:12 nxdrive.synchronizer: DEBUG: Creating local folder 'Nuxeo Drive Test Workspace' in '/tmp/tmpogT0Sd-nxdrive-tests-user-1/Nuxeo Drive/'
21:20:12 nxdrive.synchronizer: DEBUG: Remote recursive scan of the content of Nuxeo Drive Test Workspace
21:20:12 nxdrive.model: TRACE: Use last known modification time to detect updates: local DB, server = '2013-12-02 21:15:29', '2013-12-02 21:15:29'
21:20:12 nxdrive.client.base_automation_client: TRACE: Dumping JSON structure: {'params': {'id': u'defaultSyncRootFolderItemFactory#default#ddcea5a8-fbb7-422b-8392-fb2e5283738f'}}
21:20:12 nxdrive.client.base_automation_client: TRACE: Calling http://localhost:8080/nuxeo/site/automation/NuxeoDrive.GetChildren with headers {'X-Authentication-Token': 'd332461b-6623-486d-8818-2f8277c15b6d', 'X-NXDocumentProperties': '*', 'X-Device-Id': u'7c01c7825b8e11e3a2c70024e805f008', 'Accept': 'application/json+nxentity, */*', '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.2-dev'}, cookies [] and JSON payload '{"params": {"id": "defaultSyncRootFolderItemFactory#default#ddcea5a8-fbb7-422b-8392-fb2e5283738f"}}'
21:20:12 nxdrive.client.base_automation_client: TRACE: Response for 'http://localhost:8080/nuxeo/site/automation/NuxeoDrive.GetChildren' with cookies [] and JSON payload: '[]'
21:20:12 nxdrive.model: TRACE: Selecting refs set([])
21:20:12 nxdrive.model: TRACE: Updated state for LastKnownState<local_folder=u'/tmp/tmpogT0Sd-nxdrive-tests-user-1/Nuxeo Drive', local_path=u'/Nuxeo Drive Test Workspace', remote_name=u'Nuxeo Drive Test Workspace', local_state='synchronized', remote_state='synchronized', pair_state='synchronized'>
21:20:12 nxdrive.synchronizer: DEBUG: [/tmp/tmpogT0Sd-nxdrive-tests-user-1/Nuxeo Drive] - [http://localhost:8080/nuxeo/]: synchronized: 1, pending: 1, local: 0.076s, remote: 0.122s sync: 0.237s
21:20:12 nxdrive.client.base_automation_client: TRACE: Using file system block size for the streaming upload buffer: 4096 bytes
21:20:12 nxdrive.client.base_automation_client: TRACE: Calling http://localhost:8080/nuxeo/site/automation/batch/upload with headers {'Content-Length': 14, 'X-Client-Version': '1.2-dev', 'X-Device-Id': u'nxdrive-test-device-1', 'X-File-Type': 'text/plain', 'X-User-Id': u'nuxeoDriveTestUser_user_1', 'X-File-Size': 14, 'Authorization': 'Basic bnV4ZW9Ecml2ZVRlc3RVc2VyX3VzZXJfMTphM2I1MmQ=', 'Cache-Control': 'no-cache', 'X-Batch-Id': '1386015330.14_607341518', 'X-File-Idx': 0, 'Content-Type': 'application/octet-stream', 'X-Application-Name': 'Nuxeo Drive', 'X-File-Name': 'Original%20File%201.txt'} and cookies [] for file /tmp/tmpxlrUjy-nxdrive-uploads/tmp81_nqp-nxdrive-file-to-upload
21:20:12 nxdrive.client.base_automation_client: TRACE: Response for 'http://localhost:8080/nuxeo/site/automation/batch/upload' with cookies [] and JSON payload: '{"uploaded":"true","batchId":"1386015330.14_607341518"}'
21:20:12 nxdrive.client.base_automation_client: TRACE: Dumping JSON structure: {'params': {'batchId': '1386015330.14_607341518', 'operationId': 'NuxeoDrive.CreateFile', 'fileIdx': '0', 'parentId': u'defaultSyncRootFolderItemFactory#default#ddcea5a8-fbb7-422b-8392-fb2e5283738f'}}
21:20:12 nxdrive.client.base_automation_client: TRACE: Calling http://localhost:8080/nuxeo/site/automation/batch/execute with headers {'X-Client-Version': '1.2-dev', 'X-NXDocumentProperties': '*', 'X-Device-Id': u'nxdrive-test-device-1', 'Accept': 'application/json+nxentity, */*', 'X-User-Id': u'nuxeoDriveTestUser_user_1', 'Cache-Control': 'no-cache', 'Content-Type': 'application/json+nxrequest', 'X-Application-Name': 'Nuxeo Drive', 'Authorization': 'Basic bnV4ZW9Ecml2ZVRlc3RVc2VyX3VzZXJfMTphM2I1MmQ='}, cookies [] and JSON payload '{"params": {"batchId": "1386015330.14_607341518", "operationId": "NuxeoDrive.CreateFile", "fileIdx": "0", "parentId": "defaultSyncRootFolderItemFactory#default#ddcea5a8-fbb7-422b-8392-fb2e5283738f"}}'
21:20:12 nxdrive.client.base_automation_client: TRACE: Response for 'http://localhost:8080/nuxeo/site/automation/batch/execute' with cookies [] and JSON payload: '{"digest":"f3b09f7a82f6ac8c6077a1ca1d3778d8","digestAlgorithm":"md5","downloadURL":"nxbigfile/default/e9c529cb-9ac7-4893-8573-53633637f546/blobholder:0/Original%20File%201.txt","canUpdate":true,"name":"Original File 1.txt","id":"defaultFileSystemItemFactory#default#e9c529cb-9ac7-4893-8573-53633637f546","path":"/org.nuxeo.drive.service.impl.DefaultTopLevelFolderItemFactory#/defaultSyncRootFolderItemFactory#default#ddcea5a8-fbb7-422b-8392-fb2e5283738f/defaultFileSystemItemFactory#default#e9c529cb-9ac7-4893-8573-53633637f546","creationDate":1386015330153,"folder":false,"creator":"nuxeoDriveTestUser_user_1","parentId":"defaultSyncRootFolderItemFactory#default#ddcea5a8-fbb7-422b-8392-fb2e5283738f","lastModificationDate":1386015330153,"canRename":true,"canDelete":true,"userName":"nuxeoDriveTestUser_user_1"}'
21:20:12 nxdrive.client.base_automation_client: TRACE: Using file system block size for the streaming upload buffer: 4096 bytes
21:20:12 nxdrive.client.base_automation_client: TRACE: Calling http://localhost:8080/nuxeo/site/automation/batch/upload with headers {'Content-Length': 14, 'X-Client-Version': '1.2-dev', 'X-Device-Id': u'nxdrive-test-device-1', 'X-File-Type': 'text/plain', 'X-User-Id': u'nuxeoDriveTestUser_user_1', 'X-File-Size': 14, 'Authorization': 'Basic bnV4ZW9Ecml2ZVRlc3RVc2VyX3VzZXJfMTphM2I1MmQ=', 'Cache-Control': 'no-cache', 'X-Batch-Id': '1386015330.17_275621383', 'X-File-Idx': 0, 'Content-Type': 'application/octet-stream', 'X-Application-Name': 'Nuxeo Drive', 'X-File-Name': 'Original%20File%202.txt'} and cookies [] for file /tmp/tmpxlrUjy-nxdrive-uploads/tmp6xtlx5-nxdrive-file-to-upload
21:20:12 nxdrive.client.base_automation_client: TRACE: Response for 'http://localhost:8080/nuxeo/site/automation/batch/upload' with cookies [] and JSON payload: '{"uploaded":"true","batchId":"1386015330.17_275621383"}'
21:20:12 nxdrive.client.base_automation_client: TRACE: Dumping JSON structure: {'params': {'batchId': '1386015330.17_275621383', 'operationId': 'NuxeoDrive.CreateFile', 'fileIdx': '0', 'parentId': u'defaultSyncRootFolderItemFactory#default#ddcea5a8-fbb7-422b-8392-fb2e5283738f'}}
21:20:12 nxdrive.client.base_automation_client: TRACE: Calling http://localhost:8080/nuxeo/site/automation/batch/execute with headers {'X-Client-Version': '1.2-dev', 'X-NXDocumentProperties': '*', 'X-Device-Id': u'nxdrive-test-device-1', 'Accept': 'application/json+nxentity, */*', 'X-User-Id': u'nuxeoDriveTestUser_user_1', 'Cache-Control': 'no-cache', 'Content-Type': 'application/json+nxrequest', 'X-Application-Name': 'Nuxeo Drive', 'Authorization': 'Basic bnV4ZW9Ecml2ZVRlc3RVc2VyX3VzZXJfMTphM2I1MmQ='}, cookies [] and JSON payload '{"params": {"batchId": "1386015330.17_275621383", "operationId": "NuxeoDrive.CreateFile", "fileIdx": "0", "parentId": "defaultSyncRootFolderItemFactory#default#ddcea5a8-fbb7-422b-8392-fb2e5283738f"}}'
21:20:12 nxdrive.client.base_automation_client: TRACE: Response for 'http://localhost:8080/nuxeo/site/automation/batch/execute' with cookies [] and JSON payload: '{"digest":"aa5728d0861fd3b174dc33202838ecb8","digestAlgorithm":"md5","downloadURL":"nxbigfile/default/587fa6ac-6f90-4862-b32b-149c7ce51ef9/blobholder:0/Original%20File%202.txt","canUpdate":true,"name":"Original File 2.txt","id":"defaultFileSystemItemFactory#default#587fa6ac-6f90-4862-b32b-149c7ce51ef9","path":"/org.nuxeo.drive.service.impl.DefaultTopLevelFolderItemFactory#/defaultSyncRootFolderItemFactory#default#ddcea5a8-fbb7-422b-8392-fb2e5283738f/defaultFileSystemItemFactory#default#587fa6ac-6f90-4862-b32b-149c7ce51ef9","creationDate":1386015330185,"folder":false,"creator":"nuxeoDriveTestUser_user_1","parentId":"defaultSyncRootFolderItemFactory#default#ddcea5a8-fbb7-422b-8392-fb2e5283738f","lastModificationDate":1386015330185,"canRename":true,"canDelete":true,"userName":"nuxeoDriveTestUser_user_1"}'
21:20:12 nxdrive.client.base_automation_client: TRACE: Dumping JSON structure: {'params': {'name': u'Original Folder 1', 'parentId': u'defaultSyncRootFolderItemFactory#default#ddcea5a8-fbb7-422b-8392-fb2e5283738f'}}
21:20:12 nxdrive.client.base_automation_client: TRACE: Calling http://localhost:8080/nuxeo/site/automation/NuxeoDrive.CreateFolder with headers {'X-Client-Version': '1.2-dev', 'X-NXDocumentProperties': '*', 'X-Device-Id': u'nxdrive-test-device-1', 'Accept': 'application/json+nxentity, */*', 'X-User-Id': u'nuxeoDriveTestUser_user_1', 'Cache-Control': 'no-cache', 'Content-Type': 'application/json+nxrequest', 'X-Application-Name': 'Nuxeo Drive', 'Authorization': 'Basic bnV4ZW9Ecml2ZVRlc3RVc2VyX3VzZXJfMTphM2I1MmQ='}, cookies [] and JSON payload '{"params": {"name": "Original Folder 1", "parentId": "defaultSyncRootFolderItemFactory#default#ddcea5a8-fbb7-422b-8392-fb2e5283738f"}}'
21:20:12 nxdrive.client.base_automation_client: TRACE: Response for 'http://localhost:8080/nuxeo/site/automation/NuxeoDrive.CreateFolder' with cookies [] and JSON payload: '{"canCreateChild":true,"name":"Original Folder 1","id":"defaultFileSystemItemFactory#default#2015d8c0-1f95-4884-a83f-266bb43a5db4","path":"/org.nuxeo.drive.service.impl.DefaultTopLevelFolderItemFactory#/defaultSyncRootFolderItemFactory#default#ddcea5a8-fbb7-422b-8392-fb2e5283738f/defaultFileSystemItemFactory#default#2015d8c0-1f95-4884-a83f-266bb43a5db4","creationDate":1386015330358,"folder":true,"creator":"nuxeoDriveTestUser_user_1","parentId":"defaultSyncRootFolderItemFactory#default#ddcea5a8-fbb7-422b-8392-fb2e5283738f","lastModificationDate":1386015330358,"canRename":true,"canDelete":true,"userName":"nuxeoDriveTestUser_user_1"}'
21:20:12 nxdrive.client.base_automation_client: TRACE: Dumping JSON structure: {'params': {'name': u'Sub-Folder 1.1', 'parentId': u'defaultFileSystemItemFactory#default#2015d8c0-1f95-4884-a83f-266bb43a5db4'}}
21:20:12 nxdrive.client.base_automation_client: TRACE: Calling http://localhost:8080/nuxeo/site/automation/NuxeoDrive.CreateFolder with headers {'X-Client-Version': '1.2-dev', 'X-NXDocumentProperties': '*', 'X-Device-Id': u'nxdrive-test-device-1', 'Accept': 'application/json+nxentity, */*', 'X-User-Id': u'nuxeoDriveTestUser_user_1', 'Cache-Control': 'no-cache', 'Content-Type': 'application/json+nxrequest', 'X-Application-Name': 'Nuxeo Drive', 'Authorization': 'Basic bnV4ZW9Ecml2ZVRlc3RVc2VyX3VzZXJfMTphM2I1MmQ='}, cookies [] and JSON payload '{"params": {"name": "Sub-Folder 1.1", "parentId": "defaultFileSystemItemFactory#default#2015d8c0-1f95-4884-a83f-266bb43a5db4"}}'
21:20:12 nxdrive.client.base_automation_client: TRACE: Response for 'http://localhost:8080/nuxeo/site/automation/NuxeoDrive.CreateFolder' with cookies [] and JSON payload: '{"canCreateChild":true,"name":"Sub-Folder 1.1","id":"defaultFileSystemItemFactory#default#60da6694-c58c-4769-b50a-55979bb6ced4","path":"/org.nuxeo.drive.service.impl.DefaultTopLevelFolderItemFactory#/defaultSyncRootFolderItemFactory#default#ddcea5a8-fbb7-422b-8392-fb2e5283738f/defaultFileSystemItemFactory#default#2015d8c0-1f95-4884-a83f-266bb43a5db4/defaultFileSystemItemFactory#default#60da6694-c58c-4769-b50a-55979bb6ced4","creationDate":1386015330558,"folder":true,"creator":"nuxeoDriveTestUser_user_1","parentId":"defaultFileSystemItemFactory#default#2015d8c0-1f95-4884-a83f-266bb43a5db4","lastModificationDate":1386015330558,"canRename":true,"canDelete":true,"userName":"nuxeoDriveTestUser_user_1"}'
21:20:12 nxdrive.client.base_automation_client: TRACE: Dumping JSON structure: {'params': {'name': u'Sub-Folder 1.2', 'parentId': u'defaultFileSystemItemFactory#default#2015d8c0-1f95-4884-a83f-266bb43a5db4'}}
21:20:12 nxdrive.client.base_automation_client: TRACE: Calling http://localhost:8080/nuxeo/site/automation/NuxeoDrive.CreateFolder with headers {'X-Client-Version': '1.2-dev', 'X-NXDocumentProperties': '*', 'X-Device-Id': u'nxdrive-test-device-1', 'Accept': 'application/json+nxentity, */*', 'X-User-Id': u'nuxeoDriveTestUser_user_1', 'Cache-Control': 'no-cache', 'Content-Type': 'application/json+nxrequest', 'X-Application-Name': 'Nuxeo Drive', 'Authorization': 'Basic bnV4ZW9Ecml2ZVRlc3RVc2VyX3VzZXJfMTphM2I1MmQ='}, cookies [] and JSON payload '{"params": {"name": "Sub-Folder 1.2", "parentId": "defaultFileSystemItemFactory#default#2015d8c0-1f95-4884-a83f-266bb43a5db4"}}'
21:20:12 nxdrive.client.base_automation_client: TRACE: Response for 'http://localhost:8080/nuxeo/site/automation/NuxeoDrive.CreateFolder' with cookies [] and JSON payload: '{"canCreateChild":true,"name":"Sub-Folder 1.2","id":"defaultFileSystemItemFactory#default#48da7f0e-6341-44cf-b1d7-60acde45a736","path":"/org.nuxeo.drive.service.impl.DefaultTopLevelFolderItemFactory#/defaultSyncRootFolderItemFactory#default#ddcea5a8-fbb7-422b-8392-fb2e5283738f/defaultFileSystemItemFactory#default#2015d8c0-1f95-4884-a83f-266bb43a5db4/defaultFileSystemItemFactory#default#48da7f0e-6341-44cf-b1d7-60acde45a736","creationDate":1386015330753,"folder":true,"creator":"nuxeoDriveTestUser_user_1","parentId":"defaultFileSystemItemFactory#default#2015d8c0-1f95-4884-a83f-266bb43a5db4","lastModificationDate":1386015330753,"canRename":true,"canDelete":true,"userName":"nuxeoDriveTestUser_user_1"}'
21:20:12 nxdrive.client.base_automation_client: TRACE: Using file system block size for the streaming upload buffer: 4096 bytes
21:20:12 nxdrive.client.base_automation_client: TRACE: Calling http://localhost:8080/nuxeo/site/automation/batch/upload with headers {'Content-Length': 14, 'X-Client-Version': '1.2-dev', 'X-Device-Id': u'nxdrive-test-device-1', 'X-File-Type': 'text/plain', 'X-User-Id': u'nuxeoDriveTestUser_user_1', 'X-File-Size': 14, 'Authorization': 'Basic bnV4ZW9Ecml2ZVRlc3RVc2VyX3VzZXJfMTphM2I1MmQ=', 'Cache-Control': 'no-cache', 'X-Batch-Id': '1386015330.83_237178228', 'X-File-Idx': 0, 'Content-Type': 'application/octet-stream', 'X-Application-Name': 'Nuxeo Drive', 'X-File-Name': 'Original%20File%201.1.txt'} and cookies [] for file /tmp/tmpxlrUjy-nxdrive-uploads/tmpmQ20gc-nxdrive-file-to-upload
21:20:12 nxdrive.client.base_automation_client: TRACE: Response for 'http://localhost:8080/nuxeo/site/automation/batch/upload' with cookies [] and JSON payload: '{"uploaded":"true","batchId":"1386015330.83_237178228"}'
21:20:12 nxdrive.client.base_automation_client: TRACE: Dumping JSON structure: {'params': {'batchId': '1386015330.83_237178228', 'operationId': 'NuxeoDrive.CreateFile', 'fileIdx': '0', 'parentId': u'defaultFileSystemItemFactory#default#2015d8c0-1f95-4884-a83f-266bb43a5db4'}}
21:20:12 nxdrive.client.base_automation_client: TRACE: Calling http://localhost:8080/nuxeo/site/automation/batch/execute with headers {'X-Client-Version': '1.2-dev', 'X-NXDocumentProperties': '*', 'X-Device-Id': u'nxdrive-test-device-1', 'Accept': 'application/json+nxentity, */*', 'X-User-Id': u'nuxeoDriveTestUser_user_1', 'Cache-Control': 'no-cache', 'Content-Type': 'application/json+nxrequest', 'X-Application-Name': 'Nuxeo Drive', 'Authorization': 'Basic bnV4ZW9Ecml2ZVRlc3RVc2VyX3VzZXJfMTphM2I1MmQ='}, cookies [] and JSON payload '{"params": {"batchId": "1386015330.83_237178228", "operationId": "NuxeoDrive.CreateFile", "fileIdx": "0", "parentId": "defaultFileSystemItemFactory#default#2015d8c0-1f95-4884-a83f-266bb43a5db4"}}'
21:20:12 nxdrive.client.base_automation_client: TRACE: Response for 'http://localhost:8080/nuxeo/site/automation/batch/execute' with cookies [] and JSON payload: '{"digest":"f3b09f7a82f6ac8c6077a1ca1d3778d8","digestAlgorithm":"md5","downloadURL":"nxbigfile/default/96290fa7-ddd5-4f80-aabe-0bf43ed29820/blobholder:0/Original%20File%201.1.txt","canUpdate":true,"name":"Original File 1.1.txt","id":"defaultFileSystemItemFactory#default#96290fa7-ddd5-4f80-aabe-0bf43ed29820","path":"/org.nuxeo.drive.service.impl.DefaultTopLevelFolderItemFactory#/defaultSyncRootFolderItemFactory#default#ddcea5a8-fbb7-422b-8392-fb2e5283738f/defaultFileSystemItemFactory#default#2015d8c0-1f95-4884-a83f-266bb43a5db4/defaultFileSystemItemFactory#default#96290fa7-ddd5-4f80-aabe-0bf43ed29820","creationDate":1386015330939,"folder":false,"creator":"nuxeoDriveTestUser_user_1","parentId":"defaultFileSystemItemFactory#default#2015d8c0-1f95-4884-a83f-266bb43a5db4","lastModificationDate":1386015330939,"canRename":true,"canDelete":true,"userName":"nuxeoDriveTestUser_user_1"}'
21:20:12 nxdrive.client.base_automation_client: TRACE: Dumping JSON structure: {'params': {'name': 'Original Folder 2', 'parentId': u'defaultSyncRootFolderItemFactory#default#ddcea5a8-fbb7-422b-8392-fb2e5283738f'}}
21:20:12 nxdrive.client.base_automation_client: TRACE: Calling http://localhost:8080/nuxeo/site/automation/NuxeoDrive.CreateFolder with headers {'X-Client-Version': '1.2-dev', 'X-NXDocumentProperties': '*', 'X-Device-Id': u'nxdrive-test-device-1', 'Accept': 'application/json+nxentity, */*', 'X-User-Id': u'nuxeoDriveTestUser_user_1', 'Cache-Control': 'no-cache', 'Content-Type': 'application/json+nxrequest', 'X-Application-Name': 'Nuxeo Drive', 'Authorization': 'Basic bnV4ZW9Ecml2ZVRlc3RVc2VyX3VzZXJfMTphM2I1MmQ='}, cookies [] and JSON payload '{"params": {"name": "Original Folder 2", "parentId": "defaultSyncRootFolderItemFactory#default#ddcea5a8-fbb7-422b-8392-fb2e5283738f"}}'
21:20:12 nxdrive.client.base_automation_client: TRACE: Response for 'http://localhost:8080/nuxeo/site/automation/NuxeoDrive.CreateFolder' with cookies [] and JSON payload: '{"canCreateChild":true,"name":"Original Folder 2","id":"defaultFileSystemItemFactory#default#11d930b7-112c-43f6-a05b-d5bc33a63805","path":"/org.nuxeo.drive.service.impl.DefaultTopLevelFolderItemFactory#/defaultSyncRootFolderItemFactory#default#ddcea5a8-fbb7-422b-8392-fb2e5283738f/defaultFileSystemItemFactory#default#11d930b7-112c-43f6-a05b-d5bc33a63805","creationDate":1386015331039,"folder":true,"creator":"nuxeoDriveTestUser_user_1","parentId":"defaultSyncRootFolderItemFactory#default#ddcea5a8-fbb7-422b-8392-fb2e5283738f","lastModificationDate":1386015331039,"canRename":true,"canDelete":true,"userName":"nuxeoDriveTestUser_user_1"}'
21:20:12 nxdrive.client.base_automation_client: TRACE: Using file system block size for the streaming upload buffer: 4096 bytes
21:20:12 nxdrive.client.base_automation_client: TRACE: Calling http://localhost:8080/nuxeo/site/automation/batch/upload with headers {'Content-Length': 14, 'X-Client-Version': '1.2-dev', 'X-Device-Id': u'nxdrive-test-device-1', 'X-File-Type': 'text/plain', 'X-User-Id': u'nuxeoDriveTestUser_user_1', 'X-File-Size': 14, 'Authorization': 'Basic bnV4ZW9Ecml2ZVRlc3RVc2VyX3VzZXJfMTphM2I1MmQ=', 'Cache-Control': 'no-cache', 'X-Batch-Id': '1386015331.05_270784179', 'X-File-Idx': 0, 'Content-Type': 'application/octet-stream', 'X-Application-Name': 'Nuxeo Drive', 'X-File-Name': 'Original%20File%203.txt'} and cookies [] for file /tmp/tmpxlrUjy-nxdrive-uploads/tmp2LM7EX-nxdrive-file-to-upload
21:20:12 nxdrive.client.base_automation_client: TRACE: Response for 'http://localhost:8080/nuxeo/site/automation/batch/upload' with cookies [] and JSON payload: '{"uploaded":"true","batchId":"1386015331.05_270784179"}'
21:20:12 nxdrive.client.base_automation_client: TRACE: Dumping JSON structure: {'params': {'batchId': '1386015331.05_270784179', 'operationId': 'NuxeoDrive.CreateFile', 'fileIdx': '0', 'parentId': u'defaultFileSystemItemFactory#default#11d930b7-112c-43f6-a05b-d5bc33a63805'}}
21:20:12 nxdrive.client.base_automation_client: TRACE: Calling http://localhost:8080/nuxeo/site/automation/batch/execute with headers {'X-Client-Version': '1.2-dev', 'X-NXDocumentProperties': '*', 'X-Device-Id': u'nxdrive-test-device-1', 'Accept': 'application/json+nxentity, */*', 'X-User-Id': u'nuxeoDriveTestUser_user_1', 'Cache-Control': 'no-cache', 'Content-Type': 'application/json+nxrequest', 'X-Application-Name': 'Nuxeo Drive', 'Authorization': 'Basic bnV4ZW9Ecml2ZVRlc3RVc2VyX3VzZXJfMTphM2I1MmQ='}, cookies [] and JSON payload '{"params": {"batchId": "1386015331.05_270784179", "operationId": "NuxeoDrive.CreateFile", "fileIdx": "0", "parentId": "defaultFileSystemItemFactory#default#11d930b7-112c-43f6-a05b-d5bc33a63805"}}'
21:20:12 nxdrive.client.base_automation_client: TRACE: Response for 'http://localhost:8080/nuxeo/site/automation/batch/execute' with cookies [] and JSON payload: '{"digest":"180b2cbf65a05357269e12a1d1324635","digestAlgorithm":"md5","downloadURL":"nxbigfile/default/f43104dc-5e60-4295-9444-8e3bb4955e14/blobholder:0/Original%20File%203.txt","canUpdate":true,"name":"Original File 3.txt","id":"defaultFileSystemItemFactory#default#f43104dc-5e60-4295-9444-8e3bb4955e14","path":"/org.nuxeo.drive.service.impl.DefaultTopLevelFolderItemFactory#/defaultSyncRootFolderItemFactory#default#ddcea5a8-fbb7-422b-8392-fb2e5283738f/defaultFileSystemItemFactory#default#11d930b7-112c-43f6-a05b-d5bc33a63805/defaultFileSystemItemFactory#default#f43104dc-5e60-4295-9444-8e3bb4955e14","creationDate":1386015331064,"folder":false,"creator":"nuxeoDriveTestUser_user_1","parentId":"defaultFileSystemItemFactory#default#11d930b7-112c-43f6-a05b-d5bc33a63805","lastModificationDate":1386015331064,"canRename":true,"canDelete":true,"userName":"nuxeoDriveTestUser_user_1"}'
21:20:12 nxdrive.client.base_automation_client: TRACE: Dumping JSON structure: {'params': {}}
21:20:12 nxdrive.client.base_automation_client: TRACE: Calling http://localhost:8080/nuxeo/site/automation/NuxeoDrive.WaitForAsyncCompletion with headers {'X-Client-Version': '1.2-dev', 'X-NXDocumentProperties': '*', 'X-Device-Id': u'nxdrive-test-administrator-device', 'Accept': 'application/json+nxentity, */*', '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": {}}'
21:20:12 nxdrive.client.base_automation_client: TRACE: Response for 'http://localhost:8080/nuxeo/site/automation/NuxeoDrive.WaitForAsyncCompletion' with cookies [] and content-type: ''
21:20:12 nxdrive.client.base_automation_client: TRACE: Dumping JSON structure: {'params': {'lastSyncDate': 1386015329000L, 'lastSyncActiveRootDefinitions': u'default:ddcea5a8-fbb7-422b-8392-fb2e5283738f'}}
21:20:12 nxdrive.client.base_automation_client: TRACE: Calling http://localhost:8080/nuxeo/site/automation/NuxeoDrive.GetChangeSummary with headers {'X-Authentication-Token': 'd332461b-6623-486d-8818-2f8277c15b6d', 'X-NXDocumentProperties': '*', 'X-Device-Id': u'7c01c7825b8e11e3a2c70024e805f008', 'Accept': 'application/json+nxentity, */*', '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.2-dev'}, cookies [] and JSON payload '{"params": {"lastSyncDate": 1386015329000, "lastSyncActiveRootDefinitions": "default:ddcea5a8-fbb7-422b-8392-fb2e5283738f"}}'
21:20:12 nxdrive.client.base_automation_client: TRACE: Response for 'http://localhost:8080/nuxeo/site/automation/NuxeoDrive.GetChangeSummary' with cookies [] and JSON payload: '{"fileSystemChanges":[{"eventId":"documentCreated","eventDate":1386015331064,"repositoryId":"default","fileSystemItem":{"digest":"180b2cbf65a05357269e12a1d1324635","digestAlgorithm":"md5","downloadURL":"nxbigfile/default/f43104dc-5e60-4295-9444-8e3bb4955e14/blobholder:0/Original%20File%203.txt","canUpdate":true,"name":"Original File 3.txt","id":"defaultFileSystemItemFactory#default#f43104dc-5e60-4295-9444-8e3bb4955e14","path":"/org.nuxeo.drive.service.impl.DefaultTopLevelFolderItemFactory#/defaultSyncRootFolderItemFactory#default#ddcea5a8-fbb7-422b-8392-fb2e5283738f/defaultFileSystemItemFactory#default#11d930b7-112c-43f6-a05b-d5bc33a63805/defaultFileSystemItemFactory#default#f43104dc-5e60-4295-9444-8e3bb4955e14","creationDate":1386015331064,"folder":false,"creator":"nuxeoDriveTestUser_user_1","parentId":"defaultFileSystemItemFactory#default#11d930b7-112c-43f6-a05b-d5bc33a63805","lastModificationDate":1386015331064,"canRename":true,"canDelete":true,"userName":"nuxeoDriveTestUser_user_1"},"fileSystemItemId":"defaultFileSystemItemFactory#default#f43104dc-5e60-4295-9444-8e3bb4955e14","fileSystemItemName":"Original File 3.txt","docUuid":"f43104dc-5e60-4295-9444-8e3bb4955e14"},{"eventId":"documentCreated","eventDate":1386015331039,"repositoryId":"default","fileSystemItem":{"canCreateChild":true,"name":"Original Folder 2","id":"defaultFileSystemItemFactory#default#11d930b7-112c-43f6-a05b-d5bc33a63805","path":"/org.nuxeo.drive.service.impl.DefaultTopLevelFolderItemFactory#/defaultSyncRootFolderItemFactory#default#ddcea5a8-fbb7-422b-8392-fb2e5283738f/defaultFileSystemItemFactory#default#11d930b7-112c-43f6-a05b-d5bc33a63805","creationDate":1386015331039,"folder":true,"creator":"nuxeoDriveTestUser_user_1","parentId":"defaultSyncRootFolderItemFactory#default#ddcea5a8-fbb7-422b-8392-fb2e5283738f","lastModificationDate":1386015331039,"canRename":true,"canDelete":true,"userName":"nuxeoDriveTestUser_user_1"},"fileSystemItemId":"defaultFileSystemItemFactory#default#11d930b7-112c-43f6-a05b-d5bc33a63805","fileSystemItemName":"Original Folder 2","docUuid":"11d930b7-112c-43f6-a05b-d5bc33a63805"},{"eventId":"documentCreated","eventDate":1386015330939,"repositoryId":"default","fileSystemItem":{"digest":"f3b09f7a82f6ac8c6077a1ca1d3778d8","digestAlgorithm":"md5","downloadURL":"nxbigfile/default/96290fa7-ddd5-4f80-aabe-0bf43ed29820/blobholder:0/Original%20File%201.1.txt","canUpdate":true,"name":"Original File 1.1.txt","id":"defaultFileSystemItemFactory#default#96290fa7-ddd5-4f80-aabe-0bf43ed29820","path":"/org.nuxeo.drive.service.impl.DefaultTopLevelFolderItemFactory#/defaultSyncRootFolderItemFactory#default#ddcea5a8-fbb7-422b-8392-fb2e5283738f/defaultFileSystemItemFactory#default#2015d8c0-1f95-4884-a83f-266bb43a5db4/defaultFileSystemItemFactory#default#96290fa7-ddd5-4f80-aabe-0bf43ed29820","creationDate":1386015330939,"folder":false,"creator":"nuxeoDriveTestUser_user_1","parentId":"defaultFileSystemItemFactory#default#2015d8c0-1f95-4884-a83f-266bb43a5db4","lastModificationDate":1386015330939,"canRename":true,"canDelete":true,"userName":"nuxeoDriveTestUser_user_1"},"fileSystemItemId":"defaultFileSystemItemFactory#default#96290fa7-ddd5-4f80-aabe-0bf43ed29820","fileSystemItemName":"Original File 1.1.txt","docUuid":"96290fa7-ddd5-4f80-aabe-0bf43ed29820"},{"eventId":"documentCreated","eventDate":1386015330753,"repositoryId":"default","fileSystemItem":{"canCreateChild":true,"name":"Sub-Folder 1.2","id":"defaultFileSystemItemFactory#default#48da7f0e-6341-44cf-b1d7-60acde45a736","path":"/org.nuxeo.drive.service.impl.DefaultTopLevelFolderItemFactory#/defaultSyncRootFolderItemFactory#default#ddcea5a8-fbb7-422b-8392-fb2e5283738f/defaultFileSystemItemFactory#default#2015d8c0-1f95-4884-a83f-266bb43a5db4/defaultFileSystemItemFactory#default#48da7f0e-6341-44cf-b1d7-60acde45a736","creationDate":1386015330753,"folder":true,"creator":"nuxeoDriveTestUser_user_1","parentId":"defaultFileSystemItemFactory#default#2015d8c0-1f95-4884-a83f-266bb43a5db4","lastModificationDate":1386015330753,"canRename":true,"canDelete":true,"userName":"nuxeoDriveTestUser_user_1"},"fileSystemItemId":"defaultFileSystemItemFactory#default#48da7f0e-6341-44cf-b1d7-60acde45a736","fileSystemItemName":"Sub-Folder 1.2","docUuid":"48da7f0e-6341-44cf-b1d7-60acde45a736"},{"eventId":"documentCreated","eventDate":1386015330558,"repositoryId":"default","fileSystemItem":{"canCreateChild":true,"name":"Sub-Folder 1.1","id":"defaultFileSystemItemFactory#default#60da6694-c58c-4769-b50a-55979bb6ced4","path":"/org.nuxeo.drive.service.impl.DefaultTopLevelFolderItemFactory#/defaultSyncRootFolderItemFactory#default#ddcea5a8-fbb7-422b-8392-fb2e5283738f/defaultFileSystemItemFactory#default#2015d8c0-1f95-4884-a83f-266bb43a5db4/defaultFileSystemItemFactory#default#60da6694-c58c-4769-b50a-55979bb6ced4","creationDate":1386015330558,"folder":true,"creator":"nuxeoDriveTestUser_user_1","parentId":"defaultFileSystemItemFactory#default#2015d8c0-1f95-4884-a83f-266bb43a5db4","lastModificationDate":1386015330558,"canRename":true,"canDelete":true,"userName":"nuxeoDriveTestUser_user_1"},"fileSystemItemId":"defaultFileSystemItemFactory#default#60da6694-c58c-4769-b50a-55979bb6ced4","fileSystemItemName":"Sub-Folder 1.1","docUuid":"60da6694-c58c-4769-b50a-55979bb6ced4"},{"eventId":"documentCreated","eventDate":1386015330358,"repositoryId":"default","fileSystemItem":{"canCreateChild":true,"name":"Original Folder 1","id":"defaultFileSystemItemFactory#default#2015d8c0-1f95-4884-a83f-266bb43a5db4","path":"/org.nuxeo.drive.service.impl.DefaultTopLevelFolderItemFactory#/defaultSyncRootFolderItemFactory#default#ddcea5a8-fbb7-422b-8392-fb2e5283738f/defaultFileSystemItemFactory#default#2015d8c0-1f95-4884-a83f-266bb43a5db4","creationDate":1386015330358,"folder":true,"creator":"nuxeoDriveTestUser_user_1","parentId":"defaultSyncRootFolderItemFactory#default#ddcea5a8-fbb7-422b-8392-fb2e5283738f","lastModificationDate":1386015330358,"canRename":true,"canDelete":true,"userName":"nuxeoDriveTestUser_user_1"},"fileSystemItemId":"defaultFileSystemItemFactory#default#2015d8c0-1f95-4884-a83f-266bb43a5db4","fileSystemItemName":"Original Folder 1","docUuid":"2015d8c0-1f95-4884-a83f-266bb43a5db4"},{"eventId":"documentCreated","eventDate":1386015330185,"repositoryId":"default","fileSystemItem":{"digest":"aa5728d0861fd3b174dc33202838ecb8","digestAlgorithm":"md5","downloadURL":"nxbigfile/default/587fa6ac-6f90-4862-b32b-149c7ce51ef9/blobholder:0/Original%20File%202.txt","canUpdate":true,"name":"Original File 2.txt","id":"defaultFileSystemItemFactory#default#587fa6ac-6f90-4862-b32b-149c7ce51ef9","path":"/org.nuxeo.drive.service.impl.DefaultTopLevelFolderItemFactory#/defaultSyncRootFolderItemFactory#default#ddcea5a8-fbb7-422b-8392-fb2e5283738f/defaultFileSystemItemFactory#default#587fa6ac-6f90-4862-b32b-149c7ce51ef9","creationDate":1386015330185,"folder":false,"creator":"nuxeoDriveTestUser_user_1","parentId":"defaultSyncRootFolderItemFactory#default#ddcea5a8-fbb7-422b-8392-fb2e5283738f","lastModificationDate":1386015330185,"canRename":true,"canDelete":true,"userName":"nuxeoDriveTestUser_user_1"},"fileSystemItemId":"defaultFileSystemItemFactory#default#587fa6ac-6f90-4862-b32b-149c7ce51ef9","fileSystemItemName":"Original File 2.txt","docUuid":"587fa6ac-6f90-4862-b32b-149c7ce51ef9"},{"eventId":"documentCreated","eventDate":1386015330153,"repositoryId":"default","fileSystemItem":{"digest":"f3b09f7a82f6ac8c6077a1ca1d3778d8","digestAlgorithm":"md5","downloadURL":"nxbigfile/default/e9c529cb-9ac7-4893-8573-53633637f546/blobholder:0/Original%20File%201.txt","canUpdate":true,"name":"Original File 1.txt","id":"defaultFileSystemItemFactory#default#e9c529cb-9ac7-4893-8573-53633637f546","path":"/org.nuxeo.drive.service.impl.DefaultTopLevelFolderItemFactory#/defaultSyncRootFolderItemFactory#default#ddcea5a8-fbb7-422b-8392-fb2e5283738f/defaultFileSystemItemFactory#default#e9c529cb-9ac7-4893-8573-53633637f546","creationDate":1386015330153,"folder":false,"creator":"nuxeoDriveTestUser_user_1","parentId":"defaultSyncRootFolderItemFactory#default#ddcea5a8-fbb7-422b-8392-fb2e5283738f","lastModificationDate":1386015330153,"canRename":true,"canDelete":true,"userName":"nuxeoDriveTestUser_user_1"},"fileSystemItemId":"defaultFileSystemItemFactory#default#e9c529cb-9ac7-4893-8573-53633637f546","fileSystemItemName":"Original File 1.txt","docUuid":"e9c529cb-9ac7-4893-8573-53633637f546"},{"eventId":"documentModified","eventDate":1386015329690,"repositoryId":"default","fileSystemItem":{"canCreateChild":true,"name":"Nuxeo Drive Test Workspace","id":"defaultSyncRootFolderItemFactory#default#ddcea5a8-fbb7-422b-8392-fb2e5283738f","path":"/org.nuxeo.drive.service.impl.DefaultTopLevelFolderItemFactory#/defaultSyncRootFolderItemFactory#default#ddcea5a8-fbb7-422b-8392-fb2e5283738f","creationDate":1386015329199,"folder":true,"creator":"Administrator","parentId":"org.nuxeo.drive.service.impl.DefaultTopLevelFolderItemFactory#","lastModificationDate":1386015329689,"canRename":true,"canDelete":true,"userName":"nuxeoDriveTestUser_user_1"},"fileSystemItemId":"defaultSyncRootFolderItemFactory#default#ddcea5a8-fbb7-422b-8392-fb2e5283738f","fileSystemItemName":"Nuxeo Drive Test Workspace","docUuid":"ddcea5a8-fbb7-422b-8392-fb2e5283738f"},{"eventId":"documentCreated","eventDate":1386015329199,"repositoryId":"default","fileSystemItem":{"canCreateChild":true,"name":"Nuxeo Drive Test Workspace","id":"defaultSyncRootFolderItemFactory#default#ddcea5a8-fbb7-422b-8392-fb2e5283738f","path":"/org.nuxeo.drive.service.impl.DefaultTopLevelFolderItemFactory#/defaultSyncRootFolderItemFactory#default#ddcea5a8-fbb7-422b-8392-fb2e5283738f","creationDate":1386015329199,"folder":true,"creator":"Administrator","parentId":"org.nuxeo.drive.service.impl.DefaultTopLevelFolderItemFactory#","lastModificationDate":1386015329689,"canRename":true,"canDelete":true,"userName":"nuxeoDriveTestUser_user_1"},"fileSystemItemId":"defaultSyncRootFolderItemFactory#default#ddcea5a8-fbb7-422b-8392-fb2e5283738f","fileSystemItemName":"Nuxeo Drive Test Workspace","docUuid":"ddcea5a8-fbb7-422b-8392-fb2e5283738f"}],"syncDate":1386015332000,"activeSynchronizationRootDefinitions":"default:ddcea5a8-fbb7-422b-8392-fb2e5283738f","hasTooManyChanges":false}'
21:20:12 nxdrive.synchronizer: DEBUG: 10 remote changes detected on http://localhost:8080/nuxeo/
21:20:12 nxdrive.synchronizer: WARNING: Could not match changed document to a bound local folder: RemoteFileInfo(name=u'Original File 3.txt', uid=u'defaultFileSystemItemFactory#default#f43104dc-5e60-4295-9444-8e3bb4955e14', parent_uid=u'defaultFileSystemItemFactory#default#11d930b7-112c-43f6-a05b-d5bc33a63805', path=u'/org.nuxeo.drive.service.impl.DefaultTopLevelFolderItemFactory#/defaultSyncRootFolderItemFactory#default#ddcea5a8-fbb7-422b-8392-fb2e5283738f/defaultFileSystemItemFactory#default#11d930b7-112c-43f6-a05b-d5bc33a63805/defaultFileSystemItemFactory#default#f43104dc-5e60-4295-9444-8e3bb4955e14', folderish=False, last_modification_time=datetime.datetime(2013, 12, 2, 21, 15, 31), digest=u'180b2cbf65a05357269e12a1d1324635', digest_algorithm=u'md5', download_url=u'nxbigfile/default/f43104dc-5e60-4295-9444-8e3bb4955e14/blobholder:0/Original%20File%203.txt', can_rename=True, can_delete=True, can_update=True, can_create_child=False)
21:20:12 nxdrive.model: TRACE: Use last known modification time to detect updates: local DB, server = 'None', '2013-12-02 21:15:31'
21:20:12 nxdrive.model: TRACE: last_remote_updated is None for doc None, set it to 2013-12-02 21:15:31
21:20:12 nxdrive.model: TRACE: Updated state for LastKnownState<local_folder=u'/tmp/tmpogT0Sd-nxdrive-tests-user-1/Nuxeo Drive', local_path=None, remote_name=u'Original Folder 2', local_state=None, remote_state=None, pair_state='unknown'>
21:20:12 nxdrive.synchronizer: TRACE: Created new pair LastKnownState<local_folder=u'Nuxeo Drive', local_path=None, remote_name=u'Original Folder 2', local_state='unknown', remote_state='unknown', pair_state='unknown'>
21:20:12 nxdrive.synchronizer: DEBUG: Marked doc_pair 'Original Folder 2' as remote creation
21:20:12 nxdrive.synchronizer: DEBUG: Remote recursive scan of the content of Original Folder 2
21:20:12 nxdrive.model: TRACE: Use last known modification time to detect updates: local DB, server = '2013-12-02 21:15:31', '2013-12-02 21:15:31'
21:20:12 nxdrive.client.base_automation_client: TRACE: Dumping JSON structure: {'params': {'id': u'defaultFileSystemItemFactory#default#11d930b7-112c-43f6-a05b-d5bc33a63805'}}
21:20:12 nxdrive.client.base_automation_client: TRACE: Calling http://localhost:8080/nuxeo/site/automation/NuxeoDrive.GetChildren with headers {'X-Authentication-Token': 'd332461b-6623-486d-8818-2f8277c15b6d', 'X-NXDocumentProperties': '*', 'X-Device-Id': u'7c01c7825b8e11e3a2c70024e805f008', 'Accept': 'application/json+nxentity, */*', '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.2-dev'}, cookies [] and JSON payload '{"params": {"id": "defaultFileSystemItemFactory#default#11d930b7-112c-43f6-a05b-d5bc33a63805"}}'
21:20:12 nxdrive.client.base_automation_client: TRACE: Response for 'http://localhost:8080/nuxeo/site/automation/NuxeoDrive.GetChildren' with cookies [] and JSON payload: '[{"digest":"180b2cbf65a05357269e12a1d1324635","digestAlgorithm":"md5","downloadURL":"nxbigfile/default/f43104dc-5e60-4295-9444-8e3bb4955e14/blobholder:0/Original%20File%203.txt","canUpdate":true,"name":"Original File 3.txt","id":"defaultFileSystemItemFactory#default#f43104dc-5e60-4295-9444-8e3bb4955e14","path":"/org.nuxeo.drive.service.impl.DefaultTopLevelFolderItemFactory#/defaultSyncRootFolderItemFactory#default#ddcea5a8-fbb7-422b-8392-fb2e5283738f/defaultFileSystemItemFactory#default#11d930b7-112c-43f6-a05b-d5bc33a63805/defaultFileSystemItemFactory#default#f43104dc-5e60-4295-9444-8e3bb4955e14","creationDate":1386015331064,"folder":false,"creator":"nuxeoDriveTestUser_user_1","parentId":"defaultFileSystemItemFactory#default#11d930b7-112c-43f6-a05b-d5bc33a63805","lastModificationDate":1386015331064,"canRename":true,"canDelete":true,"userName":"nuxeoDriveTestUser_user_1"}]'
21:20:12 nxdrive.model: TRACE: Selecting refs set([u'defaultFileSystemItemFactory#default#f43104dc-5e60-4295-9444-8e3bb4955e14'])
21:20:12 nxdrive.model: TRACE: Use last known modification time to detect updates: local DB, server = 'None', '2013-12-02 21:15:31'
21:20:12 nxdrive.model: TRACE: last_remote_updated is None for doc None, set it to 2013-12-02 21:15:31
21:20:12 nxdrive.model: TRACE: Updated state for LastKnownState<local_folder=u'/tmp/tmpogT0Sd-nxdrive-tests-user-1/Nuxeo Drive', local_path=None, remote_name=u'Original File 3.txt', local_state=None, remote_state=None, pair_state='unknown'>
21:20:12 nxdrive.synchronizer: TRACE: Created new pair LastKnownState<local_folder=u'Nuxeo Drive', local_path=None, remote_name=u'Original File 3.txt', local_state='unknown', remote_state='unknown', pair_state='unknown'>
21:20:12 nxdrive.model: TRACE: Use last known modification time to detect updates: local DB, server = '2013-12-02 21:15:31', '2013-12-02 21:15:31'
21:20:12 nxdrive.synchronizer: WARNING: Could not match changed document to a bound local folder: RemoteFileInfo(name=u'Original File 1.1.txt', uid=u'defaultFileSystemItemFactory#default#96290fa7-ddd5-4f80-aabe-0bf43ed29820', parent_uid=u'defaultFileSystemItemFactory#default#2015d8c0-1f95-4884-a83f-266bb43a5db4', path=u'/org.nuxeo.drive.service.impl.DefaultTopLevelFolderItemFactory#/defaultSyncRootFolderItemFactory#default#ddcea5a8-fbb7-422b-8392-fb2e5283738f/defaultFileSystemItemFactory#default#2015d8c0-1f95-4884-a83f-266bb43a5db4/defaultFileSystemItemFactory#default#96290fa7-ddd5-4f80-aabe-0bf43ed29820', folderish=False, last_modification_time=datetime.datetime(2013, 12, 2, 21, 15, 30), digest=u'f3b09f7a82f6ac8c6077a1ca1d3778d8', digest_algorithm=u'md5', download_url=u'nxbigfile/default/96290fa7-ddd5-4f80-aabe-0bf43ed29820/blobholder:0/Original%20File%201.1.txt', can_rename=True, can_delete=True, can_update=True, can_create_child=False)
21:20:12 nxdrive.synchronizer: WARNING: Could not match changed document to a bound local folder: RemoteFileInfo(name=u'Sub-Folder 1.2', uid=u'defaultFileSystemItemFactory#default#48da7f0e-6341-44cf-b1d7-60acde45a736', parent_uid=u'defaultFileSystemItemFactory#default#2015d8c0-1f95-4884-a83f-266bb43a5db4', path=u'/org.nuxeo.drive.service.impl.DefaultTopLevelFolderItemFactory#/defaultSyncRootFolderItemFactory#default#ddcea5a8-fbb7-422b-8392-fb2e5283738f/defaultFileSystemItemFactory#default#2015d8c0-1f95-4884-a83f-266bb43a5db4/defaultFileSystemItemFactory#default#48da7f0e-6341-44cf-b1d7-60acde45a736', folderish=True, last_modification_time=datetime.datetime(2013, 12, 2, 21, 15, 30), digest=None, digest_algorithm=None, download_url=None, can_rename=True, can_delete=True, can_update=False, can_create_child=True)
21:20:12 nxdrive.synchronizer: WARNING: Could not match changed document to a bound local folder: RemoteFileInfo(name=u'Sub-Folder 1.1', uid=u'defaultFileSystemItemFactory#default#60da6694-c58c-4769-b50a-55979bb6ced4', parent_uid=u'defaultFileSystemItemFactory#default#2015d8c0-1f95-4884-a83f-266bb43a5db4', path=u'/org.nuxeo.drive.service.impl.DefaultTopLevelFolderItemFactory#/defaultSyncRootFolderItemFactory#default#ddcea5a8-fbb7-422b-8392-fb2e5283738f/defaultFileSystemItemFactory#default#2015d8c0-1f95-4884-a83f-266bb43a5db4/defaultFileSystemItemFactory#default#60da6694-c58c-4769-b50a-55979bb6ced4', folderish=True, last_modification_time=datetime.datetime(2013, 12, 2, 21, 15, 30), digest=None, digest_algorithm=None, download_url=None, can_rename=True, can_delete=True, can_update=False, can_create_child=True)
21:20:12 nxdrive.model: TRACE: Use last known modification time to detect updates: local DB, server = 'None', '2013-12-02 21:15:30'
21:20:12 nxdrive.model: TRACE: last_remote_updated is None for doc None, set it to 2013-12-02 21:15:30
21:20:12 nxdrive.model: TRACE: Updated state for LastKnownState<local_folder=u'/tmp/tmpogT0Sd-nxdrive-tests-user-1/Nuxeo Drive', local_path=None, remote_name=u'Original Folder 1', local_state=None, remote_state=None, pair_state='unknown'>
21:20:12 nxdrive.synchronizer: TRACE: Created new pair LastKnownState<local_folder=u'Nuxeo Drive', local_path=None, remote_name=u'Original Folder 1', local_state='unknown', remote_state='unknown', pair_state='unknown'>
21:20:12 nxdrive.synchronizer: DEBUG: Marked doc_pair 'Original Folder 1' as remote creation
21:20:12 nxdrive.synchronizer: DEBUG: Remote recursive scan of the content of Original Folder 1
21:20:12 nxdrive.model: TRACE: Use last known modification time to detect updates: local DB, server = '2013-12-02 21:15:30', '2013-12-02 21:15:30'
21:20:12 nxdrive.client.base_automation_client: TRACE: Dumping JSON structure: {'params': {'id': u'defaultFileSystemItemFactory#default#2015d8c0-1f95-4884-a83f-266bb43a5db4'}}
21:20:12 nxdrive.client.base_automation_client: TRACE: Calling http://localhost:8080/nuxeo/site/automation/NuxeoDrive.GetChildren with headers {'X-Authentication-Token': 'd332461b-6623-486d-8818-2f8277c15b6d', 'X-NXDocumentProperties': '*', 'X-Device-Id': u'7c01c7825b8e11e3a2c70024e805f008', 'Accept': 'application/json+nxentity, */*', '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.2-dev'}, cookies [] and JSON payload '{"params": {"id": "defaultFileSystemItemFactory#default#2015d8c0-1f95-4884-a83f-266bb43a5db4"}}'
21:20:12 nxdrive.client.base_automation_client: TRACE: Response for 'http://localhost:8080/nuxeo/site/automation/NuxeoDrive.GetChildren' with cookies [] and JSON payload: '[{"canCreateChild":true,"name":"Sub-Folder 1.1","id":"defaultFileSystemItemFactory#default#60da6694-c58c-4769-b50a-55979bb6ced4","path":"/org.nuxeo.drive.service.impl.DefaultTopLevelFolderItemFactory#/defaultSyncRootFolderItemFactory#default#ddcea5a8-fbb7-422b-8392-fb2e5283738f/defaultFileSystemItemFactory#default#2015d8c0-1f95-4884-a83f-266bb43a5db4/defaultFileSystemItemFactory#default#60da6694-c58c-4769-b50a-55979bb6ced4","creationDate":1386015330558,"folder":true,"creator":"nuxeoDriveTestUser_user_1","parentId":"defaultFileSystemItemFactory#default#2015d8c0-1f95-4884-a83f-266bb43a5db4","lastModificationDate":1386015330558,"canRename":true,"canDelete":true,"userName":"nuxeoDriveTestUser_user_1"},{"canCreateChild":true,"name":"Sub-Folder 1.2","id":"defaultFileSystemItemFactory#default#48da7f0e-6341-44cf-b1d7-60acde45a736","path":"/org.nuxeo.drive.service.impl.DefaultTopLevelFolderItemFactory#/defaultSyncRootFolderItemFactory#default#ddcea5a8-fbb7-422b-8392-fb2e5283738f/defaultFileSystemItemFactory#default#2015d8c0-1f95-4884-a83f-266bb43a5db4/defaultFileSystemItemFactory#default#48da7f0e-6341-44cf-b1d7-60acde45a736","creationDate":1386015330753,"folder":true,"creator":"nuxeoDriveTestUser_user_1","parentId":"defaultFileSystemItemFactory#default#2015d8c0-1f95-4884-a83f-266bb43a5db4","lastModificationDate":1386015330753,"canRename":true,"canDelete":true,"userName":"nuxeoDriveTestUser_user_1"},{"digest":"f3b09f7a82f6ac8c6077a1ca1d3778d8","digestAlgorithm":"md5","downloadURL":"nxbigfile/default/96290fa7-ddd5-4f80-aabe-0bf43ed29820/blobholder:0/Original%20File%201.1.txt","canUpdate":true,"name":"Original File 1.1.txt","id":"defaultFileSystemItemFactory#default#96290fa7-ddd5-4f80-aabe-0bf43ed29820","path":"/org.nuxeo.drive.service.impl.DefaultTopLevelFolderItemFactory#/defaultSyncRootFolderItemFactory#default#ddcea5a8-fbb7-422b-8392-fb2e5283738f/defaultFileSystemItemFactory#default#2015d8c0-1f95-4884-a83f-266bb43a5db4/defaultFileSystemItemFactory#default#96290fa7-ddd5-4f80-aabe-0bf43ed29820","creationDate":1386015330939,"folder":false,"creator":"nuxeoDriveTestUser_user_1","parentId":"defaultFileSystemItemFactory#default#2015d8c0-1f95-4884-a83f-266bb43a5db4","lastModificationDate":1386015330939,"canRename":true,"canDelete":true,"userName":"nuxeoDriveTestUser_user_1"}]'
21:20:12 nxdrive.model: TRACE: Selecting refs set([u'defaultFileSystemItemFactory#default#96290fa7-ddd5-4f80-aabe-0bf43ed29820', u'defaultFileSystemItemFactory#default#48da7f0e-6341-44cf-b1d7-60acde45a736', u'defaultFileSystemItemFactory#default#60da6694-c58c-4769-b50a-55979bb6ced4'])
21:20:12 nxdrive.model: TRACE: Use last known modification time to detect updates: local DB, server = 'None', '2013-12-02 21:15:30'
21:20:12 nxdrive.model: TRACE: last_remote_updated is None for doc None, set it to 2013-12-02 21:15:30
21:20:12 nxdrive.model: TRACE: Updated state for LastKnownState<local_folder=u'/tmp/tmpogT0Sd-nxdrive-tests-user-1/Nuxeo Drive', local_path=None, remote_name=u'Sub-Folder 1.1', local_state=None, remote_state=None, pair_state='unknown'>
21:20:12 nxdrive.synchronizer: TRACE: Created new pair LastKnownState<local_folder=u'Nuxeo Drive', local_path=None, remote_name=u'Sub-Folder 1.1', local_state='unknown', remote_state='unknown', pair_state='unknown'>
21:20:12 nxdrive.model: TRACE: Use last known modification time to detect updates: local DB, server = '2013-12-02 21:15:30', '2013-12-02 21:15:30'
21:20:12 nxdrive.client.base_automation_client: TRACE: Dumping JSON structure: {'params': {'id': u'defaultFileSystemItemFactory#default#60da6694-c58c-4769-b50a-55979bb6ced4'}}
21:20:12 nxdrive.client.base_automation_client: TRACE: Calling http://localhost:8080/nuxeo/site/automation/NuxeoDrive.GetChildren with headers {'X-Authentication-Token': 'd332461b-6623-486d-8818-2f8277c15b6d', 'X-NXDocumentProperties': '*', 'X-Device-Id': u'7c01c7825b8e11e3a2c70024e805f008', 'Accept': 'application/json+nxentity, */*', '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.2-dev'}, cookies [] and JSON payload '{"params": {"id": "defaultFileSystemItemFactory#default#60da6694-c58c-4769-b50a-55979bb6ced4"}}'
21:20:12 nxdrive.client.base_automation_client: TRACE: Response for 'http://localhost:8080/nuxeo/site/automation/NuxeoDrive.GetChildren' with cookies [] and JSON payload: '[]'
21:20:12 nxdrive.model: TRACE: Selecting refs set([])
21:20:12 nxdrive.model: TRACE: Use last known modification time to detect updates: local DB, server = 'None', '2013-12-02 21:15:30'
21:20:12 nxdrive.model: TRACE: last_remote_updated is None for doc None, set it to 2013-12-02 21:15:30
21:20:12 nxdrive.model: TRACE: Updated state for LastKnownState<local_folder=u'/tmp/tmpogT0Sd-nxdrive-tests-user-1/Nuxeo Drive', local_path=None, remote_name=u'Sub-Folder 1.2', local_state=None, remote_state=None, pair_state='unknown'>
21:20:12 nxdrive.synchronizer: TRACE: Created new pair LastKnownState<local_folder=u'Nuxeo Drive', local_path=None, remote_name=u'Sub-Folder 1.2', local_state='unknown', remote_state='unknown', pair_state='unknown'>
21:20:12 nxdrive.model: TRACE: Use last known modification time to detect updates: local DB, server = '2013-12-02 21:15:30', '2013-12-02 21:15:30'
21:20:12 nxdrive.client.base_automation_client: TRACE: Dumping JSON structure: {'params': {'id': u'defaultFileSystemItemFactory#default#48da7f0e-6341-44cf-b1d7-60acde45a736'}}
21:20:12 nxdrive.client.base_automation_client: TRACE: Calling http://localhost:8080/nuxeo/site/automation/NuxeoDrive.GetChildren with headers {'X-Authentication-Token': 'd332461b-6623-486d-8818-2f8277c15b6d', 'X-NXDocumentProperties': '*', 'X-Device-Id': u'7c01c7825b8e11e3a2c70024e805f008', 'Accept': 'application/json+nxentity, */*', '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.2-dev'}, cookies [] and JSON payload '{"params": {"id": "defaultFileSystemItemFactory#default#48da7f0e-6341-44cf-b1d7-60acde45a736"}}'
21:20:12 nxdrive.client.base_automation_client: TRACE: Response for 'http://localhost:8080/nuxeo/site/automation/NuxeoDrive.GetChildren' with cookies [] and JSON payload: '[]'
21:20:12 nxdrive.model: TRACE: Selecting refs set([])
21:20:12 nxdrive.model: TRACE: Use last known modification time to detect updates: local DB, server = 'None', '2013-12-02 21:15:30'
21:20:12 nxdrive.model: TRACE: last_remote_updated is None for doc None, set it to 2013-12-02 21:15:30
21:20:12 nxdrive.model: TRACE: Updated state for LastKnownState<local_folder=u'/tmp/tmpogT0Sd-nxdrive-tests-user-1/Nuxeo Drive', local_path=None, remote_name=u'Original File 1.1.txt', local_state=None, remote_state=None, pair_state='unknown'>
21:20:12 nxdrive.synchronizer: TRACE: Created new pair LastKnownState<local_folder=u'Nuxeo Drive', local_path=None, remote_name=u'Original File 1.1.txt', local_state='unknown', remote_state='unknown', pair_state='unknown'>
21:20:12 nxdrive.model: TRACE: Use last known modification time to detect updates: local DB, server = '2013-12-02 21:15:30', '2013-12-02 21:15:30'
21:20:12 nxdrive.model: TRACE: Use last known modification time to detect updates: local DB, server = 'None', '2013-12-02 21:15:30'
21:20:12 nxdrive.model: TRACE: last_remote_updated is None for doc None, set it to 2013-12-02 21:15:30
21:20:12 nxdrive.model: TRACE: Updated state for LastKnownState<local_folder=u'/tmp/tmpogT0Sd-nxdrive-tests-user-1/Nuxeo Drive', local_path=None, remote_name=u'Original File 2.txt', local_state=None, remote_state=None, pair_state='unknown'>
21:20:12 nxdrive.synchronizer: TRACE: Created new pair LastKnownState<local_folder=u'Nuxeo Drive', local_path=None, remote_name=u'Original File 2.txt', local_state='unknown', remote_state='unknown', pair_state='unknown'>
21:20:12 nxdrive.synchronizer: DEBUG: Marked doc_pair 'Original File 2.txt' as remote creation
21:20:12 nxdrive.model: TRACE: Use last known modification time to detect updates: local DB, server = 'None', '2013-12-02 21:15:30'
21:20:12 nxdrive.model: TRACE: last_remote_updated is None for doc None, set it to 2013-12-02 21:15:30
21:20:12 nxdrive.model: TRACE: Updated state for LastKnownState<local_folder=u'/tmp/tmpogT0Sd-nxdrive-tests-user-1/Nuxeo Drive', local_path=None, remote_name=u'Original File 1.txt', local_state=None, remote_state=None, pair_state='unknown'>
21:20:12 nxdrive.synchronizer: TRACE: Created new pair LastKnownState<local_folder=u'Nuxeo Drive', local_path=None, remote_name=u'Original File 1.txt', local_state='unknown', remote_state='unknown', pair_state='unknown'>
21:20:12 nxdrive.synchronizer: DEBUG: Marked doc_pair 'Original File 1.txt' as remote creation
21:20:12 nxdrive.synchronizer: DEBUG: Refreshing remote state info for doc_pair 'Nuxeo Drive Test Workspace'
21:20:12 nxdrive.model: TRACE: Use last known modification time to detect updates: local DB, server = '2013-12-02 21:15:29', '2013-12-02 21:15:29'
21:20:12 nxdrive.client.base_automation_client: TRACE: Dumping JSON structure: {'params': {'id': u'defaultSyncRootFolderItemFactory#default#ddcea5a8-fbb7-422b-8392-fb2e5283738f'}}
21:20:12 nxdrive.client.base_automation_client: TRACE: Calling http://localhost:8080/nuxeo/site/automation/NuxeoDrive.GetChildren with headers {'X-Authentication-Token': 'd332461b-6623-486d-8818-2f8277c15b6d', 'X-NXDocumentProperties': '*', 'X-Device-Id': u'7c01c7825b8e11e3a2c70024e805f008', 'Accept': 'application/json+nxentity, */*', '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.2-dev'}, cookies [] and JSON payload '{"params": {"id": "defaultSyncRootFolderItemFactory#default#ddcea5a8-fbb7-422b-8392-fb2e5283738f"}}'
21:20:12 nxdrive.client.base_automation_client: TRACE: Response for 'http://localhost:8080/nuxeo/site/automation/NuxeoDrive.GetChildren' with cookies [] and JSON payload: '[{"digest":"f3b09f7a82f6ac8c6077a1ca1d3778d8","digestAlgorithm":"md5","downloadURL":"nxbigfile/default/e9c529cb-9ac7-4893-8573-53633637f546/blobholder:0/Original%20File%201.txt","canUpdate":true,"name":"Original File 1.txt","id":"defaultFileSystemItemFactory#default#e9c529cb-9ac7-4893-8573-53633637f546","path":"/org.nuxeo.drive.service.impl.DefaultTopLevelFolderItemFactory#/defaultSyncRootFolderItemFactory#default#ddcea5a8-fbb7-422b-8392-fb2e5283738f/defaultFileSystemItemFactory#default#e9c529cb-9ac7-4893-8573-53633637f546","creationDate":1386015330153,"folder":false,"creator":"nuxeoDriveTestUser_user_1","parentId":"defaultSyncRootFolderItemFactory#default#ddcea5a8-fbb7-422b-8392-fb2e5283738f","lastModificationDate":1386015330153,"canRename":true,"canDelete":true,"userName":"nuxeoDriveTestUser_user_1"},{"digest":"aa5728d0861fd3b174dc33202838ecb8","digestAlgorithm":"md5","downloadURL":"nxbigfile/default/587fa6ac-6f90-4862-b32b-149c7ce51ef9/blobholder:0/Original%20File%202.txt","canUpdate":true,"name":"Original File 2.txt","id":"defaultFileSystemItemFactory#default#587fa6ac-6f90-4862-b32b-149c7ce51ef9","path":"/org.nuxeo.drive.service.impl.DefaultTopLevelFolderItemFactory#/defaultSyncRootFolderItemFactory#default#ddcea5a8-fbb7-422b-8392-fb2e5283738f/defaultFileSystemItemFactory#default#587fa6ac-6f90-4862-b32b-149c7ce51ef9","creationDate":1386015330185,"folder":false,"creator":"nuxeoDriveTestUser_user_1","parentId":"defaultSyncRootFolderItemFactory#default#ddcea5a8-fbb7-422b-8392-fb2e5283738f","lastModificationDate":1386015330185,"canRename":true,"canDelete":true,"userName":"nuxeoDriveTestUser_user_1"},{"canCreateChild":true,"name":"Original Folder 1","id":"defaultFileSystemItemFactory#default#2015d8c0-1f95-4884-a83f-266bb43a5db4","path":"/org.nuxeo.drive.service.impl.DefaultTopLevelFolderItemFactory#/defaultSyncRootFolderItemFactory#default#ddcea5a8-fbb7-422b-8392-fb2e5283738f/defaultFileSystemItemFactory#default#2015d8c0-1f95-4884-a83f-266bb43a5db4","creationDate":1386015330358,"folder":true,"creator":"nuxeoDriveTestUser_user_1","parentId":"defaultSyncRootFolderItemFactory#default#ddcea5a8-fbb7-422b-8392-fb2e5283738f","lastModificationDate":1386015330358,"canRename":true,"canDelete":true,"userName":"nuxeoDriveTestUser_user_1"},{"canCreateChild":true,"name":"Original Folder 2","id":"defaultFileSystemItemFactory#default#11d930b7-112c-43f6-a05b-d5bc33a63805","path":"/org.nuxeo.drive.service.impl.DefaultTopLevelFolderItemFactory#/defaultSyncRootFolderItemFactory#default#ddcea5a8-fbb7-422b-8392-fb2e5283738f/defaultFileSystemItemFactory#default#11d930b7-112c-43f6-a05b-d5bc33a63805","creationDate":1386015331039,"folder":true,"creator":"nuxeoDriveTestUser_user_1","parentId":"defaultSyncRootFolderItemFactory#default#ddcea5a8-fbb7-422b-8392-fb2e5283738f","lastModificationDate":1386015331039,"canRename":true,"canDelete":true,"userName":"nuxeoDriveTestUser_user_1"}]'
21:20:12 nxdrive.model: TRACE: Selecting refs set([u'defaultFileSystemItemFactory#default#587fa6ac-6f90-4862-b32b-149c7ce51ef9', u'defaultFileSystemItemFactory#default#2015d8c0-1f95-4884-a83f-266bb43a5db4', u'defaultFileSystemItemFactory#default#11d930b7-112c-43f6-a05b-d5bc33a63805', u'defaultFileSystemItemFactory#default#e9c529cb-9ac7-4893-8573-53633637f546'])
21:20:12 nxdrive.model: TRACE: Selecting paths set([u'/Nuxeo Drive Test Workspace'])
21:20:12 nxdrive.model: TRACE: Selecting paths set([])
21:20:12 nxdrive.client.base_automation_client: TRACE: Dumping JSON structure: {'params': {'id': u'defaultFileSystemItemFactory#default#e9c529cb-9ac7-4893-8573-53633637f546'}}
21:20:12 nxdrive.client.base_automation_client: TRACE: Calling http://localhost:8080/nuxeo/site/automation/NuxeoDrive.GetFileSystemItem with headers {'X-Authentication-Token': 'd332461b-6623-486d-8818-2f8277c15b6d', 'X-NXDocumentProperties': '*', 'X-Device-Id': u'7c01c7825b8e11e3a2c70024e805f008', 'Accept': 'application/json+nxentity, */*', '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.2-dev'}, cookies [] and JSON payload '{"params": {"id": "defaultFileSystemItemFactory#default#e9c529cb-9ac7-4893-8573-53633637f546"}}'
21:20:12 nxdrive.client.base_automation_client: TRACE: Response for 'http://localhost:8080/nuxeo/site/automation/NuxeoDrive.GetFileSystemItem' with cookies [] and JSON payload: '{"digest":"f3b09f7a82f6ac8c6077a1ca1d3778d8","digestAlgorithm":"md5","downloadURL":"nxbigfile/default/e9c529cb-9ac7-4893-8573-53633637f546/blobholder:0/Original%20File%201.txt","canUpdate":true,"name":"Original File 1.txt","id":"defaultFileSystemItemFactory#default#e9c529cb-9ac7-4893-8573-53633637f546","path":"/org.nuxeo.drive.service.impl.DefaultTopLevelFolderItemFactory#/defaultSyncRootFolderItemFactory#default#ddcea5a8-fbb7-422b-8392-fb2e5283738f/defaultFileSystemItemFactory#default#e9c529cb-9ac7-4893-8573-53633637f546","creationDate":1386015330153,"folder":false,"creator":"nuxeoDriveTestUser_user_1","parentId":"defaultSyncRootFolderItemFactory#default#ddcea5a8-fbb7-422b-8392-fb2e5283738f","lastModificationDate":1386015330153,"canRename":true,"canDelete":true,"userName":"nuxeoDriveTestUser_user_1"}'
21:20:12 nxdrive.model: TRACE: Use last known modification time to detect updates: local DB, server = '2013-12-02 21:15:30', '2013-12-02 21:15:30'
21:20:12 nxdrive.model: TRACE: Updated state for LastKnownState<local_folder=u'/tmp/tmpogT0Sd-nxdrive-tests-user-1/Nuxeo Drive', local_path=None, remote_name=u'Original File 1.txt', local_state=u'unknown', remote_state='created', pair_state='remotely_created'>
21:20:12 nxdrive.synchronizer: DEBUG: Creating local file 'Original File 1.txt' in '/tmp/tmpogT0Sd-nxdrive-tests-user-1/Nuxeo Drive/Nuxeo Drive Test Workspace'
21:20:12 nxdrive.client.base_automation_client: TRACE: Dumping JSON structure: {'params': {'id': u'defaultFileSystemItemFactory#default#e9c529cb-9ac7-4893-8573-53633637f546'}}
21:20:12 nxdrive.client.base_automation_client: TRACE: Calling http://localhost:8080/nuxeo/site/automation/NuxeoDrive.GetFileSystemItem with headers {'X-Authentication-Token': 'd332461b-6623-486d-8818-2f8277c15b6d', 'X-NXDocumentProperties': '*', 'X-Device-Id': u'7c01c7825b8e11e3a2c70024e805f008', 'Accept': 'application/json+nxentity, */*', '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.2-dev'}, cookies [] and JSON payload '{"params": {"id": "defaultFileSystemItemFactory#default#e9c529cb-9ac7-4893-8573-53633637f546"}}'
21:20:12 nxdrive.client.base_automation_client: TRACE: Response for 'http://localhost:8080/nuxeo/site/automation/NuxeoDrive.GetFileSystemItem' with cookies [] and JSON payload: '{"digest":"f3b09f7a82f6ac8c6077a1ca1d3778d8","digestAlgorithm":"md5","downloadURL":"nxbigfile/default/e9c529cb-9ac7-4893-8573-53633637f546/blobholder:0/Original%20File%201.txt","canUpdate":true,"name":"Original File 1.txt","id":"defaultFileSystemItemFactory#default#e9c529cb-9ac7-4893-8573-53633637f546","path":"/org.nuxeo.drive.service.impl.DefaultTopLevelFolderItemFactory#/defaultSyncRootFolderItemFactory#default#ddcea5a8-fbb7-422b-8392-fb2e5283738f/defaultFileSystemItemFactory#default#e9c529cb-9ac7-4893-8573-53633637f546","creationDate":1386015330153,"folder":false,"creator":"nuxeoDriveTestUser_user_1","parentId":"defaultSyncRootFolderItemFactory#default#ddcea5a8-fbb7-422b-8392-fb2e5283738f","lastModificationDate":1386015330153,"canRename":true,"canDelete":true,"userName":"nuxeoDriveTestUser_user_1"}'
21:20:12 nxdrive.client.remote_file_system_client: TRACE: Calling 'http://localhost:8080/nuxeo/nxbigfile/default/e9c529cb-9ac7-4893-8573-53633637f546/blobholder:0/Original%20File%201.txt' with headers: {'X-Authentication-Token': 'd332461b-6623-486d-8818-2f8277c15b6d', 'X-Device-Id': u'7c01c7825b8e11e3a2c70024e805f008', 'X-User-Id': u'nuxeoDriveTestUser_user_1', 'Cache-Control': 'no-cache', 'X-Application-Name': 'Nuxeo Drive', 'X-Client-Version': '1.2-dev'}
21:20:12 nxdrive.model: TRACE: Updated state for LastKnownState<local_folder=u'/tmp/tmpogT0Sd-nxdrive-tests-user-1/Nuxeo Drive', local_path=u'/Nuxeo Drive Test Workspace/Original File 1.txt', remote_name=u'Original File 1.txt', local_state='synchronized', remote_state='synchronized', pair_state='synchronized'>
21:20:12 nxdrive.client.base_automation_client: TRACE: Dumping JSON structure: {'params': {'id': u'defaultFileSystemItemFactory#default#587fa6ac-6f90-4862-b32b-149c7ce51ef9'}}
21:20:12 nxdrive.client.base_automation_client: TRACE: Calling http://localhost:8080/nuxeo/site/automation/NuxeoDrive.GetFileSystemItem with headers {'X-Authentication-Token': 'd332461b-6623-486d-8818-2f8277c15b6d', 'X-NXDocumentProperties': '*', 'X-Device-Id': u'7c01c7825b8e11e3a2c70024e805f008', 'Accept': 'application/json+nxentity, */*', '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.2-dev'}, cookies [] and JSON payload '{"params": {"id": "defaultFileSystemItemFactory#default#587fa6ac-6f90-4862-b32b-149c7ce51ef9"}}'
21:20:12 nxdrive.client.base_automation_client: TRACE: Response for 'http://localhost:8080/nuxeo/site/automation/NuxeoDrive.GetFileSystemItem' with cookies [] and JSON payload: '{"digest":"aa5728d0861fd3b174dc33202838ecb8","digestAlgorithm":"md5","downloadURL":"nxbigfile/default/587fa6ac-6f90-4862-b32b-149c7ce51ef9/blobholder:0/Original%20File%202.txt","canUpdate":true,"name":"Original File 2.txt","id":"defaultFileSystemItemFactory#default#587fa6ac-6f90-4862-b32b-149c7ce51ef9","path":"/org.nuxeo.drive.service.impl.DefaultTopLevelFolderItemFactory#/defaultSyncRootFolderItemFactory#default#ddcea5a8-fbb7-422b-8392-fb2e5283738f/defaultFileSystemItemFactory#default#587fa6ac-6f90-4862-b32b-149c7ce51ef9","creationDate":1386015330185,"folder":false,"creator":"nuxeoDriveTestUser_user_1","parentId":"defaultSyncRootFolderItemFactory#default#ddcea5a8-fbb7-422b-8392-fb2e5283738f","lastModificationDate":1386015330185,"canRename":true,"canDelete":true,"userName":"nuxeoDriveTestUser_user_1"}'
21:20:12 nxdrive.model: TRACE: Use last known modification time to detect updates: local DB, server = '2013-12-02 21:15:30', '2013-12-02 21:15:30'
21:20:12 nxdrive.model: TRACE: Updated state for LastKnownState<local_folder=u'/tmp/tmpogT0Sd-nxdrive-tests-user-1/Nuxeo Drive', local_path=None, remote_name=u'Original File 2.txt', local_state=u'unknown', remote_state='created', pair_state='remotely_created'>
21:20:12 nxdrive.synchronizer: DEBUG: Creating local file 'Original File 2.txt' in '/tmp/tmpogT0Sd-nxdrive-tests-user-1/Nuxeo Drive/Nuxeo Drive Test Workspace'
21:20:12 nxdrive.client.base_automation_client: TRACE: Dumping JSON structure: {'params': {'id': u'defaultFileSystemItemFactory#default#587fa6ac-6f90-4862-b32b-149c7ce51ef9'}}
21:20:12 nxdrive.client.base_automation_client: TRACE: Calling http://localhost:8080/nuxeo/site/automation/NuxeoDrive.GetFileSystemItem with headers {'X-Authentication-Token': 'd332461b-6623-486d-8818-2f8277c15b6d', 'X-NXDocumentProperties': '*', 'X-Device-Id': u'7c01c7825b8e11e3a2c70024e805f008', 'Accept': 'application/json+nxentity, */*', '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.2-dev'}, cookies [] and JSON payload '{"params": {"id": "defaultFileSystemItemFactory#default#587fa6ac-6f90-4862-b32b-149c7ce51ef9"}}'
21:20:12 nxdrive.client.base_automation_client: TRACE: Response for 'http://localhost:8080/nuxeo/site/automation/NuxeoDrive.GetFileSystemItem' with cookies [] and JSON payload: '{"digest":"aa5728d0861fd3b174dc33202838ecb8","digestAlgorithm":"md5","downloadURL":"nxbigfile/default/587fa6ac-6f90-4862-b32b-149c7ce51ef9/blobholder:0/Original%20File%202.txt","canUpdate":true,"name":"Original File 2.txt","id":"defaultFileSystemItemFactory#default#587fa6ac-6f90-4862-b32b-149c7ce51ef9","path":"/org.nuxeo.drive.service.impl.DefaultTopLevelFolderItemFactory#/defaultSyncRootFolderItemFactory#default#ddcea5a8-fbb7-422b-8392-fb2e5283738f/defaultFileSystemItemFactory#default#587fa6ac-6f90-4862-b32b-149c7ce51ef9","creationDate":1386015330185,"folder":false,"creator":"nuxeoDriveTestUser_user_1","parentId":"defaultSyncRootFolderItemFactory#default#ddcea5a8-fbb7-422b-8392-fb2e5283738f","lastModificationDate":1386015330185,"canRename":true,"canDelete":true,"userName":"nuxeoDriveTestUser_user_1"}'
21:20:12 nxdrive.client.remote_file_system_client: TRACE: Calling 'http://localhost:8080/nuxeo/nxbigfile/default/587fa6ac-6f90-4862-b32b-149c7ce51ef9/blobholder:0/Original%20File%202.txt' with headers: {'X-Authentication-Token': 'd332461b-6623-486d-8818-2f8277c15b6d', 'X-Device-Id': u'7c01c7825b8e11e3a2c70024e805f008', 'X-User-Id': u'nuxeoDriveTestUser_user_1', 'Cache-Control': 'no-cache', 'X-Application-Name': 'Nuxeo Drive', 'X-Client-Version': '1.2-dev'}
21:20:12 nxdrive.model: TRACE: Updated state for LastKnownState<local_folder=u'/tmp/tmpogT0Sd-nxdrive-tests-user-1/Nuxeo Drive', local_path=u'/Nuxeo Drive Test Workspace/Original File 2.txt', remote_name=u'Original File 2.txt', local_state='synchronized', remote_state='synchronized', pair_state='synchronized'>
21:20:12 nxdrive.client.base_automation_client: TRACE: Dumping JSON structure: {'params': {'id': u'defaultFileSystemItemFactory#default#2015d8c0-1f95-4884-a83f-266bb43a5db4'}}
21:20:12 nxdrive.client.base_automation_client: TRACE: Calling http://localhost:8080/nuxeo/site/automation/NuxeoDrive.GetFileSystemItem with headers {'X-Authentication-Token': 'd332461b-6623-486d-8818-2f8277c15b6d', 'X-NXDocumentProperties': '*', 'X-Device-Id': u'7c01c7825b8e11e3a2c70024e805f008', 'Accept': 'application/json+nxentity, */*', '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.2-dev'}, cookies [] and JSON payload '{"params": {"id": "defaultFileSystemItemFactory#default#2015d8c0-1f95-4884-a83f-266bb43a5db4"}}'
21:20:12 nxdrive.client.base_automation_client: TRACE: Response for 'http://localhost:8080/nuxeo/site/automation/NuxeoDrive.GetFileSystemItem' with cookies [] and JSON payload: '{"canCreateChild":true,"name":"Original Folder 1","id":"defaultFileSystemItemFactory#default#2015d8c0-1f95-4884-a83f-266bb43a5db4","path":"/org.nuxeo.drive.service.impl.DefaultTopLevelFolderItemFactory#/defaultSyncRootFolderItemFactory#default#ddcea5a8-fbb7-422b-8392-fb2e5283738f/defaultFileSystemItemFactory#default#2015d8c0-1f95-4884-a83f-266bb43a5db4","creationDate":1386015330358,"folder":true,"creator":"nuxeoDriveTestUser_user_1","parentId":"defaultSyncRootFolderItemFactory#default#ddcea5a8-fbb7-422b-8392-fb2e5283738f","lastModificationDate":1386015330358,"canRename":true,"canDelete":true,"userName":"nuxeoDriveTestUser_user_1"}'
21:20:12 nxdrive.model: TRACE: Use last known modification time to detect updates: local DB, server = '2013-12-02 21:15:30', '2013-12-02 21:15:30'
21:20:12 nxdrive.model: TRACE: Updated state for LastKnownState<local_folder=u'/tmp/tmpogT0Sd-nxdrive-tests-user-1/Nuxeo Drive', local_path=None, remote_name=u'Original Folder 1', local_state=u'unknown', remote_state='created', pair_state='remotely_created'>
21:20:12 nxdrive.synchronizer: DEBUG: Creating local folder 'Original Folder 1' in '/tmp/tmpogT0Sd-nxdrive-tests-user-1/Nuxeo Drive/Nuxeo Drive Test Workspace'
21:20:12 nxdrive.synchronizer: DEBUG: Remote recursive scan of the content of Original Folder 1
21:20:12 nxdrive.model: TRACE: Use last known modification time to detect updates: local DB, server = '2013-12-02 21:15:30', '2013-12-02 21:15:30'
21:20:12 nxdrive.client.base_automation_client: TRACE: Dumping JSON structure: {'params': {'id': u'defaultFileSystemItemFactory#default#2015d8c0-1f95-4884-a83f-266bb43a5db4'}}
21:20:12 nxdrive.client.base_automation_client: TRACE: Calling http://localhost:8080/nuxeo/site/automation/NuxeoDrive.GetChildren with headers {'X-Authentication-Token': 'd332461b-6623-486d-8818-2f8277c15b6d', 'X-NXDocumentProperties': '*', 'X-Device-Id': u'7c01c7825b8e11e3a2c70024e805f008', 'Accept': 'application/json+nxentity, */*', '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.2-dev'}, cookies [] and JSON payload '{"params": {"id": "defaultFileSystemItemFactory#default#2015d8c0-1f95-4884-a83f-266bb43a5db4"}}'
21:20:12 nxdrive.client.base_automation_client: TRACE: Response for 'http://localhost:8080/nuxeo/site/automation/NuxeoDrive.GetChildren' with cookies [] and JSON payload: '[{"canCreateChild":true,"name":"Sub-Folder 1.1","id":"defaultFileSystemItemFactory#default#60da6694-c58c-4769-b50a-55979bb6ced4","path":"/org.nuxeo.drive.service.impl.DefaultTopLevelFolderItemFactory#/defaultSyncRootFolderItemFactory#default#ddcea5a8-fbb7-422b-8392-fb2e5283738f/defaultFileSystemItemFactory#default#2015d8c0-1f95-4884-a83f-266bb43a5db4/defaultFileSystemItemFactory#default#60da6694-c58c-4769-b50a-55979bb6ced4","creationDate":1386015330558,"folder":true,"creator":"nuxeoDriveTestUser_user_1","parentId":"defaultFileSystemItemFactory#default#2015d8c0-1f95-4884-a83f-266bb43a5db4","lastModificationDate":1386015330558,"canRename":true,"canDelete":true,"userName":"nuxeoDriveTestUser_user_1"},{"canCreateChild":true,"name":"Sub-Folder 1.2","id":"defaultFileSystemItemFactory#default#48da7f0e-6341-44cf-b1d7-60acde45a736","path":"/org.nuxeo.drive.service.impl.DefaultTopLevelFolderItemFactory#/defaultSyncRootFolderItemFactory#default#ddcea5a8-fbb7-422b-8392-fb2e5283738f/defaultFileSystemItemFactory#default#2015d8c0-1f95-4884-a83f-266bb43a5db4/defaultFileSystemItemFactory#default#48da7f0e-6341-44cf-b1d7-60acde45a736","creationDate":1386015330753,"folder":true,"creator":"nuxeoDriveTestUser_user_1","parentId":"defaultFileSystemItemFactory#default#2015d8c0-1f95-4884-a83f-266bb43a5db4","lastModificationDate":1386015330753,"canRename":true,"canDelete":true,"userName":"nuxeoDriveTestUser_user_1"},{"digest":"f3b09f7a82f6ac8c6077a1ca1d3778d8","digestAlgorithm":"md5","downloadURL":"nxbigfile/default/96290fa7-ddd5-4f80-aabe-0bf43ed29820/blobholder:0/Original%20File%201.1.txt","canUpdate":true,"name":"Original File 1.1.txt","id":"defaultFileSystemItemFactory#default#96290fa7-ddd5-4f80-aabe-0bf43ed29820","path":"/org.nuxeo.drive.service.impl.DefaultTopLevelFolderItemFactory#/defaultSyncRootFolderItemFactory#default#ddcea5a8-fbb7-422b-8392-fb2e5283738f/defaultFileSystemItemFactory#default#2015d8c0-1f95-4884-a83f-266bb43a5db4/defaultFileSystemItemFactory#default#96290fa7-ddd5-4f80-aabe-0bf43ed29820","creationDate":1386015330939,"folder":false,"creator":"nuxeoDriveTestUser_user_1","parentId":"defaultFileSystemItemFactory#default#2015d8c0-1f95-4884-a83f-266bb43a5db4","lastModificationDate":1386015330939,"canRename":true,"canDelete":true,"userName":"nuxeoDriveTestUser_user_1"}]'
21:20:12 nxdrive.model: TRACE: Selecting refs set([u'defaultFileSystemItemFactory#default#96290fa7-ddd5-4f80-aabe-0bf43ed29820', u'defaultFileSystemItemFactory#default#48da7f0e-6341-44cf-b1d7-60acde45a736', u'defaultFileSystemItemFactory#default#60da6694-c58c-4769-b50a-55979bb6ced4'])
21:20:12 nxdrive.model: TRACE: Updated state for LastKnownState<local_folder=u'/tmp/tmpogT0Sd-nxdrive-tests-user-1/Nuxeo Drive', local_path=u'/Nuxeo Drive Test Workspace/Original Folder 1', remote_name=u'Original Folder 1', local_state='synchronized', remote_state='synchronized', pair_state='synchronized'>
21:20:12 nxdrive.client.base_automation_client: TRACE: Dumping JSON structure: {'params': {'id': u'defaultFileSystemItemFactory#default#11d930b7-112c-43f6-a05b-d5bc33a63805'}}
21:20:12 nxdrive.client.base_automation_client: TRACE: Calling http://localhost:8080/nuxeo/site/automation/NuxeoDrive.GetFileSystemItem with headers {'X-Authentication-Token': 'd332461b-6623-486d-8818-2f8277c15b6d', 'X-NXDocumentProperties': '*', 'X-Device-Id': u'7c01c7825b8e11e3a2c70024e805f008', 'Accept': 'application/json+nxentity, */*', '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.2-dev'}, cookies [] and JSON payload '{"params": {"id": "defaultFileSystemItemFactory#default#11d930b7-112c-43f6-a05b-d5bc33a63805"}}'
21:20:12 nxdrive.client.base_automation_client: TRACE: Response for 'http://localhost:8080/nuxeo/site/automation/NuxeoDrive.GetFileSystemItem' with cookies [] and JSON payload: '{"canCreateChild":true,"name":"Original Folder 2","id":"defaultFileSystemItemFactory#default#11d930b7-112c-43f6-a05b-d5bc33a63805","path":"/org.nuxeo.drive.service.impl.DefaultTopLevelFolderItemFactory#/defaultSyncRootFolderItemFactory#default#ddcea5a8-fbb7-422b-8392-fb2e5283738f/defaultFileSystemItemFactory#default#11d930b7-112c-43f6-a05b-d5bc33a63805","creationDate":1386015331039,"folder":true,"creator":"nuxeoDriveTestUser_user_1","parentId":"defaultSyncRootFolderItemFactory#default#ddcea5a8-fbb7-422b-8392-fb2e5283738f","lastModificationDate":1386015331039,"canRename":true,"canDelete":true,"userName":"nuxeoDriveTestUser_user_1"}'
21:20:12 nxdrive.model: TRACE: Use last known modification time to detect updates: local DB, server = '2013-12-02 21:15:31', '2013-12-02 21:15:31'
21:20:12 nxdrive.model: TRACE: Updated state for LastKnownState<local_folder=u'/tmp/tmpogT0Sd-nxdrive-tests-user-1/Nuxeo Drive', local_path=None, remote_name=u'Original Folder 2', local_state=u'unknown', remote_state='created', pair_state='remotely_created'>
21:20:12 nxdrive.synchronizer: DEBUG: Creating local folder 'Original Folder 2' in '/tmp/tmpogT0Sd-nxdrive-tests-user-1/Nuxeo Drive/Nuxeo Drive Test Workspace'
21:20:12 nxdrive.synchronizer: DEBUG: Remote recursive scan of the content of Original Folder 2
21:20:12 nxdrive.model: TRACE: Use last known modification time to detect updates: local DB, server = '2013-12-02 21:15:31', '2013-12-02 21:15:31'
21:20:12 nxdrive.client.base_automation_client: TRACE: Dumping JSON structure: {'params': {'id': u'defaultFileSystemItemFactory#default#11d930b7-112c-43f6-a05b-d5bc33a63805'}}
21:20:12 nxdrive.client.base_automation_client: TRACE: Calling http://localhost:8080/nuxeo/site/automation/NuxeoDrive.GetChildren with headers {'X-Authentication-Token': 'd332461b-6623-486d-8818-2f8277c15b6d', 'X-NXDocumentProperties': '*', 'X-Device-Id': u'7c01c7825b8e11e3a2c70024e805f008', 'Accept': 'application/json+nxentity, */*', '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.2-dev'}, cookies [] and JSON payload '{"params": {"id": "defaultFileSystemItemFactory#default#11d930b7-112c-43f6-a05b-d5bc33a63805"}}'
21:20:12 nxdrive.client.base_automation_client: TRACE: Response for 'http://localhost:8080/nuxeo/site/automation/NuxeoDrive.GetChildren' with cookies [] and JSON payload: '[{"digest":"180b2cbf65a05357269e12a1d1324635","digestAlgorithm":"md5","downloadURL":"nxbigfile/default/f43104dc-5e60-4295-9444-8e3bb4955e14/blobholder:0/Original%20File%203.txt","canUpdate":true,"name":"Original File 3.txt","id":"defaultFileSystemItemFactory#default#f43104dc-5e60-4295-9444-8e3bb4955e14","path":"/org.nuxeo.drive.service.impl.DefaultTopLevelFolderItemFactory#/defaultSyncRootFolderItemFactory#default#ddcea5a8-fbb7-422b-8392-fb2e5283738f/defaultFileSystemItemFactory#default#11d930b7-112c-43f6-a05b-d5bc33a63805/defaultFileSystemItemFactory#default#f43104dc-5e60-4295-9444-8e3bb4955e14","creationDate":1386015331064,"folder":false,"creator":"nuxeoDriveTestUser_user_1","parentId":"defaultFileSystemItemFactory#default#11d930b7-112c-43f6-a05b-d5bc33a63805","lastModificationDate":1386015331064,"canRename":true,"canDelete":true,"userName":"nuxeoDriveTestUser_user_1"}]'
21:20:12 nxdrive.model: TRACE: Selecting refs set([u'defaultFileSystemItemFactory#default#f43104dc-5e60-4295-9444-8e3bb4955e14'])
21:20:12 nxdrive.model: TRACE: Updated state for LastKnownState<local_folder=u'/tmp/tmpogT0Sd-nxdrive-tests-user-1/Nuxeo Drive', local_path=u'/Nuxeo Drive Test Workspace/Original Folder 2', remote_name=u'Original Folder 2', local_state='synchronized', remote_state='synchronized', pair_state='synchronized'>
21:20:12 nxdrive.client.base_automation_client: TRACE: Dumping JSON structure: {'params': {'id': u'defaultFileSystemItemFactory#default#f43104dc-5e60-4295-9444-8e3bb4955e14'}}
21:20:12 nxdrive.client.base_automation_client: TRACE: Calling http://localhost:8080/nuxeo/site/automation/NuxeoDrive.GetFileSystemItem with headers {'X-Authentication-Token': 'd332461b-6623-486d-8818-2f8277c15b6d', 'X-NXDocumentProperties': '*', 'X-Device-Id': u'7c01c7825b8e11e3a2c70024e805f008', 'Accept': 'application/json+nxentity, */*', '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.2-dev'}, cookies [] and JSON payload '{"params": {"id": "defaultFileSystemItemFactory#default#f43104dc-5e60-4295-9444-8e3bb4955e14"}}'
21:20:12 nxdrive.client.base_automation_client: TRACE: Response for 'http://localhost:8080/nuxeo/site/automation/NuxeoDrive.GetFileSystemItem' with cookies [] and JSON payload: '{"digest":"180b2cbf65a05357269e12a1d1324635","digestAlgorithm":"md5","downloadURL":"nxbigfile/default/f43104dc-5e60-4295-9444-8e3bb4955e14/blobholder:0/Original%20File%203.txt","canUpdate":true,"name":"Original File 3.txt","id":"defaultFileSystemItemFactory#default#f43104dc-5e60-4295-9444-8e3bb4955e14","path":"/org.nuxeo.drive.service.impl.DefaultTopLevelFolderItemFactory#/defaultSyncRootFolderItemFactory#default#ddcea5a8-fbb7-422b-8392-fb2e5283738f/defaultFileSystemItemFactory#default#11d930b7-112c-43f6-a05b-d5bc33a63805/defaultFileSystemItemFactory#default#f43104dc-5e60-4295-9444-8e3bb4955e14","creationDate":1386015331064,"folder":false,"creator":"nuxeoDriveTestUser_user_1","parentId":"defaultFileSystemItemFactory#default#11d930b7-112c-43f6-a05b-d5bc33a63805","lastModificationDate":1386015331064,"canRename":true,"canDelete":true,"userName":"nuxeoDriveTestUser_user_1"}'
21:20:12 nxdrive.model: TRACE: Use last known modification time to detect updates: local DB, server = '2013-12-02 21:15:31', '2013-12-02 21:15:31'
21:20:12 nxdrive.model: TRACE: Updated state for LastKnownState<local_folder=u'/tmp/tmpogT0Sd-nxdrive-tests-user-1/Nuxeo Drive', local_path=None, remote_name=u'Original File 3.txt', local_state=u'unknown', remote_state='created', pair_state='remotely_created'>
21:20:12 nxdrive.synchronizer: DEBUG: Creating local file 'Original File 3.txt' in '/tmp/tmpogT0Sd-nxdrive-tests-user-1/Nuxeo Drive/Nuxeo Drive Test Workspace/Original Folder 2'
21:20:12 nxdrive.client.base_automation_client: TRACE: Dumping JSON structure: {'params': {'id': u'defaultFileSystemItemFactory#default#f43104dc-5e60-4295-9444-8e3bb4955e14'}}
21:20:12 nxdrive.client.base_automation_client: TRACE: Calling http://localhost:8080/nuxeo/site/automation/NuxeoDrive.GetFileSystemItem with headers {'X-Authentication-Token': 'd332461b-6623-486d-8818-2f8277c15b6d', 'X-NXDocumentProperties': '*', 'X-Device-Id': u'7c01c7825b8e11e3a2c70024e805f008', 'Accept': 'application/json+nxentity, */*', '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.2-dev'}, cookies [] and JSON payload '{"params": {"id": "defaultFileSystemItemFactory#default#f43104dc-5e60-4295-9444-8e3bb4955e14"}}'
21:20:12 nxdrive.client.base_automation_client: TRACE: Response for 'http://localhost:8080/nuxeo/site/automation/NuxeoDrive.GetFileSystemItem' with cookies [] and JSON payload: '{"digest":"180b2cbf65a05357269e12a1d1324635","digestAlgorithm":"md5","downloadURL":"nxbigfile/default/f43104dc-5e60-4295-9444-8e3bb4955e14/blobholder:0/Original%20File%203.txt","canUpdate":true,"name":"Original File 3.txt","id":"defaultFileSystemItemFactory#default#f43104dc-5e60-4295-9444-8e3bb4955e14","path":"/org.nuxeo.drive.service.impl.DefaultTopLevelFolderItemFactory#/defaultSyncRootFolderItemFactory#default#ddcea5a8-fbb7-422b-8392-fb2e5283738f/defaultFileSystemItemFactory#default#11d930b7-112c-43f6-a05b-d5bc33a63805/defaultFileSystemItemFactory#default#f43104dc-5e60-4295-9444-8e3bb4955e14","creationDate":1386015331064,"folder":false,"creator":"nuxeoDriveTestUser_user_1","parentId":"defaultFileSystemItemFactory#default#11d930b7-112c-43f6-a05b-d5bc33a63805","lastModificationDate":1386015331064,"canRename":true,"canDelete":true,"userName":"nuxeoDriveTestUser_user_1"}'
21:20:12 nxdrive.client.remote_file_system_client: TRACE: Calling 'http://localhost:8080/nuxeo/nxbigfile/default/f43104dc-5e60-4295-9444-8e3bb4955e14/blobholder:0/Original%20File%203.txt' with headers: {'X-Authentication-Token': 'd332461b-6623-486d-8818-2f8277c15b6d', 'X-Device-Id': u'7c01c7825b8e11e3a2c70024e805f008', 'X-User-Id': u'nuxeoDriveTestUser_user_1', 'Cache-Control': 'no-cache', 'X-Application-Name': 'Nuxeo Drive', 'X-Client-Version': '1.2-dev'}
21:20:12 nxdrive.model: TRACE: Updated state for LastKnownState<local_folder=u'/tmp/tmpogT0Sd-nxdrive-tests-user-1/Nuxeo Drive', local_path=u'/Nuxeo Drive Test Workspace/Original Folder 2/Original File 3.txt', remote_name=u'Original File 3.txt', local_state='synchronized', remote_state='synchronized', pair_state='synchronized'>
21:20:12 nxdrive.client.base_automation_client: TRACE: Dumping JSON structure: {'params': {'id': u'defaultFileSystemItemFactory#default#96290fa7-ddd5-4f80-aabe-0bf43ed29820'}}
21:20:12 nxdrive.client.base_automation_client: TRACE: Calling http://localhost:8080/nuxeo/site/automation/NuxeoDrive.GetFileSystemItem with headers {'X-Authentication-Token': 'd332461b-6623-486d-8818-2f8277c15b6d', 'X-NXDocumentProperties': '*', 'X-Device-Id': u'7c01c7825b8e11e3a2c70024e805f008', 'Accept': 'application/json+nxentity, */*', '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.2-dev'}, cookies [] and JSON payload '{"params": {"id": "defaultFileSystemItemFactory#default#96290fa7-ddd5-4f80-aabe-0bf43ed29820"}}'
21:20:12 nxdrive.client.base_automation_client: TRACE: Response for 'http://localhost:8080/nuxeo/site/automation/NuxeoDrive.GetFileSystemItem' with cookies [] and JSON payload: '{"digest":"f3b09f7a82f6ac8c6077a1ca1d3778d8","digestAlgorithm":"md5","downloadURL":"nxbigfile/default/96290fa7-ddd5-4f80-aabe-0bf43ed29820/blobholder:0/Original%20File%201.1.txt","canUpdate":true,"name":"Original File 1.1.txt","id":"defaultFileSystemItemFactory#default#96290fa7-ddd5-4f80-aabe-0bf43ed29820","path":"/org.nuxeo.drive.service.impl.DefaultTopLevelFolderItemFactory#/defaultSyncRootFolderItemFactory#default#ddcea5a8-fbb7-422b-8392-fb2e5283738f/defaultFileSystemItemFactory#default#2015d8c0-1f95-4884-a83f-266bb43a5db4/defaultFileSystemItemFactory#default#96290fa7-ddd5-4f80-aabe-0bf43ed29820","creationDate":1386015330939,"folder":false,"creator":"nuxeoDriveTestUser_user_1","parentId":"defaultFileSystemItemFactory#default#2015d8c0-1f95-4884-a83f-266bb43a5db4","lastModificationDate":1386015330939,"canRename":true,"canDelete":true,"userName":"nuxeoDriveTestUser_user_1"}'
21:20:12 nxdrive.model: TRACE: Use last known modification time to detect updates: local DB, server = '2013-12-02 21:15:30', '2013-12-02 21:15:30'
21:20:12 nxdrive.model: TRACE: Updated state for LastKnownState<local_folder=u'/tmp/tmpogT0Sd-nxdrive-tests-user-1/Nuxeo Drive', local_path=None, remote_name=u'Original File 1.1.txt', local_state=u'unknown', remote_state='created', pair_state='remotely_created'>
21:20:12 nxdrive.synchronizer: DEBUG: Creating local file 'Original File 1.1.txt' in '/tmp/tmpogT0Sd-nxdrive-tests-user-1/Nuxeo Drive/Nuxeo Drive Test Workspace/Original Folder 1'
21:20:12 nxdrive.client.base_automation_client: TRACE: Dumping JSON structure: {'params': {'id': u'defaultFileSystemItemFactory#default#96290fa7-ddd5-4f80-aabe-0bf43ed29820'}}
21:20:12 nxdrive.client.base_automation_client: TRACE: Calling http://localhost:8080/nuxeo/site/automation/NuxeoDrive.GetFileSystemItem with headers {'X-Authentication-Token': 'd332461b-6623-486d-8818-2f8277c15b6d', 'X-NXDocumentProperties': '*', 'X-Device-Id': u'7c01c7825b8e11e3a2c70024e805f008', 'Accept': 'application/json+nxentity, */*', '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.2-dev'}, cookies [] and JSON payload '{"params": {"id": "defaultFileSystemItemFactory#default#96290fa7-ddd5-4f80-aabe-0bf43ed29820"}}'
21:20:12 nxdrive.client.base_automation_client: TRACE: Response for 'http://localhost:8080/nuxeo/site/automation/NuxeoDrive.GetFileSystemItem' with cookies [] and JSON payload: '{"digest":"f3b09f7a82f6ac8c6077a1ca1d3778d8","digestAlgorithm":"md5","downloadURL":"nxbigfile/default/96290fa7-ddd5-4f80-aabe-0bf43ed29820/blobholder:0/Original%20File%201.1.txt","canUpdate":true,"name":"Original File 1.1.txt","id":"defaultFileSystemItemFactory#default#96290fa7-ddd5-4f80-aabe-0bf43ed29820","path":"/org.nuxeo.drive.service.impl.DefaultTopLevelFolderItemFactory#/defaultSyncRootFolderItemFactory#default#ddcea5a8-fbb7-422b-8392-fb2e5283738f/defaultFileSystemItemFactory#default#2015d8c0-1f95-4884-a83f-266bb43a5db4/defaultFileSystemItemFactory#default#96290fa7-ddd5-4f80-aabe-0bf43ed29820","creationDate":1386015330939,"folder":false,"creator":"nuxeoDriveTestUser_user_1","parentId":"defaultFileSystemItemFactory#default#2015d8c0-1f95-4884-a83f-266bb43a5db4","lastModificationDate":1386015330939,"canRename":true,"canDelete":true,"userName":"nuxeoDriveTestUser_user_1"}'
21:20:12 nxdrive.client.remote_file_system_client: TRACE: Calling 'http://localhost:8080/nuxeo/nxbigfile/default/96290fa7-ddd5-4f80-aabe-0bf43ed29820/blobholder:0/Original%20File%201.1.txt' with headers: {'X-Authentication-Token': 'd332461b-6623-486d-8818-2f8277c15b6d', 'X-Device-Id': u'7c01c7825b8e11e3a2c70024e805f008', 'X-User-Id': u'nuxeoDriveTestUser_user_1', 'Cache-Control': 'no-cache', 'X-Application-Name': 'Nuxeo Drive', 'X-Client-Version': '1.2-dev'}
21:20:12 nxdrive.model: TRACE: Updated state for LastKnownState<local_folder=u'/tmp/tmpogT0Sd-nxdrive-tests-user-1/Nuxeo Drive', local_path=u'/Nuxeo Drive Test Workspace/Original Folder 1/Original File 1.1.txt', remote_name=u'Original File 1.1.txt', local_state='synchronized', remote_state='synchronized', pair_state='synchronized'>
21:20:12 nxdrive.client.base_automation_client: TRACE: Dumping JSON structure: {'params': {'id': u'defaultFileSystemItemFactory#default#60da6694-c58c-4769-b50a-55979bb6ced4'}}
21:20:12 nxdrive.client.base_automation_client: TRACE: Calling http://localhost:8080/nuxeo/site/automation/NuxeoDrive.GetFileSystemItem with headers {'X-Authentication-Token': 'd332461b-6623-486d-8818-2f8277c15b6d', 'X-NXDocumentProperties': '*', 'X-Device-Id': u'7c01c7825b8e11e3a2c70024e805f008', 'Accept': 'application/json+nxentity, */*', '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.2-dev'}, cookies [] and JSON payload '{"params": {"id": "defaultFileSystemItemFactory#default#60da6694-c58c-4769-b50a-55979bb6ced4"}}'
21:20:12 nxdrive.client.base_automation_client: TRACE: Response for 'http://localhost:8080/nuxeo/site/automation/NuxeoDrive.GetFileSystemItem' with cookies [] and JSON payload: '{"canCreateChild":true,"name":"Sub-Folder 1.1","id":"defaultFileSystemItemFactory#default#60da6694-c58c-4769-b50a-55979bb6ced4","path":"/org.nuxeo.drive.service.impl.DefaultTopLevelFolderItemFactory#/defaultSyncRootFolderItemFactory#default#ddcea5a8-fbb7-422b-8392-fb2e5283738f/defaultFileSystemItemFactory#default#2015d8c0-1f95-4884-a83f-266bb43a5db4/defaultFileSystemItemFactory#default#60da6694-c58c-4769-b50a-55979bb6ced4","creationDate":1386015330558,"folder":true,"creator":"nuxeoDriveTestUser_user_1","parentId":"defaultFileSystemItemFactory#default#2015d8c0-1f95-4884-a83f-266bb43a5db4","lastModificationDate":1386015330558,"canRename":true,"canDelete":true,"userName":"nuxeoDriveTestUser_user_1"}'
21:20:12 nxdrive.model: TRACE: Use last known modification time to detect updates: local DB, server = '2013-12-02 21:15:30', '2013-12-02 21:15:30'
21:20:12 nxdrive.model: TRACE: Updated state for LastKnownState<local_folder=u'/tmp/tmpogT0Sd-nxdrive-tests-user-1/Nuxeo Drive', local_path=None, remote_name=u'Sub-Folder 1.1', local_state=u'unknown', remote_state='created', pair_state='remotely_created'>
21:20:12 nxdrive.synchronizer: DEBUG: Creating local folder 'Sub-Folder 1.1' in '/tmp/tmpogT0Sd-nxdrive-tests-user-1/Nuxeo Drive/Nuxeo Drive Test Workspace/Original Folder 1'
21:20:12 nxdrive.synchronizer: DEBUG: Remote recursive scan of the content of Sub-Folder 1.1
21:20:12 nxdrive.model: TRACE: Use last known modification time to detect updates: local DB, server = '2013-12-02 21:15:30', '2013-12-02 21:15:30'
21:20:12 nxdrive.client.base_automation_client: TRACE: Dumping JSON structure: {'params': {'id': u'defaultFileSystemItemFactory#default#60da6694-c58c-4769-b50a-55979bb6ced4'}}
21:20:12 nxdrive.client.base_automation_client: TRACE: Calling http://localhost:8080/nuxeo/site/automation/NuxeoDrive.GetChildren with headers {'X-Authentication-Token': 'd332461b-6623-486d-8818-2f8277c15b6d', 'X-NXDocumentProperties': '*', 'X-Device-Id': u'7c01c7825b8e11e3a2c70024e805f008', 'Accept': 'application/json+nxentity, */*', '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.2-dev'}, cookies [] and JSON payload '{"params": {"id": "defaultFileSystemItemFactory#default#60da6694-c58c-4769-b50a-55979bb6ced4"}}'
21:20:12 nxdrive.client.base_automation_client: TRACE: Response for 'http://localhost:8080/nuxeo/site/automation/NuxeoDrive.GetChildren' with cookies [] and JSON payload: '[]'
21:20:12 nxdrive.model: TRACE: Selecting refs set([])
21:20:12 nxdrive.model: TRACE: Updated state for LastKnownState<local_folder=u'/tmp/tmpogT0Sd-nxdrive-tests-user-1/Nuxeo Drive', local_path=u'/Nuxeo Drive Test Workspace/Original Folder 1/Sub-Folder 1.1', remote_name=u'Sub-Folder 1.1', local_state='synchronized', remote_state='synchronized', pair_state='synchronized'>
21:20:12 nxdrive.client.base_automation_client: TRACE: Dumping JSON structure: {'params': {'id': u'defaultFileSystemItemFactory#default#48da7f0e-6341-44cf-b1d7-60acde45a736'}}
21:20:12 nxdrive.client.base_automation_client: TRACE: Calling http://localhost:8080/nuxeo/site/automation/NuxeoDrive.GetFileSystemItem with headers {'X-Authentication-Token': 'd332461b-6623-486d-8818-2f8277c15b6d', 'X-NXDocumentProperties': '*', 'X-Device-Id': u'7c01c7825b8e11e3a2c70024e805f008', 'Accept': 'application/json+nxentity, */*', '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.2-dev'}, cookies [] and JSON payload '{"params": {"id": "defaultFileSystemItemFactory#default#48da7f0e-6341-44cf-b1d7-60acde45a736"}}'
21:20:12 nxdrive.client.base_automation_client: TRACE: Response for 'http://localhost:8080/nuxeo/site/automation/NuxeoDrive.GetFileSystemItem' with cookies [] and JSON payload: '{"canCreateChild":true,"name":"Sub-Folder 1.2","id":"defaultFileSystemItemFactory#default#48da7f0e-6341-44cf-b1d7-60acde45a736","path":"/org.nuxeo.drive.service.impl.DefaultTopLevelFolderItemFactory#/defaultSyncRootFolderItemFactory#default#ddcea5a8-fbb7-422b-8392-fb2e5283738f/defaultFileSystemItemFactory#default#2015d8c0-1f95-4884-a83f-266bb43a5db4/defaultFileSystemItemFactory#default#48da7f0e-6341-44cf-b1d7-60acde45a736","creationDate":1386015330753,"folder":true,"creator":"nuxeoDriveTestUser_user_1","parentId":"defaultFileSystemItemFactory#default#2015d8c0-1f95-4884-a83f-266bb43a5db4","lastModificationDate":1386015330753,"canRename":true,"canDelete":true,"userName":"nuxeoDriveTestUser_user_1"}'
21:20:12 nxdrive.model: TRACE: Use last known modification time to detect updates: local DB, server = '2013-12-02 21:15:30', '2013-12-02 21:15:30'
21:20:12 nxdrive.model: TRACE: Updated state for LastKnownState<local_folder=u'/tmp/tmpogT0Sd-nxdrive-tests-user-1/Nuxeo Drive', local_path=None, remote_name=u'Sub-Folder 1.2', local_state=u'unknown', remote_state='created', pair_state='remotely_created'>
21:20:12 nxdrive.synchronizer: DEBUG: Creating local folder 'Sub-Folder 1.2' in '/tmp/tmpogT0Sd-nxdrive-tests-user-1/Nuxeo Drive/Nuxeo Drive Test Workspace/Original Folder 1'
21:20:12 nxdrive.synchronizer: DEBUG: Remote recursive scan of the content of Sub-Folder 1.2
21:20:12 nxdrive.model: TRACE: Use last known modification time to detect updates: local DB, server = '2013-12-02 21:15:30', '2013-12-02 21:15:30'
21:20:12 nxdrive.client.base_automation_client: TRACE: Dumping JSON structure: {'params': {'id': u'defaultFileSystemItemFactory#default#48da7f0e-6341-44cf-b1d7-60acde45a736'}}
21:20:12 nxdrive.client.base_automation_client: TRACE: Calling http://localhost:8080/nuxeo/site/automation/NuxeoDrive.GetChildren with headers {'X-Authentication-Token': 'd332461b-6623-486d-8818-2f8277c15b6d', 'X-NXDocumentProperties': '*', 'X-Device-Id': u'7c01c7825b8e11e3a2c70024e805f008', 'Accept': 'application/json+nxentity, */*', '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.2-dev'}, cookies [] and JSON payload '{"params": {"id": "defaultFileSystemItemFactory#default#48da7f0e-6341-44cf-b1d7-60acde45a736"}}'
21:20:12 nxdrive.client.base_automation_client: TRACE: Response for 'http://localhost:8080/nuxeo/site/automation/NuxeoDrive.GetChildren' with cookies [] and JSON payload: '[]'
21:20:12 nxdrive.model: TRACE: Selecting refs set([])
21:20:12 nxdrive.model: TRACE: Updated state for LastKnownState<local_folder=u'/tmp/tmpogT0Sd-nxdrive-tests-user-1/Nuxeo Drive', local_path=u'/Nuxeo Drive Test Workspace/Original Folder 1/Sub-Folder 1.2', remote_name=u'Sub-Folder 1.2', local_state='synchronized', remote_state='synchronized', pair_state='synchronized'>
21:20:12 nxdrive.synchronizer: DEBUG: [/tmp/tmpogT0Sd-nxdrive-tests-user-1/Nuxeo Drive] - [http://localhost:8080/nuxeo/]: synchronized: 8, pending: 8, local: 0.142s, remote: 0.285s sync: 1.487s
21:20:12 nxdrive.client.base_automation_client: TRACE: Dumping JSON structure: {'params': {'id': u'defaultSyncRootFolderItemFactory#default#ddcea5a8-fbb7-422b-8392-fb2e5283738f', 'name': u'Renamed Nuxeo Drive Test Workspace'}}
21:20:12 nxdrive.client.base_automation_client: TRACE: Calling http://localhost:8080/nuxeo/site/automation/NuxeoDrive.Rename with headers {'X-Client-Version': '1.2-dev', 'X-NXDocumentProperties': '*', 'X-Device-Id': u'nxdrive-test-device-1', 'Accept': 'application/json+nxentity, */*', 'X-User-Id': u'nuxeoDriveTestUser_user_1', 'Cache-Control': 'no-cache', 'Content-Type': 'application/json+nxrequest', 'X-Application-Name': 'Nuxeo Drive', 'Authorization': 'Basic bnV4ZW9Ecml2ZVRlc3RVc2VyX3VzZXJfMTphM2I1MmQ='}, cookies [] and JSON payload '{"params": {"id": "defaultSyncRootFolderItemFactory#default#ddcea5a8-fbb7-422b-8392-fb2e5283738f", "name": "Renamed Nuxeo Drive Test Workspace"}}'
21:20:12 nxdrive.client.base_automation_client: TRACE: Response for 'http://localhost:8080/nuxeo/site/automation/NuxeoDrive.Rename' with cookies [] and JSON payload: '{"canCreateChild":true,"name":"Renamed Nuxeo Drive Test Workspace","id":"defaultSyncRootFolderItemFactory#default#ddcea5a8-fbb7-422b-8392-fb2e5283738f","path":"/org.nuxeo.drive.service.impl.DefaultTopLevelFolderItemFactory#/defaultSyncRootFolderItemFactory#default#ddcea5a8-fbb7-422b-8392-fb2e5283738f","creationDate":1386015329199,"folder":true,"creator":"Administrator","parentId":"org.nuxeo.drive.service.impl.DefaultTopLevelFolderItemFactory#","lastModificationDate":1386015334301,"canRename":true,"canDelete":true,"userName":"nuxeoDriveTestUser_user_1"}'
21:20:12 nxdrive.client.base_automation_client: TRACE: Dumping JSON structure: {'params': {'id': u'defaultSyncRootFolderItemFactory#default#ddcea5a8-fbb7-422b-8392-fb2e5283738f'}}
21:20:12 nxdrive.client.base_automation_client: TRACE: Calling http://localhost:8080/nuxeo/site/automation/NuxeoDrive.GetFileSystemItem with headers {'X-Client-Version': '1.2-dev', 'X-NXDocumentProperties': '*', 'X-Device-Id': u'nxdrive-test-device-1', 'Accept': 'application/json+nxentity, */*', 'X-User-Id': u'nuxeoDriveTestUser_user_1', 'Cache-Control': 'no-cache', 'Content-Type': 'application/json+nxrequest', 'X-Application-Name': 'Nuxeo Drive', 'Authorization': 'Basic bnV4ZW9Ecml2ZVRlc3RVc2VyX3VzZXJfMTphM2I1MmQ='}, cookies [] and JSON payload '{"params": {"id": "defaultSyncRootFolderItemFactory#default#ddcea5a8-fbb7-422b-8392-fb2e5283738f"}}'
21:20:12 nxdrive.client.base_automation_client: TRACE: Response for 'http://localhost:8080/nuxeo/site/automation/NuxeoDrive.GetFileSystemItem' with cookies [] and JSON payload: '{"canCreateChild":true,"name":"Renamed Nuxeo Drive Test Workspace","id":"defaultSyncRootFolderItemFactory#default#ddcea5a8-fbb7-422b-8392-fb2e5283738f","path":"/org.nuxeo.drive.service.impl.DefaultTopLevelFolderItemFactory#/defaultSyncRootFolderItemFactory#default#ddcea5a8-fbb7-422b-8392-fb2e5283738f","creationDate":1386015329199,"folder":true,"creator":"Administrator","parentId":"org.nuxeo.drive.service.impl.DefaultTopLevelFolderItemFactory#","lastModificationDate":1386015334301,"canRename":true,"canDelete":true,"userName":"nuxeoDriveTestUser_user_1"}'
21:20:12 nxdrive.client.base_automation_client: TRACE: Dumping JSON structure: {'params': {}}
21:20:12 nxdrive.client.base_automation_client: TRACE: Calling http://localhost:8080/nuxeo/site/automation/NuxeoDrive.WaitForAsyncCompletion with headers {'X-Client-Version': '1.2-dev', 'X-NXDocumentProperties': '*', 'X-Device-Id': u'nxdrive-test-administrator-device', 'Accept': 'application/json+nxentity, */*', '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": {}}'
21:20:12 nxdrive.client.base_automation_client: TRACE: Response for 'http://localhost:8080/nuxeo/site/automation/NuxeoDrive.WaitForAsyncCompletion' with cookies [] and content-type: ''
21:20:12 nxdrive.client.base_automation_client: TRACE: Dumping JSON structure: {'params': {'lastSyncDate': 1386015332000L, 'lastSyncActiveRootDefinitions': u'default:ddcea5a8-fbb7-422b-8392-fb2e5283738f'}}
21:20:12 nxdrive.client.base_automation_client: TRACE: Calling http://localhost:8080/nuxeo/site/automation/NuxeoDrive.GetChangeSummary with headers {'X-Authentication-Token': 'd332461b-6623-486d-8818-2f8277c15b6d', 'X-NXDocumentProperties': '*', 'X-Device-Id': u'7c01c7825b8e11e3a2c70024e805f008', 'Accept': 'application/json+nxentity, */*', '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.2-dev'}, cookies [] and JSON payload '{"params": {"lastSyncDate": 1386015332000, "lastSyncActiveRootDefinitions": "default:ddcea5a8-fbb7-422b-8392-fb2e5283738f"}}'
21:20:12 nxdrive.client.base_automation_client: TRACE: Response for 'http://localhost:8080/nuxeo/site/automation/NuxeoDrive.GetChangeSummary' with cookies [] and JSON payload: '{"fileSystemChanges":[],"syncDate":1386015335000,"activeSynchronizationRootDefinitions":"default:ddcea5a8-fbb7-422b-8392-fb2e5283738f","hasTooManyChanges":false}'
21:20:12 nxdrive.model: TRACE: Selecting paths set([u'/Nuxeo Drive Test Workspace'])
21:20:12 nxdrive.model: TRACE: Selecting paths set([u'/Nuxeo Drive Test Workspace/Original File 2.txt', u'/Nuxeo Drive Test Workspace/Original Folder 1', u'/Nuxeo Drive Test Workspace/Original Folder 2', u'/Nuxeo Drive Test Workspace/Original File 1.txt'])
21:20:12 nxdrive.model: TRACE: Selecting paths set([u'/Nuxeo Drive Test Workspace/Original Folder 1/Sub-Folder 1.2', u'/Nuxeo Drive Test Workspace/Original Folder 1/Sub-Folder 1.1', u'/Nuxeo Drive Test Workspace/Original Folder 1/Original File 1.1.txt'])
21:20:12 nxdrive.model: TRACE: Selecting paths set([])
21:20:12 nxdrive.model: TRACE: Selecting paths set([])
21:20:12 nxdrive.model: TRACE: Selecting paths set([u'/Nuxeo Drive Test Workspace/Original Folder 2/Original File 3.txt'])
21:20:12 nxdrive.synchronizer: DEBUG: [/tmp/tmpogT0Sd-nxdrive-tests-user-1/Nuxeo Drive] - [http://localhost:8080/nuxeo/]: synchronized: 0, pending: 0, local: 0.209s, remote: 0.069s sync: 0.008s
21:20:12 --------------------- >> end captured logging << ---------------------

Attachments

Issue Links

duplicates

NXDRIVE-69 Drive build safety check up

Resolved

Activity

People

Assignee:

Antoine Taillefer

Reporter:

Antoine Taillefer

Participants:

Antoine Taillefer

Votes:

0 Vote for this issue

Watchers:

1 Start watching this issue

Dates

Created:

2013-12-12 14:12

Updated:

2014-10-30 10:37

Resolved:

2014-09-01 13:20