Class org.syncany.tests.integration.scenarios.FailedSplitSyncUpScenarioTest

1

tests

0

failures

0

ignored

6.431s

duration

100%

successful

Tests

Test Duration Result
testUpFailsOnFirstTransaction 6.431s passed

Standard error

27-4-17 11:01:35.254 | org.syncany.plu | Test worker    | FINE : Validating required fields
27-4-17 11:01:35.254 | UnreliableLocal | Test worker    | INFO : Operation successful:     abs=1 rel=1 op=init init
27-4-17 11:01:35.255 | UnreliableLocal | Test worker    | INFO : Operation successful:     abs=2 rel=1 op=connect connect
27-4-17 11:01:35.255 | LocalTransferMa | Test worker    | INFO : testTargetExists: Target exists.
27-4-17 11:01:35.283 | org.syncany.plu | Test worker    | FINE : Validating required fields
27-4-17 11:01:35.283 | TransferManager | Test worker    | INFO : Building UnreliableLocalTransferManager from config 'syncany-170427110008054-78157-client-A' ...
27-4-17 11:01:35.284 | TransferManager | Test worker    | INFO : - With feature ReadAfterWriteConsistent
27-4-17 11:01:35.284 | TransferManager | Test worker    | INFO : - With feature Retriable
27-4-17 11:01:35.284 | TransferManager | Test worker    | FINE : - Checking if selected features supported ...
27-4-17 11:01:35.284 | TransferManager | Test worker    | FINE : - Checking for duplicate features ...
27-4-17 11:01:35.284 | TransferManager | Test worker    | FINE : - Checking required feature TransactionAware in UnreliableLocalTransferManager ...
27-4-17 11:01:35.284 | TransferManager | Test worker    | FINE : - Checking required feature Retriable in UnreliableLocalTransferManager ...
27-4-17 11:01:35.284 | TransferManager | Test worker    | INFO : - SKIPPING unsupported optional feature ReadAfterWriteConsistent
27-4-17 11:01:35.284 | TransferManager | Test worker    | FINE : - Wrapping TransferManager UnreliableLocalTransferManager in RetriableFeatureTransferManager
27-4-17 11:01:35.284 | org.syncany.plu | Test worker    | FINE : Validating required fields
27-4-17 11:01:35.284 | TransferManager | Test worker    | INFO : Building UnreliableLocalTransferManager from config 'syncany-170427110008054-78157-client-A' ...
27-4-17 11:01:35.284 | TransferManager | Test worker    | INFO : - With feature ReadAfterWriteConsistent
27-4-17 11:01:35.284 | TransferManager | Test worker    | INFO : - With feature Retriable
27-4-17 11:01:35.284 | TransferManager | Test worker    | INFO : - With feature PathAware
27-4-17 11:01:35.284 | TransferManager | Test worker    | INFO : - With feature TransactionAware
27-4-17 11:01:35.284 | TransferManager | Test worker    | FINE : - Checking if selected features supported ...
27-4-17 11:01:35.284 | TransferManager | Test worker    | FINE : - Checking for duplicate features ...
27-4-17 11:01:35.284 | TransferManager | Test worker    | FINE : - Checking required feature TransactionAware in UnreliableLocalTransferManager ...
27-4-17 11:01:35.284 | TransferManager | Test worker    | FINE : - Checking required feature Retriable in UnreliableLocalTransferManager ...
27-4-17 11:01:35.284 | TransferManager | Test worker    | INFO : - SKIPPING unsupported optional feature ReadAfterWriteConsistent
27-4-17 11:01:35.284 | TransferManager | Test worker    | FINE : - Wrapping TransferManager UnreliableLocalTransferManager in RetriableFeatureTransferManager
27-4-17 11:01:35.284 | TransferManager | Test worker    | INFO : - SKIPPING unsupported optional feature PathAware
27-4-17 11:01:35.284 | TransferManager | Test worker    | FINE : - Wrapping TransferManager RetriableFeatureTransferManager in TransactionAwareFeatureTransferManager
27-4-17 11:01:35.294 | hsqldb.db.HSQLD | Test worker    | INFO : Checkpoint start
27-4-17 11:01:35.294 | hsqldb.db.HSQLD | Test worker    | INFO : checkpointClose start
27-4-17 11:01:35.301 | hsqldb.db.HSQLD | Test worker    | FINE : log close size: 0
27-4-17 11:01:35.304 | hsqldb.db.HSQLD | Test worker    | INFO : checkpointClose end
27-4-17 11:01:35.304 | hsqldb.db.HSQLD | Test worker    | INFO : Checkpoint end - txts: 1
27-4-17 11:01:35.310 | DatabaseConnect | Test worker    | INFO : Found 0 tables.
27-4-17 11:01:35.310 | DatabaseConnect | Test worker    | INFO : Database has no tables. Creating tables from script.create.all.sql
27-4-17 11:01:35.310 | SqlRunner       | Test worker    | INFO : SQL (comment): -- Tables
27-4-17 11:01:35.310 | 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) ); 
27-4-17 11:01:35.312 | 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 ); 
27-4-17 11:01:35.315 | 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 ); 
27-4-17 11:01:35.317 | 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 ); 
27-4-17 11:01:35.319 | 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 ); 
27-4-17 11:01:35.321 | 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 ); 
27-4-17 11:01:35.323 | 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 ); 
27-4-17 11:01:35.325 | 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 ); 
27-4-17 11:01:35.328 | 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 ); 
27-4-17 11:01:35.330 | 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) ); 
27-4-17 11:01:35.332 | 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) ); 
27-4-17 11:01:35.334 | 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) ); 
27-4-17 11:01:35.336 | SqlRunner       | Test worker    | INFO : SQL (comment): -- Non-primary indices
27-4-17 11:01:35.336 | SqlRunner       | Test worker    | INFO : SQL:   CREATE INDEX idx_databaseversion_status ON databaseversion (status); 
27-4-17 11:01:35.337 | hsqldb.db.HSQLD | Test worker    | INFO : dataFileCache open start
27-4-17 11:01:35.339 | hsqldb.db.HSQLD | Test worker    | INFO : dataFileCache open end
27-4-17 11:01:35.342 | SqlRunner       | Test worker    | INFO : SQL: CREATE INDEX idx_databaseversion_vectorclock_serialized ON databaseversion (vectorclock_serialized); 
27-4-17 11:01:35.344 | SqlRunner       | Test worker    | INFO : SQL: CREATE INDEX idx_fileversion_path ON fileversion (path); 
27-4-17 11:01:35.346 | SqlRunner       | Test worker    | INFO : SQL: CREATE INDEX idx_fileversion_status ON fileversion (status); 
27-4-17 11:01:35.348 | SqlRunner       | Test worker    | INFO : SQL: CREATE INDEX idx_fileversion_filecontent_checksum ON fileversion (filecontent_checksum); 
27-4-17 11:01:35.350 | SqlRunner       | Test worker    | INFO : SQL (comment): -- Views
27-4-17 11:01:35.350 | 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'; 
27-4-17 11:01:35.353 | 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'; 
27-4-17 11:01:35.355 | 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; 
27-4-17 11:01:35.358 | 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'; 
27-4-17 11:01:35.360 | SqlRunner       | Test worker    | INFO : SQL (comment): -- Full Views
27-4-17 11:01:35.360 | 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; 
27-4-17 11:01:35.363 | 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; 
27-4-17 11:01:35.365 | SqlRunner       | Test worker    | INFO : SQL (comment): -- Functions
27-4-17 11:01:35.365 | SqlRunner       | Test worker    | INFO : SQL (new delimiter): end;
27-4-17 11:01:35.366 | 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; 
27-4-17 11:01:35.368 | UpOperation     | Test worker    | INFO : 
27-4-17 11:01:35.368 | UpOperation     | Test worker    | INFO : Running 'Sync up' at client A ...
27-4-17 11:01:35.368 | UpOperation     | Test worker    | INFO : --------------------------------------------
27-4-17 11:01:35.368 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event UpStartSyncExternalEvent (org.syncany.operations.daemon.messages.UpStartSyncExternalEvent@8a93a63) ...
27-4-17 11:01:35.369 | DatabaseConnect | Test worker    | INFO : Found 12 tables.
27-4-17 11:01:35.369 | StatusOperation | Test worker    | INFO : 
27-4-17 11:01:35.369 | StatusOperation | Test worker    | INFO : Running 'Status' at client A ...
27-4-17 11:01:35.369 | StatusOperation | Test worker    | INFO : --------------------------------------------
27-4-17 11:01:35.369 | StatusOperation | Test worker    | INFO : Querying current file tree from database ...
27-4-17 11:01:35.369 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event StatusStartSyncExternalEvent (org.syncany.operations.daemon.messages.StatusStartSyncExternalEvent@35f9f78b) ...
27-4-17 11:01:35.369 | StatusOperation | Test worker    | INFO : Analyzing local folder /tmp/syncanytest/syncany-170427110008054-78157-client-A ...
27-4-17 11:01:35.370 | StatusOperation | Test worker    | FINE : - Ignoring file (syncany app-related): .syncany
27-4-17 11:01:35.370 | StatusOperation | Test worker    | FINE : - New file: file3
27-4-17 11:01:35.370 | StatusOperation | Test worker    | FINE : - New file: file1
27-4-17 11:01:35.370 | StatusOperation | Test worker    | FINE : - New file: file2
27-4-17 11:01:35.370 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event StatusEndSyncExternalEvent (org.syncany.operations.daemon.messages.StatusEndSyncExternalEvent@31a8ed6f) ...
27-4-17 11:01:35.370 | AbstractTransfe | Test worker    | INFO : Looking for other running remote operations ...
27-4-17 11:01:35.370 | UnreliableLocal | Test worker    | INFO : Operation successful:     abs=3 rel=1 op=list list(ActionRemoteFile)
27-4-17 11:01:35.370 | UnreliableLocal | Test worker    | INFO : Operation successful:     abs=4 rel=2 op=connect connect
27-4-17 11:01:35.370 | UnreliableLocal | Test worker    | INFO : Operation successful:     abs=5 rel=2 op=list list(TransactionRemoteFile)
27-4-17 11:01:35.370 | UnreliableLocal | Test worker    | INFO : Operation successful:     abs=6 rel=3 op=connect connect
27-4-17 11:01:35.371 | DatabaseConnect | Test worker    | INFO : Found 12 tables.
27-4-17 11:01:35.371 | LsRemoteOperati | Test worker    | INFO : 
27-4-17 11:01:35.371 | LsRemoteOperati | Test worker    | INFO : Running 'Remote Status' at client A ...
27-4-17 11:01:35.371 | LsRemoteOperati | Test worker    | INFO : --------------------------------------------
27-4-17 11:01:35.371 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event LsRemoteStartSyncExternalEvent (org.syncany.operations.daemon.messages.LsRemoteStartSyncExternalEvent@6abeaf7c) ...
27-4-17 11:01:35.371 | LsRemoteOperati | Test worker    | INFO : Retrieving remote database list.
27-4-17 11:01:35.371 | UnreliableLocal | Test worker    | INFO : Operation successful:     abs=7 rel=3 op=list list(DatabaseRemoteFile)
27-4-17 11:01:35.371 | UnreliableLocal | Test worker    | INFO : Operation successful:     abs=8 rel=4 op=connect connect
27-4-17 11:01:35.371 | UnreliableLocal | Test worker    | INFO : Operation successful:     abs=9 rel=4 op=list list(TransactionRemoteFile)
27-4-17 11:01:35.371 | UnreliableLocal | Test worker    | INFO : Operation successful:     abs=10 rel=5 op=connect connect
27-4-17 11:01:35.371 | UnreliableLocal | Test worker    | INFO : Operation successful:     abs=11 rel=1 op=disconnect disconnect
27-4-17 11:01:35.372 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event LsRemoteEndSyncExternalEvent (org.syncany.operations.daemon.messages.LsRemoteEndSyncExternalEvent@62b2f1b7) ...
27-4-17 11:01:35.372 | UpOperation     | Test worker    | INFO : No remote changes, ready to upload.
27-4-17 11:01:35.372 | ActionFileHandl | Test worker    | INFO : Starting action for RemoteFile[name=action-up-A-1493290895284] ...
27-4-17 11:01:35.372 | ActionFileHandl | Test worker    | INFO : Uploading action file: RemoteFile[name=action-up-A-1493290895284]
27-4-17 11:01:35.372 | UnreliableLocal | Test worker    | INFO : Operation successful:     abs=12 rel=1 op=upload upload(/tmp/syncany-action-2131544417762109709.tmp, action-up-A-1493290895284)
27-4-17 11:01:35.372 | UnreliableLocal | Test worker    | INFO : Operation successful:     abs=13 rel=6 op=connect connect
27-4-17 11:01:35.372 | ActionFileHandl | Test worker    | INFO : Scheduling action renewal task for every 2 minutes, for RemoteFile[name=action-up-A-1493290895284] ...
27-4-17 11:01:35.373 | DatabaseConnect | Test worker    | INFO : Found 12 tables.
27-4-17 11:01:35.373 | UnreliableLocal | Test worker    | INFO : Operation successful:     abs=14 rel=5 op=list list(TransactionRemoteFile)
27-4-17 11:01:35.373 | UnreliableLocal | Test worker    | INFO : Operation successful:     abs=15 rel=7 op=connect connect
27-4-17 11:01:35.373 | TransactionAwar | Test worker    | INFO : Done rolling back previous transactions.
27-4-17 11:01:35.373 | AsyncIndexer    | AsyncI/syncany | INFO : Starting Indexing.
27-4-17 11:01:35.373 | InternalEventBu | AsyncI/syncany | INFO : Event bus 'LocalEventBus': Posting event UpIndexStartSyncExternalEvent (org.syncany.operations.daemon.messages.UpIndexStartSyncExternalEvent@68ff32da) ...
27-4-17 11:01:35.373 | Indexer         | AsyncI/syncany | FINE : - Looking for deleted files ...
27-4-17 11:01:35.373 | Indexer         | AsyncI/syncany | FINE : - +File /tmp/syncanytest/syncany-170427110008054-78157-client-A/file1
27-4-17 11:01:35.373 | Indexer         | AsyncI/syncany | FINE : - +FileContent: /tmp/syncanytest/syncany-170427110008054-78157-client-A/file1
27-4-17 11:01:35.381 | Indexer         | AsyncI/syncany | FINE : - Chunk new: 07d28f05d4500026fcefaffbf7bd5933b235e35c
27-4-17 11:01:35.381 | Indexer         | AsyncI/syncany | FINE : - +MultiChunk 8f856026b7c2969cae8b823d29be9ff687c81dcd
27-4-17 11:01:35.386 | Indexer         | AsyncI/syncany | FINE : - Chunk > MultiChunk: 07d28f05d4500026fcefaffbf7bd5933b235e35c > 8f856026b7c2969cae8b823d29be9ff687c81dcd
27-4-17 11:01:35.386 | Indexer         | AsyncI/syncany | FINE : - Chunk > FileContent: 07d28f05d4500026fcefaffbf7bd5933b235e35c > /tmp/syncanytest/syncany-170427110008054-78157-client-A/file1
27-4-17 11:01:35.391 | Indexer         | AsyncI/syncany | FINE : - Chunk new: 3d053370ac6086ab9fdf95576f2940168b3a8db8
27-4-17 11:01:35.395 | Indexer         | AsyncI/syncany | FINE : - Chunk > MultiChunk: 3d053370ac6086ab9fdf95576f2940168b3a8db8 > 8f856026b7c2969cae8b823d29be9ff687c81dcd
27-4-17 11:01:35.395 | Indexer         | AsyncI/syncany | FINE : - Chunk > FileContent: 3d053370ac6086ab9fdf95576f2940168b3a8db8 > /tmp/syncanytest/syncany-170427110008054-78157-client-A/file1
27-4-17 11:01:35.400 | Indexer         | AsyncI/syncany | FINE : - Chunk new: 5a1477d696d7420d6013ccb0ace4938558111f40
27-4-17 11:01:35.405 | Indexer         | AsyncI/syncany | FINE : - Chunk > MultiChunk: 5a1477d696d7420d6013ccb0ace4938558111f40 > 8f856026b7c2969cae8b823d29be9ff687c81dcd
27-4-17 11:01:35.405 | Indexer         | AsyncI/syncany | FINE : - Chunk > FileContent: 5a1477d696d7420d6013ccb0ace4938558111f40 > /tmp/syncanytest/syncany-170427110008054-78157-client-A/file1
27-4-17 11:01:35.410 | Indexer         | AsyncI/syncany | FINE : - Chunk new: ad41caa3f218be01c91d760da5e9fe3a8e774293
27-4-17 11:01:35.414 | Indexer         | AsyncI/syncany | FINE : - Chunk > MultiChunk: ad41caa3f218be01c91d760da5e9fe3a8e774293 > 8f856026b7c2969cae8b823d29be9ff687c81dcd
27-4-17 11:01:35.414 | Indexer         | AsyncI/syncany | FINE : - Chunk > FileContent: ad41caa3f218be01c91d760da5e9fe3a8e774293 > /tmp/syncanytest/syncany-170427110008054-78157-client-A/file1
27-4-17 11:01:35.420 | Indexer         | AsyncI/syncany | FINE : - Chunk new: 1b72c16c2fea4b3ca60bca9bfdda2f11a8b5401e
27-4-17 11:01:35.424 | Indexer         | AsyncI/syncany | FINE : - Chunk > MultiChunk: 1b72c16c2fea4b3ca60bca9bfdda2f11a8b5401e > 8f856026b7c2969cae8b823d29be9ff687c81dcd
27-4-17 11:01:35.424 | Indexer         | AsyncI/syncany | FINE : - Chunk > FileContent: 1b72c16c2fea4b3ca60bca9bfdda2f11a8b5401e > /tmp/syncanytest/syncany-170427110008054-78157-client-A/file1
27-4-17 11:01:35.429 | Indexer         | AsyncI/syncany | FINE : - Chunk new: 819cebecef89818fd9632ea619fd8519b0523091
27-4-17 11:01:35.434 | Indexer         | AsyncI/syncany | FINE : - Chunk > MultiChunk: 819cebecef89818fd9632ea619fd8519b0523091 > 8f856026b7c2969cae8b823d29be9ff687c81dcd
27-4-17 11:01:35.434 | Indexer         | AsyncI/syncany | FINE : - Chunk > FileContent: 819cebecef89818fd9632ea619fd8519b0523091 > /tmp/syncanytest/syncany-170427110008054-78157-client-A/file1
27-4-17 11:01:35.439 | Indexer         | AsyncI/syncany | FINE : - Chunk new: d9f0e3c5d7f906f0aa7ffce9f3566b1c822a7442
27-4-17 11:01:35.443 | Indexer         | AsyncI/syncany | FINE : - Chunk > MultiChunk: d9f0e3c5d7f906f0aa7ffce9f3566b1c822a7442 > 8f856026b7c2969cae8b823d29be9ff687c81dcd
27-4-17 11:01:35.443 | Indexer         | AsyncI/syncany | FINE : - Chunk > FileContent: d9f0e3c5d7f906f0aa7ffce9f3566b1c822a7442 > /tmp/syncanytest/syncany-170427110008054-78157-client-A/file1
27-4-17 11:01:35.448 | Indexer         | AsyncI/syncany | FINE : - Chunk new: 6a7adaf09b92b450a9b83111c88b060183de787f
27-4-17 11:01:35.453 | Indexer         | AsyncI/syncany | FINE : - Chunk > MultiChunk: 6a7adaf09b92b450a9b83111c88b060183de787f > 8f856026b7c2969cae8b823d29be9ff687c81dcd
27-4-17 11:01:35.453 | Indexer         | AsyncI/syncany | FINE : - Chunk > FileContent: 6a7adaf09b92b450a9b83111c88b060183de787f > /tmp/syncanytest/syncany-170427110008054-78157-client-A/file1
27-4-17 11:01:35.458 | Indexer         | AsyncI/syncany | FINE : - Chunk new: 87fe569ca8bca7d635c14c0c9a49a535421a48f3
27-4-17 11:01:35.459 | Indexer         | AsyncI/syncany | FINE : - /MultiChunk 8f856026b7c2969cae8b823d29be9ff687c81dcd
27-4-17 11:01:35.459 | Indexer         | AsyncI/syncany | FINE : - +MultiChunk 4fc95c9d71dd5fcb829e448842429d6c0a4e931d
27-4-17 11:01:35.464 | Indexer         | AsyncI/syncany | FINE : - Chunk > MultiChunk: 87fe569ca8bca7d635c14c0c9a49a535421a48f3 > 4fc95c9d71dd5fcb829e448842429d6c0a4e931d
27-4-17 11:01:35.464 | Indexer         | AsyncI/syncany | FINE : - Chunk > FileContent: 87fe569ca8bca7d635c14c0c9a49a535421a48f3 > /tmp/syncanytest/syncany-170427110008054-78157-client-A/file1
27-4-17 11:01:35.469 | Indexer         | AsyncI/syncany | FINE : - Chunk new: 95f51bc8eb89bf0b6e1d0be53d1cf3f30d57916f
27-4-17 11:01:35.474 | Indexer         | AsyncI/syncany | FINE : - Chunk > MultiChunk: 95f51bc8eb89bf0b6e1d0be53d1cf3f30d57916f > 4fc95c9d71dd5fcb829e448842429d6c0a4e931d
27-4-17 11:01:35.474 | Indexer         | AsyncI/syncany | FINE : - Chunk > FileContent: 95f51bc8eb89bf0b6e1d0be53d1cf3f30d57916f > /tmp/syncanytest/syncany-170427110008054-78157-client-A/file1
27-4-17 11:01:35.474 | Indexer         | AsyncI/syncany | FINE : - /File: file1 (checksum 682c86de3f50e450700108976180d43da989916e)
27-4-17 11:01:35.475 | Indexer         | AsyncI/syncany | FINE :    * No old file history found, starting new history (path: file1, checksum: 682c86de3f50e450700108976180d43da989916e)
27-4-17 11:01:35.475 | FileVersionComp | AsyncI/syncany | INFO :      - [DELETED]: Local file DIFFERS from file version, actual file is NULL, for file file1
27-4-17 11:01:35.475 | Indexer         | AsyncI/syncany | INFO :    * Added file version:    FileVersion [version=1, path=file1, type=FILE, status=NEW, size=5242880, lastModified=Thu Apr 27 11:01:35 UTC 2017, linkTarget=null, checksum=682c86de3f50e450700108976180d43da989916e, updated=Thu Apr 27 11:01:35 UTC 2017, posixPermissions=rw-rw-r--, dosAttributes=--a-]
27-4-17 11:01:35.475 | Indexer         | AsyncI/syncany | INFO :      based on file version: null
27-4-17 11:01:35.475 | InternalEventBu | AsyncI/syncany | INFO : Event bus 'LocalEventBus': Posting event UpIndexChangesDetectedSyncExternalEvent (org.syncany.operations.daemon.messages.UpIndexChangesDetectedSyncExternalEvent@74e3ba33) ...
27-4-17 11:01:35.476 | Indexer         | AsyncI/syncany | FINE : - /MultiChunk 4fc95c9d71dd5fcb829e448842429d6c0a4e931d
27-4-17 11:01:35.476 | Indexer         | AsyncI/syncany | FINE : Processed new database version: DatabaseVersion [header=UnknownMachine/()/T=1493290895373, chunks=10, multiChunks=2, fileContents=1, fileHistories=1]
27-4-17 11:01:35.476 | UpOperation     | Test worker    | INFO : No DIRTY data found in database (no dirty databases); Nothing to do here.
27-4-17 11:01:35.476 | UpOperation     | Test worker    | INFO : Last vector clock was: ()
27-4-17 11:01:35.476 | UpOperation     | Test worker    | INFO : Uploading new multichunks ...
27-4-17 11:01:35.477 | UpOperation     | Test worker    | INFO : - Uploading multichunk 8f856026b7c2969cae8b823d29be9ff687c81dcd from /tmp/syncanytest/syncany-170427110008054-78157-client-A/.syncany/cache/multichunk-8f856026b7c2969cae8b823d29be9ff687c81dcd to RemoteFile[name=multichunk-8f856026b7c2969cae8b823d29be9ff687c81dcd] ...
27-4-17 11:01:35.477 | RemoteTransacti | Test worker    | INFO : - Adding file to TX for UPLOAD: /tmp/syncanytest/syncany-170427110008054-78157-client-A/.syncany/cache/multichunk-8f856026b7c2969cae8b823d29be9ff687c81dcd -> Temp. remote file: RemoteFile[name=temp-ZVNHT-multichunk-8f856026b7c2969cae8b823d29be9ff687c81dcd], final location: RemoteFile[name=multichunk-8f856026b7c2969cae8b823d29be9ff687c81dcd]
27-4-17 11:01:35.477 | UpOperation     | Test worker    | INFO : - Uploading multichunk 4fc95c9d71dd5fcb829e448842429d6c0a4e931d from /tmp/syncanytest/syncany-170427110008054-78157-client-A/.syncany/cache/multichunk-4fc95c9d71dd5fcb829e448842429d6c0a4e931d to RemoteFile[name=multichunk-4fc95c9d71dd5fcb829e448842429d6c0a4e931d] ...
27-4-17 11:01:35.477 | RemoteTransacti | Test worker    | INFO : - Adding file to TX for UPLOAD: /tmp/syncanytest/syncany-170427110008054-78157-client-A/.syncany/cache/multichunk-4fc95c9d71dd5fcb829e448842429d6c0a4e931d -> Temp. remote file: RemoteFile[name=temp-vfOMg-multichunk-4fc95c9d71dd5fcb829e448842429d6c0a4e931d], final location: RemoteFile[name=multichunk-4fc95c9d71dd5fcb829e448842429d6c0a4e931d]
27-4-17 11:01:35.477 | UpOperation     | Test worker    | INFO : Uploading database: DatabaseVersion [header=A/(A1)/T=1493290895476, chunks=10, multiChunks=2, fileContents=1, fileHistories=1]
27-4-17 11:01:35.477 | UpOperation     | Test worker    | INFO : Saving local delta database, version A/(A1)/T=1493290895476 to file /tmp/syncanytest/syncany-170427110008054-78157-client-A/.syncany/cache/database-A-0000000001 ... 
27-4-17 11:01:35.477 | UpOperation     | Test worker    | INFO : - Saving database to /tmp/syncanytest/syncany-170427110008054-78157-client-A/.syncany/cache/database-A-0000000001 ...
27-4-17 11:01:35.477 | InternalEventBu | AsyncI/syncany | INFO : Event bus 'LocalEventBus': Posting event UpIndexMidSyncExternalEvent (org.syncany.operations.daemon.messages.UpIndexMidSyncExternalEvent@37e8ccd5) ...
27-4-17 11:01:35.478 | Indexer         | AsyncI/syncany | FINE : - +File /tmp/syncanytest/syncany-170427110008054-78157-client-A/file2
27-4-17 11:01:35.478 | UpOperation     | Test worker    | INFO : - Uploading local delta database file ...
27-4-17 11:01:35.478 | UpOperation     | Test worker    | INFO : - Uploading /tmp/syncanytest/syncany-170427110008054-78157-client-A/.syncany/cache/database-A-0000000001 to RemoteFile[name=database-A-0000000001] ...
27-4-17 11:01:35.478 | RemoteTransacti | Test worker    | INFO : - Adding file to TX for UPLOAD: /tmp/syncanytest/syncany-170427110008054-78157-client-A/.syncany/cache/database-A-0000000001 -> Temp. remote file: RemoteFile[name=temp-kfgeR-database-A-0000000001], final location: RemoteFile[name=database-A-0000000001]
27-4-17 11:01:35.478 | Indexer         | AsyncI/syncany | FINE : - +FileContent: /tmp/syncanytest/syncany-170427110008054-78157-client-A/file2
27-4-17 11:01:35.478 | UpOperation     | Test worker    | INFO : Adding shutdown hook (to allow resuming the upload) ...
27-4-17 11:01:35.478 | RemoteTransacti | Test worker    | INFO : Starting TX.commit() ...
27-4-17 11:01:35.479 | Indexer         | AsyncI/syncany | FINE : - Chunk new: 26735405ef803e38c95eaebea4577241c5843404
27-4-17 11:01:35.479 | Indexer         | AsyncI/syncany | FINE : - +MultiChunk 63d5205e70db891b18e76c3fddde03bd0d7f2775
27-4-17 11:01:35.479 | RemoteTransacti | Test worker    | INFO : Wrote transaction manifest to temporary file: /tmp/syncanytest/syncany-170427110008054-78157-client-A/.syncany/cache/temp-transaction-6670117527776307475.tmp
27-4-17 11:01:35.480 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event UpUploadFileSyncExternalEvent (org.syncany.operations.daemon.messages.UpUploadFileSyncExternalEvent@52013b7b) ...
27-4-17 11:01:35.480 | RemoteTransacti | Test worker    | INFO : - Uploading remote transaction file RemoteFile[name=transaction-8d57e74] ...
27-4-17 11:01:35.480 | UnreliableLocal | Test worker    | INFO : Operation successful:     abs=16 rel=2 op=upload upload(/tmp/syncanytest/syncany-170427110008054-78157-client-A/.syncany/cache/temp-transaction-6670117527776307475.tmp, transaction-8d57e74)
27-4-17 11:01:35.480 | UnreliableLocal | Test worker    | INFO : Operation successful:     abs=17 rel=8 op=connect connect
27-4-17 11:01:35.480 | RemoteTransacti | Test worker    | INFO : - Starting to upload data in commit.
27-4-17 11:01:35.480 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event UpUploadFileInTransactionSyncExternalEvent (org.syncany.operations.daemon.messages.UpUploadFileInTransactionSyncExternalEvent@23f60fa4) ...
27-4-17 11:01:35.480 | RemoteTransacti | Test worker    | INFO : - Uploading /tmp/syncanytest/syncany-170427110008054-78157-client-A/.syncany/cache/multichunk-8f856026b7c2969cae8b823d29be9ff687c81dcd to temp. file RemoteFile[name=temp-ZVNHT-multichunk-8f856026b7c2969cae8b823d29be9ff687c81dcd] ...
27-4-17 11:01:35.480 | UnreliableLocal | Test worker    | INFO : Operation successful:     abs=18 rel=3 op=upload upload(/tmp/syncanytest/syncany-170427110008054-78157-client-A/.syncany/cache/multichunk-8f856026b7c2969cae8b823d29be9ff687c81dcd, temp-ZVNHT-multichunk-8f856026b7c2969cae8b823d29be9ff687c81dcd)
27-4-17 11:01:35.480 | UnreliableLocal | Test worker    | INFO : Operation successful:     abs=19 rel=9 op=connect connect
27-4-17 11:01:35.484 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event UpUploadFileInTransactionSyncExternalEvent (org.syncany.operations.daemon.messages.UpUploadFileInTransactionSyncExternalEvent@be9107c) ...
27-4-17 11:01:35.485 | RemoteTransacti | Test worker    | INFO : - Uploading /tmp/syncanytest/syncany-170427110008054-78157-client-A/.syncany/cache/multichunk-4fc95c9d71dd5fcb829e448842429d6c0a4e931d to temp. file RemoteFile[name=temp-vfOMg-multichunk-4fc95c9d71dd5fcb829e448842429d6c0a4e931d] ...
27-4-17 11:01:35.485 | UnreliableLocal | Test worker    | INFO : Operation NOT successful: abs=20 rel=4 op=upload upload(/tmp/syncanytest/syncany-170427110008054-78157-client-A/.syncany/cache/multichunk-4fc95c9d71dd5fcb829e448842429d6c0a4e931d, temp-vfOMg-multichunk-4fc95c9d71dd5fcb829e448842429d6c0a4e931d)
27-4-17 11:01:35.485 | RetriableFeatur | Test worker    | WARN : Transfer method failed. 1/3 retries. Sleeping 3000ms ...
org.syncany.plugins.transfer.StorageException: Operation failed: upload(/tmp/syncanytest/syncany-170427110008054-78157-client-A/.syncany/cache/multichunk-4fc95c9d71dd5fcb829e448842429d6c0a4e931d, temp-vfOMg-multichunk-4fc95c9d71dd5fcb829e448842429d6c0a4e931d)
	at org.syncany.plugins.unreliable_local.UnreliableLocalTransferManager.upload(UnreliableLocalTransferManager.java:126)
	at org.syncany.plugins.transfer.features.RetriableFeatureTransferManager$6.execute(RetriableFeatureTransferManager.java:124)
	at org.syncany.plugins.transfer.features.RetriableFeatureTransferManager.retryMethod(RetriableFeatureTransferManager.java:210)
	at org.syncany.plugins.transfer.features.RetriableFeatureTransferManager.upload(RetriableFeatureTransferManager.java:121)
	at org.syncany.plugins.transfer.features.TransactionAwareFeatureTransferManager.upload(TransactionAwareFeatureTransferManager.java:138)
	at org.syncany.plugins.transfer.RemoteTransaction.uploadAndMoveToTempLocation(RemoteTransaction.java:222)
	at org.syncany.plugins.transfer.RemoteTransaction.commit(RemoteTransaction.java:142)
	at org.syncany.plugins.transfer.RemoteTransaction.commit(RemoteTransaction.java:131)
	at org.syncany.operations.up.UpOperation.executeTransactions(UpOperation.java:308)
	at org.syncany.operations.up.UpOperation.execute(UpOperation.java:168)
	at org.syncany.tests.util.TestClient.up(TestClient.java:94)
	at org.syncany.tests.integration.scenarios.FailedSplitSyncUpScenarioTest.testUpFailsOnFirstTransaction(FailedSplitSyncUpScenarioTest.java:45)
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
	at java.lang.reflect.Method.invoke(Method.java:498)
	at org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(FrameworkMethod.java:44)
	at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:15)
	at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java:41)
	at org.junit.internal.runners.statements.InvokeMethod.evaluate(InvokeMethod.java:20)
	at org.junit.runners.ParentRunner.runLeaf(ParentRunner.java:263)
	at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:69)
	at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:48)
	at org.junit.runners.ParentRunner$3.run(ParentRunner.java:231)
	at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:60)
	at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:229)
	at org.junit.runners.ParentRunner.access$000(ParentRunner.java:50)
	at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:222)
	at org.junit.runners.ParentRunner.run(ParentRunner.java:292)
	at org.gradle.api.internal.tasks.testing.junit.JUnitTestClassExecuter.runTestClass(JUnitTestClassExecuter.java:114)
	at org.gradle.api.internal.tasks.testing.junit.JUnitTestClassExecuter.execute(JUnitTestClassExecuter.java:57)
	at org.gradle.api.internal.tasks.testing.junit.JUnitTestClassProcessor.processTestClass(JUnitTestClassProcessor.java:66)
	at org.gradle.api.internal.tasks.testing.SuiteTestClassProcessor.processTestClass(SuiteTestClassProcessor.java:51)
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
	at java.lang.reflect.Method.invoke(Method.java:498)
	at org.gradle.internal.dispatch.ReflectionDispatch.dispatch(ReflectionDispatch.java:35)
	at org.gradle.internal.dispatch.ReflectionDispatch.dispatch(ReflectionDispatch.java:24)
	at org.gradle.internal.dispatch.ContextClassLoaderDispatch.dispatch(ContextClassLoaderDispatch.java:32)
	at org.gradle.internal.dispatch.ProxyDispatchAdapter$DispatchingInvocationHandler.invoke(ProxyDispatchAdapter.java:93)
	at com.sun.proxy.$Proxy2.processTestClass(Unknown Source)
	at org.gradle.api.internal.tasks.testing.worker.TestWorker.processTestClass(TestWorker.java:109)
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
	at java.lang.reflect.Method.invoke(Method.java:498)
	at org.gradle.internal.dispatch.ReflectionDispatch.dispatch(ReflectionDispatch.java:35)
	at org.gradle.internal.dispatch.ReflectionDispatch.dispatch(ReflectionDispatch.java:24)
	at org.gradle.internal.remote.internal.hub.MessageHubBackedObjectConnection$DispatchWrapper.dispatch(MessageHubBackedObjectConnection.java:147)
	at org.gradle.internal.remote.internal.hub.MessageHubBackedObjectConnection$DispatchWrapper.dispatch(MessageHubBackedObjectConnection.java:129)
	at org.gradle.internal.remote.internal.hub.MessageHub$Handler.run(MessageHub.java:404)
	at org.gradle.internal.concurrent.ExecutorPolicy$CatchAndRecordFailures.onExecute(ExecutorPolicy.java:63)
	at org.gradle.internal.concurrent.StoppableExecutorImpl$1.run(StoppableExecutorImpl.java:46)
	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
	at java.lang.Thread.run(Thread.java:745)
