|  Console Output
Skipping 709 KB.. Full Log2-9866-3a95d0f5b9db to='target/repo/k6yk3uam-kxri9-local', fromRepoLocalSync=true
05:53:28.723 [pool-31-thread-1] INFO c.c.c.c.r.s.RepoToRepoSync - sync: locally syncing e51df6f6-5164-4fb2-9866-3a95d0f5b9db ('target/repo/k6yk3uam-kxri9-local')
05:53:28.750 [pool-31-thread-1] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 12.94%
05:53:28.758 [pool-31-thread-1] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 25.12%
05:53:28.759 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 1.00%
05:53:28.760 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 50.00%
05:53:28.762 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 58.17%
05:53:28.766 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 63.61%
05:53:28.770 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 74.50%
05:53:28.778 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 80.62%
05:53:28.781 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 90.83%
05:53:28.786 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 95.94%
05:53:28.793 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 100.00%
05:53:28.823 [main] INFO c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=9e70fd1c-b55f-4d11-9f52-57e8ced74b30 remoteRepositoryId=e51df6f6-5164-4fb2-9866-3a95d0f5b9db localRepositoryRevisionSynced=-1 localRepositoryRevisionInProgress=2
05:53:28.886 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path=''
05:53:28.922 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path='/1'
05:53:28.946 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 31.31%
05:53:28.955 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 36.58%
05:53:28.956 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 41.85%
05:53:28.956 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/1/a'
05:53:28.965 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='ecc5678f01e199923ae558b0a263bbc2c7ce3838' to.sha1='<NoInstanceOf_NormalFileDto>'): /1/a
05:53:29.029 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=1, fromRepositoryId="FileInProgressMarker", toRepositoryId="1", path="9e70fd1c-b55f-4d11-9f52-57e8ced74b30"] on repo=e51df6f6-5164-4fb2-9866-3a95d0f5b9db
05:53:29.035 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/1/a'
05:53:29.035 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='ecc5678f01e199923ae558b0a263bbc2c7ce3838' toSha1='null'
05:53:29.036 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/1/a'
05:53:29.036 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/1/a' offset=0
05:53:29.047 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/1/a' offset=0
05:53:29.063 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 579615 bytes in 27 ms. path='/1/a'
05:53:29.068 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_86f7e437faa5a7fce15d1ddcb9eaeaea377667b8_0 into destFile a
05:53:29.086 [closeDeferredTimer-197e53dd-3] INFO c.c.c.l.LocalRepoManagerImpl - [197e53dd]_close: Shutting down real LocalRepoManager.
05:53:29.088 [closeDeferredTimer-6023bed6-3] INFO c.c.c.l.LocalRepoManagerImpl - [6023bed6]_close: Shutting down real LocalRepoManager.
05:53:29.930 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=1, fromRepositoryId="FileInProgressMarker", toRepositoryId="1", path="9e70fd1c-b55f-4d11-9f52-57e8ced74b30"] on repo=e51df6f6-5164-4fb2-9866-3a95d0f5b9db
05:53:29.950 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/1/b'
05:53:29.970 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='863707fb9e8c9e382d04568a7f6be4b11e6c408a' to.sha1='<NoInstanceOf_NormalFileDto>'): /1/b
05:53:29.991 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=2, fromRepositoryId="FileInProgressMarker", toRepositoryId="2", path="9e70fd1c-b55f-4d11-9f52-57e8ced74b30"] on repo=e51df6f6-5164-4fb2-9866-3a95d0f5b9db
05:53:30.027 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/1/b'
05:53:30.027 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='863707fb9e8c9e382d04568a7f6be4b11e6c408a' toSha1='null'
05:53:30.027 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/1/b'
05:53:30.027 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/1/b' offset=0
05:53:30.034 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/1/b' offset=0
05:53:30.047 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 535040 bytes in 20 ms. path='/1/b'
05:53:30.052 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_e9d71f5ee7c92d6dc9e92ffdad17b8bd49418f98_0 into destFile b
05:53:30.091 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=2, fromRepositoryId="FileInProgressMarker", toRepositoryId="2", path="9e70fd1c-b55f-4d11-9f52-57e8ced74b30"] on repo=e51df6f6-5164-4fb2-9866-3a95d0f5b9db
05:53:30.127 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/a'
05:53:30.137 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='fc4259b3123997a2aacbf5db23c43cfa00bad3b6' to.sha1='<NoInstanceOf_NormalFileDto>'): /a
05:53:30.173 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=3, fromRepositoryId="FileInProgressMarker", toRepositoryId="3", path="9e70fd1c-b55f-4d11-9f52-57e8ced74b30"] on repo=e51df6f6-5164-4fb2-9866-3a95d0f5b9db
05:53:30.189 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/a'
05:53:30.190 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='fc4259b3123997a2aacbf5db23c43cfa00bad3b6' toSha1='null'
05:53:30.190 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/a'
05:53:30.190 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/a' offset=0
05:53:30.190 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/a' offset=0
05:53:30.196 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 34110 bytes in 6 ms. path='/a'
05:53:30.200 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_86f7e437faa5a7fce15d1ddcb9eaeaea377667b8_0 into destFile a
05:53:30.221 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=3, fromRepositoryId="FileInProgressMarker", toRepositoryId="3", path="9e70fd1c-b55f-4d11-9f52-57e8ced74b30"] on repo=e51df6f6-5164-4fb2-9866-3a95d0f5b9db
05:53:30.233 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 48.43%
05:53:30.259 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=9e70fd1c-b55f-4d11-9f52-57e8ced74b30 remoteRepositoryId=e51df6f6-5164-4fb2-9866-3a95d0f5b9db localRepositoryRevisionSynced=2
05:53:30.287 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=e51df6f6-5164-4fb2-9866-3a95d0f5b9db remoteRepositoryId=9e70fd1c-b55f-4d11-9f52-57e8ced74b30 transaction.localRevision=19 remoteFromLocalRevision=2
05:53:30.358 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncUp: fromID=e51df6f6-5164-4fb2-9866-3a95d0f5b9db from='target/repo/k6yk3uam-kxri9-local' toID=9e70fd1c-b55f-4d11-9f52-57e8ced74b30 to='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.local/target/repo/k6yk3uan-v5jrp-remote/2'
05:53:30.514 [main] INFO c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=e51df6f6-5164-4fb2-9866-3a95d0f5b9db remoteRepositoryId=9e70fd1c-b55f-4d11-9f52-57e8ced74b30 localRepositoryRevisionSynced=-1 localRepositoryRevisionInProgress=19
05:53:30.528 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 63.43%
05:53:30.528 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 74.63%
05:53:30.540 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=e51df6f6-5164-4fb2-9866-3a95d0f5b9db remoteRepositoryId=9e70fd1c-b55f-4d11-9f52-57e8ced74b30 localRepositoryRevisionSynced=19
05:53:30.627 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=9e70fd1c-b55f-4d11-9f52-57e8ced74b30 remoteRepositoryId=e51df6f6-5164-4fb2-9866-3a95d0f5b9db transaction.localRevision=5 remoteFromLocalRevision=19
05:53:30.796 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDown: fromID=9e70fd1c-b55f-4d11-9f52-57e8ced74b30 from='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.local/target/repo/k6yk3uan-v5jrp-remote/2' toID=e51df6f6-5164-4fb2-9866-3a95d0f5b9db to='target/repo/k6yk3uam-kxri9-local', fromRepoLocalSync=false
05:53:31.279 [main] INFO c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=9e70fd1c-b55f-4d11-9f52-57e8ced74b30 remoteRepositoryId=e51df6f6-5164-4fb2-9866-3a95d0f5b9db localRepositoryRevisionSynced=2 localRepositoryRevisionInProgress=5
05:53:31.285 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 88.31%
05:53:31.286 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 99.50%
05:53:31.292 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=9e70fd1c-b55f-4d11-9f52-57e8ced74b30 remoteRepositoryId=e51df6f6-5164-4fb2-9866-3a95d0f5b9db localRepositoryRevisionSynced=5
05:53:31.467 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=e51df6f6-5164-4fb2-9866-3a95d0f5b9db remoteRepositoryId=9e70fd1c-b55f-4d11-9f52-57e8ced74b30 transaction.localRevision=22 remoteFromLocalRevision=5
05:53:31.476 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 100.00%
05:53:31.498 [main] INFO c.c.c.l.LocalRepoManagerImpl - [5a202948]getCloseDeferredMillis: closeDeferredMillis=20000
05:53:31.498 [main] INFO c.c.c.l.LocalRepoManagerImpl - [5a202948]close: Deferring shut down of real LocalRepoManager 20000 ms.
05:53:31.498 [main] INFO c.c.c.l.LocalRepoManagerImpl - [757721c3]getCloseDeferredMillis: closeDeferredMillis=20000
05:53:31.499 [main] INFO c.c.c.l.LocalRepoManagerImpl - [757721c3]close: Deferring shut down of real LocalRepoManager 20000 ms.
05:53:31.535 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 1.00%
05:53:31.536 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 50.00%
05:53:31.537 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 58.17%
05:53:31.541 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 63.61%
05:53:31.546 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 74.50%
05:53:31.584 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 80.62%
05:53:31.588 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 90.83%
05:53:31.610 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 96.55%
05:53:31.795 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 100.00%
05:53:31.838 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 0.50%
05:53:31.838 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDown: fromID=9e70fd1c-b55f-4d11-9f52-57e8ced74b30 from='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.local/target/repo/k6yk3uan-v5jrp-remote/2' toID=e51df6f6-5164-4fb2-9866-3a95d0f5b9db to='target/repo/k6yk3uam-kxri9-local', fromRepoLocalSync=true
05:53:31.839 [pool-32-thread-1] INFO c.c.c.c.r.s.RepoToRepoSync - sync: locally syncing e51df6f6-5164-4fb2-9866-3a95d0f5b9db ('target/repo/k6yk3uam-kxri9-local')
05:53:31.850 [pool-32-thread-1] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 12.94%
05:53:31.864 [pool-32-thread-1] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 18.27%
05:53:31.874 [pool-32-thread-1] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 25.12%
05:53:31.884 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 1.00%
05:53:31.885 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 50.00%
05:53:31.887 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 58.17%
05:53:31.893 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 63.61%
05:53:31.898 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 74.50%
05:53:31.912 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 80.62%
05:53:31.918 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 90.83%
05:53:31.924 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 96.55%
05:53:31.933 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 100.00%
05:53:31.947 [main] INFO c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=9e70fd1c-b55f-4d11-9f52-57e8ced74b30 remoteRepositoryId=e51df6f6-5164-4fb2-9866-3a95d0f5b9db localRepositoryRevisionSynced=5 localRepositoryRevisionInProgress=8
05:53:31.974 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path='/1'
05:53:31.998 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 31.31%
05:53:32.001 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path='/1/5'
05:53:32.018 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 36.58%
05:53:32.018 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 41.85%
05:53:32.019 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/1/5/aaa'
05:53:32.031 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='337ebf05a911edc82d8d521f7e99b148be7249dd' to.sha1='<NoInstanceOf_NormalFileDto>'): /1/5/aaa
05:53:32.049 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=4, fromRepositoryId="FileInProgressMarker", toRepositoryId="4", path="9e70fd1c-b55f-4d11-9f52-57e8ced74b30"] on repo=e51df6f6-5164-4fb2-9866-3a95d0f5b9db
05:53:32.055 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/1/5/aaa'
05:53:32.055 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='337ebf05a911edc82d8d521f7e99b148be7249dd' toSha1='null'
05:53:32.055 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/1/5/aaa'
05:53:32.056 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/1/5/aaa' offset=0
05:53:32.065 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/1/5/aaa' offset=0
05:53:32.075 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 419510 bytes in 19 ms. path='/1/5/aaa'
05:53:32.079 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_7e240de74fb1ed08fa08d38063f6a6a91462a815_0 into destFile aaa
05:53:32.109 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=4, fromRepositoryId="FileInProgressMarker", toRepositoryId="4", path="9e70fd1c-b55f-4d11-9f52-57e8ced74b30"] on repo=e51df6f6-5164-4fb2-9866-3a95d0f5b9db
05:53:32.124 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/1/5/bbb'
05:53:32.141 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='3e29135ffbe62316b34f74263251d7ded444947c' to.sha1='<NoInstanceOf_NormalFileDto>'): /1/5/bbb
05:53:32.162 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=5, fromRepositoryId="FileInProgressMarker", toRepositoryId="5", path="9e70fd1c-b55f-4d11-9f52-57e8ced74b30"] on repo=e51df6f6-5164-4fb2-9866-3a95d0f5b9db
05:53:32.168 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/1/5/bbb'
05:53:32.168 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='3e29135ffbe62316b34f74263251d7ded444947c' toSha1='null'
05:53:32.168 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/1/5/bbb'
05:53:32.168 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/1/5/bbb' offset=0
05:53:32.169 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/1/5/bbb' offset=0
05:53:32.177 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 15664 bytes in 9 ms. path='/1/5/bbb'
05:53:32.185 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_5cb138284d431abd6a053a56625ec088bfb88912_0 into destFile bbb
05:53:32.200 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=5, fromRepositoryId="FileInProgressMarker", toRepositoryId="5", path="9e70fd1c-b55f-4d11-9f52-57e8ced74b30"] on repo=e51df6f6-5164-4fb2-9866-3a95d0f5b9db
05:53:32.209 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 48.43%
05:53:32.214 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=9e70fd1c-b55f-4d11-9f52-57e8ced74b30 remoteRepositoryId=e51df6f6-5164-4fb2-9866-3a95d0f5b9db localRepositoryRevisionSynced=8
05:53:32.224 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=e51df6f6-5164-4fb2-9866-3a95d0f5b9db remoteRepositoryId=9e70fd1c-b55f-4d11-9f52-57e8ced74b30 transaction.localRevision=35 remoteFromLocalRevision=8
05:53:32.234 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncUp: fromID=e51df6f6-5164-4fb2-9866-3a95d0f5b9db from='target/repo/k6yk3uam-kxri9-local' toID=9e70fd1c-b55f-4d11-9f52-57e8ced74b30 to='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.local/target/repo/k6yk3uan-v5jrp-remote/2'
05:53:32.253 [main] INFO c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=e51df6f6-5164-4fb2-9866-3a95d0f5b9db remoteRepositoryId=9e70fd1c-b55f-4d11-9f52-57e8ced74b30 localRepositoryRevisionSynced=19 localRepositoryRevisionInProgress=35
05:53:32.256 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 63.43%
05:53:32.256 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 74.63%
05:53:32.267 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=e51df6f6-5164-4fb2-9866-3a95d0f5b9db remoteRepositoryId=9e70fd1c-b55f-4d11-9f52-57e8ced74b30 localRepositoryRevisionSynced=35
05:53:32.276 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=9e70fd1c-b55f-4d11-9f52-57e8ced74b30 remoteRepositoryId=e51df6f6-5164-4fb2-9866-3a95d0f5b9db transaction.localRevision=11 remoteFromLocalRevision=35
05:53:32.283 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDown: fromID=9e70fd1c-b55f-4d11-9f52-57e8ced74b30 from='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.local/target/repo/k6yk3uan-v5jrp-remote/2' toID=e51df6f6-5164-4fb2-9866-3a95d0f5b9db to='target/repo/k6yk3uam-kxri9-local', fromRepoLocalSync=false
05:53:32.294 [main] INFO c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=9e70fd1c-b55f-4d11-9f52-57e8ced74b30 remoteRepositoryId=e51df6f6-5164-4fb2-9866-3a95d0f5b9db localRepositoryRevisionSynced=8 localRepositoryRevisionInProgress=11
05:53:32.300 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 88.31%
05:53:32.300 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 99.50%
05:53:32.304 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=9e70fd1c-b55f-4d11-9f52-57e8ced74b30 remoteRepositoryId=e51df6f6-5164-4fb2-9866-3a95d0f5b9db localRepositoryRevisionSynced=11
05:53:32.322 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=e51df6f6-5164-4fb2-9866-3a95d0f5b9db remoteRepositoryId=9e70fd1c-b55f-4d11-9f52-57e8ced74b30 transaction.localRevision=38 remoteFromLocalRevision=11
05:53:32.328 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 100.00%
05:53:32.328 [main] INFO c.c.c.l.LocalRepoManagerImpl - [5a202948]close: Deferring shut down of real LocalRepoManager 20000 ms.
05:53:32.348 [main] INFO c.c.c.l.LocalRepoManagerImpl - [757721c3]close: Deferring shut down of real LocalRepoManager 20000 ms.
05:53:32.385 [main] INFO c.c.c.l.LocalRepoManagerImpl - [52512096]<init>: localRoot='/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.local/target/repo/k6yk45sw-w4yfi-local'
05:53:32.392 [main] INFO c.c.c.l.LocalRepoManagerImpl - [52512096]initLockFile: Repository '/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.local/target/repo/k6yk45sw-w4yfi-local' locked successfully.
05:53:36.712 [main] INFO c.c.c.l.LocalRepoManagerImpl - [52512096]initPersistenceManagerFactory: repositoryId=179d1409-4547-4311-b8b6-e09f6a57f0fe
05:53:36.718 [main] INFO c.c.c.l.LocalRepoManagerImpl - [52512096]initPersistenceManagerFactory: Started up PersistenceManagerFactory successfully in 2372 ms.
05:53:36.744 [main] INFO c.c.c.l.LocalRepoManagerImpl - [443c098]<init>: localRoot='/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.local/target/repo/k6yk45sw-fg5e9-remote'
05:53:36.750 [main] INFO c.c.c.l.LocalRepoManagerImpl - [443c098]initLockFile: Repository '/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.local/target/repo/k6yk45sw-fg5e9-remote' locked successfully.
05:53:37.082 [closeDeferredTimer-3e46d795-1] INFO c.c.c.l.LocalRepoManagerImpl - [3e46d795]_close: Shutting down real LocalRepoManager.
05:53:37.458 [closeDeferredTimer-711977bc-1] INFO c.c.c.l.LocalRepoManagerImpl - [711977bc]_close: Shutting down real LocalRepoManager.
05:53:43.302 [main] INFO c.c.c.l.LocalRepoManagerImpl - [443c098]initPersistenceManagerFactory: repositoryId=94ce2525-6586-4e4a-bcda-f53bfd605caf
05:53:43.309 [main] INFO c.c.c.l.LocalRepoManagerImpl - [443c098]initPersistenceManagerFactory: Started up PersistenceManagerFactory successfully in 2273 ms.
05:53:43.426 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 1.00%
05:53:43.434 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 50.00%
05:53:43.444 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 58.00%
05:53:43.510 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 63.61%
05:53:43.531 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 74.34%
05:53:43.555 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 79.43%
05:53:43.569 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 90.67%
05:53:43.609 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 95.70%
05:53:43.636 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 100.00%
05:53:43.662 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - local repo: 179d1409-4547-4311-b8b6-e09f6a57f0fe
05:53:43.663 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - remote repo: 94ce2525-6586-4e4a-bcda-f53bfd605caf
05:53:43.669 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 0.50%
05:53:43.676 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDown: fromID=94ce2525-6586-4e4a-bcda-f53bfd605caf from='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.local/target/repo/k6yk45sw-fg5e9-remote' toID=179d1409-4547-4311-b8b6-e09f6a57f0fe to='target/repo/k6yk45sw-w4yfi-local', fromRepoLocalSync=true
05:53:43.676 [pool-33-thread-1] INFO c.c.c.c.r.s.RepoToRepoSync - sync: locally syncing 179d1409-4547-4311-b8b6-e09f6a57f0fe ('target/repo/k6yk45sw-w4yfi-local')
05:53:43.703 [pool-33-thread-1] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 12.94%
05:53:43.709 [pool-33-thread-1] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 25.12%
05:53:43.716 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 1.00%
05:53:43.717 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 50.00%
05:53:43.718 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 58.17%
05:53:43.722 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 63.61%
05:53:43.726 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 74.50%
05:53:43.733 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 80.62%
05:53:43.740 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 90.83%
05:53:43.745 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 95.94%
05:53:43.751 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 100.00%
05:53:43.780 [main] INFO c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=94ce2525-6586-4e4a-bcda-f53bfd605caf remoteRepositoryId=179d1409-4547-4311-b8b6-e09f6a57f0fe localRepositoryRevisionSynced=-1 localRepositoryRevisionInProgress=2
05:53:43.821 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path=''
05:53:43.850 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path='/1'
05:53:43.874 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 30.22%
05:53:43.874 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path='/2'
05:53:43.887 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path='/2/1'
05:53:43.902 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path='/3'
05:53:43.928 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 35.46%
05:53:43.929 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 40.70%
05:53:43.929 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/1/a'
05:53:43.940 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='2e6e79f0c0c17d23502a6f1240b819529cf22074' to.sha1='<NoInstanceOf_NormalFileDto>'): /1/a
05:53:43.997 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=1, fromRepositoryId="FileInProgressMarker", toRepositoryId="1", path="94ce2525-6586-4e4a-bcda-f53bfd605caf"] on repo=179d1409-4547-4311-b8b6-e09f6a57f0fe
05:53:44.003 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/1/a'
05:53:44.004 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='2e6e79f0c0c17d23502a6f1240b819529cf22074' toSha1='null'
05:53:44.004 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/1/a'
05:53:44.004 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/1/a' offset=0
05:53:44.009 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/1/a' offset=0
05:53:44.023 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 436720 bytes in 19 ms. path='/1/a'
05:53:44.028 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_86f7e437faa5a7fce15d1ddcb9eaeaea377667b8_0 into destFile a
05:53:44.083 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=1, fromRepositoryId="FileInProgressMarker", toRepositoryId="1", path="94ce2525-6586-4e4a-bcda-f53bfd605caf"] on repo=179d1409-4547-4311-b8b6-e09f6a57f0fe
05:53:44.104 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/1/b'
05:53:44.118 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='9a70d3d2d07ca2c8c7da24df27857ab5d4b80459' to.sha1='<NoInstanceOf_NormalFileDto>'): /1/b
05:53:44.138 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=2, fromRepositoryId="FileInProgressMarker", toRepositoryId="2", path="94ce2525-6586-4e4a-bcda-f53bfd605caf"] on repo=179d1409-4547-4311-b8b6-e09f6a57f0fe
05:53:44.144 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/1/b'
05:53:44.145 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='9a70d3d2d07ca2c8c7da24df27857ab5d4b80459' toSha1='null'
05:53:44.145 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/1/b'
05:53:44.145 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/1/b' offset=0
05:53:44.149 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/1/b' offset=0
05:53:44.160 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 286056 bytes in 15 ms. path='/1/b'
05:53:44.166 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_e9d71f5ee7c92d6dc9e92ffdad17b8bd49418f98_0 into destFile b
05:53:44.197 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=2, fromRepositoryId="FileInProgressMarker", toRepositoryId="2", path="94ce2525-6586-4e4a-bcda-f53bfd605caf"] on repo=179d1409-4547-4311-b8b6-e09f6a57f0fe
05:53:44.206 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/1/c'
05:53:44.216 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='a92bba542d4517ac1fc4226caa5d4565a5d68ffa' to.sha1='<NoInstanceOf_NormalFileDto>'): /1/c
05:53:44.237 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=3, fromRepositoryId="FileInProgressMarker", toRepositoryId="3", path="94ce2525-6586-4e4a-bcda-f53bfd605caf"] on repo=179d1409-4547-4311-b8b6-e09f6a57f0fe
05:53:44.243 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/1/c'
05:53:44.243 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='a92bba542d4517ac1fc4226caa5d4565a5d68ffa' toSha1='null'
05:53:44.243 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/1/c'
05:53:44.243 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/1/c' offset=0
05:53:44.254 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/1/c' offset=0
05:53:44.272 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 826224 bytes in 29 ms. path='/1/c'
05:53:44.277 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_84a516841ba77a5b4648de2cd0dfcb30ea46dbb4_0 into destFile c
05:53:44.329 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=3, fromRepositoryId="FileInProgressMarker", toRepositoryId="3", path="94ce2525-6586-4e4a-bcda-f53bfd605caf"] on repo=179d1409-4547-4311-b8b6-e09f6a57f0fe
05:53:44.337 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/2/1/a'
05:53:44.347 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='077e4ad2d80c5c40281925bdc94a0ce1fa187950' to.sha1='<NoInstanceOf_NormalFileDto>'): /2/1/a
05:53:44.369 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=4, fromRepositoryId="FileInProgressMarker", toRepositoryId="4", path="94ce2525-6586-4e4a-bcda-f53bfd605caf"] on repo=179d1409-4547-4311-b8b6-e09f6a57f0fe
05:53:44.375 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/2/1/a'
05:53:44.375 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='077e4ad2d80c5c40281925bdc94a0ce1fa187950' toSha1='null'
05:53:44.375 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/2/1/a'
05:53:44.375 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/2/1/a' offset=0
05:53:44.376 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/2/1/a' offset=0
05:53:44.389 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 16920 bytes in 14 ms. path='/2/1/a'
05:53:44.394 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_86f7e437faa5a7fce15d1ddcb9eaeaea377667b8_0 into destFile a
05:53:44.408 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=4, fromRepositoryId="FileInProgressMarker", toRepositoryId="4", path="94ce2525-6586-4e4a-bcda-f53bfd605caf"] on repo=179d1409-4547-4311-b8b6-e09f6a57f0fe
05:53:44.416 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/2/1/b'
05:53:44.427 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='83834d6351410efb41c8e46d70e2782afb7dc743' to.sha1='<NoInstanceOf_NormalFileDto>'): /2/1/b
05:53:44.450 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=5, fromRepositoryId="FileInProgressMarker", toRepositoryId="5", path="94ce2525-6586-4e4a-bcda-f53bfd605caf"] on repo=179d1409-4547-4311-b8b6-e09f6a57f0fe
05:53:44.456 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/2/1/b'
05:53:44.456 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='83834d6351410efb41c8e46d70e2782afb7dc743' toSha1='null'
05:53:44.456 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/2/1/b'
05:53:44.456 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/2/1/b' offset=0
05:53:44.459 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/2/1/b' offset=0
05:53:44.472 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 155664 bytes in 16 ms. path='/2/1/b'
05:53:44.477 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_e9d71f5ee7c92d6dc9e92ffdad17b8bd49418f98_0 into destFile b
05:53:44.499 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=5, fromRepositoryId="FileInProgressMarker", toRepositoryId="5", path="94ce2525-6586-4e4a-bcda-f53bfd605caf"] on repo=179d1409-4547-4311-b8b6-e09f6a57f0fe
05:53:44.510 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/2/a'
05:53:44.518 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='263dda083665fc6b3747134f5d89f6a2dba63556' to.sha1='<NoInstanceOf_NormalFileDto>'): /2/a
05:53:44.537 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=6, fromRepositoryId="FileInProgressMarker", toRepositoryId="6", path="94ce2525-6586-4e4a-bcda-f53bfd605caf"] on repo=179d1409-4547-4311-b8b6-e09f6a57f0fe
05:53:44.542 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/2/a'
05:53:44.542 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='263dda083665fc6b3747134f5d89f6a2dba63556' toSha1='null'
05:53:44.543 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/2/a'
05:53:44.543 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/2/a' offset=0
05:53:44.546 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/2/a' offset=0
05:53:44.553 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 229030 bytes in 10 ms. path='/2/a'
05:53:44.557 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_86f7e437faa5a7fce15d1ddcb9eaeaea377667b8_0 into destFile a
05:53:44.582 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=6, fromRepositoryId="FileInProgressMarker", toRepositoryId="6", path="94ce2525-6586-4e4a-bcda-f53bfd605caf"] on repo=179d1409-4547-4311-b8b6-e09f6a57f0fe
05:53:44.590 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/3/a'
05:53:44.596 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='0f744c451a95e0dc6100c0f93e48f5ac8a31412c' to.sha1='<NoInstanceOf_NormalFileDto>'): /3/a
05:53:44.616 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=7, fromRepositoryId="FileInProgressMarker", toRepositoryId="7", path="94ce2525-6586-4e4a-bcda-f53bfd605caf"] on repo=179d1409-4547-4311-b8b6-e09f6a57f0fe
05:53:44.621 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/3/a'
05:53:44.621 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='0f744c451a95e0dc6100c0f93e48f5ac8a31412c' toSha1='null'
05:53:44.621 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/3/a'
05:53:44.622 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/3/a' offset=0
05:53:44.622 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/3/a' offset=0
05:53:44.627 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 1477 bytes in 5 ms. path='/3/a'
05:53:44.631 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_86f7e437faa5a7fce15d1ddcb9eaeaea377667b8_0 into destFile a
05:53:44.643 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=7, fromRepositoryId="FileInProgressMarker", toRepositoryId="7", path="94ce2525-6586-4e4a-bcda-f53bfd605caf"] on repo=179d1409-4547-4311-b8b6-e09f6a57f0fe
05:53:44.651 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/3/b'
05:53:44.658 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='3d4efc0c412aaaffd596a315a1da596046f76786' to.sha1='<NoInstanceOf_NormalFileDto>'): /3/b
05:53:44.674 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=8, fromRepositoryId="FileInProgressMarker", toRepositoryId="8", path="94ce2525-6586-4e4a-bcda-f53bfd605caf"] on repo=179d1409-4547-4311-b8b6-e09f6a57f0fe
05:53:44.680 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/3/b'
05:53:44.680 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='3d4efc0c412aaaffd596a315a1da596046f76786' toSha1='null'
05:53:44.680 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/3/b'
05:53:44.680 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/3/b' offset=0
05:53:44.684 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/3/b' offset=0
05:53:44.694 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 309168 bytes in 14 ms. path='/3/b'
05:53:44.699 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_e9d71f5ee7c92d6dc9e92ffdad17b8bd49418f98_0 into destFile b
05:53:44.727 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=8, fromRepositoryId="FileInProgressMarker", toRepositoryId="8", path="94ce2525-6586-4e4a-bcda-f53bfd605caf"] on repo=179d1409-4547-4311-b8b6-e09f6a57f0fe
05:53:44.737 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/3/c'
05:53:44.754 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='d97bcee430d126f6bc5d7ffc6f317fb026d479b6' to.sha1='<NoInstanceOf_NormalFileDto>'): /3/c
05:53:44.776 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=9, fromRepositoryId="FileInProgressMarker", toRepositoryId="9", path="94ce2525-6586-4e4a-bcda-f53bfd605caf"] on repo=179d1409-4547-4311-b8b6-e09f6a57f0fe
05:53:44.782 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/3/c'
05:53:44.782 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='d97bcee430d126f6bc5d7ffc6f317fb026d479b6' toSha1='null'
05:53:44.782 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/3/c'
05:53:44.783 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/3/c' offset=0
05:53:44.791 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/3/c' offset=0
05:53:44.802 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 426857 bytes in 19 ms. path='/3/c'
05:53:44.807 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_84a516841ba77a5b4648de2cd0dfcb30ea46dbb4_0 into destFile c
05:53:44.836 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=9, fromRepositoryId="FileInProgressMarker", toRepositoryId="9", path="94ce2525-6586-4e4a-bcda-f53bfd605caf"] on repo=179d1409-4547-4311-b8b6-e09f6a57f0fe
05:53:44.845 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/3/d'
05:53:44.859 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='318b2f27e59bb57686b95ed3b21d298738c5275c' to.sha1='<NoInstanceOf_NormalFileDto>'): /3/d
05:53:44.877 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=10, fromRepositoryId="FileInProgressMarker", toRepositoryId="10", path="94ce2525-6586-4e4a-bcda-f53bfd605caf"] on repo=179d1409-4547-4311-b8b6-e09f6a57f0fe
05:53:44.882 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/3/d'
05:53:44.882 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='318b2f27e59bb57686b95ed3b21d298738c5275c' toSha1='null'
05:53:44.882 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/3/d'
05:53:44.882 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/3/d' offset=0
05:53:44.895 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/3/d' offset=0
05:53:44.914 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 701904 bytes in 32 ms. path='/3/d'
05:53:44.918 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_3c363836cf4e16666669a25da280a1865c2d2874_0 into destFile d
05:53:44.960 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=10, fromRepositoryId="FileInProgressMarker", toRepositoryId="10", path="94ce2525-6586-4e4a-bcda-f53bfd605caf"] on repo=179d1409-4547-4311-b8b6-e09f6a57f0fe
05:53:44.968 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 49.27%
05:53:44.987 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=94ce2525-6586-4e4a-bcda-f53bfd605caf remoteRepositoryId=179d1409-4547-4311-b8b6-e09f6a57f0fe localRepositoryRevisionSynced=2
05:53:45.000 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=179d1409-4547-4311-b8b6-e09f6a57f0fe remoteRepositoryId=94ce2525-6586-4e4a-bcda-f53bfd605caf transaction.localRevision=57 remoteFromLocalRevision=2
05:53:45.013 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncUp: fromID=179d1409-4547-4311-b8b6-e09f6a57f0fe from='target/repo/k6yk45sw-w4yfi-local' toID=94ce2525-6586-4e4a-bcda-f53bfd605caf to='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.local/target/repo/k6yk45sw-fg5e9-remote'
05:53:45.047 [main] INFO c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=179d1409-4547-4311-b8b6-e09f6a57f0fe remoteRepositoryId=94ce2525-6586-4e4a-bcda-f53bfd605caf localRepositoryRevisionSynced=-1 localRepositoryRevisionInProgress=57
05:53:45.062 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 63.43%
05:53:45.062 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 74.63%
05:53:45.073 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=179d1409-4547-4311-b8b6-e09f6a57f0fe remoteRepositoryId=94ce2525-6586-4e4a-bcda-f53bfd605caf localRepositoryRevisionSynced=57
05:53:45.088 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=94ce2525-6586-4e4a-bcda-f53bfd605caf remoteRepositoryId=179d1409-4547-4311-b8b6-e09f6a57f0fe transaction.localRevision=5 remoteFromLocalRevision=57
05:53:45.094 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDown: fromID=94ce2525-6586-4e4a-bcda-f53bfd605caf from='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.local/target/repo/k6yk45sw-fg5e9-remote' toID=179d1409-4547-4311-b8b6-e09f6a57f0fe to='target/repo/k6yk45sw-w4yfi-local', fromRepoLocalSync=false
05:53:45.116 [main] INFO c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=94ce2525-6586-4e4a-bcda-f53bfd605caf remoteRepositoryId=179d1409-4547-4311-b8b6-e09f6a57f0fe localRepositoryRevisionSynced=2 localRepositoryRevisionInProgress=5
05:53:45.117 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 88.31%
05:53:45.118 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 99.50%
05:53:45.121 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=94ce2525-6586-4e4a-bcda-f53bfd605caf remoteRepositoryId=179d1409-4547-4311-b8b6-e09f6a57f0fe localRepositoryRevisionSynced=5
05:53:45.132 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=179d1409-4547-4311-b8b6-e09f6a57f0fe remoteRepositoryId=94ce2525-6586-4e4a-bcda-f53bfd605caf transaction.localRevision=60 remoteFromLocalRevision=5
05:53:45.138 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 100.00%
05:53:45.158 [main] INFO c.c.c.l.LocalRepoManagerImpl - [52512096]getCloseDeferredMillis: closeDeferredMillis=20000
05:53:45.158 [main] INFO c.c.c.l.LocalRepoManagerImpl - [52512096]close: Deferring shut down of real LocalRepoManager 20000 ms.
05:53:45.159 [main] INFO c.c.c.l.LocalRepoManagerImpl - [443c098]getCloseDeferredMillis: closeDeferredMillis=20000
05:53:45.159 [main] INFO c.c.c.l.LocalRepoManagerImpl - [443c098]close: Deferring shut down of real LocalRepoManager 20000 ms.
05:53:45.239 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 1.00%
05:53:45.241 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 50.00%
05:53:45.242 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 58.17%
05:53:45.245 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 63.61%
05:53:45.249 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 74.50%
05:53:45.282 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 80.62%
05:53:45.286 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 90.83%
05:53:45.307 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 96.55%
05:53:45.326 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 100.00%
05:53:45.348 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 0.50%
05:53:45.352 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDown: fromID=94ce2525-6586-4e4a-bcda-f53bfd605caf from='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.local/target/repo/k6yk45sw-fg5e9-remote' toID=179d1409-4547-4311-b8b6-e09f6a57f0fe to='target/repo/k6yk45sw-w4yfi-local', fromRepoLocalSync=true
05:53:45.352 [pool-34-thread-1] INFO c.c.c.c.r.s.RepoToRepoSync - sync: locally syncing 179d1409-4547-4311-b8b6-e09f6a57f0fe ('target/repo/k6yk45sw-w4yfi-local')
05:53:45.356 [pool-34-thread-1] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 12.94%
05:53:45.376 [pool-34-thread-1] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 19.03%
05:53:45.404 [pool-34-thread-1] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 24.11%
05:53:45.422 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 1.00%
05:53:45.425 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 50.00%
05:53:45.429 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 58.17%
05:53:45.434 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 63.61%
05:53:45.438 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 74.50%
05:53:45.451 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 80.62%
05:53:45.455 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 90.83%
05:53:45.461 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 96.55%
05:53:45.470 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 100.00%
05:53:45.481 [main] INFO c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=94ce2525-6586-4e4a-bcda-f53bfd605caf remoteRepositoryId=179d1409-4547-4311-b8b6-e09f6a57f0fe localRepositoryRevisionSynced=5 localRepositoryRevisionInProgress=8
05:53:45.498 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 29.95%
05:53:45.499 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path='/2/1'
05:53:45.530 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path='/2/1/5'
05:53:45.552 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path='/3'
05:53:45.569 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 35.11%
05:53:45.572 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 40.28%
05:53:45.573 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 45.45%
05:53:45.573 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/2/1/5/aaa'
05:53:45.587 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='1c75e4071474dbaf3453bbf3cbc50e905373d14f' to.sha1='<NoInstanceOf_NormalFileDto>'): /2/1/5/aaa
05:53:45.608 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=11, fromRepositoryId="FileInProgressMarker", toRepositoryId="11", path="94ce2525-6586-4e4a-bcda-f53bfd605caf"] on repo=179d1409-4547-4311-b8b6-e09f6a57f0fe
05:53:45.614 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/2/1/5/aaa'
05:53:45.614 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='1c75e4071474dbaf3453bbf3cbc50e905373d14f' toSha1='null'
05:53:45.614 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/2/1/5/aaa'
05:53:45.614 [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
05:53:45.619 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/2/1/5/aaa' offset=0
05:53:45.629 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 152388 bytes in 15 ms. path='/2/1/5/aaa'
05:53:45.635 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_7e240de74fb1ed08fa08d38063f6a6a91462a815_0 into destFile aaa
05:53:45.654 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=11, fromRepositoryId="FileInProgressMarker", toRepositoryId="11", path="94ce2525-6586-4e4a-bcda-f53bfd605caf"] on repo=179d1409-4547-4311-b8b6-e09f6a57f0fe
05:53:45.662 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/2/1/5/bbb'
05:53:45.681 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='9d207a68ab5afb2440c4f48152ffdcd8308e4dbe' to.sha1='<NoInstanceOf_NormalFileDto>'): /2/1/5/bbb
05:53:45.701 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=12, fromRepositoryId="FileInProgressMarker", toRepositoryId="12", path="94ce2525-6586-4e4a-bcda-f53bfd605caf"] on repo=179d1409-4547-4311-b8b6-e09f6a57f0fe
05:53:45.706 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/2/1/5/bbb'
05:53:45.706 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='9d207a68ab5afb2440c4f48152ffdcd8308e4dbe' toSha1='null'
05:53:45.706 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/2/1/5/bbb'
05:53:45.706 [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
05:53:45.709 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/2/1/5/bbb' offset=0
05:53:45.717 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 171957 bytes in 11 ms. path='/2/1/5/bbb'
05:53:45.723 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_5cb138284d431abd6a053a56625ec088bfb88912_0 into destFile bbb
05:53:45.744 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=12, fromRepositoryId="FileInProgressMarker", toRepositoryId="12", path="94ce2525-6586-4e4a-bcda-f53bfd605caf"] on repo=179d1409-4547-4311-b8b6-e09f6a57f0fe
05:53:45.752 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/3/e'
05:53:45.761 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='b3fd971467511093cc2086c925bf94b6ce2c0c76' to.sha1='<NoInstanceOf_NormalFileDto>'): /3/e
05:53:45.778 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=13, fromRepositoryId="FileInProgressMarker", toRepositoryId="13", path="94ce2525-6586-4e4a-bcda-f53bfd605caf"] on repo=179d1409-4547-4311-b8b6-e09f6a57f0fe
05:53:45.784 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/3/e'
05:53:45.784 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='b3fd971467511093cc2086c925bf94b6ce2c0c76' toSha1='null'
05:53:45.784 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/3/e'
05:53:45.785 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/3/e' offset=0
05:53:45.792 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/3/e' offset=0
05:53:45.810 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 541304 bytes in 25 ms. path='/3/e'
05:53:45.816 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_58e6b3a414a1e090dfc6029add0f3555ccba127f_0 into destFile e
05:53:45.853 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=13, fromRepositoryId="FileInProgressMarker", toRepositoryId="13", path="94ce2525-6586-4e4a-bcda-f53bfd605caf"] on repo=179d1409-4547-4311-b8b6-e09f6a57f0fe
05:53:45.870 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=94ce2525-6586-4e4a-bcda-f53bfd605caf remoteRepositoryId=179d1409-4547-4311-b8b6-e09f6a57f0fe localRepositoryRevisionSynced=8
05:53:45.881 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=179d1409-4547-4311-b8b6-e09f6a57f0fe remoteRepositoryId=94ce2525-6586-4e4a-bcda-f53bfd605caf transaction.localRevision=79 remoteFromLocalRevision=8
05:53:45.898 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncUp: fromID=179d1409-4547-4311-b8b6-e09f6a57f0fe from='target/repo/k6yk45sw-w4yfi-local' toID=94ce2525-6586-4e4a-bcda-f53bfd605caf to='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.local/target/repo/k6yk45sw-fg5e9-remote'
05:53:45.917 [main] INFO c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=179d1409-4547-4311-b8b6-e09f6a57f0fe remoteRepositoryId=94ce2525-6586-4e4a-bcda-f53bfd605caf localRepositoryRevisionSynced=57 localRepositoryRevisionInProgress=79
05:53:45.920 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 52.24%
05:53:45.921 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 63.43%
05:53:45.921 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 74.63%
05:53:45.925 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=179d1409-4547-4311-b8b6-e09f6a57f0fe remoteRepositoryId=94ce2525-6586-4e4a-bcda-f53bfd605caf localRepositoryRevisionSynced=79
05:53:45.933 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=94ce2525-6586-4e4a-bcda-f53bfd605caf remoteRepositoryId=179d1409-4547-4311-b8b6-e09f6a57f0fe transaction.localRevision=11 remoteFromLocalRevision=79
05:53:45.940 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDown: fromID=94ce2525-6586-4e4a-bcda-f53bfd605caf from='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.local/target/repo/k6yk45sw-fg5e9-remote' toID=179d1409-4547-4311-b8b6-e09f6a57f0fe to='target/repo/k6yk45sw-w4yfi-local', fromRepoLocalSync=false
05:53:45.954 [main] INFO c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=94ce2525-6586-4e4a-bcda-f53bfd605caf remoteRepositoryId=179d1409-4547-4311-b8b6-e09f6a57f0fe localRepositoryRevisionSynced=8 localRepositoryRevisionInProgress=11
05:53:45.957 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 88.31%
05:53:45.957 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 99.50%
05:53:45.961 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=94ce2525-6586-4e4a-bcda-f53bfd605caf remoteRepositoryId=179d1409-4547-4311-b8b6-e09f6a57f0fe localRepositoryRevisionSynced=11
05:53:45.970 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=179d1409-4547-4311-b8b6-e09f6a57f0fe remoteRepositoryId=94ce2525-6586-4e4a-bcda-f53bfd605caf transaction.localRevision=82 remoteFromLocalRevision=11
05:53:45.980 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 100.00%
05:53:45.980 [main] INFO c.c.c.l.LocalRepoManagerImpl - [52512096]close: Deferring shut down of real LocalRepoManager 20000 ms.
05:53:46.004 [main] INFO c.c.c.l.LocalRepoManagerImpl - [443c098]close: Deferring shut down of real LocalRepoManager 20000 ms.
05:53:46.073 [main] INFO c.c.c.l.LocalRepoManagerImpl - [5d0e2a2c]<init>: localRoot='/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.local/target/repo/k6yk4gd2-7mo8h-local'
05:53:46.078 [main] INFO c.c.c.l.LocalRepoManagerImpl - [5d0e2a2c]initLockFile: Repository '/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.local/target/repo/k6yk4gd2-7mo8h-local' locked successfully.
05:53:49.847 [main] INFO c.c.c.l.LocalRepoManagerImpl - [5d0e2a2c]initPersistenceManagerFactory: repositoryId=b1b4c948-743d-4fd2-8bbb-b37207fc9015
05:53:49.852 [main] INFO c.c.c.l.LocalRepoManagerImpl - [5d0e2a2c]initPersistenceManagerFactory: Started up PersistenceManagerFactory successfully in 1735 ms.
05:53:49.876 [main] INFO c.c.c.l.LocalRepoManagerImpl - [3f3ddcd7]<init>: localRoot='/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.local/target/repo/k6yk4gd3-1o6bh-remote'
05:53:49.882 [main] INFO c.c.c.l.LocalRepoManagerImpl - [3f3ddcd7]initLockFile: Repository '/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.local/target/repo/k6yk4gd3-1o6bh-remote' locked successfully.
05:53:52.328 [closeDeferredTimer-5a202948-2] INFO c.c.c.l.LocalRepoManagerImpl - [5a202948]_close: Shutting down real LocalRepoManager.
05:53:52.724 [closeDeferredTimer-757721c3-2] INFO c.c.c.l.LocalRepoManagerImpl - [757721c3]_close: Shutting down real LocalRepoManager.
05:53:56.396 [main] INFO c.c.c.l.LocalRepoManagerImpl - [3f3ddcd7]initPersistenceManagerFactory: repositoryId=8b630ea1-5fd5-49df-b996-434bdda58481
05:53:56.403 [main] INFO c.c.c.l.LocalRepoManagerImpl - [3f3ddcd7]initPersistenceManagerFactory: Started up PersistenceManagerFactory successfully in 4380 ms.
05:53:56.520 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 1.00%
05:53:56.530 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 50.00%
05:53:56.541 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 58.00%
05:53:56.598 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 63.61%
05:53:56.617 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 74.34%
05:53:56.641 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 79.42%
05:53:56.659 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 90.67%
05:53:56.678 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 95.69%
05:53:56.712 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 100.00%
05:53:56.741 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - local repo: b1b4c948-743d-4fd2-8bbb-b37207fc9015
05:53:56.741 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - remote repo: 8b630ea1-5fd5-49df-b996-434bdda58481
05:53:56.747 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 0.50%
05:53:56.753 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDown: fromID=8b630ea1-5fd5-49df-b996-434bdda58481 from='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.local/target/repo/k6yk4gd3-1o6bh-remote' toID=b1b4c948-743d-4fd2-8bbb-b37207fc9015 to='target/repo/k6yk4gd2-7mo8h-local', fromRepoLocalSync=true
05:53:56.753 [pool-35-thread-1] INFO c.c.c.c.r.s.RepoToRepoSync - sync: locally syncing b1b4c948-743d-4fd2-8bbb-b37207fc9015 ('target/repo/k6yk4gd2-7mo8h-local')
05:53:56.778 [pool-35-thread-1] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 12.94%
05:53:56.787 [pool-35-thread-1] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 25.12%
05:53:56.793 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 1.00%
05:53:56.794 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 50.00%
05:53:56.797 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 58.17%
05:53:56.805 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 63.61%
05:53:56.812 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 74.50%
05:53:56.821 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 80.62%
05:53:56.825 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 90.83%
05:53:56.830 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 95.94%
05:53:56.838 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 100.00%
05:53:56.869 [main] INFO c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=8b630ea1-5fd5-49df-b996-434bdda58481 remoteRepositoryId=b1b4c948-743d-4fd2-8bbb-b37207fc9015 localRepositoryRevisionSynced=-1 localRepositoryRevisionInProgress=2
05:53:56.911 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path=''
05:53:56.943 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path='/1'
05:53:56.961 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 30.22%
05:53:56.961 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path='/2'
05:53:56.979 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path='/2/1'
05:53:56.999 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path='/3'
05:53:57.021 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 35.46%
05:53:57.022 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 40.70%
05:53:57.022 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/1/a'
05:53:57.030 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='92fb540f1a25fbf68b0e2cc7d3c10495cf5824d6' to.sha1='<NoInstanceOf_NormalFileDto>'): /1/a
05:53:57.099 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=1, fromRepositoryId="FileInProgressMarker", toRepositoryId="1", path="8b630ea1-5fd5-49df-b996-434bdda58481"] on repo=b1b4c948-743d-4fd2-8bbb-b37207fc9015
05:53:57.105 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/1/a'
05:53:57.105 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='92fb540f1a25fbf68b0e2cc7d3c10495cf5824d6' toSha1='null'
05:53:57.105 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/1/a'
05:53:57.105 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/1/a' offset=0
05:53:57.111 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/1/a' offset=0
05:53:57.132 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 314700 bytes in 27 ms. path='/1/a'
05:53:57.137 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_86f7e437faa5a7fce15d1ddcb9eaeaea377667b8_0 into destFile a
05:53:57.184 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=1, fromRepositoryId="FileInProgressMarker", toRepositoryId="1", path="8b630ea1-5fd5-49df-b996-434bdda58481"] on repo=b1b4c948-743d-4fd2-8bbb-b37207fc9015
05:53:57.197 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/1/b'
05:53:57.211 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='b9b6119e6a9f059fe4e407864753870b2febef51' to.sha1='<NoInstanceOf_NormalFileDto>'): /1/b
05:53:57.230 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=2, fromRepositoryId="FileInProgressMarker", toRepositoryId="2", path="8b630ea1-5fd5-49df-b996-434bdda58481"] on repo=b1b4c948-743d-4fd2-8bbb-b37207fc9015
05:53:57.234 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/1/b'
05:53:57.235 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='b9b6119e6a9f059fe4e407864753870b2febef51' toSha1='null'
05:53:57.235 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/1/b'
05:53:57.235 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/1/b' offset=0
05:53:57.241 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/1/b' offset=0
05:53:57.255 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 335181 bytes in 20 ms. path='/1/b'
05:53:57.259 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_e9d71f5ee7c92d6dc9e92ffdad17b8bd49418f98_0 into destFile b
05:53:57.285 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=2, fromRepositoryId="FileInProgressMarker", toRepositoryId="2", path="8b630ea1-5fd5-49df-b996-434bdda58481"] on repo=b1b4c948-743d-4fd2-8bbb-b37207fc9015
05:53:57.292 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/1/c'
05:53:57.309 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='42666fd76f1baa758c482c4a5348947fa0245e35' to.sha1='<NoInstanceOf_NormalFileDto>'): /1/c
05:53:57.328 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=3, fromRepositoryId="FileInProgressMarker", toRepositoryId="3", path="8b630ea1-5fd5-49df-b996-434bdda58481"] on repo=b1b4c948-743d-4fd2-8bbb-b37207fc9015
05:53:57.333 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/1/c'
05:53:57.333 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='42666fd76f1baa758c482c4a5348947fa0245e35' toSha1='null'
05:53:57.333 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/1/c'
05:53:57.333 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/1/c' offset=0
05:53:57.334 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/1/c' offset=0
05:53:57.342 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 33733 bytes in 9 ms. path='/1/c'
05:53:57.349 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_84a516841ba77a5b4648de2cd0dfcb30ea46dbb4_0 into destFile c
05:53:57.363 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=3, fromRepositoryId="FileInProgressMarker", toRepositoryId="3", path="8b630ea1-5fd5-49df-b996-434bdda58481"] on repo=b1b4c948-743d-4fd2-8bbb-b37207fc9015
05:53:57.372 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/2/1/a'
05:53:57.388 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='badc93cff350ebd1224411a1d83a071def2bbeee' to.sha1='<NoInstanceOf_NormalFileDto>'): /2/1/a
05:53:57.405 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=4, fromRepositoryId="FileInProgressMarker", toRepositoryId="4", path="8b630ea1-5fd5-49df-b996-434bdda58481"] on repo=b1b4c948-743d-4fd2-8bbb-b37207fc9015
05:53:57.410 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/2/1/a'
05:53:57.410 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='badc93cff350ebd1224411a1d83a071def2bbeee' toSha1='null'
05:53:57.410 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/2/1/a'
05:53:57.410 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/2/1/a' offset=0
05:53:57.411 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/2/1/a' offset=0
05:53:57.422 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 54072 bytes in 12 ms. path='/2/1/a'
05:53:57.426 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_86f7e437faa5a7fce15d1ddcb9eaeaea377667b8_0 into destFile a
05:53:57.440 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=4, fromRepositoryId="FileInProgressMarker", toRepositoryId="4", path="8b630ea1-5fd5-49df-b996-434bdda58481"] on repo=b1b4c948-743d-4fd2-8bbb-b37207fc9015
05:53:57.446 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/2/1/b'
05:53:57.462 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='7f6fd8ff1d1dc3dad853c0157eeb1311a428cbc0' to.sha1='<NoInstanceOf_NormalFileDto>'): /2/1/b
05:53:57.482 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=5, fromRepositoryId="FileInProgressMarker", toRepositoryId="5", path="8b630ea1-5fd5-49df-b996-434bdda58481"] on repo=b1b4c948-743d-4fd2-8bbb-b37207fc9015
05:53:57.486 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/2/1/b'
05:53:57.486 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='7f6fd8ff1d1dc3dad853c0157eeb1311a428cbc0' toSha1='null'
05:53:57.486 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/2/1/b'
05:53:57.486 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/2/1/b' offset=0
05:53:57.489 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/2/1/b' offset=0
05:53:57.499 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 150258 bytes in 13 ms. path='/2/1/b'
05:53:57.505 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_e9d71f5ee7c92d6dc9e92ffdad17b8bd49418f98_0 into destFile b
05:53:57.523 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=5, fromRepositoryId="FileInProgressMarker", toRepositoryId="5", path="8b630ea1-5fd5-49df-b996-434bdda58481"] on repo=b1b4c948-743d-4fd2-8bbb-b37207fc9015
05:53:57.529 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/2/a'
05:53:57.542 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='191913b0987b92372008e9f8ab536b74a4f9ba58' to.sha1='<NoInstanceOf_NormalFileDto>'): /2/a
05:53:57.558 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=6, fromRepositoryId="FileInProgressMarker", toRepositoryId="6", path="8b630ea1-5fd5-49df-b996-434bdda58481"] on repo=b1b4c948-743d-4fd2-8bbb-b37207fc9015
05:53:57.563 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/2/a'
05:53:57.563 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='191913b0987b92372008e9f8ab536b74a4f9ba58' toSha1='null'
05:53:57.563 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/2/a'
05:53:57.563 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/2/a' offset=0
05:53:57.577 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/2/a' offset=0
05:53:57.588 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 466640 bytes in 25 ms. path='/2/a'
05:53:57.594 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_86f7e437faa5a7fce15d1ddcb9eaeaea377667b8_0 into destFile a
05:53:57.629 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=6, fromRepositoryId="FileInProgressMarker", toRepositoryId="6", path="8b630ea1-5fd5-49df-b996-434bdda58481"] on repo=b1b4c948-743d-4fd2-8bbb-b37207fc9015
05:53:57.637 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/3/a'
05:53:57.650 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='f53d5579abf535bdfdccba351c12c4b7c753b1dd' to.sha1='<NoInstanceOf_NormalFileDto>'): /3/a
05:53:57.666 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=7, fromRepositoryId="FileInProgressMarker", toRepositoryId="7", path="8b630ea1-5fd5-49df-b996-434bdda58481"] on repo=b1b4c948-743d-4fd2-8bbb-b37207fc9015
05:53:57.671 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/3/a'
05:53:57.671 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='f53d5579abf535bdfdccba351c12c4b7c753b1dd' toSha1='null'
05:53:57.671 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/3/a'
05:53:57.672 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/3/a' offset=0
05:53:57.684 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/3/a' offset=0
05:53:57.696 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 388476 bytes in 24 ms. path='/3/a'
05:53:57.701 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_86f7e437faa5a7fce15d1ddcb9eaeaea377667b8_0 into destFile a
05:53:57.730 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=7, fromRepositoryId="FileInProgressMarker", toRepositoryId="7", path="8b630ea1-5fd5-49df-b996-434bdda58481"] on repo=b1b4c948-743d-4fd2-8bbb-b37207fc9015
05:53:57.740 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/3/b'
05:53:57.752 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='6606bd1317c3f9ea6a48e1ad159707fcc959512c' to.sha1='<NoInstanceOf_NormalFileDto>'): /3/b
05:53:57.767 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=8, fromRepositoryId="FileInProgressMarker", toRepositoryId="8", path="8b630ea1-5fd5-49df-b996-434bdda58481"] on repo=b1b4c948-743d-4fd2-8bbb-b37207fc9015
05:53:57.773 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/3/b'
05:53:57.773 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='6606bd1317c3f9ea6a48e1ad159707fcc959512c' toSha1='null'
05:53:57.773 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/3/b'
05:53:57.773 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/3/b' offset=0
05:53:57.775 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/3/b' offset=0
05:53:57.783 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 60736 bytes in 10 ms. path='/3/b'
05:53:57.790 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_e9d71f5ee7c92d6dc9e92ffdad17b8bd49418f98_0 into destFile b
05:53:57.808 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=8, fromRepositoryId="FileInProgressMarker", toRepositoryId="8", path="8b630ea1-5fd5-49df-b996-434bdda58481"] on repo=b1b4c948-743d-4fd2-8bbb-b37207fc9015
05:53:57.814 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/3/c'
05:53:57.824 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='71a87ecee6836440e54bc3445bcd4be7123e3286' to.sha1='<NoInstanceOf_NormalFileDto>'): /3/c
05:53:57.840 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=9, fromRepositoryId="FileInProgressMarker", toRepositoryId="9", path="8b630ea1-5fd5-49df-b996-434bdda58481"] on repo=b1b4c948-743d-4fd2-8bbb-b37207fc9015
05:53:57.846 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/3/c'
05:53:57.846 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='71a87ecee6836440e54bc3445bcd4be7123e3286' toSha1='null'
05:53:57.846 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/3/c'
05:53:57.846 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/3/c' offset=0
05:53:57.846 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/3/c' offset=0
05:53:57.854 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 25785 bytes in 8 ms. path='/3/c'
05:53:57.858 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_84a516841ba77a5b4648de2cd0dfcb30ea46dbb4_0 into destFile c
05:53:57.872 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=9, fromRepositoryId="FileInProgressMarker", toRepositoryId="9", path="8b630ea1-5fd5-49df-b996-434bdda58481"] on repo=b1b4c948-743d-4fd2-8bbb-b37207fc9015
05:53:57.880 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/3/d'
05:53:57.894 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='9ef77edd30528b4c9cd2fc5bdc946bdfd57992a4' to.sha1='<NoInstanceOf_NormalFileDto>'): /3/d
05:53:57.913 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=10, fromRepositoryId="FileInProgressMarker", toRepositoryId="10", path="8b630ea1-5fd5-49df-b996-434bdda58481"] on repo=b1b4c948-743d-4fd2-8bbb-b37207fc9015
05:53:57.918 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/3/d'
05:53:57.918 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='9ef77edd30528b4c9cd2fc5bdc946bdfd57992a4' toSha1='null'
05:53:57.919 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/3/d'
05:53:57.919 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/3/d' offset=0
05:53:57.924 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/3/d' offset=0
05:53:57.938 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 209454 bytes in 19 ms. path='/3/d'
05:53:57.944 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_3c363836cf4e16666669a25da280a1865c2d2874_0 into destFile d
05:53:57.969 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=10, fromRepositoryId="FileInProgressMarker", toRepositoryId="10", path="8b630ea1-5fd5-49df-b996-434bdda58481"] on repo=b1b4c948-743d-4fd2-8bbb-b37207fc9015
05:53:57.977 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 49.27%
05:53:58.004 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=8b630ea1-5fd5-49df-b996-434bdda58481 remoteRepositoryId=b1b4c948-743d-4fd2-8bbb-b37207fc9015 localRepositoryRevisionSynced=2
05:53:58.025 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=b1b4c948-743d-4fd2-8bbb-b37207fc9015 remoteRepositoryId=8b630ea1-5fd5-49df-b996-434bdda58481 transaction.localRevision=57 remoteFromLocalRevision=2
05:53:58.039 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncUp: fromID=b1b4c948-743d-4fd2-8bbb-b37207fc9015 from='target/repo/k6yk4gd2-7mo8h-local' toID=8b630ea1-5fd5-49df-b996-434bdda58481 to='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.local/target/repo/k6yk4gd3-1o6bh-remote'
05:53:58.069 [main] INFO c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=b1b4c948-743d-4fd2-8bbb-b37207fc9015 remoteRepositoryId=8b630ea1-5fd5-49df-b996-434bdda58481 localRepositoryRevisionSynced=-1 localRepositoryRevisionInProgress=57
05:53:58.078 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 63.43%
05:53:58.079 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 74.63%
05:53:58.089 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=b1b4c948-743d-4fd2-8bbb-b37207fc9015 remoteRepositoryId=8b630ea1-5fd5-49df-b996-434bdda58481 localRepositoryRevisionSynced=57
05:53:58.103 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=8b630ea1-5fd5-49df-b996-434bdda58481 remoteRepositoryId=b1b4c948-743d-4fd2-8bbb-b37207fc9015 transaction.localRevision=5 remoteFromLocalRevision=57
05:53:58.109 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDown: fromID=8b630ea1-5fd5-49df-b996-434bdda58481 from='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.local/target/repo/k6yk4gd3-1o6bh-remote' toID=b1b4c948-743d-4fd2-8bbb-b37207fc9015 to='target/repo/k6yk4gd2-7mo8h-local', fromRepoLocalSync=false
05:53:58.129 [main] INFO c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=8b630ea1-5fd5-49df-b996-434bdda58481 remoteRepositoryId=b1b4c948-743d-4fd2-8bbb-b37207fc9015 localRepositoryRevisionSynced=2 localRepositoryRevisionInProgress=5
05:53:58.134 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 88.31%
05:53:58.134 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 99.50%
05:53:58.140 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=8b630ea1-5fd5-49df-b996-434bdda58481 remoteRepositoryId=b1b4c948-743d-4fd2-8bbb-b37207fc9015 localRepositoryRevisionSynced=5
05:53:58.148 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=b1b4c948-743d-4fd2-8bbb-b37207fc9015 remoteRepositoryId=8b630ea1-5fd5-49df-b996-434bdda58481 transaction.localRevision=60 remoteFromLocalRevision=5
05:53:58.154 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 100.00%
05:53:58.171 [main] INFO c.c.c.l.LocalRepoManagerImpl - [5d0e2a2c]getCloseDeferredMillis: closeDeferredMillis=20000
05:53:58.171 [main] INFO c.c.c.l.LocalRepoManagerImpl - [5d0e2a2c]close: Deferring shut down of real LocalRepoManager 20000 ms.
05:53:58.171 [main] INFO c.c.c.l.LocalRepoManagerImpl - [3f3ddcd7]getCloseDeferredMillis: closeDeferredMillis=20000
05:53:58.171 [main] INFO c.c.c.l.LocalRepoManagerImpl - [3f3ddcd7]close: Deferring shut down of real LocalRepoManager 20000 ms.
05:53:58.219 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 1.00%
05:53:58.221 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 50.00%
05:53:58.222 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 58.17%
05:53:58.226 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 63.61%
05:53:58.230 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 74.50%
05:53:58.277 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 80.62%
05:53:58.285 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 90.83%
05:53:58.290 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 95.94%
05:53:58.314 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 100.00%
05:53:58.334 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 0.50%
05:53:58.335 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDown: fromID=8b630ea1-5fd5-49df-b996-434bdda58481 from='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.local/target/repo/k6yk4gd3-1o6bh-remote' toID=b1b4c948-743d-4fd2-8bbb-b37207fc9015 to='target/repo/k6yk4gd2-7mo8h-local', fromRepoLocalSync=true
05:53:58.335 [pool-36-thread-1] INFO c.c.c.c.r.s.RepoToRepoSync - sync: locally syncing b1b4c948-743d-4fd2-8bbb-b37207fc9015 ('target/repo/k6yk4gd2-7mo8h-local')
05:53:58.338 [pool-36-thread-1] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 12.94%
05:53:58.359 [pool-36-thread-1] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 19.03%
05:53:58.381 [pool-36-thread-1] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 24.11%
05:53:58.400 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 1.00%
05:53:58.401 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 50.00%
05:53:58.402 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 58.17%
05:53:58.406 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 63.61%
05:53:58.411 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 74.50%
05:53:58.418 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 80.62%
05:53:58.421 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 90.83%
05:53:58.427 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 95.94%
05:53:58.436 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 100.00%
05:53:58.448 [main] INFO c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=8b630ea1-5fd5-49df-b996-434bdda58481 remoteRepositoryId=b1b4c948-743d-4fd2-8bbb-b37207fc9015 localRepositoryRevisionSynced=5 localRepositoryRevisionInProgress=8
05:53:58.471 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 29.23%
05:53:58.472 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path='/2/1'
05:53:58.493 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 34.83%
05:53:58.495 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncModifications: Deleting '/2/1/a'
05:53:58.548 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 40.42%
05:53:58.549 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 46.02%
05:53:58.567 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=8b630ea1-5fd5-49df-b996-434bdda58481 remoteRepositoryId=b1b4c948-743d-4fd2-8bbb-b37207fc9015 localRepositoryRevisionSynced=8
05:53:58.582 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=b1b4c948-743d-4fd2-8bbb-b37207fc9015 remoteRepositoryId=8b630ea1-5fd5-49df-b996-434bdda58481 transaction.localRevision=63 remoteFromLocalRevision=8
05:53:58.591 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncUp: fromID=b1b4c948-743d-4fd2-8bbb-b37207fc9015 from='target/repo/k6yk4gd2-7mo8h-local' toID=8b630ea1-5fd5-49df-b996-434bdda58481 to='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.local/target/repo/k6yk4gd3-1o6bh-remote'
05:53:58.611 [main] INFO c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=b1b4c948-743d-4fd2-8bbb-b37207fc9015 remoteRepositoryId=8b630ea1-5fd5-49df-b996-434bdda58481 localRepositoryRevisionSynced=57 localRepositoryRevisionInProgress=63
05:53:58.624 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 52.24%
05:53:58.624 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 59.70%
05:53:58.625 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncModifications: Deleting '/2/1/a'
05:53:58.630 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 67.16%
05:53:58.630 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 74.63%
05:53:58.648 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=b1b4c948-743d-4fd2-8bbb-b37207fc9015 remoteRepositoryId=8b630ea1-5fd5-49df-b996-434bdda58481 localRepositoryRevisionSynced=63
05:53:58.655 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=8b630ea1-5fd5-49df-b996-434bdda58481 remoteRepositoryId=b1b4c948-743d-4fd2-8bbb-b37207fc9015 transaction.localRevision=11 remoteFromLocalRevision=63
05:53:58.664 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDown: fromID=8b630ea1-5fd5-49df-b996-434bdda58481 from='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.local/target/repo/k6yk4gd3-1o6bh-remote' toID=b1b4c948-743d-4fd2-8bbb-b37207fc9015 to='target/repo/k6yk4gd2-7mo8h-local', fromRepoLocalSync=false
05:53:58.680 [main] INFO c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=8b630ea1-5fd5-49df-b996-434bdda58481 remoteRepositoryId=b1b4c948-743d-4fd2-8bbb-b37207fc9015 localRepositoryRevisionSynced=8 localRepositoryRevisionInProgress=11
05:53:58.683 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 88.31%
05:53:58.683 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 99.50%
05:53:58.688 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=8b630ea1-5fd5-49df-b996-434bdda58481 remoteRepositoryId=b1b4c948-743d-4fd2-8bbb-b37207fc9015 localRepositoryRevisionSynced=11
05:53:58.697 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=b1b4c948-743d-4fd2-8bbb-b37207fc9015 remoteRepositoryId=8b630ea1-5fd5-49df-b996-434bdda58481 transaction.localRevision=66 remoteFromLocalRevision=11
05:53:58.703 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 100.00%
05:53:58.703 [main] INFO c.c.c.l.LocalRepoManagerImpl - [5d0e2a2c]close: Deferring shut down of real LocalRepoManager 20000 ms.
05:53:58.722 [main] INFO c.c.c.l.LocalRepoManagerImpl - [3f3ddcd7]close: Deferring shut down of real LocalRepoManager 20000 ms.
05:53:58.764 [main] INFO c.c.c.l.LocalRepoManagerImpl - [488a9cb3]<init>: localRoot='/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.local/target/repo/k6yk4q5n-chejn-local'
05:53:58.770 [main] INFO c.c.c.l.LocalRepoManagerImpl - [488a9cb3]initLockFile: Repository '/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.local/target/repo/k6yk4q5n-chejn-local' locked successfully.
05:54:03.447 [main] INFO c.c.c.l.LocalRepoManagerImpl - [488a9cb3]initPersistenceManagerFactory: repositoryId=b9287f2b-e7ab-44d4-b862-0a237a41fc89
05:54:03.453 [main] INFO c.c.c.l.LocalRepoManagerImpl - [488a9cb3]initPersistenceManagerFactory: Started up PersistenceManagerFactory successfully in 2447 ms.
05:54:03.470 [main] INFO c.c.c.l.LocalRepoManagerImpl - [41babd59]<init>: localRoot='/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.local/target/repo/k6yk4q5n-e8x9g-remote'
05:54:03.477 [main] INFO c.c.c.l.LocalRepoManagerImpl - [41babd59]initLockFile: Repository '/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.local/target/repo/k6yk4q5n-e8x9g-remote' locked successfully.
05:54:05.985 [closeDeferredTimer-52512096-2] INFO c.c.c.l.LocalRepoManagerImpl - [52512096]_close: Shutting down real LocalRepoManager.
05:54:06.314 [closeDeferredTimer-443c098-2] INFO c.c.c.l.LocalRepoManagerImpl - [443c098]_close: Shutting down real LocalRepoManager.
05:54:09.409 [main] INFO c.c.c.l.LocalRepoManagerImpl - [41babd59]initPersistenceManagerFactory: repositoryId=d2cc4a03-e345-4d42-8f47-6095a6161c3c
05:54:09.413 [main] INFO c.c.c.l.LocalRepoManagerImpl - [41babd59]initPersistenceManagerFactory: Started up PersistenceManagerFactory successfully in 4337 ms.
05:54:09.525 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 1.00%
05:54:09.534 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 50.00%
05:54:09.548 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 58.00%
05:54:09.616 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 63.61%
05:54:09.628 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 74.34%
05:54:09.652 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 80.56%
05:54:09.663 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 90.67%
05:54:09.689 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 95.74%
05:54:09.724 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 100.00%
05:54:09.749 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - local repo: b9287f2b-e7ab-44d4-b862-0a237a41fc89
05:54:09.750 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - remote repo: d2cc4a03-e345-4d42-8f47-6095a6161c3c
05:54:09.754 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 0.50%
05:54:09.754 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDown: fromID=d2cc4a03-e345-4d42-8f47-6095a6161c3c from='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.local/target/repo/k6yk4q5n-e8x9g-remote/2' toID=b9287f2b-e7ab-44d4-b862-0a237a41fc89 to='target/repo/k6yk4q5n-chejn-local', fromRepoLocalSync=true
05:54:09.754 [pool-37-thread-1] INFO c.c.c.c.r.s.RepoToRepoSync - sync: locally syncing b9287f2b-e7ab-44d4-b862-0a237a41fc89 ('target/repo/k6yk4q5n-chejn-local')
05:54:09.777 [pool-37-thread-1] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 12.94%
05:54:09.784 [pool-37-thread-1] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 25.12%
05:54:09.787 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 1.00%
05:54:09.788 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 50.00%
05:54:09.790 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 58.17%
05:54:09.795 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 63.61%
05:54:09.801 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 74.50%
05:54:09.811 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 80.62%
05:54:09.816 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 90.83%
05:54:09.821 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 95.94%
05:54:09.829 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 100.00%
05:54:09.857 [main] INFO c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=d2cc4a03-e345-4d42-8f47-6095a6161c3c remoteRepositoryId=b9287f2b-e7ab-44d4-b862-0a237a41fc89 localRepositoryRevisionSynced=-1 localRepositoryRevisionInProgress=2
05:54:09.910 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path=''
05:54:09.941 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path='/1'
05:54:09.958 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 31.31%
05:54:09.962 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 36.58%
05:54:09.962 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 41.85%
05:54:09.962 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/1/a'
05:54:09.972 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='1d2a07aa151803a769733a3c666bf1133877ecfa' to.sha1='<NoInstanceOf_NormalFileDto>'): /1/a
05:54:10.030 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=1, fromRepositoryId="FileInProgressMarker", toRepositoryId="1", path="d2cc4a03-e345-4d42-8f47-6095a6161c3c"] on repo=b9287f2b-e7ab-44d4-b862-0a237a41fc89
05:54:10.036 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/1/a'
05:54:10.036 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='1d2a07aa151803a769733a3c666bf1133877ecfa' toSha1='null'
05:54:10.036 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/1/a'
05:54:10.036 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/1/a' offset=0
05:54:10.038 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/1/a' offset=0
05:54:10.053 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 196749 bytes in 17 ms. path='/1/a'
05:54:10.058 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_86f7e437faa5a7fce15d1ddcb9eaeaea377667b8_0 into destFile a
05:54:10.103 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=1, fromRepositoryId="FileInProgressMarker", toRepositoryId="1", path="d2cc4a03-e345-4d42-8f47-6095a6161c3c"] on repo=b9287f2b-e7ab-44d4-b862-0a237a41fc89
05:54:10.121 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/1/b'
05:54:10.132 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='407a195cae6249293527fb2c212d2511d235ebb6' to.sha1='<NoInstanceOf_NormalFileDto>'): /1/b
05:54:10.154 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=2, fromRepositoryId="FileInProgressMarker", toRepositoryId="2", path="d2cc4a03-e345-4d42-8f47-6095a6161c3c"] on repo=b9287f2b-e7ab-44d4-b862-0a237a41fc89
05:54:10.160 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/1/b'
05:54:10.161 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='407a195cae6249293527fb2c212d2511d235ebb6' toSha1='null'
05:54:10.161 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/1/b'
05:54:10.161 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/1/b' offset=0
05:54:10.164 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/1/b' offset=0
05:54:10.176 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 150780 bytes in 15 ms. path='/1/b'
05:54:10.181 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_e9d71f5ee7c92d6dc9e92ffdad17b8bd49418f98_0 into destFile b
05:54:10.199 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=2, fromRepositoryId="FileInProgressMarker", toRepositoryId="2", path="d2cc4a03-e345-4d42-8f47-6095a6161c3c"] on repo=b9287f2b-e7ab-44d4-b862-0a237a41fc89
05:54:10.210 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/a'
05:54:10.222 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='a16f0fd14dad2d66408f2520258073f0e03cd3d0' to.sha1='<NoInstanceOf_NormalFileDto>'): /a
05:54:10.245 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=3, fromRepositoryId="FileInProgressMarker", toRepositoryId="3", path="d2cc4a03-e345-4d42-8f47-6095a6161c3c"] on repo=b9287f2b-e7ab-44d4-b862-0a237a41fc89
05:54:10.251 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/a'
05:54:10.251 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='a16f0fd14dad2d66408f2520258073f0e03cd3d0' toSha1='null'
05:54:10.252 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/a'
05:54:10.252 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/a' offset=0
05:54:10.253 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/a' offset=0
05:54:10.260 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 19852 bytes in 8 ms. path='/a'
05:54:10.263 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_86f7e437faa5a7fce15d1ddcb9eaeaea377667b8_0 into destFile a
05:54:10.281 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=3, fromRepositoryId="FileInProgressMarker", toRepositoryId="3", path="d2cc4a03-e345-4d42-8f47-6095a6161c3c"] on repo=b9287f2b-e7ab-44d4-b862-0a237a41fc89
05:54:10.292 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 48.43%
05:54:10.314 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=d2cc4a03-e345-4d42-8f47-6095a6161c3c remoteRepositoryId=b9287f2b-e7ab-44d4-b862-0a237a41fc89 localRepositoryRevisionSynced=2
05:54:10.331 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=b9287f2b-e7ab-44d4-b862-0a237a41fc89 remoteRepositoryId=d2cc4a03-e345-4d42-8f47-6095a6161c3c transaction.localRevision=19 remoteFromLocalRevision=2
05:54:10.339 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncUp: fromID=b9287f2b-e7ab-44d4-b862-0a237a41fc89 from='target/repo/k6yk4q5n-chejn-local' toID=d2cc4a03-e345-4d42-8f47-6095a6161c3c to='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.local/target/repo/k6yk4q5n-e8x9g-remote/2'
05:54:10.377 [main] INFO c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=b9287f2b-e7ab-44d4-b862-0a237a41fc89 remoteRepositoryId=d2cc4a03-e345-4d42-8f47-6095a6161c3c localRepositoryRevisionSynced=-1 localRepositoryRevisionInProgress=19
05:54:10.391 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 63.43%
05:54:10.391 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 74.63%
05:54:10.404 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=b9287f2b-e7ab-44d4-b862-0a237a41fc89 remoteRepositoryId=d2cc4a03-e345-4d42-8f47-6095a6161c3c localRepositoryRevisionSynced=19
05:54:10.418 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=d2cc4a03-e345-4d42-8f47-6095a6161c3c remoteRepositoryId=b9287f2b-e7ab-44d4-b862-0a237a41fc89 transaction.localRevision=5 remoteFromLocalRevision=19
05:54:10.423 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDown: fromID=d2cc4a03-e345-4d42-8f47-6095a6161c3c from='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.local/target/repo/k6yk4q5n-e8x9g-remote/2' toID=b9287f2b-e7ab-44d4-b862-0a237a41fc89 to='target/repo/k6yk4q5n-chejn-local', fromRepoLocalSync=false
05:54:10.441 [main] INFO c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=d2cc4a03-e345-4d42-8f47-6095a6161c3c remoteRepositoryId=b9287f2b-e7ab-44d4-b862-0a237a41fc89 localRepositoryRevisionSynced=2 localRepositoryRevisionInProgress=5
05:54:10.449 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 88.31%
05:54:10.449 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 99.50%
05:54:10.453 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=d2cc4a03-e345-4d42-8f47-6095a6161c3c remoteRepositoryId=b9287f2b-e7ab-44d4-b862-0a237a41fc89 localRepositoryRevisionSynced=5
05:54:10.460 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=b9287f2b-e7ab-44d4-b862-0a237a41fc89 remoteRepositoryId=d2cc4a03-e345-4d42-8f47-6095a6161c3c transaction.localRevision=22 remoteFromLocalRevision=5
05:54:10.466 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 100.00%
05:54:10.483 [main] INFO c.c.c.l.LocalRepoManagerImpl - [488a9cb3]getCloseDeferredMillis: closeDeferredMillis=20000
05:54:10.483 [main] INFO c.c.c.l.LocalRepoManagerImpl - [488a9cb3]close: Deferring shut down of real LocalRepoManager 20000 ms.
05:54:10.484 [main] INFO c.c.c.l.LocalRepoManagerImpl - [41babd59]getCloseDeferredMillis: closeDeferredMillis=20000
05:54:10.484 [main] INFO c.c.c.l.LocalRepoManagerImpl - [41babd59]close: Deferring shut down of real LocalRepoManager 20000 ms.
05:54:10.496 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 1.00%
05:54:10.497 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 50.00%
05:54:10.499 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 58.17%
05:54:10.503 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 63.61%
05:54:10.507 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 74.50%
05:54:10.550 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 80.62%
05:54:10.553 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 90.83%
05:54:10.557 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 95.94%
05:54:10.571 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 100.00%
05:54:10.589 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 0.50%
05:54:10.590 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDown: fromID=d2cc4a03-e345-4d42-8f47-6095a6161c3c from='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.local/target/repo/k6yk4q5n-e8x9g-remote/2' toID=b9287f2b-e7ab-44d4-b862-0a237a41fc89 to='target/repo/k6yk4q5n-chejn-local', fromRepoLocalSync=true
05:54:10.592 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 1.00%
05:54:10.592 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 50.00%
05:54:10.594 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 58.17%
05:54:10.598 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 63.61%
05:54:10.600 [pool-38-thread-1] INFO c.c.c.c.r.s.RepoToRepoSync - sync: locally syncing b9287f2b-e7ab-44d4-b862-0a237a41fc89 ('target/repo/k6yk4q5n-chejn-local')
05:54:10.606 [pool-38-thread-1] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 12.94%
05:54:10.614 [pool-38-thread-1] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 18.27%
05:54:10.616 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 74.50%
05:54:10.622 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 80.62%
05:54:10.625 [pool-38-thread-1] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 25.12%
05:54:10.627 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 90.83%
05:54:10.633 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 95.94%
05:54:10.641 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 100.00%
05:54:10.651 [main] INFO c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=d2cc4a03-e345-4d42-8f47-6095a6161c3c remoteRepositoryId=b9287f2b-e7ab-44d4-b862-0a237a41fc89 localRepositoryRevisionSynced=5 localRepositoryRevisionInProgress=8
05:54:10.676 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 32.34%
05:54:10.677 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path='/1'
05:54:10.697 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncModifications: Deleting '/1/a'
05:54:10.743 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 39.80%
05:54:10.743 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 47.26%
05:54:10.766 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=d2cc4a03-e345-4d42-8f47-6095a6161c3c remoteRepositoryId=b9287f2b-e7ab-44d4-b862-0a237a41fc89 localRepositoryRevisionSynced=8
05:54:10.776 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=b9287f2b-e7ab-44d4-b862-0a237a41fc89 remoteRepositoryId=d2cc4a03-e345-4d42-8f47-6095a6161c3c transaction.localRevision=25 remoteFromLocalRevision=8
05:54:10.785 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncUp: fromID=b9287f2b-e7ab-44d4-b862-0a237a41fc89 from='target/repo/k6yk4q5n-chejn-local' toID=d2cc4a03-e345-4d42-8f47-6095a6161c3c to='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.local/target/repo/k6yk4q5n-e8x9g-remote/2'
05:54:10.801 [main] INFO c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=b9287f2b-e7ab-44d4-b862-0a237a41fc89 remoteRepositoryId=d2cc4a03-e345-4d42-8f47-6095a6161c3c localRepositoryRevisionSynced=19 localRepositoryRevisionInProgress=25
05:54:10.815 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 59.70%
05:54:10.815 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncModifications: Deleting '/1/a'
05:54:10.820 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 67.16%
05:54:10.820 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 74.63%
05:54:10.839 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=b9287f2b-e7ab-44d4-b862-0a237a41fc89 remoteRepositoryId=d2cc4a03-e345-4d42-8f47-6095a6161c3c localRepositoryRevisionSynced=25
05:54:10.848 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=d2cc4a03-e345-4d42-8f47-6095a6161c3c remoteRepositoryId=b9287f2b-e7ab-44d4-b862-0a237a41fc89 transaction.localRevision=11 remoteFromLocalRevision=25
05:54:10.857 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDown: fromID=d2cc4a03-e345-4d42-8f47-6095a6161c3c from='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.local/target/repo/k6yk4q5n-e8x9g-remote/2' toID=b9287f2b-e7ab-44d4-b862-0a237a41fc89 to='target/repo/k6yk4q5n-chejn-local', fromRepoLocalSync=false
05:54:10.870 [main] INFO c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=d2cc4a03-e345-4d42-8f47-6095a6161c3c remoteRepositoryId=b9287f2b-e7ab-44d4-b862-0a237a41fc89 localRepositoryRevisionSynced=8 localRepositoryRevisionInProgress=11
05:54:10.878 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 88.31%
05:54:10.878 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 99.50%
05:54:10.884 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=d2cc4a03-e345-4d42-8f47-6095a6161c3c remoteRepositoryId=b9287f2b-e7ab-44d4-b862-0a237a41fc89 localRepositoryRevisionSynced=11
05:54:10.890 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=b9287f2b-e7ab-44d4-b862-0a237a41fc89 remoteRepositoryId=d2cc4a03-e345-4d42-8f47-6095a6161c3c transaction.localRevision=28 remoteFromLocalRevision=11
05:54:10.895 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 100.00%
05:54:10.895 [main] INFO c.c.c.l.LocalRepoManagerImpl - [488a9cb3]close: Deferring shut down of real LocalRepoManager 20000 ms.
05:54:10.920 [main] INFO c.c.c.l.LocalRepoManagerImpl - [41babd59]close: Deferring shut down of real LocalRepoManager 20000 ms.
05:54:10.938 [main] INFO c.c.c.l.LocalRepoManagerImpl - [61e72314]<init>: localRoot='/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.local/target/repo/k6yk4zjs-axuxy-local'
05:54:10.943 [main] INFO c.c.c.l.LocalRepoManagerImpl - [61e72314]initLockFile: Repository '/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.local/target/repo/k6yk4zjs-axuxy-local' locked successfully.
05:54:15.941 [main] INFO c.c.c.l.LocalRepoManagerImpl - [61e72314]initPersistenceManagerFactory: repositoryId=240c9b75-c9f6-4435-8d49-631d38713bc0
05:54:15.947 [main] INFO c.c.c.l.LocalRepoManagerImpl - [61e72314]initPersistenceManagerFactory: Started up PersistenceManagerFactory successfully in 2456 ms.
05:54:15.980 [main] INFO c.c.c.l.LocalRepoManagerImpl - [33bfc770]<init>: localRoot='/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.local/target/repo/k6yk4zjt-nne9z-remote'
05:54:15.988 [main] INFO c.c.c.l.LocalRepoManagerImpl - [33bfc770]initLockFile: Repository '/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.local/target/repo/k6yk4zjt-nne9z-remote' locked successfully.
05:54:18.705 [closeDeferredTimer-5d0e2a2c-2] INFO c.c.c.l.LocalRepoManagerImpl - [5d0e2a2c]_close: Shutting down real LocalRepoManager.
05:54:19.042 [closeDeferredTimer-3f3ddcd7-2] INFO c.c.c.l.LocalRepoManagerImpl - [3f3ddcd7]_close: Shutting down real LocalRepoManager.
05:54:21.577 [main] INFO c.c.c.l.LocalRepoManagerImpl - [33bfc770]initPersistenceManagerFactory: repositoryId=9aeb3205-e579-4574-b52e-aa61e92bb3fa
05:54:21.581 [main] INFO c.c.c.l.LocalRepoManagerImpl - [33bfc770]initPersistenceManagerFactory: Started up PersistenceManagerFactory successfully in 3739 ms.
05:54:21.673 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 1.00%
05:54:21.680 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 50.00%
05:54:21.691 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 58.00%
05:54:21.734 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 63.61%
05:54:21.751 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 74.34%
05:54:21.781 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 79.36%
05:54:21.796 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 90.67%
05:54:21.822 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 95.69%
05:54:21.848 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 100.00%
05:54:21.872 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - local repo: 240c9b75-c9f6-4435-8d49-631d38713bc0
05:54:21.872 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - remote repo: 9aeb3205-e579-4574-b52e-aa61e92bb3fa
05:54:21.876 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 0.50%
05:54:21.877 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDown: fromID=9aeb3205-e579-4574-b52e-aa61e92bb3fa from='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.local/target/repo/k6yk4zjt-nne9z-remote' toID=240c9b75-c9f6-4435-8d49-631d38713bc0 to='target/repo/k6yk4zjs-axuxy-local', fromRepoLocalSync=true
05:54:21.877 [pool-39-thread-1] INFO c.c.c.c.r.s.RepoToRepoSync - sync: locally syncing 240c9b75-c9f6-4435-8d49-631d38713bc0 ('target/repo/k6yk4zjs-axuxy-local')
05:54:21.917 [pool-39-thread-1] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 12.94%
05:54:21.926 [pool-39-thread-1] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 25.12%
05:54:21.929 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 1.00%
05:54:21.930 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 50.00%
05:54:21.933 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 58.17%
05:54:21.937 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 63.61%
05:54:21.941 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 74.50%
05:54:21.948 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 80.62%
05:54:21.951 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 90.83%
05:54:21.956 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 95.94%
05:54:21.963 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 100.00%
05:54:22.046 [main] INFO c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=9aeb3205-e579-4574-b52e-aa61e92bb3fa remoteRepositoryId=240c9b75-c9f6-4435-8d49-631d38713bc0 localRepositoryRevisionSynced=-1 localRepositoryRevisionInProgress=2
05:54:22.086 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path=''
05:54:22.116 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path='/1'
05:54:22.130 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 30.22%
05:54:22.131 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path='/2'
05:54:22.140 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path='/2/1'
05:54:22.150 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path='/3'
05:54:22.165 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 35.46%
05:54:22.165 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 40.70%
05:54:22.165 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/1/a'
05:54:22.172 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='4ad3743a6465a011babf4b99322e684a3139fa78' to.sha1='<NoInstanceOf_NormalFileDto>'): /1/a
05:54:22.210 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=1, fromRepositoryId="FileInProgressMarker", toRepositoryId="1", path="9aeb3205-e579-4574-b52e-aa61e92bb3fa"] on repo=240c9b75-c9f6-4435-8d49-631d38713bc0
05:54:22.211 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/1/a'
05:54:22.211 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='4ad3743a6465a011babf4b99322e684a3139fa78' toSha1='null'
05:54:22.211 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/1/a'
05:54:22.211 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/1/a' offset=0
05:54:22.216 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/1/a' offset=0
05:54:22.227 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 339592 bytes in 16 ms. path='/1/a'
05:54:22.231 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_86f7e437faa5a7fce15d1ddcb9eaeaea377667b8_0 into destFile a
05:54:22.274 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=1, fromRepositoryId="FileInProgressMarker", toRepositoryId="1", path="9aeb3205-e579-4574-b52e-aa61e92bb3fa"] on repo=240c9b75-c9f6-4435-8d49-631d38713bc0
05:54:22.281 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/1/b'
05:54:22.290 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='3d02aaa4927065a1b4dbbdb33770b9a7e2781830' to.sha1='<NoInstanceOf_NormalFileDto>'): /1/b
05:54:22.301 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=2, fromRepositoryId="FileInProgressMarker", toRepositoryId="2", path="9aeb3205-e579-4574-b52e-aa61e92bb3fa"] on repo=240c9b75-c9f6-4435-8d49-631d38713bc0
05:54:22.302 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/1/b'
05:54:22.302 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='3d02aaa4927065a1b4dbbdb33770b9a7e2781830' toSha1='null'
05:54:22.302 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/1/b'
05:54:22.302 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/1/b' offset=0
05:54:22.302 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/1/b' offset=0
05:54:22.307 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 56850 bytes in 5 ms. path='/1/b'
05:54:22.311 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_e9d71f5ee7c92d6dc9e92ffdad17b8bd49418f98_0 into destFile b
05:54:22.321 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=2, fromRepositoryId="FileInProgressMarker", toRepositoryId="2", path="9aeb3205-e579-4574-b52e-aa61e92bb3fa"] on repo=240c9b75-c9f6-4435-8d49-631d38713bc0
05:54:22.326 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/1/c'
05:54:22.337 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='5ea81eb34f56c5569137e86f71dba868bdcad519' to.sha1='<NoInstanceOf_NormalFileDto>'): /1/c
05:54:22.351 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=3, fromRepositoryId="FileInProgressMarker", toRepositoryId="3", path="9aeb3205-e579-4574-b52e-aa61e92bb3fa"] on repo=240c9b75-c9f6-4435-8d49-631d38713bc0
05:54:22.353 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/1/c'
05:54:22.353 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='5ea81eb34f56c5569137e86f71dba868bdcad519' toSha1='null'
05:54:22.353 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/1/c'
05:54:22.353 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/1/c' offset=0
05:54:22.354 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/1/c' offset=0
05:54:22.359 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 44968 bytes in 6 ms. path='/1/c'
05:54:22.364 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_84a516841ba77a5b4648de2cd0dfcb30ea46dbb4_0 into destFile c
05:54:22.375 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=3, fromRepositoryId="FileInProgressMarker", toRepositoryId="3", path="9aeb3205-e579-4574-b52e-aa61e92bb3fa"] on repo=240c9b75-c9f6-4435-8d49-631d38713bc0
05:54:22.378 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/2/1/a'
05:54:22.386 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='7a027f58b0074c64567534c05ecb1291789cd916' to.sha1='<NoInstanceOf_NormalFileDto>'): /2/1/a
05:54:22.400 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=4, fromRepositoryId="FileInProgressMarker", toRepositoryId="4", path="9aeb3205-e579-4574-b52e-aa61e92bb3fa"] on repo=240c9b75-c9f6-4435-8d49-631d38713bc0
05:54:22.402 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/2/1/a'
05:54:22.402 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='7a027f58b0074c64567534c05ecb1291789cd916' toSha1='null'
05:54:22.402 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/2/1/a'
05:54:22.402 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/2/1/a' offset=0
05:54:22.403 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/2/1/a' offset=0
05:54:22.411 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 83925 bytes in 9 ms. path='/2/1/a'
05:54:22.416 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_86f7e437faa5a7fce15d1ddcb9eaeaea377667b8_0 into destFile a
05:54:22.429 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=4, fromRepositoryId="FileInProgressMarker", toRepositoryId="4", path="9aeb3205-e579-4574-b52e-aa61e92bb3fa"] on repo=240c9b75-c9f6-4435-8d49-631d38713bc0
05:54:22.432 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/2/1/b'
05:54:22.440 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='bd0a01a3105da4fa4f246be1460991c9ab7776f4' to.sha1='<NoInstanceOf_NormalFileDto>'): /2/1/b
05:54:22.455 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=5, fromRepositoryId="FileInProgressMarker", toRepositoryId="5", path="9aeb3205-e579-4574-b52e-aa61e92bb3fa"] on repo=240c9b75-c9f6-4435-8d49-631d38713bc0
05:54:22.457 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/2/1/b'
05:54:22.457 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='bd0a01a3105da4fa4f246be1460991c9ab7776f4' toSha1='null'
05:54:22.457 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/2/1/b'
05:54:22.457 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/2/1/b' offset=0
05:54:22.461 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/2/1/b' offset=0
05:54:22.475 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 383040 bytes in 18 ms. path='/2/1/b'
05:54:22.480 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_e9d71f5ee7c92d6dc9e92ffdad17b8bd49418f98_0 into destFile b
05:54:22.503 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=5, fromRepositoryId="FileInProgressMarker", toRepositoryId="5", path="9aeb3205-e579-4574-b52e-aa61e92bb3fa"] on repo=240c9b75-c9f6-4435-8d49-631d38713bc0
05:54:22.507 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/2/a'
05:54:22.515 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='06beac6ca6605086e740719376b488253c21c3c7' to.sha1='<NoInstanceOf_NormalFileDto>'): /2/a
05:54:22.530 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=6, fromRepositoryId="FileInProgressMarker", toRepositoryId="6", path="9aeb3205-e579-4574-b52e-aa61e92bb3fa"] on repo=240c9b75-c9f6-4435-8d49-631d38713bc0
05:54:22.531 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/2/a'
05:54:22.531 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='06beac6ca6605086e740719376b488253c21c3c7' toSha1='null'
05:54:22.531 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/2/a'
05:54:22.531 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/2/a' offset=0
05:54:22.534 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/2/a' offset=0
05:54:22.542 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 251526 bytes in 11 ms. path='/2/a'
05:54:22.547 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_86f7e437faa5a7fce15d1ddcb9eaeaea377667b8_0 into destFile a
05:54:22.566 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=6, fromRepositoryId="FileInProgressMarker", toRepositoryId="6", path="9aeb3205-e579-4574-b52e-aa61e92bb3fa"] on repo=240c9b75-c9f6-4435-8d49-631d38713bc0
05:54:22.569 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/3/a'
05:54:22.578 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='72d127dcf612eddaf6416c5063878c0e36e0eadb' to.sha1='<NoInstanceOf_NormalFileDto>'): /3/a
05:54:22.591 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=7, fromRepositoryId="FileInProgressMarker", toRepositoryId="7", path="9aeb3205-e579-4574-b52e-aa61e92bb3fa"] on repo=240c9b75-c9f6-4435-8d49-631d38713bc0
05:54:22.592 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/3/a'
05:54:22.592 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='72d127dcf612eddaf6416c5063878c0e36e0eadb' toSha1='null'
05:54:22.592 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/3/a'
05:54:22.592 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/3/a' offset=0
05:54:22.594 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/3/a' offset=0
05:54:22.599 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 97272 bytes in 7 ms. path='/3/a'
05:54:22.603 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_86f7e437faa5a7fce15d1ddcb9eaeaea377667b8_0 into destFile a
05:54:22.615 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=7, fromRepositoryId="FileInProgressMarker", toRepositoryId="7", path="9aeb3205-e579-4574-b52e-aa61e92bb3fa"] on repo=240c9b75-c9f6-4435-8d49-631d38713bc0
05:54:22.618 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/3/b'
05:54:22.629 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='afa02839900e628af6480ee9e5c42f176e4ea7f7' to.sha1='<NoInstanceOf_NormalFileDto>'): /3/b
05:54:22.641 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=8, fromRepositoryId="FileInProgressMarker", toRepositoryId="8", path="9aeb3205-e579-4574-b52e-aa61e92bb3fa"] on repo=240c9b75-c9f6-4435-8d49-631d38713bc0
05:54:22.643 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/3/b'
05:54:22.643 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='afa02839900e628af6480ee9e5c42f176e4ea7f7' toSha1='null'
05:54:22.643 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/3/b'
05:54:22.643 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/3/b' offset=0
05:54:22.645 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/3/b' offset=0
05:54:22.652 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 24012 bytes in 9 ms. path='/3/b'
05:54:22.656 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_e9d71f5ee7c92d6dc9e92ffdad17b8bd49418f98_0 into destFile b
05:54:22.669 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=8, fromRepositoryId="FileInProgressMarker", toRepositoryId="8", path="9aeb3205-e579-4574-b52e-aa61e92bb3fa"] on repo=240c9b75-c9f6-4435-8d49-631d38713bc0
05:54:22.672 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/3/c'
05:54:22.681 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='3ed187c719a36a45caaa95a7b5cc296d5f559c75' to.sha1='<NoInstanceOf_NormalFileDto>'): /3/c
05:54:22.694 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=9, fromRepositoryId="FileInProgressMarker", toRepositoryId="9", path="9aeb3205-e579-4574-b52e-aa61e92bb3fa"] on repo=240c9b75-c9f6-4435-8d49-631d38713bc0
05:54:22.696 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/3/c'
05:54:22.696 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='3ed187c719a36a45caaa95a7b5cc296d5f559c75' toSha1='null'
05:54:22.696 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/3/c'
05:54:22.696 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/3/c' offset=0
05:54:22.701 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/3/c' offset=0
05:54:22.710 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 315945 bytes in 14 ms. path='/3/c'
05:54:22.715 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_84a516841ba77a5b4648de2cd0dfcb30ea46dbb4_0 into destFile c
05:54:22.741 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=9, fromRepositoryId="FileInProgressMarker", toRepositoryId="9", path="9aeb3205-e579-4574-b52e-aa61e92bb3fa"] on repo=240c9b75-c9f6-4435-8d49-631d38713bc0
05:54:22.745 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/3/d'
05:54:22.753 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='79c418a269035d0ae7849f2ab0d5627a0f979748' to.sha1='<NoInstanceOf_NormalFileDto>'): /3/d
05:54:22.768 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=10, fromRepositoryId="FileInProgressMarker", toRepositoryId="10", path="9aeb3205-e579-4574-b52e-aa61e92bb3fa"] on repo=240c9b75-c9f6-4435-8d49-631d38713bc0
05:54:22.770 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/3/d'
05:54:22.770 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='79c418a269035d0ae7849f2ab0d5627a0f979748' toSha1='null'
05:54:22.770 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/3/d'
05:54:22.770 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/3/d' offset=0
05:54:22.773 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/3/d' offset=0
05:54:22.791 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 292560 bytes in 21 ms. path='/3/d'
05:54:22.796 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_3c363836cf4e16666669a25da280a1865c2d2874_0 into destFile d
05:54:22.817 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=10, fromRepositoryId="FileInProgressMarker", toRepositoryId="10", path="9aeb3205-e579-4574-b52e-aa61e92bb3fa"] on repo=240c9b75-c9f6-4435-8d49-631d38713bc0
05:54:22.822 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 49.27%
05:54:22.842 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=9aeb3205-e579-4574-b52e-aa61e92bb3fa remoteRepositoryId=240c9b75-c9f6-4435-8d49-631d38713bc0 localRepositoryRevisionSynced=2
05:54:22.857 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=240c9b75-c9f6-4435-8d49-631d38713bc0 remoteRepositoryId=9aeb3205-e579-4574-b52e-aa61e92bb3fa transaction.localRevision=57 remoteFromLocalRevision=2
05:54:22.865 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncUp: fromID=240c9b75-c9f6-4435-8d49-631d38713bc0 from='target/repo/k6yk4zjs-axuxy-local' toID=9aeb3205-e579-4574-b52e-aa61e92bb3fa to='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.local/target/repo/k6yk4zjt-nne9z-remote'
05:54:22.888 [main] INFO c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=240c9b75-c9f6-4435-8d49-631d38713bc0 remoteRepositoryId=9aeb3205-e579-4574-b52e-aa61e92bb3fa localRepositoryRevisionSynced=-1 localRepositoryRevisionInProgress=57
05:54:22.901 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 63.43%
05:54:22.901 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 74.63%
05:54:22.914 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=240c9b75-c9f6-4435-8d49-631d38713bc0 remoteRepositoryId=9aeb3205-e579-4574-b52e-aa61e92bb3fa localRepositoryRevisionSynced=57
05:54:22.924 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=9aeb3205-e579-4574-b52e-aa61e92bb3fa remoteRepositoryId=240c9b75-c9f6-4435-8d49-631d38713bc0 transaction.localRevision=5 remoteFromLocalRevision=57
05:54:22.929 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDown: fromID=9aeb3205-e579-4574-b52e-aa61e92bb3fa from='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.local/target/repo/k6yk4zjt-nne9z-remote' toID=240c9b75-c9f6-4435-8d49-631d38713bc0 to='target/repo/k6yk4zjs-axuxy-local', fromRepoLocalSync=false
05:54:22.945 [main] INFO c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=9aeb3205-e579-4574-b52e-aa61e92bb3fa remoteRepositoryId=240c9b75-c9f6-4435-8d49-631d38713bc0 localRepositoryRevisionSynced=2 localRepositoryRevisionInProgress=5
05:54:22.947 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 88.31%
05:54:22.947 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 99.50%
05:54:22.952 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=9aeb3205-e579-4574-b52e-aa61e92bb3fa remoteRepositoryId=240c9b75-c9f6-4435-8d49-631d38713bc0 localRepositoryRevisionSynced=5
05:54:22.958 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=240c9b75-c9f6-4435-8d49-631d38713bc0 remoteRepositoryId=9aeb3205-e579-4574-b52e-aa61e92bb3fa transaction.localRevision=60 remoteFromLocalRevision=5
05:54:22.960 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 100.00%
05:54:22.974 [main] INFO c.c.c.l.LocalRepoManagerImpl - [61e72314]getCloseDeferredMillis: closeDeferredMillis=20000
05:54:22.974 [main] INFO c.c.c.l.LocalRepoManagerImpl - [61e72314]close: Deferring shut down of real LocalRepoManager 20000 ms.
05:54:22.975 [main] INFO c.c.c.l.LocalRepoManagerImpl - [33bfc770]getCloseDeferredMillis: closeDeferredMillis=20000
05:54:22.975 [main] INFO c.c.c.l.LocalRepoManagerImpl - [33bfc770]close: Deferring shut down of real LocalRepoManager 20000 ms.
05:54:23.023 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 1.00%
05:54:23.025 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 50.00%
05:54:23.027 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 62.25%
05:54:23.030 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 68.38%
05:54:23.032 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 74.50%
05:54:23.034 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 86.75%
05:54:23.037 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 92.88%
05:54:23.044 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 99.00%
05:54:23.108 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 100.00%
05:54:23.130 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 0.50%
05:54:23.137 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDown: fromID=9aeb3205-e579-4574-b52e-aa61e92bb3fa from='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.local/target/repo/k6yk4zjt-nne9z-remote' toID=240c9b75-c9f6-4435-8d49-631d38713bc0 to='target/repo/k6yk4zjs-axuxy-local', fromRepoLocalSync=true
05:54:23.137 [pool-40-thread-1] INFO c.c.c.c.r.s.RepoToRepoSync - sync: locally syncing 240c9b75-c9f6-4435-8d49-631d38713bc0 ('target/repo/k6yk4zjs-axuxy-local')
05:54:23.143 [pool-40-thread-1] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 12.94%
05:54:23.166 [pool-40-thread-1] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 19.03%
05:54:23.194 [pool-40-thread-1] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 24.11%
05:54:23.216 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 1.00%
05:54:23.217 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 50.00%
05:54:23.219 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 62.25%
05:54:23.223 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 68.38%
05:54:23.226 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 74.50%
05:54:23.230 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 86.75%
05:54:23.235 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 92.88%
05:54:23.239 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 99.00%
05:54:23.248 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 100.00%
05:54:23.258 [main] INFO c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=9aeb3205-e579-4574-b52e-aa61e92bb3fa remoteRepositoryId=240c9b75-c9f6-4435-8d49-631d38713bc0 localRepositoryRevisionSynced=5 localRepositoryRevisionInProgress=8
05:54:23.280 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 31.09%
05:54:23.280 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path=''
05:54:23.301 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncModifications: Deleting '/2'
05:54:23.372 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 38.56%
05:54:23.373 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 46.02%
05:54:23.394 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=9aeb3205-e579-4574-b52e-aa61e92bb3fa remoteRepositoryId=240c9b75-c9f6-4435-8d49-631d38713bc0 localRepositoryRevisionSynced=8
05:54:23.404 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=240c9b75-c9f6-4435-8d49-631d38713bc0 remoteRepositoryId=9aeb3205-e579-4574-b52e-aa61e92bb3fa transaction.localRevision=63 remoteFromLocalRevision=8
05:54:23.412 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncUp: fromID=240c9b75-c9f6-4435-8d49-631d38713bc0 from='target/repo/k6yk4zjs-axuxy-local' toID=9aeb3205-e579-4574-b52e-aa61e92bb3fa to='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.local/target/repo/k6yk4zjt-nne9z-remote'
05:54:23.425 [main] INFO c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=240c9b75-c9f6-4435-8d49-631d38713bc0 remoteRepositoryId=9aeb3205-e579-4574-b52e-aa61e92bb3fa localRepositoryRevisionSynced=57 localRepositoryRevisionInProgress=63
05:54:23.435 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 52.24%
05:54:23.436 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 59.70%
05:54:23.436 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncModifications: Deleting '/2'
05:54:23.440 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 67.16%
05:54:23.440 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 74.63%
05:54:23.458 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=240c9b75-c9f6-4435-8d49-631d38713bc0 remoteRepositoryId=9aeb3205-e579-4574-b52e-aa61e92bb3fa localRepositoryRevisionSynced=63
05:54:23.463 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=9aeb3205-e579-4574-b52e-aa61e92bb3fa remoteRepositoryId=240c9b75-c9f6-4435-8d49-631d38713bc0 transaction.localRevision=11 remoteFromLocalRevision=63
05:54:23.470 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDown: fromID=9aeb3205-e579-4574-b52e-aa61e92bb3fa from='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.local/target/repo/k6yk4zjt-nne9z-remote' toID=240c9b75-c9f6-4435-8d49-631d38713bc0 to='target/repo/k6yk4zjs-axuxy-local', fromRepoLocalSync=false
05:54:23.481 [main] INFO c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=9aeb3205-e579-4574-b52e-aa61e92bb3fa remoteRepositoryId=240c9b75-c9f6-4435-8d49-631d38713bc0 localRepositoryRevisionSynced=8 localRepositoryRevisionInProgress=11
05:54:23.484 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 88.31%
05:54:23.484 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 99.50%
05:54:23.488 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=9aeb3205-e579-4574-b52e-aa61e92bb3fa remoteRepositoryId=240c9b75-c9f6-4435-8d49-631d38713bc0 localRepositoryRevisionSynced=11
05:54:23.496 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=240c9b75-c9f6-4435-8d49-631d38713bc0 remoteRepositoryId=9aeb3205-e579-4574-b52e-aa61e92bb3fa transaction.localRevision=66 remoteFromLocalRevision=11
05:54:23.498 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 100.00%
05:54:23.498 [main] INFO c.c.c.l.LocalRepoManagerImpl - [61e72314]close: Deferring shut down of real LocalRepoManager 20000 ms.
05:54:23.507 [main] INFO c.c.c.l.LocalRepoManagerImpl - [33bfc770]close: Deferring shut down of real LocalRepoManager 20000 ms.
Tests run: 20, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 384.94 sec - in co.codewizards.cloudstore.local.sync.RepoToRepoSyncTest
Results :
Tests run: 63, Failures: 0, Errors: 0, Skipped: 0
[JENKINS] Recording test results
[INFO]
[INFO] --- maven-jar-plugin:2.4:jar (default-jar) @ co.codewizards.cloudstore.local ---
[INFO] Building jar: /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.local/target/co.codewizards.cloudstore.local-0.10.8-SNAPSHOT.jar
[INFO]
[INFO] --- maven-source-plugin:2.1.2:jar-no-fork (attach-sources) @ co.codewizards.cloudstore.local ---
[INFO] co already added, skipping
[INFO] co/codewizards already added, skipping
[INFO] co/codewizards/cloudstore already added, skipping
[INFO] co/codewizards/cloudstore/local already added, skipping
[INFO] co/codewizards/cloudstore/local/db already added, skipping
[INFO] co/codewizards/cloudstore/local/persistence already added, skipping
[INFO] co/codewizards/cloudstore/local/transport already added, skipping
[INFO] co/codewizards/cloudstore/local/dto already added, skipping
[INFO] co/codewizards/cloudstore/local/persistence/package-sqlite.orm already added, skipping
[INFO] Building jar: /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.local/target/co.codewizards.cloudstore.local-0.10.8-SNAPSHOT-sources.jar
[INFO] co already added, skipping
[INFO] co/codewizards already added, skipping
[INFO] co/codewizards/cloudstore already added, skipping
[INFO] co/codewizards/cloudstore/local already added, skipping
[INFO] co/codewizards/cloudstore/local/db already added, skipping
[INFO] co/codewizards/cloudstore/local/persistence already added, skipping
[INFO] co/codewizards/cloudstore/local/transport already added, skipping
[INFO] co/codewizards/cloudstore/local/dto already added, skipping
[INFO] co/codewizards/cloudstore/local/persistence/package-sqlite.orm already added, skipping
[INFO]
[INFO] --- maven-install-plugin:2.4:install (default-install) @ co.codewizards.cloudstore.local ---
[INFO] Installing /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.local/target/co.codewizards.cloudstore.local-0.10.8-SNAPSHOT.jar to /home/tomcat/.m2/repository/co/codewizards/cloudstore/co.codewizards.cloudstore.local/0.10.8-SNAPSHOT/co.codewizards.cloudstore.local-0.10.8-SNAPSHOT.jar
[INFO] Installing /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.local/pom.xml to /home/tomcat/.m2/repository/co/codewizards/cloudstore/co.codewizards.cloudstore.local/0.10.8-SNAPSHOT/co.codewizards.cloudstore.local-0.10.8-SNAPSHOT.pom
[INFO] Installing /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.local/target/co.codewizards.cloudstore.local-0.10.8-SNAPSHOT-sources.jar to /home/tomcat/.m2/repository/co/codewizards/cloudstore/co.codewizards.cloudstore.local/0.10.8-SNAPSHOT/co.codewizards.cloudstore.local-0.10.8-SNAPSHOT-sources.jar
[INFO]
[INFO] --- maven-deploy-plugin:2.7:deploy (default-deploy) @ co.codewizards.cloudstore.local ---
[INFO] Downloading: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/snapshot/co/codewizards/cloudstore/co.codewizards.cloudstore.local/0.10.8-SNAPSHOT/maven-metadata.xml
[INFO] Downloaded: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/snapshot/co/codewizards/cloudstore/co.codewizards.cloudstore.local/0.10.8-SNAPSHOT/maven-metadata.xml (1 KB at 333.3 KB/sec)
[INFO] Uploading: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/snapshot/co/codewizards/cloudstore/co.codewizards.cloudstore.local/0.10.8-SNAPSHOT/co.codewizards.cloudstore.local-0.10.8-20200223.045425-6.jar
[INFO] Uploaded: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/snapshot/co/codewizards/cloudstore/co.codewizards.cloudstore.local/0.10.8-SNAPSHOT/co.codewizards.cloudstore.local-0.10.8-20200223.045425-6.jar (203 KB at 33791.3 KB/sec)
[INFO] Uploading: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/snapshot/co/codewizards/cloudstore/co.codewizards.cloudstore.local/0.10.8-SNAPSHOT/co.codewizards.cloudstore.local-0.10.8-20200223.045425-6.pom
[INFO] Uploaded: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/snapshot/co/codewizards/cloudstore/co.codewizards.cloudstore.local/0.10.8-SNAPSHOT/co.codewizards.cloudstore.local-0.10.8-20200223.045425-6.pom (3 KB at 2565.4 KB/sec)
[INFO] Downloading: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/snapshot/co/codewizards/cloudstore/co.codewizards.cloudstore.local/maven-metadata.xml
[INFO] Downloaded: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/snapshot/co/codewizards/cloudstore/co.codewizards.cloudstore.local/maven-metadata.xml (317 B at 309.6 KB/sec)
[INFO] Uploading: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/snapshot/co/codewizards/cloudstore/co.codewizards.cloudstore.local/0.10.8-SNAPSHOT/maven-metadata.xml
[INFO] Uploaded: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/snapshot/co/codewizards/cloudstore/co.codewizards.cloudstore.local/0.10.8-SNAPSHOT/maven-metadata.xml (1 KB at 1000.0 KB/sec)
[INFO] Uploading: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/snapshot/co/codewizards/cloudstore/co.codewizards.cloudstore.local/maven-metadata.xml
[INFO] Uploaded: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/snapshot/co/codewizards/cloudstore/co.codewizards.cloudstore.local/maven-metadata.xml (317 B at 309.6 KB/sec)
[INFO] Uploading: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/snapshot/co/codewizards/cloudstore/co.codewizards.cloudstore.local/0.10.8-SNAPSHOT/co.codewizards.cloudstore.local-0.10.8-20200223.045425-6-sources.jar
[INFO] Uploaded: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/snapshot/co/codewizards/cloudstore/co.codewizards.cloudstore.local/0.10.8-SNAPSHOT/co.codewizards.cloudstore.local-0.10.8-20200223.045425-6-sources.jar (105 KB at 26069.1 KB/sec)
[INFO] Uploading: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/snapshot/co/codewizards/cloudstore/co.codewizards.cloudstore.local/0.10.8-SNAPSHOT/maven-metadata.xml
[INFO] Uploaded: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/snapshot/co/codewizards/cloudstore/co.codewizards.cloudstore.local/0.10.8-SNAPSHOT/maven-metadata.xml (1 KB at 1000.0 KB/sec)
[INFO]
[INFO] --- maven-site-plugin:3.3:site (default-site) @ co.codewizards.cloudstore.local ---
[INFO] Relativizing decoration links with respect to project URL: http://cloudstore.codewizards.co/co.codewizards.cloudstore.local
[INFO] Rendering site with org.apache.maven.skins:maven-default-skin:jar:1.0 skin.
[INFO] Relativizing decoration links with respect to project URL: http://cloudstore.codewizards.co/co.codewizards.cloudstore.local/de
[INFO] Rendering site with org.apache.maven.skins:maven-default-skin:jar:1.0 skin.
[JENKINS] Archiving site from /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.local/target/site to /home/tomcat/.jenkins/jobs/co.codewizards.cloudstore_java_8/site/co.codewizards.cloudstore.local
[INFO]
[INFO] >>> maven-javadoc-plugin:2.9.1:javadoc (javadoc-generate-unfiltered) > generate-sources @ co.codewizards.cloudstore.local >>>
[WARNING] Failed to getClass for org.apache.maven.plugin.javadoc.JavadocReport
[INFO]
[INFO] <<< maven-javadoc-plugin:2.9.1:javadoc (javadoc-generate-unfiltered) < generate-sources @ co.codewizards.cloudstore.local <<<
[INFO]
[INFO] --- maven-javadoc-plugin:2.9.1:javadoc (javadoc-generate-unfiltered) @ co.codewizards.cloudstore.local ---
[INFO]
[INFO] --- maven-resources-plugin:2.5:copy-resources (javadoc-filter) @ co.codewizards.cloudstore.local ---
[debug] execute contextualize
[INFO] Using 'UTF-8' encoding to copy filtered resources.
[INFO] skip non existing resourceDirectory /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.local/target/javadoc.unfiltered
[INFO] skip non existing resourceDirectory /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.local/target/javadoc.unfiltered
[INFO]
[INFO] --- maven-resources-plugin:2.5:copy-resources (javadoc-copy-to-site) @ co.codewizards.cloudstore.local ---
[debug] execute contextualize
[INFO] Using 'UTF-8' encoding to copy filtered resources.
[INFO] skip non existing resourceDirectory /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.local/target/apidocs
[INFO]
[INFO] --- maven-site-plugin:3.3:deploy (default-deploy) @ co.codewizards.cloudstore.local ---
file:/tmp/cloudstore-junk/ - Session: Opened
[INFO] Pushing /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.local/target/site
[INFO] >>> to file:/tmp/cloudstore-junk/co.codewizards.cloudstore.local
[INFO] >>> to file:/tmp/cloudstore-junk/de/co.codewizards.cloudstore.local
file:/tmp/cloudstore-junk/ - Session: Disconnecting
file:/tmp/cloudstore-junk/ - Session: Disconnected
|
Help us localize this page