Class org.syncany.tests.integration.scenarios.EvilCUpWithoutDownScenarioTest

1

tests

0

failures

0

ignored

0.736s

duration

100%

successful

Tests

Test Duration Result
testEvilC 0.736s passed

Standard error

16-2-17 8:19:53.261 | org.syncany.plu | Test worker    | FINE : Validating required fields
16-2-17 8:19:53.262 | LocalTransferMa | Test worker    | INFO : testTargetExists: Target exists.
16-2-17 8:19:53.277 | org.syncany.plu | Test worker    | FINE : Validating required fields
16-2-17 8:19:53.277 | TransferManager | Test worker    | INFO : Building LocalTransferManager from config 'syncany-170216081829395-47281-client-A' ...
16-2-17 8:19:53.277 | TransferManager | Test worker    | INFO : - With feature ReadAfterWriteConsistent
16-2-17 8:19:53.277 | TransferManager | Test worker    | INFO : - With feature Retriable
16-2-17 8:19:53.277 | TransferManager | Test worker    | FINE : - Checking if selected features supported ...
16-2-17 8:19:53.277 | TransferManager | Test worker    | FINE : - Checking for duplicate features ...
16-2-17 8:19:53.277 | TransferManager | Test worker    | FINE : - Checking required feature TransactionAware in LocalTransferManager ...
16-2-17 8:19:53.277 | TransferManager | Test worker    | FINE : - Checking required feature Retriable in LocalTransferManager ...
16-2-17 8:19:53.277 | TransferManager | Test worker    | INFO : - SKIPPING unsupported optional feature ReadAfterWriteConsistent
16-2-17 8:19:53.277 | TransferManager | Test worker    | FINE : - Wrapping TransferManager LocalTransferManager in RetriableFeatureTransferManager
16-2-17 8:19:53.277 | org.syncany.plu | Test worker    | FINE : Validating required fields
16-2-17 8:19:53.277 | TransferManager | Test worker    | INFO : Building LocalTransferManager from config 'syncany-170216081829395-47281-client-A' ...
16-2-17 8:19:53.277 | TransferManager | Test worker    | INFO : - With feature ReadAfterWriteConsistent
16-2-17 8:19:53.278 | TransferManager | Test worker    | INFO : - With feature Retriable
16-2-17 8:19:53.278 | TransferManager | Test worker    | INFO : - With feature PathAware
16-2-17 8:19:53.278 | TransferManager | Test worker    | INFO : - With feature TransactionAware
16-2-17 8:19:53.278 | TransferManager | Test worker    | FINE : - Checking if selected features supported ...
16-2-17 8:19:53.278 | TransferManager | Test worker    | FINE : - Checking for duplicate features ...
16-2-17 8:19:53.278 | TransferManager | Test worker    | FINE : - Checking required feature TransactionAware in LocalTransferManager ...
16-2-17 8:19:53.278 | TransferManager | Test worker    | FINE : - Checking required feature Retriable in LocalTransferManager ...
16-2-17 8:19:53.278 | TransferManager | Test worker    | INFO : - SKIPPING unsupported optional feature ReadAfterWriteConsistent
16-2-17 8:19:53.278 | TransferManager | Test worker    | FINE : - Wrapping TransferManager LocalTransferManager in RetriableFeatureTransferManager
16-2-17 8:19:53.278 | TransferManager | Test worker    | INFO : - SKIPPING unsupported optional feature PathAware
16-2-17 8:19:53.278 | TransferManager | Test worker    | FINE : - Wrapping TransferManager RetriableFeatureTransferManager in TransactionAwareFeatureTransferManager
16-2-17 8:19:53.296 | hsqldb.db.HSQLD | Test worker    | INFO : Checkpoint start
16-2-17 8:19:53.297 | hsqldb.db.HSQLD | Test worker    | INFO : checkpointClose start
16-2-17 8:19:53.307 | hsqldb.db.HSQLD | Test worker    | FINE : log close size: 0
16-2-17 8:19:53.310 | hsqldb.db.HSQLD | Test worker    | INFO : checkpointClose end
16-2-17 8:19:53.310 | hsqldb.db.HSQLD | Test worker    | INFO : Checkpoint end - txts: 1
16-2-17 8:19:53.317 | DatabaseConnect | Test worker    | INFO : Found 0 tables.
16-2-17 8:19:53.317 | DatabaseConnect | Test worker    | INFO : Database has no tables. Creating tables from script.create.all.sql
16-2-17 8:19:53.318 | SqlRunner       | Test worker    | INFO : SQL (comment): -- Tables
16-2-17 8:19:53.318 | SqlRunner       | Test worker    | INFO : SQL:  CREATE CACHED TABLE IF NOT EXISTS databaseversion ( id int NOT NULL IDENTITY, status varchar(45) NOT NULL, localtime datetime NOT NULL, client varchar(45) NOT NULL, vectorclock_serialized varchar(1024) NOT NULL, UNIQUE (vectorclock_serialized) ); 
16-2-17 8:19:53.320 | SqlRunner       | Test worker    | INFO : SQL:  CREATE CACHED TABLE IF NOT EXISTS  chunk ( checksum varchar(40) NOT NULL, databaseversion_id int NOT NULL, size bigint NOT NULL, PRIMARY KEY (checksum), FOREIGN KEY (databaseversion_id) REFERENCES databaseversion (id) ON DELETE NO ACTION ON UPDATE NO ACTION ); 
16-2-17 8:19:53.323 | SqlRunner       | Test worker    | INFO : SQL:  CREATE CACHED TABLE IF NOT EXISTS  databaseversion_vectorclock ( databaseversion_id int NOT NULL, client varchar(45) NOT NULL, logicaltime int NOT NULL, PRIMARY KEY (databaseversion_id, client), FOREIGN KEY (databaseversion_id) REFERENCES databaseversion (id) ON DELETE NO ACTION ON UPDATE NO ACTION ); 
16-2-17 8:19:53.325 | SqlRunner       | Test worker    | INFO : SQL:  CREATE CACHED TABLE IF NOT EXISTS  filecontent ( checksum varchar(40) NOT NULL, databaseversion_id int NOT NULL, size bigint NOT NULL, PRIMARY KEY (checksum), FOREIGN KEY (databaseversion_id) REFERENCES databaseversion (id) ON DELETE NO ACTION ON UPDATE NO ACTION ); 
16-2-17 8:19:53.327 | SqlRunner       | Test worker    | INFO : SQL:  CREATE CACHED TABLE IF NOT EXISTS  filecontent_chunk ( filecontent_checksum varchar(40) NOT NULL, chunk_checksum varchar(40) NOT NULL, num int NOT NULL, PRIMARY KEY (filecontent_checksum, chunk_checksum, num), FOREIGN KEY (filecontent_checksum) REFERENCES filecontent (checksum) ON DELETE NO ACTION ON UPDATE NO ACTION, FOREIGN KEY (chunk_checksum) REFERENCES chunk (checksum) ON DELETE NO ACTION ON UPDATE NO ACTION ); 
16-2-17 8:19:53.329 | SqlRunner       | Test worker    | INFO : SQL:  CREATE CACHED TABLE IF NOT EXISTS  filehistory ( id varchar(40) NOT NULL, databaseversion_id int NOT NULL, PRIMARY KEY (id, databaseversion_id), FOREIGN KEY (databaseversion_id) REFERENCES databaseversion (id) ON DELETE NO ACTION ON UPDATE NO ACTION ); 
16-2-17 8:19:53.332 | SqlRunner       | Test worker    | INFO : SQL:  CREATE CACHED TABLE IF NOT EXISTS  fileversion ( filehistory_id varchar(40) NOT NULL, version int NOT NULL, databaseversion_id int NOT NULL, path varchar(1024) NOT NULL, type varchar(45) NOT NULL, status varchar(45) NOT NULL, size bigint NOT NULL, lastmodified datetime NOT NULL, linktarget varchar(1024), filecontent_checksum varchar(40) DEFAULT NULL, updated datetime NOT NULL, posixperms varchar(45) DEFAULT NULL, dosattrs varchar(45) DEFAULT NULL, PRIMARY KEY (filehistory_id, version, databaseversion_id), FOREIGN KEY (filehistory_id, databaseversion_id) REFERENCES filehistory (id, databaseversion_id) ON DELETE NO ACTION ON UPDATE NO ACTION, FOREIGN KEY (filecontent_checksum) REFERENCES filecontent (checksum) ON DELETE NO ACTION ON UPDATE NO ACTION ); 
16-2-17 8:19:53.334 | SqlRunner       | Test worker    | INFO : SQL:  CREATE CACHED TABLE IF NOT EXISTS  multichunk ( id varchar(40) NOT NULL, databaseversion_id int NOT NULL, size bigint NOT NULL, PRIMARY KEY (id), FOREIGN KEY (databaseversion_id) REFERENCES databaseversion (id) ON DELETE NO ACTION ON UPDATE NO ACTION ); 
16-2-17 8:19:53.337 | SqlRunner       | Test worker    | INFO : SQL:  CREATE CACHED TABLE IF NOT EXISTS  multichunk_chunk ( multichunk_id varchar(40) NOT NULL, chunk_checksum varchar(40) NOT NULL, PRIMARY KEY (multichunk_id, chunk_checksum), FOREIGN KEY (multichunk_id) REFERENCES multichunk (id) ON DELETE NO ACTION ON UPDATE NO ACTION, FOREIGN KEY (chunk_checksum) REFERENCES chunk (checksum) ON DELETE NO ACTION ON UPDATE NO ACTION ); 
16-2-17 8:19:53.339 | SqlRunner       | Test worker    | INFO : SQL:  CREATE CACHED TABLE IF NOT EXISTS  multichunk_muddy ( id varchar(40) NOT NULL, machine_name varchar(255) NOT NULL, machine_version int NOT NULL, PRIMARY KEY (id) ); 
16-2-17 8:19:53.341 | SqlRunner       | Test worker    | INFO : SQL:  CREATE CACHED TABLE IF NOT EXISTS  known_databases ( id int NOT NULL IDENTITY, client varchar(45) NOT NULL, filenumber int NOT NULL, UNIQUE (client, filenumber) ); 
16-2-17 8:19:53.343 | SqlRunner       | Test worker    | INFO : SQL:  CREATE CACHED TABLE IF NOT EXISTS  general_settings ( key varchar(255) NOT NULL, value varchar(255) NOT NULL, PRIMARY KEY (key) ); 
16-2-17 8:19:53.345 | SqlRunner       | Test worker    | INFO : SQL (comment): -- Non-primary indices
16-2-17 8:19:53.345 | SqlRunner       | Test worker    | INFO : SQL:   CREATE INDEX idx_databaseversion_status ON databaseversion (status); 
16-2-17 8:19:53.346 | hsqldb.db.HSQLD | Test worker    | INFO : dataFileCache open start
16-2-17 8:19:53.348 | hsqldb.db.HSQLD | Test worker    | INFO : dataFileCache open end
16-2-17 8:19:53.350 | SqlRunner       | Test worker    | INFO : SQL: CREATE INDEX idx_databaseversion_vectorclock_serialized ON databaseversion (vectorclock_serialized); 
16-2-17 8:19:53.352 | SqlRunner       | Test worker    | INFO : SQL: CREATE INDEX idx_fileversion_path ON fileversion (path); 
16-2-17 8:19:53.354 | SqlRunner       | Test worker    | INFO : SQL: CREATE INDEX idx_fileversion_status ON fileversion (status); 
16-2-17 8:19:53.356 | SqlRunner       | Test worker    | INFO : SQL: CREATE INDEX idx_fileversion_filecontent_checksum ON fileversion (filecontent_checksum); 
16-2-17 8:19:53.358 | SqlRunner       | Test worker    | INFO : SQL (comment): -- Views
16-2-17 8:19:53.358 | SqlRunner       | Test worker    | INFO : SQL:    CREATE VIEW databaseversion_master AS SELECT dbv.*, vc.logicaltime as client_version FROM databaseversion dbv JOIN databaseversion_vectorclock vc on dbv.id=vc.databaseversion_id and dbv.client=vc.client WHERE dbv.status='MASTER'; 
16-2-17 8:19:53.361 | SqlRunner       | Test worker    | INFO : SQL:  CREATE VIEW fileversion_master AS SELECT fv0.* FROM fileversion fv0 JOIN databaseversion dbv ON fv0.databaseversion_id=dbv.id AND dbv.status='MASTER'; 
16-2-17 8:19:53.363 | SqlRunner       | Test worker    | INFO : SQL:  CREATE VIEW fileversion_master_maxversion AS SELECT DISTINCT filehistory_id, MAX(version) version FROM fileversion_master GROUP BY filehistory_id; 
16-2-17 8:19:53.365 | SqlRunner       | Test worker    | INFO : SQL:  CREATE VIEW fileversion_master_last AS SELECT fv.* FROM fileversion_master_maxversion fvmax JOIN fileversion_master fv ON fvmax.filehistory_id=fv.filehistory_id AND fvmax.version=fv.version WHERE fv.status<>'DELETED'; 
16-2-17 8:19:53.368 | SqlRunner       | Test worker    | INFO : SQL (comment): -- Full Views
16-2-17 8:19:53.368 | SqlRunner       | Test worker    | INFO : SQL:    create view filehistory_full as select dbv.status as databaseversion_status, dbv.localtime as databaseversion_localtime, dbv.client as databaseversion_client, dbv.vectorclock_serialized as databaseversion_vectorclock_serialized, fh.* from databaseversion dbv join filehistory fh on dbv.id=fh.databaseversion_id; 
16-2-17 8:19:53.372 | SqlRunner       | Test worker    | INFO : SQL:  create view fileversion_full as select fhf.databaseversion_status, fhf.databaseversion_localtime, fhf.databaseversion_client, fhf.databaseversion_vectorclock_serialized, fv.* from filehistory_full fhf join fileversion fv on fhf.id=fv.filehistory_id and fhf.databaseversion_id=fv.databaseversion_id; 
16-2-17 8:19:53.374 | SqlRunner       | Test worker    | INFO : SQL (comment): -- Functions
16-2-17 8:19:53.374 | SqlRunner       | Test worker    | INFO : SQL (new delimiter): end;
16-2-17 8:19:53.374 | SqlRunner       | Test worker    | INFO : SQL:     create function substr_count(haystack varchar(255), needle varchar(255)) returns integer begin atomic declare strCount integer; declare lastIndex integer;  set strCount = 0; set lastIndex = 1;  while lastIndex <> 0 do set lastIndex = locate(needle, haystack, lastIndex);  if lastIndex <> 0 then set strCount = strCount + 1; set lastIndex = lastIndex + length(needle); end if; end while;  return strCount; end; 
16-2-17 8:19:53.377 | UpOperation     | Test worker    | INFO : 
16-2-17 8:19:53.377 | UpOperation     | Test worker    | INFO : Running 'Sync up' at client A ...
16-2-17 8:19:53.377 | UpOperation     | Test worker    | INFO : --------------------------------------------
16-2-17 8:19:53.377 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event UpStartSyncExternalEvent (org.syncany.operations.daemon.messages.UpStartSyncExternalEvent@4293f1f3) ...
16-2-17 8:19:53.378 | DatabaseConnect | Test worker    | INFO : Found 12 tables.
16-2-17 8:19:53.378 | StatusOperation | Test worker    | INFO : 
16-2-17 8:19:53.378 | StatusOperation | Test worker    | INFO : Running 'Status' at client A ...
16-2-17 8:19:53.378 | StatusOperation | Test worker    | INFO : --------------------------------------------
16-2-17 8:19:53.378 | StatusOperation | Test worker    | INFO : Force checksum ENABLED.
16-2-17 8:19:53.378 | StatusOperation | Test worker    | INFO : Querying current file tree from database ...
16-2-17 8:19:53.378 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event StatusStartSyncExternalEvent (org.syncany.operations.daemon.messages.StatusStartSyncExternalEvent@5778f593) ...
16-2-17 8:19:53.379 | StatusOperation | Test worker    | INFO : Analyzing local folder /tmp/syncanytest/syncany-170216081829395-47281-client-A ...
16-2-17 8:19:53.379 | StatusOperation | Test worker    | FINE : - Ignoring file (syncany app-related): .syncany
16-2-17 8:19:53.379 | StatusOperation | Test worker    | FINE : - New file: A1
16-2-17 8:19:53.380 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event StatusEndSyncExternalEvent (org.syncany.operations.daemon.messages.StatusEndSyncExternalEvent@7684a7d1) ...
16-2-17 8:19:53.380 | AbstractTransfe | Test worker    | INFO : Looking for other running remote operations ...
16-2-17 8:19:53.380 | DatabaseConnect | Test worker    | INFO : Found 12 tables.
16-2-17 8:19:53.380 | LsRemoteOperati | Test worker    | INFO : 
16-2-17 8:19:53.380 | LsRemoteOperati | Test worker    | INFO : Running 'Remote Status' at client A ...
16-2-17 8:19:53.381 | LsRemoteOperati | Test worker    | INFO : --------------------------------------------
16-2-17 8:19:53.381 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event LsRemoteStartSyncExternalEvent (org.syncany.operations.daemon.messages.LsRemoteStartSyncExternalEvent@730cb459) ...
16-2-17 8:19:53.381 | LsRemoteOperati | Test worker    | INFO : Retrieving remote database list.
16-2-17 8:19:53.381 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event LsRemoteEndSyncExternalEvent (org.syncany.operations.daemon.messages.LsRemoteEndSyncExternalEvent@24c9fa2) ...
16-2-17 8:19:53.381 | UpOperation     | Test worker    | INFO : No remote changes, ready to upload.
16-2-17 8:19:53.381 | ActionFileHandl | Test worker    | INFO : Starting action for RemoteFile[name=action-up-A-1487233193278] ...
16-2-17 8:19:53.381 | ActionFileHandl | Test worker    | INFO : Uploading action file: RemoteFile[name=action-up-A-1487233193278]
16-2-17 8:19:53.382 | ActionFileHandl | Test worker    | INFO : Scheduling action renewal task for every 2 minutes, for RemoteFile[name=action-up-A-1487233193278] ...
16-2-17 8:19:53.383 | DatabaseConnect | Test worker    | INFO : Found 12 tables.
16-2-17 8:19:53.383 | TransactionAwar | Test worker    | INFO : Done rolling back previous transactions.
16-2-17 8:19:53.385 | AsyncIndexer    | AsyncI/syncany | INFO : Starting Indexing.
16-2-17 8:19:53.385 | InternalEventBu | AsyncI/syncany | INFO : Event bus 'LocalEventBus': Posting event UpIndexStartSyncExternalEvent (org.syncany.operations.daemon.messages.UpIndexStartSyncExternalEvent@64e4f1ef) ...
16-2-17 8:19:53.385 | Indexer         | AsyncI/syncany | FINE : - Looking for deleted files ...
16-2-17 8:19:53.385 | Indexer         | AsyncI/syncany | FINE : - +File /tmp/syncanytest/syncany-170216081829395-47281-client-A/A1
16-2-17 8:19:53.385 | Indexer         | AsyncI/syncany | FINE : - +FileContent: /tmp/syncanytest/syncany-170216081829395-47281-client-A/A1
16-2-17 8:19:53.387 | Indexer         | AsyncI/syncany | FINE : - Chunk new: 3bbf054717fb37875d5d76e6a673cd437309f4c5
16-2-17 8:19:53.388 | Indexer         | AsyncI/syncany | FINE : - +MultiChunk 8efba2273dacad4c288a0f9fcb26965e1d1ba7a2
16-2-17 8:19:53.388 | Indexer         | AsyncI/syncany | FINE : - Chunk > MultiChunk: 3bbf054717fb37875d5d76e6a673cd437309f4c5 > 8efba2273dacad4c288a0f9fcb26965e1d1ba7a2
16-2-17 8:19:53.388 | Indexer         | AsyncI/syncany | FINE : - Chunk > FileContent: 3bbf054717fb37875d5d76e6a673cd437309f4c5 > /tmp/syncanytest/syncany-170216081829395-47281-client-A/A1
16-2-17 8:19:53.389 | Indexer         | AsyncI/syncany | FINE : - /File: A1 (checksum 3bbf054717fb37875d5d76e6a673cd437309f4c5)
16-2-17 8:19:53.389 | Indexer         | AsyncI/syncany | FINE :    * No old file history found, starting new history (path: A1, checksum: 3bbf054717fb37875d5d76e6a673cd437309f4c5)
16-2-17 8:19:53.389 | FileVersionComp | AsyncI/syncany | INFO :      - [DELETED]: Local file DIFFERS from file version, actual file is NULL, for file A1
16-2-17 8:19:53.389 | Indexer         | AsyncI/syncany | INFO :    * Added file version:    FileVersion [version=1, path=A1, type=FILE, status=NEW, size=51200, lastModified=Thu Feb 16 08:19:53 UTC 2017, linkTarget=null, checksum=3bbf054717fb37875d5d76e6a673cd437309f4c5, updated=Thu Feb 16 08:19:53 UTC 2017, posixPermissions=rw-rw-r--, dosAttributes=--a-]
16-2-17 8:19:53.390 | Indexer         | AsyncI/syncany | INFO :      based on file version: null
16-2-17 8:19:53.390 | InternalEventBu | AsyncI/syncany | INFO : Event bus 'LocalEventBus': Posting event UpIndexChangesDetectedSyncExternalEvent (org.syncany.operations.daemon.messages.UpIndexChangesDetectedSyncExternalEvent@1a53ecf5) ...
16-2-17 8:19:53.390 | Indexer         | AsyncI/syncany | FINE : - /MultiChunk 8efba2273dacad4c288a0f9fcb26965e1d1ba7a2
16-2-17 8:19:53.390 | InternalEventBu | AsyncI/syncany | INFO : Event bus 'LocalEventBus': Posting event UpIndexEndSyncExternalEvent (org.syncany.operations.daemon.messages.UpIndexEndSyncExternalEvent@6c8975ed) ...
16-2-17 8:19:53.390 | Indexer         | AsyncI/syncany | FINE : Processed new database version: DatabaseVersion [header=UnknownMachine/()/T=1487233193385, chunks=1, multiChunks=1, fileContents=1, fileHistories=1]
16-2-17 8:19:53.390 | UpOperation     | Test worker    | INFO : No DIRTY data found in database (no dirty databases); Nothing to do here.
16-2-17 8:19:53.391 | UpOperation     | Test worker    | INFO : Last vector clock was: ()
16-2-17 8:19:53.391 | UpOperation     | Test worker    | INFO : Uploading new multichunks ...
16-2-17 8:19:53.391 | UpOperation     | Test worker    | INFO : - Uploading multichunk 8efba2273dacad4c288a0f9fcb26965e1d1ba7a2 from /tmp/syncanytest/syncany-170216081829395-47281-client-A/.syncany/cache/multichunk-8efba2273dacad4c288a0f9fcb26965e1d1ba7a2 to RemoteFile[name=multichunk-8efba2273dacad4c288a0f9fcb26965e1d1ba7a2] ...
16-2-17 8:19:53.392 | RemoteTransacti | Test worker    | INFO : - Adding file to TX for UPLOAD: /tmp/syncanytest/syncany-170216081829395-47281-client-A/.syncany/cache/multichunk-8efba2273dacad4c288a0f9fcb26965e1d1ba7a2 -> Temp. remote file: RemoteFile[name=temp-VejKW-multichunk-8efba2273dacad4c288a0f9fcb26965e1d1ba7a2], final location: RemoteFile[name=multichunk-8efba2273dacad4c288a0f9fcb26965e1d1ba7a2]
16-2-17 8:19:53.392 | UpOperation     | Test worker    | INFO : Uploading database: DatabaseVersion [header=A/(A1)/T=1487233193391, chunks=1, multiChunks=1, fileContents=1, fileHistories=1]
16-2-17 8:19:53.393 | UpOperation     | Test worker    | INFO : Saving local delta database, version A/(A1)/T=1487233193391 to file /tmp/syncanytest/syncany-170216081829395-47281-client-A/.syncany/cache/database-A-0000000001 ... 
16-2-17 8:19:53.393 | UpOperation     | Test worker    | INFO : - Saving database to /tmp/syncanytest/syncany-170216081829395-47281-client-A/.syncany/cache/database-A-0000000001 ...
16-2-17 8:19:53.393 | UpOperation     | Test worker    | INFO : - Uploading local delta database file ...
16-2-17 8:19:53.393 | UpOperation     | Test worker    | INFO : - Uploading /tmp/syncanytest/syncany-170216081829395-47281-client-A/.syncany/cache/database-A-0000000001 to RemoteFile[name=database-A-0000000001] ...
16-2-17 8:19:53.393 | RemoteTransacti | Test worker    | INFO : - Adding file to TX for UPLOAD: /tmp/syncanytest/syncany-170216081829395-47281-client-A/.syncany/cache/database-A-0000000001 -> Temp. remote file: RemoteFile[name=temp-qOfMB-database-A-0000000001], final location: RemoteFile[name=database-A-0000000001]
16-2-17 8:19:53.393 | UpOperation     | Test worker    | INFO : Adding shutdown hook (to allow resuming the upload) ...
16-2-17 8:19:53.394 | RemoteTransacti | Test worker    | INFO : Starting TX.commit() ...
16-2-17 8:19:53.395 | RemoteTransacti | Test worker    | INFO : Wrote transaction manifest to temporary file: /tmp/syncanytest/syncany-170216081829395-47281-client-A/.syncany/cache/temp-transaction-1386972732478212889.tmp
16-2-17 8:19:53.395 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event UpUploadFileSyncExternalEvent (org.syncany.operations.daemon.messages.UpUploadFileSyncExternalEvent@68503348) ...
16-2-17 8:19:53.395 | RemoteTransacti | Test worker    | INFO : - Uploading remote transaction file RemoteFile[name=transaction-5afc159d] ...
16-2-17 8:19:53.395 | RemoteTransacti | Test worker    | INFO : - Starting to upload data in commit.
16-2-17 8:19:53.395 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event UpUploadFileInTransactionSyncExternalEvent (org.syncany.operations.daemon.messages.UpUploadFileInTransactionSyncExternalEvent@27746bf3) ...
16-2-17 8:19:53.395 | RemoteTransacti | Test worker    | INFO : - Uploading /tmp/syncanytest/syncany-170216081829395-47281-client-A/.syncany/cache/multichunk-8efba2273dacad4c288a0f9fcb26965e1d1ba7a2 to temp. file RemoteFile[name=temp-VejKW-multichunk-8efba2273dacad4c288a0f9fcb26965e1d1ba7a2] ...
16-2-17 8:19:53.395 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event UpUploadFileInTransactionSyncExternalEvent (org.syncany.operations.daemon.messages.UpUploadFileInTransactionSyncExternalEvent@52e3e8a0) ...
16-2-17 8:19:53.395 | RemoteTransacti | Test worker    | INFO : - Uploading /tmp/syncanytest/syncany-170216081829395-47281-client-A/.syncany/cache/database-A-0000000001 to temp. file RemoteFile[name=temp-qOfMB-database-A-0000000001] ...
16-2-17 8:19:53.396 | RemoteTransacti | Test worker    | INFO : - Moving temp. file RemoteFile[name=temp-VejKW-multichunk-8efba2273dacad4c288a0f9fcb26965e1d1ba7a2] to final location RemoteFile[name=multichunk-8efba2273dacad4c288a0f9fcb26965e1d1ba7a2] ...
16-2-17 8:19:53.396 | RemoteTransacti | Test worker    | INFO : - Moving temp. file RemoteFile[name=temp-qOfMB-database-A-0000000001] to final location RemoteFile[name=database-A-0000000001] ...
16-2-17 8:19:53.396 | RemoteTransacti | Test worker    | INFO : - Deleting remote transaction file RemoteFile[name=transaction-5afc159d] ...
16-2-17 8:19:53.397 | RemoteTransacti | Test worker    | INFO : END of TX.commmit(): Succesfully committed transaction.
16-2-17 8:19:53.400 | RemoteTransacti | Test worker    | INFO : END of TX.delTemp(): Sucessfully deleted final files.
16-2-17 8:19:53.400 | UpOperation     | Test worker    | INFO : Persisting local SQL database (new database version A/(A1)/T=1487233193391) ...
16-2-17 8:19:53.402 | UpOperation     | Test worker    | INFO : Removing DIRTY database versions from database ...
16-2-17 8:19:53.402 | InternalEventBu | AsyncI/syncany | INFO : Event bus 'LocalEventBus': Posting event UpIndexMidSyncExternalEvent (org.syncany.operations.daemon.messages.UpIndexMidSyncExternalEvent@9a0e2fe) ...
16-2-17 8:19:53.409 | AsyncIndexer    | AsyncI/syncany | INFO : Stopping indexing. Signal end of stream with empty databaseversion
16-2-17 8:19:53.409 | UpOperation     | Test worker    | INFO : Adding database version to result changes:DatabaseVersion [header=A/(A1)/T=1487233193391, chunks=1, multiChunks=1, fileContents=1, fileHistories=1]
16-2-17 8:19:53.410 | UpOperation     | Test worker    | INFO : Committing local database.
16-2-17 8:19:53.410 | UpOperation     | Test worker    | FINE : Waiting for new database version.
16-2-17 8:19:53.410 | UpOperation     | Test worker    | FINE : Took new database version: DatabaseVersion [header=UnknownMachine/()/T=1487233193409, chunks=0, multiChunks=0, fileContents=0, fileHistories=0]
16-2-17 8:19:53.410 | UpOperation     | Test worker    | INFO : Sync up done.
16-2-17 8:19:53.412 | ActionFileHandl | Test worker    | INFO : Finishing action for RemoteFile[name=action-up-A-1487233193278] ...
16-2-17 8:19:53.412 | ActionFileHandl | Test worker    | INFO : Deleting action file: RemoteFile[name=action-up-A-1487233193278]
16-2-17 8:19:53.412 | AbstractTransfe | Test worker    | INFO : Cleaning own old action files ...
16-2-17 8:19:53.412 | Cache           | Test worker    | INFO : Cache size okay (51 KB), no need to clean (keep size is 500 MB)
16-2-17 8:19:53.412 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event UpEndSyncExternalEvent (org.syncany.operations.daemon.messages.UpEndSyncExternalEvent@56d2be31) ...
16-2-17 8:19:53.412 | org.syncany.plu | Test worker    | FINE : Validating required fields
16-2-17 8:19:53.412 | TransferManager | Test worker    | INFO : Building LocalTransferManager from config 'syncany-170216081829395-47281-client-A' ...
16-2-17 8:19:53.412 | TransferManager | Test worker    | INFO : - With feature ReadAfterWriteConsistent
16-2-17 8:19:53.412 | TransferManager | Test worker    | INFO : - With feature Retriable
16-2-17 8:19:53.412 | TransferManager | Test worker    | FINE : - Checking if selected features supported ...
16-2-17 8:19:53.412 | TransferManager | Test worker    | FINE : - Checking for duplicate features ...
16-2-17 8:19:53.412 | TransferManager | Test worker    | FINE : - Checking required feature TransactionAware in LocalTransferManager ...
16-2-17 8:19:53.412 | TransferManager | Test worker    | FINE : - Checking required feature Retriable in LocalTransferManager ...
16-2-17 8:19:53.412 | TransferManager | Test worker    | INFO : - SKIPPING unsupported optional feature ReadAfterWriteConsistent
16-2-17 8:19:53.413 | TransferManager | Test worker    | FINE : - Wrapping TransferManager LocalTransferManager in RetriableFeatureTransferManager
16-2-17 8:19:53.413 | org.syncany.plu | Test worker    | FINE : Validating required fields
16-2-17 8:19:53.413 | TransferManager | Test worker    | INFO : Building LocalTransferManager from config 'syncany-170216081829395-47281-client-A' ...
16-2-17 8:19:53.413 | TransferManager | Test worker    | INFO : - With feature ReadAfterWriteConsistent
16-2-17 8:19:53.413 | TransferManager | Test worker    | INFO : - With feature Retriable
16-2-17 8:19:53.413 | TransferManager | Test worker    | INFO : - With feature PathAware
16-2-17 8:19:53.413 | TransferManager | Test worker    | INFO : - With feature TransactionAware
16-2-17 8:19:53.413 | TransferManager | Test worker    | FINE : - Checking if selected features supported ...
16-2-17 8:19:53.413 | TransferManager | Test worker    | FINE : - Checking for duplicate features ...
16-2-17 8:19:53.413 | TransferManager | Test worker    | FINE : - Checking required feature TransactionAware in LocalTransferManager ...
16-2-17 8:19:53.413 | TransferManager | Test worker    | FINE : - Checking required feature Retriable in LocalTransferManager ...
16-2-17 8:19:53.413 | TransferManager | Test worker    | INFO : - SKIPPING unsupported optional feature ReadAfterWriteConsistent
16-2-17 8:19:53.413 | TransferManager | Test worker    | FINE : - Wrapping TransferManager LocalTransferManager in RetriableFeatureTransferManager
16-2-17 8:19:53.413 | TransferManager | Test worker    | INFO : - SKIPPING unsupported optional feature PathAware
16-2-17 8:19:53.413 | TransferManager | Test worker    | FINE : - Wrapping TransferManager RetriableFeatureTransferManager in TransactionAwareFeatureTransferManager
16-2-17 8:19:53.414 | DatabaseConnect | Test worker    | INFO : Found 12 tables.
16-2-17 8:19:53.414 | UpOperation     | Test worker    | INFO : 
16-2-17 8:19:53.414 | UpOperation     | Test worker    | INFO : Running 'Sync up' at client A ...
16-2-17 8:19:53.414 | UpOperation     | Test worker    | INFO : --------------------------------------------
16-2-17 8:19:53.414 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event UpStartSyncExternalEvent (org.syncany.operations.daemon.messages.UpStartSyncExternalEvent@26cbe763) ...
16-2-17 8:19:53.415 | DatabaseConnect | Test worker    | INFO : Found 12 tables.
16-2-17 8:19:53.415 | StatusOperation | Test worker    | INFO : 
16-2-17 8:19:53.415 | StatusOperation | Test worker    | INFO : Running 'Status' at client A ...
16-2-17 8:19:53.415 | StatusOperation | Test worker    | INFO : --------------------------------------------
16-2-17 8:19:53.415 | StatusOperation | Test worker    | INFO : Force checksum ENABLED.
16-2-17 8:19:53.415 | StatusOperation | Test worker    | INFO : Querying current file tree from database ...
16-2-17 8:19:53.415 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event StatusStartSyncExternalEvent (org.syncany.operations.daemon.messages.StatusStartSyncExternalEvent@53a598ce) ...
16-2-17 8:19:53.415 | StatusOperation | Test worker    | INFO : Analyzing local folder /tmp/syncanytest/syncany-170216081829395-47281-client-A ...
16-2-17 8:19:53.415 | StatusOperation | Test worker    | FINE : - Ignoring file (syncany app-related): .syncany
16-2-17 8:19:53.415 | StatusOperation | Test worker    | FINE : - New file: A2
16-2-17 8:19:53.416 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event StatusEndSyncExternalEvent (org.syncany.operations.daemon.messages.StatusEndSyncExternalEvent@bd1df92) ...
16-2-17 8:19:53.416 | AbstractTransfe | Test worker    | INFO : Looking for other running remote operations ...
16-2-17 8:19:53.416 | DatabaseConnect | Test worker    | INFO : Found 12 tables.
16-2-17 8:19:53.416 | LsRemoteOperati | Test worker    | INFO : 
16-2-17 8:19:53.416 | LsRemoteOperati | Test worker    | INFO : Running 'Remote Status' at client A ...
16-2-17 8:19:53.417 | LsRemoteOperati | Test worker    | INFO : --------------------------------------------
16-2-17 8:19:53.417 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event LsRemoteStartSyncExternalEvent (org.syncany.operations.daemon.messages.LsRemoteStartSyncExternalEvent@7a23b3ce) ...
16-2-17 8:19:53.417 | LsRemoteOperati | Test worker    | INFO : Retrieving remote database list.
16-2-17 8:19:53.417 | LsRemoteOperati | Test worker    | INFO : - Remote database database-A-0000000001 is already known (in local database). Ignoring.
16-2-17 8:19:53.417 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event LsRemoteEndSyncExternalEvent (org.syncany.operations.daemon.messages.LsRemoteEndSyncExternalEvent@3e0f6890) ...
16-2-17 8:19:53.417 | UpOperation     | Test worker    | INFO : No remote changes, ready to upload.
16-2-17 8:19:53.417 | ActionFileHandl | Test worker    | INFO : Starting action for RemoteFile[name=action-up-A-1487233193413] ...
16-2-17 8:19:53.417 | ActionFileHandl | Test worker    | INFO : Uploading action file: RemoteFile[name=action-up-A-1487233193413]
16-2-17 8:19:53.418 | ActionFileHandl | Test worker    | INFO : Scheduling action renewal task for every 2 minutes, for RemoteFile[name=action-up-A-1487233193413] ...
16-2-17 8:19:53.418 | DatabaseConnect | Test worker    | INFO : Found 12 tables.
16-2-17 8:19:53.419 | TransactionAwar | Test worker    | INFO : Done rolling back previous transactions.
16-2-17 8:19:53.419 | AsyncIndexer    | AsyncI/syncany | INFO : Starting Indexing.
16-2-17 8:19:53.419 | InternalEventBu | AsyncI/syncany | INFO : Event bus 'LocalEventBus': Posting event UpIndexStartSyncExternalEvent (org.syncany.operations.daemon.messages.UpIndexStartSyncExternalEvent@62998fc8) ...
16-2-17 8:19:53.419 | Indexer         | AsyncI/syncany | FINE : - Looking for deleted files ...
16-2-17 8:19:53.420 | Indexer         | AsyncI/syncany | FINE :   + Deleted: Adding DELETED version: FileVersion [version=2, path=A1, type=FILE, status=DELETED, size=51200, lastModified=Thu Feb 16 08:19:53 UTC 2017, linkTarget=null, checksum=3bbf054717fb37875d5d76e6a673cd437309f4c5, updated=Thu Feb 16 08:19:53 UTC 2017, posixPermissions=rw-rw-r--, dosAttributes=--a-]
16-2-17 8:19:53.420 | Indexer         | AsyncI/syncany | FINE :                            based on: FileVersion [version=1, path=A1, type=FILE, status=NEW, size=51200, lastModified=Thu Feb 16 08:19:53 UTC 2017, linkTarget=null, checksum=3bbf054717fb37875d5d76e6a673cd437309f4c5, updated=Thu Feb 16 08:19:53 UTC 2017, posixPermissions=rw-rw-r--, dosAttributes=--a-]
16-2-17 8:19:53.420 | Indexer         | AsyncI/syncany | FINE : - +File /tmp/syncanytest/syncany-170216081829395-47281-client-A/A2
16-2-17 8:19:53.421 | Indexer         | AsyncI/syncany | FINE : - +FileContent: /tmp/syncanytest/syncany-170216081829395-47281-client-A/A2
16-2-17 8:19:53.422 | Indexer         | AsyncI/syncany | FINE : - Chunk exists: 3bbf054717fb37875d5d76e6a673cd437309f4c5
16-2-17 8:19:53.422 | Indexer         | AsyncI/syncany | FINE : - Chunk > FileContent: 3bbf054717fb37875d5d76e6a673cd437309f4c5 > /tmp/syncanytest/syncany-170216081829395-47281-client-A/A2
16-2-17 8:19:53.422 | Indexer         | AsyncI/syncany | FINE : - /File: A2 (checksum 3bbf054717fb37875d5d76e6a673cd437309f4c5)
16-2-17 8:19:53.423 | Indexer         | AsyncI/syncany | FINE :    * Found old file history 53422e52f90b6fbe861856c6e0b02ef9e9d592c8 (by checksum: 3bbf054717fb37875d5d76e6a673cd437309f4c5), appending new version.
16-2-17 8:19:53.423 | FileVersionComp | AsyncI/syncany | INFO :      - [CHANGED_PATH]: Local file DIFFERS from file version, expected PATH = A2, but actual PATH = A1, for file A1
16-2-17 8:19:53.423 | Indexer         | AsyncI/syncany | INFO :    * Added file version:    FileVersion [version=2, path=A2, type=FILE, status=CHANGED, size=51200, lastModified=Thu Feb 16 08:19:53 UTC 2017, linkTarget=null, checksum=3bbf054717fb37875d5d76e6a673cd437309f4c5, updated=Thu Feb 16 08:19:53 UTC 2017, posixPermissions=rw-rw-r--, dosAttributes=--a-]
16-2-17 8:19:53.423 | Indexer         | AsyncI/syncany | INFO :      based on file version: FileVersion [version=1, path=A1, type=FILE, status=NEW, size=51200, lastModified=Thu Feb 16 08:19:53 UTC 2017, linkTarget=null, checksum=3bbf054717fb37875d5d76e6a673cd437309f4c5, updated=Thu Feb 16 08:19:53 UTC 2017, posixPermissions=rw-rw-r--, dosAttributes=--a-]
16-2-17 8:19:53.423 | InternalEventBu | AsyncI/syncany | INFO : Event bus 'LocalEventBus': Posting event UpIndexChangesDetectedSyncExternalEvent (org.syncany.operations.daemon.messages.UpIndexChangesDetectedSyncExternalEvent@275698b0) ...
16-2-17 8:19:53.424 | InternalEventBu | AsyncI/syncany | INFO : Event bus 'LocalEventBus': Posting event UpIndexEndSyncExternalEvent (org.syncany.operations.daemon.messages.UpIndexEndSyncExternalEvent@2cab7093) ...
16-2-17 8:19:53.424 | Indexer         | AsyncI/syncany | FINE : Processed new database version: DatabaseVersion [header=UnknownMachine/()/T=1487233193419, chunks=0, multiChunks=0, fileContents=0, fileHistories=1]
16-2-17 8:19:53.424 | UpOperation     | Test worker    | INFO : No DIRTY data found in database (no dirty databases); Nothing to do here.
16-2-17 8:19:53.424 | UpOperation     | Test worker    | INFO : Last vector clock was: (A1)
16-2-17 8:19:53.425 | UpOperation     | Test worker    | INFO : Uploading new multichunks ...
16-2-17 8:19:53.425 | UpOperation     | Test worker    | INFO : Uploading database: DatabaseVersion [header=A/(A2)/T=1487233193425, chunks=0, multiChunks=0, fileContents=0, fileHistories=1]
16-2-17 8:19:53.425 | UpOperation     | Test worker    | INFO : Saving local delta database, version A/(A2)/T=1487233193425 to file /tmp/syncanytest/syncany-170216081829395-47281-client-A/.syncany/cache/database-A-0000000002 ... 
16-2-17 8:19:53.425 | UpOperation     | Test worker    | INFO : - Saving database to /tmp/syncanytest/syncany-170216081829395-47281-client-A/.syncany/cache/database-A-0000000002 ...
16-2-17 8:19:53.426 | UpOperation     | Test worker    | INFO : - Uploading local delta database file ...
16-2-17 8:19:53.426 | UpOperation     | Test worker    | INFO : - Uploading /tmp/syncanytest/syncany-170216081829395-47281-client-A/.syncany/cache/database-A-0000000002 to RemoteFile[name=database-A-0000000002] ...
16-2-17 8:19:53.426 | RemoteTransacti | Test worker    | INFO : - Adding file to TX for UPLOAD: /tmp/syncanytest/syncany-170216081829395-47281-client-A/.syncany/cache/database-A-0000000002 -> Temp. remote file: RemoteFile[name=temp-IyXyA-database-A-0000000002], final location: RemoteFile[name=database-A-0000000002]
16-2-17 8:19:53.426 | UpOperation     | Test worker    | INFO : Adding shutdown hook (to allow resuming the upload) ...
16-2-17 8:19:53.426 | RemoteTransacti | Test worker    | INFO : Starting TX.commit() ...
16-2-17 8:19:53.427 | RemoteTransacti | Test worker    | INFO : Wrote transaction manifest to temporary file: /tmp/syncanytest/syncany-170216081829395-47281-client-A/.syncany/cache/temp-transaction-3867386714083347398.tmp
16-2-17 8:19:53.427 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event UpUploadFileSyncExternalEvent (org.syncany.operations.daemon.messages.UpUploadFileSyncExternalEvent@13302de3) ...
16-2-17 8:19:53.427 | RemoteTransacti | Test worker    | INFO : - Uploading remote transaction file RemoteFile[name=transaction-5849879c] ...
16-2-17 8:19:53.428 | RemoteTransacti | Test worker    | INFO : - Starting to upload data in commit.
16-2-17 8:19:53.428 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event UpUploadFileInTransactionSyncExternalEvent (org.syncany.operations.daemon.messages.UpUploadFileInTransactionSyncExternalEvent@392440f0) ...
16-2-17 8:19:53.428 | RemoteTransacti | Test worker    | INFO : - Uploading /tmp/syncanytest/syncany-170216081829395-47281-client-A/.syncany/cache/database-A-0000000002 to temp. file RemoteFile[name=temp-IyXyA-database-A-0000000002] ...
16-2-17 8:19:53.428 | RemoteTransacti | Test worker    | INFO : - Moving temp. file RemoteFile[name=temp-IyXyA-database-A-0000000002] to final location RemoteFile[name=database-A-0000000002] ...
16-2-17 8:19:53.429 | RemoteTransacti | Test worker    | INFO : - Deleting remote transaction file RemoteFile[name=transaction-5849879c] ...
16-2-17 8:19:53.429 | RemoteTransacti | Test worker    | INFO : END of TX.commmit(): Succesfully committed transaction.
16-2-17 8:19:53.429 | RemoteTransacti | Test worker    | INFO : END of TX.delTemp(): Sucessfully deleted final files.
16-2-17 8:19:53.429 | UpOperation     | Test worker    | INFO : Persisting local SQL database (new database version A/(A2)/T=1487233193425) ...
16-2-17 8:19:53.430 | UpOperation     | Test worker    | INFO : Removing DIRTY database versions from database ...
16-2-17 8:19:53.432 | InternalEventBu | AsyncI/syncany | INFO : Event bus 'LocalEventBus': Posting event UpIndexMidSyncExternalEvent (org.syncany.operations.daemon.messages.UpIndexMidSyncExternalEvent@5938d9e1) ...
16-2-17 8:19:53.434 | UpOperation     | Test worker    | INFO : Adding database version to result changes:DatabaseVersion [header=A/(A2)/T=1487233193425, chunks=0, multiChunks=0, fileContents=0, fileHistories=1]
16-2-17 8:19:53.434 | UpOperation     | Test worker    | INFO : Committing local database.
16-2-17 8:19:53.434 | UpOperation     | Test worker    | FINE : Waiting for new database version.
16-2-17 8:19:53.436 | AsyncIndexer    | AsyncI/syncany | INFO : Stopping indexing. Signal end of stream with empty databaseversion
16-2-17 8:19:53.436 | UpOperation     | Test worker    | FINE : Took new database version: DatabaseVersion [header=UnknownMachine/()/T=1487233193436, chunks=0, multiChunks=0, fileContents=0, fileHistories=0]
16-2-17 8:19:53.436 | UpOperation     | Test worker    | INFO : Sync up done.
16-2-17 8:19:53.438 | ActionFileHandl | Test worker    | INFO : Finishing action for RemoteFile[name=action-up-A-1487233193413] ...
16-2-17 8:19:53.438 | ActionFileHandl | Test worker    | INFO : Deleting action file: RemoteFile[name=action-up-A-1487233193413]
16-2-17 8:19:53.438 | AbstractTransfe | Test worker    | INFO : Cleaning own old action files ...
16-2-17 8:19:53.439 | Cache           | Test worker    | INFO : Cache size okay (52 KB), no need to clean (keep size is 500 MB)
16-2-17 8:19:53.439 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event UpEndSyncExternalEvent (org.syncany.operations.daemon.messages.UpEndSyncExternalEvent@16345156) ...
16-2-17 8:19:53.439 | org.syncany.plu | Test worker    | FINE : Validating required fields
16-2-17 8:19:53.439 | TransferManager | Test worker    | INFO : Building LocalTransferManager from config 'syncany-170216081829395-47281-client-A' ...
16-2-17 8:19:53.439 | TransferManager | Test worker    | INFO : - With feature ReadAfterWriteConsistent
16-2-17 8:19:53.439 | TransferManager | Test worker    | INFO : - With feature Retriable
16-2-17 8:19:53.439 | TransferManager | Test worker    | FINE : - Checking if selected features supported ...
16-2-17 8:19:53.439 | TransferManager | Test worker    | FINE : - Checking for duplicate features ...
16-2-17 8:19:53.439 | TransferManager | Test worker    | FINE : - Checking required feature TransactionAware in LocalTransferManager ...
16-2-17 8:19:53.439 | TransferManager | Test worker    | FINE : - Checking required feature Retriable in LocalTransferManager ...
16-2-17 8:19:53.439 | TransferManager | Test worker    | INFO : - SKIPPING unsupported optional feature ReadAfterWriteConsistent
16-2-17 8:19:53.439 | TransferManager | Test worker    | FINE : - Wrapping TransferManager LocalTransferManager in RetriableFeatureTransferManager
16-2-17 8:19:53.439 | org.syncany.plu | Test worker    | FINE : Validating required fields
16-2-17 8:19:53.440 | TransferManager | Test worker    | INFO : Building LocalTransferManager from config 'syncany-170216081829395-47281-client-A' ...
16-2-17 8:19:53.440 | TransferManager | Test worker    | INFO : - With feature ReadAfterWriteConsistent
16-2-17 8:19:53.440 | TransferManager | Test worker    | INFO : - With feature Retriable
16-2-17 8:19:53.440 | TransferManager | Test worker    | INFO : - With feature PathAware
16-2-17 8:19:53.440 | TransferManager | Test worker    | INFO : - With feature TransactionAware
16-2-17 8:19:53.440 | TransferManager | Test worker    | FINE : - Checking if selected features supported ...
16-2-17 8:19:53.440 | TransferManager | Test worker    | FINE : - Checking for duplicate features ...
16-2-17 8:19:53.440 | TransferManager | Test worker    | FINE : - Checking required feature TransactionAware in LocalTransferManager ...
16-2-17 8:19:53.440 | TransferManager | Test worker    | FINE : - Checking required feature Retriable in LocalTransferManager ...
16-2-17 8:19:53.440 | TransferManager | Test worker    | INFO : - SKIPPING unsupported optional feature ReadAfterWriteConsistent
16-2-17 8:19:53.440 | TransferManager | Test worker    | FINE : - Wrapping TransferManager LocalTransferManager in RetriableFeatureTransferManager
16-2-17 8:19:53.440 | TransferManager | Test worker    | INFO : - SKIPPING unsupported optional feature PathAware
16-2-17 8:19:53.440 | TransferManager | Test worker    | FINE : - Wrapping TransferManager RetriableFeatureTransferManager in TransactionAwareFeatureTransferManager
16-2-17 8:19:53.441 | DatabaseConnect | Test worker    | INFO : Found 12 tables.
16-2-17 8:19:53.441 | UpOperation     | Test worker    | INFO : 
16-2-17 8:19:53.441 | UpOperation     | Test worker    | INFO : Running 'Sync up' at client A ...
16-2-17 8:19:53.441 | UpOperation     | Test worker    | INFO : --------------------------------------------
16-2-17 8:19:53.441 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event UpStartSyncExternalEvent (org.syncany.operations.daemon.messages.UpStartSyncExternalEvent@52180e56) ...
16-2-17 8:19:53.442 | DatabaseConnect | Test worker    | INFO : Found 12 tables.
16-2-17 8:19:53.442 | StatusOperation | Test worker    | INFO : 
16-2-17 8:19:53.442 | StatusOperation | Test worker    | INFO : Running 'Status' at client A ...
16-2-17 8:19:53.442 | StatusOperation | Test worker    | INFO : --------------------------------------------
16-2-17 8:19:53.442 | StatusOperation | Test worker    | INFO : Force checksum ENABLED.
16-2-17 8:19:53.442 | StatusOperation | Test worker    | INFO : Querying current file tree from database ...
16-2-17 8:19:53.442 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event StatusStartSyncExternalEvent (org.syncany.operations.daemon.messages.StatusStartSyncExternalEvent@1081b5e1) ...
16-2-17 8:19:53.442 | StatusOperation | Test worker    | INFO : Analyzing local folder /tmp/syncanytest/syncany-170216081829395-47281-client-A ...
16-2-17 8:19:53.442 | StatusOperation | Test worker    | FINE : - Ignoring file (syncany app-related): .syncany
16-2-17 8:19:53.443 | FileVersionComp | Test worker    | INFO :      - [CHANGED_CHECKSUM]: Local file DIFFERS from file version, expected CHECKSUM = 3bbf054717fb37875d5d76e6a673cd437309f4c5, but actual CHECKSUM = fb073b8aea2e985ed83cd0a4412b798fe911428e, for file A2
16-2-17 8:19:53.443 | StatusOperation | Test worker    | FINE : - New file: A3
16-2-17 8:19:53.443 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event StatusEndSyncExternalEvent (org.syncany.operations.daemon.messages.StatusEndSyncExternalEvent@193274dc) ...
16-2-17 8:19:53.443 | AbstractTransfe | Test worker    | INFO : Looking for other running remote operations ...
16-2-17 8:19:53.444 | DatabaseConnect | Test worker    | INFO : Found 12 tables.
16-2-17 8:19:53.444 | LsRemoteOperati | Test worker    | INFO : 
16-2-17 8:19:53.444 | LsRemoteOperati | Test worker    | INFO : Running 'Remote Status' at client A ...
16-2-17 8:19:53.444 | LsRemoteOperati | Test worker    | INFO : --------------------------------------------
16-2-17 8:19:53.444 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event LsRemoteStartSyncExternalEvent (org.syncany.operations.daemon.messages.LsRemoteStartSyncExternalEvent@3f85fbca) ...
16-2-17 8:19:53.444 | LsRemoteOperati | Test worker    | INFO : Retrieving remote database list.
16-2-17 8:19:53.444 | LsRemoteOperati | Test worker    | INFO : - Remote database database-A-0000000002 is already known (in local database). Ignoring.
16-2-17 8:19:53.444 | LsRemoteOperati | Test worker    | INFO : - Remote database database-A-0000000001 is already known (in local database). Ignoring.
16-2-17 8:19:53.444 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event LsRemoteEndSyncExternalEvent (org.syncany.operations.daemon.messages.LsRemoteEndSyncExternalEvent@1fec4e7a) ...
16-2-17 8:19:53.445 | UpOperation     | Test worker    | INFO : No remote changes, ready to upload.
16-2-17 8:19:53.445 | ActionFileHandl | Test worker    | INFO : Starting action for RemoteFile[name=action-up-A-1487233193440] ...
16-2-17 8:19:53.445 | ActionFileHandl | Test worker    | INFO : Uploading action file: RemoteFile[name=action-up-A-1487233193440]
16-2-17 8:19:53.445 | ActionFileHandl | Test worker    | INFO : Scheduling action renewal task for every 2 minutes, for RemoteFile[name=action-up-A-1487233193440] ...
16-2-17 8:19:53.446 | DatabaseConnect | Test worker    | INFO : Found 12 tables.
16-2-17 8:19:53.446 | TransactionAwar | Test worker    | INFO : Done rolling back previous transactions.
16-2-17 8:19:53.446 | AsyncIndexer    | AsyncI/syncany | INFO : Starting Indexing.
16-2-17 8:19:53.446 | InternalEventBu | AsyncI/syncany | INFO : Event bus 'LocalEventBus': Posting event UpIndexStartSyncExternalEvent (org.syncany.operations.daemon.messages.UpIndexStartSyncExternalEvent@c844ca5) ...
16-2-17 8:19:53.446 | Indexer         | AsyncI/syncany | FINE : - Looking for deleted files ...
16-2-17 8:19:53.446 | Indexer         | AsyncI/syncany | FINE : - +File /tmp/syncanytest/syncany-170216081829395-47281-client-A/A3
16-2-17 8:19:53.447 | Indexer         | AsyncI/syncany | FINE : - +FileContent: /tmp/syncanytest/syncany-170216081829395-47281-client-A/A3
16-2-17 8:19:53.448 | Indexer         | AsyncI/syncany | FINE : - Chunk new: db017b97ff6e9e8df6c8ba9158064dd65824bc95
16-2-17 8:19:53.448 | Indexer         | AsyncI/syncany | FINE : - +MultiChunk 15aa4aa6b32b4d905a0ca51bfb145bb1df5ebe63
16-2-17 8:19:53.449 | Indexer         | AsyncI/syncany | FINE : - Chunk > MultiChunk: db017b97ff6e9e8df6c8ba9158064dd65824bc95 > 15aa4aa6b32b4d905a0ca51bfb145bb1df5ebe63
16-2-17 8:19:53.449 | Indexer         | AsyncI/syncany | FINE : - Chunk > FileContent: db017b97ff6e9e8df6c8ba9158064dd65824bc95 > /tmp/syncanytest/syncany-170216081829395-47281-client-A/A3
16-2-17 8:19:53.449 | Indexer         | AsyncI/syncany | FINE : - /File: A3 (checksum db017b97ff6e9e8df6c8ba9158064dd65824bc95)
16-2-17 8:19:53.450 | Indexer         | AsyncI/syncany | FINE :    * No old file history found, starting new history (path: A3, checksum: db017b97ff6e9e8df6c8ba9158064dd65824bc95)
16-2-17 8:19:53.450 | FileVersionComp | AsyncI/syncany | INFO :      - [DELETED]: Local file DIFFERS from file version, actual file is NULL, for file A3
16-2-17 8:19:53.450 | Indexer         | AsyncI/syncany | INFO :    * Added file version:    FileVersion [version=1, path=A3, type=FILE, status=NEW, size=51200, lastModified=Thu Feb 16 08:19:53 UTC 2017, linkTarget=null, checksum=db017b97ff6e9e8df6c8ba9158064dd65824bc95, updated=Thu Feb 16 08:19:53 UTC 2017, posixPermissions=rw-rw-r--, dosAttributes=--a-]
16-2-17 8:19:53.450 | Indexer         | AsyncI/syncany | INFO :      based on file version: null
16-2-17 8:19:53.450 | InternalEventBu | AsyncI/syncany | INFO : Event bus 'LocalEventBus': Posting event UpIndexChangesDetectedSyncExternalEvent (org.syncany.operations.daemon.messages.UpIndexChangesDetectedSyncExternalEvent@ed6b6e4) ...
16-2-17 8:19:53.450 | Indexer         | AsyncI/syncany | FINE : - +File /tmp/syncanytest/syncany-170216081829395-47281-client-A/A2
16-2-17 8:19:53.450 | Indexer         | AsyncI/syncany | FINE : - +FileContent: /tmp/syncanytest/syncany-170216081829395-47281-client-A/A2
16-2-17 8:19:53.451 | Indexer         | AsyncI/syncany | FINE : - Chunk new: fb073b8aea2e985ed83cd0a4412b798fe911428e
16-2-17 8:19:53.452 | Indexer         | AsyncI/syncany | FINE : - Chunk > MultiChunk: fb073b8aea2e985ed83cd0a4412b798fe911428e > 15aa4aa6b32b4d905a0ca51bfb145bb1df5ebe63
16-2-17 8:19:53.452 | Indexer         | AsyncI/syncany | FINE : - Chunk > FileContent: fb073b8aea2e985ed83cd0a4412b798fe911428e > /tmp/syncanytest/syncany-170216081829395-47281-client-A/A2
16-2-17 8:19:53.452 | Indexer         | AsyncI/syncany | FINE : - /File: A2 (checksum fb073b8aea2e985ed83cd0a4412b798fe911428e)
16-2-17 8:19:53.453 | Indexer         | AsyncI/syncany | FINE :    * Found old file history 53422e52f90b6fbe861856c6e0b02ef9e9d592c8 (by path: A2), appending new version.
16-2-17 8:19:53.453 | FileVersionComp | AsyncI/syncany | INFO :      - [CHANGED_CHECKSUM]: Local file DIFFERS from file version, expected CHECKSUM = fb073b8aea2e985ed83cd0a4412b798fe911428e, but actual CHECKSUM = 3bbf054717fb37875d5d76e6a673cd437309f4c5, for file A2
16-2-17 8:19:53.453 | Indexer         | AsyncI/syncany | INFO :    * Added file version:    FileVersion [version=3, path=A2, type=FILE, status=CHANGED, size=51200, lastModified=Thu Feb 16 08:19:53 UTC 2017, linkTarget=null, checksum=fb073b8aea2e985ed83cd0a4412b798fe911428e, updated=Thu Feb 16 08:19:53 UTC 2017, posixPermissions=rw-rw-r--, dosAttributes=--a-]
16-2-17 8:19:53.453 | Indexer         | AsyncI/syncany | INFO :      based on file version: FileVersion [version=2, path=A2, type=FILE, status=CHANGED, size=51200, lastModified=Thu Feb 16 08:19:53 UTC 2017, linkTarget=null, checksum=3bbf054717fb37875d5d76e6a673cd437309f4c5, updated=Thu Feb 16 08:19:53 UTC 2017, posixPermissions=rw-rw-r--, dosAttributes=--a-]
16-2-17 8:19:53.454 | Indexer         | AsyncI/syncany | FINE : - /MultiChunk 15aa4aa6b32b4d905a0ca51bfb145bb1df5ebe63
16-2-17 8:19:53.454 | InternalEventBu | AsyncI/syncany | INFO : Event bus 'LocalEventBus': Posting event UpIndexEndSyncExternalEvent (org.syncany.operations.daemon.messages.UpIndexEndSyncExternalEvent@77323170) ...
16-2-17 8:19:53.454 | Indexer         | AsyncI/syncany | FINE : Processed new database version: DatabaseVersion [header=UnknownMachine/()/T=1487233193446, chunks=2, multiChunks=1, fileContents=2, fileHistories=2]
16-2-17 8:19:53.454 | UpOperation     | Test worker    | INFO : No DIRTY data found in database (no dirty databases); Nothing to do here.
16-2-17 8:19:53.454 | UpOperation     | Test worker    | INFO : Last vector clock was: (A2)
16-2-17 8:19:53.455 | UpOperation     | Test worker    | INFO : Uploading new multichunks ...
16-2-17 8:19:53.455 | UpOperation     | Test worker    | INFO : - Uploading multichunk 15aa4aa6b32b4d905a0ca51bfb145bb1df5ebe63 from /tmp/syncanytest/syncany-170216081829395-47281-client-A/.syncany/cache/multichunk-15aa4aa6b32b4d905a0ca51bfb145bb1df5ebe63 to RemoteFile[name=multichunk-15aa4aa6b32b4d905a0ca51bfb145bb1df5ebe63] ...
16-2-17 8:19:53.455 | RemoteTransacti | Test worker    | INFO : - Adding file to TX for UPLOAD: /tmp/syncanytest/syncany-170216081829395-47281-client-A/.syncany/cache/multichunk-15aa4aa6b32b4d905a0ca51bfb145bb1df5ebe63 -> Temp. remote file: RemoteFile[name=temp-vijLH-multichunk-15aa4aa6b32b4d905a0ca51bfb145bb1df5ebe63], final location: RemoteFile[name=multichunk-15aa4aa6b32b4d905a0ca51bfb145bb1df5ebe63]
16-2-17 8:19:53.455 | UpOperation     | Test worker    | INFO : Uploading database: DatabaseVersion [header=A/(A3)/T=1487233193455, chunks=2, multiChunks=1, fileContents=2, fileHistories=2]
16-2-17 8:19:53.455 | UpOperation     | Test worker    | INFO : Saving local delta database, version A/(A3)/T=1487233193455 to file /tmp/syncanytest/syncany-170216081829395-47281-client-A/.syncany/cache/database-A-0000000003 ... 
16-2-17 8:19:53.455 | UpOperation     | Test worker    | INFO : - Saving database to /tmp/syncanytest/syncany-170216081829395-47281-client-A/.syncany/cache/database-A-0000000003 ...
16-2-17 8:19:53.456 | UpOperation     | Test worker    | INFO : - Uploading local delta database file ...
16-2-17 8:19:53.456 | UpOperation     | Test worker    | INFO : - Uploading /tmp/syncanytest/syncany-170216081829395-47281-client-A/.syncany/cache/database-A-0000000003 to RemoteFile[name=database-A-0000000003] ...
16-2-17 8:19:53.456 | RemoteTransacti | Test worker    | INFO : - Adding file to TX for UPLOAD: /tmp/syncanytest/syncany-170216081829395-47281-client-A/.syncany/cache/database-A-0000000003 -> Temp. remote file: RemoteFile[name=temp-nmtVe-database-A-0000000003], final location: RemoteFile[name=database-A-0000000003]
16-2-17 8:19:53.456 | UpOperation     | Test worker    | INFO : Adding shutdown hook (to allow resuming the upload) ...
16-2-17 8:19:53.456 | RemoteTransacti | Test worker    | INFO : Starting TX.commit() ...
16-2-17 8:19:53.459 | RemoteTransacti | Test worker    | INFO : Wrote transaction manifest to temporary file: /tmp/syncanytest/syncany-170216081829395-47281-client-A/.syncany/cache/temp-transaction-3826798980359888217.tmp
16-2-17 8:19:53.459 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event UpUploadFileSyncExternalEvent (org.syncany.operations.daemon.messages.UpUploadFileSyncExternalEvent@674890ff) ...
16-2-17 8:19:53.459 | RemoteTransacti | Test worker    | INFO : - Uploading remote transaction file RemoteFile[name=transaction-40f020d9] ...
16-2-17 8:19:53.459 | RemoteTransacti | Test worker    | INFO : - Starting to upload data in commit.
16-2-17 8:19:53.460 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event UpUploadFileInTransactionSyncExternalEvent (org.syncany.operations.daemon.messages.UpUploadFileInTransactionSyncExternalEvent@7a4b5fda) ...
16-2-17 8:19:53.460 | RemoteTransacti | Test worker    | INFO : - Uploading /tmp/syncanytest/syncany-170216081829395-47281-client-A/.syncany/cache/multichunk-15aa4aa6b32b4d905a0ca51bfb145bb1df5ebe63 to temp. file RemoteFile[name=temp-vijLH-multichunk-15aa4aa6b32b4d905a0ca51bfb145bb1df5ebe63] ...
16-2-17 8:19:53.460 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event UpUploadFileInTransactionSyncExternalEvent (org.syncany.operations.daemon.messages.UpUploadFileInTransactionSyncExternalEvent@5b67e141) ...
16-2-17 8:19:53.460 | RemoteTransacti | Test worker    | INFO : - Uploading /tmp/syncanytest/syncany-170216081829395-47281-client-A/.syncany/cache/database-A-0000000003 to temp. file RemoteFile[name=temp-nmtVe-database-A-0000000003] ...
16-2-17 8:19:53.461 | RemoteTransacti | Test worker    | INFO : - Moving temp. file RemoteFile[name=temp-vijLH-multichunk-15aa4aa6b32b4d905a0ca51bfb145bb1df5ebe63] to final location RemoteFile[name=multichunk-15aa4aa6b32b4d905a0ca51bfb145bb1df5ebe63] ...
16-2-17 8:19:53.461 | RemoteTransacti | Test worker    | INFO : - Moving temp. file RemoteFile[name=temp-nmtVe-database-A-0000000003] to final location RemoteFile[name=database-A-0000000003] ...
16-2-17 8:19:53.461 | RemoteTransacti | Test worker    | INFO : - Deleting remote transaction file RemoteFile[name=transaction-40f020d9] ...
16-2-17 8:19:53.461 | RemoteTransacti | Test worker    | INFO : END of TX.commmit(): Succesfully committed transaction.
16-2-17 8:19:53.461 | RemoteTransacti | Test worker    | INFO : END of TX.delTemp(): Sucessfully deleted final files.
16-2-17 8:19:53.461 | UpOperation     | Test worker    | INFO : Persisting local SQL database (new database version A/(A3)/T=1487233193455) ...
16-2-17 8:19:53.464 | InternalEventBu | AsyncI/syncany | INFO : Event bus 'LocalEventBus': Posting event UpIndexMidSyncExternalEvent (org.syncany.operations.daemon.messages.UpIndexMidSyncExternalEvent@54d117d7) ...
16-2-17 8:19:53.464 | UpOperation     | Test worker    | INFO : Removing DIRTY database versions from database ...
16-2-17 8:19:53.468 | AsyncIndexer    | AsyncI/syncany | INFO : Stopping indexing. Signal end of stream with empty databaseversion
16-2-17 8:19:53.470 | UpOperation     | Test worker    | INFO : Adding database version to result changes:DatabaseVersion [header=A/(A3)/T=1487233193455, chunks=2, multiChunks=1, fileContents=2, fileHistories=2]
16-2-17 8:19:53.470 | UpOperation     | Test worker    | INFO : Committing local database.
16-2-17 8:19:53.470 | UpOperation     | Test worker    | FINE : Waiting for new database version.
16-2-17 8:19:53.470 | UpOperation     | Test worker    | FINE : Took new database version: DatabaseVersion [header=UnknownMachine/()/T=1487233193468, chunks=0, multiChunks=0, fileContents=0, fileHistories=0]
16-2-17 8:19:53.471 | UpOperation     | Test worker    | INFO : Sync up done.
16-2-17 8:19:53.473 | ActionFileHandl | Test worker    | INFO : Finishing action for RemoteFile[name=action-up-A-1487233193440] ...
16-2-17 8:19:53.476 | ActionFileHandl | Test worker    | INFO : Deleting action file: RemoteFile[name=action-up-A-1487233193440]
16-2-17 8:19:53.476 | AbstractTransfe | Test worker    | INFO : Cleaning own old action files ...
16-2-17 8:19:53.476 | Cache           | Test worker    | INFO : Cache size okay (154 KB), no need to clean (keep size is 500 MB)
16-2-17 8:19:53.476 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event UpEndSyncExternalEvent (org.syncany.operations.daemon.messages.UpEndSyncExternalEvent@7b242b41) ...
16-2-17 8:19:53.477 | org.syncany.plu | Test worker    | FINE : Validating required fields
16-2-17 8:19:53.477 | TransferManager | Test worker    | INFO : Building LocalTransferManager from config 'syncany-170216081829395-47281-client-A' ...
16-2-17 8:19:53.477 | TransferManager | Test worker    | INFO : - With feature ReadAfterWriteConsistent
16-2-17 8:19:53.477 | TransferManager | Test worker    | INFO : - With feature Retriable
16-2-17 8:19:53.477 | TransferManager | Test worker    | FINE : - Checking if selected features supported ...
16-2-17 8:19:53.477 | TransferManager | Test worker    | FINE : - Checking for duplicate features ...
16-2-17 8:19:53.477 | TransferManager | Test worker    | FINE : - Checking required feature TransactionAware in LocalTransferManager ...
16-2-17 8:19:53.477 | TransferManager | Test worker    | FINE : - Checking required feature Retriable in LocalTransferManager ...
16-2-17 8:19:53.477 | TransferManager | Test worker    | INFO : - SKIPPING unsupported optional feature ReadAfterWriteConsistent
16-2-17 8:19:53.478 | TransferManager | Test worker    | FINE : - Wrapping TransferManager LocalTransferManager in RetriableFeatureTransferManager
16-2-17 8:19:53.478 | org.syncany.plu | Test worker    | FINE : Validating required fields
16-2-17 8:19:53.478 | TransferManager | Test worker    | INFO : Building LocalTransferManager from config 'syncany-170216081829395-47281-client-A' ...
16-2-17 8:19:53.478 | TransferManager | Test worker    | INFO : - With feature ReadAfterWriteConsistent
16-2-17 8:19:53.478 | TransferManager | Test worker    | INFO : - With feature Retriable
16-2-17 8:19:53.478 | TransferManager | Test worker    | INFO : - With feature PathAware
16-2-17 8:19:53.478 | TransferManager | Test worker    | INFO : - With feature TransactionAware
16-2-17 8:19:53.478 | TransferManager | Test worker    | FINE : - Checking if selected features supported ...
16-2-17 8:19:53.478 | TransferManager | Test worker    | FINE : - Checking for duplicate features ...
16-2-17 8:19:53.478 | TransferManager | Test worker    | FINE : - Checking required feature TransactionAware in LocalTransferManager ...
16-2-17 8:19:53.478 | TransferManager | Test worker    | FINE : - Checking required feature Retriable in LocalTransferManager ...
16-2-17 8:19:53.478 | TransferManager | Test worker    | INFO : - SKIPPING unsupported optional feature ReadAfterWriteConsistent
16-2-17 8:19:53.479 | TransferManager | Test worker    | FINE : - Wrapping TransferManager LocalTransferManager in RetriableFeatureTransferManager
16-2-17 8:19:53.479 | TransferManager | Test worker    | INFO : - SKIPPING unsupported optional feature PathAware
16-2-17 8:19:53.479 | TransferManager | Test worker    | FINE : - Wrapping TransferManager RetriableFeatureTransferManager in TransactionAwareFeatureTransferManager
16-2-17 8:19:53.481 | DatabaseConnect | Test worker    | INFO : Found 12 tables.
16-2-17 8:19:53.481 | UpOperation     | Test worker    | INFO : 
16-2-17 8:19:53.481 | UpOperation     | Test worker    | INFO : Running 'Sync up' at client A ...
16-2-17 8:19:53.481 | UpOperation     | Test worker    | INFO : --------------------------------------------
16-2-17 8:19:53.481 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event UpStartSyncExternalEvent (org.syncany.operations.daemon.messages.UpStartSyncExternalEvent@480c8c90) ...
16-2-17 8:19:53.482 | DatabaseConnect | Test worker    | INFO : Found 12 tables.
16-2-17 8:19:53.482 | StatusOperation | Test worker    | INFO : 
16-2-17 8:19:53.482 | StatusOperation | Test worker    | INFO : Running 'Status' at client A ...
16-2-17 8:19:53.482 | StatusOperation | Test worker    | INFO : --------------------------------------------
16-2-17 8:19:53.482 | StatusOperation | Test worker    | INFO : Force checksum ENABLED.
16-2-17 8:19:53.482 | StatusOperation | Test worker    | INFO : Querying current file tree from database ...
16-2-17 8:19:53.482 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event StatusStartSyncExternalEvent (org.syncany.operations.daemon.messages.StatusStartSyncExternalEvent@10e86440) ...
16-2-17 8:19:53.483 | StatusOperation | Test worker    | INFO : Analyzing local folder /tmp/syncanytest/syncany-170216081829395-47281-client-A ...
16-2-17 8:19:53.483 | StatusOperation | Test worker    | FINE : - Ignoring file (syncany app-related): .syncany
16-2-17 8:19:53.484 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event StatusEndSyncExternalEvent (org.syncany.operations.daemon.messages.StatusEndSyncExternalEvent@23fa230e) ...
16-2-17 8:19:53.484 | AbstractTransfe | Test worker    | INFO : Looking for other running remote operations ...
16-2-17 8:19:53.485 | DatabaseConnect | Test worker    | INFO : Found 12 tables.
16-2-17 8:19:53.485 | LsRemoteOperati | Test worker    | INFO : 
16-2-17 8:19:53.485 | LsRemoteOperati | Test worker    | INFO : Running 'Remote Status' at client A ...
16-2-17 8:19:53.485 | LsRemoteOperati | Test worker    | INFO : --------------------------------------------
16-2-17 8:19:53.485 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event LsRemoteStartSyncExternalEvent (org.syncany.operations.daemon.messages.LsRemoteStartSyncExternalEvent@7806f418) ...
16-2-17 8:19:53.485 | LsRemoteOperati | Test worker    | INFO : Retrieving remote database list.
16-2-17 8:19:53.485 | LsRemoteOperati | Test worker    | INFO : - Remote database database-A-0000000002 is already known (in local database). Ignoring.
16-2-17 8:19:53.486 | LsRemoteOperati | Test worker    | INFO : - Remote database database-A-0000000003 is already known (in local database). Ignoring.
16-2-17 8:19:53.486 | LsRemoteOperati | Test worker    | INFO : - Remote database database-A-0000000001 is already known (in local database). Ignoring.
16-2-17 8:19:53.486 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event LsRemoteEndSyncExternalEvent (org.syncany.operations.daemon.messages.LsRemoteEndSyncExternalEvent@699fe0) ...
16-2-17 8:19:53.486 | UpOperation     | Test worker    | INFO : No remote changes, ready to upload.
16-2-17 8:19:53.486 | ActionFileHandl | Test worker    | INFO : Starting action for RemoteFile[name=action-up-A-1487233193479] ...
16-2-17 8:19:53.486 | ActionFileHandl | Test worker    | INFO : Uploading action file: RemoteFile[name=action-up-A-1487233193479]
16-2-17 8:19:53.486 | ActionFileHandl | Test worker    | INFO : Scheduling action renewal task for every 2 minutes, for RemoteFile[name=action-up-A-1487233193479] ...
16-2-17 8:19:53.487 | DatabaseConnect | Test worker    | INFO : Found 12 tables.
16-2-17 8:19:53.488 | TransactionAwar | Test worker    | INFO : Done rolling back previous transactions.
16-2-17 8:19:53.488 | AsyncIndexer    | AsyncI/syncany | INFO : Starting Indexing.
16-2-17 8:19:53.488 | Indexer         | AsyncI/syncany | FINE : - Looking for deleted files ...
16-2-17 8:19:53.489 | Indexer         | AsyncI/syncany | FINE :   + Deleted: Adding DELETED version: FileVersion [version=2, path=A3, type=FILE, status=DELETED, size=51200, lastModified=Thu Feb 16 08:19:53 UTC 2017, linkTarget=null, checksum=db017b97ff6e9e8df6c8ba9158064dd65824bc95, updated=Thu Feb 16 08:19:53 UTC 2017, posixPermissions=rw-rw-r--, dosAttributes=--a-]
16-2-17 8:19:53.489 | Indexer         | AsyncI/syncany | FINE :                            based on: FileVersion [version=1, path=A3, type=FILE, status=NEW, size=51200, lastModified=Thu Feb 16 08:19:53 UTC 2017, linkTarget=null, checksum=db017b97ff6e9e8df6c8ba9158064dd65824bc95, updated=Thu Feb 16 08:19:53 UTC 2017, posixPermissions=rw-rw-r--, dosAttributes=--a-]
16-2-17 8:19:53.489 | Indexer         | AsyncI/syncany | FINE : Added database version with only deletions: DatabaseVersion [header=UnknownMachine/()/T=1487233193488, chunks=0, multiChunks=0, fileContents=0, fileHistories=1]
16-2-17 8:19:53.490 | UpOperation     | Test worker    | INFO : No DIRTY data found in database (no dirty databases); Nothing to do here.
16-2-17 8:19:53.490 | UpOperation     | Test worker    | INFO : Last vector clock was: (A3)
16-2-17 8:19:53.490 | UpOperation     | Test worker    | INFO : Uploading new multichunks ...
16-2-17 8:19:53.490 | UpOperation     | Test worker    | INFO : Uploading database: DatabaseVersion [header=A/(A4)/T=1487233193490, chunks=0, multiChunks=0, fileContents=0, fileHistories=1]
16-2-17 8:19:53.490 | UpOperation     | Test worker    | INFO : Saving local delta database, version A/(A4)/T=1487233193490 to file /tmp/syncanytest/syncany-170216081829395-47281-client-A/.syncany/cache/database-A-0000000004 ... 
16-2-17 8:19:53.490 | UpOperation     | Test worker    | INFO : - Saving database to /tmp/syncanytest/syncany-170216081829395-47281-client-A/.syncany/cache/database-A-0000000004 ...
16-2-17 8:19:53.491 | UpOperation     | Test worker    | INFO : - Uploading local delta database file ...
16-2-17 8:19:53.491 | UpOperation     | Test worker    | INFO : - Uploading /tmp/syncanytest/syncany-170216081829395-47281-client-A/.syncany/cache/database-A-0000000004 to RemoteFile[name=database-A-0000000004] ...
16-2-17 8:19:53.491 | RemoteTransacti | Test worker    | INFO : - Adding file to TX for UPLOAD: /tmp/syncanytest/syncany-170216081829395-47281-client-A/.syncany/cache/database-A-0000000004 -> Temp. remote file: RemoteFile[name=temp-PJrpV-database-A-0000000004], final location: RemoteFile[name=database-A-0000000004]
16-2-17 8:19:53.491 | UpOperation     | Test worker    | INFO : Adding shutdown hook (to allow resuming the upload) ...
16-2-17 8:19:53.491 | RemoteTransacti | Test worker    | INFO : Starting TX.commit() ...
16-2-17 8:19:53.493 | RemoteTransacti | Test worker    | INFO : Wrote transaction manifest to temporary file: /tmp/syncanytest/syncany-170216081829395-47281-client-A/.syncany/cache/temp-transaction-5515944799603769784.tmp
16-2-17 8:19:53.493 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event UpUploadFileSyncExternalEvent (org.syncany.operations.daemon.messages.UpUploadFileSyncExternalEvent@403aa101) ...
16-2-17 8:19:53.493 | RemoteTransacti | Test worker    | INFO : - Uploading remote transaction file RemoteFile[name=transaction-26e45a8f] ...
16-2-17 8:19:53.493 | RemoteTransacti | Test worker    | INFO : - Starting to upload data in commit.
16-2-17 8:19:53.493 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event UpUploadFileInTransactionSyncExternalEvent (org.syncany.operations.daemon.messages.UpUploadFileInTransactionSyncExternalEvent@3e1f8c98) ...
16-2-17 8:19:53.493 | RemoteTransacti | Test worker    | INFO : - Uploading /tmp/syncanytest/syncany-170216081829395-47281-client-A/.syncany/cache/database-A-0000000004 to temp. file RemoteFile[name=temp-PJrpV-database-A-0000000004] ...
16-2-17 8:19:53.494 | RemoteTransacti | Test worker    | INFO : - Moving temp. file RemoteFile[name=temp-PJrpV-database-A-0000000004] to final location RemoteFile[name=database-A-0000000004] ...
16-2-17 8:19:53.494 | RemoteTransacti | Test worker    | INFO : - Deleting remote transaction file RemoteFile[name=transaction-26e45a8f] ...
16-2-17 8:19:53.494 | RemoteTransacti | Test worker    | INFO : END of TX.commmit(): Succesfully committed transaction.
16-2-17 8:19:53.494 | RemoteTransacti | Test worker    | INFO : END of TX.delTemp(): Sucessfully deleted final files.
16-2-17 8:19:53.494 | UpOperation     | Test worker    | INFO : Persisting local SQL database (new database version A/(A4)/T=1487233193490) ...
16-2-17 8:19:53.495 | UpOperation     | Test worker    | INFO : Removing DIRTY database versions from database ...
16-2-17 8:19:53.502 | UpOperation     | Test worker    | INFO : Adding database version to result changes:DatabaseVersion [header=A/(A4)/T=1487233193490, chunks=0, multiChunks=0, fileContents=0, fileHistories=1]
16-2-17 8:19:53.502 | UpOperation     | Test worker    | INFO : Committing local database.
16-2-17 8:19:53.502 | UpOperation     | Test worker    | FINE : Waiting for new database version.
16-2-17 8:19:53.504 | AsyncIndexer    | AsyncI/syncany | INFO : Stopping indexing. Signal end of stream with empty databaseversion
16-2-17 8:19:53.504 | UpOperation     | Test worker    | FINE : Took new database version: DatabaseVersion [header=UnknownMachine/()/T=1487233193504, chunks=0, multiChunks=0, fileContents=0, fileHistories=0]
16-2-17 8:19:53.504 | UpOperation     | Test worker    | INFO : Sync up done.
16-2-17 8:19:53.506 | ActionFileHandl | Test worker    | INFO : Finishing action for RemoteFile[name=action-up-A-1487233193479] ...
16-2-17 8:19:53.506 | ActionFileHandl | Test worker    | INFO : Deleting action file: RemoteFile[name=action-up-A-1487233193479]
16-2-17 8:19:53.506 | AbstractTransfe | Test worker    | INFO : Cleaning own old action files ...
16-2-17 8:19:53.507 | Cache           | Test worker    | INFO : Cache size okay (155 KB), no need to clean (keep size is 500 MB)
16-2-17 8:19:53.507 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event UpEndSyncExternalEvent (org.syncany.operations.daemon.messages.UpEndSyncExternalEvent@7de89aee) ...
16-2-17 8:19:53.507 | org.syncany.plu | Test worker    | FINE : Validating required fields
16-2-17 8:19:53.507 | TransferManager | Test worker    | INFO : Building LocalTransferManager from config 'syncany-170216081829395-47281-client-B' ...
16-2-17 8:19:53.507 | TransferManager | Test worker    | INFO : - With feature ReadAfterWriteConsistent
16-2-17 8:19:53.507 | TransferManager | Test worker    | INFO : - With feature Retriable
16-2-17 8:19:53.507 | TransferManager | Test worker    | FINE : - Checking if selected features supported ...
16-2-17 8:19:53.507 | TransferManager | Test worker    | FINE : - Checking for duplicate features ...
16-2-17 8:19:53.507 | TransferManager | Test worker    | FINE : - Checking required feature TransactionAware in LocalTransferManager ...
16-2-17 8:19:53.507 | TransferManager | Test worker    | FINE : - Checking required feature Retriable in LocalTransferManager ...
16-2-17 8:19:53.507 | TransferManager | Test worker    | INFO : - SKIPPING unsupported optional feature ReadAfterWriteConsistent
16-2-17 8:19:53.507 | TransferManager | Test worker    | FINE : - Wrapping TransferManager LocalTransferManager in RetriableFeatureTransferManager
16-2-17 8:19:53.507 | org.syncany.plu | Test worker    | FINE : Validating required fields
16-2-17 8:19:53.507 | TransferManager | Test worker    | INFO : Building LocalTransferManager from config 'syncany-170216081829395-47281-client-B' ...
16-2-17 8:19:53.507 | TransferManager | Test worker    | INFO : - With feature ReadAfterWriteConsistent
16-2-17 8:19:53.507 | TransferManager | Test worker    | INFO : - With feature Retriable
16-2-17 8:19:53.507 | TransferManager | Test worker    | INFO : - With feature PathAware
16-2-17 8:19:53.507 | TransferManager | Test worker    | INFO : - With feature TransactionAware
16-2-17 8:19:53.507 | TransferManager | Test worker    | FINE : - Checking if selected features supported ...
16-2-17 8:19:53.507 | TransferManager | Test worker    | FINE : - Checking for duplicate features ...
16-2-17 8:19:53.507 | TransferManager | Test worker    | FINE : - Checking required feature TransactionAware in LocalTransferManager ...
16-2-17 8:19:53.507 | TransferManager | Test worker    | FINE : - Checking required feature Retriable in LocalTransferManager ...
16-2-17 8:19:53.507 | TransferManager | Test worker    | INFO : - SKIPPING unsupported optional feature ReadAfterWriteConsistent
16-2-17 8:19:53.507 | TransferManager | Test worker    | FINE : - Wrapping TransferManager LocalTransferManager in RetriableFeatureTransferManager
16-2-17 8:19:53.507 | TransferManager | Test worker    | INFO : - SKIPPING unsupported optional feature PathAware
16-2-17 8:19:53.507 | TransferManager | Test worker    | FINE : - Wrapping TransferManager RetriableFeatureTransferManager in TransactionAwareFeatureTransferManager
16-2-17 8:19:53.522 | hsqldb.db.HSQLD | Test worker    | INFO : Checkpoint start
16-2-17 8:19:53.522 | hsqldb.db.HSQLD | Test worker    | INFO : checkpointClose start
16-2-17 8:19:53.530 | hsqldb.db.HSQLD | Test worker    | FINE : log close size: 0
16-2-17 8:19:53.532 | hsqldb.db.HSQLD | Test worker    | INFO : checkpointClose end
16-2-17 8:19:53.532 | hsqldb.db.HSQLD | Test worker    | INFO : Checkpoint end - txts: 1
16-2-17 8:19:53.538 | DatabaseConnect | Test worker    | INFO : Found 0 tables.
16-2-17 8:19:53.538 | DatabaseConnect | Test worker    | INFO : Database has no tables. Creating tables from script.create.all.sql
16-2-17 8:19:53.538 | SqlRunner       | Test worker    | INFO : SQL (comment): -- Tables
16-2-17 8:19:53.538 | SqlRunner       | Test worker    | INFO : SQL:  CREATE CACHED TABLE IF NOT EXISTS databaseversion ( id int NOT NULL IDENTITY, status varchar(45) NOT NULL, localtime datetime NOT NULL, client varchar(45) NOT NULL, vectorclock_serialized varchar(1024) NOT NULL, UNIQUE (vectorclock_serialized) ); 
16-2-17 8:19:53.542 | SqlRunner       | Test worker    | INFO : SQL:  CREATE CACHED TABLE IF NOT EXISTS  chunk ( checksum varchar(40) NOT NULL, databaseversion_id int NOT NULL, size bigint NOT NULL, PRIMARY KEY (checksum), FOREIGN KEY (databaseversion_id) REFERENCES databaseversion (id) ON DELETE NO ACTION ON UPDATE NO ACTION ); 
16-2-17 8:19:53.544 | SqlRunner       | Test worker    | INFO : SQL:  CREATE CACHED TABLE IF NOT EXISTS  databaseversion_vectorclock ( databaseversion_id int NOT NULL, client varchar(45) NOT NULL, logicaltime int NOT NULL, PRIMARY KEY (databaseversion_id, client), FOREIGN KEY (databaseversion_id) REFERENCES databaseversion (id) ON DELETE NO ACTION ON UPDATE NO ACTION ); 
16-2-17 8:19:53.546 | SqlRunner       | Test worker    | INFO : SQL:  CREATE CACHED TABLE IF NOT EXISTS  filecontent ( checksum varchar(40) NOT NULL, databaseversion_id int NOT NULL, size bigint NOT NULL, PRIMARY KEY (checksum), FOREIGN KEY (databaseversion_id) REFERENCES databaseversion (id) ON DELETE NO ACTION ON UPDATE NO ACTION ); 
16-2-17 8:19:53.548 | SqlRunner       | Test worker    | INFO : SQL:  CREATE CACHED TABLE IF NOT EXISTS  filecontent_chunk ( filecontent_checksum varchar(40) NOT NULL, chunk_checksum varchar(40) NOT NULL, num int NOT NULL, PRIMARY KEY (filecontent_checksum, chunk_checksum, num), FOREIGN KEY (filecontent_checksum) REFERENCES filecontent (checksum) ON DELETE NO ACTION ON UPDATE NO ACTION, FOREIGN KEY (chunk_checksum) REFERENCES chunk (checksum) ON DELETE NO ACTION ON UPDATE NO ACTION ); 
16-2-17 8:19:53.550 | SqlRunner       | Test worker    | INFO : SQL:  CREATE CACHED TABLE IF NOT EXISTS  filehistory ( id varchar(40) NOT NULL, databaseversion_id int NOT NULL, PRIMARY KEY (id, databaseversion_id), FOREIGN KEY (databaseversion_id) REFERENCES databaseversion (id) ON DELETE NO ACTION ON UPDATE NO ACTION ); 
16-2-17 8:19:53.552 | SqlRunner       | Test worker    | INFO : SQL:  CREATE CACHED TABLE IF NOT EXISTS  fileversion ( filehistory_id varchar(40) NOT NULL, version int NOT NULL, databaseversion_id int NOT NULL, path varchar(1024) NOT NULL, type varchar(45) NOT NULL, status varchar(45) NOT NULL, size bigint NOT NULL, lastmodified datetime NOT NULL, linktarget varchar(1024), filecontent_checksum varchar(40) DEFAULT NULL, updated datetime NOT NULL, posixperms varchar(45) DEFAULT NULL, dosattrs varchar(45) DEFAULT NULL, PRIMARY KEY (filehistory_id, version, databaseversion_id), FOREIGN KEY (filehistory_id, databaseversion_id) REFERENCES filehistory (id, databaseversion_id) ON DELETE NO ACTION ON UPDATE NO ACTION, FOREIGN KEY (filecontent_checksum) REFERENCES filecontent (checksum) ON DELETE NO ACTION ON UPDATE NO ACTION ); 
16-2-17 8:19:53.555 | SqlRunner       | Test worker    | INFO : SQL:  CREATE CACHED TABLE IF NOT EXISTS  multichunk ( id varchar(40) NOT NULL, databaseversion_id int NOT NULL, size bigint NOT NULL, PRIMARY KEY (id), FOREIGN KEY (databaseversion_id) REFERENCES databaseversion (id) ON DELETE NO ACTION ON UPDATE NO ACTION ); 
16-2-17 8:19:53.557 | SqlRunner       | Test worker    | INFO : SQL:  CREATE CACHED TABLE IF NOT EXISTS  multichunk_chunk ( multichunk_id varchar(40) NOT NULL, chunk_checksum varchar(40) NOT NULL, PRIMARY KEY (multichunk_id, chunk_checksum), FOREIGN KEY (multichunk_id) REFERENCES multichunk (id) ON DELETE NO ACTION ON UPDATE NO ACTION, FOREIGN KEY (chunk_checksum) REFERENCES chunk (checksum) ON DELETE NO ACTION ON UPDATE NO ACTION ); 
16-2-17 8:19:53.559 | SqlRunner       | Test worker    | INFO : SQL:  CREATE CACHED TABLE IF NOT EXISTS  multichunk_muddy ( id varchar(40) NOT NULL, machine_name varchar(255) NOT NULL, machine_version int NOT NULL, PRIMARY KEY (id) ); 
16-2-17 8:19:53.561 | SqlRunner       | Test worker    | INFO : SQL:  CREATE CACHED TABLE IF NOT EXISTS  known_databases ( id int NOT NULL IDENTITY, client varchar(45) NOT NULL, filenumber int NOT NULL, UNIQUE (client, filenumber) ); 
16-2-17 8:19:53.563 | SqlRunner       | Test worker    | INFO : SQL:  CREATE CACHED TABLE IF NOT EXISTS  general_settings ( key varchar(255) NOT NULL, value varchar(255) NOT NULL, PRIMARY KEY (key) ); 
16-2-17 8:19:53.565 | SqlRunner       | Test worker    | INFO : SQL (comment): -- Non-primary indices
16-2-17 8:19:53.565 | SqlRunner       | Test worker    | INFO : SQL:   CREATE INDEX idx_databaseversion_status ON databaseversion (status); 
16-2-17 8:19:53.566 | hsqldb.db.HSQLD | Test worker    | INFO : dataFileCache open start
16-2-17 8:19:53.570 | hsqldb.db.HSQLD | Test worker    | INFO : dataFileCache open end
16-2-17 8:19:53.572 | SqlRunner       | Test worker    | INFO : SQL: CREATE INDEX idx_databaseversion_vectorclock_serialized ON databaseversion (vectorclock_serialized); 
16-2-17 8:19:53.574 | SqlRunner       | Test worker    | INFO : SQL: CREATE INDEX idx_fileversion_path ON fileversion (path); 
16-2-17 8:19:53.576 | SqlRunner       | Test worker    | INFO : SQL: CREATE INDEX idx_fileversion_status ON fileversion (status); 
16-2-17 8:19:53.578 | SqlRunner       | Test worker    | INFO : SQL: CREATE INDEX idx_fileversion_filecontent_checksum ON fileversion (filecontent_checksum); 
16-2-17 8:19:53.580 | SqlRunner       | Test worker    | INFO : SQL (comment): -- Views
16-2-17 8:19:53.580 | SqlRunner       | Test worker    | INFO : SQL:    CREATE VIEW databaseversion_master AS SELECT dbv.*, vc.logicaltime as client_version FROM databaseversion dbv JOIN databaseversion_vectorclock vc on dbv.id=vc.databaseversion_id and dbv.client=vc.client WHERE dbv.status='MASTER'; 
16-2-17 8:19:53.583 | SqlRunner       | Test worker    | INFO : SQL:  CREATE VIEW fileversion_master AS SELECT fv0.* FROM fileversion fv0 JOIN databaseversion dbv ON fv0.databaseversion_id=dbv.id AND dbv.status='MASTER'; 
16-2-17 8:19:53.585 | SqlRunner       | Test worker    | INFO : SQL:  CREATE VIEW fileversion_master_maxversion AS SELECT DISTINCT filehistory_id, MAX(version) version FROM fileversion_master GROUP BY filehistory_id; 
16-2-17 8:19:53.588 | SqlRunner       | Test worker    | INFO : SQL:  CREATE VIEW fileversion_master_last AS SELECT fv.* FROM fileversion_master_maxversion fvmax JOIN fileversion_master fv ON fvmax.filehistory_id=fv.filehistory_id AND fvmax.version=fv.version WHERE fv.status<>'DELETED'; 
16-2-17 8:19:53.590 | SqlRunner       | Test worker    | INFO : SQL (comment): -- Full Views
16-2-17 8:19:53.590 | SqlRunner       | Test worker    | INFO : SQL:    create view filehistory_full as select dbv.status as databaseversion_status, dbv.localtime as databaseversion_localtime, dbv.client as databaseversion_client, dbv.vectorclock_serialized as databaseversion_vectorclock_serialized, fh.* from databaseversion dbv join filehistory fh on dbv.id=fh.databaseversion_id; 
16-2-17 8:19:53.592 | SqlRunner       | Test worker    | INFO : SQL:  create view fileversion_full as select fhf.databaseversion_status, fhf.databaseversion_localtime, fhf.databaseversion_client, fhf.databaseversion_vectorclock_serialized, fv.* from filehistory_full fhf join fileversion fv on fhf.id=fv.filehistory_id and fhf.databaseversion_id=fv.databaseversion_id; 
16-2-17 8:19:53.595 | SqlRunner       | Test worker    | INFO : SQL (comment): -- Functions
16-2-17 8:19:53.595 | SqlRunner       | Test worker    | INFO : SQL (new delimiter): end;
16-2-17 8:19:53.595 | SqlRunner       | Test worker    | INFO : SQL:     create function substr_count(haystack varchar(255), needle varchar(255)) returns integer begin atomic declare strCount integer; declare lastIndex integer;  set strCount = 0; set lastIndex = 1;  while lastIndex <> 0 do set lastIndex = locate(needle, haystack, lastIndex);  if lastIndex <> 0 then set strCount = strCount + 1; set lastIndex = lastIndex + length(needle); end if; end while;  return strCount; end; 
16-2-17 8:19:53.598 | DownOperation   | Test worker    | INFO : 
16-2-17 8:19:53.598 | DownOperation   | Test worker    | INFO : Running 'Sync down' at client B ...
16-2-17 8:19:53.598 | DownOperation   | Test worker    | INFO : --------------------------------------------
16-2-17 8:19:53.598 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event DownStartSyncExternalEvent (org.syncany.operations.daemon.messages.DownStartSyncExternalEvent@41eb48aa) ...
16-2-17 8:19:53.598 | AbstractTransfe | Test worker    | INFO : Looking for other running remote operations ...
16-2-17 8:19:53.599 | DatabaseConnect | Test worker    | INFO : Found 12 tables.
16-2-17 8:19:53.599 | LsRemoteOperati | Test worker    | INFO : 
16-2-17 8:19:53.599 | LsRemoteOperati | Test worker    | INFO : Running 'Remote Status' at client B ...
16-2-17 8:19:53.599 | LsRemoteOperati | Test worker    | INFO : --------------------------------------------
16-2-17 8:19:53.599 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event LsRemoteStartSyncExternalEvent (org.syncany.operations.daemon.messages.LsRemoteStartSyncExternalEvent@35a891a4) ...
16-2-17 8:19:53.600 | LsRemoteOperati | Test worker    | INFO : Retrieving remote database list.
16-2-17 8:19:53.600 | LsRemoteOperati | Test worker    | INFO : - Remote database database-A-0000000004 is new.
16-2-17 8:19:53.600 | LsRemoteOperati | Test worker    | INFO : - Remote database database-A-0000000002 is new.
16-2-17 8:19:53.600 | LsRemoteOperati | Test worker    | INFO : - Remote database database-A-0000000003 is new.
16-2-17 8:19:53.600 | LsRemoteOperati | Test worker    | INFO : - Remote database database-A-0000000001 is new.
16-2-17 8:19:53.600 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event LsRemoteEndSyncExternalEvent (org.syncany.operations.daemon.messages.LsRemoteEndSyncExternalEvent@7bbc78c0) ...
16-2-17 8:19:53.600 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event DownChangesDetectedSyncExternalEvent (org.syncany.operations.daemon.messages.DownChangesDetectedSyncExternalEvent@7629f979) ...
16-2-17 8:19:53.600 | ActionFileHandl | Test worker    | INFO : Starting action for RemoteFile[name=action-down-B-1487233193507] ...
16-2-17 8:19:53.600 | ActionFileHandl | Test worker    | INFO : Uploading action file: RemoteFile[name=action-down-B-1487233193507]
16-2-17 8:19:53.601 | ActionFileHandl | Test worker    | INFO : Scheduling action renewal task for every 2 minutes, for RemoteFile[name=action-down-B-1487233193507] ...
16-2-17 8:19:53.601 | DownOperation   | Test worker    | INFO : Downloading unknown databases.
16-2-17 8:19:53.601 | DownOperation   | Test worker    | INFO : - Downloading database-A-0000000004 to local cache at /tmp/syncanytest/syncany-170216081829395-47281-client-B/.syncany/cache/database-A-0000000004
16-2-17 8:19:53.601 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event DownDownloadFileSyncExternalEvent (org.syncany.operations.daemon.messages.DownDownloadFileSyncExternalEvent@31abb4ee) ...
16-2-17 8:19:53.602 | DownOperation   | Test worker    | INFO : - Downloading database-A-0000000002 to local cache at /tmp/syncanytest/syncany-170216081829395-47281-client-B/.syncany/cache/database-A-0000000002
16-2-17 8:19:53.602 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event DownDownloadFileSyncExternalEvent (org.syncany.operations.daemon.messages.DownDownloadFileSyncExternalEvent@8f07193) ...
16-2-17 8:19:53.602 | DownOperation   | Test worker    | INFO : - Downloading database-A-0000000003 to local cache at /tmp/syncanytest/syncany-170216081829395-47281-client-B/.syncany/cache/database-A-0000000003
16-2-17 8:19:53.602 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event DownDownloadFileSyncExternalEvent (org.syncany.operations.daemon.messages.DownDownloadFileSyncExternalEvent@647c2b1f) ...
16-2-17 8:19:53.603 | DownOperation   | Test worker    | INFO : - Downloading database-A-0000000001 to local cache at /tmp/syncanytest/syncany-170216081829395-47281-client-B/.syncany/cache/database-A-0000000001
16-2-17 8:19:53.603 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event DownDownloadFileSyncExternalEvent (org.syncany.operations.daemon.messages.DownDownloadFileSyncExternalEvent@4a3e24) ...
16-2-17 8:19:53.603 | DownOperation   | Test worker    | INFO : Loading database headers, creating branches ...
16-2-17 8:19:53.603 | DatabaseXmlSeri | Test worker    | INFO : - Loading database (HEADER_ONLY) from file /tmp/syncanytest/syncany-170216081829395-47281-client-B/.syncany/cache/database-A-0000000001 ...
16-2-17 8:19:53.605 | DatabaseXmlPars | Test worker    | INFO :    + Added database version A/(A1)/T=1487233193391
16-2-17 8:19:53.605 | DatabaseXmlSeri | Test worker    | INFO : - Loading database (HEADER_ONLY) from file /tmp/syncanytest/syncany-170216081829395-47281-client-B/.syncany/cache/database-A-0000000002 ...
16-2-17 8:19:53.606 | DatabaseXmlPars | Test worker    | INFO :    + Added database version A/(A2)/T=1487233193425
16-2-17 8:19:53.606 | DatabaseXmlSeri | Test worker    | INFO : - Loading database (HEADER_ONLY) from file /tmp/syncanytest/syncany-170216081829395-47281-client-B/.syncany/cache/database-A-0000000003 ...
16-2-17 8:19:53.607 | DatabaseXmlPars | Test worker    | INFO :    + Added database version A/(A3)/T=1487233193455
16-2-17 8:19:53.607 | DatabaseXmlSeri | Test worker    | INFO : - Loading database (HEADER_ONLY) from file /tmp/syncanytest/syncany-170216081829395-47281-client-B/.syncany/cache/database-A-0000000004 ...
16-2-17 8:19:53.608 | DatabaseXmlPars | Test worker    | INFO :    + Added database version A/(A4)/T=1487233193490
16-2-17 8:19:53.608 | LocalTransferMa | Test worker    | INFO : Cannot create instance of CleanupRemoteFile for file /tmp/syncanytest/syncany-170216081829395-47281-repo/databases/database-A-0000000004; maybe invalid file name pattern. Ignoring file.
16-2-17 8:19:53.608 | LocalTransferMa | Test worker    | INFO : Cannot create instance of CleanupRemoteFile for file /tmp/syncanytest/syncany-170216081829395-47281-repo/databases/database-A-0000000002; maybe invalid file name pattern. Ignoring file.
16-2-17 8:19:53.609 | LocalTransferMa | Test worker    | INFO : Cannot create instance of CleanupRemoteFile for file /tmp/syncanytest/syncany-170216081829395-47281-repo/databases/database-A-0000000001; maybe invalid file name pattern. Ignoring file.
16-2-17 8:19:53.609 | LocalTransferMa | Test worker    | INFO : Cannot create instance of CleanupRemoteFile for file /tmp/syncanytest/syncany-170216081829395-47281-repo/databases/database-A-0000000003; maybe invalid file name pattern. Ignoring file.
16-2-17 8:19:53.609 | ApplicationSqlD | Test worker    | INFO : SQL (general_settings): Read cleanupNumber  = (not set)
16-2-17 8:19:53.609 | DownOperation   | Test worker    | INFO : Populated unknown branches: {A=[A/(A1)/T=1487233193391, A/(A2)/T=1487233193425, A/(A3)/T=1487233193455, A/(A4)/T=1487233193490], B=[]}
16-2-17 8:19:53.609 | DownOperation   | Test worker    | INFO : Determine winner using database reconciliator ...
16-2-17 8:19:53.609 | DatabaseReconci | Test worker    | INFO : Adding database version to winning branch: A/(A1)/T=1487233193391
16-2-17 8:19:53.609 | DatabaseReconci | Test worker    | INFO : Adding database version to winning branch: A/(A2)/T=1487233193425
16-2-17 8:19:53.609 | DatabaseReconci | Test worker    | INFO : Adding database version to winning branch: A/(A3)/T=1487233193455
16-2-17 8:19:53.610 | DatabaseReconci | Test worker    | INFO : Adding database version to winning branch: A/(A4)/T=1487233193490
16-2-17 8:19:53.610 | DatabaseReconci | Test worker    | INFO : - Winner is A with branch: 
16-2-17 8:19:53.610 | DatabaseReconci | Test worker    | INFO :   + A/(A1)/T=1487233193391
16-2-17 8:19:53.610 | DatabaseReconci | Test worker    | INFO :   + A/(A2)/T=1487233193425
16-2-17 8:19:53.610 | DatabaseReconci | Test worker    | INFO :   + A/(A3)/T=1487233193455
16-2-17 8:19:53.610 | DatabaseReconci | Test worker    | INFO :   + A/(A4)/T=1487233193490
16-2-17 8:19:53.610 | DownOperation   | Test worker    | INFO : - Database versions to REMOVE locally: []
16-2-17 8:19:53.610 | DownOperation   | Test worker    | INFO :   + Nothing to purge locally. No conflicts. Only updates. Nice!
16-2-17 8:19:53.610 | DatabaseReconci | Test worker    | INFO : Finding winnersApplyBranch.
16-2-17 8:19:53.610 | DatabaseReconci | Test worker    | INFO : Losers Branch: []
16-2-17 8:19:53.610 | DatabaseReconci | Test worker    | INFO : Winners Branch: [A/(A1)/T=1487233193391, A/(A2)/T=1487233193425, A/(A3)/T=1487233193455, A/(A4)/T=1487233193490]
16-2-17 8:19:53.610 | DownOperation   | Test worker    | INFO : - Cleanup occurred: false
16-2-17 8:19:53.610 | DownOperation   | Test worker    | INFO : - Database versions to APPLY locally: [A/(A1)/T=1487233193391, A/(A2)/T=1487233193425, A/(A3)/T=1487233193455, A/(A4)/T=1487233193490]
16-2-17 8:19:53.611 | DownOperation   | Test worker    | INFO : Loading winners database (DEFAULT) ...
16-2-17 8:19:53.611 | DatabaseXmlSeri | Test worker    | INFO : - Loading database (FULL) from file /tmp/syncanytest/syncany-170216081829395-47281-client-B/.syncany/cache/database-A-0000000001 ...
16-2-17 8:19:53.612 | DatabaseXmlPars | Test worker    | INFO :    + Added database version A/(A1)/T=1487233193391
16-2-17 8:19:53.612 | DatabaseXmlSeri | Test worker    | INFO : - Loading database (FULL) from file /tmp/syncanytest/syncany-170216081829395-47281-client-B/.syncany/cache/database-A-0000000002 ...
16-2-17 8:19:53.613 | DatabaseXmlPars | Test worker    | INFO :    + Added database version A/(A2)/T=1487233193425
16-2-17 8:19:53.613 | DatabaseXmlSeri | Test worker    | INFO : - Loading database (FULL) from file /tmp/syncanytest/syncany-170216081829395-47281-client-B/.syncany/cache/database-A-0000000003 ...
16-2-17 8:19:53.614 | DatabaseXmlPars | Test worker    | INFO :    + Added database version A/(A3)/T=1487233193455
16-2-17 8:19:53.614 | DatabaseXmlSeri | Test worker    | INFO : - Loading database (FULL) from file /tmp/syncanytest/syncany-170216081829395-47281-client-B/.syncany/cache/database-A-0000000004 ...
16-2-17 8:19:53.616 | DatabaseXmlPars | Test worker    | INFO :    + Added database version A/(A4)/T=1487233193490
16-2-17 8:19:53.616 | DownOperation   | Test worker    | INFO : Determine file system actions ...
16-2-17 8:19:53.617 | DatabaseConnect | Test worker    | INFO : Found 12 tables.
16-2-17 8:19:53.619 | FileSystemActio | Test worker    | INFO : - Loading current file tree...
16-2-17 8:19:53.619 | FileSystemActio | Test worker    | INFO : - Determine filesystem actions ...
16-2-17 8:19:53.620 | FileSystemActio | Test worker    | INFO :   + Comparing local version: null
16-2-17 8:19:53.620 | FileSystemActio | Test worker    | INFO :     with winning version   : FileVersion [version=2, path=A3, type=FILE, status=DELETED, size=51200, lastModified=Thu Feb 16 08:19:53 UTC 2017, linkTarget=null, checksum=db017b97ff6e9e8df6c8ba9158064dd65824bc95, updated=Thu Feb 16 08:19:53 UTC 2017, posixPermissions=rw-rw-r--, dosAttributes=--a-]
16-2-17 8:19:53.620 | FileVersionComp | Test worker    | INFO :      - []: Local file does not exist, and expected file was deleted, for file A3
16-2-17 8:19:53.620 | FileSystemActio | Test worker    | INFO :      -> (1) Equals: Nothing to do, winning version equals winning file: FileVersion [version=2, path=A3, type=FILE, status=DELETED, size=51200, lastModified=Thu Feb 16 08:19:53 UTC 2017, linkTarget=null, checksum=db017b97ff6e9e8df6c8ba9158064dd65824bc95, updated=Thu Feb 16 08:19:53 UTC 2017, posixPermissions=rw-rw-r--, dosAttributes=--a-] AND /tmp/syncanytest/syncany-170216081829395-47281-client-B/A3
16-2-17 8:19:53.620 | FileSystemActio | Test worker    | INFO :   + Comparing local version: null
16-2-17 8:19:53.620 | FileSystemActio | Test worker    | INFO :     with winning version   : FileVersion [version=3, path=A2, type=FILE, status=CHANGED, size=51200, lastModified=Thu Feb 16 08:19:53 UTC 2017, linkTarget=null, checksum=fb073b8aea2e985ed83cd0a4412b798fe911428e, updated=Thu Feb 16 08:19:53 UTC 2017, posixPermissions=rw-rw-r--, dosAttributes=--a-]
16-2-17 8:19:53.620 | FileVersionComp | Test worker    | INFO :      - [DELETED]: Local file DIFFERS from file version, expected EXISTS = true, but actual EXISTS = false, for file A2
16-2-17 8:19:53.621 | FileSystemActio | Test worker    | INFO :      -> (2) Deleted: Local file does NOT exist, but it should, winning version not known: FileVersion [version=3, path=A2, type=FILE, status=CHANGED, size=51200, lastModified=Thu Feb 16 08:19:53 UTC 2017, linkTarget=null, checksum=fb073b8aea2e985ed83cd0a4412b798fe911428e, updated=Thu Feb 16 08:19:53 UTC 2017, posixPermissions=rw-rw-r--, dosAttributes=--a-] AND /tmp/syncanytest/syncany-170216081829395-47281-client-B/A2
16-2-17 8:19:53.621 | FileSystemActio | Test worker    | INFO :      -> NewFileSystemAction [file1=null, file2=FileVersion [version=3, path=A2, type=FILE, status=CHANGED, size=51200, lastModified=Thu Feb 16 08:19:53 UTC 2017, linkTarget=null, checksum=fb073b8aea2e985ed83cd0a4412b798fe911428e, updated=Thu Feb 16 08:19:53 UTC 2017, posixPermissions=rw-rw-r--, dosAttributes=--a-]]
16-2-17 8:19:53.621 | DownOperation   | Test worker    | INFO :   + Adding multichunk 15aa4aa6b32b4d905a0ca51bfb145bb1df5ebe63 to download list ...
16-2-17 8:19:53.621 | Downloader      | Test worker    | INFO : Downloading and extracting multichunks ...
16-2-17 8:19:53.621 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event DownDownloadFileSyncExternalEvent (org.syncany.operations.daemon.messages.DownDownloadFileSyncExternalEvent@5c92332b) ...
16-2-17 8:19:53.622 | Downloader      | Test worker    | INFO :   + Downloading multichunk 15aa4aa6b32b4d905a0ca51bfb145bb1df5ebe63 ...
16-2-17 8:19:53.622 | Downloader      | Test worker    | INFO :   + Decrypting multichunk 15aa4aa6b32b4d905a0ca51bfb145bb1df5ebe63 ...
16-2-17 8:19:53.622 | Downloader      | Test worker    | FINE :   + Locally deleting multichunk 15aa4aa6b32b4d905a0ca51bfb145bb1df5ebe63 ...
16-2-17 8:19:53.624 | FileSystemActio | Test worker    | INFO :    Sorted actions:
16-2-17 8:19:53.624 | FileSystemActio | Test worker    | INFO :    + NewFileSystemAction [file1=null, file2=FileVersion [version=3, path=A2, type=FILE, status=CHANGED, size=51200, lastModified=Thu Feb 16 08:19:53 UTC 2017, linkTarget=null, checksum=fb073b8aea2e985ed83cd0a4412b798fe911428e, updated=Thu Feb 16 08:19:53 UTC 2017, posixPermissions=rw-rw-r--, dosAttributes=--a-]]
16-2-17 8:19:53.624 | DownOperation   | Test worker    | FINE : - Applying file system actions (sorted!) ...
16-2-17 8:19:53.624 | DownOperation   | Test worker    | FINE :    +  NewFileSystemAction [file1=null, file2=FileVersion [version=3, path=A2, type=FILE, status=CHANGED, size=51200, lastModified=Thu Feb 16 08:19:53 UTC 2017, linkTarget=null, checksum=fb073b8aea2e985ed83cd0a4412b798fe911428e, updated=Thu Feb 16 08:19:53 UTC 2017, posixPermissions=rw-rw-r--, dosAttributes=--a-]]
16-2-17 8:19:53.624 | Assembler       | Test worker    | INFO :      - Creating file A2 to /tmp/syncanytest/syncany-170216081829395-47281-client-B/.syncany/cache/temp-reconstructedFileVersion-1650336585004506052.tmp ...
16-2-17 8:19:53.625 | FileSystemActio | Test worker    | INFO :      - Setting POSIX permissions: rw-rw-r-- ...
16-2-17 8:19:53.625 | DownOperation   | Test worker    | INFO : - Adding database versions to SQL database ...
16-2-17 8:19:53.626 | DownOperation   | Test worker    | INFO :   + Applying database version (A1)
16-2-17 8:19:53.626 | DownOperation   | Test worker    | FINE :   + Contents: DatabaseVersion [header=A/(A1)/T=1487233193391, chunks=1, multiChunks=1, fileContents=1, fileHistories=1]
16-2-17 8:19:53.627 | DownOperation   | Test worker    | INFO :   + Applying database version (A2)
16-2-17 8:19:53.627 | DownOperation   | Test worker    | FINE :   + Contents: DatabaseVersion [header=A/(A2)/T=1487233193425, chunks=0, multiChunks=0, fileContents=0, fileHistories=1]
16-2-17 8:19:53.628 | DownOperation   | Test worker    | INFO :   + Applying database version (A3)
16-2-17 8:19:53.628 | DownOperation   | Test worker    | FINE :   + Contents: DatabaseVersion [header=A/(A3)/T=1487233193455, chunks=2, multiChunks=1, fileContents=2, fileHistories=2]
16-2-17 8:19:53.630 | DownOperation   | Test worker    | INFO :   + Applying database version (A4)
16-2-17 8:19:53.630 | DownOperation   | Test worker    | FINE :   + Contents: DatabaseVersion [header=A/(A4)/T=1487233193490, chunks=0, multiChunks=0, fileContents=0, fileHistories=1]
16-2-17 8:19:53.637 | ActionFileHandl | Test worker    | INFO : Finishing action for RemoteFile[name=action-down-B-1487233193507] ...
16-2-17 8:19:53.637 | ActionFileHandl | Test worker    | INFO : Deleting action file: RemoteFile[name=action-down-B-1487233193507]
16-2-17 8:19:53.637 | AbstractTransfe | Test worker    | INFO : Cleaning own old action files ...
16-2-17 8:19:53.637 | Cache           | Test worker    | INFO : Cache size okay (104 KB), no need to clean (keep size is 500 MB)
16-2-17 8:19:53.637 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event DownEndSyncExternalEvent (org.syncany.operations.daemon.messages.DownEndSyncExternalEvent@3aca59b0) ...
16-2-17 8:19:53.637 | DownOperation   | Test worker    | INFO : Sync down done.
16-2-17 8:19:53.642 | FileVersionComp | Test worker    | INFO :      - [CHANGED_PATH]: Local file DIFFERS from file version, expected PATH = tmp/syncanytest/syncany-170216081829395-47281-client-A/A2, but actual PATH = tmp/syncanytest/syncany-170216081829395-47281-client-B/A2, for file tmp/syncanytest/syncany-170216081829395-47281-client-B/A2
16-2-17 8:19:53.642 | org.syncany.plu | Test worker    | FINE : Validating required fields
16-2-17 8:19:53.642 | TransferManager | Test worker    | INFO : Building LocalTransferManager from config 'syncany-170216081829395-47281-client-B' ...
16-2-17 8:19:53.643 | TransferManager | Test worker    | INFO : - With feature ReadAfterWriteConsistent
16-2-17 8:19:53.643 | TransferManager | Test worker    | INFO : - With feature Retriable
16-2-17 8:19:53.643 | TransferManager | Test worker    | FINE : - Checking if selected features supported ...
16-2-17 8:19:53.643 | TransferManager | Test worker    | FINE : - Checking for duplicate features ...
16-2-17 8:19:53.643 | TransferManager | Test worker    | FINE : - Checking required feature TransactionAware in LocalTransferManager ...
16-2-17 8:19:53.643 | TransferManager | Test worker    | FINE : - Checking required feature Retriable in LocalTransferManager ...
16-2-17 8:19:53.643 | TransferManager | Test worker    | INFO : - SKIPPING unsupported optional feature ReadAfterWriteConsistent
16-2-17 8:19:53.643 | TransferManager | Test worker    | FINE : - Wrapping TransferManager LocalTransferManager in RetriableFeatureTransferManager
16-2-17 8:19:53.643 | org.syncany.plu | Test worker    | FINE : Validating required fields
16-2-17 8:19:53.643 | TransferManager | Test worker    | INFO : Building LocalTransferManager from config 'syncany-170216081829395-47281-client-B' ...
16-2-17 8:19:53.643 | TransferManager | Test worker    | INFO : - With feature ReadAfterWriteConsistent
16-2-17 8:19:53.644 | TransferManager | Test worker    | INFO : - With feature Retriable
16-2-17 8:19:53.644 | TransferManager | Test worker    | INFO : - With feature PathAware
16-2-17 8:19:53.644 | TransferManager | Test worker    | INFO : - With feature TransactionAware
16-2-17 8:19:53.644 | TransferManager | Test worker    | FINE : - Checking if selected features supported ...
16-2-17 8:19:53.644 | TransferManager | Test worker    | FINE : - Checking for duplicate features ...
16-2-17 8:19:53.644 | TransferManager | Test worker    | FINE : - Checking required feature TransactionAware in LocalTransferManager ...
16-2-17 8:19:53.644 | TransferManager | Test worker    | FINE : - Checking required feature Retriable in LocalTransferManager ...
16-2-17 8:19:53.644 | TransferManager | Test worker    | INFO : - SKIPPING unsupported optional feature ReadAfterWriteConsistent
16-2-17 8:19:53.644 | TransferManager | Test worker    | FINE : - Wrapping TransferManager LocalTransferManager in RetriableFeatureTransferManager
16-2-17 8:19:53.644 | TransferManager | Test worker    | INFO : - SKIPPING unsupported optional feature PathAware
16-2-17 8:19:53.644 | TransferManager | Test worker    | FINE : - Wrapping TransferManager RetriableFeatureTransferManager in TransactionAwareFeatureTransferManager
16-2-17 8:19:53.645 | DatabaseConnect | Test worker    | INFO : Found 12 tables.
16-2-17 8:19:53.646 | UpOperation     | Test worker    | INFO : 
16-2-17 8:19:53.646 | UpOperation     | Test worker    | INFO : Running 'Sync up' at client B ...
16-2-17 8:19:53.646 | UpOperation     | Test worker    | INFO : --------------------------------------------
16-2-17 8:19:53.646 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event UpStartSyncExternalEvent (org.syncany.operations.daemon.messages.UpStartSyncExternalEvent@15834795) ...
16-2-17 8:19:53.646 | DatabaseConnect | Test worker    | INFO : Found 12 tables.
16-2-17 8:19:53.646 | StatusOperation | Test worker    | INFO : 
16-2-17 8:19:53.646 | StatusOperation | Test worker    | INFO : Running 'Status' at client B ...
16-2-17 8:19:53.646 | StatusOperation | Test worker    | INFO : --------------------------------------------
16-2-17 8:19:53.646 | StatusOperation | Test worker    | INFO : Querying current file tree from database ...
16-2-17 8:19:53.646 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event StatusStartSyncExternalEvent (org.syncany.operations.daemon.messages.StatusStartSyncExternalEvent@7e4b9905) ...
16-2-17 8:19:53.647 | StatusOperation | Test worker    | INFO : Analyzing local folder /tmp/syncanytest/syncany-170216081829395-47281-client-B ...
16-2-17 8:19:53.647 | StatusOperation | Test worker    | FINE : - Ignoring file (syncany app-related): .syncany
16-2-17 8:19:53.647 | StatusOperation | Test worker    | FINE : - New file: A4,B1
16-2-17 8:19:53.647 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event StatusEndSyncExternalEvent (org.syncany.operations.daemon.messages.StatusEndSyncExternalEvent@44c0ad06) ...
16-2-17 8:19:53.647 | AbstractTransfe | Test worker    | INFO : Looking for other running remote operations ...
16-2-17 8:19:53.648 | DatabaseConnect | Test worker    | INFO : Found 12 tables.
16-2-17 8:19:53.648 | LsRemoteOperati | Test worker    | INFO : 
16-2-17 8:19:53.648 | LsRemoteOperati | Test worker    | INFO : Running 'Remote Status' at client B ...
16-2-17 8:19:53.648 | LsRemoteOperati | Test worker    | INFO : --------------------------------------------
16-2-17 8:19:53.648 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event LsRemoteStartSyncExternalEvent (org.syncany.operations.daemon.messages.LsRemoteStartSyncExternalEvent@522e3bfc) ...
16-2-17 8:19:53.649 | LsRemoteOperati | Test worker    | INFO : Retrieving remote database list.
16-2-17 8:19:53.649 | LsRemoteOperati | Test worker    | INFO : - Remote database database-A-0000000004 is already known (in local database). Ignoring.
16-2-17 8:19:53.649 | LsRemoteOperati | Test worker    | INFO : - Remote database database-A-0000000002 is already known (in local database). Ignoring.
16-2-17 8:19:53.649 | LsRemoteOperati | Test worker    | INFO : - Remote database database-A-0000000003 is already known (in local database). Ignoring.
16-2-17 8:19:53.649 | LsRemoteOperati | Test worker    | INFO : - Remote database database-A-0000000001 is already known (in local database). Ignoring.
16-2-17 8:19:53.649 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event LsRemoteEndSyncExternalEvent (org.syncany.operations.daemon.messages.LsRemoteEndSyncExternalEvent@5482154e) ...
16-2-17 8:19:53.649 | UpOperation     | Test worker    | INFO : No remote changes, ready to upload.
16-2-17 8:19:53.649 | ActionFileHandl | Test worker    | INFO : Starting action for RemoteFile[name=action-up-B-1487233193645] ...
16-2-17 8:19:53.649 | ActionFileHandl | Test worker    | INFO : Uploading action file: RemoteFile[name=action-up-B-1487233193645]
16-2-17 8:19:53.650 | ActionFileHandl | Test worker    | INFO : Scheduling action renewal task for every 2 minutes, for RemoteFile[name=action-up-B-1487233193645] ...
16-2-17 8:19:53.650 | DatabaseConnect | Test worker    | INFO : Found 12 tables.
16-2-17 8:19:53.651 | TransactionAwar | Test worker    | INFO : Done rolling back previous transactions.
16-2-17 8:19:53.651 | AsyncIndexer    | AsyncI/syncany | INFO : Starting Indexing.
16-2-17 8:19:53.651 | InternalEventBu | AsyncI/syncany | INFO : Event bus 'LocalEventBus': Posting event UpIndexStartSyncExternalEvent (org.syncany.operations.daemon.messages.UpIndexStartSyncExternalEvent@2b580e4a) ...
16-2-17 8:19:53.651 | Indexer         | AsyncI/syncany | FINE : - Looking for deleted files ...
16-2-17 8:19:53.651 | Indexer         | AsyncI/syncany | FINE : - +File /tmp/syncanytest/syncany-170216081829395-47281-client-B/A4,B1
16-2-17 8:19:53.651 | Indexer         | AsyncI/syncany | FINE : - +FileContent: /tmp/syncanytest/syncany-170216081829395-47281-client-B/A4,B1
16-2-17 8:19:53.653 | Indexer         | AsyncI/syncany | FINE : - Chunk new: 2a355a2b2b4bbac69e38a6c1b370782d125b1245
16-2-17 8:19:53.653 | Indexer         | AsyncI/syncany | FINE : - +MultiChunk 1e664298198ec1f6bcbd5cd8cd09596a767fbace
16-2-17 8:19:53.654 | Indexer         | AsyncI/syncany | FINE : - Chunk > MultiChunk: 2a355a2b2b4bbac69e38a6c1b370782d125b1245 > 1e664298198ec1f6bcbd5cd8cd09596a767fbace
16-2-17 8:19:53.654 | Indexer         | AsyncI/syncany | FINE : - Chunk > FileContent: 2a355a2b2b4bbac69e38a6c1b370782d125b1245 > /tmp/syncanytest/syncany-170216081829395-47281-client-B/A4,B1
16-2-17 8:19:53.654 | Indexer         | AsyncI/syncany | FINE : - /File: A4,B1 (checksum 2a355a2b2b4bbac69e38a6c1b370782d125b1245)
16-2-17 8:19:53.655 | Indexer         | AsyncI/syncany | FINE :    * No old file history found, starting new history (path: A4,B1, checksum: 2a355a2b2b4bbac69e38a6c1b370782d125b1245)
16-2-17 8:19:53.655 | FileVersionComp | AsyncI/syncany | INFO :      - [DELETED]: Local file DIFFERS from file version, actual file is NULL, for file A4,B1
16-2-17 8:19:53.655 | Indexer         | AsyncI/syncany | INFO :    * Added file version:    FileVersion [version=1, path=A4,B1, type=FILE, status=NEW, size=51200, lastModified=Thu Feb 16 08:19:53 UTC 2017, linkTarget=null, checksum=2a355a2b2b4bbac69e38a6c1b370782d125b1245, updated=Thu Feb 16 08:19:53 UTC 2017, posixPermissions=rw-rw-r--, dosAttributes=--a-]
16-2-17 8:19:53.656 | Indexer         | AsyncI/syncany | INFO :      based on file version: null
16-2-17 8:19:53.656 | InternalEventBu | AsyncI/syncany | INFO : Event bus 'LocalEventBus': Posting event UpIndexChangesDetectedSyncExternalEvent (org.syncany.operations.daemon.messages.UpIndexChangesDetectedSyncExternalEvent@40c72daf) ...
16-2-17 8:19:53.656 | Indexer         | AsyncI/syncany | FINE : - /MultiChunk 1e664298198ec1f6bcbd5cd8cd09596a767fbace
16-2-17 8:19:53.656 | InternalEventBu | AsyncI/syncany | INFO : Event bus 'LocalEventBus': Posting event UpIndexEndSyncExternalEvent (org.syncany.operations.daemon.messages.UpIndexEndSyncExternalEvent@2982ee8c) ...
16-2-17 8:19:53.656 | Indexer         | AsyncI/syncany | FINE : Processed new database version: DatabaseVersion [header=UnknownMachine/()/T=1487233193651, chunks=1, multiChunks=1, fileContents=1, fileHistories=1]
16-2-17 8:19:53.657 | UpOperation     | Test worker    | INFO : No DIRTY data found in database (no dirty databases); Nothing to do here.
16-2-17 8:19:53.657 | UpOperation     | Test worker    | INFO : Last vector clock was: (A4)
16-2-17 8:19:53.657 | UpOperation     | Test worker    | INFO : Uploading new multichunks ...
16-2-17 8:19:53.657 | UpOperation     | Test worker    | INFO : - Uploading multichunk 1e664298198ec1f6bcbd5cd8cd09596a767fbace from /tmp/syncanytest/syncany-170216081829395-47281-client-B/.syncany/cache/multichunk-1e664298198ec1f6bcbd5cd8cd09596a767fbace to RemoteFile[name=multichunk-1e664298198ec1f6bcbd5cd8cd09596a767fbace] ...
16-2-17 8:19:53.657 | RemoteTransacti | Test worker    | INFO : - Adding file to TX for UPLOAD: /tmp/syncanytest/syncany-170216081829395-47281-client-B/.syncany/cache/multichunk-1e664298198ec1f6bcbd5cd8cd09596a767fbace -> Temp. remote file: RemoteFile[name=temp-KVDqm-multichunk-1e664298198ec1f6bcbd5cd8cd09596a767fbace], final location: RemoteFile[name=multichunk-1e664298198ec1f6bcbd5cd8cd09596a767fbace]
16-2-17 8:19:53.657 | UpOperation     | Test worker    | INFO : Uploading database: DatabaseVersion [header=B/(A4,B1)/T=1487233193657, chunks=1, multiChunks=1, fileContents=1, fileHistories=1]
16-2-17 8:19:53.657 | UpOperation     | Test worker    | INFO : Saving local delta database, version B/(A4,B1)/T=1487233193657 to file /tmp/syncanytest/syncany-170216081829395-47281-client-B/.syncany/cache/database-B-0000000001 ... 
16-2-17 8:19:53.658 | UpOperation     | Test worker    | INFO : - Saving database to /tmp/syncanytest/syncany-170216081829395-47281-client-B/.syncany/cache/database-B-0000000001 ...
16-2-17 8:19:53.658 | UpOperation     | Test worker    | INFO : - Uploading local delta database file ...
16-2-17 8:19:53.658 | UpOperation     | Test worker    | INFO : - Uploading /tmp/syncanytest/syncany-170216081829395-47281-client-B/.syncany/cache/database-B-0000000001 to RemoteFile[name=database-B-0000000001] ...
16-2-17 8:19:53.658 | RemoteTransacti | Test worker    | INFO : - Adding file to TX for UPLOAD: /tmp/syncanytest/syncany-170216081829395-47281-client-B/.syncany/cache/database-B-0000000001 -> Temp. remote file: RemoteFile[name=temp-nqsaZ-database-B-0000000001], final location: RemoteFile[name=database-B-0000000001]
16-2-17 8:19:53.659 | UpOperation     | Test worker    | INFO : Adding shutdown hook (to allow resuming the upload) ...
16-2-17 8:19:53.659 | RemoteTransacti | Test worker    | INFO : Starting TX.commit() ...
16-2-17 8:19:53.660 | RemoteTransacti | Test worker    | INFO : Wrote transaction manifest to temporary file: /tmp/syncanytest/syncany-170216081829395-47281-client-B/.syncany/cache/temp-transaction-695017832046889940.tmp
16-2-17 8:19:53.660 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event UpUploadFileSyncExternalEvent (org.syncany.operations.daemon.messages.UpUploadFileSyncExternalEvent@a15e6ed) ...
16-2-17 8:19:53.660 | RemoteTransacti | Test worker    | INFO : - Uploading remote transaction file RemoteFile[name=transaction-4240a8b6] ...
16-2-17 8:19:53.660 | RemoteTransacti | Test worker    | INFO : - Starting to upload data in commit.
16-2-17 8:19:53.661 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event UpUploadFileInTransactionSyncExternalEvent (org.syncany.operations.daemon.messages.UpUploadFileInTransactionSyncExternalEvent@4c4d2ea6) ...
16-2-17 8:19:53.661 | RemoteTransacti | Test worker    | INFO : - Uploading /tmp/syncanytest/syncany-170216081829395-47281-client-B/.syncany/cache/multichunk-1e664298198ec1f6bcbd5cd8cd09596a767fbace to temp. file RemoteFile[name=temp-KVDqm-multichunk-1e664298198ec1f6bcbd5cd8cd09596a767fbace] ...
16-2-17 8:19:53.661 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event UpUploadFileInTransactionSyncExternalEvent (org.syncany.operations.daemon.messages.UpUploadFileInTransactionSyncExternalEvent@40a92e8d) ...
16-2-17 8:19:53.661 | RemoteTransacti | Test worker    | INFO : - Uploading /tmp/syncanytest/syncany-170216081829395-47281-client-B/.syncany/cache/database-B-0000000001 to temp. file RemoteFile[name=temp-nqsaZ-database-B-0000000001] ...
16-2-17 8:19:53.662 | RemoteTransacti | Test worker    | INFO : - Moving temp. file RemoteFile[name=temp-KVDqm-multichunk-1e664298198ec1f6bcbd5cd8cd09596a767fbace] to final location RemoteFile[name=multichunk-1e664298198ec1f6bcbd5cd8cd09596a767fbace] ...
16-2-17 8:19:53.662 | RemoteTransacti | Test worker    | INFO : - Moving temp. file RemoteFile[name=temp-nqsaZ-database-B-0000000001] to final location RemoteFile[name=database-B-0000000001] ...
16-2-17 8:19:53.662 | RemoteTransacti | Test worker    | INFO : - Deleting remote transaction file RemoteFile[name=transaction-4240a8b6] ...
16-2-17 8:19:53.662 | RemoteTransacti | Test worker    | INFO : END of TX.commmit(): Succesfully committed transaction.
16-2-17 8:19:53.662 | RemoteTransacti | Test worker    | INFO : END of TX.delTemp(): Sucessfully deleted final files.
16-2-17 8:19:53.662 | UpOperation     | Test worker    | INFO : Persisting local SQL database (new database version B/(A4,B1)/T=1487233193657) ...
16-2-17 8:19:53.664 | InternalEventBu | AsyncI/syncany | INFO : Event bus 'LocalEventBus': Posting event UpIndexMidSyncExternalEvent (org.syncany.operations.daemon.messages.UpIndexMidSyncExternalEvent@7fa43652) ...
16-2-17 8:19:53.672 | UpOperation     | Test worker    | INFO : Removing DIRTY database versions from database ...
16-2-17 8:19:53.676 | AsyncIndexer    | AsyncI/syncany | INFO : Stopping indexing. Signal end of stream with empty databaseversion
16-2-17 8:19:53.682 | UpOperation     | Test worker    | INFO : Adding database version to result changes:DatabaseVersion [header=B/(A4,B1)/T=1487233193657, chunks=1, multiChunks=1, fileContents=1, fileHistories=1]
16-2-17 8:19:53.682 | UpOperation     | Test worker    | INFO : Committing local database.
16-2-17 8:19:53.683 | UpOperation     | Test worker    | FINE : Waiting for new database version.
16-2-17 8:19:53.683 | UpOperation     | Test worker    | FINE : Took new database version: DatabaseVersion [header=UnknownMachine/()/T=1487233193676, chunks=0, multiChunks=0, fileContents=0, fileHistories=0]
16-2-17 8:19:53.683 | UpOperation     | Test worker    | INFO : Sync up done.
16-2-17 8:19:53.685 | ActionFileHandl | Test worker    | INFO : Finishing action for RemoteFile[name=action-up-B-1487233193645] ...
16-2-17 8:19:53.685 | ActionFileHandl | Test worker    | INFO : Deleting action file: RemoteFile[name=action-up-B-1487233193645]
16-2-17 8:19:53.685 | AbstractTransfe | Test worker    | INFO : Cleaning own old action files ...
16-2-17 8:19:53.685 | Cache           | Test worker    | INFO : Cache size okay (156 KB), no need to clean (keep size is 500 MB)
16-2-17 8:19:53.685 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event UpEndSyncExternalEvent (org.syncany.operations.daemon.messages.UpEndSyncExternalEvent@5d1efaba) ...
16-2-17 8:19:53.686 | org.syncany.plu | Test worker    | FINE : Validating required fields
16-2-17 8:19:53.686 | TransferManager | Test worker    | INFO : Building LocalTransferManager from config 'syncany-170216081829395-47281-client-C' ...
16-2-17 8:19:53.686 | TransferManager | Test worker    | INFO : - With feature ReadAfterWriteConsistent
16-2-17 8:19:53.686 | TransferManager | Test worker    | INFO : - With feature Retriable
16-2-17 8:19:53.686 | TransferManager | Test worker    | FINE : - Checking if selected features supported ...
16-2-17 8:19:53.686 | TransferManager | Test worker    | FINE : - Checking for duplicate features ...
16-2-17 8:19:53.686 | TransferManager | Test worker    | FINE : - Checking required feature TransactionAware in LocalTransferManager ...
16-2-17 8:19:53.686 | TransferManager | Test worker    | FINE : - Checking required feature Retriable in LocalTransferManager ...
16-2-17 8:19:53.686 | TransferManager | Test worker    | INFO : - SKIPPING unsupported optional feature ReadAfterWriteConsistent
16-2-17 8:19:53.686 | TransferManager | Test worker    | FINE : - Wrapping TransferManager LocalTransferManager in RetriableFeatureTransferManager
16-2-17 8:19:53.686 | org.syncany.plu | Test worker    | FINE : Validating required fields
16-2-17 8:19:53.686 | TransferManager | Test worker    | INFO : Building LocalTransferManager from config 'syncany-170216081829395-47281-client-C' ...
16-2-17 8:19:53.686 | TransferManager | Test worker    | INFO : - With feature ReadAfterWriteConsistent
16-2-17 8:19:53.686 | TransferManager | Test worker    | INFO : - With feature Retriable
16-2-17 8:19:53.686 | TransferManager | Test worker    | INFO : - With feature PathAware
16-2-17 8:19:53.686 | TransferManager | Test worker    | INFO : - With feature TransactionAware
16-2-17 8:19:53.686 | TransferManager | Test worker    | FINE : - Checking if selected features supported ...
16-2-17 8:19:53.686 | TransferManager | Test worker    | FINE : - Checking for duplicate features ...
16-2-17 8:19:53.686 | TransferManager | Test worker    | FINE : - Checking required feature TransactionAware in LocalTransferManager ...
16-2-17 8:19:53.686 | TransferManager | Test worker    | FINE : - Checking required feature Retriable in LocalTransferManager ...
16-2-17 8:19:53.686 | TransferManager | Test worker    | INFO : - SKIPPING unsupported optional feature ReadAfterWriteConsistent
16-2-17 8:19:53.686 | TransferManager | Test worker    | FINE : - Wrapping TransferManager LocalTransferManager in RetriableFeatureTransferManager
16-2-17 8:19:53.686 | TransferManager | Test worker    | INFO : - SKIPPING unsupported optional feature PathAware
16-2-17 8:19:53.686 | TransferManager | Test worker    | FINE : - Wrapping TransferManager RetriableFeatureTransferManager in TransactionAwareFeatureTransferManager
16-2-17 8:19:53.697 | hsqldb.db.HSQLD | Test worker    | INFO : Checkpoint start
16-2-17 8:19:53.697 | hsqldb.db.HSQLD | Test worker    | INFO : checkpointClose start
16-2-17 8:19:53.708 | hsqldb.db.HSQLD | Test worker    | FINE : log close size: 0
16-2-17 8:19:53.710 | hsqldb.db.HSQLD | Test worker    | INFO : checkpointClose end
16-2-17 8:19:53.711 | hsqldb.db.HSQLD | Test worker    | INFO : Checkpoint end - txts: 1
16-2-17 8:19:53.716 | DatabaseConnect | Test worker    | INFO : Found 0 tables.
16-2-17 8:19:53.716 | DatabaseConnect | Test worker    | INFO : Database has no tables. Creating tables from script.create.all.sql
16-2-17 8:19:53.716 | SqlRunner       | Test worker    | INFO : SQL (comment): -- Tables
16-2-17 8:19:53.716 | SqlRunner       | Test worker    | INFO : SQL:  CREATE CACHED TABLE IF NOT EXISTS databaseversion ( id int NOT NULL IDENTITY, status varchar(45) NOT NULL, localtime datetime NOT NULL, client varchar(45) NOT NULL, vectorclock_serialized varchar(1024) NOT NULL, UNIQUE (vectorclock_serialized) ); 
16-2-17 8:19:53.718 | SqlRunner       | Test worker    | INFO : SQL:  CREATE CACHED TABLE IF NOT EXISTS  chunk ( checksum varchar(40) NOT NULL, databaseversion_id int NOT NULL, size bigint NOT NULL, PRIMARY KEY (checksum), FOREIGN KEY (databaseversion_id) REFERENCES databaseversion (id) ON DELETE NO ACTION ON UPDATE NO ACTION ); 
16-2-17 8:19:53.720 | SqlRunner       | Test worker    | INFO : SQL:  CREATE CACHED TABLE IF NOT EXISTS  databaseversion_vectorclock ( databaseversion_id int NOT NULL, client varchar(45) NOT NULL, logicaltime int NOT NULL, PRIMARY KEY (databaseversion_id, client), FOREIGN KEY (databaseversion_id) REFERENCES databaseversion (id) ON DELETE NO ACTION ON UPDATE NO ACTION ); 
16-2-17 8:19:53.722 | SqlRunner       | Test worker    | INFO : SQL:  CREATE CACHED TABLE IF NOT EXISTS  filecontent ( checksum varchar(40) NOT NULL, databaseversion_id int NOT NULL, size bigint NOT NULL, PRIMARY KEY (checksum), FOREIGN KEY (databaseversion_id) REFERENCES databaseversion (id) ON DELETE NO ACTION ON UPDATE NO ACTION ); 
16-2-17 8:19:53.725 | SqlRunner       | Test worker    | INFO : SQL:  CREATE CACHED TABLE IF NOT EXISTS  filecontent_chunk ( filecontent_checksum varchar(40) NOT NULL, chunk_checksum varchar(40) NOT NULL, num int NOT NULL, PRIMARY KEY (filecontent_checksum, chunk_checksum, num), FOREIGN KEY (filecontent_checksum) REFERENCES filecontent (checksum) ON DELETE NO ACTION ON UPDATE NO ACTION, FOREIGN KEY (chunk_checksum) REFERENCES chunk (checksum) ON DELETE NO ACTION ON UPDATE NO ACTION ); 
16-2-17 8:19:53.727 | SqlRunner       | Test worker    | INFO : SQL:  CREATE CACHED TABLE IF NOT EXISTS  filehistory ( id varchar(40) NOT NULL, databaseversion_id int NOT NULL, PRIMARY KEY (id, databaseversion_id), FOREIGN KEY (databaseversion_id) REFERENCES databaseversion (id) ON DELETE NO ACTION ON UPDATE NO ACTION ); 
16-2-17 8:19:53.729 | SqlRunner       | Test worker    | INFO : SQL:  CREATE CACHED TABLE IF NOT EXISTS  fileversion ( filehistory_id varchar(40) NOT NULL, version int NOT NULL, databaseversion_id int NOT NULL, path varchar(1024) NOT NULL, type varchar(45) NOT NULL, status varchar(45) NOT NULL, size bigint NOT NULL, lastmodified datetime NOT NULL, linktarget varchar(1024), filecontent_checksum varchar(40) DEFAULT NULL, updated datetime NOT NULL, posixperms varchar(45) DEFAULT NULL, dosattrs varchar(45) DEFAULT NULL, PRIMARY KEY (filehistory_id, version, databaseversion_id), FOREIGN KEY (filehistory_id, databaseversion_id) REFERENCES filehistory (id, databaseversion_id) ON DELETE NO ACTION ON UPDATE NO ACTION, FOREIGN KEY (filecontent_checksum) REFERENCES filecontent (checksum) ON DELETE NO ACTION ON UPDATE NO ACTION ); 
16-2-17 8:19:53.731 | SqlRunner       | Test worker    | INFO : SQL:  CREATE CACHED TABLE IF NOT EXISTS  multichunk ( id varchar(40) NOT NULL, databaseversion_id int NOT NULL, size bigint NOT NULL, PRIMARY KEY (id), FOREIGN KEY (databaseversion_id) REFERENCES databaseversion (id) ON DELETE NO ACTION ON UPDATE NO ACTION ); 
16-2-17 8:19:53.733 | SqlRunner       | Test worker    | INFO : SQL:  CREATE CACHED TABLE IF NOT EXISTS  multichunk_chunk ( multichunk_id varchar(40) NOT NULL, chunk_checksum varchar(40) NOT NULL, PRIMARY KEY (multichunk_id, chunk_checksum), FOREIGN KEY (multichunk_id) REFERENCES multichunk (id) ON DELETE NO ACTION ON UPDATE NO ACTION, FOREIGN KEY (chunk_checksum) REFERENCES chunk (checksum) ON DELETE NO ACTION ON UPDATE NO ACTION ); 
16-2-17 8:19:53.735 | SqlRunner       | Test worker    | INFO : SQL:  CREATE CACHED TABLE IF NOT EXISTS  multichunk_muddy ( id varchar(40) NOT NULL, machine_name varchar(255) NOT NULL, machine_version int NOT NULL, PRIMARY KEY (id) ); 
16-2-17 8:19:53.737 | SqlRunner       | Test worker    | INFO : SQL:  CREATE CACHED TABLE IF NOT EXISTS  known_databases ( id int NOT NULL IDENTITY, client varchar(45) NOT NULL, filenumber int NOT NULL, UNIQUE (client, filenumber) ); 
16-2-17 8:19:53.739 | SqlRunner       | Test worker    | INFO : SQL:  CREATE CACHED TABLE IF NOT EXISTS  general_settings ( key varchar(255) NOT NULL, value varchar(255) NOT NULL, PRIMARY KEY (key) ); 
16-2-17 8:19:53.741 | SqlRunner       | Test worker    | INFO : SQL (comment): -- Non-primary indices
16-2-17 8:19:53.741 | SqlRunner       | Test worker    | INFO : SQL:   CREATE INDEX idx_databaseversion_status ON databaseversion (status); 
16-2-17 8:19:53.742 | hsqldb.db.HSQLD | Test worker    | INFO : dataFileCache open start
16-2-17 8:19:53.744 | hsqldb.db.HSQLD | Test worker    | INFO : dataFileCache open end
16-2-17 8:19:53.746 | SqlRunner       | Test worker    | INFO : SQL: CREATE INDEX idx_databaseversion_vectorclock_serialized ON databaseversion (vectorclock_serialized); 
16-2-17 8:19:53.748 | SqlRunner       | Test worker    | INFO : SQL: CREATE INDEX idx_fileversion_path ON fileversion (path); 
16-2-17 8:19:53.750 | SqlRunner       | Test worker    | INFO : SQL: CREATE INDEX idx_fileversion_status ON fileversion (status); 
16-2-17 8:19:53.752 | SqlRunner       | Test worker    | INFO : SQL: CREATE INDEX idx_fileversion_filecontent_checksum ON fileversion (filecontent_checksum); 
16-2-17 8:19:53.754 | SqlRunner       | Test worker    | INFO : SQL (comment): -- Views
16-2-17 8:19:53.754 | SqlRunner       | Test worker    | INFO : SQL:    CREATE VIEW databaseversion_master AS SELECT dbv.*, vc.logicaltime as client_version FROM databaseversion dbv JOIN databaseversion_vectorclock vc on dbv.id=vc.databaseversion_id and dbv.client=vc.client WHERE dbv.status='MASTER'; 
16-2-17 8:19:53.757 | SqlRunner       | Test worker    | INFO : SQL:  CREATE VIEW fileversion_master AS SELECT fv0.* FROM fileversion fv0 JOIN databaseversion dbv ON fv0.databaseversion_id=dbv.id AND dbv.status='MASTER'; 
16-2-17 8:19:53.759 | SqlRunner       | Test worker    | INFO : SQL:  CREATE VIEW fileversion_master_maxversion AS SELECT DISTINCT filehistory_id, MAX(version) version FROM fileversion_master GROUP BY filehistory_id; 
16-2-17 8:19:53.761 | SqlRunner       | Test worker    | INFO : SQL:  CREATE VIEW fileversion_master_last AS SELECT fv.* FROM fileversion_master_maxversion fvmax JOIN fileversion_master fv ON fvmax.filehistory_id=fv.filehistory_id AND fvmax.version=fv.version WHERE fv.status<>'DELETED'; 
16-2-17 8:19:53.763 | SqlRunner       | Test worker    | INFO : SQL (comment): -- Full Views
16-2-17 8:19:53.763 | SqlRunner       | Test worker    | INFO : SQL:    create view filehistory_full as select dbv.status as databaseversion_status, dbv.localtime as databaseversion_localtime, dbv.client as databaseversion_client, dbv.vectorclock_serialized as databaseversion_vectorclock_serialized, fh.* from databaseversion dbv join filehistory fh on dbv.id=fh.databaseversion_id; 
16-2-17 8:19:53.766 | SqlRunner       | Test worker    | INFO : SQL:  create view fileversion_full as select fhf.databaseversion_status, fhf.databaseversion_localtime, fhf.databaseversion_client, fhf.databaseversion_vectorclock_serialized, fv.* from filehistory_full fhf join fileversion fv on fhf.id=fv.filehistory_id and fhf.databaseversion_id=fv.databaseversion_id; 
16-2-17 8:19:53.768 | SqlRunner       | Test worker    | INFO : SQL (comment): -- Functions
16-2-17 8:19:53.768 | SqlRunner       | Test worker    | INFO : SQL (new delimiter): end;
16-2-17 8:19:53.768 | SqlRunner       | Test worker    | INFO : SQL:     create function substr_count(haystack varchar(255), needle varchar(255)) returns integer begin atomic declare strCount integer; declare lastIndex integer;  set strCount = 0; set lastIndex = 1;  while lastIndex <> 0 do set lastIndex = locate(needle, haystack, lastIndex);  if lastIndex <> 0 then set strCount = strCount + 1; set lastIndex = lastIndex + length(needle); end if; end while;  return strCount; end; 
16-2-17 8:19:53.770 | UpOperation     | Test worker    | INFO : 
16-2-17 8:19:53.770 | UpOperation     | Test worker    | INFO : Running 'Sync up' at client C ...
16-2-17 8:19:53.770 | UpOperation     | Test worker    | INFO : --------------------------------------------
16-2-17 8:19:53.770 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event UpStartSyncExternalEvent (org.syncany.operations.daemon.messages.UpStartSyncExternalEvent@35aaed5c) ...
16-2-17 8:19:53.771 | DatabaseConnect | Test worker    | INFO : Found 12 tables.
16-2-17 8:19:53.771 | StatusOperation | Test worker    | INFO : 
16-2-17 8:19:53.771 | StatusOperation | Test worker    | INFO : Running 'Status' at client C ...
16-2-17 8:19:53.771 | StatusOperation | Test worker    | INFO : --------------------------------------------
16-2-17 8:19:53.771 | StatusOperation | Test worker    | INFO : Querying current file tree from database ...
16-2-17 8:19:53.771 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event StatusStartSyncExternalEvent (org.syncany.operations.daemon.messages.StatusStartSyncExternalEvent@4a6c14c9) ...
16-2-17 8:19:53.772 | StatusOperation | Test worker    | INFO : Analyzing local folder /tmp/syncanytest/syncany-170216081829395-47281-client-C ...
16-2-17 8:19:53.772 | StatusOperation | Test worker    | FINE : - Ignoring file (syncany app-related): .syncany
16-2-17 8:19:53.772 | StatusOperation | Test worker    | FINE : - New file: C1
16-2-17 8:19:53.772 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event StatusEndSyncExternalEvent (org.syncany.operations.daemon.messages.StatusEndSyncExternalEvent@4927c90c) ...
16-2-17 8:19:53.772 | AbstractTransfe | Test worker    | INFO : Looking for other running remote operations ...
16-2-17 8:19:53.773 | DatabaseConnect | Test worker    | INFO : Found 12 tables.
16-2-17 8:19:53.773 | LsRemoteOperati | Test worker    | INFO : 
16-2-17 8:19:53.773 | LsRemoteOperati | Test worker    | INFO : Running 'Remote Status' at client C ...
16-2-17 8:19:53.773 | LsRemoteOperati | Test worker    | INFO : --------------------------------------------
16-2-17 8:19:53.773 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event LsRemoteStartSyncExternalEvent (org.syncany.operations.daemon.messages.LsRemoteStartSyncExternalEvent@5dd37352) ...
16-2-17 8:19:53.773 | LsRemoteOperati | Test worker    | INFO : Retrieving remote database list.
16-2-17 8:19:53.773 | LsRemoteOperati | Test worker    | INFO : - Remote database database-A-0000000004 is new.
16-2-17 8:19:53.773 | LsRemoteOperati | Test worker    | INFO : - Remote database database-A-0000000002 is new.
16-2-17 8:19:53.773 | LsRemoteOperati | Test worker    | INFO : - Remote database database-A-0000000003 is new.
16-2-17 8:19:53.773 | LsRemoteOperati | Test worker    | INFO : - Remote database database-B-0000000001 is new.
16-2-17 8:19:53.773 | LsRemoteOperati | Test worker    | INFO : - Remote database database-A-0000000001 is new.
16-2-17 8:19:53.773 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event LsRemoteEndSyncExternalEvent (org.syncany.operations.daemon.messages.LsRemoteEndSyncExternalEvent@16698245) ...
16-2-17 8:19:53.773 | UpOperation     | Test worker    | INFO : There are remote changes. Call 'down' first or use --force-upload you must, Luke!
16-2-17 8:19:53.773 | UpOperation     | Test worker    | FINE : Unknown remote databases are: [RemoteFile[name=database-A-0000000004], RemoteFile[name=database-A-0000000002], RemoteFile[name=database-A-0000000003], RemoteFile[name=database-B-0000000001], RemoteFile[name=database-A-0000000001]]
16-2-17 8:19:53.774 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event UpEndSyncExternalEvent (org.syncany.operations.daemon.messages.UpEndSyncExternalEvent@5861ca09) ...
16-2-17 8:19:53.774 | org.syncany.plu | Test worker    | FINE : Validating required fields
16-2-17 8:19:53.774 | TransferManager | Test worker    | INFO : Building LocalTransferManager from config 'syncany-170216081829395-47281-client-C' ...
16-2-17 8:19:53.774 | TransferManager | Test worker    | INFO : - With feature ReadAfterWriteConsistent
16-2-17 8:19:53.774 | TransferManager | Test worker    | INFO : - With feature Retriable
16-2-17 8:19:53.774 | TransferManager | Test worker    | FINE : - Checking if selected features supported ...
16-2-17 8:19:53.774 | TransferManager | Test worker    | FINE : - Checking for duplicate features ...
16-2-17 8:19:53.774 | TransferManager | Test worker    | FINE : - Checking required feature TransactionAware in LocalTransferManager ...
16-2-17 8:19:53.774 | TransferManager | Test worker    | FINE : - Checking required feature Retriable in LocalTransferManager ...
16-2-17 8:19:53.774 | TransferManager | Test worker    | INFO : - SKIPPING unsupported optional feature ReadAfterWriteConsistent
16-2-17 8:19:53.774 | TransferManager | Test worker    | FINE : - Wrapping TransferManager LocalTransferManager in RetriableFeatureTransferManager
16-2-17 8:19:53.774 | org.syncany.plu | Test worker    | FINE : Validating required fields
16-2-17 8:19:53.774 | TransferManager | Test worker    | INFO : Building LocalTransferManager from config 'syncany-170216081829395-47281-client-C' ...
16-2-17 8:19:53.774 | TransferManager | Test worker    | INFO : - With feature ReadAfterWriteConsistent
16-2-17 8:19:53.774 | TransferManager | Test worker    | INFO : - With feature Retriable
16-2-17 8:19:53.775 | TransferManager | Test worker    | INFO : - With feature PathAware
16-2-17 8:19:53.775 | TransferManager | Test worker    | INFO : - With feature TransactionAware
16-2-17 8:19:53.775 | TransferManager | Test worker    | FINE : - Checking if selected features supported ...
16-2-17 8:19:53.775 | TransferManager | Test worker    | FINE : - Checking for duplicate features ...
16-2-17 8:19:53.775 | TransferManager | Test worker    | FINE : - Checking required feature TransactionAware in LocalTransferManager ...
16-2-17 8:19:53.775 | TransferManager | Test worker    | FINE : - Checking required feature Retriable in LocalTransferManager ...
16-2-17 8:19:53.775 | TransferManager | Test worker    | INFO : - SKIPPING unsupported optional feature ReadAfterWriteConsistent
16-2-17 8:19:53.775 | TransferManager | Test worker    | FINE : - Wrapping TransferManager LocalTransferManager in RetriableFeatureTransferManager
16-2-17 8:19:53.775 | TransferManager | Test worker    | INFO : - SKIPPING unsupported optional feature PathAware
16-2-17 8:19:53.775 | TransferManager | Test worker    | FINE : - Wrapping TransferManager RetriableFeatureTransferManager in TransactionAwareFeatureTransferManager
16-2-17 8:19:53.776 | DatabaseConnect | Test worker    | INFO : Found 12 tables.
16-2-17 8:19:53.776 | UpOperation     | Test worker    | INFO : 
16-2-17 8:19:53.776 | UpOperation     | Test worker    | INFO : Running 'Sync up' at client C ...
16-2-17 8:19:53.776 | UpOperation     | Test worker    | INFO : --------------------------------------------
16-2-17 8:19:53.776 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event UpStartSyncExternalEvent (org.syncany.operations.daemon.messages.UpStartSyncExternalEvent@7532e78f) ...
16-2-17 8:19:53.776 | DatabaseConnect | Test worker    | INFO : Found 12 tables.
16-2-17 8:19:53.776 | StatusOperation | Test worker    | INFO : 
16-2-17 8:19:53.776 | StatusOperation | Test worker    | INFO : Running 'Status' at client C ...
16-2-17 8:19:53.776 | StatusOperation | Test worker    | INFO : --------------------------------------------
16-2-17 8:19:53.776 | StatusOperation | Test worker    | INFO : Querying current file tree from database ...
16-2-17 8:19:53.776 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event StatusStartSyncExternalEvent (org.syncany.operations.daemon.messages.StatusStartSyncExternalEvent@79f49ebf) ...
16-2-17 8:19:53.777 | StatusOperation | Test worker    | INFO : Analyzing local folder /tmp/syncanytest/syncany-170216081829395-47281-client-C ...
16-2-17 8:19:53.777 | StatusOperation | Test worker    | FINE : - Ignoring file (syncany app-related): .syncany
16-2-17 8:19:53.777 | StatusOperation | Test worker    | FINE : - New file: C1
16-2-17 8:19:53.777 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event StatusEndSyncExternalEvent (org.syncany.operations.daemon.messages.StatusEndSyncExternalEvent@61e7a548) ...
16-2-17 8:19:53.777 | AbstractTransfe | Test worker    | INFO : Looking for other running remote operations ...
16-2-17 8:19:53.778 | DatabaseConnect | Test worker    | INFO : Found 12 tables.
16-2-17 8:19:53.778 | LsRemoteOperati | Test worker    | INFO : 
16-2-17 8:19:53.778 | LsRemoteOperati | Test worker    | INFO : Running 'Remote Status' at client C ...
16-2-17 8:19:53.778 | LsRemoteOperati | Test worker    | INFO : --------------------------------------------
16-2-17 8:19:53.778 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event LsRemoteStartSyncExternalEvent (org.syncany.operations.daemon.messages.LsRemoteStartSyncExternalEvent@4ef6b539) ...
16-2-17 8:19:53.778 | LsRemoteOperati | Test worker    | INFO : Retrieving remote database list.
16-2-17 8:19:53.778 | LsRemoteOperati | Test worker    | INFO : - Remote database database-A-0000000004 is new.
16-2-17 8:19:53.778 | LsRemoteOperati | Test worker    | INFO : - Remote database database-A-0000000002 is new.
16-2-17 8:19:53.778 | LsRemoteOperati | Test worker    | INFO : - Remote database database-A-0000000003 is new.
16-2-17 8:19:53.778 | LsRemoteOperati | Test worker    | INFO : - Remote database database-B-0000000001 is new.
16-2-17 8:19:53.778 | LsRemoteOperati | Test worker    | INFO : - Remote database database-A-0000000001 is new.
16-2-17 8:19:53.778 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event LsRemoteEndSyncExternalEvent (org.syncany.operations.daemon.messages.LsRemoteEndSyncExternalEvent@3666e4ff) ...
16-2-17 8:19:53.778 | UpOperation     | Test worker    | INFO : There are remote changes. Call 'down' first or use --force-upload you must, Luke!
16-2-17 8:19:53.778 | UpOperation     | Test worker    | FINE : Unknown remote databases are: [RemoteFile[name=database-A-0000000004], RemoteFile[name=database-A-0000000002], RemoteFile[name=database-A-0000000003], RemoteFile[name=database-B-0000000001], RemoteFile[name=database-A-0000000001]]
16-2-17 8:19:53.778 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event UpEndSyncExternalEvent (org.syncany.operations.daemon.messages.UpEndSyncExternalEvent@4fe46098) ...
16-2-17 8:19:53.778 | org.syncany.plu | Test worker    | FINE : Validating required fields
16-2-17 8:19:53.778 | TransferManager | Test worker    | INFO : Building LocalTransferManager from config 'syncany-170216081829395-47281-client-C' ...
16-2-17 8:19:53.778 | TransferManager | Test worker    | INFO : - With feature ReadAfterWriteConsistent
16-2-17 8:19:53.778 | TransferManager | Test worker    | INFO : - With feature Retriable
16-2-17 8:19:53.778 | TransferManager | Test worker    | FINE : - Checking if selected features supported ...
16-2-17 8:19:53.778 | TransferManager | Test worker    | FINE : - Checking for duplicate features ...
16-2-17 8:19:53.778 | TransferManager | Test worker    | FINE : - Checking required feature TransactionAware in LocalTransferManager ...
16-2-17 8:19:53.778 | TransferManager | Test worker    | FINE : - Checking required feature Retriable in LocalTransferManager ...
16-2-17 8:19:53.778 | TransferManager | Test worker    | INFO : - SKIPPING unsupported optional feature ReadAfterWriteConsistent
16-2-17 8:19:53.778 | TransferManager | Test worker    | FINE : - Wrapping TransferManager LocalTransferManager in RetriableFeatureTransferManager
16-2-17 8:19:53.778 | org.syncany.plu | Test worker    | FINE : Validating required fields
16-2-17 8:19:53.779 | TransferManager | Test worker    | INFO : Building LocalTransferManager from config 'syncany-170216081829395-47281-client-C' ...
16-2-17 8:19:53.779 | TransferManager | Test worker    | INFO : - With feature ReadAfterWriteConsistent
16-2-17 8:19:53.779 | TransferManager | Test worker    | INFO : - With feature Retriable
16-2-17 8:19:53.779 | TransferManager | Test worker    | INFO : - With feature PathAware
16-2-17 8:19:53.779 | TransferManager | Test worker    | INFO : - With feature TransactionAware
16-2-17 8:19:53.779 | TransferManager | Test worker    | FINE : - Checking if selected features supported ...
16-2-17 8:19:53.779 | TransferManager | Test worker    | FINE : - Checking for duplicate features ...
16-2-17 8:19:53.779 | TransferManager | Test worker    | FINE : - Checking required feature TransactionAware in LocalTransferManager ...
16-2-17 8:19:53.779 | TransferManager | Test worker    | FINE : - Checking required feature Retriable in LocalTransferManager ...
16-2-17 8:19:53.779 | TransferManager | Test worker    | INFO : - SKIPPING unsupported optional feature ReadAfterWriteConsistent
16-2-17 8:19:53.779 | TransferManager | Test worker    | FINE : - Wrapping TransferManager LocalTransferManager in RetriableFeatureTransferManager
16-2-17 8:19:53.779 | TransferManager | Test worker    | INFO : - SKIPPING unsupported optional feature PathAware
16-2-17 8:19:53.779 | TransferManager | Test worker    | FINE : - Wrapping TransferManager RetriableFeatureTransferManager in TransactionAwareFeatureTransferManager
16-2-17 8:19:53.780 | DatabaseConnect | Test worker    | INFO : Found 12 tables.
16-2-17 8:19:53.780 | UpOperation     | Test worker    | INFO : 
16-2-17 8:19:53.780 | UpOperation     | Test worker    | INFO : Running 'Sync up' at client C ...
16-2-17 8:19:53.780 | UpOperation     | Test worker    | INFO : --------------------------------------------
16-2-17 8:19:53.780 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event UpStartSyncExternalEvent (org.syncany.operations.daemon.messages.UpStartSyncExternalEvent@60eb0395) ...
16-2-17 8:19:53.780 | DatabaseConnect | Test worker    | INFO : Found 12 tables.
16-2-17 8:19:53.780 | StatusOperation | Test worker    | INFO : 
16-2-17 8:19:53.780 | StatusOperation | Test worker    | INFO : Running 'Status' at client C ...
16-2-17 8:19:53.780 | StatusOperation | Test worker    | INFO : --------------------------------------------
16-2-17 8:19:53.780 | StatusOperation | Test worker    | INFO : Querying current file tree from database ...
16-2-17 8:19:53.780 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event StatusStartSyncExternalEvent (org.syncany.operations.daemon.messages.StatusStartSyncExternalEvent@da70e76) ...
16-2-17 8:19:53.781 | StatusOperation | Test worker    | INFO : Analyzing local folder /tmp/syncanytest/syncany-170216081829395-47281-client-C ...
16-2-17 8:19:53.781 | StatusOperation | Test worker    | FINE : - Ignoring file (syncany app-related): .syncany
16-2-17 8:19:53.781 | StatusOperation | Test worker    | FINE : - New file: C1
16-2-17 8:19:53.781 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event StatusEndSyncExternalEvent (org.syncany.operations.daemon.messages.StatusEndSyncExternalEvent@142b7b7d) ...
16-2-17 8:19:53.781 | AbstractTransfe | Test worker    | INFO : Looking for other running remote operations ...
16-2-17 8:19:53.782 | DatabaseConnect | Test worker    | INFO : Found 12 tables.
16-2-17 8:19:53.782 | LsRemoteOperati | Test worker    | INFO : 
16-2-17 8:19:53.782 | LsRemoteOperati | Test worker    | INFO : Running 'Remote Status' at client C ...
16-2-17 8:19:53.782 | LsRemoteOperati | Test worker    | INFO : --------------------------------------------
16-2-17 8:19:53.782 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event LsRemoteStartSyncExternalEvent (org.syncany.operations.daemon.messages.LsRemoteStartSyncExternalEvent@4d87b70c) ...
16-2-17 8:19:53.782 | LsRemoteOperati | Test worker    | INFO : Retrieving remote database list.
16-2-17 8:19:53.782 | LsRemoteOperati | Test worker    | INFO : - Remote database database-A-0000000004 is new.
16-2-17 8:19:53.782 | LsRemoteOperati | Test worker    | INFO : - Remote database database-A-0000000002 is new.
16-2-17 8:19:53.782 | LsRemoteOperati | Test worker    | INFO : - Remote database database-A-0000000003 is new.
16-2-17 8:19:53.782 | LsRemoteOperati | Test worker    | INFO : - Remote database database-B-0000000001 is new.
16-2-17 8:19:53.782 | LsRemoteOperati | Test worker    | INFO : - Remote database database-A-0000000001 is new.
16-2-17 8:19:53.782 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event LsRemoteEndSyncExternalEvent (org.syncany.operations.daemon.messages.LsRemoteEndSyncExternalEvent@5ddaedfc) ...
16-2-17 8:19:53.782 | UpOperation     | Test worker    | INFO : There are remote changes. Call 'down' first or use --force-upload you must, Luke!
16-2-17 8:19:53.782 | UpOperation     | Test worker    | FINE : Unknown remote databases are: [RemoteFile[name=database-A-0000000004], RemoteFile[name=database-A-0000000002], RemoteFile[name=database-A-0000000003], RemoteFile[name=database-B-0000000001], RemoteFile[name=database-A-0000000001]]
16-2-17 8:19:53.782 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event UpEndSyncExternalEvent (org.syncany.operations.daemon.messages.UpEndSyncExternalEvent@4a3c259e) ...
16-2-17 8:19:53.782 | org.syncany.plu | Test worker    | FINE : Validating required fields
16-2-17 8:19:53.783 | TransferManager | Test worker    | INFO : Building LocalTransferManager from config 'syncany-170216081829395-47281-client-A' ...
16-2-17 8:19:53.783 | TransferManager | Test worker    | INFO : - With feature ReadAfterWriteConsistent
16-2-17 8:19:53.783 | TransferManager | Test worker    | INFO : - With feature Retriable
16-2-17 8:19:53.783 | TransferManager | Test worker    | FINE : - Checking if selected features supported ...
16-2-17 8:19:53.783 | TransferManager | Test worker    | FINE : - Checking for duplicate features ...
16-2-17 8:19:53.783 | TransferManager | Test worker    | FINE : - Checking required feature TransactionAware in LocalTransferManager ...
16-2-17 8:19:53.783 | TransferManager | Test worker    | FINE : - Checking required feature Retriable in LocalTransferManager ...
16-2-17 8:19:53.783 | TransferManager | Test worker    | INFO : - SKIPPING unsupported optional feature ReadAfterWriteConsistent
16-2-17 8:19:53.783 | TransferManager | Test worker    | FINE : - Wrapping TransferManager LocalTransferManager in RetriableFeatureTransferManager
16-2-17 8:19:53.783 | org.syncany.plu | Test worker    | FINE : Validating required fields
16-2-17 8:19:53.783 | TransferManager | Test worker    | INFO : Building LocalTransferManager from config 'syncany-170216081829395-47281-client-A' ...
16-2-17 8:19:53.783 | TransferManager | Test worker    | INFO : - With feature ReadAfterWriteConsistent
16-2-17 8:19:53.783 | TransferManager | Test worker    | INFO : - With feature Retriable
16-2-17 8:19:53.783 | TransferManager | Test worker    | INFO : - With feature PathAware
16-2-17 8:19:53.783 | TransferManager | Test worker    | INFO : - With feature TransactionAware
16-2-17 8:19:53.783 | TransferManager | Test worker    | FINE : - Checking if selected features supported ...
16-2-17 8:19:53.783 | TransferManager | Test worker    | FINE : - Checking for duplicate features ...
16-2-17 8:19:53.783 | TransferManager | Test worker    | FINE : - Checking required feature TransactionAware in LocalTransferManager ...
16-2-17 8:19:53.783 | TransferManager | Test worker    | FINE : - Checking required feature Retriable in LocalTransferManager ...
16-2-17 8:19:53.783 | TransferManager | Test worker    | INFO : - SKIPPING unsupported optional feature ReadAfterWriteConsistent
16-2-17 8:19:53.783 | TransferManager | Test worker    | FINE : - Wrapping TransferManager LocalTransferManager in RetriableFeatureTransferManager
16-2-17 8:19:53.783 | TransferManager | Test worker    | INFO : - SKIPPING unsupported optional feature PathAware
16-2-17 8:19:53.783 | TransferManager | Test worker    | FINE : - Wrapping TransferManager RetriableFeatureTransferManager in TransactionAwareFeatureTransferManager
16-2-17 8:19:53.784 | DatabaseConnect | Test worker    | INFO : Found 12 tables.
16-2-17 8:19:53.784 | DownOperation   | Test worker    | INFO : 
16-2-17 8:19:53.784 | DownOperation   | Test worker    | INFO : Running 'Sync down' at client A ...
16-2-17 8:19:53.784 | DownOperation   | Test worker    | INFO : --------------------------------------------
16-2-17 8:19:53.784 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event DownStartSyncExternalEvent (org.syncany.operations.daemon.messages.DownStartSyncExternalEvent@44a38fe) ...
16-2-17 8:19:53.784 | AbstractTransfe | Test worker    | INFO : Looking for other running remote operations ...
16-2-17 8:19:53.785 | DatabaseConnect | Test worker    | INFO : Found 12 tables.
16-2-17 8:19:53.785 | LsRemoteOperati | Test worker    | INFO : 
16-2-17 8:19:53.785 | LsRemoteOperati | Test worker    | INFO : Running 'Remote Status' at client A ...
16-2-17 8:19:53.785 | LsRemoteOperati | Test worker    | INFO : --------------------------------------------
16-2-17 8:19:53.785 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event LsRemoteStartSyncExternalEvent (org.syncany.operations.daemon.messages.LsRemoteStartSyncExternalEvent@25ce5403) ...
16-2-17 8:19:53.785 | LsRemoteOperati | Test worker    | INFO : Retrieving remote database list.
16-2-17 8:19:53.785 | LsRemoteOperati | Test worker    | INFO : - Remote database database-A-0000000004 is already known (in local database). Ignoring.
16-2-17 8:19:53.785 | LsRemoteOperati | Test worker    | INFO : - Remote database database-A-0000000002 is already known (in local database). Ignoring.
16-2-17 8:19:53.785 | LsRemoteOperati | Test worker    | INFO : - Remote database database-A-0000000003 is already known (in local database). Ignoring.
16-2-17 8:19:53.785 | LsRemoteOperati | Test worker    | INFO : - Remote database database-B-0000000001 is new.
16-2-17 8:19:53.785 | LsRemoteOperati | Test worker    | INFO : - Remote database database-A-0000000001 is already known (in local database). Ignoring.
16-2-17 8:19:53.785 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event LsRemoteEndSyncExternalEvent (org.syncany.operations.daemon.messages.LsRemoteEndSyncExternalEvent@54e1127d) ...
16-2-17 8:19:53.785 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event DownChangesDetectedSyncExternalEvent (org.syncany.operations.daemon.messages.DownChangesDetectedSyncExternalEvent@76b2d9d7) ...
16-2-17 8:19:53.785 | ActionFileHandl | Test worker    | INFO : Starting action for RemoteFile[name=action-down-A-1487233193784] ...
16-2-17 8:19:53.785 | ActionFileHandl | Test worker    | INFO : Uploading action file: RemoteFile[name=action-down-A-1487233193784]
16-2-17 8:19:53.786 | ActionFileHandl | Test worker    | INFO : Scheduling action renewal task for every 2 minutes, for RemoteFile[name=action-down-A-1487233193784] ...
16-2-17 8:19:53.786 | DownOperation   | Test worker    | INFO : Downloading unknown databases.
16-2-17 8:19:53.786 | DownOperation   | Test worker    | INFO : - Downloading database-B-0000000001 to local cache at /tmp/syncanytest/syncany-170216081829395-47281-client-A/.syncany/cache/database-B-0000000001
16-2-17 8:19:53.786 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event DownDownloadFileSyncExternalEvent (org.syncany.operations.daemon.messages.DownDownloadFileSyncExternalEvent@36ecd1fb) ...
16-2-17 8:19:53.787 | DownOperation   | Test worker    | INFO : Loading database headers, creating branches ...
16-2-17 8:19:53.787 | DatabaseXmlSeri | Test worker    | INFO : - Loading database (HEADER_ONLY) from file /tmp/syncanytest/syncany-170216081829395-47281-client-A/.syncany/cache/database-B-0000000001 ...
16-2-17 8:19:53.788 | DatabaseXmlPars | Test worker    | INFO :    + Added database version B/(A4,B1)/T=1487233193657
16-2-17 8:19:53.788 | LocalTransferMa | Test worker    | INFO : Cannot create instance of CleanupRemoteFile for file /tmp/syncanytest/syncany-170216081829395-47281-repo/databases/database-B-0000000001; maybe invalid file name pattern. Ignoring file.
16-2-17 8:19:53.788 | LocalTransferMa | Test worker    | INFO : Cannot create instance of CleanupRemoteFile for file /tmp/syncanytest/syncany-170216081829395-47281-repo/databases/database-A-0000000004; maybe invalid file name pattern. Ignoring file.
16-2-17 8:19:53.788 | LocalTransferMa | Test worker    | INFO : Cannot create instance of CleanupRemoteFile for file /tmp/syncanytest/syncany-170216081829395-47281-repo/databases/database-A-0000000002; maybe invalid file name pattern. Ignoring file.
16-2-17 8:19:53.789 | LocalTransferMa | Test worker    | INFO : Cannot create instance of CleanupRemoteFile for file /tmp/syncanytest/syncany-170216081829395-47281-repo/databases/database-A-0000000001; maybe invalid file name pattern. Ignoring file.
16-2-17 8:19:53.789 | LocalTransferMa | Test worker    | INFO : Cannot create instance of CleanupRemoteFile for file /tmp/syncanytest/syncany-170216081829395-47281-repo/databases/database-A-0000000003; maybe invalid file name pattern. Ignoring file.
16-2-17 8:19:53.789 | ApplicationSqlD | Test worker    | INFO : SQL (general_settings): Read cleanupNumber  = (not set)
16-2-17 8:19:53.789 | DownOperation   | Test worker    | INFO : Populated unknown branches: {A=[A/(A1)/T=1487233193391, A/(A2)/T=1487233193425, A/(A3)/T=1487233193455, A/(A4)/T=1487233193490], B=[B/(A4,B1)/T=1487233193657]}
16-2-17 8:19:53.789 | DownOperation   | Test worker    | INFO : Determine winner using database reconciliator ...
16-2-17 8:19:53.789 | DatabaseReconci | Test worker    | INFO : Adding database version to winning branch: A/(A1)/T=1487233193391
16-2-17 8:19:53.789 | DatabaseReconci | Test worker    | INFO : Adding database version to winning branch: A/(A2)/T=1487233193425
16-2-17 8:19:53.789 | DatabaseReconci | Test worker    | INFO : Adding database version to winning branch: A/(A3)/T=1487233193455
16-2-17 8:19:53.789 | DatabaseReconci | Test worker    | INFO : Adding database version to winning branch: A/(A4)/T=1487233193490
16-2-17 8:19:53.789 | DatabaseReconci | Test worker    | INFO : Adding database version to winning branch: B/(A4,B1)/T=1487233193657
16-2-17 8:19:53.789 | DatabaseReconci | Test worker    | INFO : - Winner is B with branch: 
16-2-17 8:19:53.789 | DatabaseReconci | Test worker    | INFO :   + A/(A1)/T=1487233193391
16-2-17 8:19:53.789 | DatabaseReconci | Test worker    | INFO :   + A/(A2)/T=1487233193425
16-2-17 8:19:53.789 | DatabaseReconci | Test worker    | INFO :   + A/(A3)/T=1487233193455
16-2-17 8:19:53.790 | DatabaseReconci | Test worker    | INFO :   + A/(A4)/T=1487233193490
16-2-17 8:19:53.790 | DatabaseReconci | Test worker    | INFO :   + B/(A4,B1)/T=1487233193657
16-2-17 8:19:53.790 | DownOperation   | Test worker    | INFO : - Database versions to REMOVE locally: []
16-2-17 8:19:53.790 | DownOperation   | Test worker    | INFO :   + Nothing to purge locally. No conflicts. Only updates. Nice!
16-2-17 8:19:53.790 | DatabaseReconci | Test worker    | INFO : Finding winnersApplyBranch.
16-2-17 8:19:53.790 | DatabaseReconci | Test worker    | INFO : Losers Branch: [A/(A1)/T=1487233193391, A/(A2)/T=1487233193425, A/(A3)/T=1487233193455, A/(A4)/T=1487233193490]
16-2-17 8:19:53.790 | DatabaseReconci | Test worker    | INFO : Winners Branch: [A/(A1)/T=1487233193391, A/(A2)/T=1487233193425, A/(A3)/T=1487233193455, A/(A4)/T=1487233193490, B/(A4,B1)/T=1487233193657]
16-2-17 8:19:53.790 | DownOperation   | Test worker    | INFO : - Cleanup occurred: false
16-2-17 8:19:53.790 | DownOperation   | Test worker    | INFO : - Database versions to APPLY locally: [B/(A4,B1)/T=1487233193657]
16-2-17 8:19:53.790 | DownOperation   | Test worker    | INFO : Loading winners database (DEFAULT) ...
16-2-17 8:19:53.790 | DatabaseXmlSeri | Test worker    | INFO : - Loading database (FULL) from file /tmp/syncanytest/syncany-170216081829395-47281-client-A/.syncany/cache/database-B-0000000001 ...
16-2-17 8:19:53.792 | DatabaseXmlPars | Test worker    | INFO :    + Added database version B/(A4,B1)/T=1487233193657
16-2-17 8:19:53.792 | DownOperation   | Test worker    | INFO : Determine file system actions ...
16-2-17 8:19:53.792 | DatabaseConnect | Test worker    | INFO : Found 12 tables.
16-2-17 8:19:53.793 | FileSystemActio | Test worker    | INFO : - Loading current file tree...
16-2-17 8:19:53.793 | FileSystemActio | Test worker    | INFO : - Determine filesystem actions ...
16-2-17 8:19:53.793 | FileSystemActio | Test worker    | INFO :   + Comparing local version: null
16-2-17 8:19:53.793 | FileSystemActio | Test worker    | INFO :     with winning version   : FileVersion [version=1, path=A4,B1, type=FILE, status=NEW, size=51200, lastModified=Thu Feb 16 08:19:53 UTC 2017, linkTarget=null, checksum=2a355a2b2b4bbac69e38a6c1b370782d125b1245, updated=Thu Feb 16 08:19:53 UTC 2017, posixPermissions=rw-rw-r--, dosAttributes=--a-]
16-2-17 8:19:53.793 | FileVersionComp | Test worker    | INFO :      - [DELETED]: Local file DIFFERS from file version, expected EXISTS = true, but actual EXISTS = false, for file A4,B1
16-2-17 8:19:53.793 | FileSystemActio | Test worker    | INFO :      -> (2) Deleted: Local file does NOT exist, but it should, winning version not known: FileVersion [version=1, path=A4,B1, type=FILE, status=NEW, size=51200, lastModified=Thu Feb 16 08:19:53 UTC 2017, linkTarget=null, checksum=2a355a2b2b4bbac69e38a6c1b370782d125b1245, updated=Thu Feb 16 08:19:53 UTC 2017, posixPermissions=rw-rw-r--, dosAttributes=--a-] AND /tmp/syncanytest/syncany-170216081829395-47281-client-A/A4,B1
16-2-17 8:19:53.793 | FileSystemActio | Test worker    | INFO :      -> NewFileSystemAction [file1=null, file2=FileVersion [version=1, path=A4,B1, type=FILE, status=NEW, size=51200, lastModified=Thu Feb 16 08:19:53 UTC 2017, linkTarget=null, checksum=2a355a2b2b4bbac69e38a6c1b370782d125b1245, updated=Thu Feb 16 08:19:53 UTC 2017, posixPermissions=rw-rw-r--, dosAttributes=--a-]]
16-2-17 8:19:53.794 | DownOperation   | Test worker    | INFO :   + Adding multichunk 1e664298198ec1f6bcbd5cd8cd09596a767fbace to download list ...
16-2-17 8:19:53.794 | Downloader      | Test worker    | INFO : Downloading and extracting multichunks ...
16-2-17 8:19:53.794 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event DownDownloadFileSyncExternalEvent (org.syncany.operations.daemon.messages.DownDownloadFileSyncExternalEvent@8cc2c28) ...
16-2-17 8:19:53.794 | Downloader      | Test worker    | INFO :   + Downloading multichunk 1e664298198ec1f6bcbd5cd8cd09596a767fbace ...
16-2-17 8:19:53.795 | Downloader      | Test worker    | INFO :   + Decrypting multichunk 1e664298198ec1f6bcbd5cd8cd09596a767fbace ...
16-2-17 8:19:53.795 | Downloader      | Test worker    | FINE :   + Locally deleting multichunk 1e664298198ec1f6bcbd5cd8cd09596a767fbace ...
16-2-17 8:19:53.795 | FileSystemActio | Test worker    | INFO :    Sorted actions:
16-2-17 8:19:53.796 | FileSystemActio | Test worker    | INFO :    + NewFileSystemAction [file1=null, file2=FileVersion [version=1, path=A4,B1, type=FILE, status=NEW, size=51200, lastModified=Thu Feb 16 08:19:53 UTC 2017, linkTarget=null, checksum=2a355a2b2b4bbac69e38a6c1b370782d125b1245, updated=Thu Feb 16 08:19:53 UTC 2017, posixPermissions=rw-rw-r--, dosAttributes=--a-]]
16-2-17 8:19:53.796 | DownOperation   | Test worker    | FINE : - Applying file system actions (sorted!) ...
16-2-17 8:19:53.796 | DownOperation   | Test worker    | FINE :    +  NewFileSystemAction [file1=null, file2=FileVersion [version=1, path=A4,B1, type=FILE, status=NEW, size=51200, lastModified=Thu Feb 16 08:19:53 UTC 2017, linkTarget=null, checksum=2a355a2b2b4bbac69e38a6c1b370782d125b1245, updated=Thu Feb 16 08:19:53 UTC 2017, posixPermissions=rw-rw-r--, dosAttributes=--a-]]
16-2-17 8:19:53.796 | Assembler       | Test worker    | INFO :      - Creating file A4,B1 to /tmp/syncanytest/syncany-170216081829395-47281-client-A/.syncany/cache/temp-reconstructedFileVersion-4095669157687759598.tmp ...
16-2-17 8:19:53.797 | FileSystemActio | Test worker    | INFO :      - Setting POSIX permissions: rw-rw-r-- ...
16-2-17 8:19:53.797 | DownOperation   | Test worker    | INFO : - Adding database versions to SQL database ...
16-2-17 8:19:53.797 | DownOperation   | Test worker    | INFO :   + Applying database version (A4,B1)
16-2-17 8:19:53.797 | DownOperation   | Test worker    | FINE :   + Contents: DatabaseVersion [header=B/(A4,B1)/T=1487233193657, chunks=1, multiChunks=1, fileContents=1, fileHistories=1]
16-2-17 8:19:53.807 | ActionFileHandl | Test worker    | INFO : Finishing action for RemoteFile[name=action-down-A-1487233193784] ...
16-2-17 8:19:53.807 | ActionFileHandl | Test worker    | INFO : Deleting action file: RemoteFile[name=action-down-A-1487233193784]
16-2-17 8:19:53.807 | AbstractTransfe | Test worker    | INFO : Cleaning own old action files ...
16-2-17 8:19:53.808 | Cache           | Test worker    | INFO : Cache size okay (206 KB), no need to clean (keep size is 500 MB)
16-2-17 8:19:53.808 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event DownEndSyncExternalEvent (org.syncany.operations.daemon.messages.DownEndSyncExternalEvent@3a7b1447) ...
16-2-17 8:19:53.808 | DownOperation   | Test worker    | INFO : Sync down done.
16-2-17 8:19:53.813 | FileVersionComp | Test worker    | INFO :      - [CHANGED_PATH]: Local file DIFFERS from file version, expected PATH = tmp/syncanytest/syncany-170216081829395-47281-client-A/A2, but actual PATH = tmp/syncanytest/syncany-170216081829395-47281-client-B/A2, for file tmp/syncanytest/syncany-170216081829395-47281-client-B/A2
16-2-17 8:19:53.817 | FileVersionComp | Test worker    | INFO :      - [CHANGED_PATH]: Local file DIFFERS from file version, expected PATH = tmp/syncanytest/syncany-170216081829395-47281-client-A/A4,B1, but actual PATH = tmp/syncanytest/syncany-170216081829395-47281-client-B/A4,B1, for file tmp/syncanytest/syncany-170216081829395-47281-client-B/A4,B1
16-2-17 8:19:53.818 | org.syncany.plu | Test worker    | FINE : Validating required fields
16-2-17 8:19:53.818 | TransferManager | Test worker    | INFO : Building LocalTransferManager from config 'syncany-170216081829395-47281-client-B' ...
16-2-17 8:19:53.818 | TransferManager | Test worker    | INFO : - With feature ReadAfterWriteConsistent
16-2-17 8:19:53.818 | TransferManager | Test worker    | INFO : - With feature Retriable
16-2-17 8:19:53.818 | TransferManager | Test worker    | FINE : - Checking if selected features supported ...
16-2-17 8:19:53.818 | TransferManager | Test worker    | FINE : - Checking for duplicate features ...
16-2-17 8:19:53.818 | TransferManager | Test worker    | FINE : - Checking required feature TransactionAware in LocalTransferManager ...
16-2-17 8:19:53.818 | TransferManager | Test worker    | FINE : - Checking required feature Retriable in LocalTransferManager ...
16-2-17 8:19:53.818 | TransferManager | Test worker    | INFO : - SKIPPING unsupported optional feature ReadAfterWriteConsistent
16-2-17 8:19:53.818 | TransferManager | Test worker    | FINE : - Wrapping TransferManager LocalTransferManager in RetriableFeatureTransferManager
16-2-17 8:19:53.819 | org.syncany.plu | Test worker    | FINE : Validating required fields
16-2-17 8:19:53.819 | TransferManager | Test worker    | INFO : Building LocalTransferManager from config 'syncany-170216081829395-47281-client-B' ...
16-2-17 8:19:53.819 | TransferManager | Test worker    | INFO : - With feature ReadAfterWriteConsistent
16-2-17 8:19:53.819 | TransferManager | Test worker    | INFO : - With feature Retriable
16-2-17 8:19:53.819 | TransferManager | Test worker    | INFO : - With feature PathAware
16-2-17 8:19:53.819 | TransferManager | Test worker    | INFO : - With feature TransactionAware
16-2-17 8:19:53.819 | TransferManager | Test worker    | FINE : - Checking if selected features supported ...
16-2-17 8:19:53.819 | TransferManager | Test worker    | FINE : - Checking for duplicate features ...
16-2-17 8:19:53.819 | TransferManager | Test worker    | FINE : - Checking required feature TransactionAware in LocalTransferManager ...
16-2-17 8:19:53.819 | TransferManager | Test worker    | FINE : - Checking required feature Retriable in LocalTransferManager ...
16-2-17 8:19:53.819 | TransferManager | Test worker    | INFO : - SKIPPING unsupported optional feature ReadAfterWriteConsistent
16-2-17 8:19:53.819 | TransferManager | Test worker    | FINE : - Wrapping TransferManager LocalTransferManager in RetriableFeatureTransferManager
16-2-17 8:19:53.819 | TransferManager | Test worker    | INFO : - SKIPPING unsupported optional feature PathAware
16-2-17 8:19:53.819 | TransferManager | Test worker    | FINE : - Wrapping TransferManager RetriableFeatureTransferManager in TransactionAwareFeatureTransferManager
16-2-17 8:19:53.820 | DatabaseConnect | Test worker    | INFO : Found 12 tables.
16-2-17 8:19:53.820 | DownOperation   | Test worker    | INFO : 
16-2-17 8:19:53.820 | DownOperation   | Test worker    | INFO : Running 'Sync down' at client B ...
16-2-17 8:19:53.820 | DownOperation   | Test worker    | INFO : --------------------------------------------
16-2-17 8:19:53.820 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event DownStartSyncExternalEvent (org.syncany.operations.daemon.messages.DownStartSyncExternalEvent@68e3c815) ...
16-2-17 8:19:53.820 | AbstractTransfe | Test worker    | INFO : Looking for other running remote operations ...
16-2-17 8:19:53.821 | DatabaseConnect | Test worker    | INFO : Found 12 tables.
16-2-17 8:19:53.821 | LsRemoteOperati | Test worker    | INFO : 
16-2-17 8:19:53.821 | LsRemoteOperati | Test worker    | INFO : Running 'Remote Status' at client B ...
16-2-17 8:19:53.821 | LsRemoteOperati | Test worker    | INFO : --------------------------------------------
16-2-17 8:19:53.821 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event LsRemoteStartSyncExternalEvent (org.syncany.operations.daemon.messages.LsRemoteStartSyncExternalEvent@25cb6544) ...
16-2-17 8:19:53.821 | LsRemoteOperati | Test worker    | INFO : Retrieving remote database list.
16-2-17 8:19:53.821 | LsRemoteOperati | Test worker    | INFO : - Remote database database-A-0000000004 is already known (in local database). Ignoring.
16-2-17 8:19:53.821 | LsRemoteOperati | Test worker    | INFO : - Remote database database-A-0000000002 is already known (in local database). Ignoring.
16-2-17 8:19:53.821 | LsRemoteOperati | Test worker    | INFO : - Remote database database-A-0000000003 is already known (in local database). Ignoring.
16-2-17 8:19:53.821 | LsRemoteOperati | Test worker    | INFO : - Remote database database-B-0000000001 is already known (in local database). Ignoring.
16-2-17 8:19:53.821 | LsRemoteOperati | Test worker    | INFO : - Remote database database-A-0000000001 is already known (in local database). Ignoring.
16-2-17 8:19:53.821 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event LsRemoteEndSyncExternalEvent (org.syncany.operations.daemon.messages.LsRemoteEndSyncExternalEvent@36d282b) ...
16-2-17 8:19:53.821 | DownOperation   | Test worker    | INFO : * Nothing new. Skipping down operation.
16-2-17 8:19:53.821 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event DownEndSyncExternalEvent (org.syncany.operations.daemon.messages.DownEndSyncExternalEvent@40189e07) ...
16-2-17 8:19:53.826 | org.syncany.plu | Test worker    | FINE : Validating required fields
16-2-17 8:19:53.827 | TransferManager | Test worker    | INFO : Building LocalTransferManager from config 'syncany-170216081829395-47281-client-C' ...
16-2-17 8:19:53.827 | TransferManager | Test worker    | INFO : - With feature ReadAfterWriteConsistent
16-2-17 8:19:53.827 | TransferManager | Test worker    | INFO : - With feature Retriable
16-2-17 8:19:53.827 | TransferManager | Test worker    | FINE : - Checking if selected features supported ...
16-2-17 8:19:53.827 | TransferManager | Test worker    | FINE : - Checking for duplicate features ...
16-2-17 8:19:53.827 | TransferManager | Test worker    | FINE : - Checking required feature TransactionAware in LocalTransferManager ...
16-2-17 8:19:53.827 | TransferManager | Test worker    | FINE : - Checking required feature Retriable in LocalTransferManager ...
16-2-17 8:19:53.827 | TransferManager | Test worker    | INFO : - SKIPPING unsupported optional feature ReadAfterWriteConsistent
16-2-17 8:19:53.827 | TransferManager | Test worker    | FINE : - Wrapping TransferManager LocalTransferManager in RetriableFeatureTransferManager
16-2-17 8:19:53.827 | org.syncany.plu | Test worker    | FINE : Validating required fields
16-2-17 8:19:53.827 | TransferManager | Test worker    | INFO : Building LocalTransferManager from config 'syncany-170216081829395-47281-client-C' ...
16-2-17 8:19:53.827 | TransferManager | Test worker    | INFO : - With feature ReadAfterWriteConsistent
16-2-17 8:19:53.827 | TransferManager | Test worker    | INFO : - With feature Retriable
16-2-17 8:19:53.827 | TransferManager | Test worker    | INFO : - With feature PathAware
16-2-17 8:19:53.827 | TransferManager | Test worker    | INFO : - With feature TransactionAware
16-2-17 8:19:53.827 | TransferManager | Test worker    | FINE : - Checking if selected features supported ...
16-2-17 8:19:53.827 | TransferManager | Test worker    | FINE : - Checking for duplicate features ...
16-2-17 8:19:53.827 | TransferManager | Test worker    | FINE : - Checking required feature TransactionAware in LocalTransferManager ...
16-2-17 8:19:53.827 | TransferManager | Test worker    | FINE : - Checking required feature Retriable in LocalTransferManager ...
16-2-17 8:19:53.827 | TransferManager | Test worker    | INFO : - SKIPPING unsupported optional feature ReadAfterWriteConsistent
16-2-17 8:19:53.827 | TransferManager | Test worker    | FINE : - Wrapping TransferManager LocalTransferManager in RetriableFeatureTransferManager
16-2-17 8:19:53.827 | TransferManager | Test worker    | INFO : - SKIPPING unsupported optional feature PathAware
16-2-17 8:19:53.827 | TransferManager | Test worker    | FINE : - Wrapping TransferManager RetriableFeatureTransferManager in TransactionAwareFeatureTransferManager
16-2-17 8:19:53.830 | DatabaseConnect | Test worker    | INFO : Found 12 tables.
16-2-17 8:19:53.830 | DownOperation   | Test worker    | INFO : 
16-2-17 8:19:53.830 | DownOperation   | Test worker    | INFO : Running 'Sync down' at client C ...
16-2-17 8:19:53.830 | DownOperation   | Test worker    | INFO : --------------------------------------------
16-2-17 8:19:53.830 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event DownStartSyncExternalEvent (org.syncany.operations.daemon.messages.DownStartSyncExternalEvent@ca28f01) ...
16-2-17 8:19:53.830 | AbstractTransfe | Test worker    | INFO : Looking for other running remote operations ...
16-2-17 8:19:53.830 | DatabaseConnect | Test worker    | INFO : Found 12 tables.
16-2-17 8:19:53.830 | LsRemoteOperati | Test worker    | INFO : 
16-2-17 8:19:53.830 | LsRemoteOperati | Test worker    | INFO : Running 'Remote Status' at client C ...
16-2-17 8:19:53.830 | LsRemoteOperati | Test worker    | INFO : --------------------------------------------
16-2-17 8:19:53.830 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event LsRemoteStartSyncExternalEvent (org.syncany.operations.daemon.messages.LsRemoteStartSyncExternalEvent@628890b3) ...
16-2-17 8:19:53.831 | LsRemoteOperati | Test worker    | INFO : Retrieving remote database list.
16-2-17 8:19:53.831 | LsRemoteOperati | Test worker    | INFO : - Remote database database-A-0000000004 is new.
16-2-17 8:19:53.831 | LsRemoteOperati | Test worker    | INFO : - Remote database database-A-0000000002 is new.
16-2-17 8:19:53.831 | LsRemoteOperati | Test worker    | INFO : - Remote database database-A-0000000003 is new.
16-2-17 8:19:53.831 | LsRemoteOperati | Test worker    | INFO : - Remote database database-B-0000000001 is new.
16-2-17 8:19:53.831 | LsRemoteOperati | Test worker    | INFO : - Remote database database-A-0000000001 is new.
16-2-17 8:19:53.831 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event LsRemoteEndSyncExternalEvent (org.syncany.operations.daemon.messages.LsRemoteEndSyncExternalEvent@7dda50c8) ...
16-2-17 8:19:53.831 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event DownChangesDetectedSyncExternalEvent (org.syncany.operations.daemon.messages.DownChangesDetectedSyncExternalEvent@3de81f0f) ...
16-2-17 8:19:53.831 | ActionFileHandl | Test worker    | INFO : Starting action for RemoteFile[name=action-down-C-1487233193828] ...
16-2-17 8:19:53.831 | ActionFileHandl | Test worker    | INFO : Uploading action file: RemoteFile[name=action-down-C-1487233193828]
16-2-17 8:19:53.831 | ActionFileHandl | Test worker    | INFO : Scheduling action renewal task for every 2 minutes, for RemoteFile[name=action-down-C-1487233193828] ...
16-2-17 8:19:53.832 | DownOperation   | Test worker    | INFO : Downloading unknown databases.
16-2-17 8:19:53.832 | DownOperation   | Test worker    | INFO : - Downloading database-A-0000000004 to local cache at /tmp/syncanytest/syncany-170216081829395-47281-client-C/.syncany/cache/database-A-0000000004
16-2-17 8:19:53.832 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event DownDownloadFileSyncExternalEvent (org.syncany.operations.daemon.messages.DownDownloadFileSyncExternalEvent@1102ab73) ...
16-2-17 8:19:53.833 | DownOperation   | Test worker    | INFO : - Downloading database-A-0000000002 to local cache at /tmp/syncanytest/syncany-170216081829395-47281-client-C/.syncany/cache/database-A-0000000002
16-2-17 8:19:53.833 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event DownDownloadFileSyncExternalEvent (org.syncany.operations.daemon.messages.DownDownloadFileSyncExternalEvent@6cefde3b) ...
16-2-17 8:19:53.834 | DownOperation   | Test worker    | INFO : - Downloading database-A-0000000003 to local cache at /tmp/syncanytest/syncany-170216081829395-47281-client-C/.syncany/cache/database-A-0000000003
16-2-17 8:19:53.834 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event DownDownloadFileSyncExternalEvent (org.syncany.operations.daemon.messages.DownDownloadFileSyncExternalEvent@6fc72dea) ...
16-2-17 8:19:53.834 | DownOperation   | Test worker    | INFO : - Downloading database-B-0000000001 to local cache at /tmp/syncanytest/syncany-170216081829395-47281-client-C/.syncany/cache/database-B-0000000001
16-2-17 8:19:53.834 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event DownDownloadFileSyncExternalEvent (org.syncany.operations.daemon.messages.DownDownloadFileSyncExternalEvent@5e840a3f) ...
16-2-17 8:19:53.834 | DownOperation   | Test worker    | INFO : - Downloading database-A-0000000001 to local cache at /tmp/syncanytest/syncany-170216081829395-47281-client-C/.syncany/cache/database-A-0000000001
16-2-17 8:19:53.834 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event DownDownloadFileSyncExternalEvent (org.syncany.operations.daemon.messages.DownDownloadFileSyncExternalEvent@5ad96baf) ...
16-2-17 8:19:53.835 | DownOperation   | Test worker    | INFO : Loading database headers, creating branches ...
16-2-17 8:19:53.835 | DatabaseXmlSeri | Test worker    | INFO : - Loading database (HEADER_ONLY) from file /tmp/syncanytest/syncany-170216081829395-47281-client-C/.syncany/cache/database-A-0000000001 ...
16-2-17 8:19:53.836 | DatabaseXmlPars | Test worker    | INFO :    + Added database version A/(A1)/T=1487233193391
16-2-17 8:19:53.836 | DatabaseXmlSeri | Test worker    | INFO : - Loading database (HEADER_ONLY) from file /tmp/syncanytest/syncany-170216081829395-47281-client-C/.syncany/cache/database-A-0000000002 ...
16-2-17 8:19:53.837 | DatabaseXmlPars | Test worker    | INFO :    + Added database version A/(A2)/T=1487233193425
16-2-17 8:19:53.837 | DatabaseXmlSeri | Test worker    | INFO : - Loading database (HEADER_ONLY) from file /tmp/syncanytest/syncany-170216081829395-47281-client-C/.syncany/cache/database-A-0000000003 ...
16-2-17 8:19:53.838 | DatabaseXmlPars | Test worker    | INFO :    + Added database version A/(A3)/T=1487233193455
16-2-17 8:19:53.838 | DatabaseXmlSeri | Test worker    | INFO : - Loading database (HEADER_ONLY) from file /tmp/syncanytest/syncany-170216081829395-47281-client-C/.syncany/cache/database-A-0000000004 ...
16-2-17 8:19:53.839 | DatabaseXmlPars | Test worker    | INFO :    + Added database version A/(A4)/T=1487233193490
16-2-17 8:19:53.839 | DatabaseXmlSeri | Test worker    | INFO : - Loading database (HEADER_ONLY) from file /tmp/syncanytest/syncany-170216081829395-47281-client-C/.syncany/cache/database-B-0000000001 ...
16-2-17 8:19:53.840 | DatabaseXmlPars | Test worker    | INFO :    + Added database version B/(A4,B1)/T=1487233193657
16-2-17 8:19:53.841 | LocalTransferMa | Test worker    | INFO : Cannot create instance of CleanupRemoteFile for file /tmp/syncanytest/syncany-170216081829395-47281-repo/databases/database-B-0000000001; maybe invalid file name pattern. Ignoring file.
16-2-17 8:19:53.841 | LocalTransferMa | Test worker    | INFO : Cannot create instance of CleanupRemoteFile for file /tmp/syncanytest/syncany-170216081829395-47281-repo/databases/database-A-0000000004; maybe invalid file name pattern. Ignoring file.
16-2-17 8:19:53.841 | LocalTransferMa | Test worker    | INFO : Cannot create instance of CleanupRemoteFile for file /tmp/syncanytest/syncany-170216081829395-47281-repo/databases/database-A-0000000002; maybe invalid file name pattern. Ignoring file.
16-2-17 8:19:53.841 | LocalTransferMa | Test worker    | INFO : Cannot create instance of CleanupRemoteFile for file /tmp/syncanytest/syncany-170216081829395-47281-repo/databases/database-A-0000000001; maybe invalid file name pattern. Ignoring file.
16-2-17 8:19:53.841 | LocalTransferMa | Test worker    | INFO : Cannot create instance of CleanupRemoteFile for file /tmp/syncanytest/syncany-170216081829395-47281-repo/databases/database-A-0000000003; maybe invalid file name pattern. Ignoring file.
16-2-17 8:19:53.841 | ApplicationSqlD | Test worker    | INFO : SQL (general_settings): Read cleanupNumber  = (not set)
16-2-17 8:19:53.841 | DownOperation   | Test worker    | INFO : Populated unknown branches: {A=[A/(A1)/T=1487233193391, A/(A2)/T=1487233193425, A/(A3)/T=1487233193455, A/(A4)/T=1487233193490], B=[B/(A4,B1)/T=1487233193657], C=[]}
16-2-17 8:19:53.841 | DownOperation   | Test worker    | INFO : Determine winner using database reconciliator ...
16-2-17 8:19:53.841 | DatabaseReconci | Test worker    | INFO : Adding database version to winning branch: A/(A1)/T=1487233193391
16-2-17 8:19:53.841 | DatabaseReconci | Test worker    | INFO : Adding database version to winning branch: A/(A2)/T=1487233193425
16-2-17 8:19:53.842 | DatabaseReconci | Test worker    | INFO : Adding database version to winning branch: A/(A3)/T=1487233193455
16-2-17 8:19:53.842 | DatabaseReconci | Test worker    | INFO : Adding database version to winning branch: A/(A4)/T=1487233193490
16-2-17 8:19:53.842 | DatabaseReconci | Test worker    | INFO : Adding database version to winning branch: B/(A4,B1)/T=1487233193657
16-2-17 8:19:53.842 | DatabaseReconci | Test worker    | INFO : - Winner is B with branch: 
16-2-17 8:19:53.842 | DatabaseReconci | Test worker    | INFO :   + A/(A1)/T=1487233193391
16-2-17 8:19:53.842 | DatabaseReconci | Test worker    | INFO :   + A/(A2)/T=1487233193425
16-2-17 8:19:53.842 | DatabaseReconci | Test worker    | INFO :   + A/(A3)/T=1487233193455
16-2-17 8:19:53.842 | DatabaseReconci | Test worker    | INFO :   + A/(A4)/T=1487233193490
16-2-17 8:19:53.842 | DatabaseReconci | Test worker    | INFO :   + B/(A4,B1)/T=1487233193657
16-2-17 8:19:53.842 | DownOperation   | Test worker    | INFO : - Database versions to REMOVE locally: []
16-2-17 8:19:53.842 | DownOperation   | Test worker    | INFO :   + Nothing to purge locally. No conflicts. Only updates. Nice!
16-2-17 8:19:53.842 | DatabaseReconci | Test worker    | INFO : Finding winnersApplyBranch.
16-2-17 8:19:53.842 | DatabaseReconci | Test worker    | INFO : Losers Branch: []
16-2-17 8:19:53.842 | DatabaseReconci | Test worker    | INFO : Winners Branch: [A/(A1)/T=1487233193391, A/(A2)/T=1487233193425, A/(A3)/T=1487233193455, A/(A4)/T=1487233193490, B/(A4,B1)/T=1487233193657]
16-2-17 8:19:53.842 | DownOperation   | Test worker    | INFO : - Cleanup occurred: false
16-2-17 8:19:53.842 | DownOperation   | Test worker    | INFO : - Database versions to APPLY locally: [A/(A1)/T=1487233193391, A/(A2)/T=1487233193425, A/(A3)/T=1487233193455, A/(A4)/T=1487233193490, B/(A4,B1)/T=1487233193657]
16-2-17 8:19:53.842 | DownOperation   | Test worker    | INFO : Loading winners database (DEFAULT) ...
16-2-17 8:19:53.842 | DatabaseXmlSeri | Test worker    | INFO : - Loading database (FULL) from file /tmp/syncanytest/syncany-170216081829395-47281-client-C/.syncany/cache/database-A-0000000001 ...
16-2-17 8:19:53.843 | DatabaseXmlPars | Test worker    | INFO :    + Added database version A/(A1)/T=1487233193391
16-2-17 8:19:53.843 | DatabaseXmlSeri | Test worker    | INFO : - Loading database (FULL) from file /tmp/syncanytest/syncany-170216081829395-47281-client-C/.syncany/cache/database-A-0000000002 ...
16-2-17 8:19:53.844 | DatabaseXmlPars | Test worker    | INFO :    + Added database version A/(A2)/T=1487233193425
16-2-17 8:19:53.844 | DatabaseXmlSeri | Test worker    | INFO : - Loading database (FULL) from file /tmp/syncanytest/syncany-170216081829395-47281-client-C/.syncany/cache/database-A-0000000003 ...
16-2-17 8:19:53.845 | DatabaseXmlPars | Test worker    | INFO :    + Added database version A/(A3)/T=1487233193455
16-2-17 8:19:53.845 | DatabaseXmlSeri | Test worker    | INFO : - Loading database (FULL) from file /tmp/syncanytest/syncany-170216081829395-47281-client-C/.syncany/cache/database-A-0000000004 ...
16-2-17 8:19:53.848 | DatabaseXmlPars | Test worker    | INFO :    + Added database version A/(A4)/T=1487233193490
16-2-17 8:19:53.848 | DatabaseXmlSeri | Test worker    | INFO : - Loading database (FULL) from file /tmp/syncanytest/syncany-170216081829395-47281-client-C/.syncany/cache/database-B-0000000001 ...
16-2-17 8:19:53.849 | DatabaseXmlPars | Test worker    | INFO :    + Added database version B/(A4,B1)/T=1487233193657
16-2-17 8:19:53.849 | DownOperation   | Test worker    | INFO : Determine file system actions ...
16-2-17 8:19:53.850 | DatabaseConnect | Test worker    | INFO : Found 12 tables.
16-2-17 8:19:53.850 | FileSystemActio | Test worker    | INFO : - Loading current file tree...
16-2-17 8:19:53.850 | FileSystemActio | Test worker    | INFO : - Determine filesystem actions ...
16-2-17 8:19:53.850 | FileSystemActio | Test worker    | INFO :   + Comparing local version: null
16-2-17 8:19:53.850 | FileSystemActio | Test worker    | INFO :     with winning version   : FileVersion [version=2, path=A3, type=FILE, status=DELETED, size=51200, lastModified=Thu Feb 16 08:19:53 UTC 2017, linkTarget=null, checksum=db017b97ff6e9e8df6c8ba9158064dd65824bc95, updated=Thu Feb 16 08:19:53 UTC 2017, posixPermissions=rw-rw-r--, dosAttributes=--a-]
16-2-17 8:19:53.851 | FileVersionComp | Test worker    | INFO :      - []: Local file does not exist, and expected file was deleted, for file A3
16-2-17 8:19:53.851 | FileSystemActio | Test worker    | INFO :      -> (1) Equals: Nothing to do, winning version equals winning file: FileVersion [version=2, path=A3, type=FILE, status=DELETED, size=51200, lastModified=Thu Feb 16 08:19:53 UTC 2017, linkTarget=null, checksum=db017b97ff6e9e8df6c8ba9158064dd65824bc95, updated=Thu Feb 16 08:19:53 UTC 2017, posixPermissions=rw-rw-r--, dosAttributes=--a-] AND /tmp/syncanytest/syncany-170216081829395-47281-client-C/A3
16-2-17 8:19:53.851 | FileSystemActio | Test worker    | INFO :   + Comparing local version: null
16-2-17 8:19:53.851 | FileSystemActio | Test worker    | INFO :     with winning version   : FileVersion [version=1, path=A4,B1, type=FILE, status=NEW, size=51200, lastModified=Thu Feb 16 08:19:53 UTC 2017, linkTarget=null, checksum=2a355a2b2b4bbac69e38a6c1b370782d125b1245, updated=Thu Feb 16 08:19:53 UTC 2017, posixPermissions=rw-rw-r--, dosAttributes=--a-]
16-2-17 8:19:53.851 | FileVersionComp | Test worker    | INFO :      - [DELETED]: Local file DIFFERS from file version, expected EXISTS = true, but actual EXISTS = false, for file A4,B1
16-2-17 8:19:53.851 | FileSystemActio | Test worker    | INFO :      -> (2) Deleted: Local file does NOT exist, but it should, winning version not known: FileVersion [version=1, path=A4,B1, type=FILE, status=NEW, size=51200, lastModified=Thu Feb 16 08:19:53 UTC 2017, linkTarget=null, checksum=2a355a2b2b4bbac69e38a6c1b370782d125b1245, updated=Thu Feb 16 08:19:53 UTC 2017, posixPermissions=rw-rw-r--, dosAttributes=--a-] AND /tmp/syncanytest/syncany-170216081829395-47281-client-C/A4,B1
16-2-17 8:19:53.851 | FileSystemActio | Test worker    | INFO :      -> NewFileSystemAction [file1=null, file2=FileVersion [version=1, path=A4,B1, type=FILE, status=NEW, size=51200, lastModified=Thu Feb 16 08:19:53 UTC 2017, linkTarget=null, checksum=2a355a2b2b4bbac69e38a6c1b370782d125b1245, updated=Thu Feb 16 08:19:53 UTC 2017, posixPermissions=rw-rw-r--, dosAttributes=--a-]]
16-2-17 8:19:53.851 | FileSystemActio | Test worker    | INFO :   + Comparing local version: null
16-2-17 8:19:53.851 | FileSystemActio | Test worker    | INFO :     with winning version   : FileVersion [version=3, path=A2, type=FILE, status=CHANGED, size=51200, lastModified=Thu Feb 16 08:19:53 UTC 2017, linkTarget=null, checksum=fb073b8aea2e985ed83cd0a4412b798fe911428e, updated=Thu Feb 16 08:19:53 UTC 2017, posixPermissions=rw-rw-r--, dosAttributes=--a-]
16-2-17 8:19:53.851 | FileVersionComp | Test worker    | INFO :      - [DELETED]: Local file DIFFERS from file version, expected EXISTS = true, but actual EXISTS = false, for file A2
16-2-17 8:19:53.851 | FileSystemActio | Test worker    | INFO :      -> (2) Deleted: Local file does NOT exist, but it should, winning version not known: FileVersion [version=3, path=A2, type=FILE, status=CHANGED, size=51200, lastModified=Thu Feb 16 08:19:53 UTC 2017, linkTarget=null, checksum=fb073b8aea2e985ed83cd0a4412b798fe911428e, updated=Thu Feb 16 08:19:53 UTC 2017, posixPermissions=rw-rw-r--, dosAttributes=--a-] AND /tmp/syncanytest/syncany-170216081829395-47281-client-C/A2
16-2-17 8:19:53.851 | FileSystemActio | Test worker    | INFO :      -> NewFileSystemAction [file1=null, file2=FileVersion [version=3, path=A2, type=FILE, status=CHANGED, size=51200, lastModified=Thu Feb 16 08:19:53 UTC 2017, linkTarget=null, checksum=fb073b8aea2e985ed83cd0a4412b798fe911428e, updated=Thu Feb 16 08:19:53 UTC 2017, posixPermissions=rw-rw-r--, dosAttributes=--a-]]
16-2-17 8:19:53.852 | DownOperation   | Test worker    | INFO :   + Adding multichunk 1e664298198ec1f6bcbd5cd8cd09596a767fbace to download list ...
16-2-17 8:19:53.852 | DownOperation   | Test worker    | INFO :   + Adding multichunk 15aa4aa6b32b4d905a0ca51bfb145bb1df5ebe63 to download list ...
16-2-17 8:19:53.852 | Downloader      | Test worker    | INFO : Downloading and extracting multichunks ...
16-2-17 8:19:53.852 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event DownDownloadFileSyncExternalEvent (org.syncany.operations.daemon.messages.DownDownloadFileSyncExternalEvent@1bc4ae17) ...
16-2-17 8:19:53.853 | Downloader      | Test worker    | INFO :   + Downloading multichunk 15aa4aa6b32b4d905a0ca51bfb145bb1df5ebe63 ...
16-2-17 8:19:53.853 | Downloader      | Test worker    | INFO :   + Decrypting multichunk 15aa4aa6b32b4d905a0ca51bfb145bb1df5ebe63 ...
16-2-17 8:19:53.853 | Downloader      | Test worker    | FINE :   + Locally deleting multichunk 15aa4aa6b32b4d905a0ca51bfb145bb1df5ebe63 ...
16-2-17 8:19:53.854 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event DownDownloadFileSyncExternalEvent (org.syncany.operations.daemon.messages.DownDownloadFileSyncExternalEvent@249a1098) ...
16-2-17 8:19:53.854 | Downloader      | Test worker    | INFO :   + Downloading multichunk 1e664298198ec1f6bcbd5cd8cd09596a767fbace ...
16-2-17 8:19:53.855 | Downloader      | Test worker    | INFO :   + Decrypting multichunk 1e664298198ec1f6bcbd5cd8cd09596a767fbace ...
16-2-17 8:19:53.855 | Downloader      | Test worker    | FINE :   + Locally deleting multichunk 1e664298198ec1f6bcbd5cd8cd09596a767fbace ...
16-2-17 8:19:53.856 | FileSystemActio | Test worker    | INFO :    Sorted actions:
16-2-17 8:19:53.856 | FileSystemActio | Test worker    | INFO :    + NewFileSystemAction [file1=null, file2=FileVersion [version=3, path=A2, type=FILE, status=CHANGED, size=51200, lastModified=Thu Feb 16 08:19:53 UTC 2017, linkTarget=null, checksum=fb073b8aea2e985ed83cd0a4412b798fe911428e, updated=Thu Feb 16 08:19:53 UTC 2017, posixPermissions=rw-rw-r--, dosAttributes=--a-]]
16-2-17 8:19:53.856 | FileSystemActio | Test worker    | INFO :    + NewFileSystemAction [file1=null, file2=FileVersion [version=1, path=A4,B1, type=FILE, status=NEW, size=51200, lastModified=Thu Feb 16 08:19:53 UTC 2017, linkTarget=null, checksum=2a355a2b2b4bbac69e38a6c1b370782d125b1245, updated=Thu Feb 16 08:19:53 UTC 2017, posixPermissions=rw-rw-r--, dosAttributes=--a-]]
16-2-17 8:19:53.856 | DownOperation   | Test worker    | FINE : - Applying file system actions (sorted!) ...
16-2-17 8:19:53.856 | DownOperation   | Test worker    | FINE :    +  NewFileSystemAction [file1=null, file2=FileVersion [version=3, path=A2, type=FILE, status=CHANGED, size=51200, lastModified=Thu Feb 16 08:19:53 UTC 2017, linkTarget=null, checksum=fb073b8aea2e985ed83cd0a4412b798fe911428e, updated=Thu Feb 16 08:19:53 UTC 2017, posixPermissions=rw-rw-r--, dosAttributes=--a-]]
16-2-17 8:19:53.856 | Assembler       | Test worker    | INFO :      - Creating file A2 to /tmp/syncanytest/syncany-170216081829395-47281-client-C/.syncany/cache/temp-reconstructedFileVersion-2395290462594607627.tmp ...
16-2-17 8:19:53.857 | FileSystemActio | Test worker    | INFO :      - Setting POSIX permissions: rw-rw-r-- ...
16-2-17 8:19:53.857 | DownOperation   | Test worker    | FINE :    +  NewFileSystemAction [file1=null, file2=FileVersion [version=1, path=A4,B1, type=FILE, status=NEW, size=51200, lastModified=Thu Feb 16 08:19:53 UTC 2017, linkTarget=null, checksum=2a355a2b2b4bbac69e38a6c1b370782d125b1245, updated=Thu Feb 16 08:19:53 UTC 2017, posixPermissions=rw-rw-r--, dosAttributes=--a-]]
16-2-17 8:19:53.858 | Assembler       | Test worker    | INFO :      - Creating file A4,B1 to /tmp/syncanytest/syncany-170216081829395-47281-client-C/.syncany/cache/temp-reconstructedFileVersion-3993421310684315377.tmp ...
16-2-17 8:19:53.859 | FileSystemActio | Test worker    | INFO :      - Setting POSIX permissions: rw-rw-r-- ...
16-2-17 8:19:53.859 | DownOperation   | Test worker    | INFO : - Adding database versions to SQL database ...
16-2-17 8:19:53.859 | DownOperation   | Test worker    | INFO :   + Applying database version (A1)
16-2-17 8:19:53.859 | DownOperation   | Test worker    | FINE :   + Contents: DatabaseVersion [header=A/(A1)/T=1487233193391, chunks=1, multiChunks=1, fileContents=1, fileHistories=1]
16-2-17 8:19:53.860 | DownOperation   | Test worker    | INFO :   + Applying database version (A2)
16-2-17 8:19:53.860 | DownOperation   | Test worker    | FINE :   + Contents: DatabaseVersion [header=A/(A2)/T=1487233193425, chunks=0, multiChunks=0, fileContents=0, fileHistories=1]
16-2-17 8:19:53.862 | DownOperation   | Test worker    | INFO :   + Applying database version (A3)
16-2-17 8:19:53.862 | DownOperation   | Test worker    | FINE :   + Contents: DatabaseVersion [header=A/(A3)/T=1487233193455, chunks=2, multiChunks=1, fileContents=2, fileHistories=2]
16-2-17 8:19:53.864 | DownOperation   | Test worker    | INFO :   + Applying database version (A4)
16-2-17 8:19:53.864 | DownOperation   | Test worker    | FINE :   + Contents: DatabaseVersion [header=A/(A4)/T=1487233193490, chunks=0, multiChunks=0, fileContents=0, fileHistories=1]
16-2-17 8:19:53.865 | DownOperation   | Test worker    | INFO :   + Applying database version (A4,B1)
16-2-17 8:19:53.865 | DownOperation   | Test worker    | FINE :   + Contents: DatabaseVersion [header=B/(A4,B1)/T=1487233193657, chunks=1, multiChunks=1, fileContents=1, fileHistories=1]
16-2-17 8:19:53.871 | ActionFileHandl | Test worker    | INFO : Finishing action for RemoteFile[name=action-down-C-1487233193828] ...
16-2-17 8:19:53.872 | ActionFileHandl | Test worker    | INFO : Deleting action file: RemoteFile[name=action-down-C-1487233193828]
16-2-17 8:19:53.872 | AbstractTransfe | Test worker    | INFO : Cleaning own old action files ...
16-2-17 8:19:53.872 | Cache           | Test worker    | INFO : Cache size okay (156 KB), no need to clean (keep size is 500 MB)
16-2-17 8:19:53.872 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event DownEndSyncExternalEvent (org.syncany.operations.daemon.messages.DownEndSyncExternalEvent@68fafae8) ...
16-2-17 8:19:53.872 | DownOperation   | Test worker    | INFO : Sync down done.
16-2-17 8:19:53.877 | org.syncany.plu | Test worker    | FINE : Validating required fields
16-2-17 8:19:53.879 | TransferManager | Test worker    | INFO : Building LocalTransferManager from config 'syncany-170216081829395-47281-client-A' ...
16-2-17 8:19:53.879 | TransferManager | Test worker    | INFO : - With feature ReadAfterWriteConsistent
16-2-17 8:19:53.879 | TransferManager | Test worker    | INFO : - With feature Retriable
16-2-17 8:19:53.879 | TransferManager | Test worker    | FINE : - Checking if selected features supported ...
16-2-17 8:19:53.879 | TransferManager | Test worker    | FINE : - Checking for duplicate features ...
16-2-17 8:19:53.879 | TransferManager | Test worker    | FINE : - Checking required feature TransactionAware in LocalTransferManager ...
16-2-17 8:19:53.879 | TransferManager | Test worker    | FINE : - Checking required feature Retriable in LocalTransferManager ...
16-2-17 8:19:53.879 | TransferManager | Test worker    | INFO : - SKIPPING unsupported optional feature ReadAfterWriteConsistent
16-2-17 8:19:53.879 | TransferManager | Test worker    | FINE : - Wrapping TransferManager LocalTransferManager in RetriableFeatureTransferManager
16-2-17 8:19:53.879 | org.syncany.plu | Test worker    | FINE : Validating required fields
16-2-17 8:19:53.879 | TransferManager | Test worker    | INFO : Building LocalTransferManager from config 'syncany-170216081829395-47281-client-A' ...
16-2-17 8:19:53.879 | TransferManager | Test worker    | INFO : - With feature ReadAfterWriteConsistent
16-2-17 8:19:53.879 | TransferManager | Test worker    | INFO : - With feature Retriable
16-2-17 8:19:53.879 | TransferManager | Test worker    | INFO : - With feature PathAware
16-2-17 8:19:53.879 | TransferManager | Test worker    | INFO : - With feature TransactionAware
16-2-17 8:19:53.879 | TransferManager | Test worker    | FINE : - Checking if selected features supported ...
16-2-17 8:19:53.879 | TransferManager | Test worker    | FINE : - Checking for duplicate features ...
16-2-17 8:19:53.879 | TransferManager | Test worker    | FINE : - Checking required feature TransactionAware in LocalTransferManager ...
16-2-17 8:19:53.880 | TransferManager | Test worker    | FINE : - Checking required feature Retriable in LocalTransferManager ...
16-2-17 8:19:53.880 | TransferManager | Test worker    | INFO : - SKIPPING unsupported optional feature ReadAfterWriteConsistent
16-2-17 8:19:53.880 | TransferManager | Test worker    | FINE : - Wrapping TransferManager LocalTransferManager in RetriableFeatureTransferManager
16-2-17 8:19:53.880 | TransferManager | Test worker    | INFO : - SKIPPING unsupported optional feature PathAware
16-2-17 8:19:53.880 | TransferManager | Test worker    | FINE : - Wrapping TransferManager RetriableFeatureTransferManager in TransactionAwareFeatureTransferManager
16-2-17 8:19:53.883 | DatabaseConnect | Test worker    | INFO : Found 12 tables.
16-2-17 8:19:53.883 | UpOperation     | Test worker    | INFO : 
16-2-17 8:19:53.883 | UpOperation     | Test worker    | INFO : Running 'Sync up' at client A ...
16-2-17 8:19:53.883 | UpOperation     | Test worker    | INFO : --------------------------------------------
16-2-17 8:19:53.883 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event UpStartSyncExternalEvent (org.syncany.operations.daemon.messages.UpStartSyncExternalEvent@35b706c5) ...
16-2-17 8:19:53.884 | DatabaseConnect | Test worker    | INFO : Found 12 tables.
16-2-17 8:19:53.884 | StatusOperation | Test worker    | INFO : 
16-2-17 8:19:53.884 | StatusOperation | Test worker    | INFO : Running 'Status' at client A ...
16-2-17 8:19:53.884 | StatusOperation | Test worker    | INFO : --------------------------------------------
16-2-17 8:19:53.884 | StatusOperation | Test worker    | INFO : Querying current file tree from database ...
16-2-17 8:19:53.884 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event StatusStartSyncExternalEvent (org.syncany.operations.daemon.messages.StatusStartSyncExternalEvent@155425f7) ...
16-2-17 8:19:53.885 | StatusOperation | Test worker    | INFO : Analyzing local folder /tmp/syncanytest/syncany-170216081829395-47281-client-A ...
16-2-17 8:19:53.885 | StatusOperation | Test worker    | FINE : - Ignoring file (syncany app-related): .syncany
16-2-17 8:19:53.885 | StatusOperation | Test worker    | INFO : - No changes to local database
16-2-17 8:19:53.885 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event StatusEndSyncExternalEvent (org.syncany.operations.daemon.messages.StatusEndSyncExternalEvent@6e92f483) ...
16-2-17 8:19:53.885 | UpOperation     | Test worker    | INFO : Local database is up-to-date (change set). NOTHING TO DO!
16-2-17 8:19:53.885 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event UpEndSyncExternalEvent (org.syncany.operations.daemon.messages.UpEndSyncExternalEvent@1ba9056a) ...
16-2-17 8:19:53.886 | org.syncany.plu | Test worker    | FINE : Validating required fields
16-2-17 8:19:53.886 | TransferManager | Test worker    | INFO : Building LocalTransferManager from config 'syncany-170216081829395-47281-client-B' ...
16-2-17 8:19:53.886 | TransferManager | Test worker    | INFO : - With feature ReadAfterWriteConsistent
16-2-17 8:19:53.886 | TransferManager | Test worker    | INFO : - With feature Retriable
16-2-17 8:19:53.886 | TransferManager | Test worker    | FINE : - Checking if selected features supported ...
16-2-17 8:19:53.886 | TransferManager | Test worker    | FINE : - Checking for duplicate features ...
16-2-17 8:19:53.886 | TransferManager | Test worker    | FINE : - Checking required feature TransactionAware in LocalTransferManager ...
16-2-17 8:19:53.886 | TransferManager | Test worker    | FINE : - Checking required feature Retriable in LocalTransferManager ...
16-2-17 8:19:53.886 | TransferManager | Test worker    | INFO : - SKIPPING unsupported optional feature ReadAfterWriteConsistent
16-2-17 8:19:53.886 | TransferManager | Test worker    | FINE : - Wrapping TransferManager LocalTransferManager in RetriableFeatureTransferManager
16-2-17 8:19:53.886 | org.syncany.plu | Test worker    | FINE : Validating required fields
16-2-17 8:19:53.886 | TransferManager | Test worker    | INFO : Building LocalTransferManager from config 'syncany-170216081829395-47281-client-B' ...
16-2-17 8:19:53.886 | TransferManager | Test worker    | INFO : - With feature ReadAfterWriteConsistent
16-2-17 8:19:53.886 | TransferManager | Test worker    | INFO : - With feature Retriable
16-2-17 8:19:53.886 | TransferManager | Test worker    | INFO : - With feature PathAware
16-2-17 8:19:53.886 | TransferManager | Test worker    | INFO : - With feature TransactionAware
16-2-17 8:19:53.886 | TransferManager | Test worker    | FINE : - Checking if selected features supported ...
16-2-17 8:19:53.886 | TransferManager | Test worker    | FINE : - Checking for duplicate features ...
16-2-17 8:19:53.886 | TransferManager | Test worker    | FINE : - Checking required feature TransactionAware in LocalTransferManager ...
16-2-17 8:19:53.886 | TransferManager | Test worker    | FINE : - Checking required feature Retriable in LocalTransferManager ...
16-2-17 8:19:53.886 | TransferManager | Test worker    | INFO : - SKIPPING unsupported optional feature ReadAfterWriteConsistent
16-2-17 8:19:53.886 | TransferManager | Test worker    | FINE : - Wrapping TransferManager LocalTransferManager in RetriableFeatureTransferManager
16-2-17 8:19:53.886 | TransferManager | Test worker    | INFO : - SKIPPING unsupported optional feature PathAware
16-2-17 8:19:53.887 | TransferManager | Test worker    | FINE : - Wrapping TransferManager RetriableFeatureTransferManager in TransactionAwareFeatureTransferManager
16-2-17 8:19:53.888 | DatabaseConnect | Test worker    | INFO : Found 12 tables.
16-2-17 8:19:53.888 | UpOperation     | Test worker    | INFO : 
16-2-17 8:19:53.888 | UpOperation     | Test worker    | INFO : Running 'Sync up' at client B ...
16-2-17 8:19:53.888 | UpOperation     | Test worker    | INFO : --------------------------------------------
16-2-17 8:19:53.888 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event UpStartSyncExternalEvent (org.syncany.operations.daemon.messages.UpStartSyncExternalEvent@2681a95a) ...
16-2-17 8:19:53.889 | DatabaseConnect | Test worker    | INFO : Found 12 tables.
16-2-17 8:19:53.889 | StatusOperation | Test worker    | INFO : 
16-2-17 8:19:53.889 | StatusOperation | Test worker    | INFO : Running 'Status' at client B ...
16-2-17 8:19:53.889 | StatusOperation | Test worker    | INFO : --------------------------------------------
16-2-17 8:19:53.889 | StatusOperation | Test worker    | INFO : Querying current file tree from database ...
16-2-17 8:19:53.889 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event StatusStartSyncExternalEvent (org.syncany.operations.daemon.messages.StatusStartSyncExternalEvent@c1fbb33) ...
16-2-17 8:19:53.890 | StatusOperation | Test worker    | INFO : Analyzing local folder /tmp/syncanytest/syncany-170216081829395-47281-client-B ...
16-2-17 8:19:53.890 | StatusOperation | Test worker    | FINE : - Ignoring file (syncany app-related): .syncany
16-2-17 8:19:53.890 | StatusOperation | Test worker    | INFO : - No changes to local database
16-2-17 8:19:53.890 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event StatusEndSyncExternalEvent (org.syncany.operations.daemon.messages.StatusEndSyncExternalEvent@2ccbec15) ...
16-2-17 8:19:53.890 | UpOperation     | Test worker    | INFO : Local database is up-to-date (change set). NOTHING TO DO!
16-2-17 8:19:53.890 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event UpEndSyncExternalEvent (org.syncany.operations.daemon.messages.UpEndSyncExternalEvent@7ded6ca6) ...
16-2-17 8:19:53.890 | org.syncany.plu | Test worker    | FINE : Validating required fields
16-2-17 8:19:53.890 | TransferManager | Test worker    | INFO : Building LocalTransferManager from config 'syncany-170216081829395-47281-client-C' ...
16-2-17 8:19:53.890 | TransferManager | Test worker    | INFO : - With feature ReadAfterWriteConsistent
16-2-17 8:19:53.890 | TransferManager | Test worker    | INFO : - With feature Retriable
16-2-17 8:19:53.890 | TransferManager | Test worker    | FINE : - Checking if selected features supported ...
16-2-17 8:19:53.890 | TransferManager | Test worker    | FINE : - Checking for duplicate features ...
16-2-17 8:19:53.890 | TransferManager | Test worker    | FINE : - Checking required feature TransactionAware in LocalTransferManager ...
16-2-17 8:19:53.890 | TransferManager | Test worker    | FINE : - Checking required feature Retriable in LocalTransferManager ...
16-2-17 8:19:53.890 | TransferManager | Test worker    | INFO : - SKIPPING unsupported optional feature ReadAfterWriteConsistent
16-2-17 8:19:53.890 | TransferManager | Test worker    | FINE : - Wrapping TransferManager LocalTransferManager in RetriableFeatureTransferManager
16-2-17 8:19:53.890 | org.syncany.plu | Test worker    | FINE : Validating required fields
16-2-17 8:19:53.890 | TransferManager | Test worker    | INFO : Building LocalTransferManager from config 'syncany-170216081829395-47281-client-C' ...
16-2-17 8:19:53.890 | TransferManager | Test worker    | INFO : - With feature ReadAfterWriteConsistent
16-2-17 8:19:53.890 | TransferManager | Test worker    | INFO : - With feature Retriable
16-2-17 8:19:53.891 | TransferManager | Test worker    | INFO : - With feature PathAware
16-2-17 8:19:53.891 | TransferManager | Test worker    | INFO : - With feature TransactionAware
16-2-17 8:19:53.891 | TransferManager | Test worker    | FINE : - Checking if selected features supported ...
16-2-17 8:19:53.891 | TransferManager | Test worker    | FINE : - Checking for duplicate features ...
16-2-17 8:19:53.891 | TransferManager | Test worker    | FINE : - Checking required feature TransactionAware in LocalTransferManager ...
16-2-17 8:19:53.891 | TransferManager | Test worker    | FINE : - Checking required feature Retriable in LocalTransferManager ...
16-2-17 8:19:53.891 | TransferManager | Test worker    | INFO : - SKIPPING unsupported optional feature ReadAfterWriteConsistent
16-2-17 8:19:53.891 | TransferManager | Test worker    | FINE : - Wrapping TransferManager LocalTransferManager in RetriableFeatureTransferManager
16-2-17 8:19:53.891 | TransferManager | Test worker    | INFO : - SKIPPING unsupported optional feature PathAware
16-2-17 8:19:53.891 | TransferManager | Test worker    | FINE : - Wrapping TransferManager RetriableFeatureTransferManager in TransactionAwareFeatureTransferManager
16-2-17 8:19:53.892 | DatabaseConnect | Test worker    | INFO : Found 12 tables.
16-2-17 8:19:53.892 | UpOperation     | Test worker    | INFO : 
16-2-17 8:19:53.892 | UpOperation     | Test worker    | INFO : Running 'Sync up' at client C ...
16-2-17 8:19:53.892 | UpOperation     | Test worker    | INFO : --------------------------------------------
16-2-17 8:19:53.892 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event UpStartSyncExternalEvent (org.syncany.operations.daemon.messages.UpStartSyncExternalEvent@5ed8b6eb) ...
16-2-17 8:19:53.892 | DatabaseConnect | Test worker    | INFO : Found 12 tables.
16-2-17 8:19:53.892 | StatusOperation | Test worker    | INFO : 
16-2-17 8:19:53.892 | StatusOperation | Test worker    | INFO : Running 'Status' at client C ...
16-2-17 8:19:53.892 | StatusOperation | Test worker    | INFO : --------------------------------------------
16-2-17 8:19:53.892 | StatusOperation | Test worker    | INFO : Querying current file tree from database ...
16-2-17 8:19:53.892 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event StatusStartSyncExternalEvent (org.syncany.operations.daemon.messages.StatusStartSyncExternalEvent@3b9e932b) ...
16-2-17 8:19:53.893 | StatusOperation | Test worker    | INFO : Analyzing local folder /tmp/syncanytest/syncany-170216081829395-47281-client-C ...
16-2-17 8:19:53.893 | StatusOperation | Test worker    | FINE : - Ignoring file (syncany app-related): .syncany
16-2-17 8:19:53.893 | StatusOperation | Test worker    | FINE : - New file: C1
16-2-17 8:19:53.894 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event StatusEndSyncExternalEvent (org.syncany.operations.daemon.messages.StatusEndSyncExternalEvent@140226e2) ...
16-2-17 8:19:53.894 | AbstractTransfe | Test worker    | INFO : Looking for other running remote operations ...
16-2-17 8:19:53.894 | DatabaseConnect | Test worker    | INFO : Found 12 tables.
16-2-17 8:19:53.894 | LsRemoteOperati | Test worker    | INFO : 
16-2-17 8:19:53.894 | LsRemoteOperati | Test worker    | INFO : Running 'Remote Status' at client C ...
16-2-17 8:19:53.894 | LsRemoteOperati | Test worker    | INFO : --------------------------------------------
16-2-17 8:19:53.894 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event LsRemoteStartSyncExternalEvent (org.syncany.operations.daemon.messages.LsRemoteStartSyncExternalEvent@cb19c4a) ...
16-2-17 8:19:53.894 | LsRemoteOperati | Test worker    | INFO : Retrieving remote database list.
16-2-17 8:19:53.895 | LsRemoteOperati | Test worker    | INFO : - Remote database database-A-0000000004 is already known (in local database). Ignoring.
16-2-17 8:19:53.895 | LsRemoteOperati | Test worker    | INFO : - Remote database database-A-0000000002 is already known (in local database). Ignoring.
16-2-17 8:19:53.895 | LsRemoteOperati | Test worker    | INFO : - Remote database database-A-0000000003 is already known (in local database). Ignoring.
16-2-17 8:19:53.895 | LsRemoteOperati | Test worker    | INFO : - Remote database database-B-0000000001 is already known (in local database). Ignoring.
16-2-17 8:19:53.895 | LsRemoteOperati | Test worker    | INFO : - Remote database database-A-0000000001 is already known (in local database). Ignoring.
16-2-17 8:19:53.895 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event LsRemoteEndSyncExternalEvent (org.syncany.operations.daemon.messages.LsRemoteEndSyncExternalEvent@7ee67d30) ...
16-2-17 8:19:53.895 | UpOperation     | Test worker    | INFO : No remote changes, ready to upload.
16-2-17 8:19:53.895 | ActionFileHandl | Test worker    | INFO : Starting action for RemoteFile[name=action-up-C-1487233193891] ...
16-2-17 8:19:53.895 | ActionFileHandl | Test worker    | INFO : Uploading action file: RemoteFile[name=action-up-C-1487233193891]
16-2-17 8:19:53.895 | ActionFileHandl | Test worker    | INFO : Scheduling action renewal task for every 2 minutes, for RemoteFile[name=action-up-C-1487233193891] ...
16-2-17 8:19:53.896 | DatabaseConnect | Test worker    | INFO : Found 12 tables.
16-2-17 8:19:53.896 | TransactionAwar | Test worker    | INFO : Done rolling back previous transactions.
16-2-17 8:19:53.896 | AsyncIndexer    | AsyncI/syncany | INFO : Starting Indexing.
16-2-17 8:19:53.896 | InternalEventBu | AsyncI/syncany | INFO : Event bus 'LocalEventBus': Posting event UpIndexStartSyncExternalEvent (org.syncany.operations.daemon.messages.UpIndexStartSyncExternalEvent@34bdc69c) ...
16-2-17 8:19:53.896 | Indexer         | AsyncI/syncany | FINE : - Looking for deleted files ...
16-2-17 8:19:53.896 | Indexer         | AsyncI/syncany | FINE : - +File /tmp/syncanytest/syncany-170216081829395-47281-client-C/C1
16-2-17 8:19:53.897 | Indexer         | AsyncI/syncany | FINE : - +FileContent: /tmp/syncanytest/syncany-170216081829395-47281-client-C/C1
16-2-17 8:19:53.898 | Indexer         | AsyncI/syncany | FINE : - Chunk new: 6c7a1313af77e516ae80f6e3f4233b98d8e26ea7
16-2-17 8:19:53.899 | Indexer         | AsyncI/syncany | FINE : - +MultiChunk 9f0e7bf0039b4fa94a773692869781e0b9012d16
16-2-17 8:19:53.899 | Indexer         | AsyncI/syncany | FINE : - Chunk > MultiChunk: 6c7a1313af77e516ae80f6e3f4233b98d8e26ea7 > 9f0e7bf0039b4fa94a773692869781e0b9012d16
16-2-17 8:19:53.899 | Indexer         | AsyncI/syncany | FINE : - Chunk > FileContent: 6c7a1313af77e516ae80f6e3f4233b98d8e26ea7 > /tmp/syncanytest/syncany-170216081829395-47281-client-C/C1
16-2-17 8:19:53.899 | Indexer         | AsyncI/syncany | FINE : - /File: C1 (checksum 6c7a1313af77e516ae80f6e3f4233b98d8e26ea7)
16-2-17 8:19:53.900 | Indexer         | AsyncI/syncany | FINE :    * No old file history found, starting new history (path: C1, checksum: 6c7a1313af77e516ae80f6e3f4233b98d8e26ea7)
16-2-17 8:19:53.900 | FileVersionComp | AsyncI/syncany | INFO :      - [DELETED]: Local file DIFFERS from file version, actual file is NULL, for file C1
16-2-17 8:19:53.900 | Indexer         | AsyncI/syncany | INFO :    * Added file version:    FileVersion [version=1, path=C1, type=FILE, status=NEW, size=51200, lastModified=Thu Feb 16 08:19:53 UTC 2017, linkTarget=null, checksum=6c7a1313af77e516ae80f6e3f4233b98d8e26ea7, updated=Thu Feb 16 08:19:53 UTC 2017, posixPermissions=rw-rw-r--, dosAttributes=--a-]
16-2-17 8:19:53.900 | Indexer         | AsyncI/syncany | INFO :      based on file version: null
16-2-17 8:19:53.900 | InternalEventBu | AsyncI/syncany | INFO : Event bus 'LocalEventBus': Posting event UpIndexChangesDetectedSyncExternalEvent (org.syncany.operations.daemon.messages.UpIndexChangesDetectedSyncExternalEvent@72e2800d) ...
16-2-17 8:19:53.901 | Indexer         | AsyncI/syncany | FINE : - /MultiChunk 9f0e7bf0039b4fa94a773692869781e0b9012d16
16-2-17 8:19:53.901 | InternalEventBu | AsyncI/syncany | INFO : Event bus 'LocalEventBus': Posting event UpIndexEndSyncExternalEvent (org.syncany.operations.daemon.messages.UpIndexEndSyncExternalEvent@436112be) ...
16-2-17 8:19:53.901 | Indexer         | AsyncI/syncany | FINE : Processed new database version: DatabaseVersion [header=UnknownMachine/()/T=1487233193896, chunks=1, multiChunks=1, fileContents=1, fileHistories=1]
16-2-17 8:19:53.901 | UpOperation     | Test worker    | INFO : No DIRTY data found in database (no dirty databases); Nothing to do here.
16-2-17 8:19:53.901 | UpOperation     | Test worker    | INFO : Last vector clock was: (A4,B1)
16-2-17 8:19:53.901 | UpOperation     | Test worker    | INFO : Uploading new multichunks ...
16-2-17 8:19:53.902 | UpOperation     | Test worker    | INFO : - Uploading multichunk 9f0e7bf0039b4fa94a773692869781e0b9012d16 from /tmp/syncanytest/syncany-170216081829395-47281-client-C/.syncany/cache/multichunk-9f0e7bf0039b4fa94a773692869781e0b9012d16 to RemoteFile[name=multichunk-9f0e7bf0039b4fa94a773692869781e0b9012d16] ...
16-2-17 8:19:53.902 | RemoteTransacti | Test worker    | INFO : - Adding file to TX for UPLOAD: /tmp/syncanytest/syncany-170216081829395-47281-client-C/.syncany/cache/multichunk-9f0e7bf0039b4fa94a773692869781e0b9012d16 -> Temp. remote file: RemoteFile[name=temp-gerGB-multichunk-9f0e7bf0039b4fa94a773692869781e0b9012d16], final location: RemoteFile[name=multichunk-9f0e7bf0039b4fa94a773692869781e0b9012d16]
16-2-17 8:19:53.902 | UpOperation     | Test worker    | INFO : Uploading database: DatabaseVersion [header=C/(A4,B1,C1)/T=1487233193901, chunks=1, multiChunks=1, fileContents=1, fileHistories=1]
16-2-17 8:19:53.902 | UpOperation     | Test worker    | INFO : Saving local delta database, version C/(A4,B1,C1)/T=1487233193901 to file /tmp/syncanytest/syncany-170216081829395-47281-client-C/.syncany/cache/database-C-0000000001 ... 
16-2-17 8:19:53.902 | UpOperation     | Test worker    | INFO : - Saving database to /tmp/syncanytest/syncany-170216081829395-47281-client-C/.syncany/cache/database-C-0000000001 ...
16-2-17 8:19:53.903 | UpOperation     | Test worker    | INFO : - Uploading local delta database file ...
16-2-17 8:19:53.903 | UpOperation     | Test worker    | INFO : - Uploading /tmp/syncanytest/syncany-170216081829395-47281-client-C/.syncany/cache/database-C-0000000001 to RemoteFile[name=database-C-0000000001] ...
16-2-17 8:19:53.903 | RemoteTransacti | Test worker    | INFO : - Adding file to TX for UPLOAD: /tmp/syncanytest/syncany-170216081829395-47281-client-C/.syncany/cache/database-C-0000000001 -> Temp. remote file: RemoteFile[name=temp-zxUcg-database-C-0000000001], final location: RemoteFile[name=database-C-0000000001]
16-2-17 8:19:53.903 | UpOperation     | Test worker    | INFO : Adding shutdown hook (to allow resuming the upload) ...
16-2-17 8:19:53.903 | RemoteTransacti | Test worker    | INFO : Starting TX.commit() ...
16-2-17 8:19:53.904 | RemoteTransacti | Test worker    | INFO : Wrote transaction manifest to temporary file: /tmp/syncanytest/syncany-170216081829395-47281-client-C/.syncany/cache/temp-transaction-3891087261636335019.tmp
16-2-17 8:19:53.904 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event UpUploadFileSyncExternalEvent (org.syncany.operations.daemon.messages.UpUploadFileSyncExternalEvent@4f0155f0) ...
16-2-17 8:19:53.904 | RemoteTransacti | Test worker    | INFO : - Uploading remote transaction file RemoteFile[name=transaction-efd427e] ...
16-2-17 8:19:53.905 | RemoteTransacti | Test worker    | INFO : - Starting to upload data in commit.
16-2-17 8:19:53.905 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event UpUploadFileInTransactionSyncExternalEvent (org.syncany.operations.daemon.messages.UpUploadFileInTransactionSyncExternalEvent@6e9b4075) ...
16-2-17 8:19:53.905 | RemoteTransacti | Test worker    | INFO : - Uploading /tmp/syncanytest/syncany-170216081829395-47281-client-C/.syncany/cache/multichunk-9f0e7bf0039b4fa94a773692869781e0b9012d16 to temp. file RemoteFile[name=temp-gerGB-multichunk-9f0e7bf0039b4fa94a773692869781e0b9012d16] ...
16-2-17 8:19:53.905 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event UpUploadFileInTransactionSyncExternalEvent (org.syncany.operations.daemon.messages.UpUploadFileInTransactionSyncExternalEvent@65349f6b) ...
16-2-17 8:19:53.905 | RemoteTransacti | Test worker    | INFO : - Uploading /tmp/syncanytest/syncany-170216081829395-47281-client-C/.syncany/cache/database-C-0000000001 to temp. file RemoteFile[name=temp-zxUcg-database-C-0000000001] ...
16-2-17 8:19:53.906 | RemoteTransacti | Test worker    | INFO : - Moving temp. file RemoteFile[name=temp-gerGB-multichunk-9f0e7bf0039b4fa94a773692869781e0b9012d16] to final location RemoteFile[name=multichunk-9f0e7bf0039b4fa94a773692869781e0b9012d16] ...
16-2-17 8:19:53.906 | RemoteTransacti | Test worker    | INFO : - Moving temp. file RemoteFile[name=temp-zxUcg-database-C-0000000001] to final location RemoteFile[name=database-C-0000000001] ...
16-2-17 8:19:53.906 | RemoteTransacti | Test worker    | INFO : - Deleting remote transaction file RemoteFile[name=transaction-efd427e] ...
16-2-17 8:19:53.906 | RemoteTransacti | Test worker    | INFO : END of TX.commmit(): Succesfully committed transaction.
16-2-17 8:19:53.906 | RemoteTransacti | Test worker    | INFO : END of TX.delTemp(): Sucessfully deleted final files.
16-2-17 8:19:53.906 | UpOperation     | Test worker    | INFO : Persisting local SQL database (new database version C/(A4,B1,C1)/T=1487233193901) ...
16-2-17 8:19:53.908 | UpOperation     | Test worker    | INFO : Removing DIRTY database versions from database ...
16-2-17 8:19:53.908 | InternalEventBu | AsyncI/syncany | INFO : Event bus 'LocalEventBus': Posting event UpIndexMidSyncExternalEvent (org.syncany.operations.daemon.messages.UpIndexMidSyncExternalEvent@43846ec9) ...
16-2-17 8:19:53.913 | UpOperation     | Test worker    | INFO : Adding database version to result changes:DatabaseVersion [header=C/(A4,B1,C1)/T=1487233193901, chunks=1, multiChunks=1, fileContents=1, fileHistories=1]
16-2-17 8:19:53.913 | UpOperation     | Test worker    | INFO : Committing local database.
16-2-17 8:19:53.913 | UpOperation     | Test worker    | FINE : Waiting for new database version.
16-2-17 8:19:53.916 | AsyncIndexer    | AsyncI/syncany | INFO : Stopping indexing. Signal end of stream with empty databaseversion
16-2-17 8:19:53.916 | UpOperation     | Test worker    | FINE : Took new database version: DatabaseVersion [header=UnknownMachine/()/T=1487233193916, chunks=0, multiChunks=0, fileContents=0, fileHistories=0]
16-2-17 8:19:53.916 | UpOperation     | Test worker    | INFO : Sync up done.
16-2-17 8:19:53.920 | ActionFileHandl | Test worker    | INFO : Finishing action for RemoteFile[name=action-up-C-1487233193891] ...
16-2-17 8:19:53.920 | ActionFileHandl | Test worker    | INFO : Deleting action file: RemoteFile[name=action-up-C-1487233193891]
16-2-17 8:19:53.920 | AbstractTransfe | Test worker    | INFO : Cleaning own old action files ...
16-2-17 8:19:53.920 | Cache           | Test worker    | INFO : Cache size okay (207 KB), no need to clean (keep size is 500 MB)
16-2-17 8:19:53.920 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event UpEndSyncExternalEvent (org.syncany.operations.daemon.messages.UpEndSyncExternalEvent@660e1961) ...
16-2-17 8:19:53.920 | org.syncany.plu | Test worker    | FINE : Validating required fields
16-2-17 8:19:53.920 | TransferManager | Test worker    | INFO : Building LocalTransferManager from config 'syncany-170216081829395-47281-client-A' ...
16-2-17 8:19:53.921 | TransferManager | Test worker    | INFO : - With feature ReadAfterWriteConsistent
16-2-17 8:19:53.921 | TransferManager | Test worker    | INFO : - With feature Retriable
16-2-17 8:19:53.921 | TransferManager | Test worker    | FINE : - Checking if selected features supported ...
16-2-17 8:19:53.921 | TransferManager | Test worker    | FINE : - Checking for duplicate features ...
16-2-17 8:19:53.921 | TransferManager | Test worker    | FINE : - Checking required feature TransactionAware in LocalTransferManager ...
16-2-17 8:19:53.921 | TransferManager | Test worker    | FINE : - Checking required feature Retriable in LocalTransferManager ...
16-2-17 8:19:53.921 | TransferManager | Test worker    | INFO : - SKIPPING unsupported optional feature ReadAfterWriteConsistent
16-2-17 8:19:53.921 | TransferManager | Test worker    | FINE : - Wrapping TransferManager LocalTransferManager in RetriableFeatureTransferManager
16-2-17 8:19:53.921 | org.syncany.plu | Test worker    | FINE : Validating required fields
16-2-17 8:19:53.921 | TransferManager | Test worker    | INFO : Building LocalTransferManager from config 'syncany-170216081829395-47281-client-A' ...
16-2-17 8:19:53.921 | TransferManager | Test worker    | INFO : - With feature ReadAfterWriteConsistent
16-2-17 8:19:53.921 | TransferManager | Test worker    | INFO : - With feature Retriable
16-2-17 8:19:53.921 | TransferManager | Test worker    | INFO : - With feature PathAware
16-2-17 8:19:53.921 | TransferManager | Test worker    | INFO : - With feature TransactionAware
16-2-17 8:19:53.921 | TransferManager | Test worker    | FINE : - Checking if selected features supported ...
16-2-17 8:19:53.921 | TransferManager | Test worker    | FINE : - Checking for duplicate features ...
16-2-17 8:19:53.921 | TransferManager | Test worker    | FINE : - Checking required feature TransactionAware in LocalTransferManager ...
16-2-17 8:19:53.921 | TransferManager | Test worker    | FINE : - Checking required feature Retriable in LocalTransferManager ...
16-2-17 8:19:53.921 | TransferManager | Test worker    | INFO : - SKIPPING unsupported optional feature ReadAfterWriteConsistent
16-2-17 8:19:53.921 | TransferManager | Test worker    | FINE : - Wrapping TransferManager LocalTransferManager in RetriableFeatureTransferManager
16-2-17 8:19:53.921 | TransferManager | Test worker    | INFO : - SKIPPING unsupported optional feature PathAware
16-2-17 8:19:53.921 | TransferManager | Test worker    | FINE : - Wrapping TransferManager RetriableFeatureTransferManager in TransactionAwareFeatureTransferManager
16-2-17 8:19:53.925 | DatabaseConnect | Test worker    | INFO : Found 12 tables.
16-2-17 8:19:53.925 | DownOperation   | Test worker    | INFO : 
16-2-17 8:19:53.925 | DownOperation   | Test worker    | INFO : Running 'Sync down' at client A ...
16-2-17 8:19:53.925 | DownOperation   | Test worker    | INFO : --------------------------------------------
16-2-17 8:19:53.925 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event DownStartSyncExternalEvent (org.syncany.operations.daemon.messages.DownStartSyncExternalEvent@747895fc) ...
16-2-17 8:19:53.925 | AbstractTransfe | Test worker    | INFO : Looking for other running remote operations ...
16-2-17 8:19:53.925 | DatabaseConnect | Test worker    | INFO : Found 12 tables.
16-2-17 8:19:53.926 | LsRemoteOperati | Test worker    | INFO : 
16-2-17 8:19:53.926 | LsRemoteOperati | Test worker    | INFO : Running 'Remote Status' at client A ...
16-2-17 8:19:53.926 | LsRemoteOperati | Test worker    | INFO : --------------------------------------------
16-2-17 8:19:53.926 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event LsRemoteStartSyncExternalEvent (org.syncany.operations.daemon.messages.LsRemoteStartSyncExternalEvent@6648d9e3) ...
16-2-17 8:19:53.926 | LsRemoteOperati | Test worker    | INFO : Retrieving remote database list.
16-2-17 8:19:53.926 | LsRemoteOperati | Test worker    | INFO : - Remote database database-A-0000000004 is already known (in local database). Ignoring.
16-2-17 8:19:53.926 | LsRemoteOperati | Test worker    | INFO : - Remote database database-A-0000000002 is already known (in local database). Ignoring.
16-2-17 8:19:53.926 | LsRemoteOperati | Test worker    | INFO : - Remote database database-A-0000000003 is already known (in local database). Ignoring.
16-2-17 8:19:53.926 | LsRemoteOperati | Test worker    | INFO : - Remote database database-C-0000000001 is new.
16-2-17 8:19:53.926 | LsRemoteOperati | Test worker    | INFO : - Remote database database-B-0000000001 is already known (in local database). Ignoring.
16-2-17 8:19:53.926 | LsRemoteOperati | Test worker    | INFO : - Remote database database-A-0000000001 is already known (in local database). Ignoring.
16-2-17 8:19:53.926 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event LsRemoteEndSyncExternalEvent (org.syncany.operations.daemon.messages.LsRemoteEndSyncExternalEvent@23a2fdb9) ...
16-2-17 8:19:53.926 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event DownChangesDetectedSyncExternalEvent (org.syncany.operations.daemon.messages.DownChangesDetectedSyncExternalEvent@b0d6dde) ...
16-2-17 8:19:53.926 | ActionFileHandl | Test worker    | INFO : Starting action for RemoteFile[name=action-down-A-1487233193921] ...
16-2-17 8:19:53.926 | ActionFileHandl | Test worker    | INFO : Uploading action file: RemoteFile[name=action-down-A-1487233193921]
16-2-17 8:19:53.927 | ActionFileHandl | Test worker    | INFO : Scheduling action renewal task for every 2 minutes, for RemoteFile[name=action-down-A-1487233193921] ...
16-2-17 8:19:53.927 | DownOperation   | Test worker    | INFO : Downloading unknown databases.
16-2-17 8:19:53.927 | DownOperation   | Test worker    | INFO : - Downloading database-C-0000000001 to local cache at /tmp/syncanytest/syncany-170216081829395-47281-client-A/.syncany/cache/database-C-0000000001
16-2-17 8:19:53.927 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event DownDownloadFileSyncExternalEvent (org.syncany.operations.daemon.messages.DownDownloadFileSyncExternalEvent@26c76566) ...
16-2-17 8:19:53.927 | DownOperation   | Test worker    | INFO : Loading database headers, creating branches ...
16-2-17 8:19:53.927 | DatabaseXmlSeri | Test worker    | INFO : - Loading database (HEADER_ONLY) from file /tmp/syncanytest/syncany-170216081829395-47281-client-A/.syncany/cache/database-C-0000000001 ...
16-2-17 8:19:53.929 | DatabaseXmlPars | Test worker    | INFO :    + Added database version C/(A4,B1,C1)/T=1487233193901
16-2-17 8:19:53.929 | LocalTransferMa | Test worker    | INFO : Cannot create instance of CleanupRemoteFile for file /tmp/syncanytest/syncany-170216081829395-47281-repo/databases/database-C-0000000001; maybe invalid file name pattern. Ignoring file.
16-2-17 8:19:53.929 | LocalTransferMa | Test worker    | INFO : Cannot create instance of CleanupRemoteFile for file /tmp/syncanytest/syncany-170216081829395-47281-repo/databases/database-B-0000000001; maybe invalid file name pattern. Ignoring file.
16-2-17 8:19:53.929 | LocalTransferMa | Test worker    | INFO : Cannot create instance of CleanupRemoteFile for file /tmp/syncanytest/syncany-170216081829395-47281-repo/databases/database-A-0000000004; maybe invalid file name pattern. Ignoring file.
16-2-17 8:19:53.929 | LocalTransferMa | Test worker    | INFO : Cannot create instance of CleanupRemoteFile for file /tmp/syncanytest/syncany-170216081829395-47281-repo/databases/database-A-0000000002; maybe invalid file name pattern. Ignoring file.
16-2-17 8:19:53.929 | LocalTransferMa | Test worker    | INFO : Cannot create instance of CleanupRemoteFile for file /tmp/syncanytest/syncany-170216081829395-47281-repo/databases/database-A-0000000001; maybe invalid file name pattern. Ignoring file.
16-2-17 8:19:53.930 | LocalTransferMa | Test worker    | INFO : Cannot create instance of CleanupRemoteFile for file /tmp/syncanytest/syncany-170216081829395-47281-repo/databases/database-A-0000000003; maybe invalid file name pattern. Ignoring file.
16-2-17 8:19:53.930 | ApplicationSqlD | Test worker    | INFO : SQL (general_settings): Read cleanupNumber  = (not set)
16-2-17 8:19:53.930 | DownOperation   | Test worker    | INFO : Populated unknown branches: {A=[A/(A1)/T=1487233193391, A/(A2)/T=1487233193425, A/(A3)/T=1487233193455, A/(A4)/T=1487233193490, B/(A4,B1)/T=1487233193657], C=[C/(A4,B1,C1)/T=1487233193901]}
16-2-17 8:19:53.930 | DownOperation   | Test worker    | INFO : Determine winner using database reconciliator ...
16-2-17 8:19:53.930 | DatabaseReconci | Test worker    | INFO : Adding database version to winning branch: A/(A1)/T=1487233193391
16-2-17 8:19:53.930 | DatabaseReconci | Test worker    | INFO : Adding database version to winning branch: A/(A2)/T=1487233193425
16-2-17 8:19:53.930 | DatabaseReconci | Test worker    | INFO : Adding database version to winning branch: A/(A3)/T=1487233193455
16-2-17 8:19:53.930 | DatabaseReconci | Test worker    | INFO : Adding database version to winning branch: A/(A4)/T=1487233193490
16-2-17 8:19:53.930 | DatabaseReconci | Test worker    | INFO : Adding database version to winning branch: B/(A4,B1)/T=1487233193657
16-2-17 8:19:53.930 | DatabaseReconci | Test worker    | INFO : Adding database version to winning branch: C/(A4,B1,C1)/T=1487233193901
16-2-17 8:19:53.930 | DatabaseReconci | Test worker    | INFO : - Winner is C with branch: 
16-2-17 8:19:53.930 | DatabaseReconci | Test worker    | INFO :   + A/(A1)/T=1487233193391
16-2-17 8:19:53.930 | DatabaseReconci | Test worker    | INFO :   + A/(A2)/T=1487233193425
16-2-17 8:19:53.930 | DatabaseReconci | Test worker    | INFO :   + A/(A3)/T=1487233193455
16-2-17 8:19:53.930 | DatabaseReconci | Test worker    | INFO :   + A/(A4)/T=1487233193490
16-2-17 8:19:53.930 | DatabaseReconci | Test worker    | INFO :   + B/(A4,B1)/T=1487233193657
16-2-17 8:19:53.930 | DatabaseReconci | Test worker    | INFO :   + C/(A4,B1,C1)/T=1487233193901
16-2-17 8:19:53.931 | DownOperation   | Test worker    | INFO : - Database versions to REMOVE locally: []
16-2-17 8:19:53.931 | DownOperation   | Test worker    | INFO :   + Nothing to purge locally. No conflicts. Only updates. Nice!
16-2-17 8:19:53.931 | DatabaseReconci | Test worker    | INFO : Finding winnersApplyBranch.
16-2-17 8:19:53.931 | DatabaseReconci | Test worker    | INFO : Losers Branch: [A/(A1)/T=1487233193391, A/(A2)/T=1487233193425, A/(A3)/T=1487233193455, A/(A4)/T=1487233193490, B/(A4,B1)/T=1487233193657]
16-2-17 8:19:53.931 | DatabaseReconci | Test worker    | INFO : Winners Branch: [A/(A1)/T=1487233193391, A/(A2)/T=1487233193425, A/(A3)/T=1487233193455, A/(A4)/T=1487233193490, B/(A4,B1)/T=1487233193657, C/(A4,B1,C1)/T=1487233193901]
16-2-17 8:19:53.931 | DownOperation   | Test worker    | INFO : - Cleanup occurred: false
16-2-17 8:19:53.931 | DownOperation   | Test worker    | INFO : - Database versions to APPLY locally: [C/(A4,B1,C1)/T=1487233193901]
16-2-17 8:19:53.931 | DownOperation   | Test worker    | INFO : Loading winners database (DEFAULT) ...
16-2-17 8:19:53.931 | DatabaseXmlSeri | Test worker    | INFO : - Loading database (FULL) from file /tmp/syncanytest/syncany-170216081829395-47281-client-A/.syncany/cache/database-C-0000000001 ...
16-2-17 8:19:53.932 | DatabaseXmlPars | Test worker    | INFO :    + Added database version C/(A4,B1,C1)/T=1487233193901
16-2-17 8:19:53.932 | DownOperation   | Test worker    | INFO : Determine file system actions ...
16-2-17 8:19:53.933 | DatabaseConnect | Test worker    | INFO : Found 12 tables.
16-2-17 8:19:53.933 | FileSystemActio | Test worker    | INFO : - Loading current file tree...
16-2-17 8:19:53.933 | FileSystemActio | Test worker    | INFO : - Determine filesystem actions ...
16-2-17 8:19:53.933 | FileSystemActio | Test worker    | INFO :   + Comparing local version: null
16-2-17 8:19:53.933 | FileSystemActio | Test worker    | INFO :     with winning version   : FileVersion [version=1, path=C1, type=FILE, status=NEW, size=51200, lastModified=Thu Feb 16 08:19:53 UTC 2017, linkTarget=null, checksum=6c7a1313af77e516ae80f6e3f4233b98d8e26ea7, updated=Thu Feb 16 08:19:53 UTC 2017, posixPermissions=rw-rw-r--, dosAttributes=--a-]
16-2-17 8:19:53.934 | FileVersionComp | Test worker    | INFO :      - [DELETED]: Local file DIFFERS from file version, expected EXISTS = true, but actual EXISTS = false, for file C1
16-2-17 8:19:53.934 | FileSystemActio | Test worker    | INFO :      -> (2) Deleted: Local file does NOT exist, but it should, winning version not known: FileVersion [version=1, path=C1, type=FILE, status=NEW, size=51200, lastModified=Thu Feb 16 08:19:53 UTC 2017, linkTarget=null, checksum=6c7a1313af77e516ae80f6e3f4233b98d8e26ea7, updated=Thu Feb 16 08:19:53 UTC 2017, posixPermissions=rw-rw-r--, dosAttributes=--a-] AND /tmp/syncanytest/syncany-170216081829395-47281-client-A/C1
16-2-17 8:19:53.934 | FileSystemActio | Test worker    | INFO :      -> NewFileSystemAction [file1=null, file2=FileVersion [version=1, path=C1, type=FILE, status=NEW, size=51200, lastModified=Thu Feb 16 08:19:53 UTC 2017, linkTarget=null, checksum=6c7a1313af77e516ae80f6e3f4233b98d8e26ea7, updated=Thu Feb 16 08:19:53 UTC 2017, posixPermissions=rw-rw-r--, dosAttributes=--a-]]
16-2-17 8:19:53.934 | DownOperation   | Test worker    | INFO :   + Adding multichunk 9f0e7bf0039b4fa94a773692869781e0b9012d16 to download list ...
16-2-17 8:19:53.934 | Downloader      | Test worker    | INFO : Downloading and extracting multichunks ...
16-2-17 8:19:53.934 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event DownDownloadFileSyncExternalEvent (org.syncany.operations.daemon.messages.DownDownloadFileSyncExternalEvent@3177176f) ...
16-2-17 8:19:53.934 | Downloader      | Test worker    | INFO :   + Downloading multichunk 9f0e7bf0039b4fa94a773692869781e0b9012d16 ...
16-2-17 8:19:53.935 | Downloader      | Test worker    | INFO :   + Decrypting multichunk 9f0e7bf0039b4fa94a773692869781e0b9012d16 ...
16-2-17 8:19:53.935 | Downloader      | Test worker    | FINE :   + Locally deleting multichunk 9f0e7bf0039b4fa94a773692869781e0b9012d16 ...
16-2-17 8:19:53.936 | FileSystemActio | Test worker    | INFO :    Sorted actions:
16-2-17 8:19:53.936 | FileSystemActio | Test worker    | INFO :    + NewFileSystemAction [file1=null, file2=FileVersion [version=1, path=C1, type=FILE, status=NEW, size=51200, lastModified=Thu Feb 16 08:19:53 UTC 2017, linkTarget=null, checksum=6c7a1313af77e516ae80f6e3f4233b98d8e26ea7, updated=Thu Feb 16 08:19:53 UTC 2017, posixPermissions=rw-rw-r--, dosAttributes=--a-]]
16-2-17 8:19:53.936 | DownOperation   | Test worker    | FINE : - Applying file system actions (sorted!) ...
16-2-17 8:19:53.936 | DownOperation   | Test worker    | FINE :    +  NewFileSystemAction [file1=null, file2=FileVersion [version=1, path=C1, type=FILE, status=NEW, size=51200, lastModified=Thu Feb 16 08:19:53 UTC 2017, linkTarget=null, checksum=6c7a1313af77e516ae80f6e3f4233b98d8e26ea7, updated=Thu Feb 16 08:19:53 UTC 2017, posixPermissions=rw-rw-r--, dosAttributes=--a-]]
16-2-17 8:19:53.936 | Assembler       | Test worker    | INFO :      - Creating file C1 to /tmp/syncanytest/syncany-170216081829395-47281-client-A/.syncany/cache/temp-reconstructedFileVersion-8136498650594955863.tmp ...
16-2-17 8:19:53.937 | FileSystemActio | Test worker    | INFO :      - Setting POSIX permissions: rw-rw-r-- ...
16-2-17 8:19:53.937 | DownOperation   | Test worker    | INFO : - Adding database versions to SQL database ...
16-2-17 8:19:53.938 | DownOperation   | Test worker    | INFO :   + Applying database version (A4,B1,C1)
16-2-17 8:19:53.938 | DownOperation   | Test worker    | FINE :   + Contents: DatabaseVersion [header=C/(A4,B1,C1)/T=1487233193901, chunks=1, multiChunks=1, fileContents=1, fileHistories=1]
16-2-17 8:19:53.944 | ActionFileHandl | Test worker    | INFO : Finishing action for RemoteFile[name=action-down-A-1487233193921] ...
16-2-17 8:19:53.944 | ActionFileHandl | Test worker    | INFO : Deleting action file: RemoteFile[name=action-down-A-1487233193921]
16-2-17 8:19:53.944 | AbstractTransfe | Test worker    | INFO : Cleaning own old action files ...
16-2-17 8:19:53.944 | Cache           | Test worker    | INFO : Cache size okay (257 KB), no need to clean (keep size is 500 MB)
16-2-17 8:19:53.944 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event DownEndSyncExternalEvent (org.syncany.operations.daemon.messages.DownEndSyncExternalEvent@5fb24129) ...
16-2-17 8:19:53.944 | DownOperation   | Test worker    | INFO : Sync down done.
16-2-17 8:19:53.944 | org.syncany.plu | Test worker    | FINE : Validating required fields
16-2-17 8:19:53.945 | TransferManager | Test worker    | INFO : Building LocalTransferManager from config 'syncany-170216081829395-47281-client-B' ...
16-2-17 8:19:53.945 | TransferManager | Test worker    | INFO : - With feature ReadAfterWriteConsistent
16-2-17 8:19:53.945 | TransferManager | Test worker    | INFO : - With feature Retriable
16-2-17 8:19:53.945 | TransferManager | Test worker    | FINE : - Checking if selected features supported ...
16-2-17 8:19:53.945 | TransferManager | Test worker    | FINE : - Checking for duplicate features ...
16-2-17 8:19:53.945 | TransferManager | Test worker    | FINE : - Checking required feature TransactionAware in LocalTransferManager ...
16-2-17 8:19:53.945 | TransferManager | Test worker    | FINE : - Checking required feature Retriable in LocalTransferManager ...
16-2-17 8:19:53.945 | TransferManager | Test worker    | INFO : - SKIPPING unsupported optional feature ReadAfterWriteConsistent
16-2-17 8:19:53.945 | TransferManager | Test worker    | FINE : - Wrapping TransferManager LocalTransferManager in RetriableFeatureTransferManager
16-2-17 8:19:53.945 | org.syncany.plu | Test worker    | FINE : Validating required fields
16-2-17 8:19:53.945 | TransferManager | Test worker    | INFO : Building LocalTransferManager from config 'syncany-170216081829395-47281-client-B' ...
16-2-17 8:19:53.945 | TransferManager | Test worker    | INFO : - With feature ReadAfterWriteConsistent
16-2-17 8:19:53.945 | TransferManager | Test worker    | INFO : - With feature Retriable
16-2-17 8:19:53.945 | TransferManager | Test worker    | INFO : - With feature PathAware
16-2-17 8:19:53.945 | TransferManager | Test worker    | INFO : - With feature TransactionAware
16-2-17 8:19:53.945 | TransferManager | Test worker    | FINE : - Checking if selected features supported ...
16-2-17 8:19:53.945 | TransferManager | Test worker    | FINE : - Checking for duplicate features ...
16-2-17 8:19:53.945 | TransferManager | Test worker    | FINE : - Checking required feature TransactionAware in LocalTransferManager ...
16-2-17 8:19:53.945 | TransferManager | Test worker    | FINE : - Checking required feature Retriable in LocalTransferManager ...
16-2-17 8:19:53.945 | TransferManager | Test worker    | INFO : - SKIPPING unsupported optional feature ReadAfterWriteConsistent
16-2-17 8:19:53.945 | TransferManager | Test worker    | FINE : - Wrapping TransferManager LocalTransferManager in RetriableFeatureTransferManager
16-2-17 8:19:53.945 | TransferManager | Test worker    | INFO : - SKIPPING unsupported optional feature PathAware
16-2-17 8:19:53.945 | TransferManager | Test worker    | FINE : - Wrapping TransferManager RetriableFeatureTransferManager in TransactionAwareFeatureTransferManager
16-2-17 8:19:53.946 | DatabaseConnect | Test worker    | INFO : Found 12 tables.
16-2-17 8:19:53.946 | DownOperation   | Test worker    | INFO : 
16-2-17 8:19:53.946 | DownOperation   | Test worker    | INFO : Running 'Sync down' at client B ...
16-2-17 8:19:53.946 | DownOperation   | Test worker    | INFO : --------------------------------------------
16-2-17 8:19:53.946 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event DownStartSyncExternalEvent (org.syncany.operations.daemon.messages.DownStartSyncExternalEvent@1af0e238) ...
16-2-17 8:19:53.946 | AbstractTransfe | Test worker    | INFO : Looking for other running remote operations ...
16-2-17 8:19:53.947 | DatabaseConnect | Test worker    | INFO : Found 12 tables.
16-2-17 8:19:53.947 | LsRemoteOperati | Test worker    | INFO : 
16-2-17 8:19:53.947 | LsRemoteOperati | Test worker    | INFO : Running 'Remote Status' at client B ...
16-2-17 8:19:53.947 | LsRemoteOperati | Test worker    | INFO : --------------------------------------------
16-2-17 8:19:53.947 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event LsRemoteStartSyncExternalEvent (org.syncany.operations.daemon.messages.LsRemoteStartSyncExternalEvent@6a316f88) ...
16-2-17 8:19:53.947 | LsRemoteOperati | Test worker    | INFO : Retrieving remote database list.
16-2-17 8:19:53.947 | LsRemoteOperati | Test worker    | INFO : - Remote database database-A-0000000004 is already known (in local database). Ignoring.
16-2-17 8:19:53.947 | LsRemoteOperati | Test worker    | INFO : - Remote database database-A-0000000002 is already known (in local database). Ignoring.
16-2-17 8:19:53.947 | LsRemoteOperati | Test worker    | INFO : - Remote database database-A-0000000003 is already known (in local database). Ignoring.
16-2-17 8:19:53.947 | LsRemoteOperati | Test worker    | INFO : - Remote database database-C-0000000001 is new.
16-2-17 8:19:53.947 | LsRemoteOperati | Test worker    | INFO : - Remote database database-B-0000000001 is already known (in local database). Ignoring.
16-2-17 8:19:53.947 | LsRemoteOperati | Test worker    | INFO : - Remote database database-A-0000000001 is already known (in local database). Ignoring.
16-2-17 8:19:53.947 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event LsRemoteEndSyncExternalEvent (org.syncany.operations.daemon.messages.LsRemoteEndSyncExternalEvent@1a1b2b1b) ...
16-2-17 8:19:53.947 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event DownChangesDetectedSyncExternalEvent (org.syncany.operations.daemon.messages.DownChangesDetectedSyncExternalEvent@38fecd1) ...
16-2-17 8:19:53.947 | ActionFileHandl | Test worker    | INFO : Starting action for RemoteFile[name=action-down-B-1487233193945] ...
16-2-17 8:19:53.947 | ActionFileHandl | Test worker    | INFO : Uploading action file: RemoteFile[name=action-down-B-1487233193945]
16-2-17 8:19:53.947 | ActionFileHandl | Test worker    | INFO : Scheduling action renewal task for every 2 minutes, for RemoteFile[name=action-down-B-1487233193945] ...
16-2-17 8:19:53.948 | DownOperation   | Test worker    | INFO : Downloading unknown databases.
16-2-17 8:19:53.948 | DownOperation   | Test worker    | INFO : - Downloading database-C-0000000001 to local cache at /tmp/syncanytest/syncany-170216081829395-47281-client-B/.syncany/cache/database-C-0000000001
16-2-17 8:19:53.948 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event DownDownloadFileSyncExternalEvent (org.syncany.operations.daemon.messages.DownDownloadFileSyncExternalEvent@193b8901) ...
16-2-17 8:19:53.948 | DownOperation   | Test worker    | INFO : Loading database headers, creating branches ...
16-2-17 8:19:53.948 | DatabaseXmlSeri | Test worker    | INFO : - Loading database (HEADER_ONLY) from file /tmp/syncanytest/syncany-170216081829395-47281-client-B/.syncany/cache/database-C-0000000001 ...
16-2-17 8:19:53.949 | DatabaseXmlPars | Test worker    | INFO :    + Added database version C/(A4,B1,C1)/T=1487233193901
16-2-17 8:19:53.950 | LocalTransferMa | Test worker    | INFO : Cannot create instance of CleanupRemoteFile for file /tmp/syncanytest/syncany-170216081829395-47281-repo/databases/database-C-0000000001; maybe invalid file name pattern. Ignoring file.
16-2-17 8:19:53.950 | LocalTransferMa | Test worker    | INFO : Cannot create instance of CleanupRemoteFile for file /tmp/syncanytest/syncany-170216081829395-47281-repo/databases/database-B-0000000001; maybe invalid file name pattern. Ignoring file.
16-2-17 8:19:53.950 | LocalTransferMa | Test worker    | INFO : Cannot create instance of CleanupRemoteFile for file /tmp/syncanytest/syncany-170216081829395-47281-repo/databases/database-A-0000000004; maybe invalid file name pattern. Ignoring file.
16-2-17 8:19:53.950 | LocalTransferMa | Test worker    | INFO : Cannot create instance of CleanupRemoteFile for file /tmp/syncanytest/syncany-170216081829395-47281-repo/databases/database-A-0000000002; maybe invalid file name pattern. Ignoring file.
16-2-17 8:19:53.950 | LocalTransferMa | Test worker    | INFO : Cannot create instance of CleanupRemoteFile for file /tmp/syncanytest/syncany-170216081829395-47281-repo/databases/database-A-0000000001; maybe invalid file name pattern. Ignoring file.
16-2-17 8:19:53.950 | LocalTransferMa | Test worker    | INFO : Cannot create instance of CleanupRemoteFile for file /tmp/syncanytest/syncany-170216081829395-47281-repo/databases/database-A-0000000003; maybe invalid file name pattern. Ignoring file.
16-2-17 8:19:53.950 | ApplicationSqlD | Test worker    | INFO : SQL (general_settings): Read cleanupNumber  = (not set)
16-2-17 8:19:53.950 | DownOperation   | Test worker    | INFO : Populated unknown branches: {B=[A/(A1)/T=1487233193391, A/(A2)/T=1487233193425, A/(A3)/T=1487233193455, A/(A4)/T=1487233193490, B/(A4,B1)/T=1487233193657], C=[C/(A4,B1,C1)/T=1487233193901]}
16-2-17 8:19:53.951 | DownOperation   | Test worker    | INFO : Determine winner using database reconciliator ...
16-2-17 8:19:53.951 | DatabaseReconci | Test worker    | INFO : Adding database version to winning branch: A/(A1)/T=1487233193391
16-2-17 8:19:53.951 | DatabaseReconci | Test worker    | INFO : Adding database version to winning branch: A/(A2)/T=1487233193425
16-2-17 8:19:53.951 | DatabaseReconci | Test worker    | INFO : Adding database version to winning branch: A/(A3)/T=1487233193455
16-2-17 8:19:53.951 | DatabaseReconci | Test worker    | INFO : Adding database version to winning branch: A/(A4)/T=1487233193490
16-2-17 8:19:53.951 | DatabaseReconci | Test worker    | INFO : Adding database version to winning branch: B/(A4,B1)/T=1487233193657
16-2-17 8:19:53.951 | DatabaseReconci | Test worker    | INFO : Adding database version to winning branch: C/(A4,B1,C1)/T=1487233193901
16-2-17 8:19:53.951 | DatabaseReconci | Test worker    | INFO : - Winner is C with branch: 
16-2-17 8:19:53.951 | DatabaseReconci | Test worker    | INFO :   + A/(A1)/T=1487233193391
16-2-17 8:19:53.951 | DatabaseReconci | Test worker    | INFO :   + A/(A2)/T=1487233193425
16-2-17 8:19:53.951 | DatabaseReconci | Test worker    | INFO :   + A/(A3)/T=1487233193455
16-2-17 8:19:53.951 | DatabaseReconci | Test worker    | INFO :   + A/(A4)/T=1487233193490
16-2-17 8:19:53.951 | DatabaseReconci | Test worker    | INFO :   + B/(A4,B1)/T=1487233193657
16-2-17 8:19:53.951 | DatabaseReconci | Test worker    | INFO :   + C/(A4,B1,C1)/T=1487233193901
16-2-17 8:19:53.951 | DownOperation   | Test worker    | INFO : - Database versions to REMOVE locally: []
16-2-17 8:19:53.951 | DownOperation   | Test worker    | INFO :   + Nothing to purge locally. No conflicts. Only updates. Nice!
16-2-17 8:19:53.951 | DatabaseReconci | Test worker    | INFO : Finding winnersApplyBranch.
16-2-17 8:19:53.951 | DatabaseReconci | Test worker    | INFO : Losers Branch: [A/(A1)/T=1487233193391, A/(A2)/T=1487233193425, A/(A3)/T=1487233193455, A/(A4)/T=1487233193490, B/(A4,B1)/T=1487233193657]
16-2-17 8:19:53.951 | DatabaseReconci | Test worker    | INFO : Winners Branch: [A/(A1)/T=1487233193391, A/(A2)/T=1487233193425, A/(A3)/T=1487233193455, A/(A4)/T=1487233193490, B/(A4,B1)/T=1487233193657, C/(A4,B1,C1)/T=1487233193901]
16-2-17 8:19:53.951 | DownOperation   | Test worker    | INFO : - Cleanup occurred: false
16-2-17 8:19:53.951 | DownOperation   | Test worker    | INFO : - Database versions to APPLY locally: [C/(A4,B1,C1)/T=1487233193901]
16-2-17 8:19:53.951 | DownOperation   | Test worker    | INFO : Loading winners database (DEFAULT) ...
16-2-17 8:19:53.951 | DatabaseXmlSeri | Test worker    | INFO : - Loading database (FULL) from file /tmp/syncanytest/syncany-170216081829395-47281-client-B/.syncany/cache/database-C-0000000001 ...
16-2-17 8:19:53.952 | DatabaseXmlPars | Test worker    | INFO :    + Added database version C/(A4,B1,C1)/T=1487233193901
16-2-17 8:19:53.953 | DownOperation   | Test worker    | INFO : Determine file system actions ...
16-2-17 8:19:53.953 | DatabaseConnect | Test worker    | INFO : Found 12 tables.
16-2-17 8:19:53.954 | FileSystemActio | Test worker    | INFO : - Loading current file tree...
16-2-17 8:19:53.954 | FileSystemActio | Test worker    | INFO : - Determine filesystem actions ...
16-2-17 8:19:53.954 | FileSystemActio | Test worker    | INFO :   + Comparing local version: null
16-2-17 8:19:53.954 | FileSystemActio | Test worker    | INFO :     with winning version   : FileVersion [version=1, path=C1, type=FILE, status=NEW, size=51200, lastModified=Thu Feb 16 08:19:53 UTC 2017, linkTarget=null, checksum=6c7a1313af77e516ae80f6e3f4233b98d8e26ea7, updated=Thu Feb 16 08:19:53 UTC 2017, posixPermissions=rw-rw-r--, dosAttributes=--a-]
16-2-17 8:19:53.954 | FileVersionComp | Test worker    | INFO :      - [DELETED]: Local file DIFFERS from file version, expected EXISTS = true, but actual EXISTS = false, for file C1
16-2-17 8:19:53.954 | FileSystemActio | Test worker    | INFO :      -> (2) Deleted: Local file does NOT exist, but it should, winning version not known: FileVersion [version=1, path=C1, type=FILE, status=NEW, size=51200, lastModified=Thu Feb 16 08:19:53 UTC 2017, linkTarget=null, checksum=6c7a1313af77e516ae80f6e3f4233b98d8e26ea7, updated=Thu Feb 16 08:19:53 UTC 2017, posixPermissions=rw-rw-r--, dosAttributes=--a-] AND /tmp/syncanytest/syncany-170216081829395-47281-client-B/C1
16-2-17 8:19:53.954 | FileSystemActio | Test worker    | INFO :      -> NewFileSystemAction [file1=null, file2=FileVersion [version=1, path=C1, type=FILE, status=NEW, size=51200, lastModified=Thu Feb 16 08:19:53 UTC 2017, linkTarget=null, checksum=6c7a1313af77e516ae80f6e3f4233b98d8e26ea7, updated=Thu Feb 16 08:19:53 UTC 2017, posixPermissions=rw-rw-r--, dosAttributes=--a-]]
16-2-17 8:19:53.955 | DownOperation   | Test worker    | INFO :   + Adding multichunk 9f0e7bf0039b4fa94a773692869781e0b9012d16 to download list ...
16-2-17 8:19:53.955 | Downloader      | Test worker    | INFO : Downloading and extracting multichunks ...
16-2-17 8:19:53.955 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event DownDownloadFileSyncExternalEvent (org.syncany.operations.daemon.messages.DownDownloadFileSyncExternalEvent@3b8e3dee) ...
16-2-17 8:19:53.955 | Downloader      | Test worker    | INFO :   + Downloading multichunk 9f0e7bf0039b4fa94a773692869781e0b9012d16 ...
16-2-17 8:19:53.955 | Downloader      | Test worker    | INFO :   + Decrypting multichunk 9f0e7bf0039b4fa94a773692869781e0b9012d16 ...
16-2-17 8:19:53.956 | Downloader      | Test worker    | FINE :   + Locally deleting multichunk 9f0e7bf0039b4fa94a773692869781e0b9012d16 ...
16-2-17 8:19:53.957 | FileSystemActio | Test worker    | INFO :    Sorted actions:
16-2-17 8:19:53.957 | FileSystemActio | Test worker    | INFO :    + NewFileSystemAction [file1=null, file2=FileVersion [version=1, path=C1, type=FILE, status=NEW, size=51200, lastModified=Thu Feb 16 08:19:53 UTC 2017, linkTarget=null, checksum=6c7a1313af77e516ae80f6e3f4233b98d8e26ea7, updated=Thu Feb 16 08:19:53 UTC 2017, posixPermissions=rw-rw-r--, dosAttributes=--a-]]
16-2-17 8:19:53.957 | DownOperation   | Test worker    | FINE : - Applying file system actions (sorted!) ...
16-2-17 8:19:53.957 | DownOperation   | Test worker    | FINE :    +  NewFileSystemAction [file1=null, file2=FileVersion [version=1, path=C1, type=FILE, status=NEW, size=51200, lastModified=Thu Feb 16 08:19:53 UTC 2017, linkTarget=null, checksum=6c7a1313af77e516ae80f6e3f4233b98d8e26ea7, updated=Thu Feb 16 08:19:53 UTC 2017, posixPermissions=rw-rw-r--, dosAttributes=--a-]]
16-2-17 8:19:53.957 | Assembler       | Test worker    | INFO :      - Creating file C1 to /tmp/syncanytest/syncany-170216081829395-47281-client-B/.syncany/cache/temp-reconstructedFileVersion-3114037561212019872.tmp ...
16-2-17 8:19:53.958 | FileSystemActio | Test worker    | INFO :      - Setting POSIX permissions: rw-rw-r-- ...
16-2-17 8:19:53.958 | DownOperation   | Test worker    | INFO : - Adding database versions to SQL database ...
16-2-17 8:19:53.958 | DownOperation   | Test worker    | INFO :   + Applying database version (A4,B1,C1)
16-2-17 8:19:53.958 | DownOperation   | Test worker    | FINE :   + Contents: DatabaseVersion [header=C/(A4,B1,C1)/T=1487233193901, chunks=1, multiChunks=1, fileContents=1, fileHistories=1]
16-2-17 8:19:53.964 | ActionFileHandl | Test worker    | INFO : Finishing action for RemoteFile[name=action-down-B-1487233193945] ...
16-2-17 8:19:53.965 | ActionFileHandl | Test worker    | INFO : Deleting action file: RemoteFile[name=action-down-B-1487233193945]
16-2-17 8:19:53.965 | AbstractTransfe | Test worker    | INFO : Cleaning own old action files ...
16-2-17 8:19:53.965 | Cache           | Test worker    | INFO : Cache size okay (207 KB), no need to clean (keep size is 500 MB)
16-2-17 8:19:53.965 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event DownEndSyncExternalEvent (org.syncany.operations.daemon.messages.DownEndSyncExternalEvent@16a74066) ...
16-2-17 8:19:53.965 | DownOperation   | Test worker    | INFO : Sync down done.
16-2-17 8:19:53.965 | org.syncany.plu | Test worker    | FINE : Validating required fields
16-2-17 8:19:53.965 | TransferManager | Test worker    | INFO : Building LocalTransferManager from config 'syncany-170216081829395-47281-client-C' ...
16-2-17 8:19:53.965 | TransferManager | Test worker    | INFO : - With feature ReadAfterWriteConsistent
16-2-17 8:19:53.965 | TransferManager | Test worker    | INFO : - With feature Retriable
16-2-17 8:19:53.965 | TransferManager | Test worker    | FINE : - Checking if selected features supported ...
16-2-17 8:19:53.965 | TransferManager | Test worker    | FINE : - Checking for duplicate features ...
16-2-17 8:19:53.965 | TransferManager | Test worker    | FINE : - Checking required feature TransactionAware in LocalTransferManager ...
16-2-17 8:19:53.965 | TransferManager | Test worker    | FINE : - Checking required feature Retriable in LocalTransferManager ...
16-2-17 8:19:53.965 | TransferManager | Test worker    | INFO : - SKIPPING unsupported optional feature ReadAfterWriteConsistent
16-2-17 8:19:53.965 | TransferManager | Test worker    | FINE : - Wrapping TransferManager LocalTransferManager in RetriableFeatureTransferManager
16-2-17 8:19:53.965 | org.syncany.plu | Test worker    | FINE : Validating required fields
16-2-17 8:19:53.965 | TransferManager | Test worker    | INFO : Building LocalTransferManager from config 'syncany-170216081829395-47281-client-C' ...
16-2-17 8:19:53.966 | TransferManager | Test worker    | INFO : - With feature ReadAfterWriteConsistent
16-2-17 8:19:53.966 | TransferManager | Test worker    | INFO : - With feature Retriable
16-2-17 8:19:53.966 | TransferManager | Test worker    | INFO : - With feature PathAware
16-2-17 8:19:53.966 | TransferManager | Test worker    | INFO : - With feature TransactionAware
16-2-17 8:19:53.966 | TransferManager | Test worker    | FINE : - Checking if selected features supported ...
16-2-17 8:19:53.966 | TransferManager | Test worker    | FINE : - Checking for duplicate features ...
16-2-17 8:19:53.966 | TransferManager | Test worker    | FINE : - Checking required feature TransactionAware in LocalTransferManager ...
16-2-17 8:19:53.966 | TransferManager | Test worker    | FINE : - Checking required feature Retriable in LocalTransferManager ...
16-2-17 8:19:53.966 | TransferManager | Test worker    | INFO : - SKIPPING unsupported optional feature ReadAfterWriteConsistent
16-2-17 8:19:53.966 | TransferManager | Test worker    | FINE : - Wrapping TransferManager LocalTransferManager in RetriableFeatureTransferManager
16-2-17 8:19:53.966 | TransferManager | Test worker    | INFO : - SKIPPING unsupported optional feature PathAware
16-2-17 8:19:53.966 | TransferManager | Test worker    | FINE : - Wrapping TransferManager RetriableFeatureTransferManager in TransactionAwareFeatureTransferManager
16-2-17 8:19:53.967 | DatabaseConnect | Test worker    | INFO : Found 12 tables.
16-2-17 8:19:53.967 | DownOperation   | Test worker    | INFO : 
16-2-17 8:19:53.967 | DownOperation   | Test worker    | INFO : Running 'Sync down' at client C ...
16-2-17 8:19:53.967 | DownOperation   | Test worker    | INFO : --------------------------------------------
16-2-17 8:19:53.967 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event DownStartSyncExternalEvent (org.syncany.operations.daemon.messages.DownStartSyncExternalEvent@578bb1ea) ...
16-2-17 8:19:53.967 | AbstractTransfe | Test worker    | INFO : Looking for other running remote operations ...
16-2-17 8:19:53.967 | DatabaseConnect | Test worker    | INFO : Found 12 tables.
16-2-17 8:19:53.967 | LsRemoteOperati | Test worker    | INFO : 
16-2-17 8:19:53.967 | LsRemoteOperati | Test worker    | INFO : Running 'Remote Status' at client C ...
16-2-17 8:19:53.967 | LsRemoteOperati | Test worker    | INFO : --------------------------------------------
16-2-17 8:19:53.967 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event LsRemoteStartSyncExternalEvent (org.syncany.operations.daemon.messages.LsRemoteStartSyncExternalEvent@5cea06ac) ...
16-2-17 8:19:53.967 | LsRemoteOperati | Test worker    | INFO : Retrieving remote database list.
16-2-17 8:19:53.968 | LsRemoteOperati | Test worker    | INFO : - Remote database database-A-0000000004 is already known (in local database). Ignoring.
16-2-17 8:19:53.968 | LsRemoteOperati | Test worker    | INFO : - Remote database database-A-0000000002 is already known (in local database). Ignoring.
16-2-17 8:19:53.968 | LsRemoteOperati | Test worker    | INFO : - Remote database database-A-0000000003 is already known (in local database). Ignoring.
16-2-17 8:19:53.968 | LsRemoteOperati | Test worker    | INFO : - Remote database database-C-0000000001 is already known (in local database). Ignoring.
16-2-17 8:19:53.968 | LsRemoteOperati | Test worker    | INFO : - Remote database database-B-0000000001 is already known (in local database). Ignoring.
16-2-17 8:19:53.968 | LsRemoteOperati | Test worker    | INFO : - Remote database database-A-0000000001 is already known (in local database). Ignoring.
16-2-17 8:19:53.968 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event LsRemoteEndSyncExternalEvent (org.syncany.operations.daemon.messages.LsRemoteEndSyncExternalEvent@506bb582) ...
16-2-17 8:19:53.968 | DownOperation   | Test worker    | INFO : * Nothing new. Skipping down operation.
16-2-17 8:19:53.968 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event DownEndSyncExternalEvent (org.syncany.operations.daemon.messages.DownEndSyncExternalEvent@360ac4d5) ...
16-2-17 8:19:53.975 | FileVersionComp | Test worker    | INFO :      - [CHANGED_PATH]: Local file DIFFERS from file version, expected PATH = tmp/syncanytest/syncany-170216081829395-47281-client-A/A2, but actual PATH = tmp/syncanytest/syncany-170216081829395-47281-client-B/A2, for file tmp/syncanytest/syncany-170216081829395-47281-client-B/A2
16-2-17 8:19:53.977 | FileVersionComp | Test worker    | INFO :      - [CHANGED_PATH]: Local file DIFFERS from file version, expected PATH = tmp/syncanytest/syncany-170216081829395-47281-client-A/A4,B1, but actual PATH = tmp/syncanytest/syncany-170216081829395-47281-client-B/A4,B1, for file tmp/syncanytest/syncany-170216081829395-47281-client-B/A4,B1
16-2-17 8:19:53.982 | FileVersionComp | Test worker    | INFO :      - [CHANGED_PATH]: Local file DIFFERS from file version, expected PATH = tmp/syncanytest/syncany-170216081829395-47281-client-A/C1, but actual PATH = tmp/syncanytest/syncany-170216081829395-47281-client-B/C1, for file tmp/syncanytest/syncany-170216081829395-47281-client-B/C1
16-2-17 8:19:53.990 | FileVersionComp | Test worker    | INFO :      - [CHANGED_PATH]: Local file DIFFERS from file version, expected PATH = tmp/syncanytest/syncany-170216081829395-47281-client-B/A2, but actual PATH = tmp/syncanytest/syncany-170216081829395-47281-client-C/A2, for file tmp/syncanytest/syncany-170216081829395-47281-client-C/A2
16-2-17 8:19:53.992 | FileVersionComp | Test worker    | INFO :      - [CHANGED_PATH]: Local file DIFFERS from file version, expected PATH = tmp/syncanytest/syncany-170216081829395-47281-client-B/A4,B1, but actual PATH = tmp/syncanytest/syncany-170216081829395-47281-client-C/A4,B1, for file tmp/syncanytest/syncany-170216081829395-47281-client-C/A4,B1
16-2-17 8:19:53.994 | FileVersionComp | Test worker    | INFO :      - [CHANGED_PATH]: Local file DIFFERS from file version, expected PATH = tmp/syncanytest/syncany-170216081829395-47281-client-B/C1, but actual PATH = tmp/syncanytest/syncany-170216081829395-47281-client-C/C1, for file tmp/syncanytest/syncany-170216081829395-47281-client-C/C1