Build: https://builds.apache.org/job/Lucene-Solr-Tests-7.7/34/
3 tests failed. FAILED: org.apache.solr.cloud.api.collections.ShardSplitTest.testSplitShardWithRule Error Message: Error from server at http://127.0.0.1:38633/f_o: Could not find collection : shardSplitWithRule_rewrite Stack Trace: org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at http://127.0.0.1:38633/f_o: Could not find collection : shardSplitWithRule_rewrite at __randomizedtesting.SeedInfo.seed([E36EE48FAF16F228:6260353D32E4A903]:0) at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:643) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:255) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:244) at org.apache.solr.client.solrj.impl.LBHttpSolrClient.doRequest(LBHttpSolrClient.java:484) at org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:414) at org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:1110) at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:884) at org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:817) at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:194) at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:211) at org.apache.solr.cloud.api.collections.ShardSplitTest.doSplitShardWithRule(ShardSplitTest.java:661) at org.apache.solr.cloud.api.collections.ShardSplitTest.testSplitShardWithRule(ShardSplitTest.java:628) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:498) at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1750) at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:938) at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:974) at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:988) at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:1075) at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:1047) 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:947) at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:832) at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:883) at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:894) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.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: junit.framework.TestSuite.org.apache.solr.handler.admin.AutoscalingHistoryHandlerTest Error Message: 1 thread leaked from SUITE scope at org.apache.solr.handler.admin.AutoscalingHistoryHandlerTest: 1) Thread[id=4992, name=zkConnectionManagerCallback-1054-thread-1, state=WAITING, group=TGRP-AutoscalingHistoryHandlerTest] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.park(LockSupport.java:175) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2039) at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1074) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1134) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) at java.lang.Thread.run(Thread.java:748) Stack Trace: com.carrotsearch.randomizedtesting.ThreadLeakError: 1 thread leaked from SUITE scope at org.apache.solr.handler.admin.AutoscalingHistoryHandlerTest: 1) Thread[id=4992, name=zkConnectionManagerCallback-1054-thread-1, state=WAITING, group=TGRP-AutoscalingHistoryHandlerTest] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.park(LockSupport.java:175) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2039) at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1074) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1134) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) at java.lang.Thread.run(Thread.java:748) at __randomizedtesting.SeedInfo.seed([E36EE48FAF16F228]:0) FAILED: junit.framework.TestSuite.org.apache.solr.handler.admin.AutoscalingHistoryHandlerTest Error Message: There are still zombie threads that couldn't be terminated: 1) Thread[id=4992, name=zkConnectionManagerCallback-1054-thread-1, state=WAITING, group=TGRP-AutoscalingHistoryHandlerTest] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.park(LockSupport.java:175) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2039) at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1074) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1134) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) at java.lang.Thread.run(Thread.java:748) Stack Trace: com.carrotsearch.randomizedtesting.ThreadLeakError: There are still zombie threads that couldn't be terminated: 1) Thread[id=4992, name=zkConnectionManagerCallback-1054-thread-1, state=WAITING, group=TGRP-AutoscalingHistoryHandlerTest] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.park(LockSupport.java:175) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2039) at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1074) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1134) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) at java.lang.Thread.run(Thread.java:748) at __randomizedtesting.SeedInfo.seed([E36EE48FAF16F228]:0) Build Log: [...truncated 12944 lines...] [junit4] Suite: org.apache.solr.handler.admin.AutoscalingHistoryHandlerTest [junit4] 2> 614321 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[E36EE48FAF16F228]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom [junit4] 2> Creating dataDir: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.7/solr/build/solr-core/test/J0/temp/solr.handler.admin.AutoscalingHistoryHandlerTest_E36EE48FAF16F228-001/init-core-data-001 [junit4] 2> 614323 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[E36EE48FAF16F228]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=true [junit4] 2> 614324 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[E36EE48FAF16F228]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason=, ssl=NaN, value=NaN, clientAuth=NaN) [junit4] 2> 614325 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[E36EE48FAF16F228]-worker) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 2 servers in /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.7/solr/build/solr-core/test/J0/temp/solr.handler.admin.AutoscalingHistoryHandlerTest_E36EE48FAF16F228-001/tempDir-001 [junit4] 2> 614325 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[E36EE48FAF16F228]-worker) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER [junit4] 2> 614343 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0 [junit4] 2> 614343 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Starting server [junit4] 2> 614447 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[E36EE48FAF16F228]-worker) [ ] o.a.s.c.ZkTestServer start zk server on port:45481 [junit4] 2> 614447 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[E36EE48FAF16F228]-worker) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:45481 [junit4] 2> 614447 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[E36EE48FAF16F228]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 45481 [junit4] 2> 614483 INFO (zkConnectionManagerCallback-936-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 614539 INFO (zkConnectionManagerCallback-938-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 614610 INFO (zkConnectionManagerCallback-940-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 614646 WARN (jetty-launcher-941-thread-1) [ ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time [junit4] 2> 614646 INFO (jetty-launcher-941-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0) [junit4] 2> 614646 INFO (jetty-launcher-941-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ... [junit4] 2> 614647 INFO (jetty-launcher-941-thread-1) [ ] o.e.j.s.Server jetty-9.4.14.v20181114; built: 2018-11-14T21:20:31.478Z; git: c4550056e785fb5665914545889f21dc136ad9e6; jvm 1.8.0_191-b12 [junit4] 2> 614647 WARN (jetty-launcher-941-thread-2) [ ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time [junit4] 2> 614647 INFO (jetty-launcher-941-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0) [junit4] 2> 614647 INFO (jetty-launcher-941-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ... [junit4] 2> 614647 INFO (jetty-launcher-941-thread-2) [ ] o.e.j.s.Server jetty-9.4.14.v20181114; built: 2018-11-14T21:20:31.478Z; git: c4550056e785fb5665914545889f21dc136ad9e6; jvm 1.8.0_191-b12 [junit4] 2> 614715 INFO (jetty-launcher-941-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0 [junit4] 2> 614715 INFO (jetty-launcher-941-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults [junit4] 2> 614715 INFO (jetty-launcher-941-thread-1) [ ] o.e.j.s.session node0 Scavenging every 600000ms [junit4] 2> 614719 INFO (jetty-launcher-941-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@7c507e02{/solr,null,AVAILABLE} [junit4] 2> 614751 INFO (jetty-launcher-941-thread-2) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0 [junit4] 2> 614751 INFO (jetty-launcher-941-thread-2) [ ] o.e.j.s.session No SessionScavenger set, using defaults [junit4] 2> 614751 INFO (jetty-launcher-941-thread-2) [ ] o.e.j.s.session node0 Scavenging every 600000ms [junit4] 2> 614763 INFO (jetty-launcher-941-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@45793932{/solr,null,AVAILABLE} [junit4] 2> 614775 INFO (jetty-launcher-941-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@2e6fe8ad{HTTP/1.1,[http/1.1]}{127.0.0.1:44565} [junit4] 2> 614775 INFO (jetty-launcher-941-thread-1) [ ] o.e.j.s.Server Started @614847ms [junit4] 2> 614775 INFO (jetty-launcher-941-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=44565} [junit4] 2> 614776 ERROR (jetty-launcher-941-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 614776 INFO (jetty-launcher-941-thread-1) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory [junit4] 2> 614776 INFO (jetty-launcher-941-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 7.7.2 [junit4] 2> 614776 INFO (jetty-launcher-941-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 614776 INFO (jetty-launcher-941-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 614776 INFO (jetty-launcher-941-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2019-06-03T14:45:41.356Z [junit4] 2> 614799 INFO (jetty-launcher-941-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@788533e{HTTP/1.1,[http/1.1]}{127.0.0.1:40946} [junit4] 2> 614799 INFO (jetty-launcher-941-thread-2) [ ] o.e.j.s.Server Started @614870ms [junit4] 2> 614806 INFO (jetty-launcher-941-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=40946} [junit4] 2> 614809 ERROR (jetty-launcher-941-thread-2) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 614809 INFO (jetty-launcher-941-thread-2) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory [junit4] 2> 614809 INFO (jetty-launcher-941-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 7.7.2 [junit4] 2> 614809 INFO (jetty-launcher-941-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 614809 INFO (jetty-launcher-941-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 614809 INFO (jetty-launcher-941-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2019-06-03T14:45:41.389Z [junit4] 2> 614843 INFO (zkConnectionManagerCallback-945-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 614861 INFO (zkConnectionManagerCallback-943-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 614862 INFO (jetty-launcher-941-thread-2) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 614877 INFO (jetty-launcher-941-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 615068 INFO (jetty-launcher-941-thread-2) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true] [junit4] 2> 615072 INFO (jetty-launcher-941-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:45481/solr [junit4] 2> 615101 INFO (zkConnectionManagerCallback-949-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 615116 INFO (zkConnectionManagerCallback-951-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 615151 INFO (jetty-launcher-941-thread-1) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true] [junit4] 2> 615154 INFO (jetty-launcher-941-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:45481/solr [junit4] 2> 615157 INFO (zkConnectionManagerCallback-957-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 615183 INFO (zkConnectionManagerCallback-959-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 615197 INFO (jetty-launcher-941-thread-1) [n:127.0.0.1:44565_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:44565_solr [junit4] 2> 615198 INFO (jetty-launcher-941-thread-1) [n:127.0.0.1:44565_solr ] o.a.s.c.Overseer Overseer (id=74804243309395976-127.0.0.1:44565_solr-n_0000000000) starting [junit4] 2> 615243 INFO (zkConnectionManagerCallback-966-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 615255 INFO (jetty-launcher-941-thread-1) [n:127.0.0.1:44565_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:45481/solr ready [junit4] 2> 615263 INFO (OverseerStateUpdate-74804243309395976-127.0.0.1:44565_solr-n_0000000000) [n:127.0.0.1:44565_solr ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:44565_solr [junit4] 2> 615279 DEBUG (OverseerAutoScalingTriggerThread-74804243309395976-127.0.0.1:44565_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Adding .auto_add_replicas and .scheduled_maintenance triggers [junit4] 2> 615279 INFO (jetty-launcher-941-thread-1) [n:127.0.0.1:44565_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:44565_solr [junit4] 2> 615281 DEBUG (OverseerAutoScalingTriggerThread-74804243309395976-127.0.0.1:44565_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 1 [junit4] 2> 615282 DEBUG (OverseerAutoScalingTriggerThread-74804243309395976-127.0.0.1:44565_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1, lastZnodeVersion 0 [junit4] 2> 615282 DEBUG (OverseerAutoScalingTriggerThread-74804243309395976-127.0.0.1:44565_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 1 [junit4] 2> 615291 INFO (zkCallback-958-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 615295 INFO (zkCallback-965-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 615319 DEBUG (OverseerAutoScalingTriggerThread-74804243309395976-127.0.0.1:44565_solr-n_0000000000) [ ] o.a.s.c.a.NodeLostTrigger NodeLostTrigger .auto_add_replicas - Initial livenodes: [127.0.0.1:44565_solr] [junit4] 2> 615331 INFO (jetty-launcher-941-thread-1) [n:127.0.0.1:44565_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory. [junit4] 2> 615347 DEBUG (ScheduledTrigger-970-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1 [junit4] 2> 615365 INFO (jetty-launcher-941-thread-2) [n:127.0.0.1:40946_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 615365 DEBUG (OverseerAutoScalingTriggerThread-74804243309395976-127.0.0.1:44565_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread -- cleaning old nodeLost / nodeAdded markers [junit4] 2> 615370 DEBUG (OverseerAutoScalingTriggerThread-74804243309395976-127.0.0.1:44565_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1, lastZnodeVersion 1 [junit4] 2> 615373 INFO (jetty-launcher-941-thread-1) [n:127.0.0.1:44565_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_44565.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3d1e6e6a [junit4] 2> 615380 INFO (jetty-launcher-941-thread-1) [n:127.0.0.1:44565_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_44565.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3d1e6e6a [junit4] 2> 615380 INFO (jetty-launcher-941-thread-1) [n:127.0.0.1:44565_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_44565.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3d1e6e6a [junit4] 2> 615386 INFO (jetty-launcher-941-thread-1) [n:127.0.0.1:44565_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.7/solr/build/solr-core/test/J0/temp/solr.handler.admin.AutoscalingHistoryHandlerTest_E36EE48FAF16F228-001/tempDir-001/node1/. [junit4] 2> 615396 INFO (jetty-launcher-941-thread-2) [n:127.0.0.1:40946_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:40946_solr as DOWN [junit4] 2> 615399 INFO (jetty-launcher-941-thread-2) [n:127.0.0.1:40946_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores [junit4] 2> 615399 INFO (jetty-launcher-941-thread-2) [n:127.0.0.1:40946_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:40946_solr [junit4] 2> 615416 INFO (zkCallback-965-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2) [junit4] 2> 615426 INFO (zkCallback-958-thread-2) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2) [junit4] 2> 615439 DEBUG (OverseerStateUpdate-74804243309395976-127.0.0.1:44565_solr-n_0000000000) [n:127.0.0.1:44565_solr ] o.a.s.c.Overseer processMessage: queueSize: 1, message = { [junit4] 2> "operation":"downnode", [junit4] 2> "node_name":"127.0.0.1:40946_solr"} current state version: 0 [junit4] 2> 615439 DEBUG (OverseerStateUpdate-74804243309395976-127.0.0.1:44565_solr-n_0000000000) [n:127.0.0.1:44565_solr ] o.a.s.c.o.NodeMutator DownNode state invoked for node: 127.0.0.1:40946_solr [junit4] 2> 615439 INFO (zkCallback-950-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2) [junit4] 2> 615479 INFO (zkConnectionManagerCallback-972-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 615480 INFO (jetty-launcher-941-thread-2) [n:127.0.0.1:40946_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2) [junit4] 2> 615481 INFO (jetty-launcher-941-thread-2) [n:127.0.0.1:40946_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:45481/solr ready [junit4] 2> 615508 INFO (jetty-launcher-941-thread-2) [n:127.0.0.1:40946_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory. [junit4] 2> 615531 INFO (jetty-launcher-941-thread-2) [n:127.0.0.1:40946_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_40946.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3d1e6e6a [junit4] 2> 615538 INFO (jetty-launcher-941-thread-2) [n:127.0.0.1:40946_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_40946.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3d1e6e6a [junit4] 2> 615538 INFO (jetty-launcher-941-thread-2) [n:127.0.0.1:40946_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_40946.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3d1e6e6a [junit4] 2> 615549 INFO (jetty-launcher-941-thread-2) [n:127.0.0.1:40946_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.7/solr/build/solr-core/test/J0/temp/solr.handler.admin.AutoscalingHistoryHandlerTest_E36EE48FAF16F228-001/tempDir-001/node2/. [junit4] 2> 615636 INFO (zkConnectionManagerCallback-978-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 615637 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[E36EE48FAF16F228]-worker) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2) [junit4] 2> 615645 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[E36EE48FAF16F228]-worker) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:45481/solr ready [junit4] 2> 615660 INFO (qtp1573456457-4763) [n:127.0.0.1:40946_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params name=.system&nrtReplicas=1&action=CREATE&numShards=1&createNodeSet=127.0.0.1:44565_solr&wt=javabin&version=2 and sendToOCPQueue=true [junit4] 2> 615703 INFO (OverseerThreadFactory-972-thread-1-processing-n:127.0.0.1:44565_solr) [n:127.0.0.1:44565_solr ] o.a.s.c.a.c.CreateCollectionCmd Create collection .system [junit4] 2> 615707 DEBUG (OverseerStateUpdate-74804243309395976-127.0.0.1:44565_solr-n_0000000000) [n:127.0.0.1:44565_solr ] o.a.s.c.Overseer processMessage: queueSize: 1, message = { [junit4] 2> "name":".system", [junit4] 2> "fromApi":"true", [junit4] 2> "numShards":1, [junit4] 2> "createNodeSet":"127.0.0.1:44565_solr", [junit4] 2> "nrtReplicas":"1", [junit4] 2> "stateFormat":"2", [junit4] 2> "replicationFactor":"1", [junit4] 2> "operation":"create"} current state version: 0 [junit4] 2> 615707 DEBUG (OverseerStateUpdate-74804243309395976-127.0.0.1:44565_solr-n_0000000000) [n:127.0.0.1:44565_solr ] o.a.s.c.o.ClusterStateMutator building a new cName: .system [junit4] 2> 615708 DEBUG (OverseerStateUpdate-74804243309395976-127.0.0.1:44565_solr-n_0000000000) [n:127.0.0.1:44565_solr ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/.system/state.json [junit4] 2> 615811 DEBUG (OverseerStateUpdate-74804243309395976-127.0.0.1:44565_solr-n_0000000000) [n:127.0.0.1:44565_solr ] o.a.s.c.Overseer processMessage: queueSize: 1, message = { [junit4] 2> "operation":"ADDREPLICA", [junit4] 2> "collection":".system", [junit4] 2> "shard":"shard1", [junit4] 2> "core":".system_shard1_replica_n1", [junit4] 2> "state":"down", [junit4] 2> "base_url":"http://127.0.0.1:44565/solr";, [junit4] 2> "type":"NRT", [junit4] 2> "waitForFinalState":"false"} current state version: 0 [junit4] 2> 615811 INFO (OverseerStateUpdate-74804243309395976-127.0.0.1:44565_solr-n_0000000000) [n:127.0.0.1:44565_solr ] o.a.s.c.o.SliceMutator createReplica() { [junit4] 2> "operation":"ADDREPLICA", [junit4] 2> "collection":".system", [junit4] 2> "shard":"shard1", [junit4] 2> "core":".system_shard1_replica_n1", [junit4] 2> "state":"down", [junit4] 2> "base_url":"http://127.0.0.1:44565/solr";, [junit4] 2> "type":"NRT", [junit4] 2> "waitForFinalState":"false"} [junit4] 2> 615813 DEBUG (OverseerStateUpdate-74804243309395976-127.0.0.1:44565_solr-n_0000000000) [n:127.0.0.1:44565_solr ] o.a.s.c.o.SliceMutator Old Slice: shard1:{ [junit4] 2> "range":"80000000-7fffffff", [junit4] 2> "state":"active", [junit4] 2> "replicas":{}} [junit4] 2> 615813 DEBUG (OverseerStateUpdate-74804243309395976-127.0.0.1:44565_solr-n_0000000000) [n:127.0.0.1:44565_solr ] o.a.s.c.o.SliceMutator New Slice: shard1:{ [junit4] 2> "range":"80000000-7fffffff", [junit4] 2> "state":"active", [junit4] 2> "replicas":{"core_node2":{ [junit4] 2> "core":".system_shard1_replica_n1", [junit4] 2> "base_url":"http://127.0.0.1:44565/solr";, [junit4] 2> "state":"down", [junit4] 2> "node_name":null, [junit4] 2> "type":"NRT"}}} [junit4] 2> 615919 DEBUG (OverseerStateUpdate-74804243309395976-127.0.0.1:44565_solr-n_0000000000) [n:127.0.0.1:44565_solr ] o.a.s.c.o.ZkStateWriter going to update_collection /collections/.system/state.json version: 0 [junit4] 2> 616026 INFO (qtp1069996609-4754) [n:127.0.0.1:44565_solr x:.system_shard1_replica_n1] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node2&collection.configName=.system&newCollection=true&name=.system_shard1_replica_n1&action=CREATE&numShards=1&collection=.system&shard=shard1&wt=javabin&version=2&replicaType=NRT [junit4] 2> 616027 INFO (qtp1069996609-4754) [n:127.0.0.1:44565_solr x:.system_shard1_replica_n1] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores [junit4] 2> 616030 DEBUG (OverseerStateUpdate-74804243309395976-127.0.0.1:44565_solr-n_0000000000) [n:127.0.0.1:44565_solr ] o.a.s.c.Overseer processMessage: queueSize: 1, message = { [junit4] 2> "core":".system_shard1_replica_n1", [junit4] 2> "core_node_name":"core_node2", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:44565/solr";, [junit4] 2> "node_name":"127.0.0.1:44565_solr", [junit4] 2> "numShards":"1", [junit4] 2> "state":"down", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":".system", [junit4] 2> "type":"NRT", [junit4] 2> "force_set_state":"false", [junit4] 2> "operation":"state"} current state version: 0 [junit4] 2> 616030 DEBUG (OverseerStateUpdate-74804243309395976-127.0.0.1:44565_solr-n_0000000000) [n:127.0.0.1:44565_solr ] o.a.s.c.o.ReplicaMutator Update state numShards=1 message={ [junit4] 2> "core":".system_shard1_replica_n1", [junit4] 2> "core_node_name":"core_node2", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:44565/solr";, [junit4] 2> "node_name":"127.0.0.1:44565_solr", [junit4] 2> "numShards":"1", [junit4] 2> "state":"down", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":".system", [junit4] 2> "type":"NRT", [junit4] 2> "force_set_state":"false", [junit4] 2> "operation":"state"} [junit4] 2> 616030 DEBUG (OverseerStateUpdate-74804243309395976-127.0.0.1:44565_solr-n_0000000000) [n:127.0.0.1:44565_solr ] o.a.s.c.o.ReplicaMutator Will update state for replica: core_node2:{"core":".system_shard1_replica_n1","base_url":"http://127.0.0.1:44565/solr","node_name":"127.0.0.1:44565_solr","state":"down","type":"NRT","force_set_state":"false"} [junit4] 2> 616030 DEBUG (OverseerStateUpdate-74804243309395976-127.0.0.1:44565_solr-n_0000000000) [n:127.0.0.1:44565_solr ] o.a.s.c.o.ReplicaMutator Collection is now: DocCollection(.system//collections/.system/state.json/1)={ [junit4] 2> "pullReplicas":"0", [junit4] 2> "replicationFactor":"1", [junit4] 2> "router":{"name":"compositeId"}, [junit4] 2> "maxShardsPerNode":"1", [junit4] 2> "autoAddReplicas":"false", [junit4] 2> "nrtReplicas":"1", [junit4] 2> "tlogReplicas":"0", [junit4] 2> "shards":{"shard1":{ [junit4] 2> "range":"80000000-7fffffff", [junit4] 2> "state":"active", [junit4] 2> "replicas":{"core_node2":{ [junit4] 2> "core":".system_shard1_replica_n1", [junit4] 2> "base_url":"http://127.0.0.1:44565/solr";, [junit4] 2> "node_name":"127.0.0.1:44565_solr", [junit4] 2> "state":"down", [junit4] 2> "type":"NRT", [junit4] 2> "force_set_state":"false"}}}}} [junit4] 2> 616135 DEBUG (OverseerStateUpdate-74804243309395976-127.0.0.1:44565_solr-n_0000000000) [n:127.0.0.1:44565_solr ] o.a.s.c.o.ZkStateWriter going to update_collection /collections/.system/state.json version: 1 [junit4] 2> 616349 DEBUG (ScheduledTrigger-970-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 1 [junit4] 2> 617039 WARN (qtp1069996609-4754) [n:127.0.0.1:44565_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1] o.a.s.c.Config You should not use LATEST as luceneMatchVersion property: if you use this setting, and then Solr upgrades to a newer release of Lucene, sizable changes may happen. If precise back compatibility is important then you should instead explicitly specify an actual Lucene version. [junit4] 2> 617041 INFO (qtp1069996609-4754) [n:127.0.0.1:44565_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.7.2 [junit4] 2> 617046 INFO (qtp1069996609-4754) [n:127.0.0.1:44565_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1] o.a.s.s.ManagedIndexSchemaFactory The schema is configured as managed, but managed schema resource managed-schema not found - loading non-managed schema schema.xml instead [junit4] 2> 617056 INFO (qtp1069996609-4754) [n:127.0.0.1:44565_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1] o.a.s.s.IndexSchema [.system_shard1_replica_n1] Schema name=_system collection or core [junit4] 2> 617075 INFO (qtp1069996609-4754) [n:127.0.0.1:44565_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1] o.a.s.s.IndexSchema Loaded schema _system collection or core/1.1 with uniqueid field id [junit4] 2> 617079 INFO (qtp1069996609-4754) [n:127.0.0.1:44565_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1] o.a.s.s.ManagedIndexSchema Created and persisted managed schema znode at /configs/.system/managed-schema [junit4] 2> 617081 INFO (qtp1069996609-4754) [n:127.0.0.1:44565_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1] o.a.s.s.ManagedIndexSchemaFactory After upgrading to managed schema in ZooKeeper, renamed the non-managed schema /configs/.system/schema.xml to /configs/.system/schema.xml.bak [junit4] 2> 617081 INFO (qtp1069996609-4754) [n:127.0.0.1:44565_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1] o.a.s.c.CoreContainer Creating SolrCore '.system_shard1_replica_n1' using configuration from collection .system, trusted=true [junit4] 2> 617082 INFO (qtp1069996609-4754) [n:127.0.0.1:44565_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_44565.solr.core..system.shard1.replica_n1' (registry 'solr.core..system.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3d1e6e6a [junit4] 2> 617082 INFO (qtp1069996609-4754) [n:127.0.0.1:44565_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1] o.a.s.c.SolrCore [[.system_shard1_replica_n1] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.7/solr/build/solr-core/test/J0/temp/solr.handler.admin.AutoscalingHistoryHandlerTest_E36EE48FAF16F228-001/tempDir-001/node1/.system_shard1_replica_n1], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.7/solr/build/solr-core/test/J0/temp/solr.handler.admin.AutoscalingHistoryHandlerTest_E36EE48FAF16F228-001/tempDir-001/node1/./.system_shard1_replica_n1/data/] [junit4] 2> 617159 INFO (qtp1069996609-4754) [n:127.0.0.1:44565_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 617159 INFO (qtp1069996609-4754) [n:127.0.0.1:44565_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 617160 INFO (qtp1069996609-4754) [n:127.0.0.1:44565_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1] o.a.s.u.CommitTracker Hard AutoCommit: if 1 uncommitted docs; [junit4] 2> 617160 INFO (qtp1069996609-4754) [n:127.0.0.1:44565_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 617162 INFO (qtp1069996609-4754) [n:127.0.0.1:44565_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1] o.a.s.s.SolrIndexSearcher Opening [Searcher@f12ed85[.system_shard1_replica_n1] main] [junit4] 2> 617163 INFO (qtp1069996609-4754) [n:127.0.0.1:44565_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/.system [junit4] 2> 617164 INFO (qtp1069996609-4754) [n:127.0.0.1:44565_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/.system [junit4] 2> 617164 INFO (qtp1069996609-4754) [n:127.0.0.1:44565_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1] o.a.s.s.ZkIndexSchemaReader Creating ZooKeeper watch for the managed schema at /configs/.system/managed-schema [junit4] 2> 617166 INFO (qtp1069996609-4754) [n:127.0.0.1:44565_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1] o.a.s.s.ZkIndexSchemaReader Current schema version 0 is already the latest [junit4] 2> 617166 INFO (qtp1069996609-4754) [n:127.0.0.1:44565_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms. [junit4] 2> 617167 INFO (qtp1069996609-4754) [n:127.0.0.1:44565_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1635330968777654272 [junit4] 2> 617169 INFO (searcherExecutor-981-thread-1-processing-n:127.0.0.1:44565_solr x:.system_shard1_replica_n1 c:.system s:shard1 r:core_node2) [n:127.0.0.1:44565_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1] o.a.s.c.SolrCore [.system_shard1_replica_n1] Registered new searcher Searcher@f12ed85[.system_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 617175 INFO (qtp1069996609-4754) [n:127.0.0.1:44565_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1] o.a.s.c.ZkShardTerms Successful update of terms at /collections/.system/terms/shard1 to Terms{values={core_node2=0}, version=0} [junit4] 2> 617175 INFO (qtp1069996609-4754) [n:127.0.0.1:44565_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/.system/leaders/shard1 [junit4] 2> 617178 INFO (qtp1069996609-4754) [n:127.0.0.1:44565_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue. [junit4] 2> 617178 INFO (qtp1069996609-4754) [n:127.0.0.1:44565_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync [junit4] 2> 617178 INFO (qtp1069996609-4754) [n:127.0.0.1:44565_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:44565/solr/.system_shard1_replica_n1/ [junit4] 2> 617190 INFO (qtp1069996609-4754) [n:127.0.0.1:44565_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me [junit4] 2> 617191 INFO (qtp1069996609-4754) [n:127.0.0.1:44565_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1] o.a.s.c.SyncStrategy http://127.0.0.1:44565/solr/.system_shard1_replica_n1/ has no replicas [junit4] 2> 617191 INFO (qtp1069996609-4754) [n:127.0.0.1:44565_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext Found all replicas participating in election, clear LIR [junit4] 2> 617191 INFO (qtp1069996609-4754) [n:127.0.0.1:44565_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/.system/leaders/shard1/leader after winning as /collections/.system/leader_elect/shard1/election/74804243309395976-core_node2-n_0000000000 [junit4] 2> 617193 INFO (qtp1069996609-4754) [n:127.0.0.1:44565_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:44565/solr/.system_shard1_replica_n1/ shard1 [junit4] 2> 617193 DEBUG (OverseerStateUpdate-74804243309395976-127.0.0.1:44565_solr-n_0000000000) [n:127.0.0.1:44565_solr ] o.a.s.c.Overseer processMessage: queueSize: 1, message = { [junit4] 2> "operation":"leader", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":".system", [junit4] 2> "base_url":"http://127.0.0.1:44565/solr";, [junit4] 2> "core":".system_shard1_replica_n1", [junit4] 2> "state":"active"} current state version: 0 [junit4] 2> 617294 DEBUG (OverseerStateUpdate-74804243309395976-127.0.0.1:44565_solr-n_0000000000) [n:127.0.0.1:44565_solr ] o.a.s.c.o.ZkStateWriter going to update_collection /collections/.system/state.json version: 2 [junit4] 2> 617295 INFO (zkCallback-958-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/.system/state.json] for collection [.system] has occurred - updating... (live nodes size: [2]) [junit4] 2> 617295 INFO (zkCallback-958-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/.system/state.json] for collection [.system] has occurred - updating... (live nodes size: [2]) [junit4] 2> 617296 INFO (qtp1069996609-4754) [n:127.0.0.1:44565_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1] o.a.s.c.ZkController I am the leader, no recovery necessary [junit4] 2> 617309 DEBUG (OverseerStateUpdate-74804243309395976-127.0.0.1:44565_solr-n_0000000000) [n:127.0.0.1:44565_solr ] o.a.s.c.Overseer processMessage: queueSize: 1, message = { [junit4] 2> "core":".system_shard1_replica_n1", [junit4] 2> "core_node_name":"core_node2", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:44565/solr";, [junit4] 2> "node_name":"127.0.0.1:44565_solr", [junit4] 2> "numShards":"1", [junit4] 2> "state":"active", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":".system", [junit4] 2> "type":"NRT", [junit4] 2> "force_set_state":"false", [junit4] 2> "operation":"state"} current state version: 0 [junit4] 2> 617309 DEBUG (OverseerStateUpdate-74804243309395976-127.0.0.1:44565_solr-n_0000000000) [n:127.0.0.1:44565_solr ] o.a.s.c.o.ReplicaMutator Update state numShards=1 message={ [junit4] 2> "core":".system_shard1_replica_n1", [junit4] 2> "core_node_name":"core_node2", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:44565/solr";, [junit4] 2> "node_name":"127.0.0.1:44565_solr", [junit4] 2> "numShards":"1", [junit4] 2> "state":"active", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":".system", [junit4] 2> "type":"NRT", [junit4] 2> "force_set_state":"false", [junit4] 2> "operation":"state"} [junit4] 2> 617309 DEBUG (OverseerStateUpdate-74804243309395976-127.0.0.1:44565_solr-n_0000000000) [n:127.0.0.1:44565_solr ] o.a.s.c.o.ReplicaMutator Will update state for replica: core_node2:{"core":".system_shard1_replica_n1","base_url":"http://127.0.0.1:44565/solr","node_name":"127.0.0.1:44565_solr","state":"active","type":"NRT","force_set_state":"false","leader":"true"} [junit4] 2> 617309 DEBUG (OverseerStateUpdate-74804243309395976-127.0.0.1:44565_solr-n_0000000000) [n:127.0.0.1:44565_solr ] o.a.s.c.o.ReplicaMutator Collection is now: DocCollection(.system//collections/.system/state.json/3)={ [junit4] 2> "pullReplicas":"0", [junit4] 2> "replicationFactor":"1", [junit4] 2> "router":{"name":"compositeId"}, [junit4] 2> "maxShardsPerNode":"1", [junit4] 2> "autoAddReplicas":"false", [junit4] 2> "nrtReplicas":"1", [junit4] 2> "tlogReplicas":"0", [junit4] 2> "shards":{"shard1":{ [junit4] 2> "range":"80000000-7fffffff", [junit4] 2> "state":"active", [junit4] 2> "replicas":{"core_node2":{ [junit4] 2> "core":".system_shard1_replica_n1", [junit4] 2> "base_url":"http://127.0.0.1:44565/solr";, [junit4] 2> "node_name":"127.0.0.1:44565_solr", [junit4] 2> "state":"active", [junit4] 2> "type":"NRT", [junit4] 2> "force_set_state":"false", [junit4] 2> "leader":"true"}}}}} [junit4] 2> 617315 ERROR (qtp1069996609-4754) [n:127.0.0.1:44565_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1] o.a.s.c.c.ZkStateReader Collection .system is not lazy or watched! [junit4] 2> 617316 INFO (qtp1069996609-4754) [n:127.0.0.1:44565_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node2&collection.configName=.system&newCollection=true&name=.system_shard1_replica_n1&action=CREATE&numShards=1&collection=.system&shard=shard1&wt=javabin&version=2&replicaType=NRT} status=0 QTime=1290 [junit4] 2> 617325 INFO (qtp1573456457-4763) [n:127.0.0.1:40946_solr ] o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most 45 seconds. Check all shard replicas [junit4] 2> 617350 DEBUG (ScheduledTrigger-970-thread-4) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2 [junit4] 2> 617410 DEBUG (OverseerStateUpdate-74804243309395976-127.0.0.1:44565_solr-n_0000000000) [n:127.0.0.1:44565_solr ] o.a.s.c.o.ZkStateWriter going to update_collection /collections/.system/state.json version: 3 [junit4] 2> 617410 INFO (zkCallback-950-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/.system/state.json] for collection [.system] has occurred - updating... (live nodes size: [2]) [junit4] 2> 617410 INFO (zkCallback-958-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/.system/state.json] for collection [.system] has occurred - updating... (live nodes size: [2]) [junit4] 2> 617410 INFO (zkCallback-958-thread-3) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/.system/state.json] for collection [.system] has occurred - updating... (live nodes size: [2]) [junit4] 2> 617410 INFO (zkCallback-958-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/.system/state.json] for collection [.system] has occurred - updating... (live nodes size: [2]) [junit4] 2> 617411 INFO (qtp1573456457-4763) [n:127.0.0.1:40946_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={name=.system&nrtReplicas=1&action=CREATE&numShards=1&createNodeSet=127.0.0.1:44565_solr&wt=javabin&version=2} status=0 QTime=1750 [junit4] 2> 617429 INFO (qtp1573456457-4764) [n:127.0.0.1:40946_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params maxShardsPerNode=3&name=AutoscalingHistoryHandlerTest_collection&nrtReplicas=3&action=CREATE&numShards=1&createNodeSet=127.0.0.1:40946_solr&wt=javabin&version=2 and sendToOCPQueue=true [junit4] 2> 617432 INFO (OverseerThreadFactory-972-thread-2-processing-n:127.0.0.1:44565_solr) [n:127.0.0.1:44565_solr ] o.a.s.c.a.c.CreateCollectionCmd Create collection AutoscalingHistoryHandlerTest_collection [junit4] 2> 617432 INFO (OverseerCollectionConfigSetProcessor-74804243309395976-127.0.0.1:44565_solr-n_0000000000) [n:127.0.0.1:44565_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> 617433 INFO (OverseerThreadFactory-972-thread-2-processing-n:127.0.0.1:44565_solr) [n:127.0.0.1:44565_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/protwords.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/protwords.txt [junit4] 2> 617435 INFO (OverseerThreadFactory-972-thread-2-processing-n:127.0.0.1:44565_solr) [n:127.0.0.1:44565_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/managed-schema to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/managed-schema [junit4] 2> 617436 INFO (OverseerThreadFactory-972-thread-2-processing-n:127.0.0.1:44565_solr) [n:127.0.0.1:44565_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/solrconfig.xml to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/solrconfig.xml [junit4] 2> 617438 INFO (OverseerThreadFactory-972-thread-2-processing-n:127.0.0.1:44565_solr) [n:127.0.0.1:44565_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/synonyms.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/synonyms.txt [junit4] 2> 617439 INFO (OverseerThreadFactory-972-thread-2-processing-n:127.0.0.1:44565_solr) [n:127.0.0.1:44565_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/stopwords.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/stopwords.txt [junit4] 2> 617440 INFO (OverseerThreadFactory-972-thread-2-processing-n:127.0.0.1:44565_solr) [n:127.0.0.1:44565_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/params.json to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/params.json [junit4] 2> 617442 INFO (OverseerThreadFactory-972-thread-2-processing-n:127.0.0.1:44565_solr) [n:127.0.0.1:44565_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/contractions_it.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/contractions_it.txt [junit4] 2> 617443 INFO (OverseerThreadFactory-972-thread-2-processing-n:127.0.0.1:44565_solr) [n:127.0.0.1:44565_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_no.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_no.txt [junit4] 2> 617445 INFO (OverseerThreadFactory-972-thread-2-processing-n:127.0.0.1:44565_solr) [n:127.0.0.1:44565_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_hy.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_hy.txt [junit4] 2> 617446 INFO (OverseerThreadFactory-972-thread-2-processing-n:127.0.0.1:44565_solr) [n:127.0.0.1:44565_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stemdict_nl.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stemdict_nl.txt [junit4] 2> 617448 INFO (OverseerThreadFactory-972-thread-2-processing-n:127.0.0.1:44565_solr) [n:127.0.0.1:44565_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/contractions_ca.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/contractions_ca.txt [junit4] 2> 617449 INFO (OverseerThreadFactory-972-thread-2-processing-n:127.0.0.1:44565_solr) [n:127.0.0.1:44565_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_id.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_id.txt [junit4] 2> 617450 INFO (OverseerThreadFactory-972-thread-2-processing-n:127.0.0.1:44565_solr) [n:127.0.0.1:44565_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ga.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_ga.txt [junit4] 2> 617452 INFO (OverseerThreadFactory-972-thread-2-processing-n:127.0.0.1:44565_solr) [n:127.0.0.1:44565_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_da.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_da.txt [junit4] 2> 617454 INFO (OverseerThreadFactory-972-thread-2-processing-n:127.0.0.1:44565_solr) [n:127.0.0.1:44565_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_hi.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_hi.txt [junit4] 2> 617455 INFO (OverseerThreadFactory-972-thread-2-processing-n:127.0.0.1:44565_solr) [n:127.0.0.1:44565_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ja.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_ja.txt [junit4] 2> 617457 INFO (OverseerThreadFactory-972-thread-2-processing-n:127.0.0.1:44565_solr) [n:127.0.0.1:44565_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_pt.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_pt.txt [junit4] 2> 617458 INFO (OverseerThreadFactory-972-thread-2-processing-n:127.0.0.1:44565_solr) [n:127.0.0.1:44565_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_hu.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_hu.txt [junit4] 2> 617460 INFO (OverseerThreadFactory-972-thread-2-processing-n:127.0.0.1:44565_solr) [n:127.0.0.1:44565_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_el.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_el.txt [junit4] 2> 617461 INFO (OverseerThreadFactory-972-thread-2-processing-n:127.0.0.1:44565_solr) [n:127.0.0.1:44565_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ru.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_ru.txt [junit4] 2> 617471 INFO (OverseerThreadFactory-972-thread-2-processing-n:127.0.0.1:44565_solr) [n:127.0.0.1:44565_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_tr.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_tr.txt [junit4] 2> 617472 INFO (OverseerThreadFactory-972-thread-2-processing-n:127.0.0.1:44565_solr) [n:127.0.0.1:44565_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ar.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_ar.txt [junit4] 2> 617474 INFO (OverseerThreadFactory-972-thread-2-processing-n:127.0.0.1:44565_solr) [n:127.0.0.1:44565_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/userdict_ja.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/userdict_ja.txt [junit4] 2> 617475 INFO (OverseerThreadFactory-972-thread-2-processing-n:127.0.0.1:44565_solr) [n:127.0.0.1:44565_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_it.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_it.txt [junit4] 2> 617477 INFO (OverseerThreadFactory-972-thread-2-processing-n:127.0.0.1:44565_solr) [n:127.0.0.1:44565_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_eu.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_eu.txt [junit4] 2> 617479 INFO (OverseerThreadFactory-972-thread-2-processing-n:127.0.0.1:44565_solr) [n:127.0.0.1:44565_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_cz.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_cz.txt [junit4] 2> 617480 INFO (OverseerThreadFactory-972-thread-2-processing-n:127.0.0.1:44565_solr) [n:127.0.0.1:44565_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stoptags_ja.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stoptags_ja.txt [junit4] 2> 617483 INFO (OverseerThreadFactory-972-thread-2-processing-n:127.0.0.1:44565_solr) [n:127.0.0.1:44565_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/contractions_fr.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/contractions_fr.txt [junit4] 2> 617484 INFO (OverseerThreadFactory-972-thread-2-processing-n:127.0.0.1:44565_solr) [n:127.0.0.1:44565_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_fa.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_fa.txt [junit4] 2> 617485 INFO (OverseerThreadFactory-972-thread-2-processing-n:127.0.0.1:44565_solr) [n:127.0.0.1:44565_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_de.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_de.txt [junit4] 2> 617487 INFO (OverseerThreadFactory-972-thread-2-processing-n:127.0.0.1:44565_solr) [n:127.0.0.1:44565_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/contractions_ga.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/contractions_ga.txt [junit4] 2> 617488 INFO (OverseerThreadFactory-972-thread-2-processing-n:127.0.0.1:44565_solr) [n:127.0.0.1:44565_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ca.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_ca.txt [junit4] 2> 617489 INFO (OverseerThreadFactory-972-thread-2-processing-n:127.0.0.1:44565_solr) [n:127.0.0.1:44565_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_nl.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_nl.txt [junit4] 2> 617490 INFO (OverseerThreadFactory-972-thread-2-processing-n:127.0.0.1:44565_solr) [n:127.0.0.1:44565_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_bg.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_bg.txt [junit4] 2> 617492 INFO (OverseerThreadFactory-972-thread-2-processing-n:127.0.0.1:44565_solr) [n:127.0.0.1:44565_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_es.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_es.txt [junit4] 2> 617493 INFO (OverseerThreadFactory-972-thread-2-processing-n:127.0.0.1:44565_solr) [n:127.0.0.1:44565_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_en.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_en.txt [junit4] 2> 617507 INFO (OverseerThreadFactory-972-thread-2-processing-n:127.0.0.1:44565_solr) [n:127.0.0.1:44565_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_lv.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_lv.txt [junit4] 2> 617509 INFO (OverseerThreadFactory-972-thread-2-processing-n:127.0.0.1:44565_solr) [n:127.0.0.1:44565_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_th.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_th.txt [junit4] 2> 617510 INFO (OverseerThreadFactory-972-thread-2-processing-n:127.0.0.1:44565_solr) [n:127.0.0.1:44565_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ro.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_ro.txt [junit4] 2> 617511 INFO (OverseerThreadFactory-972-thread-2-processing-n:127.0.0.1:44565_solr) [n:127.0.0.1:44565_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_fr.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_fr.txt [junit4] 2> 617513 INFO (OverseerThreadFactory-972-thread-2-processing-n:127.0.0.1:44565_solr) [n:127.0.0.1:44565_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_sv.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_sv.txt [junit4] 2> 617514 INFO (OverseerThreadFactory-972-thread-2-processing-n:127.0.0.1:44565_solr) [n:127.0.0.1:44565_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_fi.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_fi.txt [junit4] 2> 617522 INFO (OverseerThreadFactory-972-thread-2-processing-n:127.0.0.1:44565_solr) [n:127.0.0.1:44565_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/hyphenations_ga.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/hyphenations_ga.txt [junit4] 2> 617523 INFO (OverseerThreadFactory-972-thread-2-processing-n:127.0.0.1:44565_solr) [n:127.0.0.1:44565_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_gl.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_gl.txt [junit4] 2> 617536 DEBUG (OverseerStateUpdate-74804243309395976-127.0.0.1:44565_solr-n_0000000000) [n:127.0.0.1:44565_solr ] o.a.s.c.Overseer processMessage: queueSize: 1, message = { [junit4] 2> "name":"AutoscalingHistoryHandlerTest_collection", [junit4] 2> "fromApi":"true", [junit4] 2> "numShards":"1", [junit4] 2> "maxShardsPerNode":"3", [junit4] 2> "createNodeSet":"127.0.0.1:40946_solr", [junit4] 2> "nrtReplicas":"3", [junit4] 2> "stateFormat":"2", [junit4] 2> "replicationFactor":"3", [junit4] 2> "operation":"create"} current state version: 0 [junit4] 2> 617536 DEBUG (OverseerStateUpdate-74804243309395976-127.0.0.1:44565_solr-n_0000000000) [n:127.0.0.1:44565_solr ] o.a.s.c.o.ClusterStateMutator building a new cName: AutoscalingHistoryHandlerTest_collection [junit4] 2> 617637 DEBUG (OverseerStateUpdate-74804243309395976-127.0.0.1:44565_solr-n_0000000000) [n:127.0.0.1:44565_solr ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/AutoscalingHistoryHandlerTest_collection/state.json [junit4] 2> 617737 WARN (OverseerThreadFactory-972-thread-2-processing-n:127.0.0.1:44565_solr) [n:127.0.0.1:44565_solr ] o.a.s.c.a.c.CreateCollectionCmd Specified number of replicas of 3 on collection AutoscalingHistoryHandlerTest_collection is higher than the number of Solr instances currently live or live and part of your createNodeSet(1). It's unusual to run two replica of the same slice on the same Solr-instance. [junit4] 2> 617743 DEBUG (OverseerStateUpdate-74804243309395976-127.0.0.1:44565_solr-n_0000000000) [n:127.0.0.1:44565_solr ] o.a.s.c.Overseer processMessage: queueSize: 1, message = { [junit4] 2> "operation":"ADDREPLICA", [junit4] 2> "collection":"AutoscalingHistoryHandlerTest_collection", [junit4] 2> "shard":"shard1", [junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n1", [junit4] 2> "state":"down", [junit4] 2> "base_url":"http://127.0.0.1:40946/solr";, [junit4] 2> "type":"NRT", [junit4] 2> "waitForFinalState":"false"} current state version: 0 [junit4] 2> 617743 INFO (OverseerStateUpdate-74804243309395976-127.0.0.1:44565_solr-n_0000000000) [n:127.0.0.1:44565_solr ] o.a.s.c.o.SliceMutator createReplica() { [junit4] 2> "operation":"ADDREPLICA", [junit4] 2> "collection":"AutoscalingHistoryHandlerTest_collection", [junit4] 2> "shard":"shard1", [junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n1", [junit4] 2> "state":"down", [junit4] 2> "base_url":"http://127.0.0.1:40946/solr";, [junit4] 2> "type":"NRT", [junit4] 2> "waitForFinalState":"false"} [junit4] 2> 617753 DEBUG (OverseerStateUpdate-74804243309395976-127.0.0.1:44565_solr-n_0000000000) [n:127.0.0.1:44565_solr ] o.a.s.c.o.SliceMutator Old Slice: shard1:{ [junit4] 2> "range":"80000000-7fffffff", [junit4] 2> "state":"active", [junit4] 2> "replicas":{}} [junit4] 2> 617753 DEBUG (OverseerStateUpdate-74804243309395976-127.0.0.1:44565_solr-n_0000000000) [n:127.0.0.1:44565_solr ] o.a.s.c.o.SliceMutator New Slice: shard1:{ [junit4] 2> "range":"80000000-7fffffff", [junit4] 2> "state":"active", [junit4] 2> "replicas":{"core_node3":{ [junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n1", [junit4] 2> "base_url":"http://127.0.0.1:40946/solr";, [junit4] 2> "state":"down", [junit4] 2> "node_name":null, [junit4] 2> "type":"NRT"}}} [junit4] 2> 617753 DEBUG (OverseerStateUpdate-74804243309395976-127.0.0.1:44565_solr-n_0000000000) [n:127.0.0.1:44565_solr ] o.a.s.c.Overseer processMessage: queueSize: 2, message = { [junit4] 2> "operation":"ADDREPLICA", [junit4] 2> "collection":"AutoscalingHistoryHandlerTest_collection", [junit4] 2> "shard":"shard1", [junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n2", [junit4] 2> "state":"down", [junit4] 2> "base_url":"http://127.0.0.1:40946/solr";, [junit4] 2> "type":"NRT", [junit4] 2> "waitForFinalState":"false"} current state version: 0 [junit4] 2> 617753 INFO (OverseerStateUpdate-74804243309395976-127.0.0.1:44565_solr-n_0000000000) [n:127.0.0.1:44565_solr ] o.a.s.c.o.SliceMutator createReplica() { [junit4] 2> "operation":"ADDREPLICA", [junit4] 2> "collection":"AutoscalingHistoryHandlerTest_collection", [junit4] 2> "shard":"shard1", [junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n2", [junit4] 2> "state":"down", [junit4] 2> "base_url":"http://127.0.0.1:40946/solr";, [junit4] 2> "type":"NRT", [junit4] 2> "waitForFinalState":"false"} [junit4] 2> 617756 DEBUG (OverseerStateUpdate-74804243309395976-127.0.0.1:44565_solr-n_0000000000) [n:127.0.0.1:44565_solr ] o.a.s.c.o.SliceMutator Old Slice: shard1:{ [junit4] 2> "range":"80000000-7fffffff", [junit4] 2> "state":"active", [junit4] 2> "replicas":{"core_node3":{ [junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n1", [junit4] 2> "base_url":"http://127.0.0.1:40946/solr";, [junit4] 2> "state":"down", [junit4] 2> "node_name":null, [junit4] 2> "type":"NRT"}}} [junit4] 2> 617756 DEBUG (OverseerStateUpdate-74804243309395976-127.0.0.1:44565_solr-n_0000000000) [n:127.0.0.1:44565_solr ] o.a.s.c.o.SliceMutator New Slice: shard1:{ [junit4] 2> "range":"80000000-7fffffff", [junit4] 2> "state":"active", [junit4] 2> "replicas":{ [junit4] 2> "core_node3":{ [junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n1", [junit4] 2> "base_url":"http://127.0.0.1:40946/solr";, [junit4] 2> "state":"down", [junit4] 2> "node_name":null, [junit4] 2> "type":"NRT"}, [junit4] 2> "core_node5":{ [junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n2", [junit4] 2> "base_url":"http://127.0.0.1:40946/solr";, [junit4] 2> "state":"down", [junit4] 2> "node_name":null, [junit4] 2> "type":"NRT"}}} [junit4] 2> 617756 DEBUG (OverseerStateUpdate-74804243309395976-127.0.0.1:44565_solr-n_0000000000) [n:127.0.0.1:44565_solr ] o.a.s.c.Overseer processMessage: queueSize: 3, message = { [junit4] 2> "operation":"ADDREPLICA", [junit4] 2> "collection":"AutoscalingHistoryHandlerTest_collection", [junit4] 2> "shard":"shard1", [junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n4", [junit4] 2> "state":"down", [junit4] 2> "base_url":"http://127.0.0.1:40946/solr";, [junit4] 2> "type":"NRT", [junit4] 2> "waitForFinalState":"false"} current state version: 0 [junit4] 2> 617756 INFO (OverseerStateUpdate-74804243309395976-127.0.0.1:44565_solr-n_0000000000) [n:127.0.0.1:44565_solr ] o.a.s.c.o.SliceMutator createReplica() { [junit4] 2> "operation":"ADDREPLICA", [junit4] 2> "collection":"AutoscalingHistoryHandlerTest_collection", [junit4] 2> "shard":"shard1", [junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n4", [junit4] 2> "state":"down", [junit4] 2> "base_url":"http://127.0.0.1:40946/solr";, [junit4] 2> "type":"NRT", [junit4] 2> "waitForFinalState":"false"} [junit4] 2> 617757 DEBUG (OverseerStateUpdate-74804243309395976-127.0.0.1:44565_solr-n_0000000000) [n:127.0.0.1:44565_solr ] o.a.s.c.o.SliceMutator Old Slice: shard1:{ [junit4] 2> "range":"80000000-7fffffff", [junit4] 2> "state":"active", [junit4] 2> "replicas":{ [junit4] 2> "core_node3":{ [junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n1", [junit4] 2> "base_url":"http://127.0.0.1:40946/solr";, [junit4] 2> "state":"down", [junit4] 2> "node_name":null, [junit4] 2> "type":"NRT"}, [junit4] 2> "core_node5":{ [junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n2", [junit4] 2> "base_url":"http://127.0.0.1:40946/solr";, [junit4] 2> "state":"down", [junit4] 2> "node_name":null, [junit4] 2> "type":"NRT"}}} [junit4] 2> 617757 DEBUG (OverseerStateUpdate-74804243309395976-127.0.0.1:44565_solr-n_0000000000) [n:127.0.0.1:44565_solr ] o.a.s.c.o.SliceMutator New Slice: shard1:{ [junit4] 2> "range":"80000000-7fffffff", [junit4] 2> "state":"active", [junit4] 2> "replicas":{ [junit4] 2> "core_node3":{ [junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n1", [junit4] 2> "base_url":"http://127.0.0.1:40946/solr";, [junit4] 2> "state":"down", [junit4] 2> "node_name":null, [junit4] 2> "type":"NRT"}, [junit4] 2> "core_node5":{ [junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n2", [junit4] 2> "base_url":"http://127.0.0.1:40946/solr";, [junit4] 2> "state":"down", [junit4] 2> "node_name":null, [junit4] 2> "type":"NRT"}, [junit4] 2> "core_node6":{ [junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n4", [junit4] 2> "base_url":"http://127.0.0.1:40946/solr";, [junit4] 2> "state":"down", [junit4] 2> "node_name":null, [junit4] 2> "type":"NRT"}}} [junit4] 2> 617858 DEBUG (OverseerStateUpdate-74804243309395976-127.0.0.1:44565_solr-n_0000000000) [n:127.0.0.1:44565_solr ] o.a.s.c.o.ZkStateWriter going to update_collection /collections/AutoscalingHistoryHandlerTest_collection/state.json version: 0 [junit4] 2> 617968 INFO (qtp1573456457-4762) [n:127.0.0.1:40946_solr x:AutoscalingHistoryHandlerTest_collection_shard1_replica_n1] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node3&collection.configName=AutoscalingHistoryHandlerTest_collection.AUTOCREATED&newCollection=true&name=AutoscalingHistoryHandlerTest_collection_shard1_replica_n1&action=CREATE&numShards=1&collection=AutoscalingHistoryHandlerTest_collection&shard=shard1&wt=javabin&version=2&replicaType=NRT [junit4] 2> 617969 INFO (qtp1573456457-4763) [n:127.0.0.1:40946_solr x:AutoscalingHistoryHandlerTest_collection_shard1_replica_n2] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node5&collection.configName=AutoscalingHistoryHandlerTest_collection.AUTOCREATED&newCollection=true&name=AutoscalingHistoryHandlerTest_collection_shard1_replica_n2&action=CREATE&numShards=1&collection=AutoscalingHistoryHandlerTest_collection&shard=shard1&wt=javabin&version=2&replicaType=NRT [junit4] 2> 617972 INFO (qtp1573456457-4766) [n:127.0.0.1:40946_solr x:AutoscalingHistoryHandlerTest_collection_shard1_replica_n4] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node6&collection.configName=AutoscalingHistoryHandlerTest_collection.AUTOCREATED&newCollection=true&name=AutoscalingHistoryHandlerTest_collection_shard1_replica_n4&action=CREATE&numShards=1&collection=AutoscalingHistoryHandlerTest_collection&shard=shard1&wt=javabin&version=2&replicaType=NRT [junit4] 2> 617976 DEBUG (OverseerStateUpdate-74804243309395976-127.0.0.1:44565_solr-n_0000000000) [n:127.0.0.1:44565_solr ] o.a.s.c.Overseer processMessage: queueSize: 2, message = { [junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n1", [junit4] 2> "core_node_name":"core_node3", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:40946/solr";, [junit4] 2> "node_name":"127.0.0.1:40946_solr", [junit4] 2> "numShards":"1", [junit4] 2> "state":"down", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"AutoscalingHistoryHandlerTest_collection", [junit4] 2> "type":"NRT", [junit4] 2> "force_set_state":"false", [junit4] 2> "operation":"state"} current state version: 0 [junit4] 2> 617976 DEBUG (OverseerStateUpdate-74804243309395976-127.0.0.1:44565_solr-n_0000000000) [n:127.0.0.1:44565_solr ] o.a.s.c.o.ReplicaMutator Update state numShards=1 message={ [junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n1", [junit4] 2> "core_node_name":"core_node3", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:40946/solr";, [junit4] 2> "node_name":"127.0.0.1:40946_solr", [junit4] 2> "numShards":"1", [junit4] 2> "state":"down", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"AutoscalingHistoryHandlerTest_collection", [junit4] 2> "type":"NRT", [junit4] 2> "force_set_state":"false", [junit4] 2> "operation":"state"} [junit4] 2> 617976 DEBUG (OverseerStateUpdate-74804243309395976-127.0.0.1:44565_solr-n_0000000000) [n:127.0.0.1:44565_solr ] o.a.s.c.o.ReplicaMutator Will update state for replica: core_node3:{"core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n1","base_url":"http://127.0.0.1:40946/solr","node_name":"127.0.0.1:40946_solr","state":"down","type":"NRT","force_set_state":"false"} [junit4] 2> 617976 DEBUG (OverseerStateUpdate-74804243309395976-127.0.0.1:44565_solr-n_0000000000) [n:127.0.0.1:44565_solr ] o.a.s.c.o.ReplicaMutator Collection is now: DocCollection(AutoscalingHistoryHandlerTest_collection//collections/AutoscalingHistoryHandlerTest_collection/state.json/1)={ [junit4] 2> "pullReplicas":"0", [junit4] 2> "replicationFactor":"3", [junit4] 2> "router":{"name":"compositeId"}, [junit4] 2> "maxShardsPerNode":"3", [junit4] 2> "autoAddReplicas":"false", [junit4] 2> "nrtReplicas":"3", [junit4] 2> "tlogReplicas":"0", [junit4] 2> "shards":{"shard1":{ [junit4] 2> "range":"80000000-7fffffff", [junit4] 2> "state":"active", [junit4] 2> "replicas":{ [junit4] 2> "core_node3":{ [junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n1", [junit4] 2> "base_url":"http://127.0.0.1:40946/solr";, [junit4] 2> "node_name":"127.0.0.1:40946_solr", [junit4] 2> "state":"down", [junit4] 2> "type":"NRT", [junit4] 2> "force_set_state":"false"}, [junit4] 2> "core_node5":{ [junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n2", [junit4] 2> "base_url":"http://127.0.0.1:40946/solr";, [junit4] 2> "state":"down", [junit4] 2> "node_name":null, [junit4] 2> "type":"NRT"}, [junit4] 2> "core_node6":{ [junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n4", [junit4] 2> "base_url":"http://127.0.0.1:40946/solr";, [junit4] 2> "state":"down", [junit4] 2> "node_name":null, [junit4] 2> "type":"NRT"}}}}} [junit4] 2> 617977 DEBUG (OverseerStateUpdate-74804243309395976-127.0.0.1:44565_solr-n_0000000000) [n:127.0.0.1:44565_solr ] o.a.s.c.Overseer processMessage: queueSize: 2, message = { [junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n4", [junit4] 2> "core_node_name":"core_node6", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:40946/solr";, [junit4] 2> "node_name":"127.0.0.1:40946_solr", [junit4] 2> "numShards":"1", [junit4] 2> "state":"down", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"AutoscalingHistoryHandlerTest_collection", [junit4] 2> "type":"NRT", [junit4] 2> "force_set_state":"false", [junit4] 2> "operation":"state"} current state version: 0 [junit4] 2> 617977 DEBUG (OverseerStateUpdate-74804243309395976-127.0.0.1:44565_solr-n_0000000000) [n:127.0.0.1:44565_solr ] o.a.s.c.o.ReplicaMutator Update state numShards=1 message={ [junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n4", [junit4] 2> "core_node_name":"core_node6", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:40946/solr";, [junit4] 2> "node_name":"127.0.0.1:40946_solr", [junit4] 2> "numShards":"1", [junit4] 2> "state":"down", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"AutoscalingHistoryHandlerTest_collection", [junit4] 2> "type":"NRT", [junit4] 2> "force_set_state":"false", [junit4] 2> "operation":"state"} [junit4] 2> 617977 DEBUG (OverseerStateUpdate-74804243309395976-127.0.0.1:44565_solr-n_0000000000) [n:127.0.0.1:44565_solr ] o.a.s.c.o.ReplicaMutator Will update state for replica: core_node6:{"core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n4","base_url":"http://127.0.0.1:40946/solr","node_name":"127.0.0.1:40946_solr","state":"down","type":"NRT","force_set_state":"false"} [junit4] 2> 617977 DEBUG (OverseerStateUpdate-74804243309395976-127.0.0.1:44565_solr-n_0000000000) [n:127.0.0.1:44565_solr ] o.a.s.c.o.ReplicaMutator Collection is now: DocCollection(AutoscalingHistoryHandlerTest_collection//collections/AutoscalingHistoryHandlerTest_collection/state.json/1)={ [junit4] 2> "pullReplicas":"0", [junit4] 2> "replicationFactor":"3", [junit4] 2> "router":{"name":"compositeId"}, [junit4] 2> "maxShardsPerNode":"3", [junit4] 2> "autoAddReplicas":"false", [junit4] 2> "nrtReplicas":"3", [junit4] 2> "tlogReplicas":"0", [junit4] 2> "shards":{"shard1":{ [junit4] 2> "range":"80000000-7fffffff", [junit4] 2> "state":"active", [junit4] 2> "replicas":{ [junit4] 2> "core_node3":{ [junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n1", [junit4] 2> "base_url":"http://127.0.0.1:40946/solr";, [junit4] 2> "node_name":"127.0.0.1:40946_solr", [junit4] 2> "state":"down", [junit4] 2> "type":"NRT", [junit4] 2> "force_set_state":"false"}, [junit4] 2> "core_node5":{ [junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n2", [junit4] 2> "base_url":"http://127.0.0.1:40946/solr";, [junit4] 2> "state":"down", [junit4] 2> "node_name":null, [junit4] 2> "type":"NRT"}, [junit4] 2> "core_node6":{ [junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n4", [junit4] 2> "base_url":"http://127.0.0.1:40946/solr";, [junit4] 2> "node_name":"127.0.0.1:40946_solr", [junit4] 2> "state":"down", [junit4] 2> "type":"NRT", [junit4] 2> "force_set_state":"false"}}}}} [junit4] 2> 617978 DEBUG (OverseerStateUpdate-74804243309395976-127.0.0.1:44565_solr-n_0000000000) [n:127.0.0.1:44565_solr ] o.a.s.c.Overseer processMessage: queueSize: 3, message = { [junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n2", [junit4] 2> "core_node_name":"core_node5", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:40946/solr";, [junit4] 2> "node_name":"127.0.0.1:40946_solr", [junit4] 2> "numShards":"1", [junit4] 2> "state":"down", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"AutoscalingHistoryHandlerTest_collection", [junit4] 2> "type":"NRT", [junit4] 2> "force_set_state":"false", [junit4] 2> "operation":"state"} current state version: 0 [junit4] 2> 617978 DEBUG (OverseerStateUpdate-74804243309395976-127.0.0.1:44565_solr-n_0000000000) [n:127.0.0.1:44565_solr ] o.a.s.c.o.ReplicaMutator Update state numShards=1 message={ [junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n2", [junit4] 2> "core_node_name":"core_node5", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:40946/solr";, [junit4] 2> "node_name":"127.0.0.1:40946_solr", [junit4] 2> "numShards":"1", [junit4] 2> "state":"down", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"AutoscalingHistoryHandlerTest_collection", [junit4] 2> "type":"NRT", [junit4] 2> "force_set_state":"false", [junit4] 2> "operation":"state"} [junit4] 2> 617978 DEBUG (OverseerStateUpdate-74804243309395976-127.0.0.1:44565_solr-n_0000000000) [n:127.0.0.1:44565_solr ] o.a.s.c.o.ReplicaMutator Will update state for replica: core_node5:{"core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n2","base_url":"http://127.0.0.1:40946/solr","node_name":"127.0.0.1:40946_solr","state":"down","type":"NRT","force_set_state":"false"} [junit4] 2> 617978 DEBUG (OverseerStateUpdate-74804243309395976-127.0.0.1:44565_solr-n_0000000000) [n:127.0.0.1:44565_solr ] o.a.s.c.o.ReplicaMutator Collection is now: DocCollection(AutoscalingHistoryHandlerTest_collection//collections/AutoscalingHistoryHandlerTest_collection/state.json/1)={ [junit4] 2> "pullReplicas":"0", [junit4] 2> "replicationFactor":"3", [junit4] 2> "router":{"name":"compositeId"}, [junit4] 2> "maxShardsPerNode":"3", [junit4] 2> "autoAddReplicas":"false", [junit4] 2> "nrtReplicas":"3", [junit4] 2> "tlogReplicas":"0", [junit4] 2> "shards":{"shard1":{ [junit4] 2> "range":"80000000-7fffffff", [junit4] 2> "state":"active", [junit4] 2> "replicas":{ [junit4] 2> "core_node3":{ [junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n1", [junit4] 2> "base_url":"http://127.0.0.1:40946/solr";, [junit4] 2> "node_name":"127.0.0.1:40946_solr", [junit4] 2> "state":"down", [junit4] 2> "type":"NRT", [junit4] 2> "force_set_state":"false"}, [junit4] 2> "core_node5":{ [junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n2", [junit4] 2> "base_url":"http://127.0.0.1:40946/solr";, [junit4] 2> "node_name":"127.0.0.1:40946_solr", [junit4] 2> "state":"down", [junit4] 2> "type":"NRT", [junit4] 2> "force_set_state":"false"}, [junit4] 2> "core_node6":{ [junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n4", [junit4] 2> "base_url":"http://127.0.0.1:40946/solr";, [junit4] 2> "node_name":"127.0.0.1:40946_solr", [junit4] 2> "state":"down", [junit4] 2> "type":"NRT", [junit4] 2> "force_set_state":"false"}}}}} [junit4] 2> 618079 DEBUG (OverseerStateUpdate-74804243309395976-127.0.0.1:44565_solr-n_0000000000) [n:127.0.0.1:44565_solr ] o.a.s.c.o.ZkStateWriter going to update_collection /collections/AutoscalingHistoryHandlerTest_collection/state.json version: 1 [junit4] 2> 618350 DEBUG (ScheduledTrigger-970-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2 [junit4] 2> 618998 INFO (qtp1573456457-4762) [n:127.0.0.1:40946_solr c:AutoscalingHistoryHandlerTest_collection s:shard1 r:core_node3 x:AutoscalingHistoryHandlerTest_collection_shard1_replica_n1] o.a.s.c.RequestParams conf resource params.json loaded . version : 0 [junit4] 2> 618998 INFO (qtp1573456457-4762) [n:127.0.0.1:40946_solr c:AutoscalingHistoryHandlerTest_collection s:shard1 r:core_node3 x:AutoscalingHistoryHandlerTest_collection_shard1_replica_n1] o.a.s.c.RequestParams request params refreshed to version 0 [junit4] 2> 618998 WARN (qtp1573456457-4762) [n:127.0.0.1:40946_solr c:AutoscalingHistoryHandlerTest_collection s:shard1 r:core_node3 x:AutoscalingHistoryHandlerTest_collection_shard1_replica_n1] o.a.s.c.SolrConfig Couldn't add files from /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.7/solr/build/solr-core/test/J0/temp/solr.handler.admin.AutoscalingHistoryHandlerTest_E36EE48FAF16F228-001/tempDir-001/node2/AutoscalingHistoryHandlerTest_collection_shard1_replica_n1/../../../../contrib/extraction/lib filtered by .*\.jar to classpath: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.7/solr/build/solr-core/test/J0/temp/solr.handler.admin.AutoscalingHistoryHandlerTest_E36EE48FAF16F228-001/tempDir-001/node2/AutoscalingHistoryHandlerTest_collection_shard1_replica_n1/../../../../contrib/extraction/lib [junit4] 2> 618999 WARN (qtp1573456457-4762) [n:127.0.0.1:40946_solr c:AutoscalingHistoryHandlerTest_collection s:shard1 r:core_node3 x:AutoscalingHistoryHandlerTest_collection_shard1_replica_n1] o.a.s.c.SolrConfig Couldn't add files from /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.7/solr/build/solr-core/test/J0/temp/solr.handler.admin.AutoscalingHistoryHandlerTest_E36EE48FAF16F228-001/tempDir-001/node2/AutoscalingHistoryHandlerTest_collection_shard1_replica_n1/../../../../dist filtered by solr-cell-\d.*\.jar to classpath: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.7/solr/build/solr-core/test/J0/temp/solr.handler.admin.AutoscalingHistoryHandlerTest_E36EE48FAF16F228-001/tempDir-001/node2/AutoscalingHistoryHandlerTest_collection_shard1_replica_n1/../../../../dist [junit4] 2> 619000 WARN (qtp1573456457-4762) [n:127.0.0.1:40946_solr c:AutoscalingHistoryHandlerTest_collection s:shard1 r:core_node3 x:AutoscalingHistoryHandler [...truncated too long message...] -ivy-fail-disallowed-ivy-version: ivy-fail: ivy-fail: ivy-configure: [ivy:configure] :: loading settings :: file = /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.7/lucene/top-level-ivy-settings.xml resolve: ivy-availability-check: [loadresource] Do not set property disallowed.ivy.jars.list as its length is 0. -ivy-fail-disallowed-ivy-version: ivy-fail: ivy-configure: [ivy:configure] :: loading settings :: file = /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.7/lucene/top-level-ivy-settings.xml resolve: ivy-availability-check: [loadresource] Do not set property disallowed.ivy.jars.list as its length is 0. -ivy-fail-disallowed-ivy-version: ivy-fail: ivy-fail: ivy-configure: [ivy:configure] :: loading settings :: file = /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.7/lucene/top-level-ivy-settings.xml resolve: ivy-availability-check: [loadresource] Do not set property disallowed.ivy.jars.list as its length is 0. -ivy-fail-disallowed-ivy-version: ivy-fail: ivy-configure: [ivy:configure] :: loading settings :: file = /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.7/lucene/top-level-ivy-settings.xml resolve: ivy-availability-check: [loadresource] Do not set property disallowed.ivy.jars.list as its length is 0. -ivy-fail-disallowed-ivy-version: ivy-fail: ivy-fail: ivy-configure: [ivy:configure] :: loading settings :: file = /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.7/lucene/top-level-ivy-settings.xml resolve: ivy-availability-check: [loadresource] Do not set property disallowed.ivy.jars.list as its length is 0. -ivy-fail-disallowed-ivy-version: ivy-fail: ivy-fail: ivy-configure: [ivy:configure] :: loading settings :: file = /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.7/lucene/top-level-ivy-settings.xml resolve: ivy-availability-check: [loadresource] Do not set property disallowed.ivy.jars.list as its length is 0. -ivy-fail-disallowed-ivy-version: ivy-fail: ivy-fail: ivy-configure: [ivy:configure] :: loading settings :: file = /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.7/lucene/top-level-ivy-settings.xml resolve: ivy-availability-check: [loadresource] Do not set property disallowed.ivy.jars.list as its length is 0. -ivy-fail-disallowed-ivy-version: ivy-fail: ivy-fail: ivy-configure: [ivy:configure] :: loading settings :: file = /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.7/lucene/top-level-ivy-settings.xml resolve: ivy-availability-check: [loadresource] Do not set property disallowed.ivy.jars.list as its length is 0. -ivy-fail-disallowed-ivy-version: ivy-fail: ivy-fail: ivy-configure: [ivy:configure] :: loading settings :: file = /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.7/lucene/top-level-ivy-settings.xml resolve: ivy-availability-check: [loadresource] Do not set property disallowed.ivy.jars.list as its length is 0. -ivy-fail-disallowed-ivy-version: ivy-fail: ivy-fail: ivy-configure: [ivy:configure] :: loading settings :: file = /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.7/lucene/top-level-ivy-settings.xml resolve: ivy-availability-check: [loadresource] Do not set property disallowed.ivy.jars.list as its length is 0. -ivy-fail-disallowed-ivy-version: ivy-fail: ivy-fail: ivy-configure: [ivy:configure] :: loading settings :: file = /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.7/lucene/top-level-ivy-settings.xml resolve: ivy-availability-check: [loadresource] Do not set property disallowed.ivy.jars.list as its length is 0. -ivy-fail-disallowed-ivy-version: ivy-fail: ivy-fail: ivy-configure: [ivy:configure] :: loading settings :: file = /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.7/lucene/top-level-ivy-settings.xml resolve: ivy-availability-check: [loadresource] Do not set property disallowed.ivy.jars.list as its length is 0. -ivy-fail-disallowed-ivy-version: ivy-fail: ivy-fail: ivy-configure: [ivy:configure] :: loading settings :: file = /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.7/lucene/top-level-ivy-settings.xml resolve: ivy-availability-check: [loadresource] Do not set property disallowed.ivy.jars.list as its length is 0. -ivy-fail-disallowed-ivy-version: ivy-fail: ivy-fail: ivy-configure: [ivy:configure] :: loading settings :: file = /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.7/lucene/top-level-ivy-settings.xml resolve: jar-checksums: [mkdir] Created dir: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.7/solr/null963026573 [copy] Copying 242 files to /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.7/solr/null963026573 [delete] Deleting directory /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.7/solr/null963026573 check-working-copy: [ivy:cachepath] :: resolving dependencies :: org.eclipse.jgit#org.eclipse.jgit-caller;working [ivy:cachepath] confs: [default] [ivy:cachepath] found org.eclipse.jgit#org.eclipse.jgit;4.6.0.201612231935-r in public [ivy:cachepath] found com.jcraft#jsch;0.1.53 in public [ivy:cachepath] found com.googlecode.javaewah#JavaEWAH;1.1.6 in public [ivy:cachepath] found org.apache.httpcomponents#httpclient;4.3.6 in public [ivy:cachepath] found org.apache.httpcomponents#httpcore;4.3.3 in public [ivy:cachepath] found commons-logging#commons-logging;1.1.3 in public [ivy:cachepath] found commons-codec#commons-codec;1.6 in public [ivy:cachepath] found org.slf4j#slf4j-api;1.7.2 in public [ivy:cachepath] :: resolution report :: resolve 87ms :: artifacts dl 15ms --------------------------------------------------------------------- | | modules || artifacts | | conf | number| search|dwnlded|evicted|| number|dwnlded| --------------------------------------------------------------------- | default | 8 | 0 | 0 | 0 || 8 | 0 | --------------------------------------------------------------------- [wc-checker] Initializing working copy... [wc-checker] SLF4J: Failed to load class "org.slf4j.impl.StaticLoggerBinder". [wc-checker] SLF4J: Defaulting to no-operation (NOP) logger implementation [wc-checker] SLF4J: See http://www.slf4j.org/codes.html#StaticLoggerBinder for further details. [wc-checker] Checking working copy status... -jenkins-base: BUILD SUCCESSFUL Total time: 162 minutes 31 seconds Archiving artifacts java.lang.InterruptedException: no matches found within 10000 at hudson.FilePath$ValidateAntFileMask.hasMatch(FilePath.java:2847) at hudson.FilePath$ValidateAntFileMask.invoke(FilePath.java:2726) at hudson.FilePath$ValidateAntFileMask.invoke(FilePath.java:2707) at hudson.FilePath$FileCallableWrapper.call(FilePath.java:3086) Also: hudson.remoting.Channel$CallSiteStackTrace: Remote call to lucene2 at hudson.remoting.Channel.attachCallSiteStackTrace(Channel.java:1741) at hudson.remoting.UserRequest$ExceptionResponse.retrieve(UserRequest.java:357) at hudson.remoting.Channel.call(Channel.java:955) at hudson.FilePath.act(FilePath.java:1072) at hudson.FilePath.act(FilePath.java:1061) at hudson.FilePath.validateAntFileMask(FilePath.java:2705) at hudson.tasks.ArtifactArchiver.perform(ArtifactArchiver.java:243) at hudson.tasks.BuildStepCompatibilityLayer.perform(BuildStepCompatibilityLayer.java:81) at hudson.tasks.BuildStepMonitor$1.perform(BuildStepMonitor.java:20) at hudson.model.AbstractBuild$AbstractBuildExecution.perform(AbstractBuild.java:744) at hudson.model.AbstractBuild$AbstractBuildExecution.performAllBuildSteps(AbstractBuild.java:690) at hudson.model.Build$BuildExecution.post2(Build.java:186) at hudson.model.AbstractBuild$AbstractBuildExecution.post(AbstractBuild.java:635) at hudson.model.Run.execute(Run.java:1835) at hudson.model.FreeStyleBuild.run(FreeStyleBuild.java:43) at hudson.model.ResourceController.execute(ResourceController.java:97) at hudson.model.Executor.run(Executor.java:429) Caused: hudson.FilePath$TunneledInterruptedException at hudson.FilePath$FileCallableWrapper.call(FilePath.java:3088) at hudson.remoting.UserRequest.perform(UserRequest.java:212) at hudson.remoting.UserRequest.perform(UserRequest.java:54) at hudson.remoting.Request$2.run(Request.java:369) at hudson.remoting.InterceptingExecutorService$1.call(InterceptingExecutorService.java:72) at java.util.concurrent.FutureTask.run(FutureTask.java:266) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617) at java.lang.Thread.run(Thread.java:748) Caused: java.lang.InterruptedException: java.lang.InterruptedException: no matches found within 10000 at hudson.FilePath.act(FilePath.java:1074) at hudson.FilePath.act(FilePath.java:1061) at hudson.FilePath.validateAntFileMask(FilePath.java:2705) at hudson.tasks.ArtifactArchiver.perform(ArtifactArchiver.java:243) at hudson.tasks.BuildStepCompatibilityLayer.perform(BuildStepCompatibilityLayer.java:81) at hudson.tasks.BuildStepMonitor$1.perform(BuildStepMonitor.java:20) at hudson.model.AbstractBuild$AbstractBuildExecution.perform(AbstractBuild.java:744) at hudson.model.AbstractBuild$AbstractBuildExecution.performAllBuildSteps(AbstractBuild.java:690) at hudson.model.Build$BuildExecution.post2(Build.java:186) at hudson.model.AbstractBuild$AbstractBuildExecution.post(AbstractBuild.java:635) at hudson.model.Run.execute(Run.java:1835) at hudson.model.FreeStyleBuild.run(FreeStyleBuild.java:43) at hudson.model.ResourceController.execute(ResourceController.java:97) at hudson.model.Executor.run(Executor.java:429) No artifacts found that match the file pattern "**/*.events,heapdumps/**,**/hs_err_pid*". Configuration error? Recording test results Build step 'Publish JUnit test result report' changed build result to UNSTABLE Email was triggered for: Unstable (Test Failures) Sending email for trigger: Unstable (Test Failures)
--------------------------------------------------------------------- To unsubscribe, e-mail: dev-unsubscr...@lucene.apache.org For additional commands, e-mail: dev-h...@lucene.apache.org
