Class org.syncany.tests.integration.operations.StatusOperationTest

4

tests

0

failures

0

ignored

7.785s

duration

100%

successful

Tests

Test Duration Result
testCreateFolderAndRunStatus 0.294s passed
testNotSoRecentFileModificationWithoutSizeOrModifiedDateChange 2.253s passed
testStatusOperation 2.796s passed
testVeryRecentFileModificationWithoutSizeOrModifiedDateChange 2.442s passed

Standard error

29-3-20 17:50:25.318 | org.syncany.plu | Test worker    | FINE : Validating required fields
29-3-20 17:50:25.321 | LocalTransferMa | Test worker    | INFO : testTargetExists: Target exists.
29-3-20 17:50:25.463 | InternalEventBu | Test worker    | INFO : Event bus: Created event bus org.syncany.config.LocalEventBus
29-3-20 17:50:25.466 | org.syncany.plu | Test worker    | FINE : Validating required fields
29-3-20 17:50:25.467 | TransferManager | Test worker    | INFO : Building LocalTransferManager from config 'syncany-200329175011381-17173-client-syncanyclient' ...
29-3-20 17:50:25.470 | TransferManager | Test worker    | INFO : - With feature ReadAfterWriteConsistent
29-3-20 17:50:25.470 | TransferManager | Test worker    | INFO : - With feature Retriable
29-3-20 17:50:25.470 | TransferManager | Test worker    | FINE : - Checking if selected features supported ...
29-3-20 17:50:25.470 | TransferManager | Test worker    | FINE : - Checking for duplicate features ...
29-3-20 17:50:25.471 | TransferManager | Test worker    | FINE : - Checking required feature TransactionAware in LocalTransferManager ...
29-3-20 17:50:25.471 | TransferManager | Test worker    | FINE : - Checking required feature Retriable in LocalTransferManager ...
29-3-20 17:50:25.471 | TransferManager | Test worker    | INFO : - SKIPPING unsupported optional feature ReadAfterWriteConsistent
29-3-20 17:50:25.473 | TransferManager | Test worker    | FINE : - Wrapping TransferManager LocalTransferManager in RetriableFeatureTransferManager
29-3-20 17:50:25.473 | org.syncany.plu | Test worker    | FINE : Validating required fields
29-3-20 17:50:25.474 | TransferManager | Test worker    | INFO : Building LocalTransferManager from config 'syncany-200329175011381-17173-client-syncanyclient' ...
29-3-20 17:50:25.475 | TransferManager | Test worker    | INFO : - With feature ReadAfterWriteConsistent
29-3-20 17:50:25.475 | TransferManager | Test worker    | INFO : - With feature Retriable
29-3-20 17:50:25.475 | TransferManager | Test worker    | INFO : - With feature PathAware
29-3-20 17:50:25.475 | TransferManager | Test worker    | INFO : - With feature TransactionAware
29-3-20 17:50:25.480 | TransferManager | Test worker    | FINE : - Checking if selected features supported ...
29-3-20 17:50:25.480 | TransferManager | Test worker    | FINE : - Checking for duplicate features ...
29-3-20 17:50:25.486 | TransferManager | Test worker    | FINE : - Checking required feature TransactionAware in LocalTransferManager ...
29-3-20 17:50:25.487 | TransferManager | Test worker    | FINE : - Checking required feature Retriable in LocalTransferManager ...
29-3-20 17:50:25.487 | TransferManager | Test worker    | INFO : - SKIPPING unsupported optional feature ReadAfterWriteConsistent
29-3-20 17:50:25.487 | TransferManager | Test worker    | FINE : - Wrapping TransferManager LocalTransferManager in RetriableFeatureTransferManager
29-3-20 17:50:25.487 | TransferManager | Test worker    | INFO : - SKIPPING unsupported optional feature PathAware
29-3-20 17:50:25.488 | TransferManager | Test worker    | FINE : - Wrapping TransferManager RetriableFeatureTransferManager in TransactionAwareFeatureTransferManager
29-3-20 17:50:25.494 | DatabaseConnect | Test worker    | INFO : Loading database driver org.hsqldb.jdbcDriver ...
29-3-20 17:50:26.885 | hsqldb.db.HSQLD | Test worker    | INFO : Checkpoint start
29-3-20 17:50:26.886 | hsqldb.db.HSQLD | Test worker    | INFO : checkpointClose start
29-3-20 17:50:26.913 | hsqldb.db.HSQLD | Test worker    | FINE : log close size: 0
29-3-20 17:50:26.925 | hsqldb.db.HSQLD | Test worker    | INFO : checkpointClose end
29-3-20 17:50:26.926 | hsqldb.db.HSQLD | Test worker    | INFO : Checkpoint end - txts: 1
29-3-20 17:50:27.038 | DatabaseConnect | Test worker    | INFO : Found 0 tables.
29-3-20 17:50:27.038 | DatabaseConnect | Test worker    | INFO : Database has no tables. Creating tables from script.create.all.sql
29-3-20 17:50:27.040 | SqlRunner       | Test worker    | INFO : SQL (comment): -- Tables
29-3-20 17:50:27.044 | 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:50:27.056 | 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:50:27.061 | 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:50:27.065 | 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:50:27.068 | 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:50:27.071 | 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:50:27.074 | 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:50:27.081 | 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:50:27.083 | 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:50:27.086 | 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:50:27.088 | 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:50:27.091 | 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:50:27.093 | SqlRunner       | Test worker    | INFO : SQL (comment): -- Non-primary indices
29-3-20 17:50:27.093 | SqlRunner       | Test worker    | INFO : SQL:   CREATE INDEX idx_databaseversion_status ON databaseversion (status); 
29-3-20 17:50:27.101 | hsqldb.db.HSQLD | Test worker    | INFO : dataFileCache open start
29-3-20 17:50:27.126 | hsqldb.db.HSQLD | Test worker    | INFO : dataFileCache open end
29-3-20 17:50:27.142 | SqlRunner       | Test worker    | INFO : SQL: CREATE INDEX idx_databaseversion_vectorclock_serialized ON databaseversion (vectorclock_serialized); 
29-3-20 17:50:27.144 | SqlRunner       | Test worker    | INFO : SQL: CREATE INDEX idx_fileversion_path ON fileversion (path); 
29-3-20 17:50:27.146 | SqlRunner       | Test worker    | INFO : SQL: CREATE INDEX idx_fileversion_status ON fileversion (status); 
29-3-20 17:50:27.147 | SqlRunner       | Test worker    | INFO : SQL: CREATE INDEX idx_fileversion_filecontent_checksum ON fileversion (filecontent_checksum); 
29-3-20 17:50:27.149 | SqlRunner       | Test worker    | INFO : SQL (comment): -- Views
29-3-20 17:50:27.149 | 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:50:27.152 | 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:50:27.155 | 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:50:27.162 | 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:50:27.172 | SqlRunner       | Test worker    | INFO : SQL (comment): -- Full Views
29-3-20 17:50:27.172 | 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:50:27.175 | 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:50:27.179 | SqlRunner       | Test worker    | INFO : SQL (comment): -- Functions
29-3-20 17:50:27.179 | SqlRunner       | Test worker    | INFO : SQL (new delimiter): end;
29-3-20 17:50:27.179 | 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:50:27.222 | UpOperation     | Test worker    | INFO : 
29-3-20 17:50:27.222 | UpOperation     | Test worker    | INFO : Running 'Sync up' at client syncanyclientyfPPRiwVTpqsQIfYYmbp ...
29-3-20 17:50:27.222 | UpOperation     | Test worker    | INFO : --------------------------------------------
29-3-20 17:50:27.224 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event UpStartSyncExternalEvent (org.syncany.operations.daemon.messages.UpStartSyncExternalEvent@b90ba3) ...
29-3-20 17:50:27.369 | DatabaseConnect | Test worker    | INFO : Found 12 tables.
29-3-20 17:50:27.369 | StatusOperation | Test worker    | INFO : 
29-3-20 17:50:27.369 | StatusOperation | Test worker    | INFO : Running 'Status' at client syncanyclientyfPPRiwVTpqsQIfYYmbp ...
29-3-20 17:50:27.369 | StatusOperation | Test worker    | INFO : --------------------------------------------
29-3-20 17:50:27.369 | StatusOperation | Test worker    | INFO : Force checksum ENABLED.
29-3-20 17:50:27.369 | StatusOperation | Test worker    | INFO : Querying current file tree from database ...
29-3-20 17:50:27.370 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event StatusStartSyncExternalEvent (org.syncany.operations.daemon.messages.StatusStartSyncExternalEvent@6f4d949e) ...
29-3-20 17:50:27.381 | StatusOperation | Test worker    | INFO : Analyzing local folder /tmp/syncanytest/syncany-200329175011381-17173-client-syncanyclient ...
29-3-20 17:50:27.388 | StatusOperation | Test worker    | FINE : - New file: rndFile-1585504225333-1919188665.dat
29-3-20 17:50:27.388 | StatusOperation | Test worker    | FINE : - Ignoring file (syncany app-related): .syncany
29-3-20 17:50:27.389 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event StatusEndSyncExternalEvent (org.syncany.operations.daemon.messages.StatusEndSyncExternalEvent@1becdeec) ...
29-3-20 17:50:27.390 | AbstractTransfe | Test worker    | INFO : Looking for other running remote operations ...
29-3-20 17:50:27.401 | DatabaseConnect | Test worker    | INFO : Found 12 tables.
29-3-20 17:50:27.401 | LsRemoteOperati | Test worker    | INFO : 
29-3-20 17:50:27.401 | LsRemoteOperati | Test worker    | INFO : Running 'Remote Status' at client syncanyclientyfPPRiwVTpqsQIfYYmbp ...
29-3-20 17:50:27.401 | LsRemoteOperati | Test worker    | INFO : --------------------------------------------
29-3-20 17:50:27.401 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event LsRemoteStartSyncExternalEvent (org.syncany.operations.daemon.messages.LsRemoteStartSyncExternalEvent@769e88e) ...
29-3-20 17:50:27.403 | LsRemoteOperati | Test worker    | INFO : Retrieving remote database list.
29-3-20 17:50:27.404 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event LsRemoteEndSyncExternalEvent (org.syncany.operations.daemon.messages.LsRemoteEndSyncExternalEvent@395f90fd) ...
29-3-20 17:50:27.405 | UpOperation     | Test worker    | INFO : No remote changes, ready to upload.
29-3-20 17:50:27.405 | ActionFileHandl | Test worker    | INFO : Starting action for RemoteFile[name=action-up-syncanyclientyfPPRiwVTpqsQIfYYmbp-1585504225491] ...
29-3-20 17:50:27.405 | ActionFileHandl | Test worker    | INFO : Uploading action file: RemoteFile[name=action-up-syncanyclientyfPPRiwVTpqsQIfYYmbp-1585504225491]
29-3-20 17:50:27.432 | ActionFileHandl | Test worker    | INFO : Scheduling action renewal task for every 2 minutes, for RemoteFile[name=action-up-syncanyclientyfPPRiwVTpqsQIfYYmbp-1585504225491] ...
29-3-20 17:50:27.449 | DatabaseConnect | Test worker    | INFO : Found 12 tables.
29-3-20 17:50:27.460 | TransactionAwar | Test worker    | INFO : Done rolling back previous transactions.
29-3-20 17:50:27.460 | AsyncIndexer    | AsyncI/syncany | INFO : Starting Indexing.
29-3-20 17:50:27.465 | InternalEventBu | AsyncI/syncany | INFO : Event bus 'LocalEventBus': Posting event UpIndexStartSyncExternalEvent (org.syncany.operations.daemon.messages.UpIndexStartSyncExternalEvent@4b766966) ...
29-3-20 17:50:27.472 | Indexer         | AsyncI/syncany | FINE : - Looking for deleted files ...
29-3-20 17:50:27.473 | Indexer         | AsyncI/syncany | FINE : - +File /tmp/syncanytest/syncany-200329175011381-17173-client-syncanyclient/rndFile-1585504225333-1919188665.dat
29-3-20 17:50:27.476 | Indexer         | AsyncI/syncany | FINE : - +FileContent: /tmp/syncanytest/syncany-200329175011381-17173-client-syncanyclient/rndFile-1585504225333-1919188665.dat
29-3-20 17:50:27.485 | Indexer         | AsyncI/syncany | FINE : - Chunk new: b4651e36b3f7ef0b6b4db572e511a225920770d5
29-3-20 17:50:27.486 | Indexer         | AsyncI/syncany | FINE : - +MultiChunk 69b99912f6fa0d5238b8e01530ab8090c5367cf8
29-3-20 17:50:27.487 | Indexer         | AsyncI/syncany | FINE : - Chunk > MultiChunk: b4651e36b3f7ef0b6b4db572e511a225920770d5 > 69b99912f6fa0d5238b8e01530ab8090c5367cf8
29-3-20 17:50:27.487 | Indexer         | AsyncI/syncany | FINE : - Chunk > FileContent: b4651e36b3f7ef0b6b4db572e511a225920770d5 > /tmp/syncanytest/syncany-200329175011381-17173-client-syncanyclient/rndFile-1585504225333-1919188665.dat
29-3-20 17:50:27.487 | Indexer         | AsyncI/syncany | FINE : - /File: rndFile-1585504225333-1919188665.dat (checksum b4651e36b3f7ef0b6b4db572e511a225920770d5)
29-3-20 17:50:27.502 | Indexer         | AsyncI/syncany | FINE :    * No old file history found, starting new history (path: rndFile-1585504225333-1919188665.dat, checksum: b4651e36b3f7ef0b6b4db572e511a225920770d5)
29-3-20 17:50:27.505 | FileVersionComp | AsyncI/syncany | INFO :      - [DELETED]: Local file DIFFERS from file version, actual file is NULL, for file rndFile-1585504225333-1919188665.dat
29-3-20 17:50:27.508 | Indexer         | AsyncI/syncany | INFO :    * Added file version:    FileVersion [version=1, path=rndFile-1585504225333-1919188665.dat, type=FILE, status=NEW, size=40, lastModified=Sun Mar 29 17:50:25 UTC 2020, linkTarget=null, checksum=b4651e36b3f7ef0b6b4db572e511a225920770d5, updated=Sun Mar 29 17:50:27 UTC 2020, posixPermissions=rw-rw-r--, dosAttributes=--a-]
29-3-20 17:50:27.508 | Indexer         | AsyncI/syncany | INFO :      based on file version: null
29-3-20 17:50:27.509 | InternalEventBu | AsyncI/syncany | INFO : Event bus 'LocalEventBus': Posting event UpIndexChangesDetectedSyncExternalEvent (org.syncany.operations.daemon.messages.UpIndexChangesDetectedSyncExternalEvent@566f89b8) ...
29-3-20 17:50:27.512 | Indexer         | AsyncI/syncany | FINE : - /MultiChunk 69b99912f6fa0d5238b8e01530ab8090c5367cf8
29-3-20 17:50:27.513 | InternalEventBu | AsyncI/syncany | INFO : Event bus 'LocalEventBus': Posting event UpIndexEndSyncExternalEvent (org.syncany.operations.daemon.messages.UpIndexEndSyncExternalEvent@7cde3533) ...
29-3-20 17:50:27.513 | Indexer         | AsyncI/syncany | FINE : Processed new database version: DatabaseVersion [header=UnknownMachine/()/T=1585504227461, chunks=1, multiChunks=1, fileContents=1, fileHistories=1]
29-3-20 17:50:27.514 | InternalEventBu | AsyncI/syncany | INFO : Event bus 'LocalEventBus': Posting event UpIndexMidSyncExternalEvent (org.syncany.operations.daemon.messages.UpIndexMidSyncExternalEvent@43625826) ...
29-3-20 17:50:27.516 | UpOperation     | Test worker    | INFO : No DIRTY data found in database (no dirty databases); Nothing to do here.
29-3-20 17:50:27.518 | UpOperation     | Test worker    | INFO : Last vector clock was: ()
29-3-20 17:50:27.527 | AsyncIndexer    | AsyncI/syncany | INFO : Stopping indexing. Signal end of stream with empty databaseversion
29-3-20 17:50:27.529 | UpOperation     | Test worker    | INFO : Uploading new multichunks ...
29-3-20 17:50:27.535 | UpOperation     | Test worker    | INFO : - Uploading multichunk 69b99912f6fa0d5238b8e01530ab8090c5367cf8 from /tmp/syncanytest/syncany-200329175011381-17173-client-syncanyclient/.syncany/cache/multichunk-69b99912f6fa0d5238b8e01530ab8090c5367cf8 to RemoteFile[name=multichunk-69b99912f6fa0d5238b8e01530ab8090c5367cf8] ...
29-3-20 17:50:27.536 | RemoteTransacti | Test worker    | INFO : - Adding file to TX for UPLOAD: /tmp/syncanytest/syncany-200329175011381-17173-client-syncanyclient/.syncany/cache/multichunk-69b99912f6fa0d5238b8e01530ab8090c5367cf8 -> Temp. remote file: RemoteFile[name=temp-iUReC-multichunk-69b99912f6fa0d5238b8e01530ab8090c5367cf8], final location: RemoteFile[name=multichunk-69b99912f6fa0d5238b8e01530ab8090c5367cf8]
29-3-20 17:50:27.538 | UpOperation     | Test worker    | INFO : Uploading database: DatabaseVersion [header=syncanyclientyfPPRiwVTpqsQIfYYmbp/(syncanyclientyfPPRiwVTpqsQIfYYmbp1)/T=1585504227524, chunks=1, multiChunks=1, fileContents=1, fileHistories=1]
29-3-20 17:50:27.546 | UpOperation     | Test worker    | INFO : Saving local delta database, version syncanyclientyfPPRiwVTpqsQIfYYmbp/(syncanyclientyfPPRiwVTpqsQIfYYmbp1)/T=1585504227524 to file /tmp/syncanytest/syncany-200329175011381-17173-client-syncanyclient/.syncany/cache/database-syncanyclientyfPPRiwVTpqsQIfYYmbp-0000000001 ... 
29-3-20 17:50:27.552 | UpOperation     | Test worker    | INFO : - Saving database to /tmp/syncanytest/syncany-200329175011381-17173-client-syncanyclient/.syncany/cache/database-syncanyclientyfPPRiwVTpqsQIfYYmbp-0000000001 ...
29-3-20 17:50:27.573 | UpOperation     | Test worker    | INFO : - Uploading local delta database file ...
29-3-20 17:50:27.573 | UpOperation     | Test worker    | INFO : - Uploading /tmp/syncanytest/syncany-200329175011381-17173-client-syncanyclient/.syncany/cache/database-syncanyclientyfPPRiwVTpqsQIfYYmbp-0000000001 to RemoteFile[name=database-syncanyclientyfPPRiwVTpqsQIfYYmbp-0000000001] ...
29-3-20 17:50:27.573 | RemoteTransacti | Test worker    | INFO : - Adding file to TX for UPLOAD: /tmp/syncanytest/syncany-200329175011381-17173-client-syncanyclient/.syncany/cache/database-syncanyclientyfPPRiwVTpqsQIfYYmbp-0000000001 -> Temp. remote file: RemoteFile[name=temp-jGmOC-database-syncanyclientyfPPRiwVTpqsQIfYYmbp-0000000001], final location: RemoteFile[name=database-syncanyclientyfPPRiwVTpqsQIfYYmbp-0000000001]
29-3-20 17:50:27.581 | UpOperation     | Test worker    | INFO : Adding shutdown hook (to allow resuming the upload) ...
29-3-20 17:50:27.581 | RemoteTransacti | Test worker    | INFO : Starting TX.commit() ...
29-3-20 17:50:27.594 | RemoteTransacti | Test worker    | INFO : Wrote transaction manifest to temporary file: /tmp/syncanytest/syncany-200329175011381-17173-client-syncanyclient/.syncany/cache/temp-transaction-6235643885824374928.tmp
29-3-20 17:50:27.596 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event UpUploadFileSyncExternalEvent (org.syncany.operations.daemon.messages.UpUploadFileSyncExternalEvent@7cf54ae8) ...
29-3-20 17:50:27.597 | RemoteTransacti | Test worker    | INFO : - Uploading remote transaction file RemoteFile[name=transaction-619b0371] ...
29-3-20 17:50:27.606 | RemoteTransacti | Test worker    | INFO : - Starting to upload data in commit.
29-3-20 17:50:27.607 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event UpUploadFileInTransactionSyncExternalEvent (org.syncany.operations.daemon.messages.UpUploadFileInTransactionSyncExternalEvent@4fbcad0e) ...
29-3-20 17:50:27.607 | RemoteTransacti | Test worker    | INFO : - Uploading /tmp/syncanytest/syncany-200329175011381-17173-client-syncanyclient/.syncany/cache/multichunk-69b99912f6fa0d5238b8e01530ab8090c5367cf8 to temp. file RemoteFile[name=temp-iUReC-multichunk-69b99912f6fa0d5238b8e01530ab8090c5367cf8] ...
29-3-20 17:50:27.608 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event UpUploadFileInTransactionSyncExternalEvent (org.syncany.operations.daemon.messages.UpUploadFileInTransactionSyncExternalEvent@5ae2abb3) ...
29-3-20 17:50:27.608 | RemoteTransacti | Test worker    | INFO : - Uploading /tmp/syncanytest/syncany-200329175011381-17173-client-syncanyclient/.syncany/cache/database-syncanyclientyfPPRiwVTpqsQIfYYmbp-0000000001 to temp. file RemoteFile[name=temp-jGmOC-database-syncanyclientyfPPRiwVTpqsQIfYYmbp-0000000001] ...
29-3-20 17:50:27.609 | RemoteTransacti | Test worker    | INFO : - Moving temp. file RemoteFile[name=temp-iUReC-multichunk-69b99912f6fa0d5238b8e01530ab8090c5367cf8] to final location RemoteFile[name=multichunk-69b99912f6fa0d5238b8e01530ab8090c5367cf8] ...
29-3-20 17:50:27.610 | RemoteTransacti | Test worker    | INFO : - Moving temp. file RemoteFile[name=temp-jGmOC-database-syncanyclientyfPPRiwVTpqsQIfYYmbp-0000000001] to final location RemoteFile[name=database-syncanyclientyfPPRiwVTpqsQIfYYmbp-0000000001] ...
29-3-20 17:50:27.610 | RemoteTransacti | Test worker    | INFO : - Deleting remote transaction file RemoteFile[name=transaction-619b0371] ...
29-3-20 17:50:27.612 | RemoteTransacti | Test worker    | INFO : END of TX.commmit(): Succesfully committed transaction.
29-3-20 17:50:27.612 | RemoteTransacti | Test worker    | INFO : END of TX.delTemp(): Sucessfully deleted final files.
29-3-20 17:50:27.612 | UpOperation     | Test worker    | INFO : Persisting local SQL database (new database version syncanyclientyfPPRiwVTpqsQIfYYmbp/(syncanyclientyfPPRiwVTpqsQIfYYmbp1)/T=1585504227524) ...
29-3-20 17:50:27.640 | UpOperation     | Test worker    | INFO : Removing DIRTY database versions from database ...
29-3-20 17:50:27.717 | UpOperation     | Test worker    | INFO : Adding database version to result changes:DatabaseVersion [header=syncanyclientyfPPRiwVTpqsQIfYYmbp/(syncanyclientyfPPRiwVTpqsQIfYYmbp1)/T=1585504227524, chunks=1, multiChunks=1, fileContents=1, fileHistories=1]
29-3-20 17:50:27.717 | UpOperation     | Test worker    | INFO : Committing local database.
29-3-20 17:50:27.718 | UpOperation     | Test worker    | FINE : Waiting for new database version.
29-3-20 17:50:27.718 | UpOperation     | Test worker    | FINE : Took new database version: DatabaseVersion [header=UnknownMachine/()/T=1585504227527, chunks=0, multiChunks=0, fileContents=0, fileHistories=0]
29-3-20 17:50:27.718 | UpOperation     | Test worker    | INFO : Sync up done.
29-3-20 17:50:27.726 | ActionFileHandl | Test worker    | INFO : Finishing action for RemoteFile[name=action-up-syncanyclientyfPPRiwVTpqsQIfYYmbp-1585504225491] ...
29-3-20 17:50:27.734 | ActionFileHandl | Test worker    | INFO : Deleting action file: RemoteFile[name=action-up-syncanyclientyfPPRiwVTpqsQIfYYmbp-1585504225491]
29-3-20 17:50:27.734 | AbstractTransfe | Test worker    | INFO : Cleaning own old action files ...
29-3-20 17:50:27.735 | Cache           | Test worker    | INFO : Cache size okay (1 KB), no need to clean (keep size is 500 MB)
29-3-20 17:50:27.736 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event UpEndSyncExternalEvent (org.syncany.operations.daemon.messages.UpEndSyncExternalEvent@728e5774) ...
29-3-20 17:50:27.739 | DatabaseConnect | Test worker    | INFO : Found 12 tables.
29-3-20 17:50:27.739 | StatusOperation | Test worker    | INFO : 
29-3-20 17:50:27.739 | StatusOperation | Test worker    | INFO : Running 'Status' at client syncanyclientyfPPRiwVTpqsQIfYYmbp ...
29-3-20 17:50:27.739 | StatusOperation | Test worker    | INFO : --------------------------------------------
29-3-20 17:50:27.739 | StatusOperation | Test worker    | INFO : Force checksum ENABLED.
29-3-20 17:50:27.739 | StatusOperation | Test worker    | INFO : Querying current file tree from database ...
29-3-20 17:50:27.739 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event StatusStartSyncExternalEvent (org.syncany.operations.daemon.messages.StatusStartSyncExternalEvent@5e30c02e) ...
29-3-20 17:50:27.749 | StatusOperation | Test worker    | INFO : Analyzing local folder /tmp/syncanytest/syncany-200329175011381-17173-client-syncanyclient ...
29-3-20 17:50:27.751 | FileVersionComp | Test worker    | INFO :      - [CHANGED_LAST_MOD_DATE]: Local file DIFFERS from file version, expected MOD. DATE = 3/29/20, 5:50 PM (1,585,504,225,330), but actual MOD. DATE = 3/29/20, 5:50 PM (1,585,504,227,734), for file rndFile-1585504225333-1919188665.dat
29-3-20 17:50:27.753 | FileVersionComp | Test worker    | INFO :      - [CHANGED_CHECKSUM, CHANGED_LAST_MOD_DATE]: Local file DIFFERS from file version, expected CHECKSUM = b4651e36b3f7ef0b6b4db572e511a225920770d5, but actual CHECKSUM = 7ee0352a5e7ef3852967c06015a4e008299999ec, for file rndFile-1585504225333-1919188665.dat
29-3-20 17:50:27.753 | StatusOperation | Test worker    | FINE : - Ignoring file (syncany app-related): .syncany
29-3-20 17:50:27.753 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event StatusEndSyncExternalEvent (org.syncany.operations.daemon.messages.StatusEndSyncExternalEvent@7ecae275) ...
29-3-20 17:50:27.760 | org.syncany.plu | Test worker    | FINE : Validating required fields
29-3-20 17:50:27.760 | LocalTransferMa | Test worker    | INFO : testTargetExists: Target exists.
29-3-20 17:50:27.917 | hsqldb.db.HSQLD | Test worker    | INFO : Checkpoint start
29-3-20 17:50:27.917 | hsqldb.db.HSQLD | Test worker    | INFO : checkpointClose start
29-3-20 17:50:27.927 | hsqldb.db.HSQLD | Test worker    | FINE : log close size: 0
29-3-20 17:50:27.929 | hsqldb.db.HSQLD | Test worker    | INFO : checkpointClose end
29-3-20 17:50:27.929 | hsqldb.db.HSQLD | Test worker    | INFO : Checkpoint end - txts: 1
29-3-20 17:50:27.934 | DatabaseConnect | Test worker    | INFO : Found 0 tables.
29-3-20 17:50:27.935 | DatabaseConnect | Test worker    | INFO : Database has no tables. Creating tables from script.create.all.sql
29-3-20 17:50:27.935 | SqlRunner       | Test worker    | INFO : SQL (comment): -- Tables
29-3-20 17:50:27.935 | 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:50:27.938 | 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:50:27.941 | 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:50:27.946 | 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:50:27.948 | 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:50:27.951 | 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:50:27.953 | 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:50:27.961 | 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:50:27.963 | 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:50:27.965 | 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:50:27.967 | 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:50:27.970 | 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:50:27.972 | SqlRunner       | Test worker    | INFO : SQL (comment): -- Non-primary indices
29-3-20 17:50:27.972 | SqlRunner       | Test worker    | INFO : SQL:   CREATE INDEX idx_databaseversion_status ON databaseversion (status); 
29-3-20 17:50:27.973 | hsqldb.db.HSQLD | Test worker    | INFO : dataFileCache open start
29-3-20 17:50:27.975 | hsqldb.db.HSQLD | Test worker    | INFO : dataFileCache open end
29-3-20 17:50:27.976 | SqlRunner       | Test worker    | INFO : SQL: CREATE INDEX idx_databaseversion_vectorclock_serialized ON databaseversion (vectorclock_serialized); 
29-3-20 17:50:27.978 | SqlRunner       | Test worker    | INFO : SQL: CREATE INDEX idx_fileversion_path ON fileversion (path); 
29-3-20 17:50:27.980 | SqlRunner       | Test worker    | INFO : SQL: CREATE INDEX idx_fileversion_status ON fileversion (status); 
29-3-20 17:50:27.981 | SqlRunner       | Test worker    | INFO : SQL: CREATE INDEX idx_fileversion_filecontent_checksum ON fileversion (filecontent_checksum); 
29-3-20 17:50:27.983 | SqlRunner       | Test worker    | INFO : SQL (comment): -- Views
29-3-20 17:50:27.983 | 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:50:27.986 | 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:50:27.991 | 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:50:27.996 | 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:50:28.001 | SqlRunner       | Test worker    | INFO : SQL (comment): -- Full Views
29-3-20 17:50:28.001 | 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:50:28.004 | 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:50:28.011 | SqlRunner       | Test worker    | INFO : SQL (comment): -- Functions
29-3-20 17:50:28.011 | SqlRunner       | Test worker    | INFO : SQL (new delimiter): end;
29-3-20 17:50:28.011 | 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:50:28.015 | StatusOperation | Test worker    | INFO : 
29-3-20 17:50:28.015 | StatusOperation | Test worker    | INFO : Running 'Status' at client syncanyclientegXvnASNnTfmpEbUSSCr ...
29-3-20 17:50:28.015 | StatusOperation | Test worker    | INFO : --------------------------------------------
29-3-20 17:50:28.015 | StatusOperation | Test worker    | INFO : Querying current file tree from database ...
29-3-20 17:50:28.015 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event StatusStartSyncExternalEvent (org.syncany.operations.daemon.messages.StatusStartSyncExternalEvent@149160e) ...
29-3-20 17:50:28.018 | StatusOperation | Test worker    | INFO : Analyzing local folder /tmp/syncanytest/syncany-200329175011381-69494-client-syncanyclient ...
29-3-20 17:50:28.018 | StatusOperation | Test worker    | FINE : - New file: rndFile-1585504227822-1894991921.dat
29-3-20 17:50:28.018 | StatusOperation | Test worker    | FINE : - New file: rndFile-1585504227812-1312957789.dat
29-3-20 17:50:28.019 | StatusOperation | Test worker    | FINE : - Ignoring file (syncany app-related): .syncany
29-3-20 17:50:28.019 | StatusOperation | Test worker    | FINE : - New file: rndFile-1585504227819-664621259.dat
29-3-20 17:50:28.019 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event StatusEndSyncExternalEvent (org.syncany.operations.daemon.messages.StatusEndSyncExternalEvent@669a28c7) ...
29-3-20 17:50:28.019 | org.syncany.plu | Test worker    | FINE : Validating required fields
29-3-20 17:50:28.020 | TransferManager | Test worker    | INFO : Building LocalTransferManager from config 'syncany-200329175011381-69494-client-syncanyclient' ...
29-3-20 17:50:28.020 | TransferManager | Test worker    | INFO : - With feature ReadAfterWriteConsistent
29-3-20 17:50:28.020 | TransferManager | Test worker    | INFO : - With feature Retriable
29-3-20 17:50:28.020 | TransferManager | Test worker    | FINE : - Checking if selected features supported ...
29-3-20 17:50:28.020 | TransferManager | Test worker    | FINE : - Checking for duplicate features ...
29-3-20 17:50:28.027 | TransferManager | Test worker    | FINE : - Checking required feature TransactionAware in LocalTransferManager ...
29-3-20 17:50:28.027 | TransferManager | Test worker    | FINE : - Checking required feature Retriable in LocalTransferManager ...
29-3-20 17:50:28.027 | TransferManager | Test worker    | INFO : - SKIPPING unsupported optional feature ReadAfterWriteConsistent
29-3-20 17:50:28.028 | TransferManager | Test worker    | FINE : - Wrapping TransferManager LocalTransferManager in RetriableFeatureTransferManager
29-3-20 17:50:28.028 | org.syncany.plu | Test worker    | FINE : Validating required fields
29-3-20 17:50:28.028 | TransferManager | Test worker    | INFO : Building LocalTransferManager from config 'syncany-200329175011381-69494-client-syncanyclient' ...
29-3-20 17:50:28.028 | TransferManager | Test worker    | INFO : - With feature ReadAfterWriteConsistent
29-3-20 17:50:28.028 | TransferManager | Test worker    | INFO : - With feature Retriable
29-3-20 17:50:28.028 | TransferManager | Test worker    | INFO : - With feature PathAware
29-3-20 17:50:28.028 | TransferManager | Test worker    | INFO : - With feature TransactionAware
29-3-20 17:50:28.028 | TransferManager | Test worker    | FINE : - Checking if selected features supported ...
29-3-20 17:50:28.029 | TransferManager | Test worker    | FINE : - Checking for duplicate features ...
29-3-20 17:50:28.029 | TransferManager | Test worker    | FINE : - Checking required feature TransactionAware in LocalTransferManager ...
29-3-20 17:50:28.029 | TransferManager | Test worker    | FINE : - Checking required feature Retriable in LocalTransferManager ...
29-3-20 17:50:28.029 | TransferManager | Test worker    | INFO : - SKIPPING unsupported optional feature ReadAfterWriteConsistent
29-3-20 17:50:28.029 | TransferManager | Test worker    | FINE : - Wrapping TransferManager LocalTransferManager in RetriableFeatureTransferManager
29-3-20 17:50:28.029 | TransferManager | Test worker    | INFO : - SKIPPING unsupported optional feature PathAware
29-3-20 17:50:28.029 | TransferManager | Test worker    | FINE : - Wrapping TransferManager RetriableFeatureTransferManager in TransactionAwareFeatureTransferManager
29-3-20 17:50:28.035 | DatabaseConnect | Test worker    | INFO : Found 12 tables.
29-3-20 17:50:28.035 | UpOperation     | Test worker    | INFO : 
29-3-20 17:50:28.035 | UpOperation     | Test worker    | INFO : Running 'Sync up' at client syncanyclientegXvnASNnTfmpEbUSSCr ...
29-3-20 17:50:28.035 | UpOperation     | Test worker    | INFO : --------------------------------------------
29-3-20 17:50:28.035 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event UpStartSyncExternalEvent (org.syncany.operations.daemon.messages.UpStartSyncExternalEvent@7c878059) ...
29-3-20 17:50:28.040 | DatabaseConnect | Test worker    | INFO : Found 12 tables.
29-3-20 17:50:28.040 | StatusOperation | Test worker    | INFO : 
29-3-20 17:50:28.040 | StatusOperation | Test worker    | INFO : Running 'Status' at client syncanyclientegXvnASNnTfmpEbUSSCr ...
29-3-20 17:50:28.040 | StatusOperation | Test worker    | INFO : --------------------------------------------
29-3-20 17:50:28.041 | StatusOperation | Test worker    | INFO : Querying current file tree from database ...
29-3-20 17:50:28.041 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event StatusStartSyncExternalEvent (org.syncany.operations.daemon.messages.StatusStartSyncExternalEvent@10bd1d0) ...
29-3-20 17:50:28.043 | StatusOperation | Test worker    | INFO : Analyzing local folder /tmp/syncanytest/syncany-200329175011381-69494-client-syncanyclient ...
29-3-20 17:50:28.044 | StatusOperation | Test worker    | FINE : - New file: rndFile-1585504227822-1894991921.dat
29-3-20 17:50:28.048 | StatusOperation | Test worker    | FINE : - New file: rndFile-1585504227812-1312957789.dat
29-3-20 17:50:28.048 | StatusOperation | Test worker    | FINE : - Ignoring file (syncany app-related): .syncany
29-3-20 17:50:28.049 | StatusOperation | Test worker    | FINE : - New file: rndFile-1585504227819-664621259.dat
29-3-20 17:50:28.049 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event StatusEndSyncExternalEvent (org.syncany.operations.daemon.messages.StatusEndSyncExternalEvent@228cc0bc) ...
29-3-20 17:50:28.052 | AbstractTransfe | Test worker    | INFO : Looking for other running remote operations ...
29-3-20 17:50:28.054 | DatabaseConnect | Test worker    | INFO : Found 12 tables.
29-3-20 17:50:28.054 | LsRemoteOperati | Test worker    | INFO : 
29-3-20 17:50:28.054 | LsRemoteOperati | Test worker    | INFO : Running 'Remote Status' at client syncanyclientegXvnASNnTfmpEbUSSCr ...
29-3-20 17:50:28.054 | LsRemoteOperati | Test worker    | INFO : --------------------------------------------
29-3-20 17:50:28.054 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event LsRemoteStartSyncExternalEvent (org.syncany.operations.daemon.messages.LsRemoteStartSyncExternalEvent@3cb00341) ...
29-3-20 17:50:28.056 | LsRemoteOperati | Test worker    | INFO : Retrieving remote database list.
29-3-20 17:50:28.056 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event LsRemoteEndSyncExternalEvent (org.syncany.operations.daemon.messages.LsRemoteEndSyncExternalEvent@18f76dfa) ...
29-3-20 17:50:28.056 | UpOperation     | Test worker    | INFO : No remote changes, ready to upload.
29-3-20 17:50:28.056 | ActionFileHandl | Test worker    | INFO : Starting action for RemoteFile[name=action-up-syncanyclientegXvnASNnTfmpEbUSSCr-1585504228029] ...
29-3-20 17:50:28.056 | ActionFileHandl | Test worker    | INFO : Uploading action file: RemoteFile[name=action-up-syncanyclientegXvnASNnTfmpEbUSSCr-1585504228029]
29-3-20 17:50:28.060 | ActionFileHandl | Test worker    | INFO : Scheduling action renewal task for every 2 minutes, for RemoteFile[name=action-up-syncanyclientegXvnASNnTfmpEbUSSCr-1585504228029] ...
29-3-20 17:50:28.062 | DatabaseConnect | Test worker    | INFO : Found 12 tables.
29-3-20 17:50:28.067 | TransactionAwar | Test worker    | INFO : Done rolling back previous transactions.
29-3-20 17:50:28.068 | AsyncIndexer    | AsyncI/syncany | INFO : Starting Indexing.
29-3-20 17:50:28.068 | InternalEventBu | AsyncI/syncany | INFO : Event bus 'LocalEventBus': Posting event UpIndexStartSyncExternalEvent (org.syncany.operations.daemon.messages.UpIndexStartSyncExternalEvent@19714565) ...
29-3-20 17:50:28.068 | Indexer         | AsyncI/syncany | FINE : - Looking for deleted files ...
29-3-20 17:50:28.068 | Indexer         | AsyncI/syncany | FINE : - +File /tmp/syncanytest/syncany-200329175011381-69494-client-syncanyclient/rndFile-1585504227812-1312957789.dat
29-3-20 17:50:28.076 | Indexer         | AsyncI/syncany | FINE : - +FileContent: /tmp/syncanytest/syncany-200329175011381-69494-client-syncanyclient/rndFile-1585504227812-1312957789.dat
29-3-20 17:50:28.088 | Indexer         | AsyncI/syncany | FINE : - Chunk new: 428df1a710a0a346257c0268e5ae05dd783d844e
29-3-20 17:50:28.088 | Indexer         | AsyncI/syncany | FINE : - +MultiChunk 030bccc675611b34d7658923c858073e1fc47551
29-3-20 17:50:28.094 | Indexer         | AsyncI/syncany | FINE : - Chunk > MultiChunk: 428df1a710a0a346257c0268e5ae05dd783d844e > 030bccc675611b34d7658923c858073e1fc47551
29-3-20 17:50:28.097 | Indexer         | AsyncI/syncany | FINE : - Chunk > FileContent: 428df1a710a0a346257c0268e5ae05dd783d844e > /tmp/syncanytest/syncany-200329175011381-69494-client-syncanyclient/rndFile-1585504227812-1312957789.dat
29-3-20 17:50:28.098 | Indexer         | AsyncI/syncany | FINE : - /File: rndFile-1585504227812-1312957789.dat (checksum 428df1a710a0a346257c0268e5ae05dd783d844e)
29-3-20 17:50:28.109 | Indexer         | AsyncI/syncany | FINE :    * No old file history found, starting new history (path: rndFile-1585504227812-1312957789.dat, checksum: 428df1a710a0a346257c0268e5ae05dd783d844e)
29-3-20 17:50:28.109 | FileVersionComp | AsyncI/syncany | INFO :      - [DELETED]: Local file DIFFERS from file version, actual file is NULL, for file rndFile-1585504227812-1312957789.dat
29-3-20 17:50:28.110 | Indexer         | AsyncI/syncany | INFO :    * Added file version:    FileVersion [version=1, path=rndFile-1585504227812-1312957789.dat, type=FILE, status=NEW, size=512000, lastModified=Sun Mar 29 17:50:27 UTC 2020, linkTarget=null, checksum=428df1a710a0a346257c0268e5ae05dd783d844e, updated=Sun Mar 29 17:50:28 UTC 2020, posixPermissions=rw-rw-r--, dosAttributes=--a-]
29-3-20 17:50:28.110 | Indexer         | AsyncI/syncany | INFO :      based on file version: null
29-3-20 17:50:28.110 | InternalEventBu | AsyncI/syncany | INFO : Event bus 'LocalEventBus': Posting event UpIndexChangesDetectedSyncExternalEvent (org.syncany.operations.daemon.messages.UpIndexChangesDetectedSyncExternalEvent@6082615e) ...
29-3-20 17:50:28.111 | Indexer         | AsyncI/syncany | FINE : - +File /tmp/syncanytest/syncany-200329175011381-69494-client-syncanyclient/rndFile-1585504227819-664621259.dat
29-3-20 17:50:28.112 | Indexer         | AsyncI/syncany | FINE : - +FileContent: /tmp/syncanytest/syncany-200329175011381-69494-client-syncanyclient/rndFile-1585504227819-664621259.dat
29-3-20 17:50:28.120 | Indexer         | AsyncI/syncany | FINE : - Chunk new: 307e391dbb649f6d1615bfbf6d323c97b4223cf4
29-3-20 17:50:28.133 | Indexer         | AsyncI/syncany | FINE : - Chunk > MultiChunk: 307e391dbb649f6d1615bfbf6d323c97b4223cf4 > 030bccc675611b34d7658923c858073e1fc47551
29-3-20 17:50:28.133 | Indexer         | AsyncI/syncany | FINE : - Chunk > FileContent: 307e391dbb649f6d1615bfbf6d323c97b4223cf4 > /tmp/syncanytest/syncany-200329175011381-69494-client-syncanyclient/rndFile-1585504227819-664621259.dat
29-3-20 17:50:28.133 | Indexer         | AsyncI/syncany | FINE : - /File: rndFile-1585504227819-664621259.dat (checksum 307e391dbb649f6d1615bfbf6d323c97b4223cf4)
29-3-20 17:50:28.135 | Indexer         | AsyncI/syncany | FINE :    * No old file history found, starting new history (path: rndFile-1585504227819-664621259.dat, checksum: 307e391dbb649f6d1615bfbf6d323c97b4223cf4)
29-3-20 17:50:28.138 | FileVersionComp | AsyncI/syncany | INFO :      - [DELETED]: Local file DIFFERS from file version, actual file is NULL, for file rndFile-1585504227819-664621259.dat
29-3-20 17:50:28.138 | Indexer         | AsyncI/syncany | INFO :    * Added file version:    FileVersion [version=1, path=rndFile-1585504227819-664621259.dat, type=FILE, status=NEW, size=512000, lastModified=Sun Mar 29 17:50:27 UTC 2020, linkTarget=null, checksum=307e391dbb649f6d1615bfbf6d323c97b4223cf4, updated=Sun Mar 29 17:50:28 UTC 2020, posixPermissions=rw-rw-r--, dosAttributes=--a-]
29-3-20 17:50:28.138 | Indexer         | AsyncI/syncany | INFO :      based on file version: null
29-3-20 17:50:28.139 | Indexer         | AsyncI/syncany | FINE : - +File /tmp/syncanytest/syncany-200329175011381-69494-client-syncanyclient/rndFile-1585504227822-1894991921.dat
29-3-20 17:50:28.139 | Indexer         | AsyncI/syncany | FINE : - +FileContent: /tmp/syncanytest/syncany-200329175011381-69494-client-syncanyclient/rndFile-1585504227822-1894991921.dat
29-3-20 17:50:28.154 | Indexer         | AsyncI/syncany | FINE : - Chunk new: f296f3041de6b778100d80be21a67fe2917bfd8e
29-3-20 17:50:28.169 | Indexer         | AsyncI/syncany | FINE : - Chunk > MultiChunk: f296f3041de6b778100d80be21a67fe2917bfd8e > 030bccc675611b34d7658923c858073e1fc47551
29-3-20 17:50:28.176 | Indexer         | AsyncI/syncany | FINE : - Chunk > FileContent: f296f3041de6b778100d80be21a67fe2917bfd8e > /tmp/syncanytest/syncany-200329175011381-69494-client-syncanyclient/rndFile-1585504227822-1894991921.dat
29-3-20 17:50:28.176 | Indexer         | AsyncI/syncany | FINE : - /File: rndFile-1585504227822-1894991921.dat (checksum f296f3041de6b778100d80be21a67fe2917bfd8e)
29-3-20 17:50:28.179 | Indexer         | AsyncI/syncany | FINE :    * No old file history found, starting new history (path: rndFile-1585504227822-1894991921.dat, checksum: f296f3041de6b778100d80be21a67fe2917bfd8e)
29-3-20 17:50:28.179 | FileVersionComp | AsyncI/syncany | INFO :      - [DELETED]: Local file DIFFERS from file version, actual file is NULL, for file rndFile-1585504227822-1894991921.dat
29-3-20 17:50:28.179 | Indexer         | AsyncI/syncany | INFO :    * Added file version:    FileVersion [version=1, path=rndFile-1585504227822-1894991921.dat, type=FILE, status=NEW, size=512000, lastModified=Sun Mar 29 17:50:27 UTC 2020, linkTarget=null, checksum=f296f3041de6b778100d80be21a67fe2917bfd8e, updated=Sun Mar 29 17:50:28 UTC 2020, posixPermissions=rw-rw-r--, dosAttributes=--a-]
29-3-20 17:50:28.180 | Indexer         | AsyncI/syncany | INFO :      based on file version: null
29-3-20 17:50:28.181 | Indexer         | AsyncI/syncany | FINE : - /MultiChunk 030bccc675611b34d7658923c858073e1fc47551
29-3-20 17:50:28.181 | InternalEventBu | AsyncI/syncany | INFO : Event bus 'LocalEventBus': Posting event UpIndexEndSyncExternalEvent (org.syncany.operations.daemon.messages.UpIndexEndSyncExternalEvent@1bf49263) ...
29-3-20 17:50:28.181 | Indexer         | AsyncI/syncany | FINE : Processed new database version: DatabaseVersion [header=UnknownMachine/()/T=1585504228068, chunks=3, multiChunks=1, fileContents=3, fileHistories=3]
29-3-20 17:50:28.182 | UpOperation     | Test worker    | INFO : No DIRTY data found in database (no dirty databases); Nothing to do here.
29-3-20 17:50:28.183 | UpOperation     | Test worker    | INFO : Last vector clock was: ()
29-3-20 17:50:28.184 | UpOperation     | Test worker    | INFO : Uploading new multichunks ...
29-3-20 17:50:28.184 | UpOperation     | Test worker    | INFO : - Uploading multichunk 030bccc675611b34d7658923c858073e1fc47551 from /tmp/syncanytest/syncany-200329175011381-69494-client-syncanyclient/.syncany/cache/multichunk-030bccc675611b34d7658923c858073e1fc47551 to RemoteFile[name=multichunk-030bccc675611b34d7658923c858073e1fc47551] ...
29-3-20 17:50:28.185 | RemoteTransacti | Test worker    | INFO : - Adding file to TX for UPLOAD: /tmp/syncanytest/syncany-200329175011381-69494-client-syncanyclient/.syncany/cache/multichunk-030bccc675611b34d7658923c858073e1fc47551 -> Temp. remote file: RemoteFile[name=temp-wJHaY-multichunk-030bccc675611b34d7658923c858073e1fc47551], final location: RemoteFile[name=multichunk-030bccc675611b34d7658923c858073e1fc47551]
29-3-20 17:50:28.185 | UpOperation     | Test worker    | INFO : Uploading database: DatabaseVersion [header=syncanyclientegXvnASNnTfmpEbUSSCr/(syncanyclientegXvnASNnTfmpEbUSSCr1)/T=1585504228184, chunks=3, multiChunks=1, fileContents=3, fileHistories=3]
29-3-20 17:50:28.185 | UpOperation     | Test worker    | INFO : Saving local delta database, version syncanyclientegXvnASNnTfmpEbUSSCr/(syncanyclientegXvnASNnTfmpEbUSSCr1)/T=1585504228184 to file /tmp/syncanytest/syncany-200329175011381-69494-client-syncanyclient/.syncany/cache/database-syncanyclientegXvnASNnTfmpEbUSSCr-0000000001 ... 
29-3-20 17:50:28.186 | UpOperation     | Test worker    | INFO : - Saving database to /tmp/syncanytest/syncany-200329175011381-69494-client-syncanyclient/.syncany/cache/database-syncanyclientegXvnASNnTfmpEbUSSCr-0000000001 ...
29-3-20 17:50:28.187 | UpOperation     | Test worker    | INFO : - Uploading local delta database file ...
29-3-20 17:50:28.187 | UpOperation     | Test worker    | INFO : - Uploading /tmp/syncanytest/syncany-200329175011381-69494-client-syncanyclient/.syncany/cache/database-syncanyclientegXvnASNnTfmpEbUSSCr-0000000001 to RemoteFile[name=database-syncanyclientegXvnASNnTfmpEbUSSCr-0000000001] ...
29-3-20 17:50:28.187 | RemoteTransacti | Test worker    | INFO : - Adding file to TX for UPLOAD: /tmp/syncanytest/syncany-200329175011381-69494-client-syncanyclient/.syncany/cache/database-syncanyclientegXvnASNnTfmpEbUSSCr-0000000001 -> Temp. remote file: RemoteFile[name=temp-PonhX-database-syncanyclientegXvnASNnTfmpEbUSSCr-0000000001], final location: RemoteFile[name=database-syncanyclientegXvnASNnTfmpEbUSSCr-0000000001]
29-3-20 17:50:28.188 | InternalEventBu | AsyncI/syncany | INFO : Event bus 'LocalEventBus': Posting event UpIndexMidSyncExternalEvent (org.syncany.operations.daemon.messages.UpIndexMidSyncExternalEvent@631ce128) ...
29-3-20 17:50:28.196 | UpOperation     | Test worker    | INFO : Adding shutdown hook (to allow resuming the upload) ...
29-3-20 17:50:28.196 | RemoteTransacti | Test worker    | INFO : Starting TX.commit() ...
29-3-20 17:50:28.200 | RemoteTransacti | Test worker    | INFO : Wrote transaction manifest to temporary file: /tmp/syncanytest/syncany-200329175011381-69494-client-syncanyclient/.syncany/cache/temp-transaction-14680125777543921577.tmp
29-3-20 17:50:28.200 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event UpUploadFileSyncExternalEvent (org.syncany.operations.daemon.messages.UpUploadFileSyncExternalEvent@1b0c27c0) ...
29-3-20 17:50:28.200 | RemoteTransacti | Test worker    | INFO : - Uploading remote transaction file RemoteFile[name=transaction-1cb8bd19] ...
29-3-20 17:50:28.202 | RemoteTransacti | Test worker    | INFO : - Starting to upload data in commit.
29-3-20 17:50:28.202 | AsyncIndexer    | AsyncI/syncany | INFO : Stopping indexing. Signal end of stream with empty databaseversion
29-3-20 17:50:28.203 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event UpUploadFileInTransactionSyncExternalEvent (org.syncany.operations.daemon.messages.UpUploadFileInTransactionSyncExternalEvent@767db4a5) ...
29-3-20 17:50:28.203 | RemoteTransacti | Test worker    | INFO : - Uploading /tmp/syncanytest/syncany-200329175011381-69494-client-syncanyclient/.syncany/cache/multichunk-030bccc675611b34d7658923c858073e1fc47551 to temp. file RemoteFile[name=temp-wJHaY-multichunk-030bccc675611b34d7658923c858073e1fc47551] ...
29-3-20 17:50:28.205 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event UpUploadFileInTransactionSyncExternalEvent (org.syncany.operations.daemon.messages.UpUploadFileInTransactionSyncExternalEvent@1c669d1c) ...
29-3-20 17:50:28.205 | RemoteTransacti | Test worker    | INFO : - Uploading /tmp/syncanytest/syncany-200329175011381-69494-client-syncanyclient/.syncany/cache/database-syncanyclientegXvnASNnTfmpEbUSSCr-0000000001 to temp. file RemoteFile[name=temp-PonhX-database-syncanyclientegXvnASNnTfmpEbUSSCr-0000000001] ...
29-3-20 17:50:28.210 | RemoteTransacti | Test worker    | INFO : - Moving temp. file RemoteFile[name=temp-wJHaY-multichunk-030bccc675611b34d7658923c858073e1fc47551] to final location RemoteFile[name=multichunk-030bccc675611b34d7658923c858073e1fc47551] ...
29-3-20 17:50:28.211 | RemoteTransacti | Test worker    | INFO : - Moving temp. file RemoteFile[name=temp-PonhX-database-syncanyclientegXvnASNnTfmpEbUSSCr-0000000001] to final location RemoteFile[name=database-syncanyclientegXvnASNnTfmpEbUSSCr-0000000001] ...
29-3-20 17:50:28.211 | RemoteTransacti | Test worker    | INFO : - Deleting remote transaction file RemoteFile[name=transaction-1cb8bd19] ...
29-3-20 17:50:28.211 | RemoteTransacti | Test worker    | INFO : END of TX.commmit(): Succesfully committed transaction.
29-3-20 17:50:28.212 | RemoteTransacti | Test worker    | INFO : END of TX.delTemp(): Sucessfully deleted final files.
29-3-20 17:50:28.212 | UpOperation     | Test worker    | INFO : Persisting local SQL database (new database version syncanyclientegXvnASNnTfmpEbUSSCr/(syncanyclientegXvnASNnTfmpEbUSSCr1)/T=1585504228184) ...
29-3-20 17:50:28.234 | UpOperation     | Test worker    | INFO : Removing DIRTY database versions from database ...
29-3-20 17:50:28.261 | UpOperation     | Test worker    | INFO : Adding database version to result changes:DatabaseVersion [header=syncanyclientegXvnASNnTfmpEbUSSCr/(syncanyclientegXvnASNnTfmpEbUSSCr1)/T=1585504228184, chunks=3, multiChunks=1, fileContents=3, fileHistories=3]
29-3-20 17:50:28.261 | UpOperation     | Test worker    | INFO : Committing local database.
29-3-20 17:50:28.261 | UpOperation     | Test worker    | FINE : Waiting for new database version.
29-3-20 17:50:28.261 | UpOperation     | Test worker    | FINE : Took new database version: DatabaseVersion [header=UnknownMachine/()/T=1585504228202, chunks=0, multiChunks=0, fileContents=0, fileHistories=0]
29-3-20 17:50:28.261 | UpOperation     | Test worker    | INFO : Sync up done.
29-3-20 17:50:28.263 | ActionFileHandl | Test worker    | INFO : Finishing action for RemoteFile[name=action-up-syncanyclientegXvnASNnTfmpEbUSSCr-1585504228029] ...
29-3-20 17:50:28.268 | ActionFileHandl | Test worker    | INFO : Deleting action file: RemoteFile[name=action-up-syncanyclientegXvnASNnTfmpEbUSSCr-1585504228029]
29-3-20 17:50:28.268 | AbstractTransfe | Test worker    | INFO : Cleaning own old action files ...
29-3-20 17:50:28.268 | Cache           | Test worker    | INFO : Cache size okay (1503 KB), no need to clean (keep size is 500 MB)
29-3-20 17:50:28.268 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event UpEndSyncExternalEvent (org.syncany.operations.daemon.messages.UpEndSyncExternalEvent@45defef6) ...
29-3-20 17:50:28.270 | DatabaseConnect | Test worker    | INFO : Found 12 tables.
29-3-20 17:50:28.271 | StatusOperation | Test worker    | INFO : 
29-3-20 17:50:28.271 | StatusOperation | Test worker    | INFO : Running 'Status' at client syncanyclientegXvnASNnTfmpEbUSSCr ...
29-3-20 17:50:28.271 | StatusOperation | Test worker    | INFO : --------------------------------------------
29-3-20 17:50:28.271 | StatusOperation | Test worker    | INFO : Querying current file tree from database ...
29-3-20 17:50:28.271 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event StatusStartSyncExternalEvent (org.syncany.operations.daemon.messages.StatusStartSyncExternalEvent@7d8079df) ...
29-3-20 17:50:28.274 | StatusOperation | Test worker    | INFO : Analyzing local folder /tmp/syncanytest/syncany-200329175011381-69494-client-syncanyclient ...
29-3-20 17:50:28.276 | StatusOperation | Test worker    | FINE : - Ignoring file (syncany app-related): .syncany
29-3-20 17:50:28.281 | StatusOperation | Test worker    | INFO : - No changes to local database
29-3-20 17:50:28.281 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event StatusEndSyncExternalEvent (org.syncany.operations.daemon.messages.StatusEndSyncExternalEvent@200607da) ...
29-3-20 17:50:30.288 | DatabaseConnect | Test worker    | INFO : Found 12 tables.
29-3-20 17:50:30.289 | StatusOperation | Test worker    | INFO : 
29-3-20 17:50:30.289 | StatusOperation | Test worker    | INFO : Running 'Status' at client syncanyclientegXvnASNnTfmpEbUSSCr ...
29-3-20 17:50:30.289 | StatusOperation | Test worker    | INFO : --------------------------------------------
29-3-20 17:50:30.289 | StatusOperation | Test worker    | INFO : Querying current file tree from database ...
29-3-20 17:50:30.289 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event StatusStartSyncExternalEvent (org.syncany.operations.daemon.messages.StatusStartSyncExternalEvent@e558968) ...
29-3-20 17:50:30.298 | StatusOperation | Test worker    | INFO : Analyzing local folder /tmp/syncanytest/syncany-200329175011381-69494-client-syncanyclient ...
29-3-20 17:50:30.300 | FileVersionComp | Test worker    | INFO :      - [CHANGED_LAST_MOD_DATE]: Local file DIFFERS from file version, expected MOD. DATE = 3/29/20, 5:50 PM (1,585,504,227,834), but actual MOD. DATE = 3/29/20, 5:50 PM (1,585,504,230,278), for file rndFile-1585504227822-1894991921.dat
29-3-20 17:50:30.301 | FileVersionComp | Test worker    | INFO :      - [CHANGED_LAST_MOD_DATE]: Local file DIFFERS from file version, expected MOD. DATE = 3/29/20, 5:50 PM (1,585,504,227,814), but actual MOD. DATE = 3/29/20, 5:50 PM (1,585,504,230,278), for file rndFile-1585504227812-1312957789.dat
29-3-20 17:50:30.304 | StatusOperation | Test worker    | FINE : - Ignoring file (syncany app-related): .syncany
29-3-20 17:50:30.306 | FileVersionComp | Test worker    | INFO :      - [CHANGED_LAST_MOD_DATE]: Local file DIFFERS from file version, expected MOD. DATE = 3/29/20, 5:50 PM (1,585,504,227,818), but actual MOD. DATE = 3/29/20, 5:50 PM (1,585,504,230,278), for file rndFile-1585504227819-664621259.dat
29-3-20 17:50:30.306 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event StatusEndSyncExternalEvent (org.syncany.operations.daemon.messages.StatusEndSyncExternalEvent@139fd18c) ...
29-3-20 17:50:30.307 | org.syncany.plu | Test worker    | FINE : Validating required fields
29-3-20 17:50:30.307 | TransferManager | Test worker    | INFO : Building LocalTransferManager from config 'syncany-200329175011381-69494-client-syncanyclient' ...
29-3-20 17:50:30.307 | TransferManager | Test worker    | INFO : - With feature ReadAfterWriteConsistent
29-3-20 17:50:30.307 | TransferManager | Test worker    | INFO : - With feature Retriable
29-3-20 17:50:30.307 | TransferManager | Test worker    | FINE : - Checking if selected features supported ...
29-3-20 17:50:30.307 | TransferManager | Test worker    | FINE : - Checking for duplicate features ...
29-3-20 17:50:30.307 | TransferManager | Test worker    | FINE : - Checking required feature TransactionAware in LocalTransferManager ...
29-3-20 17:50:30.307 | TransferManager | Test worker    | FINE : - Checking required feature Retriable in LocalTransferManager ...
29-3-20 17:50:30.307 | TransferManager | Test worker    | INFO : - SKIPPING unsupported optional feature ReadAfterWriteConsistent
29-3-20 17:50:30.307 | TransferManager | Test worker    | FINE : - Wrapping TransferManager LocalTransferManager in RetriableFeatureTransferManager
29-3-20 17:50:30.308 | org.syncany.plu | Test worker    | FINE : Validating required fields
29-3-20 17:50:30.316 | TransferManager | Test worker    | INFO : Building LocalTransferManager from config 'syncany-200329175011381-69494-client-syncanyclient' ...
29-3-20 17:50:30.316 | TransferManager | Test worker    | INFO : - With feature ReadAfterWriteConsistent
29-3-20 17:50:30.316 | TransferManager | Test worker    | INFO : - With feature Retriable
29-3-20 17:50:30.316 | TransferManager | Test worker    | INFO : - With feature PathAware
29-3-20 17:50:30.316 | TransferManager | Test worker    | INFO : - With feature TransactionAware
29-3-20 17:50:30.316 | TransferManager | Test worker    | FINE : - Checking if selected features supported ...
29-3-20 17:50:30.316 | TransferManager | Test worker    | FINE : - Checking for duplicate features ...
29-3-20 17:50:30.317 | TransferManager | Test worker    | FINE : - Checking required feature TransactionAware in LocalTransferManager ...
29-3-20 17:50:30.317 | TransferManager | Test worker    | FINE : - Checking required feature Retriable in LocalTransferManager ...
29-3-20 17:50:30.317 | TransferManager | Test worker    | INFO : - SKIPPING unsupported optional feature ReadAfterWriteConsistent
29-3-20 17:50:30.317 | TransferManager | Test worker    | FINE : - Wrapping TransferManager LocalTransferManager in RetriableFeatureTransferManager
29-3-20 17:50:30.317 | TransferManager | Test worker    | INFO : - SKIPPING unsupported optional feature PathAware
29-3-20 17:50:30.317 | TransferManager | Test worker    | FINE : - Wrapping TransferManager RetriableFeatureTransferManager in TransactionAwareFeatureTransferManager
29-3-20 17:50:30.321 | DatabaseConnect | Test worker    | INFO : Found 12 tables.
29-3-20 17:50:30.321 | UpOperation     | Test worker    | INFO : 
29-3-20 17:50:30.322 | UpOperation     | Test worker    | INFO : Running 'Sync up' at client syncanyclientegXvnASNnTfmpEbUSSCr ...
29-3-20 17:50:30.322 | UpOperation     | Test worker    | INFO : --------------------------------------------
29-3-20 17:50:30.322 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event UpStartSyncExternalEvent (org.syncany.operations.daemon.messages.UpStartSyncExternalEvent@6d1c6e0f) ...
29-3-20 17:50:30.323 | DatabaseConnect | Test worker    | INFO : Found 12 tables.
29-3-20 17:50:30.323 | StatusOperation | Test worker    | INFO : 
29-3-20 17:50:30.323 | StatusOperation | Test worker    | INFO : Running 'Status' at client syncanyclientegXvnASNnTfmpEbUSSCr ...
29-3-20 17:50:30.323 | StatusOperation | Test worker    | INFO : --------------------------------------------
29-3-20 17:50:30.323 | StatusOperation | Test worker    | INFO : Querying current file tree from database ...
29-3-20 17:50:30.323 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event StatusStartSyncExternalEvent (org.syncany.operations.daemon.messages.StatusStartSyncExternalEvent@43a4fd82) ...
29-3-20 17:50:30.336 | StatusOperation | Test worker    | INFO : Analyzing local folder /tmp/syncanytest/syncany-200329175011381-69494-client-syncanyclient ...
29-3-20 17:50:30.337 | FileVersionComp | Test worker    | INFO :      - [CHANGED_LAST_MOD_DATE]: Local file DIFFERS from file version, expected MOD. DATE = 3/29/20, 5:50 PM (1,585,504,227,834), but actual MOD. DATE = 3/29/20, 5:50 PM (1,585,504,230,278), for file rndFile-1585504227822-1894991921.dat
29-3-20 17:50:30.337 | FileVersionComp | Test worker    | INFO :      - [CHANGED_LAST_MOD_DATE]: Local file DIFFERS from file version, expected MOD. DATE = 3/29/20, 5:50 PM (1,585,504,227,814), but actual MOD. DATE = 3/29/20, 5:50 PM (1,585,504,230,278), for file rndFile-1585504227812-1312957789.dat
29-3-20 17:50:30.338 | StatusOperation | Test worker    | FINE : - Ignoring file (syncany app-related): .syncany
29-3-20 17:50:30.345 | FileVersionComp | Test worker    | INFO :      - [CHANGED_LAST_MOD_DATE]: Local file DIFFERS from file version, expected MOD. DATE = 3/29/20, 5:50 PM (1,585,504,227,818), but actual MOD. DATE = 3/29/20, 5:50 PM (1,585,504,230,278), for file rndFile-1585504227819-664621259.dat
29-3-20 17:50:30.346 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event StatusEndSyncExternalEvent (org.syncany.operations.daemon.messages.StatusEndSyncExternalEvent@6926df51) ...
29-3-20 17:50:30.346 | AbstractTransfe | Test worker    | INFO : Looking for other running remote operations ...
29-3-20 17:50:30.352 | DatabaseConnect | Test worker    | INFO : Found 12 tables.
29-3-20 17:50:30.352 | LsRemoteOperati | Test worker    | INFO : 
29-3-20 17:50:30.352 | LsRemoteOperati | Test worker    | INFO : Running 'Remote Status' at client syncanyclientegXvnASNnTfmpEbUSSCr ...
29-3-20 17:50:30.352 | LsRemoteOperati | Test worker    | INFO : --------------------------------------------
29-3-20 17:50:30.352 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event LsRemoteStartSyncExternalEvent (org.syncany.operations.daemon.messages.LsRemoteStartSyncExternalEvent@63210e) ...
29-3-20 17:50:30.353 | LsRemoteOperati | Test worker    | INFO : Retrieving remote database list.
29-3-20 17:50:30.353 | LsRemoteOperati | Test worker    | INFO : - Remote database database-syncanyclientegXvnASNnTfmpEbUSSCr-0000000001 is already known (in local database). Ignoring.
29-3-20 17:50:30.353 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event LsRemoteEndSyncExternalEvent (org.syncany.operations.daemon.messages.LsRemoteEndSyncExternalEvent@b315d9f) ...
29-3-20 17:50:30.354 | UpOperation     | Test worker    | INFO : No remote changes, ready to upload.
29-3-20 17:50:30.354 | ActionFileHandl | Test worker    | INFO : Starting action for RemoteFile[name=action-up-syncanyclientegXvnASNnTfmpEbUSSCr-1585504230317] ...
29-3-20 17:50:30.354 | ActionFileHandl | Test worker    | INFO : Uploading action file: RemoteFile[name=action-up-syncanyclientegXvnASNnTfmpEbUSSCr-1585504230317]
29-3-20 17:50:30.356 | ActionFileHandl | Test worker    | INFO : Scheduling action renewal task for every 2 minutes, for RemoteFile[name=action-up-syncanyclientegXvnASNnTfmpEbUSSCr-1585504230317] ...
29-3-20 17:50:30.358 | DatabaseConnect | Test worker    | INFO : Found 12 tables.
29-3-20 17:50:30.358 | TransactionAwar | Test worker    | INFO : Done rolling back previous transactions.
29-3-20 17:50:30.364 | AsyncIndexer    | AsyncI/syncany | INFO : Starting Indexing.
29-3-20 17:50:30.364 | InternalEventBu | AsyncI/syncany | INFO : Event bus 'LocalEventBus': Posting event UpIndexStartSyncExternalEvent (org.syncany.operations.daemon.messages.UpIndexStartSyncExternalEvent@5d629bac) ...
29-3-20 17:50:30.365 | Indexer         | AsyncI/syncany | FINE : - Looking for deleted files ...
29-3-20 17:50:30.365 | Indexer         | AsyncI/syncany | FINE : - +File /tmp/syncanytest/syncany-200329175011381-69494-client-syncanyclient/rndFile-1585504227812-1312957789.dat
29-3-20 17:50:30.365 | Indexer         | AsyncI/syncany | FINE : - +FileContent: /tmp/syncanytest/syncany-200329175011381-69494-client-syncanyclient/rndFile-1585504227812-1312957789.dat
29-3-20 17:50:30.389 | Indexer         | AsyncI/syncany | FINE : - Chunk new: 9a4e1c8341e6e8c77d56a2cb39aef54432e31a15
29-3-20 17:50:30.389 | Indexer         | AsyncI/syncany | FINE : - +MultiChunk d53cff9720acb6d5431a3f2b248ac3ef2cd8df96
29-3-20 17:50:30.395 | Indexer         | AsyncI/syncany | FINE : - Chunk > MultiChunk: 9a4e1c8341e6e8c77d56a2cb39aef54432e31a15 > d53cff9720acb6d5431a3f2b248ac3ef2cd8df96
29-3-20 17:50:30.395 | Indexer         | AsyncI/syncany | FINE : - Chunk > FileContent: 9a4e1c8341e6e8c77d56a2cb39aef54432e31a15 > /tmp/syncanytest/syncany-200329175011381-69494-client-syncanyclient/rndFile-1585504227812-1312957789.dat
29-3-20 17:50:30.396 | Indexer         | AsyncI/syncany | FINE : - /File: rndFile-1585504227812-1312957789.dat (checksum 9a4e1c8341e6e8c77d56a2cb39aef54432e31a15)
29-3-20 17:50:30.408 | Indexer         | AsyncI/syncany | FINE :    * Found old file history 30cee6622c668aa9796f8299ea3ca7d2c930b8f4 (by path: rndFile-1585504227812-1312957789.dat), appending new version.
29-3-20 17:50:30.408 | FileVersionComp | AsyncI/syncany | INFO :      - [CHANGED_LAST_MOD_DATE]: Local file DIFFERS from file version, expected MOD. DATE = 3/29/20, 5:50 PM (1,585,504,230,278), but actual MOD. DATE = 3/29/20, 5:50 PM (1,585,504,227,814), for file rndFile-1585504227812-1312957789.dat
29-3-20 17:50:30.409 | FileVersionComp | AsyncI/syncany | INFO :      - [CHANGED_CHECKSUM, CHANGED_LAST_MOD_DATE]: Local file DIFFERS from file version, expected CHECKSUM = 9a4e1c8341e6e8c77d56a2cb39aef54432e31a15, but actual CHECKSUM = 428df1a710a0a346257c0268e5ae05dd783d844e, for file rndFile-1585504227812-1312957789.dat
29-3-20 17:50:30.409 | Indexer         | AsyncI/syncany | INFO :    * Added file version:    FileVersion [version=2, path=rndFile-1585504227812-1312957789.dat, type=FILE, status=CHANGED, size=512000, lastModified=Sun Mar 29 17:50:30 UTC 2020, linkTarget=null, checksum=9a4e1c8341e6e8c77d56a2cb39aef54432e31a15, updated=Sun Mar 29 17:50:30 UTC 2020, posixPermissions=rw-rw-r--, dosAttributes=--a-]
29-3-20 17:50:30.409 | Indexer         | AsyncI/syncany | INFO :      based on file version: FileVersion [version=1, path=rndFile-1585504227812-1312957789.dat, type=FILE, status=NEW, size=512000, lastModified=Sun Mar 29 17:50:27 UTC 2020, linkTarget=null, checksum=428df1a710a0a346257c0268e5ae05dd783d844e, updated=Sun Mar 29 17:50:28 UTC 2020, posixPermissions=rw-rw-r--, dosAttributes=--a-]
29-3-20 17:50:30.409 | InternalEventBu | AsyncI/syncany | INFO : Event bus 'LocalEventBus': Posting event UpIndexChangesDetectedSyncExternalEvent (org.syncany.operations.daemon.messages.UpIndexChangesDetectedSyncExternalEvent@5e687b86) ...
29-3-20 17:50:30.411 | Indexer         | AsyncI/syncany | FINE : - +File /tmp/syncanytest/syncany-200329175011381-69494-client-syncanyclient/rndFile-1585504227819-664621259.dat
29-3-20 17:50:30.411 | Indexer         | AsyncI/syncany | FINE : - +FileContent: /tmp/syncanytest/syncany-200329175011381-69494-client-syncanyclient/rndFile-1585504227819-664621259.dat
29-3-20 17:50:30.419 | Indexer         | AsyncI/syncany | FINE : - Chunk new: c6f6379806c98adab919b0fed2c5fd2aaa8e2f86
29-3-20 17:50:30.425 | Indexer         | AsyncI/syncany | FINE : - Chunk > MultiChunk: c6f6379806c98adab919b0fed2c5fd2aaa8e2f86 > d53cff9720acb6d5431a3f2b248ac3ef2cd8df96
29-3-20 17:50:30.425 | Indexer         | AsyncI/syncany | FINE : - Chunk > FileContent: c6f6379806c98adab919b0fed2c5fd2aaa8e2f86 > /tmp/syncanytest/syncany-200329175011381-69494-client-syncanyclient/rndFile-1585504227819-664621259.dat
29-3-20 17:50:30.426 | Indexer         | AsyncI/syncany | FINE : - /File: rndFile-1585504227819-664621259.dat (checksum c6f6379806c98adab919b0fed2c5fd2aaa8e2f86)
29-3-20 17:50:30.436 | Indexer         | AsyncI/syncany | FINE :    * Found old file history bc3136c544bda320ff7a0fb9462feec3bc0c913b (by path: rndFile-1585504227819-664621259.dat), appending new version.
29-3-20 17:50:30.444 | FileVersionComp | AsyncI/syncany | INFO :      - [CHANGED_LAST_MOD_DATE]: Local file DIFFERS from file version, expected MOD. DATE = 3/29/20, 5:50 PM (1,585,504,230,278), but actual MOD. DATE = 3/29/20, 5:50 PM (1,585,504,227,818), for file rndFile-1585504227819-664621259.dat
29-3-20 17:50:30.444 | FileVersionComp | AsyncI/syncany | INFO :      - [CHANGED_CHECKSUM, CHANGED_LAST_MOD_DATE]: Local file DIFFERS from file version, expected CHECKSUM = c6f6379806c98adab919b0fed2c5fd2aaa8e2f86, but actual CHECKSUM = 307e391dbb649f6d1615bfbf6d323c97b4223cf4, for file rndFile-1585504227819-664621259.dat
29-3-20 17:50:30.445 | Indexer         | AsyncI/syncany | INFO :    * Added file version:    FileVersion [version=2, path=rndFile-1585504227819-664621259.dat, type=FILE, status=CHANGED, size=512000, lastModified=Sun Mar 29 17:50:30 UTC 2020, linkTarget=null, checksum=c6f6379806c98adab919b0fed2c5fd2aaa8e2f86, updated=Sun Mar 29 17:50:30 UTC 2020, posixPermissions=rw-rw-r--, dosAttributes=--a-]
29-3-20 17:50:30.445 | Indexer         | AsyncI/syncany | INFO :      based on file version: FileVersion [version=1, path=rndFile-1585504227819-664621259.dat, type=FILE, status=NEW, size=512000, lastModified=Sun Mar 29 17:50:27 UTC 2020, linkTarget=null, checksum=307e391dbb649f6d1615bfbf6d323c97b4223cf4, updated=Sun Mar 29 17:50:28 UTC 2020, posixPermissions=rw-rw-r--, dosAttributes=--a-]
29-3-20 17:50:30.446 | Indexer         | AsyncI/syncany | FINE : - +File /tmp/syncanytest/syncany-200329175011381-69494-client-syncanyclient/rndFile-1585504227822-1894991921.dat
29-3-20 17:50:30.446 | Indexer         | AsyncI/syncany | FINE : - +FileContent: /tmp/syncanytest/syncany-200329175011381-69494-client-syncanyclient/rndFile-1585504227822-1894991921.dat
29-3-20 17:50:30.453 | Indexer         | AsyncI/syncany | FINE : - Chunk new: 4e3f49825d8f54779cf932eae218ea1c5b57ec8d
29-3-20 17:50:30.462 | Indexer         | AsyncI/syncany | FINE : - Chunk > MultiChunk: 4e3f49825d8f54779cf932eae218ea1c5b57ec8d > d53cff9720acb6d5431a3f2b248ac3ef2cd8df96
29-3-20 17:50:30.462 | Indexer         | AsyncI/syncany | FINE : - Chunk > FileContent: 4e3f49825d8f54779cf932eae218ea1c5b57ec8d > /tmp/syncanytest/syncany-200329175011381-69494-client-syncanyclient/rndFile-1585504227822-1894991921.dat
29-3-20 17:50:30.462 | Indexer         | AsyncI/syncany | FINE : - /File: rndFile-1585504227822-1894991921.dat (checksum 4e3f49825d8f54779cf932eae218ea1c5b57ec8d)
29-3-20 17:50:30.465 | Indexer         | AsyncI/syncany | FINE :    * Found old file history d90b9fa057bb9816da2d32f8fa959ff85c0b7666 (by path: rndFile-1585504227822-1894991921.dat), appending new version.
29-3-20 17:50:30.466 | FileVersionComp | AsyncI/syncany | INFO :      - [CHANGED_LAST_MOD_DATE]: Local file DIFFERS from file version, expected MOD. DATE = 3/29/20, 5:50 PM (1,585,504,230,278), but actual MOD. DATE = 3/29/20, 5:50 PM (1,585,504,227,834), for file rndFile-1585504227822-1894991921.dat
29-3-20 17:50:30.466 | FileVersionComp | AsyncI/syncany | INFO :      - [CHANGED_CHECKSUM, CHANGED_LAST_MOD_DATE]: Local file DIFFERS from file version, expected CHECKSUM = 4e3f49825d8f54779cf932eae218ea1c5b57ec8d, but actual CHECKSUM = f296f3041de6b778100d80be21a67fe2917bfd8e, for file rndFile-1585504227822-1894991921.dat
29-3-20 17:50:30.470 | Indexer         | AsyncI/syncany | INFO :    * Added file version:    FileVersion [version=2, path=rndFile-1585504227822-1894991921.dat, type=FILE, status=CHANGED, size=512000, lastModified=Sun Mar 29 17:50:30 UTC 2020, linkTarget=null, checksum=4e3f49825d8f54779cf932eae218ea1c5b57ec8d, updated=Sun Mar 29 17:50:30 UTC 2020, posixPermissions=rw-rw-r--, dosAttributes=--a-]
29-3-20 17:50:30.470 | Indexer         | AsyncI/syncany | INFO :      based on file version: FileVersion [version=1, path=rndFile-1585504227822-1894991921.dat, type=FILE, status=NEW, size=512000, lastModified=Sun Mar 29 17:50:27 UTC 2020, linkTarget=null, checksum=f296f3041de6b778100d80be21a67fe2917bfd8e, updated=Sun Mar 29 17:50:28 UTC 2020, posixPermissions=rw-rw-r--, dosAttributes=--a-]
29-3-20 17:50:30.473 | Indexer         | AsyncI/syncany | FINE : - /MultiChunk d53cff9720acb6d5431a3f2b248ac3ef2cd8df96
29-3-20 17:50:30.473 | InternalEventBu | AsyncI/syncany | INFO : Event bus 'LocalEventBus': Posting event UpIndexEndSyncExternalEvent (org.syncany.operations.daemon.messages.UpIndexEndSyncExternalEvent@56f73fb) ...
29-3-20 17:50:30.473 | Indexer         | AsyncI/syncany | FINE : Processed new database version: DatabaseVersion [header=UnknownMachine/()/T=1585504230364, chunks=3, multiChunks=1, fileContents=3, fileHistories=3]
29-3-20 17:50:30.474 | UpOperation     | Test worker    | INFO : No DIRTY data found in database (no dirty databases); Nothing to do here.
29-3-20 17:50:30.475 | UpOperation     | Test worker    | INFO : Last vector clock was: (syncanyclientegXvnASNnTfmpEbUSSCr1)
29-3-20 17:50:30.476 | UpOperation     | Test worker    | INFO : Uploading new multichunks ...
29-3-20 17:50:30.476 | UpOperation     | Test worker    | INFO : - Uploading multichunk d53cff9720acb6d5431a3f2b248ac3ef2cd8df96 from /tmp/syncanytest/syncany-200329175011381-69494-client-syncanyclient/.syncany/cache/multichunk-d53cff9720acb6d5431a3f2b248ac3ef2cd8df96 to RemoteFile[name=multichunk-d53cff9720acb6d5431a3f2b248ac3ef2cd8df96] ...
29-3-20 17:50:30.477 | RemoteTransacti | Test worker    | INFO : - Adding file to TX for UPLOAD: /tmp/syncanytest/syncany-200329175011381-69494-client-syncanyclient/.syncany/cache/multichunk-d53cff9720acb6d5431a3f2b248ac3ef2cd8df96 -> Temp. remote file: RemoteFile[name=temp-BASYt-multichunk-d53cff9720acb6d5431a3f2b248ac3ef2cd8df96], final location: RemoteFile[name=multichunk-d53cff9720acb6d5431a3f2b248ac3ef2cd8df96]
29-3-20 17:50:30.478 | UpOperation     | Test worker    | INFO : Uploading database: DatabaseVersion [header=syncanyclientegXvnASNnTfmpEbUSSCr/(syncanyclientegXvnASNnTfmpEbUSSCr2)/T=1585504230476, chunks=3, multiChunks=1, fileContents=3, fileHistories=3]
29-3-20 17:50:30.479 | UpOperation     | Test worker    | INFO : Saving local delta database, version syncanyclientegXvnASNnTfmpEbUSSCr/(syncanyclientegXvnASNnTfmpEbUSSCr2)/T=1585504230476 to file /tmp/syncanytest/syncany-200329175011381-69494-client-syncanyclient/.syncany/cache/database-syncanyclientegXvnASNnTfmpEbUSSCr-0000000002 ... 
29-3-20 17:50:30.479 | UpOperation     | Test worker    | INFO : - Saving database to /tmp/syncanytest/syncany-200329175011381-69494-client-syncanyclient/.syncany/cache/database-syncanyclientegXvnASNnTfmpEbUSSCr-0000000002 ...
29-3-20 17:50:30.481 | UpOperation     | Test worker    | INFO : - Uploading local delta database file ...
29-3-20 17:50:30.482 | UpOperation     | Test worker    | INFO : - Uploading /tmp/syncanytest/syncany-200329175011381-69494-client-syncanyclient/.syncany/cache/database-syncanyclientegXvnASNnTfmpEbUSSCr-0000000002 to RemoteFile[name=database-syncanyclientegXvnASNnTfmpEbUSSCr-0000000002] ...
29-3-20 17:50:30.482 | RemoteTransacti | Test worker    | INFO : - Adding file to TX for UPLOAD: /tmp/syncanytest/syncany-200329175011381-69494-client-syncanyclient/.syncany/cache/database-syncanyclientegXvnASNnTfmpEbUSSCr-0000000002 -> Temp. remote file: RemoteFile[name=temp-CwSQv-database-syncanyclientegXvnASNnTfmpEbUSSCr-0000000002], final location: RemoteFile[name=database-syncanyclientegXvnASNnTfmpEbUSSCr-0000000002]
29-3-20 17:50:30.483 | UpOperation     | Test worker    | INFO : Adding shutdown hook (to allow resuming the upload) ...
29-3-20 17:50:30.483 | RemoteTransacti | Test worker    | INFO : Starting TX.commit() ...
29-3-20 17:50:30.487 | RemoteTransacti | Test worker    | INFO : Wrote transaction manifest to temporary file: /tmp/syncanytest/syncany-200329175011381-69494-client-syncanyclient/.syncany/cache/temp-transaction-6903745677960221027.tmp
29-3-20 17:50:30.483 | InternalEventBu | AsyncI/syncany | INFO : Event bus 'LocalEventBus': Posting event UpIndexMidSyncExternalEvent (org.syncany.operations.daemon.messages.UpIndexMidSyncExternalEvent@8fb2eeb) ...
29-3-20 17:50:30.490 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event UpUploadFileSyncExternalEvent (org.syncany.operations.daemon.messages.UpUploadFileSyncExternalEvent@374c58ec) ...
29-3-20 17:50:30.490 | RemoteTransacti | Test worker    | INFO : - Uploading remote transaction file RemoteFile[name=transaction-28f04c89] ...
29-3-20 17:50:30.490 | RemoteTransacti | Test worker    | INFO : - Starting to upload data in commit.
29-3-20 17:50:30.491 | AsyncIndexer    | AsyncI/syncany | INFO : Stopping indexing. Signal end of stream with empty databaseversion
29-3-20 17:50:30.492 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event UpUploadFileInTransactionSyncExternalEvent (org.syncany.operations.daemon.messages.UpUploadFileInTransactionSyncExternalEvent@7d993f4f) ...
29-3-20 17:50:30.492 | RemoteTransacti | Test worker    | INFO : - Uploading /tmp/syncanytest/syncany-200329175011381-69494-client-syncanyclient/.syncany/cache/multichunk-d53cff9720acb6d5431a3f2b248ac3ef2cd8df96 to temp. file RemoteFile[name=temp-BASYt-multichunk-d53cff9720acb6d5431a3f2b248ac3ef2cd8df96] ...
29-3-20 17:50:30.494 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event UpUploadFileInTransactionSyncExternalEvent (org.syncany.operations.daemon.messages.UpUploadFileInTransactionSyncExternalEvent@28410f88) ...
29-3-20 17:50:30.494 | RemoteTransacti | Test worker    | INFO : - Uploading /tmp/syncanytest/syncany-200329175011381-69494-client-syncanyclient/.syncany/cache/database-syncanyclientegXvnASNnTfmpEbUSSCr-0000000002 to temp. file RemoteFile[name=temp-CwSQv-database-syncanyclientegXvnASNnTfmpEbUSSCr-0000000002] ...
29-3-20 17:50:30.496 | RemoteTransacti | Test worker    | INFO : - Moving temp. file RemoteFile[name=temp-BASYt-multichunk-d53cff9720acb6d5431a3f2b248ac3ef2cd8df96] to final location RemoteFile[name=multichunk-d53cff9720acb6d5431a3f2b248ac3ef2cd8df96] ...
29-3-20 17:50:30.496 | RemoteTransacti | Test worker    | INFO : - Moving temp. file RemoteFile[name=temp-CwSQv-database-syncanyclientegXvnASNnTfmpEbUSSCr-0000000002] to final location RemoteFile[name=database-syncanyclientegXvnASNnTfmpEbUSSCr-0000000002] ...
29-3-20 17:50:30.496 | RemoteTransacti | Test worker    | INFO : - Deleting remote transaction file RemoteFile[name=transaction-28f04c89] ...
29-3-20 17:50:30.497 | RemoteTransacti | Test worker    | INFO : END of TX.commmit(): Succesfully committed transaction.
29-3-20 17:50:30.497 | RemoteTransacti | Test worker    | INFO : END of TX.delTemp(): Sucessfully deleted final files.
29-3-20 17:50:30.497 | UpOperation     | Test worker    | INFO : Persisting local SQL database (new database version syncanyclientegXvnASNnTfmpEbUSSCr/(syncanyclientegXvnASNnTfmpEbUSSCr2)/T=1585504230476) ...
29-3-20 17:50:30.521 | UpOperation     | Test worker    | INFO : Removing DIRTY database versions from database ...
29-3-20 17:50:30.535 | UpOperation     | Test worker    | INFO : Adding database version to result changes:DatabaseVersion [header=syncanyclientegXvnASNnTfmpEbUSSCr/(syncanyclientegXvnASNnTfmpEbUSSCr2)/T=1585504230476, chunks=3, multiChunks=1, fileContents=3, fileHistories=3]
29-3-20 17:50:30.535 | UpOperation     | Test worker    | INFO : Committing local database.
29-3-20 17:50:30.535 | UpOperation     | Test worker    | FINE : Waiting for new database version.
29-3-20 17:50:30.536 | UpOperation     | Test worker    | FINE : Took new database version: DatabaseVersion [header=UnknownMachine/()/T=1585504230491, chunks=0, multiChunks=0, fileContents=0, fileHistories=0]
29-3-20 17:50:30.536 | UpOperation     | Test worker    | INFO : Sync up done.
29-3-20 17:50:30.537 | ActionFileHandl | Test worker    | INFO : Finishing action for RemoteFile[name=action-up-syncanyclientegXvnASNnTfmpEbUSSCr-1585504230317] ...
29-3-20 17:50:30.538 | ActionFileHandl | Test worker    | INFO : Deleting action file: RemoteFile[name=action-up-syncanyclientegXvnASNnTfmpEbUSSCr-1585504230317]
29-3-20 17:50:30.538 | AbstractTransfe | Test worker    | INFO : Cleaning own old action files ...
29-3-20 17:50:30.538 | Cache           | Test worker    | INFO : Cache size okay (3007 KB), no need to clean (keep size is 500 MB)
29-3-20 17:50:30.538 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event UpEndSyncExternalEvent (org.syncany.operations.daemon.messages.UpEndSyncExternalEvent@f27915b) ...
29-3-20 17:50:30.541 | DatabaseConnect | Test worker    | INFO : Found 12 tables.
29-3-20 17:50:30.541 | StatusOperation | Test worker    | INFO : 
29-3-20 17:50:30.541 | StatusOperation | Test worker    | INFO : Running 'Status' at client syncanyclientegXvnASNnTfmpEbUSSCr ...
29-3-20 17:50:30.541 | StatusOperation | Test worker    | INFO : --------------------------------------------
29-3-20 17:50:30.541 | StatusOperation | Test worker    | INFO : Querying current file tree from database ...
29-3-20 17:50:30.541 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event StatusStartSyncExternalEvent (org.syncany.operations.daemon.messages.StatusStartSyncExternalEvent@48183fa) ...
29-3-20 17:50:30.551 | StatusOperation | Test worker    | INFO : Analyzing local folder /tmp/syncanytest/syncany-200329175011381-69494-client-syncanyclient ...
29-3-20 17:50:30.552 | StatusOperation | Test worker    | FINE : - Ignoring file (syncany app-related): .syncany
29-3-20 17:50:30.553 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event StatusEndSyncExternalEvent (org.syncany.operations.daemon.messages.StatusEndSyncExternalEvent@25fd2574) ...
29-3-20 17:50:30.560 | org.syncany.plu | Test worker    | FINE : Validating required fields
29-3-20 17:50:30.561 | LocalTransferMa | Test worker    | INFO : testTargetExists: Target exists.
29-3-20 17:50:30.574 | org.syncany.plu | Test worker    | FINE : Validating required fields
29-3-20 17:50:30.575 | TransferManager | Test worker    | INFO : Building LocalTransferManager from config 'syncany-200329175011381-99607-client-syncanyclient' ...
29-3-20 17:50:30.577 | TransferManager | Test worker    | INFO : - With feature ReadAfterWriteConsistent
29-3-20 17:50:30.577 | TransferManager | Test worker    | INFO : - With feature Retriable
29-3-20 17:50:30.577 | TransferManager | Test worker    | FINE : - Checking if selected features supported ...
29-3-20 17:50:30.577 | TransferManager | Test worker    | FINE : - Checking for duplicate features ...
29-3-20 17:50:30.577 | TransferManager | Test worker    | FINE : - Checking required feature TransactionAware in LocalTransferManager ...
29-3-20 17:50:30.578 | TransferManager | Test worker    | FINE : - Checking required feature Retriable in LocalTransferManager ...
29-3-20 17:50:30.578 | TransferManager | Test worker    | INFO : - SKIPPING unsupported optional feature ReadAfterWriteConsistent
29-3-20 17:50:30.578 | TransferManager | Test worker    | FINE : - Wrapping TransferManager LocalTransferManager in RetriableFeatureTransferManager
29-3-20 17:50:30.578 | org.syncany.plu | Test worker    | FINE : Validating required fields
29-3-20 17:50:30.580 | TransferManager | Test worker    | INFO : Building LocalTransferManager from config 'syncany-200329175011381-99607-client-syncanyclient' ...
29-3-20 17:50:30.581 | TransferManager | Test worker    | INFO : - With feature ReadAfterWriteConsistent
29-3-20 17:50:30.581 | TransferManager | Test worker    | INFO : - With feature Retriable
29-3-20 17:50:30.582 | TransferManager | Test worker    | INFO : - With feature PathAware
29-3-20 17:50:30.582 | TransferManager | Test worker    | INFO : - With feature TransactionAware
29-3-20 17:50:30.582 | TransferManager | Test worker    | FINE : - Checking if selected features supported ...
29-3-20 17:50:30.582 | TransferManager | Test worker    | FINE : - Checking for duplicate features ...
29-3-20 17:50:30.582 | TransferManager | Test worker    | FINE : - Checking required feature TransactionAware in LocalTransferManager ...
29-3-20 17:50:30.582 | TransferManager | Test worker    | FINE : - Checking required feature Retriable in LocalTransferManager ...
29-3-20 17:50:30.583 | TransferManager | Test worker    | INFO : - SKIPPING unsupported optional feature ReadAfterWriteConsistent
29-3-20 17:50:30.584 | TransferManager | Test worker    | FINE : - Wrapping TransferManager LocalTransferManager in RetriableFeatureTransferManager
29-3-20 17:50:30.584 | TransferManager | Test worker    | INFO : - SKIPPING unsupported optional feature PathAware
29-3-20 17:50:30.584 | TransferManager | Test worker    | FINE : - Wrapping TransferManager RetriableFeatureTransferManager in TransactionAwareFeatureTransferManager
29-3-20 17:50:30.636 | hsqldb.db.HSQLD | Test worker    | INFO : Checkpoint start
29-3-20 17:50:30.637 | hsqldb.db.HSQLD | Test worker    | INFO : checkpointClose start
29-3-20 17:50:30.646 | hsqldb.db.HSQLD | Test worker    | FINE : log close size: 0
29-3-20 17:50:30.648 | hsqldb.db.HSQLD | Test worker    | INFO : checkpointClose end
29-3-20 17:50:30.648 | hsqldb.db.HSQLD | Test worker    | INFO : Checkpoint end - txts: 1
29-3-20 17:50:30.653 | DatabaseConnect | Test worker    | INFO : Found 0 tables.
29-3-20 17:50:30.653 | DatabaseConnect | Test worker    | INFO : Database has no tables. Creating tables from script.create.all.sql
29-3-20 17:50:30.653 | SqlRunner       | Test worker    | INFO : SQL (comment): -- Tables
29-3-20 17:50:30.653 | 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:50:30.656 | 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:50:30.658 | 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:50:30.660 | 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:50:30.662 | 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:50:30.665 | 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:50:30.667 | 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:50:30.670 | 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:50:30.672 | 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:50:30.674 | 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:50:30.676 | 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:50:30.678 | 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:50:30.679 | SqlRunner       | Test worker    | INFO : SQL (comment): -- Non-primary indices
29-3-20 17:50:30.679 | SqlRunner       | Test worker    | INFO : SQL:   CREATE INDEX idx_databaseversion_status ON databaseversion (status); 
29-3-20 17:50:30.680 | hsqldb.db.HSQLD | Test worker    | INFO : dataFileCache open start
29-3-20 17:50:30.682 | hsqldb.db.HSQLD | Test worker    | INFO : dataFileCache open end
29-3-20 17:50:30.684 | SqlRunner       | Test worker    | INFO : SQL: CREATE INDEX idx_databaseversion_vectorclock_serialized ON databaseversion (vectorclock_serialized); 
29-3-20 17:50:30.685 | SqlRunner       | Test worker    | INFO : SQL: CREATE INDEX idx_fileversion_path ON fileversion (path); 
29-3-20 17:50:30.687 | SqlRunner       | Test worker    | INFO : SQL: CREATE INDEX idx_fileversion_status ON fileversion (status); 
29-3-20 17:50:30.688 | SqlRunner       | Test worker    | INFO : SQL: CREATE INDEX idx_fileversion_filecontent_checksum ON fileversion (filecontent_checksum); 
29-3-20 17:50:30.689 | SqlRunner       | Test worker    | INFO : SQL (comment): -- Views
29-3-20 17:50:30.690 | 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:50:30.692 | 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:50:30.694 | 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:50:30.696 | 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:50:30.700 | SqlRunner       | Test worker    | INFO : SQL (comment): -- Full Views
29-3-20 17:50:30.700 | 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:50:30.703 | 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:50:30.705 | SqlRunner       | Test worker    | INFO : SQL (comment): -- Functions
29-3-20 17:50:30.705 | SqlRunner       | Test worker    | INFO : SQL (new delimiter): end;
29-3-20 17:50:30.705 | 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:50:30.712 | UpOperation     | Test worker    | INFO : 
29-3-20 17:50:30.712 | UpOperation     | Test worker    | INFO : Running 'Sync up' at client syncanyclientKTkSsHrbRQsaJmshiKXb ...
29-3-20 17:50:30.712 | UpOperation     | Test worker    | INFO : --------------------------------------------
29-3-20 17:50:30.712 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event UpStartSyncExternalEvent (org.syncany.operations.daemon.messages.UpStartSyncExternalEvent@548fc25a) ...
29-3-20 17:50:30.714 | DatabaseConnect | Test worker    | INFO : Found 12 tables.
29-3-20 17:50:30.714 | StatusOperation | Test worker    | INFO : 
29-3-20 17:50:30.714 | StatusOperation | Test worker    | INFO : Running 'Status' at client syncanyclientKTkSsHrbRQsaJmshiKXb ...
29-3-20 17:50:30.714 | StatusOperation | Test worker    | INFO : --------------------------------------------
29-3-20 17:50:30.714 | StatusOperation | Test worker    | INFO : Querying current file tree from database ...
29-3-20 17:50:30.714 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event StatusStartSyncExternalEvent (org.syncany.operations.daemon.messages.StatusStartSyncExternalEvent@52757687) ...
29-3-20 17:50:30.715 | StatusOperation | Test worker    | INFO : Analyzing local folder /tmp/syncanytest/syncany-200329175011381-99607-client-syncanyclient ...
29-3-20 17:50:30.716 | StatusOperation | Test worker    | FINE : - Ignoring file (syncany app-related): .syncany
29-3-20 17:50:30.716 | StatusOperation | Test worker    | FINE : - New file: rndFile-1585504230572-2049990084.dat
29-3-20 17:50:30.716 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event StatusEndSyncExternalEvent (org.syncany.operations.daemon.messages.StatusEndSyncExternalEvent@31598899) ...
29-3-20 17:50:30.716 | AbstractTransfe | Test worker    | INFO : Looking for other running remote operations ...
29-3-20 17:50:30.718 | DatabaseConnect | Test worker    | INFO : Found 12 tables.
29-3-20 17:50:30.720 | LsRemoteOperati | Test worker    | INFO : 
29-3-20 17:50:30.720 | LsRemoteOperati | Test worker    | INFO : Running 'Remote Status' at client syncanyclientKTkSsHrbRQsaJmshiKXb ...
29-3-20 17:50:30.720 | LsRemoteOperati | Test worker    | INFO : --------------------------------------------
29-3-20 17:50:30.720 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event LsRemoteStartSyncExternalEvent (org.syncany.operations.daemon.messages.LsRemoteStartSyncExternalEvent@466e47ae) ...
29-3-20 17:50:30.720 | LsRemoteOperati | Test worker    | INFO : Retrieving remote database list.
29-3-20 17:50:30.721 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event LsRemoteEndSyncExternalEvent (org.syncany.operations.daemon.messages.LsRemoteEndSyncExternalEvent@6433182f) ...
29-3-20 17:50:30.721 | UpOperation     | Test worker    | INFO : No remote changes, ready to upload.
29-3-20 17:50:30.721 | ActionFileHandl | Test worker    | INFO : Starting action for RemoteFile[name=action-up-syncanyclientKTkSsHrbRQsaJmshiKXb-1585504230584] ...
29-3-20 17:50:30.721 | ActionFileHandl | Test worker    | INFO : Uploading action file: RemoteFile[name=action-up-syncanyclientKTkSsHrbRQsaJmshiKXb-1585504230584]
29-3-20 17:50:30.724 | ActionFileHandl | Test worker    | INFO : Scheduling action renewal task for every 2 minutes, for RemoteFile[name=action-up-syncanyclientKTkSsHrbRQsaJmshiKXb-1585504230584] ...
29-3-20 17:50:30.726 | DatabaseConnect | Test worker    | INFO : Found 12 tables.
29-3-20 17:50:30.732 | TransactionAwar | Test worker    | INFO : Done rolling back previous transactions.
29-3-20 17:50:30.732 | AsyncIndexer    | AsyncI/syncany | INFO : Starting Indexing.
29-3-20 17:50:30.732 | InternalEventBu | AsyncI/syncany | INFO : Event bus 'LocalEventBus': Posting event UpIndexStartSyncExternalEvent (org.syncany.operations.daemon.messages.UpIndexStartSyncExternalEvent@22d49a1f) ...
29-3-20 17:50:30.732 | Indexer         | AsyncI/syncany | FINE : - Looking for deleted files ...
29-3-20 17:50:30.732 | Indexer         | AsyncI/syncany | FINE : - +File /tmp/syncanytest/syncany-200329175011381-99607-client-syncanyclient/rndFile-1585504230572-2049990084.dat
29-3-20 17:50:30.732 | Indexer         | AsyncI/syncany | FINE : - +FileContent: /tmp/syncanytest/syncany-200329175011381-99607-client-syncanyclient/rndFile-1585504230572-2049990084.dat
29-3-20 17:50:30.737 | Indexer         | AsyncI/syncany | FINE : - Chunk new: 69742fd13f1a7d143ce7001a906e83de37908e59
29-3-20 17:50:30.737 | Indexer         | AsyncI/syncany | FINE : - +MultiChunk 003c582e8d4672462d25a1dbd3f6480332cbb7bb
29-3-20 17:50:30.738 | Indexer         | AsyncI/syncany | FINE : - Chunk > MultiChunk: 69742fd13f1a7d143ce7001a906e83de37908e59 > 003c582e8d4672462d25a1dbd3f6480332cbb7bb
29-3-20 17:50:30.738 | Indexer         | AsyncI/syncany | FINE : - Chunk > FileContent: 69742fd13f1a7d143ce7001a906e83de37908e59 > /tmp/syncanytest/syncany-200329175011381-99607-client-syncanyclient/rndFile-1585504230572-2049990084.dat
29-3-20 17:50:30.738 | Indexer         | AsyncI/syncany | FINE : - /File: rndFile-1585504230572-2049990084.dat (checksum 69742fd13f1a7d143ce7001a906e83de37908e59)
29-3-20 17:50:30.741 | Indexer         | AsyncI/syncany | FINE :    * No old file history found, starting new history (path: rndFile-1585504230572-2049990084.dat, checksum: 69742fd13f1a7d143ce7001a906e83de37908e59)
29-3-20 17:50:30.742 | FileVersionComp | AsyncI/syncany | INFO :      - [DELETED]: Local file DIFFERS from file version, actual file is NULL, for file rndFile-1585504230572-2049990084.dat
29-3-20 17:50:30.742 | Indexer         | AsyncI/syncany | INFO :    * Added file version:    FileVersion [version=1, path=rndFile-1585504230572-2049990084.dat, type=FILE, status=NEW, size=40, lastModified=Sun Mar 29 17:50:30 UTC 2020, linkTarget=null, checksum=69742fd13f1a7d143ce7001a906e83de37908e59, updated=Sun Mar 29 17:50:30 UTC 2020, posixPermissions=rw-rw-r--, dosAttributes=--a-]
29-3-20 17:50:30.742 | Indexer         | AsyncI/syncany | INFO :      based on file version: null
29-3-20 17:50:30.742 | InternalEventBu | AsyncI/syncany | INFO : Event bus 'LocalEventBus': Posting event UpIndexChangesDetectedSyncExternalEvent (org.syncany.operations.daemon.messages.UpIndexChangesDetectedSyncExternalEvent@711625f) ...
29-3-20 17:50:30.744 | Indexer         | AsyncI/syncany | FINE : - /MultiChunk 003c582e8d4672462d25a1dbd3f6480332cbb7bb
29-3-20 17:50:30.744 | InternalEventBu | AsyncI/syncany | INFO : Event bus 'LocalEventBus': Posting event UpIndexEndSyncExternalEvent (org.syncany.operations.daemon.messages.UpIndexEndSyncExternalEvent@5f5d29b7) ...
29-3-20 17:50:30.744 | Indexer         | AsyncI/syncany | FINE : Processed new database version: DatabaseVersion [header=UnknownMachine/()/T=1585504230732, chunks=1, multiChunks=1, fileContents=1, fileHistories=1]
29-3-20 17:50:30.745 | UpOperation     | Test worker    | INFO : No DIRTY data found in database (no dirty databases); Nothing to do here.
29-3-20 17:50:30.745 | UpOperation     | Test worker    | INFO : Last vector clock was: ()
29-3-20 17:50:30.746 | UpOperation     | Test worker    | INFO : Uploading new multichunks ...
29-3-20 17:50:30.747 | UpOperation     | Test worker    | INFO : - Uploading multichunk 003c582e8d4672462d25a1dbd3f6480332cbb7bb from /tmp/syncanytest/syncany-200329175011381-99607-client-syncanyclient/.syncany/cache/multichunk-003c582e8d4672462d25a1dbd3f6480332cbb7bb to RemoteFile[name=multichunk-003c582e8d4672462d25a1dbd3f6480332cbb7bb] ...
29-3-20 17:50:30.747 | RemoteTransacti | Test worker    | INFO : - Adding file to TX for UPLOAD: /tmp/syncanytest/syncany-200329175011381-99607-client-syncanyclient/.syncany/cache/multichunk-003c582e8d4672462d25a1dbd3f6480332cbb7bb -> Temp. remote file: RemoteFile[name=temp-Kiiaq-multichunk-003c582e8d4672462d25a1dbd3f6480332cbb7bb], final location: RemoteFile[name=multichunk-003c582e8d4672462d25a1dbd3f6480332cbb7bb]
29-3-20 17:50:30.747 | UpOperation     | Test worker    | INFO : Uploading database: DatabaseVersion [header=syncanyclientKTkSsHrbRQsaJmshiKXb/(syncanyclientKTkSsHrbRQsaJmshiKXb1)/T=1585504230746, chunks=1, multiChunks=1, fileContents=1, fileHistories=1]
29-3-20 17:50:30.748 | UpOperation     | Test worker    | INFO : Saving local delta database, version syncanyclientKTkSsHrbRQsaJmshiKXb/(syncanyclientKTkSsHrbRQsaJmshiKXb1)/T=1585504230746 to file /tmp/syncanytest/syncany-200329175011381-99607-client-syncanyclient/.syncany/cache/database-syncanyclientKTkSsHrbRQsaJmshiKXb-0000000001 ... 
29-3-20 17:50:30.748 | UpOperation     | Test worker    | INFO : - Saving database to /tmp/syncanytest/syncany-200329175011381-99607-client-syncanyclient/.syncany/cache/database-syncanyclientKTkSsHrbRQsaJmshiKXb-0000000001 ...
29-3-20 17:50:30.749 | UpOperation     | Test worker    | INFO : - Uploading local delta database file ...
29-3-20 17:50:30.749 | UpOperation     | Test worker    | INFO : - Uploading /tmp/syncanytest/syncany-200329175011381-99607-client-syncanyclient/.syncany/cache/database-syncanyclientKTkSsHrbRQsaJmshiKXb-0000000001 to RemoteFile[name=database-syncanyclientKTkSsHrbRQsaJmshiKXb-0000000001] ...
29-3-20 17:50:30.749 | RemoteTransacti | Test worker    | INFO : - Adding file to TX for UPLOAD: /tmp/syncanytest/syncany-200329175011381-99607-client-syncanyclient/.syncany/cache/database-syncanyclientKTkSsHrbRQsaJmshiKXb-0000000001 -> Temp. remote file: RemoteFile[name=temp-mNUCo-database-syncanyclientKTkSsHrbRQsaJmshiKXb-0000000001], final location: RemoteFile[name=database-syncanyclientKTkSsHrbRQsaJmshiKXb-0000000001]
29-3-20 17:50:30.750 | UpOperation     | Test worker    | INFO : Adding shutdown hook (to allow resuming the upload) ...
29-3-20 17:50:30.750 | RemoteTransacti | Test worker    | INFO : Starting TX.commit() ...
29-3-20 17:50:30.750 | InternalEventBu | AsyncI/syncany | INFO : Event bus 'LocalEventBus': Posting event UpIndexMidSyncExternalEvent (org.syncany.operations.daemon.messages.UpIndexMidSyncExternalEvent@519d44cf) ...
29-3-20 17:50:30.754 | RemoteTransacti | Test worker    | INFO : Wrote transaction manifest to temporary file: /tmp/syncanytest/syncany-200329175011381-99607-client-syncanyclient/.syncany/cache/temp-transaction-5786026263832201110.tmp
29-3-20 17:50:30.754 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event UpUploadFileSyncExternalEvent (org.syncany.operations.daemon.messages.UpUploadFileSyncExternalEvent@5a48931) ...
29-3-20 17:50:30.755 | RemoteTransacti | Test worker    | INFO : - Uploading remote transaction file RemoteFile[name=transaction-171a928f] ...
29-3-20 17:50:30.756 | RemoteTransacti | Test worker    | INFO : - Starting to upload data in commit.
29-3-20 17:50:30.756 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event UpUploadFileInTransactionSyncExternalEvent (org.syncany.operations.daemon.messages.UpUploadFileInTransactionSyncExternalEvent@2ca759ed) ...
29-3-20 17:50:30.760 | AsyncIndexer    | AsyncI/syncany | INFO : Stopping indexing. Signal end of stream with empty databaseversion
29-3-20 17:50:30.760 | RemoteTransacti | Test worker    | INFO : - Uploading /tmp/syncanytest/syncany-200329175011381-99607-client-syncanyclient/.syncany/cache/multichunk-003c582e8d4672462d25a1dbd3f6480332cbb7bb to temp. file RemoteFile[name=temp-Kiiaq-multichunk-003c582e8d4672462d25a1dbd3f6480332cbb7bb] ...
29-3-20 17:50:30.761 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event UpUploadFileInTransactionSyncExternalEvent (org.syncany.operations.daemon.messages.UpUploadFileInTransactionSyncExternalEvent@778c85e5) ...
29-3-20 17:50:30.761 | RemoteTransacti | Test worker    | INFO : - Uploading /tmp/syncanytest/syncany-200329175011381-99607-client-syncanyclient/.syncany/cache/database-syncanyclientKTkSsHrbRQsaJmshiKXb-0000000001 to temp. file RemoteFile[name=temp-mNUCo-database-syncanyclientKTkSsHrbRQsaJmshiKXb-0000000001] ...
29-3-20 17:50:30.762 | RemoteTransacti | Test worker    | INFO : - Moving temp. file RemoteFile[name=temp-Kiiaq-multichunk-003c582e8d4672462d25a1dbd3f6480332cbb7bb] to final location RemoteFile[name=multichunk-003c582e8d4672462d25a1dbd3f6480332cbb7bb] ...
29-3-20 17:50:30.763 | RemoteTransacti | Test worker    | INFO : - Moving temp. file RemoteFile[name=temp-mNUCo-database-syncanyclientKTkSsHrbRQsaJmshiKXb-0000000001] to final location RemoteFile[name=database-syncanyclientKTkSsHrbRQsaJmshiKXb-0000000001] ...
29-3-20 17:50:30.763 | RemoteTransacti | Test worker    | INFO : - Deleting remote transaction file RemoteFile[name=transaction-171a928f] ...
29-3-20 17:50:30.763 | RemoteTransacti | Test worker    | INFO : END of TX.commmit(): Succesfully committed transaction.
29-3-20 17:50:30.763 | RemoteTransacti | Test worker    | INFO : END of TX.delTemp(): Sucessfully deleted final files.
29-3-20 17:50:30.763 | UpOperation     | Test worker    | INFO : Persisting local SQL database (new database version syncanyclientKTkSsHrbRQsaJmshiKXb/(syncanyclientKTkSsHrbRQsaJmshiKXb1)/T=1585504230746) ...
29-3-20 17:50:30.777 | UpOperation     | Test worker    | INFO : Removing DIRTY database versions from database ...
29-3-20 17:50:30.795 | UpOperation     | Test worker    | INFO : Adding database version to result changes:DatabaseVersion [header=syncanyclientKTkSsHrbRQsaJmshiKXb/(syncanyclientKTkSsHrbRQsaJmshiKXb1)/T=1585504230746, chunks=1, multiChunks=1, fileContents=1, fileHistories=1]
29-3-20 17:50:30.795 | UpOperation     | Test worker    | INFO : Committing local database.
29-3-20 17:50:30.795 | UpOperation     | Test worker    | FINE : Waiting for new database version.
29-3-20 17:50:30.795 | UpOperation     | Test worker    | FINE : Took new database version: DatabaseVersion [header=UnknownMachine/()/T=1585504230760, chunks=0, multiChunks=0, fileContents=0, fileHistories=0]
29-3-20 17:50:30.795 | UpOperation     | Test worker    | INFO : Sync up done.
29-3-20 17:50:30.796 | ActionFileHandl | Test worker    | INFO : Finishing action for RemoteFile[name=action-up-syncanyclientKTkSsHrbRQsaJmshiKXb-1585504230584] ...
29-3-20 17:50:30.800 | ActionFileHandl | Test worker    | INFO : Deleting action file: RemoteFile[name=action-up-syncanyclientKTkSsHrbRQsaJmshiKXb-1585504230584]
29-3-20 17:50:30.800 | AbstractTransfe | Test worker    | INFO : Cleaning own old action files ...
29-3-20 17:50:30.800 | Cache           | Test worker    | INFO : Cache size okay (1 KB), no need to clean (keep size is 500 MB)
29-3-20 17:50:30.800 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event UpEndSyncExternalEvent (org.syncany.operations.daemon.messages.UpEndSyncExternalEvent@36ce0150) ...
29-3-20 17:50:32.804 | DatabaseConnect | Test worker    | INFO : Found 12 tables.
29-3-20 17:50:32.804 | StatusOperation | Test worker    | INFO : 
29-3-20 17:50:32.804 | StatusOperation | Test worker    | INFO : Running 'Status' at client syncanyclientKTkSsHrbRQsaJmshiKXb ...
29-3-20 17:50:32.804 | StatusOperation | Test worker    | INFO : --------------------------------------------
29-3-20 17:50:32.804 | StatusOperation | Test worker    | INFO : Querying current file tree from database ...
29-3-20 17:50:32.804 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event StatusStartSyncExternalEvent (org.syncany.operations.daemon.messages.StatusStartSyncExternalEvent@457849) ...
29-3-20 17:50:32.807 | StatusOperation | Test worker    | INFO : Analyzing local folder /tmp/syncanytest/syncany-200329175011381-99607-client-syncanyclient ...
29-3-20 17:50:32.807 | StatusOperation | Test worker    | FINE : - Ignoring file (syncany app-related): .syncany
29-3-20 17:50:32.808 | FileVersionComp | Test worker    | INFO :      - [CHANGED_LAST_MOD_DATE]: Local file DIFFERS from file version, expected MOD. DATE = 3/29/20, 5:50 PM (1,585,504,230,570), but actual MOD. DATE = 3/29/20, 5:50 PM (1,585,504,232,798), for file rndFile-1585504230572-2049990084.dat
29-3-20 17:50:32.812 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event StatusEndSyncExternalEvent (org.syncany.operations.daemon.messages.StatusEndSyncExternalEvent@1a9f2632) ...
29-3-20 17:50:32.816 | org.syncany.plu | Test worker    | FINE : Validating required fields
29-3-20 17:50:32.816 | LocalTransferMa | Test worker    | INFO : testTargetExists: Target exists.
29-3-20 17:50:32.905 | hsqldb.db.HSQLD | Test worker    | INFO : Checkpoint start
29-3-20 17:50:32.906 | hsqldb.db.HSQLD | Test worker    | INFO : checkpointClose start
29-3-20 17:50:32.916 | hsqldb.db.HSQLD | Test worker    | FINE : log close size: 0
29-3-20 17:50:32.919 | hsqldb.db.HSQLD | Test worker    | INFO : checkpointClose end
29-3-20 17:50:32.919 | hsqldb.db.HSQLD | Test worker    | INFO : Checkpoint end - txts: 1
29-3-20 17:50:32.924 | DatabaseConnect | Test worker    | INFO : Found 0 tables.
29-3-20 17:50:32.924 | DatabaseConnect | Test worker    | INFO : Database has no tables. Creating tables from script.create.all.sql
29-3-20 17:50:32.924 | SqlRunner       | Test worker    | INFO : SQL (comment): -- Tables
29-3-20 17:50:32.924 | 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:50:32.928 | 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:50:32.936 | 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:50:32.938 | 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:50:32.942 | 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:50:32.945 | 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:50:32.948 | 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:50:32.950 | 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:50:32.952 | 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:50:32.955 | 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:50:32.958 | 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:50:32.961 | 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:50:32.963 | SqlRunner       | Test worker    | INFO : SQL (comment): -- Non-primary indices
29-3-20 17:50:32.963 | SqlRunner       | Test worker    | INFO : SQL:   CREATE INDEX idx_databaseversion_status ON databaseversion (status); 
29-3-20 17:50:32.963 | hsqldb.db.HSQLD | Test worker    | INFO : dataFileCache open start
29-3-20 17:50:32.969 | hsqldb.db.HSQLD | Test worker    | INFO : dataFileCache open end
29-3-20 17:50:32.971 | SqlRunner       | Test worker    | INFO : SQL: CREATE INDEX idx_databaseversion_vectorclock_serialized ON databaseversion (vectorclock_serialized); 
29-3-20 17:50:32.972 | SqlRunner       | Test worker    | INFO : SQL: CREATE INDEX idx_fileversion_path ON fileversion (path); 
29-3-20 17:50:32.974 | SqlRunner       | Test worker    | INFO : SQL: CREATE INDEX idx_fileversion_status ON fileversion (status); 
29-3-20 17:50:32.975 | SqlRunner       | Test worker    | INFO : SQL: CREATE INDEX idx_fileversion_filecontent_checksum ON fileversion (filecontent_checksum); 
29-3-20 17:50:32.976 | SqlRunner       | Test worker    | INFO : SQL (comment): -- Views
29-3-20 17:50:32.977 | 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:50:32.979 | 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:50:32.981 | 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:50:32.983 | 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:50:32.989 | SqlRunner       | Test worker    | INFO : SQL (comment): -- Full Views
29-3-20 17:50:32.989 | 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:50:32.993 | 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:50:33.001 | SqlRunner       | Test worker    | INFO : SQL (comment): -- Functions
29-3-20 17:50:33.001 | SqlRunner       | Test worker    | INFO : SQL (new delimiter): end;
29-3-20 17:50:33.001 | 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:50:33.009 | StatusOperation | Test worker    | INFO : 
29-3-20 17:50:33.009 | StatusOperation | Test worker    | INFO : Running 'Status' at client syncanyclientwzJKrvPKimrncMSZMRXX ...
29-3-20 17:50:33.009 | StatusOperation | Test worker    | INFO : --------------------------------------------
29-3-20 17:50:33.009 | StatusOperation | Test worker    | INFO : Querying current file tree from database ...
29-3-20 17:50:33.009 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event StatusStartSyncExternalEvent (org.syncany.operations.daemon.messages.StatusStartSyncExternalEvent@7c2cea2b) ...
29-3-20 17:50:33.010 | StatusOperation | Test worker    | INFO : Analyzing local folder /tmp/syncanytest/syncany-200329175011381-20635-client-syncanyclient ...
29-3-20 17:50:33.011 | StatusOperation | Test worker    | FINE : - New file: somefolder
29-3-20 17:50:33.011 | StatusOperation | Test worker    | FINE : - Ignoring file (syncany app-related): .syncany
29-3-20 17:50:33.011 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event StatusEndSyncExternalEvent (org.syncany.operations.daemon.messages.StatusEndSyncExternalEvent@20e5dbf8) ...
29-3-20 17:50:33.011 | org.syncany.plu | Test worker    | FINE : Validating required fields
29-3-20 17:50:33.012 | TransferManager | Test worker    | INFO : Building LocalTransferManager from config 'syncany-200329175011381-20635-client-syncanyclient' ...
29-3-20 17:50:33.012 | TransferManager | Test worker    | INFO : - With feature ReadAfterWriteConsistent
29-3-20 17:50:33.012 | TransferManager | Test worker    | INFO : - With feature Retriable
29-3-20 17:50:33.012 | TransferManager | Test worker    | FINE : - Checking if selected features supported ...
29-3-20 17:50:33.012 | TransferManager | Test worker    | FINE : - Checking for duplicate features ...
29-3-20 17:50:33.012 | TransferManager | Test worker    | FINE : - Checking required feature TransactionAware in LocalTransferManager ...
29-3-20 17:50:33.012 | TransferManager | Test worker    | FINE : - Checking required feature Retriable in LocalTransferManager ...
29-3-20 17:50:33.012 | TransferManager | Test worker    | INFO : - SKIPPING unsupported optional feature ReadAfterWriteConsistent
29-3-20 17:50:33.012 | TransferManager | Test worker    | FINE : - Wrapping TransferManager LocalTransferManager in RetriableFeatureTransferManager
29-3-20 17:50:33.012 | org.syncany.plu | Test worker    | FINE : Validating required fields
29-3-20 17:50:33.012 | TransferManager | Test worker    | INFO : Building LocalTransferManager from config 'syncany-200329175011381-20635-client-syncanyclient' ...
29-3-20 17:50:33.012 | TransferManager | Test worker    | INFO : - With feature ReadAfterWriteConsistent
29-3-20 17:50:33.012 | TransferManager | Test worker    | INFO : - With feature Retriable
29-3-20 17:50:33.013 | TransferManager | Test worker    | INFO : - With feature PathAware
29-3-20 17:50:33.013 | TransferManager | Test worker    | INFO : - With feature TransactionAware
29-3-20 17:50:33.013 | TransferManager | Test worker    | FINE : - Checking if selected features supported ...
29-3-20 17:50:33.013 | TransferManager | Test worker    | FINE : - Checking for duplicate features ...
29-3-20 17:50:33.013 | TransferManager | Test worker    | FINE : - Checking required feature TransactionAware in LocalTransferManager ...
29-3-20 17:50:33.013 | TransferManager | Test worker    | FINE : - Checking required feature Retriable in LocalTransferManager ...
29-3-20 17:50:33.013 | TransferManager | Test worker    | INFO : - SKIPPING unsupported optional feature ReadAfterWriteConsistent
29-3-20 17:50:33.013 | TransferManager | Test worker    | FINE : - Wrapping TransferManager LocalTransferManager in RetriableFeatureTransferManager
29-3-20 17:50:33.013 | TransferManager | Test worker    | INFO : - SKIPPING unsupported optional feature PathAware
29-3-20 17:50:33.013 | TransferManager | Test worker    | FINE : - Wrapping TransferManager RetriableFeatureTransferManager in TransactionAwareFeatureTransferManager
29-3-20 17:50:33.021 | DatabaseConnect | Test worker    | INFO : Found 12 tables.
29-3-20 17:50:33.021 | UpOperation     | Test worker    | INFO : 
29-3-20 17:50:33.021 | UpOperation     | Test worker    | INFO : Running 'Sync up' at client syncanyclientwzJKrvPKimrncMSZMRXX ...
29-3-20 17:50:33.021 | UpOperation     | Test worker    | INFO : --------------------------------------------
29-3-20 17:50:33.022 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event UpStartSyncExternalEvent (org.syncany.operations.daemon.messages.UpStartSyncExternalEvent@20f7598d) ...
29-3-20 17:50:33.023 | DatabaseConnect | Test worker    | INFO : Found 12 tables.
29-3-20 17:50:33.023 | StatusOperation | Test worker    | INFO : 
29-3-20 17:50:33.023 | StatusOperation | Test worker    | INFO : Running 'Status' at client syncanyclientwzJKrvPKimrncMSZMRXX ...
29-3-20 17:50:33.023 | StatusOperation | Test worker    | INFO : --------------------------------------------
29-3-20 17:50:33.023 | StatusOperation | Test worker    | INFO : Querying current file tree from database ...
29-3-20 17:50:33.023 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event StatusStartSyncExternalEvent (org.syncany.operations.daemon.messages.StatusStartSyncExternalEvent@7d786f81) ...
29-3-20 17:50:33.025 | StatusOperation | Test worker    | INFO : Analyzing local folder /tmp/syncanytest/syncany-200329175011381-20635-client-syncanyclient ...
29-3-20 17:50:33.025 | StatusOperation | Test worker    | FINE : - New file: somefolder
29-3-20 17:50:33.025 | StatusOperation | Test worker    | FINE : - Ignoring file (syncany app-related): .syncany
29-3-20 17:50:33.025 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event StatusEndSyncExternalEvent (org.syncany.operations.daemon.messages.StatusEndSyncExternalEvent@1cad36e1) ...
29-3-20 17:50:33.026 | AbstractTransfe | Test worker    | INFO : Looking for other running remote operations ...
29-3-20 17:50:33.027 | DatabaseConnect | Test worker    | INFO : Found 12 tables.
29-3-20 17:50:33.027 | LsRemoteOperati | Test worker    | INFO : 
29-3-20 17:50:33.027 | LsRemoteOperati | Test worker    | INFO : Running 'Remote Status' at client syncanyclientwzJKrvPKimrncMSZMRXX ...
29-3-20 17:50:33.027 | LsRemoteOperati | Test worker    | INFO : --------------------------------------------
29-3-20 17:50:33.027 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event LsRemoteStartSyncExternalEvent (org.syncany.operations.daemon.messages.LsRemoteStartSyncExternalEvent@3a722fc2) ...
29-3-20 17:50:33.028 | LsRemoteOperati | Test worker    | INFO : Retrieving remote database list.
29-3-20 17:50:33.036 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event LsRemoteEndSyncExternalEvent (org.syncany.operations.daemon.messages.LsRemoteEndSyncExternalEvent@4915a614) ...
29-3-20 17:50:33.037 | UpOperation     | Test worker    | INFO : No remote changes, ready to upload.
29-3-20 17:50:33.037 | ActionFileHandl | Test worker    | INFO : Starting action for RemoteFile[name=action-up-syncanyclientwzJKrvPKimrncMSZMRXX-1585504233013] ...
29-3-20 17:50:33.037 | ActionFileHandl | Test worker    | INFO : Uploading action file: RemoteFile[name=action-up-syncanyclientwzJKrvPKimrncMSZMRXX-1585504233013]
29-3-20 17:50:33.037 | ActionFileHandl | Test worker    | INFO : Scheduling action renewal task for every 2 minutes, for RemoteFile[name=action-up-syncanyclientwzJKrvPKimrncMSZMRXX-1585504233013] ...
29-3-20 17:50:33.046 | DatabaseConnect | Test worker    | INFO : Found 12 tables.
29-3-20 17:50:33.052 | TransactionAwar | Test worker    | INFO : Done rolling back previous transactions.
29-3-20 17:50:33.052 | AsyncIndexer    | AsyncI/syncany | INFO : Starting Indexing.
29-3-20 17:50:33.053 | InternalEventBu | AsyncI/syncany | INFO : Event bus 'LocalEventBus': Posting event UpIndexStartSyncExternalEvent (org.syncany.operations.daemon.messages.UpIndexStartSyncExternalEvent@769a8133) ...
29-3-20 17:50:33.053 | Indexer         | AsyncI/syncany | FINE : - Looking for deleted files ...
29-3-20 17:50:33.053 | Indexer         | AsyncI/syncany | FINE : - +File /tmp/syncanytest/syncany-200329175011381-20635-client-syncanyclient/somefolder
29-3-20 17:50:33.054 | Indexer         | AsyncI/syncany | FINE : - /File: somefolder (directory/symlink/0-byte-file)
29-3-20 17:50:33.060 | Indexer         | AsyncI/syncany | FINE :    * No old file history found, starting new history (path: somefolder, FOLDER)
29-3-20 17:50:33.061 | FileVersionComp | AsyncI/syncany | INFO :      - [DELETED]: Local file DIFFERS from file version, actual file is NULL, for file somefolder
29-3-20 17:50:33.061 | Indexer         | AsyncI/syncany | INFO :    * Added file version:    FileVersion [version=1, path=somefolder, type=FOLDER, status=NEW, size=4096, lastModified=Sun Mar 29 17:50:32 UTC 2020, linkTarget=null, checksum=null, updated=Sun Mar 29 17:50:33 UTC 2020, posixPermissions=rwxrwxr-x, dosAttributes=--a-]
29-3-20 17:50:33.061 | Indexer         | AsyncI/syncany | INFO :      based on file version: null
29-3-20 17:50:33.061 | InternalEventBu | AsyncI/syncany | INFO : Event bus 'LocalEventBus': Posting event UpIndexChangesDetectedSyncExternalEvent (org.syncany.operations.daemon.messages.UpIndexChangesDetectedSyncExternalEvent@784c6a43) ...
29-3-20 17:50:33.062 | InternalEventBu | AsyncI/syncany | INFO : Event bus 'LocalEventBus': Posting event UpIndexEndSyncExternalEvent (org.syncany.operations.daemon.messages.UpIndexEndSyncExternalEvent@5d01291a) ...
29-3-20 17:50:33.062 | Indexer         | AsyncI/syncany | FINE : Processed new database version: DatabaseVersion [header=UnknownMachine/()/T=1585504233052, chunks=0, multiChunks=0, fileContents=0, fileHistories=1]
29-3-20 17:50:33.062 | InternalEventBu | AsyncI/syncany | INFO : Event bus 'LocalEventBus': Posting event UpIndexMidSyncExternalEvent (org.syncany.operations.daemon.messages.UpIndexMidSyncExternalEvent@6fa3a7c1) ...
29-3-20 17:50:33.063 | UpOperation     | Test worker    | INFO : No DIRTY data found in database (no dirty databases); Nothing to do here.
29-3-20 17:50:33.063 | UpOperation     | Test worker    | INFO : Last vector clock was: ()
29-3-20 17:50:33.064 | UpOperation     | Test worker    | INFO : Uploading new multichunks ...
29-3-20 17:50:33.065 | UpOperation     | Test worker    | INFO : Uploading database: DatabaseVersion [header=syncanyclientwzJKrvPKimrncMSZMRXX/(syncanyclientwzJKrvPKimrncMSZMRXX1)/T=1585504233064, chunks=0, multiChunks=0, fileContents=0, fileHistories=1]
29-3-20 17:50:33.065 | UpOperation     | Test worker    | INFO : Saving local delta database, version syncanyclientwzJKrvPKimrncMSZMRXX/(syncanyclientwzJKrvPKimrncMSZMRXX1)/T=1585504233064 to file /tmp/syncanytest/syncany-200329175011381-20635-client-syncanyclient/.syncany/cache/database-syncanyclientwzJKrvPKimrncMSZMRXX-0000000001 ... 
29-3-20 17:50:33.065 | UpOperation     | Test worker    | INFO : - Saving database to /tmp/syncanytest/syncany-200329175011381-20635-client-syncanyclient/.syncany/cache/database-syncanyclientwzJKrvPKimrncMSZMRXX-0000000001 ...
29-3-20 17:50:33.066 | UpOperation     | Test worker    | INFO : - Uploading local delta database file ...
29-3-20 17:50:33.066 | UpOperation     | Test worker    | INFO : - Uploading /tmp/syncanytest/syncany-200329175011381-20635-client-syncanyclient/.syncany/cache/database-syncanyclientwzJKrvPKimrncMSZMRXX-0000000001 to RemoteFile[name=database-syncanyclientwzJKrvPKimrncMSZMRXX-0000000001] ...
29-3-20 17:50:33.066 | RemoteTransacti | Test worker    | INFO : - Adding file to TX for UPLOAD: /tmp/syncanytest/syncany-200329175011381-20635-client-syncanyclient/.syncany/cache/database-syncanyclientwzJKrvPKimrncMSZMRXX-0000000001 -> Temp. remote file: RemoteFile[name=temp-LoXzv-database-syncanyclientwzJKrvPKimrncMSZMRXX-0000000001], final location: RemoteFile[name=database-syncanyclientwzJKrvPKimrncMSZMRXX-0000000001]
29-3-20 17:50:33.067 | UpOperation     | Test worker    | INFO : Adding shutdown hook (to allow resuming the upload) ...
29-3-20 17:50:33.067 | RemoteTransacti | Test worker    | INFO : Starting TX.commit() ...
29-3-20 17:50:33.070 | RemoteTransacti | Test worker    | INFO : Wrote transaction manifest to temporary file: /tmp/syncanytest/syncany-200329175011381-20635-client-syncanyclient/.syncany/cache/temp-transaction-7546966286462306684.tmp
29-3-20 17:50:33.070 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event UpUploadFileSyncExternalEvent (org.syncany.operations.daemon.messages.UpUploadFileSyncExternalEvent@6d212ded) ...
29-3-20 17:50:33.070 | RemoteTransacti | Test worker    | INFO : - Uploading remote transaction file RemoteFile[name=transaction-57eb047] ...
29-3-20 17:50:33.070 | RemoteTransacti | Test worker    | INFO : - Starting to upload data in commit.
29-3-20 17:50:33.071 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event UpUploadFileInTransactionSyncExternalEvent (org.syncany.operations.daemon.messages.UpUploadFileInTransactionSyncExternalEvent@195cd1a8) ...
29-3-20 17:50:33.071 | RemoteTransacti | Test worker    | INFO : - Uploading /tmp/syncanytest/syncany-200329175011381-20635-client-syncanyclient/.syncany/cache/database-syncanyclientwzJKrvPKimrncMSZMRXX-0000000001 to temp. file RemoteFile[name=temp-LoXzv-database-syncanyclientwzJKrvPKimrncMSZMRXX-0000000001] ...
29-3-20 17:50:33.071 | RemoteTransacti | Test worker    | INFO : - Moving temp. file RemoteFile[name=temp-LoXzv-database-syncanyclientwzJKrvPKimrncMSZMRXX-0000000001] to final location RemoteFile[name=database-syncanyclientwzJKrvPKimrncMSZMRXX-0000000001] ...
29-3-20 17:50:33.071 | RemoteTransacti | Test worker    | INFO : - Deleting remote transaction file RemoteFile[name=transaction-57eb047] ...
29-3-20 17:50:33.072 | RemoteTransacti | Test worker    | INFO : END of TX.commmit(): Succesfully committed transaction.
29-3-20 17:50:33.072 | RemoteTransacti | Test worker    | INFO : END of TX.delTemp(): Sucessfully deleted final files.
29-3-20 17:50:33.072 | UpOperation     | Test worker    | INFO : Persisting local SQL database (new database version syncanyclientwzJKrvPKimrncMSZMRXX/(syncanyclientwzJKrvPKimrncMSZMRXX1)/T=1585504233064) ...
29-3-20 17:50:33.085 | UpOperation     | Test worker    | INFO : Removing DIRTY database versions from database ...
29-3-20 17:50:33.090 | AsyncIndexer    | AsyncI/syncany | INFO : Stopping indexing. Signal end of stream with empty databaseversion
29-3-20 17:50:33.097 | UpOperation     | Test worker    | INFO : Adding database version to result changes:DatabaseVersion [header=syncanyclientwzJKrvPKimrncMSZMRXX/(syncanyclientwzJKrvPKimrncMSZMRXX1)/T=1585504233064, chunks=0, multiChunks=0, fileContents=0, fileHistories=1]
29-3-20 17:50:33.097 | UpOperation     | Test worker    | INFO : Committing local database.
29-3-20 17:50:33.097 | UpOperation     | Test worker    | FINE : Waiting for new database version.
29-3-20 17:50:33.097 | UpOperation     | Test worker    | FINE : Took new database version: DatabaseVersion [header=UnknownMachine/()/T=1585504233090, chunks=0, multiChunks=0, fileContents=0, fileHistories=0]
29-3-20 17:50:33.097 | UpOperation     | Test worker    | INFO : Sync up done.
29-3-20 17:50:33.098 | ActionFileHandl | Test worker    | INFO : Finishing action for RemoteFile[name=action-up-syncanyclientwzJKrvPKimrncMSZMRXX-1585504233013] ...
29-3-20 17:50:33.102 | ActionFileHandl | Test worker    | INFO : Deleting action file: RemoteFile[name=action-up-syncanyclientwzJKrvPKimrncMSZMRXX-1585504233013]
29-3-20 17:50:33.102 | AbstractTransfe | Test worker    | INFO : Cleaning own old action files ...
29-3-20 17:50:33.103 | Cache           | Test worker    | INFO : Cache size okay (0 KB), no need to clean (keep size is 500 MB)
29-3-20 17:50:33.103 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event UpEndSyncExternalEvent (org.syncany.operations.daemon.messages.UpEndSyncExternalEvent@2950d112) ...
29-3-20 17:50:33.104 | DatabaseConnect | Test worker    | INFO : Found 12 tables.
29-3-20 17:50:33.104 | StatusOperation | Test worker    | INFO : 
29-3-20 17:50:33.105 | StatusOperation | Test worker    | INFO : Running 'Status' at client syncanyclientwzJKrvPKimrncMSZMRXX ...
29-3-20 17:50:33.105 | StatusOperation | Test worker    | INFO : --------------------------------------------
29-3-20 17:50:33.105 | StatusOperation | Test worker    | INFO : Querying current file tree from database ...
29-3-20 17:50:33.105 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event StatusStartSyncExternalEvent (org.syncany.operations.daemon.messages.StatusStartSyncExternalEvent@4170bbd3) ...
29-3-20 17:50:33.106 | StatusOperation | Test worker    | INFO : Analyzing local folder /tmp/syncanytest/syncany-200329175011381-20635-client-syncanyclient ...
29-3-20 17:50:33.107 | StatusOperation | Test worker    | FINE : - Ignoring file (syncany app-related): .syncany
29-3-20 17:50:33.107 | StatusOperation | Test worker    | INFO : - No changes to local database
29-3-20 17:50:33.107 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event StatusEndSyncExternalEvent (org.syncany.operations.daemon.messages.StatusEndSyncExternalEvent@581bc645) ...