Class org.syncany.tests.integration.scenarios.RenameToExistingFileScenarioTest

1

tests

0

failures

0

ignored

0.617s

duration

100%

successful

Tests

Test Duration Result
testRenameFileButDestinationExists 0.617s passed

Standard error

27-4-17 11:01:43.679 | org.syncany.plu | Test worker    | FINE : Validating required fields
27-4-17 11:01:43.679 | LocalTransferMa | Test worker    | INFO : testTargetExists: Target exists.
27-4-17 11:01:43.688 | org.syncany.plu | Test worker    | FINE : Validating required fields
27-4-17 11:01:43.688 | TransferManager | Test worker    | INFO : Building LocalTransferManager from config 'syncany-170427110008054-98113-client-A' ...
27-4-17 11:01:43.688 | TransferManager | Test worker    | INFO : - With feature ReadAfterWriteConsistent
27-4-17 11:01:43.688 | TransferManager | Test worker    | INFO : - With feature Retriable
27-4-17 11:01:43.688 | TransferManager | Test worker    | FINE : - Checking if selected features supported ...
27-4-17 11:01:43.688 | TransferManager | Test worker    | FINE : - Checking for duplicate features ...
27-4-17 11:01:43.688 | TransferManager | Test worker    | FINE : - Checking required feature TransactionAware in LocalTransferManager ...
27-4-17 11:01:43.688 | TransferManager | Test worker    | FINE : - Checking required feature Retriable in LocalTransferManager ...
27-4-17 11:01:43.688 | TransferManager | Test worker    | INFO : - SKIPPING unsupported optional feature ReadAfterWriteConsistent
27-4-17 11:01:43.688 | TransferManager | Test worker    | FINE : - Wrapping TransferManager LocalTransferManager in RetriableFeatureTransferManager
27-4-17 11:01:43.688 | org.syncany.plu | Test worker    | FINE : Validating required fields
27-4-17 11:01:43.689 | TransferManager | Test worker    | INFO : Building LocalTransferManager from config 'syncany-170427110008054-98113-client-A' ...
27-4-17 11:01:43.689 | TransferManager | Test worker    | INFO : - With feature ReadAfterWriteConsistent
27-4-17 11:01:43.689 | TransferManager | Test worker    | INFO : - With feature Retriable
27-4-17 11:01:43.689 | TransferManager | Test worker    | INFO : - With feature PathAware
27-4-17 11:01:43.689 | TransferManager | Test worker    | INFO : - With feature TransactionAware
27-4-17 11:01:43.689 | TransferManager | Test worker    | FINE : - Checking if selected features supported ...
27-4-17 11:01:43.689 | TransferManager | Test worker    | FINE : - Checking for duplicate features ...
27-4-17 11:01:43.689 | TransferManager | Test worker    | FINE : - Checking required feature TransactionAware in LocalTransferManager ...
27-4-17 11:01:43.689 | TransferManager | Test worker    | FINE : - Checking required feature Retriable in LocalTransferManager ...
27-4-17 11:01:43.689 | TransferManager | Test worker    | INFO : - SKIPPING unsupported optional feature ReadAfterWriteConsistent
27-4-17 11:01:43.689 | TransferManager | Test worker    | FINE : - Wrapping TransferManager LocalTransferManager in RetriableFeatureTransferManager
27-4-17 11:01:43.689 | TransferManager | Test worker    | INFO : - SKIPPING unsupported optional feature PathAware
27-4-17 11:01:43.689 | TransferManager | Test worker    | FINE : - Wrapping TransferManager RetriableFeatureTransferManager in TransactionAwareFeatureTransferManager
27-4-17 11:01:43.722 | hsqldb.db.HSQLD | Test worker    | INFO : Checkpoint start
27-4-17 11:01:43.722 | hsqldb.db.HSQLD | Test worker    | INFO : checkpointClose start
27-4-17 11:01:43.736 | hsqldb.db.HSQLD | Test worker    | FINE : log close size: 0
27-4-17 11:01:43.739 | hsqldb.db.HSQLD | Test worker    | INFO : checkpointClose end
27-4-17 11:01:43.739 | hsqldb.db.HSQLD | Test worker    | INFO : Checkpoint end - txts: 1
27-4-17 11:01:43.744 | DatabaseConnect | Test worker    | INFO : Found 0 tables.
27-4-17 11:01:43.744 | DatabaseConnect | Test worker    | INFO : Database has no tables. Creating tables from script.create.all.sql
27-4-17 11:01:43.745 | SqlRunner       | Test worker    | INFO : SQL (comment): -- Tables
27-4-17 11:01:43.745 | 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) ); 
27-4-17 11:01:43.747 | 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 ); 
27-4-17 11:01:43.752 | 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 ); 
27-4-17 11:01:43.754 | 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 ); 
27-4-17 11:01:43.757 | 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 ); 
27-4-17 11:01:43.759 | 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 ); 
27-4-17 11:01:43.761 | 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 ); 
27-4-17 11:01:43.764 | 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 ); 
27-4-17 11:01:43.766 | 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 ); 
27-4-17 11:01:43.769 | 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) ); 
27-4-17 11:01:43.771 | 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) ); 
27-4-17 11:01:43.773 | 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) ); 
27-4-17 11:01:43.776 | SqlRunner       | Test worker    | INFO : SQL (comment): -- Non-primary indices
27-4-17 11:01:43.776 | SqlRunner       | Test worker    | INFO : SQL:   CREATE INDEX idx_databaseversion_status ON databaseversion (status); 
27-4-17 11:01:43.777 | hsqldb.db.HSQLD | Test worker    | INFO : dataFileCache open start
27-4-17 11:01:43.779 | hsqldb.db.HSQLD | Test worker    | INFO : dataFileCache open end
27-4-17 11:01:43.782 | SqlRunner       | Test worker    | INFO : SQL: CREATE INDEX idx_databaseversion_vectorclock_serialized ON databaseversion (vectorclock_serialized); 
27-4-17 11:01:43.784 | SqlRunner       | Test worker    | INFO : SQL: CREATE INDEX idx_fileversion_path ON fileversion (path); 
27-4-17 11:01:43.786 | SqlRunner       | Test worker    | INFO : SQL: CREATE INDEX idx_fileversion_status ON fileversion (status); 
27-4-17 11:01:43.788 | SqlRunner       | Test worker    | INFO : SQL: CREATE INDEX idx_fileversion_filecontent_checksum ON fileversion (filecontent_checksum); 
27-4-17 11:01:43.790 | SqlRunner       | Test worker    | INFO : SQL (comment): -- Views
27-4-17 11:01:43.791 | 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'; 
27-4-17 11:01:43.794 | 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'; 
27-4-17 11:01:43.796 | 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; 
27-4-17 11:01:43.799 | 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'; 
27-4-17 11:01:43.802 | SqlRunner       | Test worker    | INFO : SQL (comment): -- Full Views
27-4-17 11:01:43.802 | 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; 
27-4-17 11:01:43.804 | 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; 
27-4-17 11:01:43.807 | SqlRunner       | Test worker    | INFO : SQL (comment): -- Functions
27-4-17 11:01:43.807 | SqlRunner       | Test worker    | INFO : SQL (new delimiter): end;
27-4-17 11:01:43.807 | 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; 
27-4-17 11:01:43.810 | UpOperation     | Test worker    | INFO : 
27-4-17 11:01:43.810 | UpOperation     | Test worker    | INFO : Running 'Sync up' at client A ...
27-4-17 11:01:43.810 | UpOperation     | Test worker    | INFO : --------------------------------------------
27-4-17 11:01:43.810 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event UpStartSyncExternalEvent (org.syncany.operations.daemon.messages.UpStartSyncExternalEvent@49de8a2d) ...
27-4-17 11:01:43.810 | DatabaseConnect | Test worker    | INFO : Found 12 tables.
27-4-17 11:01:43.810 | StatusOperation | Test worker    | INFO : 
27-4-17 11:01:43.810 | StatusOperation | Test worker    | INFO : Running 'Status' at client A ...
27-4-17 11:01:43.810 | StatusOperation | Test worker    | INFO : --------------------------------------------
27-4-17 11:01:43.811 | StatusOperation | Test worker    | INFO : Querying current file tree from database ...
27-4-17 11:01:43.811 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event StatusStartSyncExternalEvent (org.syncany.operations.daemon.messages.StatusStartSyncExternalEvent@3ddeab3) ...
27-4-17 11:01:43.811 | StatusOperation | Test worker    | INFO : Analyzing local folder /tmp/syncanytest/syncany-170427110008054-98113-client-A ...
27-4-17 11:01:43.811 | StatusOperation | Test worker    | FINE : - Ignoring file (syncany app-related): .syncany
27-4-17 11:01:43.811 | StatusOperation | Test worker    | FINE : - New file: A-original
27-4-17 11:01:43.811 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event StatusEndSyncExternalEvent (org.syncany.operations.daemon.messages.StatusEndSyncExternalEvent@5a36d6e5) ...
27-4-17 11:01:43.812 | AbstractTransfe | Test worker    | INFO : Looking for other running remote operations ...
27-4-17 11:01:43.812 | DatabaseConnect | Test worker    | INFO : Found 12 tables.
27-4-17 11:01:43.812 | LsRemoteOperati | Test worker    | INFO : 
27-4-17 11:01:43.812 | LsRemoteOperati | Test worker    | INFO : Running 'Remote Status' at client A ...
27-4-17 11:01:43.812 | LsRemoteOperati | Test worker    | INFO : --------------------------------------------
27-4-17 11:01:43.812 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event LsRemoteStartSyncExternalEvent (org.syncany.operations.daemon.messages.LsRemoteStartSyncExternalEvent@5f0e45e7) ...
27-4-17 11:01:43.812 | LsRemoteOperati | Test worker    | INFO : Retrieving remote database list.
27-4-17 11:01:43.812 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event LsRemoteEndSyncExternalEvent (org.syncany.operations.daemon.messages.LsRemoteEndSyncExternalEvent@2a767d5e) ...
27-4-17 11:01:43.813 | UpOperation     | Test worker    | INFO : No remote changes, ready to upload.
27-4-17 11:01:43.813 | ActionFileHandl | Test worker    | INFO : Starting action for RemoteFile[name=action-up-A-1493290903689] ...
27-4-17 11:01:43.813 | ActionFileHandl | Test worker    | INFO : Uploading action file: RemoteFile[name=action-up-A-1493290903689]
27-4-17 11:01:43.813 | ActionFileHandl | Test worker    | INFO : Scheduling action renewal task for every 2 minutes, for RemoteFile[name=action-up-A-1493290903689] ...
27-4-17 11:01:43.813 | DatabaseConnect | Test worker    | INFO : Found 12 tables.
27-4-17 11:01:43.820 | TransactionAwar | Test worker    | INFO : Done rolling back previous transactions.
27-4-17 11:01:43.820 | AsyncIndexer    | AsyncI/syncany | INFO : Starting Indexing.
27-4-17 11:01:43.825 | InternalEventBu | AsyncI/syncany | INFO : Event bus 'LocalEventBus': Posting event UpIndexStartSyncExternalEvent (org.syncany.operations.daemon.messages.UpIndexStartSyncExternalEvent@7c4a61d2) ...
27-4-17 11:01:43.825 | Indexer         | AsyncI/syncany | FINE : - Looking for deleted files ...
27-4-17 11:01:43.825 | Indexer         | AsyncI/syncany | FINE : - +File /tmp/syncanytest/syncany-170427110008054-98113-client-A/A-original
27-4-17 11:01:43.826 | Indexer         | AsyncI/syncany | FINE : - +FileContent: /tmp/syncanytest/syncany-170427110008054-98113-client-A/A-original
27-4-17 11:01:43.835 | Indexer         | AsyncI/syncany | FINE : - Chunk new: 96f3a7e0c416555772b625018ce7a3b9c1494be0
27-4-17 11:01:43.835 | Indexer         | AsyncI/syncany | FINE : - +MultiChunk dd092348493894d56db8af1a32ea1809c882e274
27-4-17 11:01:43.836 | Indexer         | AsyncI/syncany | FINE : - Chunk > MultiChunk: 96f3a7e0c416555772b625018ce7a3b9c1494be0 > dd092348493894d56db8af1a32ea1809c882e274
27-4-17 11:01:43.836 | Indexer         | AsyncI/syncany | FINE : - Chunk > FileContent: 96f3a7e0c416555772b625018ce7a3b9c1494be0 > /tmp/syncanytest/syncany-170427110008054-98113-client-A/A-original
27-4-17 11:01:43.836 | Indexer         | AsyncI/syncany | FINE : - /File: A-original (checksum 96f3a7e0c416555772b625018ce7a3b9c1494be0)
27-4-17 11:01:43.837 | Indexer         | AsyncI/syncany | FINE :    * No old file history found, starting new history (path: A-original, checksum: 96f3a7e0c416555772b625018ce7a3b9c1494be0)
27-4-17 11:01:43.837 | FileVersionComp | AsyncI/syncany | INFO :      - [DELETED]: Local file DIFFERS from file version, actual file is NULL, for file A-original
27-4-17 11:01:43.838 | Indexer         | AsyncI/syncany | INFO :    * Added file version:    FileVersion [version=1, path=A-original, type=FILE, status=NEW, size=51200, lastModified=Thu Apr 27 11:01:43 UTC 2017, linkTarget=null, checksum=96f3a7e0c416555772b625018ce7a3b9c1494be0, updated=Thu Apr 27 11:01:43 UTC 2017, posixPermissions=rw-rw-r--, dosAttributes=--a-]
27-4-17 11:01:43.838 | Indexer         | AsyncI/syncany | INFO :      based on file version: null
27-4-17 11:01:43.838 | InternalEventBu | AsyncI/syncany | INFO : Event bus 'LocalEventBus': Posting event UpIndexChangesDetectedSyncExternalEvent (org.syncany.operations.daemon.messages.UpIndexChangesDetectedSyncExternalEvent@13876d98) ...
27-4-17 11:01:43.838 | Indexer         | AsyncI/syncany | FINE : - /MultiChunk dd092348493894d56db8af1a32ea1809c882e274
27-4-17 11:01:43.838 | InternalEventBu | AsyncI/syncany | INFO : Event bus 'LocalEventBus': Posting event UpIndexEndSyncExternalEvent (org.syncany.operations.daemon.messages.UpIndexEndSyncExternalEvent@56e625db) ...
27-4-17 11:01:43.838 | Indexer         | AsyncI/syncany | FINE : Processed new database version: DatabaseVersion [header=UnknownMachine/()/T=1493290903825, chunks=1, multiChunks=1, fileContents=1, fileHistories=1]
27-4-17 11:01:43.838 | InternalEventBu | AsyncI/syncany | INFO : Event bus 'LocalEventBus': Posting event UpIndexMidSyncExternalEvent (org.syncany.operations.daemon.messages.UpIndexMidSyncExternalEvent@ee8163c) ...
27-4-17 11:01:43.839 | UpOperation     | Test worker    | INFO : No DIRTY data found in database (no dirty databases); Nothing to do here.
27-4-17 11:01:43.839 | UpOperation     | Test worker    | INFO : Last vector clock was: ()
27-4-17 11:01:43.840 | UpOperation     | Test worker    | INFO : Uploading new multichunks ...
27-4-17 11:01:43.840 | UpOperation     | Test worker    | INFO : - Uploading multichunk dd092348493894d56db8af1a32ea1809c882e274 from /tmp/syncanytest/syncany-170427110008054-98113-client-A/.syncany/cache/multichunk-dd092348493894d56db8af1a32ea1809c882e274 to RemoteFile[name=multichunk-dd092348493894d56db8af1a32ea1809c882e274] ...
27-4-17 11:01:43.840 | RemoteTransacti | Test worker    | INFO : - Adding file to TX for UPLOAD: /tmp/syncanytest/syncany-170427110008054-98113-client-A/.syncany/cache/multichunk-dd092348493894d56db8af1a32ea1809c882e274 -> Temp. remote file: RemoteFile[name=temp-eikZZ-multichunk-dd092348493894d56db8af1a32ea1809c882e274], final location: RemoteFile[name=multichunk-dd092348493894d56db8af1a32ea1809c882e274]
27-4-17 11:01:43.840 | UpOperation     | Test worker    | INFO : Uploading database: DatabaseVersion [header=A/(A1)/T=1493290903840, chunks=1, multiChunks=1, fileContents=1, fileHistories=1]
27-4-17 11:01:43.840 | UpOperation     | Test worker    | INFO : Saving local delta database, version A/(A1)/T=1493290903840 to file /tmp/syncanytest/syncany-170427110008054-98113-client-A/.syncany/cache/database-A-0000000001 ... 
27-4-17 11:01:43.840 | UpOperation     | Test worker    | INFO : - Saving database to /tmp/syncanytest/syncany-170427110008054-98113-client-A/.syncany/cache/database-A-0000000001 ...
27-4-17 11:01:43.841 | UpOperation     | Test worker    | INFO : - Uploading local delta database file ...
27-4-17 11:01:43.841 | UpOperation     | Test worker    | INFO : - Uploading /tmp/syncanytest/syncany-170427110008054-98113-client-A/.syncany/cache/database-A-0000000001 to RemoteFile[name=database-A-0000000001] ...
27-4-17 11:01:43.841 | RemoteTransacti | Test worker    | INFO : - Adding file to TX for UPLOAD: /tmp/syncanytest/syncany-170427110008054-98113-client-A/.syncany/cache/database-A-0000000001 -> Temp. remote file: RemoteFile[name=temp-aJZRR-database-A-0000000001], final location: RemoteFile[name=database-A-0000000001]
27-4-17 11:01:43.841 | UpOperation     | Test worker    | INFO : Adding shutdown hook (to allow resuming the upload) ...
27-4-17 11:01:43.841 | RemoteTransacti | Test worker    | INFO : Starting TX.commit() ...
27-4-17 11:01:43.842 | RemoteTransacti | Test worker    | INFO : Wrote transaction manifest to temporary file: /tmp/syncanytest/syncany-170427110008054-98113-client-A/.syncany/cache/temp-transaction-8442536603958506041.tmp
27-4-17 11:01:43.842 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event UpUploadFileSyncExternalEvent (org.syncany.operations.daemon.messages.UpUploadFileSyncExternalEvent@28f56c99) ...
27-4-17 11:01:43.843 | RemoteTransacti | Test worker    | INFO : - Uploading remote transaction file RemoteFile[name=transaction-60a5f9c3] ...
27-4-17 11:01:43.843 | RemoteTransacti | Test worker    | INFO : - Starting to upload data in commit.
27-4-17 11:01:43.843 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event UpUploadFileInTransactionSyncExternalEvent (org.syncany.operations.daemon.messages.UpUploadFileInTransactionSyncExternalEvent@9994dc1) ...
27-4-17 11:01:43.843 | RemoteTransacti | Test worker    | INFO : - Uploading /tmp/syncanytest/syncany-170427110008054-98113-client-A/.syncany/cache/multichunk-dd092348493894d56db8af1a32ea1809c882e274 to temp. file RemoteFile[name=temp-eikZZ-multichunk-dd092348493894d56db8af1a32ea1809c882e274] ...
27-4-17 11:01:43.843 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event UpUploadFileInTransactionSyncExternalEvent (org.syncany.operations.daemon.messages.UpUploadFileInTransactionSyncExternalEvent@56a79bf6) ...
27-4-17 11:01:43.843 | RemoteTransacti | Test worker    | INFO : - Uploading /tmp/syncanytest/syncany-170427110008054-98113-client-A/.syncany/cache/database-A-0000000001 to temp. file RemoteFile[name=temp-aJZRR-database-A-0000000001] ...
27-4-17 11:01:43.844 | RemoteTransacti | Test worker    | INFO : - Moving temp. file RemoteFile[name=temp-eikZZ-multichunk-dd092348493894d56db8af1a32ea1809c882e274] to final location RemoteFile[name=multichunk-dd092348493894d56db8af1a32ea1809c882e274] ...
27-4-17 11:01:43.844 | RemoteTransacti | Test worker    | INFO : - Moving temp. file RemoteFile[name=temp-aJZRR-database-A-0000000001] to final location RemoteFile[name=database-A-0000000001] ...
27-4-17 11:01:43.844 | RemoteTransacti | Test worker    | INFO : - Deleting remote transaction file RemoteFile[name=transaction-60a5f9c3] ...
27-4-17 11:01:43.844 | RemoteTransacti | Test worker    | INFO : END of TX.commmit(): Succesfully committed transaction.
27-4-17 11:01:43.844 | RemoteTransacti | Test worker    | INFO : END of TX.delTemp(): Sucessfully deleted final files.
27-4-17 11:01:43.844 | UpOperation     | Test worker    | INFO : Persisting local SQL database (new database version A/(A1)/T=1493290903840) ...
27-4-17 11:01:43.847 | UpOperation     | Test worker    | INFO : Removing DIRTY database versions from database ...
27-4-17 11:01:43.855 | AsyncIndexer    | AsyncI/syncany | INFO : Stopping indexing. Signal end of stream with empty databaseversion
27-4-17 11:01:43.858 | UpOperation     | Test worker    | INFO : Adding database version to result changes:DatabaseVersion [header=A/(A1)/T=1493290903840, chunks=1, multiChunks=1, fileContents=1, fileHistories=1]
27-4-17 11:01:43.858 | UpOperation     | Test worker    | INFO : Committing local database.
27-4-17 11:01:43.858 | UpOperation     | Test worker    | FINE : Waiting for new database version.
27-4-17 11:01:43.858 | UpOperation     | Test worker    | FINE : Took new database version: DatabaseVersion [header=UnknownMachine/()/T=1493290903855, chunks=0, multiChunks=0, fileContents=0, fileHistories=0]
27-4-17 11:01:43.858 | UpOperation     | Test worker    | INFO : Sync up done.
27-4-17 11:01:43.860 | ActionFileHandl | Test worker    | INFO : Finishing action for RemoteFile[name=action-up-A-1493290903689] ...
27-4-17 11:01:43.869 | ActionFileHandl | Test worker    | INFO : Deleting action file: RemoteFile[name=action-up-A-1493290903689]
27-4-17 11:01:43.869 | AbstractTransfe | Test worker    | INFO : Cleaning own old action files ...
27-4-17 11:01:43.870 | Cache           | Test worker    | INFO : Cache size okay (51 KB), no need to clean (keep size is 500 MB)
27-4-17 11:01:43.870 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event UpEndSyncExternalEvent (org.syncany.operations.daemon.messages.UpEndSyncExternalEvent@1426a06) ...
27-4-17 11:01:43.870 | org.syncany.plu | Test worker    | FINE : Validating required fields
27-4-17 11:01:43.870 | TransferManager | Test worker    | INFO : Building LocalTransferManager from config 'syncany-170427110008054-98113-client-B' ...
27-4-17 11:01:43.870 | TransferManager | Test worker    | INFO : - With feature ReadAfterWriteConsistent
27-4-17 11:01:43.870 | TransferManager | Test worker    | INFO : - With feature Retriable
27-4-17 11:01:43.870 | TransferManager | Test worker    | FINE : - Checking if selected features supported ...
27-4-17 11:01:43.870 | TransferManager | Test worker    | FINE : - Checking for duplicate features ...
27-4-17 11:01:43.870 | TransferManager | Test worker    | FINE : - Checking required feature TransactionAware in LocalTransferManager ...
27-4-17 11:01:43.870 | TransferManager | Test worker    | FINE : - Checking required feature Retriable in LocalTransferManager ...
27-4-17 11:01:43.870 | TransferManager | Test worker    | INFO : - SKIPPING unsupported optional feature ReadAfterWriteConsistent
27-4-17 11:01:43.870 | TransferManager | Test worker    | FINE : - Wrapping TransferManager LocalTransferManager in RetriableFeatureTransferManager
27-4-17 11:01:43.870 | org.syncany.plu | Test worker    | FINE : Validating required fields
27-4-17 11:01:43.870 | TransferManager | Test worker    | INFO : Building LocalTransferManager from config 'syncany-170427110008054-98113-client-B' ...
27-4-17 11:01:43.870 | TransferManager | Test worker    | INFO : - With feature ReadAfterWriteConsistent
27-4-17 11:01:43.870 | TransferManager | Test worker    | INFO : - With feature Retriable
27-4-17 11:01:43.870 | TransferManager | Test worker    | INFO : - With feature PathAware
27-4-17 11:01:43.871 | TransferManager | Test worker    | INFO : - With feature TransactionAware
27-4-17 11:01:43.871 | TransferManager | Test worker    | FINE : - Checking if selected features supported ...
27-4-17 11:01:43.871 | TransferManager | Test worker    | FINE : - Checking for duplicate features ...
27-4-17 11:01:43.871 | TransferManager | Test worker    | FINE : - Checking required feature TransactionAware in LocalTransferManager ...
27-4-17 11:01:43.871 | TransferManager | Test worker    | FINE : - Checking required feature Retriable in LocalTransferManager ...
27-4-17 11:01:43.871 | TransferManager | Test worker    | INFO : - SKIPPING unsupported optional feature ReadAfterWriteConsistent
27-4-17 11:01:43.871 | TransferManager | Test worker    | FINE : - Wrapping TransferManager LocalTransferManager in RetriableFeatureTransferManager
27-4-17 11:01:43.871 | TransferManager | Test worker    | INFO : - SKIPPING unsupported optional feature PathAware
27-4-17 11:01:43.871 | TransferManager | Test worker    | FINE : - Wrapping TransferManager RetriableFeatureTransferManager in TransactionAwareFeatureTransferManager
27-4-17 11:01:43.886 | hsqldb.db.HSQLD | Test worker    | INFO : Checkpoint start
27-4-17 11:01:43.886 | hsqldb.db.HSQLD | Test worker    | INFO : checkpointClose start
27-4-17 11:01:43.897 | hsqldb.db.HSQLD | Test worker    | FINE : log close size: 0
27-4-17 11:01:43.900 | hsqldb.db.HSQLD | Test worker    | INFO : checkpointClose end
27-4-17 11:01:43.900 | hsqldb.db.HSQLD | Test worker    | INFO : Checkpoint end - txts: 1
27-4-17 11:01:43.906 | DatabaseConnect | Test worker    | INFO : Found 0 tables.
27-4-17 11:01:43.906 | DatabaseConnect | Test worker    | INFO : Database has no tables. Creating tables from script.create.all.sql
27-4-17 11:01:43.906 | SqlRunner       | Test worker    | INFO : SQL (comment): -- Tables
27-4-17 11:01:43.906 | 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) ); 
27-4-17 11:01:43.909 | 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 ); 
27-4-17 11:01:43.911 | 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 ); 
27-4-17 11:01:43.913 | 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 ); 
27-4-17 11:01:43.915 | 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 ); 
27-4-17 11:01:43.918 | 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 ); 
27-4-17 11:01:43.920 | 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 ); 
27-4-17 11:01:43.923 | 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 ); 
27-4-17 11:01:43.925 | 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 ); 
27-4-17 11:01:43.927 | 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) ); 
27-4-17 11:01:43.929 | 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) ); 
27-4-17 11:01:43.932 | 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) ); 
27-4-17 11:01:43.934 | SqlRunner       | Test worker    | INFO : SQL (comment): -- Non-primary indices
27-4-17 11:01:43.934 | SqlRunner       | Test worker    | INFO : SQL:   CREATE INDEX idx_databaseversion_status ON databaseversion (status); 
27-4-17 11:01:43.935 | hsqldb.db.HSQLD | Test worker    | INFO : dataFileCache open start
27-4-17 11:01:43.937 | hsqldb.db.HSQLD | Test worker    | INFO : dataFileCache open end
27-4-17 11:01:43.939 | SqlRunner       | Test worker    | INFO : SQL: CREATE INDEX idx_databaseversion_vectorclock_serialized ON databaseversion (vectorclock_serialized); 
27-4-17 11:01:43.941 | SqlRunner       | Test worker    | INFO : SQL: CREATE INDEX idx_fileversion_path ON fileversion (path); 
27-4-17 11:01:43.943 | SqlRunner       | Test worker    | INFO : SQL: CREATE INDEX idx_fileversion_status ON fileversion (status); 
27-4-17 11:01:43.946 | SqlRunner       | Test worker    | INFO : SQL: CREATE INDEX idx_fileversion_filecontent_checksum ON fileversion (filecontent_checksum); 
27-4-17 11:01:43.948 | SqlRunner       | Test worker    | INFO : SQL (comment): -- Views
27-4-17 11:01:43.948 | 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'; 
27-4-17 11:01:43.951 | 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'; 
27-4-17 11:01:43.953 | 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; 
27-4-17 11:01:43.956 | 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'; 
27-4-17 11:01:43.958 | SqlRunner       | Test worker    | INFO : SQL (comment): -- Full Views
27-4-17 11:01:43.959 | 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; 
27-4-17 11:01:43.961 | 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; 
27-4-17 11:01:43.964 | SqlRunner       | Test worker    | INFO : SQL (comment): -- Functions
27-4-17 11:01:43.964 | SqlRunner       | Test worker    | INFO : SQL (new delimiter): end;
27-4-17 11:01:43.964 | 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; 
27-4-17 11:01:43.966 | DownOperation   | Test worker    | INFO : 
27-4-17 11:01:43.966 | DownOperation   | Test worker    | INFO : Running 'Sync down' at client B ...
27-4-17 11:01:43.967 | DownOperation   | Test worker    | INFO : --------------------------------------------
27-4-17 11:01:43.967 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event DownStartSyncExternalEvent (org.syncany.operations.daemon.messages.DownStartSyncExternalEvent@2612a9b9) ...
27-4-17 11:01:43.967 | AbstractTransfe | Test worker    | INFO : Looking for other running remote operations ...
27-4-17 11:01:43.967 | DatabaseConnect | Test worker    | INFO : Found 12 tables.
27-4-17 11:01:43.967 | LsRemoteOperati | Test worker    | INFO : 
27-4-17 11:01:43.967 | LsRemoteOperati | Test worker    | INFO : Running 'Remote Status' at client B ...
27-4-17 11:01:43.967 | LsRemoteOperati | Test worker    | INFO : --------------------------------------------
27-4-17 11:01:43.967 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event LsRemoteStartSyncExternalEvent (org.syncany.operations.daemon.messages.LsRemoteStartSyncExternalEvent@279cbe9e) ...
27-4-17 11:01:43.968 | LsRemoteOperati | Test worker    | INFO : Retrieving remote database list.
27-4-17 11:01:43.968 | LsRemoteOperati | Test worker    | INFO : - Remote database database-A-0000000001 is new.
27-4-17 11:01:43.968 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event LsRemoteEndSyncExternalEvent (org.syncany.operations.daemon.messages.LsRemoteEndSyncExternalEvent@5a8cada3) ...
27-4-17 11:01:43.968 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event DownChangesDetectedSyncExternalEvent (org.syncany.operations.daemon.messages.DownChangesDetectedSyncExternalEvent@484d6830) ...
27-4-17 11:01:43.968 | ActionFileHandl | Test worker    | INFO : Starting action for RemoteFile[name=action-down-B-1493290903871] ...
27-4-17 11:01:43.968 | ActionFileHandl | Test worker    | INFO : Uploading action file: RemoteFile[name=action-down-B-1493290903871]
27-4-17 11:01:43.968 | ActionFileHandl | Test worker    | INFO : Scheduling action renewal task for every 2 minutes, for RemoteFile[name=action-down-B-1493290903871] ...
27-4-17 11:01:43.968 | DownOperation   | Test worker    | INFO : Downloading unknown databases.
27-4-17 11:01:43.968 | DownOperation   | Test worker    | INFO : - Downloading database-A-0000000001 to local cache at /tmp/syncanytest/syncany-170427110008054-98113-client-B/.syncany/cache/database-A-0000000001
27-4-17 11:01:43.969 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event DownDownloadFileSyncExternalEvent (org.syncany.operations.daemon.messages.DownDownloadFileSyncExternalEvent@58c6aa31) ...
27-4-17 11:01:43.969 | DownOperation   | Test worker    | INFO : Loading database headers, creating branches ...
27-4-17 11:01:43.969 | DatabaseXmlSeri | Test worker    | INFO : - Loading database (HEADER_ONLY) from file /tmp/syncanytest/syncany-170427110008054-98113-client-B/.syncany/cache/database-A-0000000001 ...
27-4-17 11:01:43.970 | DatabaseXmlPars | Test worker    | INFO :    + Added database version A/(A1)/T=1493290903840
27-4-17 11:01:43.971 | LocalTransferMa | Test worker    | INFO : Cannot create instance of CleanupRemoteFile for file /tmp/syncanytest/syncany-170427110008054-98113-repo/databases/database-A-0000000001; maybe invalid file name pattern. Ignoring file.
27-4-17 11:01:43.971 | ApplicationSqlD | Test worker    | INFO : SQL (general_settings): Read cleanupNumber  = (not set)
27-4-17 11:01:43.971 | DownOperation   | Test worker    | INFO : Populated unknown branches: {A=[A/(A1)/T=1493290903840], B=[]}
27-4-17 11:01:43.971 | DownOperation   | Test worker    | INFO : Determine winner using database reconciliator ...
27-4-17 11:01:43.971 | DatabaseReconci | Test worker    | INFO : Adding database version to winning branch: A/(A1)/T=1493290903840
27-4-17 11:01:43.971 | DatabaseReconci | Test worker    | INFO : - Winner is A with branch: 
27-4-17 11:01:43.971 | DatabaseReconci | Test worker    | INFO :   + A/(A1)/T=1493290903840
27-4-17 11:01:43.971 | DownOperation   | Test worker    | INFO : - Database versions to REMOVE locally: []
27-4-17 11:01:43.971 | DownOperation   | Test worker    | INFO :   + Nothing to purge locally. No conflicts. Only updates. Nice!
27-4-17 11:01:43.971 | DatabaseReconci | Test worker    | INFO : Finding winnersApplyBranch.
27-4-17 11:01:43.971 | DatabaseReconci | Test worker    | INFO : Losers Branch: []
27-4-17 11:01:43.971 | DatabaseReconci | Test worker    | INFO : Winners Branch: [A/(A1)/T=1493290903840]
27-4-17 11:01:43.971 | DownOperation   | Test worker    | INFO : - Cleanup occurred: false
27-4-17 11:01:43.971 | DownOperation   | Test worker    | INFO : - Database versions to APPLY locally: [A/(A1)/T=1493290903840]
27-4-17 11:01:43.971 | DownOperation   | Test worker    | INFO : Loading winners database (DEFAULT) ...
27-4-17 11:01:43.971 | DatabaseXmlSeri | Test worker    | INFO : - Loading database (FULL) from file /tmp/syncanytest/syncany-170427110008054-98113-client-B/.syncany/cache/database-A-0000000001 ...
27-4-17 11:01:43.972 | DatabaseXmlPars | Test worker    | INFO :    + Added database version A/(A1)/T=1493290903840
27-4-17 11:01:43.973 | DownOperation   | Test worker    | INFO : Determine file system actions ...
27-4-17 11:01:43.973 | DatabaseConnect | Test worker    | INFO : Found 12 tables.
27-4-17 11:01:43.977 | FileSystemActio | Test worker    | INFO : - Loading current file tree...
27-4-17 11:01:43.977 | FileSystemActio | Test worker    | INFO : - Determine filesystem actions ...
27-4-17 11:01:43.977 | FileSystemActio | Test worker    | INFO :   + Comparing local version: null
27-4-17 11:01:43.977 | FileSystemActio | Test worker    | INFO :     with winning version   : FileVersion [version=1, path=A-original, type=FILE, status=NEW, size=51200, lastModified=Thu Apr 27 11:01:43 UTC 2017, linkTarget=null, checksum=96f3a7e0c416555772b625018ce7a3b9c1494be0, updated=Thu Apr 27 11:01:43 UTC 2017, posixPermissions=rw-rw-r--, dosAttributes=--a-]
27-4-17 11:01:43.977 | FileVersionComp | Test worker    | INFO :      - [DELETED]: Local file DIFFERS from file version, expected EXISTS = true, but actual EXISTS = false, for file A-original
27-4-17 11:01:43.977 | FileSystemActio | Test worker    | INFO :      -> (2) Deleted: Local file does NOT exist, but it should, winning version not known: FileVersion [version=1, path=A-original, type=FILE, status=NEW, size=51200, lastModified=Thu Apr 27 11:01:43 UTC 2017, linkTarget=null, checksum=96f3a7e0c416555772b625018ce7a3b9c1494be0, updated=Thu Apr 27 11:01:43 UTC 2017, posixPermissions=rw-rw-r--, dosAttributes=--a-] AND /tmp/syncanytest/syncany-170427110008054-98113-client-B/A-original
27-4-17 11:01:43.977 | FileSystemActio | Test worker    | INFO :      -> NewFileSystemAction [file1=null, file2=FileVersion [version=1, path=A-original, type=FILE, status=NEW, size=51200, lastModified=Thu Apr 27 11:01:43 UTC 2017, linkTarget=null, checksum=96f3a7e0c416555772b625018ce7a3b9c1494be0, updated=Thu Apr 27 11:01:43 UTC 2017, posixPermissions=rw-rw-r--, dosAttributes=--a-]]
27-4-17 11:01:43.978 | DownOperation   | Test worker    | INFO :   + Adding multichunk dd092348493894d56db8af1a32ea1809c882e274 to download list ...
27-4-17 11:01:43.978 | Downloader      | Test worker    | INFO : Downloading and extracting multichunks ...
27-4-17 11:01:43.978 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event DownDownloadFileSyncExternalEvent (org.syncany.operations.daemon.messages.DownDownloadFileSyncExternalEvent@2f983109) ...
27-4-17 11:01:43.978 | Downloader      | Test worker    | INFO :   + Downloading multichunk dd092348493894d56db8af1a32ea1809c882e274 ...
27-4-17 11:01:43.978 | Downloader      | Test worker    | INFO :   + Decrypting multichunk dd092348493894d56db8af1a32ea1809c882e274 ...
27-4-17 11:01:43.978 | Downloader      | Test worker    | FINE :   + Locally deleting multichunk dd092348493894d56db8af1a32ea1809c882e274 ...
27-4-17 11:01:43.981 | FileSystemActio | Test worker    | INFO :    Sorted actions:
27-4-17 11:01:43.981 | FileSystemActio | Test worker    | INFO :    + NewFileSystemAction [file1=null, file2=FileVersion [version=1, path=A-original, type=FILE, status=NEW, size=51200, lastModified=Thu Apr 27 11:01:43 UTC 2017, linkTarget=null, checksum=96f3a7e0c416555772b625018ce7a3b9c1494be0, updated=Thu Apr 27 11:01:43 UTC 2017, posixPermissions=rw-rw-r--, dosAttributes=--a-]]
27-4-17 11:01:43.981 | DownOperation   | Test worker    | FINE : - Applying file system actions (sorted!) ...
27-4-17 11:01:43.981 | DownOperation   | Test worker    | FINE :    +  NewFileSystemAction [file1=null, file2=FileVersion [version=1, path=A-original, type=FILE, status=NEW, size=51200, lastModified=Thu Apr 27 11:01:43 UTC 2017, linkTarget=null, checksum=96f3a7e0c416555772b625018ce7a3b9c1494be0, updated=Thu Apr 27 11:01:43 UTC 2017, posixPermissions=rw-rw-r--, dosAttributes=--a-]]
27-4-17 11:01:43.982 | Assembler       | Test worker    | INFO :      - Creating file A-original to /tmp/syncanytest/syncany-170427110008054-98113-client-B/.syncany/cache/temp-reconstructedFileVersion-4336471811005857831.tmp ...
27-4-17 11:01:43.983 | FileSystemActio | Test worker    | INFO :      - Setting POSIX permissions: rw-rw-r-- ...
27-4-17 11:01:43.983 | DownOperation   | Test worker    | INFO : - Adding database versions to SQL database ...
27-4-17 11:01:43.983 | DownOperation   | Test worker    | INFO :   + Applying database version (A1)
27-4-17 11:01:43.983 | DownOperation   | Test worker    | FINE :   + Contents: DatabaseVersion [header=A/(A1)/T=1493290903840, chunks=1, multiChunks=1, fileContents=1, fileHistories=1]
27-4-17 11:01:43.994 | ActionFileHandl | Test worker    | INFO : Finishing action for RemoteFile[name=action-down-B-1493290903871] ...
27-4-17 11:01:44.001 | ActionFileHandl | Test worker    | INFO : Deleting action file: RemoteFile[name=action-down-B-1493290903871]
27-4-17 11:01:44.001 | AbstractTransfe | Test worker    | INFO : Cleaning own old action files ...
27-4-17 11:01:44.002 | Cache           | Test worker    | INFO : Cache size okay (51 KB), no need to clean (keep size is 500 MB)
27-4-17 11:01:44.002 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event DownEndSyncExternalEvent (org.syncany.operations.daemon.messages.DownEndSyncExternalEvent@72954811) ...
27-4-17 11:01:44.002 | DownOperation   | Test worker    | INFO : Sync down done.
27-4-17 11:01:44.006 | FileVersionComp | Test worker    | INFO :      - [CHANGED_PATH]: Local file DIFFERS from file version, expected PATH = tmp/syncanytest/syncany-170427110008054-98113-client-A/A-original, but actual PATH = tmp/syncanytest/syncany-170427110008054-98113-client-B/A-original, for file tmp/syncanytest/syncany-170427110008054-98113-client-B/A-original
27-4-17 11:01:44.007 | DatabaseConnect | Test worker    | INFO : Found 12 tables.
27-4-17 11:01:44.007 | DatabaseConnect | Test worker    | INFO : Found 12 tables.
27-4-17 11:01:44.038 | TestAssertUtil  | Test worker    | FINE :  Comparing database table: select CHECKSUM, SIZE from chunk order by CHECKSUM asc
27-4-17 11:01:44.039 | TestAssertUtil  | Test worker    | FINE :  Comparing database table: select STATUS, LOCALTIME, CLIENT, VECTORCLOCK_SERIALIZED from databaseversion order by ID asc
27-4-17 11:01:44.039 | TestAssertUtil  | Test worker    | FINE :  Comparing database table: select CLIENT, LOGICALTIME from databaseversion_vectorclock order by DATABASEVERSION_ID asc, CLIENT asc
27-4-17 11:01:44.040 | TestAssertUtil  | Test worker    | FINE :  Comparing database table: select CHECKSUM, SIZE from filecontent order by CHECKSUM asc
27-4-17 11:01:44.040 | TestAssertUtil  | Test worker    | FINE :  Comparing database table: select FILECONTENT_CHECKSUM, CHUNK_CHECKSUM, NUM from filecontent_chunk order by FILECONTENT_CHECKSUM asc, CHUNK_CHECKSUM asc, NUM asc
27-4-17 11:01:44.041 | TestAssertUtil  | Test worker    | FINE :  Comparing database table: select ID from filehistory order by ID asc, DATABASEVERSION_ID asc
27-4-17 11:01:44.046 | TestAssertUtil  | Test worker    | FINE :  Comparing database table: select FILEHISTORY_ID, VERSION, PATH, TYPE, STATUS, SIZE, LASTMODIFIED, LINKTARGET, FILECONTENT_CHECKSUM, UPDATED, POSIXPERMS, DOSATTRS from fileversion order by FILEHISTORY_ID asc, VERSION asc, DATABASEVERSION_ID asc
27-4-17 11:01:44.046 | TestAssertUtil  | Test worker    | FINE :  Comparing database table: select ID, SIZE from multichunk order by ID asc
27-4-17 11:01:44.047 | TestAssertUtil  | Test worker    | FINE :  Comparing database table: select MULTICHUNK_ID, CHUNK_CHECKSUM from multichunk_chunk order by MULTICHUNK_ID asc, CHUNK_CHECKSUM asc
27-4-17 11:01:44.047 | org.syncany.plu | Test worker    | FINE : Validating required fields
27-4-17 11:01:44.048 | TransferManager | Test worker    | INFO : Building LocalTransferManager from config 'syncany-170427110008054-98113-client-A' ...
27-4-17 11:01:44.048 | TransferManager | Test worker    | INFO : - With feature ReadAfterWriteConsistent
27-4-17 11:01:44.048 | TransferManager | Test worker    | INFO : - With feature Retriable
27-4-17 11:01:44.048 | TransferManager | Test worker    | FINE : - Checking if selected features supported ...
27-4-17 11:01:44.048 | TransferManager | Test worker    | FINE : - Checking for duplicate features ...
27-4-17 11:01:44.048 | TransferManager | Test worker    | FINE : - Checking required feature TransactionAware in LocalTransferManager ...
27-4-17 11:01:44.048 | TransferManager | Test worker    | FINE : - Checking required feature Retriable in LocalTransferManager ...
27-4-17 11:01:44.048 | TransferManager | Test worker    | INFO : - SKIPPING unsupported optional feature ReadAfterWriteConsistent
27-4-17 11:01:44.048 | TransferManager | Test worker    | FINE : - Wrapping TransferManager LocalTransferManager in RetriableFeatureTransferManager
27-4-17 11:01:44.048 | org.syncany.plu | Test worker    | FINE : Validating required fields
27-4-17 11:01:44.049 | TransferManager | Test worker    | INFO : Building LocalTransferManager from config 'syncany-170427110008054-98113-client-A' ...
27-4-17 11:01:44.049 | TransferManager | Test worker    | INFO : - With feature ReadAfterWriteConsistent
27-4-17 11:01:44.049 | TransferManager | Test worker    | INFO : - With feature Retriable
27-4-17 11:01:44.049 | TransferManager | Test worker    | INFO : - With feature PathAware
27-4-17 11:01:44.049 | TransferManager | Test worker    | INFO : - With feature TransactionAware
27-4-17 11:01:44.049 | TransferManager | Test worker    | FINE : - Checking if selected features supported ...
27-4-17 11:01:44.049 | TransferManager | Test worker    | FINE : - Checking for duplicate features ...
27-4-17 11:01:44.049 | TransferManager | Test worker    | FINE : - Checking required feature TransactionAware in LocalTransferManager ...
27-4-17 11:01:44.049 | TransferManager | Test worker    | FINE : - Checking required feature Retriable in LocalTransferManager ...
27-4-17 11:01:44.049 | TransferManager | Test worker    | INFO : - SKIPPING unsupported optional feature ReadAfterWriteConsistent
27-4-17 11:01:44.049 | TransferManager | Test worker    | FINE : - Wrapping TransferManager LocalTransferManager in RetriableFeatureTransferManager
27-4-17 11:01:44.050 | TransferManager | Test worker    | INFO : - SKIPPING unsupported optional feature PathAware
27-4-17 11:01:44.050 | TransferManager | Test worker    | FINE : - Wrapping TransferManager RetriableFeatureTransferManager in TransactionAwareFeatureTransferManager
27-4-17 11:01:44.051 | DatabaseConnect | Test worker    | INFO : Found 12 tables.
27-4-17 11:01:44.051 | UpOperation     | Test worker    | INFO : 
27-4-17 11:01:44.051 | UpOperation     | Test worker    | INFO : Running 'Sync up' at client A ...
27-4-17 11:01:44.051 | UpOperation     | Test worker    | INFO : --------------------------------------------
27-4-17 11:01:44.051 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event UpStartSyncExternalEvent (org.syncany.operations.daemon.messages.UpStartSyncExternalEvent@7940d80a) ...
27-4-17 11:01:44.051 | DatabaseConnect | Test worker    | INFO : Found 12 tables.
27-4-17 11:01:44.051 | StatusOperation | Test worker    | INFO : 
27-4-17 11:01:44.051 | StatusOperation | Test worker    | INFO : Running 'Status' at client A ...
27-4-17 11:01:44.051 | StatusOperation | Test worker    | INFO : --------------------------------------------
27-4-17 11:01:44.051 | StatusOperation | Test worker    | INFO : Querying current file tree from database ...
27-4-17 11:01:44.051 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event StatusStartSyncExternalEvent (org.syncany.operations.daemon.messages.StatusStartSyncExternalEvent@12671d46) ...
27-4-17 11:01:44.054 | StatusOperation | Test worker    | INFO : Analyzing local folder /tmp/syncanytest/syncany-170427110008054-98113-client-A ...
27-4-17 11:01:44.054 | StatusOperation | Test worker    | FINE : - Ignoring file (syncany app-related): .syncany
27-4-17 11:01:44.054 | StatusOperation | Test worker    | FINE : - New file: A-moved
27-4-17 11:01:44.056 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event StatusEndSyncExternalEvent (org.syncany.operations.daemon.messages.StatusEndSyncExternalEvent@749d3fcd) ...
27-4-17 11:01:44.056 | AbstractTransfe | Test worker    | INFO : Looking for other running remote operations ...
27-4-17 11:01:44.057 | DatabaseConnect | Test worker    | INFO : Found 12 tables.
27-4-17 11:01:44.058 | LsRemoteOperati | Test worker    | INFO : 
27-4-17 11:01:44.059 | LsRemoteOperati | Test worker    | INFO : Running 'Remote Status' at client A ...
27-4-17 11:01:44.059 | LsRemoteOperati | Test worker    | INFO : --------------------------------------------
27-4-17 11:01:44.059 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event LsRemoteStartSyncExternalEvent (org.syncany.operations.daemon.messages.LsRemoteStartSyncExternalEvent@42ad76d2) ...
27-4-17 11:01:44.059 | LsRemoteOperati | Test worker    | INFO : Retrieving remote database list.
27-4-17 11:01:44.059 | LsRemoteOperati | Test worker    | INFO : - Remote database database-A-0000000001 is already known (in local database). Ignoring.
27-4-17 11:01:44.059 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event LsRemoteEndSyncExternalEvent (org.syncany.operations.daemon.messages.LsRemoteEndSyncExternalEvent@44173b66) ...
27-4-17 11:01:44.059 | UpOperation     | Test worker    | INFO : No remote changes, ready to upload.
27-4-17 11:01:44.059 | ActionFileHandl | Test worker    | INFO : Starting action for RemoteFile[name=action-up-A-1493290904050] ...
27-4-17 11:01:44.060 | ActionFileHandl | Test worker    | INFO : Uploading action file: RemoteFile[name=action-up-A-1493290904050]
27-4-17 11:01:44.061 | ActionFileHandl | Test worker    | INFO : Scheduling action renewal task for every 2 minutes, for RemoteFile[name=action-up-A-1493290904050] ...
27-4-17 11:01:44.062 | DatabaseConnect | Test worker    | INFO : Found 12 tables.
27-4-17 11:01:44.062 | TransactionAwar | Test worker    | INFO : Done rolling back previous transactions.
27-4-17 11:01:44.062 | AsyncIndexer    | AsyncI/syncany | INFO : Starting Indexing.
27-4-17 11:01:44.063 | InternalEventBu | AsyncI/syncany | INFO : Event bus 'LocalEventBus': Posting event UpIndexStartSyncExternalEvent (org.syncany.operations.daemon.messages.UpIndexStartSyncExternalEvent@1730885c) ...
27-4-17 11:01:44.063 | Indexer         | AsyncI/syncany | FINE : - Looking for deleted files ...
27-4-17 11:01:44.074 | Indexer         | AsyncI/syncany | FINE :   + Deleted: Adding DELETED version: FileVersion [version=2, path=A-original, type=FILE, status=DELETED, size=51200, lastModified=Thu Apr 27 11:01:43 UTC 2017, linkTarget=null, checksum=96f3a7e0c416555772b625018ce7a3b9c1494be0, updated=Thu Apr 27 11:01:44 UTC 2017, posixPermissions=rw-rw-r--, dosAttributes=--a-]
27-4-17 11:01:44.074 | Indexer         | AsyncI/syncany | FINE :                            based on: FileVersion [version=1, path=A-original, type=FILE, status=NEW, size=51200, lastModified=Thu Apr 27 11:01:43 UTC 2017, linkTarget=null, checksum=96f3a7e0c416555772b625018ce7a3b9c1494be0, updated=Thu Apr 27 11:01:43 UTC 2017, posixPermissions=rw-rw-r--, dosAttributes=--a-]
27-4-17 11:01:44.074 | Indexer         | AsyncI/syncany | FINE : - +File /tmp/syncanytest/syncany-170427110008054-98113-client-A/A-moved
27-4-17 11:01:44.075 | Indexer         | AsyncI/syncany | FINE : - +FileContent: /tmp/syncanytest/syncany-170427110008054-98113-client-A/A-moved
27-4-17 11:01:44.076 | Indexer         | AsyncI/syncany | FINE : - Chunk exists: 96f3a7e0c416555772b625018ce7a3b9c1494be0
27-4-17 11:01:44.076 | Indexer         | AsyncI/syncany | FINE : - Chunk > FileContent: 96f3a7e0c416555772b625018ce7a3b9c1494be0 > /tmp/syncanytest/syncany-170427110008054-98113-client-A/A-moved
27-4-17 11:01:44.076 | Indexer         | AsyncI/syncany | FINE : - /File: A-moved (checksum 96f3a7e0c416555772b625018ce7a3b9c1494be0)
27-4-17 11:01:44.077 | Indexer         | AsyncI/syncany | FINE :    * Found old file history 216317e882fe68276df3c7c9779c1694e3b7b33d (by checksum: 96f3a7e0c416555772b625018ce7a3b9c1494be0), appending new version.
27-4-17 11:01:44.077 | FileVersionComp | AsyncI/syncany | INFO :      - [CHANGED_PATH]: Local file DIFFERS from file version, expected PATH = A-moved, but actual PATH = A-original, for file A-original
27-4-17 11:01:44.077 | Indexer         | AsyncI/syncany | INFO :    * Added file version:    FileVersion [version=2, path=A-moved, type=FILE, status=CHANGED, size=51200, lastModified=Thu Apr 27 11:01:43 UTC 2017, linkTarget=null, checksum=96f3a7e0c416555772b625018ce7a3b9c1494be0, updated=Thu Apr 27 11:01:44 UTC 2017, posixPermissions=rw-rw-r--, dosAttributes=--a-]
27-4-17 11:01:44.078 | Indexer         | AsyncI/syncany | INFO :      based on file version: FileVersion [version=1, path=A-original, type=FILE, status=NEW, size=51200, lastModified=Thu Apr 27 11:01:43 UTC 2017, linkTarget=null, checksum=96f3a7e0c416555772b625018ce7a3b9c1494be0, updated=Thu Apr 27 11:01:43 UTC 2017, posixPermissions=rw-rw-r--, dosAttributes=--a-]
27-4-17 11:01:44.078 | InternalEventBu | AsyncI/syncany | INFO : Event bus 'LocalEventBus': Posting event UpIndexChangesDetectedSyncExternalEvent (org.syncany.operations.daemon.messages.UpIndexChangesDetectedSyncExternalEvent@755896a1) ...
27-4-17 11:01:44.078 | InternalEventBu | AsyncI/syncany | INFO : Event bus 'LocalEventBus': Posting event UpIndexEndSyncExternalEvent (org.syncany.operations.daemon.messages.UpIndexEndSyncExternalEvent@72d02a3f) ...
27-4-17 11:01:44.079 | Indexer         | AsyncI/syncany | FINE : Processed new database version: DatabaseVersion [header=UnknownMachine/()/T=1493290904062, chunks=0, multiChunks=0, fileContents=0, fileHistories=1]
27-4-17 11:01:44.079 | UpOperation     | Test worker    | INFO : No DIRTY data found in database (no dirty databases); Nothing to do here.
27-4-17 11:01:44.079 | UpOperation     | Test worker    | INFO : Last vector clock was: (A1)
27-4-17 11:01:44.079 | UpOperation     | Test worker    | INFO : Uploading new multichunks ...
27-4-17 11:01:44.080 | UpOperation     | Test worker    | INFO : Uploading database: DatabaseVersion [header=A/(A2)/T=1493290904079, chunks=0, multiChunks=0, fileContents=0, fileHistories=1]
27-4-17 11:01:44.080 | UpOperation     | Test worker    | INFO : Saving local delta database, version A/(A2)/T=1493290904079 to file /tmp/syncanytest/syncany-170427110008054-98113-client-A/.syncany/cache/database-A-0000000002 ... 
27-4-17 11:01:44.080 | UpOperation     | Test worker    | INFO : - Saving database to /tmp/syncanytest/syncany-170427110008054-98113-client-A/.syncany/cache/database-A-0000000002 ...
27-4-17 11:01:44.080 | UpOperation     | Test worker    | INFO : - Uploading local delta database file ...
27-4-17 11:01:44.080 | UpOperation     | Test worker    | INFO : - Uploading /tmp/syncanytest/syncany-170427110008054-98113-client-A/.syncany/cache/database-A-0000000002 to RemoteFile[name=database-A-0000000002] ...
27-4-17 11:01:44.080 | RemoteTransacti | Test worker    | INFO : - Adding file to TX for UPLOAD: /tmp/syncanytest/syncany-170427110008054-98113-client-A/.syncany/cache/database-A-0000000002 -> Temp. remote file: RemoteFile[name=temp-JeOlS-database-A-0000000002], final location: RemoteFile[name=database-A-0000000002]
27-4-17 11:01:44.081 | UpOperation     | Test worker    | INFO : Adding shutdown hook (to allow resuming the upload) ...
27-4-17 11:01:44.081 | RemoteTransacti | Test worker    | INFO : Starting TX.commit() ...
27-4-17 11:01:44.085 | RemoteTransacti | Test worker    | INFO : Wrote transaction manifest to temporary file: /tmp/syncanytest/syncany-170427110008054-98113-client-A/.syncany/cache/temp-transaction-3228597087707867517.tmp
27-4-17 11:01:44.085 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event UpUploadFileSyncExternalEvent (org.syncany.operations.daemon.messages.UpUploadFileSyncExternalEvent@426a37) ...
27-4-17 11:01:44.085 | RemoteTransacti | Test worker    | INFO : - Uploading remote transaction file RemoteFile[name=transaction-140e9cb2] ...
27-4-17 11:01:44.086 | RemoteTransacti | Test worker    | INFO : - Starting to upload data in commit.
27-4-17 11:01:44.086 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event UpUploadFileInTransactionSyncExternalEvent (org.syncany.operations.daemon.messages.UpUploadFileInTransactionSyncExternalEvent@2c466d34) ...
27-4-17 11:01:44.087 | RemoteTransacti | Test worker    | INFO : - Uploading /tmp/syncanytest/syncany-170427110008054-98113-client-A/.syncany/cache/database-A-0000000002 to temp. file RemoteFile[name=temp-JeOlS-database-A-0000000002] ...
27-4-17 11:01:44.088 | RemoteTransacti | Test worker    | INFO : - Moving temp. file RemoteFile[name=temp-JeOlS-database-A-0000000002] to final location RemoteFile[name=database-A-0000000002] ...
27-4-17 11:01:44.088 | RemoteTransacti | Test worker    | INFO : - Deleting remote transaction file RemoteFile[name=transaction-140e9cb2] ...
27-4-17 11:01:44.088 | RemoteTransacti | Test worker    | INFO : END of TX.commmit(): Succesfully committed transaction.
27-4-17 11:01:44.088 | RemoteTransacti | Test worker    | INFO : END of TX.delTemp(): Sucessfully deleted final files.
27-4-17 11:01:44.088 | UpOperation     | Test worker    | INFO : Persisting local SQL database (new database version A/(A2)/T=1493290904079) ...
27-4-17 11:01:44.089 | UpOperation     | Test worker    | INFO : Removing DIRTY database versions from database ...
27-4-17 11:01:44.092 | InternalEventBu | AsyncI/syncany | INFO : Event bus 'LocalEventBus': Posting event UpIndexMidSyncExternalEvent (org.syncany.operations.daemon.messages.UpIndexMidSyncExternalEvent@2904002b) ...
27-4-17 11:01:44.100 | UpOperation     | Test worker    | INFO : Adding database version to result changes:DatabaseVersion [header=A/(A2)/T=1493290904079, chunks=0, multiChunks=0, fileContents=0, fileHistories=1]
27-4-17 11:01:44.100 | UpOperation     | Test worker    | INFO : Committing local database.
27-4-17 11:01:44.100 | UpOperation     | Test worker    | FINE : Waiting for new database version.
27-4-17 11:01:44.102 | AsyncIndexer    | AsyncI/syncany | INFO : Stopping indexing. Signal end of stream with empty databaseversion
27-4-17 11:01:44.102 | UpOperation     | Test worker    | FINE : Took new database version: DatabaseVersion [header=UnknownMachine/()/T=1493290904102, chunks=0, multiChunks=0, fileContents=0, fileHistories=0]
27-4-17 11:01:44.103 | UpOperation     | Test worker    | INFO : Sync up done.
27-4-17 11:01:44.104 | ActionFileHandl | Test worker    | INFO : Finishing action for RemoteFile[name=action-up-A-1493290904050] ...
27-4-17 11:01:44.109 | ActionFileHandl | Test worker    | INFO : Deleting action file: RemoteFile[name=action-up-A-1493290904050]
27-4-17 11:01:44.110 | AbstractTransfe | Test worker    | INFO : Cleaning own old action files ...
27-4-17 11:01:44.110 | Cache           | Test worker    | INFO : Cache size okay (52 KB), no need to clean (keep size is 500 MB)
27-4-17 11:01:44.110 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event UpEndSyncExternalEvent (org.syncany.operations.daemon.messages.UpEndSyncExternalEvent@314b9baa) ...
27-4-17 11:01:44.111 | org.syncany.plu | Test worker    | FINE : Validating required fields
27-4-17 11:01:44.111 | TransferManager | Test worker    | INFO : Building LocalTransferManager from config 'syncany-170427110008054-98113-client-B' ...
27-4-17 11:01:44.111 | TransferManager | Test worker    | INFO : - With feature ReadAfterWriteConsistent
27-4-17 11:01:44.111 | TransferManager | Test worker    | INFO : - With feature Retriable
27-4-17 11:01:44.111 | TransferManager | Test worker    | FINE : - Checking if selected features supported ...
27-4-17 11:01:44.111 | TransferManager | Test worker    | FINE : - Checking for duplicate features ...
27-4-17 11:01:44.111 | TransferManager | Test worker    | FINE : - Checking required feature TransactionAware in LocalTransferManager ...
27-4-17 11:01:44.111 | TransferManager | Test worker    | FINE : - Checking required feature Retriable in LocalTransferManager ...
27-4-17 11:01:44.111 | TransferManager | Test worker    | INFO : - SKIPPING unsupported optional feature ReadAfterWriteConsistent
27-4-17 11:01:44.111 | TransferManager | Test worker    | FINE : - Wrapping TransferManager LocalTransferManager in RetriableFeatureTransferManager
27-4-17 11:01:44.111 | org.syncany.plu | Test worker    | FINE : Validating required fields
27-4-17 11:01:44.111 | TransferManager | Test worker    | INFO : Building LocalTransferManager from config 'syncany-170427110008054-98113-client-B' ...
27-4-17 11:01:44.111 | TransferManager | Test worker    | INFO : - With feature ReadAfterWriteConsistent
27-4-17 11:01:44.111 | TransferManager | Test worker    | INFO : - With feature Retriable
27-4-17 11:01:44.111 | TransferManager | Test worker    | INFO : - With feature PathAware
27-4-17 11:01:44.111 | TransferManager | Test worker    | INFO : - With feature TransactionAware
27-4-17 11:01:44.111 | TransferManager | Test worker    | FINE : - Checking if selected features supported ...
27-4-17 11:01:44.111 | TransferManager | Test worker    | FINE : - Checking for duplicate features ...
27-4-17 11:01:44.111 | TransferManager | Test worker    | FINE : - Checking required feature TransactionAware in LocalTransferManager ...
27-4-17 11:01:44.111 | TransferManager | Test worker    | FINE : - Checking required feature Retriable in LocalTransferManager ...
27-4-17 11:01:44.111 | TransferManager | Test worker    | INFO : - SKIPPING unsupported optional feature ReadAfterWriteConsistent
27-4-17 11:01:44.111 | TransferManager | Test worker    | FINE : - Wrapping TransferManager LocalTransferManager in RetriableFeatureTransferManager
27-4-17 11:01:44.111 | TransferManager | Test worker    | INFO : - SKIPPING unsupported optional feature PathAware
27-4-17 11:01:44.111 | TransferManager | Test worker    | FINE : - Wrapping TransferManager RetriableFeatureTransferManager in TransactionAwareFeatureTransferManager
27-4-17 11:01:44.122 | DatabaseConnect | Test worker    | INFO : Found 12 tables.
27-4-17 11:01:44.122 | DownOperation   | Test worker    | INFO : 
27-4-17 11:01:44.122 | DownOperation   | Test worker    | INFO : Running 'Sync down' at client B ...
27-4-17 11:01:44.122 | DownOperation   | Test worker    | INFO : --------------------------------------------
27-4-17 11:01:44.122 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event DownStartSyncExternalEvent (org.syncany.operations.daemon.messages.DownStartSyncExternalEvent@2362f1c1) ...
27-4-17 11:01:44.122 | AbstractTransfe | Test worker    | INFO : Looking for other running remote operations ...
27-4-17 11:01:44.123 | DatabaseConnect | Test worker    | INFO : Found 12 tables.
27-4-17 11:01:44.123 | LsRemoteOperati | Test worker    | INFO : 
27-4-17 11:01:44.123 | LsRemoteOperati | Test worker    | INFO : Running 'Remote Status' at client B ...
27-4-17 11:01:44.123 | LsRemoteOperati | Test worker    | INFO : --------------------------------------------
27-4-17 11:01:44.123 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event LsRemoteStartSyncExternalEvent (org.syncany.operations.daemon.messages.LsRemoteStartSyncExternalEvent@54245450) ...
27-4-17 11:01:44.123 | LsRemoteOperati | Test worker    | INFO : Retrieving remote database list.
27-4-17 11:01:44.123 | LsRemoteOperati | Test worker    | INFO : - Remote database database-A-0000000002 is new.
27-4-17 11:01:44.123 | LsRemoteOperati | Test worker    | INFO : - Remote database database-A-0000000001 is already known (in local database). Ignoring.
27-4-17 11:01:44.123 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event LsRemoteEndSyncExternalEvent (org.syncany.operations.daemon.messages.LsRemoteEndSyncExternalEvent@4681af52) ...
27-4-17 11:01:44.123 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event DownChangesDetectedSyncExternalEvent (org.syncany.operations.daemon.messages.DownChangesDetectedSyncExternalEvent@76fd656) ...
27-4-17 11:01:44.123 | ActionFileHandl | Test worker    | INFO : Starting action for RemoteFile[name=action-down-B-1493290904111] ...
27-4-17 11:01:44.123 | ActionFileHandl | Test worker    | INFO : Uploading action file: RemoteFile[name=action-down-B-1493290904111]
27-4-17 11:01:44.124 | ActionFileHandl | Test worker    | INFO : Scheduling action renewal task for every 2 minutes, for RemoteFile[name=action-down-B-1493290904111] ...
27-4-17 11:01:44.124 | DownOperation   | Test worker    | INFO : Downloading unknown databases.
27-4-17 11:01:44.124 | DownOperation   | Test worker    | INFO : - Downloading database-A-0000000002 to local cache at /tmp/syncanytest/syncany-170427110008054-98113-client-B/.syncany/cache/database-A-0000000002
27-4-17 11:01:44.124 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event DownDownloadFileSyncExternalEvent (org.syncany.operations.daemon.messages.DownDownloadFileSyncExternalEvent@56803a7) ...
27-4-17 11:01:44.125 | DownOperation   | Test worker    | INFO : Loading database headers, creating branches ...
27-4-17 11:01:44.125 | DatabaseXmlSeri | Test worker    | INFO : - Loading database (HEADER_ONLY) from file /tmp/syncanytest/syncany-170427110008054-98113-client-B/.syncany/cache/database-A-0000000002 ...
27-4-17 11:01:44.126 | DatabaseXmlPars | Test worker    | INFO :    + Added database version A/(A2)/T=1493290904079
27-4-17 11:01:44.127 | LocalTransferMa | Test worker    | INFO : Cannot create instance of CleanupRemoteFile for file /tmp/syncanytest/syncany-170427110008054-98113-repo/databases/database-A-0000000002; maybe invalid file name pattern. Ignoring file.
27-4-17 11:01:44.127 | LocalTransferMa | Test worker    | INFO : Cannot create instance of CleanupRemoteFile for file /tmp/syncanytest/syncany-170427110008054-98113-repo/databases/database-A-0000000001; maybe invalid file name pattern. Ignoring file.
27-4-17 11:01:44.127 | ApplicationSqlD | Test worker    | INFO : SQL (general_settings): Read cleanupNumber  = (not set)
27-4-17 11:01:44.127 | DownOperation   | Test worker    | INFO : Populated unknown branches: {A=[A/(A2)/T=1493290904079], B=[A/(A1)/T=1493290903840]}
27-4-17 11:01:44.127 | DownOperation   | Test worker    | INFO : Determine winner using database reconciliator ...
27-4-17 11:01:44.127 | DatabaseReconci | Test worker    | INFO : Adding database version to winning branch: A/(A1)/T=1493290903840
27-4-17 11:01:44.127 | DatabaseReconci | Test worker    | INFO : Adding database version to winning branch: A/(A2)/T=1493290904079
27-4-17 11:01:44.127 | DatabaseReconci | Test worker    | INFO : - Winner is A with branch: 
27-4-17 11:01:44.127 | DatabaseReconci | Test worker    | INFO :   + A/(A1)/T=1493290903840
27-4-17 11:01:44.127 | DatabaseReconci | Test worker    | INFO :   + A/(A2)/T=1493290904079
27-4-17 11:01:44.127 | DownOperation   | Test worker    | INFO : - Database versions to REMOVE locally: []
27-4-17 11:01:44.127 | DownOperation   | Test worker    | INFO :   + Nothing to purge locally. No conflicts. Only updates. Nice!
27-4-17 11:01:44.127 | DatabaseReconci | Test worker    | INFO : Finding winnersApplyBranch.
27-4-17 11:01:44.127 | DatabaseReconci | Test worker    | INFO : Losers Branch: [A/(A1)/T=1493290903840]
27-4-17 11:01:44.127 | DatabaseReconci | Test worker    | INFO : Winners Branch: [A/(A1)/T=1493290903840, A/(A2)/T=1493290904079]
27-4-17 11:01:44.127 | DownOperation   | Test worker    | INFO : - Cleanup occurred: false
27-4-17 11:01:44.127 | DownOperation   | Test worker    | INFO : - Database versions to APPLY locally: [A/(A2)/T=1493290904079]
27-4-17 11:01:44.127 | DownOperation   | Test worker    | INFO : Loading winners database (DEFAULT) ...
27-4-17 11:01:44.127 | DatabaseXmlSeri | Test worker    | INFO : - Loading database (FULL) from file /tmp/syncanytest/syncany-170427110008054-98113-client-B/.syncany/cache/database-A-0000000002 ...
27-4-17 11:01:44.128 | DatabaseXmlPars | Test worker    | INFO :    + Added database version A/(A2)/T=1493290904079
27-4-17 11:01:44.128 | DownOperation   | Test worker    | INFO : Determine file system actions ...
27-4-17 11:01:44.129 | DatabaseConnect | Test worker    | INFO : Found 12 tables.
27-4-17 11:01:44.130 | FileSystemActio | Test worker    | INFO : - Loading current file tree...
27-4-17 11:01:44.130 | FileSystemActio | Test worker    | INFO : - Determine filesystem actions ...
27-4-17 11:01:44.130 | FileSystemActio | Test worker    | INFO :   + Comparing local version: FileVersion [version=1, path=A-original, type=FILE, status=NEW, size=51200, lastModified=Thu Apr 27 11:01:43 UTC 2017, linkTarget=null, checksum=96f3a7e0c416555772b625018ce7a3b9c1494be0, updated=Thu Apr 27 11:01:43 UTC 2017, posixPermissions=rw-rw-r--, dosAttributes=--a-]
27-4-17 11:01:44.130 | FileSystemActio | Test worker    | INFO :     with winning version   : FileVersion [version=2, path=A-moved, type=FILE, status=CHANGED, size=51200, lastModified=Thu Apr 27 11:01:43 UTC 2017, linkTarget=null, checksum=96f3a7e0c416555772b625018ce7a3b9c1494be0, updated=Thu Apr 27 11:01:44 UTC 2017, posixPermissions=rw-rw-r--, dosAttributes=--a-]
27-4-17 11:01:44.130 | FileVersionComp | Test worker    | INFO :      - [CHANGED_PATH]: Local file DIFFERS from file version, expected PATH = A-moved, but actual PATH = A-original, for file A-original
27-4-17 11:01:44.131 | FileSystemActio | Test worker    | INFO :      -> (12) Rename / Changed file attributes: Local file has different file attributes: local file = /tmp/syncanytest/syncany-170427110008054-98113-client-B/A-original, local version = FileVersion [version=1, path=A-original, type=FILE, status=NEW, size=51200, lastModified=Thu Apr 27 11:01:43 UTC 2017, linkTarget=null, checksum=96f3a7e0c416555772b625018ce7a3b9c1494be0, updated=Thu Apr 27 11:01:43 UTC 2017, posixPermissions=rw-rw-r--, dosAttributes=--a-], winning version = FileVersion [version=2, path=A-moved, type=FILE, status=CHANGED, size=51200, lastModified=Thu Apr 27 11:01:43 UTC 2017, linkTarget=null, checksum=96f3a7e0c416555772b625018ce7a3b9c1494be0, updated=Thu Apr 27 11:01:44 UTC 2017, posixPermissions=rw-rw-r--, dosAttributes=--a-]
27-4-17 11:01:44.131 | FileSystemActio | Test worker    | INFO :      -> RenameFileSystemAction [file1=FileVersion [version=1, path=A-original, type=FILE, status=NEW, size=51200, lastModified=Thu Apr 27 11:01:43 UTC 2017, linkTarget=null, checksum=96f3a7e0c416555772b625018ce7a3b9c1494be0, updated=Thu Apr 27 11:01:43 UTC 2017, posixPermissions=rw-rw-r--, dosAttributes=--a-], file2=FileVersion [version=2, path=A-moved, type=FILE, status=CHANGED, size=51200, lastModified=Thu Apr 27 11:01:43 UTC 2017, linkTarget=null, checksum=96f3a7e0c416555772b625018ce7a3b9c1494be0, updated=Thu Apr 27 11:01:44 UTC 2017, posixPermissions=rw-rw-r--, dosAttributes=--a-]]
27-4-17 11:01:44.131 | Downloader      | Test worker    | INFO : Downloading and extracting multichunks ...
27-4-17 11:01:44.131 | FileSystemActio | Test worker    | INFO :    Sorted actions:
27-4-17 11:01:44.131 | FileSystemActio | Test worker    | INFO :    + RenameFileSystemAction [file1=FileVersion [version=1, path=A-original, type=FILE, status=NEW, size=51200, lastModified=Thu Apr 27 11:01:43 UTC 2017, linkTarget=null, checksum=96f3a7e0c416555772b625018ce7a3b9c1494be0, updated=Thu Apr 27 11:01:43 UTC 2017, posixPermissions=rw-rw-r--, dosAttributes=--a-], file2=FileVersion [version=2, path=A-moved, type=FILE, status=CHANGED, size=51200, lastModified=Thu Apr 27 11:01:43 UTC 2017, linkTarget=null, checksum=96f3a7e0c416555772b625018ce7a3b9c1494be0, updated=Thu Apr 27 11:01:44 UTC 2017, posixPermissions=rw-rw-r--, dosAttributes=--a-]]
27-4-17 11:01:44.131 | DownOperation   | Test worker    | FINE : - Applying file system actions (sorted!) ...
27-4-17 11:01:44.131 | DownOperation   | Test worker    | FINE :    +  RenameFileSystemAction [file1=FileVersion [version=1, path=A-original, type=FILE, status=NEW, size=51200, lastModified=Thu Apr 27 11:01:43 UTC 2017, linkTarget=null, checksum=96f3a7e0c416555772b625018ce7a3b9c1494be0, updated=Thu Apr 27 11:01:43 UTC 2017, posixPermissions=rw-rw-r--, dosAttributes=--a-], file2=FileVersion [version=2, path=A-moved, type=FILE, status=CHANGED, size=51200, lastModified=Thu Apr 27 11:01:43 UTC 2017, linkTarget=null, checksum=96f3a7e0c416555772b625018ce7a3b9c1494be0, updated=Thu Apr 27 11:01:44 UTC 2017, posixPermissions=rw-rw-r--, dosAttributes=--a-]]
27-4-17 11:01:44.132 | FileVersionComp | Test worker    | INFO :      - [CHANGED_CHECKSUM]: Local file DIFFERS from file version, expected CHECKSUM = 96f3a7e0c416555772b625018ce7a3b9c1494be0, but actual CHECKSUM = c66a963e502fe2fbea27f59d105007c0d8acef3a, for file A-moved
27-4-17 11:01:44.132 | FileSystemActio | Test worker    | INFO :      - (4) File at destination differs, creating conflict file for /tmp/syncanytest/syncany-170427110008054-98113-client-B/A-moved ...
27-4-17 11:01:44.132 | FileSystemActio | Test worker    | INFO :      - Local version conflicts, moving local file A-moved to A-moved (B's conflicted copy, 27 Apr 17, 11-01 AM) ...
27-4-17 11:01:44.133 | FileSystemActio | Test worker    | INFO :      - Setting POSIX permissions: rw-rw-r-- ...
27-4-17 11:01:44.133 | DownOperation   | Test worker    | INFO : - Adding database versions to SQL database ...
27-4-17 11:01:44.133 | DownOperation   | Test worker    | INFO :   + Applying database version (A2)
27-4-17 11:01:44.133 | DownOperation   | Test worker    | FINE :   + Contents: DatabaseVersion [header=A/(A2)/T=1493290904079, chunks=0, multiChunks=0, fileContents=0, fileHistories=1]
27-4-17 11:01:44.140 | ActionFileHandl | Test worker    | INFO : Finishing action for RemoteFile[name=action-down-B-1493290904111] ...
27-4-17 11:01:44.143 | ActionFileHandl | Test worker    | INFO : Deleting action file: RemoteFile[name=action-down-B-1493290904111]
27-4-17 11:01:44.144 | AbstractTransfe | Test worker    | INFO : Cleaning own old action files ...
27-4-17 11:01:44.144 | Cache           | Test worker    | INFO : Cache size okay (52 KB), no need to clean (keep size is 500 MB)
27-4-17 11:01:44.144 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event DownEndSyncExternalEvent (org.syncany.operations.daemon.messages.DownEndSyncExternalEvent@21caba85) ...
27-4-17 11:01:44.144 | DownOperation   | Test worker    | INFO : Sync down done.
27-4-17 11:01:44.146 | org.syncany.plu | Test worker    | FINE : Validating required fields
27-4-17 11:01:44.146 | TransferManager | Test worker    | INFO : Building LocalTransferManager from config 'syncany-170427110008054-98113-client-B' ...
27-4-17 11:01:44.146 | TransferManager | Test worker    | INFO : - With feature ReadAfterWriteConsistent
27-4-17 11:01:44.146 | TransferManager | Test worker    | INFO : - With feature Retriable
27-4-17 11:01:44.146 | TransferManager | Test worker    | FINE : - Checking if selected features supported ...
27-4-17 11:01:44.146 | TransferManager | Test worker    | FINE : - Checking for duplicate features ...
27-4-17 11:01:44.146 | TransferManager | Test worker    | FINE : - Checking required feature TransactionAware in LocalTransferManager ...
27-4-17 11:01:44.146 | TransferManager | Test worker    | FINE : - Checking required feature Retriable in LocalTransferManager ...
27-4-17 11:01:44.146 | TransferManager | Test worker    | INFO : - SKIPPING unsupported optional feature ReadAfterWriteConsistent
27-4-17 11:01:44.146 | TransferManager | Test worker    | FINE : - Wrapping TransferManager LocalTransferManager in RetriableFeatureTransferManager
27-4-17 11:01:44.146 | org.syncany.plu | Test worker    | FINE : Validating required fields
27-4-17 11:01:44.146 | TransferManager | Test worker    | INFO : Building LocalTransferManager from config 'syncany-170427110008054-98113-client-B' ...
27-4-17 11:01:44.146 | TransferManager | Test worker    | INFO : - With feature ReadAfterWriteConsistent
27-4-17 11:01:44.146 | TransferManager | Test worker    | INFO : - With feature Retriable
27-4-17 11:01:44.146 | TransferManager | Test worker    | INFO : - With feature PathAware
27-4-17 11:01:44.146 | TransferManager | Test worker    | INFO : - With feature TransactionAware
27-4-17 11:01:44.146 | TransferManager | Test worker    | FINE : - Checking if selected features supported ...
27-4-17 11:01:44.146 | TransferManager | Test worker    | FINE : - Checking for duplicate features ...
27-4-17 11:01:44.146 | TransferManager | Test worker    | FINE : - Checking required feature TransactionAware in LocalTransferManager ...
27-4-17 11:01:44.146 | TransferManager | Test worker    | FINE : - Checking required feature Retriable in LocalTransferManager ...
27-4-17 11:01:44.146 | TransferManager | Test worker    | INFO : - SKIPPING unsupported optional feature ReadAfterWriteConsistent
27-4-17 11:01:44.146 | TransferManager | Test worker    | FINE : - Wrapping TransferManager LocalTransferManager in RetriableFeatureTransferManager
27-4-17 11:01:44.146 | TransferManager | Test worker    | INFO : - SKIPPING unsupported optional feature PathAware
27-4-17 11:01:44.146 | TransferManager | Test worker    | FINE : - Wrapping TransferManager RetriableFeatureTransferManager in TransactionAwareFeatureTransferManager
27-4-17 11:01:44.150 | DatabaseConnect | Test worker    | INFO : Found 12 tables.
27-4-17 11:01:44.150 | UpOperation     | Test worker    | INFO : 
27-4-17 11:01:44.150 | UpOperation     | Test worker    | INFO : Running 'Sync up' at client B ...
27-4-17 11:01:44.150 | UpOperation     | Test worker    | INFO : --------------------------------------------
27-4-17 11:01:44.150 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event UpStartSyncExternalEvent (org.syncany.operations.daemon.messages.UpStartSyncExternalEvent@3efb7056) ...
27-4-17 11:01:44.150 | DatabaseConnect | Test worker    | INFO : Found 12 tables.
27-4-17 11:01:44.151 | StatusOperation | Test worker    | INFO : 
27-4-17 11:01:44.151 | StatusOperation | Test worker    | INFO : Running 'Status' at client B ...
27-4-17 11:01:44.151 | StatusOperation | Test worker    | INFO : --------------------------------------------
27-4-17 11:01:44.151 | StatusOperation | Test worker    | INFO : Querying current file tree from database ...
27-4-17 11:01:44.151 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event StatusStartSyncExternalEvent (org.syncany.operations.daemon.messages.StatusStartSyncExternalEvent@4fe297) ...
27-4-17 11:01:44.151 | StatusOperation | Test worker    | INFO : Analyzing local folder /tmp/syncanytest/syncany-170427110008054-98113-client-B ...
27-4-17 11:01:44.151 | StatusOperation | Test worker    | FINE : - Ignoring file (syncany app-related): .syncany
27-4-17 11:01:44.151 | StatusOperation | Test worker    | FINE : - New file: A-moved (B's conflicted copy, 27 Apr 17, 11-01 AM)
27-4-17 11:01:44.152 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event StatusEndSyncExternalEvent (org.syncany.operations.daemon.messages.StatusEndSyncExternalEvent@21ab30b0) ...
27-4-17 11:01:44.152 | AbstractTransfe | Test worker    | INFO : Looking for other running remote operations ...
27-4-17 11:01:44.153 | DatabaseConnect | Test worker    | INFO : Found 12 tables.
27-4-17 11:01:44.153 | LsRemoteOperati | Test worker    | INFO : 
27-4-17 11:01:44.153 | LsRemoteOperati | Test worker    | INFO : Running 'Remote Status' at client B ...
27-4-17 11:01:44.153 | LsRemoteOperati | Test worker    | INFO : --------------------------------------------
27-4-17 11:01:44.153 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event LsRemoteStartSyncExternalEvent (org.syncany.operations.daemon.messages.LsRemoteStartSyncExternalEvent@5245eee4) ...
27-4-17 11:01:44.153 | LsRemoteOperati | Test worker    | INFO : Retrieving remote database list.
27-4-17 11:01:44.154 | LsRemoteOperati | Test worker    | INFO : - Remote database database-A-0000000002 is already known (in local database). Ignoring.
27-4-17 11:01:44.154 | LsRemoteOperati | Test worker    | INFO : - Remote database database-A-0000000001 is already known (in local database). Ignoring.
27-4-17 11:01:44.154 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event LsRemoteEndSyncExternalEvent (org.syncany.operations.daemon.messages.LsRemoteEndSyncExternalEvent@4fdb80) ...
27-4-17 11:01:44.154 | UpOperation     | Test worker    | INFO : No remote changes, ready to upload.
27-4-17 11:01:44.154 | ActionFileHandl | Test worker    | INFO : Starting action for RemoteFile[name=action-up-B-1493290904146] ...
27-4-17 11:01:44.154 | ActionFileHandl | Test worker    | INFO : Uploading action file: RemoteFile[name=action-up-B-1493290904146]
27-4-17 11:01:44.154 | ActionFileHandl | Test worker    | INFO : Scheduling action renewal task for every 2 minutes, for RemoteFile[name=action-up-B-1493290904146] ...
27-4-17 11:01:44.155 | DatabaseConnect | Test worker    | INFO : Found 12 tables.
27-4-17 11:01:44.157 | TransactionAwar | Test worker    | INFO : Done rolling back previous transactions.
27-4-17 11:01:44.157 | AsyncIndexer    | AsyncI/syncany | INFO : Starting Indexing.
27-4-17 11:01:44.158 | InternalEventBu | AsyncI/syncany | INFO : Event bus 'LocalEventBus': Posting event UpIndexStartSyncExternalEvent (org.syncany.operations.daemon.messages.UpIndexStartSyncExternalEvent@6787b6e3) ...
27-4-17 11:01:44.158 | Indexer         | AsyncI/syncany | FINE : - Looking for deleted files ...
27-4-17 11:01:44.158 | Indexer         | AsyncI/syncany | FINE : - +File /tmp/syncanytest/syncany-170427110008054-98113-client-B/A-moved (B's conflicted copy, 27 Apr 17, 11-01 AM)
27-4-17 11:01:44.158 | Indexer         | AsyncI/syncany | FINE : - +FileContent: /tmp/syncanytest/syncany-170427110008054-98113-client-B/A-moved (B's conflicted copy, 27 Apr 17, 11-01 AM)
27-4-17 11:01:44.163 | Indexer         | AsyncI/syncany | FINE : - Chunk new: c66a963e502fe2fbea27f59d105007c0d8acef3a
27-4-17 11:01:44.163 | Indexer         | AsyncI/syncany | FINE : - +MultiChunk 142a70870fe3a53f0bc4170c3858480ad122f545
27-4-17 11:01:44.164 | Indexer         | AsyncI/syncany | FINE : - Chunk > MultiChunk: c66a963e502fe2fbea27f59d105007c0d8acef3a > 142a70870fe3a53f0bc4170c3858480ad122f545
27-4-17 11:01:44.164 | Indexer         | AsyncI/syncany | FINE : - Chunk > FileContent: c66a963e502fe2fbea27f59d105007c0d8acef3a > /tmp/syncanytest/syncany-170427110008054-98113-client-B/A-moved (B's conflicted copy, 27 Apr 17, 11-01 AM)
27-4-17 11:01:44.164 | Indexer         | AsyncI/syncany | FINE : - /File: A-moved (B's conflicted copy, 27 Apr 17, 11-01 AM) (checksum c66a963e502fe2fbea27f59d105007c0d8acef3a)
27-4-17 11:01:44.165 | Indexer         | AsyncI/syncany | FINE :    * No old file history found, starting new history (path: A-moved (B's conflicted copy, 27 Apr 17, 11-01 AM), checksum: c66a963e502fe2fbea27f59d105007c0d8acef3a)
27-4-17 11:01:44.165 | FileVersionComp | AsyncI/syncany | INFO :      - [DELETED]: Local file DIFFERS from file version, actual file is NULL, for file A-moved (B's conflicted copy, 27 Apr 17, 11-01 AM)
27-4-17 11:01:44.165 | Indexer         | AsyncI/syncany | INFO :    * Added file version:    FileVersion [version=1, path=A-moved (B's conflicted copy, 27 Apr 17, 11-01 AM), type=FILE, status=NEW, size=51200, lastModified=Thu Apr 27 11:01:44 UTC 2017, linkTarget=null, checksum=c66a963e502fe2fbea27f59d105007c0d8acef3a, updated=Thu Apr 27 11:01:44 UTC 2017, posixPermissions=rw-rw-r--, dosAttributes=--a-]
27-4-17 11:01:44.165 | Indexer         | AsyncI/syncany | INFO :      based on file version: null
27-4-17 11:01:44.165 | InternalEventBu | AsyncI/syncany | INFO : Event bus 'LocalEventBus': Posting event UpIndexChangesDetectedSyncExternalEvent (org.syncany.operations.daemon.messages.UpIndexChangesDetectedSyncExternalEvent@79724f4c) ...
27-4-17 11:01:44.165 | Indexer         | AsyncI/syncany | FINE : - /MultiChunk 142a70870fe3a53f0bc4170c3858480ad122f545
27-4-17 11:01:44.165 | InternalEventBu | AsyncI/syncany | INFO : Event bus 'LocalEventBus': Posting event UpIndexEndSyncExternalEvent (org.syncany.operations.daemon.messages.UpIndexEndSyncExternalEvent@1a1fe12e) ...
27-4-17 11:01:44.165 | Indexer         | AsyncI/syncany | FINE : Processed new database version: DatabaseVersion [header=UnknownMachine/()/T=1493290904158, chunks=1, multiChunks=1, fileContents=1, fileHistories=1]
27-4-17 11:01:44.166 | UpOperation     | Test worker    | INFO : No DIRTY data found in database (no dirty databases); Nothing to do here.
27-4-17 11:01:44.166 | UpOperation     | Test worker    | INFO : Last vector clock was: (A2)
27-4-17 11:01:44.166 | UpOperation     | Test worker    | INFO : Uploading new multichunks ...
27-4-17 11:01:44.166 | UpOperation     | Test worker    | INFO : - Uploading multichunk 142a70870fe3a53f0bc4170c3858480ad122f545 from /tmp/syncanytest/syncany-170427110008054-98113-client-B/.syncany/cache/multichunk-142a70870fe3a53f0bc4170c3858480ad122f545 to RemoteFile[name=multichunk-142a70870fe3a53f0bc4170c3858480ad122f545] ...
27-4-17 11:01:44.166 | RemoteTransacti | Test worker    | INFO : - Adding file to TX for UPLOAD: /tmp/syncanytest/syncany-170427110008054-98113-client-B/.syncany/cache/multichunk-142a70870fe3a53f0bc4170c3858480ad122f545 -> Temp. remote file: RemoteFile[name=temp-cKkfx-multichunk-142a70870fe3a53f0bc4170c3858480ad122f545], final location: RemoteFile[name=multichunk-142a70870fe3a53f0bc4170c3858480ad122f545]
27-4-17 11:01:44.166 | UpOperation     | Test worker    | INFO : Uploading database: DatabaseVersion [header=B/(A2,B1)/T=1493290904166, chunks=1, multiChunks=1, fileContents=1, fileHistories=1]
27-4-17 11:01:44.167 | UpOperation     | Test worker    | INFO : Saving local delta database, version B/(A2,B1)/T=1493290904166 to file /tmp/syncanytest/syncany-170427110008054-98113-client-B/.syncany/cache/database-B-0000000001 ... 
27-4-17 11:01:44.167 | UpOperation     | Test worker    | INFO : - Saving database to /tmp/syncanytest/syncany-170427110008054-98113-client-B/.syncany/cache/database-B-0000000001 ...
27-4-17 11:01:44.167 | UpOperation     | Test worker    | INFO : - Uploading local delta database file ...
27-4-17 11:01:44.167 | UpOperation     | Test worker    | INFO : - Uploading /tmp/syncanytest/syncany-170427110008054-98113-client-B/.syncany/cache/database-B-0000000001 to RemoteFile[name=database-B-0000000001] ...
27-4-17 11:01:44.168 | RemoteTransacti | Test worker    | INFO : - Adding file to TX for UPLOAD: /tmp/syncanytest/syncany-170427110008054-98113-client-B/.syncany/cache/database-B-0000000001 -> Temp. remote file: RemoteFile[name=temp-FjiwJ-database-B-0000000001], final location: RemoteFile[name=database-B-0000000001]
27-4-17 11:01:44.168 | UpOperation     | Test worker    | INFO : Adding shutdown hook (to allow resuming the upload) ...
27-4-17 11:01:44.168 | RemoteTransacti | Test worker    | INFO : Starting TX.commit() ...
27-4-17 11:01:44.169 | RemoteTransacti | Test worker    | INFO : Wrote transaction manifest to temporary file: /tmp/syncanytest/syncany-170427110008054-98113-client-B/.syncany/cache/temp-transaction-7433143519622803415.tmp
27-4-17 11:01:44.169 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event UpUploadFileSyncExternalEvent (org.syncany.operations.daemon.messages.UpUploadFileSyncExternalEvent@54ad737f) ...
27-4-17 11:01:44.169 | RemoteTransacti | Test worker    | INFO : - Uploading remote transaction file RemoteFile[name=transaction-1ba5f19e] ...
27-4-17 11:01:44.169 | RemoteTransacti | Test worker    | INFO : - Starting to upload data in commit.
27-4-17 11:01:44.170 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event UpUploadFileInTransactionSyncExternalEvent (org.syncany.operations.daemon.messages.UpUploadFileInTransactionSyncExternalEvent@59d10dc1) ...
27-4-17 11:01:44.170 | RemoteTransacti | Test worker    | INFO : - Uploading /tmp/syncanytest/syncany-170427110008054-98113-client-B/.syncany/cache/multichunk-142a70870fe3a53f0bc4170c3858480ad122f545 to temp. file RemoteFile[name=temp-cKkfx-multichunk-142a70870fe3a53f0bc4170c3858480ad122f545] ...
27-4-17 11:01:44.170 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event UpUploadFileInTransactionSyncExternalEvent (org.syncany.operations.daemon.messages.UpUploadFileInTransactionSyncExternalEvent@19c3f008) ...
27-4-17 11:01:44.170 | RemoteTransacti | Test worker    | INFO : - Uploading /tmp/syncanytest/syncany-170427110008054-98113-client-B/.syncany/cache/database-B-0000000001 to temp. file RemoteFile[name=temp-FjiwJ-database-B-0000000001] ...
27-4-17 11:01:44.170 | RemoteTransacti | Test worker    | INFO : - Moving temp. file RemoteFile[name=temp-cKkfx-multichunk-142a70870fe3a53f0bc4170c3858480ad122f545] to final location RemoteFile[name=multichunk-142a70870fe3a53f0bc4170c3858480ad122f545] ...
27-4-17 11:01:44.170 | RemoteTransacti | Test worker    | INFO : - Moving temp. file RemoteFile[name=temp-FjiwJ-database-B-0000000001] to final location RemoteFile[name=database-B-0000000001] ...
27-4-17 11:01:44.170 | RemoteTransacti | Test worker    | INFO : - Deleting remote transaction file RemoteFile[name=transaction-1ba5f19e] ...
27-4-17 11:01:44.171 | RemoteTransacti | Test worker    | INFO : END of TX.commmit(): Succesfully committed transaction.
27-4-17 11:01:44.171 | RemoteTransacti | Test worker    | INFO : END of TX.delTemp(): Sucessfully deleted final files.
27-4-17 11:01:44.171 | UpOperation     | Test worker    | INFO : Persisting local SQL database (new database version B/(A2,B1)/T=1493290904166) ...
27-4-17 11:01:44.172 | UpOperation     | Test worker    | INFO : Removing DIRTY database versions from database ...
27-4-17 11:01:44.173 | InternalEventBu | AsyncI/syncany | INFO : Event bus 'LocalEventBus': Posting event UpIndexMidSyncExternalEvent (org.syncany.operations.daemon.messages.UpIndexMidSyncExternalEvent@776c235d) ...
27-4-17 11:01:44.190 | UpOperation     | Test worker    | INFO : Adding database version to result changes:DatabaseVersion [header=B/(A2,B1)/T=1493290904166, chunks=1, multiChunks=1, fileContents=1, fileHistories=1]
27-4-17 11:01:44.190 | UpOperation     | Test worker    | INFO : Committing local database.
27-4-17 11:01:44.190 | UpOperation     | Test worker    | FINE : Waiting for new database version.
27-4-17 11:01:44.193 | AsyncIndexer    | AsyncI/syncany | INFO : Stopping indexing. Signal end of stream with empty databaseversion
27-4-17 11:01:44.193 | UpOperation     | Test worker    | FINE : Took new database version: DatabaseVersion [header=UnknownMachine/()/T=1493290904193, chunks=0, multiChunks=0, fileContents=0, fileHistories=0]
27-4-17 11:01:44.193 | UpOperation     | Test worker    | INFO : Sync up done.
27-4-17 11:01:44.195 | ActionFileHandl | Test worker    | INFO : Finishing action for RemoteFile[name=action-up-B-1493290904146] ...
27-4-17 11:01:44.201 | ActionFileHandl | Test worker    | INFO : Deleting action file: RemoteFile[name=action-up-B-1493290904146]
27-4-17 11:01:44.201 | AbstractTransfe | Test worker    | INFO : Cleaning own old action files ...
27-4-17 11:01:44.202 | Cache           | Test worker    | INFO : Cache size okay (103 KB), no need to clean (keep size is 500 MB)
27-4-17 11:01:44.202 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event UpEndSyncExternalEvent (org.syncany.operations.daemon.messages.UpEndSyncExternalEvent@1337fa37) ...
27-4-17 11:01:44.202 | org.syncany.plu | Test worker    | FINE : Validating required fields
27-4-17 11:01:44.202 | TransferManager | Test worker    | INFO : Building LocalTransferManager from config 'syncany-170427110008054-98113-client-B' ...
27-4-17 11:01:44.202 | TransferManager | Test worker    | INFO : - With feature ReadAfterWriteConsistent
27-4-17 11:01:44.202 | TransferManager | Test worker    | INFO : - With feature Retriable
27-4-17 11:01:44.202 | TransferManager | Test worker    | FINE : - Checking if selected features supported ...
27-4-17 11:01:44.202 | TransferManager | Test worker    | FINE : - Checking for duplicate features ...
27-4-17 11:01:44.202 | TransferManager | Test worker    | FINE : - Checking required feature TransactionAware in LocalTransferManager ...
27-4-17 11:01:44.202 | TransferManager | Test worker    | FINE : - Checking required feature Retriable in LocalTransferManager ...
27-4-17 11:01:44.202 | TransferManager | Test worker    | INFO : - SKIPPING unsupported optional feature ReadAfterWriteConsistent
27-4-17 11:01:44.202 | TransferManager | Test worker    | FINE : - Wrapping TransferManager LocalTransferManager in RetriableFeatureTransferManager
27-4-17 11:01:44.202 | org.syncany.plu | Test worker    | FINE : Validating required fields
27-4-17 11:01:44.202 | TransferManager | Test worker    | INFO : Building LocalTransferManager from config 'syncany-170427110008054-98113-client-B' ...
27-4-17 11:01:44.202 | TransferManager | Test worker    | INFO : - With feature ReadAfterWriteConsistent
27-4-17 11:01:44.202 | TransferManager | Test worker    | INFO : - With feature Retriable
27-4-17 11:01:44.202 | TransferManager | Test worker    | INFO : - With feature PathAware
27-4-17 11:01:44.202 | TransferManager | Test worker    | INFO : - With feature TransactionAware
27-4-17 11:01:44.202 | TransferManager | Test worker    | FINE : - Checking if selected features supported ...
27-4-17 11:01:44.202 | TransferManager | Test worker    | FINE : - Checking for duplicate features ...
27-4-17 11:01:44.203 | TransferManager | Test worker    | FINE : - Checking required feature TransactionAware in LocalTransferManager ...
27-4-17 11:01:44.203 | TransferManager | Test worker    | FINE : - Checking required feature Retriable in LocalTransferManager ...
27-4-17 11:01:44.203 | TransferManager | Test worker    | INFO : - SKIPPING unsupported optional feature ReadAfterWriteConsistent
27-4-17 11:01:44.203 | TransferManager | Test worker    | FINE : - Wrapping TransferManager LocalTransferManager in RetriableFeatureTransferManager
27-4-17 11:01:44.203 | TransferManager | Test worker    | INFO : - SKIPPING unsupported optional feature PathAware
27-4-17 11:01:44.203 | TransferManager | Test worker    | FINE : - Wrapping TransferManager RetriableFeatureTransferManager in TransactionAwareFeatureTransferManager
27-4-17 11:01:44.207 | DatabaseConnect | Test worker    | INFO : Found 12 tables.
27-4-17 11:01:44.207 | DownOperation   | Test worker    | INFO : 
27-4-17 11:01:44.207 | DownOperation   | Test worker    | INFO : Running 'Sync down' at client B ...
27-4-17 11:01:44.207 | DownOperation   | Test worker    | INFO : --------------------------------------------
27-4-17 11:01:44.207 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event DownStartSyncExternalEvent (org.syncany.operations.daemon.messages.DownStartSyncExternalEvent@1829e68d) ...
27-4-17 11:01:44.207 | AbstractTransfe | Test worker    | INFO : Looking for other running remote operations ...
27-4-17 11:01:44.207 | DatabaseConnect | Test worker    | INFO : Found 12 tables.
27-4-17 11:01:44.207 | LsRemoteOperati | Test worker    | INFO : 
27-4-17 11:01:44.207 | LsRemoteOperati | Test worker    | INFO : Running 'Remote Status' at client B ...
27-4-17 11:01:44.207 | LsRemoteOperati | Test worker    | INFO : --------------------------------------------
27-4-17 11:01:44.207 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event LsRemoteStartSyncExternalEvent (org.syncany.operations.daemon.messages.LsRemoteStartSyncExternalEvent@4f33647d) ...
27-4-17 11:01:44.208 | LsRemoteOperati | Test worker    | INFO : Retrieving remote database list.
27-4-17 11:01:44.208 | LsRemoteOperati | Test worker    | INFO : - Remote database database-A-0000000002 is already known (in local database). Ignoring.
27-4-17 11:01:44.208 | LsRemoteOperati | Test worker    | INFO : - Remote database database-B-0000000001 is already known (in local database). Ignoring.
27-4-17 11:01:44.208 | LsRemoteOperati | Test worker    | INFO : - Remote database database-A-0000000001 is already known (in local database). Ignoring.
27-4-17 11:01:44.208 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event LsRemoteEndSyncExternalEvent (org.syncany.operations.daemon.messages.LsRemoteEndSyncExternalEvent@7698e347) ...
27-4-17 11:01:44.208 | DownOperation   | Test worker    | INFO : * Nothing new. Skipping down operation.
27-4-17 11:01:44.208 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event DownEndSyncExternalEvent (org.syncany.operations.daemon.messages.DownEndSyncExternalEvent@5b2f0307) ...
27-4-17 11:01:44.208 | org.syncany.plu | Test worker    | FINE : Validating required fields
27-4-17 11:01:44.208 | TransferManager | Test worker    | INFO : Building LocalTransferManager from config 'syncany-170427110008054-98113-client-A' ...
27-4-17 11:01:44.208 | TransferManager | Test worker    | INFO : - With feature ReadAfterWriteConsistent
27-4-17 11:01:44.208 | TransferManager | Test worker    | INFO : - With feature Retriable
27-4-17 11:01:44.208 | TransferManager | Test worker    | FINE : - Checking if selected features supported ...
27-4-17 11:01:44.208 | TransferManager | Test worker    | FINE : - Checking for duplicate features ...
27-4-17 11:01:44.208 | TransferManager | Test worker    | FINE : - Checking required feature TransactionAware in LocalTransferManager ...
27-4-17 11:01:44.208 | TransferManager | Test worker    | FINE : - Checking required feature Retriable in LocalTransferManager ...
27-4-17 11:01:44.208 | TransferManager | Test worker    | INFO : - SKIPPING unsupported optional feature ReadAfterWriteConsistent
27-4-17 11:01:44.208 | TransferManager | Test worker    | FINE : - Wrapping TransferManager LocalTransferManager in RetriableFeatureTransferManager
27-4-17 11:01:44.208 | org.syncany.plu | Test worker    | FINE : Validating required fields
27-4-17 11:01:44.208 | TransferManager | Test worker    | INFO : Building LocalTransferManager from config 'syncany-170427110008054-98113-client-A' ...
27-4-17 11:01:44.208 | TransferManager | Test worker    | INFO : - With feature ReadAfterWriteConsistent
27-4-17 11:01:44.208 | TransferManager | Test worker    | INFO : - With feature Retriable
27-4-17 11:01:44.208 | TransferManager | Test worker    | INFO : - With feature PathAware
27-4-17 11:01:44.208 | TransferManager | Test worker    | INFO : - With feature TransactionAware
27-4-17 11:01:44.208 | TransferManager | Test worker    | FINE : - Checking if selected features supported ...
27-4-17 11:01:44.208 | TransferManager | Test worker    | FINE : - Checking for duplicate features ...
27-4-17 11:01:44.208 | TransferManager | Test worker    | FINE : - Checking required feature TransactionAware in LocalTransferManager ...
27-4-17 11:01:44.208 | TransferManager | Test worker    | FINE : - Checking required feature Retriable in LocalTransferManager ...
27-4-17 11:01:44.208 | TransferManager | Test worker    | INFO : - SKIPPING unsupported optional feature ReadAfterWriteConsistent
27-4-17 11:01:44.208 | TransferManager | Test worker    | FINE : - Wrapping TransferManager LocalTransferManager in RetriableFeatureTransferManager
27-4-17 11:01:44.208 | TransferManager | Test worker    | INFO : - SKIPPING unsupported optional feature PathAware
27-4-17 11:01:44.208 | TransferManager | Test worker    | FINE : - Wrapping TransferManager RetriableFeatureTransferManager in TransactionAwareFeatureTransferManager
27-4-17 11:01:44.217 | DatabaseConnect | Test worker    | INFO : Found 12 tables.
27-4-17 11:01:44.217 | UpOperation     | Test worker    | INFO : 
27-4-17 11:01:44.217 | UpOperation     | Test worker    | INFO : Running 'Sync up' at client A ...
27-4-17 11:01:44.217 | UpOperation     | Test worker    | INFO : --------------------------------------------
27-4-17 11:01:44.217 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event UpStartSyncExternalEvent (org.syncany.operations.daemon.messages.UpStartSyncExternalEvent@3d28f004) ...
27-4-17 11:01:44.217 | DatabaseConnect | Test worker    | INFO : Found 12 tables.
27-4-17 11:01:44.217 | StatusOperation | Test worker    | INFO : 
27-4-17 11:01:44.217 | StatusOperation | Test worker    | INFO : Running 'Status' at client A ...
27-4-17 11:01:44.217 | StatusOperation | Test worker    | INFO : --------------------------------------------
27-4-17 11:01:44.217 | StatusOperation | Test worker    | INFO : Querying current file tree from database ...
27-4-17 11:01:44.217 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event StatusStartSyncExternalEvent (org.syncany.operations.daemon.messages.StatusStartSyncExternalEvent@2f8fb382) ...
27-4-17 11:01:44.218 | StatusOperation | Test worker    | INFO : Analyzing local folder /tmp/syncanytest/syncany-170427110008054-98113-client-A ...
27-4-17 11:01:44.218 | StatusOperation | Test worker    | FINE : - Ignoring file (syncany app-related): .syncany
27-4-17 11:01:44.219 | StatusOperation | Test worker    | INFO : - No changes to local database
27-4-17 11:01:44.219 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event StatusEndSyncExternalEvent (org.syncany.operations.daemon.messages.StatusEndSyncExternalEvent@4d07431f) ...
27-4-17 11:01:44.219 | UpOperation     | Test worker    | INFO : Local database is up-to-date (change set). NOTHING TO DO!
27-4-17 11:01:44.219 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event UpEndSyncExternalEvent (org.syncany.operations.daemon.messages.UpEndSyncExternalEvent@11db3414) ...
27-4-17 11:01:44.219 | org.syncany.plu | Test worker    | FINE : Validating required fields
27-4-17 11:01:44.219 | TransferManager | Test worker    | INFO : Building LocalTransferManager from config 'syncany-170427110008054-98113-client-A' ...
27-4-17 11:01:44.219 | TransferManager | Test worker    | INFO : - With feature ReadAfterWriteConsistent
27-4-17 11:01:44.219 | TransferManager | Test worker    | INFO : - With feature Retriable
27-4-17 11:01:44.219 | TransferManager | Test worker    | FINE : - Checking if selected features supported ...
27-4-17 11:01:44.219 | TransferManager | Test worker    | FINE : - Checking for duplicate features ...
27-4-17 11:01:44.219 | TransferManager | Test worker    | FINE : - Checking required feature TransactionAware in LocalTransferManager ...
27-4-17 11:01:44.219 | TransferManager | Test worker    | FINE : - Checking required feature Retriable in LocalTransferManager ...
27-4-17 11:01:44.219 | TransferManager | Test worker    | INFO : - SKIPPING unsupported optional feature ReadAfterWriteConsistent
27-4-17 11:01:44.219 | TransferManager | Test worker    | FINE : - Wrapping TransferManager LocalTransferManager in RetriableFeatureTransferManager
27-4-17 11:01:44.219 | org.syncany.plu | Test worker    | FINE : Validating required fields
27-4-17 11:01:44.219 | TransferManager | Test worker    | INFO : Building LocalTransferManager from config 'syncany-170427110008054-98113-client-A' ...
27-4-17 11:01:44.219 | TransferManager | Test worker    | INFO : - With feature ReadAfterWriteConsistent
27-4-17 11:01:44.219 | TransferManager | Test worker    | INFO : - With feature Retriable
27-4-17 11:01:44.219 | TransferManager | Test worker    | INFO : - With feature PathAware
27-4-17 11:01:44.219 | TransferManager | Test worker    | INFO : - With feature TransactionAware
27-4-17 11:01:44.219 | TransferManager | Test worker    | FINE : - Checking if selected features supported ...
27-4-17 11:01:44.219 | TransferManager | Test worker    | FINE : - Checking for duplicate features ...
27-4-17 11:01:44.219 | TransferManager | Test worker    | FINE : - Checking required feature TransactionAware in LocalTransferManager ...
27-4-17 11:01:44.219 | TransferManager | Test worker    | FINE : - Checking required feature Retriable in LocalTransferManager ...
27-4-17 11:01:44.219 | TransferManager | Test worker    | INFO : - SKIPPING unsupported optional feature ReadAfterWriteConsistent
27-4-17 11:01:44.219 | TransferManager | Test worker    | FINE : - Wrapping TransferManager LocalTransferManager in RetriableFeatureTransferManager
27-4-17 11:01:44.219 | TransferManager | Test worker    | INFO : - SKIPPING unsupported optional feature PathAware
27-4-17 11:01:44.219 | TransferManager | Test worker    | FINE : - Wrapping TransferManager RetriableFeatureTransferManager in TransactionAwareFeatureTransferManager
27-4-17 11:01:44.222 | DatabaseConnect | Test worker    | INFO : Found 12 tables.
27-4-17 11:01:44.222 | DownOperation   | Test worker    | INFO : 
27-4-17 11:01:44.222 | DownOperation   | Test worker    | INFO : Running 'Sync down' at client A ...
27-4-17 11:01:44.222 | DownOperation   | Test worker    | INFO : --------------------------------------------
27-4-17 11:01:44.222 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event DownStartSyncExternalEvent (org.syncany.operations.daemon.messages.DownStartSyncExternalEvent@2d70cc6d) ...
27-4-17 11:01:44.222 | AbstractTransfe | Test worker    | INFO : Looking for other running remote operations ...
27-4-17 11:01:44.223 | DatabaseConnect | Test worker    | INFO : Found 12 tables.
27-4-17 11:01:44.223 | LsRemoteOperati | Test worker    | INFO : 
27-4-17 11:01:44.223 | LsRemoteOperati | Test worker    | INFO : Running 'Remote Status' at client A ...
27-4-17 11:01:44.223 | LsRemoteOperati | Test worker    | INFO : --------------------------------------------
27-4-17 11:01:44.223 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event LsRemoteStartSyncExternalEvent (org.syncany.operations.daemon.messages.LsRemoteStartSyncExternalEvent@122b89a4) ...
27-4-17 11:01:44.224 | LsRemoteOperati | Test worker    | INFO : Retrieving remote database list.
27-4-17 11:01:44.224 | LsRemoteOperati | Test worker    | INFO : - Remote database database-A-0000000002 is already known (in local database). Ignoring.
27-4-17 11:01:44.224 | LsRemoteOperati | Test worker    | INFO : - Remote database database-B-0000000001 is new.
27-4-17 11:01:44.224 | LsRemoteOperati | Test worker    | INFO : - Remote database database-A-0000000001 is already known (in local database). Ignoring.
27-4-17 11:01:44.224 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event LsRemoteEndSyncExternalEvent (org.syncany.operations.daemon.messages.LsRemoteEndSyncExternalEvent@7d4676cb) ...
27-4-17 11:01:44.224 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event DownChangesDetectedSyncExternalEvent (org.syncany.operations.daemon.messages.DownChangesDetectedSyncExternalEvent@64accf41) ...
27-4-17 11:01:44.224 | ActionFileHandl | Test worker    | INFO : Starting action for RemoteFile[name=action-down-A-1493290904220] ...
27-4-17 11:01:44.224 | ActionFileHandl | Test worker    | INFO : Uploading action file: RemoteFile[name=action-down-A-1493290904220]
27-4-17 11:01:44.225 | ActionFileHandl | Test worker    | INFO : Scheduling action renewal task for every 2 minutes, for RemoteFile[name=action-down-A-1493290904220] ...
27-4-17 11:01:44.225 | DownOperation   | Test worker    | INFO : Downloading unknown databases.
27-4-17 11:01:44.225 | DownOperation   | Test worker    | INFO : - Downloading database-B-0000000001 to local cache at /tmp/syncanytest/syncany-170427110008054-98113-client-A/.syncany/cache/database-B-0000000001
27-4-17 11:01:44.225 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event DownDownloadFileSyncExternalEvent (org.syncany.operations.daemon.messages.DownDownloadFileSyncExternalEvent@443a4542) ...
27-4-17 11:01:44.226 | DownOperation   | Test worker    | INFO : Loading database headers, creating branches ...
27-4-17 11:01:44.226 | DatabaseXmlSeri | Test worker    | INFO : - Loading database (HEADER_ONLY) from file /tmp/syncanytest/syncany-170427110008054-98113-client-A/.syncany/cache/database-B-0000000001 ...
27-4-17 11:01:44.227 | DatabaseXmlPars | Test worker    | INFO :    + Added database version B/(A2,B1)/T=1493290904166
27-4-17 11:01:44.230 | LocalTransferMa | Test worker    | INFO : Cannot create instance of CleanupRemoteFile for file /tmp/syncanytest/syncany-170427110008054-98113-repo/databases/database-B-0000000001; maybe invalid file name pattern. Ignoring file.
27-4-17 11:01:44.233 | LocalTransferMa | Test worker    | INFO : Cannot create instance of CleanupRemoteFile for file /tmp/syncanytest/syncany-170427110008054-98113-repo/databases/database-A-0000000002; maybe invalid file name pattern. Ignoring file.
27-4-17 11:01:44.233 | LocalTransferMa | Test worker    | INFO : Cannot create instance of CleanupRemoteFile for file /tmp/syncanytest/syncany-170427110008054-98113-repo/databases/database-A-0000000001; maybe invalid file name pattern. Ignoring file.
27-4-17 11:01:44.234 | ApplicationSqlD | Test worker    | INFO : SQL (general_settings): Read cleanupNumber  = (not set)
27-4-17 11:01:44.234 | DownOperation   | Test worker    | INFO : Populated unknown branches: {A=[A/(A1)/T=1493290903840, A/(A2)/T=1493290904079], B=[B/(A2,B1)/T=1493290904166]}
27-4-17 11:01:44.234 | DownOperation   | Test worker    | INFO : Determine winner using database reconciliator ...
27-4-17 11:01:44.234 | DatabaseReconci | Test worker    | INFO : Adding database version to winning branch: A/(A1)/T=1493290903840
27-4-17 11:01:44.234 | DatabaseReconci | Test worker    | INFO : Adding database version to winning branch: A/(A2)/T=1493290904079
27-4-17 11:01:44.234 | DatabaseReconci | Test worker    | INFO : Adding database version to winning branch: B/(A2,B1)/T=1493290904166
27-4-17 11:01:44.234 | DatabaseReconci | Test worker    | INFO : - Winner is B with branch: 
27-4-17 11:01:44.234 | DatabaseReconci | Test worker    | INFO :   + A/(A1)/T=1493290903840
27-4-17 11:01:44.235 | DatabaseReconci | Test worker    | INFO :   + A/(A2)/T=1493290904079
27-4-17 11:01:44.235 | DatabaseReconci | Test worker    | INFO :   + B/(A2,B1)/T=1493290904166
27-4-17 11:01:44.235 | DownOperation   | Test worker    | INFO : - Database versions to REMOVE locally: []
27-4-17 11:01:44.235 | DownOperation   | Test worker    | INFO :   + Nothing to purge locally. No conflicts. Only updates. Nice!
27-4-17 11:01:44.235 | DatabaseReconci | Test worker    | INFO : Finding winnersApplyBranch.
27-4-17 11:01:44.235 | DatabaseReconci | Test worker    | INFO : Losers Branch: [A/(A1)/T=1493290903840, A/(A2)/T=1493290904079]
27-4-17 11:01:44.235 | DatabaseReconci | Test worker    | INFO : Winners Branch: [A/(A1)/T=1493290903840, A/(A2)/T=1493290904079, B/(A2,B1)/T=1493290904166]
27-4-17 11:01:44.235 | DownOperation   | Test worker    | INFO : - Cleanup occurred: false
27-4-17 11:01:44.235 | DownOperation   | Test worker    | INFO : - Database versions to APPLY locally: [B/(A2,B1)/T=1493290904166]
27-4-17 11:01:44.235 | DownOperation   | Test worker    | INFO : Loading winners database (DEFAULT) ...
27-4-17 11:01:44.235 | DatabaseXmlSeri | Test worker    | INFO : - Loading database (FULL) from file /tmp/syncanytest/syncany-170427110008054-98113-client-A/.syncany/cache/database-B-0000000001 ...
27-4-17 11:01:44.237 | DatabaseXmlPars | Test worker    | INFO :    + Added database version B/(A2,B1)/T=1493290904166
27-4-17 11:01:44.237 | DownOperation   | Test worker    | INFO : Determine file system actions ...
27-4-17 11:01:44.238 | DatabaseConnect | Test worker    | INFO : Found 12 tables.
27-4-17 11:01:44.239 | FileSystemActio | Test worker    | INFO : - Loading current file tree...
27-4-17 11:01:44.239 | FileSystemActio | Test worker    | INFO : - Determine filesystem actions ...
27-4-17 11:01:44.239 | FileSystemActio | Test worker    | INFO :   + Comparing local version: null
27-4-17 11:01:44.239 | FileSystemActio | Test worker    | INFO :     with winning version   : FileVersion [version=1, path=A-moved (B's conflicted copy, 27 Apr 17, 11-01 AM), type=FILE, status=NEW, size=51200, lastModified=Thu Apr 27 11:01:44 UTC 2017, linkTarget=null, checksum=c66a963e502fe2fbea27f59d105007c0d8acef3a, updated=Thu Apr 27 11:01:44 UTC 2017, posixPermissions=rw-rw-r--, dosAttributes=--a-]
27-4-17 11:01:44.241 | FileVersionComp | Test worker    | INFO :      - [DELETED]: Local file DIFFERS from file version, expected EXISTS = true, but actual EXISTS = false, for file A-moved (B's conflicted copy, 27 Apr 17, 11-01 AM)
27-4-17 11:01:44.241 | FileSystemActio | Test worker    | INFO :      -> (2) Deleted: Local file does NOT exist, but it should, winning version not known: FileVersion [version=1, path=A-moved (B's conflicted copy, 27 Apr 17, 11-01 AM), type=FILE, status=NEW, size=51200, lastModified=Thu Apr 27 11:01:44 UTC 2017, linkTarget=null, checksum=c66a963e502fe2fbea27f59d105007c0d8acef3a, updated=Thu Apr 27 11:01:44 UTC 2017, posixPermissions=rw-rw-r--, dosAttributes=--a-] AND /tmp/syncanytest/syncany-170427110008054-98113-client-A/A-moved (B's conflicted copy, 27 Apr 17, 11-01 AM)
27-4-17 11:01:44.241 | FileSystemActio | Test worker    | INFO :      -> NewFileSystemAction [file1=null, file2=FileVersion [version=1, path=A-moved (B's conflicted copy, 27 Apr 17, 11-01 AM), type=FILE, status=NEW, size=51200, lastModified=Thu Apr 27 11:01:44 UTC 2017, linkTarget=null, checksum=c66a963e502fe2fbea27f59d105007c0d8acef3a, updated=Thu Apr 27 11:01:44 UTC 2017, posixPermissions=rw-rw-r--, dosAttributes=--a-]]
27-4-17 11:01:44.241 | DownOperation   | Test worker    | INFO :   + Adding multichunk 142a70870fe3a53f0bc4170c3858480ad122f545 to download list ...
27-4-17 11:01:44.242 | Downloader      | Test worker    | INFO : Downloading and extracting multichunks ...
27-4-17 11:01:44.242 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event DownDownloadFileSyncExternalEvent (org.syncany.operations.daemon.messages.DownDownloadFileSyncExternalEvent@1ba561e) ...
27-4-17 11:01:44.242 | Downloader      | Test worker    | INFO :   + Downloading multichunk 142a70870fe3a53f0bc4170c3858480ad122f545 ...
27-4-17 11:01:44.242 | Downloader      | Test worker    | INFO :   + Decrypting multichunk 142a70870fe3a53f0bc4170c3858480ad122f545 ...
27-4-17 11:01:44.243 | Downloader      | Test worker    | FINE :   + Locally deleting multichunk 142a70870fe3a53f0bc4170c3858480ad122f545 ...
27-4-17 11:01:44.244 | FileSystemActio | Test worker    | INFO :    Sorted actions:
27-4-17 11:01:44.244 | FileSystemActio | Test worker    | INFO :    + NewFileSystemAction [file1=null, file2=FileVersion [version=1, path=A-moved (B's conflicted copy, 27 Apr 17, 11-01 AM), type=FILE, status=NEW, size=51200, lastModified=Thu Apr 27 11:01:44 UTC 2017, linkTarget=null, checksum=c66a963e502fe2fbea27f59d105007c0d8acef3a, updated=Thu Apr 27 11:01:44 UTC 2017, posixPermissions=rw-rw-r--, dosAttributes=--a-]]
27-4-17 11:01:44.244 | DownOperation   | Test worker    | FINE : - Applying file system actions (sorted!) ...
27-4-17 11:01:44.244 | DownOperation   | Test worker    | FINE :    +  NewFileSystemAction [file1=null, file2=FileVersion [version=1, path=A-moved (B's conflicted copy, 27 Apr 17, 11-01 AM), type=FILE, status=NEW, size=51200, lastModified=Thu Apr 27 11:01:44 UTC 2017, linkTarget=null, checksum=c66a963e502fe2fbea27f59d105007c0d8acef3a, updated=Thu Apr 27 11:01:44 UTC 2017, posixPermissions=rw-rw-r--, dosAttributes=--a-]]
27-4-17 11:01:44.244 | Assembler       | Test worker    | INFO :      - Creating file A-moved (B's conflicted copy, 27 Apr 17, 11-01 AM) to /tmp/syncanytest/syncany-170427110008054-98113-client-A/.syncany/cache/temp-reconstructedFileVersion-5035761047513898367.tmp ...
27-4-17 11:01:44.246 | FileSystemActio | Test worker    | INFO :      - Setting POSIX permissions: rw-rw-r-- ...
27-4-17 11:01:44.246 | DownOperation   | Test worker    | INFO : - Adding database versions to SQL database ...
27-4-17 11:01:44.246 | DownOperation   | Test worker    | INFO :   + Applying database version (A2,B1)
27-4-17 11:01:44.247 | DownOperation   | Test worker    | FINE :   + Contents: DatabaseVersion [header=B/(A2,B1)/T=1493290904166, chunks=1, multiChunks=1, fileContents=1, fileHistories=1]
27-4-17 11:01:44.258 | ActionFileHandl | Test worker    | INFO : Finishing action for RemoteFile[name=action-down-A-1493290904220] ...
27-4-17 11:01:44.265 | ActionFileHandl | Test worker    | INFO : Deleting action file: RemoteFile[name=action-down-A-1493290904220]
27-4-17 11:01:44.265 | AbstractTransfe | Test worker    | INFO : Cleaning own old action files ...
27-4-17 11:01:44.266 | Cache           | Test worker    | INFO : Cache size okay (103 KB), no need to clean (keep size is 500 MB)
27-4-17 11:01:44.266 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event DownEndSyncExternalEvent (org.syncany.operations.daemon.messages.DownEndSyncExternalEvent@718520a6) ...
27-4-17 11:01:44.266 | DownOperation   | Test worker    | INFO : Sync down done.
27-4-17 11:01:44.271 | FileVersionComp | Test worker    | INFO :      - [CHANGED_PATH]: Local file DIFFERS from file version, expected PATH = tmp/syncanytest/syncany-170427110008054-98113-client-A/A-moved (B's conflicted copy, 27 Apr 17, 11-01 AM), but actual PATH = tmp/syncanytest/syncany-170427110008054-98113-client-B/A-moved (B's conflicted copy, 27 Apr 17, 11-01 AM), for file tmp/syncanytest/syncany-170427110008054-98113-client-B/A-moved (B's conflicted copy, 27 Apr 17, 11-01 AM)
27-4-17 11:01:44.273 | FileVersionComp | Test worker    | INFO :      - [CHANGED_PATH]: Local file DIFFERS from file version, expected PATH = tmp/syncanytest/syncany-170427110008054-98113-client-A/A-moved, but actual PATH = tmp/syncanytest/syncany-170427110008054-98113-client-B/A-moved, for file tmp/syncanytest/syncany-170427110008054-98113-client-B/A-moved
27-4-17 11:01:44.274 | DatabaseConnect | Test worker    | INFO : Found 12 tables.
27-4-17 11:01:44.274 | DatabaseConnect | Test worker    | INFO : Found 12 tables.
27-4-17 11:01:44.289 | TestAssertUtil  | Test worker    | FINE :  Comparing database table: select CHECKSUM, SIZE from chunk order by CHECKSUM asc
27-4-17 11:01:44.289 | TestAssertUtil  | Test worker    | FINE :  Comparing database table: select STATUS, LOCALTIME, CLIENT, VECTORCLOCK_SERIALIZED from databaseversion order by ID asc
27-4-17 11:01:44.290 | TestAssertUtil  | Test worker    | FINE :  Comparing database table: select CLIENT, LOGICALTIME from databaseversion_vectorclock order by DATABASEVERSION_ID asc, CLIENT asc
27-4-17 11:01:44.290 | TestAssertUtil  | Test worker    | FINE :  Comparing database table: select CHECKSUM, SIZE from filecontent order by CHECKSUM asc
27-4-17 11:01:44.291 | TestAssertUtil  | Test worker    | FINE :  Comparing database table: select FILECONTENT_CHECKSUM, CHUNK_CHECKSUM, NUM from filecontent_chunk order by FILECONTENT_CHECKSUM asc, CHUNK_CHECKSUM asc, NUM asc
27-4-17 11:01:44.291 | TestAssertUtil  | Test worker    | FINE :  Comparing database table: select ID from filehistory order by ID asc, DATABASEVERSION_ID asc
27-4-17 11:01:44.292 | TestAssertUtil  | Test worker    | FINE :  Comparing database table: select FILEHISTORY_ID, VERSION, PATH, TYPE, STATUS, SIZE, LASTMODIFIED, LINKTARGET, FILECONTENT_CHECKSUM, UPDATED, POSIXPERMS, DOSATTRS from fileversion order by FILEHISTORY_ID asc, VERSION asc, DATABASEVERSION_ID asc
27-4-17 11:01:44.292 | TestAssertUtil  | Test worker    | FINE :  Comparing database table: select ID, SIZE from multichunk order by ID asc
27-4-17 11:01:44.293 | TestAssertUtil  | Test worker    | FINE :  Comparing database table: select MULTICHUNK_ID, CHUNK_CHECKSUM from multichunk_chunk order by MULTICHUNK_ID asc, CHUNK_CHECKSUM asc