Class org.syncany.tests.integration.scenarios.DoSameActionAtTwoClientsTest

1

tests

0

failures

0

ignored

0.748s

duration

100%

successful

Tests

Test Duration Result
testIssue76 0.748s passed

Standard error

16-2-17 8:19:03.469 | org.syncany.plu | Test worker    | FINE : Validating required fields
16-2-17 8:19:03.469 | LocalTransferMa | Test worker    | INFO : testTargetExists: Target exists.
16-2-17 8:19:03.487 | org.syncany.plu | Test worker    | FINE : Validating required fields
16-2-17 8:19:03.494 | TransferManager | Test worker    | INFO : Building LocalTransferManager from config 'syncany-170216081832804-36803-client-A' ...
16-2-17 8:19:03.494 | TransferManager | Test worker    | INFO : - With feature ReadAfterWriteConsistent
16-2-17 8:19:03.494 | TransferManager | Test worker    | INFO : - With feature Retriable
16-2-17 8:19:03.498 | TransferManager | Test worker    | FINE : - Checking if selected features supported ...
16-2-17 8:19:03.498 | TransferManager | Test worker    | FINE : - Checking for duplicate features ...
16-2-17 8:19:03.498 | TransferManager | Test worker    | FINE : - Checking required feature TransactionAware in LocalTransferManager ...
16-2-17 8:19:03.498 | TransferManager | Test worker    | FINE : - Checking required feature Retriable in LocalTransferManager ...
16-2-17 8:19:03.498 | TransferManager | Test worker    | INFO : - SKIPPING unsupported optional feature ReadAfterWriteConsistent
16-2-17 8:19:03.498 | TransferManager | Test worker    | FINE : - Wrapping TransferManager LocalTransferManager in RetriableFeatureTransferManager
16-2-17 8:19:03.498 | org.syncany.plu | Test worker    | FINE : Validating required fields
16-2-17 8:19:03.500 | TransferManager | Test worker    | INFO : Building LocalTransferManager from config 'syncany-170216081832804-36803-client-A' ...
16-2-17 8:19:03.500 | TransferManager | Test worker    | INFO : - With feature ReadAfterWriteConsistent
16-2-17 8:19:03.500 | TransferManager | Test worker    | INFO : - With feature Retriable
16-2-17 8:19:03.500 | TransferManager | Test worker    | INFO : - With feature PathAware
16-2-17 8:19:03.500 | TransferManager | Test worker    | INFO : - With feature TransactionAware
16-2-17 8:19:03.500 | TransferManager | Test worker    | FINE : - Checking if selected features supported ...
16-2-17 8:19:03.500 | TransferManager | Test worker    | FINE : - Checking for duplicate features ...
16-2-17 8:19:03.501 | TransferManager | Test worker    | FINE : - Checking required feature TransactionAware in LocalTransferManager ...
16-2-17 8:19:03.501 | TransferManager | Test worker    | FINE : - Checking required feature Retriable in LocalTransferManager ...
16-2-17 8:19:03.501 | TransferManager | Test worker    | INFO : - SKIPPING unsupported optional feature ReadAfterWriteConsistent
16-2-17 8:19:03.501 | TransferManager | Test worker    | FINE : - Wrapping TransferManager LocalTransferManager in RetriableFeatureTransferManager
16-2-17 8:19:03.501 | TransferManager | Test worker    | INFO : - SKIPPING unsupported optional feature PathAware
16-2-17 8:19:03.501 | TransferManager | Test worker    | FINE : - Wrapping TransferManager RetriableFeatureTransferManager in TransactionAwareFeatureTransferManager
16-2-17 8:19:03.538 | hsqldb.db.HSQLD | Test worker    | INFO : Checkpoint start
16-2-17 8:19:03.539 | hsqldb.db.HSQLD | Test worker    | INFO : checkpointClose start
16-2-17 8:19:03.545 | hsqldb.db.HSQLD | Test worker    | FINE : log close size: 0
16-2-17 8:19:03.548 | hsqldb.db.HSQLD | Test worker    | INFO : checkpointClose end
16-2-17 8:19:03.548 | hsqldb.db.HSQLD | Test worker    | INFO : Checkpoint end - txts: 1
16-2-17 8:19:03.557 | DatabaseConnect | Test worker    | INFO : Found 0 tables.
16-2-17 8:19:03.557 | DatabaseConnect | Test worker    | INFO : Database has no tables. Creating tables from script.create.all.sql
16-2-17 8:19:03.557 | SqlRunner       | Test worker    | INFO : SQL (comment): -- Tables
16-2-17 8:19:03.557 | 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) ); 
16-2-17 8:19:03.560 | 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 ); 
16-2-17 8:19:03.562 | 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 ); 
16-2-17 8:19:03.564 | 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 ); 
16-2-17 8:19:03.567 | 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 ); 
16-2-17 8:19:03.569 | 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 ); 
16-2-17 8:19:03.571 | 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 ); 
16-2-17 8:19:03.573 | SqlRunner       | Test worker    | INFO : SQL:  CREATE CACHED TABLE IF NOT EXISTS  multichunk ( id varchar(40) NOT NULL, databaseversion_id int NOT NULL, size bigint NOT NULL, PRIMARY KEY (id), FOREIGN KEY (databaseversion_id) REFERENCES databaseversion (id) ON DELETE NO ACTION ON UPDATE NO ACTION ); 
16-2-17 8:19:03.575 | 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 ); 
16-2-17 8:19:03.577 | 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) ); 
16-2-17 8:19:03.579 | 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) ); 
16-2-17 8:19:03.581 | 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) ); 
16-2-17 8:19:03.583 | SqlRunner       | Test worker    | INFO : SQL (comment): -- Non-primary indices
16-2-17 8:19:03.583 | SqlRunner       | Test worker    | INFO : SQL:   CREATE INDEX idx_databaseversion_status ON databaseversion (status); 
16-2-17 8:19:03.584 | hsqldb.db.HSQLD | Test worker    | INFO : dataFileCache open start
16-2-17 8:19:03.586 | hsqldb.db.HSQLD | Test worker    | INFO : dataFileCache open end
16-2-17 8:19:03.588 | SqlRunner       | Test worker    | INFO : SQL: CREATE INDEX idx_databaseversion_vectorclock_serialized ON databaseversion (vectorclock_serialized); 
16-2-17 8:19:03.590 | SqlRunner       | Test worker    | INFO : SQL: CREATE INDEX idx_fileversion_path ON fileversion (path); 
16-2-17 8:19:03.591 | SqlRunner       | Test worker    | INFO : SQL: CREATE INDEX idx_fileversion_status ON fileversion (status); 
16-2-17 8:19:03.593 | SqlRunner       | Test worker    | INFO : SQL: CREATE INDEX idx_fileversion_filecontent_checksum ON fileversion (filecontent_checksum); 
16-2-17 8:19:03.596 | SqlRunner       | Test worker    | INFO : SQL (comment): -- Views
16-2-17 8:19:03.596 | 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'; 
16-2-17 8:19:03.598 | 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'; 
16-2-17 8:19:03.601 | 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; 
16-2-17 8:19:03.603 | 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'; 
16-2-17 8:19:03.609 | SqlRunner       | Test worker    | INFO : SQL (comment): -- Full Views
16-2-17 8:19:03.609 | 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; 
16-2-17 8:19:03.612 | 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; 
16-2-17 8:19:03.614 | SqlRunner       | Test worker    | INFO : SQL (comment): -- Functions
16-2-17 8:19:03.614 | SqlRunner       | Test worker    | INFO : SQL (new delimiter): end;
16-2-17 8:19:03.614 | 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; 
16-2-17 8:19:03.617 | UpOperation     | Test worker    | INFO : 
16-2-17 8:19:03.617 | UpOperation     | Test worker    | INFO : Running 'Sync up' at client A ...
16-2-17 8:19:03.617 | UpOperation     | Test worker    | INFO : --------------------------------------------
16-2-17 8:19:03.617 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event UpStartSyncExternalEvent (org.syncany.operations.daemon.messages.UpStartSyncExternalEvent@58acee37) ...
16-2-17 8:19:03.618 | DatabaseConnect | Test worker    | INFO : Found 12 tables.
16-2-17 8:19:03.618 | StatusOperation | Test worker    | INFO : 
16-2-17 8:19:03.618 | StatusOperation | Test worker    | INFO : Running 'Status' at client A ...
16-2-17 8:19:03.618 | StatusOperation | Test worker    | INFO : --------------------------------------------
16-2-17 8:19:03.618 | StatusOperation | Test worker    | INFO : Force checksum ENABLED.
16-2-17 8:19:03.618 | StatusOperation | Test worker    | INFO : Querying current file tree from database ...
16-2-17 8:19:03.618 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event StatusStartSyncExternalEvent (org.syncany.operations.daemon.messages.StatusStartSyncExternalEvent@2a9dd6c0) ...
16-2-17 8:19:03.618 | StatusOperation | Test worker    | INFO : Analyzing local folder /tmp/syncanytest/syncany-170216081832804-36803-client-A ...
16-2-17 8:19:03.619 | StatusOperation | Test worker    | FINE : - Ignoring file (syncany app-related): .syncany
16-2-17 8:19:03.619 | StatusOperation | Test worker    | FINE : - New file: sphinxbase-0.8
16-2-17 8:19:03.619 | StatusOperation | Test worker    | FINE : - New file: sphinxbase-0.8/src
16-2-17 8:19:03.619 | StatusOperation | Test worker    | FINE : - New file: sphinxbase-0.8/src/sphinx_adtools
16-2-17 8:19:03.619 | StatusOperation | Test worker    | FINE : - New file: sphinxbase-0.8/src/sphinx_adtools/sphinx_pitch.c
16-2-17 8:19:03.619 | StatusOperation | Test worker    | FINE : - New file: sphinxbase-0.8/config.sub
16-2-17 8:19:03.619 | StatusOperation | Test worker    | FINE : - New file: sphinxbase-0.8/win32
16-2-17 8:19:03.619 | StatusOperation | Test worker    | FINE : - New file: sphinxbase-0.8/win32/sphinx_jsgf2fsg
16-2-17 8:19:03.620 | StatusOperation | Test worker    | FINE : - New file: sphinxbase-0.8/win32/sphinx_jsgf2fsg/sphinx_jsgf2fsg.vcxproj
16-2-17 8:19:03.620 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event StatusEndSyncExternalEvent (org.syncany.operations.daemon.messages.StatusEndSyncExternalEvent@3ad51f98) ...
16-2-17 8:19:03.620 | AbstractTransfe | Test worker    | INFO : Looking for other running remote operations ...
16-2-17 8:19:03.620 | DatabaseConnect | Test worker    | INFO : Found 12 tables.
16-2-17 8:19:03.620 | LsRemoteOperati | Test worker    | INFO : 
16-2-17 8:19:03.620 | LsRemoteOperati | Test worker    | INFO : Running 'Remote Status' at client A ...
16-2-17 8:19:03.621 | LsRemoteOperati | Test worker    | INFO : --------------------------------------------
16-2-17 8:19:03.621 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event LsRemoteStartSyncExternalEvent (org.syncany.operations.daemon.messages.LsRemoteStartSyncExternalEvent@8a499a) ...
16-2-17 8:19:03.621 | LsRemoteOperati | Test worker    | INFO : Retrieving remote database list.
16-2-17 8:19:03.621 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event LsRemoteEndSyncExternalEvent (org.syncany.operations.daemon.messages.LsRemoteEndSyncExternalEvent@223889c6) ...
16-2-17 8:19:03.621 | UpOperation     | Test worker    | INFO : No remote changes, ready to upload.
16-2-17 8:19:03.621 | ActionFileHandl | Test worker    | INFO : Starting action for RemoteFile[name=action-up-A-1487233143501] ...
16-2-17 8:19:03.621 | ActionFileHandl | Test worker    | INFO : Uploading action file: RemoteFile[name=action-up-A-1487233143501]
16-2-17 8:19:03.621 | ActionFileHandl | Test worker    | INFO : Scheduling action renewal task for every 2 minutes, for RemoteFile[name=action-up-A-1487233143501] ...
16-2-17 8:19:03.622 | DatabaseConnect | Test worker    | INFO : Found 12 tables.
16-2-17 8:19:03.627 | TransactionAwar | Test worker    | INFO : Done rolling back previous transactions.
16-2-17 8:19:03.627 | AsyncIndexer    | AsyncI/syncany | INFO : Starting Indexing.
16-2-17 8:19:03.627 | InternalEventBu | AsyncI/syncany | INFO : Event bus 'LocalEventBus': Posting event UpIndexStartSyncExternalEvent (org.syncany.operations.daemon.messages.UpIndexStartSyncExternalEvent@6fd09ac5) ...
16-2-17 8:19:03.627 | Indexer         | AsyncI/syncany | FINE : - Looking for deleted files ...
16-2-17 8:19:03.627 | Indexer         | AsyncI/syncany | FINE : - +File /tmp/syncanytest/syncany-170216081832804-36803-client-A/sphinxbase-0.8
16-2-17 8:19:03.627 | Indexer         | AsyncI/syncany | FINE : - /File: sphinxbase-0.8 (directory/symlink/0-byte-file)
16-2-17 8:19:03.628 | Indexer         | AsyncI/syncany | FINE :    * No old file history found, starting new history (path: sphinxbase-0.8, FOLDER)
16-2-17 8:19:03.628 | FileVersionComp | AsyncI/syncany | INFO :      - [DELETED]: Local file DIFFERS from file version, actual file is NULL, for file sphinxbase-0.8
16-2-17 8:19:03.629 | Indexer         | AsyncI/syncany | INFO :    * Added file version:    FileVersion [version=1, path=sphinxbase-0.8, type=FOLDER, status=NEW, size=4096, lastModified=Thu Feb 16 08:19:03 UTC 2017, linkTarget=null, checksum=null, updated=Thu Feb 16 08:19:03 UTC 2017, posixPermissions=rwxrwxr-x, dosAttributes=--a-]
16-2-17 8:19:03.629 | Indexer         | AsyncI/syncany | INFO :      based on file version: null
16-2-17 8:19:03.629 | InternalEventBu | AsyncI/syncany | INFO : Event bus 'LocalEventBus': Posting event UpIndexChangesDetectedSyncExternalEvent (org.syncany.operations.daemon.messages.UpIndexChangesDetectedSyncExternalEvent@28c315f8) ...
16-2-17 8:19:03.629 | Indexer         | AsyncI/syncany | FINE : - +File /tmp/syncanytest/syncany-170216081832804-36803-client-A/sphinxbase-0.8/config.sub
16-2-17 8:19:03.629 | Indexer         | AsyncI/syncany | FINE : - +FileContent: /tmp/syncanytest/syncany-170216081832804-36803-client-A/sphinxbase-0.8/config.sub
16-2-17 8:19:03.632 | Indexer         | AsyncI/syncany | FINE : - Chunk new: 23dfa10beaedb1d38157c1acc50dc4ea102859b1
16-2-17 8:19:03.632 | Indexer         | AsyncI/syncany | FINE : - +MultiChunk 76d051f0ac0e510d3c0b4766bd7c7adbc6ea7e0b
16-2-17 8:19:03.633 | Indexer         | AsyncI/syncany | FINE : - Chunk > MultiChunk: 23dfa10beaedb1d38157c1acc50dc4ea102859b1 > 76d051f0ac0e510d3c0b4766bd7c7adbc6ea7e0b
16-2-17 8:19:03.633 | Indexer         | AsyncI/syncany | FINE : - Chunk > FileContent: 23dfa10beaedb1d38157c1acc50dc4ea102859b1 > /tmp/syncanytest/syncany-170216081832804-36803-client-A/sphinxbase-0.8/config.sub
16-2-17 8:19:03.633 | Indexer         | AsyncI/syncany | FINE : - /File: sphinxbase-0.8/config.sub (checksum 23dfa10beaedb1d38157c1acc50dc4ea102859b1)
16-2-17 8:19:03.634 | Indexer         | AsyncI/syncany | FINE :    * No old file history found, starting new history (path: sphinxbase-0.8/config.sub, checksum: 23dfa10beaedb1d38157c1acc50dc4ea102859b1)
16-2-17 8:19:03.635 | FileVersionComp | AsyncI/syncany | INFO :      - [DELETED]: Local file DIFFERS from file version, actual file is NULL, for file sphinxbase-0.8/config.sub
16-2-17 8:19:03.635 | Indexer         | AsyncI/syncany | INFO :    * Added file version:    FileVersion [version=1, path=sphinxbase-0.8/config.sub, type=FILE, status=NEW, size=51200, lastModified=Thu Feb 16 08:19:03 UTC 2017, linkTarget=null, checksum=23dfa10beaedb1d38157c1acc50dc4ea102859b1, updated=Thu Feb 16 08:19:03 UTC 2017, posixPermissions=rw-rw-r--, dosAttributes=--a-]
16-2-17 8:19:03.635 | Indexer         | AsyncI/syncany | INFO :      based on file version: null
16-2-17 8:19:03.635 | Indexer         | AsyncI/syncany | FINE : - +File /tmp/syncanytest/syncany-170216081832804-36803-client-A/sphinxbase-0.8/src
16-2-17 8:19:03.636 | Indexer         | AsyncI/syncany | FINE : - /File: sphinxbase-0.8/src (directory/symlink/0-byte-file)
16-2-17 8:19:03.639 | Indexer         | AsyncI/syncany | FINE :    * No old file history found, starting new history (path: sphinxbase-0.8/src, FOLDER)
16-2-17 8:19:03.639 | FileVersionComp | AsyncI/syncany | INFO :      - [DELETED]: Local file DIFFERS from file version, actual file is NULL, for file sphinxbase-0.8/src
16-2-17 8:19:03.639 | Indexer         | AsyncI/syncany | INFO :    * Added file version:    FileVersion [version=1, path=sphinxbase-0.8/src, type=FOLDER, status=NEW, size=4096, lastModified=Thu Feb 16 08:19:03 UTC 2017, linkTarget=null, checksum=null, updated=Thu Feb 16 08:19:03 UTC 2017, posixPermissions=rwxrwxr-x, dosAttributes=--a-]
16-2-17 8:19:03.639 | Indexer         | AsyncI/syncany | INFO :      based on file version: null
16-2-17 8:19:03.639 | Indexer         | AsyncI/syncany | FINE : - +File /tmp/syncanytest/syncany-170216081832804-36803-client-A/sphinxbase-0.8/src/sphinx_adtools
16-2-17 8:19:03.639 | Indexer         | AsyncI/syncany | FINE : - /File: sphinxbase-0.8/src/sphinx_adtools (directory/symlink/0-byte-file)
16-2-17 8:19:03.639 | Indexer         | AsyncI/syncany | FINE :    * No old file history found, starting new history (path: sphinxbase-0.8/src/sphinx_adtools, FOLDER)
16-2-17 8:19:03.639 | FileVersionComp | AsyncI/syncany | INFO :      - [DELETED]: Local file DIFFERS from file version, actual file is NULL, for file sphinxbase-0.8/src/sphinx_adtools
16-2-17 8:19:03.639 | Indexer         | AsyncI/syncany | INFO :    * Added file version:    FileVersion [version=1, path=sphinxbase-0.8/src/sphinx_adtools, type=FOLDER, status=NEW, size=4096, lastModified=Thu Feb 16 08:19:03 UTC 2017, linkTarget=null, checksum=null, updated=Thu Feb 16 08:19:03 UTC 2017, posixPermissions=rwxrwxr-x, dosAttributes=--a-]
16-2-17 8:19:03.639 | Indexer         | AsyncI/syncany | INFO :      based on file version: null
16-2-17 8:19:03.639 | Indexer         | AsyncI/syncany | FINE : - +File /tmp/syncanytest/syncany-170216081832804-36803-client-A/sphinxbase-0.8/src/sphinx_adtools/sphinx_pitch.c
16-2-17 8:19:03.640 | Indexer         | AsyncI/syncany | FINE : - +FileContent: /tmp/syncanytest/syncany-170216081832804-36803-client-A/sphinxbase-0.8/src/sphinx_adtools/sphinx_pitch.c
16-2-17 8:19:03.641 | Indexer         | AsyncI/syncany | FINE : - Chunk new: 779bfa820b43f6503f8e932a013cc79b68d62a7f
16-2-17 8:19:03.641 | Indexer         | AsyncI/syncany | FINE : - Chunk > MultiChunk: 779bfa820b43f6503f8e932a013cc79b68d62a7f > 76d051f0ac0e510d3c0b4766bd7c7adbc6ea7e0b
16-2-17 8:19:03.641 | Indexer         | AsyncI/syncany | FINE : - Chunk > FileContent: 779bfa820b43f6503f8e932a013cc79b68d62a7f > /tmp/syncanytest/syncany-170216081832804-36803-client-A/sphinxbase-0.8/src/sphinx_adtools/sphinx_pitch.c
16-2-17 8:19:03.641 | Indexer         | AsyncI/syncany | FINE : - /File: sphinxbase-0.8/src/sphinx_adtools/sphinx_pitch.c (checksum 779bfa820b43f6503f8e932a013cc79b68d62a7f)
16-2-17 8:19:03.642 | Indexer         | AsyncI/syncany | FINE :    * No old file history found, starting new history (path: sphinxbase-0.8/src/sphinx_adtools/sphinx_pitch.c, checksum: 779bfa820b43f6503f8e932a013cc79b68d62a7f)
16-2-17 8:19:03.642 | FileVersionComp | AsyncI/syncany | INFO :      - [DELETED]: Local file DIFFERS from file version, actual file is NULL, for file sphinxbase-0.8/src/sphinx_adtools/sphinx_pitch.c
16-2-17 8:19:03.642 | Indexer         | AsyncI/syncany | INFO :    * Added file version:    FileVersion [version=1, path=sphinxbase-0.8/src/sphinx_adtools/sphinx_pitch.c, type=FILE, status=NEW, size=51200, lastModified=Thu Feb 16 08:19:03 UTC 2017, linkTarget=null, checksum=779bfa820b43f6503f8e932a013cc79b68d62a7f, updated=Thu Feb 16 08:19:03 UTC 2017, posixPermissions=rw-rw-r--, dosAttributes=--a-]
16-2-17 8:19:03.642 | Indexer         | AsyncI/syncany | INFO :      based on file version: null
16-2-17 8:19:03.648 | Indexer         | AsyncI/syncany | FINE : - +File /tmp/syncanytest/syncany-170216081832804-36803-client-A/sphinxbase-0.8/win32
16-2-17 8:19:03.648 | Indexer         | AsyncI/syncany | FINE : - /File: sphinxbase-0.8/win32 (directory/symlink/0-byte-file)
16-2-17 8:19:03.649 | Indexer         | AsyncI/syncany | FINE :    * No old file history found, starting new history (path: sphinxbase-0.8/win32, FOLDER)
16-2-17 8:19:03.650 | FileVersionComp | AsyncI/syncany | INFO :      - [DELETED]: Local file DIFFERS from file version, actual file is NULL, for file sphinxbase-0.8/win32
16-2-17 8:19:03.650 | Indexer         | AsyncI/syncany | INFO :    * Added file version:    FileVersion [version=1, path=sphinxbase-0.8/win32, type=FOLDER, status=NEW, size=4096, lastModified=Thu Feb 16 08:19:03 UTC 2017, linkTarget=null, checksum=null, updated=Thu Feb 16 08:19:03 UTC 2017, posixPermissions=rwxrwxr-x, dosAttributes=--a-]
16-2-17 8:19:03.650 | Indexer         | AsyncI/syncany | INFO :      based on file version: null
16-2-17 8:19:03.650 | Indexer         | AsyncI/syncany | FINE : - +File /tmp/syncanytest/syncany-170216081832804-36803-client-A/sphinxbase-0.8/win32/sphinx_jsgf2fsg
16-2-17 8:19:03.650 | Indexer         | AsyncI/syncany | FINE : - /File: sphinxbase-0.8/win32/sphinx_jsgf2fsg (directory/symlink/0-byte-file)
16-2-17 8:19:03.650 | Indexer         | AsyncI/syncany | FINE :    * No old file history found, starting new history (path: sphinxbase-0.8/win32/sphinx_jsgf2fsg, FOLDER)
16-2-17 8:19:03.652 | FileVersionComp | AsyncI/syncany | INFO :      - [DELETED]: Local file DIFFERS from file version, actual file is NULL, for file sphinxbase-0.8/win32/sphinx_jsgf2fsg
16-2-17 8:19:03.652 | Indexer         | AsyncI/syncany | INFO :    * Added file version:    FileVersion [version=1, path=sphinxbase-0.8/win32/sphinx_jsgf2fsg, type=FOLDER, status=NEW, size=4096, lastModified=Thu Feb 16 08:19:03 UTC 2017, linkTarget=null, checksum=null, updated=Thu Feb 16 08:19:03 UTC 2017, posixPermissions=rwxrwxr-x, dosAttributes=--a-]
16-2-17 8:19:03.652 | Indexer         | AsyncI/syncany | INFO :      based on file version: null
16-2-17 8:19:03.652 | Indexer         | AsyncI/syncany | FINE : - +File /tmp/syncanytest/syncany-170216081832804-36803-client-A/sphinxbase-0.8/win32/sphinx_jsgf2fsg/sphinx_jsgf2fsg.vcxproj
16-2-17 8:19:03.652 | Indexer         | AsyncI/syncany | FINE : - +FileContent: /tmp/syncanytest/syncany-170216081832804-36803-client-A/sphinxbase-0.8/win32/sphinx_jsgf2fsg/sphinx_jsgf2fsg.vcxproj
16-2-17 8:19:03.653 | Indexer         | AsyncI/syncany | FINE : - Chunk new: ea985797ba9c8eaf3c9c1d94022f24f0c198553d
16-2-17 8:19:03.654 | Indexer         | AsyncI/syncany | FINE : - Chunk > MultiChunk: ea985797ba9c8eaf3c9c1d94022f24f0c198553d > 76d051f0ac0e510d3c0b4766bd7c7adbc6ea7e0b
16-2-17 8:19:03.654 | Indexer         | AsyncI/syncany | FINE : - Chunk > FileContent: ea985797ba9c8eaf3c9c1d94022f24f0c198553d > /tmp/syncanytest/syncany-170216081832804-36803-client-A/sphinxbase-0.8/win32/sphinx_jsgf2fsg/sphinx_jsgf2fsg.vcxproj
16-2-17 8:19:03.654 | Indexer         | AsyncI/syncany | FINE : - /File: sphinxbase-0.8/win32/sphinx_jsgf2fsg/sphinx_jsgf2fsg.vcxproj (checksum ea985797ba9c8eaf3c9c1d94022f24f0c198553d)
16-2-17 8:19:03.654 | Indexer         | AsyncI/syncany | FINE :    * No old file history found, starting new history (path: sphinxbase-0.8/win32/sphinx_jsgf2fsg/sphinx_jsgf2fsg.vcxproj, checksum: ea985797ba9c8eaf3c9c1d94022f24f0c198553d)
16-2-17 8:19:03.654 | FileVersionComp | AsyncI/syncany | INFO :      - [DELETED]: Local file DIFFERS from file version, actual file is NULL, for file sphinxbase-0.8/win32/sphinx_jsgf2fsg/sphinx_jsgf2fsg.vcxproj
16-2-17 8:19:03.654 | Indexer         | AsyncI/syncany | INFO :    * Added file version:    FileVersion [version=1, path=sphinxbase-0.8/win32/sphinx_jsgf2fsg/sphinx_jsgf2fsg.vcxproj, type=FILE, status=NEW, size=51200, lastModified=Thu Feb 16 08:19:03 UTC 2017, linkTarget=null, checksum=ea985797ba9c8eaf3c9c1d94022f24f0c198553d, updated=Thu Feb 16 08:19:03 UTC 2017, posixPermissions=rw-rw-r--, dosAttributes=--a-]
16-2-17 8:19:03.654 | Indexer         | AsyncI/syncany | INFO :      based on file version: null
16-2-17 8:19:03.655 | Indexer         | AsyncI/syncany | FINE : - /MultiChunk 76d051f0ac0e510d3c0b4766bd7c7adbc6ea7e0b
16-2-17 8:19:03.655 | InternalEventBu | AsyncI/syncany | INFO : Event bus 'LocalEventBus': Posting event UpIndexEndSyncExternalEvent (org.syncany.operations.daemon.messages.UpIndexEndSyncExternalEvent@783bcb82) ...
16-2-17 8:19:03.655 | Indexer         | AsyncI/syncany | FINE : Processed new database version: DatabaseVersion [header=UnknownMachine/()/T=1487233143627, chunks=3, multiChunks=1, fileContents=3, fileHistories=8]
16-2-17 8:19:03.668 | UpOperation     | Test worker    | INFO : No DIRTY data found in database (no dirty databases); Nothing to do here.
16-2-17 8:19:03.668 | UpOperation     | Test worker    | INFO : Last vector clock was: ()
16-2-17 8:19:03.669 | UpOperation     | Test worker    | INFO : Uploading new multichunks ...
16-2-17 8:19:03.669 | UpOperation     | Test worker    | INFO : - Uploading multichunk 76d051f0ac0e510d3c0b4766bd7c7adbc6ea7e0b from /tmp/syncanytest/syncany-170216081832804-36803-client-A/.syncany/cache/multichunk-76d051f0ac0e510d3c0b4766bd7c7adbc6ea7e0b to RemoteFile[name=multichunk-76d051f0ac0e510d3c0b4766bd7c7adbc6ea7e0b] ...
16-2-17 8:19:03.669 | RemoteTransacti | Test worker    | INFO : - Adding file to TX for UPLOAD: /tmp/syncanytest/syncany-170216081832804-36803-client-A/.syncany/cache/multichunk-76d051f0ac0e510d3c0b4766bd7c7adbc6ea7e0b -> Temp. remote file: RemoteFile[name=temp-tndjO-multichunk-76d051f0ac0e510d3c0b4766bd7c7adbc6ea7e0b], final location: RemoteFile[name=multichunk-76d051f0ac0e510d3c0b4766bd7c7adbc6ea7e0b]
16-2-17 8:19:03.669 | UpOperation     | Test worker    | INFO : Uploading database: DatabaseVersion [header=A/(A1)/T=1487233143669, chunks=3, multiChunks=1, fileContents=3, fileHistories=8]
16-2-17 8:19:03.670 | UpOperation     | Test worker    | INFO : Saving local delta database, version A/(A1)/T=1487233143669 to file /tmp/syncanytest/syncany-170216081832804-36803-client-A/.syncany/cache/database-A-0000000001 ... 
16-2-17 8:19:03.670 | UpOperation     | Test worker    | INFO : - Saving database to /tmp/syncanytest/syncany-170216081832804-36803-client-A/.syncany/cache/database-A-0000000001 ...
16-2-17 8:19:03.671 | UpOperation     | Test worker    | INFO : - Uploading local delta database file ...
16-2-17 8:19:03.671 | UpOperation     | Test worker    | INFO : - Uploading /tmp/syncanytest/syncany-170216081832804-36803-client-A/.syncany/cache/database-A-0000000001 to RemoteFile[name=database-A-0000000001] ...
16-2-17 8:19:03.671 | RemoteTransacti | Test worker    | INFO : - Adding file to TX for UPLOAD: /tmp/syncanytest/syncany-170216081832804-36803-client-A/.syncany/cache/database-A-0000000001 -> Temp. remote file: RemoteFile[name=temp-sOnTD-database-A-0000000001], final location: RemoteFile[name=database-A-0000000001]
16-2-17 8:19:03.671 | UpOperation     | Test worker    | INFO : Adding shutdown hook (to allow resuming the upload) ...
16-2-17 8:19:03.671 | RemoteTransacti | Test worker    | INFO : Starting TX.commit() ...
16-2-17 8:19:03.673 | RemoteTransacti | Test worker    | INFO : Wrote transaction manifest to temporary file: /tmp/syncanytest/syncany-170216081832804-36803-client-A/.syncany/cache/temp-transaction-6793198753958552365.tmp
16-2-17 8:19:03.673 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event UpUploadFileSyncExternalEvent (org.syncany.operations.daemon.messages.UpUploadFileSyncExternalEvent@6af39d3c) ...
16-2-17 8:19:03.673 | RemoteTransacti | Test worker    | INFO : - Uploading remote transaction file RemoteFile[name=transaction-ac52223] ...
16-2-17 8:19:03.673 | RemoteTransacti | Test worker    | INFO : - Starting to upload data in commit.
16-2-17 8:19:03.673 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event UpUploadFileInTransactionSyncExternalEvent (org.syncany.operations.daemon.messages.UpUploadFileInTransactionSyncExternalEvent@5a4c4dea) ...
16-2-17 8:19:03.673 | RemoteTransacti | Test worker    | INFO : - Uploading /tmp/syncanytest/syncany-170216081832804-36803-client-A/.syncany/cache/multichunk-76d051f0ac0e510d3c0b4766bd7c7adbc6ea7e0b to temp. file RemoteFile[name=temp-tndjO-multichunk-76d051f0ac0e510d3c0b4766bd7c7adbc6ea7e0b] ...
16-2-17 8:19:03.674 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event UpUploadFileInTransactionSyncExternalEvent (org.syncany.operations.daemon.messages.UpUploadFileInTransactionSyncExternalEvent@53dae111) ...
16-2-17 8:19:03.674 | RemoteTransacti | Test worker    | INFO : - Uploading /tmp/syncanytest/syncany-170216081832804-36803-client-A/.syncany/cache/database-A-0000000001 to temp. file RemoteFile[name=temp-sOnTD-database-A-0000000001] ...
16-2-17 8:19:03.674 | RemoteTransacti | Test worker    | INFO : - Moving temp. file RemoteFile[name=temp-tndjO-multichunk-76d051f0ac0e510d3c0b4766bd7c7adbc6ea7e0b] to final location RemoteFile[name=multichunk-76d051f0ac0e510d3c0b4766bd7c7adbc6ea7e0b] ...
16-2-17 8:19:03.675 | RemoteTransacti | Test worker    | INFO : - Moving temp. file RemoteFile[name=temp-sOnTD-database-A-0000000001] to final location RemoteFile[name=database-A-0000000001] ...
16-2-17 8:19:03.675 | RemoteTransacti | Test worker    | INFO : - Deleting remote transaction file RemoteFile[name=transaction-ac52223] ...
16-2-17 8:19:03.675 | RemoteTransacti | Test worker    | INFO : END of TX.commmit(): Succesfully committed transaction.
16-2-17 8:19:03.675 | RemoteTransacti | Test worker    | INFO : END of TX.delTemp(): Sucessfully deleted final files.
16-2-17 8:19:03.675 | UpOperation     | Test worker    | INFO : Persisting local SQL database (new database version A/(A1)/T=1487233143669) ...
16-2-17 8:19:03.676 | InternalEventBu | AsyncI/syncany | INFO : Event bus 'LocalEventBus': Posting event UpIndexMidSyncExternalEvent (org.syncany.operations.daemon.messages.UpIndexMidSyncExternalEvent@5b46f266) ...
16-2-17 8:19:03.679 | AsyncIndexer    | AsyncI/syncany | INFO : Stopping indexing. Signal end of stream with empty databaseversion
16-2-17 8:19:03.693 | UpOperation     | Test worker    | INFO : Removing DIRTY database versions from database ...
16-2-17 8:19:03.712 | UpOperation     | Test worker    | INFO : Adding database version to result changes:DatabaseVersion [header=A/(A1)/T=1487233143669, chunks=3, multiChunks=1, fileContents=3, fileHistories=8]
16-2-17 8:19:03.712 | UpOperation     | Test worker    | INFO : Committing local database.
16-2-17 8:19:03.712 | UpOperation     | Test worker    | FINE : Waiting for new database version.
16-2-17 8:19:03.712 | UpOperation     | Test worker    | FINE : Took new database version: DatabaseVersion [header=UnknownMachine/()/T=1487233143679, chunks=0, multiChunks=0, fileContents=0, fileHistories=0]
16-2-17 8:19:03.712 | UpOperation     | Test worker    | INFO : Sync up done.
16-2-17 8:19:03.715 | ActionFileHandl | Test worker    | INFO : Finishing action for RemoteFile[name=action-up-A-1487233143501] ...
16-2-17 8:19:03.720 | ActionFileHandl | Test worker    | INFO : Deleting action file: RemoteFile[name=action-up-A-1487233143501]
16-2-17 8:19:03.721 | AbstractTransfe | Test worker    | INFO : Cleaning own old action files ...
16-2-17 8:19:03.721 | Cache           | Test worker    | INFO : Cache size okay (154 KB), no need to clean (keep size is 500 MB)
16-2-17 8:19:03.721 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event UpEndSyncExternalEvent (org.syncany.operations.daemon.messages.UpEndSyncExternalEvent@1924da99) ...
16-2-17 8:19:03.721 | org.syncany.plu | Test worker    | FINE : Validating required fields
16-2-17 8:19:03.721 | TransferManager | Test worker    | INFO : Building LocalTransferManager from config 'syncany-170216081832804-36803-client-B' ...
16-2-17 8:19:03.721 | TransferManager | Test worker    | INFO : - With feature ReadAfterWriteConsistent
16-2-17 8:19:03.721 | TransferManager | Test worker    | INFO : - With feature Retriable
16-2-17 8:19:03.721 | TransferManager | Test worker    | FINE : - Checking if selected features supported ...
16-2-17 8:19:03.721 | TransferManager | Test worker    | FINE : - Checking for duplicate features ...
16-2-17 8:19:03.721 | TransferManager | Test worker    | FINE : - Checking required feature TransactionAware in LocalTransferManager ...
16-2-17 8:19:03.721 | TransferManager | Test worker    | FINE : - Checking required feature Retriable in LocalTransferManager ...
16-2-17 8:19:03.721 | TransferManager | Test worker    | INFO : - SKIPPING unsupported optional feature ReadAfterWriteConsistent
16-2-17 8:19:03.721 | TransferManager | Test worker    | FINE : - Wrapping TransferManager LocalTransferManager in RetriableFeatureTransferManager
16-2-17 8:19:03.721 | org.syncany.plu | Test worker    | FINE : Validating required fields
16-2-17 8:19:03.722 | TransferManager | Test worker    | INFO : Building LocalTransferManager from config 'syncany-170216081832804-36803-client-B' ...
16-2-17 8:19:03.722 | TransferManager | Test worker    | INFO : - With feature ReadAfterWriteConsistent
16-2-17 8:19:03.722 | TransferManager | Test worker    | INFO : - With feature Retriable
16-2-17 8:19:03.722 | TransferManager | Test worker    | INFO : - With feature PathAware
16-2-17 8:19:03.722 | TransferManager | Test worker    | INFO : - With feature TransactionAware
16-2-17 8:19:03.722 | TransferManager | Test worker    | FINE : - Checking if selected features supported ...
16-2-17 8:19:03.722 | TransferManager | Test worker    | FINE : - Checking for duplicate features ...
16-2-17 8:19:03.722 | TransferManager | Test worker    | FINE : - Checking required feature TransactionAware in LocalTransferManager ...
16-2-17 8:19:03.722 | TransferManager | Test worker    | FINE : - Checking required feature Retriable in LocalTransferManager ...
16-2-17 8:19:03.722 | TransferManager | Test worker    | INFO : - SKIPPING unsupported optional feature ReadAfterWriteConsistent
16-2-17 8:19:03.722 | TransferManager | Test worker    | FINE : - Wrapping TransferManager LocalTransferManager in RetriableFeatureTransferManager
16-2-17 8:19:03.722 | TransferManager | Test worker    | INFO : - SKIPPING unsupported optional feature PathAware
16-2-17 8:19:03.722 | TransferManager | Test worker    | FINE : - Wrapping TransferManager RetriableFeatureTransferManager in TransactionAwareFeatureTransferManager
16-2-17 8:19:03.757 | hsqldb.db.HSQLD | Test worker    | INFO : Checkpoint start
16-2-17 8:19:03.757 | hsqldb.db.HSQLD | Test worker    | INFO : checkpointClose start
16-2-17 8:19:03.767 | hsqldb.db.HSQLD | Test worker    | FINE : log close size: 0
16-2-17 8:19:03.774 | hsqldb.db.HSQLD | Test worker    | INFO : checkpointClose end
16-2-17 8:19:03.774 | hsqldb.db.HSQLD | Test worker    | INFO : Checkpoint end - txts: 1
16-2-17 8:19:03.779 | DatabaseConnect | Test worker    | INFO : Found 0 tables.
16-2-17 8:19:03.779 | DatabaseConnect | Test worker    | INFO : Database has no tables. Creating tables from script.create.all.sql
16-2-17 8:19:03.780 | SqlRunner       | Test worker    | INFO : SQL (comment): -- Tables
16-2-17 8:19:03.780 | 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) ); 
16-2-17 8:19:03.785 | 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 ); 
16-2-17 8:19:03.788 | 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 ); 
16-2-17 8:19:03.790 | 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 ); 
16-2-17 8:19:03.792 | 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 ); 
16-2-17 8:19:03.794 | 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 ); 
16-2-17 8:19:03.797 | 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 ); 
16-2-17 8:19:03.801 | 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 ); 
16-2-17 8:19:03.803 | 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 ); 
16-2-17 8:19:03.805 | 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) ); 
16-2-17 8:19:03.807 | 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) ); 
16-2-17 8:19:03.809 | 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) ); 
16-2-17 8:19:03.811 | SqlRunner       | Test worker    | INFO : SQL (comment): -- Non-primary indices
16-2-17 8:19:03.811 | SqlRunner       | Test worker    | INFO : SQL:   CREATE INDEX idx_databaseversion_status ON databaseversion (status); 
16-2-17 8:19:03.812 | hsqldb.db.HSQLD | Test worker    | INFO : dataFileCache open start
16-2-17 8:19:03.815 | hsqldb.db.HSQLD | Test worker    | INFO : dataFileCache open end
16-2-17 8:19:03.821 | SqlRunner       | Test worker    | INFO : SQL: CREATE INDEX idx_databaseversion_vectorclock_serialized ON databaseversion (vectorclock_serialized); 
16-2-17 8:19:03.823 | SqlRunner       | Test worker    | INFO : SQL: CREATE INDEX idx_fileversion_path ON fileversion (path); 
16-2-17 8:19:03.826 | SqlRunner       | Test worker    | INFO : SQL: CREATE INDEX idx_fileversion_status ON fileversion (status); 
16-2-17 8:19:03.828 | SqlRunner       | Test worker    | INFO : SQL: CREATE INDEX idx_fileversion_filecontent_checksum ON fileversion (filecontent_checksum); 
16-2-17 8:19:03.830 | SqlRunner       | Test worker    | INFO : SQL (comment): -- Views
16-2-17 8:19:03.830 | 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'; 
16-2-17 8:19:03.832 | 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'; 
16-2-17 8:19:03.834 | 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; 
16-2-17 8:19:03.837 | 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'; 
16-2-17 8:19:03.842 | SqlRunner       | Test worker    | INFO : SQL (comment): -- Full Views
16-2-17 8:19:03.842 | 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; 
16-2-17 8:19:03.845 | 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; 
16-2-17 8:19:03.847 | SqlRunner       | Test worker    | INFO : SQL (comment): -- Functions
16-2-17 8:19:03.847 | SqlRunner       | Test worker    | INFO : SQL (new delimiter): end;
16-2-17 8:19:03.847 | 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; 
16-2-17 8:19:03.850 | UpOperation     | Test worker    | INFO : 
16-2-17 8:19:03.850 | UpOperation     | Test worker    | INFO : Running 'Sync up' at client B ...
16-2-17 8:19:03.850 | UpOperation     | Test worker    | INFO : --------------------------------------------
16-2-17 8:19:03.850 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event UpStartSyncExternalEvent (org.syncany.operations.daemon.messages.UpStartSyncExternalEvent@18fde879) ...
16-2-17 8:19:03.852 | DatabaseConnect | Test worker    | INFO : Found 12 tables.
16-2-17 8:19:03.852 | StatusOperation | Test worker    | INFO : 
16-2-17 8:19:03.852 | StatusOperation | Test worker    | INFO : Running 'Status' at client B ...
16-2-17 8:19:03.852 | StatusOperation | Test worker    | INFO : --------------------------------------------
16-2-17 8:19:03.852 | StatusOperation | Test worker    | INFO : Querying current file tree from database ...
16-2-17 8:19:03.852 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event StatusStartSyncExternalEvent (org.syncany.operations.daemon.messages.StatusStartSyncExternalEvent@10ca91cc) ...
16-2-17 8:19:03.853 | StatusOperation | Test worker    | INFO : Analyzing local folder /tmp/syncanytest/syncany-170216081832804-36803-client-B ...
16-2-17 8:19:03.853 | StatusOperation | Test worker    | FINE : - Ignoring file (syncany app-related): .syncany
16-2-17 8:19:03.853 | StatusOperation | Test worker    | FINE : - New file: sphinxbase-0.8
16-2-17 8:19:03.853 | StatusOperation | Test worker    | FINE : - New file: sphinxbase-0.8/src
16-2-17 8:19:03.854 | StatusOperation | Test worker    | FINE : - New file: sphinxbase-0.8/src/sphinx_adtools
16-2-17 8:19:03.854 | StatusOperation | Test worker    | FINE : - New file: sphinxbase-0.8/src/sphinx_adtools/sphinx_pitch.c
16-2-17 8:19:03.854 | StatusOperation | Test worker    | FINE : - New file: sphinxbase-0.8/config.sub
16-2-17 8:19:03.854 | StatusOperation | Test worker    | FINE : - New file: sphinxbase-0.8/win32
16-2-17 8:19:03.854 | StatusOperation | Test worker    | FINE : - New file: sphinxbase-0.8/win32/sphinx_jsgf2fsg
16-2-17 8:19:03.854 | StatusOperation | Test worker    | FINE : - New file: sphinxbase-0.8/win32/sphinx_jsgf2fsg/sphinx_jsgf2fsg.vcxproj
16-2-17 8:19:03.854 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event StatusEndSyncExternalEvent (org.syncany.operations.daemon.messages.StatusEndSyncExternalEvent@327680f3) ...
16-2-17 8:19:03.854 | AbstractTransfe | Test worker    | INFO : Looking for other running remote operations ...
16-2-17 8:19:03.854 | UpOperation     | Test worker    | INFO : Force (--force-upload) is enabled, ignoring potential remote changes.
16-2-17 8:19:03.854 | ActionFileHandl | Test worker    | INFO : Starting action for RemoteFile[name=action-up-B-1487233143722] ...
16-2-17 8:19:03.854 | ActionFileHandl | Test worker    | INFO : Uploading action file: RemoteFile[name=action-up-B-1487233143722]
16-2-17 8:19:03.860 | ActionFileHandl | Test worker    | INFO : Scheduling action renewal task for every 2 minutes, for RemoteFile[name=action-up-B-1487233143722] ...
16-2-17 8:19:03.861 | DatabaseConnect | Test worker    | INFO : Found 12 tables.
16-2-17 8:19:03.867 | TransactionAwar | Test worker    | INFO : Done rolling back previous transactions.
16-2-17 8:19:03.867 | AsyncIndexer    | AsyncI/syncany | INFO : Starting Indexing.
16-2-17 8:19:03.868 | InternalEventBu | AsyncI/syncany | INFO : Event bus 'LocalEventBus': Posting event UpIndexStartSyncExternalEvent (org.syncany.operations.daemon.messages.UpIndexStartSyncExternalEvent@4ff2f9ab) ...
16-2-17 8:19:03.868 | Indexer         | AsyncI/syncany | FINE : - Looking for deleted files ...
16-2-17 8:19:03.868 | Indexer         | AsyncI/syncany | FINE : - +File /tmp/syncanytest/syncany-170216081832804-36803-client-B/sphinxbase-0.8
16-2-17 8:19:03.868 | Indexer         | AsyncI/syncany | FINE : - /File: sphinxbase-0.8 (directory/symlink/0-byte-file)
16-2-17 8:19:03.868 | Indexer         | AsyncI/syncany | FINE :    * No old file history found, starting new history (path: sphinxbase-0.8, FOLDER)
16-2-17 8:19:03.868 | FileVersionComp | AsyncI/syncany | INFO :      - [DELETED]: Local file DIFFERS from file version, actual file is NULL, for file sphinxbase-0.8
16-2-17 8:19:03.868 | Indexer         | AsyncI/syncany | INFO :    * Added file version:    FileVersion [version=1, path=sphinxbase-0.8, type=FOLDER, status=NEW, size=4096, lastModified=Thu Feb 16 08:19:03 UTC 2017, linkTarget=null, checksum=null, updated=Thu Feb 16 08:19:03 UTC 2017, posixPermissions=rwxrwxr-x, dosAttributes=--a-]
16-2-17 8:19:03.868 | Indexer         | AsyncI/syncany | INFO :      based on file version: null
16-2-17 8:19:03.868 | InternalEventBu | AsyncI/syncany | INFO : Event bus 'LocalEventBus': Posting event UpIndexChangesDetectedSyncExternalEvent (org.syncany.operations.daemon.messages.UpIndexChangesDetectedSyncExternalEvent@3bcb7e8c) ...
16-2-17 8:19:03.869 | Indexer         | AsyncI/syncany | FINE : - +File /tmp/syncanytest/syncany-170216081832804-36803-client-B/sphinxbase-0.8/config.sub
16-2-17 8:19:03.869 | Indexer         | AsyncI/syncany | FINE : - +FileContent: /tmp/syncanytest/syncany-170216081832804-36803-client-B/sphinxbase-0.8/config.sub
16-2-17 8:19:03.881 | Indexer         | AsyncI/syncany | FINE : - Chunk new: 23dfa10beaedb1d38157c1acc50dc4ea102859b1
16-2-17 8:19:03.882 | Indexer         | AsyncI/syncany | FINE : - +MultiChunk a38a88b691aeedb4f279c6fb2357055a0626d1c2
16-2-17 8:19:03.883 | Indexer         | AsyncI/syncany | FINE : - Chunk > MultiChunk: 23dfa10beaedb1d38157c1acc50dc4ea102859b1 > a38a88b691aeedb4f279c6fb2357055a0626d1c2
16-2-17 8:19:03.883 | Indexer         | AsyncI/syncany | FINE : - Chunk > FileContent: 23dfa10beaedb1d38157c1acc50dc4ea102859b1 > /tmp/syncanytest/syncany-170216081832804-36803-client-B/sphinxbase-0.8/config.sub
16-2-17 8:19:03.883 | Indexer         | AsyncI/syncany | FINE : - /File: sphinxbase-0.8/config.sub (checksum 23dfa10beaedb1d38157c1acc50dc4ea102859b1)
16-2-17 8:19:03.884 | Indexer         | AsyncI/syncany | FINE :    * No old file history found, starting new history (path: sphinxbase-0.8/config.sub, checksum: 23dfa10beaedb1d38157c1acc50dc4ea102859b1)
16-2-17 8:19:03.884 | FileVersionComp | AsyncI/syncany | INFO :      - [DELETED]: Local file DIFFERS from file version, actual file is NULL, for file sphinxbase-0.8/config.sub
16-2-17 8:19:03.885 | Indexer         | AsyncI/syncany | INFO :    * Added file version:    FileVersion [version=1, path=sphinxbase-0.8/config.sub, type=FILE, status=NEW, size=51200, lastModified=Thu Feb 16 08:19:03 UTC 2017, linkTarget=null, checksum=23dfa10beaedb1d38157c1acc50dc4ea102859b1, updated=Thu Feb 16 08:19:03 UTC 2017, posixPermissions=rw-rw-r--, dosAttributes=--a-]
16-2-17 8:19:03.885 | Indexer         | AsyncI/syncany | INFO :      based on file version: null
16-2-17 8:19:03.885 | Indexer         | AsyncI/syncany | FINE : - +File /tmp/syncanytest/syncany-170216081832804-36803-client-B/sphinxbase-0.8/src
16-2-17 8:19:03.885 | Indexer         | AsyncI/syncany | FINE : - /File: sphinxbase-0.8/src (directory/symlink/0-byte-file)
16-2-17 8:19:03.885 | Indexer         | AsyncI/syncany | FINE :    * No old file history found, starting new history (path: sphinxbase-0.8/src, FOLDER)
16-2-17 8:19:03.885 | FileVersionComp | AsyncI/syncany | INFO :      - [DELETED]: Local file DIFFERS from file version, actual file is NULL, for file sphinxbase-0.8/src
16-2-17 8:19:03.885 | Indexer         | AsyncI/syncany | INFO :    * Added file version:    FileVersion [version=1, path=sphinxbase-0.8/src, type=FOLDER, status=NEW, size=4096, lastModified=Thu Feb 16 08:19:03 UTC 2017, linkTarget=null, checksum=null, updated=Thu Feb 16 08:19:03 UTC 2017, posixPermissions=rwxrwxr-x, dosAttributes=--a-]
16-2-17 8:19:03.885 | Indexer         | AsyncI/syncany | INFO :      based on file version: null
16-2-17 8:19:03.885 | Indexer         | AsyncI/syncany | FINE : - +File /tmp/syncanytest/syncany-170216081832804-36803-client-B/sphinxbase-0.8/src/sphinx_adtools
16-2-17 8:19:03.885 | Indexer         | AsyncI/syncany | FINE : - /File: sphinxbase-0.8/src/sphinx_adtools (directory/symlink/0-byte-file)
16-2-17 8:19:03.886 | Indexer         | AsyncI/syncany | FINE :    * No old file history found, starting new history (path: sphinxbase-0.8/src/sphinx_adtools, FOLDER)
16-2-17 8:19:03.888 | FileVersionComp | AsyncI/syncany | INFO :      - [DELETED]: Local file DIFFERS from file version, actual file is NULL, for file sphinxbase-0.8/src/sphinx_adtools
16-2-17 8:19:03.888 | Indexer         | AsyncI/syncany | INFO :    * Added file version:    FileVersion [version=1, path=sphinxbase-0.8/src/sphinx_adtools, type=FOLDER, status=NEW, size=4096, lastModified=Thu Feb 16 08:19:03 UTC 2017, linkTarget=null, checksum=null, updated=Thu Feb 16 08:19:03 UTC 2017, posixPermissions=rwxrwxr-x, dosAttributes=--a-]
16-2-17 8:19:03.888 | Indexer         | AsyncI/syncany | INFO :      based on file version: null
16-2-17 8:19:03.888 | Indexer         | AsyncI/syncany | FINE : - +File /tmp/syncanytest/syncany-170216081832804-36803-client-B/sphinxbase-0.8/src/sphinx_adtools/sphinx_pitch.c
16-2-17 8:19:03.888 | Indexer         | AsyncI/syncany | FINE : - +FileContent: /tmp/syncanytest/syncany-170216081832804-36803-client-B/sphinxbase-0.8/src/sphinx_adtools/sphinx_pitch.c
16-2-17 8:19:03.890 | Indexer         | AsyncI/syncany | FINE : - Chunk new: 779bfa820b43f6503f8e932a013cc79b68d62a7f
16-2-17 8:19:03.891 | Indexer         | AsyncI/syncany | FINE : - Chunk > MultiChunk: 779bfa820b43f6503f8e932a013cc79b68d62a7f > a38a88b691aeedb4f279c6fb2357055a0626d1c2
16-2-17 8:19:03.891 | Indexer         | AsyncI/syncany | FINE : - Chunk > FileContent: 779bfa820b43f6503f8e932a013cc79b68d62a7f > /tmp/syncanytest/syncany-170216081832804-36803-client-B/sphinxbase-0.8/src/sphinx_adtools/sphinx_pitch.c
16-2-17 8:19:03.892 | Indexer         | AsyncI/syncany | FINE : - /File: sphinxbase-0.8/src/sphinx_adtools/sphinx_pitch.c (checksum 779bfa820b43f6503f8e932a013cc79b68d62a7f)
16-2-17 8:19:03.895 | Indexer         | AsyncI/syncany | FINE :    * No old file history found, starting new history (path: sphinxbase-0.8/src/sphinx_adtools/sphinx_pitch.c, checksum: 779bfa820b43f6503f8e932a013cc79b68d62a7f)
16-2-17 8:19:03.895 | FileVersionComp | AsyncI/syncany | INFO :      - [DELETED]: Local file DIFFERS from file version, actual file is NULL, for file sphinxbase-0.8/src/sphinx_adtools/sphinx_pitch.c
16-2-17 8:19:03.895 | Indexer         | AsyncI/syncany | INFO :    * Added file version:    FileVersion [version=1, path=sphinxbase-0.8/src/sphinx_adtools/sphinx_pitch.c, type=FILE, status=NEW, size=51200, lastModified=Thu Feb 16 08:19:03 UTC 2017, linkTarget=null, checksum=779bfa820b43f6503f8e932a013cc79b68d62a7f, updated=Thu Feb 16 08:19:03 UTC 2017, posixPermissions=rw-rw-r--, dosAttributes=--a-]
16-2-17 8:19:03.895 | Indexer         | AsyncI/syncany | INFO :      based on file version: null
16-2-17 8:19:03.896 | Indexer         | AsyncI/syncany | FINE : - +File /tmp/syncanytest/syncany-170216081832804-36803-client-B/sphinxbase-0.8/win32
16-2-17 8:19:03.897 | Indexer         | AsyncI/syncany | FINE : - /File: sphinxbase-0.8/win32 (directory/symlink/0-byte-file)
16-2-17 8:19:03.900 | Indexer         | AsyncI/syncany | FINE :    * No old file history found, starting new history (path: sphinxbase-0.8/win32, FOLDER)
16-2-17 8:19:03.900 | FileVersionComp | AsyncI/syncany | INFO :      - [DELETED]: Local file DIFFERS from file version, actual file is NULL, for file sphinxbase-0.8/win32
16-2-17 8:19:03.900 | Indexer         | AsyncI/syncany | INFO :    * Added file version:    FileVersion [version=1, path=sphinxbase-0.8/win32, type=FOLDER, status=NEW, size=4096, lastModified=Thu Feb 16 08:19:03 UTC 2017, linkTarget=null, checksum=null, updated=Thu Feb 16 08:19:03 UTC 2017, posixPermissions=rwxrwxr-x, dosAttributes=--a-]
16-2-17 8:19:03.901 | Indexer         | AsyncI/syncany | INFO :      based on file version: null
16-2-17 8:19:03.901 | Indexer         | AsyncI/syncany | FINE : - +File /tmp/syncanytest/syncany-170216081832804-36803-client-B/sphinxbase-0.8/win32/sphinx_jsgf2fsg
16-2-17 8:19:03.901 | Indexer         | AsyncI/syncany | FINE : - /File: sphinxbase-0.8/win32/sphinx_jsgf2fsg (directory/symlink/0-byte-file)
16-2-17 8:19:03.901 | Indexer         | AsyncI/syncany | FINE :    * No old file history found, starting new history (path: sphinxbase-0.8/win32/sphinx_jsgf2fsg, FOLDER)
16-2-17 8:19:03.901 | FileVersionComp | AsyncI/syncany | INFO :      - [DELETED]: Local file DIFFERS from file version, actual file is NULL, for file sphinxbase-0.8/win32/sphinx_jsgf2fsg
16-2-17 8:19:03.901 | Indexer         | AsyncI/syncany | INFO :    * Added file version:    FileVersion [version=1, path=sphinxbase-0.8/win32/sphinx_jsgf2fsg, type=FOLDER, status=NEW, size=4096, lastModified=Thu Feb 16 08:19:03 UTC 2017, linkTarget=null, checksum=null, updated=Thu Feb 16 08:19:03 UTC 2017, posixPermissions=rwxrwxr-x, dosAttributes=--a-]
16-2-17 8:19:03.901 | Indexer         | AsyncI/syncany | INFO :      based on file version: null
16-2-17 8:19:03.901 | Indexer         | AsyncI/syncany | FINE : - +File /tmp/syncanytest/syncany-170216081832804-36803-client-B/sphinxbase-0.8/win32/sphinx_jsgf2fsg/sphinx_jsgf2fsg.vcxproj
16-2-17 8:19:03.901 | Indexer         | AsyncI/syncany | FINE : - +FileContent: /tmp/syncanytest/syncany-170216081832804-36803-client-B/sphinxbase-0.8/win32/sphinx_jsgf2fsg/sphinx_jsgf2fsg.vcxproj
16-2-17 8:19:03.905 | Indexer         | AsyncI/syncany | FINE : - Chunk new: ea985797ba9c8eaf3c9c1d94022f24f0c198553d
16-2-17 8:19:03.906 | Indexer         | AsyncI/syncany | FINE : - Chunk > MultiChunk: ea985797ba9c8eaf3c9c1d94022f24f0c198553d > a38a88b691aeedb4f279c6fb2357055a0626d1c2
16-2-17 8:19:03.906 | Indexer         | AsyncI/syncany | FINE : - Chunk > FileContent: ea985797ba9c8eaf3c9c1d94022f24f0c198553d > /tmp/syncanytest/syncany-170216081832804-36803-client-B/sphinxbase-0.8/win32/sphinx_jsgf2fsg/sphinx_jsgf2fsg.vcxproj
16-2-17 8:19:03.906 | Indexer         | AsyncI/syncany | FINE : - /File: sphinxbase-0.8/win32/sphinx_jsgf2fsg/sphinx_jsgf2fsg.vcxproj (checksum ea985797ba9c8eaf3c9c1d94022f24f0c198553d)
16-2-17 8:19:03.907 | Indexer         | AsyncI/syncany | FINE :    * No old file history found, starting new history (path: sphinxbase-0.8/win32/sphinx_jsgf2fsg/sphinx_jsgf2fsg.vcxproj, checksum: ea985797ba9c8eaf3c9c1d94022f24f0c198553d)
16-2-17 8:19:03.907 | FileVersionComp | AsyncI/syncany | INFO :      - [DELETED]: Local file DIFFERS from file version, actual file is NULL, for file sphinxbase-0.8/win32/sphinx_jsgf2fsg/sphinx_jsgf2fsg.vcxproj
16-2-17 8:19:03.907 | Indexer         | AsyncI/syncany | INFO :    * Added file version:    FileVersion [version=1, path=sphinxbase-0.8/win32/sphinx_jsgf2fsg/sphinx_jsgf2fsg.vcxproj, type=FILE, status=NEW, size=51200, lastModified=Thu Feb 16 08:19:03 UTC 2017, linkTarget=null, checksum=ea985797ba9c8eaf3c9c1d94022f24f0c198553d, updated=Thu Feb 16 08:19:03 UTC 2017, posixPermissions=rw-rw-r--, dosAttributes=--a-]
16-2-17 8:19:03.908 | Indexer         | AsyncI/syncany | INFO :      based on file version: null
16-2-17 8:19:03.908 | Indexer         | AsyncI/syncany | FINE : - /MultiChunk a38a88b691aeedb4f279c6fb2357055a0626d1c2
16-2-17 8:19:03.909 | InternalEventBu | AsyncI/syncany | INFO : Event bus 'LocalEventBus': Posting event UpIndexEndSyncExternalEvent (org.syncany.operations.daemon.messages.UpIndexEndSyncExternalEvent@172d7b6b) ...
16-2-17 8:19:03.909 | Indexer         | AsyncI/syncany | FINE : Processed new database version: DatabaseVersion [header=UnknownMachine/()/T=1487233143867, chunks=3, multiChunks=1, fileContents=3, fileHistories=8]
16-2-17 8:19:03.912 | UpOperation     | Test worker    | INFO : No DIRTY data found in database (no dirty databases); Nothing to do here.
16-2-17 8:19:03.912 | UpOperation     | Test worker    | INFO : Last vector clock was: ()
16-2-17 8:19:03.913 | UpOperation     | Test worker    | INFO : Uploading new multichunks ...
16-2-17 8:19:03.913 | UpOperation     | Test worker    | INFO : - Uploading multichunk a38a88b691aeedb4f279c6fb2357055a0626d1c2 from /tmp/syncanytest/syncany-170216081832804-36803-client-B/.syncany/cache/multichunk-a38a88b691aeedb4f279c6fb2357055a0626d1c2 to RemoteFile[name=multichunk-a38a88b691aeedb4f279c6fb2357055a0626d1c2] ...
16-2-17 8:19:03.914 | RemoteTransacti | Test worker    | INFO : - Adding file to TX for UPLOAD: /tmp/syncanytest/syncany-170216081832804-36803-client-B/.syncany/cache/multichunk-a38a88b691aeedb4f279c6fb2357055a0626d1c2 -> Temp. remote file: RemoteFile[name=temp-xlsjU-multichunk-a38a88b691aeedb4f279c6fb2357055a0626d1c2], final location: RemoteFile[name=multichunk-a38a88b691aeedb4f279c6fb2357055a0626d1c2]
16-2-17 8:19:03.914 | UpOperation     | Test worker    | INFO : Uploading database: DatabaseVersion [header=B/(B1)/T=1487233143913, chunks=3, multiChunks=1, fileContents=3, fileHistories=8]
16-2-17 8:19:03.914 | UpOperation     | Test worker    | INFO : Saving local delta database, version B/(B1)/T=1487233143913 to file /tmp/syncanytest/syncany-170216081832804-36803-client-B/.syncany/cache/database-B-0000000001 ... 
16-2-17 8:19:03.914 | UpOperation     | Test worker    | INFO : - Saving database to /tmp/syncanytest/syncany-170216081832804-36803-client-B/.syncany/cache/database-B-0000000001 ...
16-2-17 8:19:03.918 | UpOperation     | Test worker    | INFO : - Uploading local delta database file ...
16-2-17 8:19:03.918 | UpOperation     | Test worker    | INFO : - Uploading /tmp/syncanytest/syncany-170216081832804-36803-client-B/.syncany/cache/database-B-0000000001 to RemoteFile[name=database-B-0000000001] ...
16-2-17 8:19:03.918 | RemoteTransacti | Test worker    | INFO : - Adding file to TX for UPLOAD: /tmp/syncanytest/syncany-170216081832804-36803-client-B/.syncany/cache/database-B-0000000001 -> Temp. remote file: RemoteFile[name=temp-Twywb-database-B-0000000001], final location: RemoteFile[name=database-B-0000000001]
16-2-17 8:19:03.919 | UpOperation     | Test worker    | INFO : Adding shutdown hook (to allow resuming the upload) ...
16-2-17 8:19:03.919 | RemoteTransacti | Test worker    | INFO : Starting TX.commit() ...
16-2-17 8:19:03.920 | RemoteTransacti | Test worker    | INFO : Wrote transaction manifest to temporary file: /tmp/syncanytest/syncany-170216081832804-36803-client-B/.syncany/cache/temp-transaction-2936490497211627483.tmp
16-2-17 8:19:03.920 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event UpUploadFileSyncExternalEvent (org.syncany.operations.daemon.messages.UpUploadFileSyncExternalEvent@706d5ed3) ...
16-2-17 8:19:03.920 | RemoteTransacti | Test worker    | INFO : - Uploading remote transaction file RemoteFile[name=transaction-44acdf43] ...
16-2-17 8:19:03.920 | RemoteTransacti | Test worker    | INFO : - Starting to upload data in commit.
16-2-17 8:19:03.921 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event UpUploadFileInTransactionSyncExternalEvent (org.syncany.operations.daemon.messages.UpUploadFileInTransactionSyncExternalEvent@1ae70822) ...
16-2-17 8:19:03.921 | RemoteTransacti | Test worker    | INFO : - Uploading /tmp/syncanytest/syncany-170216081832804-36803-client-B/.syncany/cache/multichunk-a38a88b691aeedb4f279c6fb2357055a0626d1c2 to temp. file RemoteFile[name=temp-xlsjU-multichunk-a38a88b691aeedb4f279c6fb2357055a0626d1c2] ...
16-2-17 8:19:03.921 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event UpUploadFileInTransactionSyncExternalEvent (org.syncany.operations.daemon.messages.UpUploadFileInTransactionSyncExternalEvent@743c9cf8) ...
16-2-17 8:19:03.921 | RemoteTransacti | Test worker    | INFO : - Uploading /tmp/syncanytest/syncany-170216081832804-36803-client-B/.syncany/cache/database-B-0000000001 to temp. file RemoteFile[name=temp-Twywb-database-B-0000000001] ...
16-2-17 8:19:03.922 | RemoteTransacti | Test worker    | INFO : - Moving temp. file RemoteFile[name=temp-xlsjU-multichunk-a38a88b691aeedb4f279c6fb2357055a0626d1c2] to final location RemoteFile[name=multichunk-a38a88b691aeedb4f279c6fb2357055a0626d1c2] ...
16-2-17 8:19:03.922 | RemoteTransacti | Test worker    | INFO : - Moving temp. file RemoteFile[name=temp-Twywb-database-B-0000000001] to final location RemoteFile[name=database-B-0000000001] ...
16-2-17 8:19:03.922 | RemoteTransacti | Test worker    | INFO : - Deleting remote transaction file RemoteFile[name=transaction-44acdf43] ...
16-2-17 8:19:03.922 | InternalEventBu | AsyncI/syncany | INFO : Event bus 'LocalEventBus': Posting event UpIndexMidSyncExternalEvent (org.syncany.operations.daemon.messages.UpIndexMidSyncExternalEvent@3a429701) ...
16-2-17 8:19:03.922 | RemoteTransacti | Test worker    | INFO : END of TX.commmit(): Succesfully committed transaction.
16-2-17 8:19:03.922 | RemoteTransacti | Test worker    | INFO : END of TX.delTemp(): Sucessfully deleted final files.
16-2-17 8:19:03.923 | UpOperation     | Test worker    | INFO : Persisting local SQL database (new database version B/(B1)/T=1487233143913) ...
16-2-17 8:19:03.926 | AsyncIndexer    | AsyncI/syncany | INFO : Stopping indexing. Signal end of stream with empty databaseversion
16-2-17 8:19:03.934 | UpOperation     | Test worker    | INFO : Removing DIRTY database versions from database ...
16-2-17 8:19:03.946 | UpOperation     | Test worker    | INFO : Adding database version to result changes:DatabaseVersion [header=B/(B1)/T=1487233143913, chunks=3, multiChunks=1, fileContents=3, fileHistories=8]
16-2-17 8:19:03.946 | UpOperation     | Test worker    | INFO : Committing local database.
16-2-17 8:19:03.946 | UpOperation     | Test worker    | FINE : Waiting for new database version.
16-2-17 8:19:03.946 | UpOperation     | Test worker    | FINE : Took new database version: DatabaseVersion [header=UnknownMachine/()/T=1487233143926, chunks=0, multiChunks=0, fileContents=0, fileHistories=0]
16-2-17 8:19:03.946 | UpOperation     | Test worker    | INFO : Sync up done.
16-2-17 8:19:03.952 | ActionFileHandl | Test worker    | INFO : Finishing action for RemoteFile[name=action-up-B-1487233143722] ...
16-2-17 8:19:03.968 | ActionFileHandl | Test worker    | INFO : Deleting action file: RemoteFile[name=action-up-B-1487233143722]
16-2-17 8:19:03.968 | AbstractTransfe | Test worker    | INFO : Cleaning own old action files ...
16-2-17 8:19:03.968 | Cache           | Test worker    | INFO : Cache size okay (154 KB), no need to clean (keep size is 500 MB)
16-2-17 8:19:03.968 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event UpEndSyncExternalEvent (org.syncany.operations.daemon.messages.UpEndSyncExternalEvent@7fa9ae8e) ...
16-2-17 8:19:03.969 | org.syncany.plu | Test worker    | FINE : Validating required fields
16-2-17 8:19:03.969 | TransferManager | Test worker    | INFO : Building LocalTransferManager from config 'syncany-170216081832804-36803-client-A' ...
16-2-17 8:19:03.969 | TransferManager | Test worker    | INFO : - With feature ReadAfterWriteConsistent
16-2-17 8:19:03.969 | TransferManager | Test worker    | INFO : - With feature Retriable
16-2-17 8:19:03.969 | TransferManager | Test worker    | FINE : - Checking if selected features supported ...
16-2-17 8:19:03.969 | TransferManager | Test worker    | FINE : - Checking for duplicate features ...
16-2-17 8:19:03.969 | TransferManager | Test worker    | FINE : - Checking required feature TransactionAware in LocalTransferManager ...
16-2-17 8:19:03.969 | TransferManager | Test worker    | FINE : - Checking required feature Retriable in LocalTransferManager ...
16-2-17 8:19:03.969 | TransferManager | Test worker    | INFO : - SKIPPING unsupported optional feature ReadAfterWriteConsistent
16-2-17 8:19:03.969 | TransferManager | Test worker    | FINE : - Wrapping TransferManager LocalTransferManager in RetriableFeatureTransferManager
16-2-17 8:19:03.969 | org.syncany.plu | Test worker    | FINE : Validating required fields
16-2-17 8:19:03.969 | TransferManager | Test worker    | INFO : Building LocalTransferManager from config 'syncany-170216081832804-36803-client-A' ...
16-2-17 8:19:03.969 | TransferManager | Test worker    | INFO : - With feature ReadAfterWriteConsistent
16-2-17 8:19:03.969 | TransferManager | Test worker    | INFO : - With feature Retriable
16-2-17 8:19:03.969 | TransferManager | Test worker    | INFO : - With feature PathAware
16-2-17 8:19:03.969 | TransferManager | Test worker    | INFO : - With feature TransactionAware
16-2-17 8:19:03.969 | TransferManager | Test worker    | FINE : - Checking if selected features supported ...
16-2-17 8:19:03.969 | TransferManager | Test worker    | FINE : - Checking for duplicate features ...
16-2-17 8:19:03.969 | TransferManager | Test worker    | FINE : - Checking required feature TransactionAware in LocalTransferManager ...
16-2-17 8:19:03.969 | TransferManager | Test worker    | FINE : - Checking required feature Retriable in LocalTransferManager ...
16-2-17 8:19:03.969 | TransferManager | Test worker    | INFO : - SKIPPING unsupported optional feature ReadAfterWriteConsistent
16-2-17 8:19:03.969 | TransferManager | Test worker    | FINE : - Wrapping TransferManager LocalTransferManager in RetriableFeatureTransferManager
16-2-17 8:19:03.969 | TransferManager | Test worker    | INFO : - SKIPPING unsupported optional feature PathAware
16-2-17 8:19:03.969 | TransferManager | Test worker    | FINE : - Wrapping TransferManager RetriableFeatureTransferManager in TransactionAwareFeatureTransferManager
16-2-17 8:19:03.977 | DatabaseConnect | Test worker    | INFO : Found 12 tables.
16-2-17 8:19:03.977 | DownOperation   | Test worker    | INFO : 
16-2-17 8:19:03.977 | DownOperation   | Test worker    | INFO : Running 'Sync down' at client A ...
16-2-17 8:19:03.977 | DownOperation   | Test worker    | INFO : --------------------------------------------
16-2-17 8:19:03.977 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event DownStartSyncExternalEvent (org.syncany.operations.daemon.messages.DownStartSyncExternalEvent@197b61cd) ...
16-2-17 8:19:03.977 | AbstractTransfe | Test worker    | INFO : Looking for other running remote operations ...
16-2-17 8:19:03.977 | DatabaseConnect | Test worker    | INFO : Found 12 tables.
16-2-17 8:19:03.977 | LsRemoteOperati | Test worker    | INFO : 
16-2-17 8:19:03.977 | LsRemoteOperati | Test worker    | INFO : Running 'Remote Status' at client A ...
16-2-17 8:19:03.977 | LsRemoteOperati | Test worker    | INFO : --------------------------------------------
16-2-17 8:19:03.978 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event LsRemoteStartSyncExternalEvent (org.syncany.operations.daemon.messages.LsRemoteStartSyncExternalEvent@4629dcf7) ...
16-2-17 8:19:03.978 | LsRemoteOperati | Test worker    | INFO : Retrieving remote database list.
16-2-17 8:19:03.978 | LsRemoteOperati | Test worker    | INFO : - Remote database database-B-0000000001 is new.
16-2-17 8:19:03.978 | LsRemoteOperati | Test worker    | INFO : - Remote database database-A-0000000001 is already known (in local database). Ignoring.
16-2-17 8:19:03.978 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event LsRemoteEndSyncExternalEvent (org.syncany.operations.daemon.messages.LsRemoteEndSyncExternalEvent@398a80f3) ...
16-2-17 8:19:03.978 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event DownChangesDetectedSyncExternalEvent (org.syncany.operations.daemon.messages.DownChangesDetectedSyncExternalEvent@7b077154) ...
16-2-17 8:19:03.978 | ActionFileHandl | Test worker    | INFO : Starting action for RemoteFile[name=action-down-A-1487233143969] ...
16-2-17 8:19:03.978 | ActionFileHandl | Test worker    | INFO : Uploading action file: RemoteFile[name=action-down-A-1487233143969]
16-2-17 8:19:03.978 | ActionFileHandl | Test worker    | INFO : Scheduling action renewal task for every 2 minutes, for RemoteFile[name=action-down-A-1487233143969] ...
16-2-17 8:19:03.979 | DownOperation   | Test worker    | INFO : Downloading unknown databases.
16-2-17 8:19:03.979 | DownOperation   | Test worker    | INFO : - Downloading database-B-0000000001 to local cache at /tmp/syncanytest/syncany-170216081832804-36803-client-A/.syncany/cache/database-B-0000000001
16-2-17 8:19:03.979 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event DownDownloadFileSyncExternalEvent (org.syncany.operations.daemon.messages.DownDownloadFileSyncExternalEvent@a929769) ...
16-2-17 8:19:03.981 | DownOperation   | Test worker    | INFO : Loading database headers, creating branches ...
16-2-17 8:19:03.981 | DatabaseXmlSeri | Test worker    | INFO : - Loading database (HEADER_ONLY) from file /tmp/syncanytest/syncany-170216081832804-36803-client-A/.syncany/cache/database-B-0000000001 ...
16-2-17 8:19:03.986 | DatabaseXmlPars | Test worker    | INFO :    + Added database version B/(B1)/T=1487233143913
16-2-17 8:19:03.988 | LocalTransferMa | Test worker    | INFO : Cannot create instance of CleanupRemoteFile for file /tmp/syncanytest/syncany-170216081832804-36803-repo/databases/database-B-0000000001; maybe invalid file name pattern. Ignoring file.
16-2-17 8:19:03.988 | LocalTransferMa | Test worker    | INFO : Cannot create instance of CleanupRemoteFile for file /tmp/syncanytest/syncany-170216081832804-36803-repo/databases/database-A-0000000001; maybe invalid file name pattern. Ignoring file.
16-2-17 8:19:03.992 | ApplicationSqlD | Test worker    | INFO : SQL (general_settings): Read cleanupNumber  = (not set)
16-2-17 8:19:03.992 | DownOperation   | Test worker    | INFO : Populated unknown branches: {A=[A/(A1)/T=1487233143669], B=[B/(B1)/T=1487233143913]}
16-2-17 8:19:03.993 | DownOperation   | Test worker    | INFO : Determine winner using database reconciliator ...
16-2-17 8:19:03.993 | DatabaseReconci | Test worker    | INFO : Adding database version to winning branch: A/(A1)/T=1487233143669
16-2-17 8:19:03.993 | DatabaseReconci | Test worker    | INFO : Ignoring databaseVersion: B/(B1)/T=1487233143913
16-2-17 8:19:03.993 | DatabaseReconci | Test worker    | INFO : - Winner is A with branch: 
16-2-17 8:19:03.993 | DatabaseReconci | Test worker    | INFO :   + A/(A1)/T=1487233143669
16-2-17 8:19:03.993 | DownOperation   | Test worker    | INFO : - Database versions to REMOVE locally: []
16-2-17 8:19:03.993 | DownOperation   | Test worker    | INFO :   + Nothing to purge locally. No conflicts. Only updates. Nice!
16-2-17 8:19:03.993 | DatabaseReconci | Test worker    | INFO : Finding winnersApplyBranch.
16-2-17 8:19:03.993 | DatabaseReconci | Test worker    | INFO : Losers Branch: [A/(A1)/T=1487233143669]
16-2-17 8:19:03.993 | DatabaseReconci | Test worker    | INFO : Winners Branch: [A/(A1)/T=1487233143669]
16-2-17 8:19:03.993 | DownOperation   | Test worker    | INFO : - Cleanup occurred: false
16-2-17 8:19:03.993 | DownOperation   | Test worker    | INFO : - Database versions to APPLY locally: []
16-2-17 8:19:03.993 | DownOperation   | Test worker    | WARN :   + Nothing to update. Nice!
16-2-17 8:19:03.994 | DownOperation   | Test worker    | INFO : DIRTY database version headers of B:  [B/(B1)/T=1487233143913]
16-2-17 8:19:03.994 | DownOperation   | Test worker    | INFO :   - Loading B/(B1)/T=1487233143913 from file /tmp/syncanytest/syncany-170216081832804-36803-client-A/.syncany/cache/database-B-0000000001
16-2-17 8:19:03.994 | DatabaseXmlSeri | Test worker    | INFO : - Loading database (FULL) from file /tmp/syncanytest/syncany-170216081832804-36803-client-A/.syncany/cache/database-B-0000000001 ...
16-2-17 8:19:03.999 | DatabaseXmlPars | Test worker    | INFO :    + Added database version B/(B1)/T=1487233143913
16-2-17 8:19:04.012 | ActionFileHandl | Test worker    | INFO : Finishing action for RemoteFile[name=action-down-A-1487233143969] ...
16-2-17 8:19:04.020 | ActionFileHandl | Test worker    | INFO : Deleting action file: RemoteFile[name=action-down-A-1487233143969]
16-2-17 8:19:04.020 | AbstractTransfe | Test worker    | INFO : Cleaning own old action files ...
16-2-17 8:19:04.020 | Cache           | Test worker    | INFO : Cache size okay (159 KB), no need to clean (keep size is 500 MB)
16-2-17 8:19:04.020 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event DownEndSyncExternalEvent (org.syncany.operations.daemon.messages.DownEndSyncExternalEvent@5aee612c) ...
16-2-17 8:19:04.020 | DownOperation   | Test worker    | INFO : Sync down done.
16-2-17 8:19:04.021 | org.syncany.plu | Test worker    | FINE : Validating required fields
16-2-17 8:19:04.021 | TransferManager | Test worker    | INFO : Building LocalTransferManager from config 'syncany-170216081832804-36803-client-B' ...
16-2-17 8:19:04.021 | TransferManager | Test worker    | INFO : - With feature ReadAfterWriteConsistent
16-2-17 8:19:04.021 | TransferManager | Test worker    | INFO : - With feature Retriable
16-2-17 8:19:04.021 | TransferManager | Test worker    | FINE : - Checking if selected features supported ...
16-2-17 8:19:04.021 | TransferManager | Test worker    | FINE : - Checking for duplicate features ...
16-2-17 8:19:04.021 | TransferManager | Test worker    | FINE : - Checking required feature TransactionAware in LocalTransferManager ...
16-2-17 8:19:04.021 | TransferManager | Test worker    | FINE : - Checking required feature Retriable in LocalTransferManager ...
16-2-17 8:19:04.021 | TransferManager | Test worker    | INFO : - SKIPPING unsupported optional feature ReadAfterWriteConsistent
16-2-17 8:19:04.022 | TransferManager | Test worker    | FINE : - Wrapping TransferManager LocalTransferManager in RetriableFeatureTransferManager
16-2-17 8:19:04.022 | org.syncany.plu | Test worker    | FINE : Validating required fields
16-2-17 8:19:04.022 | TransferManager | Test worker    | INFO : Building LocalTransferManager from config 'syncany-170216081832804-36803-client-B' ...
16-2-17 8:19:04.022 | TransferManager | Test worker    | INFO : - With feature ReadAfterWriteConsistent
16-2-17 8:19:04.022 | TransferManager | Test worker    | INFO : - With feature Retriable
16-2-17 8:19:04.022 | TransferManager | Test worker    | INFO : - With feature PathAware
16-2-17 8:19:04.022 | TransferManager | Test worker    | INFO : - With feature TransactionAware
16-2-17 8:19:04.022 | TransferManager | Test worker    | FINE : - Checking if selected features supported ...
16-2-17 8:19:04.022 | TransferManager | Test worker    | FINE : - Checking for duplicate features ...
16-2-17 8:19:04.022 | TransferManager | Test worker    | FINE : - Checking required feature TransactionAware in LocalTransferManager ...
16-2-17 8:19:04.022 | TransferManager | Test worker    | FINE : - Checking required feature Retriable in LocalTransferManager ...
16-2-17 8:19:04.022 | TransferManager | Test worker    | INFO : - SKIPPING unsupported optional feature ReadAfterWriteConsistent
16-2-17 8:19:04.022 | TransferManager | Test worker    | FINE : - Wrapping TransferManager LocalTransferManager in RetriableFeatureTransferManager
16-2-17 8:19:04.022 | TransferManager | Test worker    | INFO : - SKIPPING unsupported optional feature PathAware
16-2-17 8:19:04.022 | TransferManager | Test worker    | FINE : - Wrapping TransferManager RetriableFeatureTransferManager in TransactionAwareFeatureTransferManager
16-2-17 8:19:04.034 | DatabaseConnect | Test worker    | INFO : Found 12 tables.
16-2-17 8:19:04.034 | DownOperation   | Test worker    | INFO : 
16-2-17 8:19:04.034 | DownOperation   | Test worker    | INFO : Running 'Sync down' at client B ...
16-2-17 8:19:04.034 | DownOperation   | Test worker    | INFO : --------------------------------------------
16-2-17 8:19:04.034 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event DownStartSyncExternalEvent (org.syncany.operations.daemon.messages.DownStartSyncExternalEvent@22a9459b) ...
16-2-17 8:19:04.034 | AbstractTransfe | Test worker    | INFO : Looking for other running remote operations ...
16-2-17 8:19:04.035 | DatabaseConnect | Test worker    | INFO : Found 12 tables.
16-2-17 8:19:04.035 | LsRemoteOperati | Test worker    | INFO : 
16-2-17 8:19:04.035 | LsRemoteOperati | Test worker    | INFO : Running 'Remote Status' at client B ...
16-2-17 8:19:04.035 | LsRemoteOperati | Test worker    | INFO : --------------------------------------------
16-2-17 8:19:04.035 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event LsRemoteStartSyncExternalEvent (org.syncany.operations.daemon.messages.LsRemoteStartSyncExternalEvent@113e1f5) ...
16-2-17 8:19:04.035 | LsRemoteOperati | Test worker    | INFO : Retrieving remote database list.
16-2-17 8:19:04.035 | LocalTransferMa | Test worker    | INFO : Cannot create instance of DatabaseRemoteFile for file /tmp/syncanytest/syncany-170216081832804-36803-repo/databases/TEMP_db-B-0000000001; maybe invalid file name pattern. Ignoring file.
16-2-17 8:19:04.035 | LsRemoteOperati | Test worker    | INFO : - Remote database database-B-0000000001 is already known (in local database). Ignoring.
16-2-17 8:19:04.035 | LsRemoteOperati | Test worker    | INFO : - Remote database database-A-0000000001 is new.
16-2-17 8:19:04.035 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event LsRemoteEndSyncExternalEvent (org.syncany.operations.daemon.messages.LsRemoteEndSyncExternalEvent@36515543) ...
16-2-17 8:19:04.035 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event DownChangesDetectedSyncExternalEvent (org.syncany.operations.daemon.messages.DownChangesDetectedSyncExternalEvent@76da02d0) ...
16-2-17 8:19:04.035 | ActionFileHandl | Test worker    | INFO : Starting action for RemoteFile[name=action-down-B-1487233144022] ...
16-2-17 8:19:04.035 | ActionFileHandl | Test worker    | INFO : Uploading action file: RemoteFile[name=action-down-B-1487233144022]
16-2-17 8:19:04.036 | ActionFileHandl | Test worker    | INFO : Scheduling action renewal task for every 2 minutes, for RemoteFile[name=action-down-B-1487233144022] ...
16-2-17 8:19:04.036 | DownOperation   | Test worker    | INFO : Downloading unknown databases.
16-2-17 8:19:04.036 | DownOperation   | Test worker    | INFO : - Downloading database-A-0000000001 to local cache at /tmp/syncanytest/syncany-170216081832804-36803-client-B/.syncany/cache/database-A-0000000001
16-2-17 8:19:04.036 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event DownDownloadFileSyncExternalEvent (org.syncany.operations.daemon.messages.DownDownloadFileSyncExternalEvent@682bb6fb) ...
16-2-17 8:19:04.037 | DownOperation   | Test worker    | INFO : Loading database headers, creating branches ...
16-2-17 8:19:04.037 | DatabaseXmlSeri | Test worker    | INFO : - Loading database (HEADER_ONLY) from file /tmp/syncanytest/syncany-170216081832804-36803-client-B/.syncany/cache/database-A-0000000001 ...
16-2-17 8:19:04.039 | DatabaseXmlPars | Test worker    | INFO :    + Added database version A/(A1)/T=1487233143669
16-2-17 8:19:04.039 | LocalTransferMa | Test worker    | INFO : Cannot create instance of CleanupRemoteFile for file /tmp/syncanytest/syncany-170216081832804-36803-repo/databases/TEMP_db-B-0000000001; maybe invalid file name pattern. Ignoring file.
16-2-17 8:19:04.041 | LocalTransferMa | Test worker    | INFO : Cannot create instance of CleanupRemoteFile for file /tmp/syncanytest/syncany-170216081832804-36803-repo/databases/database-B-0000000001; maybe invalid file name pattern. Ignoring file.
16-2-17 8:19:04.041 | LocalTransferMa | Test worker    | INFO : Cannot create instance of CleanupRemoteFile for file /tmp/syncanytest/syncany-170216081832804-36803-repo/databases/database-A-0000000001; maybe invalid file name pattern. Ignoring file.
16-2-17 8:19:04.041 | ApplicationSqlD | Test worker    | INFO : SQL (general_settings): Read cleanupNumber  = (not set)
16-2-17 8:19:04.041 | DownOperation   | Test worker    | INFO : Populated unknown branches: {A=[A/(A1)/T=1487233143669], B=[B/(B1)/T=1487233143913]}
16-2-17 8:19:04.041 | DownOperation   | Test worker    | INFO : Determine winner using database reconciliator ...
16-2-17 8:19:04.041 | DatabaseReconci | Test worker    | INFO : Adding database version to winning branch: A/(A1)/T=1487233143669
16-2-17 8:19:04.041 | DatabaseReconci | Test worker    | INFO : Ignoring databaseVersion: B/(B1)/T=1487233143913
16-2-17 8:19:04.041 | DatabaseReconci | Test worker    | INFO : - Winner is A with branch: 
16-2-17 8:19:04.041 | DatabaseReconci | Test worker    | INFO :   + A/(A1)/T=1487233143669
16-2-17 8:19:04.041 | DownOperation   | Test worker    | INFO : - Database versions to REMOVE locally: [B/(B1)/T=1487233143913]
16-2-17 8:19:04.041 | DownOperation   | Test worker    | INFO :   + Marking databases as DIRTY locally ...
16-2-17 8:19:04.041 | DownOperation   | Test worker    | INFO :     * MASTER->DIRTY: B/(B1)/T=1487233143913
16-2-17 8:19:04.047 | DownOperation   | Test worker    | INFO :     * Deleting own remote database file RemoteFile[name=database-B-0000000001] ...
16-2-17 8:19:04.047 | DatabaseReconci | Test worker    | INFO : Finding winnersApplyBranch.
16-2-17 8:19:04.047 | DatabaseReconci | Test worker    | INFO : Losers Branch: [B/(B1)/T=1487233143913]
16-2-17 8:19:04.047 | DatabaseReconci | Test worker    | INFO : Winners Branch: [A/(A1)/T=1487233143669]
16-2-17 8:19:04.047 | DownOperation   | Test worker    | INFO : - Cleanup occurred: false
16-2-17 8:19:04.047 | DownOperation   | Test worker    | INFO : - Database versions to APPLY locally: [A/(A1)/T=1487233143669]
16-2-17 8:19:04.047 | DownOperation   | Test worker    | INFO : Loading winners database (DEFAULT) ...
16-2-17 8:19:04.047 | DatabaseXmlSeri | Test worker    | INFO : - Loading database (FULL) from file /tmp/syncanytest/syncany-170216081832804-36803-client-B/.syncany/cache/database-A-0000000001 ...
16-2-17 8:19:04.053 | DatabaseXmlPars | Test worker    | INFO :    + Added database version A/(A1)/T=1487233143669
16-2-17 8:19:04.053 | DownOperation   | Test worker    | INFO : Determine file system actions ...
16-2-17 8:19:04.054 | DatabaseConnect | Test worker    | INFO : Found 12 tables.
16-2-17 8:19:04.054 | FileSystemActio | Test worker    | INFO : - Loading current file tree...
16-2-17 8:19:04.055 | FileSystemActio | Test worker    | INFO : - Determine filesystem actions ...
16-2-17 8:19:04.055 | FileSystemActio | Test worker    | INFO :   + Comparing local version: null
16-2-17 8:19:04.055 | FileSystemActio | Test worker    | INFO :     with winning version   : FileVersion [version=1, path=sphinxbase-0.8, type=FOLDER, status=NEW, size=4096, lastModified=Thu Feb 16 08:19:03 UTC 2017, linkTarget=null, checksum=null, updated=Thu Feb 16 08:19:03 UTC 2017, posixPermissions=rwxrwxr-x, dosAttributes=--a-]
16-2-17 8:19:04.055 | FileSystemActio | Test worker    | INFO :      -> (1) Equals: Nothing to do, winning version equals winning file: FileVersion [version=1, path=sphinxbase-0.8, type=FOLDER, status=NEW, size=4096, lastModified=Thu Feb 16 08:19:03 UTC 2017, linkTarget=null, checksum=null, updated=Thu Feb 16 08:19:03 UTC 2017, posixPermissions=rwxrwxr-x, dosAttributes=--a-] AND /tmp/syncanytest/syncany-170216081832804-36803-client-B/sphinxbase-0.8
16-2-17 8:19:04.055 | FileSystemActio | Test worker    | INFO :   + Comparing local version: null
16-2-17 8:19:04.055 | FileSystemActio | Test worker    | INFO :     with winning version   : FileVersion [version=1, path=sphinxbase-0.8/win32, type=FOLDER, status=NEW, size=4096, lastModified=Thu Feb 16 08:19:03 UTC 2017, linkTarget=null, checksum=null, updated=Thu Feb 16 08:19:03 UTC 2017, posixPermissions=rwxrwxr-x, dosAttributes=--a-]
16-2-17 8:19:04.055 | FileSystemActio | Test worker    | INFO :      -> (1) Equals: Nothing to do, winning version equals winning file: FileVersion [version=1, path=sphinxbase-0.8/win32, type=FOLDER, status=NEW, size=4096, lastModified=Thu Feb 16 08:19:03 UTC 2017, linkTarget=null, checksum=null, updated=Thu Feb 16 08:19:03 UTC 2017, posixPermissions=rwxrwxr-x, dosAttributes=--a-] AND /tmp/syncanytest/syncany-170216081832804-36803-client-B/sphinxbase-0.8/win32
16-2-17 8:19:04.055 | FileSystemActio | Test worker    | INFO :   + Comparing local version: null
16-2-17 8:19:04.055 | FileSystemActio | Test worker    | INFO :     with winning version   : FileVersion [version=1, path=sphinxbase-0.8/win32/sphinx_jsgf2fsg, type=FOLDER, status=NEW, size=4096, lastModified=Thu Feb 16 08:19:03 UTC 2017, linkTarget=null, checksum=null, updated=Thu Feb 16 08:19:03 UTC 2017, posixPermissions=rwxrwxr-x, dosAttributes=--a-]
16-2-17 8:19:04.055 | FileSystemActio | Test worker    | INFO :      -> (1) Equals: Nothing to do, winning version equals winning file: FileVersion [version=1, path=sphinxbase-0.8/win32/sphinx_jsgf2fsg, type=FOLDER, status=NEW, size=4096, lastModified=Thu Feb 16 08:19:03 UTC 2017, linkTarget=null, checksum=null, updated=Thu Feb 16 08:19:03 UTC 2017, posixPermissions=rwxrwxr-x, dosAttributes=--a-] AND /tmp/syncanytest/syncany-170216081832804-36803-client-B/sphinxbase-0.8/win32/sphinx_jsgf2fsg
16-2-17 8:19:04.055 | FileSystemActio | Test worker    | INFO :   + Comparing local version: null
16-2-17 8:19:04.055 | FileSystemActio | Test worker    | INFO :     with winning version   : FileVersion [version=1, path=sphinxbase-0.8/config.sub, type=FILE, status=NEW, size=51200, lastModified=Thu Feb 16 08:19:03 UTC 2017, linkTarget=null, checksum=23dfa10beaedb1d38157c1acc50dc4ea102859b1, updated=Thu Feb 16 08:19:03 UTC 2017, posixPermissions=rw-rw-r--, dosAttributes=--a-]
16-2-17 8:19:04.056 | FileSystemActio | Test worker    | INFO :      -> (1) Equals: Nothing to do, winning version equals winning file: FileVersion [version=1, path=sphinxbase-0.8/config.sub, type=FILE, status=NEW, size=51200, lastModified=Thu Feb 16 08:19:03 UTC 2017, linkTarget=null, checksum=23dfa10beaedb1d38157c1acc50dc4ea102859b1, updated=Thu Feb 16 08:19:03 UTC 2017, posixPermissions=rw-rw-r--, dosAttributes=--a-] AND /tmp/syncanytest/syncany-170216081832804-36803-client-B/sphinxbase-0.8/config.sub
16-2-17 8:19:04.056 | FileSystemActio | Test worker    | INFO :   + Comparing local version: null
16-2-17 8:19:04.057 | FileSystemActio | Test worker    | INFO :     with winning version   : FileVersion [version=1, path=sphinxbase-0.8/win32/sphinx_jsgf2fsg/sphinx_jsgf2fsg.vcxproj, type=FILE, status=NEW, size=51200, lastModified=Thu Feb 16 08:19:03 UTC 2017, linkTarget=null, checksum=ea985797ba9c8eaf3c9c1d94022f24f0c198553d, updated=Thu Feb 16 08:19:03 UTC 2017, posixPermissions=rw-rw-r--, dosAttributes=--a-]
16-2-17 8:19:04.057 | FileSystemActio | Test worker    | INFO :      -> (1) Equals: Nothing to do, winning version equals winning file: FileVersion [version=1, path=sphinxbase-0.8/win32/sphinx_jsgf2fsg/sphinx_jsgf2fsg.vcxproj, type=FILE, status=NEW, size=51200, lastModified=Thu Feb 16 08:19:03 UTC 2017, linkTarget=null, checksum=ea985797ba9c8eaf3c9c1d94022f24f0c198553d, updated=Thu Feb 16 08:19:03 UTC 2017, posixPermissions=rw-rw-r--, dosAttributes=--a-] AND /tmp/syncanytest/syncany-170216081832804-36803-client-B/sphinxbase-0.8/win32/sphinx_jsgf2fsg/sphinx_jsgf2fsg.vcxproj
16-2-17 8:19:04.057 | FileSystemActio | Test worker    | INFO :   + Comparing local version: null
16-2-17 8:19:04.057 | FileSystemActio | Test worker    | INFO :     with winning version   : FileVersion [version=1, path=sphinxbase-0.8/src, type=FOLDER, status=NEW, size=4096, lastModified=Thu Feb 16 08:19:03 UTC 2017, linkTarget=null, checksum=null, updated=Thu Feb 16 08:19:03 UTC 2017, posixPermissions=rwxrwxr-x, dosAttributes=--a-]
16-2-17 8:19:04.057 | FileSystemActio | Test worker    | INFO :      -> (1) Equals: Nothing to do, winning version equals winning file: FileVersion [version=1, path=sphinxbase-0.8/src, type=FOLDER, status=NEW, size=4096, lastModified=Thu Feb 16 08:19:03 UTC 2017, linkTarget=null, checksum=null, updated=Thu Feb 16 08:19:03 UTC 2017, posixPermissions=rwxrwxr-x, dosAttributes=--a-] AND /tmp/syncanytest/syncany-170216081832804-36803-client-B/sphinxbase-0.8/src
16-2-17 8:19:04.057 | FileSystemActio | Test worker    | INFO :   + Comparing local version: null
16-2-17 8:19:04.057 | FileSystemActio | Test worker    | INFO :     with winning version   : FileVersion [version=1, path=sphinxbase-0.8/src/sphinx_adtools/sphinx_pitch.c, type=FILE, status=NEW, size=51200, lastModified=Thu Feb 16 08:19:03 UTC 2017, linkTarget=null, checksum=779bfa820b43f6503f8e932a013cc79b68d62a7f, updated=Thu Feb 16 08:19:03 UTC 2017, posixPermissions=rw-rw-r--, dosAttributes=--a-]
16-2-17 8:19:04.059 | FileSystemActio | Test worker    | INFO :      -> (1) Equals: Nothing to do, winning version equals winning file: FileVersion [version=1, path=sphinxbase-0.8/src/sphinx_adtools/sphinx_pitch.c, type=FILE, status=NEW, size=51200, lastModified=Thu Feb 16 08:19:03 UTC 2017, linkTarget=null, checksum=779bfa820b43f6503f8e932a013cc79b68d62a7f, updated=Thu Feb 16 08:19:03 UTC 2017, posixPermissions=rw-rw-r--, dosAttributes=--a-] AND /tmp/syncanytest/syncany-170216081832804-36803-client-B/sphinxbase-0.8/src/sphinx_adtools/sphinx_pitch.c
16-2-17 8:19:04.060 | FileSystemActio | Test worker    | INFO :   + Comparing local version: null
16-2-17 8:19:04.060 | FileSystemActio | Test worker    | INFO :     with winning version   : FileVersion [version=1, path=sphinxbase-0.8/src/sphinx_adtools, type=FOLDER, status=NEW, size=4096, lastModified=Thu Feb 16 08:19:03 UTC 2017, linkTarget=null, checksum=null, updated=Thu Feb 16 08:19:03 UTC 2017, posixPermissions=rwxrwxr-x, dosAttributes=--a-]
16-2-17 8:19:04.060 | FileSystemActio | Test worker    | INFO :      -> (1) Equals: Nothing to do, winning version equals winning file: FileVersion [version=1, path=sphinxbase-0.8/src/sphinx_adtools, type=FOLDER, status=NEW, size=4096, lastModified=Thu Feb 16 08:19:03 UTC 2017, linkTarget=null, checksum=null, updated=Thu Feb 16 08:19:03 UTC 2017, posixPermissions=rwxrwxr-x, dosAttributes=--a-] AND /tmp/syncanytest/syncany-170216081832804-36803-client-B/sphinxbase-0.8/src/sphinx_adtools
16-2-17 8:19:04.060 | Downloader      | Test worker    | INFO : Downloading and extracting multichunks ...
16-2-17 8:19:04.060 | FileSystemActio | Test worker    | INFO :    Sorted actions:
16-2-17 8:19:04.060 | DownOperation   | Test worker    | FINE : - Applying file system actions (sorted!) ...
16-2-17 8:19:04.060 | DownOperation   | Test worker    | INFO : - Adding database versions to SQL database ...
16-2-17 8:19:04.060 | DownOperation   | Test worker    | INFO :   + Applying database version (A1)
16-2-17 8:19:04.060 | DownOperation   | Test worker    | FINE :   + Contents: DatabaseVersion [header=A/(A1)/T=1487233143669, chunks=3, multiChunks=1, fileContents=3, fileHistories=8]
16-2-17 8:19:04.072 | ActionFileHandl | Test worker    | INFO : Finishing action for RemoteFile[name=action-down-B-1487233144022] ...
16-2-17 8:19:04.079 | ActionFileHandl | Test worker    | INFO : Deleting action file: RemoteFile[name=action-down-B-1487233144022]
16-2-17 8:19:04.079 | AbstractTransfe | Test worker    | INFO : Cleaning own old action files ...
16-2-17 8:19:04.079 | Cache           | Test worker    | INFO : Cache size okay (159 KB), no need to clean (keep size is 500 MB)
16-2-17 8:19:04.079 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event DownEndSyncExternalEvent (org.syncany.operations.daemon.messages.DownEndSyncExternalEvent@a892a29) ...
16-2-17 8:19:04.079 | DownOperation   | Test worker    | INFO : Sync down done.
16-2-17 8:19:04.081 | org.syncany.plu | Test worker    | FINE : Validating required fields
16-2-17 8:19:04.081 | TransferManager | Test worker    | INFO : Building LocalTransferManager from config 'syncany-170216081832804-36803-client-B' ...
16-2-17 8:19:04.081 | TransferManager | Test worker    | INFO : - With feature ReadAfterWriteConsistent
16-2-17 8:19:04.081 | TransferManager | Test worker    | INFO : - With feature Retriable
16-2-17 8:19:04.081 | TransferManager | Test worker    | FINE : - Checking if selected features supported ...
16-2-17 8:19:04.081 | TransferManager | Test worker    | FINE : - Checking for duplicate features ...
16-2-17 8:19:04.081 | TransferManager | Test worker    | FINE : - Checking required feature TransactionAware in LocalTransferManager ...
16-2-17 8:19:04.081 | TransferManager | Test worker    | FINE : - Checking required feature Retriable in LocalTransferManager ...
16-2-17 8:19:04.081 | TransferManager | Test worker    | INFO : - SKIPPING unsupported optional feature ReadAfterWriteConsistent
16-2-17 8:19:04.081 | TransferManager | Test worker    | FINE : - Wrapping TransferManager LocalTransferManager in RetriableFeatureTransferManager
16-2-17 8:19:04.081 | org.syncany.plu | Test worker    | FINE : Validating required fields
16-2-17 8:19:04.082 | TransferManager | Test worker    | INFO : Building LocalTransferManager from config 'syncany-170216081832804-36803-client-B' ...
16-2-17 8:19:04.082 | TransferManager | Test worker    | INFO : - With feature ReadAfterWriteConsistent
16-2-17 8:19:04.082 | TransferManager | Test worker    | INFO : - With feature Retriable
16-2-17 8:19:04.082 | TransferManager | Test worker    | INFO : - With feature PathAware
16-2-17 8:19:04.082 | TransferManager | Test worker    | INFO : - With feature TransactionAware
16-2-17 8:19:04.082 | TransferManager | Test worker    | FINE : - Checking if selected features supported ...
16-2-17 8:19:04.082 | TransferManager | Test worker    | FINE : - Checking for duplicate features ...
16-2-17 8:19:04.082 | TransferManager | Test worker    | FINE : - Checking required feature TransactionAware in LocalTransferManager ...
16-2-17 8:19:04.082 | TransferManager | Test worker    | FINE : - Checking required feature Retriable in LocalTransferManager ...
16-2-17 8:19:04.082 | TransferManager | Test worker    | INFO : - SKIPPING unsupported optional feature ReadAfterWriteConsistent
16-2-17 8:19:04.082 | TransferManager | Test worker    | FINE : - Wrapping TransferManager LocalTransferManager in RetriableFeatureTransferManager
16-2-17 8:19:04.082 | TransferManager | Test worker    | INFO : - SKIPPING unsupported optional feature PathAware
16-2-17 8:19:04.082 | TransferManager | Test worker    | FINE : - Wrapping TransferManager RetriableFeatureTransferManager in TransactionAwareFeatureTransferManager
16-2-17 8:19:04.094 | DatabaseConnect | Test worker    | INFO : Found 12 tables.
16-2-17 8:19:04.094 | UpOperation     | Test worker    | INFO : 
16-2-17 8:19:04.094 | UpOperation     | Test worker    | INFO : Running 'Sync up' at client B ...
16-2-17 8:19:04.094 | UpOperation     | Test worker    | INFO : --------------------------------------------
16-2-17 8:19:04.094 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event UpStartSyncExternalEvent (org.syncany.operations.daemon.messages.UpStartSyncExternalEvent@3b97a93c) ...
16-2-17 8:19:04.095 | DatabaseConnect | Test worker    | INFO : Found 12 tables.
16-2-17 8:19:04.095 | StatusOperation | Test worker    | INFO : 
16-2-17 8:19:04.095 | StatusOperation | Test worker    | INFO : Running 'Status' at client B ...
16-2-17 8:19:04.095 | StatusOperation | Test worker    | INFO : --------------------------------------------
16-2-17 8:19:04.095 | StatusOperation | Test worker    | INFO : Querying current file tree from database ...
16-2-17 8:19:04.096 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event StatusStartSyncExternalEvent (org.syncany.operations.daemon.messages.StatusStartSyncExternalEvent@1534ccd3) ...
16-2-17 8:19:04.097 | StatusOperation | Test worker    | INFO : Analyzing local folder /tmp/syncanytest/syncany-170216081832804-36803-client-B ...
16-2-17 8:19:04.097 | StatusOperation | Test worker    | FINE : - Ignoring file (syncany app-related): .syncany
16-2-17 8:19:04.098 | FileVersionComp | Test worker    | INFO :      - [CHANGED_ATTRIBUTES]: Local file DIFFERS from file version, expected POSIX ATTRS = rwxrwxr-x, but actual POSIX ATTRS = rwxrwxrwx, for file sphinxbase-0.8
16-2-17 8:19:04.099 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event StatusEndSyncExternalEvent (org.syncany.operations.daemon.messages.StatusEndSyncExternalEvent@60021e52) ...
16-2-17 8:19:04.099 | AbstractTransfe | Test worker    | INFO : Looking for other running remote operations ...
16-2-17 8:19:04.099 | DatabaseConnect | Test worker    | INFO : Found 12 tables.
16-2-17 8:19:04.099 | LsRemoteOperati | Test worker    | INFO : 
16-2-17 8:19:04.099 | LsRemoteOperati | Test worker    | INFO : Running 'Remote Status' at client B ...
16-2-17 8:19:04.100 | LsRemoteOperati | Test worker    | INFO : --------------------------------------------
16-2-17 8:19:04.100 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event LsRemoteStartSyncExternalEvent (org.syncany.operations.daemon.messages.LsRemoteStartSyncExternalEvent@7d80a50b) ...
16-2-17 8:19:04.100 | LsRemoteOperati | Test worker    | INFO : Retrieving remote database list.
16-2-17 8:19:04.100 | LocalTransferMa | Test worker    | INFO : Cannot create instance of DatabaseRemoteFile for file /tmp/syncanytest/syncany-170216081832804-36803-repo/databases/TEMP_db-B-0000000001; maybe invalid file name pattern. Ignoring file.
16-2-17 8:19:04.100 | LsRemoteOperati | Test worker    | INFO : - Remote database database-A-0000000001 is already known (in local database). Ignoring.
16-2-17 8:19:04.100 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event LsRemoteEndSyncExternalEvent (org.syncany.operations.daemon.messages.LsRemoteEndSyncExternalEvent@1c8ad5ee) ...
16-2-17 8:19:04.100 | UpOperation     | Test worker    | INFO : No remote changes, ready to upload.
16-2-17 8:19:04.100 | ActionFileHandl | Test worker    | INFO : Starting action for RemoteFile[name=action-up-B-1487233144082] ...
16-2-17 8:19:04.100 | ActionFileHandl | Test worker    | INFO : Uploading action file: RemoteFile[name=action-up-B-1487233144082]
16-2-17 8:19:04.100 | ActionFileHandl | Test worker    | INFO : Scheduling action renewal task for every 2 minutes, for RemoteFile[name=action-up-B-1487233144082] ...
16-2-17 8:19:04.101 | DatabaseConnect | Test worker    | INFO : Found 12 tables.
16-2-17 8:19:04.110 | TransactionAwar | Test worker    | INFO : Done rolling back previous transactions.
16-2-17 8:19:04.110 | AsyncIndexer    | AsyncI/syncany | INFO : Starting Indexing.
16-2-17 8:19:04.115 | InternalEventBu | AsyncI/syncany | INFO : Event bus 'LocalEventBus': Posting event UpIndexStartSyncExternalEvent (org.syncany.operations.daemon.messages.UpIndexStartSyncExternalEvent@50fb848) ...
16-2-17 8:19:04.115 | Indexer         | AsyncI/syncany | FINE : - Looking for deleted files ...
16-2-17 8:19:04.115 | Indexer         | AsyncI/syncany | FINE : - +File /tmp/syncanytest/syncany-170216081832804-36803-client-B/sphinxbase-0.8
16-2-17 8:19:04.115 | Indexer         | AsyncI/syncany | FINE : - /File: sphinxbase-0.8 (directory/symlink/0-byte-file)
16-2-17 8:19:04.121 | Indexer         | AsyncI/syncany | FINE :    * Found old file history 4e30893f85fd3c2f505b0ab6ce52f272b7a28c1e (by path: sphinxbase-0.8), FOLDER, appending new version.
16-2-17 8:19:04.121 | FileVersionComp | AsyncI/syncany | INFO :      - [CHANGED_ATTRIBUTES]: Local file DIFFERS from file version, expected POSIX ATTRS = rwxrwxrwx, but actual POSIX ATTRS = rwxrwxr-x, for file sphinxbase-0.8
16-2-17 8:19:04.121 | Indexer         | AsyncI/syncany | INFO :    * Added file version:    FileVersion [version=2, path=sphinxbase-0.8, type=FOLDER, status=CHANGED, size=4096, lastModified=Thu Feb 16 08:19:03 UTC 2017, linkTarget=null, checksum=null, updated=Thu Feb 16 08:19:04 UTC 2017, posixPermissions=rwxrwxrwx, dosAttributes=--a-]
16-2-17 8:19:04.121 | Indexer         | AsyncI/syncany | INFO :      based on file version: FileVersion [version=1, path=sphinxbase-0.8, type=FOLDER, status=NEW, size=4096, lastModified=Thu Feb 16 08:19:03 UTC 2017, linkTarget=null, checksum=null, updated=Thu Feb 16 08:19:03 UTC 2017, posixPermissions=rwxrwxr-x, dosAttributes=--a-]
16-2-17 8:19:04.121 | InternalEventBu | AsyncI/syncany | INFO : Event bus 'LocalEventBus': Posting event UpIndexChangesDetectedSyncExternalEvent (org.syncany.operations.daemon.messages.UpIndexChangesDetectedSyncExternalEvent@2e94ed86) ...
16-2-17 8:19:04.121 | InternalEventBu | AsyncI/syncany | INFO : Event bus 'LocalEventBus': Posting event UpIndexEndSyncExternalEvent (org.syncany.operations.daemon.messages.UpIndexEndSyncExternalEvent@7ace528a) ...
16-2-17 8:19:04.121 | Indexer         | AsyncI/syncany | FINE : Processed new database version: DatabaseVersion [header=UnknownMachine/()/T=1487233144115, chunks=0, multiChunks=0, fileContents=0, fileHistories=1]
16-2-17 8:19:04.121 | UpOperation     | Test worker    | INFO : Adding DIRTY data to new database version: 
16-2-17 8:19:04.123 | UpOperation     | Test worker    | INFO : - Adding chunks/multichunks/filecontents from database version B/(B1)/T=1487233143913
16-2-17 8:19:04.123 | UpOperation     | Test worker    | INFO : Last vector clock was: (A1)
16-2-17 8:19:04.124 | UpOperation     | Test worker    | INFO : Uploading new multichunks ...
16-2-17 8:19:04.124 | UpOperation     | Test worker    | INFO : - Ignoring multichunk (from dirty database, already uploaded), a38a88b691aeedb4f279c6fb2357055a0626d1c2 ...
16-2-17 8:19:04.124 | UpOperation     | Test worker    | INFO : Uploading database: DatabaseVersion [header=B/(A1,B2)/T=1487233144124, chunks=3, multiChunks=1, fileContents=3, fileHistories=1]
16-2-17 8:19:04.124 | UpOperation     | Test worker    | INFO : Saving local delta database, version B/(A1,B2)/T=1487233144124 to file /tmp/syncanytest/syncany-170216081832804-36803-client-B/.syncany/cache/database-B-0000000002 ... 
16-2-17 8:19:04.124 | UpOperation     | Test worker    | INFO : - Saving database to /tmp/syncanytest/syncany-170216081832804-36803-client-B/.syncany/cache/database-B-0000000002 ...
16-2-17 8:19:04.125 | UpOperation     | Test worker    | INFO : - Uploading local delta database file ...
16-2-17 8:19:04.125 | UpOperation     | Test worker    | INFO : - Uploading /tmp/syncanytest/syncany-170216081832804-36803-client-B/.syncany/cache/database-B-0000000002 to RemoteFile[name=database-B-0000000002] ...
16-2-17 8:19:04.125 | RemoteTransacti | Test worker    | INFO : - Adding file to TX for UPLOAD: /tmp/syncanytest/syncany-170216081832804-36803-client-B/.syncany/cache/database-B-0000000002 -> Temp. remote file: RemoteFile[name=temp-rCxEO-database-B-0000000002], final location: RemoteFile[name=database-B-0000000002]
16-2-17 8:19:04.125 | UpOperation     | Test worker    | INFO : Adding shutdown hook (to allow resuming the upload) ...
16-2-17 8:19:04.125 | RemoteTransacti | Test worker    | INFO : Starting TX.commit() ...
16-2-17 8:19:04.127 | RemoteTransacti | Test worker    | INFO : Wrote transaction manifest to temporary file: /tmp/syncanytest/syncany-170216081832804-36803-client-B/.syncany/cache/temp-transaction-4032946254265878438.tmp
16-2-17 8:19:04.127 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event UpUploadFileSyncExternalEvent (org.syncany.operations.daemon.messages.UpUploadFileSyncExternalEvent@2bd4fb77) ...
16-2-17 8:19:04.127 | RemoteTransacti | Test worker    | INFO : - Uploading remote transaction file RemoteFile[name=transaction-7640a72e] ...
16-2-17 8:19:04.127 | RemoteTransacti | Test worker    | INFO : - Starting to upload data in commit.
16-2-17 8:19:04.127 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event UpUploadFileInTransactionSyncExternalEvent (org.syncany.operations.daemon.messages.UpUploadFileInTransactionSyncExternalEvent@5c918af2) ...
16-2-17 8:19:04.127 | RemoteTransacti | Test worker    | INFO : - Uploading /tmp/syncanytest/syncany-170216081832804-36803-client-B/.syncany/cache/database-B-0000000002 to temp. file RemoteFile[name=temp-rCxEO-database-B-0000000002] ...
16-2-17 8:19:04.127 | RemoteTransacti | Test worker    | INFO : - Moving temp. file RemoteFile[name=temp-rCxEO-database-B-0000000002] to final location RemoteFile[name=database-B-0000000002] ...
16-2-17 8:19:04.128 | RemoteTransacti | Test worker    | INFO : - Deleting remote transaction file RemoteFile[name=transaction-7640a72e] ...
16-2-17 8:19:04.128 | RemoteTransacti | Test worker    | INFO : END of TX.commmit(): Succesfully committed transaction.
16-2-17 8:19:04.128 | RemoteTransacti | Test worker    | INFO : END of TX.delTemp(): Sucessfully deleted final files.
16-2-17 8:19:04.128 | UpOperation     | Test worker    | INFO : Persisting local SQL database (new database version B/(A1,B2)/T=1487233144124) ...
16-2-17 8:19:04.128 | InternalEventBu | AsyncI/syncany | INFO : Event bus 'LocalEventBus': Posting event UpIndexMidSyncExternalEvent (org.syncany.operations.daemon.messages.UpIndexMidSyncExternalEvent@4b202c6d) ...
16-2-17 8:19:04.131 | UpOperation     | Test worker    | INFO : Removing DIRTY database versions from database ...
16-2-17 8:19:04.134 | AsyncIndexer    | AsyncI/syncany | INFO : Stopping indexing. Signal end of stream with empty databaseversion
16-2-17 8:19:04.150 | UpOperation     | Test worker    | INFO : Adding database version to result changes:DatabaseVersion [header=B/(A1,B2)/T=1487233144124, chunks=3, multiChunks=1, fileContents=3, fileHistories=1]
16-2-17 8:19:04.150 | UpOperation     | Test worker    | INFO : Committing local database.
16-2-17 8:19:04.150 | UpOperation     | Test worker    | FINE : Waiting for new database version.
16-2-17 8:19:04.150 | UpOperation     | Test worker    | FINE : Took new database version: DatabaseVersion [header=UnknownMachine/()/T=1487233144134, chunks=0, multiChunks=0, fileContents=0, fileHistories=0]
16-2-17 8:19:04.150 | UpOperation     | Test worker    | INFO : Sync up done.
16-2-17 8:19:04.152 | ActionFileHandl | Test worker    | INFO : Finishing action for RemoteFile[name=action-up-B-1487233144082] ...
16-2-17 8:19:04.158 | ActionFileHandl | Test worker    | INFO : Deleting action file: RemoteFile[name=action-up-B-1487233144082]
16-2-17 8:19:04.159 | AbstractTransfe | Test worker    | INFO : Cleaning own old action files ...
16-2-17 8:19:04.159 | Cache           | Test worker    | INFO : Cache size okay (160 KB), no need to clean (keep size is 500 MB)
16-2-17 8:19:04.159 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event UpEndSyncExternalEvent (org.syncany.operations.daemon.messages.UpEndSyncExternalEvent@476c9155) ...
16-2-17 8:19:04.160 | org.syncany.plu | Test worker    | FINE : Validating required fields
16-2-17 8:19:04.161 | TransferManager | Test worker    | INFO : Building LocalTransferManager from config 'syncany-170216081832804-36803-client-A' ...
16-2-17 8:19:04.161 | TransferManager | Test worker    | INFO : - With feature ReadAfterWriteConsistent
16-2-17 8:19:04.161 | TransferManager | Test worker    | INFO : - With feature Retriable
16-2-17 8:19:04.161 | TransferManager | Test worker    | FINE : - Checking if selected features supported ...
16-2-17 8:19:04.161 | TransferManager | Test worker    | FINE : - Checking for duplicate features ...
16-2-17 8:19:04.161 | TransferManager | Test worker    | FINE : - Checking required feature TransactionAware in LocalTransferManager ...
16-2-17 8:19:04.161 | TransferManager | Test worker    | FINE : - Checking required feature Retriable in LocalTransferManager ...
16-2-17 8:19:04.161 | TransferManager | Test worker    | INFO : - SKIPPING unsupported optional feature ReadAfterWriteConsistent
16-2-17 8:19:04.161 | TransferManager | Test worker    | FINE : - Wrapping TransferManager LocalTransferManager in RetriableFeatureTransferManager
16-2-17 8:19:04.161 | org.syncany.plu | Test worker    | FINE : Validating required fields
16-2-17 8:19:04.161 | TransferManager | Test worker    | INFO : Building LocalTransferManager from config 'syncany-170216081832804-36803-client-A' ...
16-2-17 8:19:04.161 | TransferManager | Test worker    | INFO : - With feature ReadAfterWriteConsistent
16-2-17 8:19:04.161 | TransferManager | Test worker    | INFO : - With feature Retriable
16-2-17 8:19:04.161 | TransferManager | Test worker    | INFO : - With feature PathAware
16-2-17 8:19:04.161 | TransferManager | Test worker    | INFO : - With feature TransactionAware
16-2-17 8:19:04.161 | TransferManager | Test worker    | FINE : - Checking if selected features supported ...
16-2-17 8:19:04.161 | TransferManager | Test worker    | FINE : - Checking for duplicate features ...
16-2-17 8:19:04.161 | TransferManager | Test worker    | FINE : - Checking required feature TransactionAware in LocalTransferManager ...
16-2-17 8:19:04.161 | TransferManager | Test worker    | FINE : - Checking required feature Retriable in LocalTransferManager ...
16-2-17 8:19:04.161 | TransferManager | Test worker    | INFO : - SKIPPING unsupported optional feature ReadAfterWriteConsistent
16-2-17 8:19:04.161 | TransferManager | Test worker    | FINE : - Wrapping TransferManager LocalTransferManager in RetriableFeatureTransferManager
16-2-17 8:19:04.161 | TransferManager | Test worker    | INFO : - SKIPPING unsupported optional feature PathAware
16-2-17 8:19:04.161 | TransferManager | Test worker    | FINE : - Wrapping TransferManager RetriableFeatureTransferManager in TransactionAwareFeatureTransferManager
16-2-17 8:19:04.165 | DatabaseConnect | Test worker    | INFO : Found 12 tables.
16-2-17 8:19:04.165 | DownOperation   | Test worker    | INFO : 
16-2-17 8:19:04.165 | DownOperation   | Test worker    | INFO : Running 'Sync down' at client A ...
16-2-17 8:19:04.165 | DownOperation   | Test worker    | INFO : --------------------------------------------
16-2-17 8:19:04.165 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event DownStartSyncExternalEvent (org.syncany.operations.daemon.messages.DownStartSyncExternalEvent@5042dbcf) ...
16-2-17 8:19:04.165 | AbstractTransfe | Test worker    | INFO : Looking for other running remote operations ...
16-2-17 8:19:04.166 | DatabaseConnect | Test worker    | INFO : Found 12 tables.
16-2-17 8:19:04.166 | LsRemoteOperati | Test worker    | INFO : 
16-2-17 8:19:04.166 | LsRemoteOperati | Test worker    | INFO : Running 'Remote Status' at client A ...
16-2-17 8:19:04.166 | LsRemoteOperati | Test worker    | INFO : --------------------------------------------
16-2-17 8:19:04.166 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event LsRemoteStartSyncExternalEvent (org.syncany.operations.daemon.messages.LsRemoteStartSyncExternalEvent@68d64bd6) ...
16-2-17 8:19:04.166 | LsRemoteOperati | Test worker    | INFO : Retrieving remote database list.
16-2-17 8:19:04.166 | LocalTransferMa | Test worker    | INFO : Cannot create instance of DatabaseRemoteFile for file /tmp/syncanytest/syncany-170216081832804-36803-repo/databases/TEMP_db-B-0000000001; maybe invalid file name pattern. Ignoring file.
16-2-17 8:19:04.166 | LsRemoteOperati | Test worker    | INFO : - Remote database database-B-0000000002 is new.
16-2-17 8:19:04.167 | LsRemoteOperati | Test worker    | INFO : - Remote database database-A-0000000001 is already known (in local database). Ignoring.
16-2-17 8:19:04.167 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event LsRemoteEndSyncExternalEvent (org.syncany.operations.daemon.messages.LsRemoteEndSyncExternalEvent@44bb3864) ...
16-2-17 8:19:04.167 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event DownChangesDetectedSyncExternalEvent (org.syncany.operations.daemon.messages.DownChangesDetectedSyncExternalEvent@13919076) ...
16-2-17 8:19:04.167 | ActionFileHandl | Test worker    | INFO : Starting action for RemoteFile[name=action-down-A-1487233144161] ...
16-2-17 8:19:04.167 | ActionFileHandl | Test worker    | INFO : Uploading action file: RemoteFile[name=action-down-A-1487233144161]
16-2-17 8:19:04.167 | ActionFileHandl | Test worker    | INFO : Scheduling action renewal task for every 2 minutes, for RemoteFile[name=action-down-A-1487233144161] ...
16-2-17 8:19:04.167 | DownOperation   | Test worker    | INFO : Downloading unknown databases.
16-2-17 8:19:04.167 | DownOperation   | Test worker    | INFO : - Downloading database-B-0000000002 to local cache at /tmp/syncanytest/syncany-170216081832804-36803-client-A/.syncany/cache/database-B-0000000002
16-2-17 8:19:04.167 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event DownDownloadFileSyncExternalEvent (org.syncany.operations.daemon.messages.DownDownloadFileSyncExternalEvent@5ef76cec) ...
16-2-17 8:19:04.168 | DownOperation   | Test worker    | INFO : Loading database headers, creating branches ...
16-2-17 8:19:04.168 | DatabaseXmlSeri | Test worker    | INFO : - Loading database (HEADER_ONLY) from file /tmp/syncanytest/syncany-170216081832804-36803-client-A/.syncany/cache/database-B-0000000002 ...
16-2-17 8:19:04.171 | DatabaseXmlPars | Test worker    | INFO :    + Added database version B/(A1,B2)/T=1487233144124
16-2-17 8:19:04.172 | LocalTransferMa | Test worker    | INFO : Cannot create instance of CleanupRemoteFile for file /tmp/syncanytest/syncany-170216081832804-36803-repo/databases/database-B-0000000002; maybe invalid file name pattern. Ignoring file.
16-2-17 8:19:04.172 | LocalTransferMa | Test worker    | INFO : Cannot create instance of CleanupRemoteFile for file /tmp/syncanytest/syncany-170216081832804-36803-repo/databases/TEMP_db-B-0000000001; maybe invalid file name pattern. Ignoring file.
16-2-17 8:19:04.172 | LocalTransferMa | Test worker    | INFO : Cannot create instance of CleanupRemoteFile for file /tmp/syncanytest/syncany-170216081832804-36803-repo/databases/database-A-0000000001; maybe invalid file name pattern. Ignoring file.
16-2-17 8:19:04.172 | ApplicationSqlD | Test worker    | INFO : SQL (general_settings): Read cleanupNumber  = (not set)
16-2-17 8:19:04.172 | DownOperation   | Test worker    | INFO : Populated unknown branches: {A=[A/(A1)/T=1487233143669], B=[B/(A1,B2)/T=1487233144124]}
16-2-17 8:19:04.172 | DownOperation   | Test worker    | INFO : Determine winner using database reconciliator ...
16-2-17 8:19:04.172 | DatabaseReconci | Test worker    | INFO : Adding database version to winning branch: A/(A1)/T=1487233143669
16-2-17 8:19:04.172 | DatabaseReconci | Test worker    | INFO : Adding database version to winning branch: B/(A1,B2)/T=1487233144124
16-2-17 8:19:04.172 | DatabaseReconci | Test worker    | INFO : - Winner is B with branch: 
16-2-17 8:19:04.172 | DatabaseReconci | Test worker    | INFO :   + A/(A1)/T=1487233143669
16-2-17 8:19:04.172 | DatabaseReconci | Test worker    | INFO :   + B/(A1,B2)/T=1487233144124
16-2-17 8:19:04.172 | DownOperation   | Test worker    | INFO : - Database versions to REMOVE locally: []
16-2-17 8:19:04.172 | DownOperation   | Test worker    | INFO :   + Nothing to purge locally. No conflicts. Only updates. Nice!
16-2-17 8:19:04.172 | DatabaseReconci | Test worker    | INFO : Finding winnersApplyBranch.
16-2-17 8:19:04.172 | DatabaseReconci | Test worker    | INFO : Losers Branch: [A/(A1)/T=1487233143669]
16-2-17 8:19:04.172 | DatabaseReconci | Test worker    | INFO : Winners Branch: [A/(A1)/T=1487233143669, B/(A1,B2)/T=1487233144124]
16-2-17 8:19:04.172 | DownOperation   | Test worker    | INFO : - Cleanup occurred: false
16-2-17 8:19:04.172 | DownOperation   | Test worker    | INFO : - Database versions to APPLY locally: [B/(A1,B2)/T=1487233144124]
16-2-17 8:19:04.172 | DownOperation   | Test worker    | INFO : Loading winners database (DEFAULT) ...
16-2-17 8:19:04.173 | DatabaseXmlSeri | Test worker    | INFO : - Loading database (FULL) from file /tmp/syncanytest/syncany-170216081832804-36803-client-A/.syncany/cache/database-B-0000000002 ...
16-2-17 8:19:04.174 | DatabaseXmlPars | Test worker    | INFO :    + Added database version B/(A1,B2)/T=1487233144124
16-2-17 8:19:04.174 | DownOperation   | Test worker    | INFO : Determine file system actions ...
16-2-17 8:19:04.175 | DatabaseConnect | Test worker    | INFO : Found 12 tables.
16-2-17 8:19:04.176 | FileSystemActio | Test worker    | INFO : - Loading current file tree...
16-2-17 8:19:04.176 | FileSystemActio | Test worker    | INFO : - Determine filesystem actions ...
16-2-17 8:19:04.176 | FileSystemActio | Test worker    | INFO :   + Comparing local version: FileVersion [version=1, path=sphinxbase-0.8, type=FOLDER, status=NEW, size=4096, lastModified=Thu Feb 16 08:19:03 UTC 2017, linkTarget=null, checksum=null, updated=Thu Feb 16 08:19:03 UTC 2017, posixPermissions=rwxrwxr-x, dosAttributes=--a-]
16-2-17 8:19:04.176 | FileSystemActio | Test worker    | INFO :     with winning version   : FileVersion [version=2, path=sphinxbase-0.8, type=FOLDER, status=CHANGED, size=4096, lastModified=Thu Feb 16 08:19:03 UTC 2017, linkTarget=null, checksum=null, updated=Thu Feb 16 08:19:04 UTC 2017, posixPermissions=rwxrwxrwx, dosAttributes=--a-]
16-2-17 8:19:04.176 | FileVersionComp | Test worker    | INFO :      - [CHANGED_ATTRIBUTES]: Local file DIFFERS from file version, expected POSIX ATTRS = rwxrwxrwx, but actual POSIX ATTRS = rwxrwxr-x, for file sphinxbase-0.8
16-2-17 8:19:04.176 | FileSystemActio | Test worker    | INFO :      -> (12) Rename / Changed file attributes: Local file has different file attributes: local file = /tmp/syncanytest/syncany-170216081832804-36803-client-A/sphinxbase-0.8, local version = FileVersion [version=1, path=sphinxbase-0.8, type=FOLDER, status=NEW, size=4096, lastModified=Thu Feb 16 08:19:03 UTC 2017, linkTarget=null, checksum=null, updated=Thu Feb 16 08:19:03 UTC 2017, posixPermissions=rwxrwxr-x, dosAttributes=--a-], winning version = FileVersion [version=2, path=sphinxbase-0.8, type=FOLDER, status=CHANGED, size=4096, lastModified=Thu Feb 16 08:19:03 UTC 2017, linkTarget=null, checksum=null, updated=Thu Feb 16 08:19:04 UTC 2017, posixPermissions=rwxrwxrwx, dosAttributes=--a-]
16-2-17 8:19:04.176 | FileSystemActio | Test worker    | INFO :      -> RenameFileSystemAction [file1=FileVersion [version=1, path=sphinxbase-0.8, type=FOLDER, status=NEW, size=4096, lastModified=Thu Feb 16 08:19:03 UTC 2017, linkTarget=null, checksum=null, updated=Thu Feb 16 08:19:03 UTC 2017, posixPermissions=rwxrwxr-x, dosAttributes=--a-], file2=FileVersion [version=2, path=sphinxbase-0.8, type=FOLDER, status=CHANGED, size=4096, lastModified=Thu Feb 16 08:19:03 UTC 2017, linkTarget=null, checksum=null, updated=Thu Feb 16 08:19:04 UTC 2017, posixPermissions=rwxrwxrwx, dosAttributes=--a-]]
16-2-17 8:19:04.176 | Downloader      | Test worker    | INFO : Downloading and extracting multichunks ...
16-2-17 8:19:04.176 | FileSystemActio | Test worker    | INFO :    Sorted actions:
16-2-17 8:19:04.176 | FileSystemActio | Test worker    | INFO :    + RenameFileSystemAction [file1=FileVersion [version=1, path=sphinxbase-0.8, type=FOLDER, status=NEW, size=4096, lastModified=Thu Feb 16 08:19:03 UTC 2017, linkTarget=null, checksum=null, updated=Thu Feb 16 08:19:03 UTC 2017, posixPermissions=rwxrwxr-x, dosAttributes=--a-], file2=FileVersion [version=2, path=sphinxbase-0.8, type=FOLDER, status=CHANGED, size=4096, lastModified=Thu Feb 16 08:19:03 UTC 2017, linkTarget=null, checksum=null, updated=Thu Feb 16 08:19:04 UTC 2017, posixPermissions=rwxrwxrwx, dosAttributes=--a-]]
16-2-17 8:19:04.176 | DownOperation   | Test worker    | FINE : - Applying file system actions (sorted!) ...
16-2-17 8:19:04.176 | DownOperation   | Test worker    | FINE :    +  RenameFileSystemAction [file1=FileVersion [version=1, path=sphinxbase-0.8, type=FOLDER, status=NEW, size=4096, lastModified=Thu Feb 16 08:19:03 UTC 2017, linkTarget=null, checksum=null, updated=Thu Feb 16 08:19:03 UTC 2017, posixPermissions=rwxrwxr-x, dosAttributes=--a-], file2=FileVersion [version=2, path=sphinxbase-0.8, type=FOLDER, status=CHANGED, size=4096, lastModified=Thu Feb 16 08:19:03 UTC 2017, linkTarget=null, checksum=null, updated=Thu Feb 16 08:19:04 UTC 2017, posixPermissions=rwxrwxrwx, dosAttributes=--a-]]
16-2-17 8:19:04.176 | FileSystemActio | Test worker    | INFO :      - Setting POSIX permissions: rwxrwxrwx ...
16-2-17 8:19:04.176 | DownOperation   | Test worker    | INFO : - Adding database versions to SQL database ...
16-2-17 8:19:04.177 | DownOperation   | Test worker    | INFO :   + Applying database version (A1,B2)
16-2-17 8:19:04.177 | DownOperation   | Test worker    | FINE :   + Contents: DatabaseVersion [header=B/(A1,B2)/T=1487233144124, chunks=3, multiChunks=1, fileContents=3, fileHistories=1]
16-2-17 8:19:04.193 | ActionFileHandl | Test worker    | INFO : Finishing action for RemoteFile[name=action-down-A-1487233144161] ...
16-2-17 8:19:04.198 | ActionFileHandl | Test worker    | INFO : Deleting action file: RemoteFile[name=action-down-A-1487233144161]
16-2-17 8:19:04.199 | AbstractTransfe | Test worker    | INFO : Cleaning own old action files ...
16-2-17 8:19:04.199 | Cache           | Test worker    | INFO : Cache size okay (160 KB), no need to clean (keep size is 500 MB)
16-2-17 8:19:04.199 | InternalEventBu | Test worker    | INFO : Event bus 'LocalEventBus': Posting event DownEndSyncExternalEvent (org.syncany.operations.daemon.messages.DownEndSyncExternalEvent@108503ee) ...
16-2-17 8:19:04.199 | DownOperation   | Test worker    | INFO : Sync down done.