You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@lucene.apache.org by Policeman Jenkins Server <je...@thetaphi.de> on 2016/08/29 16:12:48 UTC
[JENKINS] Lucene-Solr-6.x-Linux (64bit/jdk1.8.0_102) - Build # 1618
- Unstable!
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-6.x-Linux/1618/
Java: 64bit/jdk1.8.0_102 -XX:-UseCompressedOops -XX:+UseConcMarkSweepGC
1 tests failed.
FAILED: junit.framework.TestSuite.org.apache.solr.util.TestSolrCLIRunExample
Error Message:
ObjectTracker found 4 object(s) that were not released!!! [MockDirectoryWrapper, SolrCore, MockDirectoryWrapper, MockDirectoryWrapper]
Stack Trace:
java.lang.AssertionError: ObjectTracker found 4 object(s) that were not released!!! [MockDirectoryWrapper, SolrCore, MockDirectoryWrapper, MockDirectoryWrapper]
at __randomizedtesting.SeedInfo.seed([BD79689EDF0D9636]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.junit.Assert.assertTrue(Assert.java:43)
at org.junit.Assert.assertNull(Assert.java:551)
at org.apache.solr.SolrTestCaseJ4.teardownTestCases(SolrTestCaseJ4.java:258)
at sun.reflect.GeneratedMethodAccessor18.invoke(Unknown Source)
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:1764)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:834)
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:367)
at java.lang.Thread.run(Thread.java:745)
Build Log:
[...truncated 11490 lines...]
[junit4] Suite: org.apache.solr.util.TestSolrCLIRunExample
[junit4] 2> Creating dataDir: /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.util.TestSolrCLIRunExample_BD79689EDF0D9636-001/init-core-data-001
[junit4] 2> 1034414 INFO (SUITE-TestSolrCLIRunExample-seed#[BD79689EDF0D9636]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.SolrTestCaseJ4$SuppressSSL(bugUrl=https://issues.apache.org/jira/browse/SOLR-5776)
[junit4] 2> 1034415 INFO (TEST-TestSolrCLIRunExample.testSchemalessExample-seed#[BD79689EDF0D9636]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testSchemalessExample
[junit4] 2> 1034415 INFO (TEST-TestSolrCLIRunExample.testSchemalessExample-seed#[BD79689EDF0D9636]) [ ] o.a.s.u.TestSolrCLIRunExample Selected port 42814 to start schemaless example Solr instance on ...
[junit4] 2> 1034452 INFO (Thread-1114) [ ] o.e.j.s.Server jetty-9.3.8.v20160314
[junit4] 2> 1034454 INFO (Thread-1114) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@1e0700be{/solr,null,AVAILABLE}
[junit4] 2> 1034456 INFO (Thread-1114) [ ] o.e.j.s.ServerConnector Started ServerConnector@9360b4b{HTTP/1.1,[http/1.1]}{127.0.0.1:42814}
[junit4] 2> 1034456 INFO (Thread-1114) [ ] o.e.j.s.Server Started @1036117ms
[junit4] 2> 1034456 INFO (Thread-1114) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=42814}
[junit4] 2> 1034456 INFO (Thread-1114) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@73d16e93
[junit4] 2> 1034456 INFO (Thread-1114) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.util.TestSolrCLIRunExample_BD79689EDF0D9636-001/tempDir-001/schemaless/solr'
[junit4] 2> 1034456 INFO (Thread-1114) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 1034456 INFO (Thread-1114) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
[junit4] 2> 1034457 INFO (Thread-1114) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.util.TestSolrCLIRunExample_BD79689EDF0D9636-001/tempDir-001/schemaless/solr/solr.xml
[junit4] 2> 1034460 ERROR (qtp67868165-3880) [ ] o.a.s.s.SolrDispatchFilter Error processing the request. CoreContainer is either not initialized or shutting down.
[junit4] 2> 1034461 WARN (qtp67868165-3880) [ ] o.e.j.s.ServletHandler /solr/admin/info/system
[junit4] 2> org.apache.solr.common.SolrException: Error processing the request. CoreContainer is either not initialized or shutting down.
[junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:217)
[junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:208)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1676)
[junit4] 2> at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:108)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1676)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:581)
[junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:224)
[junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1160)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:511)
[junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:185)
[junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1092)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141)
[junit4] 2> at org.eclipse.jetty.server.handler.gzip.GzipHandler.handle(GzipHandler.java:462)
[junit4] 2> at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:134)
[junit4] 2> at org.eclipse.jetty.server.Server.handle(Server.java:518)
[junit4] 2> at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:308)
[junit4] 2> at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:244)
[junit4] 2> at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:273)
[junit4] 2> at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:95)
[junit4] 2> at org.eclipse.jetty.io.SelectChannelEndPoint$2.run(SelectChannelEndPoint.java:93)
[junit4] 2> at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.produceAndRun(ExecuteProduceConsume.java:246)
[junit4] 2> at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.run(ExecuteProduceConsume.java:156)
[junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:654)
[junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool$3.run(QueuedThreadPool.java:572)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2> 1034461 INFO (Thread-1114) [ ] o.a.s.c.CorePropertiesLocator Config-defined core root directory: /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.util.TestSolrCLIRunExample_BD79689EDF0D9636-001/tempDir-001/schemaless/solr
[junit4] 2> 1034461 INFO (Thread-1114) [ ] o.a.s.c.CoreContainer New CoreContainer 824164723
[junit4] 2> 1034461 INFO (Thread-1114) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.util.TestSolrCLIRunExample_BD79689EDF0D9636-001/tempDir-001/schemaless/solr]
[junit4] 2> 1034462 WARN (Thread-1114) [ ] o.a.s.c.CoreContainer Couldn't add files from /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.util.TestSolrCLIRunExample_BD79689EDF0D9636-001/tempDir-001/schemaless/solr/lib to classpath: /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.util.TestSolrCLIRunExample_BD79689EDF0D9636-001/tempDir-001/schemaless/solr/lib
[junit4] 2> 1034462 INFO (Thread-1114) [ ] o.a.s.h.c.HttpShardHandlerFactory created with socketTimeout : 600000,connTimeout : 60000,maxConnectionsPerHost : 20,maxConnections : 10000,corePoolSize : 0,maximumPoolSize : 2147483647,maxThreadIdleTime : 5,sizeOfQueue : -1,fairnessPolicy : false,useRetries : false,connectionsEvictorSleepDelay : 5000,maxConnectionIdleTime : 40000,
[junit4] 2> 1034463 INFO (Thread-1114) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=600000&connTimeout=60000&retry=true
[junit4] 2> 1034463 INFO (Thread-1114) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 1034463 INFO (Thread-1114) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 1034463 INFO (Thread-1114) [ ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module.
[junit4] 2> 1034463 INFO (Thread-1114) [ ] o.a.s.c.CoreContainer No authentication plugin used.
[junit4] 2> 1034463 INFO (Thread-1114) [ ] o.a.s.c.CorePropertiesLocator Looking for core definitions underneath /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.util.TestSolrCLIRunExample_BD79689EDF0D9636-001/tempDir-001/schemaless/solr
[junit4] 2> 1034464 INFO (Thread-1114) [ ] o.a.s.c.CorePropertiesLocator Found 0 core definitions
[junit4] 2> 1034464 INFO (Thread-1114) [ ] o.a.s.s.SolrDispatchFilter user.dir=/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0
[junit4] 2> 1034464 INFO (Thread-1114) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done
[junit4] 2> 1036490 INFO (qtp67868165-3875) [ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/info/system params={wt=json} status=0 QTime=23
[junit4] 2> 1036514 INFO (qtp67868165-3876) [ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={core=gettingstarted&action=STATUS&wt=json} status=0 QTime=0
[junit4] 2> 1036525 INFO (qtp67868165-3878) [ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/info/system params={wt=json} status=0 QTime=6
[junit4] 2> 1036536 INFO (qtp67868165-3875) [ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/info/system params={wt=json} status=0 QTime=6
[junit4] 2> 1036540 INFO (qtp67868165-3880) [ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={core=gettingstarted&action=STATUS&wt=json} status=0 QTime=0
[junit4] 2> 1036555 INFO (qtp67868165-3882) [ ] o.a.s.h.a.CoreAdminOperation core create command name=gettingstarted&action=CREATE&instanceDir=gettingstarted&wt=json
[junit4] 2> 1036555 INFO (qtp67868165-3882) [ ] o.a.s.c.CoreDescriptor Created CoreDescriptor: {name=gettingstarted, config=solrconfig.xml, loadOnStartup=true, schema=schema.xml, configSetProperties=configsetprops.json, transient=false, dataDir=data/}
[junit4] 2> 1036555 INFO (qtp67868165-3882) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.util.TestSolrCLIRunExample_BD79689EDF0D9636-001/tempDir-001/schemaless/solr/gettingstarted'
[junit4] 2> 1036556 INFO (qtp67868165-3882) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 1036556 INFO (qtp67868165-3882) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
[junit4] 2> 1036566 INFO (qtp67868165-3882) [ ] o.a.s.c.SolrConfig current version of requestparams : 0
[junit4] 2> 1036567 INFO (qtp67868165-3882) [ ] o.a.s.c.SolrConfig Adding specified lib dirs to ClassLoader
[junit4] 2> 1036568 WARN (qtp67868165-3882) [ ] o.a.s.c.SolrConfig Couldn't add files from /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.util.TestSolrCLIRunExample_BD79689EDF0D9636-001/tempDir-001/schemaless/solr/gettingstarted/../../../../contrib/extraction/lib filtered by .*\.jar to classpath: /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.util.TestSolrCLIRunExample_BD79689EDF0D9636-001/tempDir-001/schemaless/solr/gettingstarted/../../../../contrib/extraction/lib
[junit4] 2> 1036568 WARN (qtp67868165-3882) [ ] o.a.s.c.SolrConfig Couldn't add files from /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.util.TestSolrCLIRunExample_BD79689EDF0D9636-001/tempDir-001/schemaless/solr/gettingstarted/../../../../dist filtered by solr-cell-\d.*\.jar to classpath: /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.util.TestSolrCLIRunExample_BD79689EDF0D9636-001/tempDir-001/schemaless/solr/gettingstarted/../../../../dist
[junit4] 2> 1036569 WARN (qtp67868165-3882) [ ] o.a.s.c.SolrConfig Couldn't add files from /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.util.TestSolrCLIRunExample_BD79689EDF0D9636-001/tempDir-001/schemaless/solr/gettingstarted/../../../../contrib/clustering/lib filtered by .*\.jar to classpath: /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.util.TestSolrCLIRunExample_BD79689EDF0D9636-001/tempDir-001/schemaless/solr/gettingstarted/../../../../contrib/clustering/lib
[junit4] 2> 1036569 WARN (qtp67868165-3882) [ ] o.a.s.c.SolrConfig Couldn't add files from /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.util.TestSolrCLIRunExample_BD79689EDF0D9636-001/tempDir-001/schemaless/solr/gettingstarted/../../../../dist filtered by solr-clustering-\d.*\.jar to classpath: /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.util.TestSolrCLIRunExample_BD79689EDF0D9636-001/tempDir-001/schemaless/solr/gettingstarted/../../../../dist
[junit4] 2> 1036569 WARN (qtp67868165-3882) [ ] o.a.s.c.SolrConfig Couldn't add files from /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.util.TestSolrCLIRunExample_BD79689EDF0D9636-001/tempDir-001/schemaless/solr/gettingstarted/../../../../contrib/langid/lib filtered by .*\.jar to classpath: /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.util.TestSolrCLIRunExample_BD79689EDF0D9636-001/tempDir-001/schemaless/solr/gettingstarted/../../../../contrib/langid/lib
[junit4] 2> 1036569 WARN (qtp67868165-3882) [ ] o.a.s.c.SolrConfig Couldn't add files from /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.util.TestSolrCLIRunExample_BD79689EDF0D9636-001/tempDir-001/schemaless/solr/gettingstarted/../../../../dist filtered by solr-langid-\d.*\.jar to classpath: /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.util.TestSolrCLIRunExample_BD79689EDF0D9636-001/tempDir-001/schemaless/solr/gettingstarted/../../../../dist
[junit4] 2> 1036570 WARN (qtp67868165-3882) [ ] o.a.s.c.SolrConfig Couldn't add files from /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.util.TestSolrCLIRunExample_BD79689EDF0D9636-001/tempDir-001/schemaless/solr/gettingstarted/../../../../contrib/velocity/lib filtered by .*\.jar to classpath: /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.util.TestSolrCLIRunExample_BD79689EDF0D9636-001/tempDir-001/schemaless/solr/gettingstarted/../../../../contrib/velocity/lib
[junit4] 2> 1036571 WARN (qtp67868165-3882) [ ] o.a.s.c.SolrConfig Couldn't add files from /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.util.TestSolrCLIRunExample_BD79689EDF0D9636-001/tempDir-001/schemaless/solr/gettingstarted/../../../../dist filtered by solr-velocity-\d.*\.jar to classpath: /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.util.TestSolrCLIRunExample_BD79689EDF0D9636-001/tempDir-001/schemaless/solr/gettingstarted/../../../../dist
[junit4] 2> 1036587 INFO (qtp67868165-3882) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.3.0
[junit4] 2> 1036605 INFO (qtp67868165-3882) [ ] o.a.s.c.SolrConfig Loaded SolrConfig: solrconfig.xml
[junit4] 2> 1036609 INFO (qtp67868165-3882) [ ] o.a.s.s.IndexSchema [gettingstarted] Schema name=example-data-driven-schema
[junit4] 2> 1036740 INFO (qtp67868165-3882) [ ] o.a.s.s.IndexSchema unique key field: id
[junit4] 2> 1036811 INFO (qtp67868165-3882) [ ] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
[junit4] 2> 1036814 INFO (qtp67868165-3882) [ ] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
[junit4] 2> 1036850 INFO (qtp67868165-3882) [ ] o.a.s.c.ConfigSetProperties Did not find ConfigSet properties, assuming default properties: Can't find resource 'configsetprops.json' in classpath or '/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.util.TestSolrCLIRunExample_BD79689EDF0D9636-001/tempDir-001/schemaless/solr/gettingstarted'
[junit4] 2> 1036851 INFO (qtp67868165-3882) [ ] o.a.s.c.CoreContainer Creating SolrCore 'gettingstarted' using configuration from instancedir /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.util.TestSolrCLIRunExample_BD79689EDF0D9636-001/tempDir-001/schemaless/solr/gettingstarted
[junit4] 2> 1036851 INFO (qtp67868165-3882) [ x:gettingstarted] o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 1036851 INFO (qtp67868165-3882) [ x:gettingstarted] o.a.s.c.SolrCore [[gettingstarted] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.util.TestSolrCLIRunExample_BD79689EDF0D9636-001/tempDir-001/schemaless/solr/gettingstarted], dataDir=[/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.util.TestSolrCLIRunExample_BD79689EDF0D9636-001/tempDir-001/schemaless/solr/gettingstarted/data/]
[junit4] 2> 1036851 INFO (qtp67868165-3882) [ x:gettingstarted] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@1809b0f9
[junit4] 2> 1036860 INFO (qtp67868165-3882) [ x:gettingstarted] o.a.s.c.SolrCore [[gettingstarted] ] Added SolrEventListener for newSearcher: [org.apache.solr.core.QuerySenderListener{queries=[]}]
[junit4] 2> 1036860 INFO (qtp67868165-3882) [ x:gettingstarted] o.a.s.c.SolrCore [[gettingstarted] ] Added SolrEventListener for firstSearcher: [org.apache.solr.core.QuerySenderListener{queries=[]}]
[junit4] 2> 1036861 INFO (qtp67868165-3882) [ x:gettingstarted] o.a.s.c.CachingDirectoryFactory return new directory for /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.util.TestSolrCLIRunExample_BD79689EDF0D9636-001/tempDir-001/schemaless/solr/gettingstarted/data/snapshot_metadata
[junit4] 2> 1036861 INFO (qtp67868165-3882) [ x:gettingstarted] o.a.s.c.s.SolrSnapshotMetaDataManager Loading from snapshot metadata file...
[junit4] 2> 1036861 INFO (qtp67868165-3882) [ x:gettingstarted] o.a.s.c.SchemaCodecFactory Using default compressionMode: BEST_SPEED
[junit4] 2> 1036861 INFO (qtp67868165-3882) [ x:gettingstarted] o.a.s.c.CachingDirectoryFactory return new directory for /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.util.TestSolrCLIRunExample_BD79689EDF0D9636-001/tempDir-001/schemaless/solr/gettingstarted/data
[junit4] 2> 1036862 INFO (qtp67868165-3882) [ x:gettingstarted] o.a.s.c.SolrCore New index directory detected: old=null new=/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.util.TestSolrCLIRunExample_BD79689EDF0D9636-001/tempDir-001/schemaless/solr/gettingstarted/data/index/
[junit4] 2> 1036862 WARN (qtp67868165-3882) [ x:gettingstarted] o.a.s.c.SolrCore [gettingstarted] Solr index directory '/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.util.TestSolrCLIRunExample_BD79689EDF0D9636-001/tempDir-001/schemaless/solr/gettingstarted/data/index' doesn't exist. Creating new index...
[junit4] 2> 1036862 INFO (qtp67868165-3882) [ x:gettingstarted] o.a.s.c.CachingDirectoryFactory return new directory for /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.util.TestSolrCLIRunExample_BD79689EDF0D9636-001/tempDir-001/schemaless/solr/gettingstarted/data/index
[junit4] 2> 1036863 INFO (qtp67868165-3882) [ x:gettingstarted] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@7225cd41 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@69ff036e),segFN=segments_1,generation=1}
[junit4] 2> 1036863 INFO (qtp67868165-3882) [ x:gettingstarted] o.a.s.c.SolrDeletionPolicy newest commit generation = 1
[junit4] 2> 1036863 INFO (OldIndexDirectoryCleanupThreadForCore-gettingstarted) [ x:gettingstarted] o.a.s.c.SolrCore Looking for old index directories to cleanup for core gettingstarted in /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.util.TestSolrCLIRunExample_BD79689EDF0D9636-001/tempDir-001/schemaless/solr/gettingstarted/data/
[junit4] 2> 1036863 INFO (qtp67868165-3882) [ x:gettingstarted] o.a.s.c.PluginBag queryResponseWriter : 'velocity' created with startup=lazy
[junit4] 2> 1036863 WARN (OldIndexDirectoryCleanupThreadForCore-gettingstarted) [ x:gettingstarted] o.a.s.c.DirectoryFactory /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.util.TestSolrCLIRunExample_BD79689EDF0D9636-001/tempDir-001/schemaless/solr/gettingstarted/data/ does not point to a valid data directory; skipping clean-up of old index directories.
[junit4] 2> 1036863 INFO (qtp67868165-3882) [ x:gettingstarted] o.a.s.r.XSLTResponseWriter xsltCacheLifetimeSeconds=5
[junit4] 2> 1036904 INFO (qtp67868165-3882) [ x:gettingstarted] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "add-unknown-fields-to-the-schema"
[junit4] 2> 1037045 INFO (qtp67868165-3882) [ x:gettingstarted] o.a.s.c.SolrCore no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 1037048 INFO (qtp67868165-3882) [ x:gettingstarted] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 1037049 INFO (qtp67868165-3882) [ x:gettingstarted] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 1037050 INFO (qtp67868165-3882) [ x:gettingstarted] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 1037051 INFO (qtp67868165-3882) [ x:gettingstarted] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 1037054 INFO (qtp67868165-3882) [ x:gettingstarted] o.a.s.c.PluginBag requestHandler : '/terms' created with startup=lazy
[junit4] 2> 1037055 INFO (qtp67868165-3882) [ x:gettingstarted] o.a.s.c.PluginBag requestHandler : '/update/extract' created with startup=lazy
[junit4] 2> 1037055 INFO (qtp67868165-3882) [ x:gettingstarted] o.a.s.c.PluginBag requestHandler : '/analysis/field' created with startup=lazy
[junit4] 2> 1037055 INFO (qtp67868165-3882) [ x:gettingstarted] o.a.s.c.PluginBag requestHandler : '/analysis/document' created with startup=lazy
[junit4] 2> 1037055 INFO (qtp67868165-3882) [ x:gettingstarted] o.a.s.c.PluginBag requestHandler : '/spell' created with startup=lazy
[junit4] 2> 1037055 INFO (qtp67868165-3882) [ x:gettingstarted] o.a.s.c.PluginBag requestHandler : '/tvrh' created with startup=lazy
[junit4] 2> 1037055 INFO (qtp67868165-3882) [ x:gettingstarted] o.a.s.c.PluginBag requestHandler : '/elevate' created with startup=lazy
[junit4] 2> 1037055 INFO (qtp67868165-3882) [ x:gettingstarted] o.a.s.c.RequestHandlers Registered paths: /admin/mbeans,/browse,/update/json/docs,/admin/luke,/export,/get,/admin/properties,/elevate,/update/json,/admin/threads,/query,/analysis/field,/analysis/document,/spell,/update/csv,/sql,/graph,/tvrh,/select,/admin/segments,/admin/system,/replication,/config,/stream,/schema,/admin/plugins,/admin/logging,/admin/ping,/update,/admin/file,/terms,/debug/dump,/update/extract
[junit4] 2> 1037055 INFO (qtp67868165-3882) [ x:gettingstarted] o.a.s.c.SolrCore Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 1037056 INFO (qtp67868165-3882) [ x:gettingstarted] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 1037056 INFO (qtp67868165-3882) [ x:gettingstarted] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 1037057 INFO (qtp67868165-3882) [ x:gettingstarted] o.a.s.u.CommitTracker Hard AutoCommit: if uncommited for 15000ms;
[junit4] 2> 1037057 INFO (qtp67868165-3882) [ x:gettingstarted] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1037057 INFO (qtp67868165-3882) [ x:gettingstarted] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@7225cd41 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@69ff036e),segFN=segments_1,generation=1}
[junit4] 2> 1037057 INFO (qtp67868165-3882) [ x:gettingstarted] o.a.s.c.SolrDeletionPolicy newest commit generation = 1
[junit4] 2> 1037058 INFO (qtp67868165-3882) [ x:gettingstarted] o.a.s.s.SolrIndexSearcher Opening [Searcher@7a20669[gettingstarted] main]
[junit4] 2> 1037058 INFO (qtp67868165-3882) [ x:gettingstarted] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.util.TestSolrCLIRunExample_BD79689EDF0D9636-001/tempDir-001/schemaless/solr/gettingstarted/conf
[junit4] 2> 1037058 INFO (qtp67868165-3882) [ x:gettingstarted] o.a.s.r.RestManager Initializing RestManager with initArgs: {storageDir=/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.util.TestSolrCLIRunExample_BD79689EDF0D9636-001/tempDir-001/schemaless/solr/gettingstarted/conf}
[junit4] 2> 1037058 INFO (qtp67868165-3882) [ x:gettingstarted] o.a.s.r.ManagedResourceStorage Reading _rest_managed.json using file:dir=/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.util.TestSolrCLIRunExample_BD79689EDF0D9636-001/tempDir-001/schemaless/solr/gettingstarted/conf
[junit4] 2> 1037058 INFO (qtp67868165-3882) [ x:gettingstarted] o.a.s.r.RestManager Initializing 0 registered ManagedResources
[junit4] 2> 1037058 INFO (qtp67868165-3882) [ x:gettingstarted] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
[junit4] 2> 1037070 INFO (qtp67868165-3882) [ x:gettingstarted] o.a.s.s.DirectSolrSpellChecker init: {name=default,field=_text_,classname=solr.DirectSolrSpellChecker,distanceMeasure=internal,accuracy=0.5,maxEdits=2,minPrefix=1,maxInspections=5,minQueryLength=4,maxQueryFrequency=0.01}
[junit4] 2> 1037070 INFO (qtp67868165-3882) [ x:gettingstarted] o.a.s.h.c.SpellCheckComponent No queryConverter defined, using default converter
[junit4] 2> 1037070 INFO (qtp67868165-3882) [ x:gettingstarted] o.a.s.h.c.QueryElevationComponent Loading QueryElevation from: /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.util.TestSolrCLIRunExample_BD79689EDF0D9636-001/tempDir-001/schemaless/solr/gettingstarted/conf/elevate.xml
[junit4] 2> 1037087 INFO (qtp67868165-3882) [ x:gettingstarted] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 1037088 INFO (searcherExecutor-1805-thread-1-processing-x:gettingstarted) [ x:gettingstarted] o.a.s.c.QuerySenderListener QuerySenderListener sending requests to Searcher@7a20669[gettingstarted] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1037088 INFO (searcherExecutor-1805-thread-1-processing-x:gettingstarted) [ x:gettingstarted] o.a.s.c.QuerySenderListener QuerySenderListener done.
[junit4] 2> 1037088 INFO (searcherExecutor-1805-thread-1-processing-x:gettingstarted) [ x:gettingstarted] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
[junit4] 2> 1037089 INFO (searcherExecutor-1805-thread-1-processing-x:gettingstarted) [ x:gettingstarted] o.a.s.c.SolrCore [gettingstarted] Registered new searcher Searcher@7a20669[gettingstarted] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1037089 INFO (qtp67868165-3882) [ x:gettingstarted] o.a.s.u.UpdateLog Looking up max value of version field to seed version buckets
[junit4] 2> 1037089 INFO (qtp67868165-3882) [ x:gettingstarted] o.a.s.u.VersionInfo Refreshing highest value of _version_ for 65536 version buckets from index
[junit4] 2> 1037090 INFO (qtp67868165-3882) [ x:gettingstarted] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1544012665083396096
[junit4] 2> 1037091 INFO (qtp67868165-3882) [ x:gettingstarted] o.a.s.u.UpdateLog Took 1.0ms to seed version buckets with highest version 1544012665083396096
[junit4] 2> 1037092 INFO (qtp67868165-3882) [ x:gettingstarted] o.a.s.c.CoreContainer registering core: gettingstarted
[junit4] 2> 1037093 INFO (qtp67868165-3882) [ x:gettingstarted] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={name=gettingstarted&action=CREATE&instanceDir=gettingstarted&wt=json} status=0 QTime=537
[junit4] 2> 1037094 INFO (TEST-TestSolrCLIRunExample.testSchemalessExample-seed#[BD79689EDF0D9636]) [ ] o.e.j.s.ServerConnector Stopped ServerConnector@9360b4b{HTTP/1.1,[http/1.1]}{127.0.0.1:42814}
[junit4] 2> 1037095 INFO (TEST-TestSolrCLIRunExample.testSchemalessExample-seed#[BD79689EDF0D9636]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=824164723
[junit4] 2> 1037095 INFO (coreCloseExecutor-1810-thread-1) [ x:gettingstarted] o.a.s.c.SolrCore [gettingstarted] CLOSING SolrCore org.apache.solr.core.SolrCore@494ff7f8
[junit4] 2> 1037096 INFO (coreCloseExecutor-1810-thread-1) [ x:gettingstarted] o.a.s.u.DirectUpdateHandler2 closing DirectUpdateHandler2{commits=0,autocommit maxTime=15000ms,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=0,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0,transaction_logs_total_size=0,transaction_logs_total_number=0}
[junit4] 2> 1037096 INFO (coreCloseExecutor-1810-thread-1) [ x:gettingstarted] o.a.s.u.SolrCoreState Closing SolrCoreState
[junit4] 2> 1037096 INFO (coreCloseExecutor-1810-thread-1) [ x:gettingstarted] o.a.s.u.DefaultSolrCoreState SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 1037096 INFO (coreCloseExecutor-1810-thread-1) [ x:gettingstarted] o.a.s.u.DefaultSolrCoreState closing IndexWriter with IndexWriterCloser
[junit4] 2> 1037096 INFO (coreCloseExecutor-1810-thread-1) [ x:gettingstarted] o.a.s.c.SolrCore [gettingstarted] Closing main searcher on request.
[junit4] 2> 1037105 INFO (coreCloseExecutor-1810-thread-1) [ x:gettingstarted] o.a.s.c.CachingDirectoryFactory Closing MockDirectoryFactory - 3 directories currently being tracked
[junit4] 2> 1037106 INFO (coreCloseExecutor-1810-thread-1) [ x:gettingstarted] o.a.s.c.CachingDirectoryFactory looking to close /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.util.TestSolrCLIRunExample_BD79689EDF0D9636-001/tempDir-001/schemaless/solr/gettingstarted/data/index [CachedDir<<refCount=0;path=/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.util.TestSolrCLIRunExample_BD79689EDF0D9636-001/tempDir-001/schemaless/solr/gettingstarted/data/index;done=false>>]
[junit4] 2> 1037106 INFO (coreCloseExecutor-1810-thread-1) [ x:gettingstarted] o.a.s.c.CachingDirectoryFactory Closing directory, CoreContainer#isShutdown=true
[junit4] 2> 1037106 INFO (coreCloseExecutor-1810-thread-1) [ x:gettingstarted] o.a.s.c.CachingDirectoryFactory Closing directory: /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.util.TestSolrCLIRunExample_BD79689EDF0D9636-001/tempDir-001/schemaless/solr/gettingstarted/data/index
[junit4] 2> 1037106 INFO (coreCloseExecutor-1810-thread-1) [ x:gettingstarted] o.a.s.c.CachingDirectoryFactory looking to close /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.util.TestSolrCLIRunExample_BD79689EDF0D9636-001/tempDir-001/schemaless/solr/gettingstarted/data [CachedDir<<refCount=0;path=/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.util.TestSolrCLIRunExample_BD79689EDF0D9636-001/tempDir-001/schemaless/solr/gettingstarted/data;done=false>>]
[junit4] 2> 1037106 INFO (coreCloseExecutor-1810-thread-1) [ x:gettingstarted] o.a.s.c.CachingDirectoryFactory Closing directory, CoreContainer#isShutdown=true
[junit4] 2> 1037106 INFO (coreCloseExecutor-1810-thread-1) [ x:gettingstarted] o.a.s.c.CachingDirectoryFactory Closing directory: /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.util.TestSolrCLIRunExample_BD79689EDF0D9636-001/tempDir-001/schemaless/solr/gettingstarted/data
[junit4] 2> 1037106 INFO (coreCloseExecutor-1810-thread-1) [ x:gettingstarted] o.a.s.c.CachingDirectoryFactory looking to close /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.util.TestSolrCLIRunExample_BD79689EDF0D9636-001/tempDir-001/schemaless/solr/gettingstarted/data/snapshot_metadata [CachedDir<<refCount=0;path=/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.util.TestSolrCLIRunExample_BD79689EDF0D9636-001/tempDir-001/schemaless/solr/gettingstarted/data/snapshot_metadata;done=false>>]
[junit4] 2> 1037106 INFO (coreCloseExecutor-1810-thread-1) [ x:gettingstarted] o.a.s.c.CachingDirectoryFactory Closing directory, CoreContainer#isShutdown=true
[junit4] 2> 1037106 INFO (coreCloseExecutor-1810-thread-1) [ x:gettingstarted] o.a.s.c.CachingDirectoryFactory Closing directory: /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.util.TestSolrCLIRunExample_BD79689EDF0D9636-001/tempDir-001/schemaless/solr/gettingstarted/data/snapshot_metadata
[junit4] 2> 1037107 INFO (TEST-TestSolrCLIRunExample.testSchemalessExample-seed#[BD79689EDF0D9636]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@1e0700be{/solr,null,UNAVAILABLE}
[junit4] 2> 1037108 INFO (TEST-TestSolrCLIRunExample.testSchemalessExample-seed#[BD79689EDF0D9636]) [ ] o.a.s.u.TestSolrCLIRunExample Stopped standalone Solr instance running on port 42814
[junit4] 2> 1037108 INFO (TEST-TestSolrCLIRunExample.testSchemalessExample-seed#[BD79689EDF0D9636]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testSchemalessExample
[junit4] 2> 1037110 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[BD79689EDF0D9636]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testInteractiveSolrCloudExample
[junit4] 2> 1037118 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[BD79689EDF0D9636]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 1037118 INFO (Thread-1124) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 1037118 INFO (Thread-1124) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 1037218 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[BD79689EDF0D9636]) [ ] o.a.s.c.ZkTestServer start zk server on port:46430
[junit4] 2> 1037219 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[BD79689EDF0D9636]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 1037220 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[BD79689EDF0D9636]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1037224 INFO (zkCallback-643-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@4a6f2fff name:ZooKeeperConnection Watcher:127.0.0.1:46430 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1037224 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[BD79689EDF0D9636]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1037225 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[BD79689EDF0D9636]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 1037225 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[BD79689EDF0D9636]) [ ] o.a.s.c.c.SolrZkClient makePath: /solr/solr.xml
[junit4] 2> 1037229 INFO (jetty-launcher-642-thread-1) [ ] o.e.j.s.Server jetty-9.3.8.v20160314
[junit4] 2> 1037230 INFO (jetty-launcher-642-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@1055b9ed{/solr,null,AVAILABLE}
[junit4] 2> 1037230 INFO (jetty-launcher-642-thread-1) [ ] o.e.j.s.ServerConnector Started ServerConnector@14f86610{HTTP/1.1,[http/1.1]}{127.0.0.1:38199}
[junit4] 2> 1037230 INFO (jetty-launcher-642-thread-1) [ ] o.e.j.s.Server Started @1038891ms
[junit4] 2> 1037230 INFO (jetty-launcher-642-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=38199}
[junit4] 2> 1037231 INFO (jetty-launcher-642-thread-1) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@73d16e93
[junit4] 2> 1037231 INFO (jetty-launcher-642-thread-1) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.util.TestSolrCLIRunExample_BD79689EDF0D9636-001/tempDir-003/node1'
[junit4] 2> 1037231 INFO (jetty-launcher-642-thread-1) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 1037231 INFO (jetty-launcher-642-thread-1) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
[junit4] 2> 1037231 INFO (jetty-launcher-642-thread-1) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 1037231 INFO (jetty-launcher-642-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1037238 INFO (zkCallback-644-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@6bf1c52e name:ZooKeeperConnection Watcher:127.0.0.1:46430/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1037238 INFO (jetty-launcher-642-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1037238 INFO (jetty-launcher-642-thread-1) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 1037238 INFO (jetty-launcher-642-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1037244 INFO (jetty-launcher-642-thread-1) [ ] o.a.s.c.CorePropertiesLocator Config-defined core root directory: /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.util.TestSolrCLIRunExample_BD79689EDF0D9636-001/tempDir-003/node1
[junit4] 2> 1037244 INFO (jetty-launcher-642-thread-1) [ ] o.a.s.c.CoreContainer New CoreContainer 1130507510
[junit4] 2> 1037244 INFO (jetty-launcher-642-thread-1) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.util.TestSolrCLIRunExample_BD79689EDF0D9636-001/tempDir-003/node1]
[junit4] 2> 1037245 WARN (jetty-launcher-642-thread-1) [ ] o.a.s.c.CoreContainer Couldn't add files from /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.util.TestSolrCLIRunExample_BD79689EDF0D9636-001/tempDir-003/node1/lib to classpath: /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.util.TestSolrCLIRunExample_BD79689EDF0D9636-001/tempDir-003/node1/lib
[junit4] 2> 1037245 INFO (jetty-launcher-642-thread-1) [ ] o.a.s.h.c.HttpShardHandlerFactory created with socketTimeout : 600000,connTimeout : 60000,maxConnectionsPerHost : 20,maxConnections : 10000,corePoolSize : 0,maximumPoolSize : 2147483647,maxThreadIdleTime : 5,sizeOfQueue : -1,fairnessPolicy : false,useRetries : false,connectionsEvictorSleepDelay : 5000,maxConnectionIdleTime : 40000,
[junit4] 2> 1037246 INFO (jetty-launcher-642-thread-1) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=600000&connTimeout=60000&retry=true
[junit4] 2> 1037247 INFO (jetty-launcher-642-thread-1) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 1037247 INFO (jetty-launcher-642-thread-1) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 1037247 INFO (jetty-launcher-642-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:46430/solr
[junit4] 2> 1037247 INFO (jetty-launcher-642-thread-1) [ ] o.a.s.c.ZkController zkHost includes chroot
[junit4] 2> 1037247 INFO (jetty-launcher-642-thread-1) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 1037247 INFO (jetty-launcher-642-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1037248 INFO (zkCallback-647-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@2359c375 name:ZooKeeperConnection Watcher:127.0.0.1:46430 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1037254 INFO (jetty-launcher-642-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1037254 INFO (jetty-launcher-642-thread-1) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 1037256 INFO (jetty-launcher-642-thread-1) [n:localhost:38199_solr ] o.a.s.c.ZkController Added new OnReconnect listener org.apache.solr.cloud.ZkController$$Lambda$80/107450868@33644816
[junit4] 2> 1037257 INFO (jetty-launcher-642-thread-1) [n:localhost:38199_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1037257 INFO (zkCallback-648-thread-1-processing-n:localhost:38199_solr) [n:localhost:38199_solr ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@5b4cf78a name:ZooKeeperConnection Watcher:127.0.0.1:46430/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1037257 INFO (jetty-launcher-642-thread-1) [n:localhost:38199_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1037258 INFO (jetty-launcher-642-thread-1) [n:localhost:38199_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue
[junit4] 2> 1037259 INFO (jetty-launcher-642-thread-1) [n:localhost:38199_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-queue-work
[junit4] 2> 1037260 INFO (jetty-launcher-642-thread-1) [n:localhost:38199_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-running
[junit4] 2> 1037261 INFO (jetty-launcher-642-thread-1) [n:localhost:38199_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-completed
[junit4] 2> 1037262 INFO (jetty-launcher-642-thread-1) [n:localhost:38199_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-failure
[junit4] 2> 1037263 INFO (jetty-launcher-642-thread-1) [n:localhost:38199_solr ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
[junit4] 2> 1037263 INFO (jetty-launcher-642-thread-1) [n:localhost:38199_solr ] o.a.s.c.c.SolrZkClient makePath: /collections
[junit4] 2> 1037264 INFO (jetty-launcher-642-thread-1) [n:localhost:38199_solr ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
[junit4] 2> 1037264 INFO (jetty-launcher-642-thread-1) [n:localhost:38199_solr ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
[junit4] 2> 1037265 INFO (jetty-launcher-642-thread-1) [n:localhost:38199_solr ] o.a.s.c.c.SolrZkClient makePath: /security.json
[junit4] 2> 1037265 INFO (jetty-launcher-642-thread-1) [n:localhost:38199_solr ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 1037266 INFO (jetty-launcher-642-thread-1) [n:localhost:38199_solr ] o.a.s.c.c.ZkStateReader Loaded empty cluster properties
[junit4] 2> 1037266 INFO (jetty-launcher-642-thread-1) [n:localhost:38199_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0)
[junit4] 2> 1037268 INFO (jetty-launcher-642-thread-1) [n:localhost:38199_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect
[junit4] 2> 1037268 INFO (jetty-launcher-642-thread-1) [n:localhost:38199_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/election
[junit4] 2> 1037269 INFO (jetty-launcher-642-thread-1) [n:localhost:38199_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 1037269 INFO (jetty-launcher-642-thread-1) [n:localhost:38199_solr ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/96500791569743875-localhost:38199_solr-n_0000000000
[junit4] 2> 1037269 INFO (jetty-launcher-642-thread-1) [n:localhost:38199_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader localhost:38199_solr
[junit4] 2> 1037269 INFO (jetty-launcher-642-thread-1) [n:localhost:38199_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/leader
[junit4] 2> 1037270 INFO (jetty-launcher-642-thread-1) [n:localhost:38199_solr ] o.a.s.c.Overseer Overseer (id=96500791569743875-localhost:38199_solr-n_0000000000) starting
[junit4] 2> 1037271 INFO (jetty-launcher-642-thread-1) [n:localhost:38199_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue-work
[junit4] 2> 1037274 INFO (jetty-launcher-642-thread-1) [n:localhost:38199_solr ] o.a.s.c.OverseerAutoReplicaFailoverThread Starting OverseerAutoReplicaFailoverThread autoReplicaFailoverWorkLoopDelay=10000 autoReplicaFailoverWaitAfterExpiration=30000 autoReplicaFailoverBadNodeExpiration=60000
[junit4] 2> 1037274 INFO (OverseerCollectionConfigSetProcessor-96500791569743875-localhost:38199_solr-n_0000000000) [n:localhost:38199_solr ] o.a.s.c.OverseerTaskProcessor Process current queue of overseer operations
[junit4] 2> 1037274 INFO (OverseerStateUpdate-96500791569743875-localhost:38199_solr-n_0000000000) [n:localhost:38199_solr ] o.a.s.c.Overseer Starting to work on the main queue
[junit4] 2> 1037274 INFO (jetty-launcher-642-thread-1) [n:localhost:38199_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/localhost:38199_solr
[junit4] 2> 1037274 INFO (jetty-launcher-642-thread-1) [n:localhost:38199_solr ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/localhost:38199_solr
[junit4] 2> 1037276 INFO (zkCallback-648-thread-1-processing-n:localhost:38199_solr) [n:localhost:38199_solr ] o.a.s.c.c.ZkStateReader A live node change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes], has occurred - updating... (live nodes size: [0])
[junit4] 2> 1037276 INFO (OverseerStateUpdate-96500791569743875-localhost:38199_solr-n_0000000000) [n:localhost:38199_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1037292 INFO (zkCallback-648-thread-1-processing-n:localhost:38199_solr) [n:localhost:38199_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (1)
[junit4] 2> 1037311 INFO (jetty-launcher-642-thread-1) [n:localhost:38199_solr ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module.
[junit4] 2> 1037311 INFO (jetty-launcher-642-thread-1) [n:localhost:38199_solr ] o.a.s.c.CoreContainer No authentication plugin used.
[junit4] 2> 1037312 INFO (jetty-launcher-642-thread-1) [n:localhost:38199_solr ] o.a.s.c.CorePropertiesLocator Looking for core definitions underneath /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.util.TestSolrCLIRunExample_BD79689EDF0D9636-001/tempDir-003/node1
[junit4] 2> 1037312 INFO (jetty-launcher-642-thread-1) [n:localhost:38199_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions
[junit4] 2> 1037312 INFO (jetty-launcher-642-thread-1) [n:localhost:38199_solr ] o.a.s.s.SolrDispatchFilter user.dir=/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0
[junit4] 2> 1037312 INFO (jetty-launcher-642-thread-1) [n:localhost:38199_solr ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done
[junit4] 2> 1037313 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[BD79689EDF0D9636]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 1037313 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[BD79689EDF0D9636]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1037314 INFO (zkCallback-650-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@13e8e1aa name:ZooKeeperConnection Watcher:127.0.0.1:46430 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1037314 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[BD79689EDF0D9636]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1037314 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[BD79689EDF0D9636]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 1037322 INFO (qtp1124791031-3905) [n:localhost:38199_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/info/system params={wt=json} status=0 QTime=5
[junit4] 2> 1037327 INFO (qtp1124791031-3907) [n:localhost:38199_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :clusterstatus with params action=CLUSTERSTATUS&wt=json and sendToOCPQueue=true
[junit4] 2> 1037329 INFO (qtp1124791031-3907) [n:localhost:38199_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=CLUSTERSTATUS&wt=json} status=0 QTime=2
[junit4] 2> 1037330 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[BD79689EDF0D9636]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 1037330 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[BD79689EDF0D9636]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1037331 INFO (zkCallback-654-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@5611f52b name:ZooKeeperConnection Watcher:127.0.0.1:46430/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1037331 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[BD79689EDF0D9636]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1037331 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[BD79689EDF0D9636]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 1037331 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[BD79689EDF0D9636]) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 1037331 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[BD79689EDF0D9636]) [ ] o.a.s.c.c.ZkStateReader Loaded empty cluster properties
[junit4] 2> 1037332 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[BD79689EDF0D9636]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1037334 INFO (qtp1124791031-3919) [n:localhost:38199_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :list with params action=list&wt=json and sendToOCPQueue=true
[junit4] 2> 1037334 INFO (qtp1124791031-3919) [n:localhost:38199_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=list&wt=json} status=0 QTime=0
[junit4] 2> 1037342 INFO (qtp1124791031-3909) [n:localhost:38199_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/info/system params={wt=json} status=0 QTime=5
[junit4] 2> 1037346 INFO (qtp1124791031-3905) [n:localhost:38199_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :clusterstatus with params action=CLUSTERSTATUS&wt=json and sendToOCPQueue=true
[junit4] 2> 1037346 INFO (qtp1124791031-3905) [n:localhost:38199_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=CLUSTERSTATUS&wt=json} status=0 QTime=0
[junit4] 2> 1037347 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[BD79689EDF0D9636]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 1037347 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[BD79689EDF0D9636]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1037348 INFO (zkCallback-657-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@621cc9ac name:ZooKeeperConnection Watcher:127.0.0.1:46430/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1037348 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[BD79689EDF0D9636]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1037348 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[BD79689EDF0D9636]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 1037348 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[BD79689EDF0D9636]) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 1037349 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[BD79689EDF0D9636]) [ ] o.a.s.c.c.ZkStateReader Loaded empty cluster properties
[junit4] 2> 1037349 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[BD79689EDF0D9636]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1037351 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[BD79689EDF0D9636]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/protwords.txt
[junit4] 2> 1037352 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[BD79689EDF0D9636]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/solrconfig.xml
[junit4] 2> 1037353 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[BD79689EDF0D9636]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/stopwords.txt
[junit4] 2> 1037354 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[BD79689EDF0D9636]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/stopwords_ar.txt
[junit4] 2> 1037355 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[BD79689EDF0D9636]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/stopwords_el.txt
[junit4] 2> 1037356 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[BD79689EDF0D9636]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/stopwords_ja.txt
[junit4] 2> 1037357 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[BD79689EDF0D9636]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/stopwords_gl.txt
[junit4] 2> 1037358 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[BD79689EDF0D9636]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/contractions_fr.txt
[junit4] 2> 1037359 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[BD79689EDF0D9636]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/stopwords_fr.txt
[junit4] 2> 1037359 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[BD79689EDF0D9636]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/stopwords_nl.txt
[junit4] 2> 1037360 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[BD79689EDF0D9636]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/stopwords_cz.txt
[junit4] 2> 1037361 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[BD79689EDF0D9636]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/stopwords_ca.txt
[junit4] 2> 1037363 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[BD79689EDF0D9636]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/stopwords_de.txt
[junit4] 2> 1037363 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[BD79689EDF0D9636]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/contractions_ga.txt
[junit4] 2> 1037364 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[BD79689EDF0D9636]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/stopwords_da.txt
[junit4] 2> 1037365 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[BD79689EDF0D9636]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/stopwords_no.txt
[junit4] 2> 1037366 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[BD79689EDF0D9636]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/stopwords_es.txt
[junit4] 2> 1037368 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[BD79689EDF0D9636]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/stopwords_sv.txt
[junit4] 2> 1037369 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[BD79689EDF0D9636]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/stopwords_fi.txt
[junit4] 2> 1037370 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[BD79689EDF0D9636]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/stopwords_hu.txt
[junit4] 2> 1037383 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[BD79689EDF0D9636]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/stopwords_bg.txt
[junit4] 2> 1037385 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[BD79689EDF0D9636]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/stopwords_fa.txt
[junit4] 2> 1037387 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[BD79689EDF0D9636]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/contractions_it.txt
[junit4] 2> 1037388 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[BD79689EDF0D9636]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/stopwords_eu.txt
[junit4] 2> 1037388 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[BD79689EDF0D9636]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/stopwords_ro.txt
[junit4] 2> 1037389 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[BD79689EDF0D9636]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/stopwords_ga.txt
[junit4] 2> 1037390 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[BD79689EDF0D9636]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/stemdict_nl.txt
[junit4] 2> 1037391 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[BD79689EDF0D9636]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/stopwords_hy.txt
[junit4] 2> 1037391 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[BD79689EDF0D9636]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/contractions_ca.txt
[junit4] 2> 1037392 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[BD79689EDF0D9636]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/stoptags_ja.txt
[junit4] 2> 1037393 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[BD79689EDF0D9636]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/stopwords_lv.txt
[junit4] 2> 1037394 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[BD79689EDF0D9636]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/userdict_ja.txt
[junit4] 2> 1037395 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[BD79689EDF0D9636]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/stopwords_tr.txt
[junit4] 2> 1037396 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[BD79689EDF0D9636]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/stopwords_it.txt
[junit4] 2> 1037396 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[BD79689EDF0D9636]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/stopwords_ru.txt
[junit4] 2> 1037397 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[BD79689EDF0D9636]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/stopwords_th.txt
[junit4] 2> 1037398 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[BD79689EDF0D9636]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/hyphenations_ga.txt
[junit4] 2> 1037399 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[BD79689EDF0D9636]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/stopwords_en.txt
[junit4] 2> 1037400 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[BD79689EDF0D9636]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/stopwords_hi.txt
[junit4] 2> 1037400 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[BD79689EDF0D9636]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/stopwords_pt.txt
[junit4] 2> 1037401 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[BD79689EDF0D9636]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/stopwords_id.txt
[junit4] 2> 1037402 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[BD79689EDF0D9636]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/synonyms.txt
[junit4] 2> 1037403 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[BD79689EDF0D9636]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/elevate.xml
[junit4] 2> 1037403 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[BD79689EDF0D9636]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/currency.xml
[junit4] 2> 1037404 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[BD79689EDF0D9636]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/params.json
[junit4] 2> 1037405 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[BD79689EDF0D9636]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/managed-schema
[junit4] 2> 1037407 INFO (qtp1124791031-3908) [n:localhost:38199_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :list with params action=list&wt=json and sendToOCPQueue=true
[junit4] 2> 1037407 INFO (qtp1124791031-3908) [n:localhost:38199_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=list&wt=json} status=0 QTime=0
[junit4] 2> 1037408 INFO (qtp1124791031-3903) [n:localhost:38199_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params replicationFactor=2&maxShardsPerNode=4&collection.configName=testCloudExamplePrompt&name=testCloudExamplePrompt&action=CREATE&numShards=2&wt=json and sendToOCPQueue=true
[junit4] 2> 1037410 INFO (OverseerCollectionConfigSetProcessor-96500791569743875-localhost:38199_solr-n_0000000000) [n:localhost:38199_solr ] o.a.s.c.OverseerTaskProcessor Overseer Collection Message Handler: Get the message id:/overseer/collection-queue-work/qn-0000000000 message:{
[junit4] 2> "name":"testCloudExamplePrompt",
[junit4] 2> "fromApi":"true",
[junit4] 2> "replicationFactor":"2",
[junit4] 2> "collection.configName":"testCloudExamplePrompt",
[junit4] 2> "numShards":"2",
[junit4] 2> "maxShardsPerNode":"4",
[junit4] 2> "stateFormat":"2",
[junit4] 2> "operation":"create"}
[junit4] 2> 1037411 INFO (OverseerThreadFactory-1817-thread-1-processing-n:localhost:38199_solr) [n:localhost:38199_solr ] o.a.s.c.OverseerCollectionMessageHandler OverseerCollectionMessageHandler.processMessage : create , {
[junit4] 2> "name":"testCloudExamplePrompt",
[junit4] 2> "fromApi":"true",
[junit4] 2> "replicationFactor":"2",
[junit4] 2> "collection.configName":"testCloudExamplePrompt",
[junit4] 2> "numShards":"2",
[junit4] 2> "maxShardsPerNode":"4",
[junit4] 2> "stateFormat":"2",
[junit4] 2> "operation":"create"}
[junit4] 2> 1037411 INFO (OverseerThreadFactory-1817-thread-1-processing-n:localhost:38199_solr) [n:localhost:38199_solr ] o.a.s.c.CreateCollectionCmd Create collection testCloudExamplePrompt
[junit4] 2> 1037411 WARN (OverseerThreadFactory-1817-thread-1-processing-n:localhost:38199_solr) [n:localhost:38199_solr ] o.a.s.c.CreateCollectionCmd Specified replicationFactor of 2 on collection testCloudExamplePrompt is higher than or equal to the number of Solr instances currently live or live and part of your createNodeSet(1). It's unusual to run two replica of the same slice on the same Solr-instance.
[junit4] 2> 1037411 INFO (OverseerThreadFactory-1817-thread-1-processing-n:localhost:38199_solr) [n:localhost:38199_solr ] o.a.s.c.OverseerCollectionMessageHandler creating collections conf node /collections/testCloudExamplePrompt
[junit4] 2> 1037411 INFO (OverseerThreadFactory-1817-thread-1-processing-n:localhost:38199_solr) [n:localhost:38199_solr ] o.a.s.c.c.SolrZkClient makePath: /collections/testCloudExamplePrompt
[junit4] 2> 1037412 INFO (zkCallback-648-thread-1-processing-n:localhost:38199_solr) [n:localhost:38199_solr ] o.a.s.c.c.ZkStateReader A collections change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections], has occurred - updating...
[junit4] 2> 1037412 INFO (zkCallback-657-thread-1) [ ] o.a.s.c.c.ZkStateReader A collections change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections], has occurred - updating...
[junit4] 2> 1037414 INFO (OverseerStateUpdate-96500791569743875-localhost:38199_solr-n_0000000000) [n:localhost:38199_solr ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
[junit4] 2> "name":"testCloudExamplePrompt",
[junit4] 2> "fromApi":"true",
[junit4] 2> "replicationFactor":"2",
[junit4] 2> "collection.configName":"testCloudExamplePrompt",
[junit4] 2> "numShards":"2",
[junit4] 2> "maxShardsPerNode":"4",
[junit4] 2> "stateFormat":"2",
[junit4] 2> "operation":"create"} current state version: 0
[junit4] 2> 1037414 INFO (OverseerStateUpdate-96500791569743875-localhost:38199_solr-n_0000000000) [n:localhost:38199_solr ] o.a.s.c.o.ClusterStateMutator building a new cName: testCloudExamplePrompt
[junit4] 2> 1037414 INFO (OverseerStateUpdate-96500791569743875-localhost:38199_solr-n_0000000000) [n:localhost:38199_solr ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/testCloudExamplePrompt/state.json
[junit4] 2> 1037514 INFO (OverseerThreadFactory-1817-thread-1-processing-n:localhost:38199_solr) [n:localhost:38199_solr ] o.a.s.c.CreateCollectionCmd Creating SolrCores for new collection testCloudExamplePrompt, shardNames [shard1, shard2] , replicationFactor : 2
[junit4] 2> 1037514 INFO (OverseerThreadFactory-1817-thread-1-processing-n:localhost:38199_solr) [n:localhost:38199_solr ] o.a.s.c.CreateCollectionCmd Creating core testCloudExamplePrompt_shard2_replica1 as part of shard shard2 of collection testCloudExamplePrompt on localhost:38199_solr
[junit4] 2> 1037514 INFO (OverseerThreadFactory-1817-thread-1-processing-n:localhost:38199_solr) [n:localhost:38199_solr ] o.a.s.c.CreateCollectionCmd Creating core testCloudExamplePrompt_shard1_replica2 as part of shard shard1 of collection testCloudExamplePrompt on localhost:38199_solr
[junit4] 2> 1037515 INFO (OverseerThreadFactory-1817-thread-1-processing-n:localhost:38199_solr) [n:localhost:38199_solr ] o.a.s.c.CreateCollectionCmd Creating core testCloudExamplePrompt_shard1_replica1 as part of shard shard1 of collection testCloudExamplePrompt on localhost:38199_solr
[junit4] 2> 1037516 INFO (OverseerThreadFactory-1817-thread-1-processing-n:localhost:38199_solr) [n:localhost:38199_solr ] o.a.s.c.CreateCollectionCmd Creating core testCloudExamplePrompt_shard2_replica2 as part of shard shard2 of collection testCloudExamplePrompt on localhost:38199_solr
[junit4] 2> 1037519 INFO (qtp1124791031-3907) [n:localhost:38199_solr ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&collection.configName=testCloudExamplePrompt&name=testCloudExamplePrompt_shard1_replica2&action=CREATE&numShards=2&collection=testCloudExamplePrompt&shard=shard1&wt=javabin&version=2
[junit4] 2> 1037519 INFO (qtp1124791031-3918) [n:localhost:38199_solr ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&collection.configName=testCloudExamplePrompt&name=testCloudExamplePrompt_shard1_replica1&action=CREATE&numShards=2&collection=testCloudExamplePrompt&shard=shard1&wt=javabin&version=2
[junit4] 2> 1037519 INFO (qtp1124791031-3908) [n:localhost:38199_solr ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&collection.configName=testCloudExamplePrompt&name=testCloudExamplePrompt_shard2_replica2&action=CREATE&numShards=2&collection=testCloudExamplePrompt&shard=shard2&wt=javabin&version=2
[junit4] 2> 1037520 INFO (qtp1124791031-3908) [n:localhost:38199_solr ] o.a.s.c.CoreDescriptor Created CoreDescriptor: {name=testCloudExamplePrompt_shard2_replica2, collection.configName=testCloudExamplePrompt, config=solrconfig.xml, transient=false, schema=schema.xml, loadOnStartup=true, configSetProperties=configsetprops.json, collection=testCloudExamplePrompt, numShards=2, dataDir=data/, shard=shard2}
[junit4] 2> 1037520 INFO (qtp1124791031-3907) [n:localhost:38199_solr ] o.a.s.c.CoreDescriptor Created CoreDescriptor: {name=testCloudExamplePrompt_shard1_replica2, collection.configName=testCloudExamplePrompt, config=solrconfig.xml, transient=false, schema=schema.xml, loadOnStartup=true, configSetProperties=configsetprops.json, collection=testCloudExamplePrompt, numShards=2, dataDir=data/, shard=shard1}
[junit4] 2> 1037520 INFO (qtp1124791031-3918) [n:localhost:38199_solr ] o.a.s.c.CoreDescriptor Created CoreDescriptor: {name=testCloudExamplePrompt_shard1_replica1, collection.configName=testCloudExamplePrompt, config=solrconfig.xml, transient=false, schema=schema.xml, loadOnStartup=true, configSetProperties=configsetprops.json, collection=testCloudExamplePrompt, numShards=2, dataDir=data/, shard=shard1}
[junit4] 2> 1037520 INFO (qtp1124791031-3908) [n:localhost:38199_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica2] o.a.s.c.ZkController publishing state=down
[junit4] 2> 1037520 INFO (qtp1124791031-3907) [n:localhost:38199_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica2] o.a.s.c.ZkController publishing state=down
[junit4] 2> 1037520 INFO (qtp1124791031-3917) [n:localhost:38199_solr ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&collection.configName=testCloudExamplePrompt&name=testCloudExamplePrompt_shard2_replica1&action=CREATE&numShards=2&collection=testCloudExamplePrompt&shard=shard2&wt=javabin&version=2
[junit4] 2> 1037520 INFO (qtp1124791031-3918) [n:localhost:38199_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica1] o.a.s.c.ZkController publishing state=down
[junit4] 2> 1037520 INFO (qtp1124791031-3917) [n:localhost:38199_solr ] o.a.s.c.CoreDescriptor Created CoreDescriptor: {name=testCloudExamplePrompt_shard2_replica1, collection.configName=testCloudExamplePrompt, config=solrconfig.xml, transient=false, schema=schema.xml, loadOnStartup=true, configSetProperties=configsetprops.json, collection=testCloudExamplePrompt, numShards=2, dataDir=data/, shard=shard2}
[junit4] 2> 1037521 INFO (qtp1124791031-3908) [n:localhost:38199_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica2] o.a.s.c.ZkController Registering watch for collection testCloudExamplePrompt
[junit4] 2> 1037521 INFO (qtp1124791031-3917) [n:localhost:38199_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica1] o.a.s.c.ZkController publishing state=down
[junit4] 2> 1037521 INFO (qtp1124791031-3918) [n:localhost:38199_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica1] o.a.s.c.ZkController Registering watch for collection testCloudExamplePrompt
[junit4] 2> 1037521 INFO (qtp1124791031-3918) [n:localhost:38199_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica1] o.a.s.c.ZkController look for our core node name
[junit4] 2> 1037521 INFO (qtp1124791031-3907) [n:localhost:38199_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica2] o.a.s.c.ZkController Registering watch for collection testCloudExamplePrompt
[junit4] 2> 1037521 INFO (qtp1124791031-3907) [n:localhost:38199_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica2] o.a.s.c.ZkController look for our core node name
[junit4] 2> 1037521 INFO (qtp1124791031-3908) [n:localhost:38199_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica2] o.a.s.c.c.ZkStateReader Add data for [testCloudExamplePrompt] ver [0]
[junit4] 2> 1037521 INFO (qtp1124791031-3917) [n:localhost:38199_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica1] o.a.s.c.ZkController Registering watch for collection testCloudExamplePrompt
[junit4] 2> 1037521 INFO (qtp1124791031-3917) [n:localhost:38199_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica1] o.a.s.c.ZkController look for our core node name
[junit4] 2> 1037521 INFO (qtp1124791031-3908) [n:localhost:38199_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica2] o.a.s.c.ZkController look for our core node name
[junit4] 2> 1037522 INFO (OverseerStateUpdate-96500791569743875-localhost:38199_solr-n_0000000000) [n:localhost:38199_solr ] o.a.s.c.Overseer processMessage: queueSize: 3, message = {
[junit4] 2> "core":"testCloudExamplePrompt_shard2_replica2",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://localhost:38199/solr",
[junit4] 2> "node_name":"localhost:38199_solr",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"down",
[junit4] 2> "shard":"shard2",
[junit4] 2> "collection":"testCloudExamplePrompt",
[junit4] 2> "operation":"state"} current state version: 0
[junit4] 2> 1037522 INFO (OverseerStateUpdate-96500791569743875-localhost:38199_solr-n_0000000000) [n:localhost:38199_solr ] o.a.s.c.o.ReplicaMutator Update state numShards=2 message={
[junit4] 2> "core":"testCloudExamplePrompt_shard2_replica2",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://localhost:38199/solr",
[junit4] 2> "node_name":"localhost:38199_solr",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"down",
[junit4] 2> "shard":"shard2",
[junit4] 2> "collection":"testCloudExamplePrompt",
[junit4] 2> "operation":"state"}
[junit4] 2> 1037523 INFO (OverseerStateUpdate-96500791569743875-localhost:38199_solr-n_0000000000) [n:localhost:38199_solr ] o.a.s.c.Overseer processMessage: queueSize: 3, message = {
[junit4] 2> "core":"testCloudExamplePrompt_shard1_replica1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://localhost:38199/solr",
[junit4] 2> "node_name":"localhost:38199_solr",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"down",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"testCloudExamplePrompt",
[junit4] 2> "operation":"state"} current state version: 0
[junit4] 2> 1037523 INFO (OverseerStateUpdate-96500791569743875-localhost:38199_solr-n_0000000000) [n:localhost:38199_solr ] o.a.s.c.o.ReplicaMutator Update state numShards=2 message={
[junit4] 2> "core":"testCloudExamplePrompt_shard1_replica1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://localhost:38199/solr",
[junit4] 2> "node_name":"localhost:38199_solr",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"down",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"testCloudExamplePrompt",
[junit4] 2> "operation":"state"}
[junit4] 2> 1037524 INFO (OverseerStateUpdate-96500791569743875-localhost:38199_solr-n_0000000000) [n:localhost:38199_solr ] o.a.s.c.Overseer processMessage: queueSize: 2, message = {
[junit4] 2> "core":"testCloudExamplePrompt_shard1_replica2",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://localhost:38199/solr",
[junit4] 2> "node_name":"localhost:38199_solr",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"down",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"testCloudExamplePrompt",
[junit4] 2> "operation":"state"} current state version: 0
[junit4] 2> 1037524 INFO (OverseerStateUpdate-96500791569743875-localhost:38199_solr-n_0000000000) [n:localhost:38199_solr ] o.a.s.c.o.ReplicaMutator Update state numShards=2 message={
[junit4] 2> "core":"testCloudExamplePrompt_shard1_replica2",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://localhost:38199/solr",
[junit4] 2> "node_name":"localhost:38199_solr",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"down",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"testCloudExamplePrompt",
[junit4] 2> "operation":"state"}
[junit4] 2> 1037525 INFO (OverseerStateUpdate-96500791569743875-localhost:38199_solr-n_0000000000) [n:localhost:38199_solr ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
[junit4] 2> "core":"testCloudExamplePrompt_shard2_replica1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://localhost:38199/solr",
[junit4] 2> "node_name":"localhost:38199_solr",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"down",
[junit4] 2> "shard":"shard2",
[junit4] 2> "collection":"testCloudExamplePrompt",
[junit4] 2> "operation":"state"} current state version: 0
[junit4] 2> 1037525 INFO (OverseerStateUpdate-96500791569743875-localhost:38199_solr-n_0000000000) [n:localhost:38199_solr ] o.a.s.c.o.ReplicaMutator Update state numShards=2 message={
[junit4] 2> "core":"testCloudExamplePrompt_shard2_replica1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://localhost:38199/solr",
[junit4] 2> "node_name":"localhost:38199_solr",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"down",
[junit4] 2> "shard":"shard2",
[junit4] 2> "collection":"testCloudExamplePrompt",
[junit4] 2> "operation":"state"}
[junit4] 2> 1037626 INFO (OverseerStateUpdate-96500791569743875-localhost:38199_solr-n_0000000000) [n:localhost:38199_solr ] o.a.s.c.o.ZkStateWriter going to update_collection /collections/testCloudExamplePrompt/state.json version: 0
[junit4] 2> 1037626 INFO (zkCallback-648-thread-1-processing-n:localhost:38199_solr) [n:localhost:38199_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testCloudExamplePrompt/state.json] for collection [testCloudExamplePrompt] has occurred - updating... (live nodes size: [1])
[junit4] 2> 1037627 INFO (zkCallback-648-thread-1-processing-n:localhost:38199_solr) [n:localhost:38199_solr ] o.a.s.c.c.ZkStateReader Updating data for [testCloudExamplePrompt] from [0] to [1]
[junit4] 2> 1038521 INFO (qtp1124791031-3908) [n:localhost:38199_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica2] o.a.s.c.ZkController waiting to find shard id in clusterstate for testCloudExamplePrompt_shard2_replica2
[junit4] 2> 1038521 INFO (qtp1124791031-3917) [n:localhost:38199_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica1] o.a.s.c.ZkController waiting to find shard id in clusterstate for testCloudExamplePrompt_shard2_replica1
[junit4] 2> 1038522 INFO (qtp1124791031-3907) [n:localhost:38199_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica2] o.a.s.c.ZkController waiting to find shard id in clusterstate for testCloudExamplePrompt_shard1_replica2
[junit4] 2> 1038521 INFO (qtp1124791031-3908) [n:localhost:38199_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica2] o.a.s.c.ZkController Check for collection zkNode:testCloudExamplePrompt
[junit4] 2> 1038522 INFO (qtp1124791031-3907) [n:localhost:38199_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica2] o.a.s.c.ZkController Check for collection zkNode:testCloudExamplePrompt
[junit4] 2> 1038521 INFO (qtp1124791031-3918) [n:localhost:38199_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica1] o.a.s.c.ZkController waiting to find shard id in clusterstate for testCloudExamplePrompt_shard1_replica1
[junit4] 2> 1038522 INFO (qtp1124791031-3917) [n:localhost:38199_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica1] o.a.s.c.ZkController Check for collection zkNode:testCloudExamplePrompt
[junit4] 2> 1038522 INFO (qtp1124791031-3918) [n:localhost:38199_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica1] o.a.s.c.ZkController Check for collection zkNode:testCloudExamplePrompt
[junit4] 2> 1038522 INFO (qtp1124791031-3908) [n:localhost:38199_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica2] o.a.s.c.ZkController Collection zkNode exists
[junit4] 2> 1038522 INFO (qtp1124791031-3907) [n:localhost:38199_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica2] o.a.s.c.ZkController Collection zkNode exists
[junit4] 2> 1038522 INFO (qtp1124791031-3917) [n:localhost:38199_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica1] o.a.s.c.ZkController Collection zkNode exists
[junit4] 2> 1038522 INFO (qtp1124791031-3918) [n:localhost:38199_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica1] o.a.s.c.ZkController Collection zkNode exists
[junit4] 2> 1038522 INFO (qtp1124791031-3907) [n:localhost:38199_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica2] o.a.s.c.c.ZkStateReader Load collection config from: [/collections/testCloudExamplePrompt]
[junit4] 2> 1038522 INFO (qtp1124791031-3918) [n:localhost:38199_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica1] o.a.s.c.c.ZkStateReader Load collection config from: [/collections/testCloudExamplePrompt]
[junit4] 2> 1038522 INFO (qtp1124791031-3908) [n:localhost:38199_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica2] o.a.s.c.c.ZkStateReader Load collection config from: [/collections/testCloudExamplePrompt]
[junit4] 2> 1038522 INFO (qtp1124791031-3917) [n:localhost:38199_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica1] o.a.s.c.c.ZkStateReader Load collection config from: [/collections/testCloudExamplePrompt]
[junit4] 2> 1038523 INFO (qtp1124791031-3907) [n:localhost:38199_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica2] o.a.s.c.c.ZkStateReader path=[/collections/testCloudExamplePrompt] [configName]=[testCloudExamplePrompt] specified config exists in ZooKeeper
[junit4] 2> 1038523 INFO (qtp1124791031-3918) [n:localhost:38199_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica1] o.a.s.c.c.ZkStateReader path=[/collections/testCloudExamplePrompt] [configName]=[testCloudExamplePrompt] specified config exists in ZooKeeper
[junit4] 2> 1038523 INFO (qtp1124791031-3907) [n:localhost:38199_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica2] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.util.TestSolrCLIRunExample_BD79689EDF0D9636-001/tempDir-003/node1/testCloudExamplePrompt_shard1_replica2'
[junit4] 2> 1038523 INFO (qtp1124791031-3918) [n:localhost:38199_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica1] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.util.TestSolrCLIRunExample_BD79689EDF0D9636-001/tempDir-003/node1/testCloudExamplePrompt_shard1_replica1'
[junit4] 2> 1038523 INFO (qtp1124791031-3917) [n:localhost:38199_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica1] o.a.s.c.c.ZkStateReader path=[/collections/testCloudExamplePrompt] [configName]=[testCloudExamplePrompt] specified config exists in ZooKeeper
[junit4] 2> 1038523 INFO (qtp1124791031-3907) [n:localhost:38199_so
[...truncated too long message...]
t4] 2> at org.eclipse.jetty.server.handler.gzip.GzipHandler.handle(GzipHandler.java:399)
[junit4] 2> at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:134)
[junit4] 2> at org.eclipse.jetty.server.Server.handle(Server.java:518)
[junit4] 2> at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:308)
[junit4] 2> at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:244)
[junit4] 2> at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:273)
[junit4] 2> at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:95)
[junit4] 2> at org.eclipse.jetty.io.SelectChannelEndPoint$2.run(SelectChannelEndPoint.java:93)
[junit4] 2> at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.produceAndRun(ExecuteProduceConsume.java:246)
[junit4] 2> at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.run(ExecuteProduceConsume.java:156)
[junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:654)
[junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool$3.run(QueuedThreadPool.java:572)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2>
[junit4] 2> 1050849 INFO (Thread-1146) [n:localhost:38199_solr ] o.a.s.c.CachingDirectoryFactory Closing MockDirectoryFactory - 3 directories currently being tracked
[junit4] 2> 1050849 INFO (Thread-1146) [n:localhost:38199_solr ] o.a.s.c.CachingDirectoryFactory looking to close /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.util.TestSolrCLIRunExample_BD79689EDF0D9636-001/tempDir-003/node1/testCloudExamplePrompt_shard1_replica1/data/snapshot_metadata [CachedDir<<refCount=0;path=/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.util.TestSolrCLIRunExample_BD79689EDF0D9636-001/tempDir-003/node1/testCloudExamplePrompt_shard1_replica1/data/snapshot_metadata;done=false>>]
[junit4] 2> 1050849 INFO (Thread-1146) [n:localhost:38199_solr ] o.a.s.c.CachingDirectoryFactory Closing directory, CoreContainer#isShutdown=true
[junit4] 2> 1050849 INFO (Thread-1146) [n:localhost:38199_solr ] o.a.s.c.CachingDirectoryFactory Closing directory: /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.util.TestSolrCLIRunExample_BD79689EDF0D9636-001/tempDir-003/node1/testCloudExamplePrompt_shard1_replica1/data/snapshot_metadata
[junit4] 2> 1050849 INFO (Thread-1146) [n:localhost:38199_solr ] o.a.s.c.CachingDirectoryFactory looking to close /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.util.TestSolrCLIRunExample_BD79689EDF0D9636-001/tempDir-003/node1/testCloudExamplePrompt_shard1_replica1/data [CachedDir<<refCount=0;path=/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.util.TestSolrCLIRunExample_BD79689EDF0D9636-001/tempDir-003/node1/testCloudExamplePrompt_shard1_replica1/data;done=false>>]
[junit4] 2> 1050849 INFO (Thread-1146) [n:localhost:38199_solr ] o.a.s.c.CachingDirectoryFactory looking to close /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.util.TestSolrCLIRunExample_BD79689EDF0D9636-001/tempDir-003/node1/testCloudExamplePrompt_shard1_replica1/data/index [CachedDir<<refCount=0;path=/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.util.TestSolrCLIRunExample_BD79689EDF0D9636-001/tempDir-003/node1/testCloudExamplePrompt_shard1_replica1/data/index;done=false>>, CachedDir<<refCount=0;path=/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.util.TestSolrCLIRunExample_BD79689EDF0D9636-001/tempDir-003/node1/testCloudExamplePrompt_shard1_replica1/data;done=false>>]
[junit4] 2> 1050849 INFO (Thread-1146) [n:localhost:38199_solr ] o.a.s.c.CachingDirectoryFactory Closing directory, CoreContainer#isShutdown=true
[junit4] 2> 1050849 INFO (Thread-1146) [n:localhost:38199_solr ] o.a.s.c.CachingDirectoryFactory Closing directory: /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.util.TestSolrCLIRunExample_BD79689EDF0D9636-001/tempDir-003/node1/testCloudExamplePrompt_shard1_replica1/data/index
[junit4] 2> 1050849 INFO (Thread-1146) [n:localhost:38199_solr ] o.a.s.c.CachingDirectoryFactory Closing directory, CoreContainer#isShutdown=true
[junit4] 2> 1050849 INFO (Thread-1146) [n:localhost:38199_solr ] o.a.s.c.CachingDirectoryFactory Closing directory: /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.util.TestSolrCLIRunExample_BD79689EDF0D9636-001/tempDir-003/node1/testCloudExamplePrompt_shard1_replica1/data
[junit4] 2> 1050849 INFO (Thread-1146) [n:localhost:38199_solr ] o.a.s.c.CachingDirectoryFactory looking to close /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.util.TestSolrCLIRunExample_BD79689EDF0D9636-001/tempDir-003/node1/testCloudExamplePrompt_shard1_replica1/data []
[junit4] 2> 1050849 INFO (Thread-1146) [n:localhost:38199_solr ] o.a.s.c.CachingDirectoryFactory Removing directory after core close: /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.util.TestSolrCLIRunExample_BD79689EDF0D9636-001/tempDir-003/node1/testCloudExamplePrompt_shard1_replica1/data
[junit4] 2> 1050849 WARN (Thread-1146) [n:localhost:38199_solr ] o.a.s.c.SolrCore
[junit4] 2> org.apache.solr.core.SolrResourceNotFoundException: Can't find resource 'managed-schema' in classpath or '/configs/testCloudExamplePrompt', cwd=/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0
[junit4] 2> at org.apache.solr.cloud.ZkSolrResourceLoader.openResource(ZkSolrResourceLoader.java:130)
[junit4] 2> at org.apache.solr.schema.SchemaManager.getFreshManagedSchema(SchemaManager.java:429)
[junit4] 2> at org.apache.solr.core.SolrCore.lambda$getConfListener$6(SolrCore.java:2571)
[junit4] 2> at org.apache.solr.cloud.ZkController.lambda$fireEventListeners$3(ZkController.java:2443)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2> 1050850 INFO (Thread-1146) [n:localhost:38199_solr ] o.a.s.c.SolrCore core reload testCloudExamplePrompt_shard1_replica1
[junit4] 2> 1050850 WARN (Thread-1146) [n:localhost:38199_solr ] o.a.s.c.ZkController listener throws error
[junit4] 2> org.apache.solr.common.SolrException: No such core: testCloudExamplePrompt_shard1_replica1
[junit4] 2> at org.apache.solr.core.CoreContainer.reload(CoreContainer.java:933)
[junit4] 2> at org.apache.solr.core.SolrCore.lambda$getConfListener$6(SolrCore.java:2578)
[junit4] 2> at org.apache.solr.cloud.ZkController.lambda$fireEventListeners$3(ZkController.java:2443)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2> Aug 29, 2016 3:40:36 PM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
[junit4] 2> WARNING: Will linger awaiting termination of 1 leaked thread(s).
[junit4] 2> NOTE: test params are: codec=DummyCompressingStoredFields(storedFieldsFormat=CompressingStoredFieldsFormat(compressionMode=DUMMY, chunkSize=1578, maxDocsPerChunk=2, blockSize=462), termVectorsFormat=CompressingTermVectorsFormat(compressionMode=DUMMY, chunkSize=1578, blockSize=462)), sim=RandomSimilarity(queryNorm=false,coord=no): {}, locale=vi-VN, timezone=America/El_Salvador
[junit4] 2> NOTE: Linux 4.4.0-31-generic amd64/Oracle Corporation 1.8.0_102 (64-bit)/cpus=12,threads=1,free=306635328,total=508887040
[junit4] 2> NOTE: All tests run in this JVM: [ResourceLoaderTest, DistributedFacetPivotSmallAdvancedTest, PreAnalyzedFieldTest, TestJoin, TestPhraseSuggestions, SuggestComponentTest, DistribCursorPagingTest, CleanupOldIndexTest, TestOnReconnectListenerSupport, TestManagedStopFilterFactory, QueryElevationComponentTest, DocumentAnalysisRequestHandlerTest, TestIBSimilarityFactory, TestFoldingMultitermQuery, TestImplicitCoreProperties, CurrencyFieldOpenExchangeTest, CachingDirectoryFactoryTest, TestRandomFaceting, ConnectionReuseTest, TestMiniSolrCloudClusterBase, OutputWriterTest, TestFaceting, TestNamedUpdateProcessors, FileBasedSpellCheckerTest, DisMaxRequestHandlerTest, URLClassifyProcessorTest, TestLuceneMatchVersion, BigEndianAscendingWordSerializerTest, LeaderInitiatedRecoveryOnShardRestartTest, DeleteInactiveReplicaTest, TestClassicSimilarityFactory, ResponseLogComponentTest, TestAnalyzedSuggestions, TestPseudoReturnFields, TestPerFieldSimilarityClassic, OverseerModifyCollectionTest, TestLocalFSCloudBackupRestore, TestDefaultStatsCache, PingRequestHandlerTest, HdfsNNFailoverTest, TestCloudDeleteByQuery, TestRequestStatusCollectionAPI, TestFileDictionaryLookup, TestConfigSetsAPIExclusivity, TestOmitPositions, TestBackupRepositoryFactory, CacheHeaderTest, StandardRequestHandlerTest, TestBulkSchemaConcurrent, TestSolrCLIRunExample]
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestSolrCLIRunExample -Dtests.seed=BD79689EDF0D9636 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=vi-VN -Dtests.timezone=America/El_Salvador -Dtests.asserts=true -Dtests.file.encoding=ISO-8859-1
[junit4] ERROR 0.00s J0 | TestSolrCLIRunExample (suite) <<<
[junit4] > Throwable #1: java.lang.AssertionError: ObjectTracker found 4 object(s) that were not released!!! [MockDirectoryWrapper, SolrCore, MockDirectoryWrapper, MockDirectoryWrapper]
[junit4] > at __randomizedtesting.SeedInfo.seed([BD79689EDF0D9636]:0)
[junit4] > at org.apache.solr.SolrTestCaseJ4.teardownTestCases(SolrTestCaseJ4.java:258)
[junit4] > at java.lang.Thread.run(Thread.java:745)
[junit4] Completed [256/631 (1!)] on J0 in 16.95s, 3 tests, 1 failure, 1 skipped <<< FAILURES!
[...truncated 61329 lines...]
[JENKINS] Lucene-Solr-6.x-Linux (32bit/jdk1.8.0_102) - Build # 1619
- Still Unstable!
Posted by Policeman Jenkins Server <je...@thetaphi.de>.
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-6.x-Linux/1619/
Java: 32bit/jdk1.8.0_102 -client -XX:+UseG1GC
1 tests failed.
FAILED: org.apache.solr.cloud.LeaderFailoverAfterPartitionTest.test
Error Message:
org.apache.solr.client.solrj.SolrServerException: No live SolrServers available to handle this request:[http://127.0.0.1:43279/_vs/c8n_1x3_lf_shard1_replica3]
Stack Trace:
org.apache.solr.client.solrj.SolrServerException: org.apache.solr.client.solrj.SolrServerException: No live SolrServers available to handle this request:[http://127.0.0.1:43279/_vs/c8n_1x3_lf_shard1_replica3]
at __randomizedtesting.SeedInfo.seed([7AB93AB2E8D17591:F2ED0568462D1869]:0)
at org.apache.solr.client.solrj.impl.CloudSolrClient.directUpdate(CloudSolrClient.java:769)
at org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:1161)
at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:1050)
at org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:992)
at org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1219)
at org.apache.solr.cloud.HttpPartitionTest.sendDoc(HttpPartitionTest.java:592)
at org.apache.solr.cloud.HttpPartitionTest.sendDoc(HttpPartitionTest.java:578)
at org.apache.solr.cloud.LeaderFailoverAfterPartitionTest.testRf3WithLeaderFailover(LeaderFailoverAfterPartitionTest.java:174)
at org.apache.solr.cloud.LeaderFailoverAfterPartitionTest.test(LeaderFailoverAfterPartitionTest.java:55)
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:1764)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:871)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:907)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:921)
at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:992)
at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:967)
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:367)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:809)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:460)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:880)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:781)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:816)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:827)
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:367)
at java.lang.Thread.run(Thread.java:745)
Caused by: org.apache.solr.client.solrj.SolrServerException: No live SolrServers available to handle this request:[http://127.0.0.1:43279/_vs/c8n_1x3_lf_shard1_replica3]
at org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:384)
at org.apache.solr.client.solrj.impl.CloudSolrClient.directUpdate(CloudSolrClient.java:763)
... 49 more
Caused by: org.apache.solr.client.solrj.SolrServerException: Server refused connection at: http://127.0.0.1:43279/_vs/c8n_1x3_lf_shard1_replica3
at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:599)
at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:261)
at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:250)
at org.apache.solr.client.solrj.impl.LBHttpSolrClient.doRequest(LBHttpSolrClient.java:405)
at org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:357)
... 50 more
Caused by: java.net.ConnectException: Connection refused
at java.net.PlainSocketImpl.socketConnect(Native Method)
at java.net.AbstractPlainSocketImpl.doConnect(AbstractPlainSocketImpl.java:350)
at java.net.AbstractPlainSocketImpl.connectToAddress(AbstractPlainSocketImpl.java:206)
at java.net.AbstractPlainSocketImpl.connect(AbstractPlainSocketImpl.java:188)
at java.net.SocksSocketImpl.connect(SocksSocketImpl.java:392)
at java.net.Socket.connect(Socket.java:589)
at org.apache.http.conn.scheme.PlainSocketFactory.connectSocket(PlainSocketFactory.java:117)
at org.apache.http.impl.conn.DefaultClientConnectionOperator.openConnection(DefaultClientConnectionOperator.java:177)
at org.apache.http.impl.conn.ManagedClientConnectionImpl.open(ManagedClientConnectionImpl.java:304)
at org.apache.http.impl.client.DefaultRequestDirector.tryConnect(DefaultRequestDirector.java:611)
at org.apache.http.impl.client.DefaultRequestDirector.execute(DefaultRequestDirector.java:446)
at org.apache.http.impl.client.AbstractHttpClient.doExecute(AbstractHttpClient.java:882)
at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:82)
at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:107)
at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:55)
at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:497)
... 54 more
Build Log:
[...truncated 12122 lines...]
[junit4] Suite: org.apache.solr.cloud.LeaderFailoverAfterPartitionTest
[junit4] 2> Creating dataDir: /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.LeaderFailoverAfterPartitionTest_7AB93AB2E8D17591-001/init-core-data-001
[junit4] 2> 1765809 INFO (SUITE-LeaderFailoverAfterPartitionTest-seed#[7AB93AB2E8D17591]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.SolrTestCaseJ4$SuppressSSL(bugUrl=https://issues.apache.org/jira/browse/SOLR-5776)
[junit4] 2> 1765809 INFO (SUITE-LeaderFailoverAfterPartitionTest-seed#[7AB93AB2E8D17591]-worker) [ ] o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /_vs/
[junit4] 2> 1765811 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[7AB93AB2E8D17591]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 1765812 INFO (Thread-3319) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 1765812 INFO (Thread-3319) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 1765912 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[7AB93AB2E8D17591]) [ ] o.a.s.c.ZkTestServer start zk server on port:32979
[junit4] 2> 1765913 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[7AB93AB2E8D17591]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 1765913 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[7AB93AB2E8D17591]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1765915 INFO (zkCallback-2747-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@157d12c name:ZooKeeperConnection Watcher:127.0.0.1:32979 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1765915 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[7AB93AB2E8D17591]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1765916 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[7AB93AB2E8D17591]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 1765916 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[7AB93AB2E8D17591]) [ ] o.a.s.c.c.SolrZkClient makePath: /solr
[junit4] 2> 1765917 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[7AB93AB2E8D17591]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 1765917 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[7AB93AB2E8D17591]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1765918 INFO (zkCallback-2748-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@1f9c4b9 name:ZooKeeperConnection Watcher:127.0.0.1:32979/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1765918 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[7AB93AB2E8D17591]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1765919 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[7AB93AB2E8D17591]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 1765919 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[7AB93AB2E8D17591]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/collection1
[junit4] 2> 1765920 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[7AB93AB2E8D17591]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/collection1/shards
[junit4] 2> 1765921 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[7AB93AB2E8D17591]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection
[junit4] 2> 1765921 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[7AB93AB2E8D17591]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/shards
[junit4] 2> 1765922 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[7AB93AB2E8D17591]) [ ] o.a.s.c.AbstractZkTestCase put /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml to /configs/conf1/solrconfig.xml
[junit4] 2> 1765922 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[7AB93AB2E8D17591]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.xml
[junit4] 2> 1765925 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[7AB93AB2E8D17591]) [ ] o.a.s.c.AbstractZkTestCase put /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/core/src/test-files/solr/collection1/conf/schema.xml to /configs/conf1/schema.xml
[junit4] 2> 1765925 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[7AB93AB2E8D17591]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/schema.xml
[junit4] 2> 1765926 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[7AB93AB2E8D17591]) [ ] o.a.s.c.AbstractZkTestCase put /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml to /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 1765927 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[7AB93AB2E8D17591]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 1765927 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[7AB93AB2E8D17591]) [ ] o.a.s.c.AbstractZkTestCase put /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/core/src/test-files/solr/collection1/conf/stopwords.txt to /configs/conf1/stopwords.txt
[junit4] 2> 1765928 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[7AB93AB2E8D17591]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/stopwords.txt
[junit4] 2> 1765929 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[7AB93AB2E8D17591]) [ ] o.a.s.c.AbstractZkTestCase put /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/core/src/test-files/solr/collection1/conf/protwords.txt to /configs/conf1/protwords.txt
[junit4] 2> 1765929 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[7AB93AB2E8D17591]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/protwords.txt
[junit4] 2> 1765930 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[7AB93AB2E8D17591]) [ ] o.a.s.c.AbstractZkTestCase put /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/core/src/test-files/solr/collection1/conf/currency.xml to /configs/conf1/currency.xml
[junit4] 2> 1765930 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[7AB93AB2E8D17591]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/currency.xml
[junit4] 2> 1765931 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[7AB93AB2E8D17591]) [ ] o.a.s.c.AbstractZkTestCase put /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml to /configs/conf1/enumsConfig.xml
[junit4] 2> 1765931 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[7AB93AB2E8D17591]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/enumsConfig.xml
[junit4] 2> 1765932 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[7AB93AB2E8D17591]) [ ] o.a.s.c.AbstractZkTestCase put /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf1/open-exchange-rates.json
[junit4] 2> 1765932 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[7AB93AB2E8D17591]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/open-exchange-rates.json
[junit4] 2> 1765933 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[7AB93AB2E8D17591]) [ ] o.a.s.c.AbstractZkTestCase put /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 1765933 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[7AB93AB2E8D17591]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 1765934 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[7AB93AB2E8D17591]) [ ] o.a.s.c.AbstractZkTestCase put /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf1/old_synonyms.txt
[junit4] 2> 1765934 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[7AB93AB2E8D17591]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/old_synonyms.txt
[junit4] 2> 1765935 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[7AB93AB2E8D17591]) [ ] o.a.s.c.AbstractZkTestCase put /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/core/src/test-files/solr/collection1/conf/synonyms.txt to /configs/conf1/synonyms.txt
[junit4] 2> 1765935 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[7AB93AB2E8D17591]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/synonyms.txt
[junit4] 2> 1766020 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[7AB93AB2E8D17591]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.LeaderFailoverAfterPartitionTest_7AB93AB2E8D17591-001/control-001/cores/collection1
[junit4] 2> 1766022 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[7AB93AB2E8D17591]) [ ] o.e.j.s.Server jetty-9.3.8.v20160314
[junit4] 2> 1766023 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[7AB93AB2E8D17591]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@e8ae3b{/_vs,null,AVAILABLE}
[junit4] 2> 1766024 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[7AB93AB2E8D17591]) [ ] o.e.j.s.ServerConnector Started ServerConnector@99babc{HTTP/1.1,[http/1.1]}{127.0.0.1:37797}
[junit4] 2> 1766024 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[7AB93AB2E8D17591]) [ ] o.e.j.s.Server Started @1767264ms
[junit4] 2> 1766024 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[7AB93AB2E8D17591]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.LeaderFailoverAfterPartitionTest_7AB93AB2E8D17591-001/tempDir-001/control/data, hostContext=/_vs, hostPort=43279, coreRootDirectory=/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.LeaderFailoverAfterPartitionTest_7AB93AB2E8D17591-001/control-001/cores}
[junit4] 2> 1766025 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[7AB93AB2E8D17591]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@1d16e93
[junit4] 2> 1766025 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[7AB93AB2E8D17591]) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.LeaderFailoverAfterPartitionTest_7AB93AB2E8D17591-001/control-001'
[junit4] 2> 1766025 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[7AB93AB2E8D17591]) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 1766025 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[7AB93AB2E8D17591]) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
[junit4] 2> 1766025 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[7AB93AB2E8D17591]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 1766026 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[7AB93AB2E8D17591]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1766027 INFO (zkCallback-2749-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@790d94 name:ZooKeeperConnection Watcher:127.0.0.1:32979/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1766027 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[7AB93AB2E8D17591]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1766027 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[7AB93AB2E8D17591]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 1766028 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[7AB93AB2E8D17591]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper)
[junit4] 2> 1766028 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[7AB93AB2E8D17591]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.LeaderFailoverAfterPartitionTest_7AB93AB2E8D17591-001/control-001/solr.xml
[junit4] 2> 1766036 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[7AB93AB2E8D17591]) [ ] o.a.s.c.CorePropertiesLocator Config-defined core root directory: /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.LeaderFailoverAfterPartitionTest_7AB93AB2E8D17591-001/control-001/cores
[junit4] 2> 1766036 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[7AB93AB2E8D17591]) [ ] o.a.s.c.CoreContainer New CoreContainer 32917697
[junit4] 2> 1766036 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[7AB93AB2E8D17591]) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.LeaderFailoverAfterPartitionTest_7AB93AB2E8D17591-001/control-001]
[junit4] 2> 1766037 WARN (TEST-LeaderFailoverAfterPartitionTest.test-seed#[7AB93AB2E8D17591]) [ ] o.a.s.c.CoreContainer Couldn't add files from /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.LeaderFailoverAfterPartitionTest_7AB93AB2E8D17591-001/control-001/lib to classpath: /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.LeaderFailoverAfterPartitionTest_7AB93AB2E8D17591-001/control-001/lib
[junit4] 2> 1766037 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[7AB93AB2E8D17591]) [ ] o.a.s.h.c.HttpShardHandlerFactory created with socketTimeout : 90000,urlScheme : ,connTimeout : 15000,maxConnectionsPerHost : 20,maxConnections : 10000,corePoolSize : 0,maximumPoolSize : 2147483647,maxThreadIdleTime : 5,sizeOfQueue : -1,fairnessPolicy : false,useRetries : false,connectionsEvictorSleepDelay : 5000,maxConnectionIdleTime : 40000,
[junit4] 2> 1766038 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[7AB93AB2E8D17591]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 1766039 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[7AB93AB2E8D17591]) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 1766039 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[7AB93AB2E8D17591]) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 1766039 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[7AB93AB2E8D17591]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:32979/solr
[junit4] 2> 1766039 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[7AB93AB2E8D17591]) [ ] o.a.s.c.ZkController zkHost includes chroot
[junit4] 2> 1766039 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[7AB93AB2E8D17591]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 1766039 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[7AB93AB2E8D17591]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1766041 INFO (zkCallback-2752-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@1952f47 name:ZooKeeperConnection Watcher:127.0.0.1:32979 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1766041 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[7AB93AB2E8D17591]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1766041 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[7AB93AB2E8D17591]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 1766042 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[7AB93AB2E8D17591]) [n:127.0.0.1:43279__vs ] o.a.s.c.ZkController Added new OnReconnect listener org.apache.solr.cloud.ZkController$$Lambda$11/21484391@463ca8
[junit4] 2> 1766042 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[7AB93AB2E8D17591]) [n:127.0.0.1:43279__vs ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1766043 INFO (zkCallback-2753-thread-1-processing-n:127.0.0.1:43279__vs) [n:127.0.0.1:43279__vs ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@906c1b name:ZooKeeperConnection Watcher:127.0.0.1:32979/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1766043 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[7AB93AB2E8D17591]) [n:127.0.0.1:43279__vs ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1766046 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[7AB93AB2E8D17591]) [n:127.0.0.1:43279__vs ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue
[junit4] 2> 1766047 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[7AB93AB2E8D17591]) [n:127.0.0.1:43279__vs ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-queue-work
[junit4] 2> 1766048 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[7AB93AB2E8D17591]) [n:127.0.0.1:43279__vs ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-running
[junit4] 2> 1766049 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[7AB93AB2E8D17591]) [n:127.0.0.1:43279__vs ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-completed
[junit4] 2> 1766050 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[7AB93AB2E8D17591]) [n:127.0.0.1:43279__vs ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-failure
[junit4] 2> 1766051 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[7AB93AB2E8D17591]) [n:127.0.0.1:43279__vs ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
[junit4] 2> 1766052 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[7AB93AB2E8D17591]) [n:127.0.0.1:43279__vs ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
[junit4] 2> 1766053 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[7AB93AB2E8D17591]) [n:127.0.0.1:43279__vs ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
[junit4] 2> 1766053 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[7AB93AB2E8D17591]) [n:127.0.0.1:43279__vs ] o.a.s.c.c.SolrZkClient makePath: /security.json
[junit4] 2> 1766054 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[7AB93AB2E8D17591]) [n:127.0.0.1:43279__vs ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 1766054 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[7AB93AB2E8D17591]) [n:127.0.0.1:43279__vs ] o.a.s.c.c.ZkStateReader Loaded empty cluster properties
[junit4] 2> 1766054 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[7AB93AB2E8D17591]) [n:127.0.0.1:43279__vs ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0)
[junit4] 2> 1766057 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[7AB93AB2E8D17591]) [n:127.0.0.1:43279__vs ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect
[junit4] 2> 1766057 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[7AB93AB2E8D17591]) [n:127.0.0.1:43279__vs ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/election
[junit4] 2> 1766058 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[7AB93AB2E8D17591]) [n:127.0.0.1:43279__vs ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 1766059 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[7AB93AB2E8D17591]) [n:127.0.0.1:43279__vs ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/96501417727557636-127.0.0.1:43279__vs-n_0000000000
[junit4] 2> 1766059 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[7AB93AB2E8D17591]) [n:127.0.0.1:43279__vs ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:43279__vs
[junit4] 2> 1766059 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[7AB93AB2E8D17591]) [n:127.0.0.1:43279__vs ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/leader
[junit4] 2> 1766061 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[7AB93AB2E8D17591]) [n:127.0.0.1:43279__vs ] o.a.s.c.Overseer Overseer (id=96501417727557636-127.0.0.1:43279__vs-n_0000000000) starting
[junit4] 2> 1766062 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[7AB93AB2E8D17591]) [n:127.0.0.1:43279__vs ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue-work
[junit4] 2> 1766065 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[7AB93AB2E8D17591]) [n:127.0.0.1:43279__vs ] o.a.s.c.OverseerAutoReplicaFailoverThread Starting OverseerAutoReplicaFailoverThread autoReplicaFailoverWorkLoopDelay=10000 autoReplicaFailoverWaitAfterExpiration=10000 autoReplicaFailoverBadNodeExpiration=60000
[junit4] 2> 1766065 INFO (OverseerCollectionConfigSetProcessor-96501417727557636-127.0.0.1:43279__vs-n_0000000000) [n:127.0.0.1:43279__vs ] o.a.s.c.OverseerTaskProcessor Process current queue of overseer operations
[junit4] 2> 1766066 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[7AB93AB2E8D17591]) [n:127.0.0.1:43279__vs ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:43279__vs
[junit4] 2> 1766066 INFO (OverseerStateUpdate-96501417727557636-127.0.0.1:43279__vs-n_0000000000) [n:127.0.0.1:43279__vs ] o.a.s.c.Overseer Starting to work on the main queue
[junit4] 2> 1766066 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[7AB93AB2E8D17591]) [n:127.0.0.1:43279__vs ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/127.0.0.1:43279__vs
[junit4] 2> 1766066 INFO (OverseerStateUpdate-96501417727557636-127.0.0.1:43279__vs-n_0000000000) [n:127.0.0.1:43279__vs ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0)
[junit4] 2> 1766067 INFO (zkCallback-2753-thread-1-processing-n:127.0.0.1:43279__vs) [n:127.0.0.1:43279__vs ] o.a.s.c.c.ZkStateReader A live node change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes], has occurred - updating... (live nodes size: [0])
[junit4] 2> 1766067 INFO (zkCallback-2753-thread-1-processing-n:127.0.0.1:43279__vs) [n:127.0.0.1:43279__vs ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1766132 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[7AB93AB2E8D17591]) [n:127.0.0.1:43279__vs ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module.
[junit4] 2> 1766132 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[7AB93AB2E8D17591]) [n:127.0.0.1:43279__vs ] o.a.s.c.CoreContainer No authentication plugin used.
[junit4] 2> 1766133 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[7AB93AB2E8D17591]) [n:127.0.0.1:43279__vs ] o.a.s.c.CorePropertiesLocator Looking for core definitions underneath /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.LeaderFailoverAfterPartitionTest_7AB93AB2E8D17591-001/control-001/cores
[junit4] 2> 1766134 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[7AB93AB2E8D17591]) [n:127.0.0.1:43279__vs ] o.a.s.c.CoreDescriptor Created CoreDescriptor: {name=collection1, config=solrconfig.xml, transient=false, schema=schema.xml, loadOnStartup=true, configSetProperties=configsetprops.json, collection=control_collection, coreNodeName=, dataDir=data/, shard=}
[junit4] 2> 1766134 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[7AB93AB2E8D17591]) [n:127.0.0.1:43279__vs ] o.a.s.c.CorePropertiesLocator Found core collection1 in /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.LeaderFailoverAfterPartitionTest_7AB93AB2E8D17591-001/control-001/cores/collection1
[junit4] 2> 1766134 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[7AB93AB2E8D17591]) [n:127.0.0.1:43279__vs ] o.a.s.c.CorePropertiesLocator Found 1 core definitions
[junit4] 2> 1766135 INFO (coreLoadExecutor-7753-thread-1-processing-n:127.0.0.1:43279__vs) [n:127.0.0.1:43279__vs c:control_collection x:collection1] o.a.s.c.ZkController publishing state=down
[junit4] 2> 1766135 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[7AB93AB2E8D17591]) [n:127.0.0.1:43279__vs ] o.a.s.s.SolrDispatchFilter user.dir=/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J2
[junit4] 2> 1766135 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[7AB93AB2E8D17591]) [n:127.0.0.1:43279__vs ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done
[junit4] 2> 1766135 INFO (coreLoadExecutor-7753-thread-1-processing-n:127.0.0.1:43279__vs) [n:127.0.0.1:43279__vs c:control_collection x:collection1] o.a.s.c.ZkController numShards not found on descriptor - reading it from system property
[junit4] 2> 1766136 INFO (coreLoadExecutor-7753-thread-1-processing-n:127.0.0.1:43279__vs) [n:127.0.0.1:43279__vs c:control_collection x:collection1] o.a.s.c.ZkController Collection control_collection not visible yet, but flagging it so a watch is registered when it becomes visible
[junit4] 2> 1766136 INFO (OverseerStateUpdate-96501417727557636-127.0.0.1:43279__vs-n_0000000000) [n:127.0.0.1:43279__vs ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:43279/_vs",
[junit4] 2> "node_name":"127.0.0.1:43279__vs",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"down",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"control_collection",
[junit4] 2> "operation":"state"} current state version: 0
[junit4] 2> 1766136 INFO (OverseerStateUpdate-96501417727557636-127.0.0.1:43279__vs-n_0000000000) [n:127.0.0.1:43279__vs ] o.a.s.c.o.ReplicaMutator Update state numShards=1 message={
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:43279/_vs",
[junit4] 2> "node_name":"127.0.0.1:43279__vs",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"down",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"control_collection",
[junit4] 2> "operation":"state"}
[junit4] 2> 1766136 INFO (coreLoadExecutor-7753-thread-1-processing-n:127.0.0.1:43279__vs) [n:127.0.0.1:43279__vs c:control_collection x:collection1] o.a.s.c.c.ZkStateReader Deleting data for [control_collection]
[junit4] 2> 1766137 INFO (OverseerStateUpdate-96501417727557636-127.0.0.1:43279__vs-n_0000000000) [n:127.0.0.1:43279__vs ] o.a.s.c.o.ClusterStateMutator building a new cName: control_collection
[junit4] 2> 1766137 INFO (OverseerStateUpdate-96501417727557636-127.0.0.1:43279__vs-n_0000000000) [n:127.0.0.1:43279__vs ] o.a.s.c.o.ReplicaMutator Assigning new node to shard shard=shard1
[junit4] 2> 1766137 INFO (coreLoadExecutor-7753-thread-1-processing-n:127.0.0.1:43279__vs) [n:127.0.0.1:43279__vs c:control_collection x:collection1] o.a.s.c.ZkController look for our core node name
[junit4] 2> 1766138 INFO (zkCallback-2753-thread-1-processing-n:127.0.0.1:43279__vs) [n:127.0.0.1:43279__vs ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json], has occurred - updating... (live nodes size: [1])
[junit4] 2> 1767138 INFO (coreLoadExecutor-7753-thread-1-processing-n:127.0.0.1:43279__vs) [n:127.0.0.1:43279__vs c:control_collection x:collection1] o.a.s.c.ZkController waiting to find shard id in clusterstate for collection1
[junit4] 2> 1767138 INFO (coreLoadExecutor-7753-thread-1-processing-n:127.0.0.1:43279__vs) [n:127.0.0.1:43279__vs c:control_collection x:collection1] o.a.s.c.ZkController Check for collection zkNode:control_collection
[junit4] 2> 1767138 INFO (coreLoadExecutor-7753-thread-1-processing-n:127.0.0.1:43279__vs) [n:127.0.0.1:43279__vs c:control_collection x:collection1] o.a.s.c.ZkController Collection zkNode exists
[junit4] 2> 1767138 INFO (coreLoadExecutor-7753-thread-1-processing-n:127.0.0.1:43279__vs) [n:127.0.0.1:43279__vs c:control_collection x:collection1] o.a.s.c.c.ZkStateReader Load collection config from: [/collections/control_collection]
[junit4] 2> 1767139 INFO (coreLoadExecutor-7753-thread-1-processing-n:127.0.0.1:43279__vs) [n:127.0.0.1:43279__vs c:control_collection x:collection1] o.a.s.c.c.ZkStateReader path=[/collections/control_collection] [configName]=[conf1] specified config exists in ZooKeeper
[junit4] 2> 1767139 INFO (coreLoadExecutor-7753-thread-1-processing-n:127.0.0.1:43279__vs) [n:127.0.0.1:43279__vs c:control_collection x:collection1] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.LeaderFailoverAfterPartitionTest_7AB93AB2E8D17591-001/control-001/cores/collection1'
[junit4] 2> 1767139 INFO (coreLoadExecutor-7753-thread-1-processing-n:127.0.0.1:43279__vs) [n:127.0.0.1:43279__vs c:control_collection x:collection1] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 1767139 INFO (coreLoadExecutor-7753-thread-1-processing-n:127.0.0.1:43279__vs) [n:127.0.0.1:43279__vs c:control_collection x:collection1] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
[junit4] 2> 1767141 INFO (coreLoadExecutor-7753-thread-1-processing-n:127.0.0.1:43279__vs) [n:127.0.0.1:43279__vs c:control_collection x:collection1] o.a.s.c.Config loaded config solrconfig.xml with version 0
[junit4] 2> 1767151 INFO (coreLoadExecutor-7753-thread-1-processing-n:127.0.0.1:43279__vs) [n:127.0.0.1:43279__vs c:control_collection x:collection1] o.a.s.c.SolrConfig current version of requestparams : -1
[junit4] 2> 1767155 WARN (coreLoadExecutor-7753-thread-1-processing-n:127.0.0.1:43279__vs) [n:127.0.0.1:43279__vs c:control_collection x:collection1] o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, use <mergePolicyFactory> instead.
[junit4] 2> 1767156 INFO (coreLoadExecutor-7753-thread-1-processing-n:127.0.0.1:43279__vs) [n:127.0.0.1:43279__vs c:control_collection x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.3.0
[junit4] 2> 1767169 INFO (coreLoadExecutor-7753-thread-1-processing-n:127.0.0.1:43279__vs) [n:127.0.0.1:43279__vs c:control_collection x:collection1] o.a.s.c.SolrConfig Loaded SolrConfig: solrconfig.xml
[junit4] 2> 1767188 INFO (coreLoadExecutor-7753-thread-1-processing-n:127.0.0.1:43279__vs) [n:127.0.0.1:43279__vs c:control_collection x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 1767310 INFO (coreLoadExecutor-7753-thread-1-processing-n:127.0.0.1:43279__vs) [n:127.0.0.1:43279__vs c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 1767318 INFO (coreLoadExecutor-7753-thread-1-processing-n:127.0.0.1:43279__vs) [n:127.0.0.1:43279__vs c:control_collection x:collection1] o.a.s.s.IndexSchema default search field in schema is text
[junit4] 2> 1767319 INFO (coreLoadExecutor-7753-thread-1-processing-n:127.0.0.1:43279__vs) [n:127.0.0.1:43279__vs c:control_collection x:collection1] o.a.s.s.IndexSchema unique key field: id
[junit4] 2> 1767331 INFO (coreLoadExecutor-7753-thread-1-processing-n:127.0.0.1:43279__vs) [n:127.0.0.1:43279__vs c:control_collection x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
[junit4] 2> 1767333 INFO (coreLoadExecutor-7753-thread-1-processing-n:127.0.0.1:43279__vs) [n:127.0.0.1:43279__vs c:control_collection x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
[junit4] 2> 1767335 INFO (coreLoadExecutor-7753-thread-1-processing-n:127.0.0.1:43279__vs) [n:127.0.0.1:43279__vs c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 1767336 INFO (coreLoadExecutor-7753-thread-1-processing-n:127.0.0.1:43279__vs) [n:127.0.0.1:43279__vs c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 1767337 INFO (coreLoadExecutor-7753-thread-1-processing-n:127.0.0.1:43279__vs) [n:127.0.0.1:43279__vs c:control_collection x:collection1] o.a.s.c.ConfigSetProperties Did not find ConfigSet properties, assuming default properties: Can't find resource 'configsetprops.json' in classpath or '/configs/conf1', cwd=/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J2
[junit4] 2> 1767337 INFO (coreLoadExecutor-7753-thread-1-processing-n:127.0.0.1:43279__vs) [n:127.0.0.1:43279__vs c:control_collection x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from collection control_collection
[junit4] 2> 1767337 INFO (coreLoadExecutor-7753-thread-1-processing-n:127.0.0.1:43279__vs) [n:127.0.0.1:43279__vs c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 1767338 INFO (coreLoadExecutor-7753-thread-1-processing-n:127.0.0.1:43279__vs) [n:127.0.0.1:43279__vs c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.LeaderFailoverAfterPartitionTest_7AB93AB2E8D17591-001/control-001/cores/collection1], dataDir=[/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.LeaderFailoverAfterPartitionTest_7AB93AB2E8D17591-001/control-001/cores/collection1/data/]
[junit4] 2> 1767338 INFO (coreLoadExecutor-7753-thread-1-processing-n:127.0.0.1:43279__vs) [n:127.0.0.1:43279__vs c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@1dd8e44
[junit4] 2> 1767339 INFO (coreLoadExecutor-7753-thread-1-processing-n:127.0.0.1:43279__vs) [n:127.0.0.1:43279__vs c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.LeaderFailoverAfterPartitionTest_7AB93AB2E8D17591-001/control-001/cores/collection1/data/snapshot_metadata
[junit4] 2> 1767339 INFO (coreLoadExecutor-7753-thread-1-processing-n:127.0.0.1:43279__vs) [n:127.0.0.1:43279__vs c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.s.SolrSnapshotMetaDataManager Loading from snapshot metadata file...
[junit4] 2> 1767339 INFO (coreLoadExecutor-7753-thread-1-processing-n:127.0.0.1:43279__vs) [n:127.0.0.1:43279__vs c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.LeaderFailoverAfterPartitionTest_7AB93AB2E8D17591-001/control-001/cores/collection1/data
[junit4] 2> 1767339 INFO (coreLoadExecutor-7753-thread-1-processing-n:127.0.0.1:43279__vs) [n:127.0.0.1:43279__vs c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore New index directory detected: old=null new=/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.LeaderFailoverAfterPartitionTest_7AB93AB2E8D17591-001/control-001/cores/collection1/data/index/
[junit4] 2> 1767339 WARN (coreLoadExecutor-7753-thread-1-processing-n:127.0.0.1:43279__vs) [n:127.0.0.1:43279__vs c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore [collection1] Solr index directory '/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.LeaderFailoverAfterPartitionTest_7AB93AB2E8D17591-001/control-001/cores/collection1/data/index' doesn't exist. Creating new index...
[junit4] 2> 1767340 INFO (coreLoadExecutor-7753-thread-1-processing-n:127.0.0.1:43279__vs) [n:127.0.0.1:43279__vs c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.LeaderFailoverAfterPartitionTest_7AB93AB2E8D17591-001/control-001/cores/collection1/data/index
[junit4] 2> 1767341 INFO (coreLoadExecutor-7753-thread-1-processing-n:127.0.0.1:43279__vs) [n:127.0.0.1:43279__vs c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=10, maxMergeAtOnceExplicit=41, maxMergedSegmentMB=61.4560546875, floorSegmentMB=1.9892578125, forceMergeDeletesPctAllowed=21.91333066360937, segmentsPerTier=47.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.6699276671546628
[junit4] 2> 1767342 INFO (coreLoadExecutor-7753-thread-1-processing-n:127.0.0.1:43279__vs) [n:127.0.0.1:43279__vs c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@4d279b lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1c3802),segFN=segments_1,generation=1}
[junit4] 2> 1767342 INFO (coreLoadExecutor-7753-thread-1-processing-n:127.0.0.1:43279__vs) [n:127.0.0.1:43279__vs c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1
[junit4] 2> 1767342 INFO (OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:43279__vs c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore Looking for old index directories to cleanup for core collection1 in /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.LeaderFailoverAfterPartitionTest_7AB93AB2E8D17591-001/control-001/cores/collection1/data/
[junit4] 2> 1767342 WARN (OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:43279__vs c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.DirectoryFactory /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.LeaderFailoverAfterPartitionTest_7AB93AB2E8D17591-001/control-001/cores/collection1/data/ does not point to a valid data directory; skipping clean-up of old index directories.
[junit4] 2> 1767346 INFO (coreLoadExecutor-7753-thread-1-processing-n:127.0.0.1:43279__vs) [n:127.0.0.1:43279__vs c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "nodistrib"
[junit4] 2> 1767346 INFO (coreLoadExecutor-7753-thread-1-processing-n:127.0.0.1:43279__vs) [n:127.0.0.1:43279__vs c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "dedupe"
[junit4] 2> 1767347 INFO (coreLoadExecutor-7753-thread-1-processing-n:127.0.0.1:43279__vs) [n:127.0.0.1:43279__vs c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 1767347 INFO (coreLoadExecutor-7753-thread-1-processing-n:127.0.0.1:43279__vs) [n:127.0.0.1:43279__vs c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "stored_sig"
[junit4] 2> 1767347 INFO (coreLoadExecutor-7753-thread-1-processing-n:127.0.0.1:43279__vs) [n:127.0.0.1:43279__vs c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 1767347 INFO (coreLoadExecutor-7753-thread-1-processing-n:127.0.0.1:43279__vs) [n:127.0.0.1:43279__vs c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 1767347 INFO (coreLoadExecutor-7753-thread-1-processing-n:127.0.0.1:43279__vs) [n:127.0.0.1:43279__vs c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 1767347 INFO (coreLoadExecutor-7753-thread-1-processing-n:127.0.0.1:43279__vs) [n:127.0.0.1:43279__vs c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 1767347 INFO (coreLoadExecutor-7753-thread-1-processing-n:127.0.0.1:43279__vs) [n:127.0.0.1:43279__vs c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 1767347 WARN (coreLoadExecutor-7753-thread-1-processing-n:127.0.0.1:43279__vs) [n:127.0.0.1:43279__vs c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type = requestHandler,name = /dump,class = DumpRequestHandler,args = {defaults={a=A,b=B}}}
[junit4] 2> 1767349 INFO (coreLoadExecutor-7753-thread-1-processing-n:127.0.0.1:43279__vs) [n:127.0.0.1:43279__vs c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 1767351 INFO (coreLoadExecutor-7753-thread-1-processing-n:127.0.0.1:43279__vs) [n:127.0.0.1:43279__vs c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 1767353 INFO (coreLoadExecutor-7753-thread-1-processing-n:127.0.0.1:43279__vs) [n:127.0.0.1:43279__vs c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 1767354 INFO (coreLoadExecutor-7753-thread-1-processing-n:127.0.0.1:43279__vs) [n:127.0.0.1:43279__vs c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 1767361 INFO (coreLoadExecutor-7753-thread-1-processing-n:127.0.0.1:43279__vs) [n:127.0.0.1:43279__vs c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.RequestHandlers Registered paths: /admin/mbeans,standard,/update/json/docs,/admin/luke,/export,/get,/admin/properties,/update/json,/admin/threads,/dump,/update/csv,/sql,/graph,/admin/segments,/admin/system,/replication,/config,/stream,/schema,/admin/plugins,/admin/logging,/admin/ping,/update,/admin/file,/terms
[junit4] 2> 1767361 INFO (coreLoadExecutor-7753-thread-1-processing-n:127.0.0.1:43279__vs) [n:127.0.0.1:43279__vs c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 1767362 INFO (coreLoadExecutor-7753-thread-1-processing-n:127.0.0.1:43279__vs) [n:127.0.0.1:43279__vs c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 1767362 INFO (coreLoadExecutor-7753-thread-1-processing-n:127.0.0.1:43279__vs) [n:127.0.0.1:43279__vs c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 1767363 INFO (coreLoadExecutor-7753-thread-1-processing-n:127.0.0.1:43279__vs) [n:127.0.0.1:43279__vs c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1767363 INFO (coreLoadExecutor-7753-thread-1-processing-n:127.0.0.1:43279__vs) [n:127.0.0.1:43279__vs c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1767363 INFO (coreLoadExecutor-7753-thread-1-processing-n:127.0.0.1:43279__vs) [n:127.0.0.1:43279__vs c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=24, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0]
[junit4] 2> 1767365 INFO (coreLoadExecutor-7753-thread-1-processing-n:127.0.0.1:43279__vs) [n:127.0.0.1:43279__vs c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@4d279b lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1c3802),segFN=segments_1,generation=1}
[junit4] 2> 1767365 INFO (coreLoadExecutor-7753-thread-1-processing-n:127.0.0.1:43279__vs) [n:127.0.0.1:43279__vs c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1
[junit4] 2> 1767365 INFO (coreLoadExecutor-7753-thread-1-processing-n:127.0.0.1:43279__vs) [n:127.0.0.1:43279__vs c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@1ea8f9a[collection1] main]
[junit4] 2> 1767366 INFO (coreLoadExecutor-7753-thread-1-processing-n:127.0.0.1:43279__vs) [n:127.0.0.1:43279__vs c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.c.ZkStateReader Load collection config from: [/collections/control_collection]
[junit4] 2> 1767367 INFO (coreLoadExecutor-7753-thread-1-processing-n:127.0.0.1:43279__vs) [n:127.0.0.1:43279__vs c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.c.ZkStateReader path=[/collections/control_collection] [configName]=[conf1] specified config exists in ZooKeeper
[junit4] 2> 1767367 INFO (coreLoadExecutor-7753-thread-1-processing-n:127.0.0.1:43279__vs) [n:127.0.0.1:43279__vs c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.ManagedResourceStorage Setting up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1
[junit4] 2> 1767367 INFO (coreLoadExecutor-7753-thread-1-processing-n:127.0.0.1:43279__vs) [n:127.0.0.1:43279__vs c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 1767367 INFO (coreLoadExecutor-7753-thread-1-processing-n:127.0.0.1:43279__vs) [n:127.0.0.1:43279__vs c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.RestManager Initializing RestManager with initArgs: {}
[junit4] 2> 1767367 INFO (coreLoadExecutor-7753-thread-1-processing-n:127.0.0.1:43279__vs) [n:127.0.0.1:43279__vs c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.ManagedResourceStorage Reading _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 1767367 INFO (coreLoadExecutor-7753-thread-1-processing-n:127.0.0.1:43279__vs) [n:127.0.0.1:43279__vs c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.ManagedResourceStorage No data found for znode /configs/conf1/_rest_managed.json
[junit4] 2> 1767367 INFO (coreLoadExecutor-7753-thread-1-processing-n:127.0.0.1:43279__vs) [n:127.0.0.1:43279__vs c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 1767368 INFO (coreLoadExecutor-7753-thread-1-processing-n:127.0.0.1:43279__vs) [n:127.0.0.1:43279__vs c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.RestManager Initializing 0 registered ManagedResources
[junit4] 2> 1767368 INFO (coreLoadExecutor-7753-thread-1-processing-n:127.0.0.1:43279__vs) [n:127.0.0.1:43279__vs c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 1767370 INFO (searcherExecutor-7754-thread-1-processing-n:127.0.0.1:43279__vs x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:43279__vs c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@1ea8f9a[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1767370 INFO (coreLoadExecutor-7753-thread-1-processing-n:127.0.0.1:43279__vs) [n:127.0.0.1:43279__vs c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.UpdateLog Looking up max value of version field to seed version buckets
[junit4] 2> 1767370 INFO (coreLoadExecutor-7753-thread-1-processing-n:127.0.0.1:43279__vs) [n:127.0.0.1:43279__vs c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.VersionInfo Refreshing highest value of _version_ for 65536 version buckets from index
[junit4] 2> 1767370 INFO (coreLoadExecutor-7753-thread-1-processing-n:127.0.0.1:43279__vs) [n:127.0.0.1:43279__vs c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.VersionInfo No terms found for _version_, cannot seed version bucket highest value from index
[junit4] 2> 1767370 INFO (coreLoadExecutor-7753-thread-1-processing-n:127.0.0.1:43279__vs) [n:127.0.0.1:43279__vs c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1544022685273554944
[junit4] 2> 1767372 INFO (coreLoadExecutor-7753-thread-1-processing-n:127.0.0.1:43279__vs) [n:127.0.0.1:43279__vs c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.UpdateLog Took 1.0ms to seed version buckets with highest version 1544022685273554944
[junit4] 2> 1767372 INFO (coreLoadExecutor-7753-thread-1-processing-n:127.0.0.1:43279__vs) [n:127.0.0.1:43279__vs c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController watch zkdir /configs/conf1
[junit4] 2> 1767373 INFO (coreLoadExecutor-7753-thread-1-processing-n:127.0.0.1:43279__vs) [n:127.0.0.1:43279__vs c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CoreContainer registering core: collection1
[junit4] 2> 1767373 INFO (coreZkRegister-7746-thread-1-processing-n:127.0.0.1:43279__vs x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:43279__vs c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController Register replica - core:collection1 address:http://127.0.0.1:43279/_vs collection:control_collection shard:shard1
[junit4] 2> 1767374 INFO (coreZkRegister-7746-thread-1-processing-n:127.0.0.1:43279__vs x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:43279__vs c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/leader_elect/shard1/election
[junit4] 2> 1767375 INFO (coreZkRegister-7746-thread-1-processing-n:127.0.0.1:43279__vs x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:43279__vs c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.LeaderElector Joined leadership election with path: /collections/control_collection/leader_elect/shard1/election/96501417727557636-core_node1-n_0000000000
[junit4] 2> 1767377 INFO (coreZkRegister-7746-thread-1-processing-n:127.0.0.1:43279__vs x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:43279__vs c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext Running the leader process for shard=shard1 and weAreReplacement=false and leaderVoteWait=10000
[junit4] 2> 1767378 INFO (coreZkRegister-7746-thread-1-processing-n:127.0.0.1:43279__vs x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:43279__vs c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 1767378 INFO (coreZkRegister-7746-thread-1-processing-n:127.0.0.1:43279__vs x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:43279__vs c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 1767378 INFO (coreZkRegister-7746-thread-1-processing-n:127.0.0.1:43279__vs x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:43279__vs c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:43279/_vs/collection1/
[junit4] 2> 1767378 INFO (OverseerStateUpdate-96501417727557636-127.0.0.1:43279__vs-n_0000000000) [n:127.0.0.1:43279__vs ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
[junit4] 2> "operation":"leader",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"control_collection"} current state version: 1
[junit4] 2> 1767379 INFO (coreZkRegister-7746-thread-1-processing-n:127.0.0.1:43279__vs x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:43279__vs c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 1767379 INFO (coreZkRegister-7746-thread-1-processing-n:127.0.0.1:43279__vs x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:43279__vs c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy http://127.0.0.1:43279/_vs/collection1/ has no replicas
[junit4] 2> 1767379 INFO (coreZkRegister-7746-thread-1-processing-n:127.0.0.1:43279__vs x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:43279__vs c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/leaders/shard1
[junit4] 2> 1767381 INFO (coreZkRegister-7746-thread-1-processing-n:127.0.0.1:43279__vs x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:43279__vs c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/control_collection/leaders/shard1/leader after winning as /collections/control_collection/leader_elect/shard1/election/96501417727557636-core_node1-n_0000000000
[junit4] 2> 1767382 INFO (coreZkRegister-7746-thread-1-processing-n:127.0.0.1:43279__vs x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:43279__vs c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:43279/_vs/collection1/ shard1
[junit4] 2> 1767382 INFO (OverseerStateUpdate-96501417727557636-127.0.0.1:43279__vs-n_0000000000) [n:127.0.0.1:43279__vs ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
[junit4] 2> "operation":"leader",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"control_collection",
[junit4] 2> "base_url":"http://127.0.0.1:43279/_vs",
[junit4] 2> "core":"collection1",
[junit4] 2> "state":"active"} current state version: 1
[junit4] 2> 1767485 INFO (zkCallback-2753-thread-1-processing-n:127.0.0.1:43279__vs) [n:127.0.0.1:43279__vs ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json], has occurred - updating... (live nodes size: [1])
[junit4] 2> 1767533 INFO (coreZkRegister-7746-thread-1-processing-n:127.0.0.1:43279__vs x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:43279__vs c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController We are http://127.0.0.1:43279/_vs/collection1/ and leader is http://127.0.0.1:43279/_vs/collection1/
[junit4] 2> 1767533 INFO (coreZkRegister-7746-thread-1-processing-n:127.0.0.1:43279__vs x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:43279__vs c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:43279/_vs
[junit4] 2> 1767533 INFO (coreZkRegister-7746-thread-1-processing-n:127.0.0.1:43279__vs x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:43279__vs c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 1767533 INFO (coreZkRegister-7746-thread-1-processing-n:127.0.0.1:43279__vs x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:43279__vs c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController publishing state=active
[junit4] 2> 1767533 INFO (coreZkRegister-7746-thread-1-processing-n:127.0.0.1:43279__vs x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:43279__vs c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController numShards not found on descriptor - reading it from system property
[junit4] 2> 1767534 INFO (coreZkRegister-7746-thread-1-processing-n:127.0.0.1:43279__vs x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:43279__vs c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.c.ZkStateReader Checking legacy cluster state for collection control_collection
[junit4] 2> 1767535 INFO (OverseerStateUpdate-96501417727557636-127.0.0.1:43279__vs-n_0000000000) [n:127.0.0.1:43279__vs ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
[junit4] 2> "core":"collection1",
[junit4] 2> "core_node_name":"core_node1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:43279/_vs",
[junit4] 2> "node_name":"127.0.0.1:43279__vs",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"active",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"control_collection",
[junit4] 2> "operation":"state"} current state version: 2
[junit4] 2> 1767535 INFO (OverseerStateUpdate-96501417727557636-127.0.0.1:43279__vs-n_0000000000) [n:127.0.0.1:43279__vs ] o.a.s.c.o.ReplicaMutator Update state numShards=1 message={
[junit4] 2> "core":"collection1",
[junit4] 2> "core_node_name":"core_node1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:43279/_vs",
[junit4] 2> "node_name":"127.0.0.1:43279__vs",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"active",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"control_collection",
[junit4] 2> "operation":"state"}
[junit4] 2> 1767637 INFO (zkCallback-2753-thread-2-processing-n:127.0.0.1:43279__vs) [n:127.0.0.1:43279__vs ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json], has occurred - updating... (live nodes size: [1])
[junit4] 2> 1767637 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[7AB93AB2E8D17591]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 1767637 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[7AB93AB2E8D17591]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1767638 INFO (zkCallback-2757-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@19e453b name:ZooKeeperConnection Watcher:127.0.0.1:32979/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1767638 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[7AB93AB2E8D17591]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1767639 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[7AB93AB2E8D17591]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 1767639 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[7AB93AB2E8D17591]) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 1767639 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[7AB93AB2E8D17591]) [ ] o.a.s.c.c.ZkStateReader Loaded empty cluster properties
[junit4] 2> 1767641 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[7AB93AB2E8D17591]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1767642 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[7AB93AB2E8D17591]) [ ] o.a.s.c.ChaosMonkey monkey: init - expire sessions:false cause connection loss:false
[junit4] 2> 1767642 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[7AB93AB2E8D17591]) [ ] o.a.s.c.AbstractFullDistribZkTestBase Creating collection1 with stateFormat=2
[junit4] 2> 1767642 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[7AB93AB2E8D17591]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 1767642 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[7AB93AB2E8D17591]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1767643 INFO (zkCallback-2758-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@16bef8c name:ZooKeeperConnection Watcher:127.0.0.1:32979/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1767643 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[7AB93AB2E8D17591]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1767643 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[7AB93AB2E8D17591]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 1767645 INFO (OverseerStateUpdate-96501417727557636-127.0.0.1:43279__vs-n_0000000000) [n:127.0.0.1:43279__vs ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
[junit4] 2> "operation":"create",
[junit4] 2> "name":"collection1",
[junit4] 2> "numShards":"2",
[junit4] 2> "stateFormat":"2"} current state version: 3
[junit4] 2> 1767645 INFO (OverseerStateUpdate-96501417727557636-127.0.0.1:43279__vs-n_0000000000) [n:127.0.0.1:43279__vs ] o.a.s.c.o.ClusterStateMutator building a new cName: collection1
[junit4] 2> 1767734 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[7AB93AB2E8D17591]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.LeaderFailoverAfterPartitionTest_7AB93AB2E8D17591-001/shard-1-001/cores/collection1
[junit4] 2> 1767734 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[7AB93AB2E8D17591]) [ ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 1 in directory /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.LeaderFailoverAfterPartitionTest_7AB93AB2E8D17591-001/shard-1-001
[junit4] 2> 1767735 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[7AB93AB2E8D17591]) [ ] o.e.j.s.Server jetty-9.3.8.v20160314
[junit4] 2> 1767737 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[7AB93AB2E8D17591]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@198cd4e{/_vs,null,AVAILABLE}
[junit4] 2> 1767738 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[7AB93AB2E8D17591]) [ ] o.e.j.s.ServerConnector Started ServerConnector@d79628{HTTP/1.1,[http/1.1]}{127.0.0.1:33917}
[junit4] 2> 1767738 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[7AB93AB2E8D17591]) [ ] o.e.j.s.Server Started @1768978ms
[junit4] 2> 1767738 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[7AB93AB2E8D17591]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.LeaderFailoverAfterPartitionTest_7AB93AB2E8D17591-001/tempDir-001/jetty1, solrconfig=solrconfig.xml, hostContext=/_vs, hostPort=35839, coreRootDirectory=/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.LeaderFailoverAfterPartitionTest_7AB93AB2E8D17591-001/shard-1-001/cores}
[junit4] 2> 1767739 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[7AB93AB2E8D17591]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@1d16e93
[junit4] 2> 1767739 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[7AB93AB2E8D17591]) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.LeaderFailoverAfterPartitionTest_7AB93AB2E8D17591-001/shard-1-001'
[junit4] 2> 1767739 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[7AB93AB2E8D17591]) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 1767739 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[7AB93AB2E8D17591]) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
[junit4] 2> 1767739 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[7AB93AB2E8D17591]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 1767740 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[7AB93AB2E8D17591]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1767741 INFO (zkCallback-2759-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@1630a58 name:ZooKeeperConnection Watcher:127.0.0.1:32979/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1767741 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[7AB93AB2E8D17591]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1767741 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[7AB93AB2E8D17591]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 1767742 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[7AB93AB2E8D17591]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper)
[junit4] 2> 1767742 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[7AB93AB2E8D17591]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.LeaderFailoverAfterPartitionTest_7AB93AB2E8D17591-001/shard-1-001/solr.xml
[junit4] 2> 1767747 INFO (OverseerStateUpdate-96501417727557636-127.0.0.1:43279__vs-n_0000000000) [n:127.0.0.1:43279__vs ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/collection1/state.json
[junit4] 2> 1767749 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[7AB93AB2E8D17591]) [ ] o.a.s.c.CorePropertiesLocator Config-defined core root directory: /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.LeaderFailoverAfterPartitionTest_7AB93AB2E8D17591-001/shard-1-001/cores
[junit4] 2> 1767749 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[7AB93AB2E8D17591]) [ ] o.a.s.c.CoreContainer New CoreContainer 7042082
[junit4] 2> 1767749 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[7AB93AB2E8D17591]) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.LeaderFailoverAfterPartitionTest_7AB93AB2E8D17591-001/shard-1-001]
[junit4] 2> 1767749 WARN (TEST-LeaderFailoverAfterPartitionTest.test-seed#[7AB93AB2E8D17591]) [ ] o.a.s.c.CoreContainer Couldn't add files from /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.LeaderFailoverAfterPartitionTest_7AB93AB2E8D17591-001/shard-1-001/lib to classpath: /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.LeaderFailoverAfterPartitionTest_7AB93AB2E8D17591-001/shard-1-001/lib
[junit4] 2> 1767750 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[7AB93AB2E8D17591]) [ ] o.a.s.h.c.HttpShardHandlerFactory created with socketTimeout : 90000,urlScheme : ,connTimeout : 15000,maxConnectionsPerHost : 20,maxConnections : 10000,corePoolSize : 0,maximumPoolSize : 2147483647,maxThreadIdleTime : 5,sizeOfQueue : -1,fairnessPolicy : false,useRetries : false,connectionsEvictorSleepDelay : 5000,maxConnectionIdleTime : 40000,
[junit4] 2> 1767751 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[7AB93AB2E8D17591]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 1767751 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[7AB93AB2E8D17591]) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 1767751 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[7AB93AB2E8D17591]) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 1767752 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[7AB93AB2E8D17591]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:32979/solr
[junit4] 2> 1767752 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[7AB93AB2E8D17591]) [ ] o.a.s.c.ZkController zkHost includes chroot
[junit4] 2> 1767752 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[7AB93AB2E8D17591]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 1767752 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[7AB93AB2E8D17591]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1767753 INFO (zkCallback-2762-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@7920f3 name:ZooKeeperConnection Watcher:127.0.0.1:32979 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1767753 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[7AB93AB2E8D17591]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1767754 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[7AB93AB2E8D17591]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 1767754 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[7AB93AB2E8D17591]) [n:127.0.0.1:35839__vs ] o.a.s.c.ZkController Added new OnReconnect listener org.apache.solr.cloud.ZkController$$Lambda$11/21484391@8a05b1
[junit4] 2> 1767754 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[7AB93AB2E8D17591]) [n:127.0.0.1:35839__vs ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1767755 INFO (zkCallback-2763-thread-1-processing-n:127.0.0.1:35839__vs) [n:127.0.0.1:35839__vs ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@b7dd7a name:ZooKeeperConnection Watcher:127.0.0.1:32979/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1767755 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[7AB93AB2E8D17591]) [n:127.0.0.1:35839__vs ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1767759 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[7AB93AB2E8D17591]) [n:127.0.0.1:35839__vs ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 1767759 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[7AB93AB2E8D17591]) [n:127.0.0.1:35839__vs ] o.a.s.c.c.ZkStateReader Loaded empty cluster properties
[junit4] 2> 1767761 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[7AB93AB2E8D17591]) [n:127.0.0.1:35839__vs ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1767763 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[7AB93AB2E8D17591]) [n:127.0.0.1:35839__vs ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 1767763 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[7AB93AB2E8D17591]) [n:127.0.0.1:35839__vs ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/96501417727557641-127.0.0.1:35839__vs-n_0000000001
[junit4] 2> 1767763 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[7AB93AB2E8D17591]) [n:127.0.0.1:35839__vs ] o.a.s.c.LeaderElector Watching path /overseer_elect/election/96501417727557636-127.0.0.1:43279__vs-n_0000000000 to know if I could be the leader
[junit4] 2> 1767764 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[7AB93AB2E8D17591]) [n:127.0.0.1:35839__vs ] o.a.s.c.ZkController Publish node=127.0.0.1:35839__vs as DOWN
[junit4] 2> 1767764 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[7AB93AB2E8D17591]) [n:127.0.0.1:35839__vs ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:35839__vs
[junit4] 2> 1767764 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[7AB93AB2E8D17591]) [n:127.0.0.1:35839__vs ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/127.0.0.1:35839__vs
[junit4] 2> 1767765 INFO (OverseerStateUpdate-96501417727557636-127.0.0.1:43279__vs-n_0000000000) [n:127.0.0.1:43279__vs ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
[junit4] 2> "operation":"downnode",
[junit4] 2> "node_name":"127.0.0.1:35839__vs"} current state version: 3
[junit4] 2> 1767765 INFO (OverseerStateUpdate-96501417727557636-127.0.0.1:43279__vs-n_0000000000) [n:127.0.0.1:43279__vs ] o.a.s.c.o.NodeMutator DownNode state invoked for node: 127.0.0.1:35839__vs
[junit4] 2> 1767765 INFO (zkCallback-2757-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes], has occurred - updating... (live nodes size: [1])
[junit4] 2> 1767765 INFO (zkCallback-2763-thread-1-processing-n:127.0.0.1:35839__vs) [n:127.0.0.1:35839__vs ] o.a.s.c.c.ZkStateReader A live node change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes], has occurred - updating... (live nodes size: [1])
[junit4] 2> 1767765 INFO (zkCallback-2753-thread-1-processing-n:127.0.0.1:43279__vs) [n:127.0.0.1:43279__vs ] o.a.s.c.c.ZkStateReader A live node change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes], has occurred - updating... (live nodes size: [1])
[junit4] 2> 1767766 INFO (zkCallback-2757-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 1767766 INFO (zkCallback-2763-thread-1-processing-n:127.0.0.1:35839__vs) [n:127.0.0.1:35839__vs ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 1767766 INFO (zkCallback-2753-thread-1-processing-n:127.0.0.1:43279__vs) [n:127.0.0.1:43279__vs ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 1767766 INFO (zkCallback-2757-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json], has occurred - updating... (live nodes size: [2])
[junit4] 2> 1767766 INFO (zkCallback-2753-thread-1-processing-n:127.0.0.1:43279__vs) [n:127.0.0.1:43279__vs ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json], has occurred - updating... (live nodes size: [2])
[junit4] 2> 1767766 INFO (zkCallback-2763-thread-1-processing-n:127.0.0.1:35839__vs) [n:127.0.0.1:35839__vs ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json], has occurred - updating... (live nodes size: [2])
[junit4] 2> 1767845 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[7AB93AB2E8D17591]) [n:127.0.0.1:35839__vs ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module.
[junit4] 2> 1767845 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[7AB93AB2E8D17591]) [n:127.0.0.1:35839__vs ] o.a.s.c.CoreContainer No authentication plugin used.
[junit4] 2> 1767845 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[7AB93AB2E8D17591]) [n:127.0.0.1:35839__vs ] o.a.s.c.CorePropertiesLocator Looking for core definitions underneath /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.LeaderFailoverAfterPartitionTest_7AB93AB2E8D17591-001/shard-1-001/cores
[junit4] 2> 1767846 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[7AB93AB2E8D17591]) [n:127.0.0.1:35839__vs ] o.a.s.c.CoreDescriptor Created CoreDescriptor: {name=collection1, config=solrconfig.xml, transient=false, schema=schema.xml, loadOnStartup=true, configSetProperties=configsetprops.json, collection=collection1, coreNodeName=, dataDir=data/, shard=}
[junit4] 2> 1767846 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[7AB93AB2E8D17591]) [n:127.0.0.1:35839__vs ] o.a.s.c.CorePropertiesLocator Found core collection1 in /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.LeaderFailoverAfterPartitionTest_7AB93AB2E8D17591-001/shard-1-001/cores/collection1
[junit4] 2> 1767846 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[7AB93AB2E8D17591]) [n:127.0.0.1:35839__vs ] o.a.s.c.CorePropertiesLocator Found 1 core definitions
[junit4] 2> 1767847 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[7AB93AB2E8D17591]) [n:127.0.0.1:35839__vs ] o.a.s.s.SolrDispatchFilter user.dir=/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J2
[junit4] 2> 1767847 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[7AB93AB2E8D17591]) [n:127.0.0.1:35839__vs ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done
[junit4] 2> 1767847 INFO (coreLoadExecutor-7764-thread-1-processing-n:127.0.0.1:35839__vs) [n:127.0.0.1:35839__vs c:collection1 x:collection1] o.a.s.c.ZkController publishing state=down
[junit4] 2> 1767847 INFO (coreLoadExecutor-7764-thread-1-processing-n:127.0.0.1:35839__vs) [n:127.0.0.1:35839__vs c:collection1 x:collection1] o.a.s.c.ZkController numShards not found on descriptor - reading it from system property
[junit4] 2> 1767848 INFO (coreLoadExecutor-7764-thread-1-processing-n:127.0.0.1:35839__vs) [n:127.0.0.1:35839__vs c:collection1 x:collection1] o.a.s.c.ZkController Registering watch for collection collection1
[junit4] 2> 1767848 INFO (OverseerStateUpdate-96501417727557636-127.0.0.1:43279__vs-n_0000000000) [n:127.0.0.1:43279__vs ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:35839/_vs",
[junit4] 2> "node_name":"127.0.0.1:35839__vs",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"down",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "operation":"state"} current state version: 4
[junit4] 2> 1767848 INFO (coreLoadExecutor-7764-thread-1-processing-n:127.0.0.1:35839__vs) [n:127.0.0.1:35839__vs c:collection1 x:collection1] o.a.s.c.c.ZkStateReader Add data for [collection1] ver [0]
[junit4] 2> 1767848 INFO (OverseerStateUpdate-96501417727557636-127.0.0.1:43279__vs-n_0000000000) [n:127.0.0.1:43279__vs ] o.a.s.c.o.ReplicaMutator Update state numShards=2 message={
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:35839/_vs",
[junit4] 2> "node_name":"127.0.0.1:35839__vs",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"down",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "operation":"state"}
[junit4] 2> 1767848 INFO (coreLoadExecutor-7764-thread-1-processing-n:127.0.0.1:35839__vs) [n:127.0.0.1:35839__vs c:collection
[...truncated too long message...]
t4] > at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:1050)
[junit4] > at org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:992)
[junit4] > at org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1219)
[junit4] > at org.apache.solr.cloud.HttpPartitionTest.sendDoc(HttpPartitionTest.java:592)
[junit4] > at org.apache.solr.cloud.HttpPartitionTest.sendDoc(HttpPartitionTest.java:578)
[junit4] > at org.apache.solr.cloud.LeaderFailoverAfterPartitionTest.testRf3WithLeaderFailover(LeaderFailoverAfterPartitionTest.java:174)
[junit4] > at org.apache.solr.cloud.LeaderFailoverAfterPartitionTest.test(LeaderFailoverAfterPartitionTest.java:55)
[junit4] > at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:992)
[junit4] > at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:967)
[junit4] > at java.lang.Thread.run(Thread.java:745)
[junit4] > Caused by: org.apache.solr.client.solrj.SolrServerException: No live SolrServers available to handle this request:[http://127.0.0.1:43279/_vs/c8n_1x3_lf_shard1_replica3]
[junit4] > at org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:384)
[junit4] > at org.apache.solr.client.solrj.impl.CloudSolrClient.directUpdate(CloudSolrClient.java:763)
[junit4] > ... 49 more
[junit4] > Caused by: org.apache.solr.client.solrj.SolrServerException: Server refused connection at: http://127.0.0.1:43279/_vs/c8n_1x3_lf_shard1_replica3
[junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:599)
[junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:261)
[junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:250)
[junit4] > at org.apache.solr.client.solrj.impl.LBHttpSolrClient.doRequest(LBHttpSolrClient.java:405)
[junit4] > at org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:357)
[junit4] > ... 50 more
[junit4] > Caused by: java.net.ConnectException: Connection refused
[junit4] > at java.net.PlainSocketImpl.socketConnect(Native Method)
[junit4] > at java.net.AbstractPlainSocketImpl.doConnect(AbstractPlainSocketImpl.java:350)
[junit4] > at java.net.AbstractPlainSocketImpl.connectToAddress(AbstractPlainSocketImpl.java:206)
[junit4] > at java.net.AbstractPlainSocketImpl.connect(AbstractPlainSocketImpl.java:188)
[junit4] > at java.net.SocksSocketImpl.connect(SocksSocketImpl.java:392)
[junit4] > at java.net.Socket.connect(Socket.java:589)
[junit4] > at org.apache.http.conn.scheme.PlainSocketFactory.connectSocket(PlainSocketFactory.java:117)
[junit4] > at org.apache.http.impl.conn.DefaultClientConnectionOperator.openConnection(DefaultClientConnectionOperator.java:177)
[junit4] > at org.apache.http.impl.conn.ManagedClientConnectionImpl.open(ManagedClientConnectionImpl.java:304)
[junit4] > at org.apache.http.impl.client.DefaultRequestDirector.tryConnect(DefaultRequestDirector.java:611)
[junit4] > at org.apache.http.impl.client.DefaultRequestDirector.execute(DefaultRequestDirector.java:446)
[junit4] > at org.apache.http.impl.client.AbstractHttpClient.doExecute(AbstractHttpClient.java:882)
[junit4] > at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:82)
[junit4] > at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:107)
[junit4] > at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:55)
[junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:497)
[junit4] > ... 54 more
[junit4] 2> 1830569 INFO (SUITE-LeaderFailoverAfterPartitionTest-seed#[7AB93AB2E8D17591]-worker) [ ] o.a.s.SolrTestCaseJ4 ###deleteCore
[junit4] 2> NOTE: leaving temporary files on disk at: /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.LeaderFailoverAfterPartitionTest_7AB93AB2E8D17591-001
[junit4] 2> NOTE: test params are: codec=CheapBastard, sim=RandomSimilarity(queryNorm=false,coord=no): {}, locale=no, timezone=Africa/Bissau
[junit4] 2> NOTE: Linux 4.4.0-31-generic i386/Oracle Corporation 1.8.0_102 (32-bit)/cpus=12,threads=1,free=150608856,total=536870912
[junit4] 2> NOTE: All tests run in this JVM: [AssignTest, BasicDistributedZk2Test, JavabinLoaderTest, BJQParserTest, TestCollationField, ScriptEngineTest, TestMiniSolrCloudCluster, TestRawTransformer, SimpleFacetsTest, SuggestComponentContextFilterQueryTest, TriLevelCompositeIdRoutingTest, CollectionReloadTest, StatelessScriptUpdateProcessorFactoryTest, TestJmxMonitoredMap, TestFieldCacheVsDocValues, TestSolrIndexConfig, DeleteStatusTest, BigEndianAscendingWordDeserializerTest, TestRestManager, HdfsBasicDistributedZkTest, TestStandardQParsers, TermVectorComponentDistributedTest, TestIndexingPerformance, TestShardHandlerFactory, RankQueryTest, TestReplicationHandlerBackup, DirectoryFactoryTest, PluginInfoTest, CircularListTest, SampleTest, DirectSolrConnectionTest, TestCharFilters, TestRequestForwarding, HdfsWriteToMultipleCollectionsTest, RecoveryZkTest, DistribJoinFromCollectionTest, TestInitParams, DistributedFacetPivotWhiteBoxTest, SharedFSAutoReplicaFailoverTest, HdfsRecoveryZkTest, AddBlockUpdateTest, TestMinMaxOnMultiValuedField, FullSolrCloudDistribCmdsTest, BasicAuthIntegrationTest, FieldMutatingUpdateProcessorTest, NoCacheHeaderTest, TestWriterPerf, TestXIncludeConfig, SuggesterTSTTest, WrapperMergePolicyFactoryTest, TestGeoJSONResponseWriter, TestNonDefinedSimilarityFactory, AtomicUpdatesTest, TestManagedSchema, TestOverriddenPrefixQueryForCustomFieldType, TestDynamicFieldResource, TestSolrJ, HdfsCollectionsAPIDistributedZkTest, FieldAnalysisRequestHandlerTest, ForceLeaderTest, TestFuzzyAnalyzedSuggestions, DocValuesMultiTest, TestSolrDeletionPolicy1, CdcrReplicationHandlerTest, AnalyticsQueryTest, SliceStateTest, TestNumericTerms32, TestStressReorder, SolrTestCaseJ4Test, XmlUpdateRequestHandlerTest, TestFastWriter, ZkStateReaderTest, DocExpirationUpdateProcessorFactoryTest, SearchHandlerTest, DistributedSuggestComponentTest, TestElisionMultitermQuery, TestSchemaResource, BadComponentTest, TestPerFieldSimilarity, TestConfigSetsAPI, DeleteNodeTest, DateRangeFieldTest, TestChildDocTransformer, TestUseDocValuesAsStored, TestPHPSerializedResponseWriter, SuggesterTest, TestSerializedLuceneMatchVersion, TestSuggestSpellingConverter, ClusterStateTest, TestFieldCacheSanityChecker, PrimitiveFieldTypeTest, TestMaxScoreQueryParser, TestRTimerTree, UpdateParamsTest, TestBlobHandler, RequestHandlersTest, TestZkChroot, TestSubQueryTransformerDistrib, LukeRequestHandlerTest, TestJettySolrRunner, TestBlendedInfixSuggestions, UnloadDistributedZkTest, OverseerTest, LeaderElectionIntegrationTest, ShardRoutingTest, LeaderElectionTest, ZkCLITest, ShardRoutingCustomTest, AutoCommitTest, TestDistributedGrouping, TestRecovery, DistributedSpellCheckComponentTest, TestReload, HardAutoCommitTest, TestRangeQuery, SolrCoreTest, StatsComponentTest, TestGroupingSearch, SolrCmdDistributorTest, QueryElevationComponentTest, BadIndexSchemaTest, TestSort, TestFiltering, TestLazyCores, DirectUpdateHandlerTest, TestBadConfig, TestIndexSearcher, SoftAutoCommitTest, ShowFileRequestHandlerTest, DistributedQueryElevationComponentTest, CurrencyFieldXmlFileTest, SolrIndexSplitterTest, TestCoreDiscovery, AnalysisAfterCoreReloadTest, TestExtendedDismaxParser, SpellCheckCollatorTest, SuggesterFSTTest, CoreAdminHandlerTest, TestFoldingMultitermQuery, DocValuesTest, SuggesterWFSTTest, TestCSVLoader, PolyFieldTest, SolrCoreCheckLockOnStartupTest, TestAtomicUpdateErrorCases, QueryEqualityTest, DirectUpdateHandlerOptimizeTest, DefaultValueUpdateProcessorTest, SortByFunctionTest, TestRemoteStreaming, DistanceFunctionTest, SolrInfoMBeanTest, DebugComponentTest, CacheHeaderTest, TestSurroundQueryParser, TestQueryUtils, StandardRequestHandlerTest, TestReversedWildcardFilterFactory, TestQueryTypes, TermsComponentTest, DocumentBuilderTest, TestValueSourceCache, MoreLikeThisHandlerTest, TestSolrQueryParser, TestArbitraryIndexDir, FastVectorHighlighterTest, LoggingHandlerTest, RegexBoostProcessorTest, SolrPluginUtilsTest, TestJmxIntegration, TestCSVResponseWriter, QueryParsingTest, UniqFieldsUpdateProcessorFactoryTest, JsonLoaderTest, BinaryUpdateRequestHandlerTest, TestComponentsName, TestBinaryResponseWriter, HighlighterConfigTest, SOLR749Test, TestQuerySenderListener, ResponseLogComponentTest, SolrIndexConfigTest, TestStressRecovery, TestDocSet, TestBinaryField, TestSearchPerf, MinimalSchemaTest, TestConfig, OutputWriterTest, TestSolrCoreProperties, TestPostingsSolrHighlighter, TestPhraseSuggestions, TestCodecSupport, SynonymTokenizerTest, EchoParamsTest, TestSweetSpotSimilarityFactory, TestIBSimilarityFactory, TestBM25SimilarityFactory, ResourceLoaderTest, TestFastOutputStream, OpenExchangeRatesOrgProviderTest, DateMathParserTest, ChaosMonkeyNothingIsSafeTest, PrimUtilsTest, DateFieldTest, TestUtils, SystemInfoHandlerTest, FileUtilsTest, TestRTGBase, CursorPagingTest, DistributedIntervalFacetingTest, TestDistributedMissingSort, TestTolerantSearch, BaseCdcrDistributedZkTest, CdcrReplicationDistributedZkTest, CdcrVersionReplicationTest, CollectionStateFormat2Test, CollectionsAPISolrJTest, DeleteInactiveReplicaTest, DeleteLastCustomShardedReplicaTest, DeleteReplicaTest, DeleteShardTest, DistributedVersionInfoTest, LeaderFailoverAfterPartitionTest]
[junit4] Completed [452/631 (1!)] on J2 in 64.80s, 1 test, 1 error <<< FAILURES!
[...truncated 53045 lines...]