Build: https://builds.apache.org/job/Lucene-Solr-Tests-8.x/169/
2 tests failed. FAILED: org.apache.solr.cloud.AliasIntegrationTest.testClusterStateProviderAPI Error Message: {} expected:<2> but was:<0> Stack Trace: java.lang.AssertionError: {} expected:<2> but was:<0> at __randomizedtesting.SeedInfo.seed([729207E9B650E1FB:6D459BC5C55B18B0]:0) at org.junit.Assert.fail(Assert.java:88) at org.junit.Assert.failNotEquals(Assert.java:834) at org.junit.Assert.assertEquals(Assert.java:645) at org.apache.solr.cloud.AliasIntegrationTest.testClusterStateProviderAPI(AliasIntegrationTest.java:291) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:498) at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1750) at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:938) at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:974) at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:988) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368) at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817) at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468) at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:947) at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:832) at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:883) at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:894) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368) at java.lang.Thread.run(Thread.java:748) FAILED: org.apache.solr.common.cloud.TestCollectionStateWatchers.testWaitForStateWatcherIsRetainedOnPredicateFailure Error Message: Did not see a fully active cluster after 30 seconds Stack Trace: java.lang.AssertionError: Did not see a fully active cluster after 30 seconds at __randomizedtesting.SeedInfo.seed([904D8DF837421588:187B2FABEFEDFD9A]:0) at org.junit.Assert.fail(Assert.java:88) at org.junit.Assert.assertTrue(Assert.java:41) at org.apache.solr.common.cloud.TestCollectionStateWatchers.testWaitForStateWatcherIsRetainedOnPredicateFailure(TestCollectionStateWatchers.java:261) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:498) at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1750) at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:938) at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:974) at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:988) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368) at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817) at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468) at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:947) at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:832) at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:883) at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:894) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368) at java.lang.Thread.run(Thread.java:748) Build Log: [...truncated 13772 lines...] [junit4] Suite: org.apache.solr.cloud.AliasIntegrationTest [junit4] 2> Creating dataDir: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.x/solr/build/solr-core/test/J1/temp/solr.cloud.AliasIntegrationTest_729207E9B650E1FB-001/init-core-data-001 [junit4] 2> 1830481 INFO (SUITE-AliasIntegrationTest-seed#[729207E9B650E1FB]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=true [junit4] 2> 1830482 INFO (SUITE-AliasIntegrationTest-seed#[729207E9B650E1FB]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (true) via: @org.apache.solr.util.RandomizeSSL(reason=, ssl=NaN, value=NaN, clientAuth=NaN) [junit4] 2> 1830483 INFO (SUITE-AliasIntegrationTest-seed#[729207E9B650E1FB]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom [junit4] 2> 1830483 INFO (SUITE-AliasIntegrationTest-seed#[729207E9B650E1FB]-worker) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 2 servers in /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.x/solr/build/solr-core/test/J1/temp/solr.cloud.AliasIntegrationTest_729207E9B650E1FB-001/tempDir-001 [junit4] 2> 1830483 INFO (SUITE-AliasIntegrationTest-seed#[729207E9B650E1FB]-worker) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER [junit4] 2> 1830517 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0 [junit4] 2> 1830517 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Starting server [junit4] 2> 1830610 INFO (SUITE-AliasIntegrationTest-seed#[729207E9B650E1FB]-worker) [ ] o.a.s.c.ZkTestServer start zk server on port:35072 [junit4] 2> 1830610 INFO (SUITE-AliasIntegrationTest-seed#[729207E9B650E1FB]-worker) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:35072 [junit4] 2> 1830610 INFO (SUITE-AliasIntegrationTest-seed#[729207E9B650E1FB]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 35072 [junit4] 2> 1830756 INFO (zkConnectionManagerCallback-3725-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1830837 INFO (zkConnectionManagerCallback-3727-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1830882 INFO (zkConnectionManagerCallback-3729-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1830912 WARN (jetty-launcher-3730-thread-1) [ ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time [junit4] 2> 1830912 INFO (jetty-launcher-3730-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0) [junit4] 2> 1830912 INFO (jetty-launcher-3730-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ... [junit4] 2> 1830912 INFO (jetty-launcher-3730-thread-1) [ ] o.e.j.s.Server jetty-9.4.14.v20181114; built: 2018-11-14T21:20:31.478Z; git: c4550056e785fb5665914545889f21dc136ad9e6; jvm 1.8.0_191-b12 [junit4] 2> 1830939 WARN (jetty-launcher-3730-thread-2) [ ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time [junit4] 2> 1830939 INFO (jetty-launcher-3730-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0) [junit4] 2> 1830939 INFO (jetty-launcher-3730-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ... [junit4] 2> 1830939 INFO (jetty-launcher-3730-thread-2) [ ] o.e.j.s.Server jetty-9.4.14.v20181114; built: 2018-11-14T21:20:31.478Z; git: c4550056e785fb5665914545889f21dc136ad9e6; jvm 1.8.0_191-b12 [junit4] 2> 1831003 INFO (jetty-launcher-3730-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0 [junit4] 2> 1831003 INFO (jetty-launcher-3730-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults [junit4] 2> 1831003 INFO (jetty-launcher-3730-thread-1) [ ] o.e.j.s.session node0 Scavenging every 660000ms [junit4] 2> 1831019 INFO (jetty-launcher-3730-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@641c5711{/solr,null,AVAILABLE} [junit4] 2> 1831020 WARN (jetty-launcher-3730-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@63c4f183[provider=null,keyStore=null,trustStore=null] [junit4] 2> 1831021 INFO (jetty-launcher-3730-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@7d9ff56e{SSL,[ssl, http/1.1]}{127.0.0.1:43046} [junit4] 2> 1831021 INFO (jetty-launcher-3730-thread-1) [ ] o.e.j.s.Server Started @1831086ms [junit4] 2> 1831021 INFO (jetty-launcher-3730-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=43046} [junit4] 2> 1831021 ERROR (jetty-launcher-3730-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 1831021 INFO (jetty-launcher-3730-thread-1) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory [junit4] 2> 1831021 INFO (jetty-launcher-3730-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.1.0 [junit4] 2> 1831021 INFO (jetty-launcher-3730-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 1831021 INFO (jetty-launcher-3730-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 1831021 INFO (jetty-launcher-3730-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2019-04-26T05:01:20.724Z [junit4] 2> 1831076 INFO (zkConnectionManagerCallback-3732-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1831076 INFO (jetty-launcher-3730-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 1831201 INFO (jetty-launcher-3730-thread-2) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0 [junit4] 2> 1831201 INFO (jetty-launcher-3730-thread-2) [ ] o.e.j.s.session No SessionScavenger set, using defaults [junit4] 2> 1831202 INFO (jetty-launcher-3730-thread-2) [ ] o.e.j.s.session node0 Scavenging every 660000ms [junit4] 2> 1831210 INFO (jetty-launcher-3730-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@6eb97f61{/solr,null,AVAILABLE} [junit4] 2> 1831210 WARN (jetty-launcher-3730-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@44bc421b[provider=null,keyStore=null,trustStore=null] [junit4] 2> 1831222 INFO (jetty-launcher-3730-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@79b1b107{SSL,[ssl, http/1.1]}{127.0.0.1:37645} [junit4] 2> 1831222 INFO (jetty-launcher-3730-thread-2) [ ] o.e.j.s.Server Started @1831287ms [junit4] 2> 1831222 INFO (jetty-launcher-3730-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=37645} [junit4] 2> 1831222 ERROR (jetty-launcher-3730-thread-2) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 1831222 INFO (jetty-launcher-3730-thread-2) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory [junit4] 2> 1831222 INFO (jetty-launcher-3730-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.1.0 [junit4] 2> 1831222 INFO (jetty-launcher-3730-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 1831222 INFO (jetty-launcher-3730-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 1831222 INFO (jetty-launcher-3730-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2019-04-26T05:01:20.925Z [junit4] 2> 1831258 INFO (zkConnectionManagerCallback-3734-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1831266 INFO (jetty-launcher-3730-thread-2) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 1831966 INFO (jetty-launcher-3730-thread-1) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true] [junit4] 2> 1831966 WARN (jetty-launcher-3730-thread-1) [ ] o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 transport since Java 8 or lower versions does not support SSL + HTTP/2 [junit4] 2> 1831967 WARN (jetty-launcher-3730-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@1bf9bf4e[provider=null,keyStore=null,trustStore=null] [junit4] 2> 1831997 WARN (jetty-launcher-3730-thread-1) [ ] o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 transport since Java 8 or lower versions does not support SSL + HTTP/2 [junit4] 2> 1832025 WARN (jetty-launcher-3730-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@52ff2fce[provider=null,keyStore=null,trustStore=null] [junit4] 2> 1832026 INFO (jetty-launcher-3730-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:35072/solr [junit4] 2> 1832044 INFO (zkConnectionManagerCallback-3742-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1832069 INFO (zkConnectionManagerCallback-3744-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1832218 INFO (jetty-launcher-3730-thread-2) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true] [junit4] 2> 1832251 WARN (jetty-launcher-3730-thread-2) [ ] o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 transport since Java 8 or lower versions does not support SSL + HTTP/2 [junit4] 2> 1832310 WARN (jetty-launcher-3730-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@363f7c71[provider=null,keyStore=null,trustStore=null] [junit4] 2> 1832325 WARN (jetty-launcher-3730-thread-2) [ ] o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 transport since Java 8 or lower versions does not support SSL + HTTP/2 [junit4] 2> 1832340 WARN (jetty-launcher-3730-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@3566b151[provider=null,keyStore=null,trustStore=null] [junit4] 2> 1832342 INFO (jetty-launcher-3730-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:35072/solr [junit4] 2> 1832377 INFO (zkConnectionManagerCallback-3752-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1832414 INFO (zkConnectionManagerCallback-3754-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1832455 INFO (jetty-launcher-3730-thread-2) [n:127.0.0.1:37645_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:37645_solr [junit4] 2> 1832455 INFO (jetty-launcher-3730-thread-2) [n:127.0.0.1:37645_solr ] o.a.s.c.Overseer Overseer (id=74586777775898632-127.0.0.1:37645_solr-n_0000000000) starting [junit4] 2> 1832480 INFO (zkConnectionManagerCallback-3761-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1832494 INFO (jetty-launcher-3730-thread-2) [n:127.0.0.1:37645_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:35072/solr ready [junit4] 2> 1832547 INFO (OverseerStateUpdate-74586777775898632-127.0.0.1:37645_solr-n_0000000000) [n:127.0.0.1:37645_solr ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:37645_solr [junit4] 2> 1832547 INFO (jetty-launcher-3730-thread-2) [n:127.0.0.1:37645_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:37645_solr [junit4] 2> 1832548 INFO (OverseerStateUpdate-74586777775898632-127.0.0.1:37645_solr-n_0000000000) [n:127.0.0.1:37645_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 1832596 INFO (zkCallback-3760-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 1832818 INFO (jetty-launcher-3730-thread-2) [n:127.0.0.1:37645_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory. [junit4] 2> 1833029 INFO (jetty-launcher-3730-thread-2) [n:127.0.0.1:37645_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_37645.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@66e6f876 [junit4] 2> 1833109 INFO (jetty-launcher-3730-thread-2) [n:127.0.0.1:37645_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_37645.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@66e6f876 [junit4] 2> 1833109 INFO (jetty-launcher-3730-thread-2) [n:127.0.0.1:37645_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_37645.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@66e6f876 [junit4] 2> 1833110 INFO (jetty-launcher-3730-thread-2) [n:127.0.0.1:37645_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.x/solr/build/solr-core/test/J1/temp/solr.cloud.AliasIntegrationTest_729207E9B650E1FB-001/tempDir-001/node2/. [junit4] 2> 1833753 INFO (jetty-launcher-3730-thread-1) [n:127.0.0.1:43046_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 1833764 INFO (jetty-launcher-3730-thread-1) [n:127.0.0.1:43046_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:43046_solr as DOWN [junit4] 2> 1833765 INFO (jetty-launcher-3730-thread-1) [n:127.0.0.1:43046_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores [junit4] 2> 1833765 INFO (jetty-launcher-3730-thread-1) [n:127.0.0.1:43046_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:43046_solr [junit4] 2> 1833766 INFO (zkCallback-3753-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2) [junit4] 2> 1833784 INFO (zkCallback-3743-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2) [junit4] 2> 1833807 INFO (zkCallback-3760-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2) [junit4] 2> 1833920 INFO (zkConnectionManagerCallback-3767-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1833938 INFO (jetty-launcher-3730-thread-1) [n:127.0.0.1:43046_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2) [junit4] 2> 1833939 INFO (jetty-launcher-3730-thread-1) [n:127.0.0.1:43046_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:35072/solr ready [junit4] 2> 1833957 INFO (jetty-launcher-3730-thread-1) [n:127.0.0.1:43046_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory. [junit4] 2> 1834048 INFO (jetty-launcher-3730-thread-1) [n:127.0.0.1:43046_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_43046.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@66e6f876 [junit4] 2> 1834084 INFO (jetty-launcher-3730-thread-1) [n:127.0.0.1:43046_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_43046.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@66e6f876 [junit4] 2> 1834084 INFO (jetty-launcher-3730-thread-1) [n:127.0.0.1:43046_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_43046.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@66e6f876 [junit4] 2> 1834085 INFO (jetty-launcher-3730-thread-1) [n:127.0.0.1:43046_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.x/solr/build/solr-core/test/J1/temp/solr.cloud.AliasIntegrationTest_729207E9B650E1FB-001/tempDir-001/node1/. [junit4] 2> 1834392 INFO (zkConnectionManagerCallback-3773-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1834451 INFO (SUITE-AliasIntegrationTest-seed#[729207E9B650E1FB]-worker) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2) [junit4] 2> 1834524 INFO (SUITE-AliasIntegrationTest-seed#[729207E9B650E1FB]-worker) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:35072/solr ready [junit4] 2> 1834792 INFO (TEST-AliasIntegrationTest.testDeleteAliasWithExistingCollectionName-seed#[729207E9B650E1FB]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testDeleteAliasWithExistingCollectionName [junit4] 2> 1834992 INFO (qtp1041934434-11211) [n:127.0.0.1:37645_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :clusterstatus with params action=CLUSTERSTATUS&wt=javabin&version=2 and sendToOCPQueue=true [junit4] 2> 1834995 INFO (qtp1041934434-11211) [n:127.0.0.1:37645_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=CLUSTERSTATUS&wt=javabin&version=2} status=0 QTime=2 [junit4] 2> 1835249 INFO (qtp740880926-11200) [n:127.0.0.1:43046_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params collection.configName=conf&name=collection_old&nrtReplicas=1&action=CREATE&numShards=2&wt=javabin&version=2 and sendToOCPQueue=true [junit4] 2> 1835319 INFO (OverseerThreadFactory-3833-thread-1-processing-n:127.0.0.1:37645_solr) [n:127.0.0.1:37645_solr ] o.a.s.c.a.c.CreateCollectionCmd Create collection collection_old [junit4] 2> 1835504 INFO (OverseerStateUpdate-74586777775898632-127.0.0.1:37645_solr-n_0000000000) [n:127.0.0.1:37645_solr ] o.a.s.c.o.SliceMutator createReplica() { [junit4] 2> "operation":"ADDREPLICA", [junit4] 2> "collection":"collection_old", [junit4] 2> "shard":"shard1", [junit4] 2> "core":"collection_old_shard1_replica_n1", [junit4] 2> "state":"down", [junit4] 2> "base_url":"https://127.0.0.1:37645/solr", [junit4] 2> "type":"NRT", [junit4] 2> "waitForFinalState":"false"} [junit4] 2> 1835559 INFO (OverseerStateUpdate-74586777775898632-127.0.0.1:37645_solr-n_0000000000) [n:127.0.0.1:37645_solr ] o.a.s.c.o.SliceMutator createReplica() { [junit4] 2> "operation":"ADDREPLICA", [junit4] 2> "collection":"collection_old", [junit4] 2> "shard":"shard2", [junit4] 2> "core":"collection_old_shard2_replica_n3", [junit4] 2> "state":"down", [junit4] 2> "base_url":"https://127.0.0.1:43046/solr", [junit4] 2> "type":"NRT", [junit4] 2> "waitForFinalState":"false"} [junit4] 2> 1835878 INFO (qtp1041934434-11209) [n:127.0.0.1:37645_solr x:collection_old_shard1_replica_n1] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node2&collection.configName=conf&newCollection=true&name=collection_old_shard1_replica_n1&action=CREATE&numShards=2&collection=collection_old&shard=shard1&wt=javabin&version=2&replicaType=NRT [junit4] 2> 1835879 INFO (qtp1041934434-11209) [n:127.0.0.1:37645_solr x:collection_old_shard1_replica_n1] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores [junit4] 2> 1835898 INFO (qtp740880926-11198) [n:127.0.0.1:43046_solr x:collection_old_shard2_replica_n3] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node4&collection.configName=conf&newCollection=true&name=collection_old_shard2_replica_n3&action=CREATE&numShards=2&collection=collection_old&shard=shard2&wt=javabin&version=2&replicaType=NRT [junit4] 2> 1837178 INFO (qtp1041934434-11209) [n:127.0.0.1:37645_solr c:collection_old s:shard1 r:core_node2 x:collection_old_shard1_replica_n1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.1.0 [junit4] 2> 1837293 INFO (qtp1041934434-11209) [n:127.0.0.1:37645_solr c:collection_old s:shard1 r:core_node2 x:collection_old_shard1_replica_n1] o.a.s.s.IndexSchema [collection_old_shard1_replica_n1] Schema name=minimal [junit4] 2> 1837308 INFO (qtp1041934434-11209) [n:127.0.0.1:37645_solr c:collection_old s:shard1 r:core_node2 x:collection_old_shard1_replica_n1] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id [junit4] 2> 1837308 INFO (qtp1041934434-11209) [n:127.0.0.1:37645_solr c:collection_old s:shard1 r:core_node2 x:collection_old_shard1_replica_n1] o.a.s.c.CoreContainer Creating SolrCore 'collection_old_shard1_replica_n1' using configuration from collection collection_old, trusted=true [junit4] 2> 1837309 INFO (qtp1041934434-11209) [n:127.0.0.1:37645_solr c:collection_old s:shard1 r:core_node2 x:collection_old_shard1_replica_n1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_37645.solr.core.collection_old.shard1.replica_n1' (registry 'solr.core.collection_old.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@66e6f876 [junit4] 2> 1837309 INFO (qtp1041934434-11209) [n:127.0.0.1:37645_solr c:collection_old s:shard1 r:core_node2 x:collection_old_shard1_replica_n1] o.a.s.c.SolrCore [[collection_old_shard1_replica_n1] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.x/solr/build/solr-core/test/J1/temp/solr.cloud.AliasIntegrationTest_729207E9B650E1FB-001/tempDir-001/node2/collection_old_shard1_replica_n1], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.x/solr/build/solr-core/test/J1/temp/solr.cloud.AliasIntegrationTest_729207E9B650E1FB-001/tempDir-001/node2/./collection_old_shard1_replica_n1/data/] [junit4] 2> 1837393 INFO (qtp740880926-11198) [n:127.0.0.1:43046_solr c:collection_old s:shard2 r:core_node4 x:collection_old_shard2_replica_n3] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.1.0 [junit4] 2> 1837642 INFO (qtp740880926-11198) [n:127.0.0.1:43046_solr c:collection_old s:shard2 r:core_node4 x:collection_old_shard2_replica_n3] o.a.s.s.IndexSchema [collection_old_shard2_replica_n3] Schema name=minimal [junit4] 2> 1837676 INFO (qtp740880926-11198) [n:127.0.0.1:43046_solr c:collection_old s:shard2 r:core_node4 x:collection_old_shard2_replica_n3] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id [junit4] 2> 1837677 INFO (qtp740880926-11198) [n:127.0.0.1:43046_solr c:collection_old s:shard2 r:core_node4 x:collection_old_shard2_replica_n3] o.a.s.c.CoreContainer Creating SolrCore 'collection_old_shard2_replica_n3' using configuration from collection collection_old, trusted=true [junit4] 2> 1837677 INFO (qtp740880926-11198) [n:127.0.0.1:43046_solr c:collection_old s:shard2 r:core_node4 x:collection_old_shard2_replica_n3] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_43046.solr.core.collection_old.shard2.replica_n3' (registry 'solr.core.collection_old.shard2.replica_n3') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@66e6f876 [junit4] 2> 1837677 INFO (qtp740880926-11198) [n:127.0.0.1:43046_solr c:collection_old s:shard2 r:core_node4 x:collection_old_shard2_replica_n3] o.a.s.c.SolrCore [[collection_old_shard2_replica_n3] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.x/solr/build/solr-core/test/J1/temp/solr.cloud.AliasIntegrationTest_729207E9B650E1FB-001/tempDir-001/node1/collection_old_shard2_replica_n3], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.x/solr/build/solr-core/test/J1/temp/solr.cloud.AliasIntegrationTest_729207E9B650E1FB-001/tempDir-001/node1/./collection_old_shard2_replica_n3/data/] [junit4] 2> 1837786 INFO (qtp1041934434-11209) [n:127.0.0.1:37645_solr c:collection_old s:shard1 r:core_node2 x:collection_old_shard1_replica_n1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 1837786 INFO (qtp1041934434-11209) [n:127.0.0.1:37645_solr c:collection_old s:shard1 r:core_node2 x:collection_old_shard1_replica_n1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 1837788 INFO (qtp1041934434-11209) [n:127.0.0.1:37645_solr c:collection_old s:shard1 r:core_node2 x:collection_old_shard1_replica_n1] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 1837788 INFO (qtp1041934434-11209) [n:127.0.0.1:37645_solr c:collection_old s:shard1 r:core_node2 x:collection_old_shard1_replica_n1] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 1837789 INFO (qtp1041934434-11209) [n:127.0.0.1:37645_solr c:collection_old s:shard1 r:core_node2 x:collection_old_shard1_replica_n1] o.a.s.s.SolrIndexSearcher Opening [Searcher@521924b1[collection_old_shard1_replica_n1] main] [junit4] 2> 1837823 INFO (qtp1041934434-11209) [n:127.0.0.1:37645_solr c:collection_old s:shard1 r:core_node2 x:collection_old_shard1_replica_n1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf [junit4] 2> 1837823 INFO (qtp1041934434-11209) [n:127.0.0.1:37645_solr c:collection_old s:shard1 r:core_node2 x:collection_old_shard1_replica_n1] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf [junit4] 2> 1837824 INFO (qtp1041934434-11209) [n:127.0.0.1:37645_solr c:collection_old s:shard1 r:core_node2 x:collection_old_shard1_replica_n1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms. [junit4] 2> 1837824 INFO (qtp1041934434-11209) [n:127.0.0.1:37645_solr c:collection_old s:shard1 r:core_node2 x:collection_old_shard1_replica_n1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1631851524943511552 [junit4] 2> 1837860 INFO (searcherExecutor-3842-thread-1-processing-n:127.0.0.1:37645_solr x:collection_old_shard1_replica_n1 c:collection_old s:shard1 r:core_node2) [n:127.0.0.1:37645_solr c:collection_old s:shard1 r:core_node2 x:collection_old_shard1_replica_n1] o.a.s.c.SolrCore [collection_old_shard1_replica_n1] Registered new searcher Searcher@521924b1[collection_old_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 1837862 INFO (qtp1041934434-11209) [n:127.0.0.1:37645_solr c:collection_old s:shard1 r:core_node2 x:collection_old_shard1_replica_n1] o.a.s.c.ZkShardTerms Successful update of terms at /collections/collection_old/terms/shard1 to Terms{values={core_node2=0}, version=0} [junit4] 2> 1837862 INFO (qtp1041934434-11209) [n:127.0.0.1:37645_solr c:collection_old s:shard1 r:core_node2 x:collection_old_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/collection_old/leaders/shard1 [junit4] 2> 1837897 INFO (qtp1041934434-11209) [n:127.0.0.1:37645_solr c:collection_old s:shard1 r:core_node2 x:collection_old_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue. [junit4] 2> 1837897 INFO (qtp1041934434-11209) [n:127.0.0.1:37645_solr c:collection_old s:shard1 r:core_node2 x:collection_old_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync [junit4] 2> 1837897 INFO (qtp1041934434-11209) [n:127.0.0.1:37645_solr c:collection_old s:shard1 r:core_node2 x:collection_old_shard1_replica_n1] o.a.s.c.SyncStrategy Sync replicas to https://127.0.0.1:37645/solr/collection_old_shard1_replica_n1/ [junit4] 2> 1837897 INFO (qtp1041934434-11209) [n:127.0.0.1:37645_solr c:collection_old s:shard1 r:core_node2 x:collection_old_shard1_replica_n1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me [junit4] 2> 1837898 INFO (qtp1041934434-11209) [n:127.0.0.1:37645_solr c:collection_old s:shard1 r:core_node2 x:collection_old_shard1_replica_n1] o.a.s.c.SyncStrategy https://127.0.0.1:37645/solr/collection_old_shard1_replica_n1/ has no replicas [junit4] 2> 1837898 INFO (qtp1041934434-11209) [n:127.0.0.1:37645_solr c:collection_old s:shard1 r:core_node2 x:collection_old_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/collection_old/leaders/shard1/leader after winning as /collections/collection_old/leader_elect/shard1/election/74586777775898632-core_node2-n_0000000000 [junit4] 2> 1837899 INFO (qtp1041934434-11209) [n:127.0.0.1:37645_solr c:collection_old s:shard1 r:core_node2 x:collection_old_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext I am the new leader: https://127.0.0.1:37645/solr/collection_old_shard1_replica_n1/ shard1 [junit4] 2> 1838006 INFO (qtp740880926-11198) [n:127.0.0.1:43046_solr c:collection_old s:shard2 r:core_node4 x:collection_old_shard2_replica_n3] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 1838006 INFO (qtp740880926-11198) [n:127.0.0.1:43046_solr c:collection_old s:shard2 r:core_node4 x:collection_old_shard2_replica_n3] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 1838025 INFO (qtp740880926-11198) [n:127.0.0.1:43046_solr c:collection_old s:shard2 r:core_node4 x:collection_old_shard2_replica_n3] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 1838025 INFO (qtp740880926-11198) [n:127.0.0.1:43046_solr c:collection_old s:shard2 r:core_node4 x:collection_old_shard2_replica_n3] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 1838026 INFO (qtp740880926-11198) [n:127.0.0.1:43046_solr c:collection_old s:shard2 r:core_node4 x:collection_old_shard2_replica_n3] o.a.s.s.SolrIndexSearcher Opening [Searcher@68c1f9e3[collection_old_shard2_replica_n3] main] [junit4] 2> 1838027 INFO (qtp740880926-11198) [n:127.0.0.1:43046_solr c:collection_old s:shard2 r:core_node4 x:collection_old_shard2_replica_n3] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf [junit4] 2> 1838028 INFO (qtp740880926-11198) [n:127.0.0.1:43046_solr c:collection_old s:shard2 r:core_node4 x:collection_old_shard2_replica_n3] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf [junit4] 2> 1838028 INFO (qtp740880926-11198) [n:127.0.0.1:43046_solr c:collection_old s:shard2 r:core_node4 x:collection_old_shard2_replica_n3] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms. [junit4] 2> 1838028 INFO (qtp740880926-11198) [n:127.0.0.1:43046_solr c:collection_old s:shard2 r:core_node4 x:collection_old_shard2_replica_n3] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1631851525157421056 [junit4] 2> 1838044 INFO (searcherExecutor-3844-thread-1-processing-n:127.0.0.1:43046_solr x:collection_old_shard2_replica_n3 c:collection_old s:shard2 r:core_node4) [n:127.0.0.1:43046_solr c:collection_old s:shard2 r:core_node4 x:collection_old_shard2_replica_n3] o.a.s.c.SolrCore [collection_old_shard2_replica_n3] Registered new searcher Searcher@68c1f9e3[collection_old_shard2_replica_n3] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 1838063 INFO (qtp740880926-11198) [n:127.0.0.1:43046_solr c:collection_old s:shard2 r:core_node4 x:collection_old_shard2_replica_n3] o.a.s.c.ZkShardTerms Successful update of terms at /collections/collection_old/terms/shard2 to Terms{values={core_node4=0}, version=0} [junit4] 2> 1838063 INFO (qtp740880926-11198) [n:127.0.0.1:43046_solr c:collection_old s:shard2 r:core_node4 x:collection_old_shard2_replica_n3] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/collection_old/leaders/shard2 [junit4] 2> 1838079 INFO (qtp740880926-11198) [n:127.0.0.1:43046_solr c:collection_old s:shard2 r:core_node4 x:collection_old_shard2_replica_n3] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue. [junit4] 2> 1838079 INFO (qtp740880926-11198) [n:127.0.0.1:43046_solr c:collection_old s:shard2 r:core_node4 x:collection_old_shard2_replica_n3] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync [junit4] 2> 1838079 INFO (qtp740880926-11198) [n:127.0.0.1:43046_solr c:collection_old s:shard2 r:core_node4 x:collection_old_shard2_replica_n3] o.a.s.c.SyncStrategy Sync replicas to https://127.0.0.1:43046/solr/collection_old_shard2_replica_n3/ [junit4] 2> 1838079 INFO (qtp740880926-11198) [n:127.0.0.1:43046_solr c:collection_old s:shard2 r:core_node4 x:collection_old_shard2_replica_n3] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me [junit4] 2> 1838079 INFO (qtp740880926-11198) [n:127.0.0.1:43046_solr c:collection_old s:shard2 r:core_node4 x:collection_old_shard2_replica_n3] o.a.s.c.SyncStrategy https://127.0.0.1:43046/solr/collection_old_shard2_replica_n3/ has no replicas [junit4] 2> 1838079 INFO (qtp740880926-11198) [n:127.0.0.1:43046_solr c:collection_old s:shard2 r:core_node4 x:collection_old_shard2_replica_n3] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/collection_old/leaders/shard2/leader after winning as /collections/collection_old/leader_elect/shard2/election/74586777775898630-core_node4-n_0000000000 [junit4] 2> 1838081 INFO (qtp740880926-11198) [n:127.0.0.1:43046_solr c:collection_old s:shard2 r:core_node4 x:collection_old_shard2_replica_n3] o.a.s.c.ShardLeaderElectionContext I am the new leader: https://127.0.0.1:43046/solr/collection_old_shard2_replica_n3/ shard2 [junit4] 2> 1838110 INFO (zkCallback-3753-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection_old/state.json] for collection [collection_old] has occurred - updating... (live nodes size: [2]) [junit4] 2> 1838118 INFO (zkCallback-3753-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection_old/state.json] for collection [collection_old] has occurred - updating... (live nodes size: [2]) [junit4] 2> 1838163 INFO (qtp1041934434-11209) [n:127.0.0.1:37645_solr c:collection_old s:shard1 r:core_node2 x:collection_old_shard1_replica_n1] o.a.s.c.ZkController I am the leader, no recovery necessary [junit4] 2> 1838165 INFO (qtp1041934434-11209) [n:127.0.0.1:37645_solr c:collection_old s:shard1 r:core_node2 x:collection_old_shard1_replica_n1] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node2&collection.configName=conf&newCollection=true&name=collection_old_shard1_replica_n1&action=CREATE&numShards=2&collection=collection_old&shard=shard1&wt=javabin&version=2&replicaType=NRT} status=0 QTime=2287 [junit4] 2> 1838296 INFO (zkCallback-3743-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection_old/state.json] for collection [collection_old] has occurred - updating... (live nodes size: [2]) [junit4] 2> 1838301 INFO (zkCallback-3753-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection_old/state.json] for collection [collection_old] has occurred - updating... (live nodes size: [2]) [junit4] 2> 1838309 INFO (zkCallback-3753-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection_old/state.json] for collection [collection_old] has occurred - updating... (live nodes size: [2]) [junit4] 2> 1838317 INFO (zkCallback-3753-thread-3) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection_old/state.json] for collection [collection_old] has occurred - updating... (live nodes size: [2]) [junit4] 2> 1838329 INFO (qtp740880926-11198) [n:127.0.0.1:43046_solr c:collection_old s:shard2 r:core_node4 x:collection_old_shard2_replica_n3] o.a.s.c.ZkController I am the leader, no recovery necessary [junit4] 2> 1838331 INFO (qtp740880926-11198) [n:127.0.0.1:43046_solr c:collection_old s:shard2 r:core_node4 x:collection_old_shard2_replica_n3] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node4&collection.configName=conf&newCollection=true&name=collection_old_shard2_replica_n3&action=CREATE&numShards=2&collection=collection_old&shard=shard2&wt=javabin&version=2&replicaType=NRT} status=0 QTime=2432 [junit4] 2> 1838439 INFO (qtp740880926-11200) [n:127.0.0.1:43046_solr ] o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most 45 seconds. Check all shard replicas [junit4] 2> 1838462 INFO (zkCallback-3743-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection_old/state.json] for collection [collection_old] has occurred - updating... (live nodes size: [2]) [junit4] 2> 1838463 INFO (zkCallback-3743-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection_old/state.json] for collection [collection_old] has occurred - updating... (live nodes size: [2]) [junit4] 2> 1838463 INFO (zkCallback-3753-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection_old/state.json] for collection [collection_old] has occurred - updating... (live nodes size: [2]) [junit4] 2> 1838463 INFO (zkCallback-3753-thread-3) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection_old/state.json] for collection [collection_old] has occurred - updating... (live nodes size: [2]) [junit4] 2> 1838463 INFO (zkCallback-3753-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection_old/state.json] for collection [collection_old] has occurred - updating... (live nodes size: [2]) [junit4] 2> 1838479 INFO (qtp740880926-11200) [n:127.0.0.1:43046_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={collection.configName=conf&name=collection_old&nrtReplicas=1&action=CREATE&numShards=2&wt=javabin&version=2} status=0 QTime=3230 [junit4] 2> 1838492 INFO (qtp740880926-11199) [n:127.0.0.1:43046_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params collection.configName=conf&name=collection_new&nrtReplicas=1&action=CREATE&numShards=1&wt=javabin&version=2 and sendToOCPQueue=true [junit4] 2> 1838589 INFO (OverseerCollectionConfigSetProcessor-74586777775898632-127.0.0.1:37645_solr-n_0000000000) [n:127.0.0.1:37645_solr ] o.a.s.c.OverseerTaskQueue Response ZK path: /overseer/collection-queue-work/qnr-0000000000 doesn't exist. Requestor may have disconnected from ZooKeeper [junit4] 2> 1838590 INFO (OverseerThreadFactory-3833-thread-2-processing-n:127.0.0.1:37645_solr) [n:127.0.0.1:37645_solr ] o.a.s.c.a.c.CreateCollectionCmd Create collection collection_new [junit4] 2> 1838851 INFO (OverseerStateUpdate-74586777775898632-127.0.0.1:37645_solr-n_0000000000) [n:127.0.0.1:37645_solr ] o.a.s.c.o.SliceMutator createReplica() { [junit4] 2> "operation":"ADDREPLICA", [junit4] 2> "collection":"collection_new", [junit4] 2> "shard":"shard1", [junit4] 2> "core":"collection_new_shard1_replica_n1", [junit4] 2> "state":"down", [junit4] 2> "base_url":"https://127.0.0.1:43046/solr", [junit4] 2> "type":"NRT", [junit4] 2> "waitForFinalState":"false"} [junit4] 2> 1839172 INFO (qtp740880926-11196) [n:127.0.0.1:43046_solr x:collection_new_shard1_replica_n1] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node2&collection.configName=conf&newCollection=true&name=collection_new_shard1_replica_n1&action=CREATE&numShards=1&collection=collection_new&shard=shard1&wt=javabin&version=2&replicaType=NRT [junit4] 2> 1840480 INFO (qtp740880926-11196) [n:127.0.0.1:43046_solr c:collection_new s:shard1 r:core_node2 x:collection_new_shard1_replica_n1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.1.0 [junit4] 2> 1840507 INFO (qtp740880926-11196) [n:127.0.0.1:43046_solr c:collection_new s:shard1 r:core_node2 x:collection_new_shard1_replica_n1] o.a.s.s.IndexSchema [collection_new_shard1_replica_n1] Schema name=minimal [junit4] 2> 1840527 INFO (qtp740880926-11196) [n:127.0.0.1:43046_solr c:collection_new s:shard1 r:core_node2 x:collection_new_shard1_replica_n1] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id [junit4] 2> 1840527 INFO (qtp740880926-11196) [n:127.0.0.1:43046_solr c:collection_new s:shard1 r:core_node2 x:collection_new_shard1_replica_n1] o.a.s.c.CoreContainer Creating SolrCore 'collection_new_shard1_replica_n1' using configuration from collection collection_new, trusted=true [junit4] 2> 1840528 INFO (qtp740880926-11196) [n:127.0.0.1:43046_solr c:collection_new s:shard1 r:core_node2 x:collection_new_shard1_replica_n1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_43046.solr.core.collection_new.shard1.replica_n1' (registry 'solr.core.collection_new.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@66e6f876 [junit4] 2> 1840528 INFO (qtp740880926-11196) [n:127.0.0.1:43046_solr c:collection_new s:shard1 r:core_node2 x:collection_new_shard1_replica_n1] o.a.s.c.SolrCore [[collection_new_shard1_replica_n1] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.x/solr/build/solr-core/test/J1/temp/solr.cloud.AliasIntegrationTest_729207E9B650E1FB-001/tempDir-001/node1/collection_new_shard1_replica_n1], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.x/solr/build/solr-core/test/J1/temp/solr.cloud.AliasIntegrationTest_729207E9B650E1FB-001/tempDir-001/node1/./collection_new_shard1_replica_n1/data/] [junit4] 2> 1840926 INFO (qtp740880926-11196) [n:127.0.0.1:43046_solr c:collection_new s:shard1 r:core_node2 x:collection_new_shard1_replica_n1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 1840927 INFO (qtp740880926-11196) [n:127.0.0.1:43046_solr c:collection_new s:shard1 r:core_node2 x:collection_new_shard1_replica_n1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 1840929 INFO (qtp740880926-11196) [n:127.0.0.1:43046_solr c:collection_new s:shard1 r:core_node2 x:collection_new_shard1_replica_n1] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 1840929 INFO (qtp740880926-11196) [n:127.0.0.1:43046_solr c:collection_new s:shard1 r:core_node2 x:collection_new_shard1_replica_n1] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 1840943 INFO (qtp740880926-11196) [n:127.0.0.1:43046_solr c:collection_new s:shard1 r:core_node2 x:collection_new_shard1_replica_n1] o.a.s.s.SolrIndexSearcher Opening [Searcher@3ed5779d[collection_new_shard1_replica_n1] main] [junit4] 2> 1840945 INFO (qtp740880926-11196) [n:127.0.0.1:43046_solr c:collection_new s:shard1 r:core_node2 x:collection_new_shard1_replica_n1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf [junit4] 2> 1840945 INFO (qtp740880926-11196) [n:127.0.0.1:43046_solr c:collection_new s:shard1 r:core_node2 x:collection_new_shard1_replica_n1] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf [junit4] 2> 1840946 INFO (qtp740880926-11196) [n:127.0.0.1:43046_solr c:collection_new s:shard1 r:core_node2 x:collection_new_shard1_replica_n1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms. [junit4] 2> 1840946 INFO (qtp740880926-11196) [n:127.0.0.1:43046_solr c:collection_new s:shard1 r:core_node2 x:collection_new_shard1_replica_n1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1631851528217165824 [junit4] 2> 1840981 INFO (searcherExecutor-3852-thread-1-processing-n:127.0.0.1:43046_solr x:collection_new_shard1_replica_n1 c:collection_new s:shard1 r:core_node2) [n:127.0.0.1:43046_solr c:collection_new s:shard1 r:core_node2 x:collection_new_shard1_replica_n1] o.a.s.c.SolrCore [collection_new_shard1_replica_n1] Registered new searcher Searcher@3ed5779d[collection_new_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 1852864 INFO (qtp740880926-11196) [n:127.0.0.1:43046_solr c:collection_new s:shard1 r:core_node2 x:collection_new_shard1_replica_n1] o.a.s.c.ZkShardTerms Successful update of terms at /collections/collection_new/terms/shard1 to Terms{values={core_node2=0}, version=0} [junit4] 2> 1852877 INFO (qtp740880926-11196) [n:127.0.0.1:43046_solr c:collection_new s:shard1 r:core_node2 x:collection_new_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/collection_new/leaders/shard1 [junit4] 2> 1852880 INFO (qtp740880926-11196) [n:127.0.0.1:43046_solr c:collection_new s:shard1 r:core_node2 x:collection_new_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue. [junit4] 2> 1852880 INFO (qtp740880926-11196) [n:127.0.0.1:43046_solr c:collection_new s:shard1 r:core_node2 x:collection_new_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync [junit4] 2> 1852880 INFO (qtp740880926-11196) [n:127.0.0.1:43046_solr c:collection_new s:shard1 r:core_node2 x:collection_new_shard1_replica_n1] o.a.s.c.SyncStrategy Sync replicas to https://127.0.0.1:43046/solr/collection_new_shard1_replica_n1/ [junit4] 2> 1852880 INFO (qtp740880926-11196) [n:127.0.0.1:43046_solr c:collection_new s:shard1 r:core_node2 x:collection_new_shard1_replica_n1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me [junit4] 2> 1852933 INFO (qtp740880926-11196) [n:127.0.0.1:43046_solr c:collection_new s:shard1 r:core_node2 x:collection_new_shard1_replica_n1] o.a.s.c.SyncStrategy https://127.0.0.1:43046/solr/collection_new_shard1_replica_n1/ has no replicas [junit4] 2> 1852933 INFO (qtp740880926-11196) [n:127.0.0.1:43046_solr c:collection_new s:shard1 r:core_node2 x:collection_new_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/collection_new/leaders/shard1/leader after winning as /collections/collection_new/leader_elect/shard1/election/74586777775898630-core_node2-n_0000000000 [junit4] 2> 1852935 INFO (qtp740880926-11196) [n:127.0.0.1:43046_solr c:collection_new s:shard1 r:core_node2 x:collection_new_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext I am the new leader: https://127.0.0.1:43046/solr/collection_new_shard1_replica_n1/ shard1 [junit4] 2> 1852945 INFO (zkCallback-3743-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection_new/state.json] for collection [collection_new] has occurred - updating... (live nodes size: [2]) [junit4] 2> 1852945 INFO (zkCallback-3743-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection_new/state.json] for collection [collection_new] has occurred - updating... (live nodes size: [2]) [junit4] 2> 1853015 INFO (qtp740880926-11196) [n:127.0.0.1:43046_solr c:collection_new s:shard1 r:core_node2 x:collection_new_shard1_replica_n1] o.a.s.c.ZkController I am the leader, no recovery necessary [junit4] 2> 1853051 INFO (qtp740880926-11196) [n:127.0.0.1:43046_solr c:collection_new s:shard1 r:core_node2 x:collection_new_shard1_replica_n1] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node2&collection.configName=conf&newCollection=true&name=collection_new_shard1_replica_n1&action=CREATE&numShards=1&collection=collection_new&shard=shard1&wt=javabin&version=2&replicaType=NRT} status=0 QTime=13879 [junit4] 2> 1853073 INFO (qtp740880926-11199) [n:127.0.0.1:43046_solr ] o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most 45 seconds. Check all shard replicas [junit4] 2> 1853125 INFO (zkCallback-3743-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection_new/state.json] for collection [collection_new] has occurred - updating... (live nodes size: [2]) [junit4] 2> 1853125 INFO (zkCallback-3743-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection_new/state.json] for collection [collection_new] has occurred - updating... (live nodes size: [2]) [junit4] 2> 1853125 INFO (qtp740880926-11199) [n:127.0.0.1:43046_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={collection.configName=conf&name=collection_new&nrtReplicas=1&action=CREATE&numShards=1&wt=javabin&version=2} status=0 QTime=14633 [junit4] 2> 1853125 INFO (zkCallback-3743-thread-3) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection_new/state.json] for collection [collection_new] has occurred - updating... (live nodes size: [2]) [junit4] 2> 1853177 INFO (TEST-AliasIntegrationTest.testDeleteAliasWithExistingCollectionName-seed#[729207E9B650E1FB]) [ ] o.a.s.c.SolrCloudTestCase active replica count: 2 expected replica count: 2 [junit4] 2> 1853193 INFO (TEST-AliasIntegrationTest.testDeleteAliasWithExistingCollectionName-seed#[729207E9B650E1FB]) [ ] o.a.s.c.SolrCloudTestCase active replica count: 1 expected replica count: 1 [junit4] 2> 1853341 INFO (qtp740880926-11197) [n:127.0.0.1:43046_solr c:collection_old s:shard2 r:core_node4 x:collection_old_shard2_replica_n3] o.a.s.c.ZkShardTerms Successful update of terms at /collections/collection_old/terms/shard2 to Terms{values={core_node4=1}, version=1} [junit4] 2> 1853341 INFO (qtp740880926-11197) [n:127.0.0.1:43046_solr c:collection_old s:shard2 r:core_node4 x:collection_old_shard2_replica_n3] o.a.s.u.p.LogUpdateProcessorFactory [collection_old_shard2_replica_n3] webapp=/solr path=/update params={_stateVer_=collection_old:5&wt=javabin&version=2}{add=[6 (1631851541174419456), 7 (1631851541213216768)]} 0 37 [junit4] 2> 1853359 INFO (qtp1041934434-11212) [n:127.0.0.1:37645_solr c:collection_old s:shard1 r:core_node2 x:collection_old_shard1_replica_n1] o.a.s.c.ZkShardTerms Successful update of terms at /collections/collection_old/terms/shard1 to Terms{values={core_node2=1}, version=1} [junit4] 2> 1853359 INFO (qtp1041934434-11212) [n:127.0.0.1:37645_solr c:collection_old s:shard1 r:core_node2 x:collection_old_shard1_replica_n1] o.a.s.u.p.LogUpdateProcessorFactory [collection_old_shard1_replica_n1] webapp=/solr path=/update params={_stateVer_=collection_old:5&wt=javabin&version=2}{add=[8 (1631851541099970560)]} 0 127 [junit4] 2> 1853854 INFO (qtp740880926-11199) [n:127.0.0.1:43046_solr c:collection_old s:shard2 r:core_node4 x:collection_old_shard2_replica_n3] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1631851541752184832,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 1853854 INFO (qtp740880926-11199) [n:127.0.0.1:43046_solr c:collection_old s:shard2 r:core_node4 x:collection_old_shard2_replica_n3] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@5c94ba59 commitCommandVersion:1631851541752184832 [junit4] 2> 1853909 INFO (qtp740880926-11199) [n:127.0.0.1:43046_solr c:collection_old s:shard2 r:core_node4 x:collection_old_shard2_replica_n3] o.a.s.s.SolrIndexSearcher Opening [Searcher@54e0cdcf[collection_old_shard2_replica_n3] main] [junit4] 2> 1853909 INFO (qtp740880926-11199) [n:127.0.0.1:43046_solr c:collection_old s:shard2 r:core_node4 x:collection_old_shard2_replica_n3] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 1853911 INFO (searcherExecutor-3844-thread-1-processing-n:127.0.0.1:43046_solr x:collection_old_shard2_replica_n3 c:collection_old s:shard2 r:core_node4) [n:127.0.0.1:43046_solr c:collection_old s:shard2 r:core_node4 x:collection_old_shard2_replica_n3] o.a.s.c.SolrCore [collection_old_shard2_replica_n3] Registered new searcher Searcher@54e0cdcf[collection_old_shard2_replica_n3] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(8.1.0):C2:[diagnostics={os=Linux, java.vendor=Oracle Corporation, java.version=1.8.0_191, java.vm.version=25.191-b12, lucene.version=8.1.0, os.arch=amd64, java.runtime.version=1.8.0_191-b12, source=flush, os.version=4.4.0-112-generic, timestamp=1556254903593}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))} [junit4] 2> 1853911 INFO (qtp740880926-11199) [n:127.0.0.1:43046_solr c:collection_old s:shard2 r:core_node4 x:collection_old_shard2_replica_n3] o.a.s.u.p.LogUpdateProcessorFactory [collection_old_shard2_replica_n3] webapp=/solr path=/update params={update.distrib=TOLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=https://127.0.0.1:37645/solr/collection_old_shard1_replica_n1/&commit_end_point=leaders&wt=javabin&version=2&expungeDeletes=false}{commit=} 0 57 [junit4] 2> 1853926 INFO (qtp1041934434-11208) [n:127.0.0.1:37645_solr c:collection_old s:shard1 r:core_node2 x:collection_old_shard1_replica_n1] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1631851541827682304,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 1853946 INFO (qtp1041934434-11208) [n:127.0.0.1:37645_solr c:collection_old s:shard1 r:core_node2 x:collection_old_shard1_replica_n1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@4b535752 commitCommandVersion:1631851541827682304 [junit4] 2> 1853969 INFO (qtp1041934434-11208) [n:127.0.0.1:37645_solr c:collection_old s:shard1 r:core_node2 x:collection_old_shard1_replica_n1] o.a.s.s.SolrIndexSearcher Opening [Searcher@55dabbb3[collection_old_shard1_replica_n1] main] [junit4] 2> 1853970 INFO (qtp1041934434-11208) [n:127.0.0.1:37645_solr c:collection_old s:shard1 r:core_node2 x:collection_old_shard1_replica_n1] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 1854032 INFO (searcherExecutor-3842-thread-1-processing-n:127.0.0.1:37645_solr x:collection_old_shard1_replica_n1 c:collection_old s:shard1 r:core_node2) [n:127.0.0.1:37645_solr c:collection_old s:shard1 r:core_node2 x:collection_old_shard1_replica_n1] o.a.s.c.SolrCore [collection_old_shard1_replica_n1] Registered new searcher Searcher@55dabbb3[collection_old_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(8.1.0):C1:[diagnostics={os=Linux, java.vendor=Oracle Corporation, java.version=1.8.0_191, java.vm.version=25.191-b12, lucene.version=8.1.0, os.arch=amd64, java.runtime.version=1.8.0_191-b12, source=flush, os.version=4.4.0-112-generic, timestamp=1556254903667}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))} [junit4] 2> 1854032 INFO (qtp1041934434-11208) [n:127.0.0.1:37645_solr c:collection_old s:shard1 r:core_node2 x:collection_old_shard1_replica_n1] o.a.s.u.p.LogUpdateProcessorFactory [collection_old_shard1_replica_n1] webapp=/solr path=/update params={_stateVer_=collection_old:5&commit=true&wt=javabin&version=2}{commit=} 0 670 [junit4] 2> 1854048 INFO (qtp740880926-11197) [n:127.0.0.1:43046_solr c:collection_new s:shard1 r:core_node2 x:collection_new_shard1_replica_n1] o.a.s.c.ZkShardTerms Successful update of terms at /collections/collection_new/terms/shard1 to Terms{values={core_node2=1}, version=1} [junit4] 2> 1854048 INFO (qtp740880926-11197) [n:127.0.0.1:43046_solr c:collection_new s:shard1 r:core_node2 x:collection_new_shard1_replica_n1] o.a.s.u.p.LogUpdateProcessorFactory [collection_new_shard1_replica_n1] webapp=/solr path=/update params={_stateVer_=collection_new:4&wt=javabin&version=2}{add=[1 (1631851541952462848)]} 0 3 [junit4] 2> 1854049 INFO (qtp740880926-11198) [n:127.0.0.1:43046_solr c:collection_new s:shard1 r:core_node2 x:collection_new_shard1_replica_n1] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1631851541956657152,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 1854050 INFO (qtp740880926-11198) [n:127.0.0.1:43046_solr c:collection_new s:shard1 r:core_node2 x:collection_new_shard1_replica_n1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@ce6dd91 commitCommandVersion:1631851541956657152 [junit4] 2> 1854086 INFO (qtp740880926-11198) [n:127.0.0.1:43046_solr c:collection_new s:shard1 r:core_node2 x:collection_new_shard1_replica_n1] o.a.s.s.SolrIndexSearcher Opening [Searcher@223a09d5[collection_new_shard1_replica_n1] main] [junit4] 2> 1854087 INFO (qtp740880926-11198) [n:127.0.0.1:43046_solr c:collection_new s:shard1 r:core_node2 x:collection_new_shard1_replica_n1] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 1854121 INFO (searcherExecutor-3852-thread-1-processing-n:127.0.0.1:43046_solr x:collection_new_shard1_replica_n1 c:collection_new s:shard1 r:core_node2) [n:127.0.0.1:43046_solr c:collection_new s:shard1 r:core_node2 x:collection_new_shard1_replica_n1] o.a.s.c.SolrCore [collection_new_shard1_replica_n1] Registered new searcher Searcher@223a09d5[collection_new_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(8.1.0):C1:[diagnostics={os=Linux, java.vendor=Oracle Corporation, java.version=1.8.0_191, java.vm.version=25.191-b12, lucene.version=8.1.0, os.arch=amd64, java.runtime.version=1.8.0_191-b12, source=flush, os.version=4.4.0-112-generic, timestamp=1556254903754}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))} [junit4] 2> 1854121 INFO (qtp740880926-11198) [n:127.0.0.1:43046_solr c:collection_new s:shard1 r:core_node2 x:collection_new_shard1_replica_n1] o.a.s.u.p.LogUpdateProcessorFactory [collection_new_shard1_replica_n1] webapp=/solr path=/update params={_stateVer_=collection_new:4&commit=true&wt=javabin&version=2}{commit=} 0 71 [junit4] 2> 1854908 INFO (qtp740880926-11198) [n:127.0.0.1:43046_solr c:collection_old s:shard2 r:core_node4 x:collection_old_shard2_replica_n3] o.a.s.c.S.Request [collection_old_shard2_replica_n3] webapp=/solr path=/select params={df=text&distrib=false&_stateVer_=collection_old:5&fl=id&fl=score&shards.purpose=4&start=0&fsv=true&shard.url=https://127.0.0.1:43046/solr/collection_old_shard2_replica_n3/&rows=10&version=2&q=*:*&NOW=1556254903836&isShard=true&wt=javabin} hits=2 status=0 QTime=17 [junit4] 2> 1855058 INFO (qtp1041934434-11212) [n:127.0.0.1:37645_solr c:collection_old s:shard1 r:core_node2 x:collection_old_shard1_replica_n1] o.a.s.c.S.Request [collection_old_shard1_replica_n1] webapp=/solr path=/select params={df=text&distrib=false&_stateVer_=collection_old:5&fl=id&fl=score&shards.purpose=4&start=0&fsv=true&shard.url=https://127.0.0.1:37645/solr/collection_old_shard1_replica_n1/&rows=10&version=2&q=*:*&NOW=1556254903836&isShard=true&wt=javabin} hits=1 status=0 QTime=0 [junit4] 2> 1855058 INFO (OverseerCollectionConfigSetProcessor-74586777775898632-127.0.0.1:37645_solr-n_0000000000) [n:127.0.0.1:37645_solr ] o.a.s.c.OverseerTaskQueue Response ZK path: /overseer/collection-queue-work/qnr-0000000002 doesn't exist. Requestor may have disconnected from ZooKeeper [junit4] 2> 1855126 INFO (qtp1041934434-11208) [n:127.0.0.1:37645_solr c:collection_old s:shard1 r:core_node2 x:collection_old_shard1_replica_n1] o.a.s.c.S.Request [collection_old_shard1_replica_n1] webapp=/solr path=/select params={q=*:*&df=text&distrib=false&_stateVer_=collection_old:5&shards.purpose=64&NOW=1556254903836&ids=8&isShard=true&shard.url=https://127.0.0.1:37645/solr/collection_old_shard1_replica_n1/&wt=javabin&version=2} status=0 QTime=0 [junit4] 2> 1855130 INFO (qtp740880926-11196) [n:127.0.0.1:43046_solr c:collection_old s:shard2 r:core_node4 x:collection_old_shard2_replica_n3] o.a.s.c.S.Request [collection_old_shard2_replica_n3] webapp=/solr path=/select params={q=*:*&df=text&distrib=false&_stateVer_=collection_old:5&shards.purpose=64&NOW=1556254903836&ids=6,7&isShard=true&shard.url=https://127.0.0.1:43046/solr/collection_old_shard2_replica_n3/&wt=javabin&version=2} status=0 QTime=0 [junit4] 2> 1855272 INFO (qtp740880926-11200) [n:127.0.0.1:43046_solr c:collection_old s:shard2 r:core_node4 x:collection_old_shard2_replica_n3] o.a.s.c.S.Request [collection_old_shard2_replica_n3] webapp=/solr path=/select params={q=*:*&_stateVer_=collection_old:5&wt=javabin&version=2} hits=3 status=0 QTime=1139 [junit4] 2> 1855276 INFO (qtp740880926-11199) [n:127.0.0.1:43046_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :createalias with params collections=collection_old&name=collection_old_reserve&action=CREATEALIAS&wt=javabin&version=2 and sendToOCPQueue=true [junit4] 2> 1855403 INFO (qtp740880926-11199) [n:127.0.0.1:43046_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={collections=collection_old&name=collection_old_reserve&action=CREATEALIAS&wt=javabin&version=2} status=0 QTime=126 [junit4] 2> 1855404 INFO (qtp740880926-11197) [n:127.0.0.1:43046_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :createalias with params collections=collection_new&name=collection_old&action=CREATEALIAS&wt=javabin&version=2 and sendToOCPQueue=true [junit4] 2> 1855459 INFO (OverseerCollectionConfigSetProcessor-74586777775898632-127.0.0.1:37645_solr-n_0000000000) [n:127.0.0.1:37645_solr ] o.a.s.c.OverseerTaskQueue Response ZK path: /overseer/collection-queue-work/qnr-0000000004 doesn't exist. Requestor may have disconnected from ZooKeeper [junit4] 2> 1855642 INFO (qtp740880926-11197) [n:127.0.0.1:43046_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={collections=collection_new&name=collection_old&action=CREATEALIAS&wt=javabin&version=2} status=0 QTime=238 [junit4] 2> 1855643 INFO (qtp740880926-11198) [n:127.0.0.1:43046_solr c:collection_new s:shard1 r:core_node2 x:collection_new_shard1_replica_n1] o.a.s.c.S.Request [collection_new_shard1_replica_n1] webapp=/solr path=/select params={q=*:*&_stateVer_=collection_old:5&wt=javabin&version=2} hits=1 status=0 QTime=0 [junit4] 2> 1855657 INFO (qtp740880926-11196) [n:127.0.0.1:43046_solr c:collection_new s:shard1 r:core_node2 x:collection_new_shard1_replica_n1] o.a.s.c.S.Request [collection_new_shard1_replica_n1] webapp=/solr path=/select params={q=*:*&_stateVer_=collection_new:4&wt=javabin&version=2} hits=1 status=0 QTime=0 [junit4] 2> 1855670 INFO (qtp740880926-11200) [n:127.0.0.1:43046_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :delete with params async=bd801eca-ce80-4de4-a74a-57ad970d6242&name=collection_old&action=DELETE&wt=javabin&version=2 and sendToOCPQueue=true [junit4] 2> 1855707 INFO (qtp740880926-11200) [n:127.0.0.1:43046_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={async=bd801eca-ce80-4de4-a74a-57ad970d6242&name=collection_old&action=DELETE&wt=javabin&version=2} status=0 QTime=36 [junit4] 2> 1855709 INFO (qtp740880926-11199) [n:127.0.0.1:43046_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :requeststatus with params requestid=bd801eca-ce80-4de4-a74a-57ad970d6242&action=REQUESTSTATUS&wt=javabin&version=2 and sendToOCPQueue=true [junit4] 2> 1855722 INFO (OverseerCollectionConfigSetProcessor-74586777775898632-127.0.0.1:37645_solr-n_0000000000) [n:127.0.0.1:37645_solr ] o.a.s.c.OverseerTaskQueue Response ZK path: /overseer/collection-queue-work/qnr-0000000006 doesn't exist. Requestor may have disconnected from ZooKeeper [junit4] 2> 1855723 ERROR (OverseerThreadFactory-3833-thread-5-processing-n:127.0.0.1:37645_solr) [n:127.0.0.1:37645_solr ] o.a.s.c.a.c.OverseerCollectionMessageHandler Collection: collection_old operation: delete failed:org.apache.solr.common.SolrException: Collection : collection_old is part of aliases: [collection_old, collection_new, collection_old_reserve], remove or modify the aliases before removing this collection. [junit4] 2> at org.apache.solr.cloud.api.collections.DeleteCollectionCmd.checkAliasReference(DeleteCollectionCmd.java:194) [junit4] 2> at org.apache.solr.cloud.api.collections.DeleteCollectionCmd.call(DeleteCollectionCmd.java:79) [junit4] 2> at org.apache.solr.cloud.api.collections.OverseerCollectionMessageHandler.processMessage(OverseerCollectionMessageHandler.java:264) [junit4] 2> at org.apache.solr.cloud.OverseerTaskProcessor$Runner.run(OverseerTaskProcessor.java:505) [junit4] 2> at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:209) [junit4] 2> at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149) [junit4] 2> at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) [junit4] 2> at java.lang.Thread.run(Thread.java:748) [junit4] 2> [junit4] 2> 1855745 INFO (qtp740880926-11199) [n:127.0.0.1:43046_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={requestid=bd801eca-ce80-4de4-a74a-57ad970d6242&action=REQUESTSTATUS&wt=javabin&version=2} status=0 QTime=36 [junit4] 2> 1855779 INFO (OverseerThreadFactory-3833-thread-5-processing-n:127.0.0.1:37645_solr) [n:127.0.0.1:37645_solr ] o.a.s.c.OverseerTaskQueue Response ZK path: /overseer/collection-queue-work/qnr-0000000008 doesn't exist. Requestor may have disconnected from ZooKeeper [junit4] 2> 1855780 INFO (qtp740880926-11199) [n:127.0.0.1:43046_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :deletestatus with params requestid=bd801eca-ce80-4de4-a74a-57ad970d6242&action=DELETESTATUS&wt=javabin&version=2 and sendToOCPQueue=true [junit4] 2> 1855781 INFO (qtp740880926-11199) [n:127.0.0.1:43046_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={requestid=bd801eca-ce80-4de4-a74a-57ad970d6242&action=DELETESTATUS&wt=javabin&version=2} status=0 QTime=1 [junit4] 2> 1855831 INFO (qtp740880926-11197) [n:127.0.0.1:43046_solr c:collection_new s:shard1 r:core_node2 x:collection_new_shard1_replica_n1] o.a.s.c.S.Request [collection_new_shard1_replica_n1] webapp=/solr path=/select params={q=*:*&_stateVer_=collection_old:5&wt=javabin&version=2} hits=1 status=0 QTime=0 [junit4] 2> 1855868 INFO (qtp740880926-11198) [n:127.0.0.1:43046_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :deletealias with params async=02cf61ba-3450-427e-aa10-0ae8b5ca92f1&name=collection_old_reserve&action=DELETEALIAS&wt=javabin&version=2 and sendToOCPQueue=true [junit4] 2> 1855884 INFO (qtp740880926-11198) [n:127.0.0.1:43046_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={async=02cf61ba-3450-427e-aa10-0ae8b5ca92f1&name=collection_old_reserve&action=DELETEALIAS&wt=javabin&version=2} status=0 QTime=15 [junit4] 2> 1855886 INFO (OverseerCollectionConfigSetProcessor-74586777775898632-127.0.0.1:37645_solr-n_0000000000) [n:127.0.0.1:37645_solr ] o.a.s.c.OverseerTaskQueue Response ZK path: /overseer/collection-queue-work/qnr-0000000008 doesn't exist. Requestor may have disconnected from ZooKeeper [junit4] 2> 1855887 INFO (OverseerThreadFactory-3833-thread-5-processing-n:127.0.0.1:37645_solr) [n:127.0.0.1:37645_solr ] o.a.s.c.OverseerTaskQueue Response ZK path: /overseer/collection-queue-work/qnr-0000000009 doesn't exist. Requestor may have disconnected from ZooKeeper [junit4] 2> 1855888 INFO (qtp740880926-11196) [n:127.0.0.1:43046_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :requeststatus with params requestid=02cf61ba-3450-427e-aa10-0ae8b5ca92f1&action=REQUESTSTATUS&wt=javabin&version=2 and sendToOCPQueue=true [junit4] 2> 1855889 INFO (qtp740880926-11196) [n:127.0.0.1:43046_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={requestid=02cf61ba-3450-427e-aa10-0ae8b5ca92f1&action=REQUESTSTATUS&wt=javabin&version=2} status=0 QTime=0 [junit4] 2> 1855889 INFO (qtp740880926-11200) [n:127.0.0.1:43046_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :deletestatus with params requestid=02cf61ba-3450-427e-aa10-0ae8b5ca92f1&action=DELETESTATUS&wt=javabin&version=2 and sendToOCPQueue=true [junit4] 2> 1855890 INFO (qtp740880926-11200) [n:127.0.0.1:43046_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={requestid=02cf61ba-3450-427e-aa10-0ae8b5ca92f1&action=DELETESTATUS&wt=javabin&version=2} status=0 QTime=0 [junit4] 2> 1855904 INFO (qtp740880926-11199) [n:127.0.0.1:43046_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :deletealias with params async=2a28fa25-9736-431e-84f1-45f9217f4a44&name=collection_old&action=DELETEALIAS&wt=javabin&version=2 and sendToOCPQueue=true [junit4] 2> 1855906 INFO (qtp740880926-11199) [n:127.0.0.1:43046_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={async=2a28fa25-9736-431e-84f1-45f9217f4a44&name=collection_old&action=DELETEALIAS&wt=javabin&version=2} status=0 QTime=2 [junit4] 2> 1855933 INFO (qtp740880926-11197) [n:127.0.0.1:43046_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :requeststatus with params requestid=2a28fa25-9736-431e-84f1-45f9217f4a44&action=REQUESTSTATUS&wt=javabin&version=2 and sendToOCPQueue=true [junit4] 2> 1855942 INFO (OverseerCollectionConfigSetProcessor-74586777775898632-127.0.0.1:37645_solr-n_0000000000) [n:127.0.0.1:37645_solr ] o.a.s.c.OverseerTaskQueue Response ZK path: /overseer/collection-queue-work/qnr-0000000009 doesn't exist. Requestor may have disconnected from ZooKeeper [junit4] 2> 1855957 INFO (OverseerThreadFactory-3833-thread-5-processing-n:127.0.0.1:37645_solr) [n:127.0.0.1:37645_solr ] o.a.s.c.OverseerTaskQueue Response ZK path: /overseer/collection-queue-work/qnr-0000000010 doesn't exist. Requestor may have disconnected from ZooKeeper [junit4] 2> 1856001 INFO (qtp740880926-11197) [n:127.0.0.1:43046_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={requestid=2a28fa25-9736-431e-84f1-45f9217f4a44&action=REQUESTSTATUS&wt=javabin&version=2} status=0 QTime=68 [junit4] 2> 1857007 INFO (qtp740880926-11198) [n:127.0.0.1:43046_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :requeststatus with params requestid=2a28fa25-9736-431e-84f1-45f9217f4a44&action=REQUESTSTATUS&wt=javabin&version=2 and sendToOCPQueue=true [junit4] 2> 1857008 INFO (qtp740880926-11198) [n:127.0.0.1:43046_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={requestid=2a28fa25-9736-431e-84f1-45f9217f4a44&action=REQUESTSTATUS&wt=javabin&version=2} status=0 QTime=0 [junit4] 2> 1857008 INFO (qtp740880926-11196) [n:127.0.0.1:43046_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :deletestatus with params requestid=2a28fa25-9736-431e-84f1-45f9217f4a44&action=DELETESTATUS&wt=javabin&version=2 and sendToOCPQueue=true [junit4] 2> 1857009 INFO (qtp740880926-11196) [n:127.0.0.1:43046_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={requestid=2a28fa25-9736-431e-84f1-45f9217f4a44&action=DELETESTATUS&wt=javabin&version=2} status=0 QTime=0 [junit4] 2> 1857010 INFO (qtp740880926-11200) [n:127.0.0.1:43046_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :delete with params async=a12d3b0b-6854-4bcc-8ba4-ce9829db3507&name=collection_new&action=DELETE&wt=javabin&version=2 and sendToOCPQueue=true [junit4] 2> 1857055 INFO (qtp740880926-11200) [n:127.0.0.1:43046_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={async=a12d3b0b-6854-4bcc-8ba4-ce9829db3507&name=collection_new&action=DELETE&wt=javabin&version=2} status=0 QTime=45 [junit4] 2> 1857056 INFO (qtp740880926-11200) [n:127.0.0.1:43046_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :requeststatus with params requestid=a12d3b0b-6854-4bcc-8ba4-ce9829db3507&action=REQUESTSTATUS&wt=javabin&version=2 and sendToOCPQueue=true [junit4] 2> 1857057 INFO (qtp740880926-11200) [n:127.0.0.1:43046_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={requestid=a12d3b0b-6854-4bcc-8ba4-ce9829db3507&action=REQUESTSTATUS&wt=javabin&version=2} status=0 QTime=0 [junit4] 2> 1857057 INFO (OverseerCollectionConfigSetProcessor-74586777775898632-127.0.0.1:37645_solr-n_0000000000) [n:127.0.0.1:37645_solr ] o.a.s.c.OverseerTaskQueue Response ZK path: /overseer/collection-queue-work/qnr-0000000010 doesn't exist. Requestor may have disconnected from ZooKeeper [junit4] 2> 1857055 INFO (OverseerThreadFactory-3833-thread-5-processing-n:127.0.0.1:37645_solr) [n:127.0.0.1:37645_solr ] o.a.s.c.a.c.OverseerCollectionMessageHandler Executing Collection Cmd=action=UNLOAD&deleteInstanceDir=true&deleteDataDir=true&deleteMetricsHistory=true, asyncId=a12d3b0b-6854-4bcc-8ba4-ce9829db3507 [junit4] 2> 1857153 INFO (qtp740880926-11199) [n:127.0.0.1:43046_solr x:collection_new_shard1_replica_n1] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={deleteInstanceDir=true&deleteMetricsHistory=true&core=collection_new_shard1_replica_n1&async=a12d3b0b-6854-4bcc-8ba4-ce9829db350738592307792485518&qt=/admin/cores&deleteDataDir=true&action=UNLOAD&wt=javabin&version=2} status=0 QTime=0 [junit4] 2> 1857154 INFO (parallelCoreAdminExecutor-3838-thread-1-processing-n:127.0.0.1:43046_solr x:collection_new_shard1_replica_n1 a12d3b0b-6854-4bcc-8ba4-ce9829db350738592307792485518 UNLOAD) [n:127.0.0.1:43046_solr x:collection_new_shard1_replica_n1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection_new.shard1.replica_n1, tag=null [junit4] 2> 1857155 INFO (parallelCoreAdminExecutor-3838-thread-1-processing-n:127.0.0.1:43046_solr x:collection_new_shard1_replica_n1 a12d3b0b-6854-4bcc-8ba4-ce9829db350738592307792485518 UNLOAD) [n:127.0.0.1:43046_solr x:collection_new_shard1_replica_n1] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@230f6315: rootName = solr_43046, domain = solr.core.collection_new.shard1.replica_n1, service url = null, agent id = null] for registry solr.core.collection_new.shard1.replica_n1 / com.codahale.metrics.MetricRegistry@1b5c8dc5 [junit4] 2> 1857202 INFO (parallelCoreAdminExecutor-3838-thread-1-processing-n:127.0.0.1:43046_solr x:collection_new_shard1_replica_n1 a12d3b0b-6854-4bcc-8ba4-ce9829db350738592307792485518 UNLOAD) [n:127.0.0.1:43046_solr x:collection_new_shard1_replica_n1] o.a.s.c.SolrCore [collection_new_shard1_replica_n1] CLOSING SolrCore org.apache.solr.core.SolrCore@1a771534 [junit4] 2> 1857202 INFO (parallelCoreAdminExecutor-3838-thread-1-processing-n:127.0.0.1:43046_solr x:collection_new_shard1_replica_n1 a12d3b0b-6854-4bcc-8ba4-ce9829db350738592307792485518 UNLOAD) [n:127.0.0.1:43046_solr x:collection_new_shard1_replica_n1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection_new.shard1.replica_n1, tag=1a771534 [junit4] 2> 1857202 INFO (parallelCoreAdminExecutor-3838-thread-1-processing-n:127.0.0.1:43046_solr x:collection_new_shard1_replica_n1 a12d3b0b-6854-4bcc-8ba4-ce9829db350738592307792485518 UNLOAD) [n:127.0.0.1:43046_solr x:collection_new_shard1_replica_n1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.collection_new.shard1.leader, tag=1a771534 [junit4] 2> 1857277 INFO (parallelCoreAdminExecutor-3838-thread-1-processing-n:127.0.0.1:43046_solr x:collection_new_shard1_replica_n1 a12d3b0b-6854-4bcc-8ba4-ce9829db350738592307792485518 UNLOAD) [n:127.0.0.1:43046_solr x:collection_new_shard1_replica_n1] o.a.s.c.ZkShardTerms Successful update of terms at /collections/collection_new/terms/shard1 to Terms{values={}, version=2} [junit4] 2> 1857291 INFO (zkCallback-3743-thread-3) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection_new/state.json] for collection [collection_new] has occurred - updating... (live nodes size: [2]) [junit4] 2> 1857291 INFO (zkCallback-3743-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection_new/state.json] for collection [collection_new] has occurred - updating... (live nodes size: [2]) [junit4] 2> 1857291 INFO (zkCallback-3743-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection_new/state.json] for collection [collection_new] has occurred - updating... (live nodes size: [2]) [junit4] 2> 1857525 INFO (qtp740880926-11200) [n:127.0.0.1:43046_solr ] o.a.s.h.a.CoreAdminOperation Checking request status for : a12d3b0b-6854-4bcc-8ba4-ce9829db350738592307792485518 [junit4] 2> 1857525 INFO (qtp740880926-11200) [n:127.0.0.1:43046_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&requestid=a12d3b0b-6854-4bcc-8ba4-ce9829db350738592307792485518&action=REQUESTSTATUS&wt=javabin&version=2} status=0 QTime=0 [junit4] 2> 1857728 INFO (zkCallback-3753-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDeleted path:/collections/collection_new/state.json] for collection [collection_new] has occurred - updating... (live nodes size: [2]) [junit4] 2> 1858069 INFO (qtp740880926-11199) [n:127.0.0.1:43046_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :requeststatus with params requestid=a12d3b0b-6854-4bcc-8ba4-ce9829db3507&action=REQUESTSTATUS&wt=javabin&version=2 and sendToOCPQueue=true [junit4] 2> 1858089 INFO (qtp740880926-11199) [n:127.0.0.1:43046_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={requestid=a12d3b0b-6854-4bcc-8ba4-ce9829db3507&action=REQUESTSTATUS&wt=javabin&version=2} status=0 QTime=20 [junit4] 2> 1858433 INFO (OverseerThreadFactory-3833-thread-5-processing-n:127.0.0.1:37645_solr) [n:127.0.0.1:37645_solr ] o.a.s.c.OverseerTaskQueue Response ZK path: /overseer/collection-queue-work/qnr-0000000011 doesn't exist. Requestor may have disconnected from ZooKeeper [junit4] 2> 1859074 INFO (OverseerCollectionConfigSetProcessor-74586777775898632-127.0.0.1:37645_solr-n_0000000000) [n:127.0.0.1:37645_solr ] o.a.s.c.OverseerTaskQueue Response ZK path: /overseer/collection-queue-work/qnr-0000000011 doesn't exist. Requestor may have disconnected from ZooKeeper [junit4] 2> 1859091 INFO (qtp740880926-11197) [n:127.0.0.1:43046_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :requeststatus with params requestid=a12d3b0b-6854-4bcc-8ba4-ce9829db3507&action=REQUESTSTATUS&wt=javabin&version=2 and sendToOCPQueue=true [junit4] 2> 1859126 INFO (qtp740880926-11197) [n:127.0.0.1:43046_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={req [...truncated too long message...] ettings :: file = /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.x/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-8.x/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-8.x/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-8.x/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-8.x/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-8.x/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-8.x/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-8.x/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-8.x/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-8.x/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-8.x/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-8.x/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-8.x/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-8.x/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-8.x/lucene/top-level-ivy-settings.xml resolve: jar-checksums: [mkdir] Created dir: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.x/solr/null82542101 [copy] Copying 239 files to /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.x/solr/null82542101 [delete] Deleting directory /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.x/solr/null82542101 check-working-copy: [ivy:cachepath] :: resolving dependencies :: #;work...@lucene2-us-west.apache.org [ivy:cachepath] confs: [default] [ivy:cachepath] found org.eclipse.jgit#org.eclipse.jgit;5.3.0.201903130848-r in public [ivy:cachepath] found com.jcraft#jsch;0.1.54 in public [ivy:cachepath] found com.jcraft#jzlib;1.1.1 in public [ivy:cachepath] found com.googlecode.javaewah#JavaEWAH;1.1.6 in public [ivy:cachepath] found org.slf4j#slf4j-api;1.7.2 in public [ivy:cachepath] found org.bouncycastle#bcpg-jdk15on;1.60 in public [ivy:cachepath] found org.bouncycastle#bcprov-jdk15on;1.60 in public [ivy:cachepath] found org.bouncycastle#bcpkix-jdk15on;1.60 in public [ivy:cachepath] found org.slf4j#slf4j-nop;1.7.2 in public [ivy:cachepath] :: resolution report :: resolve 78ms :: artifacts dl 12ms --------------------------------------------------------------------- | | modules || artifacts | | conf | number| search|dwnlded|evicted|| number|dwnlded| --------------------------------------------------------------------- | default | 9 | 0 | 0 | 0 || 9 | 0 | --------------------------------------------------------------------- [wc-checker] Initializing working copy... [wc-checker] Checking working copy status... -jenkins-base: BUILD SUCCESSFUL Total time: 237 minutes 48 seconds Archiving artifacts java.lang.InterruptedException: no matches found within 10000 at hudson.FilePath$ValidateAntFileMask.hasMatch(FilePath.java:2847) at hudson.FilePath$ValidateAntFileMask.invoke(FilePath.java:2726) at hudson.FilePath$ValidateAntFileMask.invoke(FilePath.java:2707) at hudson.FilePath$FileCallableWrapper.call(FilePath.java:3086) Also: hudson.remoting.Channel$CallSiteStackTrace: Remote call to lucene2 at hudson.remoting.Channel.attachCallSiteStackTrace(Channel.java:1741) at hudson.remoting.UserRequest$ExceptionResponse.retrieve(UserRequest.java:357) at hudson.remoting.Channel.call(Channel.java:955) at hudson.FilePath.act(FilePath.java:1072) at hudson.FilePath.act(FilePath.java:1061) at hudson.FilePath.validateAntFileMask(FilePath.java:2705) at hudson.tasks.ArtifactArchiver.perform(ArtifactArchiver.java:243) at hudson.tasks.BuildStepCompatibilityLayer.perform(BuildStepCompatibilityLayer.java:81) at hudson.tasks.BuildStepMonitor$1.perform(BuildStepMonitor.java:20) at hudson.model.AbstractBuild$AbstractBuildExecution.perform(AbstractBuild.java:744) at hudson.model.AbstractBuild$AbstractBuildExecution.performAllBuildSteps(AbstractBuild.java:690) at hudson.model.Build$BuildExecution.post2(Build.java:186) at hudson.model.AbstractBuild$AbstractBuildExecution.post(AbstractBuild.java:635) at hudson.model.Run.execute(Run.java:1835) at hudson.model.FreeStyleBuild.run(FreeStyleBuild.java:43) at hudson.model.ResourceController.execute(ResourceController.java:97) at hudson.model.Executor.run(Executor.java:429) Caused: hudson.FilePath$TunneledInterruptedException at hudson.FilePath$FileCallableWrapper.call(FilePath.java:3088) at hudson.remoting.UserRequest.perform(UserRequest.java:212) at hudson.remoting.UserRequest.perform(UserRequest.java:54) at hudson.remoting.Request$2.run(Request.java:369) at hudson.remoting.InterceptingExecutorService$1.call(InterceptingExecutorService.java:72) at java.util.concurrent.FutureTask.run(FutureTask.java:266) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617) at java.lang.Thread.run(Thread.java:748) Caused: java.lang.InterruptedException: java.lang.InterruptedException: no matches found within 10000 at hudson.FilePath.act(FilePath.java:1074) at hudson.FilePath.act(FilePath.java:1061) at hudson.FilePath.validateAntFileMask(FilePath.java:2705) at hudson.tasks.ArtifactArchiver.perform(ArtifactArchiver.java:243) at hudson.tasks.BuildStepCompatibilityLayer.perform(BuildStepCompatibilityLayer.java:81) at hudson.tasks.BuildStepMonitor$1.perform(BuildStepMonitor.java:20) at hudson.model.AbstractBuild$AbstractBuildExecution.perform(AbstractBuild.java:744) at hudson.model.AbstractBuild$AbstractBuildExecution.performAllBuildSteps(AbstractBuild.java:690) at hudson.model.Build$BuildExecution.post2(Build.java:186) at hudson.model.AbstractBuild$AbstractBuildExecution.post(AbstractBuild.java:635) at hudson.model.Run.execute(Run.java:1835) at hudson.model.FreeStyleBuild.run(FreeStyleBuild.java:43) at hudson.model.ResourceController.execute(ResourceController.java:97) at hudson.model.Executor.run(Executor.java:429) No artifacts found that match the file pattern "**/*.events,heapdumps/**,**/hs_err_pid*". Configuration error? Recording test results Build step 'Publish JUnit test result report' changed build result to UNSTABLE Email was triggered for: Unstable (Test Failures) Sending email for trigger: Unstable (Test Failures)
--------------------------------------------------------------------- To unsubscribe, e-mail: dev-unsubscr...@lucene.apache.org For additional commands, e-mail: dev-h...@lucene.apache.org