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

4

tests

0

failures

0

ignored

8.653s

duration

100%

successful

Tests

Test Duration Result
testCreateFolderAndRunStatus 0.548s passed
testNotSoRecentFileModificationWithoutSizeOrModifiedDateChange 2.400s passed
testStatusOperation 3.096s passed
testVeryRecentFileModificationWithoutSizeOrModifiedDateChange 2.609s passed

Standard error

29-5-19 15:03:23.824 | org.syncany.plu | Test worker    | FINE : Validating required fields
29-5-19 15:03:23.832 | LocalTransferMa | Test worker    | INFO : testTargetExists: Target exists.
29-5-19 15:03:24.006 | InternalEventBu | Test worker    | INFO : Event bus: Created event bus org.syncany.config.LocalEventBus
29-5-19 15:03:24.017 | org.syncany.plu | Test worker    | FINE : Validating required fields
29-5-19 15:03:24.018 | TransferManager | Test worker    | INFO : Building LocalTransferManager from config 'syncany-190529150308467-91800-client-syncanyclient' ...
29-5-19 15:03:24.020 | TransferManager | Test worker    | INFO : - With feature ReadAfterWriteConsistent
29-5-19 15:03:24.021 | TransferManager | Test worker    | INFO : - With feature Retriable
29-5-19 15:03:24.021 | TransferManager | Test worker    | FINE : - Checking if selected features supported ...
29-5-19 15:03:24.021 | TransferManager | Test worker    | FINE : - Checking for duplicate features ...
29-5-19 15:03:24.021 | TransferManager | Test worker    | FINE : - Checking required feature TransactionAware in LocalTransferManager ...
29-5-19 15:03:24.024 | TransferManager | Test worker    | FINE : - Checking required feature Retriable in LocalTransferManager ...
29-5-19 15:03:24.024 | TransferManager | Test worker    | INFO : - SKIPPING unsupported optional feature ReadAfterWriteConsistent
29-5-19 15:03:24.026 | TransferManager | Test worker    | FINE : - Wrapping TransferManager LocalTransferManager in RetriableFeatureTransferManager
29-5-19 15:03:24.034 | org.syncany.plu | Test worker    | FINE : Validating required fields
29-5-19 15:03:24.035 | TransferManager | Test worker    | INFO : Building LocalTransferManager from config 'syncany-190529150308467-91800-client-syncanyclient' ...
29-5-19 15:03:24.035 | TransferManager | Test worker    | INFO : - With feature ReadAfterWriteConsistent
29-5-19 15:03:24.035 | TransferManager | Test worker    | INFO : - With feature Retriable
29-5-19 15:03:24.035 | TransferManager | Test worker    | INFO : - With feature PathAware
29-5-19 15:03:24.036 | TransferManager | Test worker    | INFO : - With feature TransactionAware
29-5-19 15:03:24.041 | TransferManager | Test worker    | FINE : - Checking if selected features supported ...
29-5-19 15:03:24.041 | TransferManager | Test worker    | FINE : - Checking for duplicate features ...
29-5-19 15:03:24.042 | TransferManager | Test worker    | FINE : - Checking required feature TransactionAware in LocalTransferManager ...
29-5-19 15:03:24.042 | TransferManager | Test worker    | FINE : - Checking required feature Retriable in LocalTransferManager ...
29-5-19 15:03:24.042 | TransferManager | Test worker    | INFO : - SKIPPING unsupported optional feature ReadAfterWriteConsistent
29-5-19 15:03:24.050 | TransferManager | Test worker    | FINE : - Wrapping TransferManager LocalTransferManager in RetriableFeatureTransferManager
29-5-19 15:03:24.050 | TransferManager | Test worker    | INFO : - SKIPPING unsupported optional feature PathAware
29-5-19 15:03:24.051 | TransferManager | Test worker    | FINE : - Wrapping TransferManager RetriableFeatureTransferManager in TransactionAwareFeatureTransferManager
29-5-19 15:03:24.063 | DatabaseConnect | Test worker    | INFO : Loading database driver org.hsqldb.jdbcDriver ...
29-5-19 15:03:25.380 | hsqldb.db.HSQLD | Test worker    | INFO : Checkpoint start
29-5-19 15:03:25.380 | hsqldb.db.HSQLD | Test worker    | INFO : checkpointClose start
29-5-19 15:03:25.424 | hsqldb.db.HSQLD | Test worker    | FINE : log close size: 0
29-5-19 15:03:25.431 | hsqldb.db.HSQLD | Test worker    | INFO : checkpointClose end
29-5-19 15:03:25.433 | hsqldb.db.HSQLD | Test worker    | INFO : Checkpoint end - txts: 1
29-5-19 15:03:25.512 | DatabaseConnect | Test worker    | INFO : Found 0 tables.
29-5-19 15:03:25.512 | DatabaseConnect | Test worker    | INFO : Database has no tables. Creating tables from script.create.all.sql
29-5-19 15:03:25.514 | SqlRunner       | Test worker    | INFO : SQL (comment): -- Tables
29-5-19 15:03:25.515 | SqlRunner       | Test worker    | INFO : SQL:  CREATE CACHED TABLE IF NOT EXISTS databaseversion ( id int NOT NULL IDENTITY, status varchar(45) NOT NULL, localtime datetime NOT NULL, client varchar(45) NOT NULL, vectorclock_serialized varchar(1024) NOT NULL, UNIQUE (vectorclock_serialized) ); 
29-5-19 15:03:25.523 | SqlRunner       | Test worker    | INFO : SQL:  CREATE CACHED TABLE IF NOT EXISTS  chunk ( checksum varchar(40) NOT NULL, databaseversion_id int NOT NULL, size bigint NOT NULL, PRIMARY KEY (checksum), FOREIGN KEY (databaseversion_id) REFERENCES databaseversion (id) ON DELETE NO ACTION ON UPDATE NO ACTION ); 
29-5-19 15:03:25.536 | SqlRunner       | Test worker    | INFO : SQL:  CREATE CACHED TABLE IF NOT EXISTS  databaseversion_vectorclock ( databaseversion_id int NOT NULL, client varchar(45) NOT NULL, logicaltime int NOT NULL, PRIMARY KEY (databaseversion_id, client), FOREIGN KEY (databaseversion_id) REFERENCES databaseversion (id) ON DELETE NO ACTION ON UPDATE NO ACTION ); 
29-5-19 15:03:25.543 | SqlRunner       | Test worker    | INFO : SQL:  CREATE CACHED TABLE IF NOT EXISTS  filecontent ( checksum varchar(40) NOT NULL, databaseversion_id int NOT NULL, size bigint NOT NULL, PRIMARY KEY (checksum), FOREIGN KEY (databaseversion_id) REFERENCES databaseversion (id) ON DELETE NO ACTION ON UPDATE NO ACTION ); 
29-5-19 15:03:25.548 | SqlRunner       | Test worker    | INFO : SQL:  CREATE CACHED TABLE IF NOT EXISTS  filecontent_chunk ( filecontent_checksum varchar(40) NOT NULL, chunk_checksum varchar(40) NOT NULL, num int NOT NULL, PRIMARY KEY (filecontent_checksum, chunk_checksum, num), FOREIGN KEY (filecontent_checksum) REFERENCES filecontent (checksum) ON DELETE NO ACTION ON UPDATE NO ACTION, FOREIGN KEY (chunk_checksum) REFERENCES chunk (checksum) ON DELETE NO ACTION ON UPDATE NO ACTION ); 
29-5-19 15:03:25.556 | SqlRunner       | Test worker    | INFO : SQL:  CREATE CACHED TABLE IF NOT EXISTS  filehistory ( id varchar(40) NOT NULL, databaseversion_id int NOT NULL, PRIMARY KEY (id, databaseversion_id), FOREIGN KEY (databaseversion_id) REFERENCES databaseversion (id) ON DELETE NO ACTION ON UPDATE NO ACTION ); 
29-5-19 15:03:25.561 | SqlRunner       | Test worker    | INFO : SQL:  CREATE CACHED TABLE IF NOT EXISTS  fileversion ( filehistory_id varchar(40) NOT NULL, version int NOT NULL, databaseversion_id int NOT NULL, path varchar(1024) NOT NULL, type varchar(45) NOT NULL, status varchar(45) NOT NULL, size bigint NOT NULL, lastmodified datetime NOT NULL, linktarget varchar(1024), filecontent_checksum varchar(40) DEFAULT NULL, updated datetime NOT NULL, posixperms varchar(45) DEFAULT NULL, dosattrs varchar(45) DEFAULT NULL, PRIMARY KEY (filehistory_id, version, databaseversion_id), FOREIGN KEY (filehistory_id, databaseversion_id) REFERENCES filehistory (id, databaseversion_id) ON DELETE NO ACTION ON UPDATE NO ACTION, FOREIGN KEY (filecontent_checksum) REFERENCES filecontent (checksum) ON DELETE NO ACTION ON UPDATE NO ACTION ); 
29-5-19 15:03:25.573 | SqlRunner       | Test worker    | INFO : SQL:  CREATE CACHED TABLE IF NOT EXISTS  multichunk ( id varchar(40) NOT NULL, databaseversion_id int NOT NULL, size bigint NOT NULL, PRIMARY KEY (id), FOREIGN KEY (databaseversion_id) REFERENCES databaseversion (id) ON DELETE NO ACTION ON UPDATE NO ACTION ); 
29-5-19 15:03:25.578 | SqlRunner       | Test worker    | INFO : SQL:  CREATE CACHED TABLE IF NOT EXISTS  multichunk_chunk ( multichunk_id varchar(40) NOT NULL, chunk_checksum varchar(40) NOT NULL, PRIMARY KEY (multichunk_id, chunk_checksum), FOREIGN KEY (multichunk_id) REFERENCES multichunk (id) ON DELETE NO ACTION ON UPDATE NO ACTION, FOREIGN KEY (chunk_checksum) REFERENCES chunk (checksum) ON DELETE NO ACTION ON UPDATE NO ACTION ); 
29-5-19 15:03:25.582 | SqlRunner       | Test worker    | INFO : SQL:  CREATE CACHED TABLE IF NOT EXISTS  multichunk_muddy ( id varchar(40) NOT NULL, machine_name varchar(255) NOT NULL, machine_version int NOT NULL, PRIMARY KEY (id) ); 
29-5-19 15:03:25.586 | SqlRunner       | Test worker    | INFO : SQL:  CREATE CACHED TABLE IF NOT EXISTS  known_databases ( id int NOT NULL IDENTITY, client varchar(45) NOT NULL, filenumber int NOT NULL, UNIQUE (client, filenumber) ); 
29-5-19 15:03:25.591 | SqlRunner       | Test worker    | INFO : SQL:  CREATE CACHED TABLE IF NOT EXISTS  general_settings ( key varchar(255) NOT NULL, value varchar(255) NOT NULL, PRIMARY KEY (key) ); 
29-5-19 15:03:25.595 | SqlRunner       | Test worker    | INFO : SQL (comment): -- Non-primary indices
29-5-19 15:03:25.596 | SqlRunner       | Test worker    | INFO : SQL:   CREATE INDEX idx_databaseversion_status ON databaseversion (status); 
29-5-19 15:03:25.607 | hsqldb.db.HSQLD | Test worker    | INFO : dataFileCache open start
29-5-19 15:03:25.678 | hsqldb.db.HSQLD | Test worker    | INFO : dataFileCache open end
29-5-19 15:03:25.690 | SqlRunner       | Test worker    | INFO : SQL: CREATE INDEX idx_databaseversion_vectorclock_serialized ON databaseversion (vectorclock_serialized); 
29-5-19 15:03:25.692 | SqlRunner       | Test worker    | INFO : SQL: CREATE INDEX idx_fileversion_path ON fileversion (path); 
29-5-19 15:03:25.694 | SqlRunner       | Test worker    | INFO : SQL: CREATE INDEX idx_fileversion_status ON fileversion (status); 
29-5-19 15:03:25.696 | SqlRunner       | Test worker    | INFO : SQL: CREATE INDEX idx_fileversion_filecontent_checksum ON fileversion (filecontent_checksum); 
29-5-19 15:03:25.699 | SqlRunner       | Test worker    | INFO : SQL (comment): -- Views
29-5-19 15:03:25.699 | SqlRunner       | Test worker    | INFO : SQL:    CREATE VIEW databaseversion_master AS SELECT dbv.*, vc.logicaltime as client_version FROM databaseversion dbv JOIN databaseversion_vectorclock vc on dbv.id=vc.databaseversion_id and dbv.client=vc.client WHERE dbv.status='MASTER'; 
29-5-19 15:03:25.705 | SqlRunner       | Test worker    | INFO : SQL:  CREATE VIEW fileversion_master AS SELECT fv0.* FROM fileversion fv0 JOIN databaseversion dbv ON fv0.databaseversion_id=dbv.id AND dbv.status='MASTER'; 
29-5-19 15:03:25.711 | SqlRunner       | Test worker    | INFO : SQL:  CREATE VIEW fileversion_master_maxversion AS SELECT DISTINCT filehistory_id, MAX(version) version FROM fileversion_master GROUP BY filehistory_id; 
29-5-19 15:03:25.720 | SqlRunner       | Test worker    | INFO : SQL:  CREATE VIEW fileversion_master_last AS SELECT fv.* FROM fileversion_master_maxversion fvmax JOIN fileversion_master fv ON fvmax.filehistory_id=fv.filehistory_id AND fvmax.version=fv.version WHERE fv.status<>'DELETED'; 
29-5-19 15:03:25.734 | SqlRunner       | Test worker    | INFO : SQL (comment): -- Full Views
29-5-19 15:03:25.735 | SqlRunner       | Test worker    | INFO : SQL:    create view filehistory_full as select dbv.status as databaseversion_status, dbv.localtime as databaseversion_localtime, dbv.client as databaseversion_client, dbv.vectorclock_serialized as databaseversion_vectorclock_serialized, fh.* from databaseversion dbv join filehistory fh on dbv.id=fh.databaseversion_id; 
29-5-19 15:03:25.739 | SqlRunner       | Test worker    | INFO : SQL:  create view fileversion_full as select fhf.databaseversion_status, fhf.databaseversion_localtime, fhf.databaseversion_client, fhf.databaseversion_vectorclock_serialized, fv.* from filehistory_full fhf join fileversion fv on fhf.id=fv.filehistory_id and fhf.databaseversion_id=fv.databaseversion_id; 
29-5-19 15:03:25.745 | SqlRunner       | Test worker    | INFO : SQL (comment): -- Functions
29-5-19 15:03:25.745 | SqlRunner       | Test worker    | INFO : SQL (new delimiter): end;
29-5-19 15:03:25.745 | SqlRunner       | Test worker    | INFO : SQL:     create function substr_count(haystack varchar(255), needle varchar(255)) returns integer begin atomic declare strCount integer; declare lastIndex integer;  set strCount = 0; set lastIndex = 1;  while lastIndex <> 0 do set lastIndex = locate(needle, haystack, lastIndex);  if lastIndex <> 0 then set strCount = strCount + 1; set lastIndex = lastIndex + length(needle); end if; end while;  return strCount; end; 
29-5-19 15:03:25.790 | UpOperation     | Test worker    | INFO : 
29-5-19 15:03:25.794 | UpOperation     | Test worker    | INFO : Running 'Sync up' at client syncanyclientaTaAJGehehPEuzKrbpzO ...
29-5-19 15:03:25.794 | UpOperation     | Test worker    | INFO : --------------------------------------------
29-5-19 15:03:25.797 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event UpStartSyncExternalEvent (org.syncany.operations.daemon.messages.UpStartSyncExternalEvent@2c6a0773) ...
29-5-19 15:03:25.920 | DatabaseConnect | Test worker    | INFO : Found 12 tables.
29-5-19 15:03:25.920 | StatusOperation | Test worker    | INFO : 
29-5-19 15:03:25.921 | StatusOperation | Test worker    | INFO : Running 'Status' at client syncanyclientaTaAJGehehPEuzKrbpzO ...
29-5-19 15:03:25.921 | StatusOperation | Test worker    | INFO : --------------------------------------------
29-5-19 15:03:25.921 | StatusOperation | Test worker    | INFO : Force checksum ENABLED.
29-5-19 15:03:25.921 | StatusOperation | Test worker    | INFO : Querying current file tree from database ...
29-5-19 15:03:25.922 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event StatusStartSyncExternalEvent (org.syncany.operations.daemon.messages.StatusStartSyncExternalEvent@7460d6bd) ...
29-5-19 15:03:25.930 | StatusOperation | Test worker    | INFO : Analyzing local folder /tmp/syncanytest/syncany-190529150308467-91800-client-syncanyclient ...
29-5-19 15:03:25.933 | StatusOperation | Test worker    | FINE : - New file: rndFile-1559142203852-1244122048.dat
29-5-19 15:03:25.934 | StatusOperation | Test worker    | FINE : - Ignoring file (syncany app-related): .syncany
29-5-19 15:03:25.935 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event StatusEndSyncExternalEvent (org.syncany.operations.daemon.messages.StatusEndSyncExternalEvent@1b412dd4) ...
29-5-19 15:03:25.938 | AbstractTransfe | Test worker    | INFO : Looking for other running remote operations ...
29-5-19 15:03:25.954 | DatabaseConnect | Test worker    | INFO : Found 12 tables.
29-5-19 15:03:25.955 | LsRemoteOperati | Test worker    | INFO : 
29-5-19 15:03:25.955 | LsRemoteOperati | Test worker    | INFO : Running 'Remote Status' at client syncanyclientaTaAJGehehPEuzKrbpzO ...
29-5-19 15:03:25.955 | LsRemoteOperati | Test worker    | INFO : --------------------------------------------
29-5-19 15:03:25.956 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event LsRemoteStartSyncExternalEvent (org.syncany.operations.daemon.messages.LsRemoteStartSyncExternalEvent@57e49595) ...
29-5-19 15:03:25.960 | LsRemoteOperati | Test worker    | INFO : Retrieving remote database list.
29-5-19 15:03:25.964 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event LsRemoteEndSyncExternalEvent (org.syncany.operations.daemon.messages.LsRemoteEndSyncExternalEvent@24ef74c) ...
29-5-19 15:03:25.966 | UpOperation     | Test worker    | INFO : No remote changes, ready to upload.
29-5-19 15:03:25.970 | ActionFileHandl | Test worker    | INFO : Starting action for RemoteFile[name=action-up-syncanyclientaTaAJGehehPEuzKrbpzO-1559142204054] ...
29-5-19 15:03:25.970 | ActionFileHandl | Test worker    | INFO : Uploading action file: RemoteFile[name=action-up-syncanyclientaTaAJGehehPEuzKrbpzO-1559142204054]
29-5-19 15:03:25.991 | ActionFileHandl | Test worker    | INFO : Scheduling action renewal task for every 2 minutes, for RemoteFile[name=action-up-syncanyclientaTaAJGehehPEuzKrbpzO-1559142204054] ...
29-5-19 15:03:26.015 | DatabaseConnect | Test worker    | INFO : Found 12 tables.
29-5-19 15:03:26.026 | TransactionAwar | Test worker    | INFO : Done rolling back previous transactions.
29-5-19 15:03:26.026 | AsyncIndexer    | AsyncI/syncany | INFO : Starting Indexing.
29-5-19 15:03:26.037 | InternalEventBu | AsyncI/syncany | INFO : Event bus 'LocalEventBus': Posting event UpIndexStartSyncExternalEvent (org.syncany.operations.daemon.messages.UpIndexStartSyncExternalEvent@53ddbb42) ...
29-5-19 15:03:26.039 | Indexer         | AsyncI/syncany | FINE : - Looking for deleted files ...
29-5-19 15:03:26.042 | Indexer         | AsyncI/syncany | FINE : - +File /tmp/syncanytest/syncany-190529150308467-91800-client-syncanyclient/rndFile-1559142203852-1244122048.dat
29-5-19 15:03:26.047 | Indexer         | AsyncI/syncany | FINE : - +FileContent: /tmp/syncanytest/syncany-190529150308467-91800-client-syncanyclient/rndFile-1559142203852-1244122048.dat
29-5-19 15:03:26.060 | Indexer         | AsyncI/syncany | FINE : - Chunk new: 37631bdade91624ef1c517b161756eb263521804
29-5-19 15:03:26.061 | Indexer         | AsyncI/syncany | FINE : - +MultiChunk 4d994fde1d850327a6002734df90aea86ed9805f
29-5-19 15:03:26.063 | Indexer         | AsyncI/syncany | FINE : - Chunk > MultiChunk: 37631bdade91624ef1c517b161756eb263521804 > 4d994fde1d850327a6002734df90aea86ed9805f
29-5-19 15:03:26.063 | Indexer         | AsyncI/syncany | FINE : - Chunk > FileContent: 37631bdade91624ef1c517b161756eb263521804 > /tmp/syncanytest/syncany-190529150308467-91800-client-syncanyclient/rndFile-1559142203852-1244122048.dat
29-5-19 15:03:26.066 | Indexer         | AsyncI/syncany | FINE : - /File: rndFile-1559142203852-1244122048.dat (checksum 37631bdade91624ef1c517b161756eb263521804)
29-5-19 15:03:26.096 | Indexer         | AsyncI/syncany | FINE :    * No old file history found, starting new history (path: rndFile-1559142203852-1244122048.dat, checksum: 37631bdade91624ef1c517b161756eb263521804)
29-5-19 15:03:26.101 | FileVersionComp | AsyncI/syncany | INFO :      - [DELETED]: Local file DIFFERS from file version, actual file is NULL, for file rndFile-1559142203852-1244122048.dat
29-5-19 15:03:26.107 | Indexer         | AsyncI/syncany | INFO :    * Added file version:    FileVersion [version=1, path=rndFile-1559142203852-1244122048.dat, type=FILE, status=NEW, size=40, lastModified=Wed May 29 15:03:23 UTC 2019, linkTarget=null, checksum=37631bdade91624ef1c517b161756eb263521804, updated=Wed May 29 15:03:26 UTC 2019, posixPermissions=rw-rw-r--, dosAttributes=--a-]
29-5-19 15:03:26.107 | Indexer         | AsyncI/syncany | INFO :      based on file version: null
29-5-19 15:03:26.108 | InternalEventBu | AsyncI/syncany | INFO : Event bus 'LocalEventBus': Posting event UpIndexChangesDetectedSyncExternalEvent (org.syncany.operations.daemon.messages.UpIndexChangesDetectedSyncExternalEvent@7acec836) ...
29-5-19 15:03:26.116 | Indexer         | AsyncI/syncany | FINE : - /MultiChunk 4d994fde1d850327a6002734df90aea86ed9805f
29-5-19 15:03:26.117 | InternalEventBu | AsyncI/syncany | INFO : Event bus 'LocalEventBus': Posting event UpIndexEndSyncExternalEvent (org.syncany.operations.daemon.messages.UpIndexEndSyncExternalEvent@1090adf3) ...
29-5-19 15:03:26.117 | Indexer         | AsyncI/syncany | FINE : Processed new database version: DatabaseVersion [header=UnknownMachine/()/T=1559142206027, chunks=1, multiChunks=1, fileContents=1, fileHistories=1]
29-5-19 15:03:26.121 | UpOperation     | Test worker    | INFO : No DIRTY data found in database (no dirty databases); Nothing to do here.
29-5-19 15:03:26.122 | InternalEventBu | AsyncI/syncany | INFO : Event bus 'LocalEventBus': Posting event UpIndexMidSyncExternalEvent (org.syncany.operations.daemon.messages.UpIndexMidSyncExternalEvent@779b5e97) ...
29-5-19 15:03:26.123 | UpOperation     | Test worker    | INFO : Last vector clock was: ()
29-5-19 15:03:26.139 | UpOperation     | Test worker    | INFO : Uploading new multichunks ...
29-5-19 15:03:26.140 | AsyncIndexer    | AsyncI/syncany | INFO : Stopping indexing. Signal end of stream with empty databaseversion
29-5-19 15:03:26.143 | UpOperation     | Test worker    | INFO : - Uploading multichunk 4d994fde1d850327a6002734df90aea86ed9805f from /tmp/syncanytest/syncany-190529150308467-91800-client-syncanyclient/.syncany/cache/multichunk-4d994fde1d850327a6002734df90aea86ed9805f to RemoteFile[name=multichunk-4d994fde1d850327a6002734df90aea86ed9805f] ...
29-5-19 15:03:26.151 | RemoteTransacti | Test worker    | INFO : - Adding file to TX for UPLOAD: /tmp/syncanytest/syncany-190529150308467-91800-client-syncanyclient/.syncany/cache/multichunk-4d994fde1d850327a6002734df90aea86ed9805f -> Temp. remote file: RemoteFile[name=temp-MNosX-multichunk-4d994fde1d850327a6002734df90aea86ed9805f], final location: RemoteFile[name=multichunk-4d994fde1d850327a6002734df90aea86ed9805f]
29-5-19 15:03:26.154 | UpOperation     | Test worker    | INFO : Uploading database: DatabaseVersion [header=syncanyclientaTaAJGehehPEuzKrbpzO/(syncanyclientaTaAJGehehPEuzKrbpzO1)/T=1559142206127, chunks=1, multiChunks=1, fileContents=1, fileHistories=1]
29-5-19 15:03:26.156 | UpOperation     | Test worker    | INFO : Saving local delta database, version syncanyclientaTaAJGehehPEuzKrbpzO/(syncanyclientaTaAJGehehPEuzKrbpzO1)/T=1559142206127 to file /tmp/syncanytest/syncany-190529150308467-91800-client-syncanyclient/.syncany/cache/database-syncanyclientaTaAJGehehPEuzKrbpzO-0000000001 ... 
29-5-19 15:03:26.157 | UpOperation     | Test worker    | INFO : - Saving database to /tmp/syncanytest/syncany-190529150308467-91800-client-syncanyclient/.syncany/cache/database-syncanyclientaTaAJGehehPEuzKrbpzO-0000000001 ...
29-5-19 15:03:26.182 | UpOperation     | Test worker    | INFO : - Uploading local delta database file ...
29-5-19 15:03:26.182 | UpOperation     | Test worker    | INFO : - Uploading /tmp/syncanytest/syncany-190529150308467-91800-client-syncanyclient/.syncany/cache/database-syncanyclientaTaAJGehehPEuzKrbpzO-0000000001 to RemoteFile[name=database-syncanyclientaTaAJGehehPEuzKrbpzO-0000000001] ...
29-5-19 15:03:26.183 | RemoteTransacti | Test worker    | INFO : - Adding file to TX for UPLOAD: /tmp/syncanytest/syncany-190529150308467-91800-client-syncanyclient/.syncany/cache/database-syncanyclientaTaAJGehehPEuzKrbpzO-0000000001 -> Temp. remote file: RemoteFile[name=temp-eOhyC-database-syncanyclientaTaAJGehehPEuzKrbpzO-0000000001], final location: RemoteFile[name=database-syncanyclientaTaAJGehehPEuzKrbpzO-0000000001]
29-5-19 15:03:26.196 | UpOperation     | Test worker    | INFO : Adding shutdown hook (to allow resuming the upload) ...
29-5-19 15:03:26.197 | RemoteTransacti | Test worker    | INFO : Starting TX.commit() ...
29-5-19 15:03:26.213 | RemoteTransacti | Test worker    | INFO : Wrote transaction manifest to temporary file: /tmp/syncanytest/syncany-190529150308467-91800-client-syncanyclient/.syncany/cache/temp-transaction-5732223565352226790.tmp
29-5-19 15:03:26.215 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event UpUploadFileSyncExternalEvent (org.syncany.operations.daemon.messages.UpUploadFileSyncExternalEvent@3ca5fa6b) ...
29-5-19 15:03:26.222 | RemoteTransacti | Test worker    | INFO : - Uploading remote transaction file RemoteFile[name=transaction-48a0364a] ...
29-5-19 15:03:26.234 | RemoteTransacti | Test worker    | INFO : - Starting to upload data in commit.
29-5-19 15:03:26.236 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event UpUploadFileInTransactionSyncExternalEvent (org.syncany.operations.daemon.messages.UpUploadFileInTransactionSyncExternalEvent@17debff9) ...
29-5-19 15:03:26.237 | RemoteTransacti | Test worker    | INFO : - Uploading /tmp/syncanytest/syncany-190529150308467-91800-client-syncanyclient/.syncany/cache/multichunk-4d994fde1d850327a6002734df90aea86ed9805f to temp. file RemoteFile[name=temp-MNosX-multichunk-4d994fde1d850327a6002734df90aea86ed9805f] ...
29-5-19 15:03:26.238 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event UpUploadFileInTransactionSyncExternalEvent (org.syncany.operations.daemon.messages.UpUploadFileInTransactionSyncExternalEvent@2dcfe339) ...
29-5-19 15:03:26.246 | RemoteTransacti | Test worker    | INFO : - Uploading /tmp/syncanytest/syncany-190529150308467-91800-client-syncanyclient/.syncany/cache/database-syncanyclientaTaAJGehehPEuzKrbpzO-0000000001 to temp. file RemoteFile[name=temp-eOhyC-database-syncanyclientaTaAJGehehPEuzKrbpzO-0000000001] ...
29-5-19 15:03:26.247 | RemoteTransacti | Test worker    | INFO : - Moving temp. file RemoteFile[name=temp-MNosX-multichunk-4d994fde1d850327a6002734df90aea86ed9805f] to final location RemoteFile[name=multichunk-4d994fde1d850327a6002734df90aea86ed9805f] ...
29-5-19 15:03:26.249 | RemoteTransacti | Test worker    | INFO : - Moving temp. file RemoteFile[name=temp-eOhyC-database-syncanyclientaTaAJGehehPEuzKrbpzO-0000000001] to final location RemoteFile[name=database-syncanyclientaTaAJGehehPEuzKrbpzO-0000000001] ...
29-5-19 15:03:26.249 | RemoteTransacti | Test worker    | INFO : - Deleting remote transaction file RemoteFile[name=transaction-48a0364a] ...
29-5-19 15:03:26.251 | RemoteTransacti | Test worker    | INFO : END of TX.commmit(): Succesfully committed transaction.
29-5-19 15:03:26.251 | RemoteTransacti | Test worker    | INFO : END of TX.delTemp(): Sucessfully deleted final files.
29-5-19 15:03:26.251 | UpOperation     | Test worker    | INFO : Persisting local SQL database (new database version syncanyclientaTaAJGehehPEuzKrbpzO/(syncanyclientaTaAJGehehPEuzKrbpzO1)/T=1559142206127) ...
29-5-19 15:03:26.291 | UpOperation     | Test worker    | INFO : Removing DIRTY database versions from database ...
29-5-19 15:03:26.368 | UpOperation     | Test worker    | INFO : Adding database version to result changes:DatabaseVersion [header=syncanyclientaTaAJGehehPEuzKrbpzO/(syncanyclientaTaAJGehehPEuzKrbpzO1)/T=1559142206127, chunks=1, multiChunks=1, fileContents=1, fileHistories=1]
29-5-19 15:03:26.369 | UpOperation     | Test worker    | INFO : Committing local database.
29-5-19 15:03:26.370 | UpOperation     | Test worker    | FINE : Waiting for new database version.
29-5-19 15:03:26.370 | UpOperation     | Test worker    | FINE : Took new database version: DatabaseVersion [header=UnknownMachine/()/T=1559142206141, chunks=0, multiChunks=0, fileContents=0, fileHistories=0]
29-5-19 15:03:26.370 | UpOperation     | Test worker    | INFO : Sync up done.
29-5-19 15:03:26.376 | ActionFileHandl | Test worker    | INFO : Finishing action for RemoteFile[name=action-up-syncanyclientaTaAJGehehPEuzKrbpzO-1559142204054] ...
29-5-19 15:03:26.380 | ActionFileHandl | Test worker    | INFO : Deleting action file: RemoteFile[name=action-up-syncanyclientaTaAJGehehPEuzKrbpzO-1559142204054]
29-5-19 15:03:26.380 | AbstractTransfe | Test worker    | INFO : Cleaning own old action files ...
29-5-19 15:03:26.382 | Cache           | Test worker    | INFO : Cache size okay (1 KB), no need to clean (keep size is 500 MB)
29-5-19 15:03:26.383 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event UpEndSyncExternalEvent (org.syncany.operations.daemon.messages.UpEndSyncExternalEvent@12d228e4) ...
29-5-19 15:03:26.391 | DatabaseConnect | Test worker    | INFO : Found 12 tables.
29-5-19 15:03:26.399 | StatusOperation | Test worker    | INFO : 
29-5-19 15:03:26.399 | StatusOperation | Test worker    | INFO : Running 'Status' at client syncanyclientaTaAJGehehPEuzKrbpzO ...
29-5-19 15:03:26.399 | StatusOperation | Test worker    | INFO : --------------------------------------------
29-5-19 15:03:26.399 | StatusOperation | Test worker    | INFO : Force checksum ENABLED.
29-5-19 15:03:26.399 | StatusOperation | Test worker    | INFO : Querying current file tree from database ...
29-5-19 15:03:26.399 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event StatusStartSyncExternalEvent (org.syncany.operations.daemon.messages.StatusStartSyncExternalEvent@1a60ca08) ...
29-5-19 15:03:26.415 | StatusOperation | Test worker    | INFO : Analyzing local folder /tmp/syncanytest/syncany-190529150308467-91800-client-syncanyclient ...
29-5-19 15:03:26.417 | FileVersionComp | Test worker    | INFO :      - [CHANGED_LAST_MOD_DATE]: Local file DIFFERS from file version, expected MOD. DATE = 5/29/19, 3:03 PM (1,559,142,203,847), but actual MOD. DATE = 5/29/19, 3:03 PM (1,559,142,206,379), for file rndFile-1559142203852-1244122048.dat
29-5-19 15:03:26.429 | FileVersionComp | Test worker    | INFO :      - [CHANGED_CHECKSUM, CHANGED_LAST_MOD_DATE]: Local file DIFFERS from file version, expected CHECKSUM = 37631bdade91624ef1c517b161756eb263521804, but actual CHECKSUM = 9461df2105116dbc0a12e51faa7b8f3d2587f5b8, for file rndFile-1559142203852-1244122048.dat
29-5-19 15:03:26.430 | StatusOperation | Test worker    | FINE : - Ignoring file (syncany app-related): .syncany
29-5-19 15:03:26.430 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event StatusEndSyncExternalEvent (org.syncany.operations.daemon.messages.StatusEndSyncExternalEvent@2ad79467) ...
29-5-19 15:03:26.433 | org.syncany.plu | Test worker    | FINE : Validating required fields
29-5-19 15:03:26.433 | LocalTransferMa | Test worker    | INFO : testTargetExists: Target exists.
29-5-19 15:03:26.563 | hsqldb.db.HSQLD | Test worker    | INFO : Checkpoint start
29-5-19 15:03:26.564 | hsqldb.db.HSQLD | Test worker    | INFO : checkpointClose start
29-5-19 15:03:26.573 | hsqldb.db.HSQLD | Test worker    | FINE : log close size: 0
29-5-19 15:03:26.576 | hsqldb.db.HSQLD | Test worker    | INFO : checkpointClose end
29-5-19 15:03:26.577 | hsqldb.db.HSQLD | Test worker    | INFO : Checkpoint end - txts: 1
29-5-19 15:03:26.585 | DatabaseConnect | Test worker    | INFO : Found 0 tables.
29-5-19 15:03:26.585 | DatabaseConnect | Test worker    | INFO : Database has no tables. Creating tables from script.create.all.sql
29-5-19 15:03:26.586 | SqlRunner       | Test worker    | INFO : SQL (comment): -- Tables
29-5-19 15:03:26.586 | SqlRunner       | Test worker    | INFO : SQL:  CREATE CACHED TABLE IF NOT EXISTS databaseversion ( id int NOT NULL IDENTITY, status varchar(45) NOT NULL, localtime datetime NOT NULL, client varchar(45) NOT NULL, vectorclock_serialized varchar(1024) NOT NULL, UNIQUE (vectorclock_serialized) ); 
29-5-19 15:03:26.596 | SqlRunner       | Test worker    | INFO : SQL:  CREATE CACHED TABLE IF NOT EXISTS  chunk ( checksum varchar(40) NOT NULL, databaseversion_id int NOT NULL, size bigint NOT NULL, PRIMARY KEY (checksum), FOREIGN KEY (databaseversion_id) REFERENCES databaseversion (id) ON DELETE NO ACTION ON UPDATE NO ACTION ); 
29-5-19 15:03:26.599 | SqlRunner       | Test worker    | INFO : SQL:  CREATE CACHED TABLE IF NOT EXISTS  databaseversion_vectorclock ( databaseversion_id int NOT NULL, client varchar(45) NOT NULL, logicaltime int NOT NULL, PRIMARY KEY (databaseversion_id, client), FOREIGN KEY (databaseversion_id) REFERENCES databaseversion (id) ON DELETE NO ACTION ON UPDATE NO ACTION ); 
29-5-19 15:03:26.602 | SqlRunner       | Test worker    | INFO : SQL:  CREATE CACHED TABLE IF NOT EXISTS  filecontent ( checksum varchar(40) NOT NULL, databaseversion_id int NOT NULL, size bigint NOT NULL, PRIMARY KEY (checksum), FOREIGN KEY (databaseversion_id) REFERENCES databaseversion (id) ON DELETE NO ACTION ON UPDATE NO ACTION ); 
29-5-19 15:03:26.605 | SqlRunner       | Test worker    | INFO : SQL:  CREATE CACHED TABLE IF NOT EXISTS  filecontent_chunk ( filecontent_checksum varchar(40) NOT NULL, chunk_checksum varchar(40) NOT NULL, num int NOT NULL, PRIMARY KEY (filecontent_checksum, chunk_checksum, num), FOREIGN KEY (filecontent_checksum) REFERENCES filecontent (checksum) ON DELETE NO ACTION ON UPDATE NO ACTION, FOREIGN KEY (chunk_checksum) REFERENCES chunk (checksum) ON DELETE NO ACTION ON UPDATE NO ACTION ); 
29-5-19 15:03:26.615 | SqlRunner       | Test worker    | INFO : SQL:  CREATE CACHED TABLE IF NOT EXISTS  filehistory ( id varchar(40) NOT NULL, databaseversion_id int NOT NULL, PRIMARY KEY (id, databaseversion_id), FOREIGN KEY (databaseversion_id) REFERENCES databaseversion (id) ON DELETE NO ACTION ON UPDATE NO ACTION ); 
29-5-19 15:03:26.619 | SqlRunner       | Test worker    | INFO : SQL:  CREATE CACHED TABLE IF NOT EXISTS  fileversion ( filehistory_id varchar(40) NOT NULL, version int NOT NULL, databaseversion_id int NOT NULL, path varchar(1024) NOT NULL, type varchar(45) NOT NULL, status varchar(45) NOT NULL, size bigint NOT NULL, lastmodified datetime NOT NULL, linktarget varchar(1024), filecontent_checksum varchar(40) DEFAULT NULL, updated datetime NOT NULL, posixperms varchar(45) DEFAULT NULL, dosattrs varchar(45) DEFAULT NULL, PRIMARY KEY (filehistory_id, version, databaseversion_id), FOREIGN KEY (filehistory_id, databaseversion_id) REFERENCES filehistory (id, databaseversion_id) ON DELETE NO ACTION ON UPDATE NO ACTION, FOREIGN KEY (filecontent_checksum) REFERENCES filecontent (checksum) ON DELETE NO ACTION ON UPDATE NO ACTION ); 
29-5-19 15:03:26.623 | SqlRunner       | Test worker    | INFO : SQL:  CREATE CACHED TABLE IF NOT EXISTS  multichunk ( id varchar(40) NOT NULL, databaseversion_id int NOT NULL, size bigint NOT NULL, PRIMARY KEY (id), FOREIGN KEY (databaseversion_id) REFERENCES databaseversion (id) ON DELETE NO ACTION ON UPDATE NO ACTION ); 
29-5-19 15:03:26.626 | SqlRunner       | Test worker    | INFO : SQL:  CREATE CACHED TABLE IF NOT EXISTS  multichunk_chunk ( multichunk_id varchar(40) NOT NULL, chunk_checksum varchar(40) NOT NULL, PRIMARY KEY (multichunk_id, chunk_checksum), FOREIGN KEY (multichunk_id) REFERENCES multichunk (id) ON DELETE NO ACTION ON UPDATE NO ACTION, FOREIGN KEY (chunk_checksum) REFERENCES chunk (checksum) ON DELETE NO ACTION ON UPDATE NO ACTION ); 
29-5-19 15:03:26.629 | SqlRunner       | Test worker    | INFO : SQL:  CREATE CACHED TABLE IF NOT EXISTS  multichunk_muddy ( id varchar(40) NOT NULL, machine_name varchar(255) NOT NULL, machine_version int NOT NULL, PRIMARY KEY (id) ); 
29-5-19 15:03:26.631 | SqlRunner       | Test worker    | INFO : SQL:  CREATE CACHED TABLE IF NOT EXISTS  known_databases ( id int NOT NULL IDENTITY, client varchar(45) NOT NULL, filenumber int NOT NULL, UNIQUE (client, filenumber) ); 
29-5-19 15:03:26.634 | SqlRunner       | Test worker    | INFO : SQL:  CREATE CACHED TABLE IF NOT EXISTS  general_settings ( key varchar(255) NOT NULL, value varchar(255) NOT NULL, PRIMARY KEY (key) ); 
29-5-19 15:03:26.636 | SqlRunner       | Test worker    | INFO : SQL (comment): -- Non-primary indices
29-5-19 15:03:26.636 | SqlRunner       | Test worker    | INFO : SQL:   CREATE INDEX idx_databaseversion_status ON databaseversion (status); 
29-5-19 15:03:26.638 | hsqldb.db.HSQLD | Test worker    | INFO : dataFileCache open start
29-5-19 15:03:26.640 | hsqldb.db.HSQLD | Test worker    | INFO : dataFileCache open end
29-5-19 15:03:26.642 | SqlRunner       | Test worker    | INFO : SQL: CREATE INDEX idx_databaseversion_vectorclock_serialized ON databaseversion (vectorclock_serialized); 
29-5-19 15:03:26.644 | SqlRunner       | Test worker    | INFO : SQL: CREATE INDEX idx_fileversion_path ON fileversion (path); 
29-5-19 15:03:26.647 | SqlRunner       | Test worker    | INFO : SQL: CREATE INDEX idx_fileversion_status ON fileversion (status); 
29-5-19 15:03:26.649 | SqlRunner       | Test worker    | INFO : SQL: CREATE INDEX idx_fileversion_filecontent_checksum ON fileversion (filecontent_checksum); 
29-5-19 15:03:26.650 | SqlRunner       | Test worker    | INFO : SQL (comment): -- Views
29-5-19 15:03:26.651 | SqlRunner       | Test worker    | INFO : SQL:    CREATE VIEW databaseversion_master AS SELECT dbv.*, vc.logicaltime as client_version FROM databaseversion dbv JOIN databaseversion_vectorclock vc on dbv.id=vc.databaseversion_id and dbv.client=vc.client WHERE dbv.status='MASTER'; 
29-5-19 15:03:26.686 | SqlRunner       | Test worker    | INFO : SQL:  CREATE VIEW fileversion_master AS SELECT fv0.* FROM fileversion fv0 JOIN databaseversion dbv ON fv0.databaseversion_id=dbv.id AND dbv.status='MASTER'; 
29-5-19 15:03:26.695 | SqlRunner       | Test worker    | INFO : SQL:  CREATE VIEW fileversion_master_maxversion AS SELECT DISTINCT filehistory_id, MAX(version) version FROM fileversion_master GROUP BY filehistory_id; 
29-5-19 15:03:26.701 | SqlRunner       | Test worker    | INFO : SQL:  CREATE VIEW fileversion_master_last AS SELECT fv.* FROM fileversion_master_maxversion fvmax JOIN fileversion_master fv ON fvmax.filehistory_id=fv.filehistory_id AND fvmax.version=fv.version WHERE fv.status<>'DELETED'; 
29-5-19 15:03:26.712 | SqlRunner       | Test worker    | INFO : SQL (comment): -- Full Views
29-5-19 15:03:26.712 | SqlRunner       | Test worker    | INFO : SQL:    create view filehistory_full as select dbv.status as databaseversion_status, dbv.localtime as databaseversion_localtime, dbv.client as databaseversion_client, dbv.vectorclock_serialized as databaseversion_vectorclock_serialized, fh.* from databaseversion dbv join filehistory fh on dbv.id=fh.databaseversion_id; 
29-5-19 15:03:26.719 | SqlRunner       | Test worker    | INFO : SQL:  create view fileversion_full as select fhf.databaseversion_status, fhf.databaseversion_localtime, fhf.databaseversion_client, fhf.databaseversion_vectorclock_serialized, fv.* from filehistory_full fhf join fileversion fv on fhf.id=fv.filehistory_id and fhf.databaseversion_id=fv.databaseversion_id; 
29-5-19 15:03:26.732 | SqlRunner       | Test worker    | INFO : SQL (comment): -- Functions
29-5-19 15:03:26.732 | SqlRunner       | Test worker    | INFO : SQL (new delimiter): end;
29-5-19 15:03:26.732 | SqlRunner       | Test worker    | INFO : SQL:     create function substr_count(haystack varchar(255), needle varchar(255)) returns integer begin atomic declare strCount integer; declare lastIndex integer;  set strCount = 0; set lastIndex = 1;  while lastIndex <> 0 do set lastIndex = locate(needle, haystack, lastIndex);  if lastIndex <> 0 then set strCount = strCount + 1; set lastIndex = lastIndex + length(needle); end if; end while;  return strCount; end; 
29-5-19 15:03:26.736 | StatusOperation | Test worker    | INFO : 
29-5-19 15:03:26.736 | StatusOperation | Test worker    | INFO : Running 'Status' at client syncanyclientmBafasIVzPifzaFSKjpe ...
29-5-19 15:03:26.737 | StatusOperation | Test worker    | INFO : --------------------------------------------
29-5-19 15:03:26.737 | StatusOperation | Test worker    | INFO : Querying current file tree from database ...
29-5-19 15:03:26.737 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event StatusStartSyncExternalEvent (org.syncany.operations.daemon.messages.StatusStartSyncExternalEvent@34274a49) ...
29-5-19 15:03:26.740 | StatusOperation | Test worker    | INFO : Analyzing local folder /tmp/syncanytest/syncany-190529150308467-91037-client-syncanyclient ...
29-5-19 15:03:26.741 | StatusOperation | Test worker    | FINE : - New file: rndFile-1559142206467-1764731734.dat
29-5-19 15:03:26.741 | StatusOperation | Test worker    | FINE : - New file: rndFile-1559142206456-1409122369.dat
29-5-19 15:03:26.742 | StatusOperation | Test worker    | FINE : - Ignoring file (syncany app-related): .syncany
29-5-19 15:03:26.742 | StatusOperation | Test worker    | FINE : - New file: rndFile-1559142206453-591483599.dat
29-5-19 15:03:26.743 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event StatusEndSyncExternalEvent (org.syncany.operations.daemon.messages.StatusEndSyncExternalEvent@2f4282f4) ...
29-5-19 15:03:26.743 | org.syncany.plu | Test worker    | FINE : Validating required fields
29-5-19 15:03:26.744 | TransferManager | Test worker    | INFO : Building LocalTransferManager from config 'syncany-190529150308467-91037-client-syncanyclient' ...
29-5-19 15:03:26.750 | TransferManager | Test worker    | INFO : - With feature ReadAfterWriteConsistent
29-5-19 15:03:26.750 | TransferManager | Test worker    | INFO : - With feature Retriable
29-5-19 15:03:26.750 | TransferManager | Test worker    | FINE : - Checking if selected features supported ...
29-5-19 15:03:26.751 | TransferManager | Test worker    | FINE : - Checking for duplicate features ...
29-5-19 15:03:26.751 | TransferManager | Test worker    | FINE : - Checking required feature TransactionAware in LocalTransferManager ...
29-5-19 15:03:26.751 | TransferManager | Test worker    | FINE : - Checking required feature Retriable in LocalTransferManager ...
29-5-19 15:03:26.751 | TransferManager | Test worker    | INFO : - SKIPPING unsupported optional feature ReadAfterWriteConsistent
29-5-19 15:03:26.751 | TransferManager | Test worker    | FINE : - Wrapping TransferManager LocalTransferManager in RetriableFeatureTransferManager
29-5-19 15:03:26.770 | org.syncany.plu | Test worker    | FINE : Validating required fields
29-5-19 15:03:26.771 | TransferManager | Test worker    | INFO : Building LocalTransferManager from config 'syncany-190529150308467-91037-client-syncanyclient' ...
29-5-19 15:03:26.771 | TransferManager | Test worker    | INFO : - With feature ReadAfterWriteConsistent
29-5-19 15:03:26.771 | TransferManager | Test worker    | INFO : - With feature Retriable
29-5-19 15:03:26.771 | TransferManager | Test worker    | INFO : - With feature PathAware
29-5-19 15:03:26.771 | TransferManager | Test worker    | INFO : - With feature TransactionAware
29-5-19 15:03:26.771 | TransferManager | Test worker    | FINE : - Checking if selected features supported ...
29-5-19 15:03:26.771 | TransferManager | Test worker    | FINE : - Checking for duplicate features ...
29-5-19 15:03:26.771 | TransferManager | Test worker    | FINE : - Checking required feature TransactionAware in LocalTransferManager ...
29-5-19 15:03:26.771 | TransferManager | Test worker    | FINE : - Checking required feature Retriable in LocalTransferManager ...
29-5-19 15:03:26.771 | TransferManager | Test worker    | INFO : - SKIPPING unsupported optional feature ReadAfterWriteConsistent
29-5-19 15:03:26.771 | TransferManager | Test worker    | FINE : - Wrapping TransferManager LocalTransferManager in RetriableFeatureTransferManager
29-5-19 15:03:26.771 | TransferManager | Test worker    | INFO : - SKIPPING unsupported optional feature PathAware
29-5-19 15:03:26.771 | TransferManager | Test worker    | FINE : - Wrapping TransferManager RetriableFeatureTransferManager in TransactionAwareFeatureTransferManager
29-5-19 15:03:26.787 | DatabaseConnect | Test worker    | INFO : Found 12 tables.
29-5-19 15:03:26.791 | UpOperation     | Test worker    | INFO : 
29-5-19 15:03:26.791 | UpOperation     | Test worker    | INFO : Running 'Sync up' at client syncanyclientmBafasIVzPifzaFSKjpe ...
29-5-19 15:03:26.791 | UpOperation     | Test worker    | INFO : --------------------------------------------
29-5-19 15:03:26.791 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event UpStartSyncExternalEvent (org.syncany.operations.daemon.messages.UpStartSyncExternalEvent@3559ae4f) ...
29-5-19 15:03:26.795 | DatabaseConnect | Test worker    | INFO : Found 12 tables.
29-5-19 15:03:26.795 | StatusOperation | Test worker    | INFO : 
29-5-19 15:03:26.795 | StatusOperation | Test worker    | INFO : Running 'Status' at client syncanyclientmBafasIVzPifzaFSKjpe ...
29-5-19 15:03:26.795 | StatusOperation | Test worker    | INFO : --------------------------------------------
29-5-19 15:03:26.795 | StatusOperation | Test worker    | INFO : Querying current file tree from database ...
29-5-19 15:03:26.796 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event StatusStartSyncExternalEvent (org.syncany.operations.daemon.messages.StatusStartSyncExternalEvent@33820b61) ...
29-5-19 15:03:26.799 | StatusOperation | Test worker    | INFO : Analyzing local folder /tmp/syncanytest/syncany-190529150308467-91037-client-syncanyclient ...
29-5-19 15:03:26.807 | StatusOperation | Test worker    | FINE : - New file: rndFile-1559142206467-1764731734.dat
29-5-19 15:03:26.807 | StatusOperation | Test worker    | FINE : - New file: rndFile-1559142206456-1409122369.dat
29-5-19 15:03:26.808 | StatusOperation | Test worker    | FINE : - Ignoring file (syncany app-related): .syncany
29-5-19 15:03:26.808 | StatusOperation | Test worker    | FINE : - New file: rndFile-1559142206453-591483599.dat
29-5-19 15:03:26.808 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event StatusEndSyncExternalEvent (org.syncany.operations.daemon.messages.StatusEndSyncExternalEvent@17a9cedf) ...
29-5-19 15:03:26.809 | AbstractTransfe | Test worker    | INFO : Looking for other running remote operations ...
29-5-19 15:03:26.811 | DatabaseConnect | Test worker    | INFO : Found 12 tables.
29-5-19 15:03:26.811 | LsRemoteOperati | Test worker    | INFO : 
29-5-19 15:03:26.812 | LsRemoteOperati | Test worker    | INFO : Running 'Remote Status' at client syncanyclientmBafasIVzPifzaFSKjpe ...
29-5-19 15:03:26.812 | LsRemoteOperati | Test worker    | INFO : --------------------------------------------
29-5-19 15:03:26.812 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event LsRemoteStartSyncExternalEvent (org.syncany.operations.daemon.messages.LsRemoteStartSyncExternalEvent@66184a47) ...
29-5-19 15:03:26.813 | LsRemoteOperati | Test worker    | INFO : Retrieving remote database list.
29-5-19 15:03:26.822 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event LsRemoteEndSyncExternalEvent (org.syncany.operations.daemon.messages.LsRemoteEndSyncExternalEvent@77bda8f6) ...
29-5-19 15:03:26.823 | UpOperation     | Test worker    | INFO : No remote changes, ready to upload.
29-5-19 15:03:26.823 | ActionFileHandl | Test worker    | INFO : Starting action for RemoteFile[name=action-up-syncanyclientmBafasIVzPifzaFSKjpe-1559142206771] ...
29-5-19 15:03:26.823 | ActionFileHandl | Test worker    | INFO : Uploading action file: RemoteFile[name=action-up-syncanyclientmBafasIVzPifzaFSKjpe-1559142206771]
29-5-19 15:03:26.824 | ActionFileHandl | Test worker    | INFO : Scheduling action renewal task for every 2 minutes, for RemoteFile[name=action-up-syncanyclientmBafasIVzPifzaFSKjpe-1559142206771] ...
29-5-19 15:03:26.831 | DatabaseConnect | Test worker    | INFO : Found 12 tables.
29-5-19 15:03:26.834 | TransactionAwar | Test worker    | INFO : Done rolling back previous transactions.
29-5-19 15:03:26.834 | AsyncIndexer    | AsyncI/syncany | INFO : Starting Indexing.
29-5-19 15:03:26.835 | InternalEventBu | AsyncI/syncany | INFO : Event bus 'LocalEventBus': Posting event UpIndexStartSyncExternalEvent (org.syncany.operations.daemon.messages.UpIndexStartSyncExternalEvent@21c465f8) ...
29-5-19 15:03:26.838 | Indexer         | AsyncI/syncany | FINE : - Looking for deleted files ...
29-5-19 15:03:26.838 | Indexer         | AsyncI/syncany | FINE : - +File /tmp/syncanytest/syncany-190529150308467-91037-client-syncanyclient/rndFile-1559142206453-591483599.dat
29-5-19 15:03:26.839 | Indexer         | AsyncI/syncany | FINE : - +FileContent: /tmp/syncanytest/syncany-190529150308467-91037-client-syncanyclient/rndFile-1559142206453-591483599.dat
29-5-19 15:03:26.857 | Indexer         | AsyncI/syncany | FINE : - Chunk new: 37acd012dab04e89fefecdc76c480e2459bd509c
29-5-19 15:03:26.865 | Indexer         | AsyncI/syncany | FINE : - +MultiChunk db2e753aee968acd2209bd6c513bc883b3f84b23
29-5-19 15:03:26.878 | Indexer         | AsyncI/syncany | FINE : - Chunk > MultiChunk: 37acd012dab04e89fefecdc76c480e2459bd509c > db2e753aee968acd2209bd6c513bc883b3f84b23
29-5-19 15:03:26.881 | Indexer         | AsyncI/syncany | FINE : - Chunk > FileContent: 37acd012dab04e89fefecdc76c480e2459bd509c > /tmp/syncanytest/syncany-190529150308467-91037-client-syncanyclient/rndFile-1559142206453-591483599.dat
29-5-19 15:03:26.882 | Indexer         | AsyncI/syncany | FINE : - /File: rndFile-1559142206453-591483599.dat (checksum 37acd012dab04e89fefecdc76c480e2459bd509c)
29-5-19 15:03:26.888 | Indexer         | AsyncI/syncany | FINE :    * No old file history found, starting new history (path: rndFile-1559142206453-591483599.dat, checksum: 37acd012dab04e89fefecdc76c480e2459bd509c)
29-5-19 15:03:26.890 | FileVersionComp | AsyncI/syncany | INFO :      - [DELETED]: Local file DIFFERS from file version, actual file is NULL, for file rndFile-1559142206453-591483599.dat
29-5-19 15:03:26.890 | Indexer         | AsyncI/syncany | INFO :    * Added file version:    FileVersion [version=1, path=rndFile-1559142206453-591483599.dat, type=FILE, status=NEW, size=512000, lastModified=Wed May 29 15:03:26 UTC 2019, linkTarget=null, checksum=37acd012dab04e89fefecdc76c480e2459bd509c, updated=Wed May 29 15:03:26 UTC 2019, posixPermissions=rw-rw-r--, dosAttributes=--a-]
29-5-19 15:03:26.891 | Indexer         | AsyncI/syncany | INFO :      based on file version: null
29-5-19 15:03:26.891 | InternalEventBu | AsyncI/syncany | INFO : Event bus 'LocalEventBus': Posting event UpIndexChangesDetectedSyncExternalEvent (org.syncany.operations.daemon.messages.UpIndexChangesDetectedSyncExternalEvent@1ffb4c7f) ...
29-5-19 15:03:26.893 | Indexer         | AsyncI/syncany | FINE : - +File /tmp/syncanytest/syncany-190529150308467-91037-client-syncanyclient/rndFile-1559142206456-1409122369.dat
29-5-19 15:03:26.893 | Indexer         | AsyncI/syncany | FINE : - +FileContent: /tmp/syncanytest/syncany-190529150308467-91037-client-syncanyclient/rndFile-1559142206456-1409122369.dat
29-5-19 15:03:26.905 | Indexer         | AsyncI/syncany | FINE : - Chunk new: 9b45639b335d7c7fcf601ce3a48298d8aa1657a3
29-5-19 15:03:26.920 | Indexer         | AsyncI/syncany | FINE : - Chunk > MultiChunk: 9b45639b335d7c7fcf601ce3a48298d8aa1657a3 > db2e753aee968acd2209bd6c513bc883b3f84b23
29-5-19 15:03:26.920 | Indexer         | AsyncI/syncany | FINE : - Chunk > FileContent: 9b45639b335d7c7fcf601ce3a48298d8aa1657a3 > /tmp/syncanytest/syncany-190529150308467-91037-client-syncanyclient/rndFile-1559142206456-1409122369.dat
29-5-19 15:03:26.921 | Indexer         | AsyncI/syncany | FINE : - /File: rndFile-1559142206456-1409122369.dat (checksum 9b45639b335d7c7fcf601ce3a48298d8aa1657a3)
29-5-19 15:03:26.924 | Indexer         | AsyncI/syncany | FINE :    * No old file history found, starting new history (path: rndFile-1559142206456-1409122369.dat, checksum: 9b45639b335d7c7fcf601ce3a48298d8aa1657a3)
29-5-19 15:03:26.930 | FileVersionComp | AsyncI/syncany | INFO :      - [DELETED]: Local file DIFFERS from file version, actual file is NULL, for file rndFile-1559142206456-1409122369.dat
29-5-19 15:03:26.931 | Indexer         | AsyncI/syncany | INFO :    * Added file version:    FileVersion [version=1, path=rndFile-1559142206456-1409122369.dat, type=FILE, status=NEW, size=512000, lastModified=Wed May 29 15:03:26 UTC 2019, linkTarget=null, checksum=9b45639b335d7c7fcf601ce3a48298d8aa1657a3, updated=Wed May 29 15:03:26 UTC 2019, posixPermissions=rw-rw-r--, dosAttributes=--a-]
29-5-19 15:03:26.931 | Indexer         | AsyncI/syncany | INFO :      based on file version: null
29-5-19 15:03:26.932 | Indexer         | AsyncI/syncany | FINE : - +File /tmp/syncanytest/syncany-190529150308467-91037-client-syncanyclient/rndFile-1559142206467-1764731734.dat
29-5-19 15:03:26.932 | Indexer         | AsyncI/syncany | FINE : - +FileContent: /tmp/syncanytest/syncany-190529150308467-91037-client-syncanyclient/rndFile-1559142206467-1764731734.dat
29-5-19 15:03:26.945 | Indexer         | AsyncI/syncany | FINE : - Chunk new: e38f2a46215b015df1eb67355356239fe636f782
29-5-19 15:03:26.960 | Indexer         | AsyncI/syncany | FINE : - Chunk > MultiChunk: e38f2a46215b015df1eb67355356239fe636f782 > db2e753aee968acd2209bd6c513bc883b3f84b23
29-5-19 15:03:26.960 | Indexer         | AsyncI/syncany | FINE : - Chunk > FileContent: e38f2a46215b015df1eb67355356239fe636f782 > /tmp/syncanytest/syncany-190529150308467-91037-client-syncanyclient/rndFile-1559142206467-1764731734.dat
29-5-19 15:03:26.961 | Indexer         | AsyncI/syncany | FINE : - /File: rndFile-1559142206467-1764731734.dat (checksum e38f2a46215b015df1eb67355356239fe636f782)
29-5-19 15:03:26.972 | Indexer         | AsyncI/syncany | FINE :    * No old file history found, starting new history (path: rndFile-1559142206467-1764731734.dat, checksum: e38f2a46215b015df1eb67355356239fe636f782)
29-5-19 15:03:26.973 | FileVersionComp | AsyncI/syncany | INFO :      - [DELETED]: Local file DIFFERS from file version, actual file is NULL, for file rndFile-1559142206467-1764731734.dat
29-5-19 15:03:26.973 | Indexer         | AsyncI/syncany | INFO :    * Added file version:    FileVersion [version=1, path=rndFile-1559142206467-1764731734.dat, type=FILE, status=NEW, size=512000, lastModified=Wed May 29 15:03:26 UTC 2019, linkTarget=null, checksum=e38f2a46215b015df1eb67355356239fe636f782, updated=Wed May 29 15:03:26 UTC 2019, posixPermissions=rw-rw-r--, dosAttributes=--a-]
29-5-19 15:03:26.974 | Indexer         | AsyncI/syncany | INFO :      based on file version: null
29-5-19 15:03:26.975 | Indexer         | AsyncI/syncany | FINE : - /MultiChunk db2e753aee968acd2209bd6c513bc883b3f84b23
29-5-19 15:03:26.975 | InternalEventBu | AsyncI/syncany | INFO : Event bus 'LocalEventBus': Posting event UpIndexEndSyncExternalEvent (org.syncany.operations.daemon.messages.UpIndexEndSyncExternalEvent@10486efe) ...
29-5-19 15:03:26.976 | Indexer         | AsyncI/syncany | FINE : Processed new database version: DatabaseVersion [header=UnknownMachine/()/T=1559142206834, chunks=3, multiChunks=1, fileContents=3, fileHistories=3]
29-5-19 15:03:26.976 | InternalEventBu | AsyncI/syncany | INFO : Event bus 'LocalEventBus': Posting event UpIndexMidSyncExternalEvent (org.syncany.operations.daemon.messages.UpIndexMidSyncExternalEvent@154a2b48) ...
29-5-19 15:03:26.977 | UpOperation     | Test worker    | INFO : No DIRTY data found in database (no dirty databases); Nothing to do here.
29-5-19 15:03:26.977 | UpOperation     | Test worker    | INFO : Last vector clock was: ()
29-5-19 15:03:26.983 | UpOperation     | Test worker    | INFO : Uploading new multichunks ...
29-5-19 15:03:26.985 | UpOperation     | Test worker    | INFO : - Uploading multichunk db2e753aee968acd2209bd6c513bc883b3f84b23 from /tmp/syncanytest/syncany-190529150308467-91037-client-syncanyclient/.syncany/cache/multichunk-db2e753aee968acd2209bd6c513bc883b3f84b23 to RemoteFile[name=multichunk-db2e753aee968acd2209bd6c513bc883b3f84b23] ...
29-5-19 15:03:26.985 | RemoteTransacti | Test worker    | INFO : - Adding file to TX for UPLOAD: /tmp/syncanytest/syncany-190529150308467-91037-client-syncanyclient/.syncany/cache/multichunk-db2e753aee968acd2209bd6c513bc883b3f84b23 -> Temp. remote file: RemoteFile[name=temp-nUrBG-multichunk-db2e753aee968acd2209bd6c513bc883b3f84b23], final location: RemoteFile[name=multichunk-db2e753aee968acd2209bd6c513bc883b3f84b23]
29-5-19 15:03:26.986 | AsyncIndexer    | AsyncI/syncany | INFO : Stopping indexing. Signal end of stream with empty databaseversion
29-5-19 15:03:26.986 | UpOperation     | Test worker    | INFO : Uploading database: DatabaseVersion [header=syncanyclientmBafasIVzPifzaFSKjpe/(syncanyclientmBafasIVzPifzaFSKjpe1)/T=1559142206983, chunks=3, multiChunks=1, fileContents=3, fileHistories=3]
29-5-19 15:03:26.987 | UpOperation     | Test worker    | INFO : Saving local delta database, version syncanyclientmBafasIVzPifzaFSKjpe/(syncanyclientmBafasIVzPifzaFSKjpe1)/T=1559142206983 to file /tmp/syncanytest/syncany-190529150308467-91037-client-syncanyclient/.syncany/cache/database-syncanyclientmBafasIVzPifzaFSKjpe-0000000001 ... 
29-5-19 15:03:26.988 | UpOperation     | Test worker    | INFO : - Saving database to /tmp/syncanytest/syncany-190529150308467-91037-client-syncanyclient/.syncany/cache/database-syncanyclientmBafasIVzPifzaFSKjpe-0000000001 ...
29-5-19 15:03:26.989 | UpOperation     | Test worker    | INFO : - Uploading local delta database file ...
29-5-19 15:03:26.989 | UpOperation     | Test worker    | INFO : - Uploading /tmp/syncanytest/syncany-190529150308467-91037-client-syncanyclient/.syncany/cache/database-syncanyclientmBafasIVzPifzaFSKjpe-0000000001 to RemoteFile[name=database-syncanyclientmBafasIVzPifzaFSKjpe-0000000001] ...
29-5-19 15:03:26.990 | RemoteTransacti | Test worker    | INFO : - Adding file to TX for UPLOAD: /tmp/syncanytest/syncany-190529150308467-91037-client-syncanyclient/.syncany/cache/database-syncanyclientmBafasIVzPifzaFSKjpe-0000000001 -> Temp. remote file: RemoteFile[name=temp-UHfqf-database-syncanyclientmBafasIVzPifzaFSKjpe-0000000001], final location: RemoteFile[name=database-syncanyclientmBafasIVzPifzaFSKjpe-0000000001]
29-5-19 15:03:26.996 | UpOperation     | Test worker    | INFO : Adding shutdown hook (to allow resuming the upload) ...
29-5-19 15:03:26.997 | RemoteTransacti | Test worker    | INFO : Starting TX.commit() ...
29-5-19 15:03:27.005 | RemoteTransacti | Test worker    | INFO : Wrote transaction manifest to temporary file: /tmp/syncanytest/syncany-190529150308467-91037-client-syncanyclient/.syncany/cache/temp-transaction-12456781306750337745.tmp
29-5-19 15:03:27.006 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event UpUploadFileSyncExternalEvent (org.syncany.operations.daemon.messages.UpUploadFileSyncExternalEvent@4a9886a2) ...
29-5-19 15:03:27.006 | RemoteTransacti | Test worker    | INFO : - Uploading remote transaction file RemoteFile[name=transaction-300c23c7] ...
29-5-19 15:03:27.008 | RemoteTransacti | Test worker    | INFO : - Starting to upload data in commit.
29-5-19 15:03:27.008 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event UpUploadFileInTransactionSyncExternalEvent (org.syncany.operations.daemon.messages.UpUploadFileInTransactionSyncExternalEvent@61ef562b) ...
29-5-19 15:03:27.008 | RemoteTransacti | Test worker    | INFO : - Uploading /tmp/syncanytest/syncany-190529150308467-91037-client-syncanyclient/.syncany/cache/multichunk-db2e753aee968acd2209bd6c513bc883b3f84b23 to temp. file RemoteFile[name=temp-nUrBG-multichunk-db2e753aee968acd2209bd6c513bc883b3f84b23] ...
29-5-19 15:03:27.014 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event UpUploadFileInTransactionSyncExternalEvent (org.syncany.operations.daemon.messages.UpUploadFileInTransactionSyncExternalEvent@2e749c3a) ...
29-5-19 15:03:27.015 | RemoteTransacti | Test worker    | INFO : - Uploading /tmp/syncanytest/syncany-190529150308467-91037-client-syncanyclient/.syncany/cache/database-syncanyclientmBafasIVzPifzaFSKjpe-0000000001 to temp. file RemoteFile[name=temp-UHfqf-database-syncanyclientmBafasIVzPifzaFSKjpe-0000000001] ...
29-5-19 15:03:27.015 | RemoteTransacti | Test worker    | INFO : - Moving temp. file RemoteFile[name=temp-nUrBG-multichunk-db2e753aee968acd2209bd6c513bc883b3f84b23] to final location RemoteFile[name=multichunk-db2e753aee968acd2209bd6c513bc883b3f84b23] ...
29-5-19 15:03:27.016 | RemoteTransacti | Test worker    | INFO : - Moving temp. file RemoteFile[name=temp-UHfqf-database-syncanyclientmBafasIVzPifzaFSKjpe-0000000001] to final location RemoteFile[name=database-syncanyclientmBafasIVzPifzaFSKjpe-0000000001] ...
29-5-19 15:03:27.016 | RemoteTransacti | Test worker    | INFO : - Deleting remote transaction file RemoteFile[name=transaction-300c23c7] ...
29-5-19 15:03:27.017 | RemoteTransacti | Test worker    | INFO : END of TX.commmit(): Succesfully committed transaction.
29-5-19 15:03:27.017 | RemoteTransacti | Test worker    | INFO : END of TX.delTemp(): Sucessfully deleted final files.
29-5-19 15:03:27.017 | UpOperation     | Test worker    | INFO : Persisting local SQL database (new database version syncanyclientmBafasIVzPifzaFSKjpe/(syncanyclientmBafasIVzPifzaFSKjpe1)/T=1559142206983) ...
29-5-19 15:03:27.065 | UpOperation     | Test worker    | INFO : Removing DIRTY database versions from database ...
29-5-19 15:03:27.088 | UpOperation     | Test worker    | INFO : Adding database version to result changes:DatabaseVersion [header=syncanyclientmBafasIVzPifzaFSKjpe/(syncanyclientmBafasIVzPifzaFSKjpe1)/T=1559142206983, chunks=3, multiChunks=1, fileContents=3, fileHistories=3]
29-5-19 15:03:27.088 | UpOperation     | Test worker    | INFO : Committing local database.
29-5-19 15:03:27.089 | UpOperation     | Test worker    | FINE : Waiting for new database version.
29-5-19 15:03:27.089 | UpOperation     | Test worker    | FINE : Took new database version: DatabaseVersion [header=UnknownMachine/()/T=1559142206986, chunks=0, multiChunks=0, fileContents=0, fileHistories=0]
29-5-19 15:03:27.089 | UpOperation     | Test worker    | INFO : Sync up done.
29-5-19 15:03:27.091 | ActionFileHandl | Test worker    | INFO : Finishing action for RemoteFile[name=action-up-syncanyclientmBafasIVzPifzaFSKjpe-1559142206771] ...
29-5-19 15:03:27.094 | ActionFileHandl | Test worker    | INFO : Deleting action file: RemoteFile[name=action-up-syncanyclientmBafasIVzPifzaFSKjpe-1559142206771]
29-5-19 15:03:27.094 | AbstractTransfe | Test worker    | INFO : Cleaning own old action files ...
29-5-19 15:03:27.095 | Cache           | Test worker    | INFO : Cache size okay (1503 KB), no need to clean (keep size is 500 MB)
29-5-19 15:03:27.095 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event UpEndSyncExternalEvent (org.syncany.operations.daemon.messages.UpEndSyncExternalEvent@660cccec) ...
29-5-19 15:03:27.097 | DatabaseConnect | Test worker    | INFO : Found 12 tables.
29-5-19 15:03:27.097 | StatusOperation | Test worker    | INFO : 
29-5-19 15:03:27.097 | StatusOperation | Test worker    | INFO : Running 'Status' at client syncanyclientmBafasIVzPifzaFSKjpe ...
29-5-19 15:03:27.097 | StatusOperation | Test worker    | INFO : --------------------------------------------
29-5-19 15:03:27.097 | StatusOperation | Test worker    | INFO : Querying current file tree from database ...
29-5-19 15:03:27.097 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event StatusStartSyncExternalEvent (org.syncany.operations.daemon.messages.StatusStartSyncExternalEvent@33f9a959) ...
29-5-19 15:03:27.101 | StatusOperation | Test worker    | INFO : Analyzing local folder /tmp/syncanytest/syncany-190529150308467-91037-client-syncanyclient ...
29-5-19 15:03:27.110 | StatusOperation | Test worker    | FINE : - Ignoring file (syncany app-related): .syncany
29-5-19 15:03:27.111 | StatusOperation | Test worker    | INFO : - No changes to local database
29-5-19 15:03:27.112 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event StatusEndSyncExternalEvent (org.syncany.operations.daemon.messages.StatusEndSyncExternalEvent@8546bf5) ...
29-5-19 15:03:29.119 | DatabaseConnect | Test worker    | INFO : Found 12 tables.
29-5-19 15:03:29.119 | StatusOperation | Test worker    | INFO : 
29-5-19 15:03:29.119 | StatusOperation | Test worker    | INFO : Running 'Status' at client syncanyclientmBafasIVzPifzaFSKjpe ...
29-5-19 15:03:29.119 | StatusOperation | Test worker    | INFO : --------------------------------------------
29-5-19 15:03:29.119 | StatusOperation | Test worker    | INFO : Querying current file tree from database ...
29-5-19 15:03:29.119 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event StatusStartSyncExternalEvent (org.syncany.operations.daemon.messages.StatusStartSyncExternalEvent@18c49bfd) ...
29-5-19 15:03:29.138 | StatusOperation | Test worker    | INFO : Analyzing local folder /tmp/syncanytest/syncany-190529150308467-91037-client-syncanyclient ...
29-5-19 15:03:29.139 | FileVersionComp | Test worker    | INFO :      - [CHANGED_LAST_MOD_DATE]: Local file DIFFERS from file version, expected MOD. DATE = 5/29/19, 3:03 PM (1,559,142,206,475), but actual MOD. DATE = 5/29/19, 3:03 PM (1,559,142,209,107), for file rndFile-1559142206467-1764731734.dat
29-5-19 15:03:29.140 | FileVersionComp | Test worker    | INFO :      - [CHANGED_LAST_MOD_DATE]: Local file DIFFERS from file version, expected MOD. DATE = 5/29/19, 3:03 PM (1,559,142,206,463), but actual MOD. DATE = 5/29/19, 3:03 PM (1,559,142,209,107), for file rndFile-1559142206456-1409122369.dat
29-5-19 15:03:29.141 | StatusOperation | Test worker    | FINE : - Ignoring file (syncany app-related): .syncany
29-5-19 15:03:29.141 | FileVersionComp | Test worker    | INFO :      - [CHANGED_LAST_MOD_DATE]: Local file DIFFERS from file version, expected MOD. DATE = 5/29/19, 3:03 PM (1,559,142,206,451), but actual MOD. DATE = 5/29/19, 3:03 PM (1,559,142,209,107), for file rndFile-1559142206453-591483599.dat
29-5-19 15:03:29.148 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event StatusEndSyncExternalEvent (org.syncany.operations.daemon.messages.StatusEndSyncExternalEvent@412c9db6) ...
29-5-19 15:03:29.149 | org.syncany.plu | Test worker    | FINE : Validating required fields
29-5-19 15:03:29.149 | TransferManager | Test worker    | INFO : Building LocalTransferManager from config 'syncany-190529150308467-91037-client-syncanyclient' ...
29-5-19 15:03:29.149 | TransferManager | Test worker    | INFO : - With feature ReadAfterWriteConsistent
29-5-19 15:03:29.149 | TransferManager | Test worker    | INFO : - With feature Retriable
29-5-19 15:03:29.149 | TransferManager | Test worker    | FINE : - Checking if selected features supported ...
29-5-19 15:03:29.149 | TransferManager | Test worker    | FINE : - Checking for duplicate features ...
29-5-19 15:03:29.149 | TransferManager | Test worker    | FINE : - Checking required feature TransactionAware in LocalTransferManager ...
29-5-19 15:03:29.149 | TransferManager | Test worker    | FINE : - Checking required feature Retriable in LocalTransferManager ...
29-5-19 15:03:29.154 | TransferManager | Test worker    | INFO : - SKIPPING unsupported optional feature ReadAfterWriteConsistent
29-5-19 15:03:29.154 | TransferManager | Test worker    | FINE : - Wrapping TransferManager LocalTransferManager in RetriableFeatureTransferManager
29-5-19 15:03:29.162 | org.syncany.plu | Test worker    | FINE : Validating required fields
29-5-19 15:03:29.163 | TransferManager | Test worker    | INFO : Building LocalTransferManager from config 'syncany-190529150308467-91037-client-syncanyclient' ...
29-5-19 15:03:29.163 | TransferManager | Test worker    | INFO : - With feature ReadAfterWriteConsistent
29-5-19 15:03:29.163 | TransferManager | Test worker    | INFO : - With feature Retriable
29-5-19 15:03:29.163 | TransferManager | Test worker    | INFO : - With feature PathAware
29-5-19 15:03:29.163 | TransferManager | Test worker    | INFO : - With feature TransactionAware
29-5-19 15:03:29.163 | TransferManager | Test worker    | FINE : - Checking if selected features supported ...
29-5-19 15:03:29.163 | TransferManager | Test worker    | FINE : - Checking for duplicate features ...
29-5-19 15:03:29.163 | TransferManager | Test worker    | FINE : - Checking required feature TransactionAware in LocalTransferManager ...
29-5-19 15:03:29.163 | TransferManager | Test worker    | FINE : - Checking required feature Retriable in LocalTransferManager ...
29-5-19 15:03:29.163 | TransferManager | Test worker    | INFO : - SKIPPING unsupported optional feature ReadAfterWriteConsistent
29-5-19 15:03:29.163 | TransferManager | Test worker    | FINE : - Wrapping TransferManager LocalTransferManager in RetriableFeatureTransferManager
29-5-19 15:03:29.163 | TransferManager | Test worker    | INFO : - SKIPPING unsupported optional feature PathAware
29-5-19 15:03:29.164 | TransferManager | Test worker    | FINE : - Wrapping TransferManager RetriableFeatureTransferManager in TransactionAwareFeatureTransferManager
29-5-19 15:03:29.172 | DatabaseConnect | Test worker    | INFO : Found 12 tables.
29-5-19 15:03:29.173 | UpOperation     | Test worker    | INFO : 
29-5-19 15:03:29.173 | UpOperation     | Test worker    | INFO : Running 'Sync up' at client syncanyclientmBafasIVzPifzaFSKjpe ...
29-5-19 15:03:29.173 | UpOperation     | Test worker    | INFO : --------------------------------------------
29-5-19 15:03:29.173 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event UpStartSyncExternalEvent (org.syncany.operations.daemon.messages.UpStartSyncExternalEvent@7d838aab) ...
29-5-19 15:03:29.185 | DatabaseConnect | Test worker    | INFO : Found 12 tables.
29-5-19 15:03:29.186 | StatusOperation | Test worker    | INFO : 
29-5-19 15:03:29.186 | StatusOperation | Test worker    | INFO : Running 'Status' at client syncanyclientmBafasIVzPifzaFSKjpe ...
29-5-19 15:03:29.186 | StatusOperation | Test worker    | INFO : --------------------------------------------
29-5-19 15:03:29.186 | StatusOperation | Test worker    | INFO : Querying current file tree from database ...
29-5-19 15:03:29.186 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event StatusStartSyncExternalEvent (org.syncany.operations.daemon.messages.StatusStartSyncExternalEvent@1f06ef49) ...
29-5-19 15:03:29.199 | StatusOperation | Test worker    | INFO : Analyzing local folder /tmp/syncanytest/syncany-190529150308467-91037-client-syncanyclient ...
29-5-19 15:03:29.200 | FileVersionComp | Test worker    | INFO :      - [CHANGED_LAST_MOD_DATE]: Local file DIFFERS from file version, expected MOD. DATE = 5/29/19, 3:03 PM (1,559,142,206,475), but actual MOD. DATE = 5/29/19, 3:03 PM (1,559,142,209,107), for file rndFile-1559142206467-1764731734.dat
29-5-19 15:03:29.201 | FileVersionComp | Test worker    | INFO :      - [CHANGED_LAST_MOD_DATE]: Local file DIFFERS from file version, expected MOD. DATE = 5/29/19, 3:03 PM (1,559,142,206,463), but actual MOD. DATE = 5/29/19, 3:03 PM (1,559,142,209,107), for file rndFile-1559142206456-1409122369.dat
29-5-19 15:03:29.212 | StatusOperation | Test worker    | FINE : - Ignoring file (syncany app-related): .syncany
29-5-19 15:03:29.213 | FileVersionComp | Test worker    | INFO :      - [CHANGED_LAST_MOD_DATE]: Local file DIFFERS from file version, expected MOD. DATE = 5/29/19, 3:03 PM (1,559,142,206,451), but actual MOD. DATE = 5/29/19, 3:03 PM (1,559,142,209,107), for file rndFile-1559142206453-591483599.dat
29-5-19 15:03:29.214 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event StatusEndSyncExternalEvent (org.syncany.operations.daemon.messages.StatusEndSyncExternalEvent@46b3040b) ...
29-5-19 15:03:29.214 | AbstractTransfe | Test worker    | INFO : Looking for other running remote operations ...
29-5-19 15:03:29.217 | DatabaseConnect | Test worker    | INFO : Found 12 tables.
29-5-19 15:03:29.217 | LsRemoteOperati | Test worker    | INFO : 
29-5-19 15:03:29.217 | LsRemoteOperati | Test worker    | INFO : Running 'Remote Status' at client syncanyclientmBafasIVzPifzaFSKjpe ...
29-5-19 15:03:29.217 | LsRemoteOperati | Test worker    | INFO : --------------------------------------------
29-5-19 15:03:29.217 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event LsRemoteStartSyncExternalEvent (org.syncany.operations.daemon.messages.LsRemoteStartSyncExternalEvent@eb65ed3) ...
29-5-19 15:03:29.226 | LsRemoteOperati | Test worker    | INFO : Retrieving remote database list.
29-5-19 15:03:29.227 | LsRemoteOperati | Test worker    | INFO : - Remote database database-syncanyclientmBafasIVzPifzaFSKjpe-0000000001 is already known (in local database). Ignoring.
29-5-19 15:03:29.227 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event LsRemoteEndSyncExternalEvent (org.syncany.operations.daemon.messages.LsRemoteEndSyncExternalEvent@11b8950a) ...
29-5-19 15:03:29.227 | UpOperation     | Test worker    | INFO : No remote changes, ready to upload.
29-5-19 15:03:29.227 | ActionFileHandl | Test worker    | INFO : Starting action for RemoteFile[name=action-up-syncanyclientmBafasIVzPifzaFSKjpe-1559142209164] ...
29-5-19 15:03:29.227 | ActionFileHandl | Test worker    | INFO : Uploading action file: RemoteFile[name=action-up-syncanyclientmBafasIVzPifzaFSKjpe-1559142209164]
29-5-19 15:03:29.228 | ActionFileHandl | Test worker    | INFO : Scheduling action renewal task for every 2 minutes, for RemoteFile[name=action-up-syncanyclientmBafasIVzPifzaFSKjpe-1559142209164] ...
29-5-19 15:03:29.232 | DatabaseConnect | Test worker    | INFO : Found 12 tables.
29-5-19 15:03:29.238 | TransactionAwar | Test worker    | INFO : Done rolling back previous transactions.
29-5-19 15:03:29.239 | AsyncIndexer    | AsyncI/syncany | INFO : Starting Indexing.
29-5-19 15:03:29.239 | InternalEventBu | AsyncI/syncany | INFO : Event bus 'LocalEventBus': Posting event UpIndexStartSyncExternalEvent (org.syncany.operations.daemon.messages.UpIndexStartSyncExternalEvent@4e414ef0) ...
29-5-19 15:03:29.239 | Indexer         | AsyncI/syncany | FINE : - Looking for deleted files ...
29-5-19 15:03:29.240 | Indexer         | AsyncI/syncany | FINE : - +File /tmp/syncanytest/syncany-190529150308467-91037-client-syncanyclient/rndFile-1559142206453-591483599.dat
29-5-19 15:03:29.240 | Indexer         | AsyncI/syncany | FINE : - +FileContent: /tmp/syncanytest/syncany-190529150308467-91037-client-syncanyclient/rndFile-1559142206453-591483599.dat
29-5-19 15:03:29.260 | Indexer         | AsyncI/syncany | FINE : - Chunk new: 0d1fb6265c3d829c18d07ccfe98aca6f0be85533
29-5-19 15:03:29.260 | Indexer         | AsyncI/syncany | FINE : - +MultiChunk a7892b270be55464531f95e49b887c120ccbf936
29-5-19 15:03:29.269 | Indexer         | AsyncI/syncany | FINE : - Chunk > MultiChunk: 0d1fb6265c3d829c18d07ccfe98aca6f0be85533 > a7892b270be55464531f95e49b887c120ccbf936
29-5-19 15:03:29.274 | Indexer         | AsyncI/syncany | FINE : - Chunk > FileContent: 0d1fb6265c3d829c18d07ccfe98aca6f0be85533 > /tmp/syncanytest/syncany-190529150308467-91037-client-syncanyclient/rndFile-1559142206453-591483599.dat
29-5-19 15:03:29.277 | Indexer         | AsyncI/syncany | FINE : - /File: rndFile-1559142206453-591483599.dat (checksum 0d1fb6265c3d829c18d07ccfe98aca6f0be85533)
29-5-19 15:03:29.291 | Indexer         | AsyncI/syncany | FINE :    * Found old file history d8afa9261393e333ebe956d773026c080b8ad26a (by path: rndFile-1559142206453-591483599.dat), appending new version.
29-5-19 15:03:29.292 | FileVersionComp | AsyncI/syncany | INFO :      - [CHANGED_LAST_MOD_DATE]: Local file DIFFERS from file version, expected MOD. DATE = 5/29/19, 3:03 PM (1,559,142,209,107), but actual MOD. DATE = 5/29/19, 3:03 PM (1,559,142,206,451), for file rndFile-1559142206453-591483599.dat
29-5-19 15:03:29.292 | FileVersionComp | AsyncI/syncany | INFO :      - [CHANGED_CHECKSUM, CHANGED_LAST_MOD_DATE]: Local file DIFFERS from file version, expected CHECKSUM = 0d1fb6265c3d829c18d07ccfe98aca6f0be85533, but actual CHECKSUM = 37acd012dab04e89fefecdc76c480e2459bd509c, for file rndFile-1559142206453-591483599.dat
29-5-19 15:03:29.293 | Indexer         | AsyncI/syncany | INFO :    * Added file version:    FileVersion [version=2, path=rndFile-1559142206453-591483599.dat, type=FILE, status=CHANGED, size=512000, lastModified=Wed May 29 15:03:29 UTC 2019, linkTarget=null, checksum=0d1fb6265c3d829c18d07ccfe98aca6f0be85533, updated=Wed May 29 15:03:29 UTC 2019, posixPermissions=rw-rw-r--, dosAttributes=--a-]
29-5-19 15:03:29.293 | Indexer         | AsyncI/syncany | INFO :      based on file version: FileVersion [version=1, path=rndFile-1559142206453-591483599.dat, type=FILE, status=NEW, size=512000, lastModified=Wed May 29 15:03:26 UTC 2019, linkTarget=null, checksum=37acd012dab04e89fefecdc76c480e2459bd509c, updated=Wed May 29 15:03:26 UTC 2019, posixPermissions=rw-rw-r--, dosAttributes=--a-]
29-5-19 15:03:29.293 | InternalEventBu | AsyncI/syncany | INFO : Event bus 'LocalEventBus': Posting event UpIndexChangesDetectedSyncExternalEvent (org.syncany.operations.daemon.messages.UpIndexChangesDetectedSyncExternalEvent@1192dfd3) ...
29-5-19 15:03:29.298 | Indexer         | AsyncI/syncany | FINE : - +File /tmp/syncanytest/syncany-190529150308467-91037-client-syncanyclient/rndFile-1559142206456-1409122369.dat
29-5-19 15:03:29.300 | Indexer         | AsyncI/syncany | FINE : - +FileContent: /tmp/syncanytest/syncany-190529150308467-91037-client-syncanyclient/rndFile-1559142206456-1409122369.dat
29-5-19 15:03:29.312 | Indexer         | AsyncI/syncany | FINE : - Chunk new: dfa0947b1ea801e04b063b0c7a60cf41701ecc42
29-5-19 15:03:29.324 | Indexer         | AsyncI/syncany | FINE : - Chunk > MultiChunk: dfa0947b1ea801e04b063b0c7a60cf41701ecc42 > a7892b270be55464531f95e49b887c120ccbf936
29-5-19 15:03:29.326 | Indexer         | AsyncI/syncany | FINE : - Chunk > FileContent: dfa0947b1ea801e04b063b0c7a60cf41701ecc42 > /tmp/syncanytest/syncany-190529150308467-91037-client-syncanyclient/rndFile-1559142206456-1409122369.dat
29-5-19 15:03:29.327 | Indexer         | AsyncI/syncany | FINE : - /File: rndFile-1559142206456-1409122369.dat (checksum dfa0947b1ea801e04b063b0c7a60cf41701ecc42)
29-5-19 15:03:29.333 | Indexer         | AsyncI/syncany | FINE :    * Found old file history 91b9d48a37d87753766e228d07016d7b6922391d (by path: rndFile-1559142206456-1409122369.dat), appending new version.
29-5-19 15:03:29.334 | FileVersionComp | AsyncI/syncany | INFO :      - [CHANGED_LAST_MOD_DATE]: Local file DIFFERS from file version, expected MOD. DATE = 5/29/19, 3:03 PM (1,559,142,209,107), but actual MOD. DATE = 5/29/19, 3:03 PM (1,559,142,206,463), for file rndFile-1559142206456-1409122369.dat
29-5-19 15:03:29.341 | FileVersionComp | AsyncI/syncany | INFO :      - [CHANGED_CHECKSUM, CHANGED_LAST_MOD_DATE]: Local file DIFFERS from file version, expected CHECKSUM = dfa0947b1ea801e04b063b0c7a60cf41701ecc42, but actual CHECKSUM = 9b45639b335d7c7fcf601ce3a48298d8aa1657a3, for file rndFile-1559142206456-1409122369.dat
29-5-19 15:03:29.341 | Indexer         | AsyncI/syncany | INFO :    * Added file version:    FileVersion [version=2, path=rndFile-1559142206456-1409122369.dat, type=FILE, status=CHANGED, size=512000, lastModified=Wed May 29 15:03:29 UTC 2019, linkTarget=null, checksum=dfa0947b1ea801e04b063b0c7a60cf41701ecc42, updated=Wed May 29 15:03:29 UTC 2019, posixPermissions=rw-rw-r--, dosAttributes=--a-]
29-5-19 15:03:29.341 | Indexer         | AsyncI/syncany | INFO :      based on file version: FileVersion [version=1, path=rndFile-1559142206456-1409122369.dat, type=FILE, status=NEW, size=512000, lastModified=Wed May 29 15:03:26 UTC 2019, linkTarget=null, checksum=9b45639b335d7c7fcf601ce3a48298d8aa1657a3, updated=Wed May 29 15:03:26 UTC 2019, posixPermissions=rw-rw-r--, dosAttributes=--a-]
29-5-19 15:03:29.344 | Indexer         | AsyncI/syncany | FINE : - +File /tmp/syncanytest/syncany-190529150308467-91037-client-syncanyclient/rndFile-1559142206467-1764731734.dat
29-5-19 15:03:29.345 | Indexer         | AsyncI/syncany | FINE : - +FileContent: /tmp/syncanytest/syncany-190529150308467-91037-client-syncanyclient/rndFile-1559142206467-1764731734.dat
29-5-19 15:03:29.353 | Indexer         | AsyncI/syncany | FINE : - Chunk new: 94618476ff212f3c1480f46baf54b3818c5565ba
29-5-19 15:03:29.361 | Indexer         | AsyncI/syncany | FINE : - Chunk > MultiChunk: 94618476ff212f3c1480f46baf54b3818c5565ba > a7892b270be55464531f95e49b887c120ccbf936
29-5-19 15:03:29.361 | Indexer         | AsyncI/syncany | FINE : - Chunk > FileContent: 94618476ff212f3c1480f46baf54b3818c5565ba > /tmp/syncanytest/syncany-190529150308467-91037-client-syncanyclient/rndFile-1559142206467-1764731734.dat
29-5-19 15:03:29.361 | Indexer         | AsyncI/syncany | FINE : - /File: rndFile-1559142206467-1764731734.dat (checksum 94618476ff212f3c1480f46baf54b3818c5565ba)
29-5-19 15:03:29.373 | Indexer         | AsyncI/syncany | FINE :    * Found old file history ae01dba1a499315b36dbb838a89825af747da85b (by path: rndFile-1559142206467-1764731734.dat), appending new version.
29-5-19 15:03:29.373 | FileVersionComp | AsyncI/syncany | INFO :      - [CHANGED_LAST_MOD_DATE]: Local file DIFFERS from file version, expected MOD. DATE = 5/29/19, 3:03 PM (1,559,142,209,107), but actual MOD. DATE = 5/29/19, 3:03 PM (1,559,142,206,475), for file rndFile-1559142206467-1764731734.dat
29-5-19 15:03:29.374 | FileVersionComp | AsyncI/syncany | INFO :      - [CHANGED_CHECKSUM, CHANGED_LAST_MOD_DATE]: Local file DIFFERS from file version, expected CHECKSUM = 94618476ff212f3c1480f46baf54b3818c5565ba, but actual CHECKSUM = e38f2a46215b015df1eb67355356239fe636f782, for file rndFile-1559142206467-1764731734.dat
29-5-19 15:03:29.378 | Indexer         | AsyncI/syncany | INFO :    * Added file version:    FileVersion [version=2, path=rndFile-1559142206467-1764731734.dat, type=FILE, status=CHANGED, size=512000, lastModified=Wed May 29 15:03:29 UTC 2019, linkTarget=null, checksum=94618476ff212f3c1480f46baf54b3818c5565ba, updated=Wed May 29 15:03:29 UTC 2019, posixPermissions=rw-rw-r--, dosAttributes=--a-]
29-5-19 15:03:29.378 | Indexer         | AsyncI/syncany | INFO :      based on file version: FileVersion [version=1, path=rndFile-1559142206467-1764731734.dat, type=FILE, status=NEW, size=512000, lastModified=Wed May 29 15:03:26 UTC 2019, linkTarget=null, checksum=e38f2a46215b015df1eb67355356239fe636f782, updated=Wed May 29 15:03:26 UTC 2019, posixPermissions=rw-rw-r--, dosAttributes=--a-]
29-5-19 15:03:29.380 | Indexer         | AsyncI/syncany | FINE : - /MultiChunk a7892b270be55464531f95e49b887c120ccbf936
29-5-19 15:03:29.380 | InternalEventBu | AsyncI/syncany | INFO : Event bus 'LocalEventBus': Posting event UpIndexEndSyncExternalEvent (org.syncany.operations.daemon.messages.UpIndexEndSyncExternalEvent@1f31077e) ...
29-5-19 15:03:29.380 | Indexer         | AsyncI/syncany | FINE : Processed new database version: DatabaseVersion [header=UnknownMachine/()/T=1559142209239, chunks=3, multiChunks=1, fileContents=3, fileHistories=3]
29-5-19 15:03:29.381 | UpOperation     | Test worker    | INFO : No DIRTY data found in database (no dirty databases); Nothing to do here.
29-5-19 15:03:29.383 | UpOperation     | Test worker    | INFO : Last vector clock was: (syncanyclientmBafasIVzPifzaFSKjpe1)
29-5-19 15:03:29.384 | UpOperation     | Test worker    | INFO : Uploading new multichunks ...
29-5-19 15:03:29.385 | UpOperation     | Test worker    | INFO : - Uploading multichunk a7892b270be55464531f95e49b887c120ccbf936 from /tmp/syncanytest/syncany-190529150308467-91037-client-syncanyclient/.syncany/cache/multichunk-a7892b270be55464531f95e49b887c120ccbf936 to RemoteFile[name=multichunk-a7892b270be55464531f95e49b887c120ccbf936] ...
29-5-19 15:03:29.385 | RemoteTransacti | Test worker    | INFO : - Adding file to TX for UPLOAD: /tmp/syncanytest/syncany-190529150308467-91037-client-syncanyclient/.syncany/cache/multichunk-a7892b270be55464531f95e49b887c120ccbf936 -> Temp. remote file: RemoteFile[name=temp-hyYoq-multichunk-a7892b270be55464531f95e49b887c120ccbf936], final location: RemoteFile[name=multichunk-a7892b270be55464531f95e49b887c120ccbf936]
29-5-19 15:03:29.385 | UpOperation     | Test worker    | INFO : Uploading database: DatabaseVersion [header=syncanyclientmBafasIVzPifzaFSKjpe/(syncanyclientmBafasIVzPifzaFSKjpe2)/T=1559142209384, chunks=3, multiChunks=1, fileContents=3, fileHistories=3]
29-5-19 15:03:29.395 | UpOperation     | Test worker    | INFO : Saving local delta database, version syncanyclientmBafasIVzPifzaFSKjpe/(syncanyclientmBafasIVzPifzaFSKjpe2)/T=1559142209384 to file /tmp/syncanytest/syncany-190529150308467-91037-client-syncanyclient/.syncany/cache/database-syncanyclientmBafasIVzPifzaFSKjpe-0000000002 ... 
29-5-19 15:03:29.395 | UpOperation     | Test worker    | INFO : - Saving database to /tmp/syncanytest/syncany-190529150308467-91037-client-syncanyclient/.syncany/cache/database-syncanyclientmBafasIVzPifzaFSKjpe-0000000002 ...
29-5-19 15:03:29.397 | UpOperation     | Test worker    | INFO : - Uploading local delta database file ...
29-5-19 15:03:29.397 | UpOperation     | Test worker    | INFO : - Uploading /tmp/syncanytest/syncany-190529150308467-91037-client-syncanyclient/.syncany/cache/database-syncanyclientmBafasIVzPifzaFSKjpe-0000000002 to RemoteFile[name=database-syncanyclientmBafasIVzPifzaFSKjpe-0000000002] ...
29-5-19 15:03:29.397 | RemoteTransacti | Test worker    | INFO : - Adding file to TX for UPLOAD: /tmp/syncanytest/syncany-190529150308467-91037-client-syncanyclient/.syncany/cache/database-syncanyclientmBafasIVzPifzaFSKjpe-0000000002 -> Temp. remote file: RemoteFile[name=temp-gTBXa-database-syncanyclientmBafasIVzPifzaFSKjpe-0000000002], final location: RemoteFile[name=database-syncanyclientmBafasIVzPifzaFSKjpe-0000000002]
29-5-19 15:03:29.402 | InternalEventBu | AsyncI/syncany | INFO : Event bus 'LocalEventBus': Posting event UpIndexMidSyncExternalEvent (org.syncany.operations.daemon.messages.UpIndexMidSyncExternalEvent@31207151) ...
29-5-19 15:03:29.404 | UpOperation     | Test worker    | INFO : Adding shutdown hook (to allow resuming the upload) ...
29-5-19 15:03:29.404 | RemoteTransacti | Test worker    | INFO : Starting TX.commit() ...
29-5-19 15:03:29.408 | AsyncIndexer    | AsyncI/syncany | INFO : Stopping indexing. Signal end of stream with empty databaseversion
29-5-19 15:03:29.416 | RemoteTransacti | Test worker    | INFO : Wrote transaction manifest to temporary file: /tmp/syncanytest/syncany-190529150308467-91037-client-syncanyclient/.syncany/cache/temp-transaction-10722420096781526339.tmp
29-5-19 15:03:29.416 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event UpUploadFileSyncExternalEvent (org.syncany.operations.daemon.messages.UpUploadFileSyncExternalEvent@1727e896) ...
29-5-19 15:03:29.416 | RemoteTransacti | Test worker    | INFO : - Uploading remote transaction file RemoteFile[name=transaction-451cb1ee] ...
29-5-19 15:03:29.417 | RemoteTransacti | Test worker    | INFO : - Starting to upload data in commit.
29-5-19 15:03:29.417 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event UpUploadFileInTransactionSyncExternalEvent (org.syncany.operations.daemon.messages.UpUploadFileInTransactionSyncExternalEvent@66cea54d) ...
29-5-19 15:03:29.417 | RemoteTransacti | Test worker    | INFO : - Uploading /tmp/syncanytest/syncany-190529150308467-91037-client-syncanyclient/.syncany/cache/multichunk-a7892b270be55464531f95e49b887c120ccbf936 to temp. file RemoteFile[name=temp-hyYoq-multichunk-a7892b270be55464531f95e49b887c120ccbf936] ...
29-5-19 15:03:29.429 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event UpUploadFileInTransactionSyncExternalEvent (org.syncany.operations.daemon.messages.UpUploadFileInTransactionSyncExternalEvent@7d9e4dc3) ...
29-5-19 15:03:29.429 | RemoteTransacti | Test worker    | INFO : - Uploading /tmp/syncanytest/syncany-190529150308467-91037-client-syncanyclient/.syncany/cache/database-syncanyclientmBafasIVzPifzaFSKjpe-0000000002 to temp. file RemoteFile[name=temp-gTBXa-database-syncanyclientmBafasIVzPifzaFSKjpe-0000000002] ...
29-5-19 15:03:29.430 | RemoteTransacti | Test worker    | INFO : - Moving temp. file RemoteFile[name=temp-hyYoq-multichunk-a7892b270be55464531f95e49b887c120ccbf936] to final location RemoteFile[name=multichunk-a7892b270be55464531f95e49b887c120ccbf936] ...
29-5-19 15:03:29.431 | RemoteTransacti | Test worker    | INFO : - Moving temp. file RemoteFile[name=temp-gTBXa-database-syncanyclientmBafasIVzPifzaFSKjpe-0000000002] to final location RemoteFile[name=database-syncanyclientmBafasIVzPifzaFSKjpe-0000000002] ...
29-5-19 15:03:29.431 | RemoteTransacti | Test worker    | INFO : - Deleting remote transaction file RemoteFile[name=transaction-451cb1ee] ...
29-5-19 15:03:29.431 | RemoteTransacti | Test worker    | INFO : END of TX.commmit(): Succesfully committed transaction.
29-5-19 15:03:29.431 | RemoteTransacti | Test worker    | INFO : END of TX.delTemp(): Sucessfully deleted final files.
29-5-19 15:03:29.431 | UpOperation     | Test worker    | INFO : Persisting local SQL database (new database version syncanyclientmBafasIVzPifzaFSKjpe/(syncanyclientmBafasIVzPifzaFSKjpe2)/T=1559142209384) ...
29-5-19 15:03:29.468 | UpOperation     | Test worker    | INFO : Removing DIRTY database versions from database ...
29-5-19 15:03:29.491 | UpOperation     | Test worker    | INFO : Adding database version to result changes:DatabaseVersion [header=syncanyclientmBafasIVzPifzaFSKjpe/(syncanyclientmBafasIVzPifzaFSKjpe2)/T=1559142209384, chunks=3, multiChunks=1, fileContents=3, fileHistories=3]
29-5-19 15:03:29.491 | UpOperation     | Test worker    | INFO : Committing local database.
29-5-19 15:03:29.491 | UpOperation     | Test worker    | FINE : Waiting for new database version.
29-5-19 15:03:29.491 | UpOperation     | Test worker    | FINE : Took new database version: DatabaseVersion [header=UnknownMachine/()/T=1559142209408, chunks=0, multiChunks=0, fileContents=0, fileHistories=0]
29-5-19 15:03:29.491 | UpOperation     | Test worker    | INFO : Sync up done.
29-5-19 15:03:29.493 | ActionFileHandl | Test worker    | INFO : Finishing action for RemoteFile[name=action-up-syncanyclientmBafasIVzPifzaFSKjpe-1559142209164] ...
29-5-19 15:03:29.494 | ActionFileHandl | Test worker    | INFO : Deleting action file: RemoteFile[name=action-up-syncanyclientmBafasIVzPifzaFSKjpe-1559142209164]
29-5-19 15:03:29.494 | AbstractTransfe | Test worker    | INFO : Cleaning own old action files ...
29-5-19 15:03:29.497 | Cache           | Test worker    | INFO : Cache size okay (3007 KB), no need to clean (keep size is 500 MB)
29-5-19 15:03:29.497 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event UpEndSyncExternalEvent (org.syncany.operations.daemon.messages.UpEndSyncExternalEvent@339781d) ...
29-5-19 15:03:29.503 | DatabaseConnect | Test worker    | INFO : Found 12 tables.
29-5-19 15:03:29.503 | StatusOperation | Test worker    | INFO : 
29-5-19 15:03:29.503 | StatusOperation | Test worker    | INFO : Running 'Status' at client syncanyclientmBafasIVzPifzaFSKjpe ...
29-5-19 15:03:29.503 | StatusOperation | Test worker    | INFO : --------------------------------------------
29-5-19 15:03:29.503 | StatusOperation | Test worker    | INFO : Querying current file tree from database ...
29-5-19 15:03:29.503 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event StatusStartSyncExternalEvent (org.syncany.operations.daemon.messages.StatusStartSyncExternalEvent@3e3cd0d0) ...
29-5-19 15:03:29.510 | StatusOperation | Test worker    | INFO : Analyzing local folder /tmp/syncanytest/syncany-190529150308467-91037-client-syncanyclient ...
29-5-19 15:03:29.519 | StatusOperation | Test worker    | FINE : - Ignoring file (syncany app-related): .syncany
29-5-19 15:03:29.519 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event StatusEndSyncExternalEvent (org.syncany.operations.daemon.messages.StatusEndSyncExternalEvent@57962dc3) ...
29-5-19 15:03:29.532 | org.syncany.plu | Test worker    | FINE : Validating required fields
29-5-19 15:03:29.533 | LocalTransferMa | Test worker    | INFO : testTargetExists: Target exists.
29-5-19 15:03:29.538 | org.syncany.plu | Test worker    | FINE : Validating required fields
29-5-19 15:03:29.548 | TransferManager | Test worker    | INFO : Building LocalTransferManager from config 'syncany-190529150308467-55776-client-syncanyclient' ...
29-5-19 15:03:29.548 | TransferManager | Test worker    | INFO : - With feature ReadAfterWriteConsistent
29-5-19 15:03:29.548 | TransferManager | Test worker    | INFO : - With feature Retriable
29-5-19 15:03:29.548 | TransferManager | Test worker    | FINE : - Checking if selected features supported ...
29-5-19 15:03:29.548 | TransferManager | Test worker    | FINE : - Checking for duplicate features ...
29-5-19 15:03:29.548 | TransferManager | Test worker    | FINE : - Checking required feature TransactionAware in LocalTransferManager ...
29-5-19 15:03:29.548 | TransferManager | Test worker    | FINE : - Checking required feature Retriable in LocalTransferManager ...
29-5-19 15:03:29.548 | TransferManager | Test worker    | INFO : - SKIPPING unsupported optional feature ReadAfterWriteConsistent
29-5-19 15:03:29.548 | TransferManager | Test worker    | FINE : - Wrapping TransferManager LocalTransferManager in RetriableFeatureTransferManager
29-5-19 15:03:29.548 | org.syncany.plu | Test worker    | FINE : Validating required fields
29-5-19 15:03:29.551 | TransferManager | Test worker    | INFO : Building LocalTransferManager from config 'syncany-190529150308467-55776-client-syncanyclient' ...
29-5-19 15:03:29.551 | TransferManager | Test worker    | INFO : - With feature ReadAfterWriteConsistent
29-5-19 15:03:29.551 | TransferManager | Test worker    | INFO : - With feature Retriable
29-5-19 15:03:29.551 | TransferManager | Test worker    | INFO : - With feature PathAware
29-5-19 15:03:29.551 | TransferManager | Test worker    | INFO : - With feature TransactionAware
29-5-19 15:03:29.551 | TransferManager | Test worker    | FINE : - Checking if selected features supported ...
29-5-19 15:03:29.551 | TransferManager | Test worker    | FINE : - Checking for duplicate features ...
29-5-19 15:03:29.551 | TransferManager | Test worker    | FINE : - Checking required feature TransactionAware in LocalTransferManager ...
29-5-19 15:03:29.551 | TransferManager | Test worker    | FINE : - Checking required feature Retriable in LocalTransferManager ...
29-5-19 15:03:29.551 | TransferManager | Test worker    | INFO : - SKIPPING unsupported optional feature ReadAfterWriteConsistent
29-5-19 15:03:29.551 | TransferManager | Test worker    | FINE : - Wrapping TransferManager LocalTransferManager in RetriableFeatureTransferManager
29-5-19 15:03:29.551 | TransferManager | Test worker    | INFO : - SKIPPING unsupported optional feature PathAware
29-5-19 15:03:29.551 | TransferManager | Test worker    | FINE : - Wrapping TransferManager RetriableFeatureTransferManager in TransactionAwareFeatureTransferManager
29-5-19 15:03:29.627 | hsqldb.db.HSQLD | Test worker    | INFO : Checkpoint start
29-5-19 15:03:29.627 | hsqldb.db.HSQLD | Test worker    | INFO : checkpointClose start
29-5-19 15:03:29.642 | hsqldb.db.HSQLD | Test worker    | FINE : log close size: 0
29-5-19 15:03:29.645 | hsqldb.db.HSQLD | Test worker    | INFO : checkpointClose end
29-5-19 15:03:29.646 | hsqldb.db.HSQLD | Test worker    | INFO : Checkpoint end - txts: 1
29-5-19 15:03:29.655 | DatabaseConnect | Test worker    | INFO : Found 0 tables.
29-5-19 15:03:29.655 | DatabaseConnect | Test worker    | INFO : Database has no tables. Creating tables from script.create.all.sql
29-5-19 15:03:29.656 | SqlRunner       | Test worker    | INFO : SQL (comment): -- Tables
29-5-19 15:03:29.656 | SqlRunner       | Test worker    | INFO : SQL:  CREATE CACHED TABLE IF NOT EXISTS databaseversion ( id int NOT NULL IDENTITY, status varchar(45) NOT NULL, localtime datetime NOT NULL, client varchar(45) NOT NULL, vectorclock_serialized varchar(1024) NOT NULL, UNIQUE (vectorclock_serialized) ); 
29-5-19 15:03:29.670 | SqlRunner       | Test worker    | INFO : SQL:  CREATE CACHED TABLE IF NOT EXISTS  chunk ( checksum varchar(40) NOT NULL, databaseversion_id int NOT NULL, size bigint NOT NULL, PRIMARY KEY (checksum), FOREIGN KEY (databaseversion_id) REFERENCES databaseversion (id) ON DELETE NO ACTION ON UPDATE NO ACTION ); 
29-5-19 15:03:29.673 | SqlRunner       | Test worker    | INFO : SQL:  CREATE CACHED TABLE IF NOT EXISTS  databaseversion_vectorclock ( databaseversion_id int NOT NULL, client varchar(45) NOT NULL, logicaltime int NOT NULL, PRIMARY KEY (databaseversion_id, client), FOREIGN KEY (databaseversion_id) REFERENCES databaseversion (id) ON DELETE NO ACTION ON UPDATE NO ACTION ); 
29-5-19 15:03:29.677 | SqlRunner       | Test worker    | INFO : SQL:  CREATE CACHED TABLE IF NOT EXISTS  filecontent ( checksum varchar(40) NOT NULL, databaseversion_id int NOT NULL, size bigint NOT NULL, PRIMARY KEY (checksum), FOREIGN KEY (databaseversion_id) REFERENCES databaseversion (id) ON DELETE NO ACTION ON UPDATE NO ACTION ); 
29-5-19 15:03:29.680 | SqlRunner       | Test worker    | INFO : SQL:  CREATE CACHED TABLE IF NOT EXISTS  filecontent_chunk ( filecontent_checksum varchar(40) NOT NULL, chunk_checksum varchar(40) NOT NULL, num int NOT NULL, PRIMARY KEY (filecontent_checksum, chunk_checksum, num), FOREIGN KEY (filecontent_checksum) REFERENCES filecontent (checksum) ON DELETE NO ACTION ON UPDATE NO ACTION, FOREIGN KEY (chunk_checksum) REFERENCES chunk (checksum) ON DELETE NO ACTION ON UPDATE NO ACTION ); 
29-5-19 15:03:29.684 | SqlRunner       | Test worker    | INFO : SQL:  CREATE CACHED TABLE IF NOT EXISTS  filehistory ( id varchar(40) NOT NULL, databaseversion_id int NOT NULL, PRIMARY KEY (id, databaseversion_id), FOREIGN KEY (databaseversion_id) REFERENCES databaseversion (id) ON DELETE NO ACTION ON UPDATE NO ACTION ); 
29-5-19 15:03:29.686 | SqlRunner       | Test worker    | INFO : SQL:  CREATE CACHED TABLE IF NOT EXISTS  fileversion ( filehistory_id varchar(40) NOT NULL, version int NOT NULL, databaseversion_id int NOT NULL, path varchar(1024) NOT NULL, type varchar(45) NOT NULL, status varchar(45) NOT NULL, size bigint NOT NULL, lastmodified datetime NOT NULL, linktarget varchar(1024), filecontent_checksum varchar(40) DEFAULT NULL, updated datetime NOT NULL, posixperms varchar(45) DEFAULT NULL, dosattrs varchar(45) DEFAULT NULL, PRIMARY KEY (filehistory_id, version, databaseversion_id), FOREIGN KEY (filehistory_id, databaseversion_id) REFERENCES filehistory (id, databaseversion_id) ON DELETE NO ACTION ON UPDATE NO ACTION, FOREIGN KEY (filecontent_checksum) REFERENCES filecontent (checksum) ON DELETE NO ACTION ON UPDATE NO ACTION ); 
29-5-19 15:03:29.691 | SqlRunner       | Test worker    | INFO : SQL:  CREATE CACHED TABLE IF NOT EXISTS  multichunk ( id varchar(40) NOT NULL, databaseversion_id int NOT NULL, size bigint NOT NULL, PRIMARY KEY (id), FOREIGN KEY (databaseversion_id) REFERENCES databaseversion (id) ON DELETE NO ACTION ON UPDATE NO ACTION ); 
29-5-19 15:03:29.694 | SqlRunner       | Test worker    | INFO : SQL:  CREATE CACHED TABLE IF NOT EXISTS  multichunk_chunk ( multichunk_id varchar(40) NOT NULL, chunk_checksum varchar(40) NOT NULL, PRIMARY KEY (multichunk_id, chunk_checksum), FOREIGN KEY (multichunk_id) REFERENCES multichunk (id) ON DELETE NO ACTION ON UPDATE NO ACTION, FOREIGN KEY (chunk_checksum) REFERENCES chunk (checksum) ON DELETE NO ACTION ON UPDATE NO ACTION ); 
29-5-19 15:03:29.697 | SqlRunner       | Test worker    | INFO : SQL:  CREATE CACHED TABLE IF NOT EXISTS  multichunk_muddy ( id varchar(40) NOT NULL, machine_name varchar(255) NOT NULL, machine_version int NOT NULL, PRIMARY KEY (id) ); 
29-5-19 15:03:29.700 | SqlRunner       | Test worker    | INFO : SQL:  CREATE CACHED TABLE IF NOT EXISTS  known_databases ( id int NOT NULL IDENTITY, client varchar(45) NOT NULL, filenumber int NOT NULL, UNIQUE (client, filenumber) ); 
29-5-19 15:03:29.702 | SqlRunner       | Test worker    | INFO : SQL:  CREATE CACHED TABLE IF NOT EXISTS  general_settings ( key varchar(255) NOT NULL, value varchar(255) NOT NULL, PRIMARY KEY (key) ); 
29-5-19 15:03:29.705 | SqlRunner       | Test worker    | INFO : SQL (comment): -- Non-primary indices
29-5-19 15:03:29.705 | SqlRunner       | Test worker    | INFO : SQL:   CREATE INDEX idx_databaseversion_status ON databaseversion (status); 
29-5-19 15:03:29.706 | hsqldb.db.HSQLD | Test worker    | INFO : dataFileCache open start
29-5-19 15:03:29.709 | hsqldb.db.HSQLD | Test worker    | INFO : dataFileCache open end
29-5-19 15:03:29.711 | SqlRunner       | Test worker    | INFO : SQL: CREATE INDEX idx_databaseversion_vectorclock_serialized ON databaseversion (vectorclock_serialized); 
29-5-19 15:03:29.713 | SqlRunner       | Test worker    | INFO : SQL: CREATE INDEX idx_fileversion_path ON fileversion (path); 
29-5-19 15:03:29.715 | SqlRunner       | Test worker    | INFO : SQL: CREATE INDEX idx_fileversion_status ON fileversion (status); 
29-5-19 15:03:29.717 | SqlRunner       | Test worker    | INFO : SQL: CREATE INDEX idx_fileversion_filecontent_checksum ON fileversion (filecontent_checksum); 
29-5-19 15:03:29.719 | SqlRunner       | Test worker    | INFO : SQL (comment): -- Views
29-5-19 15:03:29.719 | SqlRunner       | Test worker    | INFO : SQL:    CREATE VIEW databaseversion_master AS SELECT dbv.*, vc.logicaltime as client_version FROM databaseversion dbv JOIN databaseversion_vectorclock vc on dbv.id=vc.databaseversion_id and dbv.client=vc.client WHERE dbv.status='MASTER'; 
29-5-19 15:03:29.722 | SqlRunner       | Test worker    | INFO : SQL:  CREATE VIEW fileversion_master AS SELECT fv0.* FROM fileversion fv0 JOIN databaseversion dbv ON fv0.databaseversion_id=dbv.id AND dbv.status='MASTER'; 
29-5-19 15:03:29.726 | SqlRunner       | Test worker    | INFO : SQL:  CREATE VIEW fileversion_master_maxversion AS SELECT DISTINCT filehistory_id, MAX(version) version FROM fileversion_master GROUP BY filehistory_id; 
29-5-19 15:03:29.730 | SqlRunner       | Test worker    | INFO : SQL:  CREATE VIEW fileversion_master_last AS SELECT fv.* FROM fileversion_master_maxversion fvmax JOIN fileversion_master fv ON fvmax.filehistory_id=fv.filehistory_id AND fvmax.version=fv.version WHERE fv.status<>'DELETED'; 
29-5-19 15:03:29.734 | SqlRunner       | Test worker    | INFO : SQL (comment): -- Full Views
29-5-19 15:03:29.735 | SqlRunner       | Test worker    | INFO : SQL:    create view filehistory_full as select dbv.status as databaseversion_status, dbv.localtime as databaseversion_localtime, dbv.client as databaseversion_client, dbv.vectorclock_serialized as databaseversion_vectorclock_serialized, fh.* from databaseversion dbv join filehistory fh on dbv.id=fh.databaseversion_id; 
29-5-19 15:03:29.738 | SqlRunner       | Test worker    | INFO : SQL:  create view fileversion_full as select fhf.databaseversion_status, fhf.databaseversion_localtime, fhf.databaseversion_client, fhf.databaseversion_vectorclock_serialized, fv.* from filehistory_full fhf join fileversion fv on fhf.id=fv.filehistory_id and fhf.databaseversion_id=fv.databaseversion_id; 
29-5-19 15:03:29.741 | SqlRunner       | Test worker    | INFO : SQL (comment): -- Functions
29-5-19 15:03:29.741 | SqlRunner       | Test worker    | INFO : SQL (new delimiter): end;
29-5-19 15:03:29.742 | SqlRunner       | Test worker    | INFO : SQL:     create function substr_count(haystack varchar(255), needle varchar(255)) returns integer begin atomic declare strCount integer; declare lastIndex integer;  set strCount = 0; set lastIndex = 1;  while lastIndex <> 0 do set lastIndex = locate(needle, haystack, lastIndex);  if lastIndex <> 0 then set strCount = strCount + 1; set lastIndex = lastIndex + length(needle); end if; end while;  return strCount; end; 
29-5-19 15:03:29.745 | UpOperation     | Test worker    | INFO : 
29-5-19 15:03:29.745 | UpOperation     | Test worker    | INFO : Running 'Sync up' at client syncanyclientCmzsAxTrywiGayXlFwHL ...
29-5-19 15:03:29.745 | UpOperation     | Test worker    | INFO : --------------------------------------------
29-5-19 15:03:29.745 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event UpStartSyncExternalEvent (org.syncany.operations.daemon.messages.UpStartSyncExternalEvent@51aab15) ...
29-5-19 15:03:29.747 | DatabaseConnect | Test worker    | INFO : Found 12 tables.
29-5-19 15:03:29.747 | StatusOperation | Test worker    | INFO : 
29-5-19 15:03:29.748 | StatusOperation | Test worker    | INFO : Running 'Status' at client syncanyclientCmzsAxTrywiGayXlFwHL ...
29-5-19 15:03:29.748 | StatusOperation | Test worker    | INFO : --------------------------------------------
29-5-19 15:03:29.748 | StatusOperation | Test worker    | INFO : Querying current file tree from database ...
29-5-19 15:03:29.748 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event StatusStartSyncExternalEvent (org.syncany.operations.daemon.messages.StatusStartSyncExternalEvent@303d042a) ...
29-5-19 15:03:29.750 | StatusOperation | Test worker    | INFO : Analyzing local folder /tmp/syncanytest/syncany-190529150308467-55776-client-syncanyclient ...
29-5-19 15:03:29.759 | StatusOperation | Test worker    | FINE : - New file: rndFile-1559142209538-1949528468.dat
29-5-19 15:03:29.759 | StatusOperation | Test worker    | FINE : - Ignoring file (syncany app-related): .syncany
29-5-19 15:03:29.759 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event StatusEndSyncExternalEvent (org.syncany.operations.daemon.messages.StatusEndSyncExternalEvent@4970a4d2) ...
29-5-19 15:03:29.760 | AbstractTransfe | Test worker    | INFO : Looking for other running remote operations ...
29-5-19 15:03:29.762 | DatabaseConnect | Test worker    | INFO : Found 12 tables.
29-5-19 15:03:29.763 | LsRemoteOperati | Test worker    | INFO : 
29-5-19 15:03:29.763 | LsRemoteOperati | Test worker    | INFO : Running 'Remote Status' at client syncanyclientCmzsAxTrywiGayXlFwHL ...
29-5-19 15:03:29.763 | LsRemoteOperati | Test worker    | INFO : --------------------------------------------
29-5-19 15:03:29.763 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event LsRemoteStartSyncExternalEvent (org.syncany.operations.daemon.messages.LsRemoteStartSyncExternalEvent@5766462d) ...
29-5-19 15:03:29.764 | LsRemoteOperati | Test worker    | INFO : Retrieving remote database list.
29-5-19 15:03:29.764 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event LsRemoteEndSyncExternalEvent (org.syncany.operations.daemon.messages.LsRemoteEndSyncExternalEvent@f5e0b35) ...
29-5-19 15:03:29.764 | UpOperation     | Test worker    | INFO : No remote changes, ready to upload.
29-5-19 15:03:29.765 | ActionFileHandl | Test worker    | INFO : Starting action for RemoteFile[name=action-up-syncanyclientCmzsAxTrywiGayXlFwHL-1559142209551] ...
29-5-19 15:03:29.765 | ActionFileHandl | Test worker    | INFO : Uploading action file: RemoteFile[name=action-up-syncanyclientCmzsAxTrywiGayXlFwHL-1559142209551]
29-5-19 15:03:29.765 | ActionFileHandl | Test worker    | INFO : Scheduling action renewal task for every 2 minutes, for RemoteFile[name=action-up-syncanyclientCmzsAxTrywiGayXlFwHL-1559142209551] ...
29-5-19 15:03:29.779 | DatabaseConnect | Test worker    | INFO : Found 12 tables.
29-5-19 15:03:29.784 | AsyncIndexer    | AsyncI/syncany | INFO : Starting Indexing.
29-5-19 15:03:29.784 | TransactionAwar | Test worker    | INFO : Done rolling back previous transactions.
29-5-19 15:03:29.785 | InternalEventBu | AsyncI/syncany | INFO : Event bus 'LocalEventBus': Posting event UpIndexStartSyncExternalEvent (org.syncany.operations.daemon.messages.UpIndexStartSyncExternalEvent@68a2cd51) ...
29-5-19 15:03:29.785 | Indexer         | AsyncI/syncany | FINE : - Looking for deleted files ...
29-5-19 15:03:29.786 | Indexer         | AsyncI/syncany | FINE : - +File /tmp/syncanytest/syncany-190529150308467-55776-client-syncanyclient/rndFile-1559142209538-1949528468.dat
29-5-19 15:03:29.786 | Indexer         | AsyncI/syncany | FINE : - +FileContent: /tmp/syncanytest/syncany-190529150308467-55776-client-syncanyclient/rndFile-1559142209538-1949528468.dat
29-5-19 15:03:29.791 | Indexer         | AsyncI/syncany | FINE : - Chunk new: 7d9ca3824573a78c804b158542453b8359ae78bc
29-5-19 15:03:29.792 | Indexer         | AsyncI/syncany | FINE : - +MultiChunk bf5100b015fba51ca8293054126c4e297295db1a
29-5-19 15:03:29.792 | Indexer         | AsyncI/syncany | FINE : - Chunk > MultiChunk: 7d9ca3824573a78c804b158542453b8359ae78bc > bf5100b015fba51ca8293054126c4e297295db1a
29-5-19 15:03:29.793 | Indexer         | AsyncI/syncany | FINE : - Chunk > FileContent: 7d9ca3824573a78c804b158542453b8359ae78bc > /tmp/syncanytest/syncany-190529150308467-55776-client-syncanyclient/rndFile-1559142209538-1949528468.dat
29-5-19 15:03:29.793 | Indexer         | AsyncI/syncany | FINE : - /File: rndFile-1559142209538-1949528468.dat (checksum 7d9ca3824573a78c804b158542453b8359ae78bc)
29-5-19 15:03:29.800 | Indexer         | AsyncI/syncany | FINE :    * No old file history found, starting new history (path: rndFile-1559142209538-1949528468.dat, checksum: 7d9ca3824573a78c804b158542453b8359ae78bc)
29-5-19 15:03:29.800 | FileVersionComp | AsyncI/syncany | INFO :      - [DELETED]: Local file DIFFERS from file version, actual file is NULL, for file rndFile-1559142209538-1949528468.dat
29-5-19 15:03:29.800 | Indexer         | AsyncI/syncany | INFO :    * Added file version:    FileVersion [version=1, path=rndFile-1559142209538-1949528468.dat, type=FILE, status=NEW, size=40, lastModified=Wed May 29 15:03:29 UTC 2019, linkTarget=null, checksum=7d9ca3824573a78c804b158542453b8359ae78bc, updated=Wed May 29 15:03:29 UTC 2019, posixPermissions=rw-rw-r--, dosAttributes=--a-]
29-5-19 15:03:29.800 | Indexer         | AsyncI/syncany | INFO :      based on file version: null
29-5-19 15:03:29.801 | InternalEventBu | AsyncI/syncany | INFO : Event bus 'LocalEventBus': Posting event UpIndexChangesDetectedSyncExternalEvent (org.syncany.operations.daemon.messages.UpIndexChangesDetectedSyncExternalEvent@48bb6243) ...
29-5-19 15:03:29.802 | Indexer         | AsyncI/syncany | FINE : - /MultiChunk bf5100b015fba51ca8293054126c4e297295db1a
29-5-19 15:03:29.803 | InternalEventBu | AsyncI/syncany | INFO : Event bus 'LocalEventBus': Posting event UpIndexEndSyncExternalEvent (org.syncany.operations.daemon.messages.UpIndexEndSyncExternalEvent@5b18200c) ...
29-5-19 15:03:29.803 | Indexer         | AsyncI/syncany | FINE : Processed new database version: DatabaseVersion [header=UnknownMachine/()/T=1559142209785, chunks=1, multiChunks=1, fileContents=1, fileHistories=1]
29-5-19 15:03:29.803 | InternalEventBu | AsyncI/syncany | INFO : Event bus 'LocalEventBus': Posting event UpIndexMidSyncExternalEvent (org.syncany.operations.daemon.messages.UpIndexMidSyncExternalEvent@4ffaaa95) ...
29-5-19 15:03:29.804 | UpOperation     | Test worker    | INFO : No DIRTY data found in database (no dirty databases); Nothing to do here.
29-5-19 15:03:29.804 | UpOperation     | Test worker    | INFO : Last vector clock was: ()
29-5-19 15:03:29.806 | UpOperation     | Test worker    | INFO : Uploading new multichunks ...
29-5-19 15:03:29.806 | UpOperation     | Test worker    | INFO : - Uploading multichunk bf5100b015fba51ca8293054126c4e297295db1a from /tmp/syncanytest/syncany-190529150308467-55776-client-syncanyclient/.syncany/cache/multichunk-bf5100b015fba51ca8293054126c4e297295db1a to RemoteFile[name=multichunk-bf5100b015fba51ca8293054126c4e297295db1a] ...
29-5-19 15:03:29.807 | RemoteTransacti | Test worker    | INFO : - Adding file to TX for UPLOAD: /tmp/syncanytest/syncany-190529150308467-55776-client-syncanyclient/.syncany/cache/multichunk-bf5100b015fba51ca8293054126c4e297295db1a -> Temp. remote file: RemoteFile[name=temp-FTPjJ-multichunk-bf5100b015fba51ca8293054126c4e297295db1a], final location: RemoteFile[name=multichunk-bf5100b015fba51ca8293054126c4e297295db1a]
29-5-19 15:03:29.807 | UpOperation     | Test worker    | INFO : Uploading database: DatabaseVersion [header=syncanyclientCmzsAxTrywiGayXlFwHL/(syncanyclientCmzsAxTrywiGayXlFwHL1)/T=1559142209806, chunks=1, multiChunks=1, fileContents=1, fileHistories=1]
29-5-19 15:03:29.807 | UpOperation     | Test worker    | INFO : Saving local delta database, version syncanyclientCmzsAxTrywiGayXlFwHL/(syncanyclientCmzsAxTrywiGayXlFwHL1)/T=1559142209806 to file /tmp/syncanytest/syncany-190529150308467-55776-client-syncanyclient/.syncany/cache/database-syncanyclientCmzsAxTrywiGayXlFwHL-0000000001 ... 
29-5-19 15:03:29.807 | UpOperation     | Test worker    | INFO : - Saving database to /tmp/syncanytest/syncany-190529150308467-55776-client-syncanyclient/.syncany/cache/database-syncanyclientCmzsAxTrywiGayXlFwHL-0000000001 ...
29-5-19 15:03:29.808 | UpOperation     | Test worker    | INFO : - Uploading local delta database file ...
29-5-19 15:03:29.808 | UpOperation     | Test worker    | INFO : - Uploading /tmp/syncanytest/syncany-190529150308467-55776-client-syncanyclient/.syncany/cache/database-syncanyclientCmzsAxTrywiGayXlFwHL-0000000001 to RemoteFile[name=database-syncanyclientCmzsAxTrywiGayXlFwHL-0000000001] ...
29-5-19 15:03:29.809 | RemoteTransacti | Test worker    | INFO : - Adding file to TX for UPLOAD: /tmp/syncanytest/syncany-190529150308467-55776-client-syncanyclient/.syncany/cache/database-syncanyclientCmzsAxTrywiGayXlFwHL-0000000001 -> Temp. remote file: RemoteFile[name=temp-xiqmX-database-syncanyclientCmzsAxTrywiGayXlFwHL-0000000001], final location: RemoteFile[name=database-syncanyclientCmzsAxTrywiGayXlFwHL-0000000001]
29-5-19 15:03:29.809 | UpOperation     | Test worker    | INFO : Adding shutdown hook (to allow resuming the upload) ...
29-5-19 15:03:29.809 | RemoteTransacti | Test worker    | INFO : Starting TX.commit() ...
29-5-19 15:03:29.818 | AsyncIndexer    | AsyncI/syncany | INFO : Stopping indexing. Signal end of stream with empty databaseversion
29-5-19 15:03:29.824 | RemoteTransacti | Test worker    | INFO : Wrote transaction manifest to temporary file: /tmp/syncanytest/syncany-190529150308467-55776-client-syncanyclient/.syncany/cache/temp-transaction-12731127876647644786.tmp
29-5-19 15:03:29.824 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event UpUploadFileSyncExternalEvent (org.syncany.operations.daemon.messages.UpUploadFileSyncExternalEvent@19632b13) ...
29-5-19 15:03:29.824 | RemoteTransacti | Test worker    | INFO : - Uploading remote transaction file RemoteFile[name=transaction-31c265a] ...
29-5-19 15:03:29.825 | RemoteTransacti | Test worker    | INFO : - Starting to upload data in commit.
29-5-19 15:03:29.825 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event UpUploadFileInTransactionSyncExternalEvent (org.syncany.operations.daemon.messages.UpUploadFileInTransactionSyncExternalEvent@6980db48) ...
29-5-19 15:03:29.825 | RemoteTransacti | Test worker    | INFO : - Uploading /tmp/syncanytest/syncany-190529150308467-55776-client-syncanyclient/.syncany/cache/multichunk-bf5100b015fba51ca8293054126c4e297295db1a to temp. file RemoteFile[name=temp-FTPjJ-multichunk-bf5100b015fba51ca8293054126c4e297295db1a] ...
29-5-19 15:03:29.826 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event UpUploadFileInTransactionSyncExternalEvent (org.syncany.operations.daemon.messages.UpUploadFileInTransactionSyncExternalEvent@5565e969) ...
29-5-19 15:03:29.826 | RemoteTransacti | Test worker    | INFO : - Uploading /tmp/syncanytest/syncany-190529150308467-55776-client-syncanyclient/.syncany/cache/database-syncanyclientCmzsAxTrywiGayXlFwHL-0000000001 to temp. file RemoteFile[name=temp-xiqmX-database-syncanyclientCmzsAxTrywiGayXlFwHL-0000000001] ...
29-5-19 15:03:29.842 | RemoteTransacti | Test worker    | INFO : - Moving temp. file RemoteFile[name=temp-FTPjJ-multichunk-bf5100b015fba51ca8293054126c4e297295db1a] to final location RemoteFile[name=multichunk-bf5100b015fba51ca8293054126c4e297295db1a] ...
29-5-19 15:03:29.844 | RemoteTransacti | Test worker    | INFO : - Moving temp. file RemoteFile[name=temp-xiqmX-database-syncanyclientCmzsAxTrywiGayXlFwHL-0000000001] to final location RemoteFile[name=database-syncanyclientCmzsAxTrywiGayXlFwHL-0000000001] ...
29-5-19 15:03:29.844 | RemoteTransacti | Test worker    | INFO : - Deleting remote transaction file RemoteFile[name=transaction-31c265a] ...
29-5-19 15:03:29.844 | RemoteTransacti | Test worker    | INFO : END of TX.commmit(): Succesfully committed transaction.
29-5-19 15:03:29.844 | RemoteTransacti | Test worker    | INFO : END of TX.delTemp(): Sucessfully deleted final files.
29-5-19 15:03:29.844 | UpOperation     | Test worker    | INFO : Persisting local SQL database (new database version syncanyclientCmzsAxTrywiGayXlFwHL/(syncanyclientCmzsAxTrywiGayXlFwHL1)/T=1559142209806) ...
29-5-19 15:03:29.852 | UpOperation     | Test worker    | INFO : Removing DIRTY database versions from database ...
29-5-19 15:03:29.886 | UpOperation     | Test worker    | INFO : Adding database version to result changes:DatabaseVersion [header=syncanyclientCmzsAxTrywiGayXlFwHL/(syncanyclientCmzsAxTrywiGayXlFwHL1)/T=1559142209806, chunks=1, multiChunks=1, fileContents=1, fileHistories=1]
29-5-19 15:03:29.886 | UpOperation     | Test worker    | INFO : Committing local database.
29-5-19 15:03:29.886 | UpOperation     | Test worker    | FINE : Waiting for new database version.
29-5-19 15:03:29.886 | UpOperation     | Test worker    | FINE : Took new database version: DatabaseVersion [header=UnknownMachine/()/T=1559142209818, chunks=0, multiChunks=0, fileContents=0, fileHistories=0]
29-5-19 15:03:29.886 | UpOperation     | Test worker    | INFO : Sync up done.
29-5-19 15:03:29.888 | ActionFileHandl | Test worker    | INFO : Finishing action for RemoteFile[name=action-up-syncanyclientCmzsAxTrywiGayXlFwHL-1559142209551] ...
29-5-19 15:03:29.894 | ActionFileHandl | Test worker    | INFO : Deleting action file: RemoteFile[name=action-up-syncanyclientCmzsAxTrywiGayXlFwHL-1559142209551]
29-5-19 15:03:29.894 | AbstractTransfe | Test worker    | INFO : Cleaning own old action files ...
29-5-19 15:03:29.895 | Cache           | Test worker    | INFO : Cache size okay (1 KB), no need to clean (keep size is 500 MB)
29-5-19 15:03:29.895 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event UpEndSyncExternalEvent (org.syncany.operations.daemon.messages.UpEndSyncExternalEvent@2ae5977a) ...
29-5-19 15:03:31.903 | DatabaseConnect | Test worker    | INFO : Found 12 tables.
29-5-19 15:03:31.906 | StatusOperation | Test worker    | INFO : 
29-5-19 15:03:31.906 | StatusOperation | Test worker    | INFO : Running 'Status' at client syncanyclientCmzsAxTrywiGayXlFwHL ...
29-5-19 15:03:31.906 | StatusOperation | Test worker    | INFO : --------------------------------------------
29-5-19 15:03:31.906 | StatusOperation | Test worker    | INFO : Querying current file tree from database ...
29-5-19 15:03:31.906 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event StatusStartSyncExternalEvent (org.syncany.operations.daemon.messages.StatusStartSyncExternalEvent@16ec3e87) ...
29-5-19 15:03:31.911 | StatusOperation | Test worker    | INFO : Analyzing local folder /tmp/syncanytest/syncany-190529150308467-55776-client-syncanyclient ...
29-5-19 15:03:31.918 | FileVersionComp | Test worker    | INFO :      - [CHANGED_LAST_MOD_DATE]: Local file DIFFERS from file version, expected MOD. DATE = 5/29/19, 3:03 PM (1,559,142,209,531), but actual MOD. DATE = 5/29/19, 3:03 PM (1,559,142,211,891), for file rndFile-1559142209538-1949528468.dat
29-5-19 15:03:31.922 | StatusOperation | Test worker    | FINE : - Ignoring file (syncany app-related): .syncany
29-5-19 15:03:31.923 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event StatusEndSyncExternalEvent (org.syncany.operations.daemon.messages.StatusEndSyncExternalEvent@4a533559) ...
29-5-19 15:03:31.942 | org.syncany.plu | Test worker    | FINE : Validating required fields
29-5-19 15:03:31.952 | LocalTransferMa | Test worker    | INFO : testTargetExists: Target exists.
29-5-19 15:03:32.103 | hsqldb.db.HSQLD | Test worker    | INFO : Checkpoint start
29-5-19 15:03:32.104 | hsqldb.db.HSQLD | Test worker    | INFO : checkpointClose start
29-5-19 15:03:32.119 | hsqldb.db.HSQLD | Test worker    | FINE : log close size: 0
29-5-19 15:03:32.123 | hsqldb.db.HSQLD | Test worker    | INFO : checkpointClose end
29-5-19 15:03:32.123 | hsqldb.db.HSQLD | Test worker    | INFO : Checkpoint end - txts: 1
29-5-19 15:03:32.138 | DatabaseConnect | Test worker    | INFO : Found 0 tables.
29-5-19 15:03:32.138 | DatabaseConnect | Test worker    | INFO : Database has no tables. Creating tables from script.create.all.sql
29-5-19 15:03:32.139 | SqlRunner       | Test worker    | INFO : SQL (comment): -- Tables
29-5-19 15:03:32.139 | SqlRunner       | Test worker    | INFO : SQL:  CREATE CACHED TABLE IF NOT EXISTS databaseversion ( id int NOT NULL IDENTITY, status varchar(45) NOT NULL, localtime datetime NOT NULL, client varchar(45) NOT NULL, vectorclock_serialized varchar(1024) NOT NULL, UNIQUE (vectorclock_serialized) ); 
29-5-19 15:03:32.148 | SqlRunner       | Test worker    | INFO : SQL:  CREATE CACHED TABLE IF NOT EXISTS  chunk ( checksum varchar(40) NOT NULL, databaseversion_id int NOT NULL, size bigint NOT NULL, PRIMARY KEY (checksum), FOREIGN KEY (databaseversion_id) REFERENCES databaseversion (id) ON DELETE NO ACTION ON UPDATE NO ACTION ); 
29-5-19 15:03:32.152 | SqlRunner       | Test worker    | INFO : SQL:  CREATE CACHED TABLE IF NOT EXISTS  databaseversion_vectorclock ( databaseversion_id int NOT NULL, client varchar(45) NOT NULL, logicaltime int NOT NULL, PRIMARY KEY (databaseversion_id, client), FOREIGN KEY (databaseversion_id) REFERENCES databaseversion (id) ON DELETE NO ACTION ON UPDATE NO ACTION ); 
29-5-19 15:03:32.160 | SqlRunner       | Test worker    | INFO : SQL:  CREATE CACHED TABLE IF NOT EXISTS  filecontent ( checksum varchar(40) NOT NULL, databaseversion_id int NOT NULL, size bigint NOT NULL, PRIMARY KEY (checksum), FOREIGN KEY (databaseversion_id) REFERENCES databaseversion (id) ON DELETE NO ACTION ON UPDATE NO ACTION ); 
29-5-19 15:03:32.168 | SqlRunner       | Test worker    | INFO : SQL:  CREATE CACHED TABLE IF NOT EXISTS  filecontent_chunk ( filecontent_checksum varchar(40) NOT NULL, chunk_checksum varchar(40) NOT NULL, num int NOT NULL, PRIMARY KEY (filecontent_checksum, chunk_checksum, num), FOREIGN KEY (filecontent_checksum) REFERENCES filecontent (checksum) ON DELETE NO ACTION ON UPDATE NO ACTION, FOREIGN KEY (chunk_checksum) REFERENCES chunk (checksum) ON DELETE NO ACTION ON UPDATE NO ACTION ); 
29-5-19 15:03:32.176 | SqlRunner       | Test worker    | INFO : SQL:  CREATE CACHED TABLE IF NOT EXISTS  filehistory ( id varchar(40) NOT NULL, databaseversion_id int NOT NULL, PRIMARY KEY (id, databaseversion_id), FOREIGN KEY (databaseversion_id) REFERENCES databaseversion (id) ON DELETE NO ACTION ON UPDATE NO ACTION ); 
29-5-19 15:03:32.180 | SqlRunner       | Test worker    | INFO : SQL:  CREATE CACHED TABLE IF NOT EXISTS  fileversion ( filehistory_id varchar(40) NOT NULL, version int NOT NULL, databaseversion_id int NOT NULL, path varchar(1024) NOT NULL, type varchar(45) NOT NULL, status varchar(45) NOT NULL, size bigint NOT NULL, lastmodified datetime NOT NULL, linktarget varchar(1024), filecontent_checksum varchar(40) DEFAULT NULL, updated datetime NOT NULL, posixperms varchar(45) DEFAULT NULL, dosattrs varchar(45) DEFAULT NULL, PRIMARY KEY (filehistory_id, version, databaseversion_id), FOREIGN KEY (filehistory_id, databaseversion_id) REFERENCES filehistory (id, databaseversion_id) ON DELETE NO ACTION ON UPDATE NO ACTION, FOREIGN KEY (filecontent_checksum) REFERENCES filecontent (checksum) ON DELETE NO ACTION ON UPDATE NO ACTION ); 
29-5-19 15:03:32.193 | SqlRunner       | Test worker    | INFO : SQL:  CREATE CACHED TABLE IF NOT EXISTS  multichunk ( id varchar(40) NOT NULL, databaseversion_id int NOT NULL, size bigint NOT NULL, PRIMARY KEY (id), FOREIGN KEY (databaseversion_id) REFERENCES databaseversion (id) ON DELETE NO ACTION ON UPDATE NO ACTION ); 
29-5-19 15:03:32.196 | SqlRunner       | Test worker    | INFO : SQL:  CREATE CACHED TABLE IF NOT EXISTS  multichunk_chunk ( multichunk_id varchar(40) NOT NULL, chunk_checksum varchar(40) NOT NULL, PRIMARY KEY (multichunk_id, chunk_checksum), FOREIGN KEY (multichunk_id) REFERENCES multichunk (id) ON DELETE NO ACTION ON UPDATE NO ACTION, FOREIGN KEY (chunk_checksum) REFERENCES chunk (checksum) ON DELETE NO ACTION ON UPDATE NO ACTION ); 
29-5-19 15:03:32.200 | SqlRunner       | Test worker    | INFO : SQL:  CREATE CACHED TABLE IF NOT EXISTS  multichunk_muddy ( id varchar(40) NOT NULL, machine_name varchar(255) NOT NULL, machine_version int NOT NULL, PRIMARY KEY (id) ); 
29-5-19 15:03:32.202 | SqlRunner       | Test worker    | INFO : SQL:  CREATE CACHED TABLE IF NOT EXISTS  known_databases ( id int NOT NULL IDENTITY, client varchar(45) NOT NULL, filenumber int NOT NULL, UNIQUE (client, filenumber) ); 
29-5-19 15:03:32.205 | SqlRunner       | Test worker    | INFO : SQL:  CREATE CACHED TABLE IF NOT EXISTS  general_settings ( key varchar(255) NOT NULL, value varchar(255) NOT NULL, PRIMARY KEY (key) ); 
29-5-19 15:03:32.208 | SqlRunner       | Test worker    | INFO : SQL (comment): -- Non-primary indices
29-5-19 15:03:32.208 | SqlRunner       | Test worker    | INFO : SQL:   CREATE INDEX idx_databaseversion_status ON databaseversion (status); 
29-5-19 15:03:32.209 | hsqldb.db.HSQLD | Test worker    | INFO : dataFileCache open start
29-5-19 15:03:32.212 | hsqldb.db.HSQLD | Test worker    | INFO : dataFileCache open end
29-5-19 15:03:32.215 | SqlRunner       | Test worker    | INFO : SQL: CREATE INDEX idx_databaseversion_vectorclock_serialized ON databaseversion (vectorclock_serialized); 
29-5-19 15:03:32.217 | SqlRunner       | Test worker    | INFO : SQL: CREATE INDEX idx_fileversion_path ON fileversion (path); 
29-5-19 15:03:32.219 | SqlRunner       | Test worker    | INFO : SQL: CREATE INDEX idx_fileversion_status ON fileversion (status); 
29-5-19 15:03:32.221 | SqlRunner       | Test worker    | INFO : SQL: CREATE INDEX idx_fileversion_filecontent_checksum ON fileversion (filecontent_checksum); 
29-5-19 15:03:32.224 | SqlRunner       | Test worker    | INFO : SQL (comment): -- Views
29-5-19 15:03:32.224 | SqlRunner       | Test worker    | INFO : SQL:    CREATE VIEW databaseversion_master AS SELECT dbv.*, vc.logicaltime as client_version FROM databaseversion dbv JOIN databaseversion_vectorclock vc on dbv.id=vc.databaseversion_id and dbv.client=vc.client WHERE dbv.status='MASTER'; 
29-5-19 15:03:32.227 | SqlRunner       | Test worker    | INFO : SQL:  CREATE VIEW fileversion_master AS SELECT fv0.* FROM fileversion fv0 JOIN databaseversion dbv ON fv0.databaseversion_id=dbv.id AND dbv.status='MASTER'; 
29-5-19 15:03:32.231 | SqlRunner       | Test worker    | INFO : SQL:  CREATE VIEW fileversion_master_maxversion AS SELECT DISTINCT filehistory_id, MAX(version) version FROM fileversion_master GROUP BY filehistory_id; 
29-5-19 15:03:32.234 | SqlRunner       | Test worker    | INFO : SQL:  CREATE VIEW fileversion_master_last AS SELECT fv.* FROM fileversion_master_maxversion fvmax JOIN fileversion_master fv ON fvmax.filehistory_id=fv.filehistory_id AND fvmax.version=fv.version WHERE fv.status<>'DELETED'; 
29-5-19 15:03:32.238 | SqlRunner       | Test worker    | INFO : SQL (comment): -- Full Views
29-5-19 15:03:32.238 | SqlRunner       | Test worker    | INFO : SQL:    create view filehistory_full as select dbv.status as databaseversion_status, dbv.localtime as databaseversion_localtime, dbv.client as databaseversion_client, dbv.vectorclock_serialized as databaseversion_vectorclock_serialized, fh.* from databaseversion dbv join filehistory fh on dbv.id=fh.databaseversion_id; 
29-5-19 15:03:32.241 | SqlRunner       | Test worker    | INFO : SQL:  create view fileversion_full as select fhf.databaseversion_status, fhf.databaseversion_localtime, fhf.databaseversion_client, fhf.databaseversion_vectorclock_serialized, fv.* from filehistory_full fhf join fileversion fv on fhf.id=fv.filehistory_id and fhf.databaseversion_id=fv.databaseversion_id; 
29-5-19 15:03:32.244 | SqlRunner       | Test worker    | INFO : SQL (comment): -- Functions
29-5-19 15:03:32.244 | SqlRunner       | Test worker    | INFO : SQL (new delimiter): end;
29-5-19 15:03:32.245 | SqlRunner       | Test worker    | INFO : SQL:     create function substr_count(haystack varchar(255), needle varchar(255)) returns integer begin atomic declare strCount integer; declare lastIndex integer;  set strCount = 0; set lastIndex = 1;  while lastIndex <> 0 do set lastIndex = locate(needle, haystack, lastIndex);  if lastIndex <> 0 then set strCount = strCount + 1; set lastIndex = lastIndex + length(needle); end if; end while;  return strCount; end; 
29-5-19 15:03:32.248 | StatusOperation | Test worker    | INFO : 
29-5-19 15:03:32.249 | StatusOperation | Test worker    | INFO : Running 'Status' at client syncanyclientSsbXnzoprwGQnjVowRIn ...
29-5-19 15:03:32.249 | StatusOperation | Test worker    | INFO : --------------------------------------------
29-5-19 15:03:32.249 | StatusOperation | Test worker    | INFO : Querying current file tree from database ...
29-5-19 15:03:32.249 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event StatusStartSyncExternalEvent (org.syncany.operations.daemon.messages.StatusStartSyncExternalEvent@1697352a) ...
29-5-19 15:03:32.251 | StatusOperation | Test worker    | INFO : Analyzing local folder /tmp/syncanytest/syncany-190529150308467-19547-client-syncanyclient ...
29-5-19 15:03:32.252 | StatusOperation | Test worker    | FINE : - New file: somefolder
29-5-19 15:03:32.252 | StatusOperation | Test worker    | FINE : - Ignoring file (syncany app-related): .syncany
29-5-19 15:03:32.253 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event StatusEndSyncExternalEvent (org.syncany.operations.daemon.messages.StatusEndSyncExternalEvent@438642e) ...
29-5-19 15:03:32.254 | org.syncany.plu | Test worker    | FINE : Validating required fields
29-5-19 15:03:32.270 | TransferManager | Test worker    | INFO : Building LocalTransferManager from config 'syncany-190529150308467-19547-client-syncanyclient' ...
29-5-19 15:03:32.271 | TransferManager | Test worker    | INFO : - With feature ReadAfterWriteConsistent
29-5-19 15:03:32.271 | TransferManager | Test worker    | INFO : - With feature Retriable
29-5-19 15:03:32.271 | TransferManager | Test worker    | FINE : - Checking if selected features supported ...
29-5-19 15:03:32.271 | TransferManager | Test worker    | FINE : - Checking for duplicate features ...
29-5-19 15:03:32.272 | TransferManager | Test worker    | FINE : - Checking required feature TransactionAware in LocalTransferManager ...
29-5-19 15:03:32.272 | TransferManager | Test worker    | FINE : - Checking required feature Retriable in LocalTransferManager ...
29-5-19 15:03:32.272 | TransferManager | Test worker    | INFO : - SKIPPING unsupported optional feature ReadAfterWriteConsistent
29-5-19 15:03:32.272 | TransferManager | Test worker    | FINE : - Wrapping TransferManager LocalTransferManager in RetriableFeatureTransferManager
29-5-19 15:03:32.272 | org.syncany.plu | Test worker    | FINE : Validating required fields
29-5-19 15:03:32.273 | TransferManager | Test worker    | INFO : Building LocalTransferManager from config 'syncany-190529150308467-19547-client-syncanyclient' ...
29-5-19 15:03:32.273 | TransferManager | Test worker    | INFO : - With feature ReadAfterWriteConsistent
29-5-19 15:03:32.273 | TransferManager | Test worker    | INFO : - With feature Retriable
29-5-19 15:03:32.273 | TransferManager | Test worker    | INFO : - With feature PathAware
29-5-19 15:03:32.273 | TransferManager | Test worker    | INFO : - With feature TransactionAware
29-5-19 15:03:32.274 | TransferManager | Test worker    | FINE : - Checking if selected features supported ...
29-5-19 15:03:32.274 | TransferManager | Test worker    | FINE : - Checking for duplicate features ...
29-5-19 15:03:32.274 | TransferManager | Test worker    | FINE : - Checking required feature TransactionAware in LocalTransferManager ...
29-5-19 15:03:32.274 | TransferManager | Test worker    | FINE : - Checking required feature Retriable in LocalTransferManager ...
29-5-19 15:03:32.274 | TransferManager | Test worker    | INFO : - SKIPPING unsupported optional feature ReadAfterWriteConsistent
29-5-19 15:03:32.274 | TransferManager | Test worker    | FINE : - Wrapping TransferManager LocalTransferManager in RetriableFeatureTransferManager
29-5-19 15:03:32.274 | TransferManager | Test worker    | INFO : - SKIPPING unsupported optional feature PathAware
29-5-19 15:03:32.275 | TransferManager | Test worker    | FINE : - Wrapping TransferManager RetriableFeatureTransferManager in TransactionAwareFeatureTransferManager
29-5-19 15:03:32.277 | DatabaseConnect | Test worker    | INFO : Found 12 tables.
29-5-19 15:03:32.278 | UpOperation     | Test worker    | INFO : 
29-5-19 15:03:32.278 | UpOperation     | Test worker    | INFO : Running 'Sync up' at client syncanyclientSsbXnzoprwGQnjVowRIn ...
29-5-19 15:03:32.278 | UpOperation     | Test worker    | INFO : --------------------------------------------
29-5-19 15:03:32.278 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event UpStartSyncExternalEvent (org.syncany.operations.daemon.messages.UpStartSyncExternalEvent@7c664aae) ...
29-5-19 15:03:32.280 | DatabaseConnect | Test worker    | INFO : Found 12 tables.
29-5-19 15:03:32.282 | StatusOperation | Test worker    | INFO : 
29-5-19 15:03:32.283 | StatusOperation | Test worker    | INFO : Running 'Status' at client syncanyclientSsbXnzoprwGQnjVowRIn ...
29-5-19 15:03:32.283 | StatusOperation | Test worker    | INFO : --------------------------------------------
29-5-19 15:03:32.283 | StatusOperation | Test worker    | INFO : Querying current file tree from database ...
29-5-19 15:03:32.283 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event StatusStartSyncExternalEvent (org.syncany.operations.daemon.messages.StatusStartSyncExternalEvent@608186c9) ...
29-5-19 15:03:32.289 | StatusOperation | Test worker    | INFO : Analyzing local folder /tmp/syncanytest/syncany-190529150308467-19547-client-syncanyclient ...
29-5-19 15:03:32.294 | StatusOperation | Test worker    | FINE : - New file: somefolder
29-5-19 15:03:32.295 | StatusOperation | Test worker    | FINE : - Ignoring file (syncany app-related): .syncany
29-5-19 15:03:32.302 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event StatusEndSyncExternalEvent (org.syncany.operations.daemon.messages.StatusEndSyncExternalEvent@53a955fe) ...
29-5-19 15:03:32.303 | AbstractTransfe | Test worker    | INFO : Looking for other running remote operations ...
29-5-19 15:03:32.305 | DatabaseConnect | Test worker    | INFO : Found 12 tables.
29-5-19 15:03:32.310 | LsRemoteOperati | Test worker    | INFO : 
29-5-19 15:03:32.310 | LsRemoteOperati | Test worker    | INFO : Running 'Remote Status' at client syncanyclientSsbXnzoprwGQnjVowRIn ...
29-5-19 15:03:32.310 | LsRemoteOperati | Test worker    | INFO : --------------------------------------------
29-5-19 15:03:32.311 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event LsRemoteStartSyncExternalEvent (org.syncany.operations.daemon.messages.LsRemoteStartSyncExternalEvent@3b4ca0be) ...
29-5-19 15:03:32.312 | LsRemoteOperati | Test worker    | INFO : Retrieving remote database list.
29-5-19 15:03:32.312 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event LsRemoteEndSyncExternalEvent (org.syncany.operations.daemon.messages.LsRemoteEndSyncExternalEvent@2128cd77) ...
29-5-19 15:03:32.312 | UpOperation     | Test worker    | INFO : No remote changes, ready to upload.
29-5-19 15:03:32.312 | ActionFileHandl | Test worker    | INFO : Starting action for RemoteFile[name=action-up-syncanyclientSsbXnzoprwGQnjVowRIn-1559142212275] ...
29-5-19 15:03:32.313 | ActionFileHandl | Test worker    | INFO : Uploading action file: RemoteFile[name=action-up-syncanyclientSsbXnzoprwGQnjVowRIn-1559142212275]
29-5-19 15:03:32.313 | ActionFileHandl | Test worker    | INFO : Scheduling action renewal task for every 2 minutes, for RemoteFile[name=action-up-syncanyclientSsbXnzoprwGQnjVowRIn-1559142212275] ...
29-5-19 15:03:32.316 | DatabaseConnect | Test worker    | INFO : Found 12 tables.
29-5-19 15:03:32.334 | TransactionAwar | Test worker    | INFO : Done rolling back previous transactions.
29-5-19 15:03:32.335 | AsyncIndexer    | AsyncI/syncany | INFO : Starting Indexing.
29-5-19 15:03:32.335 | InternalEventBu | AsyncI/syncany | INFO : Event bus 'LocalEventBus': Posting event UpIndexStartSyncExternalEvent (org.syncany.operations.daemon.messages.UpIndexStartSyncExternalEvent@384ccabb) ...
29-5-19 15:03:32.335 | Indexer         | AsyncI/syncany | FINE : - Looking for deleted files ...
29-5-19 15:03:32.335 | Indexer         | AsyncI/syncany | FINE : - +File /tmp/syncanytest/syncany-190529150308467-19547-client-syncanyclient/somefolder
29-5-19 15:03:32.338 | Indexer         | AsyncI/syncany | FINE : - /File: somefolder (directory/symlink/0-byte-file)
29-5-19 15:03:32.343 | Indexer         | AsyncI/syncany | FINE :    * No old file history found, starting new history (path: somefolder, FOLDER)
29-5-19 15:03:32.344 | FileVersionComp | AsyncI/syncany | INFO :      - [DELETED]: Local file DIFFERS from file version, actual file is NULL, for file somefolder
29-5-19 15:03:32.345 | Indexer         | AsyncI/syncany | INFO :    * Added file version:    FileVersion [version=1, path=somefolder, type=FOLDER, status=NEW, size=4096, lastModified=Wed May 29 15:03:31 UTC 2019, linkTarget=null, checksum=null, updated=Wed May 29 15:03:32 UTC 2019, posixPermissions=rwxrwxr-x, dosAttributes=--a-]
29-5-19 15:03:32.345 | Indexer         | AsyncI/syncany | INFO :      based on file version: null
29-5-19 15:03:32.345 | InternalEventBu | AsyncI/syncany | INFO : Event bus 'LocalEventBus': Posting event UpIndexChangesDetectedSyncExternalEvent (org.syncany.operations.daemon.messages.UpIndexChangesDetectedSyncExternalEvent@4aa26e02) ...
29-5-19 15:03:32.345 | InternalEventBu | AsyncI/syncany | INFO : Event bus 'LocalEventBus': Posting event UpIndexEndSyncExternalEvent (org.syncany.operations.daemon.messages.UpIndexEndSyncExternalEvent@13d72d86) ...
29-5-19 15:03:32.345 | Indexer         | AsyncI/syncany | FINE : Processed new database version: DatabaseVersion [header=UnknownMachine/()/T=1559142212335, chunks=0, multiChunks=0, fileContents=0, fileHistories=1]
29-5-19 15:03:32.345 | InternalEventBu | AsyncI/syncany | INFO : Event bus 'LocalEventBus': Posting event UpIndexMidSyncExternalEvent (org.syncany.operations.daemon.messages.UpIndexMidSyncExternalEvent@4ac4d137) ...
29-5-19 15:03:32.347 | UpOperation     | Test worker    | INFO : No DIRTY data found in database (no dirty databases); Nothing to do here.
29-5-19 15:03:32.347 | UpOperation     | Test worker    | INFO : Last vector clock was: ()
29-5-19 15:03:32.348 | UpOperation     | Test worker    | INFO : Uploading new multichunks ...
29-5-19 15:03:32.349 | UpOperation     | Test worker    | INFO : Uploading database: DatabaseVersion [header=syncanyclientSsbXnzoprwGQnjVowRIn/(syncanyclientSsbXnzoprwGQnjVowRIn1)/T=1559142212348, chunks=0, multiChunks=0, fileContents=0, fileHistories=1]
29-5-19 15:03:32.349 | UpOperation     | Test worker    | INFO : Saving local delta database, version syncanyclientSsbXnzoprwGQnjVowRIn/(syncanyclientSsbXnzoprwGQnjVowRIn1)/T=1559142212348 to file /tmp/syncanytest/syncany-190529150308467-19547-client-syncanyclient/.syncany/cache/database-syncanyclientSsbXnzoprwGQnjVowRIn-0000000001 ... 
29-5-19 15:03:32.350 | UpOperation     | Test worker    | INFO : - Saving database to /tmp/syncanytest/syncany-190529150308467-19547-client-syncanyclient/.syncany/cache/database-syncanyclientSsbXnzoprwGQnjVowRIn-0000000001 ...
29-5-19 15:03:32.354 | UpOperation     | Test worker    | INFO : - Uploading local delta database file ...
29-5-19 15:03:32.355 | UpOperation     | Test worker    | INFO : - Uploading /tmp/syncanytest/syncany-190529150308467-19547-client-syncanyclient/.syncany/cache/database-syncanyclientSsbXnzoprwGQnjVowRIn-0000000001 to RemoteFile[name=database-syncanyclientSsbXnzoprwGQnjVowRIn-0000000001] ...
29-5-19 15:03:32.355 | RemoteTransacti | Test worker    | INFO : - Adding file to TX for UPLOAD: /tmp/syncanytest/syncany-190529150308467-19547-client-syncanyclient/.syncany/cache/database-syncanyclientSsbXnzoprwGQnjVowRIn-0000000001 -> Temp. remote file: RemoteFile[name=temp-GOpIo-database-syncanyclientSsbXnzoprwGQnjVowRIn-0000000001], final location: RemoteFile[name=database-syncanyclientSsbXnzoprwGQnjVowRIn-0000000001]
29-5-19 15:03:32.356 | UpOperation     | Test worker    | INFO : Adding shutdown hook (to allow resuming the upload) ...
29-5-19 15:03:32.356 | RemoteTransacti | Test worker    | INFO : Starting TX.commit() ...
29-5-19 15:03:32.369 | AsyncIndexer    | AsyncI/syncany | INFO : Stopping indexing. Signal end of stream with empty databaseversion
29-5-19 15:03:32.372 | RemoteTransacti | Test worker    | INFO : Wrote transaction manifest to temporary file: /tmp/syncanytest/syncany-190529150308467-19547-client-syncanyclient/.syncany/cache/temp-transaction-14770819840951455622.tmp
29-5-19 15:03:32.373 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event UpUploadFileSyncExternalEvent (org.syncany.operations.daemon.messages.UpUploadFileSyncExternalEvent@8c88dd5) ...
29-5-19 15:03:32.373 | RemoteTransacti | Test worker    | INFO : - Uploading remote transaction file RemoteFile[name=transaction-785ce44c] ...
29-5-19 15:03:32.373 | RemoteTransacti | Test worker    | INFO : - Starting to upload data in commit.
29-5-19 15:03:32.374 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event UpUploadFileInTransactionSyncExternalEvent (org.syncany.operations.daemon.messages.UpUploadFileInTransactionSyncExternalEvent@de1064c) ...
29-5-19 15:03:32.374 | RemoteTransacti | Test worker    | INFO : - Uploading /tmp/syncanytest/syncany-190529150308467-19547-client-syncanyclient/.syncany/cache/database-syncanyclientSsbXnzoprwGQnjVowRIn-0000000001 to temp. file RemoteFile[name=temp-GOpIo-database-syncanyclientSsbXnzoprwGQnjVowRIn-0000000001] ...
29-5-19 15:03:32.375 | RemoteTransacti | Test worker    | INFO : - Moving temp. file RemoteFile[name=temp-GOpIo-database-syncanyclientSsbXnzoprwGQnjVowRIn-0000000001] to final location RemoteFile[name=database-syncanyclientSsbXnzoprwGQnjVowRIn-0000000001] ...
29-5-19 15:03:32.375 | RemoteTransacti | Test worker    | INFO : - Deleting remote transaction file RemoteFile[name=transaction-785ce44c] ...
29-5-19 15:03:32.375 | RemoteTransacti | Test worker    | INFO : END of TX.commmit(): Succesfully committed transaction.
29-5-19 15:03:32.375 | RemoteTransacti | Test worker    | INFO : END of TX.delTemp(): Sucessfully deleted final files.
29-5-19 15:03:32.377 | UpOperation     | Test worker    | INFO : Persisting local SQL database (new database version syncanyclientSsbXnzoprwGQnjVowRIn/(syncanyclientSsbXnzoprwGQnjVowRIn1)/T=1559142212348) ...
29-5-19 15:03:32.388 | UpOperation     | Test worker    | INFO : Removing DIRTY database versions from database ...
29-5-19 15:03:32.413 | UpOperation     | Test worker    | INFO : Adding database version to result changes:DatabaseVersion [header=syncanyclientSsbXnzoprwGQnjVowRIn/(syncanyclientSsbXnzoprwGQnjVowRIn1)/T=1559142212348, chunks=0, multiChunks=0, fileContents=0, fileHistories=1]
29-5-19 15:03:32.414 | UpOperation     | Test worker    | INFO : Committing local database.
29-5-19 15:03:32.414 | UpOperation     | Test worker    | FINE : Waiting for new database version.
29-5-19 15:03:32.414 | UpOperation     | Test worker    | FINE : Took new database version: DatabaseVersion [header=UnknownMachine/()/T=1559142212370, chunks=0, multiChunks=0, fileContents=0, fileHistories=0]
29-5-19 15:03:32.414 | UpOperation     | Test worker    | INFO : Sync up done.
29-5-19 15:03:32.416 | ActionFileHandl | Test worker    | INFO : Finishing action for RemoteFile[name=action-up-syncanyclientSsbXnzoprwGQnjVowRIn-1559142212275] ...
29-5-19 15:03:32.422 | ActionFileHandl | Test worker    | INFO : Deleting action file: RemoteFile[name=action-up-syncanyclientSsbXnzoprwGQnjVowRIn-1559142212275]
29-5-19 15:03:32.422 | AbstractTransfe | Test worker    | INFO : Cleaning own old action files ...
29-5-19 15:03:32.423 | Cache           | Test worker    | INFO : Cache size okay (0 KB), no need to clean (keep size is 500 MB)
29-5-19 15:03:32.423 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event UpEndSyncExternalEvent (org.syncany.operations.daemon.messages.UpEndSyncExternalEvent@48467433) ...
29-5-19 15:03:32.425 | DatabaseConnect | Test worker    | INFO : Found 12 tables.
29-5-19 15:03:32.425 | StatusOperation | Test worker    | INFO : 
29-5-19 15:03:32.425 | StatusOperation | Test worker    | INFO : Running 'Status' at client syncanyclientSsbXnzoprwGQnjVowRIn ...
29-5-19 15:03:32.425 | StatusOperation | Test worker    | INFO : --------------------------------------------
29-5-19 15:03:32.425 | StatusOperation | Test worker    | INFO : Querying current file tree from database ...
29-5-19 15:03:32.425 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event StatusStartSyncExternalEvent (org.syncany.operations.daemon.messages.StatusStartSyncExternalEvent@69430313) ...
29-5-19 15:03:32.433 | StatusOperation | Test worker    | INFO : Analyzing local folder /tmp/syncanytest/syncany-190529150308467-19547-client-syncanyclient ...
29-5-19 15:03:32.437 | StatusOperation | Test worker    | FINE : - Ignoring file (syncany app-related): .syncany
29-5-19 15:03:32.437 | StatusOperation | Test worker    | INFO : - No changes to local database
29-5-19 15:03:32.437 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event StatusEndSyncExternalEvent (org.syncany.operations.daemon.messages.StatusEndSyncExternalEvent@58c5a57f) ...