Build: https://ci-builds.apache.org/job/Lucene/job/Lucene-Solr-NightlyTests-8.11/28/
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:39235/solr Stack Trace: org.apache.solr.client.solrj.SolrServerException: Timeout occurred while waiting response from server at: http://127.0.0.1:39235/solr at __randomizedtesting.SeedInfo.seed([F495435EAE901B78]: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 16273 lines...] [junit4] Suite: org.apache.solr.servlet.HttpSolrCallGetCoreTest [junit4] 2> 4840720 INFO (SUITE-HttpSolrCallGetCoreTest-seed#[F495435EAE901B78]-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-NightlyTests-8.11/checkout/solr/server/solr/configsets/_default/conf' [junit4] 2> 4840721 INFO (SUITE-HttpSolrCallGetCoreTest-seed#[F495435EAE901B78]-worker) [ ] o.a.s.u.ErrorLogMuter Closing ErrorLogMuter-regex-798 after mutting 0 log messages [junit4] 2> 4840721 INFO (SUITE-HttpSolrCallGetCoreTest-seed#[F495435EAE901B78]-worker) [ ] o.a.s.u.ErrorLogMuter Creating ErrorLogMuter-regex-799 for ERROR logs matching regex: ignore_exception [junit4] 2> 4840722 INFO (SUITE-HttpSolrCallGetCoreTest-seed#[F495435EAE901B78]-worker) [ ] o.a.s.SolrTestCaseJ4 Created dataDir: /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-NightlyTests-8.11/checkout/solr/build/solr-core/test/J0/temp/solr.servlet.HttpSolrCallGetCoreTest_F495435EAE901B78-001/data-dir-342-001 [junit4] 2> 4840722 WARN (SUITE-HttpSolrCallGetCoreTest-seed#[F495435EAE901B78]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=3 numCloses=3 [junit4] 2> 4840723 INFO (SUITE-HttpSolrCallGetCoreTest-seed#[F495435EAE901B78]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=true [junit4] 2> 4840725 INFO (SUITE-HttpSolrCallGetCoreTest-seed#[F495435EAE901B78]-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> 4840725 INFO (SUITE-HttpSolrCallGetCoreTest-seed#[F495435EAE901B78]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom [junit4] 2> 4840725 INFO (SUITE-HttpSolrCallGetCoreTest-seed#[F495435EAE901B78]-worker) [ ] o.a.s.c.SolrCloudTestCase Using per-replica state [junit4] 2> 4840725 INFO (SUITE-HttpSolrCallGetCoreTest-seed#[F495435EAE901B78]-worker) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 1 servers in /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-NightlyTests-8.11/checkout/solr/build/solr-core/test/J0/temp/solr.servlet.HttpSolrCallGetCoreTest_F495435EAE901B78-001/tempDir-001 [junit4] 2> 4840725 INFO (SUITE-HttpSolrCallGetCoreTest-seed#[F495435EAE901B78]-worker) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER [junit4] 2> 4840726 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer client port: 0.0.0.0/0.0.0.0:0 [junit4] 2> 4840726 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Starting server [junit4] 2> 4840727 WARN (ZkTestServer Run Thread) [ ] o.a.z.s.ServerCnxnFactory maxCnxns is not configured, using default value 0. [junit4] 2> 4840826 INFO (SUITE-HttpSolrCallGetCoreTest-seed#[F495435EAE901B78]-worker) [ ] o.a.s.c.ZkTestServer start zk server on port: 45663 [junit4] 2> 4840826 INFO (SUITE-HttpSolrCallGetCoreTest-seed#[F495435EAE901B78]-worker) [ ] o.a.s.c.ZkTestServer waitForServerUp: 127.0.0.1:45663 [junit4] 2> 4840826 INFO (SUITE-HttpSolrCallGetCoreTest-seed#[F495435EAE901B78]-worker) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:45663 [junit4] 2> 4840826 INFO (SUITE-HttpSolrCallGetCoreTest-seed#[F495435EAE901B78]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 45663 [junit4] 2> 4840827 INFO (SUITE-HttpSolrCallGetCoreTest-seed#[F495435EAE901B78]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 4840832 INFO (zkConnectionManagerCallback-46930-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 4840832 INFO (SUITE-HttpSolrCallGetCoreTest-seed#[F495435EAE901B78]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 4840834 INFO (SUITE-HttpSolrCallGetCoreTest-seed#[F495435EAE901B78]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 4840836 INFO (zkConnectionManagerCallback-46932-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 4840836 INFO (SUITE-HttpSolrCallGetCoreTest-seed#[F495435EAE901B78]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 4840839 INFO (SUITE-HttpSolrCallGetCoreTest-seed#[F495435EAE901B78]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 4840841 INFO (zkConnectionManagerCallback-46934-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 4840841 INFO (SUITE-HttpSolrCallGetCoreTest-seed#[F495435EAE901B78]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 4840943 INFO (jetty-launcher-46935-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0) [junit4] 2> 4840943 INFO (jetty-launcher-46935-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ... [junit4] 2> 4840943 INFO (jetty-launcher-46935-thread-1) [ ] o.e.j.s.Server jetty-9.4.44.v20210927; built: 2021-09-27T23:02:44.612Z; git: 8da83308eeca865e495e53ef315a249d63ba9332; jvm 1.8.0_291-b10 [junit4] 2> 4840944 INFO (jetty-launcher-46935-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0 [junit4] 2> 4840944 INFO (jetty-launcher-46935-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults [junit4] 2> 4840944 INFO (jetty-launcher-46935-thread-1) [ ] o.e.j.s.session node0 Scavenging every 600000ms [junit4] 2> 4840944 INFO (jetty-launcher-46935-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@285481a{/solr,null,AVAILABLE} [junit4] 2> 4840944 INFO (jetty-launcher-46935-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@11ffb5cb{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:39235} [junit4] 2> 4840944 INFO (jetty-launcher-46935-thread-1) [ ] o.e.j.s.Server Started @4841018ms [junit4] 2> 4840944 INFO (jetty-launcher-46935-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, zkHost=127.0.0.1:45663/solr, hostPort=39235} [junit4] 2> 4840944 ERROR (jetty-launcher-46935-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 4840944 INFO (jetty-launcher-46935-thread-1) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory [junit4] 2> 4840944 INFO (jetty-launcher-46935-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.11.2 [junit4] 2> 4840944 INFO (jetty-launcher-46935-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 4840944 INFO (jetty-launcher-46935-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-NightlyTests-8.11/checkout/solr [junit4] 2> 4840944 INFO (jetty-launcher-46935-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2021-12-20T13:12:48.445Z [junit4] 2> 4840949 INFO (jetty-launcher-46935-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 4840953 INFO (zkConnectionManagerCallback-46937-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 4840953 INFO (jetty-launcher-46935-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 4840954 INFO (jetty-launcher-46935-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 4840957 INFO (jetty-launcher-46935-thread-1) [ ] o.a.s.c.SolrXmlConfig Metrics collection is disabled. [junit4] 2> 4841457 INFO (jetty-launcher-46935-thread-1) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true] [junit4] 2> 4841458 WARN (jetty-launcher-46935-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@4842c80d[provider=null,keyStore=null,trustStore=null] [junit4] 2> 4841458 WARN (jetty-launcher-46935-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@4842c80d[provider=null,keyStore=null,trustStore=null] [junit4] 2> 4841460 WARN (jetty-launcher-46935-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@1985723a[provider=null,keyStore=null,trustStore=null] [junit4] 2> 4841461 WARN (jetty-launcher-46935-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@1985723a[provider=null,keyStore=null,trustStore=null] [junit4] 2> 4841462 INFO (jetty-launcher-46935-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:45663/solr [junit4] 2> 4841465 INFO (jetty-launcher-46935-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 4841467 INFO (zkConnectionManagerCallback-46948-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 4841467 INFO (jetty-launcher-46935-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 4841569 INFO (jetty-launcher-46935-thread-1) [n:127.0.0.1:39235_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 4841570 INFO (zkConnectionManagerCallback-46950-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 4841570 INFO (jetty-launcher-46935-thread-1) [n:127.0.0.1:39235_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 4841644 WARN (jetty-launcher-46935-thread-1) [n:127.0.0.1:39235_solr ] o.a.s.c.ZkController Contents of zookeeper /security.json are world-readable; consider setting up ACLs as described in https://solr.apache.org/guide/zookeeper-access-control.html [junit4] 2> 4841648 INFO (jetty-launcher-46935-thread-1) [n:127.0.0.1:39235_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:39235_solr [junit4] 2> 4841649 INFO (jetty-launcher-46935-thread-1) [n:127.0.0.1:39235_solr ] o.a.s.c.Overseer Overseer (id=74747988506378245-127.0.0.1:39235_solr-n_0000000000) starting [junit4] 2> 4841653 INFO (OverseerStateUpdate-74747988506378245-127.0.0.1:39235_solr-n_0000000000) [n:127.0.0.1:39235_solr ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:39235_solr [junit4] 2> 4841653 INFO (jetty-launcher-46935-thread-1) [n:127.0.0.1:39235_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:39235_solr [junit4] 2> 4841654 INFO (OverseerStateUpdate-74747988506378245-127.0.0.1:39235_solr-n_0000000000) [n:127.0.0.1:39235_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 4841656 INFO (jetty-launcher-46935-thread-1) [n:127.0.0.1:39235_solr ] o.a.s.p.PackageLoader /packages.json updated to version -1 [junit4] 2> 4841656 WARN (jetty-launcher-46935-thread-1) [n:127.0.0.1:39235_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> 4841687 INFO (jetty-launcher-46935-thread-1) [n:127.0.0.1:39235_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-NightlyTests-8.11/checkout/solr/build/solr-core/test/J0/temp/solr.servlet.HttpSolrCallGetCoreTest_F495435EAE901B78-001/tempDir-001/node1 [junit4] 2> 4841697 INFO (SUITE-HttpSolrCallGetCoreTest-seed#[F495435EAE901B78]-worker) [ ] o.a.s.c.MiniSolrCloudCluster waitForAllNodes: numServers=1 [junit4] 2> 4841698 INFO (SUITE-HttpSolrCallGetCoreTest-seed#[F495435EAE901B78]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 4841699 INFO (zkConnectionManagerCallback-46965-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 4841699 INFO (SUITE-HttpSolrCallGetCoreTest-seed#[F495435EAE901B78]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 4841700 INFO (SUITE-HttpSolrCallGetCoreTest-seed#[F495435EAE901B78]-worker) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 4841701 INFO (SUITE-HttpSolrCallGetCoreTest-seed#[F495435EAE901B78]-worker) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:45663/solr ready [junit4] 2> 4841709 INFO (OverseerThreadFactory-46957-thread-1-processing-n:127.0.0.1:39235_solr) [n:127.0.0.1:39235_solr ] o.a.s.c.a.c.CreateCollectionCmd Create collection collection1 [junit4] 2> 4841711 WARN (OverseerThreadFactory-46957-thread-1-processing-n:127.0.0.1:39235_solr) [n:127.0.0.1:39235_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> 4841713 INFO (OverseerThreadFactory-46957-thread-1-processing-n:127.0.0.1:39235_solr) [n:127.0.0.1:39235_solr ] o.a.s.c.o.SliceMutator createReplica() { [junit4] 2> "core":"collection1_shard1_replica_n1", [junit4] 2> "node_name":"127.0.0.1:39235_solr", [junit4] 2> "base_url":"http://127.0.0.1:39235/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> 4841715 INFO (OverseerThreadFactory-46957-thread-1-processing-n:127.0.0.1:39235_solr) [n:127.0.0.1:39235_solr ] o.a.s.c.o.SliceMutator createReplica() { [junit4] 2> "core":"collection1_shard1_replica_n3", [junit4] 2> "node_name":"127.0.0.1:39235_solr", [junit4] 2> "base_url":"http://127.0.0.1:39235/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> 4841718 INFO (OverseerThreadFactory-46957-thread-1-processing-n:127.0.0.1:39235_solr) [n:127.0.0.1:39235_solr ] o.a.s.c.o.SliceMutator createReplica() { [junit4] 2> "core":"collection1_shard2_replica_n5", [junit4] 2> "node_name":"127.0.0.1:39235_solr", [junit4] 2> "base_url":"http://127.0.0.1:39235/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> 4841721 INFO (OverseerThreadFactory-46957-thread-1-processing-n:127.0.0.1:39235_solr) [n:127.0.0.1:39235_solr ] o.a.s.c.o.SliceMutator createReplica() { [junit4] 2> "core":"collection1_shard2_replica_n7", [junit4] 2> "node_name":"127.0.0.1:39235_solr", [junit4] 2> "base_url":"http://127.0.0.1:39235/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> 4841723 INFO (OverseerThreadFactory-46957-thread-1-processing-n:127.0.0.1:39235_solr) [n:127.0.0.1:39235_solr ] o.a.s.c.o.SliceMutator createReplica() { [junit4] 2> "core":"collection1_shard3_replica_n9", [junit4] 2> "node_name":"127.0.0.1:39235_solr", [junit4] 2> "base_url":"http://127.0.0.1:39235/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> 4841725 INFO (OverseerThreadFactory-46957-thread-1-processing-n:127.0.0.1:39235_solr) [n:127.0.0.1:39235_solr ] o.a.s.c.o.SliceMutator createReplica() { [junit4] 2> "core":"collection1_shard3_replica_n11", [junit4] 2> "node_name":"127.0.0.1:39235_solr", [junit4] 2> "base_url":"http://127.0.0.1:39235/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> 4841733 INFO (qtp523782236-111305) [n:127.0.0.1:39235_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> 4841734 INFO (qtp523782236-111301) [n:127.0.0.1:39235_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> 4841734 INFO (qtp523782236-111305) [n:127.0.0.1:39235_solr x:collection1_shard1_replica_n1 ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient core cache for max 2147483647 cores with initial capacity of 1024 [junit4] 2> 4841734 INFO (qtp523782236-111306) [n:127.0.0.1:39235_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> 4841737 INFO (qtp523782236-111304) [n:127.0.0.1:39235_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> 4841755 INFO (qtp523782236-111346) [n:127.0.0.1:39235_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> 4841756 INFO (qtp523782236-111345) [n:127.0.0.1:39235_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> 4841764 INFO (qtp523782236-111304) [n:127.0.0.1:39235_solr c:collection1 s:shard2 r:core_node8 x:collection1_shard2_replica_n7 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.11.2 [junit4] 2> 4841765 INFO (qtp523782236-111306) [n:127.0.0.1:39235_solr c:collection1 s:shard2 r:core_node6 x:collection1_shard2_replica_n5 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.11.2 [junit4] 2> 4841767 INFO (qtp523782236-111301) [n:127.0.0.1:39235_solr c:collection1 s:shard1 r:core_node4 x:collection1_shard1_replica_n3 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.11.2 [junit4] 2> 4841769 INFO (qtp523782236-111304) [n:127.0.0.1:39235_solr c:collection1 s:shard2 r:core_node8 x:collection1_shard2_replica_n7 ] o.a.s.s.IndexSchema Schema name=minimal [junit4] 2> 4841770 INFO (qtp523782236-111304) [n:127.0.0.1:39235_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> 4841770 INFO (qtp523782236-111304) [n:127.0.0.1:39235_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> 4841770 INFO (qtp523782236-111306) [n:127.0.0.1:39235_solr c:collection1 s:shard2 r:core_node6 x:collection1_shard2_replica_n5 ] o.a.s.s.IndexSchema Schema name=minimal [junit4] 2> 4841770 INFO (qtp523782236-111306) [n:127.0.0.1:39235_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> 4841770 INFO (qtp523782236-111306) [n:127.0.0.1:39235_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> 4841770 INFO (qtp523782236-111306) [n:127.0.0.1:39235_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-NightlyTests-8.11/checkout/solr/build/solr-core/test/J0/temp/solr.servlet.HttpSolrCallGetCoreTest_F495435EAE901B78-001/tempDir-001/node1/collection1_shard2_replica_n5], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-NightlyTests-8.11/checkout/solr/build/solr-core/test/J0/temp/solr.servlet.HttpSolrCallGetCoreTest_F495435EAE901B78-001/tempDir-001/node1/collection1_shard2_replica_n5/data/] [junit4] 2> 4841770 INFO (qtp523782236-111304) [n:127.0.0.1:39235_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-NightlyTests-8.11/checkout/solr/build/solr-core/test/J0/temp/solr.servlet.HttpSolrCallGetCoreTest_F495435EAE901B78-001/tempDir-001/node1/collection1_shard2_replica_n7], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-NightlyTests-8.11/checkout/solr/build/solr-core/test/J0/temp/solr.servlet.HttpSolrCallGetCoreTest_F495435EAE901B78-001/tempDir-001/node1/collection1_shard2_replica_n7/data/] [junit4] 2> 4841772 INFO (qtp523782236-111301) [n:127.0.0.1:39235_solr c:collection1 s:shard1 r:core_node4 x:collection1_shard1_replica_n3 ] o.a.s.s.IndexSchema Schema name=minimal [junit4] 2> 4841772 INFO (qtp523782236-111301) [n:127.0.0.1:39235_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> 4841772 INFO (qtp523782236-111301) [n:127.0.0.1:39235_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> 4841772 INFO (qtp523782236-111301) [n:127.0.0.1:39235_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-NightlyTests-8.11/checkout/solr/build/solr-core/test/J0/temp/solr.servlet.HttpSolrCallGetCoreTest_F495435EAE901B78-001/tempDir-001/node1/collection1_shard1_replica_n3], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-NightlyTests-8.11/checkout/solr/build/solr-core/test/J0/temp/solr.servlet.HttpSolrCallGetCoreTest_F495435EAE901B78-001/tempDir-001/node1/collection1_shard1_replica_n3/data/] [junit4] 2> 4841788 INFO (qtp523782236-111345) [n:127.0.0.1:39235_solr c:collection1 s:shard3 r:core_node10 x:collection1_shard3_replica_n9 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.11.2 [junit4] 2> 4841789 INFO (qtp523782236-111345) [n:127.0.0.1:39235_solr c:collection1 s:shard3 r:core_node10 x:collection1_shard3_replica_n9 ] o.a.s.s.IndexSchema Schema name=minimal [junit4] 2> 4841789 INFO (qtp523782236-111345) [n:127.0.0.1:39235_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> 4841789 INFO (qtp523782236-111345) [n:127.0.0.1:39235_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> 4841790 INFO (qtp523782236-111345) [n:127.0.0.1:39235_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-NightlyTests-8.11/checkout/solr/build/solr-core/test/J0/temp/solr.servlet.HttpSolrCallGetCoreTest_F495435EAE901B78-001/tempDir-001/node1/collection1_shard3_replica_n9], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-NightlyTests-8.11/checkout/solr/build/solr-core/test/J0/temp/solr.servlet.HttpSolrCallGetCoreTest_F495435EAE901B78-001/tempDir-001/node1/collection1_shard3_replica_n9/data/] [junit4] 2> 4841792 INFO (qtp523782236-111305) [n:127.0.0.1:39235_solr c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.11.2 [junit4] 2> 4841793 INFO (qtp523782236-111305) [n:127.0.0.1:39235_solr c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1 ] o.a.s.s.IndexSchema Schema name=minimal [junit4] 2> 4841794 INFO (qtp523782236-111305) [n:127.0.0.1:39235_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> 4841794 INFO (qtp523782236-111305) [n:127.0.0.1:39235_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> 4841794 INFO (qtp523782236-111305) [n:127.0.0.1:39235_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-NightlyTests-8.11/checkout/solr/build/solr-core/test/J0/temp/solr.servlet.HttpSolrCallGetCoreTest_F495435EAE901B78-001/tempDir-001/node1/collection1_shard1_replica_n1], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-NightlyTests-8.11/checkout/solr/build/solr-core/test/J0/temp/solr.servlet.HttpSolrCallGetCoreTest_F495435EAE901B78-001/tempDir-001/node1/collection1_shard1_replica_n1/data/] [junit4] 2> 4841799 INFO (qtp523782236-111346) [n:127.0.0.1:39235_solr c:collection1 s:shard3 r:core_node12 x:collection1_shard3_replica_n11 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.11.2 [junit4] 2> 4841800 INFO (qtp523782236-111346) [n:127.0.0.1:39235_solr c:collection1 s:shard3 r:core_node12 x:collection1_shard3_replica_n11 ] o.a.s.s.IndexSchema Schema name=minimal [junit4] 2> 4841800 INFO (qtp523782236-111346) [n:127.0.0.1:39235_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> 4841800 INFO (qtp523782236-111346) [n:127.0.0.1:39235_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> 4841800 INFO (qtp523782236-111346) [n:127.0.0.1:39235_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-NightlyTests-8.11/checkout/solr/build/solr-core/test/J0/temp/solr.servlet.HttpSolrCallGetCoreTest_F495435EAE901B78-001/tempDir-001/node1/collection1_shard3_replica_n11], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-NightlyTests-8.11/checkout/solr/build/solr-core/test/J0/temp/solr.servlet.HttpSolrCallGetCoreTest_F495435EAE901B78-001/tempDir-001/node1/collection1_shard3_replica_n11/data/] [junit4] 2> 4841833 INFO (qtp523782236-111304) [n:127.0.0.1:39235_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> 4841833 INFO (qtp523782236-111304) [n:127.0.0.1:39235_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> 4841834 INFO (qtp523782236-111304) [n:127.0.0.1:39235_solr c:collection1 s:shard2 r:core_node8 x:collection1_shard2_replica_n7 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 4841834 INFO (qtp523782236-111304) [n:127.0.0.1:39235_solr c:collection1 s:shard2 r:core_node8 x:collection1_shard2_replica_n7 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 4841835 INFO (qtp523782236-111304) [n:127.0.0.1:39235_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> 4841835 INFO (qtp523782236-111304) [n:127.0.0.1:39235_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> 4841835 INFO (qtp523782236-111304) [n:127.0.0.1:39235_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> 4841835 INFO (qtp523782236-111304) [n:127.0.0.1:39235_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 1719670899302465536 [junit4] 2> 4841841 INFO (qtp523782236-111306) [n:127.0.0.1:39235_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> 4841841 INFO (qtp523782236-111306) [n:127.0.0.1:39235_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> 4841842 INFO (qtp523782236-111306) [n:127.0.0.1:39235_solr c:collection1 s:shard2 r:core_node6 x:collection1_shard2_replica_n5 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 4841842 INFO (qtp523782236-111306) [n:127.0.0.1:39235_solr c:collection1 s:shard2 r:core_node6 x:collection1_shard2_replica_n5 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 4841843 INFO (qtp523782236-111306) [n:127.0.0.1:39235_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> 4841843 INFO (qtp523782236-111306) [n:127.0.0.1:39235_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> 4841843 INFO (qtp523782236-111306) [n:127.0.0.1:39235_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> 4841843 INFO (qtp523782236-111306) [n:127.0.0.1:39235_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 1719670899310854144 [junit4] 2> 4841843 INFO (qtp523782236-111305) [n:127.0.0.1:39235_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> 4841843 INFO (qtp523782236-111305) [n:127.0.0.1:39235_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> 4841844 INFO (qtp523782236-111305) [n:127.0.0.1:39235_solr c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 4841844 INFO (qtp523782236-111305) [n:127.0.0.1:39235_solr c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 4841844 INFO (searcherExecutor-46967-thread-1-processing-n:127.0.0.1:39235_solr x:collection1_shard2_replica_n7 c:collection1 s:shard2 r:core_node8) [n:127.0.0.1:39235_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> 4841845 INFO (qtp523782236-111345) [n:127.0.0.1:39235_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> 4841845 INFO (qtp523782236-111345) [n:127.0.0.1:39235_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> 4841845 INFO (searcherExecutor-46969-thread-1-processing-n:127.0.0.1:39235_solr x:collection1_shard2_replica_n5 c:collection1 s:shard2 r:core_node6) [n:127.0.0.1:39235_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> 4841846 INFO (qtp523782236-111345) [n:127.0.0.1:39235_solr c:collection1 s:shard3 r:core_node10 x:collection1_shard3_replica_n9 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 4841846 INFO (qtp523782236-111345) [n:127.0.0.1:39235_solr c:collection1 s:shard3 r:core_node10 x:collection1_shard3_replica_n9 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 4841846 INFO (qtp523782236-111305) [n:127.0.0.1:39235_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> 4841847 INFO (qtp523782236-111301) [n:127.0.0.1:39235_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> 4841847 INFO (qtp523782236-111301) [n:127.0.0.1:39235_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> 4841848 INFO (qtp523782236-111301) [n:127.0.0.1:39235_solr c:collection1 s:shard1 r:core_node4 x:collection1_shard1_replica_n3 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 4841848 INFO (qtp523782236-111301) [n:127.0.0.1:39235_solr c:collection1 s:shard1 r:core_node4 x:collection1_shard1_replica_n3 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 4841849 INFO (qtp523782236-111305) [n:127.0.0.1:39235_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> 4841849 INFO (qtp523782236-111305) [n:127.0.0.1:39235_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> 4841849 INFO (qtp523782236-111305) [n:127.0.0.1:39235_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 1719670899317145600 [junit4] 2> 4841850 INFO (qtp523782236-111301) [n:127.0.0.1:39235_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> 4841851 INFO (qtp523782236-111345) [n:127.0.0.1:39235_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> 4841851 INFO (qtp523782236-111301) [n:127.0.0.1:39235_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> 4841851 INFO (qtp523782236-111301) [n:127.0.0.1:39235_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> 4841851 INFO (qtp523782236-111301) [n:127.0.0.1:39235_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 1719670899319242752 [junit4] 2> 4841852 INFO (qtp523782236-111345) [n:127.0.0.1:39235_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> 4841852 INFO (qtp523782236-111345) [n:127.0.0.1:39235_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> 4841852 INFO (qtp523782236-111345) [n:127.0.0.1:39235_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 1719670899320291328 [junit4] 2> 4841853 INFO (searcherExecutor-46975-thread-1-processing-n:127.0.0.1:39235_solr x:collection1_shard1_replica_n1 c:collection1 s:shard1 r:core_node2) [n:127.0.0.1:39235_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> 4841855 INFO (qtp523782236-111346) [n:127.0.0.1:39235_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> 4841855 INFO (qtp523782236-111346) [n:127.0.0.1:39235_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> 4841856 INFO (qtp523782236-111346) [n:127.0.0.1:39235_solr c:collection1 s:shard3 r:core_node12 x:collection1_shard3_replica_n11 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 4841856 INFO (qtp523782236-111346) [n:127.0.0.1:39235_solr c:collection1 s:shard3 r:core_node12 x:collection1_shard3_replica_n11 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 4841857 INFO (searcherExecutor-46973-thread-1-processing-n:127.0.0.1:39235_solr x:collection1_shard3_replica_n9 c:collection1 s:shard3 r:core_node10) [n:127.0.0.1:39235_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> 4841857 INFO (qtp523782236-111346) [n:127.0.0.1:39235_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> 4841857 INFO (qtp523782236-111346) [n:127.0.0.1:39235_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> 4841858 INFO (qtp523782236-111346) [n:127.0.0.1:39235_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> 4841858 INFO (qtp523782236-111346) [n:127.0.0.1:39235_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 1719670899326582784 [junit4] 2> 4841858 INFO (searcherExecutor-46971-thread-1-processing-n:127.0.0.1:39235_solr x:collection1_shard1_replica_n3 c:collection1 s:shard1 r:core_node4) [n:127.0.0.1:39235_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> 4841860 INFO (searcherExecutor-46977-thread-1-processing-n:127.0.0.1:39235_solr x:collection1_shard3_replica_n11 c:collection1 s:shard3 r:core_node12) [n:127.0.0.1:39235_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> 4841860 INFO (qtp523782236-111306) [n:127.0.0.1:39235_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> 4841860 INFO (qtp523782236-111306) [n:127.0.0.1:39235_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> 4841861 INFO (qtp523782236-111304) [n:127.0.0.1:39235_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> 4841861 INFO (qtp523782236-111304) [n:127.0.0.1:39235_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> 4841865 INFO (qtp523782236-111305) [n:127.0.0.1:39235_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> 4841865 INFO (qtp523782236-111305) [n:127.0.0.1:39235_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> 4841869 INFO (qtp523782236-111345) [n:127.0.0.1:39235_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> 4841869 INFO (qtp523782236-111345) [n:127.0.0.1:39235_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> 4841869 INFO (qtp523782236-111304) [n:127.0.0.1:39235_solr c:collection1 s:shard2 r:core_node8 x:collection1_shard2_replica_n7 ] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue. [junit4] 2> 4841869 INFO (qtp523782236-111346) [n:127.0.0.1:39235_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> 4841869 INFO (qtp523782236-111346) [n:127.0.0.1:39235_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> 4841870 INFO (qtp523782236-111301) [n:127.0.0.1:39235_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> 4841870 INFO (qtp523782236-111301) [n:127.0.0.1:39235_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> 4841870 INFO (qtp523782236-111304) [n:127.0.0.1:39235_solr c:collection1 s:shard2 r:core_node8 x:collection1_shard2_replica_n7 ] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync [junit4] 2> 4841870 INFO (qtp523782236-111304) [n:127.0.0.1:39235_solr c:collection1 s:shard2 r:core_node8 x:collection1_shard2_replica_n7 ] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:39235/solr/collection1_shard2_replica_n7/ [junit4] 2> 4841871 INFO (qtp523782236-111304) [n:127.0.0.1:39235_solr c:collection1 s:shard2 r:core_node8 x:collection1_shard2_replica_n7 ] o.a.s.u.PeerSync PeerSync: core=collection1_shard2_replica_n7 url=http://127.0.0.1:39235/solr START replicas=[http://127.0.0.1:39235/solr/collection1_shard2_replica_n5/] nUpdates=100 [junit4] 2> 4841871 INFO (qtp523782236-111304) [n:127.0.0.1:39235_solr c:collection1 s:shard2 r:core_node8 x:collection1_shard2_replica_n7 ] o.a.s.u.PeerSync PeerSync: core=collection1_shard2_replica_n7 url=http://127.0.0.1:39235/solr DONE. We have no versions. sync failed. [junit4] 2> 4841873 INFO (qtp523782236-111305) [n:127.0.0.1:39235_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> 4841873 INFO (qtp523782236-111305) [n:127.0.0.1:39235_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> 4841873 INFO (qtp523782236-111305) [n:127.0.0.1:39235_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:39235/solr/collection1_shard1_replica_n1/ [junit4] 2> 4841874 INFO (qtp523782236-111361) [n:127.0.0.1:39235_solr c:collection1 s:shard2 r:core_node6 x:collection1_shard2_replica_n5 ] o.a.s.c.S.Request [collection1_shard2_replica_n5] webapp=/solr path=/get params={distrib=false&qt=/get&fingerprint=false&getVersions=100&wt=javabin&version=2} status=0 QTime=0 [junit4] 2> 4841874 INFO (qtp523782236-111305) [n:127.0.0.1:39235_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:39235/solr START replicas=[http://127.0.0.1:39235/solr/collection1_shard1_replica_n3/] nUpdates=100 [junit4] 2> 4841874 INFO (qtp523782236-111304) [n:127.0.0.1:39235_solr c:collection1 s:shard2 r:core_node8 x:collection1_shard2_replica_n7 ] o.a.s.c.SyncStrategy Leader's attempt to sync with shard failed, moving to the next candidate [junit4] 2> 4841874 INFO (qtp523782236-111304) [n:127.0.0.1:39235_solr c:collection1 s:shard2 r:core_node8 x:collection1_shard2_replica_n7 ] 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> 4841874 INFO (qtp523782236-111304) [n:127.0.0.1:39235_solr c:collection1 s:shard2 r:core_node8 x:collection1_shard2_replica_n7 ] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/collection1/leaders/shard2/leader after winning as /collections/collection1/leader_elect/shard2/election/74747988506378245-core_node8-n_0000000000 [junit4] 2> 4841879 INFO (qtp523782236-111304) [n:127.0.0.1:39235_solr c:collection1 s:shard2 r:core_node8 x:collection1_shard2_replica_n7 ] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:39235/solr/collection1_shard2_replica_n7/ shard2 [junit4] 2> 4841880 INFO (qtp523782236-111345) [n:127.0.0.1:39235_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> 4841880 INFO (qtp523782236-111345) [n:127.0.0.1:39235_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> 4841880 INFO (qtp523782236-111345) [n:127.0.0.1:39235_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:39235/solr/collection1_shard3_replica_n9/ [junit4] 2> 4841880 INFO (qtp523782236-111345) [n:127.0.0.1:39235_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:39235/solr START replicas=[http://127.0.0.1:39235/solr/collection1_shard3_replica_n11/] nUpdates=100 [junit4] 2> 4841881 INFO (qtp523782236-111305) [n:127.0.0.1:39235_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:39235/solr DONE. We have no versions. sync failed. [junit4] 2> 4841881 INFO (qtp523782236-111345) [n:127.0.0.1:39235_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:39235/solr DONE. We have no versions. sync failed. [junit4] 2> 4841882 INFO (qtp523782236-111361) [n:127.0.0.1:39235_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> 4841882 INFO (qtp523782236-111305) [n:127.0.0.1:39235_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> 4841882 INFO (qtp523782236-111305) [n:127.0.0.1:39235_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> 4841882 INFO (qtp523782236-111305) [n:127.0.0.1:39235_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/74747988506378245-core_node2-n_0000000000 [junit4] 2> 4841882 INFO (qtp523782236-111304) [n:127.0.0.1:39235_solr c:collection1 s:shard2 r:core_node8 x:collection1_shard2_replica_n7 ] o.a.s.c.ZkController I am the leader, no recovery necessary [junit4] 2> 4841884 INFO (qtp523782236-111305) [n:127.0.0.1:39235_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:39235/solr/collection1_shard1_replica_n1/ shard1 [junit4] 2> 4841885 INFO (zkCallback-46949-thread-2) [ ] 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> 4841885 INFO (zkCallback-46949-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> 4841885 INFO (qtp523782236-111361) [n:127.0.0.1:39235_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> 4841885 INFO (zkCallback-46949-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> 4841886 INFO (qtp523782236-111345) [n:127.0.0.1:39235_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> 4841886 INFO (qtp523782236-111345) [n:127.0.0.1:39235_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> 4841886 INFO (qtp523782236-111345) [n:127.0.0.1:39235_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/74747988506378245-core_node10-n_0000000000 [junit4] 2> 4841886 INFO (qtp523782236-111304) [n:127.0.0.1:39235_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=149 [junit4] 2> 4841887 INFO (qtp523782236-111345) [n:127.0.0.1:39235_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:39235/solr/collection1_shard3_replica_n9/ shard3 [junit4] 2> 4841888 INFO (qtp523782236-111345) [n:127.0.0.1:39235_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> 4841889 INFO (zkCallback-46949-thread-2) [ ] 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> 4841893 INFO (zkCallback-46949-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> 4841893 INFO (zkCallback-46949-thread-5) [ ] 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> 4841893 INFO (zkCallback-46949-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> 4841895 INFO (qtp523782236-111305) [n:127.0.0.1:39235_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> 4841895 INFO (qtp523782236-111345) [n:127.0.0.1:39235_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=139 [junit4] 2> 4841897 INFO (zkCallback-46949-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> 4841897 INFO (zkCallback-46949-thread-2) [ ] 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> 4841897 INFO (zkCallback-46949-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> 4841897 INFO (zkCallback-46949-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> 4841897 INFO (zkCallback-46949-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> 4841898 INFO (qtp523782236-111305) [n:127.0.0.1:39235_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=164 [junit4] 2> 4842868 INFO (zkCallback-46949-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> 4842868 INFO (zkCallback-46949-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> 4842868 INFO (zkCallback-46949-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> 4842868 INFO (zkCallback-46949-thread-2) [ ] 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> 4842868 INFO (zkCallback-46949-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> 4842870 INFO (qtp523782236-111306) [n:127.0.0.1:39235_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=1135 [junit4] 2> 4842878 INFO (zkCallback-46949-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> 4842878 INFO (zkCallback-46949-thread-2) [ ] 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> 4842878 INFO (zkCallback-46949-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> 4842878 INFO (zkCallback-46949-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> 4842878 INFO (zkCallback-46949-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> 4842879 INFO (qtp523782236-111301) [n:127.0.0.1:39235_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=1146 [junit4] 2> 4961841 ERROR (OverseerThreadFactory-46957-thread-1-processing-n:127.0.0.1:39235_solr) [n:127.0.0.1:39235_solr ] o.a.s.c.a.c.OverseerCollectionMessageHandler Error from shard: http://127.0.0.1:39235/solr [junit4] 2> => org.apache.solr.client.solrj.SolrServerException: Timeout occurred while waiting response from server at: http://127.0.0.1:39235/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:39235/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_291] [junit4] 2> at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511) ~[?:1.8.0_291] [junit4] 2> at java.util.concurrent.FutureTask.run(FutureTask.java:266) ~[?:1.8.0_291] [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_291] [junit4] 2> at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) [?:1.8.0_291] [junit4] 2> at java.lang.Thread.run(Thread.java:748) [?:1.8.0_291] [junit4] 2> Caused by: java.net.SocketTimeoutException: Read timed out [junit4] 2> at java.net.SocketInputStream.socketRead0(Native Method) ~[?:1.8.0_291] [junit4] 2> at java.net.SocketInputStream.socketRead(SocketInputStream.java:116) ~[?:1.8.0_291] [junit4] 2> at java.net.SocketInputStream.read(SocketInputStream.java:171) ~[?:1.8.0_291] [junit4] 2> at java.net.SocketInputStream.read(SocketInputStream.java:141) ~[?:1.8.0_291] [junit4] 2> at org.apache.http.impl.io.SessionInputBufferImpl.streamRead(SessionInputBufferImpl.java:137) ~[httpcore-4.4.14.jar:4.4.14] [junit4] 2> at org.apache.http.impl.io.SessionInputBufferImpl.fillBuffer(SessionInputBufferImpl.java:153) ~[httpcore-4.4.14.jar:4.4.14] [junit4] 2> at org.apache.http.impl.io.SessionInputBufferImpl.readLine(SessionInputBufferImpl.java:280) ~[httpcore-4.4.14.jar:4.4.14] [junit4] 2> at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:138) ~[httpclient-4.5.13.jar:4.5.13] [junit4] 2> at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:56) ~[httpclient-4.5.13.jar:4.5.13] [junit4] 2> at org.apache.http.impl.io.AbstractMessageParser.parse(AbstractMessageParser.java:259) ~[httpcore-4.4.14.jar:4.4.14] [junit4] 2> at org.apache.http.impl.DefaultBHttpClientConnection.receiveResponseHeader(DefaultBHttpClientConnection.java:163) ~[httpcore-4.4.14.jar:4.4.14] [junit4] 2> at org.apache.http.impl.conn.CPoolProxy.receiveResponseHeader(CPoolProxy.java:157) ~[httpclient-4.5.13.jar:4.5.13] [junit4] 2> at org.apache.http.protocol.HttpRequestExecutor.doReceiveResponse(HttpRequestExecutor.java:273) ~[httpcore-4.4.14.jar:4.4.14] [junit4] 2> at org.apache.http.protocol.HttpRequestExecutor.execute(HttpRequestExecutor.java:125) ~[httpcore-4.4.14.jar:4.4.14] [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.13.jar:4.5.13] [junit4] 2> at org.apache.http.impl.execchain.ProtocolExec.execute(ProtocolExec.java:186) ~[httpclient-4.5.13.jar:4.5.13] [junit4] 2> at org.apache.http.impl.execchain.RetryExec.execute(RetryExec.java:89) ~[httpclient-4.5.13.jar:4.5.13] [junit4] 2> at org.apache.http.impl.execchain.RedirectExec.execute(RedirectExec.java:110) ~[httpclient-4.5.13.jar:4.5.13] [junit4] 2> at org.apache.http.impl.client.InternalHttpClient.doExecute(InternalHttpClient.java:185) ~[httpclient-4.5.13.jar:4.5.13] [junit4] 2> at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:83) ~[httpclient-4.5.13.jar:4.5.13] [junit4] 2> at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:56) ~[httpclient-4.5.13.jar:4.5.13] [junit4] 2> at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:571) ~[java/:?] [junit4] 2> ... 14 more [junit4] 2> 5021707 ERROR (qtp523782236-111303) [n:127.0.0.1:39235_solr ] o.a.s.h.RequestHandlerBase org.apache.solr.common.SolrException: create the collection time out:180s [junit4] 2> => 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> org.apache.solr.common.SolrException: create the collection time out:180s [junit4] 2> at org.apache.solr.handler.admin.CollectionsHandler.sendToOCPQueue(CollectionsHandler.java:394) ~[java/:?] [junit4] 2> at org.apache.solr.handler.admin.CollectionsHandler.invokeAction(CollectionsHandler.java:299) ~[java/:?] [junit4] 2> at org.apache.solr.handler.admin.CollectionsHandler.handleRequestBody(CollectionsHandler.java:257) ~[java/:?] [junit4] 2> at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:216) ~[java/:?] [junit4] 2> at org.apache.solr.servlet.HttpSolrCall.handleAdmin(HttpSolrCall.java:836) ~[java/:?] [junit4] 2> at org.apache.solr.servlet.HttpSolrCall.handleAdminRequest(HttpSolrCall.java:800) ~[java/:?] [junit4] 2> at org.apache.solr.servlet.HttpSolrCall.call(HttpSolrCall.java:545) ~[java/:?] [junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:427) ~[java/:?] [junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:357) ~[java/:?] [junit4] 2> at org.eclipse.jetty.servlet.FilterHolder.doFilter(FilterHolder.java:193) ~[jetty-servlet-9.4.44.v20210927.jar:9.4.44.v20210927] [junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$Chain.doFilter(ServletHandler.java:1601) ~[jetty-servlet-9.4.44.v20210927.jar:9.4.44.v20210927] [junit4] 2> at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:166) ~[java/:?] [junit4] 2> at org.eclipse.jetty.servlet.FilterHolder.doFilter(FilterHolder.java:193) ~[jetty-servlet-9.4.44.v20210927.jar:9.4.44.v20210927] [junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$Chain.doFilter(ServletHandler.java:1601) ~[jetty-servlet-9.4.44.v20210927.jar:9.4.44.v20210927] [junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:548) ~[jetty-servlet-9.4.44.v20210927.jar:9.4.44.v20210927] [junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:233) ~[jetty-server-9.4.44.v20210927.jar:9.4.44.v20210927] [junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:1624) ~[jetty-server-9.4.44.v20210927.jar:9.4.44.v20210927] [junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:233) ~[jetty-server-9.4.44.v20210927.jar:9.4.44.v20210927] [junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1434) ~[jetty-server-9.4.44.v20210927.jar:9.4.44.v20210927] [junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:188) ~[jetty-server-9.4.44.v20210927.jar:9.4.44.v20210927] [junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:501) ~[jetty-servlet-9.4.44.v20210927.jar:9.4.44.v20210927] [junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:1594) ~[jetty-server-9.4.44.v20210927.jar:9.4.44.v20210927] [junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:186) ~[jetty-server-9.4.44.v20210927.jar:9.4.44.v20210927] [junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1349) ~[jetty-server-9.4.44.v20210927.jar:9.4.44.v20210927] [junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141) ~[jetty-server-9.4.44.v20210927.jar:9.4.44.v20210927] [junit4] 2> at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:127) ~[jetty-server-9.4.44.v20210927.jar:9.4.44.v20210927] [junit4] 2> at org.eclipse.jetty.rewrite.handler.RewriteHandler.handle(RewriteHandler.java:322) ~[jetty-rewrite-9.4.44.v20210927.jar:9.4.44.v20210927] [junit4] 2> at org.eclipse.jetty.server.handler.gzip.GzipHandler.handle(GzipHandler.java:763) ~[jetty-server-9.4.44.v20210927.jar:9.4.44.v20210927] [junit4] 2> at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:127) ~[jetty-server-9.4.44.v20210927.jar:9.4.44.v20210927] [junit4] 2> at org.eclipse.jetty.server.Server.handle(Server.java:516) ~[jetty-server-9.4.44.v20210927.jar:9.4.44.v20210927] [junit4] 2> at org.eclipse.jetty.server.HttpChannel.lambda$handle$1(HttpChannel.java:400) ~[jetty-server-9.4.44.v20210927.jar:9.4.44.v20210927] [junit4] 2> at org.eclipse.jetty.server.HttpChannel.dispatch(HttpChannel.java:645) [jetty-server-9.4.44.v20210927.jar:9.4.44.v20210927] [junit4] 2> at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:392) [jetty-server-9.4.44.v20210927.jar:9.4.44.v20210927] [junit4] 2> at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:277) [jetty-server-9.4.44.v20210927.jar:9.4.44.v20210927] [junit4] 2> at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:311) [jetty-io-9.4.44.v20210927.jar:9.4.44.v20210927] [junit4] 2> at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:105) [jetty-io-9.4.44.v20210927.jar:9.4.44.v20210927] [junit4] 2> at org.eclipse.jetty.io.ChannelEndPoint$1.run(ChannelEndPoint.java:104) [jetty-io-9.4.44.v20210927.jar:9.4.44.v20210927] [junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:883) [jetty-util-9.4.44.v20210927.jar:9.4.44.v20210927] [junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool$Runner.run(QueuedThreadPool.java:1034) [jetty-util-9.4.44.v20210927.jar:9.4.44.v20210927] [junit4] 2> at java.lang.Thread.run(Thread.java:748) [?:1.8.0_291] [junit4] 2> 5021708 INFO (qtp523782236-111303) [n:127.0.0.1:39235_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=180003 [junit4] 2> 5021708 ERROR (qtp523782236-111303) [n:127.0.0.1:39235_solr ] o.a.s.s.HttpSolrCall org.apache.solr.common.SolrException: create the collection time out:180s [junit4] 2> => 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> org.apache.solr.common.SolrException: create the collection time out:180s [junit4] 2> at org.apache.solr.handler.admin.CollectionsHandler.sendToOCPQueue(CollectionsHandler.java:394) ~[java/:?] [junit4] 2> at org.apache.solr.handler.admin.CollectionsHandler.invokeAction(CollectionsHandler.java:299) ~[java/:?] [junit4] 2> at org.apache.solr.handler.admin.CollectionsHandler.handleRequestBody(CollectionsHandler.java:257) ~[java/:?] [junit4] 2> at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:216) ~[java/:?] [junit4] 2> at org.apache.solr.servlet.HttpSolrCall.handleAdmin(HttpSolrCall.java:836) ~[java/:?] [junit4] 2> at org.apache.solr.servlet.HttpSolrCall.handleAdminRequest(HttpSolrCall.java:800) ~[java/:?] [junit4] 2> at org.apache.solr.servlet.HttpSolrCall.call(HttpSolrCall.java:545) ~[java/:?] [junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:427) ~[java/:?] [junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:357) ~[java/:?] [junit4] 2> at org.eclipse.jetty.servlet.FilterHolder.doFilter(FilterHolder.java:193) ~[jetty-servlet-9.4.44.v20210927.jar:9.4.44.v20210927] [junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$Chain.doFilter(ServletHandler.java:1601) ~[jetty-servlet-9.4.44.v20210927.jar:9.4.44.v20210927] [junit4] 2> at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:166) ~[java/:?] [junit4] 2> at org.eclipse.jetty.servlet.FilterHolder.doFilter(FilterHolder.java:193) ~[jetty-servlet-9.4.44.v20210927.jar:9.4.44.v20210927] [junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$Chain.doFilter(ServletHandler.java:1601) ~[jetty-servlet-9.4.44.v20210927.jar:9.4.44.v20210927] [junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:548) ~[jetty-servlet-9.4.44.v20210927.jar:9.4.44.v20210927] [junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:233) ~[jetty-server-9.4.44.v20210927.jar:9.4.44.v20210927] [junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:1624) ~[jetty-server-9.4.44.v20210927.jar:9.4.44.v20210927] [junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:233) ~[jetty-server-9.4.44.v20210927.jar:9.4.44.v20210927] [junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1434) ~[jetty-server-9.4.44.v20210927.jar:9.4.44.v20210927] [junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:188) ~[jetty-server-9.4.44.v20210927.jar:9.4.44.v20210927] [junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:501) ~[jetty-servlet-9.4.44.v20210927.jar:9.4.44.v20210927] [junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:1594) ~[jetty-server-9.4.44.v20210927.jar:9.4.44.v20210927] [junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:186) ~[jetty-server-9.4.44.v20210927.jar:9.4.44.v20210927] [junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1349) ~[jetty-server-9.4.44.v20210927.jar:9.4.44.v20210927] [junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141) ~[jetty-server-9.4.44.v20210927.jar:9.4.44.v20210927] [junit4] 2> at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:127) ~[jetty-server-9.4.44.v20210927.jar:9.4.44.v20210927] [junit4] 2> at org.eclipse.jetty.rewrite.handler.RewriteHandler.handle(RewriteHandler.java:322) ~[jetty-rewrite-9.4.44.v20210927.jar:9.4.44.v20210927] [junit4] 2> at org.eclipse.jetty.server.handler.gzip.GzipHandler.handle(GzipHandler.java:763) ~[jetty-server-9.4.44.v20210927.jar:9.4.44.v20210927] [junit4] 2> at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:127) ~[jetty-server-9.4.44.v20210927.jar:9.4.44.v20210927] [junit4] 2> at org.eclipse.jetty.server.Server.handle(Server.java:516) ~[jetty-server-9.4.44.v20210927.jar:9.4.44.v20210927] [junit4] 2> at org.eclipse.jetty.server.HttpChannel.lambda$handle$1(HttpChannel.java:400) ~[jetty-server-9.4.44.v20210927.jar:9.4.44.v20210927] [junit4] 2> at org.eclipse.jetty.server.HttpChannel.dispatch(HttpChannel.java:645) [jetty-server-9.4.44.v20210927.jar:9.4.44.v20210927] [junit4] 2> at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:392) [jetty-server-9.4.44.v20210927.jar:9.4.44.v20210927] [junit4] 2> at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:277) [jetty-server-9.4.44.v20210927.jar:9.4.44.v20210927] [junit4] 2> at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:311) [jetty-io-9.4.44.v20210927.jar:9.4.44.v20210927] [junit4] 2> at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:105) [jetty-io-9.4.44.v20210927.jar:9.4.44.v20210927] [junit4] 2> at org.eclipse.jetty.io.ChannelEndPoint$1.run(ChannelEndPoint.java:104) [jetty-io-9.4.44.v20210927.jar:9.4.44.v20210927] [junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:883) [jetty-util-9.4.44.v20210927.jar:9.4.44.v20210927] [junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool$Runner.run(QueuedThreadPool.java:1034) [jetty-util-9.4.44.v20210927.jar:9.4.44.v20210927] [junit4] 2> at java.lang.Thread.run(Thread.java:748) [?:1.8.0_291] [junit4] 2> 5021710 INFO (jetty-closer-47002-thread-1) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=959048643 [junit4] 2> 5021710 INFO (jetty-closer-47002-thread-1) [ ] o.a.s.c.ZkController Remove node as live in ZooKeeper:/live_nodes/127.0.0.1:39235_solr [junit4] 2> 5021710 INFO (jetty-closer-47002-thread-1) [ ] o.a.s.c.ZkController Publish this node as DOWN... [junit4] 2> 5021710 INFO (jetty-closer-47002-thread-1) [ ] o.a.s.c.ZkController Publish node=127.0.0.1:39235_solr as DOWN [junit4] 2> 5021716 INFO (zkCallback-46949-thread-8) [ ] 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> 5021717 INFO (coreCloseExecutor-47006-thread-1) [n:127.0.0.1:39235_solr ] o.a.s.c.SolrCore [collection1_shard2_replica_n7] CLOSING SolrCore org.apache.solr.core.SolrCore@2d5ff4ff [junit4] 2> 5021717 INFO (coreCloseExecutor-47006-thread-2) [n:127.0.0.1:39235_solr ] o.a.s.c.SolrCore [collection1_shard2_replica_n5] CLOSING SolrCore org.apache.solr.core.SolrCore@7deb9c4f [junit4] 2> 5021717 INFO (coreCloseExecutor-47006-thread-1) [n:127.0.0.1:39235_solr ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1.shard2.replica_n7 tag=SolrCore@2d5ff4ff [junit4] 2> 5021717 INFO (coreCloseExecutor-47006-thread-1) [n:127.0.0.1:39235_solr ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.collection1.shard2.leader tag=SolrCore@2d5ff4ff [junit4] 2> 5021718 INFO (zkCallback-46949-thread-7) [ ] 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> 5021721 INFO (zkCallback-46949-thread-9) [ ] 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> 5021721 INFO (coreCloseExecutor-47006-thread-2) [...truncated too long message...] tDocTermOrds, TestUpdate, PeerSyncWithLeaderTest, CopyFieldTest, TestSkipOverseerOperations, BaseCdcrDistributedZkTest, DeleteNodeTest, CoreMergeIndexesAdminHandlerTest, TestPerFieldSimilarityWithDefaultOverride, TestSolrQueryParser, CurrencyRangeFacetCloudTest, TestReload, TestUnInvertedFieldException, TestRetrieveFieldsOptimizer, TestCopyFieldCollectionResource, TestDistribDocBasedVersion, SolrTestCaseJ4Test, TestSnapshotCoreBackup, CdcrReplicationHandlerTest, DeleteByIdWithRouterFieldTest, FieldAnalysisRequestHandlerTest, CoreAdminCreateDiscoverTest, TestOmitPositions, TestConfigReload, AddBlockUpdateTest, ConcurrentCreateCollectionTest, DisMaxRequestHandlerTest, DistributedIntervalFacetingTest, EchoParamsTest, OutputWriterTest, SampleTest, SolrInfoBeanTest, SolrTestCaseJ4DeleteCoreTest, PathHierarchyTokenizerFactoryTest, ProtectedTermFilterFactoryTest, TestCharFilters, TestLuceneMatchVersion, TestReversedWildcardFilterFactory, TestWordDelimiterFilterFactory, TokenizerChainTest, TestEmbeddedSolrServerSchemaAPI, AddReplicaTest, AliasIntegrationTest, AssignBackwardCompatibilityTest, BasicDistributedZk2Test, BasicDistributedZkTest, ChaosMonkeySafeLeaderWithPullReplicasTest, CleanupOldIndexTest, ClusterStateMockUtilTest, ClusterStateTest, ClusterStateUpdateTest, ConnectionManagerTest, CreateCollectionCleanupTest, CreateRoutedAliasTest, DeleteInactiveReplicaTest, DistribCursorPagingTest, DistribDocExpirationUpdateProcessorTest, DistribJoinFromCollectionTest, ForceLeaderWithTlogReplicasTest, FullSolrCloudDistribCmdsTest, HttpPartitionOnCommitTest, HttpPartitionTest, LeaderFailoverAfterPartitionTest, MoveReplicaHDFSFailoverTest, MoveReplicaHDFSTest, MoveReplicaTest, MultiThreadedOCPTest, NestedShardedAtomicUpdateTest, NodeMutatorTest, OverriddenZkACLAndCredentialsProvidersTest, OverseerCollectionConfigSetProcessorTest, OverseerModifyCollectionTest, OverseerRolesTest, OverseerTaskQueueTest, OverseerTest, PeerSyncReplicationTest, SystemCollectionCompatTest, TestCloudConsistency, TestCloudRecovery, TestExclusionRuleCollectionAccess, TestLeaderElectionWithEmptyReplica, TestStressCloudBlindAtomicUpdates, TestTlogReplayVsRecovery, TestTlogReplica, TestTolerantUpdateProcessorCloud, TestTolerantUpdateProcessorRandomCloud, TestUtilizeNode, TestWaitForStateWithJettyShutdowns, TestWithCollection, TestZkChroot, ZkControllerTest, ZkFailoverTest, ZkNodePropsTest, ZkSolrClientTest, AssignTest, AsyncCallRequestStatusResponseTest, CollectionReloadTest, CollectionTooManyReplicasTest, CollectionsAPIAsyncDistributedZkTest, CollectionsAPIDistributedZkTest, HdfsCollectionsAPIDistributedZkTest, LocalFSCloudIncrementalBackupTest, PurgeGraphTest, SimpleCollectionCreateDeleteTest, SplitByPrefixTest, TestCollectionAPI, TestCollectionsAPIViaSolrCloudCluster, TestHdfsCloudBackupRestore, TestLocalFSCloudBackupRestore, TestReplicaProperties, IndexSizeTriggerSizeEstimationTest, NodeAddedTriggerIntegrationTest, NodeLostTriggerIntegrationTest, NodeLostTriggerTest, ScheduledMaintenanceTriggerTest, TestSimDistributedQueue, TestSimExecutePlanAction, TestSimExtremeIndexing, TestSimGenericDistributedQueue, TestSnapshotCloudManager, HdfsChaosMonkeyNothingIsSafeTest, HdfsChaosMonkeySafeLeaderTest, HdfsRecoverLeaseTest, HdfsRecoveryZkTest, HdfsSyncSliceTest, ZkCollectionPropsCachingTest, ZkStateReaderTest, ZkStateWriterTest, AlternateDirectoryTest, BlobRepositoryCloudTest, ByteBuffersDirectoryFactoryTest, CachingDirectoryFactoryTest, PluginInfoTest, QueryResultKeyTest, TestCodecSupport, TestConfigSetImmutable, TestCorePropertiesReload, TestCustomStream, TestMergePolicyConfig, TestMinimalConfig, TestNRTOpen, TestQuerySenderListener, TestQuerySenderNoQuery, TestSolrXml, TestXIncludeConfig, BackupIdTest, HdfsBackupRepositoryIntegrationTest, TestDistribPackageStore, BinaryUpdateRequestHandlerTest, CSVRequestHandlerTest, PingRequestHandlerTest, TestReplicationHandler, RegexBytesRefFilterTest, SubstringBytesRefFilterTest, TestFacetMethods, TestFaceting, TestRemoteStreaming, TestSolrRequestInfo, TestStreamBody, TestCSVResponseWriter, TestJavabinTupleStreamParser, TestSubQueryTransformerDistrib, TestManagedResource, TestManagedResourceStorage, TestRestManager, TestBulkSchemaAPI, TestManagedSynonymFilterFactory, ChangedSchemaMergeTest, DocValuesMissingTest, ManagedSchemaRoundRobinCloudTest, NotRequiredUniqueKeyTest, NumericFieldsTest, OpenExchangeRatesOrgProviderTest, PolyFieldTest, PreAnalyzedFieldManagedSchemaCloudTest, PreAnalyzedFieldTest, RequiredFieldsTest, SchemaApiFailureTest, TestHalfAndHalfDocValues, TestManagedSchema, TestUseDocValuesAsStored2, AnalyticsQueryTest, MaxScoreCollectorTest, MergeStrategyTest, RankQueryTest, ReturnFieldsTest, SortSpecParsingTest, TestDocSet, TestElisionMultitermQuery, TestFastLRUCache, TestFilteredDocIdSet, TestFiltering, TestRecoveryHdfs, SpatialHeatmapFacetsTest, TestJsonFacetsStatsParsing, TestJsonFacetsWithNestedObjects, TestJsonRangeFacets, AggValueSourceTest, SortByFunctionTest, TestFunctionQuery, TestMinMaxOnMultiValuedField, TestOrdValues, BlockJoinFacetDistribTest, BlockJoinFacetRandomTest, BlockJoinFacetSimpleTest, CrossCollectionJoinQueryTest, InvalidConfigJoinQueryTest, TestCloudNestedDocsSort, TestNestedDocsSort, TestScoreJoinQPNoScore, CloudMLTQParserTest, TestClassicSimilarityFactory, TestDefaultStatsCache, TestLRUStatsCache, SolrLogAuditLoggerPluginTest, TestAuthorizationFramework, DirectSolrConnectionTest, HttpSolrCallGetCoreTest] [junit4] 2> NOTE: download the large Jenkins line-docs file by running 'ant get-jenkins-line-docs' in the lucene directory. [junit4] 2> NOTE: reproduce with: ant test -Dtestcase=HttpSolrCallGetCoreTest -Dtests.seed=F495435EAE901B78 -Dtests.multiplier=2 -Dtests.nightly=true -Dtests.slow=true -Dtests.linedocsfile=/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-NightlyTests-8.11/test-data/enwiki.random.lines.txt -Dtests.locale=sq-AL -Dtests.timezone=Africa/Djibouti -Dtests.asserts=true -Dtests.file.encoding=UTF-8 [junit4] ERROR 0.00s J0 | HttpSolrCallGetCoreTest (suite) <<< [junit4] > Throwable #1: org.apache.solr.client.solrj.SolrServerException: Timeout occurred while waiting response from server at: http://127.0.0.1:39235/solr [junit4] > at __randomizedtesting.SeedInfo.seed([F495435EAE901B78]: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 [901/955 (1!)] on J0 in 182.35s, 0 tests, 1 error <<< FAILURES! [...truncated 54497 lines...]
--------------------------------------------------------------------- To unsubscribe, e-mail: [email protected] For additional commands, e-mail: [email protected]
