Class org.syncany.tests.integration.scenarios.Issue520NoResumeOnCorruptXmlScenarioTest

2

tests

0

failures

0

ignored

0.720s

duration

100%

successful

Tests

Test Duration Result
testCorruptTransactionFile 0.409s passed
testCorruptTransactionListFile 0.311s passed

Standard error

29-5-19 15:05:18.299 | org.syncany.plu | Test worker    | FINE : Validating required fields
29-5-19 15:05:18.299 | LocalTransferMa | Test worker    | INFO : testTargetExists: Target exists.
29-5-19 15:05:18.311 | org.syncany.plu | Test worker    | FINE : Validating required fields
29-5-19 15:05:18.311 | TransferManager | Test worker    | INFO : Building LocalTransferManager from config 'syncany-190529150308467-70313-client-A' ...
29-5-19 15:05:18.311 | TransferManager | Test worker    | INFO : - With feature ReadAfterWriteConsistent
29-5-19 15:05:18.311 | TransferManager | Test worker    | INFO : - With feature Retriable
29-5-19 15:05:18.311 | TransferManager | Test worker    | FINE : - Checking if selected features supported ...
29-5-19 15:05:18.311 | TransferManager | Test worker    | FINE : - Checking for duplicate features ...
29-5-19 15:05:18.311 | TransferManager | Test worker    | FINE : - Checking required feature TransactionAware in LocalTransferManager ...
29-5-19 15:05:18.311 | TransferManager | Test worker    | FINE : - Checking required feature Retriable in LocalTransferManager ...
29-5-19 15:05:18.311 | TransferManager | Test worker    | INFO : - SKIPPING unsupported optional feature ReadAfterWriteConsistent
29-5-19 15:05:18.311 | TransferManager | Test worker    | FINE : - Wrapping TransferManager LocalTransferManager in RetriableFeatureTransferManager
29-5-19 15:05:18.311 | org.syncany.plu | Test worker    | FINE : Validating required fields
29-5-19 15:05:18.311 | TransferManager | Test worker    | INFO : Building LocalTransferManager from config 'syncany-190529150308467-70313-client-A' ...
29-5-19 15:05:18.311 | TransferManager | Test worker    | INFO : - With feature ReadAfterWriteConsistent
29-5-19 15:05:18.311 | TransferManager | Test worker    | INFO : - With feature Retriable
29-5-19 15:05:18.311 | TransferManager | Test worker    | INFO : - With feature PathAware
29-5-19 15:05:18.311 | TransferManager | Test worker    | INFO : - With feature TransactionAware
29-5-19 15:05:18.311 | TransferManager | Test worker    | FINE : - Checking if selected features supported ...
29-5-19 15:05:18.311 | TransferManager | Test worker    | FINE : - Checking for duplicate features ...
29-5-19 15:05:18.312 | TransferManager | Test worker    | FINE : - Checking required feature TransactionAware in LocalTransferManager ...
29-5-19 15:05:18.312 | TransferManager | Test worker    | FINE : - Checking required feature Retriable in LocalTransferManager ...
29-5-19 15:05:18.312 | TransferManager | Test worker    | INFO : - SKIPPING unsupported optional feature ReadAfterWriteConsistent
29-5-19 15:05:18.312 | TransferManager | Test worker    | FINE : - Wrapping TransferManager LocalTransferManager in RetriableFeatureTransferManager
29-5-19 15:05:18.312 | TransferManager | Test worker    | INFO : - SKIPPING unsupported optional feature PathAware
29-5-19 15:05:18.312 | TransferManager | Test worker    | FINE : - Wrapping TransferManager RetriableFeatureTransferManager in TransactionAwareFeatureTransferManager
29-5-19 15:05:18.338 | hsqldb.db.HSQLD | Test worker    | INFO : Checkpoint start
29-5-19 15:05:18.339 | hsqldb.db.HSQLD | Test worker    | INFO : checkpointClose start
29-5-19 15:05:18.356 | hsqldb.db.HSQLD | Test worker    | FINE : log close size: 0
29-5-19 15:05:18.360 | hsqldb.db.HSQLD | Test worker    | INFO : checkpointClose end
29-5-19 15:05:18.361 | hsqldb.db.HSQLD | Test worker    | INFO : Checkpoint end - txts: 1
29-5-19 15:05:18.366 | DatabaseConnect | Test worker    | INFO : Found 0 tables.
29-5-19 15:05:18.366 | DatabaseConnect | Test worker    | INFO : Database has no tables. Creating tables from script.create.all.sql
29-5-19 15:05:18.366 | SqlRunner       | Test worker    | INFO : SQL (comment): -- Tables
29-5-19 15:05:18.367 | 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-5-19 15:05:18.369 | 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-5-19 15:05:18.371 | 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-5-19 15:05:18.373 | 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-5-19 15:05:18.376 | 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-5-19 15:05:18.378 | 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-5-19 15:05:18.380 | 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-5-19 15:05:18.382 | 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-5-19 15:05:18.385 | 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-5-19 15:05:18.387 | 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-5-19 15:05:18.389 | 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-5-19 15:05:18.391 | 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-5-19 15:05:18.396 | SqlRunner       | Test worker    | INFO : SQL (comment): -- Non-primary indices
29-5-19 15:05:18.396 | SqlRunner       | Test worker    | INFO : SQL:   CREATE INDEX idx_databaseversion_status ON databaseversion (status); 
29-5-19 15:05:18.397 | hsqldb.db.HSQLD | Test worker    | INFO : dataFileCache open start
29-5-19 15:05:18.400 | hsqldb.db.HSQLD | Test worker    | INFO : dataFileCache open end
29-5-19 15:05:18.403 | SqlRunner       | Test worker    | INFO : SQL: CREATE INDEX idx_databaseversion_vectorclock_serialized ON databaseversion (vectorclock_serialized); 
29-5-19 15:05:18.406 | SqlRunner       | Test worker    | INFO : SQL: CREATE INDEX idx_fileversion_path ON fileversion (path); 
29-5-19 15:05:18.408 | SqlRunner       | Test worker    | INFO : SQL: CREATE INDEX idx_fileversion_status ON fileversion (status); 
29-5-19 15:05:18.410 | SqlRunner       | Test worker    | INFO : SQL: CREATE INDEX idx_fileversion_filecontent_checksum ON fileversion (filecontent_checksum); 
29-5-19 15:05:18.411 | SqlRunner       | Test worker    | INFO : SQL (comment): -- Views
29-5-19 15:05:18.411 | 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-5-19 15:05:18.414 | 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-5-19 15:05:18.416 | 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-5-19 15:05:18.418 | 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-5-19 15:05:18.421 | SqlRunner       | Test worker    | INFO : SQL (comment): -- Full Views
29-5-19 15:05:18.422 | 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-5-19 15:05:18.424 | 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-5-19 15:05:18.426 | SqlRunner       | Test worker    | INFO : SQL (comment): -- Functions
29-5-19 15:05:18.427 | SqlRunner       | Test worker    | INFO : SQL (new delimiter): end;
29-5-19 15:05:18.427 | 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-5-19 15:05:18.429 | UpOperation     | Test worker    | INFO : 
29-5-19 15:05:18.429 | UpOperation     | Test worker    | INFO : Running 'Sync up' at client A ...
29-5-19 15:05:18.429 | UpOperation     | Test worker    | INFO : --------------------------------------------
29-5-19 15:05:18.429 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event UpStartSyncExternalEvent (org.syncany.operations.daemon.messages.UpStartSyncExternalEvent@47322b88) ...
29-5-19 15:05:18.430 | DatabaseConnect | Test worker    | INFO : Found 12 tables.
29-5-19 15:05:18.430 | StatusOperation | Test worker    | INFO : 
29-5-19 15:05:18.430 | StatusOperation | Test worker    | INFO : Running 'Status' at client A ...
29-5-19 15:05:18.430 | StatusOperation | Test worker    | INFO : --------------------------------------------
29-5-19 15:05:18.430 | StatusOperation | Test worker    | INFO : Force checksum ENABLED.
29-5-19 15:05:18.430 | StatusOperation | Test worker    | INFO : Querying current file tree from database ...
29-5-19 15:05:18.430 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event StatusStartSyncExternalEvent (org.syncany.operations.daemon.messages.StatusStartSyncExternalEvent@1c13df0c) ...
29-5-19 15:05:18.431 | StatusOperation | Test worker    | INFO : Analyzing local folder /tmp/syncanytest/syncany-190529150308467-70313-client-A ...
29-5-19 15:05:18.431 | StatusOperation | Test worker    | FINE : - New file: file1.txt
29-5-19 15:05:18.431 | StatusOperation | Test worker    | FINE : - Ignoring file (syncany app-related): .syncany
29-5-19 15:05:18.431 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event StatusEndSyncExternalEvent (org.syncany.operations.daemon.messages.StatusEndSyncExternalEvent@18696ce9) ...
29-5-19 15:05:18.431 | AbstractTransfe | Test worker    | INFO : Looking for other running remote operations ...
29-5-19 15:05:18.432 | DatabaseConnect | Test worker    | INFO : Found 12 tables.
29-5-19 15:05:18.432 | LsRemoteOperati | Test worker    | INFO : 
29-5-19 15:05:18.432 | LsRemoteOperati | Test worker    | INFO : Running 'Remote Status' at client A ...
29-5-19 15:05:18.432 | LsRemoteOperati | Test worker    | INFO : --------------------------------------------
29-5-19 15:05:18.432 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event LsRemoteStartSyncExternalEvent (org.syncany.operations.daemon.messages.LsRemoteStartSyncExternalEvent@1cf78733) ...
29-5-19 15:05:18.435 | LsRemoteOperati | Test worker    | INFO : Retrieving remote database list.
29-5-19 15:05:18.435 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event LsRemoteEndSyncExternalEvent (org.syncany.operations.daemon.messages.LsRemoteEndSyncExternalEvent@481f9948) ...
29-5-19 15:05:18.435 | UpOperation     | Test worker    | INFO : No remote changes, ready to upload.
29-5-19 15:05:18.435 | ActionFileHandl | Test worker    | INFO : Starting action for RemoteFile[name=action-up-A-1559142318312] ...
29-5-19 15:05:18.435 | ActionFileHandl | Test worker    | INFO : Uploading action file: RemoteFile[name=action-up-A-1559142318312]
29-5-19 15:05:18.435 | ActionFileHandl | Test worker    | INFO : Scheduling action renewal task for every 2 minutes, for RemoteFile[name=action-up-A-1559142318312] ...
29-5-19 15:05:18.443 | DatabaseConnect | Test worker    | INFO : Found 12 tables.
29-5-19 15:05:18.451 | TransactionAwar | Test worker    | INFO : Done rolling back previous transactions.
29-5-19 15:05:18.452 | AsyncIndexer    | AsyncI/syncany | INFO : Starting Indexing.
29-5-19 15:05:18.452 | InternalEventBu | AsyncI/syncany | INFO : Event bus 'LocalEventBus': Posting event UpIndexStartSyncExternalEvent (org.syncany.operations.daemon.messages.UpIndexStartSyncExternalEvent@658ada42) ...
29-5-19 15:05:18.452 | Indexer         | AsyncI/syncany | FINE : - Looking for deleted files ...
29-5-19 15:05:18.452 | Indexer         | AsyncI/syncany | FINE : - +File /tmp/syncanytest/syncany-190529150308467-70313-client-A/file1.txt
29-5-19 15:05:18.453 | Indexer         | AsyncI/syncany | FINE : - +FileContent: /tmp/syncanytest/syncany-190529150308467-70313-client-A/file1.txt
29-5-19 15:05:18.455 | Indexer         | AsyncI/syncany | FINE : - Chunk new: 5db5a5b8241c59046d794605378e58d1a3c98ca4
29-5-19 15:05:18.455 | Indexer         | AsyncI/syncany | FINE : - +MultiChunk 2a7331f8f739ef38f0d4320b704622c0c197a6fa
29-5-19 15:05:18.456 | Indexer         | AsyncI/syncany | FINE : - Chunk > MultiChunk: 5db5a5b8241c59046d794605378e58d1a3c98ca4 > 2a7331f8f739ef38f0d4320b704622c0c197a6fa
29-5-19 15:05:18.456 | Indexer         | AsyncI/syncany | FINE : - Chunk > FileContent: 5db5a5b8241c59046d794605378e58d1a3c98ca4 > /tmp/syncanytest/syncany-190529150308467-70313-client-A/file1.txt
29-5-19 15:05:18.456 | Indexer         | AsyncI/syncany | FINE : - /File: file1.txt (checksum 5db5a5b8241c59046d794605378e58d1a3c98ca4)
29-5-19 15:05:18.457 | Indexer         | AsyncI/syncany | FINE :    * No old file history found, starting new history (path: file1.txt, checksum: 5db5a5b8241c59046d794605378e58d1a3c98ca4)
29-5-19 15:05:18.461 | FileVersionComp | AsyncI/syncany | INFO :      - [DELETED]: Local file DIFFERS from file version, actual file is NULL, for file file1.txt
29-5-19 15:05:18.461 | Indexer         | AsyncI/syncany | INFO :    * Added file version:    FileVersion [version=1, path=file1.txt, type=FILE, status=NEW, size=1024, lastModified=Wed May 29 15:05:18 UTC 2019, linkTarget=null, checksum=5db5a5b8241c59046d794605378e58d1a3c98ca4, updated=Wed May 29 15:05:18 UTC 2019, posixPermissions=rw-rw-r--, dosAttributes=--a-]
29-5-19 15:05:18.461 | Indexer         | AsyncI/syncany | INFO :      based on file version: null
29-5-19 15:05:18.461 | InternalEventBu | AsyncI/syncany | INFO : Event bus 'LocalEventBus': Posting event UpIndexChangesDetectedSyncExternalEvent (org.syncany.operations.daemon.messages.UpIndexChangesDetectedSyncExternalEvent@67942469) ...
29-5-19 15:05:18.462 | Indexer         | AsyncI/syncany | FINE : - /MultiChunk 2a7331f8f739ef38f0d4320b704622c0c197a6fa
29-5-19 15:05:18.462 | InternalEventBu | AsyncI/syncany | INFO : Event bus 'LocalEventBus': Posting event UpIndexEndSyncExternalEvent (org.syncany.operations.daemon.messages.UpIndexEndSyncExternalEvent@d05f1e) ...
29-5-19 15:05:18.462 | Indexer         | AsyncI/syncany | FINE : Processed new database version: DatabaseVersion [header=UnknownMachine/()/T=1559142318452, chunks=1, multiChunks=1, fileContents=1, fileHistories=1]
29-5-19 15:05:18.462 | InternalEventBu | AsyncI/syncany | INFO : Event bus 'LocalEventBus': Posting event UpIndexMidSyncExternalEvent (org.syncany.operations.daemon.messages.UpIndexMidSyncExternalEvent@627e8106) ...
29-5-19 15:05:18.462 | UpOperation     | Test worker    | INFO : No DIRTY data found in database (no dirty databases); Nothing to do here.
29-5-19 15:05:18.463 | UpOperation     | Test worker    | INFO : Last vector clock was: ()
29-5-19 15:05:18.463 | UpOperation     | Test worker    | INFO : Uploading new multichunks ...
29-5-19 15:05:18.463 | UpOperation     | Test worker    | INFO : - Uploading multichunk 2a7331f8f739ef38f0d4320b704622c0c197a6fa from /tmp/syncanytest/syncany-190529150308467-70313-client-A/.syncany/cache/multichunk-2a7331f8f739ef38f0d4320b704622c0c197a6fa to RemoteFile[name=multichunk-2a7331f8f739ef38f0d4320b704622c0c197a6fa] ...
29-5-19 15:05:18.464 | RemoteTransacti | Test worker    | INFO : - Adding file to TX for UPLOAD: /tmp/syncanytest/syncany-190529150308467-70313-client-A/.syncany/cache/multichunk-2a7331f8f739ef38f0d4320b704622c0c197a6fa -> Temp. remote file: RemoteFile[name=temp-lwTlV-multichunk-2a7331f8f739ef38f0d4320b704622c0c197a6fa], final location: RemoteFile[name=multichunk-2a7331f8f739ef38f0d4320b704622c0c197a6fa]
29-5-19 15:05:18.464 | UpOperation     | Test worker    | INFO : Uploading database: DatabaseVersion [header=A/(A1)/T=1559142318463, chunks=1, multiChunks=1, fileContents=1, fileHistories=1]
29-5-19 15:05:18.465 | UpOperation     | Test worker    | INFO : Saving local delta database, version A/(A1)/T=1559142318463 to file /tmp/syncanytest/syncany-190529150308467-70313-client-A/.syncany/cache/database-A-0000000001 ... 
29-5-19 15:05:18.465 | UpOperation     | Test worker    | INFO : - Saving database to /tmp/syncanytest/syncany-190529150308467-70313-client-A/.syncany/cache/database-A-0000000001 ...
29-5-19 15:05:18.465 | AsyncIndexer    | AsyncI/syncany | INFO : Stopping indexing. Signal end of stream with empty databaseversion
29-5-19 15:05:18.466 | UpOperation     | Test worker    | INFO : - Uploading local delta database file ...
29-5-19 15:05:18.466 | UpOperation     | Test worker    | INFO : - Uploading /tmp/syncanytest/syncany-190529150308467-70313-client-A/.syncany/cache/database-A-0000000001 to RemoteFile[name=database-A-0000000001] ...
29-5-19 15:05:18.466 | RemoteTransacti | Test worker    | INFO : - Adding file to TX for UPLOAD: /tmp/syncanytest/syncany-190529150308467-70313-client-A/.syncany/cache/database-A-0000000001 -> Temp. remote file: RemoteFile[name=temp-ewuxn-database-A-0000000001], final location: RemoteFile[name=database-A-0000000001]
29-5-19 15:05:18.470 | UpOperation     | Test worker    | INFO : Adding shutdown hook (to allow resuming the upload) ...
29-5-19 15:05:18.470 | RemoteTransacti | Test worker    | INFO : Starting TX.commit() ...
29-5-19 15:05:18.471 | RemoteTransacti | Test worker    | INFO : Wrote transaction manifest to temporary file: /tmp/syncanytest/syncany-190529150308467-70313-client-A/.syncany/cache/temp-transaction-12672454275497662078.tmp
29-5-19 15:05:18.471 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event UpUploadFileSyncExternalEvent (org.syncany.operations.daemon.messages.UpUploadFileSyncExternalEvent@5f226fe2) ...
29-5-19 15:05:18.471 | RemoteTransacti | Test worker    | INFO : - Uploading remote transaction file RemoteFile[name=transaction-2ee37860] ...
29-5-19 15:05:18.472 | RemoteTransacti | Test worker    | INFO : - Starting to upload data in commit.
29-5-19 15:05:18.472 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event UpUploadFileInTransactionSyncExternalEvent (org.syncany.operations.daemon.messages.UpUploadFileInTransactionSyncExternalEvent@6e43b6d5) ...
29-5-19 15:05:18.472 | RemoteTransacti | Test worker    | INFO : - Uploading /tmp/syncanytest/syncany-190529150308467-70313-client-A/.syncany/cache/multichunk-2a7331f8f739ef38f0d4320b704622c0c197a6fa to temp. file RemoteFile[name=temp-lwTlV-multichunk-2a7331f8f739ef38f0d4320b704622c0c197a6fa] ...
29-5-19 15:05:18.472 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event UpUploadFileInTransactionSyncExternalEvent (org.syncany.operations.daemon.messages.UpUploadFileInTransactionSyncExternalEvent@66927f24) ...
29-5-19 15:05:18.472 | RemoteTransacti | Test worker    | INFO : - Uploading /tmp/syncanytest/syncany-190529150308467-70313-client-A/.syncany/cache/database-A-0000000001 to temp. file RemoteFile[name=temp-ewuxn-database-A-0000000001] ...
29-5-19 15:05:18.472 | RemoteTransacti | Test worker    | INFO : - Moving temp. file RemoteFile[name=temp-lwTlV-multichunk-2a7331f8f739ef38f0d4320b704622c0c197a6fa] to final location RemoteFile[name=multichunk-2a7331f8f739ef38f0d4320b704622c0c197a6fa] ...
29-5-19 15:05:18.473 | RemoteTransacti | Test worker    | INFO : - Moving temp. file RemoteFile[name=temp-ewuxn-database-A-0000000001] to final location RemoteFile[name=database-A-0000000001] ...
29-5-19 15:05:18.473 | RemoteTransacti | Test worker    | INFO : - Deleting remote transaction file RemoteFile[name=transaction-2ee37860] ...
29-5-19 15:05:18.473 | RemoteTransacti | Test worker    | INFO : END of TX.commmit(): Succesfully committed transaction.
29-5-19 15:05:18.473 | RemoteTransacti | Test worker    | INFO : END of TX.delTemp(): Sucessfully deleted final files.
29-5-19 15:05:18.473 | UpOperation     | Test worker    | INFO : Persisting local SQL database (new database version A/(A1)/T=1559142318463) ...
29-5-19 15:05:18.484 | UpOperation     | Test worker    | INFO : Removing DIRTY database versions from database ...
29-5-19 15:05:18.500 | UpOperation     | Test worker    | INFO : Adding database version to result changes:DatabaseVersion [header=A/(A1)/T=1559142318463, chunks=1, multiChunks=1, fileContents=1, fileHistories=1]
29-5-19 15:05:18.500 | UpOperation     | Test worker    | INFO : Committing local database.
29-5-19 15:05:18.500 | UpOperation     | Test worker    | FINE : Waiting for new database version.
29-5-19 15:05:18.500 | UpOperation     | Test worker    | FINE : Took new database version: DatabaseVersion [header=UnknownMachine/()/T=1559142318466, chunks=0, multiChunks=0, fileContents=0, fileHistories=0]
29-5-19 15:05:18.500 | UpOperation     | Test worker    | INFO : Sync up done.
29-5-19 15:05:18.502 | ActionFileHandl | Test worker    | INFO : Finishing action for RemoteFile[name=action-up-A-1559142318312] ...
29-5-19 15:05:18.525 | ActionFileHandl | Test worker    | INFO : Deleting action file: RemoteFile[name=action-up-A-1559142318312]
29-5-19 15:05:18.526 | AbstractTransfe | Test worker    | INFO : Cleaning own old action files ...
29-5-19 15:05:18.526 | Cache           | Test worker    | INFO : Cache size okay (2 KB), no need to clean (keep size is 500 MB)
29-5-19 15:05:18.526 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event UpEndSyncExternalEvent (org.syncany.operations.daemon.messages.UpEndSyncExternalEvent@75533a3e) ...
29-5-19 15:05:18.526 | org.syncany.plu | Test worker    | FINE : Validating required fields
29-5-19 15:05:18.527 | TransferManager | Test worker    | INFO : Building LocalTransferManager from config 'syncany-190529150308467-70313-client-A' ...
29-5-19 15:05:18.527 | TransferManager | Test worker    | INFO : - With feature ReadAfterWriteConsistent
29-5-19 15:05:18.527 | TransferManager | Test worker    | INFO : - With feature Retriable
29-5-19 15:05:18.527 | TransferManager | Test worker    | FINE : - Checking if selected features supported ...
29-5-19 15:05:18.527 | TransferManager | Test worker    | FINE : - Checking for duplicate features ...
29-5-19 15:05:18.527 | TransferManager | Test worker    | FINE : - Checking required feature TransactionAware in LocalTransferManager ...
29-5-19 15:05:18.527 | TransferManager | Test worker    | FINE : - Checking required feature Retriable in LocalTransferManager ...
29-5-19 15:05:18.527 | TransferManager | Test worker    | INFO : - SKIPPING unsupported optional feature ReadAfterWriteConsistent
29-5-19 15:05:18.527 | TransferManager | Test worker    | FINE : - Wrapping TransferManager LocalTransferManager in RetriableFeatureTransferManager
29-5-19 15:05:18.527 | org.syncany.plu | Test worker    | FINE : Validating required fields
29-5-19 15:05:18.527 | TransferManager | Test worker    | INFO : Building LocalTransferManager from config 'syncany-190529150308467-70313-client-A' ...
29-5-19 15:05:18.527 | TransferManager | Test worker    | INFO : - With feature ReadAfterWriteConsistent
29-5-19 15:05:18.527 | TransferManager | Test worker    | INFO : - With feature Retriable
29-5-19 15:05:18.527 | TransferManager | Test worker    | INFO : - With feature PathAware
29-5-19 15:05:18.527 | TransferManager | Test worker    | INFO : - With feature TransactionAware
29-5-19 15:05:18.527 | TransferManager | Test worker    | FINE : - Checking if selected features supported ...
29-5-19 15:05:18.527 | TransferManager | Test worker    | FINE : - Checking for duplicate features ...
29-5-19 15:05:18.527 | TransferManager | Test worker    | FINE : - Checking required feature TransactionAware in LocalTransferManager ...
29-5-19 15:05:18.527 | TransferManager | Test worker    | FINE : - Checking required feature Retriable in LocalTransferManager ...
29-5-19 15:05:18.527 | TransferManager | Test worker    | INFO : - SKIPPING unsupported optional feature ReadAfterWriteConsistent
29-5-19 15:05:18.528 | TransferManager | Test worker    | FINE : - Wrapping TransferManager LocalTransferManager in RetriableFeatureTransferManager
29-5-19 15:05:18.528 | TransferManager | Test worker    | INFO : - SKIPPING unsupported optional feature PathAware
29-5-19 15:05:18.528 | TransferManager | Test worker    | FINE : - Wrapping TransferManager RetriableFeatureTransferManager in TransactionAwareFeatureTransferManager
29-5-19 15:05:18.529 | DatabaseConnect | Test worker    | INFO : Found 12 tables.
29-5-19 15:05:18.529 | UpOperation     | Test worker    | INFO : 
29-5-19 15:05:18.529 | UpOperation     | Test worker    | INFO : Running 'Sync up' at client A ...
29-5-19 15:05:18.529 | UpOperation     | Test worker    | INFO : --------------------------------------------
29-5-19 15:05:18.529 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event UpStartSyncExternalEvent (org.syncany.operations.daemon.messages.UpStartSyncExternalEvent@1354bda9) ...
29-5-19 15:05:18.530 | DatabaseConnect | Test worker    | INFO : Found 12 tables.
29-5-19 15:05:18.530 | StatusOperation | Test worker    | INFO : 
29-5-19 15:05:18.530 | StatusOperation | Test worker    | INFO : Running 'Status' at client A ...
29-5-19 15:05:18.530 | StatusOperation | Test worker    | INFO : --------------------------------------------
29-5-19 15:05:18.530 | StatusOperation | Test worker    | INFO : Force checksum ENABLED.
29-5-19 15:05:18.530 | StatusOperation | Test worker    | INFO : Querying current file tree from database ...
29-5-19 15:05:18.530 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event StatusStartSyncExternalEvent (org.syncany.operations.daemon.messages.StatusStartSyncExternalEvent@7a0f1a8f) ...
29-5-19 15:05:18.531 | StatusOperation | Test worker    | INFO : Analyzing local folder /tmp/syncanytest/syncany-190529150308467-70313-client-A ...
29-5-19 15:05:18.531 | StatusOperation | Test worker    | FINE : - New file: file2.txt
29-5-19 15:05:18.532 | StatusOperation | Test worker    | FINE : - Ignoring file (syncany app-related): .syncany
29-5-19 15:05:18.532 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event StatusEndSyncExternalEvent (org.syncany.operations.daemon.messages.StatusEndSyncExternalEvent@3ab1209d) ...
29-5-19 15:05:18.532 | AbstractTransfe | Test worker    | INFO : Looking for other running remote operations ...
29-5-19 15:05:18.532 | DatabaseConnect | Test worker    | INFO : Found 12 tables.
29-5-19 15:05:18.533 | LsRemoteOperati | Test worker    | INFO : 
29-5-19 15:05:18.533 | LsRemoteOperati | Test worker    | INFO : Running 'Remote Status' at client A ...
29-5-19 15:05:18.533 | LsRemoteOperati | Test worker    | INFO : --------------------------------------------
29-5-19 15:05:18.533 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event LsRemoteStartSyncExternalEvent (org.syncany.operations.daemon.messages.LsRemoteStartSyncExternalEvent@47bdaa5) ...
29-5-19 15:05:18.533 | LsRemoteOperati | Test worker    | INFO : Retrieving remote database list.
29-5-19 15:05:18.533 | LsRemoteOperati | Test worker    | INFO : - Remote database database-A-0000000001 is already known (in local database). Ignoring.
29-5-19 15:05:18.533 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event LsRemoteEndSyncExternalEvent (org.syncany.operations.daemon.messages.LsRemoteEndSyncExternalEvent@4bcf7cc8) ...
29-5-19 15:05:18.534 | UpOperation     | Test worker    | INFO : No remote changes, ready to upload.
29-5-19 15:05:18.534 | ActionFileHandl | Test worker    | INFO : Starting action for RemoteFile[name=action-up-A-1559142318528] ...
29-5-19 15:05:18.534 | ActionFileHandl | Test worker    | INFO : Uploading action file: RemoteFile[name=action-up-A-1559142318528]
29-5-19 15:05:18.535 | ActionFileHandl | Test worker    | INFO : Scheduling action renewal task for every 2 minutes, for RemoteFile[name=action-up-A-1559142318528] ...
29-5-19 15:05:18.535 | UpOperation     | Test worker    | INFO : Found local transaction to resume.
29-5-19 15:05:18.535 | UpOperation     | Test worker    | INFO : Attempting to find transactionRemoteFile
29-5-19 15:05:18.535 | DatabaseXmlSeri | Test worker    | INFO : - Loading database (FULL) from file /tmp/syncanytest/syncany-190529150308467-70313-client-A/.syncany/state/transaction-database.0000000001.xml ...
29-5-19 15:05:18.558 | UpOperation     | Test worker    | WARN : Cannot load database versions from 'state'. Cannot resume.
29-5-19 15:05:18.567 | DatabaseConnect | Test worker    | INFO : Found 12 tables.
29-5-19 15:05:18.574 | TransactionAwar | Test worker    | INFO : Done rolling back previous transactions.
29-5-19 15:05:18.575 | AsyncIndexer    | AsyncI/syncany | INFO : Starting Indexing.
29-5-19 15:05:18.576 | InternalEventBu | AsyncI/syncany | INFO : Event bus 'LocalEventBus': Posting event UpIndexStartSyncExternalEvent (org.syncany.operations.daemon.messages.UpIndexStartSyncExternalEvent@10b33615) ...
29-5-19 15:05:18.576 | Indexer         | AsyncI/syncany | FINE : - Looking for deleted files ...
29-5-19 15:05:18.576 | Indexer         | AsyncI/syncany | FINE : - +File /tmp/syncanytest/syncany-190529150308467-70313-client-A/file2.txt
29-5-19 15:05:18.576 | Indexer         | AsyncI/syncany | FINE : - +FileContent: /tmp/syncanytest/syncany-190529150308467-70313-client-A/file2.txt
29-5-19 15:05:18.577 | Indexer         | AsyncI/syncany | FINE : - Chunk new: be88422e717411146a17485ab98a00f6a1c5de8f
29-5-19 15:05:18.577 | Indexer         | AsyncI/syncany | FINE : - +MultiChunk 0079dfe0f7eb4365bb2471d39eb07b34add8ff15
29-5-19 15:05:18.577 | Indexer         | AsyncI/syncany | FINE : - Chunk > MultiChunk: be88422e717411146a17485ab98a00f6a1c5de8f > 0079dfe0f7eb4365bb2471d39eb07b34add8ff15
29-5-19 15:05:18.577 | Indexer         | AsyncI/syncany | FINE : - Chunk > FileContent: be88422e717411146a17485ab98a00f6a1c5de8f > /tmp/syncanytest/syncany-190529150308467-70313-client-A/file2.txt
29-5-19 15:05:18.577 | Indexer         | AsyncI/syncany | FINE : - /File: file2.txt (checksum be88422e717411146a17485ab98a00f6a1c5de8f)
29-5-19 15:05:18.583 | Indexer         | AsyncI/syncany | FINE :    * No old file history found, starting new history (path: file2.txt, checksum: be88422e717411146a17485ab98a00f6a1c5de8f)
29-5-19 15:05:18.583 | FileVersionComp | AsyncI/syncany | INFO :      - [DELETED]: Local file DIFFERS from file version, actual file is NULL, for file file2.txt
29-5-19 15:05:18.583 | Indexer         | AsyncI/syncany | INFO :    * Added file version:    FileVersion [version=1, path=file2.txt, type=FILE, status=NEW, size=1024, lastModified=Wed May 29 15:05:18 UTC 2019, linkTarget=null, checksum=be88422e717411146a17485ab98a00f6a1c5de8f, updated=Wed May 29 15:05:18 UTC 2019, posixPermissions=rw-rw-r--, dosAttributes=--a-]
29-5-19 15:05:18.583 | Indexer         | AsyncI/syncany | INFO :      based on file version: null
29-5-19 15:05:18.583 | InternalEventBu | AsyncI/syncany | INFO : Event bus 'LocalEventBus': Posting event UpIndexChangesDetectedSyncExternalEvent (org.syncany.operations.daemon.messages.UpIndexChangesDetectedSyncExternalEvent@aa7a98e) ...
29-5-19 15:05:18.586 | Indexer         | AsyncI/syncany | FINE : - /MultiChunk 0079dfe0f7eb4365bb2471d39eb07b34add8ff15
29-5-19 15:05:18.586 | InternalEventBu | AsyncI/syncany | INFO : Event bus 'LocalEventBus': Posting event UpIndexEndSyncExternalEvent (org.syncany.operations.daemon.messages.UpIndexEndSyncExternalEvent@b8e9d5a) ...
29-5-19 15:05:18.586 | Indexer         | AsyncI/syncany | FINE : Processed new database version: DatabaseVersion [header=UnknownMachine/()/T=1559142318576, chunks=1, multiChunks=1, fileContents=1, fileHistories=1]
29-5-19 15:05:18.586 | UpOperation     | Test worker    | INFO : No DIRTY data found in database (no dirty databases); Nothing to do here.
29-5-19 15:05:18.587 | UpOperation     | Test worker    | INFO : Last vector clock was: (A1)
29-5-19 15:05:18.587 | UpOperation     | Test worker    | INFO : Uploading new multichunks ...
29-5-19 15:05:18.587 | UpOperation     | Test worker    | INFO : - Uploading multichunk 0079dfe0f7eb4365bb2471d39eb07b34add8ff15 from /tmp/syncanytest/syncany-190529150308467-70313-client-A/.syncany/cache/multichunk-0079dfe0f7eb4365bb2471d39eb07b34add8ff15 to RemoteFile[name=multichunk-0079dfe0f7eb4365bb2471d39eb07b34add8ff15] ...
29-5-19 15:05:18.588 | RemoteTransacti | Test worker    | INFO : - Adding file to TX for UPLOAD: /tmp/syncanytest/syncany-190529150308467-70313-client-A/.syncany/cache/multichunk-0079dfe0f7eb4365bb2471d39eb07b34add8ff15 -> Temp. remote file: RemoteFile[name=temp-TYiLK-multichunk-0079dfe0f7eb4365bb2471d39eb07b34add8ff15], final location: RemoteFile[name=multichunk-0079dfe0f7eb4365bb2471d39eb07b34add8ff15]
29-5-19 15:05:18.588 | UpOperation     | Test worker    | INFO : Uploading database: DatabaseVersion [header=A/(A2)/T=1559142318587, chunks=1, multiChunks=1, fileContents=1, fileHistories=1]
29-5-19 15:05:18.588 | InternalEventBu | AsyncI/syncany | INFO : Event bus 'LocalEventBus': Posting event UpIndexMidSyncExternalEvent (org.syncany.operations.daemon.messages.UpIndexMidSyncExternalEvent@777c36c7) ...
29-5-19 15:05:18.592 | UpOperation     | Test worker    | INFO : Saving local delta database, version A/(A2)/T=1559142318587 to file /tmp/syncanytest/syncany-190529150308467-70313-client-A/.syncany/cache/database-A-0000000002 ... 
29-5-19 15:05:18.592 | UpOperation     | Test worker    | INFO : - Saving database to /tmp/syncanytest/syncany-190529150308467-70313-client-A/.syncany/cache/database-A-0000000002 ...
29-5-19 15:05:18.593 | UpOperation     | Test worker    | INFO : - Uploading local delta database file ...
29-5-19 15:05:18.593 | UpOperation     | Test worker    | INFO : - Uploading /tmp/syncanytest/syncany-190529150308467-70313-client-A/.syncany/cache/database-A-0000000002 to RemoteFile[name=database-A-0000000002] ...
29-5-19 15:05:18.593 | RemoteTransacti | Test worker    | INFO : - Adding file to TX for UPLOAD: /tmp/syncanytest/syncany-190529150308467-70313-client-A/.syncany/cache/database-A-0000000002 -> Temp. remote file: RemoteFile[name=temp-qEYnw-database-A-0000000002], final location: RemoteFile[name=database-A-0000000002]
29-5-19 15:05:18.594 | UpOperation     | Test worker    | INFO : Adding shutdown hook (to allow resuming the upload) ...
29-5-19 15:05:18.594 | RemoteTransacti | Test worker    | INFO : Starting TX.commit() ...
29-5-19 15:05:18.597 | AsyncIndexer    | AsyncI/syncany | INFO : Stopping indexing. Signal end of stream with empty databaseversion
29-5-19 15:05:18.598 | RemoteTransacti | Test worker    | INFO : Wrote transaction manifest to temporary file: /tmp/syncanytest/syncany-190529150308467-70313-client-A/.syncany/cache/temp-transaction-14050987296412274865.tmp
29-5-19 15:05:18.598 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event UpUploadFileSyncExternalEvent (org.syncany.operations.daemon.messages.UpUploadFileSyncExternalEvent@75e984d) ...
29-5-19 15:05:18.598 | RemoteTransacti | Test worker    | INFO : - Uploading remote transaction file RemoteFile[name=transaction-491428c9] ...
29-5-19 15:05:18.600 | RemoteTransacti | Test worker    | INFO : - Starting to upload data in commit.
29-5-19 15:05:18.600 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event UpUploadFileInTransactionSyncExternalEvent (org.syncany.operations.daemon.messages.UpUploadFileInTransactionSyncExternalEvent@538feeb5) ...
29-5-19 15:05:18.600 | RemoteTransacti | Test worker    | INFO : - Uploading /tmp/syncanytest/syncany-190529150308467-70313-client-A/.syncany/cache/multichunk-0079dfe0f7eb4365bb2471d39eb07b34add8ff15 to temp. file RemoteFile[name=temp-TYiLK-multichunk-0079dfe0f7eb4365bb2471d39eb07b34add8ff15] ...
29-5-19 15:05:18.601 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event UpUploadFileInTransactionSyncExternalEvent (org.syncany.operations.daemon.messages.UpUploadFileInTransactionSyncExternalEvent@4ce35876) ...
29-5-19 15:05:18.601 | RemoteTransacti | Test worker    | INFO : - Uploading /tmp/syncanytest/syncany-190529150308467-70313-client-A/.syncany/cache/database-A-0000000002 to temp. file RemoteFile[name=temp-qEYnw-database-A-0000000002] ...
29-5-19 15:05:18.601 | RemoteTransacti | Test worker    | INFO : - Moving temp. file RemoteFile[name=temp-TYiLK-multichunk-0079dfe0f7eb4365bb2471d39eb07b34add8ff15] to final location RemoteFile[name=multichunk-0079dfe0f7eb4365bb2471d39eb07b34add8ff15] ...
29-5-19 15:05:18.601 | RemoteTransacti | Test worker    | INFO : - Moving temp. file RemoteFile[name=temp-qEYnw-database-A-0000000002] to final location RemoteFile[name=database-A-0000000002] ...
29-5-19 15:05:18.601 | RemoteTransacti | Test worker    | INFO : - Deleting remote transaction file RemoteFile[name=transaction-491428c9] ...
29-5-19 15:05:18.601 | RemoteTransacti | Test worker    | INFO : END of TX.commmit(): Succesfully committed transaction.
29-5-19 15:05:18.601 | RemoteTransacti | Test worker    | INFO : END of TX.delTemp(): Sucessfully deleted final files.
29-5-19 15:05:18.601 | UpOperation     | Test worker    | INFO : Persisting local SQL database (new database version A/(A2)/T=1559142318587) ...
29-5-19 15:05:18.608 | UpOperation     | Test worker    | INFO : Removing DIRTY database versions from database ...
29-5-19 15:05:18.678 | UpOperation     | Test worker    | INFO : Adding database version to result changes:DatabaseVersion [header=A/(A2)/T=1559142318587, chunks=1, multiChunks=1, fileContents=1, fileHistories=1]
29-5-19 15:05:18.678 | UpOperation     | Test worker    | INFO : Committing local database.
29-5-19 15:05:18.678 | UpOperation     | Test worker    | FINE : Waiting for new database version.
29-5-19 15:05:18.678 | UpOperation     | Test worker    | FINE : Took new database version: DatabaseVersion [header=UnknownMachine/()/T=1559142318597, chunks=0, multiChunks=0, fileContents=0, fileHistories=0]
29-5-19 15:05:18.678 | UpOperation     | Test worker    | INFO : Sync up done.
29-5-19 15:05:18.688 | ActionFileHandl | Test worker    | INFO : Finishing action for RemoteFile[name=action-up-A-1559142318528] ...
29-5-19 15:05:18.700 | ActionFileHandl | Test worker    | INFO : Deleting action file: RemoteFile[name=action-up-A-1559142318528]
29-5-19 15:05:18.700 | AbstractTransfe | Test worker    | INFO : Cleaning own old action files ...
29-5-19 15:05:18.700 | Cache           | Test worker    | INFO : Cache size okay (4 KB), no need to clean (keep size is 500 MB)
29-5-19 15:05:18.701 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event UpEndSyncExternalEvent (org.syncany.operations.daemon.messages.UpEndSyncExternalEvent@54e0c0ce) ...
29-5-19 15:05:18.710 | org.syncany.plu | Test worker    | FINE : Validating required fields
29-5-19 15:05:18.710 | LocalTransferMa | Test worker    | INFO : testTargetExists: Target exists.
29-5-19 15:05:18.721 | org.syncany.plu | Test worker    | FINE : Validating required fields
29-5-19 15:05:18.722 | TransferManager | Test worker    | INFO : Building LocalTransferManager from config 'syncany-190529150308467-17932-client-A' ...
29-5-19 15:05:18.722 | TransferManager | Test worker    | INFO : - With feature ReadAfterWriteConsistent
29-5-19 15:05:18.722 | TransferManager | Test worker    | INFO : - With feature Retriable
29-5-19 15:05:18.722 | TransferManager | Test worker    | FINE : - Checking if selected features supported ...
29-5-19 15:05:18.722 | TransferManager | Test worker    | FINE : - Checking for duplicate features ...
29-5-19 15:05:18.722 | TransferManager | Test worker    | FINE : - Checking required feature TransactionAware in LocalTransferManager ...
29-5-19 15:05:18.722 | TransferManager | Test worker    | FINE : - Checking required feature Retriable in LocalTransferManager ...
29-5-19 15:05:18.722 | TransferManager | Test worker    | INFO : - SKIPPING unsupported optional feature ReadAfterWriteConsistent
29-5-19 15:05:18.724 | TransferManager | Test worker    | FINE : - Wrapping TransferManager LocalTransferManager in RetriableFeatureTransferManager
29-5-19 15:05:18.724 | org.syncany.plu | Test worker    | FINE : Validating required fields
29-5-19 15:05:18.724 | TransferManager | Test worker    | INFO : Building LocalTransferManager from config 'syncany-190529150308467-17932-client-A' ...
29-5-19 15:05:18.724 | TransferManager | Test worker    | INFO : - With feature ReadAfterWriteConsistent
29-5-19 15:05:18.724 | TransferManager | Test worker    | INFO : - With feature Retriable
29-5-19 15:05:18.724 | TransferManager | Test worker    | INFO : - With feature PathAware
29-5-19 15:05:18.724 | TransferManager | Test worker    | INFO : - With feature TransactionAware
29-5-19 15:05:18.724 | TransferManager | Test worker    | FINE : - Checking if selected features supported ...
29-5-19 15:05:18.724 | TransferManager | Test worker    | FINE : - Checking for duplicate features ...
29-5-19 15:05:18.724 | TransferManager | Test worker    | FINE : - Checking required feature TransactionAware in LocalTransferManager ...
29-5-19 15:05:18.724 | TransferManager | Test worker    | FINE : - Checking required feature Retriable in LocalTransferManager ...
29-5-19 15:05:18.724 | TransferManager | Test worker    | INFO : - SKIPPING unsupported optional feature ReadAfterWriteConsistent
29-5-19 15:05:18.724 | TransferManager | Test worker    | FINE : - Wrapping TransferManager LocalTransferManager in RetriableFeatureTransferManager
29-5-19 15:05:18.724 | TransferManager | Test worker    | INFO : - SKIPPING unsupported optional feature PathAware
29-5-19 15:05:18.724 | TransferManager | Test worker    | FINE : - Wrapping TransferManager RetriableFeatureTransferManager in TransactionAwareFeatureTransferManager
29-5-19 15:05:18.772 | hsqldb.db.HSQLD | Test worker    | INFO : Checkpoint start
29-5-19 15:05:18.773 | hsqldb.db.HSQLD | Test worker    | INFO : checkpointClose start
29-5-19 15:05:18.792 | hsqldb.db.HSQLD | Test worker    | FINE : log close size: 0
29-5-19 15:05:18.796 | hsqldb.db.HSQLD | Test worker    | INFO : checkpointClose end
29-5-19 15:05:18.797 | hsqldb.db.HSQLD | Test worker    | INFO : Checkpoint end - txts: 1
29-5-19 15:05:18.813 | DatabaseConnect | Test worker    | INFO : Found 0 tables.
29-5-19 15:05:18.813 | DatabaseConnect | Test worker    | INFO : Database has no tables. Creating tables from script.create.all.sql
29-5-19 15:05:18.813 | SqlRunner       | Test worker    | INFO : SQL (comment): -- Tables
29-5-19 15:05:18.813 | 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-5-19 15:05:18.821 | 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-5-19 15:05:18.823 | 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-5-19 15:05:18.825 | 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-5-19 15:05:18.828 | 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-5-19 15:05:18.830 | 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-5-19 15:05:18.832 | 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-5-19 15:05:18.834 | 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-5-19 15:05:18.836 | 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-5-19 15:05:18.838 | 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-5-19 15:05:18.840 | 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-5-19 15:05:18.842 | 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-5-19 15:05:18.844 | SqlRunner       | Test worker    | INFO : SQL (comment): -- Non-primary indices
29-5-19 15:05:18.844 | SqlRunner       | Test worker    | INFO : SQL:   CREATE INDEX idx_databaseversion_status ON databaseversion (status); 
29-5-19 15:05:18.845 | hsqldb.db.HSQLD | Test worker    | INFO : dataFileCache open start
29-5-19 15:05:18.848 | hsqldb.db.HSQLD | Test worker    | INFO : dataFileCache open end
29-5-19 15:05:18.850 | SqlRunner       | Test worker    | INFO : SQL: CREATE INDEX idx_databaseversion_vectorclock_serialized ON databaseversion (vectorclock_serialized); 
29-5-19 15:05:18.852 | SqlRunner       | Test worker    | INFO : SQL: CREATE INDEX idx_fileversion_path ON fileversion (path); 
29-5-19 15:05:18.854 | SqlRunner       | Test worker    | INFO : SQL: CREATE INDEX idx_fileversion_status ON fileversion (status); 
29-5-19 15:05:18.855 | SqlRunner       | Test worker    | INFO : SQL: CREATE INDEX idx_fileversion_filecontent_checksum ON fileversion (filecontent_checksum); 
29-5-19 15:05:18.857 | SqlRunner       | Test worker    | INFO : SQL (comment): -- Views
29-5-19 15:05:18.857 | 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-5-19 15:05:18.860 | 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-5-19 15:05:18.862 | 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-5-19 15:05:18.864 | 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-5-19 15:05:18.866 | SqlRunner       | Test worker    | INFO : SQL (comment): -- Full Views
29-5-19 15:05:18.867 | 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-5-19 15:05:18.869 | 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-5-19 15:05:18.871 | SqlRunner       | Test worker    | INFO : SQL (comment): -- Functions
29-5-19 15:05:18.871 | SqlRunner       | Test worker    | INFO : SQL (new delimiter): end;
29-5-19 15:05:18.871 | 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-5-19 15:05:18.874 | UpOperation     | Test worker    | INFO : 
29-5-19 15:05:18.874 | UpOperation     | Test worker    | INFO : Running 'Sync up' at client A ...
29-5-19 15:05:18.874 | UpOperation     | Test worker    | INFO : --------------------------------------------
29-5-19 15:05:18.874 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event UpStartSyncExternalEvent (org.syncany.operations.daemon.messages.UpStartSyncExternalEvent@7fd35ec1) ...
29-5-19 15:05:18.875 | DatabaseConnect | Test worker    | INFO : Found 12 tables.
29-5-19 15:05:18.875 | StatusOperation | Test worker    | INFO : 
29-5-19 15:05:18.875 | StatusOperation | Test worker    | INFO : Running 'Status' at client A ...
29-5-19 15:05:18.875 | StatusOperation | Test worker    | INFO : --------------------------------------------
29-5-19 15:05:18.875 | StatusOperation | Test worker    | INFO : Force checksum ENABLED.
29-5-19 15:05:18.875 | StatusOperation | Test worker    | INFO : Querying current file tree from database ...
29-5-19 15:05:18.875 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event StatusStartSyncExternalEvent (org.syncany.operations.daemon.messages.StatusStartSyncExternalEvent@4999d24c) ...
29-5-19 15:05:18.875 | StatusOperation | Test worker    | INFO : Analyzing local folder /tmp/syncanytest/syncany-190529150308467-17932-client-A ...
29-5-19 15:05:18.876 | StatusOperation | Test worker    | FINE : - New file: file1.txt
29-5-19 15:05:18.876 | StatusOperation | Test worker    | FINE : - Ignoring file (syncany app-related): .syncany
29-5-19 15:05:18.876 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event StatusEndSyncExternalEvent (org.syncany.operations.daemon.messages.StatusEndSyncExternalEvent@43bfc948) ...
29-5-19 15:05:18.876 | AbstractTransfe | Test worker    | INFO : Looking for other running remote operations ...
29-5-19 15:05:18.877 | DatabaseConnect | Test worker    | INFO : Found 12 tables.
29-5-19 15:05:18.877 | LsRemoteOperati | Test worker    | INFO : 
29-5-19 15:05:18.877 | LsRemoteOperati | Test worker    | INFO : Running 'Remote Status' at client A ...
29-5-19 15:05:18.877 | LsRemoteOperati | Test worker    | INFO : --------------------------------------------
29-5-19 15:05:18.877 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event LsRemoteStartSyncExternalEvent (org.syncany.operations.daemon.messages.LsRemoteStartSyncExternalEvent@30e634d6) ...
29-5-19 15:05:18.877 | LsRemoteOperati | Test worker    | INFO : Retrieving remote database list.
29-5-19 15:05:18.877 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event LsRemoteEndSyncExternalEvent (org.syncany.operations.daemon.messages.LsRemoteEndSyncExternalEvent@29a992b2) ...
29-5-19 15:05:18.877 | UpOperation     | Test worker    | INFO : No remote changes, ready to upload.
29-5-19 15:05:18.877 | ActionFileHandl | Test worker    | INFO : Starting action for RemoteFile[name=action-up-A-1559142318724] ...
29-5-19 15:05:18.877 | ActionFileHandl | Test worker    | INFO : Uploading action file: RemoteFile[name=action-up-A-1559142318724]
29-5-19 15:05:18.877 | ActionFileHandl | Test worker    | INFO : Scheduling action renewal task for every 2 minutes, for RemoteFile[name=action-up-A-1559142318724] ...
29-5-19 15:05:18.878 | DatabaseConnect | Test worker    | INFO : Found 12 tables.
29-5-19 15:05:18.890 | TransactionAwar | Test worker    | INFO : Done rolling back previous transactions.
29-5-19 15:05:18.890 | AsyncIndexer    | AsyncI/syncany | INFO : Starting Indexing.
29-5-19 15:05:18.891 | InternalEventBu | AsyncI/syncany | INFO : Event bus 'LocalEventBus': Posting event UpIndexStartSyncExternalEvent (org.syncany.operations.daemon.messages.UpIndexStartSyncExternalEvent@14998548) ...
29-5-19 15:05:18.891 | Indexer         | AsyncI/syncany | FINE : - Looking for deleted files ...
29-5-19 15:05:18.891 | Indexer         | AsyncI/syncany | FINE : - +File /tmp/syncanytest/syncany-190529150308467-17932-client-A/file1.txt
29-5-19 15:05:18.891 | Indexer         | AsyncI/syncany | FINE : - +FileContent: /tmp/syncanytest/syncany-190529150308467-17932-client-A/file1.txt
29-5-19 15:05:18.892 | Indexer         | AsyncI/syncany | FINE : - Chunk new: 5ce31f56a7e3be533d389b4f6d6a0c38670885a5
29-5-19 15:05:18.892 | Indexer         | AsyncI/syncany | FINE : - +MultiChunk 559254da8b55505fa2d4e6b01594ed00046ee474
29-5-19 15:05:18.892 | Indexer         | AsyncI/syncany | FINE : - Chunk > MultiChunk: 5ce31f56a7e3be533d389b4f6d6a0c38670885a5 > 559254da8b55505fa2d4e6b01594ed00046ee474
29-5-19 15:05:18.892 | Indexer         | AsyncI/syncany | FINE : - Chunk > FileContent: 5ce31f56a7e3be533d389b4f6d6a0c38670885a5 > /tmp/syncanytest/syncany-190529150308467-17932-client-A/file1.txt
29-5-19 15:05:18.893 | Indexer         | AsyncI/syncany | FINE : - /File: file1.txt (checksum 5ce31f56a7e3be533d389b4f6d6a0c38670885a5)
29-5-19 15:05:18.893 | Indexer         | AsyncI/syncany | FINE :    * No old file history found, starting new history (path: file1.txt, checksum: 5ce31f56a7e3be533d389b4f6d6a0c38670885a5)
29-5-19 15:05:18.893 | FileVersionComp | AsyncI/syncany | INFO :      - [DELETED]: Local file DIFFERS from file version, actual file is NULL, for file file1.txt
29-5-19 15:05:18.893 | Indexer         | AsyncI/syncany | INFO :    * Added file version:    FileVersion [version=1, path=file1.txt, type=FILE, status=NEW, size=1024, lastModified=Wed May 29 15:05:18 UTC 2019, linkTarget=null, checksum=5ce31f56a7e3be533d389b4f6d6a0c38670885a5, updated=Wed May 29 15:05:18 UTC 2019, posixPermissions=rw-rw-r--, dosAttributes=--a-]
29-5-19 15:05:18.894 | Indexer         | AsyncI/syncany | INFO :      based on file version: null
29-5-19 15:05:18.902 | InternalEventBu | AsyncI/syncany | INFO : Event bus 'LocalEventBus': Posting event UpIndexChangesDetectedSyncExternalEvent (org.syncany.operations.daemon.messages.UpIndexChangesDetectedSyncExternalEvent@5e45bac7) ...
29-5-19 15:05:18.903 | Indexer         | AsyncI/syncany | FINE : - /MultiChunk 559254da8b55505fa2d4e6b01594ed00046ee474
29-5-19 15:05:18.903 | InternalEventBu | AsyncI/syncany | INFO : Event bus 'LocalEventBus': Posting event UpIndexEndSyncExternalEvent (org.syncany.operations.daemon.messages.UpIndexEndSyncExternalEvent@4ba49295) ...
29-5-19 15:05:18.903 | Indexer         | AsyncI/syncany | FINE : Processed new database version: DatabaseVersion [header=UnknownMachine/()/T=1559142318890, chunks=1, multiChunks=1, fileContents=1, fileHistories=1]
29-5-19 15:05:18.903 | UpOperation     | Test worker    | INFO : No DIRTY data found in database (no dirty databases); Nothing to do here.
29-5-19 15:05:18.903 | UpOperation     | Test worker    | INFO : Last vector clock was: ()
29-5-19 15:05:18.904 | UpOperation     | Test worker    | INFO : Uploading new multichunks ...
29-5-19 15:05:18.904 | UpOperation     | Test worker    | INFO : - Uploading multichunk 559254da8b55505fa2d4e6b01594ed00046ee474 from /tmp/syncanytest/syncany-190529150308467-17932-client-A/.syncany/cache/multichunk-559254da8b55505fa2d4e6b01594ed00046ee474 to RemoteFile[name=multichunk-559254da8b55505fa2d4e6b01594ed00046ee474] ...
29-5-19 15:05:18.904 | RemoteTransacti | Test worker    | INFO : - Adding file to TX for UPLOAD: /tmp/syncanytest/syncany-190529150308467-17932-client-A/.syncany/cache/multichunk-559254da8b55505fa2d4e6b01594ed00046ee474 -> Temp. remote file: RemoteFile[name=temp-dDwqk-multichunk-559254da8b55505fa2d4e6b01594ed00046ee474], final location: RemoteFile[name=multichunk-559254da8b55505fa2d4e6b01594ed00046ee474]
29-5-19 15:05:18.904 | UpOperation     | Test worker    | INFO : Uploading database: DatabaseVersion [header=A/(A1)/T=1559142318904, chunks=1, multiChunks=1, fileContents=1, fileHistories=1]
29-5-19 15:05:18.904 | UpOperation     | Test worker    | INFO : Saving local delta database, version A/(A1)/T=1559142318904 to file /tmp/syncanytest/syncany-190529150308467-17932-client-A/.syncany/cache/database-A-0000000001 ... 
29-5-19 15:05:18.904 | UpOperation     | Test worker    | INFO : - Saving database to /tmp/syncanytest/syncany-190529150308467-17932-client-A/.syncany/cache/database-A-0000000001 ...
29-5-19 15:05:18.905 | UpOperation     | Test worker    | INFO : - Uploading local delta database file ...
29-5-19 15:05:18.905 | UpOperation     | Test worker    | INFO : - Uploading /tmp/syncanytest/syncany-190529150308467-17932-client-A/.syncany/cache/database-A-0000000001 to RemoteFile[name=database-A-0000000001] ...
29-5-19 15:05:18.905 | RemoteTransacti | Test worker    | INFO : - Adding file to TX for UPLOAD: /tmp/syncanytest/syncany-190529150308467-17932-client-A/.syncany/cache/database-A-0000000001 -> Temp. remote file: RemoteFile[name=temp-oANPB-database-A-0000000001], final location: RemoteFile[name=database-A-0000000001]
29-5-19 15:05:18.906 | UpOperation     | Test worker    | INFO : Adding shutdown hook (to allow resuming the upload) ...
29-5-19 15:05:18.906 | RemoteTransacti | Test worker    | INFO : Starting TX.commit() ...
29-5-19 15:05:18.908 | RemoteTransacti | Test worker    | INFO : Wrote transaction manifest to temporary file: /tmp/syncanytest/syncany-190529150308467-17932-client-A/.syncany/cache/temp-transaction-10829832580408279758.tmp
29-5-19 15:05:18.908 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event UpUploadFileSyncExternalEvent (org.syncany.operations.daemon.messages.UpUploadFileSyncExternalEvent@52246bf6) ...
29-5-19 15:05:18.908 | RemoteTransacti | Test worker    | INFO : - Uploading remote transaction file RemoteFile[name=transaction-4343a33a] ...
29-5-19 15:05:18.908 | RemoteTransacti | Test worker    | INFO : - Starting to upload data in commit.
29-5-19 15:05:18.909 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event UpUploadFileInTransactionSyncExternalEvent (org.syncany.operations.daemon.messages.UpUploadFileInTransactionSyncExternalEvent@6ddab623) ...
29-5-19 15:05:18.909 | RemoteTransacti | Test worker    | INFO : - Uploading /tmp/syncanytest/syncany-190529150308467-17932-client-A/.syncany/cache/multichunk-559254da8b55505fa2d4e6b01594ed00046ee474 to temp. file RemoteFile[name=temp-dDwqk-multichunk-559254da8b55505fa2d4e6b01594ed00046ee474] ...
29-5-19 15:05:18.909 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event UpUploadFileInTransactionSyncExternalEvent (org.syncany.operations.daemon.messages.UpUploadFileInTransactionSyncExternalEvent@69a5e651) ...
29-5-19 15:05:18.909 | RemoteTransacti | Test worker    | INFO : - Uploading /tmp/syncanytest/syncany-190529150308467-17932-client-A/.syncany/cache/database-A-0000000001 to temp. file RemoteFile[name=temp-oANPB-database-A-0000000001] ...
29-5-19 15:05:18.909 | RemoteTransacti | Test worker    | INFO : - Moving temp. file RemoteFile[name=temp-dDwqk-multichunk-559254da8b55505fa2d4e6b01594ed00046ee474] to final location RemoteFile[name=multichunk-559254da8b55505fa2d4e6b01594ed00046ee474] ...
29-5-19 15:05:18.909 | RemoteTransacti | Test worker    | INFO : - Moving temp. file RemoteFile[name=temp-oANPB-database-A-0000000001] to final location RemoteFile[name=database-A-0000000001] ...
29-5-19 15:05:18.909 | RemoteTransacti | Test worker    | INFO : - Deleting remote transaction file RemoteFile[name=transaction-4343a33a] ...
29-5-19 15:05:18.910 | RemoteTransacti | Test worker    | INFO : END of TX.commmit(): Succesfully committed transaction.
29-5-19 15:05:18.910 | RemoteTransacti | Test worker    | INFO : END of TX.delTemp(): Sucessfully deleted final files.
29-5-19 15:05:18.910 | UpOperation     | Test worker    | INFO : Persisting local SQL database (new database version A/(A1)/T=1559142318904) ...
29-5-19 15:05:18.911 | UpOperation     | Test worker    | INFO : Removing DIRTY database versions from database ...
29-5-19 15:05:18.913 | InternalEventBu | AsyncI/syncany | INFO : Event bus 'LocalEventBus': Posting event UpIndexMidSyncExternalEvent (org.syncany.operations.daemon.messages.UpIndexMidSyncExternalEvent@27dd3205) ...
29-5-19 15:05:18.933 | UpOperation     | Test worker    | INFO : Adding database version to result changes:DatabaseVersion [header=A/(A1)/T=1559142318904, chunks=1, multiChunks=1, fileContents=1, fileHistories=1]
29-5-19 15:05:18.933 | UpOperation     | Test worker    | INFO : Committing local database.
29-5-19 15:05:18.933 | UpOperation     | Test worker    | FINE : Waiting for new database version.
29-5-19 15:05:18.935 | AsyncIndexer    | AsyncI/syncany | INFO : Stopping indexing. Signal end of stream with empty databaseversion
29-5-19 15:05:18.936 | UpOperation     | Test worker    | FINE : Took new database version: DatabaseVersion [header=UnknownMachine/()/T=1559142318936, chunks=0, multiChunks=0, fileContents=0, fileHistories=0]
29-5-19 15:05:18.936 | UpOperation     | Test worker    | INFO : Sync up done.
29-5-19 15:05:18.939 | ActionFileHandl | Test worker    | INFO : Finishing action for RemoteFile[name=action-up-A-1559142318724] ...
29-5-19 15:05:18.950 | ActionFileHandl | Test worker    | INFO : Deleting action file: RemoteFile[name=action-up-A-1559142318724]
29-5-19 15:05:18.950 | AbstractTransfe | Test worker    | INFO : Cleaning own old action files ...
29-5-19 15:05:18.950 | Cache           | Test worker    | INFO : Cache size okay (2 KB), no need to clean (keep size is 500 MB)
29-5-19 15:05:18.950 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event UpEndSyncExternalEvent (org.syncany.operations.daemon.messages.UpEndSyncExternalEvent@257e5c8a) ...
29-5-19 15:05:18.951 | org.syncany.plu | Test worker    | FINE : Validating required fields
29-5-19 15:05:18.951 | TransferManager | Test worker    | INFO : Building LocalTransferManager from config 'syncany-190529150308467-17932-client-A' ...
29-5-19 15:05:18.951 | TransferManager | Test worker    | INFO : - With feature ReadAfterWriteConsistent
29-5-19 15:05:18.951 | TransferManager | Test worker    | INFO : - With feature Retriable
29-5-19 15:05:18.951 | TransferManager | Test worker    | FINE : - Checking if selected features supported ...
29-5-19 15:05:18.951 | TransferManager | Test worker    | FINE : - Checking for duplicate features ...
29-5-19 15:05:18.951 | TransferManager | Test worker    | FINE : - Checking required feature TransactionAware in LocalTransferManager ...
29-5-19 15:05:18.951 | TransferManager | Test worker    | FINE : - Checking required feature Retriable in LocalTransferManager ...
29-5-19 15:05:18.951 | TransferManager | Test worker    | INFO : - SKIPPING unsupported optional feature ReadAfterWriteConsistent
29-5-19 15:05:18.951 | TransferManager | Test worker    | FINE : - Wrapping TransferManager LocalTransferManager in RetriableFeatureTransferManager
29-5-19 15:05:18.951 | org.syncany.plu | Test worker    | FINE : Validating required fields
29-5-19 15:05:18.952 | TransferManager | Test worker    | INFO : Building LocalTransferManager from config 'syncany-190529150308467-17932-client-A' ...
29-5-19 15:05:18.952 | TransferManager | Test worker    | INFO : - With feature ReadAfterWriteConsistent
29-5-19 15:05:18.952 | TransferManager | Test worker    | INFO : - With feature Retriable
29-5-19 15:05:18.952 | TransferManager | Test worker    | INFO : - With feature PathAware
29-5-19 15:05:18.952 | TransferManager | Test worker    | INFO : - With feature TransactionAware
29-5-19 15:05:18.952 | TransferManager | Test worker    | FINE : - Checking if selected features supported ...
29-5-19 15:05:18.952 | TransferManager | Test worker    | FINE : - Checking for duplicate features ...
29-5-19 15:05:18.952 | TransferManager | Test worker    | FINE : - Checking required feature TransactionAware in LocalTransferManager ...
29-5-19 15:05:18.952 | TransferManager | Test worker    | FINE : - Checking required feature Retriable in LocalTransferManager ...
29-5-19 15:05:18.952 | TransferManager | Test worker    | INFO : - SKIPPING unsupported optional feature ReadAfterWriteConsistent
29-5-19 15:05:18.952 | TransferManager | Test worker    | FINE : - Wrapping TransferManager LocalTransferManager in RetriableFeatureTransferManager
29-5-19 15:05:18.952 | TransferManager | Test worker    | INFO : - SKIPPING unsupported optional feature PathAware
29-5-19 15:05:18.952 | TransferManager | Test worker    | FINE : - Wrapping TransferManager RetriableFeatureTransferManager in TransactionAwareFeatureTransferManager
29-5-19 15:05:18.959 | DatabaseConnect | Test worker    | INFO : Found 12 tables.
29-5-19 15:05:18.959 | UpOperation     | Test worker    | INFO : 
29-5-19 15:05:18.959 | UpOperation     | Test worker    | INFO : Running 'Sync up' at client A ...
29-5-19 15:05:18.959 | UpOperation     | Test worker    | INFO : --------------------------------------------
29-5-19 15:05:18.959 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event UpStartSyncExternalEvent (org.syncany.operations.daemon.messages.UpStartSyncExternalEvent@4717efdb) ...
29-5-19 15:05:18.960 | DatabaseConnect | Test worker    | INFO : Found 12 tables.
29-5-19 15:05:18.960 | StatusOperation | Test worker    | INFO : 
29-5-19 15:05:18.960 | StatusOperation | Test worker    | INFO : Running 'Status' at client A ...
29-5-19 15:05:18.960 | StatusOperation | Test worker    | INFO : --------------------------------------------
29-5-19 15:05:18.960 | StatusOperation | Test worker    | INFO : Force checksum ENABLED.
29-5-19 15:05:18.960 | StatusOperation | Test worker    | INFO : Querying current file tree from database ...
29-5-19 15:05:18.960 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event StatusStartSyncExternalEvent (org.syncany.operations.daemon.messages.StatusStartSyncExternalEvent@7bb77955) ...
29-5-19 15:05:18.961 | StatusOperation | Test worker    | INFO : Analyzing local folder /tmp/syncanytest/syncany-190529150308467-17932-client-A ...
29-5-19 15:05:18.963 | StatusOperation | Test worker    | FINE : - New file: file2.txt
29-5-19 15:05:18.963 | StatusOperation | Test worker    | FINE : - Ignoring file (syncany app-related): .syncany
29-5-19 15:05:18.963 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event StatusEndSyncExternalEvent (org.syncany.operations.daemon.messages.StatusEndSyncExternalEvent@130004d) ...
29-5-19 15:05:18.963 | AbstractTransfe | Test worker    | INFO : Looking for other running remote operations ...
29-5-19 15:05:18.964 | DatabaseConnect | Test worker    | INFO : Found 12 tables.
29-5-19 15:05:18.964 | LsRemoteOperati | Test worker    | INFO : 
29-5-19 15:05:18.964 | LsRemoteOperati | Test worker    | INFO : Running 'Remote Status' at client A ...
29-5-19 15:05:18.964 | LsRemoteOperati | Test worker    | INFO : --------------------------------------------
29-5-19 15:05:18.964 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event LsRemoteStartSyncExternalEvent (org.syncany.operations.daemon.messages.LsRemoteStartSyncExternalEvent@42590d87) ...
29-5-19 15:05:18.964 | LsRemoteOperati | Test worker    | INFO : Retrieving remote database list.
29-5-19 15:05:18.964 | LsRemoteOperati | Test worker    | INFO : - Remote database database-A-0000000001 is already known (in local database). Ignoring.
29-5-19 15:05:18.964 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event LsRemoteEndSyncExternalEvent (org.syncany.operations.daemon.messages.LsRemoteEndSyncExternalEvent@4960c90a) ...
29-5-19 15:05:18.964 | UpOperation     | Test worker    | INFO : No remote changes, ready to upload.
29-5-19 15:05:18.964 | ActionFileHandl | Test worker    | INFO : Starting action for RemoteFile[name=action-up-A-1559142318952] ...
29-5-19 15:05:18.964 | ActionFileHandl | Test worker    | INFO : Uploading action file: RemoteFile[name=action-up-A-1559142318952]
29-5-19 15:05:18.965 | ActionFileHandl | Test worker    | INFO : Scheduling action renewal task for every 2 minutes, for RemoteFile[name=action-up-A-1559142318952] ...
29-5-19 15:05:18.965 | TransactionAwar | Test worker    | WARN : Cannot parse line in transaction list: INVALID. Cannot resume.
29-5-19 15:05:18.966 | DatabaseConnect | Test worker    | INFO : Found 12 tables.
29-5-19 15:05:18.974 | TransactionAwar | Test worker    | INFO : Done rolling back previous transactions.
29-5-19 15:05:18.975 | TransactionAwar | Test worker    | WARN : Cannot parse line in transaction list: INVALID. Cannot resume.
29-5-19 15:05:18.975 | AsyncIndexer    | AsyncI/syncany | INFO : Starting Indexing.
29-5-19 15:05:18.975 | InternalEventBu | AsyncI/syncany | INFO : Event bus 'LocalEventBus': Posting event UpIndexStartSyncExternalEvent (org.syncany.operations.daemon.messages.UpIndexStartSyncExternalEvent@40eb3791) ...
29-5-19 15:05:18.975 | Indexer         | AsyncI/syncany | FINE : - Looking for deleted files ...
29-5-19 15:05:18.975 | Indexer         | AsyncI/syncany | FINE : - +File /tmp/syncanytest/syncany-190529150308467-17932-client-A/file2.txt
29-5-19 15:05:18.975 | Indexer         | AsyncI/syncany | FINE : - +FileContent: /tmp/syncanytest/syncany-190529150308467-17932-client-A/file2.txt
29-5-19 15:05:18.976 | Indexer         | AsyncI/syncany | FINE : - Chunk new: 9c5e00e2df9c3b107386be991fb6206708553be3
29-5-19 15:05:18.976 | Indexer         | AsyncI/syncany | FINE : - +MultiChunk a1bddb7378d5f91ea4e29d46bafd096faa6f860e
29-5-19 15:05:18.976 | Indexer         | AsyncI/syncany | FINE : - Chunk > MultiChunk: 9c5e00e2df9c3b107386be991fb6206708553be3 > a1bddb7378d5f91ea4e29d46bafd096faa6f860e
29-5-19 15:05:18.976 | Indexer         | AsyncI/syncany | FINE : - Chunk > FileContent: 9c5e00e2df9c3b107386be991fb6206708553be3 > /tmp/syncanytest/syncany-190529150308467-17932-client-A/file2.txt
29-5-19 15:05:18.976 | Indexer         | AsyncI/syncany | FINE : - /File: file2.txt (checksum 9c5e00e2df9c3b107386be991fb6206708553be3)
29-5-19 15:05:18.983 | Indexer         | AsyncI/syncany | FINE :    * No old file history found, starting new history (path: file2.txt, checksum: 9c5e00e2df9c3b107386be991fb6206708553be3)
29-5-19 15:05:18.983 | FileVersionComp | AsyncI/syncany | INFO :      - [DELETED]: Local file DIFFERS from file version, actual file is NULL, for file file2.txt
29-5-19 15:05:18.983 | Indexer         | AsyncI/syncany | INFO :    * Added file version:    FileVersion [version=1, path=file2.txt, type=FILE, status=NEW, size=1024, lastModified=Wed May 29 15:05:18 UTC 2019, linkTarget=null, checksum=9c5e00e2df9c3b107386be991fb6206708553be3, updated=Wed May 29 15:05:18 UTC 2019, posixPermissions=rw-rw-r--, dosAttributes=--a-]
29-5-19 15:05:18.983 | Indexer         | AsyncI/syncany | INFO :      based on file version: null
29-5-19 15:05:18.983 | InternalEventBu | AsyncI/syncany | INFO : Event bus 'LocalEventBus': Posting event UpIndexChangesDetectedSyncExternalEvent (org.syncany.operations.daemon.messages.UpIndexChangesDetectedSyncExternalEvent@1bfe200b) ...
29-5-19 15:05:18.983 | Indexer         | AsyncI/syncany | FINE : - /MultiChunk a1bddb7378d5f91ea4e29d46bafd096faa6f860e
29-5-19 15:05:18.983 | InternalEventBu | AsyncI/syncany | INFO : Event bus 'LocalEventBus': Posting event UpIndexEndSyncExternalEvent (org.syncany.operations.daemon.messages.UpIndexEndSyncExternalEvent@5fe74ad9) ...
29-5-19 15:05:18.983 | Indexer         | AsyncI/syncany | FINE : Processed new database version: DatabaseVersion [header=UnknownMachine/()/T=1559142318975, chunks=1, multiChunks=1, fileContents=1, fileHistories=1]
29-5-19 15:05:18.984 | UpOperation     | Test worker    | INFO : No DIRTY data found in database (no dirty databases); Nothing to do here.
29-5-19 15:05:18.984 | UpOperation     | Test worker    | INFO : Last vector clock was: (A1)
29-5-19 15:05:18.984 | UpOperation     | Test worker    | INFO : Uploading new multichunks ...
29-5-19 15:05:18.984 | UpOperation     | Test worker    | INFO : - Uploading multichunk a1bddb7378d5f91ea4e29d46bafd096faa6f860e from /tmp/syncanytest/syncany-190529150308467-17932-client-A/.syncany/cache/multichunk-a1bddb7378d5f91ea4e29d46bafd096faa6f860e to RemoteFile[name=multichunk-a1bddb7378d5f91ea4e29d46bafd096faa6f860e] ...
29-5-19 15:05:18.984 | RemoteTransacti | Test worker    | INFO : - Adding file to TX for UPLOAD: /tmp/syncanytest/syncany-190529150308467-17932-client-A/.syncany/cache/multichunk-a1bddb7378d5f91ea4e29d46bafd096faa6f860e -> Temp. remote file: RemoteFile[name=temp-EahoI-multichunk-a1bddb7378d5f91ea4e29d46bafd096faa6f860e], final location: RemoteFile[name=multichunk-a1bddb7378d5f91ea4e29d46bafd096faa6f860e]
29-5-19 15:05:18.985 | UpOperation     | Test worker    | INFO : Uploading database: DatabaseVersion [header=A/(A2)/T=1559142318984, chunks=1, multiChunks=1, fileContents=1, fileHistories=1]
29-5-19 15:05:18.985 | UpOperation     | Test worker    | INFO : Saving local delta database, version A/(A2)/T=1559142318984 to file /tmp/syncanytest/syncany-190529150308467-17932-client-A/.syncany/cache/database-A-0000000002 ... 
29-5-19 15:05:18.985 | UpOperation     | Test worker    | INFO : - Saving database to /tmp/syncanytest/syncany-190529150308467-17932-client-A/.syncany/cache/database-A-0000000002 ...
29-5-19 15:05:18.987 | UpOperation     | Test worker    | INFO : - Uploading local delta database file ...
29-5-19 15:05:18.987 | UpOperation     | Test worker    | INFO : - Uploading /tmp/syncanytest/syncany-190529150308467-17932-client-A/.syncany/cache/database-A-0000000002 to RemoteFile[name=database-A-0000000002] ...
29-5-19 15:05:18.987 | RemoteTransacti | Test worker    | INFO : - Adding file to TX for UPLOAD: /tmp/syncanytest/syncany-190529150308467-17932-client-A/.syncany/cache/database-A-0000000002 -> Temp. remote file: RemoteFile[name=temp-xZqsR-database-A-0000000002], final location: RemoteFile[name=database-A-0000000002]
29-5-19 15:05:18.987 | UpOperation     | Test worker    | INFO : Adding shutdown hook (to allow resuming the upload) ...
29-5-19 15:05:18.987 | RemoteTransacti | Test worker    | INFO : Starting TX.commit() ...
29-5-19 15:05:18.989 | RemoteTransacti | Test worker    | INFO : Wrote transaction manifest to temporary file: /tmp/syncanytest/syncany-190529150308467-17932-client-A/.syncany/cache/temp-transaction-9550463474181743832.tmp
29-5-19 15:05:18.989 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event UpUploadFileSyncExternalEvent (org.syncany.operations.daemon.messages.UpUploadFileSyncExternalEvent@2d5a00bb) ...
29-5-19 15:05:18.989 | RemoteTransacti | Test worker    | INFO : - Uploading remote transaction file RemoteFile[name=transaction-7a4d783e] ...
29-5-19 15:05:18.989 | RemoteTransacti | Test worker    | INFO : - Starting to upload data in commit.
29-5-19 15:05:18.989 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event UpUploadFileInTransactionSyncExternalEvent (org.syncany.operations.daemon.messages.UpUploadFileInTransactionSyncExternalEvent@5cd3c178) ...
29-5-19 15:05:18.989 | RemoteTransacti | Test worker    | INFO : - Uploading /tmp/syncanytest/syncany-190529150308467-17932-client-A/.syncany/cache/multichunk-a1bddb7378d5f91ea4e29d46bafd096faa6f860e to temp. file RemoteFile[name=temp-EahoI-multichunk-a1bddb7378d5f91ea4e29d46bafd096faa6f860e] ...
29-5-19 15:05:18.990 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event UpUploadFileInTransactionSyncExternalEvent (org.syncany.operations.daemon.messages.UpUploadFileInTransactionSyncExternalEvent@359b0b3c) ...
29-5-19 15:05:18.990 | RemoteTransacti | Test worker    | INFO : - Uploading /tmp/syncanytest/syncany-190529150308467-17932-client-A/.syncany/cache/database-A-0000000002 to temp. file RemoteFile[name=temp-xZqsR-database-A-0000000002] ...
29-5-19 15:05:18.990 | RemoteTransacti | Test worker    | INFO : - Moving temp. file RemoteFile[name=temp-EahoI-multichunk-a1bddb7378d5f91ea4e29d46bafd096faa6f860e] to final location RemoteFile[name=multichunk-a1bddb7378d5f91ea4e29d46bafd096faa6f860e] ...
29-5-19 15:05:18.990 | RemoteTransacti | Test worker    | INFO : - Moving temp. file RemoteFile[name=temp-xZqsR-database-A-0000000002] to final location RemoteFile[name=database-A-0000000002] ...
29-5-19 15:05:18.990 | RemoteTransacti | Test worker    | INFO : - Deleting remote transaction file RemoteFile[name=transaction-7a4d783e] ...
29-5-19 15:05:18.990 | RemoteTransacti | Test worker    | INFO : END of TX.commmit(): Succesfully committed transaction.
29-5-19 15:05:18.990 | RemoteTransacti | Test worker    | INFO : END of TX.delTemp(): Sucessfully deleted final files.
29-5-19 15:05:18.990 | UpOperation     | Test worker    | INFO : Persisting local SQL database (new database version A/(A2)/T=1559142318984) ...
29-5-19 15:05:18.992 | UpOperation     | Test worker    | INFO : Removing DIRTY database versions from database ...
29-5-19 15:05:18.994 | InternalEventBu | AsyncI/syncany | INFO : Event bus 'LocalEventBus': Posting event UpIndexMidSyncExternalEvent (org.syncany.operations.daemon.messages.UpIndexMidSyncExternalEvent@31814ea5) ...
29-5-19 15:05:19.005 | AsyncIndexer    | AsyncI/syncany | INFO : Stopping indexing. Signal end of stream with empty databaseversion
29-5-19 15:05:19.007 | UpOperation     | Test worker    | INFO : Adding database version to result changes:DatabaseVersion [header=A/(A2)/T=1559142318984, chunks=1, multiChunks=1, fileContents=1, fileHistories=1]
29-5-19 15:05:19.007 | UpOperation     | Test worker    | INFO : Committing local database.
29-5-19 15:05:19.007 | UpOperation     | Test worker    | FINE : Waiting for new database version.
29-5-19 15:05:19.007 | UpOperation     | Test worker    | FINE : Took new database version: DatabaseVersion [header=UnknownMachine/()/T=1559142319005, chunks=0, multiChunks=0, fileContents=0, fileHistories=0]
29-5-19 15:05:19.007 | UpOperation     | Test worker    | INFO : Sync up done.
29-5-19 15:05:19.009 | ActionFileHandl | Test worker    | INFO : Finishing action for RemoteFile[name=action-up-A-1559142318952] ...
29-5-19 15:05:19.018 | ActionFileHandl | Test worker    | INFO : Deleting action file: RemoteFile[name=action-up-A-1559142318952]
29-5-19 15:05:19.019 | AbstractTransfe | Test worker    | INFO : Cleaning own old action files ...
29-5-19 15:05:19.019 | Cache           | Test worker    | INFO : Cache size okay (4 KB), no need to clean (keep size is 500 MB)
29-5-19 15:05:19.019 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event UpEndSyncExternalEvent (org.syncany.operations.daemon.messages.UpEndSyncExternalEvent@1eb60b54) ...