[NXP-13395] Drive: investigate on randomly failing test_synchronize_paged_delete_detection - Nuxeo Issue Tracker

XML

Word

Printable

Details

Type: Bug
Status: Resolved
Priority: Minor
Resolution: Fixed
Affects Version/s: None
Fix Version/s: 5.6.0-HF31, 5.8.0-HF08, 5.9.2
Component/s: Nuxeo Drive

Tags:
- QAEmergency
- bugday
Backlog priority:
850
Sprint:
Sprint 3

Description

For example:

14:06:22 FAIL: test_synchronize_paged_delete_detection (nxdrive.tests.test_integration_synchronization.TestIntegrationSynchronization)
14:06:22 ----------------------------------------------------------------------
14:06:22 Traceback (most recent call last):
14:06:22   File "/home/hudson/tmp/workspace/IT-nuxeo-drive-5.6.0-linux/nuxeo-drive-client/nxdrive/tests/test_integration_synchronization.py", line 1139, in test_synchronize_paged_delete_detection
14:06:22     self.assertFalse(remote.exists('/Local folder/Local file 1.odt'))
14:06:22 AssertionError: True is not false
14:06:22 -------------------- >> begin captured logging << --------------------
14:06:22 nxdrive.controller: DEBUG: nxdrive installed in '/home/hudson/tmp/workspace/IT-nuxeo-drive-5.6.0-linux/nuxeo-drive-client/nxdrive'
14:06:22 nxdrive.controller: DEBUG: nxdrive configured in '/tmp/tmpjtj05a-nxdrive-tests-user-1/nuxeo-drive-conf'
14:06:22 nxdrive.model: DEBUG: Set client version to 1.2-dev
14:06:22 nxdrive.controller: DEBUG: nxdrive installed in '/home/hudson/tmp/workspace/IT-nuxeo-drive-5.6.0-linux/nuxeo-drive-client/nxdrive'
14:06:22 nxdrive.controller: DEBUG: nxdrive configured in '/tmp/tmpitIufP-nxdrive-tests-user-2/nuxeo-drive-conf'
14:06:22 nxdrive.model: DEBUG: Set client version to 1.2-dev
14:06:22 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 []
14:06:22 nxdrive.client.base_automation_client: TRACE: Dumping JSON structure: {'params': {'value': u'/'}}
14:06:22 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": "/"}}'
14:06:22 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":"fcb7d93e-3797-4e38-8cfa-3386969f7ca0","path":"/","type":"Root","state":null,"versionLabel":"","title":"fcb7d93e-3797-4e38-8cfa-3386969f7ca0","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":{}}'
14:06:22 nxdrive.client.base_automation_client: TRACE: Dumping JSON structure: {'params': {'userNames': 'user_1, user_2', 'permission': 'ReadWrite'}}
14:06:22 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"}}'
14:06:22 nxdrive.client.base_automation_client: TRACE: Response for 'http://localhost:8080/nuxeo/site/automation/NuxeoDrive.SetupIntegrationTests' with cookies [] and content-type: 'text/plain'
14:06:22 nxdrive.client.base_automation_client: TRACE: Dumping JSON structure: {'params': {'value': u'/default-domain/workspaces/nuxeo-drive-test-workspace'}}
14:06:22 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"}}'
14:06:22 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":"27ed517b-a25e-4af0-8f30-177d5b22b7a4","path":"/default-domain/workspaces/nuxeo-drive-test-workspace","type":"Workspace","state":"project","versionLabel":"","title":"Nuxeo Drive Test Workspace","lastModified":"2013-12-12T13:05:33.29Z","properties":{"dc:creator":"Administrator","dc:source":null,"dc:contributors":["Administrator"],"dc:nature":null,"dc:created":"2013-12-12T13:05:33.29Z","dc:description":null,"dc:rights":null,"dc:subjects":[],"dc:publisher":null,"dc:valid":null,"dc:format":null,"dc:issued":null,"dc:modified":"2013-12-12T13:05:33.29Z","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":"1386853533297","contextParameters":{}}'
14:06:22 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 bnV4ZW9Ecml2ZVRlc3RVc2VyX3VzZXJfMTo2MDQwNmQ='} and cookies []
14:06:22 nxdrive.client.base_automation_client: TRACE: Dumping JSON structure: {'params': {'value': u'27ed517b-a25e-4af0-8f30-177d5b22b7a4'}}
14:06:22 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 bnV4ZW9Ecml2ZVRlc3RVc2VyX3VzZXJfMTo2MDQwNmQ='}, cookies [] and JSON payload '{"params": {"value": "27ed517b-a25e-4af0-8f30-177d5b22b7a4"}}'
14:06:22 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":"27ed517b-a25e-4af0-8f30-177d5b22b7a4","path":"/default-domain/workspaces/nuxeo-drive-test-workspace","type":"Workspace","state":"project","versionLabel":"","title":"Nuxeo Drive Test Workspace","lastModified":"2013-12-12T13:05:33.29Z","properties":{"dc:creator":"Administrator","dc:source":null,"dc:contributors":["Administrator"],"dc:nature":null,"dc:created":"2013-12-12T13:05:33.29Z","dc:description":null,"dc:rights":null,"dc:subjects":[],"dc:publisher":null,"dc:valid":null,"dc:format":null,"dc:issued":null,"dc:modified":"2013-12-12T13:05:33.29Z","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":"1386853533297","contextParameters":{}}'
14:06:22 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 bnV4ZW9Ecml2ZVRlc3RVc2VyX3VzZXJfMjo3YmE4ZjA='} and cookies []
14:06:22 nxdrive.client.base_automation_client: TRACE: Dumping JSON structure: {'params': {'value': u'27ed517b-a25e-4af0-8f30-177d5b22b7a4'}}
14:06:22 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 bnV4ZW9Ecml2ZVRlc3RVc2VyX3VzZXJfMjo3YmE4ZjA='}, cookies [] and JSON payload '{"params": {"value": "27ed517b-a25e-4af0-8f30-177d5b22b7a4"}}'
14:06:22 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":"27ed517b-a25e-4af0-8f30-177d5b22b7a4","path":"/default-domain/workspaces/nuxeo-drive-test-workspace","type":"Workspace","state":"project","versionLabel":"","title":"Nuxeo Drive Test Workspace","lastModified":"2013-12-12T13:05:33.29Z","properties":{"dc:creator":"Administrator","dc:source":null,"dc:contributors":["Administrator"],"dc:nature":null,"dc:created":"2013-12-12T13:05:33.29Z","dc:description":null,"dc:rights":null,"dc:subjects":[],"dc:publisher":null,"dc:valid":null,"dc:format":null,"dc:issued":null,"dc:modified":"2013-12-12T13:05:33.29Z","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":"1386853533297","contextParameters":{}}'
14:06:22 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 bnV4ZW9Ecml2ZVRlc3RVc2VyX3VzZXJfMTo2MDQwNmQ='} and cookies []
14:06:22 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 bnV4ZW9Ecml2ZVRlc3RVc2VyX3VzZXJfMjo3YmE4ZjA='} and cookies []
14:06:22 nxdrive.controller: DEBUG: nxdrive installed in '/home/hudson/tmp/workspace/IT-nuxeo-drive-5.6.0-linux/nuxeo-drive-client/nxdrive'
14:06:22 nxdrive.controller: DEBUG: nxdrive configured in '/tmp/tmpjtj05a-nxdrive-tests-user-1/nuxeo-drive-conf'
14:06:22 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'14a7e54c632e11e38f5e00219b008ad3', 'X-User-Id': u'nuxeoDriveTestUser_user_1', 'Cache-Control': 'no-cache', 'X-Application-Name': 'Nuxeo Drive', 'Authorization': 'Basic bnV4ZW9Ecml2ZVRlc3RVc2VyX3VzZXJfMTo2MDQwNmQ='} and cookies []
14:06:22 nxdrive.client.base_automation_client: TRACE: Calling http://localhost:8080/nuxeo/authentication/token?applicationName=Nuxeo+Drive&deviceDescription=Linux+Desktop&revoke=false&deviceId=14a7e54c632e11e38f5e00219b008ad3&permission=ReadWrite with headers {'X-Client-Version': '1.2-dev', 'X-Device-Id': u'14a7e54c632e11e38f5e00219b008ad3', 'X-User-Id': u'nuxeoDriveTestUser_user_1', 'Cache-Control': 'no-cache', 'X-Application-Name': 'Nuxeo Drive', 'Authorization': 'Basic bnV4ZW9Ecml2ZVRlc3RVc2VyX3VzZXJfMTo2MDQwNmQ='} and cookies []
14:06:22 nxdrive.client.base_automation_client: TRACE: Got token 'fcd4b71e-3976-454a-b7b0-d8d8e89ba55b' with cookies []
14:06:22 nxdrive.controller: INFO: Binding '/tmp/tmpjtj05a-nxdrive-tests-user-1/Nuxeo Drive' to 'http://localhost:8080/nuxeo/' with account 'nuxeoDriveTestUser_user_1'
14:06:22 nxdrive.client.base_automation_client: TRACE: Calling http://localhost:8080/nuxeo/site/automation/ with headers {'X-Authentication-Token': 'fcd4b71e-3976-454a-b7b0-d8d8e89ba55b', 'X-Device-Id': u'14a7e54c632e11e38f5e00219b008ad3', 'X-User-Id': u'nuxeoDriveTestUser_user_1', 'Cache-Control': 'no-cache', 'X-Application-Name': 'Nuxeo Drive', 'X-Client-Version': '1.2-dev'} and cookies []
14:06:22 nxdrive.client.base_automation_client: TRACE: Dumping JSON structure: {'params': {}}
14:06:22 nxdrive.client.base_automation_client: TRACE: Calling http://localhost:8080/nuxeo/site/automation/NuxeoDrive.GetTopLevelFolder with headers {'X-Authentication-Token': 'fcd4b71e-3976-454a-b7b0-d8d8e89ba55b', 'X-NXDocumentProperties': '*', 'X-Device-Id': u'14a7e54c632e11e38f5e00219b008ad3', '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": {}}'
14:06:22 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":460,"folder":true,"creator":"system","parentId":null,"lastModificationDate":460,"canRename":false,"canDelete":false,"userName":"nuxeoDriveTestUser_user_1"}'
14:06:22 nxdrive.model: TRACE: Updated state for LastKnownState<local_folder=u'/tmp/tmpjtj05a-nxdrive-tests-user-1/Nuxeo Drive', local_path=u'/', remote_name=None, local_state=None, remote_state=None, pair_state='unknown'>
14:06:22 nxdrive.model: TRACE: Use last known modification time to detect updates: local DB, server = 'None', '1970-01-01 01:00:00'
14:06:22 nxdrive.model: TRACE: last_remote_updated is None for doc None, set it to 1970-01-01 01:00:00
14:06:22 nxdrive.model: TRACE: Updated state for LastKnownState<local_folder=u'/tmp/tmpjtj05a-nxdrive-tests-user-1/Nuxeo Drive', local_path=u'/', remote_name=u'Nuxeo Drive', local_state='synchronized', remote_state='synchronized', pair_state='synchronized'>
14:06:22 nxdrive.client.base_automation_client: TRACE: Calling http://localhost:8080/nuxeo/site/automation/ with headers {'X-Authentication-Token': u'fcd4b71e-3976-454a-b7b0-d8d8e89ba55b', 'X-Device-Id': u'14a7e54c632e11e38f5e00219b008ad3', 'X-User-Id': u'nuxeoDriveTestUser_user_1', 'Cache-Control': 'no-cache', 'X-Application-Name': 'Nuxeo Drive', 'X-Client-Version': '1.2-dev'} and cookies []
14:06:22 nxdrive.client.base_automation_client: TRACE: Dumping JSON structure: {'input': u'doc:27ed517b-a25e-4af0-8f30-177d5b22b7a4', 'params': {'enable': True}}
14:06:22 nxdrive.client.base_automation_client: TRACE: Calling http://localhost:8080/nuxeo/site/automation/NuxeoDrive.SetSynchronization with headers {'X-Authentication-Token': u'fcd4b71e-3976-454a-b7b0-d8d8e89ba55b', 'X-NXDocumentProperties': '*', 'X-Device-Id': u'14a7e54c632e11e38f5e00219b008ad3', '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:27ed517b-a25e-4af0-8f30-177d5b22b7a4", "params": {"enable": true}}'
14:06:22 nxdrive.client.base_automation_client: TRACE: Response for 'http://localhost:8080/nuxeo/site/automation/NuxeoDrive.SetSynchronization' with cookies [] and content-type: ''
14:06:22 nxdrive.client.base_automation_client: TRACE: Dumping JSON structure: {'params': {}}
14:06:22 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": {}}'
14:06:22 nxdrive.client.base_automation_client: TRACE: Response for 'http://localhost:8080/nuxeo/site/automation/NuxeoDrive.WaitForAsyncCompletion' with cookies [] and content-type: ''
14:06:22 nxdrive.synchronizer: INFO: Starting synchronization (pid=26809)
14:06:22 nxdrive.client.base_automation_client: TRACE: Dumping JSON structure: {'params': {}}
14:06:22 nxdrive.client.base_automation_client: TRACE: Calling http://localhost:8080/nuxeo/site/automation/NuxeoDrive.GetChangeSummary with headers {'X-Authentication-Token': 'fcd4b71e-3976-454a-b7b0-d8d8e89ba55b', 'X-NXDocumentProperties': '*', 'X-Device-Id': u'14a7e54c632e11e38f5e00219b008ad3', '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": {}}'
14:06:22 nxdrive.client.base_automation_client: TRACE: Response for 'http://localhost:8080/nuxeo/site/automation/NuxeoDrive.GetChangeSummary' with cookies [] and JSON payload: '{"fileSystemChanges":[],"syncDate":1386853534000,"activeSynchronizationRootDefinitions":"default:27ed517b-a25e-4af0-8f30-177d5b22b7a4","hasTooManyChanges":false}'
14:06:22 nxdrive.synchronizer: DEBUG: Remote full scan of /tmp/tmpjtj05a-nxdrive-tests-user-1/Nuxeo Drive. Reasons: forced: False, too many changes: False, first pass: True
14:06:22 nxdrive.client.base_automation_client: TRACE: Dumping JSON structure: {'params': {'id': u'org.nuxeo.drive.service.impl.DefaultTopLevelFolderItemFactory#'}}
14:06:22 nxdrive.client.base_automation_client: TRACE: Calling http://localhost:8080/nuxeo/site/automation/NuxeoDrive.GetFileSystemItem with headers {'X-Authentication-Token': 'fcd4b71e-3976-454a-b7b0-d8d8e89ba55b', 'X-NXDocumentProperties': '*', 'X-Device-Id': u'14a7e54c632e11e38f5e00219b008ad3', '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#"}}'
14:06:22 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":706,"folder":true,"creator":"system","parentId":null,"lastModificationDate":706,"canRename":false,"canDelete":false,"userName":"nuxeoDriveTestUser_user_1"}'
14:06:22 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'
14:06:22 nxdrive.client.base_automation_client: TRACE: Dumping JSON structure: {'params': {'id': u'org.nuxeo.drive.service.impl.DefaultTopLevelFolderItemFactory#'}}
14:06:22 nxdrive.client.base_automation_client: TRACE: Calling http://localhost:8080/nuxeo/site/automation/NuxeoDrive.GetChildren with headers {'X-Authentication-Token': 'fcd4b71e-3976-454a-b7b0-d8d8e89ba55b', 'X-NXDocumentProperties': '*', 'X-Device-Id': u'14a7e54c632e11e38f5e00219b008ad3', '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#"}}'
14:06:22 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#27ed517b-a25e-4af0-8f30-177d5b22b7a4","path":"/org.nuxeo.drive.service.impl.DefaultTopLevelFolderItemFactory#/defaultSyncRootFolderItemFactory#default#27ed517b-a25e-4af0-8f30-177d5b22b7a4","creationDate":1386853533297,"folder":true,"creator":"Administrator","parentId":"org.nuxeo.drive.service.impl.DefaultTopLevelFolderItemFactory#","lastModificationDate":1386853533617,"canRename":true,"canDelete":true,"userName":"nuxeoDriveTestUser_user_1"}]'
14:06:22 nxdrive.model: TRACE: Selecting refs set([u'defaultSyncRootFolderItemFactory#default#27ed517b-a25e-4af0-8f30-177d5b22b7a4'])
14:06:22 nxdrive.model: TRACE: Use last known modification time to detect updates: local DB, server = 'None', '2013-12-12 14:05:33'
14:06:22 nxdrive.model: TRACE: last_remote_updated is None for doc None, set it to 2013-12-12 14:05:33
14:06:22 nxdrive.model: TRACE: Updated state for LastKnownState<local_folder=u'/tmp/tmpjtj05a-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'>
14:06:22 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'>
14:06:22 nxdrive.model: TRACE: Use last known modification time to detect updates: local DB, server = '2013-12-12 14:05:33', '2013-12-12 14:05:33'
14:06:22 nxdrive.client.base_automation_client: TRACE: Dumping JSON structure: {'params': {'id': u'defaultSyncRootFolderItemFactory#default#27ed517b-a25e-4af0-8f30-177d5b22b7a4'}}
14:06:22 nxdrive.client.base_automation_client: TRACE: Calling http://localhost:8080/nuxeo/site/automation/NuxeoDrive.GetChildren with headers {'X-Authentication-Token': 'fcd4b71e-3976-454a-b7b0-d8d8e89ba55b', 'X-NXDocumentProperties': '*', 'X-Device-Id': u'14a7e54c632e11e38f5e00219b008ad3', '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#27ed517b-a25e-4af0-8f30-177d5b22b7a4"}}'
14:06:22 nxdrive.client.base_automation_client: TRACE: Response for 'http://localhost:8080/nuxeo/site/automation/NuxeoDrive.GetChildren' with cookies [] and JSON payload: '[]'
14:06:22 nxdrive.model: TRACE: Selecting refs set([])
14:06:22 nxdrive.model: TRACE: Selecting paths set([])
14:06:22 nxdrive.client.base_automation_client: TRACE: Dumping JSON structure: {'params': {'id': u'defaultSyncRootFolderItemFactory#default#27ed517b-a25e-4af0-8f30-177d5b22b7a4'}}
14:06:22 nxdrive.client.base_automation_client: TRACE: Calling http://localhost:8080/nuxeo/site/automation/NuxeoDrive.GetFileSystemItem with headers {'X-Authentication-Token': 'fcd4b71e-3976-454a-b7b0-d8d8e89ba55b', 'X-NXDocumentProperties': '*', 'X-Device-Id': u'14a7e54c632e11e38f5e00219b008ad3', '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#27ed517b-a25e-4af0-8f30-177d5b22b7a4"}}'
14:06:22 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#27ed517b-a25e-4af0-8f30-177d5b22b7a4","path":"/org.nuxeo.drive.service.impl.DefaultTopLevelFolderItemFactory#/defaultSyncRootFolderItemFactory#default#27ed517b-a25e-4af0-8f30-177d5b22b7a4","creationDate":1386853533297,"folder":true,"creator":"Administrator","parentId":"org.nuxeo.drive.service.impl.DefaultTopLevelFolderItemFactory#","lastModificationDate":1386853533617,"canRename":true,"canDelete":true,"userName":"nuxeoDriveTestUser_user_1"}'
14:06:22 nxdrive.model: TRACE: Use last known modification time to detect updates: local DB, server = '2013-12-12 14:05:33', '2013-12-12 14:05:33'
14:06:22 nxdrive.model: TRACE: Updated state for LastKnownState<local_folder=u'/tmp/tmpjtj05a-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'>
14:06:22 nxdrive.synchronizer: DEBUG: Creating local folder 'Nuxeo Drive Test Workspace' in '/tmp/tmpjtj05a-nxdrive-tests-user-1/Nuxeo Drive/'
14:06:22 nxdrive.synchronizer: DEBUG: Remote recursive scan of the content of Nuxeo Drive Test Workspace
14:06:22 nxdrive.model: TRACE: Use last known modification time to detect updates: local DB, server = '2013-12-12 14:05:33', '2013-12-12 14:05:33'
14:06:22 nxdrive.client.base_automation_client: TRACE: Dumping JSON structure: {'params': {'id': u'defaultSyncRootFolderItemFactory#default#27ed517b-a25e-4af0-8f30-177d5b22b7a4'}}
14:06:22 nxdrive.client.base_automation_client: TRACE: Calling http://localhost:8080/nuxeo/site/automation/NuxeoDrive.GetChildren with headers {'X-Authentication-Token': 'fcd4b71e-3976-454a-b7b0-d8d8e89ba55b', 'X-NXDocumentProperties': '*', 'X-Device-Id': u'14a7e54c632e11e38f5e00219b008ad3', '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#27ed517b-a25e-4af0-8f30-177d5b22b7a4"}}'
14:06:22 nxdrive.client.base_automation_client: TRACE: Response for 'http://localhost:8080/nuxeo/site/automation/NuxeoDrive.GetChildren' with cookies [] and JSON payload: '[]'
14:06:22 nxdrive.model: TRACE: Selecting refs set([])
14:06:22 nxdrive.model: TRACE: Updated state for LastKnownState<local_folder=u'/tmp/tmpjtj05a-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'>
14:06:22 nxdrive.synchronizer: DEBUG: [/tmp/tmpjtj05a-nxdrive-tests-user-1/Nuxeo Drive] - [http://localhost:8080/nuxeo/]: synchronized: 1, pending: 1, local: 0.142s, remote: 0.312s sync: 0.373s
14:06:22 nxdrive.synchronizer: INFO: Stopping synchronization after 1 loops
14:06:22 nxdrive.client.base_automation_client: TRACE: Dumping JSON structure: {'input': u'doc:/default-domain/workspaces/nuxeo-drive-test-workspace/', 'params': {'type': 'Folder', 'name': 'Remote folder', 'properties': 'dc:title=Remote folder'}}
14:06:22 nxdrive.client.base_automation_client: TRACE: Calling http://localhost:8080/nuxeo/site/automation/Document.Create 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 bnV4ZW9Ecml2ZVRlc3RVc2VyX3VzZXJfMTo2MDQwNmQ='}, cookies [] and JSON payload '{"input": "doc:/default-domain/workspaces/nuxeo-drive-test-workspace/", "params": {"type": "Folder", "name": "Remote folder", "properties": "dc:title=Remote folder"}}'
14:06:22 nxdrive.client.base_automation_client: TRACE: Response for 'http://localhost:8080/nuxeo/site/automation/Document.Create' with cookies [] and JSON payload: '{"entity-type":"document","repository":"default","uid":"87e88128-77d0-4e3b-8ebe-169fc12798be","path":"/default-domain/workspaces/nuxeo-drive-test-workspace/Remote folder","type":"Folder","state":"project","versionLabel":"","title":"Remote folder","lastModified":"2013-12-12T13:05:35.52Z","properties":{"dc:creator":"nuxeoDriveTestUser_user_1","dc:source":null,"dc:contributors":["nuxeoDriveTestUser_user_1"],"dc:nature":null,"dc:created":"2013-12-12T13:05:35.52Z","dc:description":null,"dc:rights":null,"dc:subjects":[],"dc:publisher":null,"dc:valid":null,"dc:format":null,"dc:issued":null,"dc:modified":"2013-12-12T13:05:35.52Z","dc:expired":null,"dc:coverage":null,"dc:language":null,"dc:title":"Remote folder","dc:lastContributor":"nuxeoDriveTestUser_user_1","common:icon":"/icons/folder.gif","common:icon-expanded":null,"common:size":null},"facets":["Folderish"],"changeToken":"1386853535520","contextParameters":{}}'
14:06:22 nxdrive.client.base_automation_client: TRACE: Dumping JSON structure: {'input': u'doc:/default-domain/workspaces/nuxeo-drive-test-workspace/Remote folder', 'params': {'type': 'File', 'name': 'Remote file 1.odt', 'properties': 'dc:title=Remote file 1.odt'}}
14:06:22 nxdrive.client.base_automation_client: TRACE: Calling http://localhost:8080/nuxeo/site/automation/Document.Create 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 bnV4ZW9Ecml2ZVRlc3RVc2VyX3VzZXJfMTo2MDQwNmQ='}, cookies [] and JSON payload '{"input": "doc:/default-domain/workspaces/nuxeo-drive-test-workspace/Remote folder", "params": {"type": "File", "name": "Remote file 1.odt", "properties": "dc:title=Remote file 1.odt"}}'
14:06:22 nxdrive.client.base_automation_client: TRACE: Response for 'http://localhost:8080/nuxeo/site/automation/Document.Create' with cookies [] and JSON payload: '{"entity-type":"document","repository":"default","uid":"54eb3256-9555-4d4a-b508-14abcb684a12","path":"/default-domain/workspaces/nuxeo-drive-test-workspace/Remote folder/Remote file 1.odt","type":"File","state":"project","versionLabel":"0.0","title":"Remote file 1.odt","lastModified":"2013-12-12T13:05:35.53Z","properties":{"dc:creator":"nuxeoDriveTestUser_user_1","dc:source":null,"dc:contributors":["nuxeoDriveTestUser_user_1"],"dc:nature":null,"dc:created":"2013-12-12T13:05:35.53Z","dc:description":null,"dc:rights":null,"dc:subjects":[],"dc:publisher":null,"dc:valid":null,"dc:format":null,"dc:issued":null,"dc:modified":"2013-12-12T13:05:35.53Z","dc:expired":null,"dc:coverage":null,"dc:language":null,"dc:title":"Remote file 1.odt","dc:lastContributor":"nuxeoDriveTestUser_user_1","uid:uid":null,"uid:minor_version":"0","uid:major_version":"0","files:files":[],"common:icon":"/icons/file.gif","common:icon-expanded":null,"common:size":null,"file:content":null,"file:filename":null,"relatedtext:relatedtextresources":[]},"facets":["Downloadable","Commentable","Versionable","Publishable","HasRelatedText"],"changeToken":"1386853535532","contextParameters":{}}'
14:06:22 nxdrive.client.base_automation_client: TRACE: Using file system block size for the streaming upload buffer: 4096 bytes
14:06:22 nxdrive.client.base_automation_client: TRACE: Calling http://localhost:8080/nuxeo/site/automation/batch/upload with headers {'Content-Length': 13, 'X-Client-Version': '1.2-dev', 'X-Device-Id': u'nxdrive-test-device-1', 'X-File-Type': 'application/vnd.oasis.opendocument.text', 'X-User-Id': u'nuxeoDriveTestUser_user_1', 'X-File-Size': 13, 'Authorization': 'Basic bnV4ZW9Ecml2ZVRlc3RVc2VyX3VzZXJfMTo2MDQwNmQ=', 'Cache-Control': 'no-cache', 'X-Batch-Id': '1386853535.54_392939575', 'X-File-Idx': 0, 'Content-Type': 'application/octet-stream', 'X-Application-Name': 'Nuxeo Drive', 'X-File-Name': 'Remote%20file%201.odt'} and cookies [] for file /tmp/tmp_reLa5-nxdrive-uploads/tmp8iZ21D-nxdrive-file-to-upload
14:06:22 nxdrive.client.base_automation_client: TRACE: Response for 'http://localhost:8080/nuxeo/site/automation/batch/upload' with cookies [] and JSON payload: '{"uploaded":"true","batchId":"1386853535.54_392939575"}'
14:06:22 nxdrive.client.base_automation_client: TRACE: Dumping JSON structure: {'params': {'batchId': '1386853535.54_392939575', 'document': u'54eb3256-9555-4d4a-b508-14abcb684a12', 'fileIdx': '0', 'operationId': 'Blob.Attach'}}
14:06:22 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 bnV4ZW9Ecml2ZVRlc3RVc2VyX3VzZXJfMTo2MDQwNmQ='}, cookies [] and JSON payload '{"params": {"batchId": "1386853535.54_392939575", "document": "54eb3256-9555-4d4a-b508-14abcb684a12", "fileIdx": "0", "operationId": "Blob.Attach"}}'
14:06:22 nxdrive.client.base_automation_client: TRACE: Response for 'http://localhost:8080/nuxeo/site/automation/batch/execute' with cookies [] and content-type: 'application/vnd.oasis.opendocument.text'
14:06:22 nxdrive.client.base_automation_client: TRACE: Dumping JSON structure: {'input': u'doc:/default-domain/workspaces/nuxeo-drive-test-workspace/Remote folder', 'params': {'type': 'File', 'name': 'Remote file 2.odt', 'properties': 'dc:title=Remote file 2.odt'}}
14:06:22 nxdrive.client.base_automation_client: TRACE: Calling http://localhost:8080/nuxeo/site/automation/Document.Create 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 bnV4ZW9Ecml2ZVRlc3RVc2VyX3VzZXJfMTo2MDQwNmQ='}, cookies [] and JSON payload '{"input": "doc:/default-domain/workspaces/nuxeo-drive-test-workspace/Remote folder", "params": {"type": "File", "name": "Remote file 2.odt", "properties": "dc:title=Remote file 2.odt"}}'
14:06:22 nxdrive.client.base_automation_client: TRACE: Response for 'http://localhost:8080/nuxeo/site/automation/Document.Create' with cookies [] and JSON payload: '{"entity-type":"document","repository":"default","uid":"cc962f68-f598-4528-9ea2-ed26d810b9ed","path":"/default-domain/workspaces/nuxeo-drive-test-workspace/Remote folder/Remote file 2.odt","type":"File","state":"project","versionLabel":"0.0","title":"Remote file 2.odt","lastModified":"2013-12-12T13:05:35.55Z","properties":{"dc:creator":"nuxeoDriveTestUser_user_1","dc:source":null,"dc:contributors":["nuxeoDriveTestUser_user_1"],"dc:nature":null,"dc:created":"2013-12-12T13:05:35.55Z","dc:description":null,"dc:rights":null,"dc:subjects":[],"dc:publisher":null,"dc:valid":null,"dc:format":null,"dc:issued":null,"dc:modified":"2013-12-12T13:05:35.55Z","dc:expired":null,"dc:coverage":null,"dc:language":null,"dc:title":"Remote file 2.odt","dc:lastContributor":"nuxeoDriveTestUser_user_1","uid:uid":null,"uid:minor_version":"0","uid:major_version":"0","files:files":[],"common:icon":"/icons/file.gif","common:icon-expanded":null,"common:size":null,"file:content":null,"file:filename":null,"relatedtext:relatedtextresources":[]},"facets":["Downloadable","Commentable","Versionable","Publishable","HasRelatedText"],"changeToken":"1386853535558","contextParameters":{}}'
14:06:22 nxdrive.client.base_automation_client: TRACE: Using file system block size for the streaming upload buffer: 4096 bytes
14:06:22 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': 'application/vnd.oasis.opendocument.text', 'X-User-Id': u'nuxeoDriveTestUser_user_1', 'X-File-Size': 14, 'Authorization': 'Basic bnV4ZW9Ecml2ZVRlc3RVc2VyX3VzZXJfMTo2MDQwNmQ=', 'Cache-Control': 'no-cache', 'X-Batch-Id': '1386853535.57_573516206', 'X-File-Idx': 0, 'Content-Type': 'application/octet-stream', 'X-Application-Name': 'Nuxeo Drive', 'X-File-Name': 'Remote%20file%202.odt'} and cookies [] for file /tmp/tmp_reLa5-nxdrive-uploads/tmpp690gG-nxdrive-file-to-upload
14:06:22 nxdrive.client.base_automation_client: TRACE: Response for 'http://localhost:8080/nuxeo/site/automation/batch/upload' with cookies [] and JSON payload: '{"uploaded":"true","batchId":"1386853535.57_573516206"}'
14:06:22 nxdrive.client.base_automation_client: TRACE: Dumping JSON structure: {'params': {'batchId': '1386853535.57_573516206', 'document': u'cc962f68-f598-4528-9ea2-ed26d810b9ed', 'fileIdx': '0', 'operationId': 'Blob.Attach'}}
14:06:22 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 bnV4ZW9Ecml2ZVRlc3RVc2VyX3VzZXJfMTo2MDQwNmQ='}, cookies [] and JSON payload '{"params": {"batchId": "1386853535.57_573516206", "document": "cc962f68-f598-4528-9ea2-ed26d810b9ed", "fileIdx": "0", "operationId": "Blob.Attach"}}'
14:06:22 nxdrive.client.base_automation_client: TRACE: Response for 'http://localhost:8080/nuxeo/site/automation/batch/execute' with cookies [] and content-type: 'application/vnd.oasis.opendocument.text'
14:06:22 nxdrive.client.base_automation_client: TRACE: Dumping JSON structure: {'params': {}}
14:06:22 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": {}}'
14:06:22 nxdrive.client.base_automation_client: TRACE: Response for 'http://localhost:8080/nuxeo/site/automation/NuxeoDrive.WaitForAsyncCompletion' with cookies [] and content-type: ''
14:06:22 nxdrive.synchronizer: INFO: Starting synchronization (pid=26809)
14:06:22 nxdrive.client.base_automation_client: TRACE: Dumping JSON structure: {'params': {'lastSyncDate': 1386853534000L, 'lastSyncActiveRootDefinitions': u'default:27ed517b-a25e-4af0-8f30-177d5b22b7a4'}}
14:06:22 nxdrive.client.base_automation_client: TRACE: Calling http://localhost:8080/nuxeo/site/automation/NuxeoDrive.GetChangeSummary with headers {'X-Authentication-Token': 'fcd4b71e-3976-454a-b7b0-d8d8e89ba55b', 'X-NXDocumentProperties': '*', 'X-Device-Id': u'14a7e54c632e11e38f5e00219b008ad3', '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": 1386853534000, "lastSyncActiveRootDefinitions": "default:27ed517b-a25e-4af0-8f30-177d5b22b7a4"}}'
14:06:22 nxdrive.client.base_automation_client: TRACE: Response for 'http://localhost:8080/nuxeo/site/automation/NuxeoDrive.GetChangeSummary' with cookies [] and JSON payload: '{"fileSystemChanges":[{"eventId":"documentModified","eventDate":1386853535580,"repositoryId":"default","fileSystemItem":{"digest":"39181c49e54b6e5ae8be13ab66f9fdb8","digestAlgorithm":"md5","downloadURL":"nxbigfile/default/cc962f68-f598-4528-9ea2-ed26d810b9ed/blobholder:0/Remote%20file%202.odt","canUpdate":true,"name":"Remote file 2.odt","id":"defaultFileSystemItemFactory#default#cc962f68-f598-4528-9ea2-ed26d810b9ed","path":"/org.nuxeo.drive.service.impl.DefaultTopLevelFolderItemFactory#/defaultSyncRootFolderItemFactory#default#27ed517b-a25e-4af0-8f30-177d5b22b7a4/defaultFileSystemItemFactory#default#87e88128-77d0-4e3b-8ebe-169fc12798be/defaultFileSystemItemFactory#default#cc962f68-f598-4528-9ea2-ed26d810b9ed","creationDate":1386853535558,"folder":false,"creator":"nuxeoDriveTestUser_user_1","parentId":"defaultFileSystemItemFactory#default#87e88128-77d0-4e3b-8ebe-169fc12798be","lastModificationDate":1386853535578,"canRename":true,"canDelete":true,"userName":"nuxeoDriveTestUser_user_1"},"fileSystemItemId":"defaultFileSystemItemFactory#default#cc962f68-f598-4528-9ea2-ed26d810b9ed","fileSystemItemName":"Remote file 2.odt","docUuid":"cc962f68-f598-4528-9ea2-ed26d810b9ed"},{"eventId":"documentCreated","eventDate":1386853535558,"repositoryId":"default","fileSystemItem":{"digest":"39181c49e54b6e5ae8be13ab66f9fdb8","digestAlgorithm":"md5","downloadURL":"nxbigfile/default/cc962f68-f598-4528-9ea2-ed26d810b9ed/blobholder:0/Remote%20file%202.odt","canUpdate":true,"name":"Remote file 2.odt","id":"defaultFileSystemItemFactory#default#cc962f68-f598-4528-9ea2-ed26d810b9ed","path":"/org.nuxeo.drive.service.impl.DefaultTopLevelFolderItemFactory#/defaultSyncRootFolderItemFactory#default#27ed517b-a25e-4af0-8f30-177d5b22b7a4/defaultFileSystemItemFactory#default#87e88128-77d0-4e3b-8ebe-169fc12798be/defaultFileSystemItemFactory#default#cc962f68-f598-4528-9ea2-ed26d810b9ed","creationDate":1386853535558,"folder":false,"creator":"nuxeoDriveTestUser_user_1","parentId":"defaultFileSystemItemFactory#default#87e88128-77d0-4e3b-8ebe-169fc12798be","lastModificationDate":1386853535578,"canRename":true,"canDelete":true,"userName":"nuxeoDriveTestUser_user_1"},"fileSystemItemId":"defaultFileSystemItemFactory#default#cc962f68-f598-4528-9ea2-ed26d810b9ed","fileSystemItemName":"Remote file 2.odt","docUuid":"cc962f68-f598-4528-9ea2-ed26d810b9ed"},{"eventId":"documentModified","eventDate":1386853535549,"repositoryId":"default","fileSystemItem":{"digest":"b8ab309a6b9a3f448092a136afa8fa25","digestAlgorithm":"md5","downloadURL":"nxbigfile/default/54eb3256-9555-4d4a-b508-14abcb684a12/blobholder:0/Remote%20file%201.odt","canUpdate":true,"name":"Remote file 1.odt","id":"defaultFileSystemItemFactory#default#54eb3256-9555-4d4a-b508-14abcb684a12","path":"/org.nuxeo.drive.service.impl.DefaultTopLevelFolderItemFactory#/defaultSyncRootFolderItemFactory#default#27ed517b-a25e-4af0-8f30-177d5b22b7a4/defaultFileSystemItemFactory#default#87e88128-77d0-4e3b-8ebe-169fc12798be/defaultFileSystemItemFactory#default#54eb3256-9555-4d4a-b508-14abcb684a12","creationDate":1386853535532,"folder":false,"creator":"nuxeoDriveTestUser_user_1","parentId":"defaultFileSystemItemFactory#default#87e88128-77d0-4e3b-8ebe-169fc12798be","lastModificationDate":1386853535547,"canRename":true,"canDelete":true,"userName":"nuxeoDriveTestUser_user_1"},"fileSystemItemId":"defaultFileSystemItemFactory#default#54eb3256-9555-4d4a-b508-14abcb684a12","fileSystemItemName":"Remote file 1.odt","docUuid":"54eb3256-9555-4d4a-b508-14abcb684a12"},{"eventId":"documentCreated","eventDate":1386853535532,"repositoryId":"default","fileSystemItem":{"digest":"b8ab309a6b9a3f448092a136afa8fa25","digestAlgorithm":"md5","downloadURL":"nxbigfile/default/54eb3256-9555-4d4a-b508-14abcb684a12/blobholder:0/Remote%20file%201.odt","canUpdate":true,"name":"Remote file 1.odt","id":"defaultFileSystemItemFactory#default#54eb3256-9555-4d4a-b508-14abcb684a12","path":"/org.nuxeo.drive.service.impl.DefaultTopLevelFolderItemFactory#/defaultSyncRootFolderItemFactory#default#27ed517b-a25e-4af0-8f30-177d5b22b7a4/defaultFileSystemItemFactory#default#87e88128-77d0-4e3b-8ebe-169fc12798be/defaultFileSystemItemFactory#default#54eb3256-9555-4d4a-b508-14abcb684a12","creationDate":1386853535532,"folder":false,"creator":"nuxeoDriveTestUser_user_1","parentId":"defaultFileSystemItemFactory#default#87e88128-77d0-4e3b-8ebe-169fc12798be","lastModificationDate":1386853535547,"canRename":true,"canDelete":true,"userName":"nuxeoDriveTestUser_user_1"},"fileSystemItemId":"defaultFileSystemItemFactory#default#54eb3256-9555-4d4a-b508-14abcb684a12","fileSystemItemName":"Remote file 1.odt","docUuid":"54eb3256-9555-4d4a-b508-14abcb684a12"},{"eventId":"documentCreated","eventDate":1386853535520,"repositoryId":"default","fileSystemItem":{"canCreateChild":true,"name":"Remote folder","id":"defaultFileSystemItemFactory#default#87e88128-77d0-4e3b-8ebe-169fc12798be","path":"/org.nuxeo.drive.service.impl.DefaultTopLevelFolderItemFactory#/defaultSyncRootFolderItemFactory#default#27ed517b-a25e-4af0-8f30-177d5b22b7a4/defaultFileSystemItemFactory#default#87e88128-77d0-4e3b-8ebe-169fc12798be","creationDate":1386853535520,"folder":true,"creator":"nuxeoDriveTestUser_user_1","parentId":"defaultSyncRootFolderItemFactory#default#27ed517b-a25e-4af0-8f30-177d5b22b7a4","lastModificationDate":1386853535520,"canRename":true,"canDelete":true,"userName":"nuxeoDriveTestUser_user_1"},"fileSystemItemId":"defaultFileSystemItemFactory#default#87e88128-77d0-4e3b-8ebe-169fc12798be","fileSystemItemName":"Remote folder","docUuid":"87e88128-77d0-4e3b-8ebe-169fc12798be"}],"syncDate":1386853536000,"activeSynchronizationRootDefinitions":"default:27ed517b-a25e-4af0-8f30-177d5b22b7a4","hasTooManyChanges":false}'
14:06:22 nxdrive.synchronizer: DEBUG: 5 remote changes detected on http://localhost:8080/nuxeo/
14:06:22 nxdrive.synchronizer: WARNING: Could not match changed document to a bound local folder: RemoteFileInfo(name=u'Remote file 2.odt', uid=u'defaultFileSystemItemFactory#default#cc962f68-f598-4528-9ea2-ed26d810b9ed', parent_uid=u'defaultFileSystemItemFactory#default#87e88128-77d0-4e3b-8ebe-169fc12798be', path=u'/org.nuxeo.drive.service.impl.DefaultTopLevelFolderItemFactory#/defaultSyncRootFolderItemFactory#default#27ed517b-a25e-4af0-8f30-177d5b22b7a4/defaultFileSystemItemFactory#default#87e88128-77d0-4e3b-8ebe-169fc12798be/defaultFileSystemItemFactory#default#cc962f68-f598-4528-9ea2-ed26d810b9ed', folderish=False, last_modification_time=datetime.datetime(2013, 12, 12, 14, 5, 35), digest=u'39181c49e54b6e5ae8be13ab66f9fdb8', digest_algorithm=u'md5', download_url=u'nxbigfile/default/cc962f68-f598-4528-9ea2-ed26d810b9ed/blobholder:0/Remote%20file%202.odt', can_rename=True, can_delete=True, can_update=True, can_create_child=False)
14:06:22 nxdrive.synchronizer: WARNING: Could not match changed document to a bound local folder: RemoteFileInfo(name=u'Remote file 2.odt', uid=u'defaultFileSystemItemFactory#default#cc962f68-f598-4528-9ea2-ed26d810b9ed', parent_uid=u'defaultFileSystemItemFactory#default#87e88128-77d0-4e3b-8ebe-169fc12798be', path=u'/org.nuxeo.drive.service.impl.DefaultTopLevelFolderItemFactory#/defaultSyncRootFolderItemFactory#default#27ed517b-a25e-4af0-8f30-177d5b22b7a4/defaultFileSystemItemFactory#default#87e88128-77d0-4e3b-8ebe-169fc12798be/defaultFileSystemItemFactory#default#cc962f68-f598-4528-9ea2-ed26d810b9ed', folderish=False, last_modification_time=datetime.datetime(2013, 12, 12, 14, 5, 35), digest=u'39181c49e54b6e5ae8be13ab66f9fdb8', digest_algorithm=u'md5', download_url=u'nxbigfile/default/cc962f68-f598-4528-9ea2-ed26d810b9ed/blobholder:0/Remote%20file%202.odt', can_rename=True, can_delete=True, can_update=True, can_create_child=False)
14:06:22 nxdrive.synchronizer: WARNING: Could not match changed document to a bound local folder: RemoteFileInfo(name=u'Remote file 1.odt', uid=u'defaultFileSystemItemFactory#default#54eb3256-9555-4d4a-b508-14abcb684a12', parent_uid=u'defaultFileSystemItemFactory#default#87e88128-77d0-4e3b-8ebe-169fc12798be', path=u'/org.nuxeo.drive.service.impl.DefaultTopLevelFolderItemFactory#/defaultSyncRootFolderItemFactory#default#27ed517b-a25e-4af0-8f30-177d5b22b7a4/defaultFileSystemItemFactory#default#87e88128-77d0-4e3b-8ebe-169fc12798be/defaultFileSystemItemFactory#default#54eb3256-9555-4d4a-b508-14abcb684a12', folderish=False, last_modification_time=datetime.datetime(2013, 12, 12, 14, 5, 35), digest=u'b8ab309a6b9a3f448092a136afa8fa25', digest_algorithm=u'md5', download_url=u'nxbigfile/default/54eb3256-9555-4d4a-b508-14abcb684a12/blobholder:0/Remote%20file%201.odt', can_rename=True, can_delete=True, can_update=True, can_create_child=False)
14:06:22 nxdrive.synchronizer: WARNING: Could not match changed document to a bound local folder: RemoteFileInfo(name=u'Remote file 1.odt', uid=u'defaultFileSystemItemFactory#default#54eb3256-9555-4d4a-b508-14abcb684a12', parent_uid=u'defaultFileSystemItemFactory#default#87e88128-77d0-4e3b-8ebe-169fc12798be', path=u'/org.nuxeo.drive.service.impl.DefaultTopLevelFolderItemFactory#/defaultSyncRootFolderItemFactory#default#27ed517b-a25e-4af0-8f30-177d5b22b7a4/defaultFileSystemItemFactory#default#87e88128-77d0-4e3b-8ebe-169fc12798be/defaultFileSystemItemFactory#default#54eb3256-9555-4d4a-b508-14abcb684a12', folderish=False, last_modification_time=datetime.datetime(2013, 12, 12, 14, 5, 35), digest=u'b8ab309a6b9a3f448092a136afa8fa25', digest_algorithm=u'md5', download_url=u'nxbigfile/default/54eb3256-9555-4d4a-b508-14abcb684a12/blobholder:0/Remote%20file%201.odt', can_rename=True, can_delete=True, can_update=True, can_create_child=False)
14:06:22 nxdrive.model: TRACE: Use last known modification time to detect updates: local DB, server = 'None', '2013-12-12 14:05:35'
14:06:22 nxdrive.model: TRACE: last_remote_updated is None for doc None, set it to 2013-12-12 14:05:35
14:06:22 nxdrive.model: TRACE: Updated state for LastKnownState<local_folder=u'/tmp/tmpjtj05a-nxdrive-tests-user-1/Nuxeo Drive', local_path=None, remote_name=u'Remote folder', local_state=None, remote_state=None, pair_state='unknown'>
14:06:22 nxdrive.synchronizer: TRACE: Created new pair LastKnownState<local_folder=u'Nuxeo Drive', local_path=None, remote_name=u'Remote folder', local_state='unknown', remote_state='unknown', pair_state='unknown'>
14:06:22 nxdrive.synchronizer: DEBUG: Marked doc_pair 'Remote folder' as remote creation
14:06:22 nxdrive.synchronizer: DEBUG: Remote recursive scan of the content of Remote folder
14:06:22 nxdrive.model: TRACE: Use last known modification time to detect updates: local DB, server = '2013-12-12 14:05:35', '2013-12-12 14:05:35'
14:06:22 nxdrive.client.base_automation_client: TRACE: Dumping JSON structure: {'params': {'id': u'defaultFileSystemItemFactory#default#87e88128-77d0-4e3b-8ebe-169fc12798be'}}
14:06:22 nxdrive.client.base_automation_client: TRACE: Calling http://localhost:8080/nuxeo/site/automation/NuxeoDrive.GetChildren with headers {'X-Authentication-Token': 'fcd4b71e-3976-454a-b7b0-d8d8e89ba55b', 'X-NXDocumentProperties': '*', 'X-Device-Id': u'14a7e54c632e11e38f5e00219b008ad3', '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#87e88128-77d0-4e3b-8ebe-169fc12798be"}}'
14:06:22 nxdrive.client.base_automation_client: TRACE: Response for 'http://localhost:8080/nuxeo/site/automation/NuxeoDrive.GetChildren' with cookies [] and JSON payload: '[{"digest":"b8ab309a6b9a3f448092a136afa8fa25","digestAlgorithm":"md5","downloadURL":"nxbigfile/default/54eb3256-9555-4d4a-b508-14abcb684a12/blobholder:0/Remote%20file%201.odt","canUpdate":true,"name":"Remote file 1.odt","id":"defaultFileSystemItemFactory#default#54eb3256-9555-4d4a-b508-14abcb684a12","path":"/org.nuxeo.drive.service.impl.DefaultTopLevelFolderItemFactory#/defaultSyncRootFolderItemFactory#default#27ed517b-a25e-4af0-8f30-177d5b22b7a4/defaultFileSystemItemFactory#default#87e88128-77d0-4e3b-8ebe-169fc12798be/defaultFileSystemItemFactory#default#54eb3256-9555-4d4a-b508-14abcb684a12","creationDate":1386853535532,"folder":false,"creator":"nuxeoDriveTestUser_user_1","parentId":"defaultFileSystemItemFactory#default#87e88128-77d0-4e3b-8ebe-169fc12798be","lastModificationDate":1386853535547,"canRename":true,"canDelete":true,"userName":"nuxeoDriveTestUser_user_1"},{"digest":"39181c49e54b6e5ae8be13ab66f9fdb8","digestAlgorithm":"md5","downloadURL":"nxbigfile/default/cc962f68-f598-4528-9ea2-ed26d810b9ed/blobholder:0/Remote%20file%202.odt","canUpdate":true,"name":"Remote file 2.odt","id":"defaultFileSystemItemFactory#default#cc962f68-f598-4528-9ea2-ed26d810b9ed","path":"/org.nuxeo.drive.service.impl.DefaultTopLevelFolderItemFactory#/defaultSyncRootFolderItemFactory#default#27ed517b-a25e-4af0-8f30-177d5b22b7a4/defaultFileSystemItemFactory#default#87e88128-77d0-4e3b-8ebe-169fc12798be/defaultFileSystemItemFactory#default#cc962f68-f598-4528-9ea2-ed26d810b9ed","creationDate":1386853535558,"folder":false,"creator":"nuxeoDriveTestUser_user_1","parentId":"defaultFileSystemItemFactory#default#87e88128-77d0-4e3b-8ebe-169fc12798be","lastModificationDate":1386853535578,"canRename":true,"canDelete":true,"userName":"nuxeoDriveTestUser_user_1"}]'
14:06:22 nxdrive.model: TRACE: Selecting refs set([u'defaultFileSystemItemFactory#default#cc962f68-f598-4528-9ea2-ed26d810b9ed', u'defaultFileSystemItemFactory#default#54eb3256-9555-4d4a-b508-14abcb684a12'])
14:06:22 nxdrive.model: TRACE: Use last known modification time to detect updates: local DB, server = 'None', '2013-12-12 14:05:35'
14:06:22 nxdrive.model: TRACE: last_remote_updated is None for doc None, set it to 2013-12-12 14:05:35
14:06:22 nxdrive.model: TRACE: Updated state for LastKnownState<local_folder=u'/tmp/tmpjtj05a-nxdrive-tests-user-1/Nuxeo Drive', local_path=None, remote_name=u'Remote file 1.odt', local_state=None, remote_state=None, pair_state='unknown'>
14:06:22 nxdrive.synchronizer: TRACE: Created new pair LastKnownState<local_folder=u'Nuxeo Drive', local_path=None, remote_name=u'Remote file 1.odt', local_state='unknown', remote_state='unknown', pair_state='unknown'>
14:06:22 nxdrive.model: TRACE: Use last known modification time to detect updates: local DB, server = '2013-12-12 14:05:35', '2013-12-12 14:05:35'
14:06:22 nxdrive.model: TRACE: Use last known modification time to detect updates: local DB, server = 'None', '2013-12-12 14:05:35'
14:06:22 nxdrive.model: TRACE: last_remote_updated is None for doc None, set it to 2013-12-12 14:05:35
14:06:22 nxdrive.model: TRACE: Updated state for LastKnownState<local_folder=u'/tmp/tmpjtj05a-nxdrive-tests-user-1/Nuxeo Drive', local_path=None, remote_name=u'Remote file 2.odt', local_state=None, remote_state=None, pair_state='unknown'>
14:06:22 nxdrive.synchronizer: TRACE: Created new pair LastKnownState<local_folder=u'Nuxeo Drive', local_path=None, remote_name=u'Remote file 2.odt', local_state='unknown', remote_state='unknown', pair_state='unknown'>
14:06:22 nxdrive.model: TRACE: Use last known modification time to detect updates: local DB, server = '2013-12-12 14:05:35', '2013-12-12 14:05:35'
14:06:22 nxdrive.model: TRACE: Selecting paths set([u'/Nuxeo Drive Test Workspace'])
14:06:22 nxdrive.model: TRACE: Selecting paths set([])
14:06:22 nxdrive.client.base_automation_client: TRACE: Dumping JSON structure: {'params': {'id': u'defaultFileSystemItemFactory#default#87e88128-77d0-4e3b-8ebe-169fc12798be'}}
14:06:22 nxdrive.client.base_automation_client: TRACE: Calling http://localhost:8080/nuxeo/site/automation/NuxeoDrive.GetFileSystemItem with headers {'X-Authentication-Token': 'fcd4b71e-3976-454a-b7b0-d8d8e89ba55b', 'X-NXDocumentProperties': '*', 'X-Device-Id': u'14a7e54c632e11e38f5e00219b008ad3', '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#87e88128-77d0-4e3b-8ebe-169fc12798be"}}'
14:06:22 nxdrive.client.base_automation_client: TRACE: Response for 'http://localhost:8080/nuxeo/site/automation/NuxeoDrive.GetFileSystemItem' with cookies [] and JSON payload: '{"canCreateChild":true,"name":"Remote folder","id":"defaultFileSystemItemFactory#default#87e88128-77d0-4e3b-8ebe-169fc12798be","path":"/org.nuxeo.drive.service.impl.DefaultTopLevelFolderItemFactory#/defaultSyncRootFolderItemFactory#default#27ed517b-a25e-4af0-8f30-177d5b22b7a4/defaultFileSystemItemFactory#default#87e88128-77d0-4e3b-8ebe-169fc12798be","creationDate":1386853535520,"folder":true,"creator":"nuxeoDriveTestUser_user_1","parentId":"defaultSyncRootFolderItemFactory#default#27ed517b-a25e-4af0-8f30-177d5b22b7a4","lastModificationDate":1386853535520,"canRename":true,"canDelete":true,"userName":"nuxeoDriveTestUser_user_1"}'
14:06:22 nxdrive.model: TRACE: Use last known modification time to detect updates: local DB, server = '2013-12-12 14:05:35', '2013-12-12 14:05:35'
14:06:22 nxdrive.model: TRACE: Updated state for LastKnownState<local_folder=u'/tmp/tmpjtj05a-nxdrive-tests-user-1/Nuxeo Drive', local_path=None, remote_name=u'Remote folder', local_state=u'unknown', remote_state='created', pair_state='remotely_created'>
14:06:22 nxdrive.synchronizer: DEBUG: Creating local folder 'Remote folder' in '/tmp/tmpjtj05a-nxdrive-tests-user-1/Nuxeo Drive/Nuxeo Drive Test Workspace'
14:06:22 nxdrive.synchronizer: DEBUG: Remote recursive scan of the content of Remote folder
14:06:22 nxdrive.model: TRACE: Use last known modification time to detect updates: local DB, server = '2013-12-12 14:05:35', '2013-12-12 14:05:35'
14:06:22 nxdrive.client.base_automation_client: TRACE: Dumping JSON structure: {'params': {'id': u'defaultFileSystemItemFactory#default#87e88128-77d0-4e3b-8ebe-169fc12798be'}}
14:06:22 nxdrive.client.base_automation_client: TRACE: Calling http://localhost:8080/nuxeo/site/automation/NuxeoDrive.GetChildren with headers {'X-Authentication-Token': 'fcd4b71e-3976-454a-b7b0-d8d8e89ba55b', 'X-NXDocumentProperties': '*', 'X-Device-Id': u'14a7e54c632e11e38f5e00219b008ad3', '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#87e88128-77d0-4e3b-8ebe-169fc12798be"}}'
14:06:22 nxdrive.client.base_automation_client: TRACE: Response for 'http://localhost:8080/nuxeo/site/automation/NuxeoDrive.GetChildren' with cookies [] and JSON payload: '[{"digest":"b8ab309a6b9a3f448092a136afa8fa25","digestAlgorithm":"md5","downloadURL":"nxbigfile/default/54eb3256-9555-4d4a-b508-14abcb684a12/blobholder:0/Remote%20file%201.odt","canUpdate":true,"name":"Remote file 1.odt","id":"defaultFileSystemItemFactory#default#54eb3256-9555-4d4a-b508-14abcb684a12","path":"/org.nuxeo.drive.service.impl.DefaultTopLevelFolderItemFactory#/defaultSyncRootFolderItemFactory#default#27ed517b-a25e-4af0-8f30-177d5b22b7a4/defaultFileSystemItemFactory#default#87e88128-77d0-4e3b-8ebe-169fc12798be/defaultFileSystemItemFactory#default#54eb3256-9555-4d4a-b508-14abcb684a12","creationDate":1386853535532,"folder":false,"creator":"nuxeoDriveTestUser_user_1","parentId":"defaultFileSystemItemFactory#default#87e88128-77d0-4e3b-8ebe-169fc12798be","lastModificationDate":1386853535547,"canRename":true,"canDelete":true,"userName":"nuxeoDriveTestUser_user_1"},{"digest":"39181c49e54b6e5ae8be13ab66f9fdb8","digestAlgorithm":"md5","downloadURL":"nxbigfile/default/cc962f68-f598-4528-9ea2-ed26d810b9ed/blobholder:0/Remote%20file%202.odt","canUpdate":true,"name":"Remote file 2.odt","id":"defaultFileSystemItemFactory#default#cc962f68-f598-4528-9ea2-ed26d810b9ed","path":"/org.nuxeo.drive.service.impl.DefaultTopLevelFolderItemFactory#/defaultSyncRootFolderItemFactory#default#27ed517b-a25e-4af0-8f30-177d5b22b7a4/defaultFileSystemItemFactory#default#87e88128-77d0-4e3b-8ebe-169fc12798be/defaultFileSystemItemFactory#default#cc962f68-f598-4528-9ea2-ed26d810b9ed","creationDate":1386853535558,"folder":false,"creator":"nuxeoDriveTestUser_user_1","parentId":"defaultFileSystemItemFactory#default#87e88128-77d0-4e3b-8ebe-169fc12798be","lastModificationDate":1386853535578,"canRename":true,"canDelete":true,"userName":"nuxeoDriveTestUser_user_1"}]'
14:06:22 nxdrive.model: TRACE: Selecting refs set([u'defaultFileSystemItemFactory#default#cc962f68-f598-4528-9ea2-ed26d810b9ed', u'defaultFileSystemItemFactory#default#54eb3256-9555-4d4a-b508-14abcb684a12'])
14:06:22 nxdrive.model: TRACE: Updated state for LastKnownState<local_folder=u'/tmp/tmpjtj05a-nxdrive-tests-user-1/Nuxeo Drive', local_path=u'/Nuxeo Drive Test Workspace/Remote folder', remote_name=u'Remote folder', local_state='synchronized', remote_state='synchronized', pair_state='synchronized'>
14:06:22 nxdrive.client.base_automation_client: TRACE: Dumping JSON structure: {'params': {'id': u'defaultFileSystemItemFactory#default#54eb3256-9555-4d4a-b508-14abcb684a12'}}
14:06:22 nxdrive.client.base_automation_client: TRACE: Calling http://localhost:8080/nuxeo/site/automation/NuxeoDrive.GetFileSystemItem with headers {'X-Authentication-Token': 'fcd4b71e-3976-454a-b7b0-d8d8e89ba55b', 'X-NXDocumentProperties': '*', 'X-Device-Id': u'14a7e54c632e11e38f5e00219b008ad3', '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#54eb3256-9555-4d4a-b508-14abcb684a12"}}'
14:06:22 nxdrive.client.base_automation_client: TRACE: Response for 'http://localhost:8080/nuxeo/site/automation/NuxeoDrive.GetFileSystemItem' with cookies [] and JSON payload: '{"digest":"b8ab309a6b9a3f448092a136afa8fa25","digestAlgorithm":"md5","downloadURL":"nxbigfile/default/54eb3256-9555-4d4a-b508-14abcb684a12/blobholder:0/Remote%20file%201.odt","canUpdate":true,"name":"Remote file 1.odt","id":"defaultFileSystemItemFactory#default#54eb3256-9555-4d4a-b508-14abcb684a12","path":"/org.nuxeo.drive.service.impl.DefaultTopLevelFolderItemFactory#/defaultSyncRootFolderItemFactory#default#27ed517b-a25e-4af0-8f30-177d5b22b7a4/defaultFileSystemItemFactory#default#87e88128-77d0-4e3b-8ebe-169fc12798be/defaultFileSystemItemFactory#default#54eb3256-9555-4d4a-b508-14abcb684a12","creationDate":1386853535532,"folder":false,"creator":"nuxeoDriveTestUser_user_1","parentId":"defaultFileSystemItemFactory#default#87e88128-77d0-4e3b-8ebe-169fc12798be","lastModificationDate":1386853535547,"canRename":true,"canDelete":true,"userName":"nuxeoDriveTestUser_user_1"}'
14:06:22 nxdrive.model: TRACE: Use last known modification time to detect updates: local DB, server = '2013-12-12 14:05:35', '2013-12-12 14:05:35'
14:06:22 nxdrive.model: TRACE: Updated state for LastKnownState<local_folder=u'/tmp/tmpjtj05a-nxdrive-tests-user-1/Nuxeo Drive', local_path=None, remote_name=u'Remote file 1.odt', local_state=u'unknown', remote_state='created', pair_state='remotely_created'>
14:06:22 nxdrive.synchronizer: DEBUG: Creating local file 'Remote file 1.odt' in '/tmp/tmpjtj05a-nxdrive-tests-user-1/Nuxeo Drive/Nuxeo Drive Test Workspace/Remote folder'
14:06:22 nxdrive.client.base_automation_client: TRACE: Dumping JSON structure: {'params': {'id': u'defaultFileSystemItemFactory#default#54eb3256-9555-4d4a-b508-14abcb684a12'}}
14:06:22 nxdrive.client.base_automation_client: TRACE: Calling http://localhost:8080/nuxeo/site/automation/NuxeoDrive.GetFileSystemItem with headers {'X-Authentication-Token': 'fcd4b71e-3976-454a-b7b0-d8d8e89ba55b', 'X-NXDocumentProperties': '*', 'X-Device-Id': u'14a7e54c632e11e38f5e00219b008ad3', '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#54eb3256-9555-4d4a-b508-14abcb684a12"}}'
14:06:22 nxdrive.client.base_automation_client: TRACE: Response for 'http://localhost:8080/nuxeo/site/automation/NuxeoDrive.GetFileSystemItem' with cookies [] and JSON payload: '{"digest":"b8ab309a6b9a3f448092a136afa8fa25","digestAlgorithm":"md5","downloadURL":"nxbigfile/default/54eb3256-9555-4d4a-b508-14abcb684a12/blobholder:0/Remote%20file%201.odt","canUpdate":true,"name":"Remote file 1.odt","id":"defaultFileSystemItemFactory#default#54eb3256-9555-4d4a-b508-14abcb684a12","path":"/org.nuxeo.drive.service.impl.DefaultTopLevelFolderItemFactory#/defaultSyncRootFolderItemFactory#default#27ed517b-a25e-4af0-8f30-177d5b22b7a4/defaultFileSystemItemFactory#default#87e88128-77d0-4e3b-8ebe-169fc12798be/defaultFileSystemItemFactory#default#54eb3256-9555-4d4a-b508-14abcb684a12","creationDate":1386853535532,"folder":false,"creator":"nuxeoDriveTestUser_user_1","parentId":"defaultFileSystemItemFactory#default#87e88128-77d0-4e3b-8ebe-169fc12798be","lastModificationDate":1386853535547,"canRename":true,"canDelete":true,"userName":"nuxeoDriveTestUser_user_1"}'
14:06:22 nxdrive.client.remote_file_system_client: TRACE: Calling 'http://localhost:8080/nuxeo/nxbigfile/default/54eb3256-9555-4d4a-b508-14abcb684a12/blobholder:0/Remote%20file%201.odt' with headers: {'X-Authentication-Token': 'fcd4b71e-3976-454a-b7b0-d8d8e89ba55b', 'X-Device-Id': u'14a7e54c632e11e38f5e00219b008ad3', 'X-User-Id': u'nuxeoDriveTestUser_user_1', 'Cache-Control': 'no-cache', 'X-Application-Name': 'Nuxeo Drive', 'X-Client-Version': '1.2-dev'}
14:06:22 nxdrive.model: TRACE: Updated state for LastKnownState<local_folder=u'/tmp/tmpjtj05a-nxdrive-tests-user-1/Nuxeo Drive', local_path=u'/Nuxeo Drive Test Workspace/Remote folder/Remote file 1.odt', remote_name=u'Remote file 1.odt', local_state='synchronized', remote_state='synchronized', pair_state='synchronized'>
14:06:22 nxdrive.client.base_automation_client: TRACE: Dumping JSON structure: {'params': {'id': u'defaultFileSystemItemFactory#default#cc962f68-f598-4528-9ea2-ed26d810b9ed'}}
14:06:22 nxdrive.client.base_automation_client: TRACE: Calling http://localhost:8080/nuxeo/site/automation/NuxeoDrive.GetFileSystemItem with headers {'X-Authentication-Token': 'fcd4b71e-3976-454a-b7b0-d8d8e89ba55b', 'X-NXDocumentProperties': '*', 'X-Device-Id': u'14a7e54c632e11e38f5e00219b008ad3', '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#cc962f68-f598-4528-9ea2-ed26d810b9ed"}}'
14:06:22 nxdrive.client.base_automation_client: TRACE: Response for 'http://localhost:8080/nuxeo/site/automation/NuxeoDrive.GetFileSystemItem' with cookies [] and JSON payload: '{"digest":"39181c49e54b6e5ae8be13ab66f9fdb8","digestAlgorithm":"md5","downloadURL":"nxbigfile/default/cc962f68-f598-4528-9ea2-ed26d810b9ed/blobholder:0/Remote%20file%202.odt","canUpdate":true,"name":"Remote file 2.odt","id":"defaultFileSystemItemFactory#default#cc962f68-f598-4528-9ea2-ed26d810b9ed","path":"/org.nuxeo.drive.service.impl.DefaultTopLevelFolderItemFactory#/defaultSyncRootFolderItemFactory#default#27ed517b-a25e-4af0-8f30-177d5b22b7a4/defaultFileSystemItemFactory#default#87e88128-77d0-4e3b-8ebe-169fc12798be/defaultFileSystemItemFactory#default#cc962f68-f598-4528-9ea2-ed26d810b9ed","creationDate":1386853535558,"folder":false,"creator":"nuxeoDriveTestUser_user_1","parentId":"defaultFileSystemItemFactory#default#87e88128-77d0-4e3b-8ebe-169fc12798be","lastModificationDate":1386853535578,"canRename":true,"canDelete":true,"userName":"nuxeoDriveTestUser_user_1"}'
14:06:22 nxdrive.model: TRACE: Use last known modification time to detect updates: local DB, server = '2013-12-12 14:05:35', '2013-12-12 14:05:35'
14:06:22 nxdrive.model: TRACE: Updated state for LastKnownState<local_folder=u'/tmp/tmpjtj05a-nxdrive-tests-user-1/Nuxeo Drive', local_path=None, remote_name=u'Remote file 2.odt', local_state=u'unknown', remote_state='created', pair_state='remotely_created'>
14:06:22 nxdrive.synchronizer: DEBUG: Creating local file 'Remote file 2.odt' in '/tmp/tmpjtj05a-nxdrive-tests-user-1/Nuxeo Drive/Nuxeo Drive Test Workspace/Remote folder'
14:06:22 nxdrive.client.base_automation_client: TRACE: Dumping JSON structure: {'params': {'id': u'defaultFileSystemItemFactory#default#cc962f68-f598-4528-9ea2-ed26d810b9ed'}}
14:06:22 nxdrive.client.base_automation_client: TRACE: Calling http://localhost:8080/nuxeo/site/automation/NuxeoDrive.GetFileSystemItem with headers {'X-Authentication-Token': 'fcd4b71e-3976-454a-b7b0-d8d8e89ba55b', 'X-NXDocumentProperties': '*', 'X-Device-Id': u'14a7e54c632e11e38f5e00219b008ad3', '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#cc962f68-f598-4528-9ea2-ed26d810b9ed"}}'
14:06:22 nxdrive.client.base_automation_client: TRACE: Response for 'http://localhost:8080/nuxeo/site/automation/NuxeoDrive.GetFileSystemItem' with cookies [] and JSON payload: '{"digest":"39181c49e54b6e5ae8be13ab66f9fdb8","digestAlgorithm":"md5","downloadURL":"nxbigfile/default/cc962f68-f598-4528-9ea2-ed26d810b9ed/blobholder:0/Remote%20file%202.odt","canUpdate":true,"name":"Remote file 2.odt","id":"defaultFileSystemItemFactory#default#cc962f68-f598-4528-9ea2-ed26d810b9ed","path":"/org.nuxeo.drive.service.impl.DefaultTopLevelFolderItemFactory#/defaultSyncRootFolderItemFactory#default#27ed517b-a25e-4af0-8f30-177d5b22b7a4/defaultFileSystemItemFactory#default#87e88128-77d0-4e3b-8ebe-169fc12798be/defaultFileSystemItemFactory#default#cc962f68-f598-4528-9ea2-ed26d810b9ed","creationDate":1386853535558,"folder":false,"creator":"nuxeoDriveTestUser_user_1","parentId":"defaultFileSystemItemFactory#default#87e88128-77d0-4e3b-8ebe-169fc12798be","lastModificationDate":1386853535578,"canRename":true,"canDelete":true,"userName":"nuxeoDriveTestUser_user_1"}'
14:06:22 nxdrive.client.remote_file_system_client: TRACE: Calling 'http://localhost:8080/nuxeo/nxbigfile/default/cc962f68-f598-4528-9ea2-ed26d810b9ed/blobholder:0/Remote%20file%202.odt' with headers: {'X-Authentication-Token': 'fcd4b71e-3976-454a-b7b0-d8d8e89ba55b', 'X-Device-Id': u'14a7e54c632e11e38f5e00219b008ad3', 'X-User-Id': u'nuxeoDriveTestUser_user_1', 'Cache-Control': 'no-cache', 'X-Application-Name': 'Nuxeo Drive', 'X-Client-Version': '1.2-dev'}
14:06:22 nxdrive.model: TRACE: Updated state for LastKnownState<local_folder=u'/tmp/tmpjtj05a-nxdrive-tests-user-1/Nuxeo Drive', local_path=u'/Nuxeo Drive Test Workspace/Remote folder/Remote file 2.odt', remote_name=u'Remote file 2.odt', local_state='synchronized', remote_state='synchronized', pair_state='synchronized'>
14:06:22 nxdrive.synchronizer: DEBUG: [/tmp/tmpjtj05a-nxdrive-tests-user-1/Nuxeo Drive] - [http://localhost:8080/nuxeo/]: synchronized: 3, pending: 3, local: 0.245s, remote: 0.091s sync: 0.759s
14:06:22 nxdrive.synchronizer: INFO: Stopping synchronization after 1 loops
14:06:22 nxdrive.client.base_automation_client: TRACE: Dumping JSON structure: {'input': u'doc:/default-domain/workspaces/nuxeo-drive-test-workspace/Remote folder', 'params': {'value': 'delete'}}
14:06:22 nxdrive.client.base_automation_client: TRACE: Calling http://localhost:8080/nuxeo/site/automation/Document.SetLifeCycle 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 bnV4ZW9Ecml2ZVRlc3RVc2VyX3VzZXJfMTo2MDQwNmQ='}, cookies [] and JSON payload '{"input": "doc:/default-domain/workspaces/nuxeo-drive-test-workspace/Remote folder", "params": {"value": "delete"}}'
14:06:22 nxdrive.client.base_automation_client: TRACE: Response for 'http://localhost:8080/nuxeo/site/automation/Document.SetLifeCycle' with cookies [] and JSON payload: '{"entity-type":"document","repository":"default","uid":"87e88128-77d0-4e3b-8ebe-169fc12798be","path":"/default-domain/workspaces/nuxeo-drive-test-workspace/Remote folder","type":"Folder","state":"deleted","versionLabel":"","title":"Remote folder","lastModified":"2013-12-12T13:05:37.74Z","properties":{"dc:creator":"nuxeoDriveTestUser_user_1","dc:source":null,"dc:contributors":["nuxeoDriveTestUser_user_1"],"dc:nature":null,"dc:created":"2013-12-12T13:05:35.52Z","dc:description":null,"dc:rights":null,"dc:subjects":[],"dc:publisher":null,"dc:valid":null,"dc:format":null,"dc:issued":null,"dc:modified":"2013-12-12T13:05:37.74Z","dc:expired":null,"dc:coverage":null,"dc:language":null,"dc:title":"Remote folder","dc:lastContributor":"nuxeoDriveTestUser_user_1","common:icon":"/icons/folder.gif","common:icon-expanded":null,"common:size":null},"facets":["Folderish"],"changeToken":"1386853537747","contextParameters":{}}'
14:06:22 nxdrive.client.base_automation_client: TRACE: Dumping JSON structure: {'params': {}}
14:06:22 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": {}}'
14:06:22 nxdrive.client.base_automation_client: TRACE: Response for 'http://localhost:8080/nuxeo/site/automation/NuxeoDrive.WaitForAsyncCompletion' with cookies [] and content-type: ''
14:06:22 nxdrive.synchronizer: INFO: Starting synchronization (pid=26809)
14:06:22 nxdrive.client.base_automation_client: TRACE: Dumping JSON structure: {'params': {'lastSyncDate': 1386853536000L, 'lastSyncActiveRootDefinitions': u'default:27ed517b-a25e-4af0-8f30-177d5b22b7a4'}}
14:06:22 nxdrive.client.base_automation_client: TRACE: Calling http://localhost:8080/nuxeo/site/automation/NuxeoDrive.GetChangeSummary with headers {'X-Authentication-Token': 'fcd4b71e-3976-454a-b7b0-d8d8e89ba55b', 'X-NXDocumentProperties': '*', 'X-Device-Id': u'14a7e54c632e11e38f5e00219b008ad3', '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": 1386853536000, "lastSyncActiveRootDefinitions": "default:27ed517b-a25e-4af0-8f30-177d5b22b7a4"}}'
14:06:22 nxdrive.client.base_automation_client: TRACE: Response for 'http://localhost:8080/nuxeo/site/automation/NuxeoDrive.GetChangeSummary' with cookies [] and JSON payload: '{"fileSystemChanges":[{"eventId":"deleted","eventDate":1386853537868,"repositoryId":"default","fileSystemItem":null,"fileSystemItemId":"defaultFileSystemItemFactory#default#cc962f68-f598-4528-9ea2-ed26d810b9ed","fileSystemItemName":"Remote file 2.odt","docUuid":"cc962f68-f598-4528-9ea2-ed26d810b9ed"},{"eventId":"deleted","eventDate":1386853537805,"repositoryId":"default","fileSystemItem":null,"fileSystemItemId":"defaultFileSystemItemFactory#default#54eb3256-9555-4d4a-b508-14abcb684a12","fileSystemItemName":"Remote file 1.odt","docUuid":"54eb3256-9555-4d4a-b508-14abcb684a12"},{"eventId":"deleted","eventDate":1386853537748,"repositoryId":"default","fileSystemItem":null,"fileSystemItemId":"defaultFileSystemItemFactory#default#87e88128-77d0-4e3b-8ebe-169fc12798be","fileSystemItemName":"Remote folder","docUuid":"87e88128-77d0-4e3b-8ebe-169fc12798be"}],"syncDate":1386853538000,"activeSynchronizationRootDefinitions":"default:27ed517b-a25e-4af0-8f30-177d5b22b7a4","hasTooManyChanges":false}'
14:06:22 nxdrive.synchronizer: DEBUG: 3 remote changes detected on http://localhost:8080/nuxeo/
14:06:22 nxdrive.synchronizer: DEBUG: Marking doc_pair 'Remote file 2.odt' as deleted
14:06:22 nxdrive.model: TRACE: Updated state for LastKnownState<local_folder=u'/tmp/tmpjtj05a-nxdrive-tests-user-1/Nuxeo Drive', local_path=u'/Nuxeo Drive Test Workspace/Remote folder/Remote file 2.odt', remote_name=u'Remote file 2.odt', local_state=u'synchronized', remote_state='deleted', pair_state='remotely_deleted'>
14:06:22 nxdrive.synchronizer: DEBUG: Marking doc_pair 'Remote file 1.odt' as deleted
14:06:22 nxdrive.model: TRACE: Updated state for LastKnownState<local_folder=u'/tmp/tmpjtj05a-nxdrive-tests-user-1/Nuxeo Drive', local_path=u'/Nuxeo Drive Test Workspace/Remote folder/Remote file 1.odt', remote_name=u'Remote file 1.odt', local_state=u'synchronized', remote_state='deleted', pair_state='remotely_deleted'>
14:06:22 nxdrive.synchronizer: DEBUG: Marking doc_pair 'Remote folder' as deleted
14:06:22 nxdrive.model: TRACE: Updated state for LastKnownState<local_folder=u'/tmp/tmpjtj05a-nxdrive-tests-user-1/Nuxeo Drive', local_path=u'/Nuxeo Drive Test Workspace/Remote folder', remote_name=u'Remote folder', local_state=u'synchronized', remote_state='deleted', pair_state='remotely_deleted'>
14:06:22 nxdrive.model: TRACE: Selecting paths set([u'/Nuxeo Drive Test Workspace'])
14:06:22 nxdrive.model: TRACE: Selecting paths set([u'/Nuxeo Drive Test Workspace/Remote folder'])
14:06:22 nxdrive.model: TRACE: Selecting paths set([u'/Nuxeo Drive Test Workspace/Remote folder/Remote file 1.odt', u'/Nuxeo Drive Test Workspace/Remote folder/Remote file 2.odt'])
14:06:22 nxdrive.synchronizer: TRACE: Handling Remote file 1.odt for deletion: last_local_updated = 2013-12-12 14:05:37, last_sync_date = 2013-12-12 14:05:37
14:06:22 nxdrive.synchronizer: DEBUG: Deleting local file '/tmp/tmpjtj05a-nxdrive-tests-user-1/Nuxeo Drive/Nuxeo Drive Test Workspace/Remote folder/Remote file 1.odt'
14:06:22 nxdrive.synchronizer: TRACE: Handling Remote file 2.odt for deletion: last_local_updated = 2013-12-12 14:05:37, last_sync_date = 2013-12-12 14:05:37
14:06:22 nxdrive.synchronizer: DEBUG: Deleting local file '/tmp/tmpjtj05a-nxdrive-tests-user-1/Nuxeo Drive/Nuxeo Drive Test Workspace/Remote folder/Remote file 2.odt'
14:06:22 nxdrive.synchronizer: TRACE: Handling Remote folder for deletion: last_local_updated = 2013-12-12 14:05:37, last_sync_date = 2013-12-12 14:05:37
14:06:22 nxdrive.synchronizer: DEBUG: Deleting local folder '/tmp/tmpjtj05a-nxdrive-tests-user-1/Nuxeo Drive/Nuxeo Drive Test Workspace/Remote folder'
14:06:22 nxdrive.synchronizer: DEBUG: [/tmp/tmpjtj05a-nxdrive-tests-user-1/Nuxeo Drive] - [http://localhost:8080/nuxeo/]: synchronized: 1, pending: 3, local: 0.144s, remote: 0.557s sync: 0.095s
14:06:22 nxdrive.synchronizer: INFO: Stopping synchronization after 1 loops
14:06:22 nxdrive.synchronizer: INFO: Starting synchronization (pid=26809)
14:06:22 nxdrive.client.base_automation_client: TRACE: Dumping JSON structure: {'params': {'lastSyncDate': 1386853538000L, 'lastSyncActiveRootDefinitions': u'default:27ed517b-a25e-4af0-8f30-177d5b22b7a4'}}
14:06:22 nxdrive.client.base_automation_client: TRACE: Calling http://localhost:8080/nuxeo/site/automation/NuxeoDrive.GetChangeSummary with headers {'X-Authentication-Token': 'fcd4b71e-3976-454a-b7b0-d8d8e89ba55b', 'X-NXDocumentProperties': '*', 'X-Device-Id': u'14a7e54c632e11e38f5e00219b008ad3', '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": 1386853538000, "lastSyncActiveRootDefinitions": "default:27ed517b-a25e-4af0-8f30-177d5b22b7a4"}}'
14:06:22 nxdrive.client.base_automation_client: TRACE: Response for 'http://localhost:8080/nuxeo/site/automation/NuxeoDrive.GetChangeSummary' with cookies [] and JSON payload: '{"fileSystemChanges":[],"syncDate":1386853539000,"activeSynchronizationRootDefinitions":"default:27ed517b-a25e-4af0-8f30-177d5b22b7a4","hasTooManyChanges":false}'
14:06:22 nxdrive.model: TRACE: Selecting paths set([u'/Nuxeo Drive Test Workspace'])
14:06:22 nxdrive.model: TRACE: Selecting paths set([u'/Nuxeo Drive Test Workspace/Local folder'])
14:06:22 nxdrive.model: TRACE: Updated state for LastKnownState<local_folder=u'/tmp/tmpjtj05a-nxdrive-tests-user-1/Nuxeo Drive', local_path=u'/Nuxeo Drive Test Workspace/Local folder', remote_name=None, local_state=None, remote_state=None, pair_state='unknown'>
14:06:22 nxdrive.synchronizer: DEBUG: Detected a new non-alignable local file at /Nuxeo Drive Test Workspace/Local folder
14:06:22 nxdrive.model: TRACE: Selecting paths set([u'/Nuxeo Drive Test Workspace/Local folder/Local file 2.odt', u'/Nuxeo Drive Test Workspace/Local folder/Local file 1.odt'])
14:06:22 nxdrive.model: TRACE: Updated state for LastKnownState<local_folder=u'/tmp/tmpjtj05a-nxdrive-tests-user-1/Nuxeo Drive', local_path=u'/Nuxeo Drive Test Workspace/Local folder/Local file 1.odt', remote_name=None, local_state=None, remote_state=None, pair_state='unknown'>
14:06:22 nxdrive.synchronizer: DEBUG: Detected a new non-alignable local file at /Nuxeo Drive Test Workspace/Local folder/Local file 1.odt
14:06:22 nxdrive.model: TRACE: Updated state for LastKnownState<local_folder=u'/tmp/tmpjtj05a-nxdrive-tests-user-1/Nuxeo Drive', local_path=u'/Nuxeo Drive Test Workspace/Local folder/Local file 2.odt', remote_name=None, local_state=None, remote_state=None, pair_state='unknown'>
14:06:22 nxdrive.synchronizer: DEBUG: Detected a new non-alignable local file at /Nuxeo Drive Test Workspace/Local folder/Local file 2.odt
14:06:22 nxdrive.model: TRACE: Updated state for LastKnownState<local_folder=u'/tmp/tmpjtj05a-nxdrive-tests-user-1/Nuxeo Drive', local_path=u'/Nuxeo Drive Test Workspace/Local folder', remote_name=None, local_state='created', remote_state=u'unknown', pair_state='locally_created'>
14:06:22 nxdrive.synchronizer: DEBUG: Creating remote folder 'Local folder' in folder 'Nuxeo Drive Test Workspace'
14:06:22 nxdrive.client.base_automation_client: TRACE: Dumping JSON structure: {'params': {'name': u'Local folder', 'parentId': u'defaultSyncRootFolderItemFactory#default#27ed517b-a25e-4af0-8f30-177d5b22b7a4'}}
14:06:22 nxdrive.client.base_automation_client: TRACE: Calling http://localhost:8080/nuxeo/site/automation/NuxeoDrive.CreateFolder with headers {'X-Authentication-Token': 'fcd4b71e-3976-454a-b7b0-d8d8e89ba55b', 'X-NXDocumentProperties': '*', 'X-Device-Id': u'14a7e54c632e11e38f5e00219b008ad3', '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": {"name": "Local folder", "parentId": "defaultSyncRootFolderItemFactory#default#27ed517b-a25e-4af0-8f30-177d5b22b7a4"}}'
14:06:22 nxdrive.client.base_automation_client: TRACE: Response for 'http://localhost:8080/nuxeo/site/automation/NuxeoDrive.CreateFolder' with cookies [] and JSON payload: '{"canCreateChild":true,"name":"Local folder","id":"defaultFileSystemItemFactory#default#a491983b-fc20-4496-88bb-2277c871ee9b","path":"/org.nuxeo.drive.service.impl.DefaultTopLevelFolderItemFactory#/defaultSyncRootFolderItemFactory#default#27ed517b-a25e-4af0-8f30-177d5b22b7a4/defaultFileSystemItemFactory#default#a491983b-fc20-4496-88bb-2277c871ee9b","creationDate":1386853539959,"folder":true,"creator":"nuxeoDriveTestUser_user_1","parentId":"defaultSyncRootFolderItemFactory#default#27ed517b-a25e-4af0-8f30-177d5b22b7a4","lastModificationDate":1386853539959,"canRename":true,"canDelete":true,"userName":"nuxeoDriveTestUser_user_1"}'
14:06:22 nxdrive.client.base_automation_client: TRACE: Dumping JSON structure: {'params': {'id': u'defaultFileSystemItemFactory#default#a491983b-fc20-4496-88bb-2277c871ee9b'}}
14:06:22 nxdrive.client.base_automation_client: TRACE: Calling http://localhost:8080/nuxeo/site/automation/NuxeoDrive.GetFileSystemItem with headers {'X-Authentication-Token': 'fcd4b71e-3976-454a-b7b0-d8d8e89ba55b', 'X-NXDocumentProperties': '*', 'X-Device-Id': u'14a7e54c632e11e38f5e00219b008ad3', '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#a491983b-fc20-4496-88bb-2277c871ee9b"}}'
14:06:22 nxdrive.client.base_automation_client: TRACE: Response for 'http://localhost:8080/nuxeo/site/automation/NuxeoDrive.GetFileSystemItem' with cookies [] and JSON payload: '{"canCreateChild":true,"name":"Local folder","id":"defaultFileSystemItemFactory#default#a491983b-fc20-4496-88bb-2277c871ee9b","path":"/org.nuxeo.drive.service.impl.DefaultTopLevelFolderItemFactory#/defaultSyncRootFolderItemFactory#default#27ed517b-a25e-4af0-8f30-177d5b22b7a4/defaultFileSystemItemFactory#default#a491983b-fc20-4496-88bb-2277c871ee9b","creationDate":1386853539959,"folder":true,"creator":"nuxeoDriveTestUser_user_1","parentId":"defaultSyncRootFolderItemFactory#default#27ed517b-a25e-4af0-8f30-177d5b22b7a4","lastModificationDate":1386853539959,"canRename":true,"canDelete":true,"userName":"nuxeoDriveTestUser_user_1"}'
14:06:22 nxdrive.model: TRACE: Use last known modification time to detect updates: local DB, server = 'None', '2013-12-12 14:05:39'
14:06:22 nxdrive.model: TRACE: last_remote_updated is None for doc None, set it to 2013-12-12 14:05:39
14:06:22 nxdrive.model: TRACE: Updated state for LastKnownState<local_folder=u'/tmp/tmpjtj05a-nxdrive-tests-user-1/Nuxeo Drive', local_path=u'/Nuxeo Drive Test Workspace/Local folder', remote_name=u'Local folder', local_state='synchronized', remote_state='synchronized', pair_state='synchronized'>
14:06:22 nxdrive.model: TRACE: Updated state for LastKnownState<local_folder=u'/tmp/tmpjtj05a-nxdrive-tests-user-1/Nuxeo Drive', local_path=u'/Nuxeo Drive Test Workspace/Local folder/Local file 1.odt', remote_name=None, local_state='created', remote_state=u'unknown', pair_state='locally_created'>
14:06:22 nxdrive.synchronizer: DEBUG: Creating remote document 'Local file 1.odt' in folder 'Local folder'
14:06:22 nxdrive.client.base_automation_client: TRACE: Using file system block size for the streaming upload buffer: 4096 bytes
14:06:22 nxdrive.client.base_automation_client: TRACE: Calling http://localhost:8080/nuxeo/site/automation/batch/upload with headers {'Content-Length': 13, 'X-Authentication-Token': 'fcd4b71e-3976-454a-b7b0-d8d8e89ba55b', 'X-Device-Id': u'14a7e54c632e11e38f5e00219b008ad3', 'X-File-Type': 'application/vnd.oasis.opendocument.text', 'X-User-Id': u'nuxeoDriveTestUser_user_1', 'X-File-Size': 13, 'Cache-Control': 'no-cache', 'X-Batch-Id': '1386853540.28_562263486', 'X-File-Idx': 0, 'Content-Type': 'application/octet-stream', 'X-Application-Name': 'Nuxeo Drive', 'X-File-Name': 'Local%20file%201.odt', 'X-Client-Version': '1.2-dev'} and cookies [] for file /tmp/tmpjtj05a-nxdrive-tests-user-1/Nuxeo Drive/Nuxeo Drive Test Workspace/Local folder/Local file 1.odt
14:06:22 nxdrive.client.base_automation_client: TRACE: Response for 'http://localhost:8080/nuxeo/site/automation/batch/upload' with cookies [] and JSON payload: '{"uploaded":"true","batchId":"1386853540.28_562263486"}'
14:06:22 nxdrive.client.base_automation_client: TRACE: Dumping JSON structure: {'params': {'batchId': '1386853540.28_562263486', 'operationId': 'NuxeoDrive.CreateFile', 'fileIdx': '0', 'parentId': u'defaultFileSystemItemFactory#default#a491983b-fc20-4496-88bb-2277c871ee9b'}}
14:06:22 nxdrive.client.base_automation_client: TRACE: Calling http://localhost:8080/nuxeo/site/automation/batch/execute with headers {'X-Authentication-Token': 'fcd4b71e-3976-454a-b7b0-d8d8e89ba55b', 'X-NXDocumentProperties': '*', 'X-Device-Id': u'14a7e54c632e11e38f5e00219b008ad3', '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": {"batchId": "1386853540.28_562263486", "operationId": "NuxeoDrive.CreateFile", "fileIdx": "0", "parentId": "defaultFileSystemItemFactory#default#a491983b-fc20-4496-88bb-2277c871ee9b"}}'
14:06:22 nxdrive.client.base_automation_client: TRACE: Response for 'http://localhost:8080/nuxeo/site/automation/batch/execute' with cookies [] and JSON payload: '{"digest":"b8ab309a6b9a3f448092a136afa8fa25","digestAlgorithm":"md5","downloadURL":"nxbigfile/default/24ebbad8-9f96-4e79-b218-c6eab937219e/blobholder:0/Local%20file%201.odt","canUpdate":true,"name":"Local file 1.odt","id":"defaultFileSystemItemFactory#default#24ebbad8-9f96-4e79-b218-c6eab937219e","path":"/org.nuxeo.drive.service.impl.DefaultTopLevelFolderItemFactory#/defaultSyncRootFolderItemFactory#default#27ed517b-a25e-4af0-8f30-177d5b22b7a4/defaultFileSystemItemFactory#default#a491983b-fc20-4496-88bb-2277c871ee9b/defaultFileSystemItemFactory#default#24ebbad8-9f96-4e79-b218-c6eab937219e","creationDate":1386853540301,"folder":false,"creator":"nuxeoDriveTestUser_user_1","parentId":"defaultFileSystemItemFactory#default#a491983b-fc20-4496-88bb-2277c871ee9b","lastModificationDate":1386853540301,"canRename":true,"canDelete":true,"userName":"nuxeoDriveTestUser_user_1"}'
14:06:22 nxdrive.client.base_automation_client: TRACE: Dumping JSON structure: {'params': {'id': u'defaultFileSystemItemFactory#default#24ebbad8-9f96-4e79-b218-c6eab937219e'}}
14:06:22 nxdrive.client.base_automation_client: TRACE: Calling http://localhost:8080/nuxeo/site/automation/NuxeoDrive.GetFileSystemItem with headers {'X-Authentication-Token': 'fcd4b71e-3976-454a-b7b0-d8d8e89ba55b', 'X-NXDocumentProperties': '*', 'X-Device-Id': u'14a7e54c632e11e38f5e00219b008ad3', '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#24ebbad8-9f96-4e79-b218-c6eab937219e"}}'
14:06:22 nxdrive.client.base_automation_client: TRACE: Response for 'http://localhost:8080/nuxeo/site/automation/NuxeoDrive.GetFileSystemItem' with cookies [] and JSON payload: '{"digest":"b8ab309a6b9a3f448092a136afa8fa25","digestAlgorithm":"md5","downloadURL":"nxbigfile/default/24ebbad8-9f96-4e79-b218-c6eab937219e/blobholder:0/Local%20file%201.odt","canUpdate":true,"name":"Local file 1.odt","id":"defaultFileSystemItemFactory#default#24ebbad8-9f96-4e79-b218-c6eab937219e","path":"/org.nuxeo.drive.service.impl.DefaultTopLevelFolderItemFactory#/defaultSyncRootFolderItemFactory#default#27ed517b-a25e-4af0-8f30-177d5b22b7a4/defaultFileSystemItemFactory#default#a491983b-fc20-4496-88bb-2277c871ee9b/defaultFileSystemItemFactory#default#24ebbad8-9f96-4e79-b218-c6eab937219e","creationDate":1386853540301,"folder":false,"creator":"nuxeoDriveTestUser_user_1","parentId":"defaultFileSystemItemFactory#default#a491983b-fc20-4496-88bb-2277c871ee9b","lastModificationDate":1386853540301,"canRename":true,"canDelete":true,"userName":"nuxeoDriveTestUser_user_1"}'
14:06:22 nxdrive.model: TRACE: Use last known modification time to detect updates: local DB, server = 'None', '2013-12-12 14:05:40'
14:06:22 nxdrive.model: TRACE: last_remote_updated is None for doc None, set it to 2013-12-12 14:05:40
14:06:22 nxdrive.model: TRACE: Updated state for LastKnownState<local_folder=u'/tmp/tmpjtj05a-nxdrive-tests-user-1/Nuxeo Drive', local_path=u'/Nuxeo Drive Test Workspace/Local folder/Local file 1.odt', remote_name=u'Local file 1.odt', local_state='synchronized', remote_state='synchronized', pair_state='synchronized'>
14:06:22 nxdrive.model: TRACE: Updated state for LastKnownState<local_folder=u'/tmp/tmpjtj05a-nxdrive-tests-user-1/Nuxeo Drive', local_path=u'/Nuxeo Drive Test Workspace/Local folder/Local file 2.odt', remote_name=None, local_state='created', remote_state=u'unknown', pair_state='locally_created'>
14:06:22 nxdrive.synchronizer: DEBUG: Creating remote document 'Local file 2.odt' in folder 'Local folder'
14:06:22 nxdrive.client.base_automation_client: TRACE: Using file system block size for the streaming upload buffer: 4096 bytes
14:06:22 nxdrive.client.base_automation_client: TRACE: Calling http://localhost:8080/nuxeo/site/automation/batch/upload with headers {'Content-Length': 14, 'X-Authentication-Token': 'fcd4b71e-3976-454a-b7b0-d8d8e89ba55b', 'X-Device-Id': u'14a7e54c632e11e38f5e00219b008ad3', 'X-File-Type': 'application/vnd.oasis.opendocument.text', 'X-User-Id': u'nuxeoDriveTestUser_user_1', 'X-File-Size': 14, 'Cache-Control': 'no-cache', 'X-Batch-Id': '1386853540.55_319397690', 'X-File-Idx': 0, 'Content-Type': 'application/octet-stream', 'X-Application-Name': 'Nuxeo Drive', 'X-File-Name': 'Local%20file%202.odt', 'X-Client-Version': '1.2-dev'} and cookies [] for file /tmp/tmpjtj05a-nxdrive-tests-user-1/Nuxeo Drive/Nuxeo Drive Test Workspace/Local folder/Local file 2.odt
14:06:22 nxdrive.client.base_automation_client: TRACE: Response for 'http://localhost:8080/nuxeo/site/automation/batch/upload' with cookies [] and JSON payload: '{"uploaded":"true","batchId":"1386853540.55_319397690"}'
14:06:22 nxdrive.client.base_automation_client: TRACE: Dumping JSON structure: {'params': {'batchId': '1386853540.55_319397690', 'operationId': 'NuxeoDrive.CreateFile', 'fileIdx': '0', 'parentId': u'defaultFileSystemItemFactory#default#a491983b-fc20-4496-88bb-2277c871ee9b'}}
14:06:22 nxdrive.client.base_automation_client: TRACE: Calling http://localhost:8080/nuxeo/site/automation/batch/execute with headers {'X-Authentication-Token': 'fcd4b71e-3976-454a-b7b0-d8d8e89ba55b', 'X-NXDocumentProperties': '*', 'X-Device-Id': u'14a7e54c632e11e38f5e00219b008ad3', '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": {"batchId": "1386853540.55_319397690", "operationId": "NuxeoDrive.CreateFile", "fileIdx": "0", "parentId": "defaultFileSystemItemFactory#default#a491983b-fc20-4496-88bb-2277c871ee9b"}}'
14:06:22 nxdrive.client.base_automation_client: TRACE: Response for 'http://localhost:8080/nuxeo/site/automation/batch/execute' with cookies [] and JSON payload: '{"digest":"39181c49e54b6e5ae8be13ab66f9fdb8","digestAlgorithm":"md5","downloadURL":"nxbigfile/default/63675ecf-9d21-4a46-a8b6-6eba7fcf8207/blobholder:0/Local%20file%202.odt","canUpdate":true,"name":"Local file 2.odt","id":"defaultFileSystemItemFactory#default#63675ecf-9d21-4a46-a8b6-6eba7fcf8207","path":"/org.nuxeo.drive.service.impl.DefaultTopLevelFolderItemFactory#/defaultSyncRootFolderItemFactory#default#27ed517b-a25e-4af0-8f30-177d5b22b7a4/defaultFileSystemItemFactory#default#a491983b-fc20-4496-88bb-2277c871ee9b/defaultFileSystemItemFactory#default#63675ecf-9d21-4a46-a8b6-6eba7fcf8207","creationDate":1386853540566,"folder":false,"creator":"nuxeoDriveTestUser_user_1","parentId":"defaultFileSystemItemFactory#default#a491983b-fc20-4496-88bb-2277c871ee9b","lastModificationDate":1386853540566,"canRename":true,"canDelete":true,"userName":"nuxeoDriveTestUser_user_1"}'
14:06:22 nxdrive.client.base_automation_client: TRACE: Dumping JSON structure: {'params': {'id': u'defaultFileSystemItemFactory#default#63675ecf-9d21-4a46-a8b6-6eba7fcf8207'}}
14:06:22 nxdrive.client.base_automation_client: TRACE: Calling http://localhost:8080/nuxeo/site/automation/NuxeoDrive.GetFileSystemItem with headers {'X-Authentication-Token': 'fcd4b71e-3976-454a-b7b0-d8d8e89ba55b', 'X-NXDocumentProperties': '*', 'X-Device-Id': u'14a7e54c632e11e38f5e00219b008ad3', '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#63675ecf-9d21-4a46-a8b6-6eba7fcf8207"}}'
14:06:22 nxdrive.client.base_automation_client: TRACE: Response for 'http://localhost:8080/nuxeo/site/automation/NuxeoDrive.GetFileSystemItem' with cookies [] and JSON payload: '{"digest":"39181c49e54b6e5ae8be13ab66f9fdb8","digestAlgorithm":"md5","downloadURL":"nxbigfile/default/63675ecf-9d21-4a46-a8b6-6eba7fcf8207/blobholder:0/Local%20file%202.odt","canUpdate":true,"name":"Local file 2.odt","id":"defaultFileSystemItemFactory#default#63675ecf-9d21-4a46-a8b6-6eba7fcf8207","path":"/org.nuxeo.drive.service.impl.DefaultTopLevelFolderItemFactory#/defaultSyncRootFolderItemFactory#default#27ed517b-a25e-4af0-8f30-177d5b22b7a4/defaultFileSystemItemFactory#default#a491983b-fc20-4496-88bb-2277c871ee9b/defaultFileSystemItemFactory#default#63675ecf-9d21-4a46-a8b6-6eba7fcf8207","creationDate":1386853540566,"folder":false,"creator":"nuxeoDriveTestUser_user_1","parentId":"defaultFileSystemItemFactory#default#a491983b-fc20-4496-88bb-2277c871ee9b","lastModificationDate":1386853540566,"canRename":true,"canDelete":true,"userName":"nuxeoDriveTestUser_user_1"}'
14:06:22 nxdrive.model: TRACE: Use last known modification time to detect updates: local DB, server = 'None', '2013-12-12 14:05:40'
14:06:22 nxdrive.model: TRACE: last_remote_updated is None for doc None, set it to 2013-12-12 14:05:40
14:06:22 nxdrive.model: TRACE: Updated state for LastKnownState<local_folder=u'/tmp/tmpjtj05a-nxdrive-tests-user-1/Nuxeo Drive', local_path=u'/Nuxeo Drive Test Workspace/Local folder/Local file 2.odt', remote_name=u'Local file 2.odt', local_state='synchronized', remote_state='synchronized', pair_state='synchronized'>
14:06:22 nxdrive.synchronizer: DEBUG: [/tmp/tmpjtj05a-nxdrive-tests-user-1/Nuxeo Drive] - [http://localhost:8080/nuxeo/]: synchronized: 3, pending: 3, local: 0.178s, remote: 0.022s sync: 0.866s
14:06:22 nxdrive.synchronizer: INFO: Stopping synchronization after 1 loops
14:06:22 nxdrive.client.base_automation_client: TRACE: Dumping JSON structure: {'params': {'query': u"SELECT * FROM Document WHERE ecm:path = '/default-domain/workspaces/nuxeo-drive-test-workspace/Local folder' AND ecm:currentLifeCycleState != 'deleted' AND ecm:isCheckedInVersion = 0 LIMIT 1"}}
14:06:22 nxdrive.client.base_automation_client: TRACE: Calling http://localhost:8080/nuxeo/site/automation/Document.Query 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 bnV4ZW9Ecml2ZVRlc3RVc2VyX3VzZXJfMTo2MDQwNmQ='}, cookies [] and JSON payload '{"params": {"query": "SELECT * FROM Document WHERE ecm:path = \'/default-domain/workspaces/nuxeo-drive-test-workspace/Local folder\' AND ecm:currentLifeCycleState != \'deleted\' AND ecm:isCheckedInVersion = 0 LIMIT 1"}}'
14:06:22 nxdrive.client.base_automation_client: TRACE: Response for 'http://localhost:8080/nuxeo/site/automation/Document.Query' with cookies [] and JSON payload: '{"entity-type":"documents","entries":[{"entity-type":"document","repository":"default","uid":"a491983b-fc20-4496-88bb-2277c871ee9b","path":"/default-domain/workspaces/nuxeo-drive-test-workspace/Local folder","type":"Folder","state":"project","versionLabel":"","title":"Local folder","lastModified":"2013-12-12T13:05:39.95Z","properties":{"dc:creator":"nuxeoDriveTestUser_user_1","dc:source":null,"dc:contributors":["nuxeoDriveTestUser_user_1"],"dc:nature":null,"dc:created":"2013-12-12T13:05:39.95Z","dc:description":null,"dc:rights":null,"dc:subjects":[],"dc:publisher":null,"dc:valid":null,"dc:format":null,"dc:issued":null,"dc:modified":"2013-12-12T13:05:39.95Z","dc:expired":null,"dc:coverage":null,"dc:language":null,"dc:title":"Local folder","dc:lastContributor":"nuxeoDriveTestUser_user_1","common:icon":"/icons/folder.gif","common:icon-expanded":null,"common:size":null},"facets":["Folderish"],"changeToken":"1386853539959","contextParameters":{}}]}'
14:06:22 nxdrive.client.base_automation_client: TRACE: Dumping JSON structure: {'params': {'query': u"SELECT * FROM Document WHERE ecm:path = '/default-domain/workspaces/nuxeo-drive-test-workspace/Local folder/Local file 1.odt' AND ecm:currentLifeCycleState != 'deleted' AND ecm:isCheckedInVersion = 0 LIMIT 1"}}
14:06:22 nxdrive.client.base_automation_client: TRACE: Calling http://localhost:8080/nuxeo/site/automation/Document.Query 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 bnV4ZW9Ecml2ZVRlc3RVc2VyX3VzZXJfMTo2MDQwNmQ='}, cookies [] and JSON payload '{"params": {"query": "SELECT * FROM Document WHERE ecm:path = \'/default-domain/workspaces/nuxeo-drive-test-workspace/Local folder/Local file 1.odt\' AND ecm:currentLifeCycleState != \'deleted\' AND ecm:isCheckedInVersion = 0 LIMIT 1"}}'
14:06:22 nxdrive.client.base_automation_client: TRACE: Response for 'http://localhost:8080/nuxeo/site/automation/Document.Query' with cookies [] and JSON payload: '{"entity-type":"documents","entries":[{"entity-type":"document","repository":"default","uid":"24ebbad8-9f96-4e79-b218-c6eab937219e","path":"/default-domain/workspaces/nuxeo-drive-test-workspace/Local folder/Local file 1.odt","type":"File","state":"project","versionLabel":"0.0","title":"Local file 1.odt","lastModified":"2013-12-12T13:05:40.30Z","properties":{"dc:creator":"nuxeoDriveTestUser_user_1","dc:source":null,"dc:contributors":["nuxeoDriveTestUser_user_1"],"dc:nature":null,"dc:created":"2013-12-12T13:05:40.30Z","dc:description":null,"dc:rights":null,"dc:subjects":[],"dc:publisher":null,"dc:valid":null,"dc:format":null,"dc:issued":null,"dc:modified":"2013-12-12T13:05:40.30Z","dc:expired":null,"dc:coverage":null,"dc:language":null,"dc:title":"Local file 1.odt","dc:lastContributor":"nuxeoDriveTestUser_user_1","uid:uid":null,"uid:minor_version":"0","uid:major_version":"0","files:files":[],"common:icon":"/icons/odt.png","common:icon-expanded":null,"common:size":"13","file:content":{"name":"Local file 1.odt","mime-type":"application/vnd.oasis.opendocument.text","encoding":null,"digest":"b8ab309a6b9a3f448092a136afa8fa25","length":"13","data":"files/24ebbad8-9f96-4e79-b218-c6eab937219e?path=%2Fcontent"},"file:filename":"Local file 1.odt","relatedtext:relatedtextresources":[]},"facets":["Downloadable","Commentable","Versionable","Publishable","HasRelatedText"],"changeToken":"1386853540301","contextParameters":{}}]}'
14:06:22 nxdrive.client.base_automation_client: TRACE: Dumping JSON structure: {'params': {'query': u"SELECT * FROM Document WHERE ecm:path = '/default-domain/workspaces/nuxeo-drive-test-workspace/Local folder/Local file 2.odt' AND ecm:currentLifeCycleState != 'deleted' AND ecm:isCheckedInVersion = 0 LIMIT 1"}}
14:06:22 nxdrive.client.base_automation_client: TRACE: Calling http://localhost:8080/nuxeo/site/automation/Document.Query 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 bnV4ZW9Ecml2ZVRlc3RVc2VyX3VzZXJfMTo2MDQwNmQ='}, cookies [] and JSON payload '{"params": {"query": "SELECT * FROM Document WHERE ecm:path = \'/default-domain/workspaces/nuxeo-drive-test-workspace/Local folder/Local file 2.odt\' AND ecm:currentLifeCycleState != \'deleted\' AND ecm:isCheckedInVersion = 0 LIMIT 1"}}'
14:06:22 nxdrive.client.base_automation_client: TRACE: Response for 'http://localhost:8080/nuxeo/site/automation/Document.Query' with cookies [] and JSON payload: '{"entity-type":"documents","entries":[{"entity-type":"document","repository":"default","uid":"63675ecf-9d21-4a46-a8b6-6eba7fcf8207","path":"/default-domain/workspaces/nuxeo-drive-test-workspace/Local folder/Local file 2.odt","type":"File","state":"project","versionLabel":"0.0","title":"Local file 2.odt","lastModified":"2013-12-12T13:05:40.56Z","properties":{"dc:creator":"nuxeoDriveTestUser_user_1","dc:source":null,"dc:contributors":["nuxeoDriveTestUser_user_1"],"dc:nature":null,"dc:created":"2013-12-12T13:05:40.56Z","dc:description":null,"dc:rights":null,"dc:subjects":[],"dc:publisher":null,"dc:valid":null,"dc:format":null,"dc:issued":null,"dc:modified":"2013-12-12T13:05:40.56Z","dc:expired":null,"dc:coverage":null,"dc:language":null,"dc:title":"Local file 2.odt","dc:lastContributor":"nuxeoDriveTestUser_user_1","uid:uid":null,"uid:minor_version":"0","uid:major_version":"0","files:files":[],"common:icon":"/icons/odt.png","common:icon-expanded":null,"common:size":"14","file:content":{"name":"Local file 2.odt","mime-type":"application/vnd.oasis.opendocument.text","encoding":null,"digest":"39181c49e54b6e5ae8be13ab66f9fdb8","length":"14","data":"files/63675ecf-9d21-4a46-a8b6-6eba7fcf8207?path=%2Fcontent"},"file:filename":"Local file 2.odt","relatedtext:relatedtextresources":[]},"facets":["Downloadable","Commentable","Versionable","Publishable","HasRelatedText"],"changeToken":"1386853540566","contextParameters":{}}]}'
14:06:22 nxdrive.synchronizer: INFO: Starting synchronization (pid=26809)
14:06:22 nxdrive.client.base_automation_client: TRACE: Dumping JSON structure: {'params': {'lastSyncDate': 1386853539000L, 'lastSyncActiveRootDefinitions': u'default:27ed517b-a25e-4af0-8f30-177d5b22b7a4'}}
14:06:22 nxdrive.client.base_automation_client: TRACE: Calling http://localhost:8080/nuxeo/site/automation/NuxeoDrive.GetChangeSummary with headers {'X-Authentication-Token': 'fcd4b71e-3976-454a-b7b0-d8d8e89ba55b', 'X-NXDocumentProperties': '*', 'X-Device-Id': u'14a7e54c632e11e38f5e00219b008ad3', '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": 1386853539000, "lastSyncActiveRootDefinitions": "default:27ed517b-a25e-4af0-8f30-177d5b22b7a4"}}'
14:06:22 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":1386853540566,"repositoryId":"default","fileSystemItem":{"digest":"39181c49e54b6e5ae8be13ab66f9fdb8","digestAlgorithm":"md5","downloadURL":"nxbigfile/default/63675ecf-9d21-4a46-a8b6-6eba7fcf8207/blobholder:0/Local%20file%202.odt","canUpdate":true,"name":"Local file 2.odt","id":"defaultFileSystemItemFactory#default#63675ecf-9d21-4a46-a8b6-6eba7fcf8207","path":"/org.nuxeo.drive.service.impl.DefaultTopLevelFolderItemFactory#/defaultSyncRootFolderItemFactory#default#27ed517b-a25e-4af0-8f30-177d5b22b7a4/defaultFileSystemItemFactory#default#a491983b-fc20-4496-88bb-2277c871ee9b/defaultFileSystemItemFactory#default#63675ecf-9d21-4a46-a8b6-6eba7fcf8207","creationDate":1386853540566,"folder":false,"creator":"nuxeoDriveTestUser_user_1","parentId":"defaultFileSystemItemFactory#default#a491983b-fc20-4496-88bb-2277c871ee9b","lastModificationDate":1386853540566,"canRename":true,"canDelete":true,"userName":"nuxeoDriveTestUser_user_1"},"fileSystemItemId":"defaultFileSystemItemFactory#default#63675ecf-9d21-4a46-a8b6-6eba7fcf8207","fileSystemItemName":"Local file 2.odt","docUuid":"63675ecf-9d21-4a46-a8b6-6eba7fcf8207"},{"eventId":"documentCreated","eventDate":1386853540301,"repositoryId":"default","fileSystemItem":{"digest":"b8ab309a6b9a3f448092a136afa8fa25","digestAlgorithm":"md5","downloadURL":"nxbigfile/default/24ebbad8-9f96-4e79-b218-c6eab937219e/blobholder:0/Local%20file%201.odt","canUpdate":true,"name":"Local file 1.odt","id":"defaultFileSystemItemFactory#default#24ebbad8-9f96-4e79-b218-c6eab937219e","path":"/org.nuxeo.drive.service.impl.DefaultTopLevelFolderItemFactory#/defaultSyncRootFolderItemFactory#default#27ed517b-a25e-4af0-8f30-177d5b22b7a4/defaultFileSystemItemFactory#default#a491983b-fc20-4496-88bb-2277c871ee9b/defaultFileSystemItemFactory#default#24ebbad8-9f96-4e79-b218-c6eab937219e","creationDate":1386853540301,"folder":false,"creator":"nuxeoDriveTestUser_user_1","parentId":"defaultFileSystemItemFactory#default#a491983b-fc20-4496-88bb-2277c871ee9b","lastModificationDate":1386853540301,"canRename":true,"canDelete":true,"userName":"nuxeoDriveTestUser_user_1"},"fileSystemItemId":"defaultFileSystemItemFactory#default#24ebbad8-9f96-4e79-b218-c6eab937219e","fileSystemItemName":"Local file 1.odt","docUuid":"24ebbad8-9f96-4e79-b218-c6eab937219e"},{"eventId":"documentCreated","eventDate":1386853539959,"repositoryId":"default","fileSystemItem":{"canCreateChild":true,"name":"Local folder","id":"defaultFileSystemItemFactory#default#a491983b-fc20-4496-88bb-2277c871ee9b","path":"/org.nuxeo.drive.service.impl.DefaultTopLevelFolderItemFactory#/defaultSyncRootFolderItemFactory#default#27ed517b-a25e-4af0-8f30-177d5b22b7a4/defaultFileSystemItemFactory#default#a491983b-fc20-4496-88bb-2277c871ee9b","creationDate":1386853539959,"folder":true,"creator":"nuxeoDriveTestUser_user_1","parentId":"defaultSyncRootFolderItemFactory#default#27ed517b-a25e-4af0-8f30-177d5b22b7a4","lastModificationDate":1386853539959,"canRename":true,"canDelete":true,"userName":"nuxeoDriveTestUser_user_1"},"fileSystemItemId":"defaultFileSystemItemFactory#default#a491983b-fc20-4496-88bb-2277c871ee9b","fileSystemItemName":"Local folder","docUuid":"a491983b-fc20-4496-88bb-2277c871ee9b"}],"syncDate":1386853541000,"activeSynchronizationRootDefinitions":"default:27ed517b-a25e-4af0-8f30-177d5b22b7a4","hasTooManyChanges":false}'
14:06:22 nxdrive.synchronizer: DEBUG: 3 remote changes detected on http://localhost:8080/nuxeo/
14:06:22 nxdrive.synchronizer: DEBUG: Refreshing remote state info for doc_pair 'Local file 2.odt'
14:06:22 nxdrive.model: TRACE: Use last known modification time to detect updates: local DB, server = '2013-12-12 14:05:40', '2013-12-12 14:05:40'
14:06:22 nxdrive.synchronizer: DEBUG: Refreshing remote state info for doc_pair 'Local file 1.odt'
14:06:22 nxdrive.model: TRACE: Use last known modification time to detect updates: local DB, server = '2013-12-12 14:05:40', '2013-12-12 14:05:40'
14:06:22 nxdrive.synchronizer: DEBUG: Refreshing remote state info for doc_pair 'Local folder'
14:06:22 nxdrive.model: TRACE: Use last known modification time to detect updates: local DB, server = '2013-12-12 14:05:39', '2013-12-12 14:05:39'
14:06:22 nxdrive.client.base_automation_client: TRACE: Dumping JSON structure: {'params': {'id': u'defaultFileSystemItemFactory#default#a491983b-fc20-4496-88bb-2277c871ee9b'}}
14:06:22 nxdrive.client.base_automation_client: TRACE: Calling http://localhost:8080/nuxeo/site/automation/NuxeoDrive.GetChildren with headers {'X-Authentication-Token': 'fcd4b71e-3976-454a-b7b0-d8d8e89ba55b', 'X-NXDocumentProperties': '*', 'X-Device-Id': u'14a7e54c632e11e38f5e00219b008ad3', '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#a491983b-fc20-4496-88bb-2277c871ee9b"}}'
14:06:22 nxdrive.client.base_automation_client: TRACE: Response for 'http://localhost:8080/nuxeo/site/automation/NuxeoDrive.GetChildren' with cookies [] and JSON payload: '[{"digest":"b8ab309a6b9a3f448092a136afa8fa25","digestAlgorithm":"md5","downloadURL":"nxbigfile/default/24ebbad8-9f96-4e79-b218-c6eab937219e/blobholder:0/Local%20file%201.odt","canUpdate":true,"name":"Local file 1.odt","id":"defaultFileSystemItemFactory#default#24ebbad8-9f96-4e79-b218-c6eab937219e","path":"/org.nuxeo.drive.service.impl.DefaultTopLevelFolderItemFactory#/defaultSyncRootFolderItemFactory#default#27ed517b-a25e-4af0-8f30-177d5b22b7a4/defaultFileSystemItemFactory#default#a491983b-fc20-4496-88bb-2277c871ee9b/defaultFileSystemItemFactory#default#24ebbad8-9f96-4e79-b218-c6eab937219e","creationDate":1386853540301,"folder":false,"creator":"nuxeoDriveTestUser_user_1","parentId":"defaultFileSystemItemFactory#default#a491983b-fc20-4496-88bb-2277c871ee9b","lastModificationDate":1386853540301,"canRename":true,"canDelete":true,"userName":"nuxeoDriveTestUser_user_1"},{"digest":"39181c49e54b6e5ae8be13ab66f9fdb8","digestAlgorithm":"md5","downloadURL":"nxbigfile/default/63675ecf-9d21-4a46-a8b6-6eba7fcf8207/blobholder:0/Local%20file%202.odt","canUpdate":true,"name":"Local file 2.odt","id":"defaultFileSystemItemFactory#default#63675ecf-9d21-4a46-a8b6-6eba7fcf8207","path":"/org.nuxeo.drive.service.impl.DefaultTopLevelFolderItemFactory#/defaultSyncRootFolderItemFactory#default#27ed517b-a25e-4af0-8f30-177d5b22b7a4/defaultFileSystemItemFactory#default#a491983b-fc20-4496-88bb-2277c871ee9b/defaultFileSystemItemFactory#default#63675ecf-9d21-4a46-a8b6-6eba7fcf8207","creationDate":1386853540566,"folder":false,"creator":"nuxeoDriveTestUser_user_1","parentId":"defaultFileSystemItemFactory#default#a491983b-fc20-4496-88bb-2277c871ee9b","lastModificationDate":1386853540566,"canRename":true,"canDelete":true,"userName":"nuxeoDriveTestUser_user_1"}]'
14:06:22 nxdrive.model: TRACE: Selecting refs set([u'defaultFileSystemItemFactory#default#63675ecf-9d21-4a46-a8b6-6eba7fcf8207', u'defaultFileSystemItemFactory#default#24ebbad8-9f96-4e79-b218-c6eab937219e'])
14:06:22 nxdrive.model: TRACE: Selecting paths set([u'/Nuxeo Drive Test Workspace'])
14:06:22 nxdrive.model: TRACE: Selecting paths set([])
14:06:22 nxdrive.synchronizer: TRACE: Marking u'defaultFileSystemItemFactory#default#a491983b-fc20-4496-88bb-2277c871ee9b' as locally deleted
14:06:22 nxdrive.synchronizer: TRACE: Marking u'defaultFileSystemItemFactory#default#24ebbad8-9f96-4e79-b218-c6eab937219e' as locally deleted
14:06:22 nxdrive.model: TRACE: Updated state for LastKnownState<local_folder=u'/tmp/tmpjtj05a-nxdrive-tests-user-1/Nuxeo Drive', local_path=u'/Nuxeo Drive Test Workspace/Local folder/Local file 1.odt', remote_name=u'Local file 1.odt', local_state='deleted', remote_state=u'synchronized', pair_state='locally_deleted'>
14:06:22 nxdrive.synchronizer: TRACE: Marking u'defaultFileSystemItemFactory#default#63675ecf-9d21-4a46-a8b6-6eba7fcf8207' as locally deleted
14:06:22 nxdrive.model: TRACE: Updated state for LastKnownState<local_folder=u'/tmp/tmpjtj05a-nxdrive-tests-user-1/Nuxeo Drive', local_path=u'/Nuxeo Drive Test Workspace/Local folder/Local file 2.odt', remote_name=u'Local file 2.odt', local_state='deleted', remote_state=u'synchronized', pair_state='locally_deleted'>
14:06:22 nxdrive.model: TRACE: Updated state for LastKnownState<local_folder=u'/tmp/tmpjtj05a-nxdrive-tests-user-1/Nuxeo Drive', local_path=u'/Nuxeo Drive Test Workspace/Local folder', remote_name=u'Local folder', local_state='deleted', remote_state=u'synchronized', pair_state='locally_deleted'>
14:06:22 nxdrive.client.base_automation_client: TRACE: Dumping JSON structure: {'params': {'id': u'defaultFileSystemItemFactory#default#a491983b-fc20-4496-88bb-2277c871ee9b'}}
14:06:22 nxdrive.client.base_automation_client: TRACE: Calling http://localhost:8080/nuxeo/site/automation/NuxeoDrive.GetFileSystemItem with headers {'X-Authentication-Token': 'fcd4b71e-3976-454a-b7b0-d8d8e89ba55b', 'X-NXDocumentProperties': '*', 'X-Device-Id': u'14a7e54c632e11e38f5e00219b008ad3', '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#a491983b-fc20-4496-88bb-2277c871ee9b"}}'
14:06:22 nxdrive.client.base_automation_client: TRACE: Response for 'http://localhost:8080/nuxeo/site/automation/NuxeoDrive.GetFileSystemItem' with cookies [] and JSON payload: '{"canCreateChild":true,"name":"Local folder","id":"defaultFileSystemItemFactory#default#a491983b-fc20-4496-88bb-2277c871ee9b","path":"/org.nuxeo.drive.service.impl.DefaultTopLevelFolderItemFactory#/defaultSyncRootFolderItemFactory#default#27ed517b-a25e-4af0-8f30-177d5b22b7a4/defaultFileSystemItemFactory#default#a491983b-fc20-4496-88bb-2277c871ee9b","creationDate":1386853539959,"folder":true,"creator":"nuxeoDriveTestUser_user_1","parentId":"defaultSyncRootFolderItemFactory#default#27ed517b-a25e-4af0-8f30-177d5b22b7a4","lastModificationDate":1386853539959,"canRename":true,"canDelete":true,"userName":"nuxeoDriveTestUser_user_1"}'
14:06:22 nxdrive.model: TRACE: Use last known modification time to detect updates: local DB, server = '2013-12-12 14:05:39', '2013-12-12 14:05:39'
14:06:22 nxdrive.synchronizer: DEBUG: Deleting or unregistering remote document 'Local folder' (defaultFileSystemItemFactory#default#a491983b-fc20-4496-88bb-2277c871ee9b)
14:06:22 nxdrive.client.base_automation_client: TRACE: Dumping JSON structure: {'params': {'id': u'defaultFileSystemItemFactory#default#a491983b-fc20-4496-88bb-2277c871ee9b'}}
14:06:22 nxdrive.client.base_automation_client: TRACE: Calling http://localhost:8080/nuxeo/site/automation/NuxeoDrive.Delete with headers {'X-Authentication-Token': 'fcd4b71e-3976-454a-b7b0-d8d8e89ba55b', 'X-NXDocumentProperties': '*', 'X-Device-Id': u'14a7e54c632e11e38f5e00219b008ad3', '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#a491983b-fc20-4496-88bb-2277c871ee9b"}}'
14:06:22 nxdrive.client.base_automation_client: TRACE: Response for 'http://localhost:8080/nuxeo/site/automation/NuxeoDrive.Delete' with cookies [] and content-type: ''
14:06:22 nxdrive.synchronizer: TRACE: Handling Local file 1.odt for deletion: last_local_updated = 2013-12-12 14:05:39, last_sync_date = 2013-12-12 14:05:40
14:06:22 nxdrive.synchronizer: TRACE: Handling Local file 2.odt for deletion: last_local_updated = 2013-12-12 14:05:39, last_sync_date = 2013-12-12 14:05:40
14:06:22 nxdrive.synchronizer: TRACE: Handling Local folder for deletion: last_local_updated = 2013-12-12 14:05:39, last_sync_date = 2013-12-12 14:05:39
14:06:22 nxdrive.synchronizer: DEBUG: [/tmp/tmpjtj05a-nxdrive-tests-user-1/Nuxeo Drive] - [http://localhost:8080/nuxeo/]: synchronized: 1, pending: 3, local: 0.170s, remote: 0.160s sync: 0.262s
14:06:22 nxdrive.synchronizer: INFO: Stopping synchronization after 1 loops
14:06:22 nxdrive.client.base_automation_client: TRACE: Dumping JSON structure: {'params': {'query': u"SELECT * FROM Document WHERE ecm:path = '/default-domain/workspaces/nuxeo-drive-test-workspace/Local folder' AND ecm:currentLifeCycleState != 'deleted' AND ecm:isCheckedInVersion = 0 LIMIT 1"}}
14:06:22 nxdrive.client.base_automation_client: TRACE: Calling http://localhost:8080/nuxeo/site/automation/Document.Query 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 bnV4ZW9Ecml2ZVRlc3RVc2VyX3VzZXJfMTo2MDQwNmQ='}, cookies [] and JSON payload '{"params": {"query": "SELECT * FROM Document WHERE ecm:path = \'/default-domain/workspaces/nuxeo-drive-test-workspace/Local folder\' AND ecm:currentLifeCycleState != \'deleted\' AND ecm:isCheckedInVersion = 0 LIMIT 1"}}'
14:06:22 nxdrive.client.base_automation_client: TRACE: Response for 'http://localhost:8080/nuxeo/site/automation/Document.Query' with cookies [] and JSON payload: '{"entity-type":"documents","entries":[]}'
14:06:22 nxdrive.client.base_automation_client: TRACE: Dumping JSON structure: {'params': {'query': u"SELECT * FROM Document WHERE ecm:path = '/default-domain/workspaces/nuxeo-drive-test-workspace/Local folder/Local file 1.odt' AND ecm:currentLifeCycleState != 'deleted' AND ecm:isCheckedInVersion = 0 LIMIT 1"}}
14:06:22 nxdrive.client.base_automation_client: TRACE: Calling http://localhost:8080/nuxeo/site/automation/Document.Query 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 bnV4ZW9Ecml2ZVRlc3RVc2VyX3VzZXJfMTo2MDQwNmQ='}, cookies [] and JSON payload '{"params": {"query": "SELECT * FROM Document WHERE ecm:path = \'/default-domain/workspaces/nuxeo-drive-test-workspace/Local folder/Local file 1.odt\' AND ecm:currentLifeCycleState != \'deleted\' AND ecm:isCheckedInVersion = 0 LIMIT 1"}}'
14:06:22 nxdrive.client.base_automation_client: TRACE: Response for 'http://localhost:8080/nuxeo/site/automation/Document.Query' with cookies [] and JSON payload: '{"entity-type":"documents","entries":[{"entity-type":"document","repository":"default","uid":"24ebbad8-9f96-4e79-b218-c6eab937219e","path":"/default-domain/workspaces/nuxeo-drive-test-workspace/Local folder/Local file 1.odt","type":"File","state":"project","versionLabel":"0.0","title":"Local file 1.odt","lastModified":"2013-12-12T13:05:40.30Z","properties":{"dc:creator":"nuxeoDriveTestUser_user_1","dc:source":null,"dc:contributors":["nuxeoDriveTestUser_user_1"],"dc:nature":null,"dc:created":"2013-12-12T13:05:40.30Z","dc:description":null,"dc:rights":null,"dc:subjects":[],"dc:publisher":null,"dc:valid":null,"dc:format":null,"dc:issued":null,"dc:modified":"2013-12-12T13:05:40.30Z","dc:expired":null,"dc:coverage":null,"dc:language":null,"dc:title":"Local file 1.odt","dc:lastContributor":"nuxeoDriveTestUser_user_1","uid:uid":null,"uid:minor_version":"0","uid:major_version":"0","files:files":[],"common:icon":"/icons/odt.png","common:icon-expanded":null,"common:size":"13","file:content":{"name":"Local file 1.odt","mime-type":"application/vnd.oasis.opendocument.text","encoding":null,"digest":"b8ab309a6b9a3f448092a136afa8fa25","length":"13","data":"files/24ebbad8-9f96-4e79-b218-c6eab937219e?path=%2Fcontent"},"file:filename":"Local file 1.odt","relatedtext:relatedtextresources":[]},"facets":["Downloadable","Commentable","Versionable","Publishable","HasRelatedText"],"changeToken":"1386853540301","contextParameters":{}}]}'
14:06:22 --------------------- >> end captured logging << ---------------------

Attachments

Activity

People

Assignee:

Antoine Taillefer

Reporter:

Antoine Taillefer

Participants:

Antoine Taillefer, Jenkins

Votes:

0 Vote for this issue

Watchers:

2 Start watching this issue

Dates

Created:

2013-12-12 14:18

Updated:

2014-02-13 18:51

Resolved:

2014-02-12 13:50

Time Tracking

Estimated:

Remaining:

Logged: