|  Console Output
Skipping 1,603 KB.. Full Log148 ms.
remoteRoot: jz6kpmyn-2fswv-remote
remoteRepositoryId: e3f4a47a-fd8b-45b2-90f9-985241041b03
08:14:56.833 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 9432 bytes in 37 ms. path='/1 {11 11ä11#+} 1/c'
08:14:56.841 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_84a516841ba77a5b4648de2cd0dfcb30ea46dbb4_0 into destFile c
08:14:56.856 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=7, fromRepositoryId="FileInProgressMarker", toRepositoryId="7", path="a97338ce-2273-46fd-8321-9659731968c3"] on repo=80ba59a7-32e1-4bb6-a446-d12f42b25542
08:14:56.861 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/2/1 {11 11ä11#+} 1/a'
08:14:56.926 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='0068be594c9c83895ae340042393211ec65e3ab5' to.sha1='<NoInstanceOf_NormalFileDto>'): /2/1 {11 11ä11#+} 1/a
08:14:56.952 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=8, fromRepositoryId="FileInProgressMarker", toRepositoryId="8", path="a97338ce-2273-46fd-8321-9659731968c3"] on repo=80ba59a7-32e1-4bb6-a446-d12f42b25542
08:14:56.954 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/2/1 {11 11ä11#+} 1/a'
08:14:57.050 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 402920 bytes in 96 ms. path='/2/1 {11 11ä11#+} 1/a'
08:14:57.057 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_86f7e437faa5a7fce15d1ddcb9eaeaea377667b8_0 into destFile a
08:14:57.089 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=8, fromRepositoryId="FileInProgressMarker", toRepositoryId="8", path="a97338ce-2273-46fd-8321-9659731968c3"] on repo=80ba59a7-32e1-4bb6-a446-d12f42b25542
08:14:57.094 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/2/1 {11 11ä11#+} 1/b'
08:14:57.154 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='b0a1041d4684a79a4f6bb5ef176d33457fbc6d52' to.sha1='<NoInstanceOf_NormalFileDto>'): /2/1 {11 11ä11#+} 1/b
08:14:57.232 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=9, fromRepositoryId="FileInProgressMarker", toRepositoryId="9", path="a97338ce-2273-46fd-8321-9659731968c3"] on repo=80ba59a7-32e1-4bb6-a446-d12f42b25542
08:14:57.235 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/2/1 {11 11ä11#+} 1/b'
08:14:57.285 [main] INFO c.c.c.l.s.LocalServer - logMemoryStats: max=3.5 GiB, allocated=372.0 MiB, used=53.0 MiB, available=3.4 GiB
08:14:57.378 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 597894 bytes in 143 ms. path='/2/1 {11 11ä11#+} 1/b'
08:14:57.386 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_e9d71f5ee7c92d6dc9e92ffdad17b8bd49418f98_0 into destFile b
08:14:57.431 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=9, fromRepositoryId="FileInProgressMarker", toRepositoryId="9", path="a97338ce-2273-46fd-8321-9659731968c3"] on repo=80ba59a7-32e1-4bb6-a446-d12f42b25542
08:14:57.440 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/2/a'
08:14:57.511 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='3c739ee6bd6021f9fd7830adcfb4d27a6ec8790e' to.sha1='<NoInstanceOf_NormalFileDto>'): /2/a
08:14:57.543 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=10, fromRepositoryId="FileInProgressMarker", toRepositoryId="10", path="a97338ce-2273-46fd-8321-9659731968c3"] on repo=80ba59a7-32e1-4bb6-a446-d12f42b25542
08:14:57.544 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/2/a'
Successfully requested to connect the following local and remote repositories:
localRepository.repositoryId = 11855d8c-ce01-4623-8085-8ac21d69292d
localRepository.localRoot = /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.test/target/repo/jz6kpmym-vdhw0-local
localRepository.publicKeySha1 = 0D:B3:8E:F0:EA:AF:10:A9:78:A8:DD:A2:AE:14:F5:A5:FD:95:7E:B6
remoteRepository.repositoryId = e3f4a47a-fd8b-45b2-90f9-985241041b03
remoteRepository.remoteRoot = https://localhost:37785/e3f4a47a-fd8b-45b2-90f9-985241041b03
remoteRepository.publicKeySha1 = DA:4E:54:93:AA:DF:CC:18:B8:17:E6:65:25:39:47:5C:BE:7C:2D:EE
Please verify the 'publicKeySha1' fingerprints! If they do not match the fingerprints shown on the server, someone is attacking you and you must cancel this request immediately! To cancel the request, use this command:
cloudstore dropRepoConnection 11855d8c-ce01-4623-8085-8ac21d69292d e3f4a47a-fd8b-45b2-90f9-985241041b03
08:14:57.640 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 435005 bytes in 96 ms. path='/2/a'
08:14:57.647 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_86f7e437faa5a7fce15d1ddcb9eaeaea377667b8_0 into destFile a
08:14:57.681 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=10, fromRepositoryId="FileInProgressMarker", toRepositoryId="10", path="a97338ce-2273-46fd-8321-9659731968c3"] on repo=80ba59a7-32e1-4bb6-a446-d12f42b25542
08:14:57.686 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/3/a'
08:14:57.727 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='a91a0b000acfe7dd90466f1481d7e1da2e68efe5' to.sha1='<NoInstanceOf_NormalFileDto>'): /3/a
08:14:57.746 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=11, fromRepositoryId="FileInProgressMarker", toRepositoryId="11", path="a97338ce-2273-46fd-8321-9659731968c3"] on repo=80ba59a7-32e1-4bb6-a446-d12f42b25542
08:14:57.748 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/3/a'
08:14:57.823 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 279548 bytes in 75 ms. path='/3/a'
08:14:57.829 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_86f7e437faa5a7fce15d1ddcb9eaeaea377667b8_0 into destFile a
08:14:57.856 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=11, fromRepositoryId="FileInProgressMarker", toRepositoryId="11", path="a97338ce-2273-46fd-8321-9659731968c3"] on repo=80ba59a7-32e1-4bb6-a446-d12f42b25542
08:14:57.860 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/3/b'
08:14:57.908 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='ba93154686db43d6dae1db130962b7273c30de68' to.sha1='<NoInstanceOf_NormalFileDto>'): /3/b
08:14:57.931 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=12, fromRepositoryId="FileInProgressMarker", toRepositoryId="12", path="a97338ce-2273-46fd-8321-9659731968c3"] on repo=80ba59a7-32e1-4bb6-a446-d12f42b25542
08:14:57.934 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/3/b'
08:14:57.935 [main] INFO c.c.c.l.s.LocalServer - logMemoryStats: max=3.5 GiB, allocated=378.0 MiB, used=45.2 MiB, available=3.4 GiB
Successfully accepted the connection request for the following local and remote repositories:
localRepository.repositoryId = e3f4a47a-fd8b-45b2-90f9-985241041b03
localRepository.localRoot = /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.test/target/repo/jz6kpmyn-2fswv-remote
localRepository.publicKeySha1 = DA:4E:54:93:AA:DF:CC:18:B8:17:E6:65:25:39:47:5C:BE:7C:2D:EE
remoteRepository.repositoryId = 11855d8c-ce01-4623-8085-8ac21d69292d
remoteRepository.publicKeySha1 = 0D:B3:8E:F0:EA:AF:10:A9:78:A8:DD:A2:AE:14:F5:A5:FD:95:7E:B6
Please verify the 'publicKeySha1' fingerprints! If they do not match the fingerprints shown on the client, someone is attacking you and you must cancel this request immediately! To cancel the request, use this command:
cloudstore dropRepoConnection e3f4a47a-fd8b-45b2-90f9-985241041b03 11855d8c-ce01-4623-8085-8ac21d69292d
08:14:57.992 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 200950 bytes in 58 ms. path='/3/b'
08:14:57.998 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_e9d71f5ee7c92d6dc9e92ffdad17b8bd49418f98_0 into destFile b
08:14:58.024 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=12, fromRepositoryId="FileInProgressMarker", toRepositoryId="12", path="a97338ce-2273-46fd-8321-9659731968c3"] on repo=80ba59a7-32e1-4bb6-a446-d12f42b25542
08:14:58.028 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/3/c'
08:14:58.062 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='05d89f1bed6b2e2a30a93e982c72baa1fc7cac9f' to.sha1='<NoInstanceOf_NormalFileDto>'): /3/c
08:14:58.080 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=13, fromRepositoryId="FileInProgressMarker", toRepositoryId="13", path="a97338ce-2273-46fd-8321-9659731968c3"] on repo=80ba59a7-32e1-4bb6-a446-d12f42b25542
08:14:58.082 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/3/c'
08:14:58.127 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 160620 bytes in 45 ms. path='/3/c'
08:14:58.133 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_84a516841ba77a5b4648de2cd0dfcb30ea46dbb4_0 into destFile c
08:14:58.152 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=13, fromRepositoryId="FileInProgressMarker", toRepositoryId="13", path="a97338ce-2273-46fd-8321-9659731968c3"] on repo=80ba59a7-32e1-4bb6-a446-d12f42b25542
08:14:58.156 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/3/d'
08:14:58.191 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='6d13bbc0a7425ac7d528f155389e51883d37f849' to.sha1='<NoInstanceOf_NormalFileDto>'): /3/d
08:14:58.210 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=14, fromRepositoryId="FileInProgressMarker", toRepositoryId="14", path="a97338ce-2273-46fd-8321-9659731968c3"] on repo=80ba59a7-32e1-4bb6-a446-d12f42b25542
08:14:58.211 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/3/d'
08:14:58.246 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 15525 bytes in 35 ms. path='/3/d'
08:14:58.251 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_3c363836cf4e16666669a25da280a1865c2d2874_0 into destFile d
08:14:58.267 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=14, fromRepositoryId="FileInProgressMarker", toRepositoryId="14", path="a97338ce-2273-46fd-8321-9659731968c3"] on repo=80ba59a7-32e1-4bb6-a446-d12f42b25542
08:14:58.274 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/5#/e'
08:14:58.311 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='ead0783cd27ea832a00f14370f979e4031045eba' to.sha1='<NoInstanceOf_NormalFileDto>'): /5#/e
08:14:58.330 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=15, fromRepositoryId="FileInProgressMarker", toRepositoryId="15", path="a97338ce-2273-46fd-8321-9659731968c3"] on repo=80ba59a7-32e1-4bb6-a446-d12f42b25542
08:14:58.331 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/5#/e'
08:14:58.447 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 419400 bytes in 116 ms. path='/5#/e'
08:14:58.453 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_58e6b3a414a1e090dfc6029add0f3555ccba127f_0 into destFile e
08:14:58.482 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=15, fromRepositoryId="FileInProgressMarker", toRepositoryId="15", path="a97338ce-2273-46fd-8321-9659731968c3"] on repo=80ba59a7-32e1-4bb6-a446-d12f42b25542
08:14:58.486 [main] INFO c.c.c.t.r.AbstractRepoToRepoSyncIT - Synchronising...: 49.42%
08:14:58.533 [qtp1876368826-29] INFO c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=a97338ce-2273-46fd-8321-9659731968c3 remoteRepositoryId=80ba59a7-32e1-4bb6-a446-d12f42b25542 localRepositoryRevisionSynced=3
08:14:58.559 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=80ba59a7-32e1-4bb6-a446-d12f42b25542 remoteRepositoryId=a97338ce-2273-46fd-8321-9659731968c3 transaction.localRevision=84 remoteFromLocalRevision=3
08:14:58.562 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncUp: fromID=80ba59a7-32e1-4bb6-a446-d12f42b25542 from='target/repo/jz6kphk0-ii18v-local' toID=a97338ce-2273-46fd-8321-9659731968c3 to='https://localhost:35971/a97338ce-2273-46fd-8321-9659731968c3'
08:14:58.618 [main] TRACE c.c.c.l.t.ChangeSetDtoBuilder - >>> buildChangeSetDto >>>
08:14:58.624 [main] TRACE c.c.c.l.t.ChangeSetDtoBuilder - localRepositoryId: 80ba59a7-32e1-4bb6-a446-d12f42b25542
08:14:58.624 [main] TRACE c.c.c.l.t.ChangeSetDtoBuilder - remoteRepositoryId: a97338ce-2273-46fd-8321-9659731968c3
08:14:58.624 [main] TRACE c.c.c.l.t.ChangeSetDtoBuilder - pathPrefix:
08:14:58.624 [main] INFO c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=80ba59a7-32e1-4bb6-a446-d12f42b25542 remoteRepositoryId=a97338ce-2273-46fd-8321-9659731968c3 localRepositoryRevisionSynced=-1 localRepositoryRevisionInProgress=84
08:14:58.631 [main] DEBUG c.c.c.l.t.ChangeSetDtoBuilder - toModificationDtos: Creating 0 ModificationDtos took 0 ms.
08:14:58.637 [main] TRACE c.c.c.l.t.ChangeSetDtoBuilder - >>> buildConfigPropSetDto >>>
08:14:58.637 [main] DEBUG c.c.c.l.t.ChangeSetDtoBuilder - buildConfigPropSetDto: pathPrefix is empty => returning null.
08:14:58.637 [main] TRACE c.c.c.l.t.ChangeSetDtoBuilder - <<< buildConfigPropSetDto <<< null
08:14:58.637 [main] TRACE c.c.c.l.t.ChangeSetDtoBuilder - <<< buildChangeSetDto <<<
08:14:58.638 [main] INFO c.c.c.t.r.AbstractRepoToRepoSyncIT - Synchronising...: 63.43%
08:14:58.638 [main] INFO c.c.c.t.r.AbstractRepoToRepoSyncIT - Synchronising...: 74.63%
08:14:58.653 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=80ba59a7-32e1-4bb6-a446-d12f42b25542 remoteRepositoryId=a97338ce-2273-46fd-8321-9659731968c3 localRepositoryRevisionSynced=84
08:14:58.681 [qtp1876368826-30] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=a97338ce-2273-46fd-8321-9659731968c3 remoteRepositoryId=80ba59a7-32e1-4bb6-a446-d12f42b25542 transaction.localRevision=6 remoteFromLocalRevision=84
08:14:58.684 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDown: fromID=a97338ce-2273-46fd-8321-9659731968c3 from='https://localhost:35971/a97338ce-2273-46fd-8321-9659731968c3' toID=80ba59a7-32e1-4bb6-a446-d12f42b25542 to='target/repo/jz6kphk0-ii18v-local', fromRepoLocalSync=false
08:14:58.685 [main] INFO c.c.c.r.c.t.RestRepoTransport - getChangeSetDto: ChangeSetDto-cache-file NOT found: /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.test/target/repo/jz6kphk0-ii18v-local/.cloudstore-repo/tmp/ChangeSetDto.a97338ce-2273-46fd-8321-9659731968c3.null.xml.gz
08:14:58.722 [pool-4-thread-1] TRACE c.c.c.l.t.ChangeSetDtoBuilder - >>> buildChangeSetDto >>>
08:14:58.725 [pool-4-thread-1] TRACE c.c.c.l.t.ChangeSetDtoBuilder - localRepositoryId: a97338ce-2273-46fd-8321-9659731968c3
08:14:58.725 [pool-4-thread-1] TRACE c.c.c.l.t.ChangeSetDtoBuilder - remoteRepositoryId: 80ba59a7-32e1-4bb6-a446-d12f42b25542
08:14:58.725 [pool-4-thread-1] TRACE c.c.c.l.t.ChangeSetDtoBuilder - pathPrefix:
08:14:58.725 [pool-4-thread-1] INFO c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=a97338ce-2273-46fd-8321-9659731968c3 remoteRepositoryId=80ba59a7-32e1-4bb6-a446-d12f42b25542 localRepositoryRevisionSynced=3 localRepositoryRevisionInProgress=6
08:14:58.726 [pool-4-thread-1] DEBUG c.c.c.l.t.ChangeSetDtoBuilder - toModificationDtos: Creating 0 ModificationDtos took 0 ms.
08:14:58.727 [pool-4-thread-1] TRACE c.c.c.l.t.ChangeSetDtoBuilder - >>> buildConfigPropSetDto >>>
08:14:58.728 [pool-4-thread-1] DEBUG c.c.c.l.t.ChangeSetDtoBuilder - buildConfigPropSetDto: pathPrefix is empty => returning null.
08:14:58.728 [pool-4-thread-1] TRACE c.c.c.l.t.ChangeSetDtoBuilder - <<< buildConfigPropSetDto <<< null
08:14:58.728 [pool-4-thread-1] TRACE c.c.c.l.t.ChangeSetDtoBuilder - <<< buildChangeSetDto <<<
08:14:58.736 [main] INFO c.c.c.r.c.t.RestRepoTransport - getChangeSetDto: Wrote ChangeSetDto-cache-file: /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.test/target/repo/jz6kphk0-ii18v-local/.cloudstore-repo/tmp/ChangeSetDto.a97338ce-2273-46fd-8321-9659731968c3.null.xml.gz
08:14:58.736 [main] INFO c.c.c.t.r.AbstractRepoToRepoSyncIT - Synchronising...: 88.31%
08:14:58.736 [main] INFO c.c.c.t.r.AbstractRepoToRepoSyncIT - Synchronising...: 99.50%
08:14:58.758 [qtp1876368826-29] INFO c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=a97338ce-2273-46fd-8321-9659731968c3 remoteRepositoryId=80ba59a7-32e1-4bb6-a446-d12f42b25542 localRepositoryRevisionSynced=6
08:14:58.769 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=80ba59a7-32e1-4bb6-a446-d12f42b25542 remoteRepositoryId=a97338ce-2273-46fd-8321-9659731968c3 transaction.localRevision=87 remoteFromLocalRevision=6
08:14:58.771 [main] INFO c.c.c.t.r.AbstractRepoToRepoSyncIT - Synchronising...: 100.00%
08:14:58.972 [main] INFO c.c.c.t.r.AbstractRepoToRepoSyncIT - Synchronising...: 0.50%
08:14:58.974 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDown: fromID=e3f4a47a-fd8b-45b2-90f9-985241041b03 from='https://localhost:37785/e3f4a47a-fd8b-45b2-90f9-985241041b03' toID=11855d8c-ce01-4623-8085-8ac21d69292d to='target/repo/jz6kpmym-vdhw0-local', fromRepoLocalSync=true
08:14:58.974 [pool-9-thread-1] INFO c.c.c.c.r.s.RepoToRepoSync - sync: locally syncing 11855d8c-ce01-4623-8085-8ac21d69292d ('target/repo/jz6kpmym-vdhw0-local')
08:14:59.003 [pool-9-thread-1] INFO c.c.c.t.r.AbstractRepoToRepoSyncIT - Synchronising...: 12.94%
08:14:59.011 [pool-9-thread-1] INFO c.c.c.t.r.AbstractRepoToRepoSyncIT - Synchronising...: 25.12%
08:14:59.014 [main] INFO c.c.c.l.LocalRepoManagerImpl - [56d822dc]getCloseDeferredMillis: closeDeferredMillis=0
08:14:59.014 [main] INFO c.c.c.l.LocalRepoManagerImpl - [56d822dc]close: Closing real LocalRepoManager immediately.
08:14:59.014 [main] INFO c.c.c.l.LocalRepoManagerImpl - [56d822dc]_close: Shutting down real LocalRepoManager.
08:14:59.015 [main] INFO c.c.c.r.c.t.RestRepoTransport - getChangeSetDto: ChangeSetDto-cache-file NOT found: /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.test/target/repo/jz6kpmym-vdhw0-local/.cloudstore-repo/tmp/ChangeSetDto.e3f4a47a-fd8b-45b2-90f9-985241041b03.-1.xml.gz
08:14:59.071 [main] INFO c.c.c.r.c.t.RestRepoTransport - getAuthToken: got new AuthToken: clientRepositoryId=11855d8c-ce01-4623-8085-8ac21d69292d serverRepositoryId=e3f4a47a-fd8b-45b2-90f9-985241041b03 renewalDateTime=2019-08-11T06:44:59.032Z expiryDateTime=2019-08-11T07:14:59.032Z
08:14:59.087 [pool-4-thread-1] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 1.00%
08:14:59.101 [pool-4-thread-1] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 50.00%
08:14:59.135 [pool-4-thread-1] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 55.03%
08:14:59.221 [pool-4-thread-1] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 64.60%
08:14:59.240 [pool-4-thread-1] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 74.40%
08:14:59.312 [pool-4-thread-1] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 84.20%
08:14:59.401 [pool-4-thread-1] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 94.00%
08:14:59.510 [pool-4-thread-1] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 100.00%
08:14:59.591 [pool-4-thread-1] TRACE c.c.c.l.t.ChangeSetDtoBuilder - >>> buildChangeSetDto >>>
08:14:59.599 [pool-4-thread-1] TRACE c.c.c.l.t.ChangeSetDtoBuilder - localRepositoryId: e3f4a47a-fd8b-45b2-90f9-985241041b03
08:14:59.599 [pool-4-thread-1] TRACE c.c.c.l.t.ChangeSetDtoBuilder - remoteRepositoryId: 11855d8c-ce01-4623-8085-8ac21d69292d
08:14:59.599 [pool-4-thread-1] TRACE c.c.c.l.t.ChangeSetDtoBuilder - pathPrefix:
08:14:59.599 [pool-4-thread-1] INFO c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=e3f4a47a-fd8b-45b2-90f9-985241041b03 remoteRepositoryId=11855d8c-ce01-4623-8085-8ac21d69292d localRepositoryRevisionSynced=-1 localRepositoryRevisionInProgress=3
08:14:59.608 [pool-4-thread-1] DEBUG c.c.c.l.t.ChangeSetDtoBuilder - toModificationDtos: Creating 0 ModificationDtos took 0 ms.
08:14:59.642 [pool-4-thread-1] TRACE c.c.c.l.t.ChangeSetDtoBuilder - >>> buildConfigPropSetDto >>>
08:14:59.642 [pool-4-thread-1] DEBUG c.c.c.l.t.ChangeSetDtoBuilder - buildConfigPropSetDto: pathPrefix is empty => returning null.
08:14:59.642 [pool-4-thread-1] TRACE c.c.c.l.t.ChangeSetDtoBuilder - <<< buildConfigPropSetDto <<< null
08:14:59.642 [pool-4-thread-1] TRACE c.c.c.l.t.ChangeSetDtoBuilder - <<< buildChangeSetDto <<<
08:14:59.672 [main] INFO c.c.c.r.c.t.RestRepoTransport - getChangeSetDto: Wrote ChangeSetDto-cache-file: /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.test/target/repo/jz6kpmym-vdhw0-local/.cloudstore-repo/tmp/ChangeSetDto.e3f4a47a-fd8b-45b2-90f9-985241041b03.-1.xml.gz
08:14:59.673 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path=''
08:14:59.705 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path='/#4'
08:14:59.758 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path='/1 {11 11ä11#+} 1'
08:14:59.774 [main] INFO c.c.c.t.r.AbstractRepoToRepoSyncIT - Synchronising...: 30.33%
08:14:59.774 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path='/2'
08:14:59.849 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path='/2/1 {11 11ä11#+} 1'
08:14:59.875 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path='/3'
08:14:59.936 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path='/5#'
08:14:59.955 [main] INFO c.c.c.l.LocalRepoManagerImpl - [5eb2972f]getCloseDeferredMillis: closeDeferredMillis=0
08:14:59.955 [main] INFO c.c.c.l.LocalRepoManagerImpl - [5eb2972f]close: Closing real LocalRepoManager immediately.
08:14:59.955 [main] INFO c.c.c.l.LocalRepoManagerImpl - [5eb2972f]_close: Shutting down real LocalRepoManager.
08:14:59.964 [main] INFO c.c.c.t.r.AbstractRepoToRepoSyncIT - Synchronising...: 35.59%
08:14:59.965 [main] INFO c.c.c.t.r.AbstractRepoToRepoSyncIT - Synchronising...: 40.86%
08:14:59.966 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/#4/#b'
08:15:00.015 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='9a3c5a9b02c7f59098cf755c393301f3d3562e29' to.sha1='<NoInstanceOf_NormalFileDto>'): /#4/#b
08:15:00.064 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=1, fromRepositoryId="FileInProgressMarker", toRepositoryId="1", path="e3f4a47a-fd8b-45b2-90f9-985241041b03"] on repo=11855d8c-ce01-4623-8085-8ac21d69292d
08:15:00.066 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/#4/#b'
08:15:00.190 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 625086 bytes in 124 ms. path='/#4/#b'
08:15:00.231 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_c691e3d4c979adbf07e3b97e33fdf3c89ed246f6_0 into destFile #b
08:15:00.353 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=1, fromRepositoryId="FileInProgressMarker", toRepositoryId="1", path="e3f4a47a-fd8b-45b2-90f9-985241041b03"] on repo=11855d8c-ce01-4623-8085-8ac21d69292d
08:15:00.363 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/#4/a'
08:15:00.403 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='99dc7a08cb44df526b3a67d24e902d31ba7526b9' to.sha1='<NoInstanceOf_NormalFileDto>'): /#4/a
08:15:00.438 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=2, fromRepositoryId="FileInProgressMarker", toRepositoryId="2", path="e3f4a47a-fd8b-45b2-90f9-985241041b03"] on repo=11855d8c-ce01-4623-8085-8ac21d69292d
08:15:00.443 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/#4/a'
08:15:00.466 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 8673 bytes in 23 ms. path='/#4/a'
08:15:00.472 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_86f7e437faa5a7fce15d1ddcb9eaeaea377667b8_0 into destFile a
08:15:00.532 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=2, fromRepositoryId="FileInProgressMarker", toRepositoryId="2", path="e3f4a47a-fd8b-45b2-90f9-985241041b03"] on repo=11855d8c-ce01-4623-8085-8ac21d69292d
08:15:00.547 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/#4/c+'
08:15:00.613 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='67c6afd41360ff92f1a5cc1aae684ec8836ee469' to.sha1='<NoInstanceOf_NormalFileDto>'): /#4/c+
08:15:00.633 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=3, fromRepositoryId="FileInProgressMarker", toRepositoryId="3", path="e3f4a47a-fd8b-45b2-90f9-985241041b03"] on repo=11855d8c-ce01-4623-8085-8ac21d69292d
08:15:00.635 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/#4/c+'
08:15:00.718 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 356135 bytes in 82 ms. path='/#4/c+'
08:15:00.726 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_8a30244b83c6cac3c79f8f95d30f96579c0f4c0b_0 into destFile c+
08:15:00.761 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=3, fromRepositoryId="FileInProgressMarker", toRepositoryId="3", path="e3f4a47a-fd8b-45b2-90f9-985241041b03"] on repo=11855d8c-ce01-4623-8085-8ac21d69292d
08:15:00.771 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/#4/d$'
08:15:00.810 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='ff891b3fd5b6bfaeb8cc7a6a2ac36cbeaaa30631' to.sha1='<NoInstanceOf_NormalFileDto>'): /#4/d$
08:15:00.857 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=4, fromRepositoryId="FileInProgressMarker", toRepositoryId="4", path="e3f4a47a-fd8b-45b2-90f9-985241041b03"] on repo=11855d8c-ce01-4623-8085-8ac21d69292d
08:15:00.874 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/#4/d$'
08:15:00.964 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 417744 bytes in 90 ms. path='/#4/d$'
08:15:00.970 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_a93a212f7eca012dceb852f364cc2c34026f0dd8_0 into destFile d$
08:15:01.035 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=4, fromRepositoryId="FileInProgressMarker", toRepositoryId="4", path="e3f4a47a-fd8b-45b2-90f9-985241041b03"] on repo=11855d8c-ce01-4623-8085-8ac21d69292d
08:15:01.052 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/1 {11 11ä11#+} 1/a'
08:15:01.102 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='5e70dccbd6cb870dae406038cce097c18cabd29c' to.sha1='<NoInstanceOf_NormalFileDto>'): /1 {11 11ä11#+} 1/a
08:15:01.169 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=5, fromRepositoryId="FileInProgressMarker", toRepositoryId="5", path="e3f4a47a-fd8b-45b2-90f9-985241041b03"] on repo=11855d8c-ce01-4623-8085-8ac21d69292d
08:15:01.171 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/1 {11 11ä11#+} 1/a'
08:15:01.204 [main] INFO c.c.c.t.r.BackupRestoreRepoToRepoSyncIT - ************************************************************************************
08:15:01.205 [main] INFO c.c.c.t.r.BackupRestoreRepoToRepoSyncIT - Creating backup: 'target/repo/jz6kphk1-z0j0y-remote' => 'target/repo/jz6kphk1-z0j0y-remote.bak'
08:15:01.210 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 23373 bytes in 38 ms. path='/1 {11 11ä11#+} 1/a'
08:15:01.216 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_86f7e437faa5a7fce15d1ddcb9eaeaea377667b8_0 into destFile a
08:15:01.229 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=5, fromRepositoryId="FileInProgressMarker", toRepositoryId="5", path="e3f4a47a-fd8b-45b2-90f9-985241041b03"] on repo=11855d8c-ce01-4623-8085-8ac21d69292d
08:15:01.233 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/1 {11 11ä11#+} 1/b'
08:15:01.264 [main] INFO c.c.c.t.r.BackupRestoreRepoToRepoSyncIT - Created backup: 'target/repo/jz6kphk1-z0j0y-remote' => 'target/repo/jz6kphk1-z0j0y-remote.bak'
08:15:01.264 [main] INFO c.c.c.t.r.BackupRestoreRepoToRepoSyncIT - ************************************************************************************
08:15:01.265 [main] INFO c.c.c.l.LocalRepoManagerImpl - [16120270]<init>: localRoot='/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.test/target/repo/jz6kphk0-ii18v-local'
08:15:01.265 [main] INFO c.c.c.l.LocalRepoManagerImpl - [16120270]initLockFile: Repository '/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.test/target/repo/jz6kphk0-ii18v-local' locked successfully.
08:15:01.272 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='305cc7477d0a7cfb9cc2a6d5e84113031bd86e4f' to.sha1='<NoInstanceOf_NormalFileDto>'): /1 {11 11ä11#+} 1/b
08:15:01.294 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=6, fromRepositoryId="FileInProgressMarker", toRepositoryId="6", path="e3f4a47a-fd8b-45b2-90f9-985241041b03"] on repo=11855d8c-ce01-4623-8085-8ac21d69292d
08:15:01.296 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/1 {11 11ä11#+} 1/b'
08:15:01.340 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 104416 bytes in 44 ms. path='/1 {11 11ä11#+} 1/b'
08:15:01.346 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_e9d71f5ee7c92d6dc9e92ffdad17b8bd49418f98_0 into destFile b
08:15:01.362 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=6, fromRepositoryId="FileInProgressMarker", toRepositoryId="6", path="e3f4a47a-fd8b-45b2-90f9-985241041b03"] on repo=11855d8c-ce01-4623-8085-8ac21d69292d
08:15:01.366 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/1 {11 11ä11#+} 1/c'
08:15:01.402 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='92665fc34d84869fed3c0c45e0f6445a599f38d2' to.sha1='<NoInstanceOf_NormalFileDto>'): /1 {11 11ä11#+} 1/c
08:15:01.422 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=7, fromRepositoryId="FileInProgressMarker", toRepositoryId="7", path="e3f4a47a-fd8b-45b2-90f9-985241041b03"] on repo=11855d8c-ce01-4623-8085-8ac21d69292d
08:15:01.424 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/1 {11 11ä11#+} 1/c'
08:15:01.562 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 621224 bytes in 138 ms. path='/1 {11 11ä11#+} 1/c'
08:15:01.569 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_84a516841ba77a5b4648de2cd0dfcb30ea46dbb4_0 into destFile c
08:15:01.610 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=7, fromRepositoryId="FileInProgressMarker", toRepositoryId="7", path="e3f4a47a-fd8b-45b2-90f9-985241041b03"] on repo=11855d8c-ce01-4623-8085-8ac21d69292d
08:15:01.616 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/2/1 {11 11ä11#+} 1/a'
08:15:01.665 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='3968b278b8fc81d43845e738d8900db030cce61e' to.sha1='<NoInstanceOf_NormalFileDto>'): /2/1 {11 11ä11#+} 1/a
08:15:01.746 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=8, fromRepositoryId="FileInProgressMarker", toRepositoryId="8", path="e3f4a47a-fd8b-45b2-90f9-985241041b03"] on repo=11855d8c-ce01-4623-8085-8ac21d69292d
08:15:01.779 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/2/1 {11 11ä11#+} 1/a'
08:15:01.896 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 213932 bytes in 117 ms. path='/2/1 {11 11ä11#+} 1/a'
08:15:01.909 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_86f7e437faa5a7fce15d1ddcb9eaeaea377667b8_0 into destFile a
08:15:01.936 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=8, fromRepositoryId="FileInProgressMarker", toRepositoryId="8", path="e3f4a47a-fd8b-45b2-90f9-985241041b03"] on repo=11855d8c-ce01-4623-8085-8ac21d69292d
08:15:01.942 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/2/1 {11 11ä11#+} 1/b'
08:15:01.998 [main] INFO c.c.c.l.LocalRepoManagerImpl - [16120270]initPersistenceManagerFactory: repositoryId=80ba59a7-32e1-4bb6-a446-d12f42b25542
08:15:01.998 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='e77d016eda8d542e3417416e9b43d6114f1db61c' to.sha1='<NoInstanceOf_NormalFileDto>'): /2/1 {11 11ä11#+} 1/b
08:15:01.999 [main] INFO c.c.c.l.LocalRepoManagerImpl - [16120270]initPersistenceManagerFactory: Started up PersistenceManagerFactory successfully in 733 ms.
08:15:02.019 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=9, fromRepositoryId="FileInProgressMarker", toRepositoryId="9", path="e3f4a47a-fd8b-45b2-90f9-985241041b03"] on repo=11855d8c-ce01-4623-8085-8ac21d69292d
08:15:02.021 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/2/1 {11 11ä11#+} 1/b'
08:15:02.142 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 506685 bytes in 121 ms. path='/2/1 {11 11ä11#+} 1/b'
08:15:02.150 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_e9d71f5ee7c92d6dc9e92ffdad17b8bd49418f98_0 into destFile b
08:15:02.188 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=9, fromRepositoryId="FileInProgressMarker", toRepositoryId="9", path="e3f4a47a-fd8b-45b2-90f9-985241041b03"] on repo=11855d8c-ce01-4623-8085-8ac21d69292d
08:15:02.192 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/2/a'
08:15:02.222 [qtp1876368826-24] INFO c.c.c.l.LocalRepoManagerImpl - [4d0eeacd]<init>: localRoot='/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.test/target/repo/jz6kphk1-z0j0y-remote'
08:15:02.222 [qtp1876368826-24] INFO c.c.c.l.LocalRepoManagerImpl - [4d0eeacd]initLockFile: Repository '/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.test/target/repo/jz6kphk1-z0j0y-remote' locked successfully.
08:15:02.236 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='2622809d6bb205df9aa07e5c1396abc9db879e4f' to.sha1='<NoInstanceOf_NormalFileDto>'): /2/a
08:15:02.266 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=10, fromRepositoryId="FileInProgressMarker", toRepositoryId="10", path="e3f4a47a-fd8b-45b2-90f9-985241041b03"] on repo=11855d8c-ce01-4623-8085-8ac21d69292d
08:15:02.268 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/2/a'
08:15:02.420 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 785466 bytes in 152 ms. path='/2/a'
08:15:02.426 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_86f7e437faa5a7fce15d1ddcb9eaeaea377667b8_0 into destFile a
08:15:02.468 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=10, fromRepositoryId="FileInProgressMarker", toRepositoryId="10", path="e3f4a47a-fd8b-45b2-90f9-985241041b03"] on repo=11855d8c-ce01-4623-8085-8ac21d69292d
08:15:02.473 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/3/a'
08:15:02.523 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='c5da7675713e0199ed5aab3c980bae719615d8fb' to.sha1='<NoInstanceOf_NormalFileDto>'): /3/a
08:15:02.546 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=11, fromRepositoryId="FileInProgressMarker", toRepositoryId="11", path="e3f4a47a-fd8b-45b2-90f9-985241041b03"] on repo=11855d8c-ce01-4623-8085-8ac21d69292d
08:15:02.549 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/3/a'
08:15:02.642 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 456680 bytes in 93 ms. path='/3/a'
08:15:02.647 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_86f7e437faa5a7fce15d1ddcb9eaeaea377667b8_0 into destFile a
08:15:02.678 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=11, fromRepositoryId="FileInProgressMarker", toRepositoryId="11", path="e3f4a47a-fd8b-45b2-90f9-985241041b03"] on repo=11855d8c-ce01-4623-8085-8ac21d69292d
08:15:02.682 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/3/b'
08:15:02.721 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='5096cd9dc92c66f5349c1736dd45e9984290bdcb' to.sha1='<NoInstanceOf_NormalFileDto>'): /3/b
08:15:02.742 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=12, fromRepositoryId="FileInProgressMarker", toRepositoryId="12", path="e3f4a47a-fd8b-45b2-90f9-985241041b03"] on repo=11855d8c-ce01-4623-8085-8ac21d69292d
08:15:02.744 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/3/b'
08:15:02.808 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 200620 bytes in 64 ms. path='/3/b'
08:15:02.815 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_e9d71f5ee7c92d6dc9e92ffdad17b8bd49418f98_0 into destFile b
08:15:02.841 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=12, fromRepositoryId="FileInProgressMarker", toRepositoryId="12", path="e3f4a47a-fd8b-45b2-90f9-985241041b03"] on repo=11855d8c-ce01-4623-8085-8ac21d69292d
08:15:02.848 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/3/c'
08:15:02.915 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='f4fc7be19bae7b18c5b46d97f23d6c247c830537' to.sha1='<NoInstanceOf_NormalFileDto>'): /3/c
08:15:02.939 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=13, fromRepositoryId="FileInProgressMarker", toRepositoryId="13", path="e3f4a47a-fd8b-45b2-90f9-985241041b03"] on repo=11855d8c-ce01-4623-8085-8ac21d69292d
08:15:02.942 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/3/c'
08:15:02.999 [qtp1876368826-24] INFO c.c.c.l.LocalRepoManagerImpl - [4d0eeacd]initPersistenceManagerFactory: repositoryId=a97338ce-2273-46fd-8321-9659731968c3
08:15:03.000 [qtp1876368826-24] INFO c.c.c.l.LocalRepoManagerImpl - [4d0eeacd]initPersistenceManagerFactory: Started up PersistenceManagerFactory successfully in 777 ms.
08:15:03.032 [qtp1876368826-24] INFO c.c.c.l.LocalRepoManagerImpl - [4d0eeacd]getCloseDeferredMillis: closeDeferredMillis=0
08:15:03.032 [qtp1876368826-24] INFO c.c.c.l.LocalRepoManagerImpl - [4d0eeacd]close: Closing real LocalRepoManager immediately.
08:15:03.032 [qtp1876368826-24] INFO c.c.c.l.LocalRepoManagerImpl - [4d0eeacd]_close: Shutting down real LocalRepoManager.
08:15:03.044 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 438773 bytes in 102 ms. path='/3/c'
08:15:03.051 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_84a516841ba77a5b4648de2cd0dfcb30ea46dbb4_0 into destFile c
08:15:03.086 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=13, fromRepositoryId="FileInProgressMarker", toRepositoryId="13", path="e3f4a47a-fd8b-45b2-90f9-985241041b03"] on repo=11855d8c-ce01-4623-8085-8ac21d69292d
08:15:03.091 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/3/d'
08:15:03.138 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='bc7fae8830a101ca106231c360081c8917f625f9' to.sha1='<NoInstanceOf_NormalFileDto>'): /3/d
08:15:03.163 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=14, fromRepositoryId="FileInProgressMarker", toRepositoryId="14", path="e3f4a47a-fd8b-45b2-90f9-985241041b03"] on repo=11855d8c-ce01-4623-8085-8ac21d69292d
08:15:03.166 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/3/d'
08:15:03.241 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 201721 bytes in 75 ms. path='/3/d'
08:15:03.248 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_3c363836cf4e16666669a25da280a1865c2d2874_0 into destFile d
08:15:03.273 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=14, fromRepositoryId="FileInProgressMarker", toRepositoryId="14", path="e3f4a47a-fd8b-45b2-90f9-985241041b03"] on repo=11855d8c-ce01-4623-8085-8ac21d69292d
08:15:03.278 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/5#/e'
08:15:03.318 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='b49d00e34e3b92fe401bf72ac219593d0da15399' to.sha1='<NoInstanceOf_NormalFileDto>'): /5#/e
08:15:03.336 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=15, fromRepositoryId="FileInProgressMarker", toRepositoryId="15", path="e3f4a47a-fd8b-45b2-90f9-985241041b03"] on repo=11855d8c-ce01-4623-8085-8ac21d69292d
08:15:03.338 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/5#/e'
08:15:03.440 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 262080 bytes in 102 ms. path='/5#/e'
08:15:03.448 [main] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_58e6b3a414a1e090dfc6029add0f3555ccba127f_0 into destFile e
08:15:03.475 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=15, fromRepositoryId="FileInProgressMarker", toRepositoryId="15", path="e3f4a47a-fd8b-45b2-90f9-985241041b03"] on repo=11855d8c-ce01-4623-8085-8ac21d69292d
08:15:03.482 [main] INFO c.c.c.t.r.AbstractRepoToRepoSyncIT - Synchronising...: 49.42%
08:15:03.504 [main] INFO c.c.c.t.r.BackupRestoreRepoToRepoSyncIT - Synchronising...: 0.50%
08:15:03.505 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDown: fromID=a97338ce-2273-46fd-8321-9659731968c3 from='https://localhost:35971/a97338ce-2273-46fd-8321-9659731968c3' toID=80ba59a7-32e1-4bb6-a446-d12f42b25542 to='target/repo/jz6kphk0-ii18v-local', fromRepoLocalSync=true
08:15:03.508 [pool-8-thread-1] INFO c.c.c.c.r.s.RepoToRepoSync - sync: locally syncing 80ba59a7-32e1-4bb6-a446-d12f42b25542 ('target/repo/jz6kphk0-ii18v-local')
08:15:03.529 [qtp1740661583-47] INFO c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=e3f4a47a-fd8b-45b2-90f9-985241041b03 remoteRepositoryId=11855d8c-ce01-4623-8085-8ac21d69292d localRepositoryRevisionSynced=3
08:15:03.552 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=11855d8c-ce01-4623-8085-8ac21d69292d remoteRepositoryId=e3f4a47a-fd8b-45b2-90f9-985241041b03 transaction.localRevision=84 remoteFromLocalRevision=3
08:15:03.556 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncUp: fromID=11855d8c-ce01-4623-8085-8ac21d69292d from='target/repo/jz6kpmym-vdhw0-local' toID=e3f4a47a-fd8b-45b2-90f9-985241041b03 to='https://localhost:37785/e3f4a47a-fd8b-45b2-90f9-985241041b03'
08:15:03.606 [pool-8-thread-1] INFO c.c.c.t.r.BackupRestoreRepoToRepoSyncIT - Synchronising...: 12.94%
08:15:03.615 [main] TRACE c.c.c.l.t.ChangeSetDtoBuilder - >>> buildChangeSetDto >>>
08:15:03.618 [main] TRACE c.c.c.l.t.ChangeSetDtoBuilder - localRepositoryId: 11855d8c-ce01-4623-8085-8ac21d69292d
08:15:03.619 [main] TRACE c.c.c.l.t.ChangeSetDtoBuilder - remoteRepositoryId: e3f4a47a-fd8b-45b2-90f9-985241041b03
08:15:03.619 [main] TRACE c.c.c.l.t.ChangeSetDtoBuilder - pathPrefix:
08:15:03.619 [main] INFO c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=11855d8c-ce01-4623-8085-8ac21d69292d remoteRepositoryId=e3f4a47a-fd8b-45b2-90f9-985241041b03 localRepositoryRevisionSynced=-1 localRepositoryRevisionInProgress=84
08:15:03.628 [main] DEBUG c.c.c.l.t.ChangeSetDtoBuilder - toModificationDtos: Creating 0 ModificationDtos took 0 ms.
08:15:03.644 [main] TRACE c.c.c.l.t.ChangeSetDtoBuilder - >>> buildConfigPropSetDto >>>
08:15:03.644 [main] DEBUG c.c.c.l.t.ChangeSetDtoBuilder - buildConfigPropSetDto: pathPrefix is empty => returning null.
08:15:03.644 [main] TRACE c.c.c.l.t.ChangeSetDtoBuilder - <<< buildConfigPropSetDto <<< null
08:15:03.644 [main] TRACE c.c.c.l.t.ChangeSetDtoBuilder - <<< buildChangeSetDto <<<
08:15:03.644 [main] INFO c.c.c.t.r.AbstractRepoToRepoSyncIT - Synchronising...: 63.43%
08:15:03.645 [main] INFO c.c.c.t.r.AbstractRepoToRepoSyncIT - Synchronising...: 74.63%
08:15:03.664 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=11855d8c-ce01-4623-8085-8ac21d69292d remoteRepositoryId=e3f4a47a-fd8b-45b2-90f9-985241041b03 localRepositoryRevisionSynced=84
08:15:03.702 [qtp1740661583-47] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=e3f4a47a-fd8b-45b2-90f9-985241041b03 remoteRepositoryId=11855d8c-ce01-4623-8085-8ac21d69292d transaction.localRevision=6 remoteFromLocalRevision=84
08:15:03.705 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDown: fromID=e3f4a47a-fd8b-45b2-90f9-985241041b03 from='https://localhost:37785/e3f4a47a-fd8b-45b2-90f9-985241041b03' toID=11855d8c-ce01-4623-8085-8ac21d69292d to='target/repo/jz6kpmym-vdhw0-local', fromRepoLocalSync=false
08:15:03.706 [main] INFO c.c.c.r.c.t.RestRepoTransport - getChangeSetDto: ChangeSetDto-cache-file NOT found: /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.test/target/repo/jz6kpmym-vdhw0-local/.cloudstore-repo/tmp/ChangeSetDto.e3f4a47a-fd8b-45b2-90f9-985241041b03.null.xml.gz
08:15:03.716 [pool-8-thread-1] INFO c.c.c.t.r.BackupRestoreRepoToRepoSyncIT - Synchronising...: 19.03%
08:15:03.748 [pool-4-thread-1] TRACE c.c.c.l.t.ChangeSetDtoBuilder - >>> buildChangeSetDto >>>
08:15:03.751 [pool-4-thread-1] TRACE c.c.c.l.t.ChangeSetDtoBuilder - localRepositoryId: e3f4a47a-fd8b-45b2-90f9-985241041b03
08:15:03.752 [pool-4-thread-1] TRACE c.c.c.l.t.ChangeSetDtoBuilder - remoteRepositoryId: 11855d8c-ce01-4623-8085-8ac21d69292d
08:15:03.752 [pool-4-thread-1] TRACE c.c.c.l.t.ChangeSetDtoBuilder - pathPrefix:
08:15:03.752 [pool-4-thread-1] INFO c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=e3f4a47a-fd8b-45b2-90f9-985241041b03 remoteRepositoryId=11855d8c-ce01-4623-8085-8ac21d69292d localRepositoryRevisionSynced=3 localRepositoryRevisionInProgress=6
08:15:03.753 [pool-4-thread-1] DEBUG c.c.c.l.t.ChangeSetDtoBuilder - toModificationDtos: Creating 0 ModificationDtos took 0 ms.
08:15:03.755 [pool-4-thread-1] TRACE c.c.c.l.t.ChangeSetDtoBuilder - >>> buildConfigPropSetDto >>>
08:15:03.755 [pool-4-thread-1] DEBUG c.c.c.l.t.ChangeSetDtoBuilder - buildConfigPropSetDto: pathPrefix is empty => returning null.
08:15:03.755 [pool-4-thread-1] TRACE c.c.c.l.t.ChangeSetDtoBuilder - <<< buildConfigPropSetDto <<< null
08:15:03.755 [pool-4-thread-1] TRACE c.c.c.l.t.ChangeSetDtoBuilder - <<< buildChangeSetDto <<<
08:15:03.761 [main] INFO c.c.c.r.c.t.RestRepoTransport - getChangeSetDto: Wrote ChangeSetDto-cache-file: /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.test/target/repo/jz6kpmym-vdhw0-local/.cloudstore-repo/tmp/ChangeSetDto.e3f4a47a-fd8b-45b2-90f9-985241041b03.null.xml.gz
08:15:03.761 [main] INFO c.c.c.t.r.AbstractRepoToRepoSyncIT - Synchronising...: 88.31%
08:15:03.762 [main] INFO c.c.c.t.r.AbstractRepoToRepoSyncIT - Synchronising...: 99.50%
08:15:03.783 [qtp1740661583-28] INFO c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=e3f4a47a-fd8b-45b2-90f9-985241041b03 remoteRepositoryId=11855d8c-ce01-4623-8085-8ac21d69292d localRepositoryRevisionSynced=6
08:15:03.790 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=11855d8c-ce01-4623-8085-8ac21d69292d remoteRepositoryId=e3f4a47a-fd8b-45b2-90f9-985241041b03 transaction.localRevision=87 remoteFromLocalRevision=6
08:15:03.792 [main] INFO c.c.c.t.r.AbstractRepoToRepoSyncIT - Synchronising...: 100.00%
08:15:03.795 [pool-8-thread-1] INFO c.c.c.t.r.BackupRestoreRepoToRepoSyncIT - Synchronising...: 24.15%
08:15:03.940 [main] INFO c.c.c.l.LocalRepoManagerImpl - [6731b6bd]getCloseDeferredMillis: closeDeferredMillis=20000
08:15:03.940 [main] INFO c.c.c.l.LocalRepoManagerImpl - [6731b6bd]close: Deferring shut down of real LocalRepoManager 20000 ms.
08:15:03.940 [main] INFO c.c.c.r.c.t.RestRepoTransport - getChangeSetDto: ChangeSetDto-cache-file NOT found: /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.test/target/repo/jz6kphk0-ii18v-local/.cloudstore-repo/tmp/ChangeSetDto.a97338ce-2273-46fd-8321-9659731968c3.6.xml.gz
08:15:03.941 [main] INFO c.c.c.l.LocalRepoManagerImpl - [7a4199e5]getCloseDeferredMillis: closeDeferredMillis=20000
08:15:03.941 [main] INFO c.c.c.l.LocalRepoManagerImpl - [7a4199e5]close: Deferring shut down of real LocalRepoManager 20000 ms.
Tests run: 4, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 144.465 sec - in co.codewizards.cloudstore.test.repotorepo.BasicRepoToRepoSyncIT
08:15:03.957 [qtp1876368826-28] INFO c.c.c.l.LocalRepoManagerImpl - [6df27125]<init>: localRoot='/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.test/target/repo/jz6kphk1-z0j0y-remote'
08:15:03.958 [qtp1876368826-28] INFO c.c.c.l.LocalRepoManagerImpl - [6df27125]initLockFile: Repository '/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.test/target/repo/jz6kphk1-z0j0y-remote' locked successfully.
08:15:03.980 [Thread-4] INFO o.e.j.s.AbstractConnector - Stopped ServerConnector@3c9045d0{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
08:15:03.981 [Thread-4] INFO o.e.j.s.session - node0 Stopped scavenging
08:15:03.988 [Thread-4] INFO o.e.j.s.h.ContextHandler - Stopped o.e.j.s.ServletContextHandler@56074756{/,null,UNAVAILABLE}
08:15:03.999 [Thread-4] INFO c.c.c.l.s.LocalServer - onStopOrFailure: File did not exist (could not delete): /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.test/target/jvm/bBU5m55YA1_27R-M2x0txQ/.cloudstore/localServerRunning.deleteToStop
08:15:04.015 [Thread-4] INFO o.e.j.s.AbstractConnector - Stopped ServerConnector@6cf7715a{SSL,[ssl, http/1.1]}{0.0.0.0:37785}
08:15:04.016 [Thread-4] INFO o.e.j.s.session - node0 Stopped scavenging
08:15:04.017 [Thread-4] INFO o.e.j.s.h.ContextHandler - Stopped o.e.j.s.ServletContextHandler@472bff38{/,null,UNAVAILABLE}
08:15:04.610 [qtp1876368826-28] INFO c.c.c.l.LocalRepoManagerImpl - [6df27125]initPersistenceManagerFactory: repositoryId=a97338ce-2273-46fd-8321-9659731968c3
08:15:04.610 [qtp1876368826-28] INFO c.c.c.l.LocalRepoManagerImpl - [6df27125]initPersistenceManagerFactory: Started up PersistenceManagerFactory successfully in 652 ms.
08:15:04.654 [qtp1876368826-28] INFO c.c.c.l.LocalRepoManagerImpl - [6df27125]getCloseDeferredMillis: closeDeferredMillis=0
08:15:04.654 [qtp1876368826-28] INFO c.c.c.l.LocalRepoManagerImpl - [6df27125]close: Closing real LocalRepoManager immediately.
08:15:04.654 [qtp1876368826-28] INFO c.c.c.l.LocalRepoManagerImpl - [6df27125]_close: Shutting down real LocalRepoManager.
08:15:05.169 [main] INFO c.c.c.r.c.t.RestRepoTransport - getAuthToken: got new AuthToken: clientRepositoryId=80ba59a7-32e1-4bb6-a446-d12f42b25542 serverRepositoryId=a97338ce-2273-46fd-8321-9659731968c3 renewalDateTime=2019-08-11T06:44:54.837Z expiryDateTime=2019-08-11T07:14:54.837Z
08:15:05.185 [qtp1876368826-29] INFO c.c.c.l.LocalRepoManagerImpl - [70c6654f]<init>: localRoot='/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.test/target/repo/jz6kphk1-z0j0y-remote'
08:15:05.188 [qtp1876368826-29] INFO c.c.c.l.LocalRepoManagerImpl - [70c6654f]initLockFile: Repository '/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.test/target/repo/jz6kphk1-z0j0y-remote' locked successfully.
08:15:06.406 [qtp1876368826-29] INFO c.c.c.l.LocalRepoManagerImpl - [70c6654f]initPersistenceManagerFactory: repositoryId=a97338ce-2273-46fd-8321-9659731968c3
08:15:06.407 [qtp1876368826-29] INFO c.c.c.l.LocalRepoManagerImpl - [70c6654f]initPersistenceManagerFactory: Started up PersistenceManagerFactory successfully in 1218 ms.
08:15:06.432 [qtp1876368826-29] INFO c.c.c.l.LocalRepoManagerImpl - [70c6654f]getCloseDeferredMillis: closeDeferredMillis=0
08:15:06.432 [qtp1876368826-29] INFO c.c.c.l.LocalRepoManagerImpl - [70c6654f]close: Closing real LocalRepoManager immediately.
08:15:06.433 [qtp1876368826-29] INFO c.c.c.l.LocalRepoManagerImpl - [70c6654f]_close: Shutting down real LocalRepoManager.
08:15:06.965 [pool-4-thread-1] INFO c.c.c.l.LocalRepoManagerImpl - [f784e96]<init>: localRoot='/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.test/target/repo/jz6kphk1-z0j0y-remote'
08:15:06.966 [pool-4-thread-1] INFO c.c.c.l.LocalRepoManagerImpl - [f784e96]initLockFile: Repository '/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.test/target/repo/jz6kphk1-z0j0y-remote' locked successfully.
08:15:07.487 [pool-4-thread-1] INFO c.c.c.l.LocalRepoManagerImpl - [f784e96]initPersistenceManagerFactory: repositoryId=a97338ce-2273-46fd-8321-9659731968c3
08:15:07.487 [pool-4-thread-1] INFO c.c.c.l.LocalRepoManagerImpl - [f784e96]initPersistenceManagerFactory: Started up PersistenceManagerFactory successfully in 521 ms.
08:15:07.509 [pool-4-thread-1] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 1.00%
08:15:07.524 [pool-4-thread-1] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 50.00%
08:15:07.545 [pool-4-thread-1] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 55.72%
08:15:07.571 [pool-4-thread-1] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 64.70%
08:15:07.579 [pool-4-thread-1] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 74.50%
08:15:07.597 [pool-4-thread-1] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 84.30%
08:15:07.619 [pool-4-thread-1] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 94.10%
08:15:07.644 [pool-4-thread-1] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 100.00%
08:15:07.679 [pool-4-thread-1] TRACE c.c.c.l.t.ChangeSetDtoBuilder - >>> buildChangeSetDto >>>
08:15:07.681 [pool-4-thread-1] TRACE c.c.c.l.t.ChangeSetDtoBuilder - localRepositoryId: a97338ce-2273-46fd-8321-9659731968c3
08:15:07.682 [pool-4-thread-1] TRACE c.c.c.l.t.ChangeSetDtoBuilder - remoteRepositoryId: 80ba59a7-32e1-4bb6-a446-d12f42b25542
08:15:07.682 [pool-4-thread-1] TRACE c.c.c.l.t.ChangeSetDtoBuilder - pathPrefix:
08:15:07.682 [pool-4-thread-1] INFO c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=a97338ce-2273-46fd-8321-9659731968c3 remoteRepositoryId=80ba59a7-32e1-4bb6-a446-d12f42b25542 localRepositoryRevisionSynced=6 localRepositoryRevisionInProgress=8
08:15:07.689 [pool-4-thread-1] DEBUG c.c.c.l.t.ChangeSetDtoBuilder - toModificationDtos: Creating 0 ModificationDtos took 0 ms.
08:15:07.694 [pool-4-thread-1] TRACE c.c.c.l.t.ChangeSetDtoBuilder - >>> buildConfigPropSetDto >>>
08:15:07.694 [pool-4-thread-1] DEBUG c.c.c.l.t.ChangeSetDtoBuilder - buildConfigPropSetDto: pathPrefix is empty => returning null.
08:15:07.694 [pool-4-thread-1] TRACE c.c.c.l.t.ChangeSetDtoBuilder - <<< buildConfigPropSetDto <<< null
08:15:07.694 [pool-4-thread-1] TRACE c.c.c.l.t.ChangeSetDtoBuilder - <<< buildChangeSetDto <<<
08:15:07.695 [pool-4-thread-1] INFO c.c.c.l.LocalRepoManagerImpl - [f784e96]getCloseDeferredMillis: closeDeferredMillis=0
08:15:07.695 [pool-4-thread-1] INFO c.c.c.l.LocalRepoManagerImpl - [f784e96]close: Closing real LocalRepoManager immediately.
08:15:07.695 [pool-4-thread-1] INFO c.c.c.l.LocalRepoManagerImpl - [f784e96]_close: Shutting down real LocalRepoManager.
08:15:08.084 [main] INFO c.c.c.r.c.t.RestRepoTransport - getChangeSetDto: Wrote ChangeSetDto-cache-file: /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.test/target/repo/jz6kphk0-ii18v-local/.cloudstore-repo/tmp/ChangeSetDto.a97338ce-2273-46fd-8321-9659731968c3.6.xml.gz
08:15:08.085 [main] INFO c.c.c.t.r.BackupRestoreRepoToRepoSyncIT - Synchronising...: 38.56%
08:15:08.085 [main] INFO c.c.c.t.r.BackupRestoreRepoToRepoSyncIT - Synchronising...: 49.75%
08:15:08.101 [qtp1876368826-25] INFO c.c.c.l.LocalRepoManagerImpl - [86a9878]<init>: localRoot='/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.test/target/repo/jz6kphk1-z0j0y-remote'
08:15:08.101 [qtp1876368826-25] INFO c.c.c.l.LocalRepoManagerImpl - [86a9878]initLockFile: Repository '/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.test/target/repo/jz6kphk1-z0j0y-remote' locked successfully.
08:15:08.643 [qtp1876368826-25] INFO c.c.c.l.LocalRepoManagerImpl - [86a9878]initPersistenceManagerFactory: repositoryId=a97338ce-2273-46fd-8321-9659731968c3
08:15:08.643 [qtp1876368826-25] INFO c.c.c.l.LocalRepoManagerImpl - [86a9878]initPersistenceManagerFactory: Started up PersistenceManagerFactory successfully in 541 ms.
08:15:08.670 [qtp1876368826-25] INFO c.c.c.l.LocalRepoManagerImpl - [86a9878]getCloseDeferredMillis: closeDeferredMillis=0
08:15:08.670 [qtp1876368826-25] INFO c.c.c.l.LocalRepoManagerImpl - [86a9878]close: Closing real LocalRepoManager immediately.
08:15:08.670 [qtp1876368826-25] INFO c.c.c.l.LocalRepoManagerImpl - [86a9878]_close: Shutting down real LocalRepoManager.
08:15:08.999 [qtp1876368826-25] INFO c.c.c.l.LocalRepoManagerImpl - [54568206]<init>: localRoot='/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.test/target/repo/jz6kphk1-z0j0y-remote'
08:15:08.999 [qtp1876368826-25] INFO c.c.c.l.LocalRepoManagerImpl - [54568206]initLockFile: Repository '/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.test/target/repo/jz6kphk1-z0j0y-remote' locked successfully.
08:15:09.546 [qtp1876368826-25] INFO c.c.c.l.LocalRepoManagerImpl - [54568206]initPersistenceManagerFactory: repositoryId=a97338ce-2273-46fd-8321-9659731968c3
08:15:09.546 [qtp1876368826-25] INFO c.c.c.l.LocalRepoManagerImpl - [54568206]initPersistenceManagerFactory: Started up PersistenceManagerFactory successfully in 546 ms.
08:15:09.608 [qtp1876368826-25] INFO c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=a97338ce-2273-46fd-8321-9659731968c3 remoteRepositoryId=80ba59a7-32e1-4bb6-a446-d12f42b25542 localRepositoryRevisionSynced=8
08:15:09.616 [qtp1876368826-25] INFO c.c.c.l.LocalRepoManagerImpl - [54568206]getCloseDeferredMillis: closeDeferredMillis=0
08:15:09.617 [qtp1876368826-25] INFO c.c.c.l.LocalRepoManagerImpl - [54568206]close: Closing real LocalRepoManager immediately.
08:15:09.617 [qtp1876368826-25] INFO c.c.c.l.LocalRepoManagerImpl - [54568206]_close: Shutting down real LocalRepoManager.
08:15:09.982 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=80ba59a7-32e1-4bb6-a446-d12f42b25542 remoteRepositoryId=a97338ce-2273-46fd-8321-9659731968c3 transaction.localRevision=89 remoteFromLocalRevision=8
08:15:09.991 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncUp: fromID=80ba59a7-32e1-4bb6-a446-d12f42b25542 from='target/repo/jz6kphk0-ii18v-local' toID=a97338ce-2273-46fd-8321-9659731968c3 to='https://localhost:35971/a97338ce-2273-46fd-8321-9659731968c3'
08:15:10.011 [qtp1876368826-25] INFO c.c.c.l.LocalRepoManagerImpl - [2c356b8a]<init>: localRoot='/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.test/target/repo/jz6kphk1-z0j0y-remote'
08:15:10.012 [qtp1876368826-25] INFO c.c.c.l.LocalRepoManagerImpl - [2c356b8a]initLockFile: Repository '/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.test/target/repo/jz6kphk1-z0j0y-remote' locked successfully.
08:15:10.615 [qtp1876368826-25] INFO c.c.c.l.LocalRepoManagerImpl - [2c356b8a]initPersistenceManagerFactory: repositoryId=a97338ce-2273-46fd-8321-9659731968c3
08:15:10.616 [qtp1876368826-25] INFO c.c.c.l.LocalRepoManagerImpl - [2c356b8a]initPersistenceManagerFactory: Started up PersistenceManagerFactory successfully in 603 ms.
08:15:10.647 [qtp1876368826-25] INFO c.c.c.l.LocalRepoManagerImpl - [2c356b8a]getCloseDeferredMillis: closeDeferredMillis=0
08:15:10.647 [qtp1876368826-25] INFO c.c.c.l.LocalRepoManagerImpl - [2c356b8a]close: Closing real LocalRepoManager immediately.
08:15:10.647 [qtp1876368826-25] INFO c.c.c.l.LocalRepoManagerImpl - [2c356b8a]_close: Shutting down real LocalRepoManager.
08:15:10.993 [qtp1876368826-25] INFO c.c.c.l.LocalRepoManagerImpl - [7280525f]<init>: localRoot='/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.test/target/repo/jz6kphk1-z0j0y-remote'
08:15:10.994 [qtp1876368826-25] INFO c.c.c.l.LocalRepoManagerImpl - [7280525f]initLockFile: Repository '/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.test/target/repo/jz6kphk1-z0j0y-remote' locked successfully.
08:15:11.580 [qtp1876368826-25] INFO c.c.c.l.LocalRepoManagerImpl - [7280525f]initPersistenceManagerFactory: repositoryId=a97338ce-2273-46fd-8321-9659731968c3
08:15:11.582 [qtp1876368826-25] INFO c.c.c.l.LocalRepoManagerImpl - [7280525f]initPersistenceManagerFactory: Started up PersistenceManagerFactory successfully in 588 ms.
08:15:11.614 [qtp1876368826-25] INFO c.c.c.l.LocalRepoManagerImpl - [7280525f]getCloseDeferredMillis: closeDeferredMillis=0
08:15:11.614 [qtp1876368826-25] INFO c.c.c.l.LocalRepoManagerImpl - [7280525f]close: Closing real LocalRepoManager immediately.
08:15:11.614 [qtp1876368826-25] INFO c.c.c.l.LocalRepoManagerImpl - [7280525f]_close: Shutting down real LocalRepoManager.
08:15:12.096 [main] TRACE c.c.c.l.t.ChangeSetDtoBuilder - >>> buildChangeSetDto >>>
08:15:12.098 [main] TRACE c.c.c.l.t.ChangeSetDtoBuilder - localRepositoryId: 80ba59a7-32e1-4bb6-a446-d12f42b25542
08:15:12.098 [main] TRACE c.c.c.l.t.ChangeSetDtoBuilder - remoteRepositoryId: a97338ce-2273-46fd-8321-9659731968c3
08:15:12.099 [main] TRACE c.c.c.l.t.ChangeSetDtoBuilder - pathPrefix:
08:15:12.099 [main] INFO c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=80ba59a7-32e1-4bb6-a446-d12f42b25542 remoteRepositoryId=a97338ce-2273-46fd-8321-9659731968c3 localRepositoryRevisionSynced=84 localRepositoryRevisionInProgress=89
08:15:12.105 [main] DEBUG c.c.c.l.t.ChangeSetDtoBuilder - toModificationDtos: Creating 0 ModificationDtos took 0 ms.
08:15:12.129 [main] TRACE c.c.c.l.t.ChangeSetDtoBuilder - >>> buildConfigPropSetDto >>>
08:15:12.130 [main] DEBUG c.c.c.l.t.ChangeSetDtoBuilder - buildConfigPropSetDto: pathPrefix is empty => returning null.
08:15:12.130 [main] TRACE c.c.c.l.t.ChangeSetDtoBuilder - <<< buildConfigPropSetDto <<< null
08:15:12.130 [main] TRACE c.c.c.l.t.ChangeSetDtoBuilder - <<< buildChangeSetDto <<<
08:15:12.130 [main] INFO c.c.c.t.r.BackupRestoreRepoToRepoSyncIT - Synchronising...: 56.31%
08:15:12.131 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path='/3'
08:15:12.155 [qtp1876368826-28] INFO c.c.c.l.LocalRepoManagerImpl - [22b5bc54]<init>: localRoot='/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.test/target/repo/jz6kphk1-z0j0y-remote'
08:15:12.156 [qtp1876368826-28] INFO c.c.c.l.LocalRepoManagerImpl - [22b5bc54]initLockFile: Repository '/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.test/target/repo/jz6kphk1-z0j0y-remote' locked successfully.
08:15:12.726 [qtp1876368826-28] INFO c.c.c.l.LocalRepoManagerImpl - [22b5bc54]initPersistenceManagerFactory: repositoryId=a97338ce-2273-46fd-8321-9659731968c3
08:15:12.726 [qtp1876368826-28] INFO c.c.c.l.LocalRepoManagerImpl - [22b5bc54]initPersistenceManagerFactory: Started up PersistenceManagerFactory successfully in 570 ms.
08:15:12.752 [qtp1876368826-28] INFO c.c.c.l.LocalRepoManagerImpl - [22b5bc54]getCloseDeferredMillis: closeDeferredMillis=0
08:15:12.753 [qtp1876368826-28] INFO c.c.c.l.LocalRepoManagerImpl - [22b5bc54]close: Closing real LocalRepoManager immediately.
08:15:12.753 [qtp1876368826-28] INFO c.c.c.l.LocalRepoManagerImpl - [22b5bc54]_close: Shutting down real LocalRepoManager.
08:15:13.194 [qtp1876368826-28] INFO c.c.c.l.LocalRepoManagerImpl - [1470e269]<init>: localRoot='/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.test/target/repo/jz6kphk1-z0j0y-remote'
08:15:13.194 [qtp1876368826-28] INFO c.c.c.l.LocalRepoManagerImpl - [1470e269]initLockFile: Repository '/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.test/target/repo/jz6kphk1-z0j0y-remote' locked successfully.
08:15:13.778 [qtp1876368826-28] INFO c.c.c.l.LocalRepoManagerImpl - [1470e269]initPersistenceManagerFactory: repositoryId=a97338ce-2273-46fd-8321-9659731968c3
08:15:13.779 [qtp1876368826-28] INFO c.c.c.l.LocalRepoManagerImpl - [1470e269]initPersistenceManagerFactory: Started up PersistenceManagerFactory successfully in 585 ms.
08:15:13.871 [qtp1876368826-28] INFO c.c.c.l.LocalRepoManagerImpl - [1470e269]getCloseDeferredMillis: closeDeferredMillis=0
08:15:13.871 [qtp1876368826-28] INFO c.c.c.l.LocalRepoManagerImpl - [1470e269]close: Closing real LocalRepoManager immediately.
08:15:13.871 [qtp1876368826-28] INFO c.c.c.l.LocalRepoManagerImpl - [1470e269]_close: Shutting down real LocalRepoManager.
08:15:14.220 [main] INFO c.c.c.t.r.BackupRestoreRepoToRepoSyncIT - Synchronising...: 62.42%
08:15:14.220 [main] INFO c.c.c.t.r.BackupRestoreRepoToRepoSyncIT - Synchronising...: 68.52%
08:15:14.221 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/3/zzz'
08:15:14.265 [qtp1876368826-28] INFO c.c.c.l.LocalRepoManagerImpl - [3102c8bc]<init>: localRoot='/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.test/target/repo/jz6kphk1-z0j0y-remote'
08:15:14.266 [qtp1876368826-28] INFO c.c.c.l.LocalRepoManagerImpl - [3102c8bc]initLockFile: Repository '/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.test/target/repo/jz6kphk1-z0j0y-remote' locked successfully.
08:15:14.812 [qtp1876368826-28] INFO c.c.c.l.LocalRepoManagerImpl - [3102c8bc]initPersistenceManagerFactory: repositoryId=a97338ce-2273-46fd-8321-9659731968c3
08:15:14.812 [qtp1876368826-28] INFO c.c.c.l.LocalRepoManagerImpl - [3102c8bc]initPersistenceManagerFactory: Started up PersistenceManagerFactory successfully in 546 ms.
08:15:14.837 [qtp1876368826-28] INFO c.c.c.l.LocalRepoManagerImpl - [3102c8bc]getCloseDeferredMillis: closeDeferredMillis=0
08:15:14.837 [qtp1876368826-28] INFO c.c.c.l.LocalRepoManagerImpl - [3102c8bc]close: Closing real LocalRepoManager immediately.
08:15:14.837 [qtp1876368826-28] INFO c.c.c.l.LocalRepoManagerImpl - [3102c8bc]_close: Shutting down real LocalRepoManager.
08:15:15.206 [pool-4-thread-1] INFO c.c.c.l.LocalRepoManagerImpl - [43c688b5]<init>: localRoot='/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.test/target/repo/jz6kphk1-z0j0y-remote'
08:15:15.207 [pool-4-thread-1] INFO c.c.c.l.LocalRepoManagerImpl - [43c688b5]initLockFile: Repository '/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.test/target/repo/jz6kphk1-z0j0y-remote' locked successfully.
08:15:15.766 [pool-4-thread-1] INFO c.c.c.l.LocalRepoManagerImpl - [43c688b5]initPersistenceManagerFactory: repositoryId=a97338ce-2273-46fd-8321-9659731968c3
08:15:15.767 [pool-4-thread-1] INFO c.c.c.l.LocalRepoManagerImpl - [43c688b5]initPersistenceManagerFactory: Started up PersistenceManagerFactory successfully in 560 ms.
08:15:15.811 [pool-4-thread-1] INFO c.c.c.l.LocalRepoManagerImpl - [43c688b5]getCloseDeferredMillis: closeDeferredMillis=0
08:15:15.811 [pool-4-thread-1] INFO c.c.c.l.LocalRepoManagerImpl - [43c688b5]close: Closing real LocalRepoManager immediately.
08:15:15.811 [pool-4-thread-1] INFO c.c.c.l.LocalRepoManagerImpl - [43c688b5]_close: Shutting down real LocalRepoManager.
08:15:16.135 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='6629a1513e594f818910b337c5116e71ffbdf056' to.sha1='<NoInstanceOf_NormalFileDto>'): /3/zzz
Aug 11, 2019 8:15:16 AM org.glassfish.jersey.client.JerseyInvocation validateHttpMethodAndEntity
WARNING: Entity must not be null for http method PUT.
08:15:16.167 [qtp1876368826-30] INFO c.c.c.l.LocalRepoManagerImpl - [15b03d]<init>: localRoot='/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.test/target/repo/jz6kphk1-z0j0y-remote'
08:15:16.168 [qtp1876368826-30] INFO c.c.c.l.LocalRepoManagerImpl - [15b03d]initLockFile: Repository '/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.test/target/repo/jz6kphk1-z0j0y-remote' locked successfully.
08:15:16.707 [qtp1876368826-30] INFO c.c.c.l.LocalRepoManagerImpl - [15b03d]initPersistenceManagerFactory: repositoryId=a97338ce-2273-46fd-8321-9659731968c3
08:15:16.707 [qtp1876368826-30] INFO c.c.c.l.LocalRepoManagerImpl - [15b03d]initPersistenceManagerFactory: Started up PersistenceManagerFactory successfully in 539 ms.
08:15:16.731 [qtp1876368826-30] INFO c.c.c.l.LocalRepoManagerImpl - [15b03d]getCloseDeferredMillis: closeDeferredMillis=0
08:15:16.732 [qtp1876368826-30] INFO c.c.c.l.LocalRepoManagerImpl - [15b03d]close: Closing real LocalRepoManager immediately.
08:15:16.732 [qtp1876368826-30] INFO c.c.c.l.LocalRepoManagerImpl - [15b03d]_close: Shutting down real LocalRepoManager.
08:15:17.142 [qtp1876368826-30] INFO c.c.c.l.LocalRepoManagerImpl - [205eaf80]<init>: localRoot='/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.test/target/repo/jz6kphk1-z0j0y-remote'
08:15:17.143 [qtp1876368826-30] INFO c.c.c.l.LocalRepoManagerImpl - [205eaf80]initLockFile: Repository '/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.test/target/repo/jz6kphk1-z0j0y-remote' locked successfully.
08:15:17.657 [qtp1876368826-30] INFO c.c.c.l.LocalRepoManagerImpl - [205eaf80]initPersistenceManagerFactory: repositoryId=a97338ce-2273-46fd-8321-9659731968c3
08:15:17.658 [qtp1876368826-30] INFO c.c.c.l.LocalRepoManagerImpl - [205eaf80]initPersistenceManagerFactory: Started up PersistenceManagerFactory successfully in 515 ms.
08:15:17.762 [qtp1876368826-30] INFO c.c.c.l.LocalRepoManagerImpl - [205eaf80]getCloseDeferredMillis: closeDeferredMillis=0
08:15:17.762 [qtp1876368826-30] INFO c.c.c.l.LocalRepoManagerImpl - [205eaf80]close: Closing real LocalRepoManager immediately.
08:15:17.762 [qtp1876368826-30] INFO c.c.c.l.LocalRepoManagerImpl - [205eaf80]_close: Shutting down real LocalRepoManager.
08:15:18.270 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=16, fromRepositoryId="FileInProgressMarker", toRepositoryId="16", path="80ba59a7-32e1-4bb6-a446-d12f42b25542"] on repo=80ba59a7-32e1-4bb6-a446-d12f42b25542
08:15:18.272 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/3/zzz'
08:15:18.408 [qtp1876368826-90] INFO c.c.c.l.LocalRepoManagerImpl - [7cbf2b90]<init>: localRoot='/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.test/target/repo/jz6kphk1-z0j0y-remote'
08:15:18.409 [qtp1876368826-90] INFO c.c.c.l.LocalRepoManagerImpl - [7cbf2b90]initLockFile: Repository '/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.test/target/repo/jz6kphk1-z0j0y-remote' locked successfully.
08:15:18.974 [qtp1876368826-90] INFO c.c.c.l.LocalRepoManagerImpl - [7cbf2b90]initPersistenceManagerFactory: repositoryId=a97338ce-2273-46fd-8321-9659731968c3
08:15:18.975 [qtp1876368826-90] INFO c.c.c.l.LocalRepoManagerImpl - [7cbf2b90]initPersistenceManagerFactory: Started up PersistenceManagerFactory successfully in 566 ms.
08:15:18.999 [qtp1876368826-90] INFO c.c.c.l.LocalRepoManagerImpl - [7cbf2b90]getCloseDeferredMillis: closeDeferredMillis=0
08:15:18.999 [qtp1876368826-90] INFO c.c.c.l.LocalRepoManagerImpl - [7cbf2b90]close: Closing real LocalRepoManager immediately.
08:15:18.999 [qtp1876368826-90] INFO c.c.c.l.LocalRepoManagerImpl - [7cbf2b90]_close: Shutting down real LocalRepoManager.
08:15:19.304 [qtp1876368826-90] INFO c.c.c.l.LocalRepoManagerImpl - [5cd81971]<init>: localRoot='/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.test/target/repo/jz6kphk1-z0j0y-remote'
08:15:19.304 [qtp1876368826-90] INFO c.c.c.l.LocalRepoManagerImpl - [5cd81971]initLockFile: Repository '/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.test/target/repo/jz6kphk1-z0j0y-remote' locked successfully.
08:15:19.869 [qtp1876368826-90] INFO c.c.c.l.LocalRepoManagerImpl - [5cd81971]initPersistenceManagerFactory: repositoryId=a97338ce-2273-46fd-8321-9659731968c3
08:15:19.869 [qtp1876368826-90] INFO c.c.c.l.LocalRepoManagerImpl - [5cd81971]initPersistenceManagerFactory: Started up PersistenceManagerFactory successfully in 565 ms.
08:15:19.937 [qtp1876368826-90] INFO c.c.c.l.LocalRepoManagerImpl - [5cd81971]getCloseDeferredMillis: closeDeferredMillis=0
08:15:19.937 [qtp1876368826-90] INFO c.c.c.l.LocalRepoManagerImpl - [5cd81971]close: Closing real LocalRepoManager immediately.
08:15:19.937 [qtp1876368826-90] INFO c.c.c.l.LocalRepoManagerImpl - [5cd81971]_close: Shutting down real LocalRepoManager.
08:15:20.382 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 836318 bytes in 2110 ms. path='/3/zzz'
Aug 11, 2019 8:15:20 AM org.glassfish.jersey.client.JerseyInvocation validateHttpMethodAndEntity
WARNING: Entity must not be null for http method PUT.
08:15:20.410 [qtp1876368826-165] INFO c.c.c.l.LocalRepoManagerImpl - [2ef0fce0]<init>: localRoot='/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.test/target/repo/jz6kphk1-z0j0y-remote'
08:15:20.411 [qtp1876368826-165] INFO c.c.c.l.LocalRepoManagerImpl - [2ef0fce0]initLockFile: Repository '/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.test/target/repo/jz6kphk1-z0j0y-remote' locked successfully.
08:15:20.922 [qtp1876368826-165] INFO c.c.c.l.LocalRepoManagerImpl - [2ef0fce0]initPersistenceManagerFactory: repositoryId=a97338ce-2273-46fd-8321-9659731968c3
08:15:20.922 [qtp1876368826-165] INFO c.c.c.l.LocalRepoManagerImpl - [2ef0fce0]initPersistenceManagerFactory: Started up PersistenceManagerFactory successfully in 511 ms.
08:15:20.950 [qtp1876368826-165] INFO c.c.c.l.LocalRepoManagerImpl - [2ef0fce0]getCloseDeferredMillis: closeDeferredMillis=0
08:15:20.950 [qtp1876368826-165] INFO c.c.c.l.LocalRepoManagerImpl - [2ef0fce0]close: Closing real LocalRepoManager immediately.
08:15:20.950 [qtp1876368826-165] INFO c.c.c.l.LocalRepoManagerImpl - [2ef0fce0]_close: Shutting down real LocalRepoManager.
08:15:21.267 [qtp1876368826-165] INFO c.c.c.l.LocalRepoManagerImpl - [7a8033a3]<init>: localRoot='/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.test/target/repo/jz6kphk1-z0j0y-remote'
08:15:21.268 [qtp1876368826-165] INFO c.c.c.l.LocalRepoManagerImpl - [7a8033a3]initLockFile: Repository '/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.test/target/repo/jz6kphk1-z0j0y-remote' locked successfully.
08:15:21.804 [qtp1876368826-165] INFO c.c.c.l.LocalRepoManagerImpl - [7a8033a3]initPersistenceManagerFactory: repositoryId=a97338ce-2273-46fd-8321-9659731968c3
08:15:21.804 [qtp1876368826-165] INFO c.c.c.l.LocalRepoManagerImpl - [7a8033a3]initPersistenceManagerFactory: Started up PersistenceManagerFactory successfully in 535 ms.
08:15:21.846 [qtp1876368826-165] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_40fa37ec00c761c7dbb6ebdee6d4a260b922f5f4_0 into destFile zzz
08:15:21.938 [qtp1876368826-165] INFO c.c.c.l.LocalRepoManagerImpl - [7a8033a3]getCloseDeferredMillis: closeDeferredMillis=0
08:15:21.938 [qtp1876368826-165] INFO c.c.c.l.LocalRepoManagerImpl - [7a8033a3]close: Closing real LocalRepoManager immediately.
08:15:21.938 [qtp1876368826-165] INFO c.c.c.l.LocalRepoManagerImpl - [7a8033a3]_close: Shutting down real LocalRepoManager.
08:15:22.602 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=16, fromRepositoryId="FileInProgressMarker", toRepositoryId="16", path="80ba59a7-32e1-4bb6-a446-d12f42b25542"] on repo=80ba59a7-32e1-4bb6-a446-d12f42b25542
08:15:22.612 [main] INFO c.c.c.t.r.BackupRestoreRepoToRepoSyncIT - Synchronising...: 72.59%
08:15:22.630 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=80ba59a7-32e1-4bb6-a446-d12f42b25542 remoteRepositoryId=a97338ce-2273-46fd-8321-9659731968c3 localRepositoryRevisionSynced=89
08:15:22.653 [qtp1876368826-25] INFO c.c.c.l.LocalRepoManagerImpl - [44575ab0]<init>: localRoot='/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.test/target/repo/jz6kphk1-z0j0y-remote'
08:15:22.654 [qtp1876368826-25] INFO c.c.c.l.LocalRepoManagerImpl - [44575ab0]initLockFile: Repository '/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.test/target/repo/jz6kphk1-z0j0y-remote' locked successfully.
08:15:23.163 [qtp1876368826-25] INFO c.c.c.l.LocalRepoManagerImpl - [44575ab0]initPersistenceManagerFactory: repositoryId=a97338ce-2273-46fd-8321-9659731968c3
08:15:23.164 [qtp1876368826-25] INFO c.c.c.l.LocalRepoManagerImpl - [44575ab0]initPersistenceManagerFactory: Started up PersistenceManagerFactory successfully in 510 ms.
08:15:23.188 [qtp1876368826-25] INFO c.c.c.l.LocalRepoManagerImpl - [44575ab0]getCloseDeferredMillis: closeDeferredMillis=0
08:15:23.188 [qtp1876368826-25] INFO c.c.c.l.LocalRepoManagerImpl - [44575ab0]close: Closing real LocalRepoManager immediately.
08:15:23.188 [qtp1876368826-25] INFO c.c.c.l.LocalRepoManagerImpl - [44575ab0]_close: Shutting down real LocalRepoManager.
08:15:23.491 [qtp1876368826-25] INFO c.c.c.l.LocalRepoManagerImpl - [6e683e9f]<init>: localRoot='/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.test/target/repo/jz6kphk1-z0j0y-remote'
08:15:23.493 [qtp1876368826-25] INFO c.c.c.l.LocalRepoManagerImpl - [6e683e9f]initLockFile: Repository '/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.test/target/repo/jz6kphk1-z0j0y-remote' locked successfully.
08:15:24.016 [qtp1876368826-25] INFO c.c.c.l.LocalRepoManagerImpl - [6e683e9f]initPersistenceManagerFactory: repositoryId=a97338ce-2273-46fd-8321-9659731968c3
08:15:24.016 [qtp1876368826-25] INFO c.c.c.l.LocalRepoManagerImpl - [6e683e9f]initPersistenceManagerFactory: Started up PersistenceManagerFactory successfully in 522 ms.
08:15:24.060 [qtp1876368826-25] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=a97338ce-2273-46fd-8321-9659731968c3 remoteRepositoryId=80ba59a7-32e1-4bb6-a446-d12f42b25542 transaction.localRevision=15 remoteFromLocalRevision=89
08:15:24.071 [qtp1876368826-25] INFO c.c.c.l.LocalRepoManagerImpl - [6e683e9f]getCloseDeferredMillis: closeDeferredMillis=0
08:15:24.071 [qtp1876368826-25] INFO c.c.c.l.LocalRepoManagerImpl - [6e683e9f]close: Closing real LocalRepoManager immediately.
08:15:24.071 [qtp1876368826-25] INFO c.c.c.l.LocalRepoManagerImpl - [6e683e9f]_close: Shutting down real LocalRepoManager.
08:15:24.375 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDown: fromID=a97338ce-2273-46fd-8321-9659731968c3 from='https://localhost:35971/a97338ce-2273-46fd-8321-9659731968c3' toID=80ba59a7-32e1-4bb6-a446-d12f42b25542 to='target/repo/jz6kphk0-ii18v-local', fromRepoLocalSync=false
08:15:24.377 [main] INFO c.c.c.r.c.t.RestRepoTransport - getChangeSetDto: ChangeSetDto-cache-file NOT found: /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.test/target/repo/jz6kphk0-ii18v-local/.cloudstore-repo/tmp/ChangeSetDto.a97338ce-2273-46fd-8321-9659731968c3.null.xml.gz
08:15:24.392 [qtp1876368826-25] INFO c.c.c.l.LocalRepoManagerImpl - [62d2e218]<init>: localRoot='/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.test/target/repo/jz6kphk1-z0j0y-remote'
08:15:24.392 [qtp1876368826-25] INFO c.c.c.l.LocalRepoManagerImpl - [62d2e218]initLockFile: Repository '/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.test/target/repo/jz6kphk1-z0j0y-remote' locked successfully.
08:15:24.924 [qtp1876368826-25] INFO c.c.c.l.LocalRepoManagerImpl - [62d2e218]initPersistenceManagerFactory: repositoryId=a97338ce-2273-46fd-8321-9659731968c3
08:15:24.924 [qtp1876368826-25] INFO c.c.c.l.LocalRepoManagerImpl - [62d2e218]initPersistenceManagerFactory: Started up PersistenceManagerFactory successfully in 531 ms.
08:15:24.948 [qtp1876368826-25] INFO c.c.c.l.LocalRepoManagerImpl - [62d2e218]getCloseDeferredMillis: closeDeferredMillis=0
08:15:24.948 [qtp1876368826-25] INFO c.c.c.l.LocalRepoManagerImpl - [62d2e218]close: Closing real LocalRepoManager immediately.
08:15:24.948 [qtp1876368826-25] INFO c.c.c.l.LocalRepoManagerImpl - [62d2e218]_close: Shutting down real LocalRepoManager.
08:15:25.261 [pool-4-thread-1] INFO c.c.c.l.LocalRepoManagerImpl - [372bcf44]<init>: localRoot='/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.test/target/repo/jz6kphk1-z0j0y-remote'
08:15:25.262 [pool-4-thread-1] INFO c.c.c.l.LocalRepoManagerImpl - [372bcf44]initLockFile: Repository '/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.test/target/repo/jz6kphk1-z0j0y-remote' locked successfully.
08:15:25.962 [pool-4-thread-1] INFO c.c.c.l.LocalRepoManagerImpl - [372bcf44]initPersistenceManagerFactory: repositoryId=a97338ce-2273-46fd-8321-9659731968c3
08:15:25.962 [pool-4-thread-1] INFO c.c.c.l.LocalRepoManagerImpl - [372bcf44]initPersistenceManagerFactory: Started up PersistenceManagerFactory successfully in 699 ms.
08:15:26.021 [pool-4-thread-1] TRACE c.c.c.l.t.ChangeSetDtoBuilder - >>> buildChangeSetDto >>>
08:15:26.023 [pool-4-thread-1] TRACE c.c.c.l.t.ChangeSetDtoBuilder - localRepositoryId: a97338ce-2273-46fd-8321-9659731968c3
08:15:26.023 [pool-4-thread-1] TRACE c.c.c.l.t.ChangeSetDtoBuilder - remoteRepositoryId: 80ba59a7-32e1-4bb6-a446-d12f42b25542
08:15:26.023 [pool-4-thread-1] TRACE c.c.c.l.t.ChangeSetDtoBuilder - pathPrefix:
08:15:26.023 [pool-4-thread-1] INFO c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=a97338ce-2273-46fd-8321-9659731968c3 remoteRepositoryId=80ba59a7-32e1-4bb6-a446-d12f42b25542 localRepositoryRevisionSynced=8 localRepositoryRevisionInProgress=15
08:15:26.031 [pool-4-thread-1] DEBUG c.c.c.l.t.ChangeSetDtoBuilder - toModificationDtos: Creating 0 ModificationDtos took 0 ms.
08:15:26.039 [pool-4-thread-1] TRACE c.c.c.l.t.ChangeSetDtoBuilder - >>> buildConfigPropSetDto >>>
08:15:26.039 [pool-4-thread-1] DEBUG c.c.c.l.t.ChangeSetDtoBuilder - buildConfigPropSetDto: pathPrefix is empty => returning null.
08:15:26.039 [pool-4-thread-1] TRACE c.c.c.l.t.ChangeSetDtoBuilder - <<< buildConfigPropSetDto <<< null
08:15:26.039 [pool-4-thread-1] TRACE c.c.c.l.t.ChangeSetDtoBuilder - <<< buildChangeSetDto <<<
08:15:26.040 [pool-4-thread-1] INFO c.c.c.l.LocalRepoManagerImpl - [372bcf44]getCloseDeferredMillis: closeDeferredMillis=0
08:15:26.040 [pool-4-thread-1] INFO c.c.c.l.LocalRepoManagerImpl - [372bcf44]close: Closing real LocalRepoManager immediately.
08:15:26.040 [pool-4-thread-1] INFO c.c.c.l.LocalRepoManagerImpl - [372bcf44]_close: Shutting down real LocalRepoManager.
08:15:26.422 [main] INFO c.c.c.r.c.t.RestRepoTransport - getChangeSetDto: Wrote ChangeSetDto-cache-file: /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.test/target/repo/jz6kphk0-ii18v-local/.cloudstore-repo/tmp/ChangeSetDto.a97338ce-2273-46fd-8321-9659731968c3.null.xml.gz
08:15:26.423 [main] INFO c.c.c.t.r.BackupRestoreRepoToRepoSyncIT - Synchronising...: 88.31%
08:15:26.423 [main] INFO c.c.c.t.r.BackupRestoreRepoToRepoSyncIT - Synchronising...: 99.50%
08:15:26.445 [qtp1876368826-90] INFO c.c.c.l.LocalRepoManagerImpl - [4e641c9]<init>: localRoot='/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.test/target/repo/jz6kphk1-z0j0y-remote'
08:15:26.446 [qtp1876368826-90] INFO c.c.c.l.LocalRepoManagerImpl - [4e641c9]initLockFile: Repository '/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.test/target/repo/jz6kphk1-z0j0y-remote' locked successfully.
08:15:27.079 [qtp1876368826-90] INFO c.c.c.l.LocalRepoManagerImpl - [4e641c9]initPersistenceManagerFactory: repositoryId=a97338ce-2273-46fd-8321-9659731968c3
08:15:27.080 [qtp1876368826-90] INFO c.c.c.l.LocalRepoManagerImpl - [4e641c9]initPersistenceManagerFactory: Started up PersistenceManagerFactory successfully in 634 ms.
08:15:27.114 [qtp1876368826-90] INFO c.c.c.l.LocalRepoManagerImpl - [4e641c9]getCloseDeferredMillis: closeDeferredMillis=0
08:15:27.114 [qtp1876368826-90] INFO c.c.c.l.LocalRepoManagerImpl - [4e641c9]close: Closing real LocalRepoManager immediately.
08:15:27.114 [qtp1876368826-90] INFO c.c.c.l.LocalRepoManagerImpl - [4e641c9]_close: Shutting down real LocalRepoManager.
08:15:27.415 [pool-4-thread-1] INFO c.c.c.l.LocalRepoManagerImpl - [1230ce59]<init>: localRoot='/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.test/target/repo/jz6kqjj3-m6kv'
08:15:27.431 [pool-4-thread-1] INFO c.c.c.l.LocalRepoManagerImpl - [1230ce59]initLockFile: Repository '/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.test/target/repo/jz6kqjj3-m6kv' locked successfully.
08:15:27.645 [qtp1876368826-90] INFO c.c.c.l.LocalRepoManagerImpl - [62c0c684]<init>: localRoot='/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.test/target/repo/jz6kphk1-z0j0y-remote'
08:15:27.646 [qtp1876368826-90] INFO c.c.c.l.LocalRepoManagerImpl - [62c0c684]initLockFile: Repository '/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.test/target/repo/jz6kphk1-z0j0y-remote' locked successfully.
08:15:28.225 [qtp1876368826-90] INFO c.c.c.l.LocalRepoManagerImpl - [62c0c684]initPersistenceManagerFactory: repositoryId=a97338ce-2273-46fd-8321-9659731968c3
08:15:28.226 [qtp1876368826-90] INFO c.c.c.l.LocalRepoManagerImpl - [62c0c684]initPersistenceManagerFactory: Started up PersistenceManagerFactory successfully in 580 ms.
08:15:28.285 [qtp1876368826-90] INFO c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=a97338ce-2273-46fd-8321-9659731968c3 remoteRepositoryId=80ba59a7-32e1-4bb6-a446-d12f42b25542 localRepositoryRevisionSynced=15
08:15:28.286 [qtp1876368826-90] INFO c.c.c.l.LocalRepoManagerImpl - [62c0c684]getCloseDeferredMillis: closeDeferredMillis=0
08:15:28.287 [qtp1876368826-90] INFO c.c.c.l.LocalRepoManagerImpl - [62c0c684]close: Closing real LocalRepoManager immediately.
08:15:28.287 [qtp1876368826-90] INFO c.c.c.l.LocalRepoManagerImpl - [62c0c684]_close: Shutting down real LocalRepoManager.
08:15:28.677 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=80ba59a7-32e1-4bb6-a446-d12f42b25542 remoteRepositoryId=a97338ce-2273-46fd-8321-9659731968c3 transaction.localRevision=94 remoteFromLocalRevision=15
08:15:28.685 [main] INFO c.c.c.t.r.BackupRestoreRepoToRepoSyncIT - Synchronising...: 100.00%
08:15:28.685 [main] INFO c.c.c.l.LocalRepoManagerImpl - [16120270]getCloseDeferredMillis: closeDeferredMillis=0
08:15:28.685 [main] INFO c.c.c.l.LocalRepoManagerImpl - [16120270]close: Closing real LocalRepoManager immediately.
08:15:28.685 [main] INFO c.c.c.l.LocalRepoManagerImpl - [16120270]_close: Shutting down real LocalRepoManager.
08:15:29.394 [main] INFO c.c.c.t.r.BackupRestoreRepoToRepoSyncIT - ************************************************************************************
08:15:29.395 [main] INFO c.c.c.t.r.BackupRestoreRepoToRepoSyncIT - Restoring backup: 'target/repo/jz6kphk1-z0j0y-remote.bak' => 'target/repo/jz6kphk1-z0j0y-remote'
08:15:29.457 [main] INFO c.c.c.t.r.BackupRestoreRepoToRepoSyncIT - Restored backup: 'target/repo/jz6kphk1-z0j0y-remote.bak' => 'target/repo/jz6kphk1-z0j0y-remote'
08:15:29.458 [main] INFO c.c.c.t.r.BackupRestoreRepoToRepoSyncIT - ************************************************************************************
08:15:29.458 [main] INFO c.c.c.l.LocalRepoManagerImpl - [3e0a112f]<init>: localRoot='/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.test/target/repo/jz6kphk0-ii18v-local'
08:15:29.459 [main] INFO c.c.c.l.LocalRepoManagerImpl - [3e0a112f]initLockFile: Repository '/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.test/target/repo/jz6kphk0-ii18v-local' locked successfully.
08:15:30.255 [main] INFO c.c.c.l.LocalRepoManagerImpl - [3e0a112f]initPersistenceManagerFactory: repositoryId=80ba59a7-32e1-4bb6-a446-d12f42b25542
08:15:30.255 [main] INFO c.c.c.l.LocalRepoManagerImpl - [3e0a112f]initPersistenceManagerFactory: Started up PersistenceManagerFactory successfully in 796 ms.
08:15:30.448 [qtp1876368826-25] INFO c.c.c.l.LocalRepoManagerImpl - [7621bed7]<init>: localRoot='/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.test/target/repo/jz6kphk1-z0j0y-remote'
08:15:30.449 [qtp1876368826-25] INFO c.c.c.l.LocalRepoManagerImpl - [7621bed7]initLockFile: Repository '/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.test/target/repo/jz6kphk1-z0j0y-remote' locked successfully.
08:15:31.247 [qtp1876368826-25] INFO c.c.c.l.LocalRepoManagerImpl - [7621bed7]initPersistenceManagerFactory: repositoryId=a97338ce-2273-46fd-8321-9659731968c3
08:15:31.247 [qtp1876368826-25] INFO c.c.c.l.LocalRepoManagerImpl - [7621bed7]initPersistenceManagerFactory: Started up PersistenceManagerFactory successfully in 797 ms.
08:15:31.268 [qtp1876368826-25] INFO c.c.c.l.LocalRepoManagerImpl - [7621bed7]getCloseDeferredMillis: closeDeferredMillis=0
08:15:31.268 [qtp1876368826-25] INFO c.c.c.l.LocalRepoManagerImpl - [7621bed7]close: Closing real LocalRepoManager immediately.
08:15:31.268 [qtp1876368826-25] INFO c.c.c.l.LocalRepoManagerImpl - [7621bed7]_close: Shutting down real LocalRepoManager.
08:15:31.725 [main] INFO c.c.c.t.r.BackupRestoreRepoToRepoSyncIT - Synchronising...: 0.50%
08:15:31.726 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDown: fromID=a97338ce-2273-46fd-8321-9659731968c3 from='https://localhost:35971/a97338ce-2273-46fd-8321-9659731968c3' toID=80ba59a7-32e1-4bb6-a446-d12f42b25542 to='target/repo/jz6kphk0-ii18v-local', fromRepoLocalSync=true
08:15:31.726 [pool-9-thread-1] INFO c.c.c.c.r.s.RepoToRepoSync - sync: locally syncing 80ba59a7-32e1-4bb6-a446-d12f42b25542 ('target/repo/jz6kphk0-ii18v-local')
08:15:31.761 [pool-9-thread-1] INFO c.c.c.t.r.BackupRestoreRepoToRepoSyncIT - Synchronising...: 12.94%
08:15:31.812 [pool-9-thread-1] INFO c.c.c.t.r.BackupRestoreRepoToRepoSyncIT - Synchronising...: 19.03%
08:15:31.854 [pool-9-thread-1] INFO c.c.c.t.r.BackupRestoreRepoToRepoSyncIT - Synchronising...: 24.15%
08:15:31.905 [main] INFO c.c.c.r.c.t.RestRepoTransport - getChangeSetDto: ChangeSetDto-cache-file NOT found: /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.test/target/repo/jz6kphk0-ii18v-local/.cloudstore-repo/tmp/ChangeSetDto.a97338ce-2273-46fd-8321-9659731968c3.15.xml.gz
08:15:31.924 [qtp1876368826-25] INFO c.c.c.l.LocalRepoManagerImpl - [5779124f]<init>: localRoot='/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.test/target/repo/jz6kphk1-z0j0y-remote'
08:15:31.925 [qtp1876368826-25] INFO c.c.c.l.LocalRepoManagerImpl - [5779124f]initLockFile: Repository '/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.test/target/repo/jz6kphk1-z0j0y-remote' locked successfully.
08:15:32.621 [qtp1876368826-25] INFO c.c.c.l.LocalRepoManagerImpl - [5779124f]initPersistenceManagerFactory: repositoryId=a97338ce-2273-46fd-8321-9659731968c3
08:15:32.622 [qtp1876368826-25] INFO c.c.c.l.LocalRepoManagerImpl - [5779124f]initPersistenceManagerFactory: Started up PersistenceManagerFactory successfully in 697 ms.
08:15:32.669 [qtp1876368826-25] INFO c.c.c.l.LocalRepoManagerImpl - [5779124f]getCloseDeferredMillis: closeDeferredMillis=0
08:15:32.669 [qtp1876368826-25] INFO c.c.c.l.LocalRepoManagerImpl - [5779124f]close: Closing real LocalRepoManager immediately.
08:15:32.669 [qtp1876368826-25] INFO c.c.c.l.LocalRepoManagerImpl - [5779124f]_close: Shutting down real LocalRepoManager.
08:15:33.065 [main] INFO c.c.c.r.c.t.RestRepoTransport - getAuthToken: got new AuthToken: clientRepositoryId=80ba59a7-32e1-4bb6-a446-d12f42b25542 serverRepositoryId=a97338ce-2273-46fd-8321-9659731968c3 renewalDateTime=2019-08-11T06:44:54.837Z expiryDateTime=2019-08-11T07:14:54.837Z
08:15:33.080 [qtp1876368826-29] INFO c.c.c.l.LocalRepoManagerImpl - [2a6a48e7]<init>: localRoot='/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.test/target/repo/jz6kphk1-z0j0y-remote'
08:15:33.080 [qtp1876368826-29] INFO c.c.c.l.LocalRepoManagerImpl - [2a6a48e7]initLockFile: Repository '/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.test/target/repo/jz6kphk1-z0j0y-remote' locked successfully.
08:15:33.687 [qtp1876368826-29] INFO c.c.c.l.LocalRepoManagerImpl - [2a6a48e7]initPersistenceManagerFactory: repositoryId=a97338ce-2273-46fd-8321-9659731968c3
08:15:33.687 [qtp1876368826-29] INFO c.c.c.l.LocalRepoManagerImpl - [2a6a48e7]initPersistenceManagerFactory: Started up PersistenceManagerFactory successfully in 606 ms.
08:15:33.723 [qtp1876368826-29] INFO c.c.c.l.LocalRepoManagerImpl - [2a6a48e7]getCloseDeferredMillis: closeDeferredMillis=0
08:15:33.723 [qtp1876368826-29] INFO c.c.c.l.LocalRepoManagerImpl - [2a6a48e7]close: Closing real LocalRepoManager immediately.
08:15:33.723 [qtp1876368826-29] INFO c.c.c.l.LocalRepoManagerImpl - [2a6a48e7]_close: Shutting down real LocalRepoManager.
08:15:34.079 [pool-4-thread-1] INFO c.c.c.l.LocalRepoManagerImpl - [3f2655f3]<init>: localRoot='/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.test/target/repo/jz6kphk1-z0j0y-remote'
08:15:34.081 [pool-4-thread-1] INFO c.c.c.l.LocalRepoManagerImpl - [3f2655f3]initLockFile: Repository '/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.test/target/repo/jz6kphk1-z0j0y-remote' locked successfully.
08:15:34.721 [pool-4-thread-1] INFO c.c.c.l.LocalRepoManagerImpl - [3f2655f3]initPersistenceManagerFactory: repositoryId=a97338ce-2273-46fd-8321-9659731968c3
08:15:34.722 [pool-4-thread-1] INFO c.c.c.l.LocalRepoManagerImpl - [3f2655f3]initPersistenceManagerFactory: Started up PersistenceManagerFactory successfully in 640 ms.
08:15:34.747 [pool-4-thread-1] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 1.00%
08:15:34.762 [pool-4-thread-1] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 50.00%
08:15:34.785 [pool-4-thread-1] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 55.72%
08:15:34.812 [pool-4-thread-1] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 64.70%
08:15:34.820 [pool-4-thread-1] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 74.50%
08:15:34.839 [pool-4-thread-1] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 84.30%
08:15:34.871 [pool-4-thread-1] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 94.10%
08:15:34.913 [pool-4-thread-1] INFO c.c.c.l.t.FileRepoTransport - Local sync...: 100.00%
08:15:34.931 [pool-4-thread-1] WARN c.c.c.l.t.ChangeSetDtoBuilder - prepareLastSyncToRemoteRepo: Enabling resyncMode! lastSyncToRemoteRepoLocalRepositoryRevisionSynced=15 overwrites lastSyncToRemoteRepo.localRepositoryRevisionSynced=6
08:15:34.954 [pool-4-thread-1] TRACE c.c.c.l.t.ChangeSetDtoBuilder - >>> buildChangeSetDto >>>
08:15:34.957 [pool-4-thread-1] TRACE c.c.c.l.t.ChangeSetDtoBuilder - localRepositoryId: a97338ce-2273-46fd-8321-9659731968c3
08:15:34.957 [pool-4-thread-1] TRACE c.c.c.l.t.ChangeSetDtoBuilder - remoteRepositoryId: 80ba59a7-32e1-4bb6-a446-d12f42b25542
08:15:34.957 [pool-4-thread-1] TRACE c.c.c.l.t.ChangeSetDtoBuilder - pathPrefix:
08:15:34.957 [pool-4-thread-1] INFO c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=a97338ce-2273-46fd-8321-9659731968c3 remoteRepositoryId=80ba59a7-32e1-4bb6-a446-d12f42b25542 localRepositoryRevisionSynced=15 localRepositoryRevisionInProgress=8
08:15:34.966 [pool-4-thread-1] DEBUG c.c.c.l.t.ChangeSetDtoBuilder - toModificationDtos: Creating 0 ModificationDtos took 0 ms.
08:15:34.975 [pool-4-thread-1] TRACE c.c.c.l.t.ChangeSetDtoBuilder - >>> buildConfigPropSetDto >>>
08:15:34.975 [pool-4-thread-1] DEBUG c.c.c.l.t.ChangeSetDtoBuilder - buildConfigPropSetDto: pathPrefix is empty => returning null.
08:15:34.975 [pool-4-thread-1] TRACE c.c.c.l.t.ChangeSetDtoBuilder - <<< buildConfigPropSetDto <<< null
08:15:34.975 [pool-4-thread-1] TRACE c.c.c.l.t.ChangeSetDtoBuilder - <<< buildChangeSetDto <<<
08:15:34.976 [pool-4-thread-1] INFO c.c.c.l.LocalRepoManagerImpl - [3f2655f3]getCloseDeferredMillis: closeDeferredMillis=0
08:15:34.976 [pool-4-thread-1] INFO c.c.c.l.LocalRepoManagerImpl - [3f2655f3]close: Closing real LocalRepoManager immediately.
08:15:34.976 [pool-4-thread-1] INFO c.c.c.l.LocalRepoManagerImpl - [3f2655f3]_close: Shutting down real LocalRepoManager.
08:15:35.560 [main] INFO c.c.c.r.c.t.RestRepoTransport - getChangeSetDto: Wrote ChangeSetDto-cache-file: /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.test/target/repo/jz6kphk0-ii18v-local/.cloudstore-repo/tmp/ChangeSetDto.a97338ce-2273-46fd-8321-9659731968c3.15.xml.gz
08:15:35.561 [main] INFO c.c.c.t.r.BackupRestoreRepoToRepoSyncIT - Synchronising...: 38.56%
08:15:35.563 [main] INFO c.c.c.t.r.BackupRestoreRepoToRepoSyncIT - Synchronising...: 49.75%
08:15:35.598 [qtp1876368826-29] INFO c.c.c.l.LocalRepoManagerImpl - [885bb2d]<init>: localRoot='/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.test/target/repo/jz6kphk1-z0j0y-remote'
08:15:35.598 [qtp1876368826-29] INFO c.c.c.l.LocalRepoManagerImpl - [885bb2d]initLockFile: Repository '/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.test/target/repo/jz6kphk1-z0j0y-remote' locked successfully.
08:15:36.250 [qtp1876368826-29] INFO c.c.c.l.LocalRepoManagerImpl - [885bb2d]initPersistenceManagerFactory: repositoryId=a97338ce-2273-46fd-8321-9659731968c3
08:15:36.250 [qtp1876368826-29] INFO c.c.c.l.LocalRepoManagerImpl - [885bb2d]initPersistenceManagerFactory: Started up PersistenceManagerFactory successfully in 652 ms.
08:15:36.283 [qtp1876368826-29] INFO c.c.c.l.LocalRepoManagerImpl - [885bb2d]getCloseDeferredMillis: closeDeferredMillis=0
08:15:36.283 [qtp1876368826-29] INFO c.c.c.l.LocalRepoManagerImpl - [885bb2d]close: Closing real LocalRepoManager immediately.
08:15:36.283 [qtp1876368826-29] INFO c.c.c.l.LocalRepoManagerImpl - [885bb2d]_close: Shutting down real LocalRepoManager.
08:15:36.685 [qtp1876368826-29] INFO c.c.c.l.LocalRepoManagerImpl - [6e183d8b]<init>: localRoot='/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.test/target/repo/jz6kphk1-z0j0y-remote'
08:15:36.687 [qtp1876368826-29] INFO c.c.c.l.LocalRepoManagerImpl - [6e183d8b]initLockFile: Repository '/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.test/target/repo/jz6kphk1-z0j0y-remote' locked successfully.
08:15:37.345 [qtp1876368826-29] INFO c.c.c.l.LocalRepoManagerImpl - [6e183d8b]initPersistenceManagerFactory: repositoryId=a97338ce-2273-46fd-8321-9659731968c3
08:15:37.346 [qtp1876368826-29] INFO c.c.c.l.LocalRepoManagerImpl - [6e183d8b]initPersistenceManagerFactory: Started up PersistenceManagerFactory successfully in 658 ms.
08:15:37.429 [qtp1876368826-29] INFO c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=a97338ce-2273-46fd-8321-9659731968c3 remoteRepositoryId=80ba59a7-32e1-4bb6-a446-d12f42b25542 localRepositoryRevisionSynced=8
08:15:37.431 [qtp1876368826-29] INFO c.c.c.l.LocalRepoManagerImpl - [6e183d8b]getCloseDeferredMillis: closeDeferredMillis=0
08:15:37.431 [qtp1876368826-29] INFO c.c.c.l.LocalRepoManagerImpl - [6e183d8b]close: Closing real LocalRepoManager immediately.
08:15:37.431 [qtp1876368826-29] INFO c.c.c.l.LocalRepoManagerImpl - [6e183d8b]_close: Shutting down real LocalRepoManager.
08:15:37.439 [pool-4-thread-1] INFO c.c.c.l.LocalRepoManagerImpl - [1230ce59]initPersistenceManagerFactory: repositoryId=861c4a5f-e965-4ec2-9393-2660ad804d8f
08:15:37.450 [pool-4-thread-1] INFO c.c.c.l.LocalRepoManagerImpl - [1230ce59]initPersistenceManagerFactory: Started up PersistenceManagerFactory successfully in 6192 ms.
08:15:37.699 [pool-4-thread-1] INFO c.c.c.l.LocalRepoManagerImpl - [1230ce59]getCloseDeferredMillis: closeDeferredMillis=20000
08:15:37.699 [pool-4-thread-1] INFO c.c.c.l.LocalRepoManagerImpl - [1230ce59]close: Deferring shut down of real LocalRepoManager 20000 ms.
08:15:37.854 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=80ba59a7-32e1-4bb6-a446-d12f42b25542 remoteRepositoryId=a97338ce-2273-46fd-8321-9659731968c3 transaction.localRevision=95 remoteFromLocalRevision=8
08:15:37.857 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncUp: fromID=80ba59a7-32e1-4bb6-a446-d12f42b25542 from='target/repo/jz6kphk0-ii18v-local' toID=a97338ce-2273-46fd-8321-9659731968c3 to='https://localhost:35971/a97338ce-2273-46fd-8321-9659731968c3'
08:15:37.880 [qtp1876368826-29] INFO c.c.c.l.LocalRepoManagerImpl - [2d8bca3e]<init>: localRoot='/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.test/target/repo/jz6kphk1-z0j0y-remote'
08:15:37.882 [qtp1876368826-29] INFO c.c.c.l.LocalRepoManagerImpl - [2d8bca3e]initLockFile: Repository '/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.test/target/repo/jz6kphk1-z0j0y-remote' locked successfully.
08:15:38.389 [qtp1876368826-29] INFO c.c.c.l.LocalRepoManagerImpl - [2d8bca3e]initPersistenceManagerFactory: repositoryId=a97338ce-2273-46fd-8321-9659731968c3
08:15:38.389 [qtp1876368826-29] INFO c.c.c.l.LocalRepoManagerImpl - [2d8bca3e]initPersistenceManagerFactory: Started up PersistenceManagerFactory successfully in 501 ms.
08:15:38.409 [qtp1876368826-29] INFO c.c.c.l.LocalRepoManagerImpl - [2d8bca3e]getCloseDeferredMillis: closeDeferredMillis=0
08:15:38.409 [qtp1876368826-29] INFO c.c.c.l.LocalRepoManagerImpl - [2d8bca3e]close: Closing real LocalRepoManager immediately.
08:15:38.409 [qtp1876368826-29] INFO c.c.c.l.LocalRepoManagerImpl - [2d8bca3e]_close: Shutting down real LocalRepoManager.
08:15:38.741 [qtp1876368826-29] INFO c.c.c.l.LocalRepoManagerImpl - [b6dde18]<init>: localRoot='/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.test/target/repo/jz6kphk1-z0j0y-remote'
08:15:38.741 [qtp1876368826-29] INFO c.c.c.l.LocalRepoManagerImpl - [b6dde18]initLockFile: Repository '/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.test/target/repo/jz6kphk1-z0j0y-remote' locked successfully.
08:15:39.295 [qtp1876368826-29] INFO c.c.c.l.LocalRepoManagerImpl - [b6dde18]initPersistenceManagerFactory: repositoryId=a97338ce-2273-46fd-8321-9659731968c3
08:15:39.295 [qtp1876368826-29] INFO c.c.c.l.LocalRepoManagerImpl - [b6dde18]initPersistenceManagerFactory: Started up PersistenceManagerFactory successfully in 553 ms.
08:15:39.320 [qtp1876368826-29] INFO c.c.c.l.LocalRepoManagerImpl - [b6dde18]getCloseDeferredMillis: closeDeferredMillis=0
08:15:39.320 [qtp1876368826-29] INFO c.c.c.l.LocalRepoManagerImpl - [b6dde18]close: Closing real LocalRepoManager immediately.
08:15:39.320 [qtp1876368826-29] INFO c.c.c.l.LocalRepoManagerImpl - [b6dde18]_close: Shutting down real LocalRepoManager.
08:15:39.698 [main] WARN c.c.c.l.t.ChangeSetDtoBuilder - prepareLastSyncToRemoteRepo: Enabling resyncMode! lastSyncToRemoteRepoLocalRepositoryRevisionSynced=84 overwrites lastSyncToRemoteRepo.localRepositoryRevisionSynced=89
08:15:39.711 [main] TRACE c.c.c.l.t.ChangeSetDtoBuilder - >>> buildChangeSetDto >>>
08:15:39.713 [main] TRACE c.c.c.l.t.ChangeSetDtoBuilder - localRepositoryId: 80ba59a7-32e1-4bb6-a446-d12f42b25542
08:15:39.714 [main] TRACE c.c.c.l.t.ChangeSetDtoBuilder - remoteRepositoryId: a97338ce-2273-46fd-8321-9659731968c3
08:15:39.714 [main] TRACE c.c.c.l.t.ChangeSetDtoBuilder - pathPrefix:
08:15:39.714 [main] INFO c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=80ba59a7-32e1-4bb6-a446-d12f42b25542 remoteRepositoryId=a97338ce-2273-46fd-8321-9659731968c3 localRepositoryRevisionSynced=84 localRepositoryRevisionInProgress=95
08:15:39.723 [main] DEBUG c.c.c.l.t.ChangeSetDtoBuilder - toModificationDtos: Creating 0 ModificationDtos took 0 ms.
08:15:39.760 [main] TRACE c.c.c.l.t.ChangeSetDtoBuilder - >>> buildConfigPropSetDto >>>
08:15:39.760 [main] DEBUG c.c.c.l.t.ChangeSetDtoBuilder - buildConfigPropSetDto: pathPrefix is empty => returning null.
08:15:39.760 [main] TRACE c.c.c.l.t.ChangeSetDtoBuilder - <<< buildConfigPropSetDto <<< null
08:15:39.760 [main] TRACE c.c.c.l.t.ChangeSetDtoBuilder - <<< buildChangeSetDto <<<
08:15:39.761 [main] INFO c.c.c.t.r.BackupRestoreRepoToRepoSyncIT - Synchronising...: 56.31%
08:15:39.761 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDirectory: path='/3'
08:15:39.782 [qtp1876368826-29] INFO c.c.c.l.LocalRepoManagerImpl - [1b803ea0]<init>: localRoot='/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.test/target/repo/jz6kphk1-z0j0y-remote'
08:15:39.783 [qtp1876368826-29] INFO c.c.c.l.LocalRepoManagerImpl - [1b803ea0]initLockFile: Repository '/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.test/target/repo/jz6kphk1-z0j0y-remote' locked successfully.
08:15:40.358 [qtp1876368826-29] INFO c.c.c.l.LocalRepoManagerImpl - [1b803ea0]initPersistenceManagerFactory: repositoryId=a97338ce-2273-46fd-8321-9659731968c3
08:15:40.358 [qtp1876368826-29] INFO c.c.c.l.LocalRepoManagerImpl - [1b803ea0]initPersistenceManagerFactory: Started up PersistenceManagerFactory successfully in 575 ms.
08:15:40.387 [qtp1876368826-29] INFO c.c.c.l.LocalRepoManagerImpl - [1b803ea0]getCloseDeferredMillis: closeDeferredMillis=0
08:15:40.387 [qtp1876368826-29] INFO c.c.c.l.LocalRepoManagerImpl - [1b803ea0]close: Closing real LocalRepoManager immediately.
08:15:40.387 [qtp1876368826-29] INFO c.c.c.l.LocalRepoManagerImpl - [1b803ea0]_close: Shutting down real LocalRepoManager.
08:15:41.061 [qtp1876368826-29] INFO c.c.c.l.LocalRepoManagerImpl - [3415441b]<init>: localRoot='/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.test/target/repo/jz6kphk1-z0j0y-remote'
08:15:41.062 [qtp1876368826-29] INFO c.c.c.l.LocalRepoManagerImpl - [3415441b]initLockFile: Repository '/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.test/target/repo/jz6kphk1-z0j0y-remote' locked successfully.
08:15:41.600 [qtp1876368826-29] INFO c.c.c.l.LocalRepoManagerImpl - [3415441b]initPersistenceManagerFactory: repositoryId=a97338ce-2273-46fd-8321-9659731968c3
08:15:41.601 [qtp1876368826-29] INFO c.c.c.l.LocalRepoManagerImpl - [3415441b]initPersistenceManagerFactory: Started up PersistenceManagerFactory successfully in 539 ms.
08:15:41.687 [qtp1876368826-29] INFO c.c.c.l.LocalRepoManagerImpl - [3415441b]getCloseDeferredMillis: closeDeferredMillis=0
08:15:41.687 [qtp1876368826-29] INFO c.c.c.l.LocalRepoManagerImpl - [3415441b]close: Closing real LocalRepoManager immediately.
08:15:41.687 [qtp1876368826-29] INFO c.c.c.l.LocalRepoManagerImpl - [3415441b]_close: Shutting down real LocalRepoManager.
08:15:42.054 [main] INFO c.c.c.t.r.BackupRestoreRepoToRepoSyncIT - Synchronising...: 62.42%
08:15:42.054 [main] INFO c.c.c.t.r.BackupRestoreRepoToRepoSyncIT - Synchronising...: 68.52%
08:15:42.054 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncFile: path='/3/zzz'
08:15:42.098 [qtp1876368826-29] INFO c.c.c.l.LocalRepoManagerImpl - [2b807d2d]<init>: localRoot='/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.test/target/repo/jz6kphk1-z0j0y-remote'
08:15:42.099 [qtp1876368826-29] INFO c.c.c.l.LocalRepoManagerImpl - [2b807d2d]initLockFile: Repository '/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.test/target/repo/jz6kphk1-z0j0y-remote' locked successfully.
Tests run: 5, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 352.32 sec - in co.codewizards.cloudstore.test.LocalServerClientIT
08:15:42.375 [Thread-4] INFO o.e.j.s.AbstractConnector - Stopped ServerConnector@734a19ef{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
08:15:42.377 [Thread-4] INFO o.e.j.s.session - node0 Stopped scavenging
08:15:42.384 [Thread-4] INFO o.e.j.s.h.ContextHandler - Stopped o.e.j.s.ServletContextHandler@5c7973d2{/,null,UNAVAILABLE}
08:15:42.399 [Thread-4] INFO c.c.c.l.s.LocalServer - onStopOrFailure: File did not exist (could not delete): /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.test/target/jvm/6aXJrHg0RBYwuQjx0NwCDg/.cloudstore/localServerRunning.deleteToStop
08:15:42.407 [Thread-4] INFO o.e.j.s.AbstractConnector - Stopped ServerConnector@4187e5aa{SSL,[ssl, http/1.1]}{0.0.0.0:35579}
08:15:42.410 [Thread-4] INFO o.e.j.s.session - node0 Stopped scavenging
08:15:42.412 [Thread-4] INFO o.e.j.s.h.ContextHandler - Stopped o.e.j.s.ServletContextHandler@cdf9087{/,null,UNAVAILABLE}
08:15:42.641 [qtp1876368826-29] INFO c.c.c.l.LocalRepoManagerImpl - [2b807d2d]initPersistenceManagerFactory: repositoryId=a97338ce-2273-46fd-8321-9659731968c3
08:15:42.641 [qtp1876368826-29] INFO c.c.c.l.LocalRepoManagerImpl - [2b807d2d]initPersistenceManagerFactory: Started up PersistenceManagerFactory successfully in 542 ms.
08:15:42.663 [qtp1876368826-29] INFO c.c.c.l.LocalRepoManagerImpl - [2b807d2d]getCloseDeferredMillis: closeDeferredMillis=0
08:15:42.663 [qtp1876368826-29] INFO c.c.c.l.LocalRepoManagerImpl - [2b807d2d]close: Closing real LocalRepoManager immediately.
08:15:42.663 [qtp1876368826-29] INFO c.c.c.l.LocalRepoManagerImpl - [2b807d2d]_close: Shutting down real LocalRepoManager.
08:15:43.185 [pool-4-thread-1] INFO c.c.c.l.LocalRepoManagerImpl - [3c6f4847]<init>: localRoot='/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.test/target/repo/jz6kphk1-z0j0y-remote'
08:15:43.186 [pool-4-thread-1] INFO c.c.c.l.LocalRepoManagerImpl - [3c6f4847]initLockFile: Repository '/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.test/target/repo/jz6kphk1-z0j0y-remote' locked successfully.
08:15:43.728 [pool-4-thread-1] INFO c.c.c.l.LocalRepoManagerImpl - [3c6f4847]initPersistenceManagerFactory: repositoryId=a97338ce-2273-46fd-8321-9659731968c3
08:15:43.728 [pool-4-thread-1] INFO c.c.c.l.LocalRepoManagerImpl - [3c6f4847]initPersistenceManagerFactory: Started up PersistenceManagerFactory successfully in 542 ms.
08:15:43.763 [pool-4-thread-1] INFO c.c.c.l.LocalRepoManagerImpl - [3c6f4847]getCloseDeferredMillis: closeDeferredMillis=0
08:15:43.763 [pool-4-thread-1] INFO c.c.c.l.LocalRepoManagerImpl - [3c6f4847]close: Closing real LocalRepoManager immediately.
08:15:43.763 [pool-4-thread-1] INFO c.c.c.l.LocalRepoManagerImpl - [3c6f4847]_close: Shutting down real LocalRepoManager.
08:15:44.097 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Beginning to copy file (from.sha1='6629a1513e594f818910b337c5116e71ffbdf056' to.sha1='<NoInstanceOf_NormalFileDto>'): /3/zzz
Aug 11, 2019 8:15:44 AM org.glassfish.jersey.client.JerseyInvocation validateHttpMethodAndEntity
WARNING: Entity must not be null for http method PUT.
08:15:44.116 [qtp1876368826-25] INFO c.c.c.l.LocalRepoManagerImpl - [4310de64]<init>: localRoot='/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.test/target/repo/jz6kphk1-z0j0y-remote'
08:15:44.117 [qtp1876368826-25] INFO c.c.c.l.LocalRepoManagerImpl - [4310de64]initLockFile: Repository '/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.test/target/repo/jz6kphk1-z0j0y-remote' locked successfully.
08:15:44.697 [qtp1876368826-25] INFO c.c.c.l.LocalRepoManagerImpl - [4310de64]initPersistenceManagerFactory: repositoryId=a97338ce-2273-46fd-8321-9659731968c3
08:15:44.698 [qtp1876368826-25] INFO c.c.c.l.LocalRepoManagerImpl - [4310de64]initPersistenceManagerFactory: Started up PersistenceManagerFactory successfully in 581 ms.
08:15:44.727 [qtp1876368826-25] INFO c.c.c.l.LocalRepoManagerImpl - [4310de64]getCloseDeferredMillis: closeDeferredMillis=0
08:15:44.727 [qtp1876368826-25] INFO c.c.c.l.LocalRepoManagerImpl - [4310de64]close: Closing real LocalRepoManager immediately.
08:15:44.727 [qtp1876368826-25] INFO c.c.c.l.LocalRepoManagerImpl - [4310de64]_close: Shutting down real LocalRepoManager.
08:15:45.097 [qtp1876368826-25] INFO c.c.c.l.LocalRepoManagerImpl - [541bd4b9]<init>: localRoot='/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.test/target/repo/jz6kphk1-z0j0y-remote'
08:15:45.098 [qtp1876368826-25] INFO c.c.c.l.LocalRepoManagerImpl - [541bd4b9]initLockFile: Repository '/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.test/target/repo/jz6kphk1-z0j0y-remote' locked successfully.
08:15:45.629 [qtp1876368826-25] INFO c.c.c.l.LocalRepoManagerImpl - [541bd4b9]initPersistenceManagerFactory: repositoryId=a97338ce-2273-46fd-8321-9659731968c3
08:15:45.629 [qtp1876368826-25] INFO c.c.c.l.LocalRepoManagerImpl - [541bd4b9]initPersistenceManagerFactory: Started up PersistenceManagerFactory successfully in 530 ms.
08:15:45.726 [qtp1876368826-25] INFO c.c.c.l.LocalRepoManagerImpl - [541bd4b9]getCloseDeferredMillis: closeDeferredMillis=0
08:15:45.727 [qtp1876368826-25] INFO c.c.c.l.LocalRepoManagerImpl - [541bd4b9]close: Closing real LocalRepoManager immediately.
08:15:45.727 [qtp1876368826-25] INFO c.c.c.l.LocalRepoManagerImpl - [541bd4b9]_close: Shutting down real LocalRepoManager.
08:15:46.113 [main] INFO c.c.c.l.t.FileRepoTransport - Storing fileInProgressMarker: FileInProgressMarker[id=17, fromRepositoryId="FileInProgressMarker", toRepositoryId="17", path="80ba59a7-32e1-4bb6-a446-d12f42b25542"] on repo=80ba59a7-32e1-4bb6-a446-d12f42b25542
08:15:46.117 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Need to copy 1 dirty file-chunks (of 1 total). path='/3/zzz'
08:15:46.251 [qtp1876368826-25] INFO c.c.c.l.LocalRepoManagerImpl - [3fa86e1]<init>: localRoot='/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.test/target/repo/jz6kphk1-z0j0y-remote'
08:15:46.251 [qtp1876368826-25] INFO c.c.c.l.LocalRepoManagerImpl - [3fa86e1]initLockFile: Repository '/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.test/target/repo/jz6kphk1-z0j0y-remote' locked successfully.
08:15:46.807 [qtp1876368826-25] INFO c.c.c.l.LocalRepoManagerImpl - [3fa86e1]initPersistenceManagerFactory: repositoryId=a97338ce-2273-46fd-8321-9659731968c3
08:15:46.807 [qtp1876368826-25] INFO c.c.c.l.LocalRepoManagerImpl - [3fa86e1]initPersistenceManagerFactory: Started up PersistenceManagerFactory successfully in 555 ms.
08:15:46.835 [qtp1876368826-25] INFO c.c.c.l.LocalRepoManagerImpl - [3fa86e1]getCloseDeferredMillis: closeDeferredMillis=0
08:15:46.836 [qtp1876368826-25] INFO c.c.c.l.LocalRepoManagerImpl - [3fa86e1]close: Closing real LocalRepoManager immediately.
08:15:46.836 [qtp1876368826-25] INFO c.c.c.l.LocalRepoManagerImpl - [3fa86e1]_close: Shutting down real LocalRepoManager.
08:15:47.187 [qtp1876368826-25] INFO c.c.c.l.LocalRepoManagerImpl - [45e7d053]<init>: localRoot='/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.test/target/repo/jz6kphk1-z0j0y-remote'
08:15:47.188 [qtp1876368826-25] INFO c.c.c.l.LocalRepoManagerImpl - [45e7d053]initLockFile: Repository '/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.test/target/repo/jz6kphk1-z0j0y-remote' locked successfully.
08:15:47.683 [qtp1876368826-25] INFO c.c.c.l.LocalRepoManagerImpl - [45e7d053]initPersistenceManagerFactory: repositoryId=a97338ce-2273-46fd-8321-9659731968c3
08:15:47.683 [qtp1876368826-25] INFO c.c.c.l.LocalRepoManagerImpl - [45e7d053]initPersistenceManagerFactory: Started up PersistenceManagerFactory successfully in 495 ms.
08:15:47.740 [qtp1876368826-25] INFO c.c.c.l.LocalRepoManagerImpl - [45e7d053]getCloseDeferredMillis: closeDeferredMillis=0
08:15:47.740 [qtp1876368826-25] INFO c.c.c.l.LocalRepoManagerImpl - [45e7d053]close: Closing real LocalRepoManager immediately.
08:15:47.740 [qtp1876368826-25] INFO c.c.c.l.LocalRepoManagerImpl - [45e7d053]_close: Shutting down real LocalRepoManager.
08:15:48.154 [main] INFO c.c.c.c.r.s.RepoToRepoSync - Copied 1 dirty file-chunks with together 836318 bytes in 2035 ms. path='/3/zzz'
Aug 11, 2019 8:15:48 AM org.glassfish.jersey.client.JerseyInvocation validateHttpMethodAndEntity
WARNING: Entity must not be null for http method PUT.
08:15:48.180 [qtp1876368826-165] INFO c.c.c.l.LocalRepoManagerImpl - [24505a0b]<init>: localRoot='/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.test/target/repo/jz6kphk1-z0j0y-remote'
08:15:48.180 [qtp1876368826-165] INFO c.c.c.l.LocalRepoManagerImpl - [24505a0b]initLockFile: Repository '/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.test/target/repo/jz6kphk1-z0j0y-remote' locked successfully.
08:15:48.771 [qtp1876368826-165] INFO c.c.c.l.LocalRepoManagerImpl - [24505a0b]initPersistenceManagerFactory: repositoryId=a97338ce-2273-46fd-8321-9659731968c3
08:15:48.771 [qtp1876368826-165] INFO c.c.c.l.LocalRepoManagerImpl - [24505a0b]initPersistenceManagerFactory: Started up PersistenceManagerFactory successfully in 591 ms.
08:15:48.795 [qtp1876368826-165] INFO c.c.c.l.LocalRepoManagerImpl - [24505a0b]getCloseDeferredMillis: closeDeferredMillis=0
08:15:48.795 [qtp1876368826-165] INFO c.c.c.l.LocalRepoManagerImpl - [24505a0b]close: Closing real LocalRepoManager immediately.
08:15:48.795 [qtp1876368826-165] INFO c.c.c.l.LocalRepoManagerImpl - [24505a0b]_close: Shutting down real LocalRepoManager.
08:15:49.175 [qtp1876368826-165] INFO c.c.c.l.LocalRepoManagerImpl - [122c52a8]<init>: localRoot='/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.test/target/repo/jz6kphk1-z0j0y-remote'
08:15:49.176 [qtp1876368826-165] INFO c.c.c.l.LocalRepoManagerImpl - [122c52a8]initLockFile: Repository '/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.test/target/repo/jz6kphk1-z0j0y-remote' locked successfully.
08:15:49.715 [qtp1876368826-165] INFO c.c.c.l.LocalRepoManagerImpl - [122c52a8]initPersistenceManagerFactory: repositoryId=a97338ce-2273-46fd-8321-9659731968c3
08:15:49.715 [qtp1876368826-165] INFO c.c.c.l.LocalRepoManagerImpl - [122c52a8]initPersistenceManagerFactory: Started up PersistenceManagerFactory successfully in 539 ms.
08:15:49.756 [qtp1876368826-165] INFO c.c.c.l.t.FileRepoTransport - endPutFile: writing tempChunkFile chunk_40fa37ec00c761c7dbb6ebdee6d4a260b922f5f4_0 into destFile zzz
08:15:49.846 [qtp1876368826-165] INFO c.c.c.l.LocalRepoManagerImpl - [122c52a8]getCloseDeferredMillis: closeDeferredMillis=0
08:15:49.847 [qtp1876368826-165] INFO c.c.c.l.LocalRepoManagerImpl - [122c52a8]close: Closing real LocalRepoManager immediately.
08:15:49.847 [qtp1876368826-165] INFO c.c.c.l.LocalRepoManagerImpl - [122c52a8]_close: Shutting down real LocalRepoManager.
08:15:50.192 [main] INFO c.c.c.l.t.FileRepoTransport - Removing fileInProgressMarker: FileInProgressMarker[id=17, fromRepositoryId="FileInProgressMarker", toRepositoryId="17", path="80ba59a7-32e1-4bb6-a446-d12f42b25542"] on repo=80ba59a7-32e1-4bb6-a446-d12f42b25542
08:15:50.215 [main] INFO c.c.c.t.r.BackupRestoreRepoToRepoSyncIT - Synchronising...: 72.59%
08:15:50.240 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=80ba59a7-32e1-4bb6-a446-d12f42b25542 remoteRepositoryId=a97338ce-2273-46fd-8321-9659731968c3 localRepositoryRevisionSynced=95
08:15:50.263 [qtp1876368826-28] INFO c.c.c.l.LocalRepoManagerImpl - [392c82fb]<init>: localRoot='/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.test/target/repo/jz6kphk1-z0j0y-remote'
08:15:50.263 [qtp1876368826-28] INFO c.c.c.l.LocalRepoManagerImpl - [392c82fb]initLockFile: Repository '/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.test/target/repo/jz6kphk1-z0j0y-remote' locked successfully.
08:15:50.843 [qtp1876368826-28] INFO c.c.c.l.LocalRepoManagerImpl - [392c82fb]initPersistenceManagerFactory: repositoryId=a97338ce-2273-46fd-8321-9659731968c3
08:15:50.844 [qtp1876368826-28] INFO c.c.c.l.LocalRepoManagerImpl - [392c82fb]initPersistenceManagerFactory: Started up PersistenceManagerFactory successfully in 581 ms.
08:15:50.872 [qtp1876368826-28] INFO c.c.c.l.LocalRepoManagerImpl - [392c82fb]getCloseDeferredMillis: closeDeferredMillis=0
08:15:50.872 [qtp1876368826-28] INFO c.c.c.l.LocalRepoManagerImpl - [392c82fb]close: Closing real LocalRepoManager immediately.
08:15:50.872 [qtp1876368826-28] INFO c.c.c.l.LocalRepoManagerImpl - [392c82fb]_close: Shutting down real LocalRepoManager.
08:15:51.219 [qtp1876368826-28] INFO c.c.c.l.LocalRepoManagerImpl - [4c5760e0]<init>: localRoot='/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.test/target/repo/jz6kphk1-z0j0y-remote'
08:15:51.219 [qtp1876368826-28] INFO c.c.c.l.LocalRepoManagerImpl - [4c5760e0]initLockFile: Repository '/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.test/target/repo/jz6kphk1-z0j0y-remote' locked successfully.
08:15:51.739 [qtp1876368826-28] INFO c.c.c.l.LocalRepoManagerImpl - [4c5760e0]initPersistenceManagerFactory: repositoryId=a97338ce-2273-46fd-8321-9659731968c3
08:15:51.739 [qtp1876368826-28] INFO c.c.c.l.LocalRepoManagerImpl - [4c5760e0]initPersistenceManagerFactory: Started up PersistenceManagerFactory successfully in 519 ms.
08:15:51.784 [qtp1876368826-28] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=a97338ce-2273-46fd-8321-9659731968c3 remoteRepositoryId=80ba59a7-32e1-4bb6-a446-d12f42b25542 transaction.localRevision=15 remoteFromLocalRevision=95
08:15:51.795 [qtp1876368826-28] INFO c.c.c.l.LocalRepoManagerImpl - [4c5760e0]getCloseDeferredMillis: closeDeferredMillis=0
08:15:51.795 [qtp1876368826-28] INFO c.c.c.l.LocalRepoManagerImpl - [4c5760e0]close: Closing real LocalRepoManager immediately.
08:15:51.795 [qtp1876368826-28] INFO c.c.c.l.LocalRepoManagerImpl - [4c5760e0]_close: Shutting down real LocalRepoManager.
08:15:52.123 [main] INFO c.c.c.c.r.s.RepoToRepoSync - syncDown: fromID=a97338ce-2273-46fd-8321-9659731968c3 from='https://localhost:35971/a97338ce-2273-46fd-8321-9659731968c3' toID=80ba59a7-32e1-4bb6-a446-d12f42b25542 to='target/repo/jz6kphk0-ii18v-local', fromRepoLocalSync=false
08:15:52.126 [main] INFO c.c.c.r.c.t.RestRepoTransport - getChangeSetDto: ChangeSetDto-cache-file NOT found: /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.test/target/repo/jz6kphk0-ii18v-local/.cloudstore-repo/tmp/ChangeSetDto.a97338ce-2273-46fd-8321-9659731968c3.null.xml.gz
08:15:52.153 [qtp1876368826-28] INFO c.c.c.l.LocalRepoManagerImpl - [3aba5c66]<init>: localRoot='/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.test/target/repo/jz6kphk1-z0j0y-remote'
08:15:52.153 [qtp1876368826-28] INFO c.c.c.l.LocalRepoManagerImpl - [3aba5c66]initLockFile: Repository '/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.test/target/repo/jz6kphk1-z0j0y-remote' locked successfully.
08:15:52.673 [qtp1876368826-28] INFO c.c.c.l.LocalRepoManagerImpl - [3aba5c66]initPersistenceManagerFactory: repositoryId=a97338ce-2273-46fd-8321-9659731968c3
08:15:52.674 [qtp1876368826-28] INFO c.c.c.l.LocalRepoManagerImpl - [3aba5c66]initPersistenceManagerFactory: Started up PersistenceManagerFactory successfully in 521 ms.
08:15:52.699 [qtp1876368826-28] INFO c.c.c.l.LocalRepoManagerImpl - [3aba5c66]getCloseDeferredMillis: closeDeferredMillis=0
08:15:52.699 [qtp1876368826-28] INFO c.c.c.l.LocalRepoManagerImpl - [3aba5c66]close: Closing real LocalRepoManager immediately.
08:15:52.699 [qtp1876368826-28] INFO c.c.c.l.LocalRepoManagerImpl - [3aba5c66]_close: Shutting down real LocalRepoManager.
08:15:52.999 [pool-4-thread-1] INFO c.c.c.l.LocalRepoManagerImpl - [4838c5e]<init>: localRoot='/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.test/target/repo/jz6kphk1-z0j0y-remote'
08:15:52.999 [pool-4-thread-1] INFO c.c.c.l.LocalRepoManagerImpl - [4838c5e]initLockFile: Repository '/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.test/target/repo/jz6kphk1-z0j0y-remote' locked successfully.
08:15:53.569 [pool-4-thread-1] INFO c.c.c.l.LocalRepoManagerImpl - [4838c5e]initPersistenceManagerFactory: repositoryId=a97338ce-2273-46fd-8321-9659731968c3
08:15:53.570 [pool-4-thread-1] INFO c.c.c.l.LocalRepoManagerImpl - [4838c5e]initPersistenceManagerFactory: Started up PersistenceManagerFactory successfully in 571 ms.
08:15:53.624 [pool-4-thread-1] TRACE c.c.c.l.t.ChangeSetDtoBuilder - >>> buildChangeSetDto >>>
08:15:53.627 [pool-4-thread-1] TRACE c.c.c.l.t.ChangeSetDtoBuilder - localRepositoryId: a97338ce-2273-46fd-8321-9659731968c3
08:15:53.628 [pool-4-thread-1] TRACE c.c.c.l.t.ChangeSetDtoBuilder - remoteRepositoryId: 80ba59a7-32e1-4bb6-a446-d12f42b25542
08:15:53.628 [pool-4-thread-1] TRACE c.c.c.l.t.ChangeSetDtoBuilder - pathPrefix:
08:15:53.628 [pool-4-thread-1] INFO c.c.c.l.t.ChangeSetDtoBuilder - buildChangeSetDto: localRepositoryId=a97338ce-2273-46fd-8321-9659731968c3 remoteRepositoryId=80ba59a7-32e1-4bb6-a446-d12f42b25542 localRepositoryRevisionSynced=8 localRepositoryRevisionInProgress=15
08:15:53.638 [pool-4-thread-1] DEBUG c.c.c.l.t.ChangeSetDtoBuilder - toModificationDtos: Creating 0 ModificationDtos took 0 ms.
08:15:53.645 [pool-4-thread-1] TRACE c.c.c.l.t.ChangeSetDtoBuilder - >>> buildConfigPropSetDto >>>
08:15:53.645 [pool-4-thread-1] DEBUG c.c.c.l.t.ChangeSetDtoBuilder - buildConfigPropSetDto: pathPrefix is empty => returning null.
08:15:53.645 [pool-4-thread-1] TRACE c.c.c.l.t.ChangeSetDtoBuilder - <<< buildConfigPropSetDto <<< null
08:15:53.645 [pool-4-thread-1] TRACE c.c.c.l.t.ChangeSetDtoBuilder - <<< buildChangeSetDto <<<
08:15:53.645 [pool-4-thread-1] INFO c.c.c.l.LocalRepoManagerImpl - [4838c5e]getCloseDeferredMillis: closeDeferredMillis=0
08:15:53.645 [pool-4-thread-1] INFO c.c.c.l.LocalRepoManagerImpl - [4838c5e]close: Closing real LocalRepoManager immediately.
08:15:53.646 [pool-4-thread-1] INFO c.c.c.l.LocalRepoManagerImpl - [4838c5e]_close: Shutting down real LocalRepoManager.
08:15:54.019 [main] INFO c.c.c.r.c.t.RestRepoTransport - getChangeSetDto: Wrote ChangeSetDto-cache-file: /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.test/target/repo/jz6kphk0-ii18v-local/.cloudstore-repo/tmp/ChangeSetDto.a97338ce-2273-46fd-8321-9659731968c3.null.xml.gz
08:15:54.020 [main] INFO c.c.c.t.r.BackupRestoreRepoToRepoSyncIT - Synchronising...: 88.31%
08:15:54.020 [main] INFO c.c.c.t.r.BackupRestoreRepoToRepoSyncIT - Synchronising...: 99.50%
08:15:54.042 [qtp1876368826-28] INFO c.c.c.l.LocalRepoManagerImpl - [1054b97e]<init>: localRoot='/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.test/target/repo/jz6kphk1-z0j0y-remote'
08:15:54.043 [qtp1876368826-28] INFO c.c.c.l.LocalRepoManagerImpl - [1054b97e]initLockFile: Repository '/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.test/target/repo/jz6kphk1-z0j0y-remote' locked successfully.
08:15:54.563 [qtp1876368826-28] INFO c.c.c.l.LocalRepoManagerImpl - [1054b97e]initPersistenceManagerFactory: repositoryId=a97338ce-2273-46fd-8321-9659731968c3
08:15:54.563 [qtp1876368826-28] INFO c.c.c.l.LocalRepoManagerImpl - [1054b97e]initPersistenceManagerFactory: Started up PersistenceManagerFactory successfully in 520 ms.
08:15:54.587 [qtp1876368826-28] INFO c.c.c.l.LocalRepoManagerImpl - [1054b97e]getCloseDeferredMillis: closeDeferredMillis=0
08:15:54.587 [qtp1876368826-28] INFO c.c.c.l.LocalRepoManagerImpl - [1054b97e]close: Closing real LocalRepoManager immediately.
08:15:54.587 [qtp1876368826-28] INFO c.c.c.l.LocalRepoManagerImpl - [1054b97e]_close: Shutting down real LocalRepoManager.
08:15:54.919 [qtp1876368826-28] INFO c.c.c.l.LocalRepoManagerImpl - [78722bdd]<init>: localRoot='/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.test/target/repo/jz6kphk1-z0j0y-remote'
08:15:54.920 [qtp1876368826-28] INFO c.c.c.l.LocalRepoManagerImpl - [78722bdd]initLockFile: Repository '/home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.test/target/repo/jz6kphk1-z0j0y-remote' locked successfully.
08:15:55.451 [qtp1876368826-28] INFO c.c.c.l.LocalRepoManagerImpl - [78722bdd]initPersistenceManagerFactory: repositoryId=a97338ce-2273-46fd-8321-9659731968c3
08:15:55.452 [qtp1876368826-28] INFO c.c.c.l.LocalRepoManagerImpl - [78722bdd]initPersistenceManagerFactory: Started up PersistenceManagerFactory successfully in 532 ms.
08:15:55.517 [qtp1876368826-28] INFO c.c.c.l.t.FileRepoTransport - endSyncFromRepository: localRepositoryId=a97338ce-2273-46fd-8321-9659731968c3 remoteRepositoryId=80ba59a7-32e1-4bb6-a446-d12f42b25542 localRepositoryRevisionSynced=15
08:15:55.528 [qtp1876368826-28] INFO c.c.c.l.LocalRepoManagerImpl - [78722bdd]getCloseDeferredMillis: closeDeferredMillis=0
08:15:55.528 [qtp1876368826-28] INFO c.c.c.l.LocalRepoManagerImpl - [78722bdd]close: Closing real LocalRepoManager immediately.
08:15:55.528 [qtp1876368826-28] INFO c.c.c.l.LocalRepoManagerImpl - [78722bdd]_close: Shutting down real LocalRepoManager.
08:15:55.842 [main] INFO c.c.c.l.t.FileRepoTransport - endSyncToRepository: localRepositoryId=80ba59a7-32e1-4bb6-a446-d12f42b25542 remoteRepositoryId=a97338ce-2273-46fd-8321-9659731968c3 transaction.localRevision=100 remoteFromLocalRevision=15
08:15:55.844 [main] INFO c.c.c.t.r.BackupRestoreRepoToRepoSyncIT - Synchronising...: 100.00%
08:15:55.908 [main] INFO c.c.c.l.LocalRepoManagerImpl - [3e0a112f]getCloseDeferredMillis: closeDeferredMillis=0
08:15:55.908 [main] INFO c.c.c.l.LocalRepoManagerImpl - [3e0a112f]close: Closing real LocalRepoManager immediately.
08:15:55.908 [main] INFO c.c.c.l.LocalRepoManagerImpl - [3e0a112f]_close: Shutting down real LocalRepoManager.
Tests run: 2, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 240.151 sec - in co.codewizards.cloudstore.test.repotorepo.BackupRestoreRepoToRepoSyncIT
08:15:56.301 [Thread-4] INFO o.e.j.s.AbstractConnector - Stopped ServerConnector@43777b1b{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
08:15:56.302 [Thread-4] INFO o.e.j.s.session - node0 Stopped scavenging
08:15:56.307 [Thread-4] INFO o.e.j.s.h.ContextHandler - Stopped o.e.j.s.ServletContextHandler@252d9318{/,null,UNAVAILABLE}
08:15:56.314 [Thread-4] INFO c.c.c.l.s.LocalServer - onStopOrFailure: File did not exist (could not delete): /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.test/target/jvm/5mktR_TiLCfQGIrXrHWZww/.cloudstore/localServerRunning.deleteToStop
08:15:56.326 [Thread-4] INFO o.e.j.s.AbstractConnector - Stopped ServerConnector@7b97295c{SSL,[ssl, http/1.1]}{0.0.0.0:35971}
08:15:56.328 [Thread-4] INFO o.e.j.s.session - node0 Stopped scavenging
08:15:56.330 [Thread-4] INFO o.e.j.s.h.ContextHandler - Stopped o.e.j.s.ServletContextHandler@5ac46679{/,null,UNAVAILABLE}
08:16:48.163 [main] WARN c.c.c.r.c.CloudStoreRestClient - execute: invocation failed (will retry): javax.ws.rs.ProcessingException: java.net.SocketException: Broken pipe (Write failed)
javax.ws.rs.ProcessingException: java.net.SocketException: Broken pipe (Write failed)
at org.glassfish.jersey.client.HttpUrlConnector.apply(HttpUrlConnector.java:184) ~[jersey-client-2.5.1.jar:na]
at org.glassfish.jersey.client.ClientRuntime.invoke(ClientRuntime.java:227) ~[jersey-client-2.5.1.jar:na]
at org.glassfish.jersey.client.JerseyInvocation$2.call(JerseyInvocation.java:671) ~[jersey-client-2.5.1.jar:na]
at org.glassfish.jersey.internal.Errors.process(Errors.java:315) ~[jersey-common-2.5.1.jar:na]
at org.glassfish.jersey.internal.Errors.process(Errors.java:297) ~[jersey-common-2.5.1.jar:na]
at org.glassfish.jersey.internal.Errors.process(Errors.java:228) ~[jersey-common-2.5.1.jar:na]
at org.glassfish.jersey.process.internal.RequestScope.runInScope(RequestScope.java:422) ~[jersey-common-2.5.1.jar:na]
at org.glassfish.jersey.client.JerseyInvocation.invoke(JerseyInvocation.java:667) ~[jersey-client-2.5.1.jar:na]
at org.glassfish.jersey.client.JerseyInvocation$Builder.method(JerseyInvocation.java:396) ~[jersey-client-2.5.1.jar:na]
at org.glassfish.jersey.client.JerseyInvocation$Builder.get(JerseyInvocation.java:296) ~[jersey-client-2.5.1.jar:na]
at co.codewizards.cloudstore.rest.client.CloudStoreRestClient.determineBaseUrl(CloudStoreRestClient.java:115) ~[co.codewizards.cloudstore.rest.client-0.10.7-SNAPSHOT.jar:na]
at co.codewizards.cloudstore.rest.client.CloudStoreRestClient.getBaseUrl(CloudStoreRestClient.java:74) ~[co.codewizards.cloudstore.rest.client-0.10.7-SNAPSHOT.jar:na]
at co.codewizards.cloudstore.rest.client.request.AbstractRequest.getBaseURL(AbstractRequest.java:146) ~[co.codewizards.cloudstore.rest.client-0.10.7-SNAPSHOT.jar:na]
at co.codewizards.cloudstore.rest.client.request.AbstractRequest.createWebTarget(AbstractRequest.java:115) ~[co.codewizards.cloudstore.rest.client-0.10.7-SNAPSHOT.jar:na]
at co.codewizards.cloudstore.rest.client.request.TestRequest.execute(TestRequest.java:27) ~[co.codewizards.cloudstore.rest.client-0.10.7-SNAPSHOT.jar:na]
at co.codewizards.cloudstore.rest.client.request.TestRequest.execute(TestRequest.java:6) ~[co.codewizards.cloudstore.rest.client-0.10.7-SNAPSHOT.jar:na]
at co.codewizards.cloudstore.rest.client.CloudStoreRestClient.execute(CloudStoreRestClient.java:163) ~[co.codewizards.cloudstore.rest.client-0.10.7-SNAPSHOT.jar:na]
at co.codewizards.cloudstore.test.CertificateHandlingAndTestServiceLRT.almostTimedOut_5min(CertificateHandlingAndTestServiceLRT.java:89) [test-classes/:na]
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) ~[na:1.8.0_222]
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) ~[na:1.8.0_222]
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) ~[na:1.8.0_222]
at java.lang.reflect.Method.invoke(Method.java:498) ~[na:1.8.0_222]
at org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(FrameworkMethod.java:50) [junit-4.12.jar:4.12]
at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:12) [junit-4.12.jar:4.12]
at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java:47) [junit-4.12.jar:4.12]
at mockit.integration.junit4.internal.JUnit4TestRunnerDecorator.executeTestMethod(JUnit4TestRunnerDecorator.java:156) [jmockit-1.24.jar:na]
at mockit.integration.junit4.internal.JUnit4TestRunnerDecorator.invokeExplosively(JUnit4TestRunnerDecorator.java:65) [jmockit-1.24.jar:na]
at mockit.integration.junit4.internal.MockFrameworkMethod.invokeExplosively(MockFrameworkMethod.java:37) [jmockit-1.24.jar:na]
at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java) [junit-4.12.jar:4.12]
at org.junit.internal.runners.statements.InvokeMethod.evaluate(InvokeMethod.java:17) [junit-4.12.jar:4.12]
at org.junit.internal.runners.statements.RunBefores.evaluate(RunBefores.java:26) [junit-4.12.jar:4.12]
at org.junit.internal.runners.statements.RunAfters.evaluate(RunAfters.java:27) [junit-4.12.jar:4.12]
at org.junit.runners.ParentRunner.runLeaf(ParentRunner.java:325) [junit-4.12.jar:4.12]
at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:78) [junit-4.12.jar:4.12]
at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:57) [junit-4.12.jar:4.12]
at org.junit.runners.ParentRunner$3.run(ParentRunner.java:290) [junit-4.12.jar:4.12]
at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:71) [junit-4.12.jar:4.12]
at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:288) [junit-4.12.jar:4.12]
at org.junit.runners.ParentRunner.access$000(ParentRunner.java:58) [junit-4.12.jar:4.12]
at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:268) [junit-4.12.jar:4.12]
at org.junit.internal.runners.statements.RunBefores.evaluate(RunBefores.java:26) [junit-4.12.jar:4.12]
at org.junit.internal.runners.statements.RunAfters.evaluate(RunAfters.java:27) [junit-4.12.jar:4.12]
at org.junit.runners.ParentRunner.run(ParentRunner.java:363) [junit-4.12.jar:4.12]
at org.apache.maven.surefire.junit4.JUnit4Provider.execute(JUnit4Provider.java:264) [surefire-junit4-2.17.jar:2.17]
at org.apache.maven.surefire.junit4.JUnit4Provider.executeTestSet(JUnit4Provider.java:153) [surefire-junit4-2.17.jar:2.17]
at org.apache.maven.surefire.junit4.JUnit4Provider.invoke(JUnit4Provider.java:124) [surefire-junit4-2.17.jar:2.17]
at org.apache.maven.surefire.booter.ForkedBooter.invokeProviderInSameClassLoader(ForkedBooter.java:200) [surefire-booter-2.17.jar:2.17]
at org.apache.maven.surefire.booter.ForkedBooter.runSuitesInProcess(ForkedBooter.java:153) [surefire-booter-2.17.jar:2.17]
at org.apache.maven.surefire.booter.ForkedBooter.main(ForkedBooter.java:103) [surefire-booter-2.17.jar:2.17]
Caused by: java.net.SocketException: Broken pipe (Write failed)
at java.net.SocketOutputStream.socketWrite0(Native Method) ~[na:1.8.0_222]
at java.net.SocketOutputStream.socketWrite(SocketOutputStream.java:111) ~[na:1.8.0_222]
at java.net.SocketOutputStream.write(SocketOutputStream.java:155) ~[na:1.8.0_222]
at sun.security.ssl.OutputRecord.writeBuffer(OutputRecord.java:431) ~[na:1.8.0_222]
at sun.security.ssl.OutputRecord.write(OutputRecord.java:417) ~[na:1.8.0_222]
at sun.security.ssl.SSLSocketImpl.writeRecordInternal(SSLSocketImpl.java:879) ~[na:1.8.0_222]
at sun.security.ssl.SSLSocketImpl.writeRecord(SSLSocketImpl.java:850) ~[na:1.8.0_222]
at sun.security.ssl.SSLSocketImpl.writeRecord(SSLSocketImpl.java:720) ~[na:1.8.0_222]
at sun.security.ssl.Handshaker.sendChangeCipherSpec(Handshaker.java:1144) ~[na:1.8.0_222]
at sun.security.ssl.ClientHandshaker.sendChangeCipherAndFinish(ClientHandshaker.java:1280) ~[na:1.8.0_222]
at sun.security.ssl.ClientHandshaker.serverHelloDone(ClientHandshaker.java:1190) ~[na:1.8.0_222]
at sun.security.ssl.ClientHandshaker.processMessage(ClientHandshaker.java:369) ~[na:1.8.0_222]
at sun.security.ssl.Handshaker.processLoop(Handshaker.java:1037) ~[na:1.8.0_222]
at sun.security.ssl.Handshaker.process_record(Handshaker.java:965) ~[na:1.8.0_222]
at sun.security.ssl.SSLSocketImpl.readRecord(SSLSocketImpl.java:1064) ~[na:1.8.0_222]
at sun.security.ssl.SSLSocketImpl.performInitialHandshake(SSLSocketImpl.java:1367) ~[na:1.8.0_222]
at sun.security.ssl.SSLSocketImpl.startHandshake(SSLSocketImpl.java:1395) ~[na:1.8.0_222]
at sun.security.ssl.SSLSocketImpl.startHandshake(SSLSocketImpl.java:1379) ~[na:1.8.0_222]
at sun.net.www.protocol.https.HttpsClient.afterConnect(HttpsClient.java:559) ~[na:1.8.0_222]
at sun.net.www.protocol.https.AbstractDelegateHttpsURLConnection.connect(AbstractDelegateHttpsURLConnection.java:185) ~[na:1.8.0_222]
at sun.net.www.protocol.http.HttpURLConnection.getInputStream0(HttpURLConnection.java:1570) ~[na:1.8.0_222]
at sun.net.www.protocol.http.HttpURLConnection.getInputStream(HttpURLConnection.java:1498) ~[na:1.8.0_222]
at java.net.HttpURLConnection.getResponseCode(HttpURLConnection.java:480) ~[na:1.8.0_222]
at sun.net.www.protocol.https.HttpsURLConnectionImpl.getResponseCode(HttpsURLConnectionImpl.java:352) ~[na:1.8.0_222]
at org.glassfish.jersey.client.HttpUrlConnector._apply(HttpUrlConnector.java:276) ~[jersey-client-2.5.1.jar:na]
at org.glassfish.jersey.client.HttpUrlConnector.apply(HttpUrlConnector.java:182) ~[jersey-client-2.5.1.jar:na]
... 48 common frames omitted
Tests run: 4, Failures: 0, Errors: 0, Skipped: 1, Time elapsed: 420.673 sec - in co.codewizards.cloudstore.test.CertificateHandlingAndTestServiceLRT
08:16:49.345 [Thread-4] INFO o.e.j.s.AbstractConnector - Stopped ServerConnector@74e73f4c{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
08:16:49.347 [Thread-4] INFO o.e.j.s.session - node0 Stopped scavenging
08:16:49.354 [Thread-4] INFO o.e.j.s.h.ContextHandler - Stopped o.e.j.s.ServletContextHandler@a7f9d9b{/,null,UNAVAILABLE}
08:16:49.364 [Thread-4] INFO c.c.c.l.s.LocalServer - onStopOrFailure: File did not exist (could not delete): /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.test/target/jvm/nGUnUPethm5AJ3AQ7fTC-g/.cloudstore/localServerRunning.deleteToStop
08:16:49.373 [Thread-4] INFO o.e.j.s.AbstractConnector - Stopped ServerConnector@72cdfc24{SSL,[ssl, http/1.1]}{0.0.0.0:35832}
08:16:49.374 [Thread-4] INFO o.e.j.s.session - node0 Stopped scavenging
08:16:49.375 [Thread-4] INFO o.e.j.s.h.ContextHandler - Stopped o.e.j.s.ServletContextHandler@24106a02{/,null,UNAVAILABLE}
Results :
Tests in error:
PathPrefixedConfigInheritanceRepoToRepoSyncIT.parentConfigWithLocalPathPrefix:174 » Runtime
Tests run: 48, Failures: 0, Errors: 1, Skipped: 1
[JENKINS] Recording test results
[INFO]
[INFO] --- maven-source-plugin:2.1.2:jar-no-fork (attach-sources) @ co.codewizards.cloudstore.test ---
[INFO] co already added, skipping
[INFO] co/codewizards already added, skipping
[INFO] co/codewizards/cloudstore already added, skipping
[INFO] co/codewizards/cloudstore/test already added, skipping
[INFO] Building jar: /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.test/target/co.codewizards.cloudstore.test-0.10.7-SNAPSHOT-sources.jar
[INFO] co already added, skipping
[INFO] co/codewizards already added, skipping
[INFO] co/codewizards/cloudstore already added, skipping
[INFO] co/codewizards/cloudstore/test already added, skipping
[INFO]
[INFO] --- maven-failsafe-plugin:2.17:verify (default) @ co.codewizards.cloudstore.test ---
[INFO] Failsafe report directory: /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.test/target/failsafe-reports
[ERROR] There are test failures.
Please refer to /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.test/target/failsafe-reports for the individual test results.
[JENKINS] Recording test results
[INFO]
[INFO] --- maven-install-plugin:2.3.1:install (default-install) @ co.codewizards.cloudstore.test ---
[INFO] Installing /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.test/target/co.codewizards.cloudstore.test-0.10.7-SNAPSHOT.jar to /home/tomcat/.m2/repository/co/codewizards/cloudstore/co.codewizards.cloudstore.test/0.10.7-SNAPSHOT/co.codewizards.cloudstore.test-0.10.7-SNAPSHOT.jar
[INFO] Installing /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.test/pom.xml to /home/tomcat/.m2/repository/co/codewizards/cloudstore/co.codewizards.cloudstore.test/0.10.7-SNAPSHOT/co.codewizards.cloudstore.test-0.10.7-SNAPSHOT.pom
[INFO] Installing /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.test/target/co.codewizards.cloudstore.test-0.10.7-SNAPSHOT-sources.jar to /home/tomcat/.m2/repository/co/codewizards/cloudstore/co.codewizards.cloudstore.test/0.10.7-SNAPSHOT/co.codewizards.cloudstore.test-0.10.7-SNAPSHOT-sources.jar
[INFO]
[INFO] --- maven-deploy-plugin:2.7:deploy (default-deploy) @ co.codewizards.cloudstore.test ---
Downloading: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/snapshot/co/codewizards/cloudstore/co.codewizards.cloudstore.test/0.10.7-SNAPSHOT/maven-metadata.xml
Downloaded: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/snapshot/co/codewizards/cloudstore/co.codewizards.cloudstore.test/0.10.7-SNAPSHOT/maven-metadata.xml (2 KB at 25.1 KB/sec)
Uploading: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/snapshot/co/codewizards/cloudstore/co.codewizards.cloudstore.test/0.10.7-SNAPSHOT/co.codewizards.cloudstore.test-0.10.7-20190811.061650-16.jar
Uploaded: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/snapshot/co/codewizards/cloudstore/co.codewizards.cloudstore.test/0.10.7-SNAPSHOT/co.codewizards.cloudstore.test-0.10.7-20190811.061650-16.jar (7 KB at 642.6 KB/sec)
Uploading: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/snapshot/co/codewizards/cloudstore/co.codewizards.cloudstore.test/0.10.7-SNAPSHOT/co.codewizards.cloudstore.test-0.10.7-20190811.061650-16.pom
Uploaded: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/snapshot/co/codewizards/cloudstore/co.codewizards.cloudstore.test/0.10.7-SNAPSHOT/co.codewizards.cloudstore.test-0.10.7-20190811.061650-16.pom (6 KB at 344.5 KB/sec)
Downloading: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/snapshot/co/codewizards/cloudstore/co.codewizards.cloudstore.test/maven-metadata.xml
Downloaded: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/snapshot/co/codewizards/cloudstore/co.codewizards.cloudstore.test/maven-metadata.xml (398 B at 97.2 KB/sec)
Uploading: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/snapshot/co/codewizards/cloudstore/co.codewizards.cloudstore.test/0.10.7-SNAPSHOT/maven-metadata.xml
Uploaded: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/snapshot/co/codewizards/cloudstore/co.codewizards.cloudstore.test/0.10.7-SNAPSHOT/maven-metadata.xml (2 KB at 1002.9 KB/sec)
Uploading: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/snapshot/co/codewizards/cloudstore/co.codewizards.cloudstore.test/maven-metadata.xml
Uploaded: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/snapshot/co/codewizards/cloudstore/co.codewizards.cloudstore.test/maven-metadata.xml (398 B at 388.7 KB/sec)
Uploading: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/snapshot/co/codewizards/cloudstore/co.codewizards.cloudstore.test/0.10.7-SNAPSHOT/co.codewizards.cloudstore.test-0.10.7-20190811.061650-16-sources.jar
Uploaded: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/snapshot/co/codewizards/cloudstore/co.codewizards.cloudstore.test/0.10.7-SNAPSHOT/co.codewizards.cloudstore.test-0.10.7-20190811.061650-16-sources.jar (3 KB at 2148.4 KB/sec)
Uploading: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/snapshot/co/codewizards/cloudstore/co.codewizards.cloudstore.test/0.10.7-SNAPSHOT/maven-metadata.xml
Uploaded: file:/srv/www/nightly/cloudstore.codewizards.co/htdocs/maven/snapshot/co/codewizards/cloudstore/co.codewizards.cloudstore.test/0.10.7-SNAPSHOT/maven-metadata.xml (2 KB)
[INFO]
[INFO] --- maven-site-plugin:3.3:site (default-site) @ co.codewizards.cloudstore.test ---
[INFO] Relativizing decoration links with respect to project URL: http://cloudstore.codewizards.co/co.codewizards.cloudstore.test
[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.test/de
[INFO] Rendering site with org.apache.maven.skins:maven-default-skin:jar:1.0 skin.
[JENKINS] Archiving site from /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.test/target/site to /home/tomcat/.jenkins/jobs/co.codewizards.cloudstore/site/co.codewizards.cloudstore.test
[WARNING] Failed to getClass for org.apache.maven.plugin.javadoc.JavadocReport
[INFO]
[INFO] --- maven-javadoc-plugin:2.9.1:javadoc (javadoc-generate-unfiltered) @ co.codewizards.cloudstore.test ---
[INFO]
[INFO] --- maven-resources-plugin:2.5:copy-resources (javadoc-filter) @ co.codewizards.cloudstore.test ---
[debug] execute contextualize
[INFO] Using 'UTF-8' encoding to copy filtered resources.
[INFO] skip non existing resourceDirectory /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.test/target/javadoc.unfiltered
[INFO] skip non existing resourceDirectory /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.test/target/javadoc.unfiltered
[INFO]
[INFO] --- maven-resources-plugin:2.5:copy-resources (javadoc-copy-to-site) @ co.codewizards.cloudstore.test ---
[debug] execute contextualize
[INFO] Using 'UTF-8' encoding to copy filtered resources.
[INFO] skip non existing resourceDirectory /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.test/target/apidocs
[INFO]
[INFO] --- maven-site-plugin:3.3:deploy (default-deploy) @ co.codewizards.cloudstore.test ---
file:/tmp/cloudstore-junk/ - Session: Opened
[INFO] Pushing /home/tomcat/.jenkins/workspace/co.codewizards.cloudstore/co.codewizards.cloudstore.test/target/site
[INFO] >>> to file:/tmp/cloudstore-junk/co.codewizards.cloudstore.test
[INFO] >>> to file:/tmp/cloudstore-junk/de/co.codewizards.cloudstore.test
file:/tmp/cloudstore-junk/ - Session: Disconnecting
file:/tmp/cloudstore-junk/ - Session: Disconnected
|
Help us localize this page