|  Console Output
Skipping 705 KB.. Full Logc.c.c.l.s.RepoToRepoSyncTest - Local sync...: 90.67%
08:05:13.767 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 95.90%
08:05:13.799 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 100.00%
08:05:13.826 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - local repo: f5b3b245-1256-489d-9e6e-27aaf4d8b908
08:05:13.826 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - remote repo: 2b0387f6-d81a-4990-b272-86883eba4a6c
08:05:13.831 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 0.50%
08:05:13.832 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDown: fromID=2b0387f6-d81a-4990-b272-86883eba4a6c from='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/jz6kd8pq-cm8so-remote/2' toID=f5b3b245-1256-489d-9e6e-27aaf4d8b908 to='target/repo/jz6kd8pp-lxl1s-local', fromRepoLocalSync=true
08:05:13.832 [pool-31-thread-1] INFO c.c.c.c.r.s.RepoToRepoSync - sync: locally syncing f5b3b245-1256-489d-9e6e-27aaf4d8b908 ('target/repo/jz6kd8pp-lxl1s-local')
08:05:13.859 [pool-31-thread-1] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 12.94%
08:05:13.864 [pool-31-thread-1] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 25.12%
08:05:13.867 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 1.00%
08:05:13.868 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 50.00%
08:05:13.870 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 58.17%
08:05:13.875 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 63.61%
08:05:13.880 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 74.50%
08:05:13.889 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 80.62%
08:05:13.894 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 90.83%
08:05:13.900 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 95.94%
08:05:13.909 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 100.00%
08:05:13.939 [main] INFO c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=2b0387f6-d81a-4990-b272-86883eba4a6c remoteRepositoryId=f5b3b245-1256-489d-9e6e-27aaf4d8b908 localRepositoryRevisionSynced=-1 localRepositoryRevisionInProgress=2
08:05:13.987 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path=''
08:05:14.014 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path='/1'
08:05:14.028 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 31.31%
08:05:14.035 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 36.58%
08:05:14.035 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 41.85%
08:05:14.035 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/1/a'
08:05:14.046 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='15ba04dafcd0ca3c0e01c16dba4670304a8ab3db' to.sha1='<NoInstanceOf_NormalFileDto>'): /1/a
08:05:14.087 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=1, fromRepositoryId="FileInProgressMarker", toRepositoryId="1", path="2b0387f6-d81a-4990-b272-86883eba4a6c"] on repo=f5b3b245-1256-489d-9e6e-27aaf4d8b908
08:05:14.088 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/1/a'
08:05:14.088 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='15ba04dafcd0ca3c0e01c16dba4670304a8ab3db' toSha1='null'
08:05:14.088 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/1/a'
08:05:14.088 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/1/a' offset=0
08:05:14.090 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/1/a' offset=0
08:05:14.096 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 83385 bytes in 8 ms. path='/1/a'
08:05:14.100 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_86f7e437faa5a7fce15d1ddcb9eaeaea377667b8_0 into destFile a
08:05:14.138 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=1, fromRepositoryId="FileInProgressMarker", toRepositoryId="1", path="2b0387f6-d81a-4990-b272-86883eba4a6c"] on repo=f5b3b245-1256-489d-9e6e-27aaf4d8b908
08:05:14.144 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/1/b'
08:05:14.156 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='81f7b327819997cff8c5df56d5ff4e6cc6509f3d' to.sha1='<NoInstanceOf_NormalFileDto>'): /1/b
08:05:14.169 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=2, fromRepositoryId="FileInProgressMarker", toRepositoryId="2", path="2b0387f6-d81a-4990-b272-86883eba4a6c"] on repo=f5b3b245-1256-489d-9e6e-27aaf4d8b908
08:05:14.171 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/1/b'
08:05:14.171 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='81f7b327819997cff8c5df56d5ff4e6cc6509f3d' toSha1='null'
08:05:14.171 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/1/b'
08:05:14.171 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/1/b' offset=0
08:05:14.174 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/1/b' offset=0
08:05:14.182 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 275355 bytes in 11 ms. path='/1/b'
08:05:14.186 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_e9d71f5ee7c92d6dc9e92ffdad17b8bd49418f98_0 into destFile b
08:05:14.206 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=2, fromRepositoryId="FileInProgressMarker", toRepositoryId="2", path="2b0387f6-d81a-4990-b272-86883eba4a6c"] on repo=f5b3b245-1256-489d-9e6e-27aaf4d8b908
08:05:14.210 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/a'
08:05:14.217 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='a937ab15d6507f4e76350433a2f1fa4a04d9ef7c' to.sha1='<NoInstanceOf_NormalFileDto>'): /a
08:05:14.228 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=3, fromRepositoryId="FileInProgressMarker", toRepositoryId="3", path="2b0387f6-d81a-4990-b272-86883eba4a6c"] on repo=f5b3b245-1256-489d-9e6e-27aaf4d8b908
08:05:14.229 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/a'
08:05:14.230 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='a937ab15d6507f4e76350433a2f1fa4a04d9ef7c' toSha1='null'
08:05:14.230 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/a'
08:05:14.230 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/a' offset=0
08:05:14.234 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/a' offset=0
08:05:14.242 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 327965 bytes in 12 ms. path='/a'
08:05:14.245 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_86f7e437faa5a7fce15d1ddcb9eaeaea377667b8_0 into destFile a
08:05:14.268 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=3, fromRepositoryId="FileInProgressMarker", toRepositoryId="3", path="2b0387f6-d81a-4990-b272-86883eba4a6c"] on repo=f5b3b245-1256-489d-9e6e-27aaf4d8b908
08:05:14.270 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 48.43%
08:05:14.289 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=2b0387f6-d81a-4990-b272-86883eba4a6c remoteRepositoryId=f5b3b245-1256-489d-9e6e-27aaf4d8b908 localRepositoryRevisionSynced=2
08:05:14.302 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=f5b3b245-1256-489d-9e6e-27aaf4d8b908 remoteRepositoryId=2b0387f6-d81a-4990-b272-86883eba4a6c transaction.localRevision=19 remoteFromLocalRevision=2
08:05:14.304 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncUp: fromID=f5b3b245-1256-489d-9e6e-27aaf4d8b908 from='target/repo/jz6kd8pp-lxl1s-local' toID=2b0387f6-d81a-4990-b272-86883eba4a6c to='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/jz6kd8pq-cm8so-remote/2'
08:05:14.326 [main] INFO c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=f5b3b245-1256-489d-9e6e-27aaf4d8b908 remoteRepositoryId=2b0387f6-d81a-4990-b272-86883eba4a6c localRepositoryRevisionSynced=-1 localRepositoryRevisionInProgress=19
08:05:14.336 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 63.43%
08:05:14.336 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 74.63%
08:05:14.348 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=f5b3b245-1256-489d-9e6e-27aaf4d8b908 remoteRepositoryId=2b0387f6-d81a-4990-b272-86883eba4a6c localRepositoryRevisionSynced=19
08:05:14.356 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=2b0387f6-d81a-4990-b272-86883eba4a6c remoteRepositoryId=f5b3b245-1256-489d-9e6e-27aaf4d8b908 transaction.localRevision=5 remoteFromLocalRevision=19
08:05:14.361 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDown: fromID=2b0387f6-d81a-4990-b272-86883eba4a6c from='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/jz6kd8pq-cm8so-remote/2' toID=f5b3b245-1256-489d-9e6e-27aaf4d8b908 to='target/repo/jz6kd8pp-lxl1s-local', fromRepoLocalSync=false
08:05:14.379 [main] INFO c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=2b0387f6-d81a-4990-b272-86883eba4a6c remoteRepositoryId=f5b3b245-1256-489d-9e6e-27aaf4d8b908 localRepositoryRevisionSynced=2 localRepositoryRevisionInProgress=5
08:05:14.386 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 88.31%
08:05:14.386 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 99.50%
08:05:14.389 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=2b0387f6-d81a-4990-b272-86883eba4a6c remoteRepositoryId=f5b3b245-1256-489d-9e6e-27aaf4d8b908 localRepositoryRevisionSynced=5
08:05:14.398 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=f5b3b245-1256-489d-9e6e-27aaf4d8b908 remoteRepositoryId=2b0387f6-d81a-4990-b272-86883eba4a6c transaction.localRevision=22 remoteFromLocalRevision=5
08:05:14.399 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 100.00%
08:05:14.415 [main] INFO c.c.c.l.LocalRepoManagerImpl - [6604a666]getCloseDeferredMillis: closeDeferredMillis=20000
08:05:14.415 [main] INFO c.c.c.l.LocalRepoManagerImpl - [6604a666]close: Deferring shut down of real LocalRepoManager 20000 ms.
08:05:14.416 [main] INFO c.c.c.l.LocalRepoManagerImpl - [4b347d5e]getCloseDeferredMillis: closeDeferredMillis=20000
08:05:14.416 [main] INFO c.c.c.l.LocalRepoManagerImpl - [4b347d5e]close: Deferring shut down of real LocalRepoManager 20000 ms.
08:05:14.446 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 1.00%
08:05:14.448 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 50.00%
08:05:14.449 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 58.17%
08:05:14.453 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 63.61%
08:05:14.457 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 74.50%
08:05:14.492 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 80.62%
08:05:14.495 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 90.83%
08:05:14.507 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 96.55%
08:05:14.528 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 100.00%
08:05:14.561 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 0.50%
08:05:14.562 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDown: fromID=2b0387f6-d81a-4990-b272-86883eba4a6c from='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/jz6kd8pq-cm8so-remote/2' toID=f5b3b245-1256-489d-9e6e-27aaf4d8b908 to='target/repo/jz6kd8pp-lxl1s-local', fromRepoLocalSync=true
08:05:14.563 [pool-32-thread-1] INFO c.c.c.c.r.s.RepoToRepoSync - sync: locally syncing f5b3b245-1256-489d-9e6e-27aaf4d8b908 ('target/repo/jz6kd8pp-lxl1s-local')
08:05:14.576 [pool-32-thread-1] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 12.94%
08:05:14.586 [pool-32-thread-1] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 18.27%
08:05:14.598 [pool-32-thread-1] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 25.12%
08:05:14.606 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 1.00%
08:05:14.609 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 50.00%
08:05:14.612 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 58.17%
08:05:14.618 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 63.61%
08:05:14.625 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 74.50%
08:05:14.639 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 80.62%
08:05:14.643 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 90.83%
08:05:14.648 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 96.55%
08:05:14.657 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 100.00%
08:05:14.667 [main] INFO c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=2b0387f6-d81a-4990-b272-86883eba4a6c remoteRepositoryId=f5b3b245-1256-489d-9e6e-27aaf4d8b908 localRepositoryRevisionSynced=5 localRepositoryRevisionInProgress=8
08:05:14.682 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path='/1'
08:05:14.693 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 31.31%
08:05:14.693 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path='/1/5'
08:05:14.705 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 36.58%
08:05:14.705 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 41.85%
08:05:14.705 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/1/5/aaa'
08:05:14.714 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='6cf2256c663693ee9aee303cca661758c703f1ca' to.sha1='<NoInstanceOf_NormalFileDto>'): /1/5/aaa
08:05:14.727 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=4, fromRepositoryId="FileInProgressMarker", toRepositoryId="4", path="2b0387f6-d81a-4990-b272-86883eba4a6c"] on repo=f5b3b245-1256-489d-9e6e-27aaf4d8b908
08:05:14.730 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/1/5/aaa'
08:05:14.730 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='6cf2256c663693ee9aee303cca661758c703f1ca' toSha1='null'
08:05:14.730 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/1/5/aaa'
08:05:14.731 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/1/5/aaa' offset=0
08:05:14.745 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/1/5/aaa' offset=0
08:05:14.758 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 433245 bytes in 27 ms. path='/1/5/aaa'
08:05:14.763 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_7e240de74fb1ed08fa08d38063f6a6a91462a815_0 into destFile aaa
08:05:14.788 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=4, fromRepositoryId="FileInProgressMarker", toRepositoryId="4", path="2b0387f6-d81a-4990-b272-86883eba4a6c"] on repo=f5b3b245-1256-489d-9e6e-27aaf4d8b908
08:05:14.791 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/1/5/bbb'
08:05:14.803 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='0812478aa6a2f549d4a762916ec3f1b71dfe6f84' to.sha1='<NoInstanceOf_NormalFileDto>'): /1/5/bbb
08:05:14.817 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=5, fromRepositoryId="FileInProgressMarker", toRepositoryId="5", path="2b0387f6-d81a-4990-b272-86883eba4a6c"] on repo=f5b3b245-1256-489d-9e6e-27aaf4d8b908
08:05:14.818 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/1/5/bbb'
08:05:14.819 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='0812478aa6a2f549d4a762916ec3f1b71dfe6f84' toSha1='null'
08:05:14.819 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/1/5/bbb'
08:05:14.819 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/1/5/bbb' offset=0
08:05:14.821 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/1/5/bbb' offset=0
08:05:14.828 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 169062 bytes in 9 ms. path='/1/5/bbb'
08:05:14.833 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_5cb138284d431abd6a053a56625ec088bfb88912_0 into destFile bbb
08:05:14.853 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=5, fromRepositoryId="FileInProgressMarker", toRepositoryId="5", path="2b0387f6-d81a-4990-b272-86883eba4a6c"] on repo=f5b3b245-1256-489d-9e6e-27aaf4d8b908
08:05:14.861 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 48.43%
08:05:14.867 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=2b0387f6-d81a-4990-b272-86883eba4a6c remoteRepositoryId=f5b3b245-1256-489d-9e6e-27aaf4d8b908 localRepositoryRevisionSynced=8
08:05:14.875 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=f5b3b245-1256-489d-9e6e-27aaf4d8b908 remoteRepositoryId=2b0387f6-d81a-4990-b272-86883eba4a6c transaction.localRevision=35 remoteFromLocalRevision=8
08:05:14.876 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncUp: fromID=f5b3b245-1256-489d-9e6e-27aaf4d8b908 from='target/repo/jz6kd8pp-lxl1s-local' toID=2b0387f6-d81a-4990-b272-86883eba4a6c to='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/jz6kd8pq-cm8so-remote/2'
08:05:14.894 [main] INFO c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=f5b3b245-1256-489d-9e6e-27aaf4d8b908 remoteRepositoryId=2b0387f6-d81a-4990-b272-86883eba4a6c localRepositoryRevisionSynced=19 localRepositoryRevisionInProgress=35
08:05:14.896 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 63.43%
08:05:14.896 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 74.63%
08:05:14.899 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=f5b3b245-1256-489d-9e6e-27aaf4d8b908 remoteRepositoryId=2b0387f6-d81a-4990-b272-86883eba4a6c localRepositoryRevisionSynced=35
08:05:14.903 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=2b0387f6-d81a-4990-b272-86883eba4a6c remoteRepositoryId=f5b3b245-1256-489d-9e6e-27aaf4d8b908 transaction.localRevision=11 remoteFromLocalRevision=35
08:05:14.909 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDown: fromID=2b0387f6-d81a-4990-b272-86883eba4a6c from='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/jz6kd8pq-cm8so-remote/2' toID=f5b3b245-1256-489d-9e6e-27aaf4d8b908 to='target/repo/jz6kd8pp-lxl1s-local', fromRepoLocalSync=false
08:05:14.921 [main] INFO c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=2b0387f6-d81a-4990-b272-86883eba4a6c remoteRepositoryId=f5b3b245-1256-489d-9e6e-27aaf4d8b908 localRepositoryRevisionSynced=8 localRepositoryRevisionInProgress=11
08:05:14.926 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 88.31%
08:05:14.926 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 99.50%
08:05:14.932 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=2b0387f6-d81a-4990-b272-86883eba4a6c remoteRepositoryId=f5b3b245-1256-489d-9e6e-27aaf4d8b908 localRepositoryRevisionSynced=11
08:05:14.941 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=f5b3b245-1256-489d-9e6e-27aaf4d8b908 remoteRepositoryId=2b0387f6-d81a-4990-b272-86883eba4a6c transaction.localRevision=38 remoteFromLocalRevision=11
08:05:14.943 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 100.00%
08:05:14.944 [main] INFO c.c.c.l.LocalRepoManagerImpl - [6604a666]close: Deferring shut down of real LocalRepoManager 20000 ms.
08:05:14.960 [main] INFO c.c.c.l.LocalRepoManagerImpl - [4b347d5e]close: Deferring shut down of real LocalRepoManager 20000 ms.
08:05:14.989 [main] INFO c.c.c.l.LocalRepoManagerImpl - [6a95894f]<init>: localRoot='/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/jz6kdf1o-78sry-local'
08:05:14.995 [main] INFO c.c.c.l.LocalRepoManagerImpl - [6a95894f]initLockFile: Repository '/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/jz6kdf1o-78sry-local' locked successfully.
08:05:15.689 [closeDeferredTimer-1db8425f-3] INFO c.c.c.l.LocalRepoManagerImpl - [1db8425f]_close: Shutting down real LocalRepoManager.
08:05:15.690 [closeDeferredTimer-1ac99c93-3] INFO c.c.c.l.LocalRepoManagerImpl - [1ac99c93]_close: Shutting down real LocalRepoManager.
08:05:19.799 [main] INFO c.c.c.l.LocalRepoManagerImpl - [6a95894f]initPersistenceManagerFactory: repositoryId=8c85f13b-6bd5-48fe-8af6-877b30c59c94
08:05:19.801 [main] INFO c.c.c.l.LocalRepoManagerImpl - [6a95894f]initPersistenceManagerFactory: Started up PersistenceManagerFactory successfully in 1722 ms.
08:05:19.827 [main] INFO c.c.c.l.LocalRepoManagerImpl - [544d0c82]<init>: localRoot='/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/jz6kdf1o-ixlzy-remote'
08:05:19.834 [main] INFO c.c.c.l.LocalRepoManagerImpl - [544d0c82]initLockFile: Repository '/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/jz6kdf1o-ixlzy-remote' locked successfully.
08:05:23.548 [main] INFO c.c.c.l.LocalRepoManagerImpl - [544d0c82]initPersistenceManagerFactory: repositoryId=47f6727e-6a0c-4acf-8348-3fc45e2e97b8
08:05:23.550 [main] INFO c.c.c.l.LocalRepoManagerImpl - [544d0c82]initPersistenceManagerFactory: Started up PersistenceManagerFactory successfully in 1457 ms.
08:05:23.654 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 1.00%
08:05:23.661 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 50.00%
08:05:23.672 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 58.00%
08:05:23.722 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 63.61%
08:05:23.735 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 74.34%
08:05:23.776 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 80.56%
08:05:23.790 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 90.67%
08:05:23.836 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 95.91%
08:05:23.856 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 100.00%
08:05:23.885 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - local repo: 8c85f13b-6bd5-48fe-8af6-877b30c59c94
08:05:23.885 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - remote repo: 47f6727e-6a0c-4acf-8348-3fc45e2e97b8
08:05:23.891 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 0.50%
08:05:23.905 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDown: fromID=47f6727e-6a0c-4acf-8348-3fc45e2e97b8 from='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/jz6kdf1o-ixlzy-remote' toID=8c85f13b-6bd5-48fe-8af6-877b30c59c94 to='target/repo/jz6kdf1o-78sry-local', fromRepoLocalSync=true
08:05:23.905 [pool-33-thread-1] INFO c.c.c.c.r.s.RepoToRepoSync - sync: locally syncing 8c85f13b-6bd5-48fe-8af6-877b30c59c94 ('target/repo/jz6kdf1o-78sry-local')
08:05:23.954 [pool-33-thread-1] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 12.94%
08:05:23.961 [pool-33-thread-1] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 25.12%
08:05:23.968 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 1.00%
08:05:23.971 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 50.00%
08:05:23.974 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 58.17%
08:05:23.978 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 63.61%
08:05:23.983 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 74.50%
08:05:23.990 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 80.62%
08:05:23.993 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 90.83%
08:05:23.998 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 95.94%
08:05:24.005 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 100.00%
08:05:24.026 [main] INFO c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=47f6727e-6a0c-4acf-8348-3fc45e2e97b8 remoteRepositoryId=8c85f13b-6bd5-48fe-8af6-877b30c59c94 localRepositoryRevisionSynced=-1 localRepositoryRevisionInProgress=2
08:05:24.062 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path=''
08:05:24.090 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path='/1'
08:05:24.106 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 30.22%
08:05:24.106 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path='/2'
08:05:24.115 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path='/2/1'
08:05:24.125 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path='/3'
08:05:24.140 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 35.46%
08:05:24.140 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 40.70%
08:05:24.140 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/1/a'
08:05:24.149 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='dc2b88f8110f46a54b5a18701e07e57af1cf2812' to.sha1='<NoInstanceOf_NormalFileDto>'): /1/a
08:05:24.218 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=1, fromRepositoryId="FileInProgressMarker", toRepositoryId="1", path="47f6727e-6a0c-4acf-8348-3fc45e2e97b8"] on repo=8c85f13b-6bd5-48fe-8af6-877b30c59c94
08:05:24.260 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/1/a'
08:05:24.260 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='dc2b88f8110f46a54b5a18701e07e57af1cf2812' toSha1='null'
08:05:24.261 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/1/a'
08:05:24.261 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/1/a' offset=0
08:05:24.264 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/1/a' offset=0
08:05:24.275 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 64902 bytes in 14 ms. path='/1/a'
08:05:24.280 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_86f7e437faa5a7fce15d1ddcb9eaeaea377667b8_0 into destFile a
08:05:24.315 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=1, fromRepositoryId="FileInProgressMarker", toRepositoryId="1", path="47f6727e-6a0c-4acf-8348-3fc45e2e97b8"] on repo=8c85f13b-6bd5-48fe-8af6-877b30c59c94
08:05:24.333 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/1/b'
08:05:24.346 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='46f62c42fbe27d57bba1bd5430325f209feb8afd' to.sha1='<NoInstanceOf_NormalFileDto>'): /1/b
08:05:24.359 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=2, fromRepositoryId="FileInProgressMarker", toRepositoryId="2", path="47f6727e-6a0c-4acf-8348-3fc45e2e97b8"] on repo=8c85f13b-6bd5-48fe-8af6-877b30c59c94
08:05:24.361 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/1/b'
08:05:24.361 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='46f62c42fbe27d57bba1bd5430325f209feb8afd' toSha1='null'
08:05:24.361 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/1/b'
08:05:24.361 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/1/b' offset=0
08:05:24.367 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/1/b' offset=0
08:05:24.380 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 505787 bytes in 19 ms. path='/1/b'
08:05:24.386 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_e9d71f5ee7c92d6dc9e92ffdad17b8bd49418f98_0 into destFile b
08:05:24.418 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=2, fromRepositoryId="FileInProgressMarker", toRepositoryId="2", path="47f6727e-6a0c-4acf-8348-3fc45e2e97b8"] on repo=8c85f13b-6bd5-48fe-8af6-877b30c59c94
08:05:24.423 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/1/c'
08:05:24.434 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='f0565c767bb84a0dd1760436c29893bb606523bd' to.sha1='<NoInstanceOf_NormalFileDto>'): /1/c
08:05:24.451 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=3, fromRepositoryId="FileInProgressMarker", toRepositoryId="3", path="47f6727e-6a0c-4acf-8348-3fc45e2e97b8"] on repo=8c85f13b-6bd5-48fe-8af6-877b30c59c94
08:05:24.453 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/1/c'
08:05:24.453 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='f0565c767bb84a0dd1760436c29893bb606523bd' toSha1='null'
08:05:24.453 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/1/c'
08:05:24.453 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/1/c' offset=0
08:05:24.455 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/1/c' offset=0
08:05:24.462 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 117630 bytes in 9 ms. path='/1/c'
08:05:24.467 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_84a516841ba77a5b4648de2cd0dfcb30ea46dbb4_0 into destFile c
08:05:24.481 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=3, fromRepositoryId="FileInProgressMarker", toRepositoryId="3", path="47f6727e-6a0c-4acf-8348-3fc45e2e97b8"] on repo=8c85f13b-6bd5-48fe-8af6-877b30c59c94
08:05:24.483 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/2/1/a'
08:05:24.493 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='6f6f968885ec1315683454e8fbc6a5aa12983236' to.sha1='<NoInstanceOf_NormalFileDto>'): /2/1/a
08:05:24.506 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=4, fromRepositoryId="FileInProgressMarker", toRepositoryId="4", path="47f6727e-6a0c-4acf-8348-3fc45e2e97b8"] on repo=8c85f13b-6bd5-48fe-8af6-877b30c59c94
08:05:24.507 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/2/1/a'
08:05:24.507 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='6f6f968885ec1315683454e8fbc6a5aa12983236' toSha1='null'
08:05:24.507 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/2/1/a'
08:05:24.507 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/2/1/a' offset=0
08:05:24.517 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/2/1/a' offset=0
08:05:24.534 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 885998 bytes in 27 ms. path='/2/1/a'
08:05:24.539 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_86f7e437faa5a7fce15d1ddcb9eaeaea377667b8_0 into destFile a
08:05:24.581 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=4, fromRepositoryId="FileInProgressMarker", toRepositoryId="4", path="47f6727e-6a0c-4acf-8348-3fc45e2e97b8"] on repo=8c85f13b-6bd5-48fe-8af6-877b30c59c94
08:05:24.584 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/2/1/b'
08:05:24.599 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='048157b2c8045e33983076a36dd6f994e000eedc' to.sha1='<NoInstanceOf_NormalFileDto>'): /2/1/b
08:05:24.613 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=5, fromRepositoryId="FileInProgressMarker", toRepositoryId="5", path="47f6727e-6a0c-4acf-8348-3fc45e2e97b8"] on repo=8c85f13b-6bd5-48fe-8af6-877b30c59c94
08:05:24.616 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/2/1/b'
08:05:24.616 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='048157b2c8045e33983076a36dd6f994e000eedc' toSha1='null'
08:05:24.616 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/2/1/b'
08:05:24.616 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/2/1/b' offset=0
08:05:24.618 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/2/1/b' offset=0
08:05:24.627 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 156849 bytes in 11 ms. path='/2/1/b'
08:05:24.634 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_e9d71f5ee7c92d6dc9e92ffdad17b8bd49418f98_0 into destFile b
08:05:24.649 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=5, fromRepositoryId="FileInProgressMarker", toRepositoryId="5", path="47f6727e-6a0c-4acf-8348-3fc45e2e97b8"] on repo=8c85f13b-6bd5-48fe-8af6-877b30c59c94
08:05:24.651 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/2/a'
08:05:24.659 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='66158a779ecae95d6b89e45f70e84b0b352e5c26' to.sha1='<NoInstanceOf_NormalFileDto>'): /2/a
08:05:24.671 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=6, fromRepositoryId="FileInProgressMarker", toRepositoryId="6", path="47f6727e-6a0c-4acf-8348-3fc45e2e97b8"] on repo=8c85f13b-6bd5-48fe-8af6-877b30c59c94
08:05:24.672 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/2/a'
08:05:24.672 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='66158a779ecae95d6b89e45f70e84b0b352e5c26' toSha1='null'
08:05:24.672 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/2/a'
08:05:24.672 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/2/a' offset=0
08:05:24.672 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/2/a' offset=0
08:05:24.677 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 10544 bytes in 5 ms. path='/2/a'
08:05:24.681 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_86f7e437faa5a7fce15d1ddcb9eaeaea377667b8_0 into destFile a
08:05:24.690 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=6, fromRepositoryId="FileInProgressMarker", toRepositoryId="6", path="47f6727e-6a0c-4acf-8348-3fc45e2e97b8"] on repo=8c85f13b-6bd5-48fe-8af6-877b30c59c94
08:05:24.693 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/3/a'
08:05:24.701 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='0731115706bef43d2a533637cd4de7905baf8e4f' to.sha1='<NoInstanceOf_NormalFileDto>'): /3/a
08:05:24.713 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=7, fromRepositoryId="FileInProgressMarker", toRepositoryId="7", path="47f6727e-6a0c-4acf-8348-3fc45e2e97b8"] on repo=8c85f13b-6bd5-48fe-8af6-877b30c59c94
08:05:24.715 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/3/a'
08:05:24.715 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='0731115706bef43d2a533637cd4de7905baf8e4f' toSha1='null'
08:05:24.715 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/3/a'
08:05:24.715 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/3/a' offset=0
08:05:24.716 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/3/a' offset=0
08:05:24.723 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 44538 bytes in 8 ms. path='/3/a'
08:05:24.729 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_86f7e437faa5a7fce15d1ddcb9eaeaea377667b8_0 into destFile a
08:05:24.739 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=7, fromRepositoryId="FileInProgressMarker", toRepositoryId="7", path="47f6727e-6a0c-4acf-8348-3fc45e2e97b8"] on repo=8c85f13b-6bd5-48fe-8af6-877b30c59c94
08:05:24.742 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/3/b'
08:05:24.750 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='12ed41a7cd01230a76320cbcfca0f083069550e7' to.sha1='<NoInstanceOf_NormalFileDto>'): /3/b
08:05:24.762 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=8, fromRepositoryId="FileInProgressMarker", toRepositoryId="8", path="47f6727e-6a0c-4acf-8348-3fc45e2e97b8"] on repo=8c85f13b-6bd5-48fe-8af6-877b30c59c94
08:05:24.763 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/3/b'
08:05:24.763 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='12ed41a7cd01230a76320cbcfca0f083069550e7' toSha1='null'
08:05:24.763 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/3/b'
08:05:24.763 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/3/b' offset=0
08:05:24.768 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/3/b' offset=0
08:05:24.779 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 467445 bytes in 16 ms. path='/3/b'
08:05:24.783 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_e9d71f5ee7c92d6dc9e92ffdad17b8bd49418f98_0 into destFile b
08:05:24.814 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=8, fromRepositoryId="FileInProgressMarker", toRepositoryId="8", path="47f6727e-6a0c-4acf-8348-3fc45e2e97b8"] on repo=8c85f13b-6bd5-48fe-8af6-877b30c59c94
08:05:24.817 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/3/c'
08:05:24.827 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='14dd1aee75f328f31205a9db7908c4e71c50faed' to.sha1='<NoInstanceOf_NormalFileDto>'): /3/c
08:05:24.839 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=9, fromRepositoryId="FileInProgressMarker", toRepositoryId="9", path="47f6727e-6a0c-4acf-8348-3fc45e2e97b8"] on repo=8c85f13b-6bd5-48fe-8af6-877b30c59c94
08:05:24.840 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/3/c'
08:05:24.840 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='14dd1aee75f328f31205a9db7908c4e71c50faed' toSha1='null'
08:05:24.840 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/3/c'
08:05:24.840 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/3/c' offset=0
08:05:24.851 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/3/c' offset=0
08:05:24.871 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 982560 bytes in 30 ms. path='/3/c'
08:05:24.875 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_84a516841ba77a5b4648de2cd0dfcb30ea46dbb4_0 into destFile c
08:05:24.922 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=9, fromRepositoryId="FileInProgressMarker", toRepositoryId="9", path="47f6727e-6a0c-4acf-8348-3fc45e2e97b8"] on repo=8c85f13b-6bd5-48fe-8af6-877b30c59c94
08:05:24.926 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/3/d'
08:05:24.936 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='de55bae36621515847b9bb8cbc381842997dff4b' to.sha1='<NoInstanceOf_NormalFileDto>'): /3/d
08:05:24.949 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=10, fromRepositoryId="FileInProgressMarker", toRepositoryId="10", path="47f6727e-6a0c-4acf-8348-3fc45e2e97b8"] on repo=8c85f13b-6bd5-48fe-8af6-877b30c59c94
08:05:24.951 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/3/d'
08:05:24.951 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='de55bae36621515847b9bb8cbc381842997dff4b' toSha1='null'
08:05:24.951 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/3/d'
08:05:24.951 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/3/d' offset=0
08:05:24.953 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/3/d' offset=0
08:05:24.963 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 94700 bytes in 12 ms. path='/3/d'
08:05:24.969 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_3c363836cf4e16666669a25da280a1865c2d2874_0 into destFile d
08:05:24.985 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=10, fromRepositoryId="FileInProgressMarker", toRepositoryId="10", path="47f6727e-6a0c-4acf-8348-3fc45e2e97b8"] on repo=8c85f13b-6bd5-48fe-8af6-877b30c59c94
08:05:24.989 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 49.27%
08:05:25.008 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=47f6727e-6a0c-4acf-8348-3fc45e2e97b8 remoteRepositoryId=8c85f13b-6bd5-48fe-8af6-877b30c59c94 localRepositoryRevisionSynced=2
08:05:25.017 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=8c85f13b-6bd5-48fe-8af6-877b30c59c94 remoteRepositoryId=47f6727e-6a0c-4acf-8348-3fc45e2e97b8 transaction.localRevision=57 remoteFromLocalRevision=2
08:05:25.018 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncUp: fromID=8c85f13b-6bd5-48fe-8af6-877b30c59c94 from='target/repo/jz6kdf1o-78sry-local' toID=47f6727e-6a0c-4acf-8348-3fc45e2e97b8 to='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/jz6kdf1o-ixlzy-remote'
08:05:25.038 [main] INFO c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=8c85f13b-6bd5-48fe-8af6-877b30c59c94 remoteRepositoryId=47f6727e-6a0c-4acf-8348-3fc45e2e97b8 localRepositoryRevisionSynced=-1 localRepositoryRevisionInProgress=57
08:05:25.049 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 63.43%
08:05:25.050 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 74.63%
08:05:25.061 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=8c85f13b-6bd5-48fe-8af6-877b30c59c94 remoteRepositoryId=47f6727e-6a0c-4acf-8348-3fc45e2e97b8 localRepositoryRevisionSynced=57
08:05:25.071 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=47f6727e-6a0c-4acf-8348-3fc45e2e97b8 remoteRepositoryId=8c85f13b-6bd5-48fe-8af6-877b30c59c94 transaction.localRevision=5 remoteFromLocalRevision=57
08:05:25.074 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDown: fromID=47f6727e-6a0c-4acf-8348-3fc45e2e97b8 from='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/jz6kdf1o-ixlzy-remote' toID=8c85f13b-6bd5-48fe-8af6-877b30c59c94 to='target/repo/jz6kdf1o-78sry-local', fromRepoLocalSync=false
08:05:25.091 [main] INFO c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=47f6727e-6a0c-4acf-8348-3fc45e2e97b8 remoteRepositoryId=8c85f13b-6bd5-48fe-8af6-877b30c59c94 localRepositoryRevisionSynced=2 localRepositoryRevisionInProgress=5
08:05:25.094 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 88.31%
08:05:25.094 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 99.50%
08:05:25.097 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=47f6727e-6a0c-4acf-8348-3fc45e2e97b8 remoteRepositoryId=8c85f13b-6bd5-48fe-8af6-877b30c59c94 localRepositoryRevisionSynced=5
08:05:25.101 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=8c85f13b-6bd5-48fe-8af6-877b30c59c94 remoteRepositoryId=47f6727e-6a0c-4acf-8348-3fc45e2e97b8 transaction.localRevision=60 remoteFromLocalRevision=5
08:05:25.103 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 100.00%
08:05:25.115 [main] INFO c.c.c.l.LocalRepoManagerImpl - [6a95894f]getCloseDeferredMillis: closeDeferredMillis=20000
08:05:25.115 [main] INFO c.c.c.l.LocalRepoManagerImpl - [6a95894f]close: Deferring shut down of real LocalRepoManager 20000 ms.
08:05:25.117 [main] INFO c.c.c.l.LocalRepoManagerImpl - [544d0c82]getCloseDeferredMillis: closeDeferredMillis=20000
08:05:25.117 [main] INFO c.c.c.l.LocalRepoManagerImpl - [544d0c82]close: Deferring shut down of real LocalRepoManager 20000 ms.
08:05:25.189 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 1.00%
08:05:25.190 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 50.00%
08:05:25.192 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 58.17%
08:05:25.196 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 63.61%
08:05:25.200 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 74.50%
08:05:25.236 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 80.62%
08:05:25.240 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 90.83%
08:05:25.251 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 96.55%
08:05:25.268 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 100.00%
08:05:25.292 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 0.50%
08:05:25.309 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDown: fromID=47f6727e-6a0c-4acf-8348-3fc45e2e97b8 from='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/jz6kdf1o-ixlzy-remote' toID=8c85f13b-6bd5-48fe-8af6-877b30c59c94 to='target/repo/jz6kdf1o-78sry-local', fromRepoLocalSync=true
08:05:25.310 [pool-34-thread-1] INFO c.c.c.c.r.s.RepoToRepoSync - sync: locally syncing 8c85f13b-6bd5-48fe-8af6-877b30c59c94 ('target/repo/jz6kdf1o-78sry-local')
08:05:25.317 [pool-34-thread-1] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 12.94%
08:05:25.345 [pool-34-thread-1] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 19.03%
08:05:25.373 [pool-34-thread-1] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 24.11%
08:05:25.390 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 1.00%
08:05:25.391 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 50.00%
08:05:25.392 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 58.17%
08:05:25.397 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 63.61%
08:05:25.403 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 74.50%
08:05:25.419 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 80.62%
08:05:25.423 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 90.83%
08:05:25.430 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 96.55%
08:05:25.439 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 100.00%
08:05:25.446 [main] INFO c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=47f6727e-6a0c-4acf-8348-3fc45e2e97b8 remoteRepositoryId=8c85f13b-6bd5-48fe-8af6-877b30c59c94 localRepositoryRevisionSynced=5 localRepositoryRevisionInProgress=8
08:05:25.464 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 29.95%
08:05:25.465 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path='/2/1'
08:05:25.485 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path='/2/1/5'
08:05:25.500 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path='/3'
08:05:25.512 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 35.11%
08:05:25.514 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 40.28%
08:05:25.514 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 45.45%
08:05:25.514 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/2/1/5/aaa'
08:05:25.530 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='e43e23425f97eca72330842068e16cf4988e2725' to.sha1='<NoInstanceOf_NormalFileDto>'): /2/1/5/aaa
08:05:25.554 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=11, fromRepositoryId="FileInProgressMarker", toRepositoryId="11", path="47f6727e-6a0c-4acf-8348-3fc45e2e97b8"] on repo=8c85f13b-6bd5-48fe-8af6-877b30c59c94
08:05:25.662 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/2/1/5/aaa'
08:05:25.662 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='e43e23425f97eca72330842068e16cf4988e2725' toSha1='null'
08:05:25.662 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/2/1/5/aaa'
08:05:25.662 [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
08:05:25.666 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/2/1/5/aaa' offset=0
08:05:25.679 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 121800 bytes in 17 ms. path='/2/1/5/aaa'
08:05:25.685 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_7e240de74fb1ed08fa08d38063f6a6a91462a815_0 into destFile aaa
08:05:25.699 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=11, fromRepositoryId="FileInProgressMarker", toRepositoryId="11", path="47f6727e-6a0c-4acf-8348-3fc45e2e97b8"] on repo=8c85f13b-6bd5-48fe-8af6-877b30c59c94
08:05:25.702 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/2/1/5/bbb'
08:05:25.720 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='8120c70c7eae9a2e4070538a88d7ea2fb651ad6e' to.sha1='<NoInstanceOf_NormalFileDto>'): /2/1/5/bbb
08:05:25.748 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=12, fromRepositoryId="FileInProgressMarker", toRepositoryId="12", path="47f6727e-6a0c-4acf-8348-3fc45e2e97b8"] on repo=8c85f13b-6bd5-48fe-8af6-877b30c59c94
08:05:25.750 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/2/1/5/bbb'
08:05:25.750 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='8120c70c7eae9a2e4070538a88d7ea2fb651ad6e' toSha1='null'
08:05:25.751 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/2/1/5/bbb'
08:05:25.751 [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
08:05:25.754 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/2/1/5/bbb' offset=0
08:05:25.765 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 197028 bytes in 14 ms. path='/2/1/5/bbb'
08:05:25.772 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_5cb138284d431abd6a053a56625ec088bfb88912_0 into destFile bbb
08:05:25.792 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=12, fromRepositoryId="FileInProgressMarker", toRepositoryId="12", path="47f6727e-6a0c-4acf-8348-3fc45e2e97b8"] on repo=8c85f13b-6bd5-48fe-8af6-877b30c59c94
08:05:25.795 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/3/e'
08:05:25.810 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='9318556abd815131d186fa2d99c366a1a38763d4' to.sha1='<NoInstanceOf_NormalFileDto>'): /3/e
08:05:25.826 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=13, fromRepositoryId="FileInProgressMarker", toRepositoryId="13", path="47f6727e-6a0c-4acf-8348-3fc45e2e97b8"] on repo=8c85f13b-6bd5-48fe-8af6-877b30c59c94
08:05:25.828 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/3/e'
08:05:25.828 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='9318556abd815131d186fa2d99c366a1a38763d4' toSha1='null'
08:05:25.828 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/3/e'
08:05:25.829 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/3/e' offset=0
08:05:25.830 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/3/e' offset=0
08:05:25.837 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 41804 bytes in 9 ms. path='/3/e'
08:05:25.843 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_58e6b3a414a1e090dfc6029add0f3555ccba127f_0 into destFile e
08:05:25.856 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=13, fromRepositoryId="FileInProgressMarker", toRepositoryId="13", path="47f6727e-6a0c-4acf-8348-3fc45e2e97b8"] on repo=8c85f13b-6bd5-48fe-8af6-877b30c59c94
08:05:25.864 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=47f6727e-6a0c-4acf-8348-3fc45e2e97b8 remoteRepositoryId=8c85f13b-6bd5-48fe-8af6-877b30c59c94 localRepositoryRevisionSynced=8
08:05:25.869 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=8c85f13b-6bd5-48fe-8af6-877b30c59c94 remoteRepositoryId=47f6727e-6a0c-4acf-8348-3fc45e2e97b8 transaction.localRevision=79 remoteFromLocalRevision=8
08:05:25.873 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncUp: fromID=8c85f13b-6bd5-48fe-8af6-877b30c59c94 from='target/repo/jz6kdf1o-78sry-local' toID=47f6727e-6a0c-4acf-8348-3fc45e2e97b8 to='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/jz6kdf1o-ixlzy-remote'
08:05:25.888 [main] INFO c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=8c85f13b-6bd5-48fe-8af6-877b30c59c94 remoteRepositoryId=47f6727e-6a0c-4acf-8348-3fc45e2e97b8 localRepositoryRevisionSynced=57 localRepositoryRevisionInProgress=79
08:05:25.890 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 52.24%
08:05:25.890 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 63.43%
08:05:25.890 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 74.63%
08:05:25.895 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=8c85f13b-6bd5-48fe-8af6-877b30c59c94 remoteRepositoryId=47f6727e-6a0c-4acf-8348-3fc45e2e97b8 localRepositoryRevisionSynced=79
08:05:25.899 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=47f6727e-6a0c-4acf-8348-3fc45e2e97b8 remoteRepositoryId=8c85f13b-6bd5-48fe-8af6-877b30c59c94 transaction.localRevision=11 remoteFromLocalRevision=79
08:05:25.901 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDown: fromID=47f6727e-6a0c-4acf-8348-3fc45e2e97b8 from='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/jz6kdf1o-ixlzy-remote' toID=8c85f13b-6bd5-48fe-8af6-877b30c59c94 to='target/repo/jz6kdf1o-78sry-local', fromRepoLocalSync=false
08:05:25.909 [main] INFO c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=47f6727e-6a0c-4acf-8348-3fc45e2e97b8 remoteRepositoryId=8c85f13b-6bd5-48fe-8af6-877b30c59c94 localRepositoryRevisionSynced=8 localRepositoryRevisionInProgress=11
08:05:25.911 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 88.31%
08:05:25.912 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 99.50%
08:05:25.916 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=47f6727e-6a0c-4acf-8348-3fc45e2e97b8 remoteRepositoryId=8c85f13b-6bd5-48fe-8af6-877b30c59c94 localRepositoryRevisionSynced=11
08:05:25.922 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=8c85f13b-6bd5-48fe-8af6-877b30c59c94 remoteRepositoryId=47f6727e-6a0c-4acf-8348-3fc45e2e97b8 transaction.localRevision=82 remoteFromLocalRevision=11
08:05:25.983 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 100.00%
08:05:25.983 [main] INFO c.c.c.l.LocalRepoManagerImpl - [6a95894f]close: Deferring shut down of real LocalRepoManager 20000 ms.
08:05:26.010 [main] INFO c.c.c.l.LocalRepoManagerImpl - [544d0c82]close: Deferring shut down of real LocalRepoManager 20000 ms.
08:05:26.107 [main] INFO c.c.c.l.LocalRepoManagerImpl - [5f70a5de]<init>: localRoot='/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/jz6kdnm9-yn8dq-local'
08:05:26.115 [main] INFO c.c.c.l.LocalRepoManagerImpl - [5f70a5de]initLockFile: Repository '/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/jz6kdnm9-yn8dq-local' locked successfully.
08:05:26.753 [closeDeferredTimer-34316d01-1] INFO c.c.c.l.LocalRepoManagerImpl - [34316d01]_close: Shutting down real LocalRepoManager.
08:05:27.178 [closeDeferredTimer-7eaca938-1] INFO c.c.c.l.LocalRepoManagerImpl - [7eaca938]_close: Shutting down real LocalRepoManager.
08:05:33.422 [main] INFO c.c.c.l.LocalRepoManagerImpl - [5f70a5de]initPersistenceManagerFactory: repositoryId=ebeea178-0b27-49c6-88d8-438df63952b5
08:05:33.429 [main] INFO c.c.c.l.LocalRepoManagerImpl - [5f70a5de]initPersistenceManagerFactory: Started up PersistenceManagerFactory successfully in 2611 ms.
08:05:33.456 [main] INFO c.c.c.l.LocalRepoManagerImpl - [5edf4926]<init>: localRoot='/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/jz6kdnm9-h29tn-remote'
08:05:33.463 [main] INFO c.c.c.l.LocalRepoManagerImpl - [5edf4926]initLockFile: Repository '/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/jz6kdnm9-h29tn-remote' locked successfully.
08:05:34.946 [closeDeferredTimer-6604a666-2] INFO c.c.c.l.LocalRepoManagerImpl - [6604a666]_close: Shutting down real LocalRepoManager.
08:05:35.207 [closeDeferredTimer-4b347d5e-2] INFO c.c.c.l.LocalRepoManagerImpl - [4b347d5e]_close: Shutting down real LocalRepoManager.
08:05:41.638 [main] INFO c.c.c.l.LocalRepoManagerImpl - [5edf4926]initPersistenceManagerFactory: repositoryId=e0925e66-6eab-46ba-b524-516a286091e3
08:05:41.640 [main] INFO c.c.c.l.LocalRepoManagerImpl - [5edf4926]initPersistenceManagerFactory: Started up PersistenceManagerFactory successfully in 2992 ms.
08:05:41.733 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 1.00%
08:05:41.742 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 50.00%
08:05:41.751 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 58.00%
08:05:41.796 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 63.61%
08:05:41.821 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 74.34%
08:05:41.853 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 79.56%
08:05:41.863 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 90.67%
08:05:41.894 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 95.72%
08:05:41.917 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 100.00%
08:05:41.937 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - local repo: ebeea178-0b27-49c6-88d8-438df63952b5
08:05:41.938 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - remote repo: e0925e66-6eab-46ba-b524-516a286091e3
08:05:41.942 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 0.50%
08:05:41.942 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDown: fromID=e0925e66-6eab-46ba-b524-516a286091e3 from='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/jz6kdnm9-h29tn-remote' toID=ebeea178-0b27-49c6-88d8-438df63952b5 to='target/repo/jz6kdnm9-yn8dq-local', fromRepoLocalSync=true
08:05:41.943 [pool-35-thread-1] INFO c.c.c.c.r.s.RepoToRepoSync - sync: locally syncing ebeea178-0b27-49c6-88d8-438df63952b5 ('target/repo/jz6kdnm9-yn8dq-local')
08:05:41.975 [pool-35-thread-1] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 12.94%
08:05:41.983 [pool-35-thread-1] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 25.12%
08:05:41.984 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 1.00%
08:05:41.986 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 50.00%
08:05:41.987 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 58.17%
08:05:41.993 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 63.61%
08:05:41.998 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 74.50%
08:05:42.003 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 80.62%
08:05:42.006 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 90.83%
08:05:42.009 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 95.94%
08:05:42.014 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 100.00%
08:05:42.030 [main] INFO c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=e0925e66-6eab-46ba-b524-516a286091e3 remoteRepositoryId=ebeea178-0b27-49c6-88d8-438df63952b5 localRepositoryRevisionSynced=-1 localRepositoryRevisionInProgress=2
08:05:42.059 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path=''
08:05:42.082 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path='/1'
08:05:42.096 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 30.22%
08:05:42.096 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path='/2'
08:05:42.104 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path='/2/1'
08:05:42.118 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path='/3'
08:05:42.138 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 35.46%
08:05:42.139 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 40.70%
08:05:42.139 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/1/a'
08:05:42.146 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='1078960a9587b1a065be6ee7713cfa5907590ea5' to.sha1='<NoInstanceOf_NormalFileDto>'): /1/a
08:05:42.182 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=1, fromRepositoryId="FileInProgressMarker", toRepositoryId="1", path="e0925e66-6eab-46ba-b524-516a286091e3"] on repo=ebeea178-0b27-49c6-88d8-438df63952b5
08:05:42.183 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/1/a'
08:05:42.183 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='1078960a9587b1a065be6ee7713cfa5907590ea5' toSha1='null'
08:05:42.183 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/1/a'
08:05:42.183 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/1/a' offset=0
08:05:42.192 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/1/a' offset=0
08:05:42.206 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 729448 bytes in 23 ms. path='/1/a'
08:05:42.211 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_86f7e437faa5a7fce15d1ddcb9eaeaea377667b8_0 into destFile a
08:05:42.268 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=1, fromRepositoryId="FileInProgressMarker", toRepositoryId="1", path="e0925e66-6eab-46ba-b524-516a286091e3"] on repo=ebeea178-0b27-49c6-88d8-438df63952b5
08:05:42.276 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/1/b'
08:05:42.292 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='db700c288e5cff006b3e7860654531b20cd74d7e' to.sha1='<NoInstanceOf_NormalFileDto>'): /1/b
08:05:42.306 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=2, fromRepositoryId="FileInProgressMarker", toRepositoryId="2", path="e0925e66-6eab-46ba-b524-516a286091e3"] on repo=ebeea178-0b27-49c6-88d8-438df63952b5
08:05:42.308 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/1/b'
08:05:42.308 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='db700c288e5cff006b3e7860654531b20cd74d7e' toSha1='null'
08:05:42.308 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/1/b'
08:05:42.308 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/1/b' offset=0
08:05:42.309 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/1/b' offset=0
08:05:42.316 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 91404 bytes in 8 ms. path='/1/b'
08:05:42.321 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_e9d71f5ee7c92d6dc9e92ffdad17b8bd49418f98_0 into destFile b
08:05:42.335 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=2, fromRepositoryId="FileInProgressMarker", toRepositoryId="2", path="e0925e66-6eab-46ba-b524-516a286091e3"] on repo=ebeea178-0b27-49c6-88d8-438df63952b5
08:05:42.345 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/1/c'
08:05:42.354 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='51d3021bb03c37afe39a5ad0633d698422e5fab7' to.sha1='<NoInstanceOf_NormalFileDto>'): /1/c
08:05:42.366 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=3, fromRepositoryId="FileInProgressMarker", toRepositoryId="3", path="e0925e66-6eab-46ba-b524-516a286091e3"] on repo=ebeea178-0b27-49c6-88d8-438df63952b5
08:05:42.368 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/1/c'
08:05:42.368 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='51d3021bb03c37afe39a5ad0633d698422e5fab7' toSha1='null'
08:05:42.368 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/1/c'
08:05:42.368 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/1/c' offset=0
08:05:42.370 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/1/c' offset=0
08:05:42.376 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 156312 bytes in 8 ms. path='/1/c'
08:05:42.380 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_84a516841ba77a5b4648de2cd0dfcb30ea46dbb4_0 into destFile c
08:05:42.394 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=3, fromRepositoryId="FileInProgressMarker", toRepositoryId="3", path="e0925e66-6eab-46ba-b524-516a286091e3"] on repo=ebeea178-0b27-49c6-88d8-438df63952b5
08:05:42.396 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/2/1/a'
08:05:42.405 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='eb7708100e86733147d581eba25d7451a23bb372' to.sha1='<NoInstanceOf_NormalFileDto>'): /2/1/a
08:05:42.417 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=4, fromRepositoryId="FileInProgressMarker", toRepositoryId="4", path="e0925e66-6eab-46ba-b524-516a286091e3"] on repo=ebeea178-0b27-49c6-88d8-438df63952b5
08:05:42.419 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/2/1/a'
08:05:42.419 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='eb7708100e86733147d581eba25d7451a23bb372' toSha1='null'
08:05:42.419 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/2/1/a'
08:05:42.419 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/2/1/a' offset=0
08:05:42.425 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/2/1/a' offset=0
08:05:42.441 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 576650 bytes in 21 ms. path='/2/1/a'
08:05:42.446 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_86f7e437faa5a7fce15d1ddcb9eaeaea377667b8_0 into destFile a
08:05:42.476 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=4, fromRepositoryId="FileInProgressMarker", toRepositoryId="4", path="e0925e66-6eab-46ba-b524-516a286091e3"] on repo=ebeea178-0b27-49c6-88d8-438df63952b5
08:05:42.479 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/2/1/b'
08:05:42.488 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='e025448138095839ae4e8978c40579cac1d7b4c8' to.sha1='<NoInstanceOf_NormalFileDto>'): /2/1/b
08:05:42.501 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=5, fromRepositoryId="FileInProgressMarker", toRepositoryId="5", path="e0925e66-6eab-46ba-b524-516a286091e3"] on repo=ebeea178-0b27-49c6-88d8-438df63952b5
08:05:42.502 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/2/1/b'
08:05:42.503 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='e025448138095839ae4e8978c40579cac1d7b4c8' toSha1='null'
08:05:42.503 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/2/1/b'
08:05:42.503 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/2/1/b' offset=0
08:05:42.504 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/2/1/b' offset=0
08:05:42.511 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 150150 bytes in 8 ms. path='/2/1/b'
08:05:42.516 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_e9d71f5ee7c92d6dc9e92ffdad17b8bd49418f98_0 into destFile b
08:05:42.531 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=5, fromRepositoryId="FileInProgressMarker", toRepositoryId="5", path="e0925e66-6eab-46ba-b524-516a286091e3"] on repo=ebeea178-0b27-49c6-88d8-438df63952b5
08:05:42.534 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/2/a'
08:05:42.541 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='04b169c065251890aba3bef54b7fe8a1f595dbd2' to.sha1='<NoInstanceOf_NormalFileDto>'): /2/a
08:05:42.553 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=6, fromRepositoryId="FileInProgressMarker", toRepositoryId="6", path="e0925e66-6eab-46ba-b524-516a286091e3"] on repo=ebeea178-0b27-49c6-88d8-438df63952b5
08:05:42.554 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/2/a'
08:05:42.554 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='04b169c065251890aba3bef54b7fe8a1f595dbd2' toSha1='null'
08:05:42.554 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/2/a'
08:05:42.554 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/2/a' offset=0
08:05:42.555 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/2/a' offset=0
08:05:42.560 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 66528 bytes in 6 ms. path='/2/a'
08:05:42.564 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_86f7e437faa5a7fce15d1ddcb9eaeaea377667b8_0 into destFile a
08:05:42.575 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=6, fromRepositoryId="FileInProgressMarker", toRepositoryId="6", path="e0925e66-6eab-46ba-b524-516a286091e3"] on repo=ebeea178-0b27-49c6-88d8-438df63952b5
08:05:42.578 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/3/a'
08:05:42.586 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='06b28ec3be187e5c4a96511981300aadf22f7d3b' to.sha1='<NoInstanceOf_NormalFileDto>'): /3/a
08:05:42.598 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=7, fromRepositoryId="FileInProgressMarker", toRepositoryId="7", path="e0925e66-6eab-46ba-b524-516a286091e3"] on repo=ebeea178-0b27-49c6-88d8-438df63952b5
08:05:42.599 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/3/a'
08:05:42.599 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='06b28ec3be187e5c4a96511981300aadf22f7d3b' toSha1='null'
08:05:42.599 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/3/a'
08:05:42.599 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/3/a' offset=0
08:05:42.601 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/3/a' offset=0
08:05:42.607 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 105480 bytes in 8 ms. path='/3/a'
08:05:42.612 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_86f7e437faa5a7fce15d1ddcb9eaeaea377667b8_0 into destFile a
08:05:42.626 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=7, fromRepositoryId="FileInProgressMarker", toRepositoryId="7", path="e0925e66-6eab-46ba-b524-516a286091e3"] on repo=ebeea178-0b27-49c6-88d8-438df63952b5
08:05:42.629 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/3/b'
08:05:42.646 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='826844564ff47539ffee11a43d93bddda670c29a' to.sha1='<NoInstanceOf_NormalFileDto>'): /3/b
08:05:42.658 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=8, fromRepositoryId="FileInProgressMarker", toRepositoryId="8", path="e0925e66-6eab-46ba-b524-516a286091e3"] on repo=ebeea178-0b27-49c6-88d8-438df63952b5
08:05:42.659 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/3/b'
08:05:42.659 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='826844564ff47539ffee11a43d93bddda670c29a' toSha1='null'
08:05:42.659 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/3/b'
08:05:42.659 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/3/b' offset=0
08:05:42.664 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/3/b' offset=0
08:05:42.673 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 407245 bytes in 14 ms. path='/3/b'
08:05:42.678 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_e9d71f5ee7c92d6dc9e92ffdad17b8bd49418f98_0 into destFile b
08:05:42.702 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=8, fromRepositoryId="FileInProgressMarker", toRepositoryId="8", path="e0925e66-6eab-46ba-b524-516a286091e3"] on repo=ebeea178-0b27-49c6-88d8-438df63952b5
08:05:42.704 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/3/c'
08:05:42.713 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='ba769a5cf499ad822de01646351ece1269135bce' to.sha1='<NoInstanceOf_NormalFileDto>'): /3/c
08:05:42.726 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=9, fromRepositoryId="FileInProgressMarker", toRepositoryId="9", path="e0925e66-6eab-46ba-b524-516a286091e3"] on repo=ebeea178-0b27-49c6-88d8-438df63952b5
08:05:42.727 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/3/c'
08:05:42.728 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='ba769a5cf499ad822de01646351ece1269135bce' toSha1='null'
08:05:42.728 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/3/c'
08:05:42.728 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/3/c' offset=0
08:05:42.732 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/3/c' offset=0
08:05:42.745 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 141519 bytes in 17 ms. path='/3/c'
08:05:42.748 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_84a516841ba77a5b4648de2cd0dfcb30ea46dbb4_0 into destFile c
08:05:42.811 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=9, fromRepositoryId="FileInProgressMarker", toRepositoryId="9", path="e0925e66-6eab-46ba-b524-516a286091e3"] on repo=ebeea178-0b27-49c6-88d8-438df63952b5
08:05:42.824 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/3/d'
08:05:42.840 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='f320c8af66d66aa268f2070e3733e0d1752e190e' to.sha1='<NoInstanceOf_NormalFileDto>'): /3/d
08:05:42.865 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=10, fromRepositoryId="FileInProgressMarker", toRepositoryId="10", path="e0925e66-6eab-46ba-b524-516a286091e3"] on repo=ebeea178-0b27-49c6-88d8-438df63952b5
08:05:42.878 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/3/d'
08:05:42.878 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='f320c8af66d66aa268f2070e3733e0d1752e190e' toSha1='null'
08:05:42.878 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/3/d'
08:05:42.878 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/3/d' offset=0
08:05:42.889 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/3/d' offset=0
08:05:42.905 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 528008 bytes in 27 ms. path='/3/d'
08:05:42.909 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_3c363836cf4e16666669a25da280a1865c2d2874_0 into destFile d
08:05:42.939 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=10, fromRepositoryId="FileInProgressMarker", toRepositoryId="10", path="e0925e66-6eab-46ba-b524-516a286091e3"] on repo=ebeea178-0b27-49c6-88d8-438df63952b5
08:05:42.942 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 49.27%
08:05:42.963 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=e0925e66-6eab-46ba-b524-516a286091e3 remoteRepositoryId=ebeea178-0b27-49c6-88d8-438df63952b5 localRepositoryRevisionSynced=2
08:05:42.970 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=ebeea178-0b27-49c6-88d8-438df63952b5 remoteRepositoryId=e0925e66-6eab-46ba-b524-516a286091e3 transaction.localRevision=57 remoteFromLocalRevision=2
08:05:42.972 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncUp: fromID=ebeea178-0b27-49c6-88d8-438df63952b5 from='target/repo/jz6kdnm9-yn8dq-local' toID=e0925e66-6eab-46ba-b524-516a286091e3 to='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/jz6kdnm9-h29tn-remote'
08:05:42.991 [main] INFO c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=ebeea178-0b27-49c6-88d8-438df63952b5 remoteRepositoryId=e0925e66-6eab-46ba-b524-516a286091e3 localRepositoryRevisionSynced=-1 localRepositoryRevisionInProgress=57
08:05:43.001 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 63.43%
08:05:43.002 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 74.63%
08:05:43.012 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=ebeea178-0b27-49c6-88d8-438df63952b5 remoteRepositoryId=e0925e66-6eab-46ba-b524-516a286091e3 localRepositoryRevisionSynced=57
08:05:43.019 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=e0925e66-6eab-46ba-b524-516a286091e3 remoteRepositoryId=ebeea178-0b27-49c6-88d8-438df63952b5 transaction.localRevision=5 remoteFromLocalRevision=57
08:05:43.021 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDown: fromID=e0925e66-6eab-46ba-b524-516a286091e3 from='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/jz6kdnm9-h29tn-remote' toID=ebeea178-0b27-49c6-88d8-438df63952b5 to='target/repo/jz6kdnm9-yn8dq-local', fromRepoLocalSync=false
08:05:43.030 [main] INFO c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=e0925e66-6eab-46ba-b524-516a286091e3 remoteRepositoryId=ebeea178-0b27-49c6-88d8-438df63952b5 localRepositoryRevisionSynced=2 localRepositoryRevisionInProgress=5
08:05:43.033 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 88.31%
08:05:43.033 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 99.50%
08:05:43.036 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=e0925e66-6eab-46ba-b524-516a286091e3 remoteRepositoryId=ebeea178-0b27-49c6-88d8-438df63952b5 localRepositoryRevisionSynced=5
08:05:43.039 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=ebeea178-0b27-49c6-88d8-438df63952b5 remoteRepositoryId=e0925e66-6eab-46ba-b524-516a286091e3 transaction.localRevision=60 remoteFromLocalRevision=5
08:05:43.040 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 100.00%
08:05:43.053 [main] INFO c.c.c.l.LocalRepoManagerImpl - [5f70a5de]getCloseDeferredMillis: closeDeferredMillis=20000
08:05:43.053 [main] INFO c.c.c.l.LocalRepoManagerImpl - [5f70a5de]close: Deferring shut down of real LocalRepoManager 20000 ms.
08:05:43.054 [main] INFO c.c.c.l.LocalRepoManagerImpl - [5edf4926]getCloseDeferredMillis: closeDeferredMillis=20000
08:05:43.054 [main] INFO c.c.c.l.LocalRepoManagerImpl - [5edf4926]close: Deferring shut down of real LocalRepoManager 20000 ms.
08:05:43.111 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 1.00%
08:05:43.112 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 50.00%
08:05:43.113 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 58.17%
08:05:43.116 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 63.61%
08:05:43.120 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 74.50%
08:05:43.158 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 80.62%
08:05:43.165 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 90.83%
08:05:43.169 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 95.94%
08:05:43.179 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 100.00%
08:05:43.203 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 0.50%
08:05:43.203 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDown: fromID=e0925e66-6eab-46ba-b524-516a286091e3 from='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/jz6kdnm9-h29tn-remote' toID=ebeea178-0b27-49c6-88d8-438df63952b5 to='target/repo/jz6kdnm9-yn8dq-local', fromRepoLocalSync=true
08:05:43.203 [pool-36-thread-1] INFO c.c.c.c.r.s.RepoToRepoSync - sync: locally syncing ebeea178-0b27-49c6-88d8-438df63952b5 ('target/repo/jz6kdnm9-yn8dq-local')
08:05:43.208 [pool-36-thread-1] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 12.94%
08:05:43.225 [pool-36-thread-1] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 19.03%
08:05:43.243 [pool-36-thread-1] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 24.11%
08:05:43.259 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 1.00%
08:05:43.259 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 50.00%
08:05:43.260 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 58.17%
08:05:43.264 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 63.61%
08:05:43.267 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 74.50%
08:05:43.272 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 80.62%
08:05:43.275 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 90.83%
08:05:43.281 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 95.94%
08:05:43.287 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 100.00%
08:05:43.293 [main] INFO c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=e0925e66-6eab-46ba-b524-516a286091e3 remoteRepositoryId=ebeea178-0b27-49c6-88d8-438df63952b5 localRepositoryRevisionSynced=5 localRepositoryRevisionInProgress=8
08:05:43.308 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 29.23%
08:05:43.308 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path='/2/1'
08:05:43.322 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 34.83%
08:05:43.322 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncModifications: Deleting '/2/1/a'
08:05:43.354 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 40.42%
08:05:43.355 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 46.02%
08:05:43.372 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=e0925e66-6eab-46ba-b524-516a286091e3 remoteRepositoryId=ebeea178-0b27-49c6-88d8-438df63952b5 localRepositoryRevisionSynced=8
08:05:43.376 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=ebeea178-0b27-49c6-88d8-438df63952b5 remoteRepositoryId=e0925e66-6eab-46ba-b524-516a286091e3 transaction.localRevision=63 remoteFromLocalRevision=8
08:05:43.377 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncUp: fromID=ebeea178-0b27-49c6-88d8-438df63952b5 from='target/repo/jz6kdnm9-yn8dq-local' toID=e0925e66-6eab-46ba-b524-516a286091e3 to='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/jz6kdnm9-h29tn-remote'
08:05:43.389 [main] INFO c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=ebeea178-0b27-49c6-88d8-438df63952b5 remoteRepositoryId=e0925e66-6eab-46ba-b524-516a286091e3 localRepositoryRevisionSynced=57 localRepositoryRevisionInProgress=63
08:05:43.404 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 52.24%
08:05:43.405 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 59.70%
08:05:43.405 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncModifications: Deleting '/2/1/a'
08:05:43.412 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 67.16%
08:05:43.413 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 74.63%
08:05:43.429 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=ebeea178-0b27-49c6-88d8-438df63952b5 remoteRepositoryId=e0925e66-6eab-46ba-b524-516a286091e3 localRepositoryRevisionSynced=63
08:05:43.433 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=e0925e66-6eab-46ba-b524-516a286091e3 remoteRepositoryId=ebeea178-0b27-49c6-88d8-438df63952b5 transaction.localRevision=11 remoteFromLocalRevision=63
08:05:43.435 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDown: fromID=e0925e66-6eab-46ba-b524-516a286091e3 from='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/jz6kdnm9-h29tn-remote' toID=ebeea178-0b27-49c6-88d8-438df63952b5 to='target/repo/jz6kdnm9-yn8dq-local', fromRepoLocalSync=false
08:05:43.440 [main] INFO c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=e0925e66-6eab-46ba-b524-516a286091e3 remoteRepositoryId=ebeea178-0b27-49c6-88d8-438df63952b5 localRepositoryRevisionSynced=8 localRepositoryRevisionInProgress=11
08:05:43.442 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 88.31%
08:05:43.443 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 99.50%
08:05:43.446 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=e0925e66-6eab-46ba-b524-516a286091e3 remoteRepositoryId=ebeea178-0b27-49c6-88d8-438df63952b5 localRepositoryRevisionSynced=11
08:05:43.449 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=ebeea178-0b27-49c6-88d8-438df63952b5 remoteRepositoryId=e0925e66-6eab-46ba-b524-516a286091e3 transaction.localRevision=66 remoteFromLocalRevision=11
08:05:43.451 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 100.00%
08:05:43.451 [main] INFO c.c.c.l.LocalRepoManagerImpl - [5f70a5de]close: Deferring shut down of real LocalRepoManager 20000 ms.
08:05:43.462 [main] INFO c.c.c.l.LocalRepoManagerImpl - [5edf4926]close: Deferring shut down of real LocalRepoManager 20000 ms.
08:05:43.514 [main] INFO c.c.c.l.LocalRepoManagerImpl - [42c7243f]<init>: localRoot='/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/jz6ke120-u7qmb-local'
08:05:43.519 [main] INFO c.c.c.l.LocalRepoManagerImpl - [42c7243f]initLockFile: Repository '/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/jz6ke120-u7qmb-local' locked successfully.
08:05:45.984 [closeDeferredTimer-6a95894f-2] INFO c.c.c.l.LocalRepoManagerImpl - [6a95894f]_close: Shutting down real LocalRepoManager.
08:05:46.259 [closeDeferredTimer-544d0c82-2] INFO c.c.c.l.LocalRepoManagerImpl - [544d0c82]_close: Shutting down real LocalRepoManager.
08:05:51.271 [main] INFO c.c.c.l.LocalRepoManagerImpl - [42c7243f]initPersistenceManagerFactory: repositoryId=acb7a131-4b05-4d19-983c-8f794a845ffa
08:05:51.275 [main] INFO c.c.c.l.LocalRepoManagerImpl - [42c7243f]initPersistenceManagerFactory: Started up PersistenceManagerFactory successfully in 1899 ms.
08:05:51.295 [main] INFO c.c.c.l.LocalRepoManagerImpl - [6203668a]<init>: localRoot='/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/jz6ke121-deuvk-remote'
08:05:51.301 [main] INFO c.c.c.l.LocalRepoManagerImpl - [6203668a]initLockFile: Repository '/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/jz6ke121-deuvk-remote' locked successfully.
08:05:54.633 [main] INFO c.c.c.l.LocalRepoManagerImpl - [6203668a]initPersistenceManagerFactory: repositoryId=79886ad6-8203-488e-8c87-62aa307b3210
08:05:54.639 [main] INFO c.c.c.l.LocalRepoManagerImpl - [6203668a]initPersistenceManagerFactory: Started up PersistenceManagerFactory successfully in 1974 ms.
08:05:54.756 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 1.00%
08:05:54.765 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 50.00%
08:05:54.779 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 58.00%
08:05:54.832 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 63.61%
08:05:54.850 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 74.34%
08:05:54.891 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 80.56%
08:05:54.903 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 90.67%
08:05:54.944 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 95.71%
08:05:54.987 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 100.00%
08:05:55.015 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - local repo: acb7a131-4b05-4d19-983c-8f794a845ffa
08:05:55.015 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - remote repo: 79886ad6-8203-488e-8c87-62aa307b3210
08:05:55.019 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 0.50%
08:05:55.019 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDown: fromID=79886ad6-8203-488e-8c87-62aa307b3210 from='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/jz6ke121-deuvk-remote/2' toID=acb7a131-4b05-4d19-983c-8f794a845ffa to='target/repo/jz6ke120-u7qmb-local', fromRepoLocalSync=true
08:05:55.020 [pool-37-thread-1] INFO c.c.c.c.r.s.RepoToRepoSync - sync: locally syncing acb7a131-4b05-4d19-983c-8f794a845ffa ('target/repo/jz6ke120-u7qmb-local')
08:05:55.047 [pool-37-thread-1] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 12.94%
08:05:55.055 [pool-37-thread-1] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 25.12%
08:05:55.058 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 1.00%
08:05:55.059 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 50.00%
08:05:55.061 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 58.17%
08:05:55.067 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 63.61%
08:05:55.071 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 74.50%
08:05:55.080 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 80.62%
08:05:55.084 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 90.83%
08:05:55.090 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 95.94%
08:05:55.098 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 100.00%
08:05:55.125 [main] INFO c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=79886ad6-8203-488e-8c87-62aa307b3210 remoteRepositoryId=acb7a131-4b05-4d19-983c-8f794a845ffa localRepositoryRevisionSynced=-1 localRepositoryRevisionInProgress=2
08:05:55.184 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path=''
08:05:55.212 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path='/1'
08:05:55.232 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 31.31%
08:05:55.241 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 36.58%
08:05:55.241 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 41.85%
08:05:55.242 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/1/a'
08:05:55.253 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='efb5843817f89c555ebe07bb10e05013a49bba94' to.sha1='<NoInstanceOf_NormalFileDto>'): /1/a
08:05:55.304 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=1, fromRepositoryId="FileInProgressMarker", toRepositoryId="1", path="79886ad6-8203-488e-8c87-62aa307b3210"] on repo=acb7a131-4b05-4d19-983c-8f794a845ffa
08:05:55.308 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/1/a'
08:05:55.308 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='efb5843817f89c555ebe07bb10e05013a49bba94' toSha1='null'
08:05:55.308 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/1/a'
08:05:55.308 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/1/a' offset=0
08:05:55.317 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/1/a' offset=0
08:05:55.333 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 540000 bytes in 25 ms. path='/1/a'
08:05:55.338 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_86f7e437faa5a7fce15d1ddcb9eaeaea377667b8_0 into destFile a
08:05:55.395 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=1, fromRepositoryId="FileInProgressMarker", toRepositoryId="1", path="79886ad6-8203-488e-8c87-62aa307b3210"] on repo=acb7a131-4b05-4d19-983c-8f794a845ffa
08:05:55.405 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/1/b'
08:05:55.416 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='78c61d33701fc2125c7cd221fd5226993e3e3908' to.sha1='<NoInstanceOf_NormalFileDto>'): /1/b
08:05:55.431 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=2, fromRepositoryId="FileInProgressMarker", toRepositoryId="2", path="79886ad6-8203-488e-8c87-62aa307b3210"] on repo=acb7a131-4b05-4d19-983c-8f794a845ffa
08:05:55.435 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/1/b'
08:05:55.435 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='78c61d33701fc2125c7cd221fd5226993e3e3908' toSha1='null'
08:05:55.435 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/1/b'
08:05:55.435 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/1/b' offset=0
08:05:55.437 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/1/b' offset=0
08:05:55.451 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 172588 bytes in 16 ms. path='/1/b'
08:05:55.455 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_e9d71f5ee7c92d6dc9e92ffdad17b8bd49418f98_0 into destFile b
08:05:55.472 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=2, fromRepositoryId="FileInProgressMarker", toRepositoryId="2", path="79886ad6-8203-488e-8c87-62aa307b3210"] on repo=acb7a131-4b05-4d19-983c-8f794a845ffa
08:05:55.479 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/a'
08:05:55.490 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='cc4cf0499ef5722d3ae8c5859a42f8cc49e3383d' to.sha1='<NoInstanceOf_NormalFileDto>'): /a
08:05:55.506 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=3, fromRepositoryId="FileInProgressMarker", toRepositoryId="3", path="79886ad6-8203-488e-8c87-62aa307b3210"] on repo=acb7a131-4b05-4d19-983c-8f794a845ffa
08:05:55.510 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/a'
08:05:55.510 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='cc4cf0499ef5722d3ae8c5859a42f8cc49e3383d' toSha1='null'
08:05:55.511 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/a'
08:05:55.511 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/a' offset=0
08:05:55.511 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/a' offset=0
08:05:55.515 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 11360 bytes in 4 ms. path='/a'
08:05:55.519 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_86f7e437faa5a7fce15d1ddcb9eaeaea377667b8_0 into destFile a
08:05:55.535 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=3, fromRepositoryId="FileInProgressMarker", toRepositoryId="3", path="79886ad6-8203-488e-8c87-62aa307b3210"] on repo=acb7a131-4b05-4d19-983c-8f794a845ffa
08:05:55.543 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 48.43%
08:05:55.563 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=79886ad6-8203-488e-8c87-62aa307b3210 remoteRepositoryId=acb7a131-4b05-4d19-983c-8f794a845ffa localRepositoryRevisionSynced=2
08:05:55.579 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=acb7a131-4b05-4d19-983c-8f794a845ffa remoteRepositoryId=79886ad6-8203-488e-8c87-62aa307b3210 transaction.localRevision=19 remoteFromLocalRevision=2
08:05:55.644 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncUp: fromID=acb7a131-4b05-4d19-983c-8f794a845ffa from='target/repo/jz6ke120-u7qmb-local' toID=79886ad6-8203-488e-8c87-62aa307b3210 to='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/jz6ke121-deuvk-remote/2'
08:05:55.674 [main] INFO c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=acb7a131-4b05-4d19-983c-8f794a845ffa remoteRepositoryId=79886ad6-8203-488e-8c87-62aa307b3210 localRepositoryRevisionSynced=-1 localRepositoryRevisionInProgress=19
08:05:55.684 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 63.43%
08:05:55.684 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 74.63%
08:05:55.695 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=acb7a131-4b05-4d19-983c-8f794a845ffa remoteRepositoryId=79886ad6-8203-488e-8c87-62aa307b3210 localRepositoryRevisionSynced=19
08:05:55.706 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=79886ad6-8203-488e-8c87-62aa307b3210 remoteRepositoryId=acb7a131-4b05-4d19-983c-8f794a845ffa transaction.localRevision=5 remoteFromLocalRevision=19
08:05:55.712 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDown: fromID=79886ad6-8203-488e-8c87-62aa307b3210 from='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/jz6ke121-deuvk-remote/2' toID=acb7a131-4b05-4d19-983c-8f794a845ffa to='target/repo/jz6ke120-u7qmb-local', fromRepoLocalSync=false
08:05:55.731 [main] INFO c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=79886ad6-8203-488e-8c87-62aa307b3210 remoteRepositoryId=acb7a131-4b05-4d19-983c-8f794a845ffa localRepositoryRevisionSynced=2 localRepositoryRevisionInProgress=5
08:05:55.736 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 88.31%
08:05:55.736 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 99.50%
08:05:55.739 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=79886ad6-8203-488e-8c87-62aa307b3210 remoteRepositoryId=acb7a131-4b05-4d19-983c-8f794a845ffa localRepositoryRevisionSynced=5
08:05:55.747 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=acb7a131-4b05-4d19-983c-8f794a845ffa remoteRepositoryId=79886ad6-8203-488e-8c87-62aa307b3210 transaction.localRevision=22 remoteFromLocalRevision=5
08:05:55.751 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 100.00%
08:05:55.766 [main] INFO c.c.c.l.LocalRepoManagerImpl - [42c7243f]getCloseDeferredMillis: closeDeferredMillis=20000
08:05:55.766 [main] INFO c.c.c.l.LocalRepoManagerImpl - [42c7243f]close: Deferring shut down of real LocalRepoManager 20000 ms.
08:05:55.777 [main] INFO c.c.c.l.LocalRepoManagerImpl - [6203668a]getCloseDeferredMillis: closeDeferredMillis=20000
08:05:55.778 [main] INFO c.c.c.l.LocalRepoManagerImpl - [6203668a]close: Deferring shut down of real LocalRepoManager 20000 ms.
08:05:55.795 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 1.00%
08:05:55.795 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 50.00%
08:05:55.796 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 58.17%
08:05:55.799 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 63.61%
08:05:55.802 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 74.50%
08:05:55.849 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 80.62%
08:05:55.851 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 90.83%
08:05:55.855 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 95.94%
08:05:55.866 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 100.00%
08:05:55.878 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 0.50%
08:05:55.879 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDown: fromID=79886ad6-8203-488e-8c87-62aa307b3210 from='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/jz6ke121-deuvk-remote/2' toID=acb7a131-4b05-4d19-983c-8f794a845ffa to='target/repo/jz6ke120-u7qmb-local', fromRepoLocalSync=true
08:05:55.879 [pool-38-thread-1] INFO c.c.c.c.r.s.RepoToRepoSync - sync: locally syncing acb7a131-4b05-4d19-983c-8f794a845ffa ('target/repo/jz6ke120-u7qmb-local')
08:05:55.883 [pool-38-thread-1] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 12.94%
08:05:55.894 [pool-38-thread-1] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 18.27%
08:05:55.904 [pool-38-thread-1] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 25.12%
08:05:55.916 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 1.00%
08:05:55.917 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 50.00%
08:05:55.918 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 58.17%
08:05:55.922 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 63.61%
08:05:55.931 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 74.50%
08:05:55.935 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 80.62%
08:05:55.938 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 90.83%
08:05:55.941 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 95.94%
08:05:55.948 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 100.00%
08:05:55.958 [main] INFO c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=79886ad6-8203-488e-8c87-62aa307b3210 remoteRepositoryId=acb7a131-4b05-4d19-983c-8f794a845ffa localRepositoryRevisionSynced=5 localRepositoryRevisionInProgress=8
08:05:55.978 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 32.34%
08:05:55.978 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path='/1'
08:05:55.993 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncModifications: Deleting '/1/a'
08:05:56.035 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 39.80%
08:05:56.035 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 47.26%
08:05:56.061 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=79886ad6-8203-488e-8c87-62aa307b3210 remoteRepositoryId=acb7a131-4b05-4d19-983c-8f794a845ffa localRepositoryRevisionSynced=8
08:05:56.083 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=acb7a131-4b05-4d19-983c-8f794a845ffa remoteRepositoryId=79886ad6-8203-488e-8c87-62aa307b3210 transaction.localRevision=25 remoteFromLocalRevision=8
08:05:56.095 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncUp: fromID=acb7a131-4b05-4d19-983c-8f794a845ffa from='target/repo/jz6ke120-u7qmb-local' toID=79886ad6-8203-488e-8c87-62aa307b3210 to='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/jz6ke121-deuvk-remote/2'
08:05:56.152 [main] INFO c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=acb7a131-4b05-4d19-983c-8f794a845ffa remoteRepositoryId=79886ad6-8203-488e-8c87-62aa307b3210 localRepositoryRevisionSynced=19 localRepositoryRevisionInProgress=25
08:05:56.166 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 59.70%
08:05:56.167 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncModifications: Deleting '/1/a'
08:05:56.176 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 67.16%
08:05:56.180 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 74.63%
08:05:56.207 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=acb7a131-4b05-4d19-983c-8f794a845ffa remoteRepositoryId=79886ad6-8203-488e-8c87-62aa307b3210 localRepositoryRevisionSynced=25
08:05:56.576 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=79886ad6-8203-488e-8c87-62aa307b3210 remoteRepositoryId=acb7a131-4b05-4d19-983c-8f794a845ffa transaction.localRevision=11 remoteFromLocalRevision=25
08:05:56.579 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDown: fromID=79886ad6-8203-488e-8c87-62aa307b3210 from='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/jz6ke121-deuvk-remote/2' toID=acb7a131-4b05-4d19-983c-8f794a845ffa to='target/repo/jz6ke120-u7qmb-local', fromRepoLocalSync=false
08:05:56.585 [main] INFO c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=79886ad6-8203-488e-8c87-62aa307b3210 remoteRepositoryId=acb7a131-4b05-4d19-983c-8f794a845ffa localRepositoryRevisionSynced=8 localRepositoryRevisionInProgress=11
08:05:56.590 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 88.31%
08:05:56.590 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 99.50%
08:05:56.594 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=79886ad6-8203-488e-8c87-62aa307b3210 remoteRepositoryId=acb7a131-4b05-4d19-983c-8f794a845ffa localRepositoryRevisionSynced=11
08:05:56.598 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=acb7a131-4b05-4d19-983c-8f794a845ffa remoteRepositoryId=79886ad6-8203-488e-8c87-62aa307b3210 transaction.localRevision=28 remoteFromLocalRevision=11
08:05:56.600 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 100.00%
08:05:56.600 [main] INFO c.c.c.l.LocalRepoManagerImpl - [42c7243f]close: Deferring shut down of real LocalRepoManager 20000 ms.
08:05:56.613 [main] INFO c.c.c.l.LocalRepoManagerImpl - [6203668a]close: Deferring shut down of real LocalRepoManager 20000 ms.
08:05:56.627 [main] INFO c.c.c.l.LocalRepoManagerImpl - [65d5f41e]<init>: localRoot='/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/jz6keb6a-kf21d-local'
08:05:56.639 [main] INFO c.c.c.l.LocalRepoManagerImpl - [65d5f41e]initLockFile: Repository '/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/jz6keb6a-kf21d-local' locked successfully.
08:05:59.884 [main] INFO c.c.c.l.LocalRepoManagerImpl - [65d5f41e]initPersistenceManagerFactory: repositoryId=3e7d8edd-e6b7-40e2-93ef-7855059eb1b4
08:05:59.891 [main] INFO c.c.c.l.LocalRepoManagerImpl - [65d5f41e]initPersistenceManagerFactory: Started up PersistenceManagerFactory successfully in 1991 ms.
08:05:59.914 [main] INFO c.c.c.l.LocalRepoManagerImpl - [d0ee353]<init>: localRoot='/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/jz6keb6a-474zd-remote'
08:05:59.920 [main] INFO c.c.c.l.LocalRepoManagerImpl - [d0ee353]initLockFile: Repository '/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/jz6keb6a-474zd-remote' locked successfully.
08:06:02.852 [main] INFO c.c.c.l.LocalRepoManagerImpl - [d0ee353]initPersistenceManagerFactory: repositoryId=54602c58-cdbc-4f51-86fb-14c197121cee
08:06:02.854 [main] INFO c.c.c.l.LocalRepoManagerImpl - [d0ee353]initPersistenceManagerFactory: Started up PersistenceManagerFactory successfully in 1391 ms.
08:06:02.950 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 1.00%
08:06:02.956 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 50.00%
08:06:02.966 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 58.00%
08:06:03.016 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 63.61%
08:06:03.031 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 74.34%
08:06:03.060 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 79.52%
08:06:03.073 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 90.67%
08:06:03.108 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 95.81%
08:06:03.144 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 100.00%
08:06:03.165 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - local repo: 3e7d8edd-e6b7-40e2-93ef-7855059eb1b4
08:06:03.165 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - remote repo: 54602c58-cdbc-4f51-86fb-14c197121cee
08:06:03.169 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 0.50%
08:06:03.170 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDown: fromID=54602c58-cdbc-4f51-86fb-14c197121cee from='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/jz6keb6a-474zd-remote' toID=3e7d8edd-e6b7-40e2-93ef-7855059eb1b4 to='target/repo/jz6keb6a-kf21d-local', fromRepoLocalSync=true
08:06:03.170 [pool-39-thread-1] INFO c.c.c.c.r.s.RepoToRepoSync - sync: locally syncing 3e7d8edd-e6b7-40e2-93ef-7855059eb1b4 ('target/repo/jz6keb6a-kf21d-local')
08:06:03.192 [pool-39-thread-1] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 12.94%
08:06:03.204 [pool-39-thread-1] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 25.12%
08:06:03.211 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 1.00%
08:06:03.214 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 50.00%
08:06:03.216 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 58.17%
08:06:03.224 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 63.61%
08:06:03.230 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 74.50%
08:06:03.238 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 80.62%
08:06:03.242 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 90.83%
08:06:03.247 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 95.94%
08:06:03.256 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 100.00%
08:06:03.278 [main] INFO c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=54602c58-cdbc-4f51-86fb-14c197121cee remoteRepositoryId=3e7d8edd-e6b7-40e2-93ef-7855059eb1b4 localRepositoryRevisionSynced=-1 localRepositoryRevisionInProgress=2
08:06:03.316 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path=''
08:06:03.350 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path='/1'
08:06:03.374 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 30.22%
08:06:03.374 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path='/2'
08:06:03.391 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path='/2/1'
08:06:03.407 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path='/3'
08:06:03.423 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 35.46%
08:06:03.424 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 40.70%
08:06:03.424 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/1/a'
08:06:03.433 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='422d289f817ee680e27123220a5dbf2bb253f6f3' to.sha1='<NoInstanceOf_NormalFileDto>'): /1/a
08:06:03.452 [closeDeferredTimer-5f70a5de-2] INFO c.c.c.l.LocalRepoManagerImpl - [5f70a5de]_close: Shutting down real LocalRepoManager.
08:06:03.830 [closeDeferredTimer-5edf4926-2] INFO c.c.c.l.LocalRepoManagerImpl - [5edf4926]_close: Shutting down real LocalRepoManager.
08:06:04.319 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=1, fromRepositoryId="FileInProgressMarker", toRepositoryId="1", path="54602c58-cdbc-4f51-86fb-14c197121cee"] on repo=3e7d8edd-e6b7-40e2-93ef-7855059eb1b4
08:06:04.321 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/1/a'
08:06:04.321 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='422d289f817ee680e27123220a5dbf2bb253f6f3' toSha1='null'
08:06:04.321 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/1/a'
08:06:04.321 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/1/a' offset=0
08:06:04.325 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/1/a' offset=0
08:06:04.337 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 194400 bytes in 16 ms. path='/1/a'
08:06:04.346 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_86f7e437faa5a7fce15d1ddcb9eaeaea377667b8_0 into destFile a
08:06:04.421 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=1, fromRepositoryId="FileInProgressMarker", toRepositoryId="1", path="54602c58-cdbc-4f51-86fb-14c197121cee"] on repo=3e7d8edd-e6b7-40e2-93ef-7855059eb1b4
08:06:04.435 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/1/b'
08:06:04.445 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='f64dd0349e6b902b687bb984b9cabad523f9408a' to.sha1='<NoInstanceOf_NormalFileDto>'): /1/b
08:06:04.466 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=2, fromRepositoryId="FileInProgressMarker", toRepositoryId="2", path="54602c58-cdbc-4f51-86fb-14c197121cee"] on repo=3e7d8edd-e6b7-40e2-93ef-7855059eb1b4
08:06:04.490 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/1/b'
08:06:04.490 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='f64dd0349e6b902b687bb984b9cabad523f9408a' toSha1='null'
08:06:04.490 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/1/b'
08:06:04.490 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/1/b' offset=0
08:06:04.491 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/1/b' offset=0
08:06:04.500 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 17208 bytes in 10 ms. path='/1/b'
08:06:04.503 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_e9d71f5ee7c92d6dc9e92ffdad17b8bd49418f98_0 into destFile b
08:06:04.583 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=2, fromRepositoryId="FileInProgressMarker", toRepositoryId="2", path="54602c58-cdbc-4f51-86fb-14c197121cee"] on repo=3e7d8edd-e6b7-40e2-93ef-7855059eb1b4
08:06:04.658 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/1/c'
08:06:04.670 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='39aa9ba022bfa96fbdba05ebc4ea1698185004d6' to.sha1='<NoInstanceOf_NormalFileDto>'): /1/c
08:06:04.701 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=3, fromRepositoryId="FileInProgressMarker", toRepositoryId="3", path="54602c58-cdbc-4f51-86fb-14c197121cee"] on repo=3e7d8edd-e6b7-40e2-93ef-7855059eb1b4
08:06:04.704 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/1/c'
08:06:04.704 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='39aa9ba022bfa96fbdba05ebc4ea1698185004d6' toSha1='null'
08:06:04.704 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/1/c'
08:06:04.704 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/1/c' offset=0
08:06:04.715 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/1/c' offset=0
08:06:04.734 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 748352 bytes in 30 ms. path='/1/c'
08:06:04.743 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_84a516841ba77a5b4648de2cd0dfcb30ea46dbb4_0 into destFile c
08:06:04.830 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=3, fromRepositoryId="FileInProgressMarker", toRepositoryId="3", path="54602c58-cdbc-4f51-86fb-14c197121cee"] on repo=3e7d8edd-e6b7-40e2-93ef-7855059eb1b4
08:06:04.837 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/2/1/a'
08:06:04.849 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='beb2268e4dfd6e834e30812d9063b607dbd2e0ca' to.sha1='<NoInstanceOf_NormalFileDto>'): /2/1/a
08:06:04.868 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=4, fromRepositoryId="FileInProgressMarker", toRepositoryId="4", path="54602c58-cdbc-4f51-86fb-14c197121cee"] on repo=3e7d8edd-e6b7-40e2-93ef-7855059eb1b4
08:06:04.871 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/2/1/a'
08:06:04.871 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='beb2268e4dfd6e834e30812d9063b607dbd2e0ca' toSha1='null'
08:06:04.872 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/2/1/a'
08:06:04.872 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/2/1/a' offset=0
08:06:04.881 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/2/1/a' offset=0
08:06:04.894 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 376656 bytes in 22 ms. path='/2/1/a'
08:06:04.898 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_86f7e437faa5a7fce15d1ddcb9eaeaea377667b8_0 into destFile a
08:06:04.962 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=4, fromRepositoryId="FileInProgressMarker", toRepositoryId="4", path="54602c58-cdbc-4f51-86fb-14c197121cee"] on repo=3e7d8edd-e6b7-40e2-93ef-7855059eb1b4
08:06:04.969 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/2/1/b'
08:06:04.978 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='0ddcb2c33bbb3575e067c140c75b7d8994167aae' to.sha1='<NoInstanceOf_NormalFileDto>'): /2/1/b
08:06:05.002 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=5, fromRepositoryId="FileInProgressMarker", toRepositoryId="5", path="54602c58-cdbc-4f51-86fb-14c197121cee"] on repo=3e7d8edd-e6b7-40e2-93ef-7855059eb1b4
08:06:05.006 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/2/1/b'
08:06:05.006 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='0ddcb2c33bbb3575e067c140c75b7d8994167aae' toSha1='null'
08:06:05.006 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/2/1/b'
08:06:05.006 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/2/1/b' offset=0
08:06:05.007 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/2/1/b' offset=0
08:06:05.015 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 150280 bytes in 9 ms. path='/2/1/b'
08:06:05.023 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_e9d71f5ee7c92d6dc9e92ffdad17b8bd49418f98_0 into destFile b
08:06:05.048 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=5, fromRepositoryId="FileInProgressMarker", toRepositoryId="5", path="54602c58-cdbc-4f51-86fb-14c197121cee"] on repo=3e7d8edd-e6b7-40e2-93ef-7855059eb1b4
08:06:05.063 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/2/a'
08:06:05.076 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='b1fdd6e3dfb1e6bf9b593737090c78e5b96978b6' to.sha1='<NoInstanceOf_NormalFileDto>'): /2/a
08:06:05.097 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=6, fromRepositoryId="FileInProgressMarker", toRepositoryId="6", path="54602c58-cdbc-4f51-86fb-14c197121cee"] on repo=3e7d8edd-e6b7-40e2-93ef-7855059eb1b4
08:06:05.100 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/2/a'
08:06:05.100 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='b1fdd6e3dfb1e6bf9b593737090c78e5b96978b6' toSha1='null'
08:06:05.100 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/2/a'
08:06:05.100 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/2/a' offset=0
08:06:05.104 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/2/a' offset=0
08:06:05.114 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 277970 bytes in 14 ms. path='/2/a'
08:06:05.118 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_86f7e437faa5a7fce15d1ddcb9eaeaea377667b8_0 into destFile a
08:06:05.148 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=6, fromRepositoryId="FileInProgressMarker", toRepositoryId="6", path="54602c58-cdbc-4f51-86fb-14c197121cee"] on repo=3e7d8edd-e6b7-40e2-93ef-7855059eb1b4
08:06:05.157 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/3/a'
08:06:05.173 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='1abfcb33d1416e42d709439378dba77edb10e0d2' to.sha1='<NoInstanceOf_NormalFileDto>'): /3/a
08:06:05.193 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=7, fromRepositoryId="FileInProgressMarker", toRepositoryId="7", path="54602c58-cdbc-4f51-86fb-14c197121cee"] on repo=3e7d8edd-e6b7-40e2-93ef-7855059eb1b4
08:06:05.209 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/3/a'
08:06:05.209 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='1abfcb33d1416e42d709439378dba77edb10e0d2' toSha1='null'
08:06:05.209 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/3/a'
08:06:05.209 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/3/a' offset=0
08:06:05.225 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/3/a' offset=0
08:06:05.241 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 867900 bytes in 32 ms. path='/3/a'
08:06:05.245 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_86f7e437faa5a7fce15d1ddcb9eaeaea377667b8_0 into destFile a
08:06:05.307 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=7, fromRepositoryId="FileInProgressMarker", toRepositoryId="7", path="54602c58-cdbc-4f51-86fb-14c197121cee"] on repo=3e7d8edd-e6b7-40e2-93ef-7855059eb1b4
08:06:05.315 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/3/b'
08:06:05.330 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='901df3a6590f11e3f97c09ce5cc1b43457589b03' to.sha1='<NoInstanceOf_NormalFileDto>'): /3/b
08:06:05.341 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=8, fromRepositoryId="FileInProgressMarker", toRepositoryId="8", path="54602c58-cdbc-4f51-86fb-14c197121cee"] on repo=3e7d8edd-e6b7-40e2-93ef-7855059eb1b4
08:06:05.342 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/3/b'
08:06:05.342 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='901df3a6590f11e3f97c09ce5cc1b43457589b03' toSha1='null'
08:06:05.342 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/3/b'
08:06:05.342 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/3/b' offset=0
08:06:05.347 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/3/b' offset=0
08:06:05.357 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 394429 bytes in 15 ms. path='/3/b'
08:06:05.360 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_e9d71f5ee7c92d6dc9e92ffdad17b8bd49418f98_0 into destFile b
08:06:05.387 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=8, fromRepositoryId="FileInProgressMarker", toRepositoryId="8", path="54602c58-cdbc-4f51-86fb-14c197121cee"] on repo=3e7d8edd-e6b7-40e2-93ef-7855059eb1b4
08:06:05.389 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/3/c'
08:06:05.395 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='116879af6933dce28675dfb800e4884753a65580' to.sha1='<NoInstanceOf_NormalFileDto>'): /3/c
08:06:05.404 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=9, fromRepositoryId="FileInProgressMarker", toRepositoryId="9", path="54602c58-cdbc-4f51-86fb-14c197121cee"] on repo=3e7d8edd-e6b7-40e2-93ef-7855059eb1b4
08:06:05.406 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/3/c'
08:06:05.406 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='116879af6933dce28675dfb800e4884753a65580' toSha1='null'
08:06:05.406 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/3/c'
08:06:05.406 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/3/c' offset=0
08:06:05.414 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/3/c' offset=0
08:06:05.426 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 730848 bytes in 20 ms. path='/3/c'
08:06:05.430 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_84a516841ba77a5b4648de2cd0dfcb30ea46dbb4_0 into destFile c
08:06:05.463 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=9, fromRepositoryId="FileInProgressMarker", toRepositoryId="9", path="54602c58-cdbc-4f51-86fb-14c197121cee"] on repo=3e7d8edd-e6b7-40e2-93ef-7855059eb1b4
08:06:05.465 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/3/d'
08:06:05.471 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='b6a2a2a0d1103eb23c61b212b1541b4c9ea1df40' to.sha1='<NoInstanceOf_NormalFileDto>'): /3/d
08:06:05.481 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=10, fromRepositoryId="FileInProgressMarker", toRepositoryId="10", path="54602c58-cdbc-4f51-86fb-14c197121cee"] on repo=3e7d8edd-e6b7-40e2-93ef-7855059eb1b4
08:06:05.483 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/3/d'
08:06:05.483 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='b6a2a2a0d1103eb23c61b212b1541b4c9ea1df40' toSha1='null'
08:06:05.483 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/3/d'
08:06:05.483 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/3/d' offset=0
08:06:05.483 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/3/d' offset=0
08:06:05.490 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 36258 bytes in 7 ms. path='/3/d'
08:06:05.494 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_3c363836cf4e16666669a25da280a1865c2d2874_0 into destFile d
08:06:05.502 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=10, fromRepositoryId="FileInProgressMarker", toRepositoryId="10", path="54602c58-cdbc-4f51-86fb-14c197121cee"] on repo=3e7d8edd-e6b7-40e2-93ef-7855059eb1b4
08:06:05.504 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 49.27%
08:06:05.517 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=54602c58-cdbc-4f51-86fb-14c197121cee remoteRepositoryId=3e7d8edd-e6b7-40e2-93ef-7855059eb1b4 localRepositoryRevisionSynced=2
08:06:05.524 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=3e7d8edd-e6b7-40e2-93ef-7855059eb1b4 remoteRepositoryId=54602c58-cdbc-4f51-86fb-14c197121cee transaction.localRevision=57 remoteFromLocalRevision=2
08:06:05.526 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncUp: fromID=3e7d8edd-e6b7-40e2-93ef-7855059eb1b4 from='target/repo/jz6keb6a-kf21d-local' toID=54602c58-cdbc-4f51-86fb-14c197121cee to='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/jz6keb6a-474zd-remote'
08:06:05.540 [main] INFO c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=3e7d8edd-e6b7-40e2-93ef-7855059eb1b4 remoteRepositoryId=54602c58-cdbc-4f51-86fb-14c197121cee localRepositoryRevisionSynced=-1 localRepositoryRevisionInProgress=57
08:06:05.556 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 63.43%
08:06:05.556 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 74.63%
08:06:05.566 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=3e7d8edd-e6b7-40e2-93ef-7855059eb1b4 remoteRepositoryId=54602c58-cdbc-4f51-86fb-14c197121cee localRepositoryRevisionSynced=57
08:06:05.572 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=54602c58-cdbc-4f51-86fb-14c197121cee remoteRepositoryId=3e7d8edd-e6b7-40e2-93ef-7855059eb1b4 transaction.localRevision=5 remoteFromLocalRevision=57
08:06:05.574 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDown: fromID=54602c58-cdbc-4f51-86fb-14c197121cee from='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/jz6keb6a-474zd-remote' toID=3e7d8edd-e6b7-40e2-93ef-7855059eb1b4 to='target/repo/jz6keb6a-kf21d-local', fromRepoLocalSync=false
08:06:05.581 [main] INFO c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=54602c58-cdbc-4f51-86fb-14c197121cee remoteRepositoryId=3e7d8edd-e6b7-40e2-93ef-7855059eb1b4 localRepositoryRevisionSynced=2 localRepositoryRevisionInProgress=5
08:06:05.583 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 88.31%
08:06:05.583 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 99.50%
08:06:05.585 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=54602c58-cdbc-4f51-86fb-14c197121cee remoteRepositoryId=3e7d8edd-e6b7-40e2-93ef-7855059eb1b4 localRepositoryRevisionSynced=5
08:06:05.588 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=3e7d8edd-e6b7-40e2-93ef-7855059eb1b4 remoteRepositoryId=54602c58-cdbc-4f51-86fb-14c197121cee transaction.localRevision=60 remoteFromLocalRevision=5
08:06:05.655 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 100.00%
08:06:05.668 [main] INFO c.c.c.l.LocalRepoManagerImpl - [65d5f41e]getCloseDeferredMillis: closeDeferredMillis=20000
08:06:05.668 [main] INFO c.c.c.l.LocalRepoManagerImpl - [65d5f41e]close: Deferring shut down of real LocalRepoManager 20000 ms.
08:06:05.669 [main] INFO c.c.c.l.LocalRepoManagerImpl - [d0ee353]getCloseDeferredMillis: closeDeferredMillis=20000
08:06:05.669 [main] INFO c.c.c.l.LocalRepoManagerImpl - [d0ee353]close: Deferring shut down of real LocalRepoManager 20000 ms.
08:06:05.734 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 1.00%
08:06:05.735 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 50.00%
08:06:05.737 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 62.25%
08:06:05.740 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 68.38%
08:06:05.742 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 74.50%
08:06:05.744 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 86.75%
08:06:05.748 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 92.88%
08:06:05.755 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 99.00%
08:06:05.797 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 100.00%
08:06:05.810 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 0.50%
08:06:05.811 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDown: fromID=54602c58-cdbc-4f51-86fb-14c197121cee from='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/jz6keb6a-474zd-remote' toID=3e7d8edd-e6b7-40e2-93ef-7855059eb1b4 to='target/repo/jz6keb6a-kf21d-local', fromRepoLocalSync=true
08:06:05.811 [pool-40-thread-1] INFO c.c.c.c.r.s.RepoToRepoSync - sync: locally syncing 3e7d8edd-e6b7-40e2-93ef-7855059eb1b4 ('target/repo/jz6keb6a-kf21d-local')
08:06:05.819 [pool-40-thread-1] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 12.94%
08:06:05.831 [pool-40-thread-1] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 19.03%
08:06:05.846 [pool-40-thread-1] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 24.11%
08:06:05.859 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 1.00%
08:06:05.861 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 50.00%
08:06:05.865 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 62.25%
08:06:05.867 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 68.38%
08:06:05.869 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 74.50%
08:06:05.871 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 86.75%
08:06:05.875 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 92.88%
08:06:05.878 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 99.00%
08:06:05.882 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 100.00%
08:06:05.887 [main] INFO c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=54602c58-cdbc-4f51-86fb-14c197121cee remoteRepositoryId=3e7d8edd-e6b7-40e2-93ef-7855059eb1b4 localRepositoryRevisionSynced=5 localRepositoryRevisionInProgress=8
08:06:05.902 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 31.09%
08:06:05.903 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path=''
08:06:05.915 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncModifications: Deleting '/2'
08:06:05.962 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 38.56%
08:06:05.963 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 46.02%
08:06:05.979 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=54602c58-cdbc-4f51-86fb-14c197121cee remoteRepositoryId=3e7d8edd-e6b7-40e2-93ef-7855059eb1b4 localRepositoryRevisionSynced=8
08:06:05.982 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=3e7d8edd-e6b7-40e2-93ef-7855059eb1b4 remoteRepositoryId=54602c58-cdbc-4f51-86fb-14c197121cee transaction.localRevision=63 remoteFromLocalRevision=8
08:06:05.984 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncUp: fromID=3e7d8edd-e6b7-40e2-93ef-7855059eb1b4 from='target/repo/jz6keb6a-kf21d-local' toID=54602c58-cdbc-4f51-86fb-14c197121cee to='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/jz6keb6a-474zd-remote'
08:06:05.994 [main] INFO c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=3e7d8edd-e6b7-40e2-93ef-7855059eb1b4 remoteRepositoryId=54602c58-cdbc-4f51-86fb-14c197121cee localRepositoryRevisionSynced=57 localRepositoryRevisionInProgress=63
08:06:06.003 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 52.24%
08:06:06.003 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 59.70%
08:06:06.003 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncModifications: Deleting '/2'
08:06:06.006 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 67.16%
08:06:06.006 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 74.63%
08:06:06.022 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=3e7d8edd-e6b7-40e2-93ef-7855059eb1b4 remoteRepositoryId=54602c58-cdbc-4f51-86fb-14c197121cee localRepositoryRevisionSynced=63
08:06:06.026 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=54602c58-cdbc-4f51-86fb-14c197121cee remoteRepositoryId=3e7d8edd-e6b7-40e2-93ef-7855059eb1b4 transaction.localRevision=11 remoteFromLocalRevision=63
08:06:06.029 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDown: fromID=54602c58-cdbc-4f51-86fb-14c197121cee from='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/jz6keb6a-474zd-remote' toID=3e7d8edd-e6b7-40e2-93ef-7855059eb1b4 to='target/repo/jz6keb6a-kf21d-local', fromRepoLocalSync=false
08:06:06.038 [main] INFO c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=54602c58-cdbc-4f51-86fb-14c197121cee remoteRepositoryId=3e7d8edd-e6b7-40e2-93ef-7855059eb1b4 localRepositoryRevisionSynced=8 localRepositoryRevisionInProgress=11
08:06:06.040 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 88.31%
08:06:06.040 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 99.50%
08:06:06.044 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=54602c58-cdbc-4f51-86fb-14c197121cee remoteRepositoryId=3e7d8edd-e6b7-40e2-93ef-7855059eb1b4 localRepositoryRevisionSynced=11
08:06:06.047 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=3e7d8edd-e6b7-40e2-93ef-7855059eb1b4 remoteRepositoryId=54602c58-cdbc-4f51-86fb-14c197121cee transaction.localRevision=66 remoteFromLocalRevision=11
08:06:06.049 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 100.00%
08:06:06.049 [main] INFO c.c.c.l.LocalRepoManagerImpl - [65d5f41e]close: Deferring shut down of real LocalRepoManager 20000 ms.
08:06:06.057 [main] INFO c.c.c.l.LocalRepoManagerImpl - [d0ee353]close: Deferring shut down of real LocalRepoManager 20000 ms.
Tests run: 20, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 310.189 sec - in co.codewizards.cloudstore.local.sync.RepoToRepoSyncTest
Results :
Tests run: 63, Failures: 0, Errors: 0, Skipped: 0
[JENKINS] Recording test results
[INFO]
[INFO] --- maven-jar-plugin:2.4:jar (default-jar) @ co.codewizards.cloudstore.local ---
[INFO] Building jar: /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/co.codewizards.cloudstore.local-0.10.7-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.7-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.7-SNAPSHOT.jar to /home/tomcat/.m2/repository/co/codewizards/cloudstore/co.codewizards.cloudstore.local/0.10.7-SNAPSHOT/co.codewizards.cloudstore.local-0.10.7-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.7-SNAPSHOT/co.codewizards.cloudstore.local-0.10.7-SNAPSHOT.pom
[INFO] Installing /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/co.codewizards.cloudstore.local-0.10.7-SNAPSHOT-sources.jar to /home/tomcat/.m2/repository/co/codewizards/cloudstore/co.codewizards.cloudstore.local/0.10.7-SNAPSHOT/co.codewizards.cloudstore.local-0.10.7-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.7-SNAPSHOT/maven-metadata.xml
Downloaded: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/snapshot/co/codewizards/cloudstore/co.codewizards.cloudstore.local/0.10.7-SNAPSHOT/maven-metadata.xml (2 KB at 20.1 KB/sec)
Uploading: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/snapshot/co/codewizards/cloudstore/co.codewizards.cloudstore.local/0.10.7-SNAPSHOT/co.codewizards.cloudstore.local-0.10.7-20190811.060608-16.jar
Uploaded: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/snapshot/co/codewizards/cloudstore/co.codewizards.cloudstore.local/0.10.7-SNAPSHOT/co.codewizards.cloudstore.local-0.10.7-20190811.060608-16.jar (203 KB at 28963.3 KB/sec)
Uploading: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/snapshot/co/codewizards/cloudstore/co.codewizards.cloudstore.local/0.10.7-SNAPSHOT/co.codewizards.cloudstore.local-0.10.7-20190811.060608-16.pom
Uploaded: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/snapshot/co/codewizards/cloudstore/co.codewizards.cloudstore.local/0.10.7-SNAPSHOT/co.codewizards.cloudstore.local-0.10.7-20190811.060608-16.pom (3 KB at 1282.7 KB/sec)
Downloading: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/snapshot/co/codewizards/cloudstore/co.codewizards.cloudstore.local/maven-metadata.xml
Downloaded: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/snapshot/co/codewizards/cloudstore/co.codewizards.cloudstore.local/maven-metadata.xml (399 B at 194.8 KB/sec)
Uploading: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/snapshot/co/codewizards/cloudstore/co.codewizards.cloudstore.local/0.10.7-SNAPSHOT/maven-metadata.xml
Uploaded: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/snapshot/co/codewizards/cloudstore/co.codewizards.cloudstore.local/0.10.7-SNAPSHOT/maven-metadata.xml (2 KB at 1003.9 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 (399 B at 389.6 KB/sec)
Uploading: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/snapshot/co/codewizards/cloudstore/co.codewizards.cloudstore.local/0.10.7-SNAPSHOT/co.codewizards.cloudstore.local-0.10.7-20190811.060608-16-sources.jar
Uploaded: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/snapshot/co/codewizards/cloudstore/co.codewizards.cloudstore.local/0.10.7-SNAPSHOT/co.codewizards.cloudstore.local-0.10.7-20190811.060608-16-sources.jar (105 KB at 52138.2 KB/sec)
Uploading: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/snapshot/co/codewizards/cloudstore/co.codewizards.cloudstore.local/0.10.7-SNAPSHOT/maven-metadata.xml
Uploaded: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/snapshot/co/codewizards/cloudstore/co.codewizards.cloudstore.local/0.10.7-SNAPSHOT/maven-metadata.xml (2 KB at 1003.9 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
|
Help us localize this page