Build: https://ci-builds.apache.org/job/Lucene/job/Lucene-Solr-Tests-8.x/1043/
1 tests failed. FAILED: org.apache.solr.cloud.TestWithCollection.testNodeAdded Error Message: Action was not fired till 30 seconds Stack Trace: java.lang.AssertionError: Action was not fired till 30 seconds at __randomizedtesting.SeedInfo.seed([654426C6B60C3717:8770B114AF9F14]:0) at org.junit.Assert.fail(Assert.java:89) at org.junit.Assert.assertTrue(Assert.java:42) at org.apache.solr.cloud.TestWithCollection.testNodeAdded(TestWithCollection.java:471) 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$8.evaluate(RandomizedRunner.java:938) at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:974) at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:988) 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.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) at 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 com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817) at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468) at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:947) at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:832) at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:883) 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) Build Log: [...truncated 13378 lines...] [junit4] Suite: org.apache.solr.cloud.TestWithCollection [junit4] 2> 13567 INFO (SUITE-TestWithCollection-seed#[654426C6B60C3717]-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-Tests-8.x/solr/server/solr/configsets/_default/conf' [junit4] 2> 13570 INFO (SUITE-TestWithCollection-seed#[654426C6B60C3717]-worker) [ ] o.a.s.SolrTestCaseJ4 Created dataDir: /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/build/solr-core/test/J3/temp/solr.cloud.TestWithCollection_654426C6B60C3717-001/data-dir-2-001 [junit4] 2> 13570 WARN (SUITE-TestWithCollection-seed#[654426C6B60C3717]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=10 numCloses=10 [junit4] 2> 13571 INFO (SUITE-TestWithCollection-seed#[654426C6B60C3717]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=true [junit4] 2> 13572 INFO (SUITE-TestWithCollection-seed#[654426C6B60C3717]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason=, ssl=NaN, value=NaN, clientAuth=NaN) [junit4] 2> 13573 INFO (SUITE-TestWithCollection-seed#[654426C6B60C3717]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom [junit4] 2> 13576 INFO (TEST-TestWithCollection.testCreateCollectionNoWithCollection-seed#[654426C6B60C3717]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testCreateCollectionNoWithCollection [junit4] 2> 13579 INFO (TEST-TestWithCollection.testCreateCollectionNoWithCollection-seed#[654426C6B60C3717]) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 2 servers in /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/build/solr-core/test/J3/temp/solr.cloud.TestWithCollection_654426C6B60C3717-001/tempDir-001 [junit4] 2> 13586 INFO (TEST-TestWithCollection.testCreateCollectionNoWithCollection-seed#[654426C6B60C3717]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER [junit4] 2> 13590 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer client port: 0.0.0.0/0.0.0.0:0 [junit4] 2> 13590 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Starting server [junit4] 2> 13847 WARN (ZkTestServer Run Thread) [ ] o.a.z.s.ServerCnxnFactory maxCnxns is not configured, using default value 0. [junit4] 2> 13876 INFO (TEST-TestWithCollection.testCreateCollectionNoWithCollection-seed#[654426C6B60C3717]) [ ] o.a.s.c.ZkTestServer start zk server on port: 44315 [junit4] 2> 13876 INFO (TEST-TestWithCollection.testCreateCollectionNoWithCollection-seed#[654426C6B60C3717]) [ ] o.a.s.c.ZkTestServer waitForServerUp: 127.0.0.1:44315 [junit4] 2> 13876 INFO (TEST-TestWithCollection.testCreateCollectionNoWithCollection-seed#[654426C6B60C3717]) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:44315 [junit4] 2> 13877 INFO (TEST-TestWithCollection.testCreateCollectionNoWithCollection-seed#[654426C6B60C3717]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 44315 [junit4] 2> 14138 INFO (TEST-TestWithCollection.testCreateCollectionNoWithCollection-seed#[654426C6B60C3717]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 14186 INFO (zkConnectionManagerCallback-24-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 14187 INFO (TEST-TestWithCollection.testCreateCollectionNoWithCollection-seed#[654426C6B60C3717]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 14389 INFO (TEST-TestWithCollection.testCreateCollectionNoWithCollection-seed#[654426C6B60C3717]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 14410 INFO (zkConnectionManagerCallback-26-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 14410 INFO (TEST-TestWithCollection.testCreateCollectionNoWithCollection-seed#[654426C6B60C3717]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 14414 INFO (TEST-TestWithCollection.testCreateCollectionNoWithCollection-seed#[654426C6B60C3717]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 14421 INFO (zkConnectionManagerCallback-28-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 14421 INFO (TEST-TestWithCollection.testCreateCollectionNoWithCollection-seed#[654426C6B60C3717]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 14754 INFO (jetty-launcher-29-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0) [junit4] 2> 14754 INFO (jetty-launcher-29-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ... [junit4] 2> 14754 INFO (jetty-launcher-29-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0) [junit4] 2> 14755 INFO (jetty-launcher-29-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ... [junit4] 2> 14761 INFO (jetty-launcher-29-thread-2) [ ] o.e.j.s.Server jetty-9.4.34.v20201102; built: 2020-11-02T14:15:39.302Z; git: e46af88704a893fc12cb0e3bf46e2c7b48a009e7; jvm 1.8.0_252-b09 [junit4] 2> 14761 INFO (jetty-launcher-29-thread-1) [ ] o.e.j.s.Server jetty-9.4.34.v20201102; built: 2020-11-02T14:15:39.302Z; git: e46af88704a893fc12cb0e3bf46e2c7b48a009e7; jvm 1.8.0_252-b09 [junit4] 2> 14985 INFO (jetty-launcher-29-thread-2) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0 [junit4] 2> 14985 INFO (jetty-launcher-29-thread-2) [ ] o.e.j.s.session No SessionScavenger set, using defaults [junit4] 2> 14988 INFO (jetty-launcher-29-thread-2) [ ] o.e.j.s.session node0 Scavenging every 660000ms [junit4] 2> 14990 INFO (jetty-launcher-29-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0 [junit4] 2> 14990 INFO (jetty-launcher-29-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults [junit4] 2> 14990 INFO (jetty-launcher-29-thread-1) [ ] o.e.j.s.session node0 Scavenging every 660000ms [junit4] 2> 15013 INFO (jetty-launcher-29-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@21204f61{/solr,null,AVAILABLE} [junit4] 2> 15013 INFO (jetty-launcher-29-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@3608b45d{/solr,null,AVAILABLE} [junit4] 2> 15022 INFO (jetty-launcher-29-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@1cfa21ee{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:37271} [junit4] 2> 15022 INFO (jetty-launcher-29-thread-1) [ ] o.e.j.s.Server Started @15049ms [junit4] 2> 15022 INFO (jetty-launcher-29-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, zkHost=127.0.0.1:44315/solr, hostPort=37271} [junit4] 2> 15022 INFO (jetty-launcher-29-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@3ff55e7e{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:37423} [junit4] 2> 15023 INFO (jetty-launcher-29-thread-2) [ ] o.e.j.s.Server Started @15050ms [junit4] 2> 15023 INFO (jetty-launcher-29-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, zkHost=127.0.0.1:44315/solr, hostPort=37423} [junit4] 2> 15048 ERROR (jetty-launcher-29-thread-2) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 15048 ERROR (jetty-launcher-29-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 15049 INFO (jetty-launcher-29-thread-2) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory [junit4] 2> 15049 INFO (jetty-launcher-29-thread-1) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory [junit4] 2> 15049 INFO (jetty-launcher-29-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.8.0 [junit4] 2> 15049 INFO (jetty-launcher-29-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.8.0 [junit4] 2> 15049 INFO (jetty-launcher-29-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 15049 INFO (jetty-launcher-29-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 15049 INFO (jetty-launcher-29-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr [junit4] 2> 15049 INFO (jetty-launcher-29-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr [junit4] 2> 15049 INFO (jetty-launcher-29-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-12-10T15:57:11.905Z [junit4] 2> 15049 INFO (jetty-launcher-29-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-12-10T15:57:11.905Z [junit4] 2> 15058 INFO (jetty-launcher-29-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 15058 INFO (jetty-launcher-29-thread-2) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 15062 INFO (zkConnectionManagerCallback-33-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 15062 INFO (jetty-launcher-29-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 15068 INFO (zkConnectionManagerCallback-32-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 15068 INFO (jetty-launcher-29-thread-2) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 15070 INFO (jetty-launcher-29-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 15071 INFO (jetty-launcher-29-thread-2) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 15086 WARN (jetty-launcher-29-thread-2-SendThread(127.0.0.1:44315)) [ ] o.a.z.ClientCnxn An exception was thrown while closing send thread for session 0x102044c00c00004. [junit4] 2> => EndOfStreamException: Unable to read additional data from server sessionid 0x102044c00c00004, likely server has closed socket [junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:77) [junit4] 2> org.apache.zookeeper.ClientCnxn$EndOfStreamException: Unable to read additional data from server sessionid 0x102044c00c00004, likely server has closed socket [junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:77) ~[zookeeper-3.6.2.jar:3.6.2] [junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doTransport(ClientCnxnSocketNIO.java:350) ~[zookeeper-3.6.2.jar:3.6.2] [junit4] 2> at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1275) [zookeeper-3.6.2.jar:3.6.2] [junit4] 2> 15086 WARN (jetty-launcher-29-thread-1-SendThread(127.0.0.1:44315)) [ ] o.a.z.ClientCnxn An exception was thrown while closing send thread for session 0x102044c00c00003. [junit4] 2> => EndOfStreamException: Unable to read additional data from server sessionid 0x102044c00c00003, likely server has closed socket [junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:77) [junit4] 2> org.apache.zookeeper.ClientCnxn$EndOfStreamException: Unable to read additional data from server sessionid 0x102044c00c00003, likely server has closed socket [junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:77) ~[zookeeper-3.6.2.jar:3.6.2] [junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doTransport(ClientCnxnSocketNIO.java:350) ~[zookeeper-3.6.2.jar:3.6.2] [junit4] 2> at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1275) [zookeeper-3.6.2.jar:3.6.2] [junit4] 2> 15977 INFO (jetty-launcher-29-thread-2) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true] [junit4] 2> 16221 WARN (jetty-launcher-29-thread-2) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@3168fc1a[provider=null,keyStore=null,trustStore=null] [junit4] 2> 16221 WARN (jetty-launcher-29-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@3168fc1a[provider=null,keyStore=null,trustStore=null] [junit4] 2> 16249 WARN (jetty-launcher-29-thread-2) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@32c00788[provider=null,keyStore=null,trustStore=null] [junit4] 2> 16250 WARN (jetty-launcher-29-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@32c00788[provider=null,keyStore=null,trustStore=null] [junit4] 2> 16251 INFO (jetty-launcher-29-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:44315/solr [junit4] 2> 16471 INFO (jetty-launcher-29-thread-2) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 16493 INFO (zkConnectionManagerCallback-47-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 16493 INFO (jetty-launcher-29-thread-2) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 16496 WARN (jetty-launcher-29-thread-2-SendThread(127.0.0.1:44315)) [ ] o.a.z.ClientCnxn An exception was thrown while closing send thread for session 0x102044c00c00005. [junit4] 2> => EndOfStreamException: Unable to read additional data from server sessionid 0x102044c00c00005, likely server has closed socket [junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:77) [junit4] 2> org.apache.zookeeper.ClientCnxn$EndOfStreamException: Unable to read additional data from server sessionid 0x102044c00c00005, likely server has closed socket [junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:77) ~[zookeeper-3.6.2.jar:3.6.2] [junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doTransport(ClientCnxnSocketNIO.java:350) ~[zookeeper-3.6.2.jar:3.6.2] [junit4] 2> at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1275) [zookeeper-3.6.2.jar:3.6.2] [junit4] 2> 16602 INFO (jetty-launcher-29-thread-2) [n:127.0.0.1:37423_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 16606 INFO (zkConnectionManagerCallback-49-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 16606 INFO (jetty-launcher-29-thread-2) [n:127.0.0.1:37423_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 17046 INFO (jetty-launcher-29-thread-2) [n:127.0.0.1:37423_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:37423_solr [junit4] 2> 17051 INFO (jetty-launcher-29-thread-2) [n:127.0.0.1:37423_solr ] o.a.s.c.Overseer Overseer (id=72625268467957766-127.0.0.1:37423_solr-n_0000000000) starting [junit4] 2> 17182 INFO (jetty-launcher-29-thread-1) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true] [junit4] 2> 17184 INFO (OverseerStateUpdate-72625268467957766-127.0.0.1:37423_solr-n_0000000000) [n:127.0.0.1:37423_solr ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:37423_solr [junit4] 2> 17187 WARN (jetty-launcher-29-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@6c90bf11[provider=null,keyStore=null,trustStore=null] [junit4] 2> 17189 WARN (jetty-launcher-29-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@6c90bf11[provider=null,keyStore=null,trustStore=null] [junit4] 2> 17192 INFO (jetty-launcher-29-thread-2) [n:127.0.0.1:37423_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:37423_solr [junit4] 2> 17206 WARN (jetty-launcher-29-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@675ac74c[provider=null,keyStore=null,trustStore=null] [junit4] 2> 17206 WARN (jetty-launcher-29-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@675ac74c[provider=null,keyStore=null,trustStore=null] [junit4] 2> 17207 INFO (jetty-launcher-29-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:44315/solr [junit4] 2> 17230 INFO (jetty-launcher-29-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 17456 INFO (zkCallback-48-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 17466 INFO (zkConnectionManagerCallback-64-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 17466 INFO (jetty-launcher-29-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 17473 DEBUG (OverseerAutoScalingTriggerThread-72625268467957766-127.0.0.1:37423_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Adding .auto_add_replicas and .scheduled_maintenance triggers [junit4] 2> 17499 DEBUG (OverseerAutoScalingTriggerThread-72625268467957766-127.0.0.1:37423_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 1 [junit4] 2> 17510 INFO (jetty-launcher-29-thread-2) [n:127.0.0.1:37423_solr ] o.a.s.p.PackageLoader /packages.json updated to version -1 [junit4] 2> 17514 WARN (jetty-launcher-29-thread-2) [n:127.0.0.1:37423_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> 17590 INFO (jetty-launcher-29-thread-1) [n:127.0.0.1:37271_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 17602 INFO (jetty-launcher-29-thread-2) [n:127.0.0.1:37423_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory. [junit4] 2> 17611 INFO (zkConnectionManagerCallback-67-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 17612 INFO (jetty-launcher-29-thread-1) [n:127.0.0.1:37271_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 17617 INFO (jetty-launcher-29-thread-1) [n:127.0.0.1:37271_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 17624 INFO (jetty-launcher-29-thread-1) [n:127.0.0.1:37271_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:37271_solr as DOWN [junit4] 2> 17627 INFO (jetty-launcher-29-thread-1) [n:127.0.0.1:37271_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores [junit4] 2> 17627 INFO (jetty-launcher-29-thread-1) [n:127.0.0.1:37271_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:37271_solr [junit4] 2> 17628 INFO (zkCallback-48-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2) [junit4] 2> 17631 DEBUG (OverseerStateUpdate-72625268467957766-127.0.0.1:37423_solr-n_0000000000) [n:127.0.0.1:37423_solr ] o.a.s.c.o.NodeMutator DownNode state invoked for node: 127.0.0.1:37271_solr [junit4] 2> 17639 INFO (jetty-launcher-29-thread-1) [n:127.0.0.1:37271_solr ] o.a.s.p.PackageLoader /packages.json updated to version -1 [junit4] 2> 17640 WARN (jetty-launcher-29-thread-1) [n:127.0.0.1:37271_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> 17640 INFO (zkCallback-66-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2) [junit4] 2> 17660 INFO (jetty-launcher-29-thread-2) [n:127.0.0.1:37423_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_37423.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@264190dd [junit4] 2> 17661 INFO (jetty-launcher-29-thread-2) [n:127.0.0.1:37423_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_37423.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@264190dd [junit4] 2> 17661 INFO (jetty-launcher-29-thread-2) [n:127.0.0.1:37423_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_37423.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@264190dd [junit4] 2> 17663 INFO (jetty-launcher-29-thread-2) [n:127.0.0.1:37423_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/build/solr-core/test/J3/temp/solr.cloud.TestWithCollection_654426C6B60C3717-001/tempDir-001/node2 [junit4] 2> 17666 DEBUG (OverseerAutoScalingTriggerThread-72625268467957766-127.0.0.1:37423_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1, lastZnodeVersion 0 [junit4] 2> 17667 DEBUG (OverseerAutoScalingTriggerThread-72625268467957766-127.0.0.1:37423_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 1 [junit4] 2> 17678 INFO (jetty-launcher-29-thread-1) [n:127.0.0.1:37271_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory. [junit4] 2> 17722 DEBUG (OverseerAutoScalingTriggerThread-72625268467957766-127.0.0.1:37423_solr-n_0000000000) [ ] o.a.s.c.a.NodeLostTrigger NodeLostTrigger .auto_add_replicas - Initial livenodes: [127.0.0.1:37423_solr, 127.0.0.1:37271_solr] [junit4] 2> 17728 DEBUG (OverseerAutoScalingTriggerThread-72625268467957766-127.0.0.1:37423_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread -- deactivating old nodeLost / nodeAdded markers [junit4] 2> 17728 DEBUG (OverseerAutoScalingTriggerThread-72625268467957766-127.0.0.1:37423_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1, lastZnodeVersion 1 [junit4] 2> 17730 DEBUG (ScheduledTrigger-54-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2 [junit4] 2> 17730 TRACE (ScheduledTrigger-54-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Current Live Nodes for .auto_add_replicas: [127.0.0.1:37423_solr, 127.0.0.1:37271_solr] [junit4] 2> 17730 TRACE (ScheduledTrigger-54-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Last Live Nodes for .auto_add_replicas: [127.0.0.1:37423_solr, 127.0.0.1:37271_solr] [junit4] 2> 17737 INFO (jetty-launcher-29-thread-1) [n:127.0.0.1:37271_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_37271.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@264190dd [junit4] 2> 17748 INFO (jetty-launcher-29-thread-1) [n:127.0.0.1:37271_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_37271.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@264190dd [junit4] 2> 17748 INFO (jetty-launcher-29-thread-1) [n:127.0.0.1:37271_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_37271.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@264190dd [junit4] 2> 17753 INFO (jetty-launcher-29-thread-1) [n:127.0.0.1:37271_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/build/solr-core/test/J3/temp/solr.cloud.TestWithCollection_654426C6B60C3717-001/tempDir-001/node1 [junit4] 2> 17781 INFO (TEST-TestWithCollection.testCreateCollectionNoWithCollection-seed#[654426C6B60C3717]) [ ] o.a.s.c.MiniSolrCloudCluster waitForAllNodes: numServers=2 [junit4] 2> 17784 INFO (TEST-TestWithCollection.testCreateCollectionNoWithCollection-seed#[654426C6B60C3717]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 18016 INFO (zkConnectionManagerCallback-84-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 18016 INFO (TEST-TestWithCollection.testCreateCollectionNoWithCollection-seed#[654426C6B60C3717]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 18020 INFO (TEST-TestWithCollection.testCreateCollectionNoWithCollection-seed#[654426C6B60C3717]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2) [junit4] 2> 18027 INFO (TEST-TestWithCollection.testCreateCollectionNoWithCollection-seed#[654426C6B60C3717]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:44315/solr ready [junit4] 2> 18039 DEBUG (zkCallback-48-thread-1) [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 2 [junit4] 2> 18039 DEBUG (OverseerAutoScalingTriggerThread-72625268467957766-127.0.0.1:37423_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 2 [junit4] 2> 18043 DEBUG (OverseerAutoScalingTriggerThread-72625268467957766-127.0.0.1:37423_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread -- deactivating old nodeLost / nodeAdded markers [junit4] 2> 18048 DEBUG (OverseerAutoScalingTriggerThread-72625268467957766-127.0.0.1:37423_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 2, lastZnodeVersion 2 [junit4] 2> 18619 INFO (OverseerThreadFactory-59-thread-1-processing-n:127.0.0.1:37423_solr) [n:127.0.0.1:37423_solr ] o.a.s.c.a.c.CreateCollectionCmd Create collection testCreateCollectionNoWithCollection_xyz [junit4] 2> 18619 ERROR (OverseerThreadFactory-59-thread-1-processing-n:127.0.0.1:37423_solr) [n:127.0.0.1:37423_solr ] o.a.s.c.a.c.OverseerCollectionMessageHandler Collection: testCreateCollectionNoWithCollection_xyz operation: create failed:org.apache.solr.common.SolrException: The 'withCollection' does not exist: testCreateCollectionNoWithCollection_abc [junit4] 2> at org.apache.solr.cloud.api.collections.CreateCollectionCmd.call(CreateCollectionCmd.java:129) [junit4] 2> at org.apache.solr.cloud.api.collections.OverseerCollectionMessageHandler.processMessage(OverseerCollectionMessageHandler.java:265) [junit4] 2> at org.apache.solr.cloud.OverseerTaskProcessor$Runner.run(OverseerTaskProcessor.java:524) [junit4] 2> at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:218) [junit4] 2> at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149) [junit4] 2> at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) [junit4] 2> at java.lang.Thread.run(Thread.java:748) [junit4] 2> [junit4] 2> 18640 INFO (qtp1099228871-64) [n:127.0.0.1:37423_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={pullReplicas=0&withCollection=testCreateCollectionNoWithCollection_abc&name=testCreateCollectionNoWithCollection_xyz&nrtReplicas=1&action=CREATE&numShards=1&tlogReplicas=0&wt=javabin&version=2} status=400 QTime=54 [junit4] 2> 18687 INFO (OverseerThreadFactory-59-thread-2-processing-n:127.0.0.1:37423_solr) [n:127.0.0.1:37423_solr ] o.a.s.c.a.c.CreateCollectionCmd Create collection testCreateCollectionNoWithCollection_abc [junit4] 2> 18687 INFO (OverseerCollectionConfigSetProcessor-72625268467957766-127.0.0.1:37423_solr-n_0000000000) [n:127.0.0.1:37423_solr ] o.a.s.c.OverseerTaskQueue Response ZK path: /overseer/collection-queue-work/qnr-0000000000 doesn't exist. Requestor may have disconnected from ZooKeeper [junit4] 2> 18687 INFO (OverseerThreadFactory-59-thread-2-processing-n:127.0.0.1:37423_solr) [n:127.0.0.1:37423_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default to /configs/testCreateCollectionNoWithCollection_abc.AUTOCREATED [junit4] 2> 18690 INFO (OverseerThreadFactory-59-thread-2-processing-n:127.0.0.1:37423_solr) [n:127.0.0.1:37423_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/protwords.txt to /configs/testCreateCollectionNoWithCollection_abc.AUTOCREATED/protwords.txt [junit4] 2> 18692 INFO (OverseerThreadFactory-59-thread-2-processing-n:127.0.0.1:37423_solr) [n:127.0.0.1:37423_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/managed-schema to /configs/testCreateCollectionNoWithCollection_abc.AUTOCREATED/managed-schema [junit4] 2> 18694 INFO (OverseerThreadFactory-59-thread-2-processing-n:127.0.0.1:37423_solr) [n:127.0.0.1:37423_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/solrconfig.xml to /configs/testCreateCollectionNoWithCollection_abc.AUTOCREATED/solrconfig.xml [junit4] 2> 18697 INFO (OverseerThreadFactory-59-thread-2-processing-n:127.0.0.1:37423_solr) [n:127.0.0.1:37423_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/contractions_it.txt to /configs/testCreateCollectionNoWithCollection_abc.AUTOCREATED/lang/contractions_it.txt [junit4] 2> 18700 INFO (OverseerThreadFactory-59-thread-2-processing-n:127.0.0.1:37423_solr) [n:127.0.0.1:37423_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/contractions_ca.txt to /configs/testCreateCollectionNoWithCollection_abc.AUTOCREATED/lang/contractions_ca.txt [junit4] 2> 18703 INFO (OverseerThreadFactory-59-thread-2-processing-n:127.0.0.1:37423_solr) [n:127.0.0.1:37423_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_no.txt to /configs/testCreateCollectionNoWithCollection_abc.AUTOCREATED/lang/stopwords_no.txt [junit4] 2> 18706 INFO (OverseerThreadFactory-59-thread-2-processing-n:127.0.0.1:37423_solr) [n:127.0.0.1:37423_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stemdict_nl.txt to /configs/testCreateCollectionNoWithCollection_abc.AUTOCREATED/lang/stemdict_nl.txt [junit4] 2> 18709 INFO (OverseerThreadFactory-59-thread-2-processing-n:127.0.0.1:37423_solr) [n:127.0.0.1:37423_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_hy.txt to /configs/testCreateCollectionNoWithCollection_abc.AUTOCREATED/lang/stopwords_hy.txt [junit4] 2> 18720 INFO (OverseerThreadFactory-59-thread-2-processing-n:127.0.0.1:37423_solr) [n:127.0.0.1:37423_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_id.txt to /configs/testCreateCollectionNoWithCollection_abc.AUTOCREATED/lang/stopwords_id.txt [junit4] 2> 18722 INFO (OverseerThreadFactory-59-thread-2-processing-n:127.0.0.1:37423_solr) [n:127.0.0.1:37423_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_et.txt to /configs/testCreateCollectionNoWithCollection_abc.AUTOCREATED/lang/stopwords_et.txt [junit4] 2> 18724 INFO (OverseerThreadFactory-59-thread-2-processing-n:127.0.0.1:37423_solr) [n:127.0.0.1:37423_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_hi.txt to /configs/testCreateCollectionNoWithCollection_abc.AUTOCREATED/lang/stopwords_hi.txt [junit4] 2> 18729 INFO (OverseerThreadFactory-59-thread-2-processing-n:127.0.0.1:37423_solr) [n:127.0.0.1:37423_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_da.txt to /configs/testCreateCollectionNoWithCollection_abc.AUTOCREATED/lang/stopwords_da.txt [junit4] 2> 18731 INFO (OverseerThreadFactory-59-thread-2-processing-n:127.0.0.1:37423_solr) [n:127.0.0.1:37423_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ga.txt to /configs/testCreateCollectionNoWithCollection_abc.AUTOCREATED/lang/stopwords_ga.txt [junit4] 2> 18734 INFO (OverseerThreadFactory-59-thread-2-processing-n:127.0.0.1:37423_solr) [n:127.0.0.1:37423_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ja.txt to /configs/testCreateCollectionNoWithCollection_abc.AUTOCREATED/lang/stopwords_ja.txt [junit4] 2> 18736 INFO (OverseerThreadFactory-59-thread-2-processing-n:127.0.0.1:37423_solr) [n:127.0.0.1:37423_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_pt.txt to /configs/testCreateCollectionNoWithCollection_abc.AUTOCREATED/lang/stopwords_pt.txt [junit4] 2> 18738 INFO (OverseerThreadFactory-59-thread-2-processing-n:127.0.0.1:37423_solr) [n:127.0.0.1:37423_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_hu.txt to /configs/testCreateCollectionNoWithCollection_abc.AUTOCREATED/lang/stopwords_hu.txt [junit4] 2> 18740 INFO (OverseerThreadFactory-59-thread-2-processing-n:127.0.0.1:37423_solr) [n:127.0.0.1:37423_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_el.txt to /configs/testCreateCollectionNoWithCollection_abc.AUTOCREATED/lang/stopwords_el.txt [junit4] 2> 18742 INFO (OverseerThreadFactory-59-thread-2-processing-n:127.0.0.1:37423_solr) [n:127.0.0.1:37423_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ru.txt to /configs/testCreateCollectionNoWithCollection_abc.AUTOCREATED/lang/stopwords_ru.txt [junit4] 2> 18744 INFO (OverseerThreadFactory-59-thread-2-processing-n:127.0.0.1:37423_solr) [n:127.0.0.1:37423_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_tr.txt to /configs/testCreateCollectionNoWithCollection_abc.AUTOCREATED/lang/stopwords_tr.txt [junit4] 2> 18745 INFO (OverseerThreadFactory-59-thread-2-processing-n:127.0.0.1:37423_solr) [n:127.0.0.1:37423_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ar.txt to /configs/testCreateCollectionNoWithCollection_abc.AUTOCREATED/lang/stopwords_ar.txt [junit4] 2> 18747 INFO (OverseerThreadFactory-59-thread-2-processing-n:127.0.0.1:37423_solr) [n:127.0.0.1:37423_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/userdict_ja.txt to /configs/testCreateCollectionNoWithCollection_abc.AUTOCREATED/lang/userdict_ja.txt [junit4] 2> 18752 INFO (OverseerThreadFactory-59-thread-2-processing-n:127.0.0.1:37423_solr) [n:127.0.0.1:37423_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_it.txt to /configs/testCreateCollectionNoWithCollection_abc.AUTOCREATED/lang/stopwords_it.txt [junit4] 2> 18754 INFO (OverseerThreadFactory-59-thread-2-processing-n:127.0.0.1:37423_solr) [n:127.0.0.1:37423_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_eu.txt to /configs/testCreateCollectionNoWithCollection_abc.AUTOCREATED/lang/stopwords_eu.txt [junit4] 2> 18755 INFO (OverseerThreadFactory-59-thread-2-processing-n:127.0.0.1:37423_solr) [n:127.0.0.1:37423_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_cz.txt to /configs/testCreateCollectionNoWithCollection_abc.AUTOCREATED/lang/stopwords_cz.txt [junit4] 2> 18757 INFO (OverseerThreadFactory-59-thread-2-processing-n:127.0.0.1:37423_solr) [n:127.0.0.1:37423_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stoptags_ja.txt to /configs/testCreateCollectionNoWithCollection_abc.AUTOCREATED/lang/stoptags_ja.txt [junit4] 2> 18759 INFO (OverseerThreadFactory-59-thread-2-processing-n:127.0.0.1:37423_solr) [n:127.0.0.1:37423_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/contractions_fr.txt to /configs/testCreateCollectionNoWithCollection_abc.AUTOCREATED/lang/contractions_fr.txt [junit4] 2> 18762 INFO (OverseerThreadFactory-59-thread-2-processing-n:127.0.0.1:37423_solr) [n:127.0.0.1:37423_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_fa.txt to /configs/testCreateCollectionNoWithCollection_abc.AUTOCREATED/lang/stopwords_fa.txt [junit4] 2> 18763 INFO (OverseerThreadFactory-59-thread-2-processing-n:127.0.0.1:37423_solr) [n:127.0.0.1:37423_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_de.txt to /configs/testCreateCollectionNoWithCollection_abc.AUTOCREATED/lang/stopwords_de.txt [junit4] 2> 18765 INFO (OverseerThreadFactory-59-thread-2-processing-n:127.0.0.1:37423_solr) [n:127.0.0.1:37423_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/contractions_ga.txt to /configs/testCreateCollectionNoWithCollection_abc.AUTOCREATED/lang/contractions_ga.txt [junit4] 2> 18767 INFO (OverseerThreadFactory-59-thread-2-processing-n:127.0.0.1:37423_solr) [n:127.0.0.1:37423_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ca.txt to /configs/testCreateCollectionNoWithCollection_abc.AUTOCREATED/lang/stopwords_ca.txt [junit4] 2> 18769 INFO (OverseerThreadFactory-59-thread-2-processing-n:127.0.0.1:37423_solr) [n:127.0.0.1:37423_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_nl.txt to /configs/testCreateCollectionNoWithCollection_abc.AUTOCREATED/lang/stopwords_nl.txt [junit4] 2> 18771 INFO (OverseerThreadFactory-59-thread-2-processing-n:127.0.0.1:37423_solr) [n:127.0.0.1:37423_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_bg.txt to /configs/testCreateCollectionNoWithCollection_abc.AUTOCREATED/lang/stopwords_bg.txt [junit4] 2> 18773 INFO (OverseerThreadFactory-59-thread-2-processing-n:127.0.0.1:37423_solr) [n:127.0.0.1:37423_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_es.txt to /configs/testCreateCollectionNoWithCollection_abc.AUTOCREATED/lang/stopwords_es.txt [junit4] 2> 18775 INFO (OverseerThreadFactory-59-thread-2-processing-n:127.0.0.1:37423_solr) [n:127.0.0.1:37423_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_en.txt to /configs/testCreateCollectionNoWithCollection_abc.AUTOCREATED/lang/stopwords_en.txt [junit4] 2> 18777 INFO (OverseerThreadFactory-59-thread-2-processing-n:127.0.0.1:37423_solr) [n:127.0.0.1:37423_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_lv.txt to /configs/testCreateCollectionNoWithCollection_abc.AUTOCREATED/lang/stopwords_lv.txt [junit4] 2> 18779 INFO (OverseerThreadFactory-59-thread-2-processing-n:127.0.0.1:37423_solr) [n:127.0.0.1:37423_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_th.txt to /configs/testCreateCollectionNoWithCollection_abc.AUTOCREATED/lang/stopwords_th.txt [junit4] 2> 18780 INFO (OverseerThreadFactory-59-thread-2-processing-n:127.0.0.1:37423_solr) [n:127.0.0.1:37423_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ro.txt to /configs/testCreateCollectionNoWithCollection_abc.AUTOCREATED/lang/stopwords_ro.txt [junit4] 2> 18782 INFO (OverseerThreadFactory-59-thread-2-processing-n:127.0.0.1:37423_solr) [n:127.0.0.1:37423_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_fr.txt to /configs/testCreateCollectionNoWithCollection_abc.AUTOCREATED/lang/stopwords_fr.txt [junit4] 2> 18785 INFO (OverseerThreadFactory-59-thread-2-processing-n:127.0.0.1:37423_solr) [n:127.0.0.1:37423_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_sv.txt to /configs/testCreateCollectionNoWithCollection_abc.AUTOCREATED/lang/stopwords_sv.txt [junit4] 2> 18787 INFO (OverseerThreadFactory-59-thread-2-processing-n:127.0.0.1:37423_solr) [n:127.0.0.1:37423_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_fi.txt to /configs/testCreateCollectionNoWithCollection_abc.AUTOCREATED/lang/stopwords_fi.txt [junit4] 2> 18789 INFO (OverseerThreadFactory-59-thread-2-processing-n:127.0.0.1:37423_solr) [n:127.0.0.1:37423_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/hyphenations_ga.txt to /configs/testCreateCollectionNoWithCollection_abc.AUTOCREATED/lang/hyphenations_ga.txt [junit4] 2> 18790 INFO (OverseerThreadFactory-59-thread-2-processing-n:127.0.0.1:37423_solr) [n:127.0.0.1:37423_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_gl.txt to /configs/testCreateCollectionNoWithCollection_abc.AUTOCREATED/lang/stopwords_gl.txt [junit4] 2> 18792 INFO (OverseerThreadFactory-59-thread-2-processing-n:127.0.0.1:37423_solr) [n:127.0.0.1:37423_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/synonyms.txt to /configs/testCreateCollectionNoWithCollection_abc.AUTOCREATED/synonyms.txt [junit4] 2> 18793 INFO (OverseerThreadFactory-59-thread-2-processing-n:127.0.0.1:37423_solr) [n:127.0.0.1:37423_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/stopwords.txt to /configs/testCreateCollectionNoWithCollection_abc.AUTOCREATED/stopwords.txt [junit4] 2> 18804 DEBUG (OverseerStateUpdate-72625268467957766-127.0.0.1:37423_solr-n_0000000000) [n:127.0.0.1:37423_solr ] o.a.s.c.o.ClusterStateMutator building a new cName: testCreateCollectionNoWithCollection_abc [junit4] 2> 18810 DEBUG (OverseerStateUpdate-72625268467957766-127.0.0.1:37423_solr-n_0000000000) [n:127.0.0.1:37423_solr ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/testCreateCollectionNoWithCollection_abc/state.json [junit4] 2> 18924 INFO (OverseerStateUpdate-72625268467957766-127.0.0.1:37423_solr-n_0000000000) [n:127.0.0.1:37423_solr ] o.a.s.c.o.SliceMutator createReplica() { [junit4] 2> "operation":"ADDREPLICA", [junit4] 2> "collection":"testCreateCollectionNoWithCollection_abc", [junit4] 2> "shard":"shard1", [junit4] 2> "core":"testCreateCollectionNoWithCollection_abc_shard1_replica_n1", [junit4] 2> "state":"down", [junit4] 2> "node_name":"127.0.0.1:37423_solr", [junit4] 2> "type":"NRT", [junit4] 2> "waitForFinalState":"false"} [junit4] 2> 18927 DEBUG (OverseerStateUpdate-72625268467957766-127.0.0.1:37423_solr-n_0000000000) [n:127.0.0.1:37423_solr ] o.a.s.c.o.SliceMutator Old Slice: shard1:{ [junit4] 2> "range":"80000000-ffffffff", [junit4] 2> "state":"active", [junit4] 2> "replicas":{}} [junit4] 2> 18927 DEBUG (OverseerStateUpdate-72625268467957766-127.0.0.1:37423_solr-n_0000000000) [n:127.0.0.1:37423_solr ] o.a.s.c.o.SliceMutator New Slice: shard1:{ [junit4] 2> "range":"80000000-ffffffff", [junit4] 2> "state":"active", [junit4] 2> "replicas":{"core_node2":{ [junit4] 2> "core":"testCreateCollectionNoWithCollection_abc_shard1_replica_n1", [junit4] 2> "node_name":"127.0.0.1:37423_solr", [junit4] 2> "state":"down", [junit4] 2> "type":"NRT"}}} [junit4] 2> 18929 INFO (OverseerStateUpdate-72625268467957766-127.0.0.1:37423_solr-n_0000000000) [n:127.0.0.1:37423_solr ] o.a.s.c.o.SliceMutator createReplica() { [junit4] 2> "operation":"ADDREPLICA", [junit4] 2> "collection":"testCreateCollectionNoWithCollection_abc", [junit4] 2> "shard":"shard2", [junit4] 2> "core":"testCreateCollectionNoWithCollection_abc_shard2_replica_n3", [junit4] 2> "state":"down", [junit4] 2> "node_name":"127.0.0.1:37271_solr", [junit4] 2> "type":"NRT", [junit4] 2> "waitForFinalState":"false"} [junit4] 2> 18931 DEBUG (OverseerStateUpdate-72625268467957766-127.0.0.1:37423_solr-n_0000000000) [n:127.0.0.1:37423_solr ] o.a.s.c.o.SliceMutator Old Slice: shard2:{ [junit4] 2> "range":"0-7fffffff", [junit4] 2> "state":"active", [junit4] 2> "replicas":{}} [junit4] 2> 18931 DEBUG (OverseerStateUpdate-72625268467957766-127.0.0.1:37423_solr-n_0000000000) [n:127.0.0.1:37423_solr ] o.a.s.c.o.SliceMutator New Slice: shard2:{ [junit4] 2> "range":"0-7fffffff", [junit4] 2> "state":"active", [junit4] 2> "replicas":{"core_node4":{ [junit4] 2> "core":"testCreateCollectionNoWithCollection_abc_shard2_replica_n3", [junit4] 2> "node_name":"127.0.0.1:37271_solr", [junit4] 2> "state":"down", [junit4] 2> "type":"NRT"}}} [junit4] 2> 19032 DEBUG (OverseerStateUpdate-72625268467957766-127.0.0.1:37423_solr-n_0000000000) [n:127.0.0.1:37423_solr ] o.a.s.c.o.ZkStateWriter going to update_collection /collections/testCreateCollectionNoWithCollection_abc/state.json version: 0 [junit4] 2> 19331 INFO (qtp1049226253-65) [n:127.0.0.1:37271_solr x:testCreateCollectionNoWithCollection_abc_shard2_replica_n3 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node4&collection.configName=testCreateCollectionNoWithCollection_abc.AUTOCREATED&newCollection=true&name=testCreateCollectionNoWithCollection_abc_shard2_replica_n3&action=CREATE&numShards=2&collection=testCreateCollectionNoWithCollection_abc&shard=shard2&wt=javabin&version=2&replicaType=NRT [junit4] 2> 19338 INFO (qtp1099228871-68) [n:127.0.0.1:37423_solr x:testCreateCollectionNoWithCollection_abc_shard1_replica_n1 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node2&collection.configName=testCreateCollectionNoWithCollection_abc.AUTOCREATED&newCollection=true&name=testCreateCollectionNoWithCollection_abc_shard1_replica_n1&action=CREATE&numShards=2&collection=testCreateCollectionNoWithCollection_abc&shard=shard1&wt=javabin&version=2&replicaType=NRT [junit4] 2> 19338 INFO (qtp1099228871-68) [n:127.0.0.1:37423_solr x:testCreateCollectionNoWithCollection_abc_shard1_replica_n1 ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores [junit4] 2> 19357 DEBUG (OverseerStateUpdate-72625268467957766-127.0.0.1:37423_solr-n_0000000000) [n:127.0.0.1:37423_solr ] o.a.s.c.o.ReplicaMutator Update state numShards=2 message={ [junit4] 2> "core":"testCreateCollectionNoWithCollection_abc_shard2_replica_n3", [junit4] 2> "core_node_name":"core_node4", [junit4] 2> "roles":null, [junit4] 2> "node_name":"127.0.0.1:37271_solr", [junit4] 2> "numShards":"2", [junit4] 2> "state":"down", [junit4] 2> "shard":"shard2", [junit4] 2> "collection":"testCreateCollectionNoWithCollection_abc", [junit4] 2> "type":"NRT", [junit4] 2> "force_set_state":"false", [junit4] 2> "operation":"state"} [junit4] 2> 19359 DEBUG (OverseerStateUpdate-72625268467957766-127.0.0.1:37423_solr-n_0000000000) [n:127.0.0.1:37423_solr ] o.a.s.c.o.ReplicaMutator Will update state for replica: core_node4:{ [junit4] 2> "core":"testCreateCollectionNoWithCollection_abc_shard2_replica_n3", [junit4] 2> "node_name":"127.0.0.1:37271_solr", [junit4] 2> "state":"down", [junit4] 2> "type":"NRT", [junit4] 2> "force_set_state":"false", [junit4] 2> "base_url":"http://127.0.0.1:37271/solr"} [junit4] 2> 19359 DEBUG (OverseerStateUpdate-72625268467957766-127.0.0.1:37423_solr-n_0000000000) [n:127.0.0.1:37423_solr ] o.a.s.c.o.ReplicaMutator Collection is now: DocCollection(testCreateCollectionNoWithCollection_abc//collections/testCreateCollectionNoWithCollection_abc/state.json/1)={ [junit4] 2> "pullReplicas":"0", [junit4] 2> "replicationFactor":"1", [junit4] 2> "router":{"name":"compositeId"}, [junit4] 2> "maxShardsPerNode":"1", [junit4] 2> "autoAddReplicas":"false", [junit4] 2> "nrtReplicas":"1", [junit4] 2> "tlogReplicas":"0", [junit4] 2> "shards":{ [junit4] 2> "shard1":{ [junit4] 2> "range":"80000000-ffffffff", [junit4] 2> "state":"active", [junit4] 2> "replicas":{"core_node2":{ [junit4] 2> "core":"testCreateCollectionNoWithCollection_abc_shard1_replica_n1", [junit4] 2> "node_name":"127.0.0.1:37423_solr", [junit4] 2> "state":"down", [junit4] 2> "type":"NRT"}}}, [junit4] 2> "shard2":{ [junit4] 2> "range":"0-7fffffff", [junit4] 2> "state":"active", [junit4] 2> "replicas":{"core_node4":{ [junit4] 2> "core":"testCreateCollectionNoWithCollection_abc_shard2_replica_n3", [junit4] 2> "node_name":"127.0.0.1:37271_solr", [junit4] 2> "state":"down", [junit4] 2> "type":"NRT", [junit4] 2> "force_set_state":"false"}}}}} [junit4] 2> 19359 DEBUG (OverseerStateUpdate-72625268467957766-127.0.0.1:37423_solr-n_0000000000) [n:127.0.0.1:37423_solr ] o.a.s.c.o.ReplicaMutator Update state numShards=2 message={ [junit4] 2> "core":"testCreateCollectionNoWithCollection_abc_shard1_replica_n1", [junit4] 2> "core_node_name":"core_node2", [junit4] 2> "roles":null, [junit4] 2> "node_name":"127.0.0.1:37423_solr", [junit4] 2> "numShards":"2", [junit4] 2> "state":"down", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"testCreateCollectionNoWithCollection_abc", [junit4] 2> "type":"NRT", [junit4] 2> "force_set_state":"false", [junit4] 2> "operation":"state"} [junit4] 2> 19363 DEBUG (OverseerStateUpdate-72625268467957766-127.0.0.1:37423_solr-n_0000000000) [n:127.0.0.1:37423_solr ] o.a.s.c.o.ReplicaMutator Will update state for replica: core_node2:{ [junit4] 2> "core":"testCreateCollectionNoWithCollection_abc_shard1_replica_n1", [junit4] 2> "node_name":"127.0.0.1:37423_solr", [junit4] 2> "state":"down", [junit4] 2> "type":"NRT", [junit4] 2> "force_set_state":"false", [junit4] 2> "base_url":"http://127.0.0.1:37423/solr"} [junit4] 2> 19365 DEBUG (OverseerStateUpdate-72625268467957766-127.0.0.1:37423_solr-n_0000000000) [n:127.0.0.1:37423_solr ] o.a.s.c.o.ReplicaMutator Collection is now: DocCollection(testCreateCollectionNoWithCollection_abc//collections/testCreateCollectionNoWithCollection_abc/state.json/1)={ [junit4] 2> "pullReplicas":"0", [junit4] 2> "replicationFactor":"1", [junit4] 2> "router":{"name":"compositeId"}, [junit4] 2> "maxShardsPerNode":"1", [junit4] 2> "autoAddReplicas":"false", [junit4] 2> "nrtReplicas":"1", [junit4] 2> "tlogReplicas":"0", [junit4] 2> "shards":{ [junit4] 2> "shard1":{ [junit4] 2> "range":"80000000-ffffffff", [junit4] 2> "state":"active", [junit4] 2> "replicas":{"core_node2":{ [junit4] 2> "core":"testCreateCollectionNoWithCollection_abc_shard1_replica_n1", [junit4] 2> "node_name":"127.0.0.1:37423_solr", [junit4] 2> "state":"down", [junit4] 2> "type":"NRT", [junit4] 2> "force_set_state":"false"}}}, [junit4] 2> "shard2":{ [junit4] 2> "range":"0-7fffffff", [junit4] 2> "state":"active", [junit4] 2> "replicas":{"core_node4":{ [junit4] 2> "core":"testCreateCollectionNoWithCollection_abc_shard2_replica_n3", [junit4] 2> "node_name":"127.0.0.1:37271_solr", [junit4] 2> "state":"down", [junit4] 2> "type":"NRT", [junit4] 2> "force_set_state":"false"}}}}} [junit4] 2> 19377 INFO (qtp1049226253-65) [n:127.0.0.1:37271_solr c:testCreateCollectionNoWithCollection_abc s:shard2 r:core_node4 x:testCreateCollectionNoWithCollection_abc_shard2_replica_n3 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.8.0 [junit4] 2> 19381 INFO (qtp1099228871-68) [n:127.0.0.1:37423_solr c:testCreateCollectionNoWithCollection_abc s:shard1 r:core_node2 x:testCreateCollectionNoWithCollection_abc_shard1_replica_n1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.8.0 [junit4] 2> 19424 INFO (qtp1099228871-68) [n:127.0.0.1:37423_solr c:testCreateCollectionNoWithCollection_abc s:shard1 r:core_node2 x:testCreateCollectionNoWithCollection_abc_shard1_replica_n1 ] o.a.s.s.IndexSchema Schema name=default-config [junit4] 2> 19426 INFO (qtp1049226253-65) [n:127.0.0.1:37271_solr c:testCreateCollectionNoWithCollection_abc s:shard2 r:core_node4 x:testCreateCollectionNoWithCollection_abc_shard2_replica_n3 ] o.a.s.s.IndexSchema Schema name=default-config [junit4] 2> 19724 DEBUG (OverseerStateUpdate-72625268467957766-127.0.0.1:37423_solr-n_0000000000) [n:127.0.0.1:37423_solr ] o.a.s.c.o.ZkStateWriter going to update_collection /collections/testCreateCollectionNoWithCollection_abc/state.json version: 1 [junit4] 2> 20111 INFO (qtp1099228871-68) [n:127.0.0.1:37423_solr c:testCreateCollectionNoWithCollection_abc s:shard1 r:core_node2 x:testCreateCollectionNoWithCollection_abc_shard1_replica_n1 ] o.a.s.s.IndexSchema Loaded schema default-config/1.6 with uniqueid field id [junit4] 2> 20112 INFO (qtp1049226253-65) [n:127.0.0.1:37271_solr c:testCreateCollectionNoWithCollection_abc s:shard2 r:core_node4 x:testCreateCollectionNoWithCollection_abc_shard2_replica_n3 ] o.a.s.s.IndexSchema Loaded schema default-config/1.6 with uniqueid field id [junit4] 2> 20383 INFO (qtp1049226253-65) [n:127.0.0.1:37271_solr c:testCreateCollectionNoWithCollection_abc s:shard2 r:core_node4 x:testCreateCollectionNoWithCollection_abc_shard2_replica_n3 ] o.a.s.c.CoreContainer Creating SolrCore 'testCreateCollectionNoWithCollection_abc_shard2_replica_n3' using configuration from configset testCreateCollectionNoWithCollection_abc.AUTOCREATED, trusted=true [junit4] 2> 20385 INFO (qtp1049226253-65) [n:127.0.0.1:37271_solr c:testCreateCollectionNoWithCollection_abc s:shard2 r:core_node4 x:testCreateCollectionNoWithCollection_abc_shard2_replica_n3 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_37271.solr.core.testCreateCollectionNoWithCollection_abc.shard2.replica_n3' (registry 'solr.core.testCreateCollectionNoWithCollection_abc.shard2.replica_n3') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@264190dd [junit4] 2> 20385 INFO (qtp1049226253-65) [n:127.0.0.1:37271_solr c:testCreateCollectionNoWithCollection_abc s:shard2 r:core_node4 x:testCreateCollectionNoWithCollection_abc_shard2_replica_n3 ] o.a.s.c.SolrCore [[testCreateCollectionNoWithCollection_abc_shard2_replica_n3] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/build/solr-core/test/J3/temp/solr.cloud.TestWithCollection_654426C6B60C3717-001/tempDir-001/node1/testCreateCollectionNoWithCollection_abc_shard2_replica_n3], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/build/solr-core/test/J3/temp/solr.cloud.TestWithCollection_654426C6B60C3717-001/tempDir-001/node1/testCreateCollectionNoWithCollection_abc_shard2_replica_n3/data/] [junit4] 2> 20412 INFO (qtp1099228871-68) [n:127.0.0.1:37423_solr c:testCreateCollectionNoWithCollection_abc s:shard1 r:core_node2 x:testCreateCollectionNoWithCollection_abc_shard1_replica_n1 ] o.a.s.c.CoreContainer Creating SolrCore 'testCreateCollectionNoWithCollection_abc_shard1_replica_n1' using configuration from configset testCreateCollectionNoWithCollection_abc.AUTOCREATED, trusted=true [junit4] 2> 20415 INFO (qtp1099228871-68) [n:127.0.0.1:37423_solr c:testCreateCollectionNoWithCollection_abc s:shard1 r:core_node2 x:testCreateCollectionNoWithCollection_abc_shard1_replica_n1 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_37423.solr.core.testCreateCollectionNoWithCollection_abc.shard1.replica_n1' (registry 'solr.core.testCreateCollectionNoWithCollection_abc.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@264190dd [junit4] 2> 20415 INFO (qtp1099228871-68) [n:127.0.0.1:37423_solr c:testCreateCollectionNoWithCollection_abc s:shard1 r:core_node2 x:testCreateCollectionNoWithCollection_abc_shard1_replica_n1 ] o.a.s.c.SolrCore [[testCreateCollectionNoWithCollection_abc_shard1_replica_n1] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/build/solr-core/test/J3/temp/solr.cloud.TestWithCollection_654426C6B60C3717-001/tempDir-001/node2/testCreateCollectionNoWithCollection_abc_shard1_replica_n1], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/build/solr-core/test/J3/temp/solr.cloud.TestWithCollection_654426C6B60C3717-001/tempDir-001/node2/testCreateCollectionNoWithCollection_abc_shard1_replica_n1/data/] [junit4] 2> 20974 INFO (qtp1099228871-68) [n:127.0.0.1:37423_solr c:testCreateCollectionNoWithCollection_abc s:shard1 r:core_node2 x:testCreateCollectionNoWithCollection_abc_shard1_replica_n1 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 20974 INFO (qtp1099228871-68) [n:127.0.0.1:37423_solr c:testCreateCollectionNoWithCollection_abc s:shard1 r:core_node2 x:testCreateCollectionNoWithCollection_abc_shard1_replica_n1 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 20978 INFO (qtp1099228871-68) [n:127.0.0.1:37423_solr c:testCreateCollectionNoWithCollection_abc s:shard1 r:core_node2 x:testCreateCollectionNoWithCollection_abc_shard1_replica_n1 ] o.a.s.u.CommitTracker Hard AutoCommit: if uncommitted for 15000ms; [junit4] 2> 20978 INFO (qtp1099228871-68) [n:127.0.0.1:37423_solr c:testCreateCollectionNoWithCollection_abc s:shard1 r:core_node2 x:testCreateCollectionNoWithCollection_abc_shard1_replica_n1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 20980 INFO (qtp1049226253-65) [n:127.0.0.1:37271_solr c:testCreateCollectionNoWithCollection_abc s:shard2 r:core_node4 x:testCreateCollectionNoWithCollection_abc_shard2_replica_n3 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 20981 INFO (qtp1049226253-65) [n:127.0.0.1:37271_solr c:testCreateCollectionNoWithCollection_abc s:shard2 r:core_node4 x:testCreateCollectionNoWithCollection_abc_shard2_replica_n3 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 20982 INFO (qtp1049226253-65) [n:127.0.0.1:37271_solr c:testCreateCollectionNoWithCollection_abc s:shard2 r:core_node4 x:testCreateCollectionNoWithCollection_abc_shard2_replica_n3 ] o.a.s.u.CommitTracker Hard AutoCommit: if uncommitted for 15000ms; [junit4] 2> 20982 INFO (qtp1049226253-65) [n:127.0.0.1:37271_solr c:testCreateCollectionNoWithCollection_abc s:shard2 r:core_node4 x:testCreateCollectionNoWithCollection_abc_shard2_replica_n3 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 20986 INFO (qtp1099228871-68) [n:127.0.0.1:37423_solr c:testCreateCollectionNoWithCollection_abc s:shard1 r:core_node2 x:testCreateCollectionNoWithCollection_abc_shard1_replica_n1 ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/testCreateCollectionNoWithCollection_abc.AUTOCREATED [junit4] 2> 20987 INFO (qtp1049226253-65) [n:127.0.0.1:37271_solr c:testCreateCollectionNoWithCollection_abc s:shard2 r:core_node4 x:testCreateCollectionNoWithCollection_abc_shard2_replica_n3 ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/testCreateCollectionNoWithCollection_abc.AUTOCREATED [junit4] 2> 20988 INFO (qtp1099228871-68) [n:127.0.0.1:37423_solr c:testCreateCollectionNoWithCollection_abc s:shard1 r:core_node2 x:testCreateCollectionNoWithCollection_abc_shard1_replica_n1 ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/testCreateCollectionNoWithCollection_abc.AUTOCREATED [junit4] 2> 20990 INFO (qtp1099228871-68) [n:127.0.0.1:37423_solr c:testCreateCollectionNoWithCollection_abc s:shard1 r:core_node2 x:testCreateCollectionNoWithCollection_abc_shard1_replica_n1 ] o.a.s.s.ZkIndexSchemaReader Creating ZooKeeper watch for the managed schema at /configs/testCreateCollectionNoWithCollection_abc.AUTOCREATED/managed-schema [junit4] 2> 20991 INFO (qtp1049226253-65) [n:127.0.0.1:37271_solr c:testCreateCollectionNoWithCollection_abc s:shard2 r:core_node4 x:testCreateCollectionNoWithCollection_abc_shard2_replica_n3 ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/testCreateCollectionNoWithCollection_abc.AUTOCREATED [junit4] 2> 20991 INFO (qtp1049226253-65) [n:127.0.0.1:37271_solr c:testCreateCollectionNoWithCollection_abc s:shard2 r:core_node4 x:testCreateCollectionNoWithCollection_abc_shard2_replica_n3 ] o.a.s.s.ZkIndexSchemaReader Creating ZooKeeper watch for the managed schema at /configs/testCreateCollectionNoWithCollection_abc.AUTOCREATED/managed-schema [junit4] 2> 20992 INFO (qtp1049226253-65) [n:127.0.0.1:37271_solr c:testCreateCollectionNoWithCollection_abc s:shard2 r:core_node4 x:testCreateCollectionNoWithCollection_abc_shard2_replica_n3 ] o.a.s.s.ZkIndexSchemaReader Current schema version 0 is already the latest [junit4] 2> 20992 INFO (qtp1049226253-65) [n:127.0.0.1:37271_solr c:testCreateCollectionNoWithCollection_abc s:shard2 r:core_node4 x:testCreateCollectionNoWithCollection_abc_shard2_replica_n3 ] o.a.s.h.c.SpellCheckComponent Initializing spell checkers [junit4] 2> 21001 INFO (qtp1099228871-68) [n:127.0.0.1:37423_solr c:testCreateCollectionNoWithCollection_abc s:shard1 r:core_node2 x:testCreateCollectionNoWithCollection_abc_shard1_replica_n1 ] o.a.s.s.ZkIndexSchemaReader Current schema version 0 is already the latest [junit4] 2> 21001 INFO (qtp1099228871-68) [n:127.0.0.1:37423_solr c:testCreateCollectionNoWithCollection_abc s:shard1 r:core_node2 x:testCreateCollectionNoWithCollection_abc_shard1_replica_n1 ] o.a.s.h.c.SpellCheckComponent Initializing spell checkers [junit4] 2> 21008 INFO (qtp1049226253-65) [n:127.0.0.1:37271_solr c:testCreateCollectionNoWithCollection_abc s:shard2 r:core_node4 x:testCreateCollectionNoWithCollection_abc_shard2_replica_n3 ] o.a.s.s.DirectSolrSpellChecker init: {name=default,field=_text_,classname=solr.DirectSolrSpellChecker,distanceMeasure=internal,accuracy=0.5,maxEdits=2,minPrefix=1,maxInspections=5,minQueryLength=4,maxQueryFrequency=0.01} [junit4] 2> 21010 INFO (qtp1099228871-68) [n:127.0.0.1:37423_solr c:testCreateCollectionNoWithCollection_abc s:shard1 r:core_node2 x:testCreateCollectionNoWithCollection_abc_shard1_replica_n1 ] o.a.s.s.DirectSolrSpellChecker init: {name=default,field=_text_,classname=solr.DirectSolrSpellChecker,distanceMeasure=internal,accuracy=0.5,maxEdits=2,minPrefix=1,maxInspections=5,minQueryLength=4,maxQueryFrequency=0.01} [junit4] 2> 21026 INFO (qtp1099228871-68) [n:127.0.0.1:37423_solr c:testCreateCollectionNoWithCollection_abc s:shard1 r:core_node2 x:testCreateCollectionNoWithCollection_abc_shard1_replica_n1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms [junit4] 2> 21026 INFO (qtp1049226253-65) [n:127.0.0.1:37271_solr c:testCreateCollectionNoWithCollection_abc s:shard2 r:core_node4 x:testCreateCollectionNoWithCollection_abc_shard2_replica_n3 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms [junit4] 2> 21041 INFO (qtp1099228871-68) [n:127.0.0.1:37423_solr c:testCreateCollectionNoWithCollection_abc s:shard1 r:core_node2 x:testCreateCollectionNoWithCollection_abc_shard1_replica_n1 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1685707384838684672 [junit4] 2> 21041 INFO (qtp1049226253-65) [n:127.0.0.1:37271_solr c:testCreateCollectionNoWithCollection_abc s:shard2 r:core_node4 x:testCreateCollectionNoWithCollection_abc_shard2_replica_n3 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1685707384838684672 [junit4] 2> 21045 INFO (searcherExecutor-86-thread-1-processing-n:127.0.0.1:37271_solr x:testCreateCollectionNoWithCollection_abc_shard2_replica_n3 c:testCreateCollectionNoWithCollection_abc s:shard2 r:core_node4) [n:127.0.0.1:37271_solr c:testCreateCollectionNoWithCollection_abc s:shard2 r:core_node4 x:testCreateCollectionNoWithCollection_abc_shard2_replica_n3 ] o.a.s.c.QuerySenderListener QuerySenderListener done. [junit4] 2> 21045 INFO (searcherExecutor-86-thread-1-processing-n:127.0.0.1:37271_solr x:testCreateCollectionNoWithCollection_abc_shard2_replica_n3 c:testCreateCollectionNoWithCollection_abc s:shard2 r:core_node4) [n:127.0.0.1:37271_solr c:testCreateCollectionNoWithCollection_abc s:shard2 r:core_node4 x:testCreateCollectionNoWithCollection_abc_shard2_replica_n3 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default [junit4] 2> 21046 INFO (searcherExecutor-88-thread-1-processing-n:127.0.0.1:37423_solr x:testCreateCollectionNoWithCollection_abc_shard1_replica_n1 c:testCreateCollectionNoWithCollection_abc s:shard1 r:core_node2) [n:127.0.0.1:37423_solr c:testCreateCollectionNoWithCollection_abc s:shard1 r:core_node2 x:testCreateCollectionNoWithCollection_abc_shard1_replica_n1 ] o.a.s.c.QuerySenderListener QuerySenderListener done. [junit4] 2> 21046 INFO (searcherExecutor-88-thread-1-processing-n:127.0.0.1:37423_solr x:testCreateCollectionNoWithCollection_abc_shard1_replica_n1 c:testCreateCollectionNoWithCollection_abc s:shard1 r:core_node2) [n:127.0.0.1:37423_solr c:testCreateCollectionNoWithCollection_abc s:shard1 r:core_node2 x:testCreateCollectionNoWithCollection_abc_shard1_replica_n1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default [junit4] 2> 21048 INFO (searcherExecutor-88-thread-1-processing-n:127.0.0.1:37423_solr x:testCreateCollectionNoWithCollection_abc_shard1_replica_n1 c:testCreateCollectionNoWithCollection_abc s:shard1 r:core_node2) [n:127.0.0.1:37423_solr c:testCreateCollectionNoWithCollection_abc s:shard1 r:core_node2 x:testCreateCollectionNoWithCollection_abc_shard1_replica_n1 ] o.a.s.c.SolrCore [testCreateCollectionNoWithCollection_abc_shard1_replica_n1] Registered new searcher autowarm time: 0 ms [junit4] 2> 21251 INFO (searcherExecutor-86-thread-1-processing-n:127.0.0.1:37271_solr x:testCreateCollectionNoWithCollection_abc_shard2_replica_n3 c:testCreateCollectionNoWithCollection_abc s:shard2 r:core_node4) [n:127.0.0.1:37271_solr c:testCreateCollectionNoWithCollection_abc s:shard2 r:core_node4 x:testCreateCollectionNoWithCollection_abc_shard2_replica_n3 ] o.a.s.c.SolrCore [testCreateCollectionNoWithCollection_abc_shard2_replica_n3] Registered new searcher autowarm time: 0 ms [junit4] 2> 21325 INFO (qtp1049226253-65) [n:127.0.0.1:37271_solr c:testCreateCollectionNoWithCollection_abc s:shard2 r:core_node4 x:testCreateCollectionNoWithCollection_abc_shard2_replica_n3 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/testCreateCollectionNoWithCollection_abc/terms/shard2 to Terms{values={core_node4=0}, version=0} [junit4] 2> 21328 INFO (qtp1049226253-65) [n:127.0.0.1:37271_solr c:testCreateCollectionNoWithCollection_abc s:shard2 r:core_node4 x:testCreateCollectionNoWithCollection_abc_shard2_replica_n3 ] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/testCreateCollectionNoWithCollection_abc/leaders/shard2 [junit4] 2> 21332 INFO (qtp1099228871-68) [n:127.0.0.1:37423_solr c:testCreateCollectionNoWithCollection_abc s:shard1 r:core_node2 x:testCreateCollectionNoWithCollection_abc_shard1_replica_n1 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/testCreateCollectionNoWithCollection_abc/terms/shard1 to Terms{values={core_node2=0}, version=0} [junit4] 2> 21332 INFO (qtp1099228871-68) [n:127.0.0.1:37423_solr c:testCreateCollectionNoWithCollection_abc s:shard1 r:core_node2 x:testCreateCollectionNoWithCollection_abc_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/testCreateCollectionNoWithCollection_abc/leaders/shard1 [junit4] 2> 21337 INFO (qtp1099228871-68) [n:127.0.0.1:37423_solr c:testCreateCollectionNoWithCollection_abc s:shard1 r:core_node2 x:testCreateCollectionNoWithCollection_abc_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue. [junit4] 2> 21338 INFO (qtp1099228871-68) [n:127.0.0.1:37423_solr c:testCreateCollectionNoWithCollection_abc s:shard1 r:core_node2 x:testCreateCollectionNoWithCollection_abc_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync [junit4] 2> 21338 INFO (qtp1099228871-68) [n:127.0.0.1:37423_solr c:testCreateCollectionNoWithCollection_abc s:shard1 r:core_node2 x:testCreateCollectionNoWithCollection_abc_shard1_replica_n1 ] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:37423/solr/testCreateCollectionNoWithCollection_abc_shard1_replica_n1/ [junit4] 2> 21339 INFO (qtp1099228871-68) [n:127.0.0.1:37423_solr c:testCreateCollectionNoWithCollection_abc s:shard1 r:core_node2 x:testCreateCollectionNoWithCollection_abc_shard1_replica_n1 ] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me [junit4] 2> 21339 INFO (qtp1099228871-68) [n:127.0.0.1:37423_solr c:testCreateCollectionNoWithCollection_abc s:shard1 r:core_node2 x:testCreateCollectionNoWithCollection_abc_shard1_replica_n1 ] o.a.s.c.SyncStrategy http://127.0.0.1:37423/solr/testCreateCollectionNoWithCollection_abc_shard1_replica_n1/ has no replicas [junit4] 2> 21341 INFO (qtp1099228871-68) [n:127.0.0.1:37423_solr c:testCreateCollectionNoWithCollection_abc s:shard1 r:core_node2 x:testCreateCollectionNoWithCollection_abc_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/testCreateCollectionNoWithCollection_abc/leaders/shard1/leader after winning as /collections/testCreateCollectionNoWithCollection_abc/leader_elect/shard1/election/72625268467957766-core_node2-n_0000000000 [junit4] 2> 21343 INFO (qtp1049226253-65) [n:127.0.0.1:37271_solr c:testCreateCollectionNoWithCollection_abc s:shard2 r:core_node4 x:testCreateCollectionNoWithCollection_abc_shard2_replica_n3 ] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue. [junit4] 2> 21343 INFO (qtp1049226253-65) [n:127.0.0.1:37271_solr c:testCreateCollectionNoWithCollection_abc s:shard2 r:core_node4 x:testCreateCollectionNoWithCollection_abc_shard2_replica_n3 ] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync [junit4] 2> 21343 INFO (qtp1049226253-65) [n:127.0.0.1:37271_solr c:testCreateCollectionNoWithCollection_abc s:shard2 r:core_node4 x:testCreateCollectionNoWithCollection_abc_shard2_replica_n3 ] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:37271/solr/testCreateCollectionNoWithCollection_abc_shard2_replica_n3/ [junit4] 2> 21345 INFO (qtp1049226253-65) [n:127.0.0.1:37271_solr c:testCreateCollectionNoWithCollection_abc s:shard2 r:core_node4 x:testCreateCollectionNoWithCollection_abc_shard2_replica_n3 ] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me [junit4] 2> 21345 INFO (qtp1049226253-65) [n:127.0.0.1:37271_solr c:testCreateCollectionNoWithCollection_abc s:shard2 r:core_node4 x:testCreateCollectionNoWithCollection_abc_shard2_replica_n3 ] o.a.s.c.SyncStrategy http://127.0.0.1:37271/solr/testCreateCollectionNoWithCollection_abc_shard2_replica_n3/ has no replicas [junit4] 2> 21345 INFO (qtp1049226253-65) [n:127.0.0.1:37271_solr c:testCreateCollectionNoWithCollection_abc s:shard2 r:core_node4 x:testCreateCollectionNoWithCollection_abc_shard2_replica_n3 ] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/testCreateCollectionNoWithCollection_abc/leaders/shard2/leader after winning as /collections/testCreateCollectionNoWithCollection_abc/leader_elect/shard2/election/72625268467957768-core_node4-n_0000000000 [junit4] 2> 21348 INFO (qtp1099228871-68) [n:127.0.0.1:37423_solr c:testCreateCollectionNoWithCollection_abc s:shard1 r:core_node2 x:testCreateCollectionNoWithCollection_abc_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:37423/solr/testCreateCollectionNoWithCollection_abc_shard1_replica_n1/ shard1 [junit4] 2> 21351 INFO (qtp1049226253-65) [n:127.0.0.1:37271_solr c:testCreateCollectionNoWithCollection_abc s:shard2 r:core_node4 x:testCreateCollectionNoWithCollection_abc_shard2_replica_n3 ] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:37271/solr/testCreateCollectionNoWithCollection_abc_shard2_replica_n3/ shard2 [junit4] 2> 21452 DEBUG (OverseerStateUpdate-72625268467957766-127.0.0.1:37423_solr-n_0000000000) [n:127.0.0.1:37423_solr ] o.a.s.c.o.ZkStateWriter going to update_collection /collections/testCreateCollectionNoWithCollection_abc/state.json version: 2 [junit4] 2> 21453 INFO (zkCallback-48-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testCreateCollectionNoWithCollection_abc/state.json] for collection [testCreateCollectionNoWithCollection_abc] has occurred - updating... (live nodes size: [2]) [junit4] 2> 21453 INFO (zkCallback-48-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testCreateCollectionNoWithCollection_abc/state.json] for collection [testCreateCollectionNoWithCollection_abc] has occurred - updating... (live nodes size: [2]) [junit4] 2> 21453 INFO (zkCallback-66-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testCreateCollectionNoWithCollection_abc/state.json] for collection [testCreateCollectionNoWithCollection_abc] has occurred - updating... (live nodes size: [2]) [junit4] 2> 21454 INFO (zkCallback-66-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testCreateCollectionNoWithCollection_abc/state.json] for collection [testCreateCollectionNoWithCollection_abc] has occurred - updating... (live nodes size: [2]) [junit4] 2> 21457 INFO (qtp1049226253-65) [n:127.0.0.1:37271_solr c:testCreateCollectionNoWithCollection_abc s:shard2 r:core_node4 x:testCreateCollectionNoWithCollection_abc_shard2_replica_n3 ] o.a.s.c.ZkController I am the leader, no recovery necessary [junit4] 2> 21458 INFO (qtp1099228871-68) [n:127.0.0.1:37423_solr c:testCreateCollectionNoWithCollection_abc s:shard1 r:core_node2 x:testCreateCollectionNoWithCollection_abc_shard1_replica_n1 ] o.a.s.c.ZkController I am the leader, no recovery necessary [junit4] 2> 21462 DEBUG (OverseerStateUpdate-72625268467957766-127.0.0.1:37423_solr-n_0000000000) [n:127.0.0.1:37423_solr ] o.a.s.c.o.ReplicaMutator Update state numShards=2 message={ [junit4] 2> "core":"testCreateCollectionNoWithCollection_abc_shard2_replica_n3", [junit4] 2> "core_node_name":"core_node4", [junit4] 2> "roles":null, [junit4] 2> "node_name":"127.0.0.1:37271_solr", [junit4] 2> "numShards":"2", [junit4] 2> "state":"active", [junit4] 2> "shard":"shard2", [junit4] 2> "collection":"testCreateCollectionNoWithCollection_abc", [junit4] 2> "type":"NRT", [junit4] 2> "force_set_state":"false", [junit4] 2> "operation":"state"} [junit4] 2> 21462 DEBUG (OverseerStateUpdate-72625268467957766-127.0.0.1:37423_solr-n_0000000000) [n:127.0.0.1:37423_solr ] o.a.s.c.o.ReplicaMutator Will update state for replica: core_node4:{ [junit4] 2> "core":"testCreateCollectionNoWithCollection_abc_shard2_replica_n3", [junit4] 2> "node_name":"127.0.0.1:37271_solr", [junit4] 2> "state":"active", [junit4] 2> "type":"NRT", [junit4] 2> "force_set_state":"false", [junit4] 2> "leader":"true", [junit4] 2> "base_url":"http://127.0.0.1:37271/solr"} [junit4] 2> 21462 DEBUG (OverseerStateUpdate-72625268467957766-127.0.0.1:37423_solr-n_0000000000) [n:127.0.0.1:37423_solr ] o.a.s.c.o.ReplicaMutator Collection is now: DocCollection(testCreateCollectionNoWithCollection_abc//collections/testCreateCollectionNoWithCollection_abc/state.json/3)={ [junit4] 2> "pullReplicas":"0", [junit4] 2> "replicationFactor":"1", [junit4] 2> "router":{"name":"compositeId"}, [junit4] 2> "maxShardsPerNode":"1", [junit4] 2> "autoAddReplicas":"false", [junit4] 2> "nrtReplicas":"1", [junit4] 2> "tlogReplicas":"0", [junit4] 2> "shards":{ [junit4] 2> "shard1":{ [junit4] 2> "range":"80000000-ffffffff", [junit4] 2> "state":"active", [junit4] 2> "replicas":{"core_node2":{ [junit4] 2> "core":"testCreateCollectionNoWithCollection_abc_shard1_replica_n1", [junit4] 2> "leader":"true", [junit4] 2> "node_name":"127.0.0.1:37423_solr", [junit4] 2> "state":"down", [junit4] 2> "type":"NRT", [junit4] 2> "force_set_state":"false"}}}, [junit4] 2> "shard2":{ [junit4] 2> "range":"0-7fffffff", [junit4] 2> "state":"active", [junit4] 2> "replicas":{"core_node4":{ [junit4] 2> "core":"testCreateCollectionNoWithCollection_abc_shard2_replica_n3", [junit4] 2> "leader":"true", [junit4] 2> "node_name":"127.0.0.1:37271_solr", [junit4] 2> "state":"active", [junit4] 2> "type":"NRT", [junit4] 2> "force_set_state":"false"}}}}} [junit4] 2> 21467 INFO (qtp1049226253-65) [n:127.0.0.1:37271_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node4&collection.configName=testCreateCollectionNoWithCollection_abc.AUTOCREATED&newCollection=true&name=testCreateCollectionNoWithCollection_abc_shard2_replica_n3&action=CREATE&numShards=2&collection=testCreateCollectionNoWithCollection_abc&shard=shard2&wt=javabin&version=2&replicaType=NRT} status=0 QTime=2138 [junit4] 2> 21469 INFO (qtp1099228871-68) [n:127.0.0.1:37423_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node2&collection.configName=testCreateCollectionNoWithCollection_abc.AUTOCREATED&newCollection=true&name=testCreateCollectionNoWithCollection_abc_shard1_replica_n1&action=CREATE&numShards=2&collection=testCreateCollectionNoWithCollection_abc&shard=shard1&wt=javabin&version=2&replicaType=NRT} status=0 QTime=2132 [junit4] 2> 21472 DEBUG (OverseerStateUpdate-72625268467957766-127.0.0.1:37423_solr-n_0000000000) [n:127.0.0.1:37423_solr ] o.a.s.c.o.ReplicaMutator Update state numShards=2 message={ [junit4] 2> "core":"testCreateCollectionNoWithCollection_abc_shard1_replica_n1", [junit4] 2> "core_node_name":"core_node2", [junit4] 2> "roles":null, [junit4] 2> "node_name":"127.0.0.1:37423_solr", [junit4] 2> "numShards":"2", [junit4] 2> "state":"active", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"testCreateCollectionNoWithCollection_abc", [junit4] 2> "type":"NRT", [junit4] 2> "force_set_state":"false", [junit4] 2> "operation":"state"} [junit4] 2> 21472 DEBUG (OverseerStateUpdate-72625268467957766-127.0.0.1:37423_solr-n_0000000000) [n:127.0.0.1:37423_solr ] o.a.s.c.o.ReplicaMutator Will update state for replica: core_node2:{ [junit4] 2> "core":"testCreateCollectionNoWithCollection_abc_shard1_replica_n1", [junit4] 2> "node_name":"127.0.0.1:37423_solr", [junit4] 2> "state":"active", [junit4] 2> "type":"NRT", [junit4] 2> "force_set_state":"false", [junit4] 2> "leader":"true", [junit4] 2> "base_url":"http://127.0.0.1:37423/solr"} [junit4] 2> 21472 DEBUG (OverseerStateUpdate-72625268467957766-127.0.0.1:37423_solr-n_0000000000) [n:127.0.0.1:37423_solr ] o.a.s.c.o.ReplicaMutator Collection is now: DocCollection(testCreateCollectionNoWithCollection_abc//collections/testCreateCollectionNoWithCollection_abc/state.json/3)={ [junit4] 2> "pullReplicas":"0", [junit4] 2> "replicationFactor":"1", [junit4] 2> "router":{"name":"compositeId"}, [junit4] 2> "maxShardsPerNode":"1", [junit4] 2> "autoAddReplicas":"false", [junit4] 2> "nrtReplicas":"1", [junit4] 2> "tlogReplicas":"0", [junit4] 2> "shards":{ [junit4] 2> "shard1":{ [junit4] 2> "range":"80000000-ffffffff", [junit4] 2> "state":"active", [junit4] 2> "replicas":{"core_node2":{ [junit4] 2> "core":"testCreateCollectionNoWithCollection_abc_shard1_replica_n1", [junit4] 2> "leader":"true", [junit4] 2> "node_name":"127.0.0.1:37423_solr", [junit4] 2> "state":"active", [junit4] 2> "type":"NRT", [junit4] 2> "force_set_state":"false"}}}, [junit4] 2> "shard2":{ [junit4] 2> "range":"0-7fffffff", [junit4] 2> "state":"active", [junit4] 2> "replicas":{"core_node4":{ [junit4] 2> "core":"testCreateCollectionNoWithCollection_abc_shard2_replica_n3", [junit4] 2> "leader":"true", [junit4] 2> "node_name":"127.0.0.1:37271_solr", [junit4] 2> "state":"active", [junit4] 2> "type":"NRT", [junit4] 2> "force_set_state":"false"}}}}} [junit4] 2> 21474 INFO (qtp1099228871-66) [n:127.0.0.1:37423_solr ] o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most 45 seconds. Check all shard replicas [junit4] 2> 21574 DEBUG (OverseerStateUpdate-72625268467957766-127.0.0.1:37423_solr-n_0000000000) [n:127.0.0.1:37423_solr ] o.a.s.c.o.ZkStateWriter going to update_collection /collections/testCreateCollectionNoWithCollection_abc/state.json version: 3 [junit4] 2> 21575 INFO (zkCallback-48-thread-3) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testCreateCollectionNoWithCollection_abc/state.json] for collection [testCreateCollectionNoWithCollection_abc] has occurred - updating... (live nodes size: [2]) [junit4] 2> 21576 INFO (zkCallback-66-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testCreateCollectionNoWithCollection_abc/state.json] for collection [testCreateCollectionNoWithCollection_abc] has occurred - updating... (live nodes size: [2]) [junit4] 2> 21576 INFO (zkCallback-48-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testCreateCollectionNoWithCollection_abc/state.json] for collection [testCreateCollectionNoWithCollection_abc] has occurred - updating... (live nodes size: [2]) [junit4] 2> 21576 INFO (zkCallback-66-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testCreateCollectionNoWithCollection_abc/state.json] for collection [testCreateCollectionNoWithCollection_abc] has occurred - updating... (live nodes size: [2]) [junit4] 2> 21576 INFO (zkCallback-48-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testCreateCollectionNoWithCollection_abc/state.json] for collection [t [...truncated too long message...] .a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@1baf9024: rootName = solr_37265, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm/com.codahale.metrics.MetricRegistry@5b4b3226 [junit4] 2> 135531 INFO (jetty-closer-983-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty tag=null [junit4] 2> 135531 INFO (jetty-closer-983-thread-1) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@7cefec09: rootName = solr_37265, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty/com.codahale.metrics.MetricRegistry@6da1b3b9 [junit4] 2> 135531 INFO (recoveryExecutor-917-thread-1-processing-n:127.0.0.1:37265_solr x:testAddReplicaWithPolicy_xyz_shard1_replica_n3 c:testAddReplicaWithPolicy_xyz s:shard1 r:core_node4) [n:127.0.0.1:37265_solr ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.testAddReplicaWithPolicy_xyz.shard1.leader tag=SolrCore@338c5ea3 [junit4] 2> 135532 INFO (jetty-closer-983-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster tag=null [junit4] 2> 135538 WARN (recoveryExecutor-917-thread-1-processing-n:127.0.0.1:37265_solr x:testAddReplicaWithPolicy_xyz_shard1_replica_n3 c:testAddReplicaWithPolicy_xyz s:shard1 r:core_node4) [n:127.0.0.1:37265_solr ] o.a.s.c.RecoveryStrategy Stopping recovery for core=[testAddReplicaWithPolicy_xyz_shard1_replica_n3] coreNodeName=[core_node4] [junit4] 2> 135538 INFO (recoveryExecutor-917-thread-1-processing-n:127.0.0.1:37265_solr x:testAddReplicaWithPolicy_xyz_shard1_replica_n3 c:testAddReplicaWithPolicy_xyz s:shard1 r:core_node4) [n:127.0.0.1:37265_solr ] o.a.s.u.DirectUpdateHandler2 Committing on IndexWriter.close() ... SKIPPED (unnecessary). [junit4] 2> 135570 INFO (closeThreadPool-995-thread-1) [ ] o.a.s.c.Overseer Overseer (id=72625275743240198-127.0.0.1:37265_solr-n_0000000000) closing [junit4] 2> 135570 INFO (OverseerStateUpdate-72625275743240198-127.0.0.1:37265_solr-n_0000000000) [n:127.0.0.1:37265_solr ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:37265_solr [junit4] 2> 135598 INFO (OverseerAutoScalingTriggerThread-72625275743240198-127.0.0.1:37265_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we are closed, exiting. [junit4] 2> 135611 WARN (jetty-launcher-901-thread-1-SendThread(127.0.0.1:46657)) [ ] o.a.z.ClientCnxn An exception was thrown while closing send thread for session 0x102044db2640008. [junit4] 2> => EndOfStreamException: Unable to read additional data from server sessionid 0x102044db2640008, likely server has closed socket [junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:77) [junit4] 2> org.apache.zookeeper.ClientCnxn$EndOfStreamException: Unable to read additional data from server sessionid 0x102044db2640008, likely server has closed socket [junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:77) ~[zookeeper-3.6.2.jar:3.6.2] [junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doTransport(ClientCnxnSocketNIO.java:350) ~[zookeeper-3.6.2.jar:3.6.2] [junit4] 2> at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1275) [zookeeper-3.6.2.jar:3.6.2] [junit4] 2> 135620 DEBUG (closeThreadPool-995-thread-1) [ ] o.a.s.c.a.ScheduledTriggers Shutting down scheduled thread pool executor now [junit4] 2> 135621 DEBUG (closeThreadPool-995-thread-1) [ ] o.a.s.c.a.ScheduledTriggers Shutting down action executor now [junit4] 2> 135621 DEBUG (closeThreadPool-995-thread-1) [ ] o.a.s.c.a.ScheduledTriggers Awaiting termination for action executor [junit4] 2> 135621 DEBUG (closeThreadPool-995-thread-1) [ ] o.a.s.c.a.ScheduledTriggers Awaiting termination for scheduled thread pool executor [junit4] 2> 135621 DEBUG (closeThreadPool-995-thread-1) [ ] o.a.s.c.a.ScheduledTriggers ScheduledTriggers closed completely [junit4] 2> 135621 DEBUG (closeThreadPool-995-thread-1) [ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread has been closed explicitly [junit4] 2> 135621 INFO (closeThreadPool-995-thread-2) [ ] o.a.s.c.Overseer Overseer (id=72625275743240198-127.0.0.1:37265_solr-n_0000000000) closing [junit4] 2> 135714 INFO (jetty-closer-983-thread-1) [ ] o.a.s.c.Overseer Overseer (id=72625275743240198-127.0.0.1:37265_solr-n_0000000000) closing [junit4] 2> 135715 INFO (jetty-closer-983-thread-1) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@5ac870ad{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:0} [junit4] 2> 135716 INFO (jetty-closer-983-thread-1) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@6d847f8{/solr,null,STOPPED} [junit4] 2> 135716 INFO (jetty-closer-983-thread-1) [ ] o.e.j.s.session node0 Stopped scavenging [junit4] 2> 136001 INFO (jetty-closer-983-thread-2) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@3039d57c{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:0} [junit4] 2> 136002 INFO (jetty-closer-983-thread-2) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@342fcff7{/solr,null,STOPPED} [junit4] 2> 136002 INFO (jetty-closer-983-thread-2) [ ] o.e.j.s.session node0 Stopped scavenging [junit4] 2> 136002 INFO (TEST-TestWithCollection.testAddReplicaWithPolicy-seed#[654426C6B60C3717]) [ ] o.a.s.c.ZkTestServer Shutting down ZkTestServer. [junit4] 2> 136003 WARN (TEST-TestWithCollection.testAddReplicaWithPolicy-seed#[654426C6B60C3717]-SendThread(127.0.0.1:46657)) [ ] o.a.z.ClientCnxn An exception was thrown while closing send thread for session 0x102044db2640000. [junit4] 2> => EndOfStreamException: Unable to read additional data from server sessionid 0x102044db2640000, likely server has closed socket [junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:77) [junit4] 2> org.apache.zookeeper.ClientCnxn$EndOfStreamException: Unable to read additional data from server sessionid 0x102044db2640000, likely server has closed socket [junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:77) ~[zookeeper-3.6.2.jar:3.6.2] [junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doTransport(ClientCnxnSocketNIO.java:350) ~[zookeeper-3.6.2.jar:3.6.2] [junit4] 2> at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1275) [zookeeper-3.6.2.jar:3.6.2] [junit4] 2> 136502 WARN (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Watch limit violations: [junit4] 2> Maximum concurrent create/delete watches above limit: [junit4] 2> [junit4] 2> 5 /solr/collections/testAddReplicaWithPolicy_xyz/terms/shard1 [junit4] 2> 5 /solr/collections/testAddReplicaWithPolicy_abc/terms/shard1 [junit4] 2> 3 /solr/clusterprops.json [junit4] 2> 3 /solr/aliases.json [junit4] 2> 2 /solr/packages.json [junit4] 2> 2 /solr/security.json [junit4] 2> 2 /solr/configs/testAddReplicaWithPolicy_abc.AUTOCREATED [junit4] 2> 2 /solr/configs/testAddReplicaWithPolicy_xyz.AUTOCREATED [junit4] 2> 2 /solr/configs/testAddReplicaWithPolicy_xyz.AUTOCREATED/managed-schema [junit4] 2> 2 /solr/configs/testAddReplicaWithPolicy_abc.AUTOCREATED/managed-schema [junit4] 2> 2 /solr/collections/testAddReplicaWithPolicy_abc/collectionprops.json [junit4] 2> [junit4] 2> Maximum concurrent data watches above limit: [junit4] 2> [junit4] 2> 38 /solr/collections/testAddReplicaWithPolicy_xyz/state.json [junit4] 2> 31 /solr/collections/testAddReplicaWithPolicy_abc/state.json [junit4] 2> 3 /solr/clusterstate.json [junit4] 2> 3 /solr/autoscaling.json [junit4] 2> [junit4] 2> Maximum concurrent children watches above limit: [junit4] 2> [junit4] 2> 39 /solr/overseer/queue [junit4] 2> 11 /solr/overseer/collection-queue-work [junit4] 2> 9 /solr/collections [junit4] 2> 6 /solr/live_nodes [junit4] 2> [junit4] 2> 136507 INFO (TEST-TestWithCollection.testAddReplicaWithPolicy-seed#[654426C6B60C3717]) [ ] o.a.s.c.ZkTestServer waitForServerDown: 127.0.0.1:46657 [junit4] 2> 136507 INFO (TEST-TestWithCollection.testAddReplicaWithPolicy-seed#[654426C6B60C3717]) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:46657 [junit4] 2> 136507 INFO (TEST-TestWithCollection.testAddReplicaWithPolicy-seed#[654426C6B60C3717]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 46657 [junit4] 2> 136507 INFO (TEST-TestWithCollection.testAddReplicaWithPolicy-seed#[654426C6B60C3717]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testAddReplicaWithPolicy [junit4] 2> NOTE: leaving temporary files on disk at: /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/build/solr-core/test/J3/temp/solr.cloud.TestWithCollection_654426C6B60C3717-001 [junit4] 2> Dec 10, 2020 3:59:13 PM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks [junit4] 2> WARNING: Will linger awaiting termination of 1 leaked thread(s). [junit4] 2> NOTE: test params are: codec=Asserting(Lucene87): {}, docValues:{}, maxPointsInLeafNode=940, maxMBSortInHeap=5.729698488625247, sim=Asserting(RandomSimilarity(queryNorm=true): {}), locale=it-CH, timezone=America/Paramaribo [junit4] 2> NOTE: Linux 5.4.0-45-generic amd64/Oracle Corporation 1.8.0_252 (64-bit)/cpus=4,threads=1,free=208356160,total=460849152 [junit4] 2> NOTE: All tests run in this JVM: [HdfsWriteToMultipleCollectionsTest, RequiredFieldsTest, TestWithCollection] [junit4] Completed [40/923 (1!)] on J3 in 123.37s, 9 tests, 1 failure <<< FAILURES! [...truncated 53703 lines...]
--------------------------------------------------------------------- To unsubscribe, e-mail: builds-unsubscr...@lucene.apache.org For additional commands, e-mail: builds-h...@lucene.apache.org