Console Output

l.LocalRepoManagerImpl - [1da6675d]_close: Shutting down real LocalRepoManager.
10:26:36.106 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [57eed99b]initPersistenceManagerFactory: repositoryId=8cb427fd-fa48-43f9-8340-18a79b1d186a
10:26:36.125 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [57eed99b]initPersistenceManagerFactory: Started up PersistenceManagerFactory successfully in 24068 ms.
10:26:36.155 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [c3f7d98]<init>: localRoot='/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/k1eq1t1u-edds0-remote'
10:26:36.159 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [c3f7d98]initLockFile: Repository '/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/k1eq1t1u-edds0-remote' locked successfully.
10:27:07.328 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [c3f7d98]initPersistenceManagerFactory: repositoryId=69056ed8-d2c0-4a18-ab78-67f18f520b74
10:27:07.509 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [c3f7d98]initPersistenceManagerFactory: Started up PersistenceManagerFactory successfully in 14120 ms.
10:27:07.864 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 1.00%
10:27:07.871 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 50.00%
10:27:07.881 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 58.00%
10:27:07.971 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 63.61%
10:27:07.981 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 74.34%
10:27:08.026 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 79.39%
10:27:08.050 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 90.67%
10:27:08.091 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 95.73%
10:27:08.135 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 100.00%
10:27:08.159 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - local repo: 8cb427fd-fa48-43f9-8340-18a79b1d186a
10:27:08.159 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - remote repo: 69056ed8-d2c0-4a18-ab78-67f18f520b74
10:27:08.163 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 0.50%
10:27:08.164 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncDown: fromID=69056ed8-d2c0-4a18-ab78-67f18f520b74 from='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/k1eq1t1u-edds0-remote/2' toID=8cb427fd-fa48-43f9-8340-18a79b1d186a to='target/repo/k1eq1t1u-32qkr-local', fromRepoLocalSync=true
10:27:08.164 [pool-31-thread-1] INFO  c.c.c.c.r.s.RepoToRepoSync - sync: locally syncing 8cb427fd-fa48-43f9-8340-18a79b1d186a ('target/repo/k1eq1t1u-32qkr-local')
10:27:08.198 [pool-31-thread-1] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 12.94%
10:27:08.211 [pool-31-thread-1] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 25.12%
10:27:08.212 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 1.00%
10:27:08.213 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 50.00%
10:27:08.214 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 58.17%
10:27:08.218 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 63.61%
10:27:08.222 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 74.50%
10:27:08.228 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 80.62%
10:27:08.231 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 90.83%
10:27:08.235 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 95.94%
10:27:08.242 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 100.00%
10:27:08.353 [main] INFO  c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=69056ed8-d2c0-4a18-ab78-67f18f520b74 remoteRepositoryId=8cb427fd-fa48-43f9-8340-18a79b1d186a localRepositoryRevisionSynced=-1 localRepositoryRevisionInProgress=2
10:27:08.400 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path=''
10:27:08.649 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path='/1'
10:27:08.688 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 31.31%
10:27:08.699 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 36.58%
10:27:08.699 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 41.85%
10:27:08.699 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/1/a'
10:27:08.711 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='4ecfa4858885726697fb31c1a18af40ffab657da' to.sha1='<NoInstanceOf_NormalFileDto>'): /1/a
10:27:08.858 [main] INFO  c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=1, fromRepositoryId="FileInProgressMarker", toRepositoryId="1", path="69056ed8-d2c0-4a18-ab78-67f18f520b74"] on repo=8cb427fd-fa48-43f9-8340-18a79b1d186a
10:27:08.926 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/1/a'
10:27:08.926 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='4ecfa4858885726697fb31c1a18af40ffab657da' toSha1='null'
10:27:08.926 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/1/a'
10:27:08.926 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/1/a' offset=0
10:27:08.930 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/1/a' offset=0
10:27:08.939 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 126731 bytes in 13 ms. path='/1/a'
10:27:08.943 [main] INFO  c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_86f7e437faa5a7fce15d1ddcb9eaeaea377667b8_0 into destFile a
10:27:09.435 [main] INFO  c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=1, fromRepositoryId="FileInProgressMarker", toRepositoryId="1", path="69056ed8-d2c0-4a18-ab78-67f18f520b74"] on repo=8cb427fd-fa48-43f9-8340-18a79b1d186a
10:27:09.477 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/1/b'
10:27:09.487 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='d3ea23d7cb74fe3cd4f78fdd0a9aba7ce4e96839' to.sha1='<NoInstanceOf_NormalFileDto>'): /1/b
10:27:09.524 [main] INFO  c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=2, fromRepositoryId="FileInProgressMarker", toRepositoryId="2", path="69056ed8-d2c0-4a18-ab78-67f18f520b74"] on repo=8cb427fd-fa48-43f9-8340-18a79b1d186a
10:27:09.532 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/1/b'
10:27:09.532 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='d3ea23d7cb74fe3cd4f78fdd0a9aba7ce4e96839' toSha1='null'
10:27:09.532 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/1/b'
10:27:09.532 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/1/b' offset=0
10:27:09.546 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/1/b' offset=0
10:27:09.558 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 628064 bytes in 26 ms. path='/1/b'
10:27:09.562 [main] INFO  c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_e9d71f5ee7c92d6dc9e92ffdad17b8bd49418f98_0 into destFile b
10:27:09.902 [main] INFO  c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=2, fromRepositoryId="FileInProgressMarker", toRepositoryId="2", path="69056ed8-d2c0-4a18-ab78-67f18f520b74"] on repo=8cb427fd-fa48-43f9-8340-18a79b1d186a
10:27:09.927 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/a'
10:27:09.937 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='894178edb9341b6ca2ef91b98f6e857ffddaa900' to.sha1='<NoInstanceOf_NormalFileDto>'): /a
10:27:09.963 [main] INFO  c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=3, fromRepositoryId="FileInProgressMarker", toRepositoryId="3", path="69056ed8-d2c0-4a18-ab78-67f18f520b74"] on repo=8cb427fd-fa48-43f9-8340-18a79b1d186a
10:27:09.983 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/a'
10:27:09.983 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='894178edb9341b6ca2ef91b98f6e857ffddaa900' toSha1='null'
10:27:09.983 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/a'
10:27:09.983 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/a' offset=0
10:27:09.998 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/a' offset=0
10:27:10.013 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 800052 bytes in 30 ms. path='/a'
10:27:10.016 [main] INFO  c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_86f7e437faa5a7fce15d1ddcb9eaeaea377667b8_0 into destFile a
10:27:10.642 [main] INFO  c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=3, fromRepositoryId="FileInProgressMarker", toRepositoryId="3", path="69056ed8-d2c0-4a18-ab78-67f18f520b74"] on repo=8cb427fd-fa48-43f9-8340-18a79b1d186a
10:27:10.682 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 48.43%
10:27:10.698 [main] INFO  c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=69056ed8-d2c0-4a18-ab78-67f18f520b74 remoteRepositoryId=8cb427fd-fa48-43f9-8340-18a79b1d186a localRepositoryRevisionSynced=2
10:27:10.725 [main] INFO  c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=8cb427fd-fa48-43f9-8340-18a79b1d186a remoteRepositoryId=69056ed8-d2c0-4a18-ab78-67f18f520b74 transaction.localRevision=19 remoteFromLocalRevision=2
10:27:10.847 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncUp: fromID=8cb427fd-fa48-43f9-8340-18a79b1d186a from='target/repo/k1eq1t1u-32qkr-local' toID=69056ed8-d2c0-4a18-ab78-67f18f520b74 to='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/k1eq1t1u-edds0-remote/2'
10:27:10.903 [main] INFO  c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=8cb427fd-fa48-43f9-8340-18a79b1d186a remoteRepositoryId=69056ed8-d2c0-4a18-ab78-67f18f520b74 localRepositoryRevisionSynced=-1 localRepositoryRevisionInProgress=19
10:27:10.918 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 63.43%
10:27:10.918 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 74.63%
10:27:10.933 [main] INFO  c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=8cb427fd-fa48-43f9-8340-18a79b1d186a remoteRepositoryId=69056ed8-d2c0-4a18-ab78-67f18f520b74 localRepositoryRevisionSynced=19
10:27:10.951 [main] INFO  c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=69056ed8-d2c0-4a18-ab78-67f18f520b74 remoteRepositoryId=8cb427fd-fa48-43f9-8340-18a79b1d186a transaction.localRevision=5 remoteFromLocalRevision=19
10:27:10.992 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncDown: fromID=69056ed8-d2c0-4a18-ab78-67f18f520b74 from='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/k1eq1t1u-edds0-remote/2' toID=8cb427fd-fa48-43f9-8340-18a79b1d186a to='target/repo/k1eq1t1u-32qkr-local', fromRepoLocalSync=false
10:27:11.026 [main] INFO  c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=69056ed8-d2c0-4a18-ab78-67f18f520b74 remoteRepositoryId=8cb427fd-fa48-43f9-8340-18a79b1d186a localRepositoryRevisionSynced=2 localRepositoryRevisionInProgress=5
10:27:11.032 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 88.31%
10:27:11.032 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 99.50%
10:27:11.037 [main] INFO  c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=69056ed8-d2c0-4a18-ab78-67f18f520b74 remoteRepositoryId=8cb427fd-fa48-43f9-8340-18a79b1d186a localRepositoryRevisionSynced=5
10:27:11.056 [main] INFO  c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=8cb427fd-fa48-43f9-8340-18a79b1d186a remoteRepositoryId=69056ed8-d2c0-4a18-ab78-67f18f520b74 transaction.localRevision=22 remoteFromLocalRevision=5
10:27:11.070 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 100.00%
10:27:11.086 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [57eed99b]getCloseDeferredMillis: closeDeferredMillis=20000
10:27:11.086 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [57eed99b]close: Deferring shut down of real LocalRepoManager 20000 ms.
10:27:11.087 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [c3f7d98]getCloseDeferredMillis: closeDeferredMillis=20000
10:27:11.087 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [c3f7d98]close: Deferring shut down of real LocalRepoManager 20000 ms.
10:27:11.121 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 1.00%
10:27:11.122 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 50.00%
10:27:11.123 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 58.17%
10:27:11.126 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 63.61%
10:27:11.130 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 74.50%
10:27:11.166 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 80.62%
10:27:11.169 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 90.83%
10:27:11.178 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 96.55%
10:27:11.248 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 100.00%
10:27:11.281 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 0.50%
10:27:11.282 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncDown: fromID=69056ed8-d2c0-4a18-ab78-67f18f520b74 from='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/k1eq1t1u-edds0-remote/2' toID=8cb427fd-fa48-43f9-8340-18a79b1d186a to='target/repo/k1eq1t1u-32qkr-local', fromRepoLocalSync=true
10:27:11.283 [pool-32-thread-1] INFO  c.c.c.c.r.s.RepoToRepoSync - sync: locally syncing 8cb427fd-fa48-43f9-8340-18a79b1d186a ('target/repo/k1eq1t1u-32qkr-local')
10:27:11.290 [pool-32-thread-1] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 12.94%
10:27:11.303 [pool-32-thread-1] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 18.27%
10:27:11.314 [pool-32-thread-1] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 25.12%
10:27:11.321 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 1.00%
10:27:11.324 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 50.00%
10:27:11.328 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 58.17%
10:27:11.334 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 63.61%
10:27:11.338 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 74.50%
10:27:11.351 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 80.62%
10:27:11.355 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 90.83%
10:27:11.360 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 96.55%
10:27:11.369 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 100.00%
10:27:11.395 [main] INFO  c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=69056ed8-d2c0-4a18-ab78-67f18f520b74 remoteRepositoryId=8cb427fd-fa48-43f9-8340-18a79b1d186a localRepositoryRevisionSynced=5 localRepositoryRevisionInProgress=8
10:27:11.416 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path='/1'
10:27:11.498 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 31.31%
10:27:11.502 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path='/1/5'
10:27:11.541 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 36.58%
10:27:11.541 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 41.85%
10:27:11.541 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/1/5/aaa'
10:27:11.551 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='ee28b8c39102cb4b7ae83e8cde36702684952bec' to.sha1='<NoInstanceOf_NormalFileDto>'): /1/5/aaa
10:27:11.595 [main] INFO  c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=4, fromRepositoryId="FileInProgressMarker", toRepositoryId="4", path="69056ed8-d2c0-4a18-ab78-67f18f520b74"] on repo=8cb427fd-fa48-43f9-8340-18a79b1d186a
10:27:11.642 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/1/5/aaa'
10:27:11.642 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='ee28b8c39102cb4b7ae83e8cde36702684952bec' toSha1='null'
10:27:11.642 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/1/5/aaa'
10:27:11.642 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/1/5/aaa' offset=0
10:27:11.652 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/1/5/aaa' offset=0
10:27:11.664 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 359520 bytes in 22 ms. path='/1/5/aaa'
10:27:11.668 [main] INFO  c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_7e240de74fb1ed08fa08d38063f6a6a91462a815_0 into destFile aaa
10:27:11.866 [main] INFO  c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=4, fromRepositoryId="FileInProgressMarker", toRepositoryId="4", path="69056ed8-d2c0-4a18-ab78-67f18f520b74"] on repo=8cb427fd-fa48-43f9-8340-18a79b1d186a
10:27:12.015 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/1/5/bbb'
10:27:12.030 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='283f50707f2c52b4970aeca63de1c66b7344d490' to.sha1='<NoInstanceOf_NormalFileDto>'): /1/5/bbb
10:27:12.059 [main] INFO  c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=5, fromRepositoryId="FileInProgressMarker", toRepositoryId="5", path="69056ed8-d2c0-4a18-ab78-67f18f520b74"] on repo=8cb427fd-fa48-43f9-8340-18a79b1d186a
10:27:12.079 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/1/5/bbb'
10:27:12.080 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='283f50707f2c52b4970aeca63de1c66b7344d490' toSha1='null'
10:27:12.080 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/1/5/bbb'
10:27:12.080 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/1/5/bbb' offset=0
10:27:12.089 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/1/5/bbb' offset=0
10:27:12.097 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 376513 bytes in 17 ms. path='/1/5/bbb'
10:27:12.101 [main] INFO  c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_5cb138284d431abd6a053a56625ec088bfb88912_0 into destFile bbb
10:27:12.140 [main] INFO  c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=5, fromRepositoryId="FileInProgressMarker", toRepositoryId="5", path="69056ed8-d2c0-4a18-ab78-67f18f520b74"] on repo=8cb427fd-fa48-43f9-8340-18a79b1d186a
10:27:12.259 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 48.43%
10:27:12.262 [main] INFO  c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=69056ed8-d2c0-4a18-ab78-67f18f520b74 remoteRepositoryId=8cb427fd-fa48-43f9-8340-18a79b1d186a localRepositoryRevisionSynced=8
10:27:12.303 [main] INFO  c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=8cb427fd-fa48-43f9-8340-18a79b1d186a remoteRepositoryId=69056ed8-d2c0-4a18-ab78-67f18f520b74 transaction.localRevision=35 remoteFromLocalRevision=8
10:27:12.321 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncUp: fromID=8cb427fd-fa48-43f9-8340-18a79b1d186a from='target/repo/k1eq1t1u-32qkr-local' toID=69056ed8-d2c0-4a18-ab78-67f18f520b74 to='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/k1eq1t1u-edds0-remote/2'
10:27:12.437 [main] INFO  c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=8cb427fd-fa48-43f9-8340-18a79b1d186a remoteRepositoryId=69056ed8-d2c0-4a18-ab78-67f18f520b74 localRepositoryRevisionSynced=19 localRepositoryRevisionInProgress=35
10:27:12.444 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 63.43%
10:27:12.444 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 74.63%
10:27:12.451 [main] INFO  c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=8cb427fd-fa48-43f9-8340-18a79b1d186a remoteRepositoryId=69056ed8-d2c0-4a18-ab78-67f18f520b74 localRepositoryRevisionSynced=35
10:27:12.481 [main] INFO  c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=69056ed8-d2c0-4a18-ab78-67f18f520b74 remoteRepositoryId=8cb427fd-fa48-43f9-8340-18a79b1d186a transaction.localRevision=11 remoteFromLocalRevision=35
10:27:12.499 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncDown: fromID=69056ed8-d2c0-4a18-ab78-67f18f520b74 from='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/k1eq1t1u-edds0-remote/2' toID=8cb427fd-fa48-43f9-8340-18a79b1d186a to='target/repo/k1eq1t1u-32qkr-local', fromRepoLocalSync=false
10:27:12.588 [main] INFO  c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=69056ed8-d2c0-4a18-ab78-67f18f520b74 remoteRepositoryId=8cb427fd-fa48-43f9-8340-18a79b1d186a localRepositoryRevisionSynced=8 localRepositoryRevisionInProgress=11
10:27:12.594 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 88.31%
10:27:12.594 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 99.50%
10:27:12.597 [main] INFO  c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=69056ed8-d2c0-4a18-ab78-67f18f520b74 remoteRepositoryId=8cb427fd-fa48-43f9-8340-18a79b1d186a localRepositoryRevisionSynced=11
10:27:12.679 [main] INFO  c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=8cb427fd-fa48-43f9-8340-18a79b1d186a remoteRepositoryId=69056ed8-d2c0-4a18-ab78-67f18f520b74 transaction.localRevision=38 remoteFromLocalRevision=11
10:27:12.702 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 100.00%
10:27:12.702 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [57eed99b]close: Deferring shut down of real LocalRepoManager 20000 ms.
10:27:12.722 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [c3f7d98]close: Deferring shut down of real LocalRepoManager 20000 ms.
10:27:12.766 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [5558adde]<init>: localRoot='/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/k1eq3oqj-tqhfz-local'
10:27:12.777 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [5558adde]initLockFile: Repository '/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/k1eq3oqj-tqhfz-local' locked successfully.
10:27:32.703 [closeDeferredTimer-57eed99b-2] INFO  c.c.c.l.LocalRepoManagerImpl - [57eed99b]_close: Shutting down real LocalRepoManager.
10:27:32.959 [closeDeferredTimer-c3f7d98-2] INFO  c.c.c.l.LocalRepoManagerImpl - [c3f7d98]_close: Shutting down real LocalRepoManager.
10:28:19.470 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [5558adde]initPersistenceManagerFactory: repositoryId=77577f01-bb2a-4a36-87ae-a805857697f0
10:28:20.027 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [5558adde]initPersistenceManagerFactory: Started up PersistenceManagerFactory successfully in 29555 ms.
10:28:20.052 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [5ed4dc24]<init>: localRoot='/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/k1eq3oqk-xeupm-remote'
10:28:20.056 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [5ed4dc24]initLockFile: Repository '/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/k1eq3oqk-xeupm-remote' locked successfully.
10:29:22.125 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [5ed4dc24]initPersistenceManagerFactory: repositoryId=830ea58d-190d-4f95-a456-a807dd8b8e40
10:29:22.162 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [5ed4dc24]initPersistenceManagerFactory: Started up PersistenceManagerFactory successfully in 26008 ms.
10:29:22.347 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 1.00%
10:29:22.352 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 50.00%
10:29:22.361 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 58.00%
10:29:22.763 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 63.61%
10:29:22.784 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 74.34%
10:29:22.808 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 80.56%
10:29:22.817 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 90.67%
10:29:22.848 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 95.74%
10:29:22.948 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 100.00%
10:29:22.972 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - local repo: 77577f01-bb2a-4a36-87ae-a805857697f0
10:29:22.972 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - remote repo: 830ea58d-190d-4f95-a456-a807dd8b8e40
10:29:22.976 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 0.50%
10:29:22.976 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncDown: fromID=830ea58d-190d-4f95-a456-a807dd8b8e40 from='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/k1eq3oqk-xeupm-remote' toID=77577f01-bb2a-4a36-87ae-a805857697f0 to='target/repo/k1eq3oqj-tqhfz-local', fromRepoLocalSync=true
10:29:22.977 [pool-33-thread-1] INFO  c.c.c.c.r.s.RepoToRepoSync - sync: locally syncing 77577f01-bb2a-4a36-87ae-a805857697f0 ('target/repo/k1eq3oqj-tqhfz-local')
10:29:23.016 [pool-33-thread-1] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 12.94%
10:29:23.023 [pool-33-thread-1] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 25.12%
10:29:23.025 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 1.00%
10:29:23.026 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 50.00%
10:29:23.028 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 58.17%
10:29:23.035 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 63.61%
10:29:23.039 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 74.50%
10:29:23.045 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 80.62%
10:29:23.048 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 90.83%
10:29:23.052 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 95.94%
10:29:23.058 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 100.00%
10:29:23.413 [main] INFO  c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=830ea58d-190d-4f95-a456-a807dd8b8e40 remoteRepositoryId=77577f01-bb2a-4a36-87ae-a805857697f0 localRepositoryRevisionSynced=-1 localRepositoryRevisionInProgress=2
10:29:23.439 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path=''
10:29:23.470 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path='/1'
10:29:23.485 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 30.22%
10:29:23.485 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path='/2'
10:29:23.495 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path='/2/1'
10:29:23.609 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path='/3'
10:29:23.745 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 35.46%
10:29:23.745 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 40.70%
10:29:23.745 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/1/a'
10:29:23.752 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='971bf1deadbbba143a48c8f80e3271a77dfac2f4' to.sha1='<NoInstanceOf_NormalFileDto>'): /1/a
10:29:23.866 [main] INFO  c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=1, fromRepositoryId="FileInProgressMarker", toRepositoryId="1", path="830ea58d-190d-4f95-a456-a807dd8b8e40"] on repo=77577f01-bb2a-4a36-87ae-a805857697f0
10:29:23.892 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/1/a'
10:29:23.892 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='971bf1deadbbba143a48c8f80e3271a77dfac2f4' toSha1='null'
10:29:23.892 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/1/a'
10:29:23.892 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/1/a' offset=0
10:29:23.902 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/1/a' offset=0
10:29:24.043 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 427498 bytes in 151 ms. path='/1/a'
10:29:24.047 [main] INFO  c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_86f7e437faa5a7fce15d1ddcb9eaeaea377667b8_0 into destFile a
10:29:24.397 [main] INFO  c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=1, fromRepositoryId="FileInProgressMarker", toRepositoryId="1", path="830ea58d-190d-4f95-a456-a807dd8b8e40"] on repo=77577f01-bb2a-4a36-87ae-a805857697f0
10:29:24.558 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/1/b'
10:29:24.567 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='f7f43a1a42d92eb7207b22bea4de712220037726' to.sha1='<NoInstanceOf_NormalFileDto>'): /1/b
10:29:25.076 [main] INFO  c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=2, fromRepositoryId="FileInProgressMarker", toRepositoryId="2", path="830ea58d-190d-4f95-a456-a807dd8b8e40"] on repo=77577f01-bb2a-4a36-87ae-a805857697f0
10:29:25.113 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/1/b'
10:29:25.113 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='f7f43a1a42d92eb7207b22bea4de712220037726' toSha1='null'
10:29:25.113 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/1/b'
10:29:25.113 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/1/b' offset=0
10:29:25.115 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/1/b' offset=0
10:29:25.124 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 77976 bytes in 11 ms. path='/1/b'
10:29:25.127 [main] INFO  c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_e9d71f5ee7c92d6dc9e92ffdad17b8bd49418f98_0 into destFile b
10:29:25.167 [main] INFO  c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=2, fromRepositoryId="FileInProgressMarker", toRepositoryId="2", path="830ea58d-190d-4f95-a456-a807dd8b8e40"] on repo=77577f01-bb2a-4a36-87ae-a805857697f0
10:29:25.384 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/1/c'
10:29:25.399 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='33571e36d72e740731f2014dae355c30c5a4db74' to.sha1='<NoInstanceOf_NormalFileDto>'): /1/c
10:29:25.435 [main] INFO  c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=3, fromRepositoryId="FileInProgressMarker", toRepositoryId="3", path="830ea58d-190d-4f95-a456-a807dd8b8e40"] on repo=77577f01-bb2a-4a36-87ae-a805857697f0
10:29:25.562 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/1/c'
10:29:25.562 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='33571e36d72e740731f2014dae355c30c5a4db74' toSha1='null'
10:29:25.562 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/1/c'
10:29:25.562 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/1/c' offset=0
10:29:25.563 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/1/c' offset=0
10:29:25.571 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 30348 bytes in 9 ms. path='/1/c'
10:29:25.574 [main] INFO  c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_84a516841ba77a5b4648de2cd0dfcb30ea46dbb4_0 into destFile c
10:29:25.604 [main] INFO  c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=3, fromRepositoryId="FileInProgressMarker", toRepositoryId="3", path="830ea58d-190d-4f95-a456-a807dd8b8e40"] on repo=77577f01-bb2a-4a36-87ae-a805857697f0
10:29:25.619 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/2/1/a'
10:29:25.631 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='cfa1dfaed60afa0a45b4cb29c63e0a79d70e2fa5' to.sha1='<NoInstanceOf_NormalFileDto>'): /2/1/a
10:29:25.682 [main] INFO  c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=4, fromRepositoryId="FileInProgressMarker", toRepositoryId="4", path="830ea58d-190d-4f95-a456-a807dd8b8e40"] on repo=77577f01-bb2a-4a36-87ae-a805857697f0
10:29:25.703 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/2/1/a'
10:29:25.703 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='cfa1dfaed60afa0a45b4cb29c63e0a79d70e2fa5' toSha1='null'
10:29:25.704 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/2/1/a'
10:29:25.704 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/2/1/a' offset=0
10:29:25.712 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/2/1/a' offset=0
10:29:25.722 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 393078 bytes in 18 ms. path='/2/1/a'
10:29:25.726 [main] INFO  c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_86f7e437faa5a7fce15d1ddcb9eaeaea377667b8_0 into destFile a
10:29:26.095 [main] INFO  c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=4, fromRepositoryId="FileInProgressMarker", toRepositoryId="4", path="830ea58d-190d-4f95-a456-a807dd8b8e40"] on repo=77577f01-bb2a-4a36-87ae-a805857697f0
10:29:26.238 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/2/1/b'
10:29:26.249 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='df9435591d1b4c5652c857066e2082d0f97cb77f' to.sha1='<NoInstanceOf_NormalFileDto>'): /2/1/b
10:29:26.675 [main] INFO  c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=5, fromRepositoryId="FileInProgressMarker", toRepositoryId="5", path="830ea58d-190d-4f95-a456-a807dd8b8e40"] on repo=77577f01-bb2a-4a36-87ae-a805857697f0
10:29:27.365 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/2/1/b'
10:29:27.365 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='df9435591d1b4c5652c857066e2082d0f97cb77f' toSha1='null'
10:29:27.365 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/2/1/b'
10:29:27.365 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/2/1/b' offset=0
10:29:27.372 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/2/1/b' offset=0
10:29:27.386 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 151416 bytes in 21 ms. path='/2/1/b'
10:29:27.391 [main] INFO  c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_e9d71f5ee7c92d6dc9e92ffdad17b8bd49418f98_0 into destFile b
10:29:27.422 [main] INFO  c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=5, fromRepositoryId="FileInProgressMarker", toRepositoryId="5", path="830ea58d-190d-4f95-a456-a807dd8b8e40"] on repo=77577f01-bb2a-4a36-87ae-a805857697f0
10:29:27.431 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/2/a'
10:29:27.441 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='e8d5aa71b38275b89ab20b9b3a5bc9146f92e80a' to.sha1='<NoInstanceOf_NormalFileDto>'): /2/a
10:29:27.491 [main] INFO  c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=6, fromRepositoryId="FileInProgressMarker", toRepositoryId="6", path="830ea58d-190d-4f95-a456-a807dd8b8e40"] on repo=77577f01-bb2a-4a36-87ae-a805857697f0
10:29:27.511 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/2/a'
10:29:27.511 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='e8d5aa71b38275b89ab20b9b3a5bc9146f92e80a' toSha1='null'
10:29:27.511 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/2/a'
10:29:27.512 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/2/a' offset=0
10:29:27.513 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/2/a' offset=0
10:29:27.525 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 28080 bytes in 13 ms. path='/2/a'
10:29:27.529 [main] INFO  c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_86f7e437faa5a7fce15d1ddcb9eaeaea377667b8_0 into destFile a
10:29:27.561 [main] INFO  c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=6, fromRepositoryId="FileInProgressMarker", toRepositoryId="6", path="830ea58d-190d-4f95-a456-a807dd8b8e40"] on repo=77577f01-bb2a-4a36-87ae-a805857697f0
10:29:27.762 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/3/a'
10:29:27.776 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='691b6ab8846aea8c284cb5e02156fbdaf4ef8dde' to.sha1='<NoInstanceOf_NormalFileDto>'): /3/a
10:29:27.812 [main] INFO  c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=7, fromRepositoryId="FileInProgressMarker", toRepositoryId="7", path="830ea58d-190d-4f95-a456-a807dd8b8e40"] on repo=77577f01-bb2a-4a36-87ae-a805857697f0
10:29:27.815 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/3/a'
10:29:27.816 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='691b6ab8846aea8c284cb5e02156fbdaf4ef8dde' toSha1='null'
10:29:27.816 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/3/a'
10:29:27.816 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/3/a' offset=0
10:29:27.817 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/3/a' offset=0
10:29:27.822 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 141082 bytes in 6 ms. path='/3/a'
10:29:27.826 [main] INFO  c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_86f7e437faa5a7fce15d1ddcb9eaeaea377667b8_0 into destFile a
10:29:28.141 [main] INFO  c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=7, fromRepositoryId="FileInProgressMarker", toRepositoryId="7", path="830ea58d-190d-4f95-a456-a807dd8b8e40"] on repo=77577f01-bb2a-4a36-87ae-a805857697f0
10:29:28.181 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/3/b'
10:29:28.188 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='f490bcb4750016ff195119f52aff2e0af47ab642' to.sha1='<NoInstanceOf_NormalFileDto>'): /3/b
10:29:28.243 [main] INFO  c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=8, fromRepositoryId="FileInProgressMarker", toRepositoryId="8", path="830ea58d-190d-4f95-a456-a807dd8b8e40"] on repo=77577f01-bb2a-4a36-87ae-a805857697f0
10:29:28.273 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/3/b'
10:29:28.274 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='f490bcb4750016ff195119f52aff2e0af47ab642' toSha1='null'
10:29:28.274 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/3/b'
10:29:28.274 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/3/b' offset=0
10:29:28.280 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/3/b' offset=0
10:29:28.290 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 381640 bytes in 16 ms. path='/3/b'
10:29:28.294 [main] INFO  c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_e9d71f5ee7c92d6dc9e92ffdad17b8bd49418f98_0 into destFile b
10:29:28.341 [main] INFO  c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=8, fromRepositoryId="FileInProgressMarker", toRepositoryId="8", path="830ea58d-190d-4f95-a456-a807dd8b8e40"] on repo=77577f01-bb2a-4a36-87ae-a805857697f0
10:29:28.390 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/3/c'
10:29:28.403 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='6236bfc5fcdbe684239e867b2027be7e38c4652a' to.sha1='<NoInstanceOf_NormalFileDto>'): /3/c
10:29:28.434 [main] INFO  c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=9, fromRepositoryId="FileInProgressMarker", toRepositoryId="9", path="830ea58d-190d-4f95-a456-a807dd8b8e40"] on repo=77577f01-bb2a-4a36-87ae-a805857697f0
10:29:28.439 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/3/c'
10:29:28.439 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='6236bfc5fcdbe684239e867b2027be7e38c4652a' toSha1='null'
10:29:28.439 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/3/c'
10:29:28.439 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/3/c' offset=0
10:29:28.450 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/3/c' offset=0
10:29:28.460 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 498272 bytes in 21 ms. path='/3/c'
10:29:28.464 [main] INFO  c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_84a516841ba77a5b4648de2cd0dfcb30ea46dbb4_0 into destFile c
10:29:28.949 [main] INFO  c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=9, fromRepositoryId="FileInProgressMarker", toRepositoryId="9", path="830ea58d-190d-4f95-a456-a807dd8b8e40"] on repo=77577f01-bb2a-4a36-87ae-a805857697f0
10:29:28.970 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/3/d'
10:29:28.976 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='ac8810d8f0ef3c7e9dcd3902cc17316ad02c80e6' to.sha1='<NoInstanceOf_NormalFileDto>'): /3/d
10:29:29.088 [main] INFO  c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=10, fromRepositoryId="FileInProgressMarker", toRepositoryId="10", path="830ea58d-190d-4f95-a456-a807dd8b8e40"] on repo=77577f01-bb2a-4a36-87ae-a805857697f0
10:29:29.100 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/3/d'
10:29:29.100 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='ac8810d8f0ef3c7e9dcd3902cc17316ad02c80e6' toSha1='null'
10:29:29.100 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/3/d'
10:29:29.101 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/3/d' offset=0
10:29:29.106 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/3/d' offset=0
10:29:29.117 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 197847 bytes in 17 ms. path='/3/d'
10:29:29.120 [main] INFO  c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_3c363836cf4e16666669a25da280a1865c2d2874_0 into destFile d
10:29:29.411 [main] INFO  c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=10, fromRepositoryId="FileInProgressMarker", toRepositoryId="10", path="830ea58d-190d-4f95-a456-a807dd8b8e40"] on repo=77577f01-bb2a-4a36-87ae-a805857697f0
10:29:29.422 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 49.27%
10:29:29.443 [main] INFO  c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=830ea58d-190d-4f95-a456-a807dd8b8e40 remoteRepositoryId=77577f01-bb2a-4a36-87ae-a805857697f0 localRepositoryRevisionSynced=2
10:29:29.463 [main] INFO  c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=77577f01-bb2a-4a36-87ae-a805857697f0 remoteRepositoryId=830ea58d-190d-4f95-a456-a807dd8b8e40 transaction.localRevision=57 remoteFromLocalRevision=2
10:29:30.003 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncUp: fromID=77577f01-bb2a-4a36-87ae-a805857697f0 from='target/repo/k1eq3oqj-tqhfz-local' toID=830ea58d-190d-4f95-a456-a807dd8b8e40 to='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/k1eq3oqk-xeupm-remote'
10:29:30.129 [main] INFO  c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=77577f01-bb2a-4a36-87ae-a805857697f0 remoteRepositoryId=830ea58d-190d-4f95-a456-a807dd8b8e40 localRepositoryRevisionSynced=-1 localRepositoryRevisionInProgress=57
10:29:30.150 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 63.43%
10:29:30.150 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 74.63%
10:29:30.160 [main] INFO  c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=77577f01-bb2a-4a36-87ae-a805857697f0 remoteRepositoryId=830ea58d-190d-4f95-a456-a807dd8b8e40 localRepositoryRevisionSynced=57
10:29:30.540 [main] INFO  c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=830ea58d-190d-4f95-a456-a807dd8b8e40 remoteRepositoryId=77577f01-bb2a-4a36-87ae-a805857697f0 transaction.localRevision=5 remoteFromLocalRevision=57
10:29:30.636 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncDown: fromID=830ea58d-190d-4f95-a456-a807dd8b8e40 from='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/k1eq3oqk-xeupm-remote' toID=77577f01-bb2a-4a36-87ae-a805857697f0 to='target/repo/k1eq3oqj-tqhfz-local', fromRepoLocalSync=false
10:29:30.705 [main] INFO  c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=830ea58d-190d-4f95-a456-a807dd8b8e40 remoteRepositoryId=77577f01-bb2a-4a36-87ae-a805857697f0 localRepositoryRevisionSynced=2 localRepositoryRevisionInProgress=5
10:29:30.708 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 88.31%
10:29:30.708 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 99.50%
10:29:30.716 [main] INFO  c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=830ea58d-190d-4f95-a456-a807dd8b8e40 remoteRepositoryId=77577f01-bb2a-4a36-87ae-a805857697f0 localRepositoryRevisionSynced=5
10:29:30.744 [main] INFO  c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=77577f01-bb2a-4a36-87ae-a805857697f0 remoteRepositoryId=830ea58d-190d-4f95-a456-a807dd8b8e40 transaction.localRevision=60 remoteFromLocalRevision=5
10:29:30.751 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 100.00%
10:29:30.764 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [5558adde]getCloseDeferredMillis: closeDeferredMillis=20000
10:29:30.764 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [5558adde]close: Deferring shut down of real LocalRepoManager 20000 ms.
10:29:30.765 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [5ed4dc24]getCloseDeferredMillis: closeDeferredMillis=20000
10:29:30.765 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [5ed4dc24]close: Deferring shut down of real LocalRepoManager 20000 ms.
10:29:30.812 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 1.00%
10:29:30.813 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 50.00%
10:29:30.814 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 58.17%
10:29:30.816 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 63.61%
10:29:30.820 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 74.50%
10:29:30.852 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 80.62%
10:29:30.855 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 90.83%
10:29:30.870 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 96.55%
10:29:31.221 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 100.00%
10:29:31.246 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 0.50%
10:29:31.247 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncDown: fromID=830ea58d-190d-4f95-a456-a807dd8b8e40 from='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/k1eq3oqk-xeupm-remote' toID=77577f01-bb2a-4a36-87ae-a805857697f0 to='target/repo/k1eq3oqj-tqhfz-local', fromRepoLocalSync=true
10:29:31.247 [pool-34-thread-1] INFO  c.c.c.c.r.s.RepoToRepoSync - sync: locally syncing 77577f01-bb2a-4a36-87ae-a805857697f0 ('target/repo/k1eq3oqj-tqhfz-local')
10:29:31.260 [pool-34-thread-1] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 12.94%
10:29:31.282 [pool-34-thread-1] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 19.03%
10:29:31.306 [pool-34-thread-1] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 24.11%
10:29:31.330 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 1.00%
10:29:31.332 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 50.00%
10:29:31.336 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 58.17%
10:29:31.341 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 63.61%
10:29:31.345 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 74.50%
10:29:31.358 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 80.62%
10:29:31.362 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 90.83%
10:29:31.368 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 96.55%
10:29:31.376 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 100.00%
10:29:31.638 [main] INFO  c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=830ea58d-190d-4f95-a456-a807dd8b8e40 remoteRepositoryId=77577f01-bb2a-4a36-87ae-a805857697f0 localRepositoryRevisionSynced=5 localRepositoryRevisionInProgress=8
10:29:31.650 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 29.95%
10:29:31.650 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path='/2/1'
10:29:31.841 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path='/2/1/5'
10:29:31.858 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path='/3'
10:29:31.874 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 35.11%
10:29:31.874 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 40.28%
10:29:31.874 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 45.45%
10:29:31.875 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/2/1/5/aaa'
10:29:31.886 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='1784c8e522f2427fb32aa719ef003c44e2629c2b' to.sha1='<NoInstanceOf_NormalFileDto>'): /2/1/5/aaa
10:29:32.100 [main] INFO  c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=11, fromRepositoryId="FileInProgressMarker", toRepositoryId="11", path="830ea58d-190d-4f95-a456-a807dd8b8e40"] on repo=77577f01-bb2a-4a36-87ae-a805857697f0
10:29:32.150 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/2/1/5/aaa'
10:29:32.151 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='1784c8e522f2427fb32aa719ef003c44e2629c2b' toSha1='null'
10:29:32.151 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/2/1/5/aaa'
10:29:32.152 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/2/1/5/aaa' offset=0
10:29:32.159 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/2/1/5/aaa' offset=0
10:29:32.170 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 420122 bytes in 19 ms. path='/2/1/5/aaa'
10:29:32.175 [main] INFO  c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_7e240de74fb1ed08fa08d38063f6a6a91462a815_0 into destFile aaa
10:29:32.502 [main] INFO  c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=11, fromRepositoryId="FileInProgressMarker", toRepositoryId="11", path="830ea58d-190d-4f95-a456-a807dd8b8e40"] on repo=77577f01-bb2a-4a36-87ae-a805857697f0
10:29:32.518 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/2/1/5/bbb'
10:29:32.531 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='bd7f7fad2187c54f204cb84d7c62a32caa538e5c' to.sha1='<NoInstanceOf_NormalFileDto>'): /2/1/5/bbb
10:29:32.552 [main] INFO  c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=12, fromRepositoryId="FileInProgressMarker", toRepositoryId="12", path="830ea58d-190d-4f95-a456-a807dd8b8e40"] on repo=77577f01-bb2a-4a36-87ae-a805857697f0
10:29:32.555 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/2/1/5/bbb'
10:29:32.555 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='bd7f7fad2187c54f204cb84d7c62a32caa538e5c' toSha1='null'
10:29:32.555 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/2/1/5/bbb'
10:29:32.555 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/2/1/5/bbb' offset=0
10:29:32.556 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/2/1/5/bbb' offset=0
10:29:32.566 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 91321 bytes in 11 ms. path='/2/1/5/bbb'
10:29:32.570 [main] INFO  c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_5cb138284d431abd6a053a56625ec088bfb88912_0 into destFile bbb
10:29:32.584 [main] INFO  c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=12, fromRepositoryId="FileInProgressMarker", toRepositoryId="12", path="830ea58d-190d-4f95-a456-a807dd8b8e40"] on repo=77577f01-bb2a-4a36-87ae-a805857697f0
10:29:32.589 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/3/e'
10:29:32.599 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='0edc1eff090db2bd3570ba5b761990435747e973' to.sha1='<NoInstanceOf_NormalFileDto>'): /3/e
10:29:32.663 [main] INFO  c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=13, fromRepositoryId="FileInProgressMarker", toRepositoryId="13", path="830ea58d-190d-4f95-a456-a807dd8b8e40"] on repo=77577f01-bb2a-4a36-87ae-a805857697f0
10:29:32.711 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/3/e'
10:29:32.711 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='0edc1eff090db2bd3570ba5b761990435747e973' toSha1='null'
10:29:32.712 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/3/e'
10:29:32.712 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/3/e' offset=0
10:29:32.720 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/3/e' offset=0
10:29:32.728 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 275481 bytes in 16 ms. path='/3/e'
10:29:32.732 [main] INFO  c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_58e6b3a414a1e090dfc6029add0f3555ccba127f_0 into destFile e
10:29:32.906 [main] INFO  c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=13, fromRepositoryId="FileInProgressMarker", toRepositoryId="13", path="830ea58d-190d-4f95-a456-a807dd8b8e40"] on repo=77577f01-bb2a-4a36-87ae-a805857697f0
10:29:32.957 [main] INFO  c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=830ea58d-190d-4f95-a456-a807dd8b8e40 remoteRepositoryId=77577f01-bb2a-4a36-87ae-a805857697f0 localRepositoryRevisionSynced=8
10:29:33.116 [main] INFO  c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=77577f01-bb2a-4a36-87ae-a805857697f0 remoteRepositoryId=830ea58d-190d-4f95-a456-a807dd8b8e40 transaction.localRevision=79 remoteFromLocalRevision=8
10:29:33.647 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncUp: fromID=77577f01-bb2a-4a36-87ae-a805857697f0 from='target/repo/k1eq3oqj-tqhfz-local' toID=830ea58d-190d-4f95-a456-a807dd8b8e40 to='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/k1eq3oqk-xeupm-remote'
10:29:33.677 [main] INFO  c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=77577f01-bb2a-4a36-87ae-a805857697f0 remoteRepositoryId=830ea58d-190d-4f95-a456-a807dd8b8e40 localRepositoryRevisionSynced=57 localRepositoryRevisionInProgress=79
10:29:33.679 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 52.24%
10:29:33.679 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 63.43%
10:29:33.679 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 74.63%
10:29:33.682 [main] INFO  c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=77577f01-bb2a-4a36-87ae-a805857697f0 remoteRepositoryId=830ea58d-190d-4f95-a456-a807dd8b8e40 localRepositoryRevisionSynced=79
10:29:33.860 [main] INFO  c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=830ea58d-190d-4f95-a456-a807dd8b8e40 remoteRepositoryId=77577f01-bb2a-4a36-87ae-a805857697f0 transaction.localRevision=11 remoteFromLocalRevision=79
10:29:33.969 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncDown: fromID=830ea58d-190d-4f95-a456-a807dd8b8e40 from='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/k1eq3oqk-xeupm-remote' toID=77577f01-bb2a-4a36-87ae-a805857697f0 to='target/repo/k1eq3oqj-tqhfz-local', fromRepoLocalSync=false
10:29:33.999 [main] INFO  c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=830ea58d-190d-4f95-a456-a807dd8b8e40 remoteRepositoryId=77577f01-bb2a-4a36-87ae-a805857697f0 localRepositoryRevisionSynced=8 localRepositoryRevisionInProgress=11
10:29:34.003 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 88.31%
10:29:34.003 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 99.50%
10:29:34.009 [main] INFO  c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=830ea58d-190d-4f95-a456-a807dd8b8e40 remoteRepositoryId=77577f01-bb2a-4a36-87ae-a805857697f0 localRepositoryRevisionSynced=11
10:29:34.061 [main] INFO  c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=77577f01-bb2a-4a36-87ae-a805857697f0 remoteRepositoryId=830ea58d-190d-4f95-a456-a807dd8b8e40 transaction.localRevision=82 remoteFromLocalRevision=11
10:29:34.144 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 100.00%
10:29:34.144 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [5558adde]close: Deferring shut down of real LocalRepoManager 20000 ms.
10:29:34.165 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [5ed4dc24]close: Deferring shut down of real LocalRepoManager 20000 ms.
10:29:34.216 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [349c50da]<init>: localRoot='/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/k1eq6pvq-43utx-local'
10:29:34.225 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [349c50da]initLockFile: Repository '/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/k1eq6pvq-43utx-local' locked successfully.
10:29:54.146 [closeDeferredTimer-5558adde-2] INFO  c.c.c.l.LocalRepoManagerImpl - [5558adde]_close: Shutting down real LocalRepoManager.
10:29:54.408 [closeDeferredTimer-5ed4dc24-2] INFO  c.c.c.l.LocalRepoManagerImpl - [5ed4dc24]_close: Shutting down real LocalRepoManager.
10:30:38.480 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [349c50da]initPersistenceManagerFactory: repositoryId=1cefce14-0b2e-47fb-b2ad-25dbe9a3960d
10:30:38.577 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [349c50da]initPersistenceManagerFactory: Started up PersistenceManagerFactory successfully in 12068 ms.
10:30:38.602 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [1a008043]<init>: localRoot='/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/k1eq6pvr-42b0o-remote'
10:30:38.607 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [1a008043]initLockFile: Repository '/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/k1eq6pvr-42b0o-remote' locked successfully.
10:31:01.716 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [1a008043]initPersistenceManagerFactory: repositoryId=84455d90-b4a3-48b3-9504-39506b966a08
10:31:01.724 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [1a008043]initPersistenceManagerFactory: Started up PersistenceManagerFactory successfully in 8336 ms.
10:31:01.837 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 1.00%
10:31:01.846 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 50.00%
10:31:01.860 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 58.00%
10:31:01.959 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 63.61%
10:31:01.970 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 74.34%
10:31:01.987 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 80.56%
10:31:01.995 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 90.67%
10:31:02.014 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 95.68%
10:31:02.050 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 100.00%
10:31:02.073 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - local repo: 1cefce14-0b2e-47fb-b2ad-25dbe9a3960d
10:31:02.073 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - remote repo: 84455d90-b4a3-48b3-9504-39506b966a08
10:31:02.077 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 0.50%
10:31:02.077 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncDown: fromID=84455d90-b4a3-48b3-9504-39506b966a08 from='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/k1eq6pvr-42b0o-remote' toID=1cefce14-0b2e-47fb-b2ad-25dbe9a3960d to='target/repo/k1eq6pvq-43utx-local', fromRepoLocalSync=true
10:31:02.078 [pool-35-thread-1] INFO  c.c.c.c.r.s.RepoToRepoSync - sync: locally syncing 1cefce14-0b2e-47fb-b2ad-25dbe9a3960d ('target/repo/k1eq6pvq-43utx-local')
10:31:02.102 [pool-35-thread-1] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 12.94%
10:31:02.109 [pool-35-thread-1] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 25.12%
10:31:02.114 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 1.00%
10:31:02.115 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 50.00%
10:31:02.118 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 58.17%
10:31:02.123 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 63.61%
10:31:02.126 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 74.50%
10:31:02.132 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 80.62%
10:31:02.136 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 90.83%
10:31:02.140 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 95.94%
10:31:02.150 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 100.00%
10:31:02.275 [main] INFO  c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=84455d90-b4a3-48b3-9504-39506b966a08 remoteRepositoryId=1cefce14-0b2e-47fb-b2ad-25dbe9a3960d localRepositoryRevisionSynced=-1 localRepositoryRevisionInProgress=2
10:31:02.302 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path=''
10:31:02.376 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path='/1'
10:31:02.398 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 30.22%
10:31:02.398 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path='/2'
10:31:02.415 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path='/2/1'
10:31:02.434 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path='/3'
10:31:02.461 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 35.46%
10:31:02.461 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 40.70%
10:31:02.461 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/1/a'
10:31:02.468 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='6f3128d19d386e88fd724c4692091b463ff7734f' to.sha1='<NoInstanceOf_NormalFileDto>'): /1/a
10:31:02.551 [main] INFO  c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=1, fromRepositoryId="FileInProgressMarker", toRepositoryId="1", path="84455d90-b4a3-48b3-9504-39506b966a08"] on repo=1cefce14-0b2e-47fb-b2ad-25dbe9a3960d
10:31:02.726 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/1/a'
10:31:02.726 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='6f3128d19d386e88fd724c4692091b463ff7734f' toSha1='null'
10:31:02.726 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/1/a'
10:31:02.727 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/1/a' offset=0
10:31:02.728 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/1/a' offset=0
10:31:02.736 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 30685 bytes in 9 ms. path='/1/a'
10:31:02.740 [main] INFO  c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_86f7e437faa5a7fce15d1ddcb9eaeaea377667b8_0 into destFile a
10:31:02.776 [main] INFO  c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=1, fromRepositoryId="FileInProgressMarker", toRepositoryId="1", path="84455d90-b4a3-48b3-9504-39506b966a08"] on repo=1cefce14-0b2e-47fb-b2ad-25dbe9a3960d
10:31:02.798 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/1/b'
10:31:02.810 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='5cf3009a82dd43b1a5ada91b6872639bfb6399b9' to.sha1='<NoInstanceOf_NormalFileDto>'): /1/b
10:31:02.840 [main] INFO  c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=2, fromRepositoryId="FileInProgressMarker", toRepositoryId="2", path="84455d90-b4a3-48b3-9504-39506b966a08"] on repo=1cefce14-0b2e-47fb-b2ad-25dbe9a3960d
10:31:02.847 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/1/b'
10:31:02.847 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='5cf3009a82dd43b1a5ada91b6872639bfb6399b9' toSha1='null'
10:31:02.847 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/1/b'
10:31:02.847 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/1/b' offset=0
10:31:02.857 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/1/b' offset=0
10:31:02.875 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 722936 bytes in 28 ms. path='/1/b'
10:31:02.879 [main] INFO  c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_e9d71f5ee7c92d6dc9e92ffdad17b8bd49418f98_0 into destFile b
10:31:03.192 [main] INFO  c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=2, fromRepositoryId="FileInProgressMarker", toRepositoryId="2", path="84455d90-b4a3-48b3-9504-39506b966a08"] on repo=1cefce14-0b2e-47fb-b2ad-25dbe9a3960d
10:31:03.214 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/1/c'
10:31:03.225 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='45a312e62ee02b9db3ce3809840800e48cdda46d' to.sha1='<NoInstanceOf_NormalFileDto>'): /1/c
10:31:03.294 [main] INFO  c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=3, fromRepositoryId="FileInProgressMarker", toRepositoryId="3", path="84455d90-b4a3-48b3-9504-39506b966a08"] on repo=1cefce14-0b2e-47fb-b2ad-25dbe9a3960d
10:31:03.302 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/1/c'
10:31:03.302 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='45a312e62ee02b9db3ce3809840800e48cdda46d' toSha1='null'
10:31:03.302 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/1/c'
10:31:03.302 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/1/c' offset=0
10:31:03.305 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/1/c' offset=0
10:31:03.312 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 82912 bytes in 10 ms. path='/1/c'
10:31:03.316 [main] INFO  c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_84a516841ba77a5b4648de2cd0dfcb30ea46dbb4_0 into destFile c
10:31:03.331 [main] INFO  c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=3, fromRepositoryId="FileInProgressMarker", toRepositoryId="3", path="84455d90-b4a3-48b3-9504-39506b966a08"] on repo=1cefce14-0b2e-47fb-b2ad-25dbe9a3960d
10:31:03.342 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/2/1/a'
10:31:03.359 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='c10f0c2353022b44c914080a8b825f8ec193fbb3' to.sha1='<NoInstanceOf_NormalFileDto>'): /2/1/a
10:31:03.375 [main] INFO  c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=4, fromRepositoryId="FileInProgressMarker", toRepositoryId="4", path="84455d90-b4a3-48b3-9504-39506b966a08"] on repo=1cefce14-0b2e-47fb-b2ad-25dbe9a3960d
10:31:03.526 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/2/1/a'
10:31:03.526 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='c10f0c2353022b44c914080a8b825f8ec193fbb3' toSha1='null'
10:31:03.526 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/2/1/a'
10:31:03.526 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/2/1/a' offset=0
10:31:03.527 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/2/1/a' offset=0
10:31:03.532 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 39411 bytes in 6 ms. path='/2/1/a'
10:31:03.537 [main] INFO  c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_86f7e437faa5a7fce15d1ddcb9eaeaea377667b8_0 into destFile a
10:31:03.568 [main] INFO  c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=4, fromRepositoryId="FileInProgressMarker", toRepositoryId="4", path="84455d90-b4a3-48b3-9504-39506b966a08"] on repo=1cefce14-0b2e-47fb-b2ad-25dbe9a3960d
10:31:03.627 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/2/1/b'
10:31:03.637 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='f1c7cee362828f9d73789111f5c36085ea6f0855' to.sha1='<NoInstanceOf_NormalFileDto>'): /2/1/b
10:31:03.654 [main] INFO  c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=5, fromRepositoryId="FileInProgressMarker", toRepositoryId="5", path="84455d90-b4a3-48b3-9504-39506b966a08"] on repo=1cefce14-0b2e-47fb-b2ad-25dbe9a3960d
10:31:03.668 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/2/1/b'
10:31:03.668 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='f1c7cee362828f9d73789111f5c36085ea6f0855' toSha1='null'
10:31:03.668 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/2/1/b'
10:31:03.668 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/2/1/b' offset=0
10:31:03.672 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/2/1/b' offset=0
10:31:03.682 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 151179 bytes in 14 ms. path='/2/1/b'
10:31:03.686 [main] INFO  c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_e9d71f5ee7c92d6dc9e92ffdad17b8bd49418f98_0 into destFile b
10:31:03.762 [main] INFO  c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=5, fromRepositoryId="FileInProgressMarker", toRepositoryId="5", path="84455d90-b4a3-48b3-9504-39506b966a08"] on repo=1cefce14-0b2e-47fb-b2ad-25dbe9a3960d
10:31:03.784 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/2/a'
10:31:03.792 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='ddc3ff9f3884affe26c27b0a4275524bf4f4151b' to.sha1='<NoInstanceOf_NormalFileDto>'): /2/a
10:31:03.892 [main] INFO  c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=6, fromRepositoryId="FileInProgressMarker", toRepositoryId="6", path="84455d90-b4a3-48b3-9504-39506b966a08"] on repo=1cefce14-0b2e-47fb-b2ad-25dbe9a3960d
10:31:03.895 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/2/a'
10:31:03.896 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='ddc3ff9f3884affe26c27b0a4275524bf4f4151b' toSha1='null'
10:31:03.896 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/2/a'
10:31:03.896 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/2/a' offset=0
10:31:03.896 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/2/a' offset=0
10:31:03.908 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 4001 bytes in 12 ms. path='/2/a'
10:31:03.912 [main] INFO  c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_86f7e437faa5a7fce15d1ddcb9eaeaea377667b8_0 into destFile a
10:31:03.930 [main] INFO  c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=6, fromRepositoryId="FileInProgressMarker", toRepositoryId="6", path="84455d90-b4a3-48b3-9504-39506b966a08"] on repo=1cefce14-0b2e-47fb-b2ad-25dbe9a3960d
10:31:03.938 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/3/a'
10:31:03.948 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='032887c59b4433628c17ae572bd3a9740829b62c' to.sha1='<NoInstanceOf_NormalFileDto>'): /3/a
10:31:04.121 [main] INFO  c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=7, fromRepositoryId="FileInProgressMarker", toRepositoryId="7", path="84455d90-b4a3-48b3-9504-39506b966a08"] on repo=1cefce14-0b2e-47fb-b2ad-25dbe9a3960d
10:31:04.449 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/3/a'
10:31:04.449 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='032887c59b4433628c17ae572bd3a9740829b62c' toSha1='null'
10:31:04.450 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/3/a'
10:31:04.450 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/3/a' offset=0
10:31:04.455 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/3/a' offset=0
10:31:04.464 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 198296 bytes in 14 ms. path='/3/a'
10:31:04.467 [main] INFO  c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_86f7e437faa5a7fce15d1ddcb9eaeaea377667b8_0 into destFile a
10:31:04.899 [main] INFO  c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=7, fromRepositoryId="FileInProgressMarker", toRepositoryId="7", path="84455d90-b4a3-48b3-9504-39506b966a08"] on repo=1cefce14-0b2e-47fb-b2ad-25dbe9a3960d
10:31:04.913 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/3/b'
10:31:04.919 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='2015cc38a3742b55767ca00eb2ee1cdd1b2d937c' to.sha1='<NoInstanceOf_NormalFileDto>'): /3/b
10:31:04.939 [main] INFO  c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=8, fromRepositoryId="FileInProgressMarker", toRepositoryId="8", path="84455d90-b4a3-48b3-9504-39506b966a08"] on repo=1cefce14-0b2e-47fb-b2ad-25dbe9a3960d
10:31:04.968 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/3/b'
10:31:04.968 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='2015cc38a3742b55767ca00eb2ee1cdd1b2d937c' toSha1='null'
10:31:04.968 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/3/b'
10:31:04.968 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/3/b' offset=0
10:31:04.975 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/3/b' offset=0
10:31:04.983 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 259923 bytes in 15 ms. path='/3/b'
10:31:04.986 [main] INFO  c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_e9d71f5ee7c92d6dc9e92ffdad17b8bd49418f98_0 into destFile b
10:31:05.025 [main] INFO  c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=8, fromRepositoryId="FileInProgressMarker", toRepositoryId="8", path="84455d90-b4a3-48b3-9504-39506b966a08"] on repo=1cefce14-0b2e-47fb-b2ad-25dbe9a3960d
10:31:05.056 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/3/c'
10:31:05.065 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='449e33302e98489f4d5ab40bf037f8bc24ce0b0c' to.sha1='<NoInstanceOf_NormalFileDto>'): /3/c
10:31:05.082 [main] INFO  c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=9, fromRepositoryId="FileInProgressMarker", toRepositoryId="9", path="84455d90-b4a3-48b3-9504-39506b966a08"] on repo=1cefce14-0b2e-47fb-b2ad-25dbe9a3960d
10:31:05.273 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/3/c'
10:31:05.273 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='449e33302e98489f4d5ab40bf037f8bc24ce0b0c' toSha1='null'
10:31:05.273 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/3/c'
10:31:05.273 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/3/c' offset=0
10:31:05.274 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/3/c' offset=0
10:31:05.281 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 63280 bytes in 8 ms. path='/3/c'
10:31:05.287 [main] INFO  c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_84a516841ba77a5b4648de2cd0dfcb30ea46dbb4_0 into destFile c
10:31:05.360 [main] INFO  c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=9, fromRepositoryId="FileInProgressMarker", toRepositoryId="9", path="84455d90-b4a3-48b3-9504-39506b966a08"] on repo=1cefce14-0b2e-47fb-b2ad-25dbe9a3960d
10:31:05.372 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/3/d'
10:31:05.382 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='a1dcd204daa720f068359cc8d7dbce5b7570e24c' to.sha1='<NoInstanceOf_NormalFileDto>'): /3/d
10:31:05.395 [main] INFO  c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=10, fromRepositoryId="FileInProgressMarker", toRepositoryId="10", path="84455d90-b4a3-48b3-9504-39506b966a08"] on repo=1cefce14-0b2e-47fb-b2ad-25dbe9a3960d
10:31:05.399 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/3/d'
10:31:05.400 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='a1dcd204daa720f068359cc8d7dbce5b7570e24c' toSha1='null'
10:31:05.400 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/3/d'
10:31:05.400 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/3/d' offset=0
10:31:05.404 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/3/d' offset=0
10:31:05.417 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 213165 bytes in 17 ms. path='/3/d'
10:31:05.421 [main] INFO  c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_3c363836cf4e16666669a25da280a1865c2d2874_0 into destFile d
10:31:05.613 [main] INFO  c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=10, fromRepositoryId="FileInProgressMarker", toRepositoryId="10", path="84455d90-b4a3-48b3-9504-39506b966a08"] on repo=1cefce14-0b2e-47fb-b2ad-25dbe9a3960d
10:31:05.633 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 49.27%
10:31:05.654 [main] INFO  c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=84455d90-b4a3-48b3-9504-39506b966a08 remoteRepositoryId=1cefce14-0b2e-47fb-b2ad-25dbe9a3960d localRepositoryRevisionSynced=2
10:31:05.675 [main] INFO  c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=1cefce14-0b2e-47fb-b2ad-25dbe9a3960d remoteRepositoryId=84455d90-b4a3-48b3-9504-39506b966a08 transaction.localRevision=57 remoteFromLocalRevision=2
10:31:05.712 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncUp: fromID=1cefce14-0b2e-47fb-b2ad-25dbe9a3960d from='target/repo/k1eq6pvq-43utx-local' toID=84455d90-b4a3-48b3-9504-39506b966a08 to='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/k1eq6pvr-42b0o-remote'
10:31:05.832 [main] INFO  c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=1cefce14-0b2e-47fb-b2ad-25dbe9a3960d remoteRepositoryId=84455d90-b4a3-48b3-9504-39506b966a08 localRepositoryRevisionSynced=-1 localRepositoryRevisionInProgress=57
10:31:05.847 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 63.43%
10:31:05.847 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 74.63%
10:31:05.857 [main] INFO  c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=1cefce14-0b2e-47fb-b2ad-25dbe9a3960d remoteRepositoryId=84455d90-b4a3-48b3-9504-39506b966a08 localRepositoryRevisionSynced=57
10:31:05.905 [main] INFO  c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=84455d90-b4a3-48b3-9504-39506b966a08 remoteRepositoryId=1cefce14-0b2e-47fb-b2ad-25dbe9a3960d transaction.localRevision=5 remoteFromLocalRevision=57
10:31:05.913 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncDown: fromID=84455d90-b4a3-48b3-9504-39506b966a08 from='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/k1eq6pvr-42b0o-remote' toID=1cefce14-0b2e-47fb-b2ad-25dbe9a3960d to='target/repo/k1eq6pvq-43utx-local', fromRepoLocalSync=false
10:31:05.929 [main] INFO  c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=84455d90-b4a3-48b3-9504-39506b966a08 remoteRepositoryId=1cefce14-0b2e-47fb-b2ad-25dbe9a3960d localRepositoryRevisionSynced=2 localRepositoryRevisionInProgress=5
10:31:05.932 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 88.31%
10:31:05.932 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 99.50%
10:31:05.936 [main] INFO  c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=84455d90-b4a3-48b3-9504-39506b966a08 remoteRepositoryId=1cefce14-0b2e-47fb-b2ad-25dbe9a3960d localRepositoryRevisionSynced=5
10:31:05.998 [main] INFO  c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=1cefce14-0b2e-47fb-b2ad-25dbe9a3960d remoteRepositoryId=84455d90-b4a3-48b3-9504-39506b966a08 transaction.localRevision=60 remoteFromLocalRevision=5
10:31:06.007 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 100.00%
10:31:06.026 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [349c50da]getCloseDeferredMillis: closeDeferredMillis=20000
10:31:06.026 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [349c50da]close: Deferring shut down of real LocalRepoManager 20000 ms.
10:31:06.026 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [1a008043]getCloseDeferredMillis: closeDeferredMillis=20000
10:31:06.027 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [1a008043]close: Deferring shut down of real LocalRepoManager 20000 ms.
10:31:06.065 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 1.00%
10:31:06.066 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 50.00%
10:31:06.067 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 58.17%
10:31:06.073 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 63.61%
10:31:06.081 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 74.50%
10:31:06.170 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 80.62%
10:31:06.178 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 90.83%
10:31:06.182 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 95.94%
10:31:06.328 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 100.00%
10:31:06.354 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 0.50%
10:31:06.354 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncDown: fromID=84455d90-b4a3-48b3-9504-39506b966a08 from='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/k1eq6pvr-42b0o-remote' toID=1cefce14-0b2e-47fb-b2ad-25dbe9a3960d to='target/repo/k1eq6pvq-43utx-local', fromRepoLocalSync=true
10:31:06.355 [pool-36-thread-1] INFO  c.c.c.c.r.s.RepoToRepoSync - sync: locally syncing 1cefce14-0b2e-47fb-b2ad-25dbe9a3960d ('target/repo/k1eq6pvq-43utx-local')
10:31:06.362 [pool-36-thread-1] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 12.94%
10:31:06.378 [pool-36-thread-1] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 19.03%
10:31:06.395 [pool-36-thread-1] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 24.11%
10:31:06.410 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 1.00%
10:31:06.411 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 50.00%
10:31:06.412 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 58.17%
10:31:06.417 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 63.61%
10:31:06.422 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 74.50%
10:31:06.429 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 80.62%
10:31:06.433 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 90.83%
10:31:06.438 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 95.94%
10:31:06.448 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 100.00%
10:31:06.507 [main] INFO  c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=84455d90-b4a3-48b3-9504-39506b966a08 remoteRepositoryId=1cefce14-0b2e-47fb-b2ad-25dbe9a3960d localRepositoryRevisionSynced=5 localRepositoryRevisionInProgress=8
10:31:06.528 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 29.23%
10:31:06.528 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path='/2/1'
10:31:06.562 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 34.83%
10:31:06.563 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncModifications: Deleting '/2/1/a'
10:31:06.634 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 40.42%
10:31:06.634 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 46.02%
10:31:06.652 [main] INFO  c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=84455d90-b4a3-48b3-9504-39506b966a08 remoteRepositoryId=1cefce14-0b2e-47fb-b2ad-25dbe9a3960d localRepositoryRevisionSynced=8
10:31:06.738 [main] INFO  c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=1cefce14-0b2e-47fb-b2ad-25dbe9a3960d remoteRepositoryId=84455d90-b4a3-48b3-9504-39506b966a08 transaction.localRevision=63 remoteFromLocalRevision=8
10:31:06.768 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncUp: fromID=1cefce14-0b2e-47fb-b2ad-25dbe9a3960d from='target/repo/k1eq6pvq-43utx-local' toID=84455d90-b4a3-48b3-9504-39506b966a08 to='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/k1eq6pvr-42b0o-remote'
10:31:06.792 [main] INFO  c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=1cefce14-0b2e-47fb-b2ad-25dbe9a3960d remoteRepositoryId=84455d90-b4a3-48b3-9504-39506b966a08 localRepositoryRevisionSynced=57 localRepositoryRevisionInProgress=63
10:31:06.804 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 52.24%
10:31:06.805 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 59.70%
10:31:06.805 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncModifications: Deleting '/2/1/a'
10:31:06.810 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 67.16%
10:31:06.810 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 74.63%
10:31:06.828 [main] INFO  c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=1cefce14-0b2e-47fb-b2ad-25dbe9a3960d remoteRepositoryId=84455d90-b4a3-48b3-9504-39506b966a08 localRepositoryRevisionSynced=63
10:31:06.836 [main] INFO  c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=84455d90-b4a3-48b3-9504-39506b966a08 remoteRepositoryId=1cefce14-0b2e-47fb-b2ad-25dbe9a3960d transaction.localRevision=11 remoteFromLocalRevision=63
10:31:06.992 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncDown: fromID=84455d90-b4a3-48b3-9504-39506b966a08 from='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/k1eq6pvr-42b0o-remote' toID=1cefce14-0b2e-47fb-b2ad-25dbe9a3960d to='target/repo/k1eq6pvq-43utx-local', fromRepoLocalSync=false
10:31:07.007 [main] INFO  c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=84455d90-b4a3-48b3-9504-39506b966a08 remoteRepositoryId=1cefce14-0b2e-47fb-b2ad-25dbe9a3960d localRepositoryRevisionSynced=8 localRepositoryRevisionInProgress=11
10:31:07.014 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 88.31%
10:31:07.015 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 99.50%
10:31:07.020 [main] INFO  c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=84455d90-b4a3-48b3-9504-39506b966a08 remoteRepositoryId=1cefce14-0b2e-47fb-b2ad-25dbe9a3960d localRepositoryRevisionSynced=11
10:31:07.077 [main] INFO  c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=1cefce14-0b2e-47fb-b2ad-25dbe9a3960d remoteRepositoryId=84455d90-b4a3-48b3-9504-39506b966a08 transaction.localRevision=66 remoteFromLocalRevision=11
10:31:07.097 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 100.00%
10:31:07.097 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [349c50da]close: Deferring shut down of real LocalRepoManager 20000 ms.
10:31:07.109 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [1a008043]close: Deferring shut down of real LocalRepoManager 20000 ms.
10:31:07.151 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [3dbdc998]<init>: localRoot='/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/k1eq8pla-rakws-local'
10:31:07.159 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [3dbdc998]initLockFile: Repository '/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/k1eq8pla-rakws-local' locked successfully.
10:31:27.099 [closeDeferredTimer-349c50da-2] INFO  c.c.c.l.LocalRepoManagerImpl - [349c50da]_close: Shutting down real LocalRepoManager.
10:31:27.376 [closeDeferredTimer-1a008043-2] INFO  c.c.c.l.LocalRepoManagerImpl - [1a008043]_close: Shutting down real LocalRepoManager.
10:31:32.975 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [3dbdc998]initPersistenceManagerFactory: repositoryId=2890b7a6-b3db-433d-a1fb-cf449fd4e872
10:31:33.180 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [3dbdc998]initPersistenceManagerFactory: Started up PersistenceManagerFactory successfully in 12633 ms.
10:31:33.208 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [1dd30290]<init>: localRoot='/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/k1eq8pla-9z7du-remote'
10:31:33.212 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [1dd30290]initLockFile: Repository '/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/k1eq8pla-9z7du-remote' locked successfully.
10:32:11.679 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [1dd30290]initPersistenceManagerFactory: repositoryId=bb504508-fcfc-4cff-8d3c-a4977ec6c5ea
10:32:11.733 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [1dd30290]initPersistenceManagerFactory: Started up PersistenceManagerFactory successfully in 10600 ms.
10:32:11.863 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 1.00%
10:32:11.868 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 50.00%
10:32:11.877 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 58.00%
10:32:11.934 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 63.61%
10:32:11.947 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 74.34%
10:32:11.985 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 80.56%
10:32:11.996 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 90.67%
10:32:12.044 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 95.68%
10:32:12.083 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 100.00%
10:32:12.107 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - local repo: 2890b7a6-b3db-433d-a1fb-cf449fd4e872
10:32:12.107 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - remote repo: bb504508-fcfc-4cff-8d3c-a4977ec6c5ea
10:32:12.110 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 0.50%
10:32:12.111 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncDown: fromID=bb504508-fcfc-4cff-8d3c-a4977ec6c5ea from='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/k1eq8pla-9z7du-remote/2' toID=2890b7a6-b3db-433d-a1fb-cf449fd4e872 to='target/repo/k1eq8pla-rakws-local', fromRepoLocalSync=true
10:32:12.111 [pool-37-thread-1] INFO  c.c.c.c.r.s.RepoToRepoSync - sync: locally syncing 2890b7a6-b3db-433d-a1fb-cf449fd4e872 ('target/repo/k1eq8pla-rakws-local')
10:32:12.141 [pool-37-thread-1] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 12.94%
10:32:12.155 [pool-37-thread-1] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 25.12%
10:32:12.158 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 1.00%
10:32:12.159 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 50.00%
10:32:12.160 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 58.17%
10:32:12.166 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 63.61%
10:32:12.169 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 74.50%
10:32:12.176 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 80.62%
10:32:12.179 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 90.83%
10:32:12.183 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 95.94%
10:32:12.189 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 100.00%
10:32:12.221 [main] INFO  c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=bb504508-fcfc-4cff-8d3c-a4977ec6c5ea remoteRepositoryId=2890b7a6-b3db-433d-a1fb-cf449fd4e872 localRepositoryRevisionSynced=-1 localRepositoryRevisionInProgress=2
10:32:12.267 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path=''
10:32:12.321 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path='/1'
10:32:12.388 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 31.31%
10:32:12.396 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 36.58%
10:32:12.396 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 41.85%
10:32:12.396 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/1/a'
10:32:12.403 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='730e7ed1e28f99c8fe856df86515805499210eee' to.sha1='<NoInstanceOf_NormalFileDto>'): /1/a
10:32:13.236 [main] INFO  c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=1, fromRepositoryId="FileInProgressMarker", toRepositoryId="1", path="bb504508-fcfc-4cff-8d3c-a4977ec6c5ea"] on repo=2890b7a6-b3db-433d-a1fb-cf449fd4e872
10:32:13.742 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/1/a'
10:32:13.742 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='730e7ed1e28f99c8fe856df86515805499210eee' toSha1='null'
10:32:13.742 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/1/a'
10:32:13.742 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/1/a' offset=0
10:32:13.754 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/1/a' offset=0
10:32:13.767 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 685875 bytes in 25 ms. path='/1/a'
10:32:13.770 [main] INFO  c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_86f7e437faa5a7fce15d1ddcb9eaeaea377667b8_0 into destFile a
10:32:13.907 [main] INFO  c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=1, fromRepositoryId="FileInProgressMarker", toRepositoryId="1", path="bb504508-fcfc-4cff-8d3c-a4977ec6c5ea"] on repo=2890b7a6-b3db-433d-a1fb-cf449fd4e872
10:32:14.121 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/1/b'
10:32:14.134 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='5ee2ce450239061dacc5fede0979379678cd1d53' to.sha1='<NoInstanceOf_NormalFileDto>'): /1/b
10:32:14.357 [main] INFO  c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=2, fromRepositoryId="FileInProgressMarker", toRepositoryId="2", path="bb504508-fcfc-4cff-8d3c-a4977ec6c5ea"] on repo=2890b7a6-b3db-433d-a1fb-cf449fd4e872
10:32:14.376 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/1/b'
10:32:14.376 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='5ee2ce450239061dacc5fede0979379678cd1d53' toSha1='null'
10:32:14.376 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/1/b'
10:32:14.376 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/1/b' offset=0
10:32:14.386 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/1/b' offset=0
10:32:14.395 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 341019 bytes in 19 ms. path='/1/b'
10:32:14.399 [main] INFO  c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_e9d71f5ee7c92d6dc9e92ffdad17b8bd49418f98_0 into destFile b
10:32:14.611 [main] INFO  c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=2, fromRepositoryId="FileInProgressMarker", toRepositoryId="2", path="bb504508-fcfc-4cff-8d3c-a4977ec6c5ea"] on repo=2890b7a6-b3db-433d-a1fb-cf449fd4e872
10:32:14.714 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/a'
10:32:14.726 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='523c56c1d3b808afed746e7c5047b716fd966121' to.sha1='<NoInstanceOf_NormalFileDto>'): /a
10:32:14.888 [main] INFO  c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=3, fromRepositoryId="FileInProgressMarker", toRepositoryId="3", path="bb504508-fcfc-4cff-8d3c-a4977ec6c5ea"] on repo=2890b7a6-b3db-433d-a1fb-cf449fd4e872
10:32:15.163 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/a'
10:32:15.163 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='523c56c1d3b808afed746e7c5047b716fd966121' toSha1='null'
10:32:15.163 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/a'
10:32:15.163 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/a' offset=0
10:32:15.164 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/a' offset=0
10:32:15.172 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 28975 bytes in 9 ms. path='/a'
10:32:15.178 [main] INFO  c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_86f7e437faa5a7fce15d1ddcb9eaeaea377667b8_0 into destFile a
10:32:15.225 [main] INFO  c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=3, fromRepositoryId="FileInProgressMarker", toRepositoryId="3", path="bb504508-fcfc-4cff-8d3c-a4977ec6c5ea"] on repo=2890b7a6-b3db-433d-a1fb-cf449fd4e872
10:32:15.241 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 48.43%
10:32:15.259 [main] INFO  c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=bb504508-fcfc-4cff-8d3c-a4977ec6c5ea remoteRepositoryId=2890b7a6-b3db-433d-a1fb-cf449fd4e872 localRepositoryRevisionSynced=2
10:32:15.269 [main] INFO  c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=2890b7a6-b3db-433d-a1fb-cf449fd4e872 remoteRepositoryId=bb504508-fcfc-4cff-8d3c-a4977ec6c5ea transaction.localRevision=19 remoteFromLocalRevision=2
10:32:15.280 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncUp: fromID=2890b7a6-b3db-433d-a1fb-cf449fd4e872 from='target/repo/k1eq8pla-rakws-local' toID=bb504508-fcfc-4cff-8d3c-a4977ec6c5ea to='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/k1eq8pla-9z7du-remote/2'
10:32:15.536 [main] INFO  c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=2890b7a6-b3db-433d-a1fb-cf449fd4e872 remoteRepositoryId=bb504508-fcfc-4cff-8d3c-a4977ec6c5ea localRepositoryRevisionSynced=-1 localRepositoryRevisionInProgress=19
10:32:15.551 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 63.43%
10:32:15.551 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 74.63%
10:32:15.560 [main] INFO  c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=2890b7a6-b3db-433d-a1fb-cf449fd4e872 remoteRepositoryId=bb504508-fcfc-4cff-8d3c-a4977ec6c5ea localRepositoryRevisionSynced=19
10:32:15.616 [main] INFO  c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=bb504508-fcfc-4cff-8d3c-a4977ec6c5ea remoteRepositoryId=2890b7a6-b3db-433d-a1fb-cf449fd4e872 transaction.localRevision=5 remoteFromLocalRevision=19
10:32:15.633 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncDown: fromID=bb504508-fcfc-4cff-8d3c-a4977ec6c5ea from='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/k1eq8pla-9z7du-remote/2' toID=2890b7a6-b3db-433d-a1fb-cf449fd4e872 to='target/repo/k1eq8pla-rakws-local', fromRepoLocalSync=false
10:32:15.666 [main] INFO  c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=bb504508-fcfc-4cff-8d3c-a4977ec6c5ea remoteRepositoryId=2890b7a6-b3db-433d-a1fb-cf449fd4e872 localRepositoryRevisionSynced=2 localRepositoryRevisionInProgress=5
10:32:15.671 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 88.31%
10:32:15.671 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 99.50%
10:32:15.674 [main] INFO  c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=bb504508-fcfc-4cff-8d3c-a4977ec6c5ea remoteRepositoryId=2890b7a6-b3db-433d-a1fb-cf449fd4e872 localRepositoryRevisionSynced=5
10:32:15.679 [main] INFO  c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=2890b7a6-b3db-433d-a1fb-cf449fd4e872 remoteRepositoryId=bb504508-fcfc-4cff-8d3c-a4977ec6c5ea transaction.localRevision=22 remoteFromLocalRevision=5
10:32:15.696 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 100.00%
10:32:15.711 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [3dbdc998]getCloseDeferredMillis: closeDeferredMillis=20000
10:32:15.711 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [3dbdc998]close: Deferring shut down of real LocalRepoManager 20000 ms.
10:32:15.712 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [1dd30290]getCloseDeferredMillis: closeDeferredMillis=20000
10:32:15.712 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [1dd30290]close: Deferring shut down of real LocalRepoManager 20000 ms.
10:32:15.732 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 1.00%
10:32:15.733 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 50.00%
10:32:15.734 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 58.17%
10:32:15.737 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 63.61%
10:32:15.740 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 74.50%
10:32:15.793 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 80.62%
10:32:15.796 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 90.83%
10:32:15.800 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 95.94%
10:32:15.811 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 100.00%
10:32:15.831 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 0.50%
10:32:15.832 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncDown: fromID=bb504508-fcfc-4cff-8d3c-a4977ec6c5ea from='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/k1eq8pla-9z7du-remote/2' toID=2890b7a6-b3db-433d-a1fb-cf449fd4e872 to='target/repo/k1eq8pla-rakws-local', fromRepoLocalSync=true
10:32:15.832 [pool-38-thread-1] INFO  c.c.c.c.r.s.RepoToRepoSync - sync: locally syncing 2890b7a6-b3db-433d-a1fb-cf449fd4e872 ('target/repo/k1eq8pla-rakws-local')
10:32:15.841 [pool-38-thread-1] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 12.94%
10:32:15.849 [pool-38-thread-1] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 18.27%
10:32:15.858 [pool-38-thread-1] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 25.12%
10:32:15.866 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 1.00%
10:32:15.867 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 50.00%
10:32:15.868 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 58.17%
10:32:15.875 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 63.61%
10:32:15.888 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 74.50%
10:32:15.892 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 80.62%
10:32:15.895 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 90.83%
10:32:15.899 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 95.94%
10:32:15.905 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 100.00%
10:32:15.912 [main] INFO  c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=bb504508-fcfc-4cff-8d3c-a4977ec6c5ea remoteRepositoryId=2890b7a6-b3db-433d-a1fb-cf449fd4e872 localRepositoryRevisionSynced=5 localRepositoryRevisionInProgress=8
10:32:15.929 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 32.34%
10:32:15.929 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path='/1'
10:32:15.966 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncModifications: Deleting '/1/a'
10:32:16.731 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 39.80%
10:32:16.731 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 47.26%
10:32:16.753 [main] INFO  c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=bb504508-fcfc-4cff-8d3c-a4977ec6c5ea remoteRepositoryId=2890b7a6-b3db-433d-a1fb-cf449fd4e872 localRepositoryRevisionSynced=8
10:32:16.761 [main] INFO  c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=2890b7a6-b3db-433d-a1fb-cf449fd4e872 remoteRepositoryId=bb504508-fcfc-4cff-8d3c-a4977ec6c5ea transaction.localRevision=25 remoteFromLocalRevision=8
10:32:16.770 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncUp: fromID=2890b7a6-b3db-433d-a1fb-cf449fd4e872 from='target/repo/k1eq8pla-rakws-local' toID=bb504508-fcfc-4cff-8d3c-a4977ec6c5ea to='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/k1eq8pla-9z7du-remote/2'
10:32:16.791 [main] INFO  c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=2890b7a6-b3db-433d-a1fb-cf449fd4e872 remoteRepositoryId=bb504508-fcfc-4cff-8d3c-a4977ec6c5ea localRepositoryRevisionSynced=19 localRepositoryRevisionInProgress=25
10:32:16.798 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 59.70%
10:32:16.799 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncModifications: Deleting '/1/a'
10:32:16.804 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 67.16%
10:32:16.804 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 74.63%
10:32:16.818 [main] INFO  c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=2890b7a6-b3db-433d-a1fb-cf449fd4e872 remoteRepositoryId=bb504508-fcfc-4cff-8d3c-a4977ec6c5ea localRepositoryRevisionSynced=25
10:32:16.835 [main] INFO  c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=bb504508-fcfc-4cff-8d3c-a4977ec6c5ea remoteRepositoryId=2890b7a6-b3db-433d-a1fb-cf449fd4e872 transaction.localRevision=11 remoteFromLocalRevision=25
10:32:16.840 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncDown: fromID=bb504508-fcfc-4cff-8d3c-a4977ec6c5ea from='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/k1eq8pla-9z7du-remote/2' toID=2890b7a6-b3db-433d-a1fb-cf449fd4e872 to='target/repo/k1eq8pla-rakws-local', fromRepoLocalSync=false
10:32:17.034 [main] INFO  c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=bb504508-fcfc-4cff-8d3c-a4977ec6c5ea remoteRepositoryId=2890b7a6-b3db-433d-a1fb-cf449fd4e872 localRepositoryRevisionSynced=8 localRepositoryRevisionInProgress=11
10:32:17.040 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 88.31%
10:32:17.041 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 99.50%
10:32:17.044 [main] INFO  c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=bb504508-fcfc-4cff-8d3c-a4977ec6c5ea remoteRepositoryId=2890b7a6-b3db-433d-a1fb-cf449fd4e872 localRepositoryRevisionSynced=11
10:32:17.077 [main] INFO  c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=2890b7a6-b3db-433d-a1fb-cf449fd4e872 remoteRepositoryId=bb504508-fcfc-4cff-8d3c-a4977ec6c5ea transaction.localRevision=28 remoteFromLocalRevision=11
10:32:17.094 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 100.00%
10:32:17.094 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [3dbdc998]close: Deferring shut down of real LocalRepoManager 20000 ms.
10:32:17.108 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [1dd30290]close: Deferring shut down of real LocalRepoManager 20000 ms.
10:32:17.119 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [11e56861]<init>: localRoot='/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/k1eqa7ku-uaq2u-local'
10:32:17.123 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [11e56861]initLockFile: Repository '/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/k1eqa7ku-uaq2u-local' locked successfully.
10:32:37.096 [closeDeferredTimer-3dbdc998-2] INFO  c.c.c.l.LocalRepoManagerImpl - [3dbdc998]_close: Shutting down real LocalRepoManager.
10:32:37.371 [closeDeferredTimer-1dd30290-2] INFO  c.c.c.l.LocalRepoManagerImpl - [1dd30290]_close: Shutting down real LocalRepoManager.
10:33:11.510 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [11e56861]initPersistenceManagerFactory: repositoryId=bbd399b9-89de-405e-a3e3-e7516900766d
10:33:11.750 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [11e56861]initPersistenceManagerFactory: Started up PersistenceManagerFactory successfully in 33273 ms.
10:33:11.770 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [45bef69f]<init>: localRoot='/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/k1eqa7ku-bcngg-remote'
10:33:11.774 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [45bef69f]initLockFile: Repository '/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/k1eqa7ku-bcngg-remote' locked successfully.
10:34:15.142 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [45bef69f]initPersistenceManagerFactory: repositoryId=49ce8f5a-cbe4-4efa-b1b8-0283d3c59778
10:34:15.149 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [45bef69f]initPersistenceManagerFactory: Started up PersistenceManagerFactory successfully in 35151 ms.
10:34:15.715 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 1.00%
10:34:15.721 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 50.00%
10:34:15.731 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 58.00%
10:34:15.928 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 63.61%
10:34:15.940 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 74.34%
10:34:15.970 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 79.39%
10:34:15.980 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 90.67%
10:34:16.000 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 95.70%
10:34:16.118 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 100.00%
10:34:16.142 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - local repo: bbd399b9-89de-405e-a3e3-e7516900766d
10:34:16.143 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - remote repo: 49ce8f5a-cbe4-4efa-b1b8-0283d3c59778
10:34:16.146 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 0.50%
10:34:16.147 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncDown: fromID=49ce8f5a-cbe4-4efa-b1b8-0283d3c59778 from='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/k1eqa7ku-bcngg-remote' toID=bbd399b9-89de-405e-a3e3-e7516900766d to='target/repo/k1eqa7ku-uaq2u-local', fromRepoLocalSync=true
10:34:16.148 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 1.00%
10:34:16.149 [pool-39-thread-1] INFO  c.c.c.c.r.s.RepoToRepoSync - sync: locally syncing bbd399b9-89de-405e-a3e3-e7516900766d ('target/repo/k1eqa7ku-uaq2u-local')
10:34:16.149 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 50.00%
10:34:16.158 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 58.17%
10:34:16.163 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 63.61%
10:34:16.166 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 74.50%
10:34:16.178 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 80.62%
10:34:16.193 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 90.83%
10:34:16.203 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 95.94%
10:34:16.205 [pool-39-thread-1] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 12.94%
10:34:16.218 [pool-39-thread-1] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 25.12%
10:34:16.218 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 100.00%
10:34:16.879 [main] INFO  c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=49ce8f5a-cbe4-4efa-b1b8-0283d3c59778 remoteRepositoryId=bbd399b9-89de-405e-a3e3-e7516900766d localRepositoryRevisionSynced=-1 localRepositoryRevisionInProgress=2
10:34:16.905 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path=''
10:34:16.961 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path='/1'
10:34:17.410 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 30.22%
10:34:17.410 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path='/2'
10:34:17.657 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path='/2/1'
10:34:17.766 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path='/3'
10:34:17.897 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 35.46%
10:34:17.898 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 40.70%
10:34:17.898 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/1/a'
10:34:17.905 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='06ba21ffb59de85762aa10807143cb1e2bfb30cb' to.sha1='<NoInstanceOf_NormalFileDto>'): /1/a
10:34:18.440 [main] INFO  c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=1, fromRepositoryId="FileInProgressMarker", toRepositoryId="1", path="49ce8f5a-cbe4-4efa-b1b8-0283d3c59778"] on repo=bbd399b9-89de-405e-a3e3-e7516900766d
10:34:18.460 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/1/a'
10:34:18.460 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='06ba21ffb59de85762aa10807143cb1e2bfb30cb' toSha1='null'
10:34:18.460 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/1/a'
10:34:18.460 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/1/a' offset=0
10:34:18.461 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/1/a' offset=0
10:34:18.468 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 81000 bytes in 8 ms. path='/1/a'
10:34:18.471 [main] INFO  c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_86f7e437faa5a7fce15d1ddcb9eaeaea377667b8_0 into destFile a
10:34:18.510 [main] INFO  c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=1, fromRepositoryId="FileInProgressMarker", toRepositoryId="1", path="49ce8f5a-cbe4-4efa-b1b8-0283d3c59778"] on repo=bbd399b9-89de-405e-a3e3-e7516900766d
10:34:18.722 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/1/b'
10:34:18.738 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='671c4fada86daaa77c4087a81d31267bc84f07d4' to.sha1='<NoInstanceOf_NormalFileDto>'): /1/b
10:34:18.801 [main] INFO  c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=2, fromRepositoryId="FileInProgressMarker", toRepositoryId="2", path="49ce8f5a-cbe4-4efa-b1b8-0283d3c59778"] on repo=bbd399b9-89de-405e-a3e3-e7516900766d
10:34:18.853 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/1/b'
10:34:18.853 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='671c4fada86daaa77c4087a81d31267bc84f07d4' toSha1='null'
10:34:18.853 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/1/b'
10:34:18.853 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/1/b' offset=0
10:34:18.858 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/1/b' offset=0
10:34:18.868 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 414990 bytes in 15 ms. path='/1/b'
10:34:18.872 [main] INFO  c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_e9d71f5ee7c92d6dc9e92ffdad17b8bd49418f98_0 into destFile b
10:34:18.950 [main] INFO  c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=2, fromRepositoryId="FileInProgressMarker", toRepositoryId="2", path="49ce8f5a-cbe4-4efa-b1b8-0283d3c59778"] on repo=bbd399b9-89de-405e-a3e3-e7516900766d
10:34:19.056 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/1/c'
10:34:19.062 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='5d2046eef8cc8ee57268f128c47e2c3d96fa2138' to.sha1='<NoInstanceOf_NormalFileDto>'): /1/c
10:34:19.130 [main] INFO  c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=3, fromRepositoryId="FileInProgressMarker", toRepositoryId="3", path="49ce8f5a-cbe4-4efa-b1b8-0283d3c59778"] on repo=bbd399b9-89de-405e-a3e3-e7516900766d
10:34:19.552 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/1/c'
10:34:19.552 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='5d2046eef8cc8ee57268f128c47e2c3d96fa2138' toSha1='null'
10:34:19.552 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/1/c'
10:34:19.552 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/1/c' offset=0
10:34:19.561 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/1/c' offset=0
10:34:19.571 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 473697 bytes in 19 ms. path='/1/c'
10:34:19.574 [main] INFO  c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_84a516841ba77a5b4648de2cd0dfcb30ea46dbb4_0 into destFile c
10:34:19.645 [main] INFO  c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=3, fromRepositoryId="FileInProgressMarker", toRepositoryId="3", path="49ce8f5a-cbe4-4efa-b1b8-0283d3c59778"] on repo=bbd399b9-89de-405e-a3e3-e7516900766d
10:34:19.687 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/2/1/a'
10:34:19.700 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='ca3aac8111f9a490bb364bec61aaf4028a7b1965' to.sha1='<NoInstanceOf_NormalFileDto>'): /2/1/a
10:34:19.811 [main] INFO  c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=4, fromRepositoryId="FileInProgressMarker", toRepositoryId="4", path="49ce8f5a-cbe4-4efa-b1b8-0283d3c59778"] on repo=bbd399b9-89de-405e-a3e3-e7516900766d
10:34:19.846 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/2/1/a'
10:34:19.846 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='ca3aac8111f9a490bb364bec61aaf4028a7b1965' toSha1='null'
10:34:19.846 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/2/1/a'
10:34:19.846 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/2/1/a' offset=0
10:34:19.850 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/2/1/a' offset=0
10:34:19.863 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 102725 bytes in 17 ms. path='/2/1/a'
10:34:19.868 [main] INFO  c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_86f7e437faa5a7fce15d1ddcb9eaeaea377667b8_0 into destFile a
10:34:19.920 [main] INFO  c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=4, fromRepositoryId="FileInProgressMarker", toRepositoryId="4", path="49ce8f5a-cbe4-4efa-b1b8-0283d3c59778"] on repo=bbd399b9-89de-405e-a3e3-e7516900766d
10:34:19.938 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/2/1/b'
10:34:19.954 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='64874834dac88e869db7de075f0259ae541717e5' to.sha1='<NoInstanceOf_NormalFileDto>'): /2/1/b
10:34:19.982 [main] INFO  c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=5, fromRepositoryId="FileInProgressMarker", toRepositoryId="5", path="49ce8f5a-cbe4-4efa-b1b8-0283d3c59778"] on repo=bbd399b9-89de-405e-a3e3-e7516900766d
10:34:19.992 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/2/1/b'
10:34:19.992 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='64874834dac88e869db7de075f0259ae541717e5' toSha1='null'
10:34:19.992 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/2/1/b'
10:34:19.992 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/2/1/b' offset=0
10:34:19.994 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/2/1/b' offset=0
10:34:20.010 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 616230 bytes in 18 ms. path='/2/1/b'
10:34:20.014 [main] INFO  c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_e9d71f5ee7c92d6dc9e92ffdad17b8bd49418f98_0 into destFile b
10:34:20.226 [main] INFO  c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=5, fromRepositoryId="FileInProgressMarker", toRepositoryId="5", path="49ce8f5a-cbe4-4efa-b1b8-0283d3c59778"] on repo=bbd399b9-89de-405e-a3e3-e7516900766d
10:34:20.248 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/2/a'
10:34:20.256 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='4fe5d206d3f5a6ba4bf9c0277c4c152be1988f45' to.sha1='<NoInstanceOf_NormalFileDto>'): /2/a
10:34:20.307 [main] INFO  c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=6, fromRepositoryId="FileInProgressMarker", toRepositoryId="6", path="49ce8f5a-cbe4-4efa-b1b8-0283d3c59778"] on repo=bbd399b9-89de-405e-a3e3-e7516900766d
10:34:20.324 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/2/a'
10:34:20.324 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='4fe5d206d3f5a6ba4bf9c0277c4c152be1988f45' toSha1='null'
10:34:20.324 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/2/a'
10:34:20.324 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/2/a' offset=0
10:34:20.325 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/2/a' offset=0
10:34:20.330 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 80948 bytes in 6 ms. path='/2/a'
10:34:20.334 [main] INFO  c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_86f7e437faa5a7fce15d1ddcb9eaeaea377667b8_0 into destFile a
10:34:20.425 [main] INFO  c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=6, fromRepositoryId="FileInProgressMarker", toRepositoryId="6", path="49ce8f5a-cbe4-4efa-b1b8-0283d3c59778"] on repo=bbd399b9-89de-405e-a3e3-e7516900766d
10:34:20.438 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/3/a'
10:34:20.446 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='da16d978582898baa3e497a60967e66eb017e8c7' to.sha1='<NoInstanceOf_NormalFileDto>'): /3/a
10:34:20.481 [main] INFO  c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=7, fromRepositoryId="FileInProgressMarker", toRepositoryId="7", path="49ce8f5a-cbe4-4efa-b1b8-0283d3c59778"] on repo=bbd399b9-89de-405e-a3e3-e7516900766d
10:34:20.505 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/3/a'
10:34:20.506 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='da16d978582898baa3e497a60967e66eb017e8c7' toSha1='null'
10:34:20.506 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/3/a'
10:34:20.506 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/3/a' offset=0
10:34:20.506 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/3/a' offset=0
10:34:20.516 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 33960 bytes in 10 ms. path='/3/a'
10:34:20.527 [main] INFO  c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_86f7e437faa5a7fce15d1ddcb9eaeaea377667b8_0 into destFile a
10:34:20.685 [main] INFO  c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=7, fromRepositoryId="FileInProgressMarker", toRepositoryId="7", path="49ce8f5a-cbe4-4efa-b1b8-0283d3c59778"] on repo=bbd399b9-89de-405e-a3e3-e7516900766d
10:34:20.763 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/3/b'
10:34:20.773 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='728caabd17cd1d06cbcee01c3eba4c36717c1bd5' to.sha1='<NoInstanceOf_NormalFileDto>'): /3/b
10:34:20.934 [main] INFO  c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=8, fromRepositoryId="FileInProgressMarker", toRepositoryId="8", path="49ce8f5a-cbe4-4efa-b1b8-0283d3c59778"] on repo=bbd399b9-89de-405e-a3e3-e7516900766d
10:34:20.953 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/3/b'
10:34:20.953 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='728caabd17cd1d06cbcee01c3eba4c36717c1bd5' toSha1='null'
10:34:20.953 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/3/b'
10:34:20.953 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/3/b' offset=0
10:34:20.956 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/3/b' offset=0
10:34:20.966 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 106020 bytes in 13 ms. path='/3/b'
10:34:20.969 [main] INFO  c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_e9d71f5ee7c92d6dc9e92ffdad17b8bd49418f98_0 into destFile b
10:34:20.998 [main] INFO  c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=8, fromRepositoryId="FileInProgressMarker", toRepositoryId="8", path="49ce8f5a-cbe4-4efa-b1b8-0283d3c59778"] on repo=bbd399b9-89de-405e-a3e3-e7516900766d
10:34:21.084 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/3/c'
10:34:21.098 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='2f84b4f7c306cced388d0e650411ca75b3aa206a' to.sha1='<NoInstanceOf_NormalFileDto>'): /3/c
10:34:21.122 [main] INFO  c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=9, fromRepositoryId="FileInProgressMarker", toRepositoryId="9", path="49ce8f5a-cbe4-4efa-b1b8-0283d3c59778"] on repo=bbd399b9-89de-405e-a3e3-e7516900766d
10:34:21.310 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/3/c'
10:34:21.310 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='2f84b4f7c306cced388d0e650411ca75b3aa206a' toSha1='null'
10:34:21.310 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/3/c'
10:34:21.310 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/3/c' offset=0
10:34:21.316 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/3/c' offset=0
10:34:21.326 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 100688 bytes in 16 ms. path='/3/c'
10:34:21.330 [main] INFO  c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_84a516841ba77a5b4648de2cd0dfcb30ea46dbb4_0 into destFile c
10:34:21.379 [main] INFO  c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=9, fromRepositoryId="FileInProgressMarker", toRepositoryId="9", path="49ce8f5a-cbe4-4efa-b1b8-0283d3c59778"] on repo=bbd399b9-89de-405e-a3e3-e7516900766d
10:34:21.391 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/3/d'
10:34:21.398 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='ae6d76e4f0c1c6e1b1c37c32b356a9b53b7e36c0' to.sha1='<NoInstanceOf_NormalFileDto>'): /3/d
10:34:21.419 [main] INFO  c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=10, fromRepositoryId="FileInProgressMarker", toRepositoryId="10", path="49ce8f5a-cbe4-4efa-b1b8-0283d3c59778"] on repo=bbd399b9-89de-405e-a3e3-e7516900766d
10:34:21.430 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/3/d'
10:34:21.430 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='ae6d76e4f0c1c6e1b1c37c32b356a9b53b7e36c0' toSha1='null'
10:34:21.430 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/3/d'
10:34:21.430 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/3/d' offset=0
10:34:21.435 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/3/d' offset=0
10:34:21.449 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 415548 bytes in 19 ms. path='/3/d'
10:34:21.454 [main] INFO  c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_3c363836cf4e16666669a25da280a1865c2d2874_0 into destFile d
10:34:21.665 [main] INFO  c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=10, fromRepositoryId="FileInProgressMarker", toRepositoryId="10", path="49ce8f5a-cbe4-4efa-b1b8-0283d3c59778"] on repo=bbd399b9-89de-405e-a3e3-e7516900766d
10:34:21.770 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 49.27%
10:34:21.788 [main] INFO  c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=49ce8f5a-cbe4-4efa-b1b8-0283d3c59778 remoteRepositoryId=bbd399b9-89de-405e-a3e3-e7516900766d localRepositoryRevisionSynced=2
10:34:21.819 [main] INFO  c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=bbd399b9-89de-405e-a3e3-e7516900766d remoteRepositoryId=49ce8f5a-cbe4-4efa-b1b8-0283d3c59778 transaction.localRevision=57 remoteFromLocalRevision=2
10:34:21.850 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncUp: fromID=bbd399b9-89de-405e-a3e3-e7516900766d from='target/repo/k1eqa7ku-uaq2u-local' toID=49ce8f5a-cbe4-4efa-b1b8-0283d3c59778 to='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/k1eqa7ku-bcngg-remote'
10:34:21.885 [main] INFO  c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=bbd399b9-89de-405e-a3e3-e7516900766d remoteRepositoryId=49ce8f5a-cbe4-4efa-b1b8-0283d3c59778 localRepositoryRevisionSynced=-1 localRepositoryRevisionInProgress=57
10:34:21.896 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 63.43%
10:34:21.896 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 74.63%
10:34:21.905 [main] INFO  c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=bbd399b9-89de-405e-a3e3-e7516900766d remoteRepositoryId=49ce8f5a-cbe4-4efa-b1b8-0283d3c59778 localRepositoryRevisionSynced=57
10:34:21.945 [main] INFO  c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=49ce8f5a-cbe4-4efa-b1b8-0283d3c59778 remoteRepositoryId=bbd399b9-89de-405e-a3e3-e7516900766d transaction.localRevision=5 remoteFromLocalRevision=57
10:34:21.952 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncDown: fromID=49ce8f5a-cbe4-4efa-b1b8-0283d3c59778 from='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/k1eqa7ku-bcngg-remote' toID=bbd399b9-89de-405e-a3e3-e7516900766d to='target/repo/k1eqa7ku-uaq2u-local', fromRepoLocalSync=false
10:34:21.991 [main] INFO  c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=49ce8f5a-cbe4-4efa-b1b8-0283d3c59778 remoteRepositoryId=bbd399b9-89de-405e-a3e3-e7516900766d localRepositoryRevisionSynced=2 localRepositoryRevisionInProgress=5
10:34:21.995 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 88.31%
10:34:21.995 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 99.50%
10:34:21.998 [main] INFO  c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=49ce8f5a-cbe4-4efa-b1b8-0283d3c59778 remoteRepositoryId=bbd399b9-89de-405e-a3e3-e7516900766d localRepositoryRevisionSynced=5
10:34:22.080 [main] INFO  c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=bbd399b9-89de-405e-a3e3-e7516900766d remoteRepositoryId=49ce8f5a-cbe4-4efa-b1b8-0283d3c59778 transaction.localRevision=60 remoteFromLocalRevision=5
10:34:22.113 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 100.00%
10:34:22.130 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [11e56861]getCloseDeferredMillis: closeDeferredMillis=20000
10:34:22.131 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [11e56861]close: Deferring shut down of real LocalRepoManager 20000 ms.
10:34:22.131 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [45bef69f]getCloseDeferredMillis: closeDeferredMillis=20000
10:34:22.131 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [45bef69f]close: Deferring shut down of real LocalRepoManager 20000 ms.
10:34:22.173 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 1.00%
10:34:22.175 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 50.00%
10:34:22.177 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 62.25%
10:34:22.180 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 68.38%
10:34:22.182 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 74.50%
10:34:22.184 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 86.75%
10:34:22.187 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 92.88%
10:34:22.194 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 99.00%
10:34:22.274 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 100.00%
10:34:22.287 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 0.50%
10:34:22.288 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncDown: fromID=49ce8f5a-cbe4-4efa-b1b8-0283d3c59778 from='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/k1eqa7ku-bcngg-remote' toID=bbd399b9-89de-405e-a3e3-e7516900766d to='target/repo/k1eqa7ku-uaq2u-local', fromRepoLocalSync=true
10:34:22.288 [pool-40-thread-1] INFO  c.c.c.c.r.s.RepoToRepoSync - sync: locally syncing bbd399b9-89de-405e-a3e3-e7516900766d ('target/repo/k1eqa7ku-uaq2u-local')
10:34:22.291 [pool-40-thread-1] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 12.94%
10:34:22.305 [pool-40-thread-1] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 19.03%
10:34:22.326 [pool-40-thread-1] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 24.11%
10:34:22.340 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 1.00%
10:34:22.341 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 50.00%
10:34:22.342 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 62.25%
10:34:22.345 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 68.38%
10:34:22.347 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 74.50%
10:34:22.349 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 86.75%
10:34:22.354 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 92.88%
10:34:22.357 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 99.00%
10:34:22.361 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 100.00%
10:34:22.950 [main] INFO  c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=49ce8f5a-cbe4-4efa-b1b8-0283d3c59778 remoteRepositoryId=bbd399b9-89de-405e-a3e3-e7516900766d localRepositoryRevisionSynced=5 localRepositoryRevisionInProgress=8
10:34:22.965 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 31.09%
10:34:22.965 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path=''
10:34:22.998 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncModifications: Deleting '/2'
10:34:23.295 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 38.56%
10:34:23.296 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 46.02%
10:34:23.313 [main] INFO  c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=49ce8f5a-cbe4-4efa-b1b8-0283d3c59778 remoteRepositoryId=bbd399b9-89de-405e-a3e3-e7516900766d localRepositoryRevisionSynced=8
10:34:23.334 [main] INFO  c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=bbd399b9-89de-405e-a3e3-e7516900766d remoteRepositoryId=49ce8f5a-cbe4-4efa-b1b8-0283d3c59778 transaction.localRevision=63 remoteFromLocalRevision=8
10:34:23.366 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncUp: fromID=bbd399b9-89de-405e-a3e3-e7516900766d from='target/repo/k1eqa7ku-uaq2u-local' toID=49ce8f5a-cbe4-4efa-b1b8-0283d3c59778 to='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/k1eqa7ku-bcngg-remote'
10:34:23.544 [main] INFO  c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=bbd399b9-89de-405e-a3e3-e7516900766d remoteRepositoryId=49ce8f5a-cbe4-4efa-b1b8-0283d3c59778 localRepositoryRevisionSynced=57 localRepositoryRevisionInProgress=63
10:34:23.553 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 52.24%
10:34:23.553 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 59.70%
10:34:23.554 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncModifications: Deleting '/2'
10:34:23.558 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 67.16%
10:34:23.558 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 74.63%
10:34:23.572 [main] INFO  c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=bbd399b9-89de-405e-a3e3-e7516900766d remoteRepositoryId=49ce8f5a-cbe4-4efa-b1b8-0283d3c59778 localRepositoryRevisionSynced=63
10:34:23.603 [main] INFO  c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=49ce8f5a-cbe4-4efa-b1b8-0283d3c59778 remoteRepositoryId=bbd399b9-89de-405e-a3e3-e7516900766d transaction.localRevision=11 remoteFromLocalRevision=63
10:34:23.614 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncDown: fromID=49ce8f5a-cbe4-4efa-b1b8-0283d3c59778 from='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/k1eqa7ku-bcngg-remote' toID=bbd399b9-89de-405e-a3e3-e7516900766d to='target/repo/k1eqa7ku-uaq2u-local', fromRepoLocalSync=false
10:34:23.634 [main] INFO  c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=49ce8f5a-cbe4-4efa-b1b8-0283d3c59778 remoteRepositoryId=bbd399b9-89de-405e-a3e3-e7516900766d localRepositoryRevisionSynced=8 localRepositoryRevisionInProgress=11
10:34:23.640 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 88.31%
10:34:23.640 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 99.50%
10:34:23.643 [main] INFO  c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=49ce8f5a-cbe4-4efa-b1b8-0283d3c59778 remoteRepositoryId=bbd399b9-89de-405e-a3e3-e7516900766d localRepositoryRevisionSynced=11
10:34:23.660 [main] INFO  c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=bbd399b9-89de-405e-a3e3-e7516900766d remoteRepositoryId=49ce8f5a-cbe4-4efa-b1b8-0283d3c59778 transaction.localRevision=66 remoteFromLocalRevision=11
10:34:23.709 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 100.00%
10:34:23.710 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [11e56861]close: Deferring shut down of real LocalRepoManager 20000 ms.
10:34:23.720 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [45bef69f]close: Deferring shut down of real LocalRepoManager 20000 ms.
Tests run: 20, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 2,368.172 sec - in co.codewizards.cloudstore.local.sync.RepoToRepoSyncTest

