Class org.syncany.tests.integration.operations.LogOperationTest

1

tests

0

failures

0

ignored

0.464s

duration

100%

successful

Tests

Test Duration Result
testLogOperation 0.464s passed

Standard error

25-5-17 10:18:53.218 | org.syncany.plu | Test worker    | FINE : Validating required fields
25-5-17 10:18:53.218 | LocalTransferMa | Test worker    | INFO : testTargetExists: Target exists.
25-5-17 10:18:53.222 | org.syncany.plu | Test worker    | FINE : Validating required fields
25-5-17 10:18:53.223 | TransferManager | Test worker    | INFO : Building LocalTransferManager from config 'syncany-170525101612005-85758-client-syncanyclient' ...
25-5-17 10:18:53.223 | TransferManager | Test worker    | INFO : - With feature ReadAfterWriteConsistent
25-5-17 10:18:53.223 | TransferManager | Test worker    | INFO : - With feature Retriable
25-5-17 10:18:53.223 | TransferManager | Test worker    | FINE : - Checking if selected features supported ...
25-5-17 10:18:53.223 | TransferManager | Test worker    | FINE : - Checking for duplicate features ...
25-5-17 10:18:53.223 | TransferManager | Test worker    | FINE : - Checking required feature TransactionAware in LocalTransferManager ...
25-5-17 10:18:53.223 | TransferManager | Test worker    | FINE : - Checking required feature Retriable in LocalTransferManager ...
25-5-17 10:18:53.223 | TransferManager | Test worker    | INFO : - SKIPPING unsupported optional feature ReadAfterWriteConsistent
25-5-17 10:18:53.224 | TransferManager | Test worker    | FINE : - Wrapping TransferManager LocalTransferManager in RetriableFeatureTransferManager
25-5-17 10:18:53.224 | org.syncany.plu | Test worker    | FINE : Validating required fields
25-5-17 10:18:53.224 | TransferManager | Test worker    | INFO : Building LocalTransferManager from config 'syncany-170525101612005-85758-client-syncanyclient' ...
25-5-17 10:18:53.224 | TransferManager | Test worker    | INFO : - With feature ReadAfterWriteConsistent
25-5-17 10:18:53.224 | TransferManager | Test worker    | INFO : - With feature Retriable
25-5-17 10:18:53.224 | TransferManager | Test worker    | INFO : - With feature PathAware
25-5-17 10:18:53.224 | TransferManager | Test worker    | INFO : - With feature TransactionAware
25-5-17 10:18:53.224 | TransferManager | Test worker    | FINE : - Checking if selected features supported ...
25-5-17 10:18:53.224 | TransferManager | Test worker    | FINE : - Checking for duplicate features ...
25-5-17 10:18:53.224 | TransferManager | Test worker    | FINE : - Checking required feature TransactionAware in LocalTransferManager ...
25-5-17 10:18:53.224 | TransferManager | Test worker    | FINE : - Checking required feature Retriable in LocalTransferManager ...
25-5-17 10:18:53.225 | TransferManager | Test worker    | INFO : - SKIPPING unsupported optional feature ReadAfterWriteConsistent
25-5-17 10:18:53.225 | TransferManager | Test worker    | FINE : - Wrapping TransferManager LocalTransferManager in RetriableFeatureTransferManager
25-5-17 10:18:53.225 | TransferManager | Test worker    | INFO : - SKIPPING unsupported optional feature PathAware
25-5-17 10:18:53.225 | TransferManager | Test worker    | FINE : - Wrapping TransferManager RetriableFeatureTransferManager in TransactionAwareFeatureTransferManager
25-5-17 10:18:53.239 | hsqldb.db.HSQLD | Test worker    | INFO : Checkpoint start
25-5-17 10:18:53.239 | hsqldb.db.HSQLD | Test worker    | INFO : checkpointClose start
25-5-17 10:18:53.249 | hsqldb.db.HSQLD | Test worker    | FINE : log close size: 0
25-5-17 10:18:53.252 | hsqldb.db.HSQLD | Test worker    | INFO : checkpointClose end
25-5-17 10:18:53.252 | hsqldb.db.HSQLD | Test worker    | INFO : Checkpoint end - txts: 1
25-5-17 10:18:53.258 | DatabaseConnect | Test worker    | INFO : Found 0 tables.
25-5-17 10:18:53.258 | DatabaseConnect | Test worker    | INFO : Database has no tables. Creating tables from script.create.all.sql
25-5-17 10:18:53.258 | SqlRunner       | Test worker    | INFO : SQL (comment): -- Tables
25-5-17 10:18:53.258 | 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) ); 
25-5-17 10:18:53.261 | 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 ); 
25-5-17 10:18:53.264 | 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 ); 
25-5-17 10:18:53.267 | 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 ); 
25-5-17 10:18:53.269 | SqlRunner       | Test worker    | INFO : SQL:  CREATE CACHED TABLE IF NOT EXISTS  filecontent_chunk ( filecontent_checksum varchar(40) NOT NULL, chunk_checksum varchar(40) NOT NULL, num int NOT NULL, PRIMARY KEY (filecontent_checksum, chunk_checksum, num), FOREIGN KEY (filecontent_checksum) REFERENCES filecontent (checksum) ON DELETE NO ACTION ON UPDATE NO ACTION, FOREIGN KEY (chunk_checksum) REFERENCES chunk (checksum) ON DELETE NO ACTION ON UPDATE NO ACTION ); 
25-5-17 10:18:53.272 | 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 ); 
25-5-17 10:18:53.274 | 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 ); 
25-5-17 10:18:53.277 | 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 ); 
25-5-17 10:18:53.280 | 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 ); 
25-5-17 10:18:53.301 | 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) ); 
25-5-17 10:18:53.303 | 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) ); 
25-5-17 10:18:53.305 | 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) ); 
25-5-17 10:18:53.307 | SqlRunner       | Test worker    | INFO : SQL (comment): -- Non-primary indices
25-5-17 10:18:53.308 | SqlRunner       | Test worker    | INFO : SQL:   CREATE INDEX idx_databaseversion_status ON databaseversion (status); 
25-5-17 10:18:53.308 | hsqldb.db.HSQLD | Test worker    | INFO : dataFileCache open start
25-5-17 10:18:53.311 | hsqldb.db.HSQLD | Test worker    | INFO : dataFileCache open end
25-5-17 10:18:53.313 | SqlRunner       | Test worker    | INFO : SQL: CREATE INDEX idx_databaseversion_vectorclock_serialized ON databaseversion (vectorclock_serialized); 
25-5-17 10:18:53.320 | SqlRunner       | Test worker    | INFO : SQL: CREATE INDEX idx_fileversion_path ON fileversion (path); 
25-5-17 10:18:53.324 | SqlRunner       | Test worker    | INFO : SQL: CREATE INDEX idx_fileversion_status ON fileversion (status); 
25-5-17 10:18:53.327 | SqlRunner       | Test worker    | INFO : SQL: CREATE INDEX idx_fileversion_filecontent_checksum ON fileversion (filecontent_checksum); 
25-5-17 10:18:53.330 | SqlRunner       | Test worker    | INFO : SQL (comment): -- Views
25-5-17 10:18:53.330 | 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'; 
25-5-17 10:18:53.332 | 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'; 
25-5-17 10:18:53.336 | 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; 
25-5-17 10:18:53.340 | 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'; 
25-5-17 10:18:53.344 | SqlRunner       | Test worker    | INFO : SQL (comment): -- Full Views
25-5-17 10:18:53.344 | 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; 
25-5-17 10:18:53.346 | 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; 
25-5-17 10:18:53.349 | SqlRunner       | Test worker    | INFO : SQL (comment): -- Functions
25-5-17 10:18:53.349 | SqlRunner       | Test worker    | INFO : SQL (new delimiter): end;
25-5-17 10:18:53.349 | 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; 
25-5-17 10:18:53.352 | UpOperation     | Test worker    | INFO : 
25-5-17 10:18:53.352 | UpOperation     | Test worker    | INFO : Running 'Sync up' at client syncanyclientUtlRgSJlhXdMEYpRfMVG ...
25-5-17 10:18:53.352 | UpOperation     | Test worker    | INFO : --------------------------------------------
25-5-17 10:18:53.352 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event UpStartSyncExternalEvent (org.syncany.operations.daemon.messages.UpStartSyncExternalEvent@34cb0130) ...
25-5-17 10:18:53.353 | DatabaseConnect | Test worker    | INFO : Found 12 tables.
25-5-17 10:18:53.353 | StatusOperation | Test worker    | INFO : 
25-5-17 10:18:53.353 | StatusOperation | Test worker    | INFO : Running 'Status' at client syncanyclientUtlRgSJlhXdMEYpRfMVG ...
25-5-17 10:18:53.353 | StatusOperation | Test worker    | INFO : --------------------------------------------
25-5-17 10:18:53.354 | StatusOperation | Test worker    | INFO : Force checksum ENABLED.
25-5-17 10:18:53.354 | StatusOperation | Test worker    | INFO : Querying current file tree from database ...
25-5-17 10:18:53.354 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event StatusStartSyncExternalEvent (org.syncany.operations.daemon.messages.StatusStartSyncExternalEvent@5248359c) ...
25-5-17 10:18:53.354 | StatusOperation | Test worker    | INFO : Analyzing local folder /tmp/syncanytest/syncany-170525101612005-85758-client-syncanyclient ...
25-5-17 10:18:53.354 | StatusOperation | Test worker    | FINE : - Ignoring file (syncany app-related): .syncany
25-5-17 10:18:53.355 | StatusOperation | Test worker    | FINE : - New file: rndFile-1495707533222-605980650.dat
25-5-17 10:18:53.355 | StatusOperation | Test worker    | FINE : - New file: rndFile-1495707533222-1287253930.dat
25-5-17 10:18:53.355 | StatusOperation | Test worker    | FINE : - New file: rndFile-1495707533222-364151122.dat
25-5-17 10:18:53.355 | StatusOperation | Test worker    | FINE : - New file: rndFile-1495707533222-1604636888.dat
25-5-17 10:18:53.355 | StatusOperation | Test worker    | FINE : - New file: rndFile-1495707533222-866775795.dat
25-5-17 10:18:53.355 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event StatusEndSyncExternalEvent (org.syncany.operations.daemon.messages.StatusEndSyncExternalEvent@75f9f209) ...
25-5-17 10:18:53.355 | AbstractTransfe | Test worker    | INFO : Looking for other running remote operations ...
25-5-17 10:18:53.355 | UpOperation     | Test worker    | INFO : Force (--force-upload) is enabled, ignoring potential remote changes.
25-5-17 10:18:53.355 | ActionFileHandl | Test worker    | INFO : Starting action for RemoteFile[name=action-up-syncanyclientUtlRgSJlhXdMEYpRfMVG-1495707533225] ...
25-5-17 10:18:53.356 | ActionFileHandl | Test worker    | INFO : Uploading action file: RemoteFile[name=action-up-syncanyclientUtlRgSJlhXdMEYpRfMVG-1495707533225]
25-5-17 10:18:53.356 | ActionFileHandl | Test worker    | INFO : Scheduling action renewal task for every 2 minutes, for RemoteFile[name=action-up-syncanyclientUtlRgSJlhXdMEYpRfMVG-1495707533225] ...
25-5-17 10:18:53.357 | DatabaseConnect | Test worker    | INFO : Found 12 tables.
25-5-17 10:18:53.362 | TransactionAwar | Test worker    | INFO : Done rolling back previous transactions.
25-5-17 10:18:53.363 | AsyncIndexer    | AsyncI/syncany | INFO : Starting Indexing.
25-5-17 10:18:53.363 | InternalEventBu | AsyncI/syncany | INFO : Event bus 'LocalEventBus': Posting event UpIndexStartSyncExternalEvent (org.syncany.operations.daemon.messages.UpIndexStartSyncExternalEvent@6b976286) ...
25-5-17 10:18:53.363 | Indexer         | AsyncI/syncany | FINE : - Looking for deleted files ...
25-5-17 10:18:53.363 | Indexer         | AsyncI/syncany | FINE : - +File /tmp/syncanytest/syncany-170525101612005-85758-client-syncanyclient/rndFile-1495707533222-1287253930.dat
25-5-17 10:18:53.364 | Indexer         | AsyncI/syncany | FINE : - +FileContent: /tmp/syncanytest/syncany-170525101612005-85758-client-syncanyclient/rndFile-1495707533222-1287253930.dat
25-5-17 10:18:53.368 | Indexer         | AsyncI/syncany | FINE : - Chunk new: 6b22f38fb08d82cadc4c43facc121b967fc1e02d
25-5-17 10:18:53.369 | Indexer         | AsyncI/syncany | FINE : - +MultiChunk 0be6bb9dff966c30ff6b9778989d81339496d9f5
25-5-17 10:18:53.369 | Indexer         | AsyncI/syncany | FINE : - Chunk > MultiChunk: 6b22f38fb08d82cadc4c43facc121b967fc1e02d > 0be6bb9dff966c30ff6b9778989d81339496d9f5
25-5-17 10:18:53.370 | Indexer         | AsyncI/syncany | FINE : - Chunk > FileContent: 6b22f38fb08d82cadc4c43facc121b967fc1e02d > /tmp/syncanytest/syncany-170525101612005-85758-client-syncanyclient/rndFile-1495707533222-1287253930.dat
25-5-17 10:18:53.370 | Indexer         | AsyncI/syncany | FINE : - /File: rndFile-1495707533222-1287253930.dat (checksum 6b22f38fb08d82cadc4c43facc121b967fc1e02d)
25-5-17 10:18:53.371 | Indexer         | AsyncI/syncany | FINE :    * No old file history found, starting new history (path: rndFile-1495707533222-1287253930.dat, checksum: 6b22f38fb08d82cadc4c43facc121b967fc1e02d)
25-5-17 10:18:53.371 | FileVersionComp | AsyncI/syncany | INFO :      - [DELETED]: Local file DIFFERS from file version, actual file is NULL, for file rndFile-1495707533222-1287253930.dat
25-5-17 10:18:53.371 | Indexer         | AsyncI/syncany | INFO :    * Added file version:    FileVersion [version=1, path=rndFile-1495707533222-1287253930.dat, type=FILE, status=NEW, size=5120, lastModified=Thu May 25 10:18:53 UTC 2017, linkTarget=null, checksum=6b22f38fb08d82cadc4c43facc121b967fc1e02d, updated=Thu May 25 10:18:53 UTC 2017, posixPermissions=rw-rw-r--, dosAttributes=--a-]
25-5-17 10:18:53.371 | Indexer         | AsyncI/syncany | INFO :      based on file version: null
25-5-17 10:18:53.371 | InternalEventBu | AsyncI/syncany | INFO : Event bus 'LocalEventBus': Posting event UpIndexChangesDetectedSyncExternalEvent (org.syncany.operations.daemon.messages.UpIndexChangesDetectedSyncExternalEvent@430e6428) ...
25-5-17 10:18:53.378 | Indexer         | AsyncI/syncany | FINE : - +File /tmp/syncanytest/syncany-170525101612005-85758-client-syncanyclient/rndFile-1495707533222-1604636888.dat
25-5-17 10:18:53.378 | Indexer         | AsyncI/syncany | FINE : - +FileContent: /tmp/syncanytest/syncany-170525101612005-85758-client-syncanyclient/rndFile-1495707533222-1604636888.dat
25-5-17 10:18:53.379 | Indexer         | AsyncI/syncany | FINE : - Chunk new: e362627babd24564e2979345ddb0077d54e80009
25-5-17 10:18:53.379 | Indexer         | AsyncI/syncany | FINE : - Chunk > MultiChunk: e362627babd24564e2979345ddb0077d54e80009 > 0be6bb9dff966c30ff6b9778989d81339496d9f5
25-5-17 10:18:53.379 | Indexer         | AsyncI/syncany | FINE : - Chunk > FileContent: e362627babd24564e2979345ddb0077d54e80009 > /tmp/syncanytest/syncany-170525101612005-85758-client-syncanyclient/rndFile-1495707533222-1604636888.dat
25-5-17 10:18:53.379 | Indexer         | AsyncI/syncany | FINE : - /File: rndFile-1495707533222-1604636888.dat (checksum e362627babd24564e2979345ddb0077d54e80009)
25-5-17 10:18:53.380 | Indexer         | AsyncI/syncany | FINE :    * No old file history found, starting new history (path: rndFile-1495707533222-1604636888.dat, checksum: e362627babd24564e2979345ddb0077d54e80009)
25-5-17 10:18:53.381 | FileVersionComp | AsyncI/syncany | INFO :      - [DELETED]: Local file DIFFERS from file version, actual file is NULL, for file rndFile-1495707533222-1604636888.dat
25-5-17 10:18:53.381 | Indexer         | AsyncI/syncany | INFO :    * Added file version:    FileVersion [version=1, path=rndFile-1495707533222-1604636888.dat, type=FILE, status=NEW, size=5120, lastModified=Thu May 25 10:18:53 UTC 2017, linkTarget=null, checksum=e362627babd24564e2979345ddb0077d54e80009, updated=Thu May 25 10:18:53 UTC 2017, posixPermissions=rw-rw-r--, dosAttributes=--a-]
25-5-17 10:18:53.381 | Indexer         | AsyncI/syncany | INFO :      based on file version: null
25-5-17 10:18:53.381 | Indexer         | AsyncI/syncany | FINE : - +File /tmp/syncanytest/syncany-170525101612005-85758-client-syncanyclient/rndFile-1495707533222-364151122.dat
25-5-17 10:18:53.381 | Indexer         | AsyncI/syncany | FINE : - +FileContent: /tmp/syncanytest/syncany-170525101612005-85758-client-syncanyclient/rndFile-1495707533222-364151122.dat
25-5-17 10:18:53.381 | Indexer         | AsyncI/syncany | FINE : - Chunk new: c2e20095238f6f105a8f50e8f0d75f95c61f122d
25-5-17 10:18:53.382 | Indexer         | AsyncI/syncany | FINE : - Chunk > MultiChunk: c2e20095238f6f105a8f50e8f0d75f95c61f122d > 0be6bb9dff966c30ff6b9778989d81339496d9f5
25-5-17 10:18:53.382 | Indexer         | AsyncI/syncany | FINE : - Chunk > FileContent: c2e20095238f6f105a8f50e8f0d75f95c61f122d > /tmp/syncanytest/syncany-170525101612005-85758-client-syncanyclient/rndFile-1495707533222-364151122.dat
25-5-17 10:18:53.382 | Indexer         | AsyncI/syncany | FINE : - /File: rndFile-1495707533222-364151122.dat (checksum c2e20095238f6f105a8f50e8f0d75f95c61f122d)
25-5-17 10:18:53.382 | Indexer         | AsyncI/syncany | FINE :    * No old file history found, starting new history (path: rndFile-1495707533222-364151122.dat, checksum: c2e20095238f6f105a8f50e8f0d75f95c61f122d)
25-5-17 10:18:53.382 | FileVersionComp | AsyncI/syncany | INFO :      - [DELETED]: Local file DIFFERS from file version, actual file is NULL, for file rndFile-1495707533222-364151122.dat
25-5-17 10:18:53.382 | Indexer         | AsyncI/syncany | INFO :    * Added file version:    FileVersion [version=1, path=rndFile-1495707533222-364151122.dat, type=FILE, status=NEW, size=5120, lastModified=Thu May 25 10:18:53 UTC 2017, linkTarget=null, checksum=c2e20095238f6f105a8f50e8f0d75f95c61f122d, updated=Thu May 25 10:18:53 UTC 2017, posixPermissions=rw-rw-r--, dosAttributes=--a-]
25-5-17 10:18:53.382 | Indexer         | AsyncI/syncany | INFO :      based on file version: null
25-5-17 10:18:53.382 | Indexer         | AsyncI/syncany | FINE : - +File /tmp/syncanytest/syncany-170525101612005-85758-client-syncanyclient/rndFile-1495707533222-605980650.dat
25-5-17 10:18:53.382 | Indexer         | AsyncI/syncany | FINE : - +FileContent: /tmp/syncanytest/syncany-170525101612005-85758-client-syncanyclient/rndFile-1495707533222-605980650.dat
25-5-17 10:18:53.384 | Indexer         | AsyncI/syncany | FINE : - Chunk new: 6e69581f2a554c5d7872c861b3ec4aac99be394f
25-5-17 10:18:53.384 | Indexer         | AsyncI/syncany | FINE : - Chunk > MultiChunk: 6e69581f2a554c5d7872c861b3ec4aac99be394f > 0be6bb9dff966c30ff6b9778989d81339496d9f5
25-5-17 10:18:53.384 | Indexer         | AsyncI/syncany | FINE : - Chunk > FileContent: 6e69581f2a554c5d7872c861b3ec4aac99be394f > /tmp/syncanytest/syncany-170525101612005-85758-client-syncanyclient/rndFile-1495707533222-605980650.dat
25-5-17 10:18:53.384 | Indexer         | AsyncI/syncany | FINE : - /File: rndFile-1495707533222-605980650.dat (checksum 6e69581f2a554c5d7872c861b3ec4aac99be394f)
25-5-17 10:18:53.384 | Indexer         | AsyncI/syncany | FINE :    * No old file history found, starting new history (path: rndFile-1495707533222-605980650.dat, checksum: 6e69581f2a554c5d7872c861b3ec4aac99be394f)
25-5-17 10:18:53.384 | FileVersionComp | AsyncI/syncany | INFO :      - [DELETED]: Local file DIFFERS from file version, actual file is NULL, for file rndFile-1495707533222-605980650.dat
25-5-17 10:18:53.385 | Indexer         | AsyncI/syncany | INFO :    * Added file version:    FileVersion [version=1, path=rndFile-1495707533222-605980650.dat, type=FILE, status=NEW, size=5120, lastModified=Thu May 25 10:18:53 UTC 2017, linkTarget=null, checksum=6e69581f2a554c5d7872c861b3ec4aac99be394f, updated=Thu May 25 10:18:53 UTC 2017, posixPermissions=rw-rw-r--, dosAttributes=--a-]
25-5-17 10:18:53.385 | Indexer         | AsyncI/syncany | INFO :      based on file version: null
25-5-17 10:18:53.385 | Indexer         | AsyncI/syncany | FINE : - +File /tmp/syncanytest/syncany-170525101612005-85758-client-syncanyclient/rndFile-1495707533222-866775795.dat
25-5-17 10:18:53.385 | Indexer         | AsyncI/syncany | FINE : - +FileContent: /tmp/syncanytest/syncany-170525101612005-85758-client-syncanyclient/rndFile-1495707533222-866775795.dat
25-5-17 10:18:53.385 | Indexer         | AsyncI/syncany | FINE : - Chunk new: 3e6dd0cc98acf6c1c89420412f2171f27ad4b3d2
25-5-17 10:18:53.385 | Indexer         | AsyncI/syncany | FINE : - Chunk > MultiChunk: 3e6dd0cc98acf6c1c89420412f2171f27ad4b3d2 > 0be6bb9dff966c30ff6b9778989d81339496d9f5
25-5-17 10:18:53.385 | Indexer         | AsyncI/syncany | FINE : - Chunk > FileContent: 3e6dd0cc98acf6c1c89420412f2171f27ad4b3d2 > /tmp/syncanytest/syncany-170525101612005-85758-client-syncanyclient/rndFile-1495707533222-866775795.dat
25-5-17 10:18:53.385 | Indexer         | AsyncI/syncany | FINE : - /File: rndFile-1495707533222-866775795.dat (checksum 3e6dd0cc98acf6c1c89420412f2171f27ad4b3d2)
25-5-17 10:18:53.386 | Indexer         | AsyncI/syncany | FINE :    * No old file history found, starting new history (path: rndFile-1495707533222-866775795.dat, checksum: 3e6dd0cc98acf6c1c89420412f2171f27ad4b3d2)
25-5-17 10:18:53.386 | FileVersionComp | AsyncI/syncany | INFO :      - [DELETED]: Local file DIFFERS from file version, actual file is NULL, for file rndFile-1495707533222-866775795.dat
25-5-17 10:18:53.386 | Indexer         | AsyncI/syncany | INFO :    * Added file version:    FileVersion [version=1, path=rndFile-1495707533222-866775795.dat, type=FILE, status=NEW, size=5120, lastModified=Thu May 25 10:18:53 UTC 2017, linkTarget=null, checksum=3e6dd0cc98acf6c1c89420412f2171f27ad4b3d2, updated=Thu May 25 10:18:53 UTC 2017, posixPermissions=rw-rw-r--, dosAttributes=--a-]
25-5-17 10:18:53.386 | Indexer         | AsyncI/syncany | INFO :      based on file version: null
25-5-17 10:18:53.387 | Indexer         | AsyncI/syncany | FINE : - /MultiChunk 0be6bb9dff966c30ff6b9778989d81339496d9f5
25-5-17 10:18:53.387 | InternalEventBu | AsyncI/syncany | INFO : Event bus 'LocalEventBus': Posting event UpIndexEndSyncExternalEvent (org.syncany.operations.daemon.messages.UpIndexEndSyncExternalEvent@589e51e5) ...
25-5-17 10:18:53.387 | Indexer         | AsyncI/syncany | FINE : Processed new database version: DatabaseVersion [header=UnknownMachine/()/T=1495707533363, chunks=5, multiChunks=1, fileContents=5, fileHistories=5]
25-5-17 10:18:53.387 | InternalEventBu | AsyncI/syncany | INFO : Event bus 'LocalEventBus': Posting event UpIndexMidSyncExternalEvent (org.syncany.operations.daemon.messages.UpIndexMidSyncExternalEvent@6f0925a3) ...
25-5-17 10:18:53.388 | UpOperation     | Test worker    | INFO : No DIRTY data found in database (no dirty databases); Nothing to do here.
25-5-17 10:18:53.389 | UpOperation     | Test worker    | INFO : Last vector clock was: ()
25-5-17 10:18:53.389 | UpOperation     | Test worker    | INFO : Uploading new multichunks ...
25-5-17 10:18:53.389 | UpOperation     | Test worker    | INFO : - Uploading multichunk 0be6bb9dff966c30ff6b9778989d81339496d9f5 from /tmp/syncanytest/syncany-170525101612005-85758-client-syncanyclient/.syncany/cache/multichunk-0be6bb9dff966c30ff6b9778989d81339496d9f5 to RemoteFile[name=multichunk-0be6bb9dff966c30ff6b9778989d81339496d9f5] ...
25-5-17 10:18:53.389 | RemoteTransacti | Test worker    | INFO : - Adding file to TX for UPLOAD: /tmp/syncanytest/syncany-170525101612005-85758-client-syncanyclient/.syncany/cache/multichunk-0be6bb9dff966c30ff6b9778989d81339496d9f5 -> Temp. remote file: RemoteFile[name=temp-yqEtM-multichunk-0be6bb9dff966c30ff6b9778989d81339496d9f5], final location: RemoteFile[name=multichunk-0be6bb9dff966c30ff6b9778989d81339496d9f5]
25-5-17 10:18:53.389 | UpOperation     | Test worker    | INFO : Uploading database: DatabaseVersion [header=syncanyclientUtlRgSJlhXdMEYpRfMVG/(syncanyclientUtlRgSJlhXdMEYpRfMVG1)/T=1495707533389, chunks=5, multiChunks=1, fileContents=5, fileHistories=5]
25-5-17 10:18:53.390 | UpOperation     | Test worker    | INFO : Saving local delta database, version syncanyclientUtlRgSJlhXdMEYpRfMVG/(syncanyclientUtlRgSJlhXdMEYpRfMVG1)/T=1495707533389 to file /tmp/syncanytest/syncany-170525101612005-85758-client-syncanyclient/.syncany/cache/database-syncanyclientUtlRgSJlhXdMEYpRfMVG-0000000001 ... 
25-5-17 10:18:53.390 | UpOperation     | Test worker    | INFO : - Saving database to /tmp/syncanytest/syncany-170525101612005-85758-client-syncanyclient/.syncany/cache/database-syncanyclientUtlRgSJlhXdMEYpRfMVG-0000000001 ...
25-5-17 10:18:53.391 | UpOperation     | Test worker    | INFO : - Uploading local delta database file ...
25-5-17 10:18:53.391 | UpOperation     | Test worker    | INFO : - Uploading /tmp/syncanytest/syncany-170525101612005-85758-client-syncanyclient/.syncany/cache/database-syncanyclientUtlRgSJlhXdMEYpRfMVG-0000000001 to RemoteFile[name=database-syncanyclientUtlRgSJlhXdMEYpRfMVG-0000000001] ...
25-5-17 10:18:53.391 | RemoteTransacti | Test worker    | INFO : - Adding file to TX for UPLOAD: /tmp/syncanytest/syncany-170525101612005-85758-client-syncanyclient/.syncany/cache/database-syncanyclientUtlRgSJlhXdMEYpRfMVG-0000000001 -> Temp. remote file: RemoteFile[name=temp-WDeRr-database-syncanyclientUtlRgSJlhXdMEYpRfMVG-0000000001], final location: RemoteFile[name=database-syncanyclientUtlRgSJlhXdMEYpRfMVG-0000000001]
25-5-17 10:18:53.391 | UpOperation     | Test worker    | INFO : Adding shutdown hook (to allow resuming the upload) ...
25-5-17 10:18:53.391 | RemoteTransacti | Test worker    | INFO : Starting TX.commit() ...
25-5-17 10:18:53.395 | RemoteTransacti | Test worker    | INFO : Wrote transaction manifest to temporary file: /tmp/syncanytest/syncany-170525101612005-85758-client-syncanyclient/.syncany/cache/temp-transaction-5434154058566644190.tmp
25-5-17 10:18:53.396 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event UpUploadFileSyncExternalEvent (org.syncany.operations.daemon.messages.UpUploadFileSyncExternalEvent@246a3d30) ...
25-5-17 10:18:53.396 | RemoteTransacti | Test worker    | INFO : - Uploading remote transaction file RemoteFile[name=transaction-26d25834] ...
25-5-17 10:18:53.396 | RemoteTransacti | Test worker    | INFO : - Starting to upload data in commit.
25-5-17 10:18:53.396 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event UpUploadFileInTransactionSyncExternalEvent (org.syncany.operations.daemon.messages.UpUploadFileInTransactionSyncExternalEvent@44e2e79b) ...
25-5-17 10:18:53.396 | RemoteTransacti | Test worker    | INFO : - Uploading /tmp/syncanytest/syncany-170525101612005-85758-client-syncanyclient/.syncany/cache/multichunk-0be6bb9dff966c30ff6b9778989d81339496d9f5 to temp. file RemoteFile[name=temp-yqEtM-multichunk-0be6bb9dff966c30ff6b9778989d81339496d9f5] ...
25-5-17 10:18:53.397 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event UpUploadFileInTransactionSyncExternalEvent (org.syncany.operations.daemon.messages.UpUploadFileInTransactionSyncExternalEvent@573e3dfc) ...
25-5-17 10:18:53.397 | RemoteTransacti | Test worker    | INFO : - Uploading /tmp/syncanytest/syncany-170525101612005-85758-client-syncanyclient/.syncany/cache/database-syncanyclientUtlRgSJlhXdMEYpRfMVG-0000000001 to temp. file RemoteFile[name=temp-WDeRr-database-syncanyclientUtlRgSJlhXdMEYpRfMVG-0000000001] ...
25-5-17 10:18:53.397 | RemoteTransacti | Test worker    | INFO : - Moving temp. file RemoteFile[name=temp-yqEtM-multichunk-0be6bb9dff966c30ff6b9778989d81339496d9f5] to final location RemoteFile[name=multichunk-0be6bb9dff966c30ff6b9778989d81339496d9f5] ...
25-5-17 10:18:53.397 | RemoteTransacti | Test worker    | INFO : - Moving temp. file RemoteFile[name=temp-WDeRr-database-syncanyclientUtlRgSJlhXdMEYpRfMVG-0000000001] to final location RemoteFile[name=database-syncanyclientUtlRgSJlhXdMEYpRfMVG-0000000001] ...
25-5-17 10:18:53.397 | RemoteTransacti | Test worker    | INFO : - Deleting remote transaction file RemoteFile[name=transaction-26d25834] ...
25-5-17 10:18:53.400 | RemoteTransacti | Test worker    | INFO : END of TX.commmit(): Succesfully committed transaction.
25-5-17 10:18:53.400 | RemoteTransacti | Test worker    | INFO : END of TX.delTemp(): Sucessfully deleted final files.
25-5-17 10:18:53.400 | UpOperation     | Test worker    | INFO : Persisting local SQL database (new database version syncanyclientUtlRgSJlhXdMEYpRfMVG/(syncanyclientUtlRgSJlhXdMEYpRfMVG1)/T=1495707533389) ...
25-5-17 10:18:53.407 | AsyncIndexer    | AsyncI/syncany | INFO : Stopping indexing. Signal end of stream with empty databaseversion
25-5-17 10:18:53.408 | UpOperation     | Test worker    | INFO : Removing DIRTY database versions from database ...
25-5-17 10:18:53.415 | UpOperation     | Test worker    | INFO : Adding database version to result changes:DatabaseVersion [header=syncanyclientUtlRgSJlhXdMEYpRfMVG/(syncanyclientUtlRgSJlhXdMEYpRfMVG1)/T=1495707533389, chunks=5, multiChunks=1, fileContents=5, fileHistories=5]
25-5-17 10:18:53.415 | UpOperation     | Test worker    | INFO : Committing local database.
25-5-17 10:18:53.415 | UpOperation     | Test worker    | FINE : Waiting for new database version.
25-5-17 10:18:53.415 | UpOperation     | Test worker    | FINE : Took new database version: DatabaseVersion [header=UnknownMachine/()/T=1495707533407, chunks=0, multiChunks=0, fileContents=0, fileHistories=0]
25-5-17 10:18:53.415 | UpOperation     | Test worker    | INFO : Sync up done.
25-5-17 10:18:53.418 | ActionFileHandl | Test worker    | INFO : Finishing action for RemoteFile[name=action-up-syncanyclientUtlRgSJlhXdMEYpRfMVG-1495707533225] ...
25-5-17 10:18:53.425 | ActionFileHandl | Test worker    | INFO : Deleting action file: RemoteFile[name=action-up-syncanyclientUtlRgSJlhXdMEYpRfMVG-1495707533225]
25-5-17 10:18:53.425 | AbstractTransfe | Test worker    | INFO : Cleaning own old action files ...
25-5-17 10:18:53.426 | Cache           | Test worker    | INFO : Cache size okay (30 KB), no need to clean (keep size is 500 MB)
25-5-17 10:18:53.426 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event UpEndSyncExternalEvent (org.syncany.operations.daemon.messages.UpEndSyncExternalEvent@72c2eb64) ...
25-5-17 10:18:53.426 | org.syncany.plu | Test worker    | FINE : Validating required fields
25-5-17 10:18:53.426 | TransferManager | Test worker    | INFO : Building LocalTransferManager from config 'syncany-170525101612005-85758-client-syncanyclient' ...
25-5-17 10:18:53.426 | TransferManager | Test worker    | INFO : - With feature ReadAfterWriteConsistent
25-5-17 10:18:53.426 | TransferManager | Test worker    | INFO : - With feature Retriable
25-5-17 10:18:53.426 | TransferManager | Test worker    | FINE : - Checking if selected features supported ...
25-5-17 10:18:53.426 | TransferManager | Test worker    | FINE : - Checking for duplicate features ...
25-5-17 10:18:53.426 | TransferManager | Test worker    | FINE : - Checking required feature TransactionAware in LocalTransferManager ...
25-5-17 10:18:53.426 | TransferManager | Test worker    | FINE : - Checking required feature Retriable in LocalTransferManager ...
25-5-17 10:18:53.426 | TransferManager | Test worker    | INFO : - SKIPPING unsupported optional feature ReadAfterWriteConsistent
25-5-17 10:18:53.427 | TransferManager | Test worker    | FINE : - Wrapping TransferManager LocalTransferManager in RetriableFeatureTransferManager
25-5-17 10:18:53.427 | org.syncany.plu | Test worker    | FINE : Validating required fields
25-5-17 10:18:53.427 | TransferManager | Test worker    | INFO : Building LocalTransferManager from config 'syncany-170525101612005-85758-client-syncanyclient' ...
25-5-17 10:18:53.427 | TransferManager | Test worker    | INFO : - With feature ReadAfterWriteConsistent
25-5-17 10:18:53.427 | TransferManager | Test worker    | INFO : - With feature Retriable
25-5-17 10:18:53.427 | TransferManager | Test worker    | INFO : - With feature PathAware
25-5-17 10:18:53.427 | TransferManager | Test worker    | INFO : - With feature TransactionAware
25-5-17 10:18:53.427 | TransferManager | Test worker    | FINE : - Checking if selected features supported ...
25-5-17 10:18:53.427 | TransferManager | Test worker    | FINE : - Checking for duplicate features ...
25-5-17 10:18:53.427 | TransferManager | Test worker    | FINE : - Checking required feature TransactionAware in LocalTransferManager ...
25-5-17 10:18:53.427 | TransferManager | Test worker    | FINE : - Checking required feature Retriable in LocalTransferManager ...
25-5-17 10:18:53.427 | TransferManager | Test worker    | INFO : - SKIPPING unsupported optional feature ReadAfterWriteConsistent
25-5-17 10:18:53.427 | TransferManager | Test worker    | FINE : - Wrapping TransferManager LocalTransferManager in RetriableFeatureTransferManager
25-5-17 10:18:53.427 | TransferManager | Test worker    | INFO : - SKIPPING unsupported optional feature PathAware
25-5-17 10:18:53.427 | TransferManager | Test worker    | FINE : - Wrapping TransferManager RetriableFeatureTransferManager in TransactionAwareFeatureTransferManager
25-5-17 10:18:53.432 | DatabaseConnect | Test worker    | INFO : Found 12 tables.
25-5-17 10:18:53.432 | UpOperation     | Test worker    | INFO : 
25-5-17 10:18:53.432 | UpOperation     | Test worker    | INFO : Running 'Sync up' at client syncanyclientUtlRgSJlhXdMEYpRfMVG ...
25-5-17 10:18:53.432 | UpOperation     | Test worker    | INFO : --------------------------------------------
25-5-17 10:18:53.432 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event UpStartSyncExternalEvent (org.syncany.operations.daemon.messages.UpStartSyncExternalEvent@7cef32d5) ...
25-5-17 10:18:53.433 | DatabaseConnect | Test worker    | INFO : Found 12 tables.
25-5-17 10:18:53.433 | StatusOperation | Test worker    | INFO : 
25-5-17 10:18:53.433 | StatusOperation | Test worker    | INFO : Running 'Status' at client syncanyclientUtlRgSJlhXdMEYpRfMVG ...
25-5-17 10:18:53.433 | StatusOperation | Test worker    | INFO : --------------------------------------------
25-5-17 10:18:53.433 | StatusOperation | Test worker    | INFO : Force checksum ENABLED.
25-5-17 10:18:53.433 | StatusOperation | Test worker    | INFO : Querying current file tree from database ...
25-5-17 10:18:53.433 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event StatusStartSyncExternalEvent (org.syncany.operations.daemon.messages.StatusStartSyncExternalEvent@487b91dc) ...
25-5-17 10:18:53.434 | StatusOperation | Test worker    | INFO : Analyzing local folder /tmp/syncanytest/syncany-170525101612005-85758-client-syncanyclient ...
25-5-17 10:18:53.434 | StatusOperation | Test worker    | FINE : - Ignoring file (syncany app-related): .syncany
25-5-17 10:18:53.434 | FileVersionComp | Test worker    | INFO :      - [CHANGED_CHECKSUM]: Local file DIFFERS from file version, expected CHECKSUM = 6e69581f2a554c5d7872c861b3ec4aac99be394f, but actual CHECKSUM = de9d9ca7766f22dfce62cf7acb09a77fb8c4200e, for file rndFile-1495707533222-605980650.dat
25-5-17 10:18:53.434 | FileVersionComp | Test worker    | INFO :      - [CHANGED_CHECKSUM]: Local file DIFFERS from file version, expected CHECKSUM = 6b22f38fb08d82cadc4c43facc121b967fc1e02d, but actual CHECKSUM = 24081fe2871d984927e397822beee0ff626f9559, for file rndFile-1495707533222-1287253930.dat
25-5-17 10:18:53.435 | FileVersionComp | Test worker    | INFO :      - [CHANGED_CHECKSUM]: Local file DIFFERS from file version, expected CHECKSUM = c2e20095238f6f105a8f50e8f0d75f95c61f122d, but actual CHECKSUM = 018d854cc13b669eb4ab553dafb72ae701870501, for file rndFile-1495707533222-364151122.dat
25-5-17 10:18:53.435 | FileVersionComp | Test worker    | INFO :      - [CHANGED_CHECKSUM]: Local file DIFFERS from file version, expected CHECKSUM = e362627babd24564e2979345ddb0077d54e80009, but actual CHECKSUM = 2cc5f1093879b90283c960d184aa4c7c8c79c243, for file rndFile-1495707533222-1604636888.dat
25-5-17 10:18:53.435 | FileVersionComp | Test worker    | INFO :      - [CHANGED_CHECKSUM]: Local file DIFFERS from file version, expected CHECKSUM = 3e6dd0cc98acf6c1c89420412f2171f27ad4b3d2, but actual CHECKSUM = 5c4d98299e6ed1e2ca1a574f03d660d6b9d46dd0, for file rndFile-1495707533222-866775795.dat
25-5-17 10:18:53.435 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event StatusEndSyncExternalEvent (org.syncany.operations.daemon.messages.StatusEndSyncExternalEvent@4f84bbfe) ...
25-5-17 10:18:53.435 | AbstractTransfe | Test worker    | INFO : Looking for other running remote operations ...
25-5-17 10:18:53.436 | UpOperation     | Test worker    | INFO : Force (--force-upload) is enabled, ignoring potential remote changes.
25-5-17 10:18:53.436 | ActionFileHandl | Test worker    | INFO : Starting action for RemoteFile[name=action-up-syncanyclientUtlRgSJlhXdMEYpRfMVG-1495707533427] ...
25-5-17 10:18:53.436 | ActionFileHandl | Test worker    | INFO : Uploading action file: RemoteFile[name=action-up-syncanyclientUtlRgSJlhXdMEYpRfMVG-1495707533427]
25-5-17 10:18:53.436 | ActionFileHandl | Test worker    | INFO : Scheduling action renewal task for every 2 minutes, for RemoteFile[name=action-up-syncanyclientUtlRgSJlhXdMEYpRfMVG-1495707533427] ...
25-5-17 10:18:53.437 | DatabaseConnect | Test worker    | INFO : Found 12 tables.
25-5-17 10:18:53.440 | TransactionAwar | Test worker    | INFO : Done rolling back previous transactions.
25-5-17 10:18:53.440 | AsyncIndexer    | AsyncI/syncany | INFO : Starting Indexing.
25-5-17 10:18:53.440 | InternalEventBu | AsyncI/syncany | INFO : Event bus 'LocalEventBus': Posting event UpIndexStartSyncExternalEvent (org.syncany.operations.daemon.messages.UpIndexStartSyncExternalEvent@36a67953) ...
25-5-17 10:18:53.441 | Indexer         | AsyncI/syncany | FINE : - Looking for deleted files ...
25-5-17 10:18:53.441 | Indexer         | AsyncI/syncany | FINE : - +File /tmp/syncanytest/syncany-170525101612005-85758-client-syncanyclient/rndFile-1495707533222-1287253930.dat
25-5-17 10:18:53.441 | Indexer         | AsyncI/syncany | FINE : - +FileContent: /tmp/syncanytest/syncany-170525101612005-85758-client-syncanyclient/rndFile-1495707533222-1287253930.dat
25-5-17 10:18:53.442 | Indexer         | AsyncI/syncany | FINE : - Chunk new: 24081fe2871d984927e397822beee0ff626f9559
25-5-17 10:18:53.443 | Indexer         | AsyncI/syncany | FINE : - +MultiChunk 560c8e457e5e4f8f95340afd09b2f6ba485fb4c4
25-5-17 10:18:53.443 | Indexer         | AsyncI/syncany | FINE : - Chunk > MultiChunk: 24081fe2871d984927e397822beee0ff626f9559 > 560c8e457e5e4f8f95340afd09b2f6ba485fb4c4
25-5-17 10:18:53.444 | Indexer         | AsyncI/syncany | FINE : - Chunk > FileContent: 24081fe2871d984927e397822beee0ff626f9559 > /tmp/syncanytest/syncany-170525101612005-85758-client-syncanyclient/rndFile-1495707533222-1287253930.dat
25-5-17 10:18:53.444 | Indexer         | AsyncI/syncany | FINE : - /File: rndFile-1495707533222-1287253930.dat (checksum 24081fe2871d984927e397822beee0ff626f9559)
25-5-17 10:18:53.446 | Indexer         | AsyncI/syncany | FINE :    * Found old file history c95ad45b73b0906c06f1a12d98855fee55edad22 (by path: rndFile-1495707533222-1287253930.dat), appending new version.
25-5-17 10:18:53.447 | FileVersionComp | AsyncI/syncany | INFO :      - [CHANGED_CHECKSUM]: Local file DIFFERS from file version, expected CHECKSUM = 24081fe2871d984927e397822beee0ff626f9559, but actual CHECKSUM = 6b22f38fb08d82cadc4c43facc121b967fc1e02d, for file rndFile-1495707533222-1287253930.dat
25-5-17 10:18:53.447 | Indexer         | AsyncI/syncany | INFO :    * Added file version:    FileVersion [version=2, path=rndFile-1495707533222-1287253930.dat, type=FILE, status=CHANGED, size=5120, lastModified=Thu May 25 10:18:53 UTC 2017, linkTarget=null, checksum=24081fe2871d984927e397822beee0ff626f9559, updated=Thu May 25 10:18:53 UTC 2017, posixPermissions=rw-rw-r--, dosAttributes=--a-]
25-5-17 10:18:53.448 | Indexer         | AsyncI/syncany | INFO :      based on file version: FileVersion [version=1, path=rndFile-1495707533222-1287253930.dat, type=FILE, status=NEW, size=5120, lastModified=Thu May 25 10:18:53 UTC 2017, linkTarget=null, checksum=6b22f38fb08d82cadc4c43facc121b967fc1e02d, updated=Thu May 25 10:18:53 UTC 2017, posixPermissions=rw-rw-r--, dosAttributes=--a-]
25-5-17 10:18:53.448 | InternalEventBu | AsyncI/syncany | INFO : Event bus 'LocalEventBus': Posting event UpIndexChangesDetectedSyncExternalEvent (org.syncany.operations.daemon.messages.UpIndexChangesDetectedSyncExternalEvent@11a379c6) ...
25-5-17 10:18:53.449 | Indexer         | AsyncI/syncany | FINE : - +File /tmp/syncanytest/syncany-170525101612005-85758-client-syncanyclient/rndFile-1495707533222-1604636888.dat
25-5-17 10:18:53.450 | Indexer         | AsyncI/syncany | FINE : - +FileContent: /tmp/syncanytest/syncany-170525101612005-85758-client-syncanyclient/rndFile-1495707533222-1604636888.dat
25-5-17 10:18:53.450 | Indexer         | AsyncI/syncany | FINE : - Chunk new: 2cc5f1093879b90283c960d184aa4c7c8c79c243
25-5-17 10:18:53.450 | Indexer         | AsyncI/syncany | FINE : - Chunk > MultiChunk: 2cc5f1093879b90283c960d184aa4c7c8c79c243 > 560c8e457e5e4f8f95340afd09b2f6ba485fb4c4
25-5-17 10:18:53.451 | Indexer         | AsyncI/syncany | FINE : - Chunk > FileContent: 2cc5f1093879b90283c960d184aa4c7c8c79c243 > /tmp/syncanytest/syncany-170525101612005-85758-client-syncanyclient/rndFile-1495707533222-1604636888.dat
25-5-17 10:18:53.451 | Indexer         | AsyncI/syncany | FINE : - /File: rndFile-1495707533222-1604636888.dat (checksum 2cc5f1093879b90283c960d184aa4c7c8c79c243)
25-5-17 10:18:53.456 | Indexer         | AsyncI/syncany | FINE :    * Found old file history c61c58bf391baf7b570ebdce81f2889654fd349d (by path: rndFile-1495707533222-1604636888.dat), appending new version.
25-5-17 10:18:53.457 | FileVersionComp | AsyncI/syncany | INFO :      - [CHANGED_CHECKSUM]: Local file DIFFERS from file version, expected CHECKSUM = 2cc5f1093879b90283c960d184aa4c7c8c79c243, but actual CHECKSUM = e362627babd24564e2979345ddb0077d54e80009, for file rndFile-1495707533222-1604636888.dat
25-5-17 10:18:53.457 | Indexer         | AsyncI/syncany | INFO :    * Added file version:    FileVersion [version=2, path=rndFile-1495707533222-1604636888.dat, type=FILE, status=CHANGED, size=5120, lastModified=Thu May 25 10:18:53 UTC 2017, linkTarget=null, checksum=2cc5f1093879b90283c960d184aa4c7c8c79c243, updated=Thu May 25 10:18:53 UTC 2017, posixPermissions=rw-rw-r--, dosAttributes=--a-]
25-5-17 10:18:53.457 | Indexer         | AsyncI/syncany | INFO :      based on file version: FileVersion [version=1, path=rndFile-1495707533222-1604636888.dat, type=FILE, status=NEW, size=5120, lastModified=Thu May 25 10:18:53 UTC 2017, linkTarget=null, checksum=e362627babd24564e2979345ddb0077d54e80009, updated=Thu May 25 10:18:53 UTC 2017, posixPermissions=rw-rw-r--, dosAttributes=--a-]
25-5-17 10:18:53.461 | Indexer         | AsyncI/syncany | FINE : - +File /tmp/syncanytest/syncany-170525101612005-85758-client-syncanyclient/rndFile-1495707533222-364151122.dat
25-5-17 10:18:53.461 | Indexer         | AsyncI/syncany | FINE : - +FileContent: /tmp/syncanytest/syncany-170525101612005-85758-client-syncanyclient/rndFile-1495707533222-364151122.dat
25-5-17 10:18:53.461 | Indexer         | AsyncI/syncany | FINE : - Chunk new: 018d854cc13b669eb4ab553dafb72ae701870501
25-5-17 10:18:53.462 | Indexer         | AsyncI/syncany | FINE : - Chunk > MultiChunk: 018d854cc13b669eb4ab553dafb72ae701870501 > 560c8e457e5e4f8f95340afd09b2f6ba485fb4c4
25-5-17 10:18:53.462 | Indexer         | AsyncI/syncany | FINE : - Chunk > FileContent: 018d854cc13b669eb4ab553dafb72ae701870501 > /tmp/syncanytest/syncany-170525101612005-85758-client-syncanyclient/rndFile-1495707533222-364151122.dat
25-5-17 10:18:53.462 | Indexer         | AsyncI/syncany | FINE : - /File: rndFile-1495707533222-364151122.dat (checksum 018d854cc13b669eb4ab553dafb72ae701870501)
25-5-17 10:18:53.463 | Indexer         | AsyncI/syncany | FINE :    * Found old file history 0376e5a3e6323147dc5fa3dc82b76c719e993853 (by path: rndFile-1495707533222-364151122.dat), appending new version.
25-5-17 10:18:53.463 | FileVersionComp | AsyncI/syncany | INFO :      - [CHANGED_CHECKSUM]: Local file DIFFERS from file version, expected CHECKSUM = 018d854cc13b669eb4ab553dafb72ae701870501, but actual CHECKSUM = c2e20095238f6f105a8f50e8f0d75f95c61f122d, for file rndFile-1495707533222-364151122.dat
25-5-17 10:18:53.463 | Indexer         | AsyncI/syncany | INFO :    * Added file version:    FileVersion [version=2, path=rndFile-1495707533222-364151122.dat, type=FILE, status=CHANGED, size=5120, lastModified=Thu May 25 10:18:53 UTC 2017, linkTarget=null, checksum=018d854cc13b669eb4ab553dafb72ae701870501, updated=Thu May 25 10:18:53 UTC 2017, posixPermissions=rw-rw-r--, dosAttributes=--a-]
25-5-17 10:18:53.464 | Indexer         | AsyncI/syncany | INFO :      based on file version: FileVersion [version=1, path=rndFile-1495707533222-364151122.dat, type=FILE, status=NEW, size=5120, lastModified=Thu May 25 10:18:53 UTC 2017, linkTarget=null, checksum=c2e20095238f6f105a8f50e8f0d75f95c61f122d, updated=Thu May 25 10:18:53 UTC 2017, posixPermissions=rw-rw-r--, dosAttributes=--a-]
25-5-17 10:18:53.464 | Indexer         | AsyncI/syncany | FINE : - +File /tmp/syncanytest/syncany-170525101612005-85758-client-syncanyclient/rndFile-1495707533222-605980650.dat
25-5-17 10:18:53.464 | Indexer         | AsyncI/syncany | FINE : - +FileContent: /tmp/syncanytest/syncany-170525101612005-85758-client-syncanyclient/rndFile-1495707533222-605980650.dat
25-5-17 10:18:53.464 | Indexer         | AsyncI/syncany | FINE : - Chunk new: de9d9ca7766f22dfce62cf7acb09a77fb8c4200e
25-5-17 10:18:53.465 | Indexer         | AsyncI/syncany | FINE : - Chunk > MultiChunk: de9d9ca7766f22dfce62cf7acb09a77fb8c4200e > 560c8e457e5e4f8f95340afd09b2f6ba485fb4c4
25-5-17 10:18:53.465 | Indexer         | AsyncI/syncany | FINE : - Chunk > FileContent: de9d9ca7766f22dfce62cf7acb09a77fb8c4200e > /tmp/syncanytest/syncany-170525101612005-85758-client-syncanyclient/rndFile-1495707533222-605980650.dat
25-5-17 10:18:53.465 | Indexer         | AsyncI/syncany | FINE : - /File: rndFile-1495707533222-605980650.dat (checksum de9d9ca7766f22dfce62cf7acb09a77fb8c4200e)
25-5-17 10:18:53.469 | Indexer         | AsyncI/syncany | FINE :    * Found old file history 09b24d2267592a9a3788b6095fac427df2acc1db (by path: rndFile-1495707533222-605980650.dat), appending new version.
25-5-17 10:18:53.472 | FileVersionComp | AsyncI/syncany | INFO :      - [CHANGED_CHECKSUM]: Local file DIFFERS from file version, expected CHECKSUM = de9d9ca7766f22dfce62cf7acb09a77fb8c4200e, but actual CHECKSUM = 6e69581f2a554c5d7872c861b3ec4aac99be394f, for file rndFile-1495707533222-605980650.dat
25-5-17 10:18:53.472 | Indexer         | AsyncI/syncany | INFO :    * Added file version:    FileVersion [version=2, path=rndFile-1495707533222-605980650.dat, type=FILE, status=CHANGED, size=5120, lastModified=Thu May 25 10:18:53 UTC 2017, linkTarget=null, checksum=de9d9ca7766f22dfce62cf7acb09a77fb8c4200e, updated=Thu May 25 10:18:53 UTC 2017, posixPermissions=rw-rw-r--, dosAttributes=--a-]
25-5-17 10:18:53.473 | Indexer         | AsyncI/syncany | INFO :      based on file version: FileVersion [version=1, path=rndFile-1495707533222-605980650.dat, type=FILE, status=NEW, size=5120, lastModified=Thu May 25 10:18:53 UTC 2017, linkTarget=null, checksum=6e69581f2a554c5d7872c861b3ec4aac99be394f, updated=Thu May 25 10:18:53 UTC 2017, posixPermissions=rw-rw-r--, dosAttributes=--a-]
25-5-17 10:18:53.473 | Indexer         | AsyncI/syncany | FINE : - +File /tmp/syncanytest/syncany-170525101612005-85758-client-syncanyclient/rndFile-1495707533222-866775795.dat
25-5-17 10:18:53.473 | Indexer         | AsyncI/syncany | FINE : - +FileContent: /tmp/syncanytest/syncany-170525101612005-85758-client-syncanyclient/rndFile-1495707533222-866775795.dat
25-5-17 10:18:53.474 | Indexer         | AsyncI/syncany | FINE : - Chunk new: 5c4d98299e6ed1e2ca1a574f03d660d6b9d46dd0
25-5-17 10:18:53.475 | Indexer         | AsyncI/syncany | FINE : - Chunk > MultiChunk: 5c4d98299e6ed1e2ca1a574f03d660d6b9d46dd0 > 560c8e457e5e4f8f95340afd09b2f6ba485fb4c4
25-5-17 10:18:53.476 | Indexer         | AsyncI/syncany | FINE : - Chunk > FileContent: 5c4d98299e6ed1e2ca1a574f03d660d6b9d46dd0 > /tmp/syncanytest/syncany-170525101612005-85758-client-syncanyclient/rndFile-1495707533222-866775795.dat
25-5-17 10:18:53.476 | Indexer         | AsyncI/syncany | FINE : - /File: rndFile-1495707533222-866775795.dat (checksum 5c4d98299e6ed1e2ca1a574f03d660d6b9d46dd0)
25-5-17 10:18:53.477 | Indexer         | AsyncI/syncany | FINE :    * Found old file history 3a162d5e01d8a58a0f56f043f28a7552a06644af (by path: rndFile-1495707533222-866775795.dat), appending new version.
25-5-17 10:18:53.478 | FileVersionComp | AsyncI/syncany | INFO :      - [CHANGED_CHECKSUM]: Local file DIFFERS from file version, expected CHECKSUM = 5c4d98299e6ed1e2ca1a574f03d660d6b9d46dd0, but actual CHECKSUM = 3e6dd0cc98acf6c1c89420412f2171f27ad4b3d2, for file rndFile-1495707533222-866775795.dat
25-5-17 10:18:53.478 | Indexer         | AsyncI/syncany | INFO :    * Added file version:    FileVersion [version=2, path=rndFile-1495707533222-866775795.dat, type=FILE, status=CHANGED, size=5120, lastModified=Thu May 25 10:18:53 UTC 2017, linkTarget=null, checksum=5c4d98299e6ed1e2ca1a574f03d660d6b9d46dd0, updated=Thu May 25 10:18:53 UTC 2017, posixPermissions=rw-rw-r--, dosAttributes=--a-]
25-5-17 10:18:53.478 | Indexer         | AsyncI/syncany | INFO :      based on file version: FileVersion [version=1, path=rndFile-1495707533222-866775795.dat, type=FILE, status=NEW, size=5120, lastModified=Thu May 25 10:18:53 UTC 2017, linkTarget=null, checksum=3e6dd0cc98acf6c1c89420412f2171f27ad4b3d2, updated=Thu May 25 10:18:53 UTC 2017, posixPermissions=rw-rw-r--, dosAttributes=--a-]
25-5-17 10:18:53.479 | Indexer         | AsyncI/syncany | FINE : - /MultiChunk 560c8e457e5e4f8f95340afd09b2f6ba485fb4c4
25-5-17 10:18:53.480 | InternalEventBu | AsyncI/syncany | INFO : Event bus 'LocalEventBus': Posting event UpIndexEndSyncExternalEvent (org.syncany.operations.daemon.messages.UpIndexEndSyncExternalEvent@b22c6c1) ...
25-5-17 10:18:53.480 | Indexer         | AsyncI/syncany | FINE : Processed new database version: DatabaseVersion [header=UnknownMachine/()/T=1495707533440, chunks=5, multiChunks=1, fileContents=5, fileHistories=5]
25-5-17 10:18:53.481 | UpOperation     | Test worker    | INFO : No DIRTY data found in database (no dirty databases); Nothing to do here.
25-5-17 10:18:53.481 | UpOperation     | Test worker    | INFO : Last vector clock was: (syncanyclientUtlRgSJlhXdMEYpRfMVG1)
25-5-17 10:18:53.481 | UpOperation     | Test worker    | INFO : Uploading new multichunks ...
25-5-17 10:18:53.482 | UpOperation     | Test worker    | INFO : - Uploading multichunk 560c8e457e5e4f8f95340afd09b2f6ba485fb4c4 from /tmp/syncanytest/syncany-170525101612005-85758-client-syncanyclient/.syncany/cache/multichunk-560c8e457e5e4f8f95340afd09b2f6ba485fb4c4 to RemoteFile[name=multichunk-560c8e457e5e4f8f95340afd09b2f6ba485fb4c4] ...
25-5-17 10:18:53.482 | RemoteTransacti | Test worker    | INFO : - Adding file to TX for UPLOAD: /tmp/syncanytest/syncany-170525101612005-85758-client-syncanyclient/.syncany/cache/multichunk-560c8e457e5e4f8f95340afd09b2f6ba485fb4c4 -> Temp. remote file: RemoteFile[name=temp-furXv-multichunk-560c8e457e5e4f8f95340afd09b2f6ba485fb4c4], final location: RemoteFile[name=multichunk-560c8e457e5e4f8f95340afd09b2f6ba485fb4c4]
25-5-17 10:18:53.482 | UpOperation     | Test worker    | INFO : Uploading database: DatabaseVersion [header=syncanyclientUtlRgSJlhXdMEYpRfMVG/(syncanyclientUtlRgSJlhXdMEYpRfMVG2)/T=1495707533481, chunks=5, multiChunks=1, fileContents=5, fileHistories=5]
25-5-17 10:18:53.482 | UpOperation     | Test worker    | INFO : Saving local delta database, version syncanyclientUtlRgSJlhXdMEYpRfMVG/(syncanyclientUtlRgSJlhXdMEYpRfMVG2)/T=1495707533481 to file /tmp/syncanytest/syncany-170525101612005-85758-client-syncanyclient/.syncany/cache/database-syncanyclientUtlRgSJlhXdMEYpRfMVG-0000000002 ... 
25-5-17 10:18:53.482 | UpOperation     | Test worker    | INFO : - Saving database to /tmp/syncanytest/syncany-170525101612005-85758-client-syncanyclient/.syncany/cache/database-syncanyclientUtlRgSJlhXdMEYpRfMVG-0000000002 ...
25-5-17 10:18:53.483 | UpOperation     | Test worker    | INFO : - Uploading local delta database file ...
25-5-17 10:18:53.483 | UpOperation     | Test worker    | INFO : - Uploading /tmp/syncanytest/syncany-170525101612005-85758-client-syncanyclient/.syncany/cache/database-syncanyclientUtlRgSJlhXdMEYpRfMVG-0000000002 to RemoteFile[name=database-syncanyclientUtlRgSJlhXdMEYpRfMVG-0000000002] ...
25-5-17 10:18:53.483 | RemoteTransacti | Test worker    | INFO : - Adding file to TX for UPLOAD: /tmp/syncanytest/syncany-170525101612005-85758-client-syncanyclient/.syncany/cache/database-syncanyclientUtlRgSJlhXdMEYpRfMVG-0000000002 -> Temp. remote file: RemoteFile[name=temp-dOEtW-database-syncanyclientUtlRgSJlhXdMEYpRfMVG-0000000002], final location: RemoteFile[name=database-syncanyclientUtlRgSJlhXdMEYpRfMVG-0000000002]
25-5-17 10:18:53.484 | UpOperation     | Test worker    | INFO : Adding shutdown hook (to allow resuming the upload) ...
25-5-17 10:18:53.484 | RemoteTransacti | Test worker    | INFO : Starting TX.commit() ...
25-5-17 10:18:53.485 | RemoteTransacti | Test worker    | INFO : Wrote transaction manifest to temporary file: /tmp/syncanytest/syncany-170525101612005-85758-client-syncanyclient/.syncany/cache/temp-transaction-3746967874506212304.tmp
25-5-17 10:18:53.485 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event UpUploadFileSyncExternalEvent (org.syncany.operations.daemon.messages.UpUploadFileSyncExternalEvent@3af475f2) ...
25-5-17 10:18:53.487 | RemoteTransacti | Test worker    | INFO : - Uploading remote transaction file RemoteFile[name=transaction-1473537b] ...
25-5-17 10:18:53.487 | RemoteTransacti | Test worker    | INFO : - Starting to upload data in commit.
25-5-17 10:18:53.488 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event UpUploadFileInTransactionSyncExternalEvent (org.syncany.operations.daemon.messages.UpUploadFileInTransactionSyncExternalEvent@1f6051bb) ...
25-5-17 10:18:53.488 | RemoteTransacti | Test worker    | INFO : - Uploading /tmp/syncanytest/syncany-170525101612005-85758-client-syncanyclient/.syncany/cache/multichunk-560c8e457e5e4f8f95340afd09b2f6ba485fb4c4 to temp. file RemoteFile[name=temp-furXv-multichunk-560c8e457e5e4f8f95340afd09b2f6ba485fb4c4] ...
25-5-17 10:18:53.488 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event UpUploadFileInTransactionSyncExternalEvent (org.syncany.operations.daemon.messages.UpUploadFileInTransactionSyncExternalEvent@689360fc) ...
25-5-17 10:18:53.488 | RemoteTransacti | Test worker    | INFO : - Uploading /tmp/syncanytest/syncany-170525101612005-85758-client-syncanyclient/.syncany/cache/database-syncanyclientUtlRgSJlhXdMEYpRfMVG-0000000002 to temp. file RemoteFile[name=temp-dOEtW-database-syncanyclientUtlRgSJlhXdMEYpRfMVG-0000000002] ...
25-5-17 10:18:53.489 | RemoteTransacti | Test worker    | INFO : - Moving temp. file RemoteFile[name=temp-furXv-multichunk-560c8e457e5e4f8f95340afd09b2f6ba485fb4c4] to final location RemoteFile[name=multichunk-560c8e457e5e4f8f95340afd09b2f6ba485fb4c4] ...
25-5-17 10:18:53.489 | RemoteTransacti | Test worker    | INFO : - Moving temp. file RemoteFile[name=temp-dOEtW-database-syncanyclientUtlRgSJlhXdMEYpRfMVG-0000000002] to final location RemoteFile[name=database-syncanyclientUtlRgSJlhXdMEYpRfMVG-0000000002] ...
25-5-17 10:18:53.489 | RemoteTransacti | Test worker    | INFO : - Deleting remote transaction file RemoteFile[name=transaction-1473537b] ...
25-5-17 10:18:53.490 | RemoteTransacti | Test worker    | INFO : END of TX.commmit(): Succesfully committed transaction.
25-5-17 10:18:53.495 | RemoteTransacti | Test worker    | INFO : END of TX.delTemp(): Sucessfully deleted final files.
25-5-17 10:18:53.495 | UpOperation     | Test worker    | INFO : Persisting local SQL database (new database version syncanyclientUtlRgSJlhXdMEYpRfMVG/(syncanyclientUtlRgSJlhXdMEYpRfMVG2)/T=1495707533481) ...
25-5-17 10:18:53.489 | InternalEventBu | AsyncI/syncany | INFO : Event bus 'LocalEventBus': Posting event UpIndexMidSyncExternalEvent (org.syncany.operations.daemon.messages.UpIndexMidSyncExternalEvent@26bd9050) ...
25-5-17 10:18:53.500 | AsyncIndexer    | AsyncI/syncany | INFO : Stopping indexing. Signal end of stream with empty databaseversion
25-5-17 10:18:53.503 | UpOperation     | Test worker    | INFO : Removing DIRTY database versions from database ...
25-5-17 10:18:53.510 | UpOperation     | Test worker    | INFO : Adding database version to result changes:DatabaseVersion [header=syncanyclientUtlRgSJlhXdMEYpRfMVG/(syncanyclientUtlRgSJlhXdMEYpRfMVG2)/T=1495707533481, chunks=5, multiChunks=1, fileContents=5, fileHistories=5]
25-5-17 10:18:53.511 | UpOperation     | Test worker    | INFO : Committing local database.
25-5-17 10:18:53.511 | UpOperation     | Test worker    | FINE : Waiting for new database version.
25-5-17 10:18:53.511 | UpOperation     | Test worker    | FINE : Took new database version: DatabaseVersion [header=UnknownMachine/()/T=1495707533500, chunks=0, multiChunks=0, fileContents=0, fileHistories=0]
25-5-17 10:18:53.511 | UpOperation     | Test worker    | INFO : Sync up done.
25-5-17 10:18:53.514 | ActionFileHandl | Test worker    | INFO : Finishing action for RemoteFile[name=action-up-syncanyclientUtlRgSJlhXdMEYpRfMVG-1495707533427] ...
25-5-17 10:18:53.519 | ActionFileHandl | Test worker    | INFO : Deleting action file: RemoteFile[name=action-up-syncanyclientUtlRgSJlhXdMEYpRfMVG-1495707533427]
25-5-17 10:18:53.519 | AbstractTransfe | Test worker    | INFO : Cleaning own old action files ...
25-5-17 10:18:53.519 | Cache           | Test worker    | INFO : Cache size okay (60 KB), no need to clean (keep size is 500 MB)
25-5-17 10:18:53.519 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event UpEndSyncExternalEvent (org.syncany.operations.daemon.messages.UpEndSyncExternalEvent@39b07336) ...
25-5-17 10:18:53.520 | org.syncany.plu | Test worker    | FINE : Validating required fields
25-5-17 10:18:53.520 | TransferManager | Test worker    | INFO : Building LocalTransferManager from config 'syncany-170525101612005-85758-client-syncanyclient' ...
25-5-17 10:18:53.520 | TransferManager | Test worker    | INFO : - With feature ReadAfterWriteConsistent
25-5-17 10:18:53.520 | TransferManager | Test worker    | INFO : - With feature Retriable
25-5-17 10:18:53.520 | TransferManager | Test worker    | FINE : - Checking if selected features supported ...
25-5-17 10:18:53.520 | TransferManager | Test worker    | FINE : - Checking for duplicate features ...
25-5-17 10:18:53.520 | TransferManager | Test worker    | FINE : - Checking required feature TransactionAware in LocalTransferManager ...
25-5-17 10:18:53.520 | TransferManager | Test worker    | FINE : - Checking required feature Retriable in LocalTransferManager ...
25-5-17 10:18:53.520 | TransferManager | Test worker    | INFO : - SKIPPING unsupported optional feature ReadAfterWriteConsistent
25-5-17 10:18:53.520 | TransferManager | Test worker    | FINE : - Wrapping TransferManager LocalTransferManager in RetriableFeatureTransferManager
25-5-17 10:18:53.520 | org.syncany.plu | Test worker    | FINE : Validating required fields
25-5-17 10:18:53.521 | TransferManager | Test worker    | INFO : Building LocalTransferManager from config 'syncany-170525101612005-85758-client-syncanyclient' ...
25-5-17 10:18:53.521 | TransferManager | Test worker    | INFO : - With feature ReadAfterWriteConsistent
25-5-17 10:18:53.521 | TransferManager | Test worker    | INFO : - With feature Retriable
25-5-17 10:18:53.521 | TransferManager | Test worker    | INFO : - With feature PathAware
25-5-17 10:18:53.521 | TransferManager | Test worker    | INFO : - With feature TransactionAware
25-5-17 10:18:53.521 | TransferManager | Test worker    | FINE : - Checking if selected features supported ...
25-5-17 10:18:53.521 | TransferManager | Test worker    | FINE : - Checking for duplicate features ...
25-5-17 10:18:53.521 | TransferManager | Test worker    | FINE : - Checking required feature TransactionAware in LocalTransferManager ...
25-5-17 10:18:53.521 | TransferManager | Test worker    | FINE : - Checking required feature Retriable in LocalTransferManager ...
25-5-17 10:18:53.521 | TransferManager | Test worker    | INFO : - SKIPPING unsupported optional feature ReadAfterWriteConsistent
25-5-17 10:18:53.521 | TransferManager | Test worker    | FINE : - Wrapping TransferManager LocalTransferManager in RetriableFeatureTransferManager
25-5-17 10:18:53.521 | TransferManager | Test worker    | INFO : - SKIPPING unsupported optional feature PathAware
25-5-17 10:18:53.521 | TransferManager | Test worker    | FINE : - Wrapping TransferManager RetriableFeatureTransferManager in TransactionAwareFeatureTransferManager
25-5-17 10:18:53.529 | DatabaseConnect | Test worker    | INFO : Found 12 tables.
25-5-17 10:18:53.529 | UpOperation     | Test worker    | INFO : 
25-5-17 10:18:53.529 | UpOperation     | Test worker    | INFO : Running 'Sync up' at client syncanyclientUtlRgSJlhXdMEYpRfMVG ...
25-5-17 10:18:53.529 | UpOperation     | Test worker    | INFO : --------------------------------------------
25-5-17 10:18:53.529 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event UpStartSyncExternalEvent (org.syncany.operations.daemon.messages.UpStartSyncExternalEvent@5827ae49) ...
25-5-17 10:18:53.529 | DatabaseConnect | Test worker    | INFO : Found 12 tables.
25-5-17 10:18:53.529 | StatusOperation | Test worker    | INFO : 
25-5-17 10:18:53.529 | StatusOperation | Test worker    | INFO : Running 'Status' at client syncanyclientUtlRgSJlhXdMEYpRfMVG ...
25-5-17 10:18:53.529 | StatusOperation | Test worker    | INFO : --------------------------------------------
25-5-17 10:18:53.529 | StatusOperation | Test worker    | INFO : Force checksum ENABLED.
25-5-17 10:18:53.529 | StatusOperation | Test worker    | INFO : Querying current file tree from database ...
25-5-17 10:18:53.529 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event StatusStartSyncExternalEvent (org.syncany.operations.daemon.messages.StatusStartSyncExternalEvent@36def743) ...
25-5-17 10:18:53.530 | StatusOperation | Test worker    | INFO : Analyzing local folder /tmp/syncanytest/syncany-170525101612005-85758-client-syncanyclient ...
25-5-17 10:18:53.530 | StatusOperation | Test worker    | FINE : - Ignoring file (syncany app-related): .syncany
25-5-17 10:18:53.531 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event StatusEndSyncExternalEvent (org.syncany.operations.daemon.messages.StatusEndSyncExternalEvent@3c1096e1) ...
25-5-17 10:18:53.531 | AbstractTransfe | Test worker    | INFO : Looking for other running remote operations ...
25-5-17 10:18:53.531 | UpOperation     | Test worker    | INFO : Force (--force-upload) is enabled, ignoring potential remote changes.
25-5-17 10:18:53.531 | ActionFileHandl | Test worker    | INFO : Starting action for RemoteFile[name=action-up-syncanyclientUtlRgSJlhXdMEYpRfMVG-1495707533521] ...
25-5-17 10:18:53.532 | ActionFileHandl | Test worker    | INFO : Uploading action file: RemoteFile[name=action-up-syncanyclientUtlRgSJlhXdMEYpRfMVG-1495707533521]
25-5-17 10:18:53.532 | ActionFileHandl | Test worker    | INFO : Scheduling action renewal task for every 2 minutes, for RemoteFile[name=action-up-syncanyclientUtlRgSJlhXdMEYpRfMVG-1495707533521] ...
25-5-17 10:18:53.533 | DatabaseConnect | Test worker    | INFO : Found 12 tables.
25-5-17 10:18:53.541 | TransactionAwar | Test worker    | INFO : Done rolling back previous transactions.
25-5-17 10:18:53.542 | AsyncIndexer    | AsyncI/syncany | INFO : Starting Indexing.
25-5-17 10:18:53.543 | Indexer         | AsyncI/syncany | FINE : - Looking for deleted files ...
25-5-17 10:18:53.544 | Indexer         | AsyncI/syncany | FINE :   + Deleted: Adding DELETED version: FileVersion [version=3, path=rndFile-1495707533222-1287253930.dat, type=FILE, status=DELETED, size=5120, lastModified=Thu May 25 10:18:53 UTC 2017, linkTarget=null, checksum=24081fe2871d984927e397822beee0ff626f9559, updated=Thu May 25 10:18:53 UTC 2017, posixPermissions=rw-rw-r--, dosAttributes=--a-]
25-5-17 10:18:53.544 | Indexer         | AsyncI/syncany | FINE :                            based on: FileVersion [version=2, path=rndFile-1495707533222-1287253930.dat, type=FILE, status=CHANGED, size=5120, lastModified=Thu May 25 10:18:53 UTC 2017, linkTarget=null, checksum=24081fe2871d984927e397822beee0ff626f9559, updated=Thu May 25 10:18:53 UTC 2017, posixPermissions=rw-rw-r--, dosAttributes=--a-]
25-5-17 10:18:53.559 | Indexer         | AsyncI/syncany | FINE :   + Deleted: Adding DELETED version: FileVersion [version=3, path=rndFile-1495707533222-1604636888.dat, type=FILE, status=DELETED, size=5120, lastModified=Thu May 25 10:18:53 UTC 2017, linkTarget=null, checksum=2cc5f1093879b90283c960d184aa4c7c8c79c243, updated=Thu May 25 10:18:53 UTC 2017, posixPermissions=rw-rw-r--, dosAttributes=--a-]
25-5-17 10:18:53.560 | Indexer         | AsyncI/syncany | FINE :                            based on: FileVersion [version=2, path=rndFile-1495707533222-1604636888.dat, type=FILE, status=CHANGED, size=5120, lastModified=Thu May 25 10:18:53 UTC 2017, linkTarget=null, checksum=2cc5f1093879b90283c960d184aa4c7c8c79c243, updated=Thu May 25 10:18:53 UTC 2017, posixPermissions=rw-rw-r--, dosAttributes=--a-]
25-5-17 10:18:53.563 | Indexer         | AsyncI/syncany | FINE :   + Deleted: Adding DELETED version: FileVersion [version=3, path=rndFile-1495707533222-364151122.dat, type=FILE, status=DELETED, size=5120, lastModified=Thu May 25 10:18:53 UTC 2017, linkTarget=null, checksum=018d854cc13b669eb4ab553dafb72ae701870501, updated=Thu May 25 10:18:53 UTC 2017, posixPermissions=rw-rw-r--, dosAttributes=--a-]
25-5-17 10:18:53.565 | Indexer         | AsyncI/syncany | FINE :                            based on: FileVersion [version=2, path=rndFile-1495707533222-364151122.dat, type=FILE, status=CHANGED, size=5120, lastModified=Thu May 25 10:18:53 UTC 2017, linkTarget=null, checksum=018d854cc13b669eb4ab553dafb72ae701870501, updated=Thu May 25 10:18:53 UTC 2017, posixPermissions=rw-rw-r--, dosAttributes=--a-]
25-5-17 10:18:53.569 | Indexer         | AsyncI/syncany | FINE :   + Deleted: Adding DELETED version: FileVersion [version=3, path=rndFile-1495707533222-605980650.dat, type=FILE, status=DELETED, size=5120, lastModified=Thu May 25 10:18:53 UTC 2017, linkTarget=null, checksum=de9d9ca7766f22dfce62cf7acb09a77fb8c4200e, updated=Thu May 25 10:18:53 UTC 2017, posixPermissions=rw-rw-r--, dosAttributes=--a-]
25-5-17 10:18:53.570 | Indexer         | AsyncI/syncany | FINE :                            based on: FileVersion [version=2, path=rndFile-1495707533222-605980650.dat, type=FILE, status=CHANGED, size=5120, lastModified=Thu May 25 10:18:53 UTC 2017, linkTarget=null, checksum=de9d9ca7766f22dfce62cf7acb09a77fb8c4200e, updated=Thu May 25 10:18:53 UTC 2017, posixPermissions=rw-rw-r--, dosAttributes=--a-]
25-5-17 10:18:53.575 | Indexer         | AsyncI/syncany | FINE :   + Deleted: Adding DELETED version: FileVersion [version=3, path=rndFile-1495707533222-866775795.dat, type=FILE, status=DELETED, size=5120, lastModified=Thu May 25 10:18:53 UTC 2017, linkTarget=null, checksum=5c4d98299e6ed1e2ca1a574f03d660d6b9d46dd0, updated=Thu May 25 10:18:53 UTC 2017, posixPermissions=rw-rw-r--, dosAttributes=--a-]
25-5-17 10:18:53.578 | Indexer         | AsyncI/syncany | FINE :                            based on: FileVersion [version=2, path=rndFile-1495707533222-866775795.dat, type=FILE, status=CHANGED, size=5120, lastModified=Thu May 25 10:18:53 UTC 2017, linkTarget=null, checksum=5c4d98299e6ed1e2ca1a574f03d660d6b9d46dd0, updated=Thu May 25 10:18:53 UTC 2017, posixPermissions=rw-rw-r--, dosAttributes=--a-]
25-5-17 10:18:53.579 | Indexer         | AsyncI/syncany | FINE : Added database version with only deletions: DatabaseVersion [header=UnknownMachine/()/T=1495707533543, chunks=0, multiChunks=0, fileContents=0, fileHistories=5]
25-5-17 10:18:53.579 | UpOperation     | Test worker    | INFO : No DIRTY data found in database (no dirty databases); Nothing to do here.
25-5-17 10:18:53.580 | UpOperation     | Test worker    | INFO : Last vector clock was: (syncanyclientUtlRgSJlhXdMEYpRfMVG2)
25-5-17 10:18:53.580 | UpOperation     | Test worker    | INFO : Uploading new multichunks ...
25-5-17 10:18:53.580 | UpOperation     | Test worker    | INFO : Uploading database: DatabaseVersion [header=syncanyclientUtlRgSJlhXdMEYpRfMVG/(syncanyclientUtlRgSJlhXdMEYpRfMVG3)/T=1495707533580, chunks=0, multiChunks=0, fileContents=0, fileHistories=5]
25-5-17 10:18:53.580 | UpOperation     | Test worker    | INFO : Saving local delta database, version syncanyclientUtlRgSJlhXdMEYpRfMVG/(syncanyclientUtlRgSJlhXdMEYpRfMVG3)/T=1495707533580 to file /tmp/syncanytest/syncany-170525101612005-85758-client-syncanyclient/.syncany/cache/database-syncanyclientUtlRgSJlhXdMEYpRfMVG-0000000003 ... 
25-5-17 10:18:53.580 | UpOperation     | Test worker    | INFO : - Saving database to /tmp/syncanytest/syncany-170525101612005-85758-client-syncanyclient/.syncany/cache/database-syncanyclientUtlRgSJlhXdMEYpRfMVG-0000000003 ...
25-5-17 10:18:53.581 | UpOperation     | Test worker    | INFO : - Uploading local delta database file ...
25-5-17 10:18:53.585 | UpOperation     | Test worker    | INFO : - Uploading /tmp/syncanytest/syncany-170525101612005-85758-client-syncanyclient/.syncany/cache/database-syncanyclientUtlRgSJlhXdMEYpRfMVG-0000000003 to RemoteFile[name=database-syncanyclientUtlRgSJlhXdMEYpRfMVG-0000000003] ...
25-5-17 10:18:53.586 | RemoteTransacti | Test worker    | INFO : - Adding file to TX for UPLOAD: /tmp/syncanytest/syncany-170525101612005-85758-client-syncanyclient/.syncany/cache/database-syncanyclientUtlRgSJlhXdMEYpRfMVG-0000000003 -> Temp. remote file: RemoteFile[name=temp-tFvsu-database-syncanyclientUtlRgSJlhXdMEYpRfMVG-0000000003], final location: RemoteFile[name=database-syncanyclientUtlRgSJlhXdMEYpRfMVG-0000000003]
25-5-17 10:18:53.586 | UpOperation     | Test worker    | INFO : Adding shutdown hook (to allow resuming the upload) ...
25-5-17 10:18:53.588 | RemoteTransacti | Test worker    | INFO : Starting TX.commit() ...
25-5-17 10:18:53.600 | RemoteTransacti | Test worker    | INFO : Wrote transaction manifest to temporary file: /tmp/syncanytest/syncany-170525101612005-85758-client-syncanyclient/.syncany/cache/temp-transaction-7248123302416723942.tmp
25-5-17 10:18:53.601 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event UpUploadFileSyncExternalEvent (org.syncany.operations.daemon.messages.UpUploadFileSyncExternalEvent@3439871) ...
25-5-17 10:18:53.601 | RemoteTransacti | Test worker    | INFO : - Uploading remote transaction file RemoteFile[name=transaction-2027ddf0] ...
25-5-17 10:18:53.606 | RemoteTransacti | Test worker    | INFO : - Starting to upload data in commit.
25-5-17 10:18:53.606 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event UpUploadFileInTransactionSyncExternalEvent (org.syncany.operations.daemon.messages.UpUploadFileInTransactionSyncExternalEvent@58c22bb0) ...
25-5-17 10:18:53.606 | RemoteTransacti | Test worker    | INFO : - Uploading /tmp/syncanytest/syncany-170525101612005-85758-client-syncanyclient/.syncany/cache/database-syncanyclientUtlRgSJlhXdMEYpRfMVG-0000000003 to temp. file RemoteFile[name=temp-tFvsu-database-syncanyclientUtlRgSJlhXdMEYpRfMVG-0000000003] ...
25-5-17 10:18:53.607 | RemoteTransacti | Test worker    | INFO : - Moving temp. file RemoteFile[name=temp-tFvsu-database-syncanyclientUtlRgSJlhXdMEYpRfMVG-0000000003] to final location RemoteFile[name=database-syncanyclientUtlRgSJlhXdMEYpRfMVG-0000000003] ...
25-5-17 10:18:53.607 | RemoteTransacti | Test worker    | INFO : - Deleting remote transaction file RemoteFile[name=transaction-2027ddf0] ...
25-5-17 10:18:53.607 | RemoteTransacti | Test worker    | INFO : END of TX.commmit(): Succesfully committed transaction.
25-5-17 10:18:53.607 | RemoteTransacti | Test worker    | INFO : END of TX.delTemp(): Sucessfully deleted final files.
25-5-17 10:18:53.607 | UpOperation     | Test worker    | INFO : Persisting local SQL database (new database version syncanyclientUtlRgSJlhXdMEYpRfMVG/(syncanyclientUtlRgSJlhXdMEYpRfMVG3)/T=1495707533580) ...
25-5-17 10:18:53.609 | UpOperation     | Test worker    | INFO : Removing DIRTY database versions from database ...
25-5-17 10:18:53.641 | AsyncIndexer    | AsyncI/syncany | INFO : Stopping indexing. Signal end of stream with empty databaseversion
25-5-17 10:18:53.651 | UpOperation     | Test worker    | INFO : Adding database version to result changes:DatabaseVersion [header=syncanyclientUtlRgSJlhXdMEYpRfMVG/(syncanyclientUtlRgSJlhXdMEYpRfMVG3)/T=1495707533580, chunks=0, multiChunks=0, fileContents=0, fileHistories=5]
25-5-17 10:18:53.652 | UpOperation     | Test worker    | INFO : Committing local database.
25-5-17 10:18:53.652 | UpOperation     | Test worker    | FINE : Waiting for new database version.
25-5-17 10:18:53.652 | UpOperation     | Test worker    | FINE : Took new database version: DatabaseVersion [header=UnknownMachine/()/T=1495707533641, chunks=0, multiChunks=0, fileContents=0, fileHistories=0]
25-5-17 10:18:53.653 | UpOperation     | Test worker    | INFO : Sync up done.
25-5-17 10:18:53.657 | ActionFileHandl | Test worker    | INFO : Finishing action for RemoteFile[name=action-up-syncanyclientUtlRgSJlhXdMEYpRfMVG-1495707533521] ...
25-5-17 10:18:53.666 | ActionFileHandl | Test worker    | INFO : Deleting action file: RemoteFile[name=action-up-syncanyclientUtlRgSJlhXdMEYpRfMVG-1495707533521]
25-5-17 10:18:53.666 | AbstractTransfe | Test worker    | INFO : Cleaning own old action files ...
25-5-17 10:18:53.666 | Cache           | Test worker    | INFO : Cache size okay (62 KB), no need to clean (keep size is 500 MB)
25-5-17 10:18:53.666 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event UpEndSyncExternalEvent (org.syncany.operations.daemon.messages.UpEndSyncExternalEvent@60d489a6) ...
25-5-17 10:18:53.672 | DatabaseConnect | Test worker    | INFO : Found 12 tables.
25-5-17 10:18:53.672 | LogOperation    | Test worker    | INFO : 
25-5-17 10:18:53.672 | LogOperation    | Test worker    | INFO : Running 'Log' at client syncanyclientUtlRgSJlhXdMEYpRfMVG ...
25-5-17 10:18:53.672 | LogOperation    | Test worker    | INFO : --------------------------------------------