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

1

tests

0

failures

0

ignored

7.097s

duration

100%

successful

Tests

Test Duration Result
testUpFailsOnFirstTransaction 7.097s passed

Standard error

29-5-19 15:05:32.029 | org.syncany.plu | Test worker    | FINE : Validating required fields
29-5-19 15:05:32.030 | UnreliableLocal | Test worker    | INFO : Operation successful:     abs=1 rel=1 op=init init
29-5-19 15:05:32.030 | UnreliableLocal | Test worker    | INFO : Operation successful:     abs=2 rel=1 op=connect connect
29-5-19 15:05:32.030 | LocalTransferMa | Test worker    | INFO : testTargetExists: Target exists.
29-5-19 15:05:32.111 | org.syncany.plu | Test worker    | FINE : Validating required fields
29-5-19 15:05:32.111 | TransferManager | Test worker    | INFO : Building UnreliableLocalTransferManager from config 'syncany-190529150308467-95774-client-A' ...
29-5-19 15:05:32.111 | TransferManager | Test worker    | INFO : - With feature ReadAfterWriteConsistent
29-5-19 15:05:32.111 | TransferManager | Test worker    | INFO : - With feature Retriable
29-5-19 15:05:32.111 | TransferManager | Test worker    | FINE : - Checking if selected features supported ...
29-5-19 15:05:32.111 | TransferManager | Test worker    | FINE : - Checking for duplicate features ...
29-5-19 15:05:32.111 | TransferManager | Test worker    | FINE : - Checking required feature TransactionAware in UnreliableLocalTransferManager ...
29-5-19 15:05:32.111 | TransferManager | Test worker    | FINE : - Checking required feature Retriable in UnreliableLocalTransferManager ...
29-5-19 15:05:32.111 | TransferManager | Test worker    | INFO : - SKIPPING unsupported optional feature ReadAfterWriteConsistent
29-5-19 15:05:32.111 | TransferManager | Test worker    | FINE : - Wrapping TransferManager UnreliableLocalTransferManager in RetriableFeatureTransferManager
29-5-19 15:05:32.111 | org.syncany.plu | Test worker    | FINE : Validating required fields
29-5-19 15:05:32.111 | TransferManager | Test worker    | INFO : Building UnreliableLocalTransferManager from config 'syncany-190529150308467-95774-client-A' ...
29-5-19 15:05:32.111 | TransferManager | Test worker    | INFO : - With feature ReadAfterWriteConsistent
29-5-19 15:05:32.112 | TransferManager | Test worker    | INFO : - With feature Retriable
29-5-19 15:05:32.112 | TransferManager | Test worker    | INFO : - With feature PathAware
29-5-19 15:05:32.112 | TransferManager | Test worker    | INFO : - With feature TransactionAware
29-5-19 15:05:32.112 | TransferManager | Test worker    | FINE : - Checking if selected features supported ...
29-5-19 15:05:32.113 | TransferManager | Test worker    | FINE : - Checking for duplicate features ...
29-5-19 15:05:32.113 | TransferManager | Test worker    | FINE : - Checking required feature TransactionAware in UnreliableLocalTransferManager ...
29-5-19 15:05:32.113 | TransferManager | Test worker    | FINE : - Checking required feature Retriable in UnreliableLocalTransferManager ...
29-5-19 15:05:32.113 | TransferManager | Test worker    | INFO : - SKIPPING unsupported optional feature ReadAfterWriteConsistent
29-5-19 15:05:32.113 | TransferManager | Test worker    | FINE : - Wrapping TransferManager UnreliableLocalTransferManager in RetriableFeatureTransferManager
29-5-19 15:05:32.113 | TransferManager | Test worker    | INFO : - SKIPPING unsupported optional feature PathAware
29-5-19 15:05:32.113 | TransferManager | Test worker    | FINE : - Wrapping TransferManager RetriableFeatureTransferManager in TransactionAwareFeatureTransferManager
29-5-19 15:05:32.167 | hsqldb.db.HSQLD | Test worker    | INFO : Checkpoint start
29-5-19 15:05:32.167 | hsqldb.db.HSQLD | Test worker    | INFO : checkpointClose start
29-5-19 15:05:32.176 | hsqldb.db.HSQLD | Test worker    | FINE : log close size: 0
29-5-19 15:05:32.179 | hsqldb.db.HSQLD | Test worker    | INFO : checkpointClose end
29-5-19 15:05:32.180 | hsqldb.db.HSQLD | Test worker    | INFO : Checkpoint end - txts: 1
29-5-19 15:05:32.185 | DatabaseConnect | Test worker    | INFO : Found 0 tables.
29-5-19 15:05:32.185 | DatabaseConnect | Test worker    | INFO : Database has no tables. Creating tables from script.create.all.sql
29-5-19 15:05:32.185 | SqlRunner       | Test worker    | INFO : SQL (comment): -- Tables
29-5-19 15:05:32.185 | SqlRunner       | Test worker    | INFO : SQL:  CREATE CACHED TABLE IF NOT EXISTS databaseversion ( id int NOT NULL IDENTITY, status varchar(45) NOT NULL, localtime datetime NOT NULL, client varchar(45) NOT NULL, vectorclock_serialized varchar(1024) NOT NULL, UNIQUE (vectorclock_serialized) ); 
29-5-19 15:05:32.188 | SqlRunner       | Test worker    | INFO : SQL:  CREATE CACHED TABLE IF NOT EXISTS  chunk ( checksum varchar(40) NOT NULL, databaseversion_id int NOT NULL, size bigint NOT NULL, PRIMARY KEY (checksum), FOREIGN KEY (databaseversion_id) REFERENCES databaseversion (id) ON DELETE NO ACTION ON UPDATE NO ACTION ); 
29-5-19 15:05:32.190 | SqlRunner       | Test worker    | INFO : SQL:  CREATE CACHED TABLE IF NOT EXISTS  databaseversion_vectorclock ( databaseversion_id int NOT NULL, client varchar(45) NOT NULL, logicaltime int NOT NULL, PRIMARY KEY (databaseversion_id, client), FOREIGN KEY (databaseversion_id) REFERENCES databaseversion (id) ON DELETE NO ACTION ON UPDATE NO ACTION ); 
29-5-19 15:05:32.194 | SqlRunner       | Test worker    | INFO : SQL:  CREATE CACHED TABLE IF NOT EXISTS  filecontent ( checksum varchar(40) NOT NULL, databaseversion_id int NOT NULL, size bigint NOT NULL, PRIMARY KEY (checksum), FOREIGN KEY (databaseversion_id) REFERENCES databaseversion (id) ON DELETE NO ACTION ON UPDATE NO ACTION ); 
29-5-19 15:05:32.196 | SqlRunner       | Test worker    | INFO : SQL:  CREATE CACHED TABLE IF NOT EXISTS  filecontent_chunk ( filecontent_checksum varchar(40) NOT NULL, chunk_checksum varchar(40) NOT NULL, num int NOT NULL, PRIMARY KEY (filecontent_checksum, chunk_checksum, num), FOREIGN KEY (filecontent_checksum) REFERENCES filecontent (checksum) ON DELETE NO ACTION ON UPDATE NO ACTION, FOREIGN KEY (chunk_checksum) REFERENCES chunk (checksum) ON DELETE NO ACTION ON UPDATE NO ACTION ); 
29-5-19 15:05:32.198 | SqlRunner       | Test worker    | INFO : SQL:  CREATE CACHED TABLE IF NOT EXISTS  filehistory ( id varchar(40) NOT NULL, databaseversion_id int NOT NULL, PRIMARY KEY (id, databaseversion_id), FOREIGN KEY (databaseversion_id) REFERENCES databaseversion (id) ON DELETE NO ACTION ON UPDATE NO ACTION ); 
29-5-19 15:05:32.200 | SqlRunner       | Test worker    | INFO : SQL:  CREATE CACHED TABLE IF NOT EXISTS  fileversion ( filehistory_id varchar(40) NOT NULL, version int NOT NULL, databaseversion_id int NOT NULL, path varchar(1024) NOT NULL, type varchar(45) NOT NULL, status varchar(45) NOT NULL, size bigint NOT NULL, lastmodified datetime NOT NULL, linktarget varchar(1024), filecontent_checksum varchar(40) DEFAULT NULL, updated datetime NOT NULL, posixperms varchar(45) DEFAULT NULL, dosattrs varchar(45) DEFAULT NULL, PRIMARY KEY (filehistory_id, version, databaseversion_id), FOREIGN KEY (filehistory_id, databaseversion_id) REFERENCES filehistory (id, databaseversion_id) ON DELETE NO ACTION ON UPDATE NO ACTION, FOREIGN KEY (filecontent_checksum) REFERENCES filecontent (checksum) ON DELETE NO ACTION ON UPDATE NO ACTION ); 
29-5-19 15:05:32.204 | SqlRunner       | Test worker    | INFO : SQL:  CREATE CACHED TABLE IF NOT EXISTS  multichunk ( id varchar(40) NOT NULL, databaseversion_id int NOT NULL, size bigint NOT NULL, PRIMARY KEY (id), FOREIGN KEY (databaseversion_id) REFERENCES databaseversion (id) ON DELETE NO ACTION ON UPDATE NO ACTION ); 
29-5-19 15:05:32.206 | SqlRunner       | Test worker    | INFO : SQL:  CREATE CACHED TABLE IF NOT EXISTS  multichunk_chunk ( multichunk_id varchar(40) NOT NULL, chunk_checksum varchar(40) NOT NULL, PRIMARY KEY (multichunk_id, chunk_checksum), FOREIGN KEY (multichunk_id) REFERENCES multichunk (id) ON DELETE NO ACTION ON UPDATE NO ACTION, FOREIGN KEY (chunk_checksum) REFERENCES chunk (checksum) ON DELETE NO ACTION ON UPDATE NO ACTION ); 
29-5-19 15:05:32.208 | SqlRunner       | Test worker    | INFO : SQL:  CREATE CACHED TABLE IF NOT EXISTS  multichunk_muddy ( id varchar(40) NOT NULL, machine_name varchar(255) NOT NULL, machine_version int NOT NULL, PRIMARY KEY (id) ); 
29-5-19 15:05:32.210 | SqlRunner       | Test worker    | INFO : SQL:  CREATE CACHED TABLE IF NOT EXISTS  known_databases ( id int NOT NULL IDENTITY, client varchar(45) NOT NULL, filenumber int NOT NULL, UNIQUE (client, filenumber) ); 
29-5-19 15:05:32.212 | SqlRunner       | Test worker    | INFO : SQL:  CREATE CACHED TABLE IF NOT EXISTS  general_settings ( key varchar(255) NOT NULL, value varchar(255) NOT NULL, PRIMARY KEY (key) ); 
29-5-19 15:05:32.214 | SqlRunner       | Test worker    | INFO : SQL (comment): -- Non-primary indices
29-5-19 15:05:32.214 | SqlRunner       | Test worker    | INFO : SQL:   CREATE INDEX idx_databaseversion_status ON databaseversion (status); 
29-5-19 15:05:32.215 | hsqldb.db.HSQLD | Test worker    | INFO : dataFileCache open start
29-5-19 15:05:32.220 | hsqldb.db.HSQLD | Test worker    | INFO : dataFileCache open end
29-5-19 15:05:32.225 | SqlRunner       | Test worker    | INFO : SQL: CREATE INDEX idx_databaseversion_vectorclock_serialized ON databaseversion (vectorclock_serialized); 
29-5-19 15:05:32.227 | SqlRunner       | Test worker    | INFO : SQL: CREATE INDEX idx_fileversion_path ON fileversion (path); 
29-5-19 15:05:32.230 | SqlRunner       | Test worker    | INFO : SQL: CREATE INDEX idx_fileversion_status ON fileversion (status); 
29-5-19 15:05:32.231 | SqlRunner       | Test worker    | INFO : SQL: CREATE INDEX idx_fileversion_filecontent_checksum ON fileversion (filecontent_checksum); 
29-5-19 15:05:32.234 | SqlRunner       | Test worker    | INFO : SQL (comment): -- Views
29-5-19 15:05:32.234 | SqlRunner       | Test worker    | INFO : SQL:    CREATE VIEW databaseversion_master AS SELECT dbv.*, vc.logicaltime as client_version FROM databaseversion dbv JOIN databaseversion_vectorclock vc on dbv.id=vc.databaseversion_id and dbv.client=vc.client WHERE dbv.status='MASTER'; 
29-5-19 15:05:32.236 | SqlRunner       | Test worker    | INFO : SQL:  CREATE VIEW fileversion_master AS SELECT fv0.* FROM fileversion fv0 JOIN databaseversion dbv ON fv0.databaseversion_id=dbv.id AND dbv.status='MASTER'; 
29-5-19 15:05:32.239 | SqlRunner       | Test worker    | INFO : SQL:  CREATE VIEW fileversion_master_maxversion AS SELECT DISTINCT filehistory_id, MAX(version) version FROM fileversion_master GROUP BY filehistory_id; 
29-5-19 15:05:32.241 | SqlRunner       | Test worker    | INFO : SQL:  CREATE VIEW fileversion_master_last AS SELECT fv.* FROM fileversion_master_maxversion fvmax JOIN fileversion_master fv ON fvmax.filehistory_id=fv.filehistory_id AND fvmax.version=fv.version WHERE fv.status<>'DELETED'; 
29-5-19 15:05:32.245 | SqlRunner       | Test worker    | INFO : SQL (comment): -- Full Views
29-5-19 15:05:32.245 | SqlRunner       | Test worker    | INFO : SQL:    create view filehistory_full as select dbv.status as databaseversion_status, dbv.localtime as databaseversion_localtime, dbv.client as databaseversion_client, dbv.vectorclock_serialized as databaseversion_vectorclock_serialized, fh.* from databaseversion dbv join filehistory fh on dbv.id=fh.databaseversion_id; 
29-5-19 15:05:32.247 | SqlRunner       | Test worker    | INFO : SQL:  create view fileversion_full as select fhf.databaseversion_status, fhf.databaseversion_localtime, fhf.databaseversion_client, fhf.databaseversion_vectorclock_serialized, fv.* from filehistory_full fhf join fileversion fv on fhf.id=fv.filehistory_id and fhf.databaseversion_id=fv.databaseversion_id; 
29-5-19 15:05:32.249 | SqlRunner       | Test worker    | INFO : SQL (comment): -- Functions
29-5-19 15:05:32.249 | SqlRunner       | Test worker    | INFO : SQL (new delimiter): end;
29-5-19 15:05:32.249 | SqlRunner       | Test worker    | INFO : SQL:     create function substr_count(haystack varchar(255), needle varchar(255)) returns integer begin atomic declare strCount integer; declare lastIndex integer;  set strCount = 0; set lastIndex = 1;  while lastIndex <> 0 do set lastIndex = locate(needle, haystack, lastIndex);  if lastIndex <> 0 then set strCount = strCount + 1; set lastIndex = lastIndex + length(needle); end if; end while;  return strCount; end; 
29-5-19 15:05:32.256 | UpOperation     | Test worker    | INFO : 
29-5-19 15:05:32.256 | UpOperation     | Test worker    | INFO : Running 'Sync up' at client A ...
29-5-19 15:05:32.256 | UpOperation     | Test worker    | INFO : --------------------------------------------
29-5-19 15:05:32.256 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event UpStartSyncExternalEvent (org.syncany.operations.daemon.messages.UpStartSyncExternalEvent@4a338ff1) ...
29-5-19 15:05:32.257 | DatabaseConnect | Test worker    | INFO : Found 12 tables.
29-5-19 15:05:32.258 | StatusOperation | Test worker    | INFO : 
29-5-19 15:05:32.258 | StatusOperation | Test worker    | INFO : Running 'Status' at client A ...
29-5-19 15:05:32.258 | StatusOperation | Test worker    | INFO : --------------------------------------------
29-5-19 15:05:32.258 | StatusOperation | Test worker    | INFO : Querying current file tree from database ...
29-5-19 15:05:32.258 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event StatusStartSyncExternalEvent (org.syncany.operations.daemon.messages.StatusStartSyncExternalEvent@5d6389e3) ...
29-5-19 15:05:32.259 | StatusOperation | Test worker    | INFO : Analyzing local folder /tmp/syncanytest/syncany-190529150308467-95774-client-A ...
29-5-19 15:05:32.259 | StatusOperation | Test worker    | FINE : - New file: file2
29-5-19 15:05:32.259 | StatusOperation | Test worker    | FINE : - New file: file3
29-5-19 15:05:32.259 | StatusOperation | Test worker    | FINE : - New file: file1
29-5-19 15:05:32.259 | StatusOperation | Test worker    | FINE : - Ignoring file (syncany app-related): .syncany
29-5-19 15:05:32.260 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event StatusEndSyncExternalEvent (org.syncany.operations.daemon.messages.StatusEndSyncExternalEvent@2a572115) ...
29-5-19 15:05:32.260 | AbstractTransfe | Test worker    | INFO : Looking for other running remote operations ...
29-5-19 15:05:32.260 | UnreliableLocal | Test worker    | INFO : Operation successful:     abs=3 rel=1 op=list list(ActionRemoteFile)
29-5-19 15:05:32.260 | UnreliableLocal | Test worker    | INFO : Operation successful:     abs=4 rel=2 op=connect connect
29-5-19 15:05:32.260 | UnreliableLocal | Test worker    | INFO : Operation successful:     abs=5 rel=2 op=list list(TransactionRemoteFile)
29-5-19 15:05:32.260 | UnreliableLocal | Test worker    | INFO : Operation successful:     abs=6 rel=3 op=connect connect
29-5-19 15:05:32.261 | DatabaseConnect | Test worker    | INFO : Found 12 tables.
29-5-19 15:05:32.262 | LsRemoteOperati | Test worker    | INFO : 
29-5-19 15:05:32.263 | LsRemoteOperati | Test worker    | INFO : Running 'Remote Status' at client A ...
29-5-19 15:05:32.263 | LsRemoteOperati | Test worker    | INFO : --------------------------------------------
29-5-19 15:05:32.263 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event LsRemoteStartSyncExternalEvent (org.syncany.operations.daemon.messages.LsRemoteStartSyncExternalEvent@6372b183) ...
29-5-19 15:05:32.263 | LsRemoteOperati | Test worker    | INFO : Retrieving remote database list.
29-5-19 15:05:32.263 | UnreliableLocal | Test worker    | INFO : Operation successful:     abs=7 rel=3 op=list list(DatabaseRemoteFile)
29-5-19 15:05:32.263 | UnreliableLocal | Test worker    | INFO : Operation successful:     abs=8 rel=4 op=connect connect
29-5-19 15:05:32.263 | UnreliableLocal | Test worker    | INFO : Operation successful:     abs=9 rel=4 op=list list(TransactionRemoteFile)
29-5-19 15:05:32.264 | UnreliableLocal | Test worker    | INFO : Operation successful:     abs=10 rel=5 op=connect connect
29-5-19 15:05:32.264 | UnreliableLocal | Test worker    | INFO : Operation successful:     abs=11 rel=1 op=disconnect disconnect
29-5-19 15:05:32.264 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event LsRemoteEndSyncExternalEvent (org.syncany.operations.daemon.messages.LsRemoteEndSyncExternalEvent@4948f772) ...
29-5-19 15:05:32.264 | UpOperation     | Test worker    | INFO : No remote changes, ready to upload.
29-5-19 15:05:32.264 | ActionFileHandl | Test worker    | INFO : Starting action for RemoteFile[name=action-up-A-1559142332113] ...
29-5-19 15:05:32.264 | ActionFileHandl | Test worker    | INFO : Uploading action file: RemoteFile[name=action-up-A-1559142332113]
29-5-19 15:05:32.264 | UnreliableLocal | Test worker    | INFO : Operation successful:     abs=12 rel=1 op=upload upload(/tmp/syncany-action-3935798428502519412.tmp, action-up-A-1559142332113)
29-5-19 15:05:32.265 | UnreliableLocal | Test worker    | INFO : Operation successful:     abs=13 rel=6 op=connect connect
29-5-19 15:05:32.265 | ActionFileHandl | Test worker    | INFO : Scheduling action renewal task for every 2 minutes, for RemoteFile[name=action-up-A-1559142332113] ...
29-5-19 15:05:32.266 | DatabaseConnect | Test worker    | INFO : Found 12 tables.
29-5-19 15:05:32.282 | UnreliableLocal | Test worker    | INFO : Operation successful:     abs=14 rel=5 op=list list(TransactionRemoteFile)
29-5-19 15:05:32.282 | UnreliableLocal | Test worker    | INFO : Operation successful:     abs=15 rel=7 op=connect connect
29-5-19 15:05:32.282 | TransactionAwar | Test worker    | INFO : Done rolling back previous transactions.
29-5-19 15:05:32.282 | AsyncIndexer    | AsyncI/syncany | INFO : Starting Indexing.
29-5-19 15:05:32.283 | InternalEventBu | AsyncI/syncany | INFO : Event bus 'LocalEventBus': Posting event UpIndexStartSyncExternalEvent (org.syncany.operations.daemon.messages.UpIndexStartSyncExternalEvent@78063096) ...
29-5-19 15:05:32.283 | Indexer         | AsyncI/syncany | FINE : - Looking for deleted files ...
29-5-19 15:05:32.283 | Indexer         | AsyncI/syncany | FINE : - +File /tmp/syncanytest/syncany-190529150308467-95774-client-A/file1
29-5-19 15:05:32.283 | Indexer         | AsyncI/syncany | FINE : - +FileContent: /tmp/syncanytest/syncany-190529150308467-95774-client-A/file1
29-5-19 15:05:32.315 | Indexer         | AsyncI/syncany | FINE : - Chunk new: 3dddcd5c74530446fd54d339138d70cf9be19b79
29-5-19 15:05:32.315 | Indexer         | AsyncI/syncany | FINE : - +MultiChunk 8aef246ccfa42cbd910db0bac56a0c4037f59708
29-5-19 15:05:32.339 | Indexer         | AsyncI/syncany | FINE : - Chunk > MultiChunk: 3dddcd5c74530446fd54d339138d70cf9be19b79 > 8aef246ccfa42cbd910db0bac56a0c4037f59708
29-5-19 15:05:32.339 | Indexer         | AsyncI/syncany | FINE : - Chunk > FileContent: 3dddcd5c74530446fd54d339138d70cf9be19b79 > /tmp/syncanytest/syncany-190529150308467-95774-client-A/file1
29-5-19 15:05:32.345 | Indexer         | AsyncI/syncany | FINE : - Chunk new: 5f99ecbd4191ab794b38bdc11f8bbecec450c463
29-5-19 15:05:32.365 | Indexer         | AsyncI/syncany | FINE : - Chunk > MultiChunk: 5f99ecbd4191ab794b38bdc11f8bbecec450c463 > 8aef246ccfa42cbd910db0bac56a0c4037f59708
29-5-19 15:05:32.366 | Indexer         | AsyncI/syncany | FINE : - Chunk > FileContent: 5f99ecbd4191ab794b38bdc11f8bbecec450c463 > /tmp/syncanytest/syncany-190529150308467-95774-client-A/file1
29-5-19 15:05:32.388 | Indexer         | AsyncI/syncany | FINE : - Chunk new: 9f90fa438f0b34fc940d21a936d2deb2c7199a91
29-5-19 15:05:32.406 | Indexer         | AsyncI/syncany | FINE : - Chunk > MultiChunk: 9f90fa438f0b34fc940d21a936d2deb2c7199a91 > 8aef246ccfa42cbd910db0bac56a0c4037f59708
29-5-19 15:05:32.406 | Indexer         | AsyncI/syncany | FINE : - Chunk > FileContent: 9f90fa438f0b34fc940d21a936d2deb2c7199a91 > /tmp/syncanytest/syncany-190529150308467-95774-client-A/file1
29-5-19 15:05:32.419 | Indexer         | AsyncI/syncany | FINE : - Chunk new: dd467316319f810e530865d0a48ec3afc9536729
29-5-19 15:05:32.429 | Indexer         | AsyncI/syncany | FINE : - Chunk > MultiChunk: dd467316319f810e530865d0a48ec3afc9536729 > 8aef246ccfa42cbd910db0bac56a0c4037f59708
29-5-19 15:05:32.429 | Indexer         | AsyncI/syncany | FINE : - Chunk > FileContent: dd467316319f810e530865d0a48ec3afc9536729 > /tmp/syncanytest/syncany-190529150308467-95774-client-A/file1
29-5-19 15:05:32.447 | Indexer         | AsyncI/syncany | FINE : - Chunk new: ce89e50ebcb98a07743ea6837b253c4f6277b628
29-5-19 15:05:32.475 | Indexer         | AsyncI/syncany | FINE : - Chunk > MultiChunk: ce89e50ebcb98a07743ea6837b253c4f6277b628 > 8aef246ccfa42cbd910db0bac56a0c4037f59708
29-5-19 15:05:32.475 | Indexer         | AsyncI/syncany | FINE : - Chunk > FileContent: ce89e50ebcb98a07743ea6837b253c4f6277b628 > /tmp/syncanytest/syncany-190529150308467-95774-client-A/file1
29-5-19 15:05:32.486 | Indexer         | AsyncI/syncany | FINE : - Chunk new: e14458577826a413d22e45e47bce49e9aab59722
29-5-19 15:05:32.512 | Indexer         | AsyncI/syncany | FINE : - Chunk > MultiChunk: e14458577826a413d22e45e47bce49e9aab59722 > 8aef246ccfa42cbd910db0bac56a0c4037f59708
29-5-19 15:05:32.512 | Indexer         | AsyncI/syncany | FINE : - Chunk > FileContent: e14458577826a413d22e45e47bce49e9aab59722 > /tmp/syncanytest/syncany-190529150308467-95774-client-A/file1
29-5-19 15:05:32.546 | Indexer         | AsyncI/syncany | FINE : - Chunk new: f99a92e3180cca5b297f4c25825085e31784b181
29-5-19 15:05:32.553 | Indexer         | AsyncI/syncany | FINE : - Chunk > MultiChunk: f99a92e3180cca5b297f4c25825085e31784b181 > 8aef246ccfa42cbd910db0bac56a0c4037f59708
29-5-19 15:05:32.553 | Indexer         | AsyncI/syncany | FINE : - Chunk > FileContent: f99a92e3180cca5b297f4c25825085e31784b181 > /tmp/syncanytest/syncany-190529150308467-95774-client-A/file1
29-5-19 15:05:32.590 | Indexer         | AsyncI/syncany | FINE : - Chunk new: efbafdf3d0cd46f40e6daa129e8f2ef5342b2c8c
29-5-19 15:05:32.606 | Indexer         | AsyncI/syncany | FINE : - Chunk > MultiChunk: efbafdf3d0cd46f40e6daa129e8f2ef5342b2c8c > 8aef246ccfa42cbd910db0bac56a0c4037f59708
29-5-19 15:05:32.606 | Indexer         | AsyncI/syncany | FINE : - Chunk > FileContent: efbafdf3d0cd46f40e6daa129e8f2ef5342b2c8c > /tmp/syncanytest/syncany-190529150308467-95774-client-A/file1
29-5-19 15:05:32.625 | Indexer         | AsyncI/syncany | FINE : - Chunk new: 6aaeba84b17e68f0a8be8e05786d9da08a93814c
29-5-19 15:05:32.630 | Indexer         | AsyncI/syncany | FINE : - /MultiChunk 8aef246ccfa42cbd910db0bac56a0c4037f59708
29-5-19 15:05:32.632 | Indexer         | AsyncI/syncany | FINE : - +MultiChunk d7fc5377336924ee500c72dbf12718c7694677ec
29-5-19 15:05:32.638 | Indexer         | AsyncI/syncany | FINE : - Chunk > MultiChunk: 6aaeba84b17e68f0a8be8e05786d9da08a93814c > d7fc5377336924ee500c72dbf12718c7694677ec
29-5-19 15:05:32.639 | Indexer         | AsyncI/syncany | FINE : - Chunk > FileContent: 6aaeba84b17e68f0a8be8e05786d9da08a93814c > /tmp/syncanytest/syncany-190529150308467-95774-client-A/file1
29-5-19 15:05:32.653 | Indexer         | AsyncI/syncany | FINE : - Chunk new: 5dbcbc957d71027ed977490f7696c448a4b75627
29-5-19 15:05:32.667 | Indexer         | AsyncI/syncany | FINE : - Chunk > MultiChunk: 5dbcbc957d71027ed977490f7696c448a4b75627 > d7fc5377336924ee500c72dbf12718c7694677ec
29-5-19 15:05:32.667 | Indexer         | AsyncI/syncany | FINE : - Chunk > FileContent: 5dbcbc957d71027ed977490f7696c448a4b75627 > /tmp/syncanytest/syncany-190529150308467-95774-client-A/file1
29-5-19 15:05:32.667 | Indexer         | AsyncI/syncany | FINE : - /File: file1 (checksum 2505d5a9b9f21d409a403fdb3e7ed35e0c473963)
29-5-19 15:05:32.668 | Indexer         | AsyncI/syncany | FINE :    * No old file history found, starting new history (path: file1, checksum: 2505d5a9b9f21d409a403fdb3e7ed35e0c473963)
29-5-19 15:05:32.668 | FileVersionComp | AsyncI/syncany | INFO :      - [DELETED]: Local file DIFFERS from file version, actual file is NULL, for file file1
29-5-19 15:05:32.668 | Indexer         | AsyncI/syncany | INFO :    * Added file version:    FileVersion [version=1, path=file1, type=FILE, status=NEW, size=5242880, lastModified=Wed May 29 15:05:32 UTC 2019, linkTarget=null, checksum=2505d5a9b9f21d409a403fdb3e7ed35e0c473963, updated=Wed May 29 15:05:32 UTC 2019, posixPermissions=rw-rw-r--, dosAttributes=--a-]
29-5-19 15:05:32.668 | Indexer         | AsyncI/syncany | INFO :      based on file version: null
29-5-19 15:05:32.668 | InternalEventBu | AsyncI/syncany | INFO : Event bus 'LocalEventBus': Posting event UpIndexChangesDetectedSyncExternalEvent (org.syncany.operations.daemon.messages.UpIndexChangesDetectedSyncExternalEvent@479d308f) ...
29-5-19 15:05:32.675 | Indexer         | AsyncI/syncany | FINE : - /MultiChunk d7fc5377336924ee500c72dbf12718c7694677ec
29-5-19 15:05:32.675 | Indexer         | AsyncI/syncany | FINE : Processed new database version: DatabaseVersion [header=UnknownMachine/()/T=1559142332282, chunks=10, multiChunks=2, fileContents=1, fileHistories=1]
29-5-19 15:05:32.675 | UpOperation     | Test worker    | INFO : No DIRTY data found in database (no dirty databases); Nothing to do here.
29-5-19 15:05:32.675 | UpOperation     | Test worker    | INFO : Last vector clock was: ()
29-5-19 15:05:32.676 | UpOperation     | Test worker    | INFO : Uploading new multichunks ...
29-5-19 15:05:32.676 | UpOperation     | Test worker    | INFO : - Uploading multichunk 8aef246ccfa42cbd910db0bac56a0c4037f59708 from /tmp/syncanytest/syncany-190529150308467-95774-client-A/.syncany/cache/multichunk-8aef246ccfa42cbd910db0bac56a0c4037f59708 to RemoteFile[name=multichunk-8aef246ccfa42cbd910db0bac56a0c4037f59708] ...
29-5-19 15:05:32.676 | RemoteTransacti | Test worker    | INFO : - Adding file to TX for UPLOAD: /tmp/syncanytest/syncany-190529150308467-95774-client-A/.syncany/cache/multichunk-8aef246ccfa42cbd910db0bac56a0c4037f59708 -> Temp. remote file: RemoteFile[name=temp-KZFLK-multichunk-8aef246ccfa42cbd910db0bac56a0c4037f59708], final location: RemoteFile[name=multichunk-8aef246ccfa42cbd910db0bac56a0c4037f59708]
29-5-19 15:05:32.676 | UpOperation     | Test worker    | INFO : - Uploading multichunk d7fc5377336924ee500c72dbf12718c7694677ec from /tmp/syncanytest/syncany-190529150308467-95774-client-A/.syncany/cache/multichunk-d7fc5377336924ee500c72dbf12718c7694677ec to RemoteFile[name=multichunk-d7fc5377336924ee500c72dbf12718c7694677ec] ...
29-5-19 15:05:32.676 | RemoteTransacti | Test worker    | INFO : - Adding file to TX for UPLOAD: /tmp/syncanytest/syncany-190529150308467-95774-client-A/.syncany/cache/multichunk-d7fc5377336924ee500c72dbf12718c7694677ec -> Temp. remote file: RemoteFile[name=temp-iqHDS-multichunk-d7fc5377336924ee500c72dbf12718c7694677ec], final location: RemoteFile[name=multichunk-d7fc5377336924ee500c72dbf12718c7694677ec]
29-5-19 15:05:32.676 | UpOperation     | Test worker    | INFO : Uploading database: DatabaseVersion [header=A/(A1)/T=1559142332676, chunks=10, multiChunks=2, fileContents=1, fileHistories=1]
29-5-19 15:05:32.676 | UpOperation     | Test worker    | INFO : Saving local delta database, version A/(A1)/T=1559142332676 to file /tmp/syncanytest/syncany-190529150308467-95774-client-A/.syncany/cache/database-A-0000000001 ... 
29-5-19 15:05:32.676 | UpOperation     | Test worker    | INFO : - Saving database to /tmp/syncanytest/syncany-190529150308467-95774-client-A/.syncany/cache/database-A-0000000001 ...
29-5-19 15:05:32.731 | InternalEventBu | AsyncI/syncany | INFO : Event bus 'LocalEventBus': Posting event UpIndexMidSyncExternalEvent (org.syncany.operations.daemon.messages.UpIndexMidSyncExternalEvent@3fe3f968) ...
29-5-19 15:05:32.731 | Indexer         | AsyncI/syncany | FINE : - +File /tmp/syncanytest/syncany-190529150308467-95774-client-A/file2
29-5-19 15:05:32.731 | Indexer         | AsyncI/syncany | FINE : - +FileContent: /tmp/syncanytest/syncany-190529150308467-95774-client-A/file2
29-5-19 15:05:32.731 | Indexer         | AsyncI/syncany | FINE : - Chunk new: 8ad0c5a4fc0ed520477a29ba107849177f5c99e3
29-5-19 15:05:32.732 | Indexer         | AsyncI/syncany | FINE : - +MultiChunk 817be3c4adfb5263915d4750e9f1a85e899b137c
29-5-19 15:05:32.732 | Indexer         | AsyncI/syncany | FINE : - Chunk > MultiChunk: 8ad0c5a4fc0ed520477a29ba107849177f5c99e3 > 817be3c4adfb5263915d4750e9f1a85e899b137c
29-5-19 15:05:32.732 | Indexer         | AsyncI/syncany | FINE : - Chunk > FileContent: 8ad0c5a4fc0ed520477a29ba107849177f5c99e3 > /tmp/syncanytest/syncany-190529150308467-95774-client-A/file2
29-5-19 15:05:32.732 | Indexer         | AsyncI/syncany | FINE : - /File: file2 (checksum 8ad0c5a4fc0ed520477a29ba107849177f5c99e3)
29-5-19 15:05:32.733 | Indexer         | AsyncI/syncany | FINE :    * No old file history found, starting new history (path: file2, checksum: 8ad0c5a4fc0ed520477a29ba107849177f5c99e3)
29-5-19 15:05:32.733 | FileVersionComp | AsyncI/syncany | INFO :      - [DELETED]: Local file DIFFERS from file version, actual file is NULL, for file file2
29-5-19 15:05:32.733 | Indexer         | AsyncI/syncany | INFO :    * Added file version:    FileVersion [version=1, path=file2, type=FILE, status=NEW, size=1024, lastModified=Wed May 29 15:05:32 UTC 2019, linkTarget=null, checksum=8ad0c5a4fc0ed520477a29ba107849177f5c99e3, updated=Wed May 29 15:05:32 UTC 2019, posixPermissions=rw-rw-r--, dosAttributes=--a-]
29-5-19 15:05:32.733 | Indexer         | AsyncI/syncany | INFO :      based on file version: null
29-5-19 15:05:32.733 | InternalEventBu | AsyncI/syncany | INFO : Event bus 'LocalEventBus': Posting event UpIndexChangesDetectedSyncExternalEvent (org.syncany.operations.daemon.messages.UpIndexChangesDetectedSyncExternalEvent@3e8537d4) ...
29-5-19 15:05:32.733 | Indexer         | AsyncI/syncany | FINE : - /MultiChunk 817be3c4adfb5263915d4750e9f1a85e899b137c
29-5-19 15:05:32.733 | Indexer         | AsyncI/syncany | FINE : Processed new database version: DatabaseVersion [header=UnknownMachine/()/T=1559142332731, chunks=1, multiChunks=1, fileContents=1, fileHistories=1]
29-5-19 15:05:32.733 | InternalEventBu | AsyncI/syncany | INFO : Event bus 'LocalEventBus': Posting event UpIndexMidSyncExternalEvent (org.syncany.operations.daemon.messages.UpIndexMidSyncExternalEvent@168cbf17) ...
29-5-19 15:05:32.733 | Indexer         | AsyncI/syncany | FINE : - +File /tmp/syncanytest/syncany-190529150308467-95774-client-A/file3
29-5-19 15:05:32.733 | Indexer         | AsyncI/syncany | FINE : - +FileContent: /tmp/syncanytest/syncany-190529150308467-95774-client-A/file3
29-5-19 15:05:32.734 | Indexer         | AsyncI/syncany | FINE : - Chunk new: 9bef688214dbdf7ba34dfa600dcc8fe2fe53ba99
29-5-19 15:05:32.734 | Indexer         | AsyncI/syncany | FINE : - +MultiChunk 505a59962ec4c33bf6865a89094dc1ea406f4370
29-5-19 15:05:32.734 | Indexer         | AsyncI/syncany | FINE : - Chunk > MultiChunk: 9bef688214dbdf7ba34dfa600dcc8fe2fe53ba99 > 505a59962ec4c33bf6865a89094dc1ea406f4370
29-5-19 15:05:32.734 | Indexer         | AsyncI/syncany | FINE : - Chunk > FileContent: 9bef688214dbdf7ba34dfa600dcc8fe2fe53ba99 > /tmp/syncanytest/syncany-190529150308467-95774-client-A/file3
29-5-19 15:05:32.734 | UpOperation     | Test worker    | INFO : - Uploading local delta database file ...
29-5-19 15:05:32.734 | UpOperation     | Test worker    | INFO : - Uploading /tmp/syncanytest/syncany-190529150308467-95774-client-A/.syncany/cache/database-A-0000000001 to RemoteFile[name=database-A-0000000001] ...
29-5-19 15:05:32.734 | RemoteTransacti | Test worker    | INFO : - Adding file to TX for UPLOAD: /tmp/syncanytest/syncany-190529150308467-95774-client-A/.syncany/cache/database-A-0000000001 -> Temp. remote file: RemoteFile[name=temp-HzgvY-database-A-0000000001], final location: RemoteFile[name=database-A-0000000001]
29-5-19 15:05:32.735 | UpOperation     | Test worker    | INFO : Adding shutdown hook (to allow resuming the upload) ...
29-5-19 15:05:32.735 | RemoteTransacti | Test worker    | INFO : Starting TX.commit() ...
29-5-19 15:05:32.736 | RemoteTransacti | Test worker    | INFO : Wrote transaction manifest to temporary file: /tmp/syncanytest/syncany-190529150308467-95774-client-A/.syncany/cache/temp-transaction-4404063933628852541.tmp
29-5-19 15:05:32.736 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event UpUploadFileSyncExternalEvent (org.syncany.operations.daemon.messages.UpUploadFileSyncExternalEvent@37f126c6) ...
29-5-19 15:05:32.736 | RemoteTransacti | Test worker    | INFO : - Uploading remote transaction file RemoteFile[name=transaction-48dc6d82] ...
29-5-19 15:05:32.736 | UnreliableLocal | Test worker    | INFO : Operation successful:     abs=16 rel=2 op=upload upload(/tmp/syncanytest/syncany-190529150308467-95774-client-A/.syncany/cache/temp-transaction-4404063933628852541.tmp, transaction-48dc6d82)
29-5-19 15:05:32.736 | UnreliableLocal | Test worker    | INFO : Operation successful:     abs=17 rel=8 op=connect connect
29-5-19 15:05:32.736 | RemoteTransacti | Test worker    | INFO : - Starting to upload data in commit.
29-5-19 15:05:32.737 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event UpUploadFileInTransactionSyncExternalEvent (org.syncany.operations.daemon.messages.UpUploadFileInTransactionSyncExternalEvent@5935fb2b) ...
29-5-19 15:05:32.737 | RemoteTransacti | Test worker    | INFO : - Uploading /tmp/syncanytest/syncany-190529150308467-95774-client-A/.syncany/cache/multichunk-8aef246ccfa42cbd910db0bac56a0c4037f59708 to temp. file RemoteFile[name=temp-KZFLK-multichunk-8aef246ccfa42cbd910db0bac56a0c4037f59708] ...
29-5-19 15:05:32.737 | UnreliableLocal | Test worker    | INFO : Operation successful:     abs=18 rel=3 op=upload upload(/tmp/syncanytest/syncany-190529150308467-95774-client-A/.syncany/cache/multichunk-8aef246ccfa42cbd910db0bac56a0c4037f59708, temp-KZFLK-multichunk-8aef246ccfa42cbd910db0bac56a0c4037f59708)
29-5-19 15:05:32.737 | UnreliableLocal | Test worker    | INFO : Operation successful:     abs=19 rel=9 op=connect connect
29-5-19 15:05:32.742 | Indexer         | AsyncI/syncany | FINE : - /File: file3 (checksum 9bef688214dbdf7ba34dfa600dcc8fe2fe53ba99)
29-5-19 15:05:32.743 | Indexer         | AsyncI/syncany | FINE :    * No old file history found, starting new history (path: file3, checksum: 9bef688214dbdf7ba34dfa600dcc8fe2fe53ba99)
29-5-19 15:05:32.743 | FileVersionComp | AsyncI/syncany | INFO :      - [DELETED]: Local file DIFFERS from file version, actual file is NULL, for file file3
29-5-19 15:05:32.743 | Indexer         | AsyncI/syncany | INFO :    * Added file version:    FileVersion [version=1, path=file3, type=FILE, status=NEW, size=1024, lastModified=Wed May 29 15:05:32 UTC 2019, linkTarget=null, checksum=9bef688214dbdf7ba34dfa600dcc8fe2fe53ba99, updated=Wed May 29 15:05:32 UTC 2019, posixPermissions=rw-rw-r--, dosAttributes=--a-]
29-5-19 15:05:32.743 | Indexer         | AsyncI/syncany | INFO :      based on file version: null
29-5-19 15:05:32.743 | InternalEventBu | AsyncI/syncany | INFO : Event bus 'LocalEventBus': Posting event UpIndexChangesDetectedSyncExternalEvent (org.syncany.operations.daemon.messages.UpIndexChangesDetectedSyncExternalEvent@b03ddad) ...
29-5-19 15:05:32.743 | Indexer         | AsyncI/syncany | FINE : - /MultiChunk 505a59962ec4c33bf6865a89094dc1ea406f4370
29-5-19 15:05:32.743 | Indexer         | AsyncI/syncany | FINE : Processed new database version: DatabaseVersion [header=UnknownMachine/()/T=1559142332733, chunks=1, multiChunks=1, fileContents=1, fileHistories=1]
29-5-19 15:05:32.743 | InternalEventBu | AsyncI/syncany | INFO : Event bus 'LocalEventBus': Posting event UpIndexMidSyncExternalEvent (org.syncany.operations.daemon.messages.UpIndexMidSyncExternalEvent@1f7bd524) ...
29-5-19 15:05:32.749 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event UpUploadFileInTransactionSyncExternalEvent (org.syncany.operations.daemon.messages.UpUploadFileInTransactionSyncExternalEvent@6e668cc4) ...
29-5-19 15:05:32.749 | RemoteTransacti | Test worker    | INFO : - Uploading /tmp/syncanytest/syncany-190529150308467-95774-client-A/.syncany/cache/multichunk-d7fc5377336924ee500c72dbf12718c7694677ec to temp. file RemoteFile[name=temp-iqHDS-multichunk-d7fc5377336924ee500c72dbf12718c7694677ec] ...
29-5-19 15:05:32.749 | UnreliableLocal | Test worker    | INFO : Operation NOT successful: abs=20 rel=4 op=upload upload(/tmp/syncanytest/syncany-190529150308467-95774-client-A/.syncany/cache/multichunk-d7fc5377336924ee500c72dbf12718c7694677ec, temp-iqHDS-multichunk-d7fc5377336924ee500c72dbf12718c7694677ec)
29-5-19 15:05:32.749 | RetriableFeatur | Test worker    | WARN : Transfer method failed. 1/3 retries. Sleeping 3000ms ...
org.syncany.plugins.transfer.StorageException: Operation failed: upload(/tmp/syncanytest/syncany-190529150308467-95774-client-A/.syncany/cache/multichunk-d7fc5377336924ee500c72dbf12718c7694677ec, temp-iqHDS-multichunk-d7fc5377336924ee500c72dbf12718c7694677ec)
	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:93)
	at org.syncany.tests.integration.scenarios.FailedSplitSyncUpScenarioTest.testUpFailsOnFirstTransaction(FailedSplitSyncUpScenarioTest.java:45)
	at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
	at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
	at java.base/java.lang.reflect.Method.invoke(Method.java:566)
	at org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(FrameworkMethod.java:50)
	at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:12)
	at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java:47)
	at org.junit.internal.runners.statements.InvokeMethod.evaluate(InvokeMethod.java:17)
	at org.junit.runners.ParentRunner.runLeaf(ParentRunner.java:325)
	at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:78)
	at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:57)
	at org.junit.runners.ParentRunner$3.run(ParentRunner.java:290)
	at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:71)
	at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:288)
	at org.junit.runners.ParentRunner.access$000(ParentRunner.java:58)
	at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:268)
	at org.junit.runners.ParentRunner.run(ParentRunner.java:363)
	at org.junit.runner.JUnitCore.run(JUnitCore.java:137)
	at org.junit.runner.JUnitCore.run(JUnitCore.java:115)
	at org.junit.vintage.engine.execution.RunnerExecutor.execute(RunnerExecutor.java:40)
	at java.base/java.util.stream.ForEachOps$ForEachOp$OfRef.accept(ForEachOps.java:183)
	at java.base/java.util.stream.ReferencePipeline$3$1.accept(ReferencePipeline.java:195)
	at java.base/java.util.Iterator.forEachRemaining(Iterator.java:133)
	at java.base/java.util.Spliterators$IteratorSpliterator.forEachRemaining(Spliterators.java:1801)
	at java.base/java.util.stream.AbstractPipeline.copyInto(AbstractPipeline.java:484)
	at java.base/java.util.stream.AbstractPipeline.wrapAndCopyInto(AbstractPipeline.java:474)
	at java.base/java.util.stream.ForEachOps$ForEachOp.evaluateSequential(ForEachOps.java:150)
	at java.base/java.util.stream.ForEachOps$ForEachOp$OfRef.evaluateSequential(ForEachOps.java:173)
	at java.base/java.util.stream.AbstractPipeline.evaluate(AbstractPipeline.java:234)
	at java.base/java.util.stream.ReferencePipeline.forEach(ReferencePipeline.java:497)
	at org.junit.vintage.engine.VintageTestEngine.executeAllChildren(VintageTestEngine.java:80)
	at org.junit.vintage.engine.VintageTestEngine.execute(VintageTestEngine.java:71)
	at org.junit.platform.launcher.core.DefaultLauncher.execute(DefaultLauncher.java:170)
	at org.junit.platform.launcher.core.DefaultLauncher.execute(DefaultLauncher.java:154)
	at org.junit.platform.launcher.core.DefaultLauncher.execute(DefaultLauncher.java:90)
	at org.gradle.api.internal.tasks.testing.junitplatform.JUnitPlatformTestClassProcessor$CollectAllTestClassesExecutor.processAllTestClasses(JUnitPlatformTestClassProcessor.java:92)
	at org.gradle.api.internal.tasks.testing.junitplatform.JUnitPlatformTestClassProcessor$CollectAllTestClassesExecutor.access$100(JUnitPlatformTestClassProcessor.java:77)
	at org.gradle.api.internal.tasks.testing.junitplatform.JUnitPlatformTestClassProcessor.stop(JUnitPlatformTestClassProcessor.java:73)
	at org.gradle.api.internal.tasks.testing.SuiteTestClassProcessor.stop(SuiteTestClassProcessor.java:61)
	at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
	at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
	at java.base/java.lang.reflect.Method.invoke(Method.java:566)
	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.$Proxy5.stop(Unknown Source)
	at org.gradle.api.internal.tasks.testing.worker.TestWorker.stop(TestWorker.java:131)
	at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
	at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
	at java.base/java.lang.reflect.Method.invoke(Method.java:566)
	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:155)
	at org.gradle.internal.remote.internal.hub.MessageHubBackedObjectConnection$DispatchWrapper.dispatch(MessageHubBackedObjectConnection.java:137)
	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.ManagedExecutorImpl$1.run(ManagedExecutorImpl.java:46)
	at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128)
	at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628)
	at org.gradle.internal.concurrent.ThreadFactoryImpl$ManagedThreadRunnable.run(ThreadFactoryImpl.java:55)
	at java.base/java.lang.Thread.run(Thread.java:834)