Results :

Tests run: 63, Failures: 0, Errors: 0, Skipped: 0

[JENKINS] Recording test results
[INFO] 
[INFO] --- maven-jar-plugin:2.4:jar (default-jar) @ co.codewizards.cloudstore.local ---
[INFO] Building jar: /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/co.codewizards.cloudstore.local-0.10.7.jar
[INFO] 
[INFO] --- maven-source-plugin:2.1.2:jar-no-fork (attach-sources) @ co.codewizards.cloudstore.local ---
[INFO] co already added, skipping
[INFO] co/codewizards already added, skipping
[INFO] co/codewizards/cloudstore already added, skipping
[INFO] co/codewizards/cloudstore/local already added, skipping
[INFO] co/codewizards/cloudstore/local/db already added, skipping
[INFO] co/codewizards/cloudstore/local/persistence already added, skipping
[INFO] co/codewizards/cloudstore/local/transport already added, skipping
[INFO] co/codewizards/cloudstore/local/dto already added, skipping
[INFO] co/codewizards/cloudstore/local/persistence/package-sqlite.orm already added, skipping
[INFO] Building jar: /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/co.codewizards.cloudstore.local-0.10.7-sources.jar
[INFO] co already added, skipping
[INFO] co/codewizards already added, skipping
[INFO] co/codewizards/cloudstore already added, skipping
[INFO] co/codewizards/cloudstore/local already added, skipping
[INFO] co/codewizards/cloudstore/local/db already added, skipping
[INFO] co/codewizards/cloudstore/local/persistence already added, skipping
[INFO] co/codewizards/cloudstore/local/transport already added, skipping
[INFO] co/codewizards/cloudstore/local/dto already added, skipping
[INFO] co/codewizards/cloudstore/local/persistence/package-sqlite.orm already added, skipping
[INFO] 
[INFO] --- maven-install-plugin:2.3.1:install (default-install) @ co.codewizards.cloudstore.local ---
[INFO] Installing /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/co.codewizards.cloudstore.local-0.10.7.jar to /home/tomcat/.m2/repository/co/codewizards/cloudstore/co.codewizards.cloudstore.local/0.10.7/co.codewizards.cloudstore.local-0.10.7.jar
[INFO] Installing /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/pom.xml to /home/tomcat/.m2/repository/co/codewizards/cloudstore/co.codewizards.cloudstore.local/0.10.7/co.codewizards.cloudstore.local-0.10.7.pom
[INFO] Installing /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/co.codewizards.cloudstore.local-0.10.7-sources.jar to /home/tomcat/.m2/repository/co/codewizards/cloudstore/co.codewizards.cloudstore.local/0.10.7/co.codewizards.cloudstore.local-0.10.7-sources.jar
[INFO] 
[INFO] --- maven-deploy-plugin:2.7:deploy (default-deploy) @ co.codewizards.cloudstore.local ---
Uploading: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/release/co/codewizards/cloudstore/co.codewizards.cloudstore.local/0.10.7/co.codewizards.cloudstore.local-0.10.7.jar
Uploaded: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/release/co/codewizards/cloudstore/co.codewizards.cloudstore.local/0.10.7/co.codewizards.cloudstore.local-0.10.7.jar (203 KB at 33787.8 KB/sec)
Uploading: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/release/co/codewizards/cloudstore/co.codewizards.cloudstore.local/0.10.7/co.codewizards.cloudstore.local-0.10.7.pom
Uploaded: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/release/co/codewizards/cloudstore/co.codewizards.cloudstore.local/0.10.7/co.codewizards.cloudstore.local-0.10.7.pom (3 KB at 2556.6 KB/sec)
Downloading: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/release/co/codewizards/cloudstore/co.codewizards.cloudstore.local/maven-metadata.xml
Downloaded: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/release/co/codewizards/cloudstore/co.codewizards.cloudstore.local/maven-metadata.xml (813 B at 11.5 KB/sec)
Uploading: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/release/co/codewizards/cloudstore/co.codewizards.cloudstore.local/maven-metadata.xml
Uploaded: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/release/co/codewizards/cloudstore/co.codewizards.cloudstore.local/maven-metadata.xml (845 B at 412.6 KB/sec)
Uploading: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/release/co/codewizards/cloudstore/co.codewizards.cloudstore.local/0.10.7/co.codewizards.cloudstore.local-0.10.7-sources.jar
Uploaded: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/release/co/codewizards/cloudstore/co.codewizards.cloudstore.local/0.10.7/co.codewizards.cloudstore.local-0.10.7-sources.jar (105 KB at 34758.8 KB/sec)
[INFO] 
[INFO] --- maven-site-plugin:3.3:site (default-site) @ co.codewizards.cloudstore.local ---
[INFO] Relativizing decoration links with respect to project URL: http://cloudstore.codewizards.co/co.codewizards.cloudstore.local
[INFO] Rendering site with org.apache.maven.skins:maven-default-skin:jar:1.0 skin.
[INFO] Relativizing decoration links with respect to project URL: http://cloudstore.codewizards.co/co.codewizards.cloudstore.local/de
[INFO] Rendering site with org.apache.maven.skins:maven-default-skin:jar:1.0 skin.
[JENKINS] Archiving site from /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/site to /home/tomcat/.jenkins/jobs/co.codewizards.cloudstore/site/co.codewizards.cloudstore.local
[WARNING] Failed to getClass for org.apache.maven.plugin.javadoc.JavadocReport
[INFO] 
[INFO] --- maven-javadoc-plugin:2.9.1:javadoc (javadoc-generate-unfiltered) @ co.codewizards.cloudstore.local ---
[INFO] 
[INFO] --- maven-resources-plugin:2.5:copy-resources (javadoc-filter) @ co.codewizards.cloudstore.local ---
[debug] execute contextualize
[INFO] Using 'UTF-8' encoding to copy filtered resources.
[INFO] skip non existing resourceDirectory /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/javadoc.unfiltered
[INFO] skip non existing resourceDirectory /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/javadoc.unfiltered
[INFO] 
[INFO] --- maven-resources-plugin:2.5:copy-resources (javadoc-copy-to-site) @ co.codewizards.cloudstore.local ---
[debug] execute contextualize
[INFO] Using 'UTF-8' encoding to copy filtered resources.
[INFO] skip non existing resourceDirectory /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/apidocs
[INFO] 
[INFO] --- maven-site-plugin:3.3:deploy (default-deploy) @ co.codewizards.cloudstore.local ---
file:/tmp/cloudstore-junk/ - Session: Opened  
[INFO] Pushing /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/site
[INFO]    >>> to file:/tmp/cloudstore-junk/co.codewizards.cloudstore.local
[INFO]    >>> to file:/tmp/cloudstore-junk/de/co.codewizards.cloudstore.local
file:/tmp/cloudstore-junk/ - Session: Disconnecting  
file:/tmp/cloudstore-junk/ - Session: Disconnected