|  Console Output
Skipping 706 KB.. Full Log Synchronising...: 0.50%
06:21:34.409 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDown: fromID=6379ce19-0bec-4462-8c43-f4caaa6f9651 from='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.local/target/repo/k6yl42b3-xe30p-remote/2' toID=cc530d94-8575-41a7-bf37-de722bf8839f to='target/repo/k6yl42b3-aa24d-local', fromRepoLocalSync=true
06:21:34.409 [pool-31-thread-1] INFO c.c.c.c.r.s.RepoToRepoSync - sync: locally syncing cc530d94-8575-41a7-bf37-de722bf8839f ('target/repo/k6yl42b3-aa24d-local')
06:21:34.434 [pool-31-thread-1] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 12.94%
06:21:34.441 [pool-31-thread-1] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 25.12%
06:21:34.444 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 1.00%
06:21:34.445 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 50.00%
06:21:34.448 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 58.17%
06:21:34.455 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 63.61%
06:21:34.459 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 74.50%
06:21:34.466 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 80.62%
06:21:34.469 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 90.83%
06:21:34.474 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 95.94%
06:21:34.480 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 100.00%
06:21:34.509 [main] INFO c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=6379ce19-0bec-4462-8c43-f4caaa6f9651 remoteRepositoryId=cc530d94-8575-41a7-bf37-de722bf8839f localRepositoryRevisionSynced=-1 localRepositoryRevisionInProgress=2
06:21:34.559 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path=''
06:21:34.579 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path='/1'
06:21:34.591 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 31.31%
06:21:34.596 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 36.58%
06:21:34.597 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 41.85%
06:21:34.597 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/1/a'
06:21:34.606 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='59876bfb731fb8b085796c86178ffa8106929cf4' to.sha1='<NoInstanceOf_NormalFileDto>'): /1/a
06:21:34.644 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=1, fromRepositoryId="FileInProgressMarker", toRepositoryId="1", path="6379ce19-0bec-4462-8c43-f4caaa6f9651"] on repo=cc530d94-8575-41a7-bf37-de722bf8839f
06:21:34.645 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/1/a'
06:21:34.645 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='59876bfb731fb8b085796c86178ffa8106929cf4' toSha1='null'
06:21:34.645 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/1/a'
06:21:34.645 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/1/a' offset=0
06:21:34.646 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/1/a' offset=0
06:21:34.651 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 24302 bytes in 6 ms. path='/1/a'
06:21:34.658 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_86f7e437faa5a7fce15d1ddcb9eaeaea377667b8_0 into destFile a
06:21:34.687 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=1, fromRepositoryId="FileInProgressMarker", toRepositoryId="1", path="6379ce19-0bec-4462-8c43-f4caaa6f9651"] on repo=cc530d94-8575-41a7-bf37-de722bf8839f
06:21:34.693 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/1/b'
06:21:34.703 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='70e16680b0a2c2bfd7d864fb305fc0bea941aa0c' to.sha1='<NoInstanceOf_NormalFileDto>'): /1/b
06:21:34.714 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=2, fromRepositoryId="FileInProgressMarker", toRepositoryId="2", path="6379ce19-0bec-4462-8c43-f4caaa6f9651"] on repo=cc530d94-8575-41a7-bf37-de722bf8839f
06:21:34.716 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/1/b'
06:21:34.716 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='70e16680b0a2c2bfd7d864fb305fc0bea941aa0c' toSha1='null'
06:21:34.716 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/1/b'
06:21:34.716 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/1/b' offset=0
06:21:34.718 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/1/b' offset=0
06:21:34.725 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 150423 bytes in 9 ms. path='/1/b'
06:21:34.729 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_e9d71f5ee7c92d6dc9e92ffdad17b8bd49418f98_0 into destFile b
06:21:34.743 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=2, fromRepositoryId="FileInProgressMarker", toRepositoryId="2", path="6379ce19-0bec-4462-8c43-f4caaa6f9651"] on repo=cc530d94-8575-41a7-bf37-de722bf8839f
06:21:34.750 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/a'
06:21:34.761 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='730b1d3027183b8aa548ebe75578879324fd430a' to.sha1='<NoInstanceOf_NormalFileDto>'): /a
06:21:34.773 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=3, fromRepositoryId="FileInProgressMarker", toRepositoryId="3", path="6379ce19-0bec-4462-8c43-f4caaa6f9651"] on repo=cc530d94-8575-41a7-bf37-de722bf8839f
06:21:34.775 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/a'
06:21:34.775 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='730b1d3027183b8aa548ebe75578879324fd430a' toSha1='null'
06:21:34.775 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/a'
06:21:34.775 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/a' offset=0
06:21:34.775 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/a' offset=0
06:21:34.779 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 33876 bytes in 4 ms. path='/a'
06:21:34.782 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_86f7e437faa5a7fce15d1ddcb9eaeaea377667b8_0 into destFile a
06:21:34.793 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=3, fromRepositoryId="FileInProgressMarker", toRepositoryId="3", path="6379ce19-0bec-4462-8c43-f4caaa6f9651"] on repo=cc530d94-8575-41a7-bf37-de722bf8839f
06:21:34.796 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 48.43%
06:21:34.813 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=6379ce19-0bec-4462-8c43-f4caaa6f9651 remoteRepositoryId=cc530d94-8575-41a7-bf37-de722bf8839f localRepositoryRevisionSynced=2
06:21:34.825 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=cc530d94-8575-41a7-bf37-de722bf8839f remoteRepositoryId=6379ce19-0bec-4462-8c43-f4caaa6f9651 transaction.localRevision=19 remoteFromLocalRevision=2
06:21:34.833 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncUp: fromID=cc530d94-8575-41a7-bf37-de722bf8839f from='target/repo/k6yl42b3-aa24d-local' toID=6379ce19-0bec-4462-8c43-f4caaa6f9651 to='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.local/target/repo/k6yl42b3-xe30p-remote/2'
06:21:34.857 [main] INFO c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=cc530d94-8575-41a7-bf37-de722bf8839f remoteRepositoryId=6379ce19-0bec-4462-8c43-f4caaa6f9651 localRepositoryRevisionSynced=-1 localRepositoryRevisionInProgress=19
06:21:34.872 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 63.43%
06:21:34.872 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 74.63%
06:21:34.883 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=cc530d94-8575-41a7-bf37-de722bf8839f remoteRepositoryId=6379ce19-0bec-4462-8c43-f4caaa6f9651 localRepositoryRevisionSynced=19
06:21:34.891 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=6379ce19-0bec-4462-8c43-f4caaa6f9651 remoteRepositoryId=cc530d94-8575-41a7-bf37-de722bf8839f transaction.localRevision=5 remoteFromLocalRevision=19
06:21:34.897 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDown: fromID=6379ce19-0bec-4462-8c43-f4caaa6f9651 from='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.local/target/repo/k6yl42b3-xe30p-remote/2' toID=cc530d94-8575-41a7-bf37-de722bf8839f to='target/repo/k6yl42b3-aa24d-local', fromRepoLocalSync=false
06:21:34.917 [main] INFO c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=6379ce19-0bec-4462-8c43-f4caaa6f9651 remoteRepositoryId=cc530d94-8575-41a7-bf37-de722bf8839f localRepositoryRevisionSynced=2 localRepositoryRevisionInProgress=5
06:21:34.922 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 88.31%
06:21:34.922 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 99.50%
06:21:34.930 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=6379ce19-0bec-4462-8c43-f4caaa6f9651 remoteRepositoryId=cc530d94-8575-41a7-bf37-de722bf8839f localRepositoryRevisionSynced=5
06:21:34.945 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=cc530d94-8575-41a7-bf37-de722bf8839f remoteRepositoryId=6379ce19-0bec-4462-8c43-f4caaa6f9651 transaction.localRevision=22 remoteFromLocalRevision=5
06:21:34.947 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 100.00%
06:21:34.964 [main] INFO c.c.c.l.LocalRepoManagerImpl - [48930622]getCloseDeferredMillis: closeDeferredMillis=20000
06:21:34.964 [main] INFO c.c.c.l.LocalRepoManagerImpl - [48930622]close: Deferring shut down of real LocalRepoManager 20000 ms.
06:21:34.965 [main] INFO c.c.c.l.LocalRepoManagerImpl - [25b1eae1]getCloseDeferredMillis: closeDeferredMillis=20000
06:21:34.965 [main] INFO c.c.c.l.LocalRepoManagerImpl - [25b1eae1]close: Deferring shut down of real LocalRepoManager 20000 ms.
06:21:34.978 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 1.00%
06:21:34.978 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 50.00%
06:21:34.980 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 58.17%
06:21:34.983 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 63.61%
06:21:34.986 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 74.50%
06:21:35.012 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 80.62%
06:21:35.016 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 90.83%
06:21:35.032 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 96.55%
06:21:35.051 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 100.00%
06:21:35.083 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 0.50%
06:21:35.084 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDown: fromID=6379ce19-0bec-4462-8c43-f4caaa6f9651 from='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.local/target/repo/k6yl42b3-xe30p-remote/2' toID=cc530d94-8575-41a7-bf37-de722bf8839f to='target/repo/k6yl42b3-aa24d-local', fromRepoLocalSync=true
06:21:35.084 [pool-32-thread-1] INFO c.c.c.c.r.s.RepoToRepoSync - sync: locally syncing cc530d94-8575-41a7-bf37-de722bf8839f ('target/repo/k6yl42b3-aa24d-local')
06:21:35.089 [pool-32-thread-1] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 12.94%
06:21:35.102 [pool-32-thread-1] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 18.27%
06:21:35.112 [pool-32-thread-1] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 25.12%
06:21:35.121 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 1.00%
06:21:35.122 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 50.00%
06:21:35.124 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 58.17%
06:21:35.128 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 63.61%
06:21:35.132 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 74.50%
06:21:35.145 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 80.62%
06:21:35.150 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 90.83%
06:21:35.155 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 96.55%
06:21:35.164 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 100.00%
06:21:35.175 [main] INFO c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=6379ce19-0bec-4462-8c43-f4caaa6f9651 remoteRepositoryId=cc530d94-8575-41a7-bf37-de722bf8839f localRepositoryRevisionSynced=5 localRepositoryRevisionInProgress=8
06:21:35.195 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path='/1'
06:21:35.208 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 31.31%
06:21:35.209 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path='/1/5'
06:21:35.218 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 36.58%
06:21:35.219 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 41.85%
06:21:35.219 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/1/5/aaa'
06:21:35.227 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='bb1d682f745f99d832fc5e0898c4c8b8b5a1aa9b' to.sha1='<NoInstanceOf_NormalFileDto>'): /1/5/aaa
06:21:35.239 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=4, fromRepositoryId="FileInProgressMarker", toRepositoryId="4", path="6379ce19-0bec-4462-8c43-f4caaa6f9651"] on repo=cc530d94-8575-41a7-bf37-de722bf8839f
06:21:35.241 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/1/5/aaa'
06:21:35.241 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='bb1d682f745f99d832fc5e0898c4c8b8b5a1aa9b' toSha1='null'
06:21:35.241 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/1/5/aaa'
06:21:35.241 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/1/5/aaa' offset=0
06:21:35.242 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/1/5/aaa' offset=0
06:21:35.248 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 20480 bytes in 7 ms. path='/1/5/aaa'
06:21:35.254 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_7e240de74fb1ed08fa08d38063f6a6a91462a815_0 into destFile aaa
06:21:35.263 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=4, fromRepositoryId="FileInProgressMarker", toRepositoryId="4", path="6379ce19-0bec-4462-8c43-f4caaa6f9651"] on repo=cc530d94-8575-41a7-bf37-de722bf8839f
06:21:35.267 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/1/5/bbb'
06:21:35.275 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='bcd6ab70dde540265f345ae326bbd6ba3dd57867' to.sha1='<NoInstanceOf_NormalFileDto>'): /1/5/bbb
06:21:35.289 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=5, fromRepositoryId="FileInProgressMarker", toRepositoryId="5", path="6379ce19-0bec-4462-8c43-f4caaa6f9651"] on repo=cc530d94-8575-41a7-bf37-de722bf8839f
06:21:35.290 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/1/5/bbb'
06:21:35.290 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='bcd6ab70dde540265f345ae326bbd6ba3dd57867' toSha1='null'
06:21:35.290 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/1/5/bbb'
06:21:35.290 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/1/5/bbb' offset=0
06:21:35.291 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/1/5/bbb' offset=0
06:21:35.299 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 148092 bytes in 9 ms. path='/1/5/bbb'
06:21:35.303 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_5cb138284d431abd6a053a56625ec088bfb88912_0 into destFile bbb
06:21:35.327 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=5, fromRepositoryId="FileInProgressMarker", toRepositoryId="5", path="6379ce19-0bec-4462-8c43-f4caaa6f9651"] on repo=cc530d94-8575-41a7-bf37-de722bf8839f
06:21:35.332 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 48.43%
06:21:35.336 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=6379ce19-0bec-4462-8c43-f4caaa6f9651 remoteRepositoryId=cc530d94-8575-41a7-bf37-de722bf8839f localRepositoryRevisionSynced=8
06:21:35.345 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=cc530d94-8575-41a7-bf37-de722bf8839f remoteRepositoryId=6379ce19-0bec-4462-8c43-f4caaa6f9651 transaction.localRevision=35 remoteFromLocalRevision=8
06:21:35.350 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncUp: fromID=cc530d94-8575-41a7-bf37-de722bf8839f from='target/repo/k6yl42b3-aa24d-local' toID=6379ce19-0bec-4462-8c43-f4caaa6f9651 to='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.local/target/repo/k6yl42b3-xe30p-remote/2'
06:21:35.363 [main] INFO c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=cc530d94-8575-41a7-bf37-de722bf8839f remoteRepositoryId=6379ce19-0bec-4462-8c43-f4caaa6f9651 localRepositoryRevisionSynced=19 localRepositoryRevisionInProgress=35
06:21:35.365 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 63.43%
06:21:35.365 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 74.63%
06:21:35.368 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=cc530d94-8575-41a7-bf37-de722bf8839f remoteRepositoryId=6379ce19-0bec-4462-8c43-f4caaa6f9651 localRepositoryRevisionSynced=35
06:21:35.372 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=6379ce19-0bec-4462-8c43-f4caaa6f9651 remoteRepositoryId=cc530d94-8575-41a7-bf37-de722bf8839f transaction.localRevision=11 remoteFromLocalRevision=35
06:21:35.378 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDown: fromID=6379ce19-0bec-4462-8c43-f4caaa6f9651 from='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.local/target/repo/k6yl42b3-xe30p-remote/2' toID=cc530d94-8575-41a7-bf37-de722bf8839f to='target/repo/k6yl42b3-aa24d-local', fromRepoLocalSync=false
06:21:35.393 [main] INFO c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=6379ce19-0bec-4462-8c43-f4caaa6f9651 remoteRepositoryId=cc530d94-8575-41a7-bf37-de722bf8839f localRepositoryRevisionSynced=8 localRepositoryRevisionInProgress=11
06:21:35.402 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 88.31%
06:21:35.402 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 99.50%
06:21:35.405 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=6379ce19-0bec-4462-8c43-f4caaa6f9651 remoteRepositoryId=cc530d94-8575-41a7-bf37-de722bf8839f localRepositoryRevisionSynced=11
06:21:35.413 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=cc530d94-8575-41a7-bf37-de722bf8839f remoteRepositoryId=6379ce19-0bec-4462-8c43-f4caaa6f9651 transaction.localRevision=38 remoteFromLocalRevision=11
06:21:35.415 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 100.00%
06:21:35.415 [main] INFO c.c.c.l.LocalRepoManagerImpl - [48930622]close: Deferring shut down of real LocalRepoManager 20000 ms.
06:21:35.434 [main] INFO c.c.c.l.LocalRepoManagerImpl - [25b1eae1]close: Deferring shut down of real LocalRepoManager 20000 ms.
06:21:35.448 [main] INFO c.c.c.l.LocalRepoManagerImpl - [13057759]<init>: localRoot='/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.local/target/repo/k6yl48gm-1qbkw-local'
06:21:35.453 [main] INFO c.c.c.l.LocalRepoManagerImpl - [13057759]initLockFile: Repository '/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.local/target/repo/k6yl48gm-1qbkw-local' locked successfully.
06:21:37.942 [main] INFO c.c.c.l.LocalRepoManagerImpl - [13057759]initPersistenceManagerFactory: repositoryId=6d8ad33f-6098-48c2-959d-afb1df40d9ff
06:21:37.944 [main] INFO c.c.c.l.LocalRepoManagerImpl - [13057759]initPersistenceManagerFactory: Started up PersistenceManagerFactory successfully in 1274 ms.
06:21:37.969 [main] INFO c.c.c.l.LocalRepoManagerImpl - [2dc612b7]<init>: localRoot='/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.local/target/repo/k6yl48gn-asq0x-remote'
06:21:37.978 [main] INFO c.c.c.l.LocalRepoManagerImpl - [2dc612b7]initLockFile: Repository '/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.local/target/repo/k6yl48gn-asq0x-remote' locked successfully.
06:21:38.302 [closeDeferredTimer-9fa722c-3] INFO c.c.c.l.LocalRepoManagerImpl - [9fa722c]_close: Shutting down real LocalRepoManager.
06:21:38.306 [closeDeferredTimer-1536cd09-3] INFO c.c.c.l.LocalRepoManagerImpl - [1536cd09]_close: Shutting down real LocalRepoManager.
06:21:43.816 [main] INFO c.c.c.l.LocalRepoManagerImpl - [2dc612b7]initPersistenceManagerFactory: repositoryId=3c2c6c9a-f364-453d-a016-a32f7edde656
06:21:43.823 [main] INFO c.c.c.l.LocalRepoManagerImpl - [2dc612b7]initPersistenceManagerFactory: Started up PersistenceManagerFactory successfully in 2392 ms.
06:21:43.920 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 1.00%
06:21:43.926 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 50.00%
06:21:43.936 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 58.00%
06:21:43.995 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 63.61%
06:21:44.020 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 74.34%
06:21:44.055 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 79.38%
06:21:44.068 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 90.67%
06:21:44.099 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 95.70%
06:21:44.125 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 100.00%
06:21:44.150 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - local repo: 6d8ad33f-6098-48c2-959d-afb1df40d9ff
06:21:44.151 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - remote repo: 3c2c6c9a-f364-453d-a016-a32f7edde656
06:21:44.155 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 0.50%
06:21:44.155 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDown: fromID=3c2c6c9a-f364-453d-a016-a32f7edde656 from='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.local/target/repo/k6yl48gn-asq0x-remote' toID=6d8ad33f-6098-48c2-959d-afb1df40d9ff to='target/repo/k6yl48gm-1qbkw-local', fromRepoLocalSync=true
06:21:44.156 [pool-33-thread-1] INFO c.c.c.c.r.s.RepoToRepoSync - sync: locally syncing 6d8ad33f-6098-48c2-959d-afb1df40d9ff ('target/repo/k6yl48gm-1qbkw-local')
06:21:44.184 [pool-33-thread-1] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 12.94%
06:21:44.190 [pool-33-thread-1] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 25.12%
06:21:44.191 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 1.00%
06:21:44.193 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 50.00%
06:21:44.197 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 58.17%
06:21:44.201 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 63.61%
06:21:44.205 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 74.50%
06:21:44.212 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 80.62%
06:21:44.215 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 90.83%
06:21:44.219 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 95.94%
06:21:44.226 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 100.00%
06:21:44.256 [main] INFO c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=3c2c6c9a-f364-453d-a016-a32f7edde656 remoteRepositoryId=6d8ad33f-6098-48c2-959d-afb1df40d9ff localRepositoryRevisionSynced=-1 localRepositoryRevisionInProgress=2
06:21:44.295 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path=''
06:21:44.317 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path='/1'
06:21:44.333 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 30.22%
06:21:44.333 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path='/2'
06:21:44.342 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path='/2/1'
06:21:44.355 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path='/3'
06:21:44.368 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 35.46%
06:21:44.368 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 40.70%
06:21:44.368 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/1/a'
06:21:44.376 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='035cfd907fc454fe64b49198c474f3b43cacea16' to.sha1='<NoInstanceOf_NormalFileDto>'): /1/a
06:21:44.411 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=1, fromRepositoryId="FileInProgressMarker", toRepositoryId="1", path="3c2c6c9a-f364-453d-a016-a32f7edde656"] on repo=6d8ad33f-6098-48c2-959d-afb1df40d9ff
06:21:44.412 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/1/a'
06:21:44.412 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='035cfd907fc454fe64b49198c474f3b43cacea16' toSha1='null'
06:21:44.412 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/1/a'
06:21:44.412 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/1/a' offset=0
06:21:44.420 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/1/a' offset=0
06:21:44.434 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 695506 bytes in 22 ms. path='/1/a'
06:21:44.438 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_86f7e437faa5a7fce15d1ddcb9eaeaea377667b8_0 into destFile a
06:21:44.496 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=1, fromRepositoryId="FileInProgressMarker", toRepositoryId="1", path="3c2c6c9a-f364-453d-a016-a32f7edde656"] on repo=6d8ad33f-6098-48c2-959d-afb1df40d9ff
06:21:44.508 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/1/b'
06:21:44.517 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='85512390ac7e716b985ec7c9e27743b35d1824f7' to.sha1='<NoInstanceOf_NormalFileDto>'): /1/b
06:21:44.532 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=2, fromRepositoryId="FileInProgressMarker", toRepositoryId="2", path="3c2c6c9a-f364-453d-a016-a32f7edde656"] on repo=6d8ad33f-6098-48c2-959d-afb1df40d9ff
06:21:44.533 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/1/b'
06:21:44.533 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='85512390ac7e716b985ec7c9e27743b35d1824f7' toSha1='null'
06:21:44.533 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/1/b'
06:21:44.533 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/1/b' offset=0
06:21:44.537 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/1/b' offset=0
06:21:44.546 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 434728 bytes in 13 ms. path='/1/b'
06:21:44.550 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_e9d71f5ee7c92d6dc9e92ffdad17b8bd49418f98_0 into destFile b
06:21:44.575 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=2, fromRepositoryId="FileInProgressMarker", toRepositoryId="2", path="3c2c6c9a-f364-453d-a016-a32f7edde656"] on repo=6d8ad33f-6098-48c2-959d-afb1df40d9ff
06:21:44.579 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/1/c'
06:21:44.587 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='ca85ba1213125a105ca35ec1dc780e420687e06d' to.sha1='<NoInstanceOf_NormalFileDto>'): /1/c
06:21:44.599 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=3, fromRepositoryId="FileInProgressMarker", toRepositoryId="3", path="3c2c6c9a-f364-453d-a016-a32f7edde656"] on repo=6d8ad33f-6098-48c2-959d-afb1df40d9ff
06:21:44.601 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/1/c'
06:21:44.601 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='ca85ba1213125a105ca35ec1dc780e420687e06d' toSha1='null'
06:21:44.601 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/1/c'
06:21:44.601 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/1/c' offset=0
06:21:44.609 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/1/c' offset=0
06:21:44.622 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 734160 bytes in 21 ms. path='/1/c'
06:21:44.627 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_84a516841ba77a5b4648de2cd0dfcb30ea46dbb4_0 into destFile c
06:21:44.663 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=3, fromRepositoryId="FileInProgressMarker", toRepositoryId="3", path="3c2c6c9a-f364-453d-a016-a32f7edde656"] on repo=6d8ad33f-6098-48c2-959d-afb1df40d9ff
06:21:44.667 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/2/1/a'
06:21:44.680 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='ba01699668f9573a49a327f704fd9ef2b344ce4f' to.sha1='<NoInstanceOf_NormalFileDto>'): /2/1/a
06:21:44.692 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=4, fromRepositoryId="FileInProgressMarker", toRepositoryId="4", path="3c2c6c9a-f364-453d-a016-a32f7edde656"] on repo=6d8ad33f-6098-48c2-959d-afb1df40d9ff
06:21:44.694 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/2/1/a'
06:21:44.694 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='ba01699668f9573a49a327f704fd9ef2b344ce4f' toSha1='null'
06:21:44.694 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/2/1/a'
06:21:44.694 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/2/1/a' offset=0
06:21:44.695 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/2/1/a' offset=0
06:21:44.702 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 108632 bytes in 8 ms. path='/2/1/a'
06:21:44.707 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_86f7e437faa5a7fce15d1ddcb9eaeaea377667b8_0 into destFile a
06:21:44.720 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=4, fromRepositoryId="FileInProgressMarker", toRepositoryId="4", path="3c2c6c9a-f364-453d-a016-a32f7edde656"] on repo=6d8ad33f-6098-48c2-959d-afb1df40d9ff
06:21:44.723 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/2/1/b'
06:21:44.735 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='536ec9b9b6279b04c987675edffd8a5d84224b05' to.sha1='<NoInstanceOf_NormalFileDto>'): /2/1/b
06:21:44.749 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=5, fromRepositoryId="FileInProgressMarker", toRepositoryId="5", path="3c2c6c9a-f364-453d-a016-a32f7edde656"] on repo=6d8ad33f-6098-48c2-959d-afb1df40d9ff
06:21:44.751 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/2/1/b'
06:21:44.752 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='536ec9b9b6279b04c987675edffd8a5d84224b05' toSha1='null'
06:21:44.752 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/2/1/b'
06:21:44.752 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/2/1/b' offset=0
06:21:44.766 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/2/1/b' offset=0
06:21:44.783 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 726066 bytes in 31 ms. path='/2/1/b'
06:21:44.789 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_e9d71f5ee7c92d6dc9e92ffdad17b8bd49418f98_0 into destFile b
06:21:44.828 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=5, fromRepositoryId="FileInProgressMarker", toRepositoryId="5", path="3c2c6c9a-f364-453d-a016-a32f7edde656"] on repo=6d8ad33f-6098-48c2-959d-afb1df40d9ff
06:21:44.831 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/2/a'
06:21:44.845 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='3da384dd1b05b0f72afd1961bb1491425910d2e6' to.sha1='<NoInstanceOf_NormalFileDto>'): /2/a
06:21:44.858 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=6, fromRepositoryId="FileInProgressMarker", toRepositoryId="6", path="3c2c6c9a-f364-453d-a016-a32f7edde656"] on repo=6d8ad33f-6098-48c2-959d-afb1df40d9ff
06:21:44.859 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/2/a'
06:21:44.859 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='3da384dd1b05b0f72afd1961bb1491425910d2e6' toSha1='null'
06:21:44.859 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/2/a'
06:21:44.859 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/2/a' offset=0
06:21:44.863 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/2/a' offset=0
06:21:44.870 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 242935 bytes in 11 ms. path='/2/a'
06:21:44.875 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_86f7e437faa5a7fce15d1ddcb9eaeaea377667b8_0 into destFile a
06:21:44.898 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=6, fromRepositoryId="FileInProgressMarker", toRepositoryId="6", path="3c2c6c9a-f364-453d-a016-a32f7edde656"] on repo=6d8ad33f-6098-48c2-959d-afb1df40d9ff
06:21:44.906 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/3/a'
06:21:44.912 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='9ac91d175652b5e143b6d73708dde48fb1d53506' to.sha1='<NoInstanceOf_NormalFileDto>'): /3/a
06:21:44.924 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=7, fromRepositoryId="FileInProgressMarker", toRepositoryId="7", path="3c2c6c9a-f364-453d-a016-a32f7edde656"] on repo=6d8ad33f-6098-48c2-959d-afb1df40d9ff
06:21:44.925 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/3/a'
06:21:44.925 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='9ac91d175652b5e143b6d73708dde48fb1d53506' toSha1='null'
06:21:44.925 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/3/a'
06:21:44.925 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/3/a' offset=0
06:21:44.926 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/3/a' offset=0
06:21:44.931 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 32670 bytes in 6 ms. path='/3/a'
06:21:44.935 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_86f7e437faa5a7fce15d1ddcb9eaeaea377667b8_0 into destFile a
06:21:44.950 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=7, fromRepositoryId="FileInProgressMarker", toRepositoryId="7", path="3c2c6c9a-f364-453d-a016-a32f7edde656"] on repo=6d8ad33f-6098-48c2-959d-afb1df40d9ff
06:21:44.954 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/3/b'
06:21:44.964 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='102b421b1467e115e837163b14adc3dbd30ddf3c' to.sha1='<NoInstanceOf_NormalFileDto>'): /3/b
06:21:44.977 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=8, fromRepositoryId="FileInProgressMarker", toRepositoryId="8", path="3c2c6c9a-f364-453d-a016-a32f7edde656"] on repo=6d8ad33f-6098-48c2-959d-afb1df40d9ff
06:21:44.979 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/3/b'
06:21:44.979 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='102b421b1467e115e837163b14adc3dbd30ddf3c' toSha1='null'
06:21:44.979 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/3/b'
06:21:44.979 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/3/b' offset=0
06:21:44.982 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/3/b' offset=0
06:21:44.992 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 308689 bytes in 13 ms. path='/3/b'
06:21:44.996 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_e9d71f5ee7c92d6dc9e92ffdad17b8bd49418f98_0 into destFile b
06:21:45.019 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=8, fromRepositoryId="FileInProgressMarker", toRepositoryId="8", path="3c2c6c9a-f364-453d-a016-a32f7edde656"] on repo=6d8ad33f-6098-48c2-959d-afb1df40d9ff
06:21:45.023 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/3/c'
06:21:45.032 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='0a9e09cd5639ba0a686b3852ab39961f2425f9dc' to.sha1='<NoInstanceOf_NormalFileDto>'): /3/c
06:21:45.045 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=9, fromRepositoryId="FileInProgressMarker", toRepositoryId="9", path="3c2c6c9a-f364-453d-a016-a32f7edde656"] on repo=6d8ad33f-6098-48c2-959d-afb1df40d9ff
06:21:45.046 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/3/c'
06:21:45.046 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='0a9e09cd5639ba0a686b3852ab39961f2425f9dc' toSha1='null'
06:21:45.046 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/3/c'
06:21:45.047 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/3/c' offset=0
06:21:45.048 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/3/c' offset=0
06:21:45.055 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 155022 bytes in 9 ms. path='/3/c'
06:21:45.058 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_84a516841ba77a5b4648de2cd0dfcb30ea46dbb4_0 into destFile c
06:21:45.072 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=9, fromRepositoryId="FileInProgressMarker", toRepositoryId="9", path="3c2c6c9a-f364-453d-a016-a32f7edde656"] on repo=6d8ad33f-6098-48c2-959d-afb1df40d9ff
06:21:45.075 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/3/d'
06:21:45.083 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='1d998b4b2a2ef39f5441f45729b0473b325454a9' to.sha1='<NoInstanceOf_NormalFileDto>'): /3/d
06:21:45.098 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=10, fromRepositoryId="FileInProgressMarker", toRepositoryId="10", path="3c2c6c9a-f364-453d-a016-a32f7edde656"] on repo=6d8ad33f-6098-48c2-959d-afb1df40d9ff
06:21:45.100 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/3/d'
06:21:45.100 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='1d998b4b2a2ef39f5441f45729b0473b325454a9' toSha1='null'
06:21:45.100 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/3/d'
06:21:45.100 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/3/d' offset=0
06:21:45.111 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/3/d' offset=0
06:21:45.130 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 580797 bytes in 30 ms. path='/3/d'
06:21:45.135 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_3c363836cf4e16666669a25da280a1865c2d2874_0 into destFile d
06:21:45.165 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=10, fromRepositoryId="FileInProgressMarker", toRepositoryId="10", path="3c2c6c9a-f364-453d-a016-a32f7edde656"] on repo=6d8ad33f-6098-48c2-959d-afb1df40d9ff
06:21:45.168 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 49.27%
06:21:45.190 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=3c2c6c9a-f364-453d-a016-a32f7edde656 remoteRepositoryId=6d8ad33f-6098-48c2-959d-afb1df40d9ff localRepositoryRevisionSynced=2
06:21:45.203 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=6d8ad33f-6098-48c2-959d-afb1df40d9ff remoteRepositoryId=3c2c6c9a-f364-453d-a016-a32f7edde656 transaction.localRevision=57 remoteFromLocalRevision=2
06:21:45.208 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncUp: fromID=6d8ad33f-6098-48c2-959d-afb1df40d9ff from='target/repo/k6yl48gm-1qbkw-local' toID=3c2c6c9a-f364-453d-a016-a32f7edde656 to='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.local/target/repo/k6yl48gn-asq0x-remote'
06:21:45.229 [main] INFO c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=6d8ad33f-6098-48c2-959d-afb1df40d9ff remoteRepositoryId=3c2c6c9a-f364-453d-a016-a32f7edde656 localRepositoryRevisionSynced=-1 localRepositoryRevisionInProgress=57
06:21:45.238 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 63.43%
06:21:45.239 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 74.63%
06:21:45.249 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=6d8ad33f-6098-48c2-959d-afb1df40d9ff remoteRepositoryId=3c2c6c9a-f364-453d-a016-a32f7edde656 localRepositoryRevisionSynced=57
06:21:45.267 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=3c2c6c9a-f364-453d-a016-a32f7edde656 remoteRepositoryId=6d8ad33f-6098-48c2-959d-afb1df40d9ff transaction.localRevision=5 remoteFromLocalRevision=57
06:21:45.274 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDown: fromID=3c2c6c9a-f364-453d-a016-a32f7edde656 from='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.local/target/repo/k6yl48gn-asq0x-remote' toID=6d8ad33f-6098-48c2-959d-afb1df40d9ff to='target/repo/k6yl48gm-1qbkw-local', fromRepoLocalSync=false
06:21:45.292 [main] INFO c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=3c2c6c9a-f364-453d-a016-a32f7edde656 remoteRepositoryId=6d8ad33f-6098-48c2-959d-afb1df40d9ff localRepositoryRevisionSynced=2 localRepositoryRevisionInProgress=5
06:21:45.295 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 88.31%
06:21:45.295 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 99.50%
06:21:45.301 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=3c2c6c9a-f364-453d-a016-a32f7edde656 remoteRepositoryId=6d8ad33f-6098-48c2-959d-afb1df40d9ff localRepositoryRevisionSynced=5
06:21:45.311 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=6d8ad33f-6098-48c2-959d-afb1df40d9ff remoteRepositoryId=3c2c6c9a-f364-453d-a016-a32f7edde656 transaction.localRevision=60 remoteFromLocalRevision=5
06:21:45.313 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 100.00%
06:21:45.334 [main] INFO c.c.c.l.LocalRepoManagerImpl - [13057759]getCloseDeferredMillis: closeDeferredMillis=20000
06:21:45.334 [main] INFO c.c.c.l.LocalRepoManagerImpl - [13057759]close: Deferring shut down of real LocalRepoManager 20000 ms.
06:21:45.335 [main] INFO c.c.c.l.LocalRepoManagerImpl - [2dc612b7]getCloseDeferredMillis: closeDeferredMillis=20000
06:21:45.335 [main] INFO c.c.c.l.LocalRepoManagerImpl - [2dc612b7]close: Deferring shut down of real LocalRepoManager 20000 ms.
06:21:45.415 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 1.00%
06:21:45.416 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 50.00%
06:21:45.418 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 58.17%
06:21:45.421 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 63.61%
06:21:45.425 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 74.50%
06:21:45.468 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 80.62%
06:21:45.473 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 90.83%
06:21:45.485 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 96.55%
06:21:45.508 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 100.00%
06:21:45.533 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 0.50%
06:21:45.534 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDown: fromID=3c2c6c9a-f364-453d-a016-a32f7edde656 from='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.local/target/repo/k6yl48gn-asq0x-remote' toID=6d8ad33f-6098-48c2-959d-afb1df40d9ff to='target/repo/k6yl48gm-1qbkw-local', fromRepoLocalSync=true
06:21:45.534 [pool-34-thread-1] INFO c.c.c.c.r.s.RepoToRepoSync - sync: locally syncing 6d8ad33f-6098-48c2-959d-afb1df40d9ff ('target/repo/k6yl48gm-1qbkw-local')
06:21:45.542 [pool-34-thread-1] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 12.94%
06:21:45.560 [pool-34-thread-1] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 19.03%
06:21:45.583 [pool-34-thread-1] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 24.11%
06:21:45.598 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 1.00%
06:21:45.601 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 50.00%
06:21:45.602 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 58.17%
06:21:45.605 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 63.61%
06:21:45.610 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 74.50%
06:21:45.621 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 80.62%
06:21:45.627 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 90.83%
06:21:45.633 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 96.55%
06:21:45.642 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 100.00%
06:21:45.652 [main] INFO c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=3c2c6c9a-f364-453d-a016-a32f7edde656 remoteRepositoryId=6d8ad33f-6098-48c2-959d-afb1df40d9ff localRepositoryRevisionSynced=5 localRepositoryRevisionInProgress=8
06:21:45.667 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 29.95%
06:21:45.668 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path='/2/1'
06:21:45.685 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path='/2/1/5'
06:21:45.696 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path='/3'
06:21:45.704 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 35.11%
06:21:45.705 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 40.28%
06:21:45.705 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 45.45%
06:21:45.706 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/2/1/5/aaa'
06:21:45.715 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='09332377e8696a303705b514447107982c7ee397' to.sha1='<NoInstanceOf_NormalFileDto>'): /2/1/5/aaa
06:21:45.731 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=11, fromRepositoryId="FileInProgressMarker", toRepositoryId="11", path="3c2c6c9a-f364-453d-a016-a32f7edde656"] on repo=6d8ad33f-6098-48c2-959d-afb1df40d9ff
06:21:45.732 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/2/1/5/aaa'
06:21:45.732 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='09332377e8696a303705b514447107982c7ee397' toSha1='null'
06:21:45.732 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/2/1/5/aaa'
06:21:45.732 [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
06:21:45.739 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/2/1/5/aaa' offset=0
06:21:45.756 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 710502 bytes in 24 ms. path='/2/1/5/aaa'
06:21:45.762 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_7e240de74fb1ed08fa08d38063f6a6a91462a815_0 into destFile aaa
06:21:45.798 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=11, fromRepositoryId="FileInProgressMarker", toRepositoryId="11", path="3c2c6c9a-f364-453d-a016-a32f7edde656"] on repo=6d8ad33f-6098-48c2-959d-afb1df40d9ff
06:21:45.801 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/2/1/5/bbb'
06:21:45.812 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='7bdfa41d88b4a33e8654b257f4792d3d5ea79549' to.sha1='<NoInstanceOf_NormalFileDto>'): /2/1/5/bbb
06:21:45.825 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=12, fromRepositoryId="FileInProgressMarker", toRepositoryId="12", path="3c2c6c9a-f364-453d-a016-a32f7edde656"] on repo=6d8ad33f-6098-48c2-959d-afb1df40d9ff
06:21:45.825 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/2/1/5/bbb'
06:21:45.825 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='7bdfa41d88b4a33e8654b257f4792d3d5ea79549' toSha1='null'
06:21:45.825 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/2/1/5/bbb'
06:21:45.825 [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
06:21:45.825 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/2/1/5/bbb' offset=0
06:21:45.836 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 31372 bytes in 11 ms. path='/2/1/5/bbb'
06:21:45.842 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_5cb138284d431abd6a053a56625ec088bfb88912_0 into destFile bbb
06:21:45.852 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=12, fromRepositoryId="FileInProgressMarker", toRepositoryId="12", path="3c2c6c9a-f364-453d-a016-a32f7edde656"] on repo=6d8ad33f-6098-48c2-959d-afb1df40d9ff
06:21:45.855 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/3/e'
06:21:45.863 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='93a96aee8c6b88176c5efeab01954e1acb9c230a' to.sha1='<NoInstanceOf_NormalFileDto>'): /3/e
06:21:45.875 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=13, fromRepositoryId="FileInProgressMarker", toRepositoryId="13", path="3c2c6c9a-f364-453d-a016-a32f7edde656"] on repo=6d8ad33f-6098-48c2-959d-afb1df40d9ff
06:21:45.876 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/3/e'
06:21:45.876 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='93a96aee8c6b88176c5efeab01954e1acb9c230a' toSha1='null'
06:21:45.876 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/3/e'
06:21:45.876 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/3/e' offset=0
06:21:45.877 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/3/e' offset=0
06:21:45.881 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 42336 bytes in 5 ms. path='/3/e'
06:21:45.885 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_58e6b3a414a1e090dfc6029add0f3555ccba127f_0 into destFile e
06:21:45.895 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=13, fromRepositoryId="FileInProgressMarker", toRepositoryId="13", path="3c2c6c9a-f364-453d-a016-a32f7edde656"] on repo=6d8ad33f-6098-48c2-959d-afb1df40d9ff
06:21:45.902 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=3c2c6c9a-f364-453d-a016-a32f7edde656 remoteRepositoryId=6d8ad33f-6098-48c2-959d-afb1df40d9ff localRepositoryRevisionSynced=8
06:21:45.911 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=6d8ad33f-6098-48c2-959d-afb1df40d9ff remoteRepositoryId=3c2c6c9a-f364-453d-a016-a32f7edde656 transaction.localRevision=79 remoteFromLocalRevision=8
06:21:45.920 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncUp: fromID=6d8ad33f-6098-48c2-959d-afb1df40d9ff from='target/repo/k6yl48gm-1qbkw-local' toID=3c2c6c9a-f364-453d-a016-a32f7edde656 to='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.local/target/repo/k6yl48gn-asq0x-remote'
06:21:45.933 [main] INFO c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=6d8ad33f-6098-48c2-959d-afb1df40d9ff remoteRepositoryId=3c2c6c9a-f364-453d-a016-a32f7edde656 localRepositoryRevisionSynced=57 localRepositoryRevisionInProgress=79
06:21:45.935 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 52.24%
06:21:45.935 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 63.43%
06:21:45.935 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 74.63%
06:21:45.938 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=6d8ad33f-6098-48c2-959d-afb1df40d9ff remoteRepositoryId=3c2c6c9a-f364-453d-a016-a32f7edde656 localRepositoryRevisionSynced=79
06:21:45.941 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=3c2c6c9a-f364-453d-a016-a32f7edde656 remoteRepositoryId=6d8ad33f-6098-48c2-959d-afb1df40d9ff transaction.localRevision=11 remoteFromLocalRevision=79
06:21:45.947 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDown: fromID=3c2c6c9a-f364-453d-a016-a32f7edde656 from='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.local/target/repo/k6yl48gn-asq0x-remote' toID=6d8ad33f-6098-48c2-959d-afb1df40d9ff to='target/repo/k6yl48gm-1qbkw-local', fromRepoLocalSync=false
06:21:45.962 [main] INFO c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=3c2c6c9a-f364-453d-a016-a32f7edde656 remoteRepositoryId=6d8ad33f-6098-48c2-959d-afb1df40d9ff localRepositoryRevisionSynced=8 localRepositoryRevisionInProgress=11
06:21:45.966 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 88.31%
06:21:45.966 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 99.50%
06:21:45.973 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=3c2c6c9a-f364-453d-a016-a32f7edde656 remoteRepositoryId=6d8ad33f-6098-48c2-959d-afb1df40d9ff localRepositoryRevisionSynced=11
06:21:45.982 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=6d8ad33f-6098-48c2-959d-afb1df40d9ff remoteRepositoryId=3c2c6c9a-f364-453d-a016-a32f7edde656 transaction.localRevision=82 remoteFromLocalRevision=11
06:21:45.984 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 100.00%
06:21:45.984 [main] INFO c.c.c.l.LocalRepoManagerImpl - [13057759]close: Deferring shut down of real LocalRepoManager 20000 ms.
06:21:46.007 [main] INFO c.c.c.l.LocalRepoManagerImpl - [2dc612b7]close: Deferring shut down of real LocalRepoManager 20000 ms.
06:21:46.090 [main] INFO c.c.c.l.LocalRepoManagerImpl - [345aa893]<init>: localRoot='/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.local/target/repo/k6yl4go7-6ql3-local'
06:21:46.108 [main] INFO c.c.c.l.LocalRepoManagerImpl - [345aa893]initLockFile: Repository '/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.local/target/repo/k6yl4go7-6ql3-local' locked successfully.
06:21:47.453 [closeDeferredTimer-6045772c-1] INFO c.c.c.l.LocalRepoManagerImpl - [6045772c]_close: Shutting down real LocalRepoManager.
06:21:47.454 [closeDeferredTimer-403a5a9f-1] INFO c.c.c.l.LocalRepoManagerImpl - [403a5a9f]_close: Shutting down real LocalRepoManager.
06:21:50.998 [main] INFO c.c.c.l.LocalRepoManagerImpl - [345aa893]initPersistenceManagerFactory: repositoryId=58105796-0302-4c31-aff1-4c4b06749925
06:21:51.000 [main] INFO c.c.c.l.LocalRepoManagerImpl - [345aa893]initPersistenceManagerFactory: Started up PersistenceManagerFactory successfully in 2681 ms.
06:21:51.015 [main] INFO c.c.c.l.LocalRepoManagerImpl - [7ea7b27c]<init>: localRoot='/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.local/target/repo/k6yl4go7-32e07-remote'
06:21:51.020 [main] INFO c.c.c.l.LocalRepoManagerImpl - [7ea7b27c]initLockFile: Repository '/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.local/target/repo/k6yl4go7-32e07-remote' locked successfully.
06:21:55.416 [closeDeferredTimer-48930622-2] INFO c.c.c.l.LocalRepoManagerImpl - [48930622]_close: Shutting down real LocalRepoManager.
06:21:55.692 [closeDeferredTimer-25b1eae1-2] INFO c.c.c.l.LocalRepoManagerImpl - [25b1eae1]_close: Shutting down real LocalRepoManager.
06:21:57.043 [main] INFO c.c.c.l.LocalRepoManagerImpl - [7ea7b27c]initPersistenceManagerFactory: repositoryId=42f6f407-da76-4570-a4e0-fb5d0978033d
06:21:57.052 [main] INFO c.c.c.l.LocalRepoManagerImpl - [7ea7b27c]initPersistenceManagerFactory: Started up PersistenceManagerFactory successfully in 3346 ms.
06:21:57.146 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 1.00%
06:21:57.156 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 50.00%
06:21:57.173 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 58.00%
06:21:57.217 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 63.61%
06:21:57.231 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 74.34%
06:21:57.250 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 80.56%
06:21:57.258 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 90.67%
06:21:57.281 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 95.80%
06:21:57.303 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 100.00%
06:21:57.330 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - local repo: 58105796-0302-4c31-aff1-4c4b06749925
06:21:57.330 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - remote repo: 42f6f407-da76-4570-a4e0-fb5d0978033d
06:21:57.335 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 0.50%
06:21:57.336 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDown: fromID=42f6f407-da76-4570-a4e0-fb5d0978033d from='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.local/target/repo/k6yl4go7-32e07-remote' toID=58105796-0302-4c31-aff1-4c4b06749925 to='target/repo/k6yl4go7-6ql3-local', fromRepoLocalSync=true
06:21:57.336 [pool-35-thread-1] INFO c.c.c.c.r.s.RepoToRepoSync - sync: locally syncing 58105796-0302-4c31-aff1-4c4b06749925 ('target/repo/k6yl4go7-6ql3-local')
06:21:57.354 [pool-35-thread-1] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 12.94%
06:21:57.361 [pool-35-thread-1] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 25.12%
06:21:57.364 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 1.00%
06:21:57.364 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 50.00%
06:21:57.366 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 58.17%
06:21:57.374 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 63.61%
06:21:57.378 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 74.50%
06:21:57.384 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 80.62%
06:21:57.388 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 90.83%
06:21:57.392 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 95.94%
06:21:57.398 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 100.00%
06:21:57.426 [main] INFO c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=42f6f407-da76-4570-a4e0-fb5d0978033d remoteRepositoryId=58105796-0302-4c31-aff1-4c4b06749925 localRepositoryRevisionSynced=-1 localRepositoryRevisionInProgress=2
06:21:57.458 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path=''
06:21:57.479 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path='/1'
06:21:57.491 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 30.22%
06:21:57.492 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path='/2'
06:21:57.499 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path='/2/1'
06:21:57.508 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path='/3'
06:21:57.521 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 35.46%
06:21:57.522 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 40.70%
06:21:57.522 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/1/a'
06:21:57.531 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='525e04d90640497d37be8033ba443dc12e7f8bf5' to.sha1='<NoInstanceOf_NormalFileDto>'): /1/a
06:21:57.573 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=1, fromRepositoryId="FileInProgressMarker", toRepositoryId="1", path="42f6f407-da76-4570-a4e0-fb5d0978033d"] on repo=58105796-0302-4c31-aff1-4c4b06749925
06:21:57.575 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/1/a'
06:21:57.575 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='525e04d90640497d37be8033ba443dc12e7f8bf5' toSha1='null'
06:21:57.575 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/1/a'
06:21:57.575 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/1/a' offset=0
06:21:57.577 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/1/a' offset=0
06:21:57.586 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 192510 bytes in 11 ms. path='/1/a'
06:21:57.592 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_86f7e437faa5a7fce15d1ddcb9eaeaea377667b8_0 into destFile a
06:21:57.637 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=1, fromRepositoryId="FileInProgressMarker", toRepositoryId="1", path="42f6f407-da76-4570-a4e0-fb5d0978033d"] on repo=58105796-0302-4c31-aff1-4c4b06749925
06:21:57.646 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/1/b'
06:21:57.658 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='e8a634702e484feba8ad28cdacae8e9f4b11a0cb' to.sha1='<NoInstanceOf_NormalFileDto>'): /1/b
06:21:57.673 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=2, fromRepositoryId="FileInProgressMarker", toRepositoryId="2", path="42f6f407-da76-4570-a4e0-fb5d0978033d"] on repo=58105796-0302-4c31-aff1-4c4b06749925
06:21:57.675 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/1/b'
06:21:57.675 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='e8a634702e484feba8ad28cdacae8e9f4b11a0cb' toSha1='null'
06:21:57.675 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/1/b'
06:21:57.675 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/1/b' offset=0
06:21:57.675 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/1/b' offset=0
06:21:57.681 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 16626 bytes in 6 ms. path='/1/b'
06:21:57.685 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_e9d71f5ee7c92d6dc9e92ffdad17b8bd49418f98_0 into destFile b
06:21:57.696 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=2, fromRepositoryId="FileInProgressMarker", toRepositoryId="2", path="42f6f407-da76-4570-a4e0-fb5d0978033d"] on repo=58105796-0302-4c31-aff1-4c4b06749925
06:21:57.700 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/1/c'
06:21:57.711 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='51c3554f04354e84339bb25a740db2d83b05fd1b' to.sha1='<NoInstanceOf_NormalFileDto>'): /1/c
06:21:57.726 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=3, fromRepositoryId="FileInProgressMarker", toRepositoryId="3", path="42f6f407-da76-4570-a4e0-fb5d0978033d"] on repo=58105796-0302-4c31-aff1-4c4b06749925
06:21:57.728 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/1/c'
06:21:57.728 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='51c3554f04354e84339bb25a740db2d83b05fd1b' toSha1='null'
06:21:57.728 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/1/c'
06:21:57.728 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/1/c' offset=0
06:21:57.729 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/1/c' offset=0
06:21:57.734 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 4914 bytes in 6 ms. path='/1/c'
06:21:57.738 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_84a516841ba77a5b4648de2cd0dfcb30ea46dbb4_0 into destFile c
06:21:57.748 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=3, fromRepositoryId="FileInProgressMarker", toRepositoryId="3", path="42f6f407-da76-4570-a4e0-fb5d0978033d"] on repo=58105796-0302-4c31-aff1-4c4b06749925
06:21:57.751 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/2/1/a'
06:21:57.762 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='45aba128a43dfc3817247c02a1c444ecda97b197' to.sha1='<NoInstanceOf_NormalFileDto>'): /2/1/a
06:21:57.779 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=4, fromRepositoryId="FileInProgressMarker", toRepositoryId="4", path="42f6f407-da76-4570-a4e0-fb5d0978033d"] on repo=58105796-0302-4c31-aff1-4c4b06749925
06:21:57.781 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/2/1/a'
06:21:57.781 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='45aba128a43dfc3817247c02a1c444ecda97b197' toSha1='null'
06:21:57.781 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/2/1/a'
06:21:57.781 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/2/1/a' offset=0
06:21:57.782 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/2/1/a' offset=0
06:21:57.788 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 40635 bytes in 7 ms. path='/2/1/a'
06:21:57.794 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_86f7e437faa5a7fce15d1ddcb9eaeaea377667b8_0 into destFile a
06:21:57.806 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=4, fromRepositoryId="FileInProgressMarker", toRepositoryId="4", path="42f6f407-da76-4570-a4e0-fb5d0978033d"] on repo=58105796-0302-4c31-aff1-4c4b06749925
06:21:57.810 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/2/1/b'
06:21:57.820 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='49a01c8967a8d8671f19161f0990e8d6d4676927' to.sha1='<NoInstanceOf_NormalFileDto>'): /2/1/b
06:21:57.838 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=5, fromRepositoryId="FileInProgressMarker", toRepositoryId="5", path="42f6f407-da76-4570-a4e0-fb5d0978033d"] on repo=58105796-0302-4c31-aff1-4c4b06749925
06:21:57.839 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/2/1/b'
06:21:57.840 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='49a01c8967a8d8671f19161f0990e8d6d4676927' toSha1='null'
06:21:57.840 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/2/1/b'
06:21:57.840 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/2/1/b' offset=0
06:21:57.843 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/2/1/b' offset=0
06:21:57.853 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 254851 bytes in 13 ms. path='/2/1/b'
06:21:57.859 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_e9d71f5ee7c92d6dc9e92ffdad17b8bd49418f98_0 into destFile b
06:21:57.879 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=5, fromRepositoryId="FileInProgressMarker", toRepositoryId="5", path="42f6f407-da76-4570-a4e0-fb5d0978033d"] on repo=58105796-0302-4c31-aff1-4c4b06749925
06:21:57.883 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/2/a'
06:21:57.891 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='2f28849f82e792f6d5ed7e4397f2d315db704545' to.sha1='<NoInstanceOf_NormalFileDto>'): /2/a
06:21:57.904 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=6, fromRepositoryId="FileInProgressMarker", toRepositoryId="6", path="42f6f407-da76-4570-a4e0-fb5d0978033d"] on repo=58105796-0302-4c31-aff1-4c4b06749925
06:21:57.905 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/2/a'
06:21:57.905 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='2f28849f82e792f6d5ed7e4397f2d315db704545' toSha1='null'
06:21:57.905 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/2/a'
06:21:57.905 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/2/a' offset=0
06:21:57.906 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/2/a' offset=0
06:21:57.910 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 8649 bytes in 5 ms. path='/2/a'
06:21:57.914 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_86f7e437faa5a7fce15d1ddcb9eaeaea377667b8_0 into destFile a
06:21:57.923 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=6, fromRepositoryId="FileInProgressMarker", toRepositoryId="6", path="42f6f407-da76-4570-a4e0-fb5d0978033d"] on repo=58105796-0302-4c31-aff1-4c4b06749925
06:21:57.926 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/3/a'
06:21:57.934 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='8f8411267636a3da72f11ca4d220886b5ec9acc6' to.sha1='<NoInstanceOf_NormalFileDto>'): /3/a
06:21:57.946 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=7, fromRepositoryId="FileInProgressMarker", toRepositoryId="7", path="42f6f407-da76-4570-a4e0-fb5d0978033d"] on repo=58105796-0302-4c31-aff1-4c4b06749925
06:21:57.947 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/3/a'
06:21:57.947 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='8f8411267636a3da72f11ca4d220886b5ec9acc6' toSha1='null'
06:21:57.947 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/3/a'
06:21:57.947 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/3/a' offset=0
06:21:57.948 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/3/a' offset=0
06:21:57.952 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 7555 bytes in 5 ms. path='/3/a'
06:21:57.956 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_86f7e437faa5a7fce15d1ddcb9eaeaea377667b8_0 into destFile a
06:21:57.965 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=7, fromRepositoryId="FileInProgressMarker", toRepositoryId="7", path="42f6f407-da76-4570-a4e0-fb5d0978033d"] on repo=58105796-0302-4c31-aff1-4c4b06749925
06:21:57.969 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/3/b'
06:21:57.977 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='ec5b460da237e75f8d0896bd08da8c1494325e91' to.sha1='<NoInstanceOf_NormalFileDto>'): /3/b
06:21:57.989 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=8, fromRepositoryId="FileInProgressMarker", toRepositoryId="8", path="42f6f407-da76-4570-a4e0-fb5d0978033d"] on repo=58105796-0302-4c31-aff1-4c4b06749925
06:21:57.991 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/3/b'
06:21:57.991 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='ec5b460da237e75f8d0896bd08da8c1494325e91' toSha1='null'
06:21:57.991 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/3/b'
06:21:57.991 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/3/b' offset=0
06:21:57.991 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/3/b' offset=0
06:21:57.997 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 9090 bytes in 6 ms. path='/3/b'
06:21:58.001 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_e9d71f5ee7c92d6dc9e92ffdad17b8bd49418f98_0 into destFile b
06:21:58.013 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=8, fromRepositoryId="FileInProgressMarker", toRepositoryId="8", path="42f6f407-da76-4570-a4e0-fb5d0978033d"] on repo=58105796-0302-4c31-aff1-4c4b06749925
06:21:58.016 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/3/c'
06:21:58.023 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='4bc376574c493d0043f0850f6a6e97852587966f' to.sha1='<NoInstanceOf_NormalFileDto>'): /3/c
06:21:58.034 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=9, fromRepositoryId="FileInProgressMarker", toRepositoryId="9", path="42f6f407-da76-4570-a4e0-fb5d0978033d"] on repo=58105796-0302-4c31-aff1-4c4b06749925
06:21:58.036 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/3/c'
06:21:58.036 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='4bc376574c493d0043f0850f6a6e97852587966f' toSha1='null'
06:21:58.036 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/3/c'
06:21:58.036 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/3/c' offset=0
06:21:58.042 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/3/c' offset=0
06:21:58.054 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 519912 bytes in 18 ms. path='/3/c'
06:21:58.058 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_84a516841ba77a5b4648de2cd0dfcb30ea46dbb4_0 into destFile c
06:21:58.087 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=9, fromRepositoryId="FileInProgressMarker", toRepositoryId="9", path="42f6f407-da76-4570-a4e0-fb5d0978033d"] on repo=58105796-0302-4c31-aff1-4c4b06749925
06:21:58.091 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/3/d'
06:21:58.102 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='354881ad115b67197c969202db86773f728cb5ba' to.sha1='<NoInstanceOf_NormalFileDto>'): /3/d
06:21:58.116 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=10, fromRepositoryId="FileInProgressMarker", toRepositoryId="10", path="42f6f407-da76-4570-a4e0-fb5d0978033d"] on repo=58105796-0302-4c31-aff1-4c4b06749925
06:21:58.118 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/3/d'
06:21:58.118 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='354881ad115b67197c969202db86773f728cb5ba' toSha1='null'
06:21:58.118 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/3/d'
06:21:58.118 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/3/d' offset=0
06:21:58.120 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/3/d' offset=0
06:21:58.131 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 146247 bytes in 13 ms. path='/3/d'
06:21:58.135 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_3c363836cf4e16666669a25da280a1865c2d2874_0 into destFile d
06:21:58.150 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=10, fromRepositoryId="FileInProgressMarker", toRepositoryId="10", path="42f6f407-da76-4570-a4e0-fb5d0978033d"] on repo=58105796-0302-4c31-aff1-4c4b06749925
06:21:58.153 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 49.27%
06:21:58.171 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=42f6f407-da76-4570-a4e0-fb5d0978033d remoteRepositoryId=58105796-0302-4c31-aff1-4c4b06749925 localRepositoryRevisionSynced=2
06:21:58.186 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=58105796-0302-4c31-aff1-4c4b06749925 remoteRepositoryId=42f6f407-da76-4570-a4e0-fb5d0978033d transaction.localRevision=57 remoteFromLocalRevision=2
06:21:58.193 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncUp: fromID=58105796-0302-4c31-aff1-4c4b06749925 from='target/repo/k6yl4go7-6ql3-local' toID=42f6f407-da76-4570-a4e0-fb5d0978033d to='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.local/target/repo/k6yl4go7-32e07-remote'
06:21:58.212 [main] INFO c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=58105796-0302-4c31-aff1-4c4b06749925 remoteRepositoryId=42f6f407-da76-4570-a4e0-fb5d0978033d localRepositoryRevisionSynced=-1 localRepositoryRevisionInProgress=57
06:21:58.224 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 63.43%
06:21:58.225 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 74.63%
06:21:58.236 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=58105796-0302-4c31-aff1-4c4b06749925 remoteRepositoryId=42f6f407-da76-4570-a4e0-fb5d0978033d localRepositoryRevisionSynced=57
06:21:58.244 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=42f6f407-da76-4570-a4e0-fb5d0978033d remoteRepositoryId=58105796-0302-4c31-aff1-4c4b06749925 transaction.localRevision=5 remoteFromLocalRevision=57
06:21:58.250 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDown: fromID=42f6f407-da76-4570-a4e0-fb5d0978033d from='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.local/target/repo/k6yl4go7-32e07-remote' toID=58105796-0302-4c31-aff1-4c4b06749925 to='target/repo/k6yl4go7-6ql3-local', fromRepoLocalSync=false
06:21:58.268 [main] INFO c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=42f6f407-da76-4570-a4e0-fb5d0978033d remoteRepositoryId=58105796-0302-4c31-aff1-4c4b06749925 localRepositoryRevisionSynced=2 localRepositoryRevisionInProgress=5
06:21:58.270 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 88.31%
06:21:58.270 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 99.50%
06:21:58.273 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=42f6f407-da76-4570-a4e0-fb5d0978033d remoteRepositoryId=58105796-0302-4c31-aff1-4c4b06749925 localRepositoryRevisionSynced=5
06:21:58.283 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=58105796-0302-4c31-aff1-4c4b06749925 remoteRepositoryId=42f6f407-da76-4570-a4e0-fb5d0978033d transaction.localRevision=60 remoteFromLocalRevision=5
06:21:58.286 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 100.00%
06:21:58.303 [main] INFO c.c.c.l.LocalRepoManagerImpl - [345aa893]getCloseDeferredMillis: closeDeferredMillis=20000
06:21:58.303 [main] INFO c.c.c.l.LocalRepoManagerImpl - [345aa893]close: Deferring shut down of real LocalRepoManager 20000 ms.
06:21:58.304 [main] INFO c.c.c.l.LocalRepoManagerImpl - [7ea7b27c]getCloseDeferredMillis: closeDeferredMillis=20000
06:21:58.304 [main] INFO c.c.c.l.LocalRepoManagerImpl - [7ea7b27c]close: Deferring shut down of real LocalRepoManager 20000 ms.
06:21:58.330 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 1.00%
06:21:58.331 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 50.00%
06:21:58.332 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 58.17%
06:21:58.336 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 63.61%
06:21:58.339 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 74.50%
06:21:58.385 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 80.62%
06:21:58.392 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 90.83%
06:21:58.396 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 95.94%
06:21:58.408 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 100.00%
06:21:58.427 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 0.50%
06:21:58.428 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDown: fromID=42f6f407-da76-4570-a4e0-fb5d0978033d from='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.local/target/repo/k6yl4go7-32e07-remote' toID=58105796-0302-4c31-aff1-4c4b06749925 to='target/repo/k6yl4go7-6ql3-local', fromRepoLocalSync=true
06:21:58.429 [pool-36-thread-1] INFO c.c.c.c.r.s.RepoToRepoSync - sync: locally syncing 58105796-0302-4c31-aff1-4c4b06749925 ('target/repo/k6yl4go7-6ql3-local')
06:21:58.439 [pool-36-thread-1] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 12.94%
06:21:58.457 [pool-36-thread-1] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 19.03%
06:21:58.478 [pool-36-thread-1] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 24.11%
06:21:58.492 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 1.00%
06:21:58.494 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 50.00%
06:21:58.497 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 58.17%
06:21:58.502 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 63.61%
06:21:58.506 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 74.50%
06:21:58.511 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 80.62%
06:21:58.514 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 90.83%
06:21:58.518 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 95.94%
06:21:58.524 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 100.00%
06:21:58.534 [main] INFO c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=42f6f407-da76-4570-a4e0-fb5d0978033d remoteRepositoryId=58105796-0302-4c31-aff1-4c4b06749925 localRepositoryRevisionSynced=5 localRepositoryRevisionInProgress=8
06:21:58.552 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 29.23%
06:21:58.553 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path='/2/1'
06:21:58.600 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 34.83%
06:21:58.604 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncModifications: Deleting '/2/1/a'
06:21:58.645 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 40.42%
06:21:58.645 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 46.02%
06:21:58.663 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=42f6f407-da76-4570-a4e0-fb5d0978033d remoteRepositoryId=58105796-0302-4c31-aff1-4c4b06749925 localRepositoryRevisionSynced=8
06:21:58.673 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=58105796-0302-4c31-aff1-4c4b06749925 remoteRepositoryId=42f6f407-da76-4570-a4e0-fb5d0978033d transaction.localRevision=63 remoteFromLocalRevision=8
06:21:58.680 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncUp: fromID=58105796-0302-4c31-aff1-4c4b06749925 from='target/repo/k6yl4go7-6ql3-local' toID=42f6f407-da76-4570-a4e0-fb5d0978033d to='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.local/target/repo/k6yl4go7-32e07-remote'
06:21:58.691 [main] INFO c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=58105796-0302-4c31-aff1-4c4b06749925 remoteRepositoryId=42f6f407-da76-4570-a4e0-fb5d0978033d localRepositoryRevisionSynced=57 localRepositoryRevisionInProgress=63
06:21:58.700 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 52.24%
06:21:58.700 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 59.70%
06:21:58.700 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncModifications: Deleting '/2/1/a'
06:21:58.706 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 67.16%
06:21:58.706 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 74.63%
06:21:58.723 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=58105796-0302-4c31-aff1-4c4b06749925 remoteRepositoryId=42f6f407-da76-4570-a4e0-fb5d0978033d localRepositoryRevisionSynced=63
06:21:58.726 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=42f6f407-da76-4570-a4e0-fb5d0978033d remoteRepositoryId=58105796-0302-4c31-aff1-4c4b06749925 transaction.localRevision=11 remoteFromLocalRevision=63
06:21:58.733 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDown: fromID=42f6f407-da76-4570-a4e0-fb5d0978033d from='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.local/target/repo/k6yl4go7-32e07-remote' toID=58105796-0302-4c31-aff1-4c4b06749925 to='target/repo/k6yl4go7-6ql3-local', fromRepoLocalSync=false
06:21:58.746 [main] INFO c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=42f6f407-da76-4570-a4e0-fb5d0978033d remoteRepositoryId=58105796-0302-4c31-aff1-4c4b06749925 localRepositoryRevisionSynced=8 localRepositoryRevisionInProgress=11
06:21:58.749 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 88.31%
06:21:58.749 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 99.50%
06:21:58.754 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=42f6f407-da76-4570-a4e0-fb5d0978033d remoteRepositoryId=58105796-0302-4c31-aff1-4c4b06749925 localRepositoryRevisionSynced=11
06:21:58.765 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=58105796-0302-4c31-aff1-4c4b06749925 remoteRepositoryId=42f6f407-da76-4570-a4e0-fb5d0978033d transaction.localRevision=66 remoteFromLocalRevision=11
06:21:58.768 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 100.00%
06:21:58.768 [main] INFO c.c.c.l.LocalRepoManagerImpl - [345aa893]close: Deferring shut down of real LocalRepoManager 20000 ms.
06:21:58.785 [main] INFO c.c.c.l.LocalRepoManagerImpl - [7ea7b27c]close: Deferring shut down of real LocalRepoManager 20000 ms.
06:21:58.821 [main] INFO c.c.c.l.LocalRepoManagerImpl - [2979e8f9]<init>: localRoot='/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.local/target/repo/k6yl4qhv-ophft-local'
06:21:58.826 [main] INFO c.c.c.l.LocalRepoManagerImpl - [2979e8f9]initLockFile: Repository '/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.local/target/repo/k6yl4qhv-ophft-local' locked successfully.
06:22:02.852 [main] INFO c.c.c.l.LocalRepoManagerImpl - [2979e8f9]initPersistenceManagerFactory: repositoryId=85a9ab44-4d0a-459e-99fd-cd6604c551e0
06:22:02.858 [main] INFO c.c.c.l.LocalRepoManagerImpl - [2979e8f9]initPersistenceManagerFactory: Started up PersistenceManagerFactory successfully in 2376 ms.
06:22:02.882 [main] INFO c.c.c.l.LocalRepoManagerImpl - [4044b44f]<init>: localRoot='/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.local/target/repo/k6yl4qhw-izmr0-remote'
06:22:02.888 [main] INFO c.c.c.l.LocalRepoManagerImpl - [4044b44f]initLockFile: Repository '/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.local/target/repo/k6yl4qhw-izmr0-remote' locked successfully.
06:22:05.985 [closeDeferredTimer-13057759-2] INFO c.c.c.l.LocalRepoManagerImpl - [13057759]_close: Shutting down real LocalRepoManager.
06:22:06.302 [closeDeferredTimer-2dc612b7-2] INFO c.c.c.l.LocalRepoManagerImpl - [2dc612b7]_close: Shutting down real LocalRepoManager.
06:22:08.550 [main] INFO c.c.c.l.LocalRepoManagerImpl - [4044b44f]initPersistenceManagerFactory: repositoryId=3f0b3639-8e61-40c4-9ea3-6d5e722f43a1
06:22:08.557 [main] INFO c.c.c.l.LocalRepoManagerImpl - [4044b44f]initPersistenceManagerFactory: Started up PersistenceManagerFactory successfully in 3794 ms.
06:22:08.770 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 1.00%
06:22:08.777 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 50.00%
06:22:08.788 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 58.00%
06:22:08.838 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 63.61%
06:22:08.846 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 74.34%
06:22:08.868 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 79.62%
06:22:08.878 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 90.67%
06:22:08.918 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 95.68%
06:22:08.944 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 100.00%
06:22:08.965 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - local repo: 85a9ab44-4d0a-459e-99fd-cd6604c551e0
06:22:08.965 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - remote repo: 3f0b3639-8e61-40c4-9ea3-6d5e722f43a1
06:22:08.969 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 0.50%
06:22:08.975 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDown: fromID=3f0b3639-8e61-40c4-9ea3-6d5e722f43a1 from='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.local/target/repo/k6yl4qhw-izmr0-remote/2' toID=85a9ab44-4d0a-459e-99fd-cd6604c551e0 to='target/repo/k6yl4qhv-ophft-local', fromRepoLocalSync=true
06:22:08.976 [pool-37-thread-1] INFO c.c.c.c.r.s.RepoToRepoSync - sync: locally syncing 85a9ab44-4d0a-459e-99fd-cd6604c551e0 ('target/repo/k6yl4qhv-ophft-local')
06:22:09.001 [pool-37-thread-1] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 12.94%
06:22:09.007 [pool-37-thread-1] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 25.12%
06:22:09.008 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 1.00%
06:22:09.010 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 50.00%
06:22:09.012 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 58.17%
06:22:09.017 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 63.61%
06:22:09.021 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 74.50%
06:22:09.033 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 80.62%
06:22:09.036 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 90.83%
06:22:09.040 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 95.94%
06:22:09.047 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 100.00%
06:22:09.077 [main] INFO c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=3f0b3639-8e61-40c4-9ea3-6d5e722f43a1 remoteRepositoryId=85a9ab44-4d0a-459e-99fd-cd6604c551e0 localRepositoryRevisionSynced=-1 localRepositoryRevisionInProgress=2
06:22:09.119 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path=''
06:22:09.140 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path='/1'
06:22:09.151 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 31.31%
06:22:09.156 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 36.58%
06:22:09.156 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 41.85%
06:22:09.156 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/1/a'
06:22:09.164 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='d0d8aba2a0aec413a317518ecf074ddc6ae51e66' to.sha1='<NoInstanceOf_NormalFileDto>'): /1/a
06:22:09.197 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=1, fromRepositoryId="FileInProgressMarker", toRepositoryId="1", path="3f0b3639-8e61-40c4-9ea3-6d5e722f43a1"] on repo=85a9ab44-4d0a-459e-99fd-cd6604c551e0
06:22:09.198 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/1/a'
06:22:09.198 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='d0d8aba2a0aec413a317518ecf074ddc6ae51e66' toSha1='null'
06:22:09.198 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/1/a'
06:22:09.198 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/1/a' offset=0
06:22:09.200 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/1/a' offset=0
06:22:09.206 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 144928 bytes in 8 ms. path='/1/a'
06:22:09.210 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_86f7e437faa5a7fce15d1ddcb9eaeaea377667b8_0 into destFile a
06:22:09.243 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=1, fromRepositoryId="FileInProgressMarker", toRepositoryId="1", path="3f0b3639-8e61-40c4-9ea3-6d5e722f43a1"] on repo=85a9ab44-4d0a-459e-99fd-cd6604c551e0
06:22:09.249 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/1/b'
06:22:09.257 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='559863a53fed0543a16925f019202bb8d35d9c62' to.sha1='<NoInstanceOf_NormalFileDto>'): /1/b
06:22:09.267 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=2, fromRepositoryId="FileInProgressMarker", toRepositoryId="2", path="3f0b3639-8e61-40c4-9ea3-6d5e722f43a1"] on repo=85a9ab44-4d0a-459e-99fd-cd6604c551e0
06:22:09.269 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/1/b'
06:22:09.269 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='559863a53fed0543a16925f019202bb8d35d9c62' toSha1='null'
06:22:09.269 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/1/b'
06:22:09.269 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/1/b' offset=0
06:22:09.271 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/1/b' offset=0
06:22:09.277 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 152055 bytes in 8 ms. path='/1/b'
06:22:09.280 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_e9d71f5ee7c92d6dc9e92ffdad17b8bd49418f98_0 into destFile b
06:22:09.294 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=2, fromRepositoryId="FileInProgressMarker", toRepositoryId="2", path="3f0b3639-8e61-40c4-9ea3-6d5e722f43a1"] on repo=85a9ab44-4d0a-459e-99fd-cd6604c551e0
06:22:09.297 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/a'
06:22:09.303 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='ecc913c7ab77159e324bbf5e72e160d86e768640' to.sha1='<NoInstanceOf_NormalFileDto>'): /a
06:22:09.314 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=3, fromRepositoryId="FileInProgressMarker", toRepositoryId="3", path="3f0b3639-8e61-40c4-9ea3-6d5e722f43a1"] on repo=85a9ab44-4d0a-459e-99fd-cd6604c551e0
06:22:09.316 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/a'
06:22:09.316 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='ecc913c7ab77159e324bbf5e72e160d86e768640' toSha1='null'
06:22:09.316 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/a'
06:22:09.316 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/a' offset=0
06:22:09.317 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/a' offset=0
06:22:09.322 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 125080 bytes in 6 ms. path='/a'
06:22:09.325 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_86f7e437faa5a7fce15d1ddcb9eaeaea377667b8_0 into destFile a
06:22:09.339 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=3, fromRepositoryId="FileInProgressMarker", toRepositoryId="3", path="3f0b3639-8e61-40c4-9ea3-6d5e722f43a1"] on repo=85a9ab44-4d0a-459e-99fd-cd6604c551e0
06:22:09.342 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 48.43%
06:22:09.355 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=3f0b3639-8e61-40c4-9ea3-6d5e722f43a1 remoteRepositoryId=85a9ab44-4d0a-459e-99fd-cd6604c551e0 localRepositoryRevisionSynced=2
06:22:09.370 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=85a9ab44-4d0a-459e-99fd-cd6604c551e0 remoteRepositoryId=3f0b3639-8e61-40c4-9ea3-6d5e722f43a1 transaction.localRevision=19 remoteFromLocalRevision=2
06:22:09.375 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncUp: fromID=85a9ab44-4d0a-459e-99fd-cd6604c551e0 from='target/repo/k6yl4qhv-ophft-local' toID=3f0b3639-8e61-40c4-9ea3-6d5e722f43a1 to='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.local/target/repo/k6yl4qhw-izmr0-remote/2'
06:22:09.392 [main] INFO c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=85a9ab44-4d0a-459e-99fd-cd6604c551e0 remoteRepositoryId=3f0b3639-8e61-40c4-9ea3-6d5e722f43a1 localRepositoryRevisionSynced=-1 localRepositoryRevisionInProgress=19
06:22:09.401 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 63.43%
06:22:09.401 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 74.63%
06:22:09.411 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=85a9ab44-4d0a-459e-99fd-cd6604c551e0 remoteRepositoryId=3f0b3639-8e61-40c4-9ea3-6d5e722f43a1 localRepositoryRevisionSynced=19
06:22:09.419 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=3f0b3639-8e61-40c4-9ea3-6d5e722f43a1 remoteRepositoryId=85a9ab44-4d0a-459e-99fd-cd6604c551e0 transaction.localRevision=5 remoteFromLocalRevision=19
06:22:09.425 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDown: fromID=3f0b3639-8e61-40c4-9ea3-6d5e722f43a1 from='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.local/target/repo/k6yl4qhw-izmr0-remote/2' toID=85a9ab44-4d0a-459e-99fd-cd6604c551e0 to='target/repo/k6yl4qhv-ophft-local', fromRepoLocalSync=false
06:22:09.441 [main] INFO c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=3f0b3639-8e61-40c4-9ea3-6d5e722f43a1 remoteRepositoryId=85a9ab44-4d0a-459e-99fd-cd6604c551e0 localRepositoryRevisionSynced=2 localRepositoryRevisionInProgress=5
06:22:09.445 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 88.31%
06:22:09.446 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 99.50%
06:22:09.451 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=3f0b3639-8e61-40c4-9ea3-6d5e722f43a1 remoteRepositoryId=85a9ab44-4d0a-459e-99fd-cd6604c551e0 localRepositoryRevisionSynced=5
06:22:09.460 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=85a9ab44-4d0a-459e-99fd-cd6604c551e0 remoteRepositoryId=3f0b3639-8e61-40c4-9ea3-6d5e722f43a1 transaction.localRevision=22 remoteFromLocalRevision=5
06:22:09.461 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 100.00%
06:22:09.477 [main] INFO c.c.c.l.LocalRepoManagerImpl - [2979e8f9]getCloseDeferredMillis: closeDeferredMillis=20000
06:22:09.477 [main] INFO c.c.c.l.LocalRepoManagerImpl - [2979e8f9]close: Deferring shut down of real LocalRepoManager 20000 ms.
06:22:09.477 [main] INFO c.c.c.l.LocalRepoManagerImpl - [4044b44f]getCloseDeferredMillis: closeDeferredMillis=20000
06:22:09.477 [main] INFO c.c.c.l.LocalRepoManagerImpl - [4044b44f]close: Deferring shut down of real LocalRepoManager 20000 ms.
06:22:09.490 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 1.00%
06:22:09.491 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 50.00%
06:22:09.492 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 58.17%
06:22:09.495 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 63.61%
06:22:09.499 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 74.50%
06:22:09.544 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 80.62%
06:22:09.547 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 90.83%
06:22:09.551 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 95.94%
06:22:09.564 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 100.00%
06:22:09.584 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 0.50%
06:22:09.585 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDown: fromID=3f0b3639-8e61-40c4-9ea3-6d5e722f43a1 from='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.local/target/repo/k6yl4qhw-izmr0-remote/2' toID=85a9ab44-4d0a-459e-99fd-cd6604c551e0 to='target/repo/k6yl4qhv-ophft-local', fromRepoLocalSync=true
06:22:09.585 [pool-38-thread-1] INFO c.c.c.c.r.s.RepoToRepoSync - sync: locally syncing 85a9ab44-4d0a-459e-99fd-cd6604c551e0 ('target/repo/k6yl4qhv-ophft-local')
06:22:09.588 [pool-38-thread-1] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 12.94%
06:22:09.594 [pool-38-thread-1] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 18.27%
06:22:09.601 [pool-38-thread-1] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 25.12%
06:22:09.609 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 1.00%
06:22:09.611 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 50.00%
06:22:09.614 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 58.17%
06:22:09.617 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 63.61%
06:22:09.625 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 74.50%
06:22:09.630 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 80.62%
06:22:09.633 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 90.83%
06:22:09.637 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 95.94%
06:22:09.644 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 100.00%
06:22:09.654 [main] INFO c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=3f0b3639-8e61-40c4-9ea3-6d5e722f43a1 remoteRepositoryId=85a9ab44-4d0a-459e-99fd-cd6604c551e0 localRepositoryRevisionSynced=5 localRepositoryRevisionInProgress=8
06:22:09.674 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 32.34%
06:22:09.674 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path='/1'
06:22:09.688 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncModifications: Deleting '/1/a'
06:22:09.720 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 39.80%
06:22:09.720 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 47.26%
06:22:09.737 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=3f0b3639-8e61-40c4-9ea3-6d5e722f43a1 remoteRepositoryId=85a9ab44-4d0a-459e-99fd-cd6604c551e0 localRepositoryRevisionSynced=8
06:22:09.746 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=85a9ab44-4d0a-459e-99fd-cd6604c551e0 remoteRepositoryId=3f0b3639-8e61-40c4-9ea3-6d5e722f43a1 transaction.localRevision=25 remoteFromLocalRevision=8
06:22:09.756 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncUp: fromID=85a9ab44-4d0a-459e-99fd-cd6604c551e0 from='target/repo/k6yl4qhv-ophft-local' toID=3f0b3639-8e61-40c4-9ea3-6d5e722f43a1 to='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.local/target/repo/k6yl4qhw-izmr0-remote/2'
06:22:09.767 [main] INFO c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=85a9ab44-4d0a-459e-99fd-cd6604c551e0 remoteRepositoryId=3f0b3639-8e61-40c4-9ea3-6d5e722f43a1 localRepositoryRevisionSynced=19 localRepositoryRevisionInProgress=25
06:22:09.776 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 59.70%
06:22:09.776 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncModifications: Deleting '/1/a'
06:22:09.781 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 67.16%
06:22:09.781 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 74.63%
06:22:09.797 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=85a9ab44-4d0a-459e-99fd-cd6604c551e0 remoteRepositoryId=3f0b3639-8e61-40c4-9ea3-6d5e722f43a1 localRepositoryRevisionSynced=25
06:22:09.801 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=3f0b3639-8e61-40c4-9ea3-6d5e722f43a1 remoteRepositoryId=85a9ab44-4d0a-459e-99fd-cd6604c551e0 transaction.localRevision=11 remoteFromLocalRevision=25
06:22:09.808 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDown: fromID=3f0b3639-8e61-40c4-9ea3-6d5e722f43a1 from='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.local/target/repo/k6yl4qhw-izmr0-remote/2' toID=85a9ab44-4d0a-459e-99fd-cd6604c551e0 to='target/repo/k6yl4qhv-ophft-local', fromRepoLocalSync=false
06:22:09.822 [main] INFO c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=3f0b3639-8e61-40c4-9ea3-6d5e722f43a1 remoteRepositoryId=85a9ab44-4d0a-459e-99fd-cd6604c551e0 localRepositoryRevisionSynced=8 localRepositoryRevisionInProgress=11
06:22:09.831 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 88.31%
06:22:09.831 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 99.50%
06:22:09.834 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=3f0b3639-8e61-40c4-9ea3-6d5e722f43a1 remoteRepositoryId=85a9ab44-4d0a-459e-99fd-cd6604c551e0 localRepositoryRevisionSynced=11
06:22:09.841 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=85a9ab44-4d0a-459e-99fd-cd6604c551e0 remoteRepositoryId=3f0b3639-8e61-40c4-9ea3-6d5e722f43a1 transaction.localRevision=28 remoteFromLocalRevision=11
06:22:09.844 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 100.00%
06:22:09.844 [main] INFO c.c.c.l.LocalRepoManagerImpl - [2979e8f9]close: Deferring shut down of real LocalRepoManager 20000 ms.
06:22:09.861 [main] INFO c.c.c.l.LocalRepoManagerImpl - [4044b44f]close: Deferring shut down of real LocalRepoManager 20000 ms.
06:22:09.871 [main] INFO c.c.c.l.LocalRepoManagerImpl - [1728a1d2]<init>: localRoot='/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.local/target/repo/k6yl4z0u-gzv1f-local'
06:22:09.877 [main] INFO c.c.c.l.LocalRepoManagerImpl - [1728a1d2]initLockFile: Repository '/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.local/target/repo/k6yl4z0u-gzv1f-local' locked successfully.
06:22:12.910 [main] INFO c.c.c.l.LocalRepoManagerImpl - [1728a1d2]initPersistenceManagerFactory: repositoryId=30958838-5185-4aa3-b571-443e1392c1ec
06:22:12.915 [main] INFO c.c.c.l.LocalRepoManagerImpl - [1728a1d2]initPersistenceManagerFactory: Started up PersistenceManagerFactory successfully in 1777 ms.
06:22:12.936 [main] INFO c.c.c.l.LocalRepoManagerImpl - [bc33894]<init>: localRoot='/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.local/target/repo/k6yl4z0u-kfl7r-remote'
06:22:12.943 [main] INFO c.c.c.l.LocalRepoManagerImpl - [bc33894]initLockFile: Repository '/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.local/target/repo/k6yl4z0u-kfl7r-remote' locked successfully.
06:22:16.576 [main] INFO c.c.c.l.LocalRepoManagerImpl - [bc33894]initPersistenceManagerFactory: repositoryId=7ba843bf-30b8-4703-a6fa-8722063de7bb
06:22:16.578 [main] INFO c.c.c.l.LocalRepoManagerImpl - [bc33894]initPersistenceManagerFactory: Started up PersistenceManagerFactory successfully in 1933 ms.
06:22:16.663 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 1.00%
06:22:16.668 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 50.00%
06:22:16.678 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 58.00%
06:22:16.721 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 63.61%
06:22:16.743 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 74.34%
06:22:16.772 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 79.44%
06:22:16.789 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 90.67%
06:22:16.811 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 95.91%
06:22:16.837 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 100.00%
06:22:16.858 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - local repo: 30958838-5185-4aa3-b571-443e1392c1ec
06:22:16.858 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - remote repo: 7ba843bf-30b8-4703-a6fa-8722063de7bb
06:22:16.861 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 0.50%
06:22:16.862 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDown: fromID=7ba843bf-30b8-4703-a6fa-8722063de7bb from='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.local/target/repo/k6yl4z0u-kfl7r-remote' toID=30958838-5185-4aa3-b571-443e1392c1ec to='target/repo/k6yl4z0u-gzv1f-local', fromRepoLocalSync=true
06:22:16.863 [pool-39-thread-1] INFO c.c.c.c.r.s.RepoToRepoSync - sync: locally syncing 30958838-5185-4aa3-b571-443e1392c1ec ('target/repo/k6yl4z0u-gzv1f-local')
06:22:16.898 [pool-39-thread-1] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 12.94%
06:22:16.908 [pool-39-thread-1] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 25.12%
06:22:16.912 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 1.00%
06:22:16.913 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 50.00%
06:22:16.916 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 58.17%
06:22:16.923 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 63.61%
06:22:16.926 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 74.50%
06:22:16.931 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 80.62%
06:22:16.934 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 90.83%
06:22:16.938 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 95.94%
06:22:16.945 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 100.00%
06:22:16.962 [main] INFO c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=7ba843bf-30b8-4703-a6fa-8722063de7bb remoteRepositoryId=30958838-5185-4aa3-b571-443e1392c1ec localRepositoryRevisionSynced=-1 localRepositoryRevisionInProgress=2
06:22:16.993 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path=''
06:22:17.016 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path='/1'
06:22:17.031 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 30.22%
06:22:17.031 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path='/2'
06:22:17.039 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path='/2/1'
06:22:17.049 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path='/3'
06:22:17.063 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 35.46%
06:22:17.063 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 40.70%
06:22:17.063 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/1/a'
06:22:17.071 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='af8a853e0a33289989cf34a2c1cbe880f1aa8f2a' to.sha1='<NoInstanceOf_NormalFileDto>'): /1/a
06:22:17.104 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=1, fromRepositoryId="FileInProgressMarker", toRepositoryId="1", path="7ba843bf-30b8-4703-a6fa-8722063de7bb"] on repo=30958838-5185-4aa3-b571-443e1392c1ec
06:22:17.107 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/1/a'
06:22:17.107 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='af8a853e0a33289989cf34a2c1cbe880f1aa8f2a' toSha1='null'
06:22:17.107 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/1/a'
06:22:17.107 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/1/a' offset=0
06:22:17.116 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/1/a' offset=0
06:22:17.126 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 399000 bytes in 19 ms. path='/1/a'
06:22:17.130 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_86f7e437faa5a7fce15d1ddcb9eaeaea377667b8_0 into destFile a
06:22:17.175 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=1, fromRepositoryId="FileInProgressMarker", toRepositoryId="1", path="7ba843bf-30b8-4703-a6fa-8722063de7bb"] on repo=30958838-5185-4aa3-b571-443e1392c1ec
06:22:17.189 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/1/b'
06:22:17.202 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='ea50d57c2c98e619aed0bb660ab3c6d2cdd280bb' to.sha1='<NoInstanceOf_NormalFileDto>'): /1/b
06:22:17.220 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=2, fromRepositoryId="FileInProgressMarker", toRepositoryId="2", path="7ba843bf-30b8-4703-a6fa-8722063de7bb"] on repo=30958838-5185-4aa3-b571-443e1392c1ec
06:22:17.222 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/1/b'
06:22:17.222 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='ea50d57c2c98e619aed0bb660ab3c6d2cdd280bb' toSha1='null'
06:22:17.222 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/1/b'
06:22:17.222 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/1/b' offset=0
06:22:17.229 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/1/b' offset=0
06:22:17.241 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 455058 bytes in 19 ms. path='/1/b'
06:22:17.245 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_e9d71f5ee7c92d6dc9e92ffdad17b8bd49418f98_0 into destFile b
06:22:17.270 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=2, fromRepositoryId="FileInProgressMarker", toRepositoryId="2", path="7ba843bf-30b8-4703-a6fa-8722063de7bb"] on repo=30958838-5185-4aa3-b571-443e1392c1ec
06:22:17.276 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/1/c'
06:22:17.283 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='13fc5ce523fc322937c4fa2358e9b85a61447356' to.sha1='<NoInstanceOf_NormalFileDto>'): /1/c
06:22:17.294 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=3, fromRepositoryId="FileInProgressMarker", toRepositoryId="3", path="7ba843bf-30b8-4703-a6fa-8722063de7bb"] on repo=30958838-5185-4aa3-b571-443e1392c1ec
06:22:17.295 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/1/c'
06:22:17.295 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='13fc5ce523fc322937c4fa2358e9b85a61447356' toSha1='null'
06:22:17.295 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/1/c'
06:22:17.295 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/1/c' offset=0
06:22:17.296 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/1/c' offset=0
06:22:17.301 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 63200 bytes in 6 ms. path='/1/c'
06:22:17.304 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_84a516841ba77a5b4648de2cd0dfcb30ea46dbb4_0 into destFile c
06:22:17.315 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=3, fromRepositoryId="FileInProgressMarker", toRepositoryId="3", path="7ba843bf-30b8-4703-a6fa-8722063de7bb"] on repo=30958838-5185-4aa3-b571-443e1392c1ec
06:22:17.318 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/2/1/a'
06:22:17.325 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='7f14d1efd0debb52ea134d5af868d73766489c73' to.sha1='<NoInstanceOf_NormalFileDto>'): /2/1/a
06:22:17.335 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=4, fromRepositoryId="FileInProgressMarker", toRepositoryId="4", path="7ba843bf-30b8-4703-a6fa-8722063de7bb"] on repo=30958838-5185-4aa3-b571-443e1392c1ec
06:22:17.337 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/2/1/a'
06:22:17.337 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='7f14d1efd0debb52ea134d5af868d73766489c73' toSha1='null'
06:22:17.337 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/2/1/a'
06:22:17.337 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/2/1/a' offset=0
06:22:17.339 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/2/1/a' offset=0
06:22:17.346 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 187720 bytes in 9 ms. path='/2/1/a'
06:22:17.351 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_86f7e437faa5a7fce15d1ddcb9eaeaea377667b8_0 into destFile a
06:22:17.367 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=4, fromRepositoryId="FileInProgressMarker", toRepositoryId="4", path="7ba843bf-30b8-4703-a6fa-8722063de7bb"] on repo=30958838-5185-4aa3-b571-443e1392c1ec
06:22:17.369 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/2/1/b'
06:22:17.378 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='60faf99c63b7f3e75dd29fedc158a715cb4449f3' to.sha1='<NoInstanceOf_NormalFileDto>'): /2/1/b
06:22:17.391 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=5, fromRepositoryId="FileInProgressMarker", toRepositoryId="5", path="7ba843bf-30b8-4703-a6fa-8722063de7bb"] on repo=30958838-5185-4aa3-b571-443e1392c1ec
06:22:17.393 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/2/1/b'
06:22:17.393 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='60faf99c63b7f3e75dd29fedc158a715cb4449f3' toSha1='null'
06:22:17.393 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/2/1/b'
06:22:17.393 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/2/1/b' offset=0
06:22:17.395 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/2/1/b' offset=0
06:22:17.402 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 153299 bytes in 9 ms. path='/2/1/b'
06:22:17.406 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_e9d71f5ee7c92d6dc9e92ffdad17b8bd49418f98_0 into destFile b
06:22:17.420 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=5, fromRepositoryId="FileInProgressMarker", toRepositoryId="5", path="7ba843bf-30b8-4703-a6fa-8722063de7bb"] on repo=30958838-5185-4aa3-b571-443e1392c1ec
06:22:17.423 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/2/a'
06:22:17.432 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='61e8f1b2b597716148aa62588aded0ecd67ea1e8' to.sha1='<NoInstanceOf_NormalFileDto>'): /2/a
06:22:17.444 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=6, fromRepositoryId="FileInProgressMarker", toRepositoryId="6", path="7ba843bf-30b8-4703-a6fa-8722063de7bb"] on repo=30958838-5185-4aa3-b571-443e1392c1ec
06:22:17.446 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/2/a'
06:22:17.446 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='61e8f1b2b597716148aa62588aded0ecd67ea1e8' toSha1='null'
06:22:17.446 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/2/a'
06:22:17.446 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/2/a' offset=0
06:22:17.450 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/2/a' offset=0
06:22:17.458 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 303398 bytes in 12 ms. path='/2/a'
06:22:17.461 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_86f7e437faa5a7fce15d1ddcb9eaeaea377667b8_0 into destFile a
06:22:17.481 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=6, fromRepositoryId="FileInProgressMarker", toRepositoryId="6", path="7ba843bf-30b8-4703-a6fa-8722063de7bb"] on repo=30958838-5185-4aa3-b571-443e1392c1ec
06:22:17.484 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/3/a'
06:22:17.490 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='53569dc5c245c147354552537498ae81b053c5a1' to.sha1='<NoInstanceOf_NormalFileDto>'): /3/a
06:22:17.499 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=7, fromRepositoryId="FileInProgressMarker", toRepositoryId="7", path="7ba843bf-30b8-4703-a6fa-8722063de7bb"] on repo=30958838-5185-4aa3-b571-443e1392c1ec
06:22:17.501 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/3/a'
06:22:17.501 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='53569dc5c245c147354552537498ae81b053c5a1' toSha1='null'
06:22:17.501 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/3/a'
06:22:17.501 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/3/a' offset=0
06:22:17.503 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/3/a' offset=0
06:22:17.508 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 200116 bytes in 7 ms. path='/3/a'
06:22:17.511 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_86f7e437faa5a7fce15d1ddcb9eaeaea377667b8_0 into destFile a
06:22:17.525 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=7, fromRepositoryId="FileInProgressMarker", toRepositoryId="7", path="7ba843bf-30b8-4703-a6fa-8722063de7bb"] on repo=30958838-5185-4aa3-b571-443e1392c1ec
06:22:17.529 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/3/b'
06:22:17.538 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='4c7a6768e079de128248e4d425470fe237bf9539' to.sha1='<NoInstanceOf_NormalFileDto>'): /3/b
06:22:17.547 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=8, fromRepositoryId="FileInProgressMarker", toRepositoryId="8", path="7ba843bf-30b8-4703-a6fa-8722063de7bb"] on repo=30958838-5185-4aa3-b571-443e1392c1ec
06:22:17.548 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/3/b'
06:22:17.549 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='4c7a6768e079de128248e4d425470fe237bf9539' toSha1='null'
06:22:17.549 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/3/b'
06:22:17.549 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/3/b' offset=0
06:22:17.549 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/3/b' offset=0
06:22:17.560 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 60636 bytes in 11 ms. path='/3/b'
06:22:17.564 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_e9d71f5ee7c92d6dc9e92ffdad17b8bd49418f98_0 into destFile b
06:22:17.576 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=8, fromRepositoryId="FileInProgressMarker", toRepositoryId="8", path="7ba843bf-30b8-4703-a6fa-8722063de7bb"] on repo=30958838-5185-4aa3-b571-443e1392c1ec
06:22:17.580 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/3/c'
06:22:17.590 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='87c33dcd944f488883d5663e8f709e1360819e47' to.sha1='<NoInstanceOf_NormalFileDto>'): /3/c
06:22:17.602 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=9, fromRepositoryId="FileInProgressMarker", toRepositoryId="9", path="7ba843bf-30b8-4703-a6fa-8722063de7bb"] on repo=30958838-5185-4aa3-b571-443e1392c1ec
06:22:17.604 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/3/c'
06:22:17.604 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='87c33dcd944f488883d5663e8f709e1360819e47' toSha1='null'
06:22:17.604 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/3/c'
06:22:17.604 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/3/c' offset=0
06:22:17.607 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/3/c' offset=0
06:22:17.614 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 256704 bytes in 10 ms. path='/3/c'
06:22:17.618 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_84a516841ba77a5b4648de2cd0dfcb30ea46dbb4_0 into destFile c
06:22:17.636 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=9, fromRepositoryId="FileInProgressMarker", toRepositoryId="9", path="7ba843bf-30b8-4703-a6fa-8722063de7bb"] on repo=30958838-5185-4aa3-b571-443e1392c1ec
06:22:17.639 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/3/d'
06:22:17.649 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='f155d95d34bd0d164f4be15838f0036c81b5847b' to.sha1='<NoInstanceOf_NormalFileDto>'): /3/d
06:22:17.663 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=10, fromRepositoryId="FileInProgressMarker", toRepositoryId="10", path="7ba843bf-30b8-4703-a6fa-8722063de7bb"] on repo=30958838-5185-4aa3-b571-443e1392c1ec
06:22:17.665 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/3/d'
06:22:17.665 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='f155d95d34bd0d164f4be15838f0036c81b5847b' toSha1='null'
06:22:17.665 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/3/d'
06:22:17.665 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/3/d' offset=0
06:22:17.665 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/3/d' offset=0
06:22:17.676 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 7834 bytes in 11 ms. path='/3/d'
06:22:17.681 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_3c363836cf4e16666669a25da280a1865c2d2874_0 into destFile d
06:22:17.690 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=10, fromRepositoryId="FileInProgressMarker", toRepositoryId="10", path="7ba843bf-30b8-4703-a6fa-8722063de7bb"] on repo=30958838-5185-4aa3-b571-443e1392c1ec
06:22:17.693 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 49.27%
06:22:17.709 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=7ba843bf-30b8-4703-a6fa-8722063de7bb remoteRepositoryId=30958838-5185-4aa3-b571-443e1392c1ec localRepositoryRevisionSynced=2
06:22:17.718 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=30958838-5185-4aa3-b571-443e1392c1ec remoteRepositoryId=7ba843bf-30b8-4703-a6fa-8722063de7bb transaction.localRevision=57 remoteFromLocalRevision=2
06:22:17.724 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncUp: fromID=30958838-5185-4aa3-b571-443e1392c1ec from='target/repo/k6yl4z0u-gzv1f-local' toID=7ba843bf-30b8-4703-a6fa-8722063de7bb to='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.local/target/repo/k6yl4z0u-kfl7r-remote'
06:22:17.743 [main] INFO c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=30958838-5185-4aa3-b571-443e1392c1ec remoteRepositoryId=7ba843bf-30b8-4703-a6fa-8722063de7bb localRepositoryRevisionSynced=-1 localRepositoryRevisionInProgress=57
06:22:17.753 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 63.43%
06:22:17.753 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 74.63%
06:22:17.764 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=30958838-5185-4aa3-b571-443e1392c1ec remoteRepositoryId=7ba843bf-30b8-4703-a6fa-8722063de7bb localRepositoryRevisionSynced=57
06:22:17.771 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=7ba843bf-30b8-4703-a6fa-8722063de7bb remoteRepositoryId=30958838-5185-4aa3-b571-443e1392c1ec transaction.localRevision=5 remoteFromLocalRevision=57
06:22:17.773 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDown: fromID=7ba843bf-30b8-4703-a6fa-8722063de7bb from='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.local/target/repo/k6yl4z0u-kfl7r-remote' toID=30958838-5185-4aa3-b571-443e1392c1ec to='target/repo/k6yl4z0u-gzv1f-local', fromRepoLocalSync=false
06:22:17.785 [main] INFO c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=7ba843bf-30b8-4703-a6fa-8722063de7bb remoteRepositoryId=30958838-5185-4aa3-b571-443e1392c1ec localRepositoryRevisionSynced=2 localRepositoryRevisionInProgress=5
06:22:17.787 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 88.31%
06:22:17.787 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 99.50%
06:22:17.790 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=7ba843bf-30b8-4703-a6fa-8722063de7bb remoteRepositoryId=30958838-5185-4aa3-b571-443e1392c1ec localRepositoryRevisionSynced=5
06:22:17.794 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=30958838-5185-4aa3-b571-443e1392c1ec remoteRepositoryId=7ba843bf-30b8-4703-a6fa-8722063de7bb transaction.localRevision=60 remoteFromLocalRevision=5
06:22:17.795 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 100.00%
06:22:17.807 [main] INFO c.c.c.l.LocalRepoManagerImpl - [1728a1d2]getCloseDeferredMillis: closeDeferredMillis=20000
06:22:17.807 [main] INFO c.c.c.l.LocalRepoManagerImpl - [1728a1d2]close: Deferring shut down of real LocalRepoManager 20000 ms.
06:22:17.810 [main] INFO c.c.c.l.LocalRepoManagerImpl - [bc33894]getCloseDeferredMillis: closeDeferredMillis=20000
06:22:17.810 [main] INFO c.c.c.l.LocalRepoManagerImpl - [bc33894]close: Deferring shut down of real LocalRepoManager 20000 ms.
06:22:17.851 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 1.00%
06:22:17.852 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 50.00%
06:22:17.855 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 62.25%
06:22:17.858 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 68.38%
06:22:17.860 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 74.50%
06:22:17.862 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 86.75%
06:22:17.865 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 92.88%
06:22:17.873 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 99.00%
06:22:17.918 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 100.00%
06:22:17.930 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 0.50%
06:22:17.930 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDown: fromID=7ba843bf-30b8-4703-a6fa-8722063de7bb from='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.local/target/repo/k6yl4z0u-kfl7r-remote' toID=30958838-5185-4aa3-b571-443e1392c1ec to='target/repo/k6yl4z0u-gzv1f-local', fromRepoLocalSync=true
06:22:17.931 [pool-40-thread-1] INFO c.c.c.c.r.s.RepoToRepoSync - sync: locally syncing 30958838-5185-4aa3-b571-443e1392c1ec ('target/repo/k6yl4z0u-gzv1f-local')
06:22:17.934 [pool-40-thread-1] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 12.94%
06:22:17.948 [pool-40-thread-1] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 19.03%
06:22:17.977 [pool-40-thread-1] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 24.11%
06:22:17.993 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 1.00%
06:22:17.995 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 50.00%
06:22:17.999 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 62.25%
06:22:18.003 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 68.38%
06:22:18.005 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 74.50%
06:22:18.008 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 86.75%
06:22:18.012 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 92.88%
06:22:18.014 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 99.00%
06:22:18.019 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 100.00%
06:22:18.024 [main] INFO c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=7ba843bf-30b8-4703-a6fa-8722063de7bb remoteRepositoryId=30958838-5185-4aa3-b571-443e1392c1ec localRepositoryRevisionSynced=5 localRepositoryRevisionInProgress=8
06:22:18.039 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 31.09%
06:22:18.039 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path=''
06:22:18.052 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncModifications: Deleting '/2'
06:22:18.100 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 38.56%
06:22:18.101 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 46.02%
06:22:18.119 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=7ba843bf-30b8-4703-a6fa-8722063de7bb remoteRepositoryId=30958838-5185-4aa3-b571-443e1392c1ec localRepositoryRevisionSynced=8
06:22:18.123 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=30958838-5185-4aa3-b571-443e1392c1ec remoteRepositoryId=7ba843bf-30b8-4703-a6fa-8722063de7bb transaction.localRevision=63 remoteFromLocalRevision=8
06:22:18.127 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncUp: fromID=30958838-5185-4aa3-b571-443e1392c1ec from='target/repo/k6yl4z0u-gzv1f-local' toID=7ba843bf-30b8-4703-a6fa-8722063de7bb to='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.local/target/repo/k6yl4z0u-kfl7r-remote'
06:22:18.138 [main] INFO c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=30958838-5185-4aa3-b571-443e1392c1ec remoteRepositoryId=7ba843bf-30b8-4703-a6fa-8722063de7bb localRepositoryRevisionSynced=57 localRepositoryRevisionInProgress=63
06:22:18.147 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 52.24%
06:22:18.148 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 59.70%
06:22:18.148 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncModifications: Deleting '/2'
06:22:18.152 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 67.16%
06:22:18.152 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 74.63%
06:22:18.170 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=30958838-5185-4aa3-b571-443e1392c1ec remoteRepositoryId=7ba843bf-30b8-4703-a6fa-8722063de7bb localRepositoryRevisionSynced=63
06:22:18.173 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=7ba843bf-30b8-4703-a6fa-8722063de7bb remoteRepositoryId=30958838-5185-4aa3-b571-443e1392c1ec transaction.localRevision=11 remoteFromLocalRevision=63
06:22:18.176 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDown: fromID=7ba843bf-30b8-4703-a6fa-8722063de7bb from='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.local/target/repo/k6yl4z0u-kfl7r-remote' toID=30958838-5185-4aa3-b571-443e1392c1ec to='target/repo/k6yl4z0u-gzv1f-local', fromRepoLocalSync=false
06:22:18.182 [main] INFO c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=7ba843bf-30b8-4703-a6fa-8722063de7bb remoteRepositoryId=30958838-5185-4aa3-b571-443e1392c1ec localRepositoryRevisionSynced=8 localRepositoryRevisionInProgress=11
06:22:18.185 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 88.31%
06:22:18.185 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 99.50%
06:22:18.189 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=7ba843bf-30b8-4703-a6fa-8722063de7bb remoteRepositoryId=30958838-5185-4aa3-b571-443e1392c1ec localRepositoryRevisionSynced=11
06:22:18.193 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=30958838-5185-4aa3-b571-443e1392c1ec remoteRepositoryId=7ba843bf-30b8-4703-a6fa-8722063de7bb transaction.localRevision=66 remoteFromLocalRevision=11
06:22:18.195 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 100.00%
06:22:18.195 [main] INFO c.c.c.l.LocalRepoManagerImpl - [1728a1d2]close: Deferring shut down of real LocalRepoManager 20000 ms.
06:22:18.205 [main] INFO c.c.c.l.LocalRepoManagerImpl - [bc33894]close: Deferring shut down of real LocalRepoManager 20000 ms.
Tests run: 20, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 313.077 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_java_8/co.codewizards.cloudstore.local/target/co.codewizards.cloudstore.local-0.10.8-SNAPSHOT.jar
[INFO]
[INFO] --- maven-source-plugin:2.1.2:jar-no-fork (attach-sources) @ co.codewizards.cloudstore.local ---
[INFO] co already added, skipping
[INFO] co/codewizards already added, skipping
[INFO] co/codewizards/cloudstore already added, skipping
[INFO] co/codewizards/cloudstore/local already added, skipping
[INFO] co/codewizards/cloudstore/local/db already added, skipping
[INFO] co/codewizards/cloudstore/local/persistence already added, skipping
[INFO] co/codewizards/cloudstore/local/transport already added, skipping
[INFO] co/codewizards/cloudstore/local/dto already added, skipping
[INFO] co/codewizards/cloudstore/local/persistence/package-sqlite.orm already added, skipping
[INFO] Building jar: /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.local/target/co.codewizards.cloudstore.local-0.10.8-SNAPSHOT-sources.jar
[INFO] co already added, skipping
[INFO] co/codewizards already added, skipping
[INFO] co/codewizards/cloudstore already added, skipping
[INFO] co/codewizards/cloudstore/local already added, skipping
[INFO] co/codewizards/cloudstore/local/db already added, skipping
[INFO] co/codewizards/cloudstore/local/persistence already added, skipping
[INFO] co/codewizards/cloudstore/local/transport already added, skipping
[INFO] co/codewizards/cloudstore/local/dto already added, skipping
[INFO] co/codewizards/cloudstore/local/persistence/package-sqlite.orm already added, skipping
[INFO]
[INFO] --- maven-install-plugin:2.4:install (default-install) @ co.codewizards.cloudstore.local ---
[INFO] Installing /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.local/target/co.codewizards.cloudstore.local-0.10.8-SNAPSHOT.jar to /home/tomcat/.m2/repository/co/codewizards/cloudstore/co.codewizards.cloudstore.local/0.10.8-SNAPSHOT/co.codewizards.cloudstore.local-0.10.8-SNAPSHOT.jar
[INFO] Installing /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.local/pom.xml to /home/tomcat/.m2/repository/co/codewizards/cloudstore/co.codewizards.cloudstore.local/0.10.8-SNAPSHOT/co.codewizards.cloudstore.local-0.10.8-SNAPSHOT.pom
[INFO] Installing /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.local/target/co.codewizards.cloudstore.local-0.10.8-SNAPSHOT-sources.jar to /home/tomcat/.m2/repository/co/codewizards/cloudstore/co.codewizards.cloudstore.local/0.10.8-SNAPSHOT/co.codewizards.cloudstore.local-0.10.8-SNAPSHOT-sources.jar
[INFO]
[INFO] --- maven-deploy-plugin:2.7:deploy (default-deploy) @ co.codewizards.cloudstore.local ---
[INFO] Downloading: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/snapshot/co/codewizards/cloudstore/co.codewizards.cloudstore.local/0.10.8-SNAPSHOT/maven-metadata.xml
[INFO] Downloaded: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/snapshot/co/codewizards/cloudstore/co.codewizards.cloudstore.local/0.10.8-SNAPSHOT/maven-metadata.xml (1 KB at 333.3 KB/sec)
[INFO] Uploading: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/snapshot/co/codewizards/cloudstore/co.codewizards.cloudstore.local/0.10.8-SNAPSHOT/co.codewizards.cloudstore.local-0.10.8-20200223.052220-7.jar
[INFO] Uploaded: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/snapshot/co/codewizards/cloudstore/co.codewizards.cloudstore.local/0.10.8-SNAPSHOT/co.codewizards.cloudstore.local-0.10.8-20200223.052220-7.jar (203 KB at 28964.0 KB/sec)
[INFO] Uploading: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/snapshot/co/codewizards/cloudstore/co.codewizards.cloudstore.local/0.10.8-SNAPSHOT/co.codewizards.cloudstore.local-0.10.8-20200223.052220-7.pom
[INFO] Uploaded: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/snapshot/co/codewizards/cloudstore/co.codewizards.cloudstore.local/0.10.8-SNAPSHOT/co.codewizards.cloudstore.local-0.10.8-20200223.052220-7.pom (3 KB at 2565.4 KB/sec)
[INFO] Downloading: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/snapshot/co/codewizards/cloudstore/co.codewizards.cloudstore.local/maven-metadata.xml
[INFO] Downloaded: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/snapshot/co/codewizards/cloudstore/co.codewizards.cloudstore.local/maven-metadata.xml (317 B at 154.8 KB/sec)
[INFO] Uploading: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/snapshot/co/codewizards/cloudstore/co.codewizards.cloudstore.local/0.10.8-SNAPSHOT/maven-metadata.xml
[INFO] Uploaded: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/snapshot/co/codewizards/cloudstore/co.codewizards.cloudstore.local/0.10.8-SNAPSHOT/maven-metadata.xml (1 KB at 500.0 KB/sec)
[INFO] Uploading: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/snapshot/co/codewizards/cloudstore/co.codewizards.cloudstore.local/maven-metadata.xml
[INFO] Uploaded: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/snapshot/co/codewizards/cloudstore/co.codewizards.cloudstore.local/maven-metadata.xml (317 B at 309.6 KB/sec)
[INFO] Uploading: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/snapshot/co/codewizards/cloudstore/co.codewizards.cloudstore.local/0.10.8-SNAPSHOT/co.codewizards.cloudstore.local-0.10.8-20200223.052220-7-sources.jar
[INFO] Uploaded: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/snapshot/co/codewizards/cloudstore/co.codewizards.cloudstore.local/0.10.8-SNAPSHOT/co.codewizards.cloudstore.local-0.10.8-20200223.052220-7-sources.jar (105 KB at 26069.1 KB/sec)
[INFO] Uploading: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/snapshot/co/codewizards/cloudstore/co.codewizards.cloudstore.local/0.10.8-SNAPSHOT/maven-metadata.xml
[INFO] Uploaded: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/snapshot/co/codewizards/cloudstore/co.codewizards.cloudstore.local/0.10.8-SNAPSHOT/maven-metadata.xml (1 KB at 500.0 KB/sec)
[INFO]
[INFO] --- maven-site-plugin:3.3:site (default-site) @ co.codewizards.cloudstore.local ---
[INFO] Relativizing decoration links with respect to project URL: http://cloudstore.codewizards.co/co.codewizards.cloudstore.local
[INFO] Rendering site with org.apache.maven.skins:maven-default-skin:jar:1.0 skin.
[INFO] Relativizing decoration links with respect to project URL: http://cloudstore.codewizards.co/co.codewizards.cloudstore.local/de
[INFO] Rendering site with org.apache.maven.skins:maven-default-skin:jar:1.0 skin.
[JENKINS] Archiving site from /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.local/target/site to /home/tomcat/.jenkins/jobs/co.codewizards.cloudstore_java_8/site/co.codewizards.cloudstore.local
[INFO]
[INFO] >>> maven-javadoc-plugin:2.9.1:javadoc (javadoc-generate-unfiltered) > generate-sources @ 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) < generate-sources @ co.codewizards.cloudstore.local <<<
[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_java_8/co.codewizards.cloudstore.local/target/javadoc.unfiltered
[INFO] skip non existing resourceDirectory /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/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_java_8/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_java_8/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