27-4-17 11:01:35.488 | Indexer         | AsyncI/syncany | FINE : - Chunk > MultiChunk: 26735405ef803e38c95eaebea4577241c5843404 > 63d5205e70db891b18e76c3fddde03bd0d7f2775
27-4-17 11:01:35.488 | Indexer         | AsyncI/syncany | FINE : - Chunk > FileContent: 26735405ef803e38c95eaebea4577241c5843404 > /tmp/syncanytest/syncany-170427110008054-78157-client-A/file2
27-4-17 11:01:35.488 | Indexer         | AsyncI/syncany | FINE : - /File: file2 (checksum 26735405ef803e38c95eaebea4577241c5843404)
27-4-17 11:01:35.489 | Indexer         | AsyncI/syncany | FINE :    * No old file history found, starting new history (path: file2, checksum: 26735405ef803e38c95eaebea4577241c5843404)
27-4-17 11:01:35.489 | FileVersionComp | AsyncI/syncany | INFO :      - [DELETED]: Local file DIFFERS from file version, actual file is NULL, for file file2
27-4-17 11:01:35.489 | Indexer         | AsyncI/syncany | INFO :    * Added file version:    FileVersion [version=1, path=file2, type=FILE, status=NEW, size=1024, lastModified=Thu Apr 27 11:01:35 UTC 2017, linkTarget=null, checksum=26735405ef803e38c95eaebea4577241c5843404, updated=Thu Apr 27 11:01:35 UTC 2017, posixPermissions=rw-rw-r--, dosAttributes=--a-]
27-4-17 11:01:35.489 | Indexer         | AsyncI/syncany | INFO :      based on file version: null
27-4-17 11:01:35.489 | InternalEventBu | AsyncI/syncany | INFO : Event bus 'LocalEventBus': Posting event UpIndexChangesDetectedSyncExternalEvent (org.syncany.operations.daemon.messages.UpIndexChangesDetectedSyncExternalEvent@204b932e) ...
27-4-17 11:01:35.490 | Indexer         | AsyncI/syncany | FINE : - /MultiChunk 63d5205e70db891b18e76c3fddde03bd0d7f2775
27-4-17 11:01:35.490 | Indexer         | AsyncI/syncany | FINE : Processed new database version: DatabaseVersion [header=UnknownMachine/()/T=1493290895478, chunks=1, multiChunks=1, fileContents=1, fileHistories=1]
27-4-17 11:01:35.490 | InternalEventBu | AsyncI/syncany | INFO : Event bus 'LocalEventBus': Posting event UpIndexMidSyncExternalEvent (org.syncany.operations.daemon.messages.UpIndexMidSyncExternalEvent@56410880) ...
27-4-17 11:01:35.490 | Indexer         | AsyncI/syncany | FINE : - +File /tmp/syncanytest/syncany-170427110008054-78157-client-A/file3
27-4-17 11:01:35.490 | Indexer         | AsyncI/syncany | FINE : - +FileContent: /tmp/syncanytest/syncany-170427110008054-78157-client-A/file3
27-4-17 11:01:35.490 | Indexer         | AsyncI/syncany | FINE : - Chunk new: cbf01d31f9492272fcc19b4ac53295226decf648
27-4-17 11:01:35.491 | Indexer         | AsyncI/syncany | FINE : - +MultiChunk d377f1cbaac54b28b25a921bd5483251726819be
27-4-17 11:01:35.491 | Indexer         | AsyncI/syncany | FINE : - Chunk > MultiChunk: cbf01d31f9492272fcc19b4ac53295226decf648 > d377f1cbaac54b28b25a921bd5483251726819be
27-4-17 11:01:35.493 | Indexer         | AsyncI/syncany | FINE : - Chunk > FileContent: cbf01d31f9492272fcc19b4ac53295226decf648 > /tmp/syncanytest/syncany-170427110008054-78157-client-A/file3
27-4-17 11:01:35.493 | Indexer         | AsyncI/syncany | FINE : - /File: file3 (checksum cbf01d31f9492272fcc19b4ac53295226decf648)
27-4-17 11:01:35.494 | Indexer         | AsyncI/syncany | FINE :    * No old file history found, starting new history (path: file3, checksum: cbf01d31f9492272fcc19b4ac53295226decf648)
27-4-17 11:01:35.494 | FileVersionComp | AsyncI/syncany | INFO :      - [DELETED]: Local file DIFFERS from file version, actual file is NULL, for file file3
27-4-17 11:01:35.494 | Indexer         | AsyncI/syncany | INFO :    * Added file version:    FileVersion [version=1, path=file3, type=FILE, status=NEW, size=1024, lastModified=Thu Apr 27 11:01:35 UTC 2017, linkTarget=null, checksum=cbf01d31f9492272fcc19b4ac53295226decf648, updated=Thu Apr 27 11:01:35 UTC 2017, posixPermissions=rw-rw-r--, dosAttributes=--a-]
27-4-17 11:01:35.494 | Indexer         | AsyncI/syncany | INFO :      based on file version: null
27-4-17 11:01:35.494 | InternalEventBu | AsyncI/syncany | INFO : Event bus 'LocalEventBus': Posting event UpIndexChangesDetectedSyncExternalEvent (org.syncany.operations.daemon.messages.UpIndexChangesDetectedSyncExternalEvent@7f4c8280) ...
27-4-17 11:01:35.494 | Indexer         | AsyncI/syncany | FINE : - /MultiChunk d377f1cbaac54b28b25a921bd5483251726819be
27-4-17 11:01:35.494 | Indexer         | AsyncI/syncany | FINE : Processed new database version: DatabaseVersion [header=UnknownMachine/()/T=1493290895490, chunks=1, multiChunks=1, fileContents=1, fileHistories=1]
27-4-17 11:01:35.494 | InternalEventBu | AsyncI/syncany | INFO : Event bus 'LocalEventBus': Posting event UpIndexMidSyncExternalEvent (org.syncany.operations.daemon.messages.UpIndexMidSyncExternalEvent@733897dd) ...
27-4-17 11:01:35.499 | AsyncIndexer    | AsyncI/syncany | INFO : Stopping indexing. Signal end of stream with empty databaseversion
27-4-17 11:01:38.486 | RetriableFeatur | Test worker    | WARN : Retrying method: 1/3 ...
27-4-17 11:01:38.486 | UnreliableLocal | Test worker    | INFO : Operation NOT successful: abs=21 rel=5 op=upload upload(/tmp/syncanytest/syncany-170427110008054-78157-client-A/.syncany/cache/multichunk-4fc95c9d71dd5fcb829e448842429d6c0a4e931d, temp-vfOMg-multichunk-4fc95c9d71dd5fcb829e448842429d6c0a4e931d)
27-4-17 11:01:38.486 | RetriableFeatur | Test worker    | WARN : Transfer method failed. 2/3 retries. Sleeping 3000ms ...
org.syncany.plugins.transfer.StorageException: Operation failed: upload(/tmp/syncanytest/syncany-170427110008054-78157-client-A/.syncany/cache/multichunk-4fc95c9d71dd5fcb829e448842429d6c0a4e931d, temp-vfOMg-multichunk-4fc95c9d71dd5fcb829e448842429d6c0a4e931d)
	at org.syncany.plugins.unreliable_local.UnreliableLocalTransferManager.upload(UnreliableLocalTransferManager.java:126)
	at org.syncany.plugins.transfer.features.RetriableFeatureTransferManager$6.execute(RetriableFeatureTransferManager.java:124)
	at org.syncany.plugins.transfer.features.RetriableFeatureTransferManager.retryMethod(RetriableFeatureTransferManager.java:210)
	at org.syncany.plugins.transfer.features.RetriableFeatureTransferManager.upload(RetriableFeatureTransferManager.java:121)
	at org.syncany.plugins.transfer.features.TransactionAwareFeatureTransferManager.upload(TransactionAwareFeatureTransferManager.java:138)
	at org.syncany.plugins.transfer.RemoteTransaction.uploadAndMoveToTempLocation(RemoteTransaction.java:222)
	at org.syncany.plugins.transfer.RemoteTransaction.commit(RemoteTransaction.java:142)
	at org.syncany.plugins.transfer.RemoteTransaction.commit(RemoteTransaction.java:131)
	at org.syncany.operations.up.UpOperation.executeTransactions(UpOperation.java:308)
	at org.syncany.operations.up.UpOperation.execute(UpOperation.java:168)
	at org.syncany.tests.util.TestClient.up(TestClient.java:94)
	at org.syncany.tests.integration.scenarios.FailedSplitSyncUpScenarioTest.testUpFailsOnFirstTransaction(FailedSplitSyncUpScenarioTest.java:45)
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
	at java.lang.reflect.Method.invoke(Method.java:498)
	at org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(FrameworkMethod.java:44)
	at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:15)
	at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java:41)
	at org.junit.internal.runners.statements.InvokeMethod.evaluate(InvokeMethod.java:20)
	at org.junit.runners.ParentRunner.runLeaf(ParentRunner.java:263)
	at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:69)
	at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:48)
	at org.junit.runners.ParentRunner$3.run(ParentRunner.java:231)
	at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:60)
	at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:229)
	at org.junit.runners.ParentRunner.access$000(ParentRunner.java:50)
	at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:222)
	at org.junit.runners.ParentRunner.run(ParentRunner.java:292)
	at org.gradle.api.internal.tasks.testing.junit.JUnitTestClassExecuter.runTestClass(JUnitTestClassExecuter.java:114)
	at org.gradle.api.internal.tasks.testing.junit.JUnitTestClassExecuter.execute(JUnitTestClassExecuter.java:57)
	at org.gradle.api.internal.tasks.testing.junit.JUnitTestClassProcessor.processTestClass(JUnitTestClassProcessor.java:66)
	at org.gradle.api.internal.tasks.testing.SuiteTestClassProcessor.processTestClass(SuiteTestClassProcessor.java:51)
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
	at java.lang.reflect.Method.invoke(Method.java:498)
	at org.gradle.internal.dispatch.ReflectionDispatch.dispatch(ReflectionDispatch.java:35)
	at org.gradle.internal.dispatch.ReflectionDispatch.dispatch(ReflectionDispatch.java:24)
	at org.gradle.internal.dispatch.ContextClassLoaderDispatch.dispatch(ContextClassLoaderDispatch.java:32)
	at org.gradle.internal.dispatch.ProxyDispatchAdapter$DispatchingInvocationHandler.invoke(ProxyDispatchAdapter.java:93)
	at com.sun.proxy.$Proxy2.processTestClass(Unknown Source)
	at org.gradle.api.internal.tasks.testing.worker.TestWorker.processTestClass(TestWorker.java:109)
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
	at java.lang.reflect.Method.invoke(Method.java:498)
	at org.gradle.internal.dispatch.ReflectionDispatch.dispatch(ReflectionDispatch.java:35)
	at org.gradle.internal.dispatch.ReflectionDispatch.dispatch(ReflectionDispatch.java:24)
	at org.gradle.internal.remote.internal.hub.MessageHubBackedObjectConnection$DispatchWrapper.dispatch(MessageHubBackedObjectConnection.java:147)
	at org.gradle.internal.remote.internal.hub.MessageHubBackedObjectConnection$DispatchWrapper.dispatch(MessageHubBackedObjectConnection.java:129)
	at org.gradle.internal.remote.internal.hub.MessageHub$Handler.run(MessageHub.java:404)
	at org.gradle.internal.concurrent.ExecutorPolicy$CatchAndRecordFailures.onExecute(ExecutorPolicy.java:63)
	at org.gradle.internal.concurrent.StoppableExecutorImpl$1.run(StoppableExecutorImpl.java:46)
	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
	at java.lang.Thread.run(Thread.java:745)
