Class org.syncany.tests.integration.scenarios.IdenticalFileMoveScenarioTest

1

tests

0

failures

0

ignored

0.303s

duration

100%

successful

Tests

Test Duration Result
testIdenticalFileMove 0.303s passed

Standard error

29-3-20 17:51:24.111 | org.syncany.plu | Test worker    | FINE : Validating required fields
29-3-20 17:51:24.111 | LocalTransferMa | Test worker    | INFO : testTargetExists: Target exists.
29-3-20 17:51:24.119 | org.syncany.plu | Test worker    | FINE : Validating required fields
29-3-20 17:51:24.120 | TransferManager | Test worker    | INFO : Building LocalTransferManager from config 'syncany-200329175011381-75279-client-A' ...
29-3-20 17:51:24.120 | TransferManager | Test worker    | INFO : - With feature ReadAfterWriteConsistent
29-3-20 17:51:24.120 | TransferManager | Test worker    | INFO : - With feature Retriable
29-3-20 17:51:24.120 | TransferManager | Test worker    | FINE : - Checking if selected features supported ...
29-3-20 17:51:24.120 | TransferManager | Test worker    | FINE : - Checking for duplicate features ...
29-3-20 17:51:24.120 | TransferManager | Test worker    | FINE : - Checking required feature TransactionAware in LocalTransferManager ...
29-3-20 17:51:24.120 | TransferManager | Test worker    | FINE : - Checking required feature Retriable in LocalTransferManager ...
29-3-20 17:51:24.120 | TransferManager | Test worker    | INFO : - SKIPPING unsupported optional feature ReadAfterWriteConsistent
29-3-20 17:51:24.120 | TransferManager | Test worker    | FINE : - Wrapping TransferManager LocalTransferManager in RetriableFeatureTransferManager
29-3-20 17:51:24.120 | org.syncany.plu | Test worker    | FINE : Validating required fields
29-3-20 17:51:24.120 | TransferManager | Test worker    | INFO : Building LocalTransferManager from config 'syncany-200329175011381-75279-client-A' ...
29-3-20 17:51:24.121 | TransferManager | Test worker    | INFO : - With feature ReadAfterWriteConsistent
29-3-20 17:51:24.121 | TransferManager | Test worker    | INFO : - With feature Retriable
29-3-20 17:51:24.121 | TransferManager | Test worker    | INFO : - With feature PathAware
29-3-20 17:51:24.121 | TransferManager | Test worker    | INFO : - With feature TransactionAware
29-3-20 17:51:24.121 | TransferManager | Test worker    | FINE : - Checking if selected features supported ...
29-3-20 17:51:24.121 | TransferManager | Test worker    | FINE : - Checking for duplicate features ...
29-3-20 17:51:24.121 | TransferManager | Test worker    | FINE : - Checking required feature TransactionAware in LocalTransferManager ...
29-3-20 17:51:24.121 | TransferManager | Test worker    | FINE : - Checking required feature Retriable in LocalTransferManager ...
29-3-20 17:51:24.121 | TransferManager | Test worker    | INFO : - SKIPPING unsupported optional feature ReadAfterWriteConsistent
29-3-20 17:51:24.121 | TransferManager | Test worker    | FINE : - Wrapping TransferManager LocalTransferManager in RetriableFeatureTransferManager
29-3-20 17:51:24.121 | TransferManager | Test worker    | INFO : - SKIPPING unsupported optional feature PathAware
29-3-20 17:51:24.121 | TransferManager | Test worker    | FINE : - Wrapping TransferManager RetriableFeatureTransferManager in TransactionAwareFeatureTransferManager
29-3-20 17:51:24.145 | hsqldb.db.HSQLD | Test worker    | INFO : Checkpoint start
29-3-20 17:51:24.145 | hsqldb.db.HSQLD | Test worker    | INFO : checkpointClose start
29-3-20 17:51:24.152 | hsqldb.db.HSQLD | Test worker    | FINE : log close size: 0
29-3-20 17:51:24.155 | hsqldb.db.HSQLD | Test worker    | INFO : checkpointClose end
29-3-20 17:51:24.156 | hsqldb.db.HSQLD | Test worker    | INFO : Checkpoint end - txts: 1
29-3-20 17:51:24.160 | DatabaseConnect | Test worker    | INFO : Found 0 tables.
29-3-20 17:51:24.160 | DatabaseConnect | Test worker    | INFO : Database has no tables. Creating tables from script.create.all.sql
29-3-20 17:51:24.161 | SqlRunner       | Test worker    | INFO : SQL (comment): -- Tables
29-3-20 17:51:24.161 | 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) ); 
29-3-20 17:51:24.163 | 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 ); 
29-3-20 17:51:24.165 | 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 ); 
29-3-20 17:51:24.167 | 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 ); 
29-3-20 17:51:24.169 | 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 ); 
29-3-20 17:51:24.171 | 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 ); 
29-3-20 17:51:24.173 | 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 ); 
29-3-20 17:51:24.175 | 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 ); 
29-3-20 17:51:24.177 | 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 ); 
29-3-20 17:51:24.179 | 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) ); 
29-3-20 17:51:24.181 | 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) ); 
29-3-20 17:51:24.183 | 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) ); 
29-3-20 17:51:24.185 | SqlRunner       | Test worker    | INFO : SQL (comment): -- Non-primary indices
29-3-20 17:51:24.186 | SqlRunner       | Test worker    | INFO : SQL:   CREATE INDEX idx_databaseversion_status ON databaseversion (status); 
29-3-20 17:51:24.186 | hsqldb.db.HSQLD | Test worker    | INFO : dataFileCache open start
29-3-20 17:51:24.189 | hsqldb.db.HSQLD | Test worker    | INFO : dataFileCache open end
29-3-20 17:51:24.190 | SqlRunner       | Test worker    | INFO : SQL: CREATE INDEX idx_databaseversion_vectorclock_serialized ON databaseversion (vectorclock_serialized); 
29-3-20 17:51:24.192 | SqlRunner       | Test worker    | INFO : SQL: CREATE INDEX idx_fileversion_path ON fileversion (path); 
29-3-20 17:51:24.194 | SqlRunner       | Test worker    | INFO : SQL: CREATE INDEX idx_fileversion_status ON fileversion (status); 
29-3-20 17:51:24.195 | SqlRunner       | Test worker    | INFO : SQL: CREATE INDEX idx_fileversion_filecontent_checksum ON fileversion (filecontent_checksum); 
29-3-20 17:51:24.197 | SqlRunner       | Test worker    | INFO : SQL (comment): -- Views
29-3-20 17:51:24.197 | 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'; 
29-3-20 17:51:24.199 | 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'; 
29-3-20 17:51:24.201 | 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; 
29-3-20 17:51:24.204 | 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'; 
29-3-20 17:51:24.210 | SqlRunner       | Test worker    | INFO : SQL (comment): -- Full Views
29-3-20 17:51:24.210 | 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; 
29-3-20 17:51:24.212 | 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; 
29-3-20 17:51:24.214 | SqlRunner       | Test worker    | INFO : SQL (comment): -- Functions
29-3-20 17:51:24.214 | SqlRunner       | Test worker    | INFO : SQL (new delimiter): end;
29-3-20 17:51:24.215 | 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; 
29-3-20 17:51:24.216 | UpOperation     | Test worker    | INFO : 
29-3-20 17:51:24.217 | UpOperation     | Test worker    | INFO : Running 'Sync up' at client A ...
29-3-20 17:51:24.217 | UpOperation     | Test worker    | INFO : --------------------------------------------
29-3-20 17:51:24.217 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event UpStartSyncExternalEvent (org.syncany.operations.daemon.messages.UpStartSyncExternalEvent@5f2f6e72) ...
29-3-20 17:51:24.220 | DatabaseConnect | Test worker    | INFO : Found 12 tables.
29-3-20 17:51:24.220 | StatusOperation | Test worker    | INFO : 
29-3-20 17:51:24.220 | StatusOperation | Test worker    | INFO : Running 'Status' at client A ...
29-3-20 17:51:24.220 | StatusOperation | Test worker    | INFO : --------------------------------------------
29-3-20 17:51:24.220 | StatusOperation | Test worker    | INFO : Querying current file tree from database ...
29-3-20 17:51:24.220 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event StatusStartSyncExternalEvent (org.syncany.operations.daemon.messages.StatusStartSyncExternalEvent@19684821) ...
29-3-20 17:51:24.221 | StatusOperation | Test worker    | INFO : Analyzing local folder /tmp/syncanytest/syncany-200329175011381-75279-client-A ...
29-3-20 17:51:24.221 | StatusOperation | Test worker    | FINE : - Ignoring file (syncany app-related): .syncany
29-3-20 17:51:24.222 | StatusOperation | Test worker    | FINE : - New file: folder
29-3-20 17:51:24.222 | StatusOperation | Test worker    | FINE : - New file: folder/subfolder1
29-3-20 17:51:24.222 | StatusOperation | Test worker    | FINE : - New file: folder/subfolder1/A-file1.jpg
29-3-20 17:51:24.222 | StatusOperation | Test worker    | FINE : - New file: folder/subfolder2
29-3-20 17:51:24.223 | StatusOperation | Test worker    | FINE : - New file: folder/subfolder2/A-file1.jpg
29-3-20 17:51:24.223 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event StatusEndSyncExternalEvent (org.syncany.operations.daemon.messages.StatusEndSyncExternalEvent@778e514c) ...
29-3-20 17:51:24.223 | AbstractTransfe | Test worker    | INFO : Looking for other running remote operations ...
29-3-20 17:51:24.229 | DatabaseConnect | Test worker    | INFO : Found 12 tables.
29-3-20 17:51:24.230 | LsRemoteOperati | Test worker    | INFO : 
29-3-20 17:51:24.230 | LsRemoteOperati | Test worker    | INFO : Running 'Remote Status' at client A ...
29-3-20 17:51:24.230 | LsRemoteOperati | Test worker    | INFO : --------------------------------------------
29-3-20 17:51:24.230 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event LsRemoteStartSyncExternalEvent (org.syncany.operations.daemon.messages.LsRemoteStartSyncExternalEvent@332f35ab) ...
29-3-20 17:51:24.230 | LsRemoteOperati | Test worker    | INFO : Retrieving remote database list.
29-3-20 17:51:24.231 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event LsRemoteEndSyncExternalEvent (org.syncany.operations.daemon.messages.LsRemoteEndSyncExternalEvent@367d2457) ...
29-3-20 17:51:24.231 | UpOperation     | Test worker    | INFO : No remote changes, ready to upload.
29-3-20 17:51:24.231 | ActionFileHandl | Test worker    | INFO : Starting action for RemoteFile[name=action-up-A-1585504284121] ...
29-3-20 17:51:24.231 | ActionFileHandl | Test worker    | INFO : Uploading action file: RemoteFile[name=action-up-A-1585504284121]
29-3-20 17:51:24.231 | ActionFileHandl | Test worker    | INFO : Scheduling action renewal task for every 2 minutes, for RemoteFile[name=action-up-A-1585504284121] ...
29-3-20 17:51:24.233 | DatabaseConnect | Test worker    | INFO : Found 12 tables.
29-3-20 17:51:24.233 | TransactionAwar | Test worker    | INFO : Done rolling back previous transactions.
29-3-20 17:51:24.233 | AsyncIndexer    | AsyncI/syncany | INFO : Starting Indexing.
29-3-20 17:51:24.235 | InternalEventBu | AsyncI/syncany | INFO : Event bus 'LocalEventBus': Posting event UpIndexStartSyncExternalEvent (org.syncany.operations.daemon.messages.UpIndexStartSyncExternalEvent@2a0cdd1b) ...
29-3-20 17:51:24.235 | Indexer         | AsyncI/syncany | FINE : - Looking for deleted files ...
29-3-20 17:51:24.235 | Indexer         | AsyncI/syncany | FINE : - +File /tmp/syncanytest/syncany-200329175011381-75279-client-A/folder
29-3-20 17:51:24.235 | Indexer         | AsyncI/syncany | FINE : - /File: folder (directory/symlink/0-byte-file)
29-3-20 17:51:24.240 | Indexer         | AsyncI/syncany | FINE :    * No old file history found, starting new history (path: folder, FOLDER)
29-3-20 17:51:24.240 | FileVersionComp | AsyncI/syncany | INFO :      - [DELETED]: Local file DIFFERS from file version, actual file is NULL, for file folder
29-3-20 17:51:24.241 | Indexer         | AsyncI/syncany | INFO :    * Added file version:    FileVersion [version=1, path=folder, type=FOLDER, status=NEW, size=4096, lastModified=Sun Mar 29 17:51:24 UTC 2020, linkTarget=null, checksum=null, updated=Sun Mar 29 17:51:24 UTC 2020, posixPermissions=rwxrwxr-x, dosAttributes=--a-]
29-3-20 17:51:24.241 | Indexer         | AsyncI/syncany | INFO :      based on file version: null
29-3-20 17:51:24.241 | InternalEventBu | AsyncI/syncany | INFO : Event bus 'LocalEventBus': Posting event UpIndexChangesDetectedSyncExternalEvent (org.syncany.operations.daemon.messages.UpIndexChangesDetectedSyncExternalEvent@4a1fc300) ...
29-3-20 17:51:24.241 | Indexer         | AsyncI/syncany | FINE : - +File /tmp/syncanytest/syncany-200329175011381-75279-client-A/folder/subfolder1
29-3-20 17:51:24.241 | Indexer         | AsyncI/syncany | FINE : - /File: folder/subfolder1 (directory/symlink/0-byte-file)
29-3-20 17:51:24.242 | Indexer         | AsyncI/syncany | FINE :    * No old file history found, starting new history (path: folder/subfolder1, FOLDER)
29-3-20 17:51:24.242 | FileVersionComp | AsyncI/syncany | INFO :      - [DELETED]: Local file DIFFERS from file version, actual file is NULL, for file folder/subfolder1
29-3-20 17:51:24.242 | Indexer         | AsyncI/syncany | INFO :    * Added file version:    FileVersion [version=1, path=folder/subfolder1, type=FOLDER, status=NEW, size=4096, lastModified=Sun Mar 29 17:51:24 UTC 2020, linkTarget=null, checksum=null, updated=Sun Mar 29 17:51:24 UTC 2020, posixPermissions=rwxrwxr-x, dosAttributes=--a-]
29-3-20 17:51:24.242 | Indexer         | AsyncI/syncany | INFO :      based on file version: null
29-3-20 17:51:24.242 | Indexer         | AsyncI/syncany | FINE : - +File /tmp/syncanytest/syncany-200329175011381-75279-client-A/folder/subfolder1/A-file1.jpg
29-3-20 17:51:24.245 | RecursiveWatche | FsSettleTim/sy | INFO : File system actions (on watched folders) settled. Updating watches ...
29-3-20 17:51:24.245 | RecursiveWatche | FsSettleTim/sy | INFO : Registering new folders at watch service ...
29-3-20 17:51:24.245 | RecursiveWatche | FsSettleTim/sy | INFO : Cancelling stale path watches ...
29-3-20 17:51:24.245 | RecursiveWatche | FsSettleTim/sy | INFO : - Cancelling /tmp/syncanytest/syncany-200329175011381-91812-client-ClientA/folder
29-3-20 17:51:24.245 | RecursiveWatche | FsSettleTim/sy | INFO : - Cancelling /tmp/syncanytest/syncany-200329175011381-91812-client-ClientA
29-3-20 17:51:24.245 | RecursiveWatche | FsSettleTim/sy | INFO : - Firing watch event (watchEventsOccurred) ...
29-3-20 17:51:24.246 | WatchOperation  | FsSettleTim/sy | INFO : RUNNING SYNC ...
29-3-20 17:51:24.246 | InternalEventBu | FsSettleTim/sy | INFO : Event bus 'LocalEventBus': Posting event WatchStartSyncExternalEvent (org.syncany.operations.daemon.messages.WatchStartSyncExternalEvent@15ecbabc) ...
29-3-20 17:51:24.246 | org.syncany.plu | FsSettleTim/sy | FINE : Validating required fields
29-3-20 17:51:24.246 | TransferManager | FsSettleTim/sy | INFO : Building LocalTransferManager from config 'syncany-200329175011381-91812-client-ClientA' ...
29-3-20 17:51:24.246 | TransferManager | FsSettleTim/sy | INFO : - With feature ReadAfterWriteConsistent
29-3-20 17:51:24.246 | TransferManager | FsSettleTim/sy | INFO : - With feature Retriable
29-3-20 17:51:24.246 | TransferManager | FsSettleTim/sy | FINE : - Checking if selected features supported ...
29-3-20 17:51:24.246 | TransferManager | FsSettleTim/sy | FINE : - Checking for duplicate features ...
29-3-20 17:51:24.246 | TransferManager | FsSettleTim/sy | FINE : - Checking required feature TransactionAware in LocalTransferManager ...
29-3-20 17:51:24.246 | TransferManager | FsSettleTim/sy | FINE : - Checking required feature Retriable in LocalTransferManager ...
29-3-20 17:51:24.246 | TransferManager | FsSettleTim/sy | INFO : - SKIPPING unsupported optional feature ReadAfterWriteConsistent
29-3-20 17:51:24.246 | TransferManager | FsSettleTim/sy | FINE : - Wrapping TransferManager LocalTransferManager in RetriableFeatureTransferManager
29-3-20 17:51:24.246 | org.syncany.plu | FsSettleTim/sy | FINE : Validating required fields
29-3-20 17:51:24.246 | TransferManager | FsSettleTim/sy | INFO : Building LocalTransferManager from config 'syncany-200329175011381-91812-client-ClientA' ...
29-3-20 17:51:24.246 | TransferManager | FsSettleTim/sy | INFO : - With feature ReadAfterWriteConsistent
29-3-20 17:51:24.246 | TransferManager | FsSettleTim/sy | INFO : - With feature Retriable
29-3-20 17:51:24.246 | TransferManager | FsSettleTim/sy | INFO : - With feature PathAware
29-3-20 17:51:24.246 | TransferManager | FsSettleTim/sy | INFO : - With feature TransactionAware
29-3-20 17:51:24.246 | TransferManager | FsSettleTim/sy | FINE : - Checking if selected features supported ...
29-3-20 17:51:24.246 | TransferManager | FsSettleTim/sy | FINE : - Checking for duplicate features ...
29-3-20 17:51:24.246 | TransferManager | FsSettleTim/sy | FINE : - Checking required feature TransactionAware in LocalTransferManager ...
29-3-20 17:51:24.246 | TransferManager | FsSettleTim/sy | FINE : - Checking required feature Retriable in LocalTransferManager ...
29-3-20 17:51:24.246 | TransferManager | FsSettleTim/sy | INFO : - SKIPPING unsupported optional feature ReadAfterWriteConsistent
29-3-20 17:51:24.246 | TransferManager | FsSettleTim/sy | FINE : - Wrapping TransferManager LocalTransferManager in RetriableFeatureTransferManager
29-3-20 17:51:24.246 | TransferManager | FsSettleTim/sy | INFO : - SKIPPING unsupported optional feature PathAware
29-3-20 17:51:24.246 | TransferManager | FsSettleTim/sy | FINE : - Wrapping TransferManager RetriableFeatureTransferManager in TransactionAwareFeatureTransferManager
29-3-20 17:51:24.247 | Indexer         | AsyncI/syncany | FINE : - +FileContent: /tmp/syncanytest/syncany-200329175011381-75279-client-A/folder/subfolder1/A-file1.jpg
29-3-20 17:51:24.254 | Indexer         | AsyncI/syncany | FINE : - Chunk new: d19d61694442f8c143f0b4548f899a7c76bc9c31
29-3-20 17:51:24.254 | Indexer         | AsyncI/syncany | FINE : - +MultiChunk c330e7cbc309dd96b6ccfa875b607d2924e7fa67
29-3-20 17:51:24.255 | Indexer         | AsyncI/syncany | FINE : - Chunk > MultiChunk: d19d61694442f8c143f0b4548f899a7c76bc9c31 > c330e7cbc309dd96b6ccfa875b607d2924e7fa67
29-3-20 17:51:24.255 | Indexer         | AsyncI/syncany | FINE : - Chunk > FileContent: d19d61694442f8c143f0b4548f899a7c76bc9c31 > /tmp/syncanytest/syncany-200329175011381-75279-client-A/folder/subfolder1/A-file1.jpg
29-3-20 17:51:24.257 | Indexer         | AsyncI/syncany | FINE : - /File: folder/subfolder1/A-file1.jpg (checksum d19d61694442f8c143f0b4548f899a7c76bc9c31)
29-3-20 17:51:24.258 | Indexer         | AsyncI/syncany | FINE :    * No old file history found, starting new history (path: folder/subfolder1/A-file1.jpg, checksum: d19d61694442f8c143f0b4548f899a7c76bc9c31)
29-3-20 17:51:24.258 | FileVersionComp | AsyncI/syncany | INFO :      - [DELETED]: Local file DIFFERS from file version, actual file is NULL, for file folder/subfolder1/A-file1.jpg
29-3-20 17:51:24.258 | Indexer         | AsyncI/syncany | INFO :    * Added file version:    FileVersion [version=1, path=folder/subfolder1/A-file1.jpg, type=FILE, status=NEW, size=100, lastModified=Sun Mar 29 17:51:24 UTC 2020, linkTarget=null, checksum=d19d61694442f8c143f0b4548f899a7c76bc9c31, updated=Sun Mar 29 17:51:24 UTC 2020, posixPermissions=rw-rw-r--, dosAttributes=--a-]
29-3-20 17:51:24.259 | Indexer         | AsyncI/syncany | INFO :      based on file version: null
29-3-20 17:51:24.259 | Indexer         | AsyncI/syncany | FINE : - +File /tmp/syncanytest/syncany-200329175011381-75279-client-A/folder/subfolder2
29-3-20 17:51:24.266 | Indexer         | AsyncI/syncany | FINE : - /File: folder/subfolder2 (directory/symlink/0-byte-file)
29-3-20 17:51:24.267 | Indexer         | AsyncI/syncany | FINE :    * No old file history found, starting new history (path: folder/subfolder2, FOLDER)
29-3-20 17:51:24.268 | FileVersionComp | AsyncI/syncany | INFO :      - [DELETED]: Local file DIFFERS from file version, actual file is NULL, for file folder/subfolder2
29-3-20 17:51:24.268 | Indexer         | AsyncI/syncany | INFO :    * Added file version:    FileVersion [version=1, path=folder/subfolder2, type=FOLDER, status=NEW, size=4096, lastModified=Sun Mar 29 17:51:24 UTC 2020, linkTarget=null, checksum=null, updated=Sun Mar 29 17:51:24 UTC 2020, posixPermissions=rwxrwxr-x, dosAttributes=--a-]
29-3-20 17:51:24.268 | Indexer         | AsyncI/syncany | INFO :      based on file version: null
29-3-20 17:51:24.268 | Indexer         | AsyncI/syncany | FINE : - +File /tmp/syncanytest/syncany-200329175011381-75279-client-A/folder/subfolder2/A-file1.jpg
29-3-20 17:51:24.269 | Indexer         | AsyncI/syncany | FINE : - +FileContent: /tmp/syncanytest/syncany-200329175011381-75279-client-A/folder/subfolder2/A-file1.jpg
29-3-20 17:51:24.270 | Indexer         | AsyncI/syncany | FINE : - Chunk exists: d19d61694442f8c143f0b4548f899a7c76bc9c31
29-3-20 17:51:24.270 | Indexer         | AsyncI/syncany | FINE : - Chunk > FileContent: d19d61694442f8c143f0b4548f899a7c76bc9c31 > /tmp/syncanytest/syncany-200329175011381-75279-client-A/folder/subfolder2/A-file1.jpg
29-3-20 17:51:24.270 | Indexer         | AsyncI/syncany | FINE : - /File: folder/subfolder2/A-file1.jpg (checksum d19d61694442f8c143f0b4548f899a7c76bc9c31)
29-3-20 17:51:24.271 | Indexer         | AsyncI/syncany | FINE :    * No old file history found, starting new history (path: folder/subfolder2/A-file1.jpg, checksum: d19d61694442f8c143f0b4548f899a7c76bc9c31)
29-3-20 17:51:24.275 | FileVersionComp | AsyncI/syncany | INFO :      - [DELETED]: Local file DIFFERS from file version, actual file is NULL, for file folder/subfolder2/A-file1.jpg
29-3-20 17:51:24.275 | Indexer         | AsyncI/syncany | INFO :    * Added file version:    FileVersion [version=1, path=folder/subfolder2/A-file1.jpg, type=FILE, status=NEW, size=100, lastModified=Sun Mar 29 17:51:24 UTC 2020, linkTarget=null, checksum=d19d61694442f8c143f0b4548f899a7c76bc9c31, updated=Sun Mar 29 17:51:24 UTC 2020, posixPermissions=rw-rw-r--, dosAttributes=--a-]
29-3-20 17:51:24.275 | Indexer         | AsyncI/syncany | INFO :      based on file version: null
29-3-20 17:51:24.278 | Indexer         | AsyncI/syncany | FINE : - /MultiChunk c330e7cbc309dd96b6ccfa875b607d2924e7fa67
29-3-20 17:51:24.278 | InternalEventBu | AsyncI/syncany | INFO : Event bus 'LocalEventBus': Posting event UpIndexEndSyncExternalEvent (org.syncany.operations.daemon.messages.UpIndexEndSyncExternalEvent@79b95e6e) ...
29-3-20 17:51:24.278 | Indexer         | AsyncI/syncany | FINE : Processed new database version: DatabaseVersion [header=UnknownMachine/()/T=1585504284234, chunks=1, multiChunks=1, fileContents=1, fileHistories=5]
29-3-20 17:51:24.278 | UpOperation     | Test worker    | INFO : No DIRTY data found in database (no dirty databases); Nothing to do here.
29-3-20 17:51:24.279 | UpOperation     | Test worker    | INFO : Last vector clock was: ()
29-3-20 17:51:24.279 | UpOperation     | Test worker    | INFO : Uploading new multichunks ...
29-3-20 17:51:24.280 | UpOperation     | Test worker    | INFO : - Uploading multichunk c330e7cbc309dd96b6ccfa875b607d2924e7fa67 from /tmp/syncanytest/syncany-200329175011381-75279-client-A/.syncany/cache/multichunk-c330e7cbc309dd96b6ccfa875b607d2924e7fa67 to RemoteFile[name=multichunk-c330e7cbc309dd96b6ccfa875b607d2924e7fa67] ...
29-3-20 17:51:24.280 | RemoteTransacti | Test worker    | INFO : - Adding file to TX for UPLOAD: /tmp/syncanytest/syncany-200329175011381-75279-client-A/.syncany/cache/multichunk-c330e7cbc309dd96b6ccfa875b607d2924e7fa67 -> Temp. remote file: RemoteFile[name=temp-KLwQo-multichunk-c330e7cbc309dd96b6ccfa875b607d2924e7fa67], final location: RemoteFile[name=multichunk-c330e7cbc309dd96b6ccfa875b607d2924e7fa67]
29-3-20 17:51:24.280 | UpOperation     | Test worker    | INFO : Uploading database: DatabaseVersion [header=A/(A1)/T=1585504284279, chunks=1, multiChunks=1, fileContents=1, fileHistories=5]
29-3-20 17:51:24.280 | UpOperation     | Test worker    | INFO : Saving local delta database, version A/(A1)/T=1585504284279 to file /tmp/syncanytest/syncany-200329175011381-75279-client-A/.syncany/cache/database-A-0000000001 ... 
29-3-20 17:51:24.280 | UpOperation     | Test worker    | INFO : - Saving database to /tmp/syncanytest/syncany-200329175011381-75279-client-A/.syncany/cache/database-A-0000000001 ...
29-3-20 17:51:24.281 | UpOperation     | Test worker    | INFO : - Uploading local delta database file ...
29-3-20 17:51:24.281 | UpOperation     | Test worker    | INFO : - Uploading /tmp/syncanytest/syncany-200329175011381-75279-client-A/.syncany/cache/database-A-0000000001 to RemoteFile[name=database-A-0000000001] ...
29-3-20 17:51:24.281 | RemoteTransacti | Test worker    | INFO : - Adding file to TX for UPLOAD: /tmp/syncanytest/syncany-200329175011381-75279-client-A/.syncany/cache/database-A-0000000001 -> Temp. remote file: RemoteFile[name=temp-VComL-database-A-0000000001], final location: RemoteFile[name=database-A-0000000001]
29-3-20 17:51:24.282 | UpOperation     | Test worker    | INFO : Adding shutdown hook (to allow resuming the upload) ...
29-3-20 17:51:24.282 | RemoteTransacti | Test worker    | INFO : Starting TX.commit() ...
29-3-20 17:51:24.283 | RemoteTransacti | Test worker    | INFO : Wrote transaction manifest to temporary file: /tmp/syncanytest/syncany-200329175011381-75279-client-A/.syncany/cache/temp-transaction-3781245148926932005.tmp
29-3-20 17:51:24.283 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event UpUploadFileSyncExternalEvent (org.syncany.operations.daemon.messages.UpUploadFileSyncExternalEvent@5aa7f8f0) ...
29-3-20 17:51:24.284 | RemoteTransacti | Test worker    | INFO : - Uploading remote transaction file RemoteFile[name=transaction-71fb557c] ...
29-3-20 17:51:24.285 | RemoteTransacti | Test worker    | INFO : - Starting to upload data in commit.
29-3-20 17:51:24.285 | InternalEventBu | AsyncI/syncany | INFO : Event bus 'LocalEventBus': Posting event UpIndexMidSyncExternalEvent (org.syncany.operations.daemon.messages.UpIndexMidSyncExternalEvent@37e360d) ...
29-3-20 17:51:24.285 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event UpUploadFileInTransactionSyncExternalEvent (org.syncany.operations.daemon.messages.UpUploadFileInTransactionSyncExternalEvent@79effdab) ...
29-3-20 17:51:24.285 | RemoteTransacti | Test worker    | INFO : - Uploading /tmp/syncanytest/syncany-200329175011381-75279-client-A/.syncany/cache/multichunk-c330e7cbc309dd96b6ccfa875b607d2924e7fa67 to temp. file RemoteFile[name=temp-KLwQo-multichunk-c330e7cbc309dd96b6ccfa875b607d2924e7fa67] ...
29-3-20 17:51:24.286 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event UpUploadFileInTransactionSyncExternalEvent (org.syncany.operations.daemon.messages.UpUploadFileInTransactionSyncExternalEvent@5ea530b8) ...
29-3-20 17:51:24.286 | RemoteTransacti | Test worker    | INFO : - Uploading /tmp/syncanytest/syncany-200329175011381-75279-client-A/.syncany/cache/database-A-0000000001 to temp. file RemoteFile[name=temp-VComL-database-A-0000000001] ...
29-3-20 17:51:24.286 | RemoteTransacti | Test worker    | INFO : - Moving temp. file RemoteFile[name=temp-KLwQo-multichunk-c330e7cbc309dd96b6ccfa875b607d2924e7fa67] to final location RemoteFile[name=multichunk-c330e7cbc309dd96b6ccfa875b607d2924e7fa67] ...
29-3-20 17:51:24.290 | RemoteTransacti | Test worker    | INFO : - Moving temp. file RemoteFile[name=temp-VComL-database-A-0000000001] to final location RemoteFile[name=database-A-0000000001] ...
29-3-20 17:51:24.290 | RemoteTransacti | Test worker    | INFO : - Deleting remote transaction file RemoteFile[name=transaction-71fb557c] ...
29-3-20 17:51:24.291 | RemoteTransacti | Test worker    | INFO : END of TX.commmit(): Succesfully committed transaction.
29-3-20 17:51:24.291 | RemoteTransacti | Test worker    | INFO : END of TX.delTemp(): Sucessfully deleted final files.
29-3-20 17:51:24.291 | UpOperation     | Test worker    | INFO : Persisting local SQL database (new database version A/(A1)/T=1585504284279) ...
29-3-20 17:51:24.293 | AsyncIndexer    | AsyncI/syncany | INFO : Stopping indexing. Signal end of stream with empty databaseversion
29-3-20 17:51:24.294 | hsqldb.db.HSQLD | FsSettleTim/sy | INFO : Checkpoint start
29-3-20 17:51:24.298 | hsqldb.db.HSQLD | FsSettleTim/sy | INFO : checkpointClose start
29-3-20 17:51:24.300 | UpOperation     | Test worker    | INFO : Removing DIRTY database versions from database ...
29-3-20 17:51:24.303 | hsqldb.db.HSQLD | FsSettleTim/sy | FINE : log close size: 0
29-3-20 17:51:24.305 | hsqldb.db.HSQLD | FsSettleTim/sy | INFO : checkpointClose end
29-3-20 17:51:24.306 | hsqldb.db.HSQLD | FsSettleTim/sy | INFO : Checkpoint end - txts: 1
29-3-20 17:51:24.310 | DatabaseConnect | FsSettleTim/sy | INFO : Found 0 tables.
29-3-20 17:51:24.310 | DatabaseConnect | FsSettleTim/sy | INFO : Database has no tables. Creating tables from script.create.all.sql
29-3-20 17:51:24.310 | UpOperation     | Test worker    | INFO : Adding database version to result changes:DatabaseVersion [header=A/(A1)/T=1585504284279, chunks=1, multiChunks=1, fileContents=1, fileHistories=5]
29-3-20 17:51:24.310 | UpOperation     | Test worker    | INFO : Committing local database.
29-3-20 17:51:24.310 | SqlRunner       | FsSettleTim/sy | INFO : SQL (comment): -- Tables
29-3-20 17:51:24.312 | SqlRunner       | FsSettleTim/sy | 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) ); 
29-3-20 17:51:24.312 | UpOperation     | Test worker    | FINE : Waiting for new database version.
29-3-20 17:51:24.313 | UpOperation     | Test worker    | FINE : Took new database version: DatabaseVersion [header=UnknownMachine/()/T=1585504284293, chunks=0, multiChunks=0, fileContents=0, fileHistories=0]
29-3-20 17:51:24.313 | UpOperation     | Test worker    | INFO : Sync up done.
29-3-20 17:51:24.314 | SqlRunner       | FsSettleTim/sy | 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 ); 
29-3-20 17:51:24.314 | ActionFileHandl | Test worker    | INFO : Finishing action for RemoteFile[name=action-up-A-1585504284121] ...
29-3-20 17:51:24.315 | ActionFileHandl | Test worker    | INFO : Deleting action file: RemoteFile[name=action-up-A-1585504284121]
29-3-20 17:51:24.315 | AbstractTransfe | Test worker    | INFO : Cleaning own old action files ...
29-3-20 17:51:24.315 | Cache           | Test worker    | INFO : Cache size okay (2 KB), no need to clean (keep size is 500 MB)
29-3-20 17:51:24.315 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event UpEndSyncExternalEvent (org.syncany.operations.daemon.messages.UpEndSyncExternalEvent@277657d9) ...
29-3-20 17:51:24.315 | org.syncany.plu | Test worker    | FINE : Validating required fields
29-3-20 17:51:24.316 | TransferManager | Test worker    | INFO : Building LocalTransferManager from config 'syncany-200329175011381-75279-client-A' ...
29-3-20 17:51:24.316 | TransferManager | Test worker    | INFO : - With feature ReadAfterWriteConsistent
29-3-20 17:51:24.316 | TransferManager | Test worker    | INFO : - With feature Retriable
29-3-20 17:51:24.316 | TransferManager | Test worker    | FINE : - Checking if selected features supported ...
29-3-20 17:51:24.316 | TransferManager | Test worker    | FINE : - Checking for duplicate features ...
29-3-20 17:51:24.316 | TransferManager | Test worker    | FINE : - Checking required feature TransactionAware in LocalTransferManager ...
29-3-20 17:51:24.316 | TransferManager | Test worker    | FINE : - Checking required feature Retriable in LocalTransferManager ...
29-3-20 17:51:24.316 | TransferManager | Test worker    | INFO : - SKIPPING unsupported optional feature ReadAfterWriteConsistent
29-3-20 17:51:24.316 | TransferManager | Test worker    | FINE : - Wrapping TransferManager LocalTransferManager in RetriableFeatureTransferManager
29-3-20 17:51:24.316 | org.syncany.plu | Test worker    | FINE : Validating required fields
29-3-20 17:51:24.316 | TransferManager | Test worker    | INFO : Building LocalTransferManager from config 'syncany-200329175011381-75279-client-A' ...
29-3-20 17:51:24.316 | TransferManager | Test worker    | INFO : - With feature ReadAfterWriteConsistent
29-3-20 17:51:24.316 | TransferManager | Test worker    | INFO : - With feature Retriable
29-3-20 17:51:24.316 | TransferManager | Test worker    | INFO : - With feature PathAware
29-3-20 17:51:24.316 | TransferManager | Test worker    | INFO : - With feature TransactionAware
29-3-20 17:51:24.316 | TransferManager | Test worker    | FINE : - Checking if selected features supported ...
29-3-20 17:51:24.316 | TransferManager | Test worker    | FINE : - Checking for duplicate features ...
29-3-20 17:51:24.316 | TransferManager | Test worker    | FINE : - Checking required feature TransactionAware in LocalTransferManager ...
29-3-20 17:51:24.316 | TransferManager | Test worker    | FINE : - Checking required feature Retriable in LocalTransferManager ...
29-3-20 17:51:24.316 | TransferManager | Test worker    | INFO : - SKIPPING unsupported optional feature ReadAfterWriteConsistent
29-3-20 17:51:24.316 | TransferManager | Test worker    | FINE : - Wrapping TransferManager LocalTransferManager in RetriableFeatureTransferManager
29-3-20 17:51:24.316 | TransferManager | Test worker    | INFO : - SKIPPING unsupported optional feature PathAware
29-3-20 17:51:24.316 | TransferManager | Test worker    | FINE : - Wrapping TransferManager RetriableFeatureTransferManager in TransactionAwareFeatureTransferManager
29-3-20 17:51:24.318 | SqlRunner       | FsSettleTim/sy | 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 ); 
29-3-20 17:51:24.318 | DatabaseConnect | Test worker    | INFO : Found 12 tables.
29-3-20 17:51:24.318 | UpOperation     | Test worker    | INFO : 
29-3-20 17:51:24.318 | UpOperation     | Test worker    | INFO : Running 'Sync up' at client A ...
29-3-20 17:51:24.318 | UpOperation     | Test worker    | INFO : --------------------------------------------
29-3-20 17:51:24.318 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event UpStartSyncExternalEvent (org.syncany.operations.daemon.messages.UpStartSyncExternalEvent@795f7693) ...
29-3-20 17:51:24.319 | DatabaseConnect | Test worker    | INFO : Found 12 tables.
29-3-20 17:51:24.319 | StatusOperation | Test worker    | INFO : 
29-3-20 17:51:24.319 | StatusOperation | Test worker    | INFO : Running 'Status' at client A ...
29-3-20 17:51:24.319 | StatusOperation | Test worker    | INFO : --------------------------------------------
29-3-20 17:51:24.319 | StatusOperation | Test worker    | INFO : Querying current file tree from database ...
29-3-20 17:51:24.319 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event StatusStartSyncExternalEvent (org.syncany.operations.daemon.messages.StatusStartSyncExternalEvent@52fe8199) ...
29-3-20 17:51:24.321 | StatusOperation | Test worker    | INFO : Analyzing local folder /tmp/syncanytest/syncany-200329175011381-75279-client-A ...
29-3-20 17:51:24.322 | StatusOperation | Test worker    | FINE : - New file: moved_folder
29-3-20 17:51:24.322 | StatusOperation | Test worker    | FINE : - New file: moved_folder/subfolder1
29-3-20 17:51:24.322 | SqlRunner       | FsSettleTim/sy | 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 ); 
29-3-20 17:51:24.323 | StatusOperation | Test worker    | FINE : - New file: moved_folder/subfolder1/A-file1.jpg
29-3-20 17:51:24.323 | StatusOperation | Test worker    | FINE : - New file: moved_folder/subfolder2
29-3-20 17:51:24.323 | StatusOperation | Test worker    | FINE : - New file: moved_folder/subfolder2/A-file1.jpg
29-3-20 17:51:24.323 | StatusOperation | Test worker    | FINE : - Ignoring file (syncany app-related): .syncany
29-3-20 17:51:24.324 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event StatusEndSyncExternalEvent (org.syncany.operations.daemon.messages.StatusEndSyncExternalEvent@4fb86e4) ...
29-3-20 17:51:24.324 | AbstractTransfe | Test worker    | INFO : Looking for other running remote operations ...
29-3-20 17:51:24.324 | SqlRunner       | FsSettleTim/sy | 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 ); 
29-3-20 17:51:24.325 | DatabaseConnect | Test worker    | INFO : Found 12 tables.
29-3-20 17:51:24.325 | LsRemoteOperati | Test worker    | INFO : 
29-3-20 17:51:24.325 | LsRemoteOperati | Test worker    | INFO : Running 'Remote Status' at client A ...
29-3-20 17:51:24.325 | LsRemoteOperati | Test worker    | INFO : --------------------------------------------
29-3-20 17:51:24.325 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event LsRemoteStartSyncExternalEvent (org.syncany.operations.daemon.messages.LsRemoteStartSyncExternalEvent@52b48e1e) ...
29-3-20 17:51:24.326 | LsRemoteOperati | Test worker    | INFO : Retrieving remote database list.
29-3-20 17:51:24.326 | LsRemoteOperati | Test worker    | INFO : - Remote database database-A-0000000001 is already known (in local database). Ignoring.
29-3-20 17:51:24.326 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event LsRemoteEndSyncExternalEvent (org.syncany.operations.daemon.messages.LsRemoteEndSyncExternalEvent@18fe429a) ...
29-3-20 17:51:24.326 | UpOperation     | Test worker    | INFO : No remote changes, ready to upload.
29-3-20 17:51:24.326 | ActionFileHandl | Test worker    | INFO : Starting action for RemoteFile[name=action-up-A-1585504284316] ...
29-3-20 17:51:24.326 | ActionFileHandl | Test worker    | INFO : Uploading action file: RemoteFile[name=action-up-A-1585504284316]
29-3-20 17:51:24.326 | SqlRunner       | FsSettleTim/sy | 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 ); 
29-3-20 17:51:24.326 | ActionFileHandl | Test worker    | INFO : Scheduling action renewal task for every 2 minutes, for RemoteFile[name=action-up-A-1585504284316] ...
29-3-20 17:51:24.328 | DatabaseConnect | Test worker    | INFO : Found 12 tables.
29-3-20 17:51:24.328 | SqlRunner       | FsSettleTim/sy | 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 ); 
29-3-20 17:51:24.329 | TransactionAwar | Test worker    | INFO : Done rolling back previous transactions.
29-3-20 17:51:24.329 | AsyncIndexer    | AsyncI/syncany | INFO : Starting Indexing.
29-3-20 17:51:24.329 | InternalEventBu | AsyncI/syncany | INFO : Event bus 'LocalEventBus': Posting event UpIndexStartSyncExternalEvent (org.syncany.operations.daemon.messages.UpIndexStartSyncExternalEvent@e90ef8a) ...
29-3-20 17:51:24.330 | Indexer         | AsyncI/syncany | FINE : - Looking for deleted files ...
29-3-20 17:51:24.330 | SqlRunner       | FsSettleTim/sy | 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 ); 
29-3-20 17:51:24.332 | Indexer         | AsyncI/syncany | FINE :   + Deleted: Adding DELETED version: FileVersion [version=2, path=folder, type=FOLDER, status=DELETED, size=4096, lastModified=Sun Mar 29 17:51:24 UTC 2020, linkTarget=null, checksum=null, updated=Sun Mar 29 17:51:24 UTC 2020, posixPermissions=rwxrwxr-x, dosAttributes=--a-]
29-3-20 17:51:24.332 | Indexer         | AsyncI/syncany | FINE :                            based on: FileVersion [version=1, path=folder, type=FOLDER, status=NEW, size=4096, lastModified=Sun Mar 29 17:51:24 UTC 2020, linkTarget=null, checksum=null, updated=Sun Mar 29 17:51:24 UTC 2020, posixPermissions=rwxrwxr-x, dosAttributes=--a-]
29-3-20 17:51:24.332 | SqlRunner       | FsSettleTim/sy | 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 ); 
29-3-20 17:51:24.333 | Indexer         | AsyncI/syncany | FINE :   + Deleted: Adding DELETED version: FileVersion [version=2, path=folder/subfolder1, type=FOLDER, status=DELETED, size=4096, lastModified=Sun Mar 29 17:51:24 UTC 2020, linkTarget=null, checksum=null, updated=Sun Mar 29 17:51:24 UTC 2020, posixPermissions=rwxrwxr-x, dosAttributes=--a-]
29-3-20 17:51:24.333 | Indexer         | AsyncI/syncany | FINE :                            based on: FileVersion [version=1, path=folder/subfolder1, type=FOLDER, status=NEW, size=4096, lastModified=Sun Mar 29 17:51:24 UTC 2020, linkTarget=null, checksum=null, updated=Sun Mar 29 17:51:24 UTC 2020, posixPermissions=rwxrwxr-x, dosAttributes=--a-]
29-3-20 17:51:24.334 | SqlRunner       | FsSettleTim/sy | 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) ); 
29-3-20 17:51:24.334 | Indexer         | AsyncI/syncany | FINE :   + Deleted: Adding DELETED version: FileVersion [version=2, path=folder/subfolder1/A-file1.jpg, type=FILE, status=DELETED, size=100, lastModified=Sun Mar 29 17:51:24 UTC 2020, linkTarget=null, checksum=d19d61694442f8c143f0b4548f899a7c76bc9c31, updated=Sun Mar 29 17:51:24 UTC 2020, posixPermissions=rw-rw-r--, dosAttributes=--a-]
29-3-20 17:51:24.334 | Indexer         | AsyncI/syncany | FINE :                            based on: FileVersion [version=1, path=folder/subfolder1/A-file1.jpg, type=FILE, status=NEW, size=100, lastModified=Sun Mar 29 17:51:24 UTC 2020, linkTarget=null, checksum=d19d61694442f8c143f0b4548f899a7c76bc9c31, updated=Sun Mar 29 17:51:24 UTC 2020, posixPermissions=rw-rw-r--, dosAttributes=--a-]
29-3-20 17:51:24.335 | Indexer         | AsyncI/syncany | FINE :   + Deleted: Adding DELETED version: FileVersion [version=2, path=folder/subfolder2, type=FOLDER, status=DELETED, size=4096, lastModified=Sun Mar 29 17:51:24 UTC 2020, linkTarget=null, checksum=null, updated=Sun Mar 29 17:51:24 UTC 2020, posixPermissions=rwxrwxr-x, dosAttributes=--a-]
29-3-20 17:51:24.335 | Indexer         | AsyncI/syncany | FINE :                            based on: FileVersion [version=1, path=folder/subfolder2, type=FOLDER, status=NEW, size=4096, lastModified=Sun Mar 29 17:51:24 UTC 2020, linkTarget=null, checksum=null, updated=Sun Mar 29 17:51:24 UTC 2020, posixPermissions=rwxrwxr-x, dosAttributes=--a-]
29-3-20 17:51:24.337 | SqlRunner       | FsSettleTim/sy | 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) ); 
29-3-20 17:51:24.337 | Indexer         | AsyncI/syncany | FINE :   + Deleted: Adding DELETED version: FileVersion [version=2, path=folder/subfolder2/A-file1.jpg, type=FILE, status=DELETED, size=100, lastModified=Sun Mar 29 17:51:24 UTC 2020, linkTarget=null, checksum=d19d61694442f8c143f0b4548f899a7c76bc9c31, updated=Sun Mar 29 17:51:24 UTC 2020, posixPermissions=rw-rw-r--, dosAttributes=--a-]
29-3-20 17:51:24.337 | Indexer         | AsyncI/syncany | FINE :                            based on: FileVersion [version=1, path=folder/subfolder2/A-file1.jpg, type=FILE, status=NEW, size=100, lastModified=Sun Mar 29 17:51:24 UTC 2020, linkTarget=null, checksum=d19d61694442f8c143f0b4548f899a7c76bc9c31, updated=Sun Mar 29 17:51:24 UTC 2020, posixPermissions=rw-rw-r--, dosAttributes=--a-]
29-3-20 17:51:24.337 | Indexer         | AsyncI/syncany | FINE : - +File /tmp/syncanytest/syncany-200329175011381-75279-client-A/moved_folder
29-3-20 17:51:24.337 | Indexer         | AsyncI/syncany | FINE : - /File: moved_folder (directory/symlink/0-byte-file)
29-3-20 17:51:24.338 | Indexer         | AsyncI/syncany | FINE :    * No old file history found, starting new history (path: moved_folder, FOLDER)
29-3-20 17:51:24.338 | FileVersionComp | AsyncI/syncany | INFO :      - [DELETED]: Local file DIFFERS from file version, actual file is NULL, for file moved_folder
29-3-20 17:51:24.338 | Indexer         | AsyncI/syncany | INFO :    * Added file version:    FileVersion [version=1, path=moved_folder, type=FOLDER, status=NEW, size=4096, lastModified=Sun Mar 29 17:51:24 UTC 2020, linkTarget=null, checksum=null, updated=Sun Mar 29 17:51:24 UTC 2020, posixPermissions=rwxrwxr-x, dosAttributes=--a-]
29-3-20 17:51:24.338 | Indexer         | AsyncI/syncany | INFO :      based on file version: null
29-3-20 17:51:24.338 | Indexer         | AsyncI/syncany | FINE : - +File /tmp/syncanytest/syncany-200329175011381-75279-client-A/moved_folder/subfolder1
29-3-20 17:51:24.338 | SqlRunner       | FsSettleTim/sy | INFO : SQL:  CREATE CACHED TABLE IF NOT EXISTS  general_settings ( key varchar(255) NOT NULL, value varchar(255) NOT NULL, PRIMARY KEY (key) ); 
29-3-20 17:51:24.339 | Indexer         | AsyncI/syncany | FINE : - /File: moved_folder/subfolder1 (directory/symlink/0-byte-file)
29-3-20 17:51:24.339 | Indexer         | AsyncI/syncany | FINE :    * No old file history found, starting new history (path: moved_folder/subfolder1, FOLDER)
29-3-20 17:51:24.339 | FileVersionComp | AsyncI/syncany | INFO :      - [DELETED]: Local file DIFFERS from file version, actual file is NULL, for file moved_folder/subfolder1
29-3-20 17:51:24.339 | Indexer         | AsyncI/syncany | INFO :    * Added file version:    FileVersion [version=1, path=moved_folder/subfolder1, type=FOLDER, status=NEW, size=4096, lastModified=Sun Mar 29 17:51:24 UTC 2020, linkTarget=null, checksum=null, updated=Sun Mar 29 17:51:24 UTC 2020, posixPermissions=rwxrwxr-x, dosAttributes=--a-]
29-3-20 17:51:24.339 | Indexer         | AsyncI/syncany | INFO :      based on file version: null
29-3-20 17:51:24.339 | Indexer         | AsyncI/syncany | FINE : - +File /tmp/syncanytest/syncany-200329175011381-75279-client-A/moved_folder/subfolder1/A-file1.jpg
29-3-20 17:51:24.340 | Indexer         | AsyncI/syncany | FINE : - +FileContent: /tmp/syncanytest/syncany-200329175011381-75279-client-A/moved_folder/subfolder1/A-file1.jpg
29-3-20 17:51:24.340 | Indexer         | AsyncI/syncany | FINE : - Chunk exists: d19d61694442f8c143f0b4548f899a7c76bc9c31
29-3-20 17:51:24.340 | Indexer         | AsyncI/syncany | FINE : - Chunk > FileContent: d19d61694442f8c143f0b4548f899a7c76bc9c31 > /tmp/syncanytest/syncany-200329175011381-75279-client-A/moved_folder/subfolder1/A-file1.jpg
29-3-20 17:51:24.340 | SqlRunner       | FsSettleTim/sy | INFO : SQL (comment): -- Non-primary indices
29-3-20 17:51:24.340 | SqlRunner       | FsSettleTim/sy | INFO : SQL:   CREATE INDEX idx_databaseversion_status ON databaseversion (status); 
29-3-20 17:51:24.340 | Indexer         | AsyncI/syncany | FINE : - /File: moved_folder/subfolder1/A-file1.jpg (checksum d19d61694442f8c143f0b4548f899a7c76bc9c31)
29-3-20 17:51:24.341 | hsqldb.db.HSQLD | FsSettleTim/sy | INFO : dataFileCache open start
29-3-20 17:51:24.343 | hsqldb.db.HSQLD | FsSettleTim/sy | INFO : dataFileCache open end
29-3-20 17:51:24.343 | Indexer         | AsyncI/syncany | FINE :    * Found old file history 0da899d68d80511f5c1018c389b889967aea2a4a (by checksum: d19d61694442f8c143f0b4548f899a7c76bc9c31), appending new version.
29-3-20 17:51:24.343 | FileVersionComp | AsyncI/syncany | INFO :      - [CHANGED_PATH]: Local file DIFFERS from file version, expected PATH = moved_folder/subfolder1/A-file1.jpg, but actual PATH = folder/subfolder1/A-file1.jpg, for file folder/subfolder1/A-file1.jpg
29-3-20 17:51:24.344 | Indexer         | AsyncI/syncany | INFO :    * Added file version:    FileVersion [version=2, path=moved_folder/subfolder1/A-file1.jpg, type=FILE, status=CHANGED, size=100, lastModified=Sun Mar 29 17:51:24 UTC 2020, linkTarget=null, checksum=d19d61694442f8c143f0b4548f899a7c76bc9c31, updated=Sun Mar 29 17:51:24 UTC 2020, posixPermissions=rw-rw-r--, dosAttributes=--a-]
29-3-20 17:51:24.344 | Indexer         | AsyncI/syncany | INFO :      based on file version: FileVersion [version=1, path=folder/subfolder1/A-file1.jpg, type=FILE, status=NEW, size=100, lastModified=Sun Mar 29 17:51:24 UTC 2020, linkTarget=null, checksum=d19d61694442f8c143f0b4548f899a7c76bc9c31, updated=Sun Mar 29 17:51:24 UTC 2020, posixPermissions=rw-rw-r--, dosAttributes=--a-]
29-3-20 17:51:24.344 | Indexer         | AsyncI/syncany | FINE : - +File /tmp/syncanytest/syncany-200329175011381-75279-client-A/moved_folder/subfolder2
29-3-20 17:51:24.344 | SqlRunner       | FsSettleTim/sy | INFO : SQL: CREATE INDEX idx_databaseversion_vectorclock_serialized ON databaseversion (vectorclock_serialized); 
29-3-20 17:51:24.344 | Indexer         | AsyncI/syncany | FINE : - /File: moved_folder/subfolder2 (directory/symlink/0-byte-file)
29-3-20 17:51:24.345 | Indexer         | AsyncI/syncany | FINE :    * No old file history found, starting new history (path: moved_folder/subfolder2, FOLDER)
29-3-20 17:51:24.345 | FileVersionComp | AsyncI/syncany | INFO :      - [DELETED]: Local file DIFFERS from file version, actual file is NULL, for file moved_folder/subfolder2
29-3-20 17:51:24.345 | Indexer         | AsyncI/syncany | INFO :    * Added file version:    FileVersion [version=1, path=moved_folder/subfolder2, type=FOLDER, status=NEW, size=4096, lastModified=Sun Mar 29 17:51:24 UTC 2020, linkTarget=null, checksum=null, updated=Sun Mar 29 17:51:24 UTC 2020, posixPermissions=rwxrwxr-x, dosAttributes=--a-]
29-3-20 17:51:24.345 | Indexer         | AsyncI/syncany | INFO :      based on file version: null
29-3-20 17:51:24.345 | Indexer         | AsyncI/syncany | FINE : - +File /tmp/syncanytest/syncany-200329175011381-75279-client-A/moved_folder/subfolder2/A-file1.jpg
29-3-20 17:51:24.346 | Indexer         | AsyncI/syncany | FINE : - +FileContent: /tmp/syncanytest/syncany-200329175011381-75279-client-A/moved_folder/subfolder2/A-file1.jpg
29-3-20 17:51:24.346 | Indexer         | AsyncI/syncany | FINE : - Chunk exists: d19d61694442f8c143f0b4548f899a7c76bc9c31
29-3-20 17:51:24.346 | Indexer         | AsyncI/syncany | FINE : - Chunk > FileContent: d19d61694442f8c143f0b4548f899a7c76bc9c31 > /tmp/syncanytest/syncany-200329175011381-75279-client-A/moved_folder/subfolder2/A-file1.jpg
29-3-20 17:51:24.346 | SqlRunner       | FsSettleTim/sy | INFO : SQL: CREATE INDEX idx_fileversion_path ON fileversion (path); 
29-3-20 17:51:24.346 | Indexer         | AsyncI/syncany | FINE : - /File: moved_folder/subfolder2/A-file1.jpg (checksum d19d61694442f8c143f0b4548f899a7c76bc9c31)
29-3-20 17:51:24.347 | SqlRunner       | FsSettleTim/sy | INFO : SQL: CREATE INDEX idx_fileversion_status ON fileversion (status); 
29-3-20 17:51:24.348 | Indexer         | AsyncI/syncany | FINE :    * Found old file history adeabba189c46868f14aa749ee039561cf3cfbb4 (by checksum: d19d61694442f8c143f0b4548f899a7c76bc9c31), appending new version.
29-3-20 17:51:24.348 | FileVersionComp | AsyncI/syncany | INFO :      - [CHANGED_PATH]: Local file DIFFERS from file version, expected PATH = moved_folder/subfolder2/A-file1.jpg, but actual PATH = folder/subfolder2/A-file1.jpg, for file folder/subfolder2/A-file1.jpg
29-3-20 17:51:24.348 | Indexer         | AsyncI/syncany | INFO :    * Added file version:    FileVersion [version=2, path=moved_folder/subfolder2/A-file1.jpg, type=FILE, status=CHANGED, size=100, lastModified=Sun Mar 29 17:51:24 UTC 2020, linkTarget=null, checksum=d19d61694442f8c143f0b4548f899a7c76bc9c31, updated=Sun Mar 29 17:51:24 UTC 2020, posixPermissions=rw-rw-r--, dosAttributes=--a-]
29-3-20 17:51:24.348 | Indexer         | AsyncI/syncany | INFO :      based on file version: FileVersion [version=1, path=folder/subfolder2/A-file1.jpg, type=FILE, status=NEW, size=100, lastModified=Sun Mar 29 17:51:24 UTC 2020, linkTarget=null, checksum=d19d61694442f8c143f0b4548f899a7c76bc9c31, updated=Sun Mar 29 17:51:24 UTC 2020, posixPermissions=rw-rw-r--, dosAttributes=--a-]
29-3-20 17:51:24.349 | InternalEventBu | AsyncI/syncany | INFO : Event bus 'LocalEventBus': Posting event UpIndexEndSyncExternalEvent (org.syncany.operations.daemon.messages.UpIndexEndSyncExternalEvent@59e79cc3) ...
29-3-20 17:51:24.349 | Indexer         | AsyncI/syncany | FINE : Processed new database version: DatabaseVersion [header=UnknownMachine/()/T=1585504284329, chunks=0, multiChunks=0, fileContents=0, fileHistories=8]
29-3-20 17:51:24.349 | SqlRunner       | FsSettleTim/sy | INFO : SQL: CREATE INDEX idx_fileversion_filecontent_checksum ON fileversion (filecontent_checksum); 
29-3-20 17:51:24.349 | UpOperation     | Test worker    | INFO : No DIRTY data found in database (no dirty databases); Nothing to do here.
29-3-20 17:51:24.350 | UpOperation     | Test worker    | INFO : Last vector clock was: (A1)
29-3-20 17:51:24.350 | UpOperation     | Test worker    | INFO : Uploading new multichunks ...
29-3-20 17:51:24.350 | UpOperation     | Test worker    | INFO : Uploading database: DatabaseVersion [header=A/(A2)/T=1585504284350, chunks=0, multiChunks=0, fileContents=0, fileHistories=8]
29-3-20 17:51:24.351 | SqlRunner       | FsSettleTim/sy | INFO : SQL (comment): -- Views
29-3-20 17:51:24.351 | UpOperation     | Test worker    | INFO : Saving local delta database, version A/(A2)/T=1585504284350 to file /tmp/syncanytest/syncany-200329175011381-75279-client-A/.syncany/cache/database-A-0000000002 ... 
29-3-20 17:51:24.351 | SqlRunner       | FsSettleTim/sy | 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'; 
29-3-20 17:51:24.351 | UpOperation     | Test worker    | INFO : - Saving database to /tmp/syncanytest/syncany-200329175011381-75279-client-A/.syncany/cache/database-A-0000000002 ...
29-3-20 17:51:24.352 | UpOperation     | Test worker    | INFO : - Uploading local delta database file ...
29-3-20 17:51:24.352 | UpOperation     | Test worker    | INFO : - Uploading /tmp/syncanytest/syncany-200329175011381-75279-client-A/.syncany/cache/database-A-0000000002 to RemoteFile[name=database-A-0000000002] ...
29-3-20 17:51:24.352 | RemoteTransacti | Test worker    | INFO : - Adding file to TX for UPLOAD: /tmp/syncanytest/syncany-200329175011381-75279-client-A/.syncany/cache/database-A-0000000002 -> Temp. remote file: RemoteFile[name=temp-tKQzi-database-A-0000000002], final location: RemoteFile[name=database-A-0000000002]
29-3-20 17:51:24.352 | UpOperation     | Test worker    | INFO : Adding shutdown hook (to allow resuming the upload) ...
29-3-20 17:51:24.352 | RemoteTransacti | Test worker    | INFO : Starting TX.commit() ...
29-3-20 17:51:24.353 | SqlRunner       | FsSettleTim/sy | 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'; 
29-3-20 17:51:24.354 | InternalEventBu | AsyncI/syncany | INFO : Event bus 'LocalEventBus': Posting event UpIndexMidSyncExternalEvent (org.syncany.operations.daemon.messages.UpIndexMidSyncExternalEvent@2b04dbd5) ...
29-3-20 17:51:24.355 | SqlRunner       | FsSettleTim/sy | INFO : SQL:  CREATE VIEW fileversion_master_maxversion AS SELECT DISTINCT filehistory_id, MAX(version) version FROM fileversion_master GROUP BY filehistory_id; 
29-3-20 17:51:24.355 | RemoteTransacti | Test worker    | INFO : Wrote transaction manifest to temporary file: /tmp/syncanytest/syncany-200329175011381-75279-client-A/.syncany/cache/temp-transaction-1167537186236720218.tmp
29-3-20 17:51:24.355 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event UpUploadFileSyncExternalEvent (org.syncany.operations.daemon.messages.UpUploadFileSyncExternalEvent@b189dac) ...
29-3-20 17:51:24.355 | RemoteTransacti | Test worker    | INFO : - Uploading remote transaction file RemoteFile[name=transaction-308cb272] ...
29-3-20 17:51:24.355 | RemoteTransacti | Test worker    | INFO : - Starting to upload data in commit.
29-3-20 17:51:24.356 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event UpUploadFileInTransactionSyncExternalEvent (org.syncany.operations.daemon.messages.UpUploadFileInTransactionSyncExternalEvent@76429eb1) ...
29-3-20 17:51:24.356 | RemoteTransacti | Test worker    | INFO : - Uploading /tmp/syncanytest/syncany-200329175011381-75279-client-A/.syncany/cache/database-A-0000000002 to temp. file RemoteFile[name=temp-tKQzi-database-A-0000000002] ...
29-3-20 17:51:24.357 | RemoteTransacti | Test worker    | INFO : - Moving temp. file RemoteFile[name=temp-tKQzi-database-A-0000000002] to final location RemoteFile[name=database-A-0000000002] ...
29-3-20 17:51:24.357 | RemoteTransacti | Test worker    | INFO : - Deleting remote transaction file RemoteFile[name=transaction-308cb272] ...
29-3-20 17:51:24.357 | SqlRunner       | FsSettleTim/sy | 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'; 
29-3-20 17:51:24.357 | RemoteTransacti | Test worker    | INFO : END of TX.commmit(): Succesfully committed transaction.
29-3-20 17:51:24.357 | RemoteTransacti | Test worker    | INFO : END of TX.delTemp(): Sucessfully deleted final files.
29-3-20 17:51:24.357 | UpOperation     | Test worker    | INFO : Persisting local SQL database (new database version A/(A2)/T=1585504284350) ...
29-3-20 17:51:24.360 | SqlRunner       | FsSettleTim/sy | INFO : SQL (comment): -- Full Views
29-3-20 17:51:24.360 | SqlRunner       | FsSettleTim/sy | 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; 
29-3-20 17:51:24.360 | AsyncIndexer    | AsyncI/syncany | INFO : Stopping indexing. Signal end of stream with empty databaseversion
29-3-20 17:51:24.363 | SqlRunner       | FsSettleTim/sy | 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; 
29-3-20 17:51:24.365 | SqlRunner       | FsSettleTim/sy | INFO : SQL (comment): -- Functions
29-3-20 17:51:24.366 | SqlRunner       | FsSettleTim/sy | INFO : SQL (new delimiter): end;
29-3-20 17:51:24.366 | SqlRunner       | FsSettleTim/sy | 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; 
29-3-20 17:51:24.368 | DownOperation   | FsSettleTim/sy | INFO : 
29-3-20 17:51:24.368 | DownOperation   | FsSettleTim/sy | INFO : Running 'Sync down' at client ClientAGfMjEZWjQmlPFvUFHkFc ...
29-3-20 17:51:24.368 | DownOperation   | FsSettleTim/sy | INFO : --------------------------------------------
29-3-20 17:51:24.368 | InternalEventBu | FsSettleTim/sy | INFO : Event bus 'LocalEventBus': Posting event DownStartSyncExternalEvent (org.syncany.operations.daemon.messages.DownStartSyncExternalEvent@5cc72862) ...
29-3-20 17:51:24.368 | AbstractTransfe | FsSettleTim/sy | INFO : Looking for other running remote operations ...
29-3-20 17:51:24.368 | LocalTransferMa | FsSettleTim/sy | SEVE : Unable to list directory
java.nio.file.NoSuchFileException: /tmp/syncanytest/syncany-200329175011381-91812-repo/actions
	at java.base/sun.nio.fs.UnixException.translateToIOException(UnixException.java:92)
	at java.base/sun.nio.fs.UnixException.rethrowAsIOException(UnixException.java:111)
	at java.base/sun.nio.fs.UnixException.rethrowAsIOException(UnixException.java:116)
	at java.base/sun.nio.fs.UnixFileSystemProvider.newDirectoryStream(UnixFileSystemProvider.java:428)
	at java.base/java.nio.file.Files.newDirectoryStream(Files.java:471)
	at org.syncany.plugins.local.LocalTransferManager.list(LocalTransferManager.java:224)
	at org.syncany.plugins.transfer.features.RetriableFeatureTransferManager$8.execute(RetriableFeatureTransferManager.java:146)
	at org.syncany.plugins.transfer.features.RetriableFeatureTransferManager.retryMethod(RetriableFeatureTransferManager.java:210)
	at org.syncany.plugins.transfer.features.RetriableFeatureTransferManager.list(RetriableFeatureTransferManager.java:143)
	at org.syncany.plugins.transfer.features.TransactionAwareFeatureTransferManager.list(TransactionAwareFeatureTransferManager.java:148)
	at org.syncany.operations.AbstractTransferOperation.otherRemoteOperationsRunning(AbstractTransferOperation.java:112)
	at org.syncany.operations.down.DownOperation.checkPreconditions(DownOperation.java:245)
	at org.syncany.operations.down.DownOperation.execute(DownOperation.java:137)
	at org.syncany.operations.watch.WatchOperation.runSync(WatchOperation.java:247)
	at org.syncany.operations.watch.WatchOperation.watchEventsOccurred(WatchOperation.java:304)
	at org.syncany.operations.watch.RecursiveWatcher.fireListenerEvents(RecursiveWatcher.java:175)
	at org.syncany.operations.watch.RecursiveWatcher.access$200(RecursiveWatcher.java:45)
	at org.syncany.operations.watch.RecursiveWatcher$2.run(RecursiveWatcher.java:167)
	at java.base/java.util.TimerThread.mainLoop(Timer.java:556)
	at java.base/java.util.TimerThread.run(Timer.java:506)