29-5-19 15:05:32.752 | AsyncIndexer    | AsyncI/syncany | INFO : Stopping indexing. Signal end of stream with empty databaseversion
29-5-19 15:05:35.754 | RetriableFeatur | Test worker    | WARN : Retrying method: 1/3 ...
29-5-19 15:05:35.754 | UnreliableLocal | Test worker    | INFO : Operation NOT successful: abs=21 rel=5 op=upload upload(/tmp/syncanytest/syncany-190529150308467-95774-client-A/.syncany/cache/multichunk-d7fc5377336924ee500c72dbf12718c7694677ec, temp-iqHDS-multichunk-d7fc5377336924ee500c72dbf12718c7694677ec)
29-5-19 15:05:35.755 | RetriableFeatur | Test worker    | WARN : Transfer method failed. 2/3 retries. Sleeping 3000ms ...
org.syncany.plugins.transfer.StorageException: Operation failed: upload(/tmp/syncanytest/syncany-190529150308467-95774-client-A/.syncany/cache/multichunk-d7fc5377336924ee500c72dbf12718c7694677ec, temp-iqHDS-multichunk-d7fc5377336924ee500c72dbf12718c7694677ec)
	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:93)
	at org.syncany.tests.integration.scenarios.FailedSplitSyncUpScenarioTest.testUpFailsOnFirstTransaction(FailedSplitSyncUpScenarioTest.java:45)
	at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
	at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
	at java.base/java.lang.reflect.Method.invoke(Method.java:566)
	at org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(FrameworkMethod.java:50)
	at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:12)
	at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java:47)
	at org.junit.internal.runners.statements.InvokeMethod.evaluate(InvokeMethod.java:17)
	at org.junit.runners.ParentRunner.runLeaf(ParentRunner.java:325)
	at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:78)
	at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:57)
	at org.junit.runners.ParentRunner$3.run(ParentRunner.java:290)
	at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:71)
	at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:288)
	at org.junit.runners.ParentRunner.access$000(ParentRunner.java:58)
	at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:268)
	at org.junit.runners.ParentRunner.run(ParentRunner.java:363)
	at org.junit.runner.JUnitCore.run(JUnitCore.java:137)
	at org.junit.runner.JUnitCore.run(JUnitCore.java:115)
	at org.junit.vintage.engine.execution.RunnerExecutor.execute(RunnerExecutor.java:40)
	at java.base/java.util.stream.ForEachOps$ForEachOp$OfRef.accept(ForEachOps.java:183)
	at java.base/java.util.stream.ReferencePipeline$3$1.accept(ReferencePipeline.java:195)
	at java.base/java.util.Iterator.forEachRemaining(Iterator.java:133)
	at java.base/java.util.Spliterators$IteratorSpliterator.forEachRemaining(Spliterators.java:1801)
	at java.base/java.util.stream.AbstractPipeline.copyInto(AbstractPipeline.java:484)
	at java.base/java.util.stream.AbstractPipeline.wrapAndCopyInto(AbstractPipeline.java:474)
	at java.base/java.util.stream.ForEachOps$ForEachOp.evaluateSequential(ForEachOps.java:150)
	at java.base/java.util.stream.ForEachOps$ForEachOp$OfRef.evaluateSequential(ForEachOps.java:173)
	at java.base/java.util.stream.AbstractPipeline.evaluate(AbstractPipeline.java:234)
	at java.base/java.util.stream.ReferencePipeline.forEach(ReferencePipeline.java:497)
	at org.junit.vintage.engine.VintageTestEngine.executeAllChildren(VintageTestEngine.java:80)
	at org.junit.vintage.engine.VintageTestEngine.execute(VintageTestEngine.java:71)
	at org.junit.platform.launcher.core.DefaultLauncher.execute(DefaultLauncher.java:170)
	at org.junit.platform.launcher.core.DefaultLauncher.execute(DefaultLauncher.java:154)
	at org.junit.platform.launcher.core.DefaultLauncher.execute(DefaultLauncher.java:90)
	at org.gradle.api.internal.tasks.testing.junitplatform.JUnitPlatformTestClassProcessor$CollectAllTestClassesExecutor.processAllTestClasses(JUnitPlatformTestClassProcessor.java:92)
	at org.gradle.api.internal.tasks.testing.junitplatform.JUnitPlatformTestClassProcessor$CollectAllTestClassesExecutor.access$100(JUnitPlatformTestClassProcessor.java:77)
	at org.gradle.api.internal.tasks.testing.junitplatform.JUnitPlatformTestClassProcessor.stop(JUnitPlatformTestClassProcessor.java:73)
	at org.gradle.api.internal.tasks.testing.SuiteTestClassProcessor.stop(SuiteTestClassProcessor.java:61)
	at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
	at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
	at java.base/java.lang.reflect.Method.invoke(Method.java:566)
	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.$Proxy5.stop(Unknown Source)
	at org.gradle.api.internal.tasks.testing.worker.TestWorker.stop(TestWorker.java:131)
	at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
	at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
	at java.base/java.lang.reflect.Method.invoke(Method.java:566)
	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:155)
	at org.gradle.internal.remote.internal.hub.MessageHubBackedObjectConnection$DispatchWrapper.dispatch(MessageHubBackedObjectConnection.java:137)
	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.ManagedExecutorImpl$1.run(ManagedExecutorImpl.java:46)
	at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128)
	at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628)
	at org.gradle.internal.concurrent.ThreadFactoryImpl$ManagedThreadRunnable.run(ThreadFactoryImpl.java:55)
	at java.base/java.lang.Thread.run(Thread.java:834)
