Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Linux/21514/ Java: 32bit/jdk1.8.0_162 -client -XX:+UseParallelGC
2 tests failed. FAILED: org.apache.solr.cloud.TestUtilizeNode.test Error Message: Stack Trace: java.lang.AssertionError at __randomizedtesting.SeedInfo.seed([1E2BEEE93086EC41:967FD1339E7A81B9]:0) at org.junit.Assert.fail(Assert.java:92) at org.junit.Assert.assertTrue(Assert.java:43) at org.junit.Assert.assertTrue(Assert.java:54) at org.apache.solr.cloud.TestUtilizeNode.test(TestUtilizeNode.java:81) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:498) at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1737) at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934) at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970) at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368) at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817) at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468) at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:943) at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829) at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879) at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368) at java.lang.Thread.run(Thread.java:748) FAILED: org.apache.solr.cloud.autoscaling.HdfsAutoAddReplicasIntegrationTest.testSimple Error Message: Waiting for collection testSimple2 null Live Nodes: [127.0.0.1:38605_solr, 127.0.0.1:44761_solr] Last available state: DocCollection(testSimple2//collections/testSimple2/state.json/21)={ "pullReplicas":"0", "replicationFactor":"2", "shards":{ "shard1":{ "range":"80000000-ffffffff", "state":"active", "replicas":{ "core_node3":{ "dataDir":"hdfs://localhost.localdomain:42189/data/testSimple2/core_node3/data/", "base_url":"http://127.0.0.1:36869/solr", "node_name":"127.0.0.1:36869_solr", "type":"NRT", "ulogDir":"hdfs://localhost.localdomain:42189/data/testSimple2/core_node3/data/tlog", "core":"testSimple2_shard1_replica_n1", "shared_storage":"true", "state":"down"}, "core_node5":{ "dataDir":"hdfs://localhost.localdomain:42189/data/testSimple2/core_node5/data/", "base_url":"http://127.0.0.1:44761/solr", "node_name":"127.0.0.1:44761_solr", "type":"NRT", "ulogDir":"hdfs://localhost.localdomain:42189/data/testSimple2/core_node5/data/tlog", "core":"testSimple2_shard1_replica_n2", "shared_storage":"true", "state":"active", "leader":"true"}}}, "shard2":{ "range":"0-7fffffff", "state":"active", "replicas":{ "core_node7":{ "dataDir":"hdfs://localhost.localdomain:42189/data/testSimple2/core_node7/data/", "base_url":"http://127.0.0.1:36869/solr", "node_name":"127.0.0.1:36869_solr", "type":"NRT", "ulogDir":"hdfs://localhost.localdomain:42189/data/testSimple2/core_node7/data/tlog", "core":"testSimple2_shard2_replica_n4", "shared_storage":"true", "state":"down"}, "core_node8":{ "dataDir":"hdfs://localhost.localdomain:42189/data/testSimple2/core_node8/data/", "base_url":"http://127.0.0.1:44761/solr", "node_name":"127.0.0.1:44761_solr", "type":"NRT", "ulogDir":"hdfs://localhost.localdomain:42189/data/testSimple2/core_node8/data/tlog", "core":"testSimple2_shard2_replica_n6", "shared_storage":"true", "state":"active", "leader":"true"}}}}, "router":{"name":"compositeId"}, "maxShardsPerNode":"2", "autoAddReplicas":"true", "nrtReplicas":"2", "tlogReplicas":"0"} Stack Trace: java.lang.AssertionError: Waiting for collection testSimple2 null Live Nodes: [127.0.0.1:38605_solr, 127.0.0.1:44761_solr] Last available state: DocCollection(testSimple2//collections/testSimple2/state.json/21)={ "pullReplicas":"0", "replicationFactor":"2", "shards":{ "shard1":{ "range":"80000000-ffffffff", "state":"active", "replicas":{ "core_node3":{ "dataDir":"hdfs://localhost.localdomain:42189/data/testSimple2/core_node3/data/", "base_url":"http://127.0.0.1:36869/solr", "node_name":"127.0.0.1:36869_solr", "type":"NRT", "ulogDir":"hdfs://localhost.localdomain:42189/data/testSimple2/core_node3/data/tlog", "core":"testSimple2_shard1_replica_n1", "shared_storage":"true", "state":"down"}, "core_node5":{ "dataDir":"hdfs://localhost.localdomain:42189/data/testSimple2/core_node5/data/", "base_url":"http://127.0.0.1:44761/solr", "node_name":"127.0.0.1:44761_solr", "type":"NRT", "ulogDir":"hdfs://localhost.localdomain:42189/data/testSimple2/core_node5/data/tlog", "core":"testSimple2_shard1_replica_n2", "shared_storage":"true", "state":"active", "leader":"true"}}}, "shard2":{ "range":"0-7fffffff", "state":"active", "replicas":{ "core_node7":{ "dataDir":"hdfs://localhost.localdomain:42189/data/testSimple2/core_node7/data/", "base_url":"http://127.0.0.1:36869/solr", "node_name":"127.0.0.1:36869_solr", "type":"NRT", "ulogDir":"hdfs://localhost.localdomain:42189/data/testSimple2/core_node7/data/tlog", "core":"testSimple2_shard2_replica_n4", "shared_storage":"true", "state":"down"}, "core_node8":{ "dataDir":"hdfs://localhost.localdomain:42189/data/testSimple2/core_node8/data/", "base_url":"http://127.0.0.1:44761/solr", "node_name":"127.0.0.1:44761_solr", "type":"NRT", "ulogDir":"hdfs://localhost.localdomain:42189/data/testSimple2/core_node8/data/tlog", "core":"testSimple2_shard2_replica_n6", "shared_storage":"true", "state":"active", "leader":"true"}}}}, "router":{"name":"compositeId"}, "maxShardsPerNode":"2", "autoAddReplicas":"true", "nrtReplicas":"2", "tlogReplicas":"0"} at __randomizedtesting.SeedInfo.seed([1E2BEEE93086EC41:2698CA1717753890]:0) at org.junit.Assert.fail(Assert.java:93) at org.apache.solr.cloud.SolrCloudTestCase.waitForState(SolrCloudTestCase.java:269) at org.apache.solr.cloud.autoscaling.AutoAddReplicasIntegrationTest.testSimple(AutoAddReplicasIntegrationTest.java:126) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:498) at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1737) at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934) at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970) at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368) at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817) at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468) at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:943) at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829) at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879) at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368) at java.lang.Thread.run(Thread.java:748) Build Log: [...truncated 13887 lines...] [junit4] Suite: org.apache.solr.cloud.TestUtilizeNode [junit4] 2> Creating dataDir: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.TestUtilizeNode_1E2BEEE93086EC41-001/init-core-data-001 [junit4] 2> 2850715 WARN (SUITE-TestUtilizeNode-seed#[1E2BEEE93086EC41]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=1 numCloses=1 [junit4] 2> 2850715 INFO (SUITE-TestUtilizeNode-seed#[1E2BEEE93086EC41]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=false [junit4] 2> 2850717 INFO (SUITE-TestUtilizeNode-seed#[1E2BEEE93086EC41]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (true) via: @org.apache.solr.util.RandomizeSSL(reason=, ssl=NaN, value=NaN, clientAuth=NaN) [junit4] 2> 2850717 INFO (SUITE-TestUtilizeNode-seed#[1E2BEEE93086EC41]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom [junit4] 2> 2850718 INFO (SUITE-TestUtilizeNode-seed#[1E2BEEE93086EC41]-worker) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 4 servers in /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.TestUtilizeNode_1E2BEEE93086EC41-001/tempDir-001 [junit4] 2> 2850718 INFO (SUITE-TestUtilizeNode-seed#[1E2BEEE93086EC41]-worker) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER [junit4] 2> 2850721 INFO (Thread-5629) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0 [junit4] 2> 2850721 INFO (Thread-5629) [ ] o.a.s.c.ZkTestServer Starting server [junit4] 2> 2850728 ERROR (Thread-5629) [ ] o.a.z.s.ZooKeeperServer ZKShutdownHandler is not registered, so ZooKeeper server won't take any action on ERROR or SHUTDOWN server state changes [junit4] 2> 2850821 INFO (SUITE-TestUtilizeNode-seed#[1E2BEEE93086EC41]-worker) [ ] o.a.s.c.ZkTestServer start zk server on port:32839 [junit4] 2> 2850847 INFO (zkConnectionManagerCallback-6898-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 2850850 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ] o.a.z.s.NIOServerCnxn Unable to read additional data from client sessionid 0x1007dc75a3c0000, likely client has closed socket [junit4] 2> 2850860 INFO (jetty-launcher-6895-thread-1) [ ] o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: 2017-11-21T12:27:37-09:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8 [junit4] 2> 2850864 INFO (jetty-launcher-6895-thread-2) [ ] o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: 2017-11-21T12:27:37-09:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8 [junit4] 2> 2850865 INFO (jetty-launcher-6895-thread-3) [ ] o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: 2017-11-21T12:27:37-09:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8 [junit4] 2> 2850866 INFO (jetty-launcher-6895-thread-4) [ ] o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: 2017-11-21T12:27:37-09:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8 [junit4] 2> 2850916 INFO (jetty-launcher-6895-thread-4) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0 [junit4] 2> 2850917 INFO (jetty-launcher-6895-thread-4) [ ] o.e.j.s.session No SessionScavenger set, using defaults [junit4] 2> 2850917 INFO (jetty-launcher-6895-thread-2) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0 [junit4] 2> 2850917 INFO (jetty-launcher-6895-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0 [junit4] 2> 2850917 INFO (jetty-launcher-6895-thread-3) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0 [junit4] 2> 2850917 INFO (jetty-launcher-6895-thread-2) [ ] o.e.j.s.session No SessionScavenger set, using defaults [junit4] 2> 2850917 INFO (jetty-launcher-6895-thread-4) [ ] o.e.j.s.session Scavenging every 660000ms [junit4] 2> 2850917 INFO (jetty-launcher-6895-thread-2) [ ] o.e.j.s.session Scavenging every 660000ms [junit4] 2> 2850917 INFO (jetty-launcher-6895-thread-3) [ ] o.e.j.s.session No SessionScavenger set, using defaults [junit4] 2> 2850917 INFO (jetty-launcher-6895-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults [junit4] 2> 2850917 INFO (jetty-launcher-6895-thread-3) [ ] o.e.j.s.session Scavenging every 660000ms [junit4] 2> 2850917 INFO (jetty-launcher-6895-thread-1) [ ] o.e.j.s.session Scavenging every 660000ms [junit4] 2> 2850918 INFO (jetty-launcher-6895-thread-4) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@6f9143{/solr,null,AVAILABLE} [junit4] 2> 2850918 INFO (jetty-launcher-6895-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@1140c6a{/solr,null,AVAILABLE} [junit4] 2> 2850919 INFO (jetty-launcher-6895-thread-3) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@9c431d{/solr,null,AVAILABLE} [junit4] 2> 2850919 INFO (jetty-launcher-6895-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@188acce{/solr,null,AVAILABLE} [junit4] 2> 2850922 INFO (jetty-launcher-6895-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@148ac45{HTTP/1.1,[http/1.1]}{127.0.0.1:33959} [junit4] 2> 2850922 INFO (jetty-launcher-6895-thread-3) [ ] o.e.j.s.AbstractConnector Started ServerConnector@a1e078{HTTP/1.1,[http/1.1]}{127.0.0.1:33273} [junit4] 2> 2850922 INFO (jetty-launcher-6895-thread-1) [ ] o.e.j.s.Server Started @2852648ms [junit4] 2> 2850922 INFO (jetty-launcher-6895-thread-3) [ ] o.e.j.s.Server Started @2852648ms [junit4] 2> 2850922 INFO (jetty-launcher-6895-thread-4) [ ] o.e.j.s.AbstractConnector Started ServerConnector@1607904{HTTP/1.1,[http/1.1]}{127.0.0.1:46881} [junit4] 2> 2850922 INFO (jetty-launcher-6895-thread-3) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=33273} [junit4] 2> 2850922 INFO (jetty-launcher-6895-thread-4) [ ] o.e.j.s.Server Started @2852648ms [junit4] 2> 2850922 INFO (jetty-launcher-6895-thread-4) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=46881} [junit4] 2> 2850923 ERROR (jetty-launcher-6895-thread-4) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 2850923 ERROR (jetty-launcher-6895-thread-3) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 2850923 INFO (jetty-launcher-6895-thread-4) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.0.0 [junit4] 2> 2850923 INFO (jetty-launcher-6895-thread-4) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 2850923 INFO (jetty-launcher-6895-thread-4) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 2850923 INFO (jetty-launcher-6895-thread-4) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-02-23T00:50:56.522Z [junit4] 2> 2850922 INFO (jetty-launcher-6895-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=33959} [junit4] 2> 2850923 INFO (jetty-launcher-6895-thread-3) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.0.0 [junit4] 2> 2850922 INFO (jetty-launcher-6895-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@9fee2{HTTP/1.1,[http/1.1]}{127.0.0.1:42613} [junit4] 2> 2850924 INFO (jetty-launcher-6895-thread-3) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 2850924 INFO (jetty-launcher-6895-thread-3) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 2850924 ERROR (jetty-launcher-6895-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 2850924 INFO (jetty-launcher-6895-thread-3) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-02-23T00:50:56.523Z [junit4] 2> 2850924 INFO (jetty-launcher-6895-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.0.0 [junit4] 2> 2850924 INFO (jetty-launcher-6895-thread-2) [ ] o.e.j.s.Server Started @2852650ms [junit4] 2> 2850924 INFO (jetty-launcher-6895-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 2850924 INFO (jetty-launcher-6895-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=42613} [junit4] 2> 2850924 INFO (jetty-launcher-6895-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 2850924 INFO (jetty-launcher-6895-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-02-23T00:50:56.523Z [junit4] 2> 2850924 ERROR (jetty-launcher-6895-thread-2) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 2850925 INFO (jetty-launcher-6895-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.0.0 [junit4] 2> 2850925 INFO (jetty-launcher-6895-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 2850925 INFO (jetty-launcher-6895-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 2850925 INFO (jetty-launcher-6895-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-02-23T00:50:56.524Z [junit4] 2> 2850952 INFO (zkConnectionManagerCallback-6900-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 2850952 INFO (zkConnectionManagerCallback-6906-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 2850953 INFO (zkConnectionManagerCallback-6904-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 2850953 INFO (jetty-launcher-6895-thread-4) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 2850953 INFO (zkConnectionManagerCallback-6902-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 2850953 INFO (jetty-launcher-6895-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 2850953 INFO (jetty-launcher-6895-thread-3) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 2850961 INFO (jetty-launcher-6895-thread-2) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 2850978 INFO (jetty-launcher-6895-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:32839/solr [junit4] 2> 2850995 INFO (zkConnectionManagerCallback-6910-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 2851008 INFO (jetty-launcher-6895-thread-3) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:32839/solr [junit4] 2> 2851020 INFO (jetty-launcher-6895-thread-4) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:32839/solr [junit4] 2> 2851020 INFO (jetty-launcher-6895-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:32839/solr [junit4] 2> 2851024 INFO (zkConnectionManagerCallback-6916-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 2851032 INFO (zkConnectionManagerCallback-6920-thread-1-processing-n:127.0.0.1:33959_solr) [n:127.0.0.1:33959_solr ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 2851032 INFO (zkConnectionManagerCallback-6922-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 2851032 INFO (zkConnectionManagerCallback-6924-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 2851033 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ] o.a.z.s.NIOServerCnxn Unable to read additional data from client sessionid 0x1007dc75a3c0009, likely client has closed socket [junit4] 2> 2851079 INFO (zkConnectionManagerCallback-6926-thread-1-processing-n:127.0.0.1:46881_solr) [n:127.0.0.1:46881_solr ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 2851079 INFO (zkConnectionManagerCallback-6930-thread-1-processing-n:127.0.0.1:42613_solr) [n:127.0.0.1:42613_solr ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 2851079 INFO (zkConnectionManagerCallback-6929-thread-1-processing-n:127.0.0.1:33273_solr) [n:127.0.0.1:33273_solr ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 2851086 INFO (jetty-launcher-6895-thread-4) [n:127.0.0.1:46881_solr ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 2851086 INFO (jetty-launcher-6895-thread-3) [n:127.0.0.1:33273_solr ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 2851086 INFO (jetty-launcher-6895-thread-2) [n:127.0.0.1:42613_solr ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 2851086 INFO (jetty-launcher-6895-thread-4) [n:127.0.0.1:46881_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:46881_solr [junit4] 2> 2851087 INFO (jetty-launcher-6895-thread-3) [n:127.0.0.1:33273_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:33273_solr [junit4] 2> 2851087 INFO (jetty-launcher-6895-thread-2) [n:127.0.0.1:42613_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:42613_solr [junit4] 2> 2851088 INFO (jetty-launcher-6895-thread-4) [n:127.0.0.1:46881_solr ] o.a.s.c.Overseer Overseer (id=72195889203773450-127.0.0.1:46881_solr-n_0000000000) starting [junit4] 2> 2851088 INFO (zkCallback-6925-thread-1-processing-n:127.0.0.1:46881_solr) [n:127.0.0.1:46881_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2) [junit4] 2> 2851091 INFO (zkCallback-6928-thread-1-processing-n:127.0.0.1:42613_solr) [n:127.0.0.1:42613_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2) [junit4] 2> 2851092 INFO (zkCallback-6927-thread-1-processing-n:127.0.0.1:33273_solr) [n:127.0.0.1:33273_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2) [junit4] 2> 2851103 INFO (jetty-launcher-6895-thread-1) [n:127.0.0.1:33959_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2) [junit4] 2> 2851104 INFO (jetty-launcher-6895-thread-1) [n:127.0.0.1:33959_solr ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 2851104 INFO (jetty-launcher-6895-thread-4) [n:127.0.0.1:46881_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:46881_solr [junit4] 2> 2851105 INFO (zkCallback-6928-thread-1-processing-n:127.0.0.1:42613_solr) [n:127.0.0.1:42613_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3) [junit4] 2> 2851105 INFO (zkCallback-6925-thread-1-processing-n:127.0.0.1:46881_solr) [n:127.0.0.1:46881_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3) [junit4] 2> 2851107 INFO (jetty-launcher-6895-thread-1) [n:127.0.0.1:33959_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:33959_solr [junit4] 2> 2851107 INFO (zkCallback-6928-thread-1-processing-n:127.0.0.1:42613_solr) [n:127.0.0.1:42613_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4) [junit4] 2> 2851123 INFO (zkCallback-6919-thread-1-processing-n:127.0.0.1:33959_solr) [n:127.0.0.1:33959_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (4) [junit4] 2> 2851127 INFO (zkCallback-6927-thread-1-processing-n:127.0.0.1:33273_solr) [n:127.0.0.1:33273_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (4) [junit4] 2> 2851127 INFO (zkCallback-6925-thread-2-processing-n:127.0.0.1:46881_solr) [n:127.0.0.1:46881_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4) [junit4] 2> 2851479 INFO (jetty-launcher-6895-thread-1) [n:127.0.0.1:33959_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_33959.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@9f1307 [junit4] 2> 2851483 INFO (jetty-launcher-6895-thread-3) [n:127.0.0.1:33273_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_33273.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@9f1307 [junit4] 2> 2851485 INFO (jetty-launcher-6895-thread-2) [n:127.0.0.1:42613_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_42613.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@9f1307 [junit4] 2> 2851486 INFO (jetty-launcher-6895-thread-4) [n:127.0.0.1:46881_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_46881.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@9f1307 [junit4] 2> 2851492 INFO (jetty-launcher-6895-thread-1) [n:127.0.0.1:33959_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_33959.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@9f1307 [junit4] 2> 2851493 INFO (jetty-launcher-6895-thread-1) [n:127.0.0.1:33959_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_33959.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@9f1307 [junit4] 2> 2851494 INFO (jetty-launcher-6895-thread-1) [n:127.0.0.1:33959_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.TestUtilizeNode_1E2BEEE93086EC41-001/tempDir-001/node1/. [junit4] 2> 2851495 INFO (jetty-launcher-6895-thread-2) [n:127.0.0.1:42613_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_42613.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@9f1307 [junit4] 2> 2851495 INFO (jetty-launcher-6895-thread-2) [n:127.0.0.1:42613_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_42613.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@9f1307 [junit4] 2> 2851496 INFO (jetty-launcher-6895-thread-2) [n:127.0.0.1:42613_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.TestUtilizeNode_1E2BEEE93086EC41-001/tempDir-001/node2/. [junit4] 2> 2851497 INFO (jetty-launcher-6895-thread-3) [n:127.0.0.1:33273_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_33273.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@9f1307 [junit4] 2> 2851497 INFO (jetty-launcher-6895-thread-3) [n:127.0.0.1:33273_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_33273.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@9f1307 [junit4] 2> 2851498 INFO (jetty-launcher-6895-thread-3) [n:127.0.0.1:33273_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.TestUtilizeNode_1E2BEEE93086EC41-001/tempDir-001/node3/. [junit4] 2> 2851500 INFO (jetty-launcher-6895-thread-4) [n:127.0.0.1:46881_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_46881.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@9f1307 [junit4] 2> 2851500 INFO (jetty-launcher-6895-thread-4) [n:127.0.0.1:46881_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_46881.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@9f1307 [junit4] 2> 2851502 INFO (jetty-launcher-6895-thread-4) [n:127.0.0.1:46881_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.TestUtilizeNode_1E2BEEE93086EC41-001/tempDir-001/node4/. [junit4] 2> 2851672 INFO (zkConnectionManagerCallback-6940-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 2851675 INFO (zkConnectionManagerCallback-6944-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 2851677 INFO (SUITE-TestUtilizeNode-seed#[1E2BEEE93086EC41]-worker) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4) [junit4] 2> 2851677 INFO (SUITE-TestUtilizeNode-seed#[1E2BEEE93086EC41]-worker) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:32839/solr ready [junit4] 2> 2851681 INFO (qtp33446152-25782) [n:127.0.0.1:46881_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :overseerstatus with params action=OVERSEERSTATUS&wt=javabin&version=2 and sendToOCPQueue=true [junit4] 2> 2851690 INFO (qtp33446152-25782) [n:127.0.0.1:46881_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=OVERSEERSTATUS&wt=javabin&version=2} status=0 QTime=8 [junit4] 2> 2851697 INFO (OverseerCollectionConfigSetProcessor-72195889203773450-127.0.0.1:46881_solr-n_0000000000) [n:127.0.0.1:46881_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> 2851728 INFO (TEST-TestUtilizeNode.test-seed#[1E2BEEE93086EC41]) [ ] o.a.s.SolrTestCaseJ4 ###Starting test [junit4] 2> 2851729 INFO (TEST-TestUtilizeNode.test-seed#[1E2BEEE93086EC41]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4) [junit4] 2> 2851735 INFO (zkConnectionManagerCallback-6947-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 2851738 INFO (qtp33446152-25786) [n:127.0.0.1:46881_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params replicationFactor=2&collection.configName=conf1&name=utilizenodecoll&nrtReplicas=2&action=CREATE&numShards=2&wt=javabin&version=2 and sendToOCPQueue=true [junit4] 2> 2851743 INFO (OverseerThreadFactory-6191-thread-2-processing-n:127.0.0.1:46881_solr) [n:127.0.0.1:46881_solr ] o.a.s.c.a.c.CreateCollectionCmd Create collection utilizenodecoll [junit4] 2> 2851853 INFO (OverseerStateUpdate-72195889203773450-127.0.0.1:46881_solr-n_0000000000) [n:127.0.0.1:46881_solr ] o.a.s.c.o.SliceMutator createReplica() { [junit4] 2> "operation":"ADDREPLICA", [junit4] 2> "collection":"utilizenodecoll", [junit4] 2> "shard":"shard1", [junit4] 2> "core":"utilizenodecoll_shard1_replica_n1", [junit4] 2> "state":"down", [junit4] 2> "base_url":"http://127.0.0.1:46881/solr", [junit4] 2> "type":"NRT", [junit4] 2> "waitForFinalState":"false"} [junit4] 2> 2851856 INFO (OverseerStateUpdate-72195889203773450-127.0.0.1:46881_solr-n_0000000000) [n:127.0.0.1:46881_solr ] o.a.s.c.o.SliceMutator createReplica() { [junit4] 2> "operation":"ADDREPLICA", [junit4] 2> "collection":"utilizenodecoll", [junit4] 2> "shard":"shard1", [junit4] 2> "core":"utilizenodecoll_shard1_replica_n3", [junit4] 2> "state":"down", [junit4] 2> "base_url":"http://127.0.0.1:33273/solr", [junit4] 2> "type":"NRT", [junit4] 2> "waitForFinalState":"false"} [junit4] 2> 2851858 INFO (OverseerStateUpdate-72195889203773450-127.0.0.1:46881_solr-n_0000000000) [n:127.0.0.1:46881_solr ] o.a.s.c.o.SliceMutator createReplica() { [junit4] 2> "operation":"ADDREPLICA", [junit4] 2> "collection":"utilizenodecoll", [junit4] 2> "shard":"shard2", [junit4] 2> "core":"utilizenodecoll_shard2_replica_n4", [junit4] 2> "state":"down", [junit4] 2> "base_url":"http://127.0.0.1:42613/solr", [junit4] 2> "type":"NRT", [junit4] 2> "waitForFinalState":"false"} [junit4] 2> 2851860 INFO (OverseerStateUpdate-72195889203773450-127.0.0.1:46881_solr-n_0000000000) [n:127.0.0.1:46881_solr ] o.a.s.c.o.SliceMutator createReplica() { [junit4] 2> "operation":"ADDREPLICA", [junit4] 2> "collection":"utilizenodecoll", [junit4] 2> "shard":"shard2", [junit4] 2> "core":"utilizenodecoll_shard2_replica_n6", [junit4] 2> "state":"down", [junit4] 2> "base_url":"http://127.0.0.1:33959/solr", [junit4] 2> "type":"NRT", [junit4] 2> "waitForFinalState":"false"} [junit4] 2> 2852065 INFO (qtp33446152-25790) [n:127.0.0.1:46881_solr ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node2&collection.configName=conf1&newCollection=true&name=utilizenodecoll_shard1_replica_n1&action=CREATE&numShards=2&collection=utilizenodecoll&shard=shard1&wt=javabin&version=2&replicaType=NRT [junit4] 2> 2852065 INFO (qtp33446152-25790) [n:127.0.0.1:46881_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores [junit4] 2> 2852082 INFO (qtp13594338-25770) [n:127.0.0.1:42613_solr ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node7&collection.configName=conf1&newCollection=true&name=utilizenodecoll_shard2_replica_n4&action=CREATE&numShards=2&collection=utilizenodecoll&shard=shard2&wt=javabin&version=2&replicaType=NRT [junit4] 2> 2852082 INFO (qtp13594338-25770) [n:127.0.0.1:42613_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores [junit4] 2> 2852088 INFO (qtp31901758-25766) [n:127.0.0.1:33959_solr ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node8&collection.configName=conf1&newCollection=true&name=utilizenodecoll_shard2_replica_n6&action=CREATE&numShards=2&collection=utilizenodecoll&shard=shard2&wt=javabin&version=2&replicaType=NRT [junit4] 2> 2852088 INFO (qtp31901758-25766) [n:127.0.0.1:33959_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores [junit4] 2> 2852089 INFO (qtp3061901-25776) [n:127.0.0.1:33273_solr ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node5&collection.configName=conf1&newCollection=true&name=utilizenodecoll_shard1_replica_n3&action=CREATE&numShards=2&collection=utilizenodecoll&shard=shard1&wt=javabin&version=2&replicaType=NRT [junit4] 2> 2852090 INFO (qtp3061901-25776) [n:127.0.0.1:33273_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores [junit4] 2> 2852194 INFO (zkCallback-6928-thread-1-processing-n:127.0.0.1:42613_solr) [n:127.0.0.1:42613_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/utilizenodecoll/state.json] for collection [utilizenodecoll] has occurred - updating... (live nodes size: [4]) [junit4] 2> 2852194 INFO (zkCallback-6927-thread-1-processing-n:127.0.0.1:33273_solr) [n:127.0.0.1:33273_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/utilizenodecoll/state.json] for collection [utilizenodecoll] has occurred - updating... (live nodes size: [4]) [junit4] 2> 2852194 INFO (zkCallback-6925-thread-1-processing-n:127.0.0.1:46881_solr) [n:127.0.0.1:46881_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/utilizenodecoll/state.json] for collection [utilizenodecoll] has occurred - updating... (live nodes size: [4]) [junit4] 2> 2852194 INFO (zkCallback-6925-thread-2-processing-n:127.0.0.1:46881_solr) [n:127.0.0.1:46881_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/utilizenodecoll/state.json] for collection [utilizenodecoll] has occurred - updating... (live nodes size: [4]) [junit4] 2> 2852194 INFO (zkCallback-6919-thread-1-processing-n:127.0.0.1:33959_solr) [n:127.0.0.1:33959_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/utilizenodecoll/state.json] for collection [utilizenodecoll] has occurred - updating... (live nodes size: [4]) [junit4] 2> 2852195 INFO (zkCallback-6928-thread-2-processing-n:127.0.0.1:42613_solr) [n:127.0.0.1:42613_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/utilizenodecoll/state.json] for collection [utilizenodecoll] has occurred - updating... (live nodes size: [4]) [junit4] 2> 2852195 INFO (zkCallback-6927-thread-2-processing-n:127.0.0.1:33273_solr) [n:127.0.0.1:33273_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/utilizenodecoll/state.json] for collection [utilizenodecoll] has occurred - updating... (live nodes size: [4]) [junit4] 2> 2852199 INFO (zkCallback-6919-thread-2-processing-n:127.0.0.1:33959_solr) [n:127.0.0.1:33959_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/utilizenodecoll/state.json] for collection [utilizenodecoll] has occurred - updating... (live nodes size: [4]) [junit4] 2> 2853104 INFO (qtp33446152-25790) [n:127.0.0.1:46881_solr c:utilizenodecoll s:shard1 r:core_node2 x:utilizenodecoll_shard1_replica_n1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0 [junit4] 2> 2853118 INFO (qtp13594338-25770) [n:127.0.0.1:42613_solr c:utilizenodecoll s:shard2 r:core_node7 x:utilizenodecoll_shard2_replica_n4] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0 [junit4] 2> 2853118 INFO (qtp3061901-25776) [n:127.0.0.1:33273_solr c:utilizenodecoll s:shard1 r:core_node5 x:utilizenodecoll_shard1_replica_n3] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0 [junit4] 2> 2853119 INFO (qtp31901758-25766) [n:127.0.0.1:33959_solr c:utilizenodecoll s:shard2 r:core_node8 x:utilizenodecoll_shard2_replica_n6] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0 [junit4] 2> 2853133 INFO (qtp3061901-25776) [n:127.0.0.1:33273_solr c:utilizenodecoll s:shard1 r:core_node5 x:utilizenodecoll_shard1_replica_n3] o.a.s.s.IndexSchema [utilizenodecoll_shard1_replica_n3] Schema name=cloud-dynamic [junit4] 2> 2853136 INFO (qtp31901758-25766) [n:127.0.0.1:33959_solr c:utilizenodecoll s:shard2 r:core_node8 x:utilizenodecoll_shard2_replica_n6] o.a.s.s.IndexSchema [utilizenodecoll_shard2_replica_n6] Schema name=cloud-dynamic [junit4] 2> 2853139 INFO (qtp33446152-25790) [n:127.0.0.1:46881_solr c:utilizenodecoll s:shard1 r:core_node2 x:utilizenodecoll_shard1_replica_n1] o.a.s.s.IndexSchema [utilizenodecoll_shard1_replica_n1] Schema name=cloud-dynamic [junit4] 2> 2853139 INFO (qtp13594338-25770) [n:127.0.0.1:42613_solr c:utilizenodecoll s:shard2 r:core_node7 x:utilizenodecoll_shard2_replica_n4] o.a.s.s.IndexSchema [utilizenodecoll_shard2_replica_n4] Schema name=cloud-dynamic [junit4] 2> 2853174 INFO (qtp31901758-25766) [n:127.0.0.1:33959_solr c:utilizenodecoll s:shard2 r:core_node8 x:utilizenodecoll_shard2_replica_n6] o.a.s.s.IndexSchema Loaded schema cloud-dynamic/1.6 with uniqueid field id [junit4] 2> 2853174 INFO (qtp31901758-25766) [n:127.0.0.1:33959_solr c:utilizenodecoll s:shard2 r:core_node8 x:utilizenodecoll_shard2_replica_n6] o.a.s.c.CoreContainer Creating SolrCore 'utilizenodecoll_shard2_replica_n6' using configuration from collection utilizenodecoll, trusted=true [junit4] 2> 2853174 INFO (qtp31901758-25766) [n:127.0.0.1:33959_solr c:utilizenodecoll s:shard2 r:core_node8 x:utilizenodecoll_shard2_replica_n6] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_33959.solr.core.utilizenodecoll.shard2.replica_n6' (registry 'solr.core.utilizenodecoll.shard2.replica_n6') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@9f1307 [junit4] 2> 2853175 INFO (qtp31901758-25766) [n:127.0.0.1:33959_solr c:utilizenodecoll s:shard2 r:core_node8 x:utilizenodecoll_shard2_replica_n6] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder [junit4] 2> 2853175 INFO (qtp31901758-25766) [n:127.0.0.1:33959_solr c:utilizenodecoll s:shard2 r:core_node8 x:utilizenodecoll_shard2_replica_n6] o.a.s.c.SolrCore [[utilizenodecoll_shard2_replica_n6] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.TestUtilizeNode_1E2BEEE93086EC41-001/tempDir-001/node1/utilizenodecoll_shard2_replica_n6], dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.TestUtilizeNode_1E2BEEE93086EC41-001/tempDir-001/node1/./utilizenodecoll_shard2_replica_n6/data/] [junit4] 2> 2853175 INFO (qtp3061901-25776) [n:127.0.0.1:33273_solr c:utilizenodecoll s:shard1 r:core_node5 x:utilizenodecoll_shard1_replica_n3] o.a.s.s.IndexSchema Loaded schema cloud-dynamic/1.6 with uniqueid field id [junit4] 2> 2853175 INFO (qtp3061901-25776) [n:127.0.0.1:33273_solr c:utilizenodecoll s:shard1 r:core_node5 x:utilizenodecoll_shard1_replica_n3] o.a.s.c.CoreContainer Creating SolrCore 'utilizenodecoll_shard1_replica_n3' using configuration from collection utilizenodecoll, trusted=true [junit4] 2> 2853176 INFO (qtp3061901-25776) [n:127.0.0.1:33273_solr c:utilizenodecoll s:shard1 r:core_node5 x:utilizenodecoll_shard1_replica_n3] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_33273.solr.core.utilizenodecoll.shard1.replica_n3' (registry 'solr.core.utilizenodecoll.shard1.replica_n3') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@9f1307 [junit4] 2> 2853176 INFO (qtp3061901-25776) [n:127.0.0.1:33273_solr c:utilizenodecoll s:shard1 r:core_node5 x:utilizenodecoll_shard1_replica_n3] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder [junit4] 2> 2853176 INFO (qtp3061901-25776) [n:127.0.0.1:33273_solr c:utilizenodecoll s:shard1 r:core_node5 x:utilizenodecoll_shard1_replica_n3] o.a.s.c.SolrCore [[utilizenodecoll_shard1_replica_n3] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.TestUtilizeNode_1E2BEEE93086EC41-001/tempDir-001/node3/utilizenodecoll_shard1_replica_n3], dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.TestUtilizeNode_1E2BEEE93086EC41-001/tempDir-001/node3/./utilizenodecoll_shard1_replica_n3/data/] [junit4] 2> 2853197 INFO (qtp33446152-25790) [n:127.0.0.1:46881_solr c:utilizenodecoll s:shard1 r:core_node2 x:utilizenodecoll_shard1_replica_n1] o.a.s.s.IndexSchema Loaded schema cloud-dynamic/1.6 with uniqueid field id [junit4] 2> 2853197 INFO (qtp33446152-25790) [n:127.0.0.1:46881_solr c:utilizenodecoll s:shard1 r:core_node2 x:utilizenodecoll_shard1_replica_n1] o.a.s.c.CoreContainer Creating SolrCore 'utilizenodecoll_shard1_replica_n1' using configuration from collection utilizenodecoll, trusted=true [junit4] 2> 2853199 INFO (qtp33446152-25790) [n:127.0.0.1:46881_solr c:utilizenodecoll s:shard1 r:core_node2 x:utilizenodecoll_shard1_replica_n1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_46881.solr.core.utilizenodecoll.shard1.replica_n1' (registry 'solr.core.utilizenodecoll.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@9f1307 [junit4] 2> 2853199 INFO (qtp33446152-25790) [n:127.0.0.1:46881_solr c:utilizenodecoll s:shard1 r:core_node2 x:utilizenodecoll_shard1_replica_n1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder [junit4] 2> 2853199 INFO (qtp33446152-25790) [n:127.0.0.1:46881_solr c:utilizenodecoll s:shard1 r:core_node2 x:utilizenodecoll_shard1_replica_n1] o.a.s.c.SolrCore [[utilizenodecoll_shard1_replica_n1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.TestUtilizeNode_1E2BEEE93086EC41-001/tempDir-001/node4/utilizenodecoll_shard1_replica_n1], dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.TestUtilizeNode_1E2BEEE93086EC41-001/tempDir-001/node4/./utilizenodecoll_shard1_replica_n1/data/] [junit4] 2> 2853200 INFO (qtp13594338-25770) [n:127.0.0.1:42613_solr c:utilizenodecoll s:shard2 r:core_node7 x:utilizenodecoll_shard2_replica_n4] o.a.s.s.IndexSchema Loaded schema cloud-dynamic/1.6 with uniqueid field id [junit4] 2> 2853200 INFO (qtp13594338-25770) [n:127.0.0.1:42613_solr c:utilizenodecoll s:shard2 r:core_node7 x:utilizenodecoll_shard2_replica_n4] o.a.s.c.CoreContainer Creating SolrCore 'utilizenodecoll_shard2_replica_n4' using configuration from collection utilizenodecoll, trusted=true [junit4] 2> 2853215 INFO (qtp13594338-25770) [n:127.0.0.1:42613_solr c:utilizenodecoll s:shard2 r:core_node7 x:utilizenodecoll_shard2_replica_n4] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_42613.solr.core.utilizenodecoll.shard2.replica_n4' (registry 'solr.core.utilizenodecoll.shard2.replica_n4') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@9f1307 [junit4] 2> 2853215 INFO (qtp13594338-25770) [n:127.0.0.1:42613_solr c:utilizenodecoll s:shard2 r:core_node7 x:utilizenodecoll_shard2_replica_n4] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder [junit4] 2> 2853215 INFO (qtp13594338-25770) [n:127.0.0.1:42613_solr c:utilizenodecoll s:shard2 r:core_node7 x:utilizenodecoll_shard2_replica_n4] o.a.s.c.SolrCore [[utilizenodecoll_shard2_replica_n4] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.TestUtilizeNode_1E2BEEE93086EC41-001/tempDir-001/node2/utilizenodecoll_shard2_replica_n4], dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.TestUtilizeNode_1E2BEEE93086EC41-001/tempDir-001/node2/./utilizenodecoll_shard2_replica_n4/data/] [junit4] 2> 2853276 INFO (qtp3061901-25776) [n:127.0.0.1:33273_solr c:utilizenodecoll s:shard1 r:core_node5 x:utilizenodecoll_shard1_replica_n3] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 2853276 INFO (qtp3061901-25776) [n:127.0.0.1:33273_solr c:utilizenodecoll s:shard1 r:core_node5 x:utilizenodecoll_shard1_replica_n3] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 2853278 INFO (qtp3061901-25776) [n:127.0.0.1:33273_solr c:utilizenodecoll s:shard1 r:core_node5 x:utilizenodecoll_shard1_replica_n3] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 2853278 INFO (qtp3061901-25776) [n:127.0.0.1:33273_solr c:utilizenodecoll s:shard1 r:core_node5 x:utilizenodecoll_shard1_replica_n3] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 2853280 INFO (qtp3061901-25776) [n:127.0.0.1:33273_solr c:utilizenodecoll s:shard1 r:core_node5 x:utilizenodecoll_shard1_replica_n3] o.a.s.s.SolrIndexSearcher Opening [Searcher@127f6d[utilizenodecoll_shard1_replica_n3] main] [junit4] 2> 2853292 INFO (qtp3061901-25776) [n:127.0.0.1:33273_solr c:utilizenodecoll s:shard1 r:core_node5 x:utilizenodecoll_shard1_replica_n3] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1 [junit4] 2> 2853293 INFO (qtp3061901-25776) [n:127.0.0.1:33273_solr c:utilizenodecoll s:shard1 r:core_node5 x:utilizenodecoll_shard1_replica_n3] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 2853294 INFO (qtp3061901-25776) [n:127.0.0.1:33273_solr c:utilizenodecoll s:shard1 r:core_node5 x:utilizenodecoll_shard1_replica_n3] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms. [junit4] 2> 2853295 INFO (searcherExecutor-6201-thread-1-processing-n:127.0.0.1:33273_solr x:utilizenodecoll_shard1_replica_n3 s:shard1 c:utilizenodecoll r:core_node5) [n:127.0.0.1:33273_solr c:utilizenodecoll s:shard1 r:core_node5 x:utilizenodecoll_shard1_replica_n3] o.a.s.c.SolrCore [utilizenodecoll_shard1_replica_n3] Registered new searcher Searcher@127f6d[utilizenodecoll_shard1_replica_n3] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 2853296 INFO (qtp3061901-25776) [n:127.0.0.1:33273_solr c:utilizenodecoll s:shard1 r:core_node5 x:utilizenodecoll_shard1_replica_n3] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1593150861627883520 [junit4] 2> 2853304 INFO (qtp31901758-25766) [n:127.0.0.1:33959_solr c:utilizenodecoll s:shard2 r:core_node8 x:utilizenodecoll_shard2_replica_n6] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 2853304 INFO (qtp31901758-25766) [n:127.0.0.1:33959_solr c:utilizenodecoll s:shard2 r:core_node8 x:utilizenodecoll_shard2_replica_n6] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 2853306 INFO (qtp31901758-25766) [n:127.0.0.1:33959_solr c:utilizenodecoll s:shard2 r:core_node8 x:utilizenodecoll_shard2_replica_n6] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 2853306 INFO (qtp31901758-25766) [n:127.0.0.1:33959_solr c:utilizenodecoll s:shard2 r:core_node8 x:utilizenodecoll_shard2_replica_n6] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 2853308 INFO (qtp31901758-25766) [n:127.0.0.1:33959_solr c:utilizenodecoll s:shard2 r:core_node8 x:utilizenodecoll_shard2_replica_n6] o.a.s.s.SolrIndexSearcher Opening [Searcher@1e5315d[utilizenodecoll_shard2_replica_n6] main] [junit4] 2> 2853309 INFO (qtp31901758-25766) [n:127.0.0.1:33959_solr c:utilizenodecoll s:shard2 r:core_node8 x:utilizenodecoll_shard2_replica_n6] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1 [junit4] 2> 2853310 INFO (qtp31901758-25766) [n:127.0.0.1:33959_solr c:utilizenodecoll s:shard2 r:core_node8 x:utilizenodecoll_shard2_replica_n6] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 2853311 INFO (qtp31901758-25766) [n:127.0.0.1:33959_solr c:utilizenodecoll s:shard2 r:core_node8 x:utilizenodecoll_shard2_replica_n6] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms. [junit4] 2> 2853313 INFO (searcherExecutor-6200-thread-1-processing-n:127.0.0.1:33959_solr x:utilizenodecoll_shard2_replica_n6 s:shard2 c:utilizenodecoll r:core_node8) [n:127.0.0.1:33959_solr c:utilizenodecoll s:shard2 r:core_node8 x:utilizenodecoll_shard2_replica_n6] o.a.s.c.SolrCore [utilizenodecoll_shard2_replica_n6] Registered new searcher Searcher@1e5315d[utilizenodecoll_shard2_replica_n6] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 2853314 INFO (qtp3061901-25776) [n:127.0.0.1:33273_solr c:utilizenodecoll s:shard1 r:core_node5 x:utilizenodecoll_shard1_replica_n3] o.a.s.c.ShardLeaderElectionContext Waiting until we see more replicas up for shard shard1: total=2 found=1 timeoutin=9999ms [junit4] 2> 2853316 INFO (qtp31901758-25766) [n:127.0.0.1:33959_solr c:utilizenodecoll s:shard2 r:core_node8 x:utilizenodecoll_shard2_replica_n6] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1593150861648855040 [junit4] 2> 2853319 INFO (qtp33446152-25790) [n:127.0.0.1:46881_solr c:utilizenodecoll s:shard1 r:core_node2 x:utilizenodecoll_shard1_replica_n1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 2853319 INFO (qtp33446152-25790) [n:127.0.0.1:46881_solr c:utilizenodecoll s:shard1 r:core_node2 x:utilizenodecoll_shard1_replica_n1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 2853320 INFO (qtp13594338-25770) [n:127.0.0.1:42613_solr c:utilizenodecoll s:shard2 r:core_node7 x:utilizenodecoll_shard2_replica_n4] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 2853320 INFO (qtp13594338-25770) [n:127.0.0.1:42613_solr c:utilizenodecoll s:shard2 r:core_node7 x:utilizenodecoll_shard2_replica_n4] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 2853321 INFO (qtp13594338-25770) [n:127.0.0.1:42613_solr c:utilizenodecoll s:shard2 r:core_node7 x:utilizenodecoll_shard2_replica_n4] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 2853321 INFO (qtp33446152-25790) [n:127.0.0.1:46881_solr c:utilizenodecoll s:shard1 r:core_node2 x:utilizenodecoll_shard1_replica_n1] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 2853321 INFO (qtp13594338-25770) [n:127.0.0.1:42613_solr c:utilizenodecoll s:shard2 r:core_node7 x:utilizenodecoll_shard2_replica_n4] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 2853321 INFO (qtp33446152-25790) [n:127.0.0.1:46881_solr c:utilizenodecoll s:shard1 r:core_node2 x:utilizenodecoll_shard1_replica_n1] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 2853324 INFO (qtp13594338-25770) [n:127.0.0.1:42613_solr c:utilizenodecoll s:shard2 r:core_node7 x:utilizenodecoll_shard2_replica_n4] o.a.s.s.SolrIndexSearcher Opening [Searcher@1ecc012[utilizenodecoll_shard2_replica_n4] main] [junit4] 2> 2853324 INFO (qtp33446152-25790) [n:127.0.0.1:46881_solr c:utilizenodecoll s:shard1 r:core_node2 x:utilizenodecoll_shard1_replica_n1] o.a.s.s.SolrIndexSearcher Opening [Searcher@134b54c[utilizenodecoll_shard1_replica_n1] main] [junit4] 2> 2853325 INFO (qtp13594338-25770) [n:127.0.0.1:42613_solr c:utilizenodecoll s:shard2 r:core_node7 x:utilizenodecoll_shard2_replica_n4] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1 [junit4] 2> 2853325 INFO (qtp13594338-25770) [n:127.0.0.1:42613_solr c:utilizenodecoll s:shard2 r:core_node7 x:utilizenodecoll_shard2_replica_n4] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 2853327 INFO (qtp13594338-25770) [n:127.0.0.1:42613_solr c:utilizenodecoll s:shard2 r:core_node7 x:utilizenodecoll_shard2_replica_n4] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms. [junit4] 2> 2853327 INFO (qtp13594338-25770) [n:127.0.0.1:42613_solr c:utilizenodecoll s:shard2 r:core_node7 x:utilizenodecoll_shard2_replica_n4] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1593150861660389376 [junit4] 2> 2853327 INFO (qtp33446152-25790) [n:127.0.0.1:46881_solr c:utilizenodecoll s:shard1 r:core_node2 x:utilizenodecoll_shard1_replica_n1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1 [junit4] 2> 2853330 INFO (searcherExecutor-6203-thread-1-processing-n:127.0.0.1:42613_solr x:utilizenodecoll_shard2_replica_n4 s:shard2 c:utilizenodecoll r:core_node7) [n:127.0.0.1:42613_solr c:utilizenodecoll s:shard2 r:core_node7 x:utilizenodecoll_shard2_replica_n4] o.a.s.c.SolrCore [utilizenodecoll_shard2_replica_n4] Registered new searcher Searcher@1ecc012[utilizenodecoll_shard2_replica_n4] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 2853330 INFO (qtp31901758-25766) [n:127.0.0.1:33959_solr c:utilizenodecoll s:shard2 r:core_node8 x:utilizenodecoll_shard2_replica_n6] o.a.s.c.ShardLeaderElectionContext Waiting until we see more replicas up for shard shard2: total=2 found=1 timeoutin=9999ms [junit4] 2> 2853330 INFO (qtp33446152-25790) [n:127.0.0.1:46881_solr c:utilizenodecoll s:shard1 r:core_node2 x:utilizenodecoll_shard1_replica_n1] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 2853334 INFO (qtp33446152-25790) [n:127.0.0.1:46881_solr c:utilizenodecoll s:shard1 r:core_node2 x:utilizenodecoll_shard1_replica_n1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms. [junit4] 2> 2853335 INFO (searcherExecutor-6202-thread-1-processing-n:127.0.0.1:46881_solr x:utilizenodecoll_shard1_replica_n1 s:shard1 c:utilizenodecoll r:core_node2) [n:127.0.0.1:46881_solr c:utilizenodecoll s:shard1 r:core_node2 x:utilizenodecoll_shard1_replica_n1] o.a.s.c.SolrCore [utilizenodecoll_shard1_replica_n1] Registered new searcher Searcher@134b54c[utilizenodecoll_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 2853335 INFO (qtp33446152-25790) [n:127.0.0.1:46881_solr c:utilizenodecoll s:shard1 r:core_node2 x:utilizenodecoll_shard1_replica_n1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1593150861668777984 [junit4] 2> 2853431 INFO (zkCallback-6928-thread-2-processing-n:127.0.0.1:42613_solr) [n:127.0.0.1:42613_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/utilizenodecoll/state.json] for collection [utilizenodecoll] has occurred - updating... (live nodes size: [4]) [junit4] 2> 2853431 INFO (zkCallback-6928-thread-1-processing-n:127.0.0.1:42613_solr) [n:127.0.0.1:42613_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/utilizenodecoll/state.json] for collection [utilizenodecoll] has occurred - updating... (live nodes size: [4]) [junit4] 2> 2853431 INFO (zkCallback-6919-thread-2-processing-n:127.0.0.1:33959_solr) [n:127.0.0.1:33959_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/utilizenodecoll/state.json] for collection [utilizenodecoll] has occurred - updating... (live nodes size: [4]) [junit4] 2> 2853432 INFO (zkCallback-6919-thread-1-processing-n:127.0.0.1:33959_solr) [n:127.0.0.1:33959_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/utilizenodecoll/state.json] for collection [utilizenodecoll] has occurred - updating... (live nodes size: [4]) [junit4] 2> 2853432 INFO (zkCallback-6927-thread-2-processing-n:127.0.0.1:33273_solr) [n:127.0.0.1:33273_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/utilizenodecoll/state.json] for collection [utilizenodecoll] has occurred - updating... (live nodes size: [4]) [junit4] 2> 2853432 INFO (zkCallback-6927-thread-1-processing-n:127.0.0.1:33273_solr) [n:127.0.0.1:33273_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/utilizenodecoll/state.json] for collection [utilizenodecoll] has occurred - updating... (live nodes size: [4]) [junit4] 2> 2853432 INFO (zkCallback-6925-thread-3-processing-n:127.0.0.1:46881_solr) [n:127.0.0.1:46881_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/utilizenodecoll/state.json] for collection [utilizenodecoll] has occurred - updating... (live nodes size: [4]) [junit4] 2> 2853432 INFO (zkCallback-6925-thread-2-processing-n:127.0.0.1:46881_solr) [n:127.0.0.1:46881_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/utilizenodecoll/state.json] for collection [utilizenodecoll] has occurred - updating... (live nodes size: [4]) [junit4] 2> 2853814 INFO (qtp3061901-25776) [n:127.0.0.1:33273_solr c:utilizenodecoll s:shard1 r:core_node5 x:utilizenodecoll_shard1_replica_n3] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue. [junit4] 2> 2853814 INFO (qtp3061901-25776) [n:127.0.0.1:33273_solr c:utilizenodecoll s:shard1 r:core_node5 x:utilizenodecoll_shard1_replica_n3] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync [junit4] 2> 2853814 INFO (qtp3061901-25776) [n:127.0.0.1:33273_solr c:utilizenodecoll s:shard1 r:core_node5 x:utilizenodecoll_shard1_replica_n3] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:33273/solr/utilizenodecoll_shard1_replica_n3/ [junit4] 2> 2853815 INFO (qtp3061901-25776) [n:127.0.0.1:33273_solr c:utilizenodecoll s:shard1 r:core_node5 x:utilizenodecoll_shard1_replica_n3] o.a.s.u.PeerSync PeerSync: core=utilizenodecoll_shard1_replica_n3 url=http://127.0.0.1:33273/solr START replicas=[http://127.0.0.1:46881/solr/utilizenodecoll_shard1_replica_n1/] nUpdates=100 [junit4] 2> 2853821 INFO (qtp33446152-25782) [n:127.0.0.1:46881_solr c:utilizenodecoll s:shard1 r:core_node2 x:utilizenodecoll_shard1_replica_n1] o.a.s.c.S.Request [utilizenodecoll_shard1_replica_n1] webapp=/solr path=/get params={distrib=false&qt=/get&fingerprint=false&getVersions=100&wt=javabin&version=2} status=0 QTime=0 [junit4] 2> 2853830 INFO (qtp31901758-25766) [n:127.0.0.1:33959_solr c:utilizenodecoll s:shard2 r:core_node8 x:utilizenodecoll_shard2_replica_n6] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue. [junit4] 2> 2853830 INFO (qtp31901758-25766) [n:127.0.0.1:33959_solr c:utilizenodecoll s:shard2 r:core_node8 x:utilizenodecoll_shard2_replica_n6] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync [junit4] 2> 2853831 INFO (qtp31901758-25766) [n:127.0.0.1:33959_solr c:utilizenodecoll s:shard2 r:core_node8 x:utilizenodecoll_shard2_replica_n6] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:33959/solr/utilizenodecoll_shard2_replica_n6/ [junit4] 2> 2853831 INFO (qtp31901758-25766) [n:127.0.0.1:33959_solr c:utilizenodecoll s:shard2 r:core_node8 x:utilizenodecoll_shard2_replica_n6] o.a.s.u.PeerSync PeerSync: core=utilizenodecoll_shard2_replica_n6 url=http://127.0.0.1:33959/solr START replicas=[http://127.0.0.1:42613/solr/utilizenodecoll_shard2_replica_n4/] nUpdates=100 [junit4] 2> 2853835 INFO (qtp13594338-25914) [n:127.0.0.1:42613_solr c:utilizenodecoll s:shard2 r:core_node7 x:utilizenodecoll_shard2_replica_n4] o.a.s.c.S.Request [utilizenodecoll_shard2_replica_n4] webapp=/solr path=/get params={distrib=false&qt=/get&fingerprint=false&getVersions=100&wt=javabin&version=2} status=0 QTime=0 [junit4] 2> 2854119 INFO (qtp3061901-25776) [n:127.0.0.1:33273_solr c:utilizenodecoll s:shard1 r:core_node5 x:utilizenodecoll_shard1_replica_n3] o.a.s.u.PeerSync PeerSync: core=utilizenodecoll_shard1_replica_n3 url=http://127.0.0.1:33273/solr DONE. We have no versions. sync failed. [junit4] 2> 2854119 INFO (qtp3061901-25776) [n:127.0.0.1:33273_solr c:utilizenodecoll s:shard1 r:core_node5 x:utilizenodecoll_shard1_replica_n3] o.a.s.c.SyncStrategy Leader's attempt to sync with shard failed, moving to the next candidate [junit4] 2> 2854119 INFO (qtp3061901-25776) [n:127.0.0.1:33273_solr c:utilizenodecoll s:shard1 r:core_node5 x:utilizenodecoll_shard1_replica_n3] o.a.s.c.ShardLeaderElectionContext We failed sync, but we have no versions - we can't sync in that case - we were active before, so become leader anyway [junit4] 2> 2854119 INFO (qtp3061901-25776) [n:127.0.0.1:33273_solr c:utilizenodecoll s:shard1 r:core_node5 x:utilizenodecoll_shard1_replica_n3] o.a.s.c.ShardLeaderElectionContext Found all replicas participating in election, clear LIR [junit4] 2> 2854123 INFO (qtp3061901-25776) [n:127.0.0.1:33273_solr c:utilizenodecoll s:shard1 r:core_node5 x:utilizenodecoll_shard1_replica_n3] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:33273/solr/utilizenodecoll_shard1_replica_n3/ shard1 [junit4] 2> 2854134 INFO (qtp31901758-25766) [n:127.0.0.1:33959_solr c:utilizenodecoll s:shard2 r:core_node8 x:utilizenodecoll_shard2_replica_n6] o.a.s.u.PeerSync PeerSync: core=utilizenodecoll_shard2_replica_n6 url=http://127.0.0.1:33959/solr DONE. We have no versions. sync failed. [junit4] 2> 2854134 INFO (qtp31901758-25766) [n:127.0.0.1:33959_solr c:utilizenodecoll s:shard2 r:core_node8 x:utilizenodecoll_shard2_replica_n6] o.a.s.c.SyncStrategy Leader's attempt to sync with shard failed, moving to the next candidate [junit4] 2> 2854134 INFO (qtp31901758-25766) [n:127.0.0.1:33959_solr c:utilizenodecoll s:shard2 r:core_node8 x:utilizenodecoll_shard2_replica_n6] o.a.s.c.ShardLeaderElectionContext We failed sync, but we have no versions - we can't sync in that case - we were active before, so become leader anyway [junit4] 2> 2854134 INFO (qtp31901758-25766) [n:127.0.0.1:33959_solr c:utilizenodecoll s:shard2 r:core_node8 x:utilizenodecoll_shard2_replica_n6] o.a.s.c.ShardLeaderElectionContext Found all replicas participating in election, clear LIR [junit4] 2> 2854138 INFO (qtp31901758-25766) [n:127.0.0.1:33959_solr c:utilizenodecoll s:shard2 r:core_node8 x:utilizenodecoll_shard2_replica_n6] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:33959/solr/utilizenodecoll_shard2_replica_n6/ shard2 [junit4] 2> 2854239 INFO (zkCallback-6919-thread-2-processing-n:127.0.0.1:33959_solr) [n:127.0.0.1:33959_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/utilizenodecoll/state.json] for collection [utilizenodecoll] has occurred - updating... (live nodes size: [4]) [junit4] 2> 2854239 INFO (zkCallback-6925-thread-2-processing-n:127.0.0.1:46881_solr) [n:127.0.0.1:46881_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/utilizenodecoll/state.json] for collection [utilizenodecoll] has occurred - updating... (live nodes size: [4]) [junit4] 2> 2854239 INFO (zkCallback-6919-thread-1-processing-n:127.0.0.1:33959_solr) [n:127.0.0.1:33959_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/utilizenodecoll/state.json] for collection [utilizenodecoll] has occurred - updating... (live nodes size: [4]) [junit4] 2> 2854239 INFO (zkCallback-6927-thread-2-processing-n:127.0.0.1:33273_solr) [n:127.0.0.1:33273_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/utilizenodecoll/state.json] for collection [utilizenodecoll] has occurred - updating... (live nodes size: [4]) [junit4] 2> 2854239 INFO (zkCallback-6928-thread-2-processing-n:127.0.0.1:42613_solr) [n:127.0.0.1:42613_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/utilizenodecoll/state.json] for collection [utilizenodecoll] has occurred - updating... (live nodes size: [4]) [junit4] 2> 2854239 INFO (zkCallback-6927-thread-1-processing-n:127.0.0.1:33273_solr) [n:127.0.0.1:33273_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/utilizenodecoll/state.json] for collection [utilizenodecoll] has occurred - updating... (live nodes size: [4]) [junit4] 2> 2854239 INFO (zkCallback-6928-thread-1-processing-n:127.0.0.1:42613_solr) [n:127.0.0.1:42613_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/utilizenodecoll/state.json] for collection [utilizenodecoll] has occurred - updating... (live nodes size: [4]) [junit4] 2> 2854239 INFO (zkCallback-6925-thread-3-processing-n:127.0.0.1:46881_solr) [n:127.0.0.1:46881_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/utilizenodecoll/state.json] for collection [utilizenodecoll] has occurred - updating... (live nodes size: [4]) [junit4] 2> 2854273 INFO (qtp3061901-25776) [n:127.0.0.1:33273_solr c:utilizenodecoll s:shard1 r:core_node5 x:utilizenodecoll_shard1_replica_n3] o.a.s.c.ZkController I am the leader, no recovery necessary [junit4] 2> 2854289 INFO (qtp31901758-25766) [n:127.0.0.1:33959_solr c:utilizenodecoll s:shard2 r:core_node8 x:utilizenodecoll_shard2_replica_n6] o.a.s.c.ZkController I am the leader, no recovery necessary [junit4] 2> 2854444 INFO (zkCallback-6919-thread-1-processing-n:127.0.0.1:33959_solr) [n:127.0.0.1:33959_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/utilizenodecoll/state.json] for collection [utilizenodecoll] has occurred - updating... (live nodes size: [4]) [junit4] 2> 2854444 INFO (zkCallback-6928-thread-1-processing-n:127.0.0.1:42613_solr) [n:127.0.0.1:42613_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/utilizenodecoll/state.json] for collection [utilizenodecoll] has occurred - updating... (live nodes size: [4]) [junit4] 2> 2854444 INFO (zkCallback-6919-thread-2-processing-n:127.0.0.1:33959_solr) [n:127.0.0.1:33959_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/utilizenodecoll/state.json] for collection [utilizenodecoll] has occurred - updating... (live nodes size: [4]) [junit4] 2> 2854444 INFO (zkCallback-6927-thread-1-processing-n:127.0.0.1:33273_solr) [n:127.0.0.1:33273_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/utilizenodecoll/state.json] for collection [utilizenodecoll] has occurred - updating... (live nodes size: [4]) [junit4] 2> 2854445 INFO (zkCallback-6927-thread-2-processing-n:127.0.0.1:33273_solr) [n:127.0.0.1:33273_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/utilizenodecoll/state.json] for collection [utilizenodecoll] has occurred - updating... (live nodes size: [4]) [junit4] 2> 2854444 INFO (zkCallback-6928-thread-2-processing-n:127.0.0.1:42613_solr) [n:127.0.0.1:42613_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/utilizenodecoll/state.json] for collection [utilizenodecoll] has occurred - updating... (live nodes size: [4]) [junit4] 2> 2854445 INFO (zkCallback-6925-thread-3-processing-n:127.0.0.1:46881_solr) [n:127.0.0.1:46881_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/utilizenodecoll/state.json] for collection [utilizenodecoll] has occurred - updating... (live nodes size: [4]) [junit4] 2> 2854445 INFO (zkCallback-6925-thread-2-processing-n:127.0.0.1:46881_solr) [n:127.0.0.1:46881_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/utilizenodecoll/state.json] for collection [utilizenodecoll] has occurred - updating... (live nodes size: [4]) [junit4] 2> 2854721 INFO (qtp31901758-25766) [n:127.0.0.1:33959_solr c:utilizenodecoll s:shard2 r:core_node8 x:utilizenodecoll_shard2_replica_n6] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node8&collection.configName=conf1&newCollection=true&name=utilizenodecoll_shard2_replica_n6&action=CREATE&numShards=2&collection=utilizenodecoll&shard=shard2&wt=javabin&version=2&replicaType=NRT} status=0 QTime=2633 [junit4] 2> 2854878 INFO (qtp33446152-25790) [n:127.0.0.1:46881_solr c:utilizenodecoll s:shard1 r:core_node2 x:utilizenodecoll_shard1_replica_n1] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node2&collection.configName=conf1&newCollection=true&name=utilizenodecoll_shard1_replica_n1&action=CREATE&numShards=2&collection=utilizenodecoll&shard=shard1&wt=javabin&version=2&replicaType=NRT} status=0 QTime=2814 [junit4] 2> 2854982 INFO (qtp3061901-25776) [n:127.0.0.1:33273_solr c:utilizenodecoll s:shard1 r:core_node5 x:utilizenodecoll_shard1_replica_n3] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node5&collection.configName=conf1&newCollection=true&name=utilizenodecoll_shard1_replica_n3&action=CREATE&numShards=2&collection=utilizenodecoll&shard=shard1&wt=javabin&version=2&replicaType=NRT} status=0 QTime=2893 [junit4] 2> 2854987 INFO (qtp13594338-25770) [n:127.0.0.1:42613_solr c:utilizenodecoll s:shard2 r:core_node7 x:utilizenodecoll_shard2_replica_n4] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node7&collection.configName=conf1&newCollection=true&name=utilizenodecoll_shard2_replica_n4&action=CREATE&numShards=2&collection=utilizenodecoll&shard=shard2&wt=javabin&version=2&replicaType=NRT} status=0 QTime=2905 [junit4] 2> 2854992 INFO (qtp33446152-25786) [n:127.0.0.1:46881_solr ] o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most 30 seconds. Check all shard replicas [junit4] 2> 2854993 INFO (qtp33446152-25786) [n:127.0.0.1:46881_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={replicationFactor=2&collection.configName=conf1&name=utilizenodecoll&nrtReplicas=2&action=CREATE&numShards=2&wt=javabin&version=2} status=0 QTime=3255 [junit4] 2> 2854994 INFO (TEST-TestUtilizeNode.test-seed#[1E2BEEE93086EC41]) [ ] o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: 2017-11-21T12:27:37-09:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8 [junit4] 2> 2855015 INFO (TEST-TestUtilizeNode.test-seed#[1E2BEEE93086EC41]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0 [junit4] 2> 2855015 INFO (TEST-TestUtilizeNode.test-seed#[1E2BEEE93086EC41]) [ ] o.e.j.s.session No SessionScavenger set, using defaults [junit4] 2> 2855015 INFO (TEST-TestUtilizeNode.test-seed#[1E2BEEE93086EC41]) [ ] o.e.j.s.session Scavenging every 660000ms [junit4] 2> 2855020 INFO (TEST-TestUtilizeNode.test-seed#[1E2BEEE93086EC41]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@84be9d{/solr,null,AVAILABLE} [junit4] 2> 2855020 INFO (TEST-TestUtilizeNode.test-seed#[1E2BEEE93086EC41]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@155a25f{HTTP/1.1,[http/1.1]}{127.0.0.1:43289} [junit4] 2> 2855020 INFO (TEST-TestUtilizeNode.test-seed#[1E2BEEE93086EC41]) [ ] o.e.j.s.Server Started @2856746ms [junit4] 2> 2855020 INFO (TEST-TestUtilizeNode.test-seed#[1E2BEEE93086EC41]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=43289} [junit4] 2> 2855021 ERROR (TEST-TestUtilizeNode.test-seed#[1E2BEEE93086EC41]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 2855022 INFO (TEST-TestUtilizeNode.test-seed#[1E2BEEE93086EC41]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.0.0 [junit4] 2> 2855022 INFO (TEST-TestUtilizeNode.test-seed#[1E2BEEE93086EC41]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 2855022 INFO (TEST-TestUtilizeNode.test-seed#[1E2BEEE93086EC41]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 2855022 INFO (TEST-TestUtilizeNode.test-seed#[1E2BEEE93086EC41]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-02-23T00:51:00.621Z [junit4] 2> 2855031 INFO (zkConnectionManagerCallback-6949-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 2855032 INFO (TEST-TestUtilizeNode.test-seed#[1E2BEEE93086EC41]) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 2855052 INFO (TEST-TestUtilizeNode.test-seed#[1E2BEEE93086EC41]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:32839/solr [junit4] 2> 2855067 INFO (zkConnectionManagerCallback-6953-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 2855079 INFO (zkConnectionManagerCallback-6955-thread-1-processing-n:127.0.0.1:43289_solr) [n:127.0.0.1:43289_solr ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 2855084 INFO (TEST-TestUtilizeNode.test-seed#[1E2BEEE93086EC41]) [n:127.0.0.1:43289_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4) [junit4] 2> 2855086 INFO (TEST-TestUtilizeNode.test-seed#[1E2BEEE93086EC41]) [n:127.0.0.1:43289_solr ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 2855088 INFO (TEST-TestUtilizeNode.test-seed#[1E2BEEE93086EC41]) [n:127.0.0.1:43289_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:43289_solr [junit4] 2> 2855089 INFO (zkCallback-6928-thread-2-processing-n:127.0.0.1:42613_solr) [n:127.0.0.1:42613_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5) [junit4] 2> 2855089 INFO (zkCallback-6927-thread-1-processing-n:127.0.0.1:33273_solr) [n:127.0.0.1:33273_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5) [junit4] 2> 2855089 INFO (zkCallback-6943-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5) [junit4] 2> 2855089 INFO (zkCallback-6943-thread-2) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5) [junit4] 2> 2855089 INFO (zkCallback-6925-thread-2-processing-n:127.0.0.1:46881_solr) [n:127.0.0.1:46881_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5) [junit4] 2> 2855095 INFO (zkCallback-6919-thread-1-processing-n:127.0.0.1:33959_solr) [n:127.0.0.1:33959_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5) [junit4] 2> 2855099 INFO (zkCallback-6954-thread-1-processing-n:127.0.0.1:43289_solr) [n:127.0.0.1:43289_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5) [junit4] 2> 2855325 INFO (TEST-TestUtilizeNode.test-seed#[1E2BEEE93086EC41]) [n:127.0.0.1:43289_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_43289.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@9f1307 [junit4] 2> 2855340 INFO (TEST-TestUtilizeNode.test-seed#[1E2BEEE93086EC41]) [n:127.0.0.1:43289_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_43289.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@9f1307 [junit4] 2> 2855341 INFO (TEST-TestUtilizeNode.test-seed#[1E2BEEE93086EC41]) [n:127.0.0.1:43289_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_43289.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@9f1307 [junit4] 2> 2855342 INFO (TEST-TestUtilizeNode.test-seed#[1E2BEEE93086EC41]) [n:127.0.0.1:43289_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.TestUtilizeNode_1E2BEEE93086EC41-001/tempDir-001/node5/. [junit4] 2> 2855446 INFO (zkConnectionManagerCallback-6959-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 2855449 INFO (qtp3061901-25795) [n:127.0.0.1:33273_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :utilizenode with params node=127.0.0.1:43289_solr&action=UTILIZENODE&wt=javabin&version=2 and sendToOCPQueue=true [junit4] 2> 2855451 INFO (OverseerCollectionConfigSetProcessor-72195889203773450-127.0.0.1:46881_solr-n_0000000000) [n:127.0.0.1:46881_solr ] o.a.s.c.OverseerTaskQueue Response ZK path: /overseer/collection-queue-work/qnr-0000000002 doesn't exist. Requestor may have disconnected from ZooKeeper [junit4] 2> 2855455 INFO (qtp3061901-25776) [n:127.0.0.1:33273_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={wt=javabin&version=2&key=solr.core.utilizenodecoll.shard1.replica_n3:INDEX.sizeInBytes} status=0 QTime=1 [junit4] 2> 2855459 INFO (qtp3061901-25778) [n:127.0.0.1:33273_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=1 [junit4] 2> 2855465 INFO (qtp31901758-25796) [n:127.0.0.1:33959_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={wt=javabin&version=2&key=solr.core.utilizenodecoll.shard2.replica_n6:INDEX.sizeInBytes} status=0 QTime=0 [junit4] 2> 2855468 INFO (qtp31901758-25767) [n:127.0.0.1:33959_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=1 [junit4] 2> 2855471 INFO (qtp13594338-25913) [n:127.0.0.1:42613_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={wt=javabin&version=2&key=solr.core.utilizenodecoll.shard2.replica_n4:INDEX.sizeInBytes} status=0 QTime=0 [junit4] 2> 2855474 INFO (qtp13594338-25793) [n:127.0.0.1:42613_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=1 [junit4] 2> 2855488 INFO (qtp5652598-25918) [n:127.0.0.1:43289_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0 [junit4] 2> 2855491 INFO (qtp33446152-25786) [n:127.0.0.1:46881_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={wt=javabin&version=2&key=solr.core.utilizenodecoll.shard1.replica_n1:INDEX.sizeInBytes} status=0 QTime=0 [junit4] 2> 2855493 INFO (qtp33446152-25790) [n:127.0.0.1:46881_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0 [junit4] 2> 2855498 INFO (qtp3061901-25776) [n:127.0.0.1:33273_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={wt=javabin&version=2&key=solr.core.utilizenodecoll.shard1.replica_n3:INDEX.sizeInBytes} status=0 QTime=0 [junit4] 2> 2855500 INFO (qtp3061901-25778) [n:127.0.0.1:33273_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0 [junit4] 2> 2855502 INFO (qtp31901758-25796) [n:127.0.0.1:33959_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={wt=javabin&version=2&key=solr.core.utilizenodecoll.shard2.replica_n6:INDEX.sizeInBytes} status=0 QTime=0 [junit4] 2> 2855504 INFO (qtp31901758-25767) [n:127.0.0.1:33959_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=1 [junit4] 2> 2855506 INFO (qtp13594338-25913) [n:127.0.0.1:42613_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/met [...truncated too long message...] solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.HdfsAutoAddReplicasIntegrationTest_1E2BEEE93086EC41-001/tempDir-002/hdfsBaseDir/data/data3/, [DISK]file:/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.HdfsAutoAddReplicasIntegrationTest_1E2BEEE93086EC41-001/tempDir-002/hdfsBaseDir/data/data4/]] heartbeating to localhost.localdomain/127.0.0.1:42189) [ ] o.a.h.h.s.d.DataNode Ending block pool service for: Block pool BP-792550639-88.99.242.108-1519347185245 (Datanode Uuid 3b4cd5da-b017-4869-9792-aa31886c062e) service to localhost.localdomain/127.0.0.1:42189 [junit4] 2> 3146817 WARN (SUITE-HdfsAutoAddReplicasIntegrationTest-seed#[1E2BEEE93086EC41]-worker) [ ] o.a.h.h.s.d.DirectoryScanner DirectoryScanner: shutdown has been called [junit4] 2> 3146828 INFO (SUITE-HdfsAutoAddReplicasIntegrationTest-seed#[1E2BEEE93086EC41]-worker) [ ] o.m.log Stopped HttpServer2$SelectChannelConnectorWithSafeStartup@localhost:0 [junit4] 2> 3146928 WARN (DataNode: [[[DISK]file:/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.HdfsAutoAddReplicasIntegrationTest_1E2BEEE93086EC41-001/tempDir-002/hdfsBaseDir/data/data1/, [DISK]file:/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.HdfsAutoAddReplicasIntegrationTest_1E2BEEE93086EC41-001/tempDir-002/hdfsBaseDir/data/data2/]] heartbeating to localhost.localdomain/127.0.0.1:42189) [ ] o.a.h.h.s.d.IncrementalBlockReportManager IncrementalBlockReportManager interrupted [junit4] 2> 3146929 WARN (DataNode: [[[DISK]file:/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.HdfsAutoAddReplicasIntegrationTest_1E2BEEE93086EC41-001/tempDir-002/hdfsBaseDir/data/data1/, [DISK]file:/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.HdfsAutoAddReplicasIntegrationTest_1E2BEEE93086EC41-001/tempDir-002/hdfsBaseDir/data/data2/]] heartbeating to localhost.localdomain/127.0.0.1:42189) [ ] o.a.h.h.s.d.DataNode Ending block pool service for: Block pool BP-792550639-88.99.242.108-1519347185245 (Datanode Uuid 6b057b4e-82d3-40e6-b050-10ddc5b9f706) service to localhost.localdomain/127.0.0.1:42189 [junit4] 2> 3146938 INFO (SUITE-HdfsAutoAddReplicasIntegrationTest-seed#[1E2BEEE93086EC41]-worker) [ ] o.m.log Stopped HttpServer2$SelectChannelConnectorWithSafeStartup@localhost.localdomain:0 [junit4] 2> 3147066 INFO (SUITE-HdfsAutoAddReplicasIntegrationTest-seed#[1E2BEEE93086EC41]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:41991 41991 [junit4] 2> NOTE: leaving temporary files on disk at: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.HdfsAutoAddReplicasIntegrationTest_1E2BEEE93086EC41-001 [junit4] 2> Feb 23, 2018 12:55:52 AM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks [junit4] 2> WARNING: Will linger awaiting termination of 66 leaked thread(s). [junit4] 2> NOTE: test params are: codec=Asserting(Lucene70): {}, docValues:{}, maxPointsInLeafNode=1125, maxMBSortInHeap=6.04642815614842, sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@e87671), locale=fr-CH, timezone=America/Cancun [junit4] 2> NOTE: Linux 4.13.0-32-generic i386/Oracle Corporation 1.8.0_162 (32-bit)/cpus=8,threads=2,free=236827816,total=521404416 [junit4] 2> NOTE: All tests run in this JVM: [SuggesterFSTTest, BadComponentTest, SolrCoreMetricManagerTest, TestTlogReplica, TestSolrCoreParser, CheckHdfsIndexTest, TestCharFilters, HttpPartitionOnCommitTest, HdfsSyncSliceTest, DOMUtilTest, TestFunctionQuery, FastVectorHighlighterTest, AlternateDirectoryTest, TestDefaultStatsCache, TestQueryUtils, CSVRequestHandlerTest, NumberUtilsTest, SyncSliceTest, AsyncCallRequestStatusResponseTest, TestSystemIdResolver, PreAnalyzedFieldManagedSchemaCloudTest, TestStreamBody, AssignTest, FileUtilsTest, TestCustomDocTransformer, TestCloudSchemaless, SuggestComponentTest, TestBackupRepositoryFactory, TestShortCircuitedRequests, AddReplicaTest, TestRandomFlRTGCloud, TestXIncludeConfig, TestFoldingMultitermQuery, PathHierarchyTokenizerFactoryTest, ResponseBuilderTest, FullHLLTest, TestSolrJ, ShardRoutingCustomTest, PrimitiveFieldTypeTest, JsonLoaderTest, ImplicitSnitchTest, SubstringBytesRefFilterTest, CustomHighlightComponentTest, ConfigSetsAPITest, TestPostingsSolrHighlighter, DeleteStatusTest, SignatureUpdateProcessorFactoryTest, TestCopyFieldCollectionResource, TestFieldTypeResource, ZkControllerTest, ParsingFieldUpdateProcessorsTest, TestAuthenticationFramework, SolrJmxReporterCloudTest, TestLegacyNumericUtils, TestStressUserVersions, ReplicationFactorTest, ConjunctionSolrSpellCheckerTest, TestSolr4Spatial, TestNRTOpen, AutoScalingHandlerTest, ResourceLoaderTest, FieldMutatingUpdateProcessorTest, FacetPivotSmallTest, TestGraphTermsQParserPlugin, TestJsonFacets, BigEndianAscendingWordSerializerTest, OverseerModifyCollectionTest, CustomCollectionTest, BasicFunctionalityTest, TestRecovery, TestSimpleTrackingShardHandler, TestExactStatsCache, PeerSyncTest, TestSQLHandler, TestPerFieldSimilarity, XsltUpdateRequestHandlerTest, SimpleMLTQParserTest, TestRequestStatusCollectionAPI, TestXmlQParserPlugin, TestDistributedGrouping, TestSearchPerf, CdcrReplicationHandlerTest, RankQueryTest, AnalyticsMergeStrategyTest, AddSchemaFieldsUpdateProcessorFactoryTest, BlockJoinFacetRandomTest, TestManagedSchema, DocValuesNotIndexedTest, TestManagedResourceStorage, LeaderElectionContextKeyTest, TestZkAclsWithHadoopAuth, ClassificationUpdateProcessorFactoryTest, TestReplicationHandlerBackup, TestUniqueKeyFieldResource, TestDocTermOrds, TestConfigSetsAPI, QueryElevationComponentTest, CloneFieldUpdateProcessorFactoryTest, TestImplicitCoreProperties, HdfsRecoverLeaseTest, TestCollapseQParserPlugin, TestCloudRecovery, SolrInfoBeanTest, PrimUtilsTest, TestRecoveryHdfs, TestSimpleQParserPlugin, TestLRUCache, TestCustomSort, MoveReplicaTest, TestClusterStateMutator, HDFSCollectionsAPITest, JavabinLoaderTest, VMParamsZkACLAndCredentialsProvidersTest, TestBM25SimilarityFactory, SolrCoreTest, TestFastLRUCache, TestElisionMultitermQuery, TestSlowCompositeReaderWrapper, DeleteNodeTest, DistribJoinFromCollectionTest, TestFuzzyAnalyzedSuggestions, TestRawResponseWriter, OverseerTest, TestFieldCollectionResource, TestScoreJoinQPScore, MissingSegmentRecoveryTest, TermsComponentTest, TestHttpShardHandlerFactory, DirectUpdateHandlerOptimizeTest, ChaosMonkeySafeLeaderTest, BasicDistributedZk2Test, BasicZkTest, TestReplicationHandler, TestRandomFaceting, ZkSolrClientTest, TestZkChroot, TestRandomDVFaceting, TestDistributedSearch, TestRangeQuery, TestGroupingSearch, TestLazyCores, DirectUpdateHandlerTest, TestIndexSearcher, SoftAutoCommitTest, TestSolrDeletionPolicy2, SampleTest, TestBinaryField, MinimalSchemaTest, TestConfig, TestSolrCoreProperties, DirectSolrConnectionTest, NotRequiredUniqueKeyTest, TestLuceneMatchVersion, SynonymTokenizerTest, TestSweetSpotSimilarityFactory, TestLMDirichletSimilarityFactory, TestIBSimilarityFactory, TimeZoneUtilsTest, ScriptEngineTest, URLClassifyProcessorTest, TestSuggestSpellingConverter, SpellingQueryConverterTest, TestUtils, ZkNodePropsTest, SliceStateTest, SystemInfoHandlerTest, UUIDFieldTest, CircularListTest, DistributedMLTComponentTest, TestRTGBase, DistributedIntervalFacetingTest, SolrTestCaseJ4Test, TestCrossCoreJoin, TestCursorMarkWithoutUniqueKey, TestHighlightDedupGrouping, TestLuceneIndexBackCompat, TestEmbeddedSolrServerAdminHandler, TestEmbeddedSolrServerConstructors, TestEmbeddedSolrServerSchemaAPI, TestJettySolrRunner, ConnectionReuseTest, ActionThrottleTest, AliasIntegrationTest, CreateCollectionCleanupTest, CreateRoutedAliasTest, LeaderInitiatedRecoveryOnShardRestartTest, MigrateRouteKeyTest, PeerSyncReplicationTest, ReplaceNodeNoTargetTest, TestCloudDeleteByQuery, TestClusterProperties, TestConfigSetsAPIZkFailure, TestDeleteCollectionOnDownNodes, TestDistribDocBasedVersion, TestLeaderElectionWithEmptyReplica, TestRebalanceLeaders, TestSolrCloudWithDelegationTokens, TestSolrCloudWithKerberosAlt, TestStressInPlaceUpdates, TestCollectionAPI, TestLocalFSCloudBackupRestore, TestReplicaProperties, HdfsAutoAddReplicasIntegrationTest] [junit4] Completed [587/778 (2!)] on J1 in 177.86s, 1 test, 1 failure <<< FAILURES! [...truncated 46372 lines...] [repro] Jenkins log URL: file:///tmp/tmp.tLXqf1iNNP [repro] Revision: edd54e551d5ae9cd675482bab042b0cce8fb02e2 [repro] Repro line: ant test -Dtestcase=TestUtilizeNode -Dtests.method=test -Dtests.seed=1E2BEEE93086EC41 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=sk-SK -Dtests.timezone=America/Glace_Bay -Dtests.asserts=true -Dtests.file.encoding=US-ASCII [repro] Repro line: ant test -Dtestcase=HdfsAutoAddReplicasIntegrationTest -Dtests.method=testSimple -Dtests.seed=1E2BEEE93086EC41 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=fr-CH -Dtests.timezone=America/Cancun -Dtests.asserts=true -Dtests.file.encoding=US-ASCII [repro] ant clean [...truncated 6 lines...] [repro] Test suites by module: [repro] solr/core [repro] HdfsAutoAddReplicasIntegrationTest [repro] TestUtilizeNode [repro] ant compile-test [...truncated 3292 lines...] [repro] ant test-nocompile -Dtests.dups=5 -Dtests.maxfailures=10 -Dtests.class="*.HdfsAutoAddReplicasIntegrationTest|*.TestUtilizeNode" -Dtests.showOutput=onerror -Dtests.seed=1E2BEEE93086EC41 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=fr-CH -Dtests.timezone=America/Cancun -Dtests.asserts=true -Dtests.file.encoding=US-ASCII [...truncated 97 lines...] [repro] Failures: [repro] 0/5 failed: org.apache.solr.cloud.TestUtilizeNode [repro] 0/5 failed: org.apache.solr.cloud.autoscaling.HdfsAutoAddReplicasIntegrationTest [repro] Exiting with code 0 [...truncated 41 lines...]
--------------------------------------------------------------------- To unsubscribe, e-mail: dev-unsubscr...@lucene.apache.org For additional commands, e-mail: dev-h...@lucene.apache.org