Console Output

Skipping 701 KB.. Full Log
n] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 50.00%
15:37:59.773 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 58.00%
15:37:59.829 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 63.61%
15:37:59.849 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 74.34%
15:37:59.880 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 79.67%
15:37:59.892 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 90.67%
15:37:59.923 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 95.69%
15:37:59.950 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 100.00%
15:37:59.975 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - local repo: d1b9a6ab-90ea-4f6a-bae9-f33f26b27251
15:37:59.976 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - remote repo: 527478dd-72ba-4ee1-b39b-75375563ab6b
15:37:59.979 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 0.50%
15:37:59.980 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncDown: fromID=527478dd-72ba-4ee1-b39b-75375563ab6b from='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/k1f175js-x17of-remote/2' toID=d1b9a6ab-90ea-4f6a-bae9-f33f26b27251 to='target/repo/k1f175jr-rp0iu-local', fromRepoLocalSync=true
15:37:59.980 [pool-31-thread-1] INFO  c.c.c.c.r.s.RepoToRepoSync - sync: locally syncing d1b9a6ab-90ea-4f6a-bae9-f33f26b27251 ('target/repo/k1f175jr-rp0iu-local')
15:38:00.013 [pool-31-thread-1] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 12.94%
15:38:00.021 [pool-31-thread-1] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 25.12%
15:38:00.026 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 1.00%
15:38:00.028 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 50.00%
15:38:00.031 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 58.17%
15:38:00.039 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 63.61%
15:38:00.043 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 74.50%
15:38:00.050 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 80.62%
15:38:00.054 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 90.83%
15:38:00.058 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 95.94%
15:38:00.065 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 100.00%
15:38:00.094 [main] INFO  c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=527478dd-72ba-4ee1-b39b-75375563ab6b remoteRepositoryId=d1b9a6ab-90ea-4f6a-bae9-f33f26b27251 localRepositoryRevisionSynced=-1 localRepositoryRevisionInProgress=2
15:38:00.131 [closeDeferredTimer-5fd1a6aa-3] INFO  c.c.c.l.LocalRepoManagerImpl - [5fd1a6aa]_close: Shutting down real LocalRepoManager.
15:38:00.133 [closeDeferredTimer-4145f71-3] INFO  c.c.c.l.LocalRepoManagerImpl - [4145f71]_close: Shutting down real LocalRepoManager.
15:38:00.835 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path=''
15:38:00.871 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path='/1'
15:38:00.890 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 31.31%
15:38:00.898 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 36.58%
15:38:00.898 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 41.85%
15:38:00.898 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/1/a'
15:38:00.910 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='83972db3e2c662bdb49c98e744b25f87461cb7de' to.sha1='<NoInstanceOf_NormalFileDto>'): /1/a
15:38:01.044 [main] INFO  c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=1, fromRepositoryId="FileInProgressMarker", toRepositoryId="1", path="527478dd-72ba-4ee1-b39b-75375563ab6b"] on repo=d1b9a6ab-90ea-4f6a-bae9-f33f26b27251
15:38:01.089 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/1/a'
15:38:01.089 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='83972db3e2c662bdb49c98e744b25f87461cb7de' toSha1='null'
15:38:01.089 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/1/a'
15:38:01.090 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/1/a' offset=0
15:38:01.096 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/1/a' offset=0
15:38:01.110 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 499200 bytes in 21 ms. path='/1/a'
15:38:01.114 [main] INFO  c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_86f7e437faa5a7fce15d1ddcb9eaeaea377667b8_0 into destFile a
15:38:01.258 [main] INFO  c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=1, fromRepositoryId="FileInProgressMarker", toRepositoryId="1", path="527478dd-72ba-4ee1-b39b-75375563ab6b"] on repo=d1b9a6ab-90ea-4f6a-bae9-f33f26b27251
15:38:01.271 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/1/b'
15:38:01.286 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='231a7e962f00323d56d40c54d8e8dac19b6b8439' to.sha1='<NoInstanceOf_NormalFileDto>'): /1/b
15:38:01.479 [main] INFO  c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=2, fromRepositoryId="FileInProgressMarker", toRepositoryId="2", path="527478dd-72ba-4ee1-b39b-75375563ab6b"] on repo=d1b9a6ab-90ea-4f6a-bae9-f33f26b27251
15:38:01.486 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/1/b'
15:38:01.486 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='231a7e962f00323d56d40c54d8e8dac19b6b8439' toSha1='null'
15:38:01.487 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/1/b'
15:38:01.487 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/1/b' offset=0
15:38:01.489 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/1/b' offset=0
15:38:01.497 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 152520 bytes in 10 ms. path='/1/b'
15:38:01.501 [main] INFO  c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_e9d71f5ee7c92d6dc9e92ffdad17b8bd49418f98_0 into destFile b
15:38:01.574 [main] INFO  c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=2, fromRepositoryId="FileInProgressMarker", toRepositoryId="2", path="527478dd-72ba-4ee1-b39b-75375563ab6b"] on repo=d1b9a6ab-90ea-4f6a-bae9-f33f26b27251
15:38:01.578 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/a'
15:38:01.586 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='09c5941543b63dff9940efe744f7df7b93a77018' to.sha1='<NoInstanceOf_NormalFileDto>'): /a
15:38:01.653 [main] INFO  c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=3, fromRepositoryId="FileInProgressMarker", toRepositoryId="3", path="527478dd-72ba-4ee1-b39b-75375563ab6b"] on repo=d1b9a6ab-90ea-4f6a-bae9-f33f26b27251
15:38:01.666 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/a'
15:38:01.666 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='09c5941543b63dff9940efe744f7df7b93a77018' toSha1='null'
15:38:01.666 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/a'
15:38:01.666 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/a' offset=0
15:38:01.668 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/a' offset=0
15:38:01.679 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 119168 bytes in 13 ms. path='/a'
15:38:01.685 [main] INFO  c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_86f7e437faa5a7fce15d1ddcb9eaeaea377667b8_0 into destFile a
15:38:01.706 [main] INFO  c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=3, fromRepositoryId="FileInProgressMarker", toRepositoryId="3", path="527478dd-72ba-4ee1-b39b-75375563ab6b"] on repo=d1b9a6ab-90ea-4f6a-bae9-f33f26b27251
15:38:01.718 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 48.43%
15:38:01.735 [main] INFO  c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=527478dd-72ba-4ee1-b39b-75375563ab6b remoteRepositoryId=d1b9a6ab-90ea-4f6a-bae9-f33f26b27251 localRepositoryRevisionSynced=2
15:38:01.789 [main] INFO  c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=d1b9a6ab-90ea-4f6a-bae9-f33f26b27251 remoteRepositoryId=527478dd-72ba-4ee1-b39b-75375563ab6b transaction.localRevision=19 remoteFromLocalRevision=2
15:38:01.861 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncUp: fromID=d1b9a6ab-90ea-4f6a-bae9-f33f26b27251 from='target/repo/k1f175jr-rp0iu-local' toID=527478dd-72ba-4ee1-b39b-75375563ab6b to='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/k1f175js-x17of-remote/2'
15:38:01.900 [main] INFO  c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=d1b9a6ab-90ea-4f6a-bae9-f33f26b27251 remoteRepositoryId=527478dd-72ba-4ee1-b39b-75375563ab6b localRepositoryRevisionSynced=-1 localRepositoryRevisionInProgress=19
15:38:01.914 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 63.43%
15:38:01.914 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 74.63%
15:38:01.927 [main] INFO  c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=d1b9a6ab-90ea-4f6a-bae9-f33f26b27251 remoteRepositoryId=527478dd-72ba-4ee1-b39b-75375563ab6b localRepositoryRevisionSynced=19
15:38:01.945 [main] INFO  c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=527478dd-72ba-4ee1-b39b-75375563ab6b remoteRepositoryId=d1b9a6ab-90ea-4f6a-bae9-f33f26b27251 transaction.localRevision=5 remoteFromLocalRevision=19
15:38:01.952 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncDown: fromID=527478dd-72ba-4ee1-b39b-75375563ab6b from='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/k1f175js-x17of-remote/2' toID=d1b9a6ab-90ea-4f6a-bae9-f33f26b27251 to='target/repo/k1f175jr-rp0iu-local', fromRepoLocalSync=false
15:38:02.024 [main] INFO  c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=527478dd-72ba-4ee1-b39b-75375563ab6b remoteRepositoryId=d1b9a6ab-90ea-4f6a-bae9-f33f26b27251 localRepositoryRevisionSynced=2 localRepositoryRevisionInProgress=5
15:38:02.032 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 88.31%
15:38:02.032 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 99.50%
15:38:02.036 [main] INFO  c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=527478dd-72ba-4ee1-b39b-75375563ab6b remoteRepositoryId=d1b9a6ab-90ea-4f6a-bae9-f33f26b27251 localRepositoryRevisionSynced=5
15:38:02.057 [main] INFO  c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=d1b9a6ab-90ea-4f6a-bae9-f33f26b27251 remoteRepositoryId=527478dd-72ba-4ee1-b39b-75375563ab6b transaction.localRevision=22 remoteFromLocalRevision=5
15:38:02.116 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 100.00%
15:38:02.134 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [7902ca46]getCloseDeferredMillis: closeDeferredMillis=20000
15:38:02.134 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [7902ca46]close: Deferring shut down of real LocalRepoManager 20000 ms.
15:38:02.135 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [49497057]getCloseDeferredMillis: closeDeferredMillis=20000
15:38:02.135 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [49497057]close: Deferring shut down of real LocalRepoManager 20000 ms.
15:38:02.159 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 1.00%
15:38:02.160 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 50.00%
15:38:02.162 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 58.17%
15:38:02.165 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 63.61%
15:38:02.169 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 74.50%
15:38:02.193 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 80.62%
15:38:02.196 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 90.83%
15:38:02.204 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 96.55%
15:38:02.229 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 100.00%
15:38:02.263 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 0.50%
15:38:02.263 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncDown: fromID=527478dd-72ba-4ee1-b39b-75375563ab6b from='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/k1f175js-x17of-remote/2' toID=d1b9a6ab-90ea-4f6a-bae9-f33f26b27251 to='target/repo/k1f175jr-rp0iu-local', fromRepoLocalSync=true
15:38:02.264 [pool-32-thread-1] INFO  c.c.c.c.r.s.RepoToRepoSync - sync: locally syncing d1b9a6ab-90ea-4f6a-bae9-f33f26b27251 ('target/repo/k1f175jr-rp0iu-local')
15:38:02.276 [pool-32-thread-1] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 12.94%
15:38:02.287 [pool-32-thread-1] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 18.27%
15:38:02.298 [pool-32-thread-1] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 25.12%
15:38:02.305 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 1.00%
15:38:02.308 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 50.00%
15:38:02.312 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 58.17%
15:38:02.319 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 63.61%
15:38:02.323 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 74.50%
15:38:02.336 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 80.62%
15:38:02.340 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 90.83%
15:38:02.347 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 96.55%
15:38:02.356 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 100.00%
15:38:02.430 [main] INFO  c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=527478dd-72ba-4ee1-b39b-75375563ab6b remoteRepositoryId=d1b9a6ab-90ea-4f6a-bae9-f33f26b27251 localRepositoryRevisionSynced=5 localRepositoryRevisionInProgress=8
15:38:02.451 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path='/1'
15:38:02.470 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 31.31%
15:38:02.475 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path='/1/5'
15:38:02.490 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 36.58%
15:38:02.490 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 41.85%
15:38:02.491 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/1/5/aaa'
15:38:02.499 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='68917b3d8196f678acb398050dcfaa8f76041578' to.sha1='<NoInstanceOf_NormalFileDto>'): /1/5/aaa
15:38:02.512 [main] INFO  c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=4, fromRepositoryId="FileInProgressMarker", toRepositoryId="4", path="527478dd-72ba-4ee1-b39b-75375563ab6b"] on repo=d1b9a6ab-90ea-4f6a-bae9-f33f26b27251
15:38:02.513 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/1/5/aaa'
15:38:02.513 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='68917b3d8196f678acb398050dcfaa8f76041578' toSha1='null'
15:38:02.513 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/1/5/aaa'
15:38:02.514 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/1/5/aaa' offset=0
15:38:02.516 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/1/5/aaa' offset=0
15:38:02.523 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 165444 bytes in 9 ms. path='/1/5/aaa'
15:38:02.527 [main] INFO  c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_7e240de74fb1ed08fa08d38063f6a6a91462a815_0 into destFile aaa
15:38:02.543 [main] INFO  c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=4, fromRepositoryId="FileInProgressMarker", toRepositoryId="4", path="527478dd-72ba-4ee1-b39b-75375563ab6b"] on repo=d1b9a6ab-90ea-4f6a-bae9-f33f26b27251
15:38:02.546 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/1/5/bbb'
15:38:02.556 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='bc2b05fa108f21a8f0dafdcebf7e0f3367c8879a' to.sha1='<NoInstanceOf_NormalFileDto>'): /1/5/bbb
15:38:02.569 [main] INFO  c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=5, fromRepositoryId="FileInProgressMarker", toRepositoryId="5", path="527478dd-72ba-4ee1-b39b-75375563ab6b"] on repo=d1b9a6ab-90ea-4f6a-bae9-f33f26b27251
15:38:02.570 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/1/5/bbb'
15:38:02.571 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='bc2b05fa108f21a8f0dafdcebf7e0f3367c8879a' toSha1='null'
15:38:02.571 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/1/5/bbb'
15:38:02.571 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/1/5/bbb' offset=0
15:38:02.571 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/1/5/bbb' offset=0
15:38:02.577 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 6608 bytes in 6 ms. path='/1/5/bbb'
15:38:02.582 [main] INFO  c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_5cb138284d431abd6a053a56625ec088bfb88912_0 into destFile bbb
15:38:02.594 [main] INFO  c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=5, fromRepositoryId="FileInProgressMarker", toRepositoryId="5", path="527478dd-72ba-4ee1-b39b-75375563ab6b"] on repo=d1b9a6ab-90ea-4f6a-bae9-f33f26b27251
15:38:02.598 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 48.43%
15:38:02.602 [main] INFO  c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=527478dd-72ba-4ee1-b39b-75375563ab6b remoteRepositoryId=d1b9a6ab-90ea-4f6a-bae9-f33f26b27251 localRepositoryRevisionSynced=8
15:38:02.609 [main] INFO  c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=d1b9a6ab-90ea-4f6a-bae9-f33f26b27251 remoteRepositoryId=527478dd-72ba-4ee1-b39b-75375563ab6b transaction.localRevision=35 remoteFromLocalRevision=8
15:38:02.612 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncUp: fromID=d1b9a6ab-90ea-4f6a-bae9-f33f26b27251 from='target/repo/k1f175jr-rp0iu-local' toID=527478dd-72ba-4ee1-b39b-75375563ab6b to='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/k1f175js-x17of-remote/2'
15:38:02.622 [main] INFO  c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=d1b9a6ab-90ea-4f6a-bae9-f33f26b27251 remoteRepositoryId=527478dd-72ba-4ee1-b39b-75375563ab6b localRepositoryRevisionSynced=19 localRepositoryRevisionInProgress=35
15:38:02.624 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 63.43%
15:38:02.624 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 74.63%
15:38:02.628 [main] INFO  c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=d1b9a6ab-90ea-4f6a-bae9-f33f26b27251 remoteRepositoryId=527478dd-72ba-4ee1-b39b-75375563ab6b localRepositoryRevisionSynced=35
15:38:02.631 [main] INFO  c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=527478dd-72ba-4ee1-b39b-75375563ab6b remoteRepositoryId=d1b9a6ab-90ea-4f6a-bae9-f33f26b27251 transaction.localRevision=11 remoteFromLocalRevision=35
15:38:02.636 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncDown: fromID=527478dd-72ba-4ee1-b39b-75375563ab6b from='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/k1f175js-x17of-remote/2' toID=d1b9a6ab-90ea-4f6a-bae9-f33f26b27251 to='target/repo/k1f175jr-rp0iu-local', fromRepoLocalSync=false
15:38:02.651 [main] INFO  c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=527478dd-72ba-4ee1-b39b-75375563ab6b remoteRepositoryId=d1b9a6ab-90ea-4f6a-bae9-f33f26b27251 localRepositoryRevisionSynced=8 localRepositoryRevisionInProgress=11
15:38:02.657 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 88.31%
15:38:02.657 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 99.50%
15:38:02.661 [main] INFO  c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=527478dd-72ba-4ee1-b39b-75375563ab6b remoteRepositoryId=d1b9a6ab-90ea-4f6a-bae9-f33f26b27251 localRepositoryRevisionSynced=11
15:38:02.669 [main] INFO  c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=d1b9a6ab-90ea-4f6a-bae9-f33f26b27251 remoteRepositoryId=527478dd-72ba-4ee1-b39b-75375563ab6b transaction.localRevision=38 remoteFromLocalRevision=11
15:38:02.670 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 100.00%
15:38:02.670 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [7902ca46]close: Deferring shut down of real LocalRepoManager 20000 ms.
15:38:02.686 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [49497057]close: Deferring shut down of real LocalRepoManager 20000 ms.
15:38:02.708 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [54874e6]<init>: localRoot='/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/k1f17f4j-o74ue-local'
15:38:02.715 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [54874e6]initLockFile: Repository '/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/k1f17f4j-o74ue-local' locked successfully.
15:38:07.503 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [54874e6]initPersistenceManagerFactory: repositoryId=f58df774-7660-4a7d-a07a-1dec8fadc086
15:38:07.510 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [54874e6]initPersistenceManagerFactory: Started up PersistenceManagerFactory successfully in 2678 ms.
15:38:07.534 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [4115a14a]<init>: localRoot='/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/k1f17f4j-94zj1-remote'
15:38:07.539 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [4115a14a]initLockFile: Repository '/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/k1f17f4j-94zj1-remote' locked successfully.
15:38:10.284 [closeDeferredTimer-6c092002-1] INFO  c.c.c.l.LocalRepoManagerImpl - [6c092002]_close: Shutting down real LocalRepoManager.
15:38:10.284 [closeDeferredTimer-792318c8-1] INFO  c.c.c.l.LocalRepoManagerImpl - [792318c8]_close: Shutting down real LocalRepoManager.
15:38:13.352 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [4115a14a]initPersistenceManagerFactory: repositoryId=768f13d5-82bb-4ae0-9df6-d32b8b386322
15:38:13.358 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [4115a14a]initPersistenceManagerFactory: Started up PersistenceManagerFactory successfully in 3662 ms.
15:38:13.464 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 1.00%
15:38:13.470 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 50.00%
15:38:13.480 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 58.00%
15:38:13.528 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 63.61%
15:38:13.544 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 74.34%
15:38:13.574 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 79.52%
15:38:13.588 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 90.67%
15:38:13.613 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 95.79%
15:38:13.634 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 100.00%
15:38:13.658 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - local repo: f58df774-7660-4a7d-a07a-1dec8fadc086
15:38:13.658 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - remote repo: 768f13d5-82bb-4ae0-9df6-d32b8b386322
15:38:13.663 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 0.50%
15:38:13.663 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncDown: fromID=768f13d5-82bb-4ae0-9df6-d32b8b386322 from='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/k1f17f4j-94zj1-remote' toID=f58df774-7660-4a7d-a07a-1dec8fadc086 to='target/repo/k1f17f4j-o74ue-local', fromRepoLocalSync=true
15:38:13.664 [pool-33-thread-1] INFO  c.c.c.c.r.s.RepoToRepoSync - sync: locally syncing f58df774-7660-4a7d-a07a-1dec8fadc086 ('target/repo/k1f17f4j-o74ue-local')
15:38:13.702 [pool-33-thread-1] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 12.94%
15:38:13.708 [pool-33-thread-1] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 25.12%
15:38:13.709 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 1.00%
15:38:13.710 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 50.00%
15:38:13.711 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 58.17%
15:38:13.716 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 63.61%
15:38:13.720 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 74.50%
15:38:13.727 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 80.62%
15:38:13.730 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 90.83%
15:38:13.735 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 95.94%
15:38:13.742 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 100.00%
15:38:13.767 [main] INFO  c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=768f13d5-82bb-4ae0-9df6-d32b8b386322 remoteRepositoryId=f58df774-7660-4a7d-a07a-1dec8fadc086 localRepositoryRevisionSynced=-1 localRepositoryRevisionInProgress=2
15:38:13.799 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path=''
15:38:13.826 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path='/1'
15:38:13.842 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 30.22%
15:38:13.842 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path='/2'
15:38:13.857 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path='/2/1'
15:38:13.875 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path='/3'
15:38:13.895 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 35.46%
15:38:13.895 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 40.70%
15:38:13.896 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/1/a'
15:38:13.908 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='80bde8ec005df793e0cd43f4d24a09b8604454eb' to.sha1='<NoInstanceOf_NormalFileDto>'): /1/a
15:38:13.967 [main] INFO  c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=1, fromRepositoryId="FileInProgressMarker", toRepositoryId="1", path="768f13d5-82bb-4ae0-9df6-d32b8b386322"] on repo=f58df774-7660-4a7d-a07a-1dec8fadc086
15:38:13.972 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/1/a'
15:38:13.973 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='80bde8ec005df793e0cd43f4d24a09b8604454eb' toSha1='null'
15:38:13.973 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/1/a'
15:38:13.973 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/1/a' offset=0
15:38:13.974 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/1/a' offset=0
15:38:13.982 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 94710 bytes in 9 ms. path='/1/a'
15:38:13.986 [main] INFO  c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_86f7e437faa5a7fce15d1ddcb9eaeaea377667b8_0 into destFile a
15:38:14.098 [main] INFO  c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=1, fromRepositoryId="FileInProgressMarker", toRepositoryId="1", path="768f13d5-82bb-4ae0-9df6-d32b8b386322"] on repo=f58df774-7660-4a7d-a07a-1dec8fadc086
15:38:14.127 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/1/b'
15:38:14.141 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='a09da237f65181bbdebc8d6dee9e9d77bb51a304' to.sha1='<NoInstanceOf_NormalFileDto>'): /1/b
15:38:14.161 [main] INFO  c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=2, fromRepositoryId="FileInProgressMarker", toRepositoryId="2", path="768f13d5-82bb-4ae0-9df6-d32b8b386322"] on repo=f58df774-7660-4a7d-a07a-1dec8fadc086
15:38:14.167 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/1/b'
15:38:14.167 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='a09da237f65181bbdebc8d6dee9e9d77bb51a304' toSha1='null'
15:38:14.167 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/1/b'
15:38:14.167 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/1/b' offset=0
15:38:14.167 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/1/b' offset=0
15:38:14.172 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 19089 bytes in 5 ms. path='/1/b'
15:38:14.179 [main] INFO  c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_e9d71f5ee7c92d6dc9e92ffdad17b8bd49418f98_0 into destFile b
15:38:14.194 [main] INFO  c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=2, fromRepositoryId="FileInProgressMarker", toRepositoryId="2", path="768f13d5-82bb-4ae0-9df6-d32b8b386322"] on repo=f58df774-7660-4a7d-a07a-1dec8fadc086
15:38:14.202 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/1/c'
15:38:14.216 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='1212fe9a6d5a4b2d76566d25f60db6fe11b332ad' to.sha1='<NoInstanceOf_NormalFileDto>'): /1/c
15:38:14.237 [main] INFO  c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=3, fromRepositoryId="FileInProgressMarker", toRepositoryId="3", path="768f13d5-82bb-4ae0-9df6-d32b8b386322"] on repo=f58df774-7660-4a7d-a07a-1dec8fadc086
15:38:14.244 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/1/c'
15:38:14.244 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='1212fe9a6d5a4b2d76566d25f60db6fe11b332ad' toSha1='null'
15:38:14.244 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/1/c'
15:38:14.244 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/1/c' offset=0
15:38:14.254 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/1/c' offset=0
15:38:14.266 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 532146 bytes in 22 ms. path='/1/c'
15:38:14.270 [main] INFO  c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_84a516841ba77a5b4648de2cd0dfcb30ea46dbb4_0 into destFile c
15:38:14.359 [main] INFO  c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=3, fromRepositoryId="FileInProgressMarker", toRepositoryId="3", path="768f13d5-82bb-4ae0-9df6-d32b8b386322"] on repo=f58df774-7660-4a7d-a07a-1dec8fadc086
15:38:14.368 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/2/1/a'
15:38:14.382 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='8f9fd87fbe968c0713695d2f2d99141ca3cb1554' to.sha1='<NoInstanceOf_NormalFileDto>'): /2/1/a
15:38:14.399 [main] INFO  c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=4, fromRepositoryId="FileInProgressMarker", toRepositoryId="4", path="768f13d5-82bb-4ae0-9df6-d32b8b386322"] on repo=f58df774-7660-4a7d-a07a-1dec8fadc086
15:38:14.404 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/2/1/a'
15:38:14.404 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='8f9fd87fbe968c0713695d2f2d99141ca3cb1554' toSha1='null'
15:38:14.404 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/2/1/a'
15:38:14.404 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/2/1/a' offset=0
15:38:14.408 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/2/1/a' offset=0
15:38:14.419 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 264700 bytes in 15 ms. path='/2/1/a'
15:38:14.423 [main] INFO  c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_86f7e437faa5a7fce15d1ddcb9eaeaea377667b8_0 into destFile a
15:38:14.447 [main] INFO  c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=4, fromRepositoryId="FileInProgressMarker", toRepositoryId="4", path="768f13d5-82bb-4ae0-9df6-d32b8b386322"] on repo=f58df774-7660-4a7d-a07a-1dec8fadc086
15:38:14.457 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/2/1/b'
15:38:14.469 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='99edfe332a710c2e3621a4572b67bb1c39ac3d53' to.sha1='<NoInstanceOf_NormalFileDto>'): /2/1/b
15:38:14.487 [main] INFO  c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=5, fromRepositoryId="FileInProgressMarker", toRepositoryId="5", path="768f13d5-82bb-4ae0-9df6-d32b8b386322"] on repo=f58df774-7660-4a7d-a07a-1dec8fadc086
15:38:14.493 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/2/1/b'
15:38:14.493 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='99edfe332a710c2e3621a4572b67bb1c39ac3d53' toSha1='null'
15:38:14.493 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/2/1/b'
15:38:14.493 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/2/1/b' offset=0
15:38:14.499 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/2/1/b' offset=0
15:38:14.513 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 308525 bytes in 20 ms. path='/2/1/b'
15:38:14.517 [main] INFO  c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_e9d71f5ee7c92d6dc9e92ffdad17b8bd49418f98_0 into destFile b
15:38:14.603 [main] INFO  c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=5, fromRepositoryId="FileInProgressMarker", toRepositoryId="5", path="768f13d5-82bb-4ae0-9df6-d32b8b386322"] on repo=f58df774-7660-4a7d-a07a-1dec8fadc086
15:38:14.611 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/2/a'
15:38:14.619 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='ae54c8d31a11b4b672d9a4b96d39f2bf4cb3e427' to.sha1='<NoInstanceOf_NormalFileDto>'): /2/a
15:38:14.638 [main] INFO  c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=6, fromRepositoryId="FileInProgressMarker", toRepositoryId="6", path="768f13d5-82bb-4ae0-9df6-d32b8b386322"] on repo=f58df774-7660-4a7d-a07a-1dec8fadc086
15:38:14.643 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/2/a'
15:38:14.643 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='ae54c8d31a11b4b672d9a4b96d39f2bf4cb3e427' toSha1='null'
15:38:14.643 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/2/a'
15:38:14.643 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/2/a' offset=0
15:38:14.644 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/2/a' offset=0
15:38:14.654 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 69024 bytes in 11 ms. path='/2/a'
15:38:14.657 [main] INFO  c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_86f7e437faa5a7fce15d1ddcb9eaeaea377667b8_0 into destFile a
15:38:14.671 [main] INFO  c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=6, fromRepositoryId="FileInProgressMarker", toRepositoryId="6", path="768f13d5-82bb-4ae0-9df6-d32b8b386322"] on repo=f58df774-7660-4a7d-a07a-1dec8fadc086
15:38:14.679 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/3/a'
15:38:14.690 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='5a581e3b88e954967ba3c09095d0d7270b354e64' to.sha1='<NoInstanceOf_NormalFileDto>'): /3/a
15:38:14.704 [main] INFO  c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=7, fromRepositoryId="FileInProgressMarker", toRepositoryId="7", path="768f13d5-82bb-4ae0-9df6-d32b8b386322"] on repo=f58df774-7660-4a7d-a07a-1dec8fadc086
15:38:14.710 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/3/a'
15:38:14.710 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='5a581e3b88e954967ba3c09095d0d7270b354e64' toSha1='null'
15:38:14.710 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/3/a'
15:38:14.710 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/3/a' offset=0
15:38:14.711 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/3/a' offset=0
15:38:14.721 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 8296 bytes in 11 ms. path='/3/a'
15:38:14.726 [main] INFO  c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_86f7e437faa5a7fce15d1ddcb9eaeaea377667b8_0 into destFile a
15:38:14.739 [main] INFO  c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=7, fromRepositoryId="FileInProgressMarker", toRepositoryId="7", path="768f13d5-82bb-4ae0-9df6-d32b8b386322"] on repo=f58df774-7660-4a7d-a07a-1dec8fadc086
15:38:14.749 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/3/b'
15:38:14.762 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='816bc30515f5f62c0899f064171b07f4928abf98' to.sha1='<NoInstanceOf_NormalFileDto>'): /3/b
15:38:14.779 [main] INFO  c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=8, fromRepositoryId="FileInProgressMarker", toRepositoryId="8", path="768f13d5-82bb-4ae0-9df6-d32b8b386322"] on repo=f58df774-7660-4a7d-a07a-1dec8fadc086
15:38:14.786 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/3/b'
15:38:14.786 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='816bc30515f5f62c0899f064171b07f4928abf98' toSha1='null'
15:38:14.786 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/3/b'
15:38:14.786 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/3/b' offset=0
15:38:14.791 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/3/b' offset=0
15:38:14.800 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 232364 bytes in 14 ms. path='/3/b'
15:38:14.803 [main] INFO  c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_e9d71f5ee7c92d6dc9e92ffdad17b8bd49418f98_0 into destFile b
15:38:14.826 [main] INFO  c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=8, fromRepositoryId="FileInProgressMarker", toRepositoryId="8", path="768f13d5-82bb-4ae0-9df6-d32b8b386322"] on repo=f58df774-7660-4a7d-a07a-1dec8fadc086
15:38:14.834 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/3/c'
15:38:14.844 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='0f7e4f7c2ef979f939f443828bd426e2d7a5f84b' to.sha1='<NoInstanceOf_NormalFileDto>'): /3/c
15:38:14.859 [main] INFO  c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=9, fromRepositoryId="FileInProgressMarker", toRepositoryId="9", path="768f13d5-82bb-4ae0-9df6-d32b8b386322"] on repo=f58df774-7660-4a7d-a07a-1dec8fadc086
15:38:14.866 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/3/c'
15:38:14.866 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='0f7e4f7c2ef979f939f443828bd426e2d7a5f84b' toSha1='null'
15:38:14.866 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/3/c'
15:38:14.866 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/3/c' offset=0
15:38:14.868 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/3/c' offset=0
15:38:14.874 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 211389 bytes in 8 ms. path='/3/c'
15:38:14.878 [main] INFO  c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_84a516841ba77a5b4648de2cd0dfcb30ea46dbb4_0 into destFile c
15:38:14.897 [main] INFO  c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=9, fromRepositoryId="FileInProgressMarker", toRepositoryId="9", path="768f13d5-82bb-4ae0-9df6-d32b8b386322"] on repo=f58df774-7660-4a7d-a07a-1dec8fadc086
15:38:14.904 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/3/d'
15:38:14.911 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='02820691505a53271e1066bd0cec6e78e21d7726' to.sha1='<NoInstanceOf_NormalFileDto>'): /3/d
15:38:14.926 [main] INFO  c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=10, fromRepositoryId="FileInProgressMarker", toRepositoryId="10", path="768f13d5-82bb-4ae0-9df6-d32b8b386322"] on repo=f58df774-7660-4a7d-a07a-1dec8fadc086
15:38:14.932 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/3/d'
15:38:14.932 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='02820691505a53271e1066bd0cec6e78e21d7726' toSha1='null'
15:38:14.932 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/3/d'
15:38:14.932 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/3/d' offset=0
15:38:14.934 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/3/d' offset=0
15:38:14.949 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 186257 bytes in 17 ms. path='/3/d'
15:38:14.954 [main] INFO  c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_3c363836cf4e16666669a25da280a1865c2d2874_0 into destFile d
15:38:14.975 [main] INFO  c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=10, fromRepositoryId="FileInProgressMarker", toRepositoryId="10", path="768f13d5-82bb-4ae0-9df6-d32b8b386322"] on repo=f58df774-7660-4a7d-a07a-1dec8fadc086
15:38:14.983 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 49.27%
15:38:14.999 [main] INFO  c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=768f13d5-82bb-4ae0-9df6-d32b8b386322 remoteRepositoryId=f58df774-7660-4a7d-a07a-1dec8fadc086 localRepositoryRevisionSynced=2
15:38:15.010 [main] INFO  c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=f58df774-7660-4a7d-a07a-1dec8fadc086 remoteRepositoryId=768f13d5-82bb-4ae0-9df6-d32b8b386322 transaction.localRevision=57 remoteFromLocalRevision=2
15:38:15.020 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncUp: fromID=f58df774-7660-4a7d-a07a-1dec8fadc086 from='target/repo/k1f17f4j-o74ue-local' toID=768f13d5-82bb-4ae0-9df6-d32b8b386322 to='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/k1f17f4j-94zj1-remote'
15:38:15.047 [main] INFO  c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=f58df774-7660-4a7d-a07a-1dec8fadc086 remoteRepositoryId=768f13d5-82bb-4ae0-9df6-d32b8b386322 localRepositoryRevisionSynced=-1 localRepositoryRevisionInProgress=57
15:38:15.064 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 63.43%
15:38:15.064 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 74.63%
15:38:15.077 [main] INFO  c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=f58df774-7660-4a7d-a07a-1dec8fadc086 remoteRepositoryId=768f13d5-82bb-4ae0-9df6-d32b8b386322 localRepositoryRevisionSynced=57
15:38:15.089 [main] INFO  c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=768f13d5-82bb-4ae0-9df6-d32b8b386322 remoteRepositoryId=f58df774-7660-4a7d-a07a-1dec8fadc086 transaction.localRevision=5 remoteFromLocalRevision=57
15:38:15.094 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncDown: fromID=768f13d5-82bb-4ae0-9df6-d32b8b386322 from='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/k1f17f4j-94zj1-remote' toID=f58df774-7660-4a7d-a07a-1dec8fadc086 to='target/repo/k1f17f4j-o74ue-local', fromRepoLocalSync=false
15:38:15.106 [main] INFO  c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=768f13d5-82bb-4ae0-9df6-d32b8b386322 remoteRepositoryId=f58df774-7660-4a7d-a07a-1dec8fadc086 localRepositoryRevisionSynced=2 localRepositoryRevisionInProgress=5
15:38:15.108 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 88.31%
15:38:15.109 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 99.50%
15:38:15.114 [main] INFO  c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=768f13d5-82bb-4ae0-9df6-d32b8b386322 remoteRepositoryId=f58df774-7660-4a7d-a07a-1dec8fadc086 localRepositoryRevisionSynced=5
15:38:15.123 [main] INFO  c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=f58df774-7660-4a7d-a07a-1dec8fadc086 remoteRepositoryId=768f13d5-82bb-4ae0-9df6-d32b8b386322 transaction.localRevision=60 remoteFromLocalRevision=5
15:38:15.129 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 100.00%
15:38:15.148 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [54874e6]getCloseDeferredMillis: closeDeferredMillis=20000
15:38:15.148 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [54874e6]close: Deferring shut down of real LocalRepoManager 20000 ms.
15:38:15.149 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [4115a14a]getCloseDeferredMillis: closeDeferredMillis=20000
15:38:15.149 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [4115a14a]close: Deferring shut down of real LocalRepoManager 20000 ms.
15:38:15.192 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 1.00%
15:38:15.193 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 50.00%
15:38:15.195 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 58.17%
15:38:15.198 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 63.61%
15:38:15.203 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 74.50%
15:38:15.239 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 80.62%
15:38:15.244 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 90.83%
15:38:15.253 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 96.55%
15:38:15.271 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 100.00%
15:38:15.297 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 0.50%
15:38:15.298 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncDown: fromID=768f13d5-82bb-4ae0-9df6-d32b8b386322 from='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/k1f17f4j-94zj1-remote' toID=f58df774-7660-4a7d-a07a-1dec8fadc086 to='target/repo/k1f17f4j-o74ue-local', fromRepoLocalSync=true
15:38:15.299 [pool-34-thread-1] INFO  c.c.c.c.r.s.RepoToRepoSync - sync: locally syncing f58df774-7660-4a7d-a07a-1dec8fadc086 ('target/repo/k1f17f4j-o74ue-local')
15:38:15.305 [pool-34-thread-1] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 12.94%
15:38:15.324 [pool-34-thread-1] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 19.03%
15:38:15.343 [pool-34-thread-1] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 24.11%
15:38:15.357 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 1.00%
15:38:15.358 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 50.00%
15:38:15.359 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 58.17%
15:38:15.372 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 63.61%
15:38:15.376 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 74.50%
15:38:15.390 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 80.62%
15:38:15.394 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 90.83%
15:38:15.399 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 96.55%
15:38:15.408 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 100.00%
15:38:15.417 [main] INFO  c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=768f13d5-82bb-4ae0-9df6-d32b8b386322 remoteRepositoryId=f58df774-7660-4a7d-a07a-1dec8fadc086 localRepositoryRevisionSynced=5 localRepositoryRevisionInProgress=8
15:38:15.433 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 29.95%
15:38:15.434 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path='/2/1'
15:38:15.454 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path='/2/1/5'
15:38:15.475 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path='/3'
15:38:15.490 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 35.11%
15:38:15.491 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 40.28%
15:38:15.491 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 45.45%
15:38:15.492 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/2/1/5/aaa'
15:38:15.508 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='9e4d952468f991e4c7bd464d5a75609f010c4d02' to.sha1='<NoInstanceOf_NormalFileDto>'): /2/1/5/aaa
15:38:15.526 [main] INFO  c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=11, fromRepositoryId="FileInProgressMarker", toRepositoryId="11", path="768f13d5-82bb-4ae0-9df6-d32b8b386322"] on repo=f58df774-7660-4a7d-a07a-1dec8fadc086
15:38:15.532 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/2/1/5/aaa'
15:38:15.532 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='9e4d952468f991e4c7bd464d5a75609f010c4d02' toSha1='null'
15:38:15.532 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/2/1/5/aaa'
15:38:15.532 [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
15:38:15.542 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/2/1/5/aaa' offset=0
15:38:15.553 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 435332 bytes in 21 ms. path='/2/1/5/aaa'
15:38:15.558 [main] INFO  c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_7e240de74fb1ed08fa08d38063f6a6a91462a815_0 into destFile aaa
15:38:15.644 [main] INFO  c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=11, fromRepositoryId="FileInProgressMarker", toRepositoryId="11", path="768f13d5-82bb-4ae0-9df6-d32b8b386322"] on repo=f58df774-7660-4a7d-a07a-1dec8fadc086
15:38:15.654 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/2/1/5/bbb'
15:38:15.669 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='17adfe901a578f6aee65cfb71a64cca3d3c922c5' to.sha1='<NoInstanceOf_NormalFileDto>'): /2/1/5/bbb
15:38:15.693 [main] INFO  c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=12, fromRepositoryId="FileInProgressMarker", toRepositoryId="12", path="768f13d5-82bb-4ae0-9df6-d32b8b386322"] on repo=f58df774-7660-4a7d-a07a-1dec8fadc086
15:38:15.699 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/2/1/5/bbb'
15:38:15.699 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='17adfe901a578f6aee65cfb71a64cca3d3c922c5' toSha1='null'
15:38:15.699 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/2/1/5/bbb'
15:38:15.699 [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
15:38:15.702 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/2/1/5/bbb' offset=0
15:38:15.712 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 80730 bytes in 13 ms. path='/2/1/5/bbb'
15:38:15.717 [main] INFO  c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_5cb138284d431abd6a053a56625ec088bfb88912_0 into destFile bbb
15:38:15.733 [main] INFO  c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=12, fromRepositoryId="FileInProgressMarker", toRepositoryId="12", path="768f13d5-82bb-4ae0-9df6-d32b8b386322"] on repo=f58df774-7660-4a7d-a07a-1dec8fadc086
15:38:15.742 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/3/e'
15:38:15.754 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='852f12a3743af6c663ba20b1c2af56c92293feb2' to.sha1='<NoInstanceOf_NormalFileDto>'): /3/e
15:38:15.771 [main] INFO  c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=13, fromRepositoryId="FileInProgressMarker", toRepositoryId="13", path="768f13d5-82bb-4ae0-9df6-d32b8b386322"] on repo=f58df774-7660-4a7d-a07a-1dec8fadc086
15:38:15.777 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/3/e'
15:38:15.777 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='852f12a3743af6c663ba20b1c2af56c92293feb2' toSha1='null'
15:38:15.777 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/3/e'
15:38:15.777 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/3/e' offset=0
15:38:15.778 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/3/e' offset=0
15:38:15.785 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 3696 bytes in 8 ms. path='/3/e'
15:38:15.795 [main] INFO  c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_58e6b3a414a1e090dfc6029add0f3555ccba127f_0 into destFile e
15:38:15.807 [main] INFO  c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=13, fromRepositoryId="FileInProgressMarker", toRepositoryId="13", path="768f13d5-82bb-4ae0-9df6-d32b8b386322"] on repo=f58df774-7660-4a7d-a07a-1dec8fadc086
15:38:15.825 [main] INFO  c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=768f13d5-82bb-4ae0-9df6-d32b8b386322 remoteRepositoryId=f58df774-7660-4a7d-a07a-1dec8fadc086 localRepositoryRevisionSynced=8
15:38:15.834 [main] INFO  c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=f58df774-7660-4a7d-a07a-1dec8fadc086 remoteRepositoryId=768f13d5-82bb-4ae0-9df6-d32b8b386322 transaction.localRevision=79 remoteFromLocalRevision=8
15:38:15.846 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncUp: fromID=f58df774-7660-4a7d-a07a-1dec8fadc086 from='target/repo/k1f17f4j-o74ue-local' toID=768f13d5-82bb-4ae0-9df6-d32b8b386322 to='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/k1f17f4j-94zj1-remote'
15:38:15.865 [main] INFO  c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=f58df774-7660-4a7d-a07a-1dec8fadc086 remoteRepositoryId=768f13d5-82bb-4ae0-9df6-d32b8b386322 localRepositoryRevisionSynced=57 localRepositoryRevisionInProgress=79
15:38:15.868 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 52.24%
15:38:15.868 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 63.43%
15:38:15.868 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 74.63%
15:38:15.874 [main] INFO  c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=f58df774-7660-4a7d-a07a-1dec8fadc086 remoteRepositoryId=768f13d5-82bb-4ae0-9df6-d32b8b386322 localRepositoryRevisionSynced=79
15:38:15.883 [main] INFO  c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=768f13d5-82bb-4ae0-9df6-d32b8b386322 remoteRepositoryId=f58df774-7660-4a7d-a07a-1dec8fadc086 transaction.localRevision=11 remoteFromLocalRevision=79
15:38:15.888 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncDown: fromID=768f13d5-82bb-4ae0-9df6-d32b8b386322 from='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/k1f17f4j-94zj1-remote' toID=f58df774-7660-4a7d-a07a-1dec8fadc086 to='target/repo/k1f17f4j-o74ue-local', fromRepoLocalSync=false
15:38:15.902 [main] INFO  c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=768f13d5-82bb-4ae0-9df6-d32b8b386322 remoteRepositoryId=f58df774-7660-4a7d-a07a-1dec8fadc086 localRepositoryRevisionSynced=8 localRepositoryRevisionInProgress=11
15:38:15.905 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 88.31%
15:38:15.905 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 99.50%
15:38:15.910 [main] INFO  c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=768f13d5-82bb-4ae0-9df6-d32b8b386322 remoteRepositoryId=f58df774-7660-4a7d-a07a-1dec8fadc086 localRepositoryRevisionSynced=11
15:38:15.917 [main] INFO  c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=f58df774-7660-4a7d-a07a-1dec8fadc086 remoteRepositoryId=768f13d5-82bb-4ae0-9df6-d32b8b386322 transaction.localRevision=82 remoteFromLocalRevision=11
15:38:15.923 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 100.00%
15:38:15.923 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [54874e6]close: Deferring shut down of real LocalRepoManager 20000 ms.
15:38:15.947 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [4115a14a]close: Deferring shut down of real LocalRepoManager 20000 ms.
15:38:15.995 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [35f5899a]<init>: localRoot='/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/k1f17pdl-hqak-local'
15:38:16.002 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [35f5899a]initLockFile: Repository '/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/k1f17pdl-hqak-local' locked successfully.
15:38:19.430 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [35f5899a]initPersistenceManagerFactory: repositoryId=5c8113ac-fe18-4041-9e98-57105e253982
15:38:19.434 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [35f5899a]initPersistenceManagerFactory: Started up PersistenceManagerFactory successfully in 1939 ms.
15:38:19.450 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [26cec5a9]<init>: localRoot='/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/k1f17pdm-lokca-remote'
15:38:19.454 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [26cec5a9]initLockFile: Repository '/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/k1f17pdm-lokca-remote' locked successfully.
15:38:22.682 [closeDeferredTimer-7902ca46-2] INFO  c.c.c.l.LocalRepoManagerImpl - [7902ca46]_close: Shutting down real LocalRepoManager.
15:38:22.979 [closeDeferredTimer-49497057-2] INFO  c.c.c.l.LocalRepoManagerImpl - [49497057]_close: Shutting down real LocalRepoManager.
15:38:25.996 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [26cec5a9]initPersistenceManagerFactory: repositoryId=d72cc432-81bf-4685-8c79-e3341ec8abfb
15:38:26.003 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [26cec5a9]initPersistenceManagerFactory: Started up PersistenceManagerFactory successfully in 3812 ms.
15:38:26.107 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 1.00%
15:38:26.115 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 50.00%
15:38:26.124 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 58.00%
15:38:26.180 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 63.61%
15:38:26.194 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 74.34%
15:38:26.227 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 80.56%
15:38:26.236 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 90.67%
15:38:26.261 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 95.72%
15:38:26.288 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 100.00%
15:38:26.312 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - local repo: 5c8113ac-fe18-4041-9e98-57105e253982
15:38:26.312 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - remote repo: d72cc432-81bf-4685-8c79-e3341ec8abfb
15:38:26.316 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 0.50%
15:38:26.317 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncDown: fromID=d72cc432-81bf-4685-8c79-e3341ec8abfb from='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/k1f17pdm-lokca-remote' toID=5c8113ac-fe18-4041-9e98-57105e253982 to='target/repo/k1f17pdl-hqak-local', fromRepoLocalSync=true
15:38:26.317 [pool-35-thread-1] INFO  c.c.c.c.r.s.RepoToRepoSync - sync: locally syncing 5c8113ac-fe18-4041-9e98-57105e253982 ('target/repo/k1f17pdl-hqak-local')
15:38:26.344 [pool-35-thread-1] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 12.94%
15:38:26.352 [pool-35-thread-1] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 25.12%
15:38:26.353 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 1.00%
15:38:26.356 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 50.00%
15:38:26.360 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 58.17%
15:38:26.367 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 63.61%
15:38:26.371 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 74.50%
15:38:26.377 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 80.62%
15:38:26.380 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 90.83%
15:38:26.384 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 95.94%
15:38:26.390 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 100.00%
15:38:26.416 [main] INFO  c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=d72cc432-81bf-4685-8c79-e3341ec8abfb remoteRepositoryId=5c8113ac-fe18-4041-9e98-57105e253982 localRepositoryRevisionSynced=-1 localRepositoryRevisionInProgress=2
15:38:26.445 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path=''
15:38:26.473 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path='/1'
15:38:26.492 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 30.22%
15:38:26.492 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path='/2'
15:38:26.502 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path='/2/1'
15:38:26.515 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path='/3'
15:38:26.533 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 35.46%
15:38:26.533 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 40.70%
15:38:26.533 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/1/a'
15:38:26.544 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='d27cde00b4f1318d22a7ef75e08599d6878d3006' to.sha1='<NoInstanceOf_NormalFileDto>'): /1/a
15:38:26.594 [main] INFO  c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=1, fromRepositoryId="FileInProgressMarker", toRepositoryId="1", path="d72cc432-81bf-4685-8c79-e3341ec8abfb"] on repo=5c8113ac-fe18-4041-9e98-57105e253982
15:38:26.598 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/1/a'
15:38:26.598 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='d27cde00b4f1318d22a7ef75e08599d6878d3006' toSha1='null'
15:38:26.598 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/1/a'
15:38:26.598 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/1/a' offset=0
15:38:26.601 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/1/a' offset=0
15:38:26.608 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 139356 bytes in 10 ms. path='/1/a'
15:38:26.612 [main] INFO  c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_86f7e437faa5a7fce15d1ddcb9eaeaea377667b8_0 into destFile a
15:38:26.686 [main] INFO  c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=1, fromRepositoryId="FileInProgressMarker", toRepositoryId="1", path="d72cc432-81bf-4685-8c79-e3341ec8abfb"] on repo=5c8113ac-fe18-4041-9e98-57105e253982
15:38:26.697 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/1/b'
15:38:26.708 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='b46b1b733649b04e49c809ed1e9ad0d5325bbc9a' to.sha1='<NoInstanceOf_NormalFileDto>'): /1/b
15:38:26.723 [main] INFO  c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=2, fromRepositoryId="FileInProgressMarker", toRepositoryId="2", path="d72cc432-81bf-4685-8c79-e3341ec8abfb"] on repo=5c8113ac-fe18-4041-9e98-57105e253982
15:38:26.726 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/1/b'
15:38:26.727 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='b46b1b733649b04e49c809ed1e9ad0d5325bbc9a' toSha1='null'
15:38:26.727 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/1/b'
15:38:26.727 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/1/b' offset=0
15:38:26.731 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/1/b' offset=0
15:38:26.741 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 290466 bytes in 14 ms. path='/1/b'
15:38:26.745 [main] INFO  c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_e9d71f5ee7c92d6dc9e92ffdad17b8bd49418f98_0 into destFile b
15:38:26.767 [main] INFO  c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=2, fromRepositoryId="FileInProgressMarker", toRepositoryId="2", path="d72cc432-81bf-4685-8c79-e3341ec8abfb"] on repo=5c8113ac-fe18-4041-9e98-57105e253982
15:38:26.774 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/1/c'
15:38:26.788 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='e48c3d7a43e9f552e42981dac09ebd9a68b38a62' to.sha1='<NoInstanceOf_NormalFileDto>'): /1/c
15:38:26.802 [main] INFO  c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=3, fromRepositoryId="FileInProgressMarker", toRepositoryId="3", path="d72cc432-81bf-4685-8c79-e3341ec8abfb"] on repo=5c8113ac-fe18-4041-9e98-57105e253982
15:38:26.806 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/1/c'
15:38:26.806 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='e48c3d7a43e9f552e42981dac09ebd9a68b38a62' toSha1='null'
15:38:26.806 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/1/c'
15:38:26.806 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/1/c' offset=0
15:38:26.810 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/1/c' offset=0
15:38:26.824 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 277650 bytes in 18 ms. path='/1/c'
15:38:26.827 [main] INFO  c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_84a516841ba77a5b4648de2cd0dfcb30ea46dbb4_0 into destFile c
15:38:26.910 [main] INFO  c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=3, fromRepositoryId="FileInProgressMarker", toRepositoryId="3", path="d72cc432-81bf-4685-8c79-e3341ec8abfb"] on repo=5c8113ac-fe18-4041-9e98-57105e253982
15:38:26.920 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/2/1/a'
15:38:26.931 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='08185c215c8e296eb5564876a7b55e8a812a5934' to.sha1='<NoInstanceOf_NormalFileDto>'): /2/1/a
15:38:26.947 [main] INFO  c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=4, fromRepositoryId="FileInProgressMarker", toRepositoryId="4", path="d72cc432-81bf-4685-8c79-e3341ec8abfb"] on repo=5c8113ac-fe18-4041-9e98-57105e253982
15:38:26.951 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/2/1/a'
15:38:26.951 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='08185c215c8e296eb5564876a7b55e8a812a5934' toSha1='null'
15:38:26.951 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/2/1/a'
15:38:26.951 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/2/1/a' offset=0
15:38:26.965 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/2/1/a' offset=0
15:38:26.979 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 633696 bytes in 28 ms. path='/2/1/a'
15:38:26.983 [main] INFO  c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_86f7e437faa5a7fce15d1ddcb9eaeaea377667b8_0 into destFile a
15:38:27.051 [main] INFO  c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=4, fromRepositoryId="FileInProgressMarker", toRepositoryId="4", path="d72cc432-81bf-4685-8c79-e3341ec8abfb"] on repo=5c8113ac-fe18-4041-9e98-57105e253982
15:38:27.060 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/2/1/b'
15:38:27.074 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='d4062027492f250ac2c7239ae0b053a81f813a84' to.sha1='<NoInstanceOf_NormalFileDto>'): /2/1/b
15:38:27.091 [main] INFO  c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=5, fromRepositoryId="FileInProgressMarker", toRepositoryId="5", path="d72cc432-81bf-4685-8c79-e3341ec8abfb"] on repo=5c8113ac-fe18-4041-9e98-57105e253982
15:38:27.096 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/2/1/b'
15:38:27.096 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='d4062027492f250ac2c7239ae0b053a81f813a84' toSha1='null'
15:38:27.096 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/2/1/b'
15:38:27.096 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/2/1/b' offset=0
15:38:27.100 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/2/1/b' offset=0
15:38:27.112 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 150773 bytes in 16 ms. path='/2/1/b'
15:38:27.118 [main] INFO  c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_e9d71f5ee7c92d6dc9e92ffdad17b8bd49418f98_0 into destFile b
15:38:27.138 [main] INFO  c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=5, fromRepositoryId="FileInProgressMarker", toRepositoryId="5", path="d72cc432-81bf-4685-8c79-e3341ec8abfb"] on repo=5c8113ac-fe18-4041-9e98-57105e253982
15:38:27.146 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/2/a'
15:38:27.158 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='0fe567057707f3e9e6a6b7e61eda8e29098cab3b' to.sha1='<NoInstanceOf_NormalFileDto>'): /2/a
15:38:27.177 [main] INFO  c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=6, fromRepositoryId="FileInProgressMarker", toRepositoryId="6", path="d72cc432-81bf-4685-8c79-e3341ec8abfb"] on repo=5c8113ac-fe18-4041-9e98-57105e253982
15:38:27.183 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/2/a'
15:38:27.183 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='0fe567057707f3e9e6a6b7e61eda8e29098cab3b' toSha1='null'
15:38:27.183 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/2/a'
15:38:27.183 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/2/a' offset=0
15:38:27.183 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/2/a' offset=0
15:38:27.193 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 2895 bytes in 10 ms. path='/2/a'
15:38:27.197 [main] INFO  c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_86f7e437faa5a7fce15d1ddcb9eaeaea377667b8_0 into destFile a
15:38:27.208 [main] INFO  c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=6, fromRepositoryId="FileInProgressMarker", toRepositoryId="6", path="d72cc432-81bf-4685-8c79-e3341ec8abfb"] on repo=5c8113ac-fe18-4041-9e98-57105e253982
15:38:27.214 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/3/a'
15:38:27.226 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='dcbb3bcd382d47123e511f97b346e047f234632f' to.sha1='<NoInstanceOf_NormalFileDto>'): /3/a
15:38:27.241 [main] INFO  c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=7, fromRepositoryId="FileInProgressMarker", toRepositoryId="7", path="d72cc432-81bf-4685-8c79-e3341ec8abfb"] on repo=5c8113ac-fe18-4041-9e98-57105e253982
15:38:27.244 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/3/a'
15:38:27.245 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='dcbb3bcd382d47123e511f97b346e047f234632f' toSha1='null'
15:38:27.245 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/3/a'
15:38:27.245 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/3/a' offset=0
15:38:27.248 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/3/a' offset=0
15:38:27.257 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 160990 bytes in 12 ms. path='/3/a'
15:38:27.263 [main] INFO  c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_86f7e437faa5a7fce15d1ddcb9eaeaea377667b8_0 into destFile a
15:38:27.310 [main] INFO  c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=7, fromRepositoryId="FileInProgressMarker", toRepositoryId="7", path="d72cc432-81bf-4685-8c79-e3341ec8abfb"] on repo=5c8113ac-fe18-4041-9e98-57105e253982
15:38:27.317 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/3/b'
15:38:27.324 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='dbd31b94193306fb8ae5b2b96a96675b8a2f08da' to.sha1='<NoInstanceOf_NormalFileDto>'): /3/b
15:38:27.343 [main] INFO  c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=8, fromRepositoryId="FileInProgressMarker", toRepositoryId="8", path="d72cc432-81bf-4685-8c79-e3341ec8abfb"] on repo=5c8113ac-fe18-4041-9e98-57105e253982
15:38:27.347 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/3/b'
15:38:27.347 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='dbd31b94193306fb8ae5b2b96a96675b8a2f08da' toSha1='null'
15:38:27.347 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/3/b'
15:38:27.347 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/3/b' offset=0
15:38:27.351 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/3/b' offset=0
15:38:27.363 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 218064 bytes in 16 ms. path='/3/b'
15:38:27.367 [main] INFO  c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_e9d71f5ee7c92d6dc9e92ffdad17b8bd49418f98_0 into destFile b
15:38:27.439 [main] INFO  c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=8, fromRepositoryId="FileInProgressMarker", toRepositoryId="8", path="d72cc432-81bf-4685-8c79-e3341ec8abfb"] on repo=5c8113ac-fe18-4041-9e98-57105e253982
15:38:27.448 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/3/c'
15:38:27.456 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='1ca68c5f0a05c519ac6d03551ed2094a2fe02190' to.sha1='<NoInstanceOf_NormalFileDto>'): /3/c
15:38:27.475 [main] INFO  c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=9, fromRepositoryId="FileInProgressMarker", toRepositoryId="9", path="d72cc432-81bf-4685-8c79-e3341ec8abfb"] on repo=5c8113ac-fe18-4041-9e98-57105e253982
15:38:27.478 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/3/c'
15:38:27.478 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='1ca68c5f0a05c519ac6d03551ed2094a2fe02190' toSha1='null'
15:38:27.478 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/3/c'
15:38:27.478 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/3/c' offset=0
15:38:27.480 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/3/c' offset=0
15:38:27.492 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 121828 bytes in 14 ms. path='/3/c'
15:38:27.496 [main] INFO  c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_84a516841ba77a5b4648de2cd0dfcb30ea46dbb4_0 into destFile c
15:38:27.510 [main] INFO  c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=9, fromRepositoryId="FileInProgressMarker", toRepositoryId="9", path="d72cc432-81bf-4685-8c79-e3341ec8abfb"] on repo=5c8113ac-fe18-4041-9e98-57105e253982
15:38:27.515 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/3/d'
15:38:27.529 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='f1ccf0f8d271fe617572b6c5b438fb0ab46360d7' to.sha1='<NoInstanceOf_NormalFileDto>'): /3/d
15:38:27.544 [main] INFO  c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=10, fromRepositoryId="FileInProgressMarker", toRepositoryId="10", path="d72cc432-81bf-4685-8c79-e3341ec8abfb"] on repo=5c8113ac-fe18-4041-9e98-57105e253982
15:38:27.547 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/3/d'
15:38:27.548 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='f1ccf0f8d271fe617572b6c5b438fb0ab46360d7' toSha1='null'
15:38:27.548 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/3/d'
15:38:27.548 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/3/d' offset=0
15:38:27.552 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/3/d' offset=0
15:38:27.569 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 283296 bytes in 21 ms. path='/3/d'
15:38:27.573 [main] INFO  c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_3c363836cf4e16666669a25da280a1865c2d2874_0 into destFile d
15:38:27.593 [main] INFO  c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=10, fromRepositoryId="FileInProgressMarker", toRepositoryId="10", path="d72cc432-81bf-4685-8c79-e3341ec8abfb"] on repo=5c8113ac-fe18-4041-9e98-57105e253982
15:38:27.598 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 49.27%
15:38:27.618 [main] INFO  c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=d72cc432-81bf-4685-8c79-e3341ec8abfb remoteRepositoryId=5c8113ac-fe18-4041-9e98-57105e253982 localRepositoryRevisionSynced=2
15:38:27.646 [main] INFO  c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=5c8113ac-fe18-4041-9e98-57105e253982 remoteRepositoryId=d72cc432-81bf-4685-8c79-e3341ec8abfb transaction.localRevision=57 remoteFromLocalRevision=2
15:38:27.656 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncUp: fromID=5c8113ac-fe18-4041-9e98-57105e253982 from='target/repo/k1f17pdl-hqak-local' toID=d72cc432-81bf-4685-8c79-e3341ec8abfb to='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/k1f17pdm-lokca-remote'
15:38:27.678 [main] INFO  c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=5c8113ac-fe18-4041-9e98-57105e253982 remoteRepositoryId=d72cc432-81bf-4685-8c79-e3341ec8abfb localRepositoryRevisionSynced=-1 localRepositoryRevisionInProgress=57
15:38:27.687 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 63.43%
15:38:27.687 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 74.63%
15:38:27.697 [main] INFO  c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=5c8113ac-fe18-4041-9e98-57105e253982 remoteRepositoryId=d72cc432-81bf-4685-8c79-e3341ec8abfb localRepositoryRevisionSynced=57
15:38:27.706 [main] INFO  c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=d72cc432-81bf-4685-8c79-e3341ec8abfb remoteRepositoryId=5c8113ac-fe18-4041-9e98-57105e253982 transaction.localRevision=5 remoteFromLocalRevision=57
15:38:27.711 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncDown: fromID=d72cc432-81bf-4685-8c79-e3341ec8abfb from='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/k1f17pdm-lokca-remote' toID=5c8113ac-fe18-4041-9e98-57105e253982 to='target/repo/k1f17pdl-hqak-local', fromRepoLocalSync=false
15:38:27.727 [main] INFO  c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=d72cc432-81bf-4685-8c79-e3341ec8abfb remoteRepositoryId=5c8113ac-fe18-4041-9e98-57105e253982 localRepositoryRevisionSynced=2 localRepositoryRevisionInProgress=5
15:38:27.729 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 88.31%
15:38:27.729 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 99.50%
15:38:27.732 [main] INFO  c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=d72cc432-81bf-4685-8c79-e3341ec8abfb remoteRepositoryId=5c8113ac-fe18-4041-9e98-57105e253982 localRepositoryRevisionSynced=5
15:38:27.739 [main] INFO  c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=5c8113ac-fe18-4041-9e98-57105e253982 remoteRepositoryId=d72cc432-81bf-4685-8c79-e3341ec8abfb transaction.localRevision=60 remoteFromLocalRevision=5
15:38:27.743 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 100.00%
15:38:27.761 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [35f5899a]getCloseDeferredMillis: closeDeferredMillis=20000
15:38:27.761 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [35f5899a]close: Deferring shut down of real LocalRepoManager 20000 ms.
15:38:27.762 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [26cec5a9]getCloseDeferredMillis: closeDeferredMillis=20000
15:38:27.762 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [26cec5a9]close: Deferring shut down of real LocalRepoManager 20000 ms.
15:38:27.808 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 1.00%
15:38:27.809 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 50.00%
15:38:27.810 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 58.17%
15:38:27.813 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 63.61%
15:38:27.816 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 74.50%
15:38:27.857 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 80.62%
15:38:27.863 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 90.83%
15:38:27.866 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 95.94%
15:38:27.877 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 100.00%
15:38:27.896 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 0.50%
15:38:27.896 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncDown: fromID=d72cc432-81bf-4685-8c79-e3341ec8abfb from='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/k1f17pdm-lokca-remote' toID=5c8113ac-fe18-4041-9e98-57105e253982 to='target/repo/k1f17pdl-hqak-local', fromRepoLocalSync=true
15:38:27.897 [pool-36-thread-1] INFO  c.c.c.c.r.s.RepoToRepoSync - sync: locally syncing 5c8113ac-fe18-4041-9e98-57105e253982 ('target/repo/k1f17pdl-hqak-local')
15:38:27.904 [pool-36-thread-1] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 12.94%
15:38:27.923 [pool-36-thread-1] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 19.03%
15:38:27.943 [pool-36-thread-1] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 24.11%
15:38:27.957 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 1.00%
15:38:27.959 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 50.00%
15:38:27.960 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 58.17%
15:38:27.964 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 63.61%
15:38:27.969 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 74.50%
15:38:27.976 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 80.62%
15:38:27.980 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 90.83%
15:38:27.986 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 95.94%
15:38:27.994 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 100.00%
15:38:28.005 [main] INFO  c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=d72cc432-81bf-4685-8c79-e3341ec8abfb remoteRepositoryId=5c8113ac-fe18-4041-9e98-57105e253982 localRepositoryRevisionSynced=5 localRepositoryRevisionInProgress=8
15:38:28.023 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 29.23%
15:38:28.024 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path='/2/1'
15:38:28.043 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 34.83%
15:38:28.044 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncModifications: Deleting '/2/1/a'
15:38:28.082 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 40.42%
15:38:28.082 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 46.02%
15:38:28.103 [main] INFO  c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=d72cc432-81bf-4685-8c79-e3341ec8abfb remoteRepositoryId=5c8113ac-fe18-4041-9e98-57105e253982 localRepositoryRevisionSynced=8
15:38:28.111 [main] INFO  c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=5c8113ac-fe18-4041-9e98-57105e253982 remoteRepositoryId=d72cc432-81bf-4685-8c79-e3341ec8abfb transaction.localRevision=63 remoteFromLocalRevision=8
15:38:28.122 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncUp: fromID=5c8113ac-fe18-4041-9e98-57105e253982 from='target/repo/k1f17pdl-hqak-local' toID=d72cc432-81bf-4685-8c79-e3341ec8abfb to='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/k1f17pdm-lokca-remote'
15:38:28.138 [main] INFO  c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=5c8113ac-fe18-4041-9e98-57105e253982 remoteRepositoryId=d72cc432-81bf-4685-8c79-e3341ec8abfb localRepositoryRevisionSynced=57 localRepositoryRevisionInProgress=63
15:38:28.149 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 52.24%
15:38:28.149 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 59.70%
15:38:28.149 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncModifications: Deleting '/2/1/a'
15:38:28.155 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 67.16%
15:38:28.155 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 74.63%
15:38:28.172 [main] INFO  c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=5c8113ac-fe18-4041-9e98-57105e253982 remoteRepositoryId=d72cc432-81bf-4685-8c79-e3341ec8abfb localRepositoryRevisionSynced=63
15:38:28.244 [main] INFO  c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=d72cc432-81bf-4685-8c79-e3341ec8abfb remoteRepositoryId=5c8113ac-fe18-4041-9e98-57105e253982 transaction.localRevision=11 remoteFromLocalRevision=63
15:38:28.261 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncDown: fromID=d72cc432-81bf-4685-8c79-e3341ec8abfb from='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/k1f17pdm-lokca-remote' toID=5c8113ac-fe18-4041-9e98-57105e253982 to='target/repo/k1f17pdl-hqak-local', fromRepoLocalSync=false
15:38:28.277 [main] INFO  c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=d72cc432-81bf-4685-8c79-e3341ec8abfb remoteRepositoryId=5c8113ac-fe18-4041-9e98-57105e253982 localRepositoryRevisionSynced=8 localRepositoryRevisionInProgress=11
15:38:28.279 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 88.31%
15:38:28.280 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 99.50%
15:38:28.284 [main] INFO  c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=d72cc432-81bf-4685-8c79-e3341ec8abfb remoteRepositoryId=5c8113ac-fe18-4041-9e98-57105e253982 localRepositoryRevisionSynced=11
15:38:28.291 [main] INFO  c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=5c8113ac-fe18-4041-9e98-57105e253982 remoteRepositoryId=d72cc432-81bf-4685-8c79-e3341ec8abfb transaction.localRevision=66 remoteFromLocalRevision=11
15:38:28.295 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 100.00%
15:38:28.295 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [35f5899a]close: Deferring shut down of real LocalRepoManager 20000 ms.
15:38:28.312 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [26cec5a9]close: Deferring shut down of real LocalRepoManager 20000 ms.
15:38:28.342 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [7f7ad55c]<init>: localRoot='/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/k1f17ywl-idd4q-local'
15:38:28.346 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [7f7ad55c]initLockFile: Repository '/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/k1f17ywl-idd4q-local' locked successfully.
15:38:30.941 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [7f7ad55c]initPersistenceManagerFactory: repositoryId=05b24dcd-b08c-4116-a8cd-c00df4fadc7f
15:38:30.943 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [7f7ad55c]initPersistenceManagerFactory: Started up PersistenceManagerFactory successfully in 1287 ms.
15:38:30.962 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [4c114515]<init>: localRoot='/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/k1f17ywl-gsz36-remote'
15:38:30.968 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [4c114515]initLockFile: Repository '/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/k1f17ywl-gsz36-remote' locked successfully.
15:38:35.498 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [4c114515]initPersistenceManagerFactory: repositoryId=0f738620-6722-4885-a75a-a519666b58b0
15:38:35.504 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [4c114515]initPersistenceManagerFactory: Started up PersistenceManagerFactory successfully in 2212 ms.
15:38:35.606 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 1.00%
15:38:35.611 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 50.00%
15:38:35.621 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 58.00%
15:38:35.674 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 63.61%
15:38:35.685 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 74.34%
15:38:35.702 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 80.56%
15:38:35.710 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 90.67%
15:38:35.732 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 95.91%
15:38:35.756 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 100.00%
15:38:35.783 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - local repo: 05b24dcd-b08c-4116-a8cd-c00df4fadc7f
15:38:35.783 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - remote repo: 0f738620-6722-4885-a75a-a519666b58b0
15:38:35.787 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 0.50%
15:38:35.788 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncDown: fromID=0f738620-6722-4885-a75a-a519666b58b0 from='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/k1f17ywl-gsz36-remote/2' toID=05b24dcd-b08c-4116-a8cd-c00df4fadc7f to='target/repo/k1f17ywl-idd4q-local', fromRepoLocalSync=true
15:38:35.788 [pool-37-thread-1] INFO  c.c.c.c.r.s.RepoToRepoSync - sync: locally syncing 05b24dcd-b08c-4116-a8cd-c00df4fadc7f ('target/repo/k1f17ywl-idd4q-local')
15:38:35.814 [pool-37-thread-1] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 12.94%
15:38:35.822 [pool-37-thread-1] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 25.12%
15:38:35.823 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 1.00%
15:38:35.824 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 50.00%
15:38:35.825 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 58.17%
15:38:35.829 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 63.61%
15:38:35.833 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 74.50%
15:38:35.841 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 80.62%
15:38:35.845 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 90.83%
15:38:35.849 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 95.94%
15:38:35.856 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 100.00%
15:38:35.885 [main] INFO  c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=0f738620-6722-4885-a75a-a519666b58b0 remoteRepositoryId=05b24dcd-b08c-4116-a8cd-c00df4fadc7f localRepositoryRevisionSynced=-1 localRepositoryRevisionInProgress=2
15:38:35.924 [closeDeferredTimer-54874e6-2] INFO  c.c.c.l.LocalRepoManagerImpl - [54874e6]_close: Shutting down real LocalRepoManager.
15:38:36.249 [closeDeferredTimer-4115a14a-2] INFO  c.c.c.l.LocalRepoManagerImpl - [4115a14a]_close: Shutting down real LocalRepoManager.
15:38:36.252 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path=''
15:38:36.798 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path='/1'
15:38:36.841 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 31.31%
15:38:36.849 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 36.58%
15:38:36.850 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 41.85%
15:38:36.850 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/1/a'
15:38:36.861 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='17d181419de04247dc09413f7de9250bc8d2a7b6' to.sha1='<NoInstanceOf_NormalFileDto>'): /1/a
15:38:36.969 [main] INFO  c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=1, fromRepositoryId="FileInProgressMarker", toRepositoryId="1", path="0f738620-6722-4885-a75a-a519666b58b0"] on repo=05b24dcd-b08c-4116-a8cd-c00df4fadc7f
15:38:36.974 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/1/a'
15:38:36.974 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='17d181419de04247dc09413f7de9250bc8d2a7b6' toSha1='null'
15:38:36.974 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/1/a'
15:38:36.974 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/1/a' offset=0
15:38:36.975 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/1/a' offset=0
15:38:36.986 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 17298 bytes in 12 ms. path='/1/a'
15:38:36.991 [main] INFO  c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_86f7e437faa5a7fce15d1ddcb9eaeaea377667b8_0 into destFile a
15:38:37.090 [main] INFO  c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=1, fromRepositoryId="FileInProgressMarker", toRepositoryId="1", path="0f738620-6722-4885-a75a-a519666b58b0"] on repo=05b24dcd-b08c-4116-a8cd-c00df4fadc7f
15:38:37.108 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/1/b'
15:38:37.123 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='09ef0a81611b2590194d6f4180dbef2d4fbc5704' to.sha1='<NoInstanceOf_NormalFileDto>'): /1/b
15:38:37.150 [main] INFO  c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=2, fromRepositoryId="FileInProgressMarker", toRepositoryId="2", path="0f738620-6722-4885-a75a-a519666b58b0"] on repo=05b24dcd-b08c-4116-a8cd-c00df4fadc7f
15:38:37.170 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/1/b'
15:38:37.170 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='09ef0a81611b2590194d6f4180dbef2d4fbc5704' toSha1='null'
15:38:37.170 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/1/b'
15:38:37.170 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/1/b' offset=0
15:38:37.176 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/1/b' offset=0
15:38:37.183 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 150000 bytes in 13 ms. path='/1/b'
15:38:37.188 [main] INFO  c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_e9d71f5ee7c92d6dc9e92ffdad17b8bd49418f98_0 into destFile b
15:38:37.275 [main] INFO  c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=2, fromRepositoryId="FileInProgressMarker", toRepositoryId="2", path="0f738620-6722-4885-a75a-a519666b58b0"] on repo=05b24dcd-b08c-4116-a8cd-c00df4fadc7f
15:38:37.296 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/a'
15:38:37.305 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='6bb8b2aef7153d4446998a7cc3b65e9345b4ac61' to.sha1='<NoInstanceOf_NormalFileDto>'): /a
15:38:37.369 [main] INFO  c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=3, fromRepositoryId="FileInProgressMarker", toRepositoryId="3", path="0f738620-6722-4885-a75a-a519666b58b0"] on repo=05b24dcd-b08c-4116-a8cd-c00df4fadc7f
15:38:37.376 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/a'
15:38:37.376 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='6bb8b2aef7153d4446998a7cc3b65e9345b4ac61' toSha1='null'
15:38:37.377 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/a'
15:38:37.377 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/a' offset=0
15:38:37.377 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/a' offset=0
15:38:37.382 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 28680 bytes in 5 ms. path='/a'
15:38:37.387 [main] INFO  c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_86f7e437faa5a7fce15d1ddcb9eaeaea377667b8_0 into destFile a
15:38:37.439 [main] INFO  c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=3, fromRepositoryId="FileInProgressMarker", toRepositoryId="3", path="0f738620-6722-4885-a75a-a519666b58b0"] on repo=05b24dcd-b08c-4116-a8cd-c00df4fadc7f
15:38:37.457 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 48.43%
15:38:37.476 [main] INFO  c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=0f738620-6722-4885-a75a-a519666b58b0 remoteRepositoryId=05b24dcd-b08c-4116-a8cd-c00df4fadc7f localRepositoryRevisionSynced=2
15:38:37.540 [main] INFO  c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=05b24dcd-b08c-4116-a8cd-c00df4fadc7f remoteRepositoryId=0f738620-6722-4885-a75a-a519666b58b0 transaction.localRevision=19 remoteFromLocalRevision=2
15:38:37.561 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncUp: fromID=05b24dcd-b08c-4116-a8cd-c00df4fadc7f from='target/repo/k1f17ywl-idd4q-local' toID=0f738620-6722-4885-a75a-a519666b58b0 to='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/k1f17ywl-gsz36-remote/2'
15:38:37.634 [main] INFO  c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=05b24dcd-b08c-4116-a8cd-c00df4fadc7f remoteRepositoryId=0f738620-6722-4885-a75a-a519666b58b0 localRepositoryRevisionSynced=-1 localRepositoryRevisionInProgress=19
15:38:37.651 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 63.43%
15:38:37.651 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 74.63%
15:38:37.665 [main] INFO  c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=05b24dcd-b08c-4116-a8cd-c00df4fadc7f remoteRepositoryId=0f738620-6722-4885-a75a-a519666b58b0 localRepositoryRevisionSynced=19
15:38:37.674 [main] INFO  c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=0f738620-6722-4885-a75a-a519666b58b0 remoteRepositoryId=05b24dcd-b08c-4116-a8cd-c00df4fadc7f transaction.localRevision=5 remoteFromLocalRevision=19
15:38:37.712 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncDown: fromID=0f738620-6722-4885-a75a-a519666b58b0 from='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/k1f17ywl-gsz36-remote/2' toID=05b24dcd-b08c-4116-a8cd-c00df4fadc7f to='target/repo/k1f17ywl-idd4q-local', fromRepoLocalSync=false
15:38:37.744 [main] INFO  c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=0f738620-6722-4885-a75a-a519666b58b0 remoteRepositoryId=05b24dcd-b08c-4116-a8cd-c00df4fadc7f localRepositoryRevisionSynced=2 localRepositoryRevisionInProgress=5
15:38:37.754 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 88.31%
15:38:37.754 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 99.50%
15:38:37.757 [main] INFO  c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=0f738620-6722-4885-a75a-a519666b58b0 remoteRepositoryId=05b24dcd-b08c-4116-a8cd-c00df4fadc7f localRepositoryRevisionSynced=5
15:38:37.836 [main] INFO  c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=05b24dcd-b08c-4116-a8cd-c00df4fadc7f remoteRepositoryId=0f738620-6722-4885-a75a-a519666b58b0 transaction.localRevision=22 remoteFromLocalRevision=5
15:38:37.849 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 100.00%
15:38:37.878 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [7f7ad55c]getCloseDeferredMillis: closeDeferredMillis=20000
15:38:37.878 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [7f7ad55c]close: Deferring shut down of real LocalRepoManager 20000 ms.
15:38:37.879 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [4c114515]getCloseDeferredMillis: closeDeferredMillis=20000
15:38:37.879 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [4c114515]close: Deferring shut down of real LocalRepoManager 20000 ms.
15:38:37.891 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 1.00%
15:38:37.891 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 50.00%
15:38:37.893 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 58.17%
15:38:37.896 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 63.61%
15:38:37.899 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 74.50%
15:38:37.998 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 80.62%
15:38:38.001 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 90.83%
15:38:38.005 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 95.94%
15:38:38.073 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 100.00%
15:38:38.092 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 0.50%
15:38:38.092 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncDown: fromID=0f738620-6722-4885-a75a-a519666b58b0 from='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/k1f17ywl-gsz36-remote/2' toID=05b24dcd-b08c-4116-a8cd-c00df4fadc7f to='target/repo/k1f17ywl-idd4q-local', fromRepoLocalSync=true
15:38:38.092 [pool-38-thread-1] INFO  c.c.c.c.r.s.RepoToRepoSync - sync: locally syncing 05b24dcd-b08c-4116-a8cd-c00df4fadc7f ('target/repo/k1f17ywl-idd4q-local')
15:38:38.100 [pool-38-thread-1] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 12.94%
15:38:38.117 [pool-38-thread-1] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 18.27%
15:38:38.127 [pool-38-thread-1] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 25.12%
15:38:38.140 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 1.00%
15:38:38.141 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 50.00%
15:38:38.143 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 58.17%
15:38:38.149 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 63.61%
15:38:38.157 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 74.50%
15:38:38.162 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 80.62%
15:38:38.165 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 90.83%
15:38:38.169 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 95.94%
15:38:38.176 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 100.00%
15:38:38.195 [main] INFO  c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=0f738620-6722-4885-a75a-a519666b58b0 remoteRepositoryId=05b24dcd-b08c-4116-a8cd-c00df4fadc7f localRepositoryRevisionSynced=5 localRepositoryRevisionInProgress=8
15:38:38.220 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 32.34%
15:38:38.220 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path='/1'
15:38:38.235 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncModifications: Deleting '/1/a'
15:38:38.265 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 39.80%
15:38:38.266 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 47.26%
15:38:38.284 [main] INFO  c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=0f738620-6722-4885-a75a-a519666b58b0 remoteRepositoryId=05b24dcd-b08c-4116-a8cd-c00df4fadc7f localRepositoryRevisionSynced=8
15:38:38.292 [main] INFO  c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=05b24dcd-b08c-4116-a8cd-c00df4fadc7f remoteRepositoryId=0f738620-6722-4885-a75a-a519666b58b0 transaction.localRevision=25 remoteFromLocalRevision=8
15:38:38.303 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncUp: fromID=05b24dcd-b08c-4116-a8cd-c00df4fadc7f from='target/repo/k1f17ywl-idd4q-local' toID=0f738620-6722-4885-a75a-a519666b58b0 to='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/k1f17ywl-gsz36-remote/2'
15:38:38.315 [main] INFO  c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=05b24dcd-b08c-4116-a8cd-c00df4fadc7f remoteRepositoryId=0f738620-6722-4885-a75a-a519666b58b0 localRepositoryRevisionSynced=19 localRepositoryRevisionInProgress=25
15:38:38.323 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 59.70%
15:38:38.324 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncModifications: Deleting '/1/a'
15:38:38.329 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 67.16%
15:38:38.329 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 74.63%
15:38:38.346 [main] INFO  c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=05b24dcd-b08c-4116-a8cd-c00df4fadc7f remoteRepositoryId=0f738620-6722-4885-a75a-a519666b58b0 localRepositoryRevisionSynced=25
15:38:38.350 [main] INFO  c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=0f738620-6722-4885-a75a-a519666b58b0 remoteRepositoryId=05b24dcd-b08c-4116-a8cd-c00df4fadc7f transaction.localRevision=11 remoteFromLocalRevision=25
15:38:38.358 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncDown: fromID=0f738620-6722-4885-a75a-a519666b58b0 from='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/k1f17ywl-gsz36-remote/2' toID=05b24dcd-b08c-4116-a8cd-c00df4fadc7f to='target/repo/k1f17ywl-idd4q-local', fromRepoLocalSync=false
15:38:38.371 [main] INFO  c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=0f738620-6722-4885-a75a-a519666b58b0 remoteRepositoryId=05b24dcd-b08c-4116-a8cd-c00df4fadc7f localRepositoryRevisionSynced=8 localRepositoryRevisionInProgress=11
15:38:38.378 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 88.31%
15:38:38.378 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 99.50%
15:38:38.381 [main] INFO  c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=0f738620-6722-4885-a75a-a519666b58b0 remoteRepositoryId=05b24dcd-b08c-4116-a8cd-c00df4fadc7f localRepositoryRevisionSynced=11
15:38:38.388 [main] INFO  c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=05b24dcd-b08c-4116-a8cd-c00df4fadc7f remoteRepositoryId=0f738620-6722-4885-a75a-a519666b58b0 transaction.localRevision=28 remoteFromLocalRevision=11
15:38:38.390 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 100.00%
15:38:38.391 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [7f7ad55c]close: Deferring shut down of real LocalRepoManager 20000 ms.
15:38:38.407 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [4c114515]close: Deferring shut down of real LocalRepoManager 20000 ms.
15:38:38.416 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [693bf831]<init>: localRoot='/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/k1f186of-a6d3x-local'
15:38:38.422 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [693bf831]initLockFile: Repository '/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/k1f186of-a6d3x-local' locked successfully.
15:38:41.953 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [693bf831]initPersistenceManagerFactory: repositoryId=6f5075c5-8b85-487f-9ef4-0d6bdcea4a54
15:38:41.954 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [693bf831]initPersistenceManagerFactory: Started up PersistenceManagerFactory successfully in 1791 ms.
15:38:41.968 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [252567e7]<init>: localRoot='/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/k1f186of-chk3q-remote'
15:38:41.973 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [252567e7]initLockFile: Repository '/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/k1f186of-chk3q-remote' locked successfully.
15:38:46.667 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [252567e7]initPersistenceManagerFactory: repositoryId=7821a20d-b9d8-4e28-9d14-8cbacf139091
15:38:46.669 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [252567e7]initPersistenceManagerFactory: Started up PersistenceManagerFactory successfully in 2484 ms.
15:38:46.751 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 1.00%
15:38:46.758 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 50.00%
15:38:46.766 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 58.00%
15:38:46.807 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 63.61%
15:38:46.831 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 74.34%
15:38:46.876 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 79.70%
15:38:46.885 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 90.67%
15:38:46.903 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 95.91%
15:38:46.923 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 100.00%
15:38:46.939 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - local repo: 6f5075c5-8b85-487f-9ef4-0d6bdcea4a54
15:38:46.939 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - remote repo: 7821a20d-b9d8-4e28-9d14-8cbacf139091
15:38:46.942 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 0.50%
15:38:46.943 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncDown: fromID=7821a20d-b9d8-4e28-9d14-8cbacf139091 from='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/k1f186of-chk3q-remote' toID=6f5075c5-8b85-487f-9ef4-0d6bdcea4a54 to='target/repo/k1f186of-a6d3x-local', fromRepoLocalSync=true
15:38:46.943 [pool-39-thread-1] INFO  c.c.c.c.r.s.RepoToRepoSync - sync: locally syncing 6f5075c5-8b85-487f-9ef4-0d6bdcea4a54 ('target/repo/k1f186of-a6d3x-local')
15:38:46.963 [pool-39-thread-1] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 12.94%
15:38:46.969 [pool-39-thread-1] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 25.12%
15:38:46.970 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 1.00%
15:38:46.971 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 50.00%
15:38:46.972 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 58.17%
15:38:46.982 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 63.61%
15:38:46.985 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 74.50%
15:38:46.991 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 80.62%
15:38:46.994 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 90.83%
15:38:46.997 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 95.94%
15:38:47.003 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 100.00%
15:38:47.020 [main] INFO  c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=7821a20d-b9d8-4e28-9d14-8cbacf139091 remoteRepositoryId=6f5075c5-8b85-487f-9ef4-0d6bdcea4a54 localRepositoryRevisionSynced=-1 localRepositoryRevisionInProgress=2
15:38:47.052 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path=''
15:38:47.074 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path='/1'
15:38:47.086 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 30.22%
15:38:47.087 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path='/2'
15:38:47.094 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path='/2/1'
15:38:47.103 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path='/3'
15:38:47.115 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 35.46%
15:38:47.116 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 40.70%
15:38:47.116 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/1/a'
15:38:47.123 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='c4d4a5f28776cf7e14a8a7d2f354801971ff94ff' to.sha1='<NoInstanceOf_NormalFileDto>'): /1/a
15:38:47.159 [main] INFO  c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=1, fromRepositoryId="FileInProgressMarker", toRepositoryId="1", path="7821a20d-b9d8-4e28-9d14-8cbacf139091"] on repo=6f5075c5-8b85-487f-9ef4-0d6bdcea4a54
15:38:47.161 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/1/a'
15:38:47.161 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='c4d4a5f28776cf7e14a8a7d2f354801971ff94ff' toSha1='null'
15:38:47.161 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/1/a'
15:38:47.161 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/1/a' offset=0
15:38:47.170 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/1/a' offset=0
15:38:47.186 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 774000 bytes in 25 ms. path='/1/a'
15:38:47.190 [main] INFO  c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_86f7e437faa5a7fce15d1ddcb9eaeaea377667b8_0 into destFile a
15:38:47.295 [main] INFO  c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=1, fromRepositoryId="FileInProgressMarker", toRepositoryId="1", path="7821a20d-b9d8-4e28-9d14-8cbacf139091"] on repo=6f5075c5-8b85-487f-9ef4-0d6bdcea4a54
15:38:47.307 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/1/b'
15:38:47.320 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='968ef7d0be0c503240a24b80e15c3ac0d3fbaf3a' to.sha1='<NoInstanceOf_NormalFileDto>'): /1/b
15:38:47.329 [main] INFO  c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=2, fromRepositoryId="FileInProgressMarker", toRepositoryId="2", path="7821a20d-b9d8-4e28-9d14-8cbacf139091"] on repo=6f5075c5-8b85-487f-9ef4-0d6bdcea4a54
15:38:47.330 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/1/b'
15:38:47.330 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='968ef7d0be0c503240a24b80e15c3ac0d3fbaf3a' toSha1='null'
15:38:47.330 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/1/b'
15:38:47.330 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/1/b' offset=0
15:38:47.335 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/1/b' offset=0
15:38:47.343 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 429030 bytes in 13 ms. path='/1/b'
15:38:47.346 [main] INFO  c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_e9d71f5ee7c92d6dc9e92ffdad17b8bd49418f98_0 into destFile b
15:38:47.420 [main] INFO  c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=2, fromRepositoryId="FileInProgressMarker", toRepositoryId="2", path="7821a20d-b9d8-4e28-9d14-8cbacf139091"] on repo=6f5075c5-8b85-487f-9ef4-0d6bdcea4a54
15:38:47.427 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/1/c'
15:38:47.437 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='4bf4f084d3f4c84e3c25d8b9e78980baf0443aa3' to.sha1='<NoInstanceOf_NormalFileDto>'): /1/c
15:38:47.447 [main] INFO  c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=3, fromRepositoryId="FileInProgressMarker", toRepositoryId="3", path="7821a20d-b9d8-4e28-9d14-8cbacf139091"] on repo=6f5075c5-8b85-487f-9ef4-0d6bdcea4a54
15:38:47.448 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/1/c'
15:38:47.449 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='4bf4f084d3f4c84e3c25d8b9e78980baf0443aa3' toSha1='null'
15:38:47.449 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/1/c'
15:38:47.449 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/1/c' offset=0
15:38:47.451 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/1/c' offset=0
15:38:47.458 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 226914 bytes in 9 ms. path='/1/c'
15:38:47.461 [main] INFO  c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_84a516841ba77a5b4648de2cd0dfcb30ea46dbb4_0 into destFile c
15:38:47.477 [main] INFO  c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=3, fromRepositoryId="FileInProgressMarker", toRepositoryId="3", path="7821a20d-b9d8-4e28-9d14-8cbacf139091"] on repo=6f5075c5-8b85-487f-9ef4-0d6bdcea4a54
15:38:47.479 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/2/1/a'
15:38:47.488 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='630a9d4582cd3be19573caa25188e2809535dafc' to.sha1='<NoInstanceOf_NormalFileDto>'): /2/1/a
15:38:47.501 [main] INFO  c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=4, fromRepositoryId="FileInProgressMarker", toRepositoryId="4", path="7821a20d-b9d8-4e28-9d14-8cbacf139091"] on repo=6f5075c5-8b85-487f-9ef4-0d6bdcea4a54
15:38:47.503 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/2/1/a'
15:38:47.503 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='630a9d4582cd3be19573caa25188e2809535dafc' toSha1='null'
15:38:47.503 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/2/1/a'
15:38:47.503 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/2/1/a' offset=0
15:38:47.513 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/2/1/a' offset=0
15:38:47.531 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 942624 bytes in 28 ms. path='/2/1/a'
15:38:47.536 [main] INFO  c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_86f7e437faa5a7fce15d1ddcb9eaeaea377667b8_0 into destFile a
15:38:47.632 [main] INFO  c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=4, fromRepositoryId="FileInProgressMarker", toRepositoryId="4", path="7821a20d-b9d8-4e28-9d14-8cbacf139091"] on repo=6f5075c5-8b85-487f-9ef4-0d6bdcea4a54
15:38:47.639 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/2/1/b'
15:38:47.649 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='85db1ddfd5e0e9b36088f9faf0b667554cba8c72' to.sha1='<NoInstanceOf_NormalFileDto>'): /2/1/b
15:38:47.663 [main] INFO  c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=5, fromRepositoryId="FileInProgressMarker", toRepositoryId="5", path="7821a20d-b9d8-4e28-9d14-8cbacf139091"] on repo=6f5075c5-8b85-487f-9ef4-0d6bdcea4a54
15:38:47.664 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/2/1/b'
15:38:47.664 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='85db1ddfd5e0e9b36088f9faf0b667554cba8c72' toSha1='null'
15:38:47.664 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/2/1/b'
15:38:47.664 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/2/1/b' offset=0
15:38:47.670 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/2/1/b' offset=0
15:38:47.680 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 470612 bytes in 16 ms. path='/2/1/b'
15:38:47.685 [main] INFO  c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_e9d71f5ee7c92d6dc9e92ffdad17b8bd49418f98_0 into destFile b
15:38:47.725 [main] INFO  c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=5, fromRepositoryId="FileInProgressMarker", toRepositoryId="5", path="7821a20d-b9d8-4e28-9d14-8cbacf139091"] on repo=6f5075c5-8b85-487f-9ef4-0d6bdcea4a54
15:38:47.733 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/2/a'
15:38:47.743 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='27b1291765988dc28ac9c3ba793bb65698ba17ff' to.sha1='<NoInstanceOf_NormalFileDto>'): /2/a
15:38:47.754 [main] INFO  c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=6, fromRepositoryId="FileInProgressMarker", toRepositoryId="6", path="7821a20d-b9d8-4e28-9d14-8cbacf139091"] on repo=6f5075c5-8b85-487f-9ef4-0d6bdcea4a54
15:38:47.755 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/2/a'
15:38:47.755 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='27b1291765988dc28ac9c3ba793bb65698ba17ff' toSha1='null'
15:38:47.755 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/2/a'
15:38:47.755 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/2/a' offset=0
15:38:47.758 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/2/a' offset=0
15:38:47.764 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 173712 bytes in 9 ms. path='/2/a'
15:38:47.768 [main] INFO  c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_86f7e437faa5a7fce15d1ddcb9eaeaea377667b8_0 into destFile a
15:38:47.783 [main] INFO  c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=6, fromRepositoryId="FileInProgressMarker", toRepositoryId="6", path="7821a20d-b9d8-4e28-9d14-8cbacf139091"] on repo=6f5075c5-8b85-487f-9ef4-0d6bdcea4a54
15:38:47.786 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/3/a'
15:38:47.793 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='64a0b580d9f9868cc8076700828a54bfbdba5c32' to.sha1='<NoInstanceOf_NormalFileDto>'): /3/a
15:38:47.804 [main] INFO  c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=7, fromRepositoryId="FileInProgressMarker", toRepositoryId="7", path="7821a20d-b9d8-4e28-9d14-8cbacf139091"] on repo=6f5075c5-8b85-487f-9ef4-0d6bdcea4a54
15:38:47.805 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/3/a'
15:38:47.805 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='64a0b580d9f9868cc8076700828a54bfbdba5c32' toSha1='null'
15:38:47.805 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/3/a'
15:38:47.805 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/3/a' offset=0
15:38:47.808 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/3/a' offset=0
15:38:47.814 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 184224 bytes in 9 ms. path='/3/a'
15:38:47.818 [main] INFO  c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_86f7e437faa5a7fce15d1ddcb9eaeaea377667b8_0 into destFile a
15:38:47.833 [main] INFO  c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=7, fromRepositoryId="FileInProgressMarker", toRepositoryId="7", path="7821a20d-b9d8-4e28-9d14-8cbacf139091"] on repo=6f5075c5-8b85-487f-9ef4-0d6bdcea4a54
15:38:47.836 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/3/b'
15:38:47.843 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='11e3671663adeffa4751338154e8f518d225546f' to.sha1='<NoInstanceOf_NormalFileDto>'): /3/b
15:38:47.854 [main] INFO  c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=8, fromRepositoryId="FileInProgressMarker", toRepositoryId="8", path="7821a20d-b9d8-4e28-9d14-8cbacf139091"] on repo=6f5075c5-8b85-487f-9ef4-0d6bdcea4a54
15:38:47.856 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/3/b'
15:38:47.856 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='11e3671663adeffa4751338154e8f518d225546f' toSha1='null'
15:38:47.856 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/3/b'
15:38:47.856 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/3/b' offset=0
15:38:47.859 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/3/b' offset=0
15:38:47.868 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 215600 bytes in 12 ms. path='/3/b'
15:38:47.872 [main] INFO  c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_e9d71f5ee7c92d6dc9e92ffdad17b8bd49418f98_0 into destFile b
15:38:47.928 [main] INFO  c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=8, fromRepositoryId="FileInProgressMarker", toRepositoryId="8", path="7821a20d-b9d8-4e28-9d14-8cbacf139091"] on repo=6f5075c5-8b85-487f-9ef4-0d6bdcea4a54
15:38:47.953 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/3/c'
15:38:47.967 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='129a3c3fe7713189d4584da22f516c184cd59bef' to.sha1='<NoInstanceOf_NormalFileDto>'): /3/c
15:38:47.979 [main] INFO  c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=9, fromRepositoryId="FileInProgressMarker", toRepositoryId="9", path="7821a20d-b9d8-4e28-9d14-8cbacf139091"] on repo=6f5075c5-8b85-487f-9ef4-0d6bdcea4a54
15:38:47.980 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/3/c'
15:38:47.981 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='129a3c3fe7713189d4584da22f516c184cd59bef' toSha1='null'
15:38:47.981 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/3/c'
15:38:47.981 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/3/c' offset=0
15:38:47.981 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/3/c' offset=0
15:38:47.984 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 29094 bytes in 3 ms. path='/3/c'
15:38:47.988 [main] INFO  c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_84a516841ba77a5b4648de2cd0dfcb30ea46dbb4_0 into destFile c
15:38:47.994 [main] INFO  c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=9, fromRepositoryId="FileInProgressMarker", toRepositoryId="9", path="7821a20d-b9d8-4e28-9d14-8cbacf139091"] on repo=6f5075c5-8b85-487f-9ef4-0d6bdcea4a54
15:38:47.997 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/3/d'
15:38:48.003 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='c59c03ecfeb9544e307ffaa9ad22d4ed27c9bfbc' to.sha1='<NoInstanceOf_NormalFileDto>'): /3/d
15:38:48.015 [main] INFO  c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=10, fromRepositoryId="FileInProgressMarker", toRepositoryId="10", path="7821a20d-b9d8-4e28-9d14-8cbacf139091"] on repo=6f5075c5-8b85-487f-9ef4-0d6bdcea4a54
15:38:48.016 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/3/d'
15:38:48.016 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='c59c03ecfeb9544e307ffaa9ad22d4ed27c9bfbc' toSha1='null'
15:38:48.016 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/3/d'
15:38:48.016 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/3/d' offset=0
15:38:48.017 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/3/d' offset=0
15:38:48.025 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 48840 bytes in 9 ms. path='/3/d'
15:38:48.029 [main] INFO  c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_3c363836cf4e16666669a25da280a1865c2d2874_0 into destFile d
15:38:48.038 [main] INFO  c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=10, fromRepositoryId="FileInProgressMarker", toRepositoryId="10", path="7821a20d-b9d8-4e28-9d14-8cbacf139091"] on repo=6f5075c5-8b85-487f-9ef4-0d6bdcea4a54
15:38:48.041 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 49.27%
15:38:48.055 [main] INFO  c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=7821a20d-b9d8-4e28-9d14-8cbacf139091 remoteRepositoryId=6f5075c5-8b85-487f-9ef4-0d6bdcea4a54 localRepositoryRevisionSynced=2
15:38:48.062 [main] INFO  c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=6f5075c5-8b85-487f-9ef4-0d6bdcea4a54 remoteRepositoryId=7821a20d-b9d8-4e28-9d14-8cbacf139091 transaction.localRevision=57 remoteFromLocalRevision=2
15:38:48.068 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncUp: fromID=6f5075c5-8b85-487f-9ef4-0d6bdcea4a54 from='target/repo/k1f186of-a6d3x-local' toID=7821a20d-b9d8-4e28-9d14-8cbacf139091 to='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/k1f186of-chk3q-remote'
15:38:48.085 [main] INFO  c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=6f5075c5-8b85-487f-9ef4-0d6bdcea4a54 remoteRepositoryId=7821a20d-b9d8-4e28-9d14-8cbacf139091 localRepositoryRevisionSynced=-1 localRepositoryRevisionInProgress=57
15:38:48.094 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 63.43%
15:38:48.094 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 74.63%
15:38:48.106 [main] INFO  c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=6f5075c5-8b85-487f-9ef4-0d6bdcea4a54 remoteRepositoryId=7821a20d-b9d8-4e28-9d14-8cbacf139091 localRepositoryRevisionSynced=57
15:38:48.121 [main] INFO  c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=7821a20d-b9d8-4e28-9d14-8cbacf139091 remoteRepositoryId=6f5075c5-8b85-487f-9ef4-0d6bdcea4a54 transaction.localRevision=5 remoteFromLocalRevision=57
15:38:48.122 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncDown: fromID=7821a20d-b9d8-4e28-9d14-8cbacf139091 from='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/k1f186of-chk3q-remote' toID=6f5075c5-8b85-487f-9ef4-0d6bdcea4a54 to='target/repo/k1f186of-a6d3x-local', fromRepoLocalSync=false
15:38:48.130 [main] INFO  c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=7821a20d-b9d8-4e28-9d14-8cbacf139091 remoteRepositoryId=6f5075c5-8b85-487f-9ef4-0d6bdcea4a54 localRepositoryRevisionSynced=2 localRepositoryRevisionInProgress=5
15:38:48.132 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 88.31%
15:38:48.132 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 99.50%
15:38:48.135 [main] INFO  c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=7821a20d-b9d8-4e28-9d14-8cbacf139091 remoteRepositoryId=6f5075c5-8b85-487f-9ef4-0d6bdcea4a54 localRepositoryRevisionSynced=5
15:38:48.137 [main] INFO  c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=6f5075c5-8b85-487f-9ef4-0d6bdcea4a54 remoteRepositoryId=7821a20d-b9d8-4e28-9d14-8cbacf139091 transaction.localRevision=60 remoteFromLocalRevision=5
15:38:48.139 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 100.00%
15:38:48.148 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [693bf831]getCloseDeferredMillis: closeDeferredMillis=20000
15:38:48.148 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [693bf831]close: Deferring shut down of real LocalRepoManager 20000 ms.
15:38:48.150 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [252567e7]getCloseDeferredMillis: closeDeferredMillis=20000
15:38:48.150 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [252567e7]close: Deferring shut down of real LocalRepoManager 20000 ms.
15:38:48.209 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 1.00%
15:38:48.210 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 50.00%
15:38:48.213 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 62.25%
15:38:48.216 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 68.38%
15:38:48.218 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 74.50%
15:38:48.220 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 86.75%
15:38:48.223 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 92.88%
15:38:48.230 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 99.00%
15:38:48.272 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 100.00%
15:38:48.284 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 0.50%
15:38:48.285 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncDown: fromID=7821a20d-b9d8-4e28-9d14-8cbacf139091 from='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/k1f186of-chk3q-remote' toID=6f5075c5-8b85-487f-9ef4-0d6bdcea4a54 to='target/repo/k1f186of-a6d3x-local', fromRepoLocalSync=true
15:38:48.285 [pool-40-thread-1] INFO  c.c.c.c.r.s.RepoToRepoSync - sync: locally syncing 6f5075c5-8b85-487f-9ef4-0d6bdcea4a54 ('target/repo/k1f186of-a6d3x-local')
15:38:48.290 [pool-40-thread-1] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 12.94%
15:38:48.296 [closeDeferredTimer-35f5899a-2] INFO  c.c.c.l.LocalRepoManagerImpl - [35f5899a]_close: Shutting down real LocalRepoManager.
15:38:48.600 [closeDeferredTimer-26cec5a9-2] INFO  c.c.c.l.LocalRepoManagerImpl - [26cec5a9]_close: Shutting down real LocalRepoManager.
15:38:49.048 [pool-40-thread-1] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 19.03%
15:38:49.070 [pool-40-thread-1] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 24.11%
15:38:49.087 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 1.00%
15:38:49.090 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 50.00%
15:38:49.094 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 62.25%
15:38:49.098 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 68.38%
15:38:49.101 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 74.50%
15:38:49.104 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 86.75%
15:38:49.108 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 92.88%
15:38:49.111 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 99.00%
15:38:49.115 [main] INFO  c.c.c.l.t.FileRepoTransport - Local sync...: 100.00%
15:38:49.272 [main] INFO  c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=7821a20d-b9d8-4e28-9d14-8cbacf139091 remoteRepositoryId=6f5075c5-8b85-487f-9ef4-0d6bdcea4a54 localRepositoryRevisionSynced=5 localRepositoryRevisionInProgress=8
15:38:49.298 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 31.09%
15:38:49.298 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path=''
15:38:49.343 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncModifications: Deleting '/2'
15:38:49.465 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 38.56%
15:38:49.466 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 46.02%
15:38:49.484 [main] INFO  c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=7821a20d-b9d8-4e28-9d14-8cbacf139091 remoteRepositoryId=6f5075c5-8b85-487f-9ef4-0d6bdcea4a54 localRepositoryRevisionSynced=8
15:38:49.536 [main] INFO  c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=6f5075c5-8b85-487f-9ef4-0d6bdcea4a54 remoteRepositoryId=7821a20d-b9d8-4e28-9d14-8cbacf139091 transaction.localRevision=63 remoteFromLocalRevision=8
15:38:49.544 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncUp: fromID=6f5075c5-8b85-487f-9ef4-0d6bdcea4a54 from='target/repo/k1f186of-a6d3x-local' toID=7821a20d-b9d8-4e28-9d14-8cbacf139091 to='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/k1f186of-chk3q-remote'
15:38:49.557 [main] INFO  c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=6f5075c5-8b85-487f-9ef4-0d6bdcea4a54 remoteRepositoryId=7821a20d-b9d8-4e28-9d14-8cbacf139091 localRepositoryRevisionSynced=57 localRepositoryRevisionInProgress=63
15:38:49.569 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 52.24%
15:38:49.569 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 59.70%
15:38:49.570 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncModifications: Deleting '/2'
15:38:49.573 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 67.16%
15:38:49.573 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 74.63%
15:38:49.590 [main] INFO  c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=6f5075c5-8b85-487f-9ef4-0d6bdcea4a54 remoteRepositoryId=7821a20d-b9d8-4e28-9d14-8cbacf139091 localRepositoryRevisionSynced=63
15:38:49.609 [main] INFO  c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=7821a20d-b9d8-4e28-9d14-8cbacf139091 remoteRepositoryId=6f5075c5-8b85-487f-9ef4-0d6bdcea4a54 transaction.localRevision=11 remoteFromLocalRevision=63
15:38:49.615 [main] INFO  c.c.c.c.r.s.RepoToRepoSync - syncDown: fromID=7821a20d-b9d8-4e28-9d14-8cbacf139091 from='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/k1f186of-chk3q-remote' toID=6f5075c5-8b85-487f-9ef4-0d6bdcea4a54 to='target/repo/k1f186of-a6d3x-local', fromRepoLocalSync=false
15:38:49.624 [main] INFO  c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=7821a20d-b9d8-4e28-9d14-8cbacf139091 remoteRepositoryId=6f5075c5-8b85-487f-9ef4-0d6bdcea4a54 localRepositoryRevisionSynced=8 localRepositoryRevisionInProgress=11
15:38:49.626 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 88.31%
15:38:49.626 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 99.50%
15:38:49.630 [main] INFO  c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=7821a20d-b9d8-4e28-9d14-8cbacf139091 remoteRepositoryId=6f5075c5-8b85-487f-9ef4-0d6bdcea4a54 localRepositoryRevisionSynced=11
15:38:49.675 [main] INFO  c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=6f5075c5-8b85-487f-9ef4-0d6bdcea4a54 remoteRepositoryId=7821a20d-b9d8-4e28-9d14-8cbacf139091 transaction.localRevision=66 remoteFromLocalRevision=11
15:38:49.686 [main] INFO  c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 100.00%
15:38:49.686 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [693bf831]close: Deferring shut down of real LocalRepoManager 20000 ms.
15:38:49.694 [main] INFO  c.c.c.l.LocalRepoManagerImpl - [252567e7]close: Deferring shut down of real LocalRepoManager 20000 ms.
Tests run: 20, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 438.126 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.8-SNAPSHOT.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.8-SNAPSHOT-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.8-SNAPSHOT.jar to /home/tomcat/.m2/repository/co/codewizards/cloudstore/co.codewizards.cloudstore.local/0.10.8-SNAPSHOT/co.codewizards.cloudstore.local-0.10.8-SNAPSHOT.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.8-SNAPSHOT/co.codewizards.cloudstore.local-0.10.8-SNAPSHOT.pom
[INFO] Installing /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/co.codewizards.cloudstore.local-0.10.8-SNAPSHOT-sources.jar to /home/tomcat/.m2/repository/co/codewizards/cloudstore/co.codewizards.cloudstore.local/0.10.8-SNAPSHOT/co.codewizards.cloudstore.local-0.10.8-SNAPSHOT-sources.jar
[INFO] 
[INFO] --- maven-deploy-plugin:2.7:deploy (default-deploy) @ co.codewizards.cloudstore.local ---
Downloading: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/snapshot/co/codewizards/cloudstore/co.codewizards.cloudstore.local/0.10.8-SNAPSHOT/maven-metadata.xml
Uploading: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/snapshot/co/codewizards/cloudstore/co.codewizards.cloudstore.local/0.10.8-SNAPSHOT/co.codewizards.cloudstore.local-0.10.8-20191006.133851-1.jar
Uploaded: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/snapshot/co/codewizards/cloudstore/co.codewizards.cloudstore.local/0.10.8-SNAPSHOT/co.codewizards.cloudstore.local-0.10.8-20191006.133851-1.jar (203 KB at 20274.3 KB/sec)
Uploading: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/snapshot/co/codewizards/cloudstore/co.codewizards.cloudstore.local/0.10.8-SNAPSHOT/co.codewizards.cloudstore.local-0.10.8-20191006.133851-1.pom
Uploaded: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/snapshot/co/codewizards/cloudstore/co.codewizards.cloudstore.local/0.10.8-SNAPSHOT/co.codewizards.cloudstore.local-0.10.8-20191006.133851-1.pom (3 KB at 2565.4 KB/sec)
Downloading: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/snapshot/co/codewizards/cloudstore/co.codewizards.cloudstore.local/maven-metadata.xml
Uploading: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/snapshot/co/codewizards/cloudstore/co.codewizards.cloudstore.local/0.10.8-SNAPSHOT/maven-metadata.xml
Uploaded: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/snapshot/co/codewizards/cloudstore/co.codewizards.cloudstore.local/0.10.8-SNAPSHOT/maven-metadata.xml (809 B at 790.0 KB/sec)
Uploading: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/snapshot/co/codewizards/cloudstore/co.codewizards.cloudstore.local/maven-metadata.xml
Uploaded: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/snapshot/co/codewizards/cloudstore/co.codewizards.cloudstore.local/maven-metadata.xml (317 B at 309.6 KB/sec)
Uploading: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/snapshot/co/codewizards/cloudstore/co.codewizards.cloudstore.local/0.10.8-SNAPSHOT/co.codewizards.cloudstore.local-0.10.8-20191006.133851-1-sources.jar
Uploaded: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/snapshot/co/codewizards/cloudstore/co.codewizards.cloudstore.local/0.10.8-SNAPSHOT/co.codewizards.cloudstore.local-0.10.8-20191006.133851-1-sources.jar (105 KB at 26069.1 KB/sec)
Uploading: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/snapshot/co/codewizards/cloudstore/co.codewizards.cloudstore.local/0.10.8-SNAPSHOT/maven-metadata.xml
Uploaded: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/snapshot/co/codewizards/cloudstore/co.codewizards.cloudstore.local/0.10.8-SNAPSHOT/maven-metadata.xml (1 KB at 500.0 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