29-5-19 15:05:38.756 | RetriableFeatur | Test worker    | WARN : Retrying method: 2/3 ...
29-5-19 15:05:38.756 | UnreliableLocal | Test worker    | INFO : Operation NOT successful: abs=22 rel=6 op=upload upload(/tmp/syncanytest/syncany-190529150308467-95774-client-A/.syncany/cache/multichunk-d7fc5377336924ee500c72dbf12718c7694677ec, temp-iqHDS-multichunk-d7fc5377336924ee500c72dbf12718c7694677ec)
29-5-19 15:05:38.756 | RetriableFeatur | Test worker    | WARN : Transfer method failed. No retries left. Throwing exception.
org.syncany.plugins.transfer.StorageException: Operation failed: upload(/tmp/syncanytest/syncany-190529150308467-95774-client-A/.syncany/cache/multichunk-d7fc5377336924ee500c72dbf12718c7694677ec, temp-iqHDS-multichunk-d7fc5377336924ee500c72dbf12718c7694677ec)
	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:93)
	at org.syncany.tests.integration.scenarios.FailedSplitSyncUpScenarioTest.testUpFailsOnFirstTransaction(FailedSplitSyncUpScenarioTest.java:45)
	at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
	at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
	at java.base/java.lang.reflect.Method.invoke(Method.java:566)
	at org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(FrameworkMethod.java:50)
	at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:12)
	at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java:47)
	at org.junit.internal.runners.statements.InvokeMethod.evaluate(InvokeMethod.java:17)
	at org.junit.runners.ParentRunner.runLeaf(ParentRunner.java:325)
	at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:78)
	at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:57)
	at org.junit.runners.ParentRunner$3.run(ParentRunner.java:290)
	at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:71)
	at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:288)
	at org.junit.runners.ParentRunner.access$000(ParentRunner.java:58)
	at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:268)
	at org.junit.runners.ParentRunner.run(ParentRunner.java:363)
	at org.junit.runner.JUnitCore.run(JUnitCore.java:137)
	at org.junit.runner.JUnitCore.run(JUnitCore.java:115)
	at org.junit.vintage.engine.execution.RunnerExecutor.execute(RunnerExecutor.java:40)
	at java.base/java.util.stream.ForEachOps$ForEachOp$OfRef.accept(ForEachOps.java:183)
	at java.base/java.util.stream.ReferencePipeline$3$1.accept(ReferencePipeline.java:195)
	at java.base/java.util.Iterator.forEachRemaining(Iterator.java:133)
	at java.base/java.util.Spliterators$IteratorSpliterator.forEachRemaining(Spliterators.java:1801)
	at java.base/java.util.stream.AbstractPipeline.copyInto(AbstractPipeline.java:484)
	at java.base/java.util.stream.AbstractPipeline.wrapAndCopyInto(AbstractPipeline.java:474)
	at java.base/java.util.stream.ForEachOps$ForEachOp.evaluateSequential(ForEachOps.java:150)
	at java.base/java.util.stream.ForEachOps$ForEachOp$OfRef.evaluateSequential(ForEachOps.java:173)
	at java.base/java.util.stream.AbstractPipeline.evaluate(AbstractPipeline.java:234)
	at java.base/java.util.stream.ReferencePipeline.forEach(ReferencePipeline.java:497)
	at org.junit.vintage.engine.VintageTestEngine.executeAllChildren(VintageTestEngine.java:80)
	at org.junit.vintage.engine.VintageTestEngine.execute(VintageTestEngine.java:71)
	at org.junit.platform.launcher.core.DefaultLauncher.execute(DefaultLauncher.java:170)
	at org.junit.platform.launcher.core.DefaultLauncher.execute(DefaultLauncher.java:154)
	at org.junit.platform.launcher.core.DefaultLauncher.execute(DefaultLauncher.java:90)
	at org.gradle.api.internal.tasks.testing.junitplatform.JUnitPlatformTestClassProcessor$CollectAllTestClassesExecutor.processAllTestClasses(JUnitPlatformTestClassProcessor.java:92)
	at org.gradle.api.internal.tasks.testing.junitplatform.JUnitPlatformTestClassProcessor$CollectAllTestClassesExecutor.access$100(JUnitPlatformTestClassProcessor.java:77)
	at org.gradle.api.internal.tasks.testing.junitplatform.JUnitPlatformTestClassProcessor.stop(JUnitPlatformTestClassProcessor.java:73)
	at org.gradle.api.internal.tasks.testing.SuiteTestClassProcessor.stop(SuiteTestClassProcessor.java:61)
	at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
	at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
	at java.base/java.lang.reflect.Method.invoke(Method.java:566)
	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.$Proxy5.stop(Unknown Source)
	at org.gradle.api.internal.tasks.testing.worker.TestWorker.stop(TestWorker.java:131)
	at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
	at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
	at java.base/java.lang.reflect.Method.invoke(Method.java:566)
	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:155)
	at org.gradle.internal.remote.internal.hub.MessageHubBackedObjectConnection$DispatchWrapper.dispatch(MessageHubBackedObjectConnection.java:137)
	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.ManagedExecutorImpl$1.run(ManagedExecutorImpl.java:46)
	at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128)
	at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628)
	at org.gradle.internal.concurrent.ThreadFactoryImpl$ManagedThreadRunnable.run(ThreadFactoryImpl.java:55)
	at java.base/java.lang.Thread.run(Thread.java:834)
