You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@lucene.apache.org by Policeman Jenkins Server <je...@thetaphi.de> on 2017/10/22 13:04:31 UTC
[JENKINS] Lucene-Solr-master-Windows (64bit/jdk1.8.0_144) - Build #
6972 - Still Unstable!
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Windows/6972/
Java: 64bit/jdk1.8.0_144 -XX:-UseCompressedOops -XX:+UseConcMarkSweepGC
1 tests failed.
FAILED: org.apache.solr.handler.admin.AutoscalingHistoryHandlerTest.testHistory
Error Message:
action did not execute
Stack Trace:
java.lang.AssertionError: action did not execute
at __randomizedtesting.SeedInfo.seed([8C9762697070A327:E16BC694CA385C20]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.junit.Assert.assertTrue(Assert.java:43)
at org.apache.solr.handler.admin.AutoscalingHistoryHandlerTest.testHistory(AutoscalingHistoryHandlerTest.java:260)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1737)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:943)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.lang.Thread.run(Thread.java:748)
Build Log:
[...truncated 14018 lines...]
[junit4] Suite: org.apache.solr.handler.admin.AutoscalingHistoryHandlerTest
[junit4] 2> 3538454 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[8C9762697070A327]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> Creating dataDir: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.handler.admin.AutoscalingHistoryHandlerTest_8C9762697070A327-001\init-core-data-001
[junit4] 2> 3538455 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[8C9762697070A327]-worker) [ ] o.a.s.SolrTestCaseJ4 Using TrieFields (NUMERIC_POINTS_SYSPROP=false) w/NUMERIC_DOCVALUES_SYSPROP=false
[junit4] 2> 3538460 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[8C9762697070A327]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason=, value=NaN, ssl=NaN, clientAuth=NaN)
[junit4] 2> 3538461 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[8C9762697070A327]-worker) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 3 servers in C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.handler.admin.AutoscalingHistoryHandlerTest_8C9762697070A327-001\tempDir-001
[junit4] 2> 3538461 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[8C9762697070A327]-worker) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 3538463 INFO (Thread-5066) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 3538463 INFO (Thread-5066) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 3538467 ERROR (Thread-5066) [ ] o.a.z.s.ZooKeeperServer ZKShutdownHandler is not registered, so ZooKeeper server won't take any action on ERROR or SHUTDOWN server state changes
[junit4] 2> 3538561 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[8C9762697070A327]-worker) [ ] o.a.s.c.ZkTestServer start zk server on port:58033
[junit4] 2> 3538566 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ] o.a.z.s.NIOServerCnxn caught end of stream exception
[junit4] 2> EndOfStreamException: Unable to read additional data from client sessionid 0x15f441145ce0000, likely client has closed socket
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:239)
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:203)
[junit4] 2> at java.lang.Thread.run(Thread.java:748)
[junit4] 2> 3538567 INFO (jetty-launcher-6257-thread-1) [ ] o.e.j.s.Server jetty-9.3.20.v20170531
[junit4] 2> 3538567 INFO (jetty-launcher-6257-thread-2) [ ] o.e.j.s.Server jetty-9.3.20.v20170531
[junit4] 2> 3538568 INFO (jetty-launcher-6257-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@5576e12e{/solr,null,AVAILABLE}
[junit4] 2> 3538568 INFO (jetty-launcher-6257-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@113e74d{/solr,null,AVAILABLE}
[junit4] 2> 3538568 INFO (jetty-launcher-6257-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@abc55b2{HTTP/1.1,[http/1.1]}{127.0.0.1:58038}
[junit4] 2> 3538568 INFO (jetty-launcher-6257-thread-1) [ ] o.e.j.s.Server Started @3548977ms
[junit4] 2> 3538568 INFO (jetty-launcher-6257-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=58038}
[junit4] 2> 3538569 ERROR (jetty-launcher-6257-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 3538569 INFO (jetty-launcher-6257-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@1de64ee9{HTTP/1.1,[http/1.1]}{127.0.0.1:58041}
[junit4] 2> 3538569 INFO (jetty-launcher-6257-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.0.0
[junit4] 2> 3538569 INFO (jetty-launcher-6257-thread-2) [ ] o.e.j.s.Server Started @3548977ms
[junit4] 2> 3538569 INFO (jetty-launcher-6257-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 3538569 INFO (jetty-launcher-6257-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=58041}
[junit4] 2> 3538569 INFO (jetty-launcher-6257-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null, Default config dir: null
[junit4] 2> 3538569 INFO (jetty-launcher-6257-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-10-22T12:31:43.669Z
[junit4] 2> 3538569 ERROR (jetty-launcher-6257-thread-2) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 3538569 INFO (jetty-launcher-6257-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.0.0
[junit4] 2> 3538569 INFO (jetty-launcher-6257-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 3538569 INFO (jetty-launcher-6257-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null, Default config dir: null
[junit4] 2> 3538569 INFO (jetty-launcher-6257-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-10-22T12:31:43.669Z
[junit4] 2> 3538570 INFO (jetty-launcher-6257-thread-3) [ ] o.e.j.s.Server jetty-9.3.20.v20170531
[junit4] 2> 3538572 INFO (jetty-launcher-6257-thread-3) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@58878230{/solr,null,AVAILABLE}
[junit4] 2> 3538577 INFO (jetty-launcher-6257-thread-3) [ ] o.e.j.s.AbstractConnector Started ServerConnector@1b0ae4f0{HTTP/1.1,[http/1.1]}{127.0.0.1:58050}
[junit4] 2> 3538577 INFO (jetty-launcher-6257-thread-3) [ ] o.e.j.s.Server Started @3548985ms
[junit4] 2> 3538577 INFO (jetty-launcher-6257-thread-3) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=58050}
[junit4] 2> 3538577 ERROR (jetty-launcher-6257-thread-3) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 3538578 INFO (jetty-launcher-6257-thread-3) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.0.0
[junit4] 2> 3538578 INFO (jetty-launcher-6257-thread-3) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 3538578 INFO (jetty-launcher-6257-thread-3) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null, Default config dir: null
[junit4] 2> 3538578 INFO (jetty-launcher-6257-thread-3) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-10-22T12:31:43.678Z
[junit4] 2> 3538578 INFO (jetty-launcher-6257-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 3538579 INFO (jetty-launcher-6257-thread-2) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 3538590 INFO (jetty-launcher-6257-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:58033/solr
[junit4] 2> 3538590 INFO (jetty-launcher-6257-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:58033/solr
[junit4] 2> 3538590 INFO (jetty-launcher-6257-thread-3) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 3538594 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ] o.a.z.s.NIOServerCnxn caught end of stream exception
[junit4] 2> EndOfStreamException: Unable to read additional data from client sessionid 0x15f441145ce0004, likely client has closed socket
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:239)
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:203)
[junit4] 2> at java.lang.Thread.run(Thread.java:748)
[junit4] 2> 3538595 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ] o.a.z.s.NIOServerCnxn caught end of stream exception
[junit4] 2> EndOfStreamException: Unable to read additional data from client sessionid 0x15f441145ce0003, likely client has closed socket
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:239)
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:203)
[junit4] 2> at java.lang.Thread.run(Thread.java:748)
[junit4] 2> 3538598 INFO (jetty-launcher-6257-thread-3) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:58033/solr
[junit4] 2> 3538601 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ] o.a.z.s.NIOServerCnxn caught end of stream exception
[junit4] 2> EndOfStreamException: Unable to read additional data from client sessionid 0x15f441145ce0008, likely client has closed socket
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:239)
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:203)
[junit4] 2> at java.lang.Thread.run(Thread.java:748)
[junit4] 2> 3538714 INFO (jetty-launcher-6257-thread-1) [n:127.0.0.1:58038_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 3538715 INFO (jetty-launcher-6257-thread-1) [n:127.0.0.1:58038_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:58038_solr
[junit4] 2> 3538716 INFO (jetty-launcher-6257-thread-1) [n:127.0.0.1:58038_solr ] o.a.s.c.Overseer Overseer (id=98872557801701382-127.0.0.1:58038_solr-n_0000000000) starting
[junit4] 2> 3538724 INFO (jetty-launcher-6257-thread-2) [n:127.0.0.1:58041_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 3538725 INFO (jetty-launcher-6257-thread-3) [n:127.0.0.1:58050_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 3538725 INFO (jetty-launcher-6257-thread-1) [n:127.0.0.1:58038_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:58038_solr
[junit4] 2> 3538725 INFO (jetty-launcher-6257-thread-2) [n:127.0.0.1:58041_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:58041_solr
[junit4] 2> 3538725 INFO (zkCallback-6272-thread-1-processing-n:127.0.0.1:58041_solr) [n:127.0.0.1:58041_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 3538725 INFO (zkCallback-6269-thread-1-processing-n:127.0.0.1:58038_solr) [n:127.0.0.1:58038_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 3538725 INFO (zkCallback-6274-thread-1-processing-n:127.0.0.1:58050_solr) [n:127.0.0.1:58050_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 3538727 INFO (zkCallback-6269-thread-1-processing-n:127.0.0.1:58038_solr) [n:127.0.0.1:58038_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 3538727 INFO (zkCallback-6274-thread-1-processing-n:127.0.0.1:58050_solr) [n:127.0.0.1:58050_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 3538727 INFO (zkCallback-6272-thread-1-processing-n:127.0.0.1:58041_solr) [n:127.0.0.1:58041_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 3538727 INFO (jetty-launcher-6257-thread-3) [n:127.0.0.1:58050_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:58050_solr
[junit4] 2> 3538778 DEBUG (OverseerAutoScalingTriggerThread-98872557801701382-127.0.0.1:58038_solr-n_0000000000) [n:127.0.0.1:58038_solr ] o.a.s.c.a.OverseerTriggerThread Adding .autoAddReplicas trigger
[junit4] 2> 3538779 DEBUG (OverseerStateUpdate-98872557801701382-127.0.0.1:58038_solr-n_0000000000) [n:127.0.0.1:58038_solr ] o.a.s.c.Overseer Starting to work on the main queue
[junit4] 2> 3538781 INFO (zkCallback-6274-thread-1-processing-n:127.0.0.1:58050_solr) [n:127.0.0.1:58050_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 3538781 INFO (zkCallback-6272-thread-1-processing-n:127.0.0.1:58041_solr) [n:127.0.0.1:58041_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 3538781 DEBUG (OverseerAutoScalingTriggerThread-98872557801701382-127.0.0.1:58038_solr-n_0000000000) [n:127.0.0.1:58038_solr ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 1
[junit4] 2> 3538782 DEBUG (OverseerAutoScalingTriggerThread-98872557801701382-127.0.0.1:58038_solr-n_0000000000) [n:127.0.0.1:58038_solr ] o.a.s.c.a.NodeLostTrigger Initial livenodes: [127.0.0.1:58038_solr, 127.0.0.1:58041_solr]
[junit4] 2> 3538782 DEBUG (OverseerAutoScalingTriggerThread-98872557801701382-127.0.0.1:58038_solr-n_0000000000) [n:127.0.0.1:58038_solr ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1, lastZnodeVersion -1
[junit4] 2> 3538782 DEBUG (OverseerAutoScalingTriggerThread-98872557801701382-127.0.0.1:58038_solr-n_0000000000) [n:127.0.0.1:58038_solr ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 1
[junit4] 2> 3538783 INFO (OverseerStateUpdate-98872557801701382-127.0.0.1:58038_solr-n_0000000000) [n:127.0.0.1:58038_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 3538786 DEBUG (OverseerStateUpdate-98872557801701382-127.0.0.1:58038_solr-n_0000000000) [n:127.0.0.1:58038_solr ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
[junit4] 2> "operation":"downnode",
[junit4] 2> "node_name":"127.0.0.1:58050_solr"} current state version: 0
[junit4] 2> 3538786 DEBUG (OverseerStateUpdate-98872557801701382-127.0.0.1:58038_solr-n_0000000000) [n:127.0.0.1:58038_solr ] o.a.s.c.o.NodeMutator DownNode state invoked for node: 127.0.0.1:58050_solr
[junit4] 2> 3538787 DEBUG (OverseerAutoScalingTriggerThread-98872557801701382-127.0.0.1:58038_solr-n_0000000000) [n:127.0.0.1:58038_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers
[junit4] 2> 3538787 DEBUG (OverseerAutoScalingTriggerThread-98872557801701382-127.0.0.1:58038_solr-n_0000000000) [n:127.0.0.1:58038_solr ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1, lastZnodeVersion 1
[junit4] 2> 3538819 DEBUG (ScheduledTrigger-10102-thread-1) [n:127.0.0.1:58038_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: [127.0.0.1:58038_solr, 127.0.0.1:58050_solr, 127.0.0.1:58041_solr]
[junit4] 2> 3538865 INFO (jetty-launcher-6257-thread-3) [n:127.0.0.1:58050_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_58050.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@27254fb8
[junit4] 2> 3538866 INFO (jetty-launcher-6257-thread-2) [n:127.0.0.1:58041_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_58041.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@27254fb8
[junit4] 2> 3538871 INFO (jetty-launcher-6257-thread-1) [n:127.0.0.1:58038_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_58038.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@27254fb8
[junit4] 2> 3538874 INFO (jetty-launcher-6257-thread-3) [n:127.0.0.1:58050_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_58050.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@27254fb8
[junit4] 2> 3538874 INFO (jetty-launcher-6257-thread-3) [n:127.0.0.1:58050_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_58050.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@27254fb8
[junit4] 2> 3538874 INFO (jetty-launcher-6257-thread-2) [n:127.0.0.1:58041_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_58041.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@27254fb8
[junit4] 2> 3538874 INFO (jetty-launcher-6257-thread-2) [n:127.0.0.1:58041_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_58041.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@27254fb8
[junit4] 2> 3538875 INFO (jetty-launcher-6257-thread-3) [n:127.0.0.1:58050_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.handler.admin.AutoscalingHistoryHandlerTest_8C9762697070A327-001\tempDir-001\node3\.
[junit4] 2> 3538875 INFO (jetty-launcher-6257-thread-2) [n:127.0.0.1:58041_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.handler.admin.AutoscalingHistoryHandlerTest_8C9762697070A327-001\tempDir-001\node2\.
[junit4] 2> 3538878 INFO (jetty-launcher-6257-thread-1) [n:127.0.0.1:58038_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_58038.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@27254fb8
[junit4] 2> 3538878 INFO (jetty-launcher-6257-thread-1) [n:127.0.0.1:58038_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_58038.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@27254fb8
[junit4] 2> 3538879 INFO (jetty-launcher-6257-thread-1) [n:127.0.0.1:58038_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.handler.admin.AutoscalingHistoryHandlerTest_8C9762697070A327-001\tempDir-001\node1\.
[junit4] 2> 3538950 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[8C9762697070A327]-worker) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3)
[junit4] 2> 3538951 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[8C9762697070A327]-worker) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:58033/solr ready
[junit4] 2> 3538956 INFO (qtp2083164565-26704) [n:127.0.0.1:58038_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params replicationFactor=3&name=AutoscalingHistoryHandlerTest_collection&nrtReplicas=3&action=CREATE&numShards=1&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 3538958 INFO (OverseerThreadFactory-10105-thread-1-processing-n:127.0.0.1:58038_solr) [n:127.0.0.1:58038_solr ] o.a.s.c.CreateCollectionCmd Create collection AutoscalingHistoryHandlerTest_collection
[junit4] 2> 3538960 INFO (OverseerThreadFactory-10105-thread-1-processing-n:127.0.0.1:58038_solr) [n:127.0.0.1:58038_solr ] o.a.s.c.CreateCollectionCmd Only _default config set found, using it.
[junit4] 2> 3538960 INFO (OverseerThreadFactory-10105-thread-1-processing-n:127.0.0.1:58038_solr) [n:127.0.0.1:58038_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/managed-schema to /configs/AutoscalingHistoryHandlerTest_collection/managed-schema
[junit4] 2> 3538962 INFO (OverseerThreadFactory-10105-thread-1-processing-n:127.0.0.1:58038_solr) [n:127.0.0.1:58038_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/protwords.txt to /configs/AutoscalingHistoryHandlerTest_collection/protwords.txt
[junit4] 2> 3538963 INFO (OverseerThreadFactory-10105-thread-1-processing-n:127.0.0.1:58038_solr) [n:127.0.0.1:58038_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/solrconfig.xml to /configs/AutoscalingHistoryHandlerTest_collection/solrconfig.xml
[junit4] 2> 3538966 INFO (OverseerThreadFactory-10105-thread-1-processing-n:127.0.0.1:58038_solr) [n:127.0.0.1:58038_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/synonyms.txt to /configs/AutoscalingHistoryHandlerTest_collection/synonyms.txt
[junit4] 2> 3538967 INFO (OverseerThreadFactory-10105-thread-1-processing-n:127.0.0.1:58038_solr) [n:127.0.0.1:58038_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/stopwords.txt to /configs/AutoscalingHistoryHandlerTest_collection/stopwords.txt
[junit4] 2> 3538969 INFO (OverseerThreadFactory-10105-thread-1-processing-n:127.0.0.1:58038_solr) [n:127.0.0.1:58038_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/contractions_it.txt to /configs/AutoscalingHistoryHandlerTest_collection/lang/contractions_it.txt
[junit4] 2> 3538971 INFO (OverseerThreadFactory-10105-thread-1-processing-n:127.0.0.1:58038_solr) [n:127.0.0.1:58038_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/contractions_ca.txt to /configs/AutoscalingHistoryHandlerTest_collection/lang/contractions_ca.txt
[junit4] 2> 3538973 INFO (OverseerThreadFactory-10105-thread-1-processing-n:127.0.0.1:58038_solr) [n:127.0.0.1:58038_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stemdict_nl.txt to /configs/AutoscalingHistoryHandlerTest_collection/lang/stemdict_nl.txt
[junit4] 2> 3538975 INFO (OverseerThreadFactory-10105-thread-1-processing-n:127.0.0.1:58038_solr) [n:127.0.0.1:58038_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_hy.txt to /configs/AutoscalingHistoryHandlerTest_collection/lang/stopwords_hy.txt
[junit4] 2> 3538977 INFO (OverseerThreadFactory-10105-thread-1-processing-n:127.0.0.1:58038_solr) [n:127.0.0.1:58038_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_no.txt to /configs/AutoscalingHistoryHandlerTest_collection/lang/stopwords_no.txt
[junit4] 2> 3538979 INFO (OverseerThreadFactory-10105-thread-1-processing-n:127.0.0.1:58038_solr) [n:127.0.0.1:58038_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_id.txt to /configs/AutoscalingHistoryHandlerTest_collection/lang/stopwords_id.txt
[junit4] 2> 3538980 INFO (OverseerThreadFactory-10105-thread-1-processing-n:127.0.0.1:58038_solr) [n:127.0.0.1:58038_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_da.txt to /configs/AutoscalingHistoryHandlerTest_collection/lang/stopwords_da.txt
[junit4] 2> 3538982 INFO (OverseerThreadFactory-10105-thread-1-processing-n:127.0.0.1:58038_solr) [n:127.0.0.1:58038_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ga.txt to /configs/AutoscalingHistoryHandlerTest_collection/lang/stopwords_ga.txt
[junit4] 2> 3538984 INFO (OverseerThreadFactory-10105-thread-1-processing-n:127.0.0.1:58038_solr) [n:127.0.0.1:58038_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_hi.txt to /configs/AutoscalingHistoryHandlerTest_collection/lang/stopwords_hi.txt
[junit4] 2> 3538986 INFO (OverseerThreadFactory-10105-thread-1-processing-n:127.0.0.1:58038_solr) [n:127.0.0.1:58038_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ja.txt to /configs/AutoscalingHistoryHandlerTest_collection/lang/stopwords_ja.txt
[junit4] 2> 3538988 INFO (OverseerThreadFactory-10105-thread-1-processing-n:127.0.0.1:58038_solr) [n:127.0.0.1:58038_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_pt.txt to /configs/AutoscalingHistoryHandlerTest_collection/lang/stopwords_pt.txt
[junit4] 2> 3538990 INFO (OverseerThreadFactory-10105-thread-1-processing-n:127.0.0.1:58038_solr) [n:127.0.0.1:58038_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_hu.txt to /configs/AutoscalingHistoryHandlerTest_collection/lang/stopwords_hu.txt
[junit4] 2> 3538992 INFO (OverseerThreadFactory-10105-thread-1-processing-n:127.0.0.1:58038_solr) [n:127.0.0.1:58038_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_el.txt to /configs/AutoscalingHistoryHandlerTest_collection/lang/stopwords_el.txt
[junit4] 2> 3538994 INFO (OverseerThreadFactory-10105-thread-1-processing-n:127.0.0.1:58038_solr) [n:127.0.0.1:58038_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ru.txt to /configs/AutoscalingHistoryHandlerTest_collection/lang/stopwords_ru.txt
[junit4] 2> 3538995 INFO (OverseerThreadFactory-10105-thread-1-processing-n:127.0.0.1:58038_solr) [n:127.0.0.1:58038_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_tr.txt to /configs/AutoscalingHistoryHandlerTest_collection/lang/stopwords_tr.txt
[junit4] 2> 3538997 INFO (OverseerThreadFactory-10105-thread-1-processing-n:127.0.0.1:58038_solr) [n:127.0.0.1:58038_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ar.txt to /configs/AutoscalingHistoryHandlerTest_collection/lang/stopwords_ar.txt
[junit4] 2> 3538999 INFO (OverseerThreadFactory-10105-thread-1-processing-n:127.0.0.1:58038_solr) [n:127.0.0.1:58038_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/userdict_ja.txt to /configs/AutoscalingHistoryHandlerTest_collection/lang/userdict_ja.txt
[junit4] 2> 3539001 INFO (OverseerThreadFactory-10105-thread-1-processing-n:127.0.0.1:58038_solr) [n:127.0.0.1:58038_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_eu.txt to /configs/AutoscalingHistoryHandlerTest_collection/lang/stopwords_eu.txt
[junit4] 2> 3539002 INFO (OverseerThreadFactory-10105-thread-1-processing-n:127.0.0.1:58038_solr) [n:127.0.0.1:58038_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_it.txt to /configs/AutoscalingHistoryHandlerTest_collection/lang/stopwords_it.txt
[junit4] 2> 3539004 INFO (OverseerThreadFactory-10105-thread-1-processing-n:127.0.0.1:58038_solr) [n:127.0.0.1:58038_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_cz.txt to /configs/AutoscalingHistoryHandlerTest_collection/lang/stopwords_cz.txt
[junit4] 2> 3539006 INFO (OverseerThreadFactory-10105-thread-1-processing-n:127.0.0.1:58038_solr) [n:127.0.0.1:58038_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stoptags_ja.txt to /configs/AutoscalingHistoryHandlerTest_collection/lang/stoptags_ja.txt
[junit4] 2> 3539008 INFO (OverseerThreadFactory-10105-thread-1-processing-n:127.0.0.1:58038_solr) [n:127.0.0.1:58038_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/contractions_fr.txt to /configs/AutoscalingHistoryHandlerTest_collection/lang/contractions_fr.txt
[junit4] 2> 3539010 INFO (OverseerThreadFactory-10105-thread-1-processing-n:127.0.0.1:58038_solr) [n:127.0.0.1:58038_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_de.txt to /configs/AutoscalingHistoryHandlerTest_collection/lang/stopwords_de.txt
[junit4] 2> 3539012 INFO (OverseerThreadFactory-10105-thread-1-processing-n:127.0.0.1:58038_solr) [n:127.0.0.1:58038_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_fa.txt to /configs/AutoscalingHistoryHandlerTest_collection/lang/stopwords_fa.txt
[junit4] 2> 3539014 INFO (OverseerThreadFactory-10105-thread-1-processing-n:127.0.0.1:58038_solr) [n:127.0.0.1:58038_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/contractions_ga.txt to /configs/AutoscalingHistoryHandlerTest_collection/lang/contractions_ga.txt
[junit4] 2> 3539016 INFO (OverseerThreadFactory-10105-thread-1-processing-n:127.0.0.1:58038_solr) [n:127.0.0.1:58038_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ca.txt to /configs/AutoscalingHistoryHandlerTest_collection/lang/stopwords_ca.txt
[junit4] 2> 3539017 INFO (OverseerThreadFactory-10105-thread-1-processing-n:127.0.0.1:58038_solr) [n:127.0.0.1:58038_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_nl.txt to /configs/AutoscalingHistoryHandlerTest_collection/lang/stopwords_nl.txt
[junit4] 2> 3539019 INFO (OverseerThreadFactory-10105-thread-1-processing-n:127.0.0.1:58038_solr) [n:127.0.0.1:58038_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_bg.txt to /configs/AutoscalingHistoryHandlerTest_collection/lang/stopwords_bg.txt
[junit4] 2> 3539021 INFO (OverseerThreadFactory-10105-thread-1-processing-n:127.0.0.1:58038_solr) [n:127.0.0.1:58038_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_en.txt to /configs/AutoscalingHistoryHandlerTest_collection/lang/stopwords_en.txt
[junit4] 2> 3539023 INFO (OverseerThreadFactory-10105-thread-1-processing-n:127.0.0.1:58038_solr) [n:127.0.0.1:58038_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_es.txt to /configs/AutoscalingHistoryHandlerTest_collection/lang/stopwords_es.txt
[junit4] 2> 3539024 INFO (OverseerThreadFactory-10105-thread-1-processing-n:127.0.0.1:58038_solr) [n:127.0.0.1:58038_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_lv.txt to /configs/AutoscalingHistoryHandlerTest_collection/lang/stopwords_lv.txt
[junit4] 2> 3539026 INFO (OverseerThreadFactory-10105-thread-1-processing-n:127.0.0.1:58038_solr) [n:127.0.0.1:58038_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ro.txt to /configs/AutoscalingHistoryHandlerTest_collection/lang/stopwords_ro.txt
[junit4] 2> 3539028 INFO (OverseerThreadFactory-10105-thread-1-processing-n:127.0.0.1:58038_solr) [n:127.0.0.1:58038_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_th.txt to /configs/AutoscalingHistoryHandlerTest_collection/lang/stopwords_th.txt
[junit4] 2> 3539030 INFO (OverseerThreadFactory-10105-thread-1-processing-n:127.0.0.1:58038_solr) [n:127.0.0.1:58038_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_fr.txt to /configs/AutoscalingHistoryHandlerTest_collection/lang/stopwords_fr.txt
[junit4] 2> 3539031 INFO (OverseerThreadFactory-10105-thread-1-processing-n:127.0.0.1:58038_solr) [n:127.0.0.1:58038_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_sv.txt to /configs/AutoscalingHistoryHandlerTest_collection/lang/stopwords_sv.txt
[junit4] 2> 3539033 INFO (OverseerThreadFactory-10105-thread-1-processing-n:127.0.0.1:58038_solr) [n:127.0.0.1:58038_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_fi.txt to /configs/AutoscalingHistoryHandlerTest_collection/lang/stopwords_fi.txt
[junit4] 2> 3539035 INFO (OverseerThreadFactory-10105-thread-1-processing-n:127.0.0.1:58038_solr) [n:127.0.0.1:58038_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/hyphenations_ga.txt to /configs/AutoscalingHistoryHandlerTest_collection/lang/hyphenations_ga.txt
[junit4] 2> 3539037 INFO (OverseerThreadFactory-10105-thread-1-processing-n:127.0.0.1:58038_solr) [n:127.0.0.1:58038_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_gl.txt to /configs/AutoscalingHistoryHandlerTest_collection/lang/stopwords_gl.txt
[junit4] 2> 3539039 INFO (OverseerThreadFactory-10105-thread-1-processing-n:127.0.0.1:58038_solr) [n:127.0.0.1:58038_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/params.json to /configs/AutoscalingHistoryHandlerTest_collection/params.json
[junit4] 2> 3539044 DEBUG (OverseerStateUpdate-98872557801701382-127.0.0.1:58038_solr-n_0000000000) [n:127.0.0.1:58038_solr ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
[junit4] 2> "name":"AutoscalingHistoryHandlerTest_collection",
[junit4] 2> "fromApi":"true",
[junit4] 2> "replicationFactor":"3",
[junit4] 2> "numShards":"1",
[junit4] 2> "nrtReplicas":"3",
[junit4] 2> "stateFormat":"2",
[junit4] 2> "operation":"create"} current state version: 0
[junit4] 2> 3539044 DEBUG (OverseerStateUpdate-98872557801701382-127.0.0.1:58038_solr-n_0000000000) [n:127.0.0.1:58038_solr ] o.a.s.c.o.ClusterStateMutator building a new cName: AutoscalingHistoryHandlerTest_collection
[junit4] 2> 3539045 DEBUG (OverseerStateUpdate-98872557801701382-127.0.0.1:58038_solr-n_0000000000) [n:127.0.0.1:58038_solr ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/AutoscalingHistoryHandlerTest_collection/state.json
[junit4] 2> 3539150 DEBUG (OverseerStateUpdate-98872557801701382-127.0.0.1:58038_solr-n_0000000000) [n:127.0.0.1:58038_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:58050/solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"} current state version: 0
[junit4] 2> 3539150 INFO (OverseerStateUpdate-98872557801701382-127.0.0.1:58038_solr-n_0000000000) [n:127.0.0.1:58038_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:58050/solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 3539151 DEBUG (OverseerStateUpdate-98872557801701382-127.0.0.1:58038_solr-n_0000000000) [n:127.0.0.1:58038_solr ] o.a.s.c.o.SliceMutator Old Slice: shard1:{
[junit4] 2> "range":"80000000-7fffffff",
[junit4] 2> "state":"active",
[junit4] 2> "replicas":{}}
[junit4] 2> 3539151 DEBUG (OverseerStateUpdate-98872557801701382-127.0.0.1:58038_solr-n_0000000000) [n:127.0.0.1:58038_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:58050/solr",
[junit4] 2> "state":"down",
[junit4] 2> "node_name":null,
[junit4] 2> "type":"NRT"}}}
[junit4] 2> 3539153 DEBUG (OverseerStateUpdate-98872557801701382-127.0.0.1:58038_solr-n_0000000000) [n:127.0.0.1:58038_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:58038/solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"} current state version: 0
[junit4] 2> 3539153 INFO (OverseerStateUpdate-98872557801701382-127.0.0.1:58038_solr-n_0000000000) [n:127.0.0.1:58038_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:58038/solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 3539154 DEBUG (OverseerStateUpdate-98872557801701382-127.0.0.1:58038_solr-n_0000000000) [n:127.0.0.1:58038_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:58050/solr",
[junit4] 2> "state":"down",
[junit4] 2> "node_name":null,
[junit4] 2> "type":"NRT"}}}
[junit4] 2> 3539155 DEBUG (OverseerStateUpdate-98872557801701382-127.0.0.1:58038_solr-n_0000000000) [n:127.0.0.1:58038_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:58050/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:58038/solr",
[junit4] 2> "state":"down",
[junit4] 2> "node_name":null,
[junit4] 2> "type":"NRT"}}}
[junit4] 2> 3539156 DEBUG (OverseerStateUpdate-98872557801701382-127.0.0.1:58038_solr-n_0000000000) [n:127.0.0.1:58038_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:58041/solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"} current state version: 0
[junit4] 2> 3539156 INFO (OverseerStateUpdate-98872557801701382-127.0.0.1:58038_solr-n_0000000000) [n:127.0.0.1:58038_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:58041/solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 3539158 DEBUG (OverseerStateUpdate-98872557801701382-127.0.0.1:58038_solr-n_0000000000) [n:127.0.0.1:58038_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:58050/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:58038/solr",
[junit4] 2> "state":"down",
[junit4] 2> "node_name":null,
[junit4] 2> "type":"NRT"}}}
[junit4] 2> 3539158 DEBUG (OverseerStateUpdate-98872557801701382-127.0.0.1:58038_solr-n_0000000000) [n:127.0.0.1:58038_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:58050/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:58038/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:58041/solr",
[junit4] 2> "state":"down",
[junit4] 2> "node_name":null,
[junit4] 2> "type":"NRT"}}}
[junit4] 2> 3539259 DEBUG (OverseerStateUpdate-98872557801701382-127.0.0.1:58038_solr-n_0000000000) [n:127.0.0.1:58038_solr ] o.a.s.c.o.ZkStateWriter going to update_collection /collections/AutoscalingHistoryHandlerTest_collection/state.json version: 0
[junit4] 2> 3539361 INFO (qtp2083164565-26689) [n:127.0.0.1:58038_solr ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node5&collection.configName=AutoscalingHistoryHandlerTest_collection&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> 3539361 INFO (qtp1192185322-26695) [n:127.0.0.1:58041_solr ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node6&collection.configName=AutoscalingHistoryHandlerTest_collection&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> 3539361 INFO (qtp696545289-26711) [n:127.0.0.1:58050_solr ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node3&collection.configName=AutoscalingHistoryHandlerTest_collection&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> 3539365 INFO (qtp2083164565-26689) [n:127.0.0.1:58038_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 3539367 INFO (qtp1192185322-26695) [n:127.0.0.1:58041_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 3539368 INFO (qtp696545289-26711) [n:127.0.0.1:58050_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 3539376 DEBUG (OverseerStateUpdate-98872557801701382-127.0.0.1:58038_solr-n_0000000000) [n:127.0.0.1:58038_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:58038/solr",
[junit4] 2> "node_name":"127.0.0.1:58038_solr",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"down",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"AutoscalingHistoryHandlerTest_collection",
[junit4] 2> "type":"NRT",
[junit4] 2> "operation":"state"} current state version: 0
[junit4] 2> 3539377 DEBUG (OverseerStateUpdate-98872557801701382-127.0.0.1:58038_solr-n_0000000000) [n:127.0.0.1:58038_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:58038/solr",
[junit4] 2> "node_name":"127.0.0.1:58038_solr",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"down",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"AutoscalingHistoryHandlerTest_collection",
[junit4] 2> "type":"NRT",
[junit4] 2> "operation":"state"}
[junit4] 2> 3539377 DEBUG (OverseerStateUpdate-98872557801701382-127.0.0.1:58038_solr-n_0000000000) [n:127.0.0.1:58038_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:58038/solr","node_name":"127.0.0.1:58038_solr","state":"down","type":"NRT"}
[junit4] 2> 3539377 DEBUG (OverseerStateUpdate-98872557801701382-127.0.0.1:58038_solr-n_0000000000) [n:127.0.0.1:58038_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":"1",
[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:58050/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:58038/solr",
[junit4] 2> "node_name":"127.0.0.1:58038_solr",
[junit4] 2> "state":"down",
[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:58041/solr",
[junit4] 2> "state":"down",
[junit4] 2> "node_name":null,
[junit4] 2> "type":"NRT"}}}}}
[junit4] 2> 3539377 DEBUG (OverseerStateUpdate-98872557801701382-127.0.0.1:58038_solr-n_0000000000) [n:127.0.0.1:58038_solr ] o.a.s.c.Overseer processMessage: queueSize: 3, 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:58050/solr",
[junit4] 2> "node_name":"127.0.0.1:58050_solr",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"down",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"AutoscalingHistoryHandlerTest_collection",
[junit4] 2> "type":"NRT",
[junit4] 2> "operation":"state"} current state version: 0
[junit4] 2> 3539377 DEBUG (OverseerStateUpdate-98872557801701382-127.0.0.1:58038_solr-n_0000000000) [n:127.0.0.1:58038_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:58050/solr",
[junit4] 2> "node_name":"127.0.0.1:58050_solr",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"down",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"AutoscalingHistoryHandlerTest_collection",
[junit4] 2> "type":"NRT",
[junit4] 2> "operation":"state"}
[junit4] 2> 3539377 DEBUG (OverseerStateUpdate-98872557801701382-127.0.0.1:58038_solr-n_0000000000) [n:127.0.0.1:58038_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:58050/solr","node_name":"127.0.0.1:58050_solr","state":"down","type":"NRT"}
[junit4] 2> 3539377 DEBUG (OverseerStateUpdate-98872557801701382-127.0.0.1:58038_solr-n_0000000000) [n:127.0.0.1:58038_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":"1",
[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:58050/solr",
[junit4] 2> "node_name":"127.0.0.1:58050_solr",
[junit4] 2> "state":"down",
[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:58038/solr",
[junit4] 2> "node_name":"127.0.0.1:58038_solr",
[junit4] 2> "state":"down",
[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:58041/solr",
[junit4] 2> "state":"down",
[junit4] 2> "node_name":null,
[junit4] 2> "type":"NRT"}}}}}
[junit4] 2> 3539378 DEBUG (OverseerStateUpdate-98872557801701382-127.0.0.1:58038_solr-n_0000000000) [n:127.0.0.1:58038_solr ] o.a.s.c.Overseer processMessage: queueSize: 3, 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:58041/solr",
[junit4] 2> "node_name":"127.0.0.1:58041_solr",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"down",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"AutoscalingHistoryHandlerTest_collection",
[junit4] 2> "type":"NRT",
[junit4] 2> "operation":"state"} current state version: 0
[junit4] 2> 3539378 DEBUG (OverseerStateUpdate-98872557801701382-127.0.0.1:58038_solr-n_0000000000) [n:127.0.0.1:58038_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:58041/solr",
[junit4] 2> "node_name":"127.0.0.1:58041_solr",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"down",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"AutoscalingHistoryHandlerTest_collection",
[junit4] 2> "type":"NRT",
[junit4] 2> "operation":"state"}
[junit4] 2> 3539378 DEBUG (OverseerStateUpdate-98872557801701382-127.0.0.1:58038_solr-n_0000000000) [n:127.0.0.1:58038_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:58041/solr","node_name":"127.0.0.1:58041_solr","state":"down","type":"NRT"}
[junit4] 2> 3539378 DEBUG (OverseerStateUpdate-98872557801701382-127.0.0.1:58038_solr-n_0000000000) [n:127.0.0.1:58038_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":"1",
[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:58050/solr",
[junit4] 2> "node_name":"127.0.0.1:58050_solr",
[junit4] 2> "state":"down",
[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:58038/solr",
[junit4] 2> "node_name":"127.0.0.1:58038_solr",
[junit4] 2> "state":"down",
[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:58041/solr",
[junit4] 2> "node_name":"127.0.0.1:58041_solr",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT"}}}}}
[junit4] 2> 3539479 DEBUG (OverseerStateUpdate-98872557801701382-127.0.0.1:58038_solr-n_0000000000) [n:127.0.0.1:58038_solr ] o.a.s.c.o.ZkStateWriter going to update_collection /collections/AutoscalingHistoryHandlerTest_collection/state.json version: 1
[junit4] 2> 3539479 INFO (zkCallback-6269-thread-1-processing-n:127.0.0.1:58038_solr) [n:127.0.0.1:58038_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/AutoscalingHistoryHandlerTest_collection/state.json] for collection [AutoscalingHistoryHandlerTest_collection] has occurred - updating... (live nodes size: [3])
[junit4] 2> 3539479 INFO (zkCallback-6272-thread-1-processing-n:127.0.0.1:58041_solr) [n:127.0.0.1:58041_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/AutoscalingHistoryHandlerTest_collection/state.json] for collection [AutoscalingHistoryHandlerTest_collection] has occurred - updating... (live nodes size: [3])
[junit4] 2> 3539479 INFO (zkCallback-6274-thread-1-processing-n:127.0.0.1:58050_solr) [n:127.0.0.1:58050_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/AutoscalingHistoryHandlerTest_collection/state.json] for collection [AutoscalingHistoryHandlerTest_collection] has occurred - updating... (live nodes size: [3])
[junit4] 2> 3539480 INFO (zkCallback-6272-thread-2-processing-n:127.0.0.1:58041_solr) [n:127.0.0.1:58041_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/AutoscalingHistoryHandlerTest_collection/state.json] for collection [AutoscalingHistoryHandlerTest_collection] has occurred - updating... (live nodes size: [3])
[junit4] 2> 3539480 INFO (zkCallback-6269-thread-2-processing-n:127.0.0.1:58038_solr) [n:127.0.0.1:58038_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/AutoscalingHistoryHandlerTest_collection/state.json] for collection [AutoscalingHistoryHandlerTest_collection] has occurred - updating... (live nodes size: [3])
[junit4] 2> 3539480 INFO (zkCallback-6274-thread-2-processing-n:127.0.0.1:58050_solr) [n:127.0.0.1:58050_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/AutoscalingHistoryHandlerTest_collection/state.json] for collection [AutoscalingHistoryHandlerTest_collection] has occurred - updating... (live nodes size: [3])
[junit4] 2> 3539822 DEBUG (ScheduledTrigger-10102-thread-1) [n:127.0.0.1:58038_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: [127.0.0.1:58038_solr, 127.0.0.1:58050_solr, 127.0.0.1:58041_solr]
[junit4] 2> 3540394 INFO (qtp696545289-26711) [n:127.0.0.1:58050_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> 3540394 INFO (qtp696545289-26711) [n:127.0.0.1:58050_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> 3540394 INFO (qtp2083164565-26689) [n:127.0.0.1:58038_solr c:AutoscalingHistoryHandlerTest_collection s:shard1 r:core_node5 x:AutoscalingHistoryHandlerTest_collection_shard1_replica_n2] o.a.s.c.RequestParams conf resource params.json loaded . version : 0
[junit4] 2> 3540394 INFO (qtp2083164565-26689) [n:127.0.0.1:58038_solr c:AutoscalingHistoryHandlerTest_collection s:shard1 r:core_node5 x:AutoscalingHistoryHandlerTest_collection_shard1_replica_n2] o.a.s.c.RequestParams request params refreshed to version 0
[junit4] 2> 3540395 WARN (qtp696545289-26711) [n:127.0.0.1:58050_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 C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.handler.admin.AutoscalingHistoryHandlerTest_8C9762697070A327-001\tempDir-001\node3\AutoscalingHistoryHandlerTest_collection_shard1_replica_n1\..\..\..\..\contrib\extraction\lib filtered by .*\.jar to classpath: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.handler.admin.AutoscalingHistoryHandlerTest_8C9762697070A327-001\tempDir-001\node3\AutoscalingHistoryHandlerTest_collection_shard1_replica_n1\..\..\..\..\contrib\extraction\lib
[junit4] 2> 3540395 WARN (qtp2083164565-26689) [n:127.0.0.1:58038_solr c:AutoscalingHistoryHandlerTest_collection s:shard1 r:core_node5 x:AutoscalingHistoryHandlerTest_collection_shard1_replica_n2] o.a.s.c.SolrConfig Couldn't add files from C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.handler.admin.AutoscalingHistoryHandlerTest_8C9762697070A327-001\tempDir-001\node1\AutoscalingHistoryHandlerTest_collection_shard1_replica_n2\..\..\..\..\contrib\extraction\lib filtered by .*\.jar to classpath: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.handler.admin.AutoscalingHistoryHandlerTest_8C9762697070A327-001\tempDir-001\node1\AutoscalingHistoryHandlerTest_collection_shard1_replica_n2\..\..\..\..\contrib\extraction\lib
[junit4] 2> 3540396 WARN (qtp696545289-26711) [n:127.0.0.1:58050_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 C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.handler.admin.AutoscalingHistoryHandlerTest_8C9762697070A327-001\tempDir-001\node3\AutoscalingHistoryHandlerTest_collection_shard1_replica_n1\..\..\..\..\dist filtered by solr-cell-\d.*\.jar to classpath: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.handler.admin.AutoscalingHistoryHandlerTest_8C9762697070A327-001\tempDir-001\node3\AutoscalingHistoryHandlerTest_collection_shard1_replica_n1\..\..\..\..\dist
[junit4] 2> 3540396 WARN (qtp2083164565-26689) [n:127.0.0.1:58038_solr c:AutoscalingHistoryHandlerTest_collection s:shard1 r:core_node5 x:AutoscalingHistoryHandlerTest_collection_shard1_replica_n2] o.a.s.c.SolrConfig Couldn't add files from C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.handler.admin.AutoscalingHistoryHandlerTest_8C9762697070A327-001\tempDir-001\node1\AutoscalingHistoryHandlerTest_collection_shard1_replica_n2\..\..\..\..\dist filtered by solr-cell-\d.*\.jar to classpath: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.handler.admin.AutoscalingHistoryHandlerTest_8C9762697070A327-001\tempDir-001\node1\AutoscalingHistoryHandlerTest_collection_shard1_replica_n2\..\..\..\..\dist
[junit4] 2> 3540396 WARN (qtp696545289-26711) [n:127.0.0.1:58050_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 C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.handler.admin.AutoscalingHistoryHandlerTest_8C9762697070A327-001\tempDir-001\node3\AutoscalingHistoryHandlerTest_collection_shard1_replica_n1\..\..\..\..\contrib\clustering\lib filtered by .*\.jar to classpath: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.handler.admin.AutoscalingHistoryHandlerTest_8C9762697070A327-001\tempDir-001\node3\AutoscalingHistoryHandlerTest_collection_shard1_replica_n1\..\..\..\..\contrib\clustering\lib
[junit4] 2> 3540396 WARN (qtp2083164565-26689) [n:127.0.0.1:58038_solr c:AutoscalingHistoryHandlerTest_collection s:shard1 r:core_node5 x:AutoscalingHistoryHandlerTest_collection_shard1_replica_n2] o.a.s.c.SolrConfig Couldn't add files from C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.handler.admin.AutoscalingHistoryHandlerTest_8C9762697070A327-001\tempDir-001\node1\AutoscalingHistoryHandlerTest_collection_shard1_replica_n2\..\..\..\..\contrib\clustering\lib filtered by .*\.jar to classpath: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.handler.admin.AutoscalingHistoryHandlerTest_8C9762697070A327-001\tempDir-001\node1\AutoscalingHistoryHandlerTest_collection_shard1_replica_n2\..\..\..\..\contrib\clustering\lib
[junit4] 2> 3540396 INFO (qtp1192185322-26695) [n:127.0.0.1:58041_solr c:AutoscalingHistoryHandlerTest_collection s:shard1 r:core_node6 x:AutoscalingHistoryHandlerTest_collection_shard1_replica_n4] o.a.s.c.RequestParams conf resource params.json loaded . version : 0
[junit4] 2> 3540396 WARN (qtp2083164565-26689) [n:127.0.0.1:58038_solr c:AutoscalingHistoryHandlerTest_collection s:shard1 r:core_node5 x:AutoscalingHistoryHandlerTest_collection_shard1_replica_n2] o.a.s.c.SolrConfig Couldn't add files from C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.handler.admin.AutoscalingHistoryHandlerTest_8C9762697070A327-001\tempDir-001\node1\AutoscalingHistoryHandlerTest_collection_shard1_replica_n2\..\..\..\..\dist filtered by solr-clustering-\d.*\.jar to classpath: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.handler.admin.AutoscalingHistoryHandlerTest_8C9762697070A327-001\tempDir-001\node1\AutoscalingHistoryHandlerTest_collection_shard1_replica_n2\..\..\..\..\dist
[junit4] 2> 3540396 WARN (qtp696545289-26711) [n:127.0.0.1:58050_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 C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.handler.admin.AutoscalingHistoryHandlerTest_8C9762697070A327-001\tempDir-001\node3\AutoscalingHistoryHandlerTest_collection_shard1_replica_n1\..\..\..\..\dist filtered by solr-clustering-\d.*\.jar to classpath: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.handler.admin.AutoscalingHistoryHandlerTest_8C9762697070A327-001\tempDir-001\node3\AutoscalingHistoryHandlerTest_collection_shard1_replica_n1\..\..\..\..\dist
[junit4] 2> 3540397 INFO (qtp1192185322-26695) [n:127.0.0.1:58041_solr c:AutoscalingHistoryHandlerTest_collection s:shard1 r:core_node6 x:AutoscalingHistoryHandlerTest_collection_shard1_replica_n4] o.a.s.c.RequestParams request params refreshed to version 0
[junit4] 2> 3540397 WARN (qtp696545289-26711) [n:127.0.0.1:58050_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 C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.handler.admin.AutoscalingHistoryHandlerTest_8C9762697070A327-001\tempDir-001\node3\AutoscalingHistoryHandlerTest_collection_shard1_replica_n1\..\..\..\..\contrib\langid\lib filtered by .*\.jar to classpath: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.handler.admin.AutoscalingHistoryHandlerTest_8C9762697070A327-001\tempDir-001\node3\AutoscalingHistoryHandlerTest_collection_shard1_replica_n1\..\..\..\..\contrib\langid\lib
[junit4] 2> 3540397 WARN (qtp696545289-26711) [n:127.0.0.1:58050_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 C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.handler.admin.AutoscalingHistoryHandlerTest_8C9762697070A327-001\tempDir-001\node3\AutoscalingHistoryHandlerTest_collection_shard1_replica_n1\..\..\..\..\dist filtered by solr-langid-\d.*\.jar to classpath: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.handler.admin.AutoscalingHistoryHandlerTest_8C9762697070A327-001\tempDir-001\node3\AutoscalingHistoryHandlerTest_collection_shard1_replica_n1\..\..\..\..\dist
[junit4] 2> 3540398 WARN (qtp1192185322-26695) [n:127.0.0.1:58041_solr c:AutoscalingHistoryHandlerTest_collection s:shard1 r:core_node6 x:AutoscalingHistoryHandlerTest_collection_shard1_replica_n4] o.a.s.c.SolrConfig Couldn't add files from C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.handler.admin.AutoscalingHistoryHandlerTest_8C9762697070A327-001\tempDir-001\node2\AutoscalingHistoryHandlerTest_collection_shard1_replica_n4\..\..\..\..\contrib\extraction\lib filtered by .*\.jar to classpath: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.handler.admin.AutoscalingHistoryHandlerTest_8C9762697070A327-001\tempDir-001\node2\AutoscalingHistoryHandlerTest_collection_shard1_replica_n4\..\..\..\..\contrib\extraction\lib
[junit4] 2> 3540398 WARN (qtp2083164565-26689) [n:127.0.0.1:58038_solr c:AutoscalingHistoryHandlerTest_collection s:shard1 r:core_node5 x:AutoscalingHistoryHandlerTest_collection_shard1_replica_n2] o.a.s.c.SolrConfig Couldn't add files from C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.handler.admin.AutoscalingHistoryHandlerTest_8C9762697070A327-001\tempDir-001\node1\AutoscalingHistoryHandlerTest_collection_shard1_replica_n2\..\..\..\..\contrib\langid\lib filtered by .*\.jar to classpath: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.handler.admin.AutoscalingHistoryHandlerTest_8C9762697070A327-001\tempDir-001\node1\AutoscalingHistoryHandlerTest_collection_shard1_replica_n2\..\..\..\..\contrib\langid\lib
[junit4] 2> 3540398 WARN (qtp696545289-26711) [n:127.0.0.1:58050_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 C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.handler.admin.AutoscalingHistoryHandlerTest_8C9762697070A327-001\tempDir-001\node3\AutoscalingHistoryHandlerTest_collection_shard1_replica_n1\..\..\..\..\contrib\velocity\lib filtered by .*\.jar to classpath: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.handler.admin.AutoscalingHistoryHandlerTest_8C9762697070A327-001\tempDir-001\node3\AutoscalingHistoryHandlerTest_collection_shard1_replica_n1\..\..\..\..\contrib\velocity\lib
[junit4] 2> 3540398 WARN (qtp2083164565-26689) [n:127.0.0.1:58038_solr c:AutoscalingHistoryHandlerTest_collection s:shard1 r:core_node5 x:AutoscalingHistoryHandlerTest_collection_shard1_replica_n2] o.a.s.c.SolrConfig Couldn't add files from C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.handler.admin.AutoscalingHistoryHandlerTest_8C9762697070A327-001\tempDir-001\node1\AutoscalingHistoryHandlerTest_collection_shard1_replica_n2\..\..\..\..\dist filtered by solr-langid-\d.*\.jar to classpath: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.handler.admin.AutoscalingHistoryHandlerTest_8C9762697070A327-001\tempDir-001\node1\AutoscalingHistoryHandlerTest_collection_shard1_replica_n2\..\..\..\..\dist
[junit4] 2> 3540398 WARN (qtp696545289-26711) [n:127.0.0.1:58050_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 C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.handler.admin.AutoscalingHistoryHandlerTest_8C9762697070A327-001\tempDir-001\node3\AutoscalingHistoryHandlerTest_collection_shard1_replica_n1\..\..\..\..\dist filtered by solr-velocity-\d.*\.jar to classpath: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.handler.admin.AutoscalingHistoryHandlerTest_8C9762697070A327-001\tempDir-001\node3\AutoscalingHistoryHandlerTest_collection_shard1_replica_n1\..\..\..\..\dist
[junit4] 2> 3540399 WARN (qtp2083164565-26689) [n:127.0.0.1:58038_solr c:AutoscalingHistoryHandlerTest_collection s:shard1 r:core_node5 x:AutoscalingHistoryHandlerTest_collection_shard1_replica_n2] o.a.s.c.SolrConfig Couldn't add files from C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.handler.admin.AutoscalingHistoryHandlerTest_8C9762697070A327-001\tempDir-001\node1\AutoscalingHistoryHandlerTest_collection_shard1_replica_n2\..\..\..\..\contrib\velocity\lib filtered by .*\.jar to classpath: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.handler.admin.AutoscalingHistoryHandlerTest_8C9762697070A327-001\tempDir-001\node1\AutoscalingHistoryHandlerTest_collection_shard1_replica_n2\..\..\..\..\contrib\velocity\lib
[junit4] 2> 3540399 WARN (qtp2083164565-26689) [n:127.0.0.1:58038_solr c:AutoscalingHistoryHandlerTest_collection s:shard1 r:core_node5 x:AutoscalingHistoryHandlerTest_collection_shard1_replica_n2] o.a.s.c.SolrConfig Couldn't add files from C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.handler.admin.AutoscalingHistoryHandlerTest_8C9762697070A327-001\tempDir-001\node1\AutoscalingHistoryHandlerTest_collection_shard1_replica_n2\..\..\..\..\dist filtered by solr-velocity-\d.*\.jar to classpath: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.handler.admin.AutoscalingHistoryHandlerTest_8C9762697070A327-001\tempDir-001\node1\AutoscalingHistoryHandlerTest_collection_shard1_replica_n2\..\..\..\..\dist
[junit4] 2> 3540404 INFO (qtp696545289-26711) [n:127.0.0.1:58050_solr c:AutoscalingHistoryHandlerTest_collection s:shard1 r:core_node3 x:AutoscalingHistoryHandlerTest_collection_shard1_replica_n1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
[junit4] 2> 3540404 INFO (qtp2083164565-26689) [n:127.0.0.1:58038_solr c:AutoscalingHistoryHandlerTest_collection s:shard1 r:core_node5 x:AutoscalingHistoryHandlerTest_collection_shard1_replica_n2] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
[junit4] 2> 3540413 WARN (qtp1192185322-26695) [n:127.0.0.1:58041_solr c:AutoscalingHistoryHandlerTest_collection s:shard1 r:core_node6 x:AutoscalingHistoryHandlerTest_collection_shard1_replica_n4] o.a.s.c.SolrConfig Couldn't add files from C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.handler.admin.AutoscalingHistoryHandlerTest_8C9762697070A327-001\tempDir-001\node2\AutoscalingHistoryHandlerTest_collection_shard1_replica_n4\..\..\..\..\dist filtered by solr-cell-\d.*\.jar to classpath: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.handler.admin.AutoscalingHistoryHandlerTest_8C9762697070A327-001\tempDir-001\node2\AutoscalingHistoryHandlerTest_collection_shard1_replica_n4\..\..\..\..\dist
[junit4] 2> 3540414 WARN (qtp1192185322-26695) [n:127.0.0.1:58041_solr c:AutoscalingHistoryHandlerTest_collection s:shard1 r:core_node6 x:AutoscalingHistoryHandlerTest_collection_shard1_replica_n4] o.a.s.c.SolrConfig Couldn't add files from C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.handler.admin.AutoscalingHistoryHandlerTest_8C9762697070A327-001\tempDir-001\node2\AutoscalingHistoryHandlerTest_collection_shard1_replica_n4\..\..\..\..\contrib\clustering\lib filtered by .*\.jar to classpath: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.handler.admin.AutoscalingHistoryHandlerTest_8C9762697070A327-001\tempDir-001\node2\AutoscalingHistoryHandlerTest_collection_shard1_replica_n4\..\..\..\..\contrib\clustering\lib
[junit4] 2> 3540414 WARN (qtp1192185322-26695) [n:127.0.0.1:58041_solr c:AutoscalingHistoryHandlerTest_collection s:shard1 r:core_node6 x:AutoscalingHistoryHandlerTest_collection_shard1_replica_n4] o.a.s.c.SolrConfig Couldn't add files from C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.handler.admin.AutoscalingHistoryHandlerTest_8C9762697070A327-001\tempDir-001\node2\AutoscalingHistoryHandlerTest_collection_shard1_replica_n4\..\..\..\..\dist filtered by solr-clustering-\d.*\.jar to classpath: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.handler.admin.AutoscalingHistoryHandlerTest_8C9762697070A327-001\tempDir-001\node2\AutoscalingHistoryHandlerTest_collection_shard1_replica_n4\..\..\..\..\dist
[junit4] 2> 3540430 WARN (qtp1192185322-26695) [n:127.0.0.1:58041_solr c:AutoscalingHistoryHandlerTest_collection s:shard1 r:core_node6 x:AutoscalingHistoryHandlerTest_collection_shard1_replica_n4] o.a.s.c.SolrConfig Couldn't add files from C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.handler.admin.AutoscalingHistoryHandlerTest_8C9762697070A327-001\tempDir-001\node2\AutoscalingHistoryHandlerTest_collection_shard1_replica_n4\..\..\..\..\contrib\langid\lib filtered by .*\.jar to classpath: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.handler.admin.AutoscalingHistoryHandlerTest_8C9762697070A327-001\tempDir-001\node2\AutoscalingHistoryHandlerTest_collection_shard1_replica_n4\..\..\..\..\contrib\langid\lib
[junit4] 2> 3540430 WARN (qtp1192185322-26695) [n:127.0.0.1:58041_solr c:AutoscalingHistoryHandlerTest_collection s:shard1 r:core_node6 x:AutoscalingHistoryHandlerTest_collection_shard1_replica_n4] o.a.s.c.SolrConfig Couldn't add files from C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.handler.admin.AutoscalingHistoryHandlerTest_8C9762697070A327-001\tempDir-001\node2\AutoscalingHistoryHandlerTest_collection_shard1_replica_n4\..\..\..\..\dist filtered by solr-langid-\d.*\.jar to classpath: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.handler.admin.AutoscalingHistoryHandlerTest_8C9762697070A327-001\tempDir-001\node2\AutoscalingHistoryHandlerTest_collection_shard1_replica_n4\..\..\..\..\dist
[junit4] 2> 3540430 INFO (qtp696545289-26711) [n:127.0.0.1:58050_solr c:AutoscalingHistoryHandlerTest_collection s:shard1 r:core_node3 x:AutoscalingHistoryHandlerTest_collection_shard1_replica_n1] o.a.s.s.IndexSchema [AutoscalingHistoryHandlerTest_collection_shard1_replica_n1] Schema name=default-config
[junit4] 2> 3540431 WARN (qtp1192185322-26695) [n:127.0.0.1:58041_solr c:AutoscalingHistoryHandlerTest_collection s:shard1 r:core_node6 x:AutoscalingHistoryHandlerTest_collection_shard1_replica_n4] o.a.s.c.SolrConfig Couldn't add files from C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.handler.admin.AutoscalingHistoryHandlerTest_8C9762697070A327-001\tempDir-001\node2\AutoscalingHistoryHandlerTest_collection_shard1_replica_n4\..\..\..\..\contrib\velocity\lib filtered by .*\.jar to classpath: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.handler.admin.AutoscalingHistoryHandlerTest_8C9762697070A327-001\tempDir-001\node2\AutoscalingHistoryHandlerTest_collection_shard1_replica_n4\..\..\..\..\contrib\velocity\lib
[junit4] 2> 3540431 WARN (qtp1192185322-26695) [n:127.0.0.1:58041_solr c:AutoscalingHistoryHandlerTest_collection s:shard1 r:core_node6 x:AutoscalingHistoryHandlerTest_collection_shard1_replica_n4] o.a.s.c.SolrConfig Couldn't add files from C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.handler.admin.AutoscalingHistoryHandlerTest_8C9762697070A327-001\tempDir-001\node2\AutoscalingHistoryHandlerTest_collection_shard1_replica_n4\..\..\..\..\dist filtered by solr-velocity-\d.*\.jar to classpath: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.handler.admin.AutoscalingHistoryHandlerTest_8C9762697070A327-001\tempDir-001\node2\AutoscalingHistoryHandlerTest_collection_shard1_replica_n4\..\..\..\..\dist
[junit4] 2> 3540433 INFO (qtp2083164565-26689) [n:127.0.0.1:58038_solr c:AutoscalingHistoryHandlerTest_collection s:shard1 r:core_node5 x:AutoscalingHistoryHandlerTest_collection_shard1_replica_n2] o.a.s.s.IndexSchema [AutoscalingHistoryHandlerTest_collection_shard1_replica_n2] Schema name=default-config
[junit4] 2> 3540446 INFO (qtp1192185322-26695) [n:127.0.0.1:58041_solr c:AutoscalingHistoryHandlerTest_collection s:shard1 r:core_node6 x:AutoscalingHistoryHandlerTest_collection_shard1_replica_n4] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
[junit4] 2> 3540475 INFO (qtp1192185322-26695) [n:127.0.0.1:58041_solr c:AutoscalingHistoryHandlerTest_collection s:shard1 r:core_node6 x:AutoscalingHistoryHandlerTest_collection_shard1_replica_n4] o.a.s.s.IndexSchema [AutoscalingHistoryHandlerTest_collection_shard1_replica_n4] Schema name=default-config
[junit4] 2> 3540606 INFO (qtp696545289-26711) [n:127.0.0.1:58050_solr c:AutoscalingHistoryHandlerTest_collection s:shard1 r:core_node3 x:AutoscalingHistoryHandlerTest_collection_shard1_replica_n1] o.a.s.s.IndexSchema Loaded schema default-config/1.6 with uniqueid field id
[junit4] 2> 3540618 INFO (qtp2083164565-26689) [n:127.0.0.1:58038_solr c:AutoscalingHistoryHandlerTest_collection s:shard1 r:core_node5 x:AutoscalingHistoryHandlerTest_collection_shard1_replica_n2] o.a.s.s.IndexSchema Loaded schema default-config/1.6 with uniqueid field id
[junit4] 2> 3540629 INFO (qtp1192185322-26695) [n:127.0.0.1:58041_solr c:AutoscalingHistoryHandlerTest_collection s:shard1 r:core_node6 x:AutoscalingHistoryHandlerTest_collection_shard1_replica_n4] o.a.s.s.IndexSchema Loaded schema default-config/1.6 with uniqueid field id
[junit4] 2> 3540635 INFO (qtp696545289-26711) [n:127.0.0.1:58050_solr c:AutoscalingHistoryHandlerTest_collection s:shard1 r:core_node3 x:AutoscalingHistoryHandlerTest_collection_shard1_replica_n1] o.a.s.c.CoreContainer Creating SolrCore 'AutoscalingHistoryHandlerTest_collection_shard1_replica_n1' using configuration from collection AutoscalingHistoryHandlerTest_collection, trusted=true
[junit4] 2> 3540636 INFO (qtp696545289-26711) [n:127.0.0.1:58050_solr c:AutoscalingHistoryHandlerTest_collection s:shard1 r:core_node3 x:AutoscalingHistoryHandlerTest_collection_shard1_replica_n1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_58050.solr.core.AutoscalingHistoryHandlerTest_collection.shard1.replica_n1' (registry 'solr.core.AutoscalingHistoryHandlerTest_collection.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@27254fb8
[junit4] 2> 3540636 INFO (qtp696545289-26711) [n:127.0.0.1:58050_solr c:AutoscalingHistoryHandlerTest_collection s:shard1 r:core_node3 x:AutoscalingHistoryHandlerTest_collection_shard1_replica_n1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 3540636 INFO (qtp696545289-26711) [n:127.0.0.1:58050_solr c:AutoscalingHistoryHandlerTest_collection s:shard1 r:core_node3 x:AutoscalingHistoryHandlerTest_collection_shard1_replica_n1] o.a.s.c.SolrCore [[AutoscalingHistoryHandlerTest_collection_shard1_replica_n1] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.handler.admin.AutoscalingHistoryHandlerTest_8C9762697070A327-001\tempDir-001\node3\AutoscalingHistoryHandlerTest_collection_shard1_replica_n1], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.handler.admin.AutoscalingHistoryHandlerTest_8C9762697070A327-001\tempDir-001\node3\.\AutoscalingHistoryHandlerTest_collection_shard1_replica_n1\data\]
[junit4] 2> 3540646 INFO (qtp696545289-26711) [n:127.0.0.1:58050_solr c:AutoscalingHistoryHandlerTest_collection s:shard1 r:core_node3 x:AutoscalingHistoryHandlerTest_collection_shard1_replica_n1] o.a.s.r.XSLTResponseWriter xsltCacheLifetimeSeconds=5
[junit4] 2> 3540653 INFO (qtp2083164565-26689) [n:127.0.0.1:58038_solr c:AutoscalingHistoryHandlerTest_collection s:shard1 r:core_node5 x:AutoscalingHistoryHandlerTest_collection_shard1_replica_n2] o.a.s.c.CoreContainer Creating SolrCore 'AutoscalingHistoryHandlerTest_collection_shard1_replica_n2' using configuration from collection AutoscalingHistoryHandlerTest_collection, trusted=true
[junit4] 2> 3540654 INFO (qtp2083164565-26689) [n:127.0.0.1:58038_solr c:AutoscalingHistoryHandlerTest_collection s:shard1 r:core_node5 x:AutoscalingHistoryHandlerTest_collection_shard1_replica_n2] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_58038.solr.core.AutoscalingHistoryHandlerTest_collection.shard1.replica_n2' (registry 'solr.core.AutoscalingHistoryHandlerTest_collection.shard1.replica_n2') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@27254fb8
[junit4] 2> 3540654 INFO (qtp2083164565-26689) [n:127.0.0.1:58038_solr c:AutoscalingHistoryHandlerTest_collection s:shard1 r:core_node5 x:AutoscalingHistoryHandlerTest_collection_shard1_replica_n2] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 3540654 INFO (qtp2083164565-26689) [n:127.0.0.1:58038_solr c:AutoscalingHistoryHandlerTest_collection s:shard1 r:core_node5 x:AutoscalingHistoryHandlerTest_collection_shard1_replica_n2] o.a.s.c.SolrCore [[AutoscalingHistoryHandlerTest_collection_shard1_replica_n2] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.handler.admin.AutoscalingHistoryHandlerTest_8C9762697070A327-001\tempDir-001\node1\AutoscalingHistoryHandlerTest_collection_shard1_replica_n2], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.handler.admin.AutoscalingHistoryHandlerTest_8C9762697070A327-001\tempDir-001\node1\.\AutoscalingHistoryHandlerTest_collection_shard1_replica_n2\data\]
[junit4] 2> 354
[...truncated too long message...]
tory:0.0.0.0/0.0.0.0:0) [ ] o.a.z.s.NIOServerCnxn caught end of stream exception
[junit4] 2> EndOfStreamException: Unable to read additional data from client sessionid 0x15f441145ce0006, likely client has closed socket
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:239)
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:203)
[junit4] 2> at java.lang.Thread.run(Thread.java:748)
[junit4] 2> 4196060 INFO (jetty-closer-6258-thread-2) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@5576e12e{/solr,null,UNAVAILABLE}
[junit4] 2> 4196061 ERROR (SUITE-AutoscalingHistoryHandlerTest-seed#[8C9762697070A327]-worker) [ ] o.a.z.s.ZooKeeperServer ZKShutdownHandler is not registered, so ZooKeeper server won't take any action on ERROR or SHUTDOWN server state changes
[junit4] 2> 4196062 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[8C9762697070A327]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:58033 58033
[junit4] 2> 4196065 INFO (Thread-5066) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:58033 58033
[junit4] 2> 4196066 WARN (Thread-5066) [ ] o.a.s.c.ZkTestServer Watch limit violations:
[junit4] 2> Maximum concurrent create/delete watches above limit:
[junit4] 2>
[junit4] 2> 17 /solr/aliases.json
[junit4] 2> 17 /solr/clusterprops.json
[junit4] 2> 5 /solr/configs/.system
[junit4] 2> 5 /solr/configs/.system/managed-schema
[junit4] 2> 4 /solr/security.json
[junit4] 2> 3 /solr/configs/AutoscalingHistoryHandlerTest_collection
[junit4] 2> 3 /solr/configs/AutoscalingHistoryHandlerTest_collection/managed-schema
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 80 /solr/collections/.system/state.json
[junit4] 2> 29 /solr/collections/AutoscalingHistoryHandlerTest_collection/state.json
[junit4] 2> 17 /solr/clusterstate.json
[junit4] 2> 3 /solr/collections/.system/leader_elect/shard1/election/98872557801701385-core_node5-n_0000000001
[junit4] 2> 2 /solr/overseer_elect/election/98872557801701382-127.0.0.1:58038_solr-n_0000000000
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 17 /solr/live_nodes
[junit4] 2> 17 /solr/collections
[junit4] 2>
[junit4] 2> NOTE: leaving temporary files on disk at: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.handler.admin.AutoscalingHistoryHandlerTest_8C9762697070A327-001
[junit4] 2> Oct 22, 2017 12:42:41 PM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
[junit4] 2> WARNING: Will linger awaiting termination of 2 leaked thread(s).
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene70): {action_s=FSTOrd50, event.source_s=BlockTreeOrds(blocksize=128), type=FSTOrd50, before.actions_ss=BlockTreeOrds(blocksize=128), event.property._enqueue_time__ss=FSTOrd50, after.actions_ss=FST50, stage_s=FSTOrd50, event.id_s=PostingsFormat(name=Memory), event.property.eventTimes_ss=FSTOrd50, source_s=PostingsFormat(name=Memory), event.type_s=FSTOrd50, responses_ts=FST50, id=PostingsFormat(name=Memory), collections_ss=FSTOrd50, operations.params_ts=FSTOrd50, event.property.nodeNames_ss=FSTOrd50}, docValues:{_version_=DocValuesFormat(name=Memory), event.time_l=DocValuesFormat(name=Memory), timestamp=DocValuesFormat(name=Memory)}, maxPointsInLeafNode=1328, maxMBSortInHeap=5.763338699283007, sim=RandomSimilarity(queryNorm=true): {}, locale=hr-HR, timezone=Africa/Kigali
[junit4] 2> NOTE: Windows 10 10.0 amd64/Oracle Corporation 1.8.0_144 (64-bit)/cpus=3,threads=1,free=128952408,total=518979584
[junit4] 2> NOTE: All tests run in this JVM: [BadComponentTest, HealthCheckHandlerTest, SolrCloudExampleTest, TestOnReconnectListenerSupport, TestPayloadScoreQParserPlugin, TestEmbeddedSolrServerConstructors, TestLuceneMatchVersion, DeleteStatusTest, CopyFieldTest, TestCollectionsAPIViaSolrCloudCluster, TestElisionMultitermQuery, LeaderElectionContextKeyTest, SimpleCollectionCreateDeleteTest, BaseCdcrDistributedZkTest, SolrShardReporterTest, DistributedIntervalFacetingTest, FieldMutatingUpdateProcessorTest, SchemaVersionSpecificBehaviorTest, ReplaceNodeTest, SolrIndexSplitterTest, TestRandomCollapseQParserPlugin, CollectionsAPIDistributedZkTest, HdfsRecoveryZkTest, TestJsonFacetRefinement, EchoParamsTest, MissingSegmentRecoveryTest, TestCloudManagedSchema, ChaosMonkeySafeLeaderTest, ZkNodePropsTest, BigEndianAscendingWordDeserializerTest, TestCloudNestedDocsSort, MBeansHandlerTest, ConcurrentDeleteAndCreateCollectionTest, TestSkipOverseerOperations, SuggestComponentTest, ShardSplitTest, SolrIndexConfigTest, SimplePostToolTest, TestSolrConfigHandler, TestMaxScoreQueryParser, TestExclusionRuleCollectionAccess, TestSQLHandler, HLLUtilTest, SpellingQueryConverterTest, TestLockTree, TestUniqueKeyFieldResource, TestCryptoKeys, TestCSVLoader, TestFunctionQuery, AnalysisErrorHandlingTest, TestQueryUtils, TlogReplayBufferedWhileIndexingTest, TestCloudRecovery, HdfsTlogReplayBufferedWhileIndexingTest, TestRandomFlRTGCloud, IndexSchemaRuntimeFieldTest, TestSubQueryTransformerCrossCore, BasicFunctionalityTest, PingRequestHandlerTest, DocValuesTest, OverseerCollectionConfigSetProcessorTest, TestUninvertingReader, SolrIndexMetricsTest, TestDefaultStatsCache, CdcrReplicationDistributedZkTest, TestReRankQParserPlugin, DateRangeFieldTest, LeaderFailureAfterFreshStartTest, ConnectionManagerTest, DirectSolrSpellCheckerTest, TestCollationFieldDocValues, UninvertDocValuesMergePolicyTest, TestConfigSetProperties, ShardRoutingCustomTest, ConjunctionSolrSpellCheckerTest, TestCopyFieldCollectionResource, TestSolrCLIRunExample, BlockJoinFacetDistribTest, TestAuthenticationFramework, SynonymTokenizerTest, CustomCollectionTest, BasicZkTest, TestCoreAdminApis, TestMultiWordSynonyms, TestPostingsSolrHighlighter, DOMUtilTest, SolrCoreCheckLockOnStartupTest, UpdateLogTest, TestDistributedGrouping, BlobRepositoryCloudTest, TermVectorComponentDistributedTest, DistributedQueryComponentOptimizationTest, CursorMarkTest, CoreSorterTest, TestAnalyzedSuggestions, LoggingHandlerTest, TestExpandComponent, TestSolrQueryParser, TemplateUpdateProcessorTest, JvmMetricsTest, TestRTGBase, SystemLogListenerTest, TestManagedSchema, TestSolrJ, TestDynamicFieldResource, ClassificationUpdateProcessorIntegrationTest, TestAnalyzeInfixSuggestions, RecoveryAfterSoftCommitTest, SolrJmxReporterCloudTest, SolrMetricReporterTest, NodeLostTriggerTest, SampleTest, CloudExitableDirectoryReaderTest, TestSchemaVersionResource, TestPerFieldSimilarity, BitVectorTest, TestShortCircuitedRequests, TestLuceneIndexBackCompat, SpellPossibilityIteratorTest, TestRequestForwarding, ExecutePlanActionTest, TestCoreContainer, BlockJoinFacetSimpleTest, TestHdfsBackupRestoreCore, TestAuthorizationFramework, TestMergePolicyConfig, TestSimpleTrackingShardHandler, CdcrRequestHandlerTest, TestQueryTypes, HttpSolrCallGetCoreTest, TestLRUCache, TestSchemaManager, TestGeoJSONResponseWriter, TestFieldCacheSortRandom, TestSort, DistributedExpandComponentTest, TestDocumentBuilder, TestScoreJoinQPNoScore, TestRestManager, TestSortByMinMaxFunction, TestRemoteStreaming, CollectionsAPIAsyncDistributedZkTest, TestCharFilters, SolrCoreMetricManagerTest, TestFoldingMultitermQuery, BadCopyFieldTest, MultiThreadedOCPTest, HdfsBasicDistributedZkTest, AnalyticsMergeStrategyTest, TestRuleBasedAuthorizationPlugin, HdfsNNFailoverTest, DocumentAnalysisRequestHandlerTest, TestDelegationWithHadoopAuth, PreAnalyzedFieldManagedSchemaCloudTest, TestReload, TestFieldCacheReopen, DistributedQueueTest, AutoScalingHandlerTest, TestRawResponseWriter, TestDynamicLoading, QueryResultKeyTest, TestManagedResource, DocumentBuilderTest, MetricsHandlerTest, DataDrivenBlockJoinTest, JavabinLoaderTest, BlockDirectoryTest, MoreLikeThisHandlerTest, TestFastWriter, TestClusterProperties, PreAnalyzedFieldTest, TestBlobHandler, TestStressLucene, DeleteReplicaTest, TestGroupingSearch, VMParamsZkACLAndCredentialsProvidersTest, TestDocTermOrds, CoreAdminHandlerTest, CachingDirectoryFactoryTest, TestClusterStateMutator, HttpTriggerListenerTest, ChangedSchemaMergeTest, HdfsChaosMonkeySafeLeaderTest, TestExceedMaxTermLength, TestSolrCloudWithHadoopAuthPlugin, TestXmlQParser, NodeMutatorTest, OutOfBoxZkACLAndCredentialsProvidersTest, OverriddenZkACLAndCredentialsProvidersTest, OverseerModifyCollectionTest, OverseerTaskQueueTest, RestartWhileUpdatingTest, RollingRestartTest, SSLMigrationTest, SolrCLIZkUtilsTest, SolrXmlInZkTest, TestCloudInspectUtil, TestCloudJSONFacetJoinDomain, TestCloudPivotFacet, TestConfigSetsAPIExclusivity, TestDeleteCollectionOnDownNodes, TestDistribDocBasedVersion, TestLeaderElectionZkExpiry, TestLocalFSCloudBackupRestore, TestPullReplica, TestSSLRandomization, TestSizeLimitedDistributedMap, TestSolrCloudWithSecureImpersonation, TestStressCloudBlindAtomicUpdates, TestTolerantUpdateProcessorCloud, ZkFailoverTest, HdfsAutoAddReplicasIntegrationTest, SearchRateTriggerTest, HdfsRestartWhileUpdatingTest, HdfsSyncSliceTest, HdfsThreadLeakTest, HdfsUnloadDistributedZkTest, HdfsWriteToMultipleCollectionsTest, StressHdfsTest, ZkStateWriterTest, RuleEngineTest, ConfigureRecoveryStrategyTest, DirectoryFactoryTest, ExitableDirectoryReaderTest, TestBackupRepositoryFactory, TestConfigOverlay, TestConfigSetImmutable, TestCorePropertiesReload, TestCustomStream, TestInfoStreamLogging, TestNRTOpen, TestShardHandlerFactory, TestSimpleTextCodec, TestSolrXml, TestSolrCloudSnapshots, TestSolrCoreSnapshots, RequestLoggingTest, TestReqParamsAPI, TestSolrConfigHandlerCloud, V2StandaloneTest, AutoscalingHistoryHandlerTest]
[junit4] Completed [745/745 (1!)] on J1 in 658.65s, 1 test, 1 failure <<< FAILURES!
[...truncated 47151 lines...]
[JENKINS] Lucene-Solr-master-Windows (64bit/jdk1.8.0_144) - Build #
6974 - Still Unstable!
Posted by Policeman Jenkins Server <je...@thetaphi.de>.
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Windows/6974/
Java: 64bit/jdk1.8.0_144 -XX:-UseCompressedOops -XX:+UseConcMarkSweepGC
1 tests failed.
FAILED: junit.framework.TestSuite.org.apache.solr.core.TestNRTOpen
Error Message:
Could not remove the following files (in the order of attempts): C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.core.TestNRTOpen_60AADBE56D33CF4F-001\init-core-data-001: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.core.TestNRTOpen_60AADBE56D33CF4F-001\init-core-data-001
Stack Trace:
java.io.IOException: Could not remove the following files (in the order of attempts):
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.core.TestNRTOpen_60AADBE56D33CF4F-001\init-core-data-001: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.core.TestNRTOpen_60AADBE56D33CF4F-001\init-core-data-001
at __randomizedtesting.SeedInfo.seed([60AADBE56D33CF4F]:0)
at org.apache.lucene.util.IOUtils.rm(IOUtils.java:329)
at org.apache.lucene.util.TestRuleTemporaryFilesCleanup.afterAlways(TestRuleTemporaryFilesCleanup.java:216)
at com.carrotsearch.randomizedtesting.rules.TestRuleAdapter$1.afterAlways(TestRuleAdapter.java:31)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:43)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.lang.Thread.run(Thread.java:748)
Build Log:
[...truncated 12030 lines...]
[junit4] Suite: org.apache.solr.core.TestNRTOpen
[junit4] 2> Creating dataDir: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.core.TestNRTOpen_60AADBE56D33CF4F-001\init-core-data-001
[junit4] 2> 817497 WARN (SUITE-TestNRTOpen-seed#[60AADBE56D33CF4F]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=1 numCloses=1
[junit4] 2> 817497 INFO (SUITE-TestNRTOpen-seed#[60AADBE56D33CF4F]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=false
[junit4] 2> 817497 INFO (SUITE-TestNRTOpen-seed#[60AADBE56D33CF4F]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason=, value=NaN, ssl=NaN, clientAuth=NaN)
[junit4] 2> 817497 INFO (SUITE-TestNRTOpen-seed#[60AADBE56D33CF4F]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 817497 INFO (SUITE-TestNRTOpen-seed#[60AADBE56D33CF4F]-worker) [ ] o.a.s.SolrTestCaseJ4 ####initCore
[junit4] 2> 817497 INFO (SUITE-TestNRTOpen-seed#[60AADBE56D33CF4F]-worker) [ ] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/C:/Users/jenkins/workspace/Lucene-Solr-master-Windows/solr/core/src/test-files/solr/collection1/lib, /C:/Users/jenkins/workspace/Lucene-Solr-master-Windows/solr/core/src/test-files/solr/collection1/lib/classes]
[junit4] 2> 817529 INFO (SUITE-TestNRTOpen-seed#[60AADBE56D33CF4F]-worker) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
[junit4] 2> 817545 INFO (SUITE-TestNRTOpen-seed#[60AADBE56D33CF4F]-worker) [ ] o.a.s.s.IndexSchema [null] Schema name=minimal
[junit4] 2> 817545 WARN (SUITE-TestNRTOpen-seed#[60AADBE56D33CF4F]-worker) [ ] o.a.s.s.IndexSchema no uniqueKey specified in schema.
[junit4] 2> 817545 INFO (SUITE-TestNRTOpen-seed#[60AADBE56D33CF4F]-worker) [ ] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field null
[junit4] 2> 817591 INFO (SUITE-TestNRTOpen-seed#[60AADBE56D33CF4F]-worker) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5f85acd6
[junit4] 2> 817607 INFO (SUITE-TestNRTOpen-seed#[60AADBE56D33CF4F]-worker) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5f85acd6
[junit4] 2> 817607 INFO (SUITE-TestNRTOpen-seed#[60AADBE56D33CF4F]-worker) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5f85acd6
[junit4] 2> 817607 INFO (coreLoadExecutor-3295-thread-1) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 817607 INFO (coreLoadExecutor-3295-thread-1) [ x:collection1] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/C:/Users/jenkins/workspace/Lucene-Solr-master-Windows/solr/core/src/test-files/solr/collection1/lib, /C:/Users/jenkins/workspace/Lucene-Solr-master-Windows/solr/core/src/test-files/solr/collection1/lib/classes]
[junit4] 2> 817639 INFO (coreLoadExecutor-3295-thread-1) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
[junit4] 2> 817654 INFO (coreLoadExecutor-3295-thread-1) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=minimal
[junit4] 2> 817654 WARN (coreLoadExecutor-3295-thread-1) [ x:collection1] o.a.s.s.IndexSchema no uniqueKey specified in schema.
[junit4] 2> 817654 INFO (coreLoadExecutor-3295-thread-1) [ x:collection1] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field null
[junit4] 2> 817654 INFO (coreLoadExecutor-3295-thread-1) [ x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\core\src\test-files\solr\collection1, trusted=true
[junit4] 2> 817654 INFO (coreLoadExecutor-3295-thread-1) [ x:collection1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.collection1' (registry 'solr.core.collection1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5f85acd6
[junit4] 2> 817654 INFO (coreLoadExecutor-3295-thread-1) [ x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 817654 INFO (coreLoadExecutor-3295-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\core\src\test-files\solr\collection1], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.core.TestNRTOpen_60AADBE56D33CF4F-001\init-core-data-001\]
[junit4] 2> 817717 INFO (coreLoadExecutor-3295-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 817717 INFO (coreLoadExecutor-3295-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 817717 INFO (coreLoadExecutor-3295-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@6fc5a7f3[collection1] main]
[junit4] 2> 817717 WARN (coreLoadExecutor-3295-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage Cannot write to config directory C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\core\src\test-files\solr\collection1\conf; switching to use InMemory storage instead.
[junit4] 2> 817717 INFO (coreLoadExecutor-3295-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 817717 INFO (searcherExecutor-3296-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@6fc5a7f3[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 817717 INFO (SUITE-TestNRTOpen-seed#[60AADBE56D33CF4F]-worker) [ ] o.a.s.SolrTestCaseJ4 ####initCore end
[junit4] 2> 817717 INFO (SUITE-TestNRTOpen-seed#[60AADBE56D33CF4F]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[(null)]} 0 1
[junit4] 2> 817717 INFO (SUITE-TestNRTOpen-seed#[60AADBE56D33CF4F]-worker) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 817717 INFO (SUITE-TestNRTOpen-seed#[60AADBE56D33CF4F]-worker) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@21bf97f3 commitCommandVersion:0
[junit4] 2> 817733 INFO (SUITE-TestNRTOpen-seed#[60AADBE56D33CF4F]-worker) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@6fdc5c6e[collection1] main]
[junit4] 2> 817733 INFO (SUITE-TestNRTOpen-seed#[60AADBE56D33CF4F]-worker) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 817733 INFO (searcherExecutor-3296-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@6fdc5c6e[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(8.0.0):c1)))}
[junit4] 2> 817733 INFO (SUITE-TestNRTOpen-seed#[60AADBE56D33CF4F]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 15
[junit4] 2> 817733 INFO (SUITE-TestNRTOpen-seed#[60AADBE56D33CF4F]-worker) [ ] o.a.s.SolrTestCaseJ4 ###deleteCore
[junit4] 2> 817733 INFO (SUITE-TestNRTOpen-seed#[60AADBE56D33CF4F]-worker) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1467529138
[junit4] 2> 817733 INFO (SUITE-TestNRTOpen-seed#[60AADBE56D33CF4F]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 817733 INFO (SUITE-TestNRTOpen-seed#[60AADBE56D33CF4F]-worker) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@2e17d9b: rootName = null, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@61994edd
[junit4] 2> 817751 INFO (SUITE-TestNRTOpen-seed#[60AADBE56D33CF4F]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 817751 INFO (SUITE-TestNRTOpen-seed#[60AADBE56D33CF4F]-worker) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@5c458ebf: rootName = null, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@9eab586
[junit4] 2> 817751 INFO (SUITE-TestNRTOpen-seed#[60AADBE56D33CF4F]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 817751 INFO (SUITE-TestNRTOpen-seed#[60AADBE56D33CF4F]-worker) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@ed5b751: rootName = null, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@3a7829ac
[junit4] 2> 817751 INFO (coreCloseExecutor-3300-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@7532c849
[junit4] 2> 817751 INFO (coreCloseExecutor-3300-thread-1) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=1966262345
[junit4] 2> 817751 INFO (coreCloseExecutor-3300-thread-1) [ x:collection1] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@97df2c2: rootName = null, domain = solr.core.collection1, service url = null, agent id = null] for registry solr.core.collection1 / com.codahale.metrics.MetricRegistry@14aaad18
[junit4] 2> 817763 INFO (SUITE-TestNRTOpen-seed#[60AADBE56D33CF4F]-worker) [ ] o.a.s.SolrTestCaseJ4 ####initCore
[junit4] 2> 817763 INFO (SUITE-TestNRTOpen-seed#[60AADBE56D33CF4F]-worker) [ ] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/C:/Users/jenkins/workspace/Lucene-Solr-master-Windows/solr/core/src/test-files/solr/collection1/lib, /C:/Users/jenkins/workspace/Lucene-Solr-master-Windows/solr/core/src/test-files/solr/collection1/lib/classes]
[junit4] 2> 817795 INFO (SUITE-TestNRTOpen-seed#[60AADBE56D33CF4F]-worker) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
[junit4] 2> 817795 INFO (SUITE-TestNRTOpen-seed#[60AADBE56D33CF4F]-worker) [ ] o.a.s.s.IndexSchema [null] Schema name=minimal
[junit4] 2> 817795 WARN (SUITE-TestNRTOpen-seed#[60AADBE56D33CF4F]-worker) [ ] o.a.s.s.IndexSchema no uniqueKey specified in schema.
[junit4] 2> 817795 INFO (SUITE-TestNRTOpen-seed#[60AADBE56D33CF4F]-worker) [ ] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field null
[junit4] 2> 817871 INFO (SUITE-TestNRTOpen-seed#[60AADBE56D33CF4F]-worker) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5f85acd6
[junit4] 2> 817871 INFO (SUITE-TestNRTOpen-seed#[60AADBE56D33CF4F]-worker) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5f85acd6
[junit4] 2> 817871 INFO (SUITE-TestNRTOpen-seed#[60AADBE56D33CF4F]-worker) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5f85acd6
[junit4] 2> 817871 INFO (coreLoadExecutor-3307-thread-1) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 817871 INFO (coreLoadExecutor-3307-thread-1) [ x:collection1] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/C:/Users/jenkins/workspace/Lucene-Solr-master-Windows/solr/core/src/test-files/solr/collection1/lib, /C:/Users/jenkins/workspace/Lucene-Solr-master-Windows/solr/core/src/test-files/solr/collection1/lib/classes]
[junit4] 2> 817918 INFO (coreLoadExecutor-3307-thread-1) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
[junit4] 2> 817918 INFO (coreLoadExecutor-3307-thread-1) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=minimal
[junit4] 2> 817918 WARN (coreLoadExecutor-3307-thread-1) [ x:collection1] o.a.s.s.IndexSchema no uniqueKey specified in schema.
[junit4] 2> 817918 INFO (coreLoadExecutor-3307-thread-1) [ x:collection1] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field null
[junit4] 2> 817918 INFO (coreLoadExecutor-3307-thread-1) [ x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\core\src\test-files\solr\collection1, trusted=true
[junit4] 2> 817918 INFO (coreLoadExecutor-3307-thread-1) [ x:collection1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.collection1' (registry 'solr.core.collection1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5f85acd6
[junit4] 2> 817918 INFO (coreLoadExecutor-3307-thread-1) [ x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 817918 INFO (coreLoadExecutor-3307-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\core\src\test-files\solr\collection1], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.core.TestNRTOpen_60AADBE56D33CF4F-001\init-core-data-001\]
[junit4] 2> 817981 INFO (coreLoadExecutor-3307-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 817981 INFO (coreLoadExecutor-3307-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 817981 INFO (coreLoadExecutor-3307-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@5641b093[collection1] main]
[junit4] 2> 817981 WARN (coreLoadExecutor-3307-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage Cannot write to config directory C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\core\src\test-files\solr\collection1\conf; switching to use InMemory storage instead.
[junit4] 2> 817981 INFO (coreLoadExecutor-3307-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 817981 INFO (searcherExecutor-3308-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@5641b093[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(8.0.0):c1)))}
[junit4] 2> 817981 INFO (SUITE-TestNRTOpen-seed#[60AADBE56D33CF4F]-worker) [ ] o.a.s.SolrTestCaseJ4 ####initCore end
[junit4] 2> 817981 INFO (TEST-TestNRTOpen.testReaderIsNRT-seed#[60AADBE56D33CF4F]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testReaderIsNRT
[junit4] 2> 817981 INFO (TEST-TestNRTOpen.testReaderIsNRT-seed#[60AADBE56D33CF4F]) [ ] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 817981 INFO (TEST-TestNRTOpen.testReaderIsNRT-seed#[60AADBE56D33CF4F]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{deleteByQuery=*:*} 0 0
[junit4] 2> 817981 INFO (TEST-TestNRTOpen.testReaderIsNRT-seed#[60AADBE56D33CF4F]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[(null)]} 0 0
[junit4] 2> 817981 INFO (TEST-TestNRTOpen.testReaderIsNRT-seed#[60AADBE56D33CF4F]) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 817981 INFO (TEST-TestNRTOpen.testReaderIsNRT-seed#[60AADBE56D33CF4F]) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@29af9fe commitCommandVersion:0
[junit4] 2> 817996 INFO (TEST-TestNRTOpen.testReaderIsNRT-seed#[60AADBE56D33CF4F]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@1e54fb85[collection1] main]
[junit4] 2> 817996 INFO (TEST-TestNRTOpen.testReaderIsNRT-seed#[60AADBE56D33CF4F]) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 817996 INFO (searcherExecutor-3308-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@1e54fb85[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_1(8.0.0):c1)))}
[junit4] 2> 817996 INFO (TEST-TestNRTOpen.testReaderIsNRT-seed#[60AADBE56D33CF4F]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 11
[junit4] 2> 817996 ERROR (TEST-TestNRTOpen.testReaderIsNRT-seed#[60AADBE56D33CF4F]) [ ] o.a.s.c.CorePropertiesLocator Couldn't load core descriptor from C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\core\src\test-files\solr\collection1\core.properties:java.nio.file.NoSuchFileException: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\core\src\test-files\solr\collection1\core.properties
[junit4] 2> 817996 INFO (TEST-TestNRTOpen.testReaderIsNRT-seed#[60AADBE56D33CF4F]) [ ] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/C:/Users/jenkins/workspace/Lucene-Solr-master-Windows/solr/core/src/test-files/solr/collection1/lib, /C:/Users/jenkins/workspace/Lucene-Solr-master-Windows/solr/core/src/test-files/solr/collection1/lib/classes]
[junit4] 2> 818043 INFO (TEST-TestNRTOpen.testReaderIsNRT-seed#[60AADBE56D33CF4F]) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
[junit4] 2> 818049 INFO (TEST-TestNRTOpen.testReaderIsNRT-seed#[60AADBE56D33CF4F]) [ ] o.a.s.s.IndexSchema [collection1] Schema name=minimal
[junit4] 2> 818056 WARN (TEST-TestNRTOpen.testReaderIsNRT-seed#[60AADBE56D33CF4F]) [ ] o.a.s.s.IndexSchema no uniqueKey specified in schema.
[junit4] 2> 818056 INFO (TEST-TestNRTOpen.testReaderIsNRT-seed#[60AADBE56D33CF4F]) [ ] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field null
[junit4] 2> 818056 INFO (TEST-TestNRTOpen.testReaderIsNRT-seed#[60AADBE56D33CF4F]) [ ] o.a.s.c.CoreContainer Reloading SolrCore 'collection1' using configuration from instancedir C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\core\src\test-files\solr\collection1
[junit4] 2> 818072 INFO (TEST-TestNRTOpen.testReaderIsNRT-seed#[60AADBE56D33CF4F]) [ x:collection1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.collection1' (registry 'solr.core.collection1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5f85acd6
[junit4] 2> 818072 INFO (TEST-TestNRTOpen.testReaderIsNRT-seed#[60AADBE56D33CF4F]) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\core\src\test-files\solr\collection1], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.core.TestNRTOpen_60AADBE56D33CF4F-001\init-core-data-001\]
[junit4] 2> 818131 INFO (TEST-TestNRTOpen.testReaderIsNRT-seed#[60AADBE56D33CF4F]) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 818131 INFO (TEST-TestNRTOpen.testReaderIsNRT-seed#[60AADBE56D33CF4F]) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 818132 INFO (TEST-TestNRTOpen.testReaderIsNRT-seed#[60AADBE56D33CF4F]) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@3aef4c35[collection1] main]
[junit4] 2> 818133 WARN (TEST-TestNRTOpen.testReaderIsNRT-seed#[60AADBE56D33CF4F]) [ x:collection1] o.a.s.r.ManagedResourceStorage Cannot write to config directory C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\core\src\test-files\solr\collection1\conf; switching to use InMemory storage instead.
[junit4] 2> 818133 INFO (TEST-TestNRTOpen.testReaderIsNRT-seed#[60AADBE56D33CF4F]) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 818135 INFO (TEST-TestNRTOpen.testReaderIsNRT-seed#[60AADBE56D33CF4F]) [ x:collection1] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
[junit4] 2> 818135 INFO (searcherExecutor-3312-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@3aef4c35[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_1(8.0.0):c1)))}
[junit4] 2> 818135 INFO (TEST-TestNRTOpen.testReaderIsNRT-seed#[60AADBE56D33CF4F]) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@63184bf0[collection1] main]
[junit4] 2> 818135 INFO (TEST-TestNRTOpen.testReaderIsNRT-seed#[60AADBE56D33CF4F]) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@f89b825
[junit4] 2> 818136 INFO (TEST-TestNRTOpen.testReaderIsNRT-seed#[60AADBE56D33CF4F]) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=260683813
[junit4] 2> 818136 INFO (TEST-TestNRTOpen.testReaderIsNRT-seed#[60AADBE56D33CF4F]) [ x:collection1] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@466e7534: rootName = null, domain = solr.core.collection1, service url = null, agent id = null] for registry solr.core.collection1 / com.codahale.metrics.MetricRegistry@2d71cfc2
[junit4] 2> 818136 INFO (searcherExecutor-3312-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@63184bf0[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_1(8.0.0):c1)))}
[junit4] 2> 818141 INFO (TEST-TestNRTOpen.testReaderIsNRT-seed#[60AADBE56D33CF4F]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[(null)]} 0 0
[junit4] 2> 818141 INFO (TEST-TestNRTOpen.testReaderIsNRT-seed#[60AADBE56D33CF4F]) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
[junit4] 2> 818147 INFO (TEST-TestNRTOpen.testReaderIsNRT-seed#[60AADBE56D33CF4F]) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@5c97253f[collection1] main]
[junit4] 2> 818148 INFO (searcherExecutor-3312-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@5c97253f[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_1(8.0.0):c1) Uninverting(_2(8.0.0):c1)))}
[junit4] 2> 818148 INFO (TEST-TestNRTOpen.testReaderIsNRT-seed#[60AADBE56D33CF4F]) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 818148 INFO (TEST-TestNRTOpen.testReaderIsNRT-seed#[60AADBE56D33CF4F]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 6
[junit4] 2> 818149 INFO (TEST-TestNRTOpen.testReaderIsNRT-seed#[60AADBE56D33CF4F]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[(null)]} 0 0
[junit4] 2> 818149 INFO (TEST-TestNRTOpen.testReaderIsNRT-seed#[60AADBE56D33CF4F]) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 818149 INFO (TEST-TestNRTOpen.testReaderIsNRT-seed#[60AADBE56D33CF4F]) [ x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@18199126 commitCommandVersion:0
[junit4] 2> 818159 INFO (TEST-TestNRTOpen.testReaderIsNRT-seed#[60AADBE56D33CF4F]) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@58ecdd8b[collection1] main]
[junit4] 2> 818159 INFO (TEST-TestNRTOpen.testReaderIsNRT-seed#[60AADBE56D33CF4F]) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 818160 INFO (searcherExecutor-3312-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@58ecdd8b[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_1(8.0.0):c1) Uninverting(_2(8.0.0):c1) Uninverting(_3(8.0.0):c1)))}
[junit4] 2> 818160 INFO (TEST-TestNRTOpen.testReaderIsNRT-seed#[60AADBE56D33CF4F]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 10
[junit4] 2> 818161 INFO (TEST-TestNRTOpen.testReaderIsNRT-seed#[60AADBE56D33CF4F]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[(null)]} 0 0
[junit4] 2> 818162 ERROR (TEST-TestNRTOpen.testReaderIsNRT-seed#[60AADBE56D33CF4F]) [ x:collection1] o.a.s.c.CorePropertiesLocator Couldn't load core descriptor from C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\core\src\test-files\solr\collection1\core.properties:java.nio.file.NoSuchFileException: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\core\src\test-files\solr\collection1\core.properties
[junit4] 2> 818163 INFO (TEST-TestNRTOpen.testReaderIsNRT-seed#[60AADBE56D33CF4F]) [ x:collection1] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/C:/Users/jenkins/workspace/Lucene-Solr-master-Windows/solr/core/src/test-files/solr/collection1/lib, /C:/Users/jenkins/workspace/Lucene-Solr-master-Windows/solr/core/src/test-files/solr/collection1/lib/classes]
[junit4] 2> 818202 INFO (TEST-TestNRTOpen.testReaderIsNRT-seed#[60AADBE56D33CF4F]) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
[junit4] 2> 818207 INFO (TEST-TestNRTOpen.testReaderIsNRT-seed#[60AADBE56D33CF4F]) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=minimal
[junit4] 2> 818209 WARN (TEST-TestNRTOpen.testReaderIsNRT-seed#[60AADBE56D33CF4F]) [ x:collection1] o.a.s.s.IndexSchema no uniqueKey specified in schema.
[junit4] 2> 818210 INFO (TEST-TestNRTOpen.testReaderIsNRT-seed#[60AADBE56D33CF4F]) [ x:collection1] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field null
[junit4] 2> 818210 INFO (TEST-TestNRTOpen.testReaderIsNRT-seed#[60AADBE56D33CF4F]) [ x:collection1] o.a.s.c.CoreContainer Reloading SolrCore 'collection1' using configuration from instancedir C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\core\src\test-files\solr\collection1
[junit4] 2> 818223 INFO (TEST-TestNRTOpen.testReaderIsNRT-seed#[60AADBE56D33CF4F]) [ x:collection1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.collection1' (registry 'solr.core.collection1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5f85acd6
[junit4] 2> 818223 INFO (TEST-TestNRTOpen.testReaderIsNRT-seed#[60AADBE56D33CF4F]) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\core\src\test-files\solr\collection1], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.core.TestNRTOpen_60AADBE56D33CF4F-001\init-core-data-001\]
[junit4] 2> 818287 INFO (TEST-TestNRTOpen.testReaderIsNRT-seed#[60AADBE56D33CF4F]) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 818287 INFO (TEST-TestNRTOpen.testReaderIsNRT-seed#[60AADBE56D33CF4F]) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 818294 INFO (TEST-TestNRTOpen.testReaderIsNRT-seed#[60AADBE56D33CF4F]) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@24bb646d[collection1] main]
[junit4] 2> 818294 WARN (TEST-TestNRTOpen.testReaderIsNRT-seed#[60AADBE56D33CF4F]) [ x:collection1] o.a.s.r.ManagedResourceStorage Cannot write to config directory C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\core\src\test-files\solr\collection1\conf; switching to use InMemory storage instead.
[junit4] 2> 818295 INFO (TEST-TestNRTOpen.testReaderIsNRT-seed#[60AADBE56D33CF4F]) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 818298 INFO (searcherExecutor-3316-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@24bb646d[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_1(8.0.0):c1) Uninverting(_2(8.0.0):c1) Uninverting(_3(8.0.0):c1) Uninverting(_4(8.0.0):c1)))}
[junit4] 2> 818302 INFO (TEST-TestNRTOpen.testReaderIsNRT-seed#[60AADBE56D33CF4F]) [ x:collection1] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
[junit4] 2> 818304 INFO (TEST-TestNRTOpen.testReaderIsNRT-seed#[60AADBE56D33CF4F]) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@10719de6[collection1] main]
[junit4] 2> 818305 INFO (TEST-TestNRTOpen.testReaderIsNRT-seed#[60AADBE56D33CF4F]) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@49950df
[junit4] 2> 818305 INFO (TEST-TestNRTOpen.testReaderIsNRT-seed#[60AADBE56D33CF4F]) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=77156575
[junit4] 2> 818305 INFO (TEST-TestNRTOpen.testReaderIsNRT-seed#[60AADBE56D33CF4F]) [ x:collection1] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@44ca1c89: rootName = null, domain = solr.core.collection1, service url = null, agent id = null] for registry solr.core.collection1 / com.codahale.metrics.MetricRegistry@2d71cfc2
[junit4] 2> 818315 INFO (searcherExecutor-3316-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@10719de6[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_1(8.0.0):c1) Uninverting(_2(8.0.0):c1) Uninverting(_3(8.0.0):c1) Uninverting(_4(8.0.0):c1)))}
[junit4] 2> 818318 INFO (TEST-TestNRTOpen.testReaderIsNRT-seed#[60AADBE56D33CF4F]) [ x:collection1] o.a.s.SolrTestCaseJ4 ###Ending testReaderIsNRT
[junit4] 2> 818320 INFO (TEST-TestNRTOpen.testSharedCores-seed#[60AADBE56D33CF4F]) [ x:collection1] o.a.s.SolrTestCaseJ4 ###Starting testSharedCores
[junit4] 2> 818320 INFO (TEST-TestNRTOpen.testSharedCores-seed#[60AADBE56D33CF4F]) [ x:collection1] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 818320 INFO (TEST-TestNRTOpen.testSharedCores-seed#[60AADBE56D33CF4F]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{deleteByQuery=*:*} 0 0
[junit4] 2> 818321 INFO (TEST-TestNRTOpen.testSharedCores-seed#[60AADBE56D33CF4F]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[(null)]} 0 0
[junit4] 2> 818322 INFO (TEST-TestNRTOpen.testSharedCores-seed#[60AADBE56D33CF4F]) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 818322 INFO (TEST-TestNRTOpen.testSharedCores-seed#[60AADBE56D33CF4F]) [ x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@3b46f144 commitCommandVersion:0
[junit4] 2> 818332 INFO (TEST-TestNRTOpen.testSharedCores-seed#[60AADBE56D33CF4F]) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@648cdde5[collection1] main]
[junit4] 2> 818333 INFO (TEST-TestNRTOpen.testSharedCores-seed#[60AADBE56D33CF4F]) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 818333 INFO (searcherExecutor-3316-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@648cdde5[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_5(8.0.0):c1)))}
[junit4] 2> 818335 INFO (TEST-TestNRTOpen.testSharedCores-seed#[60AADBE56D33CF4F]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 13
[junit4] 2> 818336 INFO (TEST-TestNRTOpen.testSharedCores-seed#[60AADBE56D33CF4F]) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=true,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 818336 WARN (TEST-TestNRTOpen.testSharedCores-seed#[60AADBE56D33CF4F]) [ x:collection1] o.a.s.u.DirectUpdateHandler2 Starting optimize... Reading and rewriting the entire index! Use with care.
[junit4] 2> 818341 INFO (TEST-TestNRTOpen.testSharedCores-seed#[60AADBE56D33CF4F]) [ x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@3b46f144 commitCommandVersion:0
[junit4] 2> 818346 INFO (TEST-TestNRTOpen.testSharedCores-seed#[60AADBE56D33CF4F]) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@70d55df0[collection1] main]
[junit4] 2> 818347 INFO (TEST-TestNRTOpen.testSharedCores-seed#[60AADBE56D33CF4F]) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 818347 INFO (searcherExecutor-3316-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@70d55df0[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_6(8.0.0):C1)))}
[junit4] 2> 818348 INFO (TEST-TestNRTOpen.testSharedCores-seed#[60AADBE56D33CF4F]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{optimize=} 0 12
[junit4] 2> 818349 INFO (TEST-TestNRTOpen.testSharedCores-seed#[60AADBE56D33CF4F]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[(null)]} 0 0
[junit4] 2> 818350 INFO (TEST-TestNRTOpen.testSharedCores-seed#[60AADBE56D33CF4F]) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
[junit4] 2> 818358 INFO (TEST-TestNRTOpen.testSharedCores-seed#[60AADBE56D33CF4F]) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@65dcb44a[collection1] main]
[junit4] 2> 818358 INFO (TEST-TestNRTOpen.testSharedCores-seed#[60AADBE56D33CF4F]) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 818358 INFO (searcherExecutor-3316-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@65dcb44a[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_6(8.0.0):C1) Uninverting(_7(8.0.0):c1)))}
[junit4] 2> 818359 INFO (TEST-TestNRTOpen.testSharedCores-seed#[60AADBE56D33CF4F]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 8
[junit4] 2> 818360 INFO (TEST-TestNRTOpen.testSharedCores-seed#[60AADBE56D33CF4F]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[(null)]} 0 0
[junit4] 2> 818360 INFO (TEST-TestNRTOpen.testSharedCores-seed#[60AADBE56D33CF4F]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[(null)]} 0 0
[junit4] 2> 818361 INFO (TEST-TestNRTOpen.testSharedCores-seed#[60AADBE56D33CF4F]) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 818361 INFO (TEST-TestNRTOpen.testSharedCores-seed#[60AADBE56D33CF4F]) [ x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@3b46f144 commitCommandVersion:0
[junit4] 2> 818375 INFO (TEST-TestNRTOpen.testSharedCores-seed#[60AADBE56D33CF4F]) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@119387f4[collection1] main]
[junit4] 2> 818377 INFO (TEST-TestNRTOpen.testSharedCores-seed#[60AADBE56D33CF4F]) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 818377 INFO (searcherExecutor-3316-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@119387f4[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_6(8.0.0):C1) Uninverting(_7(8.0.0):c1) Uninverting(_8(8.0.0):c2)))}
[junit4] 2> 818377 INFO (TEST-TestNRTOpen.testSharedCores-seed#[60AADBE56D33CF4F]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 15
[junit4] 2> 818378 INFO (TEST-TestNRTOpen.testSharedCores-seed#[60AADBE56D33CF4F]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{deleteByQuery=foo2:doc} 0 0
[junit4] 2> 818378 INFO (TEST-TestNRTOpen.testSharedCores-seed#[60AADBE56D33CF4F]) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 818378 INFO (TEST-TestNRTOpen.testSharedCores-seed#[60AADBE56D33CF4F]) [ x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@3b46f144 commitCommandVersion:0
[junit4] 2> 818383 INFO (TEST-TestNRTOpen.testSharedCores-seed#[60AADBE56D33CF4F]) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@594bcd06[collection1] main]
[junit4] 2> 818384 INFO (TEST-TestNRTOpen.testSharedCores-seed#[60AADBE56D33CF4F]) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 818385 INFO (searcherExecutor-3316-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@594bcd06[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_6(8.0.0):C1) Uninverting(_7(8.0.0):c1) Uninverting(_8(8.0.0):c2/1:delGen=1)))}
[junit4] 2> 818385 INFO (TEST-TestNRTOpen.testSharedCores-seed#[60AADBE56D33CF4F]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 7
[junit4] 2> 818385 INFO (TEST-TestNRTOpen.testSharedCores-seed#[60AADBE56D33CF4F]) [ x:collection1] o.a.s.SolrTestCaseJ4 ###Ending testSharedCores
[junit4] 2> 818386 INFO (SUITE-TestNRTOpen-seed#[60AADBE56D33CF4F]-worker) [ x:collection1] o.a.s.SolrTestCaseJ4 ###deleteCore
[junit4] 2> 818386 INFO (SUITE-TestNRTOpen-seed#[60AADBE56D33CF4F]-worker) [ x:collection1] o.a.s.c.CoreContainer Shutting down CoreContainer instance=2090610964
[junit4] 2> 818386 INFO (SUITE-TestNRTOpen-seed#[60AADBE56D33CF4F]-worker) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 818386 INFO (SUITE-TestNRTOpen-seed#[60AADBE56D33CF4F]-worker) [ x:collection1] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@6ed8c3a9: rootName = null, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@5680d00a
[junit4] 2> 818390 INFO (SUITE-TestNRTOpen-seed#[60AADBE56D33CF4F]-worker) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 818390 INFO (SUITE-TestNRTOpen-seed#[60AADBE56D33CF4F]-worker) [ x:collection1] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@ad7dfc1: rootName = null, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@9eab586
[junit4] 2> 818392 INFO (SUITE-TestNRTOpen-seed#[60AADBE56D33CF4F]-worker) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 818392 INFO (SUITE-TestNRTOpen-seed#[60AADBE56D33CF4F]-worker) [ x:collection1] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@29f0149c: rootName = null, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@3a7829ac
[junit4] 2> 818393 INFO (coreCloseExecutor-3320-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@40d62252
[junit4] 2> 818393 INFO (coreCloseExecutor-3320-thread-1-processing-x:collection1) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=1087775314
[junit4] 2> 818393 INFO (coreCloseExecutor-3320-thread-1-processing-x:collection1) [ x:collection1] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@2ebb2cc7: rootName = null, domain = solr.core.collection1, service url = null, agent id = null] for registry solr.core.collection1 / com.codahale.metrics.MetricRegistry@2d71cfc2
[junit4] 2> 818399 INFO (SUITE-TestNRTOpen-seed#[60AADBE56D33CF4F]-worker) [ x:collection1] o.a.s.SolrTestCaseJ4 ------------------------------------------------------- Done waiting for tracked resources to be released
[junit4] 2> Oct 23, 2017 1:31:04 PM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
[junit4] 2> WARNING: Will linger awaiting termination of 1 leaked thread(s).
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene70), sim=RandomSimilarity(queryNorm=true): {}, locale=es-PE, timezone=America/Thunder_Bay
[junit4] 2> NOTE: Windows 10 10.0 amd64/Oracle Corporation 1.8.0_144 (64-bit)/cpus=3,threads=1,free=319662320,total=518979584
[junit4] 2> NOTE: All tests run in this JVM: [VMParamsZkACLAndCredentialsProvidersTest, TestManagedSchemaThreadSafety, SortSpecParsingTest, TestRandomFaceting, TestSolrCloudWithHadoopAuthPlugin, ConjunctionSolrSpellCheckerTest, TestStressLiveNodes, TestCloudRecovery, OpenCloseCoreStressTest, TestConfigSetProperties, DistributedFacetPivotSmallAdvancedTest, CdcrRequestHandlerTest, TestSolr4Spatial2, DeleteLastCustomShardedReplicaTest, QueryEqualityTest, LeaderInitiatedRecoveryOnShardRestartTest, DeleteReplicaTest, TestBadConfig, DocumentAnalysisRequestHandlerTest, CopyFieldTest, RuleEngineTest, JavabinLoaderTest, UpdateRequestProcessorFactoryTest, TestRemoteStreaming, TestStressVersions, TestRandomCollapseQParserPlugin, TestMacros, TestClusterProperties, TestSchemaVersionResource, SpellPossibilityIteratorTest, LukeRequestHandlerTest, TestAnalyzedSuggestions, TestDistribDocBasedVersion, EchoParamsTest, BadComponentTest, SynonymTokenizerTest, TestPayloadScoreQParserPlugin, TestGroupingSearch, TestSchemaManager, ShardSplitTest, TestInPlaceUpdatesDistrib, TestLeaderInitiatedRecoveryThread, CdcrReplicationDistributedZkTest, TestDocumentBuilder, SystemInfoHandlerTest, TestSolrQueryParser, HdfsBasicDistributedZkTest, TestFastLRUCache, DistributedExpandComponentTest, EnumFieldTest, MissingSegmentRecoveryTest, TemplateUpdateProcessorTest, BlockJoinFacetSimpleTest, SearchHandlerTest, HdfsWriteToMultipleCollectionsTest, TestLRUCache, TestComplexPhraseQParserPlugin, HdfsChaosMonkeySafeLeaderTest, TestBlobHandler, FullSolrCloudDistribCmdsTest, TestScoreJoinQPNoScore, LeaderElectionContextKeyTest, TestNumericTokenStream, TestChildDocTransformer, DistributedFacetPivotWhiteBoxTest, TestSolrIndexConfig, SimplePostToolTest, TestExactSharedStatsCache, QueryResultKeyTest, TestCollapseQParserPlugin, DocValuesNotIndexedTest, TestLeaderElectionWithEmptyReplica, ZkSolrClientTest, AnalysisAfterCoreReloadTest, PeerSyncTest, SimpleMLTQParserTest, AnalyticsMergeStrategyTest, SolrIndexMetricsTest, TestMaxScoreQueryParser, SOLR749Test, RequestLoggingTest, TestSortByMinMaxFunction, TestCopyFieldCollectionResource, DistributedQueryComponentOptimizationTest, TestSolrConfigHandler, TestSort, CoreMergeIndexesAdminHandlerTest, PluginInfoTest, TestNRTOpen]
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestNRTOpen -Dtests.seed=60AADBE56D33CF4F -Dtests.slow=true -Dtests.locale=es-PE -Dtests.timezone=America/Thunder_Bay -Dtests.asserts=true -Dtests.file.encoding=ISO-8859-1
[junit4] ERROR 0.00s J1 | TestNRTOpen (suite) <<<
[junit4] > Throwable #1: java.io.IOException: Could not remove the following files (in the order of attempts):
[junit4] > C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.core.TestNRTOpen_60AADBE56D33CF4F-001\init-core-data-001: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.core.TestNRTOpen_60AADBE56D33CF4F-001\init-core-data-001
[junit4] > at __randomizedtesting.SeedInfo.seed([60AADBE56D33CF4F]:0)
[junit4] > at org.apache.lucene.util.IOUtils.rm(IOUtils.java:329)
[junit4] > at java.lang.Thread.run(Thread.java:748)
[junit4] Completed [168/745 (1!)] on J1 in 1.18s, 2 tests, 1 error <<< FAILURES!
[...truncated 49133 lines...]
[JENKINS] Lucene-Solr-master-Windows (64bit/jdk-9) - Build # 6973 -
Still Unstable!
Posted by Policeman Jenkins Server <je...@thetaphi.de>.
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Windows/6973/
Java: 64bit/jdk-9 -XX:-UseCompressedOops -XX:+UseConcMarkSweepGC --illegal-access=deny
3 tests failed.
FAILED: org.apache.lucene.replicator.IndexAndTaxonomyReplicationClientTest.testConsistencyOnExceptions
Error Message:
Captured an uncaught exception in thread: Thread[id=22, name=ReplicationThread-indexAndTaxo, state=RUNNABLE, group=TGRP-IndexAndTaxonomyReplicationClientTest]
Stack Trace:
com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught exception in thread: Thread[id=22, name=ReplicationThread-indexAndTaxo, state=RUNNABLE, group=TGRP-IndexAndTaxonomyReplicationClientTest]
at __randomizedtesting.SeedInfo.seed([F83F3B6DDC6A281F:77B1DCCDCE06DBE0]:0)
Caused by: java.lang.AssertionError: handler failed too many times: -1
at __randomizedtesting.SeedInfo.seed([F83F3B6DDC6A281F]:0)
at org.apache.lucene.replicator.IndexAndTaxonomyReplicationClientTest$4.handleUpdateException(IndexAndTaxonomyReplicationClientTest.java:422)
at org.apache.lucene.replicator.ReplicationClient$ReplicationThread.run(ReplicationClient.java:77)
FAILED: org.apache.solr.cloud.TestCollectionsAPIViaSolrCloudCluster.testCollectionCreateSearchDelete
Error Message:
Error from server at http://127.0.0.1:61267/solr/testcollection_shard1_replica_n2: Expected mime type application/octet-stream but got text/html. <html> <head> <meta http-equiv="Content-Type" content="text/html;charset=ISO-8859-1"/> <title>Error 404 </title> </head> <body> <h2>HTTP ERROR: 404</h2> <p>Problem accessing /solr/testcollection_shard1_replica_n2/update. Reason: <pre> Can not find: /solr/testcollection_shard1_replica_n2/update</pre></p> <hr /><a href="http://eclipse.org/jetty">Powered by Jetty:// 9.3.20.v20170531</a><hr/> </body> </html>
Stack Trace:
org.apache.solr.client.solrj.impl.CloudSolrClient$RouteException: Error from server at http://127.0.0.1:61267/solr/testcollection_shard1_replica_n2: Expected mime type application/octet-stream but got text/html. <html>
<head>
<meta http-equiv="Content-Type" content="text/html;charset=ISO-8859-1"/>
<title>Error 404 </title>
</head>
<body>
<h2>HTTP ERROR: 404</h2>
<p>Problem accessing /solr/testcollection_shard1_replica_n2/update. Reason:
<pre> Can not find: /solr/testcollection_shard1_replica_n2/update</pre></p>
<hr /><a href="http://eclipse.org/jetty">Powered by Jetty:// 9.3.20.v20170531</a><hr/>
</body>
</html>
at __randomizedtesting.SeedInfo.seed([216A22F099D20562:82908C551E3AEFC7]:0)
at org.apache.solr.client.solrj.impl.CloudSolrClient.directUpdate(CloudSolrClient.java:541)
at org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:998)
at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:867)
at org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:800)
at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:178)
at org.apache.solr.client.solrj.request.UpdateRequest.commit(UpdateRequest.java:233)
at org.apache.solr.cloud.TestCollectionsAPIViaSolrCloudCluster.testCollectionCreateSearchDelete(TestCollectionsAPIViaSolrCloudCluster.java:167)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.base/java.lang.reflect.Method.invoke(Method.java:564)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1737)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:943)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at 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.base/java.lang.Thread.run(Thread.java:844)
Caused by: org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at http://127.0.0.1:61267/solr/testcollection_shard1_replica_n2: Expected mime type application/octet-stream but got text/html. <html>
<head>
<meta http-equiv="Content-Type" content="text/html;charset=ISO-8859-1"/>
<title>Error 404 </title>
</head>
<body>
<h2>HTTP ERROR: 404</h2>
<p>Problem accessing /solr/testcollection_shard1_replica_n2/update. Reason:
<pre> Can not find: /solr/testcollection_shard1_replica_n2/update</pre></p>
<hr /><a href="http://eclipse.org/jetty">Powered by Jetty:// 9.3.20.v20170531</a><hr/>
</body>
</html>
at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:590)
at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:253)
at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:242)
at org.apache.solr.client.solrj.impl.LBHttpSolrClient.doRequest(LBHttpSolrClient.java:483)
at org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:413)
at org.apache.solr.client.solrj.impl.CloudSolrClient.lambda$directUpdate$0(CloudSolrClient.java:518)
at java.base/java.util.concurrent.FutureTask.run(FutureTask.java:264)
at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:188)
at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1167)
at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:641)
... 1 more
FAILED: org.apache.solr.cloud.autoscaling.ExecutePlanActionTest.testIntegration
Error Message:
Timed out waiting for replicas of collection to be 2 again null Live Nodes: [127.0.0.1:53683_solr] Last available state: DocCollection(testIntegration//collections/testIntegration/state.json/7)={ "pullReplicas":"0", "replicationFactor":"2", "shards":{"shard1":{ "range":"80000000-7fffffff", "state":"active", "replicas":{ "core_node3":{ "core":"testIntegration_shard1_replica_n1", "base_url":"https://127.0.0.1:53688/solr", "node_name":"127.0.0.1:53688_solr", "state":"down", "type":"NRT"}, "core_node4":{ "core":"testIntegration_shard1_replica_n2", "base_url":"https://127.0.0.1:53683/solr", "node_name":"127.0.0.1:53683_solr", "state":"active", "type":"NRT", "leader":"true"}}}}, "router":{"name":"compositeId"}, "maxShardsPerNode":"1", "autoAddReplicas":"false", "nrtReplicas":"2", "tlogReplicas":"0"}
Stack Trace:
java.lang.AssertionError: Timed out waiting for replicas of collection to be 2 again
null
Live Nodes: [127.0.0.1:53683_solr]
Last available state: DocCollection(testIntegration//collections/testIntegration/state.json/7)={
"pullReplicas":"0",
"replicationFactor":"2",
"shards":{"shard1":{
"range":"80000000-7fffffff",
"state":"active",
"replicas":{
"core_node3":{
"core":"testIntegration_shard1_replica_n1",
"base_url":"https://127.0.0.1:53688/solr",
"node_name":"127.0.0.1:53688_solr",
"state":"down",
"type":"NRT"},
"core_node4":{
"core":"testIntegration_shard1_replica_n2",
"base_url":"https://127.0.0.1:53683/solr",
"node_name":"127.0.0.1:53683_solr",
"state":"active",
"type":"NRT",
"leader":"true"}}}},
"router":{"name":"compositeId"},
"maxShardsPerNode":"1",
"autoAddReplicas":"false",
"nrtReplicas":"2",
"tlogReplicas":"0"}
at __randomizedtesting.SeedInfo.seed([216A22F099D20562:910B2CDCBCEDA447]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.apache.solr.cloud.SolrCloudTestCase.waitForState(SolrCloudTestCase.java:269)
at org.apache.solr.cloud.autoscaling.ExecutePlanActionTest.testIntegration(ExecutePlanActionTest.java:209)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.base/java.lang.reflect.Method.invoke(Method.java:564)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1737)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:943)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at 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.base/java.lang.Thread.run(Thread.java:844)
Build Log:
[...truncated 1735 lines...]
[junit4] JVM J1: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\lucene\build\core\test\temp\junit4-J1-20171022_231036_5284734341613349587943.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[...truncated 3 lines...]
[junit4] JVM J0: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\lucene\build\core\test\temp\junit4-J0-20171022_231036_52816126972839557696017.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 309 lines...]
[junit4] JVM J1: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\lucene\build\test-framework\test\temp\junit4-J1-20171022_231602_664988439007176742401.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[...truncated 3 lines...]
[junit4] JVM J0: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\lucene\build\test-framework\test\temp\junit4-J0-20171022_231602_6642628595798534822333.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 1055 lines...]
[junit4] JVM J1: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\lucene\build\analysis\common\test\temp\junit4-J1-20171022_231707_10310470616435667894358.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[...truncated 3 lines...]
[junit4] JVM J0: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\lucene\build\analysis\common\test\temp\junit4-J0-20171022_231707_1039790915678000136176.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 230 lines...]
[junit4] JVM J0: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\lucene\build\analysis\icu\test\temp\junit4-J0-20171022_231853_8529583991892717368554.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[junit4] JVM J1: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\lucene\build\analysis\icu\test\temp\junit4-J1-20171022_231853_85216395090249788098894.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[...truncated 244 lines...]
[junit4] JVM J0: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\lucene\build\analysis\kuromoji\test\temp\junit4-J0-20171022_231907_05917871063537729205529.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 9 lines...]
[junit4] JVM J1: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\lucene\build\analysis\kuromoji\test\temp\junit4-J1-20171022_231907_05915477919955841580157.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[...truncated 155 lines...]
[junit4] JVM J0: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\lucene\build\analysis\morfologik\test\temp\junit4-J0-20171022_231942_6341979117988784002689.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 3 lines...]
[junit4] JVM J1: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\lucene\build\analysis\morfologik\test\temp\junit4-J1-20171022_231942_63414974151688126994288.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[...truncated 167 lines...]
[junit4] JVM J0: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\lucene\build\analysis\phonetic\test\temp\junit4-J0-20171022_231948_02313769343074363448118.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 3 lines...]
[junit4] JVM J1: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\lucene\build\analysis\phonetic\test\temp\junit4-J1-20171022_231948_0236961379540579717386.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[...truncated 152 lines...]
[junit4] JVM J0: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\lucene\build\analysis\smartcn\test\temp\junit4-J0-20171022_231958_79110720091516862669623.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 3 lines...]
[junit4] JVM J1: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\lucene\build\analysis\smartcn\test\temp\junit4-J1-20171022_231958_791720928404785533807.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[...truncated 157 lines...]
[junit4] JVM J1: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\lucene\build\analysis\stempel\test\temp\junit4-J1-20171022_232007_34113511791602077406842.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[...truncated 3 lines...]
[junit4] JVM J0: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\lucene\build\analysis\stempel\test\temp\junit4-J0-20171022_232007_34117884557885109416800.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 168 lines...]
[junit4] JVM J0: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\lucene\build\analysis\uima\test\temp\junit4-J0-20171022_232011_46114877929462853024393.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 153 lines...]
[junit4] JVM J1: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\lucene\build\backward-codecs\test\temp\junit4-J1-20171022_232029_9257043135277888918400.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[...truncated 19 lines...]
[junit4] JVM J0: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\lucene\build\backward-codecs\test\temp\junit4-J0-20171022_232029_9252484230722153224676.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 1269 lines...]
[junit4] JVM J1: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\lucene\build\benchmark\test\temp\junit4-J1-20171022_232051_4373729566922613548285.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[junit4] JVM J0: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\lucene\build\benchmark\test\temp\junit4-J0-20171022_232051_4411987486947820568938.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 235 lines...]
[junit4] JVM J1: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\lucene\build\classification\test\temp\junit4-J1-20171022_232105_06913799609367659017741.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[junit4] JVM J0: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\lucene\build\classification\test\temp\junit4-J0-20171022_232105_0693866428208911639711.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 256 lines...]
[junit4] JVM J1: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\lucene\build\codecs\test\temp\junit4-J1-20171022_232119_7039816051730066284985.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[...truncated 7 lines...]
[junit4] JVM J0: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\lucene\build\codecs\test\temp\junit4-J0-20171022_232119_7032409008558624161579.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 241 lines...]
[junit4] JVM J1: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\lucene\build\demo\test\temp\junit4-J1-20171022_232248_0661738351378045414626.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[junit4] JVM J0: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\lucene\build\demo\test\temp\junit4-J0-20171022_232248_06610763773910769504780.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 168 lines...]
[junit4] JVM J0: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\lucene\build\expressions\test\temp\junit4-J0-20171022_232252_00911596801378346084997.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 6 lines...]
[junit4] JVM J1: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\lucene\build\expressions\test\temp\junit4-J1-20171022_232252_00916156113905309310174.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[...truncated 227 lines...]
[junit4] JVM J0: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\lucene\build\facet\test\temp\junit4-J0-20171022_232257_629504550115622383553.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 3 lines...]
[junit4] JVM J1: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\lucene\build\facet\test\temp\junit4-J1-20171022_232257_6299622053477465168401.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[...truncated 180 lines...]
[junit4] JVM J0: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\lucene\build\grouping\test\temp\junit4-J0-20171022_232323_69514055382997805876238.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 3 lines...]
[junit4] JVM J1: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\lucene\build\grouping\test\temp\junit4-J1-20171022_232323_69515759039700971724864.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[...truncated 244 lines...]
[junit4] JVM J0: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\lucene\build\highlighter\test\temp\junit4-J0-20171022_232332_1879975509659128129678.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 3 lines...]
[junit4] JVM J1: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\lucene\build\highlighter\test\temp\junit4-J1-20171022_232332_18711683398798555643505.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[...truncated 164 lines...]
[junit4] JVM J1: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\lucene\build\join\test\temp\junit4-J1-20171022_232348_87111946284470919610143.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[...truncated 3 lines...]
[junit4] JVM J0: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\lucene\build\join\test\temp\junit4-J0-20171022_232348_8716011521174028193264.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 148 lines...]
[junit4] JVM J0: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\lucene\build\memory\test\temp\junit4-J0-20171022_232400_80010523627088310414057.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 3 lines...]
[junit4] JVM J1: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\lucene\build\memory\test\temp\junit4-J1-20171022_232400_80013311048410854757372.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[...truncated 185 lines...]
[junit4] JVM J1: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\lucene\build\misc\test\temp\junit4-J1-20171022_232407_4459663799826243284703.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[...truncated 3 lines...]
[junit4] JVM J0: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\lucene\build\misc\test\temp\junit4-J0-20171022_232407_4459479752199280051253.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 313 lines...]
[junit4] JVM J1: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\lucene\build\queries\test\temp\junit4-J1-20171022_232429_18813769037611878865082.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[...truncated 3 lines...]
[junit4] JVM J0: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\lucene\build\queries\test\temp\junit4-J0-20171022_232429_18816299620087861999399.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 225 lines...]
[junit4] JVM J1: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\lucene\build\queryparser\test\temp\junit4-J1-20171022_232437_9526717234561578683719.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[...truncated 3 lines...]
[junit4] JVM J0: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\lucene\build\queryparser\test\temp\junit4-J0-20171022_232437_95212818992727361881884.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 188 lines...]
[junit4] Suite: org.apache.lucene.replicator.IndexAndTaxonomyReplicationClientTest
[junit4] 2> golg 23, 2017 7:24:56 I?ITBEAIVET com.carrotsearch.randomizedtesting.RandomizedRunner$QueueUncaughtExceptionsHandler uncaughtException
[junit4] 2> WARNING: Uncaught exception in thread: Thread[ReplicationThread-indexAndTaxo,5,TGRP-IndexAndTaxonomyReplicationClientTest]
[junit4] 2> java.lang.AssertionError: handler failed too many times: -1
[junit4] 2> at __randomizedtesting.SeedInfo.seed([F83F3B6DDC6A281F]:0)
[junit4] 2> at org.apache.lucene.replicator.IndexAndTaxonomyReplicationClientTest$4.handleUpdateException(IndexAndTaxonomyReplicationClientTest.java:422)
[junit4] 2> at org.apache.lucene.replicator.ReplicationClient$ReplicationThread.run(ReplicationClient.java:77)
[junit4] 2>
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=IndexAndTaxonomyReplicationClientTest -Dtests.method=testConsistencyOnExceptions -Dtests.seed=F83F3B6DDC6A281F -Dtests.slow=true -Dtests.locale=se-SE -Dtests.timezone=Asia/Irkutsk -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[junit4] ERROR 1.04s J1 | IndexAndTaxonomyReplicationClientTest.testConsistencyOnExceptions <<<
[junit4] > Throwable #1: com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught exception in thread: Thread[id=22, name=ReplicationThread-indexAndTaxo, state=RUNNABLE, group=TGRP-IndexAndTaxonomyReplicationClientTest]
[junit4] > at __randomizedtesting.SeedInfo.seed([F83F3B6DDC6A281F:77B1DCCDCE06DBE0]:0)
[junit4] > Caused by: java.lang.AssertionError: handler failed too many times: -1
[junit4] > at __randomizedtesting.SeedInfo.seed([F83F3B6DDC6A281F]:0)
[junit4] > at org.apache.lucene.replicator.IndexAndTaxonomyReplicationClientTest$4.handleUpdateException(IndexAndTaxonomyReplicationClientTest.java:422)
[junit4] > at org.apache.lucene.replicator.ReplicationClient$ReplicationThread.run(ReplicationClient.java:77)
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene70): {$full_path$=PostingsFormat(name=MockRandom), $facets=PostingsFormat(name=Asserting), $payloads$=PostingsFormat(name=Memory)}, docValues:{$facets=DocValuesFormat(name=Lucene70)}, maxPointsInLeafNode=893, maxMBSortInHeap=5.412506782394516, sim=RandomSimilarity(queryNorm=true): {}, locale=se-SE, timezone=Asia/Irkutsk
[junit4] 2> NOTE: Windows 10 10.0 amd64/Oracle Corporation 9 (64-bit)/cpus=3,threads=1,free=59993912,total=97320960
[junit4] 2> NOTE: All tests run in this JVM: [SessionTokenTest, TestNRTReplication, IndexAndTaxonomyReplicationClientTest]
[junit4] Completed [3/9 (1!)] on J1 in 2.16s, 5 tests, 1 error <<< FAILURES!
[...truncated 12 lines...]
[junit4] JVM J1: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\lucene\build\replicator\test\temp\junit4-J1-20171022_232447_7935522512649834036903.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[...truncated 9 lines...]
[junit4] JVM J0: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\lucene\build\replicator\test\temp\junit4-J0-20171022_232447_79315651355551654550064.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 222 lines...]
[junit4] JVM J1: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\lucene\build\sandbox\test\temp\junit4-J1-20171022_232502_7946544913653252060859.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[...truncated 7 lines...]
[junit4] JVM J0: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\lucene\build\sandbox\test\temp\junit4-J0-20171022_232502_7946348003063397202793.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 416 lines...]
[junit4] JVM J0: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\lucene\build\spatial-extras\test\temp\junit4-J0-20171022_232546_00914297973326096045265.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 3 lines...]
[junit4] JVM J1: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\lucene\build\spatial-extras\test\temp\junit4-J1-20171022_232546_009482189646333750920.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[...truncated 178 lines...]
[junit4] JVM J0: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\lucene\build\spatial3d\test\temp\junit4-J0-20171022_232605_27016220113565510620618.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 8 lines...]
[junit4] JVM J1: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\lucene\build\spatial3d\test\temp\junit4-J1-20171022_232605_27013478269353689900568.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[...truncated 147 lines...]
[junit4] JVM J0: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\lucene\build\spatial\test\temp\junit4-J0-20171022_232622_10614107964372961344992.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 245 lines...]
[junit4] JVM J0: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\lucene\build\suggest\test\temp\junit4-J0-20171022_232625_6465875596399535658178.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 3 lines...]
[junit4] JVM J1: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\lucene\build\suggest\test\temp\junit4-J1-20171022_232625_64611909165670686018993.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[...truncated 2160 lines...]
[junit4] Suite: org.apache.solr.cloud.autoscaling.ExecutePlanActionTest
[junit4] 2> Creating dataDir: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.autoscaling.ExecutePlanActionTest_216A22F099D20562-001\init-core-data-001
[junit4] 2> 562624 WARN (SUITE-ExecutePlanActionTest-seed#[216A22F099D20562]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=7 numCloses=7
[junit4] 2> 562624 INFO (SUITE-ExecutePlanActionTest-seed#[216A22F099D20562]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=true
[junit4] 2> 562626 INFO (SUITE-ExecutePlanActionTest-seed#[216A22F099D20562]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason="", value=0.0/0.0, ssl=0.0/0.0, clientAuth=0.0/0.0)
[junit4] 2> 562627 INFO (SUITE-ExecutePlanActionTest-seed#[216A22F099D20562]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 562627 INFO (SUITE-ExecutePlanActionTest-seed#[216A22F099D20562]-worker) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 2 servers in C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.autoscaling.ExecutePlanActionTest_216A22F099D20562-001\tempDir-001
[junit4] 2> 562627 INFO (SUITE-ExecutePlanActionTest-seed#[216A22F099D20562]-worker) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 562637 INFO (Thread-1757) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 562637 INFO (Thread-1757) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 562641 ERROR (Thread-1757) [ ] o.a.z.s.ZooKeeperServer ZKShutdownHandler is not registered, so ZooKeeper server won't take any action on ERROR or SHUTDOWN server state changes
[junit4] 2> 562727 INFO (SUITE-ExecutePlanActionTest-seed#[216A22F099D20562]-worker) [ ] o.a.s.c.ZkTestServer start zk server on port:53679
[junit4] 2> 562734 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ] o.a.z.s.NIOServerCnxn caught end of stream exception
[junit4] 2> EndOfStreamException: Unable to read additional data from client sessionid 0x15f46729deb0000, likely client has closed socket
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:239)
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:203)
[junit4] 2> at java.base/java.lang.Thread.run(Thread.java:844)
[junit4] 2> 562751 INFO (jetty-launcher-2597-thread-2) [ ] o.e.j.s.Server jetty-9.3.20.v20170531
[junit4] 2> 562753 INFO (jetty-launcher-2597-thread-1) [ ] o.e.j.s.Server jetty-9.3.20.v20170531
[junit4] 2> 562754 INFO (jetty-launcher-2597-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@6fcae453{/solr,null,AVAILABLE}
[junit4] 2> 562757 INFO (jetty-launcher-2597-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@187e1046{SSL,[ssl, http/1.1]}{127.0.0.1:53683}
[junit4] 2> 562757 INFO (jetty-launcher-2597-thread-1) [ ] o.e.j.s.Server Started @572962ms
[junit4] 2> 562757 INFO (jetty-launcher-2597-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=53683}
[junit4] 2> 562757 ERROR (jetty-launcher-2597-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 562757 INFO (jetty-launcher-2597-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.0.0
[junit4] 2> 562757 INFO (jetty-launcher-2597-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 562757 INFO (jetty-launcher-2597-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null, Default config dir: null
[junit4] 2> 562757 INFO (jetty-launcher-2597-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-10-22T23:37:17.663501500Z
[junit4] 2> 562764 INFO (jetty-launcher-2597-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@2c85f33c{/solr,null,AVAILABLE}
[junit4] 2> 562766 INFO (jetty-launcher-2597-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@45c2dce1{SSL,[ssl, http/1.1]}{127.0.0.1:53688}
[junit4] 2> 562766 INFO (jetty-launcher-2597-thread-2) [ ] o.e.j.s.Server Started @572972ms
[junit4] 2> 562766 INFO (jetty-launcher-2597-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=53688}
[junit4] 2> 562766 ERROR (jetty-launcher-2597-thread-2) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 562766 INFO (jetty-launcher-2597-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.0.0
[junit4] 2> 562766 INFO (jetty-launcher-2597-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 562766 INFO (jetty-launcher-2597-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null, Default config dir: null
[junit4] 2> 562766 INFO (jetty-launcher-2597-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-10-22T23:37:17.672645500Z
[junit4] 2> 562784 INFO (jetty-launcher-2597-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 562788 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ] o.a.z.s.NIOServerCnxn caught end of stream exception
[junit4] 2> EndOfStreamException: Unable to read additional data from client sessionid 0x15f46729deb0001, likely client has closed socket
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:239)
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:203)
[junit4] 2> at java.base/java.lang.Thread.run(Thread.java:844)
[junit4] 2> 562789 INFO (jetty-launcher-2597-thread-2) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 562790 INFO (jetty-launcher-2597-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:53679/solr
[junit4] 2> 562799 INFO (jetty-launcher-2597-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:53679/solr
[junit4] 2> 562927 INFO (jetty-launcher-2597-thread-2) [ ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 562927 INFO (jetty-launcher-2597-thread-1) [ ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 562928 INFO (jetty-launcher-2597-thread-2) [ ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:53688_solr
[junit4] 2> 562928 INFO (jetty-launcher-2597-thread-1) [ ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:53683_solr
[junit4] 2> 562929 INFO (jetty-launcher-2597-thread-2) [ ] o.a.s.c.Overseer Overseer (id=98875174915080198-127.0.0.1:53688_solr-n_0000000000) starting
[junit4] 2> 562930 INFO (zkCallback-2608-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 562930 INFO (zkCallback-2609-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 562937 INFO (jetty-launcher-2597-thread-2) [ ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:53688_solr
[junit4] 2> 562943 INFO (zkCallback-2608-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 562943 INFO (zkCallback-2609-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 562961 DEBUG (OverseerAutoScalingTriggerThread-98875174915080198-127.0.0.1:53688_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Adding .autoAddReplicas trigger
[junit4] 2> 562964 DEBUG (OverseerAutoScalingTriggerThread-98875174915080198-127.0.0.1:53688_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 1
[junit4] 2> 562965 DEBUG (OverseerAutoScalingTriggerThread-98875174915080198-127.0.0.1:53688_solr-n_0000000000) [ ] o.a.s.c.a.NodeLostTrigger Initial livenodes: [127.0.0.1:53683_solr, 127.0.0.1:53688_solr]
[junit4] 2> 562966 DEBUG (OverseerAutoScalingTriggerThread-98875174915080198-127.0.0.1:53688_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1, lastZnodeVersion -1
[junit4] 2> 562966 DEBUG (OverseerAutoScalingTriggerThread-98875174915080198-127.0.0.1:53688_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 1
[junit4] 2> 562971 DEBUG (OverseerAutoScalingTriggerThread-98875174915080198-127.0.0.1:53688_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers
[junit4] 2> 562971 DEBUG (OverseerAutoScalingTriggerThread-98875174915080198-127.0.0.1:53688_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1, lastZnodeVersion 1
[junit4] 2> 562978 DEBUG (ScheduledTrigger-3720-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: [127.0.0.1:53683_solr, 127.0.0.1:53688_solr]
[junit4] 2> 563009 INFO (jetty-launcher-2597-thread-1) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_53683.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3bc60d85
[junit4] 2> 563009 INFO (jetty-launcher-2597-thread-2) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_53688.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3bc60d85
[junit4] 2> 563019 INFO (jetty-launcher-2597-thread-2) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_53688.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3bc60d85
[junit4] 2> 563020 INFO (jetty-launcher-2597-thread-1) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_53683.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3bc60d85
[junit4] 2> 563020 INFO (jetty-launcher-2597-thread-2) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_53688.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3bc60d85
[junit4] 2> 563020 INFO (jetty-launcher-2597-thread-1) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_53683.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3bc60d85
[junit4] 2> 563021 INFO (jetty-launcher-2597-thread-2) [ ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.autoscaling.ExecutePlanActionTest_216A22F099D20562-001\tempDir-001\node2\.
[junit4] 2> 563021 INFO (jetty-launcher-2597-thread-1) [ ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.autoscaling.ExecutePlanActionTest_216A22F099D20562-001\tempDir-001\node1\.
[junit4] 2> 563064 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ] o.a.z.s.NIOServerCnxn caught end of stream exception
[junit4] 2> EndOfStreamException: Unable to read additional data from client sessionid 0x15f46729deb0007, likely client has closed socket
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:239)
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:203)
[junit4] 2> at java.base/java.lang.Thread.run(Thread.java:844)
[junit4] 2> 563069 INFO (SUITE-ExecutePlanActionTest-seed#[216A22F099D20562]-worker) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 563070 INFO (SUITE-ExecutePlanActionTest-seed#[216A22F099D20562]-worker) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:53679/solr ready
[junit4] 2> 563084 INFO (TEST-ExecutePlanActionTest.testExecute-seed#[216A22F099D20562]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testExecute
[junit4] 2> 563084 DEBUG (zkCallback-2609-thread-1) [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 2
[junit4] 2> 563084 DEBUG (OverseerAutoScalingTriggerThread-98875174915080198-127.0.0.1:53688_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 2
[junit4] 2> 563090 DEBUG (OverseerAutoScalingTriggerThread-98875174915080198-127.0.0.1:53688_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeLost markers
[junit4] 2> 563091 DEBUG (OverseerAutoScalingTriggerThread-98875174915080198-127.0.0.1:53688_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers
[junit4] 2> 563112 DEBUG (OverseerAutoScalingTriggerThread-98875174915080198-127.0.0.1:53688_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 2, lastZnodeVersion 2
[junit4] 2> 563122 INFO (qtp2014545226-11034) [ ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params replicationFactor=2&collection.configName=conf&maxShardsPerNode=1&name=testExecute&nrtReplicas=2&action=CREATE&numShards=1&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 563128 INFO (OverseerThreadFactory-3722-thread-1) [ ] o.a.s.c.CreateCollectionCmd Create collection testExecute
[junit4] 2> 563289 INFO (OverseerStateUpdate-98875174915080198-127.0.0.1:53688_solr-n_0000000000) [ ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"testExecute",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"testExecute_shard1_replica_n1",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"https://127.0.0.1:53688/solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 563291 INFO (OverseerStateUpdate-98875174915080198-127.0.0.1:53688_solr-n_0000000000) [ ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"testExecute",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"testExecute_shard1_replica_n2",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"https://127.0.0.1:53683/solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 563498 INFO (qtp2014545226-11036) [ ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node3&collection.configName=conf&newCollection=true&name=testExecute_shard1_replica_n1&action=CREATE&numShards=1&collection=testExecute&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 563498 INFO (qtp2014545226-11036) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 563513 INFO (qtp440481688-11042) [ ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node4&collection.configName=conf&newCollection=true&name=testExecute_shard1_replica_n2&action=CREATE&numShards=1&collection=testExecute&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 563514 INFO (qtp440481688-11042) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 563621 INFO (zkCallback-2608-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testExecute/state.json] for collection [testExecute] has occurred - updating... (live nodes size: [2])
[junit4] 2> 563621 INFO (zkCallback-2609-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testExecute/state.json] for collection [testExecute] has occurred - updating... (live nodes size: [2])
[junit4] 2> 563622 INFO (zkCallback-2608-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testExecute/state.json] for collection [testExecute] has occurred - updating... (live nodes size: [2])
[junit4] 2> 563622 INFO (zkCallback-2609-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testExecute/state.json] for collection [testExecute] has occurred - updating... (live nodes size: [2])
[junit4] 2> 564512 INFO (qtp2014545226-11036) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
[junit4] 2> 564523 INFO (qtp2014545226-11036) [ ] o.a.s.s.IndexSchema [testExecute_shard1_replica_n1] Schema name=minimal
[junit4] 2> 564527 INFO (qtp2014545226-11036) [ ] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 564527 INFO (qtp2014545226-11036) [ ] o.a.s.c.CoreContainer Creating SolrCore 'testExecute_shard1_replica_n1' using configuration from collection testExecute, trusted=true
[junit4] 2> 564528 INFO (qtp2014545226-11036) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_53688.solr.core.testExecute.shard1.replica_n1' (registry 'solr.core.testExecute.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3bc60d85
[junit4] 2> 564528 INFO (qtp2014545226-11036) [ ] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 564528 INFO (qtp2014545226-11036) [ ] o.a.s.c.SolrCore [[testExecute_shard1_replica_n1] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.autoscaling.ExecutePlanActionTest_216A22F099D20562-001\tempDir-001\node2\testExecute_shard1_replica_n1], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.autoscaling.ExecutePlanActionTest_216A22F099D20562-001\tempDir-001\node2\.\testExecute_shard1_replica_n1\data\]
[junit4] 2> 564528 INFO (qtp440481688-11042) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
[junit4] 2> 564537 INFO (qtp440481688-11042) [ ] o.a.s.s.IndexSchema [testExecute_shard1_replica_n2] Schema name=minimal
[junit4] 2> 564541 INFO (qtp440481688-11042) [ ] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 564541 INFO (qtp440481688-11042) [ ] o.a.s.c.CoreContainer Creating SolrCore 'testExecute_shard1_replica_n2' using configuration from collection testExecute, trusted=true
[junit4] 2> 564543 INFO (qtp440481688-11042) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_53683.solr.core.testExecute.shard1.replica_n2' (registry 'solr.core.testExecute.shard1.replica_n2') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3bc60d85
[junit4] 2> 564543 INFO (qtp440481688-11042) [ ] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 564543 INFO (qtp440481688-11042) [ ] o.a.s.c.SolrCore [[testExecute_shard1_replica_n2] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.autoscaling.ExecutePlanActionTest_216A22F099D20562-001\tempDir-001\node1\testExecute_shard1_replica_n2], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.autoscaling.ExecutePlanActionTest_216A22F099D20562-001\tempDir-001\node1\.\testExecute_shard1_replica_n2\data\]
[junit4] 2> 564611 INFO (qtp2014545226-11036) [ ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 564611 INFO (qtp2014545226-11036) [ ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 564613 INFO (qtp2014545226-11036) [ ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 564613 INFO (qtp2014545226-11036) [ ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 564617 INFO (qtp440481688-11042) [ ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 564617 INFO (qtp440481688-11042) [ ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 564619 INFO (qtp440481688-11042) [ ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 564619 INFO (qtp440481688-11042) [ ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 564620 INFO (qtp2014545226-11036) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@49062e88[testExecute_shard1_replica_n1] main]
[junit4] 2> 564621 INFO (qtp2014545226-11036) [ ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf
[junit4] 2> 564621 INFO (qtp440481688-11042) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@2d66cd10[testExecute_shard1_replica_n2] main]
[junit4] 2> 564621 INFO (qtp2014545226-11036) [ ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf
[junit4] 2> 564622 INFO (qtp2014545226-11036) [ ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 564622 INFO (qtp2014545226-11036) [ ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1582002800718512128
[junit4] 2> 564623 INFO (searcherExecutor-3727-thread-1) [ ] o.a.s.c.SolrCore [testExecute_shard1_replica_n1] Registered new searcher Searcher@49062e88[testExecute_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 564625 INFO (qtp440481688-11042) [ ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf
[junit4] 2> 564626 INFO (qtp440481688-11042) [ ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf
[junit4] 2> 564626 INFO (qtp440481688-11042) [ ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 564627 INFO (qtp440481688-11042) [ ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1582002800723755008
[junit4] 2> 564627 INFO (searcherExecutor-3728-thread-1) [ ] o.a.s.c.SolrCore [testExecute_shard1_replica_n2] Registered new searcher Searcher@2d66cd10[testExecute_shard1_replica_n2] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 564633 INFO (qtp2014545226-11036) [ ] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 564634 INFO (qtp2014545226-11036) [ ] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 564634 INFO (qtp2014545226-11036) [ ] o.a.s.c.SyncStrategy Sync replicas to https://127.0.0.1:53688/solr/testExecute_shard1_replica_n1/
[junit4] 2> 564634 INFO (qtp2014545226-11036) [ ] o.a.s.u.PeerSync PeerSync: core=testExecute_shard1_replica_n1 url=https://127.0.0.1:53688/solr START replicas=[https://127.0.0.1:53683/solr/testExecute_shard1_replica_n2/] nUpdates=100
[junit4] 2> 564639 INFO (qtp440481688-11045) [ ] o.a.s.c.S.Request [testExecute_shard1_replica_n2] webapp=/solr path=/get params={distrib=false&qt=/get&fingerprint=false&getVersions=100&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 564736 INFO (zkCallback-2608-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testExecute/state.json] for collection [testExecute] has occurred - updating... (live nodes size: [2])
[junit4] 2> 564737 INFO (zkCallback-2608-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testExecute/state.json] for collection [testExecute] has occurred - updating... (live nodes size: [2])
[junit4] 2> 564737 INFO (zkCallback-2609-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testExecute/state.json] for collection [testExecute] has occurred - updating... (live nodes size: [2])
[junit4] 2> 564737 INFO (zkCallback-2609-thread-3) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testExecute/state.json] for collection [testExecute] has occurred - updating... (live nodes size: [2])
[junit4] 2> 564935 INFO (qtp2014545226-11036) [ ] o.a.s.u.PeerSync PeerSync: core=testExecute_shard1_replica_n1 url=https://127.0.0.1:53688/solr DONE. We have no versions. sync failed.
[junit4] 2> 564935 INFO (qtp2014545226-11036) [ ] o.a.s.c.SyncStrategy Leader's attempt to sync with shard failed, moving to the next candidate
[junit4] 2> 564935 INFO (qtp2014545226-11036) [ ] o.a.s.c.ShardLeaderElectionContext We failed sync, but we have no versions - we can't sync in that case - we were active before, so become leader anyway
[junit4] 2> 564935 INFO (qtp2014545226-11036) [ ] o.a.s.c.ShardLeaderElectionContext Found all replicas participating in election, clear LIR
[junit4] 2> 564939 INFO (qtp2014545226-11036) [ ] o.a.s.c.ShardLeaderElectionContext I am the new leader: https://127.0.0.1:53688/solr/testExecute_shard1_replica_n1/ shard1
[junit4] 2> 565042 INFO (zkCallback-2609-thread-3) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testExecute/state.json] for collection [testExecute] has occurred - updating... (live nodes size: [2])
[junit4] 2> 565042 INFO (zkCallback-2608-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testExecute/state.json] for collection [testExecute] has occurred - updating... (live nodes size: [2])
[junit4] 2> 565042 INFO (zkCallback-2608-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testExecute/state.json] for collection [testExecute] has occurred - updating... (live nodes size: [2])
[junit4] 2> 565042 INFO (zkCallback-2609-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testExecute/state.json] for collection [testExecute] has occurred - updating... (live nodes size: [2])
[junit4] 2> 565092 INFO (qtp2014545226-11036) [ ] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 565094 INFO (qtp2014545226-11036) [ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node3&collection.configName=conf&newCollection=true&name=testExecute_shard1_replica_n1&action=CREATE&numShards=1&collection=testExecute&shard=shard1&wt=javabin&version=2&replicaType=NRT} status=0 QTime=1596
[junit4] 2> 565196 INFO (zkCallback-2609-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testExecute/state.json] for collection [testExecute] has occurred - updating... (live nodes size: [2])
[junit4] 2> 565196 INFO (zkCallback-2608-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testExecute/state.json] for collection [testExecute] has occurred - updating... (live nodes size: [2])
[junit4] 2> 565197 INFO (zkCallback-2608-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testExecute/state.json] for collection [testExecute] has occurred - updating... (live nodes size: [2])
[junit4] 2> 565196 INFO (zkCallback-2609-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testExecute/state.json] for collection [testExecute] has occurred - updating... (live nodes size: [2])
[junit4] 2> 565644 INFO (qtp440481688-11042) [ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node4&collection.configName=conf&newCollection=true&name=testExecute_shard1_replica_n2&action=CREATE&numShards=1&collection=testExecute&shard=shard1&wt=javabin&version=2&replicaType=NRT} status=0 QTime=2131
[junit4] 2> 565659 INFO (qtp2014545226-11034) [ ] o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most 30 seconds. Check all shard replicas
[junit4] 2> 565768 INFO (zkCallback-2608-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testExecute/state.json] for collection [testExecute] has occurred - updating... (live nodes size: [2])
[junit4] 2> 565768 INFO (zkCallback-2608-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testExecute/state.json] for collection [testExecute] has occurred - updating... (live nodes size: [2])
[junit4] 2> 565769 INFO (zkCallback-2609-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testExecute/state.json] for collection [testExecute] has occurred - updating... (live nodes size: [2])
[junit4] 2> 565769 INFO (zkCallback-2609-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testExecute/state.json] for collection [testExecute] has occurred - updating... (live nodes size: [2])
[junit4] 2> 566659 INFO (qtp2014545226-11034) [ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={replicationFactor=2&collection.configName=conf&maxShardsPerNode=1&name=testExecute&nrtReplicas=2&action=CREATE&numShards=1&wt=javabin&version=2} status=0 QTime=3537
[junit4] 2> 566665 DEBUG (TEST-ExecutePlanActionTest.testExecute-seed#[216A22F099D20562]) [ ] o.a.s.c.a.ExecutePlanAction -- processing event: {
[junit4] 2> "id":"14f008b359fc6ec0Ta83csubplbw55yuenqvn7zxmw",
[junit4] 2> "source":"mock_trigger_name",
[junit4] 2> "eventTime":1508715441571000000,
[junit4] 2> "eventType":"NODELOST",
[junit4] 2> "properties":{
[junit4] 2> "eventTimes":[1508715441571000000],
[junit4] 2> "nodeNames":["127.0.0.1:53683_solr"]}} with context properties: {operations=[org.apache.solr.client.solrj.request.CollectionAdminRequest$MoveReplica@3aa12252, org.apache.solr.cloud.autoscaling.ExecutePlanActionTest$1@64d5b490]}
[junit4] 2> 566666 INFO (TEST-ExecutePlanActionTest.testExecute-seed#[216A22F099D20562]) [ ] o.a.s.c.a.ExecutePlanAction Executing operation: action=MOVEREPLICA&collection=testExecute&targetNode=127.0.0.1:53688_solr&replica=core_node4
[junit4] 2> 566672 DEBUG (TEST-ExecutePlanActionTest.testExecute-seed#[216A22F099D20562]) [ ] o.a.s.c.a.ExecutePlanAction Saved requestId: mock_trigger_name/14f008b359fc6ec0Ta83csubplbw55yuenqvn7zxmw/0 in znode: /autoscaling/triggerState/mock_trigger_name/execute_plan/op-0000000000
[junit4] 2> 566678 INFO (qtp2014545226-11054) [ ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :movereplica with params async=mock_trigger_name/14f008b359fc6ec0Ta83csubplbw55yuenqvn7zxmw/0&replica=core_node4&action=MOVEREPLICA&collection=testExecute&targetNode=127.0.0.1:53688_solr&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 566682 INFO (qtp2014545226-11054) [ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={async=mock_trigger_name/14f008b359fc6ec0Ta83csubplbw55yuenqvn7zxmw/0&replica=core_node4&action=MOVEREPLICA&collection=testExecute&targetNode=127.0.0.1:53688_solr&wt=javabin&version=2} status=0 QTime=4
[junit4] 2> 566684 INFO (qtp2014545226-11030) [ ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :requeststatus with params requestid=mock_trigger_name/14f008b359fc6ec0Ta83csubplbw55yuenqvn7zxmw/0&action=REQUESTSTATUS&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 566688 INFO (qtp2014545226-11030) [ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={requestid=mock_trigger_name/14f008b359fc6ec0Ta83csubplbw55yuenqvn7zxmw/0&action=REQUESTSTATUS&wt=javabin&version=2} status=0 QTime=4
[junit4] 2> 566694 INFO (OverseerCollectionConfigSetProcessor-98875174915080198-127.0.0.1:53688_solr-n_0000000000) [ ] 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> 566694 INFO (OverseerThreadFactory-3722-thread-2) [ ] o.a.s.c.MoveReplicaCmd Replica will be moved core_node4:{"core":"testExecute_shard1_replica_n2","base_url":"https://127.0.0.1:53683/solr","node_name":"127.0.0.1:53683_solr","state":"active","type":"NRT"}
[junit4] 2> 566699 INFO (OverseerThreadFactory-3722-thread-2) [ ] o.a.s.c.AddReplicaCmd Node Identified 127.0.0.1:53688_solr for creating new replica
[junit4] 2> 566703 INFO (OverseerStateUpdate-98875174915080198-127.0.0.1:53688_solr-n_0000000000) [ ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"addreplica",
[junit4] 2> "collection":"testExecute",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"testExecute_shard1_replica_n5",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"https://127.0.0.1:53688/solr",
[junit4] 2> "node_name":"127.0.0.1:53688_solr",
[junit4] 2> "type":"NRT"}
[junit4] 2> 566807 INFO (zkCallback-2609-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testExecute/state.json] for collection [testExecute] has occurred - updating... (live nodes size: [2])
[junit4] 2> 566807 INFO (zkCallback-2608-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testExecute/state.json] for collection [testExecute] has occurred - updating... (live nodes size: [2])
[junit4] 2> 566807 INFO (zkCallback-2609-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testExecute/state.json] for collection [testExecute] has occurred - updating... (live nodes size: [2])
[junit4] 2> 566807 INFO (zkCallback-2608-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testExecute/state.json] for collection [testExecute] has occurred - updating... (live nodes size: [2])
[junit4] 2> 566906 INFO (qtp2014545226-11033) [ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={async=mock_trigger_name/14f008b359fc6ec0Ta83csubplbw55yuenqvn7zxmw/045053573538651&qt=/admin/cores&coreNodeName=core_node6&collection.configName=conf&name=testExecute_shard1_replica_n5&action=CREATE&collection=testExecute&shard=shard1&wt=javabin&version=2&replicaType=NRT} status=0 QTime=0
[junit4] 2> 566907 INFO (parallelCoreAdminExecutor-3724-thread-1) [ ] o.a.s.h.a.CoreAdminOperation core create command async=mock_trigger_name/14f008b359fc6ec0Ta83csubplbw55yuenqvn7zxmw/045053573538651&qt=/admin/cores&coreNodeName=core_node6&collection.configName=conf&name=testExecute_shard1_replica_n5&action=CREATE&collection=testExecute&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 566907 INFO (qtp2014545226-11035) [ ] o.a.s.h.a.CoreAdminOperation Checking request status for : mock_trigger_name/14f008b359fc6ec0Ta83csubplbw55yuenqvn7zxmw/045053573538651
[junit4] 2> 566907 INFO (qtp2014545226-11035) [ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&requestid=mock_trigger_name/14f008b359fc6ec0Ta83csubplbw55yuenqvn7zxmw/045053573538651&action=REQUESTSTATUS&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 566942 INFO (parallelCoreAdminExecutor-3724-thread-1) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
[junit4] 2> 566954 INFO (parallelCoreAdminExecutor-3724-thread-1) [ ] o.a.s.s.IndexSchema [testExecute_shard1_replica_n5] Schema name=minimal
[junit4] 2> 566956 INFO (parallelCoreAdminExecutor-3724-thread-1) [ ] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 566956 INFO (parallelCoreAdminExecutor-3724-thread-1) [ ] o.a.s.c.CoreContainer Creating SolrCore 'testExecute_shard1_replica_n5' using configuration from collection testExecute, trusted=true
[junit4] 2> 566956 INFO (parallelCoreAdminExecutor-3724-thread-1) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_53688.solr.core.testExecute.shard1.replica_n5' (registry 'solr.core.testExecute.shard1.replica_n5') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3bc60d85
[junit4] 2> 566957 INFO (parallelCoreAdminExecutor-3724-thread-1) [ ] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 566957 INFO (parallelCoreAdminExecutor-3724-thread-1) [ ] o.a.s.c.SolrCore [[testExecute_shard1_replica_n5] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.autoscaling.ExecutePlanActionTest_216A22F099D20562-001\tempDir-001\node2\testExecute_shard1_replica_n5], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.autoscaling.ExecutePlanActionTest_216A22F099D20562-001\tempDir-001\node2\.\testExecute_shard1_replica_n5\data\]
[junit4] 2> 567017 INFO (zkCallback-2609-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testExecute/state.json] for collection [testExecute] has occurred - updating... (live nodes size: [2])
[junit4] 2> 567017 INFO (zkCallback-2608-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testExecute/state.json] for collection [testExecute] has occurred - updating... (live nodes size: [2])
[junit4] 2> 567017 INFO (zkCallback-2608-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testExecute/state.json] for collection [testExecute] has occurred - updating... (live nodes size: [2])
[junit4] 2> 567017 INFO (zkCallback-2609-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testExecute/state.json] for collection [testExecute] has occurred - updating... (live nodes size: [2])
[junit4] 2> 567021 INFO (parallelCoreAdminExecutor-3724-thread-1) [ ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 567021 INFO (parallelCoreAdminExecutor-3724-thread-1) [ ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 567023 INFO (parallelCoreAdminExecutor-3724-thread-1) [ ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 567023 INFO (parallelCoreAdminExecutor-3724-thread-1) [ ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 567024 INFO (parallelCoreAdminExecutor-3724-thread-1) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@5d740a8b[testExecute_shard1_replica_n5] main]
[junit4] 2> 567026 INFO (parallelCoreAdminExecutor-3724-thread-1) [ ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf
[junit4] 2> 567026 INFO (parallelCoreAdminExecutor-3724-thread-1) [ ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf
[junit4] 2> 567027 INFO (parallelCoreAdminExecutor-3724-thread-1) [ ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 567027 INFO (searcherExecutor-3737-thread-1) [ ] o.a.s.c.SolrCore [testExecute_shard1_replica_n5] Registered new searcher Searcher@5d740a8b[testExecute_shard1_replica_n5] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 567027 INFO (parallelCoreAdminExecutor-3724-thread-1) [ ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1582002803240337408
[junit4] 2> 567030 INFO (parallelCoreAdminExecutor-3724-thread-1) [ ] o.a.s.c.ZkController Core needs to recover:testExecute_shard1_replica_n5
[junit4] 2> 567031 INFO (updateExecutor-2605-thread-1) [ ] o.a.s.u.DefaultSolrCoreState Running recovery
[junit4] 2> 567032 INFO (recoveryExecutor-2606-thread-1) [ ] o.a.s.c.RecoveryStrategy Starting recovery process. recoveringAfterStartup=true
[junit4] 2> 567032 INFO (recoveryExecutor-2606-thread-1) [ ] o.a.s.c.RecoveryStrategy ###### startupVersions=[[]]
[junit4] 2> 567032 INFO (recoveryExecutor-2606-thread-1) [ ] o.a.s.c.RecoveryStrategy Begin buffering updates. core=[testExecute_shard1_replica_n5]
[junit4] 2> 567032 INFO (recoveryExecutor-2606-thread-1) [ ] o.a.s.u.UpdateLog Starting to buffer updates. FSUpdateLog{state=ACTIVE, tlog=null}
[junit4] 2> 567032 INFO (recoveryExecutor-2606-thread-1) [ ] o.a.s.c.RecoveryStrategy Publishing state of core [testExecute_shard1_replica_n5] as recovering, leader is [https://127.0.0.1:53688/solr/testExecute_shard1_replica_n1/] and I am [https://127.0.0.1:53688/solr/testExecute_shard1_replica_n5/]
[junit4] 2> 567034 INFO (recoveryExecutor-2606-thread-1) [ ] o.a.s.c.RecoveryStrategy Sending prep recovery command to [https://127.0.0.1:53688/solr]; [WaitForState: action=PREPRECOVERY&core=testExecute_shard1_replica_n1&nodeName=127.0.0.1:53688_solr&coreNodeName=core_node6&state=recovering&checkLive=true&onlyIfLeader=true&onlyIfLeaderActive=true]
[junit4] 2> 567038 INFO (qtp2014545226-11034) [ ] o.a.s.h.a.PrepRecoveryOp Going to wait for coreNodeName: core_node6, state: recovering, checkLive: true, onlyIfLeader: true, onlyIfLeaderActive: true, maxTime: 183 s
[junit4] 2> 567039 INFO (qtp2014545226-11034) [ ] o.a.s.h.a.PrepRecoveryOp In WaitForState(recovering): collection=testExecute, shard=shard1, thisCore=testExecute_shard1_replica_n1, leaderDoesNotNeedRecovery=false, isLeader? true, live=true, checkLive=true, currentState=down, localState=active, nodeName=127.0.0.1:53688_solr, coreNodeName=core_node6, onlyIfActiveCheckResult=false, nodeProps: core_node6:{"core":"testExecute_shard1_replica_n5","base_url":"https://127.0.0.1:53688/solr","node_name":"127.0.0.1:53688_solr","state":"down","type":"NRT"}
[junit4] 2> 567136 INFO (zkCallback-2608-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testExecute/state.json] for collection [testExecute] has occurred - updating... (live nodes size: [2])
[junit4] 2> 567136 INFO (zkCallback-2609-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [Watche
[...truncated too long message...]
im) ----
[junit4] Java HotSpot(TM) 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 3 lines...]
[junit4] JVM J1: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-solrj\test\temp\junit4-J1-20171023_003338_39018061414474526055607.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[...truncated 1363 lines...]
[junit4] JVM J1: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\contrib\solr-analysis-extras\test\temp\junit4-J1-20171023_003712_8726549270890754220504.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[junit4] JVM J0: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\contrib\solr-analysis-extras\test\temp\junit4-J0-20171023_003712_8724030012748488774311.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 785 lines...]
[junit4] JVM J1: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\contrib\solr-analytics\test\temp\junit4-J1-20171023_003736_6101184714034593917814.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[junit4] JVM J0: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\contrib\solr-analytics\test\temp\junit4-J0-20171023_003736_61013927642858910562779.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 554 lines...]
[junit4] JVM J1: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\contrib\solr-clustering\test\temp\junit4-J1-20171023_003858_2399433338378636379844.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[...truncated 3 lines...]
[junit4] JVM J0: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\contrib\solr-clustering\test\temp\junit4-J0-20171023_003858_2395012851022497297066.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 1258 lines...]
[junit4] JVM J1: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\contrib\solr-dataimporthandler-extras\test\temp\junit4-J1-20171023_003937_7908393417941007942170.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[...truncated 3 lines...]
[junit4] JVM J0: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\contrib\solr-dataimporthandler-extras\test\temp\junit4-J0-20171023_003937_7906845277113267456994.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 647 lines...]
[junit4] JVM J0: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\contrib\solr-dataimporthandler\test\temp\junit4-J0-20171023_004001_5211277554325405937641.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 3 lines...]
[junit4] JVM J1: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\contrib\solr-dataimporthandler\test\temp\junit4-J1-20171023_004001_5219312666664877542635.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[...truncated 558 lines...]
[junit4] JVM J1: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\contrib\solr-cell\test\temp\junit4-J1-20171023_004051_37410488790944452714586.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[...truncated 3 lines...]
[junit4] JVM J0: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\contrib\solr-cell\test\temp\junit4-J0-20171023_004051_3744395920186976814122.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 559 lines...]
[junit4] JVM J1: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\contrib\solr-langid\test\temp\junit4-J1-20171023_004122_82417891747140687865525.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[...truncated 3 lines...]
[junit4] JVM J0: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\contrib\solr-langid\test\temp\junit4-J0-20171023_004122_82415645547389131526349.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 649 lines...]
[junit4] JVM J0: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\contrib\solr-ltr\test\temp\junit4-J0-20171023_004147_0106685550120816859837.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 3 lines...]
[junit4] JVM J1: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\contrib\solr-ltr\test\temp\junit4-J1-20171023_004147_0101061062818587628967.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[...truncated 596 lines...]
[junit4] JVM J1: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\contrib\solr-uima\test\temp\junit4-J1-20171023_004241_99916069565893985822201.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[...truncated 3 lines...]
[junit4] JVM J0: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\contrib\solr-uima\test\temp\junit4-J0-20171023_004241_9998635607550482166213.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 549 lines...]
[junit4] JVM J0: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\contrib\solr-velocity\test\temp\junit4-J0-20171023_004317_69111414069563266391755.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 31562 lines...]