Build: https://builds.apache.org/job/Lucene-Solr-Tests-master/2522/
2 tests failed. FAILED: org.apache.solr.cloud.ConcurrentCreateRoutedAliasTest.testConcurrentCreateRoutedAliasComplex Error Message: concurrent alias creation failed org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at https://127.0.0.1:42292/solr: Can't create collection alias for collections='testAliasCplx0_2017-12-25_23_24_25', 'testAliasCplx0_2017-12-25_23_24_25' is not an existing collection or alias Stack Trace: java.lang.AssertionError: concurrent alias creation failed org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at https://127.0.0.1:42292/solr: Can't create collection alias for collections='testAliasCplx0_2017-12-25_23_24_25', 'testAliasCplx0_2017-12-25_23_24_25' is not an existing collection or alias at __randomizedtesting.SeedInfo.seed([13F1FD94F96B8A5C:79A381DDBB3549C7]:0) at org.junit.Assert.fail(Assert.java:93) at org.junit.Assert.assertTrue(Assert.java:43) at org.junit.Assert.assertNull(Assert.java:551) at org.apache.solr.cloud.ConcurrentCreateRoutedAliasTest.testConcurrentCreateRoutedAliasComplex(ConcurrentCreateRoutedAliasTest.java:133) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:498) at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1737) at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934) at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970) at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368) at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817) at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468) at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:943) at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829) at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879) at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368) at java.lang.Thread.run(Thread.java:748) FAILED: org.apache.solr.cloud.TestPullReplica.testCreateDelete {seed=[13F1FD94F96B8A5C:BCB7B0243B59B940]} Error Message: Error from server at http://127.0.0.1:35479/solr: KeeperErrorCode = NoNode for /overseer/collection-queue-work/qnr-0000000010 Stack Trace: org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at http://127.0.0.1:35479/solr: KeeperErrorCode = NoNode for /overseer/collection-queue-work/qnr-0000000010 at __randomizedtesting.SeedInfo.seed([13F1FD94F96B8A5C:BCB7B0243B59B940]:0) at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:643) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:255) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:244) at org.apache.solr.client.solrj.impl.LBHttpSolrClient.doRequest(LBHttpSolrClient.java:483) at org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:413) at org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:1106) at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:886) at org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:819) at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:194) at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:211) at org.apache.solr.cloud.TestPullReplica.tearDown(TestPullReplica.java:116) at sun.reflect.GeneratedMethodAccessor7.invoke(Unknown Source) 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$10.evaluate(RandomizedRunner.java:992) 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 13021 lines...] [junit4] Suite: org.apache.solr.cloud.TestPullReplica [junit4] 2> 1225422 INFO (SUITE-TestPullReplica-seed#[13F1FD94F96B8A5C]-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/J1/temp/solr.cloud.TestPullReplica_13F1FD94F96B8A5C-001/init-core-data-001 [junit4] 2> 1225422 WARN (SUITE-TestPullReplica-seed#[13F1FD94F96B8A5C]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=18 numCloses=18 [junit4] 2> 1225422 INFO (SUITE-TestPullReplica-seed#[13F1FD94F96B8A5C]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=false [junit4] 2> 1225436 INFO (SUITE-TestPullReplica-seed#[13F1FD94F96B8A5C]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason=, ssl=NaN, value=NaN, clientAuth=NaN) [junit4] 2> 1225437 INFO (SUITE-TestPullReplica-seed#[13F1FD94F96B8A5C]-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/J1/temp/solr.cloud.TestPullReplica_13F1FD94F96B8A5C-001/tempDir-001 [junit4] 2> 1225437 INFO (SUITE-TestPullReplica-seed#[13F1FD94F96B8A5C]-worker) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER [junit4] 2> 1225437 INFO (Thread-723) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0 [junit4] 2> 1225437 INFO (Thread-723) [ ] o.a.s.c.ZkTestServer Starting server [junit4] 2> 1225461 ERROR (Thread-723) [ ] 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> 1225537 INFO (SUITE-TestPullReplica-seed#[13F1FD94F96B8A5C]-worker) [ ] o.a.s.c.ZkTestServer start zk server on port:45894 [junit4] 2> 1225648 INFO (zkConnectionManagerCallback-1036-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1225668 INFO (jetty-launcher-1033-thread-1) [ ] o.e.j.s.Server jetty-9.4.10.v20180503; built: 2018-05-03T15:56:21.710Z; git: daa59876e6f384329b122929e70a80934569428c; jvm 1.8.0_172-b11 [junit4] 2> 1225669 INFO (jetty-launcher-1033-thread-2) [ ] o.e.j.s.Server jetty-9.4.10.v20180503; built: 2018-05-03T15:56:21.710Z; git: daa59876e6f384329b122929e70a80934569428c; jvm 1.8.0_172-b11 [junit4] 2> 1225702 INFO (jetty-launcher-1033-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0 [junit4] 2> 1225702 INFO (jetty-launcher-1033-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults [junit4] 2> 1225703 INFO (jetty-launcher-1033-thread-1) [ ] o.e.j.s.session node0 Scavenging every 600000ms [junit4] 2> 1225703 INFO (jetty-launcher-1033-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@523f9cf7{/solr,null,AVAILABLE} [junit4] 2> 1225703 INFO (jetty-launcher-1033-thread-2) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0 [junit4] 2> 1225703 INFO (jetty-launcher-1033-thread-2) [ ] o.e.j.s.session No SessionScavenger set, using defaults [junit4] 2> 1225703 INFO (jetty-launcher-1033-thread-2) [ ] o.e.j.s.session node0 Scavenging every 600000ms [junit4] 2> 1225703 INFO (jetty-launcher-1033-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@524c0af6{/solr,null,AVAILABLE} [junit4] 2> 1225758 INFO (jetty-launcher-1033-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@84bfb1d{HTTP/1.1,[http/1.1]}{127.0.0.1:35479} [junit4] 2> 1225758 INFO (jetty-launcher-1033-thread-1) [ ] o.e.j.s.Server Started @1225934ms [junit4] 2> 1225758 INFO (jetty-launcher-1033-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=35479} [junit4] 2> 1225759 ERROR (jetty-launcher-1033-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 1225759 INFO (jetty-launcher-1033-thread-1) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory [junit4] 2> 1225759 INFO (jetty-launcher-1033-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.0.0 [junit4] 2> 1225759 INFO (jetty-launcher-1033-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 1225759 INFO (jetty-launcher-1033-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 1225759 INFO (jetty-launcher-1033-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-05-09T07:15:33.315Z [junit4] 2> 1225760 INFO (jetty-launcher-1033-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@79c30fd7{HTTP/1.1,[http/1.1]}{127.0.0.1:46601} [junit4] 2> 1225760 INFO (jetty-launcher-1033-thread-2) [ ] o.e.j.s.Server Started @1225935ms [junit4] 2> 1225760 INFO (jetty-launcher-1033-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=46601} [junit4] 2> 1225760 ERROR (jetty-launcher-1033-thread-2) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 1225760 INFO (jetty-launcher-1033-thread-2) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory [junit4] 2> 1225760 INFO (jetty-launcher-1033-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.0.0 [junit4] 2> 1225760 INFO (jetty-launcher-1033-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 1225760 INFO (jetty-launcher-1033-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 1225760 INFO (jetty-launcher-1033-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-05-09T07:15:33.316Z [junit4] 2> 1225802 INFO (zkConnectionManagerCallback-1038-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1225810 INFO (jetty-launcher-1033-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 1225814 INFO (zkConnectionManagerCallback-1040-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1225869 INFO (jetty-launcher-1033-thread-2) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 1225905 INFO (jetty-launcher-1033-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:45894/solr [junit4] 2> 1225925 INFO (zkConnectionManagerCallback-1044-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1225943 INFO (zkConnectionManagerCallback-1046-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1225961 INFO (jetty-launcher-1033-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:45894/solr [junit4] 2> 1226078 INFO (zkConnectionManagerCallback-1050-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1226244 INFO (zkConnectionManagerCallback-1054-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1226371 INFO (jetty-launcher-1033-thread-2) [n:127.0.0.1:46601_solr ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 1226372 INFO (jetty-launcher-1033-thread-2) [n:127.0.0.1:46601_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:46601_solr [junit4] 2> 1226373 INFO (jetty-launcher-1033-thread-2) [n:127.0.0.1:46601_solr ] o.a.s.c.Overseer Overseer (id=72594172261629958-127.0.0.1:46601_solr-n_0000000000) starting [junit4] 2> 1226561 INFO (zkConnectionManagerCallback-1061-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1226641 INFO (jetty-launcher-1033-thread-2) [n:127.0.0.1:46601_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:45894/solr ready [junit4] 2> 1226642 INFO (jetty-launcher-1033-thread-2) [n:127.0.0.1:46601_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:46601_solr [junit4] 2> 1226751 INFO (OverseerStateUpdate-72594172261629958-127.0.0.1:46601_solr-n_0000000000) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 1226870 INFO (zkCallback-1060-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 1227366 INFO (jetty-launcher-1033-thread-1) [n:127.0.0.1:35479_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 1227401 INFO (jetty-launcher-1033-thread-1) [n:127.0.0.1:35479_solr ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 1227505 INFO (jetty-launcher-1033-thread-1) [n:127.0.0.1:35479_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores [junit4] 2> 1227505 INFO (jetty-launcher-1033-thread-1) [n:127.0.0.1:35479_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:35479_solr [junit4] 2> 1227506 INFO (zkCallback-1053-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2) [junit4] 2> 1227624 INFO (zkCallback-1045-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2) [junit4] 2> 1227634 INFO (zkCallback-1060-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2) [junit4] 2> 1227842 INFO (jetty-launcher-1033-thread-2) [n:127.0.0.1:46601_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_46601.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@4314f79a [junit4] 2> 1227880 INFO (jetty-launcher-1033-thread-2) [n:127.0.0.1:46601_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_46601.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@4314f79a [junit4] 2> 1227880 INFO (jetty-launcher-1033-thread-2) [n:127.0.0.1:46601_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_46601.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@4314f79a [junit4] 2> 1227914 INFO (jetty-launcher-1033-thread-2) [n:127.0.0.1:46601_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/J1/temp/solr.cloud.TestPullReplica_13F1FD94F96B8A5C-001/tempDir-001/node2/. [junit4] 2> 1228548 INFO (jetty-launcher-1033-thread-1) [n:127.0.0.1:35479_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_35479.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@4314f79a [junit4] 2> 1228567 INFO (jetty-launcher-1033-thread-1) [n:127.0.0.1:35479_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_35479.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@4314f79a [junit4] 2> 1228567 INFO (jetty-launcher-1033-thread-1) [n:127.0.0.1:35479_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_35479.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@4314f79a [junit4] 2> 1228585 INFO (jetty-launcher-1033-thread-1) [n:127.0.0.1:35479_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/J1/temp/solr.cloud.TestPullReplica_13F1FD94F96B8A5C-001/tempDir-001/node1/. [junit4] 2> 1228622 INFO (zkConnectionManagerCallback-1066-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1228624 INFO (jetty-launcher-1033-thread-1) [n:127.0.0.1:35479_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2) [junit4] 2> 1228642 INFO (jetty-launcher-1033-thread-1) [n:127.0.0.1:35479_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:45894/solr ready [junit4] 2> 1228772 INFO (zkConnectionManagerCallback-1068-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1228861 INFO (zkConnectionManagerCallback-1073-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1228882 INFO (SUITE-TestPullReplica-seed#[13F1FD94F96B8A5C]-worker) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2) [junit4] 2> 1228883 INFO (SUITE-TestPullReplica-seed#[13F1FD94F96B8A5C]-worker) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:45894/solr ready [junit4] 2> 1228964 INFO (SUITE-TestPullReplica-seed#[13F1FD94F96B8A5C]-worker) [ ] o.a.s.c.TestPullReplica Using legacyCloud?: false [junit4] 2> 1228987 INFO (qtp1985270943-3971) [n:127.0.0.1:35479_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> 1228988 INFO (qtp1985270943-3971) [n:127.0.0.1:35479_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> 1229067 INFO (TEST-TestPullReplica.testCreateDelete-seed#[13F1FD94F96B8A5C]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testCreateDelete {seed=[13F1FD94F96B8A5C:8E10CD80F9B726C]} [junit4] 2> 1229113 INFO (qtp115826095-3979) [n:127.0.0.1:46601_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params pullReplicas=3&replicationFactor=1&collection.configName=conf&maxShardsPerNode=100&name=pull_replica_test_create_delete&action=CREATE&numShards=2 and sendToOCPQueue=true [junit4] 2> 1229162 INFO (OverseerThreadFactory-1827-thread-1) [ ] o.a.s.c.a.c.CreateCollectionCmd Create collection pull_replica_test_create_delete [junit4] 2> 1229170 WARN (OverseerThreadFactory-1827-thread-1) [ ] o.a.s.c.a.c.CreateCollectionCmd Specified number of replicas of 4 on collection pull_replica_test_create_delete is higher than the number of Solr instances currently live or live and part of your createNodeSet(2). It's unusual to run two replica of the same slice on the same Solr-instance. [junit4] 2> 1229373 INFO (OverseerStateUpdate-72594172261629958-127.0.0.1:46601_solr-n_0000000000) [ ] o.a.s.c.o.SliceMutator createReplica() { [junit4] 2> "operation":"ADDREPLICA", [junit4] 2> "collection":"pull_replica_test_create_delete", [junit4] 2> "shard":"shard1", [junit4] 2> "core":"pull_replica_test_create_delete_shard1_replica_n1", [junit4] 2> "state":"down", [junit4] 2> "base_url":"http://127.0.0.1:46601/solr";, [junit4] 2> "type":"NRT", [junit4] 2> "waitForFinalState":"false"} [junit4] 2> 1229375 INFO (OverseerStateUpdate-72594172261629958-127.0.0.1:46601_solr-n_0000000000) [ ] o.a.s.c.o.SliceMutator createReplica() { [junit4] 2> "operation":"ADDREPLICA", [junit4] 2> "collection":"pull_replica_test_create_delete", [junit4] 2> "shard":"shard1", [junit4] 2> "core":"pull_replica_test_create_delete_shard1_replica_p2", [junit4] 2> "state":"down", [junit4] 2> "base_url":"http://127.0.0.1:35479/solr";, [junit4] 2> "type":"PULL", [junit4] 2> "waitForFinalState":"false"} [junit4] 2> 1229390 INFO (OverseerStateUpdate-72594172261629958-127.0.0.1:46601_solr-n_0000000000) [ ] o.a.s.c.o.SliceMutator createReplica() { [junit4] 2> "operation":"ADDREPLICA", [junit4] 2> "collection":"pull_replica_test_create_delete", [junit4] 2> "shard":"shard1", [junit4] 2> "core":"pull_replica_test_create_delete_shard1_replica_p3", [junit4] 2> "state":"down", [junit4] 2> "base_url":"http://127.0.0.1:46601/solr";, [junit4] 2> "type":"PULL", [junit4] 2> "waitForFinalState":"false"} [junit4] 2> 1229392 INFO (OverseerStateUpdate-72594172261629958-127.0.0.1:46601_solr-n_0000000000) [ ] o.a.s.c.o.SliceMutator createReplica() { [junit4] 2> "operation":"ADDREPLICA", [junit4] 2> "collection":"pull_replica_test_create_delete", [junit4] 2> "shard":"shard1", [junit4] 2> "core":"pull_replica_test_create_delete_shard1_replica_p4", [junit4] 2> "state":"down", [junit4] 2> "base_url":"http://127.0.0.1:35479/solr";, [junit4] 2> "type":"PULL", [junit4] 2> "waitForFinalState":"false"} [junit4] 2> 1229410 INFO (OverseerStateUpdate-72594172261629958-127.0.0.1:46601_solr-n_0000000000) [ ] o.a.s.c.o.SliceMutator createReplica() { [junit4] 2> "operation":"ADDREPLICA", [junit4] 2> "collection":"pull_replica_test_create_delete", [junit4] 2> "shard":"shard2", [junit4] 2> "core":"pull_replica_test_create_delete_shard2_replica_n6", [junit4] 2> "state":"down", [junit4] 2> "base_url":"http://127.0.0.1:46601/solr";, [junit4] 2> "type":"NRT", [junit4] 2> "waitForFinalState":"false"} [junit4] 2> 1229413 INFO (OverseerStateUpdate-72594172261629958-127.0.0.1:46601_solr-n_0000000000) [ ] o.a.s.c.o.SliceMutator createReplica() { [junit4] 2> "operation":"ADDREPLICA", [junit4] 2> "collection":"pull_replica_test_create_delete", [junit4] 2> "shard":"shard2", [junit4] 2> "core":"pull_replica_test_create_delete_shard2_replica_p9", [junit4] 2> "state":"down", [junit4] 2> "base_url":"http://127.0.0.1:35479/solr";, [junit4] 2> "type":"PULL", [junit4] 2> "waitForFinalState":"false"} [junit4] 2> 1229448 INFO (OverseerStateUpdate-72594172261629958-127.0.0.1:46601_solr-n_0000000000) [ ] o.a.s.c.o.SliceMutator createReplica() { [junit4] 2> "operation":"ADDREPLICA", [junit4] 2> "collection":"pull_replica_test_create_delete", [junit4] 2> "shard":"shard2", [junit4] 2> "core":"pull_replica_test_create_delete_shard2_replica_p11", [junit4] 2> "state":"down", [junit4] 2> "base_url":"http://127.0.0.1:46601/solr";, [junit4] 2> "type":"PULL", [junit4] 2> "waitForFinalState":"false"} [junit4] 2> 1229464 INFO (OverseerStateUpdate-72594172261629958-127.0.0.1:46601_solr-n_0000000000) [ ] o.a.s.c.o.SliceMutator createReplica() { [junit4] 2> "operation":"ADDREPLICA", [junit4] 2> "collection":"pull_replica_test_create_delete", [junit4] 2> "shard":"shard2", [junit4] 2> "core":"pull_replica_test_create_delete_shard2_replica_p14", [junit4] 2> "state":"down", [junit4] 2> "base_url":"http://127.0.0.1:35479/solr";, [junit4] 2> "type":"PULL", [junit4] 2> "waitForFinalState":"false"} [junit4] 2> 1229768 INFO (qtp115826095-3970) [n:127.0.0.1:46601_solr x:pull_replica_test_create_delete_shard1_replica_n1] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node5&collection.configName=conf&newCollection=true&name=pull_replica_test_create_delete_shard1_replica_n1&action=CREATE&numShards=2&collection=pull_replica_test_create_delete&shard=shard1&wt=javabin&version=2&replicaType=NRT [junit4] 2> 1229768 INFO (qtp115826095-3970) [n:127.0.0.1:46601_solr x:pull_replica_test_create_delete_shard1_replica_n1] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores [junit4] 2> 1229784 INFO (qtp1985270943-3966) [n:127.0.0.1:35479_solr x:pull_replica_test_create_delete_shard1_replica_p2] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node7&collection.configName=conf&newCollection=true&name=pull_replica_test_create_delete_shard1_replica_p2&action=CREATE&numShards=2&collection=pull_replica_test_create_delete&shard=shard1&wt=javabin&version=2&replicaType=PULL [junit4] 2> 1229785 INFO (qtp115826095-3978) [n:127.0.0.1:46601_solr x:pull_replica_test_create_delete_shard2_replica_n6] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node12&collection.configName=conf&newCollection=true&name=pull_replica_test_create_delete_shard2_replica_n6&action=CREATE&numShards=2&collection=pull_replica_test_create_delete&shard=shard2&wt=javabin&version=2&replicaType=NRT [junit4] 2> 1229786 INFO (qtp115826095-3972) [n:127.0.0.1:46601_solr x:pull_replica_test_create_delete_shard1_replica_p3] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node8&collection.configName=conf&newCollection=true&name=pull_replica_test_create_delete_shard1_replica_p3&action=CREATE&numShards=2&collection=pull_replica_test_create_delete&shard=shard1&wt=javabin&version=2&replicaType=PULL [junit4] 2> 1229803 INFO (qtp1985270943-3973) [n:127.0.0.1:35479_solr x:pull_replica_test_create_delete_shard2_replica_p14] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node16&collection.configName=conf&newCollection=true&name=pull_replica_test_create_delete_shard2_replica_p14&action=CREATE&numShards=2&collection=pull_replica_test_create_delete&shard=shard2&wt=javabin&version=2&replicaType=PULL [junit4] 2> 1229822 INFO (qtp115826095-3976) [n:127.0.0.1:46601_solr x:pull_replica_test_create_delete_shard2_replica_p11] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node15&collection.configName=conf&newCollection=true&name=pull_replica_test_create_delete_shard2_replica_p11&action=CREATE&numShards=2&collection=pull_replica_test_create_delete&shard=shard2&wt=javabin&version=2&replicaType=PULL [junit4] 2> 1230007 INFO (qtp1985270943-4051) [n:127.0.0.1:35479_solr x:pull_replica_test_create_delete_shard2_replica_p9] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node13&collection.configName=conf&newCollection=true&name=pull_replica_test_create_delete_shard2_replica_p9&action=CREATE&numShards=2&collection=pull_replica_test_create_delete&shard=shard2&wt=javabin&version=2&replicaType=PULL [junit4] 2> 1230039 INFO (qtp1985270943-3969) [n:127.0.0.1:35479_solr x:pull_replica_test_create_delete_shard1_replica_p4] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node10&collection.configName=conf&newCollection=true&name=pull_replica_test_create_delete_shard1_replica_p4&action=CREATE&numShards=2&collection=pull_replica_test_create_delete&shard=shard1&wt=javabin&version=2&replicaType=PULL [junit4] 2> 1230982 INFO (qtp115826095-3978) [n:127.0.0.1:46601_solr c:pull_replica_test_create_delete s:shard2 r:core_node12 x:pull_replica_test_create_delete_shard2_replica_n6] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0 [junit4] 2> 1231022 INFO (qtp115826095-3972) [n:127.0.0.1:46601_solr c:pull_replica_test_create_delete s:shard1 r:core_node8 x:pull_replica_test_create_delete_shard1_replica_p3] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0 [junit4] 2> 1231039 INFO (qtp1985270943-3966) [n:127.0.0.1:35479_solr c:pull_replica_test_create_delete s:shard1 r:core_node7 x:pull_replica_test_create_delete_shard1_replica_p2] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0 [junit4] 2> 1231079 INFO (qtp115826095-3978) [n:127.0.0.1:46601_solr c:pull_replica_test_create_delete s:shard2 r:core_node12 x:pull_replica_test_create_delete_shard2_replica_n6] o.a.s.s.IndexSchema [pull_replica_test_create_delete_shard2_replica_n6] Schema name=minimal [junit4] 2> 1231081 INFO (qtp115826095-3978) [n:127.0.0.1:46601_solr c:pull_replica_test_create_delete s:shard2 r:core_node12 x:pull_replica_test_create_delete_shard2_replica_n6] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id [junit4] 2> 1231081 INFO (qtp115826095-3978) [n:127.0.0.1:46601_solr c:pull_replica_test_create_delete s:shard2 r:core_node12 x:pull_replica_test_create_delete_shard2_replica_n6] o.a.s.c.CoreContainer Creating SolrCore 'pull_replica_test_create_delete_shard2_replica_n6' using configuration from collection pull_replica_test_create_delete, trusted=true [junit4] 2> 1231082 INFO (qtp115826095-3978) [n:127.0.0.1:46601_solr c:pull_replica_test_create_delete s:shard2 r:core_node12 x:pull_replica_test_create_delete_shard2_replica_n6] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_46601.solr.core.pull_replica_test_create_delete.shard2.replica_n6' (registry 'solr.core.pull_replica_test_create_delete.shard2.replica_n6') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@4314f79a [junit4] 2> 1231082 INFO (qtp115826095-3978) [n:127.0.0.1:46601_solr c:pull_replica_test_create_delete s:shard2 r:core_node12 x:pull_replica_test_create_delete_shard2_replica_n6] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder [junit4] 2> 1231082 INFO (qtp115826095-3978) [n:127.0.0.1:46601_solr c:pull_replica_test_create_delete s:shard2 r:core_node12 x:pull_replica_test_create_delete_shard2_replica_n6] o.a.s.c.SolrCore [[pull_replica_test_create_delete_shard2_replica_n6] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.cloud.TestPullReplica_13F1FD94F96B8A5C-001/tempDir-001/node2/pull_replica_test_create_delete_shard2_replica_n6], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.cloud.TestPullReplica_13F1FD94F96B8A5C-001/tempDir-001/node2/./pull_replica_test_create_delete_shard2_replica_n6/data/] [junit4] 2> 1231173 INFO (qtp115826095-3972) [n:127.0.0.1:46601_solr c:pull_replica_test_create_delete s:shard1 r:core_node8 x:pull_replica_test_create_delete_shard1_replica_p3] o.a.s.s.IndexSchema [pull_replica_test_create_delete_shard1_replica_p3] Schema name=minimal [junit4] 2> 1231176 INFO (qtp115826095-3976) [n:127.0.0.1:46601_solr c:pull_replica_test_create_delete s:shard2 r:core_node15 x:pull_replica_test_create_delete_shard2_replica_p11] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0 [junit4] 2> 1231236 INFO (qtp115826095-3972) [n:127.0.0.1:46601_solr c:pull_replica_test_create_delete s:shard1 r:core_node8 x:pull_replica_test_create_delete_shard1_replica_p3] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id [junit4] 2> 1231236 INFO (qtp115826095-3972) [n:127.0.0.1:46601_solr c:pull_replica_test_create_delete s:shard1 r:core_node8 x:pull_replica_test_create_delete_shard1_replica_p3] o.a.s.c.CoreContainer Creating SolrCore 'pull_replica_test_create_delete_shard1_replica_p3' using configuration from collection pull_replica_test_create_delete, trusted=true [junit4] 2> 1231237 INFO (qtp115826095-3972) [n:127.0.0.1:46601_solr c:pull_replica_test_create_delete s:shard1 r:core_node8 x:pull_replica_test_create_delete_shard1_replica_p3] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_46601.solr.core.pull_replica_test_create_delete.shard1.replica_p3' (registry 'solr.core.pull_replica_test_create_delete.shard1.replica_p3') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@4314f79a [junit4] 2> 1231237 INFO (qtp115826095-3972) [n:127.0.0.1:46601_solr c:pull_replica_test_create_delete s:shard1 r:core_node8 x:pull_replica_test_create_delete_shard1_replica_p3] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder [junit4] 2> 1231237 INFO (qtp115826095-3972) [n:127.0.0.1:46601_solr c:pull_replica_test_create_delete s:shard1 r:core_node8 x:pull_replica_test_create_delete_shard1_replica_p3] o.a.s.c.SolrCore [[pull_replica_test_create_delete_shard1_replica_p3] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.cloud.TestPullReplica_13F1FD94F96B8A5C-001/tempDir-001/node2/pull_replica_test_create_delete_shard1_replica_p3], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.cloud.TestPullReplica_13F1FD94F96B8A5C-001/tempDir-001/node2/./pull_replica_test_create_delete_shard1_replica_p3/data/] [junit4] 2> 1231269 INFO (qtp1985270943-3966) [n:127.0.0.1:35479_solr c:pull_replica_test_create_delete s:shard1 r:core_node7 x:pull_replica_test_create_delete_shard1_replica_p2] o.a.s.s.IndexSchema [pull_replica_test_create_delete_shard1_replica_p2] Schema name=minimal [junit4] 2> 1231271 INFO (qtp1985270943-3966) [n:127.0.0.1:35479_solr c:pull_replica_test_create_delete s:shard1 r:core_node7 x:pull_replica_test_create_delete_shard1_replica_p2] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id [junit4] 2> 1231271 INFO (qtp1985270943-3966) [n:127.0.0.1:35479_solr c:pull_replica_test_create_delete s:shard1 r:core_node7 x:pull_replica_test_create_delete_shard1_replica_p2] o.a.s.c.CoreContainer Creating SolrCore 'pull_replica_test_create_delete_shard1_replica_p2' using configuration from collection pull_replica_test_create_delete, trusted=true [junit4] 2> 1231272 INFO (qtp1985270943-3966) [n:127.0.0.1:35479_solr c:pull_replica_test_create_delete s:shard1 r:core_node7 x:pull_replica_test_create_delete_shard1_replica_p2] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_35479.solr.core.pull_replica_test_create_delete.shard1.replica_p2' (registry 'solr.core.pull_replica_test_create_delete.shard1.replica_p2') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@4314f79a [junit4] 2> 1231272 INFO (qtp1985270943-3966) [n:127.0.0.1:35479_solr c:pull_replica_test_create_delete s:shard1 r:core_node7 x:pull_replica_test_create_delete_shard1_replica_p2] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder [junit4] 2> 1231272 INFO (qtp1985270943-3966) [n:127.0.0.1:35479_solr c:pull_replica_test_create_delete s:shard1 r:core_node7 x:pull_replica_test_create_delete_shard1_replica_p2] o.a.s.c.SolrCore [[pull_replica_test_create_delete_shard1_replica_p2] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.cloud.TestPullReplica_13F1FD94F96B8A5C-001/tempDir-001/node1/pull_replica_test_create_delete_shard1_replica_p2], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.cloud.TestPullReplica_13F1FD94F96B8A5C-001/tempDir-001/node1/./pull_replica_test_create_delete_shard1_replica_p2/data/] [junit4] 2> 1231326 INFO (qtp115826095-3976) [n:127.0.0.1:46601_solr c:pull_replica_test_create_delete s:shard2 r:core_node15 x:pull_replica_test_create_delete_shard2_replica_p11] o.a.s.s.IndexSchema [pull_replica_test_create_delete_shard2_replica_p11] Schema name=minimal [junit4] 2> 1231328 INFO (qtp115826095-3976) [n:127.0.0.1:46601_solr c:pull_replica_test_create_delete s:shard2 r:core_node15 x:pull_replica_test_create_delete_shard2_replica_p11] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id [junit4] 2> 1231328 INFO (qtp115826095-3976) [n:127.0.0.1:46601_solr c:pull_replica_test_create_delete s:shard2 r:core_node15 x:pull_replica_test_create_delete_shard2_replica_p11] o.a.s.c.CoreContainer Creating SolrCore 'pull_replica_test_create_delete_shard2_replica_p11' using configuration from collection pull_replica_test_create_delete, trusted=true [junit4] 2> 1231329 INFO (qtp115826095-3976) [n:127.0.0.1:46601_solr c:pull_replica_test_create_delete s:shard2 r:core_node15 x:pull_replica_test_create_delete_shard2_replica_p11] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_46601.solr.core.pull_replica_test_create_delete.shard2.replica_p11' (registry 'solr.core.pull_replica_test_create_delete.shard2.replica_p11') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@4314f79a [junit4] 2> 1231329 INFO (qtp115826095-3976) [n:127.0.0.1:46601_solr c:pull_replica_test_create_delete s:shard2 r:core_node15 x:pull_replica_test_create_delete_shard2_replica_p11] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder [junit4] 2> 1231354 INFO (qtp1985270943-4051) [n:127.0.0.1:35479_solr c:pull_replica_test_create_delete s:shard2 r:core_node13 x:pull_replica_test_create_delete_shard2_replica_p9] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0 [junit4] 2> 1231398 INFO (qtp115826095-3976) [n:127.0.0.1:46601_solr c:pull_replica_test_create_delete s:shard2 r:core_node15 x:pull_replica_test_create_delete_shard2_replica_p11] o.a.s.c.SolrCore [[pull_replica_test_create_delete_shard2_replica_p11] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.cloud.TestPullReplica_13F1FD94F96B8A5C-001/tempDir-001/node2/pull_replica_test_create_delete_shard2_replica_p11], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.cloud.TestPullReplica_13F1FD94F96B8A5C-001/tempDir-001/node2/./pull_replica_test_create_delete_shard2_replica_p11/data/] [junit4] 2> 1231445 INFO (qtp1985270943-4051) [n:127.0.0.1:35479_solr c:pull_replica_test_create_delete s:shard2 r:core_node13 x:pull_replica_test_create_delete_shard2_replica_p9] o.a.s.s.IndexSchema [pull_replica_test_create_delete_shard2_replica_p9] Schema name=minimal [junit4] 2> 1231466 INFO (qtp1985270943-3969) [n:127.0.0.1:35479_solr c:pull_replica_test_create_delete s:shard1 r:core_node10 x:pull_replica_test_create_delete_shard1_replica_p4] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0 [junit4] 2> 1231481 INFO (qtp115826095-3970) [n:127.0.0.1:46601_solr c:pull_replica_test_create_delete s:shard1 r:core_node5 x:pull_replica_test_create_delete_shard1_replica_n1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0 [junit4] 2> 1231500 INFO (qtp1985270943-3973) [n:127.0.0.1:35479_solr c:pull_replica_test_create_delete s:shard2 r:core_node16 x:pull_replica_test_create_delete_shard2_replica_p14] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0 [junit4] 2> 1231721 INFO (qtp1985270943-4051) [n:127.0.0.1:35479_solr c:pull_replica_test_create_delete s:shard2 r:core_node13 x:pull_replica_test_create_delete_shard2_replica_p9] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id [junit4] 2> 1231721 INFO (qtp1985270943-4051) [n:127.0.0.1:35479_solr c:pull_replica_test_create_delete s:shard2 r:core_node13 x:pull_replica_test_create_delete_shard2_replica_p9] o.a.s.c.CoreContainer Creating SolrCore 'pull_replica_test_create_delete_shard2_replica_p9' using configuration from collection pull_replica_test_create_delete, trusted=true [junit4] 2> 1231721 INFO (qtp1985270943-4051) [n:127.0.0.1:35479_solr c:pull_replica_test_create_delete s:shard2 r:core_node13 x:pull_replica_test_create_delete_shard2_replica_p9] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_35479.solr.core.pull_replica_test_create_delete.shard2.replica_p9' (registry 'solr.core.pull_replica_test_create_delete.shard2.replica_p9') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@4314f79a [junit4] 2> 1231722 INFO (qtp1985270943-4051) [n:127.0.0.1:35479_solr c:pull_replica_test_create_delete s:shard2 r:core_node13 x:pull_replica_test_create_delete_shard2_replica_p9] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder [junit4] 2> 1231722 INFO (qtp1985270943-4051) [n:127.0.0.1:35479_solr c:pull_replica_test_create_delete s:shard2 r:core_node13 x:pull_replica_test_create_delete_shard2_replica_p9] o.a.s.c.SolrCore [[pull_replica_test_create_delete_shard2_replica_p9] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.cloud.TestPullReplica_13F1FD94F96B8A5C-001/tempDir-001/node1/pull_replica_test_create_delete_shard2_replica_p9], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.cloud.TestPullReplica_13F1FD94F96B8A5C-001/tempDir-001/node1/./pull_replica_test_create_delete_shard2_replica_p9/data/] [junit4] 2> 1231866 INFO (qtp115826095-3970) [n:127.0.0.1:46601_solr c:pull_replica_test_create_delete s:shard1 r:core_node5 x:pull_replica_test_create_delete_shard1_replica_n1] o.a.s.s.IndexSchema [pull_replica_test_create_delete_shard1_replica_n1] Schema name=minimal [junit4] 2> 1231868 INFO (qtp115826095-3970) [n:127.0.0.1:46601_solr c:pull_replica_test_create_delete s:shard1 r:core_node5 x:pull_replica_test_create_delete_shard1_replica_n1] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id [junit4] 2> 1231868 INFO (qtp115826095-3970) [n:127.0.0.1:46601_solr c:pull_replica_test_create_delete s:shard1 r:core_node5 x:pull_replica_test_create_delete_shard1_replica_n1] o.a.s.c.CoreContainer Creating SolrCore 'pull_replica_test_create_delete_shard1_replica_n1' using configuration from collection pull_replica_test_create_delete, trusted=true [junit4] 2> 1231869 INFO (qtp115826095-3970) [n:127.0.0.1:46601_solr c:pull_replica_test_create_delete s:shard1 r:core_node5 x:pull_replica_test_create_delete_shard1_replica_n1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_46601.solr.core.pull_replica_test_create_delete.shard1.replica_n1' (registry 'solr.core.pull_replica_test_create_delete.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@4314f79a [junit4] 2> 1231869 INFO (qtp115826095-3970) [n:127.0.0.1:46601_solr c:pull_replica_test_create_delete s:shard1 r:core_node5 x:pull_replica_test_create_delete_shard1_replica_n1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder [junit4] 2> 1231869 INFO (qtp115826095-3970) [n:127.0.0.1:46601_solr c:pull_replica_test_create_delete s:shard1 r:core_node5 x:pull_replica_test_create_delete_shard1_replica_n1] o.a.s.c.SolrCore [[pull_replica_test_create_delete_shard1_replica_n1] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.cloud.TestPullReplica_13F1FD94F96B8A5C-001/tempDir-001/node2/pull_replica_test_create_delete_shard1_replica_n1], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.cloud.TestPullReplica_13F1FD94F96B8A5C-001/tempDir-001/node2/./pull_replica_test_create_delete_shard1_replica_n1/data/] [junit4] 2> 1231883 INFO (qtp1985270943-3973) [n:127.0.0.1:35479_solr c:pull_replica_test_create_delete s:shard2 r:core_node16 x:pull_replica_test_create_delete_shard2_replica_p14] o.a.s.s.IndexSchema [pull_replica_test_create_delete_shard2_replica_p14] Schema name=minimal [junit4] 2> 1231885 INFO (qtp1985270943-3973) [n:127.0.0.1:35479_solr c:pull_replica_test_create_delete s:shard2 r:core_node16 x:pull_replica_test_create_delete_shard2_replica_p14] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id [junit4] 2> 1231885 INFO (qtp1985270943-3973) [n:127.0.0.1:35479_solr c:pull_replica_test_create_delete s:shard2 r:core_node16 x:pull_replica_test_create_delete_shard2_replica_p14] o.a.s.c.CoreContainer Creating SolrCore 'pull_replica_test_create_delete_shard2_replica_p14' using configuration from collection pull_replica_test_create_delete, trusted=true [junit4] 2> 1231885 INFO (qtp1985270943-3973) [n:127.0.0.1:35479_solr c:pull_replica_test_create_delete s:shard2 r:core_node16 x:pull_replica_test_create_delete_shard2_replica_p14] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_35479.solr.core.pull_replica_test_create_delete.shard2.replica_p14' (registry 'solr.core.pull_replica_test_create_delete.shard2.replica_p14') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@4314f79a [junit4] 2> 1231904 INFO (qtp1985270943-3973) [n:127.0.0.1:35479_solr c:pull_replica_test_create_delete s:shard2 r:core_node16 x:pull_replica_test_create_delete_shard2_replica_p14] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder [junit4] 2> 1231904 INFO (qtp1985270943-3973) [n:127.0.0.1:35479_solr c:pull_replica_test_create_delete s:shard2 r:core_node16 x:pull_replica_test_create_delete_shard2_replica_p14] o.a.s.c.SolrCore [[pull_replica_test_create_delete_shard2_replica_p14] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.cloud.TestPullReplica_13F1FD94F96B8A5C-001/tempDir-001/node1/pull_replica_test_create_delete_shard2_replica_p14], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.cloud.TestPullReplica_13F1FD94F96B8A5C-001/tempDir-001/node1/./pull_replica_test_create_delete_shard2_replica_p14/data/] [junit4] 2> 1231948 INFO (qtp1985270943-3969) [n:127.0.0.1:35479_solr c:pull_replica_test_create_delete s:shard1 r:core_node10 x:pull_replica_test_create_delete_shard1_replica_p4] o.a.s.s.IndexSchema [pull_replica_test_create_delete_shard1_replica_p4] Schema name=minimal [junit4] 2> 1231950 INFO (qtp1985270943-3969) [n:127.0.0.1:35479_solr c:pull_replica_test_create_delete s:shard1 r:core_node10 x:pull_replica_test_create_delete_shard1_replica_p4] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id [junit4] 2> 1231950 INFO (qtp1985270943-3969) [n:127.0.0.1:35479_solr c:pull_replica_test_create_delete s:shard1 r:core_node10 x:pull_replica_test_create_delete_shard1_replica_p4] o.a.s.c.CoreContainer Creating SolrCore 'pull_replica_test_create_delete_shard1_replica_p4' using configuration from collection pull_replica_test_create_delete, trusted=true [junit4] 2> 1231950 INFO (qtp1985270943-3969) [n:127.0.0.1:35479_solr c:pull_replica_test_create_delete s:shard1 r:core_node10 x:pull_replica_test_create_delete_shard1_replica_p4] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_35479.solr.core.pull_replica_test_create_delete.shard1.replica_p4' (registry 'solr.core.pull_replica_test_create_delete.shard1.replica_p4') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@4314f79a [junit4] 2> 1231950 INFO (qtp1985270943-3969) [n:127.0.0.1:35479_solr c:pull_replica_test_create_delete s:shard1 r:core_node10 x:pull_replica_test_create_delete_shard1_replica_p4] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder [junit4] 2> 1232040 INFO (qtp1985270943-3969) [n:127.0.0.1:35479_solr c:pull_replica_test_create_delete s:shard1 r:core_node10 x:pull_replica_test_create_delete_shard1_replica_p4] o.a.s.c.SolrCore [[pull_replica_test_create_delete_shard1_replica_p4] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.cloud.TestPullReplica_13F1FD94F96B8A5C-001/tempDir-001/node1/pull_replica_test_create_delete_shard1_replica_p4], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.cloud.TestPullReplica_13F1FD94F96B8A5C-001/tempDir-001/node1/./pull_replica_test_create_delete_shard1_replica_p4/data/] [junit4] 2> 1232373 INFO (qtp1985270943-3966) [n:127.0.0.1:35479_solr c:pull_replica_test_create_delete s:shard1 r:core_node7 x:pull_replica_test_create_delete_shard1_replica_p2] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 1232373 INFO (qtp1985270943-3966) [n:127.0.0.1:35479_solr c:pull_replica_test_create_delete s:shard1 r:core_node7 x:pull_replica_test_create_delete_shard1_replica_p2] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 1232375 INFO (qtp1985270943-3966) [n:127.0.0.1:35479_solr c:pull_replica_test_create_delete s:shard1 r:core_node7 x:pull_replica_test_create_delete_shard1_replica_p2] o.a.s.s.SolrIndexSearcher Opening [Searcher@66177d22[pull_replica_test_create_delete_shard1_replica_p2] main] [junit4] 2> 1232377 INFO (qtp1985270943-3966) [n:127.0.0.1:35479_solr c:pull_replica_test_create_delete s:shard1 r:core_node7 x:pull_replica_test_create_delete_shard1_replica_p2] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf [junit4] 2> 1232378 INFO (qtp1985270943-3966) [n:127.0.0.1:35479_solr c:pull_replica_test_create_delete s:shard1 r:core_node7 x:pull_replica_test_create_delete_shard1_replica_p2] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf [junit4] 2> 1232378 INFO (qtp1985270943-3966) [n:127.0.0.1:35479_solr c:pull_replica_test_create_delete s:shard1 r:core_node7 x:pull_replica_test_create_delete_shard1_replica_p2] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms. [junit4] 2> 1232390 INFO (searcherExecutor-1834-thread-1-processing-n:127.0.0.1:35479_solr x:pull_replica_test_create_delete_shard1_replica_p2 c:pull_replica_test_create_delete s:shard1 r:core_node7) [n:127.0.0.1:35479_solr c:pull_replica_test_create_delete s:shard1 r:core_node7 x:pull_replica_test_create_delete_shard1_replica_p2] o.a.s.c.SolrCore [pull_replica_test_create_delete_shard1_replica_p2] Registered new searcher Searcher@66177d22[pull_replica_test_create_delete_shard1_replica_p2] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 1232450 INFO (qtp1985270943-4051) [n:127.0.0.1:35479_solr c:pull_replica_test_create_delete s:shard2 r:core_node13 x:pull_replica_test_create_delete_shard2_replica_p9] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 1232450 INFO (qtp1985270943-4051) [n:127.0.0.1:35479_solr c:pull_replica_test_create_delete s:shard2 r:core_node13 x:pull_replica_test_create_delete_shard2_replica_p9] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 1232469 INFO (qtp1985270943-3966) [n:127.0.0.1:35479_solr c:pull_replica_test_create_delete s:shard1 r:core_node7 x:pull_replica_test_create_delete_shard1_replica_p2] o.a.s.c.ZkController pull_replica_test_create_delete_shard1_replica_p2 starting background replication from leader [junit4] 2> 1232469 INFO (qtp1985270943-3966) [n:127.0.0.1:35479_solr c:pull_replica_test_create_delete s:shard1 r:core_node7 x:pull_replica_test_create_delete_shard1_replica_p2] o.a.s.c.ReplicateFromLeader Will start replication from leader with poll interval: 00:00:03 [junit4] 2> 1232487 INFO (qtp1985270943-4051) [n:127.0.0.1:35479_solr c:pull_replica_test_create_delete s:shard2 r:core_node13 x:pull_replica_test_create_delete_shard2_replica_p9] o.a.s.s.SolrIndexSearcher Opening [Searcher@417e748e[pull_replica_test_create_delete_shard2_replica_p9] main] [junit4] 2> 1232488 INFO (qtp1985270943-4051) [n:127.0.0.1:35479_solr c:pull_replica_test_create_delete s:shard2 r:core_node13 x:pull_replica_test_create_delete_shard2_replica_p9] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf [junit4] 2> 1232488 INFO (qtp1985270943-4051) [n:127.0.0.1:35479_solr c:pull_replica_test_create_delete s:shard2 r:core_node13 x:pull_replica_test_create_delete_shard2_replica_p9] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf [junit4] 2> 1232488 INFO (qtp1985270943-4051) [n:127.0.0.1:35479_solr c:pull_replica_test_create_delete s:shard2 r:core_node13 x:pull_replica_test_create_delete_shard2_replica_p9] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms. [junit4] 2> 1232519 INFO (qtp115826095-3978) [n:127.0.0.1:46601_solr c:pull_replica_test_create_delete s:shard2 r:core_node12 x:pull_replica_test_create_delete_shard2_replica_n6] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 1232519 INFO (qtp115826095-3978) [n:127.0.0.1:46601_solr c:pull_replica_test_create_delete s:shard2 r:core_node12 x:pull_replica_test_create_delete_shard2_replica_n6] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 1232756 INFO (qtp115826095-3978) [n:127.0.0.1:46601_solr c:pull_replica_test_create_delete s:shard2 r:core_node12 x:pull_replica_test_create_delete_shard2_replica_n6] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 1232756 INFO (qtp115826095-3978) [n:127.0.0.1:46601_solr c:pull_replica_test_create_delete s:shard2 r:core_node12 x:pull_replica_test_create_delete_shard2_replica_n6] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 1232751 INFO (searcherExecutor-1836-thread-1-processing-n:127.0.0.1:35479_solr x:pull_replica_test_create_delete_shard2_replica_p9 c:pull_replica_test_create_delete s:shard2 r:core_node13) [n:127.0.0.1:35479_solr c:pull_replica_test_create_delete s:shard2 r:core_node13 x:pull_replica_test_create_delete_shard2_replica_p9] o.a.s.c.SolrCore [pull_replica_test_create_delete_shard2_replica_p9] Registered new searcher Searcher@417e748e[pull_replica_test_create_delete_shard2_replica_p9] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 1232684 INFO (qtp1985270943-3966) [n:127.0.0.1:35479_solr c:pull_replica_test_create_delete s:shard1 r:core_node7 x:pull_replica_test_create_delete_shard1_replica_p2] o.a.s.h.ReplicationHandler Poll scheduled at an interval of 3000ms [junit4] 2> 1232771 INFO (qtp1985270943-3966) [n:127.0.0.1:35479_solr c:pull_replica_test_create_delete s:shard1 r:core_node7 x:pull_replica_test_create_delete_shard1_replica_p2] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms. [junit4] 2> 1232771 INFO (qtp115826095-3978) [n:127.0.0.1:46601_solr c:pull_replica_test_create_delete s:shard2 r:core_node12 x:pull_replica_test_create_delete_shard2_replica_n6] o.a.s.s.SolrIndexSearcher Opening [Searcher@faa8915[pull_replica_test_create_delete_shard2_replica_n6] main] [junit4] 2> 1233016 INFO (qtp1985270943-4051) [n:127.0.0.1:35479_solr c:pull_replica_test_create_delete s:shard2 r:core_node13 x:pull_replica_test_create_delete_shard2_replica_p9] o.a.s.c.ZkController pull_replica_test_create_delete_shard2_replica_p9 starting background replication from leader [junit4] 2> 1233016 INFO (qtp1985270943-4051) [n:127.0.0.1:35479_solr c:pull_replica_test_create_delete s:shard2 r:core_node13 x:pull_replica_test_create_delete_shard2_replica_p9] o.a.s.c.ReplicateFromLeader Will start replication from leader with poll interval: 00:00:03 [junit4] 2> 1233022 INFO (qtp115826095-3970) [n:127.0.0.1:46601_solr c:pull_replica_test_create_delete s:shard1 r:core_node5 x:pull_replica_test_create_delete_shard1_replica_n1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 1233022 INFO (qtp115826095-3970) [n:127.0.0.1:46601_solr c:pull_replica_test_create_delete s:shard1 r:core_node5 x:pull_replica_test_create_delete_shard1_replica_n1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 1233023 INFO (qtp115826095-3970) [n:127.0.0.1:46601_solr c:pull_replica_test_create_delete s:shard1 r:core_node5 x:pull_replica_test_create_delete_shard1_replica_n1] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 1233024 INFO (qtp115826095-3970) [n:127.0.0.1:46601_solr c:pull_replica_test_create_delete s:shard1 r:core_node5 x:pull_replica_test_create_delete_shard1_replica_n1] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 1233025 INFO (qtp115826095-3970) [n:127.0.0.1:46601_solr c:pull_replica_test_create_delete s:shard1 r:core_node5 x:pull_replica_test_create_delete_shard1_replica_n1] o.a.s.s.SolrIndexSearcher Opening [Searcher@6ff7c576[pull_replica_test_create_delete_shard1_replica_n1] main] [junit4] 2> 1233031 INFO (qtp1985270943-4051) [n:127.0.0.1:35479_solr c:pull_replica_test_create_delete s:shard2 r:core_node13 x:pull_replica_test_create_delete_shard2_replica_p9] o.a.s.h.ReplicationHandler Poll scheduled at an interval of 3000ms [junit4] 2> 1233031 INFO (qtp1985270943-4051) [n:127.0.0.1:35479_solr c:pull_replica_test_create_delete s:shard2 r:core_node13 x:pull_replica_test_create_delete_shard2_replica_p9] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms. [junit4] 2> 1233047 INFO (qtp115826095-3976) [n:127.0.0.1:46601_solr c:pull_replica_test_create_delete s:shard2 r:core_node15 x:pull_replica_test_create_delete_shard2_replica_p11] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 1233047 INFO (qtp115826095-3976) [n:127.0.0.1:46601_solr c:pull_replica_test_create_delete s:shard2 r:core_node15 x:pull_replica_test_create_delete_shard2_replica_p11] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 1233063 INFO (qtp115826095-3976) [n:127.0.0.1:46601_solr c:pull_replica_test_create_delete s:shard2 r:core_node15 x:pull_replica_test_create_delete_shard2_replica_p11] o.a.s.s.SolrIndexSearcher Opening [Searcher@6a48bf0a[pull_replica_test_create_delete_shard2_replica_p11] main] [junit4] 2> 1233063 INFO (qtp115826095-3972) [n:127.0.0.1:46601_solr c:pull_replica_test_create_delete s:shard1 r:core_node8 x:pull_replica_test_create_delete_shard1_replica_p3] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 1233063 INFO (qtp115826095-3972) [n:127.0.0.1:46601_solr c:pull_replica_test_create_delete s:shard1 r:core_node8 x:pull_replica_test_create_delete_shard1_replica_p3] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 1233064 INFO (qtp115826095-3978) [n:127.0.0.1:46601_solr c:pull_replica_test_create_delete s:shard2 r:core_node12 x:pull_replica_test_create_delete_shard2_replica_n6] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf [junit4] 2> 1233066 INFO (qtp115826095-3972) [n:127.0.0.1:46601_solr c:pull_replica_test_create_delete s:shard1 r:core_node8 x:pull_replica_test_create_delete_shard1_replica_p3] o.a.s.s.SolrIndexSearcher Opening [Searcher@51ecfb1b[pull_replica_test_create_delete_shard1_replica_p3] main] [junit4] 2> 1233066 INFO (qtp115826095-3976) [n:127.0.0.1:46601_solr c:pull_replica_test_create_delete s:shard2 r:core_node15 x:pull_replica_test_create_delete_shard2_replica_p11] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf [junit4] 2> 1233066 INFO (qtp115826095-3970) [n:127.0.0.1:46601_solr c:pull_replica_test_create_delete s:shard1 r:core_node5 x:pull_replica_test_create_delete_shard1_replica_n1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf [junit4] 2> 1233066 INFO (qtp115826095-3978) [n:127.0.0.1:46601_solr c:pull_replica_test_create_delete s:shard2 r:core_node12 x:pull_replica_test_create_delete_shard2_replica_n6] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf [junit4] 2> 1233066 INFO (qtp115826095-3976) [n:127.0.0.1:46601_solr c:pull_replica_test_create_delete s:shard2 r:core_node15 x:pull_replica_test_create_delete_shard2_replica_p11] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf [junit4] 2> 1233067 INFO (qtp115826095-3978) [n:127.0.0.1:46601_solr c:pull_replica_test_create_delete s:shard2 r:core_node12 x:pull_replica_test_create_delete_shard2_replica_n6] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms. [junit4] 2> 1233067 INFO (qtp115826095-3978) [n:127.0.0.1:46601_solr c:pull_replica_test_create_delete s:shard2 r:core_node12 x:pull_replica_test_create_delete_shard2_replica_n6] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1599969837053902848 [junit4] 2> 1233084 INFO (searcherExecutor-1832-thread-1-processing-n:127.0.0.1:46601_solr x:pull_replica_test_create_delete_shard2_replica_n6 c:pull_replica_test_create_delete s:shard2 r:core_node12) [n:127.0.0.1:46601_solr c:pull_replica_test_create_delete s:shard2 r:core_node12 x:pull_replica_test_create_delete_shard2_replica_n6] o.a.s.c.SolrCore [pull_replica_test_create_delete_shard2_replica_n6] Registered new searcher Searcher@faa8915[pull_replica_test_create_delete_shard2_replica_n6] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 1233084 INFO (qtp115826095-3970) [n:127.0.0.1:46601_solr c:pull_replica_test_create_delete s:shard1 r:core_node5 x:pull_replica_test_create_delete_shard1_replica_n1] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf [junit4] 2> 1233084 INFO (qtp115826095-3970) [n:127.0.0.1:46601_solr c:pull_replica_test_create_delete s:shard1 r:core_node5 x:pull_replica_test_create_delete_shard1_replica_n1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms. [junit4] 2> 1233085 INFO (searcherExecutor-1837-thread-1-processing-n:127.0.0.1:46601_solr x:pull_replica_test_create_delete_shard1_replica_n1 c:pull_replica_test_create_delete s:shard1 r:core_node5) [n:127.0.0.1:46601_solr c:pull_replica_test_create_delete s:shard1 r:core_node5 x:pull_replica_test_create_delete_shard1_replica_n1] o.a.s.c.SolrCore [pull_replica_test_create_delete_shard1_replica_n1] Registered new searcher Searcher@6ff7c576[pull_replica_test_create_delete_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 1233085 INFO (qtp115826095-3970) [n:127.0.0.1:46601_solr c:pull_replica_test_create_delete s:shard1 r:core_node5 x:pull_replica_test_create_delete_shard1_replica_n1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1599969837072777216 [junit4] 2> 1233100 INFO (qtp115826095-3972) [n:127.0.0.1:46601_solr c:pull_replica_test_create_delete s:shard1 r:core_node8 x:pull_replica_test_create_delete_shard1_replica_p3] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf [junit4] 2> 1233100 INFO (qtp115826095-3976) [n:127.0.0.1:46601_solr c:pull_replica_test_create_delete s:shard2 r:core_node15 x:pull_replica_test_create_delete_shard2_replica_p11] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms. [junit4] 2> 1233100 INFO (qtp115826095-3972) [n:127.0.0.1:46601_solr c:pull_replica_test_create_delete s:shard1 r:core_node8 x:pull_replica_test_create_delete_shard1_replica_p3] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf [junit4] 2> 1233101 INFO (qtp115826095-3972) [n:127.0.0.1:46601_solr c:pull_replica_test_create_delete s:shard1 r:core_node8 x:pull_replica_test_create_delete_shard1_replica_p3] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms. [junit4] 2> 1233102 INFO (searcherExecutor-1833-thread-1-processing-n:127.0.0.1:46601_solr x:pull_replica_test_create_delete_shard1_replica_p3 c:pull_replica_test_create_delete s:shard1 r:core_node8) [n:127.0.0.1:46601_solr c:pull_replica_test_create_delete s:shard1 r:core_node8 x:pull_replica_test_create_delete_shard1_replica_p3] o.a.s.c.SolrCore [pull_replica_test_create_delete_shard1_replica_p3] Registered new searcher Searcher@51ecfb1b[pull_replica_test_create_delete_shard1_replica_p3] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 1233104 INFO (qtp1985270943-3973) [n:127.0.0.1:35479_solr c:pull_replica_test_create_delete s:shard2 r:core_node16 x:pull_replica_test_create_delete_shard2_replica_p14] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 1233104 INFO (qtp1985270943-3973) [n:127.0.0.1:35479_solr c:pull_replica_test_create_delete s:shard2 r:core_node16 x:pull_replica_test_create_delete_shard2_replica_p14] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 1233125 INFO (searcherExecutor-1835-thread-1-processing-n:127.0.0.1:46601_solr x:pull_replica_test_create_delete_shard2_replica_p11 c:pull_replica_test_create_delete s:shard2 r:core_node15) [n:127.0.0.1:46601_solr c:pull_replica_test_create_delete s:shard2 r:core_node15 x:pull_replica_test_create_delete_shard2_replica_p11] o.a.s.c.SolrCore [pull_replica_test_create_delete_shard2_replica_p11] Registered new searcher Searcher@6a48bf0a[pull_replica_test_create_delete_shard2_replica_p11] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 1233173 INFO (qtp1985270943-3973) [n:127.0.0.1:35479_solr c:pull_replica_test_create_delete s:shard2 r:core_node16 x:pull_replica_test_create_delete_shard2_replica_p14] o.a.s.s.SolrIndexSearcher Opening [Searcher@10c80417[pull_replica_test_create_delete_shard2_replica_p14] main] [junit4] 2> 1233182 INFO (qtp1985270943-3973) [n:127.0.0.1:35479_solr c:pull_replica_test_create_delete s:shard2 r:core_node16 x:pull_replica_test_create_delete_shard2_replica_p14] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf [junit4] 2> 1233182 INFO (qtp1985270943-3973) [n:127.0.0.1:35479_solr c:pull_replica_test_create_delete s:shard2 r:core_node16 x:pull_replica_test_create_delete_shard2_replica_p14] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf [junit4] 2> 1233183 INFO (qtp1985270943-3973) [n:127.0.0.1:35479_solr c:pull_replica_test_create_delete s:shard2 r:core_node16 x:pull_replica_test_create_delete_shard2_replica_p14] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms. [junit4] 2> 1233184 INFO (qtp1985270943-3973) [n:127.0.0.1:35479_solr c:pull_replica_test_create_delete s:shard2 r:core_node16 x:pull_replica_test_create_delete_shard2_replica_p14] o.a.s.c.ZkController pull_replica_test_create_delete_shard2_replica_p14 starting background replication from leader [junit4] 2> 1233184 INFO (qtp1985270943-3973) [n:127.0.0.1:35479_solr c:pull_replica_test_create_delete s:shard2 r:core_node16 x:pull_replica_test_create_delete_shard2_replica_p14] o.a.s.c.ReplicateFromLeader Will start replication from leader with poll interval: 00:00:03 [junit4] 2> 1233193 INFO (searcherExecutor-1838-thread-1-processing-n:127.0.0.1:35479_solr x:pull_replica_test_create_delete_shard2_replica_p14 c:pull_replica_test_create_delete s:shard2 r:core_node16) [n:127.0.0.1:35479_solr c:pull_replica_test_create_delete s:shard2 r:core_node16 x:pull_replica_test_create_delete_shard2_replica_p14] o.a.s.c.SolrCore [pull_replica_test_create_delete_shard2_replica_p14] Registered new searcher Searcher@10c80417[pull_replica_test_create_delete_shard2_replica_p14] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 1233209 INFO (qtp115826095-3970) [n:127.0.0.1:46601_solr c:pull_replica_test_create_delete s:shard1 r:core_node5 x:pull_replica_test_create_delete_shard1_replica_n1] o.a.s.c.ZkShardTerms Successful update of terms at /collections/pull_replica_test_create_delete/terms/shard1 to Terms{values={core_node5=0}, version=0} [junit4] 2> 1233310 INFO (qtp1985270943-3973) [n:127.0.0.1:35479_solr c:pull_replica_test_create_delete s:shard2 r:core_node16 x:pull_replica_test_create_delete_shard2_replica_p14] o.a.s.h.ReplicationHandler Poll scheduled at an interval of 3000ms [junit4] 2> 1233310 INFO (qtp1985270943-3973) [n:127.0.0.1:35479_solr c:pull_replica_test_create_delete s:shard2 r:core_node16 x:pull_replica_test_create_delete_shard2_replica_p14] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms. [junit4] 2> 1233327 INFO (qtp1985270943-3969) [n:127.0.0.1:35479_solr c:pull_replica_test_create_delete s:shard1 r:core_node10 x:pull_replica_test_create_delete_shard1_replica_p4] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 1233327 INFO (qtp1985270943-3969) [n:127.0.0.1:35479_solr c:pull_replica_test_create_delete s:shard1 r:core_node10 x:pull_replica_test_create_delete_shard1_replica_p4] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 1233329 INFO (qtp1985270943-3969) [n:127.0.0.1:35479_solr c:pull_replica_test_create_delete s:shard1 r:core_node10 x:pull_replica_test_create_delete_shard1_replica_p4] o.a.s.s.SolrIndexSearcher Opening [Searcher@58080549[pull_replica_test_create_delete_shard1_replica_p4] main] [junit4] 2> 1233330 INFO (qtp1985270943-3969) [n:127.0.0.1:35479_solr c:pull_replica_test_create_delete s:shard1 r:core_node10 x:pull_replica_test_create_delete_shard1_replica_p4] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf [junit4] 2> 1233330 INFO (qtp1985270943-3969) [n:127.0.0.1:35479_solr c:pull_replica_test_create_delete s:shard1 r:core_node10 x:pull_replica_test_create_delete_shard1_replica_p4] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf [junit4] 2> 1233335 INFO (qtp115826095-3972) [n:127.0.0.1:46601_solr c:pull_replica_test_create_delete s:shard1 r:core_node8 x:pull_replica_test_create_delete_shard1_replica_p3] o.a.s.c.ZkController pull_replica_test_create_delete_shard1_replica_p3 starting background replication from leader [junit4] 2> 1233335 INFO (qtp115826095-3972) [n:127.0.0.1:46601_solr c:pull_replica_test_create_delete s:shard1 r:core_node8 x:pull_replica_test_create_delete_shard1_replica_p3] o.a.s.c.ReplicateFromLeader Will start replication from leader with poll interval: 00:00:03 [junit4] 2> 1233336 INFO (qtp115826095-3976) [n:127.0.0.1:46601_solr c:pull_replica_test_create_delete s:shard2 r:core_node15 x:pull_replica_test_create_delete_shard2_replica_p11] o.a.s.c.ZkController pull_replica_test_create_delete_shard2_replica_p11 starting background replication from leader [junit4] 2> 1233336 INFO (qtp115826095-3976) [n:127.0.0.1:46601_solr c:pull_replica_test_create_delete s:shard2 r:core_node15 x:pull_replica_test_create_delete_shard2_replica_p11] o.a.s.c.ReplicateFromLeader Will start replication from leader with poll interval: 00:00:03 [junit4] 2> 1233339 INFO (qtp115826095-3972) [n:127.0.0.1:46601_solr c:pull_replica_test_create_delete s:shard1 r:core_node8 x:pull_replica_test_create_delete_shard1_replica_p3] o.a.s.h.ReplicationHandler Poll scheduled at an interval of 3000ms [junit4] 2> 1233339 INFO (qtp115826095-3972) [n:127.0.0.1:46601_solr c:pull_replica_test_create_delete s:shard1 r:core_node8 x:pull_replica_test_create_delete_shard1_replica_p3] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms. [junit4] 2> 1233355 INFO (qtp115826095-3976) [n:127.0.0.1:46601_solr c:pull_replica_test_create_delete s:shard2 r:core_node15 x:pull_replica_test_create_delete_shard2_replica_p11] o.a.s.h.ReplicationHandler Poll scheduled at an interval of 3000ms [junit4] 2> 1233392 INFO (qtp115826095-3976) [n:127.0.0.1:46601_solr c:pull_replica_test_create_delete s:shard2 r:core_node15 x:pull_replica_test_create_delete_shard2_replica_p11] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms. [junit4] 2> 1233392 INFO (qtp115826095-3978) [n:127.0.0.1:46601_solr c:pull_replica_test_create_delete s:shard2 r:core_node12 x:pull_replica_test_create_delete_shard2_replica_n6] o.a.s.c.ZkShardTerms Successful update of terms at /collections/pull_replica_test_create_delete/terms/shard2 to Terms{values={core_node12=0}, version=0} [junit4] 2> 1233372 INFO (qtp1985270943-3969) [n:127.0.0.1:35479_solr c:pull_replica_test_create_delete s:shard1 r:core_node10 x:pull_replica_test_create_delete_shard1_replica_p4] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms. [junit4] 2> 1233400 INFO (searcherExecutor-1839-thread-1-processing-n:127.0.0.1:35479_solr x:pull_replica_test_create_delete_shard1_replica_p4 c:pull_replica_test_create_delete s:shard1 r:core_node10) [n:127.0.0.1:35479_solr c:pull_replica_test_create_delete s:shard1 r:core_node10 x:pull_replica_test_create_delete_shard1_replica_p4] o.a.s.c.SolrCore [pull_replica_test_create_delete_shard1_replica_p4] Registered new searcher Searcher@58080549[pull_replica_test_create_delete_shard1_replica_p4] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 1233446 INFO (qtp1985270943-3969) [n:127.0.0.1:35479_solr c:pull_replica_test_create_delete s:shard1 r:core_node10 x:pull_replica_test_create_delete_shard1_replica_p4] o.a.s.c.ZkController pull_replica_test_create_delete_shard1_replica_p4 starting background replication from leader [junit4] 2> 1233446 INFO (qtp1985270943-3969) [n:127.0.0.1:35479_solr c:pull_replica_test_create_delete s:shard1 r:core_node10 x:pull_replica_test_create_delete_shard1_replica_p4] o.a.s.c.ReplicateFromLeader Will start replication from leader with poll interval: 00:00:03 [junit4] 2> 1233465 INFO (qtp1985270943-3969) [n:127.0.0.1:35479_solr c:pull_replica_test_create_delete s:shard1 r:core_node10 x:pull_replica_test_create_delete_shard1_replica_p4] o.a.s.h.ReplicationHandler Poll scheduled at an interval of 3000ms [junit4] 2> 1233465 INFO (qtp1985270943-3969) [n:127.0.0.1:35479_solr c:pull_replica_test_create_delete s:shard1 r:core_node10 x:pull_replica_test_create_delete_shard1_replica_p4] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms. [junit4] 2> 1233684 INFO (qtp115826095-3970) [n:127.0.0.1:46601_solr c:pull_replica_test_create_delete s:shard1 r:core_node5 x:pull_replica_test_create_delete_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue. [junit4] 2> 1233684 INFO (qtp115826095-3970) [n:127.0.0.1:46601_solr c:pull_replica_test_create_delete s:shard1 r:core_node5 x:pull_replica_test_create_delete_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync [junit4] 2> 1233684 INFO (qtp115826095-3970) [n:127.0.0.1:46601_solr c:pull_replica_test_create_delete s:shard1 r:core_node5 x:pull_replica_test_create_delete_shard1_replica_n1] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:46601/solr/pull_replica_test_create_delete_shard1_replica_n1/ [junit4] 2> 1233700 INFO (qtp115826095-3970) [n:127.0.0.1:46601_solr c:pull_replica_test_create_delete s:shard1 r:core_node5 x:pull_replica_test_create_delete_shard1_replica_n1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me [junit4] 2> 1233720 INFO (qtp115826095-3970) [n:127.0.0.1:46601_solr c:pull_replica_test_create_delete s:shard1 r:core_node5 x:pull_replica_test_create_delete_shard1_replica_n1] o.a.s.c.SyncStrategy http://127.0.0.1:46601/solr/pull_replica_test_create_delete_shard1_replica_n1/ has no replicas [junit4] 2> 1233721 INFO (qtp115826095-3970) [n:127.0.0.1:46601_solr c:pull_replica_test_create_delete s:shard1 r:core_node5 x:pull_replica_test_create_delete_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext Found all replicas participating in election, clear LIR [junit4] 2> 1233850 INFO (qtp115826095-3978) [n:127.0.0.1:46601_solr c:pull_replica_test_create_delete s:shard2 r:core_node12 x:pull_replica_test_create_delete_shard2_replica_n6] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue. [junit4] 2> 1233850 INFO (qtp115826095-3978) [n:127.0.0.1:46601_solr c:pull_replica_test_create_delete s:shard2 r:core_node12 x:pull_replica_test_create_delete_shard2_replica_n6] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync [junit4] 2> 1233850 INFO (qtp115826095-3978) [n:127.0.0.1:46601_solr c:pull_replica_test_create_delete s:shard2 r:core_node12 x:pull_replica_test_create_delete_shard2_replica_n6] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:46601/solr/pull_replica_test_create_delete_shard2_replica_n6/ [junit4] 2> 1233887 INFO (qtp115826095-3978) [n:127.0.0.1:46601_solr c:pull_replica_test_create_delete s:shard2 r:core_node12 x:pull_replica_test_create_delete_shard2_replica_n6] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me [junit4] 2> 1233923 INFO (qtp115826095-3978) [n:127.0.0.1:46601_solr c:pull_replica_test_create_delete s:shard2 r:core_node12 x:pull_replica_test_create_delete_shard2_replica_n6] o.a.s.c.SyncStrategy http://127.0.0.1:46601/solr/pull_replica_test_create_delete_shard2_replica_n6/ has no replicas [junit4] 2> 1233923 INFO (qtp115826095-3978) [n:127.0.0.1:46601_solr c:pull_replica_test_create_delete s:shard2 r:core_node12 x:pull_replica_test_create_delete_shard2_replica_n6] o.a.s.c.ShardLeaderElectionContext Found all replicas participating in election, clear LIR [junit4] 2> 1234029 INFO (qtp115826095-3970) [n:127.0.0.1:46601_solr c:pull_replica_test_create_delete s:shard1 r:core_node5 x:pull_replica_test_create_delete_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:46601/solr/pull_replica_test_create_delete_shard1_replica_n1/ shard1 [junit4] 2> 1234088 INFO (qtp115826095-3978) [n:127.0.0.1:46601_solr c:pull_replica_test_create_delete s:shard2 r:core_node12 x:pull_replica_test_create_delete_shard2_replica_n6] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:46601/solr/pull_replica_test_create_delete_shard2_replica_n6/ shard2 [junit4] 2> 1234287 INFO (qtp1985270943-3966) [n:127.0.0.1:35479_solr c:pull_replica_test_create_delete s:shard1 r:core_node7 x:pull_replica_test_create_delete_shard1_replica_p2] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node7&collection.configName=conf&newCollection=true&name=pull_replica_test_create_delete_shard1_replica_p2&action=CREATE&numShards=2&collection=pull_replica_test_create_delete&shard=shard1&wt=javabin&version=2&replicaType=PULL} status=0 QTime=4503 [junit4] 2> 1234292 INFO (indexFetcher-1858-thread-1) [ ] o.a.s.h.IndexFetcher Replica core_node12 is leader but it's state is down, skipping replication [junit4] 2> 1234300 INFO (indexFetcher-1873-thread-1) [ ] o.a.s.h.IndexFetcher Replica core_node5 is leader but it's state is down, skipping replication [junit4] 2> 1234302 INFO (indexFetcher-1875-thread-1) [ ] o.a.s.h.IndexFetcher Replica core_node12 is leader but it's state is down, skipping replication [junit4] 2> 1234303 INFO (qtp115826095-3978) [n:127.0.0.1:46601_solr c:pull_replica_test_create_delete s:shard2 r:core_node12 x:pull_replica_test_create_delete_shard2_replica_n6] o.a.s.c.ZkController I am the leader, no recovery necessary [junit4] 2> 1234304 INFO (qtp115826095-3970) [n:127.0.0.1:46601_solr c:pull_replica_test_create_delete s:shard1 r:core_node5 x:pull_replica_test_create_delete_shard1_replica_n1] o.a.s.c.ZkController I am the leader, no recovery necessary [junit4] 2> 1234329 INFO (indexFetcher-1852-thread-1) [ ] o.a.s.h.IndexFetcher Replica core_node5 is leader but it's state is down, skipping replication [junit4] 2> 1234338 INFO (qtp115826095-3978) [n:127.0.0.1:46601_solr c:pull_replica_test_create_delete s:shard2 r:core_node12 x:pull_replica_test_create_delete_shard2_replica_n6] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node12&collection.configName=conf&newCollection=true&name=pull_replica_test_create_delete_shard2_replica_n6&action=CREATE&numShards=2&collection=pull_replica_test_create_delete&shard=shard2&wt=javabin&version=2&replicaType=NRT} status=0 QTime=4554 [junit4] 2> 1234339 INFO (qtp115826095-3970) [n:127.0.0.1:46601_solr c:pull_replica_test_create_delete s:shard1 r:core_node5 x:pull_replica_test_create_delete_shard1_replica_n1] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node5&collection.configName=conf&newCollection=true&name=pull_replica_test_create_delete_shard1_replica_n1&action=CREATE&numShards=2&collection=pull_replica_test_create_delete&shard=shard1&wt=javabin&version=2&replicaType=NRT} status=0 QTime=4571 [junit4] 2> 1234496 INFO (qtp1985270943-3973) [n:127.0.0.1:35479_solr c:pull_replica_test_create_delete s:shard2 r:core_node16 x:pull_replica_test_create_delete_shard2_replica_p14] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node16&collection.configName=conf&newCollection=true&name=pull_replica_test_create_delete_shard2_replica_p14&action=CREATE&numShards=2&collection=pull_replica_test_create_delete&shard=shard2&wt=javabin&version=2&replicaType=PULL} status=0 QTime=4693 [junit4] 2> 1234506 INFO (qtp115826095-3976) [n:127.0.0.1:46601_solr c:pull_replica_test_create_delete s:shard2 r:core_node15 x:pull_replica_test_create_delete_shard2_replica_p11] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node15&collection.configName=conf&newCollection=true&name=pull_replica_test_create_delete_shard2_replica_p11&action=CREATE&numShards=2&collection=pull_replica_test_create_delete&shard=shard2&wt=javabin&version=2&replicaType=PULL} status=0 QTime=4683 [junit4] 2> 1234507 INFO (qtp1985270943-3969) [n:127.0.0.1:35479_solr c:pull_replica_test_create_delete s:shard1 r:core_node10 x:pull_replica_test_create_delete_shard1_replica_p4] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node10&collection.configName=conf&newCollection=true&name=pull_replica_test_create_delete_shard1_replica_p4&action=CREATE&numShards=2&collection=pull_replica_test_create_delete&shard=shard1&wt=javabin&version=2&replicaType=PULL} status=0 QTime=4468 [junit4] 2> 1234508 INFO (indexFetcher-1877-thread-1) [ ] o.a.s.h.IndexFetcher Replica core_node5 is leader but it's state is down, skipping replication [junit4] 2> 1234618 INFO (qtp115826095-3972) [n:127.0.0.1:46601_solr c:pull_replica_test_create_delete s:shard1 r:core_node8 x:pull_replica_test_create_delete_shard1_replica_p3] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node8&collection.configName=conf&newCollection=true&name=pull_replica_test_create_delete_shard1_replica_p3&action=CREATE&numShards=2&collection=pull_replica_test_create_delete&shard=shard1&wt=javabin&version=2&replicaType=PULL} status=0 QTime=4832 [junit4] 2> 1234796 INFO (zkCallback-1053-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/pull_replica_test_create_delete/state.json] for collection [pull_replica_test_create_delete] has occurred - updating... (live nodes size: [2]) [junit4] 2> 1234804 INFO (zkCallback-1045-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/pull_replica_test_create_delete/state.json] for collection [pull_replica_test_create_delete] has occurred - updating... (live nodes size: [2]) [junit4] 2> 1235170 INFO (qtp1985270943-4051) [n:127.0.0.1:35479_solr c:pull_replica_test_create_delete s:shard2 r:core_node13 x:pull_replica_test_create_delete_shard2_replica_p9] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node13&collection.configName=conf&newCollection=true&name=pull_replica_test_create_delete_shard2_replica_p9&action=CREATE&numShards=2&collection=pull_replica_test_create_delete&shard=shard2&wt=javabin&version=2&replicaType=PULL} status=0 QTime=5163 [junit4] 2> 1235252 INFO (qtp115826095-3979) [n:127.0.0.1:46601_solr ] o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most 30 seconds. Check all shard replicas [junit4] [...truncated too long message...] 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/null1689893229 [copy] Copying 40 files to /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/null1689893229 [delete] Deleting directory /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/null1689893229 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/null1723654580 [copy] Copying 246 files to /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/null1723654580 [delete] Deleting directory /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/null1723654580 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 93ms :: artifacts dl 10ms --------------------------------------------------------------------- | | 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: 279 minutes 12 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)
--------------------------------------------------------------------- To unsubscribe, e-mail: dev-unsubscr...@lucene.apache.org For additional commands, e-mail: dev-h...@lucene.apache.org
