You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@lucene.apache.org by Apache Jenkins Server <je...@builds.apache.org> on 2018/04/04 22:07:45 UTC
[JENKINS] Lucene-Solr-Tests-master - Build # 2471 - Unstable
Build: https://builds.apache.org/job/Lucene-Solr-Tests-master/2471/
1 tests failed.
FAILED: org.apache.solr.handler.admin.AutoscalingHistoryHandlerTest.testHistory
Error Message:
expected:<8> but was:<10>
Stack Trace:
java.lang.AssertionError: expected:<8> but was:<10>
at __randomizedtesting.SeedInfo.seed([9929ADB97916BF7C:F4D50944C35E407B]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.junit.Assert.failNotEquals(Assert.java:647)
at org.junit.Assert.assertEquals(Assert.java:128)
at org.junit.Assert.assertEquals(Assert.java:472)
at org.junit.Assert.assertEquals(Assert.java:456)
at org.apache.solr.handler.admin.AutoscalingHistoryHandlerTest.testHistory(AutoscalingHistoryHandlerTest.java:355)
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 1897 lines...]
[junit4] JVM J0: stdout was not empty, see: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/build/core/test/temp/junit4-J0-20180404_182057_2354493641547582891717.sysout
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] codec: FastCompressingStoredFields, pf: TestBloomFilteredLucenePostings, dvf: Memory
[junit4] <<< JVM J0: EOF ----
[...truncated 10325 lines...]
[junit4] Suite: org.apache.solr.handler.admin.AutoscalingHistoryHandlerTest
[junit4] 2> 378037 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[9929ADB97916BF7C]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> Creating dataDir: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.handler.admin.AutoscalingHistoryHandlerTest_9929ADB97916BF7C-001/init-core-data-001
[junit4] 2> 378038 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[9929ADB97916BF7C]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=true
[junit4] 2> 378039 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[9929ADB97916BF7C]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (true) via: @org.apache.solr.util.RandomizeSSL(reason=, ssl=NaN, value=NaN, clientAuth=NaN)
[junit4] 2> 378052 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[9929ADB97916BF7C]-worker) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 2 servers in /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.handler.admin.AutoscalingHistoryHandlerTest_9929ADB97916BF7C-001/tempDir-001
[junit4] 2> 378052 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[9929ADB97916BF7C]-worker) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 378052 INFO (Thread-311) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 378052 INFO (Thread-311) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 378073 ERROR (Thread-311) [ ] 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> 378152 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[9929ADB97916BF7C]-worker) [ ] o.a.s.c.ZkTestServer start zk server on port:45056
[junit4] 2> 378155 INFO (zkConnectionManagerCallback-545-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 378159 INFO (jetty-launcher-542-thread-1) [ ] o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: ????-??-??T??:??:??-05:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8
[junit4] 2> 378159 INFO (jetty-launcher-542-thread-2) [ ] o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: ????-??-??T??:??:??-05:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8
[junit4] 2> 378160 INFO (jetty-launcher-542-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 378160 INFO (jetty-launcher-542-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 378160 INFO (jetty-launcher-542-thread-2) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 378160 INFO (jetty-launcher-542-thread-2) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 378160 INFO (jetty-launcher-542-thread-1) [ ] o.e.j.s.session Scavenging every 600000ms
[junit4] 2> 378160 INFO (jetty-launcher-542-thread-2) [ ] o.e.j.s.session Scavenging every 600000ms
[junit4] 2> 378160 INFO (jetty-launcher-542-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@3e40fd5{/solr,null,AVAILABLE}
[junit4] 2> 378160 INFO (jetty-launcher-542-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@cb175d8{/solr,null,AVAILABLE}
[junit4] 2> 378192 INFO (jetty-launcher-542-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@53452af7{HTTP/1.1,[http/1.1]}{127.0.0.1:37296}
[junit4] 2> 378192 INFO (jetty-launcher-542-thread-1) [ ] o.e.j.s.Server Started @378311ms
[junit4] 2> 378192 INFO (jetty-launcher-542-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=37296}
[junit4] 2> 378192 ERROR (jetty-launcher-542-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 378192 INFO (jetty-launcher-542-thread-1) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 378193 INFO (jetty-launcher-542-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.0.0
[junit4] 2> 378193 INFO (jetty-launcher-542-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 378193 INFO (jetty-launcher-542-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 378193 INFO (jetty-launcher-542-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-04-04T20:06:19.444Z
[junit4] 2> 378193 INFO (jetty-launcher-542-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@458eb7b1{HTTP/1.1,[http/1.1]}{127.0.0.1:38845}
[junit4] 2> 378193 INFO (jetty-launcher-542-thread-2) [ ] o.e.j.s.Server Started @378312ms
[junit4] 2> 378193 INFO (jetty-launcher-542-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=38845}
[junit4] 2> 378264 ERROR (jetty-launcher-542-thread-2) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 378264 INFO (jetty-launcher-542-thread-2) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 378264 INFO (jetty-launcher-542-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.0.0
[junit4] 2> 378264 INFO (jetty-launcher-542-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 378264 INFO (jetty-launcher-542-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 378264 INFO (jetty-launcher-542-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-04-04T20:06:19.515Z
[junit4] 2> 378265 INFO (zkConnectionManagerCallback-547-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 378266 INFO (jetty-launcher-542-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 378267 INFO (zkConnectionManagerCallback-549-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 378267 INFO (jetty-launcher-542-thread-2) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 378309 INFO (jetty-launcher-542-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:45056/solr
[junit4] 2> 378310 INFO (zkConnectionManagerCallback-553-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 378311 INFO (jetty-launcher-542-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:45056/solr
[junit4] 2> 378333 INFO (zkConnectionManagerCallback-557-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 378334 INFO (zkConnectionManagerCallback-559-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 378336 INFO (zkConnectionManagerCallback-561-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 378567 INFO (jetty-launcher-542-thread-2) [n:127.0.0.1:38845_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 378567 INFO (jetty-launcher-542-thread-2) [n:127.0.0.1:38845_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:38845_solr
[junit4] 2> 378568 INFO (jetty-launcher-542-thread-2) [n:127.0.0.1:38845_solr ] o.a.s.c.Overseer Overseer (id=72399022197047301-127.0.0.1:38845_solr-n_0000000000) starting
[junit4] 2> 378584 INFO (zkConnectionManagerCallback-570-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 378586 INFO (jetty-launcher-542-thread-2) [n:127.0.0.1:38845_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:45056/solr ready
[junit4] 2> 378587 DEBUG (OverseerStateUpdate-72399022197047301-127.0.0.1:38845_solr-n_0000000000) [ ] o.a.s.c.Overseer Starting to work on the main queue
[junit4] 2> 378596 INFO (jetty-launcher-542-thread-2) [n:127.0.0.1:38845_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:38845_solr
[junit4] 2> 378598 DEBUG (OverseerAutoScalingTriggerThread-72399022197047301-127.0.0.1:38845_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Adding .auto_add_replicas and .scheduled_maintenance triggers
[junit4] 2> 378620 INFO (zkCallback-569-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 378621 INFO (zkCallback-558-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 378621 DEBUG (OverseerAutoScalingTriggerThread-72399022197047301-127.0.0.1:38845_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 1
[junit4] 2> 378624 DEBUG (OverseerAutoScalingTriggerThread-72399022197047301-127.0.0.1:38845_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1, lastZnodeVersion -1
[junit4] 2> 378624 DEBUG (OverseerAutoScalingTriggerThread-72399022197047301-127.0.0.1:38845_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 1
[junit4] 2> 378644 INFO (jetty-launcher-542-thread-1) [n:127.0.0.1:37296_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 378654 INFO (jetty-launcher-542-thread-1) [n:127.0.0.1:37296_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 378656 INFO (jetty-launcher-542-thread-1) [n:127.0.0.1:37296_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 378656 INFO (jetty-launcher-542-thread-1) [n:127.0.0.1:37296_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:37296_solr
[junit4] 2> 378656 DEBUG (OverseerStateUpdate-72399022197047301-127.0.0.1:38845_solr-n_0000000000) [ ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
[junit4] 2> "operation":"downnode",
[junit4] 2> "node_name":"127.0.0.1:37296_solr"} current state version: 0
[junit4] 2> 378657 DEBUG (OverseerStateUpdate-72399022197047301-127.0.0.1:38845_solr-n_0000000000) [ ] o.a.s.c.o.NodeMutator DownNode state invoked for node: 127.0.0.1:37296_solr
[junit4] 2> 378665 INFO (zkCallback-558-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 378686 INFO (zkCallback-560-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 378718 INFO (zkCallback-569-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 378734 DEBUG (OverseerAutoScalingTriggerThread-72399022197047301-127.0.0.1:38845_solr-n_0000000000) [ ] o.a.s.c.a.NodeLostTrigger NodeLostTrigger .auto_add_replicas - Initial livenodes: [127.0.0.1:37296_solr, 127.0.0.1:38845_solr]
[junit4] 2> 378763 DEBUG (OverseerAutoScalingTriggerThread-72399022197047301-127.0.0.1:38845_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers
[junit4] 2> 378778 DEBUG (ScheduledTrigger-491-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2
[junit4] 2> 378779 DEBUG (OverseerAutoScalingTriggerThread-72399022197047301-127.0.0.1:38845_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1, lastZnodeVersion 1
[junit4] 2> 379150 INFO (jetty-launcher-542-thread-1) [n:127.0.0.1:37296_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_37296.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@c0811da
[junit4] 2> 379181 INFO (jetty-launcher-542-thread-1) [n:127.0.0.1:37296_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_37296.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@c0811da
[junit4] 2> 379181 INFO (jetty-launcher-542-thread-1) [n:127.0.0.1:37296_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_37296.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@c0811da
[junit4] 2> 379182 INFO (jetty-launcher-542-thread-1) [n:127.0.0.1:37296_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.handler.admin.AutoscalingHistoryHandlerTest_9929ADB97916BF7C-001/tempDir-001/node1/.
[junit4] 2> 379216 INFO (zkConnectionManagerCallback-575-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 379217 INFO (jetty-launcher-542-thread-1) [n:127.0.0.1:37296_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 379218 INFO (jetty-launcher-542-thread-1) [n:127.0.0.1:37296_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:45056/solr ready
[junit4] 2> 379819 DEBUG (ScheduledTrigger-491-thread-3) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2
[junit4] 2> 379999 INFO (jetty-launcher-542-thread-2) [n:127.0.0.1:38845_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_38845.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@c0811da
[junit4] 2> 380023 INFO (jetty-launcher-542-thread-2) [n:127.0.0.1:38845_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_38845.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@c0811da
[junit4] 2> 380023 INFO (jetty-launcher-542-thread-2) [n:127.0.0.1:38845_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_38845.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@c0811da
[junit4] 2> 380024 INFO (jetty-launcher-542-thread-2) [n:127.0.0.1:38845_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.handler.admin.AutoscalingHistoryHandlerTest_9929ADB97916BF7C-001/tempDir-001/node2/.
[junit4] 2> 380112 INFO (zkConnectionManagerCallback-577-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 380138 INFO (zkConnectionManagerCallback-582-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 380152 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[9929ADB97916BF7C]-worker) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 380153 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[9929ADB97916BF7C]-worker) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:45056/solr ready
[junit4] 2> 380169 INFO (qtp293276880-1551) [n:127.0.0.1:37296_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params replicationFactor=1&name=.system&nrtReplicas=1&action=CREATE&numShards=1&createNodeSet=127.0.0.1:37296_solr&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 380196 INFO (OverseerThreadFactory-493-thread-1) [ ] o.a.s.c.a.c.CreateCollectionCmd Create collection .system
[junit4] 2> 380213 DEBUG (OverseerStateUpdate-72399022197047301-127.0.0.1:38845_solr-n_0000000000) [ ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
[junit4] 2> "name":".system",
[junit4] 2> "fromApi":"true",
[junit4] 2> "replicationFactor":"1",
[junit4] 2> "numShards":1,
[junit4] 2> "createNodeSet":"127.0.0.1:37296_solr",
[junit4] 2> "nrtReplicas":"1",
[junit4] 2> "stateFormat":"2",
[junit4] 2> "operation":"create"} current state version: 0
[junit4] 2> 380213 DEBUG (OverseerStateUpdate-72399022197047301-127.0.0.1:38845_solr-n_0000000000) [ ] o.a.s.c.o.ClusterStateMutator building a new cName: .system
[junit4] 2> 380228 DEBUG (OverseerStateUpdate-72399022197047301-127.0.0.1:38845_solr-n_0000000000) [ ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/.system/state.json
[junit4] 2> 380317 DEBUG (OverseerStateUpdate-72399022197047301-127.0.0.1:38845_solr-n_0000000000) [ ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":".system",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":".system_shard1_replica_n1",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:37296/solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"} current state version: 0
[junit4] 2> 380317 INFO (OverseerStateUpdate-72399022197047301-127.0.0.1:38845_solr-n_0000000000) [ ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":".system",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":".system_shard1_replica_n1",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:37296/solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 380318 DEBUG (OverseerStateUpdate-72399022197047301-127.0.0.1:38845_solr-n_0000000000) [ ] o.a.s.c.o.SliceMutator Old Slice: shard1:{
[junit4] 2> "range":"80000000-7fffffff",
[junit4] 2> "state":"active",
[junit4] 2> "replicas":{}}
[junit4] 2> 380318 DEBUG (OverseerStateUpdate-72399022197047301-127.0.0.1:38845_solr-n_0000000000) [ ] o.a.s.c.o.SliceMutator New Slice: shard1:{
[junit4] 2> "range":"80000000-7fffffff",
[junit4] 2> "state":"active",
[junit4] 2> "replicas":{"core_node2":{
[junit4] 2> "core":".system_shard1_replica_n1",
[junit4] 2> "base_url":"http://127.0.0.1:37296/solr",
[junit4] 2> "state":"down",
[junit4] 2> "node_name":null,
[junit4] 2> "type":"NRT"}}}
[junit4] 2> 380418 DEBUG (OverseerStateUpdate-72399022197047301-127.0.0.1:38845_solr-n_0000000000) [ ] o.a.s.c.o.ZkStateWriter going to update_collection /collections/.system/state.json version: 0
[junit4] 2> 380520 INFO (qtp293276880-1559) [n:127.0.0.1:37296_solr ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node2&collection.configName=.system&newCollection=true&name=.system_shard1_replica_n1&action=CREATE&numShards=1&collection=.system&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 380536 DEBUG (OverseerStateUpdate-72399022197047301-127.0.0.1:38845_solr-n_0000000000) [ ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
[junit4] 2> "core":".system_shard1_replica_n1",
[junit4] 2> "core_node_name":"core_node2",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:37296/solr",
[junit4] 2> "node_name":"127.0.0.1:37296_solr",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"down",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":".system",
[junit4] 2> "type":"NRT",
[junit4] 2> "operation":"state"} current state version: 0
[junit4] 2> 380536 DEBUG (OverseerStateUpdate-72399022197047301-127.0.0.1:38845_solr-n_0000000000) [ ] o.a.s.c.o.ReplicaMutator Update state numShards=1 message={
[junit4] 2> "core":".system_shard1_replica_n1",
[junit4] 2> "core_node_name":"core_node2",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:37296/solr",
[junit4] 2> "node_name":"127.0.0.1:37296_solr",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"down",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":".system",
[junit4] 2> "type":"NRT",
[junit4] 2> "operation":"state"}
[junit4] 2> 380536 DEBUG (OverseerStateUpdate-72399022197047301-127.0.0.1:38845_solr-n_0000000000) [ ] o.a.s.c.o.ReplicaMutator Will update state for replica: core_node2:{"core":".system_shard1_replica_n1","base_url":"http://127.0.0.1:37296/solr","node_name":"127.0.0.1:37296_solr","state":"down","type":"NRT"}
[junit4] 2> 380536 DEBUG (OverseerStateUpdate-72399022197047301-127.0.0.1:38845_solr-n_0000000000) [ ] o.a.s.c.o.ReplicaMutator Collection is now: DocCollection(.system//collections/.system/state.json/1)={
[junit4] 2> "pullReplicas":"0",
[junit4] 2> "replicationFactor":"1",
[junit4] 2> "router":{"name":"compositeId"},
[junit4] 2> "maxShardsPerNode":"1",
[junit4] 2> "autoAddReplicas":"false",
[junit4] 2> "nrtReplicas":"1",
[junit4] 2> "tlogReplicas":"0",
[junit4] 2> "shards":{"shard1":{
[junit4] 2> "range":"80000000-7fffffff",
[junit4] 2> "state":"active",
[junit4] 2> "replicas":{"core_node2":{
[junit4] 2> "core":".system_shard1_replica_n1",
[junit4] 2> "base_url":"http://127.0.0.1:37296/solr",
[junit4] 2> "node_name":"127.0.0.1:37296_solr",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT"}}}}}
[junit4] 2> 380637 DEBUG (OverseerStateUpdate-72399022197047301-127.0.0.1:38845_solr-n_0000000000) [ ] o.a.s.c.o.ZkStateWriter going to update_collection /collections/.system/state.json version: 1
[junit4] 2> 380638 INFO (zkCallback-560-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/.system/state.json] for collection [.system] has occurred - updating... (live nodes size: [2])
[junit4] 2> 380638 INFO (zkCallback-560-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/.system/state.json] for collection [.system] has occurred - updating... (live nodes size: [2])
[junit4] 2> 380819 DEBUG (ScheduledTrigger-491-thread-4) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2
[junit4] 2> 381534 WARN (qtp293276880-1559) [n:127.0.0.1:37296_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1] o.a.s.c.Config You should not use LATEST as luceneMatchVersion property: if you use this setting, and then Solr upgrades to a newer release of Lucene, sizable changes may happen. If precise back compatibility is important then you should instead explicitly specify an actual Lucene version.
[junit4] 2> 381536 INFO (qtp293276880-1559) [n:127.0.0.1:37296_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
[junit4] 2> 381571 INFO (qtp293276880-1559) [n:127.0.0.1:37296_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1] o.a.s.s.ManagedIndexSchemaFactory The schema is configured as managed, but managed schema resource managed-schema not found - loading non-managed schema schema.xml instead
[junit4] 2> 381582 INFO (qtp293276880-1559) [n:127.0.0.1:37296_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1] o.a.s.s.IndexSchema [.system_shard1_replica_n1] Schema name=_system collection or core
[junit4] 2> 381614 INFO (qtp293276880-1559) [n:127.0.0.1:37296_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1] o.a.s.s.IndexSchema Loaded schema _system collection or core/1.1 with uniqueid field id
[junit4] 2> 381652 INFO (qtp293276880-1559) [n:127.0.0.1:37296_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1] o.a.s.s.ManagedIndexSchema Created and persisted managed schema znode at /configs/.system/managed-schema
[junit4] 2> 381654 INFO (qtp293276880-1559) [n:127.0.0.1:37296_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1] o.a.s.s.ManagedIndexSchemaFactory After upgrading to managed schema in ZooKeeper, renamed the non-managed schema /configs/.system/schema.xml to /configs/.system/schema.xml.bak
[junit4] 2> 381654 INFO (qtp293276880-1559) [n:127.0.0.1:37296_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1] o.a.s.c.CoreContainer Creating SolrCore '.system_shard1_replica_n1' using configuration from collection .system, trusted=true
[junit4] 2> 381673 INFO (qtp293276880-1559) [n:127.0.0.1:37296_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_37296.solr.core..system.shard1.replica_n1' (registry 'solr.core..system.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@c0811da
[junit4] 2> 381674 INFO (qtp293276880-1559) [n:127.0.0.1:37296_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 381674 INFO (qtp293276880-1559) [n:127.0.0.1:37296_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1] o.a.s.c.SolrCore [[.system_shard1_replica_n1] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.handler.admin.AutoscalingHistoryHandlerTest_9929ADB97916BF7C-001/tempDir-001/node1/.system_shard1_replica_n1], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.handler.admin.AutoscalingHistoryHandlerTest_9929ADB97916BF7C-001/tempDir-001/node1/./.system_shard1_replica_n1/data/]
[junit4] 2> 381819 DEBUG (ScheduledTrigger-491-thread-3) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2
[junit4] 2> 381840 INFO (qtp293276880-1559) [n:127.0.0.1:37296_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 381840 INFO (qtp293276880-1559) [n:127.0.0.1:37296_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 381841 INFO (qtp293276880-1559) [n:127.0.0.1:37296_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1] o.a.s.u.CommitTracker Hard AutoCommit: if 1 uncommited docs
[junit4] 2> 381842 INFO (qtp293276880-1559) [n:127.0.0.1:37296_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 381844 INFO (qtp293276880-1559) [n:127.0.0.1:37296_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1] o.a.s.s.SolrIndexSearcher Opening [Searcher@3bc80470[.system_shard1_replica_n1] main]
[junit4] 2> 381845 INFO (qtp293276880-1559) [n:127.0.0.1:37296_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/.system
[junit4] 2> 381845 INFO (qtp293276880-1559) [n:127.0.0.1:37296_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/.system
[junit4] 2> 381848 INFO (qtp293276880-1559) [n:127.0.0.1:37296_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1] o.a.s.s.ZkIndexSchemaReader Creating ZooKeeper watch for the managed schema at /configs/.system/managed-schema
[junit4] 2> 381849 INFO (qtp293276880-1559) [n:127.0.0.1:37296_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1] o.a.s.s.ZkIndexSchemaReader Current schema version 0 is already the latest
[junit4] 2> 381850 INFO (qtp293276880-1559) [n:127.0.0.1:37296_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 381868 INFO (searcherExecutor-498-thread-1-processing-n:127.0.0.1:37296_solr x:.system_shard1_replica_n1 c:.system s:shard1 r:core_node2) [n:127.0.0.1:37296_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1] o.a.s.c.SolrCore [.system_shard1_replica_n1] Registered new searcher Searcher@3bc80470[.system_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 381868 INFO (qtp293276880-1559) [n:127.0.0.1:37296_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1596847432001388544
[junit4] 2> 381874 INFO (qtp293276880-1559) [n:127.0.0.1:37296_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1] o.a.s.c.ZkShardTerms Successful update of terms at /collections/.system/terms/shard1 to Terms{values={core_node2=0}, version=0}
[junit4] 2> 381884 INFO (qtp293276880-1559) [n:127.0.0.1:37296_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 381884 INFO (qtp293276880-1559) [n:127.0.0.1:37296_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 381884 INFO (qtp293276880-1559) [n:127.0.0.1:37296_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:37296/solr/.system_shard1_replica_n1/
[junit4] 2> 381884 INFO (qtp293276880-1559) [n:127.0.0.1:37296_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 381884 INFO (qtp293276880-1559) [n:127.0.0.1:37296_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1] o.a.s.c.SyncStrategy http://127.0.0.1:37296/solr/.system_shard1_replica_n1/ has no replicas
[junit4] 2> 381884 INFO (qtp293276880-1559) [n:127.0.0.1:37296_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext Found all replicas participating in election, clear LIR
[junit4] 2> 381887 INFO (qtp293276880-1559) [n:127.0.0.1:37296_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:37296/solr/.system_shard1_replica_n1/ shard1
[junit4] 2> 381888 DEBUG (OverseerStateUpdate-72399022197047301-127.0.0.1:38845_solr-n_0000000000) [ ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
[junit4] 2> "operation":"leader",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":".system",
[junit4] 2> "base_url":"http://127.0.0.1:37296/solr",
[junit4] 2> "core":".system_shard1_replica_n1",
[junit4] 2> "state":"active"} current state version: 0
[junit4] 2> 381989 DEBUG (OverseerStateUpdate-72399022197047301-127.0.0.1:38845_solr-n_0000000000) [ ] o.a.s.c.o.ZkStateWriter going to update_collection /collections/.system/state.json version: 2
[junit4] 2> 381989 INFO (zkCallback-560-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/.system/state.json] for collection [.system] has occurred - updating... (live nodes size: [2])
[junit4] 2> 381989 INFO (zkCallback-560-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/.system/state.json] for collection [.system] has occurred - updating... (live nodes size: [2])
[junit4] 2> 382038 INFO (qtp293276880-1559) [n:127.0.0.1:37296_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 382048 INFO (qtp293276880-1559) [n:127.0.0.1:37296_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node2&collection.configName=.system&newCollection=true&name=.system_shard1_replica_n1&action=CREATE&numShards=1&collection=.system&shard=shard1&wt=javabin&version=2&replicaType=NRT} status=0 QTime=1527
[junit4] 2> 382048 DEBUG (OverseerStateUpdate-72399022197047301-127.0.0.1:38845_solr-n_0000000000) [ ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
[junit4] 2> "core":".system_shard1_replica_n1",
[junit4] 2> "core_node_name":"core_node2",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:37296/solr",
[junit4] 2> "node_name":"127.0.0.1:37296_solr",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"active",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":".system",
[junit4] 2> "type":"NRT",
[junit4] 2> "operation":"state"} current state version: 0
[junit4] 2> 382048 DEBUG (OverseerStateUpdate-72399022197047301-127.0.0.1:38845_solr-n_0000000000) [ ] o.a.s.c.o.ReplicaMutator Update state numShards=1 message={
[junit4] 2> "core":".system_shard1_replica_n1",
[junit4] 2> "core_node_name":"core_node2",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:37296/solr",
[junit4] 2> "node_name":"127.0.0.1:37296_solr",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"active",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":".system",
[junit4] 2> "type":"NRT",
[junit4] 2> "operation":"state"}
[junit4] 2> 382049 DEBUG (OverseerStateUpdate-72399022197047301-127.0.0.1:38845_solr-n_0000000000) [ ] o.a.s.c.o.ReplicaMutator Will update state for replica: core_node2:{"core":".system_shard1_replica_n1","base_url":"http://127.0.0.1:37296/solr","node_name":"127.0.0.1:37296_solr","state":"active","type":"NRT","leader":"true"}
[junit4] 2> 382049 DEBUG (OverseerStateUpdate-72399022197047301-127.0.0.1:38845_solr-n_0000000000) [ ] o.a.s.c.o.ReplicaMutator Collection is now: DocCollection(.system//collections/.system/state.json/3)={
[junit4] 2> "pullReplicas":"0",
[junit4] 2> "replicationFactor":"1",
[junit4] 2> "router":{"name":"compositeId"},
[junit4] 2> "maxShardsPerNode":"1",
[junit4] 2> "autoAddReplicas":"false",
[junit4] 2> "nrtReplicas":"1",
[junit4] 2> "tlogReplicas":"0",
[junit4] 2> "shards":{"shard1":{
[junit4] 2> "range":"80000000-7fffffff",
[junit4] 2> "state":"active",
[junit4] 2> "replicas":{"core_node2":{
[junit4] 2> "core":".system_shard1_replica_n1",
[junit4] 2> "base_url":"http://127.0.0.1:37296/solr",
[junit4] 2> "node_name":"127.0.0.1:37296_solr",
[junit4] 2> "state":"active",
[junit4] 2> "type":"NRT",
[junit4] 2> "leader":"true"}}}}}
[junit4] 2> 382069 INFO (qtp293276880-1551) [n:127.0.0.1:37296_solr ] o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most 30 seconds. Check all shard replicas
[junit4] 2> 382160 DEBUG (OverseerStateUpdate-72399022197047301-127.0.0.1:38845_solr-n_0000000000) [ ] o.a.s.c.o.ZkStateWriter going to update_collection /collections/.system/state.json version: 3
[junit4] 2> 382160 INFO (zkCallback-560-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/.system/state.json] for collection [.system] has occurred - updating... (live nodes size: [2])
[junit4] 2> 382160 INFO (zkCallback-560-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/.system/state.json] for collection [.system] has occurred - updating... (live nodes size: [2])
[junit4] 2> 382204 INFO (OverseerCollectionConfigSetProcessor-72399022197047301-127.0.0.1:38845_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> 382821 DEBUG (ScheduledTrigger-491-thread-4) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2
[junit4] 2> 383069 INFO (qtp293276880-1551) [n:127.0.0.1:37296_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={replicationFactor=1&name=.system&nrtReplicas=1&action=CREATE&numShards=1&createNodeSet=127.0.0.1:37296_solr&wt=javabin&version=2} status=0 QTime=2900
[junit4] 2> 383072 INFO (qtp293276880-1555) [n:127.0.0.1:37296_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params replicationFactor=3&maxShardsPerNode=3&name=AutoscalingHistoryHandlerTest_collection&nrtReplicas=3&action=CREATE&numShards=1&createNodeSet=127.0.0.1:38845_solr&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 383126 INFO (OverseerThreadFactory-493-thread-2) [ ] o.a.s.c.a.c.CreateCollectionCmd Create collection AutoscalingHistoryHandlerTest_collection
[junit4] 2> 383144 INFO (OverseerThreadFactory-493-thread-2) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/protwords.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/protwords.txt
[junit4] 2> 383148 INFO (OverseerThreadFactory-493-thread-2) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/managed-schema to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/managed-schema
[junit4] 2> 383149 INFO (OverseerThreadFactory-493-thread-2) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/solrconfig.xml to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/solrconfig.xml
[junit4] 2> 383172 INFO (OverseerThreadFactory-493-thread-2) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/synonyms.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/synonyms.txt
[junit4] 2> 383182 INFO (OverseerThreadFactory-493-thread-2) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/stopwords.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/stopwords.txt
[junit4] 2> 383184 INFO (OverseerThreadFactory-493-thread-2) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/params.json to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/params.json
[junit4] 2> 383185 INFO (OverseerThreadFactory-493-thread-2) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/contractions_it.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/contractions_it.txt
[junit4] 2> 383239 INFO (OverseerThreadFactory-493-thread-2) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_no.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_no.txt
[junit4] 2> 383241 INFO (OverseerThreadFactory-493-thread-2) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_hy.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_hy.txt
[junit4] 2> 383243 INFO (OverseerThreadFactory-493-thread-2) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stemdict_nl.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stemdict_nl.txt
[junit4] 2> 383244 INFO (OverseerThreadFactory-493-thread-2) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/contractions_ca.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/contractions_ca.txt
[junit4] 2> 383246 INFO (OverseerThreadFactory-493-thread-2) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_id.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_id.txt
[junit4] 2> 383247 INFO (OverseerThreadFactory-493-thread-2) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ga.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_ga.txt
[junit4] 2> 383248 INFO (OverseerThreadFactory-493-thread-2) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_da.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_da.txt
[junit4] 2> 383250 INFO (OverseerThreadFactory-493-thread-2) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_hi.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_hi.txt
[junit4] 2> 383251 INFO (OverseerThreadFactory-493-thread-2) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ja.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_ja.txt
[junit4] 2> 383265 INFO (OverseerThreadFactory-493-thread-2) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_pt.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_pt.txt
[junit4] 2> 383268 INFO (OverseerThreadFactory-493-thread-2) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_hu.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_hu.txt
[junit4] 2> 383310 INFO (OverseerThreadFactory-493-thread-2) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_el.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_el.txt
[junit4] 2> 383311 INFO (OverseerThreadFactory-493-thread-2) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ru.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_ru.txt
[junit4] 2> 383312 INFO (OverseerThreadFactory-493-thread-2) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_tr.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_tr.txt
[junit4] 2> 383313 INFO (OverseerThreadFactory-493-thread-2) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ar.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_ar.txt
[junit4] 2> 383314 INFO (OverseerThreadFactory-493-thread-2) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/userdict_ja.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/userdict_ja.txt
[junit4] 2> 383316 INFO (OverseerThreadFactory-493-thread-2) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_it.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_it.txt
[junit4] 2> 383363 INFO (OverseerThreadFactory-493-thread-2) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_eu.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_eu.txt
[junit4] 2> 383382 INFO (OverseerThreadFactory-493-thread-2) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_cz.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_cz.txt
[junit4] 2> 383418 INFO (OverseerThreadFactory-493-thread-2) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stoptags_ja.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stoptags_ja.txt
[junit4] 2> 383465 INFO (OverseerThreadFactory-493-thread-2) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/contractions_fr.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/contractions_fr.txt
[junit4] 2> 383467 INFO (OverseerThreadFactory-493-thread-2) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_fa.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_fa.txt
[junit4] 2> 383477 INFO (OverseerThreadFactory-493-thread-2) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_de.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_de.txt
[junit4] 2> 383478 INFO (OverseerThreadFactory-493-thread-2) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/contractions_ga.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/contractions_ga.txt
[junit4] 2> 383488 INFO (OverseerThreadFactory-493-thread-2) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ca.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_ca.txt
[junit4] 2> 383489 INFO (OverseerThreadFactory-493-thread-2) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_nl.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_nl.txt
[junit4] 2> 383491 INFO (OverseerThreadFactory-493-thread-2) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_bg.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_bg.txt
[junit4] 2> 383513 INFO (OverseerThreadFactory-493-thread-2) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_es.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_es.txt
[junit4] 2> 383514 INFO (OverseerThreadFactory-493-thread-2) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_en.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_en.txt
[junit4] 2> 383524 INFO (OverseerThreadFactory-493-thread-2) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_lv.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_lv.txt
[junit4] 2> 383525 INFO (OverseerThreadFactory-493-thread-2) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_th.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_th.txt
[junit4] 2> 383527 INFO (OverseerThreadFactory-493-thread-2) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ro.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_ro.txt
[junit4] 2> 383536 INFO (OverseerThreadFactory-493-thread-2) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_fr.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_fr.txt
[junit4] 2> 383538 INFO (OverseerThreadFactory-493-thread-2) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_sv.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_sv.txt
[junit4] 2> 383540 INFO (OverseerThreadFactory-493-thread-2) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_fi.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_fi.txt
[junit4] 2> 383549 INFO (OverseerThreadFactory-493-thread-2) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/hyphenations_ga.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/hyphenations_ga.txt
[junit4] 2> 383551 INFO (OverseerThreadFactory-493-thread-2) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_gl.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_gl.txt
[junit4] 2> 383561 WARN (OverseerThreadFactory-493-thread-2) [ ] o.a.s.c.a.c.CreateCollectionCmd Specified number of replicas of 3 on collection AutoscalingHistoryHandlerTest_collection is higher than the number of Solr instances currently live or live and part of your createNodeSet(1). It's unusual to run two replica of the same slice on the same Solr-instance.
[junit4] 2> 383587 DEBUG (OverseerStateUpdate-72399022197047301-127.0.0.1:38845_solr-n_0000000000) [ ] 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> "maxShardsPerNode":"3",
[junit4] 2> "createNodeSet":"127.0.0.1:38845_solr",
[junit4] 2> "nrtReplicas":"3",
[junit4] 2> "stateFormat":"2",
[junit4] 2> "operation":"create"} current state version: 0
[junit4] 2> 383587 DEBUG (OverseerStateUpdate-72399022197047301-127.0.0.1:38845_solr-n_0000000000) [ ] o.a.s.c.o.ClusterStateMutator building a new cName: AutoscalingHistoryHandlerTest_collection
[junit4] 2> 383688 DEBUG (OverseerStateUpdate-72399022197047301-127.0.0.1:38845_solr-n_0000000000) [ ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/AutoscalingHistoryHandlerTest_collection/state.json
[junit4] 2> 383788 DEBUG (OverseerStateUpdate-72399022197047301-127.0.0.1:38845_solr-n_0000000000) [ ] 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_n1",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:38845/solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"} current state version: 0
[junit4] 2> 383788 INFO (OverseerStateUpdate-72399022197047301-127.0.0.1:38845_solr-n_0000000000) [ ] 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:38845/solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 383789 DEBUG (OverseerStateUpdate-72399022197047301-127.0.0.1:38845_solr-n_0000000000) [ ] o.a.s.c.o.SliceMutator Old Slice: shard1:{
[junit4] 2> "range":"80000000-7fffffff",
[junit4] 2> "state":"active",
[junit4] 2> "replicas":{}}
[junit4] 2> 383789 DEBUG (OverseerStateUpdate-72399022197047301-127.0.0.1:38845_solr-n_0000000000) [ ] o.a.s.c.o.SliceMutator New Slice: shard1:{
[junit4] 2> "range":"80000000-7fffffff",
[junit4] 2> "state":"active",
[junit4] 2> "replicas":{"core_node4":{
[junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n1",
[junit4] 2> "base_url":"http://127.0.0.1:38845/solr",
[junit4] 2> "state":"down",
[junit4] 2> "node_name":null,
[junit4] 2> "type":"NRT"}}}
[junit4] 2> 383789 DEBUG (OverseerStateUpdate-72399022197047301-127.0.0.1:38845_solr-n_0000000000) [ ] 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:38845/solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"} current state version: 0
[junit4] 2> 383789 INFO (OverseerStateUpdate-72399022197047301-127.0.0.1:38845_solr-n_0000000000) [ ] 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:38845/solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 383790 DEBUG (OverseerStateUpdate-72399022197047301-127.0.0.1:38845_solr-n_0000000000) [ ] o.a.s.c.o.SliceMutator Old Slice: shard1:{
[junit4] 2> "range":"80000000-7fffffff",
[junit4] 2> "state":"active",
[junit4] 2> "replicas":{"core_node4":{
[junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n1",
[junit4] 2> "base_url":"http://127.0.0.1:38845/solr",
[junit4] 2> "state":"down",
[junit4] 2> "node_name":null,
[junit4] 2> "type":"NRT"}}}
[junit4] 2> 383790 DEBUG (OverseerStateUpdate-72399022197047301-127.0.0.1:38845_solr-n_0000000000) [ ] o.a.s.c.o.SliceMutator New Slice: shard1:{
[junit4] 2> "range":"80000000-7fffffff",
[junit4] 2> "state":"active",
[junit4] 2> "replicas":{
[junit4] 2> "core_node4":{
[junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n1",
[junit4] 2> "base_url":"http://127.0.0.1:38845/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:38845/solr",
[junit4] 2> "state":"down",
[junit4] 2> "node_name":null,
[junit4] 2> "type":"NRT"}}}
[junit4] 2> 383801 DEBUG (OverseerStateUpdate-72399022197047301-127.0.0.1:38845_solr-n_0000000000) [ ] 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_n3",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:38845/solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"} current state version: 0
[junit4] 2> 383801 INFO (OverseerStateUpdate-72399022197047301-127.0.0.1:38845_solr-n_0000000000) [ ] 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_n3",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:38845/solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 383802 DEBUG (OverseerStateUpdate-72399022197047301-127.0.0.1:38845_solr-n_0000000000) [ ] o.a.s.c.o.SliceMutator Old Slice: shard1:{
[junit4] 2> "range":"80000000-7fffffff",
[junit4] 2> "state":"active",
[junit4] 2> "replicas":{
[junit4] 2> "core_node4":{
[junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n1",
[junit4] 2> "base_url":"http://127.0.0.1:38845/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:38845/solr",
[junit4] 2> "state":"down",
[junit4] 2> "node_name":null,
[junit4] 2> "type":"NRT"}}}
[junit4] 2> 383802 DEBUG (OverseerStateUpdate-72399022197047301-127.0.0.1:38845_solr-n_0000000000) [ ] o.a.s.c.o.SliceMutator New Slice: shard1:{
[junit4] 2> "range":"80000000-7fffffff",
[junit4] 2> "state":"active",
[junit4] 2> "replicas":{
[junit4] 2> "core_node4":{
[junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n1",
[junit4] 2> "base_url":"http://127.0.0.1:38845/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:38845/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_n3",
[junit4] 2> "base_url":"http://127.0.0.1:38845/solr",
[junit4] 2> "state":"down",
[junit4] 2> "node_name":null,
[junit4] 2> "type":"NRT"}}}
[junit4] 2> 383821 DEBUG (ScheduledTrigger-491-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2
[junit4] 2> 383905 DEBUG (OverseerStateUpdate-72399022197047301-127.0.0.1:38845_solr-n_0000000000) [ ] o.a.s.c.o.ZkStateWriter going to update_collection /collections/AutoscalingHistoryHandlerTest_collection/state.json version: 0
[junit4] 2> 383993 INFO (qtp132155466-1552) [n:127.0.0.1:38845_solr ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node4&collection.configName=AutoscalingHistoryHandlerTest_collection.AUTOCREATED&newCollection=true&name=AutoscalingHistoryHandlerTest_collection_shard1_replica_n1&action=CREATE&numShards=1&collection=AutoscalingHistoryHandlerTest_collection&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 383994 INFO (qtp132155466-1552) [n:127.0.0.1:38845_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 383994 INFO (qtp132155466-1562) [n:127.0.0.1:38845_solr ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node6&collection.configName=AutoscalingHistoryHandlerTest_collection.AUTOCREATED&newCollection=true&name=AutoscalingHistoryHandlerTest_collection_shard1_replica_n3&action=CREATE&numShards=1&collection=AutoscalingHistoryHandlerTest_collection&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 383995 INFO (qtp132155466-1564) [n:127.0.0.1:38845_solr ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node5&collection.configName=AutoscalingHistoryHandlerTest_collection.AUTOCREATED&newCollection=true&name=AutoscalingHistoryHandlerTest_collection_shard1_replica_n2&action=CREATE&numShards=1&collection=AutoscalingHistoryHandlerTest_collection&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 384079 DEBUG (OverseerStateUpdate-72399022197047301-127.0.0.1:38845_solr-n_0000000000) [ ] o.a.s.c.Overseer processMessage: queueSize: 2, message = {
[junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n1",
[junit4] 2> "core_node_name":"core_node4",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:38845/solr",
[junit4] 2> "node_name":"127.0.0.1:38845_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> 384079 DEBUG (OverseerStateUpdate-72399022197047301-127.0.0.1:38845_solr-n_0000000000) [ ] 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_node4",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:38845/solr",
[junit4] 2> "node_name":"127.0.0.1:38845_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> 384079 DEBUG (OverseerStateUpdate-72399022197047301-127.0.0.1:38845_solr-n_0000000000) [ ] o.a.s.c.o.ReplicaMutator Will update state for replica: core_node4:{"core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n1","base_url":"http://127.0.0.1:38845/solr","node_name":"127.0.0.1:38845_solr","state":"down","type":"NRT"}
[junit4] 2> 384079 DEBUG (OverseerStateUpdate-72399022197047301-127.0.0.1:38845_solr-n_0000000000) [ ] o.a.s.c.o.ReplicaMutator Collection is now: DocCollection(AutoscalingHistoryHandlerTest_collection//collections/AutoscalingHistoryHandlerTest_collection/state.json/1)={
[junit4] 2> "pullReplicas":"0",
[junit4] 2> "replicationFactor":"3",
[junit4] 2> "router":{"name":"compositeId"},
[junit4] 2> "maxShardsPerNode":"3",
[junit4] 2> "autoAddReplicas":"false",
[junit4] 2> "nrtReplicas":"3",
[junit4] 2> "tlogReplicas":"0",
[junit4] 2> "shards":{"shard1":{
[junit4] 2> "range":"80000000-7fffffff",
[junit4] 2> "state":"active",
[junit4] 2> "replicas":{
[junit4] 2> "core_node4":{
[junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n1",
[junit4] 2> "base_url":"http://127.0.0.1:38845/solr",
[junit4] 2> "node_name":"127.0.0.1:38845_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:38845/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_n3",
[junit4] 2> "base_url":"http://127.0.0.1:38845/solr",
[junit4] 2> "state":"down",
[junit4] 2> "node_name":null,
[junit4] 2> "type":"NRT"}}}}}
[junit4] 2> 384079 DEBUG (OverseerStateUpdate-72399022197047301-127.0.0.1:38845_solr-n_0000000000) [ ] o.a.s.c.Overseer processMessage: queueSize: 2, 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:38845/solr",
[junit4] 2> "node_name":"127.0.0.1:38845_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> 384079 DEBUG (OverseerStateUpdate-72399022197047301-127.0.0.1:38845_solr-n_0000000000) [ ] 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:38845/solr",
[junit4] 2> "node_name":"127.0.0.1:38845_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> 384079 DEBUG (OverseerStateUpdate-72399022197047301-127.0.0.1:38845_solr-n_0000000000) [ ] 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:38845/solr","node_name":"127.0.0.1:38845_solr","state":"down","type":"NRT"}
[junit4] 2> 384079 DEBUG (OverseerStateUpdate-72399022197047301-127.0.0.1:38845_solr-n_0000000000) [ ] o.a.s.c.o.ReplicaMutator Collection is now: DocCollection(AutoscalingHistoryHandlerTest_collection//collections/AutoscalingHistoryHandlerTest_collection/state.json/1)={
[junit4] 2> "pullReplicas":"0",
[junit4] 2> "replicationFactor":"3",
[junit4] 2> "router":{"name":"compositeId"},
[junit4] 2> "maxShardsPerNode":"3",
[junit4] 2> "autoAddReplicas":"false",
[junit4] 2> "nrtReplicas":"3",
[junit4] 2> "tlogReplicas":"0",
[junit4] 2> "shards":{"shard1":{
[junit4] 2> "range":"80000000-7fffffff",
[junit4] 2> "state":"active",
[junit4] 2> "replicas":{
[junit4] 2> "core_node4":{
[junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n1",
[junit4] 2> "base_url":"http://127.0.0.1:38845/solr",
[junit4] 2> "node_name":"127.0.0.1:38845_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:38845/solr",
[junit4] 2> "node_name":"127.0.0.1:38845_solr",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT"},
[junit4] 2> "core_node6":{
[junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n3",
[junit4] 2> "base_url":"http://127.0.0.1:38845/solr",
[junit4] 2> "state":"down",
[junit4] 2> "node_name":null,
[junit4] 2> "type":"NRT"}}}}}
[junit4] 2> 384102 DEBUG (OverseerStateUpdate-72399022197047301-127.0.0.1:38845_solr-n_0000000000) [ ] o.a.s.c.Overseer processMessage: queueSize: 3, message = {
[junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n3",
[junit4] 2> "core_node_name":"core_node6",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:38845/solr",
[junit4] 2> "node_name":"127.0.0.1:38845_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> 384102 DEBUG (OverseerStateUpdate-72399022197047301-127.0.0.1:38845_solr-n_0000000000) [ ] o.a.s.c.o.ReplicaMutator Update state numShards=1 message={
[junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n3",
[junit4] 2> "core_node_name":"core_node6",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:38845/solr",
[junit4] 2> "node_name":"127.0.0.1:38845_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> 384102 DEBUG (OverseerStateUpdate-72399022197047301-127.0.0.1:38845_solr-n_0000000000) [ ] o.a.s.c.o.ReplicaMutator Will update state for replica: core_node6:{"core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n3","base_url":"http://127.0.0.1:38845/solr","node_name":"127.0.0.1:38845_solr","state":"down","type":"NRT"}
[junit4] 2> 384102 DEBUG (OverseerStateUpdate-72399022197047301-127.0.0.1:38845_solr-n_0000000000) [ ] o.a.s.c.o.ReplicaMutator Collection is now: DocCollection(AutoscalingHistoryHandlerTest_collection//collections/AutoscalingHistoryHandlerTest_collection/state.json/1)={
[junit4] 2> "pullReplicas":"0",
[junit4] 2> "replicationFactor":"3",
[junit4] 2> "router":{"name":"compositeId"},
[junit4] 2> "maxShardsPerNode":"3",
[junit4] 2> "autoAddReplicas":"false",
[junit4] 2> "nrtReplicas":"3",
[junit4] 2> "tlogReplicas":"0",
[junit4] 2> "shards":{"shard1":{
[junit4] 2> "range":"80000000-7fffffff",
[junit4] 2> "state":"active",
[junit4] 2> "replicas":{
[junit4] 2> "core_node4":{
[junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n1",
[junit4] 2> "base_url":"http://127.0.0.1:38845/solr",
[junit4] 2> "node_name":"127.0.0.1:38845_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:38845/solr",
[junit4] 2> "node_name":"127.0.0.1:38845_solr",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT"},
[junit4] 2> "core_node6":{
[junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n3",
[junit4] 2> "base_url":"http://127.0.0.1:38845/solr",
[junit4] 2> "node_name":"127.0.0.1:38845_solr",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT"}}}}}
[junit4] 2> 384203 DEBUG (OverseerStateUpdate-72399022197047301-127.0.0.1:38845_solr-n_0000000000) [ ] o.a.s.c.o.ZkStateWriter going to update_collection /collections/AutoscalingHistoryHandlerTest_collection/state.json version: 1
[junit4] 2> 384203 INFO (zkCallback-558-thread-1) [ ] 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: [2])
[junit4] 2> 384204 INFO (zkCallback-558-thread-2) [ ] 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: [2])
[junit4] 2> 384204 INFO (zkCallback-558-thread-4) [ ] 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: [2])
[junit4] 2> 384204 INFO (zkCallback-558-thread-3) [ ] 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: [2])
[junit4] 2> 384822 DEBUG (ScheduledTrigger-491-thread-4) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2
[junit4] 2> 385124 INFO (qtp132155466-1564) [n:127.0.0.1:38845_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> 385125 INFO (qtp132155466-1564) [n:127.0.0.1:38845_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> 385126 WARN (qtp132155466-1564) [n:127.0.0.1:38845_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 /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.handler.admin.AutoscalingHistoryHandlerTest_9929ADB97916BF7C-001/tempDir-001/node2/AutoscalingHistoryHandlerTest_collection_shard1_replica_n2/../../../../contrib/extraction/lib filtered by .*\.jar to classpath: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.handler.admin.AutoscalingHistoryHandlerTest_9929ADB97916BF7C-001/tempDir-001/node2/AutoscalingHistoryHandlerTest_collection_shard1_replica_n2/../../../../contrib/extraction/lib
[junit4] 2> 385127 WARN (qtp132155466-1564) [n:127.0.0.1:38845_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 /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.handler.admin.AutoscalingHistoryHandlerTest_9929ADB97916BF7C-001/tempDir-001/node2/AutoscalingHistoryHandlerTest_collection_shard1_replica_n2/../../../../dist filtered by solr-cell-\d.*\.jar to classpath: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.handler.admin.AutoscalingHistoryHandlerTest_9929ADB97916BF7C-001/tempDir-001/node2/AutoscalingHistoryHandlerTest_collection_shard1_replica_n2/../../../../dist
[junit4] 2> 385127 WARN (qtp132155466-1564) [n:127.0.0.1:38845_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 /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.handler.admin.AutoscalingHistoryHandlerTest_9929ADB97916BF7C-001/tempDir-001/node2/AutoscalingHistoryHandlerTest_collection_shard1_replica_n2/../../../../contrib/clustering/lib filtered by .*\.jar to classpath: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.handler.admin.AutoscalingHistoryHandlerTest_9929ADB97916BF7C-001/tempDir-001/node2/AutoscalingHistoryHandlerTest_collection_shard1_replica_n2/../../../../contrib/clustering/lib
[junit4] 2> 385139 WARN (qtp132155466-1564) [n:127.0.0.1:38845_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 /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.handler.admin.AutoscalingHistoryHandlerTest_9929ADB97916BF7C-001/tempDir-001/node2/AutoscalingHistoryHandlerTest_collection_shard1_replica_n2/../../../../dist filtered by solr-clustering-\d.*\.jar to classpath: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.handler.admin.AutoscalingHistoryHandlerTest_9929ADB97916BF7C-001/tempDir-001/node2/AutoscalingHistoryHandlerTest_collection_shard1_replica_n2/../../../../dist
[junit4] 2> 385140 WARN (qtp132155466-1564) [n:127.0.0.1:38845_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 /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.handler.admin.AutoscalingHistoryHandlerTest_9929ADB97916BF7C-001/tempDir-001/node2/AutoscalingHistoryHandlerTest_collection_shard1_replica_n2/../../../../contrib/langid/lib filtered by .*\.jar to classpath: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.handler.admin.AutoscalingHistoryHandlerTest_9929ADB97916BF7C-001/tempDir-001/node2/AutoscalingHistoryHandlerTest_collection_shard1_replica_n2/../../../../contrib/langid/lib
[junit4] 2> 385140 WARN (qtp132155466-1564) [n:127.0.0.1:38845_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 /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.handler.admin.AutoscalingHistoryHandlerTest_9929ADB97916BF7C-001/tempDir-001/node2/AutoscalingHistoryHandlerTest_collection_shard1_replica_n2/../../../../dist filtered by solr-langid-\d.*\.jar to classpath: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.handler.admin.AutoscalingHistoryHandlerTest_9929ADB97916BF7C-001/tempDir-001/node2/AutoscalingHistoryHandlerTest_collection_shard1_replica_n2/../../../../dist
[junit4] 2> 385140 WARN (qtp132155466-1564) [n:127.0.0.1:38845_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 /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.handler.admin.AutoscalingHistoryHandlerTest_9929ADB97916BF7C-001/tempDir-001/node2/AutoscalingHistoryHandlerTest_collection_shard1_replica_n2/../../../../contrib/velocity/lib filtered by .*\.jar to classpath: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.handler.admin.AutoscalingHistoryHandlerTest_9929ADB97916BF7C-001/tempDir-001/node2/AutoscalingHistoryHandlerTest_collection_shard1_replica_n2/../../../../contrib/velocity/lib
[junit4] 2> 385140 WARN (qtp132155466-1564) [n:127.0.0.1:38845_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 /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.handler.admin.AutoscalingHistoryHandlerTest_9929ADB97916BF7C-001/tempDir-001/node2/AutoscalingHistoryHandlerTest_collection_shard1_replica_n2/../../../../dist filtered by solr-velocity-\d.*\.jar to classpath: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.handler.admin.AutoscalingHistoryHandlerTest_9929ADB97916BF7C-001/tempDir-001/node2/AutoscalingHistoryHandlerTest_collection_shard1_replica_n2/../../../../dist
[junit4] 2> 385162 INFO (qtp132155466-1564) [n:127.0.0.1:38845_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> 385182 INFO (qtp132155466-1562) [n:127.0.0.1:38845_solr c:AutoscalingHistoryHandlerTest_collection s:shard1 r:core_node6 x:AutoscalingHistoryHandlerTest_collection_shard1_replica_n3] o.a.s.c.RequestParams conf resource params.json loaded . version : 0
[junit4] 2> 385182 INFO (qtp132155466-1562) [n:127.0.0.1:38845_solr c:AutoscalingHistoryHandlerTest_collection s:shard1 r:core_node6 x:AutoscalingHistoryHandlerTest_collection_shard1_replica_n3] o.a.s.c.RequestParams request params refreshed to version 0
[junit4] 2> 385183 WARN (qtp132155466-1562) [n:127.0.0.1:38845_solr c:AutoscalingHistoryHandlerTest_collection s:shard1 r:core_node6 x:AutoscalingHistoryHandlerTest_collection_shard1_replica_n3] o.a.s.c.SolrConfig Couldn't add files from /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.handler.admin.AutoscalingHistoryHandlerTest_9929ADB97916BF7C-001/tempDir-001/node2/AutoscalingHistoryHandlerTest_collection_shard1_replica_n3/../../../../contrib/extraction/lib filtered by .*\.jar to classpath: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.handler.admin.AutoscalingHistoryHandlerTest_9929ADB97916BF7C-001/tempDir-001/node2/AutoscalingHistoryHandlerTest_collection_shard1_replica_n3/../../../../contrib/extraction/lib
[junit4] 2> 385183 WARN (qtp132155466-1562) [n:127.0.0.1:38845_solr c:AutoscalingHistoryHandlerTest_collection s:shard1 r:core_node6 x:AutoscalingHistoryHandlerTest_collection_shard1_replica_n3] o.a.s.c.SolrConfig Couldn't add files from /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.handler.admin.AutoscalingHistoryHandlerTest_9929ADB97916BF7C-001/tempDir-001/node2/AutoscalingHistoryHandlerTest_collection_shard1_replica_n3/../../../../dist filtered by solr-cell-\d.*\.jar to classpath: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.handler.admin.AutoscalingHistoryHandlerTest_9929ADB97916BF7C-001/tempDir-001/node2/AutoscalingHistoryHandlerTest_collection_shard1_replica_n3/../../../../dist
[junit4] 2> 385184 WARN (qtp132155466-1562) [n:127.0.0.1:38845_solr c:AutoscalingHistoryHandlerTest_collection s:shard1 r:core_node6 x:AutoscalingHistoryHandlerTest_collection_shard1_replica_n3] o.a.s.c.SolrConfig Couldn't add files from /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.handler.admin.AutoscalingHistoryHandlerTest_9929ADB97916BF7C-001/tempDir-001/node2/AutoscalingHistoryHandlerTest_collection_shard1_replica_n3/../../../../contrib/clustering/lib filtered by .*\.jar to classpath: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.handler.admin.AutoscalingHistoryHandlerTest_9929ADB97916BF7C-001/tempDir-001/node2/AutoscalingHistoryHandlerTest_collection_shard1_replica_n3/../../../../contrib/clustering/lib
[junit4] 2> 385184 WARN (qtp132155466-1562) [n:127.0.0.1:38845_solr c:AutoscalingHistoryHandlerTest_collection s:shard1 r:core_node6 x:AutoscalingHistoryHandlerTest_collection_shard1_replica_n3] o.a.s.c.SolrConfig Couldn't add files from /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.handler.admin.AutoscalingHistoryHandlerTest_9929ADB97916BF7C-001/tempDir-001/node2/AutoscalingHistoryHandlerTest_collection_shard1_replica_n3/../../../../dist filtered by solr-clustering-\d.*\.jar to classpath: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.handler.admin.AutoscalingHistoryHandlerTest_9929ADB97916BF7C-001/tempDir-001/node2/AutoscalingHistoryHandlerTest_collection_shard1_replica_n3/../../../../dist
[junit4] 2> 385202 INFO (qtp132155466-1552) [n:127.0.0.1:38845_solr c:AutoscalingHistoryHandlerTest_collection s:shard1 r:core_node4 x:AutoscalingHistoryHandlerTest_collection_shard1_replica_n1] o.a.s.c.RequestParams conf resource params.json loaded . version : 0
[junit4] 2> 385203 INFO (qtp132155466-1552) [n:127.0.0.1:38845_solr c:AutoscalingHistoryHandlerTest_collection s:shard1 r:core_node4 x:AutoscalingHistoryHandlerTest_collection_shard1_replica_n1] o.a.s.c.RequestParams request params refreshed to version 0
[junit4] 2> 385203 WARN (qtp132155466-1552) [n:127.0.0.1:38845_solr c:AutoscalingHistoryHandlerTest_collection s:shard1 r:core_node4 x:AutoscalingHistoryHandlerTest_collection_shard1_replica_n1] o.a.s.c.SolrConfig Couldn't add files from /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.handler.admin.AutoscalingHistoryHandlerTest_9929ADB97916BF7C-001/tempDir-001/node2/AutoscalingHistoryHandlerTest_collection_shard1_replica_n1/../../../../contrib/extraction/lib filtered by .*\.jar to classpath: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.handler.admin.AutoscalingHistoryHandlerTest_9929ADB97916BF7C-001/tempDir-001/node2/AutoscalingHistoryHandlerTest_collection_shard1_replica_n1/../../../../contrib/extraction/lib
[junit4] 2> 385203 WARN (qtp132155466-1552) [n:127.0.0.1:38845_solr c:AutoscalingHistoryHandlerTest_collection s:shard1 r:core_node4 x:Au
[...truncated too long message...]
gs.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file = /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file = /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file = /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file = /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/top-level-ivy-settings.xml
resolve:
jar-checksums:
[mkdir] Created dir: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/null368175394
[copy] Copying 40 files to /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/null368175394
[delete] Deleting directory /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/null368175394
resolve-example:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file = /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/top-level-ivy-settings.xml
resolve:
resolve-server:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file = /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/top-level-ivy-settings.xml
resolve:
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file = /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file = /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file = /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file = /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file = /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file = /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file = /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file = /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file = /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file = /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file = /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file = /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file = /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file = /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file = /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file = /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/top-level-ivy-settings.xml
resolve:
jar-checksums:
[mkdir] Created dir: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/null1283844529
[copy] Copying 246 files to /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/null1283844529
[delete] Deleting directory /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/null1283844529
check-working-copy:
[ivy:cachepath] :: resolving dependencies :: org.eclipse.jgit#org.eclipse.jgit-caller;working
[ivy:cachepath] confs: [default]
[ivy:cachepath] found org.eclipse.jgit#org.eclipse.jgit;4.6.0.201612231935-r in public
[ivy:cachepath] found com.jcraft#jsch;0.1.53 in public
[ivy:cachepath] found com.googlecode.javaewah#JavaEWAH;1.1.6 in public
[ivy:cachepath] found org.apache.httpcomponents#httpclient;4.3.6 in public
[ivy:cachepath] found org.apache.httpcomponents#httpcore;4.3.3 in public
[ivy:cachepath] found commons-logging#commons-logging;1.1.3 in public
[ivy:cachepath] found commons-codec#commons-codec;1.6 in public
[ivy:cachepath] found org.slf4j#slf4j-api;1.7.2 in public
[ivy:cachepath] :: resolution report :: resolve 71ms :: artifacts dl 11ms
---------------------------------------------------------------------
| | modules || artifacts |
| conf | number| search|dwnlded|evicted|| number|dwnlded|
---------------------------------------------------------------------
| default | 8 | 0 | 0 | 0 || 8 | 0 |
---------------------------------------------------------------------
[wc-checker] Initializing working copy...
[wc-checker] SLF4J: Failed to load class "org.slf4j.impl.StaticLoggerBinder".
[wc-checker] SLF4J: Defaulting to no-operation (NOP) logger implementation
[wc-checker] SLF4J: See http://www.slf4j.org/codes.html#StaticLoggerBinder for further details.
[wc-checker] Checking working copy status...
-jenkins-base:
BUILD SUCCESSFUL
Total time: 226 minutes 55 seconds
Archiving artifacts
WARN: No artifacts found that match the file pattern "**/*.events,heapdumps/**,**/hs_err_pid*". Configuration error?
WARN: java.lang.InterruptedException: no matches found within 10000
Recording test results
Build step 'Publish JUnit test result report' changed build result to UNSTABLE
Email was triggered for: Unstable (Test Failures)
Sending email for trigger: Unstable (Test Failures)
[JENKINS] Lucene-Solr-Tests-master - Build # 2473 - Failure
Posted by Apache Jenkins Server <je...@builds.apache.org>.
Build: https://builds.apache.org/job/Lucene-Solr-Tests-master/2473/
All tests passed
Build Log:
[...truncated 52810 lines...]
-ecj-javadoc-lint-tests:
[mkdir] Created dir: /tmp/ecj1297188283
[ecj-lint] Compiling 20 source files to /tmp/ecj1297188283
[ecj-lint] ----------
[ecj-lint] 1. ERROR in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/spatial3d/src/test/org/apache/lucene/spatial3d/geom/GeoPolygonTest.java (at line 24)
[ecj-lint] import org.junit.Ignore;
[ecj-lint] ^^^^^^^^^^^^^^^^
[ecj-lint] The import org.junit.Ignore is never used
[ecj-lint] ----------
[ecj-lint] 1 problem (1 error)
BUILD FAILED
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/build.xml:633: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/build.xml:101: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/build.xml:208: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/common-build.xml:2264: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/common-build.xml:2095: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/common-build.xml:2128: Compile failed; see the compiler error output for details.
Total time: 87 minutes 48 seconds
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
Recording test results
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any
[JENKINS] Lucene-Solr-Tests-master - Build # 2472 - Still Unstable
Posted by Apache Jenkins Server <je...@builds.apache.org>.
Build: https://builds.apache.org/job/Lucene-Solr-Tests-master/2472/
1 tests failed.
FAILED: org.apache.solr.cloud.TestPullReplicaErrorHandling.testPullReplicaDisconnectsFromZooKeeper
Error Message:
Didn't get expected doc count. Expected: 10, Found: 0
Stack Trace:
java.lang.AssertionError: Didn't get expected doc count. Expected: 10, Found: 0
at __randomizedtesting.SeedInfo.seed([7F7146C1E56BCFA0:60386642C081C0C0]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.apache.solr.cloud.TestPullReplicaErrorHandling.assertNumDocs(TestPullReplicaErrorHandling.java:246)
at org.apache.solr.cloud.TestPullReplicaErrorHandling.assertNumDocs(TestPullReplicaErrorHandling.java:251)
at org.apache.solr.cloud.TestPullReplicaErrorHandling.testPullReplicaDisconnectsFromZooKeeper(TestPullReplicaErrorHandling.java:222)
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 14052 lines...]
[junit4] Suite: org.apache.solr.cloud.TestPullReplicaErrorHandling
[junit4] 2> 1905493 INFO (SUITE-TestPullReplicaErrorHandling-seed#[7F7146C1E56BCFA0]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> Creating dataDir: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.cloud.TestPullReplicaErrorHandling_7F7146C1E56BCFA0-001/init-core-data-001
[junit4] 2> 1905494 WARN (SUITE-TestPullReplicaErrorHandling-seed#[7F7146C1E56BCFA0]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=13 numCloses=13
[junit4] 2> 1905494 INFO (SUITE-TestPullReplicaErrorHandling-seed#[7F7146C1E56BCFA0]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=true
[junit4] 2> 1905495 INFO (SUITE-TestPullReplicaErrorHandling-seed#[7F7146C1E56BCFA0]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.SolrTestCaseJ4$SuppressSSL(bugUrl=https://issues.apache.org/jira/browse/SOLR-5776)
[junit4] 2> 1905495 INFO (SUITE-TestPullReplicaErrorHandling-seed#[7F7146C1E56BCFA0]-worker) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 4 servers in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.cloud.TestPullReplicaErrorHandling_7F7146C1E56BCFA0-001/tempDir-001
[junit4] 2> 1905495 INFO (SUITE-TestPullReplicaErrorHandling-seed#[7F7146C1E56BCFA0]-worker) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 1905496 INFO (Thread-5426) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 1905496 INFO (Thread-5426) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 1905504 ERROR (Thread-5426) [ ] 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> 1905596 INFO (SUITE-TestPullReplicaErrorHandling-seed#[7F7146C1E56BCFA0]-worker) [ ] o.a.s.c.ZkTestServer start zk server on port:36656
[junit4] 2> 1905600 INFO (zkConnectionManagerCallback-5152-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1905606 INFO (jetty-launcher-5149-thread-1) [ ] o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: 2017-11-21T23:27:37+02:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8
[junit4] 2> 1905606 INFO (jetty-launcher-5149-thread-2) [ ] o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: 2017-11-21T23:27:37+02:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8
[junit4] 2> 1905607 INFO (jetty-launcher-5149-thread-3) [ ] o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: 2017-11-21T23:27:37+02:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8
[junit4] 2> 1905607 INFO (jetty-launcher-5149-thread-4) [ ] o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: 2017-11-21T23:27:37+02:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8
[junit4] 2> 1905608 INFO (jetty-launcher-5149-thread-2) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1905608 INFO (jetty-launcher-5149-thread-2) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1905608 INFO (jetty-launcher-5149-thread-2) [ ] o.e.j.s.session Scavenging every 600000ms
[junit4] 2> 1905608 INFO (jetty-launcher-5149-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@1db7f861{/solr,null,AVAILABLE}
[junit4] 2> 1905608 INFO (jetty-launcher-5149-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@2cdc0ec1{HTTP/1.1,[http/1.1]}{127.0.0.1:33941}
[junit4] 2> 1905608 INFO (jetty-launcher-5149-thread-2) [ ] o.e.j.s.Server Started @1905648ms
[junit4] 2> 1905608 INFO (jetty-launcher-5149-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=33941}
[junit4] 2> 1905609 ERROR (jetty-launcher-5149-thread-2) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1905609 INFO (jetty-launcher-5149-thread-2) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1905609 INFO (jetty-launcher-5149-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.0.0
[junit4] 2> 1905609 INFO (jetty-launcher-5149-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1905609 INFO (jetty-launcher-5149-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1905609 INFO (jetty-launcher-5149-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-04-05T09:51:51.838Z
[junit4] 2> 1905614 INFO (jetty-launcher-5149-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1905614 INFO (jetty-launcher-5149-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1905614 INFO (jetty-launcher-5149-thread-1) [ ] o.e.j.s.session Scavenging every 600000ms
[junit4] 2> 1905614 INFO (jetty-launcher-5149-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@73d1ff45{/solr,null,AVAILABLE}
[junit4] 2> 1905614 INFO (jetty-launcher-5149-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@55d8b866{HTTP/1.1,[http/1.1]}{127.0.0.1:60358}
[junit4] 2> 1905614 INFO (jetty-launcher-5149-thread-1) [ ] o.e.j.s.Server Started @1905654ms
[junit4] 2> 1905614 INFO (jetty-launcher-5149-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=60358}
[junit4] 2> 1905614 ERROR (jetty-launcher-5149-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1905614 INFO (jetty-launcher-5149-thread-1) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1905614 INFO (jetty-launcher-5149-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.0.0
[junit4] 2> 1905614 INFO (jetty-launcher-5149-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1905614 INFO (jetty-launcher-5149-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1905614 INFO (jetty-launcher-5149-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-04-05T09:51:51.843Z
[junit4] 2> 1905618 INFO (jetty-launcher-5149-thread-4) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1905618 INFO (jetty-launcher-5149-thread-4) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1905618 INFO (jetty-launcher-5149-thread-4) [ ] o.e.j.s.session Scavenging every 600000ms
[junit4] 2> 1905618 INFO (jetty-launcher-5149-thread-4) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@2352cf3e{/solr,null,AVAILABLE}
[junit4] 2> 1905618 INFO (jetty-launcher-5149-thread-4) [ ] o.e.j.s.AbstractConnector Started ServerConnector@49af18d{HTTP/1.1,[http/1.1]}{127.0.0.1:53600}
[junit4] 2> 1905618 INFO (jetty-launcher-5149-thread-4) [ ] o.e.j.s.Server Started @1905658ms
[junit4] 2> 1905618 INFO (jetty-launcher-5149-thread-4) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=53600}
[junit4] 2> 1905618 ERROR (jetty-launcher-5149-thread-4) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1905618 INFO (jetty-launcher-5149-thread-4) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1905618 INFO (jetty-launcher-5149-thread-4) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.0.0
[junit4] 2> 1905618 INFO (jetty-launcher-5149-thread-4) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1905618 INFO (jetty-launcher-5149-thread-4) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1905619 INFO (jetty-launcher-5149-thread-4) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-04-05T09:51:51.848Z
[junit4] 2> 1905626 INFO (zkConnectionManagerCallback-5156-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1905626 INFO (zkConnectionManagerCallback-5154-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1905626 INFO (jetty-launcher-5149-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1905628 INFO (zkConnectionManagerCallback-5158-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1905628 INFO (jetty-launcher-5149-thread-3) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1905628 INFO (jetty-launcher-5149-thread-3) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1905628 INFO (jetty-launcher-5149-thread-3) [ ] o.e.j.s.session Scavenging every 600000ms
[junit4] 2> 1905628 INFO (jetty-launcher-5149-thread-4) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1905628 INFO (jetty-launcher-5149-thread-3) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@5d5dd30e{/solr,null,AVAILABLE}
[junit4] 2> 1905629 INFO (jetty-launcher-5149-thread-3) [ ] o.e.j.s.AbstractConnector Started ServerConnector@1ea5fa04{HTTP/1.1,[http/1.1]}{127.0.0.1:58178}
[junit4] 2> 1905629 INFO (jetty-launcher-5149-thread-3) [ ] o.e.j.s.Server Started @1905668ms
[junit4] 2> 1905629 INFO (jetty-launcher-5149-thread-3) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=58178}
[junit4] 2> 1905629 ERROR (jetty-launcher-5149-thread-3) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1905629 INFO (jetty-launcher-5149-thread-3) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1905629 INFO (jetty-launcher-5149-thread-3) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.0.0
[junit4] 2> 1905629 INFO (jetty-launcher-5149-thread-3) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1905629 INFO (jetty-launcher-5149-thread-3) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1905629 INFO (jetty-launcher-5149-thread-2) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1905629 INFO (jetty-launcher-5149-thread-3) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-04-05T09:51:51.858Z
[junit4] 2> 1905636 INFO (jetty-launcher-5149-thread-4) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:36656/solr
[junit4] 2> 1905637 INFO (zkConnectionManagerCallback-5160-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1905652 INFO (jetty-launcher-5149-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:36656/solr
[junit4] 2> 1905653 INFO (jetty-launcher-5149-thread-3) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1905660 INFO (jetty-launcher-5149-thread-3) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:36656/solr
[junit4] 2> 1905661 INFO (zkConnectionManagerCallback-5164-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1905668 INFO (zkConnectionManagerCallback-5168-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1905669 INFO (jetty-launcher-5149-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:36656/solr
[junit4] 2> 1905674 INFO (zkConnectionManagerCallback-5172-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1905676 INFO (zkConnectionManagerCallback-5175-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1905677 INFO (zkConnectionManagerCallback-5178-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1905678 INFO (zkConnectionManagerCallback-5180-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1905678 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ] o.a.z.s.NIOServerCnxn Unable to read additional data from client sessionid 0x1056544548f0008, likely client has closed socket
[junit4] 2> 1905682 INFO (zkConnectionManagerCallback-5182-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1905688 INFO (zkConnectionManagerCallback-5184-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1905715 INFO (jetty-launcher-5149-thread-2) [n:127.0.0.1:33941_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 1905718 INFO (jetty-launcher-5149-thread-2) [n:127.0.0.1:33941_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:33941_solr
[junit4] 2> 1905719 INFO (jetty-launcher-5149-thread-2) [n:127.0.0.1:33941_solr ] o.a.s.c.Overseer Overseer (id=73576313072320524-127.0.0.1:33941_solr-n_0000000000) starting
[junit4] 2> 1905733 INFO (zkConnectionManagerCallback-5197-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1905738 INFO (jetty-launcher-5149-thread-2) [n:127.0.0.1:33941_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:36656/solr ready
[junit4] 2> 1905753 INFO (jetty-launcher-5149-thread-2) [n:127.0.0.1:33941_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:33941_solr
[junit4] 2> 1905770 INFO (zkCallback-5196-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1905773 INFO (zkCallback-5183-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1905793 INFO (jetty-launcher-5149-thread-3) [n:127.0.0.1:58178_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1905795 INFO (jetty-launcher-5149-thread-3) [n:127.0.0.1:58178_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 1905797 INFO (jetty-launcher-5149-thread-3) [n:127.0.0.1:58178_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 1905797 INFO (jetty-launcher-5149-thread-3) [n:127.0.0.1:58178_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:58178_solr
[junit4] 2> 1905802 INFO (zkCallback-5196-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 1905806 INFO (zkCallback-5183-thread-2) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 1905808 INFO (zkCallback-5181-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 1905815 INFO (jetty-launcher-5149-thread-4) [n:127.0.0.1:53600_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 1905816 INFO (jetty-launcher-5149-thread-1) [n:127.0.0.1:60358_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 1905817 INFO (jetty-launcher-5149-thread-4) [n:127.0.0.1:53600_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 1905818 INFO (jetty-launcher-5149-thread-1) [n:127.0.0.1:60358_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 1905818 INFO (jetty-launcher-5149-thread-4) [n:127.0.0.1:53600_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 1905819 INFO (jetty-launcher-5149-thread-4) [n:127.0.0.1:53600_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:53600_solr
[junit4] 2> 1905825 INFO (zkCallback-5196-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 1905826 INFO (zkCallback-5183-thread-2) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 1905829 INFO (zkCallback-5181-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 1905829 INFO (jetty-launcher-5149-thread-1) [n:127.0.0.1:60358_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 1905830 INFO (jetty-launcher-5149-thread-1) [n:127.0.0.1:60358_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:60358_solr
[junit4] 2> 1905830 INFO (zkCallback-5183-thread-2) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1905831 INFO (zkCallback-5181-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1905831 INFO (zkCallback-5196-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1905846 INFO (zkCallback-5174-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (4)
[junit4] 2> 1905851 INFO (zkCallback-5179-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (4)
[junit4] 2> 1905858 INFO (jetty-launcher-5149-thread-2) [n:127.0.0.1:33941_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_33941.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@58fde865
[junit4] 2> 1905873 INFO (jetty-launcher-5149-thread-2) [n:127.0.0.1:33941_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_33941.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@58fde865
[junit4] 2> 1905873 INFO (jetty-launcher-5149-thread-2) [n:127.0.0.1:33941_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_33941.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@58fde865
[junit4] 2> 1905874 INFO (jetty-launcher-5149-thread-2) [n:127.0.0.1:33941_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.cloud.TestPullReplicaErrorHandling_7F7146C1E56BCFA0-001/tempDir-001/node2/.
[junit4] 2> 1905879 INFO (jetty-launcher-5149-thread-3) [n:127.0.0.1:58178_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_58178.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@58fde865
[junit4] 2> 1905885 INFO (jetty-launcher-5149-thread-3) [n:127.0.0.1:58178_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_58178.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@58fde865
[junit4] 2> 1905885 INFO (jetty-launcher-5149-thread-3) [n:127.0.0.1:58178_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_58178.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@58fde865
[junit4] 2> 1905886 INFO (jetty-launcher-5149-thread-3) [n:127.0.0.1:58178_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.cloud.TestPullReplicaErrorHandling_7F7146C1E56BCFA0-001/tempDir-001/node3/.
[junit4] 2> 1905898 INFO (zkConnectionManagerCallback-5202-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1905899 INFO (jetty-launcher-5149-thread-3) [n:127.0.0.1:58178_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 1905900 INFO (jetty-launcher-5149-thread-3) [n:127.0.0.1:58178_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:36656/solr ready
[junit4] 2> 1905942 INFO (jetty-launcher-5149-thread-4) [n:127.0.0.1:53600_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_53600.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@58fde865
[junit4] 2> 1905948 INFO (jetty-launcher-5149-thread-4) [n:127.0.0.1:53600_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_53600.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@58fde865
[junit4] 2> 1905948 INFO (jetty-launcher-5149-thread-4) [n:127.0.0.1:53600_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_53600.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@58fde865
[junit4] 2> 1905949 INFO (jetty-launcher-5149-thread-4) [n:127.0.0.1:53600_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.cloud.TestPullReplicaErrorHandling_7F7146C1E56BCFA0-001/tempDir-001/node4/.
[junit4] 2> 1905952 INFO (zkConnectionManagerCallback-5207-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1905952 INFO (jetty-launcher-5149-thread-4) [n:127.0.0.1:53600_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 1905953 INFO (jetty-launcher-5149-thread-4) [n:127.0.0.1:53600_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:36656/solr ready
[junit4] 2> 1906252 INFO (jetty-launcher-5149-thread-1) [n:127.0.0.1:60358_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_60358.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@58fde865
[junit4] 2> 1906258 INFO (jetty-launcher-5149-thread-1) [n:127.0.0.1:60358_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_60358.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@58fde865
[junit4] 2> 1906258 INFO (jetty-launcher-5149-thread-1) [n:127.0.0.1:60358_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_60358.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@58fde865
[junit4] 2> 1906259 INFO (jetty-launcher-5149-thread-1) [n:127.0.0.1:60358_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.cloud.TestPullReplicaErrorHandling_7F7146C1E56BCFA0-001/tempDir-001/node1/.
[junit4] 2> 1906262 INFO (zkConnectionManagerCallback-5212-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1906263 INFO (jetty-launcher-5149-thread-1) [n:127.0.0.1:60358_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 1906263 INFO (jetty-launcher-5149-thread-1) [n:127.0.0.1:60358_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:36656/solr ready
[junit4] 2> 1906292 INFO (zkConnectionManagerCallback-5214-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1906295 INFO (zkConnectionManagerCallback-5219-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1906295 INFO (SUITE-TestPullReplicaErrorHandling-seed#[7F7146C1E56BCFA0]-worker) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 1906296 INFO (SUITE-TestPullReplicaErrorHandling-seed#[7F7146C1E56BCFA0]-worker) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:36656/solr ready
[junit4] 2> 1906301 INFO (SUITE-TestPullReplicaErrorHandling-seed#[7F7146C1E56BCFA0]-worker) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@2cdc0ec1{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 1906303 INFO (SUITE-TestPullReplicaErrorHandling-seed#[7F7146C1E56BCFA0]-worker) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1170030783
[junit4] 2> 1906303 INFO (SUITE-TestPullReplicaErrorHandling-seed#[7F7146C1E56BCFA0]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 1906303 INFO (SUITE-TestPullReplicaErrorHandling-seed#[7F7146C1E56BCFA0]-worker) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@ae955e9: rootName = solr_33941, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@2ec3cee5
[junit4] 2> 1906309 INFO (SUITE-TestPullReplicaErrorHandling-seed#[7F7146C1E56BCFA0]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 1906309 INFO (SUITE-TestPullReplicaErrorHandling-seed#[7F7146C1E56BCFA0]-worker) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@14d8fcc5: rootName = solr_33941, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@21e1c8e0
[junit4] 2> 1906312 INFO (SUITE-TestPullReplicaErrorHandling-seed#[7F7146C1E56BCFA0]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 1906312 INFO (SUITE-TestPullReplicaErrorHandling-seed#[7F7146C1E56BCFA0]-worker) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@3f80adf8: rootName = solr_33941, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@75e5954d
[junit4] 2> 1906313 INFO (SUITE-TestPullReplicaErrorHandling-seed#[7F7146C1E56BCFA0]-worker) [ ] o.a.s.c.ZkController Remove node as live in ZooKeeper:/live_nodes/127.0.0.1:33941_solr
[junit4] 2> 1906314 INFO (zkCallback-5181-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 1906314 INFO (zkCallback-5183-thread-2) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 1906314 INFO (zkCallback-5196-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 1906314 INFO (SUITE-TestPullReplicaErrorHandling-seed#[7F7146C1E56BCFA0]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster, tag=null
[junit4] 2> 1906314 INFO (zkCallback-5179-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 1906315 INFO (SUITE-TestPullReplicaErrorHandling-seed#[7F7146C1E56BCFA0]-worker) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 1906317 INFO (zkCallback-5211-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 1906317 INFO (zkCallback-5218-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 1906317 INFO (zkCallback-5174-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 1906317 INFO (SUITE-TestPullReplicaErrorHandling-seed#[7F7146C1E56BCFA0]-worker) [ ] o.a.s.c.Overseer Overseer (id=73576313072320524-127.0.0.1:33941_solr-n_0000000000) closing
[junit4] 2> 1906317 INFO (zkCallback-5201-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 1906317 INFO (OverseerStateUpdate-73576313072320524-127.0.0.1:33941_solr-n_0000000000) [ ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:33941_solr
[junit4] 2> 1906319 WARN (OverseerAutoScalingTriggerThread-73576313072320524-127.0.0.1:33941_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we are closed, exiting.
[junit4] 2> 1906322 INFO (zkCallback-5206-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 1906327 INFO (zkCallback-5181-thread-1) [ ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:58178_solr
[junit4] 2> 1906327 INFO (SUITE-TestPullReplicaErrorHandling-seed#[7F7146C1E56BCFA0]-worker) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@1db7f861{/solr,null,UNAVAILABLE}
[junit4] 2> 1906328 INFO (SUITE-TestPullReplicaErrorHandling-seed#[7F7146C1E56BCFA0]-worker) [ ] o.e.j.s.session Stopped scavenging
[junit4] 2> 1906329 INFO (zkCallback-5181-thread-1) [ ] o.a.s.c.Overseer Overseer (id=73576313072320523-127.0.0.1:58178_solr-n_0000000001) starting
[junit4] 2> 1906329 INFO (SUITE-TestPullReplicaErrorHandling-seed#[7F7146C1E56BCFA0]-worker) [ ] o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: 2017-11-21T23:27:37+02:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8
[junit4] 2> 1906330 INFO (SUITE-TestPullReplicaErrorHandling-seed#[7F7146C1E56BCFA0]-worker) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1906330 INFO (SUITE-TestPullReplicaErrorHandling-seed#[7F7146C1E56BCFA0]-worker) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1906330 INFO (SUITE-TestPullReplicaErrorHandling-seed#[7F7146C1E56BCFA0]-worker) [ ] o.e.j.s.session Scavenging every 600000ms
[junit4] 2> 1906330 INFO (SUITE-TestPullReplicaErrorHandling-seed#[7F7146C1E56BCFA0]-worker) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@7fb3f738{/solr,null,AVAILABLE}
[junit4] 2> 1906337 INFO (SUITE-TestPullReplicaErrorHandling-seed#[7F7146C1E56BCFA0]-worker) [ ] o.e.j.s.AbstractConnector Started ServerConnector@58c1b11d{HTTP/1.1,[http/1.1]}{127.0.0.1:38075}
[junit4] 2> 1906337 INFO (SUITE-TestPullReplicaErrorHandling-seed#[7F7146C1E56BCFA0]-worker) [ ] o.e.j.s.Server Started @1906377ms
[junit4] 2> 1906337 INFO (SUITE-TestPullReplicaErrorHandling-seed#[7F7146C1E56BCFA0]-worker) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=56830}
[junit4] 2> 1906337 ERROR (SUITE-TestPullReplicaErrorHandling-seed#[7F7146C1E56BCFA0]-worker) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1906338 INFO (SUITE-TestPullReplicaErrorHandling-seed#[7F7146C1E56BCFA0]-worker) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1906338 INFO (SUITE-TestPullReplicaErrorHandling-seed#[7F7146C1E56BCFA0]-worker) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.0.0
[junit4] 2> 1906338 INFO (SUITE-TestPullReplicaErrorHandling-seed#[7F7146C1E56BCFA0]-worker) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1906338 INFO (SUITE-TestPullReplicaErrorHandling-seed#[7F7146C1E56BCFA0]-worker) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1906338 INFO (SUITE-TestPullReplicaErrorHandling-seed#[7F7146C1E56BCFA0]-worker) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-04-05T09:51:52.567Z
[junit4] 2> 1906339 INFO (zkConnectionManagerCallback-5221-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1906339 INFO (SUITE-TestPullReplicaErrorHandling-seed#[7F7146C1E56BCFA0]-worker) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1906347 INFO (SUITE-TestPullReplicaErrorHandling-seed#[7F7146C1E56BCFA0]-worker) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:36656/solr
[junit4] 2> 1906348 INFO (zkConnectionManagerCallback-5225-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1906349 INFO (zkConnectionManagerCallback-5227-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1906354 INFO (SUITE-TestPullReplicaErrorHandling-seed#[7F7146C1E56BCFA0]-worker) [n:127.0.0.1:56830_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3)
[junit4] 2> 1906355 INFO (SUITE-TestPullReplicaErrorHandling-seed#[7F7146C1E56BCFA0]-worker) [n:127.0.0.1:56830_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 1906356 INFO (SUITE-TestPullReplicaErrorHandling-seed#[7F7146C1E56BCFA0]-worker) [n:127.0.0.1:56830_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 1906356 INFO (SUITE-TestPullReplicaErrorHandling-seed#[7F7146C1E56BCFA0]-worker) [n:127.0.0.1:56830_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:56830_solr
[junit4] 2> 1906357 INFO (zkCallback-5174-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1906357 INFO (zkCallback-5181-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1906361 INFO (zkCallback-5211-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1906361 INFO (zkCallback-5179-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1906363 INFO (zkCallback-5226-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1906363 INFO (zkCallback-5201-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1906363 INFO (zkCallback-5206-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1906364 INFO (zkCallback-5218-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1906454 INFO (SUITE-TestPullReplicaErrorHandling-seed#[7F7146C1E56BCFA0]-worker) [n:127.0.0.1:56830_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_56830.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@58fde865
[junit4] 2> 1906461 INFO (SUITE-TestPullReplicaErrorHandling-seed#[7F7146C1E56BCFA0]-worker) [n:127.0.0.1:56830_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_56830.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@58fde865
[junit4] 2> 1906461 INFO (SUITE-TestPullReplicaErrorHandling-seed#[7F7146C1E56BCFA0]-worker) [n:127.0.0.1:56830_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_56830.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@58fde865
[junit4] 2> 1906462 INFO (SUITE-TestPullReplicaErrorHandling-seed#[7F7146C1E56BCFA0]-worker) [n:127.0.0.1:56830_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.cloud.TestPullReplicaErrorHandling_7F7146C1E56BCFA0-001/tempDir-001/node2/.
[junit4] 2> 1906465 INFO (zkConnectionManagerCallback-5234-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1906466 INFO (SUITE-TestPullReplicaErrorHandling-seed#[7F7146C1E56BCFA0]-worker) [n:127.0.0.1:56830_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 1906466 INFO (SUITE-TestPullReplicaErrorHandling-seed#[7F7146C1E56BCFA0]-worker) [n:127.0.0.1:56830_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:36656/solr ready
[junit4] 2> 1906500 INFO (SUITE-TestPullReplicaErrorHandling-seed#[7F7146C1E56BCFA0]-worker) [ ] o.a.s.c.TestPullReplicaErrorHandling Adding proxy for URL: http://127.0.0.1:38075/solr. Proxy: http://127.0.0.1:56830/solr
[junit4] 2> 1906501 INFO (SUITE-TestPullReplicaErrorHandling-seed#[7F7146C1E56BCFA0]-worker) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@1ea5fa04{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 1906503 INFO (SUITE-TestPullReplicaErrorHandling-seed#[7F7146C1E56BCFA0]-worker) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1828504044
[junit4] 2> 1906503 INFO (SUITE-TestPullReplicaErrorHandling-seed#[7F7146C1E56BCFA0]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 1906503 INFO (SUITE-TestPullReplicaErrorHandling-seed#[7F7146C1E56BCFA0]-worker) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@54445d14: rootName = solr_58178, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@8cc52ba
[junit4] 2> 1906509 INFO (SUITE-TestPullReplicaErrorHandling-seed#[7F7146C1E56BCFA0]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 1906509 INFO (SUITE-TestPullReplicaErrorHandling-seed#[7F7146C1E56BCFA0]-worker) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@6b46aa22: rootName = solr_58178, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@21e1c8e0
[junit4] 2> 1906512 INFO (SUITE-TestPullReplicaErrorHandling-seed#[7F7146C1E56BCFA0]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 1906512 INFO (SUITE-TestPullReplicaErrorHandling-seed#[7F7146C1E56BCFA0]-worker) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@b2476a6: rootName = solr_58178, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@75e5954d
[junit4] 2> 1906513 INFO (SUITE-TestPullReplicaErrorHandling-seed#[7F7146C1E56BCFA0]-worker) [ ] o.a.s.c.ZkController Remove node as live in ZooKeeper:/live_nodes/127.0.0.1:58178_solr
[junit4] 2> 1906514 INFO (zkCallback-5179-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 1906514 INFO (zkCallback-5201-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 1906515 INFO (zkCallback-5206-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 1906515 INFO (zkCallback-5174-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 1906515 INFO (zkCallback-5218-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 1906515 INFO (SUITE-TestPullReplicaErrorHandling-seed#[7F7146C1E56BCFA0]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster, tag=null
[junit4] 2> 1906515 INFO (zkCallback-5226-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 1906515 INFO (zkCallback-5211-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 1906515 INFO (SUITE-TestPullReplicaErrorHandling-seed#[7F7146C1E56BCFA0]-worker) [ ] o.a.s.c.Overseer Overseer (id=73576313072320523-127.0.0.1:58178_solr-n_0000000001) closing
[junit4] 2> 1906517 INFO (OverseerStateUpdate-73576313072320523-127.0.0.1:58178_solr-n_0000000001) [ ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:58178_solr
[junit4] 2> 1906517 INFO (zkCallback-5233-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 1906517 WARN (OverseerAutoScalingTriggerThread-73576313072320523-127.0.0.1:58178_solr-n_0000000001) [ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we are closed, exiting.
[junit4] 2> 1906518 INFO (zkCallback-5181-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 1906525 INFO (SUITE-TestPullReplicaErrorHandling-seed#[7F7146C1E56BCFA0]-worker) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@5d5dd30e{/solr,null,UNAVAILABLE}
[junit4] 2> 1906525 INFO (zkCallback-5174-thread-1) [ ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:53600_solr
[junit4] 2> 1906526 INFO (SUITE-TestPullReplicaErrorHandling-seed#[7F7146C1E56BCFA0]-worker) [ ] o.e.j.s.session Stopped scavenging
[junit4] 2> 1906527 INFO (SUITE-TestPullReplicaErrorHandling-seed#[7F7146C1E56BCFA0]-worker) [ ] o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: 2017-11-21T23:27:37+02:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8
[junit4] 2> 1906527 INFO (SUITE-TestPullReplicaErrorHandling-seed#[7F7146C1E56BCFA0]-worker) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1906527 INFO (SUITE-TestPullReplicaErrorHandling-seed#[7F7146C1E56BCFA0]-worker) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1906527 INFO (SUITE-TestPullReplicaErrorHandling-seed#[7F7146C1E56BCFA0]-worker) [ ] o.e.j.s.session Scavenging every 660000ms
[junit4] 2> 1906528 INFO (zkCallback-5174-thread-1) [ ] o.a.s.c.Overseer Overseer (id=73576313072320521-127.0.0.1:53600_solr-n_0000000002) starting
[junit4] 2> 1906528 INFO (SUITE-TestPullReplicaErrorHandling-seed#[7F7146C1E56BCFA0]-worker) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@17769206{/solr,null,AVAILABLE}
[junit4] 2> 1906528 INFO (SUITE-TestPullReplicaErrorHandling-seed#[7F7146C1E56BCFA0]-worker) [ ] o.e.j.s.AbstractConnector Started ServerConnector@25424915{HTTP/1.1,[http/1.1]}{127.0.0.1:59323}
[junit4] 2> 1906528 INFO (SUITE-TestPullReplicaErrorHandling-seed#[7F7146C1E56BCFA0]-worker) [ ] o.e.j.s.Server Started @1906568ms
[junit4] 2> 1906528 INFO (SUITE-TestPullReplicaErrorHandling-seed#[7F7146C1E56BCFA0]-worker) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=57212}
[junit4] 2> 1906528 ERROR (SUITE-TestPullReplicaErrorHandling-seed#[7F7146C1E56BCFA0]-worker) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1906530 INFO (SUITE-TestPullReplicaErrorHandling-seed#[7F7146C1E56BCFA0]-worker) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1906530 INFO (SUITE-TestPullReplicaErrorHandling-seed#[7F7146C1E56BCFA0]-worker) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.0.0
[junit4] 2> 1906530 INFO (SUITE-TestPullReplicaErrorHandling-seed#[7F7146C1E56BCFA0]-worker) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1906530 INFO (SUITE-TestPullReplicaErrorHandling-seed#[7F7146C1E56BCFA0]-worker) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1906530 INFO (SUITE-TestPullReplicaErrorHandling-seed#[7F7146C1E56BCFA0]-worker) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-04-05T09:51:52.759Z
[junit4] 2> 1906531 INFO (zkConnectionManagerCallback-5236-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1906531 INFO (SUITE-TestPullReplicaErrorHandling-seed#[7F7146C1E56BCFA0]-worker) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1906539 INFO (SUITE-TestPullReplicaErrorHandling-seed#[7F7146C1E56BCFA0]-worker) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:36656/solr
[junit4] 2> 1906540 INFO (zkConnectionManagerCallback-5240-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1906541 INFO (zkConnectionManagerCallback-5242-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1906546 INFO (SUITE-TestPullReplicaErrorHandling-seed#[7F7146C1E56BCFA0]-worker) [n:127.0.0.1:57212_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3)
[junit4] 2> 1906548 INFO (SUITE-TestPullReplicaErrorHandling-seed#[7F7146C1E56BCFA0]-worker) [n:127.0.0.1:57212_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 1906549 INFO (SUITE-TestPullReplicaErrorHandling-seed#[7F7146C1E56BCFA0]-worker) [n:127.0.0.1:57212_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 1906549 INFO (SUITE-TestPullReplicaErrorHandling-seed#[7F7146C1E56BCFA0]-worker) [n:127.0.0.1:57212_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:57212_solr
[junit4] 2> 1906550 INFO (zkCallback-5226-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1906550 INFO (zkCallback-5218-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1906550 INFO (zkCallback-5174-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1906550 INFO (zkCallback-5179-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1906550 INFO (zkCallback-5233-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1906550 INFO (zkCallback-5211-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1906550 INFO (zkCallback-5206-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1906552 INFO (zkCallback-5241-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1906693 INFO (SUITE-TestPullReplicaErrorHandling-seed#[7F7146C1E56BCFA0]-worker) [n:127.0.0.1:57212_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_57212.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@58fde865
[junit4] 2> 1906700 INFO (SUITE-TestPullReplicaErrorHandling-seed#[7F7146C1E56BCFA0]-worker) [n:127.0.0.1:57212_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_57212.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@58fde865
[junit4] 2> 1906701 INFO (SUITE-TestPullReplicaErrorHandling-seed#[7F7146C1E56BCFA0]-worker) [n:127.0.0.1:57212_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_57212.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@58fde865
[junit4] 2> 1906702 INFO (SUITE-TestPullReplicaErrorHandling-seed#[7F7146C1E56BCFA0]-worker) [n:127.0.0.1:57212_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.cloud.TestPullReplicaErrorHandling_7F7146C1E56BCFA0-001/tempDir-001/node3/.
[junit4] 2> 1906704 INFO (zkConnectionManagerCallback-5249-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1906705 INFO (SUITE-TestPullReplicaErrorHandling-seed#[7F7146C1E56BCFA0]-worker) [n:127.0.0.1:57212_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 1906706 INFO (SUITE-TestPullReplicaErrorHandling-seed#[7F7146C1E56BCFA0]-worker) [n:127.0.0.1:57212_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:36656/solr ready
[junit4] 2> 1906749 INFO (SUITE-TestPullReplicaErrorHandling-seed#[7F7146C1E56BCFA0]-worker) [ ] o.a.s.c.TestPullReplicaErrorHandling Adding proxy for URL: http://127.0.0.1:59323/solr. Proxy: http://127.0.0.1:57212/solr
[junit4] 2> 1906750 INFO (SUITE-TestPullReplicaErrorHandling-seed#[7F7146C1E56BCFA0]-worker) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@49af18d{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 1906752 INFO (SUITE-TestPullReplicaErrorHandling-seed#[7F7146C1E56BCFA0]-worker) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1815786523
[junit4] 2> 1906752 INFO (SUITE-TestPullReplicaErrorHandling-seed#[7F7146C1E56BCFA0]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 1906752 INFO (SUITE-TestPullReplicaErrorHandling-seed#[7F7146C1E56BCFA0]-worker) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@68787b4c: rootName = solr_53600, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@761c5ce1
[junit4] 2> 1906758 INFO (SUITE-TestPullReplicaErrorHandling-seed#[7F7146C1E56BCFA0]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 1906758 INFO (SUITE-TestPullReplicaErrorHandling-seed#[7F7146C1E56BCFA0]-worker) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@20afcba2: rootName = solr_53600, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@21e1c8e0
[junit4] 2> 1906761 INFO (SUITE-TestPullReplicaErrorHandling-seed#[7F7146C1E56BCFA0]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 1906761 INFO (SUITE-TestPullReplicaErrorHandling-seed#[7F7146C1E56BCFA0]-worker) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@7a305902: rootName = solr_53600, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@75e5954d
[junit4] 2> 1906763 INFO (SUITE-TestPullReplicaErrorHandling-seed#[7F7146C1E56BCFA0]-worker) [ ] o.a.s.c.ZkController Remove node as live in ZooKeeper:/live_nodes/127.0.0.1:53600_solr
[junit4] 2> 1906764 INFO (SUITE-TestPullReplicaErrorHandling-seed#[7F7146C1E56BCFA0]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster, tag=null
[junit4] 2> 1906764 INFO (zkCallback-5211-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 1906764 INFO (zkCallback-5233-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 1906764 INFO (zkCallback-5179-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 1906764 INFO (zkCallback-5226-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 1906764 INFO (zkCallback-5218-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 1906764 INFO (zkCallback-5241-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 1906764 INFO (zkCallback-5206-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 1906764 INFO (zkCallback-5174-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 1906764 INFO (SUITE-TestPullReplicaErrorHandling-seed#[7F7146C1E56BCFA0]-worker) [ ] o.a.s.c.Overseer Overseer (id=73576313072320521-127.0.0.1:53600_solr-n_0000000002) closing
[junit4] 2> 1906766 WARN (OverseerAutoScalingTriggerThread-73576313072320521-127.0.0.1:53600_solr-n_0000000002) [ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we are closed, exiting.
[junit4] 2> 1906766 INFO (OverseerStateUpdate-73576313072320521-127.0.0.1:53600_solr-n_0000000002) [ ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:53600_solr
[junit4] 2> 1906772 INFO (zkCallback-5248-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 1906774 INFO (zkCallback-5179-thread-1) [ ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:60358_solr
[junit4] 2> 1906774 INFO (SUITE-TestPullReplicaErrorHandling-seed#[7F7146C1E56BCFA0]-worker) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@2352cf3e{/solr,null,UNAVAILABLE}
[junit4] 2> 1906776 INFO (SUITE-TestPullReplicaErrorHandling-seed#[7F7146C1E56BCFA0]-worker) [ ] o.e.j.s.session Stopped scavenging
[junit4] 2> 1906776 INFO (zkCallback-5179-thread-1) [ ] o.a.s.c.Overseer Overseer (id=73576313072320522-127.0.0.1:60358_solr-n_0000000003) starting
[junit4] 2> 1906776 INFO (SUITE-TestPullReplicaErrorHandling-seed#[7F7146C1E56BCFA0]-worker) [ ] o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: 2017-11-21T23:27:37+02:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8
[junit4] 2> 1906777 INFO (SUITE-TestPullReplicaErrorHandling-seed#[7F7146C1E56BCFA0]-worker) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1906777 INFO (SUITE-TestPullReplicaErrorHandling-seed#[7F7146C1E56BCFA0]-worker) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1906777 INFO (SUITE-TestPullReplicaErrorHandling-seed#[7F7146C1E56BCFA0]-worker) [ ] o.e.j.s.session Scavenging every 660000ms
[junit4] 2> 1906777 INFO (SUITE-TestPullReplicaErrorHandling-seed#[7F7146C1E56BCFA0]-worker) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@6b6f3d17{/solr,null,AVAILABLE}
[junit4] 2> 1906778 INFO (SUITE-TestPullReplicaErrorHandling-seed#[7F7146C1E56BCFA0]-worker) [ ] o.e.j.s.AbstractConnector Started ServerConnector@6a42f0a4{HTTP/1.1,[http/1.1]}{127.0.0.1:55356}
[junit4] 2> 1906778 INFO (SUITE-TestPullReplicaErrorHandling-seed#[7F7146C1E56BCFA0]-worker) [ ] o.e.j.s.Server Started @1906817ms
[junit4] 2> 1906778 INFO (SUITE-TestPullReplicaErrorHandling-seed#[7F7146C1E56BCFA0]-worker) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=50777}
[junit4] 2> 1906778 ERROR (SUITE-TestPullReplicaErrorHandling-seed#[7F7146C1E56BCFA0]-worker) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1906779 INFO (SUITE-TestPullReplicaErrorHandling-seed#[7F7146C1E56BCFA0]-worker) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1906779 INFO (SUITE-TestPullReplicaErrorHandling-seed#[7F7146C1E56BCFA0]-worker) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.0.0
[junit4] 2> 1906779 INFO (SUITE-TestPullReplicaErrorHandling-seed#[7F7146C1E56BCFA0]-worker) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1906779 INFO (SUITE-TestPullReplicaErrorHandling-seed#[7F7146C1E56BCFA0]-worker) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1906779 INFO (SUITE-TestPullReplicaErrorHandling-seed#[7F7146C1E56BCFA0]-worker) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-04-05T09:51:53.008Z
[junit4] 2> 1906781 INFO (zkConnectionManagerCallback-5251-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1906781 INFO (SUITE-TestPullReplicaErrorHandling-seed#[7F7146C1E56BCFA0]-worker) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1906789 INFO (SUITE-TestPullReplicaErrorHandling-seed#[7F7146C1E56BCFA0]-worker) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:36656/solr
[junit4] 2> 1906790 INFO (zkConnectionManagerCallback-5255-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1906791 INFO (zkConnectionManagerCallback-5257-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1906796 INFO (SUITE-TestPullReplicaErrorHandling-seed#[7F7146C1E56BCFA0]-worker) [n:127.0.0.1:50777_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3)
[junit4] 2> 1906797 INFO (SUITE-TestPullReplicaErrorHandling-seed#[7F7146C1E56BCFA0]-worker) [n:127.0.0.1:50777_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 1906798 INFO (SUITE-TestPullReplicaErrorHandling-seed#[7F7146C1E56BCFA0]-worker) [n:127.0.0.1:50777_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 1906798 INFO (SUITE-TestPullReplicaErrorHandling-seed#[7F7146C1E56BCFA0]-worker) [n:127.0.0.1:50777_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:50777_solr
[junit4] 2> 1906805 INFO (zkCallback-5248-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1906805 INFO (zkCallback-5218-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1906805 INFO (zkCallback-5241-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1906805 INFO (zkCallback-5211-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1906805 INFO (zkCallback-5226-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1906805 INFO (zkCallback-5233-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1906805 INFO (zkCallback-5179-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1906808 INFO (zkCallback-5256-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1907127 INFO (SUITE-TestPullReplicaErrorHandling-seed#[7F7146C1E56BCFA0]-worker) [n:127.0.0.1:50777_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_50777.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@58fde865
[junit4] 2> 1907134 INFO (SUITE-TestPullReplicaErrorHandling-seed#[7F7146C1E56BCFA0]-worker) [n:127.0.0.1:50777_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_50777.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@58fde865
[junit4] 2> 1907135 INFO (SUITE-TestPullReplicaErrorHandling-seed#[7F7146C1E56BCFA0]-worker) [n:127.0.0.1:50777_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_50777.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@58fde865
[junit4] 2> 1907136 INFO (SUITE-TestPullReplicaErrorHandling-seed#[7F7146C1E56BCFA0]-worker) [n:127.0.0.1:50777_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.cloud.TestPullReplicaErrorHandling_7F7146C1E56BCFA0-001/tempDir-001/node4/.
[junit4] 2> 1907138 INFO (zkConnectionManagerCallback-5264-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1907139 INFO (SUITE-TestPullReplicaErrorHandling-seed#[7F7146C1E56BCFA0]-worker) [n:127.0.0.1:50777_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 1907140 INFO (SUITE-TestPullReplicaErrorHandling-seed#[7F7146C1E56BCFA0]-worker) [n:127.0.0.1:50777_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:36656/solr ready
[junit4] 2> 1907172 INFO (SUITE-TestPullReplicaErrorHandling-seed#[7F7146C1E56BCFA0]-worker) [ ] o.a.s.c.TestPullReplicaErrorHandling Adding proxy for URL: http://127.0.0.1:55356/solr. Proxy: http://127.0.0.1:50777/solr
[junit4] 2> 1907173 INFO (SUITE-TestPullReplicaErrorHandling-seed#[7F7146C1E56BCFA0]-worker) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@55d8b866{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 1907176 INFO (SUITE-TestPullReplicaErrorHandling-seed#[7F7146C1E56BCFA0]-worker) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=342716718
[junit4] 2> 1907176 INFO (SUITE-TestPullReplicaErrorHandling-seed#[7F7146C1E56BCFA0]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 1907176 INFO (SUITE-TestPullReplicaErrorHandling-seed#[7F7146C1E56BCFA0]-worker) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@122ffd75: rootName = solr_60358, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@6d73edbf
[junit4] 2> 1907183 INFO (SUITE-TestPullReplicaErrorHandling-seed#[7F7146C1E56BCFA0]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 1907183 INFO (SUITE-TestPullReplicaErrorHandling-seed#[7F7146C1E56BCFA0]-worker) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@2686244: rootName = solr_60358, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@21e1c8e0
[junit4] 2> 1907186 INFO (SUITE-TestPullReplicaErrorHandling-seed#[7F7146C1E56BCFA0]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 1907186 INFO (SUITE-TestPullReplicaErrorHandling-seed#[7F7146C1E56BCFA0]-worker) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@4a90b6b: rootName = solr_60358, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@75e5954d
[junit4] 2> 1907188 INFO (SUITE-TestPullReplicaErrorHandling-seed#[7F7146C1E56BCFA0]-worker) [ ] o.a.s.c.ZkController Remove node as live in ZooKeeper:/live_nodes/127.0.0.1:60358_solr
[junit4] 2> 1907188 INFO (SUITE-TestPullReplicaErrorHandling-seed#[7F7146C1E56BCFA0]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster, tag=null
[junit4] 2> 1907188 INFO (zkCallback-5248-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 1907188 INFO (zkCallback-5218-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 1907189 INFO (zkCallback-5179-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 1907189 INFO (zkCallback-5256-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 1907189 INFO (SUITE-TestPullReplicaErrorHandling-seed#[7F7146C1E56BCFA0]-worker) [ ] o.a.s.c.Overseer Overseer (id=73576313072320522-127.0.0.1:60358_solr-n_0000000003) closing
[junit4] 2> 1907189 INFO (zkCallback-5226-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 1907189 INFO (zkCallback-5241-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 1907189 INFO (OverseerStateUpdate-73576313072320522-127.0.0.1:60358_solr-n_0000000003) [ ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:60358_solr
[junit4] 2> 1907189 INFO (zkCallback-5211-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 1907192 WARN (OverseerAutoScalingTriggerThread-73576313072320522-127.0.0.1:60358_solr-n_0000000003) [ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we are closed, exiting.
[junit4] 2> 1907192 INFO (zkCallback-5233-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 1907194 INFO (zkCallback-5263-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 1907199 INFO (SUITE-TestPullReplicaErrorHandling-seed#[7F7146C1E56BCFA0]-worker) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@73d1ff45{/solr,null,UNAVAILABLE}
[junit4] 2> 1907199 INFO (zkCallback-5226-thread-1) [ ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:56830_solr
[junit4] 2> 1907200 INFO (SUITE-TestPullReplicaErrorHandling-seed#[7F7146C1E56BCFA0]-worker) [ ] o.e.j.s.session Stopped scavenging
[junit4] 2> 1907200 INFO (SUITE-TestPullReplicaErrorHandling-seed#[7F7146C1E56BCFA0]-worker) [ ] o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: 2017-11-21T23:27:37+02:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8
[junit4] 2> 1907201 INFO (zkCallback-5226-thread-1) [ ] o.a.s.c.Overseer Overseer (id=73576313072320533-127.0.0.1:56830_solr-n_0000000004) starting
[junit4] 2> 1907201 INFO (SUITE-TestPullReplicaErrorHandling-seed#[7F7146C1E56BCFA0]-worker) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1907201 INFO (SUITE-TestPullReplicaErrorHandling-seed#[7F7146C1E56BCFA0]-worker) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1907201 INFO (SUITE-TestPullReplicaErrorHandling-seed#[7F7146C1E56BCFA0]-worker) [ ] o.e.j.s.session Scavenging every 660000ms
[junit4] 2> 1907201 INFO (SUITE-TestPullReplicaErrorHandling-seed#[7F7146C1E56BCFA0]-worker) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@3c2838ee{/solr,null,AVAILABLE}
[junit4] 2> 1907202 INFO (SUITE-TestPullReplicaErrorHandling-seed#[7F7146C1E56BCFA0]-worker) [ ] o.e.j.s.AbstractConnector Started ServerConnector@13903ad{HTTP/1.1,[http/1.1]}{127.0.0.1:46890}
[junit4] 2> 1907202 INFO (SUITE-TestPullReplicaErrorHandling-seed#[7F7146C1E56BCFA0]-worker) [ ] o.e.j.s.Server Started @1907242ms
[junit4] 2> 1907202 INFO (SUITE-TestPullReplicaErrorHandling-seed#[7F7146C1E56BCFA0]-worker) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=47694}
[junit4] 2> 1907202 ERROR (SUITE-TestPullReplicaErrorHandling-seed#[7F7146C1E56BCFA0]-worker) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1907204 INFO (SUITE-TestPullReplicaErrorHandling-seed#[7F7146C1E56BCFA0]-worker) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1907204 INFO (SUITE-TestPullReplicaErrorHandling-seed#[7F7146C1E56BCFA0]-worker) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.0.0
[junit4] 2> 1907204 INFO (SUITE-TestPullReplicaErrorHandling-seed#[7F7146C1E56BCFA0]-worker) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1907204 INFO (SUITE-TestPullReplicaErrorHandling-seed#[7F7146C1E56BCFA0]-worker) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1907204 INFO (SUITE-TestPullReplicaErrorHandling-seed#[7F7146C1E56BCFA0]-worker) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-04-05T09:51:53.433Z
[junit4] 2> 1907205 INFO (zkConnectionManagerCallback-5266-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1907205 INFO (SUITE-TestPullReplicaErrorHandling-seed#[7F7146C1E56BCFA0]-worker) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1907214 INFO (SUITE-TestPullReplicaErrorHandling-seed#[7F7146C1E56BCFA0]-worker) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:36656/solr
[junit4] 2> 1907220 INFO (zkConnectionManagerCallback-5270-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1907221 INFO (zkConnectionManagerCallback-5272-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1907225 INFO (SUITE-TestPullReplicaErrorHandling-seed#[7F7146C1E56BCFA0]-worker) [n:127.0.0.1:47694_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3)
[junit4] 2> 1907227 INFO (SUITE-TestPullReplicaErrorHandling-seed#[7F7146C1E56BCFA0]-worker) [n:127.0.0.1:47694_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 1907228 INFO (SUITE-TestPullReplicaErrorHandling-seed#[7F7146C1E56BCFA0]-worker) [n:127.0.0.1:47694_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 1907228 INFO (SUITE-TestPullReplicaErrorHandling-seed#[7F7146C1E56BCFA0]-worker) [n:127.0.0.1:47694_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:47694_solr
[junit4] 2> 1907229 INFO (zkCallback-5256-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1907229 INFO (zkCallback-5241-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1907229 INFO (zkCallback-5248-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1907229 INFO (zkCallback-5233-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1907229 INFO (zkCallback-5218-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1907229 INFO (zkCallback-5263-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1907233 INFO (zkCallback-5226-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1907239 INFO (zkCallback-5271-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1907484 INFO (SUITE-TestPullReplicaErrorHandling-seed#[7F7146C1E56BCFA0]-worker) [n:127.0.0.1:47694_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_47694.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@58fde865
[junit4] 2> 1907491 INFO (SUITE-TestPullReplicaErrorHandling-seed#[7F7146C1E56BCFA0]-worker) [n:127.0.0.1:47694_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_47694.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@58fde865
[junit4] 2> 1907491 INFO (SUITE-TestPullReplicaErrorHandling-seed#[7F7146C1E56BCFA0]-worker) [n:127.0.0.1:47694_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_47694.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@58fde865
[junit4] 2> 1907492 INFO (SUITE-TestPullReplicaErrorHandling-seed#[7F7146C1E56BCFA0]-worker) [n:127.0.0.1:47694_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.cloud.TestPullReplicaErrorHandling_7F7146C1E56BCFA0-001/tempDir-001/node1/.
[junit4] 2> 1907498 INFO (zkConnectionManagerCallback-5279-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1907499 INFO (SUITE-TestPullReplicaErrorHandling-seed#[7F7146C1E56BCFA0]-worker) [n:127.0.0.1:47694_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 1907500 INFO (SUITE-TestPullReplicaErrorHandling-seed#[7F7146C1E56BCFA0]-worker) [n:127.0.0.1:47694_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:36656/solr ready
[junit4] 2> 1907533 INFO (SUITE-TestPullReplicaErrorHandling-seed#[7F7146C1E56BCFA0]-worker) [ ] o.a.s.c.TestPullReplicaErrorHandling Adding proxy for URL: http://127.0.0.1:46890/solr. Proxy: http://127.0.0.1:47694/solr
[junit4] 2> 1907534 INFO (SocketProxy-Acceptor-47694) [ ] o.a.s.c.SocketProxy accepted Socket[addr=/127.0.0.1,port=54537,localport=47694], receiveBufferSize:531000
[junit4] 2> 1907545 INFO (SocketProxy-Acceptor-47694) [ ] o.a.s.c.SocketProxy proxy connection Socket[addr=/127.0.0.1,port=46890,localport=47936], receiveBufferSize=530904
[junit4] 2> 1907549 INFO (qtp134497995-22039) [n:127.0.0.1:47694_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :clusterprop with params val=false&name=legacyCloud&action=CLUSTERPROP&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 1907550 INFO (qtp134497995-22039) [n:127.0.0.1:47694_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={val=false&name=legacyCloud&action=CLUSTERPROP&wt=javabin&version=2} status=0 QTime=1
[junit4] 2> 1907669 INFO (TEST-TestPullReplicaErrorHandling.testPullReplicaDisconnectsFromZooKeeper-seed#[7F7146C1E56BCFA0]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testPullReplicaDisconnectsFromZooKeeper
[junit4] 2> 1907671 INFO (qtp134497995-22041) [n:127.0.0.1:47694_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params pullReplicas=1&replicationFactor=1&collection.configName=conf&maxShardsPerNode=1&name=pull_replica_error_handling_test_pull_replica_disconnects_from_zoo_keeper&nrtReplicas=1&action=CREATE&numShards=1&tlogReplicas=0&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 1907674 INFO (OverseerThreadFactory-8036-thread-1) [ ] o.a.s.c.a.c.CreateCollectionCmd Create collection pull_replica_error_handling_test_pull_replica_disconnects_from_zoo_keeper
[junit4] 2> 1907781 INFO (OverseerStateUpdate-73576313072320533-127.0.0.1:56830_solr-n_0000000004) [ ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"pull_replica_error_handling_test_pull_replica_disconnects_from_zoo_keeper",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"pull_replica_error_handling_test_pull_replica_disconnects_from_zoo_keeper_shard1_replica_n1",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:56830/solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 1907783 INFO (OverseerStateUpdate-73576313072320533-127.0.0.1:56830_solr-n_0000000004) [ ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"pull_replica_error_handling_test_pull_replica_disconnects_from_zoo_keeper",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"pull_replica_error_handling_test_pull_replica_disconnects_from_zoo_keeper_shard1_replica_p2",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:47694/solr",
[junit4] 2> "type":"PULL",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 1907987 INFO (SocketProxy-Acceptor-56830) [ ] o.a.s.c.SocketProxy accepted Socket[addr=/127.0.0.1,port=50097,localport=56830], receiveBufferSize:531000
[junit4] 2> 1907987 INFO (SocketProxy-Acceptor-56830) [ ] o.a.s.c.SocketProxy proxy connection Socket[addr=/127.0.0.1,port=38075,localport=55141], receiveBufferSize=530904
[junit4] 2> 1907989 INFO (SocketProxy-Acceptor-47694) [ ] o.a.s.c.SocketProxy accepted Socket[addr=/127.0.0.1,port=54547,localport=47694], receiveBufferSize:531000
[junit4] 2> 1907990 INFO (qtp2026204642-21926) [n:127.0.0.1:56830_solr ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node3&collection.configName=conf&newCollection=true&name=pull_replica_error_handling_test_pull_replica_disconnects_from_zoo_keeper_shard1_replica_n1&action=CREATE&numShards=1&collection=pull_replica_error_handling_test_pull_replica_disconnects_from_zoo_keeper&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 1907991 INFO (SocketProxy-Acceptor-47694) [ ] o.a.s.c.SocketProxy proxy connection Socket[addr=/127.0.0.1,port=46890,localport=47946], receiveBufferSize=530904
[junit4] 2> 1907994 INFO (qtp134497995-22044) [n:127.0.0.1:47694_solr ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node4&collection.configName=conf&newCollection=true&name=pull_replica_error_handling_test_pull_replica_disconnects_from_zoo_keeper_shard1_replica_p2&action=CREATE&numShards=1&collection=pull_replica_error_handling_test_pull_replica_disconnects_from_zoo_keeper&shard=shard1&wt=javabin&version=2&replicaType=PULL
[junit4] 2> 1908098 INFO (zkCallback-5226-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/pull_replica_error_handling_test_pull_replica_disconnects_from_zoo_keeper/state.json] for collection [pull_replica_error_handling_test_pull_replica_disconnects_from_zoo_keeper] has occurred - updating... (live nodes size: [4])
[junit4] 2> 1908098 INFO (zkCallback-5271-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/pull_replica_error_handling_test_pull_replica_disconnects_from_zoo_keeper/state.json] for collection [pull_replica_error_handling_test_pull_replica_disconnects_from_zoo_keeper] has occurred - updating... (live nodes size: [4])
[junit4] 2> 1908099 INFO (zkCallback-5271-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/pull_replica_error_handling_test_pull_replica_disconnects_from_zoo_keeper/state.json] for collection [pull_replica_error_handling_test_pull_replica_disconnects_from_zoo_keeper] has occurred - updating... (live nodes size: [4])
[junit4] 2> 1908105 INFO (zkCallback-5226-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/pull_replica_error_handling_test_pull_replica_disconnects_from_zoo_keeper/state.json] for collection [pull_replica_error_handling_test_pull_replica_disconnects_from_zoo_keeper] has occurred - updating... (live nodes size: [4])
[junit4] 2> 1909016 INFO (qtp2026204642-21926) [n:127.0.0.1:56830_solr c:pull_replica_error_handling_test_pull_replica_disconnects_from_zoo_keeper s:shard1 r:core_node3 x:pull_replica_error_handling_test_pull_replica_disconnects_from_zoo_keeper_shard1_replica_n1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
[junit4] 2> 1909016 INFO (qtp134497995-22044) [n:127.0.0.1:47694_solr c:pull_replica_error_handling_test_pull_replica_disconnects_from_zoo_keeper s:shard1 r:core_node4 x:pull_replica_error_handling_test_pull_replica_disconnects_from_zoo_keeper_shard1_replica_p2] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
[junit4] 2> 1909023 INFO (qtp2026204642-21926) [n:127.0.0.1:56830_solr c:pull_replica_error_handling_test_pull_replica_disconnects_from_zoo_keeper s:shard1 r:core_node3 x:pull_replica_error_handling_test_pull_replica_disconnects_from_zoo_keeper_shard1_replica_n1] o.a.s.s.IndexSchema [pull_replica_error_handling_test_pull_replica_disconnects_from_zoo_keeper_shard1_replica_n1] Schema name=minimal
[junit4] 2> 1909023 INFO (qtp134497995-22044) [n:127.0.0.1:47694_solr c:pull_replica_error_handling_test_pull_replica_disconnects_from_zoo_keeper s:shard1 r:core_node4 x:pull_replica_error_handling_test_pull_replica_disconnects_from_zoo_keeper_shard1_replica_p2] o.a.s.s.IndexSchema [pull_replica_error_handling_test_pull_replica_disconnects_from_zoo_keeper_shard1_replica_p2] Schema name=minimal
[junit4] 2> 1909025 INFO (qtp2026204642-21926) [n:127.0.0.1:56830_solr c:pull_replica_error_handling_test_pull_replica_disconnects_from_zoo_keeper s:shard1 r:core_node3 x:pull_replica_error_handling_test_pull_replica_disconnects_from_zoo_keeper_shard1_replica_n1] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 1909025 INFO (qtp134497995-22044) [n:127.0.0.1:47694_solr c:pull_replica_error_handling_test_pull_replica_disconnects_from_zoo_keeper s:shard1 r:core_node4 x:pull_replica_error_handling_test_pull_replica_disconnects_from_zoo_keeper_shard1_replica_p2] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 1909025 INFO (qtp2026204642-21926) [n:127.0.0.1:56830_solr c:pull_replica_error_handling_test_pull_replica_disconnects_from_zoo_keeper s:shard1 r:core_node3 x:pull_replica_error_handling_test_pull_replica_disconnects_from_zoo_keeper_shard1_replica_n1] o.a.s.c.CoreContainer Creating SolrCore 'pull_replica_error_handling_test_pull_replica_disconnects_from_zoo_keeper_shard1_replica_n1' using configuration from collection pull_replica_error_handling_test_pull_replica_disconnects_from_zoo_keeper, trusted=true
[junit4] 2> 1909025 INFO (qtp134497995-22044) [n:127.0.0.1:47694_solr c:pull_replica_error_handling_test_pull_replica_disconnects_from_zoo_keeper s:shard1 r:core_node4 x:pull_replica_error_handling_test_pull_replica_disconnects_from_zoo_keeper_shard1_replica_p2] o.a.s.c.CoreContainer Creating SolrCore 'pull_replica_error_handling_test_pull_replica_disconnects_from_zoo_keeper_shard1_replica_p2' using configuration from collection pull_replica_error_handling_test_pull_replica_disconnects_from_zoo_keeper, trusted=true
[junit4] 2> 1909026 INFO (qtp2026204642-21926) [n:127.0.0.1:56830_solr c:pull_replica_error_handling_test_pull_replica_disconnects_from_zoo_keeper s:shard1 r:core_node3 x:pull_replica_error_handling_test_pull_replica_disconnects_from_zoo_keeper_shard1_replica_n1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_56830.solr.core.pull_replica_error_handling_test_pull_replica_disconnects_from_zoo_keeper.shard1.replica_n1' (registry 'solr.core.pull_replica_error_handling_test_pull_replica_disconnects_from_zoo_keeper.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@58fde865
[junit4] 2> 1909026 INFO (qtp134497995-22044) [n:127.0.0.1:47694_solr c:pull_replica_error_handling_test_pull_replica_disconnects_from_zoo_keeper s:shard1 r:core_node4 x:pull_replica_error_handling_test_pull_replica_disconnects_from_zoo_keeper_shard1_replica_p2] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_47694.solr.core.pull_replica_error_handling_test_pull_replica_disconnects_from_zoo_keeper.shard1.replica_p2' (registry 'solr.core.pull_replica_error_handling_test_pull_replica_disconnects_from_zoo_keeper.shard1.replica_p2') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@58fde865
[junit4] 2> 1909026 INFO (qtp2026204642-21926) [n:127.0.0.1:56830_solr c:pull_replica_error_handling_test_pull_replica_disconnects_from_zoo_keeper s:shard1 r:core_node3 x:pull_replica_error_handling_test_pull_replica_disconnects_from_zoo_keeper_shard1_replica_n1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 1909026 INFO (qtp134497995-22044) [n:127.0.0.1:47694_solr c:pull_replica_error_handling_test_pull_replica_disconnects_from_zoo_keeper s:shard1 r:core_node4 x:pull_replica_error_handling_test_pull_replica_disconnects_from_zoo_keeper_shard1_replica_p2] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 1909026 INFO (qtp2026204642-21926) [n:127.0.0.1:56830_solr c:pull_replica_error_handling_test_pull_replica_disconnects_from_zoo_keeper s:shard1 r:core_node3 x:pull_replica_error_handling_test_pull_replica_disconnects_from_zoo_keeper_shard1_replica_n1] o.a.s.c.SolrCore [[pull_replica_error_handling_test_pull_replica_disconnects_from_zoo_keeper_shard1_replica_n1] ] Opening new SolrCore at [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.cloud.TestPullReplicaErrorHandling_7F7146C1E56BCFA0-001/tempDir-001/node2/pull_replica_error_handling_test_pull_replica_disconnects_from_zoo_keeper_shard1_replica_n1], dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.cloud.TestPullReplicaErrorHandling_7F7146C1E56BCFA0-001/tempDir-001/node2/./pull_replica_error_handling_test_pull_replica_disconnects_from_zoo_keeper_sha
[...truncated too long message...]
ce/Lucene-Solr-Tests-master/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file = /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file = /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file = /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file = /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/top-level-ivy-settings.xml
resolve:
jar-checksums:
[mkdir] Created dir: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/null1823322083
[copy] Copying 40 files to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/null1823322083
[delete] Deleting directory /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/null1823322083
resolve-example:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file = /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/top-level-ivy-settings.xml
resolve:
resolve-server:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file = /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/top-level-ivy-settings.xml
resolve:
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file = /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file = /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file = /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file = /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file = /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file = /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file = /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file = /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file = /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file = /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file = /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file = /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file = /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file = /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file = /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file = /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/top-level-ivy-settings.xml
resolve:
jar-checksums:
[mkdir] Created dir: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/null2138537569
[copy] Copying 246 files to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/null2138537569
[delete] Deleting directory /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/null2138537569
check-working-copy:
[ivy:cachepath] :: resolving dependencies :: org.eclipse.jgit#org.eclipse.jgit-caller;working
[ivy:cachepath] confs: [default]
[ivy:cachepath] found org.eclipse.jgit#org.eclipse.jgit;4.6.0.201612231935-r in public
[ivy:cachepath] found com.jcraft#jsch;0.1.53 in public
[ivy:cachepath] found com.googlecode.javaewah#JavaEWAH;1.1.6 in public
[ivy:cachepath] found org.apache.httpcomponents#httpclient;4.3.6 in public
[ivy:cachepath] found org.apache.httpcomponents#httpcore;4.3.3 in public
[ivy:cachepath] found commons-logging#commons-logging;1.1.3 in public
[ivy:cachepath] found commons-codec#commons-codec;1.6 in public
[ivy:cachepath] found org.slf4j#slf4j-api;1.7.2 in public
[ivy:cachepath] :: resolution report :: resolve 20ms :: artifacts dl 1ms
---------------------------------------------------------------------
| | modules || artifacts |
| conf | number| search|dwnlded|evicted|| number|dwnlded|
---------------------------------------------------------------------
| default | 8 | 0 | 0 | 0 || 8 | 0 |
---------------------------------------------------------------------
[wc-checker] Initializing working copy...
[wc-checker] SLF4J: Failed to load class "org.slf4j.impl.StaticLoggerBinder".
[wc-checker] SLF4J: Defaulting to no-operation (NOP) logger implementation
[wc-checker] SLF4J: See http://www.slf4j.org/codes.html#StaticLoggerBinder for further details.
[wc-checker] Checking working copy status...
-jenkins-base:
BUILD SUCCESSFUL
Total time: 84 minutes 48 seconds
Archiving artifacts
WARN: No artifacts found that match the file pattern "**/*.events,heapdumps/**,**/hs_err_pid*". Configuration error?
WARN: java.lang.InterruptedException: no matches found within 10000
Recording test results
Build step 'Publish JUnit test result report' changed build result to UNSTABLE
Email was triggered for: Unstable (Test Failures)
Sending email for trigger: Unstable (Test Failures)