|  Console Output
Skipping 706 KB.. Full Logedf6a8-c7cd-4fec-99f9-17eae99060e0 to='target/repo/k98qdf6h-ewgl6-local', fromRepoLocalSync=true
19:05:56.791 [pool-31-thread-1] INFO c.c.c.c.r.s.RepoToRepoSync - sync: locally syncing b2edf6a8-c7cd-4fec-99f9-17eae99060e0 ('target/repo/k98qdf6h-ewgl6-local')
19:05:56.818 [pool-31-thread-1] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 12.94%
19:05:56.826 [pool-31-thread-1] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 25.12%
19:05:56.831 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 1.00%
19:05:56.834 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 50.00%
19:05:56.835 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 58.17%
19:05:56.839 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 63.61%
19:05:56.844 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 74.50%
19:05:56.850 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 80.62%
19:05:56.853 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 90.83%
19:05:56.858 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 95.94%
19:05:56.865 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 100.00%
19:05:56.896 [main] INFO c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=001153a2-e7aa-4523-9b2f-cec50edabe4e remoteRepositoryId=b2edf6a8-c7cd-4fec-99f9-17eae99060e0 localRepositoryRevisionSynced=-1 localRepositoryRevisionInProgress=2
19:05:56.944 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path=''
19:05:56.973 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path='/1'
19:05:56.992 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 31.31%
19:05:57.003 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 36.58%
19:05:57.003 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 41.85%
19:05:57.003 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/1/a'
19:05:57.011 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='1f0ee0e970101dbad5bafc7d78d3bd2acfffe160' to.sha1='<NoInstanceOf_NormalFileDto>'): /1/a
19:05:57.067 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=1, fromRepositoryId="FileInProgressMarker", toRepositoryId="1", path="001153a2-e7aa-4523-9b2f-cec50edabe4e"] on repo=b2edf6a8-c7cd-4fec-99f9-17eae99060e0
19:05:57.073 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/1/a'
19:05:57.073 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='1f0ee0e970101dbad5bafc7d78d3bd2acfffe160' toSha1='null'
19:05:57.073 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/1/a'
19:05:57.073 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/1/a' offset=0
19:05:57.075 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/1/a' offset=0
19:05:57.087 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 54450 bytes in 14 ms. path='/1/a'
19:05:57.090 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_86f7e437faa5a7fce15d1ddcb9eaeaea377667b8_0 into destFile a
19:05:57.123 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=1, fromRepositoryId="FileInProgressMarker", toRepositoryId="1", path="001153a2-e7aa-4523-9b2f-cec50edabe4e"] on repo=b2edf6a8-c7cd-4fec-99f9-17eae99060e0
19:05:57.135 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/1/b'
19:05:57.143 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='73f29b7875b387f335e5fb74cb0e9d6682e33795' to.sha1='<NoInstanceOf_NormalFileDto>'): /1/b
19:05:57.158 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=2, fromRepositoryId="FileInProgressMarker", toRepositoryId="2", path="001153a2-e7aa-4523-9b2f-cec50edabe4e"] on repo=b2edf6a8-c7cd-4fec-99f9-17eae99060e0
19:05:57.163 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/1/b'
19:05:57.164 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='73f29b7875b387f335e5fb74cb0e9d6682e33795' toSha1='null'
19:05:57.164 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/1/b'
19:05:57.164 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/1/b' offset=0
19:05:57.169 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/1/b' offset=0
19:05:57.179 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 444370 bytes in 15 ms. path='/1/b'
19:05:57.184 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_e9d71f5ee7c92d6dc9e92ffdad17b8bd49418f98_0 into destFile b
19:05:57.216 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=2, fromRepositoryId="FileInProgressMarker", toRepositoryId="2", path="001153a2-e7aa-4523-9b2f-cec50edabe4e"] on repo=b2edf6a8-c7cd-4fec-99f9-17eae99060e0
19:05:57.225 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/a'
19:05:57.233 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='a9a7b0ec9984e84578c062702ab7e954b2635955' to.sha1='<NoInstanceOf_NormalFileDto>'): /a
19:05:57.251 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=3, fromRepositoryId="FileInProgressMarker", toRepositoryId="3", path="001153a2-e7aa-4523-9b2f-cec50edabe4e"] on repo=b2edf6a8-c7cd-4fec-99f9-17eae99060e0
19:05:57.257 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/a'
19:05:57.257 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='a9a7b0ec9984e84578c062702ab7e954b2635955' toSha1='null'
19:05:57.257 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/a'
19:05:57.257 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/a' offset=0
19:05:57.258 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/a' offset=0
19:05:57.263 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 100381 bytes in 6 ms. path='/a'
19:05:57.266 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_86f7e437faa5a7fce15d1ddcb9eaeaea377667b8_0 into destFile a
19:05:57.285 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=3, fromRepositoryId="FileInProgressMarker", toRepositoryId="3", path="001153a2-e7aa-4523-9b2f-cec50edabe4e"] on repo=b2edf6a8-c7cd-4fec-99f9-17eae99060e0
19:05:57.296 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 48.43%
19:05:57.312 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=001153a2-e7aa-4523-9b2f-cec50edabe4e remoteRepositoryId=b2edf6a8-c7cd-4fec-99f9-17eae99060e0 localRepositoryRevisionSynced=2
19:05:57.340 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=b2edf6a8-c7cd-4fec-99f9-17eae99060e0 remoteRepositoryId=001153a2-e7aa-4523-9b2f-cec50edabe4e transaction.localRevision=19 remoteFromLocalRevision=2
19:05:57.551 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncUp: fromID=b2edf6a8-c7cd-4fec-99f9-17eae99060e0 from='target/repo/k98qdf6h-ewgl6-local' toID=001153a2-e7aa-4523-9b2f-cec50edabe4e to='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.local/target/repo/k98qdf6i-tuddi-remote/2'
19:05:57.584 [main] INFO c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=b2edf6a8-c7cd-4fec-99f9-17eae99060e0 remoteRepositoryId=001153a2-e7aa-4523-9b2f-cec50edabe4e localRepositoryRevisionSynced=-1 localRepositoryRevisionInProgress=19
19:05:57.597 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 63.43%
19:05:57.598 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 74.63%
19:05:57.606 [closeDeferredTimer-8fc1750-3] INFO c.c.c.l.LocalRepoManagerImpl - [8fc1750]_close: Shutting down real LocalRepoManager.
19:05:57.609 [closeDeferredTimer-7e34ca05-3] INFO c.c.c.l.LocalRepoManagerImpl - [7e34ca05]_close: Shutting down real LocalRepoManager.
19:05:58.280 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=b2edf6a8-c7cd-4fec-99f9-17eae99060e0 remoteRepositoryId=001153a2-e7aa-4523-9b2f-cec50edabe4e localRepositoryRevisionSynced=19
19:05:58.346 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=001153a2-e7aa-4523-9b2f-cec50edabe4e remoteRepositoryId=b2edf6a8-c7cd-4fec-99f9-17eae99060e0 transaction.localRevision=5 remoteFromLocalRevision=19
19:05:58.352 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDown: fromID=001153a2-e7aa-4523-9b2f-cec50edabe4e from='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.local/target/repo/k98qdf6i-tuddi-remote/2' toID=b2edf6a8-c7cd-4fec-99f9-17eae99060e0 to='target/repo/k98qdf6h-ewgl6-local', fromRepoLocalSync=false
19:05:58.416 [main] INFO c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=001153a2-e7aa-4523-9b2f-cec50edabe4e remoteRepositoryId=b2edf6a8-c7cd-4fec-99f9-17eae99060e0 localRepositoryRevisionSynced=2 localRepositoryRevisionInProgress=5
19:05:58.425 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 88.31%
19:05:58.425 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 99.50%
19:05:58.429 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=001153a2-e7aa-4523-9b2f-cec50edabe4e remoteRepositoryId=b2edf6a8-c7cd-4fec-99f9-17eae99060e0 localRepositoryRevisionSynced=5
19:05:58.521 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=b2edf6a8-c7cd-4fec-99f9-17eae99060e0 remoteRepositoryId=001153a2-e7aa-4523-9b2f-cec50edabe4e transaction.localRevision=22 remoteFromLocalRevision=5
19:05:58.533 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 100.00%
19:05:58.558 [main] INFO c.c.c.l.LocalRepoManagerImpl - [4cd54017]getCloseDeferredMillis: closeDeferredMillis=20000
19:05:58.558 [main] INFO c.c.c.l.LocalRepoManagerImpl - [4cd54017]close: Deferring shut down of real LocalRepoManager 20000 ms.
19:05:58.559 [main] INFO c.c.c.l.LocalRepoManagerImpl - [288b8b5b]getCloseDeferredMillis: closeDeferredMillis=20000
19:05:58.559 [main] INFO c.c.c.l.LocalRepoManagerImpl - [288b8b5b]close: Deferring shut down of real LocalRepoManager 20000 ms.
19:05:58.600 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 1.00%
19:05:58.602 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 50.00%
19:05:58.603 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 58.17%
19:05:58.607 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 63.61%
19:05:58.613 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 74.50%
19:05:58.664 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 80.62%
19:05:58.669 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 90.83%
19:05:58.695 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 96.55%
19:05:58.776 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 100.00%
19:05:58.806 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 0.50%
19:05:58.820 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDown: fromID=001153a2-e7aa-4523-9b2f-cec50edabe4e from='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.local/target/repo/k98qdf6i-tuddi-remote/2' toID=b2edf6a8-c7cd-4fec-99f9-17eae99060e0 to='target/repo/k98qdf6h-ewgl6-local', fromRepoLocalSync=true
19:05:58.820 [pool-32-thread-1] INFO c.c.c.c.r.s.RepoToRepoSync - sync: locally syncing b2edf6a8-c7cd-4fec-99f9-17eae99060e0 ('target/repo/k98qdf6h-ewgl6-local')
19:05:58.823 [pool-32-thread-1] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 12.94%
19:05:58.829 [pool-32-thread-1] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 18.27%
19:05:58.838 [pool-32-thread-1] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 25.12%
19:05:58.848 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 1.00%
19:05:58.850 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 50.00%
19:05:58.852 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 58.17%
19:05:58.855 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 63.61%
19:05:58.859 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 74.50%
19:05:58.872 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 80.62%
19:05:58.876 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 90.83%
19:05:58.882 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 96.55%
19:05:58.891 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 100.00%
19:05:58.982 [main] INFO c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=001153a2-e7aa-4523-9b2f-cec50edabe4e remoteRepositoryId=b2edf6a8-c7cd-4fec-99f9-17eae99060e0 localRepositoryRevisionSynced=5 localRepositoryRevisionInProgress=8
19:05:59.001 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path='/1'
19:05:59.055 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 31.31%
19:05:59.056 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path='/1/5'
19:05:59.085 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 36.58%
19:05:59.085 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 41.85%
19:05:59.085 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/1/5/aaa'
19:05:59.109 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='6899b24ecb8ec5f129c388e8301492920960d0c6' to.sha1='<NoInstanceOf_NormalFileDto>'): /1/5/aaa
19:05:59.170 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=4, fromRepositoryId="FileInProgressMarker", toRepositoryId="4", path="001153a2-e7aa-4523-9b2f-cec50edabe4e"] on repo=b2edf6a8-c7cd-4fec-99f9-17eae99060e0
19:05:59.180 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/1/5/aaa'
19:05:59.180 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='6899b24ecb8ec5f129c388e8301492920960d0c6' toSha1='null'
19:05:59.180 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/1/5/aaa'
19:05:59.180 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/1/5/aaa' offset=0
19:05:59.188 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/1/5/aaa' offset=0
19:05:59.204 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 657436 bytes in 24 ms. path='/1/5/aaa'
19:05:59.210 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_7e240de74fb1ed08fa08d38063f6a6a91462a815_0 into destFile aaa
19:05:59.256 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=4, fromRepositoryId="FileInProgressMarker", toRepositoryId="4", path="001153a2-e7aa-4523-9b2f-cec50edabe4e"] on repo=b2edf6a8-c7cd-4fec-99f9-17eae99060e0
19:05:59.276 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/1/5/bbb'
19:05:59.291 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='339df04df001e0e3b14b8549c494b41b6129bcd3' to.sha1='<NoInstanceOf_NormalFileDto>'): /1/5/bbb
19:05:59.320 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=5, fromRepositoryId="FileInProgressMarker", toRepositoryId="5", path="001153a2-e7aa-4523-9b2f-cec50edabe4e"] on repo=b2edf6a8-c7cd-4fec-99f9-17eae99060e0
19:05:59.326 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/1/5/bbb'
19:05:59.326 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='339df04df001e0e3b14b8549c494b41b6129bcd3' toSha1='null'
19:05:59.327 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/1/5/bbb'
19:05:59.327 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/1/5/bbb' offset=0
19:05:59.332 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/1/5/bbb' offset=0
19:05:59.342 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 247464 bytes in 15 ms. path='/1/5/bbb'
19:05:59.353 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_5cb138284d431abd6a053a56625ec088bfb88912_0 into destFile bbb
19:05:59.560 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=5, fromRepositoryId="FileInProgressMarker", toRepositoryId="5", path="001153a2-e7aa-4523-9b2f-cec50edabe4e"] on repo=b2edf6a8-c7cd-4fec-99f9-17eae99060e0
19:05:59.690 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 48.43%
19:05:59.693 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=001153a2-e7aa-4523-9b2f-cec50edabe4e remoteRepositoryId=b2edf6a8-c7cd-4fec-99f9-17eae99060e0 localRepositoryRevisionSynced=8
19:05:59.716 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=b2edf6a8-c7cd-4fec-99f9-17eae99060e0 remoteRepositoryId=001153a2-e7aa-4523-9b2f-cec50edabe4e transaction.localRevision=35 remoteFromLocalRevision=8
19:05:59.728 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncUp: fromID=b2edf6a8-c7cd-4fec-99f9-17eae99060e0 from='target/repo/k98qdf6h-ewgl6-local' toID=001153a2-e7aa-4523-9b2f-cec50edabe4e to='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.local/target/repo/k98qdf6i-tuddi-remote/2'
19:05:59.766 [main] INFO c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=b2edf6a8-c7cd-4fec-99f9-17eae99060e0 remoteRepositoryId=001153a2-e7aa-4523-9b2f-cec50edabe4e localRepositoryRevisionSynced=19 localRepositoryRevisionInProgress=35
19:05:59.769 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 63.43%
19:05:59.769 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 74.63%
19:05:59.784 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=b2edf6a8-c7cd-4fec-99f9-17eae99060e0 remoteRepositoryId=001153a2-e7aa-4523-9b2f-cec50edabe4e localRepositoryRevisionSynced=35
19:05:59.865 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=001153a2-e7aa-4523-9b2f-cec50edabe4e remoteRepositoryId=b2edf6a8-c7cd-4fec-99f9-17eae99060e0 transaction.localRevision=11 remoteFromLocalRevision=35
19:05:59.917 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDown: fromID=001153a2-e7aa-4523-9b2f-cec50edabe4e from='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.local/target/repo/k98qdf6i-tuddi-remote/2' toID=b2edf6a8-c7cd-4fec-99f9-17eae99060e0 to='target/repo/k98qdf6h-ewgl6-local', fromRepoLocalSync=false
19:05:59.946 [main] INFO c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=001153a2-e7aa-4523-9b2f-cec50edabe4e remoteRepositoryId=b2edf6a8-c7cd-4fec-99f9-17eae99060e0 localRepositoryRevisionSynced=8 localRepositoryRevisionInProgress=11
19:05:59.952 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 88.31%
19:05:59.953 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 99.50%
19:05:59.957 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=001153a2-e7aa-4523-9b2f-cec50edabe4e remoteRepositoryId=b2edf6a8-c7cd-4fec-99f9-17eae99060e0 localRepositoryRevisionSynced=11
19:06:00.019 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=b2edf6a8-c7cd-4fec-99f9-17eae99060e0 remoteRepositoryId=001153a2-e7aa-4523-9b2f-cec50edabe4e transaction.localRevision=38 remoteFromLocalRevision=11
19:06:00.031 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 100.00%
19:06:00.031 [main] INFO c.c.c.l.LocalRepoManagerImpl - [4cd54017]close: Deferring shut down of real LocalRepoManager 20000 ms.
19:06:00.052 [main] INFO c.c.c.l.LocalRepoManagerImpl - [288b8b5b]close: Deferring shut down of real LocalRepoManager 20000 ms.
19:06:00.084 [main] INFO c.c.c.l.LocalRepoManagerImpl - [35a0ae8e]<init>: localRoot='/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.local/target/repo/k98qdo2b-rud46-local'
19:06:00.089 [main] INFO c.c.c.l.LocalRepoManagerImpl - [35a0ae8e]initLockFile: Repository '/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.local/target/repo/k98qdo2b-rud46-local' locked successfully.
19:06:03.572 [main] INFO c.c.c.l.LocalRepoManagerImpl - [35a0ae8e]initPersistenceManagerFactory: repositoryId=fba5a795-da30-47ff-bb30-f53d0bcae1cf
19:06:03.576 [main] INFO c.c.c.l.LocalRepoManagerImpl - [35a0ae8e]initPersistenceManagerFactory: Started up PersistenceManagerFactory successfully in 1516 ms.
19:06:03.598 [main] INFO c.c.c.l.LocalRepoManagerImpl - [10686a98]<init>: localRoot='/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.local/target/repo/k98qdo2b-kzaga-remote'
19:06:03.606 [main] INFO c.c.c.l.LocalRepoManagerImpl - [10686a98]initLockFile: Repository '/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.local/target/repo/k98qdo2b-kzaga-remote' locked successfully.
19:06:07.270 [main] INFO c.c.c.l.LocalRepoManagerImpl - [10686a98]initPersistenceManagerFactory: repositoryId=b14f32ab-5a49-43e7-bdcd-3da2485c2693
19:06:07.277 [main] INFO c.c.c.l.LocalRepoManagerImpl - [10686a98]initPersistenceManagerFactory: Started up PersistenceManagerFactory successfully in 2018 ms.
19:06:07.436 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 1.00%
19:06:07.442 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 50.00%
19:06:07.453 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 58.00%
19:06:07.498 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 63.61%
19:06:07.521 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 74.34%
19:06:07.552 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 79.36%
19:06:07.572 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 90.67%
19:06:07.610 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 95.91%
19:06:07.648 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 100.00%
19:06:07.674 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - local repo: fba5a795-da30-47ff-bb30-f53d0bcae1cf
19:06:07.674 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - remote repo: b14f32ab-5a49-43e7-bdcd-3da2485c2693
19:06:07.680 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 0.50%
19:06:07.684 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDown: fromID=b14f32ab-5a49-43e7-bdcd-3da2485c2693 from='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.local/target/repo/k98qdo2b-kzaga-remote' toID=fba5a795-da30-47ff-bb30-f53d0bcae1cf to='target/repo/k98qdo2b-rud46-local', fromRepoLocalSync=true
19:06:07.686 [pool-33-thread-1] INFO c.c.c.c.r.s.RepoToRepoSync - sync: locally syncing fba5a795-da30-47ff-bb30-f53d0bcae1cf ('target/repo/k98qdo2b-rud46-local')
19:06:07.717 [pool-33-thread-1] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 12.94%
19:06:07.723 [pool-33-thread-1] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 25.12%
19:06:07.726 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 1.00%
19:06:07.728 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 50.00%
19:06:07.730 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 58.17%
19:06:07.735 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 63.61%
19:06:07.739 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 74.50%
19:06:07.746 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 80.62%
19:06:07.749 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 90.83%
19:06:07.754 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 95.94%
19:06:07.760 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 100.00%
19:06:07.778 [main] INFO c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=b14f32ab-5a49-43e7-bdcd-3da2485c2693 remoteRepositoryId=fba5a795-da30-47ff-bb30-f53d0bcae1cf localRepositoryRevisionSynced=-1 localRepositoryRevisionInProgress=2
19:06:07.812 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path=''
19:06:07.856 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path='/1'
19:06:07.873 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 30.22%
19:06:07.873 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path='/2'
19:06:07.884 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path='/2/1'
19:06:07.904 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path='/3'
19:06:07.919 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 35.46%
19:06:07.919 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 40.70%
19:06:07.919 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/1/a'
19:06:07.925 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='0c2426004bcdb875189f52d18047c6ffc2f5edc9' to.sha1='<NoInstanceOf_NormalFileDto>'): /1/a
19:06:07.967 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=1, fromRepositoryId="FileInProgressMarker", toRepositoryId="1", path="b14f32ab-5a49-43e7-bdcd-3da2485c2693"] on repo=fba5a795-da30-47ff-bb30-f53d0bcae1cf
19:06:07.972 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/1/a'
19:06:07.972 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='0c2426004bcdb875189f52d18047c6ffc2f5edc9' toSha1='null'
19:06:07.972 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/1/a'
19:06:07.972 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/1/a' offset=0
19:06:07.972 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/1/a' offset=0
19:06:07.977 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 28896 bytes in 5 ms. path='/1/a'
19:06:07.980 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_86f7e437faa5a7fce15d1ddcb9eaeaea377667b8_0 into destFile a
19:06:08.015 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=1, fromRepositoryId="FileInProgressMarker", toRepositoryId="1", path="b14f32ab-5a49-43e7-bdcd-3da2485c2693"] on repo=fba5a795-da30-47ff-bb30-f53d0bcae1cf
19:06:08.040 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/1/b'
19:06:08.051 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='43ab4b6017cd870083ebf2a1813314d302319a2c' to.sha1='<NoInstanceOf_NormalFileDto>'): /1/b
19:06:08.079 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=2, fromRepositoryId="FileInProgressMarker", toRepositoryId="2", path="b14f32ab-5a49-43e7-bdcd-3da2485c2693"] on repo=fba5a795-da30-47ff-bb30-f53d0bcae1cf
19:06:08.084 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/1/b'
19:06:08.084 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='43ab4b6017cd870083ebf2a1813314d302319a2c' toSha1='null'
19:06:08.084 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/1/b'
19:06:08.084 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/1/b' offset=0
19:06:08.085 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/1/b' offset=0
19:06:08.090 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 54351 bytes in 6 ms. path='/1/b'
19:06:08.095 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_e9d71f5ee7c92d6dc9e92ffdad17b8bd49418f98_0 into destFile b
19:06:08.151 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=2, fromRepositoryId="FileInProgressMarker", toRepositoryId="2", path="b14f32ab-5a49-43e7-bdcd-3da2485c2693"] on repo=fba5a795-da30-47ff-bb30-f53d0bcae1cf
19:06:08.163 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/1/c'
19:06:08.170 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='a324cad506b9e2999536c78a424ba86b837e273f' to.sha1='<NoInstanceOf_NormalFileDto>'): /1/c
19:06:08.186 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=3, fromRepositoryId="FileInProgressMarker", toRepositoryId="3", path="b14f32ab-5a49-43e7-bdcd-3da2485c2693"] on repo=fba5a795-da30-47ff-bb30-f53d0bcae1cf
19:06:08.189 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/1/c'
19:06:08.190 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='a324cad506b9e2999536c78a424ba86b837e273f' toSha1='null'
19:06:08.190 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/1/c'
19:06:08.190 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/1/c' offset=0
19:06:08.190 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/1/c' offset=0
19:06:08.195 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 33000 bytes in 5 ms. path='/1/c'
19:06:08.198 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_84a516841ba77a5b4648de2cd0dfcb30ea46dbb4_0 into destFile c
19:06:08.210 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=3, fromRepositoryId="FileInProgressMarker", toRepositoryId="3", path="b14f32ab-5a49-43e7-bdcd-3da2485c2693"] on repo=fba5a795-da30-47ff-bb30-f53d0bcae1cf
19:06:08.216 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/2/1/a'
19:06:08.226 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='cee8e18c794db6997a71385afdbdf2e64c20948f' to.sha1='<NoInstanceOf_NormalFileDto>'): /2/1/a
19:06:08.244 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=4, fromRepositoryId="FileInProgressMarker", toRepositoryId="4", path="b14f32ab-5a49-43e7-bdcd-3da2485c2693"] on repo=fba5a795-da30-47ff-bb30-f53d0bcae1cf
19:06:08.248 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/2/1/a'
19:06:08.248 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='cee8e18c794db6997a71385afdbdf2e64c20948f' toSha1='null'
19:06:08.248 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/2/1/a'
19:06:08.248 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/2/1/a' offset=0
19:06:08.250 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/2/1/a' offset=0
19:06:08.261 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 151750 bytes in 13 ms. path='/2/1/a'
19:06:08.265 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_86f7e437faa5a7fce15d1ddcb9eaeaea377667b8_0 into destFile a
19:06:08.282 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=4, fromRepositoryId="FileInProgressMarker", toRepositoryId="4", path="b14f32ab-5a49-43e7-bdcd-3da2485c2693"] on repo=fba5a795-da30-47ff-bb30-f53d0bcae1cf
19:06:08.292 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/2/1/b'
19:06:08.309 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='942516e135500c6682002246b118e7d13c071aed' to.sha1='<NoInstanceOf_NormalFileDto>'): /2/1/b
19:06:08.325 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=5, fromRepositoryId="FileInProgressMarker", toRepositoryId="5", path="b14f32ab-5a49-43e7-bdcd-3da2485c2693"] on repo=fba5a795-da30-47ff-bb30-f53d0bcae1cf
19:06:08.329 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/2/1/b'
19:06:08.329 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='942516e135500c6682002246b118e7d13c071aed' toSha1='null'
19:06:08.329 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/2/1/b'
19:06:08.329 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/2/1/b' offset=0
19:06:08.332 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/2/1/b' offset=0
19:06:08.346 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 150072 bytes in 17 ms. path='/2/1/b'
19:06:08.350 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_e9d71f5ee7c92d6dc9e92ffdad17b8bd49418f98_0 into destFile b
19:06:08.369 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=5, fromRepositoryId="FileInProgressMarker", toRepositoryId="5", path="b14f32ab-5a49-43e7-bdcd-3da2485c2693"] on repo=fba5a795-da30-47ff-bb30-f53d0bcae1cf
19:06:08.378 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/2/a'
19:06:08.387 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='1d85ea757fbc3ad259ab08cfe682155c1d03bba0' to.sha1='<NoInstanceOf_NormalFileDto>'): /2/a
19:06:08.400 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=6, fromRepositoryId="FileInProgressMarker", toRepositoryId="6", path="b14f32ab-5a49-43e7-bdcd-3da2485c2693"] on repo=fba5a795-da30-47ff-bb30-f53d0bcae1cf
19:06:08.403 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/2/a'
19:06:08.404 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='1d85ea757fbc3ad259ab08cfe682155c1d03bba0' toSha1='null'
19:06:08.404 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/2/a'
19:06:08.404 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/2/a' offset=0
19:06:08.406 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/2/a' offset=0
19:06:08.416 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 250348 bytes in 12 ms. path='/2/a'
19:06:08.423 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_86f7e437faa5a7fce15d1ddcb9eaeaea377667b8_0 into destFile a
19:06:08.458 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=6, fromRepositoryId="FileInProgressMarker", toRepositoryId="6", path="b14f32ab-5a49-43e7-bdcd-3da2485c2693"] on repo=fba5a795-da30-47ff-bb30-f53d0bcae1cf
19:06:08.465 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/3/a'
19:06:08.475 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='6c354127a85a00c5a985524817e58e2886d378ef' to.sha1='<NoInstanceOf_NormalFileDto>'): /3/a
19:06:08.486 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=7, fromRepositoryId="FileInProgressMarker", toRepositoryId="7", path="b14f32ab-5a49-43e7-bdcd-3da2485c2693"] on repo=fba5a795-da30-47ff-bb30-f53d0bcae1cf
19:06:08.489 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/3/a'
19:06:08.489 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='6c354127a85a00c5a985524817e58e2886d378ef' toSha1='null'
19:06:08.489 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/3/a'
19:06:08.489 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/3/a' offset=0
19:06:08.493 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/3/a' offset=0
19:06:08.504 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 305502 bytes in 15 ms. path='/3/a'
19:06:08.508 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_86f7e437faa5a7fce15d1ddcb9eaeaea377667b8_0 into destFile a
19:06:08.530 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=7, fromRepositoryId="FileInProgressMarker", toRepositoryId="7", path="b14f32ab-5a49-43e7-bdcd-3da2485c2693"] on repo=fba5a795-da30-47ff-bb30-f53d0bcae1cf
19:06:08.536 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/3/b'
19:06:08.547 [closeDeferredTimer-69acd2d1-1] INFO c.c.c.l.LocalRepoManagerImpl - [69acd2d1]_close: Shutting down real LocalRepoManager.
19:06:08.547 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='13e742eef66780255a14da76f82f306db3397f3f' to.sha1='<NoInstanceOf_NormalFileDto>'): /3/b
19:06:08.548 [closeDeferredTimer-75cf2b19-1] INFO c.c.c.l.LocalRepoManagerImpl - [75cf2b19]_close: Shutting down real LocalRepoManager.
19:06:09.351 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=8, fromRepositoryId="FileInProgressMarker", toRepositoryId="8", path="b14f32ab-5a49-43e7-bdcd-3da2485c2693"] on repo=fba5a795-da30-47ff-bb30-f53d0bcae1cf
19:06:09.447 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/3/b'
19:06:09.448 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='13e742eef66780255a14da76f82f306db3397f3f' toSha1='null'
19:06:09.450 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/3/b'
19:06:09.451 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/3/b' offset=0
19:06:09.484 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/3/b' offset=0
19:06:09.509 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 804264 bytes in 58 ms. path='/3/b'
19:06:09.514 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_e9d71f5ee7c92d6dc9e92ffdad17b8bd49418f98_0 into destFile b
19:06:09.578 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=8, fromRepositoryId="FileInProgressMarker", toRepositoryId="8", path="b14f32ab-5a49-43e7-bdcd-3da2485c2693"] on repo=fba5a795-da30-47ff-bb30-f53d0bcae1cf
19:06:09.597 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/3/c'
19:06:09.608 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='75081a85def2a8e3a9977feb53ecbd1401d0b8f3' to.sha1='<NoInstanceOf_NormalFileDto>'): /3/c
19:06:09.625 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=9, fromRepositoryId="FileInProgressMarker", toRepositoryId="9", path="b14f32ab-5a49-43e7-bdcd-3da2485c2693"] on repo=fba5a795-da30-47ff-bb30-f53d0bcae1cf
19:06:09.631 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/3/c'
19:06:09.631 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='75081a85def2a8e3a9977feb53ecbd1401d0b8f3' toSha1='null'
19:06:09.631 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/3/c'
19:06:09.631 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/3/c' offset=0
19:06:09.635 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/3/c' offset=0
19:06:09.649 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 153090 bytes in 18 ms. path='/3/c'
19:06:09.653 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_84a516841ba77a5b4648de2cd0dfcb30ea46dbb4_0 into destFile c
19:06:09.680 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=9, fromRepositoryId="FileInProgressMarker", toRepositoryId="9", path="b14f32ab-5a49-43e7-bdcd-3da2485c2693"] on repo=fba5a795-da30-47ff-bb30-f53d0bcae1cf
19:06:09.694 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/3/d'
19:06:09.703 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='553a856e09f7ec9db4a5b11e3909b78f11af12e7' to.sha1='<NoInstanceOf_NormalFileDto>'): /3/d
19:06:09.722 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=10, fromRepositoryId="FileInProgressMarker", toRepositoryId="10", path="b14f32ab-5a49-43e7-bdcd-3da2485c2693"] on repo=fba5a795-da30-47ff-bb30-f53d0bcae1cf
19:06:09.738 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/3/d'
19:06:09.738 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='553a856e09f7ec9db4a5b11e3909b78f11af12e7' toSha1='null'
19:06:09.738 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/3/d'
19:06:09.738 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/3/d' offset=0
19:06:09.739 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/3/d' offset=0
19:06:09.758 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 18460 bytes in 20 ms. path='/3/d'
19:06:09.764 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_3c363836cf4e16666669a25da280a1865c2d2874_0 into destFile d
19:06:09.794 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=10, fromRepositoryId="FileInProgressMarker", toRepositoryId="10", path="b14f32ab-5a49-43e7-bdcd-3da2485c2693"] on repo=fba5a795-da30-47ff-bb30-f53d0bcae1cf
19:06:09.812 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 49.27%
19:06:09.834 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=b14f32ab-5a49-43e7-bdcd-3da2485c2693 remoteRepositoryId=fba5a795-da30-47ff-bb30-f53d0bcae1cf localRepositoryRevisionSynced=2
19:06:09.863 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=fba5a795-da30-47ff-bb30-f53d0bcae1cf remoteRepositoryId=b14f32ab-5a49-43e7-bdcd-3da2485c2693 transaction.localRevision=57 remoteFromLocalRevision=2
19:06:09.880 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncUp: fromID=fba5a795-da30-47ff-bb30-f53d0bcae1cf from='target/repo/k98qdo2b-rud46-local' toID=b14f32ab-5a49-43e7-bdcd-3da2485c2693 to='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.local/target/repo/k98qdo2b-kzaga-remote'
19:06:09.931 [main] INFO c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=fba5a795-da30-47ff-bb30-f53d0bcae1cf remoteRepositoryId=b14f32ab-5a49-43e7-bdcd-3da2485c2693 localRepositoryRevisionSynced=-1 localRepositoryRevisionInProgress=57
19:06:09.948 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 63.43%
19:06:09.948 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 74.63%
19:06:09.959 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=fba5a795-da30-47ff-bb30-f53d0bcae1cf remoteRepositoryId=b14f32ab-5a49-43e7-bdcd-3da2485c2693 localRepositoryRevisionSynced=57
19:06:09.978 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=b14f32ab-5a49-43e7-bdcd-3da2485c2693 remoteRepositoryId=fba5a795-da30-47ff-bb30-f53d0bcae1cf transaction.localRevision=5 remoteFromLocalRevision=57
19:06:09.995 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDown: fromID=b14f32ab-5a49-43e7-bdcd-3da2485c2693 from='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.local/target/repo/k98qdo2b-kzaga-remote' toID=fba5a795-da30-47ff-bb30-f53d0bcae1cf to='target/repo/k98qdo2b-rud46-local', fromRepoLocalSync=false
19:06:10.035 [main] INFO c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=b14f32ab-5a49-43e7-bdcd-3da2485c2693 remoteRepositoryId=fba5a795-da30-47ff-bb30-f53d0bcae1cf localRepositoryRevisionSynced=2 localRepositoryRevisionInProgress=5
19:06:10.036 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 88.31%
19:06:10.036 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 99.50%
19:06:10.039 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=b14f32ab-5a49-43e7-bdcd-3da2485c2693 remoteRepositoryId=fba5a795-da30-47ff-bb30-f53d0bcae1cf localRepositoryRevisionSynced=5
19:06:10.047 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=fba5a795-da30-47ff-bb30-f53d0bcae1cf remoteRepositoryId=b14f32ab-5a49-43e7-bdcd-3da2485c2693 transaction.localRevision=60 remoteFromLocalRevision=5
19:06:10.064 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 100.00%
19:06:10.079 [main] INFO c.c.c.l.LocalRepoManagerImpl - [35a0ae8e]getCloseDeferredMillis: closeDeferredMillis=20000
19:06:10.079 [main] INFO c.c.c.l.LocalRepoManagerImpl - [35a0ae8e]close: Deferring shut down of real LocalRepoManager 20000 ms.
19:06:10.080 [main] INFO c.c.c.l.LocalRepoManagerImpl - [10686a98]getCloseDeferredMillis: closeDeferredMillis=20000
19:06:10.081 [main] INFO c.c.c.l.LocalRepoManagerImpl - [10686a98]close: Deferring shut down of real LocalRepoManager 20000 ms.
19:06:10.143 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 1.00%
19:06:10.144 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 50.00%
19:06:10.145 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 58.17%
19:06:10.149 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 63.61%
19:06:10.154 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 74.50%
19:06:10.200 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 80.62%
19:06:10.204 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 90.83%
19:06:10.226 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 96.55%
19:06:10.251 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 100.00%
19:06:10.278 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 0.50%
19:06:10.279 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDown: fromID=b14f32ab-5a49-43e7-bdcd-3da2485c2693 from='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.local/target/repo/k98qdo2b-kzaga-remote' toID=fba5a795-da30-47ff-bb30-f53d0bcae1cf to='target/repo/k98qdo2b-rud46-local', fromRepoLocalSync=true
19:06:10.279 [pool-34-thread-1] INFO c.c.c.c.r.s.RepoToRepoSync - sync: locally syncing fba5a795-da30-47ff-bb30-f53d0bcae1cf ('target/repo/k98qdo2b-rud46-local')
19:06:10.287 [pool-34-thread-1] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 12.94%
19:06:10.309 [pool-34-thread-1] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 19.03%
19:06:10.332 [pool-34-thread-1] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 24.11%
19:06:10.349 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 1.00%
19:06:10.350 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 50.00%
19:06:10.352 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 58.17%
19:06:10.356 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 63.61%
19:06:10.360 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 74.50%
19:06:10.377 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 80.62%
19:06:10.382 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 90.83%
19:06:10.391 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 96.55%
19:06:10.401 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 100.00%
19:06:10.426 [main] INFO c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=b14f32ab-5a49-43e7-bdcd-3da2485c2693 remoteRepositoryId=fba5a795-da30-47ff-bb30-f53d0bcae1cf localRepositoryRevisionSynced=5 localRepositoryRevisionInProgress=8
19:06:10.441 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 29.95%
19:06:10.442 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path='/2/1'
19:06:10.472 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path='/2/1/5'
19:06:10.488 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path='/3'
19:06:10.502 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 35.11%
19:06:10.503 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 40.28%
19:06:10.503 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 45.45%
19:06:10.503 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/2/1/5/aaa'
19:06:10.513 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='5788eb84ac90deaf2900b2d0ed26f99581718372' to.sha1='<NoInstanceOf_NormalFileDto>'): /2/1/5/aaa
19:06:10.531 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=11, fromRepositoryId="FileInProgressMarker", toRepositoryId="11", path="b14f32ab-5a49-43e7-bdcd-3da2485c2693"] on repo=fba5a795-da30-47ff-bb30-f53d0bcae1cf
19:06:10.534 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/2/1/5/aaa'
19:06:10.534 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='5788eb84ac90deaf2900b2d0ed26f99581718372' toSha1='null'
19:06:10.535 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/2/1/5/aaa'
19:06:10.535 [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
19:06:10.538 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/2/1/5/aaa' offset=0
19:06:10.550 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 326230 bytes in 15 ms. path='/2/1/5/aaa'
19:06:10.556 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_7e240de74fb1ed08fa08d38063f6a6a91462a815_0 into destFile aaa
19:06:10.581 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=11, fromRepositoryId="FileInProgressMarker", toRepositoryId="11", path="b14f32ab-5a49-43e7-bdcd-3da2485c2693"] on repo=fba5a795-da30-47ff-bb30-f53d0bcae1cf
19:06:10.587 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/2/1/5/bbb'
19:06:10.601 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='48bc754ab5056fd5f9c10f5a45fd05a04eff1c15' to.sha1='<NoInstanceOf_NormalFileDto>'): /2/1/5/bbb
19:06:10.621 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=12, fromRepositoryId="FileInProgressMarker", toRepositoryId="12", path="b14f32ab-5a49-43e7-bdcd-3da2485c2693"] on repo=fba5a795-da30-47ff-bb30-f53d0bcae1cf
19:06:10.625 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/2/1/5/bbb'
19:06:10.625 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='48bc754ab5056fd5f9c10f5a45fd05a04eff1c15' toSha1='null'
19:06:10.625 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/2/1/5/bbb'
19:06:10.625 [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
19:06:10.633 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/2/1/5/bbb' offset=0
19:06:10.650 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 645840 bytes in 25 ms. path='/2/1/5/bbb'
19:06:10.658 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_5cb138284d431abd6a053a56625ec088bfb88912_0 into destFile bbb
19:06:10.698 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=12, fromRepositoryId="FileInProgressMarker", toRepositoryId="12", path="b14f32ab-5a49-43e7-bdcd-3da2485c2693"] on repo=fba5a795-da30-47ff-bb30-f53d0bcae1cf
19:06:10.706 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/3/e'
19:06:10.717 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='83a6e31bf097c6392790c0cf7f152fc42052185d' to.sha1='<NoInstanceOf_NormalFileDto>'): /3/e
19:06:10.735 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=13, fromRepositoryId="FileInProgressMarker", toRepositoryId="13", path="b14f32ab-5a49-43e7-bdcd-3da2485c2693"] on repo=fba5a795-da30-47ff-bb30-f53d0bcae1cf
19:06:10.740 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/3/e'
19:06:10.740 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='83a6e31bf097c6392790c0cf7f152fc42052185d' toSha1='null'
19:06:10.740 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/3/e'
19:06:10.740 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/3/e' offset=0
19:06:10.746 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/3/e' offset=0
19:06:10.760 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 511973 bytes in 20 ms. path='/3/e'
19:06:10.765 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_58e6b3a414a1e090dfc6029add0f3555ccba127f_0 into destFile e
19:06:10.801 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=13, fromRepositoryId="FileInProgressMarker", toRepositoryId="13", path="b14f32ab-5a49-43e7-bdcd-3da2485c2693"] on repo=fba5a795-da30-47ff-bb30-f53d0bcae1cf
19:06:10.812 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=b14f32ab-5a49-43e7-bdcd-3da2485c2693 remoteRepositoryId=fba5a795-da30-47ff-bb30-f53d0bcae1cf localRepositoryRevisionSynced=8
19:06:10.816 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=fba5a795-da30-47ff-bb30-f53d0bcae1cf remoteRepositoryId=b14f32ab-5a49-43e7-bdcd-3da2485c2693 transaction.localRevision=79 remoteFromLocalRevision=8
19:06:10.825 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncUp: fromID=fba5a795-da30-47ff-bb30-f53d0bcae1cf from='target/repo/k98qdo2b-rud46-local' toID=b14f32ab-5a49-43e7-bdcd-3da2485c2693 to='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.local/target/repo/k98qdo2b-kzaga-remote'
19:06:10.842 [main] INFO c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=fba5a795-da30-47ff-bb30-f53d0bcae1cf remoteRepositoryId=b14f32ab-5a49-43e7-bdcd-3da2485c2693 localRepositoryRevisionSynced=57 localRepositoryRevisionInProgress=79
19:06:10.845 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 52.24%
19:06:10.845 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 63.43%
19:06:10.846 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 74.63%
19:06:10.850 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=fba5a795-da30-47ff-bb30-f53d0bcae1cf remoteRepositoryId=b14f32ab-5a49-43e7-bdcd-3da2485c2693 localRepositoryRevisionSynced=79
19:06:10.857 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=b14f32ab-5a49-43e7-bdcd-3da2485c2693 remoteRepositoryId=fba5a795-da30-47ff-bb30-f53d0bcae1cf transaction.localRevision=11 remoteFromLocalRevision=79
19:06:10.858 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDown: fromID=b14f32ab-5a49-43e7-bdcd-3da2485c2693 from='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.local/target/repo/k98qdo2b-kzaga-remote' toID=fba5a795-da30-47ff-bb30-f53d0bcae1cf to='target/repo/k98qdo2b-rud46-local', fromRepoLocalSync=false
19:06:10.866 [main] INFO c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=b14f32ab-5a49-43e7-bdcd-3da2485c2693 remoteRepositoryId=fba5a795-da30-47ff-bb30-f53d0bcae1cf localRepositoryRevisionSynced=8 localRepositoryRevisionInProgress=11
19:06:10.869 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 88.31%
19:06:10.869 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 99.50%
19:06:10.873 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=b14f32ab-5a49-43e7-bdcd-3da2485c2693 remoteRepositoryId=fba5a795-da30-47ff-bb30-f53d0bcae1cf localRepositoryRevisionSynced=11
19:06:10.877 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=fba5a795-da30-47ff-bb30-f53d0bcae1cf remoteRepositoryId=b14f32ab-5a49-43e7-bdcd-3da2485c2693 transaction.localRevision=82 remoteFromLocalRevision=11
19:06:10.882 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 100.00%
19:06:10.883 [main] INFO c.c.c.l.LocalRepoManagerImpl - [35a0ae8e]close: Deferring shut down of real LocalRepoManager 20000 ms.
19:06:10.904 [main] INFO c.c.c.l.LocalRepoManagerImpl - [10686a98]close: Deferring shut down of real LocalRepoManager 20000 ms.
19:06:10.981 [main] INFO c.c.c.l.LocalRepoManagerImpl - [5180743a]<init>: localRoot='/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.local/target/repo/k98qdwgy-tq3hd-local'
19:06:10.989 [main] INFO c.c.c.l.LocalRepoManagerImpl - [5180743a]initLockFile: Repository '/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.local/target/repo/k98qdwgy-tq3hd-local' locked successfully.
19:06:13.562 [main] INFO c.c.c.l.LocalRepoManagerImpl - [5180743a]initPersistenceManagerFactory: repositoryId=6defe6e1-4c22-446d-9527-c40a21cd0d16
19:06:13.563 [main] INFO c.c.c.l.LocalRepoManagerImpl - [5180743a]initPersistenceManagerFactory: Started up PersistenceManagerFactory successfully in 1355 ms.
19:06:13.584 [main] INFO c.c.c.l.LocalRepoManagerImpl - [314f6bf0]<init>: localRoot='/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.local/target/repo/k98qdwgz-tymhk-remote'
19:06:13.589 [main] INFO c.c.c.l.LocalRepoManagerImpl - [314f6bf0]initLockFile: Repository '/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.local/target/repo/k98qdwgz-tymhk-remote' locked successfully.
19:06:17.971 [main] INFO c.c.c.l.LocalRepoManagerImpl - [314f6bf0]initPersistenceManagerFactory: repositoryId=69768b5b-8a05-4d5c-ab5f-8227de97f105
19:06:17.979 [main] INFO c.c.c.l.LocalRepoManagerImpl - [314f6bf0]initPersistenceManagerFactory: Started up PersistenceManagerFactory successfully in 2484 ms.
19:06:18.074 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 1.00%
19:06:18.082 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 50.00%
19:06:18.092 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 58.00%
19:06:18.142 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 63.61%
19:06:18.151 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 74.34%
19:06:18.169 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 79.70%
19:06:18.179 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 90.67%
19:06:18.200 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 95.91%
19:06:18.225 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 100.00%
19:06:18.248 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - local repo: 6defe6e1-4c22-446d-9527-c40a21cd0d16
19:06:18.248 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - remote repo: 69768b5b-8a05-4d5c-ab5f-8227de97f105
19:06:18.252 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 0.50%
19:06:18.252 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDown: fromID=69768b5b-8a05-4d5c-ab5f-8227de97f105 from='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.local/target/repo/k98qdwgz-tymhk-remote' toID=6defe6e1-4c22-446d-9527-c40a21cd0d16 to='target/repo/k98qdwgy-tq3hd-local', fromRepoLocalSync=true
19:06:18.253 [pool-35-thread-1] INFO c.c.c.c.r.s.RepoToRepoSync - sync: locally syncing 6defe6e1-4c22-446d-9527-c40a21cd0d16 ('target/repo/k98qdwgy-tq3hd-local')
19:06:18.282 [pool-35-thread-1] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 12.94%
19:06:18.287 [pool-35-thread-1] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 25.12%
19:06:18.288 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 1.00%
19:06:18.290 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 50.00%
19:06:18.294 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 58.17%
19:06:18.303 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 63.61%
19:06:18.306 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 74.50%
19:06:18.312 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 80.62%
19:06:18.315 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 90.83%
19:06:18.318 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 95.94%
19:06:18.324 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 100.00%
19:06:18.349 [main] INFO c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=69768b5b-8a05-4d5c-ab5f-8227de97f105 remoteRepositoryId=6defe6e1-4c22-446d-9527-c40a21cd0d16 localRepositoryRevisionSynced=-1 localRepositoryRevisionInProgress=2
19:06:18.386 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path=''
19:06:18.413 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path='/1'
19:06:18.425 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 30.22%
19:06:18.425 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path='/2'
19:06:18.432 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path='/2/1'
19:06:18.442 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path='/3'
19:06:18.456 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 35.46%
19:06:18.457 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 40.70%
19:06:18.457 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/1/a'
19:06:18.465 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='ed3526d5aec9f0321b0c6ba24bd9eb1dcff117ab' to.sha1='<NoInstanceOf_NormalFileDto>'): /1/a
19:06:18.504 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=1, fromRepositoryId="FileInProgressMarker", toRepositoryId="1", path="69768b5b-8a05-4d5c-ab5f-8227de97f105"] on repo=6defe6e1-4c22-446d-9527-c40a21cd0d16
19:06:18.506 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/1/a'
19:06:18.506 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='ed3526d5aec9f0321b0c6ba24bd9eb1dcff117ab' toSha1='null'
19:06:18.506 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/1/a'
19:06:18.506 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/1/a' offset=0
19:06:18.507 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/1/a' offset=0
19:06:18.514 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 65268 bytes in 8 ms. path='/1/a'
19:06:18.518 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_86f7e437faa5a7fce15d1ddcb9eaeaea377667b8_0 into destFile a
19:06:18.556 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=1, fromRepositoryId="FileInProgressMarker", toRepositoryId="1", path="69768b5b-8a05-4d5c-ab5f-8227de97f105"] on repo=6defe6e1-4c22-446d-9527-c40a21cd0d16
19:06:18.565 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/1/b'
19:06:18.577 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='9413a6abc191ffe19f0923a71ad055b6d9ba2ef7' to.sha1='<NoInstanceOf_NormalFileDto>'): /1/b
19:06:18.590 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=2, fromRepositoryId="FileInProgressMarker", toRepositoryId="2", path="69768b5b-8a05-4d5c-ab5f-8227de97f105"] on repo=6defe6e1-4c22-446d-9527-c40a21cd0d16
19:06:18.591 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/1/b'
19:06:18.592 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='9413a6abc191ffe19f0923a71ad055b6d9ba2ef7' toSha1='null'
19:06:18.592 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/1/b'
19:06:18.592 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/1/b' offset=0
19:06:18.593 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/1/b' offset=0
19:06:18.601 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 138761 bytes in 9 ms. path='/1/b'
19:06:18.605 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_e9d71f5ee7c92d6dc9e92ffdad17b8bd49418f98_0 into destFile b
19:06:18.619 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=2, fromRepositoryId="FileInProgressMarker", toRepositoryId="2", path="69768b5b-8a05-4d5c-ab5f-8227de97f105"] on repo=6defe6e1-4c22-446d-9527-c40a21cd0d16
19:06:18.624 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/1/c'
19:06:18.632 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='fa7bd6f7cbbd49887a23fd0a3dd93eb93493407a' to.sha1='<NoInstanceOf_NormalFileDto>'): /1/c
19:06:18.647 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=3, fromRepositoryId="FileInProgressMarker", toRepositoryId="3", path="69768b5b-8a05-4d5c-ab5f-8227de97f105"] on repo=6defe6e1-4c22-446d-9527-c40a21cd0d16
19:06:18.648 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/1/c'
19:06:18.648 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='fa7bd6f7cbbd49887a23fd0a3dd93eb93493407a' toSha1='null'
19:06:18.649 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/1/c'
19:06:18.649 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/1/c' offset=0
19:06:18.652 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/1/c' offset=0
19:06:18.660 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 327453 bytes in 11 ms. path='/1/c'
19:06:18.663 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_84a516841ba77a5b4648de2cd0dfcb30ea46dbb4_0 into destFile c
19:06:18.684 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=3, fromRepositoryId="FileInProgressMarker", toRepositoryId="3", path="69768b5b-8a05-4d5c-ab5f-8227de97f105"] on repo=6defe6e1-4c22-446d-9527-c40a21cd0d16
19:06:18.688 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/2/1/a'
19:06:18.700 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='114a7877e8cd2e9f9d866426ff0f64054093165d' to.sha1='<NoInstanceOf_NormalFileDto>'): /2/1/a
19:06:18.716 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=4, fromRepositoryId="FileInProgressMarker", toRepositoryId="4", path="69768b5b-8a05-4d5c-ab5f-8227de97f105"] on repo=6defe6e1-4c22-446d-9527-c40a21cd0d16
19:06:18.717 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/2/1/a'
19:06:18.717 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='114a7877e8cd2e9f9d866426ff0f64054093165d' toSha1='null'
19:06:18.718 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/2/1/a'
19:06:18.718 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/2/1/a' offset=0
19:06:18.718 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/2/1/a' offset=0
19:06:18.724 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 21132 bytes in 6 ms. path='/2/1/a'
19:06:18.730 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_86f7e437faa5a7fce15d1ddcb9eaeaea377667b8_0 into destFile a
19:06:18.741 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=4, fromRepositoryId="FileInProgressMarker", toRepositoryId="4", path="69768b5b-8a05-4d5c-ab5f-8227de97f105"] on repo=6defe6e1-4c22-446d-9527-c40a21cd0d16
19:06:18.744 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/2/1/b'
19:06:18.755 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='56109474ff577655a1852716c37f1176fcf83bdd' to.sha1='<NoInstanceOf_NormalFileDto>'): /2/1/b
19:06:18.772 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=5, fromRepositoryId="FileInProgressMarker", toRepositoryId="5", path="69768b5b-8a05-4d5c-ab5f-8227de97f105"] on repo=6defe6e1-4c22-446d-9527-c40a21cd0d16
19:06:18.773 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/2/1/b'
19:06:18.774 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='56109474ff577655a1852716c37f1176fcf83bdd' toSha1='null'
19:06:18.774 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/2/1/b'
19:06:18.774 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/2/1/b' offset=0
19:06:18.776 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/2/1/b' offset=0
19:06:18.785 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 150257 bytes in 10 ms. path='/2/1/b'
19:06:18.790 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_e9d71f5ee7c92d6dc9e92ffdad17b8bd49418f98_0 into destFile b
19:06:18.806 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=5, fromRepositoryId="FileInProgressMarker", toRepositoryId="5", path="69768b5b-8a05-4d5c-ab5f-8227de97f105"] on repo=6defe6e1-4c22-446d-9527-c40a21cd0d16
19:06:18.810 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/2/a'
19:06:18.820 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='18b3549a8d726d012de6fb79b9fa38cc6a71f757' to.sha1='<NoInstanceOf_NormalFileDto>'): /2/a
19:06:18.834 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=6, fromRepositoryId="FileInProgressMarker", toRepositoryId="6", path="69768b5b-8a05-4d5c-ab5f-8227de97f105"] on repo=6defe6e1-4c22-446d-9527-c40a21cd0d16
19:06:18.835 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/2/a'
19:06:18.835 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='18b3549a8d726d012de6fb79b9fa38cc6a71f757' toSha1='null'
19:06:18.835 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/2/a'
19:06:18.836 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/2/a' offset=0
19:06:18.838 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/2/a' offset=0
19:06:18.845 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 174307 bytes in 9 ms. path='/2/a'
19:06:18.851 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_86f7e437faa5a7fce15d1ddcb9eaeaea377667b8_0 into destFile a
19:06:18.868 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=6, fromRepositoryId="FileInProgressMarker", toRepositoryId="6", path="69768b5b-8a05-4d5c-ab5f-8227de97f105"] on repo=6defe6e1-4c22-446d-9527-c40a21cd0d16
19:06:18.871 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/3/a'
19:06:18.880 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='98b38e965347dbd5c45506feb11ffde37136e133' to.sha1='<NoInstanceOf_NormalFileDto>'): /3/a
19:06:18.896 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=7, fromRepositoryId="FileInProgressMarker", toRepositoryId="7", path="69768b5b-8a05-4d5c-ab5f-8227de97f105"] on repo=6defe6e1-4c22-446d-9527-c40a21cd0d16
19:06:18.897 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/3/a'
19:06:18.897 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='98b38e965347dbd5c45506feb11ffde37136e133' toSha1='null'
19:06:18.897 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/3/a'
19:06:18.897 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/3/a' offset=0
19:06:18.900 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/3/a' offset=0
19:06:18.909 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 240108 bytes in 12 ms. path='/3/a'
19:06:18.914 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_86f7e437faa5a7fce15d1ddcb9eaeaea377667b8_0 into destFile a
19:06:18.933 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=7, fromRepositoryId="FileInProgressMarker", toRepositoryId="7", path="69768b5b-8a05-4d5c-ab5f-8227de97f105"] on repo=6defe6e1-4c22-446d-9527-c40a21cd0d16
19:06:18.937 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/3/b'
19:06:18.946 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='9d2632a0a73cbeb6a2c65c648d62cb375997b541' to.sha1='<NoInstanceOf_NormalFileDto>'): /3/b
19:06:18.960 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=8, fromRepositoryId="FileInProgressMarker", toRepositoryId="8", path="69768b5b-8a05-4d5c-ab5f-8227de97f105"] on repo=6defe6e1-4c22-446d-9527-c40a21cd0d16
19:06:18.961 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/3/b'
19:06:18.961 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='9d2632a0a73cbeb6a2c65c648d62cb375997b541' toSha1='null'
19:06:18.961 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/3/b'
19:06:18.961 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/3/b' offset=0
19:06:18.963 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/3/b' offset=0
19:06:18.970 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 112308 bytes in 9 ms. path='/3/b'
19:06:18.974 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_e9d71f5ee7c92d6dc9e92ffdad17b8bd49418f98_0 into destFile b
19:06:18.992 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=8, fromRepositoryId="FileInProgressMarker", toRepositoryId="8", path="69768b5b-8a05-4d5c-ab5f-8227de97f105"] on repo=6defe6e1-4c22-446d-9527-c40a21cd0d16
19:06:18.997 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/3/c'
19:06:19.004 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='190e97fe72c4f928354ce46f5c30c4782bf9e4df' to.sha1='<NoInstanceOf_NormalFileDto>'): /3/c
19:06:19.015 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=9, fromRepositoryId="FileInProgressMarker", toRepositoryId="9", path="69768b5b-8a05-4d5c-ab5f-8227de97f105"] on repo=6defe6e1-4c22-446d-9527-c40a21cd0d16
19:06:19.016 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/3/c'
19:06:19.016 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='190e97fe72c4f928354ce46f5c30c4782bf9e4df' toSha1='null'
19:06:19.016 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/3/c'
19:06:19.016 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/3/c' offset=0
19:06:19.021 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/3/c' offset=0
19:06:19.030 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 388960 bytes in 14 ms. path='/3/c'
19:06:19.034 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_84a516841ba77a5b4648de2cd0dfcb30ea46dbb4_0 into destFile c
19:06:19.057 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=9, fromRepositoryId="FileInProgressMarker", toRepositoryId="9", path="69768b5b-8a05-4d5c-ab5f-8227de97f105"] on repo=6defe6e1-4c22-446d-9527-c40a21cd0d16
19:06:19.060 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/3/d'
19:06:19.070 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='8a2988a172b7e6fd99b9850a57e183192f17f429' to.sha1='<NoInstanceOf_NormalFileDto>'): /3/d
19:06:19.082 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=10, fromRepositoryId="FileInProgressMarker", toRepositoryId="10", path="69768b5b-8a05-4d5c-ab5f-8227de97f105"] on repo=6defe6e1-4c22-446d-9527-c40a21cd0d16
19:06:19.084 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/3/d'
19:06:19.084 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='8a2988a172b7e6fd99b9850a57e183192f17f429' toSha1='null'
19:06:19.084 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/3/d'
19:06:19.084 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/3/d' offset=0
19:06:19.085 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/3/d' offset=0
19:06:19.092 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 11439 bytes in 8 ms. path='/3/d'
19:06:19.095 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_3c363836cf4e16666669a25da280a1865c2d2874_0 into destFile d
19:06:19.104 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=10, fromRepositoryId="FileInProgressMarker", toRepositoryId="10", path="69768b5b-8a05-4d5c-ab5f-8227de97f105"] on repo=6defe6e1-4c22-446d-9527-c40a21cd0d16
19:06:19.108 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 49.27%
19:06:19.124 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=69768b5b-8a05-4d5c-ab5f-8227de97f105 remoteRepositoryId=6defe6e1-4c22-446d-9527-c40a21cd0d16 localRepositoryRevisionSynced=2
19:06:19.144 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=6defe6e1-4c22-446d-9527-c40a21cd0d16 remoteRepositoryId=69768b5b-8a05-4d5c-ab5f-8227de97f105 transaction.localRevision=57 remoteFromLocalRevision=2
19:06:19.155 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncUp: fromID=6defe6e1-4c22-446d-9527-c40a21cd0d16 from='target/repo/k98qdwgy-tq3hd-local' toID=69768b5b-8a05-4d5c-ab5f-8227de97f105 to='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.local/target/repo/k98qdwgz-tymhk-remote'
19:06:19.174 [main] INFO c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=6defe6e1-4c22-446d-9527-c40a21cd0d16 remoteRepositoryId=69768b5b-8a05-4d5c-ab5f-8227de97f105 localRepositoryRevisionSynced=-1 localRepositoryRevisionInProgress=57
19:06:19.185 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 63.43%
19:06:19.185 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 74.63%
19:06:19.196 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=6defe6e1-4c22-446d-9527-c40a21cd0d16 remoteRepositoryId=69768b5b-8a05-4d5c-ab5f-8227de97f105 localRepositoryRevisionSynced=57
19:06:19.204 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=69768b5b-8a05-4d5c-ab5f-8227de97f105 remoteRepositoryId=6defe6e1-4c22-446d-9527-c40a21cd0d16 transaction.localRevision=5 remoteFromLocalRevision=57
19:06:19.210 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDown: fromID=69768b5b-8a05-4d5c-ab5f-8227de97f105 from='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.local/target/repo/k98qdwgz-tymhk-remote' toID=6defe6e1-4c22-446d-9527-c40a21cd0d16 to='target/repo/k98qdwgy-tq3hd-local', fromRepoLocalSync=false
19:06:19.226 [main] INFO c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=69768b5b-8a05-4d5c-ab5f-8227de97f105 remoteRepositoryId=6defe6e1-4c22-446d-9527-c40a21cd0d16 localRepositoryRevisionSynced=2 localRepositoryRevisionInProgress=5
19:06:19.229 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 88.31%
19:06:19.230 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 99.50%
19:06:19.235 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=69768b5b-8a05-4d5c-ab5f-8227de97f105 remoteRepositoryId=6defe6e1-4c22-446d-9527-c40a21cd0d16 localRepositoryRevisionSynced=5
19:06:19.244 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=6defe6e1-4c22-446d-9527-c40a21cd0d16 remoteRepositoryId=69768b5b-8a05-4d5c-ab5f-8227de97f105 transaction.localRevision=60 remoteFromLocalRevision=5
19:06:19.246 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 100.00%
19:06:19.259 [main] INFO c.c.c.l.LocalRepoManagerImpl - [5180743a]getCloseDeferredMillis: closeDeferredMillis=20000
19:06:19.259 [main] INFO c.c.c.l.LocalRepoManagerImpl - [5180743a]close: Deferring shut down of real LocalRepoManager 20000 ms.
19:06:19.260 [main] INFO c.c.c.l.LocalRepoManagerImpl - [314f6bf0]getCloseDeferredMillis: closeDeferredMillis=20000
19:06:19.260 [main] INFO c.c.c.l.LocalRepoManagerImpl - [314f6bf0]close: Deferring shut down of real LocalRepoManager 20000 ms.
19:06:19.292 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 1.00%
19:06:19.293 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 50.00%
19:06:19.294 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 58.17%
19:06:19.298 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 63.61%
19:06:19.301 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 74.50%
19:06:19.348 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 80.62%
19:06:19.354 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 90.83%
19:06:19.358 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 95.94%
19:06:19.411 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 100.00%
19:06:19.432 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 0.50%
19:06:19.432 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDown: fromID=69768b5b-8a05-4d5c-ab5f-8227de97f105 from='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.local/target/repo/k98qdwgz-tymhk-remote' toID=6defe6e1-4c22-446d-9527-c40a21cd0d16 to='target/repo/k98qdwgy-tq3hd-local', fromRepoLocalSync=true
19:06:19.433 [pool-36-thread-1] INFO c.c.c.c.r.s.RepoToRepoSync - sync: locally syncing 6defe6e1-4c22-446d-9527-c40a21cd0d16 ('target/repo/k98qdwgy-tq3hd-local')
19:06:19.438 [pool-36-thread-1] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 12.94%
19:06:19.450 [pool-36-thread-1] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 19.03%
19:06:19.465 [pool-36-thread-1] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 24.11%
19:06:19.477 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 1.00%
19:06:19.478 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 50.00%
19:06:19.479 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 58.17%
19:06:19.482 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 63.61%
19:06:19.486 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 74.50%
19:06:19.492 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 80.62%
19:06:19.495 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 90.83%
19:06:19.499 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 95.94%
19:06:19.505 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 100.00%
19:06:19.515 [main] INFO c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=69768b5b-8a05-4d5c-ab5f-8227de97f105 remoteRepositoryId=6defe6e1-4c22-446d-9527-c40a21cd0d16 localRepositoryRevisionSynced=5 localRepositoryRevisionInProgress=8
19:06:19.534 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 29.23%
19:06:19.535 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path='/2/1'
19:06:19.549 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 34.83%
19:06:19.550 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncModifications: Deleting '/2/1/a'
19:06:19.586 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 40.42%
19:06:19.586 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 46.02%
19:06:19.604 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=69768b5b-8a05-4d5c-ab5f-8227de97f105 remoteRepositoryId=6defe6e1-4c22-446d-9527-c40a21cd0d16 localRepositoryRevisionSynced=8
19:06:19.614 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=6defe6e1-4c22-446d-9527-c40a21cd0d16 remoteRepositoryId=69768b5b-8a05-4d5c-ab5f-8227de97f105 transaction.localRevision=63 remoteFromLocalRevision=8
19:06:19.621 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncUp: fromID=6defe6e1-4c22-446d-9527-c40a21cd0d16 from='target/repo/k98qdwgy-tq3hd-local' toID=69768b5b-8a05-4d5c-ab5f-8227de97f105 to='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.local/target/repo/k98qdwgz-tymhk-remote'
19:06:19.632 [main] INFO c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=6defe6e1-4c22-446d-9527-c40a21cd0d16 remoteRepositoryId=69768b5b-8a05-4d5c-ab5f-8227de97f105 localRepositoryRevisionSynced=57 localRepositoryRevisionInProgress=63
19:06:19.642 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 52.24%
19:06:19.642 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 59.70%
19:06:19.642 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncModifications: Deleting '/2/1/a'
19:06:19.647 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 67.16%
19:06:19.647 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 74.63%
19:06:19.664 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=6defe6e1-4c22-446d-9527-c40a21cd0d16 remoteRepositoryId=69768b5b-8a05-4d5c-ab5f-8227de97f105 localRepositoryRevisionSynced=63
19:06:19.668 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=69768b5b-8a05-4d5c-ab5f-8227de97f105 remoteRepositoryId=6defe6e1-4c22-446d-9527-c40a21cd0d16 transaction.localRevision=11 remoteFromLocalRevision=63
19:06:19.676 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDown: fromID=69768b5b-8a05-4d5c-ab5f-8227de97f105 from='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.local/target/repo/k98qdwgz-tymhk-remote' toID=6defe6e1-4c22-446d-9527-c40a21cd0d16 to='target/repo/k98qdwgy-tq3hd-local', fromRepoLocalSync=false
19:06:19.689 [main] INFO c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=69768b5b-8a05-4d5c-ab5f-8227de97f105 remoteRepositoryId=6defe6e1-4c22-446d-9527-c40a21cd0d16 localRepositoryRevisionSynced=8 localRepositoryRevisionInProgress=11
19:06:19.691 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 88.31%
19:06:19.691 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 99.50%
19:06:19.695 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=69768b5b-8a05-4d5c-ab5f-8227de97f105 remoteRepositoryId=6defe6e1-4c22-446d-9527-c40a21cd0d16 localRepositoryRevisionSynced=11
19:06:19.702 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=6defe6e1-4c22-446d-9527-c40a21cd0d16 remoteRepositoryId=69768b5b-8a05-4d5c-ab5f-8227de97f105 transaction.localRevision=66 remoteFromLocalRevision=11
19:06:19.703 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 100.00%
19:06:19.703 [main] INFO c.c.c.l.LocalRepoManagerImpl - [5180743a]close: Deferring shut down of real LocalRepoManager 20000 ms.
19:06:19.718 [main] INFO c.c.c.l.LocalRepoManagerImpl - [314f6bf0]close: Deferring shut down of real LocalRepoManager 20000 ms.
19:06:19.750 [main] INFO c.c.c.l.LocalRepoManagerImpl - [7af47606]<init>: localRoot='/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.local/target/repo/k98qe38l-8hg15-local'
19:06:19.756 [main] INFO c.c.c.l.LocalRepoManagerImpl - [7af47606]initLockFile: Repository '/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.local/target/repo/k98qe38l-8hg15-local' locked successfully.
19:06:20.033 [closeDeferredTimer-4cd54017-2] INFO c.c.c.l.LocalRepoManagerImpl - [4cd54017]_close: Shutting down real LocalRepoManager.
19:06:20.337 [closeDeferredTimer-288b8b5b-2] INFO c.c.c.l.LocalRepoManagerImpl - [288b8b5b]_close: Shutting down real LocalRepoManager.
19:06:23.845 [main] INFO c.c.c.l.LocalRepoManagerImpl - [7af47606]initPersistenceManagerFactory: repositoryId=e3397d66-014d-4402-a156-54cdd5a1f70d
19:06:23.847 [main] INFO c.c.c.l.LocalRepoManagerImpl - [7af47606]initPersistenceManagerFactory: Started up PersistenceManagerFactory successfully in 1564 ms.
19:06:23.865 [main] INFO c.c.c.l.LocalRepoManagerImpl - [6bb95d60]<init>: localRoot='/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.local/target/repo/k98qe38l-529kv-remote'
19:06:23.871 [main] INFO c.c.c.l.LocalRepoManagerImpl - [6bb95d60]initLockFile: Repository '/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.local/target/repo/k98qe38l-529kv-remote' locked successfully.
19:06:27.773 [main] INFO c.c.c.l.LocalRepoManagerImpl - [6bb95d60]initPersistenceManagerFactory: repositoryId=f3c0a97a-67da-4fd8-b0ae-52a65d69e82d
19:06:27.786 [main] INFO c.c.c.l.LocalRepoManagerImpl - [6bb95d60]initPersistenceManagerFactory: Started up PersistenceManagerFactory successfully in 2447 ms.
19:06:27.879 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 1.00%
19:06:27.885 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 50.00%
19:06:27.896 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 58.00%
19:06:27.943 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 63.61%
19:06:27.961 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 74.34%
19:06:27.993 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 79.58%
19:06:28.008 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 90.67%
19:06:28.032 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 95.91%
19:06:28.061 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 100.00%
19:06:28.088 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - local repo: e3397d66-014d-4402-a156-54cdd5a1f70d
19:06:28.088 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - remote repo: f3c0a97a-67da-4fd8-b0ae-52a65d69e82d
19:06:28.093 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 0.50%
19:06:28.093 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDown: fromID=f3c0a97a-67da-4fd8-b0ae-52a65d69e82d from='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.local/target/repo/k98qe38l-529kv-remote/2' toID=e3397d66-014d-4402-a156-54cdd5a1f70d to='target/repo/k98qe38l-8hg15-local', fromRepoLocalSync=true
19:06:28.094 [pool-37-thread-1] INFO c.c.c.c.r.s.RepoToRepoSync - sync: locally syncing e3397d66-014d-4402-a156-54cdd5a1f70d ('target/repo/k98qe38l-8hg15-local')
19:06:28.118 [pool-37-thread-1] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 12.94%
19:06:28.124 [pool-37-thread-1] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 25.12%
19:06:28.125 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 1.00%
19:06:28.126 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 50.00%
19:06:28.127 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 58.17%
19:06:28.132 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 63.61%
19:06:28.137 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 74.50%
19:06:28.145 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 80.62%
19:06:28.150 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 90.83%
19:06:28.156 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 95.94%
19:06:28.164 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 100.00%
19:06:28.199 [main] INFO c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=f3c0a97a-67da-4fd8-b0ae-52a65d69e82d remoteRepositoryId=e3397d66-014d-4402-a156-54cdd5a1f70d localRepositoryRevisionSynced=-1 localRepositoryRevisionInProgress=2
19:06:28.249 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path=''
19:06:28.274 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path='/1'
19:06:28.287 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 31.31%
19:06:28.298 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 36.58%
19:06:28.299 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 41.85%
19:06:28.299 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/1/a'
19:06:28.307 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='5226cfa4ceeb407e97658ce63494877c6c80e90f' to.sha1='<NoInstanceOf_NormalFileDto>'): /1/a
19:06:28.341 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=1, fromRepositoryId="FileInProgressMarker", toRepositoryId="1", path="f3c0a97a-67da-4fd8-b0ae-52a65d69e82d"] on repo=e3397d66-014d-4402-a156-54cdd5a1f70d
19:06:28.343 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/1/a'
19:06:28.343 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='5226cfa4ceeb407e97658ce63494877c6c80e90f' toSha1='null'
19:06:28.343 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/1/a'
19:06:28.343 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/1/a' offset=0
19:06:28.345 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/1/a' offset=0
19:06:28.352 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 138168 bytes in 9 ms. path='/1/a'
19:06:28.355 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_86f7e437faa5a7fce15d1ddcb9eaeaea377667b8_0 into destFile a
19:06:28.388 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=1, fromRepositoryId="FileInProgressMarker", toRepositoryId="1", path="f3c0a97a-67da-4fd8-b0ae-52a65d69e82d"] on repo=e3397d66-014d-4402-a156-54cdd5a1f70d
19:06:28.393 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/1/b'
19:06:28.403 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='bb2dd23d26f8eade8afda128b8b89f7198e80714' to.sha1='<NoInstanceOf_NormalFileDto>'): /1/b
19:06:28.415 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=2, fromRepositoryId="FileInProgressMarker", toRepositoryId="2", path="f3c0a97a-67da-4fd8-b0ae-52a65d69e82d"] on repo=e3397d66-014d-4402-a156-54cdd5a1f70d
19:06:28.416 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/1/b'
19:06:28.416 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='bb2dd23d26f8eade8afda128b8b89f7198e80714' toSha1='null'
19:06:28.416 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/1/b'
19:06:28.416 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/1/b' offset=0
19:06:28.421 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/1/b' offset=0
19:06:28.431 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 405834 bytes in 15 ms. path='/1/b'
19:06:28.435 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_e9d71f5ee7c92d6dc9e92ffdad17b8bd49418f98_0 into destFile b
19:06:28.459 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=2, fromRepositoryId="FileInProgressMarker", toRepositoryId="2", path="f3c0a97a-67da-4fd8-b0ae-52a65d69e82d"] on repo=e3397d66-014d-4402-a156-54cdd5a1f70d
19:06:28.462 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/a'
19:06:28.468 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='6d861b8b276a6c5bc8e748ac469d2d17bdd62363' to.sha1='<NoInstanceOf_NormalFileDto>'): /a
19:06:28.480 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=3, fromRepositoryId="FileInProgressMarker", toRepositoryId="3", path="f3c0a97a-67da-4fd8-b0ae-52a65d69e82d"] on repo=e3397d66-014d-4402-a156-54cdd5a1f70d
19:06:28.483 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/a'
19:06:28.483 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='6d861b8b276a6c5bc8e748ac469d2d17bdd62363' toSha1='null'
19:06:28.483 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/a'
19:06:28.483 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/a' offset=0
19:06:28.485 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/a' offset=0
19:06:28.491 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 195269 bytes in 8 ms. path='/a'
19:06:28.495 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_86f7e437faa5a7fce15d1ddcb9eaeaea377667b8_0 into destFile a
19:06:28.510 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=3, fromRepositoryId="FileInProgressMarker", toRepositoryId="3", path="f3c0a97a-67da-4fd8-b0ae-52a65d69e82d"] on repo=e3397d66-014d-4402-a156-54cdd5a1f70d
19:06:28.513 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 48.43%
19:06:28.531 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=f3c0a97a-67da-4fd8-b0ae-52a65d69e82d remoteRepositoryId=e3397d66-014d-4402-a156-54cdd5a1f70d localRepositoryRevisionSynced=2
19:06:28.544 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=e3397d66-014d-4402-a156-54cdd5a1f70d remoteRepositoryId=f3c0a97a-67da-4fd8-b0ae-52a65d69e82d transaction.localRevision=19 remoteFromLocalRevision=2
19:06:28.550 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncUp: fromID=e3397d66-014d-4402-a156-54cdd5a1f70d from='target/repo/k98qe38l-8hg15-local' toID=f3c0a97a-67da-4fd8-b0ae-52a65d69e82d to='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.local/target/repo/k98qe38l-529kv-remote/2'
19:06:28.568 [main] INFO c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=e3397d66-014d-4402-a156-54cdd5a1f70d remoteRepositoryId=f3c0a97a-67da-4fd8-b0ae-52a65d69e82d localRepositoryRevisionSynced=-1 localRepositoryRevisionInProgress=19
19:06:28.577 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 63.43%
19:06:28.577 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 74.63%
19:06:28.588 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=e3397d66-014d-4402-a156-54cdd5a1f70d remoteRepositoryId=f3c0a97a-67da-4fd8-b0ae-52a65d69e82d localRepositoryRevisionSynced=19
19:06:28.596 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=f3c0a97a-67da-4fd8-b0ae-52a65d69e82d remoteRepositoryId=e3397d66-014d-4402-a156-54cdd5a1f70d transaction.localRevision=5 remoteFromLocalRevision=19
19:06:28.602 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDown: fromID=f3c0a97a-67da-4fd8-b0ae-52a65d69e82d from='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.local/target/repo/k98qe38l-529kv-remote/2' toID=e3397d66-014d-4402-a156-54cdd5a1f70d to='target/repo/k98qe38l-8hg15-local', fromRepoLocalSync=false
19:06:28.618 [main] INFO c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=f3c0a97a-67da-4fd8-b0ae-52a65d69e82d remoteRepositoryId=e3397d66-014d-4402-a156-54cdd5a1f70d localRepositoryRevisionSynced=2 localRepositoryRevisionInProgress=5
19:06:28.623 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 88.31%
19:06:28.623 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 99.50%
19:06:28.628 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=f3c0a97a-67da-4fd8-b0ae-52a65d69e82d remoteRepositoryId=e3397d66-014d-4402-a156-54cdd5a1f70d localRepositoryRevisionSynced=5
19:06:28.637 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=e3397d66-014d-4402-a156-54cdd5a1f70d remoteRepositoryId=f3c0a97a-67da-4fd8-b0ae-52a65d69e82d transaction.localRevision=22 remoteFromLocalRevision=5
19:06:28.639 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 100.00%
19:06:28.654 [main] INFO c.c.c.l.LocalRepoManagerImpl - [7af47606]getCloseDeferredMillis: closeDeferredMillis=20000
19:06:28.654 [main] INFO c.c.c.l.LocalRepoManagerImpl - [7af47606]close: Deferring shut down of real LocalRepoManager 20000 ms.
19:06:28.655 [main] INFO c.c.c.l.LocalRepoManagerImpl - [6bb95d60]getCloseDeferredMillis: closeDeferredMillis=20000
19:06:28.655 [main] INFO c.c.c.l.LocalRepoManagerImpl - [6bb95d60]close: Deferring shut down of real LocalRepoManager 20000 ms.
19:06:28.671 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 1.00%
19:06:28.672 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 50.00%
19:06:28.673 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 58.17%
19:06:28.676 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 63.61%
19:06:28.680 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 74.50%
19:06:28.721 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 80.62%
19:06:28.724 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 90.83%
19:06:28.728 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 95.94%
19:06:28.740 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 100.00%
19:06:28.759 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 0.50%
19:06:28.760 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDown: fromID=f3c0a97a-67da-4fd8-b0ae-52a65d69e82d from='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.local/target/repo/k98qe38l-529kv-remote/2' toID=e3397d66-014d-4402-a156-54cdd5a1f70d to='target/repo/k98qe38l-8hg15-local', fromRepoLocalSync=true
19:06:28.760 [pool-38-thread-1] INFO c.c.c.c.r.s.RepoToRepoSync - sync: locally syncing e3397d66-014d-4402-a156-54cdd5a1f70d ('target/repo/k98qe38l-8hg15-local')
19:06:28.766 [pool-38-thread-1] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 12.94%
19:06:28.777 [pool-38-thread-1] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 18.27%
19:06:28.785 [pool-38-thread-1] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 25.12%
19:06:28.797 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 1.00%
19:06:28.799 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 50.00%
19:06:28.803 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 58.17%
19:06:28.806 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 63.61%
19:06:28.815 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 74.50%
19:06:28.820 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 80.62%
19:06:28.823 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 90.83%
19:06:28.827 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 95.94%
19:06:28.834 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 100.00%
19:06:28.845 [main] INFO c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=f3c0a97a-67da-4fd8-b0ae-52a65d69e82d remoteRepositoryId=e3397d66-014d-4402-a156-54cdd5a1f70d localRepositoryRevisionSynced=5 localRepositoryRevisionInProgress=8
19:06:28.864 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 32.34%
19:06:28.865 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path='/1'
19:06:28.878 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncModifications: Deleting '/1/a'
19:06:28.914 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 39.80%
19:06:28.915 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 47.26%
19:06:28.932 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=f3c0a97a-67da-4fd8-b0ae-52a65d69e82d remoteRepositoryId=e3397d66-014d-4402-a156-54cdd5a1f70d localRepositoryRevisionSynced=8
19:06:28.943 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=e3397d66-014d-4402-a156-54cdd5a1f70d remoteRepositoryId=f3c0a97a-67da-4fd8-b0ae-52a65d69e82d transaction.localRevision=25 remoteFromLocalRevision=8
19:06:28.950 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncUp: fromID=e3397d66-014d-4402-a156-54cdd5a1f70d from='target/repo/k98qe38l-8hg15-local' toID=f3c0a97a-67da-4fd8-b0ae-52a65d69e82d to='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.local/target/repo/k98qe38l-529kv-remote/2'
19:06:28.965 [main] INFO c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=e3397d66-014d-4402-a156-54cdd5a1f70d remoteRepositoryId=f3c0a97a-67da-4fd8-b0ae-52a65d69e82d localRepositoryRevisionSynced=19 localRepositoryRevisionInProgress=25
19:06:28.977 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 59.70%
19:06:28.977 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncModifications: Deleting '/1/a'
19:06:28.983 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 67.16%
19:06:28.983 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 74.63%
19:06:28.999 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=e3397d66-014d-4402-a156-54cdd5a1f70d remoteRepositoryId=f3c0a97a-67da-4fd8-b0ae-52a65d69e82d localRepositoryRevisionSynced=25
19:06:29.003 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=f3c0a97a-67da-4fd8-b0ae-52a65d69e82d remoteRepositoryId=e3397d66-014d-4402-a156-54cdd5a1f70d transaction.localRevision=11 remoteFromLocalRevision=25
19:06:29.010 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDown: fromID=f3c0a97a-67da-4fd8-b0ae-52a65d69e82d from='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.local/target/repo/k98qe38l-529kv-remote/2' toID=e3397d66-014d-4402-a156-54cdd5a1f70d to='target/repo/k98qe38l-8hg15-local', fromRepoLocalSync=false
19:06:29.022 [main] INFO c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=f3c0a97a-67da-4fd8-b0ae-52a65d69e82d remoteRepositoryId=e3397d66-014d-4402-a156-54cdd5a1f70d localRepositoryRevisionSynced=8 localRepositoryRevisionInProgress=11
19:06:29.030 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 88.31%
19:06:29.030 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 99.50%
19:06:29.033 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=f3c0a97a-67da-4fd8-b0ae-52a65d69e82d remoteRepositoryId=e3397d66-014d-4402-a156-54cdd5a1f70d localRepositoryRevisionSynced=11
19:06:29.041 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=e3397d66-014d-4402-a156-54cdd5a1f70d remoteRepositoryId=f3c0a97a-67da-4fd8-b0ae-52a65d69e82d transaction.localRevision=28 remoteFromLocalRevision=11
19:06:29.042 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 100.00%
19:06:29.042 [main] INFO c.c.c.l.LocalRepoManagerImpl - [7af47606]close: Deferring shut down of real LocalRepoManager 20000 ms.
19:06:29.061 [main] INFO c.c.c.l.LocalRepoManagerImpl - [6bb95d60]close: Deferring shut down of real LocalRepoManager 20000 ms.
19:06:29.076 [main] INFO c.c.c.l.LocalRepoManagerImpl - [4e907609]<init>: localRoot='/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.local/target/repo/k98qeafn-fntq5-local'
19:06:29.082 [main] INFO c.c.c.l.LocalRepoManagerImpl - [4e907609]initLockFile: Repository '/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.local/target/repo/k98qeafn-fntq5-local' locked successfully.
19:06:30.883 [closeDeferredTimer-35a0ae8e-2] INFO c.c.c.l.LocalRepoManagerImpl - [35a0ae8e]_close: Shutting down real LocalRepoManager.
19:06:31.214 [closeDeferredTimer-10686a98-2] INFO c.c.c.l.LocalRepoManagerImpl - [10686a98]_close: Shutting down real LocalRepoManager.
19:06:34.215 [main] INFO c.c.c.l.LocalRepoManagerImpl - [4e907609]initPersistenceManagerFactory: repositoryId=ed7762bf-473e-4490-a063-ec5e27a72e55
19:06:34.217 [main] INFO c.c.c.l.LocalRepoManagerImpl - [4e907609]initPersistenceManagerFactory: Started up PersistenceManagerFactory successfully in 4145 ms.
19:06:34.240 [main] INFO c.c.c.l.LocalRepoManagerImpl - [3479bc1]<init>: localRoot='/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.local/target/repo/k98qeafn-abba8-remote'
19:06:34.247 [main] INFO c.c.c.l.LocalRepoManagerImpl - [3479bc1]initLockFile: Repository '/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.local/target/repo/k98qeafn-abba8-remote' locked successfully.
19:06:38.573 [main] INFO c.c.c.l.LocalRepoManagerImpl - [3479bc1]initPersistenceManagerFactory: repositoryId=151b8d6b-3544-4582-9542-f3e4c9d5d77e
19:06:38.579 [main] INFO c.c.c.l.LocalRepoManagerImpl - [3479bc1]initPersistenceManagerFactory: Started up PersistenceManagerFactory successfully in 2198 ms.
19:06:38.652 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 1.00%
19:06:38.658 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 50.00%
19:06:38.666 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 58.00%
19:06:38.707 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 63.61%
19:06:38.721 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 74.34%
19:06:38.746 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 79.49%
19:06:38.756 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 90.67%
19:06:38.790 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 95.91%
19:06:38.815 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 100.00%
19:06:38.834 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - local repo: ed7762bf-473e-4490-a063-ec5e27a72e55
19:06:38.834 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - remote repo: 151b8d6b-3544-4582-9542-f3e4c9d5d77e
19:06:38.838 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 0.50%
19:06:38.839 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDown: fromID=151b8d6b-3544-4582-9542-f3e4c9d5d77e from='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.local/target/repo/k98qeafn-abba8-remote' toID=ed7762bf-473e-4490-a063-ec5e27a72e55 to='target/repo/k98qeafn-fntq5-local', fromRepoLocalSync=true
19:06:38.840 [pool-39-thread-1] INFO c.c.c.c.r.s.RepoToRepoSync - sync: locally syncing ed7762bf-473e-4490-a063-ec5e27a72e55 ('target/repo/k98qeafn-fntq5-local')
19:06:38.866 [pool-39-thread-1] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 12.94%
19:06:38.870 [pool-39-thread-1] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 25.12%
19:06:38.873 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 1.00%
19:06:38.875 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 50.00%
19:06:38.878 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 58.17%
19:06:38.885 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 63.61%
19:06:38.888 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 74.50%
19:06:38.893 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 80.62%
19:06:38.896 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 90.83%
19:06:38.899 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 95.94%
19:06:38.904 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 100.00%
19:06:38.931 [main] INFO c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=151b8d6b-3544-4582-9542-f3e4c9d5d77e remoteRepositoryId=ed7762bf-473e-4490-a063-ec5e27a72e55 localRepositoryRevisionSynced=-1 localRepositoryRevisionInProgress=2
19:06:38.963 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path=''
19:06:38.989 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path='/1'
19:06:38.999 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 30.22%
19:06:38.999 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path='/2'
19:06:39.005 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path='/2/1'
19:06:39.014 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path='/3'
19:06:39.028 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 35.46%
19:06:39.028 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 40.70%
19:06:39.028 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/1/a'
19:06:39.035 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='c747017b2c9f2097c5d65dba4e097b7200a8698c' to.sha1='<NoInstanceOf_NormalFileDto>'): /1/a
19:06:39.065 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=1, fromRepositoryId="FileInProgressMarker", toRepositoryId="1", path="151b8d6b-3544-4582-9542-f3e4c9d5d77e"] on repo=ed7762bf-473e-4490-a063-ec5e27a72e55
19:06:39.066 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/1/a'
19:06:39.066 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='c747017b2c9f2097c5d65dba4e097b7200a8698c' toSha1='null'
19:06:39.066 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/1/a'
19:06:39.066 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/1/a' offset=0
19:06:39.069 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/1/a' offset=0
19:06:39.077 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 250415 bytes in 11 ms. path='/1/a'
19:06:39.082 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_86f7e437faa5a7fce15d1ddcb9eaeaea377667b8_0 into destFile a
19:06:39.119 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=1, fromRepositoryId="FileInProgressMarker", toRepositoryId="1", path="151b8d6b-3544-4582-9542-f3e4c9d5d77e"] on repo=ed7762bf-473e-4490-a063-ec5e27a72e55
19:06:39.124 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/1/b'
19:06:39.136 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='9ffa53c8499e2b81328191af431026f48a43b820' to.sha1='<NoInstanceOf_NormalFileDto>'): /1/b
19:06:39.149 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=2, fromRepositoryId="FileInProgressMarker", toRepositoryId="2", path="151b8d6b-3544-4582-9542-f3e4c9d5d77e"] on repo=ed7762bf-473e-4490-a063-ec5e27a72e55
19:06:39.150 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/1/b'
19:06:39.150 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='9ffa53c8499e2b81328191af431026f48a43b820' toSha1='null'
19:06:39.150 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/1/b'
19:06:39.150 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/1/b' offset=0
19:06:39.151 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/1/b' offset=0
19:06:39.156 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 76164 bytes in 6 ms. path='/1/b'
19:06:39.159 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_e9d71f5ee7c92d6dc9e92ffdad17b8bd49418f98_0 into destFile b
19:06:39.178 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=2, fromRepositoryId="FileInProgressMarker", toRepositoryId="2", path="151b8d6b-3544-4582-9542-f3e4c9d5d77e"] on repo=ed7762bf-473e-4490-a063-ec5e27a72e55
19:06:39.185 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/1/c'
19:06:39.192 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='c558662a57df1903b0d6d51e0496082f2e920dab' to.sha1='<NoInstanceOf_NormalFileDto>'): /1/c
19:06:39.222 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=3, fromRepositoryId="FileInProgressMarker", toRepositoryId="3", path="151b8d6b-3544-4582-9542-f3e4c9d5d77e"] on repo=ed7762bf-473e-4490-a063-ec5e27a72e55
19:06:39.228 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/1/c'
19:06:39.228 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='c558662a57df1903b0d6d51e0496082f2e920dab' toSha1='null'
19:06:39.229 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/1/c'
19:06:39.229 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/1/c' offset=0
19:06:39.229 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/1/c' offset=0
19:06:39.233 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 10488 bytes in 4 ms. path='/1/c'
19:06:39.237 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_84a516841ba77a5b4648de2cd0dfcb30ea46dbb4_0 into destFile c
19:06:39.258 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=3, fromRepositoryId="FileInProgressMarker", toRepositoryId="3", path="151b8d6b-3544-4582-9542-f3e4c9d5d77e"] on repo=ed7762bf-473e-4490-a063-ec5e27a72e55
19:06:39.265 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/2/1/a'
19:06:39.272 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='48225827e38d17c075ff912c6669dce225f0d4f4' to.sha1='<NoInstanceOf_NormalFileDto>'): /2/1/a
19:06:39.290 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=4, fromRepositoryId="FileInProgressMarker", toRepositoryId="4", path="151b8d6b-3544-4582-9542-f3e4c9d5d77e"] on repo=ed7762bf-473e-4490-a063-ec5e27a72e55
19:06:39.294 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/2/1/a'
19:06:39.294 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='48225827e38d17c075ff912c6669dce225f0d4f4' toSha1='null'
19:06:39.294 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/2/1/a'
19:06:39.294 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/2/1/a' offset=0
19:06:39.299 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/2/1/a' offset=0
19:06:39.314 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 442720 bytes in 20 ms. path='/2/1/a'
19:06:39.318 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_86f7e437faa5a7fce15d1ddcb9eaeaea377667b8_0 into destFile a
19:06:39.351 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=4, fromRepositoryId="FileInProgressMarker", toRepositoryId="4", path="151b8d6b-3544-4582-9542-f3e4c9d5d77e"] on repo=ed7762bf-473e-4490-a063-ec5e27a72e55
19:06:39.354 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/2/1/b'
19:06:39.361 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='a6d2e2519a31aa6dd2fd37a2b012acf49084706c' to.sha1='<NoInstanceOf_NormalFileDto>'): /2/1/b
19:06:39.380 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=5, fromRepositoryId="FileInProgressMarker", toRepositoryId="5", path="151b8d6b-3544-4582-9542-f3e4c9d5d77e"] on repo=ed7762bf-473e-4490-a063-ec5e27a72e55
19:06:39.442 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/2/1/b'
19:06:39.442 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='a6d2e2519a31aa6dd2fd37a2b012acf49084706c' toSha1='null'
19:06:39.443 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/2/1/b'
19:06:39.443 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/2/1/b' offset=0
19:06:39.445 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/2/1/b' offset=0
19:06:39.452 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 153209 bytes in 9 ms. path='/2/1/b'
19:06:39.456 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_e9d71f5ee7c92d6dc9e92ffdad17b8bd49418f98_0 into destFile b
19:06:39.469 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=5, fromRepositoryId="FileInProgressMarker", toRepositoryId="5", path="151b8d6b-3544-4582-9542-f3e4c9d5d77e"] on repo=ed7762bf-473e-4490-a063-ec5e27a72e55
19:06:39.471 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/2/a'
19:06:39.477 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='1fac7cb4b568f6dab81a26409617c5872114c5e6' to.sha1='<NoInstanceOf_NormalFileDto>'): /2/a
19:06:39.486 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=6, fromRepositoryId="FileInProgressMarker", toRepositoryId="6", path="151b8d6b-3544-4582-9542-f3e4c9d5d77e"] on repo=ed7762bf-473e-4490-a063-ec5e27a72e55
19:06:39.488 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/2/a'
19:06:39.488 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='1fac7cb4b568f6dab81a26409617c5872114c5e6' toSha1='null'
19:06:39.488 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/2/a'
19:06:39.489 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/2/a' offset=0
19:06:39.490 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/2/a' offset=0
19:06:39.495 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 142933 bytes in 7 ms. path='/2/a'
19:06:39.500 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_86f7e437faa5a7fce15d1ddcb9eaeaea377667b8_0 into destFile a
19:06:39.513 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=6, fromRepositoryId="FileInProgressMarker", toRepositoryId="6", path="151b8d6b-3544-4582-9542-f3e4c9d5d77e"] on repo=ed7762bf-473e-4490-a063-ec5e27a72e55
19:06:39.515 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/3/a'
19:06:39.522 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='4682970d882be262ec3ac5a0ddb6951742d04421' to.sha1='<NoInstanceOf_NormalFileDto>'): /3/a
19:06:39.532 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=7, fromRepositoryId="FileInProgressMarker", toRepositoryId="7", path="151b8d6b-3544-4582-9542-f3e4c9d5d77e"] on repo=ed7762bf-473e-4490-a063-ec5e27a72e55
19:06:39.534 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/3/a'
19:06:39.534 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='4682970d882be262ec3ac5a0ddb6951742d04421' toSha1='null'
19:06:39.534 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/3/a'
19:06:39.534 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/3/a' offset=0
19:06:39.537 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/3/a' offset=0
19:06:39.544 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 213992 bytes in 10 ms. path='/3/a'
19:06:39.548 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_86f7e437faa5a7fce15d1ddcb9eaeaea377667b8_0 into destFile a
19:06:39.564 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=7, fromRepositoryId="FileInProgressMarker", toRepositoryId="7", path="151b8d6b-3544-4582-9542-f3e4c9d5d77e"] on repo=ed7762bf-473e-4490-a063-ec5e27a72e55
19:06:39.567 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/3/b'
19:06:39.577 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='f04ef0f753bc93836b82c48d5c0f73cfdf8fd9d2' to.sha1='<NoInstanceOf_NormalFileDto>'): /3/b
19:06:39.590 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=8, fromRepositoryId="FileInProgressMarker", toRepositoryId="8", path="151b8d6b-3544-4582-9542-f3e4c9d5d77e"] on repo=ed7762bf-473e-4490-a063-ec5e27a72e55
19:06:39.592 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/3/b'
19:06:39.592 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='f04ef0f753bc93836b82c48d5c0f73cfdf8fd9d2' toSha1='null'
19:06:39.592 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/3/b'
19:06:39.592 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/3/b' offset=0
19:06:39.599 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/3/b' offset=0
19:06:39.614 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 545148 bytes in 22 ms. path='/3/b'
19:06:39.620 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_e9d71f5ee7c92d6dc9e92ffdad17b8bd49418f98_0 into destFile b
19:06:39.656 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=8, fromRepositoryId="FileInProgressMarker", toRepositoryId="8", path="151b8d6b-3544-4582-9542-f3e4c9d5d77e"] on repo=ed7762bf-473e-4490-a063-ec5e27a72e55
19:06:39.665 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/3/c'
19:06:39.674 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='375b42d20a376f3ba14f5c1303fa0ffe76e5a749' to.sha1='<NoInstanceOf_NormalFileDto>'): /3/c
19:06:39.702 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=9, fromRepositoryId="FileInProgressMarker", toRepositoryId="9", path="151b8d6b-3544-4582-9542-f3e4c9d5d77e"] on repo=ed7762bf-473e-4490-a063-ec5e27a72e55
19:06:39.703 [closeDeferredTimer-5180743a-2] INFO c.c.c.l.LocalRepoManagerImpl - [5180743a]_close: Shutting down real LocalRepoManager.
19:06:40.054 [closeDeferredTimer-314f6bf0-2] INFO c.c.c.l.LocalRepoManagerImpl - [314f6bf0]_close: Shutting down real LocalRepoManager.
19:06:40.056 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/3/c'
19:06:40.058 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='375b42d20a376f3ba14f5c1303fa0ffe76e5a749' toSha1='null'
19:06:40.058 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/3/c'
19:06:40.059 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/3/c' offset=0
19:06:40.380 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/3/c' offset=0
19:06:40.404 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 626827 bytes in 345 ms. path='/3/c'
19:06:40.409 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_84a516841ba77a5b4648de2cd0dfcb30ea46dbb4_0 into destFile c
19:06:40.471 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=9, fromRepositoryId="FileInProgressMarker", toRepositoryId="9", path="151b8d6b-3544-4582-9542-f3e4c9d5d77e"] on repo=ed7762bf-473e-4490-a063-ec5e27a72e55
19:06:40.480 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/3/d'
19:06:40.490 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='25765766dabd6044e408f69887614764c3eef5fa' to.sha1='<NoInstanceOf_NormalFileDto>'): /3/d
19:06:40.508 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=10, fromRepositoryId="FileInProgressMarker", toRepositoryId="10", path="151b8d6b-3544-4582-9542-f3e4c9d5d77e"] on repo=ed7762bf-473e-4490-a063-ec5e27a72e55
19:06:40.513 [main] DEBUG c.c.c.c.r.s.RepoToRepoSync - Comparing 1 FileChunkDtos. path='/3/d'
19:06:40.513 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Enlisting dirty FileChunkDto. index=0 fromOffset=0 toOffset=null fromSha1='25765766dabd6044e408f69887614764c3eef5fa' toSha1='null'
19:06:40.513 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/3/d'
19:06:40.513 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Reading data for dirty FileChunkDto (index 0 of 1). path='/3/d' offset=0
19:06:40.514 [main] TRACE c.c.c.c.r.s.RepoToRepoSync - Writing data for dirty FileChunkDto (1 of 1). path='/3/d' offset=0
19:06:40.534 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 26432 bytes in 21 ms. path='/3/d'
19:06:40.538 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_3c363836cf4e16666669a25da280a1865c2d2874_0 into destFile d
19:06:40.570 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=10, fromRepositoryId="FileInProgressMarker", toRepositoryId="10", path="151b8d6b-3544-4582-9542-f3e4c9d5d77e"] on repo=ed7762bf-473e-4490-a063-ec5e27a72e55
19:06:40.579 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 49.27%
19:06:40.599 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=151b8d6b-3544-4582-9542-f3e4c9d5d77e remoteRepositoryId=ed7762bf-473e-4490-a063-ec5e27a72e55 localRepositoryRevisionSynced=2
19:06:40.676 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=ed7762bf-473e-4490-a063-ec5e27a72e55 remoteRepositoryId=151b8d6b-3544-4582-9542-f3e4c9d5d77e transaction.localRevision=57 remoteFromLocalRevision=2
19:06:40.694 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncUp: fromID=ed7762bf-473e-4490-a063-ec5e27a72e55 from='target/repo/k98qeafn-fntq5-local' toID=151b8d6b-3544-4582-9542-f3e4c9d5d77e to='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.local/target/repo/k98qeafn-abba8-remote'
19:06:40.804 [main] INFO c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=ed7762bf-473e-4490-a063-ec5e27a72e55 remoteRepositoryId=151b8d6b-3544-4582-9542-f3e4c9d5d77e localRepositoryRevisionSynced=-1 localRepositoryRevisionInProgress=57
19:06:40.820 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 63.43%
19:06:40.821 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 74.63%
19:06:40.834 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=ed7762bf-473e-4490-a063-ec5e27a72e55 remoteRepositoryId=151b8d6b-3544-4582-9542-f3e4c9d5d77e localRepositoryRevisionSynced=57
19:06:40.900 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=151b8d6b-3544-4582-9542-f3e4c9d5d77e remoteRepositoryId=ed7762bf-473e-4490-a063-ec5e27a72e55 transaction.localRevision=5 remoteFromLocalRevision=57
19:06:40.907 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDown: fromID=151b8d6b-3544-4582-9542-f3e4c9d5d77e from='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.local/target/repo/k98qeafn-abba8-remote' toID=ed7762bf-473e-4490-a063-ec5e27a72e55 to='target/repo/k98qeafn-fntq5-local', fromRepoLocalSync=false
19:06:40.929 [main] INFO c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=151b8d6b-3544-4582-9542-f3e4c9d5d77e remoteRepositoryId=ed7762bf-473e-4490-a063-ec5e27a72e55 localRepositoryRevisionSynced=2 localRepositoryRevisionInProgress=5
19:06:40.931 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 88.31%
19:06:40.931 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 99.50%
19:06:40.935 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=151b8d6b-3544-4582-9542-f3e4c9d5d77e remoteRepositoryId=ed7762bf-473e-4490-a063-ec5e27a72e55 localRepositoryRevisionSynced=5
19:06:40.943 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=ed7762bf-473e-4490-a063-ec5e27a72e55 remoteRepositoryId=151b8d6b-3544-4582-9542-f3e4c9d5d77e transaction.localRevision=60 remoteFromLocalRevision=5
19:06:40.945 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 100.00%
19:06:40.963 [main] INFO c.c.c.l.LocalRepoManagerImpl - [4e907609]getCloseDeferredMillis: closeDeferredMillis=20000
19:06:40.963 [main] INFO c.c.c.l.LocalRepoManagerImpl - [4e907609]close: Deferring shut down of real LocalRepoManager 20000 ms.
19:06:40.963 [main] INFO c.c.c.l.LocalRepoManagerImpl - [3479bc1]getCloseDeferredMillis: closeDeferredMillis=20000
19:06:40.963 [main] INFO c.c.c.l.LocalRepoManagerImpl - [3479bc1]close: Deferring shut down of real LocalRepoManager 20000 ms.
19:06:41.018 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 1.00%
19:06:41.020 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 50.00%
19:06:41.022 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 62.25%
19:06:41.026 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 68.38%
19:06:41.029 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 74.50%
19:06:41.032 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 86.75%
19:06:41.036 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 92.88%
19:06:41.044 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 99.00%
19:06:41.215 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Local sync...: 100.00%
19:06:41.235 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 0.50%
19:06:41.236 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDown: fromID=151b8d6b-3544-4582-9542-f3e4c9d5d77e from='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.local/target/repo/k98qeafn-abba8-remote' toID=ed7762bf-473e-4490-a063-ec5e27a72e55 to='target/repo/k98qeafn-fntq5-local', fromRepoLocalSync=true
19:06:41.237 [pool-40-thread-1] INFO c.c.c.c.r.s.RepoToRepoSync - sync: locally syncing ed7762bf-473e-4490-a063-ec5e27a72e55 ('target/repo/k98qeafn-fntq5-local')
19:06:41.243 [pool-40-thread-1] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 12.94%
19:06:41.264 [pool-40-thread-1] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 19.03%
19:06:41.280 [pool-40-thread-1] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 24.11%
19:06:41.294 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 1.00%
19:06:41.297 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 50.00%
19:06:41.302 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 62.25%
19:06:41.308 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 68.38%
19:06:41.310 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 74.50%
19:06:41.313 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 86.75%
19:06:41.317 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 92.88%
19:06:41.320 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 99.00%
19:06:41.324 [main] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 100.00%
19:06:41.352 [main] INFO c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=151b8d6b-3544-4582-9542-f3e4c9d5d77e remoteRepositoryId=ed7762bf-473e-4490-a063-ec5e27a72e55 localRepositoryRevisionSynced=5 localRepositoryRevisionInProgress=8
19:06:41.370 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 31.09%
19:06:41.371 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path=''
19:06:41.449 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncModifications: Deleting '/2'
19:06:41.557 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 38.56%
19:06:41.558 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 46.02%
19:06:41.584 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=151b8d6b-3544-4582-9542-f3e4c9d5d77e remoteRepositoryId=ed7762bf-473e-4490-a063-ec5e27a72e55 localRepositoryRevisionSynced=8
19:06:41.632 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=ed7762bf-473e-4490-a063-ec5e27a72e55 remoteRepositoryId=151b8d6b-3544-4582-9542-f3e4c9d5d77e transaction.localRevision=63 remoteFromLocalRevision=8
19:06:41.654 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncUp: fromID=ed7762bf-473e-4490-a063-ec5e27a72e55 from='target/repo/k98qeafn-fntq5-local' toID=151b8d6b-3544-4582-9542-f3e4c9d5d77e to='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.local/target/repo/k98qeafn-abba8-remote'
19:06:41.667 [main] INFO c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=ed7762bf-473e-4490-a063-ec5e27a72e55 remoteRepositoryId=151b8d6b-3544-4582-9542-f3e4c9d5d77e localRepositoryRevisionSynced=57 localRepositoryRevisionInProgress=63
19:06:41.678 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 52.24%
19:06:41.679 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 59.70%
19:06:41.680 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncModifications: Deleting '/2'
19:06:41.683 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 67.16%
19:06:41.683 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 74.63%
19:06:41.698 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=ed7762bf-473e-4490-a063-ec5e27a72e55 remoteRepositoryId=151b8d6b-3544-4582-9542-f3e4c9d5d77e localRepositoryRevisionSynced=63
19:06:41.713 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=151b8d6b-3544-4582-9542-f3e4c9d5d77e remoteRepositoryId=ed7762bf-473e-4490-a063-ec5e27a72e55 transaction.localRevision=11 remoteFromLocalRevision=63
19:06:41.726 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDown: fromID=151b8d6b-3544-4582-9542-f3e4c9d5d77e from='file:/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore_java_8/co.codewizards.cloudstore.local/target/repo/k98qeafn-abba8-remote' toID=ed7762bf-473e-4490-a063-ec5e27a72e55 to='target/repo/k98qeafn-fntq5-local', fromRepoLocalSync=false
19:06:41.748 [main] INFO c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=151b8d6b-3544-4582-9542-f3e4c9d5d77e remoteRepositoryId=ed7762bf-473e-4490-a063-ec5e27a72e55 localRepositoryRevisionSynced=8 localRepositoryRevisionInProgress=11
19:06:41.752 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 88.31%
19:06:41.752 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 99.50%
19:06:41.756 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=151b8d6b-3544-4582-9542-f3e4c9d5d77e remoteRepositoryId=ed7762bf-473e-4490-a063-ec5e27a72e55 localRepositoryRevisionSynced=11
19:06:41.786 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=ed7762bf-473e-4490-a063-ec5e27a72e55 remoteRepositoryId=151b8d6b-3544-4582-9542-f3e4c9d5d77e transaction.localRevision=66 remoteFromLocalRevision=11
19:06:41.804 [main] INFO c.c.c.l.s.RepoToRepoSyncTest - Synchronising...: 100.00%
19:06:41.804 [main] INFO c.c.c.l.LocalRepoManagerImpl - [4e907609]close: Deferring shut down of real LocalRepoManager 20000 ms.
19:06:41.816 [main] INFO c.c.c.l.LocalRepoManagerImpl - [3479bc1]close: Deferring shut down of real LocalRepoManager 20000 ms.
Tests run: 20, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 314.879 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 500.0 KB/sec)
[INFO] Uploading: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/snapshot/co/codewizards/cloudstore/co.codewizards.cloudstore.local/0.10.8-SNAPSHOT/co.codewizards.cloudstore.local-0.10.8-20200420.170644-2.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-20200420.170644-2.jar (203 KB at 40549.8 KB/sec)
[INFO] Uploading: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/snapshot/co/codewizards/cloudstore/co.codewizards.cloudstore.local/0.10.8-SNAPSHOT/co.codewizards.cloudstore.local-0.10.8-20200420.170644-2.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-20200420.170644-2.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 (357 B at 348.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 (357 B)
[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-20200420.170644-2-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-20200420.170644-2-sources.jar (105 KB at 34758.8 KB/sec)
[INFO] Uploading: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/snapshot/co/codewizards/cloudstore/co.codewizards.cloudstore.local/0.10.8-SNAPSHOT/maven-metadata.xml
[INFO] Uploaded: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/snapshot/co/codewizards/cloudstore/co.codewizards.cloudstore.local/0.10.8-SNAPSHOT/maven-metadata.xml (1 KB at 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