27-4-17 11:01:41.487 | RetriableFeatur | Test worker    | WARN : Retrying method: 2/3 ...
27-4-17 11:01:41.487 | UnreliableLocal | Test worker    | INFO : Operation NOT successful: abs=22 rel=6 op=upload upload(/tmp/syncanytest/syncany-170427110008054-78157-client-A/.syncany/cache/multichunk-4fc95c9d71dd5fcb829e448842429d6c0a4e931d, temp-vfOMg-multichunk-4fc95c9d71dd5fcb829e448842429d6c0a4e931d)
27-4-17 11:01:41.487 | RetriableFeatur | Test worker    | WARN : Transfer method failed. No retries left. Throwing exception.
org.syncany.plugins.transfer.StorageException: Operation failed: upload(/tmp/syncanytest/syncany-170427110008054-78157-client-A/.syncany/cache/multichunk-4fc95c9d71dd5fcb829e448842429d6c0a4e931d, temp-vfOMg-multichunk-4fc95c9d71dd5fcb829e448842429d6c0a4e931d)
	at org.syncany.plugins.unreliable_local.UnreliableLocalTransferManager.upload(UnreliableLocalTransferManager.java:126)
	at org.syncany.plugins.transfer.features.RetriableFeatureTransferManager$6.execute(RetriableFeatureTransferManager.java:124)
	at org.syncany.plugins.transfer.features.RetriableFeatureTransferManager.retryMethod(RetriableFeatureTransferManager.java:210)
	at org.syncany.plugins.transfer.features.RetriableFeatureTransferManager.upload(RetriableFeatureTransferManager.java:121)
	at org.syncany.plugins.transfer.features.TransactionAwareFeatureTransferManager.upload(TransactionAwareFeatureTransferManager.java:138)
	at org.syncany.plugins.transfer.RemoteTransaction.uploadAndMoveToTempLocation(RemoteTransaction.java:222)
	at org.syncany.plugins.transfer.RemoteTransaction.commit(RemoteTransaction.java:142)
	at org.syncany.plugins.transfer.RemoteTransaction.commit(RemoteTransaction.java:131)
	at org.syncany.operations.up.UpOperation.executeTransactions(UpOperation.java:308)
	at org.syncany.operations.up.UpOperation.execute(UpOperation.java:168)
	at org.syncany.tests.util.TestClient.up(TestClient.java:94)
	at org.syncany.tests.integration.scenarios.FailedSplitSyncUpScenarioTest.testUpFailsOnFirstTransaction(FailedSplitSyncUpScenarioTest.java:45)
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
	at java.lang.reflect.Method.invoke(Method.java:498)
	at org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(FrameworkMethod.java:44)
	at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:15)
	at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java:41)
	at org.junit.internal.runners.statements.InvokeMethod.evaluate(InvokeMethod.java:20)
	at org.junit.runners.ParentRunner.runLeaf(ParentRunner.java:263)
	at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:69)
	at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:48)
	at org.junit.runners.ParentRunner$3.run(ParentRunner.java:231)
	at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:60)
	at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:229)
	at org.junit.runners.ParentRunner.access$000(ParentRunner.java:50)
	at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:222)
	at org.junit.runners.ParentRunner.run(ParentRunner.java:292)
	at org.gradle.api.internal.tasks.testing.junit.JUnitTestClassExecuter.runTestClass(JUnitTestClassExecuter.java:114)
	at org.gradle.api.internal.tasks.testing.junit.JUnitTestClassExecuter.execute(JUnitTestClassExecuter.java:57)
	at org.gradle.api.internal.tasks.testing.junit.JUnitTestClassProcessor.processTestClass(JUnitTestClassProcessor.java:66)
	at org.gradle.api.internal.tasks.testing.SuiteTestClassProcessor.processTestClass(SuiteTestClassProcessor.java:51)
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
	at java.lang.reflect.Method.invoke(Method.java:498)
	at org.gradle.internal.dispatch.ReflectionDispatch.dispatch(ReflectionDispatch.java:35)
	at org.gradle.internal.dispatch.ReflectionDispatch.dispatch(ReflectionDispatch.java:24)
	at org.gradle.internal.dispatch.ContextClassLoaderDispatch.dispatch(ContextClassLoaderDispatch.java:32)
	at org.gradle.internal.dispatch.ProxyDispatchAdapter$DispatchingInvocationHandler.invoke(ProxyDispatchAdapter.java:93)
	at com.sun.proxy.$Proxy2.processTestClass(Unknown Source)
	at org.gradle.api.internal.tasks.testing.worker.TestWorker.processTestClass(TestWorker.java:109)
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
	at java.lang.reflect.Method.invoke(Method.java:498)
	at org.gradle.internal.dispatch.ReflectionDispatch.dispatch(ReflectionDispatch.java:35)
	at org.gradle.internal.dispatch.ReflectionDispatch.dispatch(ReflectionDispatch.java:24)
	at org.gradle.internal.remote.internal.hub.MessageHubBackedObjectConnection$DispatchWrapper.dispatch(MessageHubBackedObjectConnection.java:147)
	at org.gradle.internal.remote.internal.hub.MessageHubBackedObjectConnection$DispatchWrapper.dispatch(MessageHubBackedObjectConnection.java:129)
	at org.gradle.internal.remote.internal.hub.MessageHub$Handler.run(MessageHub.java:404)
	at org.gradle.internal.concurrent.ExecutorPolicy$CatchAndRecordFailures.onExecute(ExecutorPolicy.java:63)
	at org.gradle.internal.concurrent.StoppableExecutorImpl$1.run(StoppableExecutorImpl.java:46)
	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
	at java.lang.Thread.run(Thread.java:745)