29-3-20 17:51:24.370 | LocalTransferMa | FsSettleTim/sy | SEVE : Unable to list directory
java.nio.file.NoSuchFileException: /tmp/syncanytest/syncany-200329175011381-91812-repo/transactions
	at java.base/sun.nio.fs.UnixException.translateToIOException(UnixException.java:92)
	at java.base/sun.nio.fs.UnixException.rethrowAsIOException(UnixException.java:111)
	at java.base/sun.nio.fs.UnixException.rethrowAsIOException(UnixException.java:116)
	at java.base/sun.nio.fs.UnixFileSystemProvider.newDirectoryStream(UnixFileSystemProvider.java:428)
	at java.base/java.nio.file.Files.newDirectoryStream(Files.java:471)
	at org.syncany.plugins.local.LocalTransferManager.list(LocalTransferManager.java:224)
	at org.syncany.plugins.transfer.features.RetriableFeatureTransferManager$8.execute(RetriableFeatureTransferManager.java:146)
	at org.syncany.plugins.transfer.features.RetriableFeatureTransferManager.retryMethod(RetriableFeatureTransferManager.java:210)
	at org.syncany.plugins.transfer.features.RetriableFeatureTransferManager.list(RetriableFeatureTransferManager.java:143)
	at org.syncany.plugins.transfer.features.TransactionAwareFeatureTransferManager.list(TransactionAwareFeatureTransferManager.java:148)
	at org.syncany.plugins.transfer.features.TransactionAwareFeatureTransferManager.retrieveRemoteTransactions(TransactionAwareFeatureTransferManager.java:485)
	at org.syncany.plugins.transfer.features.TransactionAwareFeatureTransferManager.addAndFilterFilesInTransaction(TransactionAwareFeatureTransferManager.java:431)
	at org.syncany.plugins.transfer.features.TransactionAwareFeatureTransferManager.list(TransactionAwareFeatureTransferManager.java:148)
	at org.syncany.operations.AbstractTransferOperation.otherRemoteOperationsRunning(AbstractTransferOperation.java:112)
	at org.syncany.operations.down.DownOperation.checkPreconditions(DownOperation.java:245)
	at org.syncany.operations.down.DownOperation.execute(DownOperation.java:137)
	at org.syncany.operations.watch.WatchOperation.runSync(WatchOperation.java:247)
	at org.syncany.operations.watch.WatchOperation.watchEventsOccurred(WatchOperation.java:304)
	at org.syncany.operations.watch.RecursiveWatcher.fireListenerEvents(RecursiveWatcher.java:175)
	at org.syncany.operations.watch.RecursiveWatcher.access$200(RecursiveWatcher.java:45)
	at org.syncany.operations.watch.RecursiveWatcher$2.run(RecursiveWatcher.java:167)
	at java.base/java.util.TimerThread.mainLoop(Timer.java:556)
	at java.base/java.util.TimerThread.run(Timer.java:506)
