You are viewing a plain text version of this content. The canonical link for it is here.
Posted to builds@lucene.apache.org by Apache Jenkins Server <je...@builds.apache.org> on 2021/03/07 21:09:33 UTC
[JENKINS] Lucene » Lucene-Solr-BadApples-Tests-8.x - Build # 209 - Still Failing!
Build: https://ci-builds.apache.org/job/Lucene/job/Lucene-Solr-BadApples-Tests-8.x/209/
All tests passed
Build Log:
[...truncated 12269 lines...]
[javac] Compiling 781 source files to /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-BadApples-Tests-8.x/solr/build/solr-solrj/classes/java
[javac] /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-BadApples-Tests-8.x/solr/solrj/src/java/org/apache/solr/client/solrj/impl/PreemptiveBasicAuthClientBuilderFactory.java:150: error: cannot find symbol
[javac] try (BufferedReader reader = Files.newBufferedReader(Path.of(configFile), StandardCharsets.UTF_8)) {
[javac] ^
[javac] symbol: method of(String)
[javac] location: interface Path
[javac] Note: Some input files use or override a deprecated API.
[javac] Note: Recompile with -Xlint:deprecation for details.
[javac] Note: Some input files use unchecked or unsafe operations.
[javac] Note: Recompile with -Xlint:unchecked for details.
[javac] 1 error
BUILD FAILED
/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-BadApples-Tests-8.x/build.xml:643: The following error occurred while executing this line:
/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-BadApples-Tests-8.x/build.xml:578: The following error occurred while executing this line:
/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-BadApples-Tests-8.x/build.xml:59: The following error occurred while executing this line:
/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-BadApples-Tests-8.x/solr/build.xml:231: The following error occurred while executing this line:
/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-BadApples-Tests-8.x/solr/common-build.xml:550: The following error occurred while executing this line:
/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-BadApples-Tests-8.x/solr/common-build.xml:498: The following error occurred while executing this line:
/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-BadApples-Tests-8.x/solr/common-build.xml:393: The following error occurred while executing this line:
/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-BadApples-Tests-8.x/solr/common-build.xml:413: The following error occurred while executing this line:
/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-BadApples-Tests-8.x/lucene/common-build.xml:581: The following error occurred while executing this line:
/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-BadApples-Tests-8.x/lucene/common-build.xml:2083: Compile failed; see the compiler error output for details.
Total time: 13 minutes 26 seconds
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
Recording test results
[Checks API] No suitable checks publisher found.
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any
[JENKINS] Lucene » Lucene-Solr-BadApples-Tests-8.x - Build # 210 - Still unstable!
Posted by Apache Jenkins Server <je...@builds.apache.org>.
Build: https://ci-builds.apache.org/job/Lucene/job/Lucene-Solr-BadApples-Tests-8.x/210/
1 tests failed.
FAILED: junit.framework.TestSuite.org.apache.solr.servlet.HttpSolrCallGetCoreTest
Error Message:
Timeout occurred while waiting response from server at: http://127.0.0.1:39999/solr
Stack Trace:
org.apache.solr.client.solrj.SolrServerException: Timeout occurred while waiting response from server at: http://127.0.0.1:39999/solr
at __randomizedtesting.SeedInfo.seed([96E9A77C44EE6AA7]:0)
at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:692)
at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:266)
at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:248)
at org.apache.solr.client.solrj.impl.LBSolrClient.doRequest(LBSolrClient.java:369)
at org.apache.solr.client.solrj.impl.LBSolrClient.request(LBSolrClient.java:297)
at org.apache.solr.client.solrj.impl.BaseCloudSolrClient.sendRequest(BaseCloudSolrClient.java:1171)
at org.apache.solr.client.solrj.impl.BaseCloudSolrClient.requestWithRetryOnStaleState(BaseCloudSolrClient.java:934)
at org.apache.solr.client.solrj.impl.BaseCloudSolrClient.request(BaseCloudSolrClient.java:866)
at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:214)
at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:231)
at org.apache.solr.servlet.HttpSolrCallGetCoreTest.setupCluster(HttpSolrCallGetCoreTest.java:53)
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:1750)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:878)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:894)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.junit.rules.RunRules.evaluate(RunRules.java:20)
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 org.junit.rules.RunRules.evaluate(RunRules.java:20)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.lang.Thread.run(Thread.java:748)
Caused by: java.net.SocketTimeoutException: Read timed out
at java.net.SocketInputStream.socketRead0(Native Method)
at java.net.SocketInputStream.socketRead(SocketInputStream.java:116)
at java.net.SocketInputStream.read(SocketInputStream.java:171)
at java.net.SocketInputStream.read(SocketInputStream.java:141)
at org.apache.http.impl.io.SessionInputBufferImpl.streamRead(SessionInputBufferImpl.java:137)
at org.apache.http.impl.io.SessionInputBufferImpl.fillBuffer(SessionInputBufferImpl.java:153)
at org.apache.http.impl.io.SessionInputBufferImpl.readLine(SessionInputBufferImpl.java:280)
at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:138)
at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:56)
at org.apache.http.impl.io.AbstractMessageParser.parse(AbstractMessageParser.java:259)
at org.apache.http.impl.DefaultBHttpClientConnection.receiveResponseHeader(DefaultBHttpClientConnection.java:163)
at org.apache.http.impl.conn.CPoolProxy.receiveResponseHeader(CPoolProxy.java:157)
at org.apache.http.protocol.HttpRequestExecutor.doReceiveResponse(HttpRequestExecutor.java:273)
at org.apache.http.protocol.HttpRequestExecutor.execute(HttpRequestExecutor.java:125)
at org.apache.http.impl.execchain.MainClientExec.execute(MainClientExec.java:272)
at org.apache.http.impl.execchain.ProtocolExec.execute(ProtocolExec.java:186)
at org.apache.http.impl.execchain.RetryExec.execute(RetryExec.java:89)
at org.apache.http.impl.execchain.RedirectExec.execute(RedirectExec.java:110)
at org.apache.http.impl.client.InternalHttpClient.doExecute(InternalHttpClient.java:185)
at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:83)
at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:56)
at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:571)
... 36 more
Build Log:
[...truncated 16462 lines...]
[junit4] Suite: org.apache.solr.servlet.HttpSolrCallGetCoreTest
[junit4] 2> 2324887 INFO (SUITE-HttpSolrCallGetCoreTest-seed#[96E9A77C44EE6AA7]-worker) [ ] o.a.s.SolrTestCase Setting 'solr.default.confdir' system property to test-framework derived value of '/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-BadApples-Tests-8.x/solr/server/solr/configsets/_default/conf'
[junit4] 2> 2324887 INFO (SUITE-HttpSolrCallGetCoreTest-seed#[96E9A77C44EE6AA7]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 2324888 INFO (SUITE-HttpSolrCallGetCoreTest-seed#[96E9A77C44EE6AA7]-worker) [ ] o.a.s.SolrTestCaseJ4 Created dataDir: /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-BadApples-Tests-8.x/solr/build/solr-core/test/J1/temp/solr.servlet.HttpSolrCallGetCoreTest_96E9A77C44EE6AA7-001/data-dir-244-001
[junit4] 2> 2324888 WARN (SUITE-HttpSolrCallGetCoreTest-seed#[96E9A77C44EE6AA7]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=9 numCloses=9
[junit4] 2> 2324888 INFO (SUITE-HttpSolrCallGetCoreTest-seed#[96E9A77C44EE6AA7]-worker) [ ] o.a.s.SolrTestCaseJ4 Using TrieFields (NUMERIC_POINTS_SYSPROP=false) w/NUMERIC_DOCVALUES_SYSPROP=false
[junit4] 2> 2324889 INFO (SUITE-HttpSolrCallGetCoreTest-seed#[96E9A77C44EE6AA7]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (true) via: @org.apache.solr.util.RandomizeSSL(reason=, ssl=NaN, value=NaN, clientAuth=NaN)
[junit4] 2> 2324890 INFO (SUITE-HttpSolrCallGetCoreTest-seed#[96E9A77C44EE6AA7]-worker) [ ] o.a.s.c.SolrCloudTestCase Using per-replica state
[junit4] 2> 2324890 INFO (SUITE-HttpSolrCallGetCoreTest-seed#[96E9A77C44EE6AA7]-worker) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 1 servers in /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-BadApples-Tests-8.x/solr/build/solr-core/test/J1/temp/solr.servlet.HttpSolrCallGetCoreTest_96E9A77C44EE6AA7-001/tempDir-001
[junit4] 2> 2324890 INFO (SUITE-HttpSolrCallGetCoreTest-seed#[96E9A77C44EE6AA7]-worker) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 2324891 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer client port: 0.0.0.0/0.0.0.0:0
[junit4] 2> 2324891 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 2324892 WARN (ZkTestServer Run Thread) [ ] o.a.z.s.ServerCnxnFactory maxCnxns is not configured, using default value 0.
[junit4] 2> 2325086 INFO (SUITE-HttpSolrCallGetCoreTest-seed#[96E9A77C44EE6AA7]-worker) [ ] o.a.s.c.ZkTestServer start zk server on port: 34977
[junit4] 2> 2325086 INFO (SUITE-HttpSolrCallGetCoreTest-seed#[96E9A77C44EE6AA7]-worker) [ ] o.a.s.c.ZkTestServer waitForServerUp: 127.0.0.1:34977
[junit4] 2> 2325086 INFO (SUITE-HttpSolrCallGetCoreTest-seed#[96E9A77C44EE6AA7]-worker) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:34977
[junit4] 2> 2325086 INFO (SUITE-HttpSolrCallGetCoreTest-seed#[96E9A77C44EE6AA7]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 34977
[junit4] 2> 2325089 INFO (SUITE-HttpSolrCallGetCoreTest-seed#[96E9A77C44EE6AA7]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2325091 INFO (zkConnectionManagerCallback-23517-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2325091 INFO (SUITE-HttpSolrCallGetCoreTest-seed#[96E9A77C44EE6AA7]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2325093 INFO (SUITE-HttpSolrCallGetCoreTest-seed#[96E9A77C44EE6AA7]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2325094 INFO (zkConnectionManagerCallback-23519-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2325094 INFO (SUITE-HttpSolrCallGetCoreTest-seed#[96E9A77C44EE6AA7]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2325095 INFO (SUITE-HttpSolrCallGetCoreTest-seed#[96E9A77C44EE6AA7]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2325097 INFO (zkConnectionManagerCallback-23521-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2325097 INFO (SUITE-HttpSolrCallGetCoreTest-seed#[96E9A77C44EE6AA7]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2325199 INFO (jetty-launcher-23522-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 2325200 INFO (jetty-launcher-23522-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 2325200 INFO (jetty-launcher-23522-thread-1) [ ] o.e.j.s.Server jetty-9.4.34.v20201102; built: 2020-11-02T14:15:39.302Z; git: e46af88704a893fc12cb0e3bf46e2c7b48a009e7; jvm 1.8.0_281-b09
[junit4] 2> 2325200 INFO (jetty-launcher-23522-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 2325200 INFO (jetty-launcher-23522-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 2325200 INFO (jetty-launcher-23522-thread-1) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 2325200 INFO (jetty-launcher-23522-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@56d7eede{/solr,null,AVAILABLE}
[junit4] 2> 2325201 INFO (jetty-launcher-23522-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@44d5b247{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:39999}
[junit4] 2> 2325201 INFO (jetty-launcher-23522-thread-1) [ ] o.e.j.s.Server Started @2325232ms
[junit4] 2> 2325201 INFO (jetty-launcher-23522-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, zkHost=127.0.0.1:34977/solr, hostPort=39999}
[junit4] 2> 2325201 ERROR (jetty-launcher-23522-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 2325201 INFO (jetty-launcher-23522-thread-1) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 2325201 INFO (jetty-launcher-23522-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.9.0
[junit4] 2> 2325201 INFO (jetty-launcher-23522-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 2325201 INFO (jetty-launcher-23522-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-BadApples-Tests-8.x/solr
[junit4] 2> 2325201 INFO (jetty-launcher-23522-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2021-03-08T10:04:45.097Z
[junit4] 2> 2325202 INFO (jetty-launcher-23522-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2325203 INFO (zkConnectionManagerCallback-23524-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2325203 INFO (jetty-launcher-23522-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2325203 INFO (jetty-launcher-23522-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 2325207 INFO (jetty-launcher-23522-thread-1) [ ] o.a.s.c.SolrXmlConfig Metrics collection is disabled.
[junit4] 2> 2326141 INFO (jetty-launcher-23522-thread-1) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 2326142 WARN (jetty-launcher-23522-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@554ed2b0[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2326142 WARN (jetty-launcher-23522-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@554ed2b0[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2326150 WARN (jetty-launcher-23522-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@2672805c[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2326150 WARN (jetty-launcher-23522-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@2672805c[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2326151 INFO (jetty-launcher-23522-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:34977/solr
[junit4] 2> 2326158 INFO (jetty-launcher-23522-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2326159 INFO (zkConnectionManagerCallback-23535-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2326159 INFO (jetty-launcher-23522-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2326270 INFO (jetty-launcher-23522-thread-1) [n:127.0.0.1:39999_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2326275 INFO (zkConnectionManagerCallback-23537-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2326275 INFO (jetty-launcher-23522-thread-1) [n:127.0.0.1:39999_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2326367 INFO (jetty-launcher-23522-thread-1) [n:127.0.0.1:39999_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:39999_solr
[junit4] 2> 2326367 INFO (jetty-launcher-23522-thread-1) [n:127.0.0.1:39999_solr ] o.a.s.c.Overseer Overseer (id=73122165950578693-127.0.0.1:39999_solr-n_0000000000) starting
[junit4] 2> 2326382 INFO (OverseerStateUpdate-73122165950578693-127.0.0.1:39999_solr-n_0000000000) [n:127.0.0.1:39999_solr ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:39999_solr
[junit4] 2> 2326382 INFO (jetty-launcher-23522-thread-1) [n:127.0.0.1:39999_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:39999_solr
[junit4] 2> 2326388 INFO (jetty-launcher-23522-thread-1) [n:127.0.0.1:39999_solr ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 2326388 WARN (jetty-launcher-23522-thread-1) [n:127.0.0.1:39999_solr ] o.a.s.c.CoreContainer Not all security plugins configured! authentication=disabled authorization=disabled. Solr is only as secure as you make it. Consider configuring authentication/authorization before exposing Solr to users internal or external. See https://s.apache.org/solrsecurity for more info
[junit4] 2> 2326394 INFO (zkCallback-23536-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 2326405 INFO (jetty-launcher-23522-thread-1) [n:127.0.0.1:39999_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-BadApples-Tests-8.x/solr/build/solr-core/test/J1/temp/solr.servlet.HttpSolrCallGetCoreTest_96E9A77C44EE6AA7-001/tempDir-001/node1
[junit4] 2> 2326418 INFO (SUITE-HttpSolrCallGetCoreTest-seed#[96E9A77C44EE6AA7]-worker) [ ] o.a.s.c.MiniSolrCloudCluster waitForAllNodes: numServers=1
[junit4] 2> 2326419 INFO (SUITE-HttpSolrCallGetCoreTest-seed#[96E9A77C44EE6AA7]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2326426 INFO (zkConnectionManagerCallback-23552-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2326426 INFO (SUITE-HttpSolrCallGetCoreTest-seed#[96E9A77C44EE6AA7]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2326579 INFO (SUITE-HttpSolrCallGetCoreTest-seed#[96E9A77C44EE6AA7]-worker) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 2326581 INFO (SUITE-HttpSolrCallGetCoreTest-seed#[96E9A77C44EE6AA7]-worker) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:34977/solr ready
[junit4] 2> 2326605 INFO (OverseerThreadFactory-23544-thread-1-processing-n:127.0.0.1:39999_solr) [n:127.0.0.1:39999_solr ] o.a.s.c.a.c.CreateCollectionCmd Create collection collection1
[junit4] 2> 2326611 WARN (OverseerThreadFactory-23544-thread-1-processing-n:127.0.0.1:39999_solr) [n:127.0.0.1:39999_solr ] o.a.s.c.a.c.CreateCollectionCmd Specified number of replicas of 2 on collection collection1 is higher than 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> 2326613 INFO (OverseerThreadFactory-23544-thread-1-processing-n:127.0.0.1:39999_solr) [n:127.0.0.1:39999_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "core":"collection1_shard1_replica_n1",
[junit4] 2> "node_name":"127.0.0.1:39999_solr",
[junit4] 2> "base_url":"http://127.0.0.1:39999/solr",
[junit4] 2> "collection":"collection1",
[junit4] 2> "shard":"shard1",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT",
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 2326616 INFO (OverseerThreadFactory-23544-thread-1-processing-n:127.0.0.1:39999_solr) [n:127.0.0.1:39999_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "core":"collection1_shard1_replica_n3",
[junit4] 2> "node_name":"127.0.0.1:39999_solr",
[junit4] 2> "base_url":"http://127.0.0.1:39999/solr",
[junit4] 2> "collection":"collection1",
[junit4] 2> "shard":"shard1",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT",
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 2326628 INFO (OverseerThreadFactory-23544-thread-1-processing-n:127.0.0.1:39999_solr) [n:127.0.0.1:39999_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "core":"collection1_shard2_replica_n5",
[junit4] 2> "node_name":"127.0.0.1:39999_solr",
[junit4] 2> "base_url":"http://127.0.0.1:39999/solr",
[junit4] 2> "collection":"collection1",
[junit4] 2> "shard":"shard2",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT",
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 2326635 INFO (OverseerThreadFactory-23544-thread-1-processing-n:127.0.0.1:39999_solr) [n:127.0.0.1:39999_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "core":"collection1_shard2_replica_n7",
[junit4] 2> "node_name":"127.0.0.1:39999_solr",
[junit4] 2> "base_url":"http://127.0.0.1:39999/solr",
[junit4] 2> "collection":"collection1",
[junit4] 2> "shard":"shard2",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT",
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 2326637 INFO (OverseerThreadFactory-23544-thread-1-processing-n:127.0.0.1:39999_solr) [n:127.0.0.1:39999_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "core":"collection1_shard3_replica_n9",
[junit4] 2> "node_name":"127.0.0.1:39999_solr",
[junit4] 2> "base_url":"http://127.0.0.1:39999/solr",
[junit4] 2> "collection":"collection1",
[junit4] 2> "shard":"shard3",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT",
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 2326643 INFO (OverseerThreadFactory-23544-thread-1-processing-n:127.0.0.1:39999_solr) [n:127.0.0.1:39999_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "core":"collection1_shard3_replica_n11",
[junit4] 2> "node_name":"127.0.0.1:39999_solr",
[junit4] 2> "base_url":"http://127.0.0.1:39999/solr",
[junit4] 2> "collection":"collection1",
[junit4] 2> "shard":"shard3",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT",
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 2326653 INFO (qtp1242711413-40237) [n:127.0.0.1:39999_solr x:collection1_shard2_replica_n5 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node6&collection.configName=config&newCollection=true&name=collection1_shard2_replica_n5&action=CREATE&numShards=3&collection=collection1&shard=shard2&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 2326653 INFO (qtp1242711413-40237) [n:127.0.0.1:39999_solr x:collection1_shard2_replica_n5 ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 2326654 INFO (qtp1242711413-40198) [n:127.0.0.1:39999_solr x:collection1_shard2_replica_n7 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node8&collection.configName=config&newCollection=true&name=collection1_shard2_replica_n7&action=CREATE&numShards=3&collection=collection1&shard=shard2&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 2326655 INFO (qtp1242711413-40199) [n:127.0.0.1:39999_solr x:collection1_shard1_replica_n3 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node4&collection.configName=config&newCollection=true&name=collection1_shard1_replica_n3&action=CREATE&numShards=3&collection=collection1&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 2326656 INFO (qtp1242711413-40195) [n:127.0.0.1:39999_solr x:collection1_shard1_replica_n1 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node2&collection.configName=config&newCollection=true&name=collection1_shard1_replica_n1&action=CREATE&numShards=3&collection=collection1&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 2326656 INFO (qtp1242711413-40197) [n:127.0.0.1:39999_solr x:collection1_shard3_replica_n9 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node10&collection.configName=config&newCollection=true&name=collection1_shard3_replica_n9&action=CREATE&numShards=3&collection=collection1&shard=shard3&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 2326659 INFO (qtp1242711413-40238) [n:127.0.0.1:39999_solr x:collection1_shard3_replica_n11 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node12&collection.configName=config&newCollection=true&name=collection1_shard3_replica_n11&action=CREATE&numShards=3&collection=collection1&shard=shard3&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 2326682 INFO (qtp1242711413-40195) [n:127.0.0.1:39999_solr c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.9.0
[junit4] 2> 2326682 INFO (qtp1242711413-40197) [n:127.0.0.1:39999_solr c:collection1 s:shard3 r:core_node10 x:collection1_shard3_replica_n9 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.9.0
[junit4] 2> 2326690 INFO (qtp1242711413-40237) [n:127.0.0.1:39999_solr c:collection1 s:shard2 r:core_node6 x:collection1_shard2_replica_n5 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.9.0
[junit4] 2> 2326692 INFO (qtp1242711413-40195) [n:127.0.0.1:39999_solr c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1 ] o.a.s.s.IndexSchema Schema name=minimal
[junit4] 2> 2326692 INFO (qtp1242711413-40199) [n:127.0.0.1:39999_solr c:collection1 s:shard1 r:core_node4 x:collection1_shard1_replica_n3 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.9.0
[junit4] 2> 2326692 INFO (qtp1242711413-40195) [n:127.0.0.1:39999_solr c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1 ] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 2326692 INFO (qtp1242711413-40195) [n:127.0.0.1:39999_solr c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1 ] o.a.s.c.CoreContainer Creating SolrCore 'collection1_shard1_replica_n1' using configuration from configset config, trusted=true
[junit4] 2> 2326693 INFO (qtp1242711413-40195) [n:127.0.0.1:39999_solr c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1 ] o.a.s.c.SolrCore [[collection1_shard1_replica_n1] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-BadApples-Tests-8.x/solr/build/solr-core/test/J1/temp/solr.servlet.HttpSolrCallGetCoreTest_96E9A77C44EE6AA7-001/tempDir-001/node1/collection1_shard1_replica_n1], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-BadApples-Tests-8.x/solr/build/solr-core/test/J1/temp/solr.servlet.HttpSolrCallGetCoreTest_96E9A77C44EE6AA7-001/tempDir-001/node1/collection1_shard1_replica_n1/data/]
[junit4] 2> 2326728 INFO (qtp1242711413-40199) [n:127.0.0.1:39999_solr c:collection1 s:shard1 r:core_node4 x:collection1_shard1_replica_n3 ] o.a.s.s.IndexSchema Schema name=minimal
[junit4] 2> 2326729 INFO (qtp1242711413-40199) [n:127.0.0.1:39999_solr c:collection1 s:shard1 r:core_node4 x:collection1_shard1_replica_n3 ] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 2326729 INFO (qtp1242711413-40199) [n:127.0.0.1:39999_solr c:collection1 s:shard1 r:core_node4 x:collection1_shard1_replica_n3 ] o.a.s.c.CoreContainer Creating SolrCore 'collection1_shard1_replica_n3' using configuration from configset config, trusted=true
[junit4] 2> 2326729 INFO (qtp1242711413-40199) [n:127.0.0.1:39999_solr c:collection1 s:shard1 r:core_node4 x:collection1_shard1_replica_n3 ] o.a.s.c.SolrCore [[collection1_shard1_replica_n3] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-BadApples-Tests-8.x/solr/build/solr-core/test/J1/temp/solr.servlet.HttpSolrCallGetCoreTest_96E9A77C44EE6AA7-001/tempDir-001/node1/collection1_shard1_replica_n3], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-BadApples-Tests-8.x/solr/build/solr-core/test/J1/temp/solr.servlet.HttpSolrCallGetCoreTest_96E9A77C44EE6AA7-001/tempDir-001/node1/collection1_shard1_replica_n3/data/]
[junit4] 2> 2326730 INFO (qtp1242711413-40197) [n:127.0.0.1:39999_solr c:collection1 s:shard3 r:core_node10 x:collection1_shard3_replica_n9 ] o.a.s.s.IndexSchema Schema name=minimal
[junit4] 2> 2326731 INFO (qtp1242711413-40197) [n:127.0.0.1:39999_solr c:collection1 s:shard3 r:core_node10 x:collection1_shard3_replica_n9 ] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 2326731 INFO (qtp1242711413-40197) [n:127.0.0.1:39999_solr c:collection1 s:shard3 r:core_node10 x:collection1_shard3_replica_n9 ] o.a.s.c.CoreContainer Creating SolrCore 'collection1_shard3_replica_n9' using configuration from configset config, trusted=true
[junit4] 2> 2326731 INFO (qtp1242711413-40197) [n:127.0.0.1:39999_solr c:collection1 s:shard3 r:core_node10 x:collection1_shard3_replica_n9 ] o.a.s.c.SolrCore [[collection1_shard3_replica_n9] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-BadApples-Tests-8.x/solr/build/solr-core/test/J1/temp/solr.servlet.HttpSolrCallGetCoreTest_96E9A77C44EE6AA7-001/tempDir-001/node1/collection1_shard3_replica_n9], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-BadApples-Tests-8.x/solr/build/solr-core/test/J1/temp/solr.servlet.HttpSolrCallGetCoreTest_96E9A77C44EE6AA7-001/tempDir-001/node1/collection1_shard3_replica_n9/data/]
[junit4] 2> 2326733 INFO (qtp1242711413-40237) [n:127.0.0.1:39999_solr c:collection1 s:shard2 r:core_node6 x:collection1_shard2_replica_n5 ] o.a.s.s.IndexSchema Schema name=minimal
[junit4] 2> 2326734 INFO (qtp1242711413-40237) [n:127.0.0.1:39999_solr c:collection1 s:shard2 r:core_node6 x:collection1_shard2_replica_n5 ] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 2326734 INFO (qtp1242711413-40237) [n:127.0.0.1:39999_solr c:collection1 s:shard2 r:core_node6 x:collection1_shard2_replica_n5 ] o.a.s.c.CoreContainer Creating SolrCore 'collection1_shard2_replica_n5' using configuration from configset config, trusted=true
[junit4] 2> 2326735 INFO (qtp1242711413-40237) [n:127.0.0.1:39999_solr c:collection1 s:shard2 r:core_node6 x:collection1_shard2_replica_n5 ] o.a.s.c.SolrCore [[collection1_shard2_replica_n5] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-BadApples-Tests-8.x/solr/build/solr-core/test/J1/temp/solr.servlet.HttpSolrCallGetCoreTest_96E9A77C44EE6AA7-001/tempDir-001/node1/collection1_shard2_replica_n5], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-BadApples-Tests-8.x/solr/build/solr-core/test/J1/temp/solr.servlet.HttpSolrCallGetCoreTest_96E9A77C44EE6AA7-001/tempDir-001/node1/collection1_shard2_replica_n5/data/]
[junit4] 2> 2326750 INFO (qtp1242711413-40198) [n:127.0.0.1:39999_solr c:collection1 s:shard2 r:core_node8 x:collection1_shard2_replica_n7 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.9.0
[junit4] 2> 2326760 INFO (qtp1242711413-40238) [n:127.0.0.1:39999_solr c:collection1 s:shard3 r:core_node12 x:collection1_shard3_replica_n11 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.9.0
[junit4] 2> 2326762 INFO (qtp1242711413-40198) [n:127.0.0.1:39999_solr c:collection1 s:shard2 r:core_node8 x:collection1_shard2_replica_n7 ] o.a.s.s.IndexSchema Schema name=minimal
[junit4] 2> 2326762 INFO (qtp1242711413-40198) [n:127.0.0.1:39999_solr c:collection1 s:shard2 r:core_node8 x:collection1_shard2_replica_n7 ] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 2326762 INFO (qtp1242711413-40198) [n:127.0.0.1:39999_solr c:collection1 s:shard2 r:core_node8 x:collection1_shard2_replica_n7 ] o.a.s.c.CoreContainer Creating SolrCore 'collection1_shard2_replica_n7' using configuration from configset config, trusted=true
[junit4] 2> 2326763 INFO (qtp1242711413-40198) [n:127.0.0.1:39999_solr c:collection1 s:shard2 r:core_node8 x:collection1_shard2_replica_n7 ] o.a.s.c.SolrCore [[collection1_shard2_replica_n7] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-BadApples-Tests-8.x/solr/build/solr-core/test/J1/temp/solr.servlet.HttpSolrCallGetCoreTest_96E9A77C44EE6AA7-001/tempDir-001/node1/collection1_shard2_replica_n7], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-BadApples-Tests-8.x/solr/build/solr-core/test/J1/temp/solr.servlet.HttpSolrCallGetCoreTest_96E9A77C44EE6AA7-001/tempDir-001/node1/collection1_shard2_replica_n7/data/]
[junit4] 2> 2326870 INFO (qtp1242711413-40238) [n:127.0.0.1:39999_solr c:collection1 s:shard3 r:core_node12 x:collection1_shard3_replica_n11 ] o.a.s.s.IndexSchema Schema name=minimal
[junit4] 2> 2326870 INFO (qtp1242711413-40238) [n:127.0.0.1:39999_solr c:collection1 s:shard3 r:core_node12 x:collection1_shard3_replica_n11 ] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 2326870 INFO (qtp1242711413-40238) [n:127.0.0.1:39999_solr c:collection1 s:shard3 r:core_node12 x:collection1_shard3_replica_n11 ] o.a.s.c.CoreContainer Creating SolrCore 'collection1_shard3_replica_n11' using configuration from configset config, trusted=true
[junit4] 2> 2326871 INFO (qtp1242711413-40238) [n:127.0.0.1:39999_solr c:collection1 s:shard3 r:core_node12 x:collection1_shard3_replica_n11 ] o.a.s.c.SolrCore [[collection1_shard3_replica_n11] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-BadApples-Tests-8.x/solr/build/solr-core/test/J1/temp/solr.servlet.HttpSolrCallGetCoreTest_96E9A77C44EE6AA7-001/tempDir-001/node1/collection1_shard3_replica_n11], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-BadApples-Tests-8.x/solr/build/solr-core/test/J1/temp/solr.servlet.HttpSolrCallGetCoreTest_96E9A77C44EE6AA7-001/tempDir-001/node1/collection1_shard3_replica_n11/data/]
[junit4] 2> 2326932 INFO (qtp1242711413-40195) [n:127.0.0.1:39999_solr c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 2326932 INFO (qtp1242711413-40195) [n:127.0.0.1:39999_solr c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 2326935 INFO (qtp1242711413-40199) [n:127.0.0.1:39999_solr c:collection1 s:shard1 r:core_node4 x:collection1_shard1_replica_n3 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 2326935 INFO (qtp1242711413-40199) [n:127.0.0.1:39999_solr c:collection1 s:shard1 r:core_node4 x:collection1_shard1_replica_n3 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 2326935 INFO (qtp1242711413-40195) [n:127.0.0.1:39999_solr c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 2326935 INFO (qtp1242711413-40195) [n:127.0.0.1:39999_solr c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 2326936 INFO (qtp1242711413-40195) [n:127.0.0.1:39999_solr c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1 ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/config
[junit4] 2> 2326936 INFO (qtp1242711413-40199) [n:127.0.0.1:39999_solr c:collection1 s:shard1 r:core_node4 x:collection1_shard1_replica_n3 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 2326936 INFO (qtp1242711413-40199) [n:127.0.0.1:39999_solr c:collection1 s:shard1 r:core_node4 x:collection1_shard1_replica_n3 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 2326936 INFO (qtp1242711413-40195) [n:127.0.0.1:39999_solr c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1 ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/config
[junit4] 2> 2326937 INFO (qtp1242711413-40195) [n:127.0.0.1:39999_solr c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms
[junit4] 2> 2326937 INFO (qtp1242711413-40195) [n:127.0.0.1:39999_solr c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1693657739383799808
[junit4] 2> 2326943 INFO (qtp1242711413-40237) [n:127.0.0.1:39999_solr c:collection1 s:shard2 r:core_node6 x:collection1_shard2_replica_n5 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 2326943 INFO (qtp1242711413-40237) [n:127.0.0.1:39999_solr c:collection1 s:shard2 r:core_node6 x:collection1_shard2_replica_n5 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 2326944 INFO (qtp1242711413-40237) [n:127.0.0.1:39999_solr c:collection1 s:shard2 r:core_node6 x:collection1_shard2_replica_n5 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 2326944 INFO (qtp1242711413-40237) [n:127.0.0.1:39999_solr c:collection1 s:shard2 r:core_node6 x:collection1_shard2_replica_n5 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 2326944 INFO (searcherExecutor-23554-thread-1-processing-n:127.0.0.1:39999_solr x:collection1_shard1_replica_n1 c:collection1 s:shard1 r:core_node2) [n:127.0.0.1:39999_solr c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1 ] o.a.s.c.SolrCore [collection1_shard1_replica_n1] Registered new searcher autowarm time: 0 ms
[junit4] 2> 2326946 INFO (qtp1242711413-40237) [n:127.0.0.1:39999_solr c:collection1 s:shard2 r:core_node6 x:collection1_shard2_replica_n5 ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/config
[junit4] 2> 2326946 INFO (qtp1242711413-40199) [n:127.0.0.1:39999_solr c:collection1 s:shard1 r:core_node4 x:collection1_shard1_replica_n3 ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/config
[junit4] 2> 2326960 INFO (qtp1242711413-40237) [n:127.0.0.1:39999_solr c:collection1 s:shard2 r:core_node6 x:collection1_shard2_replica_n5 ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/config
[junit4] 2> 2326960 INFO (qtp1242711413-40237) [n:127.0.0.1:39999_solr c:collection1 s:shard2 r:core_node6 x:collection1_shard2_replica_n5 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms
[junit4] 2> 2326960 INFO (qtp1242711413-40199) [n:127.0.0.1:39999_solr c:collection1 s:shard1 r:core_node4 x:collection1_shard1_replica_n3 ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/config
[junit4] 2> 2326960 INFO (qtp1242711413-40199) [n:127.0.0.1:39999_solr c:collection1 s:shard1 r:core_node4 x:collection1_shard1_replica_n3 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms
[junit4] 2> 2326960 INFO (qtp1242711413-40199) [n:127.0.0.1:39999_solr c:collection1 s:shard1 r:core_node4 x:collection1_shard1_replica_n3 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1693657739407917056
[junit4] 2> 2326960 INFO (qtp1242711413-40237) [n:127.0.0.1:39999_solr c:collection1 s:shard2 r:core_node6 x:collection1_shard2_replica_n5 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1693657739407917056
[junit4] 2> 2326963 INFO (searcherExecutor-23560-thread-1-processing-n:127.0.0.1:39999_solr x:collection1_shard2_replica_n5 c:collection1 s:shard2 r:core_node6) [n:127.0.0.1:39999_solr c:collection1 s:shard2 r:core_node6 x:collection1_shard2_replica_n5 ] o.a.s.c.SolrCore [collection1_shard2_replica_n5] Registered new searcher autowarm time: 0 ms
[junit4] 2> 2326966 INFO (searcherExecutor-23556-thread-1-processing-n:127.0.0.1:39999_solr x:collection1_shard1_replica_n3 c:collection1 s:shard1 r:core_node4) [n:127.0.0.1:39999_solr c:collection1 s:shard1 r:core_node4 x:collection1_shard1_replica_n3 ] o.a.s.c.SolrCore [collection1_shard1_replica_n3] Registered new searcher autowarm time: 0 ms
[junit4] 2> 2326972 INFO (qtp1242711413-40195) [n:127.0.0.1:39999_solr c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/collection1/terms/shard1 to Terms{values={core_node2=0}, version=0}
[junit4] 2> 2326972 INFO (qtp1242711413-40195) [n:127.0.0.1:39999_solr c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/collection1/leaders/shard1
[junit4] 2> 2326974 INFO (qtp1242711413-40199) [n:127.0.0.1:39999_solr c:collection1 s:shard1 r:core_node4 x:collection1_shard1_replica_n3 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/collection1/terms/shard1 to Terms{values={core_node2=0, core_node4=0}, version=1}
[junit4] 2> 2326974 INFO (qtp1242711413-40199) [n:127.0.0.1:39999_solr c:collection1 s:shard1 r:core_node4 x:collection1_shard1_replica_n3 ] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/collection1/leaders/shard1
[junit4] 2> 2326979 INFO (qtp1242711413-40197) [n:127.0.0.1:39999_solr c:collection1 s:shard3 r:core_node10 x:collection1_shard3_replica_n9 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 2326979 INFO (qtp1242711413-40197) [n:127.0.0.1:39999_solr c:collection1 s:shard3 r:core_node10 x:collection1_shard3_replica_n9 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 2326980 INFO (qtp1242711413-40197) [n:127.0.0.1:39999_solr c:collection1 s:shard3 r:core_node10 x:collection1_shard3_replica_n9 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 2326980 INFO (qtp1242711413-40197) [n:127.0.0.1:39999_solr c:collection1 s:shard3 r:core_node10 x:collection1_shard3_replica_n9 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 2326982 INFO (qtp1242711413-40197) [n:127.0.0.1:39999_solr c:collection1 s:shard3 r:core_node10 x:collection1_shard3_replica_n9 ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/config
[junit4] 2> 2326983 INFO (qtp1242711413-40197) [n:127.0.0.1:39999_solr c:collection1 s:shard3 r:core_node10 x:collection1_shard3_replica_n9 ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/config
[junit4] 2> 2326983 INFO (qtp1242711413-40197) [n:127.0.0.1:39999_solr c:collection1 s:shard3 r:core_node10 x:collection1_shard3_replica_n9 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms
[junit4] 2> 2326983 INFO (qtp1242711413-40197) [n:127.0.0.1:39999_solr c:collection1 s:shard3 r:core_node10 x:collection1_shard3_replica_n9 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1693657739432034304
[junit4] 2> 2326984 INFO (qtp1242711413-40237) [n:127.0.0.1:39999_solr c:collection1 s:shard2 r:core_node6 x:collection1_shard2_replica_n5 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/collection1/terms/shard2 to Terms{values={core_node6=0}, version=0}
[junit4] 2> 2326984 INFO (qtp1242711413-40237) [n:127.0.0.1:39999_solr c:collection1 s:shard2 r:core_node6 x:collection1_shard2_replica_n5 ] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/collection1/leaders/shard2
[junit4] 2> 2326985 INFO (qtp1242711413-40198) [n:127.0.0.1:39999_solr c:collection1 s:shard2 r:core_node8 x:collection1_shard2_replica_n7 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 2326985 INFO (qtp1242711413-40198) [n:127.0.0.1:39999_solr c:collection1 s:shard2 r:core_node8 x:collection1_shard2_replica_n7 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 2326986 INFO (qtp1242711413-40198) [n:127.0.0.1:39999_solr c:collection1 s:shard2 r:core_node8 x:collection1_shard2_replica_n7 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 2326986 INFO (qtp1242711413-40198) [n:127.0.0.1:39999_solr c:collection1 s:shard2 r:core_node8 x:collection1_shard2_replica_n7 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 2327027 INFO (qtp1242711413-40195) [n:127.0.0.1:39999_solr c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 2327027 INFO (qtp1242711413-40195) [n:127.0.0.1:39999_solr c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 2327028 INFO (qtp1242711413-40195) [n:127.0.0.1:39999_solr c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1 ] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:39999/solr/collection1_shard1_replica_n1/
[junit4] 2> 2327038 INFO (qtp1242711413-40238) [n:127.0.0.1:39999_solr c:collection1 s:shard3 r:core_node12 x:collection1_shard3_replica_n11 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 2327038 INFO (qtp1242711413-40238) [n:127.0.0.1:39999_solr c:collection1 s:shard3 r:core_node12 x:collection1_shard3_replica_n11 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 2327039 INFO (qtp1242711413-40238) [n:127.0.0.1:39999_solr c:collection1 s:shard3 r:core_node12 x:collection1_shard3_replica_n11 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 2327039 INFO (qtp1242711413-40238) [n:127.0.0.1:39999_solr c:collection1 s:shard3 r:core_node12 x:collection1_shard3_replica_n11 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 2327040 INFO (qtp1242711413-40195) [n:127.0.0.1:39999_solr c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1 ] o.a.s.u.PeerSync PeerSync: core=collection1_shard1_replica_n1 url=http://127.0.0.1:39999/solr START replicas=[http://127.0.0.1:39999/solr/collection1_shard1_replica_n3/] nUpdates=100
[junit4] 2> 2327040 INFO (qtp1242711413-40198) [n:127.0.0.1:39999_solr c:collection1 s:shard2 r:core_node8 x:collection1_shard2_replica_n7 ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/config
[junit4] 2> 2327040 INFO (qtp1242711413-40195) [n:127.0.0.1:39999_solr c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1 ] o.a.s.u.PeerSync PeerSync: core=collection1_shard1_replica_n1 url=http://127.0.0.1:39999/solr DONE. We have no versions. sync failed.
[junit4] 2> 2327041 INFO (qtp1242711413-40198) [n:127.0.0.1:39999_solr c:collection1 s:shard2 r:core_node8 x:collection1_shard2_replica_n7 ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/config
[junit4] 2> 2327041 INFO (searcherExecutor-23558-thread-1-processing-n:127.0.0.1:39999_solr x:collection1_shard3_replica_n9 c:collection1 s:shard3 r:core_node10) [n:127.0.0.1:39999_solr c:collection1 s:shard3 r:core_node10 x:collection1_shard3_replica_n9 ] o.a.s.c.SolrCore [collection1_shard3_replica_n9] Registered new searcher autowarm time: 0 ms
[junit4] 2> 2327041 INFO (qtp1242711413-40198) [n:127.0.0.1:39999_solr c:collection1 s:shard2 r:core_node8 x:collection1_shard2_replica_n7 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms
[junit4] 2> 2327041 INFO (qtp1242711413-40198) [n:127.0.0.1:39999_solr c:collection1 s:shard2 r:core_node8 x:collection1_shard2_replica_n7 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1693657739492851712
[junit4] 2> 2327042 INFO (qtp1242711413-40254) [n:127.0.0.1:39999_solr c:collection1 s:shard1 r:core_node4 x:collection1_shard1_replica_n3 ] o.a.s.c.S.Request [collection1_shard1_replica_n3] webapp=/solr path=/get params={distrib=false&qt=/get&fingerprint=false&getVersions=100&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 2327042 INFO (qtp1242711413-40195) [n:127.0.0.1:39999_solr c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1 ] o.a.s.c.SyncStrategy Leader's attempt to sync with shard failed, moving to the next candidate
[junit4] 2> 2327042 INFO (qtp1242711413-40195) [n:127.0.0.1:39999_solr c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext We failed sync, but we have no versions - we can't sync in that case - we were active before, so become leader anyway
[junit4] 2> 2327042 INFO (qtp1242711413-40195) [n:127.0.0.1:39999_solr c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/collection1/leaders/shard1/leader after winning as /collections/collection1/leader_elect/shard1/election/73122165950578693-core_node2-n_0000000000
[junit4] 2> 2327044 INFO (searcherExecutor-23562-thread-1-processing-n:127.0.0.1:39999_solr x:collection1_shard2_replica_n7 c:collection1 s:shard2 r:core_node8) [n:127.0.0.1:39999_solr c:collection1 s:shard2 r:core_node8 x:collection1_shard2_replica_n7 ] o.a.s.c.SolrCore [collection1_shard2_replica_n7] Registered new searcher autowarm time: 0 ms
[junit4] 2> 2327044 INFO (qtp1242711413-40238) [n:127.0.0.1:39999_solr c:collection1 s:shard3 r:core_node12 x:collection1_shard3_replica_n11 ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/config
[junit4] 2> 2327045 INFO (qtp1242711413-40238) [n:127.0.0.1:39999_solr c:collection1 s:shard3 r:core_node12 x:collection1_shard3_replica_n11 ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/config
[junit4] 2> 2327045 INFO (qtp1242711413-40238) [n:127.0.0.1:39999_solr c:collection1 s:shard3 r:core_node12 x:collection1_shard3_replica_n11 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms
[junit4] 2> 2327045 INFO (qtp1242711413-40238) [n:127.0.0.1:39999_solr c:collection1 s:shard3 r:core_node12 x:collection1_shard3_replica_n11 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1693657739497046016
[junit4] 2> 2327047 INFO (qtp1242711413-40195) [n:127.0.0.1:39999_solr c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:39999/solr/collection1_shard1_replica_n1/ shard1
[junit4] 2> 2327048 INFO (qtp1242711413-40198) [n:127.0.0.1:39999_solr c:collection1 s:shard2 r:core_node8 x:collection1_shard2_replica_n7 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/collection1/terms/shard2 to Terms{values={core_node6=0, core_node8=0}, version=1}
[junit4] 2> 2327048 INFO (qtp1242711413-40198) [n:127.0.0.1:39999_solr c:collection1 s:shard2 r:core_node8 x:collection1_shard2_replica_n7 ] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/collection1/leaders/shard2
[junit4] 2> 2327048 INFO (zkCallback-23536-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [1])
[junit4] 2> 2327048 INFO (zkCallback-23536-thread-3) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [1])
[junit4] 2> 2327049 INFO (searcherExecutor-23564-thread-1-processing-n:127.0.0.1:39999_solr x:collection1_shard3_replica_n11 c:collection1 s:shard3 r:core_node12) [n:127.0.0.1:39999_solr c:collection1 s:shard3 r:core_node12 x:collection1_shard3_replica_n11 ] o.a.s.c.SolrCore [collection1_shard3_replica_n11] Registered new searcher autowarm time: 0 ms
[junit4] 2> 2327051 INFO (qtp1242711413-40237) [n:127.0.0.1:39999_solr c:collection1 s:shard2 r:core_node6 x:collection1_shard2_replica_n5 ] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 2327051 INFO (qtp1242711413-40237) [n:127.0.0.1:39999_solr c:collection1 s:shard2 r:core_node6 x:collection1_shard2_replica_n5 ] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 2327051 INFO (qtp1242711413-40237) [n:127.0.0.1:39999_solr c:collection1 s:shard2 r:core_node6 x:collection1_shard2_replica_n5 ] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:39999/solr/collection1_shard2_replica_n5/
[junit4] 2> 2327052 INFO (qtp1242711413-40237) [n:127.0.0.1:39999_solr c:collection1 s:shard2 r:core_node6 x:collection1_shard2_replica_n5 ] o.a.s.u.PeerSync PeerSync: core=collection1_shard2_replica_n5 url=http://127.0.0.1:39999/solr START replicas=[http://127.0.0.1:39999/solr/collection1_shard2_replica_n7/] nUpdates=100
[junit4] 2> 2327052 INFO (qtp1242711413-40237) [n:127.0.0.1:39999_solr c:collection1 s:shard2 r:core_node6 x:collection1_shard2_replica_n5 ] o.a.s.u.PeerSync PeerSync: core=collection1_shard2_replica_n5 url=http://127.0.0.1:39999/solr DONE. We have no versions. sync failed.
[junit4] 2> 2327053 INFO (qtp1242711413-40254) [n:127.0.0.1:39999_solr c:collection1 s:shard2 r:core_node8 x:collection1_shard2_replica_n7 ] o.a.s.c.S.Request [collection1_shard2_replica_n7] webapp=/solr path=/get params={distrib=false&qt=/get&fingerprint=false&getVersions=100&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 2327058 INFO (qtp1242711413-40237) [n:127.0.0.1:39999_solr c:collection1 s:shard2 r:core_node6 x:collection1_shard2_replica_n5 ] o.a.s.c.SyncStrategy Leader's attempt to sync with shard failed, moving to the next candidate
[junit4] 2> 2327058 INFO (qtp1242711413-40237) [n:127.0.0.1:39999_solr c:collection1 s:shard2 r:core_node6 x:collection1_shard2_replica_n5 ] o.a.s.c.ShardLeaderElectionContext We failed sync, but we have no versions - we can't sync in that case - we were active before, so become leader anyway
[junit4] 2> 2327058 INFO (qtp1242711413-40237) [n:127.0.0.1:39999_solr c:collection1 s:shard2 r:core_node6 x:collection1_shard2_replica_n5 ] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/collection1/leaders/shard2/leader after winning as /collections/collection1/leader_elect/shard2/election/73122165950578693-core_node6-n_0000000000
[junit4] 2> 2327060 INFO (qtp1242711413-40195) [n:127.0.0.1:39999_solr c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1 ] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 2327070 INFO (qtp1242711413-40197) [n:127.0.0.1:39999_solr c:collection1 s:shard3 r:core_node10 x:collection1_shard3_replica_n9 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/collection1/terms/shard3 to Terms{values={core_node10=0}, version=0}
[junit4] 2> 2327070 INFO (qtp1242711413-40197) [n:127.0.0.1:39999_solr c:collection1 s:shard3 r:core_node10 x:collection1_shard3_replica_n9 ] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/collection1/leaders/shard3
[junit4] 2> 2327071 INFO (qtp1242711413-40238) [n:127.0.0.1:39999_solr c:collection1 s:shard3 r:core_node12 x:collection1_shard3_replica_n11 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/collection1/terms/shard3 to Terms{values={core_node10=0, core_node12=0}, version=1}
[junit4] 2> 2327071 INFO (qtp1242711413-40238) [n:127.0.0.1:39999_solr c:collection1 s:shard3 r:core_node12 x:collection1_shard3_replica_n11 ] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/collection1/leaders/shard3
[junit4] 2> 2327072 INFO (zkCallback-23536-thread-3) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [1])
[junit4] 2> 2327072 INFO (qtp1242711413-40237) [n:127.0.0.1:39999_solr c:collection1 s:shard2 r:core_node6 x:collection1_shard2_replica_n5 ] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:39999/solr/collection1_shard2_replica_n5/ shard2
[junit4] 2> 2327075 INFO (qtp1242711413-40237) [n:127.0.0.1:39999_solr c:collection1 s:shard2 r:core_node6 x:collection1_shard2_replica_n5 ] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 2327079 INFO (qtp1242711413-40195) [n:127.0.0.1:39999_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node2&collection.configName=config&newCollection=true&name=collection1_shard1_replica_n1&action=CREATE&numShards=3&collection=collection1&shard=shard1&wt=javabin&version=2&replicaType=NRT} status=0 QTime=423
[junit4] 2> 2327084 INFO (zkCallback-23536-thread-4) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [1])
[junit4] 2> 2327092 INFO (qtp1242711413-40237) [n:127.0.0.1:39999_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node6&collection.configName=config&newCollection=true&name=collection1_shard2_replica_n5&action=CREATE&numShards=3&collection=collection1&shard=shard2&wt=javabin&version=2&replicaType=NRT} status=0 QTime=439
[junit4] 2> 2327095 INFO (qtp1242711413-40197) [n:127.0.0.1:39999_solr c:collection1 s:shard3 r:core_node10 x:collection1_shard3_replica_n9 ] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 2327095 INFO (qtp1242711413-40197) [n:127.0.0.1:39999_solr c:collection1 s:shard3 r:core_node10 x:collection1_shard3_replica_n9 ] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 2327095 INFO (qtp1242711413-40197) [n:127.0.0.1:39999_solr c:collection1 s:shard3 r:core_node10 x:collection1_shard3_replica_n9 ] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:39999/solr/collection1_shard3_replica_n9/
[junit4] 2> 2327095 INFO (qtp1242711413-40197) [n:127.0.0.1:39999_solr c:collection1 s:shard3 r:core_node10 x:collection1_shard3_replica_n9 ] o.a.s.u.PeerSync PeerSync: core=collection1_shard3_replica_n9 url=http://127.0.0.1:39999/solr START replicas=[http://127.0.0.1:39999/solr/collection1_shard3_replica_n11/] nUpdates=100
[junit4] 2> 2327095 INFO (qtp1242711413-40197) [n:127.0.0.1:39999_solr c:collection1 s:shard3 r:core_node10 x:collection1_shard3_replica_n9 ] o.a.s.u.PeerSync PeerSync: core=collection1_shard3_replica_n9 url=http://127.0.0.1:39999/solr DONE. We have no versions. sync failed.
[junit4] 2> 2327096 INFO (qtp1242711413-40254) [n:127.0.0.1:39999_solr c:collection1 s:shard3 r:core_node12 x:collection1_shard3_replica_n11 ] o.a.s.c.S.Request [collection1_shard3_replica_n11] webapp=/solr path=/get params={distrib=false&qt=/get&fingerprint=false&getVersions=100&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 2327096 INFO (qtp1242711413-40197) [n:127.0.0.1:39999_solr c:collection1 s:shard3 r:core_node10 x:collection1_shard3_replica_n9 ] o.a.s.c.SyncStrategy Leader's attempt to sync with shard failed, moving to the next candidate
[junit4] 2> 2327096 INFO (qtp1242711413-40197) [n:127.0.0.1:39999_solr c:collection1 s:shard3 r:core_node10 x:collection1_shard3_replica_n9 ] o.a.s.c.ShardLeaderElectionContext We failed sync, but we have no versions - we can't sync in that case - we were active before, so become leader anyway
[junit4] 2> 2327096 INFO (qtp1242711413-40197) [n:127.0.0.1:39999_solr c:collection1 s:shard3 r:core_node10 x:collection1_shard3_replica_n9 ] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/collection1/leaders/shard3/leader after winning as /collections/collection1/leader_elect/shard3/election/73122165950578693-core_node10-n_0000000000
[junit4] 2> 2327098 INFO (qtp1242711413-40197) [n:127.0.0.1:39999_solr c:collection1 s:shard3 r:core_node10 x:collection1_shard3_replica_n9 ] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:39999/solr/collection1_shard3_replica_n9/ shard3
[junit4] 2> 2327098 INFO (zkCallback-23536-thread-4) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [1])
[junit4] 2> 2327099 INFO (qtp1242711413-40197) [n:127.0.0.1:39999_solr c:collection1 s:shard3 r:core_node10 x:collection1_shard3_replica_n9 ] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 2327101 INFO (qtp1242711413-40197) [n:127.0.0.1:39999_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node10&collection.configName=config&newCollection=true&name=collection1_shard3_replica_n9&action=CREATE&numShards=3&collection=collection1&shard=shard3&wt=javabin&version=2&replicaType=NRT} status=0 QTime=445
[junit4] 2> 2327102 INFO (zkCallback-23536-thread-4) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [1])
[junit4] 2> 2327985 INFO (zkCallback-23536-thread-4) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [1])
[junit4] 2> 2327988 INFO (qtp1242711413-40199) [n:127.0.0.1:39999_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node4&collection.configName=config&newCollection=true&name=collection1_shard1_replica_n3&action=CREATE&numShards=3&collection=collection1&shard=shard1&wt=javabin&version=2&replicaType=NRT} status=0 QTime=1332
[junit4] 2> 2328056 INFO (zkCallback-23536-thread-4) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [1])
[junit4] 2> 2328057 INFO (qtp1242711413-40198) [n:127.0.0.1:39999_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node8&collection.configName=config&newCollection=true&name=collection1_shard2_replica_n7&action=CREATE&numShards=3&collection=collection1&shard=shard2&wt=javabin&version=2&replicaType=NRT} status=0 QTime=1403
[junit4] 2> 2416657 WARN (SUITE-HttpSolrCallGetCoreTest-seed#[96E9A77C44EE6AA7]-worker-SendThread(127.0.0.1:34977)) [ ] o.a.z.ClientCnxn An exception was thrown while closing send thread for session 0x103c838f6b10006.
[junit4] 2> => EndOfStreamException: Unable to read additional data from server sessionid 0x103c838f6b10006, likely server has closed socket
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:77)
[junit4] 2> org.apache.zookeeper.ClientCnxn$EndOfStreamException: Unable to read additional data from server sessionid 0x103c838f6b10006, likely server has closed socket
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:77) ~[zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doTransport(ClientCnxnSocketNIO.java:350) ~[zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1275) [zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> 2446741 ERROR (OverseerThreadFactory-23544-thread-1-processing-n:127.0.0.1:39999_solr) [n:127.0.0.1:39999_solr ] o.a.s.c.a.c.OverseerCollectionMessageHandler Error from shard: http://127.0.0.1:39999/solr
[junit4] 2> => org.apache.solr.client.solrj.SolrServerException: Timeout occurred while waiting response from server at: http://127.0.0.1:39999/solr
[junit4] 2> at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:692)
[junit4] 2> org.apache.solr.client.solrj.SolrServerException: Timeout occurred while waiting response from server at: http://127.0.0.1:39999/solr
[junit4] 2> at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:692) ~[java/:?]
[junit4] 2> at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:266) ~[java/:?]
[junit4] 2> at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:248) ~[java/:?]
[junit4] 2> at org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1290) ~[java/:?]
[junit4] 2> at org.apache.solr.handler.component.HttpShardHandlerFactory$1.request(HttpShardHandlerFactory.java:169) ~[java/:?]
[junit4] 2> at org.apache.solr.handler.component.ShardRequestor.call(ShardRequestor.java:130) ~[java/:?]
[junit4] 2> at org.apache.solr.handler.component.ShardRequestor.call(ShardRequestor.java:41) ~[java/:?]
[junit4] 2> at java.util.concurrent.FutureTask.run(FutureTask.java:266) ~[?:1.8.0_281]
[junit4] 2> at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511) ~[?:1.8.0_281]
[junit4] 2> at java.util.concurrent.FutureTask.run(FutureTask.java:266) ~[?:1.8.0_281]
[junit4] 2> at com.codahale.metrics.InstrumentedExecutorService$InstrumentedRunnable.run(InstrumentedExecutorService.java:180) ~[metrics-core-4.1.5.jar:4.1.5]
[junit4] 2> at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:218) ~[java/:?]
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149) [?:1.8.0_281]
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) [?:1.8.0_281]
[junit4] 2> at java.lang.Thread.run(Thread.java:748) [?:1.8.0_281]
[junit4] 2> Caused by: java.net.SocketTimeoutException: Read timed out
[junit4] 2> at java.net.SocketInputStream.socketRead0(Native Method) ~[?:1.8.0_281]
[junit4] 2> at java.net.SocketInputStream.socketRead(SocketInputStream.java:116) ~[?:1.8.0_281]
[junit4] 2> at java.net.SocketInputStream.read(SocketInputStream.java:171) ~[?:1.8.0_281]
[junit4] 2> at java.net.SocketInputStream.read(SocketInputStream.java:141) ~[?:1.8.0_281]
[junit4] 2> at org.apache.http.impl.io.SessionInputBufferImpl.streamRead(SessionInputBufferImpl.java:137) ~[httpcore-4.4.13.jar:4.4.13]
[junit4] 2> at org.apache.http.impl.io.SessionInputBufferImpl.fillBuffer(SessionInputBufferImpl.java:153) ~[httpcore-4.4.13.jar:4.4.13]
[junit4] 2> at org.apache.http.impl.io.SessionInputBufferImpl.readLine(SessionInputBufferImpl.java:280) ~[httpcore-4.4.13.jar:4.4.13]
[junit4] 2> at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:138) ~[httpclient-4.5.12.jar:4.5.12]
[junit4] 2> at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:56) ~[httpclient-4.5.12.jar:4.5.12]
[junit4] 2> at org.apache.http.impl.io.AbstractMessageParser.parse(AbstractMessageParser.java:259) ~[httpcore-4.4.13.jar:4.4.13]
[junit4] 2> at org.apache.http.impl.DefaultBHttpClientConnection.receiveResponseHeader(DefaultBHttpClientConnection.java:163) ~[httpcore-4.4.13.jar:4.4.13]
[junit4] 2> at org.apache.http.impl.conn.CPoolProxy.receiveResponseHeader(CPoolProxy.java:157) ~[httpclient-4.5.12.jar:4.5.12]
[junit4] 2> at org.apache.http.protocol.HttpRequestExecutor.doReceiveResponse(HttpRequestExecutor.java:273) ~[httpcore-4.4.13.jar:4.4.13]
[junit4] 2> at org.apache.http.protocol.HttpRequestExecutor.execute(HttpRequestExecutor.java:125) ~[httpcore-4.4.13.jar:4.4.13]
[junit4] 2> at org.apache.solr.util.stats.InstrumentedHttpRequestExecutor.execute(InstrumentedHttpRequestExecutor.java:120) ~[java/:?]
[junit4] 2> at org.apache.http.impl.execchain.MainClientExec.execute(MainClientExec.java:272) ~[httpclient-4.5.12.jar:4.5.12]
[junit4] 2> at org.apache.http.impl.execchain.ProtocolExec.execute(ProtocolExec.java:186) ~[httpclient-4.5.12.jar:4.5.12]
[junit4] 2> at org.apache.http.impl.execchain.RetryExec.execute(RetryExec.java:89) ~[httpclient-4.5.12.jar:4.5.12]
[junit4] 2> at org.apache.http.impl.execchain.RedirectExec.execute(RedirectExec.java:110) ~[httpclient-4.5.12.jar:4.5.12]
[junit4] 2> at org.apache.http.impl.client.InternalHttpClient.doExecute(InternalHttpClient.java:185) ~[httpclient-4.5.12.jar:4.5.12]
[junit4] 2> at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:83) ~[httpclient-4.5.12.jar:4.5.12]
[junit4] 2> at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:56) ~[httpclient-4.5.12.jar:4.5.12]
[junit4] 2> at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:571) ~[java/:?]
[junit4] 2> ... 14 more
[junit4] 2> 2506599 ERROR (qtp1242711413-40196) [n:127.0.0.1:39999_solr ] o.a.s.h.RequestHandlerBase org.apache.solr.common.SolrException: create the collection time out:180s
[junit4] 2> at org.apache.solr.handler.admin.CollectionsHandler.sendToOCPQueue(CollectionsHandler.java:394)
[junit4] 2> at org.apache.solr.handler.admin.CollectionsHandler.invokeAction(CollectionsHandler.java:299)
[junit4] 2> at org.apache.solr.handler.admin.CollectionsHandler.handleRequestBody(CollectionsHandler.java:257)
[junit4] 2> at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:216)
[junit4] 2> at org.apache.solr.servlet.HttpSolrCall.handleAdmin(HttpSolrCall.java:836)
[junit4] 2> at org.apache.solr.servlet.HttpSolrCall.handleAdminRequest(HttpSolrCall.java:800)
[junit4] 2> at org.apache.solr.servlet.HttpSolrCall.call(HttpSolrCall.java:545)
[junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:427)
[junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:357)
[junit4] 2> at org.eclipse.jetty.servlet.FilterHolder.doFilter(FilterHolder.java:193)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$Chain.doFilter(ServletHandler.java:1601)
[junit4] 2> at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:166)
[junit4] 2> at org.eclipse.jetty.servlet.FilterHolder.doFilter(FilterHolder.java:193)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$Chain.doFilter(ServletHandler.java:1601)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:548)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:233)
[junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:1612)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:233)
[junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1434)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:188)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:501)
[junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:1582)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:186)
[junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1349)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141)
[junit4] 2> at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:127)
[junit4] 2> at org.eclipse.jetty.rewrite.handler.RewriteHandler.handle(RewriteHandler.java:322)
[junit4] 2> at org.eclipse.jetty.server.handler.gzip.GzipHandler.handle(GzipHandler.java:766)
[junit4] 2> at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:127)
[junit4] 2> at org.eclipse.jetty.server.Server.handle(Server.java:516)
[junit4] 2> at org.eclipse.jetty.server.HttpChannel.lambda$handle$1(HttpChannel.java:383)
[junit4] 2> at org.eclipse.jetty.server.HttpChannel.dispatch(HttpChannel.java:556)
[junit4] 2> at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:375)
[junit4] 2> at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:273)
[junit4] 2> at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:311)
[junit4] 2> at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:105)
[junit4] 2> at org.eclipse.jetty.io.ChannelEndPoint$1.run(ChannelEndPoint.java:104)
[junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:773)
[junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool$Runner.run(QueuedThreadPool.java:905)
[junit4] 2> at java.lang.Thread.run(Thread.java:748)
[junit4] 2>
[junit4] 2> 2506599 INFO (qtp1242711413-40196) [n:127.0.0.1:39999_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={collection.configName=config&maxShardsPerNode=6&name=collection1&nrtReplicas=2&action=CREATE&numShards=3&perReplicaState=true&wt=javabin&version=2} status=500 QTime=180009
[junit4] 2> 2506599 ERROR (qtp1242711413-40196) [n:127.0.0.1:39999_solr ] o.a.s.s.HttpSolrCall org.apache.solr.common.SolrException: create the collection time out:180s
[junit4] 2> at org.apache.solr.handler.admin.CollectionsHandler.sendToOCPQueue(CollectionsHandler.java:394)
[junit4] 2> at org.apache.solr.handler.admin.CollectionsHandler.invokeAction(CollectionsHandler.java:299)
[junit4] 2> at org.apache.solr.handler.admin.CollectionsHandler.handleRequestBody(CollectionsHandler.java:257)
[junit4] 2> at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:216)
[junit4] 2> at org.apache.solr.servlet.HttpSolrCall.handleAdmin(HttpSolrCall.java:836)
[junit4] 2> at org.apache.solr.servlet.HttpSolrCall.handleAdminRequest(HttpSolrCall.java:800)
[junit4] 2> at org.apache.solr.servlet.HttpSolrCall.call(HttpSolrCall.java:545)
[junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:427)
[junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:357)
[junit4] 2> at org.eclipse.jetty.servlet.FilterHolder.doFilter(FilterHolder.java:193)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$Chain.doFilter(ServletHandler.java:1601)
[junit4] 2> at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:166)
[junit4] 2> at org.eclipse.jetty.servlet.FilterHolder.doFilter(FilterHolder.java:193)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$Chain.doFilter(ServletHandler.java:1601)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:548)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:233)
[junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:1612)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:233)
[junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1434)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:188)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:501)
[junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:1582)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:186)
[junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1349)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141)
[junit4] 2> at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:127)
[junit4] 2> at org.eclipse.jetty.rewrite.handler.RewriteHandler.handle(RewriteHandler.java:322)
[junit4] 2> at org.eclipse.jetty.server.handler.gzip.GzipHandler.handle(GzipHandler.java:766)
[junit4] 2> at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:127)
[junit4] 2> at org.eclipse.jetty.server.Server.handle(Server.java:516)
[junit4] 2> at org.eclipse.jetty.server.HttpChannel.lambda$handle$1(HttpChannel.java:383)
[junit4] 2> at org.eclipse.jetty.server.HttpChannel.dispatch(HttpChannel.java:556)
[junit4] 2> at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:375)
[junit4] 2> at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:273)
[junit4] 2> at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:311)
[junit4] 2> at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:105)
[junit4] 2> at org.eclipse.jetty.io.ChannelEndPoint$1.run(ChannelEndPoint.java:104)
[junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:773)
[junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool$Runner.run(QueuedThreadPool.java:905)
[junit4] 2> at java.lang.Thread.run(Thread.java:748)
[junit4] 2>
[junit4] 2> 2506602 INFO (jetty-closer-23589-thread-1) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1412491196
[junit4] 2> 2506602 INFO (jetty-closer-23589-thread-1) [ ] o.a.s.c.ZkController Remove node as live in ZooKeeper:/live_nodes/127.0.0.1:39999_solr
[junit4] 2> 2506602 INFO (jetty-closer-23589-thread-1) [ ] o.a.s.c.ZkController Publish this node as DOWN...
[junit4] 2> 2506602 INFO (jetty-closer-23589-thread-1) [ ] o.a.s.c.ZkController Publish node=127.0.0.1:39999_solr as DOWN
[junit4] 2> 2506604 INFO (coreCloseExecutor-23593-thread-1) [n:127.0.0.1:39999_solr ] o.a.s.c.SolrCore [collection1_shard1_replica_n1] CLOSING SolrCore org.apache.solr.core.SolrCore@77e71e79
[junit4] 2> 2506604 INFO (coreCloseExecutor-23593-thread-1) [n:127.0.0.1:39999_solr ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1.shard1.replica_n1 tag=SolrCore@77e71e79
[junit4] 2> 2506604 INFO (coreCloseExecutor-23593-thread-1) [n:127.0.0.1:39999_solr ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.collection1.shard1.leader tag=SolrCore@77e71e79
[junit4] 2> 2506604 INFO (coreCloseExecutor-23593-thread-2) [n:127.0.0.1:39999_solr ] o.a.s.c.SolrCore [collection1_shard1_replica_n3] CLOSING SolrCore org.apache.solr.core.SolrCore@494ff191
[junit4] 2> 2506604 INFO (coreCloseExecutor-23593-thread-2) [n:127.0.0.1:39999_solr ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1.shard1.replica_n3 tag=SolrCore@494ff191
[junit4] 2> 2506604 INFO (coreCloseExecutor-23593-thread-2) [n:127.0.0.1:39999_solr ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.collection1.shard1.leader tag=SolrCore@494ff191
[junit4] 2> 2506605 INFO (coreCloseExecutor-23593-thread-3) [n:127.0.0.1:39999_solr ] o.a.s.c.SolrCore [collection1_shard2_replica_n5] CLOSING SolrCore org.apache.solr.core.SolrCore@c3df79
[junit4] 2> 2506605 INFO (coreCloseExecutor-23593-thread-3) [n:127.0.0.1:39999_solr ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1.shard2.replica_n5 tag=SolrCore@c3df79
[junit4] 2> 2506605 INFO (coreCloseExecutor-23593-thread-3) [n:127.0.0.1:39999_solr ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.collection1.shard2.leader tag=SolrCore@c3df79
[junit4] 2> 2506605 INFO (coreCloseExecutor-23593-thread-4) [n:127.0.0.1:39999_solr ] o.a.s.c.SolrCore [collection1_shard3_replica_n9] CLOSING SolrCore org.apache.solr.core.SolrCore@3ee745d8
[junit4] 2> 2506605 INFO (coreCloseExecutor-23593-thread-4) [n:127.0.0.1:39999_solr ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1.shard3.replica_n9 tag=SolrCore@3ee745d8
[junit4] 2> 2506605 INFO (coreCloseExecutor-23593-thread-4) [n:127.0.0.1:39999_solr ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.collection1.shard3.leader tag=SolrCore@3ee745d8
[junit4] 2> 2506605 INFO (zkCallback-23536-thread-6) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [1])
[junit4] 2> 2506605 INFO (coreCloseExecutor-23593-thread-5) [n:127.0.0.1:39999_solr ] o.a.s.c.SolrCore [collection1_shard2_replica_n7] CLOSING SolrCore org.apache.solr.core.SolrCore@537c238f
[junit4] 2> 2506605 INFO (coreCloseExecutor-23593-thread-5) [n:127.0.0.1:39999_solr ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1.shard2.replica_n7 tag=SolrCore@537c238f
[junit4] 2> 2506605 INFO (coreCloseExecutor-23593-thread-5) [n:127.0.0.1:39999_solr ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.collection1.shard2.leader tag=SolrCore@537c238f
[junit4] 2> 2506607 INFO (coreCloseExecutor-23593-thread-1) [n:127.0.0.1:39999_solr ] o.a.s.u.DirectUpdateHandler2 Committing on IndexWriter.close() ... SKIPPED (unnecessary).
[junit4] 2> 2506607 INFO (coreCloseExecutor-23593-thread-2) [n:127.0.0.1:39999_solr ] o.a.s.u.DirectUpdateHandler2 Committing on IndexWriter.close() ... SKIPPED (unnecessary).
[junit4] 2> 2506607 INFO (coreCloseExecutor-23593-thread-4) [n:127.0.0.1:39999_solr ] o.a.s.u.DirectUpdateHandler2 Committing on IndexWriter.close() ... SKIPPED (unnecessary).
[junit4] 2> 2506607 INFO (coreCloseExecutor-23593-thread-5) [n:127.0.0.1:39999_solr ] o.a.s.u.DirectUpdateHandler2 Committing on IndexWriter.close() ... SKIPPED (unnecessary).
[junit4] 2> 2506607 INFO (coreCloseExecutor-23593-thread-3) [n:127.0.0.1:39999_solr ] o.a.s.u.DirectUpdateHandler2 Committing on IndexWriter.close() ... SKIPPED (unnecessary).
[junit4] 2> 2506608 INFO (coreCloseExecutor-23593-thread-6) [n:127.0.0.1:39999_solr ] o.a.s.c.SolrCore [collection1_shard3_replica_n11] CLOSING SolrCore org.apache.solr.core.SolrCore@4dd6cd60
[junit4] 2> 2506608 INFO (coreCloseExecutor-23593-thread-6) [n:127.0.0.1:39999_solr ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1.shard3.replica_n11 tag=SolrCore@4dd6cd60
[junit4] 2> 2506608 INFO (coreCloseExecutor-23593-thread-6) [n:127.0.0.1:39999_solr ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.collection1.shard3.leader tag=SolrCore@4dd6cd60
[junit4] 2> 2506614 INFO (coreCloseExecutor-23593-thread-6) [n:127.0.0.1:39999_solr ] o.a.s.u.DirectUpdateHandler2 Committing on IndexWriter.close() ... SKIPPED (unnecessary).
[junit4] 2> 2506616 INFO (jetty-closer-23589-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node tag=null
[junit4] 2> 2506616 INFO (jetty-closer-23589-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm tag=null
[junit4] 2> 2506616 INFO (jetty-closer-23589-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty tag=null
[junit4] 2> 2506616 INFO (jetty-closer-23589-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster tag=null
[junit4] 2> 2506616 INFO (closeThreadPool-23595-thread-2) [ ] o.a.s.c.Overseer Overseer (id=73122165950578693-127.0.0.1:39999_solr-n_0000000000) closing
[junit4] 2> 2506617 INFO (OverseerStateUpdate-73122165950578693-127.0.0.1:39999_solr-n_0000000000) [n:127.0.0.1:39999_solr ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:39999_solr
[junit4] 2> 2506719 ERROR (OverseerThreadFactory-23544-thread-1-processing-n:127.0.0.1:39999_solr) [n:127.0.0.1:39999_solr ] o.a.s.c.a.c.OverseerCollectionMessageHandler Collection: collection1 operation: create failed:org.apache.solr.common.SolrException: Error fetching per-replica states
[junit4] 2> at org.apache.solr.common.cloud.PerReplicaStates.fetch(PerReplicaStates.java:142)
[junit4] 2> at org.apache.solr.cloud.api.collections.CreateCollectionCmd.call(CreateCollectionCmd.java:355)
[junit4] 2> at org.apache.solr.cloud.api.collections.OverseerCollectionMessageHandler.processMessage(OverseerCollectionMessageHandler.java:270)
[junit4] 2> at org.apache.solr.cloud.OverseerTaskProcessor$Runner.run(OverseerTaskProcessor.java:524)
[junit4] 2> at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:218)
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
[junit4] 2> at java.lang.Thread.run(Thread.java:748)
[junit4] 2> Caused by: org.apache.zookeeper.KeeperException$SessionExpiredException: KeeperErrorCode = Session expired for /collections/collection1/state.json
[junit4] 2> at org.apache.zookeeper.KeeperException.create(KeeperException.java:134)
[junit4] 2> at org.apache.zookeeper.KeeperException.create(KeeperException.java:54)
[junit4] 2> at org.apache.zookeeper.ZooKeeper.getChildren(ZooKeeper.java:2857)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient.lambda$getChildren$5(SolrZkClient.java:339)
[junit4] 2> at org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:79)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient.getChildren(SolrZkClient.java:339)
[junit4] 2> at org.apache.solr.common.cloud.PerReplicaStates.fetch(PerReplicaStates.java:139)
[junit4] 2> ... 7 more
[junit4] 2>
[junit4] 2> 2506719 ERROR (OverseerThreadFactory-23544-thread-1-processing-n:127.0.0.1:39999_solr) [n:127.0.0.1:39999_solr ] o.a.s.c.OverseerTaskProcessor :org.apache.zookeeper.KeeperException$SessionExpiredException: KeeperErrorCode = Session expired for /overseer/collection-queue-work/qnr-0000000000
[junit4] 2> at org.apache.zookeeper.KeeperException.create(KeeperException.java:134)
[junit4] 2> at org.apache.zookeeper.KeeperException.create(KeeperException.java:54)
[junit4] 2> at org.apache.zookeeper.ZooKeeper.setData(ZooKeeper.java:2561)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient.lambda$setData$7(SolrZkClient.java:363)
[junit4] 2> at org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:79)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient.setData(SolrZkClient.java:363)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient.setData(SolrZkClient.java:583)
[junit4] 2> at org.apache.solr.cloud.OverseerTaskQueue.remove(OverseerTaskQueue.java:118)
[junit4] 2> at org.apache.solr.cloud.OverseerTaskProcessor$Runner.markTaskComplete(OverseerTaskProcessor.java:601)
[junit4] 2> at org.apache.solr.cloud.OverseerTaskProcessor$Runner.run(OverseerTaskProcessor.java:550)
[junit4] 2> at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$exe
[...truncated too long message...]
stSystemCollAutoCreate, OverseerSolrResponseUnsafeSerializationTest, CloneFieldUpdateProcessorFactoryTest, TestManagedSynonymGraphFilterFactory, MoveReplicaHDFSTest, LoggingHandlerTest, SearchRateTriggerTest, LargeFieldTest, TestDistributedSearch, TestCloudDeleteByQuery, SecurityHeadersTest, TestHashPartitioner, TestLegacyBM25SimilarityFactory, TriggerEventQueueTest, TestStressLucene, TestRangeQuery, TaggingAttributeTest, HttpPartitionOnCommitTest, TestReversedWildcardFilterFactory, TestMultiValuedNumericRangeQuery, TestSubQueryTransformerDistrib, DateMathParserTest, TestCollectionAPI, SuggestComponentContextFilterQueryTest, CustomTermsComponentTest, DistributedIntervalFacetingTest, ZookeeperStatusHandlerTest, TestConfigReload, SortByFunctionTest, TestJoin, TestSchemaManager, TestFieldCollectionResource, TestBulkSchemaConcurrent, CoreAdminHandlerTest, TestSolrXml, ChaosMonkeyNothingIsSafeTest, SolrGraphiteReporterTest, PackageManagerCLITest, TestPivotHelperCode, AutoScalingHandlerTest, AnalyticsQueryTest, TestSafeXMLParsing, TestBackupRepositoryFactory, TestFastOutputStream, TestZkChroot, XMLAtomicUpdateMultivalueTest, SolrCoreMetricManagerTest, TestSolrCoreProperties, TestSweetSpotSimilarityFactory, PurgeGraphTest, TestSolrConfigHandlerCloud, LeaderTragicEventTest, TestEmbeddedSolrServerAdminHandler, TestPackages, BJQFilterAccessibleTest, TestDFRSimilarityFactory, TestExactSharedStatsCache, TestTestInjection, HighlighterMaxOffsetTest, NumberUtilsTest, TestConfigSetsAPIZkFailure, TestInfoStreamLogging, TestClusterProperties, TestRestoreCore, MetricTriggerIntegrationTest, TermsComponentTest, TestGraphTermsQParserPlugin, MultiDestinationAuditLoggerTest, TestSimUtils, PeerSyncTest, TestComplexPhraseLeadingWildcard, TestUseDocValuesAsStored, CreateRoutedAliasTest, VMParamsZkACLAndCredentialsProvidersTest, AsyncCallRequestStatusResponseTest, DistributedQueryComponentOptimizationTest, PhrasesIdentificationComponentTest, ChangedSchemaMergeTest, TestFacetMethods, TestLogWatcher, TestRawTransformer, TestUnifiedSolrHighlighter, BasicFunctionalityTest, CdcrVersionReplicationTest, ShowFileRequestHandlerTest, SystemInfoHandlerTest, PeerSyncWithLeaderTest, PluginInfoTest, TestImpersonationWithHadoopAuth, TestExceedMaxTermLength, CategoryRoutedAliasUpdateProcessorTest, LeaderElectionIntegrationTest, ShardRoutingTest, TestInPlaceUpdatesRequiredField, TestConfigOverlay, NestedShardedAtomicUpdateTest, MetricsHistoryWithAuthIntegrationTest, BlockJoinFacetRandomTest, RegexBoostProcessorTest, TestSimNodeLostTrigger, TestManagedSchemaThreadSafety, StressHdfsTest, TestQuerySenderNoQuery, TestDynamicLoading, ReplicationFactorTest, ConvertedLegacyTest, CursorPagingTest, OutputWriterTest, SampleTest, SolrInfoBeanTest, SolrTestCaseJ4DeleteCoreTest, SolrTestCaseJ4Test, TestCrossCoreJoin, PathHierarchyTokenizerFactoryTest, ProtectedTermFilterFactoryTest, TestCharFilters, TestDeprecatedFilters, TestEmbeddedSolrServerConstructors, TestEmbeddedSolrServerSchemaAPI, TestJettySolrRunner, ConnectionReuseTest, ActionThrottleTest, AddReplicaTest, AliasIntegrationTest, AssignBackwardCompatibilityTest, ChaosMonkeyNothingIsSafeWithPullReplicasTest, ChaosMonkeySafeLeaderTest, CleanupOldIndexTest, CloudExitableDirectoryReaderTest, ClusterStateMockUtilTest, ClusterStateTest, ClusterStateUpdateTest, CollectionPropsTest, CollectionStateFormat2Test, ConcurrentCreateRoutedAliasTest, ConnectionManagerTest, DeleteLastCustomShardedReplicaTest, DeleteNodeTest, DeleteReplicaTest, DocValuesNotIndexedTest, LeaderElectionTest, MultiSolrCloudTestCaseTest, NodeMutatorTest, OutOfBoxZkACLAndCredentialsProvidersTest, OverriddenZkACLAndCredentialsProvidersTest, OverseerStatusTest, RecoveryAfterSoftCommitTest, SharedFSAutoReplicaFailoverTest, SliceStateTest, SplitShardTest, SystemCollectionCompatTest, TestAuthenticationFramework, TestCloudPivotFacet, TestDeleteCollectionOnDownNodes, TestDistributedMap, TestLeaderElectionWithEmptyReplica, TestPullReplica, TestPullReplicaErrorHandling, TestStressInPlaceUpdates, TlogReplayBufferedWhileIndexingTest, TriLevelCompositeIdRoutingTest, ZkCLITest, CollectionsAPIAsyncDistributedZkTest, TestReplicaProperties, NodeAddedTriggerIntegrationTest, RestoreTriggerStateTest, TestSimClusterStateProvider, TestSimComputePlanAction, TestSimDistribStateManager, TestSimExtremeIndexing, TestSimLargeCluster, RAMDirectoryFactoryTest, TestConfigSetImmutable, TestDynamicLoadingUrl, TestMinimalConfig, TestNRTOpen, TestSimpleTextCodec, TestSolrConfigHandler, TestContainerPlugin, TestSQLHandlerNonCloud, TestStressIncrementalBackup, DistributedFacetPivotLargeTest, DistributedFacetPivotSmallTest, DistributedSuggestComponentTest, ResponseBuilderTest, ResponseLogComponentTest, ShardsWhitelistTest, TermVectorComponentDistributedTest, TestLegacyField, TestLegacyNumericUtils, TestNumericRangeQuery64, SolrCloudReportersTest, TestStreamBody, TestWriterPerf, SmileWriterTest, TestCSVResponseWriter, TestGraphMLResponseWriter, TestChildDocTransformerHierarchy, TestSchemaNameResource, TestSerializedLuceneMatchVersion, DateRangeFieldTest, NotRequiredUniqueKeyTest, OpenExchangeRatesOrgProviderTest, PolyFieldTest, SpatialRPTFieldTypeTest, TestUseDocValuesAsStored2, CurrencyRangeFacetCloudTest, QueryParsingTest, SignificantTermsQParserPluginTest, SolrIndexSearcherTest, TestAddFieldRealTimeGet, TestFieldSortValues, TestLFUCache, TestSolrFieldCacheBean, TestTrieFacet, DistributedFacetSimpleRefinementLongTailTest, SpatialHeatmapFacetsTest, SimpleMLTQParserTest, TestBM25SimilarityFactory, TestNonDefinedSimilarityFactory, TestPerFieldSimilarity, TestExactStatsCache, BasicAuthStandaloneTest, TestAuthorizationFramework, HttpSolrCallGetCoreTest]
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=HttpSolrCallGetCoreTest -Dtests.seed=96E9A77C44EE6AA7 -Dtests.multiplier=2 -Dtests.slow=true -Dtests.badapples=true -Dtests.locale=fi -Dtests.timezone=America/Argentina/La_Rioja -Dtests.asserts=true -Dtests.file.encoding=US-ASCII
[junit4] ERROR 0.00s J1 | HttpSolrCallGetCoreTest (suite) <<<
[junit4] > Throwable #1: org.apache.solr.client.solrj.SolrServerException: Timeout occurred while waiting response from server at: http://127.0.0.1:39999/solr
[junit4] > at __randomizedtesting.SeedInfo.seed([96E9A77C44EE6AA7]:0)
[junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:692)
[junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:266)
[junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:248)
[junit4] > at org.apache.solr.client.solrj.impl.LBSolrClient.doRequest(LBSolrClient.java:369)
[junit4] > at org.apache.solr.client.solrj.impl.LBSolrClient.request(LBSolrClient.java:297)
[junit4] > at org.apache.solr.client.solrj.impl.BaseCloudSolrClient.sendRequest(BaseCloudSolrClient.java:1171)
[junit4] > at org.apache.solr.client.solrj.impl.BaseCloudSolrClient.requestWithRetryOnStaleState(BaseCloudSolrClient.java:934)
[junit4] > at org.apache.solr.client.solrj.impl.BaseCloudSolrClient.request(BaseCloudSolrClient.java:866)
[junit4] > at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:214)
[junit4] > at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:231)
[junit4] > at org.apache.solr.servlet.HttpSolrCallGetCoreTest.setupCluster(HttpSolrCallGetCoreTest.java:53)
[junit4] > at java.lang.Thread.run(Thread.java:748)
[junit4] > Caused by: java.net.SocketTimeoutException: Read timed out
[junit4] > at java.net.SocketInputStream.socketRead0(Native Method)
[junit4] > at java.net.SocketInputStream.socketRead(SocketInputStream.java:116)
[junit4] > at java.net.SocketInputStream.read(SocketInputStream.java:171)
[junit4] > at java.net.SocketInputStream.read(SocketInputStream.java:141)
[junit4] > at org.apache.http.impl.io.SessionInputBufferImpl.streamRead(SessionInputBufferImpl.java:137)
[junit4] > at org.apache.http.impl.io.SessionInputBufferImpl.fillBuffer(SessionInputBufferImpl.java:153)
[junit4] > at org.apache.http.impl.io.SessionInputBufferImpl.readLine(SessionInputBufferImpl.java:280)
[junit4] > at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:138)
[junit4] > at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:56)
[junit4] > at org.apache.http.impl.io.AbstractMessageParser.parse(AbstractMessageParser.java:259)
[junit4] > at org.apache.http.impl.DefaultBHttpClientConnection.receiveResponseHeader(DefaultBHttpClientConnection.java:163)
[junit4] > at org.apache.http.impl.conn.CPoolProxy.receiveResponseHeader(CPoolProxy.java:157)
[junit4] > at org.apache.http.protocol.HttpRequestExecutor.doReceiveResponse(HttpRequestExecutor.java:273)
[junit4] > at org.apache.http.protocol.HttpRequestExecutor.execute(HttpRequestExecutor.java:125)
[junit4] > at org.apache.http.impl.execchain.MainClientExec.execute(MainClientExec.java:272)
[junit4] > at org.apache.http.impl.execchain.ProtocolExec.execute(ProtocolExec.java:186)
[junit4] > at org.apache.http.impl.execchain.RetryExec.execute(RetryExec.java:89)
[junit4] > at org.apache.http.impl.execchain.RedirectExec.execute(RedirectExec.java:110)
[junit4] > at org.apache.http.impl.client.InternalHttpClient.doExecute(InternalHttpClient.java:185)
[junit4] > at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:83)
[junit4] > at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:56)
[junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:571)
[junit4] > ... 36 more
[junit4] Completed [934/934 (1!)] on J1 in 200.94s, 0 tests, 1 error <<< FAILURES!
[...truncated 50775 lines...]