29-5-19 15:05:38.758 | UpOperation     | Test worker    | FINE : Waiting for new database version.
29-5-19 15:05:38.758 | UpOperation     | Test worker    | FINE : Took new database version: DatabaseVersion [header=UnknownMachine/()/T=1559142332731, chunks=1, multiChunks=1, fileContents=1, fileHistories=1]
29-5-19 15:05:38.758 | UpOperation     | Test worker    | INFO : Last vector clock was: ()
29-5-19 15:05:38.759 | UpOperation     | Test worker    | INFO : Uploading new multichunks ...
29-5-19 15:05:38.759 | UpOperation     | Test worker    | INFO : - Uploading multichunk 817be3c4adfb5263915d4750e9f1a85e899b137c from /tmp/syncanytest/syncany-190529150308467-95774-client-A/.syncany/cache/multichunk-817be3c4adfb5263915d4750e9f1a85e899b137c to RemoteFile[name=multichunk-817be3c4adfb5263915d4750e9f1a85e899b137c] ...
29-5-19 15:05:38.760 | RemoteTransacti | Test worker    | INFO : - Adding file to TX for UPLOAD: /tmp/syncanytest/syncany-190529150308467-95774-client-A/.syncany/cache/multichunk-817be3c4adfb5263915d4750e9f1a85e899b137c -> Temp. remote file: RemoteFile[name=temp-blusS-multichunk-817be3c4adfb5263915d4750e9f1a85e899b137c], final location: RemoteFile[name=multichunk-817be3c4adfb5263915d4750e9f1a85e899b137c]
29-5-19 15:05:38.760 | UpOperation     | Test worker    | INFO : Uploading database: DatabaseVersion [header=A/(A2)/T=1559142338759, chunks=1, multiChunks=1, fileContents=1, fileHistories=1]
29-5-19 15:05:38.760 | UpOperation     | Test worker    | INFO : Saving local delta database, version A/(A2)/T=1559142338759 to file /tmp/syncanytest/syncany-190529150308467-95774-client-A/.syncany/cache/database-A-0000000002 ... 
29-5-19 15:05:38.760 | UpOperation     | Test worker    | INFO : - Saving database to /tmp/syncanytest/syncany-190529150308467-95774-client-A/.syncany/cache/database-A-0000000002 ...
29-5-19 15:05:38.761 | UpOperation     | Test worker    | INFO : - Uploading local delta database file ...
29-5-19 15:05:38.761 | UpOperation     | Test worker    | INFO : - Uploading /tmp/syncanytest/syncany-190529150308467-95774-client-A/.syncany/cache/database-A-0000000002 to RemoteFile[name=database-A-0000000002] ...
29-5-19 15:05:38.761 | RemoteTransacti | Test worker    | INFO : - Adding file to TX for UPLOAD: /tmp/syncanytest/syncany-190529150308467-95774-client-A/.syncany/cache/database-A-0000000002 -> Temp. remote file: RemoteFile[name=temp-UhPtv-database-A-0000000002], final location: RemoteFile[name=database-A-0000000002]
29-5-19 15:05:38.761 | UpOperation     | Test worker    | INFO : Adding shutdown hook (to allow resuming the upload) ...
29-5-19 15:05:38.762 | UpOperation     | Test worker    | FINE : Waiting for new database version.
29-5-19 15:05:38.762 | UpOperation     | Test worker    | FINE : Took new database version: DatabaseVersion [header=UnknownMachine/()/T=1559142332733, chunks=1, multiChunks=1, fileContents=1, fileHistories=1]
29-5-19 15:05:38.762 | UpOperation     | Test worker    | INFO : Last vector clock was: ()
29-5-19 15:05:38.762 | UpOperation     | Test worker    | INFO : Uploading new multichunks ...
29-5-19 15:05:38.764 | UpOperation     | Test worker    | INFO : - Uploading multichunk 505a59962ec4c33bf6865a89094dc1ea406f4370 from /tmp/syncanytest/syncany-190529150308467-95774-client-A/.syncany/cache/multichunk-505a59962ec4c33bf6865a89094dc1ea406f4370 to RemoteFile[name=multichunk-505a59962ec4c33bf6865a89094dc1ea406f4370] ...
29-5-19 15:05:38.765 | RemoteTransacti | Test worker    | INFO : - Adding file to TX for UPLOAD: /tmp/syncanytest/syncany-190529150308467-95774-client-A/.syncany/cache/multichunk-505a59962ec4c33bf6865a89094dc1ea406f4370 -> Temp. remote file: RemoteFile[name=temp-agqti-multichunk-505a59962ec4c33bf6865a89094dc1ea406f4370], final location: RemoteFile[name=multichunk-505a59962ec4c33bf6865a89094dc1ea406f4370]
29-5-19 15:05:38.765 | UpOperation     | Test worker    | INFO : Uploading database: DatabaseVersion [header=A/(A3)/T=1559142338762, chunks=1, multiChunks=1, fileContents=1, fileHistories=1]
29-5-19 15:05:38.766 | UpOperation     | Test worker    | INFO : Saving local delta database, version A/(A3)/T=1559142338762 to file /tmp/syncanytest/syncany-190529150308467-95774-client-A/.syncany/cache/database-A-0000000003 ... 
29-5-19 15:05:38.766 | UpOperation     | Test worker    | INFO : - Saving database to /tmp/syncanytest/syncany-190529150308467-95774-client-A/.syncany/cache/database-A-0000000003 ...
29-5-19 15:05:38.767 | UpOperation     | Test worker    | INFO : - Uploading local delta database file ...
29-5-19 15:05:38.767 | UpOperation     | Test worker    | INFO : - Uploading /tmp/syncanytest/syncany-190529150308467-95774-client-A/.syncany/cache/database-A-0000000003 to RemoteFile[name=database-A-0000000003] ...
29-5-19 15:05:38.767 | RemoteTransacti | Test worker    | INFO : - Adding file to TX for UPLOAD: /tmp/syncanytest/syncany-190529150308467-95774-client-A/.syncany/cache/database-A-0000000003 -> Temp. remote file: RemoteFile[name=temp-vOuin-database-A-0000000003], final location: RemoteFile[name=database-A-0000000003]
29-5-19 15:05:38.767 | UpOperation     | Test worker    | INFO : Adding shutdown hook (to allow resuming the upload) ...
29-5-19 15:05:38.767 | UpOperation     | Test worker    | FINE : Waiting for new database version.
29-5-19 15:05:38.767 | UpOperation     | Test worker    | FINE : Took new database version: DatabaseVersion [header=UnknownMachine/()/T=1559142332754, chunks=0, multiChunks=0, fileContents=0, fileHistories=0]
29-5-19 15:05:38.768 | UpOperation     | Test worker    | INFO : Persisting status of UpOperation to /tmp/syncanytest/syncany-190529150308467-95774-client-A/.syncany/state ...
29-5-19 15:05:38.778 | RemoteTransacti | Test worker    | INFO : Wrote transaction manifest to temporary file: /tmp/syncanytest/syncany-190529150308467-95774-client-A/.syncany/state/transaction-actions.0000000001.xml
29-5-19 15:05:38.784 | RemoteTransacti | Test worker    | INFO : Wrote transaction manifest to temporary file: /tmp/syncanytest/syncany-190529150308467-95774-client-A/.syncany/state/transaction-actions.0000000002.xml
29-5-19 15:05:38.786 | RemoteTransacti | Test worker    | INFO : Wrote transaction manifest to temporary file: /tmp/syncanytest/syncany-190529150308467-95774-client-A/.syncany/state/transaction-actions.0000000003.xml
29-5-19 15:05:38.818 | RemoteTransacti | Test worker    | INFO : Wrote transaction manifest to temporary file: /tmp/syncanytest/syncany-190529150308467-95774-client-A/.syncany/state/transaction-actions.xml
29-5-19 15:05:38.821 | org.syncany.plu | Test worker    | FINE : Validating required fields
29-5-19 15:05:38.821 | TransferManager | Test worker    | INFO : Building UnreliableLocalTransferManager from config 'syncany-190529150308467-95774-client-A' ...
29-5-19 15:05:38.821 | TransferManager | Test worker    | INFO : - With feature ReadAfterWriteConsistent
29-5-19 15:05:38.822 | TransferManager | Test worker    | INFO : - With feature Retriable
29-5-19 15:05:38.822 | TransferManager | Test worker    | FINE : - Checking if selected features supported ...
29-5-19 15:05:38.822 | TransferManager | Test worker    | FINE : - Checking for duplicate features ...
29-5-19 15:05:38.822 | TransferManager | Test worker    | FINE : - Checking required feature TransactionAware in UnreliableLocalTransferManager ...
29-5-19 15:05:38.822 | TransferManager | Test worker    | FINE : - Checking required feature Retriable in UnreliableLocalTransferManager ...
29-5-19 15:05:38.822 | TransferManager | Test worker    | INFO : - SKIPPING unsupported optional feature ReadAfterWriteConsistent
29-5-19 15:05:38.822 | TransferManager | Test worker    | FINE : - Wrapping TransferManager UnreliableLocalTransferManager in RetriableFeatureTransferManager
29-5-19 15:05:38.822 | org.syncany.plu | Test worker    | FINE : Validating required fields
29-5-19 15:05:38.822 | TransferManager | Test worker    | INFO : Building UnreliableLocalTransferManager from config 'syncany-190529150308467-95774-client-A' ...
29-5-19 15:05:38.822 | TransferManager | Test worker    | INFO : - With feature ReadAfterWriteConsistent
29-5-19 15:05:38.822 | TransferManager | Test worker    | INFO : - With feature Retriable
29-5-19 15:05:38.823 | TransferManager | Test worker    | INFO : - With feature PathAware
29-5-19 15:05:38.823 | TransferManager | Test worker    | INFO : - With feature TransactionAware
29-5-19 15:05:38.823 | TransferManager | Test worker    | FINE : - Checking if selected features supported ...
29-5-19 15:05:38.823 | TransferManager | Test worker    | FINE : - Checking for duplicate features ...
29-5-19 15:05:38.823 | TransferManager | Test worker    | FINE : - Checking required feature TransactionAware in UnreliableLocalTransferManager ...
29-5-19 15:05:38.823 | TransferManager | Test worker    | FINE : - Checking required feature Retriable in UnreliableLocalTransferManager ...
29-5-19 15:05:38.823 | TransferManager | Test worker    | INFO : - SKIPPING unsupported optional feature ReadAfterWriteConsistent
29-5-19 15:05:38.823 | TransferManager | Test worker    | FINE : - Wrapping TransferManager UnreliableLocalTransferManager in RetriableFeatureTransferManager
29-5-19 15:05:38.823 | TransferManager | Test worker    | INFO : - SKIPPING unsupported optional feature PathAware
29-5-19 15:05:38.823 | TransferManager | Test worker    | FINE : - Wrapping TransferManager RetriableFeatureTransferManager in TransactionAwareFeatureTransferManager
29-5-19 15:05:38.835 | DatabaseConnect | Test worker    | INFO : Found 12 tables.
29-5-19 15:05:38.835 | UpOperation     | Test worker    | INFO : 
29-5-19 15:05:38.835 | UpOperation     | Test worker    | INFO : Running 'Sync up' at client A ...
29-5-19 15:05:38.835 | UpOperation     | Test worker    | INFO : --------------------------------------------
29-5-19 15:05:38.835 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event UpStartSyncExternalEvent (org.syncany.operations.daemon.messages.UpStartSyncExternalEvent@63cec6cc) ...
29-5-19 15:05:38.836 | DatabaseConnect | Test worker    | INFO : Found 12 tables.
29-5-19 15:05:38.836 | StatusOperation | Test worker    | INFO : 
29-5-19 15:05:38.836 | StatusOperation | Test worker    | INFO : Running 'Status' at client A ...
29-5-19 15:05:38.836 | StatusOperation | Test worker    | INFO : --------------------------------------------
29-5-19 15:05:38.836 | StatusOperation | Test worker    | INFO : Querying current file tree from database ...
29-5-19 15:05:38.836 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event StatusStartSyncExternalEvent (org.syncany.operations.daemon.messages.StatusStartSyncExternalEvent@6ca1bf05) ...
29-5-19 15:05:38.843 | StatusOperation | Test worker    | INFO : Analyzing local folder /tmp/syncanytest/syncany-190529150308467-95774-client-A ...
29-5-19 15:05:38.845 | StatusOperation | Test worker    | FINE : - New file: file2
29-5-19 15:05:38.845 | StatusOperation | Test worker    | FINE : - New file: file3
29-5-19 15:05:38.846 | StatusOperation | Test worker    | FINE : - New file: file1
29-5-19 15:05:38.846 | StatusOperation | Test worker    | FINE : - Ignoring file (syncany app-related): .syncany
29-5-19 15:05:38.846 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event StatusEndSyncExternalEvent (org.syncany.operations.daemon.messages.StatusEndSyncExternalEvent@43abf9f8) ...
29-5-19 15:05:38.846 | AbstractTransfe | Test worker    | INFO : Looking for other running remote operations ...
29-5-19 15:05:38.846 | UnreliableLocal | Test worker    | INFO : Operation successful:     abs=23 rel=6 op=list list(ActionRemoteFile)
29-5-19 15:05:38.846 | UnreliableLocal | Test worker    | INFO : Operation successful:     abs=24 rel=10 op=connect connect
29-5-19 15:05:38.848 | UnreliableLocal | Test worker    | INFO : Operation successful:     abs=25 rel=7 op=list list(TransactionRemoteFile)
29-5-19 15:05:38.849 | UnreliableLocal | Test worker    | INFO : Operation successful:     abs=26 rel=11 op=connect connect
29-5-19 15:05:38.849 | UnreliableLocal | Test worker    | INFO : Operation successful:     abs=27 rel=1 op=download download(transaction-48dc6d82, /tmp/syncanytest/syncany-190529150308467-95774-client-A/.syncany/cache/temp-transaction-9599295595830517335.tmp)
29-5-19 15:05:38.850 | UnreliableLocal | Test worker    | INFO : Operation successful:     abs=28 rel=12 op=connect connect
29-5-19 15:05:38.863 | DatabaseConnect | Test worker    | INFO : Found 12 tables.
29-5-19 15:05:38.870 | LsRemoteOperati | Test worker    | INFO : 
29-5-19 15:05:38.870 | LsRemoteOperati | Test worker    | INFO : Running 'Remote Status' at client A ...
29-5-19 15:05:38.870 | LsRemoteOperati | Test worker    | INFO : --------------------------------------------
29-5-19 15:05:38.870 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event LsRemoteStartSyncExternalEvent (org.syncany.operations.daemon.messages.LsRemoteStartSyncExternalEvent@3a457aae) ...
29-5-19 15:05:38.871 | LsRemoteOperati | Test worker    | INFO : Retrieving remote database list.
29-5-19 15:05:38.871 | UnreliableLocal | Test worker    | INFO : Operation successful:     abs=29 rel=8 op=list list(DatabaseRemoteFile)
29-5-19 15:05:38.871 | UnreliableLocal | Test worker    | INFO : Operation successful:     abs=30 rel=13 op=connect connect
29-5-19 15:05:38.874 | UnreliableLocal | Test worker    | INFO : Operation successful:     abs=31 rel=9 op=list list(TransactionRemoteFile)
29-5-19 15:05:38.874 | UnreliableLocal | Test worker    | INFO : Operation successful:     abs=32 rel=14 op=connect connect
29-5-19 15:05:38.875 | UnreliableLocal | Test worker    | INFO : Operation successful:     abs=33 rel=2 op=download download(transaction-48dc6d82, /tmp/syncanytest/syncany-190529150308467-95774-client-A/.syncany/cache/temp-transaction-128038339022824114.tmp)
29-5-19 15:05:38.875 | UnreliableLocal | Test worker    | INFO : Operation successful:     abs=34 rel=15 op=connect connect
29-5-19 15:05:38.903 | UnreliableLocal | Test worker    | INFO : Operation successful:     abs=35 rel=2 op=disconnect disconnect
29-5-19 15:05:38.903 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event LsRemoteEndSyncExternalEvent (org.syncany.operations.daemon.messages.LsRemoteEndSyncExternalEvent@6c685ebb) ...
29-5-19 15:05:38.903 | UpOperation     | Test worker    | INFO : No remote changes, ready to upload.
29-5-19 15:05:38.903 | ActionFileHandl | Test worker    | INFO : Starting action for RemoteFile[name=action-up-A-1559142338823] ...
29-5-19 15:05:38.903 | ActionFileHandl | Test worker    | INFO : Uploading action file: RemoteFile[name=action-up-A-1559142338823]
29-5-19 15:05:38.904 | UnreliableLocal | Test worker    | INFO : Operation successful:     abs=36 rel=7 op=upload upload(/tmp/syncany-action-3148369227803142219.tmp, action-up-A-1559142338823)
29-5-19 15:05:38.904 | UnreliableLocal | Test worker    | INFO : Operation successful:     abs=37 rel=16 op=connect connect
29-5-19 15:05:38.905 | ActionFileHandl | Test worker    | INFO : Scheduling action renewal task for every 2 minutes, for RemoteFile[name=action-up-A-1559142338823] ...
29-5-19 15:05:38.906 | UpOperation     | Test worker    | INFO : Found local transaction to resume.
29-5-19 15:05:38.906 | UpOperation     | Test worker    | INFO : Attempting to find transactionRemoteFile
29-5-19 15:05:38.933 | DatabaseXmlSeri | Test worker    | INFO : - Loading database (FULL) from file /tmp/syncanytest/syncany-190529150308467-95774-client-A/.syncany/state/transaction-database.0000000001.xml ...
29-5-19 15:05:38.948 | DatabaseXmlPars | Test worker    | INFO :    + Added database version A/(A1)/T=1559142332676
29-5-19 15:05:38.949 | DatabaseXmlSeri | Test worker    | INFO : - Loading database (FULL) from file /tmp/syncanytest/syncany-190529150308467-95774-client-A/.syncany/state/transaction-database.0000000002.xml ...
29-5-19 15:05:38.979 | DatabaseXmlPars | Test worker    | INFO :    + Added database version A/(A2)/T=1559142338759
29-5-19 15:05:38.986 | DatabaseXmlSeri | Test worker    | INFO : - Loading database (FULL) from file /tmp/syncanytest/syncany-190529150308467-95774-client-A/.syncany/state/transaction-database.0000000003.xml ...
29-5-19 15:05:38.994 | DatabaseXmlPars | Test worker    | INFO :    + Added database version A/(A3)/T=1559142338762
29-5-19 15:05:38.996 | UnreliableLocal | Test worker    | INFO : Operation successful:     abs=38 rel=10 op=list list(TransactionRemoteFile)
29-5-19 15:05:38.997 | UnreliableLocal | Test worker    | INFO : Operation successful:     abs=39 rel=17 op=connect connect
29-5-19 15:05:38.997 | UnreliableLocal | Test worker    | INFO : Operation successful:     abs=40 rel=3 op=download download(transaction-48dc6d82, /tmp/syncanytest/syncany-190529150308467-95774-client-A/.syncany/cache/temp-transaction-5211733314491569588.tmp)
29-5-19 15:05:38.998 | UnreliableLocal | Test worker    | INFO : Operation successful:     abs=41 rel=18 op=connect connect
29-5-19 15:05:39.023 | UpOperation     | Test worker    | INFO : No DIRTY data found in database (no dirty databases); Nothing to do here.
29-5-19 15:05:39.023 | UpOperation     | Test worker    | INFO : Uploading database: DatabaseVersion [header=A/(A1)/T=1559142332676, chunks=10, multiChunks=2, fileContents=1, fileHistories=1]
29-5-19 15:05:39.024 | UpOperation     | Test worker    | INFO : Saving local delta database, version A/(A1)/T=1559142332676 to file /tmp/syncanytest/syncany-190529150308467-95774-client-A/.syncany/cache/database-A-0000000001 ... 
29-5-19 15:05:39.024 | UpOperation     | Test worker    | INFO : - Saving database to /tmp/syncanytest/syncany-190529150308467-95774-client-A/.syncany/cache/database-A-0000000001 ...
29-5-19 15:05:39.025 | UpOperation     | Test worker    | INFO : Adding shutdown hook (to allow resuming the upload) ...
29-5-19 15:05:39.025 | RemoteTransacti | Test worker    | INFO : - Starting to upload data in commit.
29-5-19 15:05:39.025 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event UpUploadFileInTransactionSyncExternalEvent (org.syncany.operations.daemon.messages.UpUploadFileInTransactionSyncExternalEvent@240b6932) ...
29-5-19 15:05:39.025 | RemoteTransacti | Test worker    | INFO : - Uploading /tmp/syncanytest/syncany-190529150308467-95774-client-A/.syncany/cache/multichunk-d7fc5377336924ee500c72dbf12718c7694677ec to temp. file RemoteFile[name=temp-iqHDS-multichunk-d7fc5377336924ee500c72dbf12718c7694677ec] ...
29-5-19 15:05:39.025 | UnreliableLocal | Test worker    | INFO : Operation successful:     abs=42 rel=8 op=upload upload(/tmp/syncanytest/syncany-190529150308467-95774-client-A/.syncany/cache/multichunk-d7fc5377336924ee500c72dbf12718c7694677ec, temp-iqHDS-multichunk-d7fc5377336924ee500c72dbf12718c7694677ec)
29-5-19 15:05:39.025 | UnreliableLocal | Test worker    | INFO : Operation successful:     abs=43 rel=19 op=connect connect
29-5-19 15:05:39.030 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event UpUploadFileInTransactionSyncExternalEvent (org.syncany.operations.daemon.messages.UpUploadFileInTransactionSyncExternalEvent@516b70d5) ...
29-5-19 15:05:39.034 | RemoteTransacti | Test worker    | INFO : - Uploading /tmp/syncanytest/syncany-190529150308467-95774-client-A/.syncany/cache/database-A-0000000001 to temp. file RemoteFile[name=temp-HzgvY-database-A-0000000001] ...
29-5-19 15:05:39.034 | UnreliableLocal | Test worker    | INFO : Operation successful:     abs=44 rel=9 op=upload upload(/tmp/syncanytest/syncany-190529150308467-95774-client-A/.syncany/cache/database-A-0000000001, temp-HzgvY-database-A-0000000001)
29-5-19 15:05:39.034 | UnreliableLocal | Test worker    | INFO : Operation successful:     abs=45 rel=20 op=connect connect
29-5-19 15:05:39.035 | RemoteTransacti | Test worker    | INFO : - Moving temp. file RemoteFile[name=temp-KZFLK-multichunk-8aef246ccfa42cbd910db0bac56a0c4037f59708] to final location RemoteFile[name=multichunk-8aef246ccfa42cbd910db0bac56a0c4037f59708] ...
29-5-19 15:05:39.035 | UnreliableLocal | Test worker    | INFO : Operation successful:     abs=46 rel=1 op=move move(temp-KZFLK-multichunk-8aef246ccfa42cbd910db0bac56a0c4037f59708,multichunk-8aef246ccfa42cbd910db0bac56a0c4037f59708)
29-5-19 15:05:39.035 | UnreliableLocal | Test worker    | INFO : Operation successful:     abs=47 rel=21 op=connect connect
29-5-19 15:05:39.035 | RemoteTransacti | Test worker    | INFO : - Moving temp. file RemoteFile[name=temp-iqHDS-multichunk-d7fc5377336924ee500c72dbf12718c7694677ec] to final location RemoteFile[name=multichunk-d7fc5377336924ee500c72dbf12718c7694677ec] ...
29-5-19 15:05:39.035 | UnreliableLocal | Test worker    | INFO : Operation successful:     abs=48 rel=2 op=move move(temp-iqHDS-multichunk-d7fc5377336924ee500c72dbf12718c7694677ec,multichunk-d7fc5377336924ee500c72dbf12718c7694677ec)
29-5-19 15:05:39.035 | UnreliableLocal | Test worker    | INFO : Operation successful:     abs=49 rel=22 op=connect connect
29-5-19 15:05:39.035 | RemoteTransacti | Test worker    | INFO : - Moving temp. file RemoteFile[name=temp-HzgvY-database-A-0000000001] to final location RemoteFile[name=database-A-0000000001] ...
29-5-19 15:05:39.035 | UnreliableLocal | Test worker    | INFO : Operation successful:     abs=50 rel=3 op=move move(temp-HzgvY-database-A-0000000001,database-A-0000000001)
29-5-19 15:05:39.035 | UnreliableLocal | Test worker    | INFO : Operation successful:     abs=51 rel=23 op=connect connect
29-5-19 15:05:39.035 | RemoteTransacti | Test worker    | INFO : - Deleting remote transaction file RemoteFile[name=transaction-48dc6d82] ...
29-5-19 15:05:39.035 | UnreliableLocal | Test worker    | INFO : Operation successful:     abs=52 rel=1 op=delete delete(transaction-48dc6d82)
29-5-19 15:05:39.035 | UnreliableLocal | Test worker    | INFO : Operation successful:     abs=53 rel=24 op=connect connect
29-5-19 15:05:39.035 | RemoteTransacti | Test worker    | INFO : END of TX.commmit(): Succesfully committed transaction.
29-5-19 15:05:39.035 | RemoteTransacti | Test worker    | INFO : END of TX.delTemp(): Sucessfully deleted final files.
29-5-19 15:05:39.035 | UpOperation     | Test worker    | INFO : Persisting local SQL database (new database version A/(A1)/T=1559142332676) ...
29-5-19 15:05:39.046 | UpOperation     | Test worker    | INFO : Removing DIRTY database versions from database ...
29-5-19 15:05:39.054 | UpOperation     | Test worker    | INFO : Adding database version to result changes:DatabaseVersion [header=A/(A1)/T=1559142332676, chunks=10, multiChunks=2, fileContents=1, fileHistories=1]
29-5-19 15:05:39.054 | UpOperation     | Test worker    | INFO : Committing local database.
29-5-19 15:05:39.054 | UpOperation     | Test worker    | FINE : Waiting for new database version.
29-5-19 15:05:39.054 | UpOperation     | Test worker    | FINE : Took new database version: DatabaseVersion [header=A/(A2)/T=1559142338759, chunks=1, multiChunks=1, fileContents=1, fileHistories=1]
29-5-19 15:05:39.054 | UpOperation     | Test worker    | INFO : Uploading database: DatabaseVersion [header=A/(A2)/T=1559142338759, chunks=1, multiChunks=1, fileContents=1, fileHistories=1]
29-5-19 15:05:39.055 | UpOperation     | Test worker    | INFO : Saving local delta database, version A/(A2)/T=1559142338759 to file /tmp/syncanytest/syncany-190529150308467-95774-client-A/.syncany/cache/database-A-0000000002 ... 
29-5-19 15:05:39.055 | UpOperation     | Test worker    | INFO : - Saving database to /tmp/syncanytest/syncany-190529150308467-95774-client-A/.syncany/cache/database-A-0000000002 ...
29-5-19 15:05:39.056 | UpOperation     | Test worker    | INFO : Adding shutdown hook (to allow resuming the upload) ...
29-5-19 15:05:39.056 | RemoteTransacti | Test worker    | INFO : Starting TX.commit() ...
29-5-19 15:05:39.057 | RemoteTransacti | Test worker    | INFO : Wrote transaction manifest to temporary file: /tmp/syncanytest/syncany-190529150308467-95774-client-A/.syncany/cache/temp-transaction-580379785367113860.tmp
29-5-19 15:05:39.057 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event UpUploadFileSyncExternalEvent (org.syncany.operations.daemon.messages.UpUploadFileSyncExternalEvent@77c191b9) ...
29-5-19 15:05:39.057 | RemoteTransacti | Test worker    | INFO : - Uploading remote transaction file RemoteFile[name=transaction-7b0e59fc] ...
29-5-19 15:05:39.057 | UnreliableLocal | Test worker    | INFO : Operation successful:     abs=54 rel=10 op=upload upload(/tmp/syncanytest/syncany-190529150308467-95774-client-A/.syncany/cache/temp-transaction-580379785367113860.tmp, transaction-7b0e59fc)
29-5-19 15:05:39.057 | UnreliableLocal | Test worker    | INFO : Operation successful:     abs=55 rel=25 op=connect connect
29-5-19 15:05:39.058 | RemoteTransacti | Test worker    | INFO : - Starting to upload data in commit.
29-5-19 15:05:39.058 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event UpUploadFileInTransactionSyncExternalEvent (org.syncany.operations.daemon.messages.UpUploadFileInTransactionSyncExternalEvent@3cb5d6ef) ...
29-5-19 15:05:39.058 | RemoteTransacti | Test worker    | INFO : - Uploading /tmp/syncanytest/syncany-190529150308467-95774-client-A/.syncany/cache/multichunk-817be3c4adfb5263915d4750e9f1a85e899b137c to temp. file RemoteFile[name=temp-blusS-multichunk-817be3c4adfb5263915d4750e9f1a85e899b137c] ...
29-5-19 15:05:39.058 | UnreliableLocal | Test worker    | INFO : Operation successful:     abs=56 rel=11 op=upload upload(/tmp/syncanytest/syncany-190529150308467-95774-client-A/.syncany/cache/multichunk-817be3c4adfb5263915d4750e9f1a85e899b137c, temp-blusS-multichunk-817be3c4adfb5263915d4750e9f1a85e899b137c)
29-5-19 15:05:39.058 | UnreliableLocal | Test worker    | INFO : Operation successful:     abs=57 rel=26 op=connect connect
29-5-19 15:05:39.064 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event UpUploadFileInTransactionSyncExternalEvent (org.syncany.operations.daemon.messages.UpUploadFileInTransactionSyncExternalEvent@622704ea) ...
29-5-19 15:05:39.064 | RemoteTransacti | Test worker    | INFO : - Uploading /tmp/syncanytest/syncany-190529150308467-95774-client-A/.syncany/cache/database-A-0000000002 to temp. file RemoteFile[name=temp-UhPtv-database-A-0000000002] ...
29-5-19 15:05:39.064 | UnreliableLocal | Test worker    | INFO : Operation successful:     abs=58 rel=12 op=upload upload(/tmp/syncanytest/syncany-190529150308467-95774-client-A/.syncany/cache/database-A-0000000002, temp-UhPtv-database-A-0000000002)
29-5-19 15:05:39.064 | UnreliableLocal | Test worker    | INFO : Operation successful:     abs=59 rel=27 op=connect connect
29-5-19 15:05:39.064 | RemoteTransacti | Test worker    | INFO : - Moving temp. file RemoteFile[name=temp-blusS-multichunk-817be3c4adfb5263915d4750e9f1a85e899b137c] to final location RemoteFile[name=multichunk-817be3c4adfb5263915d4750e9f1a85e899b137c] ...
29-5-19 15:05:39.064 | UnreliableLocal | Test worker    | INFO : Operation successful:     abs=60 rel=4 op=move move(temp-blusS-multichunk-817be3c4adfb5263915d4750e9f1a85e899b137c,multichunk-817be3c4adfb5263915d4750e9f1a85e899b137c)
29-5-19 15:05:39.064 | UnreliableLocal | Test worker    | INFO : Operation successful:     abs=61 rel=28 op=connect connect
29-5-19 15:05:39.064 | RemoteTransacti | Test worker    | INFO : - Moving temp. file RemoteFile[name=temp-UhPtv-database-A-0000000002] to final location RemoteFile[name=database-A-0000000002] ...
29-5-19 15:05:39.064 | UnreliableLocal | Test worker    | INFO : Operation successful:     abs=62 rel=5 op=move move(temp-UhPtv-database-A-0000000002,database-A-0000000002)
29-5-19 15:05:39.065 | UnreliableLocal | Test worker    | INFO : Operation successful:     abs=63 rel=29 op=connect connect
29-5-19 15:05:39.065 | RemoteTransacti | Test worker    | INFO : - Deleting remote transaction file RemoteFile[name=transaction-7b0e59fc] ...
29-5-19 15:05:39.067 | UnreliableLocal | Test worker    | INFO : Operation successful:     abs=64 rel=2 op=delete delete(transaction-7b0e59fc)
29-5-19 15:05:39.067 | UnreliableLocal | Test worker    | INFO : Operation successful:     abs=65 rel=30 op=connect connect
29-5-19 15:05:39.067 | RemoteTransacti | Test worker    | INFO : END of TX.commmit(): Succesfully committed transaction.
29-5-19 15:05:39.067 | RemoteTransacti | Test worker    | INFO : END of TX.delTemp(): Sucessfully deleted final files.
29-5-19 15:05:39.067 | UpOperation     | Test worker    | INFO : Persisting local SQL database (new database version A/(A2)/T=1559142338759) ...
29-5-19 15:05:39.069 | UpOperation     | Test worker    | INFO : Removing DIRTY database versions from database ...
29-5-19 15:05:39.080 | UpOperation     | Test worker    | INFO : Adding database version to result changes:DatabaseVersion [header=A/(A2)/T=1559142338759, chunks=1, multiChunks=1, fileContents=1, fileHistories=1]
29-5-19 15:05:39.080 | UpOperation     | Test worker    | INFO : Committing local database.
29-5-19 15:05:39.080 | UpOperation     | Test worker    | FINE : Waiting for new database version.
29-5-19 15:05:39.080 | UpOperation     | Test worker    | FINE : Took new database version: DatabaseVersion [header=A/(A3)/T=1559142338762, chunks=1, multiChunks=1, fileContents=1, fileHistories=1]
29-5-19 15:05:39.080 | UpOperation     | Test worker    | INFO : Uploading database: DatabaseVersion [header=A/(A3)/T=1559142338762, chunks=1, multiChunks=1, fileContents=1, fileHistories=1]
29-5-19 15:05:39.081 | UpOperation     | Test worker    | INFO : Saving local delta database, version A/(A3)/T=1559142338762 to file /tmp/syncanytest/syncany-190529150308467-95774-client-A/.syncany/cache/database-A-0000000003 ... 
29-5-19 15:05:39.081 | UpOperation     | Test worker    | INFO : - Saving database to /tmp/syncanytest/syncany-190529150308467-95774-client-A/.syncany/cache/database-A-0000000003 ...
29-5-19 15:05:39.082 | UpOperation     | Test worker    | INFO : Adding shutdown hook (to allow resuming the upload) ...
29-5-19 15:05:39.082 | RemoteTransacti | Test worker    | INFO : Starting TX.commit() ...
29-5-19 15:05:39.085 | RemoteTransacti | Test worker    | INFO : Wrote transaction manifest to temporary file: /tmp/syncanytest/syncany-190529150308467-95774-client-A/.syncany/cache/temp-transaction-6239334121175761438.tmp
29-5-19 15:05:39.085 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event UpUploadFileSyncExternalEvent (org.syncany.operations.daemon.messages.UpUploadFileSyncExternalEvent@7f32b8d1) ...
29-5-19 15:05:39.090 | RemoteTransacti | Test worker    | INFO : - Uploading remote transaction file RemoteFile[name=transaction-512bcf7b] ...
29-5-19 15:05:39.090 | UnreliableLocal | Test worker    | INFO : Operation successful:     abs=66 rel=13 op=upload upload(/tmp/syncanytest/syncany-190529150308467-95774-client-A/.syncany/cache/temp-transaction-6239334121175761438.tmp, transaction-512bcf7b)
29-5-19 15:05:39.090 | UnreliableLocal | Test worker    | INFO : Operation successful:     abs=67 rel=31 op=connect connect
29-5-19 15:05:39.091 | RemoteTransacti | Test worker    | INFO : - Starting to upload data in commit.
29-5-19 15:05:39.091 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event UpUploadFileInTransactionSyncExternalEvent (org.syncany.operations.daemon.messages.UpUploadFileInTransactionSyncExternalEvent@3f305664) ...
29-5-19 15:05:39.091 | RemoteTransacti | Test worker    | INFO : - Uploading /tmp/syncanytest/syncany-190529150308467-95774-client-A/.syncany/cache/multichunk-505a59962ec4c33bf6865a89094dc1ea406f4370 to temp. file RemoteFile[name=temp-agqti-multichunk-505a59962ec4c33bf6865a89094dc1ea406f4370] ...
29-5-19 15:05:39.091 | UnreliableLocal | Test worker    | INFO : Operation successful:     abs=68 rel=14 op=upload upload(/tmp/syncanytest/syncany-190529150308467-95774-client-A/.syncany/cache/multichunk-505a59962ec4c33bf6865a89094dc1ea406f4370, temp-agqti-multichunk-505a59962ec4c33bf6865a89094dc1ea406f4370)
29-5-19 15:05:39.091 | UnreliableLocal | Test worker    | INFO : Operation successful:     abs=69 rel=32 op=connect connect
29-5-19 15:05:39.092 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event UpUploadFileInTransactionSyncExternalEvent (org.syncany.operations.daemon.messages.UpUploadFileInTransactionSyncExternalEvent@28466d9) ...
29-5-19 15:05:39.092 | RemoteTransacti | Test worker    | INFO : - Uploading /tmp/syncanytest/syncany-190529150308467-95774-client-A/.syncany/cache/database-A-0000000003 to temp. file RemoteFile[name=temp-vOuin-database-A-0000000003] ...
29-5-19 15:05:39.092 | UnreliableLocal | Test worker    | INFO : Operation successful:     abs=70 rel=15 op=upload upload(/tmp/syncanytest/syncany-190529150308467-95774-client-A/.syncany/cache/database-A-0000000003, temp-vOuin-database-A-0000000003)
29-5-19 15:05:39.092 | UnreliableLocal | Test worker    | INFO : Operation successful:     abs=71 rel=33 op=connect connect
29-5-19 15:05:39.092 | RemoteTransacti | Test worker    | INFO : - Moving temp. file RemoteFile[name=temp-agqti-multichunk-505a59962ec4c33bf6865a89094dc1ea406f4370] to final location RemoteFile[name=multichunk-505a59962ec4c33bf6865a89094dc1ea406f4370] ...
29-5-19 15:05:39.092 | UnreliableLocal | Test worker    | INFO : Operation successful:     abs=72 rel=6 op=move move(temp-agqti-multichunk-505a59962ec4c33bf6865a89094dc1ea406f4370,multichunk-505a59962ec4c33bf6865a89094dc1ea406f4370)
29-5-19 15:05:39.092 | UnreliableLocal | Test worker    | INFO : Operation successful:     abs=73 rel=34 op=connect connect
29-5-19 15:05:39.092 | RemoteTransacti | Test worker    | INFO : - Moving temp. file RemoteFile[name=temp-vOuin-database-A-0000000003] to final location RemoteFile[name=database-A-0000000003] ...
29-5-19 15:05:39.092 | UnreliableLocal | Test worker    | INFO : Operation successful:     abs=74 rel=7 op=move move(temp-vOuin-database-A-0000000003,database-A-0000000003)
29-5-19 15:05:39.092 | UnreliableLocal | Test worker    | INFO : Operation successful:     abs=75 rel=35 op=connect connect
29-5-19 15:05:39.093 | RemoteTransacti | Test worker    | INFO : - Deleting remote transaction file RemoteFile[name=transaction-512bcf7b] ...
29-5-19 15:05:39.093 | UnreliableLocal | Test worker    | INFO : Operation successful:     abs=76 rel=3 op=delete delete(transaction-512bcf7b)
29-5-19 15:05:39.093 | UnreliableLocal | Test worker    | INFO : Operation successful:     abs=77 rel=36 op=connect connect
29-5-19 15:05:39.093 | RemoteTransacti | Test worker    | INFO : END of TX.commmit(): Succesfully committed transaction.
29-5-19 15:05:39.093 | RemoteTransacti | Test worker    | INFO : END of TX.delTemp(): Sucessfully deleted final files.
29-5-19 15:05:39.093 | UpOperation     | Test worker    | INFO : Persisting local SQL database (new database version A/(A3)/T=1559142338762) ...
29-5-19 15:05:39.100 | UpOperation     | Test worker    | INFO : Removing DIRTY database versions from database ...
29-5-19 15:05:39.109 | UpOperation     | Test worker    | INFO : Adding database version to result changes:DatabaseVersion [header=A/(A3)/T=1559142338762, chunks=1, multiChunks=1, fileContents=1, fileHistories=1]
29-5-19 15:05:39.109 | UpOperation     | Test worker    | INFO : Committing local database.
29-5-19 15:05:39.109 | UpOperation     | Test worker    | FINE : Waiting for new database version.
29-5-19 15:05:39.109 | UpOperation     | Test worker    | FINE : Took new database version: DatabaseVersion [header=UnknownMachine/()/T=1559142338995, chunks=0, multiChunks=0, fileContents=0, fileHistories=0]
29-5-19 15:05:39.109 | UpOperation     | Test worker    | INFO : Sync up done.
29-5-19 15:05:39.111 | ActionFileHandl | Test worker    | INFO : Finishing action for RemoteFile[name=action-up-A-1559142338823] ...
29-5-19 15:05:39.118 | ActionFileHandl | Test worker    | INFO : Deleting action file: RemoteFile[name=action-up-A-1559142338823]
29-5-19 15:05:39.118 | UnreliableLocal | Test worker    | INFO : Operation successful:     abs=78 rel=4 op=delete delete(action-up-A-1559142338823)
29-5-19 15:05:39.118 | UnreliableLocal | Test worker    | INFO : Operation successful:     abs=79 rel=37 op=connect connect
29-5-19 15:05:39.118 | AbstractTransfe | Test worker    | INFO : Cleaning own old action files ...
29-5-19 15:05:39.118 | UnreliableLocal | Test worker    | INFO : Operation successful:     abs=80 rel=11 op=list list(ActionRemoteFile)
29-5-19 15:05:39.119 | UnreliableLocal | Test worker    | INFO : Operation successful:     abs=81 rel=38 op=connect connect
29-5-19 15:05:39.119 | UnreliableLocal | Test worker    | INFO : Operation successful:     abs=82 rel=12 op=list list(TransactionRemoteFile)
29-5-19 15:05:39.119 | UnreliableLocal | Test worker    | INFO : Operation successful:     abs=83 rel=39 op=connect connect
29-5-19 15:05:39.119 | AbstractTransfe | Test worker    | INFO : - Deleting own action file RemoteFile[name=action-up-A-1559142332113] ...
29-5-19 15:05:39.119 | UnreliableLocal | Test worker    | INFO : Operation successful:     abs=84 rel=5 op=delete delete(action-up-A-1559142332113)
29-5-19 15:05:39.119 | UnreliableLocal | Test worker    | INFO : Operation successful:     abs=85 rel=40 op=connect connect
29-5-19 15:05:39.119 | UnreliableLocal | Test worker    | INFO : Operation successful:     abs=86 rel=3 op=disconnect disconnect
29-5-19 15:05:39.119 | Cache           | Test worker    | INFO : Cache size okay (5131 KB), no need to clean (keep size is 500 MB)
29-5-19 15:05:39.119 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event UpEndSyncExternalEvent (org.syncany.operations.daemon.messages.UpEndSyncExternalEvent@1e632598) ...