29-3-20 17:51:24.373 | DatabaseConnect | FsSettleTim/sy | INFO : Found 12 tables.
29-3-20 17:51:24.373 | LsRemoteOperati | FsSettleTim/sy | INFO : 
29-3-20 17:51:24.373 | LsRemoteOperati | FsSettleTim/sy | INFO : Running 'Remote Status' at client ClientAGfMjEZWjQmlPFvUFHkFc ...
29-3-20 17:51:24.373 | LsRemoteOperati | FsSettleTim/sy | INFO : --------------------------------------------
29-3-20 17:51:24.373 | InternalEventBu | FsSettleTim/sy | INFO : Event bus 'LocalEventBus': Posting event LsRemoteStartSyncExternalEvent (org.syncany.operations.daemon.messages.LsRemoteStartSyncExternalEvent@67c74eb0) ...
29-3-20 17:51:24.373 | LsRemoteOperati | FsSettleTim/sy | INFO : Retrieving remote database list.
29-3-20 17:51:24.374 | LocalTransferMa | FsSettleTim/sy | SEVE : Unable to list directory
java.nio.file.NoSuchFileException: /tmp/syncanytest/syncany-200329175011381-91812-repo/databases
	at java.base/sun.nio.fs.UnixException.translateToIOException(UnixException.java:92)
	at java.base/sun.nio.fs.UnixException.rethrowAsIOException(UnixException.java:111)
	at java.base/sun.nio.fs.UnixException.rethrowAsIOException(UnixException.java:116)
	at java.base/sun.nio.fs.UnixFileSystemProvider.newDirectoryStream(UnixFileSystemProvider.java:428)
	at java.base/java.nio.file.Files.newDirectoryStream(Files.java:471)
	at org.syncany.plugins.local.LocalTransferManager.list(LocalTransferManager.java:224)
	at org.syncany.plugins.transfer.features.RetriableFeatureTransferManager$8.execute(RetriableFeatureTransferManager.java:146)
	at org.syncany.plugins.transfer.features.RetriableFeatureTransferManager.retryMethod(RetriableFeatureTransferManager.java:210)
	at org.syncany.plugins.transfer.features.RetriableFeatureTransferManager.list(RetriableFeatureTransferManager.java:143)
	at org.syncany.plugins.transfer.features.TransactionAwareFeatureTransferManager.list(TransactionAwareFeatureTransferManager.java:148)
	at org.syncany.operations.ls_remote.LsRemoteOperation.listUnknownRemoteDatabases(LsRemoteOperation.java:107)
	at org.syncany.operations.ls_remote.LsRemoteOperation.execute(LsRemoteOperation.java:78)
	at org.syncany.operations.down.DownOperation.listUnknownRemoteDatabases(DownOperation.java:272)
	at org.syncany.operations.down.DownOperation.checkPreconditions(DownOperation.java:253)
	at org.syncany.operations.down.DownOperation.execute(DownOperation.java:137)
	at org.syncany.operations.watch.WatchOperation.runSync(WatchOperation.java:247)
	at org.syncany.operations.watch.WatchOperation.watchEventsOccurred(WatchOperation.java:304)
	at org.syncany.operations.watch.RecursiveWatcher.fireListenerEvents(RecursiveWatcher.java:175)
	at org.syncany.operations.watch.RecursiveWatcher.access$200(RecursiveWatcher.java:45)
	at org.syncany.operations.watch.RecursiveWatcher$2.run(RecursiveWatcher.java:167)
	at java.base/java.util.TimerThread.mainLoop(Timer.java:556)
	at java.base/java.util.TimerThread.run(Timer.java:506)
