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

2

tests

0

failures

0

ignored

0.597s

duration

100%

successful

Tests

Test Duration Result
testCorruptTransactionFile 0.359s passed
testCorruptTransactionListFile 0.238s passed

Standard error

29-3-20 17:52:18.814 | org.syncany.plu | Test worker    | FINE : Validating required fields
29-3-20 17:52:18.814 | LocalTransferMa | Test worker    | INFO : testTargetExists: Target exists.
29-3-20 17:52:18.817 | org.syncany.plu | Test worker    | FINE : Validating required fields
29-3-20 17:52:18.817 | TransferManager | Test worker    | INFO : Building LocalTransferManager from config 'syncany-200329175011381-90083-client-A' ...
29-3-20 17:52:18.817 | TransferManager | Test worker    | INFO : - With feature ReadAfterWriteConsistent
29-3-20 17:52:18.817 | TransferManager | Test worker    | INFO : - With feature Retriable
29-3-20 17:52:18.817 | TransferManager | Test worker    | FINE : - Checking if selected features supported ...
29-3-20 17:52:18.817 | TransferManager | Test worker    | FINE : - Checking for duplicate features ...
29-3-20 17:52:18.817 | TransferManager | Test worker    | FINE : - Checking required feature TransactionAware in LocalTransferManager ...
29-3-20 17:52:18.817 | TransferManager | Test worker    | FINE : - Checking required feature Retriable in LocalTransferManager ...
29-3-20 17:52:18.818 | TransferManager | Test worker    | INFO : - SKIPPING unsupported optional feature ReadAfterWriteConsistent
29-3-20 17:52:18.818 | TransferManager | Test worker    | FINE : - Wrapping TransferManager LocalTransferManager in RetriableFeatureTransferManager
29-3-20 17:52:18.818 | org.syncany.plu | Test worker    | FINE : Validating required fields
29-3-20 17:52:18.818 | TransferManager | Test worker    | INFO : Building LocalTransferManager from config 'syncany-200329175011381-90083-client-A' ...
29-3-20 17:52:18.818 | TransferManager | Test worker    | INFO : - With feature ReadAfterWriteConsistent
29-3-20 17:52:18.818 | TransferManager | Test worker    | INFO : - With feature Retriable
29-3-20 17:52:18.818 | TransferManager | Test worker    | INFO : - With feature PathAware
29-3-20 17:52:18.818 | TransferManager | Test worker    | INFO : - With feature TransactionAware
29-3-20 17:52:18.818 | TransferManager | Test worker    | FINE : - Checking if selected features supported ...
29-3-20 17:52:18.818 | TransferManager | Test worker    | FINE : - Checking for duplicate features ...
29-3-20 17:52:18.818 | TransferManager | Test worker    | FINE : - Checking required feature TransactionAware in LocalTransferManager ...
29-3-20 17:52:18.818 | TransferManager | Test worker    | FINE : - Checking required feature Retriable in LocalTransferManager ...
29-3-20 17:52:18.818 | TransferManager | Test worker    | INFO : - SKIPPING unsupported optional feature ReadAfterWriteConsistent
29-3-20 17:52:18.818 | TransferManager | Test worker    | FINE : - Wrapping TransferManager LocalTransferManager in RetriableFeatureTransferManager
29-3-20 17:52:18.818 | TransferManager | Test worker    | INFO : - SKIPPING unsupported optional feature PathAware
29-3-20 17:52:18.818 | TransferManager | Test worker    | FINE : - Wrapping TransferManager RetriableFeatureTransferManager in TransactionAwareFeatureTransferManager
29-3-20 17:52:18.889 | hsqldb.db.HSQLD | Test worker    | INFO : Checkpoint start
29-3-20 17:52:18.890 | hsqldb.db.HSQLD | Test worker    | INFO : checkpointClose start
29-3-20 17:52:18.898 | hsqldb.db.HSQLD | Test worker    | FINE : log close size: 0
29-3-20 17:52:18.900 | hsqldb.db.HSQLD | Test worker    | INFO : checkpointClose end
29-3-20 17:52:18.901 | hsqldb.db.HSQLD | Test worker    | INFO : Checkpoint end - txts: 1
29-3-20 17:52:18.904 | DatabaseConnect | Test worker    | INFO : Found 0 tables.
29-3-20 17:52:18.904 | DatabaseConnect | Test worker    | INFO : Database has no tables. Creating tables from script.create.all.sql
29-3-20 17:52:18.905 | SqlRunner       | Test worker    | INFO : SQL (comment): -- Tables
29-3-20 17:52:18.905 | 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:52:18.907 | 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:52:18.909 | 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:52:18.911 | 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:52:18.912 | 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:52:18.914 | 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:52:18.916 | 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:52:18.919 | 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:52:18.920 | 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:52:18.921 | 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:52:18.923 | 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:52:18.924 | 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:52:18.926 | SqlRunner       | Test worker    | INFO : SQL (comment): -- Non-primary indices
29-3-20 17:52:18.926 | SqlRunner       | Test worker    | INFO : SQL:   CREATE INDEX idx_databaseversion_status ON databaseversion (status); 
29-3-20 17:52:18.926 | hsqldb.db.HSQLD | Test worker    | INFO : dataFileCache open start
29-3-20 17:52:18.928 | hsqldb.db.HSQLD | Test worker    | INFO : dataFileCache open end
29-3-20 17:52:18.930 | SqlRunner       | Test worker    | INFO : SQL: CREATE INDEX idx_databaseversion_vectorclock_serialized ON databaseversion (vectorclock_serialized); 
29-3-20 17:52:18.931 | SqlRunner       | Test worker    | INFO : SQL: CREATE INDEX idx_fileversion_path ON fileversion (path); 
29-3-20 17:52:18.932 | SqlRunner       | Test worker    | INFO : SQL: CREATE INDEX idx_fileversion_status ON fileversion (status); 
29-3-20 17:52:18.933 | SqlRunner       | Test worker    | INFO : SQL: CREATE INDEX idx_fileversion_filecontent_checksum ON fileversion (filecontent_checksum); 
29-3-20 17:52:18.935 | SqlRunner       | Test worker    | INFO : SQL (comment): -- Views
29-3-20 17:52:18.935 | 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:52:18.937 | 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:52:18.938 | 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:52:18.940 | 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:52:18.942 | SqlRunner       | Test worker    | INFO : SQL (comment): -- Full Views
29-3-20 17:52:18.942 | 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:52:18.949 | 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:52:18.950 | SqlRunner       | Test worker    | INFO : SQL (comment): -- Functions
29-3-20 17:52:18.950 | SqlRunner       | Test worker    | INFO : SQL (new delimiter): end;
29-3-20 17:52:18.950 | 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:52:18.952 | UpOperation     | Test worker    | INFO : 
29-3-20 17:52:18.952 | UpOperation     | Test worker    | INFO : Running 'Sync up' at client A ...
29-3-20 17:52:18.952 | UpOperation     | Test worker    | INFO : --------------------------------------------
29-3-20 17:52:18.952 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event UpStartSyncExternalEvent (org.syncany.operations.daemon.messages.UpStartSyncExternalEvent@1d312ea9) ...
29-3-20 17:52:18.953 | DatabaseConnect | Test worker    | INFO : Found 12 tables.
29-3-20 17:52:18.953 | StatusOperation | Test worker    | INFO : 
29-3-20 17:52:18.953 | StatusOperation | Test worker    | INFO : Running 'Status' at client A ...
29-3-20 17:52:18.953 | StatusOperation | Test worker    | INFO : --------------------------------------------
29-3-20 17:52:18.953 | StatusOperation | Test worker    | INFO : Force checksum ENABLED.
29-3-20 17:52:18.953 | StatusOperation | Test worker    | INFO : Querying current file tree from database ...
29-3-20 17:52:18.953 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event StatusStartSyncExternalEvent (org.syncany.operations.daemon.messages.StatusStartSyncExternalEvent@66ccb51b) ...
29-3-20 17:52:18.953 | StatusOperation | Test worker    | INFO : Analyzing local folder /tmp/syncanytest/syncany-200329175011381-90083-client-A ...
29-3-20 17:52:18.954 | StatusOperation | Test worker    | FINE : - New file: file1.txt
29-3-20 17:52:18.954 | StatusOperation | Test worker    | FINE : - Ignoring file (syncany app-related): .syncany
29-3-20 17:52:18.954 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event StatusEndSyncExternalEvent (org.syncany.operations.daemon.messages.StatusEndSyncExternalEvent@703cf2af) ...
29-3-20 17:52:18.954 | AbstractTransfe | Test worker    | INFO : Looking for other running remote operations ...
29-3-20 17:52:18.954 | DatabaseConnect | Test worker    | INFO : Found 12 tables.
29-3-20 17:52:18.955 | LsRemoteOperati | Test worker    | INFO : 
29-3-20 17:52:18.955 | LsRemoteOperati | Test worker    | INFO : Running 'Remote Status' at client A ...
29-3-20 17:52:18.955 | LsRemoteOperati | Test worker    | INFO : --------------------------------------------
29-3-20 17:52:18.955 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event LsRemoteStartSyncExternalEvent (org.syncany.operations.daemon.messages.LsRemoteStartSyncExternalEvent@7c448849) ...
29-3-20 17:52:18.955 | LsRemoteOperati | Test worker    | INFO : Retrieving remote database list.
29-3-20 17:52:18.955 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event LsRemoteEndSyncExternalEvent (org.syncany.operations.daemon.messages.LsRemoteEndSyncExternalEvent@286300ea) ...
29-3-20 17:52:18.955 | UpOperation     | Test worker    | INFO : No remote changes, ready to upload.
29-3-20 17:52:18.955 | ActionFileHandl | Test worker    | INFO : Starting action for RemoteFile[name=action-up-A-1585504338818] ...
29-3-20 17:52:18.955 | ActionFileHandl | Test worker    | INFO : Uploading action file: RemoteFile[name=action-up-A-1585504338818]
29-3-20 17:52:18.955 | ActionFileHandl | Test worker    | INFO : Scheduling action renewal task for every 2 minutes, for RemoteFile[name=action-up-A-1585504338818] ...
29-3-20 17:52:18.956 | DatabaseConnect | Test worker    | INFO : Found 12 tables.
29-3-20 17:52:18.960 | TransactionAwar | Test worker    | INFO : Done rolling back previous transactions.
29-3-20 17:52:18.960 | AsyncIndexer    | AsyncI/syncany | INFO : Starting Indexing.
29-3-20 17:52:18.961 | InternalEventBu | AsyncI/syncany | INFO : Event bus 'LocalEventBus': Posting event UpIndexStartSyncExternalEvent (org.syncany.operations.daemon.messages.UpIndexStartSyncExternalEvent@56769625) ...
29-3-20 17:52:18.961 | Indexer         | AsyncI/syncany | FINE : - Looking for deleted files ...
29-3-20 17:52:18.961 | Indexer         | AsyncI/syncany | FINE : - +File /tmp/syncanytest/syncany-200329175011381-90083-client-A/file1.txt
29-3-20 17:52:18.961 | Indexer         | AsyncI/syncany | FINE : - +FileContent: /tmp/syncanytest/syncany-200329175011381-90083-client-A/file1.txt
29-3-20 17:52:18.972 | Indexer         | AsyncI/syncany | FINE : - Chunk new: 5db5a5b8241c59046d794605378e58d1a3c98ca4
29-3-20 17:52:18.972 | Indexer         | AsyncI/syncany | FINE : - +MultiChunk b807fa90be90405895bad675804d63a65e1f077d
29-3-20 17:52:18.972 | Indexer         | AsyncI/syncany | FINE : - Chunk > MultiChunk: 5db5a5b8241c59046d794605378e58d1a3c98ca4 > b807fa90be90405895bad675804d63a65e1f077d
29-3-20 17:52:18.972 | Indexer         | AsyncI/syncany | FINE : - Chunk > FileContent: 5db5a5b8241c59046d794605378e58d1a3c98ca4 > /tmp/syncanytest/syncany-200329175011381-90083-client-A/file1.txt
29-3-20 17:52:18.973 | Indexer         | AsyncI/syncany | FINE : - /File: file1.txt (checksum 5db5a5b8241c59046d794605378e58d1a3c98ca4)
29-3-20 17:52:18.973 | Indexer         | AsyncI/syncany | FINE :    * No old file history found, starting new history (path: file1.txt, checksum: 5db5a5b8241c59046d794605378e58d1a3c98ca4)
29-3-20 17:52:18.973 | FileVersionComp | AsyncI/syncany | INFO :      - [DELETED]: Local file DIFFERS from file version, actual file is NULL, for file file1.txt
29-3-20 17:52:18.973 | Indexer         | AsyncI/syncany | INFO :    * Added file version:    FileVersion [version=1, path=file1.txt, type=FILE, status=NEW, size=1024, lastModified=Sun Mar 29 17:52:18 UTC 2020, linkTarget=null, checksum=5db5a5b8241c59046d794605378e58d1a3c98ca4, updated=Sun Mar 29 17:52:18 UTC 2020, posixPermissions=rw-rw-r--, dosAttributes=--a-]
29-3-20 17:52:18.973 | Indexer         | AsyncI/syncany | INFO :      based on file version: null
29-3-20 17:52:18.973 | InternalEventBu | AsyncI/syncany | INFO : Event bus 'LocalEventBus': Posting event UpIndexChangesDetectedSyncExternalEvent (org.syncany.operations.daemon.messages.UpIndexChangesDetectedSyncExternalEvent@6e2d40c9) ...
29-3-20 17:52:18.980 | Indexer         | AsyncI/syncany | FINE : - /MultiChunk b807fa90be90405895bad675804d63a65e1f077d
29-3-20 17:52:18.980 | InternalEventBu | AsyncI/syncany | INFO : Event bus 'LocalEventBus': Posting event UpIndexEndSyncExternalEvent (org.syncany.operations.daemon.messages.UpIndexEndSyncExternalEvent@6ea16fde) ...
29-3-20 17:52:18.980 | Indexer         | AsyncI/syncany | FINE : Processed new database version: DatabaseVersion [header=UnknownMachine/()/T=1585504338960, chunks=1, multiChunks=1, fileContents=1, fileHistories=1]
29-3-20 17:52:18.980 | UpOperation     | Test worker    | INFO : No DIRTY data found in database (no dirty databases); Nothing to do here.
29-3-20 17:52:18.981 | UpOperation     | Test worker    | INFO : Last vector clock was: ()
29-3-20 17:52:18.981 | UpOperation     | Test worker    | INFO : Uploading new multichunks ...
29-3-20 17:52:18.981 | UpOperation     | Test worker    | INFO : - Uploading multichunk b807fa90be90405895bad675804d63a65e1f077d from /tmp/syncanytest/syncany-200329175011381-90083-client-A/.syncany/cache/multichunk-b807fa90be90405895bad675804d63a65e1f077d to RemoteFile[name=multichunk-b807fa90be90405895bad675804d63a65e1f077d] ...
29-3-20 17:52:18.981 | RemoteTransacti | Test worker    | INFO : - Adding file to TX for UPLOAD: /tmp/syncanytest/syncany-200329175011381-90083-client-A/.syncany/cache/multichunk-b807fa90be90405895bad675804d63a65e1f077d -> Temp. remote file: RemoteFile[name=temp-ytTMV-multichunk-b807fa90be90405895bad675804d63a65e1f077d], final location: RemoteFile[name=multichunk-b807fa90be90405895bad675804d63a65e1f077d]
29-3-20 17:52:18.981 | UpOperation     | Test worker    | INFO : Uploading database: DatabaseVersion [header=A/(A1)/T=1585504338981, chunks=1, multiChunks=1, fileContents=1, fileHistories=1]
29-3-20 17:52:18.982 | UpOperation     | Test worker    | INFO : Saving local delta database, version A/(A1)/T=1585504338981 to file /tmp/syncanytest/syncany-200329175011381-90083-client-A/.syncany/cache/database-A-0000000001 ... 
29-3-20 17:52:18.982 | UpOperation     | Test worker    | INFO : - Saving database to /tmp/syncanytest/syncany-200329175011381-90083-client-A/.syncany/cache/database-A-0000000001 ...
29-3-20 17:52:18.982 | UpOperation     | Test worker    | INFO : - Uploading local delta database file ...
29-3-20 17:52:18.982 | UpOperation     | Test worker    | INFO : - Uploading /tmp/syncanytest/syncany-200329175011381-90083-client-A/.syncany/cache/database-A-0000000001 to RemoteFile[name=database-A-0000000001] ...
29-3-20 17:52:18.982 | RemoteTransacti | Test worker    | INFO : - Adding file to TX for UPLOAD: /tmp/syncanytest/syncany-200329175011381-90083-client-A/.syncany/cache/database-A-0000000001 -> Temp. remote file: RemoteFile[name=temp-RnyuA-database-A-0000000001], final location: RemoteFile[name=database-A-0000000001]
29-3-20 17:52:18.983 | UpOperation     | Test worker    | INFO : Adding shutdown hook (to allow resuming the upload) ...
29-3-20 17:52:18.983 | RemoteTransacti | Test worker    | INFO : Starting TX.commit() ...
29-3-20 17:52:18.984 | RemoteTransacti | Test worker    | INFO : Wrote transaction manifest to temporary file: /tmp/syncanytest/syncany-200329175011381-90083-client-A/.syncany/cache/temp-transaction-5784885336942991407.tmp
29-3-20 17:52:18.984 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event UpUploadFileSyncExternalEvent (org.syncany.operations.daemon.messages.UpUploadFileSyncExternalEvent@1e164520) ...
29-3-20 17:52:18.984 | RemoteTransacti | Test worker    | INFO : - Uploading remote transaction file RemoteFile[name=transaction-2c5ed880] ...
29-3-20 17:52:18.984 | RemoteTransacti | Test worker    | INFO : - Starting to upload data in commit.
29-3-20 17:52:18.984 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event UpUploadFileInTransactionSyncExternalEvent (org.syncany.operations.daemon.messages.UpUploadFileInTransactionSyncExternalEvent@46e45d08) ...
29-3-20 17:52:18.984 | RemoteTransacti | Test worker    | INFO : - Uploading /tmp/syncanytest/syncany-200329175011381-90083-client-A/.syncany/cache/multichunk-b807fa90be90405895bad675804d63a65e1f077d to temp. file RemoteFile[name=temp-ytTMV-multichunk-b807fa90be90405895bad675804d63a65e1f077d] ...
29-3-20 17:52:18.985 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event UpUploadFileInTransactionSyncExternalEvent (org.syncany.operations.daemon.messages.UpUploadFileInTransactionSyncExternalEvent@5c73c593) ...
29-3-20 17:52:18.985 | RemoteTransacti | Test worker    | INFO : - Uploading /tmp/syncanytest/syncany-200329175011381-90083-client-A/.syncany/cache/database-A-0000000001 to temp. file RemoteFile[name=temp-RnyuA-database-A-0000000001] ...
29-3-20 17:52:18.985 | RemoteTransacti | Test worker    | INFO : - Moving temp. file RemoteFile[name=temp-ytTMV-multichunk-b807fa90be90405895bad675804d63a65e1f077d] to final location RemoteFile[name=multichunk-b807fa90be90405895bad675804d63a65e1f077d] ...
29-3-20 17:52:18.985 | RemoteTransacti | Test worker    | INFO : - Moving temp. file RemoteFile[name=temp-RnyuA-database-A-0000000001] to final location RemoteFile[name=database-A-0000000001] ...
29-3-20 17:52:18.985 | RemoteTransacti | Test worker    | INFO : - Deleting remote transaction file RemoteFile[name=transaction-2c5ed880] ...
29-3-20 17:52:18.985 | RemoteTransacti | Test worker    | INFO : END of TX.commmit(): Succesfully committed transaction.
29-3-20 17:52:18.985 | RemoteTransacti | Test worker    | INFO : END of TX.delTemp(): Sucessfully deleted final files.
29-3-20 17:52:18.985 | UpOperation     | Test worker    | INFO : Persisting local SQL database (new database version A/(A1)/T=1585504338981) ...
29-3-20 17:52:18.987 | UpOperation     | Test worker    | INFO : Removing DIRTY database versions from database ...
29-3-20 17:52:18.988 | InternalEventBu | AsyncI/syncany | INFO : Event bus 'LocalEventBus': Posting event UpIndexMidSyncExternalEvent (org.syncany.operations.daemon.messages.UpIndexMidSyncExternalEvent@27516cc3) ...
29-3-20 17:52:18.991 | AsyncIndexer    | AsyncI/syncany | INFO : Stopping indexing. Signal end of stream with empty databaseversion
29-3-20 17:52:19.004 | UpOperation     | Test worker    | INFO : Adding database version to result changes:DatabaseVersion [header=A/(A1)/T=1585504338981, chunks=1, multiChunks=1, fileContents=1, fileHistories=1]
29-3-20 17:52:19.004 | UpOperation     | Test worker    | INFO : Committing local database.
29-3-20 17:52:19.004 | UpOperation     | Test worker    | FINE : Waiting for new database version.
29-3-20 17:52:19.004 | UpOperation     | Test worker    | FINE : Took new database version: DatabaseVersion [header=UnknownMachine/()/T=1585504338991, chunks=0, multiChunks=0, fileContents=0, fileHistories=0]
29-3-20 17:52:19.004 | UpOperation     | Test worker    | INFO : Sync up done.
29-3-20 17:52:19.008 | ActionFileHandl | Test worker    | INFO : Finishing action for RemoteFile[name=action-up-A-1585504338818] ...
29-3-20 17:52:19.016 | ActionFileHandl | Test worker    | INFO : Deleting action file: RemoteFile[name=action-up-A-1585504338818]
29-3-20 17:52:19.016 | AbstractTransfe | Test worker    | INFO : Cleaning own old action files ...
29-3-20 17:52:19.016 | Cache           | Test worker    | INFO : Cache size okay (2 KB), no need to clean (keep size is 500 MB)
29-3-20 17:52:19.016 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event UpEndSyncExternalEvent (org.syncany.operations.daemon.messages.UpEndSyncExternalEvent@4061fcf8) ...
29-3-20 17:52:19.017 | org.syncany.plu | Test worker    | FINE : Validating required fields
29-3-20 17:52:19.017 | TransferManager | Test worker    | INFO : Building LocalTransferManager from config 'syncany-200329175011381-90083-client-A' ...
29-3-20 17:52:19.017 | TransferManager | Test worker    | INFO : - With feature ReadAfterWriteConsistent
29-3-20 17:52:19.017 | TransferManager | Test worker    | INFO : - With feature Retriable
29-3-20 17:52:19.017 | TransferManager | Test worker    | FINE : - Checking if selected features supported ...
29-3-20 17:52:19.017 | TransferManager | Test worker    | FINE : - Checking for duplicate features ...
29-3-20 17:52:19.017 | TransferManager | Test worker    | FINE : - Checking required feature TransactionAware in LocalTransferManager ...
29-3-20 17:52:19.017 | TransferManager | Test worker    | FINE : - Checking required feature Retriable in LocalTransferManager ...
29-3-20 17:52:19.017 | TransferManager | Test worker    | INFO : - SKIPPING unsupported optional feature ReadAfterWriteConsistent
29-3-20 17:52:19.017 | TransferManager | Test worker    | FINE : - Wrapping TransferManager LocalTransferManager in RetriableFeatureTransferManager
29-3-20 17:52:19.018 | org.syncany.plu | Test worker    | FINE : Validating required fields
29-3-20 17:52:19.018 | TransferManager | Test worker    | INFO : Building LocalTransferManager from config 'syncany-200329175011381-90083-client-A' ...
29-3-20 17:52:19.018 | TransferManager | Test worker    | INFO : - With feature ReadAfterWriteConsistent
29-3-20 17:52:19.018 | TransferManager | Test worker    | INFO : - With feature Retriable
29-3-20 17:52:19.018 | TransferManager | Test worker    | INFO : - With feature PathAware
29-3-20 17:52:19.018 | TransferManager | Test worker    | INFO : - With feature TransactionAware
29-3-20 17:52:19.018 | TransferManager | Test worker    | FINE : - Checking if selected features supported ...
29-3-20 17:52:19.018 | TransferManager | Test worker    | FINE : - Checking for duplicate features ...
29-3-20 17:52:19.018 | TransferManager | Test worker    | FINE : - Checking required feature TransactionAware in LocalTransferManager ...
29-3-20 17:52:19.018 | TransferManager | Test worker    | FINE : - Checking required feature Retriable in LocalTransferManager ...
29-3-20 17:52:19.018 | TransferManager | Test worker    | INFO : - SKIPPING unsupported optional feature ReadAfterWriteConsistent
29-3-20 17:52:19.018 | TransferManager | Test worker    | FINE : - Wrapping TransferManager LocalTransferManager in RetriableFeatureTransferManager
29-3-20 17:52:19.018 | TransferManager | Test worker    | INFO : - SKIPPING unsupported optional feature PathAware
29-3-20 17:52:19.019 | TransferManager | Test worker    | FINE : - Wrapping TransferManager RetriableFeatureTransferManager in TransactionAwareFeatureTransferManager
29-3-20 17:52:19.025 | DatabaseConnect | Test worker    | INFO : Found 12 tables.
29-3-20 17:52:19.025 | UpOperation     | Test worker    | INFO : 
29-3-20 17:52:19.025 | UpOperation     | Test worker    | INFO : Running 'Sync up' at client A ...
29-3-20 17:52:19.026 | UpOperation     | Test worker    | INFO : --------------------------------------------
29-3-20 17:52:19.026 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event UpStartSyncExternalEvent (org.syncany.operations.daemon.messages.UpStartSyncExternalEvent@c19899c) ...
29-3-20 17:52:19.026 | DatabaseConnect | Test worker    | INFO : Found 12 tables.
29-3-20 17:52:19.026 | StatusOperation | Test worker    | INFO : 
29-3-20 17:52:19.026 | StatusOperation | Test worker    | INFO : Running 'Status' at client A ...
29-3-20 17:52:19.027 | StatusOperation | Test worker    | INFO : --------------------------------------------
29-3-20 17:52:19.027 | StatusOperation | Test worker    | INFO : Force checksum ENABLED.
29-3-20 17:52:19.027 | StatusOperation | Test worker    | INFO : Querying current file tree from database ...
29-3-20 17:52:19.027 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event StatusStartSyncExternalEvent (org.syncany.operations.daemon.messages.StatusStartSyncExternalEvent@742410bd) ...
29-3-20 17:52:19.027 | StatusOperation | Test worker    | INFO : Analyzing local folder /tmp/syncanytest/syncany-200329175011381-90083-client-A ...
29-3-20 17:52:19.028 | StatusOperation | Test worker    | FINE : - New file: file2.txt
29-3-20 17:52:19.028 | StatusOperation | Test worker    | FINE : - Ignoring file (syncany app-related): .syncany
29-3-20 17:52:19.028 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event StatusEndSyncExternalEvent (org.syncany.operations.daemon.messages.StatusEndSyncExternalEvent@5925433c) ...
29-3-20 17:52:19.028 | AbstractTransfe | Test worker    | INFO : Looking for other running remote operations ...
29-3-20 17:52:19.029 | DatabaseConnect | Test worker    | INFO : Found 12 tables.
29-3-20 17:52:19.029 | LsRemoteOperati | Test worker    | INFO : 
29-3-20 17:52:19.029 | LsRemoteOperati | Test worker    | INFO : Running 'Remote Status' at client A ...
29-3-20 17:52:19.029 | LsRemoteOperati | Test worker    | INFO : --------------------------------------------
29-3-20 17:52:19.029 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event LsRemoteStartSyncExternalEvent (org.syncany.operations.daemon.messages.LsRemoteStartSyncExternalEvent@3e124f0a) ...
29-3-20 17:52:19.030 | LsRemoteOperati | Test worker    | INFO : Retrieving remote database list.
29-3-20 17:52:19.030 | LsRemoteOperati | Test worker    | INFO : - Remote database database-A-0000000001 is already known (in local database). Ignoring.
29-3-20 17:52:19.031 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event LsRemoteEndSyncExternalEvent (org.syncany.operations.daemon.messages.LsRemoteEndSyncExternalEvent@6f77ea32) ...
29-3-20 17:52:19.031 | UpOperation     | Test worker    | INFO : No remote changes, ready to upload.
29-3-20 17:52:19.031 | ActionFileHandl | Test worker    | INFO : Starting action for RemoteFile[name=action-up-A-1585504339019] ...
29-3-20 17:52:19.031 | ActionFileHandl | Test worker    | INFO : Uploading action file: RemoteFile[name=action-up-A-1585504339019]
29-3-20 17:52:19.031 | ActionFileHandl | Test worker    | INFO : Scheduling action renewal task for every 2 minutes, for RemoteFile[name=action-up-A-1585504339019] ...
29-3-20 17:52:19.032 | UpOperation     | Test worker    | INFO : Found local transaction to resume.
29-3-20 17:52:19.040 | UpOperation     | Test worker    | INFO : Attempting to find transactionRemoteFile
29-3-20 17:52:19.040 | DatabaseXmlSeri | Test worker    | INFO : - Loading database (FULL) from file /tmp/syncanytest/syncany-200329175011381-90083-client-A/.syncany/state/transaction-database.0000000001.xml ...
29-3-20 17:52:19.058 | UpOperation     | Test worker    | WARN : Cannot load database versions from 'state'. Cannot resume.
29-3-20 17:52:19.066 | DatabaseConnect | Test worker    | INFO : Found 12 tables.
29-3-20 17:52:19.070 | TransactionAwar | Test worker    | INFO : Done rolling back previous transactions.
29-3-20 17:52:19.071 | AsyncIndexer    | AsyncI/syncany | INFO : Starting Indexing.
29-3-20 17:52:19.071 | InternalEventBu | AsyncI/syncany | INFO : Event bus 'LocalEventBus': Posting event UpIndexStartSyncExternalEvent (org.syncany.operations.daemon.messages.UpIndexStartSyncExternalEvent@6537b35c) ...
29-3-20 17:52:19.071 | Indexer         | AsyncI/syncany | FINE : - Looking for deleted files ...
29-3-20 17:52:19.071 | Indexer         | AsyncI/syncany | FINE : - +File /tmp/syncanytest/syncany-200329175011381-90083-client-A/file2.txt
29-3-20 17:52:19.071 | Indexer         | AsyncI/syncany | FINE : - +FileContent: /tmp/syncanytest/syncany-200329175011381-90083-client-A/file2.txt
29-3-20 17:52:19.072 | Indexer         | AsyncI/syncany | FINE : - Chunk new: be88422e717411146a17485ab98a00f6a1c5de8f
29-3-20 17:52:19.072 | Indexer         | AsyncI/syncany | FINE : - +MultiChunk 58d26c05a13bf1309a8727ecb11d82e712cec988
29-3-20 17:52:19.072 | Indexer         | AsyncI/syncany | FINE : - Chunk > MultiChunk: be88422e717411146a17485ab98a00f6a1c5de8f > 58d26c05a13bf1309a8727ecb11d82e712cec988
29-3-20 17:52:19.073 | Indexer         | AsyncI/syncany | FINE : - Chunk > FileContent: be88422e717411146a17485ab98a00f6a1c5de8f > /tmp/syncanytest/syncany-200329175011381-90083-client-A/file2.txt
29-3-20 17:52:19.074 | Indexer         | AsyncI/syncany | FINE : - /File: file2.txt (checksum be88422e717411146a17485ab98a00f6a1c5de8f)
29-3-20 17:52:19.087 | Indexer         | AsyncI/syncany | FINE :    * No old file history found, starting new history (path: file2.txt, checksum: be88422e717411146a17485ab98a00f6a1c5de8f)
29-3-20 17:52:19.087 | FileVersionComp | AsyncI/syncany | INFO :      - [DELETED]: Local file DIFFERS from file version, actual file is NULL, for file file2.txt
29-3-20 17:52:19.087 | Indexer         | AsyncI/syncany | INFO :    * Added file version:    FileVersion [version=1, path=file2.txt, type=FILE, status=NEW, size=1024, lastModified=Sun Mar 29 17:52:19 UTC 2020, linkTarget=null, checksum=be88422e717411146a17485ab98a00f6a1c5de8f, updated=Sun Mar 29 17:52:19 UTC 2020, posixPermissions=rw-rw-r--, dosAttributes=--a-]
29-3-20 17:52:19.087 | Indexer         | AsyncI/syncany | INFO :      based on file version: null
29-3-20 17:52:19.087 | InternalEventBu | AsyncI/syncany | INFO : Event bus 'LocalEventBus': Posting event UpIndexChangesDetectedSyncExternalEvent (org.syncany.operations.daemon.messages.UpIndexChangesDetectedSyncExternalEvent@6739ddcf) ...
29-3-20 17:52:19.126 | Indexer         | AsyncI/syncany | FINE : - /MultiChunk 58d26c05a13bf1309a8727ecb11d82e712cec988
29-3-20 17:52:19.127 | InternalEventBu | AsyncI/syncany | INFO : Event bus 'LocalEventBus': Posting event UpIndexEndSyncExternalEvent (org.syncany.operations.daemon.messages.UpIndexEndSyncExternalEvent@336f3afc) ...
29-3-20 17:52:19.127 | Indexer         | AsyncI/syncany | FINE : Processed new database version: DatabaseVersion [header=UnknownMachine/()/T=1585504339071, chunks=1, multiChunks=1, fileContents=1, fileHistories=1]
29-3-20 17:52:19.127 | InternalEventBu | AsyncI/syncany | INFO : Event bus 'LocalEventBus': Posting event UpIndexMidSyncExternalEvent (org.syncany.operations.daemon.messages.UpIndexMidSyncExternalEvent@193cf706) ...
29-3-20 17:52:19.128 | AsyncIndexer    | AsyncI/syncany | INFO : Stopping indexing. Signal end of stream with empty databaseversion
29-3-20 17:52:19.131 | UpOperation     | Test worker    | INFO : No DIRTY data found in database (no dirty databases); Nothing to do here.
29-3-20 17:52:19.132 | UpOperation     | Test worker    | INFO : Last vector clock was: (A1)
29-3-20 17:52:19.132 | UpOperation     | Test worker    | INFO : Uploading new multichunks ...
29-3-20 17:52:19.132 | UpOperation     | Test worker    | INFO : - Uploading multichunk 58d26c05a13bf1309a8727ecb11d82e712cec988 from /tmp/syncanytest/syncany-200329175011381-90083-client-A/.syncany/cache/multichunk-58d26c05a13bf1309a8727ecb11d82e712cec988 to RemoteFile[name=multichunk-58d26c05a13bf1309a8727ecb11d82e712cec988] ...
29-3-20 17:52:19.132 | RemoteTransacti | Test worker    | INFO : - Adding file to TX for UPLOAD: /tmp/syncanytest/syncany-200329175011381-90083-client-A/.syncany/cache/multichunk-58d26c05a13bf1309a8727ecb11d82e712cec988 -> Temp. remote file: RemoteFile[name=temp-QWxIX-multichunk-58d26c05a13bf1309a8727ecb11d82e712cec988], final location: RemoteFile[name=multichunk-58d26c05a13bf1309a8727ecb11d82e712cec988]
29-3-20 17:52:19.133 | UpOperation     | Test worker    | INFO : Uploading database: DatabaseVersion [header=A/(A2)/T=1585504339132, chunks=1, multiChunks=1, fileContents=1, fileHistories=1]
29-3-20 17:52:19.133 | UpOperation     | Test worker    | INFO : Saving local delta database, version A/(A2)/T=1585504339132 to file /tmp/syncanytest/syncany-200329175011381-90083-client-A/.syncany/cache/database-A-0000000002 ... 
29-3-20 17:52:19.133 | UpOperation     | Test worker    | INFO : - Saving database to /tmp/syncanytest/syncany-200329175011381-90083-client-A/.syncany/cache/database-A-0000000002 ...
29-3-20 17:52:19.133 | UpOperation     | Test worker    | INFO : - Uploading local delta database file ...
29-3-20 17:52:19.133 | UpOperation     | Test worker    | INFO : - Uploading /tmp/syncanytest/syncany-200329175011381-90083-client-A/.syncany/cache/database-A-0000000002 to RemoteFile[name=database-A-0000000002] ...
29-3-20 17:52:19.133 | RemoteTransacti | Test worker    | INFO : - Adding file to TX for UPLOAD: /tmp/syncanytest/syncany-200329175011381-90083-client-A/.syncany/cache/database-A-0000000002 -> Temp. remote file: RemoteFile[name=temp-oksQi-database-A-0000000002], final location: RemoteFile[name=database-A-0000000002]
29-3-20 17:52:19.134 | UpOperation     | Test worker    | INFO : Adding shutdown hook (to allow resuming the upload) ...
29-3-20 17:52:19.134 | RemoteTransacti | Test worker    | INFO : Starting TX.commit() ...
29-3-20 17:52:19.135 | RemoteTransacti | Test worker    | INFO : Wrote transaction manifest to temporary file: /tmp/syncanytest/syncany-200329175011381-90083-client-A/.syncany/cache/temp-transaction-2092096788029140986.tmp
29-3-20 17:52:19.135 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event UpUploadFileSyncExternalEvent (org.syncany.operations.daemon.messages.UpUploadFileSyncExternalEvent@18f2ea18) ...
29-3-20 17:52:19.135 | RemoteTransacti | Test worker    | INFO : - Uploading remote transaction file RemoteFile[name=transaction-4d952a0c] ...
29-3-20 17:52:19.136 | RemoteTransacti | Test worker    | INFO : - Starting to upload data in commit.
29-3-20 17:52:19.136 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event UpUploadFileInTransactionSyncExternalEvent (org.syncany.operations.daemon.messages.UpUploadFileInTransactionSyncExternalEvent@4750c905) ...
29-3-20 17:52:19.136 | RemoteTransacti | Test worker    | INFO : - Uploading /tmp/syncanytest/syncany-200329175011381-90083-client-A/.syncany/cache/multichunk-58d26c05a13bf1309a8727ecb11d82e712cec988 to temp. file RemoteFile[name=temp-QWxIX-multichunk-58d26c05a13bf1309a8727ecb11d82e712cec988] ...
29-3-20 17:52:19.137 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event UpUploadFileInTransactionSyncExternalEvent (org.syncany.operations.daemon.messages.UpUploadFileInTransactionSyncExternalEvent@11075a5a) ...
29-3-20 17:52:19.137 | RemoteTransacti | Test worker    | INFO : - Uploading /tmp/syncanytest/syncany-200329175011381-90083-client-A/.syncany/cache/database-A-0000000002 to temp. file RemoteFile[name=temp-oksQi-database-A-0000000002] ...
29-3-20 17:52:19.138 | RemoteTransacti | Test worker    | INFO : - Moving temp. file RemoteFile[name=temp-QWxIX-multichunk-58d26c05a13bf1309a8727ecb11d82e712cec988] to final location RemoteFile[name=multichunk-58d26c05a13bf1309a8727ecb11d82e712cec988] ...
29-3-20 17:52:19.138 | RemoteTransacti | Test worker    | INFO : - Moving temp. file RemoteFile[name=temp-oksQi-database-A-0000000002] to final location RemoteFile[name=database-A-0000000002] ...
29-3-20 17:52:19.138 | RemoteTransacti | Test worker    | INFO : - Deleting remote transaction file RemoteFile[name=transaction-4d952a0c] ...
29-3-20 17:52:19.138 | RemoteTransacti | Test worker    | INFO : END of TX.commmit(): Succesfully committed transaction.
29-3-20 17:52:19.138 | RemoteTransacti | Test worker    | INFO : END of TX.delTemp(): Sucessfully deleted final files.
29-3-20 17:52:19.138 | UpOperation     | Test worker    | INFO : Persisting local SQL database (new database version A/(A2)/T=1585504339132) ...
29-3-20 17:52:19.139 | UpOperation     | Test worker    | INFO : Removing DIRTY database versions from database ...
29-3-20 17:52:19.154 | UpOperation     | Test worker    | INFO : Adding database version to result changes:DatabaseVersion [header=A/(A2)/T=1585504339132, chunks=1, multiChunks=1, fileContents=1, fileHistories=1]
29-3-20 17:52:19.154 | UpOperation     | Test worker    | INFO : Committing local database.
29-3-20 17:52:19.154 | UpOperation     | Test worker    | FINE : Waiting for new database version.
29-3-20 17:52:19.154 | UpOperation     | Test worker    | FINE : Took new database version: DatabaseVersion [header=UnknownMachine/()/T=1585504339128, chunks=0, multiChunks=0, fileContents=0, fileHistories=0]
29-3-20 17:52:19.154 | UpOperation     | Test worker    | INFO : Sync up done.
29-3-20 17:52:19.160 | ActionFileHandl | Test worker    | INFO : Finishing action for RemoteFile[name=action-up-A-1585504339019] ...
29-3-20 17:52:19.166 | ActionFileHandl | Test worker    | INFO : Deleting action file: RemoteFile[name=action-up-A-1585504339019]
29-3-20 17:52:19.166 | AbstractTransfe | Test worker    | INFO : Cleaning own old action files ...
29-3-20 17:52:19.166 | Cache           | Test worker    | INFO : Cache size okay (4 KB), no need to clean (keep size is 500 MB)
29-3-20 17:52:19.166 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event UpEndSyncExternalEvent (org.syncany.operations.daemon.messages.UpEndSyncExternalEvent@439bd038) ...
29-3-20 17:52:19.175 | org.syncany.plu | Test worker    | FINE : Validating required fields
29-3-20 17:52:19.176 | LocalTransferMa | Test worker    | INFO : testTargetExists: Target exists.
29-3-20 17:52:19.188 | org.syncany.plu | Test worker    | FINE : Validating required fields
29-3-20 17:52:19.188 | TransferManager | Test worker    | INFO : Building LocalTransferManager from config 'syncany-200329175011381-18056-client-A' ...
29-3-20 17:52:19.188 | TransferManager | Test worker    | INFO : - With feature ReadAfterWriteConsistent
29-3-20 17:52:19.188 | TransferManager | Test worker    | INFO : - With feature Retriable
29-3-20 17:52:19.188 | TransferManager | Test worker    | FINE : - Checking if selected features supported ...
29-3-20 17:52:19.188 | TransferManager | Test worker    | FINE : - Checking for duplicate features ...
29-3-20 17:52:19.188 | TransferManager | Test worker    | FINE : - Checking required feature TransactionAware in LocalTransferManager ...
29-3-20 17:52:19.188 | TransferManager | Test worker    | FINE : - Checking required feature Retriable in LocalTransferManager ...
29-3-20 17:52:19.188 | TransferManager | Test worker    | INFO : - SKIPPING unsupported optional feature ReadAfterWriteConsistent
29-3-20 17:52:19.188 | TransferManager | Test worker    | FINE : - Wrapping TransferManager LocalTransferManager in RetriableFeatureTransferManager
29-3-20 17:52:19.188 | org.syncany.plu | Test worker    | FINE : Validating required fields
29-3-20 17:52:19.188 | TransferManager | Test worker    | INFO : Building LocalTransferManager from config 'syncany-200329175011381-18056-client-A' ...
29-3-20 17:52:19.188 | TransferManager | Test worker    | INFO : - With feature ReadAfterWriteConsistent
29-3-20 17:52:19.188 | TransferManager | Test worker    | INFO : - With feature Retriable
29-3-20 17:52:19.188 | TransferManager | Test worker    | INFO : - With feature PathAware
29-3-20 17:52:19.189 | TransferManager | Test worker    | INFO : - With feature TransactionAware
29-3-20 17:52:19.189 | TransferManager | Test worker    | FINE : - Checking if selected features supported ...
29-3-20 17:52:19.189 | TransferManager | Test worker    | FINE : - Checking for duplicate features ...
29-3-20 17:52:19.189 | TransferManager | Test worker    | FINE : - Checking required feature TransactionAware in LocalTransferManager ...
29-3-20 17:52:19.189 | TransferManager | Test worker    | FINE : - Checking required feature Retriable in LocalTransferManager ...
29-3-20 17:52:19.189 | TransferManager | Test worker    | INFO : - SKIPPING unsupported optional feature ReadAfterWriteConsistent
29-3-20 17:52:19.189 | TransferManager | Test worker    | FINE : - Wrapping TransferManager LocalTransferManager in RetriableFeatureTransferManager
29-3-20 17:52:19.189 | TransferManager | Test worker    | INFO : - SKIPPING unsupported optional feature PathAware
29-3-20 17:52:19.189 | TransferManager | Test worker    | FINE : - Wrapping TransferManager RetriableFeatureTransferManager in TransactionAwareFeatureTransferManager
29-3-20 17:52:19.227 | hsqldb.db.HSQLD | Test worker    | INFO : Checkpoint start
29-3-20 17:52:19.232 | hsqldb.db.HSQLD | Test worker    | INFO : checkpointClose start
29-3-20 17:52:19.245 | hsqldb.db.HSQLD | Test worker    | FINE : log close size: 0
29-3-20 17:52:19.249 | hsqldb.db.HSQLD | Test worker    | INFO : checkpointClose end
29-3-20 17:52:19.249 | hsqldb.db.HSQLD | Test worker    | INFO : Checkpoint end - txts: 1
29-3-20 17:52:19.259 | DatabaseConnect | Test worker    | INFO : Found 0 tables.
29-3-20 17:52:19.259 | DatabaseConnect | Test worker    | INFO : Database has no tables. Creating tables from script.create.all.sql
29-3-20 17:52:19.259 | SqlRunner       | Test worker    | INFO : SQL (comment): -- Tables
29-3-20 17:52:19.260 | 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:52:19.265 | 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:52:19.266 | 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:52:19.268 | 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:52:19.269 | 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:52:19.271 | 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:52:19.272 | 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:52:19.274 | 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:52:19.275 | 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:52:19.277 | 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:52:19.281 | 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:52:19.282 | 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:52:19.283 | SqlRunner       | Test worker    | INFO : SQL (comment): -- Non-primary indices
29-3-20 17:52:19.283 | SqlRunner       | Test worker    | INFO : SQL:   CREATE INDEX idx_databaseversion_status ON databaseversion (status); 
29-3-20 17:52:19.284 | hsqldb.db.HSQLD | Test worker    | INFO : dataFileCache open start
29-3-20 17:52:19.286 | hsqldb.db.HSQLD | Test worker    | INFO : dataFileCache open end
29-3-20 17:52:19.287 | SqlRunner       | Test worker    | INFO : SQL: CREATE INDEX idx_databaseversion_vectorclock_serialized ON databaseversion (vectorclock_serialized); 
29-3-20 17:52:19.288 | SqlRunner       | Test worker    | INFO : SQL: CREATE INDEX idx_fileversion_path ON fileversion (path); 
29-3-20 17:52:19.289 | SqlRunner       | Test worker    | INFO : SQL: CREATE INDEX idx_fileversion_status ON fileversion (status); 
29-3-20 17:52:19.291 | SqlRunner       | Test worker    | INFO : SQL: CREATE INDEX idx_fileversion_filecontent_checksum ON fileversion (filecontent_checksum); 
29-3-20 17:52:19.293 | SqlRunner       | Test worker    | INFO : SQL (comment): -- Views
29-3-20 17:52:19.293 | 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:52:19.294 | 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:52:19.296 | 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:52:19.297 | 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:52:19.299 | SqlRunner       | Test worker    | INFO : SQL (comment): -- Full Views
29-3-20 17:52:19.299 | 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:52:19.301 | 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:52:19.302 | SqlRunner       | Test worker    | INFO : SQL (comment): -- Functions
29-3-20 17:52:19.302 | SqlRunner       | Test worker    | INFO : SQL (new delimiter): end;
29-3-20 17:52:19.302 | 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:52:19.304 | UpOperation     | Test worker    | INFO : 
29-3-20 17:52:19.304 | UpOperation     | Test worker    | INFO : Running 'Sync up' at client A ...
29-3-20 17:52:19.304 | UpOperation     | Test worker    | INFO : --------------------------------------------
29-3-20 17:52:19.304 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event UpStartSyncExternalEvent (org.syncany.operations.daemon.messages.UpStartSyncExternalEvent@57dd8050) ...
29-3-20 17:52:19.305 | DatabaseConnect | Test worker    | INFO : Found 12 tables.
29-3-20 17:52:19.305 | StatusOperation | Test worker    | INFO : 
29-3-20 17:52:19.305 | StatusOperation | Test worker    | INFO : Running 'Status' at client A ...
29-3-20 17:52:19.305 | StatusOperation | Test worker    | INFO : --------------------------------------------
29-3-20 17:52:19.305 | StatusOperation | Test worker    | INFO : Force checksum ENABLED.
29-3-20 17:52:19.305 | StatusOperation | Test worker    | INFO : Querying current file tree from database ...
29-3-20 17:52:19.305 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event StatusStartSyncExternalEvent (org.syncany.operations.daemon.messages.StatusStartSyncExternalEvent@565fb25d) ...
29-3-20 17:52:19.306 | StatusOperation | Test worker    | INFO : Analyzing local folder /tmp/syncanytest/syncany-200329175011381-18056-client-A ...
29-3-20 17:52:19.306 | StatusOperation | Test worker    | FINE : - New file: file1.txt
29-3-20 17:52:19.306 | StatusOperation | Test worker    | FINE : - Ignoring file (syncany app-related): .syncany
29-3-20 17:52:19.306 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event StatusEndSyncExternalEvent (org.syncany.operations.daemon.messages.StatusEndSyncExternalEvent@18099eb2) ...
29-3-20 17:52:19.306 | AbstractTransfe | Test worker    | INFO : Looking for other running remote operations ...
29-3-20 17:52:19.307 | DatabaseConnect | Test worker    | INFO : Found 12 tables.
29-3-20 17:52:19.307 | LsRemoteOperati | Test worker    | INFO : 
29-3-20 17:52:19.307 | LsRemoteOperati | Test worker    | INFO : Running 'Remote Status' at client A ...
29-3-20 17:52:19.307 | LsRemoteOperati | Test worker    | INFO : --------------------------------------------
29-3-20 17:52:19.307 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event LsRemoteStartSyncExternalEvent (org.syncany.operations.daemon.messages.LsRemoteStartSyncExternalEvent@53760995) ...
29-3-20 17:52:19.307 | LsRemoteOperati | Test worker    | INFO : Retrieving remote database list.
29-3-20 17:52:19.307 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event LsRemoteEndSyncExternalEvent (org.syncany.operations.daemon.messages.LsRemoteEndSyncExternalEvent@1148a373) ...
29-3-20 17:52:19.307 | UpOperation     | Test worker    | INFO : No remote changes, ready to upload.
29-3-20 17:52:19.307 | ActionFileHandl | Test worker    | INFO : Starting action for RemoteFile[name=action-up-A-1585504339189] ...
29-3-20 17:52:19.307 | ActionFileHandl | Test worker    | INFO : Uploading action file: RemoteFile[name=action-up-A-1585504339189]
29-3-20 17:52:19.307 | ActionFileHandl | Test worker    | INFO : Scheduling action renewal task for every 2 minutes, for RemoteFile[name=action-up-A-1585504339189] ...
29-3-20 17:52:19.308 | DatabaseConnect | Test worker    | INFO : Found 12 tables.
29-3-20 17:52:19.314 | TransactionAwar | Test worker    | INFO : Done rolling back previous transactions.
29-3-20 17:52:19.314 | AsyncIndexer    | AsyncI/syncany | INFO : Starting Indexing.
29-3-20 17:52:19.314 | InternalEventBu | AsyncI/syncany | INFO : Event bus 'LocalEventBus': Posting event UpIndexStartSyncExternalEvent (org.syncany.operations.daemon.messages.UpIndexStartSyncExternalEvent@215f49a5) ...
29-3-20 17:52:19.314 | Indexer         | AsyncI/syncany | FINE : - Looking for deleted files ...
29-3-20 17:52:19.314 | Indexer         | AsyncI/syncany | FINE : - +File /tmp/syncanytest/syncany-200329175011381-18056-client-A/file1.txt
29-3-20 17:52:19.315 | Indexer         | AsyncI/syncany | FINE : - +FileContent: /tmp/syncanytest/syncany-200329175011381-18056-client-A/file1.txt
29-3-20 17:52:19.326 | Indexer         | AsyncI/syncany | FINE : - Chunk new: 5ce31f56a7e3be533d389b4f6d6a0c38670885a5
29-3-20 17:52:19.326 | Indexer         | AsyncI/syncany | FINE : - +MultiChunk b1c7cfd5fcfaef51272a7c99547b11b2d76b67a5
29-3-20 17:52:19.326 | Indexer         | AsyncI/syncany | FINE : - Chunk > MultiChunk: 5ce31f56a7e3be533d389b4f6d6a0c38670885a5 > b1c7cfd5fcfaef51272a7c99547b11b2d76b67a5
29-3-20 17:52:19.326 | Indexer         | AsyncI/syncany | FINE : - Chunk > FileContent: 5ce31f56a7e3be533d389b4f6d6a0c38670885a5 > /tmp/syncanytest/syncany-200329175011381-18056-client-A/file1.txt
29-3-20 17:52:19.326 | Indexer         | AsyncI/syncany | FINE : - /File: file1.txt (checksum 5ce31f56a7e3be533d389b4f6d6a0c38670885a5)
29-3-20 17:52:19.327 | Indexer         | AsyncI/syncany | FINE :    * No old file history found, starting new history (path: file1.txt, checksum: 5ce31f56a7e3be533d389b4f6d6a0c38670885a5)
29-3-20 17:52:19.327 | FileVersionComp | AsyncI/syncany | INFO :      - [DELETED]: Local file DIFFERS from file version, actual file is NULL, for file file1.txt
29-3-20 17:52:19.327 | Indexer         | AsyncI/syncany | INFO :    * Added file version:    FileVersion [version=1, path=file1.txt, type=FILE, status=NEW, size=1024, lastModified=Sun Mar 29 17:52:19 UTC 2020, linkTarget=null, checksum=5ce31f56a7e3be533d389b4f6d6a0c38670885a5, updated=Sun Mar 29 17:52:19 UTC 2020, posixPermissions=rw-rw-r--, dosAttributes=--a-]
29-3-20 17:52:19.327 | Indexer         | AsyncI/syncany | INFO :      based on file version: null
29-3-20 17:52:19.327 | InternalEventBu | AsyncI/syncany | INFO : Event bus 'LocalEventBus': Posting event UpIndexChangesDetectedSyncExternalEvent (org.syncany.operations.daemon.messages.UpIndexChangesDetectedSyncExternalEvent@5458d11e) ...
29-3-20 17:52:19.327 | Indexer         | AsyncI/syncany | FINE : - /MultiChunk b1c7cfd5fcfaef51272a7c99547b11b2d76b67a5
29-3-20 17:52:19.327 | InternalEventBu | AsyncI/syncany | INFO : Event bus 'LocalEventBus': Posting event UpIndexEndSyncExternalEvent (org.syncany.operations.daemon.messages.UpIndexEndSyncExternalEvent@1f8ccd15) ...
29-3-20 17:52:19.327 | Indexer         | AsyncI/syncany | FINE : Processed new database version: DatabaseVersion [header=UnknownMachine/()/T=1585504339314, chunks=1, multiChunks=1, fileContents=1, fileHistories=1]
29-3-20 17:52:19.327 | InternalEventBu | AsyncI/syncany | INFO : Event bus 'LocalEventBus': Posting event UpIndexMidSyncExternalEvent (org.syncany.operations.daemon.messages.UpIndexMidSyncExternalEvent@524c9046) ...
29-3-20 17:52:19.327 | UpOperation     | Test worker    | INFO : No DIRTY data found in database (no dirty databases); Nothing to do here.
29-3-20 17:52:19.328 | UpOperation     | Test worker    | INFO : Last vector clock was: ()
29-3-20 17:52:19.328 | UpOperation     | Test worker    | INFO : Uploading new multichunks ...
29-3-20 17:52:19.328 | UpOperation     | Test worker    | INFO : - Uploading multichunk b1c7cfd5fcfaef51272a7c99547b11b2d76b67a5 from /tmp/syncanytest/syncany-200329175011381-18056-client-A/.syncany/cache/multichunk-b1c7cfd5fcfaef51272a7c99547b11b2d76b67a5 to RemoteFile[name=multichunk-b1c7cfd5fcfaef51272a7c99547b11b2d76b67a5] ...
29-3-20 17:52:19.328 | RemoteTransacti | Test worker    | INFO : - Adding file to TX for UPLOAD: /tmp/syncanytest/syncany-200329175011381-18056-client-A/.syncany/cache/multichunk-b1c7cfd5fcfaef51272a7c99547b11b2d76b67a5 -> Temp. remote file: RemoteFile[name=temp-WFIgz-multichunk-b1c7cfd5fcfaef51272a7c99547b11b2d76b67a5], final location: RemoteFile[name=multichunk-b1c7cfd5fcfaef51272a7c99547b11b2d76b67a5]
29-3-20 17:52:19.328 | UpOperation     | Test worker    | INFO : Uploading database: DatabaseVersion [header=A/(A1)/T=1585504339328, chunks=1, multiChunks=1, fileContents=1, fileHistories=1]
29-3-20 17:52:19.328 | UpOperation     | Test worker    | INFO : Saving local delta database, version A/(A1)/T=1585504339328 to file /tmp/syncanytest/syncany-200329175011381-18056-client-A/.syncany/cache/database-A-0000000001 ... 
29-3-20 17:52:19.328 | UpOperation     | Test worker    | INFO : - Saving database to /tmp/syncanytest/syncany-200329175011381-18056-client-A/.syncany/cache/database-A-0000000001 ...
29-3-20 17:52:19.329 | UpOperation     | Test worker    | INFO : - Uploading local delta database file ...
29-3-20 17:52:19.329 | UpOperation     | Test worker    | INFO : - Uploading /tmp/syncanytest/syncany-200329175011381-18056-client-A/.syncany/cache/database-A-0000000001 to RemoteFile[name=database-A-0000000001] ...
29-3-20 17:52:19.329 | RemoteTransacti | Test worker    | INFO : - Adding file to TX for UPLOAD: /tmp/syncanytest/syncany-200329175011381-18056-client-A/.syncany/cache/database-A-0000000001 -> Temp. remote file: RemoteFile[name=temp-VupXq-database-A-0000000001], final location: RemoteFile[name=database-A-0000000001]
29-3-20 17:52:19.329 | UpOperation     | Test worker    | INFO : Adding shutdown hook (to allow resuming the upload) ...
29-3-20 17:52:19.329 | RemoteTransacti | Test worker    | INFO : Starting TX.commit() ...
29-3-20 17:52:19.330 | RemoteTransacti | Test worker    | INFO : Wrote transaction manifest to temporary file: /tmp/syncanytest/syncany-200329175011381-18056-client-A/.syncany/cache/temp-transaction-10470499386297622617.tmp
29-3-20 17:52:19.331 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event UpUploadFileSyncExternalEvent (org.syncany.operations.daemon.messages.UpUploadFileSyncExternalEvent@4b1e4ef3) ...
29-3-20 17:52:19.331 | RemoteTransacti | Test worker    | INFO : - Uploading remote transaction file RemoteFile[name=transaction-d709d70] ...
29-3-20 17:52:19.331 | RemoteTransacti | Test worker    | INFO : - Starting to upload data in commit.
29-3-20 17:52:19.331 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event UpUploadFileInTransactionSyncExternalEvent (org.syncany.operations.daemon.messages.UpUploadFileInTransactionSyncExternalEvent@35df5a67) ...
29-3-20 17:52:19.331 | RemoteTransacti | Test worker    | INFO : - Uploading /tmp/syncanytest/syncany-200329175011381-18056-client-A/.syncany/cache/multichunk-b1c7cfd5fcfaef51272a7c99547b11b2d76b67a5 to temp. file RemoteFile[name=temp-WFIgz-multichunk-b1c7cfd5fcfaef51272a7c99547b11b2d76b67a5] ...
29-3-20 17:52:19.332 | AsyncIndexer    | AsyncI/syncany | INFO : Stopping indexing. Signal end of stream with empty databaseversion
29-3-20 17:52:19.333 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event UpUploadFileInTransactionSyncExternalEvent (org.syncany.operations.daemon.messages.UpUploadFileInTransactionSyncExternalEvent@583e0753) ...
29-3-20 17:52:19.333 | RemoteTransacti | Test worker    | INFO : - Uploading /tmp/syncanytest/syncany-200329175011381-18056-client-A/.syncany/cache/database-A-0000000001 to temp. file RemoteFile[name=temp-VupXq-database-A-0000000001] ...
29-3-20 17:52:19.333 | RemoteTransacti | Test worker    | INFO : - Moving temp. file RemoteFile[name=temp-WFIgz-multichunk-b1c7cfd5fcfaef51272a7c99547b11b2d76b67a5] to final location RemoteFile[name=multichunk-b1c7cfd5fcfaef51272a7c99547b11b2d76b67a5] ...
29-3-20 17:52:19.333 | RemoteTransacti | Test worker    | INFO : - Moving temp. file RemoteFile[name=temp-VupXq-database-A-0000000001] to final location RemoteFile[name=database-A-0000000001] ...
29-3-20 17:52:19.334 | RemoteTransacti | Test worker    | INFO : - Deleting remote transaction file RemoteFile[name=transaction-d709d70] ...
29-3-20 17:52:19.334 | RemoteTransacti | Test worker    | INFO : END of TX.commmit(): Succesfully committed transaction.
29-3-20 17:52:19.334 | RemoteTransacti | Test worker    | INFO : END of TX.delTemp(): Sucessfully deleted final files.
29-3-20 17:52:19.334 | UpOperation     | Test worker    | INFO : Persisting local SQL database (new database version A/(A1)/T=1585504339328) ...
29-3-20 17:52:19.335 | UpOperation     | Test worker    | INFO : Removing DIRTY database versions from database ...
29-3-20 17:52:19.346 | UpOperation     | Test worker    | INFO : Adding database version to result changes:DatabaseVersion [header=A/(A1)/T=1585504339328, chunks=1, multiChunks=1, fileContents=1, fileHistories=1]
29-3-20 17:52:19.346 | UpOperation     | Test worker    | INFO : Committing local database.
29-3-20 17:52:19.346 | UpOperation     | Test worker    | FINE : Waiting for new database version.
29-3-20 17:52:19.346 | UpOperation     | Test worker    | FINE : Took new database version: DatabaseVersion [header=UnknownMachine/()/T=1585504339332, chunks=0, multiChunks=0, fileContents=0, fileHistories=0]
29-3-20 17:52:19.346 | UpOperation     | Test worker    | INFO : Sync up done.
29-3-20 17:52:19.347 | ActionFileHandl | Test worker    | INFO : Finishing action for RemoteFile[name=action-up-A-1585504339189] ...
29-3-20 17:52:19.351 | ActionFileHandl | Test worker    | INFO : Deleting action file: RemoteFile[name=action-up-A-1585504339189]
29-3-20 17:52:19.351 | AbstractTransfe | Test worker    | INFO : Cleaning own old action files ...
29-3-20 17:52:19.351 | Cache           | Test worker    | INFO : Cache size okay (2 KB), no need to clean (keep size is 500 MB)
29-3-20 17:52:19.351 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event UpEndSyncExternalEvent (org.syncany.operations.daemon.messages.UpEndSyncExternalEvent@35d0eed0) ...
29-3-20 17:52:19.352 | org.syncany.plu | Test worker    | FINE : Validating required fields
29-3-20 17:52:19.352 | TransferManager | Test worker    | INFO : Building LocalTransferManager from config 'syncany-200329175011381-18056-client-A' ...
29-3-20 17:52:19.352 | TransferManager | Test worker    | INFO : - With feature ReadAfterWriteConsistent
29-3-20 17:52:19.352 | TransferManager | Test worker    | INFO : - With feature Retriable
29-3-20 17:52:19.352 | TransferManager | Test worker    | FINE : - Checking if selected features supported ...
29-3-20 17:52:19.352 | TransferManager | Test worker    | FINE : - Checking for duplicate features ...
29-3-20 17:52:19.352 | TransferManager | Test worker    | FINE : - Checking required feature TransactionAware in LocalTransferManager ...
29-3-20 17:52:19.352 | TransferManager | Test worker    | FINE : - Checking required feature Retriable in LocalTransferManager ...
29-3-20 17:52:19.352 | TransferManager | Test worker    | INFO : - SKIPPING unsupported optional feature ReadAfterWriteConsistent
29-3-20 17:52:19.352 | TransferManager | Test worker    | FINE : - Wrapping TransferManager LocalTransferManager in RetriableFeatureTransferManager
29-3-20 17:52:19.352 | org.syncany.plu | Test worker    | FINE : Validating required fields
29-3-20 17:52:19.352 | TransferManager | Test worker    | INFO : Building LocalTransferManager from config 'syncany-200329175011381-18056-client-A' ...
29-3-20 17:52:19.352 | TransferManager | Test worker    | INFO : - With feature ReadAfterWriteConsistent
29-3-20 17:52:19.352 | TransferManager | Test worker    | INFO : - With feature Retriable
29-3-20 17:52:19.352 | TransferManager | Test worker    | INFO : - With feature PathAware
29-3-20 17:52:19.352 | TransferManager | Test worker    | INFO : - With feature TransactionAware
29-3-20 17:52:19.352 | TransferManager | Test worker    | FINE : - Checking if selected features supported ...
29-3-20 17:52:19.352 | TransferManager | Test worker    | FINE : - Checking for duplicate features ...
29-3-20 17:52:19.352 | TransferManager | Test worker    | FINE : - Checking required feature TransactionAware in LocalTransferManager ...
29-3-20 17:52:19.352 | TransferManager | Test worker    | FINE : - Checking required feature Retriable in LocalTransferManager ...
29-3-20 17:52:19.352 | TransferManager | Test worker    | INFO : - SKIPPING unsupported optional feature ReadAfterWriteConsistent
29-3-20 17:52:19.352 | TransferManager | Test worker    | FINE : - Wrapping TransferManager LocalTransferManager in RetriableFeatureTransferManager
29-3-20 17:52:19.352 | TransferManager | Test worker    | INFO : - SKIPPING unsupported optional feature PathAware
29-3-20 17:52:19.352 | TransferManager | Test worker    | FINE : - Wrapping TransferManager RetriableFeatureTransferManager in TransactionAwareFeatureTransferManager
29-3-20 17:52:19.360 | DatabaseConnect | Test worker    | INFO : Found 12 tables.
29-3-20 17:52:19.360 | UpOperation     | Test worker    | INFO : 
29-3-20 17:52:19.361 | UpOperation     | Test worker    | INFO : Running 'Sync up' at client A ...
29-3-20 17:52:19.361 | UpOperation     | Test worker    | INFO : --------------------------------------------
29-3-20 17:52:19.361 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event UpStartSyncExternalEvent (org.syncany.operations.daemon.messages.UpStartSyncExternalEvent@4354dbe4) ...
29-3-20 17:52:19.361 | DatabaseConnect | Test worker    | INFO : Found 12 tables.
29-3-20 17:52:19.361 | StatusOperation | Test worker    | INFO : 
29-3-20 17:52:19.361 | StatusOperation | Test worker    | INFO : Running 'Status' at client A ...
29-3-20 17:52:19.361 | StatusOperation | Test worker    | INFO : --------------------------------------------
29-3-20 17:52:19.361 | StatusOperation | Test worker    | INFO : Force checksum ENABLED.
29-3-20 17:52:19.361 | StatusOperation | Test worker    | INFO : Querying current file tree from database ...
29-3-20 17:52:19.361 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event StatusStartSyncExternalEvent (org.syncany.operations.daemon.messages.StatusStartSyncExternalEvent@41f027c8) ...
29-3-20 17:52:19.362 | StatusOperation | Test worker    | INFO : Analyzing local folder /tmp/syncanytest/syncany-200329175011381-18056-client-A ...
29-3-20 17:52:19.362 | StatusOperation | Test worker    | FINE : - New file: file2.txt
29-3-20 17:52:19.362 | StatusOperation | Test worker    | FINE : - Ignoring file (syncany app-related): .syncany
29-3-20 17:52:19.362 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event StatusEndSyncExternalEvent (org.syncany.operations.daemon.messages.StatusEndSyncExternalEvent@26ca0e22) ...
29-3-20 17:52:19.362 | AbstractTransfe | Test worker    | INFO : Looking for other running remote operations ...
29-3-20 17:52:19.363 | DatabaseConnect | Test worker    | INFO : Found 12 tables.
29-3-20 17:52:19.363 | LsRemoteOperati | Test worker    | INFO : 
29-3-20 17:52:19.363 | LsRemoteOperati | Test worker    | INFO : Running 'Remote Status' at client A ...
29-3-20 17:52:19.363 | LsRemoteOperati | Test worker    | INFO : --------------------------------------------
29-3-20 17:52:19.363 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event LsRemoteStartSyncExternalEvent (org.syncany.operations.daemon.messages.LsRemoteStartSyncExternalEvent@3b3faeb8) ...
29-3-20 17:52:19.363 | LsRemoteOperati | Test worker    | INFO : Retrieving remote database list.
29-3-20 17:52:19.363 | LsRemoteOperati | Test worker    | INFO : - Remote database database-A-0000000001 is already known (in local database). Ignoring.
29-3-20 17:52:19.363 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event LsRemoteEndSyncExternalEvent (org.syncany.operations.daemon.messages.LsRemoteEndSyncExternalEvent@984aff6) ...
29-3-20 17:52:19.363 | UpOperation     | Test worker    | INFO : No remote changes, ready to upload.
29-3-20 17:52:19.363 | ActionFileHandl | Test worker    | INFO : Starting action for RemoteFile[name=action-up-A-1585504339352] ...
29-3-20 17:52:19.363 | ActionFileHandl | Test worker    | INFO : Uploading action file: RemoteFile[name=action-up-A-1585504339352]
29-3-20 17:52:19.363 | ActionFileHandl | Test worker    | INFO : Scheduling action renewal task for every 2 minutes, for RemoteFile[name=action-up-A-1585504339352] ...
29-3-20 17:52:19.364 | TransactionAwar | Test worker    | WARN : Cannot parse line in transaction list: INVALID. Cannot resume.
29-3-20 17:52:19.364 | DatabaseConnect | Test worker    | INFO : Found 12 tables.
29-3-20 17:52:19.376 | TransactionAwar | Test worker    | INFO : Done rolling back previous transactions.
29-3-20 17:52:19.376 | TransactionAwar | Test worker    | WARN : Cannot parse line in transaction list: INVALID. Cannot resume.
29-3-20 17:52:19.377 | AsyncIndexer    | AsyncI/syncany | INFO : Starting Indexing.
29-3-20 17:52:19.377 | InternalEventBu | AsyncI/syncany | INFO : Event bus 'LocalEventBus': Posting event UpIndexStartSyncExternalEvent (org.syncany.operations.daemon.messages.UpIndexStartSyncExternalEvent@64ccfd74) ...
29-3-20 17:52:19.377 | Indexer         | AsyncI/syncany | FINE : - Looking for deleted files ...
29-3-20 17:52:19.377 | Indexer         | AsyncI/syncany | FINE : - +File /tmp/syncanytest/syncany-200329175011381-18056-client-A/file2.txt
29-3-20 17:52:19.377 | Indexer         | AsyncI/syncany | FINE : - +FileContent: /tmp/syncanytest/syncany-200329175011381-18056-client-A/file2.txt
29-3-20 17:52:19.377 | Indexer         | AsyncI/syncany | FINE : - Chunk new: 9c5e00e2df9c3b107386be991fb6206708553be3
29-3-20 17:52:19.377 | Indexer         | AsyncI/syncany | FINE : - +MultiChunk 547546df2b1023b9f57e365a1c5f4aca5b05e49c
29-3-20 17:52:19.378 | Indexer         | AsyncI/syncany | FINE : - Chunk > MultiChunk: 9c5e00e2df9c3b107386be991fb6206708553be3 > 547546df2b1023b9f57e365a1c5f4aca5b05e49c
29-3-20 17:52:19.378 | Indexer         | AsyncI/syncany | FINE : - Chunk > FileContent: 9c5e00e2df9c3b107386be991fb6206708553be3 > /tmp/syncanytest/syncany-200329175011381-18056-client-A/file2.txt
29-3-20 17:52:19.378 | Indexer         | AsyncI/syncany | FINE : - /File: file2.txt (checksum 9c5e00e2df9c3b107386be991fb6206708553be3)
29-3-20 17:52:19.388 | Indexer         | AsyncI/syncany | FINE :    * No old file history found, starting new history (path: file2.txt, checksum: 9c5e00e2df9c3b107386be991fb6206708553be3)
29-3-20 17:52:19.388 | FileVersionComp | AsyncI/syncany | INFO :      - [DELETED]: Local file DIFFERS from file version, actual file is NULL, for file file2.txt
29-3-20 17:52:19.388 | Indexer         | AsyncI/syncany | INFO :    * Added file version:    FileVersion [version=1, path=file2.txt, type=FILE, status=NEW, size=1024, lastModified=Sun Mar 29 17:52:19 UTC 2020, linkTarget=null, checksum=9c5e00e2df9c3b107386be991fb6206708553be3, updated=Sun Mar 29 17:52:19 UTC 2020, posixPermissions=rw-rw-r--, dosAttributes=--a-]
29-3-20 17:52:19.388 | Indexer         | AsyncI/syncany | INFO :      based on file version: null
29-3-20 17:52:19.388 | InternalEventBu | AsyncI/syncany | INFO : Event bus 'LocalEventBus': Posting event UpIndexChangesDetectedSyncExternalEvent (org.syncany.operations.daemon.messages.UpIndexChangesDetectedSyncExternalEvent@7da05f6) ...
29-3-20 17:52:19.389 | Indexer         | AsyncI/syncany | FINE : - /MultiChunk 547546df2b1023b9f57e365a1c5f4aca5b05e49c
29-3-20 17:52:19.389 | InternalEventBu | AsyncI/syncany | INFO : Event bus 'LocalEventBus': Posting event UpIndexEndSyncExternalEvent (org.syncany.operations.daemon.messages.UpIndexEndSyncExternalEvent@1a8526be) ...
29-3-20 17:52:19.389 | Indexer         | AsyncI/syncany | FINE : Processed new database version: DatabaseVersion [header=UnknownMachine/()/T=1585504339377, chunks=1, multiChunks=1, fileContents=1, fileHistories=1]
29-3-20 17:52:19.389 | UpOperation     | Test worker    | INFO : No DIRTY data found in database (no dirty databases); Nothing to do here.
29-3-20 17:52:19.389 | UpOperation     | Test worker    | INFO : Last vector clock was: (A1)
29-3-20 17:52:19.389 | UpOperation     | Test worker    | INFO : Uploading new multichunks ...
29-3-20 17:52:19.390 | UpOperation     | Test worker    | INFO : - Uploading multichunk 547546df2b1023b9f57e365a1c5f4aca5b05e49c from /tmp/syncanytest/syncany-200329175011381-18056-client-A/.syncany/cache/multichunk-547546df2b1023b9f57e365a1c5f4aca5b05e49c to RemoteFile[name=multichunk-547546df2b1023b9f57e365a1c5f4aca5b05e49c] ...
29-3-20 17:52:19.390 | RemoteTransacti | Test worker    | INFO : - Adding file to TX for UPLOAD: /tmp/syncanytest/syncany-200329175011381-18056-client-A/.syncany/cache/multichunk-547546df2b1023b9f57e365a1c5f4aca5b05e49c -> Temp. remote file: RemoteFile[name=temp-SarlL-multichunk-547546df2b1023b9f57e365a1c5f4aca5b05e49c], final location: RemoteFile[name=multichunk-547546df2b1023b9f57e365a1c5f4aca5b05e49c]
29-3-20 17:52:19.390 | UpOperation     | Test worker    | INFO : Uploading database: DatabaseVersion [header=A/(A2)/T=1585504339389, chunks=1, multiChunks=1, fileContents=1, fileHistories=1]
29-3-20 17:52:19.390 | UpOperation     | Test worker    | INFO : Saving local delta database, version A/(A2)/T=1585504339389 to file /tmp/syncanytest/syncany-200329175011381-18056-client-A/.syncany/cache/database-A-0000000002 ... 
29-3-20 17:52:19.390 | UpOperation     | Test worker    | INFO : - Saving database to /tmp/syncanytest/syncany-200329175011381-18056-client-A/.syncany/cache/database-A-0000000002 ...
29-3-20 17:52:19.390 | UpOperation     | Test worker    | INFO : - Uploading local delta database file ...
29-3-20 17:52:19.390 | UpOperation     | Test worker    | INFO : - Uploading /tmp/syncanytest/syncany-200329175011381-18056-client-A/.syncany/cache/database-A-0000000002 to RemoteFile[name=database-A-0000000002] ...
29-3-20 17:52:19.390 | RemoteTransacti | Test worker    | INFO : - Adding file to TX for UPLOAD: /tmp/syncanytest/syncany-200329175011381-18056-client-A/.syncany/cache/database-A-0000000002 -> Temp. remote file: RemoteFile[name=temp-mAZtr-database-A-0000000002], final location: RemoteFile[name=database-A-0000000002]
29-3-20 17:52:19.391 | UpOperation     | Test worker    | INFO : Adding shutdown hook (to allow resuming the upload) ...
29-3-20 17:52:19.391 | RemoteTransacti | Test worker    | INFO : Starting TX.commit() ...
29-3-20 17:52:19.392 | RemoteTransacti | Test worker    | INFO : Wrote transaction manifest to temporary file: /tmp/syncanytest/syncany-200329175011381-18056-client-A/.syncany/cache/temp-transaction-7384250614398045317.tmp
29-3-20 17:52:19.392 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event UpUploadFileSyncExternalEvent (org.syncany.operations.daemon.messages.UpUploadFileSyncExternalEvent@3bfe9182) ...
29-3-20 17:52:19.392 | RemoteTransacti | Test worker    | INFO : - Uploading remote transaction file RemoteFile[name=transaction-5cd32fd6] ...
29-3-20 17:52:19.392 | RemoteTransacti | Test worker    | INFO : - Starting to upload data in commit.
29-3-20 17:52:19.392 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event UpUploadFileInTransactionSyncExternalEvent (org.syncany.operations.daemon.messages.UpUploadFileInTransactionSyncExternalEvent@59b70911) ...
29-3-20 17:52:19.392 | RemoteTransacti | Test worker    | INFO : - Uploading /tmp/syncanytest/syncany-200329175011381-18056-client-A/.syncany/cache/multichunk-547546df2b1023b9f57e365a1c5f4aca5b05e49c to temp. file RemoteFile[name=temp-SarlL-multichunk-547546df2b1023b9f57e365a1c5f4aca5b05e49c] ...
29-3-20 17:52:19.392 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event UpUploadFileInTransactionSyncExternalEvent (org.syncany.operations.daemon.messages.UpUploadFileInTransactionSyncExternalEvent@e9ec1b4) ...
29-3-20 17:52:19.393 | RemoteTransacti | Test worker    | INFO : - Uploading /tmp/syncanytest/syncany-200329175011381-18056-client-A/.syncany/cache/database-A-0000000002 to temp. file RemoteFile[name=temp-mAZtr-database-A-0000000002] ...
29-3-20 17:52:19.393 | RemoteTransacti | Test worker    | INFO : - Moving temp. file RemoteFile[name=temp-SarlL-multichunk-547546df2b1023b9f57e365a1c5f4aca5b05e49c] to final location RemoteFile[name=multichunk-547546df2b1023b9f57e365a1c5f4aca5b05e49c] ...
29-3-20 17:52:19.393 | RemoteTransacti | Test worker    | INFO : - Moving temp. file RemoteFile[name=temp-mAZtr-database-A-0000000002] to final location RemoteFile[name=database-A-0000000002] ...
29-3-20 17:52:19.393 | RemoteTransacti | Test worker    | INFO : - Deleting remote transaction file RemoteFile[name=transaction-5cd32fd6] ...
29-3-20 17:52:19.393 | RemoteTransacti | Test worker    | INFO : END of TX.commmit(): Succesfully committed transaction.
29-3-20 17:52:19.393 | RemoteTransacti | Test worker    | INFO : END of TX.delTemp(): Sucessfully deleted final files.
29-3-20 17:52:19.393 | UpOperation     | Test worker    | INFO : Persisting local SQL database (new database version A/(A2)/T=1585504339389) ...
29-3-20 17:52:19.395 | UpOperation     | Test worker    | INFO : Removing DIRTY database versions from database ...
29-3-20 17:52:19.396 | InternalEventBu | AsyncI/syncany | INFO : Event bus 'LocalEventBus': Posting event UpIndexMidSyncExternalEvent (org.syncany.operations.daemon.messages.UpIndexMidSyncExternalEvent@6f8d95f2) ...
29-3-20 17:52:19.398 | AsyncIndexer    | AsyncI/syncany | INFO : Stopping indexing. Signal end of stream with empty databaseversion
29-3-20 17:52:19.399 | UpOperation     | Test worker    | INFO : Adding database version to result changes:DatabaseVersion [header=A/(A2)/T=1585504339389, chunks=1, multiChunks=1, fileContents=1, fileHistories=1]
29-3-20 17:52:19.400 | UpOperation     | Test worker    | INFO : Committing local database.
29-3-20 17:52:19.400 | UpOperation     | Test worker    | FINE : Waiting for new database version.
29-3-20 17:52:19.400 | UpOperation     | Test worker    | FINE : Took new database version: DatabaseVersion [header=UnknownMachine/()/T=1585504339398, chunks=0, multiChunks=0, fileContents=0, fileHistories=0]
29-3-20 17:52:19.400 | UpOperation     | Test worker    | INFO : Sync up done.
29-3-20 17:52:19.401 | ActionFileHandl | Test worker    | INFO : Finishing action for RemoteFile[name=action-up-A-1585504339352] ...
29-3-20 17:52:19.410 | ActionFileHandl | Test worker    | INFO : Deleting action file: RemoteFile[name=action-up-A-1585504339352]
29-3-20 17:52:19.410 | AbstractTransfe | Test worker    | INFO : Cleaning own old action files ...
29-3-20 17:52:19.410 | Cache           | Test worker    | INFO : Cache size okay (4 KB), no need to clean (keep size is 500 MB)
29-3-20 17:52:19.410 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event UpEndSyncExternalEvent (org.syncany.operations.daemon.messages.UpEndSyncExternalEvent@5b1050ab) ...