You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@lucene.apache.org by Apache Jenkins Server <je...@builds.apache.org> on 2018/03/28 22:14:53 UTC
[JENKINS] Lucene-Solr-BadApples-Tests-master - Build # 23 - Failure
Build: https://builds.apache.org/job/Lucene-Solr-BadApples-Tests-master/23/
No tests ran.
Build Log:
[...truncated 6 lines...]
ERROR: Error fetching remote repo 'origin'
hudson.plugins.git.GitException: Failed to fetch from git://git.apache.org/lucene-solr.git
at hudson.plugins.git.GitSCM.fetchFrom(GitSCM.java:862)
at hudson.plugins.git.GitSCM.retrieveChanges(GitSCM.java:1129)
at hudson.plugins.git.GitSCM.checkout(GitSCM.java:1160)
at hudson.scm.SCM.checkout(SCM.java:495)
at hudson.model.AbstractProject.checkout(AbstractProject.java:1202)
at hudson.model.AbstractBuild$AbstractBuildExecution.defaultCheckout(AbstractBuild.java:574)
at jenkins.scm.SCMCheckoutStrategy.checkout(SCMCheckoutStrategy.java:86)
at hudson.model.AbstractBuild$AbstractBuildExecution.run(AbstractBuild.java:499)
at hudson.model.Run.execute(Run.java:1724)
at hudson.model.FreeStyleBuild.run(FreeStyleBuild.java:43)
at hudson.model.ResourceController.execute(ResourceController.java:97)
at hudson.model.Executor.run(Executor.java:429)
Caused by: hudson.plugins.git.GitException: Command "git config remote.origin.url git://git.apache.org/lucene-solr.git" returned status code 4:
stdout:
stderr: error: failed to write new configuration file .git/config.lock
at org.jenkinsci.plugins.gitclient.CliGitAPIImpl.launchCommandIn(CliGitAPIImpl.java:1996)
at org.jenkinsci.plugins.gitclient.CliGitAPIImpl.launchCommandIn(CliGitAPIImpl.java:1964)
at org.jenkinsci.plugins.gitclient.CliGitAPIImpl.launchCommandIn(CliGitAPIImpl.java:1960)
at org.jenkinsci.plugins.gitclient.CliGitAPIImpl.launchCommand(CliGitAPIImpl.java:1597)
at org.jenkinsci.plugins.gitclient.CliGitAPIImpl.launchCommand(CliGitAPIImpl.java:1609)
at org.jenkinsci.plugins.gitclient.CliGitAPIImpl.setRemoteUrl(CliGitAPIImpl.java:1243)
at hudson.plugins.git.GitAPI.setRemoteUrl(GitAPI.java:160)
at sun.reflect.GeneratedMethodAccessor239.invoke(Unknown Source)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at hudson.remoting.RemoteInvocationHandler$RPCRequest.perform(RemoteInvocationHandler.java:922)
at hudson.remoting.RemoteInvocationHandler$RPCRequest.call(RemoteInvocationHandler.java:896)
at hudson.remoting.RemoteInvocationHandler$RPCRequest.call(RemoteInvocationHandler.java:853)
at hudson.remoting.UserRequest.perform(UserRequest.java:207)
at hudson.remoting.UserRequest.perform(UserRequest.java:53)
at hudson.remoting.Request$2.run(Request.java:358)
at hudson.remoting.InterceptingExecutorService$1.call(InterceptingExecutorService.java:72)
at java.util.concurrent.FutureTask.run(FutureTask.java:266)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
at java.lang.Thread.run(Thread.java:748)
Suppressed: hudson.remoting.Channel$CallSiteStackTrace: Remote call to lucene
at hudson.remoting.Channel.attachCallSiteStackTrace(Channel.java:1693)
at hudson.remoting.UserResponse.retrieve(UserRequest.java:310)
at hudson.remoting.Channel.call(Channel.java:908)
at hudson.remoting.RemoteInvocationHandler.invoke(RemoteInvocationHandler.java:281)
at com.sun.proxy.$Proxy110.setRemoteUrl(Unknown Source)
at org.jenkinsci.plugins.gitclient.RemoteGitImpl.setRemoteUrl(RemoteGitImpl.java:295)
at hudson.plugins.git.GitSCM.fetchFrom(GitSCM.java:850)
at hudson.plugins.git.GitSCM.retrieveChanges(GitSCM.java:1129)
at hudson.plugins.git.GitSCM.checkout(GitSCM.java:1160)
at hudson.scm.SCM.checkout(SCM.java:495)
at hudson.model.AbstractProject.checkout(AbstractProject.java:1202)
at hudson.model.AbstractBuild$AbstractBuildExecution.defaultCheckout(AbstractBuild.java:574)
at jenkins.scm.SCMCheckoutStrategy.checkout(SCMCheckoutStrategy.java:86)
at hudson.model.AbstractBuild$AbstractBuildExecution.run(AbstractBuild.java:499)
at hudson.model.Run.execute(Run.java:1724)
at hudson.model.FreeStyleBuild.run(FreeStyleBuild.java:43)
at hudson.model.ResourceController.execute(ResourceController.java:97)
at hudson.model.Executor.run(Executor.java:429)
ERROR: Error fetching remote repo 'origin'
Retrying after 10 seconds
> git rev-parse --is-inside-work-tree # timeout=10
Fetching changes from the remote Git repository
> git config remote.origin.url git://git.apache.org/lucene-solr.git # timeout=10
ERROR: Error fetching remote repo 'origin'
hudson.plugins.git.GitException: Failed to fetch from git://git.apache.org/lucene-solr.git
at hudson.plugins.git.GitSCM.fetchFrom(GitSCM.java:862)
at hudson.plugins.git.GitSCM.retrieveChanges(GitSCM.java:1129)
at hudson.plugins.git.GitSCM.checkout(GitSCM.java:1160)
at hudson.scm.SCM.checkout(SCM.java:495)
at hudson.model.AbstractProject.checkout(AbstractProject.java:1202)
at hudson.model.AbstractBuild$AbstractBuildExecution.defaultCheckout(AbstractBuild.java:574)
at jenkins.scm.SCMCheckoutStrategy.checkout(SCMCheckoutStrategy.java:86)
at hudson.model.AbstractBuild$AbstractBuildExecution.run(AbstractBuild.java:499)
at hudson.model.Run.execute(Run.java:1724)
at hudson.model.FreeStyleBuild.run(FreeStyleBuild.java:43)
at hudson.model.ResourceController.execute(ResourceController.java:97)
at hudson.model.Executor.run(Executor.java:429)
Caused by: hudson.plugins.git.GitException: Command "git config remote.origin.url git://git.apache.org/lucene-solr.git" returned status code 4:
stdout:
stderr: error: failed to write new configuration file .git/config.lock
at org.jenkinsci.plugins.gitclient.CliGitAPIImpl.launchCommandIn(CliGitAPIImpl.java:1996)
at org.jenkinsci.plugins.gitclient.CliGitAPIImpl.launchCommandIn(CliGitAPIImpl.java:1964)
at org.jenkinsci.plugins.gitclient.CliGitAPIImpl.launchCommandIn(CliGitAPIImpl.java:1960)
at org.jenkinsci.plugins.gitclient.CliGitAPIImpl.launchCommand(CliGitAPIImpl.java:1597)
at org.jenkinsci.plugins.gitclient.CliGitAPIImpl.launchCommand(CliGitAPIImpl.java:1609)
at org.jenkinsci.plugins.gitclient.CliGitAPIImpl.setRemoteUrl(CliGitAPIImpl.java:1243)
at hudson.plugins.git.GitAPI.setRemoteUrl(GitAPI.java:160)
at sun.reflect.GeneratedMethodAccessor239.invoke(Unknown Source)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at hudson.remoting.RemoteInvocationHandler$RPCRequest.perform(RemoteInvocationHandler.java:922)
at hudson.remoting.RemoteInvocationHandler$RPCRequest.call(RemoteInvocationHandler.java:896)
at hudson.remoting.RemoteInvocationHandler$RPCRequest.call(RemoteInvocationHandler.java:853)
at hudson.remoting.UserRequest.perform(UserRequest.java:207)
at hudson.remoting.UserRequest.perform(UserRequest.java:53)
at hudson.remoting.Request$2.run(Request.java:358)
at hudson.remoting.InterceptingExecutorService$1.call(InterceptingExecutorService.java:72)
at java.util.concurrent.FutureTask.run(FutureTask.java:266)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
at java.lang.Thread.run(Thread.java:748)
Suppressed: hudson.remoting.Channel$CallSiteStackTrace: Remote call to lucene
at hudson.remoting.Channel.attachCallSiteStackTrace(Channel.java:1693)
at hudson.remoting.UserResponse.retrieve(UserRequest.java:310)
at hudson.remoting.Channel.call(Channel.java:908)
at hudson.remoting.RemoteInvocationHandler.invoke(RemoteInvocationHandler.java:281)
at com.sun.proxy.$Proxy110.setRemoteUrl(Unknown Source)
at org.jenkinsci.plugins.gitclient.RemoteGitImpl.setRemoteUrl(RemoteGitImpl.java:295)
at hudson.plugins.git.GitSCM.fetchFrom(GitSCM.java:850)
at hudson.plugins.git.GitSCM.retrieveChanges(GitSCM.java:1129)
at hudson.plugins.git.GitSCM.checkout(GitSCM.java:1160)
at hudson.scm.SCM.checkout(SCM.java:495)
at hudson.model.AbstractProject.checkout(AbstractProject.java:1202)
at hudson.model.AbstractBuild$AbstractBuildExecution.defaultCheckout(AbstractBuild.java:574)
at jenkins.scm.SCMCheckoutStrategy.checkout(SCMCheckoutStrategy.java:86)
at hudson.model.AbstractBuild$AbstractBuildExecution.run(AbstractBuild.java:499)
at hudson.model.Run.execute(Run.java:1724)
at hudson.model.FreeStyleBuild.run(FreeStyleBuild.java:43)
at hudson.model.ResourceController.execute(ResourceController.java:97)
at hudson.model.Executor.run(Executor.java:429)
ERROR: Error fetching remote repo 'origin'
Retrying after 10 seconds
> git rev-parse --is-inside-work-tree # timeout=10
Fetching changes from the remote Git repository
> git config remote.origin.url git://git.apache.org/lucene-solr.git # timeout=10
ERROR: Error fetching remote repo 'origin'
hudson.plugins.git.GitException: Failed to fetch from git://git.apache.org/lucene-solr.git
at hudson.plugins.git.GitSCM.fetchFrom(GitSCM.java:862)
at hudson.plugins.git.GitSCM.retrieveChanges(GitSCM.java:1129)
at hudson.plugins.git.GitSCM.checkout(GitSCM.java:1160)
at hudson.scm.SCM.checkout(SCM.java:495)
at hudson.model.AbstractProject.checkout(AbstractProject.java:1202)
at hudson.model.AbstractBuild$AbstractBuildExecution.defaultCheckout(AbstractBuild.java:574)
at jenkins.scm.SCMCheckoutStrategy.checkout(SCMCheckoutStrategy.java:86)
at hudson.model.AbstractBuild$AbstractBuildExecution.run(AbstractBuild.java:499)
at hudson.model.Run.execute(Run.java:1724)
at hudson.model.FreeStyleBuild.run(FreeStyleBuild.java:43)
at hudson.model.ResourceController.execute(ResourceController.java:97)
at hudson.model.Executor.run(Executor.java:429)
Caused by: hudson.plugins.git.GitException: Command "git config remote.origin.url git://git.apache.org/lucene-solr.git" returned status code 4:
stdout:
stderr: error: failed to write new configuration file .git/config.lock
at org.jenkinsci.plugins.gitclient.CliGitAPIImpl.launchCommandIn(CliGitAPIImpl.java:1996)
at org.jenkinsci.plugins.gitclient.CliGitAPIImpl.launchCommandIn(CliGitAPIImpl.java:1964)
at org.jenkinsci.plugins.gitclient.CliGitAPIImpl.launchCommandIn(CliGitAPIImpl.java:1960)
at org.jenkinsci.plugins.gitclient.CliGitAPIImpl.launchCommand(CliGitAPIImpl.java:1597)
at org.jenkinsci.plugins.gitclient.CliGitAPIImpl.launchCommand(CliGitAPIImpl.java:1609)
at org.jenkinsci.plugins.gitclient.CliGitAPIImpl.setRemoteUrl(CliGitAPIImpl.java:1243)
at hudson.plugins.git.GitAPI.setRemoteUrl(GitAPI.java:160)
at sun.reflect.GeneratedMethodAccessor239.invoke(Unknown Source)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at hudson.remoting.RemoteInvocationHandler$RPCRequest.perform(RemoteInvocationHandler.java:922)
at hudson.remoting.RemoteInvocationHandler$RPCRequest.call(RemoteInvocationHandler.java:896)
at hudson.remoting.RemoteInvocationHandler$RPCRequest.call(RemoteInvocationHandler.java:853)
at hudson.remoting.UserRequest.perform(UserRequest.java:207)
at hudson.remoting.UserRequest.perform(UserRequest.java:53)
at hudson.remoting.Request$2.run(Request.java:358)
at hudson.remoting.InterceptingExecutorService$1.call(InterceptingExecutorService.java:72)
at java.util.concurrent.FutureTask.run(FutureTask.java:266)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
at java.lang.Thread.run(Thread.java:748)
Suppressed: hudson.remoting.Channel$CallSiteStackTrace: Remote call to lucene
at hudson.remoting.Channel.attachCallSiteStackTrace(Channel.java:1693)
at hudson.remoting.UserResponse.retrieve(UserRequest.java:310)
at hudson.remoting.Channel.call(Channel.java:908)
at hudson.remoting.RemoteInvocationHandler.invoke(RemoteInvocationHandler.java:281)
at com.sun.proxy.$Proxy110.setRemoteUrl(Unknown Source)
at org.jenkinsci.plugins.gitclient.RemoteGitImpl.setRemoteUrl(RemoteGitImpl.java:295)
at hudson.plugins.git.GitSCM.fetchFrom(GitSCM.java:850)
at hudson.plugins.git.GitSCM.retrieveChanges(GitSCM.java:1129)
at hudson.plugins.git.GitSCM.checkout(GitSCM.java:1160)
at hudson.scm.SCM.checkout(SCM.java:495)
at hudson.model.AbstractProject.checkout(AbstractProject.java:1202)
at hudson.model.AbstractBuild$AbstractBuildExecution.defaultCheckout(AbstractBuild.java:574)
at jenkins.scm.SCMCheckoutStrategy.checkout(SCMCheckoutStrategy.java:86)
at hudson.model.AbstractBuild$AbstractBuildExecution.run(AbstractBuild.java:499)
at hudson.model.Run.execute(Run.java:1724)
at hudson.model.FreeStyleBuild.run(FreeStyleBuild.java:43)
at hudson.model.ResourceController.execute(ResourceController.java:97)
at hudson.model.Executor.run(Executor.java:429)
ERROR: Error fetching remote repo 'origin'
Archiving artifacts
Recording test results
ERROR: Step ‘Publish JUnit test result report’ failed: Test reports were found but none of them are new. Did leafNodes run?
For example, /home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/lucene/build/analysis/common/test/TEST-org.apache.lucene.analysis.ar.TestArabicAnalyzer.xml is 9 hr 54 min old
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any
[JENKINS] Lucene-Solr-BadApples-Tests-master - Build # 25 - Failure
Posted by Apache Jenkins Server <je...@builds.apache.org>.
Build: https://builds.apache.org/job/Lucene-Solr-BadApples-Tests-master/25/
No tests ran.
Build Log:
[...truncated 6 lines...]
ERROR: Error fetching remote repo 'origin'
hudson.plugins.git.GitException: Failed to fetch from git://git.apache.org/lucene-solr.git
at hudson.plugins.git.GitSCM.fetchFrom(GitSCM.java:862)
at hudson.plugins.git.GitSCM.retrieveChanges(GitSCM.java:1129)
at hudson.plugins.git.GitSCM.checkout(GitSCM.java:1160)
at hudson.scm.SCM.checkout(SCM.java:495)
at hudson.model.AbstractProject.checkout(AbstractProject.java:1202)
at hudson.model.AbstractBuild$AbstractBuildExecution.defaultCheckout(AbstractBuild.java:574)
at jenkins.scm.SCMCheckoutStrategy.checkout(SCMCheckoutStrategy.java:86)
at hudson.model.AbstractBuild$AbstractBuildExecution.run(AbstractBuild.java:499)
at hudson.model.Run.execute(Run.java:1724)
at hudson.model.FreeStyleBuild.run(FreeStyleBuild.java:43)
at hudson.model.ResourceController.execute(ResourceController.java:97)
at hudson.model.Executor.run(Executor.java:429)
Caused by: hudson.plugins.git.GitException: Command "git config remote.origin.url git://git.apache.org/lucene-solr.git" returned status code 4:
stdout:
stderr: error: failed to write new configuration file .git/config.lock
at org.jenkinsci.plugins.gitclient.CliGitAPIImpl.launchCommandIn(CliGitAPIImpl.java:1996)
at org.jenkinsci.plugins.gitclient.CliGitAPIImpl.launchCommandIn(CliGitAPIImpl.java:1964)
at org.jenkinsci.plugins.gitclient.CliGitAPIImpl.launchCommandIn(CliGitAPIImpl.java:1960)
at org.jenkinsci.plugins.gitclient.CliGitAPIImpl.launchCommand(CliGitAPIImpl.java:1597)
at org.jenkinsci.plugins.gitclient.CliGitAPIImpl.launchCommand(CliGitAPIImpl.java:1609)
at org.jenkinsci.plugins.gitclient.CliGitAPIImpl.setRemoteUrl(CliGitAPIImpl.java:1243)
at hudson.plugins.git.GitAPI.setRemoteUrl(GitAPI.java:160)
at sun.reflect.GeneratedMethodAccessor63.invoke(Unknown Source)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at hudson.remoting.RemoteInvocationHandler$RPCRequest.perform(RemoteInvocationHandler.java:922)
at hudson.remoting.RemoteInvocationHandler$RPCRequest.call(RemoteInvocationHandler.java:896)
at hudson.remoting.RemoteInvocationHandler$RPCRequest.call(RemoteInvocationHandler.java:853)
at hudson.remoting.UserRequest.perform(UserRequest.java:207)
at hudson.remoting.UserRequest.perform(UserRequest.java:53)
at hudson.remoting.Request$2.run(Request.java:358)
at hudson.remoting.InterceptingExecutorService$1.call(InterceptingExecutorService.java:72)
at java.util.concurrent.FutureTask.run(FutureTask.java:266)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
at java.lang.Thread.run(Thread.java:748)
Suppressed: hudson.remoting.Channel$CallSiteStackTrace: Remote call to lucene
at hudson.remoting.Channel.attachCallSiteStackTrace(Channel.java:1693)
at hudson.remoting.UserResponse.retrieve(UserRequest.java:310)
at hudson.remoting.Channel.call(Channel.java:908)
at hudson.remoting.RemoteInvocationHandler.invoke(RemoteInvocationHandler.java:281)
at com.sun.proxy.$Proxy110.setRemoteUrl(Unknown Source)
at org.jenkinsci.plugins.gitclient.RemoteGitImpl.setRemoteUrl(RemoteGitImpl.java:295)
at hudson.plugins.git.GitSCM.fetchFrom(GitSCM.java:850)
at hudson.plugins.git.GitSCM.retrieveChanges(GitSCM.java:1129)
at hudson.plugins.git.GitSCM.checkout(GitSCM.java:1160)
at hudson.scm.SCM.checkout(SCM.java:495)
at hudson.model.AbstractProject.checkout(AbstractProject.java:1202)
at hudson.model.AbstractBuild$AbstractBuildExecution.defaultCheckout(AbstractBuild.java:574)
at jenkins.scm.SCMCheckoutStrategy.checkout(SCMCheckoutStrategy.java:86)
at hudson.model.AbstractBuild$AbstractBuildExecution.run(AbstractBuild.java:499)
at hudson.model.Run.execute(Run.java:1724)
at hudson.model.FreeStyleBuild.run(FreeStyleBuild.java:43)
at hudson.model.ResourceController.execute(ResourceController.java:97)
at hudson.model.Executor.run(Executor.java:429)
ERROR: Error fetching remote repo 'origin'
Retrying after 10 seconds
> git rev-parse --is-inside-work-tree # timeout=10
Fetching changes from the remote Git repository
> git config remote.origin.url git://git.apache.org/lucene-solr.git # timeout=10
ERROR: Error fetching remote repo 'origin'
hudson.plugins.git.GitException: Failed to fetch from git://git.apache.org/lucene-solr.git
at hudson.plugins.git.GitSCM.fetchFrom(GitSCM.java:862)
at hudson.plugins.git.GitSCM.retrieveChanges(GitSCM.java:1129)
at hudson.plugins.git.GitSCM.checkout(GitSCM.java:1160)
at hudson.scm.SCM.checkout(SCM.java:495)
at hudson.model.AbstractProject.checkout(AbstractProject.java:1202)
at hudson.model.AbstractBuild$AbstractBuildExecution.defaultCheckout(AbstractBuild.java:574)
at jenkins.scm.SCMCheckoutStrategy.checkout(SCMCheckoutStrategy.java:86)
at hudson.model.AbstractBuild$AbstractBuildExecution.run(AbstractBuild.java:499)
at hudson.model.Run.execute(Run.java:1724)
at hudson.model.FreeStyleBuild.run(FreeStyleBuild.java:43)
at hudson.model.ResourceController.execute(ResourceController.java:97)
at hudson.model.Executor.run(Executor.java:429)
Caused by: hudson.plugins.git.GitException: Command "git config remote.origin.url git://git.apache.org/lucene-solr.git" returned status code 4:
stdout:
stderr: error: failed to write new configuration file .git/config.lock
at org.jenkinsci.plugins.gitclient.CliGitAPIImpl.launchCommandIn(CliGitAPIImpl.java:1996)
at org.jenkinsci.plugins.gitclient.CliGitAPIImpl.launchCommandIn(CliGitAPIImpl.java:1964)
at org.jenkinsci.plugins.gitclient.CliGitAPIImpl.launchCommandIn(CliGitAPIImpl.java:1960)
at org.jenkinsci.plugins.gitclient.CliGitAPIImpl.launchCommand(CliGitAPIImpl.java:1597)
at org.jenkinsci.plugins.gitclient.CliGitAPIImpl.launchCommand(CliGitAPIImpl.java:1609)
at org.jenkinsci.plugins.gitclient.CliGitAPIImpl.setRemoteUrl(CliGitAPIImpl.java:1243)
at hudson.plugins.git.GitAPI.setRemoteUrl(GitAPI.java:160)
at sun.reflect.GeneratedMethodAccessor63.invoke(Unknown Source)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at hudson.remoting.RemoteInvocationHandler$RPCRequest.perform(RemoteInvocationHandler.java:922)
at hudson.remoting.RemoteInvocationHandler$RPCRequest.call(RemoteInvocationHandler.java:896)
at hudson.remoting.RemoteInvocationHandler$RPCRequest.call(RemoteInvocationHandler.java:853)
at hudson.remoting.UserRequest.perform(UserRequest.java:207)
at hudson.remoting.UserRequest.perform(UserRequest.java:53)
at hudson.remoting.Request$2.run(Request.java:358)
at hudson.remoting.InterceptingExecutorService$1.call(InterceptingExecutorService.java:72)
at java.util.concurrent.FutureTask.run(FutureTask.java:266)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
at java.lang.Thread.run(Thread.java:748)
Suppressed: hudson.remoting.Channel$CallSiteStackTrace: Remote call to lucene
at hudson.remoting.Channel.attachCallSiteStackTrace(Channel.java:1693)
at hudson.remoting.UserResponse.retrieve(UserRequest.java:310)
at hudson.remoting.Channel.call(Channel.java:908)
at hudson.remoting.RemoteInvocationHandler.invoke(RemoteInvocationHandler.java:281)
at com.sun.proxy.$Proxy110.setRemoteUrl(Unknown Source)
at org.jenkinsci.plugins.gitclient.RemoteGitImpl.setRemoteUrl(RemoteGitImpl.java:295)
at hudson.plugins.git.GitSCM.fetchFrom(GitSCM.java:850)
at hudson.plugins.git.GitSCM.retrieveChanges(GitSCM.java:1129)
at hudson.plugins.git.GitSCM.checkout(GitSCM.java:1160)
at hudson.scm.SCM.checkout(SCM.java:495)
at hudson.model.AbstractProject.checkout(AbstractProject.java:1202)
at hudson.model.AbstractBuild$AbstractBuildExecution.defaultCheckout(AbstractBuild.java:574)
at jenkins.scm.SCMCheckoutStrategy.checkout(SCMCheckoutStrategy.java:86)
at hudson.model.AbstractBuild$AbstractBuildExecution.run(AbstractBuild.java:499)
at hudson.model.Run.execute(Run.java:1724)
at hudson.model.FreeStyleBuild.run(FreeStyleBuild.java:43)
at hudson.model.ResourceController.execute(ResourceController.java:97)
at hudson.model.Executor.run(Executor.java:429)
ERROR: Error fetching remote repo 'origin'
Retrying after 10 seconds
> git rev-parse --is-inside-work-tree # timeout=10
Fetching changes from the remote Git repository
> git config remote.origin.url git://git.apache.org/lucene-solr.git # timeout=10
ERROR: Error fetching remote repo 'origin'
hudson.plugins.git.GitException: Failed to fetch from git://git.apache.org/lucene-solr.git
at hudson.plugins.git.GitSCM.fetchFrom(GitSCM.java:862)
at hudson.plugins.git.GitSCM.retrieveChanges(GitSCM.java:1129)
at hudson.plugins.git.GitSCM.checkout(GitSCM.java:1160)
at hudson.scm.SCM.checkout(SCM.java:495)
at hudson.model.AbstractProject.checkout(AbstractProject.java:1202)
at hudson.model.AbstractBuild$AbstractBuildExecution.defaultCheckout(AbstractBuild.java:574)
at jenkins.scm.SCMCheckoutStrategy.checkout(SCMCheckoutStrategy.java:86)
at hudson.model.AbstractBuild$AbstractBuildExecution.run(AbstractBuild.java:499)
at hudson.model.Run.execute(Run.java:1724)
at hudson.model.FreeStyleBuild.run(FreeStyleBuild.java:43)
at hudson.model.ResourceController.execute(ResourceController.java:97)
at hudson.model.Executor.run(Executor.java:429)
Caused by: hudson.plugins.git.GitException: Command "git config remote.origin.url git://git.apache.org/lucene-solr.git" returned status code 4:
stdout:
stderr: error: failed to write new configuration file .git/config.lock
at org.jenkinsci.plugins.gitclient.CliGitAPIImpl.launchCommandIn(CliGitAPIImpl.java:1996)
at org.jenkinsci.plugins.gitclient.CliGitAPIImpl.launchCommandIn(CliGitAPIImpl.java:1964)
at org.jenkinsci.plugins.gitclient.CliGitAPIImpl.launchCommandIn(CliGitAPIImpl.java:1960)
at org.jenkinsci.plugins.gitclient.CliGitAPIImpl.launchCommand(CliGitAPIImpl.java:1597)
at org.jenkinsci.plugins.gitclient.CliGitAPIImpl.launchCommand(CliGitAPIImpl.java:1609)
at org.jenkinsci.plugins.gitclient.CliGitAPIImpl.setRemoteUrl(CliGitAPIImpl.java:1243)
at hudson.plugins.git.GitAPI.setRemoteUrl(GitAPI.java:160)
at sun.reflect.GeneratedMethodAccessor63.invoke(Unknown Source)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at hudson.remoting.RemoteInvocationHandler$RPCRequest.perform(RemoteInvocationHandler.java:922)
at hudson.remoting.RemoteInvocationHandler$RPCRequest.call(RemoteInvocationHandler.java:896)
at hudson.remoting.RemoteInvocationHandler$RPCRequest.call(RemoteInvocationHandler.java:853)
at hudson.remoting.UserRequest.perform(UserRequest.java:207)
at hudson.remoting.UserRequest.perform(UserRequest.java:53)
at hudson.remoting.Request$2.run(Request.java:358)
at hudson.remoting.InterceptingExecutorService$1.call(InterceptingExecutorService.java:72)
at java.util.concurrent.FutureTask.run(FutureTask.java:266)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
at java.lang.Thread.run(Thread.java:748)
Suppressed: hudson.remoting.Channel$CallSiteStackTrace: Remote call to lucene
at hudson.remoting.Channel.attachCallSiteStackTrace(Channel.java:1693)
at hudson.remoting.UserResponse.retrieve(UserRequest.java:310)
at hudson.remoting.Channel.call(Channel.java:908)
at hudson.remoting.RemoteInvocationHandler.invoke(RemoteInvocationHandler.java:281)
at com.sun.proxy.$Proxy110.setRemoteUrl(Unknown Source)
at org.jenkinsci.plugins.gitclient.RemoteGitImpl.setRemoteUrl(RemoteGitImpl.java:295)
at hudson.plugins.git.GitSCM.fetchFrom(GitSCM.java:850)
at hudson.plugins.git.GitSCM.retrieveChanges(GitSCM.java:1129)
at hudson.plugins.git.GitSCM.checkout(GitSCM.java:1160)
at hudson.scm.SCM.checkout(SCM.java:495)
at hudson.model.AbstractProject.checkout(AbstractProject.java:1202)
at hudson.model.AbstractBuild$AbstractBuildExecution.defaultCheckout(AbstractBuild.java:574)
at jenkins.scm.SCMCheckoutStrategy.checkout(SCMCheckoutStrategy.java:86)
at hudson.model.AbstractBuild$AbstractBuildExecution.run(AbstractBuild.java:499)
at hudson.model.Run.execute(Run.java:1724)
at hudson.model.FreeStyleBuild.run(FreeStyleBuild.java:43)
at hudson.model.ResourceController.execute(ResourceController.java:97)
at hudson.model.Executor.run(Executor.java:429)
ERROR: Error fetching remote repo 'origin'
Archiving artifacts
Recording test results
ERROR: Step ‘Publish JUnit test result report’ failed: Test reports were found but none of them are new. Did leafNodes run?
For example, /home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/lucene/build/analysis/common/test/TEST-org.apache.lucene.analysis.ar.TestArabicAnalyzer.xml is 18 hr old
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any
[JENKINS] Lucene-Solr-BadApples-Tests-master - Build # 24 - Still
unstable
Posted by Apache Jenkins Server <je...@builds.apache.org>.
Build: https://builds.apache.org/job/Lucene-Solr-BadApples-Tests-master/24/
3 tests failed.
FAILED: org.apache.solr.cloud.CollectionPropsTest.testReadWriteCached
Error Message:
Could not see value change after setting collection property. Name: property2, current value: value2, expected value: newValue
Stack Trace:
java.lang.AssertionError: Could not see value change after setting collection property. Name: property2, current value: value2, expected value: newValue
at __randomizedtesting.SeedInfo.seed([1BCE6473A2A5E68A:FD89A9BD30939A79]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.apache.solr.cloud.CollectionPropsTest.waitForValue(CollectionPropsTest.java:146)
at org.apache.solr.cloud.CollectionPropsTest.testReadWriteCached(CollectionPropsTest.java:115)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1737)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:943)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.lang.Thread.run(Thread.java:748)
FAILED: org.apache.solr.cloud.DeleteInactiveReplicaTest.deleteInactiveReplicaTest
Error Message:
Deleted core was still loaded!
Stack Trace:
java.lang.AssertionError: Deleted core was still loaded!
at __randomizedtesting.SeedInfo.seed([1BCE6473A2A5E68A:D6F0FF80ED049068]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.junit.Assert.assertTrue(Assert.java:43)
at org.junit.Assert.assertNotNull(Assert.java:526)
at org.apache.solr.cloud.DeleteInactiveReplicaTest.deleteInactiveReplicaTest(DeleteInactiveReplicaTest.java:86)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1737)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:943)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.lang.Thread.run(Thread.java:748)
FAILED: org.apache.solr.handler.TestReplicationHandler.doTestIndexFetchOnMasterRestart
Error Message:
expected:<1> but was:<2>
Stack Trace:
java.lang.AssertionError: expected:<1> but was:<2>
at __randomizedtesting.SeedInfo.seed([1BCE6473A2A5E68A:C339A097097E24D6]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.junit.Assert.failNotEquals(Assert.java:647)
at org.junit.Assert.assertEquals(Assert.java:128)
at org.junit.Assert.assertEquals(Assert.java:472)
at org.junit.Assert.assertEquals(Assert.java:456)
at org.apache.solr.handler.TestReplicationHandler.doTestIndexFetchOnMasterRestart(TestReplicationHandler.java:664)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1737)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:943)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.lang.Thread.run(Thread.java:748)
Build Log:
[...truncated 1844 lines...]
[junit4] JVM J0: stdout was not empty, see: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/lucene/build/core/test/temp/junit4-J0-20180330_195532_498553079099310282510.sysout
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] codec: Asserting, pf: FSTOrd50, dvf: Memory
[junit4] <<< JVM J0: EOF ----
[...truncated 10244 lines...]
[junit4] Suite: org.apache.solr.cloud.CollectionPropsTest
[junit4] 2> 61084 INFO (SUITE-CollectionPropsTest-seed#[1BCE6473A2A5E68A]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> Creating dataDir: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionPropsTest_1BCE6473A2A5E68A-001/init-core-data-001
[junit4] 2> 61085 WARN (SUITE-CollectionPropsTest-seed#[1BCE6473A2A5E68A]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=4 numCloses=4
[junit4] 2> 61085 INFO (SUITE-CollectionPropsTest-seed#[1BCE6473A2A5E68A]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=true
[junit4] 2> 61086 INFO (SUITE-CollectionPropsTest-seed#[1BCE6473A2A5E68A]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.SolrTestCaseJ4$SuppressSSL(bugUrl=None)
[junit4] 2> 61086 INFO (SUITE-CollectionPropsTest-seed#[1BCE6473A2A5E68A]-worker) [ ] o.a.s.c.CollectionPropsTest Using legacyCloud?: false
[junit4] 2> 61087 INFO (SUITE-CollectionPropsTest-seed#[1BCE6473A2A5E68A]-worker) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 4 servers in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionPropsTest_1BCE6473A2A5E68A-001/tempDir-001
[junit4] 2> 61087 INFO (SUITE-CollectionPropsTest-seed#[1BCE6473A2A5E68A]-worker) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 61087 INFO (Thread-68) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 61087 INFO (Thread-68) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 61093 ERROR (Thread-68) [ ] o.a.z.s.ZooKeeperServer ZKShutdownHandler is not registered, so ZooKeeper server won't take any action on ERROR or SHUTDOWN server state changes
[junit4] 2> 61187 INFO (SUITE-CollectionPropsTest-seed#[1BCE6473A2A5E68A]-worker) [ ] o.a.s.c.ZkTestServer start zk server on port:35243
[junit4] 2> 61200 INFO (zkConnectionManagerCallback-102-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 61205 INFO (jetty-launcher-99-thread-1) [ ] o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: 2017-11-21T23:27:37+02:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8
[junit4] 2> 61210 INFO (jetty-launcher-99-thread-3) [ ] o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: 2017-11-21T23:27:37+02:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8
[junit4] 2> 61210 INFO (jetty-launcher-99-thread-4) [ ] o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: 2017-11-21T23:27:37+02:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8
[junit4] 2> 61213 INFO (jetty-launcher-99-thread-2) [ ] o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: 2017-11-21T23:27:37+02:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8
[junit4] 2> 61216 INFO (jetty-launcher-99-thread-3) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 61216 INFO (jetty-launcher-99-thread-3) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 61216 INFO (jetty-launcher-99-thread-3) [ ] o.e.j.s.session Scavenging every 600000ms
[junit4] 2> 61216 INFO (jetty-launcher-99-thread-3) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@293efa23{/solr,null,AVAILABLE}
[junit4] 2> 61217 INFO (jetty-launcher-99-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 61217 INFO (jetty-launcher-99-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 61217 INFO (jetty-launcher-99-thread-1) [ ] o.e.j.s.session Scavenging every 660000ms
[junit4] 2> 61219 INFO (jetty-launcher-99-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@7d45997b{/solr,null,AVAILABLE}
[junit4] 2> 61220 INFO (jetty-launcher-99-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@7a7cd67f{HTTP/1.1,[http/1.1]}{127.0.0.1:55609}
[junit4] 2> 61220 INFO (jetty-launcher-99-thread-1) [ ] o.e.j.s.Server Started @61256ms
[junit4] 2> 61220 INFO (jetty-launcher-99-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=55609}
[junit4] 2> 61220 ERROR (jetty-launcher-99-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 61220 INFO (jetty-launcher-99-thread-1) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 61220 INFO (jetty-launcher-99-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.0.0
[junit4] 2> 61220 INFO (jetty-launcher-99-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 61220 INFO (jetty-launcher-99-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 61220 INFO (jetty-launcher-99-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-03-30T20:23:41.981Z
[junit4] 2> 61221 INFO (jetty-launcher-99-thread-3) [ ] o.e.j.s.AbstractConnector Started ServerConnector@24d7a586{HTTP/1.1,[http/1.1]}{127.0.0.1:45957}
[junit4] 2> 61221 INFO (jetty-launcher-99-thread-3) [ ] o.e.j.s.Server Started @61257ms
[junit4] 2> 61221 INFO (jetty-launcher-99-thread-3) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=45957}
[junit4] 2> 61221 ERROR (jetty-launcher-99-thread-3) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 61221 INFO (jetty-launcher-99-thread-3) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 61221 INFO (jetty-launcher-99-thread-3) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.0.0
[junit4] 2> 61221 INFO (jetty-launcher-99-thread-3) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 61221 INFO (jetty-launcher-99-thread-3) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 61221 INFO (jetty-launcher-99-thread-3) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-03-30T20:23:41.982Z
[junit4] 2> 61233 INFO (jetty-launcher-99-thread-4) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 61233 INFO (jetty-launcher-99-thread-4) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 61233 INFO (jetty-launcher-99-thread-4) [ ] o.e.j.s.session Scavenging every 660000ms
[junit4] 2> 61234 INFO (jetty-launcher-99-thread-4) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@1d6f71ed{/solr,null,AVAILABLE}
[junit4] 2> 61237 INFO (jetty-launcher-99-thread-2) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 61237 INFO (jetty-launcher-99-thread-2) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 61237 INFO (jetty-launcher-99-thread-2) [ ] o.e.j.s.session Scavenging every 660000ms
[junit4] 2> 61237 INFO (jetty-launcher-99-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@32905030{/solr,null,AVAILABLE}
[junit4] 2> 61238 INFO (jetty-launcher-99-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@89f172b{HTTP/1.1,[http/1.1]}{127.0.0.1:50641}
[junit4] 2> 61238 INFO (jetty-launcher-99-thread-2) [ ] o.e.j.s.Server Started @61274ms
[junit4] 2> 61238 INFO (jetty-launcher-99-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=50641}
[junit4] 2> 61238 ERROR (jetty-launcher-99-thread-2) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 61238 INFO (jetty-launcher-99-thread-2) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 61238 INFO (jetty-launcher-99-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.0.0
[junit4] 2> 61238 INFO (jetty-launcher-99-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 61238 INFO (jetty-launcher-99-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 61238 INFO (jetty-launcher-99-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-03-30T20:23:41.999Z
[junit4] 2> 61242 INFO (jetty-launcher-99-thread-4) [ ] o.e.j.s.AbstractConnector Started ServerConnector@6f577151{HTTP/1.1,[http/1.1]}{127.0.0.1:33332}
[junit4] 2> 61242 INFO (jetty-launcher-99-thread-4) [ ] o.e.j.s.Server Started @61279ms
[junit4] 2> 61242 INFO (jetty-launcher-99-thread-4) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=33332}
[junit4] 2> 61243 ERROR (jetty-launcher-99-thread-4) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 61243 INFO (jetty-launcher-99-thread-4) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 61243 INFO (jetty-launcher-99-thread-4) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.0.0
[junit4] 2> 61243 INFO (jetty-launcher-99-thread-4) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 61243 INFO (jetty-launcher-99-thread-4) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 61243 INFO (jetty-launcher-99-thread-4) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-03-30T20:23:42.004Z
[junit4] 2> 61244 INFO (zkConnectionManagerCallback-106-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 61244 INFO (zkConnectionManagerCallback-104-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 61249 INFO (zkConnectionManagerCallback-108-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 61250 INFO (jetty-launcher-99-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 61250 INFO (jetty-launcher-99-thread-3) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 61250 INFO (jetty-launcher-99-thread-2) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 61269 INFO (zkConnectionManagerCallback-110-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 61274 INFO (jetty-launcher-99-thread-4) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 61274 INFO (jetty-launcher-99-thread-3) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:35243/solr
[junit4] 2> 61275 INFO (jetty-launcher-99-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:35243/solr
[junit4] 2> 61283 INFO (jetty-launcher-99-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:35243/solr
[junit4] 2> 61298 INFO (zkConnectionManagerCallback-122-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 61301 INFO (zkConnectionManagerCallback-119-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 61304 INFO (zkConnectionManagerCallback-116-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 61307 INFO (jetty-launcher-99-thread-4) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:35243/solr
[junit4] 2> 61308 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ] o.a.z.s.NIOServerCnxn Unable to read additional data from client sessionid 0x10548a0a2fc0004, likely client has closed socket
[junit4] 2> 61317 INFO (zkConnectionManagerCallback-128-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 61324 INFO (zkConnectionManagerCallback-130-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 61325 INFO (zkConnectionManagerCallback-126-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 61327 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ] o.a.z.s.NIOServerCnxn Unable to read additional data from client sessionid 0x10548a0a2fc000a, likely client has closed socket
[junit4] 2> 61337 INFO (zkConnectionManagerCallback-132-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 61351 INFO (zkConnectionManagerCallback-135-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 61366 INFO (jetty-launcher-99-thread-3) [n:127.0.0.1:45957_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 61366 INFO (jetty-launcher-99-thread-3) [n:127.0.0.1:45957_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:45957_solr
[junit4] 2> 61367 INFO (jetty-launcher-99-thread-3) [n:127.0.0.1:45957_solr ] o.a.s.c.Overseer Overseer (id=73544823687872523-127.0.0.1:45957_solr-n_0000000000) starting
[junit4] 2> 61367 INFO (jetty-launcher-99-thread-4) [n:127.0.0.1:33332_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 61368 INFO (jetty-launcher-99-thread-4) [n:127.0.0.1:33332_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:33332_solr
[junit4] 2> 61422 INFO (zkCallback-134-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 61422 INFO (zkCallback-131-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 61429 INFO (zkConnectionManagerCallback-142-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 61431 INFO (jetty-launcher-99-thread-3) [n:127.0.0.1:45957_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 61431 INFO (jetty-launcher-99-thread-2) [n:127.0.0.1:50641_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 61432 INFO (jetty-launcher-99-thread-3) [n:127.0.0.1:45957_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:35243/solr ready
[junit4] 2> 61433 INFO (jetty-launcher-99-thread-2) [n:127.0.0.1:50641_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 61435 INFO (jetty-launcher-99-thread-2) [n:127.0.0.1:50641_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 61435 INFO (jetty-launcher-99-thread-2) [n:127.0.0.1:50641_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:50641_solr
[junit4] 2> 61441 INFO (jetty-launcher-99-thread-1) [n:127.0.0.1:55609_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 61443 INFO (OverseerStateUpdate-73544823687872523-127.0.0.1:45957_solr-n_0000000000) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 61446 INFO (jetty-launcher-99-thread-3) [n:127.0.0.1:45957_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 61446 INFO (jetty-launcher-99-thread-3) [n:127.0.0.1:45957_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:45957_solr
[junit4] 2> 61466 INFO (zkCallback-131-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 61466 INFO (zkCallback-134-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (3)
[junit4] 2> 61467 INFO (jetty-launcher-99-thread-1) [n:127.0.0.1:55609_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 61469 INFO (jetty-launcher-99-thread-1) [n:127.0.0.1:55609_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 61469 INFO (jetty-launcher-99-thread-1) [n:127.0.0.1:55609_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:55609_solr
[junit4] 2> 61470 INFO (zkCallback-134-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 61477 INFO (zkCallback-131-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 61488 INFO (zkCallback-127-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (3)
[junit4] 2> 61513 INFO (zkCallback-127-thread-2) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 61515 INFO (zkCallback-129-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (4)
[junit4] 2> 61529 INFO (zkCallback-141-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (4)
[junit4] 2> 61669 INFO (jetty-launcher-99-thread-4) [n:127.0.0.1:33332_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_33332.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2c042cac
[junit4] 2> 61676 INFO (jetty-launcher-99-thread-4) [n:127.0.0.1:33332_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_33332.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2c042cac
[junit4] 2> 61676 INFO (jetty-launcher-99-thread-4) [n:127.0.0.1:33332_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_33332.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2c042cac
[junit4] 2> 61677 INFO (jetty-launcher-99-thread-4) [n:127.0.0.1:33332_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionPropsTest_1BCE6473A2A5E68A-001/tempDir-001/node4/.
[junit4] 2> 61709 INFO (zkConnectionManagerCallback-146-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 61742 INFO (jetty-launcher-99-thread-4) [n:127.0.0.1:33332_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 61742 INFO (jetty-launcher-99-thread-4) [n:127.0.0.1:33332_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:35243/solr ready
[junit4] 2> 61971 INFO (jetty-launcher-99-thread-1) [n:127.0.0.1:55609_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_55609.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2c042cac
[junit4] 2> 61984 INFO (jetty-launcher-99-thread-1) [n:127.0.0.1:55609_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_55609.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2c042cac
[junit4] 2> 61984 INFO (jetty-launcher-99-thread-1) [n:127.0.0.1:55609_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_55609.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2c042cac
[junit4] 2> 61985 INFO (jetty-launcher-99-thread-1) [n:127.0.0.1:55609_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionPropsTest_1BCE6473A2A5E68A-001/tempDir-001/node1/.
[junit4] 2> 62064 INFO (zkConnectionManagerCallback-150-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 62076 INFO (jetty-launcher-99-thread-1) [n:127.0.0.1:55609_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 62076 INFO (jetty-launcher-99-thread-1) [n:127.0.0.1:55609_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:35243/solr ready
[junit4] 2> 62742 INFO (jetty-launcher-99-thread-2) [n:127.0.0.1:50641_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_50641.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2c042cac
[junit4] 2> 62760 INFO (jetty-launcher-99-thread-2) [n:127.0.0.1:50641_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_50641.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2c042cac
[junit4] 2> 62760 INFO (jetty-launcher-99-thread-2) [n:127.0.0.1:50641_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_50641.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2c042cac
[junit4] 2> 62761 INFO (jetty-launcher-99-thread-2) [n:127.0.0.1:50641_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionPropsTest_1BCE6473A2A5E68A-001/tempDir-001/node3/.
[junit4] 2> 62834 INFO (zkConnectionManagerCallback-154-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 62844 INFO (jetty-launcher-99-thread-2) [n:127.0.0.1:50641_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 62844 INFO (jetty-launcher-99-thread-2) [n:127.0.0.1:50641_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:35243/solr ready
[junit4] 2> 63021 INFO (jetty-launcher-99-thread-3) [n:127.0.0.1:45957_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_45957.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2c042cac
[junit4] 2> 63044 INFO (jetty-launcher-99-thread-3) [n:127.0.0.1:45957_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_45957.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2c042cac
[junit4] 2> 63045 INFO (jetty-launcher-99-thread-3) [n:127.0.0.1:45957_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_45957.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2c042cac
[junit4] 2> 63046 INFO (jetty-launcher-99-thread-3) [n:127.0.0.1:45957_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionPropsTest_1BCE6473A2A5E68A-001/tempDir-001/node2/.
[junit4] 2> 63136 INFO (zkConnectionManagerCallback-156-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 63161 INFO (zkConnectionManagerCallback-160-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 63163 INFO (SUITE-CollectionPropsTest-seed#[1BCE6473A2A5E68A]-worker) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 63163 INFO (SUITE-CollectionPropsTest-seed#[1BCE6473A2A5E68A]-worker) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:35243/solr ready
[junit4] 2> 63286 INFO (TEST-CollectionPropsTest.testReadWriteCached-seed#[1BCE6473A2A5E68A]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testReadWriteCached
[junit4] 2> 63304 INFO (qtp853108670-436) [n:127.0.0.1:45957_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params replicationFactor=2&collection.configName=conf&name=CollectionPropsTest22693325713388335&nrtReplicas=2&action=CREATE&numShards=2&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 63312 INFO (OverseerThreadFactory-207-thread-1) [ ] o.a.s.c.a.c.CreateCollectionCmd Create collection CollectionPropsTest22693325713388335
[junit4] 2> 63443 INFO (OverseerStateUpdate-73544823687872523-127.0.0.1:45957_solr-n_0000000000) [ ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"CollectionPropsTest22693325713388335",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"CollectionPropsTest22693325713388335_shard1_replica_n1",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:33332/solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 63445 INFO (OverseerStateUpdate-73544823687872523-127.0.0.1:45957_solr-n_0000000000) [ ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"CollectionPropsTest22693325713388335",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"CollectionPropsTest22693325713388335_shard1_replica_n2",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:50641/solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 63460 INFO (OverseerStateUpdate-73544823687872523-127.0.0.1:45957_solr-n_0000000000) [ ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"CollectionPropsTest22693325713388335",
[junit4] 2> "shard":"shard2",
[junit4] 2> "core":"CollectionPropsTest22693325713388335_shard2_replica_n5",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:45957/solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 63467 INFO (OverseerStateUpdate-73544823687872523-127.0.0.1:45957_solr-n_0000000000) [ ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"CollectionPropsTest22693325713388335",
[junit4] 2> "shard":"shard2",
[junit4] 2> "core":"CollectionPropsTest22693325713388335_shard2_replica_n6",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:55609/solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 63717 INFO (qtp675863651-444) [n:127.0.0.1:50641_solr ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node4&collection.configName=conf&newCollection=true&name=CollectionPropsTest22693325713388335_shard1_replica_n2&action=CREATE&numShards=2&collection=CollectionPropsTest22693325713388335&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 63742 INFO (qtp184111179-442) [n:127.0.0.1:33332_solr ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node3&collection.configName=conf&newCollection=true&name=CollectionPropsTest22693325713388335_shard1_replica_n1&action=CREATE&numShards=2&collection=CollectionPropsTest22693325713388335&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 63742 INFO (qtp184111179-442) [n:127.0.0.1:33332_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 63744 INFO (qtp67052619-433) [n:127.0.0.1:55609_solr ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node8&collection.configName=conf&newCollection=true&name=CollectionPropsTest22693325713388335_shard2_replica_n6&action=CREATE&numShards=2&collection=CollectionPropsTest22693325713388335&shard=shard2&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 63745 INFO (qtp853108670-447) [n:127.0.0.1:45957_solr ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node7&collection.configName=conf&newCollection=true&name=CollectionPropsTest22693325713388335_shard2_replica_n5&action=CREATE&numShards=2&collection=CollectionPropsTest22693325713388335&shard=shard2&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 63901 INFO (zkCallback-131-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/CollectionPropsTest22693325713388335/state.json] for collection [CollectionPropsTest22693325713388335] has occurred - updating... (live nodes size: [4])
[junit4] 2> 63902 INFO (zkCallback-129-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/CollectionPropsTest22693325713388335/state.json] for collection [CollectionPropsTest22693325713388335] has occurred - updating... (live nodes size: [4])
[junit4] 2> 63903 INFO (zkCallback-131-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/CollectionPropsTest22693325713388335/state.json] for collection [CollectionPropsTest22693325713388335] has occurred - updating... (live nodes size: [4])
[junit4] 2> 63903 INFO (zkCallback-127-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/CollectionPropsTest22693325713388335/state.json] for collection [CollectionPropsTest22693325713388335] has occurred - updating... (live nodes size: [4])
[junit4] 2> 63903 INFO (zkCallback-134-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/CollectionPropsTest22693325713388335/state.json] for collection [CollectionPropsTest22693325713388335] has occurred - updating... (live nodes size: [4])
[junit4] 2> 63921 INFO (zkCallback-129-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/CollectionPropsTest22693325713388335/state.json] for collection [CollectionPropsTest22693325713388335] has occurred - updating... (live nodes size: [4])
[junit4] 2> 63933 INFO (zkCallback-127-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/CollectionPropsTest22693325713388335/state.json] for collection [CollectionPropsTest22693325713388335] has occurred - updating... (live nodes size: [4])
[junit4] 2> 63934 INFO (zkCallback-134-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/CollectionPropsTest22693325713388335/state.json] for collection [CollectionPropsTest22693325713388335] has occurred - updating... (live nodes size: [4])
[junit4] 2> 64810 INFO (qtp184111179-442) [n:127.0.0.1:33332_solr c:CollectionPropsTest22693325713388335 s:shard1 r:core_node3 x:CollectionPropsTest22693325713388335_shard1_replica_n1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
[junit4] 2> 64816 INFO (qtp675863651-444) [n:127.0.0.1:50641_solr c:CollectionPropsTest22693325713388335 s:shard1 r:core_node4 x:CollectionPropsTest22693325713388335_shard1_replica_n2] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
[junit4] 2> 64819 INFO (qtp853108670-447) [n:127.0.0.1:45957_solr c:CollectionPropsTest22693325713388335 s:shard2 r:core_node7 x:CollectionPropsTest22693325713388335_shard2_replica_n5] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
[junit4] 2> 65340 INFO (qtp675863651-444) [n:127.0.0.1:50641_solr c:CollectionPropsTest22693325713388335 s:shard1 r:core_node4 x:CollectionPropsTest22693325713388335_shard1_replica_n2] o.a.s.s.IndexSchema [CollectionPropsTest22693325713388335_shard1_replica_n2] Schema name=minimal
[junit4] 2> 65344 INFO (qtp675863651-444) [n:127.0.0.1:50641_solr c:CollectionPropsTest22693325713388335 s:shard1 r:core_node4 x:CollectionPropsTest22693325713388335_shard1_replica_n2] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 65344 INFO (qtp675863651-444) [n:127.0.0.1:50641_solr c:CollectionPropsTest22693325713388335 s:shard1 r:core_node4 x:CollectionPropsTest22693325713388335_shard1_replica_n2] o.a.s.c.CoreContainer Creating SolrCore 'CollectionPropsTest22693325713388335_shard1_replica_n2' using configuration from collection CollectionPropsTest22693325713388335, trusted=true
[junit4] 2> 65344 INFO (qtp675863651-444) [n:127.0.0.1:50641_solr c:CollectionPropsTest22693325713388335 s:shard1 r:core_node4 x:CollectionPropsTest22693325713388335_shard1_replica_n2] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_50641.solr.core.CollectionPropsTest22693325713388335.shard1.replica_n2' (registry 'solr.core.CollectionPropsTest22693325713388335.shard1.replica_n2') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2c042cac
[junit4] 2> 65345 INFO (qtp675863651-444) [n:127.0.0.1:50641_solr c:CollectionPropsTest22693325713388335 s:shard1 r:core_node4 x:CollectionPropsTest22693325713388335_shard1_replica_n2] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 65345 INFO (qtp675863651-444) [n:127.0.0.1:50641_solr c:CollectionPropsTest22693325713388335 s:shard1 r:core_node4 x:CollectionPropsTest22693325713388335_shard1_replica_n2] o.a.s.c.SolrCore [[CollectionPropsTest22693325713388335_shard1_replica_n2] ] Opening new SolrCore at [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionPropsTest_1BCE6473A2A5E68A-001/tempDir-001/node3/CollectionPropsTest22693325713388335_shard1_replica_n2], dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionPropsTest_1BCE6473A2A5E68A-001/tempDir-001/node3/./CollectionPropsTest22693325713388335_shard1_replica_n2/data/]
[junit4] 2> 65354 INFO (qtp67052619-433) [n:127.0.0.1:55609_solr c:CollectionPropsTest22693325713388335 s:shard2 r:core_node8 x:CollectionPropsTest22693325713388335_shard2_replica_n6] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
[junit4] 2> 65357 INFO (qtp184111179-442) [n:127.0.0.1:33332_solr c:CollectionPropsTest22693325713388335 s:shard1 r:core_node3 x:CollectionPropsTest22693325713388335_shard1_replica_n1] o.a.s.s.IndexSchema [CollectionPropsTest22693325713388335_shard1_replica_n1] Schema name=minimal
[junit4] 2> 65365 INFO (qtp853108670-447) [n:127.0.0.1:45957_solr c:CollectionPropsTest22693325713388335 s:shard2 r:core_node7 x:CollectionPropsTest22693325713388335_shard2_replica_n5] o.a.s.s.IndexSchema [CollectionPropsTest22693325713388335_shard2_replica_n5] Schema name=minimal
[junit4] 2> 65365 INFO (qtp67052619-433) [n:127.0.0.1:55609_solr c:CollectionPropsTest22693325713388335 s:shard2 r:core_node8 x:CollectionPropsTest22693325713388335_shard2_replica_n6] o.a.s.s.IndexSchema [CollectionPropsTest22693325713388335_shard2_replica_n6] Schema name=minimal
[junit4] 2> 65368 INFO (qtp67052619-433) [n:127.0.0.1:55609_solr c:CollectionPropsTest22693325713388335 s:shard2 r:core_node8 x:CollectionPropsTest22693325713388335_shard2_replica_n6] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 65368 INFO (qtp853108670-447) [n:127.0.0.1:45957_solr c:CollectionPropsTest22693325713388335 s:shard2 r:core_node7 x:CollectionPropsTest22693325713388335_shard2_replica_n5] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 65368 INFO (qtp67052619-433) [n:127.0.0.1:55609_solr c:CollectionPropsTest22693325713388335 s:shard2 r:core_node8 x:CollectionPropsTest22693325713388335_shard2_replica_n6] o.a.s.c.CoreContainer Creating SolrCore 'CollectionPropsTest22693325713388335_shard2_replica_n6' using configuration from collection CollectionPropsTest22693325713388335, trusted=true
[junit4] 2> 65368 INFO (qtp853108670-447) [n:127.0.0.1:45957_solr c:CollectionPropsTest22693325713388335 s:shard2 r:core_node7 x:CollectionPropsTest22693325713388335_shard2_replica_n5] o.a.s.c.CoreContainer Creating SolrCore 'CollectionPropsTest22693325713388335_shard2_replica_n5' using configuration from collection CollectionPropsTest22693325713388335, trusted=true
[junit4] 2> 65368 INFO (qtp67052619-433) [n:127.0.0.1:55609_solr c:CollectionPropsTest22693325713388335 s:shard2 r:core_node8 x:CollectionPropsTest22693325713388335_shard2_replica_n6] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_55609.solr.core.CollectionPropsTest22693325713388335.shard2.replica_n6' (registry 'solr.core.CollectionPropsTest22693325713388335.shard2.replica_n6') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2c042cac
[junit4] 2> 65368 INFO (qtp853108670-447) [n:127.0.0.1:45957_solr c:CollectionPropsTest22693325713388335 s:shard2 r:core_node7 x:CollectionPropsTest22693325713388335_shard2_replica_n5] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_45957.solr.core.CollectionPropsTest22693325713388335.shard2.replica_n5' (registry 'solr.core.CollectionPropsTest22693325713388335.shard2.replica_n5') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2c042cac
[junit4] 2> 65368 INFO (qtp67052619-433) [n:127.0.0.1:55609_solr c:CollectionPropsTest22693325713388335 s:shard2 r:core_node8 x:CollectionPropsTest22693325713388335_shard2_replica_n6] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 65368 INFO (qtp853108670-447) [n:127.0.0.1:45957_solr c:CollectionPropsTest22693325713388335 s:shard2 r:core_node7 x:CollectionPropsTest22693325713388335_shard2_replica_n5] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 65368 INFO (qtp67052619-433) [n:127.0.0.1:55609_solr c:CollectionPropsTest22693325713388335 s:shard2 r:core_node8 x:CollectionPropsTest22693325713388335_shard2_replica_n6] o.a.s.c.SolrCore [[CollectionPropsTest22693325713388335_shard2_replica_n6] ] Opening new SolrCore at [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionPropsTest_1BCE6473A2A5E68A-001/tempDir-001/node1/CollectionPropsTest22693325713388335_shard2_replica_n6], dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionPropsTest_1BCE6473A2A5E68A-001/tempDir-001/node1/./CollectionPropsTest22693325713388335_shard2_replica_n6/data/]
[junit4] 2> 65368 INFO (qtp853108670-447) [n:127.0.0.1:45957_solr c:CollectionPropsTest22693325713388335 s:shard2 r:core_node7 x:CollectionPropsTest22693325713388335_shard2_replica_n5] o.a.s.c.SolrCore [[CollectionPropsTest22693325713388335_shard2_replica_n5] ] Opening new SolrCore at [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionPropsTest_1BCE6473A2A5E68A-001/tempDir-001/node2/CollectionPropsTest22693325713388335_shard2_replica_n5], dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionPropsTest_1BCE6473A2A5E68A-001/tempDir-001/node2/./CollectionPropsTest22693325713388335_shard2_replica_n5/data/]
[junit4] 2> 65378 INFO (qtp184111179-442) [n:127.0.0.1:33332_solr c:CollectionPropsTest22693325713388335 s:shard1 r:core_node3 x:CollectionPropsTest22693325713388335_shard1_replica_n1] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 65378 INFO (qtp184111179-442) [n:127.0.0.1:33332_solr c:CollectionPropsTest22693325713388335 s:shard1 r:core_node3 x:CollectionPropsTest22693325713388335_shard1_replica_n1] o.a.s.c.CoreContainer Creating SolrCore 'CollectionPropsTest22693325713388335_shard1_replica_n1' using configuration from collection CollectionPropsTest22693325713388335, trusted=true
[junit4] 2> 65378 INFO (qtp184111179-442) [n:127.0.0.1:33332_solr c:CollectionPropsTest22693325713388335 s:shard1 r:core_node3 x:CollectionPropsTest22693325713388335_shard1_replica_n1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_33332.solr.core.CollectionPropsTest22693325713388335.shard1.replica_n1' (registry 'solr.core.CollectionPropsTest22693325713388335.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2c042cac
[junit4] 2> 65379 INFO (qtp184111179-442) [n:127.0.0.1:33332_solr c:CollectionPropsTest22693325713388335 s:shard1 r:core_node3 x:CollectionPropsTest22693325713388335_shard1_replica_n1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 65379 INFO (qtp184111179-442) [n:127.0.0.1:33332_solr c:CollectionPropsTest22693325713388335 s:shard1 r:core_node3 x:CollectionPropsTest22693325713388335_shard1_replica_n1] o.a.s.c.SolrCore [[CollectionPropsTest22693325713388335_shard1_replica_n1] ] Opening new SolrCore at [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionPropsTest_1BCE6473A2A5E68A-001/tempDir-001/node4/CollectionPropsTest22693325713388335_shard1_replica_n1], dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionPropsTest_1BCE6473A2A5E68A-001/tempDir-001/node4/./CollectionPropsTest22693325713388335_shard1_replica_n1/data/]
[junit4] 2> 65492 INFO (qtp184111179-442) [n:127.0.0.1:33332_solr c:CollectionPropsTest22693325713388335 s:shard1 r:core_node3 x:CollectionPropsTest22693325713388335_shard1_replica_n1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 65492 INFO (qtp184111179-442) [n:127.0.0.1:33332_solr c:CollectionPropsTest22693325713388335 s:shard1 r:core_node3 x:CollectionPropsTest22693325713388335_shard1_replica_n1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 65494 INFO (qtp184111179-442) [n:127.0.0.1:33332_solr c:CollectionPropsTest22693325713388335 s:shard1 r:core_node3 x:CollectionPropsTest22693325713388335_shard1_replica_n1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 65494 INFO (qtp184111179-442) [n:127.0.0.1:33332_solr c:CollectionPropsTest22693325713388335 s:shard1 r:core_node3 x:CollectionPropsTest22693325713388335_shard1_replica_n1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 65497 INFO (qtp184111179-442) [n:127.0.0.1:33332_solr c:CollectionPropsTest22693325713388335 s:shard1 r:core_node3 x:CollectionPropsTest22693325713388335_shard1_replica_n1] o.a.s.s.SolrIndexSearcher Opening [Searcher@406b0b60[CollectionPropsTest22693325713388335_shard1_replica_n1] main]
[junit4] 2> 65498 INFO (qtp184111179-442) [n:127.0.0.1:33332_solr c:CollectionPropsTest22693325713388335 s:shard1 r:core_node3 x:CollectionPropsTest22693325713388335_shard1_replica_n1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf
[junit4] 2> 65499 INFO (qtp184111179-442) [n:127.0.0.1:33332_solr c:CollectionPropsTest22693325713388335 s:shard1 r:core_node3 x:CollectionPropsTest22693325713388335_shard1_replica_n1] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf
[junit4] 2> 65500 INFO (qtp184111179-442) [n:127.0.0.1:33332_solr c:CollectionPropsTest22693325713388335 s:shard1 r:core_node3 x:CollectionPropsTest22693325713388335_shard1_replica_n1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 65500 INFO (qtp184111179-442) [n:127.0.0.1:33332_solr c:CollectionPropsTest22693325713388335 s:shard1 r:core_node3 x:CollectionPropsTest22693325713388335_shard1_replica_n1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1596395540983054336
[junit4] 2> 65507 INFO (qtp675863651-444) [n:127.0.0.1:50641_solr c:CollectionPropsTest22693325713388335 s:shard1 r:core_node4 x:CollectionPropsTest22693325713388335_shard1_replica_n2] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 65507 INFO (qtp675863651-444) [n:127.0.0.1:50641_solr c:CollectionPropsTest22693325713388335 s:shard1 r:core_node4 x:CollectionPropsTest22693325713388335_shard1_replica_n2] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 65508 INFO (qtp675863651-444) [n:127.0.0.1:50641_solr c:CollectionPropsTest22693325713388335 s:shard1 r:core_node4 x:CollectionPropsTest22693325713388335_shard1_replica_n2] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 65508 INFO (qtp675863651-444) [n:127.0.0.1:50641_solr c:CollectionPropsTest22693325713388335 s:shard1 r:core_node4 x:CollectionPropsTest22693325713388335_shard1_replica_n2] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 65508 INFO (searcherExecutor-219-thread-1-processing-n:127.0.0.1:33332_solr x:CollectionPropsTest22693325713388335_shard1_replica_n1 c:CollectionPropsTest22693325713388335 s:shard1 r:core_node3) [n:127.0.0.1:33332_solr c:CollectionPropsTest22693325713388335 s:shard1 r:core_node3 x:CollectionPropsTest22693325713388335_shard1_replica_n1] o.a.s.c.SolrCore [CollectionPropsTest22693325713388335_shard1_replica_n1] Registered new searcher Searcher@406b0b60[CollectionPropsTest22693325713388335_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 65514 INFO (qtp184111179-442) [n:127.0.0.1:33332_solr c:CollectionPropsTest22693325713388335 s:shard1 r:core_node3 x:CollectionPropsTest22693325713388335_shard1_replica_n1] o.a.s.c.ZkShardTerms Successful update of terms at /collections/CollectionPropsTest22693325713388335/terms/shard1 to Terms{values={core_node3=0}, version=0}
[junit4] 2> 65536 INFO (qtp675863651-444) [n:127.0.0.1:50641_solr c:CollectionPropsTest22693325713388335 s:shard1 r:core_node4 x:CollectionPropsTest22693325713388335_shard1_replica_n2] o.a.s.s.SolrIndexSearcher Opening [Searcher@558c22f4[CollectionPropsTest22693325713388335_shard1_replica_n2] main]
[junit4] 2> 65537 INFO (qtp675863651-444) [n:127.0.0.1:50641_solr c:CollectionPropsTest22693325713388335 s:shard1 r:core_node4 x:CollectionPropsTest22693325713388335_shard1_replica_n2] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf
[junit4] 2> 65538 INFO (qtp675863651-444) [n:127.0.0.1:50641_solr c:CollectionPropsTest22693325713388335 s:shard1 r:core_node4 x:CollectionPropsTest22693325713388335_shard1_replica_n2] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf
[junit4] 2> 65539 INFO (qtp184111179-442) [n:127.0.0.1:33332_solr c:CollectionPropsTest22693325713388335 s:shard1 r:core_node3 x:CollectionPropsTest22693325713388335_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext Waiting until we see more replicas up for shard shard1: total=2 found=1 timeoutin=9999ms
[junit4] 2> 65539 INFO (qtp675863651-444) [n:127.0.0.1:50641_solr c:CollectionPropsTest22693325713388335 s:shard1 r:core_node4 x:CollectionPropsTest22693325713388335_shard1_replica_n2] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 65540 INFO (qtp675863651-444) [n:127.0.0.1:50641_solr c:CollectionPropsTest22693325713388335 s:shard1 r:core_node4 x:CollectionPropsTest22693325713388335_shard1_replica_n2] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1596395541024997376
[junit4] 2> 65543 INFO (searcherExecutor-216-thread-1-processing-n:127.0.0.1:50641_solr x:CollectionPropsTest22693325713388335_shard1_replica_n2 c:CollectionPropsTest22693325713388335 s:shard1 r:core_node4) [n:127.0.0.1:50641_solr c:CollectionPropsTest22693325713388335 s:shard1 r:core_node4 x:CollectionPropsTest22693325713388335_shard1_replica_n2] o.a.s.c.SolrCore [CollectionPropsTest22693325713388335_shard1_replica_n2] Registered new searcher Searcher@558c22f4[CollectionPropsTest22693325713388335_shard1_replica_n2] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 65550 INFO (qtp675863651-444) [n:127.0.0.1:50641_solr c:CollectionPropsTest22693325713388335 s:shard1 r:core_node4 x:CollectionPropsTest22693325713388335_shard1_replica_n2] o.a.s.c.ZkShardTerms Successful update of terms at /collections/CollectionPropsTest22693325713388335/terms/shard1 to Terms{values={core_node3=0, core_node4=0}, version=1}
[junit4] 2> 65577 INFO (qtp853108670-447) [n:127.0.0.1:45957_solr c:CollectionPropsTest22693325713388335 s:shard2 r:core_node7 x:CollectionPropsTest22693325713388335_shard2_replica_n5] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 65577 INFO (qtp853108670-447) [n:127.0.0.1:45957_solr c:CollectionPropsTest22693325713388335 s:shard2 r:core_node7 x:CollectionPropsTest22693325713388335_shard2_replica_n5] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 65578 INFO (qtp853108670-447) [n:127.0.0.1:45957_solr c:CollectionPropsTest22693325713388335 s:shard2 r:core_node7 x:CollectionPropsTest22693325713388335_shard2_replica_n5] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 65578 INFO (qtp853108670-447) [n:127.0.0.1:45957_solr c:CollectionPropsTest22693325713388335 s:shard2 r:core_node7 x:CollectionPropsTest22693325713388335_shard2_replica_n5] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 65580 INFO (qtp853108670-447) [n:127.0.0.1:45957_solr c:CollectionPropsTest22693325713388335 s:shard2 r:core_node7 x:CollectionPropsTest22693325713388335_shard2_replica_n5] o.a.s.s.SolrIndexSearcher Opening [Searcher@2418204e[CollectionPropsTest22693325713388335_shard2_replica_n5] main]
[junit4] 2> 65581 INFO (qtp853108670-447) [n:127.0.0.1:45957_solr c:CollectionPropsTest22693325713388335 s:shard2 r:core_node7 x:CollectionPropsTest22693325713388335_shard2_replica_n5] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf
[junit4] 2> 65582 INFO (qtp853108670-447) [n:127.0.0.1:45957_solr c:CollectionPropsTest22693325713388335 s:shard2 r:core_node7 x:CollectionPropsTest22693325713388335_shard2_replica_n5] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf
[junit4] 2> 65582 INFO (qtp853108670-447) [n:127.0.0.1:45957_solr c:CollectionPropsTest22693325713388335 s:shard2 r:core_node7 x:CollectionPropsTest22693325713388335_shard2_replica_n5] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 65586 INFO (qtp67052619-433) [n:127.0.0.1:55609_solr c:CollectionPropsTest22693325713388335 s:shard2 r:core_node8 x:CollectionPropsTest22693325713388335_shard2_replica_n6] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 65586 INFO (qtp67052619-433) [n:127.0.0.1:55609_solr c:CollectionPropsTest22693325713388335 s:shard2 r:core_node8 x:CollectionPropsTest22693325713388335_shard2_replica_n6] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 65587 INFO (qtp67052619-433) [n:127.0.0.1:55609_solr c:CollectionPropsTest22693325713388335 s:shard2 r:core_node8 x:CollectionPropsTest22693325713388335_shard2_replica_n6] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 65587 INFO (qtp67052619-433) [n:127.0.0.1:55609_solr c:CollectionPropsTest22693325713388335 s:shard2 r:core_node8 x:CollectionPropsTest22693325713388335_shard2_replica_n6] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 65588 INFO (qtp853108670-447) [n:127.0.0.1:45957_solr c:CollectionPropsTest22693325713388335 s:shard2 r:core_node7 x:CollectionPropsTest22693325713388335_shard2_replica_n5] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1596395541075329024
[junit4] 2> 65589 INFO (searcherExecutor-218-thread-1-processing-n:127.0.0.1:45957_solr x:CollectionPropsTest22693325713388335_shard2_replica_n5 c:CollectionPropsTest22693325713388335 s:shard2 r:core_node7) [n:127.0.0.1:45957_solr c:CollectionPropsTest22693325713388335 s:shard2 r:core_node7 x:CollectionPropsTest22693325713388335_shard2_replica_n5] o.a.s.c.SolrCore [CollectionPropsTest22693325713388335_shard2_replica_n5] Registered new searcher Searcher@2418204e[CollectionPropsTest22693325713388335_shard2_replica_n5] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 65591 INFO (qtp853108670-447) [n:127.0.0.1:45957_solr c:CollectionPropsTest22693325713388335 s:shard2 r:core_node7 x:CollectionPropsTest22693325713388335_shard2_replica_n5] o.a.s.c.ZkShardTerms Successful update of terms at /collections/CollectionPropsTest22693325713388335/terms/shard2 to Terms{values={core_node7=0}, version=0}
[junit4] 2> 65592 INFO (qtp67052619-433) [n:127.0.0.1:55609_solr c:CollectionPropsTest22693325713388335 s:shard2 r:core_node8 x:CollectionPropsTest22693325713388335_shard2_replica_n6] o.a.s.s.SolrIndexSearcher Opening [Searcher@290b1333[CollectionPropsTest22693325713388335_shard2_replica_n6] main]
[junit4] 2> 65593 INFO (qtp67052619-433) [n:127.0.0.1:55609_solr c:CollectionPropsTest22693325713388335 s:shard2 r:core_node8 x:CollectionPropsTest22693325713388335_shard2_replica_n6] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf
[junit4] 2> 65593 INFO (qtp67052619-433) [n:127.0.0.1:55609_solr c:CollectionPropsTest22693325713388335 s:shard2 r:core_node8 x:CollectionPropsTest22693325713388335_shard2_replica_n6] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf
[junit4] 2> 65594 INFO (qtp67052619-433) [n:127.0.0.1:55609_solr c:CollectionPropsTest22693325713388335 s:shard2 r:core_node8 x:CollectionPropsTest22693325713388335_shard2_replica_n6] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 65594 INFO (qtp67052619-433) [n:127.0.0.1:55609_solr c:CollectionPropsTest22693325713388335 s:shard2 r:core_node8 x:CollectionPropsTest22693325713388335_shard2_replica_n6] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1596395541081620480
[junit4] 2> 65597 INFO (qtp853108670-447) [n:127.0.0.1:45957_solr c:CollectionPropsTest22693325713388335 s:shard2 r:core_node7 x:CollectionPropsTest22693325713388335_shard2_replica_n5] o.a.s.c.ShardLeaderElectionContext Waiting until we see more replicas up for shard shard2: total=2 found=1 timeoutin=9998ms
[junit4] 2> 65598 INFO (searcherExecutor-217-thread-1-processing-n:127.0.0.1:55609_solr x:CollectionPropsTest22693325713388335_shard2_replica_n6 c:CollectionPropsTest22693325713388335 s:shard2 r:core_node8) [n:127.0.0.1:55609_solr c:CollectionPropsTest22693325713388335 s:shard2 r:core_node8 x:CollectionPropsTest22693325713388335_shard2_replica_n6] o.a.s.c.SolrCore [CollectionPropsTest22693325713388335_shard2_replica_n6] Registered new searcher Searcher@290b1333[CollectionPropsTest22693325713388335_shard2_replica_n6] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 65600 INFO (qtp67052619-433) [n:127.0.0.1:55609_solr c:CollectionPropsTest22693325713388335 s:shard2 r:core_node8 x:CollectionPropsTest22693325713388335_shard2_replica_n6] o.a.s.c.ZkShardTerms Successful update of terms at /collections/CollectionPropsTest22693325713388335/terms/shard2 to Terms{values={core_node7=0, core_node8=0}, version=1}
[junit4] 2> 65700 INFO (zkCallback-134-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/CollectionPropsTest22693325713388335/state.json] for collection [CollectionPropsTest22693325713388335] has occurred - updating... (live nodes size: [4])
[junit4] 2> 65700 INFO (zkCallback-129-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/CollectionPropsTest22693325713388335/state.json] for collection [CollectionPropsTest22693325713388335] has occurred - updating... (live nodes size: [4])
[junit4] 2> 65700 INFO (zkCallback-134-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/CollectionPropsTest22693325713388335/state.json] for collection [CollectionPropsTest22693325713388335] has occurred - updating... (live nodes size: [4])
[junit4] 2> 65700 INFO (zkCallback-131-thread-3) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/CollectionPropsTest22693325713388335/state.json] for collection [CollectionPropsTest22693325713388335] has occurred - updating... (live nodes size: [4])
[junit4] 2> 65700 INFO (zkCallback-129-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/CollectionPropsTest22693325713388335/state.json] for collection [CollectionPropsTest22693325713388335] has occurred - updating... (live nodes size: [4])
[junit4] 2> 65700 INFO (zkCallback-131-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/CollectionPropsTest22693325713388335/state.json] for collection [CollectionPropsTest22693325713388335] has occurred - updating... (live nodes size: [4])
[junit4] 2> 65701 INFO (zkCallback-127-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/CollectionPropsTest22693325713388335/state.json] for collection [CollectionPropsTest22693325713388335] has occurred - updating... (live nodes size: [4])
[junit4] 2> 65701 INFO (zkCallback-127-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/CollectionPropsTest22693325713388335/state.json] for collection [CollectionPropsTest22693325713388335] has occurred - updating... (live nodes size: [4])
[junit4] 2> 66039 INFO (qtp184111179-442) [n:127.0.0.1:33332_solr c:CollectionPropsTest22693325713388335 s:shard1 r:core_node3 x:CollectionPropsTest22693325713388335_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 66039 INFO (qtp184111179-442) [n:127.0.0.1:33332_solr c:CollectionPropsTest22693325713388335 s:shard1 r:core_node3 x:CollectionPropsTest22693325713388335_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 66039 INFO (qtp184111179-442) [n:127.0.0.1:33332_solr c:CollectionPropsTest22693325713388335 s:shard1 r:core_node3 x:CollectionPropsTest22693325713388335_shard1_replica_n1] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:33332/solr/CollectionPropsTest22693325713388335_shard1_replica_n1/
[junit4] 2> 66040 INFO (qtp184111179-442) [n:127.0.0.1:33332_solr c:CollectionPropsTest22693325713388335 s:shard1 r:core_node3 x:CollectionPropsTest22693325713388335_shard1_replica_n1] o.a.s.u.PeerSync PeerSync: core=CollectionPropsTest22693325713388335_shard1_replica_n1 url=http://127.0.0.1:33332/solr START replicas=[http://127.0.0.1:50641/solr/CollectionPropsTest22693325713388335_shard1_replica_n2/] nUpdates=100
[junit4] 2> 66049 INFO (qtp675863651-468) [n:127.0.0.1:50641_solr c:CollectionPropsTest22693325713388335 s:shard1 r:core_node4 x:CollectionPropsTest22693325713388335_shard1_replica_n2] o.a.s.c.S.Request [CollectionPropsTest22693325713388335_shard1_replica_n2] webapp=/solr path=/get params={distrib=false&qt=/get&fingerprint=false&getVersions=100&wt=javabin&version=2} status=0 QTime=1
[junit4] 2> 66098 INFO (qtp853108670-447) [n:127.0.0.1:45957_solr c:CollectionPropsTest22693325713388335 s:shard2 r:core_node7 x:CollectionPropsTest22693325713388335_shard2_replica_n5] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 66098 INFO (qtp853108670-447) [n:127.0.0.1:45957_solr c:CollectionPropsTest22693325713388335 s:shard2 r:core_node7 x:CollectionPropsTest22693325713388335_shard2_replica_n5] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 66098 INFO (qtp853108670-447) [n:127.0.0.1:45957_solr c:CollectionPropsTest22693325713388335 s:shard2 r:core_node7 x:CollectionPropsTest22693325713388335_shard2_replica_n5] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:45957/solr/CollectionPropsTest22693325713388335_shard2_replica_n5/
[junit4] 2> 66098 INFO (qtp853108670-447) [n:127.0.0.1:45957_solr c:CollectionPropsTest22693325713388335 s:shard2 r:core_node7 x:CollectionPropsTest22693325713388335_shard2_replica_n5] o.a.s.u.PeerSync PeerSync: core=CollectionPropsTest22693325713388335_shard2_replica_n5 url=http://127.0.0.1:45957/solr START replicas=[http://127.0.0.1:55609/solr/CollectionPropsTest22693325713388335_shard2_replica_n6/] nUpdates=100
[junit4] 2> 66122 INFO (qtp67052619-439) [n:127.0.0.1:55609_solr c:CollectionPropsTest22693325713388335 s:shard2 r:core_node8 x:CollectionPropsTest22693325713388335_shard2_replica_n6] o.a.s.c.S.Request [CollectionPropsTest22693325713388335_shard2_replica_n6] webapp=/solr path=/get params={distrib=false&qt=/get&fingerprint=false&getVersions=100&wt=javabin&version=2} status=0 QTime=6
[junit4] 2> 66342 INFO (qtp184111179-442) [n:127.0.0.1:33332_solr c:CollectionPropsTest22693325713388335 s:shard1 r:core_node3 x:CollectionPropsTest22693325713388335_shard1_replica_n1] o.a.s.u.PeerSync PeerSync: core=CollectionPropsTest22693325713388335_shard1_replica_n1 url=http://127.0.0.1:33332/solr DONE. We have no versions. sync failed.
[junit4] 2> 66342 INFO (qtp184111179-442) [n:127.0.0.1:33332_solr c:CollectionPropsTest22693325713388335 s:shard1 r:core_node3 x:CollectionPropsTest22693325713388335_shard1_replica_n1] o.a.s.c.SyncStrategy Leader's attempt to sync with shard failed, moving to the next candidate
[junit4] 2> 66342 INFO (qtp184111179-442) [n:127.0.0.1:33332_solr c:CollectionPropsTest22693325713388335 s:shard1 r:core_node3 x:CollectionPropsTest22693325713388335_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext We failed sync, but we have no versions - we can't sync in that case - we were active before, so become leader anyway
[junit4] 2> 66342 INFO (qtp184111179-442) [n:127.0.0.1:33332_solr c:CollectionPropsTest22693325713388335 s:shard1 r:core_node3 x:CollectionPropsTest22693325713388335_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext Found all replicas participating in election, clear LIR
[junit4] 2> 66348 INFO (qtp184111179-442) [n:127.0.0.1:33332_solr c:CollectionPropsTest22693325713388335 s:shard1 r:core_node3 x:CollectionPropsTest22693325713388335_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:33332/solr/CollectionPropsTest22693325713388335_shard1_replica_n1/ shard1
[junit4] 2> 66403 INFO (qtp853108670-447) [n:127.0.0.1:45957_solr c:CollectionPropsTest22693325713388335 s:shard2 r:core_node7 x:CollectionPropsTest22693325713388335_shard2_replica_n5] o.a.s.u.PeerSync PeerSync: core=CollectionPropsTest22693325713388335_shard2_replica_n5 url=http://127.0.0.1:45957/solr DONE. We have no versions. sync failed.
[junit4] 2> 66403 INFO (qtp853108670-447) [n:127.0.0.1:45957_solr c:CollectionPropsTest22693325713388335 s:shard2 r:core_node7 x:CollectionPropsTest22693325713388335_shard2_replica_n5] o.a.s.c.SyncStrategy Leader's attempt to sync with shard failed, moving to the next candidate
[junit4] 2> 66403 INFO (qtp853108670-447) [n:127.0.0.1:45957_solr c:CollectionPropsTest22693325713388335 s:shard2 r:core_node7 x:CollectionPropsTest22693325713388335_shard2_replica_n5] o.a.s.c.ShardLeaderElectionContext We failed sync, but we have no versions - we can't sync in that case - we were active before, so become leader anyway
[junit4] 2> 66403 INFO (qtp853108670-447) [n:127.0.0.1:45957_solr c:CollectionPropsTest22693325713388335 s:shard2 r:core_node7 x:CollectionPropsTest22693325713388335_shard2_replica_n5] o.a.s.c.ShardLeaderElectionContext Found all replicas participating in election, clear LIR
[junit4] 2> 66406 INFO (qtp853108670-447) [n:127.0.0.1:45957_solr c:CollectionPropsTest22693325713388335 s:shard2 r:core_node7 x:CollectionPropsTest22693325713388335_shard2_replica_n5] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:45957/solr/CollectionPropsTest22693325713388335_shard2_replica_n5/ shard2
[junit4] 2> 66508 INFO (zkCallback-127-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/CollectionPropsTest22693325713388335/state.json] for collection [CollectionPropsTest22693325713388335] has occurred - updating... (live nodes size: [4])
[junit4] 2> 66508 INFO (zkCallback-127-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/CollectionPropsTest22693325713388335/state.json] for collection [CollectionPropsTest22693325713388335] has occurred - updating... (live nodes size: [4])
[junit4] 2> 66508 INFO (zkCallback-134-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/CollectionPropsTest22693325713388335/state.json] for collection [CollectionPropsTest22693325713388335] has occurred - updating... (live nodes size: [4])
[junit4] 2> 66508 INFO (zkCallback-129-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/CollectionPropsTest22693325713388335/state.json] for collection [CollectionPropsTest22693325713388335] has occurred - updating... (live nodes size: [4])
[junit4] 2> 66508 INFO (zkCallback-134-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/CollectionPropsTest22693325713388335/state.json] for collection [CollectionPropsTest22693325713388335] has occurred - updating... (live nodes size: [4])
[junit4] 2> 66508 INFO (zkCallback-131-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/CollectionPropsTest22693325713388335/state.json] for collection [CollectionPropsTest22693325713388335] has occurred - updating... (live nodes size: [4])
[junit4] 2> 66508 INFO (zkCallback-129-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/CollectionPropsTest22693325713388335/state.json] for collection [CollectionPropsTest22693325713388335] has occurred - updating... (live nodes size: [4])
[junit4] 2> 66508 INFO (zkCallback-131-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/CollectionPropsTest22693325713388335/state.json] for collection [CollectionPropsTest22693325713388335] has occurred - updating... (live nodes size: [4])
[junit4] 2> 66549 INFO (qtp184111179-442) [n:127.0.0.1:33332_solr c:CollectionPropsTest22693325713388335 s:shard1 r:core_node3 x:CollectionPropsTest22693325713388335_shard1_replica_n1] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 66552 INFO (qtp184111179-442) [n:127.0.0.1:33332_solr c:CollectionPropsTest22693325713388335 s:shard1 r:core_node3 x:CollectionPropsTest22693325713388335_shard1_replica_n1] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node3&collection.configName=conf&newCollection=true&name=CollectionPropsTest22693325713388335_shard1_replica_n1&action=CREATE&numShards=2&collection=CollectionPropsTest22693325713388335&shard=shard1&wt=javabin&version=2&replicaType=NRT} status=0 QTime=2810
[junit4] 2> 66556 INFO (qtp675863651-444) [n:127.0.0.1:50641_solr c:CollectionPropsTest22693325713388335 s:shard1 r:core_node4 x:CollectionPropsTest22693325713388335_shard1_replica_n2] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node4&collection.configName=conf&newCollection=true&name=CollectionPropsTest22693325713388335_shard1_replica_n2&action=CREATE&numShards=2&collection=CollectionPropsTest22693325713388335&shard=shard1&wt=javabin&version=2&replicaType=NRT} status=0 QTime=2838
[junit4] 2> 66557 INFO (qtp853108670-447) [n:127.0.0.1:45957_solr c:CollectionPropsTest22693325713388335 s:shard2 r:core_node7 x:CollectionPropsTest22693325713388335_shard2_replica_n5] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 66560 INFO (qtp853108670-447) [n:127.0.0.1:45957_solr c:CollectionPropsTest22693325713388335 s:shard2 r:core_node7 x:CollectionPropsTest22693325713388335_shard2_replica_n5] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node7&collection.configName=conf&newCollection=true&name=CollectionPropsTest22693325713388335_shard2_replica_n5&action=CREATE&numShards=2&collection=CollectionPropsTest22693325713388335&shard=shard2&wt=javabin&version=2&replicaType=NRT} status=0 QTime=2814
[junit4] 2> 66604 INFO (qtp67052619-433) [n:127.0.0.1:55609_solr c:CollectionPropsTest22693325713388335 s:shard2 r:core_node8 x:CollectionPropsTest22693325713388335_shard2_replica_n6] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node8&collection.configName=conf&newCollection=true&name=CollectionPropsTest22693325713388335_shard2_replica_n6&action=CREATE&numShards=2&collection=CollectionPropsTest22693325713388335&shard=shard2&wt=javabin&version=2&replicaType=NRT} status=0 QTime=2861
[junit4] 2> 66609 INFO (qtp853108670-436) [n:127.0.0.1:45957_solr ] o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most 30 seconds. Check all shard replicas
[junit4] 2> 66706 INFO (zkCallback-134-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/CollectionPropsTest22693325713388335/state.json] for collection [CollectionPropsTest22693325713388335] has occurred - updating... (live nodes size: [4])
[junit4] 2> 66706 INFO (zkCallback-129-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/CollectionPropsTest22693325713388335/state.json] for collection [CollectionPropsTest22693325713388335] has occurred - updating... (live nodes size: [4])
[junit4] 2> 66706 INFO (zkCallback-134-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/CollectionPropsTest22693325713388335/state.json] for collection [CollectionPropsTest22693325713388335] has occurred - updating... (live nodes size: [4])
[junit4] 2> 66706 INFO (zkCallback-129-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/CollectionPropsTest22693325713388335/state.json] for collection [CollectionPropsTest22693325713388335] has occurred - updating... (live nodes size: [4])
[junit4] 2> 66707 INFO (zkCallback-131-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/CollectionPropsTest22693325713388335/state.json] for collection [CollectionPropsTest22693325713388335] has occurred - updating... (live nodes size: [4])
[junit4] 2> 66707 INFO (zkCallback-131-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/CollectionPropsTest22693325713388335/state.json] for collection [CollectionPropsTest22693325713388335] has occurred - updating... (live nodes size: [4])
[junit4] 2> 66707 INFO (zkCallback-127-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/CollectionPropsTest22693325713388335/state.json] for collection [CollectionPropsTest22693325713388335] has occurred - updating... (live nodes size: [4])
[junit4] 2> 66707 INFO (zkCallback-127-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/CollectionPropsTest22693325713388335/state.json] for collection [CollectionPropsTest22693325713388335] has occurred - updating... (live nodes size: [4])
[junit4] 2> 67347 INFO (OverseerCollectionConfigSetProcessor-73544823687872523-127.0.0.1:45957_solr-n_0000000000) [ ] o.a.s.c.OverseerTaskQueue Response ZK path: /overseer/collection-queue-work/qnr-0000000000 doesn't exist. Requestor may have disconnected from ZooKeeper
[junit4] 2> 67609 INFO (qtp853108670-436) [n:127.0.0.1:45957_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={replicationFactor=2&collection.configName=conf&name=CollectionPropsTest22693325713388335&nrtReplicas=2&action=CREATE&numShards=2&wt=javabin&version=2} status=0 QTime=4305
[junit4] 2> 67614 INFO (zkCallback-159-thread-1) [ ] o.a.s.c.c.ZkStateReader A collection property change: [WatchedEvent state:SyncConnected type:NodeCreated path:/collections/CollectionPropsTest22693325713388335/collectionprops.json] for collection [CollectionPropsTest22693325713388335] has occurred - updating...
[junit4] 2> 67633 INFO (zkCallback-159-thread-2) [ ] o.a.s.c.c.ZkStateReader A collection property change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/CollectionPropsTest22693325713388335/collectionprops.json] for collection [CollectionPropsTest22693325713388335] has occurred - updating...
[junit4] 2> 67676 INFO (zkCallback-159-thread-2) [ ] o.a.s.c.c.ZkStateReader A collection property change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/CollectionPropsTest22693325713388335/collectionprops.json] for collection [CollectionPropsTest22693325713388335] has occurred - updating...
[junit4] 2> 67677 INFO (zkCallback-159-thread-1) [ ] o.a.s.c.c.ZkStateReader A collection property change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/CollectionPropsTest22693325713388335/collectionprops.json] for collection [CollectionPropsTest22693325713388335] has occurred - updating...
[junit4] 2> 73621 INFO (TEST-CollectionPropsTest.testReadWriteCached-seed#[1BCE6473A2A5E68A]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testReadWriteCached
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=CollectionPropsTest -Dtests.method=testReadWriteCached -Dtests.seed=1BCE6473A2A5E68A -Dtests.multiplier=2 -Dtests.slow=true -Dtests.badapples=true -Dtests.locale=de -Dtests.timezone=MIT -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[junit4] FAILURE 10.5s J2 | CollectionPropsTest.testRe
[...truncated too long message...]
olr c:delDeadColl s:shard1 r:core_node5 x:delDeadColl_shard1_replica_n2] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.delDeadColl.shard1.leader, tag=266399bc
[junit4] 2> 1807585 INFO (jetty-closer-4443-thread-3) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@2f5fc813{/solr,null,UNAVAILABLE}
[junit4] 2> 1807585 INFO (jetty-closer-4443-thread-3) [ ] o.e.j.s.session Stopped scavenging
[junit4] 2> 1807587 INFO (coreCloseExecutor-7451-thread-1) [n:127.0.0.1:53049_solr c:delDeadColl s:shard2 r:core_node7 x:delDeadColl_shard2_replica_n4] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.delDeadColl.shard2.leader, tag=2f492681
[junit4] 2> 1807588 INFO (coreCloseExecutor-7454-thread-1) [n:127.0.0.1:52476_solr c:delDeadColl s:shard1 r:core_node3 x:delDeadColl_shard1_replica_n1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.delDeadColl.shard1.leader, tag=2582575c
[junit4] 2> 1807589 INFO (jetty-closer-4443-thread-4) [ ] o.a.s.c.Overseer Overseer (id=73544937112666120-127.0.0.1:52476_solr-n_0000000000) closing
[junit4] 2> 1807589 INFO (OverseerStateUpdate-73544937112666120-127.0.0.1:52476_solr-n_0000000000) [ ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:52476_solr
[junit4] 2> 1807589 WARN (OverseerAutoScalingTriggerThread-73544937112666120-127.0.0.1:52476_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we are closed, exiting.
[junit4] 2> 1807591 INFO (jetty-closer-4443-thread-2) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@1d09aab4{/solr,null,UNAVAILABLE}
[junit4] 2> 1807591 INFO (jetty-closer-4443-thread-2) [ ] o.e.j.s.session Stopped scavenging
[junit4] 2> 1807592 INFO (jetty-closer-4443-thread-4) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@335a083{/solr,null,UNAVAILABLE}
[junit4] 2> 1807592 INFO (jetty-closer-4443-thread-4) [ ] o.e.j.s.session Stopped scavenging
[junit4] 2> 1807593 ERROR (SUITE-DeleteInactiveReplicaTest-seed#[1BCE6473A2A5E68A]-worker) [ ] o.a.z.s.ZooKeeperServer ZKShutdownHandler is not registered, so ZooKeeper server won't take any action on ERROR or SHUTDOWN server state changes
[junit4] 2> 1807593 INFO (SUITE-DeleteInactiveReplicaTest-seed#[1BCE6473A2A5E68A]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:49148 49148
[junit4] 2> 1807676 INFO (Thread-4923) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:49148 49148
[junit4] 2> 1807677 WARN (Thread-4923) [ ] o.a.s.c.ZkTestServer Watch limit violations:
[junit4] 2> Maximum concurrent create/delete watches above limit:
[junit4] 2>
[junit4] 2> 11 /solr/aliases.json
[junit4] 2> 9 /solr/clusterprops.json
[junit4] 2> 5 /solr/collections/delDeadColl/terms/shard2
[junit4] 2> 5 /solr/security.json
[junit4] 2> 5 /solr/collections/delDeadColl/terms/shard1
[junit4] 2> 4 /solr/configs/conf
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 36 /solr/collections/delDeadColl/state.json
[junit4] 2> 11 /solr/clusterstate.json
[junit4] 2> 11 /solr/clusterprops.json
[junit4] 2> 2 /solr/overseer_elect/election/73544937112666120-127.0.0.1:52476_solr-n_0000000000
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 11 /solr/live_nodes
[junit4] 2> 11 /solr/collections
[junit4] 2>
[junit4] 2> NOTE: leaving temporary files on disk at: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/solr/build/solr-core/test/J0/temp/solr.cloud.DeleteInactiveReplicaTest_1BCE6473A2A5E68A-001
[junit4] 2> Mar 30, 2018 8:52:48 PM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
[junit4] 2> WARNING: Will linger awaiting termination of 6 leaked thread(s).
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene70): {}, docValues:{}, maxPointsInLeafNode=1499, maxMBSortInHeap=6.15012976099786, sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@5b79e766), locale=uk, timezone=America/North_Dakota/New_Salem
[junit4] 2> NOTE: Linux 3.13.0-88-generic amd64/Oracle Corporation 1.8.0_144 (64-bit)/cpus=4,threads=1,free=217532496,total=524812288
[junit4] 2> NOTE: All tests run in this JVM: [TestGroupingSearch, TestChildDocTransformer, StatsReloadRaceTest, CreateRoutedAliasTest, TestTolerantUpdateProcessorCloud, TestHdfsBackupRestoreCore, HighlighterMaxOffsetTest, TestRawTransformer, EnumFieldTest, TolerantUpdateProcessorTest, BasicAuthStandaloneTest, TestMergePolicyConfig, HdfsRestartWhileUpdatingTest, TestExactStatsCache, TestDeleteCollectionOnDownNodes, BufferStoreTest, TestIBSimilarityFactory, TestRestManager, DebugComponentTest, XmlUpdateRequestHandlerTest, TestOmitPositions, TestComponentsName, FullSolrCloudDistribCmdsTest, TestDistributedSearch, TestShardHandlerFactory, AddSchemaFieldsUpdateProcessorFactoryTest, TestDocTermOrds, ChaosMonkeyNothingIsSafeWithPullReplicasTest, RulesTest, ReplaceNodeTest, CurrencyFieldTypeTest, DirectUpdateHandlerTest, CleanupOldIndexTest, RequestHandlersTest, CollectionsAPIDistributedZkTest, TestMiniSolrCloudClusterSSL, TestCorePropertiesReload, BadCopyFieldTest, TestPayloadScoreQParserPlugin, SpellingQueryConverterTest, DistributedFacetPivotSmallTest, TestSmileRequest, TestRandomRequestDistribution, CollectionStateFormat2Test, TestSchemaManager, TestManagedStopFilterFactory, QueryResultKeyTest, TestSubQueryTransformerDistrib, TemplateUpdateProcessorTest, TestCloudPivotFacet, NodeLostTriggerTest, ZkCLITest, DeleteStatusTest, TestIndexSearcher, FullHLLTest, BinaryUpdateRequestHandlerTest, TestExceedMaxTermLength, ScheduledMaintenanceTriggerTest, CdcrVersionReplicationTest, TestLargeCluster, TestScoreJoinQPScore, SolrPluginUtilsTest, TestReloadDeadlock, AnalysisErrorHandlingTest, TestExtendedDismaxParser, TestRemoteStreaming, TestHighlightDedupGrouping, DistributedQueryComponentOptimizationTest, ZkSolrClientTest, HdfsTlogReplayBufferedWhileIndexingTest, TestCloudDeleteByQuery, TestLMDirichletSimilarityFactory, BigEndianAscendingWordSerializerTest, ZkNodePropsTest, StatsComponentTest, TestSolrXml, SolrJmxReporterTest, TestExportWriter, TestFastOutputStream, DocValuesNotIndexedTest, TestMaxScoreQueryParser, TestPolicyCloud, DocumentAnalysisRequestHandlerTest, PeerSyncWithIndexFingerprintCachingTest, TestCollationField, SpellCheckCollatorTest, DistributedDebugComponentTest, LeaderFailureAfterFreshStartTest, TestSolrFieldCacheBean, SchemaApiFailureTest, TestBinaryField, TestWordDelimiterFilterFactory, LIRRollingUpdatesTest, MBeansHandlerTest, TestCSVResponseWriter, TestPKIAuthenticationPlugin, RequestLoggingTest, UUIDFieldTest, SuggesterWFSTTest, TlogReplayBufferedWhileIndexingTest, CachingDirectoryFactoryTest, PropertiesRequestHandlerTest, HttpTriggerListenerTest, TestCloudSearcherWarming, HdfsBasicDistributedZkTest, MoveReplicaHDFSTest, TestConfigsApi, CollectionReloadTest, TestInPlaceUpdatesStandalone, TestSolrDeletionPolicy1, TestCollectionAPI, TermVectorComponentDistributedTest, OpenExchangeRatesOrgProviderTest, SolrMetricManagerTest, FieldAnalysisRequestHandlerTest, TestFieldResource, TriggerSetPropertiesIntegrationTest, EchoParamsTest, TestPolicyCloud, TestBlobHandler, TestNumericRangeQuery64, HttpPartitionOnCommitTest, TestDynamicFieldCollectionResource, TestLuceneMatchVersion, AutoAddReplicasIntegrationTest, TestStressLucene, UninvertDocValuesMergePolicyTest, OpenCloseCoreStressTest, ExternalFileFieldSortTest, TestRandomFaceting, SimpleFacetsTest, OverseerTest, TestZkChroot, ShardRoutingCustomTest, TestFaceting, TestRecovery, TestHashPartitioner, TestRangeQuery, DistributedQueryElevationComponentTest, SolrIndexSplitterTest, SimplePostToolTest, TestCoreDiscovery, AnalysisAfterCoreReloadTest, SignatureUpdateProcessorFactoryTest, SuggesterFSTTest, CoreAdminHandlerTest, SolrRequestParserTest, DocValuesTest, SuggesterTest, SpatialFilterTest, TestCSVLoader, WordBreakSolrSpellCheckerTest, SchemaVersionSpecificBehaviorTest, SolrCoreCheckLockOnStartupTest, TestPseudoReturnFields, FieldMutatingUpdateProcessorTest, DirectUpdateHandlerOptimizeTest, DefaultValueUpdateProcessorTest, SortByFunctionTest, DistanceFunctionTest, CacheHeaderTest, IndexBasedSpellCheckerTest, TestSurroundQueryParser, DisMaxRequestHandlerTest, TestQueryUtils, TestWriterPerf, DirectSolrSpellCheckerTest, TestQueryTypes, PrimitiveFieldTypeTest, TestValueSourceCache, PathHierarchyTokenizerFactoryTest, TestIndexingPerformance, RequiredFieldsTest, TestSolrQueryParser, FastVectorHighlighterTest, LoggingHandlerTest, RegexBoostProcessorTest, TestJmxIntegration, ReturnFieldsTest, UpdateRequestProcessorFactoryTest, TestAnalyzedSuggestions, JsonLoaderTest, TestPHPSerializedResponseWriter, CSVRequestHandlerTest, HighlighterConfigTest, SOLR749Test, TestQuerySenderListener, UpdateParamsTest, TestSolrIndexConfig, TestQuerySenderNoQuery, SolrIndexConfigTest, BadComponentTest, TestStressRecovery, MultiTermTest, TestDocSet, TestSearchPerf, TestElisionMultitermQuery, TestConfig, OutputWriterTest, TestSolrCoreProperties, TestPostingsSolrHighlighter, TestPhraseSuggestions, SpellPossibilityIteratorTest, TestCharFilters, SynonymTokenizerTest, TestXIncludeConfig, TestDFRSimilarityFactory, TestBM25SimilarityFactory, TestFastWriter, ResourceLoaderTest, URLClassifyProcessorTest, PluginInfoTest, DateMathParserTest, ChaosMonkeyNothingIsSafeTest, TestTolerantSearch, TokenizerChainTest, TestLuceneIndexBackCompat, ConnectionReuseTest, AliasIntegrationTest, ConcurrentCreateRoutedAliasTest, ConfigSetsAPITest, ConnectionManagerTest, CreateCollectionCleanupTest, DeleteInactiveReplicaTest]
[junit4] Completed [537/795 (3!)] on J0 in 16.10s, 1 test, 1 failure <<< FAILURES!
[...truncated 50356 lines...]