29-3-20 17:51:24.375 | LocalTransferMa | FsSettleTim/sy | SEVE : Unable to list directory
java.nio.file.NoSuchFileException: /tmp/syncanytest/syncany-200329175011381-91812-repo/transactions
	at java.base/sun.nio.fs.UnixException.translateToIOException(UnixException.java:92)
	at java.base/sun.nio.fs.UnixException.rethrowAsIOException(UnixException.java:111)
	at java.base/sun.nio.fs.UnixException.rethrowAsIOException(UnixException.java:116)
	at java.base/sun.nio.fs.UnixFileSystemProvider.newDirectoryStream(UnixFileSystemProvider.java:428)
	at java.base/java.nio.file.Files.newDirectoryStream(Files.java:471)
	at org.syncany.plugins.local.LocalTransferManager.list(LocalTransferManager.java:224)
	at org.syncany.plugins.transfer.features.RetriableFeatureTransferManager$8.execute(RetriableFeatureTransferManager.java:146)
	at org.syncany.plugins.transfer.features.RetriableFeatureTransferManager.retryMethod(RetriableFeatureTransferManager.java:210)
	at org.syncany.plugins.transfer.features.RetriableFeatureTransferManager.list(RetriableFeatureTransferManager.java:143)
	at org.syncany.plugins.transfer.features.TransactionAwareFeatureTransferManager.list(TransactionAwareFeatureTransferManager.java:148)
	at org.syncany.plugins.transfer.features.TransactionAwareFeatureTransferManager.retrieveRemoteTransactions(TransactionAwareFeatureTransferManager.java:485)
	at org.syncany.plugins.transfer.features.TransactionAwareFeatureTransferManager.addAndFilterFilesInTransaction(TransactionAwareFeatureTransferManager.java:431)
	at org.syncany.plugins.transfer.features.TransactionAwareFeatureTransferManager.list(TransactionAwareFeatureTransferManager.java:148)
	at org.syncany.operations.ls_remote.LsRemoteOperation.listUnknownRemoteDatabases(LsRemoteOperation.java:107)
	at org.syncany.operations.ls_remote.LsRemoteOperation.execute(LsRemoteOperation.java:78)
	at org.syncany.operations.down.DownOperation.listUnknownRemoteDatabases(DownOperation.java:272)
	at org.syncany.operations.down.DownOperation.checkPreconditions(DownOperation.java:253)
	at org.syncany.operations.down.DownOperation.execute(DownOperation.java:137)
	at org.syncany.operations.watch.WatchOperation.runSync(WatchOperation.java:247)
	at org.syncany.operations.watch.WatchOperation.watchEventsOccurred(WatchOperation.java:304)
	at org.syncany.operations.watch.RecursiveWatcher.fireListenerEvents(RecursiveWatcher.java:175)
	at org.syncany.operations.watch.RecursiveWatcher.access$200(RecursiveWatcher.java:45)
	at org.syncany.operations.watch.RecursiveWatcher$2.run(RecursiveWatcher.java:167)
	at java.base/java.util.TimerThread.mainLoop(Timer.java:556)
	at java.base/java.util.TimerThread.run(Timer.java:506)
