|  Console Output
Skipping 705 KB.. Full Log...: 58.00%
11:40:40.905 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 63.61%
11:40:40.916 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 74.34%
11:40:40.939 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 79.43%
11:40:40.952 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 90.67%
11:40:40.977 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 95.69%
11:40:41.007 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 100.00%
11:40:41.033 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - local repo: 76029501-b8af-4c00-aae0-9992d0def9b1
11:40:41.033 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - remote repo: 33989cb3-4dbf-4c0f-933d-2ba004590eb5
11:40:41.037 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 0.50%
11:40:41.038 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDown: fromID=33989cb3-4dbf-4c0f-933d-2ba004590eb5 from='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/k0ksh4ad-i7ro4-remote/2' toID=76029501-b8af-4c00-aae0-9992d0def9b1 to='target/repo/k0ksh4ad-s7tu0-local', fromRepoLocalSync=true
11:40:41.038 [pool-31-thread-1] INFO c.c.c.c.r.s.RepoToRepoSync - sync: locally syncing 76029501-b8af-4c00-aae0-9992d0def9b1 ('target/repo/k0ksh4ad-s7tu0-local')
11:40:41.065 [pool-31-thread-1] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 12.94%
11:40:41.076 [pool-31-thread-1] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 25.12%
11:40:41.082 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 1.00%
11:40:41.084 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 50.00%
11:40:41.085 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 58.17%
11:40:41.090 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 63.61%
11:40:41.096 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 74.50%
11:40:41.102 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 80.62%
11:40:41.105 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 90.83%
11:40:41.109 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 95.94%
11:40:41.115 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 100.00%
11:40:41.143 [main] INFO c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=33989cb3-4dbf-4c0f-933d-2ba004590eb5 remoteRepositoryId=76029501-b8af-4c00-aae0-9992d0def9b1 localRepositoryRevisionSynced=-1 localRepositoryRevisionInProgress=2
11:40:41.193 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path=''
11:40:41.215 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path='/1'
11:40:41.231 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 31.31%
11:40:41.237 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 36.58%
11:40:41.237 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 41.85%
11:40:41.237 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/1/a'
11:40:41.245 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='3d68170342fca9c09d5cb1e7a720a24bedf5e03f' to.sha1='<NoInstanceOf_NormalFileDto>'): /1/a
11:40:41.309 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=1, fromRepositoryId="FileInProgressMarker", toRepositoryId="1", path="33989cb3-4dbf-4c0f-933d-2ba004590eb5"] on repo=76029501-b8af-4c00-aae0-9992d0def9b1
11:40:41.310 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/1/a'
11:40:41.310 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='3d68170342fca9c09d5cb1e7a720a24bedf5e03f' toSha1='null'
11:40:41.310 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/1/a'
11:40:41.310 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/1/a' offset=0
11:40:41.312 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/1/a' offset=0
11:40:41.320 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 131940 bytes in 10 ms. path='/1/a'
11:40:41.324 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_86f7e437faa5a7fce15d1ddcb9eaeaea377667b8_0 into destFile a
11:40:41.352 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=1, fromRepositoryId="FileInProgressMarker", toRepositoryId="1", path="33989cb3-4dbf-4c0f-933d-2ba004590eb5"] on repo=76029501-b8af-4c00-aae0-9992d0def9b1
11:40:41.358 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/1/b'
11:40:41.366 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='fe73b6c89df6d168d99ccc4943ae46866a9d4838' to.sha1='<NoInstanceOf_NormalFileDto>'): /1/b
11:40:41.384 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=2, fromRepositoryId="FileInProgressMarker", toRepositoryId="2", path="33989cb3-4dbf-4c0f-933d-2ba004590eb5"] on repo=76029501-b8af-4c00-aae0-9992d0def9b1
11:40:41.386 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/1/b'
11:40:41.386 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='fe73b6c89df6d168d99ccc4943ae46866a9d4838' toSha1='null'
11:40:41.386 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/1/b'
11:40:41.386 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/1/b' offset=0
11:40:41.388 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/1/b' offset=0
11:40:41.396 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 186576 bytes in 10 ms. path='/1/b'
11:40:41.400 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_e9d71f5ee7c92d6dc9e92ffdad17b8bd49418f98_0 into destFile b
11:40:41.415 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=2, fromRepositoryId="FileInProgressMarker", toRepositoryId="2", path="33989cb3-4dbf-4c0f-933d-2ba004590eb5"] on repo=76029501-b8af-4c00-aae0-9992d0def9b1
11:40:41.419 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/a'
11:40:41.427 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='72a214ad1c86759e33e0c442b9e82ce9b4850fbb' to.sha1='<NoInstanceOf_NormalFileDto>'): /a
11:40:41.439 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=3, fromRepositoryId="FileInProgressMarker", toRepositoryId="3", path="33989cb3-4dbf-4c0f-933d-2ba004590eb5"] on repo=76029501-b8af-4c00-aae0-9992d0def9b1
11:40:41.441 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/a'
11:40:41.441 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='72a214ad1c86759e33e0c442b9e82ce9b4850fbb' toSha1='null'
11:40:41.441 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/a'
11:40:41.441 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/a' offset=0
11:40:41.444 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/a' offset=0
11:40:41.458 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 229419 bytes in 17 ms. path='/a'
11:40:41.461 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_86f7e437faa5a7fce15d1ddcb9eaeaea377667b8_0 into destFile a
11:40:41.480 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=3, fromRepositoryId="FileInProgressMarker", toRepositoryId="3", path="33989cb3-4dbf-4c0f-933d-2ba004590eb5"] on repo=76029501-b8af-4c00-aae0-9992d0def9b1
11:40:41.482 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 48.43%
11:40:41.497 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=33989cb3-4dbf-4c0f-933d-2ba004590eb5 remoteRepositoryId=76029501-b8af-4c00-aae0-9992d0def9b1 localRepositoryRevisionSynced=2
11:40:41.511 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=76029501-b8af-4c00-aae0-9992d0def9b1 remoteRepositoryId=33989cb3-4dbf-4c0f-933d-2ba004590eb5 transaction.localRevision=19 remoteFromLocalRevision=2
11:40:41.517 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncUp: fromID=76029501-b8af-4c00-aae0-9992d0def9b1 from='target/repo/k0ksh4ad-s7tu0-local' toID=33989cb3-4dbf-4c0f-933d-2ba004590eb5 to='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/k0ksh4ad-i7ro4-remote/2'
11:40:41.535 [main] INFO c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=76029501-b8af-4c00-aae0-9992d0def9b1 remoteRepositoryId=33989cb3-4dbf-4c0f-933d-2ba004590eb5 localRepositoryRevisionSynced=-1 localRepositoryRevisionInProgress=19
11:40:41.547 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 63.43%
11:40:41.547 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 74.63%
11:40:41.557 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=76029501-b8af-4c00-aae0-9992d0def9b1 remoteRepositoryId=33989cb3-4dbf-4c0f-933d-2ba004590eb5 localRepositoryRevisionSynced=19
11:40:41.565 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=33989cb3-4dbf-4c0f-933d-2ba004590eb5 remoteRepositoryId=76029501-b8af-4c00-aae0-9992d0def9b1 transaction.localRevision=5 remoteFromLocalRevision=19
11:40:41.570 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDown: fromID=33989cb3-4dbf-4c0f-933d-2ba004590eb5 from='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/k0ksh4ad-i7ro4-remote/2' toID=76029501-b8af-4c00-aae0-9992d0def9b1 to='target/repo/k0ksh4ad-s7tu0-local', fromRepoLocalSync=false
11:40:41.585 [main] INFO c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=33989cb3-4dbf-4c0f-933d-2ba004590eb5 remoteRepositoryId=76029501-b8af-4c00-aae0-9992d0def9b1 localRepositoryRevisionSynced=2 localRepositoryRevisionInProgress=5
11:40:41.593 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 88.31%
11:40:41.593 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 99.50%
11:40:41.601 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=33989cb3-4dbf-4c0f-933d-2ba004590eb5 remoteRepositoryId=76029501-b8af-4c00-aae0-9992d0def9b1 localRepositoryRevisionSynced=5
11:40:41.609 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=76029501-b8af-4c00-aae0-9992d0def9b1 remoteRepositoryId=33989cb3-4dbf-4c0f-933d-2ba004590eb5 transaction.localRevision=22 remoteFromLocalRevision=5
11:40:41.611 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 100.00%
11:40:41.631 [main] INFO c.c.c.l.LocalRepoManagerImpl - [7b802fba]getCloseDeferredMillis: closeDeferredMillis=20000
11:40:41.631 [main] INFO c.c.c.l.LocalRepoManagerImpl - [7b802fba]close: Deferring shut down of real LocalRepoManager 20000 ms.
11:40:41.632 [main] INFO c.c.c.l.LocalRepoManagerImpl - [1eb5eff8]getCloseDeferredMillis: closeDeferredMillis=20000
11:40:41.632 [main] INFO c.c.c.l.LocalRepoManagerImpl - [1eb5eff8]close: Deferring shut down of real LocalRepoManager 20000 ms.
11:40:41.652 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 1.00%
11:40:41.653 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 50.00%
11:40:41.654 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 58.17%
11:40:41.657 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 63.61%
11:40:41.661 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 74.50%
11:40:41.690 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 80.62%
11:40:41.694 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 90.83%
11:40:41.704 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 96.55%
11:40:41.722 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 100.00%
11:40:41.750 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 0.50%
11:40:41.751 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDown: fromID=33989cb3-4dbf-4c0f-933d-2ba004590eb5 from='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/k0ksh4ad-i7ro4-remote/2' toID=76029501-b8af-4c00-aae0-9992d0def9b1 to='target/repo/k0ksh4ad-s7tu0-local', fromRepoLocalSync=true
11:40:41.752 [pool-32-thread-1] INFO c.c.c.c.r.s.RepoToRepoSync - sync: locally syncing 76029501-b8af-4c00-aae0-9992d0def9b1 ('target/repo/k0ksh4ad-s7tu0-local')
11:40:41.763 [pool-32-thread-1] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 12.94%
11:40:41.772 [pool-32-thread-1] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 18.27%
11:40:41.783 [pool-32-thread-1] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 25.12%
11:40:41.789 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 1.00%
11:40:41.790 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 50.00%
11:40:41.791 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 58.17%
11:40:41.794 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 63.61%
11:40:41.797 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 74.50%
11:40:41.813 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 80.62%
11:40:41.816 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 90.83%
11:40:41.821 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 96.55%
11:40:41.830 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 100.00%
11:40:41.840 [main] INFO c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=33989cb3-4dbf-4c0f-933d-2ba004590eb5 remoteRepositoryId=76029501-b8af-4c00-aae0-9992d0def9b1 localRepositoryRevisionSynced=5 localRepositoryRevisionInProgress=8
11:40:41.858 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path='/1'
11:40:41.873 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 31.31%
11:40:41.876 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path='/1/5'
11:40:41.886 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 36.58%
11:40:41.886 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 41.85%
11:40:41.886 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/1/5/aaa'
11:40:41.893 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='16f2066c81edc3d4ca055c225970d5f800ffa86d' to.sha1='<NoInstanceOf_NormalFileDto>'): /1/5/aaa
11:40:41.905 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=4, fromRepositoryId="FileInProgressMarker", toRepositoryId="4", path="33989cb3-4dbf-4c0f-933d-2ba004590eb5"] on repo=76029501-b8af-4c00-aae0-9992d0def9b1
11:40:41.906 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/1/5/aaa'
11:40:41.906 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='16f2066c81edc3d4ca055c225970d5f800ffa86d' toSha1='null'
11:40:41.906 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/1/5/aaa'
11:40:41.906 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/1/5/aaa' offset=0
11:40:41.910 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/1/5/aaa' offset=0
11:40:41.918 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 304708 bytes in 12 ms. path='/1/5/aaa'
11:40:41.922 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_7e240de74fb1ed08fa08d38063f6a6a91462a815_0 into destFile aaa
11:40:41.942 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=4, fromRepositoryId="FileInProgressMarker", toRepositoryId="4", path="33989cb3-4dbf-4c0f-933d-2ba004590eb5"] on repo=76029501-b8af-4c00-aae0-9992d0def9b1
11:40:41.947 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/1/5/bbb'
11:40:41.962 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='7a766db4ced91a2eb27071cfb93ad46be36f64c5' to.sha1='<NoInstanceOf_NormalFileDto>'): /1/5/bbb
11:40:41.975 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=5, fromRepositoryId="FileInProgressMarker", toRepositoryId="5", path="33989cb3-4dbf-4c0f-933d-2ba004590eb5"] on repo=76029501-b8af-4c00-aae0-9992d0def9b1
11:40:41.976 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/1/5/bbb'
11:40:41.977 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='7a766db4ced91a2eb27071cfb93ad46be36f64c5' toSha1='null'
11:40:41.977 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/1/5/bbb'
11:40:41.977 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/1/5/bbb' offset=0
11:40:41.981 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/1/5/bbb' offset=0
11:40:41.990 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 104180 bytes in 13 ms. path='/1/5/bbb'
11:40:41.995 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_5cb138284d431abd6a053a56625ec088bfb88912_0 into destFile bbb
11:40:42.008 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=5, fromRepositoryId="FileInProgressMarker", toRepositoryId="5", path="33989cb3-4dbf-4c0f-933d-2ba004590eb5"] on repo=76029501-b8af-4c00-aae0-9992d0def9b1
11:40:42.010 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 48.43%
11:40:42.013 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=33989cb3-4dbf-4c0f-933d-2ba004590eb5 remoteRepositoryId=76029501-b8af-4c00-aae0-9992d0def9b1 localRepositoryRevisionSynced=8
11:40:42.021 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=76029501-b8af-4c00-aae0-9992d0def9b1 remoteRepositoryId=33989cb3-4dbf-4c0f-933d-2ba004590eb5 transaction.localRevision=35 remoteFromLocalRevision=8
11:40:42.025 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncUp: fromID=76029501-b8af-4c00-aae0-9992d0def9b1 from='target/repo/k0ksh4ad-s7tu0-local' toID=33989cb3-4dbf-4c0f-933d-2ba004590eb5 to='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/k0ksh4ad-i7ro4-remote/2'
11:40:42.037 [main] INFO c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=76029501-b8af-4c00-aae0-9992d0def9b1 remoteRepositoryId=33989cb3-4dbf-4c0f-933d-2ba004590eb5 localRepositoryRevisionSynced=19 localRepositoryRevisionInProgress=35
11:40:42.039 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 63.43%
11:40:42.039 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 74.63%
11:40:42.042 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=76029501-b8af-4c00-aae0-9992d0def9b1 remoteRepositoryId=33989cb3-4dbf-4c0f-933d-2ba004590eb5 localRepositoryRevisionSynced=35
11:40:42.050 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=33989cb3-4dbf-4c0f-933d-2ba004590eb5 remoteRepositoryId=76029501-b8af-4c00-aae0-9992d0def9b1 transaction.localRevision=11 remoteFromLocalRevision=35
11:40:42.057 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDown: fromID=33989cb3-4dbf-4c0f-933d-2ba004590eb5 from='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/k0ksh4ad-i7ro4-remote/2' toID=76029501-b8af-4c00-aae0-9992d0def9b1 to='target/repo/k0ksh4ad-s7tu0-local', fromRepoLocalSync=false
11:40:42.071 [main] INFO c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=33989cb3-4dbf-4c0f-933d-2ba004590eb5 remoteRepositoryId=76029501-b8af-4c00-aae0-9992d0def9b1 localRepositoryRevisionSynced=8 localRepositoryRevisionInProgress=11
11:40:42.080 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 88.31%
11:40:42.080 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 99.50%
11:40:42.083 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=33989cb3-4dbf-4c0f-933d-2ba004590eb5 remoteRepositoryId=76029501-b8af-4c00-aae0-9992d0def9b1 localRepositoryRevisionSynced=11
11:40:42.091 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=76029501-b8af-4c00-aae0-9992d0def9b1 remoteRepositoryId=33989cb3-4dbf-4c0f-933d-2ba004590eb5 transaction.localRevision=38 remoteFromLocalRevision=11
11:40:42.093 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 100.00%
11:40:42.093 [main] INFO c.c.c.l.LocalRepoManagerImpl - [7b802fba]close: Deferring shut down of real LocalRepoManager 20000 ms.
11:40:42.114 [main] INFO c.c.c.l.LocalRepoManagerImpl - [1eb5eff8]close: Deferring shut down of real LocalRepoManager 20000 ms.
11:40:42.135 [main] INFO c.c.c.l.LocalRepoManagerImpl - [182fcda5]<init>: localRoot='/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/k0kshb12-f83jf-local'
11:40:42.141 [main] INFO c.c.c.l.LocalRepoManagerImpl - [182fcda5]initLockFile: Repository '/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/k0kshb12-f83jf-local' locked successfully.
11:40:42.467 [closeDeferredTimer-1fa924c-3] INFO c.c.c.l.LocalRepoManagerImpl - [1fa924c]_close: Shutting down real LocalRepoManager.
11:40:42.470 [closeDeferredTimer-3ce1edbe-3] INFO c.c.c.l.LocalRepoManagerImpl - [3ce1edbe]_close: Shutting down real LocalRepoManager.
11:40:49.666 [main] INFO c.c.c.l.LocalRepoManagerImpl - [182fcda5]initPersistenceManagerFactory: repositoryId=ce4a4408-73d2-4f14-9d69-145061033e0e
11:40:49.673 [main] INFO c.c.c.l.LocalRepoManagerImpl - [182fcda5]initPersistenceManagerFactory: Started up PersistenceManagerFactory successfully in 2853 ms.
11:40:49.695 [main] INFO c.c.c.l.LocalRepoManagerImpl - [2d32d675]<init>: localRoot='/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/k0kshb13-trz4k-remote'
11:40:49.703 [main] INFO c.c.c.l.LocalRepoManagerImpl - [2d32d675]initLockFile: Repository '/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/k0kshb13-trz4k-remote' locked successfully.
11:40:53.342 [main] INFO c.c.c.l.LocalRepoManagerImpl - [2d32d675]initPersistenceManagerFactory: repositoryId=e0b57b2e-7604-4794-baaf-63ddadd33f09
11:40:53.349 [main] INFO c.c.c.l.LocalRepoManagerImpl - [2d32d675]initPersistenceManagerFactory: Started up PersistenceManagerFactory successfully in 2128 ms.
11:40:53.386 [closeDeferredTimer-379cb756-1] INFO c.c.c.l.LocalRepoManagerImpl - [379cb756]_close: Shutting down real LocalRepoManager.
11:40:53.664 [closeDeferredTimer-72613a51-1] INFO c.c.c.l.LocalRepoManagerImpl - [72613a51]_close: Shutting down real LocalRepoManager.
11:40:54.197 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 1.00%
11:40:54.204 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 50.00%
11:40:54.217 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 58.00%
11:40:54.292 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 63.61%
11:40:54.308 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 74.34%
11:40:54.346 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 79.42%
11:40:54.364 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 90.67%
11:40:54.390 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 95.74%
11:40:54.453 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 100.00%
11:40:54.494 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - local repo: ce4a4408-73d2-4f14-9d69-145061033e0e
11:40:54.494 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - remote repo: e0b57b2e-7604-4794-baaf-63ddadd33f09
11:40:54.498 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 0.50%
11:40:54.499 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDown: fromID=e0b57b2e-7604-4794-baaf-63ddadd33f09 from='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/k0kshb13-trz4k-remote' toID=ce4a4408-73d2-4f14-9d69-145061033e0e to='target/repo/k0kshb12-f83jf-local', fromRepoLocalSync=true
11:40:54.499 [pool-33-thread-1] INFO c.c.c.c.r.s.RepoToRepoSync - sync: locally syncing ce4a4408-73d2-4f14-9d69-145061033e0e ('target/repo/k0kshb12-f83jf-local')
11:40:54.533 [pool-33-thread-1] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 12.94%
11:40:54.539 [pool-33-thread-1] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 25.12%
11:40:54.549 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 1.00%
11:40:54.551 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 50.00%
11:40:54.553 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 58.17%
11:40:54.561 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 63.61%
11:40:54.565 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 74.50%
11:40:54.571 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 80.62%
11:40:54.574 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 90.83%
11:40:54.578 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 95.94%
11:40:54.584 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 100.00%
11:40:54.665 [main] INFO c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=e0b57b2e-7604-4794-baaf-63ddadd33f09 remoteRepositoryId=ce4a4408-73d2-4f14-9d69-145061033e0e localRepositoryRevisionSynced=-1 localRepositoryRevisionInProgress=2
11:40:54.701 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path=''
11:40:54.779 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path='/1'
11:40:54.863 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 30.22%
11:40:54.863 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path='/2'
11:40:54.894 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path='/2/1'
11:40:54.978 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path='/3'
11:40:55.102 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 35.46%
11:40:55.102 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 40.70%
11:40:55.103 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/1/a'
11:40:55.109 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='5a625b26351ba000796e77da3846e82f6eaba81b' to.sha1='<NoInstanceOf_NormalFileDto>'): /1/a
11:40:55.293 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=1, fromRepositoryId="FileInProgressMarker", toRepositoryId="1", path="e0b57b2e-7604-4794-baaf-63ddadd33f09"] on repo=ce4a4408-73d2-4f14-9d69-145061033e0e
11:40:55.301 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/1/a'
11:40:55.302 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='5a625b26351ba000796e77da3846e82f6eaba81b' toSha1='null'
11:40:55.302 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/1/a'
11:40:55.302 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/1/a' offset=0
11:40:55.314 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/1/a' offset=0
11:40:55.325 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 255222 bytes in 23 ms. path='/1/a'
11:40:55.328 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_86f7e437faa5a7fce15d1ddcb9eaeaea377667b8_0 into destFile a
11:40:55.441 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=1, fromRepositoryId="FileInProgressMarker", toRepositoryId="1", path="e0b57b2e-7604-4794-baaf-63ddadd33f09"] on repo=ce4a4408-73d2-4f14-9d69-145061033e0e
11:40:55.492 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/1/b'
11:40:55.507 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='d5280b68b208ce692ce6d7410e61ab25565680ff' to.sha1='<NoInstanceOf_NormalFileDto>'): /1/b
11:40:55.533 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=2, fromRepositoryId="FileInProgressMarker", toRepositoryId="2", path="e0b57b2e-7604-4794-baaf-63ddadd33f09"] on repo=ce4a4408-73d2-4f14-9d69-145061033e0e
11:40:55.539 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/1/b'
11:40:55.539 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='d5280b68b208ce692ce6d7410e61ab25565680ff' toSha1='null'
11:40:55.539 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/1/b'
11:40:55.540 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/1/b' offset=0
11:40:55.543 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/1/b' offset=0
11:40:55.553 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 72729 bytes in 13 ms. path='/1/b'
11:40:55.560 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_e9d71f5ee7c92d6dc9e92ffdad17b8bd49418f98_0 into destFile b
11:40:55.591 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=2, fromRepositoryId="FileInProgressMarker", toRepositoryId="2", path="e0b57b2e-7604-4794-baaf-63ddadd33f09"] on repo=ce4a4408-73d2-4f14-9d69-145061033e0e
11:40:55.620 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/1/c'
11:40:55.633 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='2d5ea8edfa3cc997be20f9f77a34fffa65d278cb' to.sha1='<NoInstanceOf_NormalFileDto>'): /1/c
11:40:55.693 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=3, fromRepositoryId="FileInProgressMarker", toRepositoryId="3", path="e0b57b2e-7604-4794-baaf-63ddadd33f09"] on repo=ce4a4408-73d2-4f14-9d69-145061033e0e
11:40:55.704 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/1/c'
11:40:55.704 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='2d5ea8edfa3cc997be20f9f77a34fffa65d278cb' toSha1='null'
11:40:55.704 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/1/c'
11:40:55.704 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/1/c' offset=0
11:40:55.714 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/1/c' offset=0
11:40:55.724 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 479481 bytes in 20 ms. path='/1/c'
11:40:55.728 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_84a516841ba77a5b4648de2cd0dfcb30ea46dbb4_0 into destFile c
11:40:55.766 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=3, fromRepositoryId="FileInProgressMarker", toRepositoryId="3", path="e0b57b2e-7604-4794-baaf-63ddadd33f09"] on repo=ce4a4408-73d2-4f14-9d69-145061033e0e
11:40:55.778 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/2/1/a'
11:40:55.790 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='db3dc61c27cca1ebca852972e1a33b19bf5e10b4' to.sha1='<NoInstanceOf_NormalFileDto>'): /2/1/a
11:40:55.809 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=4, fromRepositoryId="FileInProgressMarker", toRepositoryId="4", path="e0b57b2e-7604-4794-baaf-63ddadd33f09"] on repo=ce4a4408-73d2-4f14-9d69-145061033e0e
11:40:55.827 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/2/1/a'
11:40:55.828 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='db3dc61c27cca1ebca852972e1a33b19bf5e10b4' toSha1='null'
11:40:55.828 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/2/1/a'
11:40:55.828 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/2/1/a' offset=0
11:40:55.838 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/2/1/a' offset=0
11:40:55.851 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 670887 bytes in 23 ms. path='/2/1/a'
11:40:55.855 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_86f7e437faa5a7fce15d1ddcb9eaeaea377667b8_0 into destFile a
11:40:55.902 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=4, fromRepositoryId="FileInProgressMarker", toRepositoryId="4", path="e0b57b2e-7604-4794-baaf-63ddadd33f09"] on repo=ce4a4408-73d2-4f14-9d69-145061033e0e
11:40:55.910 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/2/1/b'
11:40:55.922 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='8b2e49ace0970287b85680f24d489926df013512' to.sha1='<NoInstanceOf_NormalFileDto>'): /2/1/b
11:40:55.949 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=5, fromRepositoryId="FileInProgressMarker", toRepositoryId="5", path="e0b57b2e-7604-4794-baaf-63ddadd33f09"] on repo=ce4a4408-73d2-4f14-9d69-145061033e0e
11:40:55.961 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/2/1/b'
11:40:55.961 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='8b2e49ace0970287b85680f24d489926df013512' toSha1='null'
11:40:55.961 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/2/1/b'
11:40:55.961 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/2/1/b' offset=0
11:40:55.966 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/2/1/b' offset=0
11:40:55.981 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 150500 bytes in 20 ms. path='/2/1/b'
11:40:55.986 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_e9d71f5ee7c92d6dc9e92ffdad17b8bd49418f98_0 into destFile b
11:40:56.005 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=5, fromRepositoryId="FileInProgressMarker", toRepositoryId="5", path="e0b57b2e-7604-4794-baaf-63ddadd33f09"] on repo=ce4a4408-73d2-4f14-9d69-145061033e0e
11:40:56.035 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/2/a'
11:40:56.049 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='584b6c1cb385597a22ad3c4a23786e2c0054f3df' to.sha1='<NoInstanceOf_NormalFileDto>'): /2/a
11:40:56.063 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=6, fromRepositoryId="FileInProgressMarker", toRepositoryId="6", path="e0b57b2e-7604-4794-baaf-63ddadd33f09"] on repo=ce4a4408-73d2-4f14-9d69-145061033e0e
11:40:56.069 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/2/a'
11:40:56.069 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='584b6c1cb385597a22ad3c4a23786e2c0054f3df' toSha1='null'
11:40:56.069 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/2/a'
11:40:56.070 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/2/a' offset=0
11:40:56.086 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/2/a' offset=0
11:40:56.098 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 697255 bytes in 28 ms. path='/2/a'
11:40:56.102 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_86f7e437faa5a7fce15d1ddcb9eaeaea377667b8_0 into destFile a
11:40:56.139 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=6, fromRepositoryId="FileInProgressMarker", toRepositoryId="6", path="e0b57b2e-7604-4794-baaf-63ddadd33f09"] on repo=ce4a4408-73d2-4f14-9d69-145061033e0e
11:40:56.150 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/3/a'
11:40:56.161 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='064ed700443c2529526516a211d861e406bc74ed' to.sha1='<NoInstanceOf_NormalFileDto>'): /3/a
11:40:56.179 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=7, fromRepositoryId="FileInProgressMarker", toRepositoryId="7", path="e0b57b2e-7604-4794-baaf-63ddadd33f09"] on repo=ce4a4408-73d2-4f14-9d69-145061033e0e
11:40:56.185 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/3/a'
11:40:56.185 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='064ed700443c2529526516a211d861e406bc74ed' toSha1='null'
11:40:56.185 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/3/a'
11:40:56.185 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/3/a' offset=0
11:40:56.186 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/3/a' offset=0
11:40:56.191 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 63580 bytes in 6 ms. path='/3/a'
11:40:56.196 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_86f7e437faa5a7fce15d1ddcb9eaeaea377667b8_0 into destFile a
11:40:56.212 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=7, fromRepositoryId="FileInProgressMarker", toRepositoryId="7", path="e0b57b2e-7604-4794-baaf-63ddadd33f09"] on repo=ce4a4408-73d2-4f14-9d69-145061033e0e
11:40:56.219 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/3/b'
11:40:56.231 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='eba055c5a3f93ae3238e25e4caa5f4f3934a1dba' to.sha1='<NoInstanceOf_NormalFileDto>'): /3/b
11:40:56.246 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=8, fromRepositoryId="FileInProgressMarker", toRepositoryId="8", path="e0b57b2e-7604-4794-baaf-63ddadd33f09"] on repo=ce4a4408-73d2-4f14-9d69-145061033e0e
11:40:56.252 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/3/b'
11:40:56.252 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='eba055c5a3f93ae3238e25e4caa5f4f3934a1dba' toSha1='null'
11:40:56.252 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/3/b'
11:40:56.253 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/3/b' offset=0
11:40:56.255 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/3/b' offset=0
11:40:56.264 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 114401 bytes in 11 ms. path='/3/b'
11:40:56.267 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_e9d71f5ee7c92d6dc9e92ffdad17b8bd49418f98_0 into destFile b
11:40:56.296 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=8, fromRepositoryId="FileInProgressMarker", toRepositoryId="8", path="e0b57b2e-7604-4794-baaf-63ddadd33f09"] on repo=ce4a4408-73d2-4f14-9d69-145061033e0e
11:40:56.304 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/3/c'
11:40:56.313 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='cd131d66c4e96282810ac4b0598629576e4ddb44' to.sha1='<NoInstanceOf_NormalFileDto>'): /3/c
11:40:56.327 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=9, fromRepositoryId="FileInProgressMarker", toRepositoryId="9", path="e0b57b2e-7604-4794-baaf-63ddadd33f09"] on repo=ce4a4408-73d2-4f14-9d69-145061033e0e
11:40:56.333 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/3/c'
11:40:56.333 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='cd131d66c4e96282810ac4b0598629576e4ddb44' toSha1='null'
11:40:56.333 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/3/c'
11:40:56.333 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/3/c' offset=0
11:40:56.338 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/3/c' offset=0
11:40:56.350 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 407796 bytes in 17 ms. path='/3/c'
11:40:56.353 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_84a516841ba77a5b4648de2cd0dfcb30ea46dbb4_0 into destFile c
11:40:56.411 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=9, fromRepositoryId="FileInProgressMarker", toRepositoryId="9", path="e0b57b2e-7604-4794-baaf-63ddadd33f09"] on repo=ce4a4408-73d2-4f14-9d69-145061033e0e
11:40:56.421 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/3/d'
11:40:56.436 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='9443d7ff5bf2d4f10c2f30b7fb1dc15c7b84bba6' to.sha1='<NoInstanceOf_NormalFileDto>'): /3/d
11:40:56.452 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=10, fromRepositoryId="FileInProgressMarker", toRepositoryId="10", path="e0b57b2e-7604-4794-baaf-63ddadd33f09"] on repo=ce4a4408-73d2-4f14-9d69-145061033e0e
11:40:56.458 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/3/d'
11:40:56.458 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='9443d7ff5bf2d4f10c2f30b7fb1dc15c7b84bba6' toSha1='null'
11:40:56.458 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/3/d'
11:40:56.458 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/3/d' offset=0
11:40:56.466 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/3/d' offset=0
11:40:56.480 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 356546 bytes in 22 ms. path='/3/d'
11:40:56.484 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_3c363836cf4e16666669a25da280a1865c2d2874_0 into destFile d
11:40:56.511 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=10, fromRepositoryId="FileInProgressMarker", toRepositoryId="10", path="e0b57b2e-7604-4794-baaf-63ddadd33f09"] on repo=ce4a4408-73d2-4f14-9d69-145061033e0e
11:40:56.518 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 49.27%
11:40:56.538 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=e0b57b2e-7604-4794-baaf-63ddadd33f09 remoteRepositoryId=ce4a4408-73d2-4f14-9d69-145061033e0e localRepositoryRevisionSynced=2
11:40:56.554 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=ce4a4408-73d2-4f14-9d69-145061033e0e remoteRepositoryId=e0b57b2e-7604-4794-baaf-63ddadd33f09 transaction.localRevision=57 remoteFromLocalRevision=2
11:40:56.564 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncUp: fromID=ce4a4408-73d2-4f14-9d69-145061033e0e from='target/repo/k0kshb12-f83jf-local' toID=e0b57b2e-7604-4794-baaf-63ddadd33f09 to='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/k0kshb13-trz4k-remote'
11:40:56.596 [main] INFO c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=ce4a4408-73d2-4f14-9d69-145061033e0e remoteRepositoryId=e0b57b2e-7604-4794-baaf-63ddadd33f09 localRepositoryRevisionSynced=-1 localRepositoryRevisionInProgress=57
11:40:56.611 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 63.43%
11:40:56.611 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 74.63%
11:40:56.622 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=ce4a4408-73d2-4f14-9d69-145061033e0e remoteRepositoryId=e0b57b2e-7604-4794-baaf-63ddadd33f09 localRepositoryRevisionSynced=57
11:40:56.636 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=e0b57b2e-7604-4794-baaf-63ddadd33f09 remoteRepositoryId=ce4a4408-73d2-4f14-9d69-145061033e0e transaction.localRevision=5 remoteFromLocalRevision=57
11:40:56.642 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDown: fromID=e0b57b2e-7604-4794-baaf-63ddadd33f09 from='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/k0kshb13-trz4k-remote' toID=ce4a4408-73d2-4f14-9d69-145061033e0e to='target/repo/k0kshb12-f83jf-local', fromRepoLocalSync=false
11:40:56.656 [main] INFO c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=e0b57b2e-7604-4794-baaf-63ddadd33f09 remoteRepositoryId=ce4a4408-73d2-4f14-9d69-145061033e0e localRepositoryRevisionSynced=2 localRepositoryRevisionInProgress=5
11:40:56.660 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 88.31%
11:40:56.660 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 99.50%
11:40:56.665 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=e0b57b2e-7604-4794-baaf-63ddadd33f09 remoteRepositoryId=ce4a4408-73d2-4f14-9d69-145061033e0e localRepositoryRevisionSynced=5
11:40:56.676 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=ce4a4408-73d2-4f14-9d69-145061033e0e remoteRepositoryId=e0b57b2e-7604-4794-baaf-63ddadd33f09 transaction.localRevision=60 remoteFromLocalRevision=5
11:40:56.682 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 100.00%
11:40:56.699 [main] INFO c.c.c.l.LocalRepoManagerImpl - [182fcda5]getCloseDeferredMillis: closeDeferredMillis=20000
11:40:56.699 [main] INFO c.c.c.l.LocalRepoManagerImpl - [182fcda5]close: Deferring shut down of real LocalRepoManager 20000 ms.
11:40:56.700 [main] INFO c.c.c.l.LocalRepoManagerImpl - [2d32d675]getCloseDeferredMillis: closeDeferredMillis=20000
11:40:56.700 [main] INFO c.c.c.l.LocalRepoManagerImpl - [2d32d675]close: Deferring shut down of real LocalRepoManager 20000 ms.
11:40:56.769 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 1.00%
11:40:56.770 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 50.00%
11:40:56.771 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 58.17%
11:40:56.774 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 63.61%
11:40:56.778 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 74.50%
11:40:56.819 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 80.62%
11:40:56.822 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 90.83%
11:40:56.840 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 96.55%
11:40:56.858 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 100.00%
11:40:56.883 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 0.50%
11:40:56.884 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDown: fromID=e0b57b2e-7604-4794-baaf-63ddadd33f09 from='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/k0kshb13-trz4k-remote' toID=ce4a4408-73d2-4f14-9d69-145061033e0e to='target/repo/k0kshb12-f83jf-local', fromRepoLocalSync=true
11:40:56.884 [pool-34-thread-1] INFO c.c.c.c.r.s.RepoToRepoSync - sync: locally syncing ce4a4408-73d2-4f14-9d69-145061033e0e ('target/repo/k0kshb12-f83jf-local')
11:40:56.889 [pool-34-thread-1] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 12.94%
11:40:56.904 [pool-34-thread-1] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 19.03%
11:40:56.922 [pool-34-thread-1] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 24.11%
11:40:56.936 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 1.00%
11:40:56.937 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 50.00%
11:40:56.938 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 58.17%
11:40:56.943 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 63.61%
11:40:56.948 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 74.50%
11:40:56.960 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 80.62%
11:40:56.964 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 90.83%
11:40:56.969 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 96.55%
11:40:56.978 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 100.00%
11:40:56.988 [main] INFO c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=e0b57b2e-7604-4794-baaf-63ddadd33f09 remoteRepositoryId=ce4a4408-73d2-4f14-9d69-145061033e0e localRepositoryRevisionSynced=5 localRepositoryRevisionInProgress=8
11:40:57.004 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 29.95%
11:40:57.004 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path='/2/1'
11:40:57.076 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path='/2/1/5'
11:40:57.093 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path='/3'
11:40:57.109 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 35.11%
11:40:57.111 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 40.28%
11:40:57.111 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 45.45%
11:40:57.112 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/2/1/5/aaa'
11:40:57.123 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='56cb89a655c8d1edd0f831fdf2cc374607bf9a9f' to.sha1='<NoInstanceOf_NormalFileDto>'): /2/1/5/aaa
11:40:57.143 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=11, fromRepositoryId="FileInProgressMarker", toRepositoryId="11", path="e0b57b2e-7604-4794-baaf-63ddadd33f09"] on repo=ce4a4408-73d2-4f14-9d69-145061033e0e
11:40:57.149 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/2/1/5/aaa'
11:40:57.149 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='56cb89a655c8d1edd0f831fdf2cc374607bf9a9f' toSha1='null'
11:40:57.149 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/2/1/5/aaa'
11:40:57.149 [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
11:40:57.150 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/2/1/5/aaa' offset=0
11:40:57.161 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 65484 bytes in 12 ms. path='/2/1/5/aaa'
11:40:57.167 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_7e240de74fb1ed08fa08d38063f6a6a91462a815_0 into destFile aaa
11:40:57.182 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=11, fromRepositoryId="FileInProgressMarker", toRepositoryId="11", path="e0b57b2e-7604-4794-baaf-63ddadd33f09"] on repo=ce4a4408-73d2-4f14-9d69-145061033e0e
11:40:57.190 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/2/1/5/bbb'
11:40:57.207 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='d204d99fe623301d131e784efae258c1ac2a397b' to.sha1='<NoInstanceOf_NormalFileDto>'): /2/1/5/bbb
11:40:57.227 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=12, fromRepositoryId="FileInProgressMarker", toRepositoryId="12", path="e0b57b2e-7604-4794-baaf-63ddadd33f09"] on repo=ce4a4408-73d2-4f14-9d69-145061033e0e
11:40:57.232 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/2/1/5/bbb'
11:40:57.232 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='d204d99fe623301d131e784efae258c1ac2a397b' toSha1='null'
11:40:57.232 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/2/1/5/bbb'
11:40:57.232 [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
11:40:57.240 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/2/1/5/bbb' offset=0
11:40:57.254 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 661520 bytes in 22 ms. path='/2/1/5/bbb'
11:40:57.260 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_5cb138284d431abd6a053a56625ec088bfb88912_0 into destFile bbb
11:40:57.297 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=12, fromRepositoryId="FileInProgressMarker", toRepositoryId="12", path="e0b57b2e-7604-4794-baaf-63ddadd33f09"] on repo=ce4a4408-73d2-4f14-9d69-145061033e0e
11:40:57.304 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/3/e'
11:40:57.311 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='ed9e254c168e6fe67dd4380da66660f05d18cbc7' to.sha1='<NoInstanceOf_NormalFileDto>'): /3/e
11:40:57.326 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=13, fromRepositoryId="FileInProgressMarker", toRepositoryId="13", path="e0b57b2e-7604-4794-baaf-63ddadd33f09"] on repo=ce4a4408-73d2-4f14-9d69-145061033e0e
11:40:57.331 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/3/e'
11:40:57.331 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='ed9e254c168e6fe67dd4380da66660f05d18cbc7' toSha1='null'
11:40:57.331 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/3/e'
11:40:57.331 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/3/e' offset=0
11:40:57.336 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/3/e' offset=0
11:40:57.345 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 427556 bytes in 14 ms. path='/3/e'
11:40:57.349 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_58e6b3a414a1e090dfc6029add0f3555ccba127f_0 into destFile e
11:40:57.378 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=13, fromRepositoryId="FileInProgressMarker", toRepositoryId="13", path="e0b57b2e-7604-4794-baaf-63ddadd33f09"] on repo=ce4a4408-73d2-4f14-9d69-145061033e0e
11:40:57.394 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=e0b57b2e-7604-4794-baaf-63ddadd33f09 remoteRepositoryId=ce4a4408-73d2-4f14-9d69-145061033e0e localRepositoryRevisionSynced=8
11:40:57.403 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=ce4a4408-73d2-4f14-9d69-145061033e0e remoteRepositoryId=e0b57b2e-7604-4794-baaf-63ddadd33f09 transaction.localRevision=79 remoteFromLocalRevision=8
11:40:57.413 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncUp: fromID=ce4a4408-73d2-4f14-9d69-145061033e0e from='target/repo/k0kshb12-f83jf-local' toID=e0b57b2e-7604-4794-baaf-63ddadd33f09 to='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/k0kshb13-trz4k-remote'
11:40:57.434 [main] INFO c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=ce4a4408-73d2-4f14-9d69-145061033e0e remoteRepositoryId=e0b57b2e-7604-4794-baaf-63ddadd33f09 localRepositoryRevisionSynced=57 localRepositoryRevisionInProgress=79
11:40:57.437 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 52.24%
11:40:57.437 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 63.43%
11:40:57.437 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 74.63%
11:40:57.441 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=ce4a4408-73d2-4f14-9d69-145061033e0e remoteRepositoryId=e0b57b2e-7604-4794-baaf-63ddadd33f09 localRepositoryRevisionSynced=79
11:40:57.448 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=e0b57b2e-7604-4794-baaf-63ddadd33f09 remoteRepositoryId=ce4a4408-73d2-4f14-9d69-145061033e0e transaction.localRevision=11 remoteFromLocalRevision=79
11:40:57.454 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDown: fromID=e0b57b2e-7604-4794-baaf-63ddadd33f09 from='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/k0kshb13-trz4k-remote' toID=ce4a4408-73d2-4f14-9d69-145061033e0e to='target/repo/k0kshb12-f83jf-local', fromRepoLocalSync=false
11:40:57.469 [main] INFO c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=e0b57b2e-7604-4794-baaf-63ddadd33f09 remoteRepositoryId=ce4a4408-73d2-4f14-9d69-145061033e0e localRepositoryRevisionSynced=8 localRepositoryRevisionInProgress=11
11:40:57.475 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 88.31%
11:40:57.476 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 99.50%
11:40:57.479 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=e0b57b2e-7604-4794-baaf-63ddadd33f09 remoteRepositoryId=ce4a4408-73d2-4f14-9d69-145061033e0e localRepositoryRevisionSynced=11
11:40:57.486 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=ce4a4408-73d2-4f14-9d69-145061033e0e remoteRepositoryId=e0b57b2e-7604-4794-baaf-63ddadd33f09 transaction.localRevision=82 remoteFromLocalRevision=11
11:40:57.492 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 100.00%
11:40:57.492 [main] INFO c.c.c.l.LocalRepoManagerImpl - [182fcda5]close: Deferring shut down of real LocalRepoManager 20000 ms.
11:40:57.512 [main] INFO c.c.c.l.LocalRepoManagerImpl - [2d32d675]close: Deferring shut down of real LocalRepoManager 20000 ms.
11:40:57.587 [main] INFO c.c.c.l.LocalRepoManagerImpl - [706fee77]<init>: localRoot='/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/k0kshmya-9rvea-local'
11:40:57.593 [main] INFO c.c.c.l.LocalRepoManagerImpl - [706fee77]initLockFile: Repository '/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/k0kshmya-9rvea-local' locked successfully.
11:41:01.401 [main] INFO c.c.c.l.LocalRepoManagerImpl - [706fee77]initPersistenceManagerFactory: repositoryId=98150f2d-603e-4f61-907a-9f667216b2e6
11:41:01.406 [main] INFO c.c.c.l.LocalRepoManagerImpl - [706fee77]initPersistenceManagerFactory: Started up PersistenceManagerFactory successfully in 1914 ms.
11:41:01.429 [main] INFO c.c.c.l.LocalRepoManagerImpl - [3325a466]<init>: localRoot='/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/k0kshmya-2mbx2-remote'
11:41:01.435 [main] INFO c.c.c.l.LocalRepoManagerImpl - [3325a466]initLockFile: Repository '/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/k0kshmya-2mbx2-remote' locked successfully.
11:41:02.094 [closeDeferredTimer-7b802fba-2] INFO c.c.c.l.LocalRepoManagerImpl - [7b802fba]_close: Shutting down real LocalRepoManager.
11:41:02.427 [closeDeferredTimer-1eb5eff8-2] INFO c.c.c.l.LocalRepoManagerImpl - [1eb5eff8]_close: Shutting down real LocalRepoManager.
11:41:06.472 [main] INFO c.c.c.l.LocalRepoManagerImpl - [3325a466]initPersistenceManagerFactory: repositoryId=b0933a3a-b6b7-437f-b7fe-152dcfc00a07
11:41:06.478 [main] INFO c.c.c.l.LocalRepoManagerImpl - [3325a466]initPersistenceManagerFactory: Started up PersistenceManagerFactory successfully in 2004 ms.
11:41:06.568 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 1.00%
11:41:06.574 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 50.00%
11:41:06.582 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 58.00%
11:41:06.622 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 63.61%
11:41:06.634 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 74.34%
11:41:06.663 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 79.45%
11:41:06.678 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 90.67%
11:41:06.694 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 95.91%
11:41:06.716 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 100.00%
11:41:06.732 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - local repo: 98150f2d-603e-4f61-907a-9f667216b2e6
11:41:06.732 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - remote repo: b0933a3a-b6b7-437f-b7fe-152dcfc00a07
11:41:06.736 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 0.50%
11:41:06.737 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDown: fromID=b0933a3a-b6b7-437f-b7fe-152dcfc00a07 from='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/k0kshmya-2mbx2-remote' toID=98150f2d-603e-4f61-907a-9f667216b2e6 to='target/repo/k0kshmya-9rvea-local', fromRepoLocalSync=true
11:41:06.737 [pool-35-thread-1] INFO c.c.c.c.r.s.RepoToRepoSync - sync: locally syncing 98150f2d-603e-4f61-907a-9f667216b2e6 ('target/repo/k0kshmya-9rvea-local')
11:41:06.761 [pool-35-thread-1] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 12.94%
11:41:06.768 [pool-35-thread-1] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 25.12%
11:41:06.769 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 1.00%
11:41:06.770 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 50.00%
11:41:06.771 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 58.17%
11:41:06.774 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 63.61%
11:41:06.778 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 74.50%
11:41:06.783 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 80.62%
11:41:06.786 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 90.83%
11:41:06.790 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 95.94%
11:41:06.795 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 100.00%
11:41:06.822 [main] INFO c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=b0933a3a-b6b7-437f-b7fe-152dcfc00a07 remoteRepositoryId=98150f2d-603e-4f61-907a-9f667216b2e6 localRepositoryRevisionSynced=-1 localRepositoryRevisionInProgress=2
11:41:06.854 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path=''
11:41:06.882 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path='/1'
11:41:06.901 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 30.22%
11:41:06.901 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path='/2'
11:41:06.911 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path='/2/1'
11:41:06.927 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path='/3'
11:41:06.946 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 35.46%
11:41:06.946 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 40.70%
11:41:06.946 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/1/a'
11:41:06.956 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='78446930a1ef9f28d7086795d4054c6c3454bdb2' to.sha1='<NoInstanceOf_NormalFileDto>'): /1/a
11:41:07.000 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=1, fromRepositoryId="FileInProgressMarker", toRepositoryId="1", path="b0933a3a-b6b7-437f-b7fe-152dcfc00a07"] on repo=98150f2d-603e-4f61-907a-9f667216b2e6
11:41:07.005 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/1/a'
11:41:07.005 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='78446930a1ef9f28d7086795d4054c6c3454bdb2' toSha1='null'
11:41:07.005 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/1/a'
11:41:07.005 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/1/a' offset=0
11:41:07.007 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/1/a' offset=0
11:41:07.018 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 119700 bytes in 13 ms. path='/1/a'
11:41:07.022 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_86f7e437faa5a7fce15d1ddcb9eaeaea377667b8_0 into destFile a
11:41:07.057 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=1, fromRepositoryId="FileInProgressMarker", toRepositoryId="1", path="b0933a3a-b6b7-437f-b7fe-152dcfc00a07"] on repo=98150f2d-603e-4f61-907a-9f667216b2e6
11:41:07.067 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/1/b'
11:41:07.080 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='8d568eef1dccc2052adf657060947687c2bcc906' to.sha1='<NoInstanceOf_NormalFileDto>'): /1/b
11:41:07.093 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=2, fromRepositoryId="FileInProgressMarker", toRepositoryId="2", path="b0933a3a-b6b7-437f-b7fe-152dcfc00a07"] on repo=98150f2d-603e-4f61-907a-9f667216b2e6
11:41:07.098 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/1/b'
11:41:07.098 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='8d568eef1dccc2052adf657060947687c2bcc906' toSha1='null'
11:41:07.098 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/1/b'
11:41:07.098 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/1/b' offset=0
11:41:07.099 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/1/b' offset=0
11:41:07.106 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 53218 bytes in 8 ms. path='/1/b'
11:41:07.113 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_e9d71f5ee7c92d6dc9e92ffdad17b8bd49418f98_0 into destFile b
11:41:07.128 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=2, fromRepositoryId="FileInProgressMarker", toRepositoryId="2", path="b0933a3a-b6b7-437f-b7fe-152dcfc00a07"] on repo=98150f2d-603e-4f61-907a-9f667216b2e6
11:41:07.139 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/1/c'
11:41:07.152 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='cc5cbd433cfb5596fa45b2cf0b4582cdeefceff9' to.sha1='<NoInstanceOf_NormalFileDto>'): /1/c
11:41:07.167 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=3, fromRepositoryId="FileInProgressMarker", toRepositoryId="3", path="b0933a3a-b6b7-437f-b7fe-152dcfc00a07"] on repo=98150f2d-603e-4f61-907a-9f667216b2e6
11:41:07.171 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/1/c'
11:41:07.171 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='cc5cbd433cfb5596fa45b2cf0b4582cdeefceff9' toSha1='null'
11:41:07.171 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/1/c'
11:41:07.171 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/1/c' offset=0
11:41:07.173 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/1/c' offset=0
11:41:07.184 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 127344 bytes in 13 ms. path='/1/c'
11:41:07.188 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_84a516841ba77a5b4648de2cd0dfcb30ea46dbb4_0 into destFile c
11:41:07.203 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=3, fromRepositoryId="FileInProgressMarker", toRepositoryId="3", path="b0933a3a-b6b7-437f-b7fe-152dcfc00a07"] on repo=98150f2d-603e-4f61-907a-9f667216b2e6
11:41:07.210 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/2/1/a'
11:41:07.223 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='e8593c02978c10e42342129423a3a0ea655dc463' to.sha1='<NoInstanceOf_NormalFileDto>'): /2/1/a
11:41:07.238 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=4, fromRepositoryId="FileInProgressMarker", toRepositoryId="4", path="b0933a3a-b6b7-437f-b7fe-152dcfc00a07"] on repo=98150f2d-603e-4f61-907a-9f667216b2e6
11:41:07.243 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/2/1/a'
11:41:07.243 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='e8593c02978c10e42342129423a3a0ea655dc463' toSha1='null'
11:41:07.243 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/2/1/a'
11:41:07.243 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/2/1/a' offset=0
11:41:07.245 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/2/1/a' offset=0
11:41:07.259 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 122976 bytes in 16 ms. path='/2/1/a'
11:41:07.264 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_86f7e437faa5a7fce15d1ddcb9eaeaea377667b8_0 into destFile a
11:41:07.280 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=4, fromRepositoryId="FileInProgressMarker", toRepositoryId="4", path="b0933a3a-b6b7-437f-b7fe-152dcfc00a07"] on repo=98150f2d-603e-4f61-907a-9f667216b2e6
11:41:07.287 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/2/1/b'
11:41:07.299 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='6f4cb001a8b916626e92ed7a6225c8c913811071' to.sha1='<NoInstanceOf_NormalFileDto>'): /2/1/b
11:41:07.317 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=5, fromRepositoryId="FileInProgressMarker", toRepositoryId="5", path="b0933a3a-b6b7-437f-b7fe-152dcfc00a07"] on repo=98150f2d-603e-4f61-907a-9f667216b2e6
11:41:07.321 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/2/1/b'
11:41:07.321 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='6f4cb001a8b916626e92ed7a6225c8c913811071' toSha1='null'
11:41:07.321 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/2/1/b'
11:41:07.321 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/2/1/b' offset=0
11:41:07.325 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/2/1/b' offset=0
11:41:07.338 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 282492 bytes in 17 ms. path='/2/1/b'
11:41:07.343 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_e9d71f5ee7c92d6dc9e92ffdad17b8bd49418f98_0 into destFile b
11:41:07.365 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=5, fromRepositoryId="FileInProgressMarker", toRepositoryId="5", path="b0933a3a-b6b7-437f-b7fe-152dcfc00a07"] on repo=98150f2d-603e-4f61-907a-9f667216b2e6
11:41:07.373 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/2/a'
11:41:07.383 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='33e3a4b1c7532cfe283c0e5899e37a87b56d1da0' to.sha1='<NoInstanceOf_NormalFileDto>'): /2/a
11:41:07.398 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=6, fromRepositoryId="FileInProgressMarker", toRepositoryId="6", path="b0933a3a-b6b7-437f-b7fe-152dcfc00a07"] on repo=98150f2d-603e-4f61-907a-9f667216b2e6
11:41:07.402 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/2/a'
11:41:07.403 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='33e3a4b1c7532cfe283c0e5899e37a87b56d1da0' toSha1='null'
11:41:07.403 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/2/a'
11:41:07.403 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/2/a' offset=0
11:41:07.408 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/2/a' offset=0
11:41:07.418 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 300297 bytes in 15 ms. path='/2/a'
11:41:07.422 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_86f7e437faa5a7fce15d1ddcb9eaeaea377667b8_0 into destFile a
11:41:07.445 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=6, fromRepositoryId="FileInProgressMarker", toRepositoryId="6", path="b0933a3a-b6b7-437f-b7fe-152dcfc00a07"] on repo=98150f2d-603e-4f61-907a-9f667216b2e6
11:41:07.450 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/3/a'
11:41:07.462 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='e4850823e35e4e8d84b335b5d1ac74e99bfb6c36' to.sha1='<NoInstanceOf_NormalFileDto>'): /3/a
11:41:07.477 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=7, fromRepositoryId="FileInProgressMarker", toRepositoryId="7", path="b0933a3a-b6b7-437f-b7fe-152dcfc00a07"] on repo=98150f2d-603e-4f61-907a-9f667216b2e6
11:41:07.481 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/3/a'
11:41:07.481 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='e4850823e35e4e8d84b335b5d1ac74e99bfb6c36' toSha1='null'
11:41:07.482 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/3/a'
11:41:07.482 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/3/a' offset=0
11:41:07.485 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/3/a' offset=0
11:41:07.497 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 207669 bytes in 15 ms. path='/3/a'
11:41:07.502 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_86f7e437faa5a7fce15d1ddcb9eaeaea377667b8_0 into destFile a
11:41:07.523 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=7, fromRepositoryId="FileInProgressMarker", toRepositoryId="7", path="b0933a3a-b6b7-437f-b7fe-152dcfc00a07"] on repo=98150f2d-603e-4f61-907a-9f667216b2e6
11:41:07.529 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/3/b'
11:41:07.540 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='478065c5f11f43fcd7f7b3c07f8e274ce9f4226b' to.sha1='<NoInstanceOf_NormalFileDto>'): /3/b
11:41:07.553 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=8, fromRepositoryId="FileInProgressMarker", toRepositoryId="8", path="b0933a3a-b6b7-437f-b7fe-152dcfc00a07"] on repo=98150f2d-603e-4f61-907a-9f667216b2e6
11:41:07.557 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/3/b'
11:41:07.557 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='478065c5f11f43fcd7f7b3c07f8e274ce9f4226b' toSha1='null'
11:41:07.557 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/3/b'
11:41:07.557 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/3/b' offset=0
11:41:07.559 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/3/b' offset=0
11:41:07.566 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 136995 bytes in 9 ms. path='/3/b'
11:41:07.570 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_e9d71f5ee7c92d6dc9e92ffdad17b8bd49418f98_0 into destFile b
11:41:07.588 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=8, fromRepositoryId="FileInProgressMarker", toRepositoryId="8", path="b0933a3a-b6b7-437f-b7fe-152dcfc00a07"] on repo=98150f2d-603e-4f61-907a-9f667216b2e6
11:41:07.594 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/3/c'
11:41:07.606 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='8df2ff99e11c1396969899dea085aca1a41aa635' to.sha1='<NoInstanceOf_NormalFileDto>'): /3/c
11:41:07.621 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=9, fromRepositoryId="FileInProgressMarker", toRepositoryId="9", path="b0933a3a-b6b7-437f-b7fe-152dcfc00a07"] on repo=98150f2d-603e-4f61-907a-9f667216b2e6
11:41:07.626 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/3/c'
11:41:07.626 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='8df2ff99e11c1396969899dea085aca1a41aa635' toSha1='null'
11:41:07.626 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/3/c'
11:41:07.626 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/3/c' offset=0
11:41:07.626 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/3/c' offset=0
11:41:07.634 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 27594 bytes in 8 ms. path='/3/c'
11:41:07.642 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_84a516841ba77a5b4648de2cd0dfcb30ea46dbb4_0 into destFile c
11:41:07.654 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=9, fromRepositoryId="FileInProgressMarker", toRepositoryId="9", path="b0933a3a-b6b7-437f-b7fe-152dcfc00a07"] on repo=98150f2d-603e-4f61-907a-9f667216b2e6
11:41:07.661 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/3/d'
11:41:07.673 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='33f56416ecdc24d950e091cb4327276c433a6af5' to.sha1='<NoInstanceOf_NormalFileDto>'): /3/d
11:41:07.688 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=10, fromRepositoryId="FileInProgressMarker", toRepositoryId="10", path="b0933a3a-b6b7-437f-b7fe-152dcfc00a07"] on repo=98150f2d-603e-4f61-907a-9f667216b2e6
11:41:07.692 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/3/d'
11:41:07.692 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='33f56416ecdc24d950e091cb4327276c433a6af5' toSha1='null'
11:41:07.692 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/3/d'
11:41:07.692 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/3/d' offset=0
11:41:07.692 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/3/d' offset=0
11:41:07.703 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 8910 bytes in 11 ms. path='/3/d'
11:41:07.708 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_3c363836cf4e16666669a25da280a1865c2d2874_0 into destFile d
11:41:07.719 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=10, fromRepositoryId="FileInProgressMarker", toRepositoryId="10", path="b0933a3a-b6b7-437f-b7fe-152dcfc00a07"] on repo=98150f2d-603e-4f61-907a-9f667216b2e6
11:41:07.725 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 49.27%
11:41:07.746 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=b0933a3a-b6b7-437f-b7fe-152dcfc00a07 remoteRepositoryId=98150f2d-603e-4f61-907a-9f667216b2e6 localRepositoryRevisionSynced=2
11:41:07.761 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=98150f2d-603e-4f61-907a-9f667216b2e6 remoteRepositoryId=b0933a3a-b6b7-437f-b7fe-152dcfc00a07 transaction.localRevision=57 remoteFromLocalRevision=2
11:41:07.769 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncUp: fromID=98150f2d-603e-4f61-907a-9f667216b2e6 from='target/repo/k0kshmya-9rvea-local' toID=b0933a3a-b6b7-437f-b7fe-152dcfc00a07 to='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/k0kshmya-2mbx2-remote'
11:41:07.798 [main] INFO c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=98150f2d-603e-4f61-907a-9f667216b2e6 remoteRepositoryId=b0933a3a-b6b7-437f-b7fe-152dcfc00a07 localRepositoryRevisionSynced=-1 localRepositoryRevisionInProgress=57
11:41:07.815 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 63.43%
11:41:07.815 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 74.63%
11:41:07.825 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=98150f2d-603e-4f61-907a-9f667216b2e6 remoteRepositoryId=b0933a3a-b6b7-437f-b7fe-152dcfc00a07 localRepositoryRevisionSynced=57
11:41:07.837 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=b0933a3a-b6b7-437f-b7fe-152dcfc00a07 remoteRepositoryId=98150f2d-603e-4f61-907a-9f667216b2e6 transaction.localRevision=5 remoteFromLocalRevision=57
11:41:07.844 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDown: fromID=b0933a3a-b6b7-437f-b7fe-152dcfc00a07 from='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/k0kshmya-2mbx2-remote' toID=98150f2d-603e-4f61-907a-9f667216b2e6 to='target/repo/k0kshmya-9rvea-local', fromRepoLocalSync=false
11:41:07.863 [main] INFO c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=b0933a3a-b6b7-437f-b7fe-152dcfc00a07 remoteRepositoryId=98150f2d-603e-4f61-907a-9f667216b2e6 localRepositoryRevisionSynced=2 localRepositoryRevisionInProgress=5
11:41:07.865 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 88.31%
11:41:07.865 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 99.50%
11:41:07.868 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=b0933a3a-b6b7-437f-b7fe-152dcfc00a07 remoteRepositoryId=98150f2d-603e-4f61-907a-9f667216b2e6 localRepositoryRevisionSynced=5
11:41:07.879 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=98150f2d-603e-4f61-907a-9f667216b2e6 remoteRepositoryId=b0933a3a-b6b7-437f-b7fe-152dcfc00a07 transaction.localRevision=60 remoteFromLocalRevision=5
11:41:07.884 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 100.00%
11:41:07.900 [main] INFO c.c.c.l.LocalRepoManagerImpl - [706fee77]getCloseDeferredMillis: closeDeferredMillis=20000
11:41:07.900 [main] INFO c.c.c.l.LocalRepoManagerImpl - [706fee77]close: Deferring shut down of real LocalRepoManager 20000 ms.
11:41:07.901 [main] INFO c.c.c.l.LocalRepoManagerImpl - [3325a466]getCloseDeferredMillis: closeDeferredMillis=20000
11:41:07.901 [main] INFO c.c.c.l.LocalRepoManagerImpl - [3325a466]close: Deferring shut down of real LocalRepoManager 20000 ms.
11:41:07.934 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 1.00%
11:41:07.935 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 50.00%
11:41:07.937 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 58.17%
11:41:07.940 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 63.61%
11:41:07.943 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 74.50%
11:41:07.988 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 80.62%
11:41:07.994 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 90.83%
11:41:07.998 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 95.94%
11:41:08.010 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 100.00%
11:41:08.033 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 0.50%
11:41:08.034 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDown: fromID=b0933a3a-b6b7-437f-b7fe-152dcfc00a07 from='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/k0kshmya-2mbx2-remote' toID=98150f2d-603e-4f61-907a-9f667216b2e6 to='target/repo/k0kshmya-9rvea-local', fromRepoLocalSync=true
11:41:08.034 [pool-36-thread-1] INFO c.c.c.c.r.s.RepoToRepoSync - sync: locally syncing 98150f2d-603e-4f61-907a-9f667216b2e6 ('target/repo/k0kshmya-9rvea-local')
11:41:08.040 [pool-36-thread-1] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 12.94%
11:41:08.055 [pool-36-thread-1] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 19.03%
11:41:08.076 [pool-36-thread-1] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 24.11%
11:41:08.089 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 1.00%
11:41:08.089 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 50.00%
11:41:08.091 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 58.17%
11:41:08.096 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 63.61%
11:41:08.099 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 74.50%
11:41:08.103 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 80.62%
11:41:08.107 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 90.83%
11:41:08.110 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 95.94%
11:41:08.116 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 100.00%
11:41:08.129 [main] INFO c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=b0933a3a-b6b7-437f-b7fe-152dcfc00a07 remoteRepositoryId=98150f2d-603e-4f61-907a-9f667216b2e6 localRepositoryRevisionSynced=5 localRepositoryRevisionInProgress=8
11:41:08.149 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 29.23%
11:41:08.149 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path='/2/1'
11:41:08.187 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 34.83%
11:41:08.189 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncModifications: Deleting '/2/1/a'
11:41:08.235 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 40.42%
11:41:08.235 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 46.02%
11:41:08.257 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=b0933a3a-b6b7-437f-b7fe-152dcfc00a07 remoteRepositoryId=98150f2d-603e-4f61-907a-9f667216b2e6 localRepositoryRevisionSynced=8
11:41:08.267 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=98150f2d-603e-4f61-907a-9f667216b2e6 remoteRepositoryId=b0933a3a-b6b7-437f-b7fe-152dcfc00a07 transaction.localRevision=63 remoteFromLocalRevision=8
11:41:08.278 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncUp: fromID=98150f2d-603e-4f61-907a-9f667216b2e6 from='target/repo/k0kshmya-9rvea-local' toID=b0933a3a-b6b7-437f-b7fe-152dcfc00a07 to='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/k0kshmya-2mbx2-remote'
11:41:08.294 [main] INFO c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=98150f2d-603e-4f61-907a-9f667216b2e6 remoteRepositoryId=b0933a3a-b6b7-437f-b7fe-152dcfc00a07 localRepositoryRevisionSynced=57 localRepositoryRevisionInProgress=63
11:41:08.304 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 52.24%
11:41:08.304 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 59.70%
11:41:08.305 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncModifications: Deleting '/2/1/a'
11:41:08.311 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 67.16%
11:41:08.311 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 74.63%
11:41:08.328 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=98150f2d-603e-4f61-907a-9f667216b2e6 remoteRepositoryId=b0933a3a-b6b7-437f-b7fe-152dcfc00a07 localRepositoryRevisionSynced=63
11:41:08.334 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=b0933a3a-b6b7-437f-b7fe-152dcfc00a07 remoteRepositoryId=98150f2d-603e-4f61-907a-9f667216b2e6 transaction.localRevision=11 remoteFromLocalRevision=63
11:41:08.341 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDown: fromID=b0933a3a-b6b7-437f-b7fe-152dcfc00a07 from='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/k0kshmya-2mbx2-remote' toID=98150f2d-603e-4f61-907a-9f667216b2e6 to='target/repo/k0kshmya-9rvea-local', fromRepoLocalSync=false
11:41:08.357 [main] INFO c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=b0933a3a-b6b7-437f-b7fe-152dcfc00a07 remoteRepositoryId=98150f2d-603e-4f61-907a-9f667216b2e6 localRepositoryRevisionSynced=8 localRepositoryRevisionInProgress=11
11:41:08.360 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 88.31%
11:41:08.360 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 99.50%
11:41:08.363 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=b0933a3a-b6b7-437f-b7fe-152dcfc00a07 remoteRepositoryId=98150f2d-603e-4f61-907a-9f667216b2e6 localRepositoryRevisionSynced=11
11:41:08.370 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=98150f2d-603e-4f61-907a-9f667216b2e6 remoteRepositoryId=b0933a3a-b6b7-437f-b7fe-152dcfc00a07 transaction.localRevision=66 remoteFromLocalRevision=11
11:41:08.374 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 100.00%
11:41:08.374 [main] INFO c.c.c.l.LocalRepoManagerImpl - [706fee77]close: Deferring shut down of real LocalRepoManager 20000 ms.
11:41:08.390 [main] INFO c.c.c.l.LocalRepoManagerImpl - [3325a466]close: Deferring shut down of real LocalRepoManager 20000 ms.
11:41:08.417 [main] INFO c.c.c.l.LocalRepoManagerImpl - [217d3824]<init>: localRoot='/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/k0kshvb3-2i3bz-local'
11:41:08.422 [main] INFO c.c.c.l.LocalRepoManagerImpl - [217d3824]initLockFile: Repository '/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/k0kshvb3-2i3bz-local' locked successfully.
11:41:10.736 [main] INFO c.c.c.l.LocalRepoManagerImpl - [217d3824]initPersistenceManagerFactory: repositoryId=bc3e4dda-aeca-4c72-8eb6-eab3f4b5fa00
11:41:10.738 [main] INFO c.c.c.l.LocalRepoManagerImpl - [217d3824]initPersistenceManagerFactory: Started up PersistenceManagerFactory successfully in 1349 ms.
11:41:10.756 [main] INFO c.c.c.l.LocalRepoManagerImpl - [36d4cd5f]<init>: localRoot='/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/k0kshvb4-646fv-remote'
11:41:10.761 [main] INFO c.c.c.l.LocalRepoManagerImpl - [36d4cd5f]initLockFile: Repository '/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/k0kshvb4-646fv-remote' locked successfully.
11:41:15.576 [main] INFO c.c.c.l.LocalRepoManagerImpl - [36d4cd5f]initPersistenceManagerFactory: repositoryId=7e1fe0dd-bf9a-4013-bc6c-a171335db902
11:41:15.582 [main] INFO c.c.c.l.LocalRepoManagerImpl - [36d4cd5f]initPersistenceManagerFactory: Started up PersistenceManagerFactory successfully in 2314 ms.
11:41:15.688 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 1.00%
11:41:15.694 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 50.00%
11:41:15.704 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 58.00%
11:41:15.747 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 63.61%
11:41:15.772 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 74.34%
11:41:15.806 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 80.56%
11:41:15.817 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 90.67%
11:41:15.842 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 95.71%
11:41:15.873 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 100.00%
11:41:15.899 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - local repo: bc3e4dda-aeca-4c72-8eb6-eab3f4b5fa00
11:41:15.899 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - remote repo: 7e1fe0dd-bf9a-4013-bc6c-a171335db902
11:41:15.903 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 0.50%
11:41:15.904 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDown: fromID=7e1fe0dd-bf9a-4013-bc6c-a171335db902 from='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/k0kshvb4-646fv-remote/2' toID=bc3e4dda-aeca-4c72-8eb6-eab3f4b5fa00 to='target/repo/k0kshvb3-2i3bz-local', fromRepoLocalSync=true
11:41:15.904 [pool-37-thread-1] INFO c.c.c.c.r.s.RepoToRepoSync - sync: locally syncing bc3e4dda-aeca-4c72-8eb6-eab3f4b5fa00 ('target/repo/k0kshvb3-2i3bz-local')
11:41:15.924 [pool-37-thread-1] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 12.94%
11:41:15.931 [pool-37-thread-1] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 25.12%
11:41:15.932 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 1.00%
11:41:15.935 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 50.00%
11:41:15.939 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 58.17%
11:41:15.947 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 63.61%
11:41:15.950 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 74.50%
11:41:15.956 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 80.62%
11:41:15.959 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 90.83%
11:41:15.963 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 95.94%
11:41:15.969 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 100.00%
11:41:16.005 [main] INFO c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=7e1fe0dd-bf9a-4013-bc6c-a171335db902 remoteRepositoryId=bc3e4dda-aeca-4c72-8eb6-eab3f4b5fa00 localRepositoryRevisionSynced=-1 localRepositoryRevisionInProgress=2
11:41:16.055 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path=''
11:41:16.085 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path='/1'
11:41:16.098 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 31.31%
11:41:16.105 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 36.58%
11:41:16.105 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 41.85%
11:41:16.105 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/1/a'
11:41:16.118 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='7db9830ba97d61c392922778feae28d691102655' to.sha1='<NoInstanceOf_NormalFileDto>'): /1/a
11:41:16.171 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=1, fromRepositoryId="FileInProgressMarker", toRepositoryId="1", path="7e1fe0dd-bf9a-4013-bc6c-a171335db902"] on repo=bc3e4dda-aeca-4c72-8eb6-eab3f4b5fa00
11:41:16.173 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/1/a'
11:41:16.173 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='7db9830ba97d61c392922778feae28d691102655' toSha1='null'
11:41:16.173 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/1/a'
11:41:16.173 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/1/a' offset=0
11:41:16.180 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/1/a' offset=0
11:41:16.196 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 460435 bytes in 23 ms. path='/1/a'
11:41:16.202 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_86f7e437faa5a7fce15d1ddcb9eaeaea377667b8_0 into destFile a
11:41:16.296 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=1, fromRepositoryId="FileInProgressMarker", toRepositoryId="1", path="7e1fe0dd-bf9a-4013-bc6c-a171335db902"] on repo=bc3e4dda-aeca-4c72-8eb6-eab3f4b5fa00
11:41:16.306 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/1/b'
11:41:16.318 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='4bd04e37beade470a48060a7efdf6f33fa857bbf' to.sha1='<NoInstanceOf_NormalFileDto>'): /1/b
11:41:16.335 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=2, fromRepositoryId="FileInProgressMarker", toRepositoryId="2", path="7e1fe0dd-bf9a-4013-bc6c-a171335db902"] on repo=bc3e4dda-aeca-4c72-8eb6-eab3f4b5fa00
11:41:16.337 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/1/b'
11:41:16.337 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='4bd04e37beade470a48060a7efdf6f33fa857bbf' toSha1='null'
11:41:16.337 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/1/b'
11:41:16.337 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/1/b' offset=0
11:41:16.340 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/1/b' offset=0
11:41:16.349 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 208496 bytes in 12 ms. path='/1/b'
11:41:16.354 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_e9d71f5ee7c92d6dc9e92ffdad17b8bd49418f98_0 into destFile b
11:41:16.374 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=2, fromRepositoryId="FileInProgressMarker", toRepositoryId="2", path="7e1fe0dd-bf9a-4013-bc6c-a171335db902"] on repo=bc3e4dda-aeca-4c72-8eb6-eab3f4b5fa00
11:41:16.378 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/a'
11:41:16.388 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='17ccdac3b9bfbb8fd6426d0e0457dc02afee01b6' to.sha1='<NoInstanceOf_NormalFileDto>'): /a
11:41:16.401 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=3, fromRepositoryId="FileInProgressMarker", toRepositoryId="3", path="7e1fe0dd-bf9a-4013-bc6c-a171335db902"] on repo=bc3e4dda-aeca-4c72-8eb6-eab3f4b5fa00
11:41:16.403 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/a'
11:41:16.403 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='17ccdac3b9bfbb8fd6426d0e0457dc02afee01b6' toSha1='null'
11:41:16.403 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/a'
11:41:16.403 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/a' offset=0
11:41:16.404 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/a' offset=0
11:41:16.409 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 18213 bytes in 6 ms. path='/a'
11:41:16.413 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_86f7e437faa5a7fce15d1ddcb9eaeaea377667b8_0 into destFile a
11:41:16.426 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=3, fromRepositoryId="FileInProgressMarker", toRepositoryId="3", path="7e1fe0dd-bf9a-4013-bc6c-a171335db902"] on repo=bc3e4dda-aeca-4c72-8eb6-eab3f4b5fa00
11:41:16.430 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 48.43%
11:41:16.451 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=7e1fe0dd-bf9a-4013-bc6c-a171335db902 remoteRepositoryId=bc3e4dda-aeca-4c72-8eb6-eab3f4b5fa00 localRepositoryRevisionSynced=2
11:41:16.466 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=bc3e4dda-aeca-4c72-8eb6-eab3f4b5fa00 remoteRepositoryId=7e1fe0dd-bf9a-4013-bc6c-a171335db902 transaction.localRevision=19 remoteFromLocalRevision=2
11:41:16.470 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncUp: fromID=bc3e4dda-aeca-4c72-8eb6-eab3f4b5fa00 from='target/repo/k0kshvb3-2i3bz-local' toID=7e1fe0dd-bf9a-4013-bc6c-a171335db902 to='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/k0kshvb4-646fv-remote/2'
11:41:16.494 [main] INFO c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=bc3e4dda-aeca-4c72-8eb6-eab3f4b5fa00 remoteRepositoryId=7e1fe0dd-bf9a-4013-bc6c-a171335db902 localRepositoryRevisionSynced=-1 localRepositoryRevisionInProgress=19
11:41:16.506 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 63.43%
11:41:16.507 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 74.63%
11:41:16.520 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=bc3e4dda-aeca-4c72-8eb6-eab3f4b5fa00 remoteRepositoryId=7e1fe0dd-bf9a-4013-bc6c-a171335db902 localRepositoryRevisionSynced=19
11:41:16.528 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=7e1fe0dd-bf9a-4013-bc6c-a171335db902 remoteRepositoryId=bc3e4dda-aeca-4c72-8eb6-eab3f4b5fa00 transaction.localRevision=5 remoteFromLocalRevision=19
11:41:16.534 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDown: fromID=7e1fe0dd-bf9a-4013-bc6c-a171335db902 from='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/k0kshvb4-646fv-remote/2' toID=bc3e4dda-aeca-4c72-8eb6-eab3f4b5fa00 to='target/repo/k0kshvb3-2i3bz-local', fromRepoLocalSync=false
11:41:16.550 [main] INFO c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=7e1fe0dd-bf9a-4013-bc6c-a171335db902 remoteRepositoryId=bc3e4dda-aeca-4c72-8eb6-eab3f4b5fa00 localRepositoryRevisionSynced=2 localRepositoryRevisionInProgress=5
11:41:16.555 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 88.31%
11:41:16.555 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 99.50%
11:41:16.558 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=7e1fe0dd-bf9a-4013-bc6c-a171335db902 remoteRepositoryId=bc3e4dda-aeca-4c72-8eb6-eab3f4b5fa00 localRepositoryRevisionSynced=5
11:41:16.578 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=bc3e4dda-aeca-4c72-8eb6-eab3f4b5fa00 remoteRepositoryId=7e1fe0dd-bf9a-4013-bc6c-a171335db902 transaction.localRevision=22 remoteFromLocalRevision=5
11:41:16.581 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 100.00%
11:41:16.593 [main] INFO c.c.c.l.LocalRepoManagerImpl - [217d3824]getCloseDeferredMillis: closeDeferredMillis=20000
11:41:16.593 [main] INFO c.c.c.l.LocalRepoManagerImpl - [217d3824]close: Deferring shut down of real LocalRepoManager 20000 ms.
11:41:16.594 [main] INFO c.c.c.l.LocalRepoManagerImpl - [36d4cd5f]getCloseDeferredMillis: closeDeferredMillis=20000
11:41:16.594 [main] INFO c.c.c.l.LocalRepoManagerImpl - [36d4cd5f]close: Deferring shut down of real LocalRepoManager 20000 ms.
11:41:16.610 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 1.00%
11:41:16.611 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 50.00%
11:41:16.613 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 58.17%
11:41:16.617 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 63.61%
11:41:16.637 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 74.50%
11:41:16.727 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 80.62%
11:41:16.730 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 90.83%
11:41:16.735 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 95.94%
11:41:16.770 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 100.00%
11:41:16.798 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 0.50%
11:41:16.804 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDown: fromID=7e1fe0dd-bf9a-4013-bc6c-a171335db902 from='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/k0kshvb4-646fv-remote/2' toID=bc3e4dda-aeca-4c72-8eb6-eab3f4b5fa00 to='target/repo/k0kshvb3-2i3bz-local', fromRepoLocalSync=true
11:41:16.806 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 1.00%
11:41:16.806 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 50.00%
11:41:16.808 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 58.17%
11:41:16.811 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 63.61%
11:41:16.812 [pool-38-thread-1] INFO c.c.c.c.r.s.RepoToRepoSync - sync: locally syncing bc3e4dda-aeca-4c72-8eb6-eab3f4b5fa00 ('target/repo/k0kshvb3-2i3bz-local')
11:41:16.816 [pool-38-thread-1] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 12.94%
11:41:16.823 [pool-38-thread-1] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 18.27%
11:41:16.828 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 74.50%
11:41:16.831 [pool-38-thread-1] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 25.12%
11:41:16.833 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 80.62%
11:41:16.837 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 90.83%
11:41:16.842 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 95.94%
11:41:16.849 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 100.00%
11:41:16.858 [main] INFO c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=7e1fe0dd-bf9a-4013-bc6c-a171335db902 remoteRepositoryId=bc3e4dda-aeca-4c72-8eb6-eab3f4b5fa00 localRepositoryRevisionSynced=5 localRepositoryRevisionInProgress=8
11:41:16.879 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 32.34%
11:41:16.879 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path='/1'
11:41:16.894 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncModifications: Deleting '/1/a'
11:41:16.928 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 39.80%
11:41:16.928 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 47.26%
11:41:16.947 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=7e1fe0dd-bf9a-4013-bc6c-a171335db902 remoteRepositoryId=bc3e4dda-aeca-4c72-8eb6-eab3f4b5fa00 localRepositoryRevisionSynced=8
11:41:16.957 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=bc3e4dda-aeca-4c72-8eb6-eab3f4b5fa00 remoteRepositoryId=7e1fe0dd-bf9a-4013-bc6c-a171335db902 transaction.localRevision=25 remoteFromLocalRevision=8
11:41:16.964 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncUp: fromID=bc3e4dda-aeca-4c72-8eb6-eab3f4b5fa00 from='target/repo/k0kshvb3-2i3bz-local' toID=7e1fe0dd-bf9a-4013-bc6c-a171335db902 to='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/k0kshvb4-646fv-remote/2'
11:41:16.978 [main] INFO c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=bc3e4dda-aeca-4c72-8eb6-eab3f4b5fa00 remoteRepositoryId=7e1fe0dd-bf9a-4013-bc6c-a171335db902 localRepositoryRevisionSynced=19 localRepositoryRevisionInProgress=25
11:41:16.990 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 59.70%
11:41:16.991 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncModifications: Deleting '/1/a'
11:41:16.998 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 67.16%
11:41:16.998 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 74.63%
11:41:17.022 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=bc3e4dda-aeca-4c72-8eb6-eab3f4b5fa00 remoteRepositoryId=7e1fe0dd-bf9a-4013-bc6c-a171335db902 localRepositoryRevisionSynced=25
11:41:17.030 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=7e1fe0dd-bf9a-4013-bc6c-a171335db902 remoteRepositoryId=bc3e4dda-aeca-4c72-8eb6-eab3f4b5fa00 transaction.localRevision=11 remoteFromLocalRevision=25
11:41:17.047 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDown: fromID=7e1fe0dd-bf9a-4013-bc6c-a171335db902 from='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/k0kshvb4-646fv-remote/2' toID=bc3e4dda-aeca-4c72-8eb6-eab3f4b5fa00 to='target/repo/k0kshvb3-2i3bz-local', fromRepoLocalSync=false
11:41:17.063 [main] INFO c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=7e1fe0dd-bf9a-4013-bc6c-a171335db902 remoteRepositoryId=bc3e4dda-aeca-4c72-8eb6-eab3f4b5fa00 localRepositoryRevisionSynced=8 localRepositoryRevisionInProgress=11
11:41:17.069 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 88.31%
11:41:17.069 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 99.50%
11:41:17.075 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=7e1fe0dd-bf9a-4013-bc6c-a171335db902 remoteRepositoryId=bc3e4dda-aeca-4c72-8eb6-eab3f4b5fa00 localRepositoryRevisionSynced=11
11:41:17.083 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=bc3e4dda-aeca-4c72-8eb6-eab3f4b5fa00 remoteRepositoryId=7e1fe0dd-bf9a-4013-bc6c-a171335db902 transaction.localRevision=28 remoteFromLocalRevision=11
11:41:17.085 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 100.00%
11:41:17.085 [main] INFO c.c.c.l.LocalRepoManagerImpl - [217d3824]close: Deferring shut down of real LocalRepoManager 20000 ms.
11:41:17.104 [main] INFO c.c.c.l.LocalRepoManagerImpl - [36d4cd5f]close: Deferring shut down of real LocalRepoManager 20000 ms.
11:41:17.114 [main] INFO c.c.c.l.LocalRepoManagerImpl - [c126600]<init>: localRoot='/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/k0ksi20q-dtf3d-local'
11:41:17.121 [main] INFO c.c.c.l.LocalRepoManagerImpl - [c126600]initLockFile: Repository '/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/k0ksi20q-dtf3d-local' locked successfully.
11:41:17.493 [closeDeferredTimer-182fcda5-2] INFO c.c.c.l.LocalRepoManagerImpl - [182fcda5]_close: Shutting down real LocalRepoManager.
11:41:17.923 [closeDeferredTimer-2d32d675-2] INFO c.c.c.l.LocalRepoManagerImpl - [2d32d675]_close: Shutting down real LocalRepoManager.
11:41:21.720 [main] INFO c.c.c.l.LocalRepoManagerImpl - [c126600]initPersistenceManagerFactory: repositoryId=19cc53bf-da8f-41d6-a491-5f573cf5dc48
11:41:21.721 [main] INFO c.c.c.l.LocalRepoManagerImpl - [c126600]initPersistenceManagerFactory: Started up PersistenceManagerFactory successfully in 1484 ms.
11:41:21.739 [main] INFO c.c.c.l.LocalRepoManagerImpl - [64f1d9d8]<init>: localRoot='/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/k0ksi20q-wj0qd-remote'
11:41:21.745 [main] INFO c.c.c.l.LocalRepoManagerImpl - [64f1d9d8]initLockFile: Repository '/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/k0ksi20q-wj0qd-remote' locked successfully.
11:41:25.941 [main] INFO c.c.c.l.LocalRepoManagerImpl - [64f1d9d8]initPersistenceManagerFactory: repositoryId=11247127-75d2-4273-96e5-da3adadf4535
11:41:25.947 [main] INFO c.c.c.l.LocalRepoManagerImpl - [64f1d9d8]initPersistenceManagerFactory: Started up PersistenceManagerFactory successfully in 2086 ms.
11:41:26.037 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 1.00%
11:41:26.043 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 50.00%
11:41:26.052 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 58.00%
11:41:26.098 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 63.61%
11:41:26.107 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 74.34%
11:41:26.141 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 79.43%
11:41:26.153 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 90.67%
11:41:26.172 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 95.74%
11:41:26.194 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 100.00%
11:41:26.214 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - local repo: 19cc53bf-da8f-41d6-a491-5f573cf5dc48
11:41:26.214 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - remote repo: 11247127-75d2-4273-96e5-da3adadf4535
11:41:26.217 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 0.50%
11:41:26.218 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDown: fromID=11247127-75d2-4273-96e5-da3adadf4535 from='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/k0ksi20q-wj0qd-remote' toID=19cc53bf-da8f-41d6-a491-5f573cf5dc48 to='target/repo/k0ksi20q-dtf3d-local', fromRepoLocalSync=true
11:41:26.218 [pool-39-thread-1] INFO c.c.c.c.r.s.RepoToRepoSync - sync: locally syncing 19cc53bf-da8f-41d6-a491-5f573cf5dc48 ('target/repo/k0ksi20q-dtf3d-local')
11:41:26.246 [pool-39-thread-1] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 12.94%
11:41:26.253 [pool-39-thread-1] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 25.12%
11:41:26.255 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 1.00%
11:41:26.256 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 50.00%
11:41:26.259 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 58.17%
11:41:26.267 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 63.61%
11:41:26.270 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 74.50%
11:41:26.276 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 80.62%
11:41:26.279 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 90.83%
11:41:26.282 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 95.94%
11:41:26.288 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 100.00%
11:41:26.317 [main] INFO c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=11247127-75d2-4273-96e5-da3adadf4535 remoteRepositoryId=19cc53bf-da8f-41d6-a491-5f573cf5dc48 localRepositoryRevisionSynced=-1 localRepositoryRevisionInProgress=2
11:41:26.348 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path=''
11:41:26.371 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path='/1'
11:41:26.382 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 30.22%
11:41:26.382 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path='/2'
11:41:26.389 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path='/2/1'
11:41:26.397 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path='/3'
11:41:26.409 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 35.46%
11:41:26.409 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 40.70%
11:41:26.409 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/1/a'
11:41:26.415 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='4fe43ff01135843527549d139d15ed59dc0ffcd1' to.sha1='<NoInstanceOf_NormalFileDto>'): /1/a
11:41:26.446 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=1, fromRepositoryId="FileInProgressMarker", toRepositoryId="1", path="11247127-75d2-4273-96e5-da3adadf4535"] on repo=19cc53bf-da8f-41d6-a491-5f573cf5dc48
11:41:26.447 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/1/a'
11:41:26.447 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='4fe43ff01135843527549d139d15ed59dc0ffcd1' toSha1='null'
11:41:26.447 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/1/a'
11:41:26.447 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/1/a' offset=0
11:41:26.448 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/1/a' offset=0
11:41:26.453 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 55223 bytes in 6 ms. path='/1/a'
11:41:26.457 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_86f7e437faa5a7fce15d1ddcb9eaeaea377667b8_0 into destFile a
11:41:26.490 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=1, fromRepositoryId="FileInProgressMarker", toRepositoryId="1", path="11247127-75d2-4273-96e5-da3adadf4535"] on repo=19cc53bf-da8f-41d6-a491-5f573cf5dc48
11:41:26.497 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/1/b'
11:41:26.504 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='0b952c1948fc7324a381fd04156bb0e03b9cb9bd' to.sha1='<NoInstanceOf_NormalFileDto>'): /1/b
11:41:26.513 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=2, fromRepositoryId="FileInProgressMarker", toRepositoryId="2", path="11247127-75d2-4273-96e5-da3adadf4535"] on repo=19cc53bf-da8f-41d6-a491-5f573cf5dc48
11:41:26.515 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/1/b'
11:41:26.515 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='0b952c1948fc7324a381fd04156bb0e03b9cb9bd' toSha1='null'
11:41:26.515 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/1/b'
11:41:26.515 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/1/b' offset=0
11:41:26.516 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/1/b' offset=0
11:41:26.522 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 130000 bytes in 7 ms. path='/1/b'
11:41:26.525 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_e9d71f5ee7c92d6dc9e92ffdad17b8bd49418f98_0 into destFile b
11:41:26.537 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=2, fromRepositoryId="FileInProgressMarker", toRepositoryId="2", path="11247127-75d2-4273-96e5-da3adadf4535"] on repo=19cc53bf-da8f-41d6-a491-5f573cf5dc48
11:41:26.540 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/1/c'
11:41:26.548 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='c29275e4b1e6b7355386d39ef3921f289fbf0512' to.sha1='<NoInstanceOf_NormalFileDto>'): /1/c
11:41:26.561 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=3, fromRepositoryId="FileInProgressMarker", toRepositoryId="3", path="11247127-75d2-4273-96e5-da3adadf4535"] on repo=19cc53bf-da8f-41d6-a491-5f573cf5dc48
11:41:26.562 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/1/c'
11:41:26.562 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='c29275e4b1e6b7355386d39ef3921f289fbf0512' toSha1='null'
11:41:26.562 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/1/c'
11:41:26.562 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/1/c' offset=0
11:41:26.567 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/1/c' offset=0
11:41:26.580 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 292812 bytes in 18 ms. path='/1/c'
11:41:26.583 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_84a516841ba77a5b4648de2cd0dfcb30ea46dbb4_0 into destFile c
11:41:26.602 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=3, fromRepositoryId="FileInProgressMarker", toRepositoryId="3", path="11247127-75d2-4273-96e5-da3adadf4535"] on repo=19cc53bf-da8f-41d6-a491-5f573cf5dc48
11:41:26.605 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/2/1/a'
11:41:26.614 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='d05226c17de2ae7712584d29bb2b04ff8d971685' to.sha1='<NoInstanceOf_NormalFileDto>'): /2/1/a
11:41:26.625 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=4, fromRepositoryId="FileInProgressMarker", toRepositoryId="4", path="11247127-75d2-4273-96e5-da3adadf4535"] on repo=19cc53bf-da8f-41d6-a491-5f573cf5dc48
11:41:26.626 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/2/1/a'
11:41:26.626 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='d05226c17de2ae7712584d29bb2b04ff8d971685' toSha1='null'
11:41:26.626 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/2/1/a'
11:41:26.626 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/2/1/a' offset=0
11:41:26.628 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/2/1/a' offset=0
11:41:26.634 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 168210 bytes in 8 ms. path='/2/1/a'
11:41:26.638 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_86f7e437faa5a7fce15d1ddcb9eaeaea377667b8_0 into destFile a
11:41:26.650 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=4, fromRepositoryId="FileInProgressMarker", toRepositoryId="4", path="11247127-75d2-4273-96e5-da3adadf4535"] on repo=19cc53bf-da8f-41d6-a491-5f573cf5dc48
11:41:26.653 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/2/1/b'
11:41:26.661 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='74d3cad0c40c24952c7a78a01ea5548a5f5c5d98' to.sha1='<NoInstanceOf_NormalFileDto>'): /2/1/b
11:41:26.676 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=5, fromRepositoryId="FileInProgressMarker", toRepositoryId="5", path="11247127-75d2-4273-96e5-da3adadf4535"] on repo=19cc53bf-da8f-41d6-a491-5f573cf5dc48
11:41:26.677 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/2/1/b'
11:41:26.677 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='74d3cad0c40c24952c7a78a01ea5548a5f5c5d98' toSha1='null'
11:41:26.678 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/2/1/b'
11:41:26.678 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/2/1/b' offset=0
11:41:26.686 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/2/1/b' offset=0
11:41:26.699 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 701855 bytes in 21 ms. path='/2/1/b'
11:41:26.704 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_e9d71f5ee7c92d6dc9e92ffdad17b8bd49418f98_0 into destFile b
11:41:26.737 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=5, fromRepositoryId="FileInProgressMarker", toRepositoryId="5", path="11247127-75d2-4273-96e5-da3adadf4535"] on repo=19cc53bf-da8f-41d6-a491-5f573cf5dc48
11:41:26.740 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/2/a'
11:41:26.749 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='0ef94789bffdb48415e9ed82378fc05020e5ee63' to.sha1='<NoInstanceOf_NormalFileDto>'): /2/a
11:41:26.763 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=6, fromRepositoryId="FileInProgressMarker", toRepositoryId="6", path="11247127-75d2-4273-96e5-da3adadf4535"] on repo=19cc53bf-da8f-41d6-a491-5f573cf5dc48
11:41:26.764 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/2/a'
11:41:26.764 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='0ef94789bffdb48415e9ed82378fc05020e5ee63' toSha1='null'
11:41:26.764 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/2/a'
11:41:26.765 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/2/a' offset=0
11:41:26.767 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/2/a' offset=0
11:41:26.776 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 305760 bytes in 11 ms. path='/2/a'
11:41:26.780 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_86f7e437faa5a7fce15d1ddcb9eaeaea377667b8_0 into destFile a
11:41:26.799 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=6, fromRepositoryId="FileInProgressMarker", toRepositoryId="6", path="11247127-75d2-4273-96e5-da3adadf4535"] on repo=19cc53bf-da8f-41d6-a491-5f573cf5dc48
11:41:26.802 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/3/a'
11:41:26.810 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='2f28b0acac777c9b5e2004b5d1abc39326e955cc' to.sha1='<NoInstanceOf_NormalFileDto>'): /3/a
11:41:26.823 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=7, fromRepositoryId="FileInProgressMarker", toRepositoryId="7", path="11247127-75d2-4273-96e5-da3adadf4535"] on repo=19cc53bf-da8f-41d6-a491-5f573cf5dc48
11:41:26.825 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/3/a'
11:41:26.825 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='2f28b0acac777c9b5e2004b5d1abc39326e955cc' toSha1='null'
11:41:26.825 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/3/a'
11:41:26.825 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/3/a' offset=0
11:41:26.826 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/3/a' offset=0
11:41:26.831 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 85644 bytes in 6 ms. path='/3/a'
11:41:26.834 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_86f7e437faa5a7fce15d1ddcb9eaeaea377667b8_0 into destFile a
11:41:26.846 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=7, fromRepositoryId="FileInProgressMarker", toRepositoryId="7", path="11247127-75d2-4273-96e5-da3adadf4535"] on repo=19cc53bf-da8f-41d6-a491-5f573cf5dc48
11:41:26.849 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/3/b'
11:41:26.858 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='0eb6e99c5fee0a0c151dcea124f03bb7d54ffb46' to.sha1='<NoInstanceOf_NormalFileDto>'): /3/b
11:41:26.870 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=8, fromRepositoryId="FileInProgressMarker", toRepositoryId="8", path="11247127-75d2-4273-96e5-da3adadf4535"] on repo=19cc53bf-da8f-41d6-a491-5f573cf5dc48
11:41:26.872 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/3/b'
11:41:26.872 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='0eb6e99c5fee0a0c151dcea124f03bb7d54ffb46' toSha1='null'
11:41:26.872 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/3/b'
11:41:26.872 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/3/b' offset=0
11:41:26.873 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/3/b' offset=0
11:41:26.878 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 61140 bytes in 6 ms. path='/3/b'
11:41:26.882 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_e9d71f5ee7c92d6dc9e92ffdad17b8bd49418f98_0 into destFile b
11:41:26.894 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=8, fromRepositoryId="FileInProgressMarker", toRepositoryId="8", path="11247127-75d2-4273-96e5-da3adadf4535"] on repo=19cc53bf-da8f-41d6-a491-5f573cf5dc48
11:41:26.897 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/3/c'
11:41:26.903 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='28e2af61f130859558121bfda948cf4b05ed62f4' to.sha1='<NoInstanceOf_NormalFileDto>'): /3/c
11:41:26.915 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=9, fromRepositoryId="FileInProgressMarker", toRepositoryId="9", path="11247127-75d2-4273-96e5-da3adadf4535"] on repo=19cc53bf-da8f-41d6-a491-5f573cf5dc48
11:41:26.917 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/3/c'
11:41:26.917 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='28e2af61f130859558121bfda948cf4b05ed62f4' toSha1='null'
11:41:26.917 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/3/c'
11:41:26.917 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/3/c' offset=0
11:41:26.920 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/3/c' offset=0
11:41:26.928 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 306360 bytes in 11 ms. path='/3/c'
11:41:26.932 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_84a516841ba77a5b4648de2cd0dfcb30ea46dbb4_0 into destFile c
11:41:26.952 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=9, fromRepositoryId="FileInProgressMarker", toRepositoryId="9", path="11247127-75d2-4273-96e5-da3adadf4535"] on repo=19cc53bf-da8f-41d6-a491-5f573cf5dc48
11:41:26.955 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/3/d'
11:41:26.962 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='1404f0c4a4eb26ae46087112f616af9866bcdded' to.sha1='<NoInstanceOf_NormalFileDto>'): /3/d
11:41:26.976 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=10, fromRepositoryId="FileInProgressMarker", toRepositoryId="10", path="11247127-75d2-4273-96e5-da3adadf4535"] on repo=19cc53bf-da8f-41d6-a491-5f573cf5dc48
11:41:26.977 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/3/d'
11:41:26.977 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='1404f0c4a4eb26ae46087112f616af9866bcdded' toSha1='null'
11:41:26.977 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/3/d'
11:41:26.977 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/3/d' offset=0
11:41:26.979 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/3/d' offset=0
11:41:26.990 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 158194 bytes in 13 ms. path='/3/d'
11:41:26.994 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_3c363836cf4e16666669a25da280a1865c2d2874_0 into destFile d
11:41:27.007 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=10, fromRepositoryId="FileInProgressMarker", toRepositoryId="10", path="11247127-75d2-4273-96e5-da3adadf4535"] on repo=19cc53bf-da8f-41d6-a491-5f573cf5dc48
11:41:27.010 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 49.27%
11:41:27.026 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=11247127-75d2-4273-96e5-da3adadf4535 remoteRepositoryId=19cc53bf-da8f-41d6-a491-5f573cf5dc48 localRepositoryRevisionSynced=2
11:41:27.042 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=19cc53bf-da8f-41d6-a491-5f573cf5dc48 remoteRepositoryId=11247127-75d2-4273-96e5-da3adadf4535 transaction.localRevision=57 remoteFromLocalRevision=2
11:41:27.048 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncUp: fromID=19cc53bf-da8f-41d6-a491-5f573cf5dc48 from='target/repo/k0ksi20q-dtf3d-local' toID=11247127-75d2-4273-96e5-da3adadf4535 to='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/k0ksi20q-wj0qd-remote'
11:41:27.065 [main] INFO c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=19cc53bf-da8f-41d6-a491-5f573cf5dc48 remoteRepositoryId=11247127-75d2-4273-96e5-da3adadf4535 localRepositoryRevisionSynced=-1 localRepositoryRevisionInProgress=57
11:41:27.074 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 63.43%
11:41:27.074 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 74.63%
11:41:27.084 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=19cc53bf-da8f-41d6-a491-5f573cf5dc48 remoteRepositoryId=11247127-75d2-4273-96e5-da3adadf4535 localRepositoryRevisionSynced=57
11:41:27.090 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=11247127-75d2-4273-96e5-da3adadf4535 remoteRepositoryId=19cc53bf-da8f-41d6-a491-5f573cf5dc48 transaction.localRevision=5 remoteFromLocalRevision=57
11:41:27.096 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDown: fromID=11247127-75d2-4273-96e5-da3adadf4535 from='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/k0ksi20q-wj0qd-remote' toID=19cc53bf-da8f-41d6-a491-5f573cf5dc48 to='target/repo/k0ksi20q-dtf3d-local', fromRepoLocalSync=false
11:41:27.112 [main] INFO c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=11247127-75d2-4273-96e5-da3adadf4535 remoteRepositoryId=19cc53bf-da8f-41d6-a491-5f573cf5dc48 localRepositoryRevisionSynced=2 localRepositoryRevisionInProgress=5
11:41:27.114 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 88.31%
11:41:27.114 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 99.50%
11:41:27.117 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=11247127-75d2-4273-96e5-da3adadf4535 remoteRepositoryId=19cc53bf-da8f-41d6-a491-5f573cf5dc48 localRepositoryRevisionSynced=5
11:41:27.127 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=19cc53bf-da8f-41d6-a491-5f573cf5dc48 remoteRepositoryId=11247127-75d2-4273-96e5-da3adadf4535 transaction.localRevision=60 remoteFromLocalRevision=5
11:41:27.130 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 100.00%
11:41:27.147 [main] INFO c.c.c.l.LocalRepoManagerImpl - [c126600]getCloseDeferredMillis: closeDeferredMillis=20000
11:41:27.147 [main] INFO c.c.c.l.LocalRepoManagerImpl - [c126600]close: Deferring shut down of real LocalRepoManager 20000 ms.
11:41:27.147 [main] INFO c.c.c.l.LocalRepoManagerImpl - [64f1d9d8]getCloseDeferredMillis: closeDeferredMillis=20000
11:41:27.148 [main] INFO c.c.c.l.LocalRepoManagerImpl - [64f1d9d8]close: Deferring shut down of real LocalRepoManager 20000 ms.
11:41:27.189 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 1.00%
11:41:27.191 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 50.00%
11:41:27.193 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 62.25%
11:41:27.196 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 68.38%
11:41:27.198 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 74.50%
11:41:27.201 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 86.75%
11:41:27.204 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 92.88%
11:41:27.211 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 99.00%
11:41:27.264 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 100.00%
11:41:27.283 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 0.50%
11:41:27.284 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDown: fromID=11247127-75d2-4273-96e5-da3adadf4535 from='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/k0ksi20q-wj0qd-remote' toID=19cc53bf-da8f-41d6-a491-5f573cf5dc48 to='target/repo/k0ksi20q-dtf3d-local', fromRepoLocalSync=true
11:41:27.284 [pool-40-thread-1] INFO c.c.c.c.r.s.RepoToRepoSync - sync: locally syncing 19cc53bf-da8f-41d6-a491-5f573cf5dc48 ('target/repo/k0ksi20q-dtf3d-local')
11:41:27.287 [pool-40-thread-1] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 12.94%
11:41:27.301 [pool-40-thread-1] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 19.03%
11:41:27.317 [pool-40-thread-1] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 24.11%
11:41:27.334 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 1.00%
11:41:27.335 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 50.00%
11:41:27.338 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 62.25%
11:41:27.345 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 68.38%
11:41:27.347 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 74.50%
11:41:27.349 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 86.75%
11:41:27.353 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 92.88%
11:41:27.355 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 99.00%
11:41:27.359 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 100.00%
11:41:27.369 [main] INFO c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=11247127-75d2-4273-96e5-da3adadf4535 remoteRepositoryId=19cc53bf-da8f-41d6-a491-5f573cf5dc48 localRepositoryRevisionSynced=5 localRepositoryRevisionInProgress=8
11:41:27.385 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 31.09%
11:41:27.386 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path=''
11:41:27.397 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncModifications: Deleting '/2'
11:41:27.441 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 38.56%
11:41:27.442 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 46.02%
11:41:27.457 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=11247127-75d2-4273-96e5-da3adadf4535 remoteRepositoryId=19cc53bf-da8f-41d6-a491-5f573cf5dc48 localRepositoryRevisionSynced=8
11:41:27.466 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=19cc53bf-da8f-41d6-a491-5f573cf5dc48 remoteRepositoryId=11247127-75d2-4273-96e5-da3adadf4535 transaction.localRevision=63 remoteFromLocalRevision=8
11:41:27.472 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncUp: fromID=19cc53bf-da8f-41d6-a491-5f573cf5dc48 from='target/repo/k0ksi20q-dtf3d-local' toID=11247127-75d2-4273-96e5-da3adadf4535 to='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/k0ksi20q-wj0qd-remote'
11:41:27.483 [main] INFO c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=19cc53bf-da8f-41d6-a491-5f573cf5dc48 remoteRepositoryId=11247127-75d2-4273-96e5-da3adadf4535 localRepositoryRevisionSynced=57 localRepositoryRevisionInProgress=63
11:41:27.491 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 52.24%
11:41:27.491 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 59.70%
11:41:27.492 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncModifications: Deleting '/2'
11:41:27.495 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 67.16%
11:41:27.495 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 74.63%
11:41:27.510 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=19cc53bf-da8f-41d6-a491-5f573cf5dc48 remoteRepositoryId=11247127-75d2-4273-96e5-da3adadf4535 localRepositoryRevisionSynced=63
11:41:27.514 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=11247127-75d2-4273-96e5-da3adadf4535 remoteRepositoryId=19cc53bf-da8f-41d6-a491-5f573cf5dc48 transaction.localRevision=11 remoteFromLocalRevision=63
11:41:27.521 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDown: fromID=11247127-75d2-4273-96e5-da3adadf4535 from='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/repo/k0ksi20q-wj0qd-remote' toID=19cc53bf-da8f-41d6-a491-5f573cf5dc48 to='target/repo/k0ksi20q-dtf3d-local', fromRepoLocalSync=false
11:41:27.531 [main] INFO c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=11247127-75d2-4273-96e5-da3adadf4535 remoteRepositoryId=19cc53bf-da8f-41d6-a491-5f573cf5dc48 localRepositoryRevisionSynced=8 localRepositoryRevisionInProgress=11
11:41:27.532 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 88.31%
11:41:27.533 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 99.50%
11:41:27.538 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=11247127-75d2-4273-96e5-da3adadf4535 remoteRepositoryId=19cc53bf-da8f-41d6-a491-5f573cf5dc48 localRepositoryRevisionSynced=11
11:41:27.546 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=19cc53bf-da8f-41d6-a491-5f573cf5dc48 remoteRepositoryId=11247127-75d2-4273-96e5-da3adadf4535 transaction.localRevision=66 remoteFromLocalRevision=11
11:41:27.547 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 100.00%
11:41:27.547 [main] INFO c.c.c.l.LocalRepoManagerImpl - [c126600]close: Deferring shut down of real LocalRepoManager 20000 ms.
11:41:27.562 [main] INFO c.c.c.l.LocalRepoManagerImpl - [64f1d9d8]close: Deferring shut down of real LocalRepoManager 20000 ms.
Tests run: 20, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 302.843 sec - in co.codewizards.cloudstore.local.sync.RepoToRepoSyncTest
Results :
Tests run: 63, Failures: 0, Errors: 0, Skipped: 0
[JENKINS] Recording test results
[INFO]
[INFO] --- maven-jar-plugin:2.4:jar (default-jar) @ co.codewizards.cloudstore.local ---
[INFO] Building jar: /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/co.codewizards.cloudstore.local-0.10.7-SNAPSHOT.jar
[INFO]
[INFO] --- maven-source-plugin:2.1.2:jar-no-fork (attach-sources) @ co.codewizards.cloudstore.local ---
[INFO] co already added, skipping
[INFO] co/codewizards already added, skipping
[INFO] co/codewizards/cloudstore already added, skipping
[INFO] co/codewizards/cloudstore/local already added, skipping
[INFO] co/codewizards/cloudstore/local/db already added, skipping
[INFO] co/codewizards/cloudstore/local/persistence already added, skipping
[INFO] co/codewizards/cloudstore/local/transport already added, skipping
[INFO] co/codewizards/cloudstore/local/dto already added, skipping
[INFO] co/codewizards/cloudstore/local/persistence/package-sqlite.orm already added, skipping
[INFO] Building jar: /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/co.codewizards.cloudstore.local-0.10.7-SNAPSHOT-sources.jar
[INFO] co already added, skipping
[INFO] co/codewizards already added, skipping
[INFO] co/codewizards/cloudstore already added, skipping
[INFO] co/codewizards/cloudstore/local already added, skipping
[INFO] co/codewizards/cloudstore/local/db already added, skipping
[INFO] co/codewizards/cloudstore/local/persistence already added, skipping
[INFO] co/codewizards/cloudstore/local/transport already added, skipping
[INFO] co/codewizards/cloudstore/local/dto already added, skipping
[INFO] co/codewizards/cloudstore/local/persistence/package-sqlite.orm already added, skipping
[INFO]
[INFO] --- maven-install-plugin:2.3.1:install (default-install) @ co.codewizards.cloudstore.local ---
[INFO] Installing /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/co.codewizards.cloudstore.local-0.10.7-SNAPSHOT.jar to /home/tomcat/.m2/repository/co/codewizards/cloudstore/co.codewizards.cloudstore.local/0.10.7-SNAPSHOT/co.codewizards.cloudstore.local-0.10.7-SNAPSHOT.jar
[INFO] Installing /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/pom.xml to /home/tomcat/.m2/repository/co/codewizards/cloudstore/co.codewizards.cloudstore.local/0.10.7-SNAPSHOT/co.codewizards.cloudstore.local-0.10.7-SNAPSHOT.pom
[INFO] Installing /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/co.codewizards.cloudstore.local-0.10.7-SNAPSHOT-sources.jar to /home/tomcat/.m2/repository/co/codewizards/cloudstore/co.codewizards.cloudstore.local/0.10.7-SNAPSHOT/co.codewizards.cloudstore.local-0.10.7-SNAPSHOT-sources.jar
[INFO]
[INFO] --- maven-deploy-plugin:2.7:deploy (default-deploy) @ co.codewizards.cloudstore.local ---
Downloading: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/snapshot/co/codewizards/cloudstore/co.codewizards.cloudstore.local/0.10.7-SNAPSHOT/maven-metadata.xml
Downloaded: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/snapshot/co/codewizards/cloudstore/co.codewizards.cloudstore.local/0.10.7-SNAPSHOT/maven-metadata.xml (2 KB at 37.2 KB/sec)
Uploading: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/snapshot/co/codewizards/cloudstore/co.codewizards.cloudstore.local/0.10.7-SNAPSHOT/co.codewizards.cloudstore.local-0.10.7-20190915.094129-19.jar
Uploaded: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/snapshot/co/codewizards/cloudstore/co.codewizards.cloudstore.local/0.10.7-SNAPSHOT/co.codewizards.cloudstore.local-0.10.7-20190915.094129-19.jar (203 KB at 33790.5 KB/sec)
Uploading: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/snapshot/co/codewizards/cloudstore/co.codewizards.cloudstore.local/0.10.7-SNAPSHOT/co.codewizards.cloudstore.local-0.10.7-20190915.094129-19.pom
Uploaded: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/snapshot/co/codewizards/cloudstore/co.codewizards.cloudstore.local/0.10.7-SNAPSHOT/co.codewizards.cloudstore.local-0.10.7-20190915.094129-19.pom (3 KB at 855.1 KB/sec)
Downloading: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/snapshot/co/codewizards/cloudstore/co.codewizards.cloudstore.local/maven-metadata.xml
Downloaded: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/snapshot/co/codewizards/cloudstore/co.codewizards.cloudstore.local/maven-metadata.xml (399 B at 64.9 KB/sec)
Uploading: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/snapshot/co/codewizards/cloudstore/co.codewizards.cloudstore.local/0.10.7-SNAPSHOT/maven-metadata.xml
Uploaded: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/snapshot/co/codewizards/cloudstore/co.codewizards.cloudstore.local/0.10.7-SNAPSHOT/maven-metadata.xml (2 KB at 502.0 KB/sec)
Uploading: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/snapshot/co/codewizards/cloudstore/co.codewizards.cloudstore.local/maven-metadata.xml
Uploaded: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/snapshot/co/codewizards/cloudstore/co.codewizards.cloudstore.local/maven-metadata.xml (399 B at 194.8 KB/sec)
Uploading: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/snapshot/co/codewizards/cloudstore/co.codewizards.cloudstore.local/0.10.7-SNAPSHOT/co.codewizards.cloudstore.local-0.10.7-20190915.094129-19-sources.jar
Uploaded: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/snapshot/co/codewizards/cloudstore/co.codewizards.cloudstore.local/0.10.7-SNAPSHOT/co.codewizards.cloudstore.local-0.10.7-20190915.094129-19-sources.jar (105 KB at 20855.3 KB/sec)
Uploading: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/snapshot/co/codewizards/cloudstore/co.codewizards.cloudstore.local/0.10.7-SNAPSHOT/maven-metadata.xml
Uploaded: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/snapshot/co/codewizards/cloudstore/co.codewizards.cloudstore.local/0.10.7-SNAPSHOT/maven-metadata.xml (2 KB at 1003.9 KB/sec)
[INFO]
[INFO] --- maven-site-plugin:3.3:site (default-site) @ co.codewizards.cloudstore.local ---
[INFO] Relativizing decoration links with respect to project URL: http://cloudstore.codewizards.co/co.codewizards.cloudstore.local
[INFO] Rendering site with org.apache.maven.skins:maven-default-skin:jar:1.0 skin.
[INFO] Relativizing decoration links with respect to project URL: http://cloudstore.codewizards.co/co.codewizards.cloudstore.local/de
[INFO] Rendering site with org.apache.maven.skins:maven-default-skin:jar:1.0 skin.
[JENKINS] Archiving site from /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/site to /home/tomcat/.jenkins/jobs/co.codewizards.cloudstore/site/co.codewizards.cloudstore.local
[WARNING] Failed to getClass for org.apache.maven.plugin.javadoc.JavadocReport
[INFO]
[INFO] --- maven-javadoc-plugin:2.9.1:javadoc (javadoc-generate-unfiltered) @ co.codewizards.cloudstore.local ---
[INFO]
[INFO] --- maven-resources-plugin:2.5:copy-resources (javadoc-filter) @ co.codewizards.cloudstore.local ---
[debug] execute contextualize
[INFO] Using 'UTF-8' encoding to copy filtered resources.
[INFO] skip non existing resourceDirectory /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/javadoc.unfiltered
[INFO] skip non existing resourceDirectory /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/javadoc.unfiltered
[INFO]
[INFO] --- maven-resources-plugin:2.5:copy-resources (javadoc-copy-to-site) @ co.codewizards.cloudstore.local ---
[debug] execute contextualize
[INFO] Using 'UTF-8' encoding to copy filtered resources.
[INFO] skip non existing resourceDirectory /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/apidocs
[INFO]
[INFO] --- maven-site-plugin:3.3:deploy (default-deploy) @ co.codewizards.cloudstore.local ---
file:/tmp/cloudstore-junk/ - Session: Opened
[INFO] Pushing /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.local/target/site
[INFO] >>> to file:/tmp/cloudstore-junk/co.codewizards.cloudstore.local
[INFO] >>> to file:/tmp/cloudstore-junk/de/co.codewizards.cloudstore.local
file:/tmp/cloudstore-junk/ - Session: Disconnecting
file:/tmp/cloudstore-junk/ - Session: Disconnected
|
Help us localize this page