27-4-17 11:01:41.488 | UpOperation     | Test worker    | FINE : Waiting for new database version.
27-4-17 11:01:41.488 | UpOperation     | Test worker    | FINE : Took new database version: DatabaseVersion [header=UnknownMachine/()/T=1493290895478, chunks=1, multiChunks=1, fileContents=1, fileHistories=1]
27-4-17 11:01:41.488 | UpOperation     | Test worker    | INFO : Last vector clock was: ()
27-4-17 11:01:41.488 | UpOperation     | Test worker    | INFO : Uploading new multichunks ...
27-4-17 11:01:41.489 | UpOperation     | Test worker    | INFO : - Uploading multichunk 63d5205e70db891b18e76c3fddde03bd0d7f2775 from /tmp/syncanytest/syncany-170427110008054-78157-client-A/.syncany/cache/multichunk-63d5205e70db891b18e76c3fddde03bd0d7f2775 to RemoteFile[name=multichunk-63d5205e70db891b18e76c3fddde03bd0d7f2775] ...
27-4-17 11:01:41.489 | RemoteTransacti | Test worker    | INFO : - Adding file to TX for UPLOAD: /tmp/syncanytest/syncany-170427110008054-78157-client-A/.syncany/cache/multichunk-63d5205e70db891b18e76c3fddde03bd0d7f2775 -> Temp. remote file: RemoteFile[name=temp-yiScY-multichunk-63d5205e70db891b18e76c3fddde03bd0d7f2775], final location: RemoteFile[name=multichunk-63d5205e70db891b18e76c3fddde03bd0d7f2775]
27-4-17 11:01:41.489 | UpOperation     | Test worker    | INFO : Uploading database: DatabaseVersion [header=A/(A2)/T=1493290901488, chunks=1, multiChunks=1, fileContents=1, fileHistories=1]
27-4-17 11:01:41.489 | UpOperation     | Test worker    | INFO : Saving local delta database, version A/(A2)/T=1493290901488 to file /tmp/syncanytest/syncany-170427110008054-78157-client-A/.syncany/cache/database-A-0000000002 ... 
27-4-17 11:01:41.489 | UpOperation     | Test worker    | INFO : - Saving database to /tmp/syncanytest/syncany-170427110008054-78157-client-A/.syncany/cache/database-A-0000000002 ...
27-4-17 11:01:41.490 | UpOperation     | Test worker    | INFO : - Uploading local delta database file ...
27-4-17 11:01:41.490 | UpOperation     | Test worker    | INFO : - Uploading /tmp/syncanytest/syncany-170427110008054-78157-client-A/.syncany/cache/database-A-0000000002 to RemoteFile[name=database-A-0000000002] ...
27-4-17 11:01:41.490 | RemoteTransacti | Test worker    | INFO : - Adding file to TX for UPLOAD: /tmp/syncanytest/syncany-170427110008054-78157-client-A/.syncany/cache/database-A-0000000002 -> Temp. remote file: RemoteFile[name=temp-oLqml-database-A-0000000002], final location: RemoteFile[name=database-A-0000000002]
27-4-17 11:01:41.490 | UpOperation     | Test worker    | INFO : Adding shutdown hook (to allow resuming the upload) ...
27-4-17 11:01:41.490 | UpOperation     | Test worker    | FINE : Waiting for new database version.
27-4-17 11:01:41.490 | UpOperation     | Test worker    | FINE : Took new database version: DatabaseVersion [header=UnknownMachine/()/T=1493290895490, chunks=1, multiChunks=1, fileContents=1, fileHistories=1]
27-4-17 11:01:41.490 | UpOperation     | Test worker    | INFO : Last vector clock was: ()
27-4-17 11:01:41.491 | UpOperation     | Test worker    | INFO : Uploading new multichunks ...
27-4-17 11:01:41.491 | UpOperation     | Test worker    | INFO : - Uploading multichunk d377f1cbaac54b28b25a921bd5483251726819be from /tmp/syncanytest/syncany-170427110008054-78157-client-A/.syncany/cache/multichunk-d377f1cbaac54b28b25a921bd5483251726819be to RemoteFile[name=multichunk-d377f1cbaac54b28b25a921bd5483251726819be] ...
27-4-17 11:01:41.491 | RemoteTransacti | Test worker    | INFO : - Adding file to TX for UPLOAD: /tmp/syncanytest/syncany-170427110008054-78157-client-A/.syncany/cache/multichunk-d377f1cbaac54b28b25a921bd5483251726819be -> Temp. remote file: RemoteFile[name=temp-nhIQM-multichunk-d377f1cbaac54b28b25a921bd5483251726819be], final location: RemoteFile[name=multichunk-d377f1cbaac54b28b25a921bd5483251726819be]
27-4-17 11:01:41.491 | UpOperation     | Test worker    | INFO : Uploading database: DatabaseVersion [header=A/(A3)/T=1493290901491, chunks=1, multiChunks=1, fileContents=1, fileHistories=1]
27-4-17 11:01:41.491 | UpOperation     | Test worker    | INFO : Saving local delta database, version A/(A3)/T=1493290901491 to file /tmp/syncanytest/syncany-170427110008054-78157-client-A/.syncany/cache/database-A-0000000003 ... 
27-4-17 11:01:41.491 | UpOperation     | Test worker    | INFO : - Saving database to /tmp/syncanytest/syncany-170427110008054-78157-client-A/.syncany/cache/database-A-0000000003 ...
27-4-17 11:01:41.492 | UpOperation     | Test worker    | INFO : - Uploading local delta database file ...
27-4-17 11:01:41.492 | UpOperation     | Test worker    | INFO : - Uploading /tmp/syncanytest/syncany-170427110008054-78157-client-A/.syncany/cache/database-A-0000000003 to RemoteFile[name=database-A-0000000003] ...
27-4-17 11:01:41.492 | RemoteTransacti | Test worker    | INFO : - Adding file to TX for UPLOAD: /tmp/syncanytest/syncany-170427110008054-78157-client-A/.syncany/cache/database-A-0000000003 -> Temp. remote file: RemoteFile[name=temp-ultwY-database-A-0000000003], final location: RemoteFile[name=database-A-0000000003]
27-4-17 11:01:41.492 | UpOperation     | Test worker    | INFO : Adding shutdown hook (to allow resuming the upload) ...
27-4-17 11:01:41.492 | UpOperation     | Test worker    | FINE : Waiting for new database version.
27-4-17 11:01:41.492 | UpOperation     | Test worker    | FINE : Took new database version: DatabaseVersion [header=UnknownMachine/()/T=1493290895499, chunks=0, multiChunks=0, fileContents=0, fileHistories=0]
27-4-17 11:01:41.492 | UpOperation     | Test worker    | INFO : Persisting status of UpOperation to /tmp/syncanytest/syncany-170427110008054-78157-client-A/.syncany/state ...
27-4-17 11:01:41.494 | RemoteTransacti | Test worker    | INFO : Wrote transaction manifest to temporary file: /tmp/syncanytest/syncany-170427110008054-78157-client-A/.syncany/state/transaction-actions.0000000001.xml
27-4-17 11:01:41.495 | RemoteTransacti | Test worker    | INFO : Wrote transaction manifest to temporary file: /tmp/syncanytest/syncany-170427110008054-78157-client-A/.syncany/state/transaction-actions.0000000002.xml
27-4-17 11:01:41.497 | RemoteTransacti | Test worker    | INFO : Wrote transaction manifest to temporary file: /tmp/syncanytest/syncany-170427110008054-78157-client-A/.syncany/state/transaction-actions.0000000003.xml
27-4-17 11:01:41.507 | RemoteTransacti | Test worker    | INFO : Wrote transaction manifest to temporary file: /tmp/syncanytest/syncany-170427110008054-78157-client-A/.syncany/state/transaction-actions.xml
27-4-17 11:01:41.509 | org.syncany.plu | Test worker    | FINE : Validating required fields
27-4-17 11:01:41.510 | TransferManager | Test worker    | INFO : Building UnreliableLocalTransferManager from config 'syncany-170427110008054-78157-client-A' ...
27-4-17 11:01:41.510 | TransferManager | Test worker    | INFO : - With feature ReadAfterWriteConsistent
27-4-17 11:01:41.510 | TransferManager | Test worker    | INFO : - With feature Retriable
27-4-17 11:01:41.510 | TransferManager | Test worker    | FINE : - Checking if selected features supported ...
27-4-17 11:01:41.510 | TransferManager | Test worker    | FINE : - Checking for duplicate features ...
27-4-17 11:01:41.510 | TransferManager | Test worker    | FINE : - Checking required feature TransactionAware in UnreliableLocalTransferManager ...
27-4-17 11:01:41.510 | TransferManager | Test worker    | FINE : - Checking required feature Retriable in UnreliableLocalTransferManager ...
27-4-17 11:01:41.510 | TransferManager | Test worker    | INFO : - SKIPPING unsupported optional feature ReadAfterWriteConsistent
27-4-17 11:01:41.510 | TransferManager | Test worker    | FINE : - Wrapping TransferManager UnreliableLocalTransferManager in RetriableFeatureTransferManager
27-4-17 11:01:41.510 | org.syncany.plu | Test worker    | FINE : Validating required fields
27-4-17 11:01:41.510 | TransferManager | Test worker    | INFO : Building UnreliableLocalTransferManager from config 'syncany-170427110008054-78157-client-A' ...
27-4-17 11:01:41.510 | TransferManager | Test worker    | INFO : - With feature ReadAfterWriteConsistent
27-4-17 11:01:41.510 | TransferManager | Test worker    | INFO : - With feature Retriable
27-4-17 11:01:41.510 | TransferManager | Test worker    | INFO : - With feature PathAware
27-4-17 11:01:41.510 | TransferManager | Test worker    | INFO : - With feature TransactionAware
27-4-17 11:01:41.510 | TransferManager | Test worker    | FINE : - Checking if selected features supported ...
27-4-17 11:01:41.510 | TransferManager | Test worker    | FINE : - Checking for duplicate features ...
27-4-17 11:01:41.510 | TransferManager | Test worker    | FINE : - Checking required feature TransactionAware in UnreliableLocalTransferManager ...
27-4-17 11:01:41.510 | TransferManager | Test worker    | FINE : - Checking required feature Retriable in UnreliableLocalTransferManager ...
27-4-17 11:01:41.510 | TransferManager | Test worker    | INFO : - SKIPPING unsupported optional feature ReadAfterWriteConsistent
27-4-17 11:01:41.510 | TransferManager | Test worker    | FINE : - Wrapping TransferManager UnreliableLocalTransferManager in RetriableFeatureTransferManager
27-4-17 11:01:41.510 | TransferManager | Test worker    | INFO : - SKIPPING unsupported optional feature PathAware
27-4-17 11:01:41.510 | TransferManager | Test worker    | FINE : - Wrapping TransferManager RetriableFeatureTransferManager in TransactionAwareFeatureTransferManager
27-4-17 11:01:41.518 | DatabaseConnect | Test worker    | INFO : Found 12 tables.
27-4-17 11:01:41.518 | UpOperation     | Test worker    | INFO : 
27-4-17 11:01:41.518 | UpOperation     | Test worker    | INFO : Running 'Sync up' at client A ...
27-4-17 11:01:41.518 | UpOperation     | Test worker    | INFO : --------------------------------------------
27-4-17 11:01:41.518 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event UpStartSyncExternalEvent (org.syncany.operations.daemon.messages.UpStartSyncExternalEvent@1ae5e321) ...
27-4-17 11:01:41.519 | DatabaseConnect | Test worker    | INFO : Found 12 tables.
27-4-17 11:01:41.519 | StatusOperation | Test worker    | INFO : 
27-4-17 11:01:41.519 | StatusOperation | Test worker    | INFO : Running 'Status' at client A ...
27-4-17 11:01:41.519 | StatusOperation | Test worker    | INFO : --------------------------------------------
27-4-17 11:01:41.519 | StatusOperation | Test worker    | INFO : Querying current file tree from database ...
27-4-17 11:01:41.519 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event StatusStartSyncExternalEvent (org.syncany.operations.daemon.messages.StatusStartSyncExternalEvent@50a832d2) ...
27-4-17 11:01:41.519 | StatusOperation | Test worker    | INFO : Analyzing local folder /tmp/syncanytest/syncany-170427110008054-78157-client-A ...
27-4-17 11:01:41.519 | StatusOperation | Test worker    | FINE : - Ignoring file (syncany app-related): .syncany
27-4-17 11:01:41.520 | StatusOperation | Test worker    | FINE : - New file: file3
27-4-17 11:01:41.520 | StatusOperation | Test worker    | FINE : - New file: file1
27-4-17 11:01:41.520 | StatusOperation | Test worker    | FINE : - New file: file2
27-4-17 11:01:41.520 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event StatusEndSyncExternalEvent (org.syncany.operations.daemon.messages.StatusEndSyncExternalEvent@5d8bdafc) ...
27-4-17 11:01:41.520 | AbstractTransfe | Test worker    | INFO : Looking for other running remote operations ...
27-4-17 11:01:41.520 | UnreliableLocal | Test worker    | INFO : Operation successful:     abs=23 rel=6 op=list list(ActionRemoteFile)
27-4-17 11:01:41.520 | UnreliableLocal | Test worker    | INFO : Operation successful:     abs=24 rel=10 op=connect connect
27-4-17 11:01:41.520 | UnreliableLocal | Test worker    | INFO : Operation successful:     abs=25 rel=7 op=list list(TransactionRemoteFile)
27-4-17 11:01:41.520 | UnreliableLocal | Test worker    | INFO : Operation successful:     abs=26 rel=11 op=connect connect
27-4-17 11:01:41.520 | UnreliableLocal | Test worker    | INFO : Operation successful:     abs=27 rel=1 op=download download(transaction-8d57e74, /tmp/syncanytest/syncany-170427110008054-78157-client-A/.syncany/cache/temp-transaction-3643861204728833543.tmp)
27-4-17 11:01:41.521 | UnreliableLocal | Test worker    | INFO : Operation successful:     abs=28 rel=12 op=connect connect
27-4-17 11:01:41.533 | DatabaseConnect | Test worker    | INFO : Found 12 tables.
27-4-17 11:01:41.533 | LsRemoteOperati | Test worker    | INFO : 
27-4-17 11:01:41.533 | LsRemoteOperati | Test worker    | INFO : Running 'Remote Status' at client A ...
27-4-17 11:01:41.533 | LsRemoteOperati | Test worker    | INFO : --------------------------------------------
27-4-17 11:01:41.533 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event LsRemoteStartSyncExternalEvent (org.syncany.operations.daemon.messages.LsRemoteStartSyncExternalEvent@6f7149ef) ...
27-4-17 11:01:41.533 | LsRemoteOperati | Test worker    | INFO : Retrieving remote database list.
27-4-17 11:01:41.533 | UnreliableLocal | Test worker    | INFO : Operation successful:     abs=29 rel=8 op=list list(DatabaseRemoteFile)
27-4-17 11:01:41.533 | UnreliableLocal | Test worker    | INFO : Operation successful:     abs=30 rel=13 op=connect connect
27-4-17 11:01:41.541 | UnreliableLocal | Test worker    | INFO : Operation successful:     abs=31 rel=9 op=list list(TransactionRemoteFile)
27-4-17 11:01:41.541 | UnreliableLocal | Test worker    | INFO : Operation successful:     abs=32 rel=14 op=connect connect
27-4-17 11:01:41.541 | UnreliableLocal | Test worker    | INFO : Operation successful:     abs=33 rel=2 op=download download(transaction-8d57e74, /tmp/syncanytest/syncany-170427110008054-78157-client-A/.syncany/cache/temp-transaction-3157144760676354181.tmp)
27-4-17 11:01:41.541 | UnreliableLocal | Test worker    | INFO : Operation successful:     abs=34 rel=15 op=connect connect
27-4-17 11:01:41.548 | UnreliableLocal | Test worker    | INFO : Operation successful:     abs=35 rel=2 op=disconnect disconnect
27-4-17 11:01:41.548 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event LsRemoteEndSyncExternalEvent (org.syncany.operations.daemon.messages.LsRemoteEndSyncExternalEvent@25a17f77) ...
27-4-17 11:01:41.548 | UpOperation     | Test worker    | INFO : No remote changes, ready to upload.
27-4-17 11:01:41.548 | ActionFileHandl | Test worker    | INFO : Starting action for RemoteFile[name=action-up-A-1493290901510] ...
27-4-17 11:01:41.548 | ActionFileHandl | Test worker    | INFO : Uploading action file: RemoteFile[name=action-up-A-1493290901510]
27-4-17 11:01:41.549 | UnreliableLocal | Test worker    | INFO : Operation successful:     abs=36 rel=7 op=upload upload(/tmp/syncany-action-1116991081554506317.tmp, action-up-A-1493290901510)
27-4-17 11:01:41.549 | UnreliableLocal | Test worker    | INFO : Operation successful:     abs=37 rel=16 op=connect connect
27-4-17 11:01:41.549 | ActionFileHandl | Test worker    | INFO : Scheduling action renewal task for every 2 minutes, for RemoteFile[name=action-up-A-1493290901510] ...
27-4-17 11:01:41.560 | UpOperation     | Test worker    | INFO : Found local transaction to resume.
27-4-17 11:01:41.560 | UpOperation     | Test worker    | INFO : Attempting to find transactionRemoteFile
27-4-17 11:01:41.586 | DatabaseXmlSeri | Test worker    | INFO : - Loading database (FULL) from file /tmp/syncanytest/syncany-170427110008054-78157-client-A/.syncany/state/transaction-database.0000000001.xml ...
27-4-17 11:01:41.587 | DatabaseXmlPars | Test worker    | INFO :    + Added database version A/(A1)/T=1493290895476
27-4-17 11:01:41.590 | DatabaseXmlSeri | Test worker    | INFO : - Loading database (FULL) from file /tmp/syncanytest/syncany-170427110008054-78157-client-A/.syncany/state/transaction-database.0000000002.xml ...
27-4-17 11:01:41.591 | DatabaseXmlPars | Test worker    | INFO :    + Added database version A/(A2)/T=1493290901488
27-4-17 11:01:41.593 | DatabaseXmlSeri | Test worker    | INFO : - Loading database (FULL) from file /tmp/syncanytest/syncany-170427110008054-78157-client-A/.syncany/state/transaction-database.0000000003.xml ...
27-4-17 11:01:41.595 | DatabaseXmlPars | Test worker    | INFO :    + Added database version A/(A3)/T=1493290901491
27-4-17 11:01:41.595 | UnreliableLocal | Test worker    | INFO : Operation successful:     abs=38 rel=10 op=list list(TransactionRemoteFile)
27-4-17 11:01:41.595 | UnreliableLocal | Test worker    | INFO : Operation successful:     abs=39 rel=17 op=connect connect
27-4-17 11:01:41.595 | UnreliableLocal | Test worker    | INFO : Operation successful:     abs=40 rel=3 op=download download(transaction-8d57e74, /tmp/syncanytest/syncany-170427110008054-78157-client-A/.syncany/cache/temp-transaction-1438616261022926561.tmp)
27-4-17 11:01:41.596 | UnreliableLocal | Test worker    | INFO : Operation successful:     abs=41 rel=18 op=connect connect
27-4-17 11:01:41.609 | UpOperation     | Test worker    | INFO : No DIRTY data found in database (no dirty databases); Nothing to do here.
27-4-17 11:01:41.609 | UpOperation     | Test worker    | INFO : Uploading database: DatabaseVersion [header=A/(A1)/T=1493290895476, chunks=10, multiChunks=2, fileContents=1, fileHistories=1]
27-4-17 11:01:41.609 | UpOperation     | Test worker    | INFO : Saving local delta database, version A/(A1)/T=1493290895476 to file /tmp/syncanytest/syncany-170427110008054-78157-client-A/.syncany/cache/database-A-0000000001 ... 
27-4-17 11:01:41.609 | UpOperation     | Test worker    | INFO : - Saving database to /tmp/syncanytest/syncany-170427110008054-78157-client-A/.syncany/cache/database-A-0000000001 ...
27-4-17 11:01:41.611 | UpOperation     | Test worker    | INFO : Adding shutdown hook (to allow resuming the upload) ...
27-4-17 11:01:41.611 | RemoteTransacti | Test worker    | INFO : - Starting to upload data in commit.
27-4-17 11:01:41.611 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event UpUploadFileInTransactionSyncExternalEvent (org.syncany.operations.daemon.messages.UpUploadFileInTransactionSyncExternalEvent@5e01f347) ...
27-4-17 11:01:41.611 | RemoteTransacti | Test worker    | INFO : - Uploading /tmp/syncanytest/syncany-170427110008054-78157-client-A/.syncany/cache/multichunk-4fc95c9d71dd5fcb829e448842429d6c0a4e931d to temp. file RemoteFile[name=temp-vfOMg-multichunk-4fc95c9d71dd5fcb829e448842429d6c0a4e931d] ...
27-4-17 11:01:41.613 | UnreliableLocal | Test worker    | INFO : Operation successful:     abs=42 rel=8 op=upload upload(/tmp/syncanytest/syncany-170427110008054-78157-client-A/.syncany/cache/multichunk-4fc95c9d71dd5fcb829e448842429d6c0a4e931d, temp-vfOMg-multichunk-4fc95c9d71dd5fcb829e448842429d6c0a4e931d)
27-4-17 11:01:41.613 | UnreliableLocal | Test worker    | INFO : Operation successful:     abs=43 rel=19 op=connect connect
27-4-17 11:01:41.615 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event UpUploadFileInTransactionSyncExternalEvent (org.syncany.operations.daemon.messages.UpUploadFileInTransactionSyncExternalEvent@65c1bdc5) ...
27-4-17 11:01:41.615 | RemoteTransacti | Test worker    | INFO : - Uploading /tmp/syncanytest/syncany-170427110008054-78157-client-A/.syncany/cache/database-A-0000000001 to temp. file RemoteFile[name=temp-kfgeR-database-A-0000000001] ...
27-4-17 11:01:41.615 | UnreliableLocal | Test worker    | INFO : Operation successful:     abs=44 rel=9 op=upload upload(/tmp/syncanytest/syncany-170427110008054-78157-client-A/.syncany/cache/database-A-0000000001, temp-kfgeR-database-A-0000000001)
27-4-17 11:01:41.615 | UnreliableLocal | Test worker    | INFO : Operation successful:     abs=45 rel=20 op=connect connect
27-4-17 11:01:41.616 | RemoteTransacti | Test worker    | INFO : - Moving temp. file RemoteFile[name=temp-ZVNHT-multichunk-8f856026b7c2969cae8b823d29be9ff687c81dcd] to final location RemoteFile[name=multichunk-8f856026b7c2969cae8b823d29be9ff687c81dcd] ...
27-4-17 11:01:41.617 | UnreliableLocal | Test worker    | INFO : Operation successful:     abs=46 rel=1 op=move move(temp-ZVNHT-multichunk-8f856026b7c2969cae8b823d29be9ff687c81dcd,multichunk-8f856026b7c2969cae8b823d29be9ff687c81dcd)
27-4-17 11:01:41.617 | UnreliableLocal | Test worker    | INFO : Operation successful:     abs=47 rel=21 op=connect connect
27-4-17 11:01:41.618 | RemoteTransacti | Test worker    | INFO : - Moving temp. file RemoteFile[name=temp-vfOMg-multichunk-4fc95c9d71dd5fcb829e448842429d6c0a4e931d] to final location RemoteFile[name=multichunk-4fc95c9d71dd5fcb829e448842429d6c0a4e931d] ...
27-4-17 11:01:41.618 | UnreliableLocal | Test worker    | INFO : Operation successful:     abs=48 rel=2 op=move move(temp-vfOMg-multichunk-4fc95c9d71dd5fcb829e448842429d6c0a4e931d,multichunk-4fc95c9d71dd5fcb829e448842429d6c0a4e931d)
27-4-17 11:01:41.618 | UnreliableLocal | Test worker    | INFO : Operation successful:     abs=49 rel=22 op=connect connect
27-4-17 11:01:41.618 | RemoteTransacti | Test worker    | INFO : - Moving temp. file RemoteFile[name=temp-kfgeR-database-A-0000000001] to final location RemoteFile[name=database-A-0000000001] ...
27-4-17 11:01:41.618 | UnreliableLocal | Test worker    | INFO : Operation successful:     abs=50 rel=3 op=move move(temp-kfgeR-database-A-0000000001,database-A-0000000001)
27-4-17 11:01:41.618 | UnreliableLocal | Test worker    | INFO : Operation successful:     abs=51 rel=23 op=connect connect
27-4-17 11:01:41.619 | RemoteTransacti | Test worker    | INFO : - Deleting remote transaction file RemoteFile[name=transaction-8d57e74] ...
27-4-17 11:01:41.619 | UnreliableLocal | Test worker    | INFO : Operation successful:     abs=52 rel=1 op=delete delete(transaction-8d57e74)
27-4-17 11:01:41.619 | UnreliableLocal | Test worker    | INFO : Operation successful:     abs=53 rel=24 op=connect connect
27-4-17 11:01:41.619 | RemoteTransacti | Test worker    | INFO : END of TX.commmit(): Succesfully committed transaction.
27-4-17 11:01:41.619 | RemoteTransacti | Test worker    | INFO : END of TX.delTemp(): Sucessfully deleted final files.
27-4-17 11:01:41.619 | UpOperation     | Test worker    | INFO : Persisting local SQL database (new database version A/(A1)/T=1493290895476) ...
27-4-17 11:01:41.626 | UpOperation     | Test worker    | INFO : Removing DIRTY database versions from database ...
27-4-17 11:01:41.632 | UpOperation     | Test worker    | INFO : Adding database version to result changes:DatabaseVersion [header=A/(A1)/T=1493290895476, chunks=10, multiChunks=2, fileContents=1, fileHistories=1]
27-4-17 11:01:41.632 | UpOperation     | Test worker    | INFO : Committing local database.
27-4-17 11:01:41.632 | UpOperation     | Test worker    | FINE : Waiting for new database version.
27-4-17 11:01:41.632 | UpOperation     | Test worker    | FINE : Took new database version: DatabaseVersion [header=A/(A2)/T=1493290901488, chunks=1, multiChunks=1, fileContents=1, fileHistories=1]
27-4-17 11:01:41.632 | UpOperation     | Test worker    | INFO : Uploading database: DatabaseVersion [header=A/(A2)/T=1493290901488, chunks=1, multiChunks=1, fileContents=1, fileHistories=1]
27-4-17 11:01:41.633 | UpOperation     | Test worker    | INFO : Saving local delta database, version A/(A2)/T=1493290901488 to file /tmp/syncanytest/syncany-170427110008054-78157-client-A/.syncany/cache/database-A-0000000002 ... 
27-4-17 11:01:41.633 | UpOperation     | Test worker    | INFO : - Saving database to /tmp/syncanytest/syncany-170427110008054-78157-client-A/.syncany/cache/database-A-0000000002 ...
27-4-17 11:01:41.634 | UpOperation     | Test worker    | INFO : Adding shutdown hook (to allow resuming the upload) ...
27-4-17 11:01:41.634 | RemoteTransacti | Test worker    | INFO : Starting TX.commit() ...
27-4-17 11:01:41.635 | RemoteTransacti | Test worker    | INFO : Wrote transaction manifest to temporary file: /tmp/syncanytest/syncany-170427110008054-78157-client-A/.syncany/cache/temp-transaction-4548067472839937444.tmp
27-4-17 11:01:41.635 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event UpUploadFileSyncExternalEvent (org.syncany.operations.daemon.messages.UpUploadFileSyncExternalEvent@4c1f39ec) ...
27-4-17 11:01:41.635 | RemoteTransacti | Test worker    | INFO : - Uploading remote transaction file RemoteFile[name=transaction-208ea1a5] ...
27-4-17 11:01:41.636 | UnreliableLocal | Test worker    | INFO : Operation successful:     abs=54 rel=10 op=upload upload(/tmp/syncanytest/syncany-170427110008054-78157-client-A/.syncany/cache/temp-transaction-4548067472839937444.tmp, transaction-208ea1a5)
27-4-17 11:01:41.636 | UnreliableLocal | Test worker    | INFO : Operation successful:     abs=55 rel=25 op=connect connect
27-4-17 11:01:41.636 | RemoteTransacti | Test worker    | INFO : - Starting to upload data in commit.
27-4-17 11:01:41.636 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event UpUploadFileInTransactionSyncExternalEvent (org.syncany.operations.daemon.messages.UpUploadFileInTransactionSyncExternalEvent@291a99b1) ...
27-4-17 11:01:41.636 | RemoteTransacti | Test worker    | INFO : - Uploading /tmp/syncanytest/syncany-170427110008054-78157-client-A/.syncany/cache/multichunk-63d5205e70db891b18e76c3fddde03bd0d7f2775 to temp. file RemoteFile[name=temp-yiScY-multichunk-63d5205e70db891b18e76c3fddde03bd0d7f2775] ...
27-4-17 11:01:41.636 | UnreliableLocal | Test worker    | INFO : Operation successful:     abs=56 rel=11 op=upload upload(/tmp/syncanytest/syncany-170427110008054-78157-client-A/.syncany/cache/multichunk-63d5205e70db891b18e76c3fddde03bd0d7f2775, temp-yiScY-multichunk-63d5205e70db891b18e76c3fddde03bd0d7f2775)
27-4-17 11:01:41.636 | UnreliableLocal | Test worker    | INFO : Operation successful:     abs=57 rel=26 op=connect connect
27-4-17 11:01:41.637 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event UpUploadFileInTransactionSyncExternalEvent (org.syncany.operations.daemon.messages.UpUploadFileInTransactionSyncExternalEvent@1b991013) ...
27-4-17 11:01:41.637 | RemoteTransacti | Test worker    | INFO : - Uploading /tmp/syncanytest/syncany-170427110008054-78157-client-A/.syncany/cache/database-A-0000000002 to temp. file RemoteFile[name=temp-oLqml-database-A-0000000002] ...
27-4-17 11:01:41.637 | UnreliableLocal | Test worker    | INFO : Operation successful:     abs=58 rel=12 op=upload upload(/tmp/syncanytest/syncany-170427110008054-78157-client-A/.syncany/cache/database-A-0000000002, temp-oLqml-database-A-0000000002)
27-4-17 11:01:41.637 | UnreliableLocal | Test worker    | INFO : Operation successful:     abs=59 rel=27 op=connect connect
27-4-17 11:01:41.637 | RemoteTransacti | Test worker    | INFO : - Moving temp. file RemoteFile[name=temp-yiScY-multichunk-63d5205e70db891b18e76c3fddde03bd0d7f2775] to final location RemoteFile[name=multichunk-63d5205e70db891b18e76c3fddde03bd0d7f2775] ...
27-4-17 11:01:41.638 | UnreliableLocal | Test worker    | INFO : Operation successful:     abs=60 rel=4 op=move move(temp-yiScY-multichunk-63d5205e70db891b18e76c3fddde03bd0d7f2775,multichunk-63d5205e70db891b18e76c3fddde03bd0d7f2775)
27-4-17 11:01:41.638 | UnreliableLocal | Test worker    | INFO : Operation successful:     abs=61 rel=28 op=connect connect
27-4-17 11:01:41.638 | RemoteTransacti | Test worker    | INFO : - Moving temp. file RemoteFile[name=temp-oLqml-database-A-0000000002] to final location RemoteFile[name=database-A-0000000002] ...
27-4-17 11:01:41.638 | UnreliableLocal | Test worker    | INFO : Operation successful:     abs=62 rel=5 op=move move(temp-oLqml-database-A-0000000002,database-A-0000000002)
27-4-17 11:01:41.638 | UnreliableLocal | Test worker    | INFO : Operation successful:     abs=63 rel=29 op=connect connect
27-4-17 11:01:41.638 | RemoteTransacti | Test worker    | INFO : - Deleting remote transaction file RemoteFile[name=transaction-208ea1a5] ...
27-4-17 11:01:41.638 | UnreliableLocal | Test worker    | INFO : Operation successful:     abs=64 rel=2 op=delete delete(transaction-208ea1a5)
27-4-17 11:01:41.638 | UnreliableLocal | Test worker    | INFO : Operation successful:     abs=65 rel=30 op=connect connect
27-4-17 11:01:41.638 | RemoteTransacti | Test worker    | INFO : END of TX.commmit(): Succesfully committed transaction.
27-4-17 11:01:41.638 | RemoteTransacti | Test worker    | INFO : END of TX.delTemp(): Sucessfully deleted final files.
27-4-17 11:01:41.638 | UpOperation     | Test worker    | INFO : Persisting local SQL database (new database version A/(A2)/T=1493290901488) ...
27-4-17 11:01:41.641 | UpOperation     | Test worker    | INFO : Removing DIRTY database versions from database ...
27-4-17 11:01:41.649 | UpOperation     | Test worker    | INFO : Adding database version to result changes:DatabaseVersion [header=A/(A2)/T=1493290901488, chunks=1, multiChunks=1, fileContents=1, fileHistories=1]
27-4-17 11:01:41.650 | UpOperation     | Test worker    | INFO : Committing local database.
27-4-17 11:01:41.650 | UpOperation     | Test worker    | FINE : Waiting for new database version.
27-4-17 11:01:41.650 | UpOperation     | Test worker    | FINE : Took new database version: DatabaseVersion [header=A/(A3)/T=1493290901491, chunks=1, multiChunks=1, fileContents=1, fileHistories=1]
27-4-17 11:01:41.650 | UpOperation     | Test worker    | INFO : Uploading database: DatabaseVersion [header=A/(A3)/T=1493290901491, chunks=1, multiChunks=1, fileContents=1, fileHistories=1]
27-4-17 11:01:41.650 | UpOperation     | Test worker    | INFO : Saving local delta database, version A/(A3)/T=1493290901491 to file /tmp/syncanytest/syncany-170427110008054-78157-client-A/.syncany/cache/database-A-0000000003 ... 
27-4-17 11:01:41.650 | UpOperation     | Test worker    | INFO : - Saving database to /tmp/syncanytest/syncany-170427110008054-78157-client-A/.syncany/cache/database-A-0000000003 ...
27-4-17 11:01:41.652 | UpOperation     | Test worker    | INFO : Adding shutdown hook (to allow resuming the upload) ...
27-4-17 11:01:41.652 | RemoteTransacti | Test worker    | INFO : Starting TX.commit() ...
27-4-17 11:01:41.653 | RemoteTransacti | Test worker    | INFO : Wrote transaction manifest to temporary file: /tmp/syncanytest/syncany-170427110008054-78157-client-A/.syncany/cache/temp-transaction-1977680869698199001.tmp
27-4-17 11:01:41.653 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event UpUploadFileSyncExternalEvent (org.syncany.operations.daemon.messages.UpUploadFileSyncExternalEvent@21a8c110) ...
27-4-17 11:01:41.654 | RemoteTransacti | Test worker    | INFO : - Uploading remote transaction file RemoteFile[name=transaction-3069b22e] ...
27-4-17 11:01:41.654 | UnreliableLocal | Test worker    | INFO : Operation successful:     abs=66 rel=13 op=upload upload(/tmp/syncanytest/syncany-170427110008054-78157-client-A/.syncany/cache/temp-transaction-1977680869698199001.tmp, transaction-3069b22e)
27-4-17 11:01:41.657 | UnreliableLocal | Test worker    | INFO : Operation successful:     abs=67 rel=31 op=connect connect
27-4-17 11:01:41.658 | RemoteTransacti | Test worker    | INFO : - Starting to upload data in commit.
27-4-17 11:01:41.658 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event UpUploadFileInTransactionSyncExternalEvent (org.syncany.operations.daemon.messages.UpUploadFileInTransactionSyncExternalEvent@4155eef6) ...
27-4-17 11:01:41.658 | RemoteTransacti | Test worker    | INFO : - Uploading /tmp/syncanytest/syncany-170427110008054-78157-client-A/.syncany/cache/multichunk-d377f1cbaac54b28b25a921bd5483251726819be to temp. file RemoteFile[name=temp-nhIQM-multichunk-d377f1cbaac54b28b25a921bd5483251726819be] ...
27-4-17 11:01:41.658 | UnreliableLocal | Test worker    | INFO : Operation successful:     abs=68 rel=14 op=upload upload(/tmp/syncanytest/syncany-170427110008054-78157-client-A/.syncany/cache/multichunk-d377f1cbaac54b28b25a921bd5483251726819be, temp-nhIQM-multichunk-d377f1cbaac54b28b25a921bd5483251726819be)
27-4-17 11:01:41.659 | UnreliableLocal | Test worker    | INFO : Operation successful:     abs=69 rel=32 op=connect connect
27-4-17 11:01:41.659 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event UpUploadFileInTransactionSyncExternalEvent (org.syncany.operations.daemon.messages.UpUploadFileInTransactionSyncExternalEvent@7e4bf002) ...
27-4-17 11:01:41.659 | RemoteTransacti | Test worker    | INFO : - Uploading /tmp/syncanytest/syncany-170427110008054-78157-client-A/.syncany/cache/database-A-0000000003 to temp. file RemoteFile[name=temp-ultwY-database-A-0000000003] ...
27-4-17 11:01:41.659 | UnreliableLocal | Test worker    | INFO : Operation successful:     abs=70 rel=15 op=upload upload(/tmp/syncanytest/syncany-170427110008054-78157-client-A/.syncany/cache/database-A-0000000003, temp-ultwY-database-A-0000000003)
27-4-17 11:01:41.659 | UnreliableLocal | Test worker    | INFO : Operation successful:     abs=71 rel=33 op=connect connect
27-4-17 11:01:41.660 | RemoteTransacti | Test worker    | INFO : - Moving temp. file RemoteFile[name=temp-nhIQM-multichunk-d377f1cbaac54b28b25a921bd5483251726819be] to final location RemoteFile[name=multichunk-d377f1cbaac54b28b25a921bd5483251726819be] ...
27-4-17 11:01:41.660 | UnreliableLocal | Test worker    | INFO : Operation successful:     abs=72 rel=6 op=move move(temp-nhIQM-multichunk-d377f1cbaac54b28b25a921bd5483251726819be,multichunk-d377f1cbaac54b28b25a921bd5483251726819be)
27-4-17 11:01:41.660 | UnreliableLocal | Test worker    | INFO : Operation successful:     abs=73 rel=34 op=connect connect
27-4-17 11:01:41.660 | RemoteTransacti | Test worker    | INFO : - Moving temp. file RemoteFile[name=temp-ultwY-database-A-0000000003] to final location RemoteFile[name=database-A-0000000003] ...
27-4-17 11:01:41.660 | UnreliableLocal | Test worker    | INFO : Operation successful:     abs=74 rel=7 op=move move(temp-ultwY-database-A-0000000003,database-A-0000000003)
27-4-17 11:01:41.660 | UnreliableLocal | Test worker    | INFO : Operation successful:     abs=75 rel=35 op=connect connect
27-4-17 11:01:41.661 | RemoteTransacti | Test worker    | INFO : - Deleting remote transaction file RemoteFile[name=transaction-3069b22e] ...
27-4-17 11:01:41.661 | UnreliableLocal | Test worker    | INFO : Operation successful:     abs=76 rel=3 op=delete delete(transaction-3069b22e)
27-4-17 11:01:41.661 | UnreliableLocal | Test worker    | INFO : Operation successful:     abs=77 rel=36 op=connect connect
27-4-17 11:01:41.661 | RemoteTransacti | Test worker    | INFO : END of TX.commmit(): Succesfully committed transaction.
27-4-17 11:01:41.661 | RemoteTransacti | Test worker    | INFO : END of TX.delTemp(): Sucessfully deleted final files.
27-4-17 11:01:41.661 | UpOperation     | Test worker    | INFO : Persisting local SQL database (new database version A/(A3)/T=1493290901491) ...
27-4-17 11:01:41.663 | UpOperation     | Test worker    | INFO : Removing DIRTY database versions from database ...
27-4-17 11:01:41.671 | UpOperation     | Test worker    | INFO : Adding database version to result changes:DatabaseVersion [header=A/(A3)/T=1493290901491, chunks=1, multiChunks=1, fileContents=1, fileHistories=1]
27-4-17 11:01:41.672 | UpOperation     | Test worker    | INFO : Committing local database.
27-4-17 11:01:41.672 | UpOperation     | Test worker    | FINE : Waiting for new database version.
27-4-17 11:01:41.672 | UpOperation     | Test worker    | FINE : Took new database version: DatabaseVersion [header=UnknownMachine/()/T=1493290901595, chunks=0, multiChunks=0, fileContents=0, fileHistories=0]
27-4-17 11:01:41.672 | UpOperation     | Test worker    | INFO : Sync up done.
27-4-17 11:01:41.675 | ActionFileHandl | Test worker    | INFO : Finishing action for RemoteFile[name=action-up-A-1493290901510] ...
27-4-17 11:01:41.679 | ActionFileHandl | Test worker    | INFO : Deleting action file: RemoteFile[name=action-up-A-1493290901510]
27-4-17 11:01:41.679 | UnreliableLocal | Test worker    | INFO : Operation successful:     abs=78 rel=4 op=delete delete(action-up-A-1493290901510)
27-4-17 11:01:41.679 | UnreliableLocal | Test worker    | INFO : Operation successful:     abs=79 rel=37 op=connect connect
27-4-17 11:01:41.679 | AbstractTransfe | Test worker    | INFO : Cleaning own old action files ...
27-4-17 11:01:41.679 | UnreliableLocal | Test worker    | INFO : Operation successful:     abs=80 rel=11 op=list list(ActionRemoteFile)
27-4-17 11:01:41.679 | UnreliableLocal | Test worker    | INFO : Operation successful:     abs=81 rel=38 op=connect connect
27-4-17 11:01:41.679 | UnreliableLocal | Test worker    | INFO : Operation successful:     abs=82 rel=12 op=list list(TransactionRemoteFile)
27-4-17 11:01:41.679 | UnreliableLocal | Test worker    | INFO : Operation successful:     abs=83 rel=39 op=connect connect
27-4-17 11:01:41.679 | AbstractTransfe | Test worker    | INFO : - Deleting own action file RemoteFile[name=action-up-A-1493290895284] ...
27-4-17 11:01:41.679 | UnreliableLocal | Test worker    | INFO : Operation successful:     abs=84 rel=5 op=delete delete(action-up-A-1493290895284)
27-4-17 11:01:41.679 | UnreliableLocal | Test worker    | INFO : Operation successful:     abs=85 rel=40 op=connect connect
27-4-17 11:01:41.679 | UnreliableLocal | Test worker    | INFO : Operation successful:     abs=86 rel=3 op=disconnect disconnect
27-4-17 11:01:41.679 | Cache           | Test worker    | INFO : Cache size okay (5131 KB), no need to clean (keep size is 500 MB)
27-4-17 11:01:41.679 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event UpEndSyncExternalEvent (org.syncany.operations.daemon.messages.UpEndSyncExternalEvent@1f3ec71d) ...