29-3-20 17:51:24.379 | InternalEventBu | FsSettleTim/sy | INFO : Event bus 'LocalEventBus': Posting event LsRemoteEndSyncExternalEvent (org.syncany.operations.daemon.messages.LsRemoteEndSyncExternalEvent@433b43ea) ...
29-3-20 17:51:24.379 | DownOperation   | FsSettleTim/sy | INFO : * Nothing new. Skipping down operation.
29-3-20 17:51:24.379 | InternalEventBu | FsSettleTim/sy | INFO : Event bus 'LocalEventBus': Posting event DownEndSyncExternalEvent (org.syncany.operations.daemon.messages.DownEndSyncExternalEvent@1545acb6) ...
29-3-20 17:51:24.379 | org.syncany.plu | FsSettleTim/sy | FINE : Validating required fields
29-3-20 17:51:24.376 | UpOperation     | Test worker    | INFO : Removing DIRTY database versions from database ...
29-3-20 17:51:24.383 | TransferManager | FsSettleTim/sy | INFO : Building LocalTransferManager from config 'syncany-200329175011381-91812-client-ClientA' ...
29-3-20 17:51:24.383 | TransferManager | FsSettleTim/sy | INFO : - With feature ReadAfterWriteConsistent
29-3-20 17:51:24.383 | TransferManager | FsSettleTim/sy | INFO : - With feature Retriable
29-3-20 17:51:24.383 | TransferManager | FsSettleTim/sy | FINE : - Checking if selected features supported ...
29-3-20 17:51:24.383 | TransferManager | FsSettleTim/sy | FINE : - Checking for duplicate features ...
29-3-20 17:51:24.384 | TransferManager | FsSettleTim/sy | FINE : - Checking required feature TransactionAware in LocalTransferManager ...
29-3-20 17:51:24.384 | TransferManager | FsSettleTim/sy | FINE : - Checking required feature Retriable in LocalTransferManager ...
29-3-20 17:51:24.384 | TransferManager | FsSettleTim/sy | INFO : - SKIPPING unsupported optional feature ReadAfterWriteConsistent
29-3-20 17:51:24.384 | TransferManager | FsSettleTim/sy | FINE : - Wrapping TransferManager LocalTransferManager in RetriableFeatureTransferManager
29-3-20 17:51:24.384 | org.syncany.plu | FsSettleTim/sy | FINE : Validating required fields
29-3-20 17:51:24.385 | TransferManager | FsSettleTim/sy | INFO : Building LocalTransferManager from config 'syncany-200329175011381-91812-client-ClientA' ...
29-3-20 17:51:24.385 | UpOperation     | Test worker    | INFO : Adding database version to result changes:DatabaseVersion [header=A/(A2)/T=1585504284350, chunks=0, multiChunks=0, fileContents=0, fileHistories=8]
29-3-20 17:51:24.385 | TransferManager | FsSettleTim/sy | INFO : - With feature ReadAfterWriteConsistent
29-3-20 17:51:24.385 | UpOperation     | Test worker    | INFO : Committing local database.
29-3-20 17:51:24.385 | UpOperation     | Test worker    | FINE : Waiting for new database version.
29-3-20 17:51:24.385 | UpOperation     | Test worker    | FINE : Took new database version: DatabaseVersion [header=UnknownMachine/()/T=1585504284361, chunks=0, multiChunks=0, fileContents=0, fileHistories=0]
29-3-20 17:51:24.385 | UpOperation     | Test worker    | INFO : Sync up done.
29-3-20 17:51:24.385 | TransferManager | FsSettleTim/sy | INFO : - With feature Retriable
29-3-20 17:51:24.385 | TransferManager | FsSettleTim/sy | INFO : - With feature PathAware
29-3-20 17:51:24.386 | TransferManager | FsSettleTim/sy | INFO : - With feature TransactionAware
29-3-20 17:51:24.386 | TransferManager | FsSettleTim/sy | FINE : - Checking if selected features supported ...
29-3-20 17:51:24.386 | TransferManager | FsSettleTim/sy | FINE : - Checking for duplicate features ...
29-3-20 17:51:24.386 | TransferManager | FsSettleTim/sy | FINE : - Checking required feature TransactionAware in LocalTransferManager ...
29-3-20 17:51:24.386 | TransferManager | FsSettleTim/sy | FINE : - Checking required feature Retriable in LocalTransferManager ...
29-3-20 17:51:24.387 | TransferManager | FsSettleTim/sy | INFO : - SKIPPING unsupported optional feature ReadAfterWriteConsistent
29-3-20 17:51:24.387 | ActionFileHandl | Test worker    | INFO : Finishing action for RemoteFile[name=action-up-A-1585504284316] ...
29-3-20 17:51:24.387 | TransferManager | FsSettleTim/sy | FINE : - Wrapping TransferManager LocalTransferManager in RetriableFeatureTransferManager
29-3-20 17:51:24.387 | TransferManager | FsSettleTim/sy | INFO : - SKIPPING unsupported optional feature PathAware
29-3-20 17:51:24.387 | TransferManager | FsSettleTim/sy | FINE : - Wrapping TransferManager RetriableFeatureTransferManager in TransactionAwareFeatureTransferManager
29-3-20 17:51:24.388 | ActionFileHandl | Test worker    | INFO : Deleting action file: RemoteFile[name=action-up-A-1585504284316]
29-3-20 17:51:24.388 | AbstractTransfe | Test worker    | INFO : Cleaning own old action files ...
29-3-20 17:51:24.388 | Cache           | Test worker    | INFO : Cache size okay (5 KB), no need to clean (keep size is 500 MB)
29-3-20 17:51:24.388 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event UpEndSyncExternalEvent (org.syncany.operations.daemon.messages.UpEndSyncExternalEvent@5ea95fa1) ...
29-3-20 17:51:24.389 | DatabaseConnect | Test worker    | INFO : Found 12 tables.
29-3-20 17:51:24.389 | StatusOperation | Test worker    | INFO : 
29-3-20 17:51:24.389 | StatusOperation | Test worker    | INFO : Running 'Status' at client A ...
29-3-20 17:51:24.389 | StatusOperation | Test worker    | INFO : --------------------------------------------
29-3-20 17:51:24.389 | StatusOperation | Test worker    | INFO : Querying current file tree from database ...
29-3-20 17:51:24.389 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event StatusStartSyncExternalEvent (org.syncany.operations.daemon.messages.StatusStartSyncExternalEvent@46d73190) ...
29-3-20 17:51:24.390 | StatusOperation | Test worker    | INFO : Analyzing local folder /tmp/syncanytest/syncany-200329175011381-75279-client-A ...
29-3-20 17:51:24.391 | StatusOperation | Test worker    | FINE : - Ignoring file (syncany app-related): .syncany
29-3-20 17:51:24.391 | StatusOperation | Test worker    | INFO : - No changes to local database
29-3-20 17:51:24.391 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event StatusEndSyncExternalEvent (org.syncany.operations.daemon.messages.StatusEndSyncExternalEvent@77f6311b) ...
29-3-20 17:51:24.392 | DatabaseConnect | Test worker    | INFO : Found 12 tables.
29-3-20 17:51:24.392 | LsOperation     | Test worker    | INFO : 
29-3-20 17:51:24.392 | LsOperation     | Test worker    | INFO : Running 'Ls' at client A ...
29-3-20 17:51:24.392 | LsOperation     | Test worker    | INFO : --------------------------------------------
29-3-20 17:51:24.392 | FileVersionSqlD | Test worker    | INFO :  getFileTree(path = moved_folder/subfolder1/%, history = %, minSlash = 2, maxSlash = 2, date <= Sat Jan 01 01:01:01 UTC 2101, types = SYMLINK, FOLDER, FILE
29-3-20 17:51:24.397 | DatabaseConnect | FsSettleTim/sy | INFO : Found 12 tables.
29-3-20 17:51:24.397 | UpOperation     | FsSettleTim/sy | INFO : 
29-3-20 17:51:24.397 | UpOperation     | FsSettleTim/sy | INFO : Running 'Sync up' at client ClientAGfMjEZWjQmlPFvUFHkFc ...
29-3-20 17:51:24.397 | UpOperation     | FsSettleTim/sy | INFO : --------------------------------------------
29-3-20 17:51:24.397 | InternalEventBu | FsSettleTim/sy | INFO : Event bus 'LocalEventBus': Posting event UpStartSyncExternalEvent (org.syncany.operations.daemon.messages.UpStartSyncExternalEvent@15e10b81) ...
29-3-20 17:51:24.398 | DatabaseConnect | FsSettleTim/sy | INFO : Found 12 tables.
29-3-20 17:51:24.398 | StatusOperation | FsSettleTim/sy | INFO : 
29-3-20 17:51:24.398 | StatusOperation | FsSettleTim/sy | INFO : Running 'Status' at client ClientAGfMjEZWjQmlPFvUFHkFc ...
29-3-20 17:51:24.398 | StatusOperation | FsSettleTim/sy | INFO : --------------------------------------------
29-3-20 17:51:24.398 | StatusOperation | FsSettleTim/sy | INFO : Querying current file tree from database ...
29-3-20 17:51:24.398 | InternalEventBu | FsSettleTim/sy | INFO : Event bus 'LocalEventBus': Posting event StatusStartSyncExternalEvent (org.syncany.operations.daemon.messages.StatusStartSyncExternalEvent@344403f5) ...
29-3-20 17:51:24.401 | StatusOperation | FsSettleTim/sy | INFO : Analyzing local folder /tmp/syncanytest/syncany-200329175011381-91812-client-ClientA ...
29-3-20 17:51:24.404 | StatusOperation | FsSettleTim/sy | FINE : - Ignoring file (syncany app-related): .syncany
29-3-20 17:51:24.404 | StatusOperation | FsSettleTim/sy | INFO : - No changes to local database
29-3-20 17:51:24.404 | InternalEventBu | FsSettleTim/sy | INFO : Event bus 'LocalEventBus': Posting event StatusEndSyncExternalEvent (org.syncany.operations.daemon.messages.StatusEndSyncExternalEvent@76e3bbc) ...
29-3-20 17:51:24.404 | UpOperation     | FsSettleTim/sy | INFO : Local database is up-to-date (change set). NOTHING TO DO!
29-3-20 17:51:24.404 | InternalEventBu | FsSettleTim/sy | INFO : Event bus 'LocalEventBus': Posting event UpEndSyncExternalEvent (org.syncany.operations.daemon.messages.UpEndSyncExternalEvent@59fd1532) ...
29-3-20 17:51:24.404 | org.syncany.plu | FsSettleTim/sy | FINE : Validating required fields
29-3-20 17:51:24.404 | TransferManager | FsSettleTim/sy | INFO : Building LocalTransferManager from config 'syncany-200329175011381-91812-client-ClientA' ...
29-3-20 17:51:24.406 | TransferManager | FsSettleTim/sy | INFO : - With feature ReadAfterWriteConsistent
29-3-20 17:51:24.406 | TransferManager | FsSettleTim/sy | INFO : - With feature Retriable
29-3-20 17:51:24.406 | TransferManager | FsSettleTim/sy | FINE : - Checking if selected features supported ...
29-3-20 17:51:24.406 | TransferManager | FsSettleTim/sy | FINE : - Checking for duplicate features ...
29-3-20 17:51:24.406 | TransferManager | FsSettleTim/sy | FINE : - Checking required feature TransactionAware in LocalTransferManager ...
29-3-20 17:51:24.406 | TransferManager | FsSettleTim/sy | FINE : - Checking required feature Retriable in LocalTransferManager ...
29-3-20 17:51:24.406 | TransferManager | FsSettleTim/sy | INFO : - SKIPPING unsupported optional feature ReadAfterWriteConsistent
29-3-20 17:51:24.406 | TransferManager | FsSettleTim/sy | FINE : - Wrapping TransferManager LocalTransferManager in RetriableFeatureTransferManager
29-3-20 17:51:24.406 | org.syncany.plu | FsSettleTim/sy | FINE : Validating required fields
29-3-20 17:51:24.406 | TransferManager | FsSettleTim/sy | INFO : Building LocalTransferManager from config 'syncany-200329175011381-91812-client-ClientA' ...
29-3-20 17:51:24.406 | TransferManager | FsSettleTim/sy | INFO : - With feature ReadAfterWriteConsistent
29-3-20 17:51:24.406 | TransferManager | FsSettleTim/sy | INFO : - With feature Retriable
29-3-20 17:51:24.407 | TransferManager | FsSettleTim/sy | INFO : - With feature PathAware
29-3-20 17:51:24.407 | TransferManager | FsSettleTim/sy | INFO : - With feature TransactionAware
29-3-20 17:51:24.407 | TransferManager | FsSettleTim/sy | FINE : - Checking if selected features supported ...
29-3-20 17:51:24.407 | TransferManager | FsSettleTim/sy | FINE : - Checking for duplicate features ...
29-3-20 17:51:24.407 | TransferManager | FsSettleTim/sy | FINE : - Checking required feature TransactionAware in LocalTransferManager ...
29-3-20 17:51:24.407 | TransferManager | FsSettleTim/sy | FINE : - Checking required feature Retriable in LocalTransferManager ...
29-3-20 17:51:24.407 | TransferManager | FsSettleTim/sy | INFO : - SKIPPING unsupported optional feature ReadAfterWriteConsistent
29-3-20 17:51:24.407 | TransferManager | FsSettleTim/sy | FINE : - Wrapping TransferManager LocalTransferManager in RetriableFeatureTransferManager
29-3-20 17:51:24.407 | TransferManager | FsSettleTim/sy | INFO : - SKIPPING unsupported optional feature PathAware
29-3-20 17:51:24.407 | TransferManager | FsSettleTim/sy | FINE : - Wrapping TransferManager RetriableFeatureTransferManager in TransactionAwareFeatureTransferManager
29-3-20 17:51:24.409 | DatabaseConnect | Test worker    | INFO : Found 12 tables.
29-3-20 17:51:24.409 | LsOperation     | Test worker    | INFO : 
29-3-20 17:51:24.409 | LsOperation     | Test worker    | INFO : Running 'Ls' at client A ...
29-3-20 17:51:24.409 | LsOperation     | Test worker    | INFO : --------------------------------------------
29-3-20 17:51:24.409 | FileVersionSqlD | Test worker    | INFO :  getFileTree(path = moved_folder/subfolder2/%, history = %, minSlash = 2, maxSlash = 2, date <= Sat Jan 01 01:01:01 UTC 2101, types = SYMLINK, FOLDER, FILE